"./sync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose" - Starting (try 1/5)
2022/03/03 05:17:29 DEBUG : Creating backend with remote "TestB2:rclone-test-sefococ1zileyew0jadadat1"
2022/03/03 05:17:29 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2022/03/03 05:17:30 DEBUG : Creating backend with remote "/tmp/rclone3180978143"
=== RUN   TestPipe
--- PASS: TestPipe (0.00s)
=== RUN   TestPipeConcurrent
--- PASS: TestPipeConcurrent (0.02s)
=== RUN   TestPipeOrderBy
=== RUN   TestPipeOrderBy/#00
=== RUN   TestPipeOrderBy/size
=== RUN   TestPipeOrderBy/name
=== RUN   TestPipeOrderBy/modtime
=== RUN   TestPipeOrderBy/size,ascending
=== RUN   TestPipeOrderBy/name,asc
=== RUN   TestPipeOrderBy/modtime,ascending
=== RUN   TestPipeOrderBy/size,descending
=== RUN   TestPipeOrderBy/name,desc
=== RUN   TestPipeOrderBy/modtime,descending
=== RUN   TestPipeOrderBy/size,mixed,50
=== RUN   TestPipeOrderBy/size,mixed,51
--- PASS: TestPipeOrderBy (0.00s)
    --- PASS: TestPipeOrderBy/#00 (0.00s)
    --- PASS: TestPipeOrderBy/size (0.00s)
    --- PASS: TestPipeOrderBy/name (0.00s)
    --- PASS: TestPipeOrderBy/modtime (0.00s)
    --- PASS: TestPipeOrderBy/size,ascending (0.00s)
    --- PASS: TestPipeOrderBy/name,asc (0.00s)
    --- PASS: TestPipeOrderBy/modtime,ascending (0.00s)
    --- PASS: TestPipeOrderBy/size,descending (0.00s)
    --- PASS: TestPipeOrderBy/name,desc (0.00s)
    --- PASS: TestPipeOrderBy/modtime,descending (0.00s)
    --- PASS: TestPipeOrderBy/size,mixed,50 (0.00s)
    --- PASS: TestPipeOrderBy/size,mixed,51 (0.00s)
=== RUN   TestNewLess
=== RUN   TestNewLess/blankOK
=== RUN   TestNewLess/tooManyParts
=== RUN   TestNewLess/tooManyParts2
=== RUN   TestNewLess/badMixed
=== RUN   TestNewLess/unknownComparison
=== RUN   TestNewLess/unknownSortDirection
=== RUN   TestNewLess/size
=== RUN   TestNewLess/name
=== RUN   TestNewLess/modtime
=== RUN   TestNewLess/size,ascending
=== RUN   TestNewLess/name,asc
=== RUN   TestNewLess/modtime,ascending
=== RUN   TestNewLess/size,descending
=== RUN   TestNewLess/name,desc
=== RUN   TestNewLess/modtime,descending
=== RUN   TestNewLess/modtime,mixed
=== RUN   TestNewLess/modtime,mixed,30
--- PASS: TestNewLess (0.00s)
    --- PASS: TestNewLess/blankOK (0.00s)
    --- PASS: TestNewLess/tooManyParts (0.00s)
    --- PASS: TestNewLess/tooManyParts2 (0.00s)
    --- PASS: TestNewLess/badMixed (0.00s)
    --- PASS: TestNewLess/unknownComparison (0.00s)
    --- PASS: TestNewLess/unknownSortDirection (0.00s)
    --- PASS: TestNewLess/size (0.00s)
    --- PASS: TestNewLess/name (0.00s)
    --- PASS: TestNewLess/modtime (0.00s)
    --- PASS: TestNewLess/size,ascending (0.00s)
    --- PASS: TestNewLess/name,asc (0.00s)
    --- PASS: TestNewLess/modtime,ascending (0.00s)
    --- PASS: TestNewLess/size,descending (0.00s)
    --- PASS: TestNewLess/name,desc (0.00s)
    --- PASS: TestNewLess/modtime,descending (0.00s)
    --- PASS: TestNewLess/modtime,mixed (0.00s)
    --- PASS: TestNewLess/modtime,mixed,30 (0.00s)
=== RUN   TestRcCopy
    rc_test.go:16: Skipping test on non local remote
--- SKIP: TestRcCopy (0.00s)
=== RUN   TestRcMove
    rc_test.go:16: Skipping test on non local remote
--- SKIP: TestRcMove (0.00s)
=== RUN   TestRcSync
    rc_test.go:16: Skipping test on non local remote
--- SKIP: TestRcSync (0.00s)
=== RUN   TestCopyWithDryRun
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:17:31 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11)
2022/03/03 05:17:31 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:17:31 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
--- PASS: TestCopyWithDryRun (1.73s)
=== RUN   TestCopy
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:17:32 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:17:32 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
2022/03/03 05:17:33 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK
2022/03/03 05:17:33 INFO  : sub dir/hello world: Copied (new)
--- PASS: TestCopy (2.59s)
=== RUN   TestCopyMissingDirectory
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:17:34 DEBUG : Creating backend with remote "/non-existing"
2022/03/03 05:17:34 ERROR : Local file system at /non-existing: error reading source root directory: directory not found
2022/03/03 05:17:34 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:17:34 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
--- PASS: TestCopyMissingDirectory (0.51s)
=== RUN   TestCopyNoTraverse
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:17:35 DEBUG : Couldn't decode error response: EOF
2022/03/03 05:17:35 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:17:35 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
2022/03/03 05:17:36 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK
2022/03/03 05:17:36 INFO  : sub dir/hello world: Copied (new)
--- PASS: TestCopyNoTraverse (2.11s)
=== RUN   TestCopyCheckFirst
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:17:37 INFO  : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Running all checks before starting transfers
2022/03/03 05:17:37 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:17:37 INFO  : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Checks finished, now starting transfers
2022/03/03 05:17:37 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
2022/03/03 05:17:37 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK
2022/03/03 05:17:37 INFO  : sub dir/hello world: Copied (new)
--- PASS: TestCopyCheckFirst (2.50s)
=== RUN   TestSyncNoTraverse
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:17:39 ERROR : Ignoring --no-traverse with sync
2022/03/03 05:17:39 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:17:39 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
2022/03/03 05:17:40 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK
2022/03/03 05:17:40 INFO  : sub dir/hello world: Copied (new)
2022/03/03 05:17:40 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncNoTraverse (1.98s)
=== RUN   TestCopyWithDepth
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:17:41 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:17:41 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
2022/03/03 05:17:46 DEBUG : hello world2: sha1 = c0bf117f9c93d02c894fe8ffd6e5cecf3545c209 OK
2022/03/03 05:17:46 INFO  : hello world2: Copied (new)
--- PASS: TestCopyWithDepth (5.76s)
=== RUN   TestCopyWithFilesFrom
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:17:47 DEBUG : hello world2: Excluded
2022/03/03 05:17:47 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:17:47 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
2022/03/03 05:17:48 DEBUG : potato2: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK
2022/03/03 05:17:48 INFO  : potato2: Copied (new)
--- PASS: TestCopyWithFilesFrom (1.24s)
=== RUN   TestCopyWithFilesFromAndNoTraverse
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:17:49 DEBUG : Couldn't decode error response: EOF
2022/03/03 05:17:49 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:17:49 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
2022/03/03 05:17:49 DEBUG : potato2: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK
2022/03/03 05:17:49 INFO  : potato2: Copied (new)
--- PASS: TestCopyWithFilesFromAndNoTraverse (2.29s)
=== RUN   TestCopyEmptyDirectories
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:17:51 DEBUG : sub dir2: Making directory
2022/03/03 05:17:51 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:17:51 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
2022/03/03 05:17:51 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK
2022/03/03 05:17:51 INFO  : sub dir/hello world: Copied (new)
2022/03/03 05:17:51 DEBUG : sub dir2: Making directory
2022/03/03 05:17:51 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: copied 1 directories
    fstest.go:251: Filtering empty directory "sub dir2"
--- PASS: TestCopyEmptyDirectories (1.42s)
=== RUN   TestMoveEmptyDirectories
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:17:52 DEBUG : sub dir2: Making directory
2022/03/03 05:17:52 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:17:52 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
2022/03/03 05:17:54 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK
2022/03/03 05:17:54 INFO  : sub dir/hello world: Copied (new)
2022/03/03 05:17:54 INFO  : sub dir/hello world: Deleted
2022/03/03 05:17:54 DEBUG : sub dir: Making directory
2022/03/03 05:17:54 DEBUG : sub dir2: Making directory
2022/03/03 05:17:54 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: copied 2 directories
    fstest.go:251: Filtering empty directory "sub dir2"
--- PASS: TestMoveEmptyDirectories (2.35s)
=== RUN   TestSyncEmptyDirectories
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:17:54 DEBUG : sub dir2: Making directory
2022/03/03 05:17:55 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:17:55 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
2022/03/03 05:17:55 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK
2022/03/03 05:17:55 INFO  : sub dir/hello world: Copied (new)
2022/03/03 05:17:55 DEBUG : sub dir2: Making directory
2022/03/03 05:17:55 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: copied 1 directories
2022/03/03 05:17:55 DEBUG : Waiting for deletions to finish
    fstest.go:251: Filtering empty directory "sub dir2"
--- PASS: TestSyncEmptyDirectories (2.23s)
=== RUN   TestServerSideCopy
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:17:57 DEBUG : Creating backend with remote "TestB2:rclone-test-sipivod4buzufog7lavidup1"
    sync_test.go:275: Server side copy (if possible) B2 bucket rclone-test-sefococ1zileyew0jadadat1 -> B2 bucket rclone-test-sipivod4buzufog7lavidup1
2022/03/03 05:17:59 DEBUG : B2 bucket rclone-test-sipivod4buzufog7lavidup1: Waiting for checks to finish
2022/03/03 05:17:59 DEBUG : B2 bucket rclone-test-sipivod4buzufog7lavidup1: Waiting for transfers to finish
2022/03/03 05:18:01 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK
2022/03/03 05:18:01 INFO  : sub dir/hello world: Copied (server-side copy)
2022/03/03 05:18:02 DEBUG : B2 bucket rclone-test-sipivod4buzufog7lavidup1: Purge remote
2022/03/03 05:18:02 DEBUG : sub dir/hello world: Deleting (id "4_zef72ad83306d4beb7ff80e1b_f1045cca93b093180_d20220303_m051759_c001_v0001115_t0059")
--- PASS: TestServerSideCopy (6.42s)
=== RUN   TestCopyAfterDelete
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:18:04 ERROR : : error listing: directory not found
2022/03/03 05:18:04 DEBUG : Local file system at /tmp/rclone3180978143: Making directory
2022/03/03 05:18:05 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:18:05 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
--- PASS: TestCopyAfterDelete (2.34s)
=== RUN   TestCopyRedownload
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:18:06 DEBUG : Local file system at /tmp/rclone3180978143: Waiting for checks to finish
2022/03/03 05:18:06 DEBUG : Local file system at /tmp/rclone3180978143: Waiting for transfers to finish
2022/03/03 05:18:07 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK
2022/03/03 05:18:07 INFO  : sub dir/hello world: Copied (new)
--- PASS: TestCopyRedownload (2.64s)
=== RUN   TestSyncBasedOnCheckSum
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:18:08 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:18:08 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
2022/03/03 05:18:08 DEBUG : check sum: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK
2022/03/03 05:18:08 INFO  : check sum: Copied (new)
2022/03/03 05:18:08 DEBUG : Waiting for deletions to finish
2022/03/03 05:18:09 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:18:09 DEBUG : check sum: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK
2022/03/03 05:18:09 DEBUG : check sum: Size and sha1 of src and dst objects identical
2022/03/03 05:18:09 DEBUG : check sum: Unchanged skipping
2022/03/03 05:18:09 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
2022/03/03 05:18:09 DEBUG : Waiting for deletions to finish
2022/03/03 05:18:09 INFO  : There was nothing to transfer
--- PASS: TestSyncBasedOnCheckSum (1.84s)
=== RUN   TestSyncSizeOnly
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:18:10 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:18:10 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
2022/03/03 05:18:11 DEBUG : sizeonly: sha1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK
2022/03/03 05:18:11 INFO  : sizeonly: Copied (new)
2022/03/03 05:18:11 DEBUG : Waiting for deletions to finish
2022/03/03 05:18:11 DEBUG : sizeonly: Sizes identical
2022/03/03 05:18:11 DEBUG : sizeonly: Unchanged skipping
2022/03/03 05:18:11 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:18:11 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
2022/03/03 05:18:11 DEBUG : Waiting for deletions to finish
2022/03/03 05:18:11 INFO  : There was nothing to transfer
--- PASS: TestSyncSizeOnly (1.94s)
=== RUN   TestSyncIgnoreSize
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:18:12 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:18:12 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
2022/03/03 05:18:12 DEBUG : ignore-size: sha1 = 4a756ca07e9487f482465a99e8286abc86ba4dc7 OK
2022/03/03 05:18:12 INFO  : ignore-size: Copied (new)
2022/03/03 05:18:12 DEBUG : Waiting for deletions to finish
2022/03/03 05:18:13 DEBUG : ignore-size: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:18:13 DEBUG : ignore-size: Unchanged skipping
2022/03/03 05:18:13 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:18:13 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
2022/03/03 05:18:13 DEBUG : Waiting for deletions to finish
2022/03/03 05:18:13 INFO  : There was nothing to transfer
--- PASS: TestSyncIgnoreSize (1.86s)
=== RUN   TestSyncIgnoreTimes
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:18:14 DEBUG : existing: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:18:14 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:18:14 DEBUG : existing: Unchanged skipping
2022/03/03 05:18:14 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
2022/03/03 05:18:14 DEBUG : Waiting for deletions to finish
2022/03/03 05:18:14 INFO  : There was nothing to transfer
2022/03/03 05:18:14 DEBUG : existing: Transferring unconditionally as --ignore-times is in use
2022/03/03 05:18:14 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:18:14 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
2022/03/03 05:18:15 DEBUG : existing: sha1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK
2022/03/03 05:18:15 INFO  : existing: Copied (replaced existing)
2022/03/03 05:18:15 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncIgnoreTimes (2.22s)
=== RUN   TestSyncIgnoreExisting
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:18:16 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:18:16 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
2022/03/03 05:18:16 DEBUG : existing: sha1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK
2022/03/03 05:18:16 INFO  : existing: Copied (new)
2022/03/03 05:18:16 DEBUG : Waiting for deletions to finish
2022/03/03 05:18:17 DEBUG : existing: Destination exists, skipping
2022/03/03 05:18:17 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:18:17 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
2022/03/03 05:18:17 DEBUG : Waiting for deletions to finish
2022/03/03 05:18:17 INFO  : There was nothing to transfer
--- PASS: TestSyncIgnoreExisting (4.66s)
=== RUN   TestSyncIgnoreErrors
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:18:21 DEBUG : d: Making directory
    fstest.go:251: Filtering empty directory "d"
2022/03/03 05:18:22 DEBUG : c/non empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2022/03/03 05:18:22 DEBUG : c/non empty space: Unchanged skipping
2022/03/03 05:18:22 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:18:22 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
2022/03/03 05:18:25 DEBUG : a/potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK
2022/03/03 05:18:25 INFO  : a/potato2: Copied (new)
2022/03/03 05:18:25 DEBUG : Waiting for deletions to finish
2022/03/03 05:18:25 INFO  : b/potato: Deleted
--- PASS: TestSyncIgnoreErrors (8.24s)
=== RUN   TestSyncAfterChangingModtimeOnly
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:18:30 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
2022/03/03 05:18:30 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:18:30 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK
2022/03/03 05:18:30 NOTICE: empty space: Skipped update modification time as --dry-run is set (size 1)
2022/03/03 05:18:30 DEBUG : empty space: Unchanged skipping
2022/03/03 05:18:30 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
2022/03/03 05:18:30 DEBUG : Waiting for deletions to finish
2022/03/03 05:18:30 INFO  : There was nothing to transfer
2022/03/03 05:18:30 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
2022/03/03 05:18:30 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:18:30 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK
2022/03/03 05:18:31 INFO  : empty space: Updated modification time in destination
2022/03/03 05:18:31 DEBUG : empty space: Unchanged skipping
2022/03/03 05:18:31 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
2022/03/03 05:18:31 DEBUG : Waiting for deletions to finish
2022/03/03 05:18:31 INFO  : There was nothing to transfer
--- PASS: TestSyncAfterChangingModtimeOnly (3.06s)
=== RUN   TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:18:33 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
2022/03/03 05:18:33 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:18:33 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK
2022/03/03 05:18:33 DEBUG : empty space: Unchanged skipping
2022/03/03 05:18:33 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
2022/03/03 05:18:33 DEBUG : Waiting for deletions to finish
2022/03/03 05:18:33 INFO  : There was nothing to transfer
--- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (2.70s)
=== RUN   TestSyncDoesntUpdateModtime
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:18:36 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
2022/03/03 05:18:36 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:18:36 DEBUG : foo: sha1 = 0beec7b5ea3f0fdbc95d0dd47f3c5bc275da8a33 (Local file system at /tmp/rclone3180978143)
2022/03/03 05:18:36 DEBUG : foo: sha1 = 62cdb7020ff920e5aa642c3d4066950dd1f01f4d (B2 bucket rclone-test-sefococ1zileyew0jadadat1)
2022/03/03 05:18:36 DEBUG : foo: sha1 differ
2022/03/03 05:18:36 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
2022/03/03 05:18:39 DEBUG : foo: sha1 = 0beec7b5ea3f0fdbc95d0dd47f3c5bc275da8a33 OK
2022/03/03 05:18:39 INFO  : foo: Copied (replaced existing)
2022/03/03 05:18:39 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncDoesntUpdateModtime (5.27s)
=== RUN   TestSyncAfterAddingAFile
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:18:40 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:18:40 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2022/03/03 05:18:40 DEBUG : empty space: Unchanged skipping
2022/03/03 05:18:40 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
2022/03/03 05:18:48 DEBUG : potato: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK
2022/03/03 05:18:48 INFO  : potato: Copied (new)
2022/03/03 05:18:48 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncAfterAddingAFile (12.06s)
=== RUN   TestSyncAfterChangingFilesSizeOnly
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:18:53 DEBUG : potato: Sizes differ (src 21 vs dst 60)
2022/03/03 05:18:53 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:18:53 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
2022/03/03 05:18:53 DEBUG : potato: sha1 = 0fd321a1023ac4e1e1054fcc0236b9d0755f722f OK
2022/03/03 05:18:53 INFO  : potato: Copied (replaced existing)
2022/03/03 05:18:53 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncAfterChangingFilesSizeOnly (3.17s)
=== RUN   TestSyncAfterChangingContentsOnly
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:18:56 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:18:56 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
2022/03/03 05:18:56 DEBUG : potato: sha1 = 1ca5094b3b73f587249eabe179c40a3eec839b1f (Local file system at /tmp/rclone3180978143)
2022/03/03 05:18:56 DEBUG : potato: sha1 = 0fd321a1023ac4e1e1054fcc0236b9d0755f722f (B2 bucket rclone-test-sefococ1zileyew0jadadat1)
2022/03/03 05:18:56 DEBUG : potato: sha1 differ
2022/03/03 05:18:56 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
2022/03/03 05:18:56 DEBUG : potato: sha1 = 1ca5094b3b73f587249eabe179c40a3eec839b1f OK
2022/03/03 05:18:56 INFO  : potato: Copied (replaced existing)
2022/03/03 05:18:56 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncAfterChangingContentsOnly (3.85s)
=== RUN   TestSyncAfterRemovingAFileAndAddingAFileDryRun
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:19:00 NOTICE: potato2: Skipped copy as --dry-run is set (size 60)
2022/03/03 05:19:00 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:19:00 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2022/03/03 05:19:00 DEBUG : empty space: Unchanged skipping
2022/03/03 05:19:00 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
2022/03/03 05:19:00 DEBUG : Waiting for deletions to finish
2022/03/03 05:19:00 NOTICE: potato: Skipped delete as --dry-run is set (size 21)
--- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (9.28s)
=== RUN   TestSyncAfterRemovingAFileAndAddingAFile
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:19:10 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2022/03/03 05:19:10 DEBUG : empty space: Unchanged skipping
2022/03/03 05:19:10 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:19:10 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
2022/03/03 05:19:10 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK
2022/03/03 05:19:10 INFO  : potato2: Copied (new)
2022/03/03 05:19:10 DEBUG : Waiting for deletions to finish
2022/03/03 05:19:11 INFO  : potato: Deleted
--- PASS: TestSyncAfterRemovingAFileAndAddingAFile (6.14s)
=== RUN   TestSyncAfterRemovingAFileAndAddingAFileSubDir
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:19:15 DEBUG : d: Making directory
2022/03/03 05:19:15 DEBUG : d/e: Making directory
    fstest.go:251: Filtering empty directory "d"
    fstest.go:251: Filtering empty directory "d/e"
2022/03/03 05:19:16 DEBUG : c/non empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2022/03/03 05:19:16 DEBUG : c/non empty space: Unchanged skipping
2022/03/03 05:19:16 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:19:16 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
2022/03/03 05:19:16 DEBUG : a/potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK
2022/03/03 05:19:16 INFO  : a/potato2: Copied (new)
2022/03/03 05:19:16 DEBUG : Waiting for deletions to finish
2022/03/03 05:19:16 INFO  : b/potato: Deleted
--- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (3.05s)
=== RUN   TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:19:18 DEBUG : d: Making directory
    fstest.go:251: Filtering empty directory "d"
2022/03/03 05:19:19 DEBUG : c/non empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2022/03/03 05:19:19 DEBUG : c/non empty space: Unchanged skipping
2022/03/03 05:19:19 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:19:19 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
2022/03/03 05:19:19 DEBUG : a/potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK
2022/03/03 05:19:19 INFO  : a/potato2: Copied (new)
2022/03/03 05:19:19 ERROR : B2 bucket rclone-test-sefococ1zileyew0jadadat1: not deleting files as there were IO errors
2022/03/03 05:19:19 ERROR : B2 bucket rclone-test-sefococ1zileyew0jadadat1: not deleting directories as there were IO errors
    fstest.go:251: Filtering empty directory "d"
--- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (5.16s)
=== RUN   TestSyncDeleteAfter
--- PASS: TestSyncDeleteAfter (0.00s)
=== RUN   TestSyncDeleteDuring
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:19:23 DEBUG : Waiting for deletions to finish
2022/03/03 05:19:24 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2022/03/03 05:19:24 DEBUG : empty space: Unchanged skipping
2022/03/03 05:19:24 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:19:24 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
2022/03/03 05:19:24 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK
2022/03/03 05:19:24 INFO  : potato2: Copied (new)
2022/03/03 05:19:24 INFO  : potato: Deleted
--- PASS: TestSyncDeleteDuring (4.50s)
=== RUN   TestSyncDeleteBefore
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:19:28 DEBUG : Waiting for deletions to finish
2022/03/03 05:19:28 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:19:28 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
2022/03/03 05:19:30 INFO  : potato: Deleted
2022/03/03 05:19:30 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2022/03/03 05:19:30 DEBUG : empty space: Unchanged skipping
2022/03/03 05:19:30 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:19:30 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
2022/03/03 05:19:31 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK
2022/03/03 05:19:31 INFO  : potato2: Copied (new)
--- PASS: TestSyncDeleteBefore (6.35s)
=== RUN   TestCopyDeleteBefore
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:19:34 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:19:34 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
2022/03/03 05:19:34 DEBUG : potato2: sha1 = 810274f0df19dab60a894a562b7a8ff04c072623 OK
2022/03/03 05:19:34 INFO  : potato2: Copied (new)
--- PASS: TestCopyDeleteBefore (3.56s)
=== RUN   TestSyncWithExclude
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:19:38 DEBUG : enormous: Excluded
2022/03/03 05:19:38 DEBUG : potato2: Excluded
2022/03/03 05:19:38 DEBUG : potato2: Excluded
2022/03/03 05:19:38 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2022/03/03 05:19:38 DEBUG : empty space: Unchanged skipping
2022/03/03 05:19:38 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:19:38 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
2022/03/03 05:19:38 DEBUG : Waiting for deletions to finish
2022/03/03 05:19:38 INFO  : There was nothing to transfer
2022/03/03 05:19:39 DEBUG : enormous: Excluded
2022/03/03 05:19:39 DEBUG : potato2: Excluded
2022/03/03 05:19:39 DEBUG : potato2: Excluded
2022/03/03 05:19:39 DEBUG : empty space: Size and modification time the same (differ by 456.789µs, within tolerance 1ms)
2022/03/03 05:19:39 DEBUG : empty space: Unchanged skipping
2022/03/03 05:19:39 DEBUG : Local file system at /tmp/rclone3180978143: Waiting for checks to finish
2022/03/03 05:19:39 DEBUG : Local file system at /tmp/rclone3180978143: Waiting for transfers to finish
2022/03/03 05:19:39 DEBUG : Waiting for deletions to finish
2022/03/03 05:19:39 INFO  : There was nothing to transfer
--- PASS: TestSyncWithExclude (3.42s)
=== RUN   TestSyncWithExcludeAndDeleteExcluded
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:19:45 DEBUG : enormous: Excluded
2022/03/03 05:19:45 DEBUG : potato2: Excluded
2022/03/03 05:19:45 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2022/03/03 05:19:45 DEBUG : empty space: Unchanged skipping
2022/03/03 05:19:45 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:19:45 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
2022/03/03 05:19:45 DEBUG : Waiting for deletions to finish
2022/03/03 05:19:46 INFO  : potato2: Deleted
2022/03/03 05:19:46 INFO  : enormous: Deleted
2022/03/03 05:19:46 INFO  : There was nothing to transfer
2022/03/03 05:19:47 DEBUG : empty space: Size and modification time the same (differ by 456.789µs, within tolerance 1ms)
2022/03/03 05:19:47 DEBUG : empty space: Unchanged skipping
2022/03/03 05:19:47 DEBUG : Local file system at /tmp/rclone3180978143: Waiting for checks to finish
2022/03/03 05:19:47 DEBUG : Local file system at /tmp/rclone3180978143: Waiting for transfers to finish
2022/03/03 05:19:47 DEBUG : Waiting for deletions to finish
2022/03/03 05:19:47 INFO  : enormous: Deleted
2022/03/03 05:19:47 INFO  : potato2: Deleted
2022/03/03 05:19:47 INFO  : There was nothing to transfer
--- PASS: TestSyncWithExcludeAndDeleteExcluded (7.36s)
=== RUN   TestSyncWithUpdateOlder
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:19:50 DEBUG : four: Sizes differ (src 4 vs dst 8)
2022/03/03 05:19:50 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring
2022/03/03 05:19:50 DEBUG : one: Destination is newer than source, skipping
2022/03/03 05:19:50 DEBUG : three: Sizes identical
2022/03/03 05:19:50 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping
2022/03/03 05:19:50 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b (Local file system at /tmp/rclone3180978143)
2022/03/03 05:19:50 DEBUG : two: sha1 = b10e0da85e90399abfec36c7376b20aa4596461b (B2 bucket rclone-test-sefococ1zileyew0jadadat1)
2022/03/03 05:19:50 DEBUG : two: sha1 differ
2022/03/03 05:19:50 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:19:50 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
2022/03/03 05:19:50 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK
2022/03/03 05:19:50 INFO  : two: Copied (replaced existing)
2022/03/03 05:19:51 DEBUG : four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK
2022/03/03 05:19:51 INFO  : four: Copied (replaced existing)
2022/03/03 05:19:53 DEBUG : five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK
2022/03/03 05:19:53 INFO  : five: Copied (new)
2022/03/03 05:19:53 DEBUG : Waiting for deletions to finish
2022/03/03 05:19:53 DEBUG : one: Destination is newer than source, skipping
2022/03/03 05:19:53 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:19:53 DEBUG : three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb (Local file system at /tmp/rclone3180978143)
2022/03/03 05:19:53 DEBUG : three: sha1 = 03731cad13f4cf7b59703c77529381cdec1632e5 (B2 bucket rclone-test-sefococ1zileyew0jadadat1)
2022/03/03 05:19:53 DEBUG : three: sha1 differ
2022/03/03 05:19:53 DEBUG : three: Destination mod time is within 1s of source but files differ, transferring
2022/03/03 05:19:53 DEBUG : five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK
2022/03/03 05:19:53 DEBUG : five: Size and sha1 of src and dst objects identical
2022/03/03 05:19:53 DEBUG : five: Destination mod time is within 1s of source and files identical, skipping
2022/03/03 05:19:53 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK
2022/03/03 05:19:53 DEBUG : two: Size and sha1 of src and dst objects identical
2022/03/03 05:19:53 DEBUG : two: Destination mod time is within 1s of source and files identical, skipping
2022/03/03 05:19:53 DEBUG : four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK
2022/03/03 05:19:53 DEBUG : four: Size and sha1 of src and dst objects identical
2022/03/03 05:19:53 DEBUG : four: Destination mod time is within 1s of source and files identical, skipping
2022/03/03 05:19:53 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
2022/03/03 05:19:54 DEBUG : three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK
2022/03/03 05:19:54 INFO  : three: Copied (replaced existing)
2022/03/03 05:19:54 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncWithUpdateOlder (10.20s)
=== RUN   TestSyncWithMaxDuration
    sync_test.go:1009: Skipping test on non local remote
--- SKIP: TestSyncWithMaxDuration (0.00s)
=== RUN   TestSyncWithTrackRenames
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
    sync_test.go:1058: Can track renames: true
2022/03/03 05:19:58 INFO  : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Making map for --track-renames
2022/03/03 05:19:58 INFO  : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Finished making map for --track-renames
2022/03/03 05:19:58 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:19:58 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for renames to finish
2022/03/03 05:19:58 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
2022/03/03 05:19:59 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK
2022/03/03 05:19:59 INFO  : potato: Copied (new)
2022/03/03 05:20:01 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK
2022/03/03 05:20:01 INFO  : yam: Copied (new)
2022/03/03 05:20:01 DEBUG : Waiting for deletions to finish
2022/03/03 05:20:01 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:20:01 DEBUG : potato: Unchanged skipping
2022/03/03 05:20:01 INFO  : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Making map for --track-renames
2022/03/03 05:20:01 INFO  : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Finished making map for --track-renames
2022/03/03 05:20:01 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:20:01 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for renames to finish
2022/03/03 05:20:02 DEBUG : Couldn't decode error response: EOF
2022/03/03 05:20:05 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK
2022/03/03 05:20:05 INFO  : yam: Copied (server-side copy) to: yaml
2022/03/03 05:20:06 INFO  : yam: Deleted
2022/03/03 05:20:06 INFO  : yaml: Renamed from "yam"
2022/03/03 05:20:06 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
2022/03/03 05:20:06 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncWithTrackRenames (9.98s)
=== RUN   TestParseRenamesStrategyModtime
--- PASS: TestParseRenamesStrategyModtime (0.00s)
=== RUN   TestRenamesStrategyModtime
--- PASS: TestRenamesStrategyModtime (0.00s)
=== RUN   TestSyncWithTrackRenamesStrategyModtime
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
    sync_test.go:1127: Can track renames: true
2022/03/03 05:20:08 INFO  : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Making map for --track-renames
2022/03/03 05:20:08 INFO  : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Finished making map for --track-renames
2022/03/03 05:20:08 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:20:08 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for renames to finish
2022/03/03 05:20:08 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
2022/03/03 05:20:09 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK
2022/03/03 05:20:09 INFO  : potato: Copied (new)
2022/03/03 05:20:09 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK
2022/03/03 05:20:09 INFO  : yam: Copied (new)
2022/03/03 05:20:09 DEBUG : Waiting for deletions to finish
2022/03/03 05:20:10 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:20:10 DEBUG : potato: Unchanged skipping
2022/03/03 05:20:10 INFO  : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Making map for --track-renames
2022/03/03 05:20:10 INFO  : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Finished making map for --track-renames
2022/03/03 05:20:10 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:20:10 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for renames to finish
2022/03/03 05:20:10 DEBUG : Couldn't decode error response: EOF
2022/03/03 05:20:11 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK
2022/03/03 05:20:11 INFO  : yam: Copied (server-side copy) to: yaml
2022/03/03 05:20:11 INFO  : yam: Deleted
2022/03/03 05:20:11 INFO  : yaml: Renamed from "yam"
2022/03/03 05:20:11 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
2022/03/03 05:20:11 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncWithTrackRenamesStrategyModtime (5.79s)
=== RUN   TestSyncWithTrackRenamesStrategyLeaf
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
    sync_test.go:1163: Can track renames: true
2022/03/03 05:20:14 INFO  : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Making map for --track-renames
2022/03/03 05:20:14 INFO  : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Finished making map for --track-renames
2022/03/03 05:20:14 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:20:14 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for renames to finish
2022/03/03 05:20:14 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
2022/03/03 05:20:14 DEBUG : sub/yam: Clearing upload URL because of error: Post "https://pod-000-1103-11.backblaze.com/b2api/v1/b2_upload_file/ef923d93306d4beb7ff80e1b/c001_v0001103_t0052": EOF
2022/03/03 05:20:14 DEBUG : pacer: low level retry 1/1 (error Post "https://pod-000-1103-11.backblaze.com/b2api/v1/b2_upload_file/ef923d93306d4beb7ff80e1b/c001_v0001103_t0052": EOF)
2022/03/03 05:20:14 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2022/03/03 05:20:14 DEBUG : sub/yam: Received error: Post "https://pod-000-1103-11.backblaze.com/b2api/v1/b2_upload_file/ef923d93306d4beb7ff80e1b/c001_v0001103_t0052": EOF - low level retry 1/10
2022/03/03 05:20:14 DEBUG : pacer: Reducing sleep to 10ms
2022/03/03 05:20:14 DEBUG : sub/yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK
2022/03/03 05:20:14 INFO  : sub/yam: Copied (new)
2022/03/03 05:20:16 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK
2022/03/03 05:20:16 INFO  : potato: Copied (new)
2022/03/03 05:20:16 DEBUG : Waiting for deletions to finish
2022/03/03 05:20:16 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:20:16 DEBUG : potato: Unchanged skipping
2022/03/03 05:20:16 INFO  : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Making map for --track-renames
2022/03/03 05:20:16 INFO  : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Finished making map for --track-renames
2022/03/03 05:20:16 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:20:16 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for renames to finish
2022/03/03 05:20:17 DEBUG : Couldn't decode error response: EOF
2022/03/03 05:20:18 DEBUG : sub/yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK
2022/03/03 05:20:18 INFO  : sub/yam: Copied (server-side copy) to: yam
2022/03/03 05:20:20 INFO  : sub/yam: Deleted
2022/03/03 05:20:20 INFO  : yam: Renamed from "sub/yam"
2022/03/03 05:20:20 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
2022/03/03 05:20:20 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncWithTrackRenamesStrategyLeaf (10.61s)
=== RUN   TestMoveWithDeleteEmptySrcDirs
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:20:24 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:20:24 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
2022/03/03 05:20:26 DEBUG : nested/sub dir/file: sha1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK
2022/03/03 05:20:26 INFO  : nested/sub dir/file: Copied (new)
2022/03/03 05:20:26 INFO  : nested/sub dir/file: Deleted
2022/03/03 05:20:26 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK
2022/03/03 05:20:26 INFO  : sub dir/hello world: Copied (new)
2022/03/03 05:20:26 INFO  : sub dir/hello world: Deleted
2022/03/03 05:20:26 INFO  : sub dir: Removing directory
2022/03/03 05:20:26 INFO  : nested/sub dir: Removing directory
2022/03/03 05:20:26 INFO  : nested: Removing directory
2022/03/03 05:20:26 DEBUG : Local file system at /tmp/rclone3180978143: deleted 3 directories
--- PASS: TestMoveWithDeleteEmptySrcDirs (4.17s)
=== RUN   TestMoveWithoutDeleteEmptySrcDirs
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:20:29 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:20:29 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
2022/03/03 05:20:29 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK
2022/03/03 05:20:29 INFO  : sub dir/hello world: Copied (new)
2022/03/03 05:20:29 INFO  : sub dir/hello world: Deleted
2022/03/03 05:20:30 DEBUG : nested/sub dir/file: sha1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK
2022/03/03 05:20:30 INFO  : nested/sub dir/file: Copied (new)
2022/03/03 05:20:30 INFO  : nested/sub dir/file: Deleted
--- PASS: TestMoveWithoutDeleteEmptySrcDirs (3.37s)
=== RUN   TestMoveWithIgnoreExisting
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:20:32 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:20:32 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
2022/03/03 05:20:32 DEBUG : existing: sha1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK
2022/03/03 05:20:32 INFO  : existing: Copied (new)
2022/03/03 05:20:32 INFO  : existing: Deleted
2022/03/03 05:20:34 DEBUG : existing-b: sha1 = 321f6b7e8bf7f29aab89c5e41a555b1b0baa41a9 OK
2022/03/03 05:20:34 INFO  : existing-b: Copied (new)
2022/03/03 05:20:34 INFO  : existing-b: Deleted
2022/03/03 05:20:34 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:20:34 DEBUG : existing: Destination exists, skipping
2022/03/03 05:20:34 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set
2022/03/03 05:20:34 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
2022/03/03 05:20:34 INFO  : There was nothing to transfer
--- PASS: TestMoveWithIgnoreExisting (4.08s)
=== RUN   TestServerSideMove
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:20:36 DEBUG : Creating backend with remote "TestB2:rclone-test-fidogup3milogeb3gemamir8"
    sync_test.go:1215: Server side move (if possible) B2 bucket rclone-test-sefococ1zileyew0jadadat1 -> B2 bucket rclone-test-fidogup3milogeb3gemamir8
2022/03/03 05:20:41 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms)
2022/03/03 05:20:41 DEBUG : empty space: Unchanged skipping
2022/03/03 05:20:41 DEBUG : potato3: Sizes differ (src 68 vs dst 60)
2022/03/03 05:20:41 DEBUG : B2 bucket rclone-test-fidogup3milogeb3gemamir8: Waiting for checks to finish
2022/03/03 05:20:42 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK
2022/03/03 05:20:42 INFO  : potato2: Copied (server-side copy)
2022/03/03 05:20:42 DEBUG : potato3: sha1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK
2022/03/03 05:20:42 INFO  : potato3: Copied (server-side copy)
2022/03/03 05:20:43 INFO  : potato2: Deleted
2022/03/03 05:20:43 INFO  : empty space: Deleted
2022/03/03 05:20:43 DEBUG : B2 bucket rclone-test-fidogup3milogeb3gemamir8: Waiting for transfers to finish
2022/03/03 05:20:43 INFO  : potato3: Deleted
2022/03/03 05:20:44 DEBUG : Creating backend with remote "TestB2:rclone-test-bewoluc9qitubik2qeyipej3"
2022/03/03 05:20:44 DEBUG : B2 bucket rclone-test-bewoluc9qitubik2qeyipej3: Waiting for checks to finish
2022/03/03 05:20:44 DEBUG : B2 bucket rclone-test-bewoluc9qitubik2qeyipej3: Waiting for transfers to finish
2022/03/03 05:20:45 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK
2022/03/03 05:20:45 INFO  : empty space: Copied (server-side copy)
2022/03/03 05:20:46 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK
2022/03/03 05:20:46 INFO  : potato2: Copied (server-side copy)
2022/03/03 05:20:46 INFO  : empty space: Deleted
2022/03/03 05:20:46 INFO  : potato2: Deleted
2022/03/03 05:20:48 DEBUG : potato3: sha1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK
2022/03/03 05:20:48 INFO  : potato3: Copied (server-side copy)
2022/03/03 05:20:48 INFO  : potato3: Deleted
2022/03/03 05:20:49 DEBUG : B2 bucket rclone-test-bewoluc9qitubik2qeyipej3: Purge remote
2022/03/03 05:20:49 DEBUG : empty space: Deleting (id "4_z1ff23d03309d4beb7ff80e1b_f1186ee9d47ac7084_d20220303_m052045_c001_v0001100_t0002")
2022/03/03 05:20:49 DEBUG : potato2: Deleting (id "4_z1ff23d03309d4beb7ff80e1b_f110468fc8dd6fc4e_d20220303_m052045_c001_v0001146_t0030")
2022/03/03 05:20:49 DEBUG : potato3: Deleting (id "4_z1ff23d03309d4beb7ff80e1b_f11846e11dab8f5b0_d20220303_m052045_c001_v0001105_t0021")
2022/03/03 05:20:50 DEBUG : B2 bucket rclone-test-fidogup3milogeb3gemamir8: Purge remote
2022/03/03 05:20:50 DEBUG : empty space: Deleting (id "4_z2fa21da3309d4beb7ff80e1b_f11288d6b45465f18_d20220303_m052045_c001_v0001116_t0026")
2022/03/03 05:20:50 DEBUG : empty space: Deleting (id "4_z2fa21da3309d4beb7ff80e1b_f11900452ff3bc61f_d20220303_m052040_c001_v0001102_t0039")
2022/03/03 05:20:50 DEBUG : potato2: Deleting (id "4_z2fa21da3309d4beb7ff80e1b_f11456464cd135f9b_d20220303_m052046_c001_v0001104_t0010")
2022/03/03 05:20:50 DEBUG : potato2: Deleting (id "4_z2fa21da3309d4beb7ff80e1b_f1145f90e27cf8e0e_d20220303_m052041_c001_v0001039_t0009")
2022/03/03 05:20:50 DEBUG : potato3: Deleting (id "4_z2fa21da3309d4beb7ff80e1b_f10562c4bfeee56ef_d20220303_m052048_c001_v0001117_t0029")
2022/03/03 05:20:50 DEBUG : potato3: Deleting (id "4_z2fa21da3309d4beb7ff80e1b_f114d159a2c2acfc3_d20220303_m052042_c001_v0001031_t0032")
2022/03/03 05:20:50 DEBUG : potato3: Deleting (id "4_z2fa21da3309d4beb7ff80e1b_f11900452ff3bc624_d20220303_m052040_c001_v0001102_t0041")
--- PASS: TestServerSideMove (15.23s)
=== RUN   TestServerSideMoveWithFilter
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:20:51 DEBUG : Creating backend with remote "TestB2:rclone-test-mironox8vopuqip2yolenup6"
    sync_test.go:1215: Server side move (if possible) B2 bucket rclone-test-sefococ1zileyew0jadadat1 -> B2 bucket rclone-test-mironox8vopuqip2yolenup6
2022/03/03 05:21:01 DEBUG : empty space: Excluded
2022/03/03 05:21:01 DEBUG : empty space: Excluded
2022/03/03 05:21:01 DEBUG : potato3: Sizes differ (src 68 vs dst 60)
2022/03/03 05:21:01 DEBUG : B2 bucket rclone-test-mironox8vopuqip2yolenup6: Waiting for checks to finish
2022/03/03 05:21:01 DEBUG : B2 bucket rclone-test-mironox8vopuqip2yolenup6: Waiting for transfers to finish
2022/03/03 05:21:01 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK
2022/03/03 05:21:01 INFO  : potato2: Copied (server-side copy)
2022/03/03 05:21:02 DEBUG : potato3: sha1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK
2022/03/03 05:21:02 INFO  : potato3: Copied (server-side copy)
2022/03/03 05:21:03 INFO  : potato3: Deleted
2022/03/03 05:21:03 INFO  : potato2: Deleted
2022/03/03 05:21:04 DEBUG : Creating backend with remote "TestB2:rclone-test-kemamat0gatolez5buvomak1"
2022/03/03 05:21:05 DEBUG : empty space: Excluded
2022/03/03 05:21:05 DEBUG : B2 bucket rclone-test-kemamat0gatolez5buvomak1: Waiting for checks to finish
2022/03/03 05:21:05 DEBUG : B2 bucket rclone-test-kemamat0gatolez5buvomak1: Waiting for transfers to finish
2022/03/03 05:21:05 DEBUG : potato3: sha1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK
2022/03/03 05:21:05 INFO  : potato3: Copied (server-side copy)
2022/03/03 05:21:06 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK
2022/03/03 05:21:06 INFO  : potato2: Copied (server-side copy)
2022/03/03 05:21:06 INFO  : potato3: Deleted
2022/03/03 05:21:07 INFO  : potato2: Deleted
2022/03/03 05:21:07 DEBUG : B2 bucket rclone-test-kemamat0gatolez5buvomak1: Purge remote
2022/03/03 05:21:07 DEBUG : potato2: Deleting (id "4_zdf727de3309d4beb7ff80e1b_f1010f3dc66516423_d20220303_m052105_c001_v0001101_t0035")
2022/03/03 05:21:07 DEBUG : potato3: Deleting (id "4_zdf727de3309d4beb7ff80e1b_f114d89353f1cafe1_d20220303_m052105_c001_v0001099_t0024")
2022/03/03 05:21:08 DEBUG : B2 bucket rclone-test-mironox8vopuqip2yolenup6: Purge remote
2022/03/03 05:21:08 DEBUG : empty space: Deleting (id "4_z4fa26d63309d4beb7ff80e1b_f114bde8225465395_d20220303_m052100_c001_v0001101_t0007")
2022/03/03 05:21:08 DEBUG : potato2: Deleting (id "4_z4fa26d63309d4beb7ff80e1b_f100d442b1fb053f9_d20220303_m052106_c001_v0001105_t0031")
2022/03/03 05:21:08 DEBUG : potato2: Deleting (id "4_z4fa26d63309d4beb7ff80e1b_f1053de68afcb8ec4_d20220303_m052101_c001_v0001098_t0018")
2022/03/03 05:21:08 DEBUG : potato3: Deleting (id "4_z4fa26d63309d4beb7ff80e1b_f1073886ed36ae192_d20220303_m052106_c001_v0001103_t0053")
2022/03/03 05:21:08 DEBUG : potato3: Deleting (id "4_z4fa26d63309d4beb7ff80e1b_f102d99ee8583c42d_d20220303_m052101_c001_v0001120_t0012")
2022/03/03 05:21:08 DEBUG : potato3: Deleting (id "4_z4fa26d63309d4beb7ff80e1b_f114bde82254653aa_d20220303_m052100_c001_v0001101_t0013")
--- PASS: TestServerSideMoveWithFilter (18.80s)
=== RUN   TestServerSideMoveDeleteEmptySourceDirs
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:21:10 DEBUG : Creating backend with remote "TestB2:rclone-test-howehuz9qemalor4qojiloq0"
2022/03/03 05:21:14 DEBUG : tomatoDir: Making directory
    sync_test.go:1215: Server side move (if possible) B2 bucket rclone-test-sefococ1zileyew0jadadat1 -> B2 bucket rclone-test-howehuz9qemalor4qojiloq0
2022/03/03 05:21:23 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms)
2022/03/03 05:21:23 DEBUG : empty space: Unchanged skipping
2022/03/03 05:21:23 DEBUG : B2 bucket rclone-test-howehuz9qemalor4qojiloq0: Waiting for checks to finish
2022/03/03 05:21:23 DEBUG : potato3: Sizes differ (src 68 vs dst 60)
2022/03/03 05:21:24 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK
2022/03/03 05:21:24 INFO  : potato2: Copied (server-side copy)
2022/03/03 05:21:25 INFO  : empty space: Deleted
2022/03/03 05:21:25 DEBUG : B2 bucket rclone-test-howehuz9qemalor4qojiloq0: Waiting for transfers to finish
2022/03/03 05:21:25 INFO  : potato2: Deleted
2022/03/03 05:21:25 DEBUG : potato3: sha1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK
2022/03/03 05:21:25 INFO  : potato3: Copied (server-side copy)
2022/03/03 05:21:25 INFO  : potato3: Deleted
2022/03/03 05:21:26 DEBUG : Creating backend with remote "TestB2:rclone-test-qiqehej5naperib3siracec9"
2022/03/03 05:21:27 DEBUG : tomatoDir: Making directory
2022/03/03 05:21:27 DEBUG : B2 bucket rclone-test-qiqehej5naperib3siracec9: Waiting for checks to finish
2022/03/03 05:21:27 DEBUG : B2 bucket rclone-test-qiqehej5naperib3siracec9: Waiting for transfers to finish
2022/03/03 05:21:28 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK
2022/03/03 05:21:28 INFO  : empty space: Copied (server-side copy)
2022/03/03 05:21:28 DEBUG : potato3: sha1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK
2022/03/03 05:21:28 INFO  : potato3: Copied (server-side copy)
2022/03/03 05:21:28 INFO  : empty space: Deleted
2022/03/03 05:21:28 INFO  : potato3: Deleted
2022/03/03 05:21:28 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK
2022/03/03 05:21:28 INFO  : potato2: Copied (server-side copy)
2022/03/03 05:21:28 INFO  : potato2: Deleted
2022/03/03 05:21:29 DEBUG : B2 bucket rclone-test-qiqehej5naperib3siracec9: Purge remote
2022/03/03 05:21:29 DEBUG : empty space: Deleting (id "4_z0f02dd53309d4beb7ff80e1b_f1044a958f1fc6772_d20220303_m052127_c001_v0001137_t0056")
2022/03/03 05:21:29 DEBUG : potato2: Deleting (id "4_z0f02dd53309d4beb7ff80e1b_f1019a3cce4ad7410_d20220303_m052127_c001_v0001109_t0030")
2022/03/03 05:21:29 DEBUG : potato3: Deleting (id "4_z0f02dd53309d4beb7ff80e1b_f108ae89fed9a1e4f_d20220303_m052127_c001_v0001099_t0059")
2022/03/03 05:21:30 DEBUG : B2 bucket rclone-test-howehuz9qemalor4qojiloq0: Purge remote
2022/03/03 05:21:30 DEBUG : empty space: Deleting (id "4_z9fa2ad53309d4beb7ff80e1b_f11478c848fb49662_d20220303_m052128_c001_v0001102_t0011")
2022/03/03 05:21:30 DEBUG : empty space: Deleting (id "4_z9fa2ad53309d4beb7ff80e1b_f119a1ae0f18fdca6_d20220303_m052116_c001_v0001115_t0010")
2022/03/03 05:21:30 DEBUG : potato2: Deleting (id "4_z9fa2ad53309d4beb7ff80e1b_f1145e7f18e0c3448_d20220303_m052128_c001_v0001146_t0041")
2022/03/03 05:21:30 DEBUG : potato2: Deleting (id "4_z9fa2ad53309d4beb7ff80e1b_f114bde8225465663_d20220303_m052123_c001_v0001101_t0045")
2022/03/03 05:21:30 DEBUG : potato3: Deleting (id "4_z9fa2ad53309d4beb7ff80e1b_f102c4abaeafe8f74_d20220303_m052128_c001_v0001092_t0045")
2022/03/03 05:21:30 DEBUG : potato3: Deleting (id "4_z9fa2ad53309d4beb7ff80e1b_f1077777b973fc206_d20220303_m052123_c001_v0001029_t0016")
2022/03/03 05:21:30 DEBUG : potato3: Deleting (id "4_z9fa2ad53309d4beb7ff80e1b_f119a1ae0f18fdcd9_d20220303_m052121_c001_v0001115_t0005")
--- PASS: TestServerSideMoveDeleteEmptySourceDirs (21.38s)
=== RUN   TestServerSideMoveOverlap
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:21:31 DEBUG : Creating backend with remote "TestB2:rclone-test-sefococ1zileyew0jadadat1/rclone-move-test"
2022/03/03 05:21:32 DEBUG : Couldn't decode error response: EOF
--- PASS: TestServerSideMoveOverlap (4.31s)
=== RUN   TestSyncOverlap
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:21:36 DEBUG : Creating backend with remote "TestB2:rclone-test-sefococ1zileyew0jadadat1/rclone-sync-test"
2022/03/03 05:21:36 DEBUG : Couldn't decode error response: EOF
--- PASS: TestSyncOverlap (0.75s)
=== RUN   TestSyncCompareDest
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:21:36 DEBUG : Creating backend with remote "TestB2:rclone-test-sefococ1zileyew0jadadat1/dst"
2022/03/03 05:21:37 DEBUG : Couldn't decode error response: EOF
2022/03/03 05:21:37 DEBUG : Creating backend with remote "TestB2:rclone-test-sefococ1zileyew0jadadat1/CompareDest"
2022/03/03 05:21:37 DEBUG : Couldn't decode error response: EOF
2022/03/03 05:21:38 DEBUG : Couldn't decode error response: EOF
2022/03/03 05:21:38 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1 path dst: Waiting for checks to finish
2022/03/03 05:21:38 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1 path dst: Waiting for transfers to finish
2022/03/03 05:21:39 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK
2022/03/03 05:21:39 INFO  : one: Copied (new)
2022/03/03 05:21:39 DEBUG : Waiting for deletions to finish
2022/03/03 05:21:40 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1 path dst: Waiting for checks to finish
2022/03/03 05:21:40 DEBUG : Couldn't decode error response: EOF
2022/03/03 05:21:40 DEBUG : one: Sizes differ (src 5 vs dst 3)
2022/03/03 05:21:40 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1 path dst: Waiting for transfers to finish
2022/03/03 05:21:41 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK
2022/03/03 05:21:41 INFO  : one: Copied (replaced existing)
2022/03/03 05:21:41 DEBUG : Waiting for deletions to finish
2022/03/03 05:21:46 DEBUG : pacer: low level retry 1/10 (error Post "https://api001.backblazeb2.com/b2api/v1/b2_list_file_names": EOF)
2022/03/03 05:21:46 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2022/03/03 05:21:46 DEBUG : pacer: Reducing sleep to 10ms
2022/03/03 05:21:47 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1 path dst: Waiting for checks to finish
2022/03/03 05:21:47 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2022/03/03 05:21:47 DEBUG : one: Destination found in --compare-dest, skipping
2022/03/03 05:21:47 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1 path dst: Waiting for transfers to finish
2022/03/03 05:21:47 DEBUG : Waiting for deletions to finish
2022/03/03 05:21:47 INFO  : There was nothing to transfer
2022/03/03 05:21:49 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2022/03/03 05:21:49 DEBUG : two: Destination found in --compare-dest, skipping
2022/03/03 05:21:49 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1 path dst: Waiting for checks to finish
2022/03/03 05:21:49 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2022/03/03 05:21:49 DEBUG : one: Destination found in --compare-dest, skipping
2022/03/03 05:21:49 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1 path dst: Waiting for transfers to finish
2022/03/03 05:21:49 DEBUG : Waiting for deletions to finish
2022/03/03 05:21:49 INFO  : There was nothing to transfer
2022/03/03 05:21:49 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2022/03/03 05:21:49 DEBUG : two: Destination found in --compare-dest, skipping
2022/03/03 05:21:49 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1 path dst: Waiting for checks to finish
2022/03/03 05:21:49 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2022/03/03 05:21:49 DEBUG : one: Destination found in --compare-dest, skipping
2022/03/03 05:21:49 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1 path dst: Waiting for transfers to finish
2022/03/03 05:21:49 DEBUG : Waiting for deletions to finish
2022/03/03 05:21:49 INFO  : There was nothing to transfer
2022/03/03 05:21:50 DEBUG : two: Modification times differ by -119h59m59.877s: 2011-12-30 12:59:59 +0000 UTC, 2011-12-25 12:59:59.123 +0000 UTC
2022/03/03 05:21:50 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK
2022/03/03 05:21:50 DEBUG : two: Destination found in --compare-dest, skipping
2022/03/03 05:21:50 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1 path dst: Waiting for checks to finish
2022/03/03 05:21:50 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2022/03/03 05:21:50 DEBUG : one: Destination found in --compare-dest, skipping
2022/03/03 05:21:50 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1 path dst: Waiting for transfers to finish
2022/03/03 05:21:50 DEBUG : Waiting for deletions to finish
2022/03/03 05:21:50 INFO  : There was nothing to transfer
2022/03/03 05:21:51 DEBUG : two: Sizes differ (src 5 vs dst 3)
2022/03/03 05:21:51 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1 path dst: Waiting for checks to finish
2022/03/03 05:21:51 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2022/03/03 05:21:51 DEBUG : one: Destination found in --compare-dest, skipping
2022/03/03 05:21:51 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1 path dst: Waiting for transfers to finish
2022/03/03 05:21:53 DEBUG : two: sha1 = a4be8877ad88f3109a6411985a1f359e10e2d930 OK
2022/03/03 05:21:53 INFO  : two: Copied (new)
2022/03/03 05:21:53 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncCompareDest (20.72s)
=== RUN   TestSyncMultipleCompareDest
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:22:00 DEBUG : Creating backend with remote "TestB2:rclone-test-sefococ1zileyew0jadadat1/dest"
2022/03/03 05:22:01 DEBUG : Couldn't decode error response: EOF
2022/03/03 05:22:01 DEBUG : Creating backend with remote "TestB2:rclone-test-sefococ1zileyew0jadadat1/pre-dest1"
2022/03/03 05:22:02 DEBUG : Couldn't decode error response: EOF
2022/03/03 05:22:02 DEBUG : Creating backend with remote "TestB2:rclone-test-sefococ1zileyew0jadadat1/pre-dest2"
2022/03/03 05:22:02 DEBUG : Couldn't decode error response: EOF
2022/03/03 05:22:03 DEBUG : 1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:22:03 DEBUG : 1: Destination found in --compare-dest, skipping
2022/03/03 05:22:03 DEBUG : Couldn't decode error response: EOF
2022/03/03 05:22:03 DEBUG : 2: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:22:03 DEBUG : 2: Destination found in --compare-dest, skipping
2022/03/03 05:22:03 DEBUG : Couldn't decode error response: EOF
2022/03/03 05:22:04 DEBUG : Couldn't decode error response: EOF
2022/03/03 05:22:04 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1 path dest: Waiting for checks to finish
2022/03/03 05:22:04 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1 path dest: Waiting for transfers to finish
2022/03/03 05:22:06 DEBUG : 3: sha1 = 77de68daecd823babbb58edb1c8e14d7106e83bb OK
2022/03/03 05:22:06 INFO  : 3: Copied (new)
2022/03/03 05:22:06 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncMultipleCompareDest (12.85s)
=== RUN   TestSyncCopyDest
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:22:10 DEBUG : Creating backend with remote "TestB2:rclone-test-sefococ1zileyew0jadadat1/dst"
2022/03/03 05:22:10 DEBUG : Config file has changed externaly - reloading
2022/03/03 05:22:11 DEBUG : Couldn't decode error response: EOF
2022/03/03 05:22:11 DEBUG : Creating backend with remote "TestB2:rclone-test-sefococ1zileyew0jadadat1/CopyDest"
2022/03/03 05:22:12 DEBUG : Couldn't decode error response: EOF
2022/03/03 05:22:12 DEBUG : Couldn't decode error response: EOF
2022/03/03 05:22:12 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1 path dst: Waiting for checks to finish
2022/03/03 05:22:12 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1 path dst: Waiting for transfers to finish
2022/03/03 05:22:13 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK
2022/03/03 05:22:13 INFO  : one: Copied (new)
2022/03/03 05:22:13 DEBUG : Waiting for deletions to finish
2022/03/03 05:22:14 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1 path dst: Waiting for checks to finish
2022/03/03 05:22:14 DEBUG : Couldn't decode error response: EOF
2022/03/03 05:22:14 DEBUG : one: Sizes differ (src 5 vs dst 3)
2022/03/03 05:22:14 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1 path dst: Waiting for transfers to finish
2022/03/03 05:22:15 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK
2022/03/03 05:22:15 INFO  : one: Copied (replaced existing)
2022/03/03 05:22:15 DEBUG : Waiting for deletions to finish
2022/03/03 05:22:18 DEBUG : Creating backend with remote "TestB2:rclone-test-sefococ1zileyew0jadadat1/BackupDir"
2022/03/03 05:22:19 DEBUG : Couldn't decode error response: EOF
2022/03/03 05:22:19 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1 path dst: Waiting for checks to finish
2022/03/03 05:22:19 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2022/03/03 05:22:19 DEBUG : one: Sizes differ (src 5 vs dst 3)
2022/03/03 05:22:19 DEBUG : Couldn't decode error response: EOF
2022/03/03 05:22:21 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK
2022/03/03 05:22:21 INFO  : one: Copied (server-side copy)
2022/03/03 05:22:21 INFO  : one: Deleted
2022/03/03 05:22:22 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK
2022/03/03 05:22:22 INFO  : one: Copied (server-side copy)
2022/03/03 05:22:22 DEBUG : one: Destination found in --copy-dest, using server-side copy
2022/03/03 05:22:22 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1 path dst: Waiting for transfers to finish
2022/03/03 05:22:22 DEBUG : Waiting for deletions to finish
2022/03/03 05:22:26 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2022/03/03 05:22:27 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK
2022/03/03 05:22:27 INFO  : two: Copied (server-side copy)
2022/03/03 05:22:27 DEBUG : two: Destination found in --copy-dest, using server-side copy
2022/03/03 05:22:27 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1 path dst: Waiting for checks to finish
2022/03/03 05:22:27 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2022/03/03 05:22:27 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2022/03/03 05:22:27 DEBUG : one: Unchanged skipping
2022/03/03 05:22:27 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1 path dst: Waiting for transfers to finish
2022/03/03 05:22:27 DEBUG : Waiting for deletions to finish
2022/03/03 05:22:27 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1 path dst: Waiting for checks to finish
2022/03/03 05:22:28 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2022/03/03 05:22:28 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2022/03/03 05:22:28 DEBUG : one: Unchanged skipping
2022/03/03 05:22:28 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2022/03/03 05:22:28 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2022/03/03 05:22:28 DEBUG : two: Unchanged skipping
2022/03/03 05:22:28 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1 path dst: Waiting for transfers to finish
2022/03/03 05:22:28 DEBUG : Waiting for deletions to finish
2022/03/03 05:22:28 INFO  : There was nothing to transfer
2022/03/03 05:22:29 DEBUG : three: Sizes differ (src 7 vs dst 5)
2022/03/03 05:22:29 DEBUG : three: Destination not found in --copy-dest
2022/03/03 05:22:29 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1 path dst: Waiting for checks to finish
2022/03/03 05:22:30 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2022/03/03 05:22:30 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2022/03/03 05:22:30 DEBUG : one: Unchanged skipping
2022/03/03 05:22:30 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2022/03/03 05:22:30 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2022/03/03 05:22:30 DEBUG : two: Unchanged skipping
2022/03/03 05:22:30 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1 path dst: Waiting for transfers to finish
2022/03/03 05:22:30 DEBUG : three: sha1 = 370e613335f3cf2f19b2a2d35a86402f55c659f8 OK
2022/03/03 05:22:30 INFO  : three: Copied (new)
2022/03/03 05:22:30 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncCopyDest (29.80s)
=== RUN   TestSyncBackupDir
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:22:45 DEBUG : Creating backend with remote "TestB2:rclone-test-sefococ1zileyew0jadadat1/dst"
2022/03/03 05:22:46 DEBUG : Couldn't decode error response: EOF
2022/03/03 05:22:46 DEBUG : Creating backend with remote "TestB2:rclone-test-sefococ1zileyew0jadadat1/backup"
2022/03/03 05:22:46 DEBUG : Couldn't decode error response: EOF
2022/03/03 05:22:46 DEBUG : one: Sizes differ (src 4 vs dst 3)
2022/03/03 05:22:46 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1 path dst: Waiting for checks to finish
2022/03/03 05:22:46 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:22:46 DEBUG : two: Unchanged skipping
2022/03/03 05:22:46 DEBUG : Couldn't decode error response: EOF
2022/03/03 05:22:48 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK
2022/03/03 05:22:48 INFO  : one: Copied (server-side copy)
2022/03/03 05:22:48 INFO  : one: Deleted
2022/03/03 05:22:48 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1 path dst: Waiting for transfers to finish
2022/03/03 05:22:49 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK
2022/03/03 05:22:49 INFO  : one: Copied (new)
2022/03/03 05:22:49 DEBUG : Waiting for deletions to finish
2022/03/03 05:22:49 DEBUG : Couldn't decode error response: EOF
2022/03/03 05:22:50 DEBUG : three.txt: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK
2022/03/03 05:22:50 INFO  : three.txt: Copied (server-side copy)
2022/03/03 05:22:51 INFO  : three.txt: Deleted
2022/03/03 05:22:51 INFO  : three.txt: Moved into backup dir
2022/03/03 05:22:52 DEBUG : one: Sizes differ (src 5 vs dst 4)
2022/03/03 05:22:52 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:22:52 DEBUG : two: Unchanged skipping
2022/03/03 05:22:52 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1 path dst: Waiting for checks to finish
2022/03/03 05:22:54 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK
2022/03/03 05:22:54 INFO  : one: Copied (server-side copy)
2022/03/03 05:22:55 INFO  : one: Deleted
2022/03/03 05:22:55 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1 path dst: Waiting for transfers to finish
2022/03/03 05:22:56 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK
2022/03/03 05:22:56 INFO  : one: Copied (new)
2022/03/03 05:22:56 DEBUG : Waiting for deletions to finish
2022/03/03 05:22:57 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK
2022/03/03 05:22:57 INFO  : three.txt: Copied (server-side copy)
2022/03/03 05:22:58 INFO  : three.txt: Deleted
2022/03/03 05:22:58 INFO  : three.txt: Moved into backup dir
--- PASS: TestSyncBackupDir (23.18s)
=== RUN   TestSyncBackupDirWithSuffix
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:23:08 DEBUG : Creating backend with remote "TestB2:rclone-test-sefococ1zileyew0jadadat1/dst"
2022/03/03 05:23:09 DEBUG : Couldn't decode error response: EOF
2022/03/03 05:23:09 DEBUG : Creating backend with remote "TestB2:rclone-test-sefococ1zileyew0jadadat1/backup"
2022/03/03 05:23:10 DEBUG : Couldn't decode error response: EOF
2022/03/03 05:23:10 DEBUG : one: Sizes differ (src 4 vs dst 3)
2022/03/03 05:23:10 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1 path dst: Waiting for checks to finish
2022/03/03 05:23:10 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:23:10 DEBUG : two: Unchanged skipping
2022/03/03 05:23:10 DEBUG : Couldn't decode error response: EOF
2022/03/03 05:23:12 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK
2022/03/03 05:23:12 INFO  : one: Copied (server-side copy) to: one.bak
2022/03/03 05:23:15 INFO  : one: Deleted
2022/03/03 05:23:15 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1 path dst: Waiting for transfers to finish
2022/03/03 05:23:17 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK
2022/03/03 05:23:17 INFO  : one: Copied (new)
2022/03/03 05:23:17 DEBUG : Waiting for deletions to finish
2022/03/03 05:23:17 DEBUG : Couldn't decode error response: EOF
2022/03/03 05:23:20 DEBUG : three.txt: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK
2022/03/03 05:23:20 INFO  : three.txt: Copied (server-side copy) to: three.txt.bak
2022/03/03 05:23:20 INFO  : three.txt: Deleted
2022/03/03 05:23:20 INFO  : three.txt: Moved into backup dir
2022/03/03 05:23:22 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1 path dst: Waiting for checks to finish
2022/03/03 05:23:22 DEBUG : one: Sizes differ (src 5 vs dst 4)
2022/03/03 05:23:22 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:23:22 DEBUG : two: Unchanged skipping
2022/03/03 05:23:24 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK
2022/03/03 05:23:24 INFO  : one: Copied (server-side copy) to: one.bak
2022/03/03 05:23:25 INFO  : one: Deleted
2022/03/03 05:23:25 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1 path dst: Waiting for transfers to finish
2022/03/03 05:23:26 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK
2022/03/03 05:23:26 INFO  : one: Copied (new)
2022/03/03 05:23:26 DEBUG : Waiting for deletions to finish
2022/03/03 05:23:29 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK
2022/03/03 05:23:29 INFO  : three.txt: Copied (server-side copy) to: three.txt.bak
2022/03/03 05:23:29 INFO  : three.txt: Deleted
2022/03/03 05:23:29 INFO  : three.txt: Moved into backup dir
--- PASS: TestSyncBackupDirWithSuffix (31.99s)
=== RUN   TestSyncBackupDirWithSuffixKeepExtension
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:23:40 DEBUG : Creating backend with remote "TestB2:rclone-test-sefococ1zileyew0jadadat1/dst"
2022/03/03 05:23:41 DEBUG : Couldn't decode error response: EOF
2022/03/03 05:23:41 DEBUG : Creating backend with remote "TestB2:rclone-test-sefococ1zileyew0jadadat1/backup"
2022/03/03 05:23:42 DEBUG : Couldn't decode error response: EOF
2022/03/03 05:23:42 DEBUG : one: Sizes differ (src 4 vs dst 3)
2022/03/03 05:23:42 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:23:42 DEBUG : two: Unchanged skipping
2022/03/03 05:23:42 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1 path dst: Waiting for checks to finish
2022/03/03 05:23:42 DEBUG : Couldn't decode error response: EOF
2022/03/03 05:23:44 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK
2022/03/03 05:23:44 INFO  : one: Copied (server-side copy) to: one-2019-01-01
2022/03/03 05:23:45 INFO  : one: Deleted
2022/03/03 05:23:45 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1 path dst: Waiting for transfers to finish
2022/03/03 05:23:46 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK
2022/03/03 05:23:46 INFO  : one: Copied (new)
2022/03/03 05:23:46 DEBUG : Waiting for deletions to finish
2022/03/03 05:23:46 DEBUG : Couldn't decode error response: EOF
2022/03/03 05:23:46 DEBUG : three.txt: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK
2022/03/03 05:23:46 INFO  : three.txt: Copied (server-side copy) to: three-2019-01-01.txt
2022/03/03 05:23:47 INFO  : three.txt: Deleted
2022/03/03 05:23:47 INFO  : three.txt: Moved into backup dir
2022/03/03 05:23:50 DEBUG : one: Sizes differ (src 5 vs dst 4)
2022/03/03 05:23:50 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:23:50 DEBUG : two: Unchanged skipping
2022/03/03 05:23:50 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1 path dst: Waiting for checks to finish
2022/03/03 05:23:51 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK
2022/03/03 05:23:51 INFO  : one: Copied (server-side copy) to: one-2019-01-01
2022/03/03 05:23:52 INFO  : one: Deleted
2022/03/03 05:23:52 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1 path dst: Waiting for transfers to finish
2022/03/03 05:23:53 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK
2022/03/03 05:23:53 INFO  : one: Copied (new)
2022/03/03 05:23:53 DEBUG : Waiting for deletions to finish
2022/03/03 05:23:54 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK
2022/03/03 05:23:54 INFO  : three.txt: Copied (server-side copy) to: three-2019-01-01.txt
2022/03/03 05:23:54 INFO  : three.txt: Deleted
2022/03/03 05:23:54 INFO  : three.txt: Moved into backup dir
--- PASS: TestSyncBackupDirWithSuffixKeepExtension (27.34s)
=== RUN   TestSyncBackupDirSuffixOnly
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:24:05 DEBUG : Creating backend with remote "TestB2:rclone-test-sefococ1zileyew0jadadat1/dst"
2022/03/03 05:24:07 DEBUG : Couldn't decode error response: EOF
2022/03/03 05:24:07 DEBUG : one: Sizes differ (src 4 vs dst 3)
2022/03/03 05:24:07 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:24:07 DEBUG : two: Unchanged skipping
2022/03/03 05:24:07 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1 path dst: Waiting for checks to finish
2022/03/03 05:24:07 DEBUG : Couldn't decode error response: EOF
2022/03/03 05:24:08 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK
2022/03/03 05:24:08 INFO  : one: Copied (server-side copy) to: one.bak
2022/03/03 05:24:08 INFO  : one: Deleted
2022/03/03 05:24:08 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1 path dst: Waiting for transfers to finish
2022/03/03 05:24:10 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK
2022/03/03 05:24:10 INFO  : one: Copied (new)
2022/03/03 05:24:10 DEBUG : Waiting for deletions to finish
2022/03/03 05:24:10 DEBUG : Couldn't decode error response: EOF
2022/03/03 05:24:11 DEBUG : three.txt: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK
2022/03/03 05:24:11 INFO  : three.txt: Copied (server-side copy) to: three.txt.bak
2022/03/03 05:24:11 INFO  : three.txt: Deleted
2022/03/03 05:24:11 INFO  : three.txt: Moved into backup dir
2022/03/03 05:24:13 DEBUG : one.bak: Excluded
2022/03/03 05:24:13 DEBUG : three.txt.bak: Excluded
2022/03/03 05:24:13 DEBUG : one: Sizes differ (src 5 vs dst 4)
2022/03/03 05:24:13 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:24:13 DEBUG : two: Unchanged skipping
2022/03/03 05:24:13 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1 path dst: Waiting for checks to finish
2022/03/03 05:24:14 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK
2022/03/03 05:24:14 INFO  : one: Copied (server-side copy) to: one.bak
2022/03/03 05:24:16 INFO  : one: Deleted
2022/03/03 05:24:16 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1 path dst: Waiting for transfers to finish
2022/03/03 05:24:18 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK
2022/03/03 05:24:18 INFO  : one: Copied (new)
2022/03/03 05:24:18 DEBUG : Waiting for deletions to finish
2022/03/03 05:24:19 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK
2022/03/03 05:24:19 INFO  : three.txt: Copied (server-side copy) to: three.txt.bak
2022/03/03 05:24:19 INFO  : three.txt: Deleted
2022/03/03 05:24:19 INFO  : three.txt: Moved into backup dir
--- PASS: TestSyncBackupDirSuffixOnly (20.44s)
=== RUN   TestSyncSuffix
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:24:26 DEBUG : Creating backend with remote "TestB2:rclone-test-sefococ1zileyew0jadadat1/dst"
2022/03/03 05:24:27 DEBUG : Couldn't decode error response: EOF
2022/03/03 05:24:27 DEBUG : one: Sizes differ (src 4 vs dst 3)
2022/03/03 05:24:28 DEBUG : Couldn't decode error response: EOF
2022/03/03 05:24:30 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK
2022/03/03 05:24:30 INFO  : one: Copied (server-side copy) to: one.bak
2022/03/03 05:24:31 INFO  : one: Deleted
2022/03/03 05:24:32 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK
2022/03/03 05:24:32 INFO  : one: Copied (new)
2022/03/03 05:24:32 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:24:32 DEBUG : two: Unchanged skipping
2022/03/03 05:24:33 DEBUG : three.txt: Sizes differ (src 6 vs dst 5)
2022/03/03 05:24:33 DEBUG : Couldn't decode error response: EOF
2022/03/03 05:24:36 DEBUG : three.txt: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK
2022/03/03 05:24:36 INFO  : three.txt: Copied (server-side copy) to: three.txt.bak
2022/03/03 05:24:36 INFO  : three.txt: Deleted
2022/03/03 05:24:39 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK
2022/03/03 05:24:39 INFO  : three.txt: Copied (new)
2022/03/03 05:24:40 DEBUG : one: Sizes differ (src 5 vs dst 4)
2022/03/03 05:24:40 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK
2022/03/03 05:24:40 INFO  : one: Copied (server-side copy) to: one.bak
2022/03/03 05:24:41 INFO  : one: Deleted
2022/03/03 05:24:44 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK
2022/03/03 05:24:44 INFO  : one: Copied (new)
2022/03/03 05:24:44 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:24:44 DEBUG : two: Unchanged skipping
2022/03/03 05:24:44 DEBUG : three.txt: Sizes differ (src 19 vs dst 6)
2022/03/03 05:24:46 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK
2022/03/03 05:24:46 INFO  : three.txt: Copied (server-side copy) to: three.txt.bak
2022/03/03 05:24:47 INFO  : three.txt: Deleted
2022/03/03 05:24:52 DEBUG : three.txt: sha1 = 476e1707cbd7f0497a7dc4d44f0b07b8566ffc30 OK
2022/03/03 05:24:52 INFO  : three.txt: Copied (new)
2022/03/03 05:24:52 DEBUG : pacer: low level retry 1/10 (error Post "https://api001.backblazeb2.com/b2api/v1/b2_list_file_names": EOF)
2022/03/03 05:24:52 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2022/03/03 05:24:52 DEBUG : pacer: Reducing sleep to 10ms
--- PASS: TestSyncSuffix (38.55s)
=== RUN   TestSyncSuffixKeepExtension
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:25:06 DEBUG : Creating backend with remote "TestB2:rclone-test-sefococ1zileyew0jadadat1/dst"
2022/03/03 05:25:07 DEBUG : Couldn't decode error response: EOF
2022/03/03 05:25:07 DEBUG : one: Sizes differ (src 4 vs dst 3)
2022/03/03 05:25:08 DEBUG : Couldn't decode error response: EOF
2022/03/03 05:25:09 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK
2022/03/03 05:25:09 INFO  : one: Copied (server-side copy) to: one-2019-01-01
2022/03/03 05:25:09 INFO  : one: Deleted
2022/03/03 05:25:11 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK
2022/03/03 05:25:11 INFO  : one: Copied (new)
2022/03/03 05:25:11 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:25:11 DEBUG : two: Unchanged skipping
2022/03/03 05:25:11 DEBUG : three.txt: Sizes differ (src 6 vs dst 5)
2022/03/03 05:25:11 DEBUG : Couldn't decode error response: EOF
2022/03/03 05:25:12 DEBUG : three.txt: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK
2022/03/03 05:25:12 INFO  : three.txt: Copied (server-side copy) to: three-2019-01-01.txt
2022/03/03 05:25:14 INFO  : three.txt: Deleted
2022/03/03 05:25:14 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK
2022/03/03 05:25:14 INFO  : three.txt: Copied (new)
2022/03/03 05:25:15 DEBUG : one: Sizes differ (src 5 vs dst 4)
2022/03/03 05:25:15 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK
2022/03/03 05:25:15 INFO  : one: Copied (server-side copy) to: one-2019-01-01
2022/03/03 05:25:18 INFO  : one: Deleted
2022/03/03 05:25:18 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK
2022/03/03 05:25:18 INFO  : one: Copied (new)
2022/03/03 05:25:19 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:25:19 DEBUG : two: Unchanged skipping
2022/03/03 05:25:19 DEBUG : three.txt: Sizes differ (src 19 vs dst 6)
2022/03/03 05:25:20 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK
2022/03/03 05:25:20 INFO  : three.txt: Copied (server-side copy) to: three-2019-01-01.txt
2022/03/03 05:25:20 INFO  : three.txt: Deleted
2022/03/03 05:25:20 DEBUG : three.txt: sha1 = 476e1707cbd7f0497a7dc4d44f0b07b8566ffc30 OK
2022/03/03 05:25:20 INFO  : three.txt: Copied (new)
--- PASS: TestSyncSuffixKeepExtension (22.47s)
=== RUN   TestSyncUTFNorm
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:25:26 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:25:26 DEBUG : Testêé: Sizes differ (src 14 vs dst 18)
2022/03/03 05:25:26 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
2022/03/03 05:25:29 DEBUG : Testêé: sha1 = a54d88e06612d820bc3be72877c74f257b561b19 OK
2022/03/03 05:25:29 INFO  : Testêé: Copied (replaced existing) to: Testêé
2022/03/03 05:25:29 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncUTFNorm (6.49s)
=== RUN   TestSyncImmutable
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:25:31 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:25:31 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
2022/03/03 05:25:33 DEBUG : existing: sha1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK
2022/03/03 05:25:33 INFO  : existing: Copied (new)
2022/03/03 05:25:33 DEBUG : Waiting for deletions to finish
2022/03/03 05:25:33 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:25:33 DEBUG : existing: Sizes differ (src 8 vs dst 6)
2022/03/03 05:25:33 ERROR : existing: Source and destination exist but do not match: immutable file modified
2022/03/03 05:25:33 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
2022/03/03 05:25:33 ERROR : B2 bucket rclone-test-sefococ1zileyew0jadadat1: not deleting files as there were IO errors
2022/03/03 05:25:33 ERROR : B2 bucket rclone-test-sefococ1zileyew0jadadat1: not deleting directories as there were IO errors
--- PASS: TestSyncImmutable (4.15s)
=== RUN   TestSyncIgnoreCase
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:25:36 DEBUG : existing: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:25:36 DEBUG : existing: Unchanged skipping
2022/03/03 05:25:36 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:25:36 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
2022/03/03 05:25:36 DEBUG : Waiting for deletions to finish
2022/03/03 05:25:36 INFO  : There was nothing to transfer
--- PASS: TestSyncIgnoreCase (4.24s)
=== RUN   TestMaxTransfer
=== RUN   TestMaxTransfer/Hard
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
    sync_test.go:1989: This test only runs on local
=== RUN   TestMaxTransfer/Soft
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
    sync_test.go:1989: This test only runs on local
=== RUN   TestMaxTransfer/Cautious
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
    sync_test.go:1989: This test only runs on local
--- PASS: TestMaxTransfer (1.04s)
    --- SKIP: TestMaxTransfer/Hard (0.35s)
    --- SKIP: TestMaxTransfer/Soft (0.34s)
    --- SKIP: TestMaxTransfer/Cautious (0.35s)
=== RUN   TestSyncConcurrentDelete
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:26:24 DEBUG : both0: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:26:24 DEBUG : both0: Unchanged skipping
2022/03/03 05:26:24 DEBUG : both1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:26:24 DEBUG : both1: Unchanged skipping
2022/03/03 05:26:24 DEBUG : both10: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:26:24 DEBUG : both10: Unchanged skipping
2022/03/03 05:26:24 DEBUG : both11: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:26:24 DEBUG : both11: Unchanged skipping
2022/03/03 05:26:24 DEBUG : both12: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:26:24 DEBUG : both12: Unchanged skipping
2022/03/03 05:26:24 DEBUG : both13: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:26:24 DEBUG : both13: Unchanged skipping
2022/03/03 05:26:24 DEBUG : both14: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:26:24 DEBUG : both14: Unchanged skipping
2022/03/03 05:26:24 DEBUG : both15: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:26:24 DEBUG : both15: Unchanged skipping
2022/03/03 05:26:24 DEBUG : both16: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:26:24 DEBUG : both16: Unchanged skipping
2022/03/03 05:26:24 DEBUG : both17: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:26:24 DEBUG : both17: Unchanged skipping
2022/03/03 05:26:24 DEBUG : both18: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:26:24 DEBUG : both18: Unchanged skipping
2022/03/03 05:26:24 DEBUG : both19: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:26:24 DEBUG : both19: Unchanged skipping
2022/03/03 05:26:24 DEBUG : both2: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:26:24 DEBUG : both2: Unchanged skipping
2022/03/03 05:26:24 DEBUG : both3: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:26:24 DEBUG : both3: Unchanged skipping
2022/03/03 05:26:24 DEBUG : both4: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:26:24 DEBUG : both4: Unchanged skipping
2022/03/03 05:26:24 DEBUG : both5: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:26:24 DEBUG : both5: Unchanged skipping
2022/03/03 05:26:24 DEBUG : both6: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:26:24 DEBUG : both6: Unchanged skipping
2022/03/03 05:26:24 DEBUG : both7: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:26:24 DEBUG : both7: Unchanged skipping
2022/03/03 05:26:24 DEBUG : both8: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:26:24 DEBUG : both8: Unchanged skipping
2022/03/03 05:26:24 DEBUG : both9: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:26:24 DEBUG : both9: Unchanged skipping
2022/03/03 05:26:24 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:26:24 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
2022/03/03 05:26:24 DEBUG : Waiting for deletions to finish
2022/03/03 05:26:24 INFO  : only1: Deleted
2022/03/03 05:26:25 INFO  : only10: Deleted
2022/03/03 05:26:25 INFO  : only12: Deleted
2022/03/03 05:26:26 INFO  : only6: Deleted
2022/03/03 05:26:26 INFO  : only13: Deleted
2022/03/03 05:26:26 INFO  : only2: Deleted
2022/03/03 05:26:26 INFO  : only18: Deleted
2022/03/03 05:26:26 INFO  : only8: Deleted
2022/03/03 05:26:26 INFO  : only3: Deleted
2022/03/03 05:26:26 INFO  : only17: Deleted
2022/03/03 05:26:27 INFO  : only15: Deleted
2022/03/03 05:26:27 INFO  : only14: Deleted
2022/03/03 05:26:28 INFO  : only4: Deleted
2022/03/03 05:26:28 INFO  : only7: Deleted
2022/03/03 05:26:28 INFO  : only11: Deleted
2022/03/03 05:26:29 INFO  : only0: Deleted
2022/03/03 05:26:29 INFO  : only16: Deleted
2022/03/03 05:26:29 INFO  : only9: Deleted
2022/03/03 05:26:31 INFO  : only5: Deleted
2022/03/03 05:26:32 INFO  : only19: Deleted
2022/03/03 05:26:32 INFO  : There was nothing to transfer
--- PASS: TestSyncConcurrentDelete (71.65s)
=== RUN   TestSyncConcurrentTruncate
    run.go:181: Remote "B2 bucket rclone-test-sefococ1zileyew0jadadat1", Local "Local file system at /tmp/rclone3180978143", Modify Window "1ms"
2022/03/03 05:27:36 DEBUG : both0: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:27:36 DEBUG : both0: Unchanged skipping
2022/03/03 05:27:36 DEBUG : both10: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:27:36 DEBUG : both10: Unchanged skipping
2022/03/03 05:27:36 DEBUG : both11: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:27:36 DEBUG : both11: Unchanged skipping
2022/03/03 05:27:36 DEBUG : both12: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:27:36 DEBUG : both12: Unchanged skipping
2022/03/03 05:27:36 DEBUG : both13: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:27:36 DEBUG : both13: Unchanged skipping
2022/03/03 05:27:36 DEBUG : both14: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:27:36 DEBUG : both14: Unchanged skipping
2022/03/03 05:27:36 DEBUG : both15: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:27:36 DEBUG : both15: Unchanged skipping
2022/03/03 05:27:36 DEBUG : both1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:27:36 DEBUG : both1: Unchanged skipping
2022/03/03 05:27:36 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for checks to finish
2022/03/03 05:27:36 DEBUG : both18: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:27:36 DEBUG : both18: Unchanged skipping
2022/03/03 05:27:36 DEBUG : both19: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:27:36 DEBUG : both19: Unchanged skipping
2022/03/03 05:27:36 DEBUG : both2: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:27:36 DEBUG : both3: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:27:36 DEBUG : both2: Unchanged skipping
2022/03/03 05:27:36 DEBUG : both4: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:27:36 DEBUG : both4: Unchanged skipping
2022/03/03 05:27:36 DEBUG : both5: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:27:36 DEBUG : both5: Unchanged skipping
2022/03/03 05:27:36 DEBUG : both6: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:27:36 DEBUG : both6: Unchanged skipping
2022/03/03 05:27:36 DEBUG : both7: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:27:36 DEBUG : both7: Unchanged skipping
2022/03/03 05:27:36 DEBUG : both8: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:27:36 DEBUG : both8: Unchanged skipping
2022/03/03 05:27:36 DEBUG : both9: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:27:36 DEBUG : both9: Unchanged skipping
2022/03/03 05:27:36 DEBUG : only0: Sizes differ (src 0 vs dst 6)
2022/03/03 05:27:36 DEBUG : both16: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:27:36 DEBUG : both16: Unchanged skipping
2022/03/03 05:27:36 DEBUG : both17: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2022/03/03 05:27:36 DEBUG : both17: Unchanged skipping
2022/03/03 05:27:36 DEBUG : only1: Sizes differ (src 0 vs dst 6)
2022/03/03 05:27:36 DEBUG : only12: Sizes differ (src 0 vs dst 6)
2022/03/03 05:27:36 DEBUG : only13: Sizes differ (src 0 vs dst 6)
2022/03/03 05:27:36 DEBUG : only14: Sizes differ (src 0 vs dst 6)
2022/03/03 05:27:36 DEBUG : only15: Sizes differ (src 0 vs dst 6)
2022/03/03 05:27:36 DEBUG : only16: Sizes differ (src 0 vs dst 6)
2022/03/03 05:27:36 DEBUG : only17: Sizes differ (src 0 vs dst 6)
2022/03/03 05:27:36 DEBUG : only18: Sizes differ (src 0 vs dst 6)
2022/03/03 05:27:36 DEBUG : only19: Sizes differ (src 0 vs dst 6)
2022/03/03 05:27:36 DEBUG : only2: Sizes differ (src 0 vs dst 6)
2022/03/03 05:27:36 DEBUG : only3: Sizes differ (src 0 vs dst 6)
2022/03/03 05:27:36 DEBUG : only4: Sizes differ (src 0 vs dst 6)
2022/03/03 05:27:36 DEBUG : only5: Sizes differ (src 0 vs dst 6)
2022/03/03 05:27:36 DEBUG : only6: Sizes differ (src 0 vs dst 6)
2022/03/03 05:27:36 DEBUG : only7: Sizes differ (src 0 vs dst 6)
2022/03/03 05:27:36 DEBUG : only8: Sizes differ (src 0 vs dst 6)
2022/03/03 05:27:36 DEBUG : only9: Sizes differ (src 0 vs dst 6)
2022/03/03 05:27:36 DEBUG : both3: Unchanged skipping
2022/03/03 05:27:36 DEBUG : only10: Sizes differ (src 0 vs dst 6)
2022/03/03 05:27:36 DEBUG : only11: Sizes differ (src 0 vs dst 6)
2022/03/03 05:27:36 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Waiting for transfers to finish
2022/03/03 05:27:36 DEBUG : only1: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2022/03/03 05:27:36 INFO  : only1: Copied (replaced existing)
2022/03/03 05:27:37 DEBUG : only14: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2022/03/03 05:27:37 INFO  : only14: Copied (replaced existing)
2022/03/03 05:27:37 DEBUG : only12: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2022/03/03 05:27:37 INFO  : only12: Copied (replaced existing)
2022/03/03 05:27:37 DEBUG : only15: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2022/03/03 05:27:37 INFO  : only15: Copied (replaced existing)
2022/03/03 05:27:37 DEBUG : only16: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2022/03/03 05:27:37 INFO  : only16: Copied (replaced existing)
2022/03/03 05:27:37 DEBUG : only17: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2022/03/03 05:27:37 INFO  : only17: Copied (replaced existing)
2022/03/03 05:27:38 DEBUG : only19: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2022/03/03 05:27:38 INFO  : only19: Copied (replaced existing)
2022/03/03 05:27:38 DEBUG : only18: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2022/03/03 05:27:38 INFO  : only18: Copied (replaced existing)
2022/03/03 05:27:38 DEBUG : only2: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2022/03/03 05:27:38 INFO  : only2: Copied (replaced existing)
2022/03/03 05:27:38 DEBUG : only3: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2022/03/03 05:27:38 INFO  : only3: Copied (replaced existing)
2022/03/03 05:27:38 DEBUG : only4: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2022/03/03 05:27:38 INFO  : only4: Copied (replaced existing)
2022/03/03 05:27:38 DEBUG : only5: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2022/03/03 05:27:38 INFO  : only5: Copied (replaced existing)
2022/03/03 05:27:38 DEBUG : only6: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2022/03/03 05:27:38 INFO  : only6: Copied (replaced existing)
2022/03/03 05:27:39 DEBUG : only7: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2022/03/03 05:27:39 INFO  : only7: Copied (replaced existing)
2022/03/03 05:27:39 DEBUG : only0: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2022/03/03 05:27:39 INFO  : only0: Copied (replaced existing)
2022/03/03 05:27:39 DEBUG : only13: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2022/03/03 05:27:39 INFO  : only13: Copied (replaced existing)
2022/03/03 05:27:39 DEBUG : only8: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2022/03/03 05:27:39 INFO  : only8: Copied (replaced existing)
2022/03/03 05:27:39 DEBUG : only9: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2022/03/03 05:27:39 INFO  : only9: Copied (replaced existing)
2022/03/03 05:27:39 DEBUG : only11: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2022/03/03 05:27:39 INFO  : only11: Copied (replaced existing)
2022/03/03 05:27:40 DEBUG : only10: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2022/03/03 05:27:40 INFO  : only10: Copied (replaced existing)
2022/03/03 05:27:40 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncConcurrentTruncate (91.19s)
PASS
2022/03/03 05:28:22 DEBUG : B2 bucket rclone-test-sefococ1zileyew0jadadat1: Purge remote
2022/03/03 05:28:23 DEBUG : BackupDir/one: Deleting (id "4_zef923d93306d4beb7ff80e1b_f117e1d56d541f079_d20220303_m052231_c001_v0001157_t0032")
2022/03/03 05:28:23 DEBUG : BackupDir/one: Deleting (id "4_zef923d93306d4beb7ff80e1b_f114d89353f1cb356_d20220303_m052220_c001_v0001099_t0045")
2022/03/03 05:28:23 DEBUG : CompareDest/one: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11398f33d9adbcfb_d20220303_m052153_c001_v0001120_t0046")
2022/03/03 05:28:23 DEBUG : CompareDest/one: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa7d7e_d20220303_m052145_c001_v0001116_t0044")
2022/03/03 05:28:23 DEBUG : CompareDest/two: Deleting (id "4_zef923d93306d4beb7ff80e1b_f10218196fb5db1c2_d20220303_m052154_c001_v0001106_t0040")
2022/03/03 05:28:23 DEBUG : CompareDest/two: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d646b96_d20220303_m052148_c001_v0001099_t0021")
2022/03/03 05:28:23 DEBUG : CopyDest/one: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1029c1eaf1f02f41_d20220303_m052231_c001_v0001042_t0032")
2022/03/03 05:28:23 DEBUG : CopyDest/one: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d646cf7_d20220303_m052217_c001_v0001099_t0037")
2022/03/03 05:28:23 DEBUG : CopyDest/three: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1023cd45849bf9fe_d20220303_m052234_c001_v0001102_t0021")
2022/03/03 05:28:23 DEBUG : CopyDest/three: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d646d8e_d20220303_m052228_c001_v0001099_t0038")
2022/03/03 05:28:23 DEBUG : CopyDest/two: Deleting (id "4_zef923d93306d4beb7ff80e1b_f115ae34e2eb71236_d20220303_m052235_c001_v0001101_t0041")
2022/03/03 05:28:24 DEBUG : CopyDest/two: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa7fa9_d20220303_m052223_c001_v0001116_t0040")
2022/03/03 05:28:24 DEBUG : EXISTING: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119dd75ee1ae12e3_d20220303_m052536_c001_v0001097_t0003")
2022/03/03 05:28:24 DEBUG : EXISTING: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d647650_d20220303_m052535_c001_v0001099_t0043")
2022/03/03 05:28:24 DEBUG : Testêé: Deleting (id "4_zef923d93306d4beb7ff80e1b_f105701f740c9d13f_d20220303_m052530_c001_v0001146_t0039")
2022/03/03 05:28:24 DEBUG : Testêé: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d6475fd_d20220303_m052529_c001_v0001099_t0020")
2022/03/03 05:28:24 DEBUG : Testêé: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa8b45_d20220303_m052524_c001_v0001116_t0014")
2022/03/03 05:28:24 DEBUG : a/potato2: Deleting (id "4_zef923d93306d4beb7ff80e1b_f112bfd5bcf76af85_d20220303_m051919_c001_v0001105_t0024")
2022/03/03 05:28:24 DEBUG : a/potato2: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048bc29_d20220303_m051918_c001_v0001103_t0045")
2022/03/03 05:28:24 DEBUG : a/potato2: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1133c2c03d80fcbc_d20220303_m051916_c001_v0001103_t0033")
2022/03/03 05:28:24 DEBUG : a/potato2: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048bbdb_d20220303_m051915_c001_v0001103_t0052")
2022/03/03 05:28:24 DEBUG : a/potato2: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11611e5f3a789387_d20220303_m051825_c001_v0001100_t0007")
2022/03/03 05:28:24 DEBUG : a/potato2: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048b70a_d20220303_m051822_c001_v0001103_t0016")
2022/03/03 05:28:24 DEBUG : b/potato: Deleting (id "4_zef923d93306d4beb7ff80e1b_f10176f1eef3f09c8_d20220303_m051920_c001_v0001043_t0000")
2022/03/03 05:28:24 DEBUG : b/potato: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048bc0d_d20220303_m051917_c001_v0001103_t0042")
2022/03/03 05:28:24 DEBUG : b/potato: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11429ad192af0194_d20220303_m051916_c001_v0001165_t0028")
2022/03/03 05:28:24 DEBUG : b/potato: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048bbb8_d20220303_m051914_c001_v0001103_t0000")
2022/03/03 05:28:24 DEBUG : b/potato: Deleting (id "4_zef923d93306d4beb7ff80e1b_f107126728e67e8e2_d20220303_m051825_c001_v0001105_t0045")
2022/03/03 05:28:24 DEBUG : b/potato: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048b6e3_d20220303_m051821_c001_v0001103_t0005")
2022/03/03 05:28:25 DEBUG : backup/one: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1199479d635ca9ea_d20220303_m052259_c001_v0001106_t0053")
2022/03/03 05:28:25 DEBUG : backup/one: Deleting (id "4_zef923d93306d4beb7ff80e1b_f10931e92ea029f1e_d20220303_m052252_c001_v0001115_t0058")
2022/03/03 05:28:25 DEBUG : backup/one: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d646e87_d20220303_m052247_c001_v0001099_t0040")
2022/03/03 05:28:25 DEBUG : backup/one-2019-01-01: Deleting (id "4_zef923d93306d4beb7ff80e1b_f101a0ce6f451659e_d20220303_m052355_c001_v0001116_t0038")
2022/03/03 05:28:25 DEBUG : backup/one-2019-01-01: Deleting (id "4_zef923d93306d4beb7ff80e1b_f116009d9f53c2934_d20220303_m052350_c001_v0001106_t0024")
2022/03/03 05:28:25 DEBUG : backup/one-2019-01-01: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1073ce2a409dea8f_d20220303_m052343_c001_v0001101_t0047")
2022/03/03 05:28:25 DEBUG : backup/one.bak: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1028b8c14d8a13c6_d20220303_m052329_c001_v0001038_t0024")
2022/03/03 05:28:25 DEBUG : backup/one.bak: Deleting (id "4_zef923d93306d4beb7ff80e1b_f100509f77e65d4b7_d20220303_m052324_c001_v0001100_t0059")
2022/03/03 05:28:25 DEBUG : backup/one.bak: Deleting (id "4_zef923d93306d4beb7ff80e1b_f101991e0b5cbeabb_d20220303_m052311_c001_v0001144_t0000")
2022/03/03 05:28:25 DEBUG : backup/three-2019-01-01.txt: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11288d6b45466acf_d20220303_m052356_c001_v0001116_t0044")
2022/03/03 05:28:25 DEBUG : backup/three-2019-01-01.txt: Deleting (id "4_zef923d93306d4beb7ff80e1b_f114993e1f0a6e3ac_d20220303_m052353_c001_v0001106_t0030")
2022/03/03 05:28:25 DEBUG : backup/three-2019-01-01.txt: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1053de68afcb99d1_d20220303_m052346_c001_v0001098_t0030")
2022/03/03 05:28:25 DEBUG : backup/three.txt: Deleting (id "4_zef923d93306d4beb7ff80e1b_f113b726b0ed0baba_d20220303_m052259_c001_v0001128_t0012")
2022/03/03 05:28:25 DEBUG : backup/three.txt: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1049f11d3bdf1a80_d20220303_m052256_c001_v0001109_t0012")
2022/03/03 05:28:25 DEBUG : backup/three.txt: Deleting (id "4_zef923d93306d4beb7ff80e1b_f114dc517c29ebbba_d20220303_m052250_c001_v0001120_t0015")
2022/03/03 05:28:25 DEBUG : backup/three.txt.bak: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1045dcd4df093a13_d20220303_m052331_c001_v0001104_t0043")
2022/03/03 05:28:25 DEBUG : backup/three.txt.bak: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11783b7fd874edc7_d20220303_m052326_c001_v0001115_t0044")
2022/03/03 05:28:25 DEBUG : backup/three.txt.bak: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1045cca93b09438b_d20220303_m052318_c001_v0001115_t0036")
2022/03/03 05:28:25 DEBUG : both0: Deleting (id "4_zef923d93306d4beb7ff80e1b_f101991e0b5cbf12b_d20220303_m052740_c001_v0001144_t0023")
2022/03/03 05:28:25 DEBUG : both0: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa9048_d20220303_m052652_c001_v0001116_t0053")
2022/03/03 05:28:25 DEBUG : both0: Deleting (id "4_zef923d93306d4beb7ff80e1b_f101df5c010563aac_d20220303_m052632_c001_v0001011_t0019")
2022/03/03 05:28:26 DEBUG : both0: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa8c2a_d20220303_m052540_c001_v0001116_t0039")
2022/03/03 05:28:26 DEBUG : both1: Deleting (id "4_zef923d93306d4beb7ff80e1b_f111f4bb2d0ccd675_d20220303_m052742_c001_v0001043_t0044")
2022/03/03 05:28:26 DEBUG : both1: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa9069_d20220303_m052654_c001_v0001116_t0006")
2022/03/03 05:28:26 DEBUG : both1: Deleting (id "4_zef923d93306d4beb7ff80e1b_f102249e1eec3ee57_d20220303_m052632_c001_v0001099_t0002")
2022/03/03 05:28:26 DEBUG : both1: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa8c61_d20220303_m052544_c001_v0001116_t0054")
2022/03/03 05:28:26 DEBUG : both10: Deleting (id "4_zef923d93306d4beb7ff80e1b_f10327a893295e7b4_d20220303_m052742_c001_v0001102_t0041")
2022/03/03 05:28:26 DEBUG : both10: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa91d1_d20220303_m052717_c001_v0001116_t0035")
2022/03/03 05:28:26 DEBUG : both10: Deleting (id "4_zef923d93306d4beb7ff80e1b_f105e3d4127780dfd_d20220303_m052633_c001_v0001039_t0016")
2022/03/03 05:28:26 DEBUG : both10: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa8d6a_d20220303_m052602_c001_v0001116_t0047")
2022/03/03 05:28:26 DEBUG : both11: Deleting (id "4_zef923d93306d4beb7ff80e1b_f116ed131ad05eaf4_d20220303_m052743_c001_v0001116_t0051")
2022/03/03 05:28:26 DEBUG : both11: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa91ea_d20220303_m052718_c001_v0001116_t0027")
2022/03/03 05:28:26 DEBUG : both11: Deleting (id "4_zef923d93306d4beb7ff80e1b_f114d89353f1cbf51_d20220303_m052633_c001_v0001099_t0022")
2022/03/03 05:28:26 DEBUG : both11: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa8d91_d20220303_m052604_c001_v0001116_t0023")
2022/03/03 05:28:26 DEBUG : both12: Deleting (id "4_zef923d93306d4beb7ff80e1b_f109748c471515a03_d20220303_m052746_c001_v0001161_t0013")
2022/03/03 05:28:26 DEBUG : both12: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa9205_d20220303_m052720_c001_v0001116_t0029")
2022/03/03 05:28:26 DEBUG : both12: Deleting (id "4_zef923d93306d4beb7ff80e1b_f105e2df6c192ab74_d20220303_m052634_c001_v0001116_t0030")
2022/03/03 05:28:26 DEBUG : both12: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa8dae_d20220303_m052606_c001_v0001116_t0027")
2022/03/03 05:28:26 DEBUG : both13: Deleting (id "4_zef923d93306d4beb7ff80e1b_f100bd91e0647b68a_d20220303_m052747_c001_v0001101_t0030")
2022/03/03 05:28:26 DEBUG : both13: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa9216_d20220303_m052722_c001_v0001116_t0037")
2022/03/03 05:28:26 DEBUG : both13: Deleting (id "4_zef923d93306d4beb7ff80e1b_f105ecbda10809f9a_d20220303_m052635_c001_v0001101_t0054")
2022/03/03 05:28:26 DEBUG : both13: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa8dcf_d20220303_m052608_c001_v0001116_t0056")
2022/03/03 05:28:27 DEBUG : both14: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11783b7fd874fa81_d20220303_m052748_c001_v0001115_t0022")
2022/03/03 05:28:27 DEBUG : both14: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa922f_d20220303_m052724_c001_v0001116_t0007")
2022/03/03 05:28:27 DEBUG : both14: Deleting (id "4_zef923d93306d4beb7ff80e1b_f10940af7baeccd24_d20220303_m052636_c001_v0001103_t0056")
2022/03/03 05:28:27 DEBUG : both14: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa8de8_d20220303_m052610_c001_v0001116_t0016")
2022/03/03 05:28:27 DEBUG : both15: Deleting (id "4_zef923d93306d4beb7ff80e1b_f10667131f966a37d_d20220303_m052749_c001_v0001102_t0032")
2022/03/03 05:28:27 DEBUG : both15: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa9241_d20220303_m052725_c001_v0001116_t0021")
2022/03/03 05:28:27 DEBUG : both15: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1044e3eb37a19b90_d20220303_m052636_c001_v0001106_t0005")
2022/03/03 05:28:27 DEBUG : both15: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa8e13_d20220303_m052613_c001_v0001116_t0006")
2022/03/03 05:28:27 DEBUG : both16: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1068a0d1ef4b3f01_d20220303_m052750_c001_v0001144_t0020")
2022/03/03 05:28:27 DEBUG : both16: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa9255_d20220303_m052727_c001_v0001116_t0027")
2022/03/03 05:28:27 DEBUG : both16: Deleting (id "4_zef923d93306d4beb7ff80e1b_f100a7ab881167626_d20220303_m052638_c001_v0001104_t0052")
2022/03/03 05:28:27 DEBUG : both16: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa8e36_d20220303_m052615_c001_v0001116_t0058")
2022/03/03 05:28:27 DEBUG : both17: Deleting (id "4_zef923d93306d4beb7ff80e1b_f110d2a7b015f4380_d20220303_m052751_c001_v0001115_t0049")
2022/03/03 05:28:27 DEBUG : both17: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa9278_d20220303_m052729_c001_v0001116_t0040")
2022/03/03 05:28:27 DEBUG : both17: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1020c562e9b3afc9_d20220303_m052638_c001_v0001131_t0020")
2022/03/03 05:28:27 DEBUG : both17: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa8e4e_d20220303_m052617_c001_v0001116_t0054")
2022/03/03 05:28:27 DEBUG : both18: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1063857ddb744a14_d20220303_m052752_c001_v0001116_t0018")
2022/03/03 05:28:27 DEBUG : both18: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa9297_d20220303_m052732_c001_v0001116_t0059")
2022/03/03 05:28:27 DEBUG : both18: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119645187a3f45cc_d20220303_m052639_c001_v0001120_t0022")
2022/03/03 05:28:27 DEBUG : both18: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa8e66_d20220303_m052618_c001_v0001116_t0016")
2022/03/03 05:28:28 DEBUG : both19: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1169e243bdcabda5_d20220303_m052755_c001_v0001102_t0015")
2022/03/03 05:28:28 DEBUG : both19: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa92ac_d20220303_m052733_c001_v0001116_t0033")
2022/03/03 05:28:28 DEBUG : both19: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1068b8191ee937d9_d20220303_m052640_c001_v0001120_t0046")
2022/03/03 05:28:28 DEBUG : both19: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa8ea8_d20220303_m052621_c001_v0001116_t0053")
2022/03/03 05:28:28 DEBUG : both2: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11396228edf8616d_d20220303_m052755_c001_v0001116_t0030")
2022/03/03 05:28:28 DEBUG : both2: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa90a4_d20220303_m052657_c001_v0001116_t0029")
2022/03/03 05:28:28 DEBUG : both2: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11526a14d4b80794_d20220303_m052641_c001_v0001116_t0038")
2022/03/03 05:28:28 DEBUG : both2: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa8c7d_d20220303_m052546_c001_v0001116_t0059")
2022/03/03 05:28:28 DEBUG : both3: Deleting (id "4_zef923d93306d4beb7ff80e1b_f100bd91e0647b7c7_d20220303_m052757_c001_v0001101_t0046")
2022/03/03 05:28:28 DEBUG : both3: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa90d4_d20220303_m052701_c001_v0001116_t0010")
2022/03/03 05:28:28 DEBUG : both3: Deleting (id "4_zef923d93306d4beb7ff80e1b_f110a84eeb3f84ba4_d20220303_m052643_c001_v0001116_t0049")
2022/03/03 05:28:28 DEBUG : both3: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa8c99_d20220303_m052548_c001_v0001116_t0002")
2022/03/03 05:28:28 DEBUG : both4: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1132198362d48659_d20220303_m052758_c001_v0001098_t0037")
2022/03/03 05:28:28 DEBUG : both4: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa90fb_d20220303_m052703_c001_v0001116_t0059")
2022/03/03 05:28:28 DEBUG : both4: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1003be2b659c05d9_d20220303_m052643_c001_v0001116_t0005")
2022/03/03 05:28:28 DEBUG : both4: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa8cb1_d20220303_m052550_c001_v0001116_t0041")
2022/03/03 05:28:28 DEBUG : both5: Deleting (id "4_zef923d93306d4beb7ff80e1b_f106ccd5a980ac3b3_d20220303_m052759_c001_v0001165_t0039")
2022/03/03 05:28:28 DEBUG : both5: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa914a_d20220303_m052709_c001_v0001116_t0037")
2022/03/03 05:28:28 DEBUG : both5: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1199479d635cb30a_d20220303_m052645_c001_v0001106_t0047")
2022/03/03 05:28:28 DEBUG : both5: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa8cd4_d20220303_m052552_c001_v0001116_t0049")
2022/03/03 05:28:29 DEBUG : both6: Deleting (id "4_zef923d93306d4beb7ff80e1b_f116009d9f53c3198_d20220303_m052759_c001_v0001106_t0036")
2022/03/03 05:28:29 DEBUG : both6: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa9161_d20220303_m052710_c001_v0001116_t0030")
2022/03/03 05:28:29 DEBUG : both6: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1063857ddb7446f0_d20220303_m052645_c001_v0001116_t0035")
2022/03/03 05:28:29 DEBUG : both6: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa8cee_d20220303_m052553_c001_v0001116_t0020")
2022/03/03 05:28:29 DEBUG : both7: Deleting (id "4_zef923d93306d4beb7ff80e1b_f103f6cfd319bd7b2_d20220303_m052800_c001_v0001144_t0045")
2022/03/03 05:28:29 DEBUG : both7: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa9177_d20220303_m052712_c001_v0001116_t0028")
2022/03/03 05:28:29 DEBUG : both7: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1045cca93b094dcd_d20220303_m052647_c001_v0001115_t0047")
2022/03/03 05:28:29 DEBUG : both7: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa8d05_d20220303_m052555_c001_v0001116_t0026")
2022/03/03 05:28:29 DEBUG : both8: Deleting (id "4_zef923d93306d4beb7ff80e1b_f114d89353f1cc382_d20220303_m052802_c001_v0001099_t0010")
2022/03/03 05:28:29 DEBUG : both8: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa918b_d20220303_m052713_c001_v0001116_t0032")
2022/03/03 05:28:29 DEBUG : both8: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1188c961d7067e13_d20220303_m052648_c001_v0001106_t0014")
2022/03/03 05:28:29 DEBUG : both8: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa8d3d_d20220303_m052559_c001_v0001116_t0008")
2022/03/03 05:28:29 DEBUG : both9: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1068a0d1ef4b3f39_d20220303_m052803_c001_v0001144_t0007")
2022/03/03 05:28:29 DEBUG : both9: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa91b3_d20220303_m052715_c001_v0001116_t0008")
2022/03/03 05:28:29 DEBUG : both9: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1179614c835c8f78_d20220303_m052649_c001_v0001109_t0024")
2022/03/03 05:28:29 DEBUG : both9: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa8d55_d20220303_m052601_c001_v0001116_t0035")
2022/03/03 05:28:29 DEBUG : c/non empty space: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1034ccc289217a58_d20220303_m051920_c001_v0001146_t0039")
2022/03/03 05:28:29 DEBUG : c/non empty space: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048bc17_d20220303_m051918_c001_v0001103_t0045")
2022/03/03 05:28:29 DEBUG : c/non empty space: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11082abebd2fea27_d20220303_m051917_c001_v0001098_t0059")
2022/03/03 05:28:29 DEBUG : c/non empty space: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048bbc7_d20220303_m051915_c001_v0001103_t0057")
2022/03/03 05:28:29 DEBUG : c/non empty space: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1003be2b659be808_d20220303_m051827_c001_v0001116_t0009")
2022/03/03 05:28:30 DEBUG : c/non empty space: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048b6f1_d20220303_m051821_c001_v0001103_t0052")
2022/03/03 05:28:30 DEBUG : check sum: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1021582307862e5f_d20220303_m051809_c001_v0001113_t0025")
2022/03/03 05:28:30 DEBUG : check sum: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048b5ae_d20220303_m051808_c001_v0001103_t0003")
2022/03/03 05:28:30 DEBUG : dest/3: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1103839048843ea1_d20220303_m052207_c001_v0001106_t0053")
2022/03/03 05:28:30 DEBUG : dest/3: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1045cca93b093f18_d20220303_m052204_c001_v0001115_t0010")
2022/03/03 05:28:30 DEBUG : dst/one: Deleting (id "4_zef923d93306d4beb7ff80e1b_f117e34bdfeefdc2e_d20220303_m052521_c001_v0001098_t0017")
2022/03/03 05:28:30 DEBUG : dst/one: Deleting (id "4_zef923d93306d4beb7ff80e1b_f113d41c714a099e9_d20220303_m052518_c001_v0001101_t0040")
2022/03/03 05:28:30 DEBUG : dst/one: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1187bc4fe104da98_d20220303_m052515_c001_v0001117_t0003")
2022/03/03 05:28:30 DEBUG : dst/one: Deleting (id "4_zef923d93306d4beb7ff80e1b_f113d41c714a098af_d20220303_m052510_c001_v0001101_t0037")
2022/03/03 05:28:30 DEBUG : dst/one: Deleting (id "4_zef923d93306d4beb7ff80e1b_f109f03b6de5213a6_d20220303_m052509_c001_v0001092_t0025")
2022/03/03 05:28:30 DEBUG : dst/one: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d6474c9_d20220303_m052502_c001_v0001099_t0005")
2022/03/03 05:28:30 DEBUG : dst/one: Deleting (id "4_zef923d93306d4beb7ff80e1b_f10175049718fcbb2_d20220303_m052453_c001_v0001117_t0018")
2022/03/03 05:28:30 DEBUG : dst/one: Deleting (id "4_zef923d93306d4beb7ff80e1b_f10941541fe1a6838_d20220303_m052442_c001_v0001116_t0017")
2022/03/03 05:28:30 DEBUG : dst/one: Deleting (id "4_zef923d93306d4beb7ff80e1b_f107fe7b136f6c0df_d20220303_m052441_c001_v0001134_t0032")
2022/03/03 05:28:30 DEBUG : dst/one: Deleting (id "4_zef923d93306d4beb7ff80e1b_f10941541fe1a6773_d20220303_m052432_c001_v0001116_t0020")
2022/03/03 05:28:30 DEBUG : dst/one: Deleting (id "4_zef923d93306d4beb7ff80e1b_f105a09f37c608317_d20220303_m052430_c001_v0001106_t0014")
2022/03/03 05:28:30 DEBUG : dst/one: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa8751_d20220303_m052423_c001_v0001116_t0050")
2022/03/03 05:28:30 DEBUG : dst/one: Deleting (id "4_zef923d93306d4beb7ff80e1b_f115ae34e2eb71d6c_d20220303_m052419_c001_v0001101_t0057")
2022/03/03 05:28:30 DEBUG : dst/one: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1036c7801cbe8edc_d20220303_m052417_c001_v0001101_t0058")
2022/03/03 05:28:30 DEBUG : dst/one: Deleting (id "4_zef923d93306d4beb7ff80e1b_f111e421870f754ca_d20220303_m052416_c001_v0001022_t0039")
2022/03/03 05:28:31 DEBUG : dst/one: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1036c7801cbe8dc6_d20220303_m052409_c001_v0001101_t0025")
2022/03/03 05:28:31 DEBUG : dst/one: Deleting (id "4_zef923d93306d4beb7ff80e1b_f118c522dcb64fe17_d20220303_m052408_c001_v0001116_t0051")
2022/03/03 05:28:31 DEBUG : dst/one: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa861a_d20220303_m052403_c001_v0001116_t0001")
2022/03/03 05:28:31 DEBUG : dst/one: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1095126ee2d43d7a_d20220303_m052358_c001_v0001105_t0004")
2022/03/03 05:28:31 DEBUG : dst/one: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1147e14aefd12aec_d20220303_m052352_c001_v0001026_t0037")
2022/03/03 05:28:31 DEBUG : dst/one: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1036c7801cbe8b35_d20220303_m052351_c001_v0001101_t0043")
2022/03/03 05:28:31 DEBUG : dst/one: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1147e14aefd12aea_d20220303_m052345_c001_v0001026_t0039")
2022/03/03 05:28:31 DEBUG : dst/one: Deleting (id "4_zef923d93306d4beb7ff80e1b_f102f5c18cb65f77b_d20220303_m052344_c001_v0001134_t0047")
2022/03/03 05:28:31 DEBUG : dst/one: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa844a_d20220303_m052335_c001_v0001116_t0010")
2022/03/03 05:28:31 DEBUG : dst/one: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1169e243bdcab2fd_d20220303_m052331_c001_v0001102_t0038")
2022/03/03 05:28:31 DEBUG : dst/one: Deleting (id "4_zef923d93306d4beb7ff80e1b_f117458308c9085bf_d20220303_m052325_c001_v0001101_t0043")
2022/03/03 05:28:31 DEBUG : dst/one: Deleting (id "4_zef923d93306d4beb7ff80e1b_f102f61bef16e96f5_d20220303_m052324_c001_v0001098_t0036")
2022/03/03 05:28:31 DEBUG : dst/one: Deleting (id "4_zef923d93306d4beb7ff80e1b_f117458308c9084c7_d20220303_m052316_c001_v0001101_t0034")
2022/03/03 05:28:31 DEBUG : dst/one: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1172622702b26cac_d20220303_m052312_c001_v0001102_t0000")
2022/03/03 05:28:31 DEBUG : dst/one: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa825d_d20220303_m052304_c001_v0001116_t0003")
2022/03/03 05:28:31 DEBUG : dst/one: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11611e5f3a789ec5_d20220303_m052300_c001_v0001100_t0007")
2022/03/03 05:28:31 DEBUG : dst/one: Deleting (id "4_zef923d93306d4beb7ff80e1b_f103dedf24fe0c348_d20220303_m052256_c001_v0001136_t0018")
2022/03/03 05:28:31 DEBUG : dst/one: Deleting (id "4_zef923d93306d4beb7ff80e1b_f107c316a11c27312_d20220303_m052254_c001_v0001116_t0041")
2022/03/03 05:28:31 DEBUG : dst/one: Deleting (id "4_zef923d93306d4beb7ff80e1b_f103dedf24fe0c338_d20220303_m052249_c001_v0001136_t0000")
2022/03/03 05:28:31 DEBUG : dst/one: Deleting (id "4_zef923d93306d4beb7ff80e1b_f100337872ecb7999_d20220303_m052248_c001_v0001165_t0017")
2022/03/03 05:28:32 DEBUG : dst/one: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa80bf_d20220303_m052240_c001_v0001116_t0059")
2022/03/03 05:28:32 DEBUG : dst/one: Deleting (id "4_zef923d93306d4beb7ff80e1b_f105ecbda10808231_d20220303_m052236_c001_v0001101_t0005")
2022/03/03 05:28:32 DEBUG : dst/one: Deleting (id "4_zef923d93306d4beb7ff80e1b_f117a64af5d5bca69_d20220303_m052222_c001_v0001130_t0020")
2022/03/03 05:28:32 DEBUG : dst/one: Deleting (id "4_zef923d93306d4beb7ff80e1b_f105d8326f23e3634_d20220303_m052221_c001_v0001001_t0000")
2022/03/03 05:28:32 DEBUG : dst/one: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa7f38_d20220303_m052216_c001_v0001116_t0031")
2022/03/03 05:28:32 DEBUG : dst/one: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1053945045a57a50_d20220303_m052214_c001_v0001100_t0021")
2022/03/03 05:28:32 DEBUG : dst/one: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1053945045a57a46_d20220303_m052213_c001_v0001100_t0010")
2022/03/03 05:28:32 DEBUG : dst/one: Deleting (id "4_zef923d93306d4beb7ff80e1b_f116009d9f53c24e3_d20220303_m052156_c001_v0001106_t0050")
2022/03/03 05:28:32 DEBUG : dst/one: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d646b58_d20220303_m052142_c001_v0001099_t0000")
2022/03/03 05:28:32 DEBUG : dst/one: Deleting (id "4_zef923d93306d4beb7ff80e1b_f10931e92ea029aa0_d20220303_m052140_c001_v0001115_t0017")
2022/03/03 05:28:32 DEBUG : dst/one: Deleting (id "4_zef923d93306d4beb7ff80e1b_f10931e92ea029a89_d20220303_m052139_c001_v0001115_t0058")
2022/03/03 05:28:32 DEBUG : dst/one-2019-01-01: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1095126ee2d44135_d20220303_m052521_c001_v0001105_t0045")
2022/03/03 05:28:32 DEBUG : dst/one-2019-01-01: Deleting (id "4_zef923d93306d4beb7ff80e1b_f104d32868b8ef218_d20220303_m052515_c001_v0001161_t0041")
2022/03/03 05:28:32 DEBUG : dst/one-2019-01-01: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1175c8c458a04f1f_d20220303_m052509_c001_v0001022_t0050")
2022/03/03 05:28:32 DEBUG : dst/one.bak: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1152b03eac1a5cea_d20220303_m052453_c001_v0001120_t0029")
2022/03/03 05:28:32 DEBUG : dst/one.bak: Deleting (id "4_zef923d93306d4beb7ff80e1b_f103958192c21f8e2_d20220303_m052440_c001_v0001150_t0043")
2022/03/03 05:28:32 DEBUG : dst/one.bak: Deleting (id "4_zef923d93306d4beb7ff80e1b_f116691977e66e7c1_d20220303_m052429_c001_v0001097_t0037")
2022/03/03 05:28:32 DEBUG : dst/one.bak: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1045c09308a5189e_d20220303_m052420_c001_v0001101_t0051")
2022/03/03 05:28:32 DEBUG : dst/one.bak: Deleting (id "4_zef923d93306d4beb7ff80e1b_f111fb9222c7b93ce_d20220303_m052413_c001_v0001098_t0015")
2022/03/03 05:28:32 DEBUG : dst/one.bak: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1059e2471e49a13c_d20220303_m052407_c001_v0001130_t0045")
2022/03/03 05:28:32 DEBUG : dst/three: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1055217166a5c85b_d20220303_m052239_c001_v0001134_t0026")
2022/03/03 05:28:32 DEBUG : dst/three: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1053945045a57af5_d20220303_m052230_c001_v0001100_t0043")
2022/03/03 05:28:33 DEBUG : dst/three-2019-01-01.txt: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1088143c0476ddd5_d20220303_m052522_c001_v0001100_t0026")
2022/03/03 05:28:33 DEBUG : dst/three-2019-01-01.txt: Deleting (id "4_zef923d93306d4beb7ff80e1b_f105ab65144284784_d20220303_m052519_c001_v0001091_t0030")
2022/03/03 05:28:33 DEBUG : dst/three-2019-01-01.txt: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1160ea2764f76dbb_d20220303_m052511_c001_v0001157_t0005")
2022/03/03 05:28:33 DEBUG : dst/three.txt: Deleting (id "4_zef923d93306d4beb7ff80e1b_f109cc50bf5aaa823_d20220303_m052523_c001_v0001044_t0011")
2022/03/03 05:28:33 DEBUG : dst/three.txt: Deleting (id "4_zef923d93306d4beb7ff80e1b_f113d41c714a09a31_d20220303_m052521_c001_v0001101_t0020")
2022/03/03 05:28:33 DEBUG : dst/three.txt: Deleting (id "4_zef923d93306d4beb7ff80e1b_f118352c226ce5d04_d20220303_m052520_c001_v0001103_t0048")
2022/03/03 05:28:33 DEBUG : dst/three.txt: Deleting (id "4_zef923d93306d4beb7ff80e1b_f113d41c714a09955_d20220303_m052514_c001_v0001101_t0026")
2022/03/03 05:28:33 DEBUG : dst/three.txt: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11262db0326d6bae_d20220303_m052512_c001_v0001037_t0018")
2022/03/03 05:28:33 DEBUG : dst/three.txt: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d6474ef_d20220303_m052505_c001_v0001099_t0033")
2022/03/03 05:28:33 DEBUG : dst/three.txt: Deleting (id "4_zef923d93306d4beb7ff80e1b_f114bde8225467043_d20220303_m052454_c001_v0001101_t0002")
2022/03/03 05:28:33 DEBUG : dst/three.txt: Deleting (id "4_zef923d93306d4beb7ff80e1b_f10941541fe1a68af_d20220303_m052447_c001_v0001116_t0056")
2022/03/03 05:28:33 DEBUG : dst/three.txt: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11223cc14ca8890a_d20220303_m052446_c001_v0001091_t0033")
2022/03/03 05:28:33 DEBUG : dst/three.txt: Deleting (id "4_zef923d93306d4beb7ff80e1b_f10941541fe1a67e0_d20220303_m052437_c001_v0001116_t0029")
2022/03/03 05:28:33 DEBUG : dst/three.txt: Deleting (id "4_zef923d93306d4beb7ff80e1b_f107685e54b8ca503_d20220303_m052436_c001_v0001109_t0000")
2022/03/03 05:28:33 DEBUG : dst/three.txt: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa8763_d20220303_m052425_c001_v0001116_t0049")
2022/03/03 05:28:33 DEBUG : dst/three.txt: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1053945045a58002_d20220303_m052419_c001_v0001100_t0034")
2022/03/03 05:28:33 DEBUG : dst/three.txt: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d64726a_d20220303_m052412_c001_v0001099_t0036")
2022/03/03 05:28:33 DEBUG : dst/three.txt: Deleting (id "4_zef923d93306d4beb7ff80e1b_f114bde8225466b04_d20220303_m052411_c001_v0001101_t0050")
2022/03/03 05:28:33 DEBUG : dst/three.txt: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa862f_d20220303_m052404_c001_v0001116_t0034")
2022/03/03 05:28:33 DEBUG : dst/three.txt: Deleting (id "4_zef923d93306d4beb7ff80e1b_f106bc7110dc139cb_d20220303_m052354_c001_v0001115_t0040")
2022/03/03 05:28:33 DEBUG : dst/three.txt: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d647143_d20220303_m052349_c001_v0001099_t0000")
2022/03/03 05:28:34 DEBUG : dst/three.txt: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11658b995483f324_d20220303_m052346_c001_v0001103_t0016")
2022/03/03 05:28:34 DEBUG : dst/three.txt: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa846b_d20220303_m052338_c001_v0001116_t0024")
2022/03/03 05:28:34 DEBUG : dst/three.txt: Deleting (id "4_zef923d93306d4beb7ff80e1b_f116fca75b210e1e0_d20220303_m052329_c001_v0001134_t0053")
2022/03/03 05:28:34 DEBUG : dst/three.txt: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d647008_d20220303_m052321_c001_v0001099_t0039")
2022/03/03 05:28:34 DEBUG : dst/three.txt: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11456464cd136c25_d20220303_m052320_c001_v0001104_t0003")
2022/03/03 05:28:34 DEBUG : dst/three.txt: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa827c_d20220303_m052305_c001_v0001116_t0041")
2022/03/03 05:28:34 DEBUG : dst/three.txt: Deleting (id "4_zef923d93306d4beb7ff80e1b_f111af4db37dfb054_d20220303_m052257_c001_v0001101_t0006")
2022/03/03 05:28:34 DEBUG : dst/three.txt: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d646eb7_d20220303_m052251_c001_v0001099_t0042")
2022/03/03 05:28:34 DEBUG : dst/three.txt: Deleting (id "4_zef923d93306d4beb7ff80e1b_f113bf74f4b84da09_d20220303_m052250_c001_v0001153_t0002")
2022/03/03 05:28:34 DEBUG : dst/three.txt: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa80f2_d20220303_m052244_c001_v0001116_t0003")
2022/03/03 05:28:34 DEBUG : dst/three.txt.bak: Deleting (id "4_zef923d93306d4beb7ff80e1b_f102f53ffa68f9281_d20220303_m052457_c001_v0001104_t0006")
2022/03/03 05:28:34 DEBUG : dst/three.txt.bak: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1167a0f7106fdc35_d20220303_m052444_c001_v0001104_t0007")
2022/03/03 05:28:34 DEBUG : dst/three.txt.bak: Deleting (id "4_zef923d93306d4beb7ff80e1b_f113817be969ad615_d20220303_m052433_c001_v0001041_t0027")
2022/03/03 05:28:34 DEBUG : dst/three.txt.bak: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1077c5babf3ee507_d20220303_m052421_c001_v0001104_t0014")
2022/03/03 05:28:34 DEBUG : dst/three.txt.bak: Deleting (id "4_zef923d93306d4beb7ff80e1b_f102c9d453e7fef99_d20220303_m052418_c001_v0001101_t0046")
2022/03/03 05:28:34 DEBUG : dst/three.txt.bak: Deleting (id "4_zef923d93306d4beb7ff80e1b_f10343fddc7cc4926_d20220303_m052410_c001_v0001115_t0005")
2022/03/03 05:28:34 DEBUG : dst/two: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1182d7c61b828da3_d20220303_m052523_c001_v0001104_t0003")
2022/03/03 05:28:34 DEBUG : dst/two: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa89dc_d20220303_m052503_c001_v0001116_t0015")
2022/03/03 05:28:34 DEBUG : dst/two: Deleting (id "4_zef923d93306d4beb7ff80e1b_f103273ed728f124f_d20220303_m052500_c001_v0001117_t0015")
2022/03/03 05:28:35 DEBUG : dst/two: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d6472fe_d20220303_m052425_c001_v0001099_t0016")
2022/03/03 05:28:35 DEBUG : dst/two: Deleting (id "4_zef923d93306d4beb7ff80e1b_f100fd38187e41b53_d20220303_m052422_c001_v0001102_t0027")
2022/03/03 05:28:35 DEBUG : dst/two: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d647207_d20220303_m052404_c001_v0001099_t0030")
2022/03/03 05:28:35 DEBUG : dst/two: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1187bc4fe104d7c9_d20220303_m052400_c001_v0001117_t0000")
2022/03/03 05:28:35 DEBUG : dst/two: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d6470c4_d20220303_m052338_c001_v0001099_t0027")
2022/03/03 05:28:35 DEBUG : dst/two: Deleting (id "4_zef923d93306d4beb7ff80e1b_f112ebcafdea1cf12_d20220303_m052334_c001_v0001101_t0027")
2022/03/03 05:28:35 DEBUG : dst/two: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d646f4c_d20220303_m052305_c001_v0001099_t0014")
2022/03/03 05:28:35 DEBUG : dst/two: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1045dcd4df0937c5_d20220303_m052301_c001_v0001104_t0009")
2022/03/03 05:28:35 DEBUG : dst/two: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d646e54_d20220303_m052243_c001_v0001099_t0037")
2022/03/03 05:28:35 DEBUG : dst/two: Deleting (id "4_zef923d93306d4beb7ff80e1b_f116a54b29ed52c63_d20220303_m052239_c001_v0001105_t0007")
2022/03/03 05:28:35 DEBUG : dst/two: Deleting (id "4_zef923d93306d4beb7ff80e1b_f110477421270ab5a_d20220303_m052226_c001_v0001109_t0010")
2022/03/03 05:28:35 DEBUG : dst/two: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1027c6339fd343b4_d20220303_m052157_c001_v0001100_t0003")
2022/03/03 05:28:35 DEBUG : dst/two: Deleting (id "4_zef923d93306d4beb7ff80e1b_f10931e92ea029b65_d20220303_m052152_c001_v0001115_t0009")
2022/03/03 05:28:35 DEBUG : empty space: Deleting (id "4_zef923d93306d4beb7ff80e1b_f100a7ab881165c1b_d20220303_m052123_c001_v0001104_t0007")
2022/03/03 05:28:35 DEBUG : empty space: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa7bc6_d20220303_m052112_c001_v0001116_t0042")
2022/03/03 05:28:35 DEBUG : empty space: Deleting (id "4_zef923d93306d4beb7ff80e1b_f100537e21bc2fd6d_d20220303_m052109_c001_v0001099_t0000")
2022/03/03 05:28:35 DEBUG : empty space: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d646937_d20220303_m052054_c001_v0001099_t0058")
2022/03/03 05:28:35 DEBUG : empty space: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11446c164e6bafb9_d20220303_m052041_c001_v0001116_t0042")
2022/03/03 05:28:35 DEBUG : empty space: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa7a0e_d20220303_m052037_c001_v0001116_t0015")
2022/03/03 05:28:36 DEBUG : empty space: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1066c80a0956fca6_d20220303_m051947_c001_v0001101_t0055")
2022/03/03 05:28:36 DEBUG : empty space: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048bddb_d20220303_m051941_c001_v0001103_t0030")
2022/03/03 05:28:36 DEBUG : empty space: Deleting (id "4_zef923d93306d4beb7ff80e1b_f107b35037142cc2d_d20220303_m051939_c001_v0001115_t0000")
2022/03/03 05:28:36 DEBUG : empty space: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048bd95_d20220303_m051937_c001_v0001103_t0006")
2022/03/03 05:28:36 DEBUG : empty space: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1119d1afef1a4d6c_d20220303_m051932_c001_v0001099_t0052")
2022/03/03 05:28:36 DEBUG : empty space: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048bcd3_d20220303_m051928_c001_v0001103_t0005")
2022/03/03 05:28:36 DEBUG : empty space: Deleting (id "4_zef923d93306d4beb7ff80e1b_f118712df2fd1200a_d20220303_m051925_c001_v0001102_t0002")
2022/03/03 05:28:36 DEBUG : empty space: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048bc7c_d20220303_m051923_c001_v0001103_t0042")
2022/03/03 05:28:36 DEBUG : empty space: Deleting (id "4_zef923d93306d4beb7ff80e1b_f117458308c906871_d20220303_m051912_c001_v0001101_t0057")
2022/03/03 05:28:36 DEBUG : empty space: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048bb3f_d20220303_m051909_c001_v0001103_t0052")
2022/03/03 05:28:36 DEBUG : empty space: Deleting (id "4_zef923d93306d4beb7ff80e1b_f10941541fe1a5391_d20220303_m051900_c001_v0001116_t0054")
2022/03/03 05:28:36 DEBUG : empty space: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048ba9d_d20220303_m051859_c001_v0001103_t0019")
2022/03/03 05:28:36 DEBUG : empty space: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11042285785b6401_d20220303_m051849_c001_v0001101_t0047")
2022/03/03 05:28:36 DEBUG : empty space: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048b8d9_d20220303_m051840_c001_v0001103_t0003")
2022/03/03 05:28:36 DEBUG : empty space: Deleting (id "4_zef923d93306d4beb7ff80e1b_f10883bffcb2ee7c4_d20220303_m051834_c001_v0001104_t0031")
2022/03/03 05:28:36 DEBUG : empty space: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048b812_d20220303_m051833_c001_v0001103_t0037")
2022/03/03 05:28:36 DEBUG : empty space: Deleting (id "4_zef923d93306d4beb7ff80e1b_f10448a2668546fcb_d20220303_m051832_c001_v0001131_t0010")
2022/03/03 05:28:36 DEBUG : empty space: Deleting (id "4_zef923d93306d4beb7ff80e1b_f108a9c4e46b7bdc1_d20220303_m051831_c001_v0001130_t0045")
2022/03/03 05:28:36 DEBUG : empty space: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048b7c8_d20220303_m051829_c001_v0001103_t0054")
2022/03/03 05:28:36 DEBUG : enormous: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1113a84b844e0022_d20220303_m051945_c001_v0001106_t0025")
2022/03/03 05:28:37 DEBUG : enormous: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048bddf_d20220303_m051941_c001_v0001103_t0032")
2022/03/03 05:28:37 DEBUG : existing: Deleting (id "4_zef923d93306d4beb7ff80e1b_f118fa56ae7fc7f20_d20220303_m052534_c001_v0001092_t0051")
2022/03/03 05:28:37 DEBUG : existing: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa8bac_d20220303_m052531_c001_v0001116_t0027")
2022/03/03 05:28:37 DEBUG : existing: Deleting (id "4_zef923d93306d4beb7ff80e1b_f117458308c9072a8_d20220303_m052035_c001_v0001101_t0040")
2022/03/03 05:28:37 DEBUG : existing: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d646869_d20220303_m052032_c001_v0001099_t0009")
2022/03/03 05:28:37 DEBUG : existing: Deleting (id "4_zef923d93306d4beb7ff80e1b_f116691977e66e22e_d20220303_m051818_c001_v0001097_t0055")
2022/03/03 05:28:37 DEBUG : existing: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048b673_d20220303_m051817_c001_v0001103_t0027")
2022/03/03 05:28:37 DEBUG : existing: Deleting (id "4_zef923d93306d4beb7ff80e1b_f117a3fbefff29928_d20220303_m051816_c001_v0001100_t0054")
2022/03/03 05:28:37 DEBUG : existing: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048b649_d20220303_m051815_c001_v0001103_t0052")
2022/03/03 05:28:37 DEBUG : existing: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048b633_d20220303_m051814_c001_v0001103_t0020")
2022/03/03 05:28:37 DEBUG : existing-b: Deleting (id "4_zef923d93306d4beb7ff80e1b_f102ee6428e50c77a_d20220303_m052035_c001_v0001157_t0007")
2022/03/03 05:28:37 DEBUG : existing-b: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa79c7_d20220303_m052032_c001_v0001116_t0058")
2022/03/03 05:28:37 DEBUG : five: Deleting (id "4_zef923d93306d4beb7ff80e1b_f10883bffcb2eed8a_d20220303_m051954_c001_v0001104_t0022")
2022/03/03 05:28:37 DEBUG : five: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa7745_d20220303_m051951_c001_v0001116_t0040")
2022/03/03 05:28:37 DEBUG : foo: Deleting (id "4_zef923d93306d4beb7ff80e1b_f116802b3f1b6c7e4_d20220303_m051839_c001_v0001109_t0034")
2022/03/03 05:28:37 DEBUG : foo: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048b895_d20220303_m051838_c001_v0001103_t0052")
2022/03/03 05:28:37 DEBUG : foo: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048b863_d20220303_m051835_c001_v0001103_t0010")
2022/03/03 05:28:37 DEBUG : four: Deleting (id "4_zef923d93306d4beb7ff80e1b_f117e1f706d44be20_d20220303_m051955_c001_v0001093_t0002")
2022/03/03 05:28:37 DEBUG : four: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d6466b6_d20220303_m051950_c001_v0001099_t0048")
2022/03/03 05:28:37 DEBUG : four: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048be86_d20220303_m051949_c001_v0001103_t0011")
2022/03/03 05:28:38 DEBUG : hello world2: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1016d31dd4cc78a9_d20220303_m051747_c001_v0001100_t0000")
2022/03/03 05:28:38 DEBUG : hello world2: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048b3b9_d20220303_m051742_c001_v0001103_t0032")
2022/03/03 05:28:38 DEBUG : ignore-size: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1169e243bdcaa612_d20220303_m051813_c001_v0001102_t0033")
2022/03/03 05:28:38 DEBUG : ignore-size: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048b605_d20220303_m051812_c001_v0001103_t0046")
2022/03/03 05:28:38 DEBUG : nested/sub dir/file: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1105f9649572fb89_d20220303_m052030_c001_v0001137_t0021")
2022/03/03 05:28:38 DEBUG : nested/sub dir/file: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa798b_d20220303_m052029_c001_v0001116_t0046")
2022/03/03 05:28:38 DEBUG : nested/sub dir/file: Deleting (id "4_zef923d93306d4beb7ff80e1b_f109a9239dad9d720_d20220303_m052027_c001_v0001104_t0002")
2022/03/03 05:28:38 DEBUG : nested/sub dir/file: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d646818_d20220303_m052025_c001_v0001099_t0040")
2022/03/03 05:28:38 DEBUG : one: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11253f381e0a99bd_d20220303_m051956_c001_v0001099_t0038")
2022/03/03 05:28:38 DEBUG : one: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048be64_d20220303_m051948_c001_v0001103_t0045")
2022/03/03 05:28:38 DEBUG : only0: Deleting (id "4_zef923d93306d4beb7ff80e1b_f115bd044b5afa40d_d20220303_m052804_c001_v0001098_t0047")
2022/03/03 05:28:38 DEBUG : only0: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa92de_d20220303_m052738_c001_v0001116_t0012")
2022/03/03 05:28:38 DEBUG : only0: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d6479fa_d20220303_m052654_c001_v0001099_t0041")
2022/03/03 05:28:38 DEBUG : only0: Deleting (id "4_zef923d93306d4beb7ff80e1b_f10612d69693e4ed2_d20220303_m052627_c001_v0001037_t0017")
2022/03/03 05:28:38 DEBUG : only0: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d6476ce_d20220303_m052544_c001_v0001099_t0006")
2022/03/03 05:28:38 DEBUG : only1: Deleting (id "4_zef923d93306d4beb7ff80e1b_f109421a7bd77a209_d20220303_m052804_c001_v0001137_t0008")
2022/03/03 05:28:38 DEBUG : only1: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d647be8_d20220303_m052736_c001_v0001099_t0040")
2022/03/03 05:28:38 DEBUG : only1: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d647a2a_d20220303_m052657_c001_v0001099_t0008")
2022/03/03 05:28:38 DEBUG : only1: Deleting (id "4_zef923d93306d4beb7ff80e1b_f104b26d1a3610828_d20220303_m052624_c001_v0001099_t0022")
2022/03/03 05:28:38 DEBUG : only1: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d6476f1_d20220303_m052546_c001_v0001099_t0043")
2022/03/03 05:28:39 DEBUG : only10: Deleting (id "4_zef923d93306d4beb7ff80e1b_f10005704612839f5_d20220303_m052805_c001_v0001103_t0043")
2022/03/03 05:28:39 DEBUG : only10: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa930e_d20220303_m052739_c001_v0001116_t0046")
2022/03/03 05:28:39 DEBUG : only10: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d647b0f_d20220303_m052718_c001_v0001099_t0022")
2022/03/03 05:28:39 DEBUG : only10: Deleting (id "4_zef923d93306d4beb7ff80e1b_f106934a018df59a3_d20220303_m052624_c001_v0001043_t0027")
2022/03/03 05:28:39 DEBUG : only10: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d6477ae_d20220303_m052604_c001_v0001099_t0005")
2022/03/03 05:28:39 DEBUG : only11: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1177bdcd50beb844_d20220303_m052805_c001_v0001116_t0019")
2022/03/03 05:28:39 DEBUG : only11: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048e31a_d20220303_m052739_c001_v0001103_t0022")
2022/03/03 05:28:39 DEBUG : only11: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d647b21_d20220303_m052720_c001_v0001099_t0050")
2022/03/03 05:28:39 DEBUG : only11: Deleting (id "4_zef923d93306d4beb7ff80e1b_f116fbada5a8e4581_d20220303_m052628_c001_v0001120_t0008")
2022/03/03 05:28:39 DEBUG : only11: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d6477bd_d20220303_m052606_c001_v0001099_t0007")
2022/03/03 05:28:39 DEBUG : only12: Deleting (id "4_zef923d93306d4beb7ff80e1b_f10941541fe1a73e0_d20220303_m052806_c001_v0001116_t0048")
2022/03/03 05:28:39 DEBUG : only12: Deleting (id "4_zef923d93306d4beb7ff80e1b_f107d58950334ec1d_d20220303_m052736_c001_v0001100_t0039")
2022/03/03 05:28:39 DEBUG : only12: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d647b39_d20220303_m052722_c001_v0001099_t0025")
2022/03/03 05:28:39 DEBUG : only12: Deleting (id "4_zef923d93306d4beb7ff80e1b_f100383a4a3878ba3_d20220303_m052625_c001_v0001017_t0007")
2022/03/03 05:28:39 DEBUG : only12: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d6477d4_d20220303_m052608_c001_v0001099_t0041")
2022/03/03 05:28:39 DEBUG : only13: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1187bc4fe104e168_d20220303_m052807_c001_v0001117_t0019")
2022/03/03 05:28:39 DEBUG : only13: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048e301_d20220303_m052737_c001_v0001103_t0039")
2022/03/03 05:28:39 DEBUG : only13: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d647b55_d20220303_m052723_c001_v0001099_t0044")
2022/03/03 05:28:39 DEBUG : only13: Deleting (id "4_zef923d93306d4beb7ff80e1b_f10259954208ae615_d20220303_m052625_c001_v0001150_t0002")
2022/03/03 05:28:39 DEBUG : only13: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d6477e4_d20220303_m052609_c001_v0001099_t0059")
2022/03/03 05:28:39 DEBUG : only14: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1003be2b659c0ac9_d20220303_m052808_c001_v0001116_t0012")
2022/03/03 05:28:39 DEBUG : only14: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d647bf2_d20220303_m052736_c001_v0001099_t0052")
2022/03/03 05:28:40 DEBUG : only14: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d647b6a_d20220303_m052725_c001_v0001099_t0034")
2022/03/03 05:28:40 DEBUG : only14: Deleting (id "4_zef923d93306d4beb7ff80e1b_f10864d908627583b_d20220303_m052626_c001_v0001109_t0016")
2022/03/03 05:28:40 DEBUG : only14: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d647819_d20220303_m052612_c001_v0001099_t0032")
2022/03/03 05:28:40 DEBUG : only15: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1057c827b9caa608_d20220303_m052810_c001_v0001036_t0039")
2022/03/03 05:28:40 DEBUG : only15: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d647bf6_d20220303_m052737_c001_v0001099_t0016")
2022/03/03 05:28:40 DEBUG : only15: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d647b81_d20220303_m052727_c001_v0001099_t0048")
2022/03/03 05:28:40 DEBUG : only15: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11611e5f3a78a6df_d20220303_m052626_c001_v0001100_t0059")
2022/03/03 05:28:40 DEBUG : only15: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d64782d_d20220303_m052614_c001_v0001099_t0002")
2022/03/03 05:28:40 DEBUG : only16: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11260f2690e63c9d_d20220303_m052811_c001_v0001098_t0046")
2022/03/03 05:28:40 DEBUG : only16: Deleting (id "4_zef923d93306d4beb7ff80e1b_f107d58950334ec26_d20220303_m052737_c001_v0001100_t0036")
2022/03/03 05:28:40 DEBUG : only16: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d647ba4_d20220303_m052729_c001_v0001099_t0005")
2022/03/03 05:28:40 DEBUG : only16: Deleting (id "4_zef923d93306d4beb7ff80e1b_f10562c4bfeee68a5_d20220303_m052628_c001_v0001117_t0007")
2022/03/03 05:28:40 DEBUG : only16: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d64784c_d20220303_m052616_c001_v0001099_t0037")
2022/03/03 05:28:40 DEBUG : only17: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1010f3dc6651922e_d20220303_m052812_c001_v0001101_t0037")
2022/03/03 05:28:40 DEBUG : only17: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d647bfb_d20220303_m052737_c001_v0001099_t0022")
2022/03/03 05:28:40 DEBUG : only17: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d647bba_d20220303_m052731_c001_v0001099_t0002")
2022/03/03 05:28:40 DEBUG : only17: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11116736fceb7030_d20220303_m052625_c001_v0001115_t0043")
2022/03/03 05:28:40 DEBUG : only17: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d64785b_d20220303_m052617_c001_v0001099_t0017")
2022/03/03 05:28:40 DEBUG : only18: Deleting (id "4_zef923d93306d4beb7ff80e1b_f118d4ab78af2011d_d20220303_m052813_c001_v0001101_t0008")
2022/03/03 05:28:41 DEBUG : only18: Deleting (id "4_zef923d93306d4beb7ff80e1b_f107d58950334ec28_d20220303_m052737_c001_v0001100_t0042")
2022/03/03 05:28:41 DEBUG : only18: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d647bc9_d20220303_m052733_c001_v0001099_t0002")
2022/03/03 05:28:41 DEBUG : only18: Deleting (id "4_zef923d93306d4beb7ff80e1b_f118712df2fd12f02_d20220303_m052626_c001_v0001102_t0014")
2022/03/03 05:28:41 DEBUG : only18: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d647889_d20220303_m052621_c001_v0001099_t0047")
2022/03/03 05:28:41 DEBUG : only19: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1171d842ff5f8688_d20220303_m052814_c001_v0001117_t0024")
2022/03/03 05:28:41 DEBUG : only19: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d647c01_d20220303_m052737_c001_v0001099_t0043")
2022/03/03 05:28:41 DEBUG : only19: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d647bd9_d20220303_m052735_c001_v0001099_t0006")
2022/03/03 05:28:41 DEBUG : only19: Deleting (id "4_zef923d93306d4beb7ff80e1b_f113e70f4180e2f1b_d20220303_m052628_c001_v0001115_t0023")
2022/03/03 05:28:41 DEBUG : only19: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d6478a4_d20220303_m052623_c001_v0001099_t0018")
2022/03/03 05:28:41 DEBUG : only2: Deleting (id "4_zef923d93306d4beb7ff80e1b_f10864d9086275bf0_d20220303_m052817_c001_v0001109_t0015")
2022/03/03 05:28:41 DEBUG : only2: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d647c05_d20220303_m052738_c001_v0001099_t0008")
2022/03/03 05:28:41 DEBUG : only2: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d647a42_d20220303_m052700_c001_v0001099_t0001")
2022/03/03 05:28:41 DEBUG : only2: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119094f0247d4ab3_d20220303_m052626_c001_v0001165_t0059")
2022/03/03 05:28:41 DEBUG : only2: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d6476f8_d20220303_m052548_c001_v0001099_t0039")
2022/03/03 05:28:41 DEBUG : only3: Deleting (id "4_zef923d93306d4beb7ff80e1b_f10707776a3745b17_d20220303_m052817_c001_v0001106_t0002")
2022/03/03 05:28:42 DEBUG : only3: Deleting (id "4_zef923d93306d4beb7ff80e1b_f107d58950334ec37_d20220303_m052738_c001_v0001100_t0023")
2022/03/03 05:28:42 DEBUG : only3: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d647a54_d20220303_m052703_c001_v0001099_t0049")
2022/03/03 05:28:42 DEBUG : only3: Deleting (id "4_zef923d93306d4beb7ff80e1b_f106b27dbdf5547c9_d20220303_m052626_c001_v0001103_t0033")
2022/03/03 05:28:42 DEBUG : only3: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d64770e_d20220303_m052549_c001_v0001099_t0008")
2022/03/03 05:28:42 DEBUG : only4: Deleting (id "4_zef923d93306d4beb7ff80e1b_f100bd91e0647ba2f_d20220303_m052818_c001_v0001101_t0024")
2022/03/03 05:28:42 DEBUG : only4: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d647c09_d20220303_m052738_c001_v0001099_t0059")
2022/03/03 05:28:42 DEBUG : only4: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d647a76_d20220303_m052706_c001_v0001099_t0045")
2022/03/03 05:28:42 DEBUG : only4: Deleting (id "4_zef923d93306d4beb7ff80e1b_f106aecfd4eacad93_d20220303_m052626_c001_v0001104_t0024")
2022/03/03 05:28:42 DEBUG : only4: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d64772d_d20220303_m052551_c001_v0001099_t0049")
2022/03/03 05:28:42 DEBUG : only5: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1133c2c03d8121ad_d20220303_m052819_c001_v0001103_t0019")
2022/03/03 05:28:42 DEBUG : only5: Deleting (id "4_zef923d93306d4beb7ff80e1b_f107d58950334ec42_d20220303_m052738_c001_v0001100_t0006")
2022/03/03 05:28:42 DEBUG : only5: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d647aac_d20220303_m052710_c001_v0001099_t0041")
2022/03/03 05:28:42 DEBUG : only5: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1131bf4b08064353_d20220303_m052628_c001_v0001144_t0049")
2022/03/03 05:28:42 DEBUG : only5: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d64773f_d20220303_m052553_c001_v0001099_t0019")
2022/03/03 05:28:42 DEBUG : only6: Deleting (id "4_zef923d93306d4beb7ff80e1b_f107c316a11c28485_d20220303_m052819_c001_v0001116_t0043")
2022/03/03 05:28:42 DEBUG : only6: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d647c11_d20220303_m052738_c001_v0001099_t0010")
2022/03/03 05:28:42 DEBUG : only6: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d647ac4_d20220303_m052712_c001_v0001099_t0003")
2022/03/03 05:28:43 DEBUG : only6: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1063857ddb744618_d20220303_m052625_c001_v0001116_t0047")
2022/03/03 05:28:43 DEBUG : only6: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d64774c_d20220303_m052555_c001_v0001099_t0022")
2022/03/03 05:28:43 DEBUG : only7: Deleting (id "4_zef923d93306d4beb7ff80e1b_f112c4aad63068838_d20220303_m052820_c001_v0001115_t0018")
2022/03/03 05:28:43 DEBUG : only7: Deleting (id "4_zef923d93306d4beb7ff80e1b_f107d58950334ec4f_d20220303_m052738_c001_v0001100_t0057")
2022/03/03 05:28:43 DEBUG : only7: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d647ada_d20220303_m052713_c001_v0001099_t0005")
2022/03/03 05:28:43 DEBUG : only7: Deleting (id "4_zef923d93306d4beb7ff80e1b_f104297a631e4435f_d20220303_m052627_c001_v0001116_t0025")
2022/03/03 05:28:43 DEBUG : only7: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d647771_d20220303_m052559_c001_v0001099_t0013")
2022/03/03 05:28:43 DEBUG : only8: Deleting (id "4_zef923d93306d4beb7ff80e1b_f115db1012014d193_d20220303_m052821_c001_v0001154_t0011")
2022/03/03 05:28:43 DEBUG : only8: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d647c13_d20220303_m052738_c001_v0001099_t0034")
2022/03/03 05:28:43 DEBUG : only8: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d647af1_d20220303_m052715_c001_v0001099_t0040")
2022/03/03 05:28:43 DEBUG : only8: Deleting (id "4_zef923d93306d4beb7ff80e1b_f116802b3f1b6dc1e_d20220303_m052625_c001_v0001109_t0053")
2022/03/03 05:28:43 DEBUG : only8: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d647787_d20220303_m052601_c001_v0001099_t0003")
2022/03/03 05:28:43 DEBUG : only9: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1063857ddb744b6b_d20220303_m052821_c001_v0001116_t0023")
2022/03/03 05:28:43 DEBUG : only9: Deleting (id "4_zef923d93306d4beb7ff80e1b_f107d58950334ec56_d20220303_m052739_c001_v0001100_t0030")
2022/03/03 05:28:43 DEBUG : only9: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d647b02_d20220303_m052717_c001_v0001099_t0013")
2022/03/03 05:28:43 DEBUG : only9: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1193a47ad3088ddd_d20220303_m052629_c001_v0001101_t0017")
2022/03/03 05:28:43 DEBUG : only9: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d64779b_d20220303_m052602_c001_v0001099_t0010")
2022/03/03 05:28:43 DEBUG : potato: Deleting (id "4_zef923d93306d4beb7ff80e1b_f113449641022e934_d20220303_m052021_c001_v0001106_t0001")
2022/03/03 05:28:43 DEBUG : potato: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa78bf_d20220303_m052014_c001_v0001116_t0028")
2022/03/03 05:28:43 DEBUG : potato: Deleting (id "4_zef923d93306d4beb7ff80e1b_f114cf970c4c3f427_d20220303_m052011_c001_v0001117_t0006")
2022/03/03 05:28:44 DEBUG : potato: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048bff8_d20220303_m052009_c001_v0001103_t0049")
2022/03/03 05:28:44 DEBUG : potato: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1091575d10c06402_d20220303_m052007_c001_v0001130_t0031")
2022/03/03 05:28:44 DEBUG : potato: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d64671b_d20220303_m051959_c001_v0001099_t0039")
2022/03/03 05:28:44 DEBUG : potato: Deleting (id "4_zef923d93306d4beb7ff80e1b_f107d58950334c530_d20220303_m051935_c001_v0001100_t0019")
2022/03/03 05:28:44 DEBUG : potato: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048bd50_d20220303_m051933_c001_v0001103_t0015")
2022/03/03 05:28:44 DEBUG : potato: Deleting (id "4_zef923d93306d4beb7ff80e1b_f105bac42f5164bf3_d20220303_m051928_c001_v0001104_t0040")
2022/03/03 05:28:44 DEBUG : potato: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048bcc0_d20220303_m051927_c001_v0001103_t0047")
2022/03/03 05:28:44 DEBUG : potato: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1095126ee2d4314f_d20220303_m051924_c001_v0001105_t0055")
2022/03/03 05:28:44 DEBUG : potato: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048bc73_d20220303_m051923_c001_v0001103_t0013")
2022/03/03 05:28:44 DEBUG : potato: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1083a23bcf200341_d20220303_m051910_c001_v0001101_t0051")
2022/03/03 05:28:44 DEBUG : potato: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048bb3c_d20220303_m051909_c001_v0001103_t0011")
2022/03/03 05:28:44 DEBUG : potato: Deleting (id "4_zef923d93306d4beb7ff80e1b_f116ff04ac2ee8a8b_d20220303_m051902_c001_v0001032_t0033")
2022/03/03 05:28:44 DEBUG : potato: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048ba94_d20220303_m051859_c001_v0001103_t0021")
2022/03/03 05:28:44 DEBUG : potato: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1044e3eb37a18167_d20220303_m051858_c001_v0001106_t0059")
2022/03/03 05:28:44 DEBUG : potato: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048ba55_d20220303_m051856_c001_v0001103_t0005")
2022/03/03 05:28:44 DEBUG : potato: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048ba4a_d20220303_m051855_c001_v0001103_t0009")
2022/03/03 05:28:44 DEBUG : potato: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1140b39e77e494ae_d20220303_m051854_c001_v0001144_t0029")
2022/03/03 05:28:44 DEBUG : potato: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048ba0c_d20220303_m051853_c001_v0001103_t0040")
2022/03/03 05:28:44 DEBUG : potato: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048b9fa_d20220303_m051852_c001_v0001103_t0055")
2022/03/03 05:28:44 DEBUG : potato: Deleting (id "4_zef923d93306d4beb7ff80e1b_f109a9239dad9cf5f_d20220303_m051849_c001_v0001104_t0006")
2022/03/03 05:28:44 DEBUG : potato: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048b8e6_d20220303_m051841_c001_v0001103_t0014")
2022/03/03 05:28:44 DEBUG : potato2: Deleting (id "4_zef923d93306d4beb7ff80e1b_f111e3ef89fec82e4_d20220303_m052135_c001_v0001131_t0018")
2022/03/03 05:28:45 DEBUG : potato2: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa7cf0_d20220303_m052133_c001_v0001116_t0057")
2022/03/03 05:28:45 DEBUG : potato2: Deleting (id "4_zef923d93306d4beb7ff80e1b_f109ff9ffe1781a1c_d20220303_m052124_c001_v0001093_t0033")
2022/03/03 05:28:45 DEBUG : potato2: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d6469e4_d20220303_m052111_c001_v0001099_t0027")
2022/03/03 05:28:45 DEBUG : potato2: Deleting (id "4_zef923d93306d4beb7ff80e1b_f10884401249a58ac_d20220303_m052102_c001_v0001116_t0048")
2022/03/03 05:28:45 DEBUG : potato2: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa7ad6_d20220303_m052052_c001_v0001116_t0011")
2022/03/03 05:28:45 DEBUG : potato2: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11288d6b45465ef5_d20220303_m052042_c001_v0001116_t0041")
2022/03/03 05:28:45 DEBUG : potato2: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d6468a1_d20220303_m052037_c001_v0001099_t0032")
2022/03/03 05:28:45 DEBUG : potato2: Deleting (id "4_zef923d93306d4beb7ff80e1b_f110264068cf22ba2_d20220303_m051945_c001_v0001099_t0015")
2022/03/03 05:28:45 DEBUG : potato2: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048bdcc_d20220303_m051941_c001_v0001103_t0042")
2022/03/03 05:28:45 DEBUG : potato2: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11658b995483e935_d20220303_m051940_c001_v0001103_t0024")
2022/03/03 05:28:45 DEBUG : potato2: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048bd8e_d20220303_m051937_c001_v0001103_t0007")
2022/03/03 05:28:45 DEBUG : potato2: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa7667_d20220303_m051935_c001_v0001116_t0054")
2022/03/03 05:28:45 DEBUG : potato2: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048bd58_d20220303_m051934_c001_v0001103_t0041")
2022/03/03 05:28:45 DEBUG : potato2: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1077f9123d6803b1_d20220303_m051933_c001_v0001044_t0013")
2022/03/03 05:28:45 DEBUG : potato2: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048bd06_d20220303_m051930_c001_v0001103_t0048")
2022/03/03 05:28:45 DEBUG : potato2: Deleting (id "4_zef923d93306d4beb7ff80e1b_f113817be969ad4f1_d20220303_m051925_c001_v0001041_t0027")
2022/03/03 05:28:45 DEBUG : potato2: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048bc89_d20220303_m051924_c001_v0001103_t0047")
2022/03/03 05:28:45 DEBUG : potato2: Deleting (id "4_zef923d93306d4beb7ff80e1b_f117bedce5c0918a1_d20220303_m051912_c001_v0001104_t0029")
2022/03/03 05:28:45 DEBUG : potato2: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048bb4e_d20220303_m051910_c001_v0001103_t0055")
2022/03/03 05:28:45 DEBUG : potato2: Deleting (id "4_zef923d93306d4beb7ff80e1b_f112769b05b4a63d4_d20220303_m051750_c001_v0001104_t0048")
2022/03/03 05:28:46 DEBUG : potato2: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048b44c_d20220303_m051749_c001_v0001103_t0018")
2022/03/03 05:28:46 DEBUG : potato2: Deleting (id "4_zef923d93306d4beb7ff80e1b_f116df43bb1694211_d20220303_m051748_c001_v0001154_t0025")
2022/03/03 05:28:46 DEBUG : potato2: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048b42b_d20220303_m051747_c001_v0001103_t0055")
2022/03/03 05:28:46 DEBUG : potato3: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1090ec2c133dd586_d20220303_m052125_c001_v0001097_t0029")
2022/03/03 05:28:46 DEBUG : potato3: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d646a10_d20220303_m052113_c001_v0001099_t0011")
2022/03/03 05:28:46 DEBUG : potato3: Deleting (id "4_zef923d93306d4beb7ff80e1b_f10553a4a7165e04b_d20220303_m052102_c001_v0001150_t0039")
2022/03/03 05:28:46 DEBUG : potato3: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa7aeb_d20220303_m052055_c001_v0001116_t0039")
2022/03/03 05:28:46 DEBUG : potato3: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1035e8781efe341b_d20220303_m052042_c001_v0001104_t0036")
2022/03/03 05:28:46 DEBUG : potato3: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d6468b8_d20220303_m052039_c001_v0001099_t0009")
2022/03/03 05:28:46 DEBUG : pre-dest1/1: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1074759454e6acb6_d20220303_m052207_c001_v0001032_t0005")
2022/03/03 05:28:46 DEBUG : pre-dest1/1: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa7e44_d20220303_m052158_c001_v0001116_t0042")
2022/03/03 05:28:46 DEBUG : pre-dest2/2: Deleting (id "4_zef923d93306d4beb7ff80e1b_f10144855ecd95b8d_d20220303_m052208_c001_v0001092_t0018")
2022/03/03 05:28:46 DEBUG : pre-dest2/2: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d646c23_d20220303_m052159_c001_v0001099_t0053")
2022/03/03 05:28:46 DEBUG : sizeonly: Deleting (id "4_zef923d93306d4beb7ff80e1b_f107d58950334bcf4_d20220303_m051811_c001_v0001100_t0043")
2022/03/03 05:28:46 DEBUG : sizeonly: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048b5da_d20220303_m051810_c001_v0001103_t0013")
2022/03/03 05:28:46 DEBUG : sub dir/hello world: Deleting (id "4_zef923d93306d4beb7ff80e1b_f114dab3e88eaf488_d20220303_m052031_c001_v0001109_t0007")
2022/03/03 05:28:46 DEBUG : sub dir/hello world: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d646842_d20220303_m052029_c001_v0001099_t0009")
2022/03/03 05:28:46 DEBUG : sub dir/hello world: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1019a3cce4ad71c7_d20220303_m052028_c001_v0001109_t0047")
2022/03/03 05:28:46 DEBUG : sub dir/hello world: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa795c_d20220303_m052025_c001_v0001116_t0056")
2022/03/03 05:28:46 DEBUG : sub dir/hello world: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119dd75ee1ae0cbc_d20220303_m051807_c001_v0001097_t0024")
2022/03/03 05:28:46 DEBUG : sub dir/hello world: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048b56e_d20220303_m051806_c001_v0001103_t0017")
2022/03/03 05:28:47 DEBUG : sub dir/hello world: Deleting (id "4_zef923d93306d4beb7ff80e1b_f118712df2fd11d28_d20220303_m051805_c001_v0001102_t0018")
2022/03/03 05:28:47 DEBUG : sub dir/hello world: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048b53f_d20220303_m051804_c001_v0001103_t0032")
2022/03/03 05:28:47 DEBUG : sub dir/hello world: Deleting (id "4_zef923d93306d4beb7ff80e1b_f113df0c1725f147f_d20220303_m051803_c001_v0001157_t0053")
2022/03/03 05:28:47 DEBUG : sub dir/hello world: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048b4cb_d20220303_m051757_c001_v0001103_t0047")
2022/03/03 05:28:47 DEBUG : sub dir/hello world: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1083970cc76e0f66_d20220303_m051756_c001_v0001106_t0008")
2022/03/03 05:28:47 DEBUG : sub dir/hello world: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048b4b9_d20220303_m051755_c001_v0001103_t0000")
2022/03/03 05:28:47 DEBUG : sub dir/hello world: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1145cf9f69ef8966_d20220303_m051754_c001_v0001150_t0048")
2022/03/03 05:28:47 DEBUG : sub dir/hello world: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048b490_d20220303_m051753_c001_v0001103_t0040")
2022/03/03 05:28:47 DEBUG : sub dir/hello world: Deleting (id "4_zef923d93306d4beb7ff80e1b_f107af206139e7b8e_d20220303_m051752_c001_v0001165_t0058")
2022/03/03 05:28:47 DEBUG : sub dir/hello world: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048b477_d20220303_m051751_c001_v0001103_t0044")
2022/03/03 05:28:47 DEBUG : sub dir/hello world: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1104774212709f6c_d20220303_m051741_c001_v0001109_t0001")
2022/03/03 05:28:47 DEBUG : sub dir/hello world: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048b390_d20220303_m051740_c001_v0001103_t0055")
2022/03/03 05:28:47 DEBUG : sub dir/hello world: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1118ee2089e879a0_d20220303_m051738_c001_v0001117_t0013")
2022/03/03 05:28:47 DEBUG : sub dir/hello world: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048b361_d20220303_m051737_c001_v0001103_t0022")
2022/03/03 05:28:47 DEBUG : sub dir/hello world: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1016b417ed8e5555_d20220303_m051736_c001_v0001012_t0019")
2022/03/03 05:28:47 DEBUG : sub dir/hello world: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048b33c_d20220303_m051735_c001_v0001103_t0028")
2022/03/03 05:28:47 DEBUG : sub dir/hello world: Deleting (id "4_zef923d93306d4beb7ff80e1b_f106fd9518fbaef35_d20220303_m051734_c001_v0001109_t0013")
2022/03/03 05:28:47 DEBUG : sub dir/hello world: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048b30d_d20220303_m051733_c001_v0001103_t0057")
2022/03/03 05:28:47 DEBUG : sub/yam: Deleting (id "4_zef923d93306d4beb7ff80e1b_f110264068cf22cd9_d20220303_m052019_c001_v0001099_t0052")
2022/03/03 05:28:47 DEBUG : sub/yam: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d6467b5_d20220303_m052014_c001_v0001099_t0012")
2022/03/03 05:28:48 DEBUG : three: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1167a0f7106fc238_d20220303_m051956_c001_v0001104_t0031")
2022/03/03 05:28:48 DEBUG : three: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048bef2_d20220303_m051953_c001_v0001103_t0028")
2022/03/03 05:28:48 DEBUG : three: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048be80_d20220303_m051949_c001_v0001103_t0036")
2022/03/03 05:28:48 DEBUG : two: Deleting (id "4_zef923d93306d4beb7ff80e1b_f113d41c714a07189_d20220303_m051957_c001_v0001101_t0027")
2022/03/03 05:28:48 DEBUG : two: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048be99_d20220303_m051950_c001_v0001103_t0028")
2022/03/03 05:28:48 DEBUG : two: Deleting (id "4_zef923d93306d4beb7ff80e1b_f11171687b048be72_d20220303_m051948_c001_v0001103_t0010")
2022/03/03 05:28:48 DEBUG : yam: Deleting (id "4_zef923d93306d4beb7ff80e1b_f10727e9275d63217_d20220303_m052021_c001_v0001144_t0010")
2022/03/03 05:28:48 DEBUG : yam: Deleting (id "4_zef923d93306d4beb7ff80e1b_f118d4ab78af1d47f_d20220303_m052017_c001_v0001101_t0045")
2022/03/03 05:28:48 DEBUG : yam: Deleting (id "4_zef923d93306d4beb7ff80e1b_f10667131f96690cd_d20220303_m052011_c001_v0001102_t0058")
2022/03/03 05:28:48 DEBUG : yam: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1166b4ec7d646781_d20220303_m052009_c001_v0001099_t0027")
2022/03/03 05:28:48 DEBUG : yam: Deleting (id "4_zef923d93306d4beb7ff80e1b_f114993e1f0a6d6f1_d20220303_m052005_c001_v0001106_t0020")
2022/03/03 05:28:48 DEBUG : yam: Deleting (id "4_zef923d93306d4beb7ff80e1b_f119b103a89aa77d6_d20220303_m051959_c001_v0001116_t0042")
2022/03/03 05:28:48 DEBUG : yaml: Deleting (id "4_zef923d93306d4beb7ff80e1b_f1169e243bdcaab1f_d20220303_m052013_c001_v0001102_t0033")
2022/03/03 05:28:48 DEBUG : yaml: Deleting (id "4_zef923d93306d4beb7ff80e1b_f104ff0a5eb45cdb8_d20220303_m052010_c001_v0001134_t0001")
2022/03/03 05:28:48 DEBUG : yaml: Deleting (id "4_zef923d93306d4beb7ff80e1b_f111fb9222c7b8906_d20220303_m052007_c001_v0001098_t0031")
2022/03/03 05:28:48 DEBUG : yaml: Deleting (id "4_zef923d93306d4beb7ff80e1b_f116a2299bf9f265c_d20220303_m052002_c001_v0001144_t0053")
"./sync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose" - Finished OK in 11m20.561004923s (try 1/5)