"./sync.test -test.v -test.timeout 30m0s -remote TestChunkerChunk50bSHA1QuickS3: -verbose -size-limit 1024" - Starting (try 1/5) === RUN TestPipe --- PASS: TestPipe (0.00s) === RUN TestPipeConcurrent --- PASS: TestPipeConcurrent (0.06s) === 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 --- 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) === RUN TestNewLess === RUN TestNewLess/blankOK === RUN TestNewLess/tooManyParts === 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 --- PASS: TestNewLess (0.00s) --- PASS: TestNewLess/blankOK (0.00s) --- PASS: TestNewLess/tooManyParts (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) === RUN TestRcCopy --- SKIP: TestRcCopy (0.00s) rc_test.go:16: Skipping test on non local remote === RUN TestRcMove --- SKIP: TestRcMove (0.00s) rc_test.go:16: Skipping test on non local remote === RUN TestRcSync --- SKIP: TestRcSync (0.00s) rc_test.go:16: Skipping test on non local remote === RUN TestCopyWithDryRun 2020/01/19 05:35:04 INFO : S3 bucket rclone-test-tapiyez7cubewab7lomotay5: Bucket "rclone-test-tapiyez7cubewab7lomotay5" created with ACL "private" 2020/01/19 05:35:04 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for checks to finish 2020/01/19 05:35:04 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for transfers to finish 2020/01/19 05:35:04 NOTICE: sub dir/hello world: Not copying as --dry-run --- PASS: TestCopyWithDryRun (0.92s) run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5'", Local "Local file system at /tmp/rclone910142536", Modify Window "1ns" === RUN TestCopy 2020/01/19 05:35:05 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for checks to finish 2020/01/19 05:35:05 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for transfers to finish 2020/01/19 05:35:05 DEBUG : sub dir/hello world.rclone_chunk.001_cf993z: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/01/19 05:35:05 INFO : sub dir/hello world.rclone_chunk.001_cf993z: Copied (server side copy) 2020/01/19 05:35:05 INFO : sub dir/hello world.rclone_chunk.001_cf993z: Deleted 2020/01/19 05:35:05 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopy (1.31s) run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5'", Local "Local file system at /tmp/rclone910142536", Modify Window "1ns" === RUN TestCopyMissingDirectory 2020/01/19 05:35:06 ERROR : : error reading source directory: directory not found 2020/01/19 05:35:06 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for checks to finish 2020/01/19 05:35:06 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for transfers to finish --- PASS: TestCopyMissingDirectory (0.25s) run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5'", Local "Local file system at /tmp/rclone910142536", Modify Window "1ns" === RUN TestCopyNoTraverse 2020/01/19 05:35:06 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for checks to finish 2020/01/19 05:35:06 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for transfers to finish 2020/01/19 05:35:06 DEBUG : sub dir/hello world.rclone_chunk.001_cf9a1i: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/01/19 05:35:06 INFO : sub dir/hello world.rclone_chunk.001_cf9a1i: Copied (server side copy) 2020/01/19 05:35:06 INFO : sub dir/hello world.rclone_chunk.001_cf9a1i: Deleted 2020/01/19 05:35:06 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoTraverse (0.18s) run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5'", Local "Local file system at /tmp/rclone910142536", Modify Window "1ns" === RUN TestSyncNoTraverse 2020/01/19 05:35:06 ERROR : Ignoring --no-traverse with sync 2020/01/19 05:35:07 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for checks to finish 2020/01/19 05:35:07 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for transfers to finish 2020/01/19 05:35:07 DEBUG : sub dir/hello world.rclone_chunk.001_cf9by1: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/01/19 05:35:07 INFO : sub dir/hello world.rclone_chunk.001_cf9by1: Copied (server side copy) 2020/01/19 05:35:07 INFO : sub dir/hello world.rclone_chunk.001_cf9by1: Deleted 2020/01/19 05:35:07 INFO : sub dir/hello world: Copied (new) 2020/01/19 05:35:07 INFO : Waiting for deletions to finish --- PASS: TestSyncNoTraverse (0.91s) run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5'", Local "Local file system at /tmp/rclone910142536", Modify Window "1ns" === RUN TestCopyWithDepth 2020/01/19 05:35:07 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for checks to finish 2020/01/19 05:35:07 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for transfers to finish 2020/01/19 05:35:07 DEBUG : hello world2.rclone_chunk.001_cf9bg7: MD5 = cc2c857f89648dbd139d7b2a6665957d OK 2020/01/19 05:35:07 INFO : hello world2.rclone_chunk.001_cf9bg7: Copied (server side copy) 2020/01/19 05:35:07 INFO : hello world2.rclone_chunk.001_cf9bg7: Deleted 2020/01/19 05:35:07 INFO : hello world2: Copied (new) --- PASS: TestCopyWithDepth (0.22s) run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5'", Local "Local file system at /tmp/rclone910142536", Modify Window "1ns" === RUN TestCopyWithFilesFrom 2020/01/19 05:35:07 DEBUG : hello world2: Excluded 2020/01/19 05:35:07 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for checks to finish 2020/01/19 05:35:07 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for transfers to finish 2020/01/19 05:35:08 DEBUG : potato2.rclone_chunk.001_cf9b9b: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/01/19 05:35:08 INFO : potato2.rclone_chunk.001_cf9b9b: Copied (server side copy) 2020/01/19 05:35:08 INFO : potato2.rclone_chunk.001_cf9b9b: Deleted 2020/01/19 05:35:08 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFrom (0.20s) run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5'", Local "Local file system at /tmp/rclone910142536", Modify Window "1ns" === RUN TestCopyWithFilesFromAndNoTraverse 2020/01/19 05:35:08 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for checks to finish 2020/01/19 05:35:08 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for transfers to finish 2020/01/19 05:35:08 DEBUG : potato2.rclone_chunk.001_cf9cfu: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/01/19 05:35:08 INFO : potato2.rclone_chunk.001_cf9cfu: Copied (server side copy) 2020/01/19 05:35:08 INFO : potato2.rclone_chunk.001_cf9cfu: Deleted 2020/01/19 05:35:08 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFromAndNoTraverse (0.41s) run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5'", Local "Local file system at /tmp/rclone910142536", Modify Window "1ns" === RUN TestCopyEmptyDirectories 2020/01/19 05:35:08 DEBUG : sub dir2: Making directory 2020/01/19 05:35:08 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for checks to finish 2020/01/19 05:35:08 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for transfers to finish 2020/01/19 05:35:08 DEBUG : sub dir/hello world.rclone_chunk.001_cf9cwv: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/01/19 05:35:08 INFO : sub dir/hello world.rclone_chunk.001_cf9cwv: Copied (server side copy) 2020/01/19 05:35:09 INFO : sub dir/hello world.rclone_chunk.001_cf9cwv: Deleted 2020/01/19 05:35:09 INFO : sub dir/hello world: Copied (new) 2020/01/19 05:35:09 DEBUG : sub dir2: Making directory 2020/01/19 05:35:09 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': copied 1 directories --- PASS: TestCopyEmptyDirectories (1.12s) run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5'", Local "Local file system at /tmp/rclone910142536", Modify Window "1ns" fstest.go:241: Filtering empty directory "sub dir2" === RUN TestMoveEmptyDirectories 2020/01/19 05:35:09 DEBUG : sub dir2: Making directory 2020/01/19 05:35:09 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for checks to finish 2020/01/19 05:35:09 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for transfers to finish 2020/01/19 05:35:09 DEBUG : sub dir/hello world.rclone_chunk.001_cf9d2v: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/01/19 05:35:09 INFO : sub dir/hello world.rclone_chunk.001_cf9d2v: Copied (server side copy) 2020/01/19 05:35:09 INFO : sub dir/hello world.rclone_chunk.001_cf9d2v: Deleted 2020/01/19 05:35:09 INFO : sub dir/hello world: Copied (new) 2020/01/19 05:35:09 INFO : sub dir/hello world: Deleted 2020/01/19 05:35:09 DEBUG : sub dir: Making directory 2020/01/19 05:35:09 DEBUG : sub dir2: Making directory 2020/01/19 05:35:09 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': copied 2 directories --- PASS: TestMoveEmptyDirectories (0.44s) run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5'", Local "Local file system at /tmp/rclone910142536", Modify Window "1ns" fstest.go:241: Filtering empty directory "sub dir2" === RUN TestSyncEmptyDirectories 2020/01/19 05:35:10 DEBUG : sub dir2: Making directory 2020/01/19 05:35:10 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for checks to finish 2020/01/19 05:35:10 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for transfers to finish 2020/01/19 05:35:10 DEBUG : sub dir/hello world.rclone_chunk.001_cf9e3x: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/01/19 05:35:10 INFO : sub dir/hello world.rclone_chunk.001_cf9e3x: Copied (server side copy) 2020/01/19 05:35:10 INFO : sub dir/hello world.rclone_chunk.001_cf9e3x: Deleted 2020/01/19 05:35:10 INFO : sub dir/hello world: Copied (new) 2020/01/19 05:35:10 DEBUG : sub dir2: Making directory 2020/01/19 05:35:10 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': copied 1 directories 2020/01/19 05:35:10 INFO : Waiting for deletions to finish --- PASS: TestSyncEmptyDirectories (0.20s) run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5'", Local "Local file system at /tmp/rclone910142536", Modify Window "1ns" fstest.go:241: Filtering empty directory "sub dir2" === RUN TestServerSideCopy 2020/01/19 05:35:10 DEBUG : sub dir/hello world.rclone_chunk.001_cf9eph: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/01/19 05:35:10 INFO : sub dir/hello world.rclone_chunk.001_cf9eph: Copied (server side copy) 2020/01/19 05:35:10 INFO : sub dir/hello world.rclone_chunk.001_cf9eph: Deleted 2020/01/19 05:35:10 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-nilayut3girebut3bogurig1': Waiting for checks to finish 2020/01/19 05:35:10 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-nilayut3girebut3bogurig1': Waiting for transfers to finish 2020/01/19 05:35:10 DEBUG : sub dir/hello world: copy non-chunked object... 2020/01/19 05:35:11 INFO : S3 bucket rclone-test-nilayut3girebut3bogurig1: Bucket "rclone-test-nilayut3girebut3bogurig1" created with ACL "private" 2020/01/19 05:35:11 INFO : sub dir/hello world: Copied (server side copy) 2020/01/19 05:35:11 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-nilayut3girebut3bogurig1': Purge object "sub dir/hello world" 2020/01/19 05:35:11 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-nilayut3girebut3bogurig1': Purge dir "sub dir" 2020/01/19 05:35:11 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-nilayut3girebut3bogurig1': Purge dir "" 2020/01/19 05:35:12 INFO : S3 bucket rclone-test-nilayut3girebut3bogurig1: Bucket "rclone-test-nilayut3girebut3bogurig1" deleted --- PASS: TestServerSideCopy (1.81s) run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5'", Local "Local file system at /tmp/rclone910142536", Modify Window "1ns" sync_test.go:257: Server side copy (if possible) Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5' -> Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-nilayut3girebut3bogurig1' === RUN TestCopyAfterDelete 2020/01/19 05:35:12 DEBUG : sub dir/hello world.rclone_chunk.001_cf9ggc: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/01/19 05:35:12 INFO : sub dir/hello world.rclone_chunk.001_cf9ggc: Copied (server side copy) 2020/01/19 05:35:12 INFO : sub dir/hello world.rclone_chunk.001_cf9ggc: Deleted 2020/01/19 05:35:12 ERROR : : error listing: directory not found 2020/01/19 05:35:12 DEBUG : Local file system at /tmp/rclone910142536: Making directory 2020/01/19 05:35:19 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for checks to finish 2020/01/19 05:35:19 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for transfers to finish --- PASS: TestCopyAfterDelete (7.49s) run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5'", Local "Local file system at /tmp/rclone910142536", Modify Window "1ns" === RUN TestCopyRedownload 2020/01/19 05:35:19 DEBUG : sub dir/hello world.rclone_chunk.001_cf9noj: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/01/19 05:35:19 INFO : sub dir/hello world.rclone_chunk.001_cf9noj: Copied (server side copy) 2020/01/19 05:35:19 INFO : sub dir/hello world.rclone_chunk.001_cf9noj: Deleted 2020/01/19 05:35:19 INFO : Local file system at /tmp/rclone910142536: Waiting for checks to finish 2020/01/19 05:35:19 INFO : Local file system at /tmp/rclone910142536: Waiting for transfers to finish 2020/01/19 05:35:19 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyRedownload (0.23s) run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5'", Local "Local file system at /tmp/rclone910142536", Modify Window "1ns" === RUN TestSyncBasedOnCheckSum 2020/01/19 05:35:20 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for checks to finish 2020/01/19 05:35:20 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for transfers to finish 2020/01/19 05:35:20 DEBUG : check sum.rclone_chunk.001_cf9o34: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/01/19 05:35:20 INFO : check sum.rclone_chunk.001_cf9o34: Copied (server side copy) 2020/01/19 05:35:20 INFO : check sum.rclone_chunk.001_cf9o34: Deleted 2020/01/19 05:35:20 INFO : check sum: Copied (new) 2020/01/19 05:35:20 INFO : Waiting for deletions to finish 2020/01/19 05:35:20 NOTICE: Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2020/01/19 05:35:20 DEBUG : check sum: Size of src and dst objects identical 2020/01/19 05:35:20 DEBUG : check sum: Unchanged skipping 2020/01/19 05:35:20 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for checks to finish 2020/01/19 05:35:20 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for transfers to finish 2020/01/19 05:35:20 INFO : Waiting for deletions to finish --- PASS: TestSyncBasedOnCheckSum (0.45s) run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5'", Local "Local file system at /tmp/rclone910142536", Modify Window "1ns" === RUN TestSyncSizeOnly 2020/01/19 05:35:20 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for checks to finish 2020/01/19 05:35:20 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for transfers to finish 2020/01/19 05:35:20 DEBUG : sizeonly.rclone_chunk.001_cf9ojv: MD5 = 8ee2027983915ec78acc45027d874316 OK 2020/01/19 05:35:20 INFO : sizeonly.rclone_chunk.001_cf9ojv: Copied (server side copy) 2020/01/19 05:35:20 INFO : sizeonly.rclone_chunk.001_cf9ojv: Deleted 2020/01/19 05:35:20 INFO : sizeonly: Copied (new) 2020/01/19 05:35:20 INFO : Waiting for deletions to finish 2020/01/19 05:35:20 DEBUG : sizeonly: Sizes identical 2020/01/19 05:35:20 DEBUG : sizeonly: Unchanged skipping 2020/01/19 05:35:20 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for checks to finish 2020/01/19 05:35:20 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for transfers to finish 2020/01/19 05:35:20 INFO : Waiting for deletions to finish --- PASS: TestSyncSizeOnly (0.48s) run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5'", Local "Local file system at /tmp/rclone910142536", Modify Window "1ns" === RUN TestSyncIgnoreSize 2020/01/19 05:35:20 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for checks to finish 2020/01/19 05:35:20 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for transfers to finish 2020/01/19 05:35:20 DEBUG : ignore-size.rclone_chunk.001_cf9oxd: MD5 = 98bf7d8c15784f0a3d63204441e1e2aa OK 2020/01/19 05:35:20 INFO : ignore-size.rclone_chunk.001_cf9oxd: Copied (server side copy) 2020/01/19 05:35:20 INFO : ignore-size.rclone_chunk.001_cf9oxd: Deleted 2020/01/19 05:35:20 INFO : ignore-size: Copied (new) 2020/01/19 05:35:20 INFO : Waiting for deletions to finish 2020/01/19 05:35:20 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for checks to finish 2020/01/19 05:35:20 DEBUG : ignore-size: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:35:20 DEBUG : ignore-size: Unchanged skipping 2020/01/19 05:35:20 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for transfers to finish 2020/01/19 05:35:20 INFO : Waiting for deletions to finish --- PASS: TestSyncIgnoreSize (0.45s) run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5'", Local "Local file system at /tmp/rclone910142536", Modify Window "1ns" === RUN TestSyncIgnoreTimes 2020/01/19 05:35:21 DEBUG : existing.rclone_chunk.001_cf9pb6: MD5 = 8ee2027983915ec78acc45027d874316 OK 2020/01/19 05:35:21 INFO : existing.rclone_chunk.001_cf9pb6: Copied (server side copy) 2020/01/19 05:35:21 INFO : existing.rclone_chunk.001_cf9pb6: Deleted 2020/01/19 05:35:21 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for checks to finish 2020/01/19 05:35:21 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:35:21 DEBUG : existing: Unchanged skipping 2020/01/19 05:35:21 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for transfers to finish 2020/01/19 05:35:21 INFO : Waiting for deletions to finish 2020/01/19 05:35:21 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2020/01/19 05:35:21 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for checks to finish 2020/01/19 05:35:21 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for transfers to finish 2020/01/19 05:35:21 DEBUG : existing.rclone_chunk.001_cf9pje: MD5 = 8ee2027983915ec78acc45027d874316 OK 2020/01/19 05:35:21 INFO : existing.rclone_chunk.001_cf9pje: Copied (server side copy) 2020/01/19 05:35:21 INFO : existing.rclone_chunk.001_cf9pje: Deleted 2020/01/19 05:35:21 INFO : existing: Copied (replaced existing) 2020/01/19 05:35:21 INFO : Waiting for deletions to finish --- PASS: TestSyncIgnoreTimes (1.26s) run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5'", Local "Local file system at /tmp/rclone910142536", Modify Window "1ns" === RUN TestSyncIgnoreExisting 2020/01/19 05:35:22 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for checks to finish 2020/01/19 05:35:22 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for transfers to finish 2020/01/19 05:35:23 DEBUG : existing.rclone_chunk.001_cf9qec: MD5 = 8ee2027983915ec78acc45027d874316 OK 2020/01/19 05:35:23 INFO : existing.rclone_chunk.001_cf9qec: Copied (server side copy) 2020/01/19 05:35:23 INFO : existing.rclone_chunk.001_cf9qec: Deleted 2020/01/19 05:35:23 INFO : existing: Copied (new) 2020/01/19 05:35:23 INFO : Waiting for deletions to finish 2020/01/19 05:35:23 DEBUG : existing: Destination exists, skipping 2020/01/19 05:35:23 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for checks to finish 2020/01/19 05:35:23 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for transfers to finish 2020/01/19 05:35:23 INFO : Waiting for deletions to finish --- PASS: TestSyncIgnoreExisting (0.88s) run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5'", Local "Local file system at /tmp/rclone910142536", Modify Window "1ns" === RUN TestSyncIgnoreErrors 2020/01/19 05:35:23 DEBUG : b/potato.rclone_chunk.001_cf9rez: MD5 = e4cb6955d9106df6263c45fcfc10f163 OK 2020/01/19 05:35:23 INFO : b/potato.rclone_chunk.001_cf9rez: Copied (server side copy) 2020/01/19 05:35:23 INFO : b/potato.rclone_chunk.001_cf9rez: Deleted 2020/01/19 05:35:23 DEBUG : c/non empty space.rclone_chunk.001_cf9r4v: MD5 = ec26039bacb958460a511485fc59d24e OK 2020/01/19 05:35:23 INFO : c/non empty space.rclone_chunk.001_cf9r4v: Copied (server side copy) 2020/01/19 05:35:23 INFO : c/non empty space.rclone_chunk.001_cf9r4v: Deleted 2020/01/19 05:35:23 DEBUG : d: Making directory 2020/01/19 05:35:23 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:35:23 DEBUG : c/non empty space: Unchanged skipping 2020/01/19 05:35:23 DEBUG : a/potato2.rclone_chunk.001_cf9r79: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2020/01/19 05:35:23 INFO : a/potato2.rclone_chunk.001_cf9r79: Copied (server side copy) 2020/01/19 05:35:23 INFO : a/potato2.rclone_chunk.001_cf9r79: Deleted 2020/01/19 05:35:23 DEBUG : a/potato2.rclone_chunk.002_cf9r79: MD5 = 01a6651a94ab602dbfc47587d234b52c OK 2020/01/19 05:35:23 INFO : a/potato2.rclone_chunk.002_cf9r79: Copied (server side copy) 2020/01/19 05:35:23 INFO : a/potato2.rclone_chunk.002_cf9r79: Deleted 2020/01/19 05:35:23 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for checks to finish 2020/01/19 05:35:23 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for transfers to finish 2020/01/19 05:35:23 INFO : a/potato2: Copied (new) 2020/01/19 05:35:23 INFO : Waiting for deletions to finish 2020/01/19 05:35:23 INFO : b/potato: Deleted --- PASS: TestSyncIgnoreErrors (0.68s) run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5'", Local "Local file system at /tmp/rclone910142536", Modify Window "1ns" fstest.go:241: Filtering empty directory "d" === RUN TestSyncAfterChangingModtimeOnly 2020/01/19 05:35:24 DEBUG : empty space.rclone_chunk.001_cf9sui: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/01/19 05:35:24 INFO : empty space.rclone_chunk.001_cf9sui: Copied (server side copy) 2020/01/19 05:35:24 INFO : empty space.rclone_chunk.001_cf9sui: Deleted 2020/01/19 05:35:24 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for checks to finish 2020/01/19 05:35:24 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/01/19 05:35:24 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for transfers to finish 2020/01/19 05:35:24 NOTICE: empty space: Not copying as --dry-run 2020/01/19 05:35:24 INFO : Waiting for deletions to finish 2020/01/19 05:35:24 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for checks to finish 2020/01/19 05:35:24 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/01/19 05:35:24 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for transfers to finish 2020/01/19 05:35:24 DEBUG : empty space.rclone_chunk.001_cf9s45: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/01/19 05:35:24 INFO : empty space.rclone_chunk.001_cf9s45: Copied (server side copy) 2020/01/19 05:35:24 INFO : empty space.rclone_chunk.001_cf9s45: Deleted 2020/01/19 05:35:24 INFO : empty space: Copied (replaced existing) 2020/01/19 05:35:24 INFO : Waiting for deletions to finish --- PASS: TestSyncAfterChangingModtimeOnly (0.61s) run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5'", Local "Local file system at /tmp/rclone910142536", Modify Window "1ns" === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (0.24s) run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5'", Local "Local file system at /tmp/rclone910142536", Modify Window "1ns" sync_test.go:558: Can't check this if no hashes supported === RUN TestSyncDoesntUpdateModtime 2020/01/19 05:35:25 DEBUG : foo.rclone_chunk.001_cf9sr4: MD5 = 37b51d194a7513e45b56f6524f2d51f2 OK 2020/01/19 05:35:25 INFO : foo.rclone_chunk.001_cf9sr4: Copied (server side copy) 2020/01/19 05:35:25 INFO : foo.rclone_chunk.001_cf9sr4: Deleted 2020/01/19 05:35:25 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for checks to finish 2020/01/19 05:35:25 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/01/19 05:35:25 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for transfers to finish 2020/01/19 05:35:25 DEBUG : foo.rclone_chunk.001_cf9tb0: MD5 = acbd18db4cc2f85cedef654fccc4a4d8 OK 2020/01/19 05:35:25 INFO : foo.rclone_chunk.001_cf9tb0: Copied (server side copy) 2020/01/19 05:35:25 INFO : foo.rclone_chunk.001_cf9tb0: Deleted 2020/01/19 05:35:25 INFO : foo: Copied (replaced existing) 2020/01/19 05:35:25 INFO : Waiting for deletions to finish --- PASS: TestSyncDoesntUpdateModtime (0.62s) run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5'", Local "Local file system at /tmp/rclone910142536", Modify Window "1ns" === RUN TestSyncAfterAddingAFile 2020/01/19 05:35:25 DEBUG : empty space.rclone_chunk.001_cf9t33: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/01/19 05:35:25 INFO : empty space.rclone_chunk.001_cf9t33: Copied (server side copy) 2020/01/19 05:35:25 INFO : empty space.rclone_chunk.001_cf9t33: Deleted 2020/01/19 05:35:25 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for checks to finish 2020/01/19 05:35:25 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:35:25 DEBUG : empty space: Unchanged skipping 2020/01/19 05:35:25 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for transfers to finish 2020/01/19 05:35:26 DEBUG : potato.rclone_chunk.001_cf9tw5: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2020/01/19 05:35:26 INFO : potato.rclone_chunk.001_cf9tw5: Copied (server side copy) 2020/01/19 05:35:26 INFO : potato.rclone_chunk.001_cf9tw5: Deleted 2020/01/19 05:35:26 DEBUG : potato.rclone_chunk.002_cf9tw5: MD5 = 01a6651a94ab602dbfc47587d234b52c OK 2020/01/19 05:35:26 INFO : potato.rclone_chunk.002_cf9tw5: Copied (server side copy) 2020/01/19 05:35:26 INFO : potato.rclone_chunk.002_cf9tw5: Deleted 2020/01/19 05:35:26 INFO : potato: Copied (new) 2020/01/19 05:35:26 INFO : Waiting for deletions to finish --- PASS: TestSyncAfterAddingAFile (1.60s) run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5'", Local "Local file system at /tmp/rclone910142536", Modify Window "1ns" === RUN TestSyncAfterChangingFilesSizeOnly 2020/01/19 05:35:27 DEBUG : potato.rclone_chunk.001_cf9vtv: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2020/01/19 05:35:27 INFO : potato.rclone_chunk.001_cf9vtv: Copied (server side copy) 2020/01/19 05:35:27 INFO : potato.rclone_chunk.001_cf9vtv: Deleted 2020/01/19 05:35:27 DEBUG : potato.rclone_chunk.002_cf9vtv: MD5 = 01a6651a94ab602dbfc47587d234b52c OK 2020/01/19 05:35:27 INFO : potato.rclone_chunk.002_cf9vtv: Copied (server side copy) 2020/01/19 05:35:27 INFO : potato.rclone_chunk.002_cf9vtv: Deleted 2020/01/19 05:35:27 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for checks to finish 2020/01/19 05:35:27 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2020/01/19 05:35:27 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for transfers to finish 2020/01/19 05:35:27 DEBUG : potato.rclone_chunk.001_cf9v3k: MD5 = 100defcf18c42a1e0dc42a789b107cd2 OK 2020/01/19 05:35:27 INFO : potato.rclone_chunk.001_cf9v3k: Copied (server side copy) 2020/01/19 05:35:27 INFO : potato.rclone_chunk.001_cf9v3k: Deleted 2020/01/19 05:35:27 INFO : potato: Copied (replaced existing) 2020/01/19 05:35:27 INFO : Waiting for deletions to finish --- PASS: TestSyncAfterChangingFilesSizeOnly (1.22s) run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5'", Local "Local file system at /tmp/rclone910142536", Modify Window "1ns" === RUN TestSyncAfterChangingContentsOnly 2020/01/19 05:35:28 DEBUG : potato.rclone_chunk.001_cf9wre: MD5 = 100defcf18c42a1e0dc42a789b107cd2 OK 2020/01/19 05:35:28 INFO : potato.rclone_chunk.001_cf9wre: Copied (server side copy) 2020/01/19 05:35:28 INFO : potato.rclone_chunk.001_cf9wre: Deleted 2020/01/19 05:35:28 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for checks to finish 2020/01/19 05:35:28 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/01/19 05:35:28 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for transfers to finish 2020/01/19 05:35:28 DEBUG : potato.rclone_chunk.001_cf9wjr: MD5 = e4cb6955d9106df6263c45fcfc10f163 OK 2020/01/19 05:35:28 INFO : potato.rclone_chunk.001_cf9wjr: Copied (server side copy) 2020/01/19 05:35:28 INFO : potato.rclone_chunk.001_cf9wjr: Deleted 2020/01/19 05:35:28 INFO : potato: Copied (replaced existing) 2020/01/19 05:35:28 INFO : Waiting for deletions to finish --- PASS: TestSyncAfterChangingContentsOnly (0.80s) run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5'", Local "Local file system at /tmp/rclone910142536", Modify Window "1ns" === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun 2020/01/19 05:35:29 DEBUG : potato.rclone_chunk.001_cf9xpj: MD5 = e4cb6955d9106df6263c45fcfc10f163 OK 2020/01/19 05:35:29 INFO : potato.rclone_chunk.001_cf9xpj: Copied (server side copy) 2020/01/19 05:35:29 INFO : potato.rclone_chunk.001_cf9xpj: Deleted 2020/01/19 05:35:29 DEBUG : empty space.rclone_chunk.001_cf9xt9: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/01/19 05:35:29 INFO : empty space.rclone_chunk.001_cf9xt9: Copied (server side copy) 2020/01/19 05:35:29 INFO : empty space.rclone_chunk.001_cf9xt9: Deleted 2020/01/19 05:35:29 NOTICE: potato2: Not copying as --dry-run 2020/01/19 05:35:29 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for checks to finish 2020/01/19 05:35:29 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:35:29 DEBUG : empty space: Unchanged skipping 2020/01/19 05:35:29 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for transfers to finish 2020/01/19 05:35:29 INFO : Waiting for deletions to finish 2020/01/19 05:35:29 NOTICE: potato: Not deleting as --dry-run --- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (1.20s) run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5'", Local "Local file system at /tmp/rclone910142536", Modify Window "1ns" === RUN TestSyncAfterRemovingAFileAndAddingAFile 2020/01/19 05:35:30 DEBUG : potato.rclone_chunk.001_cf9ypd: MD5 = e4cb6955d9106df6263c45fcfc10f163 OK 2020/01/19 05:35:30 INFO : potato.rclone_chunk.001_cf9ypd: Copied (server side copy) 2020/01/19 05:35:30 INFO : potato.rclone_chunk.001_cf9ypd: Deleted 2020/01/19 05:35:30 DEBUG : empty space.rclone_chunk.001_cf9ymw: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/01/19 05:35:30 INFO : empty space.rclone_chunk.001_cf9ymw: Copied (server side copy) 2020/01/19 05:35:30 INFO : empty space.rclone_chunk.001_cf9ymw: Deleted 2020/01/19 05:35:30 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for checks to finish 2020/01/19 05:35:30 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:35:30 DEBUG : empty space: Unchanged skipping 2020/01/19 05:35:30 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for transfers to finish 2020/01/19 05:35:30 DEBUG : potato2.rclone_chunk.001_cf9yae: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2020/01/19 05:35:30 INFO : potato2.rclone_chunk.001_cf9yae: Copied (server side copy) 2020/01/19 05:35:30 INFO : potato2.rclone_chunk.001_cf9yae: Deleted 2020/01/19 05:35:31 DEBUG : potato2.rclone_chunk.002_cf9yae: MD5 = 01a6651a94ab602dbfc47587d234b52c OK 2020/01/19 05:35:31 INFO : potato2.rclone_chunk.002_cf9yae: Copied (server side copy) 2020/01/19 05:35:31 INFO : potato2.rclone_chunk.002_cf9yae: Deleted 2020/01/19 05:35:31 INFO : potato2: Copied (new) 2020/01/19 05:35:31 INFO : Waiting for deletions to finish 2020/01/19 05:35:31 INFO : potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (1.09s) run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5'", Local "Local file system at /tmp/rclone910142536", Modify Window "1ns" === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir 2020/01/19 05:35:31 DEBUG : b/potato.rclone_chunk.001_cf9zei: MD5 = e4cb6955d9106df6263c45fcfc10f163 OK 2020/01/19 05:35:31 INFO : b/potato.rclone_chunk.001_cf9zei: Copied (server side copy) 2020/01/19 05:35:31 INFO : b/potato.rclone_chunk.001_cf9zei: Deleted 2020/01/19 05:35:31 DEBUG : c/non empty space.rclone_chunk.001_cf9zb7: MD5 = ec26039bacb958460a511485fc59d24e OK 2020/01/19 05:35:31 INFO : c/non empty space.rclone_chunk.001_cf9zb7: Copied (server side copy) 2020/01/19 05:35:31 INFO : c/non empty space.rclone_chunk.001_cf9zb7: Deleted 2020/01/19 05:35:31 DEBUG : d: Making directory 2020/01/19 05:35:31 DEBUG : d/e: Making directory 2020/01/19 05:35:31 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:35:31 DEBUG : c/non empty space: Unchanged skipping 2020/01/19 05:35:31 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for checks to finish 2020/01/19 05:35:31 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for transfers to finish 2020/01/19 05:35:31 DEBUG : a/potato2.rclone_chunk.001_cf9znn: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2020/01/19 05:35:31 INFO : a/potato2.rclone_chunk.001_cf9znn: Copied (server side copy) 2020/01/19 05:35:31 INFO : a/potato2.rclone_chunk.001_cf9znn: Deleted 2020/01/19 05:35:31 DEBUG : a/potato2.rclone_chunk.002_cf9znn: MD5 = 01a6651a94ab602dbfc47587d234b52c OK 2020/01/19 05:35:31 INFO : a/potato2.rclone_chunk.002_cf9znn: Copied (server side copy) 2020/01/19 05:35:31 INFO : a/potato2.rclone_chunk.002_cf9znn: Deleted 2020/01/19 05:35:31 INFO : a/potato2: Copied (new) 2020/01/19 05:35:31 INFO : Waiting for deletions to finish 2020/01/19 05:35:32 INFO : b/potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (0.94s) run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5'", Local "Local file system at /tmp/rclone910142536", Modify Window "1ns" fstest.go:241: Filtering empty directory "d" fstest.go:241: Filtering empty directory "d/e" === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors 2020/01/19 05:35:32 DEBUG : b/potato.rclone_chunk.001_cfa0hn: MD5 = e4cb6955d9106df6263c45fcfc10f163 OK 2020/01/19 05:35:32 INFO : b/potato.rclone_chunk.001_cfa0hn: Copied (server side copy) 2020/01/19 05:35:32 INFO : b/potato.rclone_chunk.001_cfa0hn: Deleted 2020/01/19 05:35:32 DEBUG : c/non empty space.rclone_chunk.001_cfa0xz: MD5 = ec26039bacb958460a511485fc59d24e OK 2020/01/19 05:35:32 INFO : c/non empty space.rclone_chunk.001_cfa0xz: Copied (server side copy) 2020/01/19 05:35:32 INFO : c/non empty space.rclone_chunk.001_cfa0xz: Deleted 2020/01/19 05:35:32 DEBUG : d: Making directory 2020/01/19 05:35:33 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for checks to finish 2020/01/19 05:35:33 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:35:33 DEBUG : c/non empty space: Unchanged skipping 2020/01/19 05:35:33 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for transfers to finish 2020/01/19 05:35:33 DEBUG : a/potato2.rclone_chunk.001_cfa1xh: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2020/01/19 05:35:33 INFO : a/potato2.rclone_chunk.001_cfa1xh: Copied (server side copy) 2020/01/19 05:35:33 INFO : a/potato2.rclone_chunk.001_cfa1xh: Deleted 2020/01/19 05:35:33 DEBUG : a/potato2.rclone_chunk.002_cfa1xh: MD5 = 01a6651a94ab602dbfc47587d234b52c OK 2020/01/19 05:35:33 INFO : a/potato2.rclone_chunk.002_cfa1xh: Copied (server side copy) 2020/01/19 05:35:33 INFO : a/potato2.rclone_chunk.002_cfa1xh: Deleted 2020/01/19 05:35:33 INFO : a/potato2: Copied (new) 2020/01/19 05:35:33 ERROR : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': not deleting files as there were IO errors 2020/01/19 05:35:33 ERROR : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': not deleting directories as there were IO errors --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (1.30s) run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5'", Local "Local file system at /tmp/rclone910142536", Modify Window "1ns" fstest.go:241: Filtering empty directory "d" fstest.go:241: Filtering empty directory "d" === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring 2020/01/19 05:35:33 DEBUG : potato.rclone_chunk.001_cfa1k2: MD5 = e4cb6955d9106df6263c45fcfc10f163 OK 2020/01/19 05:35:33 INFO : potato.rclone_chunk.001_cfa1k2: Copied (server side copy) 2020/01/19 05:35:33 INFO : potato.rclone_chunk.001_cfa1k2: Deleted 2020/01/19 05:35:33 DEBUG : empty space.rclone_chunk.001_cfa1bw: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/01/19 05:35:33 INFO : empty space.rclone_chunk.001_cfa1bw: Copied (server side copy) 2020/01/19 05:35:33 INFO : empty space.rclone_chunk.001_cfa1bw: Deleted 2020/01/19 05:35:34 INFO : Waiting for deletions to finish 2020/01/19 05:35:34 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for checks to finish 2020/01/19 05:35:34 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:35:34 DEBUG : empty space: Unchanged skipping 2020/01/19 05:35:34 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for transfers to finish 2020/01/19 05:35:34 INFO : potato: Deleted 2020/01/19 05:35:34 DEBUG : potato2.rclone_chunk.001_cfa2n4: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2020/01/19 05:35:34 INFO : potato2.rclone_chunk.001_cfa2n4: Copied (server side copy) 2020/01/19 05:35:34 INFO : potato2.rclone_chunk.001_cfa2n4: Deleted 2020/01/19 05:35:34 DEBUG : potato2.rclone_chunk.002_cfa2n4: MD5 = 01a6651a94ab602dbfc47587d234b52c OK 2020/01/19 05:35:34 INFO : potato2.rclone_chunk.002_cfa2n4: Copied (server side copy) 2020/01/19 05:35:34 INFO : potato2.rclone_chunk.002_cfa2n4: Deleted 2020/01/19 05:35:34 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteDuring (0.85s) run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5'", Local "Local file system at /tmp/rclone910142536", Modify Window "1ns" === RUN TestSyncDeleteBefore 2020/01/19 05:35:34 DEBUG : potato.rclone_chunk.001_cfa2eq: MD5 = e4cb6955d9106df6263c45fcfc10f163 OK 2020/01/19 05:35:34 INFO : potato.rclone_chunk.001_cfa2eq: Copied (server side copy) 2020/01/19 05:35:34 INFO : potato.rclone_chunk.001_cfa2eq: Deleted 2020/01/19 05:35:34 DEBUG : empty space.rclone_chunk.001_cfa2hm: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/01/19 05:35:34 INFO : empty space.rclone_chunk.001_cfa2hm: Copied (server side copy) 2020/01/19 05:35:34 INFO : empty space.rclone_chunk.001_cfa2hm: Deleted 2020/01/19 05:35:35 INFO : Waiting for deletions to finish 2020/01/19 05:35:35 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for checks to finish 2020/01/19 05:35:35 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for transfers to finish 2020/01/19 05:35:35 INFO : potato: Deleted 2020/01/19 05:35:35 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for checks to finish 2020/01/19 05:35:35 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:35:35 DEBUG : empty space: Unchanged skipping 2020/01/19 05:35:35 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for transfers to finish 2020/01/19 05:35:35 DEBUG : potato2.rclone_chunk.001_cfa383: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2020/01/19 05:35:35 INFO : potato2.rclone_chunk.001_cfa383: Copied (server side copy) 2020/01/19 05:35:35 INFO : potato2.rclone_chunk.001_cfa383: Deleted 2020/01/19 05:35:35 DEBUG : potato2.rclone_chunk.002_cfa383: MD5 = 01a6651a94ab602dbfc47587d234b52c OK 2020/01/19 05:35:35 INFO : potato2.rclone_chunk.002_cfa383: Copied (server side copy) 2020/01/19 05:35:35 INFO : potato2.rclone_chunk.002_cfa383: Deleted 2020/01/19 05:35:35 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteBefore (1.14s) run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5'", Local "Local file system at /tmp/rclone910142536", Modify Window "1ns" === RUN TestCopyDeleteBefore 2020/01/19 05:35:35 DEBUG : potato.rclone_chunk.001_cfa3if: MD5 = a77cf635f7af90988de91597e3a4519b OK 2020/01/19 05:35:35 INFO : potato.rclone_chunk.001_cfa3if: Copied (server side copy) 2020/01/19 05:35:35 INFO : potato.rclone_chunk.001_cfa3if: Deleted 2020/01/19 05:35:36 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for checks to finish 2020/01/19 05:35:36 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for transfers to finish 2020/01/19 05:35:36 DEBUG : potato2.rclone_chunk.001_cfa4f1: MD5 = 9519bc2e940e1696ea7365b4b81adedc OK 2020/01/19 05:35:36 INFO : potato2.rclone_chunk.001_cfa4f1: Copied (server side copy) 2020/01/19 05:35:36 INFO : potato2.rclone_chunk.001_cfa4f1: Deleted 2020/01/19 05:35:36 INFO : potato2: Copied (new) --- PASS: TestCopyDeleteBefore (0.56s) run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5'", Local "Local file system at /tmp/rclone910142536", Modify Window "1ns" === RUN TestSyncWithExclude 2020/01/19 05:35:36 DEBUG : potato2.rclone_chunk.001_cfa46q: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2020/01/19 05:35:36 INFO : potato2.rclone_chunk.001_cfa46q: Copied (server side copy) 2020/01/19 05:35:36 INFO : potato2.rclone_chunk.001_cfa46q: Deleted 2020/01/19 05:35:36 DEBUG : potato2.rclone_chunk.002_cfa46q: MD5 = 01a6651a94ab602dbfc47587d234b52c OK 2020/01/19 05:35:36 INFO : potato2.rclone_chunk.002_cfa46q: Copied (server side copy) 2020/01/19 05:35:36 INFO : potato2.rclone_chunk.002_cfa46q: Deleted 2020/01/19 05:35:36 DEBUG : empty space.rclone_chunk.001_cfa48s: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/01/19 05:35:36 INFO : empty space.rclone_chunk.001_cfa48s: Copied (server side copy) 2020/01/19 05:35:36 INFO : empty space.rclone_chunk.001_cfa48s: Deleted 2020/01/19 05:35:37 DEBUG : potato2: Excluded 2020/01/19 05:35:37 DEBUG : enormous: Excluded 2020/01/19 05:35:37 DEBUG : potato2: Excluded 2020/01/19 05:35:37 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for checks to finish 2020/01/19 05:35:37 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:35:37 DEBUG : empty space: Unchanged skipping 2020/01/19 05:35:37 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for transfers to finish 2020/01/19 05:35:37 INFO : Waiting for deletions to finish 2020/01/19 05:35:37 DEBUG : potato2: Excluded 2020/01/19 05:35:37 DEBUG : enormous: Excluded 2020/01/19 05:35:37 DEBUG : potato2: Excluded 2020/01/19 05:35:37 INFO : Local file system at /tmp/rclone910142536: Waiting for checks to finish 2020/01/19 05:35:37 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:35:37 DEBUG : empty space: Unchanged skipping 2020/01/19 05:35:37 INFO : Local file system at /tmp/rclone910142536: Waiting for transfers to finish 2020/01/19 05:35:37 INFO : Waiting for deletions to finish --- PASS: TestSyncWithExclude (2.73s) run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5'", Local "Local file system at /tmp/rclone910142536", Modify Window "1ns" === RUN TestSyncWithExcludeAndDeleteExcluded 2020/01/19 05:35:39 DEBUG : potato2.rclone_chunk.001_cfa6ml: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2020/01/19 05:35:39 INFO : potato2.rclone_chunk.001_cfa6ml: Copied (server side copy) 2020/01/19 05:35:39 INFO : potato2.rclone_chunk.001_cfa6ml: Deleted 2020/01/19 05:35:39 DEBUG : potato2.rclone_chunk.002_cfa6ml: MD5 = 01a6651a94ab602dbfc47587d234b52c OK 2020/01/19 05:35:39 INFO : potato2.rclone_chunk.002_cfa6ml: Copied (server side copy) 2020/01/19 05:35:39 INFO : potato2.rclone_chunk.002_cfa6ml: Deleted 2020/01/19 05:35:39 DEBUG : empty space.rclone_chunk.001_cfa7x8: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/01/19 05:35:39 INFO : empty space.rclone_chunk.001_cfa7x8: Copied (server side copy) 2020/01/19 05:35:39 INFO : empty space.rclone_chunk.001_cfa7x8: Deleted 2020/01/19 05:35:39 DEBUG : enormous.rclone_chunk.001_cfa7mh: MD5 = 1e2d1a8f03accd4907d668b183c2f9f0 OK 2020/01/19 05:35:39 INFO : enormous.rclone_chunk.001_cfa7mh: Copied (server side copy) 2020/01/19 05:35:39 INFO : enormous.rclone_chunk.001_cfa7mh: Deleted 2020/01/19 05:35:39 DEBUG : enormous.rclone_chunk.002_cfa7mh: MD5 = 1e2d1a8f03accd4907d668b183c2f9f0 OK 2020/01/19 05:35:39 INFO : enormous.rclone_chunk.002_cfa7mh: Copied (server side copy) 2020/01/19 05:35:39 INFO : enormous.rclone_chunk.002_cfa7mh: Deleted 2020/01/19 05:35:39 DEBUG : potato2: Excluded 2020/01/19 05:35:39 DEBUG : enormous: Excluded 2020/01/19 05:35:39 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for checks to finish 2020/01/19 05:35:39 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:35:39 DEBUG : empty space: Unchanged skipping 2020/01/19 05:35:39 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for transfers to finish 2020/01/19 05:35:39 INFO : Waiting for deletions to finish 2020/01/19 05:35:39 INFO : potato2: Deleted 2020/01/19 05:35:39 INFO : enormous: Deleted 2020/01/19 05:35:40 INFO : Local file system at /tmp/rclone910142536: Waiting for checks to finish 2020/01/19 05:35:40 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:35:40 DEBUG : empty space: Unchanged skipping 2020/01/19 05:35:40 INFO : Local file system at /tmp/rclone910142536: Waiting for transfers to finish 2020/01/19 05:35:40 INFO : Waiting for deletions to finish 2020/01/19 05:35:40 INFO : enormous: Deleted 2020/01/19 05:35:40 INFO : potato2: Deleted --- PASS: TestSyncWithExcludeAndDeleteExcluded (1.11s) run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5'", Local "Local file system at /tmp/rclone910142536", Modify Window "1ns" === RUN TestSyncWithUpdateOlder 2020/01/19 05:35:40 DEBUG : one.rclone_chunk.001_cfa8rv: MD5 = bc21e6484530fc9d0313cb816b733396 OK 2020/01/19 05:35:40 INFO : one.rclone_chunk.001_cfa8rv: Copied (server side copy) 2020/01/19 05:35:40 INFO : one.rclone_chunk.001_cfa8rv: Deleted 2020/01/19 05:35:40 DEBUG : two.rclone_chunk.001_cfa8oz: MD5 = 0f82d86afa0f5dc965c5c15aca58dcfb OK 2020/01/19 05:35:40 INFO : two.rclone_chunk.001_cfa8oz: Copied (server side copy) 2020/01/19 05:35:40 INFO : two.rclone_chunk.001_cfa8oz: Deleted 2020/01/19 05:35:40 DEBUG : three.rclone_chunk.001_cfa8ek: MD5 = 413af0de1f97a2155acf2b8b26ab36e2 OK 2020/01/19 05:35:40 INFO : three.rclone_chunk.001_cfa8ek: Copied (server side copy) 2020/01/19 05:35:40 INFO : three.rclone_chunk.001_cfa8ek: Deleted 2020/01/19 05:35:40 DEBUG : four.rclone_chunk.001_cfa84h: MD5 = 0a04eb9a514fbe0829cf48049cc69fc7 OK 2020/01/19 05:35:40 INFO : four.rclone_chunk.001_cfa84h: Copied (server side copy) 2020/01/19 05:35:40 INFO : four.rclone_chunk.001_cfa84h: Deleted 2020/01/19 05:35:40 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for checks to finish 2020/01/19 05:35:40 DEBUG : one: Destination is newer than source, skipping 2020/01/19 05:35:40 DEBUG : four: Sizes differ (src 4 vs dst 8) 2020/01/19 05:35:40 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2020/01/19 05:35:40 DEBUG : three: Sizes identical 2020/01/19 05:35:40 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2020/01/19 05:35:40 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for transfers to finish 2020/01/19 05:35:40 DEBUG : five.rclone_chunk.001_cfa8sf: MD5 = 30056e1cab7a61d256fc8edd970d14f5 OK 2020/01/19 05:35:40 INFO : five.rclone_chunk.001_cfa8sf: Copied (server side copy) 2020/01/19 05:35:40 DEBUG : four.rclone_chunk.001_cfa8a0: MD5 = 8cbad96aced40b3838dd9f07f6ef5772 OK 2020/01/19 05:35:40 INFO : four.rclone_chunk.001_cfa8a0: Copied (server side copy) 2020/01/19 05:35:40 INFO : five.rclone_chunk.001_cfa8sf: Deleted 2020/01/19 05:35:40 INFO : five: Copied (new) 2020/01/19 05:35:40 INFO : four.rclone_chunk.001_cfa8a0: Deleted 2020/01/19 05:35:40 INFO : four: Copied (replaced existing) 2020/01/19 05:35:41 DEBUG : two.rclone_chunk.001_cfa8sg: MD5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2020/01/19 05:35:41 INFO : two.rclone_chunk.001_cfa8sg: Copied (server side copy) 2020/01/19 05:35:41 INFO : two.rclone_chunk.001_cfa8sg: Deleted 2020/01/19 05:35:41 INFO : two: Copied (replaced existing) 2020/01/19 05:35:41 INFO : Waiting for deletions to finish --- PASS: TestSyncWithUpdateOlder (1.80s) run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5'", Local "Local file system at /tmp/rclone910142536", Modify Window "1ns" sync_test.go:980: Skip test with --checksum as no hashes supported === RUN TestSyncWithTrackRenames 2020/01/19 05:35:41 ERROR : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Ignoring --track-renames as the source and destination do not have a common hash 2020/01/19 05:35:41 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for checks to finish 2020/01/19 05:35:41 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for transfers to finish 2020/01/19 05:35:41 DEBUG : yam.rclone_chunk.001_cfa9pd: MD5 = 48dc443644c4b89556dc06834b46451d OK 2020/01/19 05:35:41 INFO : yam.rclone_chunk.001_cfa9pd: Copied (server side copy) 2020/01/19 05:35:41 DEBUG : potato.rclone_chunk.001_cfa94u: MD5 = 7f6fa9ddec9bfebda9510af0323fd696 OK 2020/01/19 05:35:41 INFO : potato.rclone_chunk.001_cfa94u: Copied (server side copy) 2020/01/19 05:35:41 INFO : yam.rclone_chunk.001_cfa9pd: Deleted 2020/01/19 05:35:41 INFO : yam: Copied (new) 2020/01/19 05:35:41 INFO : potato.rclone_chunk.001_cfa94u: Deleted 2020/01/19 05:35:41 INFO : potato: Copied (new) 2020/01/19 05:35:41 INFO : Waiting for deletions to finish 2020/01/19 05:35:42 ERROR : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Ignoring --track-renames as the source and destination do not have a common hash 2020/01/19 05:35:42 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for checks to finish 2020/01/19 05:35:42 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:35:42 DEBUG : potato: Unchanged skipping 2020/01/19 05:35:42 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for transfers to finish 2020/01/19 05:35:42 DEBUG : yaml.rclone_chunk.001_cfaau8: MD5 = 48dc443644c4b89556dc06834b46451d OK 2020/01/19 05:35:42 INFO : yaml.rclone_chunk.001_cfaau8: Copied (server side copy) 2020/01/19 05:35:42 INFO : yaml.rclone_chunk.001_cfaau8: Deleted 2020/01/19 05:35:42 INFO : yaml: Copied (new) 2020/01/19 05:35:42 INFO : Waiting for deletions to finish 2020/01/19 05:35:42 INFO : yam: Deleted --- PASS: TestSyncWithTrackRenames (0.40s) run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5'", Local "Local file system at /tmp/rclone910142536", Modify Window "1ns" sync_test.go:1005: Can track renames: false === RUN TestMoveWithDeleteEmptySrcDirs 2020/01/19 05:35:42 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for checks to finish 2020/01/19 05:35:42 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for transfers to finish 2020/01/19 05:35:42 DEBUG : nested/sub dir/file.rclone_chunk.001_cfaa4w: MD5 = 83d3784ea62518eafc60e98d84f877ad OK 2020/01/19 05:35:42 INFO : nested/sub dir/file.rclone_chunk.001_cfaa4w: Copied (server side copy) 2020/01/19 05:35:42 DEBUG : sub dir/hello world.rclone_chunk.001_cfaa1w: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/01/19 05:35:42 INFO : sub dir/hello world.rclone_chunk.001_cfaa1w: Copied (server side copy) 2020/01/19 05:35:42 INFO : nested/sub dir/file.rclone_chunk.001_cfaa4w: Deleted 2020/01/19 05:35:42 INFO : nested/sub dir/file: Copied (new) 2020/01/19 05:35:42 INFO : nested/sub dir/file: Deleted 2020/01/19 05:35:42 INFO : sub dir/hello world.rclone_chunk.001_cfaa1w: Deleted 2020/01/19 05:35:42 INFO : sub dir/hello world: Copied (new) 2020/01/19 05:35:42 INFO : sub dir/hello world: Deleted 2020/01/19 05:35:42 DEBUG : sub dir: Removing directory 2020/01/19 05:35:42 DEBUG : nested/sub dir: Removing directory 2020/01/19 05:35:42 DEBUG : nested: Removing directory 2020/01/19 05:35:42 DEBUG : Local file system at /tmp/rclone910142536: deleted 3 directories --- PASS: TestMoveWithDeleteEmptySrcDirs (0.23s) run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5'", Local "Local file system at /tmp/rclone910142536", Modify Window "1ns" === RUN TestMoveWithoutDeleteEmptySrcDirs 2020/01/19 05:35:42 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for checks to finish 2020/01/19 05:35:42 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for transfers to finish 2020/01/19 05:35:42 DEBUG : nested/sub dir/file.rclone_chunk.001_cfaab8: MD5 = 83d3784ea62518eafc60e98d84f877ad OK 2020/01/19 05:35:42 INFO : nested/sub dir/file.rclone_chunk.001_cfaab8: Copied (server side copy) 2020/01/19 05:35:42 DEBUG : sub dir/hello world.rclone_chunk.001_cfaax3: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/01/19 05:35:42 INFO : sub dir/hello world.rclone_chunk.001_cfaax3: Copied (server side copy) 2020/01/19 05:35:42 INFO : nested/sub dir/file.rclone_chunk.001_cfaab8: Deleted 2020/01/19 05:35:42 INFO : nested/sub dir/file: Copied (new) 2020/01/19 05:35:42 INFO : nested/sub dir/file: Deleted 2020/01/19 05:35:42 INFO : sub dir/hello world.rclone_chunk.001_cfaax3: Deleted 2020/01/19 05:35:42 INFO : sub dir/hello world: Copied (new) 2020/01/19 05:35:42 INFO : sub dir/hello world: Deleted --- PASS: TestMoveWithoutDeleteEmptySrcDirs (0.72s) run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5'", Local "Local file system at /tmp/rclone910142536", Modify Window "1ns" === RUN TestServerSideMove 2020/01/19 05:35:43 DEBUG : potato2.rclone_chunk.001_cfabrs: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2020/01/19 05:35:43 INFO : potato2.rclone_chunk.001_cfabrs: Copied (server side copy) 2020/01/19 05:35:43 INFO : potato2.rclone_chunk.001_cfabrs: Deleted 2020/01/19 05:35:43 DEBUG : potato2.rclone_chunk.002_cfabrs: MD5 = 01a6651a94ab602dbfc47587d234b52c OK 2020/01/19 05:35:43 INFO : potato2.rclone_chunk.002_cfabrs: Copied (server side copy) 2020/01/19 05:35:43 INFO : potato2.rclone_chunk.002_cfabrs: Deleted 2020/01/19 05:35:43 DEBUG : empty space.rclone_chunk.001_cfabon: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/01/19 05:35:43 INFO : empty space.rclone_chunk.001_cfabon: Copied (server side copy) 2020/01/19 05:35:43 INFO : empty space.rclone_chunk.001_cfabon: Deleted 2020/01/19 05:35:43 DEBUG : potato3.rclone_chunk.001_cfab2y: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2020/01/19 05:35:43 INFO : potato3.rclone_chunk.001_cfab2y: Copied (server side copy) 2020/01/19 05:35:43 INFO : potato3.rclone_chunk.001_cfab2y: Deleted 2020/01/19 05:35:43 DEBUG : potato3.rclone_chunk.002_cfab2y: MD5 = 111c387f2584838efb7e0ec6f2fa2e10 OK 2020/01/19 05:35:43 INFO : potato3.rclone_chunk.002_cfab2y: Copied (server side copy) 2020/01/19 05:35:43 INFO : potato3.rclone_chunk.002_cfab2y: Deleted 2020/01/19 05:35:44 INFO : S3 bucket rclone-test-xemuven2dikexoj8torunur5: Bucket "rclone-test-xemuven2dikexoj8torunur5" created with ACL "private" 2020/01/19 05:35:44 DEBUG : empty space.rclone_chunk.001_cfac7o: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/01/19 05:35:44 INFO : empty space.rclone_chunk.001_cfac7o: Copied (server side copy) 2020/01/19 05:35:44 INFO : empty space.rclone_chunk.001_cfac7o: Deleted 2020/01/19 05:35:44 DEBUG : potato3.rclone_chunk.001_cfac1y: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2020/01/19 05:35:44 INFO : potato3.rclone_chunk.001_cfac1y: Copied (server side copy) 2020/01/19 05:35:44 INFO : potato3.rclone_chunk.001_cfac1y: Deleted 2020/01/19 05:35:44 DEBUG : potato3.rclone_chunk.002_cfac1y: MD5 = 01a6651a94ab602dbfc47587d234b52c OK 2020/01/19 05:35:44 INFO : potato3.rclone_chunk.002_cfac1y: Copied (server side copy) 2020/01/19 05:35:44 INFO : potato3.rclone_chunk.002_cfac1y: Deleted 2020/01/19 05:35:45 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2020/01/19 05:35:45 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-xemuven2dikexoj8torunur5': Waiting for checks to finish 2020/01/19 05:35:45 DEBUG : potato2: Can't copy - required hash not found 2020/01/19 05:35:45 DEBUG : potato3: Can't copy - required hash not found 2020/01/19 05:35:45 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:35:45 DEBUG : empty space: Unchanged skipping 2020/01/19 05:35:45 INFO : empty space: Deleted 2020/01/19 05:35:45 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-xemuven2dikexoj8torunur5': Waiting for transfers to finish 2020/01/19 05:35:45 DEBUG : potato3.rclone_chunk.001_cfad0g: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2020/01/19 05:35:45 INFO : potato3.rclone_chunk.001_cfad0g: Copied (server side copy) 2020/01/19 05:35:45 INFO : potato3.rclone_chunk.001_cfad0g: Deleted 2020/01/19 05:35:45 DEBUG : potato3.rclone_chunk.002_cfad0g: MD5 = 111c387f2584838efb7e0ec6f2fa2e10 OK 2020/01/19 05:35:45 INFO : potato3.rclone_chunk.002_cfad0g: Copied (server side copy) 2020/01/19 05:35:45 INFO : potato3.rclone_chunk.002_cfad0g: Deleted 2020/01/19 05:35:45 INFO : potato3: Copied (replaced existing) 2020/01/19 05:35:45 INFO : potato3: Deleted 2020/01/19 05:35:46 DEBUG : potato2.rclone_chunk.001_cfadw4: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2020/01/19 05:35:46 INFO : potato2.rclone_chunk.001_cfadw4: Copied (server side copy) 2020/01/19 05:35:46 INFO : potato2.rclone_chunk.001_cfadw4: Deleted 2020/01/19 05:35:46 DEBUG : potato2.rclone_chunk.002_cfadw4: MD5 = 01a6651a94ab602dbfc47587d234b52c OK 2020/01/19 05:35:46 INFO : potato2.rclone_chunk.002_cfadw4: Copied (server side copy) 2020/01/19 05:35:46 INFO : potato2.rclone_chunk.002_cfadw4: Deleted 2020/01/19 05:35:46 INFO : potato2: Copied (new) 2020/01/19 05:35:46 INFO : potato2: Deleted 2020/01/19 05:35:46 DEBUG : empty space: copy non-chunked object... 2020/01/19 05:35:46 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-qujofaj4qofisaw5rucocox5': Waiting for checks to finish 2020/01/19 05:35:46 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-qujofaj4qofisaw5rucocox5': Waiting for transfers to finish 2020/01/19 05:35:46 DEBUG : potato2: Can't copy - required hash not found 2020/01/19 05:35:46 DEBUG : potato3: Can't copy - required hash not found 2020/01/19 05:35:47 INFO : S3 bucket rclone-test-qujofaj4qofisaw5rucocox5: Bucket "rclone-test-qujofaj4qofisaw5rucocox5" created with ACL "private" 2020/01/19 05:35:47 INFO : empty space: Copied (server side copy) 2020/01/19 05:35:47 INFO : empty space: Deleted 2020/01/19 05:35:47 DEBUG : potato2.rclone_chunk.001_cfae5i: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2020/01/19 05:35:47 INFO : potato2.rclone_chunk.001_cfae5i: Copied (server side copy) 2020/01/19 05:35:47 INFO : potato2.rclone_chunk.001_cfae5i: Deleted 2020/01/19 05:35:47 DEBUG : potato2.rclone_chunk.002_cfae5i: MD5 = 01a6651a94ab602dbfc47587d234b52c OK 2020/01/19 05:35:47 INFO : potato2.rclone_chunk.002_cfae5i: Copied (server side copy) 2020/01/19 05:35:47 INFO : potato2.rclone_chunk.002_cfae5i: Deleted 2020/01/19 05:35:47 INFO : potato2: Copied (new) 2020/01/19 05:35:47 INFO : potato2: Deleted 2020/01/19 05:35:47 DEBUG : potato3.rclone_chunk.001_cfae7b: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2020/01/19 05:35:47 INFO : potato3.rclone_chunk.001_cfae7b: Copied (server side copy) 2020/01/19 05:35:47 INFO : potato3.rclone_chunk.001_cfae7b: Deleted 2020/01/19 05:35:47 DEBUG : potato3.rclone_chunk.002_cfae7b: MD5 = 111c387f2584838efb7e0ec6f2fa2e10 OK 2020/01/19 05:35:47 INFO : potato3.rclone_chunk.002_cfae7b: Copied (server side copy) 2020/01/19 05:35:47 INFO : potato3.rclone_chunk.002_cfae7b: Deleted 2020/01/19 05:35:47 INFO : potato3: Copied (new) 2020/01/19 05:35:47 INFO : potato3: Deleted 2020/01/19 05:35:48 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-qujofaj4qofisaw5rucocox5': Purge object "empty space" 2020/01/19 05:35:48 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-qujofaj4qofisaw5rucocox5': Purge object "potato2" 2020/01/19 05:35:48 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-qujofaj4qofisaw5rucocox5': Purge object "potato3" 2020/01/19 05:35:48 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-qujofaj4qofisaw5rucocox5': Purge dir "" 2020/01/19 05:35:48 INFO : S3 bucket rclone-test-qujofaj4qofisaw5rucocox5: Bucket "rclone-test-qujofaj4qofisaw5rucocox5" deleted 2020/01/19 05:35:48 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-xemuven2dikexoj8torunur5': Purge dir "" 2020/01/19 05:35:48 INFO : S3 bucket rclone-test-xemuven2dikexoj8torunur5: Bucket "rclone-test-xemuven2dikexoj8torunur5" deleted --- PASS: TestServerSideMove (6.00s) run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5'", Local "Local file system at /tmp/rclone910142536", Modify Window "1ns" sync_test.go:1110: Server side move (if possible) Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5' -> Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-xemuven2dikexoj8torunur5' === RUN TestServerSideMoveWithFilter 2020/01/19 05:35:49 DEBUG : potato2.rclone_chunk.001_cfahw2: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2020/01/19 05:35:49 INFO : potato2.rclone_chunk.001_cfahw2: Copied (server side copy) 2020/01/19 05:35:49 INFO : potato2.rclone_chunk.001_cfahw2: Deleted 2020/01/19 05:35:49 DEBUG : potato2.rclone_chunk.002_cfahw2: MD5 = 01a6651a94ab602dbfc47587d234b52c OK 2020/01/19 05:35:49 INFO : potato2.rclone_chunk.002_cfahw2: Copied (server side copy) 2020/01/19 05:35:49 INFO : potato2.rclone_chunk.002_cfahw2: Deleted 2020/01/19 05:35:49 DEBUG : empty space.rclone_chunk.001_cfahfe: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/01/19 05:35:49 INFO : empty space.rclone_chunk.001_cfahfe: Copied (server side copy) 2020/01/19 05:35:49 INFO : empty space.rclone_chunk.001_cfahfe: Deleted 2020/01/19 05:35:49 DEBUG : potato3.rclone_chunk.001_cfahkx: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2020/01/19 05:35:49 INFO : potato3.rclone_chunk.001_cfahkx: Copied (server side copy) 2020/01/19 05:35:49 INFO : potato3.rclone_chunk.001_cfahkx: Deleted 2020/01/19 05:35:49 DEBUG : potato3.rclone_chunk.002_cfahkx: MD5 = 111c387f2584838efb7e0ec6f2fa2e10 OK 2020/01/19 05:35:49 INFO : potato3.rclone_chunk.002_cfahkx: Copied (server side copy) 2020/01/19 05:35:49 INFO : potato3.rclone_chunk.002_cfahkx: Deleted 2020/01/19 05:35:51 INFO : S3 bucket rclone-test-mudijup3tokojud3gawuhox3: Bucket "rclone-test-mudijup3tokojud3gawuhox3" created with ACL "private" 2020/01/19 05:35:51 DEBUG : empty space.rclone_chunk.001_cfajkt: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/01/19 05:35:51 INFO : empty space.rclone_chunk.001_cfajkt: Copied (server side copy) 2020/01/19 05:35:51 INFO : empty space.rclone_chunk.001_cfajkt: Deleted 2020/01/19 05:35:51 DEBUG : potato3.rclone_chunk.001_cfajbs: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2020/01/19 05:35:51 INFO : potato3.rclone_chunk.001_cfajbs: Copied (server side copy) 2020/01/19 05:35:51 INFO : potato3.rclone_chunk.001_cfajbs: Deleted 2020/01/19 05:35:51 DEBUG : potato3.rclone_chunk.002_cfajbs: MD5 = 01a6651a94ab602dbfc47587d234b52c OK 2020/01/19 05:35:51 INFO : potato3.rclone_chunk.002_cfajbs: Copied (server side copy) 2020/01/19 05:35:51 INFO : potato3.rclone_chunk.002_cfajbs: Deleted 2020/01/19 05:35:51 DEBUG : empty space: Excluded 2020/01/19 05:35:51 DEBUG : empty space: Excluded 2020/01/19 05:35:51 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2020/01/19 05:35:51 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-mudijup3tokojud3gawuhox3': Waiting for checks to finish 2020/01/19 05:35:51 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-mudijup3tokojud3gawuhox3': Waiting for transfers to finish 2020/01/19 05:35:52 DEBUG : potato3: Can't copy - required hash not found 2020/01/19 05:35:52 DEBUG : potato2: Can't copy - required hash not found 2020/01/19 05:35:52 DEBUG : potato2.rclone_chunk.001_cfak6c: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2020/01/19 05:35:52 INFO : potato2.rclone_chunk.001_cfak6c: Copied (server side copy) 2020/01/19 05:35:52 INFO : potato2.rclone_chunk.001_cfak6c: Deleted 2020/01/19 05:35:52 DEBUG : potato2.rclone_chunk.002_cfak6c: MD5 = 01a6651a94ab602dbfc47587d234b52c OK 2020/01/19 05:35:52 INFO : potato2.rclone_chunk.002_cfak6c: Copied (server side copy) 2020/01/19 05:35:52 INFO : potato2.rclone_chunk.002_cfak6c: Deleted 2020/01/19 05:35:52 INFO : potato2: Copied (new) 2020/01/19 05:35:52 INFO : potato2: Deleted 2020/01/19 05:35:52 DEBUG : potato3.rclone_chunk.001_cfak9e: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2020/01/19 05:35:52 INFO : potato3.rclone_chunk.001_cfak9e: Copied (server side copy) 2020/01/19 05:35:53 INFO : potato3.rclone_chunk.001_cfak9e: Deleted 2020/01/19 05:35:53 DEBUG : potato3.rclone_chunk.002_cfak9e: MD5 = 111c387f2584838efb7e0ec6f2fa2e10 OK 2020/01/19 05:35:53 INFO : potato3.rclone_chunk.002_cfak9e: Copied (server side copy) 2020/01/19 05:35:53 INFO : potato3.rclone_chunk.002_cfak9e: Deleted 2020/01/19 05:35:53 INFO : potato3: Copied (replaced existing) 2020/01/19 05:35:53 INFO : potato3: Deleted 2020/01/19 05:35:53 DEBUG : empty space: Excluded 2020/01/19 05:35:53 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-pexabom5pabonuy0bileyur7': Waiting for checks to finish 2020/01/19 05:35:53 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-pexabom5pabonuy0bileyur7': Waiting for transfers to finish 2020/01/19 05:35:53 DEBUG : potato3: Can't copy - required hash not found 2020/01/19 05:35:53 DEBUG : potato2: Can't copy - required hash not found 2020/01/19 05:35:54 INFO : S3 bucket rclone-test-pexabom5pabonuy0bileyur7: Bucket "rclone-test-pexabom5pabonuy0bileyur7" created with ACL "private" 2020/01/19 05:35:54 DEBUG : potato2.rclone_chunk.001_cfal7o: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2020/01/19 05:35:54 INFO : potato2.rclone_chunk.001_cfal7o: Copied (server side copy) 2020/01/19 05:35:54 INFO : potato2.rclone_chunk.001_cfal7o: Deleted 2020/01/19 05:35:54 DEBUG : potato3.rclone_chunk.001_cfalw5: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2020/01/19 05:35:54 INFO : potato3.rclone_chunk.001_cfalw5: Copied (server side copy) 2020/01/19 05:35:54 INFO : potato3.rclone_chunk.001_cfalw5: Deleted 2020/01/19 05:35:54 DEBUG : potato2.rclone_chunk.002_cfal7o: MD5 = 01a6651a94ab602dbfc47587d234b52c OK 2020/01/19 05:35:54 INFO : potato2.rclone_chunk.002_cfal7o: Copied (server side copy) 2020/01/19 05:35:54 INFO : potato2.rclone_chunk.002_cfal7o: Deleted 2020/01/19 05:35:54 DEBUG : potato3.rclone_chunk.002_cfalw5: MD5 = 111c387f2584838efb7e0ec6f2fa2e10 OK 2020/01/19 05:35:54 INFO : potato3.rclone_chunk.002_cfalw5: Copied (server side copy) 2020/01/19 05:35:54 INFO : potato3.rclone_chunk.002_cfalw5: Deleted 2020/01/19 05:35:54 INFO : potato2: Copied (new) 2020/01/19 05:35:54 INFO : potato3: Copied (new) 2020/01/19 05:35:54 INFO : potato2: Deleted 2020/01/19 05:35:54 INFO : potato3: Deleted 2020/01/19 05:35:55 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-pexabom5pabonuy0bileyur7': Purge object "potato2" 2020/01/19 05:35:55 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-pexabom5pabonuy0bileyur7': Purge object "potato3" 2020/01/19 05:35:55 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-pexabom5pabonuy0bileyur7': Purge dir "" 2020/01/19 05:35:55 INFO : S3 bucket rclone-test-pexabom5pabonuy0bileyur7: Bucket "rclone-test-pexabom5pabonuy0bileyur7" deleted 2020/01/19 05:35:55 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-mudijup3tokojud3gawuhox3': Purge object "empty space" 2020/01/19 05:35:55 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-mudijup3tokojud3gawuhox3': Purge dir "" 2020/01/19 05:35:56 INFO : S3 bucket rclone-test-mudijup3tokojud3gawuhox3: Bucket "rclone-test-mudijup3tokojud3gawuhox3" deleted --- PASS: TestServerSideMoveWithFilter (7.08s) run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5'", Local "Local file system at /tmp/rclone910142536", Modify Window "1ns" sync_test.go:1110: Server side move (if possible) Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5' -> Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-mudijup3tokojud3gawuhox3' === RUN TestServerSideMoveDeleteEmptySourceDirs 2020/01/19 05:35:56 DEBUG : potato2.rclone_chunk.001_cfaoay: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2020/01/19 05:35:56 INFO : potato2.rclone_chunk.001_cfaoay: Copied (server side copy) 2020/01/19 05:35:56 INFO : potato2.rclone_chunk.001_cfaoay: Deleted 2020/01/19 05:35:56 DEBUG : potato2.rclone_chunk.002_cfaoay: MD5 = 01a6651a94ab602dbfc47587d234b52c OK 2020/01/19 05:35:56 INFO : potato2.rclone_chunk.002_cfaoay: Copied (server side copy) 2020/01/19 05:35:56 INFO : potato2.rclone_chunk.002_cfaoay: Deleted 2020/01/19 05:35:56 DEBUG : empty space.rclone_chunk.001_cfao8w: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/01/19 05:35:56 INFO : empty space.rclone_chunk.001_cfao8w: Copied (server side copy) 2020/01/19 05:35:56 INFO : empty space.rclone_chunk.001_cfao8w: Deleted 2020/01/19 05:35:56 DEBUG : potato3.rclone_chunk.001_cfaoyh: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2020/01/19 05:35:56 INFO : potato3.rclone_chunk.001_cfaoyh: Copied (server side copy) 2020/01/19 05:35:56 INFO : potato3.rclone_chunk.001_cfaoyh: Deleted 2020/01/19 05:35:56 DEBUG : potato3.rclone_chunk.002_cfaoyh: MD5 = 111c387f2584838efb7e0ec6f2fa2e10 OK 2020/01/19 05:35:56 INFO : potato3.rclone_chunk.002_cfaoyh: Copied (server side copy) 2020/01/19 05:35:56 INFO : potato3.rclone_chunk.002_cfaoyh: Deleted 2020/01/19 05:35:56 DEBUG : tomatoDir: Making directory 2020/01/19 05:35:57 INFO : S3 bucket rclone-test-gizaxul3bozelaw0gajesog3: Bucket "rclone-test-gizaxul3bozelaw0gajesog3" created with ACL "private" 2020/01/19 05:35:57 DEBUG : empty space.rclone_chunk.001_cfapas: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/01/19 05:35:57 INFO : empty space.rclone_chunk.001_cfapas: Copied (server side copy) 2020/01/19 05:35:57 INFO : empty space.rclone_chunk.001_cfapas: Deleted 2020/01/19 05:35:57 DEBUG : potato3.rclone_chunk.001_cfapw4: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2020/01/19 05:35:57 INFO : potato3.rclone_chunk.001_cfapw4: Copied (server side copy) 2020/01/19 05:35:57 INFO : potato3.rclone_chunk.001_cfapw4: Deleted 2020/01/19 05:35:57 DEBUG : potato3.rclone_chunk.002_cfapw4: MD5 = 01a6651a94ab602dbfc47587d234b52c OK 2020/01/19 05:35:57 INFO : potato3.rclone_chunk.002_cfapw4: Copied (server side copy) 2020/01/19 05:35:57 INFO : potato3.rclone_chunk.002_cfapw4: Deleted 2020/01/19 05:35:57 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2020/01/19 05:35:57 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-gizaxul3bozelaw0gajesog3': Waiting for checks to finish 2020/01/19 05:35:57 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:35:57 DEBUG : empty space: Unchanged skipping 2020/01/19 05:35:57 INFO : empty space: Deleted 2020/01/19 05:35:57 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-gizaxul3bozelaw0gajesog3': Waiting for transfers to finish 2020/01/19 05:35:58 DEBUG : potato2: Can't copy - required hash not found 2020/01/19 05:35:58 DEBUG : potato2.rclone_chunk.001_cfaqg0: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2020/01/19 05:35:58 INFO : potato2.rclone_chunk.001_cfaqg0: Copied (server side copy) 2020/01/19 05:35:58 INFO : potato2.rclone_chunk.001_cfaqg0: Deleted 2020/01/19 05:35:58 DEBUG : potato2.rclone_chunk.002_cfaqg0: MD5 = 01a6651a94ab602dbfc47587d234b52c OK 2020/01/19 05:35:58 INFO : potato2.rclone_chunk.002_cfaqg0: Copied (server side copy) 2020/01/19 05:35:58 INFO : potato2.rclone_chunk.002_cfaqg0: Deleted 2020/01/19 05:35:58 INFO : potato2: Copied (new) 2020/01/19 05:35:58 INFO : potato2: Deleted 2020/01/19 05:35:58 DEBUG : potato3: Can't copy - required hash not found 2020/01/19 05:36:08 DEBUG : potato3.rclone_chunk.001_cfb005: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2020/01/19 05:36:08 INFO : potato3.rclone_chunk.001_cfb005: Copied (server side copy) 2020/01/19 05:36:08 INFO : potato3.rclone_chunk.001_cfb005: Deleted 2020/01/19 05:36:08 DEBUG : potato3.rclone_chunk.002_cfb005: MD5 = 111c387f2584838efb7e0ec6f2fa2e10 OK 2020/01/19 05:36:08 INFO : potato3.rclone_chunk.002_cfb005: Copied (server side copy) 2020/01/19 05:36:08 INFO : potato3.rclone_chunk.002_cfb005: Deleted 2020/01/19 05:36:08 INFO : potato3: Copied (replaced existing) 2020/01/19 05:36:08 INFO : potato3: Deleted 2020/01/19 05:36:09 DEBUG : tomatoDir: Making directory 2020/01/19 05:36:09 DEBUG : empty space: copy non-chunked object... 2020/01/19 05:36:09 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-nuluwik2murowok3ratejol8': Waiting for checks to finish 2020/01/19 05:36:09 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-nuluwik2murowok3ratejol8': Waiting for transfers to finish 2020/01/19 05:36:09 DEBUG : potato2: Can't copy - required hash not found 2020/01/19 05:36:09 DEBUG : potato3: Can't copy - required hash not found 2020/01/19 05:36:09 INFO : S3 bucket rclone-test-nuluwik2murowok3ratejol8: Bucket "rclone-test-nuluwik2murowok3ratejol8" created with ACL "private" 2020/01/19 05:36:10 INFO : empty space: Copied (server side copy) 2020/01/19 05:36:10 INFO : empty space: Deleted 2020/01/19 05:36:10 DEBUG : potato3.rclone_chunk.001_cfb1ix: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2020/01/19 05:36:10 INFO : potato3.rclone_chunk.001_cfb1ix: Copied (server side copy) 2020/01/19 05:36:10 DEBUG : potato2.rclone_chunk.001_cfb145: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2020/01/19 05:36:10 INFO : potato2.rclone_chunk.001_cfb145: Copied (server side copy) 2020/01/19 05:36:10 INFO : potato3.rclone_chunk.001_cfb1ix: Deleted 2020/01/19 05:36:10 INFO : potato2.rclone_chunk.001_cfb145: Deleted 2020/01/19 05:36:10 DEBUG : potato3.rclone_chunk.002_cfb1ix: MD5 = 111c387f2584838efb7e0ec6f2fa2e10 OK 2020/01/19 05:36:10 INFO : potato3.rclone_chunk.002_cfb1ix: Copied (server side copy) 2020/01/19 05:36:10 INFO : potato3.rclone_chunk.002_cfb1ix: Deleted 2020/01/19 05:36:10 DEBUG : potato2.rclone_chunk.002_cfb145: MD5 = 01a6651a94ab602dbfc47587d234b52c OK 2020/01/19 05:36:10 INFO : potato2.rclone_chunk.002_cfb145: Copied (server side copy) 2020/01/19 05:36:10 INFO : potato3: Copied (new) 2020/01/19 05:36:10 INFO : potato2.rclone_chunk.002_cfb145: Deleted 2020/01/19 05:36:10 INFO : potato2: Copied (new) 2020/01/19 05:36:10 INFO : potato3: Deleted 2020/01/19 05:36:10 INFO : potato2: Deleted 2020/01/19 05:36:10 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-nuluwik2murowok3ratejol8': Purge object "empty space" 2020/01/19 05:36:10 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-nuluwik2murowok3ratejol8': Purge object "potato2" 2020/01/19 05:36:10 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-nuluwik2murowok3ratejol8': Purge object "potato3" 2020/01/19 05:36:10 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-nuluwik2murowok3ratejol8': Purge dir "" 2020/01/19 05:36:10 INFO : S3 bucket rclone-test-nuluwik2murowok3ratejol8: Bucket "rclone-test-nuluwik2murowok3ratejol8" deleted 2020/01/19 05:36:10 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-gizaxul3bozelaw0gajesog3': Purge dir "" 2020/01/19 05:36:11 INFO : S3 bucket rclone-test-gizaxul3bozelaw0gajesog3: Bucket "rclone-test-gizaxul3bozelaw0gajesog3" deleted --- PASS: TestServerSideMoveDeleteEmptySourceDirs (15.16s) run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5'", Local "Local file system at /tmp/rclone910142536", Modify Window "1ns" sync_test.go:1110: Server side move (if possible) Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5' -> Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-gizaxul3bozelaw0gajesog3' === RUN TestServerSideMoveOverlap 2020/01/19 05:36:11 DEBUG : potato2.rclone_chunk.001_cfb3sv: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2020/01/19 05:36:11 INFO : potato2.rclone_chunk.001_cfb3sv: Copied (server side copy) 2020/01/19 05:36:11 INFO : potato2.rclone_chunk.001_cfb3sv: Deleted 2020/01/19 05:36:11 DEBUG : potato2.rclone_chunk.002_cfb3sv: MD5 = 01a6651a94ab602dbfc47587d234b52c OK 2020/01/19 05:36:11 INFO : potato2.rclone_chunk.002_cfb3sv: Copied (server side copy) 2020/01/19 05:36:11 INFO : potato2.rclone_chunk.002_cfb3sv: Deleted --- PASS: TestServerSideMoveOverlap (0.34s) run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5'", Local "Local file system at /tmp/rclone910142536", Modify Window "1ns" === RUN TestSyncOverlap --- PASS: TestSyncOverlap (0.04s) run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5'", Local "Local file system at /tmp/rclone910142536", Modify Window "1ns" === RUN TestSyncCompareDest 2020/01/19 05:36:11 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5/dst': Waiting for checks to finish 2020/01/19 05:36:11 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5/dst': Waiting for transfers to finish 2020/01/19 05:36:11 DEBUG : one.rclone_chunk.001_cfb3k1: MD5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2020/01/19 05:36:11 INFO : one.rclone_chunk.001_cfb3k1: Copied (server side copy) 2020/01/19 05:36:11 INFO : one.rclone_chunk.001_cfb3k1: Deleted 2020/01/19 05:36:11 INFO : one: Copied (new) 2020/01/19 05:36:11 INFO : Waiting for deletions to finish 2020/01/19 05:36:12 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5/dst': Waiting for checks to finish 2020/01/19 05:36:12 DEBUG : one: Sizes differ (src 5 vs dst 3) 2020/01/19 05:36:12 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5/dst': Waiting for transfers to finish 2020/01/19 05:36:12 DEBUG : one.rclone_chunk.001_cfb43r: MD5 = 07912d142f5d63ee918b34796b5a2432 OK 2020/01/19 05:36:12 INFO : one.rclone_chunk.001_cfb43r: Copied (server side copy) 2020/01/19 05:36:12 INFO : one.rclone_chunk.001_cfb43r: Deleted 2020/01/19 05:36:12 INFO : one: Copied (replaced existing) 2020/01/19 05:36:12 INFO : Waiting for deletions to finish 2020/01/19 05:36:12 DEBUG : dst/one.rclone_chunk.001_cfb4nf: MD5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2020/01/19 05:36:12 INFO : dst/one.rclone_chunk.001_cfb4nf: Copied (server side copy) 2020/01/19 05:36:12 INFO : dst/one.rclone_chunk.001_cfb4nf: Deleted 2020/01/19 05:36:12 DEBUG : CompareDest/one.rclone_chunk.001_cfb43b: MD5 = 07912d142f5d63ee918b34796b5a2432 OK 2020/01/19 05:36:12 INFO : CompareDest/one.rclone_chunk.001_cfb43b: Copied (server side copy) 2020/01/19 05:36:12 INFO : CompareDest/one.rclone_chunk.001_cfb43b: Deleted 2020/01/19 05:36:13 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5/dst': Waiting for checks to finish 2020/01/19 05:36:13 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:36:13 DEBUG : one: Destination found in --compare-dest, skipping 2020/01/19 05:36:13 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5/dst': Waiting for transfers to finish 2020/01/19 05:36:13 INFO : Waiting for deletions to finish 2020/01/19 05:36:13 DEBUG : CompareDest/two.rclone_chunk.001_cfb5vl: MD5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2020/01/19 05:36:13 INFO : CompareDest/two.rclone_chunk.001_cfb5vl: Copied (server side copy) 2020/01/19 05:36:13 INFO : CompareDest/two.rclone_chunk.001_cfb5vl: Deleted 2020/01/19 05:36:14 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:36:14 DEBUG : two: Destination found in --compare-dest, skipping 2020/01/19 05:36:14 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5/dst': Waiting for checks to finish 2020/01/19 05:36:14 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:36:14 DEBUG : one: Destination found in --compare-dest, skipping 2020/01/19 05:36:14 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5/dst': Waiting for transfers to finish 2020/01/19 05:36:14 INFO : Waiting for deletions to finish 2020/01/19 05:36:14 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:36:14 DEBUG : two: Destination found in --compare-dest, skipping 2020/01/19 05:36:14 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5/dst': Waiting for checks to finish 2020/01/19 05:36:14 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:36:14 DEBUG : one: Destination found in --compare-dest, skipping 2020/01/19 05:36:14 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5/dst': Waiting for transfers to finish 2020/01/19 05:36:14 INFO : Waiting for deletions to finish 2020/01/19 05:36:14 DEBUG : two: Sizes differ (src 5 vs dst 3) 2020/01/19 05:36:14 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5/dst': Waiting for checks to finish 2020/01/19 05:36:14 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:36:14 DEBUG : one: Destination found in --compare-dest, skipping 2020/01/19 05:36:14 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5/dst': Waiting for transfers to finish 2020/01/19 05:36:15 DEBUG : two.rclone_chunk.001_cfb68z: MD5 = 2379e4ce8c3380e996ab0509f17069ad OK 2020/01/19 05:36:15 INFO : two.rclone_chunk.001_cfb68z: Copied (server side copy) 2020/01/19 05:36:15 INFO : two.rclone_chunk.001_cfb68z: Deleted 2020/01/19 05:36:15 INFO : two: Copied (new) 2020/01/19 05:36:15 INFO : Waiting for deletions to finish --- PASS: TestSyncCompareDest (3.34s) run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5'", Local "Local file system at /tmp/rclone910142536", Modify Window "1ns" === RUN TestSyncCopyDest 2020/01/19 05:36:15 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5/dst': Waiting for checks to finish 2020/01/19 05:36:15 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5/dst': Waiting for transfers to finish 2020/01/19 05:36:15 DEBUG : one.rclone_chunk.001_cfb7jq: MD5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2020/01/19 05:36:15 INFO : one.rclone_chunk.001_cfb7jq: Copied (server side copy) 2020/01/19 05:36:15 INFO : one.rclone_chunk.001_cfb7jq: Deleted 2020/01/19 05:36:15 INFO : one: Copied (new) 2020/01/19 05:36:15 INFO : Waiting for deletions to finish 2020/01/19 05:36:15 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5/dst': Waiting for checks to finish 2020/01/19 05:36:15 DEBUG : one: Sizes differ (src 5 vs dst 3) 2020/01/19 05:36:15 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5/dst': Waiting for transfers to finish 2020/01/19 05:36:15 DEBUG : one.rclone_chunk.001_cfb7yq: MD5 = 07912d142f5d63ee918b34796b5a2432 OK 2020/01/19 05:36:15 INFO : one.rclone_chunk.001_cfb7yq: Copied (server side copy) 2020/01/19 05:36:15 INFO : one.rclone_chunk.001_cfb7yq: Deleted 2020/01/19 05:36:15 INFO : one: Copied (replaced existing) 2020/01/19 05:36:15 INFO : Waiting for deletions to finish 2020/01/19 05:36:15 DEBUG : dst/one.rclone_chunk.001_cfb7eo: MD5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2020/01/19 05:36:15 INFO : dst/one.rclone_chunk.001_cfb7eo: Copied (server side copy) 2020/01/19 05:36:15 INFO : dst/one.rclone_chunk.001_cfb7eo: Deleted 2020/01/19 05:36:15 DEBUG : CopyDest/one.rclone_chunk.001_cfb745: MD5 = 07912d142f5d63ee918b34796b5a2432 OK 2020/01/19 05:36:15 INFO : CopyDest/one.rclone_chunk.001_cfb745: Copied (server side copy) 2020/01/19 05:36:15 INFO : CopyDest/one.rclone_chunk.001_cfb745: Deleted 2020/01/19 05:36:15 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5/dst': Waiting for checks to finish 2020/01/19 05:36:16 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:36:16 DEBUG : one: Sizes differ (src 5 vs dst 3) 2020/01/19 05:36:16 DEBUG : one: copy non-chunked object... 2020/01/19 05:36:16 INFO : one: Copied (server side copy) 2020/01/19 05:36:16 INFO : one: Deleted 2020/01/19 05:36:16 DEBUG : one: copy non-chunked object... 2020/01/19 05:36:16 INFO : one: Copied (server side copy) 2020/01/19 05:36:16 DEBUG : one: Destination found in --copy-dest, using server side copy 2020/01/19 05:36:16 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5/dst': Waiting for transfers to finish 2020/01/19 05:36:16 INFO : Waiting for deletions to finish 2020/01/19 05:36:16 DEBUG : CopyDest/two.rclone_chunk.001_cfb8iw: MD5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2020/01/19 05:36:16 INFO : CopyDest/two.rclone_chunk.001_cfb8iw: Copied (server side copy) 2020/01/19 05:36:17 INFO : CopyDest/two.rclone_chunk.001_cfb8iw: Deleted 2020/01/19 05:36:17 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:36:17 DEBUG : two: copy non-chunked object... 2020/01/19 05:36:17 INFO : two: Copied (server side copy) 2020/01/19 05:36:17 DEBUG : two: Destination found in --copy-dest, using server side copy 2020/01/19 05:36:17 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5/dst': Waiting for checks to finish 2020/01/19 05:36:17 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:36:17 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:36:17 DEBUG : one: Unchanged skipping 2020/01/19 05:36:17 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5/dst': Waiting for transfers to finish 2020/01/19 05:36:17 INFO : Waiting for deletions to finish 2020/01/19 05:36:17 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5/dst': Waiting for checks to finish 2020/01/19 05:36:17 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:36:17 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:36:17 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:36:17 DEBUG : two: Unchanged skipping 2020/01/19 05:36:17 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:36:17 DEBUG : one: Unchanged skipping 2020/01/19 05:36:17 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5/dst': Waiting for transfers to finish 2020/01/19 05:36:17 INFO : Waiting for deletions to finish 2020/01/19 05:36:17 DEBUG : CopyDest/three.rclone_chunk.001_cfb9yx: MD5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2020/01/19 05:36:17 INFO : CopyDest/three.rclone_chunk.001_cfb9yx: Copied (server side copy) 2020/01/19 05:36:17 INFO : CopyDest/three.rclone_chunk.001_cfb9yx: Deleted 2020/01/19 05:36:18 DEBUG : three: Sizes differ (src 7 vs dst 5) 2020/01/19 05:36:18 DEBUG : three: Destination not found in --copy-dest 2020/01/19 05:36:18 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5/dst': Waiting for checks to finish 2020/01/19 05:36:18 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:36:18 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:36:18 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:36:18 DEBUG : one: Unchanged skipping 2020/01/19 05:36:18 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:36:18 DEBUG : two: Unchanged skipping 2020/01/19 05:36:18 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5/dst': Waiting for transfers to finish 2020/01/19 05:36:18 DEBUG : three.rclone_chunk.001_cfbapg: MD5 = 1bccb9dccb3e9f6a3f9d2a8bdb54b7f5 OK 2020/01/19 05:36:18 INFO : three.rclone_chunk.001_cfbapg: Copied (server side copy) 2020/01/19 05:36:18 INFO : three.rclone_chunk.001_cfbapg: Deleted 2020/01/19 05:36:18 INFO : three: Copied (new) 2020/01/19 05:36:18 INFO : Waiting for deletions to finish --- PASS: TestSyncCopyDest (3.26s) run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5'", Local "Local file system at /tmp/rclone910142536", Modify Window "1ns" === RUN TestSyncBackupDir 2020/01/19 05:36:18 DEBUG : dst/one.rclone_chunk.001_cfbaee: MD5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2020/01/19 05:36:18 INFO : dst/one.rclone_chunk.001_cfbaee: Copied (server side copy) 2020/01/19 05:36:18 INFO : dst/one.rclone_chunk.001_cfbaee: Deleted 2020/01/19 05:36:18 DEBUG : dst/two.rclone_chunk.001_cfbas8: MD5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2020/01/19 05:36:18 INFO : dst/two.rclone_chunk.001_cfbas8: Copied (server side copy) 2020/01/19 05:36:18 INFO : dst/two.rclone_chunk.001_cfbas8: Deleted 2020/01/19 05:36:18 DEBUG : dst/three.txt.rclone_chunk.001_cfba82: MD5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2020/01/19 05:36:18 INFO : dst/three.txt.rclone_chunk.001_cfba82: Copied (server side copy) 2020/01/19 05:36:18 INFO : dst/three.txt.rclone_chunk.001_cfba82: Deleted 2020/01/19 05:36:18 DEBUG : one: Sizes differ (src 4 vs dst 3) 2020/01/19 05:36:18 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5/dst': Waiting for checks to finish 2020/01/19 05:36:18 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:36:18 DEBUG : two: Unchanged skipping 2020/01/19 05:36:18 DEBUG : one: copy non-chunked object... 2020/01/19 05:36:19 INFO : one: Copied (server side copy) 2020/01/19 05:36:19 INFO : one: Deleted 2020/01/19 05:36:19 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5/dst': Waiting for transfers to finish 2020/01/19 05:36:19 DEBUG : one.rclone_chunk.001_cfbb40: MD5 = c7957179c41f69d44f217a108c7915d8 OK 2020/01/19 05:36:19 INFO : one.rclone_chunk.001_cfbb40: Copied (server side copy) 2020/01/19 05:36:19 INFO : one.rclone_chunk.001_cfbb40: Deleted 2020/01/19 05:36:19 INFO : one: Copied (new) 2020/01/19 05:36:19 INFO : Waiting for deletions to finish 2020/01/19 05:36:19 DEBUG : three.txt: copy non-chunked object... 2020/01/19 05:36:19 INFO : three.txt: Copied (server side copy) 2020/01/19 05:36:19 INFO : three.txt: Deleted 2020/01/19 05:36:19 INFO : three.txt: Moved into backup dir 2020/01/19 05:36:19 DEBUG : dst/three.txt.rclone_chunk.001_cfbbix: MD5 = 91341eed84691a83caea73aa785736d5 OK 2020/01/19 05:36:19 INFO : dst/three.txt.rclone_chunk.001_cfbbix: Copied (server side copy) 2020/01/19 05:36:19 INFO : dst/three.txt.rclone_chunk.001_cfbbix: Deleted 2020/01/19 05:36:19 DEBUG : one: Sizes differ (src 5 vs dst 4) 2020/01/19 05:36:19 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5/dst': Waiting for checks to finish 2020/01/19 05:36:19 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:36:19 DEBUG : two: Unchanged skipping 2020/01/19 05:36:19 DEBUG : one: copy non-chunked object... 2020/01/19 05:36:19 INFO : one: Copied (server side copy) 2020/01/19 05:36:19 INFO : one: Deleted 2020/01/19 05:36:19 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5/dst': Waiting for transfers to finish 2020/01/19 05:36:19 DEBUG : one.rclone_chunk.001_cfbbvs: MD5 = 0f93e81041f0cab37c37a05ae998b219 OK 2020/01/19 05:36:19 INFO : one.rclone_chunk.001_cfbbvs: Copied (server side copy) 2020/01/19 05:36:19 INFO : one.rclone_chunk.001_cfbbvs: Deleted 2020/01/19 05:36:19 INFO : one: Copied (new) 2020/01/19 05:36:19 INFO : Waiting for deletions to finish 2020/01/19 05:36:19 DEBUG : three.txt: copy non-chunked object... 2020/01/19 05:36:20 INFO : three.txt: Copied (server side copy) 2020/01/19 05:36:20 INFO : three.txt: Deleted 2020/01/19 05:36:20 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (2.19s) run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5'", Local "Local file system at /tmp/rclone910142536", Modify Window "1ns" === RUN TestSyncBackupDirWithSuffix 2020/01/19 05:36:20 DEBUG : dst/one.rclone_chunk.001_cfbcof: MD5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2020/01/19 05:36:20 INFO : dst/one.rclone_chunk.001_cfbcof: Copied (server side copy) 2020/01/19 05:36:20 INFO : dst/one.rclone_chunk.001_cfbcof: Deleted 2020/01/19 05:36:20 DEBUG : dst/two.rclone_chunk.001_cfbcuh: MD5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2020/01/19 05:36:20 INFO : dst/two.rclone_chunk.001_cfbcuh: Copied (server side copy) 2020/01/19 05:36:20 INFO : dst/two.rclone_chunk.001_cfbcuh: Deleted 2020/01/19 05:36:20 DEBUG : dst/three.txt.rclone_chunk.001_cfbcrp: MD5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2020/01/19 05:36:20 INFO : dst/three.txt.rclone_chunk.001_cfbcrp: Copied (server side copy) 2020/01/19 05:36:20 INFO : dst/three.txt.rclone_chunk.001_cfbcrp: Deleted 2020/01/19 05:36:21 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5/dst': Waiting for checks to finish 2020/01/19 05:36:21 DEBUG : one: Sizes differ (src 4 vs dst 3) 2020/01/19 05:36:21 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:36:21 DEBUG : two: Unchanged skipping 2020/01/19 05:36:21 DEBUG : one: copy non-chunked object... 2020/01/19 05:36:21 INFO : one: Copied (server side copy) 2020/01/19 05:36:21 INFO : one: Deleted 2020/01/19 05:36:21 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5/dst': Waiting for transfers to finish 2020/01/19 05:36:21 DEBUG : one.rclone_chunk.001_cfbdfb: MD5 = c7957179c41f69d44f217a108c7915d8 OK 2020/01/19 05:36:21 INFO : one.rclone_chunk.001_cfbdfb: Copied (server side copy) 2020/01/19 05:36:21 INFO : one.rclone_chunk.001_cfbdfb: Deleted 2020/01/19 05:36:21 INFO : one: Copied (new) 2020/01/19 05:36:21 INFO : Waiting for deletions to finish 2020/01/19 05:36:21 DEBUG : three.txt: copy non-chunked object... 2020/01/19 05:36:21 INFO : three.txt: Copied (server side copy) 2020/01/19 05:36:21 INFO : three.txt: Deleted 2020/01/19 05:36:21 INFO : three.txt: Moved into backup dir 2020/01/19 05:36:21 DEBUG : dst/three.txt.rclone_chunk.001_cfbd0m: MD5 = 91341eed84691a83caea73aa785736d5 OK 2020/01/19 05:36:21 INFO : dst/three.txt.rclone_chunk.001_cfbd0m: Copied (server side copy) 2020/01/19 05:36:21 INFO : dst/three.txt.rclone_chunk.001_cfbd0m: Deleted 2020/01/19 05:36:22 DEBUG : one: Sizes differ (src 5 vs dst 4) 2020/01/19 05:36:22 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5/dst': Waiting for checks to finish 2020/01/19 05:36:22 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:36:22 DEBUG : two: Unchanged skipping 2020/01/19 05:36:22 DEBUG : one: copy non-chunked object... 2020/01/19 05:36:22 INFO : one: Copied (server side copy) 2020/01/19 05:36:22 INFO : one: Deleted 2020/01/19 05:36:22 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5/dst': Waiting for transfers to finish 2020/01/19 05:36:22 DEBUG : one.rclone_chunk.001_cfbecx: MD5 = 0f93e81041f0cab37c37a05ae998b219 OK 2020/01/19 05:36:22 INFO : one.rclone_chunk.001_cfbecx: Copied (server side copy) 2020/01/19 05:36:22 INFO : one.rclone_chunk.001_cfbecx: Deleted 2020/01/19 05:36:22 INFO : one: Copied (new) 2020/01/19 05:36:22 INFO : Waiting for deletions to finish 2020/01/19 05:36:22 DEBUG : three.txt: copy non-chunked object... 2020/01/19 05:36:22 INFO : three.txt: Copied (server side copy) 2020/01/19 05:36:22 INFO : three.txt: Deleted 2020/01/19 05:36:22 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (2.48s) run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5'", Local "Local file system at /tmp/rclone910142536", Modify Window "1ns" === RUN TestSyncBackupDirWithSuffixKeepExtension 2020/01/19 05:36:23 DEBUG : dst/one.rclone_chunk.001_cfbfxr: MD5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2020/01/19 05:36:23 INFO : dst/one.rclone_chunk.001_cfbfxr: Copied (server side copy) 2020/01/19 05:36:23 INFO : dst/one.rclone_chunk.001_cfbfxr: Deleted 2020/01/19 05:36:23 DEBUG : dst/two.rclone_chunk.001_cfbf1y: MD5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2020/01/19 05:36:23 INFO : dst/two.rclone_chunk.001_cfbf1y: Copied (server side copy) 2020/01/19 05:36:23 INFO : dst/two.rclone_chunk.001_cfbf1y: Deleted 2020/01/19 05:36:23 DEBUG : dst/three.txt.rclone_chunk.001_cfbfaf: MD5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2020/01/19 05:36:23 INFO : dst/three.txt.rclone_chunk.001_cfbfaf: Copied (server side copy) 2020/01/19 05:36:23 INFO : dst/three.txt.rclone_chunk.001_cfbfaf: Deleted 2020/01/19 05:36:23 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5/dst': Waiting for checks to finish 2020/01/19 05:36:23 DEBUG : one: Sizes differ (src 4 vs dst 3) 2020/01/19 05:36:23 DEBUG : one: copy non-chunked object... 2020/01/19 05:36:23 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:36:23 DEBUG : two: Unchanged skipping 2020/01/19 05:36:24 INFO : one: Copied (server side copy) 2020/01/19 05:36:24 INFO : one: Deleted 2020/01/19 05:36:24 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5/dst': Waiting for transfers to finish 2020/01/19 05:36:24 DEBUG : one.rclone_chunk.001_cfbgaf: MD5 = c7957179c41f69d44f217a108c7915d8 OK 2020/01/19 05:36:24 INFO : one.rclone_chunk.001_cfbgaf: Copied (server side copy) 2020/01/19 05:36:24 INFO : one.rclone_chunk.001_cfbgaf: Deleted 2020/01/19 05:36:24 INFO : one: Copied (new) 2020/01/19 05:36:24 INFO : Waiting for deletions to finish 2020/01/19 05:36:24 DEBUG : three.txt: copy non-chunked object... 2020/01/19 05:36:24 INFO : three.txt: Copied (server side copy) 2020/01/19 05:36:24 INFO : three.txt: Deleted 2020/01/19 05:36:24 INFO : three.txt: Moved into backup dir 2020/01/19 05:36:24 DEBUG : dst/three.txt.rclone_chunk.001_cfbgm1: MD5 = 91341eed84691a83caea73aa785736d5 OK 2020/01/19 05:36:24 INFO : dst/three.txt.rclone_chunk.001_cfbgm1: Copied (server side copy) 2020/01/19 05:36:24 INFO : dst/three.txt.rclone_chunk.001_cfbgm1: Deleted 2020/01/19 05:36:25 DEBUG : one: Sizes differ (src 5 vs dst 4) 2020/01/19 05:36:25 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5/dst': Waiting for checks to finish 2020/01/19 05:36:25 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:36:25 DEBUG : two: Unchanged skipping 2020/01/19 05:36:25 DEBUG : one: copy non-chunked object... 2020/01/19 05:36:25 INFO : one: Copied (server side copy) 2020/01/19 05:36:26 INFO : one: Deleted 2020/01/19 05:36:26 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5/dst': Waiting for transfers to finish 2020/01/19 05:36:26 DEBUG : one.rclone_chunk.001_cfbiun: MD5 = 0f93e81041f0cab37c37a05ae998b219 OK 2020/01/19 05:36:26 INFO : one.rclone_chunk.001_cfbiun: Copied (server side copy) 2020/01/19 05:36:26 INFO : one.rclone_chunk.001_cfbiun: Deleted 2020/01/19 05:36:26 INFO : one: Copied (new) 2020/01/19 05:36:26 INFO : Waiting for deletions to finish 2020/01/19 05:36:26 DEBUG : three.txt: copy non-chunked object... 2020/01/19 05:36:26 INFO : three.txt: Copied (server side copy) 2020/01/19 05:36:26 INFO : three.txt: Deleted 2020/01/19 05:36:26 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (3.96s) run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5'", Local "Local file system at /tmp/rclone910142536", Modify Window "1ns" === RUN TestSyncSuffix 2020/01/19 05:36:27 DEBUG : dst/one.rclone_chunk.001_cfbjfs: MD5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2020/01/19 05:36:27 INFO : dst/one.rclone_chunk.001_cfbjfs: Copied (server side copy) 2020/01/19 05:36:27 INFO : dst/one.rclone_chunk.001_cfbjfs: Deleted 2020/01/19 05:36:27 DEBUG : dst/two.rclone_chunk.001_cfbj1g: MD5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2020/01/19 05:36:27 INFO : dst/two.rclone_chunk.001_cfbj1g: Copied (server side copy) 2020/01/19 05:36:27 INFO : dst/two.rclone_chunk.001_cfbj1g: Deleted 2020/01/19 05:36:27 DEBUG : dst/three.txt.rclone_chunk.001_cfbj23: MD5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2020/01/19 05:36:27 INFO : dst/three.txt.rclone_chunk.001_cfbj23: Copied (server side copy) 2020/01/19 05:36:27 INFO : dst/three.txt.rclone_chunk.001_cfbj23: Deleted 2020/01/19 05:36:27 DEBUG : one: Sizes differ (src 4 vs dst 3) 2020/01/19 05:36:27 DEBUG : one: copy non-chunked object... 2020/01/19 05:36:27 INFO : one: Copied (server side copy) 2020/01/19 05:36:27 INFO : one: Deleted 2020/01/19 05:36:27 DEBUG : one.rclone_chunk.001_cfbjrl: MD5 = c7957179c41f69d44f217a108c7915d8 OK 2020/01/19 05:36:27 INFO : one.rclone_chunk.001_cfbjrl: Copied (server side copy) 2020/01/19 05:36:27 INFO : one.rclone_chunk.001_cfbjrl: Deleted 2020/01/19 05:36:27 INFO : one: Copied (new) 2020/01/19 05:36:27 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:36:27 DEBUG : two: Unchanged skipping 2020/01/19 05:36:27 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2020/01/19 05:36:27 DEBUG : three.txt: copy non-chunked object... 2020/01/19 05:36:27 INFO : three.txt: Copied (server side copy) 2020/01/19 05:36:28 INFO : three.txt: Deleted 2020/01/19 05:36:28 DEBUG : three.txt.rclone_chunk.001_cfbk7m: MD5 = 91341eed84691a83caea73aa785736d5 OK 2020/01/19 05:36:28 INFO : three.txt.rclone_chunk.001_cfbk7m: Copied (server side copy) 2020/01/19 05:36:28 INFO : three.txt.rclone_chunk.001_cfbk7m: Deleted 2020/01/19 05:36:28 INFO : three.txt: Copied (new) 2020/01/19 05:36:28 DEBUG : one: Sizes differ (src 5 vs dst 4) 2020/01/19 05:36:28 DEBUG : one: copy non-chunked object... 2020/01/19 05:36:28 INFO : one: Copied (server side copy) 2020/01/19 05:36:28 INFO : one: Deleted 2020/01/19 05:36:28 DEBUG : one.rclone_chunk.001_cfbk63: MD5 = 0f93e81041f0cab37c37a05ae998b219 OK 2020/01/19 05:36:28 INFO : one.rclone_chunk.001_cfbk63: Copied (server side copy) 2020/01/19 05:36:28 INFO : one.rclone_chunk.001_cfbk63: Deleted 2020/01/19 05:36:28 INFO : one: Copied (new) 2020/01/19 05:36:28 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:36:28 DEBUG : two: Unchanged skipping 2020/01/19 05:36:28 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2020/01/19 05:36:29 DEBUG : three.txt: copy non-chunked object... 2020/01/19 05:36:29 INFO : three.txt: Copied (server side copy) 2020/01/19 05:36:29 INFO : three.txt: Deleted 2020/01/19 05:36:29 DEBUG : three.txt.rclone_chunk.001_cfblch: MD5 = 423e01c5e84a30d129b97aedb6e219a9 OK 2020/01/19 05:36:29 INFO : three.txt.rclone_chunk.001_cfblch: Copied (server side copy) 2020/01/19 05:36:29 INFO : three.txt.rclone_chunk.001_cfblch: Deleted 2020/01/19 05:36:29 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (2.37s) run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5'", Local "Local file system at /tmp/rclone910142536", Modify Window "1ns" === RUN TestSyncSuffixKeepExtension 2020/01/19 05:36:29 DEBUG : dst/one.rclone_chunk.001_cfblhk: MD5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2020/01/19 05:36:29 INFO : dst/one.rclone_chunk.001_cfblhk: Copied (server side copy) 2020/01/19 05:36:29 INFO : dst/one.rclone_chunk.001_cfblhk: Deleted 2020/01/19 05:36:29 DEBUG : dst/two.rclone_chunk.001_cfbl5z: MD5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2020/01/19 05:36:29 INFO : dst/two.rclone_chunk.001_cfbl5z: Copied (server side copy) 2020/01/19 05:36:29 INFO : dst/two.rclone_chunk.001_cfbl5z: Deleted 2020/01/19 05:36:29 DEBUG : dst/three.txt.rclone_chunk.001_cfbl0t: MD5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2020/01/19 05:36:29 INFO : dst/three.txt.rclone_chunk.001_cfbl0t: Copied (server side copy) 2020/01/19 05:36:29 INFO : dst/three.txt.rclone_chunk.001_cfbl0t: Deleted 2020/01/19 05:36:29 DEBUG : one: Sizes differ (src 4 vs dst 3) 2020/01/19 05:36:29 DEBUG : one: copy non-chunked object... 2020/01/19 05:36:29 INFO : one: Copied (server side copy) 2020/01/19 05:36:29 INFO : one: Deleted 2020/01/19 05:36:30 DEBUG : one.rclone_chunk.001_cfblo6: MD5 = c7957179c41f69d44f217a108c7915d8 OK 2020/01/19 05:36:30 INFO : one.rclone_chunk.001_cfblo6: Copied (server side copy) 2020/01/19 05:36:30 INFO : one.rclone_chunk.001_cfblo6: Deleted 2020/01/19 05:36:30 INFO : one: Copied (new) 2020/01/19 05:36:30 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:36:30 DEBUG : two: Unchanged skipping 2020/01/19 05:36:30 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2020/01/19 05:36:30 DEBUG : three.txt: copy non-chunked object... 2020/01/19 05:36:30 INFO : three.txt: Copied (server side copy) 2020/01/19 05:36:30 INFO : three.txt: Deleted 2020/01/19 05:36:30 DEBUG : three.txt.rclone_chunk.001_cfbmhg: MD5 = 91341eed84691a83caea73aa785736d5 OK 2020/01/19 05:36:30 INFO : three.txt.rclone_chunk.001_cfbmhg: Copied (server side copy) 2020/01/19 05:36:30 INFO : three.txt.rclone_chunk.001_cfbmhg: Deleted 2020/01/19 05:36:30 INFO : three.txt: Copied (new) 2020/01/19 05:36:30 DEBUG : one: Sizes differ (src 5 vs dst 4) 2020/01/19 05:36:30 DEBUG : one: copy non-chunked object... 2020/01/19 05:36:30 INFO : one: Copied (server side copy) 2020/01/19 05:36:30 INFO : one: Deleted 2020/01/19 05:36:30 DEBUG : one.rclone_chunk.001_cfbmz4: MD5 = 0f93e81041f0cab37c37a05ae998b219 OK 2020/01/19 05:36:30 INFO : one.rclone_chunk.001_cfbmz4: Copied (server side copy) 2020/01/19 05:36:30 INFO : one.rclone_chunk.001_cfbmz4: Deleted 2020/01/19 05:36:30 INFO : one: Copied (new) 2020/01/19 05:36:30 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:36:30 DEBUG : two: Unchanged skipping 2020/01/19 05:36:30 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2020/01/19 05:36:30 DEBUG : three.txt: copy non-chunked object... 2020/01/19 05:36:30 INFO : three.txt: Copied (server side copy) 2020/01/19 05:36:30 INFO : three.txt: Deleted 2020/01/19 05:36:30 DEBUG : three.txt.rclone_chunk.001_cfbm5k: MD5 = 423e01c5e84a30d129b97aedb6e219a9 OK 2020/01/19 05:36:30 INFO : three.txt.rclone_chunk.001_cfbm5k: Copied (server side copy) 2020/01/19 05:36:30 INFO : three.txt.rclone_chunk.001_cfbm5k: Deleted 2020/01/19 05:36:30 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (1.60s) run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5'", Local "Local file system at /tmp/rclone910142536", Modify Window "1ns" === RUN TestSyncUTFNorm 2020/01/19 05:36:31 DEBUG : Testêé.rclone_chunk.001_cfbnru: MD5 = 3b5c716b1f94ae30389ea40ea76fa0d5 OK 2020/01/19 05:36:31 INFO : Testêé.rclone_chunk.001_cfbnru: Copied (server side copy) 2020/01/19 05:36:31 INFO : Testêé.rclone_chunk.001_cfbnru: Deleted 2020/01/19 05:36:31 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for checks to finish 2020/01/19 05:36:31 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2020/01/19 05:36:31 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for transfers to finish 2020/01/19 05:36:31 DEBUG : Testêé.rclone_chunk.001_cfbnyx: MD5 = ce114e4501d2f4e2dcea3e17b546f339 OK 2020/01/19 05:36:31 INFO : Testêé.rclone_chunk.001_cfbnyx: Copied (server side copy) 2020/01/19 05:36:31 INFO : Testêé.rclone_chunk.001_cfbnyx: Deleted 2020/01/19 05:36:31 INFO : Testêé: Copied (replaced existing) 2020/01/19 05:36:31 INFO : Waiting for deletions to finish --- PASS: TestSyncUTFNorm (1.00s) run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5'", Local "Local file system at /tmp/rclone910142536", Modify Window "1ns" === RUN TestSyncImmutable 2020/01/19 05:36:32 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for checks to finish 2020/01/19 05:36:32 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for transfers to finish 2020/01/19 05:36:32 DEBUG : existing.rclone_chunk.001_cfbo85: MD5 = 8ee2027983915ec78acc45027d874316 OK 2020/01/19 05:36:32 INFO : existing.rclone_chunk.001_cfbo85: Copied (server side copy) 2020/01/19 05:36:32 INFO : existing.rclone_chunk.001_cfbo85: Deleted 2020/01/19 05:36:32 INFO : existing: Copied (new) 2020/01/19 05:36:32 INFO : Waiting for deletions to finish 2020/01/19 05:36:32 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2020/01/19 05:36:32 ERROR : existing: Source and destination exist but do not match: immutable file modified 2020/01/19 05:36:32 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for checks to finish 2020/01/19 05:36:32 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for transfers to finish 2020/01/19 05:36:32 ERROR : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': not deleting files as there were IO errors 2020/01/19 05:36:32 ERROR : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': not deleting directories as there were IO errors --- PASS: TestSyncImmutable (0.77s) run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5'", Local "Local file system at /tmp/rclone910142536", Modify Window "1ns" === RUN TestSyncIgnoreCase 2020/01/19 05:36:32 DEBUG : EXISTING.rclone_chunk.001_cfbod3: MD5 = 8ee2027983915ec78acc45027d874316 OK 2020/01/19 05:36:32 INFO : EXISTING.rclone_chunk.001_cfbod3: Copied (server side copy) 2020/01/19 05:36:32 INFO : EXISTING.rclone_chunk.001_cfbod3: Deleted 2020/01/19 05:36:33 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for checks to finish 2020/01/19 05:36:33 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:36:33 DEBUG : existing: Unchanged skipping 2020/01/19 05:36:33 INFO : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Waiting for transfers to finish 2020/01/19 05:36:33 INFO : Waiting for deletions to finish --- PASS: TestSyncIgnoreCase (0.47s) run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5'", Local "Local file system at /tmp/rclone910142536", Modify Window "1ns" === RUN TestAbort --- SKIP: TestAbort (0.02s) run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5'", Local "Local file system at /tmp/rclone910142536", Modify Window "1ns" sync_test.go:1741: This test only runs on local PASS 2020/01/19 05:36:33 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tapiyez7cubewab7lomotay5': Purge dir "" 2020/01/19 05:36:33 INFO : S3 bucket rclone-test-tapiyez7cubewab7lomotay5: Bucket "rclone-test-tapiyez7cubewab7lomotay5" deleted "./sync.test -test.v -test.timeout 30m0s -remote TestChunkerChunk50bSHA1QuickS3: -verbose -size-limit 1024" - Finished OK in 1m29.499512768s (try 1/5)