"./sync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -fast-list" - Starting (try 1/5) 2021/04/05 05:52:21 DEBUG : Creating backend with remote "TestB2:rclone-test-qokuher9zohutep2binudaq8" 2021/04/05 05:52:22 DEBUG : Creating backend with remote "/tmp/rclone391697543" === RUN TestPipe --- PASS: TestPipe (0.00s) === RUN TestPipeConcurrent --- PASS: TestPipeConcurrent (0.07s) === RUN TestPipeOrderBy === RUN TestPipeOrderBy/#00 === RUN TestPipeOrderBy/size === RUN TestPipeOrderBy/name === RUN TestPipeOrderBy/modtime === RUN TestPipeOrderBy/size,ascending === RUN TestPipeOrderBy/name,asc === RUN TestPipeOrderBy/modtime,ascending === RUN TestPipeOrderBy/size,descending === RUN TestPipeOrderBy/name,desc === RUN TestPipeOrderBy/modtime,descending === RUN TestPipeOrderBy/size,mixed,50 === RUN TestPipeOrderBy/size,mixed,51 --- PASS: TestPipeOrderBy (0.00s) --- PASS: TestPipeOrderBy/#00 (0.00s) --- PASS: TestPipeOrderBy/size (0.00s) --- PASS: TestPipeOrderBy/name (0.00s) --- PASS: TestPipeOrderBy/modtime (0.00s) --- PASS: TestPipeOrderBy/size,ascending (0.00s) --- PASS: TestPipeOrderBy/name,asc (0.00s) --- PASS: TestPipeOrderBy/modtime,ascending (0.00s) --- PASS: TestPipeOrderBy/size,descending (0.00s) --- PASS: TestPipeOrderBy/name,desc (0.00s) --- PASS: TestPipeOrderBy/modtime,descending (0.00s) --- PASS: TestPipeOrderBy/size,mixed,50 (0.00s) --- PASS: TestPipeOrderBy/size,mixed,51 (0.00s) === RUN TestNewLess === RUN TestNewLess/blankOK === RUN TestNewLess/tooManyParts === RUN TestNewLess/tooManyParts2 === RUN TestNewLess/badMixed === RUN TestNewLess/unknownComparison === RUN TestNewLess/unknownSortDirection === RUN TestNewLess/size === RUN TestNewLess/name === RUN TestNewLess/modtime === RUN TestNewLess/size,ascending === RUN TestNewLess/name,asc === RUN TestNewLess/modtime,ascending === RUN TestNewLess/size,descending === RUN TestNewLess/name,desc === RUN TestNewLess/modtime,descending === RUN TestNewLess/modtime,mixed === RUN TestNewLess/modtime,mixed,30 --- PASS: TestNewLess (0.00s) --- PASS: TestNewLess/blankOK (0.00s) --- PASS: TestNewLess/tooManyParts (0.00s) --- PASS: TestNewLess/tooManyParts2 (0.00s) --- PASS: TestNewLess/badMixed (0.00s) --- PASS: TestNewLess/unknownComparison (0.00s) --- PASS: TestNewLess/unknownSortDirection (0.00s) --- PASS: TestNewLess/size (0.00s) --- PASS: TestNewLess/name (0.00s) --- PASS: TestNewLess/modtime (0.00s) --- PASS: TestNewLess/size,ascending (0.00s) --- PASS: TestNewLess/name,asc (0.00s) --- PASS: TestNewLess/modtime,ascending (0.00s) --- PASS: TestNewLess/size,descending (0.00s) --- PASS: TestNewLess/name,desc (0.00s) --- PASS: TestNewLess/modtime,descending (0.00s) --- PASS: TestNewLess/modtime,mixed (0.00s) --- PASS: TestNewLess/modtime,mixed,30 (0.00s) === RUN TestRcCopy rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcCopy (0.00s) === RUN TestRcMove rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcMove (0.00s) === RUN TestRcSync rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcSync (0.00s) === RUN TestCopyWithDryRun run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" 2021/04/05 05:52:23 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) 2021/04/05 05:52:23 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for checks to finish 2021/04/05 05:52:23 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for transfers to finish --- PASS: TestCopyWithDryRun (1.38s) === RUN TestCopy run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" 2021/04/05 05:52:23 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for checks to finish 2021/04/05 05:52:23 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for transfers to finish 2021/04/05 05:52:25 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/04/05 05:52:25 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopy (2.28s) === RUN TestCopyMissingDirectory run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" 2021/04/05 05:52:25 DEBUG : Creating backend with remote "/non-existing" 2021/04/05 05:52:26 ERROR : : error reading source directory: directory not found 2021/04/05 05:52:26 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for checks to finish 2021/04/05 05:52:26 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for transfers to finish --- PASS: TestCopyMissingDirectory (0.42s) === RUN TestCopyNoTraverse run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" 2021/04/05 05:52:26 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:52:26 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for checks to finish 2021/04/05 05:52:26 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for transfers to finish 2021/04/05 05:52:27 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/04/05 05:52:27 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoTraverse (1.94s) === RUN TestCopyCheckFirst run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" 2021/04/05 05:52:28 INFO : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Running all checks before starting transfers 2021/04/05 05:52:28 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for checks to finish 2021/04/05 05:52:28 INFO : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Checks finished, now starting transfers 2021/04/05 05:52:28 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for transfers to finish 2021/04/05 05:52:29 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/04/05 05:52:29 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyCheckFirst (2.12s) === RUN TestSyncNoTraverse run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" 2021/04/05 05:52:30 ERROR : Ignoring --no-traverse with sync 2021/04/05 05:52:30 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for checks to finish 2021/04/05 05:52:30 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for transfers to finish 2021/04/05 05:52:31 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/04/05 05:52:31 INFO : sub dir/hello world: Copied (new) 2021/04/05 05:52:31 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoTraverse (1.69s) === RUN TestCopyWithDepth run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" 2021/04/05 05:52:32 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for checks to finish 2021/04/05 05:52:32 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for transfers to finish 2021/04/05 05:52:33 DEBUG : hello world2: SHA-1 = c0bf117f9c93d02c894fe8ffd6e5cecf3545c209 OK 2021/04/05 05:52:33 INFO : hello world2: Copied (new) --- PASS: TestCopyWithDepth (1.98s) === RUN TestCopyWithFilesFrom run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" 2021/04/05 05:52:34 DEBUG : hello world2: Excluded 2021/04/05 05:52:34 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for checks to finish 2021/04/05 05:52:34 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for transfers to finish 2021/04/05 05:52:35 DEBUG : potato2: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/04/05 05:52:35 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFrom (4.31s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" 2021/04/05 05:52:38 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:52:38 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for checks to finish 2021/04/05 05:52:38 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for transfers to finish 2021/04/05 05:52:41 DEBUG : potato2: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/04/05 05:52:41 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFromAndNoTraverse (3.68s) === RUN TestCopyEmptyDirectories run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" 2021/04/05 05:52:42 DEBUG : sub dir2: Making directory 2021/04/05 05:52:42 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for checks to finish 2021/04/05 05:52:42 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for transfers to finish 2021/04/05 05:52:42 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/04/05 05:52:42 INFO : sub dir/hello world: Copied (new) 2021/04/05 05:52:42 DEBUG : sub dir2: Making directory 2021/04/05 05:52:42 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: copied 1 directories fstest.go:251: Filtering empty directory "sub dir2" --- PASS: TestCopyEmptyDirectories (1.72s) === RUN TestMoveEmptyDirectories run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" 2021/04/05 05:52:43 DEBUG : sub dir2: Making directory 2021/04/05 05:52:43 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for checks to finish 2021/04/05 05:52:43 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for transfers to finish 2021/04/05 05:52:47 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/04/05 05:52:47 INFO : sub dir/hello world: Copied (new) 2021/04/05 05:52:47 INFO : sub dir/hello world: Deleted 2021/04/05 05:52:47 DEBUG : sub dir: Making directory 2021/04/05 05:52:47 DEBUG : sub dir2: Making directory 2021/04/05 05:52:47 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: copied 2 directories fstest.go:251: Filtering empty directory "sub dir2" --- PASS: TestMoveEmptyDirectories (4.15s) === RUN TestSyncEmptyDirectories run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" 2021/04/05 05:52:47 DEBUG : sub dir2: Making directory 2021/04/05 05:52:48 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for checks to finish 2021/04/05 05:52:48 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for transfers to finish 2021/04/05 05:52:49 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/04/05 05:52:49 INFO : sub dir/hello world: Copied (new) 2021/04/05 05:52:49 DEBUG : sub dir2: Making directory 2021/04/05 05:52:49 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: copied 1 directories 2021/04/05 05:52:49 DEBUG : Waiting for deletions to finish fstest.go:251: Filtering empty directory "sub dir2" --- PASS: TestSyncEmptyDirectories (1.95s) === RUN TestServerSideCopy run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" 2021/04/05 05:52:50 DEBUG : Creating backend with remote "TestB2:rclone-test-xoqomex7kuvivos8wapagip8" sync_test.go:281: Server side copy (if possible) B2 bucket rclone-test-qokuher9zohutep2binudaq8 -> B2 bucket rclone-test-xoqomex7kuvivos8wapagip8 2021/04/05 05:52:51 DEBUG : B2 bucket rclone-test-xoqomex7kuvivos8wapagip8: Waiting for checks to finish 2021/04/05 05:52:51 DEBUG : B2 bucket rclone-test-xoqomex7kuvivos8wapagip8: Waiting for transfers to finish 2021/04/05 05:52:52 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/04/05 05:52:52 INFO : sub dir/hello world: Copied (server-side copy) 2021/04/05 05:52:52 DEBUG : B2 bucket rclone-test-xoqomex7kuvivos8wapagip8: Purge remote 2021/04/05 05:52:52 DEBUG : sub dir/hello world: Deleting (id "4_z1f624d73907dab0b7f880e1b_f1025c7f524f88b55_d20210405_m055252_c001_v0001137_t0020") --- PASS: TestServerSideCopy (4.42s) === RUN TestCopyAfterDelete run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" 2021/04/05 05:52:55 ERROR : : error listing: directory not found 2021/04/05 05:52:55 DEBUG : Local file system at /tmp/rclone391697543: Making directory 2021/04/05 05:52:55 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for checks to finish 2021/04/05 05:52:55 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for transfers to finish --- PASS: TestCopyAfterDelete (2.04s) === RUN TestCopyRedownload run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" 2021/04/05 05:52:57 DEBUG : Local file system at /tmp/rclone391697543: Waiting for checks to finish 2021/04/05 05:52:57 DEBUG : Local file system at /tmp/rclone391697543: Waiting for transfers to finish 2021/04/05 05:52:57 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/04/05 05:52:57 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyRedownload (2.17s) === RUN TestSyncBasedOnCheckSum run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" 2021/04/05 05:52:58 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for checks to finish 2021/04/05 05:52:58 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for transfers to finish 2021/04/05 05:52:59 DEBUG : check sum: SHA-1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2021/04/05 05:52:59 INFO : check sum: Copied (new) 2021/04/05 05:52:59 DEBUG : Waiting for deletions to finish 2021/04/05 05:52:59 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for checks to finish 2021/04/05 05:52:59 DEBUG : check sum: SHA-1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2021/04/05 05:52:59 DEBUG : check sum: Size and SHA-1 of src and dst objects identical 2021/04/05 05:52:59 DEBUG : check sum: Unchanged skipping 2021/04/05 05:52:59 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for transfers to finish 2021/04/05 05:52:59 DEBUG : Waiting for deletions to finish 2021/04/05 05:52:59 INFO : There was nothing to transfer --- PASS: TestSyncBasedOnCheckSum (2.24s) === RUN TestSyncSizeOnly run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" 2021/04/05 05:53:00 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for checks to finish 2021/04/05 05:53:00 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for transfers to finish 2021/04/05 05:53:02 DEBUG : sizeonly: SHA-1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2021/04/05 05:53:02 INFO : sizeonly: Copied (new) 2021/04/05 05:53:02 DEBUG : Waiting for deletions to finish 2021/04/05 05:53:02 DEBUG : sizeonly: Sizes identical 2021/04/05 05:53:02 DEBUG : sizeonly: Unchanged skipping 2021/04/05 05:53:02 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for checks to finish 2021/04/05 05:53:02 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for transfers to finish 2021/04/05 05:53:02 DEBUG : Waiting for deletions to finish 2021/04/05 05:53:02 INFO : There was nothing to transfer --- PASS: TestSyncSizeOnly (3.08s) === RUN TestSyncIgnoreSize run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" 2021/04/05 05:53:04 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for checks to finish 2021/04/05 05:53:04 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for transfers to finish 2021/04/05 05:53:04 DEBUG : ignore-size: SHA-1 = 4a756ca07e9487f482465a99e8286abc86ba4dc7 OK 2021/04/05 05:53:04 INFO : ignore-size: Copied (new) 2021/04/05 05:53:04 DEBUG : Waiting for deletions to finish 2021/04/05 05:53:05 DEBUG : ignore-size: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/04/05 05:53:05 DEBUG : ignore-size: Unchanged skipping 2021/04/05 05:53:05 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for checks to finish 2021/04/05 05:53:05 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for transfers to finish 2021/04/05 05:53:05 DEBUG : Waiting for deletions to finish 2021/04/05 05:53:05 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreSize (2.18s) === RUN TestSyncIgnoreTimes run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" 2021/04/05 05:53:07 DEBUG : existing: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/04/05 05:53:07 DEBUG : existing: Unchanged skipping 2021/04/05 05:53:07 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for checks to finish 2021/04/05 05:53:07 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for transfers to finish 2021/04/05 05:53:07 DEBUG : Waiting for deletions to finish 2021/04/05 05:53:07 INFO : There was nothing to transfer 2021/04/05 05:53:07 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2021/04/05 05:53:07 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for checks to finish 2021/04/05 05:53:07 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for transfers to finish 2021/04/05 05:53:08 DEBUG : existing: SHA-1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2021/04/05 05:53:08 INFO : existing: Copied (replaced existing) 2021/04/05 05:53:08 DEBUG : Waiting for deletions to finish --- PASS: TestSyncIgnoreTimes (3.31s) === RUN TestSyncIgnoreExisting run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" 2021/04/05 05:53:09 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for checks to finish 2021/04/05 05:53:09 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for transfers to finish 2021/04/05 05:53:10 DEBUG : existing: SHA-1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2021/04/05 05:53:10 INFO : existing: Copied (new) 2021/04/05 05:53:10 DEBUG : Waiting for deletions to finish 2021/04/05 05:53:10 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for checks to finish 2021/04/05 05:53:10 DEBUG : existing: Destination exists, skipping 2021/04/05 05:53:10 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for transfers to finish 2021/04/05 05:53:10 DEBUG : Waiting for deletions to finish 2021/04/05 05:53:10 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreExisting (2.49s) === RUN TestSyncIgnoreErrors run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" 2021/04/05 05:53:13 DEBUG : d: Making directory fstest.go:251: Filtering empty directory "d" 2021/04/05 05:53:13 DEBUG : c/non empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/04/05 05:53:13 DEBUG : c/non empty space: Unchanged skipping 2021/04/05 05:53:13 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for checks to finish 2021/04/05 05:53:13 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for transfers to finish 2021/04/05 05:53:14 DEBUG : a/potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/04/05 05:53:14 INFO : a/potato2: Copied (new) 2021/04/05 05:53:14 DEBUG : Waiting for deletions to finish 2021/04/05 05:53:15 INFO : b/potato: Deleted --- PASS: TestSyncIgnoreErrors (4.72s) === RUN TestSyncAfterChangingModtimeOnly run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" 2021/04/05 05:53:21 DEBUG : empty space: Modification times differ by -95456h54m52.624456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499 +0000 UTC 2021/04/05 05:53:21 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for checks to finish 2021/04/05 05:53:21 DEBUG : empty space: SHA-1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2021/04/05 05:53:21 NOTICE: empty space: Skipped update modification time as --dry-run is set (size 1) 2021/04/05 05:53:21 DEBUG : empty space: Unchanged skipping 2021/04/05 05:53:21 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for transfers to finish 2021/04/05 05:53:21 DEBUG : Waiting for deletions to finish 2021/04/05 05:53:21 INFO : There was nothing to transfer 2021/04/05 05:53:21 DEBUG : empty space: Modification times differ by -95456h54m52.624456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499 +0000 UTC 2021/04/05 05:53:21 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for checks to finish 2021/04/05 05:53:21 DEBUG : empty space: SHA-1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2021/04/05 05:53:22 INFO : empty space: Updated modification time in destination 2021/04/05 05:53:22 DEBUG : empty space: Unchanged skipping 2021/04/05 05:53:22 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for transfers to finish 2021/04/05 05:53:22 DEBUG : Waiting for deletions to finish 2021/04/05 05:53:22 INFO : There was nothing to transfer --- PASS: TestSyncAfterChangingModtimeOnly (7.03s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" 2021/04/05 05:53:24 DEBUG : empty space: Modification times differ by -95456h54m52.624456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499 +0000 UTC 2021/04/05 05:53:24 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for checks to finish 2021/04/05 05:53:24 DEBUG : empty space: SHA-1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2021/04/05 05:53:24 DEBUG : empty space: Unchanged skipping 2021/04/05 05:53:24 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for transfers to finish 2021/04/05 05:53:24 DEBUG : Waiting for deletions to finish 2021/04/05 05:53:24 INFO : There was nothing to transfer --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (2.56s) === RUN TestSyncDoesntUpdateModtime run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" 2021/04/05 05:53:27 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for checks to finish 2021/04/05 05:53:27 DEBUG : foo: Modification times differ by -95456h54m52.624456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499 +0000 UTC 2021/04/05 05:53:27 DEBUG : foo: SHA-1 = 0beec7b5ea3f0fdbc95d0dd47f3c5bc275da8a33 (Local file system at /tmp/rclone391697543) 2021/04/05 05:53:27 DEBUG : foo: SHA-1 = 62cdb7020ff920e5aa642c3d4066950dd1f01f4d (B2 bucket rclone-test-qokuher9zohutep2binudaq8) 2021/04/05 05:53:27 DEBUG : foo: SHA-1 differ 2021/04/05 05:53:27 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for transfers to finish 2021/04/05 05:53:30 DEBUG : foo: SHA-1 = 0beec7b5ea3f0fdbc95d0dd47f3c5bc275da8a33 OK 2021/04/05 05:53:30 INFO : foo: Copied (replaced existing) 2021/04/05 05:53:30 DEBUG : Waiting for deletions to finish --- PASS: TestSyncDoesntUpdateModtime (4.83s) === RUN TestSyncAfterAddingAFile run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" 2021/04/05 05:53:32 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/04/05 05:53:32 DEBUG : empty space: Unchanged skipping 2021/04/05 05:53:32 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for checks to finish 2021/04/05 05:53:32 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for transfers to finish 2021/04/05 05:53:33 DEBUG : potato: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/04/05 05:53:33 INFO : potato: Copied (new) 2021/04/05 05:53:33 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterAddingAFile (4.41s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" 2021/04/05 05:53:36 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for checks to finish 2021/04/05 05:53:36 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2021/04/05 05:53:36 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for transfers to finish 2021/04/05 05:53:37 DEBUG : potato: SHA-1 = 0fd321a1023ac4e1e1054fcc0236b9d0755f722f OK 2021/04/05 05:53:37 INFO : potato: Copied (replaced existing) 2021/04/05 05:53:37 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingFilesSizeOnly (2.99s) === RUN TestSyncAfterChangingContentsOnly run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" 2021/04/05 05:53:39 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 2021/04/05 05:53:39 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for checks to finish 2021/04/05 05:53:39 DEBUG : potato: SHA-1 = 1ca5094b3b73f587249eabe179c40a3eec839b1f (Local file system at /tmp/rclone391697543) 2021/04/05 05:53:39 DEBUG : potato: SHA-1 = 0fd321a1023ac4e1e1054fcc0236b9d0755f722f (B2 bucket rclone-test-qokuher9zohutep2binudaq8) 2021/04/05 05:53:39 DEBUG : potato: SHA-1 differ 2021/04/05 05:53:39 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for transfers to finish 2021/04/05 05:53:40 DEBUG : potato: SHA-1 = 1ca5094b3b73f587249eabe179c40a3eec839b1f OK 2021/04/05 05:53:40 INFO : potato: Copied (replaced existing) 2021/04/05 05:53:40 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingContentsOnly (2.87s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" 2021/04/05 05:53:42 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2021/04/05 05:53:42 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for checks to finish 2021/04/05 05:53:42 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/04/05 05:53:42 DEBUG : empty space: Unchanged skipping 2021/04/05 05:53:42 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for transfers to finish 2021/04/05 05:53:42 DEBUG : Waiting for deletions to finish 2021/04/05 05:53:42 NOTICE: potato: Skipped delete as --dry-run is set (size 21) --- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (4.15s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" 2021/04/05 05:53:47 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/04/05 05:53:47 DEBUG : empty space: Unchanged skipping 2021/04/05 05:53:47 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for checks to finish 2021/04/05 05:53:47 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for transfers to finish 2021/04/05 05:53:48 DEBUG : potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/04/05 05:53:48 INFO : potato2: Copied (new) 2021/04/05 05:53:48 DEBUG : Waiting for deletions to finish 2021/04/05 05:53:48 INFO : potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (4.80s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" 2021/04/05 05:53:51 DEBUG : d: Making directory 2021/04/05 05:53:51 DEBUG : d/e: Making directory fstest.go:251: Filtering empty directory "d" fstest.go:251: Filtering empty directory "d/e" 2021/04/05 05:53:52 DEBUG : c/non empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/04/05 05:53:52 DEBUG : c/non empty space: Unchanged skipping 2021/04/05 05:53:52 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for checks to finish 2021/04/05 05:53:52 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for transfers to finish 2021/04/05 05:53:52 DEBUG : a/potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/04/05 05:53:52 INFO : a/potato2: Copied (new) 2021/04/05 05:53:52 DEBUG : Waiting for deletions to finish 2021/04/05 05:53:52 INFO : b/potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (3.83s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" 2021/04/05 05:53:55 DEBUG : d: Making directory fstest.go:251: Filtering empty directory "d" 2021/04/05 05:53:56 DEBUG : c/non empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/04/05 05:53:56 DEBUG : c/non empty space: Unchanged skipping 2021/04/05 05:53:56 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for checks to finish 2021/04/05 05:53:56 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for transfers to finish 2021/04/05 05:53:57 DEBUG : a/potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/04/05 05:53:57 INFO : a/potato2: Copied (new) 2021/04/05 05:53:57 ERROR : B2 bucket rclone-test-qokuher9zohutep2binudaq8: not deleting files as there were IO errors 2021/04/05 05:53:57 ERROR : B2 bucket rclone-test-qokuher9zohutep2binudaq8: not deleting directories as there were IO errors fstest.go:251: Filtering empty directory "d" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (4.92s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" 2021/04/05 05:54:00 DEBUG : Waiting for deletions to finish 2021/04/05 05:54:00 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/04/05 05:54:00 DEBUG : empty space: Unchanged skipping 2021/04/05 05:54:00 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for checks to finish 2021/04/05 05:54:00 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for transfers to finish 2021/04/05 05:54:01 INFO : potato: Deleted 2021/04/05 05:54:02 DEBUG : potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/04/05 05:54:02 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteDuring (4.26s) === RUN TestSyncDeleteBefore run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" 2021/04/05 05:54:05 DEBUG : Waiting for deletions to finish 2021/04/05 05:54:05 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for checks to finish 2021/04/05 05:54:05 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for transfers to finish 2021/04/05 05:54:05 INFO : potato: Deleted 2021/04/05 05:54:05 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/04/05 05:54:05 DEBUG : empty space: Unchanged skipping 2021/04/05 05:54:05 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for checks to finish 2021/04/05 05:54:05 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for transfers to finish 2021/04/05 05:54:06 DEBUG : potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/04/05 05:54:06 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteBefore (6.23s) === RUN TestCopyDeleteBefore run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" 2021/04/05 05:54:10 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for checks to finish 2021/04/05 05:54:10 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for transfers to finish 2021/04/05 05:54:11 DEBUG : potato2: SHA-1 = 810274f0df19dab60a894a562b7a8ff04c072623 OK 2021/04/05 05:54:11 INFO : potato2: Copied (new) --- PASS: TestCopyDeleteBefore (3.66s) === RUN TestSyncWithExclude run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" 2021/04/05 05:54:14 DEBUG : enormous: Excluded 2021/04/05 05:54:14 DEBUG : potato2: Excluded 2021/04/05 05:54:15 DEBUG : potato2: Excluded from sync (and deletion) 2021/04/05 05:54:15 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/04/05 05:54:15 DEBUG : empty space: Unchanged skipping 2021/04/05 05:54:15 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for checks to finish 2021/04/05 05:54:15 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for transfers to finish 2021/04/05 05:54:15 DEBUG : Waiting for deletions to finish 2021/04/05 05:54:15 INFO : There was nothing to transfer 2021/04/05 05:54:15 DEBUG : enormous: Excluded 2021/04/05 05:54:15 DEBUG : potato2: Excluded 2021/04/05 05:54:15 DEBUG : potato2: Excluded from sync (and deletion) 2021/04/05 05:54:15 DEBUG : empty space: Size and modification time the same (differ by 456.789µs, within tolerance 1ms) 2021/04/05 05:54:15 DEBUG : empty space: Unchanged skipping 2021/04/05 05:54:15 DEBUG : Local file system at /tmp/rclone391697543: Waiting for checks to finish 2021/04/05 05:54:15 DEBUG : Local file system at /tmp/rclone391697543: Waiting for transfers to finish 2021/04/05 05:54:15 DEBUG : Waiting for deletions to finish 2021/04/05 05:54:15 INFO : There was nothing to transfer --- PASS: TestSyncWithExclude (5.20s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" 2021/04/05 05:54:21 DEBUG : enormous: Excluded 2021/04/05 05:54:21 DEBUG : potato2: Excluded 2021/04/05 05:54:21 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for checks to finish 2021/04/05 05:54:21 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/04/05 05:54:21 DEBUG : empty space: Unchanged skipping 2021/04/05 05:54:21 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for transfers to finish 2021/04/05 05:54:21 DEBUG : Waiting for deletions to finish 2021/04/05 05:54:21 INFO : potato2: Deleted 2021/04/05 05:54:22 INFO : enormous: Deleted 2021/04/05 05:54:22 INFO : There was nothing to transfer 2021/04/05 05:54:22 DEBUG : Local file system at /tmp/rclone391697543: Waiting for checks to finish 2021/04/05 05:54:22 DEBUG : empty space: Size and modification time the same (differ by 456.789µs, within tolerance 1ms) 2021/04/05 05:54:22 DEBUG : empty space: Unchanged skipping 2021/04/05 05:54:22 DEBUG : Local file system at /tmp/rclone391697543: Waiting for transfers to finish 2021/04/05 05:54:22 DEBUG : Waiting for deletions to finish 2021/04/05 05:54:22 INFO : enormous: Deleted 2021/04/05 05:54:22 INFO : potato2: Deleted 2021/04/05 05:54:22 INFO : There was nothing to transfer --- PASS: TestSyncWithExcludeAndDeleteExcluded (5.00s) === RUN TestSyncWithUpdateOlder run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" 2021/04/05 05:54:27 DEBUG : four: Sizes differ (src 4 vs dst 8) 2021/04/05 05:54:27 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2021/04/05 05:54:27 DEBUG : one: Destination is newer than source, skipping 2021/04/05 05:54:27 DEBUG : three: Sizes identical 2021/04/05 05:54:27 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2021/04/05 05:54:27 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for checks to finish 2021/04/05 05:54:27 DEBUG : two: SHA-1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b (Local file system at /tmp/rclone391697543) 2021/04/05 05:54:27 DEBUG : two: SHA-1 = b10e0da85e90399abfec36c7376b20aa4596461b (B2 bucket rclone-test-qokuher9zohutep2binudaq8) 2021/04/05 05:54:27 DEBUG : two: SHA-1 differ 2021/04/05 05:54:27 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for transfers to finish 2021/04/05 05:54:27 DEBUG : four: SHA-1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2021/04/05 05:54:27 INFO : four: Copied (replaced existing) 2021/04/05 05:54:28 DEBUG : five: SHA-1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2021/04/05 05:54:28 INFO : five: Copied (new) 2021/04/05 05:54:28 DEBUG : two: SHA-1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2021/04/05 05:54:28 INFO : two: Copied (replaced existing) 2021/04/05 05:54:28 DEBUG : Waiting for deletions to finish 2021/04/05 05:54:28 DEBUG : one: Destination is newer than source, skipping 2021/04/05 05:54:28 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for checks to finish 2021/04/05 05:54:28 DEBUG : five: SHA-1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2021/04/05 05:54:28 DEBUG : five: Size and SHA-1 of src and dst objects identical 2021/04/05 05:54:28 DEBUG : five: Destination mod time is within 1s of source and files identical, skipping 2021/04/05 05:54:28 DEBUG : two: SHA-1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2021/04/05 05:54:28 DEBUG : two: Size and SHA-1 of src and dst objects identical 2021/04/05 05:54:28 DEBUG : two: Destination mod time is within 1s of source and files identical, skipping 2021/04/05 05:54:28 DEBUG : four: SHA-1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2021/04/05 05:54:28 DEBUG : four: Size and SHA-1 of src and dst objects identical 2021/04/05 05:54:28 DEBUG : four: Destination mod time is within 1s of source and files identical, skipping 2021/04/05 05:54:28 DEBUG : three: SHA-1 = b802f384302cb24fbab0a44997e820bf2e8507bb (Local file system at /tmp/rclone391697543) 2021/04/05 05:54:28 DEBUG : three: SHA-1 = 03731cad13f4cf7b59703c77529381cdec1632e5 (B2 bucket rclone-test-qokuher9zohutep2binudaq8) 2021/04/05 05:54:28 DEBUG : three: SHA-1 differ 2021/04/05 05:54:28 DEBUG : three: Destination mod time is within 1s of source but files differ, transferring 2021/04/05 05:54:28 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for transfers to finish 2021/04/05 05:54:29 DEBUG : three: SHA-1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/04/05 05:54:29 INFO : three: Copied (replaced existing) 2021/04/05 05:54:29 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithUpdateOlder (9.62s) === RUN TestSyncWithMaxDuration sync_test.go:1039: Skipping test on non local remote --- SKIP: TestSyncWithMaxDuration (0.00s) === RUN TestSyncWithTrackRenames run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" sync_test.go:1088: Can track renames: true 2021/04/05 05:54:33 INFO : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Making map for --track-renames 2021/04/05 05:54:33 INFO : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Finished making map for --track-renames 2021/04/05 05:54:33 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for checks to finish 2021/04/05 05:54:33 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for renames to finish 2021/04/05 05:54:33 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for transfers to finish 2021/04/05 05:54:33 DEBUG : yam: SHA-1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2021/04/05 05:54:33 INFO : yam: Copied (new) 2021/04/05 05:54:33 DEBUG : potato: SHA-1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2021/04/05 05:54:33 INFO : potato: Copied (new) 2021/04/05 05:54:33 DEBUG : Waiting for deletions to finish 2021/04/05 05:54:34 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/04/05 05:54:34 DEBUG : potato: Unchanged skipping 2021/04/05 05:54:34 INFO : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Making map for --track-renames 2021/04/05 05:54:34 INFO : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Finished making map for --track-renames 2021/04/05 05:54:34 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for checks to finish 2021/04/05 05:54:34 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for renames to finish 2021/04/05 05:54:34 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:54:35 DEBUG : yam: SHA-1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2021/04/05 05:54:35 INFO : yam: Copied (server-side copy) to: yaml 2021/04/05 05:54:35 INFO : yam: Deleted 2021/04/05 05:54:35 INFO : yaml: Renamed from "yam" 2021/04/05 05:54:35 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for transfers to finish 2021/04/05 05:54:35 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenames (9.56s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" sync_test.go:1157: Can track renames: true 2021/04/05 05:54:42 INFO : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Making map for --track-renames 2021/04/05 05:54:42 INFO : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Finished making map for --track-renames 2021/04/05 05:54:42 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for checks to finish 2021/04/05 05:54:42 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for renames to finish 2021/04/05 05:54:42 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for transfers to finish 2021/04/05 05:54:43 DEBUG : yam: SHA-1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2021/04/05 05:54:43 INFO : yam: Copied (new) 2021/04/05 05:54:44 DEBUG : potato: SHA-1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2021/04/05 05:54:44 INFO : potato: Copied (new) 2021/04/05 05:54:44 DEBUG : Waiting for deletions to finish 2021/04/05 05:54:44 INFO : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Making map for --track-renames 2021/04/05 05:54:44 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/04/05 05:54:44 INFO : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Finished making map for --track-renames 2021/04/05 05:54:44 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for checks to finish 2021/04/05 05:54:44 DEBUG : potato: Unchanged skipping 2021/04/05 05:54:44 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for renames to finish 2021/04/05 05:54:45 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:54:45 DEBUG : yam: SHA-1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2021/04/05 05:54:45 INFO : yam: Copied (server-side copy) to: yaml 2021/04/05 05:54:46 INFO : yam: Deleted 2021/04/05 05:54:46 INFO : yaml: Renamed from "yam" 2021/04/05 05:54:46 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for transfers to finish 2021/04/05 05:54:46 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenamesStrategyModtime (5.62s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" sync_test.go:1193: Can track renames: true 2021/04/05 05:54:48 INFO : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Making map for --track-renames 2021/04/05 05:54:48 INFO : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Finished making map for --track-renames 2021/04/05 05:54:48 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for checks to finish 2021/04/05 05:54:48 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for renames to finish 2021/04/05 05:54:48 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for transfers to finish 2021/04/05 05:54:48 DEBUG : sub/yam: SHA-1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2021/04/05 05:54:48 INFO : sub/yam: Copied (new) 2021/04/05 05:54:49 DEBUG : potato: SHA-1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2021/04/05 05:54:49 INFO : potato: Copied (new) 2021/04/05 05:54:49 DEBUG : Waiting for deletions to finish 2021/04/05 05:54:49 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/04/05 05:54:49 DEBUG : potato: Unchanged skipping 2021/04/05 05:54:49 INFO : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Making map for --track-renames 2021/04/05 05:54:49 INFO : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Finished making map for --track-renames 2021/04/05 05:54:49 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for checks to finish 2021/04/05 05:54:49 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for renames to finish 2021/04/05 05:54:49 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:54:50 DEBUG : sub/yam: SHA-1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2021/04/05 05:54:50 INFO : sub/yam: Copied (server-side copy) to: yam 2021/04/05 05:54:51 INFO : sub/yam: Deleted 2021/04/05 05:54:51 INFO : yam: Renamed from "sub/yam" 2021/04/05 05:54:51 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for transfers to finish 2021/04/05 05:54:51 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenamesStrategyLeaf (4.35s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" 2021/04/05 05:54:52 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for checks to finish 2021/04/05 05:54:52 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for transfers to finish 2021/04/05 05:54:53 DEBUG : nested/sub dir/file: SHA-1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK 2021/04/05 05:54:53 INFO : nested/sub dir/file: Copied (new) 2021/04/05 05:54:53 INFO : nested/sub dir/file: Deleted 2021/04/05 05:54:53 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/04/05 05:54:53 INFO : sub dir/hello world: Copied (new) 2021/04/05 05:54:53 INFO : sub dir/hello world: Deleted 2021/04/05 05:54:53 DEBUG : sub dir: Removing directory 2021/04/05 05:54:53 DEBUG : nested/sub dir: Removing directory 2021/04/05 05:54:53 DEBUG : nested: Removing directory 2021/04/05 05:54:53 DEBUG : Local file system at /tmp/rclone391697543: deleted 3 directories --- PASS: TestMoveWithDeleteEmptySrcDirs (3.34s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" 2021/04/05 05:54:55 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for checks to finish 2021/04/05 05:54:55 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for transfers to finish 2021/04/05 05:54:56 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/04/05 05:54:56 INFO : sub dir/hello world: Copied (new) 2021/04/05 05:54:56 INFO : sub dir/hello world: Deleted 2021/04/05 05:54:57 DEBUG : nested/sub dir/file: SHA-1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK 2021/04/05 05:54:57 INFO : nested/sub dir/file: Copied (new) 2021/04/05 05:54:57 INFO : nested/sub dir/file: Deleted --- PASS: TestMoveWithoutDeleteEmptySrcDirs (2.82s) === RUN TestServerSideMove run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" 2021/04/05 05:54:58 DEBUG : Creating backend with remote "TestB2:rclone-test-mapuyez0zemacon4hacomal3" sync_test.go:1245: Server side move (if possible) B2 bucket rclone-test-qokuher9zohutep2binudaq8 -> B2 bucket rclone-test-mapuyez0zemacon4hacomal3 2021/04/05 05:55:04 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms) 2021/04/05 05:55:04 DEBUG : empty space: Unchanged skipping 2021/04/05 05:55:04 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2021/04/05 05:55:04 DEBUG : B2 bucket rclone-test-mapuyez0zemacon4hacomal3: Waiting for checks to finish 2021/04/05 05:55:05 INFO : empty space: Deleted 2021/04/05 05:55:05 DEBUG : B2 bucket rclone-test-mapuyez0zemacon4hacomal3: Waiting for transfers to finish 2021/04/05 05:55:05 DEBUG : potato3: SHA-1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2021/04/05 05:55:05 INFO : potato3: Copied (server-side copy) 2021/04/05 05:55:06 INFO : potato3: Deleted 2021/04/05 05:55:06 DEBUG : potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/04/05 05:55:06 INFO : potato2: Copied (server-side copy) 2021/04/05 05:55:06 INFO : potato2: Deleted 2021/04/05 05:55:07 DEBUG : Creating backend with remote "TestB2:rclone-test-nuwarin4kegayek4siboroh1" 2021/04/05 05:55:07 DEBUG : B2 bucket rclone-test-nuwarin4kegayek4siboroh1: Waiting for checks to finish 2021/04/05 05:55:07 DEBUG : B2 bucket rclone-test-nuwarin4kegayek4siboroh1: Waiting for transfers to finish 2021/04/05 05:55:08 DEBUG : empty space: SHA-1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2021/04/05 05:55:08 INFO : empty space: Copied (server-side copy) 2021/04/05 05:55:08 DEBUG : potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/04/05 05:55:08 INFO : potato2: Copied (server-side copy) 2021/04/05 05:55:08 DEBUG : potato3: SHA-1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2021/04/05 05:55:08 INFO : potato3: Copied (server-side copy) 2021/04/05 05:55:09 INFO : potato3: Deleted 2021/04/05 05:55:09 INFO : potato2: Deleted 2021/04/05 05:55:09 INFO : empty space: Deleted 2021/04/05 05:55:10 DEBUG : B2 bucket rclone-test-nuwarin4kegayek4siboroh1: Purge remote 2021/04/05 05:55:10 DEBUG : empty space: Deleting (id "4_z3fb25da3909dab0b7f880e1b_f105e06eb92a13f98_d20210405_m055508_c001_v0001157_t0053") 2021/04/05 05:55:10 DEBUG : potato2: Deleting (id "4_z3fb25da3909dab0b7f880e1b_f1049220463697c81_d20210405_m055508_c001_v0001030_t0019") 2021/04/05 05:55:10 DEBUG : potato3: Deleting (id "4_z3fb25da3909dab0b7f880e1b_f11297cd67b0dd8c5_d20210405_m055508_c001_v0001157_t0038") 2021/04/05 05:55:10 DEBUG : B2 bucket rclone-test-mapuyez0zemacon4hacomal3: Purge remote 2021/04/05 05:55:10 DEBUG : empty space: Deleting (id "4_z0f424d63909dab0b7f880e1b_f101c61a9a7351ca5_d20210405_m055508_c001_v0001037_t0055") 2021/04/05 05:55:10 DEBUG : empty space: Deleting (id "4_z0f424d63909dab0b7f880e1b_f1145a505a2b41b4f_d20210405_m055503_c001_v0001154_t0001") 2021/04/05 05:55:10 DEBUG : potato2: Deleting (id "4_z0f424d63909dab0b7f880e1b_f1032d0253fb72b28_d20210405_m055508_c001_v0001144_t0004") 2021/04/05 05:55:10 DEBUG : potato2: Deleting (id "4_z0f424d63909dab0b7f880e1b_f1017a8029db018e9_d20210405_m055505_c001_v0001145_t0001") 2021/04/05 05:55:10 DEBUG : potato3: Deleting (id "4_z0f424d63909dab0b7f880e1b_f1145e05725b92bf9_d20210405_m055508_c001_v0001157_t0057") 2021/04/05 05:55:10 DEBUG : potato3: Deleting (id "4_z0f424d63909dab0b7f880e1b_f114ca3c764feb91c_d20210405_m055505_c001_v0001144_t0029") 2021/04/05 05:55:10 DEBUG : potato3: Deleting (id "4_z0f424d63909dab0b7f880e1b_f1145a505a2b41b53_d20210405_m055503_c001_v0001154_t0001") --- PASS: TestServerSideMove (13.24s) === RUN TestServerSideMoveWithFilter run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" 2021/04/05 05:55:11 DEBUG : Creating backend with remote "TestB2:rclone-test-belituj9nazasoz2nexitec9" sync_test.go:1245: Server side move (if possible) B2 bucket rclone-test-qokuher9zohutep2binudaq8 -> B2 bucket rclone-test-belituj9nazasoz2nexitec9 2021/04/05 05:55:17 DEBUG : empty space: Excluded from sync (and deletion) 2021/04/05 05:55:17 DEBUG : empty space: Excluded from sync (and deletion) 2021/04/05 05:55:17 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2021/04/05 05:55:17 DEBUG : B2 bucket rclone-test-belituj9nazasoz2nexitec9: Waiting for checks to finish 2021/04/05 05:55:17 DEBUG : B2 bucket rclone-test-belituj9nazasoz2nexitec9: Waiting for transfers to finish 2021/04/05 05:55:18 DEBUG : potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/04/05 05:55:18 INFO : potato2: Copied (server-side copy) 2021/04/05 05:55:18 DEBUG : potato3: SHA-1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2021/04/05 05:55:18 INFO : potato3: Copied (server-side copy) 2021/04/05 05:55:18 INFO : potato2: Deleted 2021/04/05 05:55:18 INFO : potato3: Deleted 2021/04/05 05:55:19 DEBUG : Creating backend with remote "TestB2:rclone-test-kogayim3jovanoj0yuqupok3" 2021/04/05 05:55:19 DEBUG : empty space: Excluded from sync (and deletion) 2021/04/05 05:55:19 DEBUG : B2 bucket rclone-test-kogayim3jovanoj0yuqupok3: Waiting for checks to finish 2021/04/05 05:55:19 DEBUG : B2 bucket rclone-test-kogayim3jovanoj0yuqupok3: Waiting for transfers to finish 2021/04/05 05:55:20 DEBUG : potato3: SHA-1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2021/04/05 05:55:20 INFO : potato3: Copied (server-side copy) 2021/04/05 05:55:21 INFO : potato3: Deleted 2021/04/05 05:55:21 DEBUG : potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/04/05 05:55:21 INFO : potato2: Copied (server-side copy) 2021/04/05 05:55:22 INFO : potato2: Deleted 2021/04/05 05:55:22 DEBUG : B2 bucket rclone-test-kogayim3jovanoj0yuqupok3: Purge remote 2021/04/05 05:55:22 DEBUG : potato2: Deleting (id "4_z2f528d93909dab0b7f880e1b_f101732c38fb1a0d9_d20210405_m055520_c001_v0001144_t0045") 2021/04/05 05:55:22 DEBUG : potato3: Deleting (id "4_z2f528d93909dab0b7f880e1b_f106fd0d85742ada2_d20210405_m055520_c001_v0001131_t0001") 2021/04/05 05:55:23 DEBUG : B2 bucket rclone-test-belituj9nazasoz2nexitec9: Purge remote 2021/04/05 05:55:23 DEBUG : empty space: Deleting (id "4_zbfc27d83909dab0b7f880e1b_f1126946d3d94c096_d20210405_m055516_c001_v0001131_t0048") 2021/04/05 05:55:23 DEBUG : potato2: Deleting (id "4_zbfc27d83909dab0b7f880e1b_f1059a01db5575d60_d20210405_m055521_c001_v0001144_t0027") 2021/04/05 05:55:23 DEBUG : potato2: Deleting (id "4_zbfc27d83909dab0b7f880e1b_f111e23f42dc0f93c_d20210405_m055517_c001_v0001157_t0015") 2021/04/05 05:55:23 DEBUG : potato3: Deleting (id "4_zbfc27d83909dab0b7f880e1b_f113c663be227d356_d20210405_m055520_c001_v0001037_t0059") 2021/04/05 05:55:23 DEBUG : potato3: Deleting (id "4_zbfc27d83909dab0b7f880e1b_f106102c5816c8332_d20210405_m055517_c001_v0001157_t0043") 2021/04/05 05:55:23 DEBUG : potato3: Deleting (id "4_zbfc27d83909dab0b7f880e1b_f1126946d3d94c098_d20210405_m055516_c001_v0001131_t0048") --- PASS: TestServerSideMoveWithFilter (14.34s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" 2021/04/05 05:55:26 DEBUG : Creating backend with remote "TestB2:rclone-test-humejiw6hewefom8kihutep8" 2021/04/05 05:55:30 DEBUG : tomatoDir: Making directory sync_test.go:1245: Server side move (if possible) B2 bucket rclone-test-qokuher9zohutep2binudaq8 -> B2 bucket rclone-test-humejiw6hewefom8kihutep8 2021/04/05 05:55:32 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms) 2021/04/05 05:55:32 DEBUG : empty space: Unchanged skipping 2021/04/05 05:55:32 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2021/04/05 05:55:32 DEBUG : B2 bucket rclone-test-humejiw6hewefom8kihutep8: Waiting for checks to finish 2021/04/05 05:55:33 INFO : empty space: Deleted 2021/04/05 05:55:33 DEBUG : B2 bucket rclone-test-humejiw6hewefom8kihutep8: Waiting for transfers to finish 2021/04/05 05:55:33 DEBUG : potato3: SHA-1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2021/04/05 05:55:33 INFO : potato3: Copied (server-side copy) 2021/04/05 05:55:34 INFO : potato3: Deleted 2021/04/05 05:55:36 DEBUG : potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/04/05 05:55:36 INFO : potato2: Copied (server-side copy) 2021/04/05 05:55:36 INFO : potato2: Deleted 2021/04/05 05:55:36 DEBUG : Creating backend with remote "TestB2:rclone-test-fufihig3gonopel2xiveror9" 2021/04/05 05:55:37 DEBUG : tomatoDir: Making directory 2021/04/05 05:55:37 DEBUG : B2 bucket rclone-test-fufihig3gonopel2xiveror9: Waiting for checks to finish 2021/04/05 05:55:37 DEBUG : B2 bucket rclone-test-fufihig3gonopel2xiveror9: Waiting for transfers to finish 2021/04/05 05:55:38 DEBUG : potato3: SHA-1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2021/04/05 05:55:38 INFO : potato3: Copied (server-side copy) 2021/04/05 05:55:38 DEBUG : potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/04/05 05:55:38 INFO : potato2: Copied (server-side copy) 2021/04/05 05:55:38 DEBUG : empty space: SHA-1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2021/04/05 05:55:38 INFO : empty space: Copied (server-side copy) 2021/04/05 05:55:38 INFO : potato2: Deleted 2021/04/05 05:55:38 INFO : potato3: Deleted 2021/04/05 05:55:39 INFO : empty space: Deleted 2021/04/05 05:55:39 DEBUG : B2 bucket rclone-test-fufihig3gonopel2xiveror9: Purge remote 2021/04/05 05:55:39 DEBUG : empty space: Deleting (id "4_z6f12cde3909dab0b7f880e1b_f11985ce906c57856_d20210405_m055538_c001_v0001157_t0024") 2021/04/05 05:55:39 DEBUG : potato2: Deleting (id "4_z6f12cde3909dab0b7f880e1b_f113858718647ae14_d20210405_m055538_c001_v0001131_t0057") 2021/04/05 05:55:39 DEBUG : potato3: Deleting (id "4_z6f12cde3909dab0b7f880e1b_f113501e88efe64f6_d20210405_m055538_c001_v0001150_t0042") 2021/04/05 05:55:40 DEBUG : B2 bucket rclone-test-humejiw6hewefom8kihutep8: Purge remote 2021/04/05 05:55:40 DEBUG : empty space: Deleting (id "4_z1f62bd23909dab0b7f880e1b_f10907ad9a0e94f88_d20210405_m055538_c001_v0001157_t0002") 2021/04/05 05:55:40 DEBUG : empty space: Deleting (id "4_z1f62bd23909dab0b7f880e1b_f107d96f6592a0366_d20210405_m055531_c001_v0001145_t0057") 2021/04/05 05:55:40 DEBUG : potato2: Deleting (id "4_z1f62bd23909dab0b7f880e1b_f102b0c5a3051a1e8_d20210405_m055538_c001_v0001134_t0024") 2021/04/05 05:55:40 DEBUG : potato2: Deleting (id "4_z1f62bd23909dab0b7f880e1b_f1084d97a36c9160a_d20210405_m055533_c001_v0001041_t0007") 2021/04/05 05:55:40 DEBUG : potato3: Deleting (id "4_z1f62bd23909dab0b7f880e1b_f111cb007d9b7ce77_d20210405_m055538_c001_v0001092_t0010") 2021/04/05 05:55:40 DEBUG : potato3: Deleting (id "4_z1f62bd23909dab0b7f880e1b_f1174fd2e0d8dd24a_d20210405_m055533_c001_v0001144_t0006") 2021/04/05 05:55:40 DEBUG : potato3: Deleting (id "4_z1f62bd23909dab0b7f880e1b_f107d96f6592a036c_d20210405_m055531_c001_v0001145_t0057") --- PASS: TestServerSideMoveDeleteEmptySourceDirs (15.00s) === RUN TestServerSideMoveOverlap run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" 2021/04/05 05:55:41 DEBUG : Creating backend with remote "TestB2:rclone-test-qokuher9zohutep2binudaq8/rclone-move-test" 2021/04/05 05:55:41 DEBUG : Couldn't decode error response: EOF --- PASS: TestServerSideMoveOverlap (2.68s) === RUN TestSyncOverlap run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" 2021/04/05 05:55:43 DEBUG : Creating backend with remote "TestB2:rclone-test-qokuher9zohutep2binudaq8/rclone-sync-test" 2021/04/05 05:55:44 DEBUG : Couldn't decode error response: EOF --- PASS: TestSyncOverlap (0.64s) === RUN TestSyncCompareDest run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" 2021/04/05 05:55:44 DEBUG : Creating backend with remote "TestB2:rclone-test-qokuher9zohutep2binudaq8/dst" 2021/04/05 05:55:44 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:55:44 DEBUG : Creating backend with remote "TestB2:rclone-test-qokuher9zohutep2binudaq8/CompareDest" 2021/04/05 05:55:45 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:55:45 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:55:45 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8 path dst: Waiting for checks to finish 2021/04/05 05:55:45 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8 path dst: Waiting for transfers to finish 2021/04/05 05:55:48 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/04/05 05:55:48 INFO : one: Copied (new) 2021/04/05 05:55:48 DEBUG : Waiting for deletions to finish 2021/04/05 05:55:49 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8 path dst: Waiting for checks to finish 2021/04/05 05:55:49 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:55:49 DEBUG : one: Sizes differ (src 5 vs dst 3) 2021/04/05 05:55:49 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8 path dst: Waiting for transfers to finish 2021/04/05 05:55:49 DEBUG : one: SHA-1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2021/04/05 05:55:49 INFO : one: Copied (replaced existing) 2021/04/05 05:55:49 DEBUG : Waiting for deletions to finish 2021/04/05 05:55:51 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8 path dst: Waiting for checks to finish 2021/04/05 05:55:52 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/04/05 05:55:52 DEBUG : one: Destination found in --compare-dest, skipping 2021/04/05 05:55:52 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8 path dst: Waiting for transfers to finish 2021/04/05 05:55:52 DEBUG : Waiting for deletions to finish 2021/04/05 05:55:52 INFO : There was nothing to transfer 2021/04/05 05:55:53 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/04/05 05:55:53 DEBUG : two: Destination found in --compare-dest, skipping 2021/04/05 05:55:53 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8 path dst: Waiting for checks to finish 2021/04/05 05:55:54 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/04/05 05:55:54 DEBUG : one: Destination found in --compare-dest, skipping 2021/04/05 05:55:54 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8 path dst: Waiting for transfers to finish 2021/04/05 05:55:54 DEBUG : Waiting for deletions to finish 2021/04/05 05:55:54 INFO : There was nothing to transfer 2021/04/05 05:55:54 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/04/05 05:55:54 DEBUG : two: Destination found in --compare-dest, skipping 2021/04/05 05:55:54 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8 path dst: Waiting for checks to finish 2021/04/05 05:55:54 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/04/05 05:55:54 DEBUG : one: Destination found in --compare-dest, skipping 2021/04/05 05:55:54 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8 path dst: Waiting for transfers to finish 2021/04/05 05:55:54 DEBUG : Waiting for deletions to finish 2021/04/05 05:55:54 INFO : There was nothing to transfer 2021/04/05 05:55:55 DEBUG : two: Sizes differ (src 5 vs dst 3) 2021/04/05 05:55:55 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8 path dst: Waiting for checks to finish 2021/04/05 05:55:55 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/04/05 05:55:55 DEBUG : one: Destination found in --compare-dest, skipping 2021/04/05 05:55:55 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8 path dst: Waiting for transfers to finish 2021/04/05 05:55:56 DEBUG : two: SHA-1 = a4be8877ad88f3109a6411985a1f359e10e2d930 OK 2021/04/05 05:55:56 INFO : two: Copied (new) 2021/04/05 05:55:56 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCompareDest (24.97s) === RUN TestSyncMultipleCompareDest run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" 2021/04/05 05:56:11 DEBUG : Creating backend with remote "TestB2:rclone-test-qokuher9zohutep2binudaq8/dest" 2021/04/05 05:56:13 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:56:13 DEBUG : Creating backend with remote "TestB2:rclone-test-qokuher9zohutep2binudaq8/pre-dest1" 2021/04/05 05:56:13 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:56:13 DEBUG : Creating backend with remote "TestB2:rclone-test-qokuher9zohutep2binudaq8/pre-dest2" 2021/04/05 05:56:13 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:56:14 DEBUG : 1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/04/05 05:56:14 DEBUG : 1: Destination found in --compare-dest, skipping 2021/04/05 05:56:14 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:56:14 DEBUG : 2: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/04/05 05:56:14 DEBUG : 2: Destination found in --compare-dest, skipping 2021/04/05 05:56:14 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:56:14 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:56:14 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8 path dest: Waiting for checks to finish 2021/04/05 05:56:14 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8 path dest: Waiting for transfers to finish 2021/04/05 05:56:16 DEBUG : 3: SHA-1 = 77de68daecd823babbb58edb1c8e14d7106e83bb OK 2021/04/05 05:56:16 INFO : 3: Copied (new) 2021/04/05 05:56:16 DEBUG : Waiting for deletions to finish --- PASS: TestSyncMultipleCompareDest (8.48s) === RUN TestSyncCopyDest run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" 2021/04/05 05:56:17 DEBUG : Creating backend with remote "TestB2:rclone-test-qokuher9zohutep2binudaq8/dst" 2021/04/05 05:56:18 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:56:18 DEBUG : Creating backend with remote "TestB2:rclone-test-qokuher9zohutep2binudaq8/CopyDest" 2021/04/05 05:56:18 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:56:19 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:56:19 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8 path dst: Waiting for checks to finish 2021/04/05 05:56:19 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8 path dst: Waiting for transfers to finish 2021/04/05 05:56:20 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/04/05 05:56:20 INFO : one: Copied (new) 2021/04/05 05:56:20 DEBUG : Waiting for deletions to finish 2021/04/05 05:56:20 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8 path dst: Waiting for checks to finish 2021/04/05 05:56:20 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:56:20 DEBUG : one: Sizes differ (src 5 vs dst 3) 2021/04/05 05:56:20 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8 path dst: Waiting for transfers to finish 2021/04/05 05:56:21 DEBUG : one: SHA-1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2021/04/05 05:56:21 INFO : one: Copied (replaced existing) 2021/04/05 05:56:21 DEBUG : Waiting for deletions to finish 2021/04/05 05:56:23 DEBUG : Creating backend with remote "TestB2:rclone-test-qokuher9zohutep2binudaq8/BackupDir" 2021/04/05 05:56:24 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:56:24 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8 path dst: Waiting for checks to finish 2021/04/05 05:56:24 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/04/05 05:56:24 DEBUG : one: Sizes differ (src 5 vs dst 3) 2021/04/05 05:56:25 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:56:26 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/04/05 05:56:26 INFO : one: Copied (server-side copy) 2021/04/05 05:56:26 INFO : one: Deleted 2021/04/05 05:56:26 DEBUG : one: SHA-1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2021/04/05 05:56:26 INFO : one: Copied (server-side copy) 2021/04/05 05:56:26 DEBUG : one: Destination found in --copy-dest, using server-side copy 2021/04/05 05:56:26 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8 path dst: Waiting for transfers to finish 2021/04/05 05:56:26 DEBUG : Waiting for deletions to finish 2021/04/05 05:56:28 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/04/05 05:56:29 DEBUG : two: SHA-1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2021/04/05 05:56:29 INFO : two: Copied (server-side copy) 2021/04/05 05:56:29 DEBUG : two: Destination found in --copy-dest, using server-side copy 2021/04/05 05:56:29 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8 path dst: Waiting for checks to finish 2021/04/05 05:56:29 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/04/05 05:56:29 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/04/05 05:56:29 DEBUG : one: Unchanged skipping 2021/04/05 05:56:29 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8 path dst: Waiting for transfers to finish 2021/04/05 05:56:29 DEBUG : Waiting for deletions to finish 2021/04/05 05:56:29 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8 path dst: Waiting for checks to finish 2021/04/05 05:56:29 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/04/05 05:56:29 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/04/05 05:56:29 DEBUG : one: Unchanged skipping 2021/04/05 05:56:29 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/04/05 05:56:29 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/04/05 05:56:29 DEBUG : two: Unchanged skipping 2021/04/05 05:56:29 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8 path dst: Waiting for transfers to finish 2021/04/05 05:56:29 DEBUG : Waiting for deletions to finish 2021/04/05 05:56:29 INFO : There was nothing to transfer 2021/04/05 05:56:31 DEBUG : three: Sizes differ (src 7 vs dst 5) 2021/04/05 05:56:31 DEBUG : three: Destination not found in --copy-dest 2021/04/05 05:56:31 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8 path dst: Waiting for checks to finish 2021/04/05 05:56:31 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/04/05 05:56:31 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/04/05 05:56:31 DEBUG : one: Unchanged skipping 2021/04/05 05:56:31 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/04/05 05:56:31 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/04/05 05:56:31 DEBUG : two: Unchanged skipping 2021/04/05 05:56:31 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8 path dst: Waiting for transfers to finish 2021/04/05 05:56:32 DEBUG : three: SHA-1 = 370e613335f3cf2f19b2a2d35a86402f55c659f8 OK 2021/04/05 05:56:32 INFO : three: Copied (new) 2021/04/05 05:56:32 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCopyDest (19.23s) === RUN TestSyncBackupDir run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" 2021/04/05 05:56:40 DEBUG : Creating backend with remote "TestB2:rclone-test-qokuher9zohutep2binudaq8/dst" 2021/04/05 05:56:42 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:56:42 DEBUG : Creating backend with remote "TestB2:rclone-test-qokuher9zohutep2binudaq8/backup" 2021/04/05 05:56:42 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:56:42 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/04/05 05:56:42 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/04/05 05:56:42 DEBUG : two: Unchanged skipping 2021/04/05 05:56:42 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8 path dst: Waiting for checks to finish 2021/04/05 05:56:42 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:56:44 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/04/05 05:56:44 INFO : one: Copied (server-side copy) 2021/04/05 05:56:44 INFO : one: Deleted 2021/04/05 05:56:44 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8 path dst: Waiting for transfers to finish 2021/04/05 05:56:45 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/04/05 05:56:45 INFO : one: Copied (new) 2021/04/05 05:56:45 DEBUG : Waiting for deletions to finish 2021/04/05 05:56:45 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:56:46 DEBUG : three.txt: SHA-1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/04/05 05:56:46 INFO : three.txt: Copied (server-side copy) 2021/04/05 05:56:47 INFO : three.txt: Deleted 2021/04/05 05:56:47 INFO : three.txt: Moved into backup dir 2021/04/05 05:56:48 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/04/05 05:56:48 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/04/05 05:56:48 DEBUG : two: Unchanged skipping 2021/04/05 05:56:48 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8 path dst: Waiting for checks to finish 2021/04/05 05:56:49 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/04/05 05:56:49 INFO : one: Copied (server-side copy) 2021/04/05 05:56:50 INFO : one: Deleted 2021/04/05 05:56:50 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8 path dst: Waiting for transfers to finish 2021/04/05 05:56:50 DEBUG : one: SHA-1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2021/04/05 05:56:50 INFO : one: Copied (new) 2021/04/05 05:56:50 DEBUG : Waiting for deletions to finish 2021/04/05 05:56:51 DEBUG : three.txt: SHA-1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2021/04/05 05:56:51 INFO : three.txt: Copied (server-side copy) 2021/04/05 05:56:52 INFO : three.txt: Deleted 2021/04/05 05:56:52 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (17.75s) === RUN TestSyncBackupDirWithSuffix run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" 2021/04/05 05:56:58 DEBUG : Creating backend with remote "TestB2:rclone-test-qokuher9zohutep2binudaq8/dst" 2021/04/05 05:56:58 DEBUG : Config file has changed externaly - reloading 2021/04/05 05:56:59 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:56:59 DEBUG : Creating backend with remote "TestB2:rclone-test-qokuher9zohutep2binudaq8/backup" 2021/04/05 05:57:00 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:57:00 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/04/05 05:57:00 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8 path dst: Waiting for checks to finish 2021/04/05 05:57:00 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/04/05 05:57:00 DEBUG : two: Unchanged skipping 2021/04/05 05:57:00 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:57:01 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/04/05 05:57:01 INFO : one: Copied (server-side copy) to: one.bak 2021/04/05 05:57:02 INFO : one: Deleted 2021/04/05 05:57:02 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8 path dst: Waiting for transfers to finish 2021/04/05 05:57:03 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/04/05 05:57:03 INFO : one: Copied (new) 2021/04/05 05:57:03 DEBUG : Waiting for deletions to finish 2021/04/05 05:57:03 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:57:04 DEBUG : three.txt: SHA-1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/04/05 05:57:04 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2021/04/05 05:57:04 INFO : three.txt: Deleted 2021/04/05 05:57:04 INFO : three.txt: Moved into backup dir 2021/04/05 05:57:06 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/04/05 05:57:06 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/04/05 05:57:06 DEBUG : two: Unchanged skipping 2021/04/05 05:57:06 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8 path dst: Waiting for checks to finish 2021/04/05 05:57:09 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/04/05 05:57:09 INFO : one: Copied (server-side copy) to: one.bak 2021/04/05 05:57:11 INFO : one: Deleted 2021/04/05 05:57:11 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8 path dst: Waiting for transfers to finish 2021/04/05 05:57:12 DEBUG : one: SHA-1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2021/04/05 05:57:12 INFO : one: Copied (new) 2021/04/05 05:57:12 DEBUG : Waiting for deletions to finish 2021/04/05 05:57:13 DEBUG : three.txt: SHA-1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2021/04/05 05:57:13 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2021/04/05 05:57:14 INFO : three.txt: Deleted 2021/04/05 05:57:14 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (21.46s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" 2021/04/05 05:57:20 DEBUG : Creating backend with remote "TestB2:rclone-test-qokuher9zohutep2binudaq8/dst" 2021/04/05 05:57:20 DEBUG : Config file has changed externaly - reloading 2021/04/05 05:57:21 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:57:21 DEBUG : Creating backend with remote "TestB2:rclone-test-qokuher9zohutep2binudaq8/backup" 2021/04/05 05:57:21 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:57:21 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/04/05 05:57:21 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8 path dst: Waiting for checks to finish 2021/04/05 05:57:21 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/04/05 05:57:21 DEBUG : two: Unchanged skipping 2021/04/05 05:57:22 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:57:23 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/04/05 05:57:23 INFO : one: Copied (server-side copy) to: one-2019-01-01 2021/04/05 05:57:23 INFO : one: Deleted 2021/04/05 05:57:23 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8 path dst: Waiting for transfers to finish 2021/04/05 05:57:25 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/04/05 05:57:25 INFO : one: Copied (new) 2021/04/05 05:57:25 DEBUG : Waiting for deletions to finish 2021/04/05 05:57:25 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:57:25 DEBUG : three.txt: SHA-1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/04/05 05:57:25 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2021/04/05 05:57:26 INFO : three.txt: Deleted 2021/04/05 05:57:26 INFO : three.txt: Moved into backup dir 2021/04/05 05:57:27 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/04/05 05:57:27 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/04/05 05:57:27 DEBUG : two: Unchanged skipping 2021/04/05 05:57:27 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8 path dst: Waiting for checks to finish 2021/04/05 05:57:29 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/04/05 05:57:29 INFO : one: Copied (server-side copy) to: one-2019-01-01 2021/04/05 05:57:29 INFO : one: Deleted 2021/04/05 05:57:29 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8 path dst: Waiting for transfers to finish 2021/04/05 05:57:29 DEBUG : one: SHA-1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2021/04/05 05:57:29 INFO : one: Copied (new) 2021/04/05 05:57:29 DEBUG : Waiting for deletions to finish 2021/04/05 05:57:30 DEBUG : three.txt: SHA-1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2021/04/05 05:57:30 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2021/04/05 05:57:31 INFO : three.txt: Deleted 2021/04/05 05:57:31 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (21.49s) === RUN TestSyncBackupDirSuffixOnly run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" 2021/04/05 05:57:41 DEBUG : Creating backend with remote "TestB2:rclone-test-qokuher9zohutep2binudaq8/dst" 2021/04/05 05:57:42 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:57:42 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/04/05 05:57:42 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/04/05 05:57:42 DEBUG : two: Unchanged skipping 2021/04/05 05:57:42 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8 path dst: Waiting for checks to finish 2021/04/05 05:57:43 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:57:43 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/04/05 05:57:43 INFO : one: Copied (server-side copy) to: one.bak 2021/04/05 05:57:44 INFO : one: Deleted 2021/04/05 05:57:44 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8 path dst: Waiting for transfers to finish 2021/04/05 05:57:45 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/04/05 05:57:45 INFO : one: Copied (new) 2021/04/05 05:57:45 DEBUG : Waiting for deletions to finish 2021/04/05 05:57:45 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:57:45 DEBUG : three.txt: SHA-1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/04/05 05:57:45 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2021/04/05 05:57:46 INFO : three.txt: Deleted 2021/04/05 05:57:46 INFO : three.txt: Moved into backup dir 2021/04/05 05:57:48 DEBUG : one.bak: Excluded from sync (and deletion) 2021/04/05 05:57:48 DEBUG : three.txt.bak: Excluded from sync (and deletion) 2021/04/05 05:57:48 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/04/05 05:57:48 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/04/05 05:57:48 DEBUG : two: Unchanged skipping 2021/04/05 05:57:48 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8 path dst: Waiting for checks to finish 2021/04/05 05:57:49 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/04/05 05:57:49 INFO : one: Copied (server-side copy) to: one.bak 2021/04/05 05:57:49 INFO : one: Deleted 2021/04/05 05:57:49 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8 path dst: Waiting for transfers to finish 2021/04/05 05:57:50 DEBUG : one: SHA-1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2021/04/05 05:57:50 INFO : one: Copied (new) 2021/04/05 05:57:50 DEBUG : Waiting for deletions to finish 2021/04/05 05:57:51 DEBUG : three.txt: SHA-1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2021/04/05 05:57:51 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2021/04/05 05:57:51 INFO : three.txt: Deleted 2021/04/05 05:57:51 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirSuffixOnly (16.64s) === RUN TestSyncSuffix run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" 2021/04/05 05:57:57 DEBUG : Creating backend with remote "TestB2:rclone-test-qokuher9zohutep2binudaq8/dst" 2021/04/05 05:57:58 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:57:58 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/04/05 05:57:58 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:58:00 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/04/05 05:58:00 INFO : one: Copied (server-side copy) to: one.bak 2021/04/05 05:58:01 INFO : one: Deleted 2021/04/05 05:58:02 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/04/05 05:58:02 INFO : one: Copied (new) 2021/04/05 05:58:02 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/04/05 05:58:02 DEBUG : two: Unchanged skipping 2021/04/05 05:58:02 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2021/04/05 05:58:02 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:58:03 DEBUG : three.txt: SHA-1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/04/05 05:58:03 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2021/04/05 05:58:03 INFO : three.txt: Deleted 2021/04/05 05:58:04 DEBUG : three.txt: SHA-1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2021/04/05 05:58:04 INFO : three.txt: Copied (new) 2021/04/05 05:58:05 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/04/05 05:58:05 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/04/05 05:58:05 INFO : one: Copied (server-side copy) to: one.bak 2021/04/05 05:58:06 INFO : one: Deleted 2021/04/05 05:58:07 DEBUG : one: SHA-1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2021/04/05 05:58:07 INFO : one: Copied (new) 2021/04/05 05:58:07 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/04/05 05:58:07 DEBUG : two: Unchanged skipping 2021/04/05 05:58:07 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2021/04/05 05:58:08 DEBUG : three.txt: SHA-1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2021/04/05 05:58:08 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2021/04/05 05:58:08 INFO : three.txt: Deleted 2021/04/05 05:58:08 DEBUG : three.txt: SHA-1 = 476e1707cbd7f0497a7dc4d44f0b07b8566ffc30 OK 2021/04/05 05:58:08 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (17.25s) === RUN TestSyncSuffixKeepExtension run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" 2021/04/05 05:58:15 DEBUG : Creating backend with remote "TestB2:rclone-test-qokuher9zohutep2binudaq8/dst" 2021/04/05 05:58:15 DEBUG : Config file has changed externaly - reloading 2021/04/05 05:58:16 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:58:16 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/04/05 05:58:16 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:58:17 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/04/05 05:58:17 INFO : one: Copied (server-side copy) to: one-2019-01-01 2021/04/05 05:58:18 INFO : one: Deleted 2021/04/05 05:58:20 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/04/05 05:58:20 INFO : one: Copied (new) 2021/04/05 05:58:20 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/04/05 05:58:20 DEBUG : two: Unchanged skipping 2021/04/05 05:58:20 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2021/04/05 05:58:20 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:58:21 DEBUG : three.txt: SHA-1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/04/05 05:58:21 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2021/04/05 05:58:21 INFO : three.txt: Deleted 2021/04/05 05:58:22 DEBUG : three.txt: SHA-1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2021/04/05 05:58:22 INFO : three.txt: Copied (new) 2021/04/05 05:58:23 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/04/05 05:58:25 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/04/05 05:58:25 INFO : one: Copied (server-side copy) to: one-2019-01-01 2021/04/05 05:58:26 INFO : one: Deleted 2021/04/05 05:58:28 DEBUG : one: SHA-1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2021/04/05 05:58:28 INFO : one: Copied (new) 2021/04/05 05:58:28 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/04/05 05:58:28 DEBUG : two: Unchanged skipping 2021/04/05 05:58:29 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2021/04/05 05:58:29 DEBUG : three.txt: SHA-1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2021/04/05 05:58:29 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2021/04/05 05:58:30 INFO : three.txt: Deleted 2021/04/05 05:58:30 DEBUG : three.txt: SHA-1 = 476e1707cbd7f0497a7dc4d44f0b07b8566ffc30 OK 2021/04/05 05:58:30 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (23.32s) === RUN TestSyncUTFNorm run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" 2021/04/05 05:58:36 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for checks to finish 2021/04/05 05:58:36 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2021/04/05 05:58:36 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for transfers to finish 2021/04/05 05:58:37 DEBUG : Testêé: SHA-1 = a54d88e06612d820bc3be72877c74f257b561b19 OK 2021/04/05 05:58:37 INFO : Testêé: Copied (replaced existing) to: Testêé 2021/04/05 05:58:37 DEBUG : Waiting for deletions to finish --- PASS: TestSyncUTFNorm (4.18s) === RUN TestSyncImmutable run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" 2021/04/05 05:58:39 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for checks to finish 2021/04/05 05:58:39 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for transfers to finish 2021/04/05 05:58:40 DEBUG : existing: SHA-1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2021/04/05 05:58:40 INFO : existing: Copied (new) 2021/04/05 05:58:40 DEBUG : Waiting for deletions to finish 2021/04/05 05:58:41 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for checks to finish 2021/04/05 05:58:41 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2021/04/05 05:58:41 ERROR : existing: Source and destination exist but do not match: immutable file modified 2021/04/05 05:58:41 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for transfers to finish 2021/04/05 05:58:41 ERROR : B2 bucket rclone-test-qokuher9zohutep2binudaq8: not deleting files as there were IO errors 2021/04/05 05:58:41 ERROR : B2 bucket rclone-test-qokuher9zohutep2binudaq8: not deleting directories as there were IO errors --- PASS: TestSyncImmutable (3.27s) === RUN TestSyncIgnoreCase run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" 2021/04/05 05:58:43 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for checks to finish 2021/04/05 05:58:43 DEBUG : existing: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/04/05 05:58:43 DEBUG : existing: Unchanged skipping 2021/04/05 05:58:43 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Waiting for transfers to finish 2021/04/05 05:58:43 DEBUG : Waiting for deletions to finish 2021/04/05 05:58:43 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreCase (2.05s) === RUN TestMaxTransfer === RUN TestMaxTransfer/Hard run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" sync_test.go:1941: This test only runs on local === RUN TestMaxTransfer/Soft run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" sync_test.go:1941: This test only runs on local === RUN TestMaxTransfer/Cautious run.go:176: Remote "B2 bucket rclone-test-qokuher9zohutep2binudaq8", Local "Local file system at /tmp/rclone391697543", Modify Window "1ms" sync_test.go:1941: This test only runs on local --- PASS: TestMaxTransfer (0.93s) --- SKIP: TestMaxTransfer/Hard (0.29s) --- SKIP: TestMaxTransfer/Soft (0.29s) --- SKIP: TestMaxTransfer/Cautious (0.34s) PASS 2021/04/05 05:58:45 DEBUG : B2 bucket rclone-test-qokuher9zohutep2binudaq8: Purge remote 2021/04/05 05:58:45 DEBUG : BackupDir/one: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f103f89391b77fcf3_d20210405_m055633_c001_v0001157_t0038") 2021/04/05 05:58:45 DEBUG : BackupDir/one: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f106fd0d85742ae05_d20210405_m055625_c001_v0001131_t0004") 2021/04/05 05:58:45 DEBUG : CompareDest/one: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f101732c38fb1a36a_d20210405_m055556_c001_v0001144_t0005") 2021/04/05 05:58:45 DEBUG : CompareDest/one: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f109935855701e8a6_d20210405_m055551_c001_v0001144_t0012") 2021/04/05 05:58:45 DEBUG : CompareDest/two: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f104482e40b1c10bd_d20210405_m055557_c001_v0001144_t0012") 2021/04/05 05:58:45 DEBUG : CompareDest/two: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b1410_d20210405_m055552_c001_v0001144_t0047") 2021/04/05 05:58:45 DEBUG : CopyDest/one: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f11504c385586090c_d20210405_m055633_c001_v0001157_t0051") 2021/04/05 05:58:45 DEBUG : CopyDest/one: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1164fab026b58998_d20210405_m055623_c001_v0001157_t0049") 2021/04/05 05:58:45 DEBUG : CopyDest/three: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1182800aa549c431_d20210405_m055634_c001_v0001022_t0012") 2021/04/05 05:58:46 DEBUG : CopyDest/three: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b1696_d20210405_m055630_c001_v0001144_t0047") 2021/04/05 05:58:46 DEBUG : CopyDest/two: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1172c116533109f9_d20210405_m055635_c001_v0001038_t0007") 2021/04/05 05:58:46 DEBUG : CopyDest/two: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f109935855701eaa8_d20210405_m055627_c001_v0001144_t0012") 2021/04/05 05:58:46 DEBUG : EXISTING: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f110b4cf9afb424de_d20210405_m055844_c001_v0001153_t0014") 2021/04/05 05:58:46 DEBUG : EXISTING: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f109935855701f181_d20210405_m055843_c001_v0001144_t0012") 2021/04/05 05:58:46 DEBUG : Testêé: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1123d6302234c26e_d20210405_m055838_c001_v0001011_t0043") 2021/04/05 05:58:46 DEBUG : Testêé: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b1f17_d20210405_m055837_c001_v0001144_t0047") 2021/04/05 05:58:46 DEBUG : Testêé: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f109935855701f114_d20210405_m055835_c001_v0001144_t0012") 2021/04/05 05:58:46 DEBUG : a/potato2: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f111e23f42dc0ec0c_d20210405_m055357_c001_v0001157_t0055") 2021/04/05 05:58:46 DEBUG : a/potato2: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b0bce_d20210405_m055356_c001_v0001144_t0047") 2021/04/05 05:58:46 DEBUG : a/potato2: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f10200cd8971a825e_d20210405_m055353_c001_v0001130_t0020") 2021/04/05 05:58:46 DEBUG : a/potato2: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b0b77_d20210405_m055352_c001_v0001144_t0047") 2021/04/05 05:58:46 DEBUG : a/potato2: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f107e6fea8b924eb4_d20210405_m055315_c001_v0001157_t0059") 2021/04/05 05:58:46 DEBUG : a/potato2: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b089d_d20210405_m055313_c001_v0001144_t0047") 2021/04/05 05:58:46 DEBUG : b/potato: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1035b7b802df0374_d20210405_m055358_c001_v0001150_t0027") 2021/04/05 05:58:47 DEBUG : b/potato: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b0b9e_d20210405_m055354_c001_v0001144_t0047") 2021/04/05 05:58:47 DEBUG : b/potato: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f10502ae67631f341_d20210405_m055352_c001_v0001150_t0000") 2021/04/05 05:58:47 DEBUG : b/potato: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b0b52_d20210405_m055350_c001_v0001144_t0047") 2021/04/05 05:58:47 DEBUG : b/potato: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f111e23f42dc0e562_d20210405_m055314_c001_v0001157_t0005") 2021/04/05 05:58:47 DEBUG : b/potato: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b0873_d20210405_m055311_c001_v0001144_t0047") 2021/04/05 05:58:47 DEBUG : backup/one: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1199c123269860e8_d20210405_m055652_c001_v0001019_t0005") 2021/04/05 05:58:47 DEBUG : backup/one: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f10813a5add61e29a_d20210405_m055649_c001_v0001134_t0000") 2021/04/05 05:58:47 DEBUG : backup/one: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1145e05725b93ca8_d20210405_m055643_c001_v0001157_t0057") 2021/04/05 05:58:47 DEBUG : backup/one-2019-01-01: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f103f89391b78034a_d20210405_m055731_c001_v0001157_t0021") 2021/04/05 05:58:47 DEBUG : backup/one-2019-01-01: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1049809f7a581406_d20210405_m055728_c001_v0001145_t0024") 2021/04/05 05:58:47 DEBUG : backup/one-2019-01-01: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f11504c38558611c1_d20210405_m055722_c001_v0001157_t0058") 2021/04/05 05:58:47 DEBUG : backup/one.bak: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f115896902155a67c_d20210405_m055714_c001_v0001150_t0031") 2021/04/05 05:58:47 DEBUG : backup/one.bak: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1173cd69718a718a_d20210405_m055706_c001_v0001003_t0031") 2021/04/05 05:58:47 DEBUG : backup/one.bak: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f111d6f78349c90a8_d20210405_m055701_c001_v0001093_t0012") 2021/04/05 05:58:47 DEBUG : backup/three-2019-01-01.txt: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f102b189955923e32_d20210405_m055732_c001_v0001153_t0019") 2021/04/05 05:58:47 DEBUG : backup/three-2019-01-01.txt: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1100e5c1d943c040_d20210405_m055730_c001_v0001157_t0017") 2021/04/05 05:58:47 DEBUG : backup/three-2019-01-01.txt: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f106102c5816c94fa_d20210405_m055725_c001_v0001157_t0032") 2021/04/05 05:58:47 DEBUG : backup/three.txt: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1164fab026b58db7_d20210405_m055653_c001_v0001157_t0003") 2021/04/05 05:58:47 DEBUG : backup/three.txt: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1199d08ae083e181_d20210405_m055651_c001_v0001145_t0048") 2021/04/05 05:58:47 DEBUG : backup/three.txt: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1199d08ae083e163_d20210405_m055646_c001_v0001145_t0049") 2021/04/05 05:58:47 DEBUG : backup/three.txt.bak: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1017a8029db01c3d_d20210405_m055714_c001_v0001145_t0050") 2021/04/05 05:58:47 DEBUG : backup/three.txt.bak: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f111d40bee794025b_d20210405_m055713_c001_v0001009_t0034") 2021/04/05 05:58:47 DEBUG : backup/three.txt.bak: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f101bd25460072155_d20210405_m055704_c001_v0001157_t0031") 2021/04/05 05:58:47 DEBUG : c/non empty space: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f100b0e08e5bcfb7c_d20210405_m055358_c001_v0001157_t0035") 2021/04/05 05:58:48 DEBUG : c/non empty space: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b0bb8_d20210405_m055354_c001_v0001144_t0047") 2021/04/05 05:58:48 DEBUG : c/non empty space: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1145a505a2b41aa9_d20210405_m055353_c001_v0001154_t0025") 2021/04/05 05:58:48 DEBUG : c/non empty space: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b0b5d_d20210405_m055350_c001_v0001144_t0047") 2021/04/05 05:58:48 DEBUG : c/non empty space: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f104f6fc457b529f1_d20210405_m055316_c001_v0001091_t0002") 2021/04/05 05:58:48 DEBUG : c/non empty space: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b0885_d20210405_m055312_c001_v0001144_t0047") 2021/04/05 05:58:48 DEBUG : check sum: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f101bd2546006fd6c_d20210405_m055300_c001_v0001157_t0005") 2021/04/05 05:58:48 DEBUG : check sum: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b0789_d20210405_m055258_c001_v0001144_t0047") 2021/04/05 05:58:48 DEBUG : dest/3: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1074ad93a2c2eb85_d20210405_m055616_c001_v0001042_t0041") 2021/04/05 05:58:48 DEBUG : dest/3: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1084ff527d83eb4b_d20210405_m055615_c001_v0001157_t0052") 2021/04/05 05:58:48 DEBUG : dst/one: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f11340414ec5f1a6b_d20210405_m055831_c001_v0001153_t0003") 2021/04/05 05:58:48 DEBUG : dst/one: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f10180c571b0ddb53_d20210405_m055827_c001_v0001154_t0006") 2021/04/05 05:58:48 DEBUG : dst/one: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1106b5319260fc09_d20210405_m055825_c001_v0001144_t0041") 2021/04/05 05:58:48 DEBUG : dst/one: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f10180c571b0ddb3c_d20210405_m055819_c001_v0001154_t0006") 2021/04/05 05:58:48 DEBUG : dst/one: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1033c7ac78f5dfdf_d20210405_m055818_c001_v0001128_t0042") 2021/04/05 05:58:48 DEBUG : dst/one: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f109935855701efcc_d20210405_m055812_c001_v0001144_t0012") 2021/04/05 05:58:48 DEBUG : dst/one: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f113911d6b897eab3_d20210405_m055809_c001_v0001008_t0027") 2021/04/05 05:58:48 DEBUG : dst/one: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1198d6f4a6084fdb_d20210405_m055807_c001_v0001090_t0024") 2021/04/05 05:58:48 DEBUG : dst/one: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1022eb9687a1ecf6_d20210405_m055806_c001_v0001157_t0031") 2021/04/05 05:58:48 DEBUG : dst/one: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1198d6f4a6084fd2_d20210405_m055801_c001_v0001090_t0024") 2021/04/05 05:58:48 DEBUG : dst/one: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1121853ea5fcbdf9_d20210405_m055800_c001_v0001144_t0001") 2021/04/05 05:58:48 DEBUG : dst/one: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f109935855701ef09_d20210405_m055755_c001_v0001144_t0012") 2021/04/05 05:58:48 DEBUG : dst/one: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1174fd2e0d8ddabf_d20210405_m055751_c001_v0001144_t0000") 2021/04/05 05:58:48 DEBUG : dst/one: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1101856a48627e89_d20210405_m055750_c001_v0001145_t0003") 2021/04/05 05:58:48 DEBUG : dst/one: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1155c05a1e751e88_d20210405_m055749_c001_v0001128_t0016") 2021/04/05 05:58:49 DEBUG : dst/one: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1101856a48627e78_d20210405_m055744_c001_v0001145_t0003") 2021/04/05 05:58:49 DEBUG : dst/one: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f113cb0659a784167_d20210405_m055743_c001_v0001157_t0056") 2021/04/05 05:58:49 DEBUG : dst/one: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1164fab026b59368_d20210405_m055738_c001_v0001157_t0049") 2021/04/05 05:58:49 DEBUG : dst/one: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f106102c5816c9657_d20210405_m055737_c001_v0001157_t0049") 2021/04/05 05:58:49 DEBUG : dst/one: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1100e5c1d943c00f_d20210405_m055730_c001_v0001157_t0035") 2021/04/05 05:58:49 DEBUG : dst/one: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f11504c385586129a_d20210405_m055729_c001_v0001157_t0041") 2021/04/05 05:58:49 DEBUG : dst/one: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1100e5c1d943bf8a_d20210405_m055724_c001_v0001157_t0035") 2021/04/05 05:58:49 DEBUG : dst/one: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f111c9c143e09f516_d20210405_m055723_c001_v0001144_t0018") 2021/04/05 05:58:49 DEBUG : dst/one: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b19d2_d20210405_m055716_c001_v0001144_t0047") 2021/04/05 05:58:49 DEBUG : dst/one: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f11342915578bb385_d20210405_m055715_c001_v0001000_t0044") 2021/04/05 05:58:49 DEBUG : dst/one: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1088d26c6b509b9d_d20210405_m055712_c001_v0001145_t0025") 2021/04/05 05:58:49 DEBUG : dst/one: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1032d0253fb732ca_d20210405_m055710_c001_v0001144_t0003") 2021/04/05 05:58:49 DEBUG : dst/one: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1088d26c6b509b37_d20210405_m055702_c001_v0001145_t0025") 2021/04/05 05:58:49 DEBUG : dst/one: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f11825738312b4a46_d20210405_m055701_c001_v0001039_t0042") 2021/04/05 05:58:49 DEBUG : dst/one: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f109935855701ec49_d20210405_m055655_c001_v0001144_t0012") 2021/04/05 05:58:49 DEBUG : dst/one: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f104fa93941e5fbcf_d20210405_m055653_c001_v0001157_t0001") 2021/04/05 05:58:49 DEBUG : dst/one: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f100f5cf9dff7ccd0_d20210405_m055650_c001_v0001144_t0002") 2021/04/05 05:58:49 DEBUG : dst/one: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1164e051aa243136_d20210405_m055649_c001_v0001144_t0059") 2021/04/05 05:58:49 DEBUG : dst/one: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f100f5cf9dff7cc7d_d20210405_m055645_c001_v0001144_t0002") 2021/04/05 05:58:49 DEBUG : dst/one: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f11496899a2c494e5_d20210405_m055644_c001_v0001137_t0030") 2021/04/05 05:58:49 DEBUG : dst/one: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1164fab026b58b78_d20210405_m055637_c001_v0001157_t0049") 2021/04/05 05:58:49 DEBUG : dst/one: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1040d135bb001fd3_d20210405_m055635_c001_v0001025_t0008") 2021/04/05 05:58:49 DEBUG : dst/one: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f101f511e78c36f7f_d20210405_m055627_c001_v0001131_t0008") 2021/04/05 05:58:49 DEBUG : dst/one: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f106334f692edaf30_d20210405_m055626_c001_v0001136_t0034") 2021/04/05 05:58:49 DEBUG : dst/one: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b160b_d20210405_m055622_c001_v0001144_t0047") 2021/04/05 05:58:50 DEBUG : dst/one: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f105e06eb92a14c4b_d20210405_m055621_c001_v0001157_t0031") 2021/04/05 05:58:50 DEBUG : dst/one: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f105e06eb92a14c25_d20210405_m055619_c001_v0001157_t0031") 2021/04/05 05:58:50 DEBUG : dst/one: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1145e05725b9346a_d20210405_m055558_c001_v0001157_t0025") 2021/04/05 05:58:50 DEBUG : dst/one: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1164fab026b58531_d20210405_m055550_c001_v0001157_t0049") 2021/04/05 05:58:50 DEBUG : dst/one: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f117e989a2442d028_d20210405_m055549_c001_v0001157_t0029") 2021/04/05 05:58:50 DEBUG : dst/one: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f117e989a2442cff9_d20210405_m055548_c001_v0001157_t0029") 2021/04/05 05:58:50 DEBUG : dst/one-2019-01-01: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f100f5cf9dff7d257_d20210405_m055831_c001_v0001144_t0046") 2021/04/05 05:58:50 DEBUG : dst/one-2019-01-01: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1142ff04febe4668_d20210405_m055823_c001_v0001145_t0013") 2021/04/05 05:58:50 DEBUG : dst/one-2019-01-01: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1022eb9687a1ee47_d20210405_m055817_c001_v0001157_t0037") 2021/04/05 05:58:50 DEBUG : dst/one.bak: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f100b0e08e5bd2632_d20210405_m055809_c001_v0001157_t0057") 2021/04/05 05:58:50 DEBUG : dst/one.bak: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f110e693dd95070a7_d20210405_m055805_c001_v0001093_t0048") 2021/04/05 05:58:50 DEBUG : dst/one.bak: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1084ff527d83fc6e_d20210405_m055759_c001_v0001157_t0041") 2021/04/05 05:58:50 DEBUG : dst/one.bak: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f11504c3855861683_d20210405_m055752_c001_v0001157_t0008") 2021/04/05 05:58:50 DEBUG : dst/one.bak: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1164fab026b594e7_d20210405_m055748_c001_v0001157_t0015") 2021/04/05 05:58:50 DEBUG : dst/one.bak: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f113501e88efe6836_d20210405_m055743_c001_v0001150_t0053") 2021/04/05 05:58:50 DEBUG : dst/three: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f117e989a2442d6b1_d20210405_m055636_c001_v0001157_t0042") 2021/04/05 05:58:50 DEBUG : dst/three: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f105e06eb92a14e41_d20210405_m055632_c001_v0001157_t0031") 2021/04/05 05:58:50 DEBUG : dst/three-2019-01-01.txt: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1164e051aa2435f7_d20210405_m055832_c001_v0001144_t0020") 2021/04/05 05:58:50 DEBUG : dst/three-2019-01-01.txt: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1020ad9329c5850f_d20210405_m055829_c001_v0001150_t0011") 2021/04/05 05:58:50 DEBUG : dst/three-2019-01-01.txt: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1145e05725b94c57_d20210405_m055820_c001_v0001157_t0059") 2021/04/05 05:58:50 DEBUG : dst/three.txt: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f115bec880074f0f2_d20210405_m055833_c001_v0001154_t0016") 2021/04/05 05:58:50 DEBUG : dst/three.txt: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f10180c571b0ddb64_d20210405_m055830_c001_v0001154_t0006") 2021/04/05 05:58:50 DEBUG : dst/three.txt: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f111e23f42dc115c9_d20210405_m055829_c001_v0001157_t0051") 2021/04/05 05:58:50 DEBUG : dst/three.txt: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f10180c571b0ddb44_d20210405_m055822_c001_v0001154_t0006") 2021/04/05 05:58:51 DEBUG : dst/three.txt: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f111e23f42dc114c9_d20210405_m055821_c001_v0001157_t0031") 2021/04/05 05:58:51 DEBUG : dst/three.txt: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1164fab026b5987b_d20210405_m055814_c001_v0001157_t0049") 2021/04/05 05:58:51 DEBUG : dst/three.txt: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f11932a25e3827c2c_d20210405_m055810_c001_v0001113_t0024") 2021/04/05 05:58:51 DEBUG : dst/three.txt: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1198d6f4a6084fdf_d20210405_m055809_c001_v0001090_t0024") 2021/04/05 05:58:51 DEBUG : dst/three.txt: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f107d96f6592a05d6_d20210405_m055808_c001_v0001145_t0026") 2021/04/05 05:58:51 DEBUG : dst/three.txt: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1198d6f4a6084fd8_d20210405_m055804_c001_v0001090_t0024") 2021/04/05 05:58:51 DEBUG : dst/three.txt: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1116580789bc51bc_d20210405_m055803_c001_v0001031_t0034") 2021/04/05 05:58:51 DEBUG : dst/three.txt: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1164fab026b5961e_d20210405_m055757_c001_v0001157_t0049") 2021/04/05 05:58:51 DEBUG : dst/three.txt: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1022eb9687a1eb4c_d20210405_m055751_c001_v0001157_t0028") 2021/04/05 05:58:51 DEBUG : dst/three.txt: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1164fab026b594c0_d20210405_m055747_c001_v0001157_t0049") 2021/04/05 05:58:51 DEBUG : dst/three.txt: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f11205524a08c30cc_d20210405_m055745_c001_v0001128_t0031") 2021/04/05 05:58:51 DEBUG : dst/three.txt: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b1b55_d20210405_m055740_c001_v0001144_t0047") 2021/04/05 05:58:51 DEBUG : dst/three.txt: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1164fab026b59253_d20210405_m055730_c001_v0001157_t0042") 2021/04/05 05:58:51 DEBUG : dst/three.txt: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b1a76_d20210405_m055727_c001_v0001144_t0047") 2021/04/05 05:58:51 DEBUG : dst/three.txt: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f103187365049b614_d20210405_m055726_c001_v0001137_t0032") 2021/04/05 05:58:51 DEBUG : dst/three.txt: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f109935855701ed6f_d20210405_m055719_c001_v0001144_t0012") 2021/04/05 05:58:51 DEBUG : dst/three.txt: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f111d40bee794025c_d20210405_m055713_c001_v0001009_t0034") 2021/04/05 05:58:51 DEBUG : dst/three.txt: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f109935855701ecc4_d20210405_m055705_c001_v0001144_t0012") 2021/04/05 05:58:51 DEBUG : dst/three.txt: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f105f2b58ebffb385_d20210405_m055704_c001_v0001029_t0000") 2021/04/05 05:58:51 DEBUG : dst/three.txt: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1164fab026b58e6f_d20210405_m055657_c001_v0001157_t0049") 2021/04/05 05:58:51 DEBUG : dst/three.txt: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f111fa560c93835f9_d20210405_m055651_c001_v0001037_t0048") 2021/04/05 05:58:51 DEBUG : dst/three.txt: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1164fab026b58cea_d20210405_m055648_c001_v0001157_t0049") 2021/04/05 05:58:51 DEBUG : dst/three.txt: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1145e05725b93d1c_d20210405_m055646_c001_v0001157_t0023") 2021/04/05 05:58:51 DEBUG : dst/three.txt: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b1756_d20210405_m055640_c001_v0001144_t0047") 2021/04/05 05:58:51 DEBUG : dst/three.txt.bak: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f113a5161f1959e3b_d20210405_m055811_c001_v0001039_t0049") 2021/04/05 05:58:51 DEBUG : dst/three.txt.bak: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f10861fe11536a882_d20210405_m055807_c001_v0001128_t0027") 2021/04/05 05:58:51 DEBUG : dst/three.txt.bak: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1101856a48627eb5_d20210405_m055802_c001_v0001145_t0014") 2021/04/05 05:58:52 DEBUG : dst/three.txt.bak: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f105e06eb92a15b49_d20210405_m055753_c001_v0001157_t0047") 2021/04/05 05:58:52 DEBUG : dst/three.txt.bak: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f117e989a2442e003_d20210405_m055750_c001_v0001157_t0036") 2021/04/05 05:58:52 DEBUG : dst/three.txt.bak: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1145e05725b94629_d20210405_m055745_c001_v0001157_t0000") 2021/04/05 05:58:52 DEBUG : dst/two: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f101bd25460072cd0_d20210405_m055834_c001_v0001157_t0001") 2021/04/05 05:58:52 DEBUG : dst/two: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b1d6d_d20210405_m055813_c001_v0001144_t0047") 2021/04/05 05:58:52 DEBUG : dst/two: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f10426971e7dd7f40_d20210405_m055811_c001_v0001137_t0036") 2021/04/05 05:58:52 DEBUG : dst/two: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b1c2d_d20210405_m055756_c001_v0001144_t0047") 2021/04/05 05:58:52 DEBUG : dst/two: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f113858718647af74_d20210405_m055753_c001_v0001131_t0006") 2021/04/05 05:58:52 DEBUG : dst/two: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f109935855701ee63_d20210405_m055739_c001_v0001144_t0012") 2021/04/05 05:58:52 DEBUG : dst/two: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1162d1f3adae67e0_d20210405_m055737_c001_v0001113_t0047") 2021/04/05 05:58:52 DEBUG : dst/two: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1164fab026b590df_d20210405_m055718_c001_v0001157_t0049") 2021/04/05 05:58:52 DEBUG : dst/two: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1164fab026b5908a_d20210405_m055715_c001_v0001157_t0040") 2021/04/05 05:58:52 DEBUG : dst/two: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b1884_d20210405_m055656_c001_v0001144_t0047") 2021/04/05 05:58:52 DEBUG : dst/two: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1106b5319260f7d4_d20210405_m055654_c001_v0001144_t0059") 2021/04/05 05:58:52 DEBUG : dst/two: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f109935855701eb58_d20210405_m055638_c001_v0001144_t0012") 2021/04/05 05:58:52 DEBUG : dst/two: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f103827918887b402_d20210405_m055636_c001_v0001042_t0039") 2021/04/05 05:58:52 DEBUG : dst/two: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1088d26c6b5099d6_d20210405_m055628_c001_v0001145_t0036") 2021/04/05 05:58:52 DEBUG : dst/two: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f11932a25e3827ab7_d20210405_m055558_c001_v0001113_t0028") 2021/04/05 05:58:52 DEBUG : dst/two: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f117e989a2442d127_d20210405_m055555_c001_v0001157_t0029") 2021/04/05 05:58:52 DEBUG : empty space: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1088d26c6b509765_d20210405_m055532_c001_v0001145_t0030") 2021/04/05 05:58:52 DEBUG : empty space: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1164fab026b5825f_d20210405_m055528_c001_v0001157_t0049") 2021/04/05 05:58:52 DEBUG : empty space: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f105ab29eeda97cf6_d20210405_m055524_c001_v0001009_t0001") 2021/04/05 05:58:52 DEBUG : empty space: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1164fab026b58034_d20210405_m055513_c001_v0001157_t0049") 2021/04/05 05:58:52 DEBUG : empty space: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1016656ccff435e1_d20210405_m055504_c001_v0001153_t0020") 2021/04/05 05:58:52 DEBUG : empty space: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1164fab026b57e4d_d20210405_m055500_c001_v0001157_t0049") 2021/04/05 05:58:53 DEBUG : empty space: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f108d88cbb4f7dd9a_d20210405_m055422_c001_v0001136_t0024") 2021/04/05 05:58:53 DEBUG : empty space: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b0d42_d20210405_m055419_c001_v0001144_t0047") 2021/04/05 05:58:53 DEBUG : empty space: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f11667f2b1c7fc3f1_d20210405_m055415_c001_v0001092_t0027") 2021/04/05 05:58:53 DEBUG : empty space: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b0cf1_d20210405_m055413_c001_v0001144_t0047") 2021/04/05 05:58:53 DEBUG : empty space: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f101bd254600707a1_d20210405_m055408_c001_v0001157_t0017") 2021/04/05 05:58:53 DEBUG : empty space: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b0c4c_d20210405_m055404_c001_v0001144_t0047") 2021/04/05 05:58:53 DEBUG : empty space: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f115896902155a28c_d20210405_m055402_c001_v0001150_t0044") 2021/04/05 05:58:53 DEBUG : empty space: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b0c08_d20210405_m055359_c001_v0001144_t0047") 2021/04/05 05:58:53 DEBUG : empty space: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f10009fb76505de2a_d20210405_m055349_c001_v0001134_t0012") 2021/04/05 05:58:53 DEBUG : empty space: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b0b08_d20210405_m055346_c001_v0001144_t0047") 2021/04/05 05:58:53 DEBUG : empty space: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f104d0dc61b19b62c_d20210405_m055343_c001_v0001042_t0017") 2021/04/05 05:58:53 DEBUG : empty space: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b0a97_d20210405_m055341_c001_v0001144_t0047") 2021/04/05 05:58:53 DEBUG : empty space: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f117e989a2442bd6d_d20210405_m055334_c001_v0001157_t0016") 2021/04/05 05:58:53 DEBUG : empty space: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b09af_d20210405_m055331_c001_v0001144_t0047") 2021/04/05 05:58:53 DEBUG : empty space: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118ec4bcfbd2a72f_d20210405_m055325_c001_v0001113_t0034") 2021/04/05 05:58:53 DEBUG : empty space: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b092e_d20210405_m055324_c001_v0001144_t0047") 2021/04/05 05:58:53 DEBUG : empty space: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f100b0e08e5bcf557_d20210405_m055323_c001_v0001157_t0052") 2021/04/05 05:58:53 DEBUG : empty space: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1145e05725b917d3_d20210405_m055322_c001_v0001157_t0041") 2021/04/05 05:58:53 DEBUG : empty space: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b08c9_d20210405_m055316_c001_v0001144_t0047") 2021/04/05 05:58:53 DEBUG : enormous: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f11620d01b631fdea_d20210405_m055421_c001_v0001120_t0011") 2021/04/05 05:58:53 DEBUG : enormous: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b0d60_d20210405_m055420_c001_v0001144_t0047") 2021/04/05 05:58:53 DEBUG : existing: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f108cb1336bd6361a_d20210405_m055841_c001_v0001040_t0055") 2021/04/05 05:58:53 DEBUG : existing: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1164fab026b59c00_d20210405_m055840_c001_v0001157_t0049") 2021/04/05 05:58:53 DEBUG : existing: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f101732c38fb197bb_d20210405_m055310_c001_v0001144_t0012") 2021/04/05 05:58:53 DEBUG : existing: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b084b_d20210405_m055309_c001_v0001144_t0047") 2021/04/05 05:58:54 DEBUG : existing: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f10279251b341b0dc_d20210405_m055308_c001_v0001092_t0036") 2021/04/05 05:58:54 DEBUG : existing: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b0826_d20210405_m055307_c001_v0001144_t0047") 2021/04/05 05:58:54 DEBUG : existing: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b080e_d20210405_m055306_c001_v0001144_t0047") 2021/04/05 05:58:54 DEBUG : five: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f11476c8d7682d71f_d20210405_m055429_c001_v0001090_t0000") 2021/04/05 05:58:54 DEBUG : five: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f109935855701e3fb_d20210405_m055427_c001_v0001144_t0012") 2021/04/05 05:58:54 DEBUG : foo: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1195084b99f3e5e3_d20210405_m055330_c001_v0001136_t0012") 2021/04/05 05:58:54 DEBUG : foo: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b0982_d20210405_m055327_c001_v0001144_t0047") 2021/04/05 05:58:54 DEBUG : foo: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b095e_d20210405_m055326_c001_v0001144_t0047") 2021/04/05 05:58:54 DEBUG : four: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1009e12cdf9a4197_d20210405_m055430_c001_v0001145_t0039") 2021/04/05 05:58:54 DEBUG : four: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b0e02_d20210405_m055427_c001_v0001144_t0047") 2021/04/05 05:58:54 DEBUG : four: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b0de8_d20210405_m055426_c001_v0001144_t0047") 2021/04/05 05:58:54 DEBUG : hello world2: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1145e05725b90e5b_d20210405_m055233_c001_v0001157_t0038") 2021/04/05 05:58:54 DEBUG : hello world2: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b05d3_d20210405_m055232_c001_v0001144_t0047") 2021/04/05 05:58:54 DEBUG : ignore-size: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1164e051aa2424ec_d20210405_m055305_c001_v0001144_t0031") 2021/04/05 05:58:54 DEBUG : ignore-size: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b07e0_d20210405_m055304_c001_v0001144_t0047") 2021/04/05 05:58:54 DEBUG : nested/sub dir/file: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f11297cd67b0dd766_d20210405_m055457_c001_v0001157_t0034") 2021/04/05 05:58:54 DEBUG : nested/sub dir/file: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f109935855701e5b0_d20210405_m055456_c001_v0001144_t0012") 2021/04/05 05:58:54 DEBUG : nested/sub dir/file: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1100415eb29bbaff_d20210405_m055454_c001_v0001042_t0006") 2021/04/05 05:58:54 DEBUG : nested/sub dir/file: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1164fab026b57d49_d20210405_m055452_c001_v0001157_t0049") 2021/04/05 05:58:54 DEBUG : one: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f108a755ac6e34e09_d20210405_m055431_c001_v0001037_t0055") 2021/04/05 05:58:54 DEBUG : one: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b0db1_d20210405_m055423_c001_v0001144_t0047") 2021/04/05 05:58:54 DEBUG : potato: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f115896902155a399_d20210405_m055451_c001_v0001150_t0043") 2021/04/05 05:58:54 DEBUG : potato: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f109935855701e539_d20210405_m055448_c001_v0001144_t0012") 2021/04/05 05:58:54 DEBUG : potato: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f113cb0659a7825de_d20210405_m055446_c001_v0001157_t0022") 2021/04/05 05:58:54 DEBUG : potato: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b0f54_d20210405_m055443_c001_v0001144_t0047") 2021/04/05 05:58:54 DEBUG : potato: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1106b5319260f07d_d20210405_m055436_c001_v0001144_t0058") 2021/04/05 05:58:55 DEBUG : potato: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f109935855701e447_d20210405_m055433_c001_v0001144_t0012") 2021/04/05 05:58:55 DEBUG : potato: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f101bfa0d515db4bd_d20210405_m055411_c001_v0001019_t0028") 2021/04/05 05:58:55 DEBUG : potato: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b0ca5_d20210405_m055409_c001_v0001144_t0047") 2021/04/05 05:58:55 DEBUG : potato: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f11297cd67b0dd034_d20210405_m055405_c001_v0001157_t0058") 2021/04/05 05:58:55 DEBUG : potato: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b0c3e_d20210405_m055403_c001_v0001144_t0047") 2021/04/05 05:58:55 DEBUG : potato: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1165c39d16492e28_d20210405_m055400_c001_v0001150_t0012") 2021/04/05 05:58:55 DEBUG : potato: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b0bfd_d20210405_m055359_c001_v0001144_t0047") 2021/04/05 05:58:55 DEBUG : potato: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f109935855701e1d7_d20210405_m055348_c001_v0001144_t0028") 2021/04/05 05:58:55 DEBUG : potato: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b0af9_d20210405_m055345_c001_v0001144_t0047") 2021/04/05 05:58:55 DEBUG : potato: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f117b7c42f33e2d00_d20210405_m055344_c001_v0001008_t0001") 2021/04/05 05:58:55 DEBUG : potato: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b0a8e_d20210405_m055341_c001_v0001144_t0047") 2021/04/05 05:58:55 DEBUG : potato: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1084ff527d83cfdc_d20210405_m055340_c001_v0001157_t0044") 2021/04/05 05:58:55 DEBUG : potato: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b0a73_d20210405_m055339_c001_v0001144_t0047") 2021/04/05 05:58:55 DEBUG : potato: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b0a58_d20210405_m055338_c001_v0001144_t0047") 2021/04/05 05:58:55 DEBUG : potato: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f102ddc27f2136057_d20210405_m055337_c001_v0001032_t0056") 2021/04/05 05:58:55 DEBUG : potato: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b0a22_d20210405_m055336_c001_v0001144_t0047") 2021/04/05 05:58:55 DEBUG : potato: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b0a0b_d20210405_m055335_c001_v0001144_t0047") 2021/04/05 05:58:55 DEBUG : potato: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f105e06eb92a12ec7_d20210405_m055334_c001_v0001157_t0056") 2021/04/05 05:58:55 DEBUG : potato: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b09c4_d20210405_m055332_c001_v0001144_t0047") 2021/04/05 05:58:55 DEBUG : potato2: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f113cb0659a782efc_d20210405_m055543_c001_v0001157_t0016") 2021/04/05 05:58:55 DEBUG : potato2: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b1370_d20210405_m055542_c001_v0001144_t0047") 2021/04/05 05:58:55 DEBUG : potato2: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1089569858f6fe6e_d20210405_m055536_c001_v0001130_t0006") 2021/04/05 05:58:55 DEBUG : potato2: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b1258_d20210405_m055527_c001_v0001144_t0047") 2021/04/05 05:58:55 DEBUG : potato2: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f114671c8aa2cf463_d20210405_m055518_c001_v0001130_t0057") 2021/04/05 05:58:55 DEBUG : potato2: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b115c_d20210405_m055512_c001_v0001144_t0047") 2021/04/05 05:58:55 DEBUG : potato2: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f10426971e7dd7dae_d20210405_m055506_c001_v0001137_t0008") 2021/04/05 05:58:56 DEBUG : potato2: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b1088_d20210405_m055459_c001_v0001144_t0047") 2021/04/05 05:58:56 DEBUG : potato2: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f117c58a7a6708e6a_d20210405_m055421_c001_v0001090_t0059") 2021/04/05 05:58:56 DEBUG : potato2: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b0d3a_d20210405_m055418_c001_v0001144_t0047") 2021/04/05 05:58:56 DEBUG : potato2: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f117704135b042dc0_d20210405_m055417_c001_v0001150_t0011") 2021/04/05 05:58:56 DEBUG : potato2: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b0ce0_d20210405_m055413_c001_v0001144_t0047") 2021/04/05 05:58:56 DEBUG : potato2: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f11985ce906c567cd_d20210405_m055412_c001_v0001157_t0051") 2021/04/05 05:58:56 DEBUG : potato2: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b0cbd_d20210405_m055410_c001_v0001144_t0047") 2021/04/05 05:58:56 DEBUG : potato2: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f107e6fea8b9256a5_d20210405_m055408_c001_v0001157_t0057") 2021/04/05 05:58:56 DEBUG : potato2: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b0c70_d20210405_m055405_c001_v0001144_t0047") 2021/04/05 05:58:56 DEBUG : potato2: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f111e23f42dc0ecc1_d20210405_m055402_c001_v0001157_t0007") 2021/04/05 05:58:56 DEBUG : potato2: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b0c1a_d20210405_m055400_c001_v0001144_t0047") 2021/04/05 05:58:56 DEBUG : potato2: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1002591a918f9439_d20210405_m055349_c001_v0001012_t0003") 2021/04/05 05:58:56 DEBUG : potato2: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b0b1f_d20210405_m055347_c001_v0001144_t0047") 2021/04/05 05:58:56 DEBUG : potato2: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1145e05725b90fd6_d20210405_m055241_c001_v0001157_t0044") 2021/04/05 05:58:56 DEBUG : potato2: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b064f_d20210405_m055239_c001_v0001144_t0047") 2021/04/05 05:58:56 DEBUG : potato2: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f10915a7dd3d5c4b7_d20210405_m055235_c001_v0001044_t0037") 2021/04/05 05:58:56 DEBUG : potato2: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b05ff_d20210405_m055234_c001_v0001144_t0047") 2021/04/05 05:58:56 DEBUG : potato3: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f117e989a2442ce5b_d20210405_m055533_c001_v0001157_t0040") 2021/04/05 05:58:56 DEBUG : potato3: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f109935855701e794_d20210405_m055529_c001_v0001144_t0012") 2021/04/05 05:58:56 DEBUG : potato3: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f101745d52aeb9103_d20210405_m055518_c001_v0001041_t0054") 2021/04/05 05:58:56 DEBUG : potato3: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f109935855701e6af_d20210405_m055514_c001_v0001144_t0012") 2021/04/05 05:58:56 DEBUG : potato3: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f10302fa6e829f8dd_d20210405_m055505_c001_v0001145_t0016") 2021/04/05 05:58:56 DEBUG : potato3: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f109935855701e607_d20210405_m055501_c001_v0001144_t0012") 2021/04/05 05:58:56 DEBUG : pre-dest1/1: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f101bd25460071aaa_d20210405_m055616_c001_v0001157_t0042") 2021/04/05 05:58:56 DEBUG : pre-dest1/1: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1164fab026b587d3_d20210405_m055609_c001_v0001157_t0049") 2021/04/05 05:58:57 DEBUG : pre-dest2/2: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f113cb0659a783440_d20210405_m055617_c001_v0001157_t0048") 2021/04/05 05:58:57 DEBUG : pre-dest2/2: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f109935855701e9c3_d20210405_m055610_c001_v0001144_t0012") 2021/04/05 05:58:57 DEBUG : sizeonly: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f101bd2546006fdd0_d20210405_m055303_c001_v0001157_t0013") 2021/04/05 05:58:57 DEBUG : sizeonly: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b07b5_d20210405_m055301_c001_v0001144_t0047") 2021/04/05 05:58:57 DEBUG : sub dir/hello world: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f107e6fea8b925e29_d20210405_m055457_c001_v0001157_t0020") 2021/04/05 05:58:57 DEBUG : sub dir/hello world: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1164fab026b57db9_d20210405_m055456_c001_v0001157_t0049") 2021/04/05 05:58:57 DEBUG : sub dir/hello world: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1032d0253fb72a66_d20210405_m055454_c001_v0001144_t0020") 2021/04/05 05:58:57 DEBUG : sub dir/hello world: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b1004_d20210405_m055453_c001_v0001144_t0047") 2021/04/05 05:58:57 DEBUG : sub dir/hello world: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f117b7c42f33e2cf1_d20210405_m055258_c001_v0001008_t0001") 2021/04/05 05:58:57 DEBUG : sub dir/hello world: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b076b_d20210405_m055256_c001_v0001144_t0047") 2021/04/05 05:58:57 DEBUG : sub dir/hello world: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1032d0253fb722b5_d20210405_m055255_c001_v0001144_t0000") 2021/04/05 05:58:57 DEBUG : sub dir/hello world: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b0748_d20210405_m055254_c001_v0001144_t0047") 2021/04/05 05:58:57 DEBUG : sub dir/hello world: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f108d8e7f517c555f_d20210405_m055253_c001_v0001144_t0036") 2021/04/05 05:58:57 DEBUG : sub dir/hello world: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b06fe_d20210405_m055250_c001_v0001144_t0047") 2021/04/05 05:58:57 DEBUG : sub dir/hello world: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f111cb007d9b7cdf2_d20210405_m055249_c001_v0001092_t0016") 2021/04/05 05:58:57 DEBUG : sub dir/hello world: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b06db_d20210405_m055248_c001_v0001144_t0047") 2021/04/05 05:58:57 DEBUG : sub dir/hello world: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f11297cd67b0dc52b_d20210405_m055247_c001_v0001157_t0014") 2021/04/05 05:58:57 DEBUG : sub dir/hello world: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b069c_d20210405_m055244_c001_v0001144_t0047") 2021/04/05 05:58:57 DEBUG : sub dir/hello world: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1059a01db55753c4_d20210405_m055243_c001_v0001144_t0025") 2021/04/05 05:58:57 DEBUG : sub dir/hello world: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b0674_d20210405_m055242_c001_v0001144_t0047") 2021/04/05 05:58:57 DEBUG : sub dir/hello world: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f10907ad9a0e931c3_d20210405_m055232_c001_v0001157_t0057") 2021/04/05 05:58:57 DEBUG : sub dir/hello world: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b05ac_d20210405_m055231_c001_v0001144_t0047") 2021/04/05 05:58:57 DEBUG : sub dir/hello world: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1092d08d875dfa54_d20210405_m055230_c001_v0001036_t0037") 2021/04/05 05:58:57 DEBUG : sub dir/hello world: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b057c_d20210405_m055229_c001_v0001144_t0047") 2021/04/05 05:58:57 DEBUG : sub dir/hello world: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1066e3e0eabfb259_d20210405_m055228_c001_v0001145_t0008") 2021/04/05 05:58:57 DEBUG : sub dir/hello world: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b055c_d20210405_m055227_c001_v0001144_t0047") 2021/04/05 05:58:58 DEBUG : sub dir/hello world: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1084ff527d83c208_d20210405_m055225_c001_v0001157_t0022") 2021/04/05 05:58:58 DEBUG : sub dir/hello world: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b052d_d20210405_m055224_c001_v0001144_t0047") 2021/04/05 05:58:58 DEBUG : sub/yam: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f11504c385585f623_d20210405_m055450_c001_v0001157_t0034") 2021/04/05 05:58:58 DEBUG : sub/yam: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1164fab026b57c95_d20210405_m055448_c001_v0001157_t0049") 2021/04/05 05:58:58 DEBUG : three: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f106c605209376b85_d20210405_m055431_c001_v0001030_t0026") 2021/04/05 05:58:58 DEBUG : three: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b0e23_d20210405_m055428_c001_v0001144_t0047") 2021/04/05 05:58:58 DEBUG : three: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b0dd8_d20210405_m055425_c001_v0001144_t0047") 2021/04/05 05:58:58 DEBUG : two: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f104704ccd38638de_d20210405_m055432_c001_v0001153_t0024") 2021/04/05 05:58:58 DEBUG : two: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1164fab026b57968_d20210405_m055427_c001_v0001157_t0049") 2021/04/05 05:58:58 DEBUG : two: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f118a8f43af8b0dc0_d20210405_m055424_c001_v0001144_t0047") 2021/04/05 05:58:58 DEBUG : yam: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1184a2f910698237_d20210405_m055451_c001_v0001157_t0003") 2021/04/05 05:58:58 DEBUG : yam: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1145e05725b92868_d20210405_m055450_c001_v0001157_t0029") 2021/04/05 05:58:58 DEBUG : yam: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f103f89391b77ef74_d20210405_m055445_c001_v0001157_t0038") 2021/04/05 05:58:58 DEBUG : yam: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1164fab026b57bd3_d20210405_m055443_c001_v0001157_t0049") 2021/04/05 05:58:58 DEBUG : yam: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f113e8cef18d410fe_d20210405_m055435_c001_v0001044_t0052") 2021/04/05 05:58:58 DEBUG : yam: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1164fab026b57a4c_d20210405_m055433_c001_v0001157_t0049") 2021/04/05 05:58:58 DEBUG : yaml: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1019d83f276bbd40_d20210405_m055447_c001_v0001090_t0001") 2021/04/05 05:58:58 DEBUG : yaml: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f11504c385585f50a_d20210405_m055445_c001_v0001157_t0052") 2021/04/05 05:58:58 DEBUG : yaml: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f104fa93941e5e52a_d20210405_m055437_c001_v0001157_t0006") 2021/04/05 05:58:58 DEBUG : yaml: Deleting (id "4_z3f22dd53906dab0b7f880e1b_f1017a8029db0181c_d20210405_m055434_c001_v0001145_t0018") "./sync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -fast-list" - Finished OK in 6m38.02696955s (try 1/5)