"./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.06s) === RUN TestPipeOrderBy === RUN TestPipeOrderBy/#00 === RUN TestPipeOrderBy/size === RUN TestPipeOrderBy/name === RUN TestPipeOrderBy/modtime === RUN TestPipeOrderBy/size,ascending === RUN TestPipeOrderBy/name,asc === RUN TestPipeOrderBy/modtime,ascending === RUN TestPipeOrderBy/size,descending === RUN TestPipeOrderBy/name,desc === RUN TestPipeOrderBy/modtime,descending === 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 --- SKIP: TestRcCopy (0.00s) rc_test.go:16: Skipping test on non local remote === RUN TestRcMove --- SKIP: TestRcMove (0.00s) rc_test.go:16: Skipping test on non local remote === RUN TestRcSync --- SKIP: TestRcSync (0.00s) rc_test.go:16: Skipping test on non local remote === RUN TestCopyWithDryRun 2020/05/15 05:24:05 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for checks to finish 2020/05/15 05:24:05 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for transfers to finish 2020/05/15 05:24:05 NOTICE: sub dir/hello world: Not copying as --dry-run --- PASS: TestCopyWithDryRun (1.28s) run.go:176: Remote "B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1", Local "Local file system at /tmp/rclone533931174", Modify Window "1ms" === RUN TestCopy 2020/05/15 05:24:06 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for checks to finish 2020/05/15 05:24:06 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for transfers to finish 2020/05/15 05:24:07 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2020/05/15 05:24:07 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopy (2.99s) run.go:176: Remote "B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1", Local "Local file system at /tmp/rclone533931174", Modify Window "1ms" === RUN TestCopyMissingDirectory 2020/05/15 05:24:09 ERROR : : error reading source directory: directory not found 2020/05/15 05:24:09 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for checks to finish 2020/05/15 05:24:09 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for transfers to finish --- PASS: TestCopyMissingDirectory (0.42s) run.go:176: Remote "B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1", Local "Local file system at /tmp/rclone533931174", Modify Window "1ms" === RUN TestCopyNoTraverse 2020/05/15 05:24:09 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for checks to finish 2020/05/15 05:24:09 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for transfers to finish 2020/05/15 05:24:10 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2020/05/15 05:24:10 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoTraverse (1.78s) run.go:176: Remote "B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1", Local "Local file system at /tmp/rclone533931174", Modify Window "1ms" === RUN TestSyncNoTraverse 2020/05/15 05:24:11 ERROR : Ignoring --no-traverse with sync 2020/05/15 05:24:11 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for checks to finish 2020/05/15 05:24:11 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for transfers to finish 2020/05/15 05:24:11 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2020/05/15 05:24:11 INFO : sub dir/hello world: Copied (new) 2020/05/15 05:24:11 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoTraverse (2.40s) run.go:176: Remote "B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1", Local "Local file system at /tmp/rclone533931174", Modify Window "1ms" === RUN TestCopyWithDepth 2020/05/15 05:24:13 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for checks to finish 2020/05/15 05:24:13 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for transfers to finish 2020/05/15 05:24:14 DEBUG : hello world2: SHA-1 = c0bf117f9c93d02c894fe8ffd6e5cecf3545c209 OK 2020/05/15 05:24:14 INFO : hello world2: Copied (new) --- PASS: TestCopyWithDepth (1.80s) run.go:176: Remote "B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1", Local "Local file system at /tmp/rclone533931174", Modify Window "1ms" === RUN TestCopyWithFilesFrom 2020/05/15 05:24:15 DEBUG : hello world2: Excluded 2020/05/15 05:24:15 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for checks to finish 2020/05/15 05:24:15 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for transfers to finish 2020/05/15 05:24:16 DEBUG : potato2: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2020/05/15 05:24:16 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFrom (2.16s) run.go:176: Remote "B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1", Local "Local file system at /tmp/rclone533931174", Modify Window "1ms" === RUN TestCopyWithFilesFromAndNoTraverse 2020/05/15 05:24:17 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for checks to finish 2020/05/15 05:24:17 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for transfers to finish 2020/05/15 05:24:17 DEBUG : potato2: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2020/05/15 05:24:17 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFromAndNoTraverse (1.23s) run.go:176: Remote "B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1", Local "Local file system at /tmp/rclone533931174", Modify Window "1ms" === RUN TestCopyEmptyDirectories 2020/05/15 05:24:18 DEBUG : sub dir2: Making directory 2020/05/15 05:24:18 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for checks to finish 2020/05/15 05:24:18 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for transfers to finish 2020/05/15 05:24:19 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2020/05/15 05:24:19 INFO : sub dir/hello world: Copied (new) 2020/05/15 05:24:19 DEBUG : sub dir2: Making directory 2020/05/15 05:24:19 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: copied 1 directories --- PASS: TestCopyEmptyDirectories (2.10s) run.go:176: Remote "B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1", Local "Local file system at /tmp/rclone533931174", Modify Window "1ms" fstest.go:247: Filtering empty directory "sub dir2" === RUN TestMoveEmptyDirectories 2020/05/15 05:24:20 DEBUG : sub dir2: Making directory 2020/05/15 05:24:20 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for checks to finish 2020/05/15 05:24:20 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for transfers to finish 2020/05/15 05:24:21 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2020/05/15 05:24:21 INFO : sub dir/hello world: Copied (new) 2020/05/15 05:24:21 INFO : sub dir/hello world: Deleted 2020/05/15 05:24:21 DEBUG : sub dir: Making directory 2020/05/15 05:24:21 DEBUG : sub dir2: Making directory 2020/05/15 05:24:21 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: copied 2 directories --- FAIL: TestMoveEmptyDirectories (8.26s) run.go:176: Remote "B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1", Local "Local file system at /tmp/rclone533931174", Modify Window "1ms" fstest.go:247: Filtering empty directory "sub dir2" 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 sync_test.go:208 Error: Should be true Test: TestMoveEmptyDirectories Messages: listing wrong, want sub dir/hello world (11) got fstest.go:200: Not found "sub dir/hello world" fstest.go:203: Error Trace: fstest.go:203 fstest.go:307 fstest.go:335 sync_test.go:208 Error: Not equal: expected: 0 actual : 1 Test: TestMoveEmptyDirectories Messages: 1 objects not found fstest.go:324: Error Trace: fstest.go:324 fstest.go:335 sync_test.go:208 Error: Not equal: expected: []string{"sub dir"} actual : []string{} Diff: --- Expected +++ Actual @@ -1,3 +1,2 @@ -([]string) (len=1) { - (string) (len=7) "sub dir" +([]string) { } Test: TestMoveEmptyDirectories Messages: directories === RUN TestSyncEmptyDirectories 2020/05/15 05:24:29 DEBUG : sub dir2: Making directory 2020/05/15 05:24:29 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for checks to finish 2020/05/15 05:24:29 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for transfers to finish 2020/05/15 05:24:29 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2020/05/15 05:24:29 INFO : sub dir/hello world: Copied (new) 2020/05/15 05:24:29 DEBUG : sub dir2: Making directory 2020/05/15 05:24:29 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: copied 1 directories 2020/05/15 05:24:29 DEBUG : Waiting for deletions to finish --- PASS: TestSyncEmptyDirectories (1.72s) run.go:176: Remote "B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1", Local "Local file system at /tmp/rclone533931174", Modify Window "1ms" fstest.go:247: Filtering empty directory "sub dir2" === RUN TestServerSideCopy 2020/05/15 05:24:39 DEBUG : B2 bucket rclone-test-bevobaz4cijehig0rawoyof6: Waiting for checks to finish 2020/05/15 05:24:39 DEBUG : B2 bucket rclone-test-bevobaz4cijehig0rawoyof6: Waiting for transfers to finish 2020/05/15 05:24:46 DEBUG : B2 bucket rclone-test-bevobaz4cijehig0rawoyof6: Purge remote 2020/05/15 05:24:47 purge failed: directory not found --- FAIL: TestServerSideCopy (16.76s) run.go:176: Remote "B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1", Local "Local file system at /tmp/rclone533931174", 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:253 Error: Should be true Test: TestServerSideCopy Messages: listing wrong, want sub dir/hello world (11) got fstest.go:200: Not found "sub dir/hello world" fstest.go:203: Error Trace: fstest.go:203 fstest.go:307 fstest.go:335 fstest.go:347 sync_test.go:253 Error: Not equal: expected: 0 actual : 1 Test: TestServerSideCopy Messages: 1 objects not found sync_test.go:258: Server side copy (if possible) B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1 -> B2 bucket rclone-test-bevobaz4cijehig0rawoyof6 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:263 Error: Should be true Test: TestServerSideCopy Messages: listing wrong, want sub dir/hello world (11) got fstest.go:200: Not found "sub dir/hello world" fstest.go:203: Error Trace: fstest.go:203 fstest.go:307 fstest.go:335 fstest.go:347 sync_test.go:263 Error: Not equal: expected: 0 actual : 1 Test: TestServerSideCopy Messages: 1 objects not found === RUN TestCopyAfterDelete 2020/05/15 05:24:48 ERROR : : error listing: directory not found 2020/05/15 05:24:48 DEBUG : Local file system at /tmp/rclone533931174: Making directory 2020/05/15 05:24:48 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for checks to finish 2020/05/15 05:24:48 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for transfers to finish --- PASS: TestCopyAfterDelete (8.19s) run.go:176: Remote "B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1", Local "Local file system at /tmp/rclone533931174", Modify Window "1ms" === RUN TestCopyRedownload 2020/05/15 05:24:57 DEBUG : Local file system at /tmp/rclone533931174: Waiting for checks to finish 2020/05/15 05:24:57 DEBUG : Local file system at /tmp/rclone533931174: Waiting for transfers to finish 2020/05/15 05:24:58 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2020/05/15 05:24:58 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyRedownload (3.38s) run.go:176: Remote "B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1", Local "Local file system at /tmp/rclone533931174", Modify Window "1ms" === RUN TestSyncBasedOnCheckSum 2020/05/15 05:24:59 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for checks to finish 2020/05/15 05:24:59 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for transfers to finish 2020/05/15 05:25:00 DEBUG : check sum: SHA-1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2020/05/15 05:25:00 INFO : check sum: Copied (new) 2020/05/15 05:25:00 DEBUG : Waiting for deletions to finish 2020/05/15 05:25:01 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for checks to finish 2020/05/15 05:25:01 DEBUG : check sum: SHA-1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2020/05/15 05:25:01 DEBUG : check sum: Size and SHA-1 of src and dst objects identical 2020/05/15 05:25:01 DEBUG : check sum: Unchanged skipping 2020/05/15 05:25:01 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for transfers to finish 2020/05/15 05:25:01 DEBUG : Waiting for deletions to finish 2020/05/15 05:25:01 INFO : There was nothing to transfer --- PASS: TestSyncBasedOnCheckSum (2.92s) run.go:176: Remote "B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1", Local "Local file system at /tmp/rclone533931174", Modify Window "1ms" === RUN TestSyncSizeOnly 2020/05/15 05:25:02 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for checks to finish 2020/05/15 05:25:02 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for transfers to finish 2020/05/15 05:25:02 DEBUG : sizeonly: SHA-1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2020/05/15 05:25:02 INFO : sizeonly: Copied (new) 2020/05/15 05:25:02 DEBUG : Waiting for deletions to finish 2020/05/15 05:25:03 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for checks to finish 2020/05/15 05:25:03 DEBUG : sizeonly: Sizes identical 2020/05/15 05:25:03 DEBUG : sizeonly: Unchanged skipping 2020/05/15 05:25:03 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for transfers to finish 2020/05/15 05:25:03 DEBUG : Waiting for deletions to finish 2020/05/15 05:25:03 INFO : There was nothing to transfer --- PASS: TestSyncSizeOnly (6.87s) run.go:176: Remote "B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1", Local "Local file system at /tmp/rclone533931174", Modify Window "1ms" === RUN TestSyncIgnoreSize 2020/05/15 05:25:09 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for checks to finish 2020/05/15 05:25:09 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for transfers to finish 2020/05/15 05:25:10 DEBUG : ignore-size: SHA-1 = 4a756ca07e9487f482465a99e8286abc86ba4dc7 OK 2020/05/15 05:25:10 INFO : ignore-size: Copied (new) 2020/05/15 05:25:10 DEBUG : Waiting for deletions to finish 2020/05/15 05:25:10 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for checks to finish 2020/05/15 05:25:10 DEBUG : ignore-size: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2020/05/15 05:25:10 DEBUG : ignore-size: Unchanged skipping 2020/05/15 05:25:10 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for transfers to finish 2020/05/15 05:25:10 DEBUG : Waiting for deletions to finish 2020/05/15 05:25:10 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreSize (2.76s) run.go:176: Remote "B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1", Local "Local file system at /tmp/rclone533931174", Modify Window "1ms" === RUN TestSyncIgnoreTimes 2020/05/15 05:25:12 DEBUG : existing: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2020/05/15 05:25:12 DEBUG : existing: Unchanged skipping 2020/05/15 05:25:12 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for checks to finish 2020/05/15 05:25:12 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for transfers to finish 2020/05/15 05:25:12 DEBUG : Waiting for deletions to finish 2020/05/15 05:25:12 INFO : There was nothing to transfer 2020/05/15 05:25:12 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2020/05/15 05:25:12 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for checks to finish 2020/05/15 05:25:12 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for transfers to finish 2020/05/15 05:25:13 DEBUG : existing: SHA-1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2020/05/15 05:25:13 INFO : existing: Copied (replaced existing) 2020/05/15 05:25:13 DEBUG : Waiting for deletions to finish --- PASS: TestSyncIgnoreTimes (2.85s) run.go:176: Remote "B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1", Local "Local file system at /tmp/rclone533931174", Modify Window "1ms" === RUN TestSyncIgnoreExisting 2020/05/15 05:25:14 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for checks to finish 2020/05/15 05:25:14 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for transfers to finish 2020/05/15 05:25:15 DEBUG : existing: SHA-1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2020/05/15 05:25:15 INFO : existing: Copied (new) 2020/05/15 05:25:15 DEBUG : Waiting for deletions to finish 2020/05/15 05:25:15 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for checks to finish 2020/05/15 05:25:15 DEBUG : existing: Destination exists, skipping 2020/05/15 05:25:15 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for transfers to finish 2020/05/15 05:25:15 DEBUG : Waiting for deletions to finish 2020/05/15 05:25:15 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreExisting (1.81s) run.go:176: Remote "B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1", Local "Local file system at /tmp/rclone533931174", Modify Window "1ms" === RUN TestSyncIgnoreErrors 2020/05/15 05:25:17 DEBUG : d: Making directory 2020/05/15 05:25:18 DEBUG : c/non empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/05/15 05:25:18 DEBUG : c/non empty space: Unchanged skipping 2020/05/15 05:25:18 DEBUG : a/potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2020/05/15 05:25:18 INFO : a/potato2: Copied (new) 2020/05/15 05:25:18 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for checks to finish 2020/05/15 05:25:18 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for transfers to finish 2020/05/15 05:25:18 DEBUG : Waiting for deletions to finish 2020/05/15 05:25:18 INFO : b/potato: Deleted --- PASS: TestSyncIgnoreErrors (4.58s) run.go:176: Remote "B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1", Local "Local file system at /tmp/rclone533931174", Modify Window "1ms" fstest.go:247: Filtering empty directory "d" === RUN TestSyncAfterChangingModtimeOnly 2020/05/15 05:25:27 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for checks to finish 2020/05/15 05:25:27 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/05/15 05:25:27 DEBUG : empty space: SHA-1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2020/05/15 05:25:27 NOTICE: empty space: Not updating modification time as --dry-run 2020/05/15 05:25:27 DEBUG : empty space: Unchanged skipping 2020/05/15 05:25:27 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for transfers to finish 2020/05/15 05:25:27 DEBUG : Waiting for deletions to finish 2020/05/15 05:25:27 INFO : There was nothing to transfer 2020/05/15 05:25:27 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for checks to finish 2020/05/15 05:25:27 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/05/15 05:25:27 DEBUG : empty space: SHA-1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2020/05/15 05:25:28 INFO : empty space: Updated modification time in destination 2020/05/15 05:25:28 DEBUG : empty space: Unchanged skipping 2020/05/15 05:25:28 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for transfers to finish 2020/05/15 05:25:28 DEBUG : Waiting for deletions to finish 2020/05/15 05:25:28 INFO : There was nothing to transfer --- PASS: TestSyncAfterChangingModtimeOnly (8.41s) run.go:176: Remote "B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1", Local "Local file system at /tmp/rclone533931174", Modify Window "1ms" === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime 2020/05/15 05:25:29 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for checks to finish 2020/05/15 05:25:29 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/05/15 05:25:29 DEBUG : empty space: SHA-1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2020/05/15 05:25:29 DEBUG : empty space: Unchanged skipping 2020/05/15 05:25:29 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for transfers to finish 2020/05/15 05:25:29 DEBUG : Waiting for deletions to finish 2020/05/15 05:25:29 INFO : There was nothing to transfer --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (4.67s) run.go:176: Remote "B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1", Local "Local file system at /tmp/rclone533931174", Modify Window "1ms" === RUN TestSyncDoesntUpdateModtime 2020/05/15 05:25:34 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for checks to finish 2020/05/15 05:25:34 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/05/15 05:25:34 DEBUG : foo: SHA-1 = 0beec7b5ea3f0fdbc95d0dd47f3c5bc275da8a33 (Local file system at /tmp/rclone533931174) 2020/05/15 05:25:34 DEBUG : foo: SHA-1 = 62cdb7020ff920e5aa642c3d4066950dd1f01f4d (B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1) 2020/05/15 05:25:34 DEBUG : foo: SHA-1 differ 2020/05/15 05:25:34 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for transfers to finish 2020/05/15 05:25:35 DEBUG : foo: SHA-1 = 0beec7b5ea3f0fdbc95d0dd47f3c5bc275da8a33 OK 2020/05/15 05:25:35 INFO : foo: Copied (replaced existing) 2020/05/15 05:25:35 DEBUG : Waiting for deletions to finish --- FAIL: TestSyncDoesntUpdateModtime (2.69s) run.go:176: Remote "B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1", Local "Local file system at /tmp/rclone533931174", Modify Window "1ms" fstest.go:140: Error Trace: fstest.go:140 fstest.go:146 fstest.go:192 fstest.go:305 fstest.go:335 fstest.go:347 sync_test.go:600 Error: Should be true Test: TestSyncDoesntUpdateModtime Messages: B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1/foo: SHA-1 hash incorrect - expecting "0beec7b5ea3f0fdbc95d0dd47f3c5bc275da8a33" got "62cdb7020ff920e5aa642c3d4066950dd1f01f4d" fstest.go:124: Error Trace: fstest.go:124 fstest.go:129 fstest.go:148 fstest.go:192 fstest.go:305 fstest.go:335 fstest.go:347 sync_test.go:600 Error: Should be true Test: TestSyncDoesntUpdateModtime Messages: foo: Modification time difference too big |95456h54m52.624456789s| > 1ms (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2001-02-03 04:05:06.499 +0000 UTC) (precision 1ms) === RUN TestSyncAfterAddingAFile 2020/05/15 05:25:37 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/05/15 05:25:37 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for checks to finish 2020/05/15 05:25:37 DEBUG : empty space: Unchanged skipping 2020/05/15 05:25:37 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for transfers to finish 2020/05/15 05:25:38 DEBUG : potato: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2020/05/15 05:25:38 INFO : potato: Copied (new) 2020/05/15 05:25:38 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterAddingAFile (3.57s) run.go:176: Remote "B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1", Local "Local file system at /tmp/rclone533931174", Modify Window "1ms" === RUN TestSyncAfterChangingFilesSizeOnly 2020/05/15 05:25:40 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for checks to finish 2020/05/15 05:25:40 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2020/05/15 05:25:40 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for transfers to finish 2020/05/15 05:25:41 DEBUG : potato: SHA-1 = 0fd321a1023ac4e1e1054fcc0236b9d0755f722f OK 2020/05/15 05:25:41 INFO : potato: Copied (replaced existing) 2020/05/15 05:25:41 DEBUG : Waiting for deletions to finish --- FAIL: TestSyncAfterChangingFilesSizeOnly (9.02s) run.go:176: Remote "B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1", Local "Local file system at /tmp/rclone533931174", 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:634 Error: Should be true Test: TestSyncAfterChangingFilesSizeOnly Messages: listing wrong, want potato (21) got potato (60) fstest.go:140: Error Trace: fstest.go:140 fstest.go:146 fstest.go:192 fstest.go:305 fstest.go:335 fstest.go:347 sync_test.go:634 Error: Should be true Test: TestSyncAfterChangingFilesSizeOnly Messages: B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1/potato: SHA-1 hash incorrect - expecting "0fd321a1023ac4e1e1054fcc0236b9d0755f722f" got "9dc7f7d3279715991a22853f5981df582b7f9f6d" fstest.go:147: Error Trace: fstest.go:147 fstest.go:192 fstest.go:305 fstest.go:335 fstest.go:347 sync_test.go:634 Error: Not equal: expected: 21 actual : 60 Test: TestSyncAfterChangingFilesSizeOnly Messages: potato: size incorrect file=21 vs obj=60 === RUN TestSyncAfterChangingContentsOnly 2020/05/15 05:25:52 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for checks to finish 2020/05/15 05:25:52 DEBUG : potato: Modification times differ by 119h59m59.876543211s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2011-12-30 12:59:59 +0000 UTC 2020/05/15 05:25:52 DEBUG : potato: SHA-1 = 1ca5094b3b73f587249eabe179c40a3eec839b1f (Local file system at /tmp/rclone533931174) 2020/05/15 05:25:52 DEBUG : potato: SHA-1 = 0fd321a1023ac4e1e1054fcc0236b9d0755f722f (B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1) 2020/05/15 05:25:52 DEBUG : potato: SHA-1 differ 2020/05/15 05:25:52 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for transfers to finish 2020/05/15 05:25:53 DEBUG : potato: SHA-1 = 1ca5094b3b73f587249eabe179c40a3eec839b1f OK 2020/05/15 05:25:53 INFO : potato: Copied (replaced existing) 2020/05/15 05:25:53 DEBUG : Waiting for deletions to finish --- FAIL: TestSyncAfterChangingContentsOnly (12.49s) run.go:176: Remote "B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1", Local "Local file system at /tmp/rclone533931174", 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 run.go:169 run.go:339 sync_test.go:658 Error: Should be true Test: TestSyncAfterChangingContentsOnly Messages: listing wrong, want got potato (21) fstest.go:188: Error Trace: fstest.go:188 fstest.go:305 fstest.go:335 run.go:169 run.go:339 sync_test.go:658 Error: Should be true Test: TestSyncAfterChangingContentsOnly Messages: Unexpected file "potato" === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun 2020/05/15 05:26:03 NOTICE: potato2: Not copying as --dry-run 2020/05/15 05:26:03 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for checks to finish 2020/05/15 05:26:03 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/05/15 05:26:03 DEBUG : empty space: Unchanged skipping 2020/05/15 05:26:03 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for transfers to finish 2020/05/15 05:26:03 DEBUG : Waiting for deletions to finish 2020/05/15 05:26:03 NOTICE: potato: Not deleting as --dry-run --- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (5.65s) run.go:176: Remote "B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1", Local "Local file system at /tmp/rclone533931174", Modify Window "1ms" === RUN TestSyncAfterRemovingAFileAndAddingAFile 2020/05/15 05:26:08 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for checks to finish 2020/05/15 05:26:08 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/05/15 05:26:08 DEBUG : empty space: Unchanged skipping 2020/05/15 05:26:08 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for transfers to finish 2020/05/15 05:26:09 DEBUG : potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2020/05/15 05:26:09 INFO : potato2: Copied (new) 2020/05/15 05:26:09 DEBUG : Waiting for deletions to finish 2020/05/15 05:26:11 INFO : potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (8.34s) run.go:176: Remote "B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1", Local "Local file system at /tmp/rclone533931174", Modify Window "1ms" === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir 2020/05/15 05:26:18 DEBUG : d: Making directory 2020/05/15 05:26:18 DEBUG : d/e: Making directory 2020/05/15 05:26:18 DEBUG : c/non empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/05/15 05:26:18 DEBUG : c/non empty space: Unchanged skipping 2020/05/15 05:26:18 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for checks to finish 2020/05/15 05:26:18 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for transfers to finish 2020/05/15 05:26:19 DEBUG : a/potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2020/05/15 05:26:19 INFO : a/potato2: Copied (new) 2020/05/15 05:26:19 DEBUG : Waiting for deletions to finish 2020/05/15 05:26:19 INFO : b/potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (5.74s) run.go:176: Remote "B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1", Local "Local file system at /tmp/rclone533931174", Modify Window "1ms" fstest.go:247: Filtering empty directory "d" fstest.go:247: Filtering empty directory "d/e" === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors 2020/05/15 05:26:22 DEBUG : d: Making directory 2020/05/15 05:26:22 DEBUG : c/non empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/05/15 05:26:22 DEBUG : c/non empty space: Unchanged skipping 2020/05/15 05:26:22 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for checks to finish 2020/05/15 05:26:22 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for transfers to finish 2020/05/15 05:26:23 DEBUG : a/potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2020/05/15 05:26:23 INFO : a/potato2: Copied (new) 2020/05/15 05:26:23 ERROR : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: not deleting files as there were IO errors 2020/05/15 05:26:23 ERROR : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: not deleting directories as there were IO errors --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (3.87s) run.go:176: Remote "B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1", Local "Local file system at /tmp/rclone533931174", Modify Window "1ms" fstest.go:247: Filtering empty directory "d" fstest.go:247: Filtering empty directory "d" === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring 2020/05/15 05:26:26 DEBUG : Waiting for deletions to finish 2020/05/15 05:26:26 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for checks to finish 2020/05/15 05:26:26 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/05/15 05:26:26 DEBUG : empty space: Unchanged skipping 2020/05/15 05:26:26 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for transfers to finish 2020/05/15 05:26:26 INFO : potato: Deleted 2020/05/15 05:26:27 DEBUG : potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2020/05/15 05:26:27 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteDuring (3.53s) run.go:176: Remote "B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1", Local "Local file system at /tmp/rclone533931174", Modify Window "1ms" === RUN TestSyncDeleteBefore 2020/05/15 05:26:29 DEBUG : Waiting for deletions to finish 2020/05/15 05:26:29 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for checks to finish 2020/05/15 05:26:29 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for transfers to finish 2020/05/15 05:26:30 INFO : potato: Deleted 2020/05/15 05:26:30 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for checks to finish 2020/05/15 05:26:30 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/05/15 05:26:30 DEBUG : empty space: Unchanged skipping 2020/05/15 05:26:30 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for transfers to finish 2020/05/15 05:26:30 DEBUG : potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2020/05/15 05:26:30 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteBefore (3.42s) run.go:176: Remote "B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1", Local "Local file system at /tmp/rclone533931174", Modify Window "1ms" === RUN TestCopyDeleteBefore 2020/05/15 05:26:32 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for checks to finish 2020/05/15 05:26:32 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for transfers to finish 2020/05/15 05:26:33 DEBUG : potato2: SHA-1 = 810274f0df19dab60a894a562b7a8ff04c072623 OK 2020/05/15 05:26:33 INFO : potato2: Copied (new) --- PASS: TestCopyDeleteBefore (1.99s) run.go:176: Remote "B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1", Local "Local file system at /tmp/rclone533931174", Modify Window "1ms" === RUN TestSyncWithExclude 2020/05/15 05:26:35 DEBUG : potato2: Excluded 2020/05/15 05:26:35 DEBUG : enormous: Excluded 2020/05/15 05:26:35 DEBUG : potato2: Excluded 2020/05/15 05:26:35 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/05/15 05:26:35 DEBUG : empty space: Unchanged skipping 2020/05/15 05:26:35 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for checks to finish 2020/05/15 05:26:35 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for transfers to finish 2020/05/15 05:26:35 DEBUG : Waiting for deletions to finish 2020/05/15 05:26:35 INFO : There was nothing to transfer 2020/05/15 05:26:35 DEBUG : potato2: Excluded 2020/05/15 05:26:35 DEBUG : enormous: Excluded 2020/05/15 05:26:35 DEBUG : potato2: Excluded 2020/05/15 05:26:35 DEBUG : empty space: Size and modification time the same (differ by 456.789µs, within tolerance 1ms) 2020/05/15 05:26:35 DEBUG : empty space: Unchanged skipping 2020/05/15 05:26:35 DEBUG : Local file system at /tmp/rclone533931174: Waiting for checks to finish 2020/05/15 05:26:35 DEBUG : Local file system at /tmp/rclone533931174: Waiting for transfers to finish 2020/05/15 05:26:35 DEBUG : Waiting for deletions to finish 2020/05/15 05:26:35 INFO : There was nothing to transfer --- PASS: TestSyncWithExclude (2.90s) run.go:176: Remote "B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1", Local "Local file system at /tmp/rclone533931174", Modify Window "1ms" === RUN TestSyncWithExcludeAndDeleteExcluded 2020/05/15 05:26:40 DEBUG : potato2: Excluded 2020/05/15 05:26:40 DEBUG : enormous: Excluded 2020/05/15 05:26:40 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/05/15 05:26:40 DEBUG : empty space: Unchanged skipping 2020/05/15 05:26:40 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for checks to finish 2020/05/15 05:26:40 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for transfers to finish 2020/05/15 05:26:40 DEBUG : Waiting for deletions to finish 2020/05/15 05:26:40 INFO : enormous: Deleted 2020/05/15 05:26:41 INFO : potato2: Deleted 2020/05/15 05:26:41 INFO : There was nothing to transfer 2020/05/15 05:26:41 DEBUG : empty space: Size and modification time the same (differ by 456.789µs, within tolerance 1ms) 2020/05/15 05:26:41 DEBUG : empty space: Unchanged skipping 2020/05/15 05:26:41 DEBUG : Local file system at /tmp/rclone533931174: Waiting for checks to finish 2020/05/15 05:26:41 DEBUG : Local file system at /tmp/rclone533931174: Waiting for transfers to finish 2020/05/15 05:26:41 DEBUG : Waiting for deletions to finish 2020/05/15 05:26:41 INFO : enormous: Deleted 2020/05/15 05:26:41 INFO : potato2: Deleted 2020/05/15 05:26:41 INFO : There was nothing to transfer --- PASS: TestSyncWithExcludeAndDeleteExcluded (5.07s) run.go:176: Remote "B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1", Local "Local file system at /tmp/rclone533931174", Modify Window "1ms" === RUN TestSyncWithUpdateOlder 2020/05/15 05:26:44 DEBUG : four: Sizes differ (src 4 vs dst 8) 2020/05/15 05:26:44 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2020/05/15 05:26:44 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for checks to finish 2020/05/15 05:26:44 DEBUG : three: Sizes identical 2020/05/15 05:26:44 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2020/05/15 05:26:44 DEBUG : one: Destination is newer than source, skipping 2020/05/15 05:26:44 DEBUG : two: SHA-1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b (Local file system at /tmp/rclone533931174) 2020/05/15 05:26:44 DEBUG : two: SHA-1 = b10e0da85e90399abfec36c7376b20aa4596461b (B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1) 2020/05/15 05:26:44 DEBUG : two: SHA-1 differ 2020/05/15 05:26:44 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for transfers to finish 2020/05/15 05:26:45 DEBUG : four: SHA-1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2020/05/15 05:26:45 INFO : four: Copied (replaced existing) 2020/05/15 05:26:45 DEBUG : two: SHA-1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2020/05/15 05:26:45 INFO : two: Copied (replaced existing) 2020/05/15 05:26:46 DEBUG : five: SHA-1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2020/05/15 05:26:46 INFO : five: Copied (new) 2020/05/15 05:26:46 DEBUG : Waiting for deletions to finish 2020/05/15 05:26:46 DEBUG : one: Destination is newer than source, skipping 2020/05/15 05:26:46 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for checks to finish 2020/05/15 05:26:46 DEBUG : three: SHA-1 = b802f384302cb24fbab0a44997e820bf2e8507bb (Local file system at /tmp/rclone533931174) 2020/05/15 05:26:46 DEBUG : three: SHA-1 = 03731cad13f4cf7b59703c77529381cdec1632e5 (B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1) 2020/05/15 05:26:46 DEBUG : three: SHA-1 differ 2020/05/15 05:26:46 DEBUG : two: SHA-1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2020/05/15 05:26:46 DEBUG : two: Size and SHA-1 of src and dst objects identical 2020/05/15 05:26:46 DEBUG : two: Destination mod time is within 1s of source and files identical, skipping 2020/05/15 05:26:46 DEBUG : three: Destination mod time is within 1s of source but files differ, transferring 2020/05/15 05:26:46 DEBUG : four: SHA-1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2020/05/15 05:26:46 DEBUG : four: Size and SHA-1 of src and dst objects identical 2020/05/15 05:26:46 DEBUG : four: Destination mod time is within 1s of source and files identical, skipping 2020/05/15 05:26:46 DEBUG : five: SHA-1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2020/05/15 05:26:46 DEBUG : five: Size and SHA-1 of src and dst objects identical 2020/05/15 05:26:46 DEBUG : five: Destination mod time is within 1s of source and files identical, skipping 2020/05/15 05:26:46 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for transfers to finish 2020/05/15 05:26:46 DEBUG : three: SHA-1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2020/05/15 05:26:46 INFO : three: Copied (replaced existing) 2020/05/15 05:26:46 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithUpdateOlder (12.98s) run.go:176: Remote "B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1", Local "Local file system at /tmp/rclone533931174", Modify Window "1ms" === RUN TestSyncWithMaxDuration --- SKIP: TestSyncWithMaxDuration (0.00s) sync_test.go:997: Skipping test on non local remote === RUN TestSyncWithTrackRenames 2020/05/15 05:26:55 INFO : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Making map for --track-renames 2020/05/15 05:26:55 INFO : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Finished making map for --track-renames 2020/05/15 05:26:55 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for checks to finish 2020/05/15 05:26:55 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for renames to finish 2020/05/15 05:26:55 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for transfers to finish 2020/05/15 05:26:56 DEBUG : yam: SHA-1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2020/05/15 05:26:56 INFO : yam: Copied (new) 2020/05/15 05:26:56 DEBUG : potato: SHA-1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2020/05/15 05:26:56 INFO : potato: Copied (new) 2020/05/15 05:26:56 DEBUG : Waiting for deletions to finish 2020/05/15 05:26:56 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2020/05/15 05:26:56 DEBUG : potato: Unchanged skipping 2020/05/15 05:26:56 INFO : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Making map for --track-renames 2020/05/15 05:26:56 INFO : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Finished making map for --track-renames 2020/05/15 05:26:56 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for checks to finish 2020/05/15 05:26:56 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for renames to finish 2020/05/15 05:26:57 DEBUG : yam: SHA-1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2020/05/15 05:26:57 INFO : yam: Copied (server side copy) 2020/05/15 05:26:58 INFO : yam: Deleted 2020/05/15 05:26:58 INFO : yaml: Renamed from "yam" 2020/05/15 05:26:58 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for transfers to finish 2020/05/15 05:26:58 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenames (4.95s) run.go:176: Remote "B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1", Local "Local file system at /tmp/rclone533931174", Modify Window "1ms" sync_test.go:1051: Can track renames: true === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime 2020/05/15 05:27:00 INFO : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Making map for --track-renames 2020/05/15 05:27:00 INFO : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Finished making map for --track-renames 2020/05/15 05:27:00 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for checks to finish 2020/05/15 05:27:00 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for renames to finish 2020/05/15 05:27:00 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for transfers to finish 2020/05/15 05:27:01 DEBUG : yam: SHA-1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2020/05/15 05:27:01 INFO : yam: Copied (new) 2020/05/15 05:27:01 DEBUG : potato: SHA-1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2020/05/15 05:27:01 INFO : potato: Copied (new) 2020/05/15 05:27:01 DEBUG : Waiting for deletions to finish 2020/05/15 05:27:01 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2020/05/15 05:27:01 DEBUG : potato: Unchanged skipping 2020/05/15 05:27:01 INFO : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Making map for --track-renames 2020/05/15 05:27:01 INFO : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Finished making map for --track-renames 2020/05/15 05:27:01 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for checks to finish 2020/05/15 05:27:01 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for renames to finish 2020/05/15 05:27:02 DEBUG : yam: SHA-1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2020/05/15 05:27:02 INFO : yam: Copied (server side copy) 2020/05/15 05:27:02 INFO : yam: Deleted 2020/05/15 05:27:02 INFO : yaml: Renamed from "yam" 2020/05/15 05:27:02 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for transfers to finish 2020/05/15 05:27:02 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenamesStrategyModtime (6.77s) run.go:176: Remote "B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1", Local "Local file system at /tmp/rclone533931174", Modify Window "1ms" sync_test.go:1122: Can track renames: true === RUN TestMoveWithDeleteEmptySrcDirs 2020/05/15 05:27:07 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for checks to finish 2020/05/15 05:27:07 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for transfers to finish 2020/05/15 05:27:07 DEBUG : nested/sub dir/file: SHA-1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK 2020/05/15 05:27:07 INFO : nested/sub dir/file: Copied (new) 2020/05/15 05:27:07 INFO : nested/sub dir/file: Deleted 2020/05/15 05:27:08 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2020/05/15 05:27:08 INFO : sub dir/hello world: Copied (new) 2020/05/15 05:27:08 INFO : sub dir/hello world: Deleted 2020/05/15 05:27:08 DEBUG : sub dir: Removing directory 2020/05/15 05:27:08 DEBUG : nested/sub dir: Removing directory 2020/05/15 05:27:08 DEBUG : nested: Removing directory 2020/05/15 05:27:08 DEBUG : Local file system at /tmp/rclone533931174: deleted 3 directories --- PASS: TestMoveWithDeleteEmptySrcDirs (3.28s) run.go:176: Remote "B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1", Local "Local file system at /tmp/rclone533931174", Modify Window "1ms" === RUN TestMoveWithoutDeleteEmptySrcDirs 2020/05/15 05:27:10 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for checks to finish 2020/05/15 05:27:10 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for transfers to finish 2020/05/15 05:27:10 DEBUG : nested/sub dir/file: SHA-1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK 2020/05/15 05:27:10 INFO : nested/sub dir/file: Copied (new) 2020/05/15 05:27:10 INFO : nested/sub dir/file: Deleted 2020/05/15 05:27:11 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2020/05/15 05:27:11 INFO : sub dir/hello world: Copied (new) 2020/05/15 05:27:11 INFO : sub dir/hello world: Deleted --- PASS: TestMoveWithoutDeleteEmptySrcDirs (2.62s) run.go:176: Remote "B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1", Local "Local file system at /tmp/rclone533931174", Modify Window "1ms" === RUN TestServerSideMove 2020/05/15 05:27:19 DEBUG : B2 bucket rclone-test-yuseyar7tuxakac6vovupot0: Waiting for checks to finish 2020/05/15 05:27:19 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms) 2020/05/15 05:27:19 DEBUG : empty space: Unchanged skipping 2020/05/15 05:27:19 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2020/05/15 05:27:19 DEBUG : potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2020/05/15 05:27:19 INFO : potato2: Copied (server side copy) 2020/05/15 05:27:20 DEBUG : potato3: SHA-1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2020/05/15 05:27:20 INFO : potato3: Copied (server side copy) 2020/05/15 05:27:20 INFO : potato2: Deleted 2020/05/15 05:27:20 INFO : empty space: Deleted 2020/05/15 05:27:20 DEBUG : B2 bucket rclone-test-yuseyar7tuxakac6vovupot0: Waiting for transfers to finish 2020/05/15 05:27:21 INFO : potato3: Deleted 2020/05/15 05:27:22 DEBUG : B2 bucket rclone-test-pagovew0jaceley9koyufed6: Waiting for checks to finish 2020/05/15 05:27:22 DEBUG : B2 bucket rclone-test-pagovew0jaceley9koyufed6: Waiting for transfers to finish 2020/05/15 05:27:22 DEBUG : potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2020/05/15 05:27:22 INFO : potato2: Copied (server side copy) 2020/05/15 05:27:23 DEBUG : potato3: SHA-1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2020/05/15 05:27:23 INFO : potato3: Copied (server side copy) 2020/05/15 05:27:23 DEBUG : empty space: SHA-1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2020/05/15 05:27:23 INFO : empty space: Copied (server side copy) 2020/05/15 05:27:23 INFO : potato3: Deleted 2020/05/15 05:27:23 INFO : potato2: Deleted 2020/05/15 05:27:24 INFO : empty space: Deleted 2020/05/15 05:27:24 DEBUG : B2 bucket rclone-test-pagovew0jaceley9koyufed6: Purge remote 2020/05/15 05:27:24 DEBUG : empty space: Deleting (id "4_z5ff22d33c0dd1b6b7f280e1b_f111df2d1e9fa45bd_d20200515_m052722_c001_v0001137_t0004") 2020/05/15 05:27:24 DEBUG : potato2: Deleting (id "4_z5ff22d33c0dd1b6b7f280e1b_f118ba4ea56942ee3_d20200515_m052722_c001_v0001128_t0002") 2020/05/15 05:27:24 DEBUG : potato3: Deleting (id "4_z5ff22d33c0dd1b6b7f280e1b_f113eb3a44feaef7e_d20200515_m052722_c001_v0001023_t0037") 2020/05/15 05:27:25 DEBUG : B2 bucket rclone-test-yuseyar7tuxakac6vovupot0: Purge remote 2020/05/15 05:27:25 DEBUG : empty space: Deleting (id "4_zffa20dd3c0dd1b6b7f280e1b_f110f800716403bf9_d20200515_m052723_c001_v0001137_t0029") 2020/05/15 05:27:25 DEBUG : empty space: Deleting (id "4_zffa20dd3c0dd1b6b7f280e1b_f10290d61e949d0e5_d20200515_m052717_c001_v0001136_t0020") 2020/05/15 05:27:25 DEBUG : potato2: Deleting (id "4_zffa20dd3c0dd1b6b7f280e1b_f10408141a8ddc108_d20200515_m052722_c001_v0001137_t0002") 2020/05/15 05:27:25 DEBUG : potato2: Deleting (id "4_zffa20dd3c0dd1b6b7f280e1b_f1115ac86854c9806_d20200515_m052719_c001_v0001130_t0054") 2020/05/15 05:27:25 DEBUG : potato3: Deleting (id "4_zffa20dd3c0dd1b6b7f280e1b_f1139f9ffaa9bbe04_d20200515_m052723_c001_v0001017_t0040") 2020/05/15 05:27:25 DEBUG : potato3: Deleting (id "4_zffa20dd3c0dd1b6b7f280e1b_f1185e6560a6829fc_d20200515_m052719_c001_v0001044_t0039") 2020/05/15 05:27:25 DEBUG : potato3: Deleting (id "4_zffa20dd3c0dd1b6b7f280e1b_f10290d61e949d0f4_d20200515_m052718_c001_v0001136_t0020") --- PASS: TestServerSideMove (13.71s) run.go:176: Remote "B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1", Local "Local file system at /tmp/rclone533931174", Modify Window "1ms" sync_test.go:1174: Server side move (if possible) B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1 -> B2 bucket rclone-test-yuseyar7tuxakac6vovupot0 === RUN TestServerSideMoveWithFilter 2020/05/15 05:27:32 DEBUG : empty space: Excluded 2020/05/15 05:27:33 DEBUG : empty space: Excluded 2020/05/15 05:27:33 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2020/05/15 05:27:33 DEBUG : B2 bucket rclone-test-lejacif5namubun3movowaj8: Waiting for checks to finish 2020/05/15 05:27:33 DEBUG : B2 bucket rclone-test-lejacif5namubun3movowaj8: Waiting for transfers to finish 2020/05/15 05:27:33 DEBUG : potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2020/05/15 05:27:33 INFO : potato2: Copied (server side copy) 2020/05/15 05:27:33 DEBUG : potato3: SHA-1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2020/05/15 05:27:33 INFO : potato3: Copied (server side copy) 2020/05/15 05:27:34 INFO : potato2: Deleted 2020/05/15 05:27:34 INFO : potato3: Deleted 2020/05/15 05:27:35 DEBUG : empty space: Excluded 2020/05/15 05:27:35 DEBUG : B2 bucket rclone-test-gayesed2wivofur6ferijor1: Waiting for checks to finish 2020/05/15 05:27:35 DEBUG : B2 bucket rclone-test-gayesed2wivofur6ferijor1: Waiting for transfers to finish 2020/05/15 05:27:36 DEBUG : potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2020/05/15 05:27:36 INFO : potato2: Copied (server side copy) 2020/05/15 05:27:37 DEBUG : potato3: SHA-1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2020/05/15 05:27:37 INFO : potato3: Copied (server side copy) 2020/05/15 05:27:37 INFO : potato2: Deleted 2020/05/15 05:27:37 INFO : potato3: Deleted 2020/05/15 05:27:37 DEBUG : B2 bucket rclone-test-gayesed2wivofur6ferijor1: Purge remote 2020/05/15 05:27:38 DEBUG : potato2: Deleting (id "4_zafb25d73c0dd1b6b7f280e1b_f104d3eb04fbc1ef8_d20200515_m052735_c001_v0001136_t0006") 2020/05/15 05:27:38 DEBUG : potato3: Deleting (id "4_zafb25d73c0dd1b6b7f280e1b_f11359625ccbcc59d_d20200515_m052736_c001_v0001136_t0021") 2020/05/15 05:27:38 DEBUG : B2 bucket rclone-test-lejacif5namubun3movowaj8: Purge remote 2020/05/15 05:27:38 DEBUG : empty space: Deleting (id "4_zcf224d53c0dd1b6b7f280e1b_f1100d665a6514886_d20200515_m052732_c001_v0001131_t0040") 2020/05/15 05:27:38 DEBUG : potato2: Deleting (id "4_zcf224d53c0dd1b6b7f280e1b_f117d99dd6d73926f_d20200515_m052736_c001_v0001137_t0054") 2020/05/15 05:27:38 DEBUG : potato2: Deleting (id "4_zcf224d53c0dd1b6b7f280e1b_f10290d61e949d21c_d20200515_m052733_c001_v0001136_t0045") 2020/05/15 05:27:38 DEBUG : potato3: Deleting (id "4_zcf224d53c0dd1b6b7f280e1b_f1002796a825fed93_d20200515_m052737_c001_v0001134_t0000") 2020/05/15 05:27:38 DEBUG : potato3: Deleting (id "4_zcf224d53c0dd1b6b7f280e1b_f1157ba13d9486c73_d20200515_m052733_c001_v0001136_t0009") 2020/05/15 05:27:38 DEBUG : potato3: Deleting (id "4_zcf224d53c0dd1b6b7f280e1b_f1100d665a651488a_d20200515_m052732_c001_v0001131_t0040") --- PASS: TestServerSideMoveWithFilter (13.59s) run.go:176: Remote "B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1", Local "Local file system at /tmp/rclone533931174", Modify Window "1ms" sync_test.go:1174: Server side move (if possible) B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1 -> B2 bucket rclone-test-lejacif5namubun3movowaj8 === RUN TestServerSideMoveDeleteEmptySourceDirs 2020/05/15 05:27:44 DEBUG : tomatoDir: Making directory 2020/05/15 05:27:48 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms) 2020/05/15 05:27:48 DEBUG : empty space: Unchanged skipping 2020/05/15 05:27:48 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2020/05/15 05:27:48 DEBUG : B2 bucket rclone-test-huyeyaf0denuzof6pesifap0: Waiting for checks to finish 2020/05/15 05:27:49 DEBUG : potato3: SHA-1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2020/05/15 05:27:49 INFO : potato3: Copied (server side copy) 2020/05/15 05:27:49 INFO : empty space: Deleted 2020/05/15 05:27:49 DEBUG : B2 bucket rclone-test-huyeyaf0denuzof6pesifap0: Waiting for transfers to finish 2020/05/15 05:27:49 INFO : potato3: Deleted 2020/05/15 05:27:49 DEBUG : potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2020/05/15 05:27:49 INFO : potato2: Copied (server side copy) 2020/05/15 05:27:50 INFO : potato2: Deleted 2020/05/15 05:27:51 DEBUG : tomatoDir: Making directory 2020/05/15 05:27:51 DEBUG : B2 bucket rclone-test-hiwulid3jumefob6gepubag3: Waiting for checks to finish 2020/05/15 05:27:51 DEBUG : B2 bucket rclone-test-hiwulid3jumefob6gepubag3: Waiting for transfers to finish 2020/05/15 05:27:52 DEBUG : potato3: SHA-1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2020/05/15 05:27:52 INFO : potato3: Copied (server side copy) 2020/05/15 05:27:53 DEBUG : potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2020/05/15 05:27:53 INFO : potato2: Copied (server side copy) 2020/05/15 05:27:53 DEBUG : empty space: SHA-1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2020/05/15 05:27:53 INFO : empty space: Copied (server side copy) 2020/05/15 05:27:53 INFO : potato2: Deleted 2020/05/15 05:27:53 INFO : empty space: Deleted 2020/05/15 05:27:53 INFO : potato3: Deleted 2020/05/15 05:27:54 DEBUG : B2 bucket rclone-test-hiwulid3jumefob6gepubag3: Purge remote 2020/05/15 05:27:54 DEBUG : empty space: Deleting (id "4_zbff29d73c0dd1b6b7f280e1b_f114295255b6c6290_d20200515_m052752_c001_v0001134_t0054") 2020/05/15 05:27:54 DEBUG : potato2: Deleting (id "4_zbff29d73c0dd1b6b7f280e1b_f1029d67fc2a586ce_d20200515_m052752_c001_v0001090_t0025") 2020/05/15 05:27:54 DEBUG : potato3: Deleting (id "4_zbff29d73c0dd1b6b7f280e1b_f10888b12a85fcfdb_d20200515_m052752_c001_v0001137_t0050") 2020/05/15 05:27:54 DEBUG : B2 bucket rclone-test-huyeyaf0denuzof6pesifap0: Purge remote 2020/05/15 05:27:55 DEBUG : empty space: Deleting (id "4_z8ff27d93c0dd1b6b7f280e1b_f10761dcb4e94bc1d_d20200515_m052753_c001_v0001134_t0016") 2020/05/15 05:27:55 DEBUG : empty space: Deleting (id "4_z8ff27d93c0dd1b6b7f280e1b_f117d99dd6d739342_d20200515_m052745_c001_v0001137_t0001") 2020/05/15 05:27:55 DEBUG : potato2: Deleting (id "4_z8ff27d93c0dd1b6b7f280e1b_f1184605a38e6d2c7_d20200515_m052753_c001_v0001090_t0005") 2020/05/15 05:27:55 DEBUG : potato2: Deleting (id "4_z8ff27d93c0dd1b6b7f280e1b_f101a5b5fadeb9fb8_d20200515_m052748_c001_v0001137_t0008") 2020/05/15 05:27:55 DEBUG : potato3: Deleting (id "4_z8ff27d93c0dd1b6b7f280e1b_f114768723e07600d_d20200515_m052752_c001_v0001137_t0039") 2020/05/15 05:27:55 DEBUG : potato3: Deleting (id "4_z8ff27d93c0dd1b6b7f280e1b_f1002796a825fee97_d20200515_m052748_c001_v0001134_t0027") 2020/05/15 05:27:55 DEBUG : potato3: Deleting (id "4_z8ff27d93c0dd1b6b7f280e1b_f117d99dd6d73937e_d20200515_m052747_c001_v0001137_t0001") --- PASS: TestServerSideMoveDeleteEmptySourceDirs (15.80s) run.go:176: Remote "B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1", Local "Local file system at /tmp/rclone533931174", Modify Window "1ms" sync_test.go:1174: Server side move (if possible) B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1 -> B2 bucket rclone-test-huyeyaf0denuzof6pesifap0 === RUN TestServerSideMoveOverlap --- PASS: TestServerSideMoveOverlap (2.60s) run.go:176: Remote "B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1", Local "Local file system at /tmp/rclone533931174", Modify Window "1ms" === RUN TestSyncOverlap --- PASS: TestSyncOverlap (0.77s) run.go:176: Remote "B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1", Local "Local file system at /tmp/rclone533931174", Modify Window "1ms" === RUN TestSyncCompareDest 2020/05/15 05:28:00 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1 path dst: Waiting for checks to finish 2020/05/15 05:28:00 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1 path dst: Waiting for transfers to finish 2020/05/15 05:28:02 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2020/05/15 05:28:02 INFO : one: Copied (new) 2020/05/15 05:28:02 DEBUG : Waiting for deletions to finish 2020/05/15 05:28:02 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1 path dst: Waiting for checks to finish 2020/05/15 05:28:02 DEBUG : one: Sizes differ (src 5 vs dst 3) 2020/05/15 05:28:02 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1 path dst: Waiting for transfers to finish 2020/05/15 05:28:03 DEBUG : one: SHA-1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2020/05/15 05:28:03 INFO : one: Copied (replaced existing) 2020/05/15 05:28:03 DEBUG : Waiting for deletions to finish 2020/05/15 05:28:06 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1 path dst: Waiting for checks to finish 2020/05/15 05:28:06 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/05/15 05:28:06 DEBUG : one: Destination found in --compare-dest, skipping 2020/05/15 05:28:06 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1 path dst: Waiting for transfers to finish 2020/05/15 05:28:06 DEBUG : Waiting for deletions to finish 2020/05/15 05:28:06 INFO : There was nothing to transfer 2020/05/15 05:28:07 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/05/15 05:28:07 DEBUG : two: Destination found in --compare-dest, skipping 2020/05/15 05:28:07 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1 path dst: Waiting for checks to finish 2020/05/15 05:28:07 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/05/15 05:28:07 DEBUG : one: Destination found in --compare-dest, skipping 2020/05/15 05:28:07 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1 path dst: Waiting for transfers to finish 2020/05/15 05:28:07 DEBUG : Waiting for deletions to finish 2020/05/15 05:28:07 INFO : There was nothing to transfer 2020/05/15 05:28:08 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/05/15 05:28:08 DEBUG : two: Destination found in --compare-dest, skipping 2020/05/15 05:28:08 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1 path dst: Waiting for checks to finish 2020/05/15 05:28:08 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/05/15 05:28:08 DEBUG : one: Destination found in --compare-dest, skipping 2020/05/15 05:28:08 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1 path dst: Waiting for transfers to finish 2020/05/15 05:28:08 DEBUG : Waiting for deletions to finish 2020/05/15 05:28:08 INFO : There was nothing to transfer 2020/05/15 05:28:09 DEBUG : two: Sizes differ (src 5 vs dst 3) 2020/05/15 05:28:09 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1 path dst: Waiting for checks to finish 2020/05/15 05:28:09 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/05/15 05:28:09 DEBUG : one: Destination found in --compare-dest, skipping 2020/05/15 05:28:09 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1 path dst: Waiting for transfers to finish 2020/05/15 05:28:10 DEBUG : two: SHA-1 = a4be8877ad88f3109a6411985a1f359e10e2d930 OK 2020/05/15 05:28:10 INFO : two: Copied (new) 2020/05/15 05:28:10 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCompareDest (14.50s) run.go:176: Remote "B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1", Local "Local file system at /tmp/rclone533931174", Modify Window "1ms" === RUN TestSyncCopyDest 2020/05/15 05:28:15 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1 path dst: Waiting for checks to finish 2020/05/15 05:28:15 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1 path dst: Waiting for transfers to finish 2020/05/15 05:28:17 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2020/05/15 05:28:17 INFO : one: Copied (new) 2020/05/15 05:28:17 DEBUG : Waiting for deletions to finish 2020/05/15 05:28:17 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1 path dst: Waiting for checks to finish 2020/05/15 05:28:17 DEBUG : one: Sizes differ (src 5 vs dst 3) 2020/05/15 05:28:17 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1 path dst: Waiting for transfers to finish 2020/05/15 05:28:18 DEBUG : one: SHA-1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2020/05/15 05:28:18 INFO : one: Copied (replaced existing) 2020/05/15 05:28:18 DEBUG : Waiting for deletions to finish 2020/05/15 05:28:21 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1 path dst: Waiting for checks to finish 2020/05/15 05:28:21 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/05/15 05:28:21 DEBUG : one: Sizes differ (src 5 vs dst 3) 2020/05/15 05:28:22 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2020/05/15 05:28:22 INFO : one: Copied (server side copy) 2020/05/15 05:28:23 INFO : one: Deleted 2020/05/15 05:28:23 DEBUG : one: SHA-1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2020/05/15 05:28:23 INFO : one: Copied (server side copy) 2020/05/15 05:28:23 DEBUG : one: Destination found in --copy-dest, using server side copy 2020/05/15 05:28:23 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1 path dst: Waiting for transfers to finish 2020/05/15 05:28:23 DEBUG : Waiting for deletions to finish 2020/05/15 05:28:25 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/05/15 05:28:25 DEBUG : two: SHA-1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2020/05/15 05:28:25 INFO : two: Copied (server side copy) 2020/05/15 05:28:25 DEBUG : two: Destination found in --copy-dest, using server side copy 2020/05/15 05:28:25 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1 path dst: Waiting for checks to finish 2020/05/15 05:28:25 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/05/15 05:28:25 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/05/15 05:28:25 DEBUG : one: Unchanged skipping 2020/05/15 05:28:25 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1 path dst: Waiting for transfers to finish 2020/05/15 05:28:25 DEBUG : Waiting for deletions to finish 2020/05/15 05:28:26 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1 path dst: Waiting for checks to finish 2020/05/15 05:28:26 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/05/15 05:28:26 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/05/15 05:28:26 DEBUG : one: Unchanged skipping 2020/05/15 05:28:26 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/05/15 05:28:26 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/05/15 05:28:26 DEBUG : two: Unchanged skipping 2020/05/15 05:28:26 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1 path dst: Waiting for transfers to finish 2020/05/15 05:28:26 DEBUG : Waiting for deletions to finish 2020/05/15 05:28:26 INFO : There was nothing to transfer 2020/05/15 05:28:28 DEBUG : three: Sizes differ (src 7 vs dst 5) 2020/05/15 05:28:28 DEBUG : three: Destination not found in --copy-dest 2020/05/15 05:28:28 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1 path dst: Waiting for checks to finish 2020/05/15 05:28:28 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/05/15 05:28:28 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/05/15 05:28:28 DEBUG : one: Unchanged skipping 2020/05/15 05:28:28 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/05/15 05:28:28 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/05/15 05:28:28 DEBUG : two: Unchanged skipping 2020/05/15 05:28:28 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1 path dst: Waiting for transfers to finish 2020/05/15 05:28:29 DEBUG : three: SHA-1 = 370e613335f3cf2f19b2a2d35a86402f55c659f8 OK 2020/05/15 05:28:29 INFO : three: Copied (new) 2020/05/15 05:28:29 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCopyDest (18.82s) run.go:176: Remote "B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1", Local "Local file system at /tmp/rclone533931174", Modify Window "1ms" === RUN TestSyncBackupDir 2020/05/15 05:28:37 DEBUG : one: Sizes differ (src 4 vs dst 3) 2020/05/15 05:28:37 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2020/05/15 05:28:37 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1 path dst: Waiting for checks to finish 2020/05/15 05:28:37 DEBUG : two: Unchanged skipping 2020/05/15 05:28:38 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2020/05/15 05:28:38 INFO : one: Copied (server side copy) 2020/05/15 05:28:38 INFO : one: Deleted 2020/05/15 05:28:38 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1 path dst: Waiting for transfers to finish 2020/05/15 05:28:40 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2020/05/15 05:28:40 INFO : one: Copied (new) 2020/05/15 05:28:40 DEBUG : Waiting for deletions to finish 2020/05/15 05:28:40 DEBUG : three.txt: SHA-1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2020/05/15 05:28:40 INFO : three.txt: Copied (server side copy) 2020/05/15 05:28:41 INFO : three.txt: Deleted 2020/05/15 05:28:41 INFO : three.txt: Moved into backup dir 2020/05/15 05:28:42 DEBUG : one: Sizes differ (src 5 vs dst 4) 2020/05/15 05:28:42 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1 path dst: Waiting for checks to finish 2020/05/15 05:28:42 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2020/05/15 05:28:42 DEBUG : two: Unchanged skipping 2020/05/15 05:28:43 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2020/05/15 05:28:43 INFO : one: Copied (server side copy) 2020/05/15 05:28:44 INFO : one: Deleted 2020/05/15 05:28:44 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1 path dst: Waiting for transfers to finish 2020/05/15 05:28:44 DEBUG : one: SHA-1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2020/05/15 05:28:44 INFO : one: Copied (new) 2020/05/15 05:28:44 DEBUG : Waiting for deletions to finish 2020/05/15 05:28:50 DEBUG : three.txt: SHA-1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2020/05/15 05:28:50 INFO : three.txt: Copied (server side copy) 2020/05/15 05:28:51 INFO : three.txt: Deleted 2020/05/15 05:28:51 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (21.46s) run.go:176: Remote "B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1", Local "Local file system at /tmp/rclone533931174", Modify Window "1ms" === RUN TestSyncBackupDirWithSuffix 2020/05/15 05:28:58 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2020/05/15 05:28:58 DEBUG : two: Unchanged skipping 2020/05/15 05:28:58 DEBUG : one: Sizes differ (src 4 vs dst 3) 2020/05/15 05:28:58 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1 path dst: Waiting for checks to finish 2020/05/15 05:28:59 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2020/05/15 05:28:59 INFO : one: Copied (server side copy) 2020/05/15 05:28:59 INFO : one: Deleted 2020/05/15 05:28:59 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1 path dst: Waiting for transfers to finish 2020/05/15 05:29:00 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2020/05/15 05:29:00 INFO : one: Copied (new) 2020/05/15 05:29:00 DEBUG : Waiting for deletions to finish 2020/05/15 05:29:01 DEBUG : three.txt: SHA-1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2020/05/15 05:29:01 INFO : three.txt: Copied (server side copy) 2020/05/15 05:29:02 INFO : three.txt: Deleted 2020/05/15 05:29:02 INFO : three.txt: Moved into backup dir 2020/05/15 05:29:03 DEBUG : one: Sizes differ (src 5 vs dst 4) 2020/05/15 05:29:03 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2020/05/15 05:29:03 DEBUG : two: Unchanged skipping 2020/05/15 05:29:03 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1 path dst: Waiting for checks to finish 2020/05/15 05:29:04 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2020/05/15 05:29:04 INFO : one: Copied (server side copy) 2020/05/15 05:29:04 INFO : one: Deleted 2020/05/15 05:29:04 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1 path dst: Waiting for transfers to finish 2020/05/15 05:29:05 DEBUG : one: SHA-1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2020/05/15 05:29:05 INFO : one: Copied (new) 2020/05/15 05:29:05 DEBUG : Waiting for deletions to finish 2020/05/15 05:29:06 DEBUG : three.txt: SHA-1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2020/05/15 05:29:06 INFO : three.txt: Copied (server side copy) 2020/05/15 05:29:07 INFO : three.txt: Deleted 2020/05/15 05:29:07 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (17.44s) run.go:176: Remote "B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1", Local "Local file system at /tmp/rclone533931174", Modify Window "1ms" === RUN TestSyncBackupDirWithSuffixKeepExtension 2020/05/15 05:29:16 DEBUG : one: Sizes differ (src 4 vs dst 3) 2020/05/15 05:29:16 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1 path dst: Waiting for checks to finish 2020/05/15 05:29:16 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2020/05/15 05:29:16 DEBUG : two: Unchanged skipping 2020/05/15 05:29:17 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2020/05/15 05:29:17 INFO : one: Copied (server side copy) 2020/05/15 05:29:18 INFO : one: Deleted 2020/05/15 05:29:18 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1 path dst: Waiting for transfers to finish 2020/05/15 05:29:20 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2020/05/15 05:29:20 INFO : one: Copied (new) 2020/05/15 05:29:20 DEBUG : Waiting for deletions to finish 2020/05/15 05:29:21 DEBUG : three.txt: SHA-1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2020/05/15 05:29:21 INFO : three.txt: Copied (server side copy) 2020/05/15 05:29:21 INFO : three.txt: Deleted 2020/05/15 05:29:21 INFO : three.txt: Moved into backup dir 2020/05/15 05:29:23 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1 path dst: Waiting for checks to finish 2020/05/15 05:29:23 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2020/05/15 05:29:23 DEBUG : two: Unchanged skipping 2020/05/15 05:29:23 DEBUG : one: Sizes differ (src 5 vs dst 4) 2020/05/15 05:29:24 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2020/05/15 05:29:24 INFO : one: Copied (server side copy) 2020/05/15 05:29:26 INFO : one: Deleted 2020/05/15 05:29:26 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1 path dst: Waiting for transfers to finish 2020/05/15 05:29:27 DEBUG : one: SHA-1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2020/05/15 05:29:27 INFO : one: Copied (new) 2020/05/15 05:29:27 DEBUG : Waiting for deletions to finish 2020/05/15 05:29:28 DEBUG : three.txt: SHA-1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2020/05/15 05:29:28 INFO : three.txt: Copied (server side copy) 2020/05/15 05:29:29 INFO : three.txt: Deleted 2020/05/15 05:29:29 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (21.71s) run.go:176: Remote "B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1", Local "Local file system at /tmp/rclone533931174", Modify Window "1ms" === RUN TestSyncSuffix 2020/05/15 05:29:43 DEBUG : one: Sizes differ (src 4 vs dst 3) 2020/05/15 05:29:44 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2020/05/15 05:29:44 INFO : one: Copied (server side copy) 2020/05/15 05:29:44 INFO : one: Deleted 2020/05/15 05:29:45 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2020/05/15 05:29:45 INFO : one: Copied (new) 2020/05/15 05:29:46 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2020/05/15 05:29:46 DEBUG : two: Unchanged skipping 2020/05/15 05:29:46 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2020/05/15 05:29:47 DEBUG : three.txt: SHA-1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2020/05/15 05:29:47 INFO : three.txt: Copied (server side copy) 2020/05/15 05:29:48 INFO : three.txt: Deleted 2020/05/15 05:29:48 DEBUG : three.txt: SHA-1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2020/05/15 05:29:48 INFO : three.txt: Copied (new) 2020/05/15 05:29:49 DEBUG : one: Sizes differ (src 5 vs dst 4) 2020/05/15 05:29:49 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2020/05/15 05:29:49 INFO : one: Copied (server side copy) 2020/05/15 05:29:50 INFO : one: Deleted 2020/05/15 05:29:51 DEBUG : one: SHA-1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2020/05/15 05:29:51 INFO : one: Copied (new) 2020/05/15 05:29:51 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2020/05/15 05:29:51 DEBUG : two: Unchanged skipping 2020/05/15 05:29:51 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2020/05/15 05:29:54 DEBUG : three.txt: SHA-1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2020/05/15 05:29:54 INFO : three.txt: Copied (server side copy) 2020/05/15 05:29:55 INFO : three.txt: Deleted 2020/05/15 05:29:55 DEBUG : three.txt: SHA-1 = 476e1707cbd7f0497a7dc4d44f0b07b8566ffc30 OK 2020/05/15 05:29:55 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (26.46s) run.go:176: Remote "B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1", Local "Local file system at /tmp/rclone533931174", Modify Window "1ms" === RUN TestSyncSuffixKeepExtension 2020/05/15 05:30:05 DEBUG : one: Sizes differ (src 4 vs dst 3) 2020/05/15 05:30:05 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2020/05/15 05:30:05 INFO : one: Copied (server side copy) 2020/05/15 05:30:06 INFO : one: Deleted 2020/05/15 05:30:07 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2020/05/15 05:30:07 INFO : one: Copied (new) 2020/05/15 05:30:07 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2020/05/15 05:30:07 DEBUG : two: Unchanged skipping 2020/05/15 05:30:07 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2020/05/15 05:30:08 DEBUG : three.txt: SHA-1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2020/05/15 05:30:08 INFO : three.txt: Copied (server side copy) 2020/05/15 05:30:09 INFO : three.txt: Deleted 2020/05/15 05:30:09 DEBUG : three.txt: SHA-1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2020/05/15 05:30:09 INFO : three.txt: Copied (new) 2020/05/15 05:30:10 DEBUG : one: Sizes differ (src 5 vs dst 4) 2020/05/15 05:30:11 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2020/05/15 05:30:11 INFO : one: Copied (server side copy) 2020/05/15 05:30:11 INFO : one: Deleted 2020/05/15 05:30:12 DEBUG : one: SHA-1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2020/05/15 05:30:12 INFO : one: Copied (new) 2020/05/15 05:30:12 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2020/05/15 05:30:12 DEBUG : two: Unchanged skipping 2020/05/15 05:30:12 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2020/05/15 05:30:14 DEBUG : three.txt: SHA-1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2020/05/15 05:30:14 INFO : three.txt: Copied (server side copy) 2020/05/15 05:30:15 INFO : three.txt: Deleted 2020/05/15 05:30:15 DEBUG : three.txt: SHA-1 = 476e1707cbd7f0497a7dc4d44f0b07b8566ffc30 OK 2020/05/15 05:30:15 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (18.80s) run.go:176: Remote "B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1", Local "Local file system at /tmp/rclone533931174", Modify Window "1ms" === RUN TestSyncUTFNorm 2020/05/15 05:30:19 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2020/05/15 05:30:19 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for checks to finish 2020/05/15 05:30:19 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for transfers to finish 2020/05/15 05:30:20 DEBUG : Testêé: SHA-1 = a54d88e06612d820bc3be72877c74f257b561b19 OK 2020/05/15 05:30:20 INFO : Testêé: Copied (replaced existing) 2020/05/15 05:30:20 DEBUG : Waiting for deletions to finish --- PASS: TestSyncUTFNorm (3.56s) run.go:176: Remote "B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1", Local "Local file system at /tmp/rclone533931174", Modify Window "1ms" === RUN TestSyncImmutable 2020/05/15 05:30:22 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for checks to finish 2020/05/15 05:30:22 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for transfers to finish 2020/05/15 05:30:23 DEBUG : existing: SHA-1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2020/05/15 05:30:23 INFO : existing: Copied (new) 2020/05/15 05:30:23 DEBUG : Waiting for deletions to finish 2020/05/15 05:30:24 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2020/05/15 05:30:24 ERROR : existing: Source and destination exist but do not match: immutable file modified 2020/05/15 05:30:24 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for checks to finish 2020/05/15 05:30:24 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for transfers to finish 2020/05/15 05:30:24 ERROR : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: not deleting files as there were IO errors 2020/05/15 05:30:24 ERROR : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: not deleting directories as there were IO errors 2020/05/15 05:30:24 INFO : There was nothing to transfer --- PASS: TestSyncImmutable (2.84s) run.go:176: Remote "B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1", Local "Local file system at /tmp/rclone533931174", Modify Window "1ms" === RUN TestSyncIgnoreCase 2020/05/15 05:30:26 DEBUG : existing: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2020/05/15 05:30:26 DEBUG : existing: Unchanged skipping 2020/05/15 05:30:26 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for checks to finish 2020/05/15 05:30:26 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Waiting for transfers to finish 2020/05/15 05:30:26 DEBUG : Waiting for deletions to finish 2020/05/15 05:30:26 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreCase (3.64s) run.go:176: Remote "B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1", Local "Local file system at /tmp/rclone533931174", Modify Window "1ms" === RUN TestAbort --- SKIP: TestAbort (0.29s) run.go:176: Remote "B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1", Local "Local file system at /tmp/rclone533931174", Modify Window "1ms" sync_test.go:1807: This test only runs on local FAIL 2020/05/15 05:30:28 DEBUG : B2 bucket rclone-test-jutehiv3kuwaluy0sulariq1: Purge remote 2020/05/15 05:30:29 DEBUG : BackupDir/one: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f10888b12a85fd481_d20200515_m052829_c001_v0001137_t0039") 2020/05/15 05:30:29 DEBUG : BackupDir/one: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f11652f094a919984_d20200515_m052822_c001_v0001136_t0027") 2020/05/15 05:30:29 DEBUG : CompareDest/one: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f11840c3dce85d130_d20200515_m052810_c001_v0001137_t0019") 2020/05/15 05:30:29 DEBUG : CompareDest/one: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b609a0_d20200515_m052805_c001_v0001113_t0016") 2020/05/15 05:30:29 DEBUG : CompareDest/two: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f111c120e7512206a_d20200515_m052811_c001_v0001136_t0018") 2020/05/15 05:30:29 DEBUG : CompareDest/two: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f11141cf9af5c95b5_d20200515_m052806_c001_v0001134_t0011") 2020/05/15 05:30:29 DEBUG : CopyDest/one: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f1143ea350e21b458_d20200515_m052830_c001_v0001092_t0004") 2020/05/15 05:30:29 DEBUG : CopyDest/one: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b609d2_d20200515_m052820_c001_v0001113_t0016") 2020/05/15 05:30:29 DEBUG : CopyDest/three: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f1064e3ef154b4264_d20200515_m052830_c001_v0001043_t0004") 2020/05/15 05:30:29 DEBUG : CopyDest/three: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f10408141a8ddc9d4_d20200515_m052827_c001_v0001137_t0004") 2020/05/15 05:30:29 DEBUG : CopyDest/two: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f1016ddf78d0a83d1_d20200515_m052830_c001_v0001038_t0028") 2020/05/15 05:30:29 DEBUG : CopyDest/two: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f11141cf9af5c973b_d20200515_m052824_c001_v0001134_t0011") 2020/05/15 05:30:29 DEBUG : EXISTING: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f107a69824daf64b1_d20200515_m053026_c001_v0001044_t0048") 2020/05/15 05:30:29 DEBUG : EXISTING: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b60af4_d20200515_m053025_c001_v0001113_t0016") 2020/05/15 05:30:29 DEBUG : Testêé: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f118278fdab4e5d71_d20200515_m053021_c001_v0001134_t0017") 2020/05/15 05:30:29 DEBUG : Testêé: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f11141cf9af5ca255_d20200515_m053020_c001_v0001134_t0011") 2020/05/15 05:30:30 DEBUG : Testêé: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b60ade_d20200515_m053018_c001_v0001113_t0016") 2020/05/15 05:30:30 DEBUG : a/potato2: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f112c319c17eb2a42_d20200515_m052623_c001_v0001136_t0003") 2020/05/15 05:30:30 DEBUG : a/potato2: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b6089b_d20200515_m052622_c001_v0001113_t0016") 2020/05/15 05:30:30 DEBUG : a/potato2: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f1104d2a00277c2bf_d20200515_m052620_c001_v0001001_t0040") 2020/05/15 05:30:30 DEBUG : a/potato2: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b6088d_d20200515_m052618_c001_v0001113_t0016") 2020/05/15 05:30:30 DEBUG : a/potato2: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f104fbbd7f4969a91_d20200515_m052519_c001_v0001131_t0042") 2020/05/15 05:30:30 DEBUG : a/potato2: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b607fb_d20200515_m052517_c001_v0001113_t0016") 2020/05/15 05:30:30 DEBUG : b/potato: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f105ebcc7e97af6e0_d20200515_m052624_c001_v0001137_t0006") 2020/05/15 05:30:30 DEBUG : b/potato: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b60897_d20200515_m052621_c001_v0001113_t0016") 2020/05/15 05:30:30 DEBUG : b/potato: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f117d99dd6d7388e8_d20200515_m052619_c001_v0001137_t0050") 2020/05/15 05:30:30 DEBUG : b/potato: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b60886_d20200515_m052616_c001_v0001113_t0016") 2020/05/15 05:30:30 DEBUG : b/potato: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f11141cf9af5c853a_d20200515_m052518_c001_v0001134_t0007") 2020/05/15 05:30:30 DEBUG : b/potato: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b607f6_d20200515_m052516_c001_v0001113_t0016") 2020/05/15 05:30:30 DEBUG : backup/one: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f103d0314a0cf1601_d20200515_m052851_c001_v0001137_t0037") 2020/05/15 05:30:30 DEBUG : backup/one: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f114295255b6c66ba_d20200515_m052843_c001_v0001134_t0000") 2020/05/15 05:30:30 DEBUG : backup/one: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f10004978a1ec4314_d20200515_m052837_c001_v0001128_t0055") 2020/05/15 05:30:30 DEBUG : backup/one-2019-01-01: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f11840c3dce85d8ff_d20200515_m052930_c001_v0001137_t0001") 2020/05/15 05:30:30 DEBUG : backup/one-2019-01-01: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f11990959e35901f0_d20200515_m052923_c001_v0001130_t0058") 2020/05/15 05:30:30 DEBUG : backup/one-2019-01-01: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f106e7974c59766e6_d20200515_m052917_c001_v0001137_t0048") 2020/05/15 05:30:30 DEBUG : backup/one.bak: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f118278fdab4e5547_d20200515_m052908_c001_v0001134_t0052") 2020/05/15 05:30:31 DEBUG : backup/one.bak: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f1005d6a42903e75c_d20200515_m052904_c001_v0001136_t0021") 2020/05/15 05:30:31 DEBUG : backup/one.bak: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f102b60e39f427293_d20200515_m052859_c001_v0001128_t0012") 2020/05/15 05:30:31 DEBUG : backup/three-2019-01-01.txt: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f118566074b5e7f16_d20200515_m052930_c001_v0001041_t0055") 2020/05/15 05:30:31 DEBUG : backup/three-2019-01-01.txt: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f11359625ccbcd1dc_d20200515_m052927_c001_v0001136_t0011") 2020/05/15 05:30:31 DEBUG : backup/three-2019-01-01.txt: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f116ce113c5771d00_d20200515_m052920_c001_v0001015_t0010") 2020/05/15 05:30:31 DEBUG : backup/three.txt: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f119320b248bb047a_d20200515_m052852_c001_v0001136_t0039") 2020/05/15 05:30:31 DEBUG : backup/three.txt: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f11317a31daa87cf8_d20200515_m052845_c001_v0001008_t0018") 2020/05/15 05:30:31 DEBUG : backup/three.txt: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f1017f79fa262c125_d20200515_m052840_c001_v0001134_t0045") 2020/05/15 05:30:31 DEBUG : backup/three.txt.bak: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f1023d655d1d571de_d20200515_m052909_c001_v0001137_t0036") 2020/05/15 05:30:31 DEBUG : backup/three.txt.bak: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109f2ab8bc245264_d20200515_m052905_c001_v0001009_t0018") 2020/05/15 05:30:31 DEBUG : backup/three.txt.bak: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f117d99dd6d739b7f_d20200515_m052900_c001_v0001137_t0058") 2020/05/15 05:30:31 DEBUG : c/non empty space: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f11652f094a918d19_d20200515_m052624_c001_v0001136_t0002") 2020/05/15 05:30:31 DEBUG : c/non empty space: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b60899_d20200515_m052622_c001_v0001113_t0016") 2020/05/15 05:30:31 DEBUG : c/non empty space: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f10761dcb4e94b20d_d20200515_m052620_c001_v0001134_t0057") 2020/05/15 05:30:31 DEBUG : c/non empty space: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b6088b_d20200515_m052617_c001_v0001113_t0016") 2020/05/15 05:30:31 DEBUG : c/non empty space: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109d36d209fe2454_d20200515_m052519_c001_v0001137_t0005") 2020/05/15 05:30:31 DEBUG : c/non empty space: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b607f8_d20200515_m052516_c001_v0001113_t0016") 2020/05/15 05:30:31 DEBUG : check sum: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f110f800716402b21_d20200515_m052501_c001_v0001137_t0022") 2020/05/15 05:30:31 DEBUG : check sum: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b607be_d20200515_m052459_c001_v0001113_t0016") 2020/05/15 05:30:31 DEBUG : dst/one: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f1057ff0ae810fc62_d20200515_m053015_c001_v0001041_t0015") 2020/05/15 05:30:31 DEBUG : dst/one: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f112b1d50ddb305e2_d20200515_m053012_c001_v0001130_t0038") 2020/05/15 05:30:31 DEBUG : dst/one: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f10888b12a85fe216_d20200515_m053011_c001_v0001137_t0032") 2020/05/15 05:30:31 DEBUG : dst/one: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f112b1d50ddb305c1_d20200515_m053007_c001_v0001130_t0038") 2020/05/15 05:30:32 DEBUG : dst/one: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f1137e31506be6fe2_d20200515_m053005_c001_v0001137_t0027") 2020/05/15 05:30:32 DEBUG : dst/one: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b60aae_d20200515_m053000_c001_v0001113_t0016") 2020/05/15 05:30:32 DEBUG : dst/one: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f111df2d1e9fa579b_d20200515_m052956_c001_v0001137_t0007") 2020/05/15 05:30:32 DEBUG : dst/one: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f1051639603834d83_d20200515_m052951_c001_v0001134_t0016") 2020/05/15 05:30:32 DEBUG : dst/one: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f110f800716404b8c_d20200515_m052949_c001_v0001137_t0042") 2020/05/15 05:30:32 DEBUG : dst/one: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f1051639603834cfd_d20200515_m052945_c001_v0001134_t0016") 2020/05/15 05:30:32 DEBUG : dst/one: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f102b60e39f4273d6_d20200515_m052944_c001_v0001128_t0024") 2020/05/15 05:30:32 DEBUG : dst/one: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b60a79_d20200515_m052933_c001_v0001113_t0016") 2020/05/15 05:30:32 DEBUG : dst/one: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f1023d655d1d57437_d20200515_m052931_c001_v0001137_t0046") 2020/05/15 05:30:32 DEBUG : dst/one: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f108c28cb9684db27_d20200515_m052927_c001_v0001091_t0051") 2020/05/15 05:30:32 DEBUG : dst/one: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f118278fdab4e56b3_d20200515_m052924_c001_v0001134_t0009") 2020/05/15 05:30:32 DEBUG : dst/one: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f108c28cb9684db23_d20200515_m052918_c001_v0001091_t0051") 2020/05/15 05:30:32 DEBUG : dst/one: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f112c319c17eb37f8_d20200515_m052917_c001_v0001136_t0048") 2020/05/15 05:30:32 DEBUG : dst/one: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f10408141a8ddcfb3_d20200515_m052912_c001_v0001137_t0004") 2020/05/15 05:30:32 DEBUG : dst/one: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f107d445bea68930c_d20200515_m052909_c001_v0001137_t0035") 2020/05/15 05:30:32 DEBUG : dst/one: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f1192345ec0797b9d_d20200515_m052905_c001_v0001137_t0058") 2020/05/15 05:30:32 DEBUG : dst/one: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f108ef3c3753e355b_d20200515_m052904_c001_v0001113_t0053") 2020/05/15 05:30:32 DEBUG : dst/one: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f1192345ec0797afb_d20200515_m052900_c001_v0001137_t0058") 2020/05/15 05:30:32 DEBUG : dst/one: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f107d2887825fd843_d20200515_m052859_c001_v0001136_t0036") 2020/05/15 05:30:32 DEBUG : dst/one: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f11141cf9af5c99ff_d20200515_m052854_c001_v0001134_t0011") 2020/05/15 05:30:32 DEBUG : dst/one: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f103d0314a0cf1629_d20200515_m052852_c001_v0001137_t0039") 2020/05/15 05:30:32 DEBUG : dst/one: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f1006c2c42613232c_d20200515_m052844_c001_v0001137_t0053") 2020/05/15 05:30:32 DEBUG : dst/one: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f111c120e751223cf_d20200515_m052843_c001_v0001136_t0016") 2020/05/15 05:30:33 DEBUG : dst/one: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f1006c2c4261322bb_d20200515_m052839_c001_v0001137_t0053") 2020/05/15 05:30:33 DEBUG : dst/one: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f106e7974c5976340_d20200515_m052838_c001_v0001137_t0045") 2020/05/15 05:30:33 DEBUG : dst/one: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b609f2_d20200515_m052833_c001_v0001113_t0016") 2020/05/15 05:30:33 DEBUG : dst/one: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f105eb3feae29a9a6_d20200515_m052831_c001_v0001136_t0016") 2020/05/15 05:30:33 DEBUG : dst/one: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f1035114e9dda35f7_d20200515_m052823_c001_v0001113_t0033") 2020/05/15 05:30:33 DEBUG : dst/one: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f1005d6a42903e479_d20200515_m052822_c001_v0001136_t0002") 2020/05/15 05:30:33 DEBUG : dst/one: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f10408141a8ddc8df_d20200515_m052819_c001_v0001137_t0004") 2020/05/15 05:30:33 DEBUG : dst/one: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f115a3c23d2e2db6d_d20200515_m052817_c001_v0001137_t0037") 2020/05/15 05:30:33 DEBUG : dst/one: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f115a3c23d2e2db3e_d20200515_m052816_c001_v0001137_t0037") 2020/05/15 05:30:33 DEBUG : dst/one: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f117d99dd6d739646_d20200515_m052812_c001_v0001137_t0013") 2020/05/15 05:30:33 DEBUG : dst/one: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f10408141a8ddc6be_d20200515_m052803_c001_v0001137_t0004") 2020/05/15 05:30:33 DEBUG : dst/one: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f107da37f76f1a4a8_d20200515_m052802_c001_v0001000_t0040") 2020/05/15 05:30:33 DEBUG : dst/one: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f107da37f76f1a4a5_d20200515_m052801_c001_v0001000_t0040") 2020/05/15 05:30:33 DEBUG : dst/one-2019-01-01: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f1163c2127e3dc38a_d20200515_m053016_c001_v0001137_t0052") 2020/05/15 05:30:33 DEBUG : dst/one-2019-01-01: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f103cc695188addec_d20200515_m053010_c001_v0001134_t0024") 2020/05/15 05:30:33 DEBUG : dst/one-2019-01-01: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f1036158eeb4cf68d_d20200515_m053005_c001_v0001136_t0009") 2020/05/15 05:30:33 DEBUG : dst/one.bak: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f1137e31506be6ee5_d20200515_m052957_c001_v0001137_t0049") 2020/05/15 05:30:33 DEBUG : dst/one.bak: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f11668dfd1da76235_d20200515_m052949_c001_v0001042_t0049") 2020/05/15 05:30:33 DEBUG : dst/one.bak: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f11141cf9af5c9e8a_d20200515_m052943_c001_v0001134_t0028") 2020/05/15 05:30:33 DEBUG : dst/three: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f1170c3bb24aa7902_d20200515_m052831_c001_v0001134_t0006") 2020/05/15 05:30:33 DEBUG : dst/three: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f115a3c23d2e2dc9d_d20200515_m052828_c001_v0001137_t0037") 2020/05/15 05:30:33 DEBUG : dst/three-2019-01-01.txt: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f108addb697cad2ff_d20200515_m053016_c001_v0001136_t0025") 2020/05/15 05:30:33 DEBUG : dst/three-2019-01-01.txt: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f111cb452400c2c6e_d20200515_m053013_c001_v0001008_t0036") 2020/05/15 05:30:34 DEBUG : dst/three-2019-01-01.txt: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109d36d209fe473c_d20200515_m053008_c001_v0001137_t0007") 2020/05/15 05:30:34 DEBUG : dst/three.txt: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f11141cf9af5ca1fd_d20200515_m053017_c001_v0001134_t0003") 2020/05/15 05:30:34 DEBUG : dst/three.txt: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f112b1d50ddb305f8_d20200515_m053015_c001_v0001130_t0038") 2020/05/15 05:30:34 DEBUG : dst/three.txt: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f111df2d1e9fa59bf_d20200515_m053014_c001_v0001137_t0019") 2020/05/15 05:30:34 DEBUG : dst/three.txt: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f112b1d50ddb305cf_d20200515_m053009_c001_v0001130_t0038") 2020/05/15 05:30:34 DEBUG : dst/three.txt: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f1036158eeb4cf6e2_d20200515_m053008_c001_v0001136_t0056") 2020/05/15 05:30:34 DEBUG : dst/three.txt: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f10408141a8ddd600_d20200515_m053003_c001_v0001137_t0004") 2020/05/15 05:30:34 DEBUG : dst/three.txt: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f1051639603834e4f_d20200515_m052958_c001_v0001134_t0002") 2020/05/15 05:30:34 DEBUG : dst/three.txt: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f1051639603834e06_d20200515_m052955_c001_v0001134_t0016") 2020/05/15 05:30:34 DEBUG : dst/three.txt: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f1023d655d1d57712_d20200515_m052954_c001_v0001137_t0036") 2020/05/15 05:30:34 DEBUG : dst/three.txt: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f1051639603834d53_d20200515_m052948_c001_v0001134_t0016") 2020/05/15 05:30:34 DEBUG : dst/three.txt: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f104f6580d39b7dc4_d20200515_m052947_c001_v0001008_t0015") 2020/05/15 05:30:34 DEBUG : dst/three.txt: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f10408141a8ddd345_d20200515_m052940_c001_v0001137_t0004") 2020/05/15 05:30:34 DEBUG : dst/three.txt: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f112d881787bb8c3a_d20200515_m052928_c001_v0001044_t0055") 2020/05/15 05:30:34 DEBUG : dst/three.txt: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f10408141a8ddd13a_d20200515_m052922_c001_v0001137_t0004") 2020/05/15 05:30:34 DEBUG : dst/three.txt: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f104b41606a185a71_d20200515_m052921_c001_v0001113_t0028") 2020/05/15 05:30:34 DEBUG : dst/three.txt: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f11141cf9af5c9be0_d20200515_m052914_c001_v0001134_t0011") 2020/05/15 05:30:34 DEBUG : dst/three.txt: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f1192345ec0797bc5_d20200515_m052906_c001_v0001137_t0025") 2020/05/15 05:30:34 DEBUG : dst/three.txt: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f11141cf9af5c9aae_d20200515_m052902_c001_v0001134_t0011") 2020/05/15 05:30:34 DEBUG : dst/three.txt: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f1137e31506be68d6_d20200515_m052901_c001_v0001137_t0056") 2020/05/15 05:30:34 DEBUG : dst/three.txt: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b60a27_d20200515_m052856_c001_v0001113_t0016") 2020/05/15 05:30:34 DEBUG : dst/three.txt: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f10736e18cb3866df_d20200515_m052850_c001_v0001032_t0031") 2020/05/15 05:30:34 DEBUG : dst/three.txt: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b60a0d_d20200515_m052842_c001_v0001113_t0016") 2020/05/15 05:30:35 DEBUG : dst/three.txt: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f115a3c23d2e2ddf0_d20200515_m052841_c001_v0001137_t0028") 2020/05/15 05:30:35 DEBUG : dst/three.txt: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f10408141a8ddcaeb_d20200515_m052835_c001_v0001137_t0004") 2020/05/15 05:30:35 DEBUG : dst/three.txt.bak: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f11492e768df2e270_d20200515_m052958_c001_v0001004_t0007") 2020/05/15 05:30:35 DEBUG : dst/three.txt.bak: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f1177b219035f58ce_d20200515_m052952_c001_v0001008_t0027") 2020/05/15 05:30:35 DEBUG : dst/three.txt.bak: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f1179a23084a52a95_d20200515_m052946_c001_v0001136_t0032") 2020/05/15 05:30:35 DEBUG : dst/two: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f106e28d9b88b38ca_d20200515_m053018_c001_v0001131_t0039") 2020/05/15 05:30:35 DEBUG : dst/two: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f11141cf9af5ca078_d20200515_m053002_c001_v0001134_t0011") 2020/05/15 05:30:35 DEBUG : dst/two: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f114295255b6c6d8d_d20200515_m052959_c001_v0001134_t0013") 2020/05/15 05:30:35 DEBUG : dst/two: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f11141cf9af5c9e11_d20200515_m052939_c001_v0001134_t0011") 2020/05/15 05:30:35 DEBUG : dst/two: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f116a8830745ad770_d20200515_m052932_c001_v0001134_t0032") 2020/05/15 05:30:35 DEBUG : dst/two: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b60a4c_d20200515_m052913_c001_v0001113_t0016") 2020/05/15 05:30:35 DEBUG : dst/two: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f103d0314a0cf17f8_d20200515_m052910_c001_v0001137_t0039") 2020/05/15 05:30:35 DEBUG : dst/two: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f10408141a8ddcda0_d20200515_m052855_c001_v0001137_t0004") 2020/05/15 05:30:35 DEBUG : dst/two: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f110db7203dfe25c7_d20200515_m052853_c001_v0001128_t0057") 2020/05/15 05:30:35 DEBUG : dst/two: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f11141cf9af5c9810_d20200515_m052833_c001_v0001134_t0011") 2020/05/15 05:30:35 DEBUG : dst/two: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f102c253d744daf2e_d20200515_m052832_c001_v0001131_t0042") 2020/05/15 05:30:35 DEBUG : dst/two: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f113ce853bc92243e_d20200515_m052825_c001_v0001130_t0025") 2020/05/15 05:30:35 DEBUG : dst/two: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f1179a23084a522c7_d20200515_m052813_c001_v0001136_t0036") 2020/05/15 05:30:35 DEBUG : dst/two: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f107da37f76f1a4ac_d20200515_m052809_c001_v0001000_t0040") 2020/05/15 05:30:35 DEBUG : empty space: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f1128f3a4d59a6d2c_d20200515_m052748_c001_v0001134_t0023") 2020/05/15 05:30:35 DEBUG : empty space: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f10408141a8ddc3eb_d20200515_m052741_c001_v0001137_t0004") 2020/05/15 05:30:35 DEBUG : empty space: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f10241aeb3c2c1233_d20200515_m052739_c001_v0001026_t0037") 2020/05/15 05:30:35 DEBUG : empty space: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f10408141a8ddc1d9_d20200515_m052728_c001_v0001137_t0004") 2020/05/15 05:30:36 DEBUG : empty space: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f11410145a5aebccf_d20200515_m052719_c001_v0001043_t0054") 2020/05/15 05:30:36 DEBUG : empty space: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f10408141a8ddbfe8_d20200515_m052714_c001_v0001137_t0004") 2020/05/15 05:30:36 DEBUG : empty space: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f105cc60d1a5c8842_d20200515_m052641_c001_v0001130_t0049") 2020/05/15 05:30:36 DEBUG : empty space: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b608db_d20200515_m052637_c001_v0001113_t0016") 2020/05/15 05:30:36 DEBUG : empty space: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f114cff2ee7973471_d20200515_m052635_c001_v0001136_t0047") 2020/05/15 05:30:36 DEBUG : empty space: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b608ce_d20200515_m052634_c001_v0001113_t0016") 2020/05/15 05:30:36 DEBUG : empty space: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f1090873b175fd0e1_d20200515_m052631_c001_v0001012_t0012") 2020/05/15 05:30:36 DEBUG : empty space: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b608b8_d20200515_m052629_c001_v0001113_t0016") 2020/05/15 05:30:36 DEBUG : empty space: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f10761dcb4e94b2e1_d20200515_m052627_c001_v0001134_t0055") 2020/05/15 05:30:36 DEBUG : empty space: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b608aa_d20200515_m052625_c001_v0001113_t0016") 2020/05/15 05:30:36 DEBUG : empty space: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f111c120e7512139b_d20200515_m052611_c001_v0001136_t0001") 2020/05/15 05:30:36 DEBUG : empty space: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b60874_d20200515_m052607_c001_v0001113_t0016") 2020/05/15 05:30:36 DEBUG : empty space: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f1062fb5347891872_d20200515_m052604_c001_v0001136_t0027") 2020/05/15 05:30:36 DEBUG : empty space: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b6086a_d20200515_m052602_c001_v0001113_t0016") 2020/05/15 05:30:36 DEBUG : empty space: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f105ebcc7e97af0cb_d20200515_m052538_c001_v0001137_t0016") 2020/05/15 05:30:36 DEBUG : empty space: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b60821_d20200515_m052536_c001_v0001113_t0016") 2020/05/15 05:30:36 DEBUG : empty space: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f103049d56bc807a3_d20200515_m052530_c001_v0001004_t0043") 2020/05/15 05:30:36 DEBUG : empty space: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b60811_d20200515_m052529_c001_v0001113_t0016") 2020/05/15 05:30:36 DEBUG : empty space: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f1012425f50348d7d_d20200515_m052528_c001_v0001093_t0032") 2020/05/15 05:30:37 DEBUG : empty space: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f114295255b6c557a_d20200515_m052527_c001_v0001134_t0038") 2020/05/15 05:30:37 DEBUG : empty space: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b60801_d20200515_m052520_c001_v0001113_t0016") 2020/05/15 05:30:37 DEBUG : enormous: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f117d3805c164bf84_d20200515_m052640_c001_v0001128_t0048") 2020/05/15 05:30:37 DEBUG : enormous: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b608df_d20200515_m052639_c001_v0001113_t0016") 2020/05/15 05:30:37 DEBUG : existing: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f113cd952d2df9bb4_d20200515_m053024_c001_v0001093_t0010") 2020/05/15 05:30:37 DEBUG : existing: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f10408141a8ddd8e2_d20200515_m053022_c001_v0001137_t0004") 2020/05/15 05:30:37 DEBUG : existing: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f1196b8b176bd7931_d20200515_m052515_c001_v0001120_t0004") 2020/05/15 05:30:37 DEBUG : existing: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b607f2_d20200515_m052514_c001_v0001113_t0016") 2020/05/15 05:30:37 DEBUG : existing: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f1179a23084a5139a_d20200515_m052513_c001_v0001136_t0005") 2020/05/15 05:30:37 DEBUG : existing: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b607ed_d20200515_m052512_c001_v0001113_t0016") 2020/05/15 05:30:37 DEBUG : existing: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b607e6_d20200515_m052511_c001_v0001113_t0016") 2020/05/15 05:30:37 DEBUG : five: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f11771dc72b72bf87_d20200515_m052646_c001_v0001019_t0010") 2020/05/15 05:30:37 DEBUG : five: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f10408141a8ddbbdf_d20200515_m052644_c001_v0001137_t0004") 2020/05/15 05:30:37 DEBUG : foo: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f117d99dd6d738384_d20200515_m052535_c001_v0001137_t0042") 2020/05/15 05:30:37 DEBUG : foo: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b60819_d20200515_m052534_c001_v0001113_t0016") 2020/05/15 05:30:37 DEBUG : foo: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b6081d_d20200515_m052534_c001_v0001113_t0016") 2020/05/15 05:30:37 DEBUG : four: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f112b1d50ddb301c0_d20200515_m052651_c001_v0001130_t0045") 2020/05/15 05:30:37 DEBUG : four: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b608f1_d20200515_m052644_c001_v0001113_t0016") 2020/05/15 05:30:37 DEBUG : four: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b608ef_d20200515_m052643_c001_v0001113_t0016") 2020/05/15 05:30:37 DEBUG : hello world2: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f1115ac86854c95d7_d20200515_m052414_c001_v0001130_t0044") 2020/05/15 05:30:37 DEBUG : hello world2: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b60730_d20200515_m052413_c001_v0001113_t0016") 2020/05/15 05:30:37 DEBUG : ignore-size: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f112732ffa9ab030c_d20200515_m052510_c001_v0001090_t0020") 2020/05/15 05:30:38 DEBUG : ignore-size: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b607e1_d20200515_m052509_c001_v0001113_t0016") 2020/05/15 05:30:38 DEBUG : nested/sub dir/file: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f100ee33c1fbc1e1d_d20200515_m052711_c001_v0001131_t0000") 2020/05/15 05:30:38 DEBUG : nested/sub dir/file: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f10408141a8ddbf47_d20200515_m052710_c001_v0001137_t0004") 2020/05/15 05:30:38 DEBUG : nested/sub dir/file: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f1137e31506be5bf8_d20200515_m052708_c001_v0001137_t0041") 2020/05/15 05:30:38 DEBUG : nested/sub dir/file: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f10408141a8ddbee8_d20200515_m052707_c001_v0001137_t0004") 2020/05/15 05:30:38 DEBUG : one: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f105c0fd730b2ee95_d20200515_m052652_c001_v0001043_t0022") 2020/05/15 05:30:38 DEBUG : one: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b608e8_d20200515_m052642_c001_v0001113_t0016") 2020/05/15 05:30:38 DEBUG : potato: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f100ee33c1fbc1dd6_d20200515_m052703_c001_v0001131_t0028") 2020/05/15 05:30:38 DEBUG : potato: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b6091f_d20200515_m052700_c001_v0001113_t0016") 2020/05/15 05:30:38 DEBUG : potato: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f103c7c774fa82e41_d20200515_m052658_c001_v0001128_t0027") 2020/05/15 05:30:38 DEBUG : potato: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f11141cf9af5c8f20_d20200515_m052655_c001_v0001134_t0011") 2020/05/15 05:30:38 DEBUG : potato: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f10290d61e949ccdc_d20200515_m052633_c001_v0001136_t0028") 2020/05/15 05:30:38 DEBUG : potato: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b608c1_d20200515_m052632_c001_v0001113_t0016") 2020/05/15 05:30:38 DEBUG : potato: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f111f77aece01d20c_d20200515_m052630_c001_v0001037_t0035") 2020/05/15 05:30:38 DEBUG : potato: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b608b4_d20200515_m052628_c001_v0001113_t0016") 2020/05/15 05:30:38 DEBUG : potato: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f1170c3bb24aa6a0c_d20200515_m052626_c001_v0001134_t0040") 2020/05/15 05:30:38 DEBUG : potato: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b608a4_d20200515_m052625_c001_v0001113_t0016") 2020/05/15 05:30:38 DEBUG : potato: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f101fceef9a854243_d20200515_m052609_c001_v0001136_t0023") 2020/05/15 05:30:38 DEBUG : potato: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b60872_d20200515_m052607_c001_v0001113_t0016") 2020/05/15 05:30:38 DEBUG : potato: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f114768723e075615_d20200515_m052604_c001_v0001137_t0013") 2020/05/15 05:30:38 DEBUG : potato: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b60868_d20200515_m052602_c001_v0001113_t0016") 2020/05/15 05:30:38 DEBUG : potato: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b60850_d20200515_m052553_c001_v0001113_t0016") 2020/05/15 05:30:38 DEBUG : potato: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f105163960383365f_d20200515_m052553_c001_v0001134_t0037") 2020/05/15 05:30:39 DEBUG : potato: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b60846_d20200515_m052549_c001_v0001113_t0016") 2020/05/15 05:30:39 DEBUG : potato: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f100faeac90bd6d73_d20200515_m052548_c001_v0001043_t0047") 2020/05/15 05:30:39 DEBUG : potato: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b60831_d20200515_m052540_c001_v0001113_t0016") 2020/05/15 05:30:39 DEBUG : potato: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b60836_d20200515_m052540_c001_v0001113_t0016") 2020/05/15 05:30:39 DEBUG : potato: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f1017f79fa262ae48_d20200515_m052539_c001_v0001134_t0040") 2020/05/15 05:30:39 DEBUG : potato: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b60825_d20200515_m052537_c001_v0001113_t0016") 2020/05/15 05:30:39 DEBUG : potato2: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f107d445bea688a6e_d20200515_m052757_c001_v0001137_t0008") 2020/05/15 05:30:39 DEBUG : potato2: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f11141cf9af5c94d2_d20200515_m052756_c001_v0001134_t0011") 2020/05/15 05:30:39 DEBUG : potato2: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f1057ff0ae810fbd5_d20200515_m052749_c001_v0001041_t0007") 2020/05/15 05:30:39 DEBUG : potato2: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f11141cf9af5c938c_d20200515_m052740_c001_v0001134_t0011") 2020/05/15 05:30:39 DEBUG : potato2: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f1157ba13d9486c7f_d20200515_m052733_c001_v0001136_t0023") 2020/05/15 05:30:39 DEBUG : potato2: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f11141cf9af5c923f_d20200515_m052727_c001_v0001134_t0011") 2020/05/15 05:30:39 DEBUG : potato2: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f112c319c17eb2f2f_d20200515_m052719_c001_v0001136_t0046") 2020/05/15 05:30:39 DEBUG : potato2: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f11141cf9af5c90e8_d20200515_m052714_c001_v0001134_t0011") 2020/05/15 05:30:39 DEBUG : potato2: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f114768723e075958_d20200515_m052640_c001_v0001137_t0050") 2020/05/15 05:30:39 DEBUG : potato2: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b608d7_d20200515_m052637_c001_v0001113_t0016") 2020/05/15 05:30:39 DEBUG : potato2: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f103381d69878f934_d20200515_m052636_c001_v0001036_t0046") 2020/05/15 05:30:39 DEBUG : potato2: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b608cb_d20200515_m052634_c001_v0001113_t0016") 2020/05/15 05:30:39 DEBUG : potato2: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f113f5f565df9e573_d20200515_m052633_c001_v0001005_t0033") 2020/05/15 05:30:39 DEBUG : potato2: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b608c5_d20200515_m052632_c001_v0001113_t0016") 2020/05/15 05:30:39 DEBUG : potato2: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f1122df86436384d6_d20200515_m052631_c001_v0001137_t0022") 2020/05/15 05:30:39 DEBUG : potato2: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b608bc_d20200515_m052630_c001_v0001113_t0016") 2020/05/15 05:30:39 DEBUG : potato2: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f10570ef409424304_d20200515_m052628_c001_v0001003_t0009") 2020/05/15 05:30:40 DEBUG : potato2: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b608ad_d20200515_m052626_c001_v0001113_t0016") 2020/05/15 05:30:40 DEBUG : potato2: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f117edb9218403ba9_d20200515_m052611_c001_v0001113_t0025") 2020/05/15 05:30:40 DEBUG : potato2: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b6087a_d20200515_m052608_c001_v0001113_t0016") 2020/05/15 05:30:40 DEBUG : potato2: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f104162ce4b5523a8_d20200515_m052418_c001_v0001092_t0003") 2020/05/15 05:30:40 DEBUG : potato2: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b6073c_d20200515_m052417_c001_v0001113_t0016") 2020/05/15 05:30:40 DEBUG : potato2: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f103d0314a0cef770_d20200515_m052416_c001_v0001137_t0022") 2020/05/15 05:30:40 DEBUG : potato2: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b60736_d20200515_m052415_c001_v0001113_t0016") 2020/05/15 05:30:40 DEBUG : potato3: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f10004978a1ec427d_d20200515_m052749_c001_v0001128_t0019") 2020/05/15 05:30:40 DEBUG : potato3: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b6097d_d20200515_m052742_c001_v0001113_t0016") 2020/05/15 05:30:40 DEBUG : potato3: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f101324257c766d81_d20200515_m052733_c001_v0001042_t0042") 2020/05/15 05:30:40 DEBUG : potato3: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b60960_d20200515_m052729_c001_v0001113_t0016") 2020/05/15 05:30:40 DEBUG : potato3: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f1062fb5347891e80_d20200515_m052720_c001_v0001136_t0058") 2020/05/15 05:30:40 DEBUG : potato3: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b60947_d20200515_m052715_c001_v0001113_t0016") 2020/05/15 05:30:40 DEBUG : sizeonly: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f11960b42759a420f_d20200515_m052503_c001_v0001031_t0006") 2020/05/15 05:30:40 DEBUG : sizeonly: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b607c9_d20200515_m052502_c001_v0001113_t0016") 2020/05/15 05:30:40 DEBUG : sub dir/hello world: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f10948238ab52115a_d20200515_m052712_c001_v0001134_t0051") 2020/05/15 05:30:40 DEBUG : sub dir/hello world: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b6093d_d20200515_m052710_c001_v0001113_t0016") 2020/05/15 05:30:40 DEBUG : sub dir/hello world: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f113435d251bf319c_d20200515_m052709_c001_v0001134_t0048") 2020/05/15 05:30:40 DEBUG : sub dir/hello world: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f11141cf9af5c903b_d20200515_m052707_c001_v0001134_t0011") 2020/05/15 05:30:40 DEBUG : sub dir/hello world: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f116a8830745aba1b_d20200515_m052458_c001_v0001134_t0022") 2020/05/15 05:30:40 DEBUG : sub dir/hello world: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b607af_d20200515_m052456_c001_v0001113_t0016") 2020/05/15 05:30:40 DEBUG : sub dir/hello world: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f114a518a8b38f670_d20200515_m052449_c001_v0001026_t0033") 2020/05/15 05:30:40 DEBUG : sub dir/hello world: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b6078d_d20200515_m052448_c001_v0001113_t0016") 2020/05/15 05:30:40 DEBUG : sub dir/hello world: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f111f80dadc77230c_d20200515_m052430_c001_v0001041_t0050") 2020/05/15 05:30:40 DEBUG : sub dir/hello world: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b6075c_d20200515_m052430_c001_v0001113_t0016") 2020/05/15 05:30:40 DEBUG : sub dir/hello world: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b6075a_d20200515_m052429_c001_v0001113_t0016") 2020/05/15 05:30:41 DEBUG : sub dir/hello world: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f11032f1ae98f0c86_d20200515_m052420_c001_v0001009_t0004") 2020/05/15 05:30:41 DEBUG : sub dir/hello world: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b6074b_d20200515_m052420_c001_v0001113_t0016") 2020/05/15 05:30:41 DEBUG : sub dir/hello world: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b60742_d20200515_m052418_c001_v0001113_t0016") 2020/05/15 05:30:41 DEBUG : sub dir/hello world: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f105ebcc7e97ae534_d20200515_m052412_c001_v0001137_t0057") 2020/05/15 05:30:41 DEBUG : sub dir/hello world: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b60729_d20200515_m052411_c001_v0001113_t0016") 2020/05/15 05:30:41 DEBUG : sub dir/hello world: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f118278fdab4e32d0_d20200515_m052410_c001_v0001134_t0021") 2020/05/15 05:30:41 DEBUG : sub dir/hello world: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b60721_d20200515_m052409_c001_v0001113_t0016") 2020/05/15 05:30:41 DEBUG : sub dir/hello world: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f10290d61e949c026_d20200515_m052408_c001_v0001136_t0032") 2020/05/15 05:30:41 DEBUG : sub dir/hello world: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b60719_d20200515_m052406_c001_v0001113_t0016") 2020/05/15 05:30:41 DEBUG : three: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f111c120e75121866_d20200515_m052652_c001_v0001136_t0048") 2020/05/15 05:30:41 DEBUG : three: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b608f8_d20200515_m052646_c001_v0001113_t0016") 2020/05/15 05:30:41 DEBUG : three: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b608ed_d20200515_m052643_c001_v0001113_t0016") 2020/05/15 05:30:41 DEBUG : two: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f104e972e28717287_d20200515_m052652_c001_v0001017_t0017") 2020/05/15 05:30:41 DEBUG : two: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f11141cf9af5c8e25_d20200515_m052645_c001_v0001134_t0011") 2020/05/15 05:30:41 DEBUG : two: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f109dc25532b608eb_d20200515_m052642_c001_v0001113_t0016") 2020/05/15 05:30:41 DEBUG : yam: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f1017f79fa262b61d_d20200515_m052702_c001_v0001134_t0043") 2020/05/15 05:30:41 DEBUG : yam: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f10408141a8ddbdf8_d20200515_m052700_c001_v0001137_t0004") 2020/05/15 05:30:41 DEBUG : yam: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f1159805d3556273c_d20200515_m052657_c001_v0001032_t0047") 2020/05/15 05:30:41 DEBUG : yam: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f10408141a8ddbd54_d20200515_m052655_c001_v0001137_t0004") 2020/05/15 05:30:41 DEBUG : yaml: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f119320b248baf8ab_d20200515_m052706_c001_v0001136_t0041") 2020/05/15 05:30:41 DEBUG : yaml: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f119320b248baf833_d20200515_m052701_c001_v0001136_t0011") 2020/05/15 05:30:41 DEBUG : yaml: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f105ebcc7e97afbbf_d20200515_m052659_c001_v0001137_t0053") 2020/05/15 05:30:41 DEBUG : yaml: Deleting (id "4_z8f022d13c0ad1b6b7f280e1b_f101fceef9a85463f_d20200515_m052657_c001_v0001136_t0051") "./sync.test -test.v -test.timeout 1h0m0s -remote TestB2: -verbose" - Finished ERROR in 6m39.338913026s (try 1/5): exit status 1: Failed [TestMoveEmptyDirectories TestServerSideCopy TestSyncDoesntUpdateModtime TestSyncAfterChangingFilesSizeOnly TestSyncAfterChangingContentsOnly]