"./sync.test -test.v -test.timeout 30m0s -remote TestChunkerChunk50bMD5QuickS3: -verbose -fast-list -size-limit 1024 -test.run '^TestServerSideMoveWithFilter$'" - Starting (try 2/5)
=== RUN   TestServerSideMoveWithFilter
2020/01/19 05:55:01 INFO  : S3 bucket rclone-test-vajazuc2lebiquc2tayireh8: Bucket "rclone-test-vajazuc2lebiquc2tayireh8" created with ACL "private"
2020/01/19 05:55:01 DEBUG : potato2.rclone_chunk.001_cg6huu: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2020/01/19 05:55:01 INFO  : potato2.rclone_chunk.001_cg6huu: Copied (server side copy)
2020/01/19 05:55:01 INFO  : potato2.rclone_chunk.001_cg6huu: Deleted
2020/01/19 05:55:01 DEBUG : potato2.rclone_chunk.002_cg6huu: MD5 = 01a6651a94ab602dbfc47587d234b52c OK
2020/01/19 05:55:01 INFO  : potato2.rclone_chunk.002_cg6huu: Copied (server side copy)
2020/01/19 05:55:01 INFO  : potato2.rclone_chunk.002_cg6huu: Deleted
2020/01/19 05:55:02 DEBUG : empty space.rclone_chunk.001_cg6idc: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK
2020/01/19 05:55:02 INFO  : empty space.rclone_chunk.001_cg6idc: Copied (server side copy)
2020/01/19 05:55:02 INFO  : empty space.rclone_chunk.001_cg6idc: Deleted
2020/01/19 05:55:02 DEBUG : potato3.rclone_chunk.001_cg6itg: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2020/01/19 05:55:02 INFO  : potato3.rclone_chunk.001_cg6itg: Copied (server side copy)
2020/01/19 05:55:02 INFO  : potato3.rclone_chunk.001_cg6itg: Deleted
2020/01/19 05:55:02 DEBUG : potato3.rclone_chunk.002_cg6itg: MD5 = 111c387f2584838efb7e0ec6f2fa2e10 OK
2020/01/19 05:55:02 INFO  : potato3.rclone_chunk.002_cg6itg: Copied (server side copy)
2020/01/19 05:55:02 INFO  : potato3.rclone_chunk.002_cg6itg: Deleted
2020/01/19 05:55:03 INFO  : S3 bucket rclone-test-ziwijes6banepeh7wofutom5: Bucket "rclone-test-ziwijes6banepeh7wofutom5" created with ACL "private"
2020/01/19 05:55:03 DEBUG : empty space.rclone_chunk.001_cg6jat: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK
2020/01/19 05:55:03 INFO  : empty space.rclone_chunk.001_cg6jat: Copied (server side copy)
2020/01/19 05:55:03 INFO  : empty space.rclone_chunk.001_cg6jat: Deleted
2020/01/19 05:55:04 DEBUG : potato3.rclone_chunk.001_cg6jei: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2020/01/19 05:55:04 INFO  : potato3.rclone_chunk.001_cg6jei: Copied (server side copy)
2020/01/19 05:55:04 INFO  : potato3.rclone_chunk.001_cg6jei: Deleted
2020/01/19 05:55:04 DEBUG : potato3.rclone_chunk.002_cg6jei: MD5 = 01a6651a94ab602dbfc47587d234b52c OK
2020/01/19 05:55:04 INFO  : potato3.rclone_chunk.002_cg6jei: Copied (server side copy)
2020/01/19 05:55:04 INFO  : potato3.rclone_chunk.002_cg6jei: Deleted
2020/01/19 05:55:04 DEBUG : empty space: Excluded from sync (and deletion)
2020/01/19 05:55:04 DEBUG : empty space: Excluded from sync (and deletion)
2020/01/19 05:55:04 INFO  : Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-ziwijes6banepeh7wofutom5': Waiting for checks to finish
2020/01/19 05:55:04 DEBUG : potato3: Sizes differ (src 68 vs dst 60)
2020/01/19 05:55:04 INFO  : Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-ziwijes6banepeh7wofutom5': Waiting for transfers to finish
2020/01/19 05:55:04 DEBUG : potato2: Can't copy - required hash not found
2020/01/19 05:55:04 DEBUG : potato3: Can't copy - required hash not found
2020/01/19 05:55:04 DEBUG : potato2.rclone_chunk.001_cg6kdq: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2020/01/19 05:55:04 INFO  : potato2.rclone_chunk.001_cg6kdq: Copied (server side copy)
2020/01/19 05:55:04 INFO  : potato2.rclone_chunk.001_cg6kdq: Deleted
2020/01/19 05:55:04 DEBUG : potato2.rclone_chunk.002_cg6kdq: MD5 = 01a6651a94ab602dbfc47587d234b52c OK
2020/01/19 05:55:04 INFO  : potato2.rclone_chunk.002_cg6kdq: Copied (server side copy)
2020/01/19 05:55:04 INFO  : potato2.rclone_chunk.002_cg6kdq: Deleted
2020/01/19 05:55:04 INFO  : potato2: Copied (new)
2020/01/19 05:55:04 INFO  : potato2: Deleted
2020/01/19 05:55:04 DEBUG : potato3.rclone_chunk.001_cg6kcx: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2020/01/19 05:55:04 INFO  : potato3.rclone_chunk.001_cg6kcx: Copied (server side copy)
2020/01/19 05:55:04 INFO  : potato3.rclone_chunk.001_cg6kcx: Deleted
2020/01/19 05:55:04 DEBUG : potato3.rclone_chunk.002_cg6kcx: MD5 = 111c387f2584838efb7e0ec6f2fa2e10 OK
2020/01/19 05:55:04 INFO  : potato3.rclone_chunk.002_cg6kcx: Copied (server side copy)
2020/01/19 05:55:04 INFO  : potato3.rclone_chunk.002_cg6kcx: Deleted
2020/01/19 05:55:04 INFO  : potato3: Copied (replaced existing)
2020/01/19 05:55:04 INFO  : potato3: Deleted
2020/01/19 05:55:04 DEBUG : empty space: Excluded from sync (and deletion)
2020/01/19 05:55:05 INFO  : Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-yeyacol3zakerud4dofiwaq8': Waiting for checks to finish
2020/01/19 05:55:05 INFO  : Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-yeyacol3zakerud4dofiwaq8': Waiting for transfers to finish
2020/01/19 05:55:05 DEBUG : potato2: Can't copy - required hash not found
2020/01/19 05:55:05 DEBUG : potato3: Can't copy - required hash not found
2020/01/19 05:55:05 INFO  : S3 bucket rclone-test-yeyacol3zakerud4dofiwaq8: Bucket "rclone-test-yeyacol3zakerud4dofiwaq8" created with ACL "private"
2020/01/19 05:55:06 DEBUG : potato3.rclone_chunk.001_cg6l4k: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2020/01/19 05:55:06 INFO  : potato3.rclone_chunk.001_cg6l4k: Copied (server side copy)
2020/01/19 05:55:06 DEBUG : potato2.rclone_chunk.001_cg6l6d: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2020/01/19 05:55:06 INFO  : potato2.rclone_chunk.001_cg6l6d: Copied (server side copy)
2020/01/19 05:55:06 INFO  : potato2.rclone_chunk.001_cg6l6d: Deleted
2020/01/19 05:55:06 INFO  : potato3.rclone_chunk.001_cg6l4k: Deleted
2020/01/19 05:55:06 DEBUG : potato3.rclone_chunk.002_cg6l4k: MD5 = 111c387f2584838efb7e0ec6f2fa2e10 OK
2020/01/19 05:55:06 INFO  : potato3.rclone_chunk.002_cg6l4k: Copied (server side copy)
2020/01/19 05:55:06 DEBUG : potato2.rclone_chunk.002_cg6l6d: MD5 = 01a6651a94ab602dbfc47587d234b52c OK
2020/01/19 05:55:06 INFO  : potato2.rclone_chunk.002_cg6l6d: Copied (server side copy)
2020/01/19 05:55:06 INFO  : potato3.rclone_chunk.002_cg6l4k: Deleted
2020/01/19 05:55:06 INFO  : potato2.rclone_chunk.002_cg6l6d: Deleted
2020/01/19 05:55:06 INFO  : potato3: Copied (new)
2020/01/19 05:55:06 INFO  : potato2: Copied (new)
2020/01/19 05:55:06 INFO  : potato3: Deleted
2020/01/19 05:55:06 INFO  : potato2: Deleted
2020/01/19 05:55:06 DEBUG : Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-yeyacol3zakerud4dofiwaq8': Purge object "potato2"
2020/01/19 05:55:06 DEBUG : Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-yeyacol3zakerud4dofiwaq8': Purge object "potato3"
2020/01/19 05:55:06 DEBUG : Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-yeyacol3zakerud4dofiwaq8': Purge dir ""
2020/01/19 05:55:06 INFO  : S3 bucket rclone-test-yeyacol3zakerud4dofiwaq8: Bucket "rclone-test-yeyacol3zakerud4dofiwaq8" deleted
2020/01/19 05:55:06 DEBUG : Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-ziwijes6banepeh7wofutom5': Purge object "empty space"
2020/01/19 05:55:06 DEBUG : Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-ziwijes6banepeh7wofutom5': Purge dir ""
2020/01/19 05:55:07 INFO  : S3 bucket rclone-test-ziwijes6banepeh7wofutom5: Bucket "rclone-test-ziwijes6banepeh7wofutom5" deleted
--- PASS: TestServerSideMoveWithFilter (6.16s)
    run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-vajazuc2lebiquc2tayireh8'", Local "Local file system at /tmp/rclone104132722", Modify Window "1ns"
    sync_test.go:1110: Server side move (if possible) Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-vajazuc2lebiquc2tayireh8' -> Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-ziwijes6banepeh7wofutom5'
PASS
2020/01/19 05:55:07 DEBUG : Chunked 'TestChunkerChunk50bMD5QuickS3:rclone-test-vajazuc2lebiquc2tayireh8': Purge dir ""
2020/01/19 05:55:07 INFO  : S3 bucket rclone-test-vajazuc2lebiquc2tayireh8: Bucket "rclone-test-vajazuc2lebiquc2tayireh8" deleted
"./sync.test -test.v -test.timeout 30m0s -remote TestChunkerChunk50bMD5QuickS3: -verbose -fast-list -size-limit 1024 -test.run '^TestServerSideMoveWithFilter$'" - Finished OK in 6.554273295s (try 2/5)