"./sync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose" - Starting (try 1/5) 2021/03/23 06:14:55 DEBUG : Creating backend with remote "TestB2:rclone-test-cexuxas0dafuxoq9kazucoc2" 2021/03/23 06:14:56 DEBUG : Creating backend with remote "/tmp/rclone189872454" === RUN TestPipe --- PASS: TestPipe (0.00s) === RUN TestPipeConcurrent --- PASS: TestPipeConcurrent (0.28s) === 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.01s) --- PASS: TestPipeOrderBy/#00 (0.00s) --- PASS: TestPipeOrderBy/size (0.00s) --- PASS: TestPipeOrderBy/name (0.00s) --- PASS: TestPipeOrderBy/modtime (0.00s) --- PASS: TestPipeOrderBy/size,ascending (0.00s) --- PASS: TestPipeOrderBy/name,asc (0.00s) --- PASS: TestPipeOrderBy/modtime,ascending (0.00s) --- PASS: TestPipeOrderBy/size,descending (0.00s) --- PASS: TestPipeOrderBy/name,desc (0.00s) --- PASS: TestPipeOrderBy/modtime,descending (0.00s) --- PASS: TestPipeOrderBy/size,mixed,50 (0.00s) --- PASS: TestPipeOrderBy/size,mixed,51 (0.00s) === RUN TestNewLess === RUN TestNewLess/blankOK === RUN TestNewLess/tooManyParts === RUN TestNewLess/tooManyParts2 === RUN TestNewLess/badMixed === RUN TestNewLess/unknownComparison === RUN TestNewLess/unknownSortDirection === RUN TestNewLess/size === RUN TestNewLess/name === RUN TestNewLess/modtime === RUN TestNewLess/size,ascending === RUN TestNewLess/name,asc === RUN TestNewLess/modtime,ascending === RUN TestNewLess/size,descending === RUN TestNewLess/name,desc === RUN TestNewLess/modtime,descending === RUN TestNewLess/modtime,mixed === RUN TestNewLess/modtime,mixed,30 --- PASS: TestNewLess (0.00s) --- PASS: TestNewLess/blankOK (0.00s) --- PASS: TestNewLess/tooManyParts (0.00s) --- PASS: TestNewLess/tooManyParts2 (0.00s) --- PASS: TestNewLess/badMixed (0.00s) --- PASS: TestNewLess/unknownComparison (0.00s) --- PASS: TestNewLess/unknownSortDirection (0.00s) --- PASS: TestNewLess/size (0.00s) --- PASS: TestNewLess/name (0.00s) --- PASS: TestNewLess/modtime (0.00s) --- PASS: TestNewLess/size,ascending (0.00s) --- PASS: TestNewLess/name,asc (0.00s) --- PASS: TestNewLess/modtime,ascending (0.00s) --- PASS: TestNewLess/size,descending (0.00s) --- PASS: TestNewLess/name,desc (0.00s) --- PASS: TestNewLess/modtime,descending (0.00s) --- PASS: TestNewLess/modtime,mixed (0.00s) --- PASS: TestNewLess/modtime,mixed,30 (0.00s) === RUN TestRcCopy rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcCopy (0.00s) === RUN TestRcMove rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcMove (0.00s) === RUN TestRcSync rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcSync (0.00s) === RUN TestCopyWithDryRun run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" 2021/03/23 06:14:57 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for checks to finish 2021/03/23 06:14:57 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for transfers to finish 2021/03/23 06:14:57 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) --- PASS: TestCopyWithDryRun (1.39s) === RUN TestCopy run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" 2021/03/23 06:14:58 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for checks to finish 2021/03/23 06:14:58 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for transfers to finish 2021/03/23 06:15:01 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/03/23 06:15:01 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopy (3.53s) === RUN TestCopyMissingDirectory run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" 2021/03/23 06:15:01 DEBUG : Creating backend with remote "/non-existing" 2021/03/23 06:15:01 ERROR : : error reading source directory: directory not found 2021/03/23 06:15:01 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for checks to finish 2021/03/23 06:15:01 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for transfers to finish --- PASS: TestCopyMissingDirectory (0.43s) === RUN TestCopyNoTraverse run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" 2021/03/23 06:15:02 DEBUG : Couldn't decode error response: EOF 2021/03/23 06:15:02 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for checks to finish 2021/03/23 06:15:02 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for transfers to finish 2021/03/23 06:15:04 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/03/23 06:15:04 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoTraverse (2.89s) === RUN TestCopyCheckFirst run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" 2021/03/23 06:15:05 INFO : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Running all checks before starting transfers 2021/03/23 06:15:05 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for checks to finish 2021/03/23 06:15:05 INFO : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Checks finished, now starting transfers 2021/03/23 06:15:05 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for transfers to finish 2021/03/23 06:15:05 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/03/23 06:15:05 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyCheckFirst (3.74s) === RUN TestSyncNoTraverse run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" 2021/03/23 06:15:08 ERROR : Ignoring --no-traverse with sync 2021/03/23 06:15:09 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for checks to finish 2021/03/23 06:15:09 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for transfers to finish 2021/03/23 06:15:09 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/03/23 06:15:09 INFO : sub dir/hello world: Copied (new) 2021/03/23 06:15:09 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoTraverse (2.10s) === RUN TestCopyWithDepth run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" 2021/03/23 06:15:11 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for checks to finish 2021/03/23 06:15:11 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for transfers to finish 2021/03/23 06:15:11 DEBUG : hello world2: SHA-1 = c0bf117f9c93d02c894fe8ffd6e5cecf3545c209 OK 2021/03/23 06:15:11 INFO : hello world2: Copied (new) --- PASS: TestCopyWithDepth (1.55s) === RUN TestCopyWithFilesFrom run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" 2021/03/23 06:15:12 DEBUG : hello world2: Excluded 2021/03/23 06:15:12 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for checks to finish 2021/03/23 06:15:12 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for transfers to finish 2021/03/23 06:15:13 DEBUG : potato2: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/03/23 06:15:13 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFrom (3.19s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" 2021/03/23 06:15:16 DEBUG : Couldn't decode error response: EOF 2021/03/23 06:15:16 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for checks to finish 2021/03/23 06:15:16 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for transfers to finish 2021/03/23 06:15:17 DEBUG : potato2: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/03/23 06:15:17 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFromAndNoTraverse (2.47s) === RUN TestCopyEmptyDirectories run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" 2021/03/23 06:15:18 DEBUG : sub dir2: Making directory 2021/03/23 06:15:18 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for checks to finish 2021/03/23 06:15:18 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for transfers to finish 2021/03/23 06:15:18 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/03/23 06:15:18 INFO : sub dir/hello world: Copied (new) 2021/03/23 06:15:18 DEBUG : sub dir2: Making directory 2021/03/23 06:15:18 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: copied 1 directories fstest.go:251: Filtering empty directory "sub dir2" --- PASS: TestCopyEmptyDirectories (1.61s) === RUN TestMoveEmptyDirectories run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" 2021/03/23 06:15:19 DEBUG : sub dir2: Making directory 2021/03/23 06:15:19 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for checks to finish 2021/03/23 06:15:19 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for transfers to finish 2021/03/23 06:15:20 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/03/23 06:15:20 INFO : sub dir/hello world: Copied (new) 2021/03/23 06:15:20 INFO : sub dir/hello world: Deleted 2021/03/23 06:15:20 DEBUG : sub dir2: Making directory 2021/03/23 06:15:20 DEBUG : sub dir: Making directory 2021/03/23 06:15:20 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: copied 2 directories fstest.go:251: Filtering empty directory "sub dir2" --- PASS: TestMoveEmptyDirectories (1.53s) === RUN TestSyncEmptyDirectories run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" 2021/03/23 06:15:21 DEBUG : sub dir2: Making directory 2021/03/23 06:15:21 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for checks to finish 2021/03/23 06:15:21 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for transfers to finish 2021/03/23 06:15:21 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/03/23 06:15:21 INFO : sub dir/hello world: Copied (new) 2021/03/23 06:15:21 DEBUG : sub dir2: Making directory 2021/03/23 06:15:21 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: copied 1 directories 2021/03/23 06:15:21 DEBUG : Waiting for deletions to finish fstest.go:251: Filtering empty directory "sub dir2" --- PASS: TestSyncEmptyDirectories (1.63s) === RUN TestServerSideCopy run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" 2021/03/23 06:15:24 DEBUG : Creating backend with remote "TestB2:rclone-test-lupofij8volusov2xuriqer0" sync_test.go:281: Server side copy (if possible) B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2 -> B2 bucket rclone-test-lupofij8volusov2xuriqer0 2021/03/23 06:15:25 DEBUG : B2 bucket rclone-test-lupofij8volusov2xuriqer0: Waiting for checks to finish 2021/03/23 06:15:25 DEBUG : B2 bucket rclone-test-lupofij8volusov2xuriqer0: Waiting for transfers to finish 2021/03/23 06:15:26 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/03/23 06:15:26 INFO : sub dir/hello world: Copied (server-side copy) 2021/03/23 06:15:26 DEBUG : B2 bucket rclone-test-lupofij8volusov2xuriqer0: Purge remote 2021/03/23 06:15:26 DEBUG : sub dir/hello world: Deleting (id "4_z8fd24d13b09d5bdb7f880e1b_f114a0666483882c2_d20210323_m061525_c001_v0001145_t0009") --- PASS: TestServerSideCopy (4.63s) === RUN TestCopyAfterDelete run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" 2021/03/23 06:15:28 ERROR : : error listing: directory not found 2021/03/23 06:15:29 DEBUG : Local file system at /tmp/rclone189872454: Making directory 2021/03/23 06:15:29 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for checks to finish 2021/03/23 06:15:29 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for transfers to finish --- PASS: TestCopyAfterDelete (2.62s) === RUN TestCopyRedownload run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" 2021/03/23 06:15:32 DEBUG : Local file system at /tmp/rclone189872454: Waiting for checks to finish 2021/03/23 06:15:32 DEBUG : Local file system at /tmp/rclone189872454: Waiting for transfers to finish 2021/03/23 06:15:32 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/03/23 06:15:32 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyRedownload (3.03s) === RUN TestSyncBasedOnCheckSum run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" 2021/03/23 06:15:33 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for checks to finish 2021/03/23 06:15:33 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for transfers to finish 2021/03/23 06:15:33 DEBUG : check sum: SHA-1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2021/03/23 06:15:33 INFO : check sum: Copied (new) 2021/03/23 06:15:33 DEBUG : Waiting for deletions to finish 2021/03/23 06:15:34 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for checks to finish 2021/03/23 06:15:34 DEBUG : check sum: SHA-1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2021/03/23 06:15:34 DEBUG : check sum: Size and SHA-1 of src and dst objects identical 2021/03/23 06:15:34 DEBUG : check sum: Unchanged skipping 2021/03/23 06:15:34 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for transfers to finish 2021/03/23 06:15:34 DEBUG : Waiting for deletions to finish 2021/03/23 06:15:34 INFO : There was nothing to transfer --- PASS: TestSyncBasedOnCheckSum (1.51s) === RUN TestSyncSizeOnly run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" 2021/03/23 06:15:34 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for checks to finish 2021/03/23 06:15:34 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for transfers to finish 2021/03/23 06:15:35 DEBUG : sizeonly: SHA-1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2021/03/23 06:15:35 INFO : sizeonly: Copied (new) 2021/03/23 06:15:35 DEBUG : Waiting for deletions to finish 2021/03/23 06:15:35 DEBUG : sizeonly: Sizes identical 2021/03/23 06:15:35 DEBUG : sizeonly: Unchanged skipping 2021/03/23 06:15:35 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for checks to finish 2021/03/23 06:15:35 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for transfers to finish 2021/03/23 06:15:35 DEBUG : Waiting for deletions to finish 2021/03/23 06:15:35 INFO : There was nothing to transfer --- PASS: TestSyncSizeOnly (1.88s) === RUN TestSyncIgnoreSize run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" 2021/03/23 06:15:36 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for checks to finish 2021/03/23 06:15:36 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for transfers to finish 2021/03/23 06:15:37 DEBUG : ignore-size: SHA-1 = 4a756ca07e9487f482465a99e8286abc86ba4dc7 OK 2021/03/23 06:15:37 INFO : ignore-size: Copied (new) 2021/03/23 06:15:37 DEBUG : Waiting for deletions to finish 2021/03/23 06:15:37 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for checks to finish 2021/03/23 06:15:37 DEBUG : ignore-size: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/03/23 06:15:37 DEBUG : ignore-size: Unchanged skipping 2021/03/23 06:15:37 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for transfers to finish 2021/03/23 06:15:37 DEBUG : Waiting for deletions to finish 2021/03/23 06:15:37 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreSize (2.23s) === RUN TestSyncIgnoreTimes run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" 2021/03/23 06:15:39 DEBUG : existing: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/03/23 06:15:39 DEBUG : existing: Unchanged skipping 2021/03/23 06:15:39 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for checks to finish 2021/03/23 06:15:39 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for transfers to finish 2021/03/23 06:15:39 DEBUG : Waiting for deletions to finish 2021/03/23 06:15:39 INFO : There was nothing to transfer 2021/03/23 06:15:39 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for checks to finish 2021/03/23 06:15:39 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2021/03/23 06:15:39 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for transfers to finish 2021/03/23 06:15:40 DEBUG : existing: SHA-1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2021/03/23 06:15:40 INFO : existing: Copied (replaced existing) 2021/03/23 06:15:40 DEBUG : Waiting for deletions to finish --- PASS: TestSyncIgnoreTimes (2.59s) === RUN TestSyncIgnoreExisting run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" 2021/03/23 06:15:41 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for checks to finish 2021/03/23 06:15:41 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for transfers to finish 2021/03/23 06:15:42 DEBUG : existing: SHA-1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2021/03/23 06:15:42 INFO : existing: Copied (new) 2021/03/23 06:15:42 DEBUG : Waiting for deletions to finish 2021/03/23 06:15:42 DEBUG : existing: Destination exists, skipping 2021/03/23 06:15:42 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for checks to finish 2021/03/23 06:15:42 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for transfers to finish 2021/03/23 06:15:42 DEBUG : Waiting for deletions to finish 2021/03/23 06:15:42 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreExisting (1.82s) === RUN TestSyncIgnoreErrors run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" 2021/03/23 06:15:46 DEBUG : d: Making directory fstest.go:251: Filtering empty directory "d" 2021/03/23 06:15:47 DEBUG : c/non empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/03/23 06:15:47 DEBUG : c/non empty space: Unchanged skipping 2021/03/23 06:15:47 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for checks to finish 2021/03/23 06:15:47 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for transfers to finish 2021/03/23 06:15:48 DEBUG : a/potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/03/23 06:15:48 INFO : a/potato2: Copied (new) 2021/03/23 06:15:48 DEBUG : Waiting for deletions to finish 2021/03/23 06:15:48 INFO : b/potato: Deleted --- PASS: TestSyncIgnoreErrors (6.79s) === RUN TestSyncAfterChangingModtimeOnly run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" 2021/03/23 06:15:53 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for checks to finish 2021/03/23 06:15:53 DEBUG : empty space: Modification times differ by -95456h54m52.624456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499 +0000 UTC 2021/03/23 06:15:53 DEBUG : empty space: SHA-1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2021/03/23 06:15:53 NOTICE: empty space: Skipped update modification time as --dry-run is set (size 1) 2021/03/23 06:15:53 DEBUG : empty space: Unchanged skipping 2021/03/23 06:15:53 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for transfers to finish 2021/03/23 06:15:53 DEBUG : Waiting for deletions to finish 2021/03/23 06:15:53 INFO : There was nothing to transfer 2021/03/23 06:15:53 DEBUG : empty space: Modification times differ by -95456h54m52.624456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499 +0000 UTC 2021/03/23 06:15:53 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for checks to finish 2021/03/23 06:15:53 DEBUG : empty space: SHA-1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2021/03/23 06:15:54 INFO : empty space: Updated modification time in destination 2021/03/23 06:15:54 DEBUG : empty space: Unchanged skipping 2021/03/23 06:15:54 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for transfers to finish 2021/03/23 06:15:54 DEBUG : Waiting for deletions to finish 2021/03/23 06:15:54 INFO : There was nothing to transfer --- PASS: TestSyncAfterChangingModtimeOnly (5.14s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" 2021/03/23 06:15:56 DEBUG : empty space: Modification times differ by -95456h54m52.624456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499 +0000 UTC 2021/03/23 06:15:56 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for checks to finish 2021/03/23 06:15:56 DEBUG : empty space: SHA-1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2021/03/23 06:15:56 DEBUG : empty space: Unchanged skipping 2021/03/23 06:15:56 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for transfers to finish 2021/03/23 06:15:56 DEBUG : Waiting for deletions to finish 2021/03/23 06:15:56 INFO : There was nothing to transfer --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (1.66s) === RUN TestSyncDoesntUpdateModtime run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" 2021/03/23 06:15:58 DEBUG : foo: Modification times differ by -95456h54m52.624456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499 +0000 UTC 2021/03/23 06:15:58 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for checks to finish 2021/03/23 06:15:58 DEBUG : foo: SHA-1 = 0beec7b5ea3f0fdbc95d0dd47f3c5bc275da8a33 (Local file system at /tmp/rclone189872454) 2021/03/23 06:15:58 DEBUG : foo: SHA-1 = 62cdb7020ff920e5aa642c3d4066950dd1f01f4d (B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2) 2021/03/23 06:15:58 DEBUG : foo: SHA-1 differ 2021/03/23 06:15:58 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for transfers to finish 2021/03/23 06:15:59 DEBUG : foo: SHA-1 = 0beec7b5ea3f0fdbc95d0dd47f3c5bc275da8a33 OK 2021/03/23 06:15:59 INFO : foo: Copied (replaced existing) 2021/03/23 06:15:59 DEBUG : Waiting for deletions to finish --- PASS: TestSyncDoesntUpdateModtime (3.13s) === RUN TestSyncAfterAddingAFile run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" 2021/03/23 06:16:01 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/03/23 06:16:01 DEBUG : empty space: Unchanged skipping 2021/03/23 06:16:01 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for checks to finish 2021/03/23 06:16:01 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for transfers to finish 2021/03/23 06:16:03 DEBUG : potato: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/03/23 06:16:03 INFO : potato: Copied (new) 2021/03/23 06:16:03 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterAddingAFile (4.58s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" 2021/03/23 06:16:06 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2021/03/23 06:16:06 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for checks to finish 2021/03/23 06:16:06 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for transfers to finish 2021/03/23 06:16:07 DEBUG : potato: SHA-1 = 0fd321a1023ac4e1e1054fcc0236b9d0755f722f OK 2021/03/23 06:16:07 INFO : potato: Copied (replaced existing) 2021/03/23 06:16:07 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingFilesSizeOnly (3.37s) === RUN TestSyncAfterChangingContentsOnly run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" 2021/03/23 06:16:08 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for checks to finish 2021/03/23 06:16:08 DEBUG : potato: Modification times differ by 119h59m59.876543211s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2011-12-30 12:59:59 +0000 UTC 2021/03/23 06:16:08 DEBUG : potato: SHA-1 = 1ca5094b3b73f587249eabe179c40a3eec839b1f (Local file system at /tmp/rclone189872454) 2021/03/23 06:16:08 DEBUG : potato: SHA-1 = 0fd321a1023ac4e1e1054fcc0236b9d0755f722f (B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2) 2021/03/23 06:16:08 DEBUG : potato: SHA-1 differ 2021/03/23 06:16:08 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for transfers to finish 2021/03/23 06:16:09 DEBUG : potato: SHA-1 = 1ca5094b3b73f587249eabe179c40a3eec839b1f OK 2021/03/23 06:16:09 INFO : potato: Copied (replaced existing) 2021/03/23 06:16:09 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingContentsOnly (3.02s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" 2021/03/23 06:16:14 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2021/03/23 06:16:14 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/03/23 06:16:14 DEBUG : empty space: Unchanged skipping 2021/03/23 06:16:14 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for checks to finish 2021/03/23 06:16:14 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for transfers to finish 2021/03/23 06:16:14 DEBUG : Waiting for deletions to finish 2021/03/23 06:16:14 NOTICE: potato: Skipped delete as --dry-run is set (size 21) --- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (7.37s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" 2021/03/23 06:16:20 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/03/23 06:16:20 DEBUG : empty space: Unchanged skipping 2021/03/23 06:16:20 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for checks to finish 2021/03/23 06:16:20 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for transfers to finish 2021/03/23 06:16:21 DEBUG : potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/03/23 06:16:21 INFO : potato2: Copied (new) 2021/03/23 06:16:21 DEBUG : Waiting for deletions to finish 2021/03/23 06:16:22 INFO : potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (4.83s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" 2021/03/23 06:16:23 DEBUG : d: Making directory 2021/03/23 06:16:23 DEBUG : d/e: Making directory fstest.go:251: Filtering empty directory "d" fstest.go:251: Filtering empty directory "d/e" 2021/03/23 06:16:24 DEBUG : c/non empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/03/23 06:16:24 DEBUG : c/non empty space: Unchanged skipping 2021/03/23 06:16:24 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for checks to finish 2021/03/23 06:16:24 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for transfers to finish 2021/03/23 06:16:24 DEBUG : a/potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/03/23 06:16:24 INFO : a/potato2: Copied (new) 2021/03/23 06:16:24 DEBUG : Waiting for deletions to finish 2021/03/23 06:16:25 INFO : b/potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (5.09s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" 2021/03/23 06:16:29 DEBUG : d: Making directory fstest.go:251: Filtering empty directory "d" 2021/03/23 06:16:30 DEBUG : c/non empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/03/23 06:16:30 DEBUG : c/non empty space: Unchanged skipping 2021/03/23 06:16:30 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for checks to finish 2021/03/23 06:16:30 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for transfers to finish 2021/03/23 06:16:30 DEBUG : a/potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/03/23 06:16:30 INFO : a/potato2: Copied (new) 2021/03/23 06:16:30 ERROR : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: not deleting files as there were IO errors 2021/03/23 06:16:30 ERROR : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: not deleting directories as there were IO errors fstest.go:251: Filtering empty directory "d" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (6.94s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" 2021/03/23 06:16:36 DEBUG : Waiting for deletions to finish 2021/03/23 06:16:36 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/03/23 06:16:36 DEBUG : empty space: Unchanged skipping 2021/03/23 06:16:36 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for checks to finish 2021/03/23 06:16:36 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for transfers to finish 2021/03/23 06:16:37 DEBUG : potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/03/23 06:16:37 INFO : potato2: Copied (new) 2021/03/23 06:16:37 INFO : potato: Deleted --- PASS: TestSyncDeleteDuring (5.52s) === RUN TestSyncDeleteBefore run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" 2021/03/23 06:16:44 DEBUG : Waiting for deletions to finish 2021/03/23 06:16:45 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for checks to finish 2021/03/23 06:16:45 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for transfers to finish 2021/03/23 06:16:45 INFO : potato: Deleted 2021/03/23 06:16:45 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/03/23 06:16:45 DEBUG : empty space: Unchanged skipping 2021/03/23 06:16:45 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for checks to finish 2021/03/23 06:16:45 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for transfers to finish 2021/03/23 06:16:46 DEBUG : potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/03/23 06:16:46 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteBefore (6.93s) === RUN TestCopyDeleteBefore run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" 2021/03/23 06:16:48 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for checks to finish 2021/03/23 06:16:48 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for transfers to finish 2021/03/23 06:16:49 DEBUG : potato2: SHA-1 = 810274f0df19dab60a894a562b7a8ff04c072623 OK 2021/03/23 06:16:49 INFO : potato2: Copied (new) --- PASS: TestCopyDeleteBefore (3.12s) === RUN TestSyncWithExclude run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" 2021/03/23 06:16:53 DEBUG : enormous: Excluded 2021/03/23 06:16:53 DEBUG : potato2: Excluded 2021/03/23 06:16:53 DEBUG : potato2: Excluded 2021/03/23 06:16:53 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/03/23 06:16:53 DEBUG : empty space: Unchanged skipping 2021/03/23 06:16:53 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for checks to finish 2021/03/23 06:16:53 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for transfers to finish 2021/03/23 06:16:53 DEBUG : Waiting for deletions to finish 2021/03/23 06:16:53 INFO : There was nothing to transfer 2021/03/23 06:16:53 DEBUG : enormous: Excluded 2021/03/23 06:16:53 DEBUG : potato2: Excluded 2021/03/23 06:16:54 DEBUG : potato2: Excluded 2021/03/23 06:16:54 DEBUG : empty space: Size and modification time the same (differ by 456.789µs, within tolerance 1ms) 2021/03/23 06:16:54 DEBUG : empty space: Unchanged skipping 2021/03/23 06:16:54 DEBUG : Local file system at /tmp/rclone189872454: Waiting for checks to finish 2021/03/23 06:16:54 DEBUG : Local file system at /tmp/rclone189872454: Waiting for transfers to finish 2021/03/23 06:16:54 DEBUG : Waiting for deletions to finish 2021/03/23 06:16:54 INFO : There was nothing to transfer --- PASS: TestSyncWithExclude (4.52s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" 2021/03/23 06:16:57 DEBUG : enormous: Excluded 2021/03/23 06:16:57 DEBUG : potato2: Excluded 2021/03/23 06:16:58 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for checks to finish 2021/03/23 06:16:58 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/03/23 06:16:58 DEBUG : empty space: Unchanged skipping 2021/03/23 06:16:58 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for transfers to finish 2021/03/23 06:16:58 DEBUG : Waiting for deletions to finish 2021/03/23 06:16:58 INFO : enormous: Deleted 2021/03/23 06:16:58 INFO : potato2: Deleted 2021/03/23 06:16:58 INFO : There was nothing to transfer 2021/03/23 06:16:59 DEBUG : Local file system at /tmp/rclone189872454: Waiting for checks to finish 2021/03/23 06:16:59 DEBUG : empty space: Size and modification time the same (differ by 456.789µs, within tolerance 1ms) 2021/03/23 06:16:59 DEBUG : empty space: Unchanged skipping 2021/03/23 06:16:59 DEBUG : Local file system at /tmp/rclone189872454: Waiting for transfers to finish 2021/03/23 06:16:59 DEBUG : Waiting for deletions to finish 2021/03/23 06:16:59 INFO : potato2: Deleted 2021/03/23 06:16:59 INFO : enormous: Deleted 2021/03/23 06:16:59 INFO : There was nothing to transfer --- PASS: TestSyncWithExcludeAndDeleteExcluded (4.58s) === RUN TestSyncWithUpdateOlder run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" 2021/03/23 06:17:05 DEBUG : four: Sizes differ (src 4 vs dst 8) 2021/03/23 06:17:05 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2021/03/23 06:17:05 DEBUG : one: Destination is newer than source, skipping 2021/03/23 06:17:05 DEBUG : three: Sizes identical 2021/03/23 06:17:05 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2021/03/23 06:17:05 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for checks to finish 2021/03/23 06:17:05 DEBUG : two: SHA-1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b (Local file system at /tmp/rclone189872454) 2021/03/23 06:17:05 DEBUG : two: SHA-1 = b10e0da85e90399abfec36c7376b20aa4596461b (B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2) 2021/03/23 06:17:05 DEBUG : two: SHA-1 differ 2021/03/23 06:17:05 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for transfers to finish 2021/03/23 06:17:06 DEBUG : four: SHA-1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2021/03/23 06:17:06 INFO : four: Copied (replaced existing) 2021/03/23 06:17:06 DEBUG : five: SHA-1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2021/03/23 06:17:06 INFO : five: Copied (new) 2021/03/23 06:17:07 DEBUG : two: SHA-1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2021/03/23 06:17:07 INFO : two: Copied (replaced existing) 2021/03/23 06:17:07 DEBUG : Waiting for deletions to finish 2021/03/23 06:17:07 DEBUG : one: Destination is newer than source, skipping 2021/03/23 06:17:07 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for checks to finish 2021/03/23 06:17:07 DEBUG : five: SHA-1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2021/03/23 06:17:07 DEBUG : five: Size and SHA-1 of src and dst objects identical 2021/03/23 06:17:07 DEBUG : five: Destination mod time is within 1s of source and files identical, skipping 2021/03/23 06:17:07 DEBUG : two: SHA-1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2021/03/23 06:17:07 DEBUG : two: Size and SHA-1 of src and dst objects identical 2021/03/23 06:17:07 DEBUG : two: Destination mod time is within 1s of source and files identical, skipping 2021/03/23 06:17:07 DEBUG : three: SHA-1 = b802f384302cb24fbab0a44997e820bf2e8507bb (Local file system at /tmp/rclone189872454) 2021/03/23 06:17:07 DEBUG : three: SHA-1 = 03731cad13f4cf7b59703c77529381cdec1632e5 (B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2) 2021/03/23 06:17:07 DEBUG : three: SHA-1 differ 2021/03/23 06:17:07 DEBUG : three: Destination mod time is within 1s of source but files differ, transferring 2021/03/23 06:17:07 DEBUG : four: SHA-1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2021/03/23 06:17:07 DEBUG : four: Size and SHA-1 of src and dst objects identical 2021/03/23 06:17:07 DEBUG : four: Destination mod time is within 1s of source and files identical, skipping 2021/03/23 06:17:07 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for transfers to finish 2021/03/23 06:17:08 DEBUG : three: SHA-1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/03/23 06:17:08 INFO : three: Copied (replaced existing) 2021/03/23 06:17:08 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithUpdateOlder (11.85s) === RUN TestSyncWithMaxDuration sync_test.go:1039: Skipping test on non local remote --- SKIP: TestSyncWithMaxDuration (0.00s) === RUN TestSyncWithTrackRenames run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" sync_test.go:1088: Can track renames: true 2021/03/23 06:17:11 INFO : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Making map for --track-renames 2021/03/23 06:17:11 INFO : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Finished making map for --track-renames 2021/03/23 06:17:11 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for checks to finish 2021/03/23 06:17:11 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for renames to finish 2021/03/23 06:17:11 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for transfers to finish 2021/03/23 06:17:11 DEBUG : yam: Clearing upload URL because of error: Post "https://pod-000-1144-10.backblaze.com/b2api/v1/b2_upload_file/7fa2dd43b08d5bdb7f880e1b/c001_v0001144_t0034": EOF 2021/03/23 06:17:11 DEBUG : pacer: low level retry 1/1 (error Post "https://pod-000-1144-10.backblaze.com/b2api/v1/b2_upload_file/7fa2dd43b08d5bdb7f880e1b/c001_v0001144_t0034": EOF) 2021/03/23 06:17:11 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2021/03/23 06:17:11 DEBUG : yam: Received error: Post "https://pod-000-1144-10.backblaze.com/b2api/v1/b2_upload_file/7fa2dd43b08d5bdb7f880e1b/c001_v0001144_t0034": EOF - low level retry 1/10 2021/03/23 06:17:12 DEBUG : pacer: Reducing sleep to 10ms 2021/03/23 06:17:12 DEBUG : potato: SHA-1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2021/03/23 06:17:12 INFO : potato: Copied (new) 2021/03/23 06:17:14 DEBUG : yam: SHA-1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2021/03/23 06:17:14 INFO : yam: Copied (new) 2021/03/23 06:17:14 DEBUG : Waiting for deletions to finish 2021/03/23 06:17:14 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/03/23 06:17:14 DEBUG : potato: Unchanged skipping 2021/03/23 06:17:14 INFO : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Making map for --track-renames 2021/03/23 06:17:14 INFO : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Finished making map for --track-renames 2021/03/23 06:17:14 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for checks to finish 2021/03/23 06:17:14 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for renames to finish 2021/03/23 06:17:14 DEBUG : Couldn't decode error response: EOF 2021/03/23 06:17:15 DEBUG : yam: SHA-1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2021/03/23 06:17:15 INFO : yam: Copied (server-side copy) to: yaml 2021/03/23 06:17:16 INFO : yam: Deleted 2021/03/23 06:17:16 INFO : yaml: Renamed from "yam" 2021/03/23 06:17:16 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for transfers to finish 2021/03/23 06:17:16 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenames (5.83s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" sync_test.go:1157: Can track renames: true 2021/03/23 06:17:17 INFO : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Making map for --track-renames 2021/03/23 06:17:17 INFO : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Finished making map for --track-renames 2021/03/23 06:17:17 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for checks to finish 2021/03/23 06:17:17 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for renames to finish 2021/03/23 06:17:17 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for transfers to finish 2021/03/23 06:17:18 DEBUG : potato: SHA-1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2021/03/23 06:17:18 INFO : potato: Copied (new) 2021/03/23 06:17:19 DEBUG : yam: SHA-1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2021/03/23 06:17:19 INFO : yam: Copied (new) 2021/03/23 06:17:19 DEBUG : Waiting for deletions to finish 2021/03/23 06:17:19 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/03/23 06:17:19 DEBUG : potato: Unchanged skipping 2021/03/23 06:17:19 INFO : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Making map for --track-renames 2021/03/23 06:17:19 INFO : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Finished making map for --track-renames 2021/03/23 06:17:19 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for checks to finish 2021/03/23 06:17:19 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for renames to finish 2021/03/23 06:17:19 DEBUG : Couldn't decode error response: EOF 2021/03/23 06:17:19 DEBUG : yam: SHA-1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2021/03/23 06:17:19 INFO : yam: Copied (server-side copy) to: yaml 2021/03/23 06:17:20 INFO : yam: Deleted 2021/03/23 06:17:20 INFO : yaml: Renamed from "yam" 2021/03/23 06:17:20 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for transfers to finish 2021/03/23 06:17:20 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenamesStrategyModtime (3.93s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" sync_test.go:1193: Can track renames: true 2021/03/23 06:17:21 INFO : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Making map for --track-renames 2021/03/23 06:17:21 INFO : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Finished making map for --track-renames 2021/03/23 06:17:21 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for checks to finish 2021/03/23 06:17:21 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for renames to finish 2021/03/23 06:17:21 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for transfers to finish 2021/03/23 06:17:22 DEBUG : sub/yam: SHA-1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2021/03/23 06:17:22 INFO : sub/yam: Copied (new) 2021/03/23 06:17:22 DEBUG : potato: SHA-1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2021/03/23 06:17:22 INFO : potato: Copied (new) 2021/03/23 06:17:22 DEBUG : Waiting for deletions to finish 2021/03/23 06:17:22 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/03/23 06:17:22 DEBUG : potato: Unchanged skipping 2021/03/23 06:17:22 INFO : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Making map for --track-renames 2021/03/23 06:17:22 INFO : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Finished making map for --track-renames 2021/03/23 06:17:22 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for checks to finish 2021/03/23 06:17:22 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for renames to finish 2021/03/23 06:17:22 DEBUG : Couldn't decode error response: EOF 2021/03/23 06:17:23 DEBUG : sub/yam: SHA-1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2021/03/23 06:17:23 INFO : sub/yam: Copied (server-side copy) to: yam 2021/03/23 06:17:24 INFO : sub/yam: Deleted 2021/03/23 06:17:24 INFO : yam: Renamed from "sub/yam" 2021/03/23 06:17:24 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for transfers to finish 2021/03/23 06:17:24 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenamesStrategyLeaf (3.71s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" 2021/03/23 06:17:25 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for checks to finish 2021/03/23 06:17:25 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for transfers to finish 2021/03/23 06:17:25 DEBUG : nested/sub dir/file: SHA-1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK 2021/03/23 06:17:25 INFO : nested/sub dir/file: Copied (new) 2021/03/23 06:17:25 INFO : nested/sub dir/file: Deleted 2021/03/23 06:17:26 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/03/23 06:17:26 INFO : sub dir/hello world: Copied (new) 2021/03/23 06:17:26 INFO : sub dir/hello world: Deleted 2021/03/23 06:17:26 DEBUG : sub dir: Removing directory 2021/03/23 06:17:26 DEBUG : nested/sub dir: Removing directory 2021/03/23 06:17:26 DEBUG : nested: Removing directory 2021/03/23 06:17:26 DEBUG : Local file system at /tmp/rclone189872454: deleted 3 directories --- PASS: TestMoveWithDeleteEmptySrcDirs (2.85s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" 2021/03/23 06:17:28 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for checks to finish 2021/03/23 06:17:28 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for transfers to finish 2021/03/23 06:17:28 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/03/23 06:17:28 INFO : sub dir/hello world: Copied (new) 2021/03/23 06:17:28 INFO : sub dir/hello world: Deleted 2021/03/23 06:17:28 DEBUG : nested/sub dir/file: SHA-1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK 2021/03/23 06:17:28 INFO : nested/sub dir/file: Copied (new) 2021/03/23 06:17:28 INFO : nested/sub dir/file: Deleted --- PASS: TestMoveWithoutDeleteEmptySrcDirs (2.16s) === RUN TestServerSideMove run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" 2021/03/23 06:17:30 DEBUG : Creating backend with remote "TestB2:rclone-test-wulayun8luvavev9ziqasij4" sync_test.go:1245: Server side move (if possible) B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2 -> B2 bucket rclone-test-wulayun8luvavev9ziqasij4 2021/03/23 06:17:36 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms) 2021/03/23 06:17:36 DEBUG : empty space: Unchanged skipping 2021/03/23 06:17:36 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2021/03/23 06:17:36 DEBUG : B2 bucket rclone-test-wulayun8luvavev9ziqasij4: Waiting for checks to finish 2021/03/23 06:17:36 INFO : empty space: Deleted 2021/03/23 06:17:36 DEBUG : B2 bucket rclone-test-wulayun8luvavev9ziqasij4: Waiting for transfers to finish 2021/03/23 06:17:36 DEBUG : potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/03/23 06:17:36 INFO : potato2: Copied (server-side copy) 2021/03/23 06:17:37 DEBUG : potato3: SHA-1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2021/03/23 06:17:37 INFO : potato3: Copied (server-side copy) 2021/03/23 06:17:37 INFO : potato2: Deleted 2021/03/23 06:17:37 INFO : potato3: Deleted 2021/03/23 06:17:37 DEBUG : Creating backend with remote "TestB2:rclone-test-dibejuf7fotohon5xiwocup2" 2021/03/23 06:17:38 DEBUG : B2 bucket rclone-test-dibejuf7fotohon5xiwocup2: Waiting for checks to finish 2021/03/23 06:17:38 DEBUG : B2 bucket rclone-test-dibejuf7fotohon5xiwocup2: Waiting for transfers to finish 2021/03/23 06:17:39 DEBUG : empty space: SHA-1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2021/03/23 06:17:39 INFO : empty space: Copied (server-side copy) 2021/03/23 06:17:39 DEBUG : potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/03/23 06:17:39 INFO : potato2: Copied (server-side copy) 2021/03/23 06:17:39 INFO : empty space: Deleted 2021/03/23 06:17:40 INFO : potato2: Deleted 2021/03/23 06:17:41 DEBUG : potato3: SHA-1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2021/03/23 06:17:41 INFO : potato3: Copied (server-side copy) 2021/03/23 06:17:41 INFO : potato3: Deleted 2021/03/23 06:17:42 DEBUG : B2 bucket rclone-test-dibejuf7fotohon5xiwocup2: Purge remote 2021/03/23 06:17:42 DEBUG : empty space: Deleting (id "4_z4fe24db3b0bd5bdb7f880e1b_f104ee099151a9a61_d20210323_m061739_c001_v0001153_t0020") 2021/03/23 06:17:42 DEBUG : potato2: Deleting (id "4_z4fe24db3b0bd5bdb7f880e1b_f111771fae0783e4a_d20210323_m061739_c001_v0001005_t0037") 2021/03/23 06:17:42 DEBUG : potato3: Deleting (id "4_z4fe24db3b0bd5bdb7f880e1b_f117a49026fdfa0c3_d20210323_m061739_c001_v0001039_t0029") 2021/03/23 06:17:42 DEBUG : B2 bucket rclone-test-wulayun8luvavev9ziqasij4: Purge remote 2021/03/23 06:17:43 DEBUG : empty space: Deleting (id "4_zaf723d93b0bd5bdb7f880e1b_f1068d7a68886bb71_d20210323_m061739_c001_v0001137_t0011") 2021/03/23 06:17:43 DEBUG : empty space: Deleting (id "4_zaf723d93b0bd5bdb7f880e1b_f11213bea233e736f_d20210323_m061735_c001_v0001128_t0049") 2021/03/23 06:17:43 DEBUG : potato2: Deleting (id "4_zaf723d93b0bd5bdb7f880e1b_f115deb61754f54dc_d20210323_m061739_c001_v0001128_t0032") 2021/03/23 06:17:43 DEBUG : potato2: Deleting (id "4_zaf723d93b0bd5bdb7f880e1b_f10359170a435a397_d20210323_m061736_c001_v0001006_t0018") 2021/03/23 06:17:43 DEBUG : potato3: Deleting (id "4_zaf723d93b0bd5bdb7f880e1b_f1124a3df778242a1_d20210323_m061741_c001_v0001131_t0006") 2021/03/23 06:17:43 DEBUG : potato3: Deleting (id "4_zaf723d93b0bd5bdb7f880e1b_f1041be36a7e0d757_d20210323_m061736_c001_v0001041_t0057") 2021/03/23 06:17:43 DEBUG : potato3: Deleting (id "4_zaf723d93b0bd5bdb7f880e1b_f11213bea233e7371_d20210323_m061735_c001_v0001128_t0049") --- PASS: TestServerSideMove (13.93s) === RUN TestServerSideMoveWithFilter run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" 2021/03/23 06:17:44 DEBUG : Creating backend with remote "TestB2:rclone-test-gevimut4rosewam1donocuz4" sync_test.go:1245: Server side move (if possible) B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2 -> B2 bucket rclone-test-gevimut4rosewam1donocuz4 2021/03/23 06:17:53 DEBUG : empty space: Excluded 2021/03/23 06:17:53 DEBUG : empty space: Excluded 2021/03/23 06:17:53 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2021/03/23 06:17:53 DEBUG : B2 bucket rclone-test-gevimut4rosewam1donocuz4: Waiting for checks to finish 2021/03/23 06:17:53 DEBUG : B2 bucket rclone-test-gevimut4rosewam1donocuz4: Waiting for transfers to finish 2021/03/23 06:17:53 DEBUG : potato3: SHA-1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2021/03/23 06:17:53 INFO : potato3: Copied (server-side copy) 2021/03/23 06:17:54 DEBUG : potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/03/23 06:17:54 INFO : potato2: Copied (server-side copy) 2021/03/23 06:17:55 INFO : potato2: Deleted 2021/03/23 06:17:56 INFO : potato3: Deleted 2021/03/23 06:17:57 DEBUG : Creating backend with remote "TestB2:rclone-test-belovof2xowosis3zoyaxev7" 2021/03/23 06:17:57 DEBUG : empty space: Excluded 2021/03/23 06:17:57 DEBUG : B2 bucket rclone-test-belovof2xowosis3zoyaxev7: Waiting for checks to finish 2021/03/23 06:17:57 DEBUG : B2 bucket rclone-test-belovof2xowosis3zoyaxev7: Waiting for transfers to finish 2021/03/23 06:17:58 DEBUG : potato3: SHA-1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2021/03/23 06:17:58 INFO : potato3: Copied (server-side copy) 2021/03/23 06:17:58 DEBUG : potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/03/23 06:17:58 INFO : potato2: Copied (server-side copy) 2021/03/23 06:17:59 INFO : potato3: Deleted 2021/03/23 06:17:59 INFO : potato2: Deleted 2021/03/23 06:17:59 DEBUG : B2 bucket rclone-test-belovof2xowosis3zoyaxev7: Purge remote 2021/03/23 06:17:59 DEBUG : potato2: Deleting (id "4_z1f529d63b0bd5bdb7f880e1b_f10144fc6c5ecc806_d20210323_m061758_c001_v0001146_t0048") 2021/03/23 06:17:59 DEBUG : potato3: Deleting (id "4_z1f529d63b0bd5bdb7f880e1b_f1161af000b357576_d20210323_m061758_c001_v0001153_t0019") 2021/03/23 06:18:00 DEBUG : B2 bucket rclone-test-gevimut4rosewam1donocuz4: Purge remote 2021/03/23 06:18:00 DEBUG : empty space: Deleting (id "4_z2fb27dc3b0bd5bdb7f880e1b_f11061bae46668ce4_d20210323_m061752_c001_v0001146_t0051") 2021/03/23 06:18:00 DEBUG : potato2: Deleting (id "4_z2fb27dc3b0bd5bdb7f880e1b_f10180639df53c39f_d20210323_m061758_c001_v0001144_t0000") 2021/03/23 06:18:00 DEBUG : potato2: Deleting (id "4_z2fb27dc3b0bd5bdb7f880e1b_f104a8b77b0fcbb8a_d20210323_m061753_c001_v0001144_t0022") 2021/03/23 06:18:00 DEBUG : potato3: Deleting (id "4_z2fb27dc3b0bd5bdb7f880e1b_f105931ac78062688_d20210323_m061758_c001_v0001144_t0000") 2021/03/23 06:18:00 DEBUG : potato3: Deleting (id "4_z2fb27dc3b0bd5bdb7f880e1b_f111bd3ed34322492_d20210323_m061753_c001_v0001038_t0022") 2021/03/23 06:18:00 DEBUG : potato3: Deleting (id "4_z2fb27dc3b0bd5bdb7f880e1b_f11061bae46668cf0_d20210323_m061752_c001_v0001146_t0051") --- PASS: TestServerSideMoveWithFilter (18.39s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" 2021/03/23 06:18:02 DEBUG : Creating backend with remote "TestB2:rclone-test-kenecoh5fusiqoj9basewif0" 2021/03/23 06:18:05 DEBUG : tomatoDir: Making directory sync_test.go:1245: Server side move (if possible) B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2 -> B2 bucket rclone-test-kenecoh5fusiqoj9basewif0 2021/03/23 06:18:08 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms) 2021/03/23 06:18:08 DEBUG : empty space: Unchanged skipping 2021/03/23 06:18:08 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2021/03/23 06:18:08 DEBUG : B2 bucket rclone-test-kenecoh5fusiqoj9basewif0: Waiting for checks to finish 2021/03/23 06:18:08 DEBUG : potato3: SHA-1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2021/03/23 06:18:08 INFO : potato3: Copied (server-side copy) 2021/03/23 06:18:08 DEBUG : potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/03/23 06:18:08 INFO : potato2: Copied (server-side copy) 2021/03/23 06:18:09 INFO : empty space: Deleted 2021/03/23 06:18:09 DEBUG : B2 bucket rclone-test-kenecoh5fusiqoj9basewif0: Waiting for transfers to finish 2021/03/23 06:18:09 INFO : potato2: Deleted 2021/03/23 06:18:09 INFO : potato3: Deleted 2021/03/23 06:18:10 DEBUG : Creating backend with remote "TestB2:rclone-test-ripuyam7toxepip8kukitij9" 2021/03/23 06:18:10 DEBUG : tomatoDir: Making directory 2021/03/23 06:18:10 DEBUG : B2 bucket rclone-test-ripuyam7toxepip8kukitij9: Waiting for checks to finish 2021/03/23 06:18:10 DEBUG : B2 bucket rclone-test-ripuyam7toxepip8kukitij9: Waiting for transfers to finish 2021/03/23 06:18:11 DEBUG : potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/03/23 06:18:11 INFO : potato2: Copied (server-side copy) 2021/03/23 06:18:11 DEBUG : potato3: SHA-1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2021/03/23 06:18:11 INFO : potato3: Copied (server-side copy) 2021/03/23 06:18:12 DEBUG : empty space: SHA-1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2021/03/23 06:18:12 INFO : empty space: Copied (server-side copy) 2021/03/23 06:18:12 INFO : potato2: Deleted 2021/03/23 06:18:12 INFO : empty space: Deleted 2021/03/23 06:18:12 INFO : potato3: Deleted 2021/03/23 06:18:12 DEBUG : B2 bucket rclone-test-ripuyam7toxepip8kukitij9: Purge remote 2021/03/23 06:18:13 DEBUG : empty space: Deleting (id "4_z6f32cd83b0bd5bdb7f880e1b_f1081010459018ec5_d20210323_m061811_c001_v0001091_t0050") 2021/03/23 06:18:13 DEBUG : potato2: Deleting (id "4_z6f32cd83b0bd5bdb7f880e1b_f111bd41afff1607a_d20210323_m061811_c001_v0001146_t0009") 2021/03/23 06:18:13 DEBUG : potato3: Deleting (id "4_z6f32cd83b0bd5bdb7f880e1b_f1161db82b8a2781c_d20210323_m061811_c001_v0001113_t0020") 2021/03/23 06:18:13 DEBUG : B2 bucket rclone-test-kenecoh5fusiqoj9basewif0: Purge remote 2021/03/23 06:18:13 DEBUG : empty space: Deleting (id "4_z4fc2bd53b0bd5bdb7f880e1b_f105c4fdc1bc39e30_d20210323_m061812_c001_v0001150_t0022") 2021/03/23 06:18:13 DEBUG : empty space: Deleting (id "4_z4fc2bd53b0bd5bdb7f880e1b_f1138875e9dfbf1c1_d20210323_m061807_c001_v0001146_t0012") 2021/03/23 06:18:13 DEBUG : potato2: Deleting (id "4_z4fc2bd53b0bd5bdb7f880e1b_f108acbe488106506_d20210323_m061811_c001_v0001025_t0029") 2021/03/23 06:18:13 DEBUG : potato2: Deleting (id "4_z4fc2bd53b0bd5bdb7f880e1b_f109ae25dc928745f_d20210323_m061808_c001_v0001146_t0036") 2021/03/23 06:18:13 DEBUG : potato3: Deleting (id "4_z4fc2bd53b0bd5bdb7f880e1b_f1196d6586bec787c_d20210323_m061812_c001_v0001144_t0001") 2021/03/23 06:18:13 DEBUG : potato3: Deleting (id "4_z4fc2bd53b0bd5bdb7f880e1b_f1031701e7cb75f63_d20210323_m061808_c001_v0001153_t0003") 2021/03/23 06:18:13 DEBUG : potato3: Deleting (id "4_z4fc2bd53b0bd5bdb7f880e1b_f1138875e9dfbf1cd_d20210323_m061807_c001_v0001146_t0012") --- PASS: TestServerSideMoveDeleteEmptySourceDirs (12.17s) === RUN TestServerSideMoveOverlap run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" 2021/03/23 06:18:14 DEBUG : Creating backend with remote "TestB2:rclone-test-cexuxas0dafuxoq9kazucoc2/rclone-move-test" 2021/03/23 06:18:15 DEBUG : Couldn't decode error response: EOF --- PASS: TestServerSideMoveOverlap (2.71s) === RUN TestSyncOverlap run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" 2021/03/23 06:18:17 DEBUG : Creating backend with remote "TestB2:rclone-test-cexuxas0dafuxoq9kazucoc2/rclone-sync-test" 2021/03/23 06:18:17 DEBUG : Couldn't decode error response: EOF --- PASS: TestSyncOverlap (0.63s) === RUN TestSyncCompareDest run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" 2021/03/23 06:18:18 DEBUG : Creating backend with remote "TestB2:rclone-test-cexuxas0dafuxoq9kazucoc2/dst" 2021/03/23 06:18:18 DEBUG : Couldn't decode error response: EOF 2021/03/23 06:18:18 DEBUG : Creating backend with remote "TestB2:rclone-test-cexuxas0dafuxoq9kazucoc2/CompareDest" 2021/03/23 06:18:18 DEBUG : Couldn't decode error response: EOF 2021/03/23 06:18:19 DEBUG : Couldn't decode error response: EOF 2021/03/23 06:18:19 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2 path dst: Waiting for checks to finish 2021/03/23 06:18:19 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2 path dst: Waiting for transfers to finish 2021/03/23 06:18:19 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/03/23 06:18:19 INFO : one: Copied (new) 2021/03/23 06:18:19 DEBUG : Waiting for deletions to finish 2021/03/23 06:18:20 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2 path dst: Waiting for checks to finish 2021/03/23 06:18:20 DEBUG : Couldn't decode error response: EOF 2021/03/23 06:18:20 DEBUG : one: Sizes differ (src 5 vs dst 3) 2021/03/23 06:18:20 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2 path dst: Waiting for transfers to finish 2021/03/23 06:18:20 DEBUG : one: SHA-1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2021/03/23 06:18:20 INFO : one: Copied (replaced existing) 2021/03/23 06:18:20 DEBUG : Waiting for deletions to finish 2021/03/23 06:18:22 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2 path dst: Waiting for checks to finish 2021/03/23 06:18:23 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/03/23 06:18:23 DEBUG : one: Destination found in --compare-dest, skipping 2021/03/23 06:18:23 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2 path dst: Waiting for transfers to finish 2021/03/23 06:18:23 DEBUG : Waiting for deletions to finish 2021/03/23 06:18:23 INFO : There was nothing to transfer 2021/03/23 06:18:24 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/03/23 06:18:24 DEBUG : two: Destination found in --compare-dest, skipping 2021/03/23 06:18:24 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2 path dst: Waiting for checks to finish 2021/03/23 06:18:24 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/03/23 06:18:24 DEBUG : one: Destination found in --compare-dest, skipping 2021/03/23 06:18:24 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2 path dst: Waiting for transfers to finish 2021/03/23 06:18:24 DEBUG : Waiting for deletions to finish 2021/03/23 06:18:24 INFO : There was nothing to transfer 2021/03/23 06:18:24 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/03/23 06:18:24 DEBUG : two: Destination found in --compare-dest, skipping 2021/03/23 06:18:24 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2 path dst: Waiting for checks to finish 2021/03/23 06:18:24 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/03/23 06:18:24 DEBUG : one: Destination found in --compare-dest, skipping 2021/03/23 06:18:24 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2 path dst: Waiting for transfers to finish 2021/03/23 06:18:24 DEBUG : Waiting for deletions to finish 2021/03/23 06:18:24 INFO : There was nothing to transfer 2021/03/23 06:18:25 DEBUG : two: Sizes differ (src 5 vs dst 3) 2021/03/23 06:18:25 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2 path dst: Waiting for checks to finish 2021/03/23 06:18:25 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/03/23 06:18:25 DEBUG : one: Destination found in --compare-dest, skipping 2021/03/23 06:18:25 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2 path dst: Waiting for transfers to finish 2021/03/23 06:18:25 DEBUG : two: SHA-1 = a4be8877ad88f3109a6411985a1f359e10e2d930 OK 2021/03/23 06:18:25 INFO : two: Copied (new) 2021/03/23 06:18:25 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCompareDest (9.78s) === RUN TestSyncMultipleCompareDest run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" 2021/03/23 06:18:30 DEBUG : Creating backend with remote "TestB2:rclone-test-cexuxas0dafuxoq9kazucoc2/dest" 2021/03/23 06:18:31 DEBUG : Couldn't decode error response: EOF 2021/03/23 06:18:31 DEBUG : Creating backend with remote "TestB2:rclone-test-cexuxas0dafuxoq9kazucoc2/pre-dest1" 2021/03/23 06:18:31 DEBUG : Couldn't decode error response: EOF 2021/03/23 06:18:31 DEBUG : Creating backend with remote "TestB2:rclone-test-cexuxas0dafuxoq9kazucoc2/pre-dest2" 2021/03/23 06:18:32 DEBUG : Couldn't decode error response: EOF 2021/03/23 06:18:32 DEBUG : 1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/03/23 06:18:32 DEBUG : 1: Destination found in --compare-dest, skipping 2021/03/23 06:18:32 DEBUG : Couldn't decode error response: EOF 2021/03/23 06:18:32 DEBUG : 2: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/03/23 06:18:32 DEBUG : 2: Destination found in --compare-dest, skipping 2021/03/23 06:18:32 DEBUG : Couldn't decode error response: EOF 2021/03/23 06:18:33 DEBUG : Couldn't decode error response: EOF 2021/03/23 06:18:33 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2 path dest: Waiting for checks to finish 2021/03/23 06:18:33 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2 path dest: Waiting for transfers to finish 2021/03/23 06:18:34 DEBUG : 3: SHA-1 = 77de68daecd823babbb58edb1c8e14d7106e83bb OK 2021/03/23 06:18:34 INFO : 3: Copied (new) 2021/03/23 06:18:34 DEBUG : Waiting for deletions to finish --- PASS: TestSyncMultipleCompareDest (7.97s) === RUN TestSyncCopyDest run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" 2021/03/23 06:18:35 DEBUG : Creating backend with remote "TestB2:rclone-test-cexuxas0dafuxoq9kazucoc2/dst" 2021/03/23 06:18:36 DEBUG : Couldn't decode error response: EOF 2021/03/23 06:18:36 DEBUG : Creating backend with remote "TestB2:rclone-test-cexuxas0dafuxoq9kazucoc2/CopyDest" 2021/03/23 06:18:36 DEBUG : Couldn't decode error response: EOF 2021/03/23 06:18:36 DEBUG : Couldn't decode error response: EOF 2021/03/23 06:18:36 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2 path dst: Waiting for checks to finish 2021/03/23 06:18:36 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2 path dst: Waiting for transfers to finish 2021/03/23 06:18:38 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/03/23 06:18:38 INFO : one: Copied (new) 2021/03/23 06:18:38 DEBUG : Waiting for deletions to finish 2021/03/23 06:18:38 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2 path dst: Waiting for checks to finish 2021/03/23 06:18:38 DEBUG : Couldn't decode error response: EOF 2021/03/23 06:18:38 DEBUG : one: Sizes differ (src 5 vs dst 3) 2021/03/23 06:18:38 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2 path dst: Waiting for transfers to finish 2021/03/23 06:18:39 DEBUG : one: SHA-1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2021/03/23 06:18:39 INFO : one: Copied (replaced existing) 2021/03/23 06:18:39 DEBUG : Waiting for deletions to finish 2021/03/23 06:18:41 DEBUG : Creating backend with remote "TestB2:rclone-test-cexuxas0dafuxoq9kazucoc2/BackupDir" 2021/03/23 06:18:41 DEBUG : Couldn't decode error response: EOF 2021/03/23 06:18:41 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2 path dst: Waiting for checks to finish 2021/03/23 06:18:41 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/03/23 06:18:41 DEBUG : one: Sizes differ (src 5 vs dst 3) 2021/03/23 06:18:41 DEBUG : Couldn't decode error response: EOF 2021/03/23 06:18:43 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/03/23 06:18:43 INFO : one: Copied (server-side copy) 2021/03/23 06:18:44 INFO : one: Deleted 2021/03/23 06:18:44 DEBUG : one: SHA-1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2021/03/23 06:18:44 INFO : one: Copied (server-side copy) 2021/03/23 06:18:44 DEBUG : one: Destination found in --copy-dest, using server-side copy 2021/03/23 06:18:44 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2 path dst: Waiting for transfers to finish 2021/03/23 06:18:44 DEBUG : Waiting for deletions to finish 2021/03/23 06:18:51 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/03/23 06:18:52 DEBUG : two: SHA-1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2021/03/23 06:18:52 INFO : two: Copied (server-side copy) 2021/03/23 06:18:52 DEBUG : two: Destination found in --copy-dest, using server-side copy 2021/03/23 06:18:52 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2 path dst: Waiting for checks to finish 2021/03/23 06:18:52 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/03/23 06:18:52 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/03/23 06:18:52 DEBUG : one: Unchanged skipping 2021/03/23 06:18:52 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2 path dst: Waiting for transfers to finish 2021/03/23 06:18:52 DEBUG : Waiting for deletions to finish 2021/03/23 06:18:53 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2 path dst: Waiting for checks to finish 2021/03/23 06:18:53 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/03/23 06:18:53 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/03/23 06:18:53 DEBUG : one: Unchanged skipping 2021/03/23 06:18:53 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/03/23 06:18:53 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/03/23 06:18:53 DEBUG : two: Unchanged skipping 2021/03/23 06:18:53 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2 path dst: Waiting for transfers to finish 2021/03/23 06:18:53 DEBUG : Waiting for deletions to finish 2021/03/23 06:18:53 INFO : There was nothing to transfer 2021/03/23 06:18:55 DEBUG : three: Sizes differ (src 7 vs dst 5) 2021/03/23 06:18:55 DEBUG : three: Destination not found in --copy-dest 2021/03/23 06:18:55 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2 path dst: Waiting for checks to finish 2021/03/23 06:18:55 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/03/23 06:18:55 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/03/23 06:18:55 DEBUG : one: Unchanged skipping 2021/03/23 06:18:55 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/03/23 06:18:55 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/03/23 06:18:55 DEBUG : two: Unchanged skipping 2021/03/23 06:18:55 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2 path dst: Waiting for transfers to finish 2021/03/23 06:18:55 DEBUG : three: SHA-1 = 370e613335f3cf2f19b2a2d35a86402f55c659f8 OK 2021/03/23 06:18:55 INFO : three: Copied (new) 2021/03/23 06:18:55 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCopyDest (23.16s) === RUN TestSyncBackupDir run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" 2021/03/23 06:19:01 DEBUG : Creating backend with remote "TestB2:rclone-test-cexuxas0dafuxoq9kazucoc2/dst" 2021/03/23 06:19:03 DEBUG : Couldn't decode error response: EOF 2021/03/23 06:19:03 DEBUG : Creating backend with remote "TestB2:rclone-test-cexuxas0dafuxoq9kazucoc2/backup" 2021/03/23 06:19:03 DEBUG : Couldn't decode error response: EOF 2021/03/23 06:19:03 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/03/23 06:19:03 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/03/23 06:19:03 DEBUG : two: Unchanged skipping 2021/03/23 06:19:03 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2 path dst: Waiting for checks to finish 2021/03/23 06:19:03 DEBUG : Couldn't decode error response: EOF 2021/03/23 06:19:05 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/03/23 06:19:05 INFO : one: Copied (server-side copy) 2021/03/23 06:19:07 INFO : one: Deleted 2021/03/23 06:19:07 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2 path dst: Waiting for transfers to finish 2021/03/23 06:19:08 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/03/23 06:19:08 INFO : one: Copied (new) 2021/03/23 06:19:08 DEBUG : Waiting for deletions to finish 2021/03/23 06:19:09 DEBUG : Couldn't decode error response: EOF 2021/03/23 06:19:09 DEBUG : three.txt: SHA-1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/03/23 06:19:09 INFO : three.txt: Copied (server-side copy) 2021/03/23 06:19:10 INFO : three.txt: Deleted 2021/03/23 06:19:10 INFO : three.txt: Moved into backup dir 2021/03/23 06:19:11 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2 path dst: Waiting for checks to finish 2021/03/23 06:19:11 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/03/23 06:19:11 DEBUG : two: Unchanged skipping 2021/03/23 06:19:11 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/03/23 06:19:12 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/03/23 06:19:12 INFO : one: Copied (server-side copy) 2021/03/23 06:19:13 INFO : one: Deleted 2021/03/23 06:19:13 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2 path dst: Waiting for transfers to finish 2021/03/23 06:19:13 DEBUG : one: SHA-1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2021/03/23 06:19:13 INFO : one: Copied (new) 2021/03/23 06:19:13 DEBUG : Waiting for deletions to finish 2021/03/23 06:19:14 DEBUG : three.txt: SHA-1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2021/03/23 06:19:14 INFO : three.txt: Copied (server-side copy) 2021/03/23 06:19:15 INFO : three.txt: Deleted 2021/03/23 06:19:15 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (18.56s) === RUN TestSyncBackupDirWithSuffix run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" 2021/03/23 06:19:19 DEBUG : Creating backend with remote "TestB2:rclone-test-cexuxas0dafuxoq9kazucoc2/dst" 2021/03/23 06:19:20 DEBUG : Couldn't decode error response: EOF 2021/03/23 06:19:20 DEBUG : Creating backend with remote "TestB2:rclone-test-cexuxas0dafuxoq9kazucoc2/backup" 2021/03/23 06:19:21 DEBUG : Couldn't decode error response: EOF 2021/03/23 06:19:21 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/03/23 06:19:21 DEBUG : two: Unchanged skipping 2021/03/23 06:19:21 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/03/23 06:19:21 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2 path dst: Waiting for checks to finish 2021/03/23 06:19:21 DEBUG : Couldn't decode error response: EOF 2021/03/23 06:19:24 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/03/23 06:19:24 INFO : one: Copied (server-side copy) to: one.bak 2021/03/23 06:19:25 INFO : one: Deleted 2021/03/23 06:19:25 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2 path dst: Waiting for transfers to finish 2021/03/23 06:19:26 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/03/23 06:19:26 INFO : one: Copied (new) 2021/03/23 06:19:26 DEBUG : Waiting for deletions to finish 2021/03/23 06:19:26 DEBUG : Couldn't decode error response: EOF 2021/03/23 06:19:26 DEBUG : three.txt: SHA-1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/03/23 06:19:26 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2021/03/23 06:19:27 INFO : three.txt: Deleted 2021/03/23 06:19:27 INFO : three.txt: Moved into backup dir 2021/03/23 06:19:28 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/03/23 06:19:28 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/03/23 06:19:28 DEBUG : two: Unchanged skipping 2021/03/23 06:19:28 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2 path dst: Waiting for checks to finish 2021/03/23 06:19:28 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/03/23 06:19:28 INFO : one: Copied (server-side copy) to: one.bak 2021/03/23 06:19:29 INFO : one: Deleted 2021/03/23 06:19:29 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2 path dst: Waiting for transfers to finish 2021/03/23 06:19:29 DEBUG : one: SHA-1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2021/03/23 06:19:29 INFO : one: Copied (new) 2021/03/23 06:19:29 DEBUG : Waiting for deletions to finish 2021/03/23 06:19:30 DEBUG : three.txt: SHA-1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2021/03/23 06:19:30 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2021/03/23 06:19:30 INFO : three.txt: Deleted 2021/03/23 06:19:30 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (16.07s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" 2021/03/23 06:19:36 DEBUG : Creating backend with remote "TestB2:rclone-test-cexuxas0dafuxoq9kazucoc2/dst" 2021/03/23 06:19:37 DEBUG : Couldn't decode error response: EOF 2021/03/23 06:19:37 DEBUG : Creating backend with remote "TestB2:rclone-test-cexuxas0dafuxoq9kazucoc2/backup" 2021/03/23 06:19:37 DEBUG : Couldn't decode error response: EOF 2021/03/23 06:19:38 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/03/23 06:19:38 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/03/23 06:19:38 DEBUG : two: Unchanged skipping 2021/03/23 06:19:38 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2 path dst: Waiting for checks to finish 2021/03/23 06:19:38 DEBUG : Couldn't decode error response: EOF 2021/03/23 06:19:39 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/03/23 06:19:39 INFO : one: Copied (server-side copy) to: one-2019-01-01 2021/03/23 06:19:39 INFO : one: Deleted 2021/03/23 06:19:39 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2 path dst: Waiting for transfers to finish 2021/03/23 06:19:40 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/03/23 06:19:40 INFO : one: Copied (new) 2021/03/23 06:19:40 DEBUG : Waiting for deletions to finish 2021/03/23 06:19:40 DEBUG : Couldn't decode error response: EOF 2021/03/23 06:19:41 DEBUG : three.txt: SHA-1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/03/23 06:19:41 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2021/03/23 06:19:41 INFO : three.txt: Deleted 2021/03/23 06:19:41 INFO : three.txt: Moved into backup dir 2021/03/23 06:19:43 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/03/23 06:19:43 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/03/23 06:19:43 DEBUG : two: Unchanged skipping 2021/03/23 06:19:43 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2 path dst: Waiting for checks to finish 2021/03/23 06:19:45 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/03/23 06:19:45 INFO : one: Copied (server-side copy) to: one-2019-01-01 2021/03/23 06:19:47 INFO : one: Deleted 2021/03/23 06:19:47 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2 path dst: Waiting for transfers to finish 2021/03/23 06:19:47 DEBUG : one: SHA-1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2021/03/23 06:19:47 INFO : one: Copied (new) 2021/03/23 06:19:47 DEBUG : Waiting for deletions to finish 2021/03/23 06:19:48 DEBUG : three.txt: SHA-1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2021/03/23 06:19:48 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2021/03/23 06:19:49 INFO : three.txt: Deleted 2021/03/23 06:19:49 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (20.17s) === RUN TestSyncBackupDirSuffixOnly run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" 2021/03/23 06:19:57 DEBUG : Creating backend with remote "TestB2:rclone-test-cexuxas0dafuxoq9kazucoc2/dst" 2021/03/23 06:19:58 DEBUG : Couldn't decode error response: EOF 2021/03/23 06:19:58 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/03/23 06:19:58 DEBUG : two: Unchanged skipping 2021/03/23 06:19:58 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/03/23 06:19:58 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2 path dst: Waiting for checks to finish 2021/03/23 06:19:58 DEBUG : Couldn't decode error response: EOF 2021/03/23 06:19:59 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/03/23 06:19:59 INFO : one: Copied (server-side copy) to: one.bak 2021/03/23 06:19:59 INFO : one: Deleted 2021/03/23 06:19:59 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2 path dst: Waiting for transfers to finish 2021/03/23 06:20:01 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/03/23 06:20:01 INFO : one: Copied (new) 2021/03/23 06:20:01 DEBUG : Waiting for deletions to finish 2021/03/23 06:20:02 DEBUG : Couldn't decode error response: EOF 2021/03/23 06:20:02 DEBUG : three.txt: SHA-1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/03/23 06:20:02 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2021/03/23 06:20:03 INFO : three.txt: Deleted 2021/03/23 06:20:03 INFO : three.txt: Moved into backup dir 2021/03/23 06:20:08 DEBUG : pacer: low level retry 1/10 (error Post "https://api001.backblazeb2.com/b2api/v1/b2_list_file_names": EOF) 2021/03/23 06:20:08 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2021/03/23 06:20:08 DEBUG : pacer: Reducing sleep to 10ms 2021/03/23 06:20:09 DEBUG : one.bak: Excluded 2021/03/23 06:20:09 DEBUG : three.txt.bak: Excluded 2021/03/23 06:20:09 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/03/23 06:20:09 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/03/23 06:20:09 DEBUG : two: Unchanged skipping 2021/03/23 06:20:09 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2 path dst: Waiting for checks to finish 2021/03/23 06:20:10 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/03/23 06:20:10 INFO : one: Copied (server-side copy) to: one.bak 2021/03/23 06:20:10 INFO : one: Deleted 2021/03/23 06:20:10 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2 path dst: Waiting for transfers to finish 2021/03/23 06:20:12 DEBUG : one: SHA-1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2021/03/23 06:20:12 INFO : one: Copied (new) 2021/03/23 06:20:12 DEBUG : Waiting for deletions to finish 2021/03/23 06:20:12 DEBUG : three.txt: SHA-1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2021/03/23 06:20:12 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2021/03/23 06:20:13 INFO : three.txt: Deleted 2021/03/23 06:20:13 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirSuffixOnly (21.90s) === RUN TestSyncSuffix run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" 2021/03/23 06:20:18 DEBUG : Creating backend with remote "TestB2:rclone-test-cexuxas0dafuxoq9kazucoc2/dst" 2021/03/23 06:20:19 DEBUG : Couldn't decode error response: EOF 2021/03/23 06:20:19 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/03/23 06:20:19 DEBUG : Couldn't decode error response: EOF 2021/03/23 06:20:21 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/03/23 06:20:21 INFO : one: Copied (server-side copy) to: one.bak 2021/03/23 06:20:21 INFO : one: Deleted 2021/03/23 06:20:22 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/03/23 06:20:22 INFO : one: Copied (new) 2021/03/23 06:20:22 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/03/23 06:20:22 DEBUG : two: Unchanged skipping 2021/03/23 06:20:23 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2021/03/23 06:20:23 DEBUG : Couldn't decode error response: EOF 2021/03/23 06:20:23 DEBUG : three.txt: SHA-1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/03/23 06:20:23 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2021/03/23 06:20:23 INFO : three.txt: Deleted 2021/03/23 06:20:24 DEBUG : three.txt: SHA-1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2021/03/23 06:20:24 INFO : three.txt: Copied (new) 2021/03/23 06:20:24 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/03/23 06:20:25 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/03/23 06:20:25 INFO : one: Copied (server-side copy) to: one.bak 2021/03/23 06:20:26 INFO : one: Deleted 2021/03/23 06:20:26 DEBUG : one: SHA-1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2021/03/23 06:20:26 INFO : one: Copied (new) 2021/03/23 06:20:27 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/03/23 06:20:27 DEBUG : two: Unchanged skipping 2021/03/23 06:20:27 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2021/03/23 06:20:27 DEBUG : three.txt: SHA-1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2021/03/23 06:20:27 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2021/03/23 06:20:27 INFO : three.txt: Deleted 2021/03/23 06:20:28 DEBUG : three.txt: SHA-1 = 476e1707cbd7f0497a7dc4d44f0b07b8566ffc30 OK 2021/03/23 06:20:28 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (16.86s) === RUN TestSyncSuffixKeepExtension run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" 2021/03/23 06:20:34 DEBUG : Creating backend with remote "TestB2:rclone-test-cexuxas0dafuxoq9kazucoc2/dst" 2021/03/23 06:20:36 DEBUG : Couldn't decode error response: EOF 2021/03/23 06:20:36 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/03/23 06:20:36 DEBUG : Couldn't decode error response: EOF 2021/03/23 06:20:37 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/03/23 06:20:37 INFO : one: Copied (server-side copy) to: one-2019-01-01 2021/03/23 06:20:38 INFO : one: Deleted 2021/03/23 06:20:38 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/03/23 06:20:38 INFO : one: Copied (new) 2021/03/23 06:20:38 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/03/23 06:20:38 DEBUG : two: Unchanged skipping 2021/03/23 06:20:39 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2021/03/23 06:20:39 DEBUG : Couldn't decode error response: EOF 2021/03/23 06:20:39 DEBUG : three.txt: SHA-1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/03/23 06:20:39 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2021/03/23 06:20:40 INFO : three.txt: Deleted 2021/03/23 06:20:40 DEBUG : three.txt: SHA-1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2021/03/23 06:20:40 INFO : three.txt: Copied (new) 2021/03/23 06:20:40 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/03/23 06:20:41 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/03/23 06:20:41 INFO : one: Copied (server-side copy) to: one-2019-01-01 2021/03/23 06:20:41 INFO : one: Deleted 2021/03/23 06:20:42 DEBUG : one: SHA-1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2021/03/23 06:20:42 INFO : one: Copied (new) 2021/03/23 06:20:42 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/03/23 06:20:42 DEBUG : two: Unchanged skipping 2021/03/23 06:20:42 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2021/03/23 06:20:43 DEBUG : three.txt: SHA-1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2021/03/23 06:20:43 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2021/03/23 06:20:43 INFO : three.txt: Deleted 2021/03/23 06:20:44 DEBUG : three.txt: SHA-1 = 476e1707cbd7f0497a7dc4d44f0b07b8566ffc30 OK 2021/03/23 06:20:44 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (14.72s) === RUN TestSyncUTFNorm run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" 2021/03/23 06:20:48 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for checks to finish 2021/03/23 06:20:48 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2021/03/23 06:20:48 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for transfers to finish 2021/03/23 06:20:49 DEBUG : Testêé: SHA-1 = a54d88e06612d820bc3be72877c74f257b561b19 OK 2021/03/23 06:20:49 INFO : Testêé: Copied (replaced existing) to: Testêé 2021/03/23 06:20:49 DEBUG : Waiting for deletions to finish --- PASS: TestSyncUTFNorm (4.04s) === RUN TestSyncImmutable run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" 2021/03/23 06:20:51 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for checks to finish 2021/03/23 06:20:51 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for transfers to finish 2021/03/23 06:20:51 DEBUG : existing: SHA-1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2021/03/23 06:20:51 INFO : existing: Copied (new) 2021/03/23 06:20:51 DEBUG : Waiting for deletions to finish 2021/03/23 06:20:52 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for checks to finish 2021/03/23 06:20:52 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2021/03/23 06:20:52 ERROR : existing: Source and destination exist but do not match: immutable file modified 2021/03/23 06:20:52 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for transfers to finish 2021/03/23 06:20:52 ERROR : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: not deleting files as there were IO errors 2021/03/23 06:20:52 ERROR : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: not deleting directories as there were IO errors --- PASS: TestSyncImmutable (1.92s) === RUN TestSyncIgnoreCase run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" 2021/03/23 06:20:53 DEBUG : existing: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/03/23 06:20:53 DEBUG : existing: Unchanged skipping 2021/03/23 06:20:53 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for checks to finish 2021/03/23 06:20:53 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Waiting for transfers to finish 2021/03/23 06:20:53 DEBUG : Waiting for deletions to finish 2021/03/23 06:20:53 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreCase (1.56s) === RUN TestMaxTransfer === RUN TestMaxTransfer/Hard run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" sync_test.go:1941: This test only runs on local === RUN TestMaxTransfer/Soft run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" sync_test.go:1941: This test only runs on local === RUN TestMaxTransfer/Cautious run.go:176: Remote "B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2", Local "Local file system at /tmp/rclone189872454", Modify Window "1ms" sync_test.go:1941: This test only runs on local --- PASS: TestMaxTransfer (0.88s) --- SKIP: TestMaxTransfer/Hard (0.30s) --- SKIP: TestMaxTransfer/Soft (0.29s) --- SKIP: TestMaxTransfer/Cautious (0.29s) PASS 2021/03/23 06:20:55 DEBUG : B2 bucket rclone-test-cexuxas0dafuxoq9kazucoc2: Purge remote 2021/03/23 06:20:56 DEBUG : BackupDir/one: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f112bb755d2adff3e_d20210323_m061856_c001_v0001033_t0034") 2021/03/23 06:20:56 DEBUG : BackupDir/one: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1164f3d4c65fc7c5_d20210323_m061842_c001_v0001011_t0017") 2021/03/23 06:20:56 DEBUG : CompareDest/one: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1100c4a3b305a958_d20210323_m061826_c001_v0001136_t0019") 2021/03/23 06:20:56 DEBUG : CompareDest/one: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f112455a8e14deeb1_d20210323_m061822_c001_v0001044_t0034") 2021/03/23 06:20:56 DEBUG : CompareDest/two: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f117a49026fdfa0ef_d20210323_m061826_c001_v0001039_t0039") 2021/03/23 06:20:56 DEBUG : CompareDest/two: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f1292661f7_d20210323_m061823_c001_v0001040_t0021") 2021/03/23 06:20:56 DEBUG : CopyDest/one: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f10675997055aacf3_d20210323_m061856_c001_v0001044_t0009") 2021/03/23 06:20:56 DEBUG : CopyDest/one: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f129266223_d20210323_m061840_c001_v0001040_t0021") 2021/03/23 06:20:56 DEBUG : CopyDest/three: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1186895b9fb3ba11_d20210323_m061857_c001_v0001090_t0021") 2021/03/23 06:20:56 DEBUG : CopyDest/three: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f129266249_d20210323_m061854_c001_v0001040_t0021") 2021/03/23 06:20:56 DEBUG : CopyDest/two: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f115c850fccac6b50_d20210323_m061857_c001_v0001146_t0033") 2021/03/23 06:20:56 DEBUG : CopyDest/two: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f112455a8e14def11_d20210323_m061844_c001_v0001044_t0034") 2021/03/23 06:20:56 DEBUG : EXISTING: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f113327735c779cd2_d20210323_m062054_c001_v0001137_t0050") 2021/03/23 06:20:56 DEBUG : EXISTING: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f129266380_d20210323_m062053_c001_v0001040_t0021") 2021/03/23 06:20:56 DEBUG : Testêé: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f100ffe4509f20b02_d20210323_m062050_c001_v0001090_t0009") 2021/03/23 06:20:56 DEBUG : Testêé: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f129266374_d20210323_m062048_c001_v0001040_t0021") 2021/03/23 06:20:56 DEBUG : Testêé: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f112455a8e14df0db_d20210323_m062047_c001_v0001044_t0034") 2021/03/23 06:20:56 DEBUG : a/potato2: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f119e31a2d958a1a6_d20210323_m061631_c001_v0001032_t0018") 2021/03/23 06:20:56 DEBUG : a/potato2: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f12926607c_d20210323_m061630_c001_v0001040_t0021") 2021/03/23 06:20:56 DEBUG : a/potato2: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f119cd9b714fc2c44_d20210323_m061627_c001_v0001146_t0057") 2021/03/23 06:20:56 DEBUG : a/potato2: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f129266066_d20210323_m061624_c001_v0001040_t0021") 2021/03/23 06:20:56 DEBUG : a/potato2: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f118849efde3be5a4_d20210323_m061549_c001_v0001130_t0040") 2021/03/23 06:20:56 DEBUG : a/potato2: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f129265ff9_d20210323_m061547_c001_v0001040_t0021") 2021/03/23 06:20:56 DEBUG : b/potato: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f109f8a894fa48eef_d20210323_m061634_c001_v0001134_t0019") 2021/03/23 06:20:57 DEBUG : b/potato: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f129266074_d20210323_m061628_c001_v0001040_t0021") 2021/03/23 06:20:57 DEBUG : b/potato: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f116c8fb04d7f9831_d20210323_m061624_c001_v0001150_t0026") 2021/03/23 06:20:57 DEBUG : b/potato: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f12926605f_d20210323_m061623_c001_v0001040_t0021") 2021/03/23 06:20:57 DEBUG : b/potato: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1124e3c7e6179af5_d20210323_m061548_c001_v0001136_t0028") 2021/03/23 06:20:57 DEBUG : b/potato: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f129265ff1_d20210323_m061543_c001_v0001040_t0021") 2021/03/23 06:20:57 DEBUG : backup/one: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f102eaf0485ee9a53_d20210323_m061915_c001_v0001144_t0020") 2021/03/23 06:20:57 DEBUG : backup/one: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1062a055e3594066_d20210323_m061911_c001_v0001144_t0044") 2021/03/23 06:20:57 DEBUG : backup/one: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1071eaae76dfaa7e_d20210323_m061904_c001_v0001015_t0026") 2021/03/23 06:20:57 DEBUG : backup/one-2019-01-01: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1074eaf5c21e5ae6_d20210323_m061950_c001_v0001032_t0019") 2021/03/23 06:20:57 DEBUG : backup/one-2019-01-01: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f10548d83b8721060_d20210323_m061943_c001_v0001039_t0041") 2021/03/23 06:20:57 DEBUG : backup/one-2019-01-01: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f112d8ba16b59f3f4_d20210323_m061939_c001_v0001038_t0022") 2021/03/23 06:20:57 DEBUG : backup/one.bak: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f109d6b443fb5e3e4_d20210323_m061930_c001_v0001145_t0045") 2021/03/23 06:20:57 DEBUG : backup/one.bak: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1077f0ac0263cea1_d20210323_m061928_c001_v0001136_t0016") 2021/03/23 06:20:57 DEBUG : backup/one.bak: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f11428a2e93417682_d20210323_m061922_c001_v0001090_t0052") 2021/03/23 06:20:57 DEBUG : backup/three-2019-01-01.txt: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f109ae25dc92876a2_d20210323_m061951_c001_v0001146_t0032") 2021/03/23 06:20:57 DEBUG : backup/three-2019-01-01.txt: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f112eb9f2e6709810_d20210323_m061947_c001_v0001113_t0010") 2021/03/23 06:20:57 DEBUG : backup/three-2019-01-01.txt: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f113a781ec9a6e593_d20210323_m061940_c001_v0001113_t0015") 2021/03/23 06:20:57 DEBUG : backup/three.txt: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f114c19eefaf2a019_d20210323_m061916_c001_v0001130_t0039") 2021/03/23 06:20:57 DEBUG : backup/three.txt: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1196d6586bec7af2_d20210323_m061914_c001_v0001144_t0048") 2021/03/23 06:20:57 DEBUG : backup/three.txt: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f112a5c8e810febc0_d20210323_m061909_c001_v0001130_t0002") 2021/03/23 06:20:57 DEBUG : backup/three.txt.bak: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f104e5aa8b2571241_d20210323_m061931_c001_v0001146_t0053") 2021/03/23 06:20:57 DEBUG : backup/three.txt.bak: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f101f64b29adbdaa8_d20210323_m061929_c001_v0001134_t0050") 2021/03/23 06:20:57 DEBUG : backup/three.txt.bak: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f117a9e7d7dccb02c_d20210323_m061926_c001_v0001153_t0011") 2021/03/23 06:20:57 DEBUG : c/non empty space: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1109f0d361594728_d20210323_m061634_c001_v0001144_t0032") 2021/03/23 06:20:58 DEBUG : c/non empty space: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f12926607a_d20210323_m061629_c001_v0001040_t0021") 2021/03/23 06:20:58 DEBUG : c/non empty space: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f104dd21e7b6a044a_d20210323_m061627_c001_v0001039_t0024") 2021/03/23 06:20:58 DEBUG : c/non empty space: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f129266064_d20210323_m061623_c001_v0001040_t0021") 2021/03/23 06:20:58 DEBUG : c/non empty space: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1066ecff892bc9fe_d20210323_m061549_c001_v0001145_t0049") 2021/03/23 06:20:58 DEBUG : c/non empty space: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f129265ff5_d20210323_m061543_c001_v0001040_t0021") 2021/03/23 06:20:58 DEBUG : check sum: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f101e3fafe92d6af6_d20210323_m061534_c001_v0001037_t0044") 2021/03/23 06:20:58 DEBUG : check sum: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f129265fd2_d20210323_m061533_c001_v0001040_t0021") 2021/03/23 06:20:58 DEBUG : dest/3: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f119edb7cf5fa4866_d20210323_m061834_c001_v0001150_t0002") 2021/03/23 06:20:58 DEBUG : dest/3: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f105b3fb8f67faf9a_d20210323_m061833_c001_v0001145_t0034") 2021/03/23 06:20:58 DEBUG : dst/one: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f108770ae723d38f3_d20210323_m062044_c001_v0001146_t0039") 2021/03/23 06:20:58 DEBUG : dst/one: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f114cd2feea99441a_d20210323_m062042_c001_v0001134_t0049") 2021/03/23 06:20:58 DEBUG : dst/one: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1050f417da73ec39_d20210323_m062041_c001_v0001113_t0008") 2021/03/23 06:20:58 DEBUG : dst/one: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f114cd2feea994404_d20210323_m062038_c001_v0001134_t0049") 2021/03/23 06:20:58 DEBUG : dst/one: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f10618ff22854b91a_d20210323_m062037_c001_v0001154_t0021") 2021/03/23 06:20:58 DEBUG : dst/one: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f129266349_d20210323_m062032_c001_v0001040_t0021") 2021/03/23 06:20:58 DEBUG : dst/one: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f108f0aed3b5311ee_d20210323_m062028_c001_v0001004_t0000") 2021/03/23 06:20:58 DEBUG : dst/one: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f113bbfbf95d4b8b6_d20210323_m062026_c001_v0001131_t0015") 2021/03/23 06:20:58 DEBUG : dst/one: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f10439e5e2c7c98c4_d20210323_m062025_c001_v0001120_t0035") 2021/03/23 06:20:58 DEBUG : dst/one: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f113bbfbf95d4b8a2_d20210323_m062022_c001_v0001131_t0015") 2021/03/23 06:20:58 DEBUG : dst/one: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1045035f4f568ef0_d20210323_m062021_c001_v0001154_t0001") 2021/03/23 06:20:58 DEBUG : dst/one: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f112455a8e14df067_d20210323_m062016_c001_v0001044_t0034") 2021/03/23 06:20:58 DEBUG : dst/one: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1066bac24c9f81c5_d20210323_m062013_c001_v0001150_t0011") 2021/03/23 06:20:58 DEBUG : dst/one: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f112735831f4094c2_d20210323_m062011_c001_v0001145_t0028") 2021/03/23 06:20:58 DEBUG : dst/one: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1118fcdf6e53548e_d20210323_m062010_c001_v0001145_t0057") 2021/03/23 06:20:58 DEBUG : dst/one: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f112735831f409465_d20210323_m062000_c001_v0001145_t0028") 2021/03/23 06:20:58 DEBUG : dst/one: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f118894e141f0c75e_d20210323_m061959_c001_v0001146_t0034") 2021/03/23 06:20:59 DEBUG : dst/one: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f112455a8e14df017_d20210323_m061954_c001_v0001044_t0034") 2021/03/23 06:20:59 DEBUG : dst/one: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f10395a20e55d7dce_d20210323_m061952_c001_v0001021_t0034") 2021/03/23 06:20:59 DEBUG : dst/one: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1056243e2219c536_d20210323_m061947_c001_v0001019_t0041") 2021/03/23 06:20:59 DEBUG : dst/one: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1043fec750082aca_d20210323_m061946_c001_v0001113_t0029") 2021/03/23 06:20:59 DEBUG : dst/one: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1056243e2219c530_d20210323_m061940_c001_v0001019_t0041") 2021/03/23 06:20:59 DEBUG : dst/one: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1187c65df0204767_d20210323_m061939_c001_v0001137_t0034") 2021/03/23 06:20:59 DEBUG : dst/one: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f112455a8e14defcf_d20210323_m061934_c001_v0001044_t0034") 2021/03/23 06:20:59 DEBUG : dst/one: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f11616de6277f88e7_d20210323_m061932_c001_v0001006_t0020") 2021/03/23 06:20:59 DEBUG : dst/one: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f105931ac780629ed_d20210323_m061929_c001_v0001144_t0027") 2021/03/23 06:20:59 DEBUG : dst/one: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f117cf1729f9eba94_d20210323_m061928_c001_v0001150_t0025") 2021/03/23 06:20:59 DEBUG : dst/one: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f105931ac780629cf_d20210323_m061925_c001_v0001144_t0027") 2021/03/23 06:20:59 DEBUG : dst/one: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f112608e2b09ee2c1_d20210323_m061924_c001_v0001032_t0030") 2021/03/23 06:20:59 DEBUG : dst/one: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f112455a8e14def8c_d20210323_m061917_c001_v0001044_t0034") 2021/03/23 06:20:59 DEBUG : dst/one: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1088bcdf746c2b52_d20210323_m061916_c001_v0001145_t0014") 2021/03/23 06:20:59 DEBUG : dst/one: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1109f0d361594c69_d20210323_m061913_c001_v0001144_t0033") 2021/03/23 06:20:59 DEBUG : dst/one: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1195803b93ce1667_d20210323_m061912_c001_v0001153_t0004") 2021/03/23 06:20:59 DEBUG : dst/one: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1109f0d361594c3c_d20210323_m061908_c001_v0001144_t0033") 2021/03/23 06:20:59 DEBUG : dst/one: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1009740a8d6cc780_d20210323_m061905_c001_v0001038_t0054") 2021/03/23 06:20:59 DEBUG : dst/one: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f112455a8e14def51_d20210323_m061859_c001_v0001044_t0034") 2021/03/23 06:20:59 DEBUG : dst/one: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f101ae932f9b0424e_d20210323_m061857_c001_v0001150_t0005") 2021/03/23 06:20:59 DEBUG : dst/one: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1066ecff892bcf3d_d20210323_m061844_c001_v0001145_t0034") 2021/03/23 06:20:59 DEBUG : dst/one: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f10135a788fbcfba6_d20210323_m061843_c001_v0001120_t0048") 2021/03/23 06:20:59 DEBUG : dst/one: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f112455a8e14deef6_d20210323_m061839_c001_v0001044_t0034") 2021/03/23 06:20:59 DEBUG : dst/one: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f114a18c35b98e383_d20210323_m061838_c001_v0001033_t0033") 2021/03/23 06:20:59 DEBUG : dst/one: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f114a18c35b98e381_d20210323_m061837_c001_v0001033_t0033") 2021/03/23 06:21:00 DEBUG : dst/one: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1192efd3cf684708_d20210323_m061826_c001_v0001145_t0049") 2021/03/23 06:21:00 DEBUG : dst/one: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f1292661ef_d20210323_m061821_c001_v0001040_t0021") 2021/03/23 06:21:00 DEBUG : dst/one: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f109c928badfbf024_d20210323_m061820_c001_v0001130_t0042") 2021/03/23 06:21:00 DEBUG : dst/one: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f109c928badfbf01c_d20210323_m061819_c001_v0001130_t0042") 2021/03/23 06:21:00 DEBUG : dst/one-2019-01-01: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1161af000b3579d6_d20210323_m062044_c001_v0001153_t0011") 2021/03/23 06:21:00 DEBUG : dst/one-2019-01-01: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f100997f072900018_d20210323_m062041_c001_v0001153_t0015") 2021/03/23 06:21:00 DEBUG : dst/one-2019-01-01: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f107188d0b3a3c637_d20210323_m062037_c001_v0001145_t0037") 2021/03/23 06:21:00 DEBUG : dst/one.bak: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f10877d7ea2e57964_d20210323_m062028_c001_v0001042_t0047") 2021/03/23 06:21:00 DEBUG : dst/one.bak: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f10976203e154e3e2_d20210323_m062024_c001_v0001153_t0025") 2021/03/23 06:21:00 DEBUG : dst/one.bak: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f103f18ef98c39340_d20210323_m062020_c001_v0001000_t0011") 2021/03/23 06:21:00 DEBUG : dst/one.bak: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1189768a9bedb0fd_d20210323_m062014_c001_v0001044_t0036") 2021/03/23 06:21:00 DEBUG : dst/one.bak: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f11816833d165fb19_d20210323_m062009_c001_v0001154_t0008") 2021/03/23 06:21:00 DEBUG : dst/one.bak: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f113f6692bb1e4729_d20210323_m061959_c001_v0001038_t0051") 2021/03/23 06:21:00 DEBUG : dst/three: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f11666013c0e3011e_d20210323_m061857_c001_v0001131_t0035") 2021/03/23 06:21:00 DEBUG : dst/three: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f114a18c35b98e39e_d20210323_m061855_c001_v0001033_t0033") 2021/03/23 06:21:00 DEBUG : dst/three-2019-01-01.txt: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f117b0a02e5f6a54d_d20210323_m062045_c001_v0001146_t0026") 2021/03/23 06:21:00 DEBUG : dst/three-2019-01-01.txt: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1013248ba36db70a_d20210323_m062042_c001_v0001137_t0023") 2021/03/23 06:21:00 DEBUG : dst/three-2019-01-01.txt: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f11172799a11a33e7_d20210323_m062039_c001_v0001153_t0020") 2021/03/23 06:21:00 DEBUG : dst/three.txt: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f10542b839ce8d495_d20210323_m062045_c001_v0001128_t0011") 2021/03/23 06:21:00 DEBUG : dst/three.txt: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f114cd2feea99442b_d20210323_m062044_c001_v0001134_t0049") 2021/03/23 06:21:00 DEBUG : dst/three.txt: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f11723ddd2c7c4fde_d20210323_m062043_c001_v0001144_t0056") 2021/03/23 06:21:00 DEBUG : dst/three.txt: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f114cd2feea99440a_d20210323_m062040_c001_v0001134_t0049") 2021/03/23 06:21:00 DEBUG : dst/three.txt: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f102eaf0485ee9dc2_d20210323_m062039_c001_v0001144_t0022") 2021/03/23 06:21:00 DEBUG : dst/three.txt: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f129266352_d20210323_m062034_c001_v0001040_t0021") 2021/03/23 06:21:00 DEBUG : dst/three.txt: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f115f0eaec2c28b2d_d20210323_m062030_c001_v0001131_t0057") 2021/03/23 06:21:01 DEBUG : dst/three.txt: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f113bbfbf95d4b8b8_d20210323_m062028_c001_v0001131_t0015") 2021/03/23 06:21:01 DEBUG : dst/three.txt: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f115df905c8c9ad9b_d20210323_m062027_c001_v0001153_t0004") 2021/03/23 06:21:01 DEBUG : dst/three.txt: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f113bbfbf95d4b8a8_d20210323_m062024_c001_v0001131_t0015") 2021/03/23 06:21:01 DEBUG : dst/three.txt: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1066bac24c9f8225_d20210323_m062023_c001_v0001150_t0050") 2021/03/23 06:21:01 DEBUG : dst/three.txt: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f112455a8e14df072_d20210323_m062017_c001_v0001044_t0034") 2021/03/23 06:21:01 DEBUG : dst/three.txt: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f105931ac78062b79_d20210323_m062012_c001_v0001144_t0052") 2021/03/23 06:21:01 DEBUG : dst/three.txt: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f1292662fb_d20210323_m062003_c001_v0001040_t0021") 2021/03/23 06:21:01 DEBUG : dst/three.txt: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f102da7f866360201_d20210323_m062002_c001_v0001113_t0057") 2021/03/23 06:21:01 DEBUG : dst/three.txt: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f112455a8e14df023_d20210323_m061956_c001_v0001044_t0034") 2021/03/23 06:21:01 DEBUG : dst/three.txt: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1151abb65ff41f9a_d20210323_m061948_c001_v0001044_t0017") 2021/03/23 06:21:01 DEBUG : dst/three.txt: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f1292662c0_d20210323_m061942_c001_v0001040_t0021") 2021/03/23 06:21:01 DEBUG : dst/three.txt: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f101366be12733e58_d20210323_m061941_c001_v0001023_t0011") 2021/03/23 06:21:01 DEBUG : dst/three.txt: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f112455a8e14defd9_d20210323_m061935_c001_v0001044_t0034") 2021/03/23 06:21:01 DEBUG : dst/three.txt: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1043ab05fb09b73d_d20210323_m061930_c001_v0001137_t0020") 2021/03/23 06:21:01 DEBUG : dst/three.txt: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f1292662a4_d20210323_m061927_c001_v0001040_t0021") 2021/03/23 06:21:01 DEBUG : dst/three.txt: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1061d152b07469a3_d20210323_m061926_c001_v0001146_t0018") 2021/03/23 06:21:01 DEBUG : dst/three.txt: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f112455a8e14def90_d20210323_m061919_c001_v0001044_t0034") 2021/03/23 06:21:01 DEBUG : dst/three.txt: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f112177f0fc26d252_d20210323_m061914_c001_v0001041_t0000") 2021/03/23 06:21:01 DEBUG : dst/three.txt: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f129266278_d20210323_m061910_c001_v0001040_t0021") 2021/03/23 06:21:01 DEBUG : dst/three.txt: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1162e5d11f0abef9_d20210323_m061909_c001_v0001120_t0027") 2021/03/23 06:21:01 DEBUG : dst/three.txt: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f112455a8e14def59_d20210323_m061900_c001_v0001044_t0034") 2021/03/23 06:21:01 DEBUG : dst/three.txt.bak: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1162d06fa69ed225_d20210323_m062031_c001_v0001144_t0044") 2021/03/23 06:21:01 DEBUG : dst/three.txt.bak: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f117b0a02e5f6a48f_d20210323_m062027_c001_v0001146_t0024") 2021/03/23 06:21:01 DEBUG : dst/three.txt.bak: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f107432225d9d7494_d20210323_m062023_c001_v0001154_t0003") 2021/03/23 06:21:01 DEBUG : dst/three.txt.bak: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f10691bfbdbba1fa9_d20210323_m062014_c001_v0001033_t0038") 2021/03/23 06:21:01 DEBUG : dst/three.txt.bak: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f114c988caf033255_d20210323_m062012_c001_v0001154_t0005") 2021/03/23 06:21:02 DEBUG : dst/three.txt.bak: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f10095b9343e1546d_d20210323_m062002_c001_v0001136_t0025") 2021/03/23 06:21:02 DEBUG : dst/two: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f107432225d9d751c_d20210323_m062046_c001_v0001154_t0011") 2021/03/23 06:21:02 DEBUG : dst/two: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f112455a8e14df0a5_d20210323_m062033_c001_v0001044_t0034") 2021/03/23 06:21:02 DEBUG : dst/two: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1146660645efa15b_d20210323_m062031_c001_v0001120_t0004") 2021/03/23 06:21:02 DEBUG : dst/two: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f12926631f_d20210323_m062017_c001_v0001040_t0021") 2021/03/23 06:21:02 DEBUG : dst/two: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f118d22ca94fbc2f5_d20210323_m062014_c001_v0001145_t0014") 2021/03/23 06:21:02 DEBUG : dst/two: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f1292662e3_d20210323_m061955_c001_v0001040_t0021") 2021/03/23 06:21:02 DEBUG : dst/two: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1196d6586bec7c7b_d20210323_m061953_c001_v0001144_t0030") 2021/03/23 06:21:02 DEBUG : dst/two: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f1292662ad_d20210323_m061934_c001_v0001040_t0021") 2021/03/23 06:21:02 DEBUG : dst/two: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f119718b8cd570cdf_d20210323_m061932_c001_v0001154_t0024") 2021/03/23 06:21:02 DEBUG : dst/two: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f12926628e_d20210323_m061918_c001_v0001040_t0021") 2021/03/23 06:21:02 DEBUG : dst/two: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1106bc3dabd89db3_d20210323_m061917_c001_v0001130_t0047") 2021/03/23 06:21:02 DEBUG : dst/two: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f129266261_d20210323_m061900_c001_v0001040_t0021") 2021/03/23 06:21:02 DEBUG : dst/two: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f109ccba313f3ad3f_d20210323_m061858_c001_v0001144_t0022") 2021/03/23 06:21:02 DEBUG : dst/two: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1067a96154ef62d8_d20210323_m061851_c001_v0001006_t0020") 2021/03/23 06:21:02 DEBUG : dst/two: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f108c3fba1b5f02df_d20210323_m061827_c001_v0001137_t0020") 2021/03/23 06:21:02 DEBUG : dst/two: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f109c928badfbf034_d20210323_m061825_c001_v0001130_t0042") 2021/03/23 06:21:02 DEBUG : empty space: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f114339e7a4c4e728_d20210323_m061808_c001_v0001113_t0028") 2021/03/23 06:21:02 DEBUG : empty space: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f112455a8e14dee77_d20210323_m061804_c001_v0001044_t0034") 2021/03/23 06:21:02 DEBUG : empty space: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f102eaf0485ee9747_d20210323_m061801_c001_v0001144_t0026") 2021/03/23 06:21:02 DEBUG : empty space: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f1292661a0_d20210323_m061748_c001_v0001040_t0021") 2021/03/23 06:21:02 DEBUG : empty space: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f102a082622c2b9a3_d20210323_m061736_c001_v0001136_t0058") 2021/03/23 06:21:02 DEBUG : empty space: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f112455a8e14dee06_d20210323_m061732_c001_v0001044_t0034") 2021/03/23 06:21:02 DEBUG : empty space: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f118894e141f0b121_d20210323_m061659_c001_v0001146_t0041") 2021/03/23 06:21:03 DEBUG : empty space: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f1292660fb_d20210323_m061656_c001_v0001040_t0021") 2021/03/23 06:21:03 DEBUG : empty space: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1063b0db6beee3bb_d20210323_m061654_c001_v0001153_t0006") 2021/03/23 06:21:03 DEBUG : empty space: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f1292660e9_d20210323_m061651_c001_v0001040_t0021") 2021/03/23 06:21:03 DEBUG : empty space: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f111bd41afff15d6e_d20210323_m061646_c001_v0001146_t0036") 2021/03/23 06:21:03 DEBUG : empty space: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f1292660ca_d20210323_m061644_c001_v0001040_t0021") 2021/03/23 06:21:03 DEBUG : empty space: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f11385f499ff48e1c_d20210323_m061637_c001_v0001040_t0042") 2021/03/23 06:21:03 DEBUG : empty space: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f12926609b_d20210323_m061636_c001_v0001040_t0021") 2021/03/23 06:21:03 DEBUG : empty space: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f111729f135b3f5e6_d20210323_m061622_c001_v0001154_t0021") 2021/03/23 06:21:03 DEBUG : empty space: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f129266051_d20210323_m061619_c001_v0001040_t0021") 2021/03/23 06:21:03 DEBUG : empty space: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f106eb98abae228a8_d20210323_m061614_c001_v0001091_t0021") 2021/03/23 06:21:03 DEBUG : empty space: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f129266042_d20210323_m061612_c001_v0001040_t0021") 2021/03/23 06:21:03 DEBUG : empty space: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1083239e1ea32962_d20210323_m061603_c001_v0001153_t0024") 2021/03/23 06:21:03 DEBUG : empty space: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f12926601a_d20210323_m061600_c001_v0001040_t0021") 2021/03/23 06:21:03 DEBUG : empty space: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1180f5482dd2787f_d20210323_m061557_c001_v0001006_t0012") 2021/03/23 06:21:03 DEBUG : empty space: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f129266006_d20210323_m061556_c001_v0001040_t0021") 2021/03/23 06:21:03 DEBUG : empty space: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f10587ef007d7a9a0_d20210323_m061555_c001_v0001040_t0058") 2021/03/23 06:21:03 DEBUG : empty space: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f10274d0a0cb40d58_d20210323_m061554_c001_v0001146_t0047") 2021/03/23 06:21:03 DEBUG : empty space: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f129266000_d20210323_m061550_c001_v0001040_t0021") 2021/03/23 06:21:03 DEBUG : enormous: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f104e5aa8b2570bda_d20210323_m061658_c001_v0001146_t0009") 2021/03/23 06:21:03 DEBUG : enormous: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f1292660fd_d20210323_m061657_c001_v0001040_t0021") 2021/03/23 06:21:03 DEBUG : existing: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024410cb9e223e7_d20210323_m062052_c001_v0001037_t0023") 2021/03/23 06:21:03 DEBUG : existing: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f112455a8e14df0e5_d20210323_m062051_c001_v0001044_t0034") 2021/03/23 06:21:03 DEBUG : existing: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f118894e141f0a816_d20210323_m061542_c001_v0001146_t0034") 2021/03/23 06:21:03 DEBUG : existing: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f129265fe9_d20210323_m061541_c001_v0001040_t0021") 2021/03/23 06:21:03 DEBUG : existing: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f118d22ca94fbb918_d20210323_m061540_c001_v0001145_t0020") 2021/03/23 06:21:03 DEBUG : existing: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f129265fe4_d20210323_m061539_c001_v0001040_t0021") 2021/03/23 06:21:04 DEBUG : existing: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f129265fe0_d20210323_m061538_c001_v0001040_t0021") 2021/03/23 06:21:04 DEBUG : five: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f115f0eaec2c2877b_d20210323_m061708_c001_v0001131_t0007") 2021/03/23 06:21:04 DEBUG : five: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1109f0d36159486c_d20210323_m061706_c001_v0001144_t0034") 2021/03/23 06:21:04 DEBUG : foo: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1188ade50ec6da9a_d20210323_m061559_c001_v0001039_t0015") 2021/03/23 06:21:04 DEBUG : foo: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f129266014_d20210323_m061558_c001_v0001040_t0021") 2021/03/23 06:21:04 DEBUG : foo: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f129266010_d20210323_m061556_c001_v0001040_t0021") 2021/03/23 06:21:04 DEBUG : four: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f112be961d6dc73f0_d20210323_m061709_c001_v0001146_t0059") 2021/03/23 06:21:04 DEBUG : four: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f129266122_d20210323_m061705_c001_v0001040_t0021") 2021/03/23 06:21:04 DEBUG : four: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f129266116_d20210323_m061703_c001_v0001040_t0021") 2021/03/23 06:21:04 DEBUG : hello world2: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f10528597fcb8dfe6_d20210323_m061512_c001_v0001036_t0049") 2021/03/23 06:21:04 DEBUG : hello world2: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f129265f98_d20210323_m061511_c001_v0001040_t0021") 2021/03/23 06:21:04 DEBUG : ignore-size: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f105e3da10babb3b3_d20210323_m061537_c001_v0001137_t0052") 2021/03/23 06:21:04 DEBUG : ignore-size: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f129265fdc_d20210323_m061536_c001_v0001040_t0021") 2021/03/23 06:21:04 DEBUG : nested/sub dir/file: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1098631b23e35b56_d20210323_m061729_c001_v0001021_t0007") 2021/03/23 06:21:04 DEBUG : nested/sub dir/file: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f112455a8e14dedf6_d20210323_m061728_c001_v0001044_t0034") 2021/03/23 06:21:04 DEBUG : nested/sub dir/file: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f119cd9b714fc2e8b_d20210323_m061727_c001_v0001146_t0016") 2021/03/23 06:21:04 DEBUG : nested/sub dir/file: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f129266165_d20210323_m061725_c001_v0001040_t0021") 2021/03/23 06:21:04 DEBUG : one: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f11723ddd2c7c475c_d20210323_m061709_c001_v0001144_t0045") 2021/03/23 06:21:04 DEBUG : one: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f129266103_d20210323_m061659_c001_v0001040_t0021") 2021/03/23 06:21:04 DEBUG : potato: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f103728da16245d7f_d20210323_m061724_c001_v0001131_t0019") 2021/03/23 06:21:04 DEBUG : potato: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f112455a8e14dedcf_d20210323_m061721_c001_v0001044_t0034") 2021/03/23 06:21:04 DEBUG : potato: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f104cfe0b69832930_d20210323_m061720_c001_v0001131_t0041") 2021/03/23 06:21:04 DEBUG : potato: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f12926614c_d20210323_m061717_c001_v0001040_t0021") 2021/03/23 06:21:04 DEBUG : potato: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f115c850fccac63dc_d20210323_m061716_c001_v0001146_t0029") 2021/03/23 06:21:04 DEBUG : potato: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f112455a8e14ded9f_d20210323_m061711_c001_v0001044_t0034") 2021/03/23 06:21:04 DEBUG : potato: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f102c25aaaa885474_d20210323_m061649_c001_v0001042_t0049") 2021/03/23 06:21:05 DEBUG : potato: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f1292660d5_d20210323_m061647_c001_v0001040_t0021") 2021/03/23 06:21:05 DEBUG : potato: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f112ad3d71673c4ad_d20210323_m061645_c001_v0001150_t0020") 2021/03/23 06:21:05 DEBUG : potato: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f1292660b9_d20210323_m061640_c001_v0001040_t0021") 2021/03/23 06:21:05 DEBUG : potato: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f10479d5efa5ca822_d20210323_m061636_c001_v0001092_t0016") 2021/03/23 06:21:05 DEBUG : potato: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f129266093_d20210323_m061635_c001_v0001040_t0021") 2021/03/23 06:21:05 DEBUG : potato: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f11723ddd2c7c4549_d20210323_m061621_c001_v0001144_t0008") 2021/03/23 06:21:05 DEBUG : potato: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f12926604e_d20210323_m061618_c001_v0001040_t0021") 2021/03/23 06:21:05 DEBUG : potato: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1009cdba0f6f2f94_d20210323_m061617_c001_v0001093_t0027") 2021/03/23 06:21:05 DEBUG : potato: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f12926603e_d20210323_m061611_c001_v0001040_t0021") 2021/03/23 06:21:05 DEBUG : potato: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f10835a0e29817876_d20210323_m061610_c001_v0001044_t0016") 2021/03/23 06:21:05 DEBUG : potato: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f12926603a_d20210323_m061609_c001_v0001040_t0021") 2021/03/23 06:21:05 DEBUG : potato: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f129266038_d20210323_m061608_c001_v0001040_t0021") 2021/03/23 06:21:05 DEBUG : potato: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f119827c2d8383541_d20210323_m061607_c001_v0001018_t0023") 2021/03/23 06:21:05 DEBUG : potato: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f129266034_d20210323_m061606_c001_v0001040_t0021") 2021/03/23 06:21:05 DEBUG : potato: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f12926602c_d20210323_m061605_c001_v0001040_t0021") 2021/03/23 06:21:05 DEBUG : potato: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1047296cb7322e46_d20210323_m061604_c001_v0001003_t0015") 2021/03/23 06:21:05 DEBUG : potato: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f129266020_d20210323_m061601_c001_v0001040_t0021") 2021/03/23 06:21:05 DEBUG : potato2: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f113408fd6bbc23cf_d20210323_m061816_c001_v0001145_t0050") 2021/03/23 06:21:05 DEBUG : potato2: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f112455a8e14dee9c_d20210323_m061815_c001_v0001044_t0034") 2021/03/23 06:21:05 DEBUG : potato2: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f114c19eefaf29f3e_d20210323_m061809_c001_v0001130_t0046") 2021/03/23 06:21:05 DEBUG : potato2: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f1292661c8_d20210323_m061803_c001_v0001040_t0021") 2021/03/23 06:21:05 DEBUG : potato2: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1012c757932dbd88_d20210323_m061754_c001_v0001000_t0031") 2021/03/23 06:21:05 DEBUG : potato2: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f112455a8e14dee2f_d20210323_m061745_c001_v0001044_t0034") 2021/03/23 06:21:05 DEBUG : potato2: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f119cd9b714fc2f11_d20210323_m061736_c001_v0001146_t0004") 2021/03/23 06:21:05 DEBUG : potato2: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f12926616d_d20210323_m061730_c001_v0001040_t0021") 2021/03/23 06:21:05 DEBUG : potato2: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f10178ddc1426fc37_d20210323_m061658_c001_v0001154_t0013") 2021/03/23 06:21:06 DEBUG : potato2: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f1292660f5_d20210323_m061655_c001_v0001040_t0021") 2021/03/23 06:21:06 DEBUG : potato2: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f119718b8cd57097f_d20210323_m061654_c001_v0001154_t0020") 2021/03/23 06:21:06 DEBUG : potato2: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f1292660e1_d20210323_m061651_c001_v0001040_t0021") 2021/03/23 06:21:06 DEBUG : potato2: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f100997f0728ff841_d20210323_m061650_c001_v0001153_t0016") 2021/03/23 06:21:06 DEBUG : potato2: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f1292660d9_d20210323_m061648_c001_v0001040_t0021") 2021/03/23 06:21:06 DEBUG : potato2: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f10548d83b8720fa0_d20210323_m061647_c001_v0001039_t0002") 2021/03/23 06:21:06 DEBUG : potato2: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f1292660d0_d20210323_m061645_c001_v0001040_t0021") 2021/03/23 06:21:06 DEBUG : potato2: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f10144fcfebda0b48_d20210323_m061640_c001_v0001038_t0012") 2021/03/23 06:21:06 DEBUG : potato2: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f1292660a3_d20210323_m061636_c001_v0001040_t0021") 2021/03/23 06:21:06 DEBUG : potato2: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f113fa00e0d4ed444_d20210323_m061622_c001_v0001130_t0029") 2021/03/23 06:21:06 DEBUG : potato2: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f129266055_d20210323_m061620_c001_v0001040_t0021") 2021/03/23 06:21:06 DEBUG : potato2: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f112735831f408ac5_d20210323_m061517_c001_v0001145_t0053") 2021/03/23 06:21:06 DEBUG : potato2: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f129265fa4_d20210323_m061516_c001_v0001040_t0021") 2021/03/23 06:21:06 DEBUG : potato2: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f105fa9edd78a1b12_d20210323_m061514_c001_v0001021_t0044") 2021/03/23 06:21:06 DEBUG : potato2: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f129265f9c_d20210323_m061513_c001_v0001040_t0021") 2021/03/23 06:21:06 DEBUG : potato3: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f11723ddd2c7c49d7_d20210323_m061808_c001_v0001144_t0003") 2021/03/23 06:21:06 DEBUG : potato3: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f1292661cb_d20210323_m061805_c001_v0001040_t0021") 2021/03/23 06:21:06 DEBUG : potato3: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f107432225d9d716c_d20210323_m061755_c001_v0001154_t0011") 2021/03/23 06:21:06 DEBUG : potato3: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f112455a8e14dee38_d20210323_m061749_c001_v0001044_t0034") 2021/03/23 06:21:06 DEBUG : potato3: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f105e3da10babb652_d20210323_m061737_c001_v0001137_t0045") 2021/03/23 06:21:06 DEBUG : potato3: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f129266173_d20210323_m061733_c001_v0001040_t0021") 2021/03/23 06:21:06 DEBUG : pre-dest1/1: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1173b3c34f082b05_d20210323_m061835_c001_v0001033_t0033") 2021/03/23 06:21:06 DEBUG : pre-dest1/1: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f112455a8e14deec4_d20210323_m061828_c001_v0001044_t0034") 2021/03/23 06:21:06 DEBUG : pre-dest2/2: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f104ee099151a9b87_d20210323_m061835_c001_v0001153_t0010") 2021/03/23 06:21:07 DEBUG : pre-dest2/2: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f129266202_d20210323_m061829_c001_v0001040_t0021") 2021/03/23 06:21:07 DEBUG : sizeonly: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f111bd3ed343222f1_d20210323_m061536_c001_v0001038_t0024") 2021/03/23 06:21:07 DEBUG : sizeonly: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f129265fd8_d20210323_m061534_c001_v0001040_t0021") 2021/03/23 06:21:07 DEBUG : sub dir/hello world: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f10475436ca38b388_d20210323_m061729_c001_v0001025_t0020") 2021/03/23 06:21:07 DEBUG : sub dir/hello world: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f129266169_d20210323_m061728_c001_v0001040_t0021") 2021/03/23 06:21:07 DEBUG : sub dir/hello world: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f10877d7ea2e57854_d20210323_m061727_c001_v0001042_t0041") 2021/03/23 06:21:07 DEBUG : sub dir/hello world: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f112455a8e14dede6_d20210323_m061725_c001_v0001044_t0034") 2021/03/23 06:21:07 DEBUG : sub dir/hello world: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f10622414ef448270_d20210323_m061532_c001_v0001038_t0053") 2021/03/23 06:21:07 DEBUG : sub dir/hello world: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f129265fc9_d20210323_m061530_c001_v0001040_t0021") 2021/03/23 06:21:07 DEBUG : sub dir/hello world: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1170a2919b64b74d_d20210323_m061529_c001_v0001145_t0054") 2021/03/23 06:21:07 DEBUG : sub dir/hello world: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f129265fbf_d20210323_m061528_c001_v0001040_t0021") 2021/03/23 06:21:07 DEBUG : sub dir/hello world: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1108f66df7c247af_d20210323_m061527_c001_v0001093_t0031") 2021/03/23 06:21:07 DEBUG : sub dir/hello world: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f129265fb2_d20210323_m061524_c001_v0001040_t0021") 2021/03/23 06:21:07 DEBUG : sub dir/hello world: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f111bfb557f4c73e3_d20210323_m061523_c001_v0001037_t0021") 2021/03/23 06:21:07 DEBUG : sub dir/hello world: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f129265fb0_d20210323_m061522_c001_v0001040_t0021") 2021/03/23 06:21:07 DEBUG : sub dir/hello world: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f118e5a788ef3a479_d20210323_m061521_c001_v0001004_t0014") 2021/03/23 06:21:07 DEBUG : sub dir/hello world: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f129265fa9_d20210323_m061520_c001_v0001040_t0021") 2021/03/23 06:21:07 DEBUG : sub dir/hello world: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f115a5d149f6bf57b_d20210323_m061519_c001_v0001134_t0051") 2021/03/23 06:21:07 DEBUG : sub dir/hello world: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f129265fa6_d20210323_m061518_c001_v0001040_t0021") 2021/03/23 06:21:07 DEBUG : sub dir/hello world: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f116e3c1a7eff1112_d20210323_m061510_c001_v0001026_t0015") 2021/03/23 06:21:07 DEBUG : sub dir/hello world: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f129265f92_d20210323_m061509_c001_v0001040_t0021") 2021/03/23 06:21:07 DEBUG : sub dir/hello world: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1019d6b2c8316053_d20210323_m061506_c001_v0001044_t0039") 2021/03/23 06:21:07 DEBUG : sub dir/hello world: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f129265f8c_d20210323_m061505_c001_v0001040_t0021") 2021/03/23 06:21:07 DEBUG : sub dir/hello world: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1037a7326f7c298f_d20210323_m061504_c001_v0001154_t0016") 2021/03/23 06:21:07 DEBUG : sub dir/hello world: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f129265f84_d20210323_m061502_c001_v0001040_t0021") 2021/03/23 06:21:07 DEBUG : sub dir/hello world: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f113dd2c39ffdf651_d20210323_m061501_c001_v0001150_t0000") 2021/03/23 06:21:08 DEBUG : sub dir/hello world: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f129265f7a_d20210323_m061459_c001_v0001040_t0021") 2021/03/23 06:21:08 DEBUG : sub/yam: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f105b3fb8f67facf5_d20210323_m061723_c001_v0001145_t0058") 2021/03/23 06:21:08 DEBUG : sub/yam: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f129266159_d20210323_m061721_c001_v0001040_t0021") 2021/03/23 06:21:08 DEBUG : three: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f101def82ce7179f6_d20210323_m061710_c001_v0001036_t0011") 2021/03/23 06:21:08 DEBUG : three: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f129266130_d20210323_m061707_c001_v0001040_t0021") 2021/03/23 06:21:08 DEBUG : three: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f129266113_d20210323_m061702_c001_v0001040_t0021") 2021/03/23 06:21:08 DEBUG : two: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1196d6586bec762f_d20210323_m061711_c001_v0001144_t0019") 2021/03/23 06:21:08 DEBUG : two: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f112455a8e14ded8a_d20210323_m061706_c001_v0001044_t0034") 2021/03/23 06:21:08 DEBUG : two: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f12926610f_d20210323_m061701_c001_v0001040_t0021") 2021/03/23 06:21:08 DEBUG : yam: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f110d3685fc525bb7_d20210323_m061724_c001_v0001092_t0053") 2021/03/23 06:21:08 DEBUG : yam: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f115c850fccac6466_d20210323_m061722_c001_v0001146_t0045") 2021/03/23 06:21:08 DEBUG : yam: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1127029e6a454d99_d20210323_m061719_c001_v0001144_t0027") 2021/03/23 06:21:08 DEBUG : yam: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f112455a8e14dedc2_d20210323_m061718_c001_v0001044_t0034") 2021/03/23 06:21:08 DEBUG : yam: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f106417ded59819d1_d20210323_m061715_c001_v0001040_t0002") 2021/03/23 06:21:08 DEBUG : yam: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1024b7f12926613b_d20210323_m061711_c001_v0001040_t0021") 2021/03/23 06:21:08 DEBUG : yaml: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f10446e0f662ea784_d20210323_m061721_c001_v0001150_t0050") 2021/03/23 06:21:08 DEBUG : yaml: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f117a9e7d7dccab13_d20210323_m061719_c001_v0001153_t0012") 2021/03/23 06:21:08 DEBUG : yaml: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f101911ed2cf2755b_d20210323_m061716_c001_v0001130_t0014") 2021/03/23 06:21:08 DEBUG : yaml: Deleting (id "4_z7fa2dd43b08d5bdb7f880e1b_f1173bc084da5a35e_d20210323_m061715_c001_v0001003_t0017") "./sync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose" - Finished OK in 6m14.768030757s (try 1/5)