"./sync.test -test.v -test.timeout 2h0m0s -remote TestPutio: -verbose -test.run '^(TestServerSideCopy|TestSyncCopyDest)$'" - Starting (try 3/5)
2022/04/15 06:11:55 DEBUG : Creating backend with remote "TestPutio:rclone-test-zupulax7xiluhep9tezapiw5"
2022/04/15 06:11:55 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2022/04/15 06:11:55 DEBUG : Creating backend with remote "/tmp/rclone3518568642"
=== RUN   TestServerSideCopy
    run.go:181: Remote "Putio root 'rclone-test-zupulax7xiluhep9tezapiw5'", Local "Local file system at /tmp/rclone3518568642", Modify Window "1s"
2022/04/15 06:11:56 DEBUG : Putio root 'rclone-test-zupulax7xiluhep9tezapiw5': chunkStart: 0, reqSize: 11
2022/04/15 06:11:56 DEBUG : Putio root 'rclone-test-zupulax7xiluhep9tezapiw5': Sending chunk. transferOffset: 0 length: 11
2022/04/15 06:11:57 DEBUG : Creating backend with remote "TestPutio:rclone-test-teyakuw3gimeyul4voberuq8"
    sync_test.go:275: Server side copy (if possible) Putio root 'rclone-test-zupulax7xiluhep9tezapiw5' -> Putio root 'rclone-test-teyakuw3gimeyul4voberuq8'
2022/04/15 06:11:57 DEBUG : Putio root 'rclone-test-teyakuw3gimeyul4voberuq8': Waiting for checks to finish
2022/04/15 06:11:57 DEBUG : Putio root 'rclone-test-teyakuw3gimeyul4voberuq8': Waiting for transfers to finish
2022/04/15 06:11:58 ERROR : sub dir/hello world: Failed to copy: unexpected status code (400) response while doing POST to https://api.put.io/v2/files/copy
    sync_test.go:278: 
        	Error Trace:	sync_test.go:278
        	Error:      	Received unexpected error:
        	            	unexpected status code (400) response while doing POST to https://api.put.io/v2/files/copy
        	Test:       	TestServerSideCopy
2022/04/15 06:11:58 DEBUG : Putio root 'rclone-test-teyakuw3gimeyul4voberuq8': Purge remote
--- FAIL: TestServerSideCopy (3.16s)
=== RUN   TestSyncCopyDest
    run.go:181: Remote "Putio root 'rclone-test-zupulax7xiluhep9tezapiw5'", Local "Local file system at /tmp/rclone3518568642", Modify Window "1s"
2022/04/15 06:11:59 DEBUG : Creating backend with remote "TestPutio:rclone-test-zupulax7xiluhep9tezapiw5/dst"
2022/04/15 06:11:59 DEBUG : Creating backend with remote "TestPutio:rclone-test-zupulax7xiluhep9tezapiw5/CopyDest"
2022/04/15 06:11:59 DEBUG : Putio root 'rclone-test-zupulax7xiluhep9tezapiw5/dst': Waiting for checks to finish
2022/04/15 06:11:59 DEBUG : Putio root 'rclone-test-zupulax7xiluhep9tezapiw5/dst': Waiting for transfers to finish
2022/04/15 06:12:00 DEBUG : Putio root 'rclone-test-zupulax7xiluhep9tezapiw5/dst': chunkStart: 0, reqSize: 3
2022/04/15 06:12:00 DEBUG : Putio root 'rclone-test-zupulax7xiluhep9tezapiw5/dst': Sending chunk. transferOffset: 0 length: 3
2022/04/15 06:12:00 DEBUG : one: crc32 = 7a6c86f1 OK
2022/04/15 06:12:00 INFO  : one: Copied (new)
2022/04/15 06:12:00 DEBUG : Waiting for deletions to finish
2022/04/15 06:12:01 DEBUG : Putio root 'rclone-test-zupulax7xiluhep9tezapiw5/dst': Waiting for checks to finish
2022/04/15 06:12:01 DEBUG : one: Sizes differ (src 5 vs dst 3)
2022/04/15 06:12:01 DEBUG : Putio root 'rclone-test-zupulax7xiluhep9tezapiw5/dst': Waiting for transfers to finish
2022/04/15 06:12:01 DEBUG : Putio root 'rclone-test-zupulax7xiluhep9tezapiw5/dst': chunkStart: 0, reqSize: 5
2022/04/15 06:12:01 DEBUG : Putio root 'rclone-test-zupulax7xiluhep9tezapiw5/dst': Sending chunk. transferOffset: 0 length: 5
2022/04/15 06:12:02 DEBUG : one: crc32 = cb217308 OK
2022/04/15 06:12:02 INFO  : one: Copied (replaced existing)
2022/04/15 06:12:02 DEBUG : Waiting for deletions to finish
2022/04/15 06:12:03 DEBUG : Putio root 'rclone-test-zupulax7xiluhep9tezapiw5': chunkStart: 0, reqSize: 3
2022/04/15 06:12:03 DEBUG : Putio root 'rclone-test-zupulax7xiluhep9tezapiw5': Sending chunk. transferOffset: 0 length: 3
2022/04/15 06:12:04 DEBUG : Putio root 'rclone-test-zupulax7xiluhep9tezapiw5': chunkStart: 0, reqSize: 5
2022/04/15 06:12:04 DEBUG : Putio root 'rclone-test-zupulax7xiluhep9tezapiw5': Sending chunk. transferOffset: 0 length: 5
2022/04/15 06:12:04 DEBUG : Creating backend with remote "TestPutio:rclone-test-zupulax7xiluhep9tezapiw5/BackupDir"
2022/04/15 06:12:05 DEBUG : Putio root 'rclone-test-zupulax7xiluhep9tezapiw5/dst': Waiting for checks to finish
2022/04/15 06:12:05 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2022/04/15 06:12:05 DEBUG : one: Sizes differ (src 5 vs dst 3)
2022/04/15 06:12:05 INFO  : one: Moved (server-side)
2022/04/15 06:12:05 ERROR : one: Failed to copy: unexpected status code (400) response while doing POST to https://api.put.io/v2/files/copy
2022/04/15 06:12:05 ERROR : one: Destination found in --copy-dest, error copying
2022/04/15 06:12:05 DEBUG : one: Sizes differ (src 5 vs dst 3)
2022/04/15 06:12:06 INFO  : one: Deleted
2022/04/15 06:12:06 ERROR : one: Couldn't move: object not found
2022/04/15 06:12:06 DEBUG : Putio root 'rclone-test-zupulax7xiluhep9tezapiw5/dst': Waiting for transfers to finish
2022/04/15 06:12:06 ERROR : Putio root 'rclone-test-zupulax7xiluhep9tezapiw5/dst': not deleting files as there were IO errors
2022/04/15 06:12:06 ERROR : Putio root 'rclone-test-zupulax7xiluhep9tezapiw5/dst': not deleting directories as there were IO errors
    sync_test.go:1648: 
        	Error Trace:	sync_test.go:1648
        	Error:      	Received unexpected error:
        	            	object not found
        	Test:       	TestSyncCopyDest
--- FAIL: TestSyncCopyDest (8.35s)
FAIL
2022/04/15 06:12:07 DEBUG : Putio root 'rclone-test-zupulax7xiluhep9tezapiw5': Purge remote
"./sync.test -test.v -test.timeout 2h0m0s -remote TestPutio: -verbose -test.run '^(TestServerSideCopy|TestSyncCopyDest)$'" - Finished ERROR in 11.984689823s (try 3/5): exit status 1: Failed [TestServerSideCopy TestSyncCopyDest]