"./sync.test -test.v -test.timeout 30m0s -remote TestChunkerS3: -verbose" - 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:17:51 INFO : S3 bucket rclone-test-curozov7demelud8binesak5: Bucket "rclone-test-curozov7demelud8binesak5" created with ACL "private" 2020/01/19 05:17:51 NOTICE: sub dir/hello world: Not copying as --dry-run 2020/01/19 05:17:51 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for checks to finish 2020/01/19 05:17:51 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for transfers to finish --- PASS: TestCopyWithDryRun (0.73s) run.go:176: Remote "Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5'", Local "Local file system at /tmp/rclone669054014", Modify Window "1ns" === RUN TestCopy 2020/01/19 05:17:51 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for checks to finish 2020/01/19 05:17:51 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for transfers to finish 2020/01/19 05:17:51 DEBUG : sub dir/hello world.rclone_chunk.001_cegj9n: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/01/19 05:17:51 INFO : sub dir/hello world.rclone_chunk.001_cegj9n: Copied (server side copy) 2020/01/19 05:17:51 INFO : sub dir/hello world.rclone_chunk.001_cegj9n: Deleted 2020/01/19 05:17:51 DEBUG : sub dir/hello world: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/01/19 05:17:51 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopy (0.65s) run.go:176: Remote "Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5'", Local "Local file system at /tmp/rclone669054014", Modify Window "1ns" === RUN TestCopyMissingDirectory 2020/01/19 05:17:52 ERROR : : error reading source directory: directory not found 2020/01/19 05:17:52 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for checks to finish 2020/01/19 05:17:52 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for transfers to finish --- PASS: TestCopyMissingDirectory (0.04s) run.go:176: Remote "Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5'", Local "Local file system at /tmp/rclone669054014", Modify Window "1ns" === RUN TestCopyNoTraverse 2020/01/19 05:17:52 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for checks to finish 2020/01/19 05:17:52 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for transfers to finish 2020/01/19 05:17:52 DEBUG : sub dir/hello world.rclone_chunk.001_cegku1: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/01/19 05:17:52 INFO : sub dir/hello world.rclone_chunk.001_cegku1: Copied (server side copy) 2020/01/19 05:17:52 INFO : sub dir/hello world.rclone_chunk.001_cegku1: Deleted 2020/01/19 05:17:52 DEBUG : sub dir/hello world: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/01/19 05:17:52 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoTraverse (0.39s) run.go:176: Remote "Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5'", Local "Local file system at /tmp/rclone669054014", Modify Window "1ns" === RUN TestSyncNoTraverse 2020/01/19 05:17:52 ERROR : Ignoring --no-traverse with sync 2020/01/19 05:17:52 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for checks to finish 2020/01/19 05:17:52 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for transfers to finish 2020/01/19 05:17:52 DEBUG : sub dir/hello world.rclone_chunk.001_cegkdq: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/01/19 05:17:52 INFO : sub dir/hello world.rclone_chunk.001_cegkdq: Copied (server side copy) 2020/01/19 05:17:52 INFO : sub dir/hello world.rclone_chunk.001_cegkdq: Deleted 2020/01/19 05:17:52 DEBUG : sub dir/hello world: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/01/19 05:17:52 INFO : sub dir/hello world: Copied (new) 2020/01/19 05:17:52 INFO : Waiting for deletions to finish --- PASS: TestSyncNoTraverse (0.64s) run.go:176: Remote "Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5'", Local "Local file system at /tmp/rclone669054014", Modify Window "1ns" === RUN TestCopyWithDepth 2020/01/19 05:17:53 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for checks to finish 2020/01/19 05:17:53 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for transfers to finish 2020/01/19 05:17:53 DEBUG : hello world2.rclone_chunk.001_ceglww: MD5 = cc2c857f89648dbd139d7b2a6665957d OK 2020/01/19 05:17:53 INFO : hello world2.rclone_chunk.001_ceglww: Copied (server side copy) 2020/01/19 05:17:53 INFO : hello world2.rclone_chunk.001_ceglww: Deleted 2020/01/19 05:17:53 DEBUG : hello world2: MD5 = cc2c857f89648dbd139d7b2a6665957d OK 2020/01/19 05:17:53 INFO : hello world2: Copied (new) --- PASS: TestCopyWithDepth (0.64s) run.go:176: Remote "Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5'", Local "Local file system at /tmp/rclone669054014", Modify Window "1ns" === RUN TestCopyWithFilesFrom 2020/01/19 05:17:53 DEBUG : hello world2: Excluded 2020/01/19 05:17:54 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for checks to finish 2020/01/19 05:17:54 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for transfers to finish 2020/01/19 05:17:54 DEBUG : potato2.rclone_chunk.001_cegmq5: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/01/19 05:17:54 INFO : potato2.rclone_chunk.001_cegmq5: Copied (server side copy) 2020/01/19 05:17:54 INFO : potato2.rclone_chunk.001_cegmq5: Deleted 2020/01/19 05:17:54 DEBUG : potato2: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/01/19 05:17:54 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFrom (0.41s) run.go:176: Remote "Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5'", Local "Local file system at /tmp/rclone669054014", Modify Window "1ns" === RUN TestCopyWithFilesFromAndNoTraverse 2020/01/19 05:17:54 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for checks to finish 2020/01/19 05:17:54 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for transfers to finish 2020/01/19 05:17:54 DEBUG : potato2.rclone_chunk.001_cegmpr: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/01/19 05:17:54 INFO : potato2.rclone_chunk.001_cegmpr: Copied (server side copy) 2020/01/19 05:17:54 INFO : potato2.rclone_chunk.001_cegmpr: Deleted 2020/01/19 05:17:54 DEBUG : potato2: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/01/19 05:17:54 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFromAndNoTraverse (0.41s) run.go:176: Remote "Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5'", Local "Local file system at /tmp/rclone669054014", Modify Window "1ns" === RUN TestCopyEmptyDirectories 2020/01/19 05:17:54 DEBUG : sub dir2: Making directory 2020/01/19 05:17:54 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for checks to finish 2020/01/19 05:17:54 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for transfers to finish 2020/01/19 05:17:54 DEBUG : sub dir/hello world.rclone_chunk.001_cegm8r: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/01/19 05:17:54 INFO : sub dir/hello world.rclone_chunk.001_cegm8r: Copied (server side copy) 2020/01/19 05:17:54 INFO : sub dir/hello world.rclone_chunk.001_cegm8r: Deleted 2020/01/19 05:17:54 DEBUG : sub dir/hello world: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/01/19 05:17:54 INFO : sub dir/hello world: Copied (new) 2020/01/19 05:17:54 DEBUG : sub dir2: Making directory 2020/01/19 05:17:54 DEBUG : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': copied 1 directories --- PASS: TestCopyEmptyDirectories (0.23s) run.go:176: Remote "Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5'", Local "Local file system at /tmp/rclone669054014", Modify Window "1ns" fstest.go:241: Filtering empty directory "sub dir2" === RUN TestMoveEmptyDirectories 2020/01/19 05:17:55 DEBUG : sub dir2: Making directory 2020/01/19 05:17:55 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for checks to finish 2020/01/19 05:17:55 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for transfers to finish 2020/01/19 05:17:55 DEBUG : sub dir/hello world.rclone_chunk.001_cegn74: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/01/19 05:17:55 INFO : sub dir/hello world.rclone_chunk.001_cegn74: Copied (server side copy) 2020/01/19 05:17:55 INFO : sub dir/hello world.rclone_chunk.001_cegn74: Deleted 2020/01/19 05:17:55 DEBUG : sub dir/hello world: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/01/19 05:17:55 INFO : sub dir/hello world: Copied (new) 2020/01/19 05:17:55 INFO : sub dir/hello world: Deleted 2020/01/19 05:17:55 DEBUG : sub dir: Making directory 2020/01/19 05:17:55 DEBUG : sub dir2: Making directory 2020/01/19 05:17:55 DEBUG : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': copied 2 directories --- PASS: TestMoveEmptyDirectories (0.42s) run.go:176: Remote "Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5'", Local "Local file system at /tmp/rclone669054014", Modify Window "1ns" fstest.go:241: Filtering empty directory "sub dir2" === RUN TestSyncEmptyDirectories 2020/01/19 05:17:55 DEBUG : sub dir2: Making directory 2020/01/19 05:17:55 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for checks to finish 2020/01/19 05:17:55 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for transfers to finish 2020/01/19 05:17:55 DEBUG : sub dir/hello world.rclone_chunk.001_cegnsz: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/01/19 05:17:55 INFO : sub dir/hello world.rclone_chunk.001_cegnsz: Copied (server side copy) 2020/01/19 05:17:55 INFO : sub dir/hello world.rclone_chunk.001_cegnsz: Deleted 2020/01/19 05:17:55 DEBUG : sub dir/hello world: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/01/19 05:17:55 INFO : sub dir/hello world: Copied (new) 2020/01/19 05:17:55 DEBUG : sub dir2: Making directory 2020/01/19 05:17:55 DEBUG : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': copied 1 directories 2020/01/19 05:17:55 INFO : Waiting for deletions to finish --- PASS: TestSyncEmptyDirectories (0.41s) run.go:176: Remote "Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5'", Local "Local file system at /tmp/rclone669054014", Modify Window "1ns" fstest.go:241: Filtering empty directory "sub dir2" === RUN TestServerSideCopy 2020/01/19 05:17:55 DEBUG : sub dir/hello world.rclone_chunk.001_cegn6f: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/01/19 05:17:55 INFO : sub dir/hello world.rclone_chunk.001_cegn6f: Copied (server side copy) 2020/01/19 05:17:55 INFO : sub dir/hello world.rclone_chunk.001_cegn6f: Deleted 2020/01/19 05:17:57 INFO : Chunked 'TestChunkerS3:rclone-test-xugefok2zadiroy6vicevak7': Waiting for checks to finish 2020/01/19 05:17:57 DEBUG : sub dir/hello world: copy non-chunked object... 2020/01/19 05:17:57 INFO : Chunked 'TestChunkerS3:rclone-test-xugefok2zadiroy6vicevak7': Waiting for transfers to finish 2020/01/19 05:17:57 INFO : S3 bucket rclone-test-xugefok2zadiroy6vicevak7: Bucket "rclone-test-xugefok2zadiroy6vicevak7" created with ACL "private" 2020/01/19 05:17:57 DEBUG : sub dir/hello world: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/01/19 05:17:57 INFO : sub dir/hello world: Copied (server side copy) 2020/01/19 05:17:58 DEBUG : Chunked 'TestChunkerS3:rclone-test-xugefok2zadiroy6vicevak7': Purge object "sub dir/hello world" 2020/01/19 05:17:58 DEBUG : Chunked 'TestChunkerS3:rclone-test-xugefok2zadiroy6vicevak7': Purge dir "sub dir" 2020/01/19 05:17:58 DEBUG : Chunked 'TestChunkerS3:rclone-test-xugefok2zadiroy6vicevak7': Purge dir "" 2020/01/19 05:17:58 INFO : S3 bucket rclone-test-xugefok2zadiroy6vicevak7: Bucket "rclone-test-xugefok2zadiroy6vicevak7" deleted --- PASS: TestServerSideCopy (2.86s) run.go:176: Remote "Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5'", Local "Local file system at /tmp/rclone669054014", Modify Window "1ns" sync_test.go:257: Server side copy (if possible) Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5' -> Chunked 'TestChunkerS3:rclone-test-xugefok2zadiroy6vicevak7' === RUN TestCopyAfterDelete 2020/01/19 05:17:58 DEBUG : sub dir/hello world.rclone_chunk.001_cegqmy: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/01/19 05:17:58 INFO : sub dir/hello world.rclone_chunk.001_cegqmy: Copied (server side copy) 2020/01/19 05:17:58 INFO : sub dir/hello world.rclone_chunk.001_cegqmy: Deleted 2020/01/19 05:17:58 ERROR : : error listing: directory not found 2020/01/19 05:17:58 DEBUG : Local file system at /tmp/rclone669054014: Making directory 2020/01/19 05:17:59 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for checks to finish 2020/01/19 05:17:59 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for transfers to finish --- PASS: TestCopyAfterDelete (0.65s) run.go:176: Remote "Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5'", Local "Local file system at /tmp/rclone669054014", Modify Window "1ns" === RUN TestCopyRedownload 2020/01/19 05:17:59 DEBUG : sub dir/hello world.rclone_chunk.001_cegr12: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/01/19 05:17:59 INFO : sub dir/hello world.rclone_chunk.001_cegr12: Copied (server side copy) 2020/01/19 05:17:59 INFO : sub dir/hello world.rclone_chunk.001_cegr12: Deleted 2020/01/19 05:17:59 INFO : Local file system at /tmp/rclone669054014: Waiting for checks to finish 2020/01/19 05:17:59 INFO : Local file system at /tmp/rclone669054014: Waiting for transfers to finish 2020/01/19 05:17:59 DEBUG : sub dir/hello world: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/01/19 05:17:59 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyRedownload (0.43s) run.go:176: Remote "Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5'", Local "Local file system at /tmp/rclone669054014", Modify Window "1ns" === RUN TestSyncBasedOnCheckSum 2020/01/19 05:18:00 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for checks to finish 2020/01/19 05:18:00 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for transfers to finish 2020/01/19 05:18:00 DEBUG : check sum.rclone_chunk.001_cegsh9: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/01/19 05:18:00 INFO : check sum.rclone_chunk.001_cegsh9: Copied (server side copy) 2020/01/19 05:18:00 INFO : check sum.rclone_chunk.001_cegsh9: Deleted 2020/01/19 05:18:00 DEBUG : check sum: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/01/19 05:18:00 INFO : check sum: Copied (new) 2020/01/19 05:18:00 INFO : Waiting for deletions to finish 2020/01/19 05:18:00 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for checks to finish 2020/01/19 05:18:00 DEBUG : check sum: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/01/19 05:18:00 DEBUG : check sum: Size and MD5 of src and dst objects identical 2020/01/19 05:18:00 DEBUG : check sum: Unchanged skipping 2020/01/19 05:18:00 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for transfers to finish 2020/01/19 05:18:00 INFO : Waiting for deletions to finish --- PASS: TestSyncBasedOnCheckSum (0.47s) run.go:176: Remote "Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5'", Local "Local file system at /tmp/rclone669054014", Modify Window "1ns" === RUN TestSyncSizeOnly 2020/01/19 05:18:00 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for checks to finish 2020/01/19 05:18:00 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for transfers to finish 2020/01/19 05:18:00 DEBUG : sizeonly.rclone_chunk.001_cegsxh: MD5 = 8ee2027983915ec78acc45027d874316 OK 2020/01/19 05:18:00 INFO : sizeonly.rclone_chunk.001_cegsxh: Copied (server side copy) 2020/01/19 05:18:00 INFO : sizeonly.rclone_chunk.001_cegsxh: Deleted 2020/01/19 05:18:00 DEBUG : sizeonly: MD5 = 8ee2027983915ec78acc45027d874316 OK 2020/01/19 05:18:00 INFO : sizeonly: Copied (new) 2020/01/19 05:18:00 INFO : Waiting for deletions to finish 2020/01/19 05:18:00 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for checks to finish 2020/01/19 05:18:00 DEBUG : sizeonly: Sizes identical 2020/01/19 05:18:00 DEBUG : sizeonly: Unchanged skipping 2020/01/19 05:18:00 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for transfers to finish 2020/01/19 05:18:00 INFO : Waiting for deletions to finish --- PASS: TestSyncSizeOnly (0.51s) run.go:176: Remote "Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5'", Local "Local file system at /tmp/rclone669054014", Modify Window "1ns" === RUN TestSyncIgnoreSize 2020/01/19 05:18:00 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for checks to finish 2020/01/19 05:18:00 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for transfers to finish 2020/01/19 05:18:00 DEBUG : ignore-size.rclone_chunk.001_cegsu8: MD5 = 98bf7d8c15784f0a3d63204441e1e2aa OK 2020/01/19 05:18:00 INFO : ignore-size.rclone_chunk.001_cegsu8: Copied (server side copy) 2020/01/19 05:18:00 INFO : ignore-size.rclone_chunk.001_cegsu8: Deleted 2020/01/19 05:18:00 DEBUG : ignore-size: MD5 = 98bf7d8c15784f0a3d63204441e1e2aa OK 2020/01/19 05:18:00 INFO : ignore-size: Copied (new) 2020/01/19 05:18:00 INFO : Waiting for deletions to finish 2020/01/19 05:18:01 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for checks to finish 2020/01/19 05:18:01 DEBUG : ignore-size: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:18:01 DEBUG : ignore-size: Unchanged skipping 2020/01/19 05:18:01 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for transfers to finish 2020/01/19 05:18:01 INFO : Waiting for deletions to finish --- PASS: TestSyncIgnoreSize (0.90s) run.go:176: Remote "Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5'", Local "Local file system at /tmp/rclone669054014", Modify Window "1ns" === RUN TestSyncIgnoreTimes 2020/01/19 05:18:01 DEBUG : existing.rclone_chunk.001_cegtsf: MD5 = 8ee2027983915ec78acc45027d874316 OK 2020/01/19 05:18:01 INFO : existing.rclone_chunk.001_cegtsf: Copied (server side copy) 2020/01/19 05:18:01 INFO : existing.rclone_chunk.001_cegtsf: Deleted 2020/01/19 05:18:02 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for checks to finish 2020/01/19 05:18:02 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:18:02 DEBUG : existing: Unchanged skipping 2020/01/19 05:18:02 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for transfers to finish 2020/01/19 05:18:02 INFO : Waiting for deletions to finish 2020/01/19 05:18:02 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2020/01/19 05:18:02 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for checks to finish 2020/01/19 05:18:02 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for transfers to finish 2020/01/19 05:18:02 DEBUG : existing.rclone_chunk.001_ceguvw: MD5 = 8ee2027983915ec78acc45027d874316 OK 2020/01/19 05:18:02 INFO : existing.rclone_chunk.001_ceguvw: Copied (server side copy) 2020/01/19 05:18:02 INFO : existing.rclone_chunk.001_ceguvw: Deleted 2020/01/19 05:18:02 DEBUG : existing: MD5 = 8ee2027983915ec78acc45027d874316 OK 2020/01/19 05:18:02 INFO : existing: Copied (replaced existing) 2020/01/19 05:18:02 INFO : Waiting for deletions to finish --- PASS: TestSyncIgnoreTimes (0.63s) run.go:176: Remote "Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5'", Local "Local file system at /tmp/rclone669054014", Modify Window "1ns" === RUN TestSyncIgnoreExisting 2020/01/19 05:18:02 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for checks to finish 2020/01/19 05:18:02 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for transfers to finish 2020/01/19 05:18:02 DEBUG : existing.rclone_chunk.001_cegunj: MD5 = 8ee2027983915ec78acc45027d874316 OK 2020/01/19 05:18:02 INFO : existing.rclone_chunk.001_cegunj: Copied (server side copy) 2020/01/19 05:18:02 INFO : existing.rclone_chunk.001_cegunj: Deleted 2020/01/19 05:18:02 DEBUG : existing: MD5 = 8ee2027983915ec78acc45027d874316 OK 2020/01/19 05:18:02 INFO : existing: Copied (new) 2020/01/19 05:18:02 INFO : Waiting for deletions to finish 2020/01/19 05:18:02 DEBUG : existing: Destination exists, skipping 2020/01/19 05:18:02 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for checks to finish 2020/01/19 05:18:02 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for transfers to finish 2020/01/19 05:18:02 INFO : Waiting for deletions to finish --- PASS: TestSyncIgnoreExisting (0.71s) run.go:176: Remote "Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5'", Local "Local file system at /tmp/rclone669054014", Modify Window "1ns" === RUN TestSyncIgnoreErrors 2020/01/19 05:18:03 DEBUG : b/potato.rclone_chunk.001_cegvez: MD5 = e4cb6955d9106df6263c45fcfc10f163 OK 2020/01/19 05:18:03 INFO : b/potato.rclone_chunk.001_cegvez: Copied (server side copy) 2020/01/19 05:18:03 INFO : b/potato.rclone_chunk.001_cegvez: Deleted 2020/01/19 05:18:03 DEBUG : c/non empty space.rclone_chunk.001_cegv06: MD5 = ec26039bacb958460a511485fc59d24e OK 2020/01/19 05:18:03 INFO : c/non empty space.rclone_chunk.001_cegv06: Copied (server side copy) 2020/01/19 05:18:03 INFO : c/non empty space.rclone_chunk.001_cegv06: Deleted 2020/01/19 05:18:03 DEBUG : d: Making directory 2020/01/19 05:18:03 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for checks to finish 2020/01/19 05:18:03 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:18:03 DEBUG : c/non empty space: Unchanged skipping 2020/01/19 05:18:03 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for transfers to finish 2020/01/19 05:18:03 DEBUG : a/potato2.rclone_chunk.001_cegvcd: MD5 = d6548b156ea68a4e003e786df99eee76 OK 2020/01/19 05:18:03 INFO : a/potato2.rclone_chunk.001_cegvcd: Copied (server side copy) 2020/01/19 05:18:03 INFO : a/potato2.rclone_chunk.001_cegvcd: Deleted 2020/01/19 05:18:03 DEBUG : a/potato2: MD5 = d6548b156ea68a4e003e786df99eee76 OK 2020/01/19 05:18:03 INFO : a/potato2: Copied (new) 2020/01/19 05:18:03 INFO : Waiting for deletions to finish 2020/01/19 05:18:03 INFO : b/potato: Deleted --- PASS: TestSyncIgnoreErrors (1.03s) run.go:176: Remote "Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5'", Local "Local file system at /tmp/rclone669054014", Modify Window "1ns" fstest.go:241: Filtering empty directory "d" === RUN TestSyncAfterChangingModtimeOnly 2020/01/19 05:18:04 DEBUG : empty space.rclone_chunk.001_cegwyl: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/01/19 05:18:04 INFO : empty space.rclone_chunk.001_cegwyl: Copied (server side copy) 2020/01/19 05:18:04 INFO : empty space.rclone_chunk.001_cegwyl: Deleted 2020/01/19 05:18:04 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for checks to finish 2020/01/19 05:18:04 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:18:04 DEBUG : empty space: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/01/19 05:18:04 NOTICE: empty space: Not updating modification time as --dry-run 2020/01/19 05:18:04 DEBUG : empty space: Unchanged skipping 2020/01/19 05:18:04 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for transfers to finish 2020/01/19 05:18:04 INFO : Waiting for deletions to finish 2020/01/19 05:18:04 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for checks to finish 2020/01/19 05:18:04 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:18:04 DEBUG : empty space: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/01/19 05:18:04 INFO : empty space: Updated modification time in destination 2020/01/19 05:18:04 DEBUG : empty space: Unchanged skipping 2020/01/19 05:18:04 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for transfers to finish 2020/01/19 05:18:04 INFO : Waiting for deletions to finish --- PASS: TestSyncAfterChangingModtimeOnly (1.00s) run.go:176: Remote "Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5'", Local "Local file system at /tmp/rclone669054014", Modify Window "1ns" === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime 2020/01/19 05:18:05 DEBUG : empty space.rclone_chunk.001_cegxen: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/01/19 05:18:05 INFO : empty space.rclone_chunk.001_cegxen: Copied (server side copy) 2020/01/19 05:18:05 INFO : empty space.rclone_chunk.001_cegxen: Deleted 2020/01/19 05:18:05 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for checks to finish 2020/01/19 05:18:05 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:18:05 DEBUG : empty space: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/01/19 05:18:05 DEBUG : empty space: Unchanged skipping 2020/01/19 05:18:05 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for transfers to finish 2020/01/19 05:18:05 INFO : Waiting for deletions to finish --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (0.49s) run.go:176: Remote "Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5'", Local "Local file system at /tmp/rclone669054014", Modify Window "1ns" === RUN TestSyncDoesntUpdateModtime 2020/01/19 05:18:05 DEBUG : foo.rclone_chunk.001_cegxzu: MD5 = 37b51d194a7513e45b56f6524f2d51f2 OK 2020/01/19 05:18:05 INFO : foo.rclone_chunk.001_cegxzu: Copied (server side copy) 2020/01/19 05:18:05 INFO : foo.rclone_chunk.001_cegxzu: Deleted 2020/01/19 05:18:05 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for checks to finish 2020/01/19 05:18:05 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:18:05 DEBUG : foo: MD5 = acbd18db4cc2f85cedef654fccc4a4d8 (Local file system at /tmp/rclone669054014) 2020/01/19 05:18:05 DEBUG : foo: MD5 = 37b51d194a7513e45b56f6524f2d51f2 (Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5') 2020/01/19 05:18:05 DEBUG : foo: MD5 differ 2020/01/19 05:18:05 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for transfers to finish 2020/01/19 05:18:05 DEBUG : foo.rclone_chunk.001_cegxpq: MD5 = acbd18db4cc2f85cedef654fccc4a4d8 OK 2020/01/19 05:18:05 INFO : foo.rclone_chunk.001_cegxpq: Copied (server side copy) 2020/01/19 05:18:05 INFO : foo.rclone_chunk.001_cegxpq: Deleted 2020/01/19 05:18:05 DEBUG : foo: MD5 = acbd18db4cc2f85cedef654fccc4a4d8 OK 2020/01/19 05:18:05 INFO : foo: Copied (replaced existing) 2020/01/19 05:18:05 INFO : Waiting for deletions to finish --- PASS: TestSyncDoesntUpdateModtime (0.63s) run.go:176: Remote "Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5'", Local "Local file system at /tmp/rclone669054014", Modify Window "1ns" === RUN TestSyncAfterAddingAFile 2020/01/19 05:18:06 DEBUG : empty space.rclone_chunk.001_cegyll: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/01/19 05:18:06 INFO : empty space.rclone_chunk.001_cegyll: Copied (server side copy) 2020/01/19 05:18:06 INFO : empty space.rclone_chunk.001_cegyll: Deleted 2020/01/19 05:18:06 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for checks to finish 2020/01/19 05:18:06 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:18:06 DEBUG : empty space: Unchanged skipping 2020/01/19 05:18:06 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for transfers to finish 2020/01/19 05:18:06 DEBUG : potato.rclone_chunk.001_cegynp: MD5 = d6548b156ea68a4e003e786df99eee76 OK 2020/01/19 05:18:06 INFO : potato.rclone_chunk.001_cegynp: Copied (server side copy) 2020/01/19 05:18:06 INFO : potato.rclone_chunk.001_cegynp: Deleted 2020/01/19 05:18:06 DEBUG : potato: MD5 = d6548b156ea68a4e003e786df99eee76 OK 2020/01/19 05:18:06 INFO : potato: Copied (new) 2020/01/19 05:18:06 INFO : Waiting for deletions to finish --- PASS: TestSyncAfterAddingAFile (0.39s) run.go:176: Remote "Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5'", Local "Local file system at /tmp/rclone669054014", Modify Window "1ns" === RUN TestSyncAfterChangingFilesSizeOnly 2020/01/19 05:18:06 DEBUG : potato.rclone_chunk.001_cegyym: MD5 = d6548b156ea68a4e003e786df99eee76 OK 2020/01/19 05:18:06 INFO : potato.rclone_chunk.001_cegyym: Copied (server side copy) 2020/01/19 05:18:06 INFO : potato.rclone_chunk.001_cegyym: Deleted 2020/01/19 05:18:06 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2020/01/19 05:18:06 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for checks to finish 2020/01/19 05:18:06 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for transfers to finish 2020/01/19 05:18:07 DEBUG : potato.rclone_chunk.001_cegy0b: MD5 = 100defcf18c42a1e0dc42a789b107cd2 OK 2020/01/19 05:18:07 INFO : potato.rclone_chunk.001_cegy0b: Copied (server side copy) 2020/01/19 05:18:07 INFO : potato.rclone_chunk.001_cegy0b: Deleted 2020/01/19 05:18:07 DEBUG : potato: MD5 = 100defcf18c42a1e0dc42a789b107cd2 OK 2020/01/19 05:18:07 INFO : potato: Copied (replaced existing) 2020/01/19 05:18:07 INFO : Waiting for deletions to finish --- PASS: TestSyncAfterChangingFilesSizeOnly (0.87s) run.go:176: Remote "Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5'", Local "Local file system at /tmp/rclone669054014", Modify Window "1ns" === RUN TestSyncAfterChangingContentsOnly 2020/01/19 05:18:07 DEBUG : potato.rclone_chunk.001_cegzd8: MD5 = 100defcf18c42a1e0dc42a789b107cd2 OK 2020/01/19 05:18:07 INFO : potato.rclone_chunk.001_cegzd8: Copied (server side copy) 2020/01/19 05:18:07 INFO : potato.rclone_chunk.001_cegzd8: Deleted 2020/01/19 05:18:07 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for checks to finish 2020/01/19 05:18:07 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:18:07 DEBUG : potato: MD5 = e4cb6955d9106df6263c45fcfc10f163 (Local file system at /tmp/rclone669054014) 2020/01/19 05:18:07 DEBUG : potato: MD5 = 100defcf18c42a1e0dc42a789b107cd2 (Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5') 2020/01/19 05:18:07 DEBUG : potato: MD5 differ 2020/01/19 05:18:07 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for transfers to finish 2020/01/19 05:18:07 DEBUG : potato.rclone_chunk.001_cegz5i: MD5 = e4cb6955d9106df6263c45fcfc10f163 OK 2020/01/19 05:18:07 INFO : potato.rclone_chunk.001_cegz5i: Copied (server side copy) 2020/01/19 05:18:07 INFO : potato.rclone_chunk.001_cegz5i: Deleted 2020/01/19 05:18:07 DEBUG : potato: MD5 = e4cb6955d9106df6263c45fcfc10f163 OK 2020/01/19 05:18:07 INFO : potato: Copied (replaced existing) 2020/01/19 05:18:07 INFO : Waiting for deletions to finish --- PASS: TestSyncAfterChangingContentsOnly (0.39s) run.go:176: Remote "Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5'", Local "Local file system at /tmp/rclone669054014", Modify Window "1ns" === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun 2020/01/19 05:18:07 DEBUG : potato.rclone_chunk.001_cegz07: MD5 = e4cb6955d9106df6263c45fcfc10f163 OK 2020/01/19 05:18:07 INFO : potato.rclone_chunk.001_cegz07: Copied (server side copy) 2020/01/19 05:18:07 INFO : potato.rclone_chunk.001_cegz07: Deleted 2020/01/19 05:18:08 DEBUG : empty space.rclone_chunk.001_cegzsr: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/01/19 05:18:08 INFO : empty space.rclone_chunk.001_cegzsr: Copied (server side copy) 2020/01/19 05:18:08 INFO : empty space.rclone_chunk.001_cegzsr: Deleted 2020/01/19 05:18:08 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for checks to finish 2020/01/19 05:18:08 NOTICE: potato2: Not copying as --dry-run 2020/01/19 05:18:08 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:18:08 DEBUG : empty space: Unchanged skipping 2020/01/19 05:18:08 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for transfers to finish 2020/01/19 05:18:08 INFO : Waiting for deletions to finish 2020/01/19 05:18:08 NOTICE: potato: Not deleting as --dry-run --- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (0.80s) run.go:176: Remote "Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5'", Local "Local file system at /tmp/rclone669054014", Modify Window "1ns" === RUN TestSyncAfterRemovingAFileAndAddingAFile 2020/01/19 05:18:08 DEBUG : potato.rclone_chunk.001_ceh0sv: MD5 = e4cb6955d9106df6263c45fcfc10f163 OK 2020/01/19 05:18:08 INFO : potato.rclone_chunk.001_ceh0sv: Copied (server side copy) 2020/01/19 05:18:08 INFO : potato.rclone_chunk.001_ceh0sv: Deleted 2020/01/19 05:18:08 DEBUG : empty space.rclone_chunk.001_ceh00e: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/01/19 05:18:08 INFO : empty space.rclone_chunk.001_ceh00e: Copied (server side copy) 2020/01/19 05:18:08 INFO : empty space.rclone_chunk.001_ceh00e: Deleted 2020/01/19 05:18:09 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for checks to finish 2020/01/19 05:18:09 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:18:09 DEBUG : empty space: Unchanged skipping 2020/01/19 05:18:09 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for transfers to finish 2020/01/19 05:18:09 DEBUG : potato2.rclone_chunk.001_ceh1m7: MD5 = d6548b156ea68a4e003e786df99eee76 OK 2020/01/19 05:18:09 INFO : potato2.rclone_chunk.001_ceh1m7: Copied (server side copy) 2020/01/19 05:18:09 INFO : potato2.rclone_chunk.001_ceh1m7: Deleted 2020/01/19 05:18:09 DEBUG : potato2: MD5 = d6548b156ea68a4e003e786df99eee76 OK 2020/01/19 05:18:09 INFO : potato2: Copied (new) 2020/01/19 05:18:09 INFO : Waiting for deletions to finish 2020/01/19 05:18:09 INFO : potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (1.45s) run.go:176: Remote "Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5'", Local "Local file system at /tmp/rclone669054014", Modify Window "1ns" === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir 2020/01/19 05:18:10 DEBUG : b/potato.rclone_chunk.001_ceh2vv: MD5 = e4cb6955d9106df6263c45fcfc10f163 OK 2020/01/19 05:18:10 INFO : b/potato.rclone_chunk.001_ceh2vv: Copied (server side copy) 2020/01/19 05:18:10 INFO : b/potato.rclone_chunk.001_ceh2vv: Deleted 2020/01/19 05:18:10 DEBUG : c/non empty space.rclone_chunk.001_ceh2g1: MD5 = ec26039bacb958460a511485fc59d24e OK 2020/01/19 05:18:10 INFO : c/non empty space.rclone_chunk.001_ceh2g1: Copied (server side copy) 2020/01/19 05:18:10 INFO : c/non empty space.rclone_chunk.001_ceh2g1: Deleted 2020/01/19 05:18:10 DEBUG : d: Making directory 2020/01/19 05:18:10 DEBUG : d/e: Making directory 2020/01/19 05:18:10 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for checks to finish 2020/01/19 05:18:10 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:18:10 DEBUG : c/non empty space: Unchanged skipping 2020/01/19 05:18:10 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for transfers to finish 2020/01/19 05:18:10 DEBUG : a/potato2.rclone_chunk.001_ceh279: MD5 = d6548b156ea68a4e003e786df99eee76 OK 2020/01/19 05:18:10 INFO : a/potato2.rclone_chunk.001_ceh279: Copied (server side copy) 2020/01/19 05:18:10 INFO : a/potato2.rclone_chunk.001_ceh279: Deleted 2020/01/19 05:18:10 DEBUG : a/potato2: MD5 = d6548b156ea68a4e003e786df99eee76 OK 2020/01/19 05:18:10 INFO : a/potato2: Copied (new) 2020/01/19 05:18:10 INFO : Waiting for deletions to finish 2020/01/19 05:18:10 INFO : b/potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (0.81s) run.go:176: Remote "Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5'", Local "Local file system at /tmp/rclone669054014", Modify Window "1ns" fstest.go:241: Filtering empty directory "d" fstest.go:241: Filtering empty directory "d/e" === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors 2020/01/19 05:18:10 DEBUG : b/potato.rclone_chunk.001_ceh2ov: MD5 = e4cb6955d9106df6263c45fcfc10f163 OK 2020/01/19 05:18:10 INFO : b/potato.rclone_chunk.001_ceh2ov: Copied (server side copy) 2020/01/19 05:18:10 INFO : b/potato.rclone_chunk.001_ceh2ov: Deleted 2020/01/19 05:18:11 DEBUG : c/non empty space.rclone_chunk.001_ceh2l2: MD5 = ec26039bacb958460a511485fc59d24e OK 2020/01/19 05:18:11 INFO : c/non empty space.rclone_chunk.001_ceh2l2: Copied (server side copy) 2020/01/19 05:18:11 INFO : c/non empty space.rclone_chunk.001_ceh2l2: Deleted 2020/01/19 05:18:11 DEBUG : d: Making directory 2020/01/19 05:18:12 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for checks to finish 2020/01/19 05:18:12 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:18:12 DEBUG : c/non empty space: Unchanged skipping 2020/01/19 05:18:12 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for transfers to finish 2020/01/19 05:18:12 DEBUG : a/potato2.rclone_chunk.001_ceh4fn: MD5 = d6548b156ea68a4e003e786df99eee76 OK 2020/01/19 05:18:12 INFO : a/potato2.rclone_chunk.001_ceh4fn: Copied (server side copy) 2020/01/19 05:18:12 INFO : a/potato2.rclone_chunk.001_ceh4fn: Deleted 2020/01/19 05:18:12 DEBUG : a/potato2: MD5 = d6548b156ea68a4e003e786df99eee76 OK 2020/01/19 05:18:12 INFO : a/potato2: Copied (new) 2020/01/19 05:18:12 ERROR : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': not deleting files as there were IO errors 2020/01/19 05:18:12 ERROR : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': not deleting directories as there were IO errors --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (1.40s) run.go:176: Remote "Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5'", Local "Local file system at /tmp/rclone669054014", 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:18:12 DEBUG : potato.rclone_chunk.001_ceh4z9: MD5 = e4cb6955d9106df6263c45fcfc10f163 OK 2020/01/19 05:18:12 INFO : potato.rclone_chunk.001_ceh4z9: Copied (server side copy) 2020/01/19 05:18:12 INFO : potato.rclone_chunk.001_ceh4z9: Deleted 2020/01/19 05:18:12 DEBUG : empty space.rclone_chunk.001_ceh4oh: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/01/19 05:18:12 INFO : empty space.rclone_chunk.001_ceh4oh: Copied (server side copy) 2020/01/19 05:18:12 INFO : empty space.rclone_chunk.001_ceh4oh: Deleted 2020/01/19 05:18:12 INFO : Waiting for deletions to finish 2020/01/19 05:18:12 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for checks to finish 2020/01/19 05:18:12 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:18:12 DEBUG : empty space: Unchanged skipping 2020/01/19 05:18:12 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for transfers to finish 2020/01/19 05:18:12 INFO : potato: Deleted 2020/01/19 05:18:12 DEBUG : potato2.rclone_chunk.001_ceh4s5: MD5 = d6548b156ea68a4e003e786df99eee76 OK 2020/01/19 05:18:12 INFO : potato2.rclone_chunk.001_ceh4s5: Copied (server side copy) 2020/01/19 05:18:12 INFO : potato2.rclone_chunk.001_ceh4s5: Deleted 2020/01/19 05:18:12 DEBUG : potato2: MD5 = d6548b156ea68a4e003e786df99eee76 OK 2020/01/19 05:18:12 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteDuring (0.48s) run.go:176: Remote "Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5'", Local "Local file system at /tmp/rclone669054014", Modify Window "1ns" === RUN TestSyncDeleteBefore 2020/01/19 05:18:12 DEBUG : potato.rclone_chunk.001_ceh4m5: MD5 = e4cb6955d9106df6263c45fcfc10f163 OK 2020/01/19 05:18:12 INFO : potato.rclone_chunk.001_ceh4m5: Copied (server side copy) 2020/01/19 05:18:12 INFO : potato.rclone_chunk.001_ceh4m5: Deleted 2020/01/19 05:18:13 DEBUG : empty space.rclone_chunk.001_ceh4un: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/01/19 05:18:13 INFO : empty space.rclone_chunk.001_ceh4un: Copied (server side copy) 2020/01/19 05:18:13 INFO : empty space.rclone_chunk.001_ceh4un: Deleted 2020/01/19 05:18:13 INFO : Waiting for deletions to finish 2020/01/19 05:18:13 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for checks to finish 2020/01/19 05:18:13 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for transfers to finish 2020/01/19 05:18:13 INFO : potato: Deleted 2020/01/19 05:18:13 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for checks to finish 2020/01/19 05:18:13 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:18:13 DEBUG : empty space: Unchanged skipping 2020/01/19 05:18:13 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for transfers to finish 2020/01/19 05:18:13 DEBUG : potato2.rclone_chunk.001_ceh5ar: MD5 = d6548b156ea68a4e003e786df99eee76 OK 2020/01/19 05:18:13 INFO : potato2.rclone_chunk.001_ceh5ar: Copied (server side copy) 2020/01/19 05:18:13 INFO : potato2.rclone_chunk.001_ceh5ar: Deleted 2020/01/19 05:18:13 DEBUG : potato2: MD5 = d6548b156ea68a4e003e786df99eee76 OK 2020/01/19 05:18:13 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteBefore (1.66s) run.go:176: Remote "Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5'", Local "Local file system at /tmp/rclone669054014", Modify Window "1ns" === RUN TestCopyDeleteBefore 2020/01/19 05:18:14 DEBUG : potato.rclone_chunk.001_ceh6lk: MD5 = a77cf635f7af90988de91597e3a4519b OK 2020/01/19 05:18:14 INFO : potato.rclone_chunk.001_ceh6lk: Copied (server side copy) 2020/01/19 05:18:14 INFO : potato.rclone_chunk.001_ceh6lk: Deleted 2020/01/19 05:18:14 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for checks to finish 2020/01/19 05:18:14 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for transfers to finish 2020/01/19 05:18:14 DEBUG : potato2.rclone_chunk.001_ceh68i: MD5 = 9519bc2e940e1696ea7365b4b81adedc OK 2020/01/19 05:18:14 INFO : potato2.rclone_chunk.001_ceh68i: Copied (server side copy) 2020/01/19 05:18:14 INFO : potato2.rclone_chunk.001_ceh68i: Deleted 2020/01/19 05:18:14 DEBUG : potato2: MD5 = 9519bc2e940e1696ea7365b4b81adedc OK 2020/01/19 05:18:14 INFO : potato2: Copied (new) --- PASS: TestCopyDeleteBefore (1.26s) run.go:176: Remote "Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5'", Local "Local file system at /tmp/rclone669054014", Modify Window "1ns" === RUN TestSyncWithExclude 2020/01/19 05:18:15 DEBUG : potato2.rclone_chunk.001_ceh76s: MD5 = d6548b156ea68a4e003e786df99eee76 OK 2020/01/19 05:18:15 INFO : potato2.rclone_chunk.001_ceh76s: Copied (server side copy) 2020/01/19 05:18:15 INFO : potato2.rclone_chunk.001_ceh76s: Deleted 2020/01/19 05:18:15 DEBUG : empty space.rclone_chunk.001_ceh7bj: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/01/19 05:18:15 INFO : empty space.rclone_chunk.001_ceh7bj: Copied (server side copy) 2020/01/19 05:18:15 INFO : empty space.rclone_chunk.001_ceh7bj: Deleted 2020/01/19 05:18:15 DEBUG : potato2: Excluded 2020/01/19 05:18:15 DEBUG : enormous: Excluded 2020/01/19 05:18:15 DEBUG : potato2: Excluded 2020/01/19 05:18:15 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for checks to finish 2020/01/19 05:18:16 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:18:16 DEBUG : empty space: Unchanged skipping 2020/01/19 05:18:16 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for transfers to finish 2020/01/19 05:18:16 INFO : Waiting for deletions to finish 2020/01/19 05:18:16 DEBUG : potato2: Excluded 2020/01/19 05:18:16 DEBUG : enormous: Excluded 2020/01/19 05:18:16 DEBUG : potato2: Excluded 2020/01/19 05:18:16 INFO : Local file system at /tmp/rclone669054014: Waiting for checks to finish 2020/01/19 05:18:16 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:18:16 DEBUG : empty space: Unchanged skipping 2020/01/19 05:18:16 INFO : Local file system at /tmp/rclone669054014: Waiting for transfers to finish 2020/01/19 05:18:16 INFO : Waiting for deletions to finish --- PASS: TestSyncWithExclude (0.45s) run.go:176: Remote "Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5'", Local "Local file system at /tmp/rclone669054014", Modify Window "1ns" === RUN TestSyncWithExcludeAndDeleteExcluded 2020/01/19 05:18:16 DEBUG : potato2.rclone_chunk.001_ceh8t0: MD5 = d6548b156ea68a4e003e786df99eee76 OK 2020/01/19 05:18:16 INFO : potato2.rclone_chunk.001_ceh8t0: Copied (server side copy) 2020/01/19 05:18:16 INFO : potato2.rclone_chunk.001_ceh8t0: Deleted 2020/01/19 05:18:16 DEBUG : empty space.rclone_chunk.001_ceh8sh: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/01/19 05:18:16 INFO : empty space.rclone_chunk.001_ceh8sh: Copied (server side copy) 2020/01/19 05:18:16 INFO : empty space.rclone_chunk.001_ceh8sh: Deleted 2020/01/19 05:18:16 DEBUG : enormous.rclone_chunk.001_ceh863: MD5 = 8adc5937e635f6c9af646f0b23560fae OK 2020/01/19 05:18:16 INFO : enormous.rclone_chunk.001_ceh863: Copied (server side copy) 2020/01/19 05:18:16 INFO : enormous.rclone_chunk.001_ceh863: Deleted 2020/01/19 05:18:16 DEBUG : potato2: Excluded 2020/01/19 05:18:16 DEBUG : enormous: Excluded 2020/01/19 05:18:16 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for checks to finish 2020/01/19 05:18:16 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:18:16 DEBUG : empty space: Unchanged skipping 2020/01/19 05:18:16 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for transfers to finish 2020/01/19 05:18:16 INFO : Waiting for deletions to finish 2020/01/19 05:18:16 INFO : enormous: Deleted 2020/01/19 05:18:16 INFO : potato2: Deleted 2020/01/19 05:18:18 INFO : Local file system at /tmp/rclone669054014: Waiting for checks to finish 2020/01/19 05:18:18 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:18:18 DEBUG : empty space: Unchanged skipping 2020/01/19 05:18:18 INFO : Local file system at /tmp/rclone669054014: Waiting for transfers to finish 2020/01/19 05:18:18 INFO : Waiting for deletions to finish 2020/01/19 05:18:18 INFO : enormous: Deleted 2020/01/19 05:18:18 INFO : potato2: Deleted --- PASS: TestSyncWithExcludeAndDeleteExcluded (2.37s) run.go:176: Remote "Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5'", Local "Local file system at /tmp/rclone669054014", Modify Window "1ns" === RUN TestSyncWithUpdateOlder 2020/01/19 05:18:18 DEBUG : one.rclone_chunk.001_cehapx: MD5 = bc21e6484530fc9d0313cb816b733396 OK 2020/01/19 05:18:18 INFO : one.rclone_chunk.001_cehapx: Copied (server side copy) 2020/01/19 05:18:18 INFO : one.rclone_chunk.001_cehapx: Deleted 2020/01/19 05:18:18 DEBUG : two.rclone_chunk.001_cehayi: MD5 = 0f82d86afa0f5dc965c5c15aca58dcfb OK 2020/01/19 05:18:18 INFO : two.rclone_chunk.001_cehayi: Copied (server side copy) 2020/01/19 05:18:18 INFO : two.rclone_chunk.001_cehayi: Deleted 2020/01/19 05:18:18 DEBUG : three.rclone_chunk.001_cehare: MD5 = 413af0de1f97a2155acf2b8b26ab36e2 OK 2020/01/19 05:18:18 INFO : three.rclone_chunk.001_cehare: Copied (server side copy) 2020/01/19 05:18:18 INFO : three.rclone_chunk.001_cehare: Deleted 2020/01/19 05:18:18 DEBUG : four.rclone_chunk.001_cehaou: MD5 = 0a04eb9a514fbe0829cf48049cc69fc7 OK 2020/01/19 05:18:18 INFO : four.rclone_chunk.001_cehaou: Copied (server side copy) 2020/01/19 05:18:18 INFO : four.rclone_chunk.001_cehaou: Deleted 2020/01/19 05:18:19 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for checks to finish 2020/01/19 05:18:19 DEBUG : four: Sizes differ (src 4 vs dst 8) 2020/01/19 05:18:19 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2020/01/19 05:18:19 DEBUG : one: Destination is newer than source, skipping 2020/01/19 05:18:19 DEBUG : three: Sizes identical 2020/01/19 05:18:19 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2020/01/19 05:18:19 DEBUG : two: MD5 = b8a9f715dbb64fd5c56e7783c6820a61 (Local file system at /tmp/rclone669054014) 2020/01/19 05:18:19 DEBUG : two: MD5 = 0f82d86afa0f5dc965c5c15aca58dcfb (Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5') 2020/01/19 05:18:19 DEBUG : two: MD5 differ 2020/01/19 05:18:19 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for transfers to finish 2020/01/19 05:18:19 DEBUG : five.rclone_chunk.001_cehbcj: MD5 = 30056e1cab7a61d256fc8edd970d14f5 OK 2020/01/19 05:18:19 INFO : five.rclone_chunk.001_cehbcj: Copied (server side copy) 2020/01/19 05:18:19 DEBUG : four.rclone_chunk.001_cehb77: MD5 = 8cbad96aced40b3838dd9f07f6ef5772 OK 2020/01/19 05:18:19 INFO : four.rclone_chunk.001_cehb77: Copied (server side copy) 2020/01/19 05:18:19 INFO : five.rclone_chunk.001_cehbcj: Deleted 2020/01/19 05:18:19 DEBUG : five: MD5 = 30056e1cab7a61d256fc8edd970d14f5 OK 2020/01/19 05:18:19 INFO : five: Copied (new) 2020/01/19 05:18:19 INFO : four.rclone_chunk.001_cehb77: Deleted 2020/01/19 05:18:19 DEBUG : four: MD5 = 8cbad96aced40b3838dd9f07f6ef5772 OK 2020/01/19 05:18:19 INFO : four: Copied (replaced existing) 2020/01/19 05:18:20 DEBUG : two.rclone_chunk.001_cehbgz: MD5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2020/01/19 05:18:20 INFO : two.rclone_chunk.001_cehbgz: Copied (server side copy) 2020/01/19 05:18:20 INFO : two.rclone_chunk.001_cehbgz: Deleted 2020/01/19 05:18:20 DEBUG : two: MD5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2020/01/19 05:18:20 INFO : two: Copied (replaced existing) 2020/01/19 05:18:20 INFO : Waiting for deletions to finish 2020/01/19 05:18:20 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for checks to finish 2020/01/19 05:18:20 DEBUG : five: MD5 = 30056e1cab7a61d256fc8edd970d14f5 OK 2020/01/19 05:18:20 DEBUG : five: Size and MD5 of src and dst objects identical 2020/01/19 05:18:20 DEBUG : five: Destination mod time is within 1s of source and files identical, skipping 2020/01/19 05:18:20 DEBUG : three: MD5 = 35d6d33467aae9a2e3dccb4b6b027878 (Local file system at /tmp/rclone669054014) 2020/01/19 05:18:20 DEBUG : three: MD5 = 413af0de1f97a2155acf2b8b26ab36e2 (Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5') 2020/01/19 05:18:20 DEBUG : three: MD5 differ 2020/01/19 05:18:20 DEBUG : three: Destination mod time is within 1s of source but files differ, transferring 2020/01/19 05:18:20 DEBUG : two: MD5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2020/01/19 05:18:20 DEBUG : two: Size and MD5 of src and dst objects identical 2020/01/19 05:18:20 DEBUG : two: Destination mod time is within 1s of source and files identical, skipping 2020/01/19 05:18:20 DEBUG : one: Destination is newer than source, skipping 2020/01/19 05:18:20 DEBUG : four: MD5 = 8cbad96aced40b3838dd9f07f6ef5772 OK 2020/01/19 05:18:20 DEBUG : four: Size and MD5 of src and dst objects identical 2020/01/19 05:18:20 DEBUG : four: Destination mod time is within 1s of source and files identical, skipping 2020/01/19 05:18:20 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for transfers to finish 2020/01/19 05:18:20 DEBUG : three.rclone_chunk.001_cehcon: MD5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2020/01/19 05:18:20 INFO : three.rclone_chunk.001_cehcon: Copied (server side copy) 2020/01/19 05:18:20 INFO : three.rclone_chunk.001_cehcon: Deleted 2020/01/19 05:18:20 DEBUG : three: MD5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2020/01/19 05:18:20 INFO : three: Copied (replaced existing) 2020/01/19 05:18:20 INFO : Waiting for deletions to finish --- PASS: TestSyncWithUpdateOlder (2.28s) run.go:176: Remote "Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5'", Local "Local file system at /tmp/rclone669054014", Modify Window "1ns" === RUN TestSyncWithTrackRenames 2020/01/19 05:18:20 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Making map for --track-renames 2020/01/19 05:18:20 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Finished making map for --track-renames 2020/01/19 05:18:20 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for checks to finish 2020/01/19 05:18:20 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for renames to finish 2020/01/19 05:18:20 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for transfers to finish 2020/01/19 05:18:20 DEBUG : potato.rclone_chunk.001_cehccc: MD5 = 7f6fa9ddec9bfebda9510af0323fd696 OK 2020/01/19 05:18:20 INFO : potato.rclone_chunk.001_cehccc: Copied (server side copy) 2020/01/19 05:18:20 DEBUG : yam.rclone_chunk.001_cehc8c: MD5 = 48dc443644c4b89556dc06834b46451d OK 2020/01/19 05:18:20 INFO : yam.rclone_chunk.001_cehc8c: Copied (server side copy) 2020/01/19 05:18:20 INFO : potato.rclone_chunk.001_cehccc: Deleted 2020/01/19 05:18:20 DEBUG : potato: MD5 = 7f6fa9ddec9bfebda9510af0323fd696 OK 2020/01/19 05:18:20 INFO : potato: Copied (new) 2020/01/19 05:18:20 INFO : yam.rclone_chunk.001_cehc8c: Deleted 2020/01/19 05:18:20 DEBUG : yam: MD5 = 48dc443644c4b89556dc06834b46451d OK 2020/01/19 05:18:20 INFO : yam: Copied (new) 2020/01/19 05:18:20 INFO : Waiting for deletions to finish 2020/01/19 05:18:20 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Making map for --track-renames 2020/01/19 05:18:20 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Finished making map for --track-renames 2020/01/19 05:18:20 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for checks to finish 2020/01/19 05:18:20 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:18:20 DEBUG : potato: Unchanged skipping 2020/01/19 05:18:20 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for renames to finish 2020/01/19 05:18:20 DEBUG : yam: copy non-chunked object... 2020/01/19 05:18:21 DEBUG : yam: MD5 = 48dc443644c4b89556dc06834b46451d OK 2020/01/19 05:18:21 INFO : yam: Copied (server side copy) 2020/01/19 05:18:21 INFO : yam: Deleted 2020/01/19 05:18:21 INFO : yaml: Renamed from "yam" 2020/01/19 05:18:21 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for transfers to finish 2020/01/19 05:18:21 INFO : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenames (0.60s) run.go:176: Remote "Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5'", Local "Local file system at /tmp/rclone669054014", Modify Window "1ns" sync_test.go:1005: Can track renames: true === RUN TestMoveWithDeleteEmptySrcDirs 2020/01/19 05:18:21 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for checks to finish 2020/01/19 05:18:21 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for transfers to finish 2020/01/19 05:18:21 DEBUG : sub dir/hello world.rclone_chunk.001_cehdey: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/01/19 05:18:21 INFO : sub dir/hello world.rclone_chunk.001_cehdey: Copied (server side copy) 2020/01/19 05:18:21 DEBUG : nested/sub dir/file.rclone_chunk.001_cehd0d: MD5 = 83d3784ea62518eafc60e98d84f877ad OK 2020/01/19 05:18:21 INFO : nested/sub dir/file.rclone_chunk.001_cehd0d: Copied (server side copy) 2020/01/19 05:18:21 INFO : nested/sub dir/file.rclone_chunk.001_cehd0d: Deleted 2020/01/19 05:18:21 DEBUG : nested/sub dir/file: MD5 = 83d3784ea62518eafc60e98d84f877ad OK 2020/01/19 05:18:21 INFO : nested/sub dir/file: Copied (new) 2020/01/19 05:18:21 INFO : nested/sub dir/file: Deleted 2020/01/19 05:18:21 INFO : sub dir/hello world.rclone_chunk.001_cehdey: Deleted 2020/01/19 05:18:21 DEBUG : sub dir/hello world: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/01/19 05:18:21 INFO : sub dir/hello world: Copied (new) 2020/01/19 05:18:21 INFO : sub dir/hello world: Deleted 2020/01/19 05:18:21 DEBUG : sub dir: Removing directory 2020/01/19 05:18:21 DEBUG : nested/sub dir: Removing directory 2020/01/19 05:18:21 DEBUG : nested: Removing directory 2020/01/19 05:18:21 DEBUG : Local file system at /tmp/rclone669054014: deleted 3 directories --- PASS: TestMoveWithDeleteEmptySrcDirs (0.43s) run.go:176: Remote "Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5'", Local "Local file system at /tmp/rclone669054014", Modify Window "1ns" === RUN TestMoveWithoutDeleteEmptySrcDirs 2020/01/19 05:18:21 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for checks to finish 2020/01/19 05:18:21 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for transfers to finish 2020/01/19 05:18:21 DEBUG : nested/sub dir/file.rclone_chunk.001_cehdi4: MD5 = 83d3784ea62518eafc60e98d84f877ad OK 2020/01/19 05:18:21 INFO : nested/sub dir/file.rclone_chunk.001_cehdi4: Copied (server side copy) 2020/01/19 05:18:21 DEBUG : sub dir/hello world.rclone_chunk.001_cehd93: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/01/19 05:18:21 INFO : sub dir/hello world.rclone_chunk.001_cehd93: Copied (server side copy) 2020/01/19 05:18:21 INFO : nested/sub dir/file.rclone_chunk.001_cehdi4: Deleted 2020/01/19 05:18:21 DEBUG : nested/sub dir/file: MD5 = 83d3784ea62518eafc60e98d84f877ad OK 2020/01/19 05:18:21 INFO : nested/sub dir/file: Copied (new) 2020/01/19 05:18:21 INFO : nested/sub dir/file: Deleted 2020/01/19 05:18:21 INFO : sub dir/hello world.rclone_chunk.001_cehd93: Deleted 2020/01/19 05:18:21 DEBUG : sub dir/hello world: MD5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2020/01/19 05:18:21 INFO : sub dir/hello world: Copied (new) 2020/01/19 05:18:21 INFO : sub dir/hello world: Deleted --- PASS: TestMoveWithoutDeleteEmptySrcDirs (0.45s) run.go:176: Remote "Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5'", Local "Local file system at /tmp/rclone669054014", Modify Window "1ns" === RUN TestServerSideMove 2020/01/19 05:18:22 DEBUG : potato2.rclone_chunk.001_cehegp: MD5 = d6548b156ea68a4e003e786df99eee76 OK 2020/01/19 05:18:22 INFO : potato2.rclone_chunk.001_cehegp: Copied (server side copy) 2020/01/19 05:18:22 INFO : potato2.rclone_chunk.001_cehegp: Deleted 2020/01/19 05:18:22 DEBUG : empty space.rclone_chunk.001_cehevf: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/01/19 05:18:22 INFO : empty space.rclone_chunk.001_cehevf: Copied (server side copy) 2020/01/19 05:18:22 INFO : empty space.rclone_chunk.001_cehevf: Deleted 2020/01/19 05:18:22 DEBUG : potato3.rclone_chunk.001_cehet0: MD5 = b1a53993579b830006f2e1610415c32c OK 2020/01/19 05:18:22 INFO : potato3.rclone_chunk.001_cehet0: Copied (server side copy) 2020/01/19 05:18:22 INFO : potato3.rclone_chunk.001_cehet0: Deleted 2020/01/19 05:18:23 INFO : S3 bucket rclone-test-peyatab8sehekuv1fiqefik0: Bucket "rclone-test-peyatab8sehekuv1fiqefik0" created with ACL "private" 2020/01/19 05:18:23 DEBUG : empty space.rclone_chunk.001_cehfsu: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/01/19 05:18:23 INFO : empty space.rclone_chunk.001_cehfsu: Copied (server side copy) 2020/01/19 05:18:23 INFO : empty space.rclone_chunk.001_cehfsu: Deleted 2020/01/19 05:18:23 DEBUG : potato3.rclone_chunk.001_cehfqf: MD5 = d6548b156ea68a4e003e786df99eee76 OK 2020/01/19 05:18:23 INFO : potato3.rclone_chunk.001_cehfqf: Copied (server side copy) 2020/01/19 05:18:23 INFO : potato3.rclone_chunk.001_cehfqf: Deleted 2020/01/19 05:18:24 DEBUG : potato2: copy non-chunked object... 2020/01/19 05:18:24 INFO : Chunked 'TestChunkerS3:rclone-test-peyatab8sehekuv1fiqefik0': Waiting for checks to finish 2020/01/19 05:18:24 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2020/01/19 05:18:24 DEBUG : potato3: copy non-chunked object... 2020/01/19 05:18:24 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:18:24 DEBUG : empty space: Unchanged skipping 2020/01/19 05:18:24 DEBUG : potato3: MD5 = b1a53993579b830006f2e1610415c32c OK 2020/01/19 05:18:24 INFO : potato3: Copied (server side copy) 2020/01/19 05:18:24 DEBUG : potato2: MD5 = d6548b156ea68a4e003e786df99eee76 OK 2020/01/19 05:18:24 INFO : potato2: Copied (server side copy) 2020/01/19 05:18:24 INFO : potato3: Deleted 2020/01/19 05:18:24 INFO : empty space: Deleted 2020/01/19 05:18:24 INFO : Chunked 'TestChunkerS3:rclone-test-peyatab8sehekuv1fiqefik0': Waiting for transfers to finish 2020/01/19 05:18:24 INFO : potato2: Deleted 2020/01/19 05:18:25 INFO : Chunked 'TestChunkerS3:rclone-test-hanoyuh1xubimof1kunovux9': Waiting for checks to finish 2020/01/19 05:18:25 DEBUG : empty space: copy non-chunked object... 2020/01/19 05:18:25 DEBUG : potato2: copy non-chunked object... 2020/01/19 05:18:25 DEBUG : potato3: copy non-chunked object... 2020/01/19 05:18:25 INFO : Chunked 'TestChunkerS3:rclone-test-hanoyuh1xubimof1kunovux9': Waiting for transfers to finish 2020/01/19 05:18:25 INFO : S3 bucket rclone-test-hanoyuh1xubimof1kunovux9: Bucket "rclone-test-hanoyuh1xubimof1kunovux9" created with ACL "private" 2020/01/19 05:18:26 DEBUG : potato2: MD5 = d6548b156ea68a4e003e786df99eee76 OK 2020/01/19 05:18:26 INFO : potato2: Copied (server side copy) 2020/01/19 05:18:26 DEBUG : potato3: MD5 = b1a53993579b830006f2e1610415c32c OK 2020/01/19 05:18:26 INFO : potato3: Copied (server side copy) 2020/01/19 05:18:26 DEBUG : empty space: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/01/19 05:18:26 INFO : empty space: Copied (server side copy) 2020/01/19 05:18:26 INFO : potato2: Deleted 2020/01/19 05:18:26 INFO : empty space: Deleted 2020/01/19 05:18:26 INFO : potato3: Deleted 2020/01/19 05:18:26 DEBUG : Chunked 'TestChunkerS3:rclone-test-hanoyuh1xubimof1kunovux9': Purge object "empty space" 2020/01/19 05:18:26 DEBUG : Chunked 'TestChunkerS3:rclone-test-hanoyuh1xubimof1kunovux9': Purge object "potato2" 2020/01/19 05:18:26 DEBUG : Chunked 'TestChunkerS3:rclone-test-hanoyuh1xubimof1kunovux9': Purge object "potato3" 2020/01/19 05:18:26 DEBUG : Chunked 'TestChunkerS3:rclone-test-hanoyuh1xubimof1kunovux9': Purge dir "" 2020/01/19 05:18:27 INFO : S3 bucket rclone-test-hanoyuh1xubimof1kunovux9: Bucket "rclone-test-hanoyuh1xubimof1kunovux9" deleted 2020/01/19 05:18:27 DEBUG : Chunked 'TestChunkerS3:rclone-test-peyatab8sehekuv1fiqefik0': Purge dir "" 2020/01/19 05:18:27 INFO : S3 bucket rclone-test-peyatab8sehekuv1fiqefik0: Bucket "rclone-test-peyatab8sehekuv1fiqefik0" deleted --- PASS: TestServerSideMove (5.44s) run.go:176: Remote "Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5'", Local "Local file system at /tmp/rclone669054014", Modify Window "1ns" sync_test.go:1110: Server side move (if possible) Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5' -> Chunked 'TestChunkerS3:rclone-test-peyatab8sehekuv1fiqefik0' === RUN TestServerSideMoveWithFilter 2020/01/19 05:18:27 DEBUG : potato2.rclone_chunk.001_cehj3t: MD5 = d6548b156ea68a4e003e786df99eee76 OK 2020/01/19 05:18:27 INFO : potato2.rclone_chunk.001_cehj3t: Copied (server side copy) 2020/01/19 05:18:27 INFO : potato2.rclone_chunk.001_cehj3t: Deleted 2020/01/19 05:18:27 DEBUG : empty space.rclone_chunk.001_cehj8r: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/01/19 05:18:27 INFO : empty space.rclone_chunk.001_cehj8r: Copied (server side copy) 2020/01/19 05:18:27 INFO : empty space.rclone_chunk.001_cehj8r: Deleted 2020/01/19 05:18:28 DEBUG : potato3.rclone_chunk.001_cehjmo: MD5 = b1a53993579b830006f2e1610415c32c OK 2020/01/19 05:18:28 INFO : potato3.rclone_chunk.001_cehjmo: Copied (server side copy) 2020/01/19 05:18:28 INFO : potato3.rclone_chunk.001_cehjmo: Deleted 2020/01/19 05:18:28 INFO : S3 bucket rclone-test-wapatak8wizoraw3tuwemew6: Bucket "rclone-test-wapatak8wizoraw3tuwemew6" created with ACL "private" 2020/01/19 05:18:28 DEBUG : empty space.rclone_chunk.001_cehknk: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/01/19 05:18:28 INFO : empty space.rclone_chunk.001_cehknk: Copied (server side copy) 2020/01/19 05:18:28 INFO : empty space.rclone_chunk.001_cehknk: Deleted 2020/01/19 05:18:28 DEBUG : potato3.rclone_chunk.001_cehktu: MD5 = d6548b156ea68a4e003e786df99eee76 OK 2020/01/19 05:18:28 INFO : potato3.rclone_chunk.001_cehktu: Copied (server side copy) 2020/01/19 05:18:28 INFO : potato3.rclone_chunk.001_cehktu: Deleted 2020/01/19 05:18:29 DEBUG : empty space: Excluded 2020/01/19 05:18:29 DEBUG : empty space: Excluded 2020/01/19 05:18:29 DEBUG : potato2: copy non-chunked object... 2020/01/19 05:18:29 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2020/01/19 05:18:29 INFO : Chunked 'TestChunkerS3:rclone-test-wapatak8wizoraw3tuwemew6': Waiting for checks to finish 2020/01/19 05:18:29 INFO : Chunked 'TestChunkerS3:rclone-test-wapatak8wizoraw3tuwemew6': Waiting for transfers to finish 2020/01/19 05:18:29 DEBUG : potato3: copy non-chunked object... 2020/01/19 05:18:29 DEBUG : potato3: MD5 = b1a53993579b830006f2e1610415c32c OK 2020/01/19 05:18:29 INFO : potato3: Copied (server side copy) 2020/01/19 05:18:29 DEBUG : potato2: MD5 = d6548b156ea68a4e003e786df99eee76 OK 2020/01/19 05:18:29 INFO : potato2: Copied (server side copy) 2020/01/19 05:18:29 INFO : potato3: Deleted 2020/01/19 05:18:29 INFO : potato2: Deleted 2020/01/19 05:18:29 DEBUG : empty space: Excluded 2020/01/19 05:18:29 DEBUG : potato2: copy non-chunked object... 2020/01/19 05:18:29 DEBUG : potato3: copy non-chunked object... 2020/01/19 05:18:29 INFO : Chunked 'TestChunkerS3:rclone-test-dafakes1zevacuw2deqofof4': Waiting for checks to finish 2020/01/19 05:18:29 INFO : Chunked 'TestChunkerS3:rclone-test-dafakes1zevacuw2deqofof4': Waiting for transfers to finish 2020/01/19 05:18:30 INFO : S3 bucket rclone-test-dafakes1zevacuw2deqofof4: Bucket "rclone-test-dafakes1zevacuw2deqofof4" created with ACL "private" 2020/01/19 05:18:30 DEBUG : potato2: MD5 = d6548b156ea68a4e003e786df99eee76 OK 2020/01/19 05:18:30 INFO : potato2: Copied (server side copy) 2020/01/19 05:18:30 DEBUG : potato3: MD5 = b1a53993579b830006f2e1610415c32c OK 2020/01/19 05:18:30 INFO : potato3: Copied (server side copy) 2020/01/19 05:18:30 INFO : potato2: Deleted 2020/01/19 05:18:30 INFO : potato3: Deleted 2020/01/19 05:18:31 DEBUG : Chunked 'TestChunkerS3:rclone-test-dafakes1zevacuw2deqofof4': Purge object "potato2" 2020/01/19 05:18:31 DEBUG : Chunked 'TestChunkerS3:rclone-test-dafakes1zevacuw2deqofof4': Purge object "potato3" 2020/01/19 05:18:31 DEBUG : Chunked 'TestChunkerS3:rclone-test-dafakes1zevacuw2deqofof4': Purge dir "" 2020/01/19 05:18:31 INFO : S3 bucket rclone-test-dafakes1zevacuw2deqofof4: Bucket "rclone-test-dafakes1zevacuw2deqofof4" deleted 2020/01/19 05:18:31 DEBUG : Chunked 'TestChunkerS3:rclone-test-wapatak8wizoraw3tuwemew6': Purge object "empty space" 2020/01/19 05:18:31 DEBUG : Chunked 'TestChunkerS3:rclone-test-wapatak8wizoraw3tuwemew6': Purge dir "" 2020/01/19 05:18:31 INFO : S3 bucket rclone-test-wapatak8wizoraw3tuwemew6: Bucket "rclone-test-wapatak8wizoraw3tuwemew6" deleted --- PASS: TestServerSideMoveWithFilter (4.51s) run.go:176: Remote "Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5'", Local "Local file system at /tmp/rclone669054014", Modify Window "1ns" sync_test.go:1110: Server side move (if possible) Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5' -> Chunked 'TestChunkerS3:rclone-test-wapatak8wizoraw3tuwemew6' === RUN TestServerSideMoveDeleteEmptySourceDirs 2020/01/19 05:18:32 DEBUG : potato2.rclone_chunk.001_cehohd: MD5 = d6548b156ea68a4e003e786df99eee76 OK 2020/01/19 05:18:32 INFO : potato2.rclone_chunk.001_cehohd: Copied (server side copy) 2020/01/19 05:18:32 INFO : potato2.rclone_chunk.001_cehohd: Deleted 2020/01/19 05:18:32 DEBUG : empty space.rclone_chunk.001_cehox4: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/01/19 05:18:32 INFO : empty space.rclone_chunk.001_cehox4: Copied (server side copy) 2020/01/19 05:18:32 INFO : empty space.rclone_chunk.001_cehox4: Deleted 2020/01/19 05:18:32 DEBUG : potato3.rclone_chunk.001_cehomd: MD5 = b1a53993579b830006f2e1610415c32c OK 2020/01/19 05:18:32 INFO : potato3.rclone_chunk.001_cehomd: Copied (server side copy) 2020/01/19 05:18:32 INFO : potato3.rclone_chunk.001_cehomd: Deleted 2020/01/19 05:18:32 DEBUG : tomatoDir: Making directory 2020/01/19 05:18:33 INFO : S3 bucket rclone-test-lofoquk1sovonuj1xivuvas7: Bucket "rclone-test-lofoquk1sovonuj1xivuvas7" created with ACL "private" 2020/01/19 05:18:33 DEBUG : empty space.rclone_chunk.001_cehpx6: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/01/19 05:18:33 INFO : empty space.rclone_chunk.001_cehpx6: Copied (server side copy) 2020/01/19 05:18:33 INFO : empty space.rclone_chunk.001_cehpx6: Deleted 2020/01/19 05:18:33 DEBUG : potato3.rclone_chunk.001_cehp46: MD5 = d6548b156ea68a4e003e786df99eee76 OK 2020/01/19 05:18:33 INFO : potato3.rclone_chunk.001_cehp46: Copied (server side copy) 2020/01/19 05:18:33 INFO : potato3.rclone_chunk.001_cehp46: Deleted 2020/01/19 05:18:34 DEBUG : potato2: copy non-chunked object... 2020/01/19 05:18:34 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2020/01/19 05:18:34 INFO : Chunked 'TestChunkerS3:rclone-test-lofoquk1sovonuj1xivuvas7': Waiting for checks to finish 2020/01/19 05:18:34 DEBUG : potato3: copy non-chunked object... 2020/01/19 05:18:34 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:18:34 DEBUG : empty space: Unchanged skipping 2020/01/19 05:18:34 DEBUG : potato2: MD5 = d6548b156ea68a4e003e786df99eee76 OK 2020/01/19 05:18:34 INFO : potato2: Copied (server side copy) 2020/01/19 05:18:34 INFO : potato2: Deleted 2020/01/19 05:18:34 DEBUG : potato3: MD5 = b1a53993579b830006f2e1610415c32c OK 2020/01/19 05:18:34 INFO : potato3: Copied (server side copy) 2020/01/19 05:18:34 INFO : empty space: Deleted 2020/01/19 05:18:34 INFO : Chunked 'TestChunkerS3:rclone-test-lofoquk1sovonuj1xivuvas7': Waiting for transfers to finish 2020/01/19 05:18:34 INFO : potato3: Deleted 2020/01/19 05:18:34 DEBUG : tomatoDir: Making directory 2020/01/19 05:18:34 INFO : Chunked 'TestChunkerS3:rclone-test-famoguh7paluyed6jewiyeg2': Waiting for checks to finish 2020/01/19 05:18:34 DEBUG : potato2: copy non-chunked object... 2020/01/19 05:18:34 DEBUG : empty space: copy non-chunked object... 2020/01/19 05:18:34 INFO : Chunked 'TestChunkerS3:rclone-test-famoguh7paluyed6jewiyeg2': Waiting for transfers to finish 2020/01/19 05:18:34 DEBUG : potato3: copy non-chunked object... 2020/01/19 05:18:35 INFO : S3 bucket rclone-test-famoguh7paluyed6jewiyeg2: Bucket "rclone-test-famoguh7paluyed6jewiyeg2" created with ACL "private" 2020/01/19 05:18:35 DEBUG : potato2: MD5 = d6548b156ea68a4e003e786df99eee76 OK 2020/01/19 05:18:35 INFO : potato2: Copied (server side copy) 2020/01/19 05:18:35 INFO : potato2: Deleted 2020/01/19 05:18:35 DEBUG : empty space: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/01/19 05:18:35 INFO : empty space: Copied (server side copy) 2020/01/19 05:18:35 DEBUG : potato3: MD5 = b1a53993579b830006f2e1610415c32c OK 2020/01/19 05:18:35 INFO : potato3: Copied (server side copy) 2020/01/19 05:18:35 INFO : empty space: Deleted 2020/01/19 05:18:35 INFO : potato3: Deleted 2020/01/19 05:18:35 DEBUG : Chunked 'TestChunkerS3:rclone-test-famoguh7paluyed6jewiyeg2': Purge object "empty space" 2020/01/19 05:18:35 DEBUG : Chunked 'TestChunkerS3:rclone-test-famoguh7paluyed6jewiyeg2': Purge object "potato2" 2020/01/19 05:18:35 DEBUG : Chunked 'TestChunkerS3:rclone-test-famoguh7paluyed6jewiyeg2': Purge object "potato3" 2020/01/19 05:18:35 DEBUG : Chunked 'TestChunkerS3:rclone-test-famoguh7paluyed6jewiyeg2': Purge dir "" 2020/01/19 05:18:35 INFO : S3 bucket rclone-test-famoguh7paluyed6jewiyeg2: Bucket "rclone-test-famoguh7paluyed6jewiyeg2" deleted 2020/01/19 05:18:35 DEBUG : Chunked 'TestChunkerS3:rclone-test-lofoquk1sovonuj1xivuvas7': Purge dir "" 2020/01/19 05:18:36 INFO : S3 bucket rclone-test-lofoquk1sovonuj1xivuvas7: Bucket "rclone-test-lofoquk1sovonuj1xivuvas7" deleted --- PASS: TestServerSideMoveDeleteEmptySourceDirs (4.96s) run.go:176: Remote "Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5'", Local "Local file system at /tmp/rclone669054014", Modify Window "1ns" sync_test.go:1110: Server side move (if possible) Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5' -> Chunked 'TestChunkerS3:rclone-test-lofoquk1sovonuj1xivuvas7' === RUN TestServerSideMoveOverlap 2020/01/19 05:18:37 DEBUG : potato2.rclone_chunk.001_ceht7r: MD5 = d6548b156ea68a4e003e786df99eee76 OK 2020/01/19 05:18:37 INFO : potato2.rclone_chunk.001_ceht7r: Copied (server side copy) 2020/01/19 05:18:37 INFO : potato2.rclone_chunk.001_ceht7r: Deleted --- PASS: TestServerSideMoveOverlap (0.18s) run.go:176: Remote "Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5'", Local "Local file system at /tmp/rclone669054014", Modify Window "1ns" === RUN TestSyncOverlap --- PASS: TestSyncOverlap (0.04s) run.go:176: Remote "Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5'", Local "Local file system at /tmp/rclone669054014", Modify Window "1ns" === RUN TestSyncCompareDest 2020/01/19 05:18:37 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5/dst': Waiting for checks to finish 2020/01/19 05:18:37 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5/dst': Waiting for transfers to finish 2020/01/19 05:18:37 DEBUG : one.rclone_chunk.001_cehtt0: MD5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2020/01/19 05:18:37 INFO : one.rclone_chunk.001_cehtt0: Copied (server side copy) 2020/01/19 05:18:37 INFO : one.rclone_chunk.001_cehtt0: Deleted 2020/01/19 05:18:37 DEBUG : one: MD5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2020/01/19 05:18:37 INFO : one: Copied (new) 2020/01/19 05:18:37 INFO : Waiting for deletions to finish 2020/01/19 05:18:38 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5/dst': Waiting for checks to finish 2020/01/19 05:18:38 DEBUG : one: Sizes differ (src 5 vs dst 3) 2020/01/19 05:18:38 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5/dst': Waiting for transfers to finish 2020/01/19 05:18:38 DEBUG : one.rclone_chunk.001_cehumo: MD5 = 07912d142f5d63ee918b34796b5a2432 OK 2020/01/19 05:18:38 INFO : one.rclone_chunk.001_cehumo: Copied (server side copy) 2020/01/19 05:18:38 INFO : one.rclone_chunk.001_cehumo: Deleted 2020/01/19 05:18:38 DEBUG : one: MD5 = 07912d142f5d63ee918b34796b5a2432 OK 2020/01/19 05:18:38 INFO : one: Copied (replaced existing) 2020/01/19 05:18:38 INFO : Waiting for deletions to finish 2020/01/19 05:18:38 DEBUG : dst/one.rclone_chunk.001_cehuew: MD5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2020/01/19 05:18:38 INFO : dst/one.rclone_chunk.001_cehuew: Copied (server side copy) 2020/01/19 05:18:38 INFO : dst/one.rclone_chunk.001_cehuew: Deleted 2020/01/19 05:18:38 DEBUG : CompareDest/one.rclone_chunk.001_cehu3t: MD5 = 07912d142f5d63ee918b34796b5a2432 OK 2020/01/19 05:18:38 INFO : CompareDest/one.rclone_chunk.001_cehu3t: Copied (server side copy) 2020/01/19 05:18:38 INFO : CompareDest/one.rclone_chunk.001_cehu3t: Deleted 2020/01/19 05:18:39 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5/dst': Waiting for checks to finish 2020/01/19 05:18:39 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:18:39 DEBUG : one: Destination found in --compare-dest, skipping 2020/01/19 05:18:39 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5/dst': Waiting for transfers to finish 2020/01/19 05:18:39 INFO : Waiting for deletions to finish 2020/01/19 05:18:39 DEBUG : CompareDest/two.rclone_chunk.001_cehvu3: MD5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2020/01/19 05:18:39 INFO : CompareDest/two.rclone_chunk.001_cehvu3: Copied (server side copy) 2020/01/19 05:18:39 INFO : CompareDest/two.rclone_chunk.001_cehvu3: Deleted 2020/01/19 05:18:39 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:18:39 DEBUG : two: Destination found in --compare-dest, skipping 2020/01/19 05:18:39 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5/dst': Waiting for checks to finish 2020/01/19 05:18:39 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:18:39 DEBUG : one: Destination found in --compare-dest, skipping 2020/01/19 05:18:39 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5/dst': Waiting for transfers to finish 2020/01/19 05:18:39 INFO : Waiting for deletions to finish 2020/01/19 05:18:39 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:18:39 DEBUG : two: Destination found in --compare-dest, skipping 2020/01/19 05:18:39 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5/dst': Waiting for checks to finish 2020/01/19 05:18:39 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:18:39 DEBUG : one: Destination found in --compare-dest, skipping 2020/01/19 05:18:39 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5/dst': Waiting for transfers to finish 2020/01/19 05:18:39 INFO : Waiting for deletions to finish 2020/01/19 05:18:40 DEBUG : two: Sizes differ (src 5 vs dst 3) 2020/01/19 05:18:40 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5/dst': Waiting for checks to finish 2020/01/19 05:18:40 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:18:40 DEBUG : one: Destination found in --compare-dest, skipping 2020/01/19 05:18:40 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5/dst': Waiting for transfers to finish 2020/01/19 05:18:40 DEBUG : two.rclone_chunk.001_cehwx9: MD5 = 2379e4ce8c3380e996ab0509f17069ad OK 2020/01/19 05:18:40 INFO : two.rclone_chunk.001_cehwx9: Copied (server side copy) 2020/01/19 05:18:40 INFO : two.rclone_chunk.001_cehwx9: Deleted 2020/01/19 05:18:40 DEBUG : two: MD5 = 2379e4ce8c3380e996ab0509f17069ad OK 2020/01/19 05:18:40 INFO : two: Copied (new) 2020/01/19 05:18:40 INFO : Waiting for deletions to finish --- PASS: TestSyncCompareDest (3.07s) run.go:176: Remote "Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5'", Local "Local file system at /tmp/rclone669054014", Modify Window "1ns" === RUN TestSyncCopyDest 2020/01/19 05:18:40 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5/dst': Waiting for checks to finish 2020/01/19 05:18:40 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5/dst': Waiting for transfers to finish 2020/01/19 05:18:40 DEBUG : one.rclone_chunk.001_cehwox: MD5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2020/01/19 05:18:40 INFO : one.rclone_chunk.001_cehwox: Copied (server side copy) 2020/01/19 05:18:40 INFO : one.rclone_chunk.001_cehwox: Deleted 2020/01/19 05:18:40 DEBUG : one: MD5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2020/01/19 05:18:40 INFO : one: Copied (new) 2020/01/19 05:18:40 INFO : Waiting for deletions to finish 2020/01/19 05:18:41 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5/dst': Waiting for checks to finish 2020/01/19 05:18:41 DEBUG : one: Sizes differ (src 5 vs dst 3) 2020/01/19 05:18:41 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5/dst': Waiting for transfers to finish 2020/01/19 05:18:41 DEBUG : one.rclone_chunk.001_cehxy5: MD5 = 07912d142f5d63ee918b34796b5a2432 OK 2020/01/19 05:18:41 INFO : one.rclone_chunk.001_cehxy5: Copied (server side copy) 2020/01/19 05:18:41 INFO : one.rclone_chunk.001_cehxy5: Deleted 2020/01/19 05:18:41 DEBUG : one: MD5 = 07912d142f5d63ee918b34796b5a2432 OK 2020/01/19 05:18:41 INFO : one: Copied (replaced existing) 2020/01/19 05:18:41 INFO : Waiting for deletions to finish 2020/01/19 05:18:42 DEBUG : dst/one.rclone_chunk.001_cehyvo: MD5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2020/01/19 05:18:42 INFO : dst/one.rclone_chunk.001_cehyvo: Copied (server side copy) 2020/01/19 05:18:42 INFO : dst/one.rclone_chunk.001_cehyvo: Deleted 2020/01/19 05:18:42 DEBUG : CopyDest/one.rclone_chunk.001_cehywd: MD5 = 07912d142f5d63ee918b34796b5a2432 OK 2020/01/19 05:18:42 INFO : CopyDest/one.rclone_chunk.001_cehywd: Copied (server side copy) 2020/01/19 05:18:42 INFO : CopyDest/one.rclone_chunk.001_cehywd: Deleted 2020/01/19 05:18:42 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5/dst': Waiting for checks to finish 2020/01/19 05:18:42 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:18:42 DEBUG : one: Sizes differ (src 5 vs dst 3) 2020/01/19 05:18:42 DEBUG : one: copy non-chunked object... 2020/01/19 05:18:43 DEBUG : one: MD5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2020/01/19 05:18:43 INFO : one: Copied (server side copy) 2020/01/19 05:18:43 INFO : one: Deleted 2020/01/19 05:18:43 DEBUG : one: copy non-chunked object... 2020/01/19 05:18:43 DEBUG : one: MD5 = 07912d142f5d63ee918b34796b5a2432 OK 2020/01/19 05:18:43 INFO : one: Copied (server side copy) 2020/01/19 05:18:43 DEBUG : one: Destination found in --copy-dest, using server side copy 2020/01/19 05:18:43 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5/dst': Waiting for transfers to finish 2020/01/19 05:18:43 INFO : Waiting for deletions to finish 2020/01/19 05:18:43 DEBUG : CopyDest/two.rclone_chunk.001_cehzy5: MD5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2020/01/19 05:18:43 INFO : CopyDest/two.rclone_chunk.001_cehzy5: Copied (server side copy) 2020/01/19 05:18:43 INFO : CopyDest/two.rclone_chunk.001_cehzy5: Deleted 2020/01/19 05:18:44 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:18:44 DEBUG : two: copy non-chunked object... 2020/01/19 05:18:44 DEBUG : two: MD5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2020/01/19 05:18:44 INFO : two: Copied (server side copy) 2020/01/19 05:18:44 DEBUG : two: Destination found in --copy-dest, using server side copy 2020/01/19 05:18:44 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5/dst': Waiting for checks to finish 2020/01/19 05:18:44 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:18:44 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:18:44 DEBUG : one: Unchanged skipping 2020/01/19 05:18:44 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5/dst': Waiting for transfers to finish 2020/01/19 05:18:44 INFO : Waiting for deletions to finish 2020/01/19 05:18:44 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5/dst': Waiting for checks to finish 2020/01/19 05:18:44 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:18:44 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:18:44 DEBUG : one: Unchanged skipping 2020/01/19 05:18:44 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:18:44 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:18:44 DEBUG : two: Unchanged skipping 2020/01/19 05:18:44 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5/dst': Waiting for transfers to finish 2020/01/19 05:18:44 INFO : Waiting for deletions to finish 2020/01/19 05:18:44 DEBUG : CopyDest/three.rclone_chunk.001_cei0sb: MD5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2020/01/19 05:18:44 INFO : CopyDest/three.rclone_chunk.001_cei0sb: Copied (server side copy) 2020/01/19 05:18:44 INFO : CopyDest/three.rclone_chunk.001_cei0sb: Deleted 2020/01/19 05:18:45 DEBUG : three: Sizes differ (src 7 vs dst 5) 2020/01/19 05:18:45 DEBUG : three: Destination not found in --copy-dest 2020/01/19 05:18:45 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5/dst': Waiting for checks to finish 2020/01/19 05:18:45 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:18:45 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:18:45 DEBUG : one: Unchanged skipping 2020/01/19 05:18:45 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:18:45 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:18:45 DEBUG : two: Unchanged skipping 2020/01/19 05:18:45 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5/dst': Waiting for transfers to finish 2020/01/19 05:18:45 DEBUG : three.rclone_chunk.001_cei1j7: MD5 = 1bccb9dccb3e9f6a3f9d2a8bdb54b7f5 OK 2020/01/19 05:18:45 INFO : three.rclone_chunk.001_cei1j7: Copied (server side copy) 2020/01/19 05:18:45 INFO : three.rclone_chunk.001_cei1j7: Deleted 2020/01/19 05:18:45 DEBUG : three: MD5 = 1bccb9dccb3e9f6a3f9d2a8bdb54b7f5 OK 2020/01/19 05:18:45 INFO : three: Copied (new) 2020/01/19 05:18:45 INFO : Waiting for deletions to finish --- PASS: TestSyncCopyDest (6.01s) run.go:176: Remote "Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5'", Local "Local file system at /tmp/rclone669054014", Modify Window "1ns" === RUN TestSyncBackupDir 2020/01/19 05:18:46 DEBUG : dst/one.rclone_chunk.001_cei285: MD5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2020/01/19 05:18:46 INFO : dst/one.rclone_chunk.001_cei285: Copied (server side copy) 2020/01/19 05:18:46 INFO : dst/one.rclone_chunk.001_cei285: Deleted 2020/01/19 05:18:46 DEBUG : dst/two.rclone_chunk.001_cei274: MD5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2020/01/19 05:18:46 INFO : dst/two.rclone_chunk.001_cei274: Copied (server side copy) 2020/01/19 05:18:46 INFO : dst/two.rclone_chunk.001_cei274: Deleted 2020/01/19 05:18:46 DEBUG : dst/three.txt.rclone_chunk.001_cei2pb: MD5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2020/01/19 05:18:46 INFO : dst/three.txt.rclone_chunk.001_cei2pb: Copied (server side copy) 2020/01/19 05:18:46 INFO : dst/three.txt.rclone_chunk.001_cei2pb: Deleted 2020/01/19 05:18:46 DEBUG : one: Sizes differ (src 4 vs dst 3) 2020/01/19 05:18:46 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5/dst': Waiting for checks to finish 2020/01/19 05:18:46 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:18:46 DEBUG : two: Unchanged skipping 2020/01/19 05:18:46 DEBUG : one: copy non-chunked object... 2020/01/19 05:18:47 DEBUG : one: MD5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2020/01/19 05:18:47 INFO : one: Copied (server side copy) 2020/01/19 05:18:47 INFO : one: Deleted 2020/01/19 05:18:47 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5/dst': Waiting for transfers to finish 2020/01/19 05:18:47 DEBUG : one.rclone_chunk.001_cei324: MD5 = c7957179c41f69d44f217a108c7915d8 OK 2020/01/19 05:18:47 INFO : one.rclone_chunk.001_cei324: Copied (server side copy) 2020/01/19 05:18:47 INFO : one.rclone_chunk.001_cei324: Deleted 2020/01/19 05:18:47 DEBUG : one: MD5 = c7957179c41f69d44f217a108c7915d8 OK 2020/01/19 05:18:47 INFO : one: Copied (new) 2020/01/19 05:18:47 INFO : Waiting for deletions to finish 2020/01/19 05:18:47 DEBUG : three.txt: copy non-chunked object... 2020/01/19 05:18:47 DEBUG : three.txt: MD5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2020/01/19 05:18:47 INFO : three.txt: Copied (server side copy) 2020/01/19 05:18:47 INFO : three.txt: Deleted 2020/01/19 05:18:47 INFO : three.txt: Moved into backup dir 2020/01/19 05:18:47 DEBUG : dst/three.txt.rclone_chunk.001_cei3ot: MD5 = 91341eed84691a83caea73aa785736d5 OK 2020/01/19 05:18:47 INFO : dst/three.txt.rclone_chunk.001_cei3ot: Copied (server side copy) 2020/01/19 05:18:47 INFO : dst/three.txt.rclone_chunk.001_cei3ot: Deleted 2020/01/19 05:18:47 DEBUG : one: Sizes differ (src 5 vs dst 4) 2020/01/19 05:18:47 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5/dst': Waiting for checks to finish 2020/01/19 05:18:47 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:18:47 DEBUG : two: Unchanged skipping 2020/01/19 05:18:47 DEBUG : one: copy non-chunked object... 2020/01/19 05:18:47 DEBUG : one: MD5 = c7957179c41f69d44f217a108c7915d8 OK 2020/01/19 05:18:47 INFO : one: Copied (server side copy) 2020/01/19 05:18:47 INFO : one: Deleted 2020/01/19 05:18:47 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5/dst': Waiting for transfers to finish 2020/01/19 05:18:47 DEBUG : one.rclone_chunk.001_cei3a8: MD5 = 0f93e81041f0cab37c37a05ae998b219 OK 2020/01/19 05:18:47 INFO : one.rclone_chunk.001_cei3a8: Copied (server side copy) 2020/01/19 05:18:47 INFO : one.rclone_chunk.001_cei3a8: Deleted 2020/01/19 05:18:47 DEBUG : one: MD5 = 0f93e81041f0cab37c37a05ae998b219 OK 2020/01/19 05:18:47 INFO : one: Copied (new) 2020/01/19 05:18:47 INFO : Waiting for deletions to finish 2020/01/19 05:18:48 DEBUG : three.txt: copy non-chunked object... 2020/01/19 05:18:48 DEBUG : three.txt: MD5 = 91341eed84691a83caea73aa785736d5 OK 2020/01/19 05:18:48 INFO : three.txt: Copied (server side copy) 2020/01/19 05:18:48 INFO : three.txt: Deleted 2020/01/19 05:18:48 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (1.89s) run.go:176: Remote "Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5'", Local "Local file system at /tmp/rclone669054014", Modify Window "1ns" === RUN TestSyncBackupDirWithSuffix 2020/01/19 05:18:48 DEBUG : dst/one.rclone_chunk.001_cei4ml: MD5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2020/01/19 05:18:48 INFO : dst/one.rclone_chunk.001_cei4ml: Copied (server side copy) 2020/01/19 05:18:48 INFO : dst/one.rclone_chunk.001_cei4ml: Deleted 2020/01/19 05:18:48 DEBUG : dst/two.rclone_chunk.001_cei4ca: MD5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2020/01/19 05:18:48 INFO : dst/two.rclone_chunk.001_cei4ca: Copied (server side copy) 2020/01/19 05:18:48 INFO : dst/two.rclone_chunk.001_cei4ca: Deleted 2020/01/19 05:18:48 DEBUG : dst/three.txt.rclone_chunk.001_cei45l: MD5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2020/01/19 05:18:48 INFO : dst/three.txt.rclone_chunk.001_cei45l: Copied (server side copy) 2020/01/19 05:18:48 INFO : dst/three.txt.rclone_chunk.001_cei45l: Deleted 2020/01/19 05:18:49 DEBUG : one: Sizes differ (src 4 vs dst 3) 2020/01/19 05:18:49 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5/dst': Waiting for checks to finish 2020/01/19 05:18:49 DEBUG : one: copy non-chunked object... 2020/01/19 05:18:49 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:18:49 DEBUG : two: Unchanged skipping 2020/01/19 05:18:49 DEBUG : one: MD5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2020/01/19 05:18:49 INFO : one: Copied (server side copy) 2020/01/19 05:18:49 INFO : one: Deleted 2020/01/19 05:18:49 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5/dst': Waiting for transfers to finish 2020/01/19 05:18:49 DEBUG : one.rclone_chunk.001_cei5ru: MD5 = c7957179c41f69d44f217a108c7915d8 OK 2020/01/19 05:18:49 INFO : one.rclone_chunk.001_cei5ru: Copied (server side copy) 2020/01/19 05:18:49 INFO : one.rclone_chunk.001_cei5ru: Deleted 2020/01/19 05:18:49 DEBUG : one: MD5 = c7957179c41f69d44f217a108c7915d8 OK 2020/01/19 05:18:49 INFO : one: Copied (new) 2020/01/19 05:18:49 INFO : Waiting for deletions to finish 2020/01/19 05:18:49 DEBUG : three.txt: copy non-chunked object... 2020/01/19 05:18:49 DEBUG : three.txt: MD5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2020/01/19 05:18:49 INFO : three.txt: Copied (server side copy) 2020/01/19 05:18:49 INFO : three.txt: Deleted 2020/01/19 05:18:49 INFO : three.txt: Moved into backup dir 2020/01/19 05:18:50 DEBUG : dst/three.txt.rclone_chunk.001_cei66k: MD5 = 91341eed84691a83caea73aa785736d5 OK 2020/01/19 05:18:50 INFO : dst/three.txt.rclone_chunk.001_cei66k: Copied (server side copy) 2020/01/19 05:18:50 INFO : dst/three.txt.rclone_chunk.001_cei66k: Deleted 2020/01/19 05:18:50 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5/dst': Waiting for checks to finish 2020/01/19 05:18:50 DEBUG : one: Sizes differ (src 5 vs dst 4) 2020/01/19 05:18:50 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:18:50 DEBUG : two: Unchanged skipping 2020/01/19 05:18:50 DEBUG : one: copy non-chunked object... 2020/01/19 05:18:50 DEBUG : one: MD5 = c7957179c41f69d44f217a108c7915d8 OK 2020/01/19 05:18:50 INFO : one: Copied (server side copy) 2020/01/19 05:18:50 INFO : one: Deleted 2020/01/19 05:18:50 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5/dst': Waiting for transfers to finish 2020/01/19 05:18:51 DEBUG : one.rclone_chunk.001_cei6pf: MD5 = 0f93e81041f0cab37c37a05ae998b219 OK 2020/01/19 05:18:51 INFO : one.rclone_chunk.001_cei6pf: Copied (server side copy) 2020/01/19 05:18:51 INFO : one.rclone_chunk.001_cei6pf: Deleted 2020/01/19 05:18:51 DEBUG : one: MD5 = 0f93e81041f0cab37c37a05ae998b219 OK 2020/01/19 05:18:51 INFO : one: Copied (new) 2020/01/19 05:18:51 INFO : Waiting for deletions to finish 2020/01/19 05:18:51 DEBUG : three.txt: copy non-chunked object... 2020/01/19 05:18:51 DEBUG : three.txt: MD5 = 91341eed84691a83caea73aa785736d5 OK 2020/01/19 05:18:51 INFO : three.txt: Copied (server side copy) 2020/01/19 05:18:51 INFO : three.txt: Deleted 2020/01/19 05:18:51 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (3.64s) run.go:176: Remote "Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5'", Local "Local file system at /tmp/rclone669054014", Modify Window "1ns" === RUN TestSyncBackupDirWithSuffixKeepExtension 2020/01/19 05:18:52 DEBUG : dst/one.rclone_chunk.001_cei82i: MD5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2020/01/19 05:18:52 INFO : dst/one.rclone_chunk.001_cei82i: Copied (server side copy) 2020/01/19 05:18:52 INFO : dst/one.rclone_chunk.001_cei82i: Deleted 2020/01/19 05:18:52 DEBUG : dst/two.rclone_chunk.001_cei8q3: MD5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2020/01/19 05:18:52 INFO : dst/two.rclone_chunk.001_cei8q3: Copied (server side copy) 2020/01/19 05:18:52 INFO : dst/two.rclone_chunk.001_cei8q3: Deleted 2020/01/19 05:18:52 DEBUG : dst/three.txt.rclone_chunk.001_cei8ri: MD5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2020/01/19 05:18:52 INFO : dst/three.txt.rclone_chunk.001_cei8ri: Copied (server side copy) 2020/01/19 05:18:52 INFO : dst/three.txt.rclone_chunk.001_cei8ri: Deleted 2020/01/19 05:18:52 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5/dst': Waiting for checks to finish 2020/01/19 05:18:52 DEBUG : one: Sizes differ (src 4 vs dst 3) 2020/01/19 05:18:52 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:18:52 DEBUG : two: Unchanged skipping 2020/01/19 05:18:52 DEBUG : one: copy non-chunked object... 2020/01/19 05:18:53 DEBUG : one: MD5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2020/01/19 05:18:53 INFO : one: Copied (server side copy) 2020/01/19 05:18:53 INFO : one: Deleted 2020/01/19 05:18:53 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5/dst': Waiting for transfers to finish 2020/01/19 05:18:53 DEBUG : one.rclone_chunk.001_cei9kg: MD5 = c7957179c41f69d44f217a108c7915d8 OK 2020/01/19 05:18:53 INFO : one.rclone_chunk.001_cei9kg: Copied (server side copy) 2020/01/19 05:18:53 INFO : one.rclone_chunk.001_cei9kg: Deleted 2020/01/19 05:18:53 DEBUG : one: MD5 = c7957179c41f69d44f217a108c7915d8 OK 2020/01/19 05:18:53 INFO : one: Copied (new) 2020/01/19 05:18:53 INFO : Waiting for deletions to finish 2020/01/19 05:18:53 DEBUG : three.txt: copy non-chunked object... 2020/01/19 05:18:53 DEBUG : three.txt: MD5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2020/01/19 05:18:53 INFO : three.txt: Copied (server side copy) 2020/01/19 05:18:53 INFO : three.txt: Deleted 2020/01/19 05:18:53 INFO : three.txt: Moved into backup dir 2020/01/19 05:18:53 DEBUG : dst/three.txt.rclone_chunk.001_cei9t2: MD5 = 91341eed84691a83caea73aa785736d5 OK 2020/01/19 05:18:53 INFO : dst/three.txt.rclone_chunk.001_cei9t2: Copied (server side copy) 2020/01/19 05:18:53 INFO : dst/three.txt.rclone_chunk.001_cei9t2: Deleted 2020/01/19 05:18:54 DEBUG : one: Sizes differ (src 5 vs dst 4) 2020/01/19 05:18:54 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5/dst': Waiting for checks to finish 2020/01/19 05:18:54 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:18:54 DEBUG : two: Unchanged skipping 2020/01/19 05:18:54 DEBUG : one: copy non-chunked object... 2020/01/19 05:18:54 DEBUG : one: MD5 = c7957179c41f69d44f217a108c7915d8 OK 2020/01/19 05:18:54 INFO : one: Copied (server side copy) 2020/01/19 05:18:54 INFO : one: Deleted 2020/01/19 05:18:54 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5/dst': Waiting for transfers to finish 2020/01/19 05:18:54 DEBUG : one.rclone_chunk.001_ceiax0: MD5 = 0f93e81041f0cab37c37a05ae998b219 OK 2020/01/19 05:18:54 INFO : one.rclone_chunk.001_ceiax0: Copied (server side copy) 2020/01/19 05:18:54 INFO : one.rclone_chunk.001_ceiax0: Deleted 2020/01/19 05:18:54 DEBUG : one: MD5 = 0f93e81041f0cab37c37a05ae998b219 OK 2020/01/19 05:18:54 INFO : one: Copied (new) 2020/01/19 05:18:54 INFO : Waiting for deletions to finish 2020/01/19 05:18:54 DEBUG : three.txt: copy non-chunked object... 2020/01/19 05:18:54 DEBUG : three.txt: MD5 = 91341eed84691a83caea73aa785736d5 OK 2020/01/19 05:18:54 INFO : three.txt: Copied (server side copy) 2020/01/19 05:18:54 INFO : three.txt: Deleted 2020/01/19 05:18:54 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (2.73s) run.go:176: Remote "Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5'", Local "Local file system at /tmp/rclone669054014", Modify Window "1ns" === RUN TestSyncSuffix 2020/01/19 05:18:54 DEBUG : dst/one.rclone_chunk.001_ceiajc: MD5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2020/01/19 05:18:54 INFO : dst/one.rclone_chunk.001_ceiajc: Copied (server side copy) 2020/01/19 05:18:54 INFO : dst/one.rclone_chunk.001_ceiajc: Deleted 2020/01/19 05:18:55 DEBUG : dst/two.rclone_chunk.001_ceia0y: MD5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2020/01/19 05:18:55 INFO : dst/two.rclone_chunk.001_ceia0y: Copied (server side copy) 2020/01/19 05:18:55 INFO : dst/two.rclone_chunk.001_ceia0y: Deleted 2020/01/19 05:18:55 DEBUG : dst/three.txt.rclone_chunk.001_ceibrl: MD5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2020/01/19 05:18:55 INFO : dst/three.txt.rclone_chunk.001_ceibrl: Copied (server side copy) 2020/01/19 05:18:55 INFO : dst/three.txt.rclone_chunk.001_ceibrl: Deleted 2020/01/19 05:18:55 DEBUG : one: Sizes differ (src 4 vs dst 3) 2020/01/19 05:18:55 DEBUG : one: copy non-chunked object... 2020/01/19 05:18:55 DEBUG : one: MD5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2020/01/19 05:18:55 INFO : one: Copied (server side copy) 2020/01/19 05:18:55 INFO : one: Deleted 2020/01/19 05:18:55 DEBUG : one.rclone_chunk.001_ceibyy: MD5 = c7957179c41f69d44f217a108c7915d8 OK 2020/01/19 05:18:55 INFO : one.rclone_chunk.001_ceibyy: Copied (server side copy) 2020/01/19 05:18:55 INFO : one.rclone_chunk.001_ceibyy: Deleted 2020/01/19 05:18:55 DEBUG : one: MD5 = c7957179c41f69d44f217a108c7915d8 OK 2020/01/19 05:18:55 INFO : one: Copied (new) 2020/01/19 05:18:55 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:18:55 DEBUG : two: Unchanged skipping 2020/01/19 05:18:56 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2020/01/19 05:18:56 DEBUG : three.txt: copy non-chunked object... 2020/01/19 05:18:56 DEBUG : three.txt: MD5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2020/01/19 05:18:56 INFO : three.txt: Copied (server side copy) 2020/01/19 05:18:56 INFO : three.txt: Deleted 2020/01/19 05:18:56 DEBUG : three.txt.rclone_chunk.001_ceicg2: MD5 = 91341eed84691a83caea73aa785736d5 OK 2020/01/19 05:18:56 INFO : three.txt.rclone_chunk.001_ceicg2: Copied (server side copy) 2020/01/19 05:18:56 INFO : three.txt.rclone_chunk.001_ceicg2: Deleted 2020/01/19 05:18:56 DEBUG : three.txt: MD5 = 91341eed84691a83caea73aa785736d5 OK 2020/01/19 05:18:56 INFO : three.txt: Copied (new) 2020/01/19 05:18:56 DEBUG : one: Sizes differ (src 5 vs dst 4) 2020/01/19 05:18:57 DEBUG : one: copy non-chunked object... 2020/01/19 05:18:57 DEBUG : one: MD5 = c7957179c41f69d44f217a108c7915d8 OK 2020/01/19 05:18:57 INFO : one: Copied (server side copy) 2020/01/19 05:18:57 INFO : one: Deleted 2020/01/19 05:18:57 DEBUG : one.rclone_chunk.001_ceidfr: MD5 = 0f93e81041f0cab37c37a05ae998b219 OK 2020/01/19 05:18:57 INFO : one.rclone_chunk.001_ceidfr: Copied (server side copy) 2020/01/19 05:18:57 INFO : one.rclone_chunk.001_ceidfr: Deleted 2020/01/19 05:18:57 DEBUG : one: MD5 = 0f93e81041f0cab37c37a05ae998b219 OK 2020/01/19 05:18:57 INFO : one: Copied (new) 2020/01/19 05:18:57 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:18:57 DEBUG : two: Unchanged skipping 2020/01/19 05:18:57 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2020/01/19 05:18:57 DEBUG : three.txt: copy non-chunked object... 2020/01/19 05:18:57 DEBUG : three.txt: MD5 = 91341eed84691a83caea73aa785736d5 OK 2020/01/19 05:18:57 INFO : three.txt: Copied (server side copy) 2020/01/19 05:18:57 INFO : three.txt: Deleted 2020/01/19 05:18:57 DEBUG : three.txt.rclone_chunk.001_ceidf2: MD5 = 423e01c5e84a30d129b97aedb6e219a9 OK 2020/01/19 05:18:57 INFO : three.txt.rclone_chunk.001_ceidf2: Copied (server side copy) 2020/01/19 05:18:57 INFO : three.txt.rclone_chunk.001_ceidf2: Deleted 2020/01/19 05:18:57 DEBUG : three.txt: MD5 = 423e01c5e84a30d129b97aedb6e219a9 OK 2020/01/19 05:18:57 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (3.05s) run.go:176: Remote "Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5'", Local "Local file system at /tmp/rclone669054014", Modify Window "1ns" === RUN TestSyncSuffixKeepExtension 2020/01/19 05:18:57 DEBUG : dst/one.rclone_chunk.001_ceid4g: MD5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2020/01/19 05:18:57 INFO : dst/one.rclone_chunk.001_ceid4g: Copied (server side copy) 2020/01/19 05:18:57 INFO : dst/one.rclone_chunk.001_ceid4g: Deleted 2020/01/19 05:18:58 DEBUG : dst/two.rclone_chunk.001_ceidsp: MD5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2020/01/19 05:18:58 INFO : dst/two.rclone_chunk.001_ceidsp: Copied (server side copy) 2020/01/19 05:18:58 INFO : dst/two.rclone_chunk.001_ceidsp: Deleted 2020/01/19 05:18:58 DEBUG : dst/three.txt.rclone_chunk.001_ceie7f: MD5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2020/01/19 05:18:58 INFO : dst/three.txt.rclone_chunk.001_ceie7f: Copied (server side copy) 2020/01/19 05:18:58 INFO : dst/three.txt.rclone_chunk.001_ceie7f: Deleted 2020/01/19 05:18:58 DEBUG : one: Sizes differ (src 4 vs dst 3) 2020/01/19 05:18:58 DEBUG : one: copy non-chunked object... 2020/01/19 05:18:58 DEBUG : one: MD5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2020/01/19 05:18:58 INFO : one: Copied (server side copy) 2020/01/19 05:18:58 INFO : one: Deleted 2020/01/19 05:18:58 DEBUG : one.rclone_chunk.001_ceiezs: MD5 = c7957179c41f69d44f217a108c7915d8 OK 2020/01/19 05:18:58 INFO : one.rclone_chunk.001_ceiezs: Copied (server side copy) 2020/01/19 05:18:58 INFO : one.rclone_chunk.001_ceiezs: Deleted 2020/01/19 05:18:58 DEBUG : one: MD5 = c7957179c41f69d44f217a108c7915d8 OK 2020/01/19 05:18:58 INFO : one: Copied (new) 2020/01/19 05:18:58 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:18:58 DEBUG : two: Unchanged skipping 2020/01/19 05:18:58 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2020/01/19 05:18:58 DEBUG : three.txt: copy non-chunked object... 2020/01/19 05:18:58 DEBUG : three.txt: MD5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2020/01/19 05:18:58 INFO : three.txt: Copied (server side copy) 2020/01/19 05:18:58 INFO : three.txt: Deleted 2020/01/19 05:18:58 DEBUG : three.txt.rclone_chunk.001_ceiest: MD5 = 91341eed84691a83caea73aa785736d5 OK 2020/01/19 05:18:58 INFO : three.txt.rclone_chunk.001_ceiest: Copied (server side copy) 2020/01/19 05:18:58 INFO : three.txt.rclone_chunk.001_ceiest: Deleted 2020/01/19 05:18:58 DEBUG : three.txt: MD5 = 91341eed84691a83caea73aa785736d5 OK 2020/01/19 05:18:58 INFO : three.txt: Copied (new) 2020/01/19 05:18:58 DEBUG : one: Sizes differ (src 5 vs dst 4) 2020/01/19 05:18:59 DEBUG : one: copy non-chunked object... 2020/01/19 05:18:59 DEBUG : one: MD5 = c7957179c41f69d44f217a108c7915d8 OK 2020/01/19 05:18:59 INFO : one: Copied (server side copy) 2020/01/19 05:18:59 INFO : one: Deleted 2020/01/19 05:18:59 DEBUG : one.rclone_chunk.001_ceif3m: MD5 = 0f93e81041f0cab37c37a05ae998b219 OK 2020/01/19 05:18:59 INFO : one.rclone_chunk.001_ceif3m: Copied (server side copy) 2020/01/19 05:18:59 INFO : one.rclone_chunk.001_ceif3m: Deleted 2020/01/19 05:18:59 DEBUG : one: MD5 = 0f93e81041f0cab37c37a05ae998b219 OK 2020/01/19 05:18:59 INFO : one: Copied (new) 2020/01/19 05:18:59 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:18:59 DEBUG : two: Unchanged skipping 2020/01/19 05:18:59 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2020/01/19 05:18:59 DEBUG : three.txt: copy non-chunked object... 2020/01/19 05:18:59 DEBUG : three.txt: MD5 = 91341eed84691a83caea73aa785736d5 OK 2020/01/19 05:18:59 INFO : three.txt: Copied (server side copy) 2020/01/19 05:18:59 INFO : three.txt: Deleted 2020/01/19 05:18:59 DEBUG : three.txt.rclone_chunk.001_ceifjn: MD5 = 423e01c5e84a30d129b97aedb6e219a9 OK 2020/01/19 05:18:59 INFO : three.txt.rclone_chunk.001_ceifjn: Copied (server side copy) 2020/01/19 05:18:59 INFO : three.txt.rclone_chunk.001_ceifjn: Deleted 2020/01/19 05:18:59 DEBUG : three.txt: MD5 = 423e01c5e84a30d129b97aedb6e219a9 OK 2020/01/19 05:18:59 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (1.85s) run.go:176: Remote "Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5'", Local "Local file system at /tmp/rclone669054014", Modify Window "1ns" === RUN TestSyncUTFNorm 2020/01/19 05:18:59 DEBUG : Testêé.rclone_chunk.001_ceife8: MD5 = 3b5c716b1f94ae30389ea40ea76fa0d5 OK 2020/01/19 05:18:59 INFO : Testêé.rclone_chunk.001_ceife8: Copied (server side copy) 2020/01/19 05:18:59 INFO : Testêé.rclone_chunk.001_ceife8: Deleted 2020/01/19 05:19:00 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for checks to finish 2020/01/19 05:19:00 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2020/01/19 05:19:00 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for transfers to finish 2020/01/19 05:19:00 DEBUG : Testêé.rclone_chunk.001_ceig0i: MD5 = ce114e4501d2f4e2dcea3e17b546f339 OK 2020/01/19 05:19:00 INFO : Testêé.rclone_chunk.001_ceig0i: Copied (server side copy) 2020/01/19 05:19:00 INFO : Testêé.rclone_chunk.001_ceig0i: Deleted 2020/01/19 05:19:00 DEBUG : Testêé: MD5 = ce114e4501d2f4e2dcea3e17b546f339 OK 2020/01/19 05:19:00 INFO : Testêé: Copied (replaced existing) 2020/01/19 05:19:00 INFO : Waiting for deletions to finish --- PASS: TestSyncUTFNorm (0.62s) run.go:176: Remote "Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5'", Local "Local file system at /tmp/rclone669054014", Modify Window "1ns" === RUN TestSyncImmutable 2020/01/19 05:19:00 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for checks to finish 2020/01/19 05:19:00 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for transfers to finish 2020/01/19 05:19:00 DEBUG : existing.rclone_chunk.001_ceig6r: MD5 = 8ee2027983915ec78acc45027d874316 OK 2020/01/19 05:19:00 INFO : existing.rclone_chunk.001_ceig6r: Copied (server side copy) 2020/01/19 05:19:00 INFO : existing.rclone_chunk.001_ceig6r: Deleted 2020/01/19 05:19:00 DEBUG : existing: MD5 = 8ee2027983915ec78acc45027d874316 OK 2020/01/19 05:19:00 INFO : existing: Copied (new) 2020/01/19 05:19:00 INFO : Waiting for deletions to finish 2020/01/19 05:19:01 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2020/01/19 05:19:01 ERROR : existing: Source and destination exist but do not match: immutable file modified 2020/01/19 05:19:01 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for checks to finish 2020/01/19 05:19:01 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for transfers to finish 2020/01/19 05:19:01 ERROR : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': not deleting files as there were IO errors 2020/01/19 05:19:01 ERROR : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': not deleting directories as there were IO errors --- PASS: TestSyncImmutable (1.20s) run.go:176: Remote "Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5'", Local "Local file system at /tmp/rclone669054014", Modify Window "1ns" === RUN TestSyncIgnoreCase 2020/01/19 05:19:01 DEBUG : EXISTING.rclone_chunk.001_ceihk1: MD5 = 8ee2027983915ec78acc45027d874316 OK 2020/01/19 05:19:01 INFO : EXISTING.rclone_chunk.001_ceihk1: Copied (server side copy) 2020/01/19 05:19:01 INFO : EXISTING.rclone_chunk.001_ceihk1: Deleted 2020/01/19 05:19:01 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for checks to finish 2020/01/19 05:19:01 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/01/19 05:19:01 DEBUG : existing: Unchanged skipping 2020/01/19 05:19:01 INFO : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Waiting for transfers to finish 2020/01/19 05:19:01 INFO : Waiting for deletions to finish --- PASS: TestSyncIgnoreCase (0.47s) run.go:176: Remote "Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5'", Local "Local file system at /tmp/rclone669054014", Modify Window "1ns" === RUN TestAbort --- SKIP: TestAbort (0.02s) run.go:176: Remote "Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5'", Local "Local file system at /tmp/rclone669054014", Modify Window "1ns" sync_test.go:1741: This test only runs on local PASS 2020/01/19 05:19:02 DEBUG : Chunked 'TestChunkerS3:rclone-test-curozov7demelud8binesak5': Purge dir "" 2020/01/19 05:19:02 INFO : S3 bucket rclone-test-curozov7demelud8binesak5: Bucket "rclone-test-curozov7demelud8binesak5" deleted "./sync.test -test.v -test.timeout 30m0s -remote TestChunkerS3: -verbose" - Finished OK in 1m11.873528985s (try 1/5)