"./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bSHA1QuickS3: -verbose -fast-list -size-limit 1024 -test.run '^TestServerSideMoveDeleteEmptySourceDirs$'" - Starting (try 5/5)
2020/12/12 06:02:32 DEBUG : Creating backend with remote "TestChunkerChunk50bSHA1QuickS3:rclone-test-wogerok5sutifab8bevasiy5"
2020/12/12 06:02:32 DEBUG : Creating backend with remote "TestS3:rclone-test-wogerok5sutifab8bevasiy5"
2020/12/12 06:02:32 DEBUG : Reset feature "ListR"
2020/12/12 06:02:32 DEBUG : Creating backend with remote "/tmp/rclone330813614"
=== RUN   TestServerSideMoveDeleteEmptySourceDirs
    run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-wogerok5sutifab8bevasiy5'", Local "Local file system at /tmp/rclone330813614", Modify Window "1ns"
2020/12/12 06:02:32 DEBUG : Creating backend with remote "TestChunkerChunk50bSHA1QuickS3:rclone-test-rerakus8xugucaq5caqutec5"
2020/12/12 06:02:32 DEBUG : Creating backend with remote "TestS3:rclone-test-rerakus8xugucaq5caqutec5"
2020/12/12 06:02:32 DEBUG : Reset feature "ListR"
2020/12/12 06:02:33 INFO  : S3 bucket rclone-test-wogerok5sutifab8bevasiy5: Bucket "rclone-test-wogerok5sutifab8bevasiy5" created with ACL "private"
2020/12/12 06:02:33 DEBUG : potato2.rclone_chunk.001_7vac97: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2020/12/12 06:02:33 INFO  : potato2.rclone_chunk.001_7vac97: Copied (server-side copy) to: potato2.rclone_chunk.001
2020/12/12 06:02:33 INFO  : potato2.rclone_chunk.001_7vac97: Deleted
2020/12/12 06:02:33 DEBUG : potato2.rclone_chunk.002_7vac97: MD5 = 01a6651a94ab602dbfc47587d234b52c OK
2020/12/12 06:02:33 INFO  : potato2.rclone_chunk.002_7vac97: Copied (server-side copy) to: potato2.rclone_chunk.002
2020/12/12 06:02:33 INFO  : potato2.rclone_chunk.002_7vac97: Deleted
2020/12/12 06:02:33 DEBUG : empty space.rclone_chunk.001_7vac2g: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK
2020/12/12 06:02:33 INFO  : empty space.rclone_chunk.001_7vac2g: Copied (server-side copy) to: empty space
2020/12/12 06:02:33 INFO  : empty space.rclone_chunk.001_7vac2g: Deleted
2020/12/12 06:02:34 DEBUG : potato3.rclone_chunk.001_7vacwp: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2020/12/12 06:02:34 INFO  : potato3.rclone_chunk.001_7vacwp: Copied (server-side copy) to: potato3.rclone_chunk.001
2020/12/12 06:02:34 INFO  : potato3.rclone_chunk.001_7vacwp: Deleted
2020/12/12 06:02:34 DEBUG : potato3.rclone_chunk.002_7vacwp: MD5 = 111c387f2584838efb7e0ec6f2fa2e10 OK
2020/12/12 06:02:34 INFO  : potato3.rclone_chunk.002_7vacwp: Copied (server-side copy) to: potato3.rclone_chunk.002
2020/12/12 06:02:34 INFO  : potato3.rclone_chunk.002_7vacwp: Deleted
2020/12/12 06:02:34 DEBUG : tomatoDir: Making directory
    sync_test.go:1294: Server side move (if possible) Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-wogerok5sutifab8bevasiy5' -> Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-rerakus8xugucaq5caqutec5'
2020/12/12 06:02:34 INFO  : S3 bucket rclone-test-rerakus8xugucaq5caqutec5: Bucket "rclone-test-rerakus8xugucaq5caqutec5" created with ACL "private"
2020/12/12 06:02:35 DEBUG : empty space.rclone_chunk.001_7vadzt: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK
2020/12/12 06:02:35 INFO  : empty space.rclone_chunk.001_7vadzt: Copied (server-side copy) to: empty space
2020/12/12 06:02:35 INFO  : empty space.rclone_chunk.001_7vadzt: Deleted
2020/12/12 06:02:35 DEBUG : potato3.rclone_chunk.001_7vae1g: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2020/12/12 06:02:35 INFO  : potato3.rclone_chunk.001_7vae1g: Copied (server-side copy) to: potato3.rclone_chunk.001
2020/12/12 06:02:35 INFO  : potato3.rclone_chunk.001_7vae1g: Deleted
2020/12/12 06:02:35 DEBUG : potato3.rclone_chunk.002_7vae1g: MD5 = 01a6651a94ab602dbfc47587d234b52c OK
2020/12/12 06:02:35 INFO  : potato3.rclone_chunk.002_7vae1g: Copied (server-side copy) to: potato3.rclone_chunk.002
2020/12/12 06:02:35 INFO  : potato3.rclone_chunk.002_7vae1g: Deleted
2020/12/12 06:02:35 DEBUG : potato3: Sizes differ (src 68 vs dst 60)
2020/12/12 06:02:35 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-rerakus8xugucaq5caqutec5': Waiting for checks to finish
2020/12/12 06:02:35 DEBUG : potato2: Can't copy - required hash not found
2020/12/12 06:02:35 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2020/12/12 06:02:35 DEBUG : empty space: Unchanged skipping
2020/12/12 06:02:35 INFO  : empty space: Deleted
2020/12/12 06:02:35 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-rerakus8xugucaq5caqutec5': Waiting for transfers to finish
2020/12/12 06:02:35 DEBUG : potato3: Can't copy - required hash not found
2020/12/12 06:02:35 DEBUG : potato2.rclone_chunk.001_7vae56: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2020/12/12 06:02:35 INFO  : potato2.rclone_chunk.001_7vae56: Copied (server-side copy) to: potato2.rclone_chunk.001
2020/12/12 06:02:35 INFO  : potato2.rclone_chunk.001_7vae56: Deleted
2020/12/12 06:02:36 DEBUG : potato2.rclone_chunk.002_7vae56: MD5 = 01a6651a94ab602dbfc47587d234b52c OK
2020/12/12 06:02:36 INFO  : potato2.rclone_chunk.002_7vae56: Copied (server-side copy) to: potato2.rclone_chunk.002
2020/12/12 06:02:36 INFO  : potato2.rclone_chunk.002_7vae56: Deleted
2020/12/12 06:02:36 DEBUG : potato3.rclone_chunk.001_7vaeub: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2020/12/12 06:02:36 INFO  : potato3.rclone_chunk.001_7vaeub: Copied (server-side copy) to: potato3.rclone_chunk.001
2020/12/12 06:02:36 INFO  : potato3.rclone_chunk.001_7vaeub: Deleted
2020/12/12 06:02:36 INFO  : potato2: Copied (new)
2020/12/12 06:02:36 DEBUG : potato3.rclone_chunk.002_7vaeub: MD5 = 111c387f2584838efb7e0ec6f2fa2e10 OK
2020/12/12 06:02:36 INFO  : potato3.rclone_chunk.002_7vaeub: Copied (server-side copy) to: potato3.rclone_chunk.002
2020/12/12 06:02:36 INFO  : potato3.rclone_chunk.002_7vaeub: Deleted
2020/12/12 06:02:36 INFO  : potato2: Deleted
2020/12/12 06:02:36 INFO  : potato3: Copied (replaced existing)
2020/12/12 06:02:36 INFO  : potato3: Deleted
2020/12/12 06:02:36 DEBUG : Creating backend with remote "TestChunkerChunk50bSHA1QuickS3:rclone-test-welaput5murotey2turatod5"
2020/12/12 06:02:36 DEBUG : Creating backend with remote "TestS3:rclone-test-welaput5murotey2turatod5"
2020/12/12 06:02:36 DEBUG : Reset feature "ListR"
2020/12/12 06:02:36 DEBUG : tomatoDir: Making directory
2020/12/12 06:02:36 DEBUG : empty space: copy non-chunked object...
2020/12/12 06:02:36 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-welaput5murotey2turatod5': Waiting for checks to finish
2020/12/12 06:02:36 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-welaput5murotey2turatod5': Waiting for transfers to finish
2020/12/12 06:02:36 DEBUG : potato3: Can't copy - required hash not found
2020/12/12 06:02:36 DEBUG : potato2: Can't copy - required hash not found
2020/12/12 06:02:37 INFO  : S3 bucket rclone-test-welaput5murotey2turatod5: Bucket "rclone-test-welaput5murotey2turatod5" created with ACL "private"
2020/12/12 06:02:37 INFO  : empty space: Copied (server-side copy)
2020/12/12 06:02:37 INFO  : empty space: Deleted
2020/12/12 06:02:37 DEBUG : potato3.rclone_chunk.001_7vafca: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2020/12/12 06:02:37 INFO  : potato3.rclone_chunk.001_7vafca: Copied (server-side copy) to: potato3.rclone_chunk.001
2020/12/12 06:02:37 INFO  : potato3.rclone_chunk.001_7vafca: Deleted
2020/12/12 06:02:37 DEBUG : potato2.rclone_chunk.001_7vafau: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2020/12/12 06:02:37 INFO  : potato2.rclone_chunk.001_7vafau: Copied (server-side copy) to: potato2.rclone_chunk.001
2020/12/12 06:02:37 DEBUG : potato3.rclone_chunk.002_7vafca: MD5 = 111c387f2584838efb7e0ec6f2fa2e10 OK
2020/12/12 06:02:37 INFO  : potato3.rclone_chunk.002_7vafca: Copied (server-side copy) to: potato3.rclone_chunk.002
2020/12/12 06:02:37 INFO  : potato2.rclone_chunk.001_7vafau: Deleted
2020/12/12 06:02:37 INFO  : potato3.rclone_chunk.002_7vafca: Deleted
2020/12/12 06:02:37 DEBUG : potato2.rclone_chunk.002_7vafau: MD5 = 01a6651a94ab602dbfc47587d234b52c OK
2020/12/12 06:02:37 INFO  : potato2.rclone_chunk.002_7vafau: Copied (server-side copy) to: potato2.rclone_chunk.002
2020/12/12 06:02:37 INFO  : potato3: Copied (new)
2020/12/12 06:02:37 INFO  : potato2.rclone_chunk.002_7vafau: Deleted
2020/12/12 06:02:37 INFO  : potato2: Copied (new)
2020/12/12 06:02:37 INFO  : potato3: Deleted
2020/12/12 06:02:37 INFO  : potato2: Deleted
2020/12/12 06:02:37 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-welaput5murotey2turatod5': Purge object "empty space"
2020/12/12 06:02:37 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-welaput5murotey2turatod5': Purge object "potato2"
2020/12/12 06:02:37 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-welaput5murotey2turatod5': Purge object "potato3"
2020/12/12 06:02:37 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-welaput5murotey2turatod5': Purge dir ""
2020/12/12 06:02:38 INFO  : S3 bucket rclone-test-welaput5murotey2turatod5: Bucket "rclone-test-welaput5murotey2turatod5" deleted
2020/12/12 06:02:38 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-rerakus8xugucaq5caqutec5': Purge dir ""
2020/12/12 06:02:38 INFO  : S3 bucket rclone-test-rerakus8xugucaq5caqutec5: Bucket "rclone-test-rerakus8xugucaq5caqutec5" deleted
--- PASS: TestServerSideMoveDeleteEmptySourceDirs (6.09s)
PASS
2020/12/12 06:02:38 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-wogerok5sutifab8bevasiy5': Purge dir ""
2020/12/12 06:02:38 INFO  : S3 bucket rclone-test-wogerok5sutifab8bevasiy5: Bucket "rclone-test-wogerok5sutifab8bevasiy5" deleted
"./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bSHA1QuickS3: -verbose -fast-list -size-limit 1024 -test.run '^TestServerSideMoveDeleteEmptySourceDirs$'" - Finished OK in 6.391111549s (try 5/5)