"./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bMD5HashS3: -verbose -fast-list -size-limit 1024" - Starting (try 1/5) 2020/12/12 05:32:51 DEBUG : Creating backend with remote "TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7" 2020/12/12 05:32:51 DEBUG : Creating backend with remote "TestS3:rclone-test-kajibaz6yucuyeb5qeradih7" 2020/12/12 05:32:51 DEBUG : Reset feature "ListR" 2020/12/12 05:32:51 DEBUG : Creating backend with remote "/tmp/rclone393843524" === RUN TestPipe --- PASS: TestPipe (0.00s) === RUN TestPipeConcurrent --- PASS: TestPipeConcurrent (0.08s) === RUN TestPipeOrderBy === RUN TestPipeOrderBy/#00 === RUN TestPipeOrderBy/size === RUN TestPipeOrderBy/name === RUN TestPipeOrderBy/modtime === RUN TestPipeOrderBy/size,ascending === RUN TestPipeOrderBy/name,asc === RUN TestPipeOrderBy/modtime,ascending === RUN TestPipeOrderBy/size,descending === RUN TestPipeOrderBy/name,desc === RUN TestPipeOrderBy/modtime,descending === RUN TestPipeOrderBy/size,mixed,50 === RUN TestPipeOrderBy/size,mixed,51 --- PASS: TestPipeOrderBy (0.00s) --- PASS: TestPipeOrderBy/#00 (0.00s) --- PASS: TestPipeOrderBy/size (0.00s) --- PASS: TestPipeOrderBy/name (0.00s) --- PASS: TestPipeOrderBy/modtime (0.00s) --- PASS: TestPipeOrderBy/size,ascending (0.00s) --- PASS: TestPipeOrderBy/name,asc (0.00s) --- PASS: TestPipeOrderBy/modtime,ascending (0.00s) --- PASS: TestPipeOrderBy/size,descending (0.00s) --- PASS: TestPipeOrderBy/name,desc (0.00s) --- PASS: TestPipeOrderBy/modtime,descending (0.00s) --- PASS: TestPipeOrderBy/size,mixed,50 (0.00s) --- PASS: TestPipeOrderBy/size,mixed,51 (0.00s) === RUN TestNewLess === RUN TestNewLess/blankOK === RUN TestNewLess/tooManyParts === RUN TestNewLess/tooManyParts2 === RUN TestNewLess/badMixed === RUN TestNewLess/unknownComparison === RUN TestNewLess/unknownSortDirection === RUN TestNewLess/size === RUN TestNewLess/name === RUN TestNewLess/modtime === RUN TestNewLess/size,ascending === RUN TestNewLess/name,asc === RUN TestNewLess/modtime,ascending === RUN TestNewLess/size,descending === RUN TestNewLess/name,desc === RUN TestNewLess/modtime,descending === RUN TestNewLess/modtime,mixed === RUN TestNewLess/modtime,mixed,30 --- PASS: TestNewLess (0.00s) --- PASS: TestNewLess/blankOK (0.00s) --- PASS: TestNewLess/tooManyParts (0.00s) --- PASS: TestNewLess/tooManyParts2 (0.00s) --- PASS: TestNewLess/badMixed (0.00s) --- PASS: TestNewLess/unknownComparison (0.00s) --- PASS: TestNewLess/unknownSortDirection (0.00s) --- PASS: TestNewLess/size (0.00s) --- PASS: TestNewLess/name (0.00s) --- PASS: TestNewLess/modtime (0.00s) --- PASS: TestNewLess/size,ascending (0.00s) --- PASS: TestNewLess/name,asc (0.00s) --- PASS: TestNewLess/modtime,ascending (0.00s) --- PASS: TestNewLess/size,descending (0.00s) --- PASS: TestNewLess/name,desc (0.00s) --- PASS: TestNewLess/modtime,descending (0.00s) --- PASS: TestNewLess/modtime,mixed (0.00s) --- PASS: TestNewLess/modtime,mixed,30 (0.00s) === RUN TestRcCopy rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcCopy (0.00s) === RUN TestRcMove rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcMove (0.00s) === RUN TestRcSync rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcSync (0.00s) === RUN TestCopyWithDryRun run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" 2020/12/12 05:32:52 INFO : S3 bucket rclone-test-kajibaz6yucuyeb5qeradih7: Bucket "rclone-test-kajibaz6yucuyeb5qeradih7" created with ACL "private" 2020/12/12 05:32:52 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set 2020/12/12 05:32:52 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for checks to finish 2020/12/12 05:32:52 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for transfers to finish --- PASS: TestCopyWithDryRun (0.69s) === RUN TestCopy run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" 2020/12/12 05:32:52 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for checks to finish 2020/12/12 05:32:52 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for transfers to finish 2020/12/12 05:32:52 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:32:52 DEBUG : sub dir/hello world.rclone_chunk.001_7twv09: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/12/12 05:32:52 INFO : sub dir/hello world.rclone_chunk.001_7twv09: Copied (server-side copy) to: sub dir/hello world 2020/12/12 05:32:52 INFO : sub dir/hello world.rclone_chunk.001_7twv09: Deleted 2020/12/12 05:32:52 DEBUG : sub dir/hello world: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/12/12 05:32:52 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopy (0.34s) === RUN TestCopyMissingDirectory run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" 2020/12/12 05:32:52 DEBUG : Creating backend with remote "/non-existing" 2020/12/12 05:32:52 ERROR : : error reading source directory: directory not found 2020/12/12 05:32:52 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for checks to finish 2020/12/12 05:32:52 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for transfers to finish --- PASS: TestCopyMissingDirectory (0.05s) === RUN TestCopyNoTraverse run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" 2020/12/12 05:32:52 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:32:52 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for checks to finish 2020/12/12 05:32:52 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for transfers to finish 2020/12/12 05:32:53 DEBUG : sub dir/hello world.rclone_chunk.001_7twv6s: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/12/12 05:32:53 INFO : sub dir/hello world.rclone_chunk.001_7twv6s: Copied (server-side copy) to: sub dir/hello world 2020/12/12 05:32:53 INFO : sub dir/hello world.rclone_chunk.001_7twv6s: Deleted 2020/12/12 05:32:53 DEBUG : sub dir/hello world: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/12/12 05:32:53 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoTraverse (0.34s) === RUN TestCopyCheckFirst run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" 2020/12/12 05:32:53 INFO : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Running all checks before starting transfers 2020/12/12 05:32:53 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for checks to finish 2020/12/12 05:32:53 INFO : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Checks finished, now starting transfers 2020/12/12 05:32:53 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for transfers to finish 2020/12/12 05:32:53 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:32:53 DEBUG : sub dir/hello world.rclone_chunk.001_7twwcb: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/12/12 05:32:53 INFO : sub dir/hello world.rclone_chunk.001_7twwcb: Copied (server-side copy) to: sub dir/hello world 2020/12/12 05:32:53 INFO : sub dir/hello world.rclone_chunk.001_7twwcb: Deleted 2020/12/12 05:32:53 DEBUG : sub dir/hello world: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/12/12 05:32:53 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyCheckFirst (0.33s) === RUN TestSyncNoTraverse run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" 2020/12/12 05:32:53 ERROR : Ignoring --no-traverse with sync 2020/12/12 05:32:53 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for checks to finish 2020/12/12 05:32:53 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for transfers to finish 2020/12/12 05:32:53 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:32:53 DEBUG : sub dir/hello world.rclone_chunk.001_7twwo4: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/12/12 05:32:53 INFO : sub dir/hello world.rclone_chunk.001_7twwo4: Copied (server-side copy) to: sub dir/hello world 2020/12/12 05:32:53 INFO : sub dir/hello world.rclone_chunk.001_7twwo4: Deleted 2020/12/12 05:32:53 DEBUG : sub dir/hello world: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/12/12 05:32:53 INFO : sub dir/hello world: Copied (new) 2020/12/12 05:32:53 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoTraverse (0.30s) === RUN TestCopyWithDepth run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" 2020/12/12 05:32:53 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for checks to finish 2020/12/12 05:32:53 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for transfers to finish 2020/12/12 05:32:53 DEBUG : hello world2: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:32:54 DEBUG : hello world2.rclone_chunk.001_7twwur: MD5 = cc2c857f89648dbd139d7b2a6665957d OK 2020/12/12 05:32:54 INFO : hello world2.rclone_chunk.001_7twwur: Copied (server-side copy) to: hello world2 2020/12/12 05:32:54 INFO : hello world2.rclone_chunk.001_7twwur: Deleted 2020/12/12 05:32:54 DEBUG : hello world2: MD5 = cc2c857f89648dbd139d7b2a6665957d OK 2020/12/12 05:32:54 INFO : hello world2: Copied (new) --- PASS: TestCopyWithDepth (0.29s) === RUN TestCopyWithFilesFrom run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" 2020/12/12 05:32:54 DEBUG : hello world2: Excluded 2020/12/12 05:32:54 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for checks to finish 2020/12/12 05:32:54 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for transfers to finish 2020/12/12 05:32:54 DEBUG : potato2: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:32:54 DEBUG : potato2.rclone_chunk.001_7twxmh: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/12/12 05:32:54 INFO : potato2.rclone_chunk.001_7twxmh: Copied (server-side copy) to: potato2 2020/12/12 05:32:54 INFO : potato2.rclone_chunk.001_7twxmh: Deleted 2020/12/12 05:32:54 DEBUG : potato2: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/12/12 05:32:54 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFrom (0.28s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" 2020/12/12 05:32:54 DEBUG : potato2: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:32:54 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for checks to finish 2020/12/12 05:32:54 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for transfers to finish 2020/12/12 05:32:54 DEBUG : potato2.rclone_chunk.001_7twxjq: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/12/12 05:32:54 INFO : potato2.rclone_chunk.001_7twxjq: Copied (server-side copy) to: potato2 2020/12/12 05:32:54 INFO : potato2.rclone_chunk.001_7twxjq: Deleted 2020/12/12 05:32:54 DEBUG : potato2: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/12/12 05:32:54 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFromAndNoTraverse (0.32s) === RUN TestCopyEmptyDirectories run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" 2020/12/12 05:32:54 DEBUG : sub dir2: Making directory 2020/12/12 05:32:54 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:32:54 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for checks to finish 2020/12/12 05:32:54 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for transfers to finish 2020/12/12 05:32:54 DEBUG : sub dir/hello world.rclone_chunk.001_7twxx7: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/12/12 05:32:54 INFO : sub dir/hello world.rclone_chunk.001_7twxx7: Copied (server-side copy) to: sub dir/hello world 2020/12/12 05:32:54 INFO : sub dir/hello world.rclone_chunk.001_7twxx7: Deleted 2020/12/12 05:32:54 DEBUG : sub dir/hello world: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/12/12 05:32:54 INFO : sub dir/hello world: Copied (new) 2020/12/12 05:32:54 DEBUG : sub dir2: Making directory 2020/12/12 05:32:54 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': copied 1 directories fstest.go:249: Filtering empty directory "sub dir2" --- PASS: TestCopyEmptyDirectories (0.33s) === RUN TestMoveEmptyDirectories run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" 2020/12/12 05:32:55 DEBUG : sub dir2: Making directory 2020/12/12 05:32:55 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for checks to finish 2020/12/12 05:32:55 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for transfers to finish 2020/12/12 05:32:55 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:32:55 DEBUG : sub dir/hello world.rclone_chunk.001_7twyg8: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/12/12 05:32:55 INFO : sub dir/hello world.rclone_chunk.001_7twyg8: Copied (server-side copy) to: sub dir/hello world 2020/12/12 05:32:55 INFO : sub dir/hello world.rclone_chunk.001_7twyg8: Deleted 2020/12/12 05:32:55 DEBUG : sub dir/hello world: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/12/12 05:32:55 INFO : sub dir/hello world: Copied (new) 2020/12/12 05:32:55 INFO : sub dir/hello world: Deleted 2020/12/12 05:32:55 DEBUG : sub dir2: Making directory 2020/12/12 05:32:55 DEBUG : sub dir: Making directory 2020/12/12 05:32:55 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': copied 2 directories fstest.go:249: Filtering empty directory "sub dir2" --- PASS: TestMoveEmptyDirectories (0.34s) === RUN TestSyncEmptyDirectories run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" 2020/12/12 05:32:55 DEBUG : sub dir2: Making directory 2020/12/12 05:32:55 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for checks to finish 2020/12/12 05:32:55 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for transfers to finish 2020/12/12 05:32:55 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:32:55 DEBUG : sub dir/hello world.rclone_chunk.001_7twywe: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/12/12 05:32:55 INFO : sub dir/hello world.rclone_chunk.001_7twywe: Copied (server-side copy) to: sub dir/hello world 2020/12/12 05:32:55 INFO : sub dir/hello world.rclone_chunk.001_7twywe: Deleted 2020/12/12 05:32:55 DEBUG : sub dir/hello world: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/12/12 05:32:55 INFO : sub dir/hello world: Copied (new) 2020/12/12 05:32:55 DEBUG : sub dir2: Making directory 2020/12/12 05:32:55 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': copied 1 directories 2020/12/12 05:32:55 DEBUG : Waiting for deletions to finish fstest.go:249: Filtering empty directory "sub dir2" --- PASS: TestSyncEmptyDirectories (0.39s) === RUN TestServerSideCopy run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" 2020/12/12 05:32:56 DEBUG : sub dir/hello world.rclone_chunk.001_7twy4b: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/12/12 05:32:56 INFO : sub dir/hello world.rclone_chunk.001_7twy4b: Copied (server-side copy) to: sub dir/hello world 2020/12/12 05:32:56 INFO : sub dir/hello world.rclone_chunk.001_7twy4b: Deleted 2020/12/12 05:32:56 DEBUG : Creating backend with remote "TestChunkerChunk50bMD5HashS3:rclone-test-cujuvuk7yucojad1kuveheg5" 2020/12/12 05:32:56 DEBUG : Creating backend with remote "TestS3:rclone-test-cujuvuk7yucojad1kuveheg5" 2020/12/12 05:32:56 DEBUG : Reset feature "ListR" sync_test.go:292: Server side copy (if possible) Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7' -> Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-cujuvuk7yucojad1kuveheg5' 2020/12/12 05:32:56 DEBUG : sub dir/hello world: copy non-chunked object... 2020/12/12 05:32:56 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-cujuvuk7yucojad1kuveheg5': Waiting for checks to finish 2020/12/12 05:32:56 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-cujuvuk7yucojad1kuveheg5': Waiting for transfers to finish 2020/12/12 05:32:56 INFO : S3 bucket rclone-test-cujuvuk7yucojad1kuveheg5: Bucket "rclone-test-cujuvuk7yucojad1kuveheg5" created with ACL "private" 2020/12/12 05:32:56 DEBUG : sub dir/hello world: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/12/12 05:32:56 INFO : sub dir/hello world: Copied (server-side copy) 2020/12/12 05:32:56 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-cujuvuk7yucojad1kuveheg5': Purge object "sub dir/hello world" 2020/12/12 05:32:56 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-cujuvuk7yucojad1kuveheg5': Purge dir "sub dir" 2020/12/12 05:32:56 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-cujuvuk7yucojad1kuveheg5': Purge dir "" 2020/12/12 05:32:57 INFO : S3 bucket rclone-test-cujuvuk7yucojad1kuveheg5: Bucket "rclone-test-cujuvuk7yucojad1kuveheg5" deleted --- PASS: TestServerSideCopy (1.40s) === RUN TestCopyAfterDelete run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" 2020/12/12 05:32:57 DEBUG : sub dir/hello world.rclone_chunk.001_7tx0tg: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/12/12 05:32:57 INFO : sub dir/hello world.rclone_chunk.001_7tx0tg: Copied (server-side copy) to: sub dir/hello world 2020/12/12 05:32:57 INFO : sub dir/hello world.rclone_chunk.001_7tx0tg: Deleted 2020/12/12 05:32:57 ERROR : : error listing: directory not found 2020/12/12 05:32:57 DEBUG : Local file system at /tmp/rclone393843524: Making directory 2020/12/12 05:32:57 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for checks to finish 2020/12/12 05:32:57 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for transfers to finish --- PASS: TestCopyAfterDelete (0.40s) === RUN TestCopyRedownload run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" 2020/12/12 05:32:57 DEBUG : sub dir/hello world.rclone_chunk.001_7tx0du: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/12/12 05:32:57 INFO : sub dir/hello world.rclone_chunk.001_7tx0du: Copied (server-side copy) to: sub dir/hello world 2020/12/12 05:32:57 INFO : sub dir/hello world.rclone_chunk.001_7tx0du: Deleted 2020/12/12 05:32:57 DEBUG : Local file system at /tmp/rclone393843524: Waiting for checks to finish 2020/12/12 05:32:57 DEBUG : Local file system at /tmp/rclone393843524: Waiting for transfers to finish 2020/12/12 05:32:57 DEBUG : sub dir/hello world: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/12/12 05:32:57 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyRedownload (0.45s) === RUN TestSyncBasedOnCheckSum run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" 2020/12/12 05:32:58 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for checks to finish 2020/12/12 05:32:58 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for transfers to finish 2020/12/12 05:32:58 DEBUG : check sum: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:32:58 DEBUG : check sum.rclone_chunk.001_7tx1uh: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/12/12 05:32:58 INFO : check sum.rclone_chunk.001_7tx1uh: Copied (server-side copy) to: check sum 2020/12/12 05:32:58 INFO : check sum.rclone_chunk.001_7tx1uh: Deleted 2020/12/12 05:32:58 DEBUG : check sum: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/12/12 05:32:58 INFO : check sum: Copied (new) 2020/12/12 05:32:58 DEBUG : Waiting for deletions to finish 2020/12/12 05:32:58 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for checks to finish 2020/12/12 05:32:58 DEBUG : check sum: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/12/12 05:32:58 DEBUG : check sum: Size and MD5 of src and dst objects identical 2020/12/12 05:32:58 DEBUG : check sum: Unchanged skipping 2020/12/12 05:32:58 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for transfers to finish 2020/12/12 05:32:58 DEBUG : Waiting for deletions to finish 2020/12/12 05:32:58 INFO : There was nothing to transfer --- PASS: TestSyncBasedOnCheckSum (0.32s) === RUN TestSyncSizeOnly run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" 2020/12/12 05:32:58 DEBUG : sizeonly: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:32:58 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for checks to finish 2020/12/12 05:32:58 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for transfers to finish 2020/12/12 05:32:58 DEBUG : sizeonly.rclone_chunk.001_7tx160: MD5 = 8ee2027983915ec78acc45027d874316 OK 2020/12/12 05:32:58 INFO : sizeonly.rclone_chunk.001_7tx160: Copied (server-side copy) to: sizeonly 2020/12/12 05:32:58 INFO : sizeonly.rclone_chunk.001_7tx160: Deleted 2020/12/12 05:32:58 DEBUG : sizeonly: MD5 = 8ee2027983915ec78acc45027d874316 OK 2020/12/12 05:32:58 INFO : sizeonly: Copied (new) 2020/12/12 05:32:58 DEBUG : Waiting for deletions to finish 2020/12/12 05:32:58 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for checks to finish 2020/12/12 05:32:58 DEBUG : sizeonly: Sizes identical 2020/12/12 05:32:58 DEBUG : sizeonly: Unchanged skipping 2020/12/12 05:32:58 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for transfers to finish 2020/12/12 05:32:58 DEBUG : Waiting for deletions to finish 2020/12/12 05:32:58 INFO : There was nothing to transfer --- PASS: TestSyncSizeOnly (0.39s) === RUN TestSyncIgnoreSize run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" 2020/12/12 05:32:58 DEBUG : ignore-size: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:32:58 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for checks to finish 2020/12/12 05:32:58 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for transfers to finish 2020/12/12 05:32:58 DEBUG : ignore-size.rclone_chunk.001_7tx1c5: MD5 = 98bf7d8c15784f0a3d63204441e1e2aa OK 2020/12/12 05:32:58 INFO : ignore-size.rclone_chunk.001_7tx1c5: Copied (server-side copy) to: ignore-size 2020/12/12 05:32:59 INFO : ignore-size.rclone_chunk.001_7tx1c5: Deleted 2020/12/12 05:32:59 DEBUG : ignore-size: MD5 = 98bf7d8c15784f0a3d63204441e1e2aa OK 2020/12/12 05:32:59 INFO : ignore-size: Copied (new) 2020/12/12 05:32:59 DEBUG : Waiting for deletions to finish 2020/12/12 05:32:59 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for checks to finish 2020/12/12 05:32:59 DEBUG : ignore-size: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/12/12 05:32:59 DEBUG : ignore-size: Unchanged skipping 2020/12/12 05:32:59 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for transfers to finish 2020/12/12 05:32:59 DEBUG : Waiting for deletions to finish 2020/12/12 05:32:59 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreSize (0.38s) === RUN TestSyncIgnoreTimes run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" 2020/12/12 05:32:59 DEBUG : existing.rclone_chunk.001_7tx2x1: MD5 = 8ee2027983915ec78acc45027d874316 OK 2020/12/12 05:32:59 INFO : existing.rclone_chunk.001_7tx2x1: Copied (server-side copy) to: existing 2020/12/12 05:32:59 INFO : existing.rclone_chunk.001_7tx2x1: Deleted 2020/12/12 05:32:59 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for checks to finish 2020/12/12 05:32:59 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/12/12 05:32:59 DEBUG : existing: Unchanged skipping 2020/12/12 05:32:59 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for transfers to finish 2020/12/12 05:32:59 DEBUG : Waiting for deletions to finish 2020/12/12 05:32:59 INFO : There was nothing to transfer 2020/12/12 05:32:59 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2020/12/12 05:32:59 DEBUG : existing: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:32:59 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for checks to finish 2020/12/12 05:32:59 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for transfers to finish 2020/12/12 05:32:59 DEBUG : existing.rclone_chunk.001_7tx2x4: MD5 = 8ee2027983915ec78acc45027d874316 OK 2020/12/12 05:32:59 INFO : existing.rclone_chunk.001_7tx2x4: Copied (server-side copy) to: existing 2020/12/12 05:32:59 INFO : existing.rclone_chunk.001_7tx2x4: Deleted 2020/12/12 05:32:59 DEBUG : existing: MD5 = 8ee2027983915ec78acc45027d874316 OK 2020/12/12 05:32:59 INFO : existing: Copied (replaced existing) 2020/12/12 05:32:59 DEBUG : Waiting for deletions to finish --- PASS: TestSyncIgnoreTimes (0.60s) === RUN TestSyncIgnoreExisting run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" 2020/12/12 05:32:59 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for checks to finish 2020/12/12 05:32:59 DEBUG : existing: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:32:59 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for transfers to finish 2020/12/12 05:32:59 DEBUG : existing.rclone_chunk.001_7tx2zw: MD5 = 8ee2027983915ec78acc45027d874316 OK 2020/12/12 05:32:59 INFO : existing.rclone_chunk.001_7tx2zw: Copied (server-side copy) to: existing 2020/12/12 05:32:59 INFO : existing.rclone_chunk.001_7tx2zw: Deleted 2020/12/12 05:32:59 DEBUG : existing: MD5 = 8ee2027983915ec78acc45027d874316 OK 2020/12/12 05:32:59 INFO : existing: Copied (new) 2020/12/12 05:32:59 DEBUG : Waiting for deletions to finish 2020/12/12 05:33:00 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for checks to finish 2020/12/12 05:33:00 DEBUG : existing: Destination exists, skipping 2020/12/12 05:33:00 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for transfers to finish 2020/12/12 05:33:00 DEBUG : Waiting for deletions to finish 2020/12/12 05:33:00 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreExisting (0.39s) === RUN TestSyncIgnoreErrors run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" 2020/12/12 05:33:00 DEBUG : b/potato.rclone_chunk.001_7tx36u: MD5 = e4cb6955d9106df6263c45fcfc10f163 OK 2020/12/12 05:33:00 INFO : b/potato.rclone_chunk.001_7tx36u: Copied (server-side copy) to: b/potato 2020/12/12 05:33:00 INFO : b/potato.rclone_chunk.001_7tx36u: Deleted 2020/12/12 05:33:00 DEBUG : c/non empty space.rclone_chunk.001_7tx3j4: MD5 = ec26039bacb958460a511485fc59d24e OK 2020/12/12 05:33:00 INFO : c/non empty space.rclone_chunk.001_7tx3j4: Copied (server-side copy) to: c/non empty space 2020/12/12 05:33:00 INFO : c/non empty space.rclone_chunk.001_7tx3j4: Deleted 2020/12/12 05:33:00 DEBUG : d: Making directory fstest.go:249: Filtering empty directory "d" 2020/12/12 05:33:00 DEBUG : a/potato2: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:33:00 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/12/12 05:33:00 DEBUG : c/non empty space: Unchanged skipping 2020/12/12 05:33:00 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for checks to finish 2020/12/12 05:33:00 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for transfers to finish 2020/12/12 05:33:00 DEBUG : a/potato2.rclone_chunk.001_7tx3e1: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2020/12/12 05:33:00 INFO : a/potato2.rclone_chunk.001_7tx3e1: Copied (server-side copy) to: a/potato2.rclone_chunk.001 2020/12/12 05:33:00 INFO : a/potato2.rclone_chunk.001_7tx3e1: Deleted 2020/12/12 05:33:00 DEBUG : a/potato2.rclone_chunk.002_7tx3e1: MD5 = 01a6651a94ab602dbfc47587d234b52c OK 2020/12/12 05:33:00 INFO : a/potato2.rclone_chunk.002_7tx3e1: Copied (server-side copy) to: a/potato2.rclone_chunk.002 2020/12/12 05:33:00 INFO : a/potato2.rclone_chunk.002_7tx3e1: Deleted 2020/12/12 05:33:00 DEBUG : a/potato2: MD5 = d6548b156ea68a4e003e786df99eee76 OK 2020/12/12 05:33:00 INFO : a/potato2: Copied (new) 2020/12/12 05:33:00 DEBUG : Waiting for deletions to finish 2020/12/12 05:33:01 INFO : b/potato: Deleted --- PASS: TestSyncIgnoreErrors (1.12s) === RUN TestSyncAfterChangingModtimeOnly run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" 2020/12/12 05:33:01 DEBUG : empty space.rclone_chunk.001_7tx4oc: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/12/12 05:33:01 INFO : empty space.rclone_chunk.001_7tx4oc: Copied (server-side copy) to: empty space 2020/12/12 05:33:01 INFO : empty space.rclone_chunk.001_7tx4oc: Deleted 2020/12/12 05:33:01 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for checks to finish 2020/12/12 05:33:01 DEBUG : empty space: Modification times differ by -95456h54m52.62345679s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499999999 +0000 UTC 2020/12/12 05:33:01 DEBUG : empty space: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/12/12 05:33:01 NOTICE: empty space: Skipped update modification time as --dry-run is set 2020/12/12 05:33:01 DEBUG : empty space: Unchanged skipping 2020/12/12 05:33:01 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for transfers to finish 2020/12/12 05:33:01 DEBUG : Waiting for deletions to finish 2020/12/12 05:33:01 INFO : There was nothing to transfer 2020/12/12 05:33:01 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for checks to finish 2020/12/12 05:33:01 DEBUG : empty space: Modification times differ by -95456h54m52.62345679s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499999999 +0000 UTC 2020/12/12 05:33:01 DEBUG : empty space: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/12/12 05:33:01 INFO : empty space: Updated modification time in destination 2020/12/12 05:33:01 DEBUG : empty space: Unchanged skipping 2020/12/12 05:33:01 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for transfers to finish 2020/12/12 05:33:01 DEBUG : Waiting for deletions to finish 2020/12/12 05:33:01 INFO : There was nothing to transfer --- PASS: TestSyncAfterChangingModtimeOnly (0.51s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" 2020/12/12 05:33:01 DEBUG : empty space.rclone_chunk.001_7tx4n8: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/12/12 05:33:01 INFO : empty space.rclone_chunk.001_7tx4n8: Copied (server-side copy) to: empty space 2020/12/12 05:33:01 INFO : empty space.rclone_chunk.001_7tx4n8: Deleted 2020/12/12 05:33:02 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for checks to finish 2020/12/12 05:33:02 DEBUG : empty space: Modification times differ by -95456h54m52.62345679s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499999999 +0000 UTC 2020/12/12 05:33:02 DEBUG : empty space: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/12/12 05:33:02 DEBUG : empty space: Unchanged skipping 2020/12/12 05:33:02 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for transfers to finish 2020/12/12 05:33:02 DEBUG : Waiting for deletions to finish 2020/12/12 05:33:02 INFO : There was nothing to transfer --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (0.35s) === RUN TestSyncDoesntUpdateModtime run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" 2020/12/12 05:33:02 DEBUG : foo.rclone_chunk.001_7tx5i5: MD5 = 37b51d194a7513e45b56f6524f2d51f2 OK 2020/12/12 05:33:02 INFO : foo.rclone_chunk.001_7tx5i5: Copied (server-side copy) to: foo 2020/12/12 05:33:02 INFO : foo.rclone_chunk.001_7tx5i5: Deleted 2020/12/12 05:33:02 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for checks to finish 2020/12/12 05:33:02 DEBUG : foo: Modification times differ by -95456h54m52.62345679s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499999999 +0000 UTC 2020/12/12 05:33:02 DEBUG : foo: MD5 = acbd18db4cc2f85cedef654fccc4a4d8 (Local file system at /tmp/rclone393843524) 2020/12/12 05:33:02 DEBUG : foo: MD5 = 37b51d194a7513e45b56f6524f2d51f2 (Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7') 2020/12/12 05:33:02 DEBUG : foo: MD5 differ 2020/12/12 05:33:02 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for transfers to finish 2020/12/12 05:33:02 DEBUG : foo: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:33:02 DEBUG : foo.rclone_chunk.001_7tx52i: MD5 = acbd18db4cc2f85cedef654fccc4a4d8 OK 2020/12/12 05:33:02 INFO : foo.rclone_chunk.001_7tx52i: Copied (server-side copy) to: foo 2020/12/12 05:33:02 INFO : foo.rclone_chunk.001_7tx52i: Deleted 2020/12/12 05:33:02 DEBUG : foo: MD5 = acbd18db4cc2f85cedef654fccc4a4d8 OK 2020/12/12 05:33:02 INFO : foo: Copied (replaced existing) 2020/12/12 05:33:02 DEBUG : Waiting for deletions to finish --- PASS: TestSyncDoesntUpdateModtime (0.58s) === RUN TestSyncAfterAddingAFile run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" 2020/12/12 05:33:02 DEBUG : empty space.rclone_chunk.001_7tx53n: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/12/12 05:33:02 INFO : empty space.rclone_chunk.001_7tx53n: Copied (server-side copy) to: empty space 2020/12/12 05:33:02 INFO : empty space.rclone_chunk.001_7tx53n: Deleted 2020/12/12 05:33:02 DEBUG : potato: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:33:02 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for checks to finish 2020/12/12 05:33:02 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/12/12 05:33:02 DEBUG : empty space: Unchanged skipping 2020/12/12 05:33:02 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for transfers to finish 2020/12/12 05:33:03 DEBUG : potato.rclone_chunk.001_7tx5bi: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2020/12/12 05:33:03 INFO : potato.rclone_chunk.001_7tx5bi: Copied (server-side copy) to: potato.rclone_chunk.001 2020/12/12 05:33:03 INFO : potato.rclone_chunk.001_7tx5bi: Deleted 2020/12/12 05:33:03 DEBUG : potato.rclone_chunk.002_7tx5bi: MD5 = 01a6651a94ab602dbfc47587d234b52c OK 2020/12/12 05:33:03 INFO : potato.rclone_chunk.002_7tx5bi: Copied (server-side copy) to: potato.rclone_chunk.002 2020/12/12 05:33:03 INFO : potato.rclone_chunk.002_7tx5bi: Deleted 2020/12/12 05:33:03 DEBUG : potato: MD5 = d6548b156ea68a4e003e786df99eee76 OK 2020/12/12 05:33:03 INFO : potato: Copied (new) 2020/12/12 05:33:03 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterAddingAFile (0.78s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" 2020/12/12 05:33:03 DEBUG : potato.rclone_chunk.001_7tx6zm: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2020/12/12 05:33:03 INFO : potato.rclone_chunk.001_7tx6zm: Copied (server-side copy) to: potato.rclone_chunk.001 2020/12/12 05:33:03 INFO : potato.rclone_chunk.001_7tx6zm: Deleted 2020/12/12 05:33:03 DEBUG : potato.rclone_chunk.002_7tx6zm: MD5 = 01a6651a94ab602dbfc47587d234b52c OK 2020/12/12 05:33:03 INFO : potato.rclone_chunk.002_7tx6zm: Copied (server-side copy) to: potato.rclone_chunk.002 2020/12/12 05:33:03 INFO : potato.rclone_chunk.002_7tx6zm: Deleted 2020/12/12 05:33:03 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for checks to finish 2020/12/12 05:33:03 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2020/12/12 05:33:03 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for transfers to finish 2020/12/12 05:33:03 DEBUG : potato: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:33:04 DEBUG : potato.rclone_chunk.001_7tx6zc: MD5 = 100defcf18c42a1e0dc42a789b107cd2 OK 2020/12/12 05:33:04 INFO : potato.rclone_chunk.001_7tx6zc: Copied (server-side copy) to: potato 2020/12/12 05:33:04 INFO : potato.rclone_chunk.001_7tx6zc: Deleted 2020/12/12 05:33:04 DEBUG : potato: MD5 = 100defcf18c42a1e0dc42a789b107cd2 OK 2020/12/12 05:33:04 INFO : potato: Copied (replaced existing) 2020/12/12 05:33:04 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingFilesSizeOnly (0.79s) === RUN TestSyncAfterChangingContentsOnly run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" 2020/12/12 05:33:04 DEBUG : potato.rclone_chunk.001_7tx7yg: MD5 = 100defcf18c42a1e0dc42a789b107cd2 OK 2020/12/12 05:33:04 INFO : potato.rclone_chunk.001_7tx7yg: Copied (server-side copy) to: potato 2020/12/12 05:33:04 INFO : potato.rclone_chunk.001_7tx7yg: Deleted 2020/12/12 05:33:04 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for checks to finish 2020/12/12 05:33:04 DEBUG : potato: Modification times differ by 119h59m59.876543211s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2011-12-30 12:59:59 +0000 UTC 2020/12/12 05:33:04 DEBUG : potato: MD5 = e4cb6955d9106df6263c45fcfc10f163 (Local file system at /tmp/rclone393843524) 2020/12/12 05:33:04 DEBUG : potato: MD5 = 100defcf18c42a1e0dc42a789b107cd2 (Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7') 2020/12/12 05:33:04 DEBUG : potato: MD5 differ 2020/12/12 05:33:04 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for transfers to finish 2020/12/12 05:33:04 DEBUG : potato: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:33:04 DEBUG : potato.rclone_chunk.001_7tx7ed: MD5 = e4cb6955d9106df6263c45fcfc10f163 OK 2020/12/12 05:33:04 INFO : potato.rclone_chunk.001_7tx7ed: Copied (server-side copy) to: potato 2020/12/12 05:33:04 INFO : potato.rclone_chunk.001_7tx7ed: Deleted 2020/12/12 05:33:04 DEBUG : potato: MD5 = e4cb6955d9106df6263c45fcfc10f163 OK 2020/12/12 05:33:04 INFO : potato: Copied (replaced existing) 2020/12/12 05:33:04 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingContentsOnly (0.54s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" 2020/12/12 05:33:05 DEBUG : potato.rclone_chunk.001_7tx7u1: MD5 = e4cb6955d9106df6263c45fcfc10f163 OK 2020/12/12 05:33:05 INFO : potato.rclone_chunk.001_7tx7u1: Copied (server-side copy) to: potato 2020/12/12 05:33:05 INFO : potato.rclone_chunk.001_7tx7u1: Deleted 2020/12/12 05:33:05 DEBUG : empty space.rclone_chunk.001_7tx838: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/12/12 05:33:05 INFO : empty space.rclone_chunk.001_7tx838: Copied (server-side copy) to: empty space 2020/12/12 05:33:05 INFO : empty space.rclone_chunk.001_7tx838: Deleted 2020/12/12 05:33:05 NOTICE: potato2: Skipped copy as --dry-run is set 2020/12/12 05:33:05 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for checks to finish 2020/12/12 05:33:05 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/12/12 05:33:05 DEBUG : empty space: Unchanged skipping 2020/12/12 05:33:05 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for transfers to finish 2020/12/12 05:33:05 DEBUG : Waiting for deletions to finish 2020/12/12 05:33:05 NOTICE: potato: Skipped delete as --dry-run is set --- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (0.55s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" 2020/12/12 05:33:05 DEBUG : potato.rclone_chunk.001_7tx87e: MD5 = e4cb6955d9106df6263c45fcfc10f163 OK 2020/12/12 05:33:05 INFO : potato.rclone_chunk.001_7tx87e: Copied (server-side copy) to: potato 2020/12/12 05:33:05 INFO : potato.rclone_chunk.001_7tx87e: Deleted 2020/12/12 05:33:05 DEBUG : empty space.rclone_chunk.001_7tx8m5: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/12/12 05:33:05 INFO : empty space.rclone_chunk.001_7tx8m5: Copied (server-side copy) to: empty space 2020/12/12 05:33:05 INFO : empty space.rclone_chunk.001_7tx8m5: Deleted 2020/12/12 05:33:05 DEBUG : potato2: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:33:05 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for checks to finish 2020/12/12 05:33:05 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/12/12 05:33:05 DEBUG : empty space: Unchanged skipping 2020/12/12 05:33:05 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for transfers to finish 2020/12/12 05:33:06 DEBUG : potato2.rclone_chunk.001_7tx8df: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2020/12/12 05:33:06 INFO : potato2.rclone_chunk.001_7tx8df: Copied (server-side copy) to: potato2.rclone_chunk.001 2020/12/12 05:33:06 INFO : potato2.rclone_chunk.001_7tx8df: Deleted 2020/12/12 05:33:06 DEBUG : potato2.rclone_chunk.002_7tx8df: MD5 = 01a6651a94ab602dbfc47587d234b52c OK 2020/12/12 05:33:06 INFO : potato2.rclone_chunk.002_7tx8df: Copied (server-side copy) to: potato2.rclone_chunk.002 2020/12/12 05:33:06 INFO : potato2.rclone_chunk.002_7tx8df: Deleted 2020/12/12 05:33:06 DEBUG : potato2: MD5 = d6548b156ea68a4e003e786df99eee76 OK 2020/12/12 05:33:06 INFO : potato2: Copied (new) 2020/12/12 05:33:06 DEBUG : Waiting for deletions to finish 2020/12/12 05:33:06 INFO : potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (1.10s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" 2020/12/12 05:33:06 DEBUG : b/potato.rclone_chunk.001_7tx9p9: MD5 = e4cb6955d9106df6263c45fcfc10f163 OK 2020/12/12 05:33:06 INFO : b/potato.rclone_chunk.001_7tx9p9: Copied (server-side copy) to: b/potato 2020/12/12 05:33:06 INFO : b/potato.rclone_chunk.001_7tx9p9: Deleted 2020/12/12 05:33:06 DEBUG : c/non empty space.rclone_chunk.001_7tx9mk: MD5 = ec26039bacb958460a511485fc59d24e OK 2020/12/12 05:33:06 INFO : c/non empty space.rclone_chunk.001_7tx9mk: Copied (server-side copy) to: c/non empty space 2020/12/12 05:33:06 INFO : c/non empty space.rclone_chunk.001_7tx9mk: Deleted 2020/12/12 05:33:06 DEBUG : d: Making directory 2020/12/12 05:33:06 DEBUG : d/e: Making directory fstest.go:249: Filtering empty directory "d" fstest.go:249: Filtering empty directory "d/e" 2020/12/12 05:33:06 DEBUG : a/potato2: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:33:07 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for checks to finish 2020/12/12 05:33:07 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/12/12 05:33:07 DEBUG : c/non empty space: Unchanged skipping 2020/12/12 05:33:07 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for transfers to finish 2020/12/12 05:33:07 DEBUG : a/potato2.rclone_chunk.001_7tx9hf: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2020/12/12 05:33:07 INFO : a/potato2.rclone_chunk.001_7tx9hf: Copied (server-side copy) to: a/potato2.rclone_chunk.001 2020/12/12 05:33:07 INFO : a/potato2.rclone_chunk.001_7tx9hf: Deleted 2020/12/12 05:33:07 DEBUG : a/potato2.rclone_chunk.002_7tx9hf: MD5 = 01a6651a94ab602dbfc47587d234b52c OK 2020/12/12 05:33:07 INFO : a/potato2.rclone_chunk.002_7tx9hf: Copied (server-side copy) to: a/potato2.rclone_chunk.002 2020/12/12 05:33:07 INFO : a/potato2.rclone_chunk.002_7tx9hf: Deleted 2020/12/12 05:33:07 DEBUG : a/potato2: MD5 = d6548b156ea68a4e003e786df99eee76 OK 2020/12/12 05:33:07 INFO : a/potato2: Copied (new) 2020/12/12 05:33:07 DEBUG : Waiting for deletions to finish 2020/12/12 05:33:07 INFO : b/potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (1.27s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" 2020/12/12 05:33:07 DEBUG : b/potato.rclone_chunk.001_7txamy: MD5 = e4cb6955d9106df6263c45fcfc10f163 OK 2020/12/12 05:33:07 INFO : b/potato.rclone_chunk.001_7txamy: Copied (server-side copy) to: b/potato 2020/12/12 05:33:07 INFO : b/potato.rclone_chunk.001_7txamy: Deleted 2020/12/12 05:33:08 DEBUG : c/non empty space.rclone_chunk.001_7txa4c: MD5 = ec26039bacb958460a511485fc59d24e OK 2020/12/12 05:33:08 INFO : c/non empty space.rclone_chunk.001_7txa4c: Copied (server-side copy) to: c/non empty space 2020/12/12 05:33:08 INFO : c/non empty space.rclone_chunk.001_7txa4c: Deleted 2020/12/12 05:33:08 DEBUG : d: Making directory fstest.go:249: Filtering empty directory "d" 2020/12/12 05:33:08 DEBUG : a/potato2: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:33:08 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for checks to finish 2020/12/12 05:33:08 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/12/12 05:33:08 DEBUG : c/non empty space: Unchanged skipping 2020/12/12 05:33:08 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for transfers to finish 2020/12/12 05:33:08 DEBUG : a/potato2.rclone_chunk.001_7txbrf: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2020/12/12 05:33:08 INFO : a/potato2.rclone_chunk.001_7txbrf: Copied (server-side copy) to: a/potato2.rclone_chunk.001 2020/12/12 05:33:08 INFO : a/potato2.rclone_chunk.001_7txbrf: Deleted 2020/12/12 05:33:08 DEBUG : a/potato2.rclone_chunk.002_7txbrf: MD5 = 01a6651a94ab602dbfc47587d234b52c OK 2020/12/12 05:33:08 INFO : a/potato2.rclone_chunk.002_7txbrf: Copied (server-side copy) to: a/potato2.rclone_chunk.002 2020/12/12 05:33:08 INFO : a/potato2.rclone_chunk.002_7txbrf: Deleted 2020/12/12 05:33:08 DEBUG : a/potato2: MD5 = d6548b156ea68a4e003e786df99eee76 OK 2020/12/12 05:33:08 INFO : a/potato2: Copied (new) 2020/12/12 05:33:08 ERROR : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': not deleting files as there were IO errors 2020/12/12 05:33:08 ERROR : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': not deleting directories as there were IO errors fstest.go:249: Filtering empty directory "d" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (1.26s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" 2020/12/12 05:33:09 DEBUG : potato.rclone_chunk.001_7txcvq: MD5 = e4cb6955d9106df6263c45fcfc10f163 OK 2020/12/12 05:33:09 INFO : potato.rclone_chunk.001_7txcvq: Copied (server-side copy) to: potato 2020/12/12 05:33:09 INFO : potato.rclone_chunk.001_7txcvq: Deleted 2020/12/12 05:33:09 DEBUG : empty space.rclone_chunk.001_7txc9o: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/12/12 05:33:09 INFO : empty space.rclone_chunk.001_7txc9o: Copied (server-side copy) to: empty space 2020/12/12 05:33:09 INFO : empty space.rclone_chunk.001_7txc9o: Deleted 2020/12/12 05:33:09 DEBUG : Waiting for deletions to finish 2020/12/12 05:33:09 DEBUG : potato2: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:33:09 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for checks to finish 2020/12/12 05:33:09 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/12/12 05:33:09 DEBUG : empty space: Unchanged skipping 2020/12/12 05:33:09 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for transfers to finish 2020/12/12 05:33:09 INFO : potato: Deleted 2020/12/12 05:33:09 DEBUG : potato2.rclone_chunk.001_7txch1: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2020/12/12 05:33:09 INFO : potato2.rclone_chunk.001_7txch1: Copied (server-side copy) to: potato2.rclone_chunk.001 2020/12/12 05:33:09 INFO : potato2.rclone_chunk.001_7txch1: Deleted 2020/12/12 05:33:09 DEBUG : potato2.rclone_chunk.002_7txch1: MD5 = 01a6651a94ab602dbfc47587d234b52c OK 2020/12/12 05:33:09 INFO : potato2.rclone_chunk.002_7txch1: Copied (server-side copy) to: potato2.rclone_chunk.002 2020/12/12 05:33:09 INFO : potato2.rclone_chunk.002_7txch1: Deleted 2020/12/12 05:33:09 DEBUG : potato2: MD5 = d6548b156ea68a4e003e786df99eee76 OK 2020/12/12 05:33:09 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteDuring (0.98s) === RUN TestSyncDeleteBefore run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" 2020/12/12 05:33:10 DEBUG : potato.rclone_chunk.001_7txdrz: MD5 = e4cb6955d9106df6263c45fcfc10f163 OK 2020/12/12 05:33:10 INFO : potato.rclone_chunk.001_7txdrz: Copied (server-side copy) to: potato 2020/12/12 05:33:10 INFO : potato.rclone_chunk.001_7txdrz: Deleted 2020/12/12 05:33:10 DEBUG : empty space.rclone_chunk.001_7txdtw: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/12/12 05:33:10 INFO : empty space.rclone_chunk.001_7txdtw: Copied (server-side copy) to: empty space 2020/12/12 05:33:10 INFO : empty space.rclone_chunk.001_7txdtw: Deleted 2020/12/12 05:33:10 DEBUG : Waiting for deletions to finish 2020/12/12 05:33:10 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for checks to finish 2020/12/12 05:33:10 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for transfers to finish 2020/12/12 05:33:10 INFO : potato: Deleted 2020/12/12 05:33:10 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for checks to finish 2020/12/12 05:33:10 DEBUG : potato2: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:33:10 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/12/12 05:33:10 DEBUG : empty space: Unchanged skipping 2020/12/12 05:33:10 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for transfers to finish 2020/12/12 05:33:10 DEBUG : potato2.rclone_chunk.001_7txd56: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2020/12/12 05:33:10 INFO : potato2.rclone_chunk.001_7txd56: Copied (server-side copy) to: potato2.rclone_chunk.001 2020/12/12 05:33:10 INFO : potato2.rclone_chunk.001_7txd56: Deleted 2020/12/12 05:33:10 DEBUG : potato2.rclone_chunk.002_7txd56: MD5 = 01a6651a94ab602dbfc47587d234b52c OK 2020/12/12 05:33:10 INFO : potato2.rclone_chunk.002_7txd56: Copied (server-side copy) to: potato2.rclone_chunk.002 2020/12/12 05:33:10 INFO : potato2.rclone_chunk.002_7txd56: Deleted 2020/12/12 05:33:10 DEBUG : potato2: MD5 = d6548b156ea68a4e003e786df99eee76 OK 2020/12/12 05:33:10 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteBefore (1.09s) === RUN TestCopyDeleteBefore run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" 2020/12/12 05:33:11 DEBUG : potato.rclone_chunk.001_7txe2m: MD5 = a77cf635f7af90988de91597e3a4519b OK 2020/12/12 05:33:11 INFO : potato.rclone_chunk.001_7txe2m: Copied (server-side copy) to: potato 2020/12/12 05:33:11 INFO : potato.rclone_chunk.001_7txe2m: Deleted 2020/12/12 05:33:11 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for checks to finish 2020/12/12 05:33:11 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for transfers to finish 2020/12/12 05:33:11 DEBUG : potato2: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:33:11 DEBUG : potato2.rclone_chunk.001_7txex5: MD5 = 9519bc2e940e1696ea7365b4b81adedc OK 2020/12/12 05:33:11 INFO : potato2.rclone_chunk.001_7txex5: Copied (server-side copy) to: potato2 2020/12/12 05:33:11 INFO : potato2.rclone_chunk.001_7txex5: Deleted 2020/12/12 05:33:11 DEBUG : potato2: MD5 = 9519bc2e940e1696ea7365b4b81adedc OK 2020/12/12 05:33:11 INFO : potato2: Copied (new) --- PASS: TestCopyDeleteBefore (0.59s) === RUN TestSyncWithExclude run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" 2020/12/12 05:33:11 DEBUG : potato2.rclone_chunk.001_7txen3: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2020/12/12 05:33:11 INFO : potato2.rclone_chunk.001_7txen3: Copied (server-side copy) to: potato2.rclone_chunk.001 2020/12/12 05:33:11 INFO : potato2.rclone_chunk.001_7txen3: Deleted 2020/12/12 05:33:11 DEBUG : potato2.rclone_chunk.002_7txen3: MD5 = 01a6651a94ab602dbfc47587d234b52c OK 2020/12/12 05:33:11 INFO : potato2.rclone_chunk.002_7txen3: Copied (server-side copy) to: potato2.rclone_chunk.002 2020/12/12 05:33:11 INFO : potato2.rclone_chunk.002_7txen3: Deleted 2020/12/12 05:33:12 DEBUG : empty space.rclone_chunk.001_7txf57: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/12/12 05:33:12 INFO : empty space.rclone_chunk.001_7txf57: Copied (server-side copy) to: empty space 2020/12/12 05:33:12 INFO : empty space.rclone_chunk.001_7txf57: Deleted 2020/12/12 05:33:12 DEBUG : enormous: Excluded 2020/12/12 05:33:12 DEBUG : potato2: Excluded 2020/12/12 05:33:12 DEBUG : potato2: Excluded 2020/12/12 05:33:12 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for checks to finish 2020/12/12 05:33:12 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/12/12 05:33:12 DEBUG : empty space: Unchanged skipping 2020/12/12 05:33:12 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for transfers to finish 2020/12/12 05:33:12 DEBUG : Waiting for deletions to finish 2020/12/12 05:33:12 INFO : There was nothing to transfer 2020/12/12 05:33:12 DEBUG : enormous: Excluded 2020/12/12 05:33:12 DEBUG : potato2: Excluded 2020/12/12 05:33:12 DEBUG : potato2: Excluded 2020/12/12 05:33:12 DEBUG : Local file system at /tmp/rclone393843524: Waiting for checks to finish 2020/12/12 05:33:12 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/12/12 05:33:12 DEBUG : empty space: Unchanged skipping 2020/12/12 05:33:12 DEBUG : Local file system at /tmp/rclone393843524: Waiting for transfers to finish 2020/12/12 05:33:12 DEBUG : Waiting for deletions to finish 2020/12/12 05:33:12 INFO : There was nothing to transfer --- PASS: TestSyncWithExclude (0.92s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" 2020/12/12 05:33:12 DEBUG : potato2.rclone_chunk.001_7txfzb: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2020/12/12 05:33:12 INFO : potato2.rclone_chunk.001_7txfzb: Copied (server-side copy) to: potato2.rclone_chunk.001 2020/12/12 05:33:12 INFO : potato2.rclone_chunk.001_7txfzb: Deleted 2020/12/12 05:33:12 DEBUG : potato2.rclone_chunk.002_7txfzb: MD5 = 01a6651a94ab602dbfc47587d234b52c OK 2020/12/12 05:33:12 INFO : potato2.rclone_chunk.002_7txfzb: Copied (server-side copy) to: potato2.rclone_chunk.002 2020/12/12 05:33:12 INFO : potato2.rclone_chunk.002_7txfzb: Deleted 2020/12/12 05:33:13 DEBUG : empty space.rclone_chunk.001_7txf4g: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/12/12 05:33:13 INFO : empty space.rclone_chunk.001_7txf4g: Copied (server-side copy) to: empty space 2020/12/12 05:33:13 INFO : empty space.rclone_chunk.001_7txf4g: Deleted 2020/12/12 05:33:13 DEBUG : enormous.rclone_chunk.001_7txg7q: MD5 = 1e2d1a8f03accd4907d668b183c2f9f0 OK 2020/12/12 05:33:13 INFO : enormous.rclone_chunk.001_7txg7q: Copied (server-side copy) to: enormous.rclone_chunk.001 2020/12/12 05:33:13 INFO : enormous.rclone_chunk.001_7txg7q: Deleted 2020/12/12 05:33:13 DEBUG : enormous.rclone_chunk.002_7txg7q: MD5 = 1e2d1a8f03accd4907d668b183c2f9f0 OK 2020/12/12 05:33:13 INFO : enormous.rclone_chunk.002_7txg7q: Copied (server-side copy) to: enormous.rclone_chunk.002 2020/12/12 05:33:13 INFO : enormous.rclone_chunk.002_7txg7q: Deleted 2020/12/12 05:33:13 DEBUG : enormous: Excluded 2020/12/12 05:33:13 DEBUG : potato2: Excluded 2020/12/12 05:33:13 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for checks to finish 2020/12/12 05:33:13 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/12/12 05:33:13 DEBUG : empty space: Unchanged skipping 2020/12/12 05:33:13 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for transfers to finish 2020/12/12 05:33:13 DEBUG : Waiting for deletions to finish 2020/12/12 05:33:13 INFO : potato2: Deleted 2020/12/12 05:33:13 INFO : enormous: Deleted 2020/12/12 05:33:13 INFO : There was nothing to transfer 2020/12/12 05:33:13 DEBUG : Local file system at /tmp/rclone393843524: Waiting for checks to finish 2020/12/12 05:33:13 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/12/12 05:33:13 DEBUG : empty space: Unchanged skipping 2020/12/12 05:33:13 DEBUG : Local file system at /tmp/rclone393843524: Waiting for transfers to finish 2020/12/12 05:33:13 DEBUG : Waiting for deletions to finish 2020/12/12 05:33:13 INFO : enormous: Deleted 2020/12/12 05:33:13 INFO : potato2: Deleted 2020/12/12 05:33:13 INFO : There was nothing to transfer --- PASS: TestSyncWithExcludeAndDeleteExcluded (1.32s) === RUN TestSyncWithUpdateOlder run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" 2020/12/12 05:33:14 DEBUG : one.rclone_chunk.001_7txgyz: MD5 = bc21e6484530fc9d0313cb816b733396 OK 2020/12/12 05:33:14 INFO : one.rclone_chunk.001_7txgyz: Copied (server-side copy) to: one 2020/12/12 05:33:14 INFO : one.rclone_chunk.001_7txgyz: Deleted 2020/12/12 05:33:14 DEBUG : two.rclone_chunk.001_7txhds: MD5 = 0f82d86afa0f5dc965c5c15aca58dcfb OK 2020/12/12 05:33:14 INFO : two.rclone_chunk.001_7txhds: Copied (server-side copy) to: two 2020/12/12 05:33:14 INFO : two.rclone_chunk.001_7txhds: Deleted 2020/12/12 05:33:14 DEBUG : three.rclone_chunk.001_7txh6c: MD5 = 413af0de1f97a2155acf2b8b26ab36e2 OK 2020/12/12 05:33:14 INFO : three.rclone_chunk.001_7txh6c: Copied (server-side copy) to: three 2020/12/12 05:33:14 INFO : three.rclone_chunk.001_7txh6c: Deleted 2020/12/12 05:33:14 DEBUG : four.rclone_chunk.001_7txho3: MD5 = 0a04eb9a514fbe0829cf48049cc69fc7 OK 2020/12/12 05:33:14 INFO : four.rclone_chunk.001_7txho3: Copied (server-side copy) to: four 2020/12/12 05:33:14 INFO : four.rclone_chunk.001_7txho3: Deleted 2020/12/12 05:33:14 DEBUG : five: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:33:14 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for checks to finish 2020/12/12 05:33:14 DEBUG : four: Sizes differ (src 4 vs dst 8) 2020/12/12 05:33:14 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2020/12/12 05:33:14 DEBUG : four: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:33:14 DEBUG : two: MD5 = b8a9f715dbb64fd5c56e7783c6820a61 (Local file system at /tmp/rclone393843524) 2020/12/12 05:33:14 DEBUG : two: MD5 = 0f82d86afa0f5dc965c5c15aca58dcfb (Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7') 2020/12/12 05:33:14 DEBUG : two: MD5 differ 2020/12/12 05:33:14 DEBUG : two: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:33:14 DEBUG : three: Sizes identical 2020/12/12 05:33:14 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2020/12/12 05:33:14 DEBUG : one: Destination is newer than source, skipping 2020/12/12 05:33:14 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for transfers to finish 2020/12/12 05:33:14 DEBUG : five.rclone_chunk.001_7txhup: MD5 = 30056e1cab7a61d256fc8edd970d14f5 OK 2020/12/12 05:33:14 INFO : five.rclone_chunk.001_7txhup: Copied (server-side copy) to: five 2020/12/12 05:33:14 INFO : five.rclone_chunk.001_7txhup: Deleted 2020/12/12 05:33:14 DEBUG : five: MD5 = 30056e1cab7a61d256fc8edd970d14f5 OK 2020/12/12 05:33:14 INFO : five: Copied (new) 2020/12/12 05:33:14 DEBUG : four.rclone_chunk.001_7txh9s: MD5 = 8cbad96aced40b3838dd9f07f6ef5772 OK 2020/12/12 05:33:14 INFO : four.rclone_chunk.001_7txh9s: Copied (server-side copy) to: four 2020/12/12 05:33:15 INFO : four.rclone_chunk.001_7txh9s: Deleted 2020/12/12 05:33:15 DEBUG : four: MD5 = 8cbad96aced40b3838dd9f07f6ef5772 OK 2020/12/12 05:33:15 INFO : four: Copied (replaced existing) 2020/12/12 05:33:15 DEBUG : two.rclone_chunk.001_7txhn5: MD5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2020/12/12 05:33:15 INFO : two.rclone_chunk.001_7txhn5: Copied (server-side copy) to: two 2020/12/12 05:33:15 INFO : two.rclone_chunk.001_7txhn5: Deleted 2020/12/12 05:33:15 DEBUG : two: MD5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2020/12/12 05:33:15 INFO : two: Copied (replaced existing) 2020/12/12 05:33:15 DEBUG : Waiting for deletions to finish 2020/12/12 05:33:15 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for checks to finish 2020/12/12 05:33:15 DEBUG : one: Destination is newer than source, skipping 2020/12/12 05:33:15 DEBUG : three: MD5 = 35d6d33467aae9a2e3dccb4b6b027878 (Local file system at /tmp/rclone393843524) 2020/12/12 05:33:15 DEBUG : three: MD5 = 413af0de1f97a2155acf2b8b26ab36e2 (Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7') 2020/12/12 05:33:15 DEBUG : three: MD5 differ 2020/12/12 05:33:15 DEBUG : three: Destination mod time is within 1s of source but files differ, transferring 2020/12/12 05:33:15 DEBUG : three: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:33:15 DEBUG : five: MD5 = 30056e1cab7a61d256fc8edd970d14f5 OK 2020/12/12 05:33:15 DEBUG : five: Size and MD5 of src and dst objects identical 2020/12/12 05:33:15 DEBUG : five: Destination mod time is within 1s of source and files identical, skipping 2020/12/12 05:33:15 DEBUG : four: MD5 = 8cbad96aced40b3838dd9f07f6ef5772 OK 2020/12/12 05:33:15 DEBUG : four: Size and MD5 of src and dst objects identical 2020/12/12 05:33:15 DEBUG : four: Destination mod time is within 1s of source and files identical, skipping 2020/12/12 05:33:15 DEBUG : two: MD5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2020/12/12 05:33:15 DEBUG : two: Size and MD5 of src and dst objects identical 2020/12/12 05:33:15 DEBUG : two: Destination mod time is within 1s of source and files identical, skipping 2020/12/12 05:33:15 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for transfers to finish 2020/12/12 05:33:15 DEBUG : three.rclone_chunk.001_7txiza: MD5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2020/12/12 05:33:15 INFO : three.rclone_chunk.001_7txiza: Copied (server-side copy) to: three 2020/12/12 05:33:15 INFO : three.rclone_chunk.001_7txiza: Deleted 2020/12/12 05:33:15 DEBUG : three: MD5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2020/12/12 05:33:15 INFO : three: Copied (replaced existing) 2020/12/12 05:33:15 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithUpdateOlder (1.75s) === RUN TestSyncWithMaxDuration sync_test.go:1074: Skipping test on non local remote --- SKIP: TestSyncWithMaxDuration (0.00s) === RUN TestSyncWithTrackRenames run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" sync_test.go:1128: Can track renames: true 2020/12/12 05:33:15 INFO : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Making map for --track-renames 2020/12/12 05:33:15 INFO : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Finished making map for --track-renames 2020/12/12 05:33:15 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for checks to finish 2020/12/12 05:33:15 DEBUG : potato: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:33:15 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for renames to finish 2020/12/12 05:33:15 DEBUG : yam: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:33:15 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for transfers to finish 2020/12/12 05:33:15 DEBUG : potato.rclone_chunk.001_7txigq: MD5 = 7f6fa9ddec9bfebda9510af0323fd696 OK 2020/12/12 05:33:15 INFO : potato.rclone_chunk.001_7txigq: Copied (server-side copy) to: potato 2020/12/12 05:33:15 DEBUG : yam.rclone_chunk.001_7txi0q: MD5 = 48dc443644c4b89556dc06834b46451d OK 2020/12/12 05:33:15 INFO : yam.rclone_chunk.001_7txi0q: Copied (server-side copy) to: yam 2020/12/12 05:33:15 INFO : potato.rclone_chunk.001_7txigq: Deleted 2020/12/12 05:33:15 DEBUG : potato: MD5 = 7f6fa9ddec9bfebda9510af0323fd696 OK 2020/12/12 05:33:15 INFO : potato: Copied (new) 2020/12/12 05:33:15 INFO : yam.rclone_chunk.001_7txi0q: Deleted 2020/12/12 05:33:15 DEBUG : yam: MD5 = 48dc443644c4b89556dc06834b46451d OK 2020/12/12 05:33:15 INFO : yam: Copied (new) 2020/12/12 05:33:15 DEBUG : Waiting for deletions to finish 2020/12/12 05:33:15 INFO : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Making map for --track-renames 2020/12/12 05:33:15 INFO : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Finished making map for --track-renames 2020/12/12 05:33:15 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for checks to finish 2020/12/12 05:33:16 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/12/12 05:33:16 DEBUG : potato: Unchanged skipping 2020/12/12 05:33:16 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for renames to finish 2020/12/12 05:33:16 DEBUG : yam: copy non-chunked object... 2020/12/12 05:33:16 DEBUG : yam: MD5 = 48dc443644c4b89556dc06834b46451d OK 2020/12/12 05:33:16 INFO : yam: Copied (server-side copy) to: yaml 2020/12/12 05:33:16 INFO : yam: Deleted 2020/12/12 05:33:16 INFO : yaml: Renamed from "yam" 2020/12/12 05:33:16 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for transfers to finish 2020/12/12 05:33:16 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenames (0.54s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" sync_test.go:1201: Can track renames: true 2020/12/12 05:33:16 INFO : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Making map for --track-renames 2020/12/12 05:33:16 INFO : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Finished making map for --track-renames 2020/12/12 05:33:16 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for checks to finish 2020/12/12 05:33:16 DEBUG : potato: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:33:16 DEBUG : yam: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:33:16 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for renames to finish 2020/12/12 05:33:16 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for transfers to finish 2020/12/12 05:33:16 DEBUG : potato.rclone_chunk.001_7txjl5: MD5 = 7f6fa9ddec9bfebda9510af0323fd696 OK 2020/12/12 05:33:16 INFO : potato.rclone_chunk.001_7txjl5: Copied (server-side copy) to: potato 2020/12/12 05:33:16 DEBUG : yam.rclone_chunk.001_7txj98: MD5 = 48dc443644c4b89556dc06834b46451d OK 2020/12/12 05:33:16 INFO : yam.rclone_chunk.001_7txj98: Copied (server-side copy) to: yam 2020/12/12 05:33:16 INFO : potato.rclone_chunk.001_7txjl5: Deleted 2020/12/12 05:33:16 DEBUG : potato: MD5 = 7f6fa9ddec9bfebda9510af0323fd696 OK 2020/12/12 05:33:16 INFO : potato: Copied (new) 2020/12/12 05:33:16 INFO : yam.rclone_chunk.001_7txj98: Deleted 2020/12/12 05:33:16 DEBUG : yam: MD5 = 48dc443644c4b89556dc06834b46451d OK 2020/12/12 05:33:16 INFO : yam: Copied (new) 2020/12/12 05:33:16 DEBUG : Waiting for deletions to finish 2020/12/12 05:33:16 INFO : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Making map for --track-renames 2020/12/12 05:33:16 INFO : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Finished making map for --track-renames 2020/12/12 05:33:16 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for checks to finish 2020/12/12 05:33:16 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/12/12 05:33:16 DEBUG : potato: Unchanged skipping 2020/12/12 05:33:16 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for renames to finish 2020/12/12 05:33:16 DEBUG : yam: copy non-chunked object... 2020/12/12 05:33:16 DEBUG : yam: MD5 = 48dc443644c4b89556dc06834b46451d OK 2020/12/12 05:33:16 INFO : yam: Copied (server-side copy) to: yaml 2020/12/12 05:33:16 INFO : yam: Deleted 2020/12/12 05:33:16 INFO : yaml: Renamed from "yam" 2020/12/12 05:33:16 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for transfers to finish 2020/12/12 05:33:16 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenamesStrategyModtime (0.53s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" sync_test.go:1241: Can track renames: true 2020/12/12 05:33:16 INFO : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Making map for --track-renames 2020/12/12 05:33:16 INFO : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Finished making map for --track-renames 2020/12/12 05:33:16 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for checks to finish 2020/12/12 05:33:16 DEBUG : potato: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:33:16 DEBUG : sub/yam: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:33:16 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for renames to finish 2020/12/12 05:33:16 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for transfers to finish 2020/12/12 05:33:16 DEBUG : potato.rclone_chunk.001_7txjp9: MD5 = 7f6fa9ddec9bfebda9510af0323fd696 OK 2020/12/12 05:33:16 INFO : potato.rclone_chunk.001_7txjp9: Copied (server-side copy) to: potato 2020/12/12 05:33:16 DEBUG : sub/yam.rclone_chunk.001_7txjpf: MD5 = 48dc443644c4b89556dc06834b46451d OK 2020/12/12 05:33:16 INFO : sub/yam.rclone_chunk.001_7txjpf: Copied (server-side copy) to: sub/yam 2020/12/12 05:33:16 INFO : potato.rclone_chunk.001_7txjp9: Deleted 2020/12/12 05:33:16 DEBUG : potato: MD5 = 7f6fa9ddec9bfebda9510af0323fd696 OK 2020/12/12 05:33:16 INFO : potato: Copied (new) 2020/12/12 05:33:16 INFO : sub/yam.rclone_chunk.001_7txjpf: Deleted 2020/12/12 05:33:16 DEBUG : sub/yam: MD5 = 48dc443644c4b89556dc06834b46451d OK 2020/12/12 05:33:16 INFO : sub/yam: Copied (new) 2020/12/12 05:33:16 DEBUG : Waiting for deletions to finish 2020/12/12 05:33:17 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/12/12 05:33:17 DEBUG : potato: Unchanged skipping 2020/12/12 05:33:17 INFO : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Making map for --track-renames 2020/12/12 05:33:17 INFO : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Finished making map for --track-renames 2020/12/12 05:33:17 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for checks to finish 2020/12/12 05:33:17 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for renames to finish 2020/12/12 05:33:17 DEBUG : sub/yam: copy non-chunked object... 2020/12/12 05:33:17 DEBUG : sub/yam: MD5 = 48dc443644c4b89556dc06834b46451d OK 2020/12/12 05:33:17 INFO : sub/yam: Copied (server-side copy) to: yam 2020/12/12 05:33:17 INFO : sub/yam: Deleted 2020/12/12 05:33:17 INFO : yam: Renamed from "sub/yam" 2020/12/12 05:33:17 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for transfers to finish 2020/12/12 05:33:17 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenamesStrategyLeaf (0.55s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" 2020/12/12 05:33:17 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:33:17 DEBUG : nested/sub dir/file: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:33:17 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for checks to finish 2020/12/12 05:33:17 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for transfers to finish 2020/12/12 05:33:17 DEBUG : nested/sub dir/file.rclone_chunk.001_7txkbk: MD5 = 83d3784ea62518eafc60e98d84f877ad OK 2020/12/12 05:33:17 INFO : nested/sub dir/file.rclone_chunk.001_7txkbk: Copied (server-side copy) to: nested/sub dir/file 2020/12/12 05:33:17 DEBUG : sub dir/hello world.rclone_chunk.001_7txk87: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/12/12 05:33:17 INFO : sub dir/hello world.rclone_chunk.001_7txk87: Copied (server-side copy) to: sub dir/hello world 2020/12/12 05:33:17 INFO : nested/sub dir/file.rclone_chunk.001_7txkbk: Deleted 2020/12/12 05:33:17 DEBUG : nested/sub dir/file: MD5 = 83d3784ea62518eafc60e98d84f877ad OK 2020/12/12 05:33:17 INFO : nested/sub dir/file: Copied (new) 2020/12/12 05:33:17 INFO : nested/sub dir/file: Deleted 2020/12/12 05:33:17 INFO : sub dir/hello world.rclone_chunk.001_7txk87: Deleted 2020/12/12 05:33:17 DEBUG : sub dir/hello world: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/12/12 05:33:17 INFO : sub dir/hello world: Copied (new) 2020/12/12 05:33:17 INFO : sub dir/hello world: Deleted 2020/12/12 05:33:17 DEBUG : sub dir: Removing directory 2020/12/12 05:33:17 DEBUG : nested/sub dir: Removing directory 2020/12/12 05:33:17 DEBUG : nested: Removing directory 2020/12/12 05:33:17 DEBUG : Local file system at /tmp/rclone393843524: deleted 3 directories --- PASS: TestMoveWithDeleteEmptySrcDirs (0.44s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" 2020/12/12 05:33:17 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:33:17 DEBUG : nested/sub dir/file: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:33:17 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for checks to finish 2020/12/12 05:33:17 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for transfers to finish 2020/12/12 05:33:17 DEBUG : sub dir/hello world.rclone_chunk.001_7txki5: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/12/12 05:33:17 INFO : sub dir/hello world.rclone_chunk.001_7txki5: Copied (server-side copy) to: sub dir/hello world 2020/12/12 05:33:17 DEBUG : nested/sub dir/file.rclone_chunk.001_7txk3x: MD5 = 83d3784ea62518eafc60e98d84f877ad OK 2020/12/12 05:33:17 INFO : nested/sub dir/file.rclone_chunk.001_7txk3x: Copied (server-side copy) to: nested/sub dir/file 2020/12/12 05:33:17 INFO : sub dir/hello world.rclone_chunk.001_7txki5: Deleted 2020/12/12 05:33:17 DEBUG : sub dir/hello world: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/12/12 05:33:17 INFO : sub dir/hello world: Copied (new) 2020/12/12 05:33:17 INFO : sub dir/hello world: Deleted 2020/12/12 05:33:17 INFO : nested/sub dir/file.rclone_chunk.001_7txk3x: Deleted 2020/12/12 05:33:17 DEBUG : nested/sub dir/file: MD5 = 83d3784ea62518eafc60e98d84f877ad OK 2020/12/12 05:33:17 INFO : nested/sub dir/file: Copied (new) 2020/12/12 05:33:17 INFO : nested/sub dir/file: Deleted --- PASS: TestMoveWithoutDeleteEmptySrcDirs (0.43s) === RUN TestServerSideMove run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" 2020/12/12 05:33:18 DEBUG : Creating backend with remote "TestChunkerChunk50bMD5HashS3:rclone-test-koramov1luxokan2wobokev1" 2020/12/12 05:33:18 DEBUG : Creating backend with remote "TestS3:rclone-test-koramov1luxokan2wobokev1" 2020/12/12 05:33:18 DEBUG : Reset feature "ListR" 2020/12/12 05:33:18 DEBUG : potato2.rclone_chunk.001_7txlmw: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2020/12/12 05:33:18 INFO : potato2.rclone_chunk.001_7txlmw: Copied (server-side copy) to: potato2.rclone_chunk.001 2020/12/12 05:33:18 INFO : potato2.rclone_chunk.001_7txlmw: Deleted 2020/12/12 05:33:18 DEBUG : potato2.rclone_chunk.002_7txlmw: MD5 = 01a6651a94ab602dbfc47587d234b52c OK 2020/12/12 05:33:18 INFO : potato2.rclone_chunk.002_7txlmw: Copied (server-side copy) to: potato2.rclone_chunk.002 2020/12/12 05:33:18 INFO : potato2.rclone_chunk.002_7txlmw: Deleted 2020/12/12 05:33:18 DEBUG : empty space.rclone_chunk.001_7txlca: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/12/12 05:33:18 INFO : empty space.rclone_chunk.001_7txlca: Copied (server-side copy) to: empty space 2020/12/12 05:33:18 INFO : empty space.rclone_chunk.001_7txlca: Deleted 2020/12/12 05:33:18 DEBUG : potato3.rclone_chunk.001_7txlqf: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2020/12/12 05:33:18 INFO : potato3.rclone_chunk.001_7txlqf: Copied (server-side copy) to: potato3.rclone_chunk.001 2020/12/12 05:33:18 INFO : potato3.rclone_chunk.001_7txlqf: Deleted 2020/12/12 05:33:19 DEBUG : potato3.rclone_chunk.002_7txlqf: MD5 = 111c387f2584838efb7e0ec6f2fa2e10 OK 2020/12/12 05:33:19 INFO : potato3.rclone_chunk.002_7txlqf: Copied (server-side copy) to: potato3.rclone_chunk.002 2020/12/12 05:33:19 INFO : potato3.rclone_chunk.002_7txlqf: Deleted sync_test.go:1294: Server side move (if possible) Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7' -> Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-koramov1luxokan2wobokev1' 2020/12/12 05:33:19 INFO : S3 bucket rclone-test-koramov1luxokan2wobokev1: Bucket "rclone-test-koramov1luxokan2wobokev1" created with ACL "private" 2020/12/12 05:33:20 DEBUG : empty space.rclone_chunk.001_7txmi5: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/12/12 05:33:20 INFO : empty space.rclone_chunk.001_7txmi5: Copied (server-side copy) to: empty space 2020/12/12 05:33:20 INFO : empty space.rclone_chunk.001_7txmi5: Deleted 2020/12/12 05:33:20 DEBUG : potato3.rclone_chunk.001_7txnok: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2020/12/12 05:33:20 INFO : potato3.rclone_chunk.001_7txnok: Copied (server-side copy) to: potato3.rclone_chunk.001 2020/12/12 05:33:20 INFO : potato3.rclone_chunk.001_7txnok: Deleted 2020/12/12 05:33:20 DEBUG : potato3.rclone_chunk.002_7txnok: MD5 = 01a6651a94ab602dbfc47587d234b52c OK 2020/12/12 05:33:20 INFO : potato3.rclone_chunk.002_7txnok: Copied (server-side copy) to: potato3.rclone_chunk.002 2020/12/12 05:33:20 INFO : potato3.rclone_chunk.002_7txnok: Deleted 2020/12/12 05:33:20 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2020/12/12 05:33:20 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-koramov1luxokan2wobokev1': Waiting for checks to finish 2020/12/12 05:33:20 DEBUG : potato3: copy 2 data chunks... 2020/12/12 05:33:20 DEBUG : potato2: copy 2 data chunks... 2020/12/12 05:33:20 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/12/12 05:33:20 DEBUG : empty space: Unchanged skipping 2020/12/12 05:33:20 INFO : empty space: Deleted 2020/12/12 05:33:20 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-koramov1luxokan2wobokev1': Waiting for transfers to finish 2020/12/12 05:33:20 DEBUG : potato3: MD5 = b1a53993579b830006f2e1610415c32c OK 2020/12/12 05:33:20 INFO : potato3: Copied (server-side copy) 2020/12/12 05:33:20 DEBUG : potato2: MD5 = d6548b156ea68a4e003e786df99eee76 OK 2020/12/12 05:33:20 INFO : potato2: Copied (server-side copy) 2020/12/12 05:33:20 INFO : potato3: Deleted 2020/12/12 05:33:20 INFO : potato2: Deleted 2020/12/12 05:33:20 DEBUG : Creating backend with remote "TestChunkerChunk50bMD5HashS3:rclone-test-zemuxuv6vanavuz9bopigog3" 2020/12/12 05:33:20 DEBUG : Creating backend with remote "TestS3:rclone-test-zemuxuv6vanavuz9bopigog3" 2020/12/12 05:33:20 DEBUG : Reset feature "ListR" 2020/12/12 05:33:21 DEBUG : empty space: copy non-chunked object... 2020/12/12 05:33:21 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-zemuxuv6vanavuz9bopigog3': Waiting for checks to finish 2020/12/12 05:33:21 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-zemuxuv6vanavuz9bopigog3': Waiting for transfers to finish 2020/12/12 05:33:21 DEBUG : potato2: copy 2 data chunks... 2020/12/12 05:33:21 DEBUG : potato3: copy 2 data chunks... 2020/12/12 05:33:21 INFO : S3 bucket rclone-test-zemuxuv6vanavuz9bopigog3: Bucket "rclone-test-zemuxuv6vanavuz9bopigog3" created with ACL "private" 2020/12/12 05:33:21 DEBUG : empty space: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/12/12 05:33:21 INFO : empty space: Copied (server-side copy) 2020/12/12 05:33:21 INFO : empty space: Deleted 2020/12/12 05:33:21 DEBUG : potato3: MD5 = b1a53993579b830006f2e1610415c32c OK 2020/12/12 05:33:21 INFO : potato3: Copied (server-side copy) 2020/12/12 05:33:21 DEBUG : potato2: MD5 = d6548b156ea68a4e003e786df99eee76 OK 2020/12/12 05:33:21 INFO : potato2: Copied (server-side copy) 2020/12/12 05:33:21 INFO : potato3: Deleted 2020/12/12 05:33:21 INFO : potato2: Deleted 2020/12/12 05:33:22 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-zemuxuv6vanavuz9bopigog3': Purge object "empty space" 2020/12/12 05:33:22 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-zemuxuv6vanavuz9bopigog3': Purge object "potato2" 2020/12/12 05:33:22 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-zemuxuv6vanavuz9bopigog3': Purge object "potato3" 2020/12/12 05:33:22 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-zemuxuv6vanavuz9bopigog3': Purge dir "" 2020/12/12 05:33:22 INFO : S3 bucket rclone-test-zemuxuv6vanavuz9bopigog3: Bucket "rclone-test-zemuxuv6vanavuz9bopigog3" deleted 2020/12/12 05:33:22 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-koramov1luxokan2wobokev1': Purge dir "" 2020/12/12 05:33:22 INFO : S3 bucket rclone-test-koramov1luxokan2wobokev1: Bucket "rclone-test-koramov1luxokan2wobokev1" deleted --- PASS: TestServerSideMove (4.72s) === RUN TestServerSideMoveWithFilter run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" 2020/12/12 05:33:22 DEBUG : Creating backend with remote "TestChunkerChunk50bMD5HashS3:rclone-test-yulirar6teyoyoj1muxovaf7" 2020/12/12 05:33:22 DEBUG : Creating backend with remote "TestS3:rclone-test-yulirar6teyoyoj1muxovaf7" 2020/12/12 05:33:22 DEBUG : Reset feature "ListR" 2020/12/12 05:33:23 DEBUG : potato2.rclone_chunk.001_7txp99: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2020/12/12 05:33:23 INFO : potato2.rclone_chunk.001_7txp99: Copied (server-side copy) to: potato2.rclone_chunk.001 2020/12/12 05:33:23 INFO : potato2.rclone_chunk.001_7txp99: Deleted 2020/12/12 05:33:23 DEBUG : potato2.rclone_chunk.002_7txp99: MD5 = 01a6651a94ab602dbfc47587d234b52c OK 2020/12/12 05:33:23 INFO : potato2.rclone_chunk.002_7txp99: Copied (server-side copy) to: potato2.rclone_chunk.002 2020/12/12 05:33:23 INFO : potato2.rclone_chunk.002_7txp99: Deleted 2020/12/12 05:33:23 DEBUG : empty space.rclone_chunk.001_7txq2d: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/12/12 05:33:23 INFO : empty space.rclone_chunk.001_7txq2d: Copied (server-side copy) to: empty space 2020/12/12 05:33:23 INFO : empty space.rclone_chunk.001_7txq2d: Deleted 2020/12/12 05:33:23 DEBUG : potato3.rclone_chunk.001_7txqfo: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2020/12/12 05:33:23 INFO : potato3.rclone_chunk.001_7txqfo: Copied (server-side copy) to: potato3.rclone_chunk.001 2020/12/12 05:33:23 INFO : potato3.rclone_chunk.001_7txqfo: Deleted 2020/12/12 05:33:23 DEBUG : potato3.rclone_chunk.002_7txqfo: MD5 = 111c387f2584838efb7e0ec6f2fa2e10 OK 2020/12/12 05:33:23 INFO : potato3.rclone_chunk.002_7txqfo: Copied (server-side copy) to: potato3.rclone_chunk.002 2020/12/12 05:33:23 INFO : potato3.rclone_chunk.002_7txqfo: Deleted sync_test.go:1294: Server side move (if possible) Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7' -> Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-yulirar6teyoyoj1muxovaf7' 2020/12/12 05:33:24 INFO : S3 bucket rclone-test-yulirar6teyoyoj1muxovaf7: Bucket "rclone-test-yulirar6teyoyoj1muxovaf7" created with ACL "private" 2020/12/12 05:33:24 DEBUG : empty space.rclone_chunk.001_7txr7b: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/12/12 05:33:24 INFO : empty space.rclone_chunk.001_7txr7b: Copied (server-side copy) to: empty space 2020/12/12 05:33:24 INFO : empty space.rclone_chunk.001_7txr7b: Deleted 2020/12/12 05:33:24 DEBUG : potato3.rclone_chunk.001_7txr46: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2020/12/12 05:33:24 INFO : potato3.rclone_chunk.001_7txr46: Copied (server-side copy) to: potato3.rclone_chunk.001 2020/12/12 05:33:24 INFO : potato3.rclone_chunk.001_7txr46: Deleted 2020/12/12 05:33:24 DEBUG : potato3.rclone_chunk.002_7txr46: MD5 = 01a6651a94ab602dbfc47587d234b52c OK 2020/12/12 05:33:24 INFO : potato3.rclone_chunk.002_7txr46: Copied (server-side copy) to: potato3.rclone_chunk.002 2020/12/12 05:33:25 INFO : potato3.rclone_chunk.002_7txr46: Deleted 2020/12/12 05:33:25 DEBUG : empty space: Excluded 2020/12/12 05:33:25 DEBUG : empty space: Excluded 2020/12/12 05:33:25 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-yulirar6teyoyoj1muxovaf7': Waiting for checks to finish 2020/12/12 05:33:25 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2020/12/12 05:33:25 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-yulirar6teyoyoj1muxovaf7': Waiting for transfers to finish 2020/12/12 05:33:25 DEBUG : potato2: copy 2 data chunks... 2020/12/12 05:33:25 DEBUG : potato3: copy 2 data chunks... 2020/12/12 05:33:25 DEBUG : potato2: MD5 = d6548b156ea68a4e003e786df99eee76 OK 2020/12/12 05:33:25 INFO : potato2: Copied (server-side copy) 2020/12/12 05:33:25 DEBUG : potato3: MD5 = b1a53993579b830006f2e1610415c32c OK 2020/12/12 05:33:25 INFO : potato3: Copied (server-side copy) 2020/12/12 05:33:25 INFO : potato2: Deleted 2020/12/12 05:33:25 INFO : potato3: Deleted 2020/12/12 05:33:25 DEBUG : Creating backend with remote "TestChunkerChunk50bMD5HashS3:rclone-test-lebitek8robekav0razaqey9" 2020/12/12 05:33:25 DEBUG : Creating backend with remote "TestS3:rclone-test-lebitek8robekav0razaqey9" 2020/12/12 05:33:25 DEBUG : Reset feature "ListR" 2020/12/12 05:33:25 DEBUG : empty space: Excluded 2020/12/12 05:33:25 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-lebitek8robekav0razaqey9': Waiting for checks to finish 2020/12/12 05:33:25 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-lebitek8robekav0razaqey9': Waiting for transfers to finish 2020/12/12 05:33:25 DEBUG : potato3: copy 2 data chunks... 2020/12/12 05:33:25 DEBUG : potato2: copy 2 data chunks... 2020/12/12 05:33:26 INFO : S3 bucket rclone-test-lebitek8robekav0razaqey9: Bucket "rclone-test-lebitek8robekav0razaqey9" created with ACL "private" 2020/12/12 05:33:26 DEBUG : potato3: MD5 = b1a53993579b830006f2e1610415c32c OK 2020/12/12 05:33:26 INFO : potato3: Copied (server-side copy) 2020/12/12 05:33:26 DEBUG : potato2: MD5 = d6548b156ea68a4e003e786df99eee76 OK 2020/12/12 05:33:26 INFO : potato2: Copied (server-side copy) 2020/12/12 05:33:26 INFO : potato3: Deleted 2020/12/12 05:33:26 INFO : potato2: Deleted 2020/12/12 05:33:26 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-lebitek8robekav0razaqey9': Purge object "potato2" 2020/12/12 05:33:26 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-lebitek8robekav0razaqey9': Purge object "potato3" 2020/12/12 05:33:27 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-lebitek8robekav0razaqey9': Purge dir "" 2020/12/12 05:33:27 INFO : S3 bucket rclone-test-lebitek8robekav0razaqey9: Bucket "rclone-test-lebitek8robekav0razaqey9" deleted 2020/12/12 05:33:27 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-yulirar6teyoyoj1muxovaf7': Purge object "empty space" 2020/12/12 05:33:27 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-yulirar6teyoyoj1muxovaf7': Purge dir "" 2020/12/12 05:33:27 INFO : S3 bucket rclone-test-yulirar6teyoyoj1muxovaf7: Bucket "rclone-test-yulirar6teyoyoj1muxovaf7" deleted --- PASS: TestServerSideMoveWithFilter (4.77s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" 2020/12/12 05:33:27 DEBUG : Creating backend with remote "TestChunkerChunk50bMD5HashS3:rclone-test-funahos3lebohib1corehog1" 2020/12/12 05:33:27 DEBUG : Creating backend with remote "TestS3:rclone-test-funahos3lebohib1corehog1" 2020/12/12 05:33:27 DEBUG : Reset feature "ListR" 2020/12/12 05:33:27 DEBUG : potato2.rclone_chunk.001_7txu5z: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2020/12/12 05:33:27 INFO : potato2.rclone_chunk.001_7txu5z: Copied (server-side copy) to: potato2.rclone_chunk.001 2020/12/12 05:33:27 INFO : potato2.rclone_chunk.001_7txu5z: Deleted 2020/12/12 05:33:27 DEBUG : potato2.rclone_chunk.002_7txu5z: MD5 = 01a6651a94ab602dbfc47587d234b52c OK 2020/12/12 05:33:27 INFO : potato2.rclone_chunk.002_7txu5z: Copied (server-side copy) to: potato2.rclone_chunk.002 2020/12/12 05:33:27 INFO : potato2.rclone_chunk.002_7txu5z: Deleted 2020/12/12 05:33:28 DEBUG : empty space.rclone_chunk.001_7txuir: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/12/12 05:33:28 INFO : empty space.rclone_chunk.001_7txuir: Copied (server-side copy) to: empty space 2020/12/12 05:33:28 INFO : empty space.rclone_chunk.001_7txuir: Deleted 2020/12/12 05:33:28 DEBUG : potato3.rclone_chunk.001_7txvev: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2020/12/12 05:33:28 INFO : potato3.rclone_chunk.001_7txvev: Copied (server-side copy) to: potato3.rclone_chunk.001 2020/12/12 05:33:28 INFO : potato3.rclone_chunk.001_7txvev: Deleted 2020/12/12 05:33:28 DEBUG : potato3.rclone_chunk.002_7txvev: MD5 = 111c387f2584838efb7e0ec6f2fa2e10 OK 2020/12/12 05:33:28 INFO : potato3.rclone_chunk.002_7txvev: Copied (server-side copy) to: potato3.rclone_chunk.002 2020/12/12 05:33:28 INFO : potato3.rclone_chunk.002_7txvev: Deleted 2020/12/12 05:33:28 DEBUG : tomatoDir: Making directory sync_test.go:1294: Server side move (if possible) Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7' -> Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-funahos3lebohib1corehog1' 2020/12/12 05:33:29 INFO : S3 bucket rclone-test-funahos3lebohib1corehog1: Bucket "rclone-test-funahos3lebohib1corehog1" created with ACL "private" 2020/12/12 05:33:29 DEBUG : empty space.rclone_chunk.001_7txw6m: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/12/12 05:33:29 INFO : empty space.rclone_chunk.001_7txw6m: Copied (server-side copy) to: empty space 2020/12/12 05:33:29 INFO : empty space.rclone_chunk.001_7txw6m: Deleted 2020/12/12 05:33:29 DEBUG : potato3.rclone_chunk.001_7txwgb: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2020/12/12 05:33:29 INFO : potato3.rclone_chunk.001_7txwgb: Copied (server-side copy) to: potato3.rclone_chunk.001 2020/12/12 05:33:29 INFO : potato3.rclone_chunk.001_7txwgb: Deleted 2020/12/12 05:33:29 DEBUG : potato3.rclone_chunk.002_7txwgb: MD5 = 01a6651a94ab602dbfc47587d234b52c OK 2020/12/12 05:33:29 INFO : potato3.rclone_chunk.002_7txwgb: Copied (server-side copy) to: potato3.rclone_chunk.002 2020/12/12 05:33:29 INFO : potato3.rclone_chunk.002_7txwgb: Deleted 2020/12/12 05:33:29 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2020/12/12 05:33:29 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-funahos3lebohib1corehog1': Waiting for checks to finish 2020/12/12 05:33:29 DEBUG : potato2: copy 2 data chunks... 2020/12/12 05:33:29 DEBUG : potato3: copy 2 data chunks... 2020/12/12 05:33:29 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/12/12 05:33:29 DEBUG : empty space: Unchanged skipping 2020/12/12 05:33:29 INFO : empty space: Deleted 2020/12/12 05:33:29 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-funahos3lebohib1corehog1': Waiting for transfers to finish 2020/12/12 05:33:30 DEBUG : potato3: MD5 = b1a53993579b830006f2e1610415c32c OK 2020/12/12 05:33:30 INFO : potato3: Copied (server-side copy) 2020/12/12 05:33:30 DEBUG : potato2: MD5 = d6548b156ea68a4e003e786df99eee76 OK 2020/12/12 05:33:30 INFO : potato2: Copied (server-side copy) 2020/12/12 05:33:30 INFO : potato3: Deleted 2020/12/12 05:33:30 INFO : potato2: Deleted 2020/12/12 05:33:30 DEBUG : Creating backend with remote "TestChunkerChunk50bMD5HashS3:rclone-test-dewazoc5qudotos6vayuzoz7" 2020/12/12 05:33:30 DEBUG : Creating backend with remote "TestS3:rclone-test-dewazoc5qudotos6vayuzoz7" 2020/12/12 05:33:30 DEBUG : Reset feature "ListR" 2020/12/12 05:33:30 DEBUG : tomatoDir: Making directory 2020/12/12 05:33:30 DEBUG : empty space: copy non-chunked object... 2020/12/12 05:33:30 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-dewazoc5qudotos6vayuzoz7': Waiting for checks to finish 2020/12/12 05:33:30 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-dewazoc5qudotos6vayuzoz7': Waiting for transfers to finish 2020/12/12 05:33:30 DEBUG : potato3: copy 2 data chunks... 2020/12/12 05:33:30 DEBUG : potato2: copy 2 data chunks... 2020/12/12 05:33:31 INFO : S3 bucket rclone-test-dewazoc5qudotos6vayuzoz7: Bucket "rclone-test-dewazoc5qudotos6vayuzoz7" created with ACL "private" 2020/12/12 05:33:31 DEBUG : empty space: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/12/12 05:33:31 INFO : empty space: Copied (server-side copy) 2020/12/12 05:33:31 INFO : empty space: Deleted 2020/12/12 05:33:31 DEBUG : potato3: MD5 = b1a53993579b830006f2e1610415c32c OK 2020/12/12 05:33:31 INFO : potato3: Copied (server-side copy) 2020/12/12 05:33:31 DEBUG : potato2: MD5 = d6548b156ea68a4e003e786df99eee76 OK 2020/12/12 05:33:31 INFO : potato2: Copied (server-side copy) 2020/12/12 05:33:31 INFO : potato3: Deleted 2020/12/12 05:33:31 INFO : potato2: Deleted 2020/12/12 05:33:31 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-dewazoc5qudotos6vayuzoz7': Purge object "empty space" 2020/12/12 05:33:31 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-dewazoc5qudotos6vayuzoz7': Purge object "potato2" 2020/12/12 05:33:31 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-dewazoc5qudotos6vayuzoz7': Purge object "potato3" 2020/12/12 05:33:31 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-dewazoc5qudotos6vayuzoz7': Purge dir "" 2020/12/12 05:33:32 INFO : S3 bucket rclone-test-dewazoc5qudotos6vayuzoz7: Bucket "rclone-test-dewazoc5qudotos6vayuzoz7" deleted 2020/12/12 05:33:32 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-funahos3lebohib1corehog1': Purge dir "" 2020/12/12 05:33:32 INFO : S3 bucket rclone-test-funahos3lebohib1corehog1: Bucket "rclone-test-funahos3lebohib1corehog1" deleted --- PASS: TestServerSideMoveDeleteEmptySourceDirs (4.76s) === RUN TestServerSideMoveOverlap run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" 2020/12/12 05:33:32 DEBUG : Creating backend with remote "TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7/rclone-move-test" 2020/12/12 05:33:32 DEBUG : Creating backend with remote "TestS3:rclone-test-kajibaz6yucuyeb5qeradih7/rclone-move-test" 2020/12/12 05:33:32 DEBUG : Reset feature "ListR" 2020/12/12 05:33:32 DEBUG : potato2.rclone_chunk.001_7txzwn: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2020/12/12 05:33:32 INFO : potato2.rclone_chunk.001_7txzwn: Copied (server-side copy) to: potato2.rclone_chunk.001 2020/12/12 05:33:32 INFO : potato2.rclone_chunk.001_7txzwn: Deleted 2020/12/12 05:33:32 DEBUG : potato2.rclone_chunk.002_7txzwn: MD5 = 01a6651a94ab602dbfc47587d234b52c OK 2020/12/12 05:33:32 INFO : potato2.rclone_chunk.002_7txzwn: Copied (server-side copy) to: potato2.rclone_chunk.002 2020/12/12 05:33:32 INFO : potato2.rclone_chunk.002_7txzwn: Deleted --- PASS: TestServerSideMoveOverlap (0.69s) === RUN TestSyncOverlap run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" 2020/12/12 05:33:33 DEBUG : Creating backend with remote "TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7/rclone-sync-test" 2020/12/12 05:33:33 DEBUG : Creating backend with remote "TestS3:rclone-test-kajibaz6yucuyeb5qeradih7/rclone-sync-test" 2020/12/12 05:33:33 DEBUG : Reset feature "ListR" --- PASS: TestSyncOverlap (0.08s) === RUN TestSyncCompareDest run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" 2020/12/12 05:33:33 DEBUG : Creating backend with remote "TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7/dst" 2020/12/12 05:33:33 DEBUG : Creating backend with remote "TestS3:rclone-test-kajibaz6yucuyeb5qeradih7/dst" 2020/12/12 05:33:33 DEBUG : Reset feature "ListR" 2020/12/12 05:33:33 DEBUG : Creating backend with remote "TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7/CompareDest" 2020/12/12 05:33:33 DEBUG : Creating backend with remote "TestS3:rclone-test-kajibaz6yucuyeb5qeradih7/CompareDest" 2020/12/12 05:33:33 DEBUG : Reset feature "ListR" 2020/12/12 05:33:33 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:33:33 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7/dst': Waiting for checks to finish 2020/12/12 05:33:33 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7/dst': Waiting for transfers to finish 2020/12/12 05:33:33 DEBUG : one.rclone_chunk.001_7ty0c9: MD5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2020/12/12 05:33:33 INFO : one.rclone_chunk.001_7ty0c9: Copied (server-side copy) to: one 2020/12/12 05:33:33 INFO : one.rclone_chunk.001_7ty0c9: Deleted 2020/12/12 05:33:33 DEBUG : one: MD5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2020/12/12 05:33:33 INFO : one: Copied (new) 2020/12/12 05:33:33 DEBUG : Waiting for deletions to finish 2020/12/12 05:33:33 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7/dst': Waiting for checks to finish 2020/12/12 05:33:33 DEBUG : one: Sizes differ (src 5 vs dst 3) 2020/12/12 05:33:33 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7/dst': Waiting for transfers to finish 2020/12/12 05:33:33 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:33:33 DEBUG : one.rclone_chunk.001_7ty0kf: MD5 = 07912d142f5d63ee918b34796b5a2432 OK 2020/12/12 05:33:33 INFO : one.rclone_chunk.001_7ty0kf: Copied (server-side copy) to: one 2020/12/12 05:33:33 INFO : one.rclone_chunk.001_7ty0kf: Deleted 2020/12/12 05:33:33 DEBUG : one: MD5 = 07912d142f5d63ee918b34796b5a2432 OK 2020/12/12 05:33:33 INFO : one: Copied (replaced existing) 2020/12/12 05:33:33 DEBUG : Waiting for deletions to finish 2020/12/12 05:33:34 DEBUG : dst/one.rclone_chunk.001_7ty1z9: MD5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2020/12/12 05:33:34 INFO : dst/one.rclone_chunk.001_7ty1z9: Copied (server-side copy) to: dst/one 2020/12/12 05:33:34 INFO : dst/one.rclone_chunk.001_7ty1z9: Deleted 2020/12/12 05:33:34 DEBUG : CompareDest/one.rclone_chunk.001_7ty1f1: MD5 = 07912d142f5d63ee918b34796b5a2432 OK 2020/12/12 05:33:34 INFO : CompareDest/one.rclone_chunk.001_7ty1f1: Copied (server-side copy) to: CompareDest/one 2020/12/12 05:33:34 INFO : CompareDest/one.rclone_chunk.001_7ty1f1: Deleted 2020/12/12 05:33:34 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7/dst': Waiting for checks to finish 2020/12/12 05:33:34 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/12/12 05:33:34 DEBUG : one: Destination found in --compare-dest, skipping 2020/12/12 05:33:34 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7/dst': Waiting for transfers to finish 2020/12/12 05:33:34 DEBUG : Waiting for deletions to finish 2020/12/12 05:33:34 INFO : There was nothing to transfer 2020/12/12 05:33:34 DEBUG : CompareDest/two.rclone_chunk.001_7ty1xk: MD5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2020/12/12 05:33:34 INFO : CompareDest/two.rclone_chunk.001_7ty1xk: Copied (server-side copy) to: CompareDest/two 2020/12/12 05:33:34 INFO : CompareDest/two.rclone_chunk.001_7ty1xk: Deleted 2020/12/12 05:33:35 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/12/12 05:33:35 DEBUG : two: Destination found in --compare-dest, skipping 2020/12/12 05:33:35 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7/dst': Waiting for checks to finish 2020/12/12 05:33:35 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/12/12 05:33:35 DEBUG : one: Destination found in --compare-dest, skipping 2020/12/12 05:33:35 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7/dst': Waiting for transfers to finish 2020/12/12 05:33:35 DEBUG : Waiting for deletions to finish 2020/12/12 05:33:35 INFO : There was nothing to transfer 2020/12/12 05:33:35 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/12/12 05:33:35 DEBUG : two: Destination found in --compare-dest, skipping 2020/12/12 05:33:35 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7/dst': Waiting for checks to finish 2020/12/12 05:33:35 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/12/12 05:33:35 DEBUG : one: Destination found in --compare-dest, skipping 2020/12/12 05:33:35 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7/dst': Waiting for transfers to finish 2020/12/12 05:33:35 DEBUG : Waiting for deletions to finish 2020/12/12 05:33:35 INFO : There was nothing to transfer 2020/12/12 05:33:35 DEBUG : two: Sizes differ (src 5 vs dst 3) 2020/12/12 05:33:35 DEBUG : two: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:33:35 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7/dst': Waiting for checks to finish 2020/12/12 05:33:35 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/12/12 05:33:35 DEBUG : one: Destination found in --compare-dest, skipping 2020/12/12 05:33:35 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7/dst': Waiting for transfers to finish 2020/12/12 05:33:35 DEBUG : two.rclone_chunk.001_7ty2r1: MD5 = 2379e4ce8c3380e996ab0509f17069ad OK 2020/12/12 05:33:35 INFO : two.rclone_chunk.001_7ty2r1: Copied (server-side copy) to: two 2020/12/12 05:33:35 INFO : two.rclone_chunk.001_7ty2r1: Deleted 2020/12/12 05:33:35 DEBUG : two: MD5 = 2379e4ce8c3380e996ab0509f17069ad OK 2020/12/12 05:33:35 INFO : two: Copied (new) 2020/12/12 05:33:35 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCompareDest (2.84s) === RUN TestSyncCopyDest run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" 2020/12/12 05:33:36 DEBUG : Creating backend with remote "TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7/dst" 2020/12/12 05:33:36 DEBUG : Creating backend with remote "TestS3:rclone-test-kajibaz6yucuyeb5qeradih7/dst" 2020/12/12 05:33:36 DEBUG : Reset feature "ListR" 2020/12/12 05:33:36 DEBUG : Creating backend with remote "TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7/CopyDest" 2020/12/12 05:33:36 DEBUG : Creating backend with remote "TestS3:rclone-test-kajibaz6yucuyeb5qeradih7/CopyDest" 2020/12/12 05:33:36 DEBUG : Reset feature "ListR" 2020/12/12 05:33:36 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:33:36 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7/dst': Waiting for checks to finish 2020/12/12 05:33:36 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7/dst': Waiting for transfers to finish 2020/12/12 05:33:36 DEBUG : one.rclone_chunk.001_7ty3o8: MD5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2020/12/12 05:33:36 INFO : one.rclone_chunk.001_7ty3o8: Copied (server-side copy) to: one 2020/12/12 05:33:36 INFO : one.rclone_chunk.001_7ty3o8: Deleted 2020/12/12 05:33:36 DEBUG : one: MD5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2020/12/12 05:33:36 INFO : one: Copied (new) 2020/12/12 05:33:36 DEBUG : Waiting for deletions to finish 2020/12/12 05:33:36 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7/dst': Waiting for checks to finish 2020/12/12 05:33:36 DEBUG : one: Sizes differ (src 5 vs dst 3) 2020/12/12 05:33:36 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7/dst': Waiting for transfers to finish 2020/12/12 05:33:36 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:33:36 DEBUG : one.rclone_chunk.001_7ty39a: MD5 = 07912d142f5d63ee918b34796b5a2432 OK 2020/12/12 05:33:36 INFO : one.rclone_chunk.001_7ty39a: Copied (server-side copy) to: one 2020/12/12 05:33:36 INFO : one.rclone_chunk.001_7ty39a: Deleted 2020/12/12 05:33:36 DEBUG : one: MD5 = 07912d142f5d63ee918b34796b5a2432 OK 2020/12/12 05:33:36 INFO : one: Copied (replaced existing) 2020/12/12 05:33:36 DEBUG : Waiting for deletions to finish 2020/12/12 05:33:37 DEBUG : dst/one.rclone_chunk.001_7ty34q: MD5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2020/12/12 05:33:37 INFO : dst/one.rclone_chunk.001_7ty34q: Copied (server-side copy) to: dst/one 2020/12/12 05:33:37 INFO : dst/one.rclone_chunk.001_7ty34q: Deleted 2020/12/12 05:33:37 DEBUG : CopyDest/one.rclone_chunk.001_7ty402: MD5 = 07912d142f5d63ee918b34796b5a2432 OK 2020/12/12 05:33:37 INFO : CopyDest/one.rclone_chunk.001_7ty402: Copied (server-side copy) to: CopyDest/one 2020/12/12 05:33:37 INFO : CopyDest/one.rclone_chunk.001_7ty402: Deleted 2020/12/12 05:33:37 DEBUG : Creating backend with remote "TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7/BackupDir" 2020/12/12 05:33:37 DEBUG : Creating backend with remote "TestS3:rclone-test-kajibaz6yucuyeb5qeradih7/BackupDir" 2020/12/12 05:33:37 DEBUG : Reset feature "ListR" 2020/12/12 05:33:37 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7/dst': Waiting for checks to finish 2020/12/12 05:33:37 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/12/12 05:33:37 DEBUG : one: Sizes differ (src 5 vs dst 3) 2020/12/12 05:33:37 DEBUG : one: copy non-chunked object... 2020/12/12 05:33:37 DEBUG : one: MD5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2020/12/12 05:33:37 INFO : one: Copied (server-side copy) 2020/12/12 05:33:37 INFO : one: Deleted 2020/12/12 05:33:37 DEBUG : one: copy non-chunked object... 2020/12/12 05:33:37 DEBUG : one: MD5 = 07912d142f5d63ee918b34796b5a2432 OK 2020/12/12 05:33:37 INFO : one: Copied (server-side copy) 2020/12/12 05:33:37 DEBUG : one: Destination found in --copy-dest, using server-side copy 2020/12/12 05:33:37 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7/dst': Waiting for transfers to finish 2020/12/12 05:33:37 DEBUG : Waiting for deletions to finish 2020/12/12 05:33:38 DEBUG : CopyDest/two.rclone_chunk.001_7ty5z1: MD5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2020/12/12 05:33:38 INFO : CopyDest/two.rclone_chunk.001_7ty5z1: Copied (server-side copy) to: CopyDest/two 2020/12/12 05:33:38 INFO : CopyDest/two.rclone_chunk.001_7ty5z1: Deleted 2020/12/12 05:33:38 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/12/12 05:33:38 DEBUG : two: copy non-chunked object... 2020/12/12 05:33:38 DEBUG : two: MD5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2020/12/12 05:33:38 INFO : two: Copied (server-side copy) 2020/12/12 05:33:38 DEBUG : two: Destination found in --copy-dest, using server-side copy 2020/12/12 05:33:38 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7/dst': Waiting for checks to finish 2020/12/12 05:33:38 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/12/12 05:33:38 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/12/12 05:33:38 DEBUG : one: Unchanged skipping 2020/12/12 05:33:38 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7/dst': Waiting for transfers to finish 2020/12/12 05:33:38 DEBUG : Waiting for deletions to finish 2020/12/12 05:33:38 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7/dst': Waiting for checks to finish 2020/12/12 05:33:38 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/12/12 05:33:38 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/12/12 05:33:38 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/12/12 05:33:38 DEBUG : one: Unchanged skipping 2020/12/12 05:33:38 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/12/12 05:33:38 DEBUG : two: Unchanged skipping 2020/12/12 05:33:38 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7/dst': Waiting for transfers to finish 2020/12/12 05:33:38 DEBUG : Waiting for deletions to finish 2020/12/12 05:33:38 INFO : There was nothing to transfer 2020/12/12 05:33:39 DEBUG : CopyDest/three.rclone_chunk.001_7ty56a: MD5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2020/12/12 05:33:39 INFO : CopyDest/three.rclone_chunk.001_7ty56a: Copied (server-side copy) to: CopyDest/three 2020/12/12 05:33:39 INFO : CopyDest/three.rclone_chunk.001_7ty56a: Deleted 2020/12/12 05:33:39 DEBUG : three: Sizes differ (src 7 vs dst 5) 2020/12/12 05:33:39 DEBUG : three: Destination not found in --copy-dest 2020/12/12 05:33:39 DEBUG : three: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:33:39 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7/dst': Waiting for checks to finish 2020/12/12 05:33:39 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/12/12 05:33:39 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/12/12 05:33:39 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/12/12 05:33:39 DEBUG : one: Unchanged skipping 2020/12/12 05:33:39 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/12/12 05:33:39 DEBUG : two: Unchanged skipping 2020/12/12 05:33:39 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7/dst': Waiting for transfers to finish 2020/12/12 05:33:39 DEBUG : three.rclone_chunk.001_7ty6zq: MD5 = 1bccb9dccb3e9f6a3f9d2a8bdb54b7f5 OK 2020/12/12 05:33:39 INFO : three.rclone_chunk.001_7ty6zq: Copied (server-side copy) to: three 2020/12/12 05:33:39 INFO : three.rclone_chunk.001_7ty6zq: Deleted 2020/12/12 05:33:39 DEBUG : three: MD5 = 1bccb9dccb3e9f6a3f9d2a8bdb54b7f5 OK 2020/12/12 05:33:39 INFO : three: Copied (new) 2020/12/12 05:33:39 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCopyDest (3.95s) === RUN TestSyncBackupDir run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" 2020/12/12 05:33:40 DEBUG : dst/one.rclone_chunk.001_7ty6o7: MD5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2020/12/12 05:33:40 INFO : dst/one.rclone_chunk.001_7ty6o7: Copied (server-side copy) to: dst/one 2020/12/12 05:33:40 INFO : dst/one.rclone_chunk.001_7ty6o7: Deleted 2020/12/12 05:33:40 DEBUG : dst/two.rclone_chunk.001_7ty7ph: MD5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2020/12/12 05:33:40 INFO : dst/two.rclone_chunk.001_7ty7ph: Copied (server-side copy) to: dst/two 2020/12/12 05:33:40 INFO : dst/two.rclone_chunk.001_7ty7ph: Deleted 2020/12/12 05:33:40 DEBUG : dst/three.txt.rclone_chunk.001_7ty7yx: MD5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2020/12/12 05:33:40 INFO : dst/three.txt.rclone_chunk.001_7ty7yx: Copied (server-side copy) to: dst/three.txt 2020/12/12 05:33:40 INFO : dst/three.txt.rclone_chunk.001_7ty7yx: Deleted 2020/12/12 05:33:40 DEBUG : Creating backend with remote "TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7/dst" 2020/12/12 05:33:40 DEBUG : Creating backend with remote "TestS3:rclone-test-kajibaz6yucuyeb5qeradih7/dst" 2020/12/12 05:33:40 DEBUG : Reset feature "ListR" 2020/12/12 05:33:40 DEBUG : Creating backend with remote "TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7/backup" 2020/12/12 05:33:40 DEBUG : Creating backend with remote "TestS3:rclone-test-kajibaz6yucuyeb5qeradih7/backup" 2020/12/12 05:33:40 DEBUG : Reset feature "ListR" 2020/12/12 05:33:40 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7/dst': Waiting for checks to finish 2020/12/12 05:33:40 DEBUG : one: Sizes differ (src 4 vs dst 3) 2020/12/12 05:33:40 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/12/12 05:33:40 DEBUG : two: Unchanged skipping 2020/12/12 05:33:40 DEBUG : one: copy non-chunked object... 2020/12/12 05:33:41 DEBUG : one: MD5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2020/12/12 05:33:41 INFO : one: Copied (server-side copy) 2020/12/12 05:33:41 INFO : one: Deleted 2020/12/12 05:33:41 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7/dst': Waiting for transfers to finish 2020/12/12 05:33:41 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:33:41 DEBUG : one.rclone_chunk.001_7ty8sn: MD5 = c7957179c41f69d44f217a108c7915d8 OK 2020/12/12 05:33:41 INFO : one.rclone_chunk.001_7ty8sn: Copied (server-side copy) to: one 2020/12/12 05:33:41 INFO : one.rclone_chunk.001_7ty8sn: Deleted 2020/12/12 05:33:41 DEBUG : one: MD5 = c7957179c41f69d44f217a108c7915d8 OK 2020/12/12 05:33:41 INFO : one: Copied (new) 2020/12/12 05:33:41 DEBUG : Waiting for deletions to finish 2020/12/12 05:33:41 DEBUG : three.txt: copy non-chunked object... 2020/12/12 05:33:41 DEBUG : three.txt: MD5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2020/12/12 05:33:41 INFO : three.txt: Copied (server-side copy) 2020/12/12 05:33:41 INFO : three.txt: Deleted 2020/12/12 05:33:41 INFO : three.txt: Moved into backup dir 2020/12/12 05:33:41 DEBUG : dst/three.txt.rclone_chunk.001_7ty8uq: MD5 = 91341eed84691a83caea73aa785736d5 OK 2020/12/12 05:33:41 INFO : dst/three.txt.rclone_chunk.001_7ty8uq: Copied (server-side copy) to: dst/three.txt 2020/12/12 05:33:41 INFO : dst/three.txt.rclone_chunk.001_7ty8uq: Deleted 2020/12/12 05:33:41 DEBUG : one: Sizes differ (src 5 vs dst 4) 2020/12/12 05:33:41 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7/dst': Waiting for checks to finish 2020/12/12 05:33:41 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/12/12 05:33:41 DEBUG : two: Unchanged skipping 2020/12/12 05:33:41 DEBUG : one: copy non-chunked object... 2020/12/12 05:33:41 DEBUG : one: MD5 = c7957179c41f69d44f217a108c7915d8 OK 2020/12/12 05:33:41 INFO : one: Copied (server-side copy) 2020/12/12 05:33:41 INFO : one: Deleted 2020/12/12 05:33:41 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7/dst': Waiting for transfers to finish 2020/12/12 05:33:41 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:33:42 DEBUG : one.rclone_chunk.001_7ty89l: MD5 = 0f93e81041f0cab37c37a05ae998b219 OK 2020/12/12 05:33:42 INFO : one.rclone_chunk.001_7ty89l: Copied (server-side copy) to: one 2020/12/12 05:33:42 INFO : one.rclone_chunk.001_7ty89l: Deleted 2020/12/12 05:33:42 DEBUG : one: MD5 = 0f93e81041f0cab37c37a05ae998b219 OK 2020/12/12 05:33:42 INFO : one: Copied (new) 2020/12/12 05:33:42 DEBUG : Waiting for deletions to finish 2020/12/12 05:33:42 DEBUG : three.txt: copy non-chunked object... 2020/12/12 05:33:42 DEBUG : three.txt: MD5 = 91341eed84691a83caea73aa785736d5 OK 2020/12/12 05:33:42 INFO : three.txt: Copied (server-side copy) 2020/12/12 05:33:42 INFO : three.txt: Deleted 2020/12/12 05:33:42 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (2.53s) === RUN TestSyncBackupDirWithSuffix run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" 2020/12/12 05:33:42 DEBUG : dst/one.rclone_chunk.001_7ty92h: MD5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2020/12/12 05:33:42 INFO : dst/one.rclone_chunk.001_7ty92h: Copied (server-side copy) to: dst/one 2020/12/12 05:33:42 INFO : dst/one.rclone_chunk.001_7ty92h: Deleted 2020/12/12 05:33:42 DEBUG : dst/two.rclone_chunk.001_7ty973: MD5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2020/12/12 05:33:42 INFO : dst/two.rclone_chunk.001_7ty973: Copied (server-side copy) to: dst/two 2020/12/12 05:33:42 INFO : dst/two.rclone_chunk.001_7ty973: Deleted 2020/12/12 05:33:43 DEBUG : dst/three.txt.rclone_chunk.001_7ty9o4: MD5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2020/12/12 05:33:43 INFO : dst/three.txt.rclone_chunk.001_7ty9o4: Copied (server-side copy) to: dst/three.txt 2020/12/12 05:33:43 INFO : dst/three.txt.rclone_chunk.001_7ty9o4: Deleted 2020/12/12 05:33:43 DEBUG : Creating backend with remote "TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7/dst" 2020/12/12 05:33:43 DEBUG : Creating backend with remote "TestS3:rclone-test-kajibaz6yucuyeb5qeradih7/dst" 2020/12/12 05:33:43 DEBUG : Reset feature "ListR" 2020/12/12 05:33:43 DEBUG : Creating backend with remote "TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7/backup" 2020/12/12 05:33:43 DEBUG : Creating backend with remote "TestS3:rclone-test-kajibaz6yucuyeb5qeradih7/backup" 2020/12/12 05:33:43 DEBUG : Reset feature "ListR" 2020/12/12 05:33:43 DEBUG : one: Sizes differ (src 4 vs dst 3) 2020/12/12 05:33:43 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7/dst': Waiting for checks to finish 2020/12/12 05:33:43 DEBUG : one: copy non-chunked object... 2020/12/12 05:33:43 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/12/12 05:33:43 DEBUG : two: Unchanged skipping 2020/12/12 05:33:43 DEBUG : one: MD5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2020/12/12 05:33:43 INFO : one: Copied (server-side copy) to: one.bak 2020/12/12 05:33:43 INFO : one: Deleted 2020/12/12 05:33:43 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7/dst': Waiting for transfers to finish 2020/12/12 05:33:43 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:33:44 DEBUG : one.rclone_chunk.001_7tyayi: MD5 = c7957179c41f69d44f217a108c7915d8 OK 2020/12/12 05:33:44 INFO : one.rclone_chunk.001_7tyayi: Copied (server-side copy) to: one 2020/12/12 05:33:44 INFO : one.rclone_chunk.001_7tyayi: Deleted 2020/12/12 05:33:44 DEBUG : one: MD5 = c7957179c41f69d44f217a108c7915d8 OK 2020/12/12 05:33:44 INFO : one: Copied (new) 2020/12/12 05:33:44 DEBUG : Waiting for deletions to finish 2020/12/12 05:33:44 DEBUG : three.txt: copy non-chunked object... 2020/12/12 05:33:44 DEBUG : three.txt: MD5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2020/12/12 05:33:44 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2020/12/12 05:33:44 INFO : three.txt: Deleted 2020/12/12 05:33:44 INFO : three.txt: Moved into backup dir 2020/12/12 05:33:44 DEBUG : dst/three.txt.rclone_chunk.001_7tyb92: MD5 = 91341eed84691a83caea73aa785736d5 OK 2020/12/12 05:33:44 INFO : dst/three.txt.rclone_chunk.001_7tyb92: Copied (server-side copy) to: dst/three.txt 2020/12/12 05:33:44 INFO : dst/three.txt.rclone_chunk.001_7tyb92: Deleted 2020/12/12 05:33:44 DEBUG : one: Sizes differ (src 5 vs dst 4) 2020/12/12 05:33:44 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7/dst': Waiting for checks to finish 2020/12/12 05:33:44 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/12/12 05:33:44 DEBUG : two: Unchanged skipping 2020/12/12 05:33:44 DEBUG : one: copy non-chunked object... 2020/12/12 05:33:44 DEBUG : one: MD5 = c7957179c41f69d44f217a108c7915d8 OK 2020/12/12 05:33:44 INFO : one: Copied (server-side copy) to: one.bak 2020/12/12 05:33:44 INFO : one: Deleted 2020/12/12 05:33:44 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7/dst': Waiting for transfers to finish 2020/12/12 05:33:44 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:33:44 DEBUG : one.rclone_chunk.001_7tybru: MD5 = 0f93e81041f0cab37c37a05ae998b219 OK 2020/12/12 05:33:44 INFO : one.rclone_chunk.001_7tybru: Copied (server-side copy) to: one 2020/12/12 05:33:44 INFO : one.rclone_chunk.001_7tybru: Deleted 2020/12/12 05:33:44 DEBUG : one: MD5 = 0f93e81041f0cab37c37a05ae998b219 OK 2020/12/12 05:33:44 INFO : one: Copied (new) 2020/12/12 05:33:44 DEBUG : Waiting for deletions to finish 2020/12/12 05:33:44 DEBUG : three.txt: copy non-chunked object... 2020/12/12 05:33:45 DEBUG : three.txt: MD5 = 91341eed84691a83caea73aa785736d5 OK 2020/12/12 05:33:45 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2020/12/12 05:33:45 INFO : three.txt: Deleted 2020/12/12 05:33:45 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (2.77s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" 2020/12/12 05:33:45 DEBUG : dst/one.rclone_chunk.001_7tyc8g: MD5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2020/12/12 05:33:45 INFO : dst/one.rclone_chunk.001_7tyc8g: Copied (server-side copy) to: dst/one 2020/12/12 05:33:45 INFO : dst/one.rclone_chunk.001_7tyc8g: Deleted 2020/12/12 05:33:45 DEBUG : dst/two.rclone_chunk.001_7tyc01: MD5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2020/12/12 05:33:45 INFO : dst/two.rclone_chunk.001_7tyc01: Copied (server-side copy) to: dst/two 2020/12/12 05:33:45 INFO : dst/two.rclone_chunk.001_7tyc01: Deleted 2020/12/12 05:33:45 DEBUG : dst/three.txt.rclone_chunk.001_7tycz4: MD5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2020/12/12 05:33:45 INFO : dst/three.txt.rclone_chunk.001_7tycz4: Copied (server-side copy) to: dst/three.txt 2020/12/12 05:33:45 INFO : dst/three.txt.rclone_chunk.001_7tycz4: Deleted 2020/12/12 05:33:46 DEBUG : Creating backend with remote "TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7/dst" 2020/12/12 05:33:46 DEBUG : Creating backend with remote "TestS3:rclone-test-kajibaz6yucuyeb5qeradih7/dst" 2020/12/12 05:33:46 DEBUG : Reset feature "ListR" 2020/12/12 05:33:46 DEBUG : Creating backend with remote "TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7/backup" 2020/12/12 05:33:46 DEBUG : Creating backend with remote "TestS3:rclone-test-kajibaz6yucuyeb5qeradih7/backup" 2020/12/12 05:33:46 DEBUG : Reset feature "ListR" 2020/12/12 05:33:46 DEBUG : one: Sizes differ (src 4 vs dst 3) 2020/12/12 05:33:46 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7/dst': Waiting for checks to finish 2020/12/12 05:33:46 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/12/12 05:33:46 DEBUG : two: Unchanged skipping 2020/12/12 05:33:46 DEBUG : one: copy non-chunked object... 2020/12/12 05:33:46 DEBUG : one: MD5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2020/12/12 05:33:46 INFO : one: Copied (server-side copy) to: one-2019-01-01 2020/12/12 05:33:46 INFO : one: Deleted 2020/12/12 05:33:46 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7/dst': Waiting for transfers to finish 2020/12/12 05:33:46 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:33:46 DEBUG : one.rclone_chunk.001_7tydrf: MD5 = c7957179c41f69d44f217a108c7915d8 OK 2020/12/12 05:33:46 INFO : one.rclone_chunk.001_7tydrf: Copied (server-side copy) to: one 2020/12/12 05:33:46 INFO : one.rclone_chunk.001_7tydrf: Deleted 2020/12/12 05:33:46 DEBUG : one: MD5 = c7957179c41f69d44f217a108c7915d8 OK 2020/12/12 05:33:46 INFO : one: Copied (new) 2020/12/12 05:33:46 DEBUG : Waiting for deletions to finish 2020/12/12 05:33:46 DEBUG : three.txt: copy non-chunked object... 2020/12/12 05:33:46 DEBUG : three.txt: MD5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2020/12/12 05:33:46 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2020/12/12 05:33:46 INFO : three.txt: Deleted 2020/12/12 05:33:46 INFO : three.txt: Moved into backup dir 2020/12/12 05:33:47 DEBUG : dst/three.txt.rclone_chunk.001_7tyeo8: MD5 = 91341eed84691a83caea73aa785736d5 OK 2020/12/12 05:33:47 INFO : dst/three.txt.rclone_chunk.001_7tyeo8: Copied (server-side copy) to: dst/three.txt 2020/12/12 05:33:47 INFO : dst/three.txt.rclone_chunk.001_7tyeo8: Deleted 2020/12/12 05:33:47 DEBUG : one: Sizes differ (src 5 vs dst 4) 2020/12/12 05:33:47 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7/dst': Waiting for checks to finish 2020/12/12 05:33:47 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/12/12 05:33:47 DEBUG : two: Unchanged skipping 2020/12/12 05:33:47 DEBUG : one: copy non-chunked object... 2020/12/12 05:33:47 DEBUG : one: MD5 = c7957179c41f69d44f217a108c7915d8 OK 2020/12/12 05:33:47 INFO : one: Copied (server-side copy) to: one-2019-01-01 2020/12/12 05:33:47 INFO : one: Deleted 2020/12/12 05:33:47 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7/dst': Waiting for transfers to finish 2020/12/12 05:33:47 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:33:47 DEBUG : one.rclone_chunk.001_7tyea3: MD5 = 0f93e81041f0cab37c37a05ae998b219 OK 2020/12/12 05:33:47 INFO : one.rclone_chunk.001_7tyea3: Copied (server-side copy) to: one 2020/12/12 05:33:47 INFO : one.rclone_chunk.001_7tyea3: Deleted 2020/12/12 05:33:47 DEBUG : one: MD5 = 0f93e81041f0cab37c37a05ae998b219 OK 2020/12/12 05:33:47 INFO : one: Copied (new) 2020/12/12 05:33:47 DEBUG : Waiting for deletions to finish 2020/12/12 05:33:47 DEBUG : three.txt: copy non-chunked object... 2020/12/12 05:33:47 DEBUG : three.txt: MD5 = 91341eed84691a83caea73aa785736d5 OK 2020/12/12 05:33:47 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2020/12/12 05:33:47 INFO : three.txt: Deleted 2020/12/12 05:33:47 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (2.92s) === RUN TestSyncBackupDirSuffixOnly run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" 2020/12/12 05:33:48 DEBUG : dst/one.rclone_chunk.001_7tyfks: MD5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2020/12/12 05:33:48 INFO : dst/one.rclone_chunk.001_7tyfks: Copied (server-side copy) to: dst/one 2020/12/12 05:33:48 INFO : dst/one.rclone_chunk.001_7tyfks: Deleted 2020/12/12 05:33:48 DEBUG : dst/two.rclone_chunk.001_7tyfid: MD5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2020/12/12 05:33:48 INFO : dst/two.rclone_chunk.001_7tyfid: Copied (server-side copy) to: dst/two 2020/12/12 05:33:48 INFO : dst/two.rclone_chunk.001_7tyfid: Deleted 2020/12/12 05:33:49 DEBUG : dst/three.txt.rclone_chunk.001_7tyfd7: MD5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2020/12/12 05:33:49 INFO : dst/three.txt.rclone_chunk.001_7tyfd7: Copied (server-side copy) to: dst/three.txt 2020/12/12 05:33:49 INFO : dst/three.txt.rclone_chunk.001_7tyfd7: Deleted 2020/12/12 05:33:49 DEBUG : Creating backend with remote "TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7/dst" 2020/12/12 05:33:49 DEBUG : Creating backend with remote "TestS3:rclone-test-kajibaz6yucuyeb5qeradih7/dst" 2020/12/12 05:33:49 DEBUG : Reset feature "ListR" 2020/12/12 05:33:49 DEBUG : one: Sizes differ (src 4 vs dst 3) 2020/12/12 05:33:49 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7/dst': Waiting for checks to finish 2020/12/12 05:33:49 DEBUG : one: copy non-chunked object... 2020/12/12 05:33:49 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/12/12 05:33:49 DEBUG : two: Unchanged skipping 2020/12/12 05:33:49 DEBUG : one: MD5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2020/12/12 05:33:49 INFO : one: Copied (server-side copy) to: one.bak 2020/12/12 05:33:49 INFO : one: Deleted 2020/12/12 05:33:49 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7/dst': Waiting for transfers to finish 2020/12/12 05:33:49 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:33:49 DEBUG : one.rclone_chunk.001_7tygtm: MD5 = c7957179c41f69d44f217a108c7915d8 OK 2020/12/12 05:33:49 INFO : one.rclone_chunk.001_7tygtm: Copied (server-side copy) to: one 2020/12/12 05:33:49 INFO : one.rclone_chunk.001_7tygtm: Deleted 2020/12/12 05:33:49 DEBUG : one: MD5 = c7957179c41f69d44f217a108c7915d8 OK 2020/12/12 05:33:49 INFO : one: Copied (new) 2020/12/12 05:33:49 DEBUG : Waiting for deletions to finish 2020/12/12 05:33:49 DEBUG : three.txt: copy non-chunked object... 2020/12/12 05:33:49 DEBUG : three.txt: MD5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2020/12/12 05:33:49 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2020/12/12 05:33:49 INFO : three.txt: Deleted 2020/12/12 05:33:49 INFO : three.txt: Moved into backup dir 2020/12/12 05:33:50 DEBUG : dst/three.txt.rclone_chunk.001_7tyhvt: MD5 = 91341eed84691a83caea73aa785736d5 OK 2020/12/12 05:33:50 INFO : dst/three.txt.rclone_chunk.001_7tyhvt: Copied (server-side copy) to: dst/three.txt 2020/12/12 05:33:50 INFO : dst/three.txt.rclone_chunk.001_7tyhvt: Deleted 2020/12/12 05:33:50 DEBUG : one.bak: Excluded 2020/12/12 05:33:50 DEBUG : three.txt.bak: Excluded 2020/12/12 05:33:50 DEBUG : one: Sizes differ (src 5 vs dst 4) 2020/12/12 05:33:50 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7/dst': Waiting for checks to finish 2020/12/12 05:33:50 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/12/12 05:33:50 DEBUG : two: Unchanged skipping 2020/12/12 05:33:50 DEBUG : one: copy non-chunked object... 2020/12/12 05:33:50 DEBUG : one: MD5 = c7957179c41f69d44f217a108c7915d8 OK 2020/12/12 05:33:50 INFO : one: Copied (server-side copy) to: one.bak 2020/12/12 05:33:50 INFO : one: Deleted 2020/12/12 05:33:50 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7/dst': Waiting for transfers to finish 2020/12/12 05:33:50 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:33:50 DEBUG : one.rclone_chunk.001_7tyhzt: MD5 = 0f93e81041f0cab37c37a05ae998b219 OK 2020/12/12 05:33:50 INFO : one.rclone_chunk.001_7tyhzt: Copied (server-side copy) to: one 2020/12/12 05:33:50 INFO : one.rclone_chunk.001_7tyhzt: Deleted 2020/12/12 05:33:50 DEBUG : one: MD5 = 0f93e81041f0cab37c37a05ae998b219 OK 2020/12/12 05:33:50 INFO : one: Copied (new) 2020/12/12 05:33:50 DEBUG : Waiting for deletions to finish 2020/12/12 05:33:50 DEBUG : three.txt: copy non-chunked object... 2020/12/12 05:33:51 DEBUG : three.txt: MD5 = 91341eed84691a83caea73aa785736d5 OK 2020/12/12 05:33:51 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2020/12/12 05:33:51 INFO : three.txt: Deleted 2020/12/12 05:33:51 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirSuffixOnly (3.10s) === RUN TestSyncSuffix run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" 2020/12/12 05:33:51 DEBUG : dst/one.rclone_chunk.001_7tyi8s: MD5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2020/12/12 05:33:51 INFO : dst/one.rclone_chunk.001_7tyi8s: Copied (server-side copy) to: dst/one 2020/12/12 05:33:51 INFO : dst/one.rclone_chunk.001_7tyi8s: Deleted 2020/12/12 05:33:51 DEBUG : dst/two.rclone_chunk.001_7tyi0x: MD5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2020/12/12 05:33:51 INFO : dst/two.rclone_chunk.001_7tyi0x: Copied (server-side copy) to: dst/two 2020/12/12 05:33:51 INFO : dst/two.rclone_chunk.001_7tyi0x: Deleted 2020/12/12 05:33:52 DEBUG : dst/three.txt.rclone_chunk.001_7tyimh: MD5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2020/12/12 05:33:52 INFO : dst/three.txt.rclone_chunk.001_7tyimh: Copied (server-side copy) to: dst/three.txt 2020/12/12 05:33:52 INFO : dst/three.txt.rclone_chunk.001_7tyimh: Deleted 2020/12/12 05:33:52 DEBUG : Creating backend with remote "TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7/dst" 2020/12/12 05:33:52 DEBUG : Creating backend with remote "TestS3:rclone-test-kajibaz6yucuyeb5qeradih7/dst" 2020/12/12 05:33:52 DEBUG : Reset feature "ListR" 2020/12/12 05:33:52 DEBUG : one: Sizes differ (src 4 vs dst 3) 2020/12/12 05:33:52 DEBUG : one: copy non-chunked object... 2020/12/12 05:33:52 DEBUG : one: MD5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2020/12/12 05:33:52 INFO : one: Copied (server-side copy) to: one.bak 2020/12/12 05:33:52 INFO : one: Deleted 2020/12/12 05:33:52 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:33:52 DEBUG : one.rclone_chunk.001_7tyjaj: MD5 = c7957179c41f69d44f217a108c7915d8 OK 2020/12/12 05:33:52 INFO : one.rclone_chunk.001_7tyjaj: Copied (server-side copy) to: one 2020/12/12 05:33:52 INFO : one.rclone_chunk.001_7tyjaj: Deleted 2020/12/12 05:33:52 DEBUG : one: MD5 = c7957179c41f69d44f217a108c7915d8 OK 2020/12/12 05:33:52 INFO : one: Copied (new) 2020/12/12 05:33:52 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/12/12 05:33:52 DEBUG : two: Unchanged skipping 2020/12/12 05:33:52 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2020/12/12 05:33:52 DEBUG : three.txt: copy non-chunked object... 2020/12/12 05:33:52 DEBUG : three.txt: MD5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2020/12/12 05:33:52 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2020/12/12 05:33:52 INFO : three.txt: Deleted 2020/12/12 05:33:52 DEBUG : three.txt: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:33:52 DEBUG : three.txt.rclone_chunk.001_7tyj4l: MD5 = 91341eed84691a83caea73aa785736d5 OK 2020/12/12 05:33:52 INFO : three.txt.rclone_chunk.001_7tyj4l: Copied (server-side copy) to: three.txt 2020/12/12 05:33:52 INFO : three.txt.rclone_chunk.001_7tyj4l: Deleted 2020/12/12 05:33:52 DEBUG : three.txt: MD5 = 91341eed84691a83caea73aa785736d5 OK 2020/12/12 05:33:52 INFO : three.txt: Copied (new) 2020/12/12 05:33:53 DEBUG : one: Sizes differ (src 5 vs dst 4) 2020/12/12 05:33:53 DEBUG : one: copy non-chunked object... 2020/12/12 05:33:53 DEBUG : one: MD5 = c7957179c41f69d44f217a108c7915d8 OK 2020/12/12 05:33:53 INFO : one: Copied (server-side copy) to: one.bak 2020/12/12 05:33:53 INFO : one: Deleted 2020/12/12 05:33:53 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:33:53 DEBUG : one.rclone_chunk.001_7tykj5: MD5 = 0f93e81041f0cab37c37a05ae998b219 OK 2020/12/12 05:33:53 INFO : one.rclone_chunk.001_7tykj5: Copied (server-side copy) to: one 2020/12/12 05:33:53 INFO : one.rclone_chunk.001_7tykj5: Deleted 2020/12/12 05:33:53 DEBUG : one: MD5 = 0f93e81041f0cab37c37a05ae998b219 OK 2020/12/12 05:33:53 INFO : one: Copied (new) 2020/12/12 05:33:53 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/12/12 05:33:53 DEBUG : two: Unchanged skipping 2020/12/12 05:33:53 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2020/12/12 05:33:53 DEBUG : three.txt: copy non-chunked object... 2020/12/12 05:33:53 DEBUG : three.txt: MD5 = 91341eed84691a83caea73aa785736d5 OK 2020/12/12 05:33:53 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2020/12/12 05:33:53 INFO : three.txt: Deleted 2020/12/12 05:33:53 DEBUG : three.txt: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:33:53 DEBUG : three.txt.rclone_chunk.001_7tyk10: MD5 = 423e01c5e84a30d129b97aedb6e219a9 OK 2020/12/12 05:33:53 INFO : three.txt.rclone_chunk.001_7tyk10: Copied (server-side copy) to: three.txt 2020/12/12 05:33:53 INFO : three.txt.rclone_chunk.001_7tyk10: Deleted 2020/12/12 05:33:53 DEBUG : three.txt: MD5 = 423e01c5e84a30d129b97aedb6e219a9 OK 2020/12/12 05:33:53 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (2.84s) === RUN TestSyncSuffixKeepExtension run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" 2020/12/12 05:33:54 DEBUG : dst/one.rclone_chunk.001_7tylsv: MD5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2020/12/12 05:33:54 INFO : dst/one.rclone_chunk.001_7tylsv: Copied (server-side copy) to: dst/one 2020/12/12 05:33:54 INFO : dst/one.rclone_chunk.001_7tylsv: Deleted 2020/12/12 05:33:54 DEBUG : dst/two.rclone_chunk.001_7tylyt: MD5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2020/12/12 05:33:54 INFO : dst/two.rclone_chunk.001_7tylyt: Copied (server-side copy) to: dst/two 2020/12/12 05:33:54 INFO : dst/two.rclone_chunk.001_7tylyt: Deleted 2020/12/12 05:33:54 DEBUG : dst/three.txt.rclone_chunk.001_7tylfn: MD5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2020/12/12 05:33:54 INFO : dst/three.txt.rclone_chunk.001_7tylfn: Copied (server-side copy) to: dst/three.txt 2020/12/12 05:33:54 INFO : dst/three.txt.rclone_chunk.001_7tylfn: Deleted 2020/12/12 05:33:54 DEBUG : Creating backend with remote "TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7/dst" 2020/12/12 05:33:54 DEBUG : Creating backend with remote "TestS3:rclone-test-kajibaz6yucuyeb5qeradih7/dst" 2020/12/12 05:33:54 DEBUG : Reset feature "ListR" 2020/12/12 05:33:54 DEBUG : one: Sizes differ (src 4 vs dst 3) 2020/12/12 05:33:54 DEBUG : one: copy non-chunked object... 2020/12/12 05:33:54 DEBUG : one: MD5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2020/12/12 05:33:54 INFO : one: Copied (server-side copy) to: one-2019-01-01 2020/12/12 05:33:54 INFO : one: Deleted 2020/12/12 05:33:54 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:33:55 DEBUG : one.rclone_chunk.001_7tylfb: MD5 = c7957179c41f69d44f217a108c7915d8 OK 2020/12/12 05:33:55 INFO : one.rclone_chunk.001_7tylfb: Copied (server-side copy) to: one 2020/12/12 05:33:55 INFO : one.rclone_chunk.001_7tylfb: Deleted 2020/12/12 05:33:55 DEBUG : one: MD5 = c7957179c41f69d44f217a108c7915d8 OK 2020/12/12 05:33:55 INFO : one: Copied (new) 2020/12/12 05:33:55 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/12/12 05:33:55 DEBUG : two: Unchanged skipping 2020/12/12 05:33:55 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2020/12/12 05:33:55 DEBUG : three.txt: copy non-chunked object... 2020/12/12 05:33:55 DEBUG : three.txt: MD5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2020/12/12 05:33:55 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2020/12/12 05:33:55 INFO : three.txt: Deleted 2020/12/12 05:33:55 DEBUG : three.txt: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:33:55 DEBUG : three.txt.rclone_chunk.001_7tymzm: MD5 = 91341eed84691a83caea73aa785736d5 OK 2020/12/12 05:33:55 INFO : three.txt.rclone_chunk.001_7tymzm: Copied (server-side copy) to: three.txt 2020/12/12 05:33:55 INFO : three.txt.rclone_chunk.001_7tymzm: Deleted 2020/12/12 05:33:55 DEBUG : three.txt: MD5 = 91341eed84691a83caea73aa785736d5 OK 2020/12/12 05:33:55 INFO : three.txt: Copied (new) 2020/12/12 05:33:55 DEBUG : one: Sizes differ (src 5 vs dst 4) 2020/12/12 05:33:55 DEBUG : one: copy non-chunked object... 2020/12/12 05:33:55 DEBUG : one: MD5 = c7957179c41f69d44f217a108c7915d8 OK 2020/12/12 05:33:55 INFO : one: Copied (server-side copy) to: one-2019-01-01 2020/12/12 05:33:55 INFO : one: Deleted 2020/12/12 05:33:55 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:33:55 DEBUG : one.rclone_chunk.001_7tym0v: MD5 = 0f93e81041f0cab37c37a05ae998b219 OK 2020/12/12 05:33:55 INFO : one.rclone_chunk.001_7tym0v: Copied (server-side copy) to: one 2020/12/12 05:33:56 INFO : one.rclone_chunk.001_7tym0v: Deleted 2020/12/12 05:33:56 DEBUG : one: MD5 = 0f93e81041f0cab37c37a05ae998b219 OK 2020/12/12 05:33:56 INFO : one: Copied (new) 2020/12/12 05:33:56 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/12/12 05:33:56 DEBUG : two: Unchanged skipping 2020/12/12 05:33:56 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2020/12/12 05:33:56 DEBUG : three.txt: copy non-chunked object... 2020/12/12 05:33:56 DEBUG : three.txt: MD5 = 91341eed84691a83caea73aa785736d5 OK 2020/12/12 05:33:56 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2020/12/12 05:33:56 INFO : three.txt: Deleted 2020/12/12 05:33:56 DEBUG : three.txt: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:33:56 DEBUG : three.txt.rclone_chunk.001_7tynh7: MD5 = 423e01c5e84a30d129b97aedb6e219a9 OK 2020/12/12 05:33:56 INFO : three.txt.rclone_chunk.001_7tynh7: Copied (server-side copy) to: three.txt 2020/12/12 05:33:56 INFO : three.txt.rclone_chunk.001_7tynh7: Deleted 2020/12/12 05:33:56 DEBUG : three.txt: MD5 = 423e01c5e84a30d129b97aedb6e219a9 OK 2020/12/12 05:33:56 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (2.55s) === RUN TestSyncUTFNorm run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" 2020/12/12 05:33:56 DEBUG : Testêé.rclone_chunk.001_7tynaa: MD5 = 3b5c716b1f94ae30389ea40ea76fa0d5 OK 2020/12/12 05:33:56 INFO : Testêé.rclone_chunk.001_7tynaa: Copied (server-side copy) to: Testêé 2020/12/12 05:33:56 INFO : Testêé.rclone_chunk.001_7tynaa: Deleted 2020/12/12 05:33:56 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2020/12/12 05:33:56 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for checks to finish 2020/12/12 05:33:56 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for transfers to finish 2020/12/12 05:33:56 DEBUG : Testêé: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:33:57 DEBUG : Testêé.rclone_chunk.001_7tynw7: MD5 = ce114e4501d2f4e2dcea3e17b546f339 OK 2020/12/12 05:33:57 INFO : Testêé.rclone_chunk.001_7tynw7: Copied (server-side copy) to: Testêé 2020/12/12 05:33:57 INFO : Testêé.rclone_chunk.001_7tynw7: Deleted 2020/12/12 05:33:57 DEBUG : Testêé: MD5 = ce114e4501d2f4e2dcea3e17b546f339 OK 2020/12/12 05:33:57 INFO : Testêé: Copied (replaced existing) to: Testêé 2020/12/12 05:33:57 DEBUG : Waiting for deletions to finish --- PASS: TestSyncUTFNorm (0.53s) === RUN TestSyncImmutable run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" 2020/12/12 05:33:57 DEBUG : existing: skip slow MD5 on source file, hashing in-transit 2020/12/12 05:33:57 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for checks to finish 2020/12/12 05:33:57 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for transfers to finish 2020/12/12 05:33:57 DEBUG : existing.rclone_chunk.001_7tyoeb: MD5 = 8ee2027983915ec78acc45027d874316 OK 2020/12/12 05:33:57 INFO : existing.rclone_chunk.001_7tyoeb: Copied (server-side copy) to: existing 2020/12/12 05:33:57 INFO : existing.rclone_chunk.001_7tyoeb: Deleted 2020/12/12 05:33:57 DEBUG : existing: MD5 = 8ee2027983915ec78acc45027d874316 OK 2020/12/12 05:33:57 INFO : existing: Copied (new) 2020/12/12 05:33:57 DEBUG : Waiting for deletions to finish 2020/12/12 05:33:57 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for checks to finish 2020/12/12 05:33:57 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2020/12/12 05:33:57 ERROR : existing: Source and destination exist but do not match: immutable file modified 2020/12/12 05:33:57 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for transfers to finish 2020/12/12 05:33:57 ERROR : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': not deleting files as there were IO errors 2020/12/12 05:33:57 ERROR : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': not deleting directories as there were IO errors 2020/12/12 05:33:57 INFO : There was nothing to transfer --- PASS: TestSyncImmutable (0.47s) === RUN TestSyncIgnoreCase run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" 2020/12/12 05:33:57 DEBUG : EXISTING.rclone_chunk.001_7tyo64: MD5 = 8ee2027983915ec78acc45027d874316 OK 2020/12/12 05:33:57 INFO : EXISTING.rclone_chunk.001_7tyo64: Copied (server-side copy) to: EXISTING 2020/12/12 05:33:57 INFO : EXISTING.rclone_chunk.001_7tyo64: Deleted 2020/12/12 05:33:57 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for checks to finish 2020/12/12 05:33:57 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/12/12 05:33:57 DEBUG : existing: Unchanged skipping 2020/12/12 05:33:57 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Waiting for transfers to finish 2020/12/12 05:33:57 DEBUG : Waiting for deletions to finish 2020/12/12 05:33:57 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreCase (0.36s) === RUN TestMaxTransfer === RUN TestMaxTransfer/Hard run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" sync_test.go:1986: This test only runs on local === RUN TestMaxTransfer/Soft run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" sync_test.go:1986: This test only runs on local === RUN TestMaxTransfer/Cautious run.go:176: Remote "Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7'", Local "Local file system at /tmp/rclone393843524", Modify Window "1ns" sync_test.go:1986: This test only runs on local --- PASS: TestMaxTransfer (0.11s) --- SKIP: TestMaxTransfer/Hard (0.04s) --- SKIP: TestMaxTransfer/Soft (0.03s) --- SKIP: TestMaxTransfer/Cautious (0.04s) PASS 2020/12/12 05:33:58 DEBUG : Chunked 'TestChunkerChunk50bMD5HashS3:rclone-test-kajibaz6yucuyeb5qeradih7': Purge dir "" 2020/12/12 05:33:58 INFO : S3 bucket rclone-test-kajibaz6yucuyeb5qeradih7: Bucket "rclone-test-kajibaz6yucuyeb5qeradih7" deleted "./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bMD5HashS3: -verbose -fast-list -size-limit 1024" - Finished OK in 1m6.774364202s (try 1/5)