"./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bSHA1QuickS3: -verbose -size-limit 1024 -test.run '^TestServerSideMoveDeleteEmptySourceDirs$'" - Starting (try 5/5)
2021/03/04 05:39:06 DEBUG : Creating backend with remote "TestChunkerChunk50bSHA1QuickS3:rclone-test-vupural6qehuhab2darigey0"
2021/03/04 05:39:06 DEBUG : Creating backend with remote "TestS3:rclone-test-vupural6qehuhab2darigey0"
2021/03/04 05:39:06 DEBUG : Reset feature "ListR"
2021/03/04 05:39:06 DEBUG : Creating backend with remote "/tmp/rclone457940571"
=== RUN   TestServerSideMoveDeleteEmptySourceDirs
    run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-vupural6qehuhab2darigey0'", Local "Local file system at /tmp/rclone457940571", Modify Window "1ns"
2021/03/04 05:39:06 DEBUG : Creating backend with remote "TestChunkerChunk50bSHA1QuickS3:rclone-test-biciseh3teyedas2wupenoc9"
2021/03/04 05:39:06 DEBUG : Creating backend with remote "TestS3:rclone-test-biciseh3teyedas2wupenoc9"
2021/03/04 05:39:06 DEBUG : Reset feature "ListR"
2021/03/04 05:39:07 INFO  : S3 bucket rclone-test-vupural6qehuhab2darigey0: Bucket "rclone-test-vupural6qehuhab2darigey0" created with ACL "private"
2021/03/04 05:39:07 DEBUG : potato2.rclone_chunk.001_fow2f8: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2021/03/04 05:39:07 INFO  : potato2.rclone_chunk.001_fow2f8: Copied (server-side copy) to: potato2.rclone_chunk.001
2021/03/04 05:39:07 INFO  : potato2.rclone_chunk.001_fow2f8: Deleted
2021/03/04 05:39:08 DEBUG : potato2.rclone_chunk.002_fow2f8: MD5 = 01a6651a94ab602dbfc47587d234b52c OK
2021/03/04 05:39:08 INFO  : potato2.rclone_chunk.002_fow2f8: Copied (server-side copy) to: potato2.rclone_chunk.002
2021/03/04 05:39:08 INFO  : potato2.rclone_chunk.002_fow2f8: Deleted
2021/03/04 05:39:08 DEBUG : empty space.rclone_chunk.001_fow3to: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK
2021/03/04 05:39:08 INFO  : empty space.rclone_chunk.001_fow3to: Copied (server-side copy) to: empty space
2021/03/04 05:39:08 INFO  : empty space.rclone_chunk.001_fow3to: Deleted
2021/03/04 05:39:08 DEBUG : potato3.rclone_chunk.001_fow3lb: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2021/03/04 05:39:08 INFO  : potato3.rclone_chunk.001_fow3lb: Copied (server-side copy) to: potato3.rclone_chunk.001
2021/03/04 05:39:08 INFO  : potato3.rclone_chunk.001_fow3lb: Deleted
2021/03/04 05:39:08 DEBUG : potato3.rclone_chunk.002_fow3lb: MD5 = 111c387f2584838efb7e0ec6f2fa2e10 OK
2021/03/04 05:39:08 INFO  : potato3.rclone_chunk.002_fow3lb: Copied (server-side copy) to: potato3.rclone_chunk.002
2021/03/04 05:39:08 INFO  : potato3.rclone_chunk.002_fow3lb: Deleted
2021/03/04 05:39:08 DEBUG : tomatoDir: Making directory
    sync_test.go:1294: Server side move (if possible) Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-vupural6qehuhab2darigey0' -> Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-biciseh3teyedas2wupenoc9'
2021/03/04 05:39:09 INFO  : S3 bucket rclone-test-biciseh3teyedas2wupenoc9: Bucket "rclone-test-biciseh3teyedas2wupenoc9" created with ACL "private"
2021/03/04 05:39:09 DEBUG : empty space.rclone_chunk.001_fow4ml: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK
2021/03/04 05:39:09 INFO  : empty space.rclone_chunk.001_fow4ml: Copied (server-side copy) to: empty space
2021/03/04 05:39:09 INFO  : empty space.rclone_chunk.001_fow4ml: Deleted
2021/03/04 05:39:10 DEBUG : potato3.rclone_chunk.001_fow40g: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2021/03/04 05:39:10 INFO  : potato3.rclone_chunk.001_fow40g: Copied (server-side copy) to: potato3.rclone_chunk.001
2021/03/04 05:39:10 INFO  : potato3.rclone_chunk.001_fow40g: Deleted
2021/03/04 05:39:10 DEBUG : potato3.rclone_chunk.002_fow40g: MD5 = 01a6651a94ab602dbfc47587d234b52c OK
2021/03/04 05:39:10 INFO  : potato3.rclone_chunk.002_fow40g: Copied (server-side copy) to: potato3.rclone_chunk.002
2021/03/04 05:39:10 INFO  : potato3.rclone_chunk.002_fow40g: Deleted
2021/03/04 05:39:10 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-biciseh3teyedas2wupenoc9': Waiting for checks to finish
2021/03/04 05:39:10 DEBUG : potato3: Sizes differ (src 68 vs dst 60)
2021/03/04 05:39:10 DEBUG : potato2: Can't copy - required hash not found
2021/03/04 05:39:10 DEBUG : potato3: Can't copy - required hash not found
2021/03/04 05:39:10 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/03/04 05:39:10 DEBUG : empty space: Unchanged skipping
2021/03/04 05:39:10 INFO  : empty space: Deleted
2021/03/04 05:39:10 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-biciseh3teyedas2wupenoc9': Waiting for transfers to finish
2021/03/04 05:39:10 DEBUG : potato2.rclone_chunk.001_fow5of: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2021/03/04 05:39:10 INFO  : potato2.rclone_chunk.001_fow5of: Copied (server-side copy) to: potato2.rclone_chunk.001
2021/03/04 05:39:10 INFO  : potato2.rclone_chunk.001_fow5of: Deleted
2021/03/04 05:39:10 DEBUG : potato2.rclone_chunk.002_fow5of: MD5 = 01a6651a94ab602dbfc47587d234b52c OK
2021/03/04 05:39:10 INFO  : potato2.rclone_chunk.002_fow5of: Copied (server-side copy) to: potato2.rclone_chunk.002
2021/03/04 05:39:10 INFO  : potato2.rclone_chunk.002_fow5of: Deleted
2021/03/04 05:39:10 DEBUG : potato3.rclone_chunk.001_fow5o9: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2021/03/04 05:39:10 INFO  : potato3.rclone_chunk.001_fow5o9: Copied (server-side copy) to: potato3.rclone_chunk.001
2021/03/04 05:39:10 INFO  : potato3.rclone_chunk.001_fow5o9: Deleted
2021/03/04 05:39:10 INFO  : potato2: Copied (new)
2021/03/04 05:39:10 DEBUG : potato3.rclone_chunk.002_fow5o9: MD5 = 111c387f2584838efb7e0ec6f2fa2e10 OK
2021/03/04 05:39:10 INFO  : potato3.rclone_chunk.002_fow5o9: Copied (server-side copy) to: potato3.rclone_chunk.002
2021/03/04 05:39:10 INFO  : potato3.rclone_chunk.002_fow5o9: Deleted
2021/03/04 05:39:10 INFO  : potato2: Deleted
2021/03/04 05:39:10 INFO  : potato3: Copied (replaced existing)
2021/03/04 05:39:11 INFO  : potato3: Deleted
2021/03/04 05:39:11 DEBUG : Creating backend with remote "TestChunkerChunk50bSHA1QuickS3:rclone-test-xafofav5hebiwen7munawax6"
2021/03/04 05:39:11 DEBUG : Creating backend with remote "TestS3:rclone-test-xafofav5hebiwen7munawax6"
2021/03/04 05:39:11 DEBUG : Reset feature "ListR"
2021/03/04 05:39:11 DEBUG : tomatoDir: Making directory
2021/03/04 05:39:11 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-xafofav5hebiwen7munawax6': Waiting for checks to finish
2021/03/04 05:39:11 DEBUG : empty space: copy non-chunked object...
2021/03/04 05:39:11 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-xafofav5hebiwen7munawax6': Waiting for transfers to finish
2021/03/04 05:39:11 DEBUG : potato3: Can't copy - required hash not found
2021/03/04 05:39:11 DEBUG : potato2: Can't copy - required hash not found
2021/03/04 05:39:11 INFO  : S3 bucket rclone-test-xafofav5hebiwen7munawax6: Bucket "rclone-test-xafofav5hebiwen7munawax6" created with ACL "private"
2021/03/04 05:39:11 INFO  : empty space: Copied (server-side copy)
2021/03/04 05:39:11 INFO  : empty space: Deleted
2021/03/04 05:39:12 ERROR : potato2: Failed to copy: object not found
2021/03/04 05:39:12 ERROR : potato2: Not deleting source as copy failed: object not found
2021/03/04 05:39:12 DEBUG : potato3.rclone_chunk.001_fow6zb: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2021/03/04 05:39:12 INFO  : potato3.rclone_chunk.001_fow6zb: Copied (server-side copy) to: potato3.rclone_chunk.001
2021/03/04 05:39:12 INFO  : potato3.rclone_chunk.001_fow6zb: Deleted
2021/03/04 05:39:12 DEBUG : potato3.rclone_chunk.002_fow6zb: MD5 = 111c387f2584838efb7e0ec6f2fa2e10 OK
2021/03/04 05:39:12 INFO  : potato3.rclone_chunk.002_fow6zb: Copied (server-side copy) to: potato3.rclone_chunk.002
2021/03/04 05:39:12 INFO  : potato3.rclone_chunk.002_fow6zb: Deleted
2021/03/04 05:39:12 INFO  : potato3: Copied (new)
2021/03/04 05:39:12 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
2021/03/04 05:39:12 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-xafofav5hebiwen7munawax6': Purge object "empty space"
2021/03/04 05:39:12 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-xafofav5hebiwen7munawax6': Purge object "potato3"
2021/03/04 05:39:12 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-xafofav5hebiwen7munawax6': Purge dir ""
2021/03/04 05:39:12 purge failed to rmdir "": BucketNotEmpty: The bucket you tried to delete is not empty
	status code: 409, request id: 1948FFDBB0F55F02, host id: OLGfLh+1FSUuOGghUkW4py5bTX59ms+v2XCQrJvW7ySi6bplOT0i8GB+XTKQU0DY0WknXBhVBKE=
2021/03/04 05:39:12 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-biciseh3teyedas2wupenoc9': Purge object "potato2"
2021/03/04 05:39:13 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-biciseh3teyedas2wupenoc9': Purge dir ""
2021/03/04 05:39:13 INFO  : S3 bucket rclone-test-biciseh3teyedas2wupenoc9: Bucket "rclone-test-biciseh3teyedas2wupenoc9" deleted
--- FAIL: TestServerSideMoveDeleteEmptySourceDirs (6.63s)
FAIL
2021/03/04 05:39:13 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-vupural6qehuhab2darigey0': Purge dir ""
2021/03/04 05:39:13 INFO  : S3 bucket rclone-test-vupural6qehuhab2darigey0: Bucket "rclone-test-vupural6qehuhab2darigey0" deleted
"./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bSHA1QuickS3: -verbose -size-limit 1024 -test.run '^TestServerSideMoveDeleteEmptySourceDirs$'" - Finished ERROR in 6.965219289s (try 5/5): exit status 1: Failed [TestServerSideMoveDeleteEmptySourceDirs]