"./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bMD5QuickS3: -verbose -fast-list -size-limit 1024 -test.run '^TestServerSideMove$'" - Starting (try 4/5)
2021/02/08 05:26:26 DEBUG : Creating backend with remote "TestChunkerChunk50bMD5QuickS3:rclone-test-natimoq7huvimok1noficoq2"
2021/02/08 05:26:26 DEBUG : Creating backend with remote "TestS3:rclone-test-natimoq7huvimok1noficoq2"
2021/02/08 05:26:26 DEBUG : Reset feature "ListR"
2021/02/08 05:26:26 DEBUG : Creating backend with remote "/tmp/rclone383457340"
=== RUN   TestServerSideMove
    run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-natimoq7huvimok1noficoq2'", Local "Local file system at /tmp/rclone383457340", Modify Window "1ns"
2021/02/08 05:26:26 DEBUG : Creating backend with remote "TestChunkerChunk50bMD5QuickS3:rclone-test-tuxotof9nawepiq6wuquvil7"
2021/02/08 05:26:26 DEBUG : Creating backend with remote "TestS3:rclone-test-tuxotof9nawepiq6wuquvil7"
2021/02/08 05:26:26 DEBUG : Reset feature "ListR"
2021/02/08 05:26:26 INFO  : S3 bucket rclone-test-natimoq7huvimok1noficoq2: Bucket "rclone-test-natimoq7huvimok1noficoq2" created with ACL "private"
2021/02/08 05:26:26 DEBUG : potato2.rclone_chunk.001_78aq8j: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2021/02/08 05:26:26 INFO  : potato2.rclone_chunk.001_78aq8j: Copied (server-side copy) to: potato2.rclone_chunk.001
2021/02/08 05:26:26 INFO  : potato2.rclone_chunk.001_78aq8j: Deleted
2021/02/08 05:26:27 DEBUG : potato2.rclone_chunk.002_78aq8j: MD5 = 01a6651a94ab602dbfc47587d234b52c OK
2021/02/08 05:26:27 INFO  : potato2.rclone_chunk.002_78aq8j: Copied (server-side copy) to: potato2.rclone_chunk.002
2021/02/08 05:26:27 INFO  : potato2.rclone_chunk.002_78aq8j: Deleted
2021/02/08 05:26:27 DEBUG : empty space.rclone_chunk.001_78arct: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK
2021/02/08 05:26:27 INFO  : empty space.rclone_chunk.001_78arct: Copied (server-side copy) to: empty space
2021/02/08 05:26:27 INFO  : empty space.rclone_chunk.001_78arct: Deleted
2021/02/08 05:26:27 DEBUG : potato3.rclone_chunk.001_78armv: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2021/02/08 05:26:27 INFO  : potato3.rclone_chunk.001_78armv: Copied (server-side copy) to: potato3.rclone_chunk.001
2021/02/08 05:26:27 INFO  : potato3.rclone_chunk.001_78armv: Deleted
2021/02/08 05:26:27 DEBUG : potato3.rclone_chunk.002_78armv: MD5 = 111c387f2584838efb7e0ec6f2fa2e10 OK
2021/02/08 05:26:27 INFO  : potato3.rclone_chunk.002_78armv: Copied (server-side copy) to: potato3.rclone_chunk.002
2021/02/08 05:26:27 INFO  : potato3.rclone_chunk.002_78armv: Deleted
    sync_test.go:1294: Server side move (if possible) Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-natimoq7huvimok1noficoq2' -> Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-tuxotof9nawepiq6wuquvil7'
2021/02/08 05:26:28 INFO  : S3 bucket rclone-test-tuxotof9nawepiq6wuquvil7: Bucket "rclone-test-tuxotof9nawepiq6wuquvil7" created with ACL "private"
2021/02/08 05:26:28 DEBUG : empty space.rclone_chunk.001_78assv: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK
2021/02/08 05:26:28 INFO  : empty space.rclone_chunk.001_78assv: Copied (server-side copy) to: empty space
2021/02/08 05:26:28 INFO  : empty space.rclone_chunk.001_78assv: Deleted
2021/02/08 05:26:28 DEBUG : potato3.rclone_chunk.001_78asi8: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2021/02/08 05:26:28 INFO  : potato3.rclone_chunk.001_78asi8: Copied (server-side copy) to: potato3.rclone_chunk.001
2021/02/08 05:26:28 INFO  : potato3.rclone_chunk.001_78asi8: Deleted
2021/02/08 05:26:29 DEBUG : potato3.rclone_chunk.002_78asi8: MD5 = 01a6651a94ab602dbfc47587d234b52c OK
2021/02/08 05:26:29 INFO  : potato3.rclone_chunk.002_78asi8: Copied (server-side copy) to: potato3.rclone_chunk.002
2021/02/08 05:26:29 INFO  : potato3.rclone_chunk.002_78asi8: Deleted
2021/02/08 05:26:29 DEBUG : potato3: Sizes differ (src 68 vs dst 60)
2021/02/08 05:26:29 DEBUG : Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-tuxotof9nawepiq6wuquvil7': Waiting for checks to finish
2021/02/08 05:26:29 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/02/08 05:26:29 DEBUG : empty space: Unchanged skipping
2021/02/08 05:26:29 DEBUG : potato2: Can't copy - required hash not found
2021/02/08 05:26:29 DEBUG : potato3: Can't copy - required hash not found
2021/02/08 05:26:29 INFO  : empty space: Deleted
2021/02/08 05:26:29 DEBUG : Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-tuxotof9nawepiq6wuquvil7': Waiting for transfers to finish
2021/02/08 05:26:29 DEBUG : potato2.rclone_chunk.001_78atuf: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2021/02/08 05:26:29 INFO  : potato2.rclone_chunk.001_78atuf: Copied (server-side copy) to: potato2.rclone_chunk.001
2021/02/08 05:26:29 INFO  : potato2.rclone_chunk.001_78atuf: Deleted
2021/02/08 05:26:29 DEBUG : potato2.rclone_chunk.002_78atuf: MD5 = 01a6651a94ab602dbfc47587d234b52c OK
2021/02/08 05:26:29 INFO  : potato2.rclone_chunk.002_78atuf: Copied (server-side copy) to: potato2.rclone_chunk.002
2021/02/08 05:26:29 INFO  : potato2.rclone_chunk.002_78atuf: Deleted
2021/02/08 05:26:29 DEBUG : potato3.rclone_chunk.001_78atfx: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2021/02/08 05:26:29 INFO  : potato3.rclone_chunk.001_78atfx: Copied (server-side copy) to: potato3.rclone_chunk.001
2021/02/08 05:26:29 INFO  : potato3.rclone_chunk.001_78atfx: Deleted
2021/02/08 05:26:29 INFO  : potato2: Copied (new)
2021/02/08 05:26:29 DEBUG : potato3.rclone_chunk.002_78atfx: MD5 = 111c387f2584838efb7e0ec6f2fa2e10 OK
2021/02/08 05:26:29 INFO  : potato3.rclone_chunk.002_78atfx: Copied (server-side copy) to: potato3.rclone_chunk.002
2021/02/08 05:26:29 INFO  : potato3.rclone_chunk.002_78atfx: Deleted
2021/02/08 05:26:29 INFO  : potato2: Deleted
2021/02/08 05:26:29 INFO  : potato3: Copied (replaced existing)
2021/02/08 05:26:29 INFO  : potato3: Deleted
2021/02/08 05:26:29 DEBUG : Creating backend with remote "TestChunkerChunk50bMD5QuickS3:rclone-test-beqopax2cibarej8poraneg7"
2021/02/08 05:26:29 DEBUG : Creating backend with remote "TestS3:rclone-test-beqopax2cibarej8poraneg7"
2021/02/08 05:26:29 DEBUG : Reset feature "ListR"
2021/02/08 05:26:30 DEBUG : empty space: copy non-chunked object...
2021/02/08 05:26:30 DEBUG : Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-beqopax2cibarej8poraneg7': Waiting for checks to finish
2021/02/08 05:26:30 DEBUG : Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-beqopax2cibarej8poraneg7': Waiting for transfers to finish
2021/02/08 05:26:30 DEBUG : potato3: Can't copy - required hash not found
2021/02/08 05:26:30 DEBUG : potato2: Can't copy - required hash not found
2021/02/08 05:26:30 INFO  : S3 bucket rclone-test-beqopax2cibarej8poraneg7: Bucket "rclone-test-beqopax2cibarej8poraneg7" created with ACL "private"
2021/02/08 05:26:30 INFO  : empty space: Copied (server-side copy)
2021/02/08 05:26:30 INFO  : empty space: Deleted
2021/02/08 05:26:30 ERROR : potato2: Failed to copy: object not found
2021/02/08 05:26:30 ERROR : potato2: Not deleting source as copy failed: object not found
2021/02/08 05:26:30 DEBUG : potato3.rclone_chunk.001_78aui8: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2021/02/08 05:26:30 INFO  : potato3.rclone_chunk.001_78aui8: Copied (server-side copy) to: potato3.rclone_chunk.001
2021/02/08 05:26:30 INFO  : potato3.rclone_chunk.001_78aui8: Deleted
2021/02/08 05:26:30 DEBUG : potato3.rclone_chunk.002_78aui8: MD5 = 111c387f2584838efb7e0ec6f2fa2e10 OK
2021/02/08 05:26:30 INFO  : potato3.rclone_chunk.002_78aui8: Copied (server-side copy) to: potato3.rclone_chunk.002
2021/02/08 05:26:30 INFO  : potato3.rclone_chunk.002_78aui8: Deleted
2021/02/08 05:26:31 INFO  : potato3: Copied (new)
2021/02/08 05:26:31 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/02/08 05:26:31 DEBUG : Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-beqopax2cibarej8poraneg7': Purge object "empty space"
2021/02/08 05:26:31 DEBUG : Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-beqopax2cibarej8poraneg7': Purge object "potato3"
2021/02/08 05:26:31 DEBUG : Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-beqopax2cibarej8poraneg7': Purge dir ""
2021/02/08 05:26:31 purge failed to rmdir "": BucketNotEmpty: The bucket you tried to delete is not empty
	status code: 409, request id: E85358D0A8BF49E9, host id: hfzA2yZq3xnZjgJvy54CQcpboJu8qXE36t352CYG5m1uqQT3q9iDv3RnFejB7A+lD0C90t/f0OQ=
2021/02/08 05:26:31 DEBUG : Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-tuxotof9nawepiq6wuquvil7': Purge object "potato2"
2021/02/08 05:26:31 DEBUG : Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-tuxotof9nawepiq6wuquvil7': Purge dir ""
2021/02/08 05:26:31 INFO  : S3 bucket rclone-test-tuxotof9nawepiq6wuquvil7: Bucket "rclone-test-tuxotof9nawepiq6wuquvil7" deleted
--- FAIL: TestServerSideMove (5.64s)
FAIL
2021/02/08 05:26:31 DEBUG : Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-natimoq7huvimok1noficoq2': Purge dir ""
2021/02/08 05:26:31 INFO  : S3 bucket rclone-test-natimoq7huvimok1noficoq2: Bucket "rclone-test-natimoq7huvimok1noficoq2" deleted
"./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bMD5QuickS3: -verbose -fast-list -size-limit 1024 -test.run '^TestServerSideMove$'" - Finished ERROR in 5.93315171s (try 4/5): exit status 1: Failed [TestServerSideMove]