"./sync.test -test.v -test.timeout 1h0m0s -remote TestB2: -verbose" - Starting (try 1/5) === 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-joxuquq0miqipox0fitiwey0", Local "Local file system at /tmp/rclone918532241", Modify Window "1ms" 2020/08/16 05:00:36 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set 2020/08/16 05:00:36 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for checks to finish 2020/08/16 05:00:36 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for transfers to finish --- PASS: TestCopyWithDryRun (1.49s) === RUN TestCopy run.go:176: Remote "B2 bucket rclone-test-joxuquq0miqipox0fitiwey0", Local "Local file system at /tmp/rclone918532241", Modify Window "1ms" 2020/08/16 05:00:37 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for checks to finish 2020/08/16 05:00:37 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for transfers to finish 2020/08/16 05:00:42 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2020/08/16 05:00:42 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopy (7.63s) === RUN TestCopyMissingDirectory run.go:176: Remote "B2 bucket rclone-test-joxuquq0miqipox0fitiwey0", Local "Local file system at /tmp/rclone918532241", Modify Window "1ms" 2020/08/16 05:00:44 ERROR : : error reading source directory: directory not found 2020/08/16 05:00:44 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for checks to finish 2020/08/16 05:00:44 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for transfers to finish --- PASS: TestCopyMissingDirectory (0.45s) === RUN TestCopyNoTraverse run.go:176: Remote "B2 bucket rclone-test-joxuquq0miqipox0fitiwey0", Local "Local file system at /tmp/rclone918532241", Modify Window "1ms" 2020/08/16 05:00:45 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for checks to finish 2020/08/16 05:00:45 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for transfers to finish 2020/08/16 05:00:46 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2020/08/16 05:00:46 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoTraverse (2.28s) === RUN TestCopyCheckFirst run.go:176: Remote "B2 bucket rclone-test-joxuquq0miqipox0fitiwey0", Local "Local file system at /tmp/rclone918532241", Modify Window "1ms" 2020/08/16 05:00:47 INFO : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Running all checks before starting transfers 2020/08/16 05:00:47 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for checks to finish 2020/08/16 05:00:47 INFO : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Checks finished, now starting transfers 2020/08/16 05:00:47 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for transfers to finish 2020/08/16 05:00:48 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2020/08/16 05:00:48 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyCheckFirst (3.80s) === RUN TestSyncNoTraverse run.go:176: Remote "B2 bucket rclone-test-joxuquq0miqipox0fitiwey0", Local "Local file system at /tmp/rclone918532241", Modify Window "1ms" 2020/08/16 05:00:51 ERROR : Ignoring --no-traverse with sync 2020/08/16 05:00:51 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for checks to finish 2020/08/16 05:00:51 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for transfers to finish 2020/08/16 05:00:52 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2020/08/16 05:00:52 INFO : sub dir/hello world: Copied (new) 2020/08/16 05:00:52 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoTraverse (2.49s) === RUN TestCopyWithDepth run.go:176: Remote "B2 bucket rclone-test-joxuquq0miqipox0fitiwey0", Local "Local file system at /tmp/rclone918532241", Modify Window "1ms" 2020/08/16 05:00:53 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for checks to finish 2020/08/16 05:00:53 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for transfers to finish 2020/08/16 05:00:54 DEBUG : hello world2: SHA-1 = c0bf117f9c93d02c894fe8ffd6e5cecf3545c209 OK 2020/08/16 05:00:54 INFO : hello world2: Copied (new) --- PASS: TestCopyWithDepth (2.08s) === RUN TestCopyWithFilesFrom run.go:176: Remote "B2 bucket rclone-test-joxuquq0miqipox0fitiwey0", Local "Local file system at /tmp/rclone918532241", Modify Window "1ms" 2020/08/16 05:00:55 DEBUG : hello world2: Excluded 2020/08/16 05:00:55 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for checks to finish 2020/08/16 05:00:55 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for transfers to finish 2020/08/16 05:01:00 DEBUG : potato2: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2020/08/16 05:01:00 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFrom (7.35s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:176: Remote "B2 bucket rclone-test-joxuquq0miqipox0fitiwey0", Local "Local file system at /tmp/rclone918532241", Modify Window "1ms" 2020/08/16 05:01:03 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for checks to finish 2020/08/16 05:01:03 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for transfers to finish 2020/08/16 05:01:04 DEBUG : potato2: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2020/08/16 05:01:04 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFromAndNoTraverse (2.51s) === RUN TestCopyEmptyDirectories run.go:176: Remote "B2 bucket rclone-test-joxuquq0miqipox0fitiwey0", Local "Local file system at /tmp/rclone918532241", Modify Window "1ms" 2020/08/16 05:01:05 DEBUG : sub dir2: Making directory 2020/08/16 05:01:05 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for checks to finish 2020/08/16 05:01:05 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for transfers to finish 2020/08/16 05:01:06 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2020/08/16 05:01:06 INFO : sub dir/hello world: Copied (new) 2020/08/16 05:01:06 DEBUG : sub dir2: Making directory 2020/08/16 05:01:06 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: copied 1 directories fstest.go:247: Filtering empty directory "sub dir2" --- PASS: TestCopyEmptyDirectories (2.12s) === RUN TestMoveEmptyDirectories run.go:176: Remote "B2 bucket rclone-test-joxuquq0miqipox0fitiwey0", Local "Local file system at /tmp/rclone918532241", Modify Window "1ms" 2020/08/16 05:01:07 DEBUG : sub dir2: Making directory 2020/08/16 05:01:07 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for checks to finish 2020/08/16 05:01:07 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for transfers to finish 2020/08/16 05:01:10 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2020/08/16 05:01:10 INFO : sub dir/hello world: Copied (new) 2020/08/16 05:01:10 INFO : sub dir/hello world: Deleted 2020/08/16 05:01:10 DEBUG : sub dir: Making directory 2020/08/16 05:01:10 DEBUG : sub dir2: Making directory 2020/08/16 05:01:10 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: copied 2 directories fstest.go:247: Filtering empty directory "sub dir2" --- PASS: TestMoveEmptyDirectories (6.92s) === RUN TestSyncEmptyDirectories run.go:176: Remote "B2 bucket rclone-test-joxuquq0miqipox0fitiwey0", Local "Local file system at /tmp/rclone918532241", Modify Window "1ms" 2020/08/16 05:01:14 DEBUG : sub dir2: Making directory 2020/08/16 05:01:14 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for checks to finish 2020/08/16 05:01:14 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for transfers to finish 2020/08/16 05:01:16 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2020/08/16 05:01:16 INFO : sub dir/hello world: Copied (new) 2020/08/16 05:01:16 DEBUG : sub dir2: Making directory 2020/08/16 05:01:16 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: copied 1 directories 2020/08/16 05:01:16 DEBUG : Waiting for deletions to finish fstest.go:247: Filtering empty directory "sub dir2" --- PASS: TestSyncEmptyDirectories (5.94s) === RUN TestServerSideCopy run.go:176: Remote "B2 bucket rclone-test-joxuquq0miqipox0fitiwey0", Local "Local file system at /tmp/rclone918532241", Modify Window "1ms" sync_test.go:275: Server side copy (if possible) B2 bucket rclone-test-joxuquq0miqipox0fitiwey0 -> B2 bucket rclone-test-hawedig2fajiqid4fifibut4 2020/08/16 05:01:23 DEBUG : B2 bucket rclone-test-hawedig2fajiqid4fifibut4: Waiting for checks to finish 2020/08/16 05:01:23 DEBUG : B2 bucket rclone-test-hawedig2fajiqid4fifibut4: Waiting for transfers to finish 2020/08/16 05:01:27 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2020/08/16 05:01:27 INFO : sub dir/hello world: Copied (server side copy) 2020/08/16 05:01:27 DEBUG : B2 bucket rclone-test-hawedig2fajiqid4fifibut4: Purge remote 2020/08/16 05:01:27 DEBUG : sub dir/hello world: Deleting (id "4_z4f42cd53a04dfb5b7f380e1b_f1010f8d6a33d7bf4_d20200816_m050123_c001_v0001144_t0032") --- PASS: TestServerSideCopy (8.82s) === RUN TestCopyAfterDelete run.go:176: Remote "B2 bucket rclone-test-joxuquq0miqipox0fitiwey0", Local "Local file system at /tmp/rclone918532241", Modify Window "1ms" 2020/08/16 05:01:40 ERROR : : error listing: directory not found 2020/08/16 05:01:40 DEBUG : Local file system at /tmp/rclone918532241: Making directory 2020/08/16 05:01:40 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for checks to finish 2020/08/16 05:01:40 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for transfers to finish --- PASS: TestCopyAfterDelete (15.20s) === RUN TestCopyRedownload run.go:176: Remote "B2 bucket rclone-test-joxuquq0miqipox0fitiwey0", Local "Local file system at /tmp/rclone918532241", Modify Window "1ms" 2020/08/16 05:01:48 DEBUG : Local file system at /tmp/rclone918532241: Waiting for checks to finish 2020/08/16 05:01:48 DEBUG : Local file system at /tmp/rclone918532241: Waiting for transfers to finish 2020/08/16 05:01:54 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2020/08/16 05:01:54 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyRedownload (10.59s) === RUN TestSyncBasedOnCheckSum run.go:176: Remote "B2 bucket rclone-test-joxuquq0miqipox0fitiwey0", Local "Local file system at /tmp/rclone918532241", Modify Window "1ms" 2020/08/16 05:01:55 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for checks to finish 2020/08/16 05:01:55 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for transfers to finish 2020/08/16 05:01:55 DEBUG : check sum: Clearing upload URL because of error: Post "https://pod-000-1144-17.backblaze.com/b2api/v1/b2_upload_file/cff20dd3a04dfb5b7f380e1b/c001_v0001144_t0048": EOF 2020/08/16 05:01:55 DEBUG : pacer: low level retry 1/1 (error Post "https://pod-000-1144-17.backblaze.com/b2api/v1/b2_upload_file/cff20dd3a04dfb5b7f380e1b/c001_v0001144_t0048": EOF) 2020/08/16 05:01:55 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2020/08/16 05:01:55 DEBUG : check sum: Received error: Post "https://pod-000-1144-17.backblaze.com/b2api/v1/b2_upload_file/cff20dd3a04dfb5b7f380e1b/c001_v0001144_t0048": EOF - low level retry 1/10 2020/08/16 05:01:56 DEBUG : pacer: Reducing sleep to 10ms 2020/08/16 05:01:59 DEBUG : check sum: SHA-1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2020/08/16 05:01:59 INFO : check sum: Copied (new) 2020/08/16 05:01:59 DEBUG : Waiting for deletions to finish 2020/08/16 05:02:00 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for checks to finish 2020/08/16 05:02:00 DEBUG : check sum: SHA-1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2020/08/16 05:02:00 DEBUG : check sum: Size and SHA-1 of src and dst objects identical 2020/08/16 05:02:00 DEBUG : check sum: Unchanged skipping 2020/08/16 05:02:00 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for transfers to finish 2020/08/16 05:02:00 DEBUG : Waiting for deletions to finish 2020/08/16 05:02:00 INFO : There was nothing to transfer --- PASS: TestSyncBasedOnCheckSum (6.35s) === RUN TestSyncSizeOnly run.go:176: Remote "B2 bucket rclone-test-joxuquq0miqipox0fitiwey0", Local "Local file system at /tmp/rclone918532241", Modify Window "1ms" 2020/08/16 05:02:01 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for checks to finish 2020/08/16 05:02:01 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for transfers to finish 2020/08/16 05:02:06 DEBUG : sizeonly: SHA-1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2020/08/16 05:02:06 INFO : sizeonly: Copied (new) 2020/08/16 05:02:06 DEBUG : Waiting for deletions to finish 2020/08/16 05:02:06 DEBUG : sizeonly: Sizes identical 2020/08/16 05:02:06 DEBUG : sizeonly: Unchanged skipping 2020/08/16 05:02:06 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for checks to finish 2020/08/16 05:02:06 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for transfers to finish 2020/08/16 05:02:06 DEBUG : Waiting for deletions to finish 2020/08/16 05:02:06 INFO : There was nothing to transfer --- PASS: TestSyncSizeOnly (5.86s) === RUN TestSyncIgnoreSize run.go:176: Remote "B2 bucket rclone-test-joxuquq0miqipox0fitiwey0", Local "Local file system at /tmp/rclone918532241", Modify Window "1ms" 2020/08/16 05:02:07 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for checks to finish 2020/08/16 05:02:07 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for transfers to finish 2020/08/16 05:02:10 DEBUG : ignore-size: SHA-1 = 4a756ca07e9487f482465a99e8286abc86ba4dc7 OK 2020/08/16 05:02:10 INFO : ignore-size: Copied (new) 2020/08/16 05:02:10 DEBUG : Waiting for deletions to finish 2020/08/16 05:02:11 DEBUG : ignore-size: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2020/08/16 05:02:11 DEBUG : ignore-size: Unchanged skipping 2020/08/16 05:02:11 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for checks to finish 2020/08/16 05:02:11 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for transfers to finish 2020/08/16 05:02:11 DEBUG : Waiting for deletions to finish 2020/08/16 05:02:11 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreSize (7.16s) === RUN TestSyncIgnoreTimes run.go:176: Remote "B2 bucket rclone-test-joxuquq0miqipox0fitiwey0", Local "Local file system at /tmp/rclone918532241", Modify Window "1ms" 2020/08/16 05:02:17 DEBUG : existing: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2020/08/16 05:02:17 DEBUG : existing: Unchanged skipping 2020/08/16 05:02:17 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for checks to finish 2020/08/16 05:02:17 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for transfers to finish 2020/08/16 05:02:17 DEBUG : Waiting for deletions to finish 2020/08/16 05:02:17 INFO : There was nothing to transfer 2020/08/16 05:02:17 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for checks to finish 2020/08/16 05:02:17 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2020/08/16 05:02:17 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for transfers to finish 2020/08/16 05:02:21 DEBUG : existing: SHA-1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2020/08/16 05:02:21 INFO : existing: Copied (replaced existing) 2020/08/16 05:02:21 DEBUG : Waiting for deletions to finish --- PASS: TestSyncIgnoreTimes (8.47s) === RUN TestSyncIgnoreExisting run.go:176: Remote "B2 bucket rclone-test-joxuquq0miqipox0fitiwey0", Local "Local file system at /tmp/rclone918532241", Modify Window "1ms" 2020/08/16 05:02:23 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for checks to finish 2020/08/16 05:02:23 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for transfers to finish 2020/08/16 05:02:27 DEBUG : existing: SHA-1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2020/08/16 05:02:27 INFO : existing: Copied (new) 2020/08/16 05:02:27 DEBUG : Waiting for deletions to finish 2020/08/16 05:02:27 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for checks to finish 2020/08/16 05:02:27 DEBUG : existing: Destination exists, skipping 2020/08/16 05:02:27 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for transfers to finish 2020/08/16 05:02:27 DEBUG : Waiting for deletions to finish 2020/08/16 05:02:27 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreExisting (5.43s) === RUN TestSyncIgnoreErrors run.go:176: Remote "B2 bucket rclone-test-joxuquq0miqipox0fitiwey0", Local "Local file system at /tmp/rclone918532241", Modify Window "1ms" 2020/08/16 05:02:36 DEBUG : d: Making directory fstest.go:247: Filtering empty directory "d" 2020/08/16 05:02:37 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for checks to finish 2020/08/16 05:02:37 DEBUG : c/non empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/08/16 05:02:37 DEBUG : c/non empty space: Unchanged skipping 2020/08/16 05:02:37 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for transfers to finish 2020/08/16 05:02:41 DEBUG : a/potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2020/08/16 05:02:41 INFO : a/potato2: Copied (new) 2020/08/16 05:02:41 DEBUG : Waiting for deletions to finish 2020/08/16 05:02:42 INFO : b/potato: Deleted --- PASS: TestSyncIgnoreErrors (23.87s) === RUN TestSyncAfterChangingModtimeOnly run.go:176: Remote "B2 bucket rclone-test-joxuquq0miqipox0fitiwey0", Local "Local file system at /tmp/rclone918532241", Modify Window "1ms" 2020/08/16 05:02:56 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 2020/08/16 05:02:56 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for checks to finish 2020/08/16 05:02:56 DEBUG : empty space: SHA-1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2020/08/16 05:02:56 NOTICE: empty space: Skipped update modification time as --dry-run is set 2020/08/16 05:02:56 DEBUG : empty space: Unchanged skipping 2020/08/16 05:02:56 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for transfers to finish 2020/08/16 05:02:56 DEBUG : Waiting for deletions to finish 2020/08/16 05:02:56 INFO : There was nothing to transfer 2020/08/16 05:02:56 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for checks to finish 2020/08/16 05:02:56 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 2020/08/16 05:02:56 DEBUG : empty space: SHA-1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2020/08/16 05:03:00 INFO : empty space: Updated modification time in destination 2020/08/16 05:03:00 DEBUG : empty space: Unchanged skipping 2020/08/16 05:03:00 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for transfers to finish 2020/08/16 05:03:00 DEBUG : Waiting for deletions to finish 2020/08/16 05:03:00 INFO : There was nothing to transfer --- PASS: TestSyncAfterChangingModtimeOnly (12.69s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:176: Remote "B2 bucket rclone-test-joxuquq0miqipox0fitiwey0", Local "Local file system at /tmp/rclone918532241", Modify Window "1ms" 2020/08/16 05:03:08 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for checks to finish 2020/08/16 05:03:08 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 2020/08/16 05:03:08 DEBUG : empty space: SHA-1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2020/08/16 05:03:08 DEBUG : empty space: Unchanged skipping 2020/08/16 05:03:08 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for transfers to finish 2020/08/16 05:03:08 DEBUG : Waiting for deletions to finish 2020/08/16 05:03:08 INFO : There was nothing to transfer --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (6.56s) === RUN TestSyncDoesntUpdateModtime run.go:176: Remote "B2 bucket rclone-test-joxuquq0miqipox0fitiwey0", Local "Local file system at /tmp/rclone918532241", Modify Window "1ms" 2020/08/16 05:03:14 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for checks to finish 2020/08/16 05:03:14 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 2020/08/16 05:03:14 DEBUG : foo: SHA-1 = 0beec7b5ea3f0fdbc95d0dd47f3c5bc275da8a33 (Local file system at /tmp/rclone918532241) 2020/08/16 05:03:14 DEBUG : foo: SHA-1 = 62cdb7020ff920e5aa642c3d4066950dd1f01f4d (B2 bucket rclone-test-joxuquq0miqipox0fitiwey0) 2020/08/16 05:03:14 DEBUG : foo: SHA-1 differ 2020/08/16 05:03:14 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for transfers to finish 2020/08/16 05:03:17 DEBUG : foo: SHA-1 = 0beec7b5ea3f0fdbc95d0dd47f3c5bc275da8a33 OK 2020/08/16 05:03:17 INFO : foo: Copied (replaced existing) 2020/08/16 05:03:17 DEBUG : Waiting for deletions to finish --- PASS: TestSyncDoesntUpdateModtime (6.99s) === RUN TestSyncAfterAddingAFile run.go:176: Remote "B2 bucket rclone-test-joxuquq0miqipox0fitiwey0", Local "Local file system at /tmp/rclone918532241", Modify Window "1ms" 2020/08/16 05:03:21 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/08/16 05:03:21 DEBUG : empty space: Unchanged skipping 2020/08/16 05:03:21 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for checks to finish 2020/08/16 05:03:21 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for transfers to finish 2020/08/16 05:03:24 DEBUG : potato: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2020/08/16 05:03:24 INFO : potato: Copied (new) 2020/08/16 05:03:24 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterAddingAFile (14.75s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:176: Remote "B2 bucket rclone-test-joxuquq0miqipox0fitiwey0", Local "Local file system at /tmp/rclone918532241", Modify Window "1ms" 2020/08/16 05:03:37 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for checks to finish 2020/08/16 05:03:37 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2020/08/16 05:03:37 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for transfers to finish 2020/08/16 05:03:41 DEBUG : potato: SHA-1 = 0fd321a1023ac4e1e1054fcc0236b9d0755f722f OK 2020/08/16 05:03:41 INFO : potato: Copied (replaced existing) 2020/08/16 05:03:41 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingFilesSizeOnly (9.30s) === RUN TestSyncAfterChangingContentsOnly run.go:176: Remote "B2 bucket rclone-test-joxuquq0miqipox0fitiwey0", Local "Local file system at /tmp/rclone918532241", Modify Window "1ms" fstest.go:295: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:302: Error Trace: fstest.go:302 fstest.go:335 fstest.go:347 sync_test.go:667 Error: Should be true Test: TestSyncAfterChangingContentsOnly Messages: listing wrong, want potato (21) got fstest.go:200: Not found "potato" fstest.go:203: Error Trace: fstest.go:203 fstest.go:307 fstest.go:335 fstest.go:347 sync_test.go:667 Error: Not equal: expected: 0 actual : 1 Test: TestSyncAfterChangingContentsOnly Messages: 1 objects not found 2020/08/16 05:03:56 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for checks to finish 2020/08/16 05:03:56 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for transfers to finish 2020/08/16 05:04:00 DEBUG : potato: SHA-1 = 1ca5094b3b73f587249eabe179c40a3eec839b1f OK 2020/08/16 05:04:00 INFO : potato: Copied (new) 2020/08/16 05:04:00 DEBUG : Waiting for deletions to finish --- FAIL: TestSyncAfterChangingContentsOnly (23.27s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:176: Remote "B2 bucket rclone-test-joxuquq0miqipox0fitiwey0", Local "Local file system at /tmp/rclone918532241", Modify Window "1ms" 2020/08/16 05:04:14 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/08/16 05:04:14 DEBUG : empty space: Unchanged skipping 2020/08/16 05:04:14 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for checks to finish 2020/08/16 05:04:14 NOTICE: potato2: Skipped copy as --dry-run is set 2020/08/16 05:04:14 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for transfers to finish 2020/08/16 05:04:14 DEBUG : Waiting for deletions to finish 2020/08/16 05:04:14 NOTICE: potato: Skipped delete as --dry-run is set --- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (43.33s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:176: Remote "B2 bucket rclone-test-joxuquq0miqipox0fitiwey0", Local "Local file system at /tmp/rclone918532241", Modify Window "1ms" 2020/08/16 05:05:02 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/08/16 05:05:02 DEBUG : empty space: Unchanged skipping 2020/08/16 05:05:02 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for checks to finish 2020/08/16 05:05:02 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for transfers to finish 2020/08/16 05:05:08 DEBUG : potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2020/08/16 05:05:08 INFO : potato2: Copied (new) 2020/08/16 05:05:08 DEBUG : Waiting for deletions to finish 2020/08/16 05:05:14 INFO : potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (29.56s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:176: Remote "B2 bucket rclone-test-joxuquq0miqipox0fitiwey0", Local "Local file system at /tmp/rclone918532241", Modify Window "1ms" 2020/08/16 05:05:27 DEBUG : d: Making directory 2020/08/16 05:05:27 DEBUG : d/e: Making directory fstest.go:247: Filtering empty directory "d" fstest.go:247: Filtering empty directory "d/e" 2020/08/16 05:05:28 DEBUG : c/non empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/08/16 05:05:28 DEBUG : c/non empty space: Unchanged skipping 2020/08/16 05:05:29 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for checks to finish 2020/08/16 05:05:29 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for transfers to finish 2020/08/16 05:05:34 DEBUG : a/potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2020/08/16 05:05:34 INFO : a/potato2: Copied (new) 2020/08/16 05:05:34 DEBUG : Waiting for deletions to finish 2020/08/16 05:05:36 INFO : b/potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (24.86s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:176: Remote "B2 bucket rclone-test-joxuquq0miqipox0fitiwey0", Local "Local file system at /tmp/rclone918532241", Modify Window "1ms" 2020/08/16 05:05:51 DEBUG : d: Making directory fstest.go:247: Filtering empty directory "d" 2020/08/16 05:05:52 DEBUG : c/non empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/08/16 05:05:52 DEBUG : c/non empty space: Unchanged skipping 2020/08/16 05:05:52 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for checks to finish 2020/08/16 05:05:52 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for transfers to finish 2020/08/16 05:05:58 DEBUG : a/potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2020/08/16 05:05:58 INFO : a/potato2: Copied (new) 2020/08/16 05:05:58 ERROR : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: not deleting files as there were IO errors 2020/08/16 05:05:58 ERROR : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: not deleting directories as there were IO errors fstest.go:247: Filtering empty directory "d" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (20.46s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:176: Remote "B2 bucket rclone-test-joxuquq0miqipox0fitiwey0", Local "Local file system at /tmp/rclone918532241", Modify Window "1ms" 2020/08/16 05:06:15 DEBUG : Waiting for deletions to finish 2020/08/16 05:06:15 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for checks to finish 2020/08/16 05:06:15 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/08/16 05:06:15 DEBUG : empty space: Unchanged skipping 2020/08/16 05:06:15 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for transfers to finish 2020/08/16 05:06:16 INFO : potato: Deleted 2020/08/16 05:06:22 DEBUG : potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2020/08/16 05:06:22 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteDuring (25.25s) === RUN TestSyncDeleteBefore run.go:176: Remote "B2 bucket rclone-test-joxuquq0miqipox0fitiwey0", Local "Local file system at /tmp/rclone918532241", Modify Window "1ms" 2020/08/16 05:06:48 DEBUG : Waiting for deletions to finish 2020/08/16 05:06:49 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for checks to finish 2020/08/16 05:06:49 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for transfers to finish 2020/08/16 05:06:49 INFO : potato: Deleted 2020/08/16 05:06:49 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for checks to finish 2020/08/16 05:06:49 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/08/16 05:06:49 DEBUG : empty space: Unchanged skipping 2020/08/16 05:06:49 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for transfers to finish 2020/08/16 05:06:54 DEBUG : potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2020/08/16 05:06:54 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteBefore (40.20s) === RUN TestCopyDeleteBefore run.go:176: Remote "B2 bucket rclone-test-joxuquq0miqipox0fitiwey0", Local "Local file system at /tmp/rclone918532241", Modify Window "1ms" 2020/08/16 05:07:15 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for checks to finish 2020/08/16 05:07:15 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for transfers to finish 2020/08/16 05:07:18 DEBUG : potato2: SHA-1 = 810274f0df19dab60a894a562b7a8ff04c072623 OK 2020/08/16 05:07:18 INFO : potato2: Copied (new) --- PASS: TestCopyDeleteBefore (11.35s) === RUN TestSyncWithExclude run.go:176: Remote "B2 bucket rclone-test-joxuquq0miqipox0fitiwey0", Local "Local file system at /tmp/rclone918532241", Modify Window "1ms" 2020/08/16 05:07:32 DEBUG : potato2: Excluded 2020/08/16 05:07:32 DEBUG : enormous: Excluded 2020/08/16 05:07:32 DEBUG : potato2: Excluded 2020/08/16 05:07:32 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/08/16 05:07:32 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for checks to finish 2020/08/16 05:07:32 DEBUG : empty space: Unchanged skipping 2020/08/16 05:07:32 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for transfers to finish 2020/08/16 05:07:32 DEBUG : Waiting for deletions to finish 2020/08/16 05:07:32 INFO : There was nothing to transfer 2020/08/16 05:07:32 DEBUG : potato2: Excluded 2020/08/16 05:07:32 DEBUG : enormous: Excluded 2020/08/16 05:07:32 DEBUG : potato2: Excluded 2020/08/16 05:07:32 DEBUG : Local file system at /tmp/rclone918532241: Waiting for checks to finish 2020/08/16 05:07:32 DEBUG : empty space: Size and modification time the same (differ by 456.789µs, within tolerance 1ms) 2020/08/16 05:07:32 DEBUG : empty space: Unchanged skipping 2020/08/16 05:07:32 DEBUG : Local file system at /tmp/rclone918532241: Waiting for transfers to finish 2020/08/16 05:07:32 DEBUG : Waiting for deletions to finish 2020/08/16 05:07:32 INFO : There was nothing to transfer --- PASS: TestSyncWithExclude (15.32s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:176: Remote "B2 bucket rclone-test-joxuquq0miqipox0fitiwey0", Local "Local file system at /tmp/rclone918532241", Modify Window "1ms" 2020/08/16 05:07:49 DEBUG : potato2: Excluded 2020/08/16 05:07:49 DEBUG : enormous: Excluded 2020/08/16 05:07:50 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for checks to finish 2020/08/16 05:07:50 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/08/16 05:07:50 DEBUG : empty space: Unchanged skipping 2020/08/16 05:07:50 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for transfers to finish 2020/08/16 05:07:50 DEBUG : Waiting for deletions to finish 2020/08/16 05:07:52 INFO : enormous: Deleted 2020/08/16 05:07:55 INFO : potato2: Deleted 2020/08/16 05:07:55 INFO : There was nothing to transfer 2020/08/16 05:07:55 DEBUG : Local file system at /tmp/rclone918532241: Waiting for checks to finish 2020/08/16 05:07:55 DEBUG : empty space: Size and modification time the same (differ by 456.789µs, within tolerance 1ms) 2020/08/16 05:07:55 DEBUG : empty space: Unchanged skipping 2020/08/16 05:07:55 DEBUG : Local file system at /tmp/rclone918532241: Waiting for transfers to finish 2020/08/16 05:07:55 DEBUG : Waiting for deletions to finish 2020/08/16 05:07:55 INFO : potato2: Deleted 2020/08/16 05:07:55 INFO : enormous: Deleted 2020/08/16 05:07:55 INFO : There was nothing to transfer --- PASS: TestSyncWithExcludeAndDeleteExcluded (19.68s) === RUN TestSyncWithUpdateOlder run.go:176: Remote "B2 bucket rclone-test-joxuquq0miqipox0fitiwey0", Local "Local file system at /tmp/rclone918532241", Modify Window "1ms" 2020/08/16 05:08:19 DEBUG : four: Sizes differ (src 4 vs dst 8) 2020/08/16 05:08:19 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2020/08/16 05:08:19 DEBUG : one: Destination is newer than source, skipping 2020/08/16 05:08:19 DEBUG : three: Sizes identical 2020/08/16 05:08:19 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2020/08/16 05:08:19 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for checks to finish 2020/08/16 05:08:19 DEBUG : two: SHA-1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b (Local file system at /tmp/rclone918532241) 2020/08/16 05:08:19 DEBUG : two: SHA-1 = b10e0da85e90399abfec36c7376b20aa4596461b (B2 bucket rclone-test-joxuquq0miqipox0fitiwey0) 2020/08/16 05:08:19 DEBUG : two: SHA-1 differ 2020/08/16 05:08:19 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for transfers to finish 2020/08/16 05:08:20 DEBUG : two: SHA-1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2020/08/16 05:08:20 INFO : two: Copied (replaced existing) 2020/08/16 05:08:21 DEBUG : four: SHA-1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2020/08/16 05:08:21 INFO : four: Copied (replaced existing) 2020/08/16 05:08:23 DEBUG : five: SHA-1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2020/08/16 05:08:23 INFO : five: Copied (new) 2020/08/16 05:08:23 DEBUG : Waiting for deletions to finish 2020/08/16 05:08:23 DEBUG : one: Destination is newer than source, skipping 2020/08/16 05:08:23 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for checks to finish 2020/08/16 05:08:23 DEBUG : five: SHA-1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2020/08/16 05:08:23 DEBUG : three: SHA-1 = b802f384302cb24fbab0a44997e820bf2e8507bb (Local file system at /tmp/rclone918532241) 2020/08/16 05:08:23 DEBUG : two: SHA-1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2020/08/16 05:08:23 DEBUG : three: SHA-1 = 03731cad13f4cf7b59703c77529381cdec1632e5 (B2 bucket rclone-test-joxuquq0miqipox0fitiwey0) 2020/08/16 05:08:23 DEBUG : two: Size and SHA-1 of src and dst objects identical 2020/08/16 05:08:23 DEBUG : three: SHA-1 differ 2020/08/16 05:08:23 DEBUG : two: Destination mod time is within 1s of source and files identical, skipping 2020/08/16 05:08:23 DEBUG : four: SHA-1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2020/08/16 05:08:23 DEBUG : three: Destination mod time is within 1s of source but files differ, transferring 2020/08/16 05:08:23 DEBUG : four: Size and SHA-1 of src and dst objects identical 2020/08/16 05:08:23 DEBUG : four: Destination mod time is within 1s of source and files identical, skipping 2020/08/16 05:08:23 DEBUG : five: Size and SHA-1 of src and dst objects identical 2020/08/16 05:08:23 DEBUG : five: Destination mod time is within 1s of source and files identical, skipping 2020/08/16 05:08:23 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for transfers to finish 2020/08/16 05:08:23 DEBUG : three: SHA-1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2020/08/16 05:08:23 INFO : three: Copied (replaced existing) 2020/08/16 05:08:23 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithUpdateOlder (38.78s) === RUN TestSyncWithMaxDuration sync_test.go:1014: Skipping test on non local remote --- SKIP: TestSyncWithMaxDuration (0.00s) === RUN TestSyncWithTrackRenames run.go:176: Remote "B2 bucket rclone-test-joxuquq0miqipox0fitiwey0", Local "Local file system at /tmp/rclone918532241", Modify Window "1ms" sync_test.go:1066: Can track renames: true 2020/08/16 05:08:34 INFO : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Making map for --track-renames 2020/08/16 05:08:34 INFO : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Finished making map for --track-renames 2020/08/16 05:08:34 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for checks to finish 2020/08/16 05:08:34 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for renames to finish 2020/08/16 05:08:34 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for transfers to finish 2020/08/16 05:08:38 DEBUG : potato: SHA-1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2020/08/16 05:08:38 INFO : potato: Copied (new) 2020/08/16 05:08:38 DEBUG : yam: SHA-1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2020/08/16 05:08:38 INFO : yam: Copied (new) 2020/08/16 05:08:38 DEBUG : Waiting for deletions to finish 2020/08/16 05:08:39 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2020/08/16 05:08:39 DEBUG : potato: Unchanged skipping 2020/08/16 05:08:39 INFO : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Making map for --track-renames 2020/08/16 05:08:39 INFO : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Finished making map for --track-renames 2020/08/16 05:08:39 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for checks to finish 2020/08/16 05:08:39 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for renames to finish 2020/08/16 05:08:39 DEBUG : yam: SHA-1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2020/08/16 05:08:39 INFO : yam: Copied (server side copy) 2020/08/16 05:08:44 INFO : yam: Deleted 2020/08/16 05:08:44 INFO : yaml: Renamed from "yam" 2020/08/16 05:08:44 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for transfers to finish 2020/08/16 05:08:44 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenames (18.03s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:176: Remote "B2 bucket rclone-test-joxuquq0miqipox0fitiwey0", Local "Local file system at /tmp/rclone918532241", Modify Window "1ms" sync_test.go:1137: Can track renames: true 2020/08/16 05:08:52 INFO : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Making map for --track-renames 2020/08/16 05:08:52 INFO : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Finished making map for --track-renames 2020/08/16 05:08:52 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for checks to finish 2020/08/16 05:08:52 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for renames to finish 2020/08/16 05:08:52 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for transfers to finish 2020/08/16 05:08:53 DEBUG : potato: SHA-1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2020/08/16 05:08:53 INFO : potato: Copied (new) 2020/08/16 05:08:57 DEBUG : yam: SHA-1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2020/08/16 05:08:57 INFO : yam: Copied (new) 2020/08/16 05:08:57 DEBUG : Waiting for deletions to finish 2020/08/16 05:08:57 INFO : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Making map for --track-renames 2020/08/16 05:08:57 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2020/08/16 05:08:57 DEBUG : potato: Unchanged skipping 2020/08/16 05:08:57 INFO : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Finished making map for --track-renames 2020/08/16 05:08:57 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for checks to finish 2020/08/16 05:08:57 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for renames to finish 2020/08/16 05:08:58 DEBUG : yam: SHA-1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2020/08/16 05:08:58 INFO : yam: Copied (server side copy) 2020/08/16 05:08:59 INFO : yam: Deleted 2020/08/16 05:08:59 INFO : yaml: Renamed from "yam" 2020/08/16 05:08:59 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for transfers to finish 2020/08/16 05:08:59 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenamesStrategyModtime (8.03s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:176: Remote "B2 bucket rclone-test-joxuquq0miqipox0fitiwey0", Local "Local file system at /tmp/rclone918532241", Modify Window "1ms" sync_test.go:1175: Can track renames: true 2020/08/16 05:09:00 INFO : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Making map for --track-renames 2020/08/16 05:09:00 INFO : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Finished making map for --track-renames 2020/08/16 05:09:00 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for checks to finish 2020/08/16 05:09:00 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for renames to finish 2020/08/16 05:09:00 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for transfers to finish 2020/08/16 05:09:02 DEBUG : sub/yam: SHA-1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2020/08/16 05:09:02 INFO : sub/yam: Copied (new) 2020/08/16 05:09:07 DEBUG : potato: SHA-1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2020/08/16 05:09:07 INFO : potato: Copied (new) 2020/08/16 05:09:07 DEBUG : Waiting for deletions to finish 2020/08/16 05:09:08 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2020/08/16 05:09:08 DEBUG : potato: Unchanged skipping 2020/08/16 05:09:08 INFO : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Making map for --track-renames 2020/08/16 05:09:08 INFO : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Finished making map for --track-renames 2020/08/16 05:09:08 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for checks to finish 2020/08/16 05:09:08 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for renames to finish 2020/08/16 05:09:09 DEBUG : sub/yam: SHA-1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2020/08/16 05:09:09 INFO : sub/yam: Copied (server side copy) 2020/08/16 05:09:09 INFO : sub/yam: Deleted 2020/08/16 05:09:09 INFO : yam: Renamed from "sub/yam" 2020/08/16 05:09:09 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for transfers to finish 2020/08/16 05:09:09 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenamesStrategyLeaf (16.96s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:176: Remote "B2 bucket rclone-test-joxuquq0miqipox0fitiwey0", Local "Local file system at /tmp/rclone918532241", Modify Window "1ms" 2020/08/16 05:09:17 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for checks to finish 2020/08/16 05:09:17 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for transfers to finish 2020/08/16 05:09:18 DEBUG : nested/sub dir/file: SHA-1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK 2020/08/16 05:09:18 INFO : nested/sub dir/file: Copied (new) 2020/08/16 05:09:18 INFO : nested/sub dir/file: Deleted 2020/08/16 05:09:22 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2020/08/16 05:09:22 INFO : sub dir/hello world: Copied (new) 2020/08/16 05:09:22 INFO : sub dir/hello world: Deleted 2020/08/16 05:09:22 DEBUG : sub dir: Removing directory 2020/08/16 05:09:22 DEBUG : nested/sub dir: Removing directory 2020/08/16 05:09:22 DEBUG : nested: Removing directory 2020/08/16 05:09:22 DEBUG : Local file system at /tmp/rclone918532241: deleted 3 directories --- PASS: TestMoveWithDeleteEmptySrcDirs (10.22s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:176: Remote "B2 bucket rclone-test-joxuquq0miqipox0fitiwey0", Local "Local file system at /tmp/rclone918532241", Modify Window "1ms" 2020/08/16 05:09:28 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for checks to finish 2020/08/16 05:09:28 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for transfers to finish 2020/08/16 05:09:28 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2020/08/16 05:09:28 INFO : sub dir/hello world: Copied (new) 2020/08/16 05:09:28 INFO : sub dir/hello world: Deleted 2020/08/16 05:09:33 DEBUG : nested/sub dir/file: SHA-1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK 2020/08/16 05:09:33 INFO : nested/sub dir/file: Copied (new) 2020/08/16 05:09:33 INFO : nested/sub dir/file: Deleted --- PASS: TestMoveWithoutDeleteEmptySrcDirs (8.94s) === RUN TestServerSideMove run.go:176: Remote "B2 bucket rclone-test-joxuquq0miqipox0fitiwey0", Local "Local file system at /tmp/rclone918532241", Modify Window "1ms" sync_test.go:1227: Server side move (if possible) B2 bucket rclone-test-joxuquq0miqipox0fitiwey0 -> B2 bucket rclone-test-xotiyuw1fexukes6quyonec6 2020/08/16 05:09:52 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms) 2020/08/16 05:09:52 DEBUG : empty space: Unchanged skipping 2020/08/16 05:09:52 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2020/08/16 05:09:52 DEBUG : B2 bucket rclone-test-xotiyuw1fexukes6quyonec6: Waiting for checks to finish 2020/08/16 05:09:52 INFO : empty space: Deleted 2020/08/16 05:09:52 DEBUG : B2 bucket rclone-test-xotiyuw1fexukes6quyonec6: Waiting for transfers to finish 2020/08/16 05:09:53 DEBUG : potato3: SHA-1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2020/08/16 05:09:53 INFO : potato3: Copied (server side copy) 2020/08/16 05:09:53 DEBUG : potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2020/08/16 05:09:53 INFO : potato2: Copied (server side copy) 2020/08/16 05:09:53 INFO : potato2: Deleted 2020/08/16 05:09:54 INFO : potato3: Deleted 2020/08/16 05:09:55 DEBUG : B2 bucket rclone-test-woviloc8dexofew3gesoher4: Waiting for checks to finish 2020/08/16 05:09:55 DEBUG : B2 bucket rclone-test-woviloc8dexofew3gesoher4: Waiting for transfers to finish 2020/08/16 05:09:55 DEBUG : potato3: SHA-1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2020/08/16 05:09:55 INFO : potato3: Copied (server side copy) 2020/08/16 05:09:55 DEBUG : potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2020/08/16 05:09:55 INFO : potato2: Copied (server side copy) 2020/08/16 05:09:59 DEBUG : empty space: SHA-1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2020/08/16 05:09:59 INFO : empty space: Copied (server side copy) 2020/08/16 05:10:01 INFO : potato2: Deleted 2020/08/16 05:10:04 INFO : empty space: Deleted 2020/08/16 05:10:06 INFO : potato3: Deleted 2020/08/16 05:10:06 DEBUG : B2 bucket rclone-test-woviloc8dexofew3gesoher4: Purge remote 2020/08/16 05:10:06 DEBUG : empty space: Deleting (id "4_z9fa29d53a0cdfb5b7f380e1b_f102c79dc22ba38f9_d20200816_m050955_c001_v0001144_t0059") 2020/08/16 05:10:06 DEBUG : potato2: Deleting (id "4_z9fa29d53a0cdfb5b7f380e1b_f1028619236b4d7d1_d20200816_m050955_c001_v0001037_t0023") 2020/08/16 05:10:06 DEBUG : potato3: Deleting (id "4_z9fa29d53a0cdfb5b7f380e1b_f11804e077b29956f_d20200816_m050955_c001_v0001137_t0041") 2020/08/16 05:10:07 DEBUG : B2 bucket rclone-test-xotiyuw1fexukes6quyonec6: Purge remote 2020/08/16 05:10:07 DEBUG : empty space: Deleting (id "4_zafc27d73a0cdfb5b7f380e1b_f104afa2fed8e6ee8_d20200816_m050959_c001_v0001144_t0051") 2020/08/16 05:10:07 DEBUG : empty space: Deleting (id "4_zafc27d73a0cdfb5b7f380e1b_f11668dea73feefdf_d20200816_m050948_c001_v0001144_t0008") 2020/08/16 05:10:07 DEBUG : potato2: Deleting (id "4_zafc27d73a0cdfb5b7f380e1b_f100d6e616225dc3a_d20200816_m050956_c001_v0001144_t0032") 2020/08/16 05:10:07 DEBUG : potato2: Deleting (id "4_zafc27d73a0cdfb5b7f380e1b_f104be74e050f6944_d20200816_m050953_c001_v0001044_t0004") 2020/08/16 05:10:07 DEBUG : potato3: Deleting (id "4_zafc27d73a0cdfb5b7f380e1b_f10763f0e7340b077_d20200816_m050955_c001_v0001144_t0019") 2020/08/16 05:10:07 DEBUG : potato3: Deleting (id "4_zafc27d73a0cdfb5b7f380e1b_f1070e34c5d3234fe_d20200816_m050952_c001_v0001120_t0033") 2020/08/16 05:10:07 DEBUG : potato3: Deleting (id "4_zafc27d73a0cdfb5b7f380e1b_f11668dea73fef00f_d20200816_m050951_c001_v0001144_t0008") --- PASS: TestServerSideMove (31.96s) === RUN TestServerSideMoveWithFilter run.go:176: Remote "B2 bucket rclone-test-joxuquq0miqipox0fitiwey0", Local "Local file system at /tmp/rclone918532241", Modify Window "1ms" sync_test.go:1227: Server side move (if possible) B2 bucket rclone-test-joxuquq0miqipox0fitiwey0 -> B2 bucket rclone-test-zapegob6gefucul5cifudob6 2020/08/16 05:10:20 DEBUG : empty space: Excluded 2020/08/16 05:10:20 DEBUG : empty space: Excluded 2020/08/16 05:10:20 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2020/08/16 05:10:20 DEBUG : B2 bucket rclone-test-zapegob6gefucul5cifudob6: Waiting for checks to finish 2020/08/16 05:10:20 DEBUG : B2 bucket rclone-test-zapegob6gefucul5cifudob6: Waiting for transfers to finish 2020/08/16 05:10:20 DEBUG : potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2020/08/16 05:10:20 INFO : potato2: Copied (server side copy) 2020/08/16 05:10:21 INFO : potato2: Deleted 2020/08/16 05:10:23 DEBUG : potato3: SHA-1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2020/08/16 05:10:23 INFO : potato3: Copied (server side copy) 2020/08/16 05:10:23 INFO : potato3: Deleted 2020/08/16 05:10:24 DEBUG : empty space: Excluded 2020/08/16 05:10:24 DEBUG : B2 bucket rclone-test-najihuf7fedoyiz8yutiset9: Waiting for checks to finish 2020/08/16 05:10:24 DEBUG : B2 bucket rclone-test-najihuf7fedoyiz8yutiset9: Waiting for transfers to finish 2020/08/16 05:10:25 DEBUG : potato3: SHA-1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2020/08/16 05:10:25 INFO : potato3: Copied (server side copy) 2020/08/16 05:10:27 DEBUG : potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2020/08/16 05:10:27 INFO : potato2: Copied (server side copy) 2020/08/16 05:10:28 INFO : potato2: Deleted 2020/08/16 05:10:56 INFO : potato3: Deleted 2020/08/16 05:10:56 DEBUG : B2 bucket rclone-test-najihuf7fedoyiz8yutiset9: Purge remote 2020/08/16 05:10:56 DEBUG : potato2: Deleting (id "4_z5f120d93a0ddfb5b7f380e1b_f116e916b3ff29ac0_d20200816_m051025_c001_v0001029_t0009") 2020/08/16 05:10:56 DEBUG : potato3: Deleting (id "4_z5f120d93a0ddfb5b7f380e1b_f1002699dddcda2c2_d20200816_m051025_c001_v0001134_t0024") 2020/08/16 05:10:57 DEBUG : B2 bucket rclone-test-zapegob6gefucul5cifudob6: Purge remote 2020/08/16 05:10:57 DEBUG : empty space: Deleting (id "4_z8f72edf3a0cdfb5b7f380e1b_f102a9a66e9d5eb48_d20200816_m051019_c001_v0001093_t0004") 2020/08/16 05:10:57 DEBUG : potato2: Deleting (id "4_z8f72edf3a0cdfb5b7f380e1b_f1137ab1eb9155cc4_d20200816_m051027_c001_v0001120_t0010") 2020/08/16 05:10:57 DEBUG : potato2: Deleting (id "4_z8f72edf3a0cdfb5b7f380e1b_f10871c563d36dd6e_d20200816_m051020_c001_v0001131_t0012") 2020/08/16 05:10:57 DEBUG : potato3: Deleting (id "4_z8f72edf3a0cdfb5b7f380e1b_f105affbf397f1363_d20200816_m051055_c001_v0001144_t0027") 2020/08/16 05:10:57 DEBUG : potato3: Deleting (id "4_z8f72edf3a0cdfb5b7f380e1b_f119965017182e1ce_d20200816_m051020_c001_v0001039_t0011") 2020/08/16 05:10:57 DEBUG : potato3: Deleting (id "4_z8f72edf3a0cdfb5b7f380e1b_f102a9a66e9d5eb4c_d20200816_m051019_c001_v0001093_t0004") --- PASS: TestServerSideMoveWithFilter (58.17s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:176: Remote "B2 bucket rclone-test-joxuquq0miqipox0fitiwey0", Local "Local file system at /tmp/rclone918532241", Modify Window "1ms" 2020/08/16 05:11:18 DEBUG : tomatoDir: Making directory sync_test.go:1227: Server side move (if possible) B2 bucket rclone-test-joxuquq0miqipox0fitiwey0 -> B2 bucket rclone-test-xoxonec8zumonak2seneced1 2020/08/16 05:11:22 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms) 2020/08/16 05:11:22 DEBUG : empty space: Unchanged skipping 2020/08/16 05:11:22 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2020/08/16 05:11:22 DEBUG : B2 bucket rclone-test-xoxonec8zumonak2seneced1: Waiting for checks to finish 2020/08/16 05:11:23 DEBUG : potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2020/08/16 05:11:23 INFO : potato2: Copied (server side copy) 2020/08/16 05:11:24 INFO : potato2: Deleted 2020/08/16 05:11:25 INFO : empty space: Deleted 2020/08/16 05:11:25 DEBUG : B2 bucket rclone-test-xoxonec8zumonak2seneced1: Waiting for transfers to finish 2020/08/16 05:11:27 DEBUG : potato3: SHA-1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2020/08/16 05:11:27 INFO : potato3: Copied (server side copy) 2020/08/16 05:11:28 INFO : potato3: Deleted 2020/08/16 05:11:29 DEBUG : tomatoDir: Making directory 2020/08/16 05:11:29 DEBUG : B2 bucket rclone-test-lirajed1qifezuk4qiqiyiq6: Waiting for checks to finish 2020/08/16 05:11:29 DEBUG : B2 bucket rclone-test-lirajed1qifezuk4qiqiyiq6: Waiting for transfers to finish 2020/08/16 05:11:30 DEBUG : empty space: SHA-1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2020/08/16 05:11:30 INFO : empty space: Copied (server side copy) 2020/08/16 05:11:30 INFO : empty space: Deleted 2020/08/16 05:11:31 DEBUG : potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2020/08/16 05:11:31 INFO : potato2: Copied (server side copy) 2020/08/16 05:11:31 INFO : potato2: Deleted 2020/08/16 05:11:32 DEBUG : potato3: SHA-1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2020/08/16 05:11:32 INFO : potato3: Copied (server side copy) 2020/08/16 05:11:37 INFO : potato3: Deleted 2020/08/16 05:11:38 DEBUG : B2 bucket rclone-test-lirajed1qifezuk4qiqiyiq6: Purge remote 2020/08/16 05:11:38 DEBUG : empty space: Deleting (id "4_z4fa20d63a0edfb5b7f380e1b_f11693902fef628cd_d20200816_m051129_c001_v0001134_t0016") 2020/08/16 05:11:38 DEBUG : potato2: Deleting (id "4_z4fa20d63a0edfb5b7f380e1b_f1012f14caf78dd08_d20200816_m051129_c001_v0001033_t0040") 2020/08/16 05:11:38 DEBUG : potato3: Deleting (id "4_z4fa20d63a0edfb5b7f380e1b_f113654f1b3c2dcb6_d20200816_m051129_c001_v0001144_t0006") 2020/08/16 05:11:39 DEBUG : B2 bucket rclone-test-xoxonec8zumonak2seneced1: Purge remote 2020/08/16 05:11:39 DEBUG : empty space: Deleting (id "4_z3fc2ddf3a0ddfb5b7f380e1b_f108a1d041c96d847_d20200816_m051130_c001_v0001134_t0004") 2020/08/16 05:11:39 DEBUG : empty space: Deleting (id "4_z3fc2ddf3a0ddfb5b7f380e1b_f101ffe6f153f9d5a_d20200816_m051120_c001_v0001113_t0009") 2020/08/16 05:11:39 DEBUG : potato2: Deleting (id "4_z3fc2ddf3a0ddfb5b7f380e1b_f104ec6aff8c494a8_d20200816_m051131_c001_v0001041_t0025") 2020/08/16 05:11:39 DEBUG : potato2: Deleting (id "4_z3fc2ddf3a0ddfb5b7f380e1b_f1014ab439fe47466_d20200816_m051123_c001_v0001137_t0052") 2020/08/16 05:11:39 DEBUG : potato3: Deleting (id "4_z3fc2ddf3a0ddfb5b7f380e1b_f100c5f899f5949e6_d20200816_m051132_c001_v0001029_t0042") 2020/08/16 05:11:39 DEBUG : potato3: Deleting (id "4_z3fc2ddf3a0ddfb5b7f380e1b_f1016765f5dab8892_d20200816_m051123_c001_v0001039_t0028") 2020/08/16 05:11:39 DEBUG : potato3: Deleting (id "4_z3fc2ddf3a0ddfb5b7f380e1b_f101ffe6f153f9d5d_d20200816_m051122_c001_v0001113_t0009") --- PASS: TestServerSideMoveDeleteEmptySourceDirs (33.31s) === RUN TestServerSideMoveOverlap run.go:176: Remote "B2 bucket rclone-test-joxuquq0miqipox0fitiwey0", Local "Local file system at /tmp/rclone918532241", Modify Window "1ms" --- PASS: TestServerSideMoveOverlap (10.02s) === RUN TestSyncOverlap run.go:176: Remote "B2 bucket rclone-test-joxuquq0miqipox0fitiwey0", Local "Local file system at /tmp/rclone918532241", Modify Window "1ms" --- PASS: TestSyncOverlap (1.26s) === RUN TestSyncCompareDest run.go:176: Remote "B2 bucket rclone-test-joxuquq0miqipox0fitiwey0", Local "Local file system at /tmp/rclone918532241", Modify Window "1ms" 2020/08/16 05:11:53 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0 path dst: Waiting for checks to finish 2020/08/16 05:11:53 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0 path dst: Waiting for transfers to finish 2020/08/16 05:11:57 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2020/08/16 05:11:57 INFO : one: Copied (new) 2020/08/16 05:11:57 DEBUG : Waiting for deletions to finish 2020/08/16 05:11:57 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0 path dst: Waiting for checks to finish 2020/08/16 05:11:58 DEBUG : one: Sizes differ (src 5 vs dst 3) 2020/08/16 05:11:58 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0 path dst: Waiting for transfers to finish 2020/08/16 05:11:58 DEBUG : one: SHA-1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2020/08/16 05:11:58 INFO : one: Copied (replaced existing) 2020/08/16 05:11:58 DEBUG : Waiting for deletions to finish 2020/08/16 05:12:05 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0 path dst: Waiting for checks to finish 2020/08/16 05:12:05 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/08/16 05:12:05 DEBUG : one: Destination found in --compare-dest, skipping 2020/08/16 05:12:05 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0 path dst: Waiting for transfers to finish 2020/08/16 05:12:05 DEBUG : Waiting for deletions to finish 2020/08/16 05:12:05 INFO : There was nothing to transfer 2020/08/16 05:12:11 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/08/16 05:12:11 DEBUG : two: Destination found in --compare-dest, skipping 2020/08/16 05:12:11 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0 path dst: Waiting for checks to finish 2020/08/16 05:12:11 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/08/16 05:12:11 DEBUG : one: Destination found in --compare-dest, skipping 2020/08/16 05:12:11 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0 path dst: Waiting for transfers to finish 2020/08/16 05:12:11 DEBUG : Waiting for deletions to finish 2020/08/16 05:12:11 INFO : There was nothing to transfer 2020/08/16 05:12:11 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/08/16 05:12:11 DEBUG : two: Destination found in --compare-dest, skipping 2020/08/16 05:12:11 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0 path dst: Waiting for checks to finish 2020/08/16 05:12:11 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/08/16 05:12:11 DEBUG : one: Destination found in --compare-dest, skipping 2020/08/16 05:12:11 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0 path dst: Waiting for transfers to finish 2020/08/16 05:12:11 DEBUG : Waiting for deletions to finish 2020/08/16 05:12:11 INFO : There was nothing to transfer 2020/08/16 05:12:12 DEBUG : two: Sizes differ (src 5 vs dst 3) 2020/08/16 05:12:12 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0 path dst: Waiting for checks to finish 2020/08/16 05:12:12 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/08/16 05:12:12 DEBUG : one: Destination found in --compare-dest, skipping 2020/08/16 05:12:12 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0 path dst: Waiting for transfers to finish 2020/08/16 05:12:13 DEBUG : two: SHA-1 = a4be8877ad88f3109a6411985a1f359e10e2d930 OK 2020/08/16 05:12:13 INFO : two: Copied (new) 2020/08/16 05:12:13 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCompareDest (27.66s) === RUN TestSyncCopyDest run.go:176: Remote "B2 bucket rclone-test-joxuquq0miqipox0fitiwey0", Local "Local file system at /tmp/rclone918532241", Modify Window "1ms" 2020/08/16 05:12:21 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0 path dst: Waiting for checks to finish 2020/08/16 05:12:21 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0 path dst: Waiting for transfers to finish 2020/08/16 05:12:25 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2020/08/16 05:12:25 INFO : one: Copied (new) 2020/08/16 05:12:25 DEBUG : Waiting for deletions to finish 2020/08/16 05:12:25 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0 path dst: Waiting for checks to finish 2020/08/16 05:12:25 DEBUG : one: Sizes differ (src 5 vs dst 3) 2020/08/16 05:12:25 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0 path dst: Waiting for transfers to finish 2020/08/16 05:12:29 DEBUG : one: SHA-1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2020/08/16 05:12:29 INFO : one: Copied (replaced existing) 2020/08/16 05:12:29 DEBUG : Waiting for deletions to finish 2020/08/16 05:12:41 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0 path dst: Waiting for checks to finish 2020/08/16 05:12:41 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/08/16 05:12:41 DEBUG : one: Sizes differ (src 5 vs dst 3) 2020/08/16 05:13:08 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2020/08/16 05:13:08 INFO : one: Copied (server side copy) 2020/08/16 05:13:13 INFO : one: Deleted 2020/08/16 05:13:14 DEBUG : one: SHA-1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2020/08/16 05:13:14 INFO : one: Copied (server side copy) 2020/08/16 05:13:14 DEBUG : one: Destination found in --copy-dest, using server side copy 2020/08/16 05:13:14 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0 path dst: Waiting for transfers to finish 2020/08/16 05:13:14 DEBUG : Waiting for deletions to finish 2020/08/16 05:13:17 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/08/16 05:13:19 DEBUG : two: SHA-1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2020/08/16 05:13:19 INFO : two: Copied (server side copy) 2020/08/16 05:13:19 DEBUG : two: Destination found in --copy-dest, using server side copy 2020/08/16 05:13:19 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0 path dst: Waiting for checks to finish 2020/08/16 05:13:19 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/08/16 05:13:19 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/08/16 05:13:19 DEBUG : one: Unchanged skipping 2020/08/16 05:13:19 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0 path dst: Waiting for transfers to finish 2020/08/16 05:13:19 DEBUG : Waiting for deletions to finish 2020/08/16 05:13:20 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0 path dst: Waiting for checks to finish 2020/08/16 05:13:20 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/08/16 05:13:20 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/08/16 05:13:20 DEBUG : one: Unchanged skipping 2020/08/16 05:13:20 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/08/16 05:13:20 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/08/16 05:13:20 DEBUG : two: Unchanged skipping 2020/08/16 05:13:20 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0 path dst: Waiting for transfers to finish 2020/08/16 05:13:20 DEBUG : Waiting for deletions to finish 2020/08/16 05:13:20 INFO : There was nothing to transfer 2020/08/16 05:13:21 DEBUG : three: Sizes differ (src 7 vs dst 5) 2020/08/16 05:13:21 DEBUG : three: Destination not found in --copy-dest 2020/08/16 05:13:21 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0 path dst: Waiting for checks to finish 2020/08/16 05:13:22 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/08/16 05:13:22 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/08/16 05:13:22 DEBUG : one: Unchanged skipping 2020/08/16 05:13:22 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/08/16 05:13:22 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/08/16 05:13:22 DEBUG : two: Unchanged skipping 2020/08/16 05:13:22 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0 path dst: Waiting for transfers to finish 2020/08/16 05:13:26 DEBUG : three: SHA-1 = 370e613335f3cf2f19b2a2d35a86402f55c659f8 OK 2020/08/16 05:13:26 INFO : three: Copied (new) 2020/08/16 05:13:26 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCopyDest (80.14s) === RUN TestSyncBackupDir run.go:176: Remote "B2 bucket rclone-test-joxuquq0miqipox0fitiwey0", Local "Local file system at /tmp/rclone918532241", Modify Window "1ms" 2020/08/16 05:13:49 DEBUG : one: Sizes differ (src 4 vs dst 3) 2020/08/16 05:13:49 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0 path dst: Waiting for checks to finish 2020/08/16 05:13:49 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2020/08/16 05:13:49 DEBUG : two: Unchanged skipping 2020/08/16 05:13:50 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2020/08/16 05:13:50 INFO : one: Copied (server side copy) 2020/08/16 05:13:51 INFO : one: Deleted 2020/08/16 05:13:51 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0 path dst: Waiting for transfers to finish 2020/08/16 05:14:01 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2020/08/16 05:14:01 INFO : one: Copied (new) 2020/08/16 05:14:01 DEBUG : Waiting for deletions to finish 2020/08/16 05:14:02 DEBUG : three.txt: SHA-1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2020/08/16 05:14:02 INFO : three.txt: Copied (server side copy) 2020/08/16 05:14:07 INFO : three.txt: Deleted 2020/08/16 05:14:07 INFO : three.txt: Moved into backup dir 2020/08/16 05:14:12 DEBUG : pacer: low level retry 1/10 (error Post "https://api001.backblazeb2.com/b2api/v1/b2_list_file_names": EOF) 2020/08/16 05:14:12 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2020/08/16 05:14:12 DEBUG : pacer: Reducing sleep to 10ms 2020/08/16 05:14:13 DEBUG : one: Sizes differ (src 5 vs dst 4) 2020/08/16 05:14:13 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0 path dst: Waiting for checks to finish 2020/08/16 05:14:13 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2020/08/16 05:14:13 DEBUG : two: Unchanged skipping 2020/08/16 05:14:13 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2020/08/16 05:14:13 INFO : one: Copied (server side copy) 2020/08/16 05:14:14 INFO : one: Deleted 2020/08/16 05:14:14 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0 path dst: Waiting for transfers to finish 2020/08/16 05:14:44 DEBUG : one: SHA-1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2020/08/16 05:14:44 INFO : one: Copied (new) 2020/08/16 05:14:44 DEBUG : Waiting for deletions to finish 2020/08/16 05:14:45 DEBUG : three.txt: SHA-1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2020/08/16 05:14:45 INFO : three.txt: Copied (server side copy) 2020/08/16 05:14:45 INFO : three.txt: Deleted 2020/08/16 05:14:45 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (71.37s) === RUN TestSyncBackupDirWithSuffix run.go:176: Remote "B2 bucket rclone-test-joxuquq0miqipox0fitiwey0", Local "Local file system at /tmp/rclone918532241", Modify Window "1ms" 2020/08/16 05:15:02 DEBUG : one: Sizes differ (src 4 vs dst 3) 2020/08/16 05:15:02 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2020/08/16 05:15:02 DEBUG : two: Unchanged skipping 2020/08/16 05:15:02 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0 path dst: Waiting for checks to finish 2020/08/16 05:15:03 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2020/08/16 05:15:03 INFO : one: Copied (server side copy) 2020/08/16 05:15:03 INFO : one: Deleted 2020/08/16 05:15:03 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0 path dst: Waiting for transfers to finish 2020/08/16 05:15:07 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2020/08/16 05:15:07 INFO : one: Copied (new) 2020/08/16 05:15:07 DEBUG : Waiting for deletions to finish 2020/08/16 05:15:09 DEBUG : three.txt: SHA-1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2020/08/16 05:15:09 INFO : three.txt: Copied (server side copy) 2020/08/16 05:15:09 INFO : three.txt: Deleted 2020/08/16 05:15:09 INFO : three.txt: Moved into backup dir 2020/08/16 05:15:13 DEBUG : one: Sizes differ (src 5 vs dst 4) 2020/08/16 05:15:13 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2020/08/16 05:15:13 DEBUG : two: Unchanged skipping 2020/08/16 05:15:13 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0 path dst: Waiting for checks to finish 2020/08/16 05:15:17 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2020/08/16 05:15:17 INFO : one: Copied (server side copy) 2020/08/16 05:15:18 INFO : one: Deleted 2020/08/16 05:15:18 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0 path dst: Waiting for transfers to finish 2020/08/16 05:15:24 DEBUG : one: SHA-1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2020/08/16 05:15:24 INFO : one: Copied (new) 2020/08/16 05:15:24 DEBUG : Waiting for deletions to finish 2020/08/16 05:15:25 DEBUG : three.txt: SHA-1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2020/08/16 05:15:25 INFO : three.txt: Copied (server side copy) 2020/08/16 05:15:26 INFO : three.txt: Deleted 2020/08/16 05:15:26 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (37.62s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:176: Remote "B2 bucket rclone-test-joxuquq0miqipox0fitiwey0", Local "Local file system at /tmp/rclone918532241", Modify Window "1ms" 2020/08/16 05:15:40 DEBUG : one: Sizes differ (src 4 vs dst 3) 2020/08/16 05:15:40 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0 path dst: Waiting for checks to finish 2020/08/16 05:15:40 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2020/08/16 05:15:40 DEBUG : two: Unchanged skipping 2020/08/16 05:15:41 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2020/08/16 05:15:41 INFO : one: Copied (server side copy) 2020/08/16 05:15:43 INFO : one: Deleted 2020/08/16 05:15:43 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0 path dst: Waiting for transfers to finish 2020/08/16 05:15:47 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2020/08/16 05:15:47 INFO : one: Copied (new) 2020/08/16 05:15:47 DEBUG : Waiting for deletions to finish 2020/08/16 05:15:48 DEBUG : three.txt: SHA-1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2020/08/16 05:15:48 INFO : three.txt: Copied (server side copy) 2020/08/16 05:15:49 INFO : three.txt: Deleted 2020/08/16 05:15:49 INFO : three.txt: Moved into backup dir 2020/08/16 05:15:50 DEBUG : one: Sizes differ (src 5 vs dst 4) 2020/08/16 05:15:50 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2020/08/16 05:15:50 DEBUG : two: Unchanged skipping 2020/08/16 05:15:50 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0 path dst: Waiting for checks to finish 2020/08/16 05:15:51 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2020/08/16 05:15:51 INFO : one: Copied (server side copy) 2020/08/16 05:15:56 INFO : one: Deleted 2020/08/16 05:15:56 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0 path dst: Waiting for transfers to finish 2020/08/16 05:15:58 DEBUG : one: SHA-1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2020/08/16 05:15:58 INFO : one: Copied (new) 2020/08/16 05:15:58 DEBUG : Waiting for deletions to finish 2020/08/16 05:15:59 DEBUG : three.txt: SHA-1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2020/08/16 05:15:59 INFO : three.txt: Copied (server side copy) 2020/08/16 05:15:59 INFO : three.txt: Deleted 2020/08/16 05:15:59 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (40.41s) === RUN TestSyncSuffix run.go:176: Remote "B2 bucket rclone-test-joxuquq0miqipox0fitiwey0", Local "Local file system at /tmp/rclone918532241", Modify Window "1ms" 2020/08/16 05:16:19 DEBUG : one: Sizes differ (src 4 vs dst 3) 2020/08/16 05:16:21 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2020/08/16 05:16:21 INFO : one: Copied (server side copy) 2020/08/16 05:16:22 INFO : one: Deleted 2020/08/16 05:16:23 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2020/08/16 05:16:23 INFO : one: Copied (new) 2020/08/16 05:16:23 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2020/08/16 05:16:23 DEBUG : two: Unchanged skipping 2020/08/16 05:16:23 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2020/08/16 05:16:24 DEBUG : three.txt: SHA-1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2020/08/16 05:16:24 INFO : three.txt: Copied (server side copy) 2020/08/16 05:16:27 INFO : three.txt: Deleted 2020/08/16 05:16:27 DEBUG : three.txt: SHA-1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2020/08/16 05:16:27 INFO : three.txt: Copied (new) 2020/08/16 05:16:28 DEBUG : one: Sizes differ (src 5 vs dst 4) 2020/08/16 05:16:31 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2020/08/16 05:16:31 INFO : one: Copied (server side copy) 2020/08/16 05:16:32 INFO : one: Deleted 2020/08/16 05:16:33 DEBUG : one: SHA-1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2020/08/16 05:16:33 INFO : one: Copied (new) 2020/08/16 05:16:33 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2020/08/16 05:16:33 DEBUG : two: Unchanged skipping 2020/08/16 05:16:33 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2020/08/16 05:16:33 DEBUG : three.txt: SHA-1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2020/08/16 05:16:33 INFO : three.txt: Copied (server side copy) 2020/08/16 05:16:34 INFO : three.txt: Deleted 2020/08/16 05:16:34 DEBUG : three.txt: SHA-1 = 476e1707cbd7f0497a7dc4d44f0b07b8566ffc30 OK 2020/08/16 05:16:34 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (34.65s) === RUN TestSyncSuffixKeepExtension run.go:176: Remote "B2 bucket rclone-test-joxuquq0miqipox0fitiwey0", Local "Local file system at /tmp/rclone918532241", Modify Window "1ms" 2020/08/16 05:16:51 DEBUG : one: Sizes differ (src 4 vs dst 3) 2020/08/16 05:16:57 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2020/08/16 05:16:57 INFO : one: Copied (server side copy) 2020/08/16 05:17:07 INFO : one: Deleted 2020/08/16 05:17:11 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2020/08/16 05:17:11 INFO : one: Copied (new) 2020/08/16 05:17:11 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2020/08/16 05:17:11 DEBUG : two: Unchanged skipping 2020/08/16 05:17:11 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2020/08/16 05:17:12 DEBUG : three.txt: SHA-1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2020/08/16 05:17:12 INFO : three.txt: Copied (server side copy) 2020/08/16 05:17:15 INFO : three.txt: Deleted 2020/08/16 05:17:19 DEBUG : three.txt: SHA-1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2020/08/16 05:17:19 INFO : three.txt: Copied (new) 2020/08/16 05:17:19 DEBUG : one: Sizes differ (src 5 vs dst 4) 2020/08/16 05:17:25 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2020/08/16 05:17:25 INFO : one: Copied (server side copy) 2020/08/16 05:17:46 INFO : one: Deleted 2020/08/16 05:17:50 DEBUG : one: SHA-1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2020/08/16 05:17:50 INFO : one: Copied (new) 2020/08/16 05:17:50 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2020/08/16 05:17:50 DEBUG : two: Unchanged skipping 2020/08/16 05:17:51 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2020/08/16 05:17:51 DEBUG : three.txt: SHA-1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2020/08/16 05:17:51 INFO : three.txt: Copied (server side copy) 2020/08/16 05:17:52 INFO : three.txt: Deleted 2020/08/16 05:17:58 DEBUG : three.txt: SHA-1 = 476e1707cbd7f0497a7dc4d44f0b07b8566ffc30 OK 2020/08/16 05:17:58 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (78.11s) === RUN TestSyncUTFNorm run.go:176: Remote "B2 bucket rclone-test-joxuquq0miqipox0fitiwey0", Local "Local file system at /tmp/rclone918532241", Modify Window "1ms" 2020/08/16 05:18:05 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for checks to finish 2020/08/16 05:18:05 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2020/08/16 05:18:05 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for transfers to finish 2020/08/16 05:18:10 DEBUG : Testêé: SHA-1 = a54d88e06612d820bc3be72877c74f257b561b19 OK 2020/08/16 05:18:10 INFO : Testêé: Copied (replaced existing) 2020/08/16 05:18:10 DEBUG : Waiting for deletions to finish --- PASS: TestSyncUTFNorm (15.93s) === RUN TestSyncImmutable run.go:176: Remote "B2 bucket rclone-test-joxuquq0miqipox0fitiwey0", Local "Local file system at /tmp/rclone918532241", Modify Window "1ms" 2020/08/16 05:18:17 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for checks to finish 2020/08/16 05:18:17 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for transfers to finish 2020/08/16 05:18:18 DEBUG : existing: SHA-1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2020/08/16 05:18:18 INFO : existing: Copied (new) 2020/08/16 05:18:18 DEBUG : Waiting for deletions to finish 2020/08/16 05:18:19 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2020/08/16 05:18:19 ERROR : existing: Source and destination exist but do not match: immutable file modified 2020/08/16 05:18:19 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for checks to finish 2020/08/16 05:18:19 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for transfers to finish 2020/08/16 05:18:19 ERROR : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: not deleting files as there were IO errors 2020/08/16 05:18:19 ERROR : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: not deleting directories as there were IO errors 2020/08/16 05:18:19 INFO : There was nothing to transfer --- PASS: TestSyncImmutable (39.23s) === RUN TestSyncIgnoreCase run.go:176: Remote "B2 bucket rclone-test-joxuquq0miqipox0fitiwey0", Local "Local file system at /tmp/rclone918532241", Modify Window "1ms" 2020/08/16 05:19:01 DEBUG : existing: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2020/08/16 05:19:01 DEBUG : existing: Unchanged skipping 2020/08/16 05:19:01 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for checks to finish 2020/08/16 05:19:01 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Waiting for transfers to finish 2020/08/16 05:19:01 DEBUG : Waiting for deletions to finish 2020/08/16 05:19:01 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreCase (8.32s) === RUN TestAbort run.go:176: Remote "B2 bucket rclone-test-joxuquq0miqipox0fitiwey0", Local "Local file system at /tmp/rclone918532241", Modify Window "1ms" sync_test.go:1860: This test only runs on local --- SKIP: TestAbort (0.30s) FAIL 2020/08/16 05:19:05 DEBUG : B2 bucket rclone-test-joxuquq0miqipox0fitiwey0: Purge remote 2020/08/16 05:19:05 DEBUG : BackupDir/one: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1044c8b2a43409be_d20200816_m051327_c001_v0001137_t0040") 2020/08/16 05:19:05 DEBUG : BackupDir/one: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f101e7765e56b2657_d20200816_m051241_c001_v0001038_t0001") 2020/08/16 05:19:05 DEBUG : CompareDest/one: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1068bfdb4565b1e3_d20200816_m051214_c001_v0001144_t0044") 2020/08/16 05:19:05 DEBUG : CompareDest/one: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a80cdf_d20200816_m051200_c001_v0001144_t0031") 2020/08/16 05:19:05 DEBUG : CompareDest/two: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1161e7946a65f98a_d20200816_m051217_c001_v0001039_t0012") 2020/08/16 05:19:05 DEBUG : CompareDest/two: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f11668dea73fef9ba_d20200816_m051206_c001_v0001144_t0026") 2020/08/16 05:19:05 DEBUG : CopyDest/one: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f109b76d7b9ed7de1_d20200816_m051327_c001_v0001144_t0053") 2020/08/16 05:19:05 DEBUG : CopyDest/one: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a80ef7_d20200816_m051231_c001_v0001144_t0031") 2020/08/16 05:19:05 DEBUG : CopyDest/three: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f11668dea73feff4f_d20200816_m051330_c001_v0001144_t0010") 2020/08/16 05:19:05 DEBUG : CopyDest/three: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f117358a458155975_d20200816_m051321_c001_v0001137_t0006") 2020/08/16 05:19:06 DEBUG : CopyDest/two: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1029ffffee5bba7a_d20200816_m051332_c001_v0001038_t0044") 2020/08/16 05:19:06 DEBUG : CopyDest/two: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f11668dea73fefe4e_d20200816_m051314_c001_v0001144_t0026") 2020/08/16 05:19:06 DEBUG : EXISTING: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f112ab9d9eb472240_d20200816_m051901_c001_v0001041_t0015") 2020/08/16 05:19:06 DEBUG : EXISTING: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a8298d_d20200816_m051857_c001_v0001144_t0031") 2020/08/16 05:19:06 DEBUG : Testêé: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f106b77d73ee5ee5d_d20200816_m051811_c001_v0001009_t0033") 2020/08/16 05:19:06 DEBUG : Testêé: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f11668dea73ff1216_d20200816_m051806_c001_v0001144_t0026") 2020/08/16 05:19:06 DEBUG : Testêé: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a82646_d20200816_m051802_c001_v0001144_t0031") 2020/08/16 05:19:06 DEBUG : a/potato2: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f119bc32e9b2d890a_d20200816_m050558_c001_v0001041_t0039") 2020/08/16 05:19:06 DEBUG : a/potato2: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a7f24d_d20200816_m050552_c001_v0001144_t0031") 2020/08/16 05:19:06 DEBUG : a/potato2: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f10864437d8fcf377_d20200816_m050536_c001_v0001144_t0005") 2020/08/16 05:19:06 DEBUG : a/potato2: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a7f09a_d20200816_m050528_c001_v0001144_t0031") 2020/08/16 05:19:06 DEBUG : a/potato2: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f11668dea73fed1c2_d20200816_m050242_c001_v0001144_t0011") 2020/08/16 05:19:06 DEBUG : a/potato2: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a7e2d2_d20200816_m050237_c001_v0001144_t0031") 2020/08/16 05:19:06 DEBUG : b/potato: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1095ca971222407a_d20200816_m050559_c001_v0001009_t0009") 2020/08/16 05:19:06 DEBUG : b/potato: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a7f1a3_d20200816_m050544_c001_v0001144_t0031") 2020/08/16 05:19:06 DEBUG : b/potato: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f105affbf397efa34_d20200816_m050534_c001_v0001144_t0008") 2020/08/16 05:19:06 DEBUG : b/potato: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a7efdc_d20200816_m050519_c001_v0001144_t0031") 2020/08/16 05:19:07 DEBUG : b/potato: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1019e99e3940a817_d20200816_m050241_c001_v0001136_t0035") 2020/08/16 05:19:07 DEBUG : b/potato: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a7e212_d20200816_m050228_c001_v0001144_t0031") 2020/08/16 05:19:07 DEBUG : backup/one: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f10498d5ffafd3a39_d20200816_m051446_c001_v0001090_t0049") 2020/08/16 05:19:07 DEBUG : backup/one: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f108d4dae50c66d5a_d20200816_m051413_c001_v0001137_t0007") 2020/08/16 05:19:07 DEBUG : backup/one: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f103b1bd955f33544_d20200816_m051349_c001_v0001008_t0015") 2020/08/16 05:19:07 DEBUG : backup/one-2019-01-01: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f102be0194026d5d5_d20200816_m051600_c001_v0001134_t0022") 2020/08/16 05:19:07 DEBUG : backup/one-2019-01-01: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1054907b2c4f15ee_d20200816_m051550_c001_v0001137_t0058") 2020/08/16 05:19:07 DEBUG : backup/one-2019-01-01: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f114e4e681e29568c_d20200816_m051540_c001_v0001136_t0004") 2020/08/16 05:19:07 DEBUG : backup/one.bak: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f114e899831e6ba00_d20200816_m051526_c001_v0001113_t0003") 2020/08/16 05:19:07 DEBUG : backup/one.bak: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f111b73e3602bc8f9_d20200816_m051513_c001_v0001144_t0012") 2020/08/16 05:19:07 DEBUG : backup/one.bak: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f117a9c713162d974_d20200816_m051502_c001_v0001134_t0045") 2020/08/16 05:19:07 DEBUG : backup/three-2019-01-01.txt: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1128498afa3fa947_d20200816_m051600_c001_v0001040_t0004") 2020/08/16 05:19:07 DEBUG : backup/three-2019-01-01.txt: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f103519c1d1f2b1ab_d20200816_m051559_c001_v0001137_t0025") 2020/08/16 05:19:07 DEBUG : backup/three-2019-01-01.txt: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1002699dddcdabd3_d20200816_m051547_c001_v0001134_t0018") 2020/08/16 05:19:07 DEBUG : backup/three.txt: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f111448b6839d2b46_d20200816_m051446_c001_v0001044_t0004") 2020/08/16 05:19:07 DEBUG : backup/three.txt: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f11285ada8c1720b4_d20200816_m051444_c001_v0001003_t0043") 2020/08/16 05:19:07 DEBUG : backup/three.txt: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f112d55bb3b9fcad2_d20200816_m051402_c001_v0001137_t0043") 2020/08/16 05:19:07 DEBUG : backup/three.txt.bak: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f104fb71ee1e146ba_d20200816_m051527_c001_v0001134_t0037") 2020/08/16 05:19:07 DEBUG : backup/three.txt.bak: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f119d35cd93ac1b0c_d20200816_m051525_c001_v0001128_t0034") 2020/08/16 05:19:07 DEBUG : backup/three.txt.bak: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1012f14caf78de61_d20200816_m051508_c001_v0001033_t0046") 2020/08/16 05:19:07 DEBUG : c/non empty space: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f109cab78fa860bc3_d20200816_m050602_c001_v0001091_t0035") 2020/08/16 05:19:07 DEBUG : c/non empty space: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a7f1f9_d20200816_m050548_c001_v0001144_t0031") 2020/08/16 05:19:07 DEBUG : c/non empty space: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f11020097a0f0d66a_d20200816_m050540_c001_v0001144_t0004") 2020/08/16 05:19:07 DEBUG : c/non empty space: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a7f045_d20200816_m050524_c001_v0001144_t0031") 2020/08/16 05:19:07 DEBUG : c/non empty space: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f10744430d60ccacf_d20200816_m050245_c001_v0001021_t0004") 2020/08/16 05:19:08 DEBUG : c/non empty space: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a7e25e_d20200816_m050231_c001_v0001144_t0031") 2020/08/16 05:19:08 DEBUG : check sum: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f110efc10d8e8e2b9_d20200816_m050200_c001_v0001025_t0001") 2020/08/16 05:19:08 DEBUG : check sum: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a7df6e_d20200816_m050156_c001_v0001144_t0031") 2020/08/16 05:19:08 DEBUG : dst/one: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1103c9c1265e2878_d20200816_m051758_c001_v0001136_t0038") 2020/08/16 05:19:08 DEBUG : dst/one: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f10864437d8fd2424_d20200816_m051747_c001_v0001144_t0057") 2020/08/16 05:19:08 DEBUG : dst/one: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f119965017182e5f5_d20200816_m051725_c001_v0001039_t0025") 2020/08/16 05:19:08 DEBUG : dst/one: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f10864437d8fd2186_d20200816_m051708_c001_v0001144_t0057") 2020/08/16 05:19:08 DEBUG : dst/one: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f114eb0714c35e624_d20200816_m051657_c001_v0001144_t0043") 2020/08/16 05:19:08 DEBUG : dst/one: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a820f3_d20200816_m051644_c001_v0001144_t0031") 2020/08/16 05:19:08 DEBUG : dst/one: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f106b5063f499758d_d20200816_m051635_c001_v0001004_t0030") 2020/08/16 05:19:08 DEBUG : dst/one: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1044c8b2a4341062_d20200816_m051632_c001_v0001137_t0013") 2020/08/16 05:19:08 DEBUG : dst/one: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f111ba36bd2864f8f_d20200816_m051631_c001_v0001130_t0001") 2020/08/16 05:19:08 DEBUG : dst/one: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1044c8b2a4340ff9_d20200816_m051623_c001_v0001137_t0013") 2020/08/16 05:19:08 DEBUG : dst/one: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f107026523ad81147_d20200816_m051621_c001_v0001044_t0005") 2020/08/16 05:19:08 DEBUG : dst/one: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a81e35_d20200816_m051609_c001_v0001144_t0031") 2020/08/16 05:19:08 DEBUG : dst/one: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f114c623925cd0305_d20200816_m051607_c001_v0001013_t0010") 2020/08/16 05:19:08 DEBUG : dst/one: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f102c79dc22ba5175_d20200816_m051556_c001_v0001144_t0059") 2020/08/16 05:19:08 DEBUG : dst/one: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f107d6ad02eee800a_d20200816_m051551_c001_v0001029_t0033") 2020/08/16 05:19:08 DEBUG : dst/one: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f102c79dc22ba5083_d20200816_m051543_c001_v0001144_t0059") 2020/08/16 05:19:09 DEBUG : dst/one: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f111d0bf7aa1f7687_d20200816_m051541_c001_v0001011_t0021") 2020/08/16 05:19:09 DEBUG : dst/one: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f117358a458156109_d20200816_m051528_c001_v0001137_t0006") 2020/08/16 05:19:09 DEBUG : dst/one: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f107ca9b58594095b_d20200816_m051527_c001_v0001134_t0032") 2020/08/16 05:19:09 DEBUG : dst/one: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f10864437d8fd1a2b_d20200816_m051518_c001_v0001144_t0027") 2020/08/16 05:19:09 DEBUG : dst/one: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f112e74b642abcd90_d20200816_m051517_c001_v0001042_t0041") 2020/08/16 05:19:09 DEBUG : dst/one: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f10864437d8fd191a_d20200816_m051504_c001_v0001144_t0027") 2020/08/16 05:19:09 DEBUG : dst/one: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f106a4ec1f9e8e8f4_d20200816_m051503_c001_v0001137_t0002") 2020/08/16 05:19:09 DEBUG : dst/one: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f11668dea73ff04ce_d20200816_m051451_c001_v0001144_t0026") 2020/08/16 05:19:09 DEBUG : dst/one: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f104afa2fed8e834c_d20200816_m051446_c001_v0001144_t0016") 2020/08/16 05:19:09 DEBUG : dst/one: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f11959df493534c17_d20200816_m051414_c001_v0001042_t0017") 2020/08/16 05:19:09 DEBUG : dst/one: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f114e6eac0c3629fe_d20200816_m051413_c001_v0001137_t0041") 2020/08/16 05:19:09 DEBUG : dst/one: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f11959df493534bf4_d20200816_m051351_c001_v0001042_t0017") 2020/08/16 05:19:09 DEBUG : dst/one: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f115c42b62101cbff_d20200816_m051350_c001_v0001137_t0018") 2020/08/16 05:19:09 DEBUG : dst/one: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a8139f_d20200816_m051339_c001_v0001144_t0031") 2020/08/16 05:19:09 DEBUG : dst/one: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f110c1f465612477e_d20200816_m051334_c001_v0001130_t0035") 2020/08/16 05:19:09 DEBUG : dst/one: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f117024f025769a1b_d20200816_m051313_c001_v0001029_t0031") 2020/08/16 05:19:09 DEBUG : dst/one: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1083226fded70b73_d20200816_m051309_c001_v0001004_t0033") 2020/08/16 05:19:09 DEBUG : dst/one: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f117358a458155630_d20200816_m051230_c001_v0001137_t0006") 2020/08/16 05:19:10 DEBUG : dst/one: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f10864437d8fd0e6a_d20200816_m051226_c001_v0001144_t0053") 2020/08/16 05:19:10 DEBUG : dst/one: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f10864437d8fd0e15_d20200816_m051221_c001_v0001144_t0053") 2020/08/16 05:19:10 DEBUG : dst/one: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f11677733a81f7870_d20200816_m051218_c001_v0001131_t0011") 2020/08/16 05:19:10 DEBUG : dst/one: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f117358a458155411_d20200816_m051159_c001_v0001137_t0006") 2020/08/16 05:19:10 DEBUG : dst/one: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f11640913b5e437e8_d20200816_m051158_c001_v0001120_t0017") 2020/08/16 05:19:10 DEBUG : dst/one: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f11640913b5e437d9_d20200816_m051153_c001_v0001120_t0017") 2020/08/16 05:19:10 DEBUG : dst/one-2019-01-01: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f11640913b5e43d8c_d20200816_m051759_c001_v0001120_t0032") 2020/08/16 05:19:10 DEBUG : dst/one-2019-01-01: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1157a58870995615_d20200816_m051720_c001_v0001144_t0052") 2020/08/16 05:19:10 DEBUG : dst/one-2019-01-01: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1161e7946a65fcbd_d20200816_m051651_c001_v0001039_t0029") 2020/08/16 05:19:10 DEBUG : dst/one.bak: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f111b73e3602bceb0_d20200816_m051638_c001_v0001144_t0026") 2020/08/16 05:19:10 DEBUG : dst/one.bak: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1032f9800a7fe6af_d20200816_m051628_c001_v0001144_t0002") 2020/08/16 05:19:10 DEBUG : dst/one.bak: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f117ec53bd6b60d2d_d20200816_m051620_c001_v0001144_t0045") 2020/08/16 05:19:10 DEBUG : dst/three: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1149b9c656b73e7c_d20200816_m051334_c001_v0001131_t0057") 2020/08/16 05:19:10 DEBUG : dst/three: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f10864437d8fd1217_d20200816_m051322_c001_v0001144_t0053") 2020/08/16 05:19:10 DEBUG : dst/three-2019-01-01.txt: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f106a4ec1f9e8f402_d20200816_m051800_c001_v0001137_t0015") 2020/08/16 05:19:10 DEBUG : dst/three-2019-01-01.txt: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f117c21db926c92e0_d20200816_m051751_c001_v0001128_t0033") 2020/08/16 05:19:10 DEBUG : dst/three-2019-01-01.txt: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f106a4ec1f9e8f18b_d20200816_m051711_c001_v0001137_t0000") 2020/08/16 05:19:10 DEBUG : dst/three.txt: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1006890fb90ddddc_d20200816_m051800_c001_v0001136_t0011") 2020/08/16 05:19:10 DEBUG : dst/three.txt: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f10864437d8fd2487_d20200816_m051752_c001_v0001144_t0057") 2020/08/16 05:19:10 DEBUG : dst/three.txt: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1087df3fca078ade_d20200816_m051751_c001_v0001136_t0033") 2020/08/16 05:19:10 DEBUG : dst/three.txt: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f10864437d8fd2219_d20200816_m051715_c001_v0001144_t0057") 2020/08/16 05:19:11 DEBUG : dst/three.txt: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f114eb0714c35e71a_d20200816_m051712_c001_v0001144_t0036") 2020/08/16 05:19:11 DEBUG : dst/three.txt: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f117358a4581565c6_d20200816_m051649_c001_v0001137_t0006") 2020/08/16 05:19:11 DEBUG : dst/three.txt: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f103490730a3d9a96_d20200816_m051641_c001_v0001039_t0022") 2020/08/16 05:19:11 DEBUG : dst/three.txt: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1044c8b2a4341071_d20200816_m051634_c001_v0001137_t0013") 2020/08/16 05:19:11 DEBUG : dst/three.txt: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f119879db3cc06115_d20200816_m051633_c001_v0001134_t0048") 2020/08/16 05:19:11 DEBUG : dst/three.txt: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1044c8b2a434102f_d20200816_m051627_c001_v0001137_t0013") 2020/08/16 05:19:11 DEBUG : dst/three.txt: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1010f8d6a33dbabf_d20200816_m051624_c001_v0001144_t0005") 2020/08/16 05:19:11 DEBUG : dst/three.txt: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f117358a4581563ee_d20200816_m051617_c001_v0001137_t0006") 2020/08/16 05:19:11 DEBUG : dst/three.txt: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f119ec40c9820e218_d20200816_m051559_c001_v0001037_t0001") 2020/08/16 05:19:11 DEBUG : dst/three.txt: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f117358a458156256_d20200816_m051549_c001_v0001137_t0006") 2020/08/16 05:19:11 DEBUG : dst/three.txt: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f105492b506c0bd36_d20200816_m051548_c001_v0001136_t0040") 2020/08/16 05:19:11 DEBUG : dst/three.txt: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f11668dea73ff07f7_d20200816_m051536_c001_v0001144_t0026") 2020/08/16 05:19:11 DEBUG : dst/three.txt: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f100548106ad1f506_d20200816_m051525_c001_v0001038_t0005") 2020/08/16 05:19:11 DEBUG : dst/three.txt: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f11668dea73ff062b_d20200816_m051510_c001_v0001144_t0026") 2020/08/16 05:19:11 DEBUG : dst/three.txt: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f110221f0a71ce3ff_d20200816_m051509_c001_v0001137_t0037") 2020/08/16 05:19:11 DEBUG : dst/three.txt: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a818d3_d20200816_m051455_c001_v0001144_t0031") 2020/08/16 05:19:11 DEBUG : dst/three.txt: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1076240f507cff61_d20200816_m051445_c001_v0001136_t0041") 2020/08/16 05:19:11 DEBUG : dst/three.txt: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a815a1_d20200816_m051407_c001_v0001144_t0031") 2020/08/16 05:19:11 DEBUG : dst/three.txt: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f11020097a0f0febd_d20200816_m051402_c001_v0001144_t0035") 2020/08/16 05:19:11 DEBUG : dst/three.txt: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f117358a458155b05_d20200816_m051346_c001_v0001137_t0006") 2020/08/16 05:19:11 DEBUG : dst/three.txt.bak: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f112b0541d2fa5bb9_d20200816_m051642_c001_v0001000_t0035") 2020/08/16 05:19:11 DEBUG : dst/three.txt.bak: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f10792d73a1aa0f43_d20200816_m051633_c001_v0001131_t0057") 2020/08/16 05:19:11 DEBUG : dst/three.txt.bak: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f11314870a4a02a5e_d20200816_m051624_c001_v0001130_t0006") 2020/08/16 05:19:12 DEBUG : dst/two: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1019c98afaf63699_d20200816_m051801_c001_v0001029_t0007") 2020/08/16 05:19:12 DEBUG : dst/two: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f11668dea73ff0cf7_d20200816_m051646_c001_v0001144_t0026") 2020/08/16 05:19:12 DEBUG : dst/two: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f116b6ffbd8a19c5d_d20200816_m051642_c001_v0001090_t0046") 2020/08/16 05:19:12 DEBUG : dst/two: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f11668dea73ff0a93_d20200816_m051613_c001_v0001144_t0026") 2020/08/16 05:19:12 DEBUG : dst/two: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f10792d73a1aa0ede_d20200816_m051608_c001_v0001131_t0046") 2020/08/16 05:19:12 DEBUG : dst/two: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a81b62_d20200816_m051529_c001_v0001144_t0031") 2020/08/16 05:19:12 DEBUG : dst/two: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f10518b89576b38e3_d20200816_m051528_c001_v0001134_t0052") 2020/08/16 05:19:12 DEBUG : dst/two: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f117358a458155ef6_d20200816_m051454_c001_v0001137_t0006") 2020/08/16 05:19:12 DEBUG : dst/two: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1019e99e3940c17c_d20200816_m051450_c001_v0001136_t0042") 2020/08/16 05:19:12 DEBUG : dst/two: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f11668dea73ff0057_d20200816_m051345_c001_v0001144_t0026") 2020/08/16 05:19:12 DEBUG : dst/two: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f11863160720c6d1a_d20200816_m051335_c001_v0001144_t0016") 2020/08/16 05:19:12 DEBUG : dst/two: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f105dee992924c39d_d20200816_m051317_c001_v0001043_t0005") 2020/08/16 05:19:12 DEBUG : dst/two: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1008eb37fa5dc49c_d20200816_m051218_c001_v0001091_t0001") 2020/08/16 05:19:12 DEBUG : dst/two: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f11640913b5e43819_d20200816_m051213_c001_v0001120_t0017") 2020/08/16 05:19:12 DEBUG : empty space: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f105affbf397f15c4_d20200816_m051123_c001_v0001144_t0020") 2020/08/16 05:19:12 DEBUG : empty space: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f117358a458155095_d20200816_m051112_c001_v0001137_t0006") 2020/08/16 05:19:12 DEBUG : empty space: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1194de8070893b8f_d20200816_m051058_c001_v0001144_t0017") 2020/08/16 05:19:12 DEBUG : empty space: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f117358a458154cb0_d20200816_m051013_c001_v0001137_t0006") 2020/08/16 05:19:12 DEBUG : empty space: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f100d18801a861ef7_d20200816_m050952_c001_v0001040_t0027") 2020/08/16 05:19:12 DEBUG : empty space: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f117358a458154b2d_d20200816_m050941_c001_v0001137_t0006") 2020/08/16 05:19:12 DEBUG : empty space: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1107aa6b5e678faa_d20200816_m050755_c001_v0001128_t0035") 2020/08/16 05:19:12 DEBUG : empty space: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a7f9c0_d20200816_m050739_c001_v0001144_t0031") 2020/08/16 05:19:13 DEBUG : empty space: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f10404afbdd3171a6_d20200816_m050732_c001_v0001033_t0031") 2020/08/16 05:19:13 DEBUG : empty space: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a7f8a4_d20200816_m050726_c001_v0001144_t0031") 2020/08/16 05:19:13 DEBUG : empty space: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f115725c3576659d0_d20200816_m050655_c001_v0001134_t0032") 2020/08/16 05:19:13 DEBUG : empty space: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a7f5b7_d20200816_m050643_c001_v0001144_t0031") 2020/08/16 05:19:13 DEBUG : empty space: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1017459327f740c6_d20200816_m050622_c001_v0001128_t0022") 2020/08/16 05:19:13 DEBUG : empty space: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a7f37a_d20200816_m050608_c001_v0001144_t0031") 2020/08/16 05:19:13 DEBUG : empty space: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f11203f8ca5877ac2_d20200816_m050515_c001_v0001120_t0018") 2020/08/16 05:19:13 DEBUG : empty space: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a7edff_d20200816_m050455_c001_v0001144_t0031") 2020/08/16 05:19:13 DEBUG : empty space: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f114e4e681e2939d5_d20200816_m050444_c001_v0001136_t0056") 2020/08/16 05:19:13 DEBUG : empty space: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a7ea7e_d20200816_m050409_c001_v0001144_t0031") 2020/08/16 05:19:13 DEBUG : empty space: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f100c6a60a8fdda2d_d20200816_m050325_c001_v0001113_t0005") 2020/08/16 05:19:13 DEBUG : empty space: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a7e683_d20200816_m050318_c001_v0001144_t0031") 2020/08/16 05:19:13 DEBUG : empty space: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f11863160720c4298_d20200816_m050309_c001_v0001144_t0002") 2020/08/16 05:19:13 DEBUG : empty space: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a7e531_d20200816_m050305_c001_v0001144_t0031") 2020/08/16 05:19:13 DEBUG : empty space: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f11020097a0f0c984_d20200816_m050301_c001_v0001144_t0044") 2020/08/16 05:19:13 DEBUG : empty space: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f11668dea73fed2ea_d20200816_m050256_c001_v0001144_t0010") 2020/08/16 05:19:13 DEBUG : empty space: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a7e42c_d20200816_m050252_c001_v0001144_t0031") 2020/08/16 05:19:13 DEBUG : enormous: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1032f9800a7fc2e6_d20200816_m050750_c001_v0001144_t0016") 2020/08/16 05:19:14 DEBUG : enormous: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a7fa13_d20200816_m050744_c001_v0001144_t0031") 2020/08/16 05:19:14 DEBUG : existing: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f109d95497fe9cca0_d20200816_m051849_c001_v0001040_t0034") 2020/08/16 05:19:14 DEBUG : existing: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f117358a458156a71_d20200816_m051818_c001_v0001137_t0006") 2020/08/16 05:19:14 DEBUG : existing: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f103519c1d1f293a9_d20200816_m050228_c001_v0001137_t0032") 2020/08/16 05:19:14 DEBUG : existing: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a7e1a6_d20200816_m050223_c001_v0001144_t0031") 2020/08/16 05:19:14 DEBUG : existing: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1194aed53d6e1996_d20200816_m050222_c001_v0001137_t0013") 2020/08/16 05:19:14 DEBUG : existing: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a7e135_d20200816_m050218_c001_v0001144_t0031") 2020/08/16 05:19:14 DEBUG : existing: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a7e0ed_d20200816_m050214_c001_v0001144_t0031") 2020/08/16 05:19:14 DEBUG : five: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f109ebd74d0090916_d20200816_m050824_c001_v0001113_t0038") 2020/08/16 05:19:14 DEBUG : five: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a7fca9_d20200816_m050819_c001_v0001144_t0031") 2020/08/16 05:19:14 DEBUG : foo: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f10920176d6da0d69_d20200816_m050317_c001_v0001039_t0044") 2020/08/16 05:19:14 DEBUG : foo: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a7e61e_d20200816_m050314_c001_v0001144_t0031") 2020/08/16 05:19:14 DEBUG : foo: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a7e5c4_d20200816_m050311_c001_v0001144_t0031") 2020/08/16 05:19:14 DEBUG : four: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1068bfdb4565a234_d20200816_m050824_c001_v0001144_t0008") 2020/08/16 05:19:14 DEBUG : four: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f11668dea73fee9ee_d20200816_m050819_c001_v0001144_t0026") 2020/08/16 05:19:14 DEBUG : four: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a7fc3e_d20200816_m050813_c001_v0001144_t0031") 2020/08/16 05:19:14 DEBUG : hello world2: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f107e75778bbe2099_d20200816_m050055_c001_v0001039_t0016") 2020/08/16 05:19:14 DEBUG : hello world2: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f117ec53bd6b5e6ec_d20200816_m050053_c001_v0001144_t0048") 2020/08/16 05:19:14 DEBUG : ignore-size: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f114eb0714c35a6bf_d20200816_m050211_c001_v0001144_t0017") 2020/08/16 05:19:14 DEBUG : ignore-size: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a7e05d_d20200816_m050207_c001_v0001144_t0031") 2020/08/16 05:19:14 DEBUG : nested/sub dir/file: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f104afa2fed8e6d22_d20200816_m050934_c001_v0001144_t0026") 2020/08/16 05:19:14 DEBUG : nested/sub dir/file: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a8017a_d20200816_m050928_c001_v0001144_t0031") 2020/08/16 05:19:14 DEBUG : nested/sub dir/file: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f10518b89576b2e69_d20200816_m050922_c001_v0001134_t0011") 2020/08/16 05:19:14 DEBUG : nested/sub dir/file: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f117358a4581549e3_d20200816_m050918_c001_v0001137_t0006") 2020/08/16 05:19:15 DEBUG : one: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f111b73e3602baef5_d20200816_m050830_c001_v0001144_t0050") 2020/08/16 05:19:15 DEBUG : one: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a7fb0b_d20200816_m050756_c001_v0001144_t0031") 2020/08/16 05:19:15 DEBUG : potato: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f106b77d73ee5ed1d_d20200816_m050910_c001_v0001009_t0023") 2020/08/16 05:19:15 DEBUG : potato: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a7ffa7_d20200816_m050901_c001_v0001144_t0031") 2020/08/16 05:19:15 DEBUG : potato: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f103adff46d6f4f0f_d20200816_m050859_c001_v0001136_t0006") 2020/08/16 05:19:15 DEBUG : potato: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f117358a45815488f_d20200816_m050853_c001_v0001137_t0006") 2020/08/16 05:19:15 DEBUG : potato: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f108bb03408507114_d20200816_m050845_c001_v0001038_t0024") 2020/08/16 05:19:15 DEBUG : potato: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f11668dea73feeade_d20200816_m050835_c001_v0001144_t0026") 2020/08/16 05:19:15 DEBUG : potato: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1130ffbad4cb4788_d20200816_m050719_c001_v0001134_t0019") 2020/08/16 05:19:15 DEBUG : potato: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a7f77a_d20200816_m050710_c001_v0001144_t0031") 2020/08/16 05:19:15 DEBUG : potato: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f10985d38acb07bd3_d20200816_m050649_c001_v0001131_t0048") 2020/08/16 05:19:15 DEBUG : potato: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a7f4f0_d20200816_m050629_c001_v0001144_t0031") 2020/08/16 05:19:15 DEBUG : potato: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f102422dea06e167b_d20200816_m050615_c001_v0001137_t0001") 2020/08/16 05:19:15 DEBUG : potato: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a7f336_d20200816_m050604_c001_v0001144_t0031") 2020/08/16 05:19:15 DEBUG : potato: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f101ffe6f153f9ad9_d20200816_m050514_c001_v0001113_t0051") 2020/08/16 05:19:15 DEBUG : potato: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a7ed95_d20200816_m050449_c001_v0001144_t0031") 2020/08/16 05:19:15 DEBUG : potato: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1145f6838ea9b517_d20200816_m050445_c001_v0001003_t0027") 2020/08/16 05:19:15 DEBUG : potato: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a7ea53_d20200816_m050406_c001_v0001144_t0031") 2020/08/16 05:19:15 DEBUG : potato: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f11863160720c461c_d20200816_m050401_c001_v0001144_t0029") 2020/08/16 05:19:15 DEBUG : potato: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a7e9b1_d20200816_m050356_c001_v0001144_t0031") 2020/08/16 05:19:15 DEBUG : potato: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1130ffbad4cb3e10_d20200816_m050342_c001_v0001134_t0033") 2020/08/16 05:19:15 DEBUG : potato: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a7e8af_d20200816_m050342_c001_v0001144_t0031") 2020/08/16 05:19:15 DEBUG : potato: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a7e834_d20200816_m050337_c001_v0001144_t0031") 2020/08/16 05:19:16 DEBUG : potato: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a7e7d1_d20200816_m050333_c001_v0001144_t0031") 2020/08/16 05:19:16 DEBUG : potato: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1068bfdb45658c5e_d20200816_m050328_c001_v0001144_t0040") 2020/08/16 05:19:16 DEBUG : potato: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a7e6b0_d20200816_m050321_c001_v0001144_t0031") 2020/08/16 05:19:16 DEBUG : potato2: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f100e2f89c746b990_d20200816_m051146_c001_v0001030_t0013") 2020/08/16 05:19:16 DEBUG : potato2: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f11668dea73fef7f7_d20200816_m051141_c001_v0001144_t0026") 2020/08/16 05:19:16 DEBUG : potato2: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f118310f3552c7a65_d20200816_m051123_c001_v0001136_t0046") 2020/08/16 05:19:16 DEBUG : potato2: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f11668dea73fef560_d20200816_m051108_c001_v0001144_t0026") 2020/08/16 05:19:16 DEBUG : potato2: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f111a2125e38cf5c8_d20200816_m051020_c001_v0001025_t0013") 2020/08/16 05:19:16 DEBUG : potato2: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f11668dea73fef15d_d20200816_m051010_c001_v0001144_t0026") 2020/08/16 05:19:16 DEBUG : potato2: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f100d29e27439a106_d20200816_m050953_c001_v0001041_t0027") 2020/08/16 05:19:16 DEBUG : potato2: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f11668dea73feef2b_d20200816_m050938_c001_v0001144_t0026") 2020/08/16 05:19:16 DEBUG : potato2: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f109b76d7b9ed6394_d20200816_m050750_c001_v0001144_t0050") 2020/08/16 05:19:16 DEBUG : potato2: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a7f972_d20200816_m050736_c001_v0001144_t0031") 2020/08/16 05:19:16 DEBUG : potato2: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1010f8d6a33d9668_d20200816_m050733_c001_v0001144_t0048") 2020/08/16 05:19:16 DEBUG : potato2: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a7f850_d20200816_m050721_c001_v0001144_t0031") 2020/08/16 05:19:16 DEBUG : potato2: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1007511da525d12c_d20200816_m050719_c001_v0001120_t0012") 2020/08/16 05:19:16 DEBUG : potato2: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a7f7ed_d20200816_m050715_c001_v0001144_t0031") 2020/08/16 05:19:16 DEBUG : potato2: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f102898aa188996b8_d20200816_m050655_c001_v0001039_t0035") 2020/08/16 05:19:16 DEBUG : potato2: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a7f610_d20200816_m050649_c001_v0001144_t0031") 2020/08/16 05:19:16 DEBUG : potato2: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f11020097a0f0da05_d20200816_m050623_c001_v0001144_t0007") 2020/08/16 05:19:16 DEBUG : potato2: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a7f400_d20200816_m050615_c001_v0001144_t0031") 2020/08/16 05:19:16 DEBUG : potato2: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f112304ad696d1a65_d20200816_m050518_c001_v0001128_t0042") 2020/08/16 05:19:16 DEBUG : potato2: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a7eea4_d20200816_m050502_c001_v0001144_t0031") 2020/08/16 05:19:16 DEBUG : potato2: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f115c42b62101a742_d20200816_m050105_c001_v0001137_t0059") 2020/08/16 05:19:17 DEBUG : potato2: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f117ec53bd6b5e791_d20200816_m050103_c001_v0001144_t0048") 2020/08/16 05:19:17 DEBUG : potato2: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f105affbf397ee42d_d20200816_m050100_c001_v0001144_t0054") 2020/08/16 05:19:17 DEBUG : potato2: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f117ec53bd6b5e746_d20200816_m050059_c001_v0001144_t0048") 2020/08/16 05:19:17 DEBUG : potato3: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1054b4521d4c4c02_d20200816_m051128_c001_v0001131_t0038") 2020/08/16 05:19:17 DEBUG : potato3: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a80917_d20200816_m051112_c001_v0001144_t0031") 2020/08/16 05:19:17 DEBUG : potato3: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f117e2e2eaa7b8685_d20200816_m051023_c001_v0001018_t0017") 2020/08/16 05:19:17 DEBUG : potato3: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a804ae_d20200816_m051014_c001_v0001144_t0031") 2020/08/16 05:19:17 DEBUG : potato3: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f115ffd7ab275c370_d20200816_m050953_c001_v0001130_t0010") 2020/08/16 05:19:17 DEBUG : potato3: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a80266_d20200816_m050942_c001_v0001144_t0031") 2020/08/16 05:19:17 DEBUG : sizeonly: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1099da060b1ab797_d20200816_m050207_c001_v0001134_t0048") 2020/08/16 05:19:17 DEBUG : sizeonly: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a7dfe1_d20200816_m050201_c001_v0001144_t0031") 2020/08/16 05:19:17 DEBUG : sub dir/hello world: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f101ffe6f153f9cc4_d20200816_m050935_c001_v0001113_t0039") 2020/08/16 05:19:17 DEBUG : sub dir/hello world: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f117358a458154a6d_d20200816_m050928_c001_v0001137_t0006") 2020/08/16 05:19:17 DEBUG : sub dir/hello world: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f114565666329fc17_d20200816_m050923_c001_v0001039_t0032") 2020/08/16 05:19:17 DEBUG : sub dir/hello world: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f11668dea73feeded_d20200816_m050918_c001_v0001144_t0026") 2020/08/16 05:19:17 DEBUG : sub dir/hello world: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f102b08b61bc7b116_d20200816_m050154_c001_v0001130_t0029") 2020/08/16 05:19:17 DEBUG : sub dir/hello world: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f117ec53bd6b5e9f4_d20200816_m050145_c001_v0001144_t0048") 2020/08/16 05:19:17 DEBUG : sub dir/hello world: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f11020097a0f0c256_d20200816_m050140_c001_v0001144_t0050") 2020/08/16 05:19:17 DEBUG : sub dir/hello world: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f117ec53bd6b5e99b_d20200816_m050139_c001_v0001144_t0048") 2020/08/16 05:19:17 DEBUG : sub dir/hello world: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f11203f8ca5877580_d20200816_m050128_c001_v0001120_t0012") 2020/08/16 05:19:17 DEBUG : sub dir/hello world: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f117ec53bd6b5e883_d20200816_m050120_c001_v0001144_t0048") 2020/08/16 05:19:17 DEBUG : sub dir/hello world: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f10864437d8fce181_d20200816_m050117_c001_v0001144_t0017") 2020/08/16 05:19:17 DEBUG : sub dir/hello world: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f117ec53bd6b5e839_d20200816_m050115_c001_v0001144_t0048") 2020/08/16 05:19:17 DEBUG : sub dir/hello world: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f102b5720d275d8c1_d20200816_m050111_c001_v0001021_t0042") 2020/08/16 05:19:17 DEBUG : sub dir/hello world: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f117ec53bd6b5e7db_d20200816_m050108_c001_v0001144_t0048") 2020/08/16 05:19:17 DEBUG : sub dir/hello world: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f109606c10a765c7c_d20200816_m050107_c001_v0001136_t0040") 2020/08/16 05:19:18 DEBUG : sub dir/hello world: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f117ec53bd6b5e7b0_d20200816_m050105_c001_v0001144_t0048") 2020/08/16 05:19:18 DEBUG : sub dir/hello world: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f114e899831e6b24f_d20200816_m050053_c001_v0001113_t0019") 2020/08/16 05:19:18 DEBUG : sub dir/hello world: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f117ec53bd6b5e6c3_d20200816_m050051_c001_v0001144_t0048") 2020/08/16 05:19:18 DEBUG : sub dir/hello world: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1010f8d6a33d796e_d20200816_m050048_c001_v0001144_t0023") 2020/08/16 05:19:18 DEBUG : sub dir/hello world: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f117ec53bd6b5e692_d20200816_m050047_c001_v0001144_t0048") 2020/08/16 05:19:18 DEBUG : sub dir/hello world: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f112d55bb3b9fa8b8_d20200816_m050046_c001_v0001137_t0028") 2020/08/16 05:19:18 DEBUG : sub dir/hello world: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f117ec53bd6b5e676_d20200816_m050045_c001_v0001144_t0048") 2020/08/16 05:19:18 DEBUG : sub dir/hello world: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f110221f0a71cb2d5_d20200816_m050043_c001_v0001137_t0035") 2020/08/16 05:19:18 DEBUG : sub dir/hello world: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f117ec53bd6b5e63d_d20200816_m050041_c001_v0001144_t0048") 2020/08/16 05:19:18 DEBUG : sub/yam: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f110221f0a71cd32e_d20200816_m050909_c001_v0001137_t0042") 2020/08/16 05:19:18 DEBUG : sub/yam: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f117358a45815490d_d20200816_m050901_c001_v0001137_t0006") 2020/08/16 05:19:18 DEBUG : three: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f110fc0e7c93222b1_d20200816_m050833_c001_v0001134_t0056") 2020/08/16 05:19:18 DEBUG : three: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f117358a458154722_d20200816_m050823_c001_v0001137_t0006") 2020/08/16 05:19:18 DEBUG : three: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a7fbed_d20200816_m050808_c001_v0001144_t0031") 2020/08/16 05:19:18 DEBUG : two: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f11179545fd50f1a8_d20200816_m050834_c001_v0001128_t0051") 2020/08/16 05:19:18 DEBUG : two: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f117358a4581546f3_d20200816_m050820_c001_v0001137_t0006") 2020/08/16 05:19:18 DEBUG : two: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a7fb69_d20200816_m050801_c001_v0001144_t0031") 2020/08/16 05:19:18 DEBUG : yam: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1010f8d6a33d9db3_d20200816_m050913_c001_v0001144_t0023") 2020/08/16 05:19:18 DEBUG : yam: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f11804e077b299410_d20200816_m050909_c001_v0001137_t0044") 2020/08/16 05:19:18 DEBUG : yam: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f110385f1f4e0cd97_d20200816_m050858_c001_v0001030_t0040") 2020/08/16 05:19:18 DEBUG : yam: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f11668dea73feec22_d20200816_m050853_c001_v0001144_t0026") 2020/08/16 05:19:19 DEBUG : yam: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f111b73e3602bafc4_d20200816_m050840_c001_v0001144_t0000") 2020/08/16 05:19:19 DEBUG : yam: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1123c7e028a7fdc9_d20200816_m050835_c001_v0001144_t0031") 2020/08/16 05:19:19 DEBUG : yaml: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f10424d34102e9463_d20200816_m050900_c001_v0001012_t0044") 2020/08/16 05:19:19 DEBUG : yaml: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1108f82d4c8587f7_d20200816_m050858_c001_v0001120_t0043") 2020/08/16 05:19:19 DEBUG : yaml: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f113654f1b3c2d2bc_d20200816_m050847_c001_v0001144_t0056") 2020/08/16 05:19:19 DEBUG : yaml: Deleting (id "4_zcff20dd3a04dfb5b7f380e1b_f1111e2e9ea96e1af_d20200816_m050839_c001_v0001026_t0006") "./sync.test -test.v -test.timeout 1h0m0s -remote TestB2: -verbose" - Finished ERROR in 18m45.28981554s (try 1/5): exit status 1: Failed [TestSyncAfterChangingContentsOnly]