"./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bSHA1QuickS3: -verbose -size-limit 1024 -test.run '^TestServerSideMoveDeleteEmptySourceDirs$'" - Starting (try 5/5)
2020/12/27 05:09:04 DEBUG : Creating backend with remote "TestChunkerChunk50bSHA1QuickS3:rclone-test-yorocej9solesep9cebugam8"
2020/12/27 05:09:04 DEBUG : Creating backend with remote "TestS3:rclone-test-yorocej9solesep9cebugam8"
2020/12/27 05:09:04 DEBUG : Reset feature "ListR"
2020/12/27 05:09:04 DEBUG : Creating backend with remote "/tmp/rclone137116595"
=== RUN   TestServerSideMoveDeleteEmptySourceDirs
    run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-yorocej9solesep9cebugam8'", Local "Local file system at /tmp/rclone137116595", Modify Window "1ns"
2020/12/27 05:09:04 DEBUG : Creating backend with remote "TestChunkerChunk50bSHA1QuickS3:rclone-test-riqilel8zojujic6ceridog2"
2020/12/27 05:09:04 DEBUG : Creating backend with remote "TestS3:rclone-test-riqilel8zojujic6ceridog2"
2020/12/27 05:09:04 DEBUG : Reset feature "ListR"
2020/12/27 05:09:04 INFO  : S3 bucket rclone-test-yorocej9solesep9cebugam8: Bucket "rclone-test-yorocej9solesep9cebugam8" created with ACL "private"
2020/12/27 05:09:05 DEBUG : potato2.rclone_chunk.001_zkt740: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2020/12/27 05:09:05 INFO  : potato2.rclone_chunk.001_zkt740: Copied (server-side copy) to: potato2.rclone_chunk.001
2020/12/27 05:09:05 INFO  : potato2.rclone_chunk.001_zkt740: Deleted
2020/12/27 05:09:05 DEBUG : potato2.rclone_chunk.002_zkt740: MD5 = 01a6651a94ab602dbfc47587d234b52c OK
2020/12/27 05:09:05 INFO  : potato2.rclone_chunk.002_zkt740: Copied (server-side copy) to: potato2.rclone_chunk.002
2020/12/27 05:09:05 INFO  : potato2.rclone_chunk.002_zkt740: Deleted
2020/12/27 05:09:05 DEBUG : empty space.rclone_chunk.001_zkt8gr: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK
2020/12/27 05:09:05 INFO  : empty space.rclone_chunk.001_zkt8gr: Copied (server-side copy) to: empty space
2020/12/27 05:09:05 INFO  : empty space.rclone_chunk.001_zkt8gr: Deleted
2020/12/27 05:09:05 DEBUG : potato3.rclone_chunk.001_zkt8ml: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2020/12/27 05:09:05 INFO  : potato3.rclone_chunk.001_zkt8ml: Copied (server-side copy) to: potato3.rclone_chunk.001
2020/12/27 05:09:05 INFO  : potato3.rclone_chunk.001_zkt8ml: Deleted
2020/12/27 05:09:05 DEBUG : potato3.rclone_chunk.002_zkt8ml: MD5 = 111c387f2584838efb7e0ec6f2fa2e10 OK
2020/12/27 05:09:05 INFO  : potato3.rclone_chunk.002_zkt8ml: Copied (server-side copy) to: potato3.rclone_chunk.002
2020/12/27 05:09:05 INFO  : potato3.rclone_chunk.002_zkt8ml: Deleted
2020/12/27 05:09:05 DEBUG : tomatoDir: Making directory
    sync_test.go:1294: Server side move (if possible) Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-yorocej9solesep9cebugam8' -> Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-riqilel8zojujic6ceridog2'
2020/12/27 05:09:06 INFO  : S3 bucket rclone-test-riqilel8zojujic6ceridog2: Bucket "rclone-test-riqilel8zojujic6ceridog2" created with ACL "private"
2020/12/27 05:09:06 DEBUG : empty space.rclone_chunk.001_zkt9vm: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK
2020/12/27 05:09:06 INFO  : empty space.rclone_chunk.001_zkt9vm: Copied (server-side copy) to: empty space
2020/12/27 05:09:06 INFO  : empty space.rclone_chunk.001_zkt9vm: Deleted
2020/12/27 05:09:06 DEBUG : potato3.rclone_chunk.001_zkt90a: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2020/12/27 05:09:06 INFO  : potato3.rclone_chunk.001_zkt90a: Copied (server-side copy) to: potato3.rclone_chunk.001
2020/12/27 05:09:06 INFO  : potato3.rclone_chunk.001_zkt90a: Deleted
2020/12/27 05:09:07 DEBUG : potato3.rclone_chunk.002_zkt90a: MD5 = 01a6651a94ab602dbfc47587d234b52c OK
2020/12/27 05:09:07 INFO  : potato3.rclone_chunk.002_zkt90a: Copied (server-side copy) to: potato3.rclone_chunk.002
2020/12/27 05:09:07 INFO  : potato3.rclone_chunk.002_zkt90a: Deleted
2020/12/27 05:09:07 DEBUG : potato3: Sizes differ (src 68 vs dst 60)
2020/12/27 05:09:07 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-riqilel8zojujic6ceridog2': Waiting for checks to finish
2020/12/27 05:09:07 DEBUG : potato2: Can't copy - required hash not found
2020/12/27 05:09:07 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2020/12/27 05:09:07 DEBUG : empty space: Unchanged skipping
2020/12/27 05:09:07 DEBUG : potato3: Can't copy - required hash not found
2020/12/27 05:09:07 INFO  : empty space: Deleted
2020/12/27 05:09:07 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-riqilel8zojujic6ceridog2': Waiting for transfers to finish
2020/12/27 05:09:07 DEBUG : potato2.rclone_chunk.001_zkta4f: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2020/12/27 05:09:07 INFO  : potato2.rclone_chunk.001_zkta4f: Copied (server-side copy) to: potato2.rclone_chunk.001
2020/12/27 05:09:07 INFO  : potato2.rclone_chunk.001_zkta4f: Deleted
2020/12/27 05:09:07 DEBUG : potato2.rclone_chunk.002_zkta4f: MD5 = 01a6651a94ab602dbfc47587d234b52c OK
2020/12/27 05:09:07 INFO  : potato2.rclone_chunk.002_zkta4f: Copied (server-side copy) to: potato2.rclone_chunk.002
2020/12/27 05:09:07 INFO  : potato2.rclone_chunk.002_zkta4f: Deleted
2020/12/27 05:09:07 DEBUG : potato3.rclone_chunk.001_zkta6u: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2020/12/27 05:09:07 INFO  : potato3.rclone_chunk.001_zkta6u: Copied (server-side copy) to: potato3.rclone_chunk.001
2020/12/27 05:09:07 INFO  : potato3.rclone_chunk.001_zkta6u: Deleted
2020/12/27 05:09:07 INFO  : potato2: Copied (new)
2020/12/27 05:09:07 DEBUG : potato3.rclone_chunk.002_zkta6u: MD5 = 111c387f2584838efb7e0ec6f2fa2e10 OK
2020/12/27 05:09:07 INFO  : potato3.rclone_chunk.002_zkta6u: Copied (server-side copy) to: potato3.rclone_chunk.002
2020/12/27 05:09:07 INFO  : potato3.rclone_chunk.002_zkta6u: Deleted
2020/12/27 05:09:07 INFO  : potato2: Deleted
2020/12/27 05:09:07 INFO  : potato3: Copied (replaced existing)
2020/12/27 05:09:07 INFO  : potato3: Deleted
2020/12/27 05:09:07 DEBUG : Creating backend with remote "TestChunkerChunk50bSHA1QuickS3:rclone-test-yameqac2tamedem9qetilis8"
2020/12/27 05:09:07 DEBUG : Creating backend with remote "TestS3:rclone-test-yameqac2tamedem9qetilis8"
2020/12/27 05:09:07 DEBUG : Reset feature "ListR"
2020/12/27 05:09:07 DEBUG : tomatoDir: Making directory
2020/12/27 05:09:07 DEBUG : empty space: copy non-chunked object...
2020/12/27 05:09:07 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-yameqac2tamedem9qetilis8': Waiting for checks to finish
2020/12/27 05:09:07 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-yameqac2tamedem9qetilis8': Waiting for transfers to finish
2020/12/27 05:09:07 DEBUG : potato2: Can't copy - required hash not found
2020/12/27 05:09:07 DEBUG : potato3: Can't copy - required hash not found
2020/12/27 05:09:08 INFO  : S3 bucket rclone-test-yameqac2tamedem9qetilis8: Bucket "rclone-test-yameqac2tamedem9qetilis8" created with ACL "private"
2020/12/27 05:09:08 INFO  : empty space: Copied (server-side copy)
2020/12/27 05:09:08 INFO  : empty space: Deleted
2020/12/27 05:09:08 ERROR : potato2: Failed to copy: object not found
2020/12/27 05:09:08 ERROR : potato2: Not deleting source as copy failed: object not found
2020/12/27 05:09:08 DEBUG : potato3.rclone_chunk.001_zktaay: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2020/12/27 05:09:08 INFO  : potato3.rclone_chunk.001_zktaay: Copied (server-side copy) to: potato3.rclone_chunk.001
2020/12/27 05:09:08 INFO  : potato3.rclone_chunk.001_zktaay: Deleted
2020/12/27 05:09:08 DEBUG : potato3.rclone_chunk.002_zktaay: MD5 = 111c387f2584838efb7e0ec6f2fa2e10 OK
2020/12/27 05:09:08 INFO  : potato3.rclone_chunk.002_zktaay: Copied (server-side copy) to: potato3.rclone_chunk.002
2020/12/27 05:09:08 INFO  : potato3.rclone_chunk.002_zktaay: Deleted
2020/12/27 05:09:08 INFO  : potato3: Copied (new)
2020/12/27 05:09:08 INFO  : potato3: Deleted
    sync_test.go:1331: 
        	Error Trace:	sync_test.go:1331
        	            				sync_test.go:1420
        	Error:      	Received unexpected error:
        	            	object not found
        	Test:       	TestServerSideMoveDeleteEmptySourceDirs
2020/12/27 05:09:08 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-yameqac2tamedem9qetilis8': Purge object "empty space"
2020/12/27 05:09:09 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-yameqac2tamedem9qetilis8': Purge object "potato3"
2020/12/27 05:09:09 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-yameqac2tamedem9qetilis8': Purge dir ""
2020/12/27 05:09:09 purge failed to rmdir "": BucketNotEmpty: The bucket you tried to delete is not empty
	status code: 409, request id: C3054A051D9E3AD6, host id: KpisIe5pVZlUni07s1ee7H/USDqyVoD9XZ9dnBJzOOZSDdMQuJU/Z3uS/Y3XWahMEsjbodRQoj4=
2020/12/27 05:09:09 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-riqilel8zojujic6ceridog2': Purge object "potato2"
2020/12/27 05:09:09 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-riqilel8zojujic6ceridog2': Purge dir ""
2020/12/27 05:09:09 INFO  : S3 bucket rclone-test-riqilel8zojujic6ceridog2: Bucket "rclone-test-riqilel8zojujic6ceridog2" deleted
--- FAIL: TestServerSideMoveDeleteEmptySourceDirs (5.27s)
FAIL
2020/12/27 05:09:09 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-yorocej9solesep9cebugam8': Purge dir ""
2020/12/27 05:09:09 INFO  : S3 bucket rclone-test-yorocej9solesep9cebugam8: Bucket "rclone-test-yorocej9solesep9cebugam8" deleted
"./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bSHA1QuickS3: -verbose -size-limit 1024 -test.run '^TestServerSideMoveDeleteEmptySourceDirs$'" - Finished ERROR in 5.562033611s (try 5/5): exit status 1: Failed [TestServerSideMoveDeleteEmptySourceDirs]