"./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bMD5QuickS3: -verbose -size-limit 1024 -test.run '^TestServerSideMoveDeleteEmptySourceDirs$'" - Starting (try 4/5)
2021/02/08 05:26:31 DEBUG : Creating backend with remote "TestChunkerChunk50bMD5QuickS3:rclone-test-xocapuc0mimivek7seqakus6"
2021/02/08 05:26:31 DEBUG : Creating backend with remote "TestS3:rclone-test-xocapuc0mimivek7seqakus6"
2021/02/08 05:26:31 DEBUG : Reset feature "ListR"
2021/02/08 05:26:31 DEBUG : Creating backend with remote "/tmp/rclone383818407"
=== RUN   TestServerSideMoveDeleteEmptySourceDirs
    run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-xocapuc0mimivek7seqakus6'", Local "Local file system at /tmp/rclone383818407", Modify Window "1ns"
2021/02/08 05:26:31 DEBUG : Creating backend with remote "TestChunkerChunk50bMD5QuickS3:rclone-test-ginucav8bikebiw7rixolat5"
2021/02/08 05:26:31 DEBUG : Creating backend with remote "TestS3:rclone-test-ginucav8bikebiw7rixolat5"
2021/02/08 05:26:31 DEBUG : Reset feature "ListR"
2021/02/08 05:26:32 INFO  : S3 bucket rclone-test-xocapuc0mimivek7seqakus6: Bucket "rclone-test-xocapuc0mimivek7seqakus6" created with ACL "private"
2021/02/08 05:26:32 DEBUG : potato2.rclone_chunk.001_78awtz: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2021/02/08 05:26:32 INFO  : potato2.rclone_chunk.001_78awtz: Copied (server-side copy) to: potato2.rclone_chunk.001
2021/02/08 05:26:32 INFO  : potato2.rclone_chunk.001_78awtz: Deleted
2021/02/08 05:26:32 DEBUG : potato2.rclone_chunk.002_78awtz: MD5 = 01a6651a94ab602dbfc47587d234b52c OK
2021/02/08 05:26:32 INFO  : potato2.rclone_chunk.002_78awtz: Copied (server-side copy) to: potato2.rclone_chunk.002
2021/02/08 05:26:32 INFO  : potato2.rclone_chunk.002_78awtz: Deleted
2021/02/08 05:26:33 DEBUG : empty space.rclone_chunk.001_78aw0v: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK
2021/02/08 05:26:33 INFO  : empty space.rclone_chunk.001_78aw0v: Copied (server-side copy) to: empty space
2021/02/08 05:26:33 INFO  : empty space.rclone_chunk.001_78aw0v: Deleted
2021/02/08 05:26:33 DEBUG : potato3.rclone_chunk.001_78axdo: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2021/02/08 05:26:33 INFO  : potato3.rclone_chunk.001_78axdo: Copied (server-side copy) to: potato3.rclone_chunk.001
2021/02/08 05:26:33 INFO  : potato3.rclone_chunk.001_78axdo: Deleted
2021/02/08 05:26:33 DEBUG : potato3.rclone_chunk.002_78axdo: MD5 = 111c387f2584838efb7e0ec6f2fa2e10 OK
2021/02/08 05:26:33 INFO  : potato3.rclone_chunk.002_78axdo: Copied (server-side copy) to: potato3.rclone_chunk.002
2021/02/08 05:26:33 INFO  : potato3.rclone_chunk.002_78axdo: Deleted
2021/02/08 05:26:33 DEBUG : tomatoDir: Making directory
    sync_test.go:1294: Server side move (if possible) Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-xocapuc0mimivek7seqakus6' -> Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-ginucav8bikebiw7rixolat5'
2021/02/08 05:26:34 INFO  : S3 bucket rclone-test-ginucav8bikebiw7rixolat5: Bucket "rclone-test-ginucav8bikebiw7rixolat5" created with ACL "private"
2021/02/08 05:26:34 DEBUG : empty space.rclone_chunk.001_78ayyc: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK
2021/02/08 05:26:34 INFO  : empty space.rclone_chunk.001_78ayyc: Copied (server-side copy) to: empty space
2021/02/08 05:26:34 INFO  : empty space.rclone_chunk.001_78ayyc: Deleted
2021/02/08 05:26:34 DEBUG : potato3.rclone_chunk.001_78aymn: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2021/02/08 05:26:34 INFO  : potato3.rclone_chunk.001_78aymn: Copied (server-side copy) to: potato3.rclone_chunk.001
2021/02/08 05:26:34 INFO  : potato3.rclone_chunk.001_78aymn: Deleted
2021/02/08 05:26:34 DEBUG : potato3.rclone_chunk.002_78aymn: MD5 = 01a6651a94ab602dbfc47587d234b52c OK
2021/02/08 05:26:34 INFO  : potato3.rclone_chunk.002_78aymn: Copied (server-side copy) to: potato3.rclone_chunk.002
2021/02/08 05:26:34 INFO  : potato3.rclone_chunk.002_78aymn: Deleted
2021/02/08 05:26:35 DEBUG : potato3: Sizes differ (src 68 vs dst 60)
2021/02/08 05:26:35 DEBUG : Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-ginucav8bikebiw7rixolat5': Waiting for checks to finish
2021/02/08 05:26:35 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/02/08 05:26:35 DEBUG : empty space: Unchanged skipping
2021/02/08 05:26:35 DEBUG : potato2: Can't copy - required hash not found
2021/02/08 05:26:35 DEBUG : potato3: Can't copy - required hash not found
2021/02/08 05:26:35 INFO  : empty space: Deleted
2021/02/08 05:26:35 DEBUG : Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-ginucav8bikebiw7rixolat5': Waiting for transfers to finish
2021/02/08 05:26:35 DEBUG : potato2.rclone_chunk.001_78azzr: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2021/02/08 05:26:35 INFO  : potato2.rclone_chunk.001_78azzr: Copied (server-side copy) to: potato2.rclone_chunk.001
2021/02/08 05:26:35 INFO  : potato2.rclone_chunk.001_78azzr: Deleted
2021/02/08 05:26:35 DEBUG : potato2.rclone_chunk.002_78azzr: MD5 = 01a6651a94ab602dbfc47587d234b52c OK
2021/02/08 05:26:35 INFO  : potato2.rclone_chunk.002_78azzr: Copied (server-side copy) to: potato2.rclone_chunk.002
2021/02/08 05:26:35 INFO  : potato2.rclone_chunk.002_78azzr: Deleted
2021/02/08 05:26:35 DEBUG : potato3.rclone_chunk.001_78az47: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2021/02/08 05:26:35 INFO  : potato3.rclone_chunk.001_78az47: Copied (server-side copy) to: potato3.rclone_chunk.001
2021/02/08 05:26:35 INFO  : potato2: Copied (new)
2021/02/08 05:26:35 INFO  : potato3.rclone_chunk.001_78az47: Deleted
2021/02/08 05:26:35 INFO  : potato2: Deleted
2021/02/08 05:26:35 DEBUG : potato3.rclone_chunk.002_78az47: MD5 = 111c387f2584838efb7e0ec6f2fa2e10 OK
2021/02/08 05:26:35 INFO  : potato3.rclone_chunk.002_78az47: Copied (server-side copy) to: potato3.rclone_chunk.002
2021/02/08 05:26:35 INFO  : potato3.rclone_chunk.002_78az47: Deleted
2021/02/08 05:26:35 INFO  : potato3: Copied (replaced existing)
2021/02/08 05:26:35 INFO  : potato3: Deleted
2021/02/08 05:26:36 DEBUG : Creating backend with remote "TestChunkerChunk50bMD5QuickS3:rclone-test-mihofaq2sezupij8vixakir0"
2021/02/08 05:26:36 DEBUG : Creating backend with remote "TestS3:rclone-test-mihofaq2sezupij8vixakir0"
2021/02/08 05:26:36 DEBUG : Reset feature "ListR"
2021/02/08 05:26:36 DEBUG : tomatoDir: Making directory
2021/02/08 05:26:36 DEBUG : empty space: copy non-chunked object...
2021/02/08 05:26:36 DEBUG : Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-mihofaq2sezupij8vixakir0': Waiting for checks to finish
2021/02/08 05:26:36 DEBUG : Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-mihofaq2sezupij8vixakir0': Waiting for transfers to finish
2021/02/08 05:26:36 DEBUG : potato3: Can't copy - required hash not found
2021/02/08 05:26:36 DEBUG : potato2: Can't copy - required hash not found
2021/02/08 05:26:36 INFO  : S3 bucket rclone-test-mihofaq2sezupij8vixakir0: Bucket "rclone-test-mihofaq2sezupij8vixakir0" created with ACL "private"
2021/02/08 05:26:36 INFO  : empty space: Copied (server-side copy)
2021/02/08 05:26:36 INFO  : empty space: Deleted
2021/02/08 05:26:37 DEBUG : potato3.rclone_chunk.001_78b0f6: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2021/02/08 05:26:37 INFO  : potato3.rclone_chunk.001_78b0f6: Copied (server-side copy) to: potato3.rclone_chunk.001
2021/02/08 05:26:37 DEBUG : potato2.rclone_chunk.001_78b0hf: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2021/02/08 05:26:37 INFO  : potato2.rclone_chunk.001_78b0hf: Copied (server-side copy) to: potato2.rclone_chunk.001
2021/02/08 05:26:37 INFO  : potato3.rclone_chunk.001_78b0f6: Deleted
2021/02/08 05:26:37 INFO  : potato2.rclone_chunk.001_78b0hf: Deleted
2021/02/08 05:26:37 DEBUG : potato3.rclone_chunk.002_78b0f6: MD5 = 111c387f2584838efb7e0ec6f2fa2e10 OK
2021/02/08 05:26:37 INFO  : potato3.rclone_chunk.002_78b0f6: Copied (server-side copy) to: potato3.rclone_chunk.002
2021/02/08 05:26:37 DEBUG : potato2.rclone_chunk.002_78b0hf: MD5 = 01a6651a94ab602dbfc47587d234b52c OK
2021/02/08 05:26:37 INFO  : potato2.rclone_chunk.002_78b0hf: Copied (server-side copy) to: potato2.rclone_chunk.002
2021/02/08 05:26:37 INFO  : potato3.rclone_chunk.002_78b0f6: Deleted
2021/02/08 05:26:37 INFO  : potato2.rclone_chunk.002_78b0hf: Deleted
2021/02/08 05:26:37 INFO  : potato3: Copied (new)
2021/02/08 05:26:37 INFO  : potato2: Copied (new)
2021/02/08 05:26:37 INFO  : potato3: Deleted
2021/02/08 05:26:37 INFO  : potato2: Deleted
2021/02/08 05:26:37 DEBUG : Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-mihofaq2sezupij8vixakir0': Purge object "empty space"
2021/02/08 05:26:37 DEBUG : Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-mihofaq2sezupij8vixakir0': Purge object "potato2"
2021/02/08 05:26:37 DEBUG : Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-mihofaq2sezupij8vixakir0': Purge object "potato3"
2021/02/08 05:26:37 DEBUG : Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-mihofaq2sezupij8vixakir0': Purge dir ""
2021/02/08 05:26:38 INFO  : S3 bucket rclone-test-mihofaq2sezupij8vixakir0: Bucket "rclone-test-mihofaq2sezupij8vixakir0" deleted
2021/02/08 05:26:38 DEBUG : Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-ginucav8bikebiw7rixolat5': Purge dir ""
2021/02/08 05:26:38 INFO  : S3 bucket rclone-test-ginucav8bikebiw7rixolat5: Bucket "rclone-test-ginucav8bikebiw7rixolat5" deleted
--- PASS: TestServerSideMoveDeleteEmptySourceDirs (7.38s)
PASS
2021/02/08 05:26:38 DEBUG : Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-xocapuc0mimivek7seqakus6': Purge dir ""
2021/02/08 05:26:38 INFO  : S3 bucket rclone-test-xocapuc0mimivek7seqakus6: Bucket "rclone-test-xocapuc0mimivek7seqakus6" deleted
"./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bMD5QuickS3: -verbose -size-limit 1024 -test.run '^TestServerSideMoveDeleteEmptySourceDirs$'" - Finished OK in 7.737416277s (try 4/5)