"./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bMD5QuickS3: -verbose -fast-list -size-limit 1024 -test.run '^TestServerSideMoveDeleteEmptySourceDirs$'" - Starting (try 5/5)
2021/03/04 05:29:59 DEBUG : Creating backend with remote "TestChunkerChunk50bMD5QuickS3:rclone-test-taduwiy5foxewum9jotimux5"
2021/03/04 05:29:59 DEBUG : Creating backend with remote "TestS3:rclone-test-taduwiy5foxewum9jotimux5"
2021/03/04 05:29:59 DEBUG : Reset feature "ListR"
2021/03/04 05:29:59 DEBUG : Creating backend with remote "/tmp/rclone681615571"
=== RUN   TestServerSideMoveDeleteEmptySourceDirs
    run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-taduwiy5foxewum9jotimux5'", Local "Local file system at /tmp/rclone681615571", Modify Window "1ns"
2021/03/04 05:29:59 DEBUG : Creating backend with remote "TestChunkerChunk50bMD5QuickS3:rclone-test-peqesem6deweron7lahunud1"
2021/03/04 05:29:59 DEBUG : Creating backend with remote "TestS3:rclone-test-peqesem6deweron7lahunud1"
2021/03/04 05:29:59 DEBUG : Reset feature "ListR"
2021/03/04 05:29:59 INFO  : S3 bucket rclone-test-taduwiy5foxewum9jotimux5: Bucket "rclone-test-taduwiy5foxewum9jotimux5" created with ACL "private"
2021/03/04 05:30:00 DEBUG : potato2.rclone_chunk.001_fogub9: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2021/03/04 05:30:00 INFO  : potato2.rclone_chunk.001_fogub9: Copied (server-side copy) to: potato2.rclone_chunk.001
2021/03/04 05:30:00 INFO  : potato2.rclone_chunk.001_fogub9: Deleted
2021/03/04 05:30:00 DEBUG : potato2.rclone_chunk.002_fogub9: MD5 = 01a6651a94ab602dbfc47587d234b52c OK
2021/03/04 05:30:00 INFO  : potato2.rclone_chunk.002_fogub9: Copied (server-side copy) to: potato2.rclone_chunk.002
2021/03/04 05:30:00 INFO  : potato2.rclone_chunk.002_fogub9: Deleted
2021/03/04 05:30:00 DEBUG : empty space.rclone_chunk.001_fogvgt: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK
2021/03/04 05:30:00 INFO  : empty space.rclone_chunk.001_fogvgt: Copied (server-side copy) to: empty space
2021/03/04 05:30:00 INFO  : empty space.rclone_chunk.001_fogvgt: Deleted
2021/03/04 05:30:00 DEBUG : potato3.rclone_chunk.001_fogvfl: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2021/03/04 05:30:00 INFO  : potato3.rclone_chunk.001_fogvfl: Copied (server-side copy) to: potato3.rclone_chunk.001
2021/03/04 05:30:00 INFO  : potato3.rclone_chunk.001_fogvfl: Deleted
2021/03/04 05:30:00 DEBUG : potato3.rclone_chunk.002_fogvfl: MD5 = 111c387f2584838efb7e0ec6f2fa2e10 OK
2021/03/04 05:30:00 INFO  : potato3.rclone_chunk.002_fogvfl: Copied (server-side copy) to: potato3.rclone_chunk.002
2021/03/04 05:30:00 INFO  : potato3.rclone_chunk.002_fogvfl: Deleted
2021/03/04 05:30:00 DEBUG : tomatoDir: Making directory
    sync_test.go:1294: Server side move (if possible) Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-taduwiy5foxewum9jotimux5' -> Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-peqesem6deweron7lahunud1'
2021/03/04 05:30:01 INFO  : S3 bucket rclone-test-peqesem6deweron7lahunud1: Bucket "rclone-test-peqesem6deweron7lahunud1" created with ACL "private"
2021/03/04 05:30:01 DEBUG : empty space.rclone_chunk.001_fogwud: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK
2021/03/04 05:30:01 INFO  : empty space.rclone_chunk.001_fogwud: Copied (server-side copy) to: empty space
2021/03/04 05:30:02 INFO  : empty space.rclone_chunk.001_fogwud: Deleted
2021/03/04 05:30:02 DEBUG : potato3.rclone_chunk.001_fogxgi: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2021/03/04 05:30:02 INFO  : potato3.rclone_chunk.001_fogxgi: Copied (server-side copy) to: potato3.rclone_chunk.001
2021/03/04 05:30:02 INFO  : potato3.rclone_chunk.001_fogxgi: Deleted
2021/03/04 05:30:03 DEBUG : potato3.rclone_chunk.002_fogxgi: MD5 = 01a6651a94ab602dbfc47587d234b52c OK
2021/03/04 05:30:03 INFO  : potato3.rclone_chunk.002_fogxgi: Copied (server-side copy) to: potato3.rclone_chunk.002
2021/03/04 05:30:03 INFO  : potato3.rclone_chunk.002_fogxgi: Deleted
2021/03/04 05:30:03 DEBUG : potato3: Sizes differ (src 68 vs dst 60)
2021/03/04 05:30:03 DEBUG : Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-peqesem6deweron7lahunud1': Waiting for checks to finish
2021/03/04 05:30:03 DEBUG : potato2: Can't copy - required hash not found
2021/03/04 05:30:03 DEBUG : potato3: Can't copy - required hash not found
2021/03/04 05:30:03 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/03/04 05:30:03 DEBUG : empty space: Unchanged skipping
2021/03/04 05:30:03 INFO  : empty space: Deleted
2021/03/04 05:30:03 DEBUG : Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-peqesem6deweron7lahunud1': Waiting for transfers to finish
2021/03/04 05:30:03 DEBUG : potato2.rclone_chunk.001_fogyam: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2021/03/04 05:30:03 INFO  : potato2.rclone_chunk.001_fogyam: Copied (server-side copy) to: potato2.rclone_chunk.001
2021/03/04 05:30:03 INFO  : potato2.rclone_chunk.001_fogyam: Deleted
2021/03/04 05:30:03 DEBUG : potato3.rclone_chunk.001_fogyvg: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2021/03/04 05:30:03 INFO  : potato3.rclone_chunk.001_fogyvg: Copied (server-side copy) to: potato3.rclone_chunk.001
2021/03/04 05:30:03 INFO  : potato3.rclone_chunk.001_fogyvg: Deleted
2021/03/04 05:30:03 DEBUG : potato2.rclone_chunk.002_fogyam: MD5 = 01a6651a94ab602dbfc47587d234b52c OK
2021/03/04 05:30:03 INFO  : potato2.rclone_chunk.002_fogyam: Copied (server-side copy) to: potato2.rclone_chunk.002
2021/03/04 05:30:03 INFO  : potato2.rclone_chunk.002_fogyam: Deleted
2021/03/04 05:30:03 DEBUG : potato3.rclone_chunk.002_fogyvg: MD5 = 111c387f2584838efb7e0ec6f2fa2e10 OK
2021/03/04 05:30:03 INFO  : potato3.rclone_chunk.002_fogyvg: Copied (server-side copy) to: potato3.rclone_chunk.002
2021/03/04 05:30:03 INFO  : potato2: Copied (new)
2021/03/04 05:30:04 INFO  : potato3.rclone_chunk.002_fogyvg: Deleted
2021/03/04 05:30:04 INFO  : potato3: Copied (replaced existing)
2021/03/04 05:30:04 INFO  : potato2: Deleted
2021/03/04 05:30:04 INFO  : potato3: Deleted
2021/03/04 05:30:04 DEBUG : Creating backend with remote "TestChunkerChunk50bMD5QuickS3:rclone-test-junozom5qegowod3wuyuruq5"
2021/03/04 05:30:04 DEBUG : Creating backend with remote "TestS3:rclone-test-junozom5qegowod3wuyuruq5"
2021/03/04 05:30:04 DEBUG : Reset feature "ListR"
2021/03/04 05:30:04 DEBUG : tomatoDir: Making directory
2021/03/04 05:30:04 DEBUG : empty space: copy non-chunked object...
2021/03/04 05:30:04 DEBUG : Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-junozom5qegowod3wuyuruq5': Waiting for checks to finish
2021/03/04 05:30:04 DEBUG : Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-junozom5qegowod3wuyuruq5': Waiting for transfers to finish
2021/03/04 05:30:04 DEBUG : potato2: Can't copy - required hash not found
2021/03/04 05:30:04 DEBUG : potato3: Can't copy - required hash not found
2021/03/04 05:30:04 INFO  : S3 bucket rclone-test-junozom5qegowod3wuyuruq5: Bucket "rclone-test-junozom5qegowod3wuyuruq5" created with ACL "private"
2021/03/04 05:30:05 INFO  : empty space: Copied (server-side copy)
2021/03/04 05:30:05 INFO  : empty space: Deleted
2021/03/04 05:30:05 ERROR : potato3: Failed to copy: object not found
2021/03/04 05:30:05 ERROR : potato3: Not deleting source as copy failed: object not found
2021/03/04 05:30:05 DEBUG : potato2.rclone_chunk.001_fogze5: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2021/03/04 05:30:05 INFO  : potato2.rclone_chunk.001_fogze5: Copied (server-side copy) to: potato2.rclone_chunk.001
2021/03/04 05:30:05 INFO  : potato2.rclone_chunk.001_fogze5: Deleted
2021/03/04 05:30:05 DEBUG : potato2.rclone_chunk.002_fogze5: MD5 = 01a6651a94ab602dbfc47587d234b52c OK
2021/03/04 05:30:05 INFO  : potato2.rclone_chunk.002_fogze5: Copied (server-side copy) to: potato2.rclone_chunk.002
2021/03/04 05:30:05 INFO  : potato2.rclone_chunk.002_fogze5: Deleted
2021/03/04 05:30:05 INFO  : potato2: Copied (new)
2021/03/04 05:30:05 INFO  : potato2: Deleted
    sync_test.go:1331: 
        	Error Trace:	sync_test.go:1331
        	            				sync_test.go:1420
        	Error:      	Received unexpected error:
        	            	object not found
        	Test:       	TestServerSideMoveDeleteEmptySourceDirs
2021/03/04 05:30:05 DEBUG : Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-junozom5qegowod3wuyuruq5': Purge object "empty space"
2021/03/04 05:30:05 DEBUG : Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-junozom5qegowod3wuyuruq5': Purge object "potato2"
2021/03/04 05:30:05 DEBUG : Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-junozom5qegowod3wuyuruq5': Purge dir ""
2021/03/04 05:30:05 purge failed to rmdir "": BucketNotEmpty: The bucket you tried to delete is not empty
	status code: 409, request id: 98E60A01544CB73A, host id: 1tUN0yf76zWIliwS8g9oyl5szBTJjo7U0KIm1atnEdaV8a5Xxs4RapO8h2npgEaJyMb7p+GA3Zc=
2021/03/04 05:30:05 DEBUG : Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-peqesem6deweron7lahunud1': Purge object "potato3"
2021/03/04 05:30:05 DEBUG : Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-peqesem6deweron7lahunud1': Purge dir ""
2021/03/04 05:30:06 INFO  : S3 bucket rclone-test-peqesem6deweron7lahunud1: Bucket "rclone-test-peqesem6deweron7lahunud1" deleted
--- FAIL: TestServerSideMoveDeleteEmptySourceDirs (7.10s)
FAIL
2021/03/04 05:30:06 DEBUG : Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-taduwiy5foxewum9jotimux5': Purge dir ""
2021/03/04 05:30:06 INFO  : S3 bucket rclone-test-taduwiy5foxewum9jotimux5: Bucket "rclone-test-taduwiy5foxewum9jotimux5" deleted
"./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bMD5QuickS3: -verbose -fast-list -size-limit 1024 -test.run '^TestServerSideMoveDeleteEmptySourceDirs$'" - Finished ERROR in 7.48467441s (try 5/5): exit status 1: Failed [TestServerSideMoveDeleteEmptySourceDirs]