"./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bMD5QuickS3: -verbose -size-limit 1024 -test.run '^TestServerSideMove$'" - Starting (try 2/5)
2021/03/04 05:17:52 DEBUG : Creating backend with remote "TestChunkerChunk50bMD5QuickS3:rclone-test-susahik8zomahag9zugapof3"
2021/03/04 05:17:52 DEBUG : Creating backend with remote "TestS3:rclone-test-susahik8zomahag9zugapof3"
2021/03/04 05:17:52 DEBUG : Reset feature "ListR"
2021/03/04 05:17:52 DEBUG : Creating backend with remote "/tmp/rclone575294873"
=== RUN   TestServerSideMove
    run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-susahik8zomahag9zugapof3'", Local "Local file system at /tmp/rclone575294873", Modify Window "1ns"
2021/03/04 05:17:52 DEBUG : Creating backend with remote "TestChunkerChunk50bMD5QuickS3:rclone-test-falataf2xixidib0lojajiy8"
2021/03/04 05:17:52 DEBUG : Creating backend with remote "TestS3:rclone-test-falataf2xixidib0lojajiy8"
2021/03/04 05:17:52 DEBUG : Reset feature "ListR"
2021/03/04 05:17:53 INFO  : S3 bucket rclone-test-susahik8zomahag9zugapof3: Bucket "rclone-test-susahik8zomahag9zugapof3" created with ACL "private"
2021/03/04 05:17:53 DEBUG : potato2.rclone_chunk.001_fnwoya: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2021/03/04 05:17:53 INFO  : potato2.rclone_chunk.001_fnwoya: Copied (server-side copy) to: potato2.rclone_chunk.001
2021/03/04 05:17:53 INFO  : potato2.rclone_chunk.001_fnwoya: Deleted
2021/03/04 05:17:53 DEBUG : potato2.rclone_chunk.002_fnwoya: MD5 = 01a6651a94ab602dbfc47587d234b52c OK
2021/03/04 05:17:53 INFO  : potato2.rclone_chunk.002_fnwoya: Copied (server-side copy) to: potato2.rclone_chunk.002
2021/03/04 05:17:53 INFO  : potato2.rclone_chunk.002_fnwoya: Deleted
2021/03/04 05:17:54 DEBUG : empty space.rclone_chunk.001_fnwpdg: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK
2021/03/04 05:17:54 INFO  : empty space.rclone_chunk.001_fnwpdg: Copied (server-side copy) to: empty space
2021/03/04 05:17:54 INFO  : empty space.rclone_chunk.001_fnwpdg: Deleted
2021/03/04 05:17:54 DEBUG : potato3.rclone_chunk.001_fnwpnh: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2021/03/04 05:17:54 INFO  : potato3.rclone_chunk.001_fnwpnh: Copied (server-side copy) to: potato3.rclone_chunk.001
2021/03/04 05:17:54 INFO  : potato3.rclone_chunk.001_fnwpnh: Deleted
2021/03/04 05:17:54 DEBUG : potato3.rclone_chunk.002_fnwpnh: MD5 = 111c387f2584838efb7e0ec6f2fa2e10 OK
2021/03/04 05:17:54 INFO  : potato3.rclone_chunk.002_fnwpnh: Copied (server-side copy) to: potato3.rclone_chunk.002
2021/03/04 05:17:54 INFO  : potato3.rclone_chunk.002_fnwpnh: Deleted
    sync_test.go:1294: Server side move (if possible) Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-susahik8zomahag9zugapof3' -> Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-falataf2xixidib0lojajiy8'
2021/03/04 05:17:55 INFO  : S3 bucket rclone-test-falataf2xixidib0lojajiy8: Bucket "rclone-test-falataf2xixidib0lojajiy8" created with ACL "private"
2021/03/04 05:17:55 DEBUG : empty space.rclone_chunk.001_fnwqs9: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK
2021/03/04 05:17:55 INFO  : empty space.rclone_chunk.001_fnwqs9: Copied (server-side copy) to: empty space
2021/03/04 05:17:55 INFO  : empty space.rclone_chunk.001_fnwqs9: Deleted
2021/03/04 05:17:55 DEBUG : potato3.rclone_chunk.001_fnwqlm: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2021/03/04 05:17:55 INFO  : potato3.rclone_chunk.001_fnwqlm: Copied (server-side copy) to: potato3.rclone_chunk.001
2021/03/04 05:17:55 INFO  : potato3.rclone_chunk.001_fnwqlm: Deleted
2021/03/04 05:17:56 DEBUG : potato3.rclone_chunk.002_fnwqlm: MD5 = 01a6651a94ab602dbfc47587d234b52c OK
2021/03/04 05:17:56 INFO  : potato3.rclone_chunk.002_fnwqlm: Copied (server-side copy) to: potato3.rclone_chunk.002
2021/03/04 05:17:56 INFO  : potato3.rclone_chunk.002_fnwqlm: Deleted
2021/03/04 05:17:56 DEBUG : potato3: Sizes differ (src 68 vs dst 60)
2021/03/04 05:17:56 DEBUG : Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-falataf2xixidib0lojajiy8': Waiting for checks to finish
2021/03/04 05:17:56 DEBUG : potato2: Can't copy - required hash not found
2021/03/04 05:17:56 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/03/04 05:17:56 DEBUG : empty space: Unchanged skipping
2021/03/04 05:17:56 DEBUG : potato3: Can't copy - required hash not found
2021/03/04 05:17:56 INFO  : empty space: Deleted
2021/03/04 05:17:56 DEBUG : Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-falataf2xixidib0lojajiy8': Waiting for transfers to finish
2021/03/04 05:17:56 DEBUG : potato2.rclone_chunk.001_fnwr2f: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2021/03/04 05:17:56 INFO  : potato2.rclone_chunk.001_fnwr2f: Copied (server-side copy) to: potato2.rclone_chunk.001
2021/03/04 05:17:56 INFO  : potato2.rclone_chunk.001_fnwr2f: Deleted
2021/03/04 05:17:56 DEBUG : potato2.rclone_chunk.002_fnwr2f: MD5 = 01a6651a94ab602dbfc47587d234b52c OK
2021/03/04 05:17:56 INFO  : potato2.rclone_chunk.002_fnwr2f: Copied (server-side copy) to: potato2.rclone_chunk.002
2021/03/04 05:17:56 INFO  : potato2.rclone_chunk.002_fnwr2f: Deleted
2021/03/04 05:17:56 INFO  : potato2: Copied (new)
2021/03/04 05:17:56 DEBUG : potato3.rclone_chunk.001_fnwrhs: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2021/03/04 05:17:56 INFO  : potato3.rclone_chunk.001_fnwrhs: Copied (server-side copy) to: potato3.rclone_chunk.001
2021/03/04 05:17:56 INFO  : potato3.rclone_chunk.001_fnwrhs: Deleted
2021/03/04 05:17:56 INFO  : potato2: Deleted
2021/03/04 05:17:56 DEBUG : potato3.rclone_chunk.002_fnwrhs: MD5 = 111c387f2584838efb7e0ec6f2fa2e10 OK
2021/03/04 05:17:56 INFO  : potato3.rclone_chunk.002_fnwrhs: Copied (server-side copy) to: potato3.rclone_chunk.002
2021/03/04 05:17:56 INFO  : potato3.rclone_chunk.002_fnwrhs: Deleted
2021/03/04 05:17:56 INFO  : potato3: Copied (replaced existing)
2021/03/04 05:17:56 INFO  : potato3: Deleted
2021/03/04 05:17:56 DEBUG : Creating backend with remote "TestChunkerChunk50bMD5QuickS3:rclone-test-tobeqig6zipavas7kexokoz3"
2021/03/04 05:17:56 DEBUG : Creating backend with remote "TestS3:rclone-test-tobeqig6zipavas7kexokoz3"
2021/03/04 05:17:56 DEBUG : Reset feature "ListR"
2021/03/04 05:17:56 DEBUG : Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-tobeqig6zipavas7kexokoz3': Waiting for checks to finish
2021/03/04 05:17:56 DEBUG : Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-tobeqig6zipavas7kexokoz3': Waiting for transfers to finish
2021/03/04 05:17:56 DEBUG : empty space: copy non-chunked object...
2021/03/04 05:17:56 DEBUG : potato2: Can't copy - required hash not found
2021/03/04 05:17:56 DEBUG : potato3: Can't copy - required hash not found
2021/03/04 05:17:57 INFO  : S3 bucket rclone-test-tobeqig6zipavas7kexokoz3: Bucket "rclone-test-tobeqig6zipavas7kexokoz3" created with ACL "private"
2021/03/04 05:17:57 INFO  : empty space: Copied (server-side copy)
2021/03/04 05:17:57 INFO  : empty space: Deleted
2021/03/04 05:17:57 ERROR : potato2: Failed to copy: object not found
2021/03/04 05:17:57 ERROR : potato2: Not deleting source as copy failed: object not found
2021/03/04 05:17:57 DEBUG : potato3.rclone_chunk.001_fnwrjc: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2021/03/04 05:17:57 INFO  : potato3.rclone_chunk.001_fnwrjc: Copied (server-side copy) to: potato3.rclone_chunk.001
2021/03/04 05:17:57 INFO  : potato3.rclone_chunk.001_fnwrjc: Deleted
2021/03/04 05:17:57 DEBUG : potato3.rclone_chunk.002_fnwrjc: MD5 = 111c387f2584838efb7e0ec6f2fa2e10 OK
2021/03/04 05:17:57 INFO  : potato3.rclone_chunk.002_fnwrjc: Copied (server-side copy) to: potato3.rclone_chunk.002
2021/03/04 05:17:57 INFO  : potato3.rclone_chunk.002_fnwrjc: Deleted
2021/03/04 05:17:58 INFO  : potato3: Copied (new)
2021/03/04 05:17:58 INFO  : potato3: Deleted
    sync_test.go:1331: 
        	Error Trace:	sync_test.go:1331
        	            				sync_test.go:1398
        	Error:      	Received unexpected error:
        	            	object not found
        	Test:       	TestServerSideMove
2021/03/04 05:17:58 DEBUG : Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-tobeqig6zipavas7kexokoz3': Purge object "empty space"
2021/03/04 05:17:58 DEBUG : Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-tobeqig6zipavas7kexokoz3': Purge object "potato3"
2021/03/04 05:17:58 DEBUG : Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-tobeqig6zipavas7kexokoz3': Purge dir ""
2021/03/04 05:17:58 purge failed to rmdir "": BucketNotEmpty: The bucket you tried to delete is not empty
	status code: 409, request id: 416DE1CC3FD822A8, host id: DxpzhzqyeoDCKl4mRdw/Wn74KpTfn7QESFeqye3Alt5qgURuFyG3drYDJvIutQawwmrktBwbGNU=
2021/03/04 05:17:58 DEBUG : Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-falataf2xixidib0lojajiy8': Purge object "potato2"
2021/03/04 05:17:58 DEBUG : Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-falataf2xixidib0lojajiy8': Purge dir ""
2021/03/04 05:17:58 INFO  : S3 bucket rclone-test-falataf2xixidib0lojajiy8: Bucket "rclone-test-falataf2xixidib0lojajiy8" deleted
--- FAIL: TestServerSideMove (5.74s)
FAIL
2021/03/04 05:17:58 DEBUG : Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-susahik8zomahag9zugapof3': Purge dir ""
2021/03/04 05:17:59 INFO  : S3 bucket rclone-test-susahik8zomahag9zugapof3: Bucket "rclone-test-susahik8zomahag9zugapof3" deleted
"./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bMD5QuickS3: -verbose -size-limit 1024 -test.run '^TestServerSideMove$'" - Finished ERROR in 6.17772151s (try 2/5): exit status 1: Failed [TestServerSideMove]