"./sync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -fast-list" - Starting (try 1/5) 2022/01/23 06:25:34 DEBUG : Creating backend with remote "TestB2:rclone-test-gugohed8fiwisur3dexixow1" 2022/01/23 06:25:34 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2022/01/23 06:25:35 DEBUG : Creating backend with remote "/tmp/rclone3379925398" === RUN TestPipe --- PASS: TestPipe (0.00s) === RUN TestPipeConcurrent --- PASS: TestPipeConcurrent (0.03s) === 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-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:25:36 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:25:36 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) 2022/01/23 06:25:36 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish --- PASS: TestCopyWithDryRun (1.70s) === RUN TestCopy run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:25:37 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:25:37 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish 2022/01/23 06:25:38 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2022/01/23 06:25:38 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopy (3.15s) === RUN TestCopyMissingDirectory run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:25:39 DEBUG : Creating backend with remote "/non-existing" 2022/01/23 06:25:40 ERROR : Local file system at /non-existing: error reading source root directory: directory not found 2022/01/23 06:25:40 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:25:40 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish --- PASS: TestCopyMissingDirectory (0.52s) === RUN TestCopyNoTraverse run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:25:41 DEBUG : Couldn't decode error response: EOF 2022/01/23 06:25:41 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:25:41 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish 2022/01/23 06:25:41 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2022/01/23 06:25:41 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoTraverse (2.98s) === RUN TestCopyCheckFirst run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:25:43 INFO : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Running all checks before starting transfers 2022/01/23 06:25:43 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:25:43 INFO : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Checks finished, now starting transfers 2022/01/23 06:25:43 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish 2022/01/23 06:25:44 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2022/01/23 06:25:44 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyCheckFirst (2.74s) === RUN TestSyncNoTraverse run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:25:46 ERROR : Ignoring --no-traverse with sync 2022/01/23 06:25:46 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:25:46 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish 2022/01/23 06:25:47 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2022/01/23 06:25:47 INFO : sub dir/hello world: Copied (new) 2022/01/23 06:25:47 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoTraverse (2.22s) === RUN TestCopyWithDepth run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:25:48 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:25:48 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish 2022/01/23 06:25:49 DEBUG : hello world2: sha1 = c0bf117f9c93d02c894fe8ffd6e5cecf3545c209 OK 2022/01/23 06:25:49 INFO : hello world2: Copied (new) --- PASS: TestCopyWithDepth (1.95s) === RUN TestCopyWithFilesFrom run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:25:50 DEBUG : hello world2: Excluded 2022/01/23 06:25:50 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:25:50 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish 2022/01/23 06:25:51 DEBUG : potato2: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2022/01/23 06:25:51 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFrom (2.75s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:25:53 DEBUG : Couldn't decode error response: EOF 2022/01/23 06:25:53 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:25:53 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish 2022/01/23 06:25:57 DEBUG : potato2: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2022/01/23 06:25:57 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFromAndNoTraverse (5.50s) === RUN TestCopyEmptyDirectories run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:25:58 DEBUG : sub dir2: Making directory 2022/01/23 06:25:58 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:25:58 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish 2022/01/23 06:25:59 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2022/01/23 06:25:59 INFO : sub dir/hello world: Copied (new) 2022/01/23 06:25:59 DEBUG : sub dir2: Making directory 2022/01/23 06:25:59 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: copied 1 directories fstest.go:251: Filtering empty directory "sub dir2" --- PASS: TestCopyEmptyDirectories (2.00s) === RUN TestMoveEmptyDirectories run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:26:00 DEBUG : sub dir2: Making directory 2022/01/23 06:26:00 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:26:00 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish 2022/01/23 06:26:03 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2022/01/23 06:26:03 INFO : sub dir/hello world: Copied (new) 2022/01/23 06:26:03 INFO : sub dir/hello world: Deleted 2022/01/23 06:26:03 DEBUG : sub dir: Making directory 2022/01/23 06:26:03 DEBUG : sub dir2: Making directory 2022/01/23 06:26:03 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: copied 2 directories fstest.go:251: Filtering empty directory "sub dir2" --- PASS: TestMoveEmptyDirectories (3.54s) === RUN TestSyncEmptyDirectories run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:26:04 DEBUG : sub dir2: Making directory 2022/01/23 06:26:04 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:26:04 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish 2022/01/23 06:26:04 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2022/01/23 06:26:04 INFO : sub dir/hello world: Copied (new) 2022/01/23 06:26:04 DEBUG : sub dir2: Making directory 2022/01/23 06:26:04 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: copied 1 directories 2022/01/23 06:26:04 DEBUG : Waiting for deletions to finish fstest.go:251: Filtering empty directory "sub dir2" --- PASS: TestSyncEmptyDirectories (1.93s) === RUN TestServerSideCopy run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:26:06 DEBUG : Creating backend with remote "TestB2:rclone-test-jifonaw2fekeqex9mowahin5" sync_test.go:275: Server side copy (if possible) B2 bucket rclone-test-gugohed8fiwisur3dexixow1 -> B2 bucket rclone-test-jifonaw2fekeqex9mowahin5 2022/01/23 06:26:07 DEBUG : B2 bucket rclone-test-jifonaw2fekeqex9mowahin5: Waiting for checks to finish 2022/01/23 06:26:07 DEBUG : B2 bucket rclone-test-jifonaw2fekeqex9mowahin5: Waiting for transfers to finish 2022/01/23 06:26:09 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2022/01/23 06:26:09 INFO : sub dir/hello world: Copied (server-side copy) 2022/01/23 06:26:09 DEBUG : B2 bucket rclone-test-jifonaw2fekeqex9mowahin5: Purge remote 2022/01/23 06:26:09 DEBUG : sub dir/hello world: Deleting (id "4_zbf020d7390dd8b5b7fe80e1b_f102a5b8bd6f78d1e_d20220123_m062608_c001_v0001021_t0032") --- PASS: TestServerSideCopy (4.45s) === RUN TestCopyAfterDelete run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:26:13 ERROR : : error listing: directory not found 2022/01/23 06:26:13 DEBUG : Local file system at /tmp/rclone3379925398: Making directory 2022/01/23 06:26:13 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:26:13 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish --- PASS: TestCopyAfterDelete (3.84s) === RUN TestCopyRedownload run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:26:15 DEBUG : Local file system at /tmp/rclone3379925398: Waiting for checks to finish 2022/01/23 06:26:15 DEBUG : Local file system at /tmp/rclone3379925398: Waiting for transfers to finish 2022/01/23 06:26:16 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2022/01/23 06:26:16 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyRedownload (2.52s) === RUN TestSyncBasedOnCheckSum run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:26:17 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:26:17 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish 2022/01/23 06:26:22 DEBUG : check sum: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2022/01/23 06:26:22 INFO : check sum: Copied (new) 2022/01/23 06:26:22 DEBUG : Waiting for deletions to finish 2022/01/23 06:26:23 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:26:23 DEBUG : check sum: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2022/01/23 06:26:23 DEBUG : check sum: Size and sha1 of src and dst objects identical 2022/01/23 06:26:23 DEBUG : check sum: Unchanged skipping 2022/01/23 06:26:23 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish 2022/01/23 06:26:23 DEBUG : Waiting for deletions to finish 2022/01/23 06:26:23 INFO : There was nothing to transfer --- PASS: TestSyncBasedOnCheckSum (7.41s) === RUN TestSyncSizeOnly run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:26:24 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:26:24 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish 2022/01/23 06:26:25 DEBUG : sizeonly: sha1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2022/01/23 06:26:25 INFO : sizeonly: Copied (new) 2022/01/23 06:26:25 DEBUG : Waiting for deletions to finish 2022/01/23 06:26:26 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:26:26 DEBUG : sizeonly: Sizes identical 2022/01/23 06:26:26 DEBUG : sizeonly: Unchanged skipping 2022/01/23 06:26:26 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish 2022/01/23 06:26:26 DEBUG : Waiting for deletions to finish 2022/01/23 06:26:26 INFO : There was nothing to transfer --- PASS: TestSyncSizeOnly (2.79s) === RUN TestSyncIgnoreSize run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:26:27 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:26:27 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish 2022/01/23 06:26:32 DEBUG : ignore-size: sha1 = 4a756ca07e9487f482465a99e8286abc86ba4dc7 OK 2022/01/23 06:26:32 INFO : ignore-size: Copied (new) 2022/01/23 06:26:32 DEBUG : Waiting for deletions to finish 2022/01/23 06:26:33 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:26:33 DEBUG : ignore-size: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:26:33 DEBUG : ignore-size: Unchanged skipping 2022/01/23 06:26:33 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish 2022/01/23 06:26:33 DEBUG : Waiting for deletions to finish 2022/01/23 06:26:33 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreSize (7.50s) === RUN TestSyncIgnoreTimes run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:26:37 DEBUG : existing: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:26:37 DEBUG : existing: Unchanged skipping 2022/01/23 06:26:37 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:26:37 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish 2022/01/23 06:26:37 DEBUG : Waiting for deletions to finish 2022/01/23 06:26:37 INFO : There was nothing to transfer 2022/01/23 06:26:37 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2022/01/23 06:26:37 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:26:37 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish 2022/01/23 06:26:38 DEBUG : existing: sha1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2022/01/23 06:26:38 INFO : existing: Copied (replaced existing) 2022/01/23 06:26:38 DEBUG : Waiting for deletions to finish --- PASS: TestSyncIgnoreTimes (13.29s) === RUN TestSyncIgnoreExisting run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:26:48 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:26:48 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish 2022/01/23 06:26:49 DEBUG : existing: sha1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2022/01/23 06:26:49 INFO : existing: Copied (new) 2022/01/23 06:26:49 DEBUG : Waiting for deletions to finish 2022/01/23 06:26:49 DEBUG : existing: Destination exists, skipping 2022/01/23 06:26:49 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:26:49 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish 2022/01/23 06:26:49 DEBUG : Waiting for deletions to finish 2022/01/23 06:26:49 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreExisting (2.65s) === RUN TestSyncIgnoreErrors run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:26:52 DEBUG : d: Making directory fstest.go:251: Filtering empty directory "d" 2022/01/23 06:26:52 DEBUG : c/non empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/23 06:26:52 DEBUG : c/non empty space: Unchanged skipping 2022/01/23 06:26:52 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:26:52 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish 2022/01/23 06:27:00 DEBUG : a/potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2022/01/23 06:27:00 INFO : a/potato2: Copied (new) 2022/01/23 06:27:00 DEBUG : Waiting for deletions to finish 2022/01/23 06:27:01 INFO : b/potato: Deleted --- PASS: TestSyncIgnoreErrors (12.27s) === RUN TestSyncAfterChangingModtimeOnly run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:27:03 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:27:03 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/01/23 06:27:03 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2022/01/23 06:27:03 NOTICE: empty space: Skipped update modification time as --dry-run is set (size 1) 2022/01/23 06:27:03 DEBUG : empty space: Unchanged skipping 2022/01/23 06:27:03 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish 2022/01/23 06:27:03 DEBUG : Waiting for deletions to finish 2022/01/23 06:27:03 INFO : There was nothing to transfer 2022/01/23 06:27:04 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:27:04 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/01/23 06:27:04 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2022/01/23 06:27:05 INFO : empty space: Updated modification time in destination 2022/01/23 06:27:05 DEBUG : empty space: Unchanged skipping 2022/01/23 06:27:05 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish 2022/01/23 06:27:05 DEBUG : Waiting for deletions to finish 2022/01/23 06:27:05 INFO : There was nothing to transfer --- PASS: TestSyncAfterChangingModtimeOnly (3.25s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:27:07 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/01/23 06:27:07 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:27:07 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2022/01/23 06:27:07 DEBUG : empty space: Unchanged skipping 2022/01/23 06:27:07 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish 2022/01/23 06:27:07 DEBUG : Waiting for deletions to finish 2022/01/23 06:27:07 INFO : There was nothing to transfer --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (2.18s) === RUN TestSyncDoesntUpdateModtime run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:27:09 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:27:09 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/01/23 06:27:09 DEBUG : foo: sha1 = 0beec7b5ea3f0fdbc95d0dd47f3c5bc275da8a33 (Local file system at /tmp/rclone3379925398) 2022/01/23 06:27:09 DEBUG : foo: sha1 = 62cdb7020ff920e5aa642c3d4066950dd1f01f4d (B2 bucket rclone-test-gugohed8fiwisur3dexixow1) 2022/01/23 06:27:09 DEBUG : foo: sha1 differ 2022/01/23 06:27:09 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish 2022/01/23 06:27:11 DEBUG : foo: sha1 = 0beec7b5ea3f0fdbc95d0dd47f3c5bc275da8a33 OK 2022/01/23 06:27:11 INFO : foo: Copied (replaced existing) 2022/01/23 06:27:11 DEBUG : Waiting for deletions to finish --- PASS: TestSyncDoesntUpdateModtime (4.91s) === RUN TestSyncAfterAddingAFile run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:27:14 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:27:14 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/23 06:27:14 DEBUG : empty space: Unchanged skipping 2022/01/23 06:27:14 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish 2022/01/23 06:27:14 DEBUG : potato: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2022/01/23 06:27:14 INFO : potato: Copied (new) 2022/01/23 06:27:14 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterAddingAFile (3.84s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:27:18 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:27:18 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2022/01/23 06:27:18 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish 2022/01/23 06:27:18 DEBUG : potato: sha1 = 0fd321a1023ac4e1e1054fcc0236b9d0755f722f OK 2022/01/23 06:27:18 INFO : potato: Copied (replaced existing) 2022/01/23 06:27:18 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingFilesSizeOnly (2.90s) === RUN TestSyncAfterChangingContentsOnly run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:27:20 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/01/23 06:27:20 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:27:20 DEBUG : potato: sha1 = 1ca5094b3b73f587249eabe179c40a3eec839b1f (Local file system at /tmp/rclone3379925398) 2022/01/23 06:27:20 DEBUG : potato: sha1 = 0fd321a1023ac4e1e1054fcc0236b9d0755f722f (B2 bucket rclone-test-gugohed8fiwisur3dexixow1) 2022/01/23 06:27:20 DEBUG : potato: sha1 differ 2022/01/23 06:27:20 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish 2022/01/23 06:27:23 DEBUG : potato: sha1 = 1ca5094b3b73f587249eabe179c40a3eec839b1f OK 2022/01/23 06:27:23 INFO : potato: Copied (replaced existing) 2022/01/23 06:27:23 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingContentsOnly (4.92s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:27:27 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2022/01/23 06:27:27 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:27:27 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/23 06:27:27 DEBUG : empty space: Unchanged skipping 2022/01/23 06:27:27 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish 2022/01/23 06:27:27 DEBUG : Waiting for deletions to finish 2022/01/23 06:27:27 NOTICE: potato: Skipped delete as --dry-run is set (size 21) --- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (4.51s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:27:31 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/23 06:27:31 DEBUG : empty space: Unchanged skipping 2022/01/23 06:27:31 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:27:31 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish 2022/01/23 06:27:31 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2022/01/23 06:27:31 INFO : potato2: Copied (new) 2022/01/23 06:27:31 DEBUG : Waiting for deletions to finish 2022/01/23 06:27:31 INFO : potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (4.10s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:27:34 DEBUG : d: Making directory 2022/01/23 06:27:34 DEBUG : d/e: Making directory fstest.go:251: Filtering empty directory "d" fstest.go:251: Filtering empty directory "d/e" 2022/01/23 06:27:34 DEBUG : c/non empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/23 06:27:34 DEBUG : c/non empty space: Unchanged skipping 2022/01/23 06:27:34 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:27:34 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish 2022/01/23 06:27:35 DEBUG : a/potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2022/01/23 06:27:35 INFO : a/potato2: Copied (new) 2022/01/23 06:27:35 DEBUG : Waiting for deletions to finish 2022/01/23 06:27:36 INFO : b/potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (5.05s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:27:48 DEBUG : d: Making directory fstest.go:251: Filtering empty directory "d" 2022/01/23 06:27:49 DEBUG : c/non empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/23 06:27:49 DEBUG : c/non empty space: Unchanged skipping 2022/01/23 06:27:49 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:27:49 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish 2022/01/23 06:27:49 DEBUG : a/potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2022/01/23 06:27:49 INFO : a/potato2: Copied (new) 2022/01/23 06:27:49 ERROR : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: not deleting files as there were IO errors 2022/01/23 06:27:49 ERROR : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: not deleting directories as there were IO errors fstest.go:251: Filtering empty directory "d" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (12.90s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:27:52 DEBUG : Waiting for deletions to finish 2022/01/23 06:27:52 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:27:52 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/23 06:27:52 DEBUG : empty space: Unchanged skipping 2022/01/23 06:27:52 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish 2022/01/23 06:27:53 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2022/01/23 06:27:53 INFO : potato2: Copied (new) 2022/01/23 06:27:53 INFO : potato: Deleted --- PASS: TestSyncDeleteDuring (3.75s) === RUN TestSyncDeleteBefore run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:27:56 DEBUG : Waiting for deletions to finish 2022/01/23 06:27:57 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:27:57 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish 2022/01/23 06:27:57 INFO : potato: Deleted 2022/01/23 06:27:57 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:27:57 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/23 06:27:57 DEBUG : empty space: Unchanged skipping 2022/01/23 06:27:57 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish 2022/01/23 06:27:58 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2022/01/23 06:27:58 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteBefore (5.51s) === RUN TestCopyDeleteBefore run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:28:02 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:28:02 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish 2022/01/23 06:28:03 DEBUG : potato2: sha1 = 810274f0df19dab60a894a562b7a8ff04c072623 OK 2022/01/23 06:28:03 INFO : potato2: Copied (new) --- PASS: TestCopyDeleteBefore (5.17s) === RUN TestSyncWithExclude run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:28:08 DEBUG : enormous: Excluded 2022/01/23 06:28:08 DEBUG : potato2: Excluded 2022/01/23 06:28:08 DEBUG : potato2: Excluded from sync (and deletion) 2022/01/23 06:28:08 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:28:08 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/23 06:28:08 DEBUG : empty space: Unchanged skipping 2022/01/23 06:28:08 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish 2022/01/23 06:28:08 DEBUG : Waiting for deletions to finish 2022/01/23 06:28:08 INFO : There was nothing to transfer 2022/01/23 06:28:08 DEBUG : enormous: Excluded 2022/01/23 06:28:08 DEBUG : potato2: Excluded 2022/01/23 06:28:08 DEBUG : potato2: Excluded from sync (and deletion) 2022/01/23 06:28:08 DEBUG : Local file system at /tmp/rclone3379925398: Waiting for checks to finish 2022/01/23 06:28:08 DEBUG : empty space: Size and modification time the same (differ by 456.789µs, within tolerance 1ms) 2022/01/23 06:28:08 DEBUG : empty space: Unchanged skipping 2022/01/23 06:28:08 DEBUG : Local file system at /tmp/rclone3379925398: Waiting for transfers to finish 2022/01/23 06:28:08 DEBUG : Waiting for deletions to finish 2022/01/23 06:28:08 INFO : There was nothing to transfer --- PASS: TestSyncWithExclude (5.00s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:28:13 DEBUG : enormous: Excluded 2022/01/23 06:28:13 DEBUG : potato2: Excluded 2022/01/23 06:28:13 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:28:13 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/23 06:28:13 DEBUG : empty space: Unchanged skipping 2022/01/23 06:28:13 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish 2022/01/23 06:28:13 DEBUG : Waiting for deletions to finish 2022/01/23 06:28:15 INFO : potato2: Deleted 2022/01/23 06:28:17 INFO : enormous: Deleted 2022/01/23 06:28:17 INFO : There was nothing to transfer 2022/01/23 06:28:17 DEBUG : Local file system at /tmp/rclone3379925398: Waiting for checks to finish 2022/01/23 06:28:17 DEBUG : empty space: Size and modification time the same (differ by 456.789µs, within tolerance 1ms) 2022/01/23 06:28:17 DEBUG : empty space: Unchanged skipping 2022/01/23 06:28:17 DEBUG : Local file system at /tmp/rclone3379925398: Waiting for transfers to finish 2022/01/23 06:28:17 DEBUG : Waiting for deletions to finish 2022/01/23 06:28:17 INFO : enormous: Deleted 2022/01/23 06:28:17 INFO : potato2: Deleted 2022/01/23 06:28:17 INFO : There was nothing to transfer --- PASS: TestSyncWithExcludeAndDeleteExcluded (7.54s) === RUN TestSyncWithUpdateOlder run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:28:18 DEBUG : one: Clearing upload URL because of error: Post "https://pod-000-1040-08.backblaze.com/b2api/v1/b2_upload_file/2fa28da390cd8b5b7fe80e1b/c001_v0001040_t0023": EOF 2022/01/23 06:28:18 DEBUG : pacer: low level retry 1/1 (error Post "https://pod-000-1040-08.backblaze.com/b2api/v1/b2_upload_file/2fa28da390cd8b5b7fe80e1b/c001_v0001040_t0023": EOF) 2022/01/23 06:28:18 DEBUG : pacer: Rate limited, increasing sleep to 20ms run.go:283: Retry Put of "one" to B2 bucket rclone-test-gugohed8fiwisur3dexixow1: 1/10 (Post "https://pod-000-1040-08.backblaze.com/b2api/v1/b2_upload_file/2fa28da390cd8b5b7fe80e1b/c001_v0001040_t0023": EOF) 2022/01/23 06:28:20 DEBUG : pacer: Reducing sleep to 10ms 2022/01/23 06:28:23 DEBUG : four: Sizes differ (src 4 vs dst 8) 2022/01/23 06:28:23 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2022/01/23 06:28:23 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:28:23 DEBUG : one: Destination is newer than source, skipping 2022/01/23 06:28:23 DEBUG : three: Sizes identical 2022/01/23 06:28:23 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2022/01/23 06:28:23 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b (Local file system at /tmp/rclone3379925398) 2022/01/23 06:28:23 DEBUG : two: sha1 = b10e0da85e90399abfec36c7376b20aa4596461b (B2 bucket rclone-test-gugohed8fiwisur3dexixow1) 2022/01/23 06:28:23 DEBUG : two: sha1 differ 2022/01/23 06:28:23 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish 2022/01/23 06:28:24 DEBUG : five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2022/01/23 06:28:24 INFO : five: Copied (new) 2022/01/23 06:28:25 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2022/01/23 06:28:25 INFO : two: Copied (replaced existing) 2022/01/23 06:28:25 DEBUG : four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2022/01/23 06:28:25 INFO : four: Copied (replaced existing) 2022/01/23 06:28:25 DEBUG : Waiting for deletions to finish 2022/01/23 06:28:26 DEBUG : one: Destination is newer than source, skipping 2022/01/23 06:28:26 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:28:26 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2022/01/23 06:28:26 DEBUG : two: Size and sha1 of src and dst objects identical 2022/01/23 06:28:26 DEBUG : two: Destination mod time is within 1s of source and files identical, skipping 2022/01/23 06:28:26 DEBUG : five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2022/01/23 06:28:26 DEBUG : five: Size and sha1 of src and dst objects identical 2022/01/23 06:28:26 DEBUG : five: Destination mod time is within 1s of source and files identical, skipping 2022/01/23 06:28:26 DEBUG : four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2022/01/23 06:28:26 DEBUG : four: Size and sha1 of src and dst objects identical 2022/01/23 06:28:26 DEBUG : four: Destination mod time is within 1s of source and files identical, skipping 2022/01/23 06:28:26 DEBUG : three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb (Local file system at /tmp/rclone3379925398) 2022/01/23 06:28:26 DEBUG : three: sha1 = 03731cad13f4cf7b59703c77529381cdec1632e5 (B2 bucket rclone-test-gugohed8fiwisur3dexixow1) 2022/01/23 06:28:26 DEBUG : three: sha1 differ 2022/01/23 06:28:26 DEBUG : three: Destination mod time is within 1s of source but files differ, transferring 2022/01/23 06:28:26 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish 2022/01/23 06:28:26 DEBUG : three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2022/01/23 06:28:26 INFO : three: Copied (replaced existing) 2022/01/23 06:28:26 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithUpdateOlder (12.12s) === 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-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" sync_test.go:1058: Can track renames: true 2022/01/23 06:28:30 INFO : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Making map for --track-renames 2022/01/23 06:28:30 INFO : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Finished making map for --track-renames 2022/01/23 06:28:30 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:28:30 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for renames to finish 2022/01/23 06:28:30 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish 2022/01/23 06:28:31 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2022/01/23 06:28:31 INFO : potato: Copied (new) 2022/01/23 06:28:31 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2022/01/23 06:28:31 INFO : yam: Copied (new) 2022/01/23 06:28:31 DEBUG : Waiting for deletions to finish 2022/01/23 06:28:32 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:28:32 DEBUG : potato: Unchanged skipping 2022/01/23 06:28:32 INFO : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Making map for --track-renames 2022/01/23 06:28:32 INFO : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Finished making map for --track-renames 2022/01/23 06:28:32 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:28:32 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for renames to finish 2022/01/23 06:28:32 DEBUG : Couldn't decode error response: EOF 2022/01/23 06:28:33 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2022/01/23 06:28:33 INFO : yam: Copied (server-side copy) to: yaml 2022/01/23 06:28:33 INFO : yam: Deleted 2022/01/23 06:28:33 INFO : yaml: Renamed from "yam" 2022/01/23 06:28:33 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish 2022/01/23 06:28:33 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenames (5.92s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" sync_test.go:1127: Can track renames: true 2022/01/23 06:28:36 INFO : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Making map for --track-renames 2022/01/23 06:28:36 INFO : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Finished making map for --track-renames 2022/01/23 06:28:36 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:28:36 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for renames to finish 2022/01/23 06:28:36 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish 2022/01/23 06:28:36 DEBUG : yam: Clearing upload URL because of error: Post "https://pod-000-1098-15.backblaze.com/b2api/v1/b2_upload_file/2fa28da390cd8b5b7fe80e1b/c001_v0001098_t0001": EOF 2022/01/23 06:28:36 DEBUG : pacer: low level retry 1/1 (error Post "https://pod-000-1098-15.backblaze.com/b2api/v1/b2_upload_file/2fa28da390cd8b5b7fe80e1b/c001_v0001098_t0001": EOF) 2022/01/23 06:28:36 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2022/01/23 06:28:36 DEBUG : yam: Received error: Post "https://pod-000-1098-15.backblaze.com/b2api/v1/b2_upload_file/2fa28da390cd8b5b7fe80e1b/c001_v0001098_t0001": EOF - low level retry 1/10 2022/01/23 06:28:36 DEBUG : yam: Clearing upload URL because of error: Post "https://pod-000-1097-06.backblaze.com/b2api/v1/b2_upload_file/2fa28da390cd8b5b7fe80e1b/c001_v0001097_t0018": EOF 2022/01/23 06:28:36 DEBUG : pacer: low level retry 1/1 (error Post "https://pod-000-1097-06.backblaze.com/b2api/v1/b2_upload_file/2fa28da390cd8b5b7fe80e1b/c001_v0001097_t0018": EOF) 2022/01/23 06:28:36 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2022/01/23 06:28:36 DEBUG : yam: Received error: Post "https://pod-000-1097-06.backblaze.com/b2api/v1/b2_upload_file/2fa28da390cd8b5b7fe80e1b/c001_v0001097_t0018": EOF - low level retry 2/10 2022/01/23 06:28:36 DEBUG : pacer: Reducing sleep to 20ms 2022/01/23 06:28:37 DEBUG : pacer: Reducing sleep to 10ms 2022/01/23 06:28:37 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2022/01/23 06:28:37 INFO : potato: Copied (new) 2022/01/23 06:28:38 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2022/01/23 06:28:38 INFO : yam: Copied (new) 2022/01/23 06:28:38 DEBUG : Waiting for deletions to finish 2022/01/23 06:28:38 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:28:38 INFO : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Making map for --track-renames 2022/01/23 06:28:38 DEBUG : potato: Unchanged skipping 2022/01/23 06:28:38 INFO : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Finished making map for --track-renames 2022/01/23 06:28:38 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:28:38 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for renames to finish 2022/01/23 06:28:39 DEBUG : Couldn't decode error response: EOF 2022/01/23 06:28:40 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2022/01/23 06:28:40 INFO : yam: Copied (server-side copy) to: yaml 2022/01/23 06:28:40 INFO : yam: Deleted 2022/01/23 06:28:40 INFO : yaml: Renamed from "yam" 2022/01/23 06:28:40 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish 2022/01/23 06:28:40 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenamesStrategyModtime (6.22s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" sync_test.go:1163: Can track renames: true 2022/01/23 06:28:42 INFO : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Making map for --track-renames 2022/01/23 06:28:42 INFO : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Finished making map for --track-renames 2022/01/23 06:28:42 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:28:42 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for renames to finish 2022/01/23 06:28:42 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish 2022/01/23 06:28:43 DEBUG : sub/yam: Clearing upload URL because of error: Post "https://pod-000-1165-19.backblaze.com/b2api/v1/b2_upload_file/2fa28da390cd8b5b7fe80e1b/c001_v0001165_t0029": EOF 2022/01/23 06:28:43 DEBUG : pacer: low level retry 1/1 (error Post "https://pod-000-1165-19.backblaze.com/b2api/v1/b2_upload_file/2fa28da390cd8b5b7fe80e1b/c001_v0001165_t0029": EOF) 2022/01/23 06:28:43 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2022/01/23 06:28:43 DEBUG : sub/yam: Received error: Post "https://pod-000-1165-19.backblaze.com/b2api/v1/b2_upload_file/2fa28da390cd8b5b7fe80e1b/c001_v0001165_t0029": EOF - low level retry 1/10 2022/01/23 06:28:43 DEBUG : pacer: Reducing sleep to 10ms 2022/01/23 06:28:43 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2022/01/23 06:28:43 INFO : potato: Copied (new) 2022/01/23 06:28:44 DEBUG : sub/yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2022/01/23 06:28:44 INFO : sub/yam: Copied (new) 2022/01/23 06:28:44 DEBUG : Waiting for deletions to finish 2022/01/23 06:28:44 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:28:44 DEBUG : potato: Unchanged skipping 2022/01/23 06:28:44 INFO : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Making map for --track-renames 2022/01/23 06:28:44 INFO : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Finished making map for --track-renames 2022/01/23 06:28:44 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:28:44 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for renames to finish 2022/01/23 06:28:45 DEBUG : Couldn't decode error response: EOF 2022/01/23 06:28:46 DEBUG : sub/yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2022/01/23 06:28:46 INFO : sub/yam: Copied (server-side copy) to: yam 2022/01/23 06:28:46 INFO : sub/yam: Deleted 2022/01/23 06:28:46 INFO : yam: Renamed from "sub/yam" 2022/01/23 06:28:46 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish 2022/01/23 06:28:46 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenamesStrategyLeaf (5.72s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:28:48 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:28:48 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish 2022/01/23 06:28:48 DEBUG : nested/sub dir/file: sha1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK 2022/01/23 06:28:48 INFO : nested/sub dir/file: Copied (new) 2022/01/23 06:28:48 INFO : nested/sub dir/file: Deleted 2022/01/23 06:28:49 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2022/01/23 06:28:49 INFO : sub dir/hello world: Copied (new) 2022/01/23 06:28:49 INFO : sub dir/hello world: Deleted 2022/01/23 06:28:49 INFO : sub dir: Removing directory 2022/01/23 06:28:49 INFO : nested/sub dir: Removing directory 2022/01/23 06:28:49 INFO : nested: Removing directory 2022/01/23 06:28:49 DEBUG : Local file system at /tmp/rclone3379925398: deleted 3 directories --- PASS: TestMoveWithDeleteEmptySrcDirs (3.93s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:28:52 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:28:52 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish 2022/01/23 06:28:52 DEBUG : nested/sub dir/file: sha1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK 2022/01/23 06:28:52 INFO : nested/sub dir/file: Copied (new) 2022/01/23 06:28:52 INFO : nested/sub dir/file: Deleted 2022/01/23 06:28:55 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2022/01/23 06:28:55 INFO : sub dir/hello world: Copied (new) 2022/01/23 06:28:55 INFO : sub dir/hello world: Deleted --- PASS: TestMoveWithoutDeleteEmptySrcDirs (4.35s) === RUN TestMoveWithIgnoreExisting run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:28:56 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:28:56 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish 2022/01/23 06:28:57 DEBUG : existing-b: sha1 = 321f6b7e8bf7f29aab89c5e41a555b1b0baa41a9 OK 2022/01/23 06:28:57 INFO : existing-b: Copied (new) 2022/01/23 06:28:57 INFO : existing-b: Deleted 2022/01/23 06:28:57 DEBUG : existing: sha1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2022/01/23 06:28:57 INFO : existing: Copied (new) 2022/01/23 06:28:57 INFO : existing: Deleted 2022/01/23 06:28:57 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:28:57 DEBUG : existing: Destination exists, skipping 2022/01/23 06:28:57 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set 2022/01/23 06:28:57 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish 2022/01/23 06:28:57 INFO : There was nothing to transfer --- PASS: TestMoveWithIgnoreExisting (3.41s) === RUN TestServerSideMove run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:29:00 DEBUG : Creating backend with remote "TestB2:rclone-test-coporux8fefinin4zerewec9" sync_test.go:1215: Server side move (if possible) B2 bucket rclone-test-gugohed8fiwisur3dexixow1 -> B2 bucket rclone-test-coporux8fefinin4zerewec9 2022/01/23 06:29:05 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms) 2022/01/23 06:29:05 DEBUG : empty space: Unchanged skipping 2022/01/23 06:29:05 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2022/01/23 06:29:05 DEBUG : B2 bucket rclone-test-coporux8fefinin4zerewec9: Waiting for checks to finish 2022/01/23 06:29:05 DEBUG : potato3: sha1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2022/01/23 06:29:05 INFO : potato3: Copied (server-side copy) 2022/01/23 06:29:06 INFO : empty space: Deleted 2022/01/23 06:29:06 DEBUG : B2 bucket rclone-test-coporux8fefinin4zerewec9: Waiting for transfers to finish 2022/01/23 06:29:06 INFO : potato3: Deleted 2022/01/23 06:29:06 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2022/01/23 06:29:06 INFO : potato2: Copied (server-side copy) 2022/01/23 06:29:06 INFO : potato2: Deleted 2022/01/23 06:29:07 DEBUG : Creating backend with remote "TestB2:rclone-test-hukulix0fodociz6notaxov4" 2022/01/23 06:29:08 DEBUG : B2 bucket rclone-test-hukulix0fodociz6notaxov4: Waiting for checks to finish 2022/01/23 06:29:08 DEBUG : B2 bucket rclone-test-hukulix0fodociz6notaxov4: Waiting for transfers to finish 2022/01/23 06:29:08 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2022/01/23 06:29:08 INFO : potato2: Copied (server-side copy) 2022/01/23 06:29:09 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2022/01/23 06:29:09 INFO : empty space: Copied (server-side copy) 2022/01/23 06:29:09 DEBUG : potato3: sha1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2022/01/23 06:29:09 INFO : potato3: Copied (server-side copy) 2022/01/23 06:29:09 INFO : potato2: Deleted 2022/01/23 06:29:09 INFO : potato3: Deleted 2022/01/23 06:29:10 INFO : empty space: Deleted 2022/01/23 06:29:10 DEBUG : B2 bucket rclone-test-hukulix0fodociz6notaxov4: Purge remote 2022/01/23 06:29:11 DEBUG : empty space: Deleting (id "4_zcff2cd7390fd8b5b7fe80e1b_f119b2fb2680e26e8_d20220123_m062908_c001_v0001131_t0028") 2022/01/23 06:29:11 DEBUG : potato2: Deleting (id "4_zcff2cd7390fd8b5b7fe80e1b_f117439e3067505e6_d20220123_m062908_c001_v0001105_t0001") 2022/01/23 06:29:11 DEBUG : potato3: Deleting (id "4_zcff2cd7390fd8b5b7fe80e1b_f1098d3c1b1607fcd_d20220123_m062908_c001_v0001144_t0001") 2022/01/23 06:29:11 DEBUG : B2 bucket rclone-test-coporux8fefinin4zerewec9: Purge remote 2022/01/23 06:29:11 DEBUG : empty space: Deleting (id "4_zaf92bd0390fd8b5b7fe80e1b_f11283dd3d03bd2f8_d20220123_m062909_c001_v0001042_t0018") 2022/01/23 06:29:11 DEBUG : empty space: Deleting (id "4_zaf92bd0390fd8b5b7fe80e1b_f1166be11469d10bc_d20220123_m062903_c001_v0001098_t0037") 2022/01/23 06:29:11 DEBUG : potato2: Deleting (id "4_zaf92bd0390fd8b5b7fe80e1b_f11183f46aea06582_d20220123_m062909_c001_v0001105_t0004") 2022/01/23 06:29:11 DEBUG : potato2: Deleting (id "4_zaf92bd0390fd8b5b7fe80e1b_f1088231669f11524_d20220123_m062905_c001_v0001006_t0013") 2022/01/23 06:29:11 DEBUG : potato3: Deleting (id "4_zaf92bd0390fd8b5b7fe80e1b_f1134b6ba21fd2559_d20220123_m062909_c001_v0001154_t0005") 2022/01/23 06:29:11 DEBUG : potato3: Deleting (id "4_zaf92bd0390fd8b5b7fe80e1b_f1091ab1981d9fe3f_d20220123_m062905_c001_v0001157_t0027") 2022/01/23 06:29:11 DEBUG : potato3: Deleting (id "4_zaf92bd0390fd8b5b7fe80e1b_f1166be11469d10be_d20220123_m062904_c001_v0001098_t0000") --- PASS: TestServerSideMove (13.07s) === RUN TestServerSideMoveWithFilter run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:29:13 DEBUG : Creating backend with remote "TestB2:rclone-test-yujoxek3weyazum6loxixek7" sync_test.go:1215: Server side move (if possible) B2 bucket rclone-test-gugohed8fiwisur3dexixow1 -> B2 bucket rclone-test-yujoxek3weyazum6loxixek7 2022/01/23 06:29:18 DEBUG : empty space: Excluded from sync (and deletion) 2022/01/23 06:29:19 DEBUG : empty space: Excluded from sync (and deletion) 2022/01/23 06:29:19 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2022/01/23 06:29:19 DEBUG : B2 bucket rclone-test-yujoxek3weyazum6loxixek7: Waiting for checks to finish 2022/01/23 06:29:19 DEBUG : B2 bucket rclone-test-yujoxek3weyazum6loxixek7: Waiting for transfers to finish 2022/01/23 06:29:19 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2022/01/23 06:29:19 INFO : potato2: Copied (server-side copy) 2022/01/23 06:29:19 DEBUG : potato3: sha1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2022/01/23 06:29:19 INFO : potato3: Copied (server-side copy) 2022/01/23 06:29:19 INFO : potato2: Deleted 2022/01/23 06:29:20 INFO : potato3: Deleted 2022/01/23 06:29:20 DEBUG : Creating backend with remote "TestB2:rclone-test-sadiyek7goqurax5hevidis6" 2022/01/23 06:29:21 DEBUG : empty space: Excluded from sync (and deletion) 2022/01/23 06:29:21 DEBUG : B2 bucket rclone-test-sadiyek7goqurax5hevidis6: Waiting for checks to finish 2022/01/23 06:29:21 DEBUG : B2 bucket rclone-test-sadiyek7goqurax5hevidis6: Waiting for transfers to finish 2022/01/23 06:29:22 DEBUG : potato3: sha1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2022/01/23 06:29:22 INFO : potato3: Copied (server-side copy) 2022/01/23 06:29:22 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2022/01/23 06:29:22 INFO : potato2: Copied (server-side copy) 2022/01/23 06:29:22 INFO : potato3: Deleted 2022/01/23 06:29:23 INFO : potato2: Deleted 2022/01/23 06:29:23 DEBUG : B2 bucket rclone-test-sadiyek7goqurax5hevidis6: Purge remote 2022/01/23 06:29:24 DEBUG : potato2: Deleting (id "4_zbfc2fdc390fd8b5b7fe80e1b_f1186d22bf833fb33_d20220123_m062922_c001_v0001161_t0003") 2022/01/23 06:29:24 DEBUG : potato3: Deleting (id "4_zbfc2fdc390fd8b5b7fe80e1b_f1186d22bf833fb35_d20220123_m062922_c001_v0001161_t0035") 2022/01/23 06:29:24 DEBUG : B2 bucket rclone-test-yujoxek3weyazum6loxixek7: Purge remote 2022/01/23 06:29:24 DEBUG : empty space: Deleting (id "4_z8f92eda390fd8b5b7fe80e1b_f115c3074ca5adc39_d20220123_m062918_c001_v0001165_t0039") 2022/01/23 06:29:24 DEBUG : potato2: Deleting (id "4_z8f92eda390fd8b5b7fe80e1b_f11580e63d82546a2_d20220123_m062922_c001_v0001038_t0012") 2022/01/23 06:29:24 DEBUG : potato2: Deleting (id "4_z8f92eda390fd8b5b7fe80e1b_f1126f05ebdcf22a4_d20220123_m062919_c001_v0001090_t0058") 2022/01/23 06:29:24 DEBUG : potato3: Deleting (id "4_z8f92eda390fd8b5b7fe80e1b_f1018b3fe126b8b9c_d20220123_m062922_c001_v0001090_t0058") 2022/01/23 06:29:24 DEBUG : potato3: Deleting (id "4_z8f92eda390fd8b5b7fe80e1b_f118cd1b3f5bc88d7_d20220123_m062919_c001_v0001023_t0040") 2022/01/23 06:29:24 DEBUG : potato3: Deleting (id "4_z8f92eda390fd8b5b7fe80e1b_f115c3074ca5adc3d_d20220123_m062918_c001_v0001165_t0032") --- PASS: TestServerSideMoveWithFilter (14.04s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:29:27 DEBUG : Creating backend with remote "TestB2:rclone-test-metodeb8bayexah8piwimux6" 2022/01/23 06:29:31 DEBUG : tomatoDir: Making directory sync_test.go:1215: Server side move (if possible) B2 bucket rclone-test-gugohed8fiwisur3dexixow1 -> B2 bucket rclone-test-metodeb8bayexah8piwimux6 2022/01/23 06:29:37 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms) 2022/01/23 06:29:37 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2022/01/23 06:29:37 DEBUG : B2 bucket rclone-test-metodeb8bayexah8piwimux6: Waiting for checks to finish 2022/01/23 06:29:37 DEBUG : empty space: Unchanged skipping 2022/01/23 06:29:37 INFO : empty space: Deleted 2022/01/23 06:29:37 DEBUG : B2 bucket rclone-test-metodeb8bayexah8piwimux6: Waiting for transfers to finish 2022/01/23 06:29:37 DEBUG : potato3: sha1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2022/01/23 06:29:37 INFO : potato3: Copied (server-side copy) 2022/01/23 06:29:38 INFO : potato3: Deleted 2022/01/23 06:29:38 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2022/01/23 06:29:38 INFO : potato2: Copied (server-side copy) 2022/01/23 06:29:39 INFO : potato2: Deleted 2022/01/23 06:29:39 DEBUG : Creating backend with remote "TestB2:rclone-test-wirubed4femabox2lobewuf3" 2022/01/23 06:29:40 DEBUG : tomatoDir: Making directory 2022/01/23 06:29:40 DEBUG : B2 bucket rclone-test-wirubed4femabox2lobewuf3: Waiting for checks to finish 2022/01/23 06:29:40 DEBUG : B2 bucket rclone-test-wirubed4femabox2lobewuf3: Waiting for transfers to finish 2022/01/23 06:29:41 DEBUG : potato3: sha1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2022/01/23 06:29:41 INFO : potato3: Copied (server-side copy) 2022/01/23 06:29:41 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2022/01/23 06:29:41 INFO : empty space: Copied (server-side copy) 2022/01/23 06:29:41 INFO : empty space: Deleted 2022/01/23 06:29:41 INFO : potato3: Deleted 2022/01/23 06:29:42 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2022/01/23 06:29:42 INFO : potato2: Copied (server-side copy) 2022/01/23 06:29:45 INFO : potato2: Deleted 2022/01/23 06:29:45 DEBUG : B2 bucket rclone-test-wirubed4femabox2lobewuf3: Purge remote 2022/01/23 06:29:45 DEBUG : empty space: Deleting (id "4_z9f224d73a00d8b5b7fe80e1b_f11201281576b781c_d20220123_m062941_c001_v0001109_t0029") 2022/01/23 06:29:45 DEBUG : potato2: Deleting (id "4_z9f224d73a00d8b5b7fe80e1b_f113b81f681ca7aed_d20220123_m062941_c001_v0001003_t0008") 2022/01/23 06:29:45 DEBUG : potato3: Deleting (id "4_z9f224d73a00d8b5b7fe80e1b_f108997cea2d5251a_d20220123_m062941_c001_v0001098_t0027") 2022/01/23 06:29:46 DEBUG : B2 bucket rclone-test-metodeb8bayexah8piwimux6: Purge remote 2022/01/23 06:29:46 DEBUG : empty space: Deleting (id "4_z0f722d23a00d8b5b7fe80e1b_f106a1635f50a03b6_d20220123_m062941_c001_v0001153_t0015") 2022/01/23 06:29:46 DEBUG : empty space: Deleting (id "4_z0f722d23a00d8b5b7fe80e1b_f110cb4734d6ee545_d20220123_m062932_c001_v0001042_t0035") 2022/01/23 06:29:46 DEBUG : potato2: Deleting (id "4_z0f722d23a00d8b5b7fe80e1b_f10282e05567fef4c_d20220123_m062942_c001_v0001146_t0059") 2022/01/23 06:29:46 DEBUG : potato2: Deleting (id "4_z0f722d23a00d8b5b7fe80e1b_f106d5ac11b29a879_d20220123_m062937_c001_v0001109_t0034") 2022/01/23 06:29:46 DEBUG : potato3: Deleting (id "4_z0f722d23a00d8b5b7fe80e1b_f116472e614a24edc_d20220123_m062941_c001_v0001128_t0030") 2022/01/23 06:29:46 DEBUG : potato3: Deleting (id "4_z0f722d23a00d8b5b7fe80e1b_f119c2b6e4c6eb5c4_d20220123_m062937_c001_v0001161_t0043") 2022/01/23 06:29:46 DEBUG : potato3: Deleting (id "4_z0f722d23a00d8b5b7fe80e1b_f110cb4734d6ee54e_d20220123_m062935_c001_v0001042_t0021") --- PASS: TestServerSideMoveDeleteEmptySourceDirs (20.73s) === RUN TestServerSideMoveOverlap run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:29:47 DEBUG : Creating backend with remote "TestB2:rclone-test-gugohed8fiwisur3dexixow1/rclone-move-test" 2022/01/23 06:29:49 DEBUG : Couldn't decode error response: EOF --- PASS: TestServerSideMoveOverlap (3.28s) === RUN TestSyncOverlap run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:29:51 DEBUG : Creating backend with remote "TestB2:rclone-test-gugohed8fiwisur3dexixow1/rclone-sync-test" 2022/01/23 06:29:51 DEBUG : Couldn't decode error response: EOF --- PASS: TestSyncOverlap (0.71s) === RUN TestSyncCompareDest run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:29:51 DEBUG : Creating backend with remote "TestB2:rclone-test-gugohed8fiwisur3dexixow1/dst" 2022/01/23 06:29:52 DEBUG : Couldn't decode error response: EOF 2022/01/23 06:29:52 DEBUG : Creating backend with remote "TestB2:rclone-test-gugohed8fiwisur3dexixow1/CompareDest" 2022/01/23 06:29:52 DEBUG : Couldn't decode error response: EOF 2022/01/23 06:29:53 DEBUG : Couldn't decode error response: EOF 2022/01/23 06:29:53 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1 path dst: Waiting for checks to finish 2022/01/23 06:29:53 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1 path dst: Waiting for transfers to finish 2022/01/23 06:29:54 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/01/23 06:29:54 INFO : one: Copied (new) 2022/01/23 06:29:54 DEBUG : Waiting for deletions to finish 2022/01/23 06:29:54 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1 path dst: Waiting for checks to finish 2022/01/23 06:29:55 DEBUG : Couldn't decode error response: EOF 2022/01/23 06:29:55 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/01/23 06:29:55 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1 path dst: Waiting for transfers to finish 2022/01/23 06:29:55 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2022/01/23 06:29:55 INFO : one: Copied (replaced existing) 2022/01/23 06:29:55 DEBUG : Waiting for deletions to finish 2022/01/23 06:29:57 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1 path dst: Waiting for checks to finish 2022/01/23 06:29:58 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/23 06:29:58 DEBUG : one: Destination found in --compare-dest, skipping 2022/01/23 06:29:58 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1 path dst: Waiting for transfers to finish 2022/01/23 06:29:58 DEBUG : Waiting for deletions to finish 2022/01/23 06:29:58 INFO : There was nothing to transfer 2022/01/23 06:29:59 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/23 06:29:59 DEBUG : two: Destination found in --compare-dest, skipping 2022/01/23 06:29:59 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1 path dst: Waiting for checks to finish 2022/01/23 06:29:59 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/23 06:29:59 DEBUG : one: Destination found in --compare-dest, skipping 2022/01/23 06:29:59 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1 path dst: Waiting for transfers to finish 2022/01/23 06:29:59 DEBUG : Waiting for deletions to finish 2022/01/23 06:29:59 INFO : There was nothing to transfer 2022/01/23 06:30:00 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/23 06:30:00 DEBUG : two: Destination found in --compare-dest, skipping 2022/01/23 06:30:00 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1 path dst: Waiting for checks to finish 2022/01/23 06:30:00 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/23 06:30:00 DEBUG : one: Destination found in --compare-dest, skipping 2022/01/23 06:30:00 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1 path dst: Waiting for transfers to finish 2022/01/23 06:30:00 DEBUG : Waiting for deletions to finish 2022/01/23 06:30:00 INFO : There was nothing to transfer 2022/01/23 06:30:00 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/01/23 06:30:00 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2022/01/23 06:30:00 DEBUG : two: Destination found in --compare-dest, skipping 2022/01/23 06:30:00 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1 path dst: Waiting for checks to finish 2022/01/23 06:30:01 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/23 06:30:01 DEBUG : one: Destination found in --compare-dest, skipping 2022/01/23 06:30:01 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1 path dst: Waiting for transfers to finish 2022/01/23 06:30:01 DEBUG : Waiting for deletions to finish 2022/01/23 06:30:01 INFO : There was nothing to transfer 2022/01/23 06:30:01 DEBUG : two: Sizes differ (src 5 vs dst 3) 2022/01/23 06:30:01 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1 path dst: Waiting for checks to finish 2022/01/23 06:30:01 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/23 06:30:01 DEBUG : one: Destination found in --compare-dest, skipping 2022/01/23 06:30:01 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1 path dst: Waiting for transfers to finish 2022/01/23 06:30:03 DEBUG : two: sha1 = a4be8877ad88f3109a6411985a1f359e10e2d930 OK 2022/01/23 06:30:03 INFO : two: Copied (new) 2022/01/23 06:30:03 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCompareDest (16.41s) === RUN TestSyncMultipleCompareDest run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:30:11 DEBUG : Creating backend with remote "TestB2:rclone-test-gugohed8fiwisur3dexixow1/dest" 2022/01/23 06:30:13 DEBUG : Couldn't decode error response: EOF 2022/01/23 06:30:13 DEBUG : Creating backend with remote "TestB2:rclone-test-gugohed8fiwisur3dexixow1/pre-dest1" 2022/01/23 06:30:13 DEBUG : Couldn't decode error response: EOF 2022/01/23 06:30:13 DEBUG : Creating backend with remote "TestB2:rclone-test-gugohed8fiwisur3dexixow1/pre-dest2" 2022/01/23 06:30:13 DEBUG : Couldn't decode error response: EOF 2022/01/23 06:30:14 DEBUG : 1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:30:14 DEBUG : 1: Destination found in --compare-dest, skipping 2022/01/23 06:30:14 DEBUG : Couldn't decode error response: EOF 2022/01/23 06:30:14 DEBUG : 2: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:30:14 DEBUG : 2: Destination found in --compare-dest, skipping 2022/01/23 06:30:14 DEBUG : Couldn't decode error response: EOF 2022/01/23 06:30:15 DEBUG : Couldn't decode error response: EOF 2022/01/23 06:30:15 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1 path dest: Waiting for checks to finish 2022/01/23 06:30:15 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1 path dest: Waiting for transfers to finish 2022/01/23 06:30:16 DEBUG : 3: sha1 = 77de68daecd823babbb58edb1c8e14d7106e83bb OK 2022/01/23 06:30:16 INFO : 3: Copied (new) 2022/01/23 06:30:16 DEBUG : Waiting for deletions to finish --- PASS: TestSyncMultipleCompareDest (10.64s) === RUN TestSyncCopyDest run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:30:18 DEBUG : Creating backend with remote "TestB2:rclone-test-gugohed8fiwisur3dexixow1/dst" 2022/01/23 06:30:19 DEBUG : Couldn't decode error response: EOF 2022/01/23 06:30:19 DEBUG : Creating backend with remote "TestB2:rclone-test-gugohed8fiwisur3dexixow1/CopyDest" 2022/01/23 06:30:20 DEBUG : Couldn't decode error response: EOF 2022/01/23 06:30:20 DEBUG : Couldn't decode error response: EOF 2022/01/23 06:30:20 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1 path dst: Waiting for checks to finish 2022/01/23 06:30:20 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1 path dst: Waiting for transfers to finish 2022/01/23 06:30:22 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/01/23 06:30:22 INFO : one: Copied (new) 2022/01/23 06:30:22 DEBUG : Waiting for deletions to finish 2022/01/23 06:30:22 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1 path dst: Waiting for checks to finish 2022/01/23 06:30:22 DEBUG : Couldn't decode error response: EOF 2022/01/23 06:30:22 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/01/23 06:30:22 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1 path dst: Waiting for transfers to finish 2022/01/23 06:30:24 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2022/01/23 06:30:24 INFO : one: Copied (replaced existing) 2022/01/23 06:30:24 DEBUG : Waiting for deletions to finish 2022/01/23 06:30:27 DEBUG : Creating backend with remote "TestB2:rclone-test-gugohed8fiwisur3dexixow1/BackupDir" 2022/01/23 06:30:27 DEBUG : Couldn't decode error response: EOF 2022/01/23 06:30:28 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1 path dst: Waiting for checks to finish 2022/01/23 06:30:28 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/23 06:30:28 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/01/23 06:30:28 DEBUG : Couldn't decode error response: EOF 2022/01/23 06:30:29 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/01/23 06:30:29 INFO : one: Copied (server-side copy) 2022/01/23 06:30:31 INFO : one: Deleted 2022/01/23 06:30:32 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2022/01/23 06:30:32 INFO : one: Copied (server-side copy) 2022/01/23 06:30:32 DEBUG : one: Destination found in --copy-dest, using server-side copy 2022/01/23 06:30:32 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1 path dst: Waiting for transfers to finish 2022/01/23 06:30:32 DEBUG : Waiting for deletions to finish 2022/01/23 06:30:34 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/23 06:30:35 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2022/01/23 06:30:35 INFO : two: Copied (server-side copy) 2022/01/23 06:30:35 DEBUG : two: Destination found in --copy-dest, using server-side copy 2022/01/23 06:30:35 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1 path dst: Waiting for checks to finish 2022/01/23 06:30:35 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/23 06:30:35 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/23 06:30:35 DEBUG : one: Unchanged skipping 2022/01/23 06:30:35 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1 path dst: Waiting for transfers to finish 2022/01/23 06:30:35 DEBUG : Waiting for deletions to finish 2022/01/23 06:30:36 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1 path dst: Waiting for checks to finish 2022/01/23 06:30:36 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/23 06:30:36 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/23 06:30:36 DEBUG : one: Unchanged skipping 2022/01/23 06:30:36 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/23 06:30:36 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/23 06:30:36 DEBUG : two: Unchanged skipping 2022/01/23 06:30:36 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1 path dst: Waiting for transfers to finish 2022/01/23 06:30:36 DEBUG : Waiting for deletions to finish 2022/01/23 06:30:36 INFO : There was nothing to transfer 2022/01/23 06:30:39 DEBUG : three: Sizes differ (src 7 vs dst 5) 2022/01/23 06:30:39 DEBUG : three: Destination not found in --copy-dest 2022/01/23 06:30:39 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1 path dst: Waiting for checks to finish 2022/01/23 06:30:39 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/23 06:30:39 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/23 06:30:39 DEBUG : one: Unchanged skipping 2022/01/23 06:30:40 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/23 06:30:40 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/23 06:30:40 DEBUG : two: Unchanged skipping 2022/01/23 06:30:40 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1 path dst: Waiting for transfers to finish 2022/01/23 06:30:41 DEBUG : three: sha1 = 370e613335f3cf2f19b2a2d35a86402f55c659f8 OK 2022/01/23 06:30:41 INFO : three: Copied (new) 2022/01/23 06:30:41 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCopyDest (27.16s) === RUN TestSyncBackupDir run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:30:49 DEBUG : Creating backend with remote "TestB2:rclone-test-gugohed8fiwisur3dexixow1/dst" 2022/01/23 06:30:50 DEBUG : Couldn't decode error response: EOF 2022/01/23 06:30:50 DEBUG : Creating backend with remote "TestB2:rclone-test-gugohed8fiwisur3dexixow1/backup" 2022/01/23 06:30:51 DEBUG : Couldn't decode error response: EOF 2022/01/23 06:30:51 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/01/23 06:30:51 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:30:51 DEBUG : two: Unchanged skipping 2022/01/23 06:30:51 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1 path dst: Waiting for checks to finish 2022/01/23 06:30:51 DEBUG : Couldn't decode error response: EOF 2022/01/23 06:30:53 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/01/23 06:30:53 INFO : one: Copied (server-side copy) 2022/01/23 06:30:53 INFO : one: Deleted 2022/01/23 06:30:53 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1 path dst: Waiting for transfers to finish 2022/01/23 06:30:55 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2022/01/23 06:30:55 INFO : one: Copied (new) 2022/01/23 06:30:55 DEBUG : Waiting for deletions to finish 2022/01/23 06:30:55 DEBUG : Couldn't decode error response: EOF 2022/01/23 06:30:55 DEBUG : three.txt: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2022/01/23 06:30:55 INFO : three.txt: Copied (server-side copy) 2022/01/23 06:30:56 INFO : three.txt: Deleted 2022/01/23 06:30:56 INFO : three.txt: Moved into backup dir 2022/01/23 06:30:57 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/01/23 06:30:57 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1 path dst: Waiting for checks to finish 2022/01/23 06:30:57 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:30:57 DEBUG : two: Unchanged skipping 2022/01/23 06:30:59 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2022/01/23 06:30:59 INFO : one: Copied (server-side copy) 2022/01/23 06:30:59 INFO : one: Deleted 2022/01/23 06:30:59 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1 path dst: Waiting for transfers to finish 2022/01/23 06:31:01 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2022/01/23 06:31:01 INFO : one: Copied (new) 2022/01/23 06:31:01 DEBUG : Waiting for deletions to finish 2022/01/23 06:31:02 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2022/01/23 06:31:02 INFO : three.txt: Copied (server-side copy) 2022/01/23 06:31:05 INFO : three.txt: Deleted 2022/01/23 06:31:05 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (24.71s) === RUN TestSyncBackupDirWithSuffix run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:31:13 DEBUG : Creating backend with remote "TestB2:rclone-test-gugohed8fiwisur3dexixow1/dst" 2022/01/23 06:31:14 DEBUG : Couldn't decode error response: EOF 2022/01/23 06:31:14 DEBUG : Creating backend with remote "TestB2:rclone-test-gugohed8fiwisur3dexixow1/backup" 2022/01/23 06:31:14 DEBUG : Couldn't decode error response: EOF 2022/01/23 06:31:15 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1 path dst: Waiting for checks to finish 2022/01/23 06:31:15 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/01/23 06:31:15 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:31:15 DEBUG : two: Unchanged skipping 2022/01/23 06:31:15 DEBUG : Couldn't decode error response: EOF 2022/01/23 06:31:17 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/01/23 06:31:17 INFO : one: Copied (server-side copy) to: one.bak 2022/01/23 06:31:17 INFO : one: Deleted 2022/01/23 06:31:17 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1 path dst: Waiting for transfers to finish 2022/01/23 06:31:18 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2022/01/23 06:31:18 INFO : one: Copied (new) 2022/01/23 06:31:18 DEBUG : Waiting for deletions to finish 2022/01/23 06:31:19 DEBUG : Couldn't decode error response: EOF 2022/01/23 06:31:20 DEBUG : three.txt: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2022/01/23 06:31:20 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/01/23 06:31:20 INFO : three.txt: Deleted 2022/01/23 06:31:20 INFO : three.txt: Moved into backup dir 2022/01/23 06:31:22 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/01/23 06:31:22 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:31:22 DEBUG : two: Unchanged skipping 2022/01/23 06:31:22 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1 path dst: Waiting for checks to finish 2022/01/23 06:31:25 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2022/01/23 06:31:25 INFO : one: Copied (server-side copy) to: one.bak 2022/01/23 06:31:25 INFO : one: Deleted 2022/01/23 06:31:25 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1 path dst: Waiting for transfers to finish 2022/01/23 06:31:26 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2022/01/23 06:31:26 INFO : one: Copied (new) 2022/01/23 06:31:26 DEBUG : Waiting for deletions to finish 2022/01/23 06:31:27 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2022/01/23 06:31:27 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/01/23 06:31:27 INFO : three.txt: Deleted 2022/01/23 06:31:27 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (22.47s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:31:36 DEBUG : Creating backend with remote "TestB2:rclone-test-gugohed8fiwisur3dexixow1/dst" 2022/01/23 06:31:38 DEBUG : Couldn't decode error response: EOF 2022/01/23 06:31:38 DEBUG : Creating backend with remote "TestB2:rclone-test-gugohed8fiwisur3dexixow1/backup" 2022/01/23 06:31:38 DEBUG : Couldn't decode error response: EOF 2022/01/23 06:31:39 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/01/23 06:31:39 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1 path dst: Waiting for checks to finish 2022/01/23 06:31:39 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:31:39 DEBUG : two: Unchanged skipping 2022/01/23 06:31:39 DEBUG : Couldn't decode error response: EOF 2022/01/23 06:31:40 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/01/23 06:31:40 INFO : one: Copied (server-side copy) to: one-2019-01-01 2022/01/23 06:31:40 INFO : one: Deleted 2022/01/23 06:31:40 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1 path dst: Waiting for transfers to finish 2022/01/23 06:31:41 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2022/01/23 06:31:41 INFO : one: Copied (new) 2022/01/23 06:31:41 DEBUG : Waiting for deletions to finish 2022/01/23 06:31:42 DEBUG : Couldn't decode error response: EOF 2022/01/23 06:31:44 DEBUG : three.txt: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2022/01/23 06:31:44 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2022/01/23 06:31:44 INFO : three.txt: Deleted 2022/01/23 06:31:44 INFO : three.txt: Moved into backup dir 2022/01/23 06:31:46 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/01/23 06:31:46 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:31:46 DEBUG : two: Unchanged skipping 2022/01/23 06:31:46 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1 path dst: Waiting for checks to finish 2022/01/23 06:31:47 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2022/01/23 06:31:47 INFO : one: Copied (server-side copy) to: one-2019-01-01 2022/01/23 06:31:48 INFO : one: Deleted 2022/01/23 06:31:48 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1 path dst: Waiting for transfers to finish 2022/01/23 06:31:49 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2022/01/23 06:31:49 INFO : one: Copied (new) 2022/01/23 06:31:49 DEBUG : Waiting for deletions to finish 2022/01/23 06:31:49 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2022/01/23 06:31:49 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2022/01/23 06:31:50 INFO : three.txt: Deleted 2022/01/23 06:31:50 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (19.98s) === RUN TestSyncBackupDirSuffixOnly run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:31:56 DEBUG : Creating backend with remote "TestB2:rclone-test-gugohed8fiwisur3dexixow1/dst" 2022/01/23 06:31:57 DEBUG : Couldn't decode error response: EOF 2022/01/23 06:31:57 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/01/23 06:31:57 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1 path dst: Waiting for checks to finish 2022/01/23 06:31:57 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:31:57 DEBUG : two: Unchanged skipping 2022/01/23 06:31:58 DEBUG : Couldn't decode error response: EOF 2022/01/23 06:32:00 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/01/23 06:32:00 INFO : one: Copied (server-side copy) to: one.bak 2022/01/23 06:32:00 INFO : one: Deleted 2022/01/23 06:32:00 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1 path dst: Waiting for transfers to finish 2022/01/23 06:32:02 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2022/01/23 06:32:02 INFO : one: Copied (new) 2022/01/23 06:32:02 DEBUG : Waiting for deletions to finish 2022/01/23 06:32:02 DEBUG : Couldn't decode error response: EOF 2022/01/23 06:32:03 DEBUG : three.txt: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2022/01/23 06:32:03 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/01/23 06:32:03 INFO : three.txt: Deleted 2022/01/23 06:32:03 INFO : three.txt: Moved into backup dir 2022/01/23 06:32:05 DEBUG : one.bak: Excluded from sync (and deletion) 2022/01/23 06:32:05 DEBUG : three.txt.bak: Excluded from sync (and deletion) 2022/01/23 06:32:05 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/01/23 06:32:05 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:32:05 DEBUG : two: Unchanged skipping 2022/01/23 06:32:05 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1 path dst: Waiting for checks to finish 2022/01/23 06:32:06 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2022/01/23 06:32:06 INFO : one: Copied (server-side copy) to: one.bak 2022/01/23 06:32:06 INFO : one: Deleted 2022/01/23 06:32:06 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1 path dst: Waiting for transfers to finish 2022/01/23 06:32:07 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2022/01/23 06:32:07 INFO : one: Copied (new) 2022/01/23 06:32:07 DEBUG : Waiting for deletions to finish 2022/01/23 06:32:07 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2022/01/23 06:32:07 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/01/23 06:32:08 INFO : three.txt: Deleted 2022/01/23 06:32:08 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirSuffixOnly (36.42s) === RUN TestSyncSuffix run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:32:33 DEBUG : Creating backend with remote "TestB2:rclone-test-gugohed8fiwisur3dexixow1/dst" 2022/01/23 06:32:34 DEBUG : Couldn't decode error response: EOF 2022/01/23 06:32:34 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/01/23 06:32:35 DEBUG : Couldn't decode error response: EOF 2022/01/23 06:32:36 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/01/23 06:32:36 INFO : one: Copied (server-side copy) to: one.bak 2022/01/23 06:32:36 INFO : one: Deleted 2022/01/23 06:32:38 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2022/01/23 06:32:38 INFO : one: Copied (new) 2022/01/23 06:32:38 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:32:38 DEBUG : two: Unchanged skipping 2022/01/23 06:32:38 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2022/01/23 06:32:38 DEBUG : Couldn't decode error response: EOF 2022/01/23 06:32:39 DEBUG : three.txt: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2022/01/23 06:32:39 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/01/23 06:32:39 INFO : three.txt: Deleted 2022/01/23 06:32:42 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2022/01/23 06:32:42 INFO : three.txt: Copied (new) 2022/01/23 06:32:42 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/01/23 06:32:43 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2022/01/23 06:32:43 INFO : one: Copied (server-side copy) to: one.bak 2022/01/23 06:32:43 INFO : one: Deleted 2022/01/23 06:32:44 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2022/01/23 06:32:44 INFO : one: Copied (new) 2022/01/23 06:32:44 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:32:44 DEBUG : two: Unchanged skipping 2022/01/23 06:32:44 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2022/01/23 06:32:45 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2022/01/23 06:32:45 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/01/23 06:32:45 INFO : three.txt: Deleted 2022/01/23 06:32:46 DEBUG : three.txt: sha1 = 476e1707cbd7f0497a7dc4d44f0b07b8566ffc30 OK 2022/01/23 06:32:46 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (20.94s) === RUN TestSyncSuffixKeepExtension run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:32:53 DEBUG : Creating backend with remote "TestB2:rclone-test-gugohed8fiwisur3dexixow1/dst" 2022/01/23 06:32:55 DEBUG : Couldn't decode error response: EOF 2022/01/23 06:32:55 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/01/23 06:32:55 DEBUG : Couldn't decode error response: EOF 2022/01/23 06:32:57 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/01/23 06:32:57 INFO : one: Copied (server-side copy) to: one-2019-01-01 2022/01/23 06:32:57 INFO : one: Deleted 2022/01/23 06:32:59 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2022/01/23 06:32:59 INFO : one: Copied (new) 2022/01/23 06:32:59 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:32:59 DEBUG : two: Unchanged skipping 2022/01/23 06:32:59 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2022/01/23 06:32:59 DEBUG : Couldn't decode error response: EOF 2022/01/23 06:33:00 DEBUG : three.txt: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2022/01/23 06:33:00 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2022/01/23 06:33:01 INFO : three.txt: Deleted 2022/01/23 06:33:02 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2022/01/23 06:33:02 INFO : three.txt: Copied (new) 2022/01/23 06:33:02 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/01/23 06:33:03 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2022/01/23 06:33:03 INFO : one: Copied (server-side copy) to: one-2019-01-01 2022/01/23 06:33:03 INFO : one: Deleted 2022/01/23 06:33:05 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2022/01/23 06:33:05 INFO : one: Copied (new) 2022/01/23 06:33:05 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:33:05 DEBUG : two: Unchanged skipping 2022/01/23 06:33:06 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2022/01/23 06:33:07 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2022/01/23 06:33:07 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2022/01/23 06:33:11 INFO : three.txt: Deleted 2022/01/23 06:33:12 DEBUG : three.txt: sha1 = 476e1707cbd7f0497a7dc4d44f0b07b8566ffc30 OK 2022/01/23 06:33:12 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (25.02s) === RUN TestSyncUTFNorm run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:33:16 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:33:16 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2022/01/23 06:33:16 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish 2022/01/23 06:33:17 DEBUG : Testêé: sha1 = a54d88e06612d820bc3be72877c74f257b561b19 OK 2022/01/23 06:33:17 INFO : Testêé: Copied (replaced existing) to: Testêé 2022/01/23 06:33:17 DEBUG : Waiting for deletions to finish --- PASS: TestSyncUTFNorm (5.62s) === RUN TestSyncImmutable run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:33:21 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:33:21 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish 2022/01/23 06:33:21 DEBUG : existing: Clearing upload URL because of error: Post "https://pod-000-1109-06.backblaze.com/b2api/v1/b2_upload_file/2fa28da390cd8b5b7fe80e1b/c001_v0001109_t0000": EOF 2022/01/23 06:33:21 DEBUG : pacer: low level retry 1/1 (error Post "https://pod-000-1109-06.backblaze.com/b2api/v1/b2_upload_file/2fa28da390cd8b5b7fe80e1b/c001_v0001109_t0000": EOF) 2022/01/23 06:33:21 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2022/01/23 06:33:21 DEBUG : existing: Received error: Post "https://pod-000-1109-06.backblaze.com/b2api/v1/b2_upload_file/2fa28da390cd8b5b7fe80e1b/c001_v0001109_t0000": EOF - low level retry 1/10 2022/01/23 06:33:22 DEBUG : pacer: Reducing sleep to 10ms 2022/01/23 06:33:22 DEBUG : existing: sha1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2022/01/23 06:33:22 INFO : existing: Copied (new) 2022/01/23 06:33:22 DEBUG : Waiting for deletions to finish 2022/01/23 06:33:23 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:33:23 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2022/01/23 06:33:23 ERROR : existing: Source and destination exist but do not match: immutable file modified 2022/01/23 06:33:23 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish 2022/01/23 06:33:23 ERROR : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: not deleting files as there were IO errors 2022/01/23 06:33:23 ERROR : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: not deleting directories as there were IO errors --- PASS: TestSyncImmutable (2.83s) === RUN TestSyncIgnoreCase run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:33:24 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:33:24 DEBUG : existing: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:33:24 DEBUG : existing: Unchanged skipping 2022/01/23 06:33:24 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish 2022/01/23 06:33:24 DEBUG : Waiting for deletions to finish 2022/01/23 06:33:24 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreCase (2.69s) === RUN TestMaxTransfer === RUN TestMaxTransfer/Hard run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" sync_test.go:1989: This test only runs on local === RUN TestMaxTransfer/Soft run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" sync_test.go:1989: This test only runs on local === RUN TestMaxTransfer/Cautious run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" sync_test.go:1989: This test only runs on local --- PASS: TestMaxTransfer (1.05s) --- SKIP: TestMaxTransfer/Hard (0.35s) --- SKIP: TestMaxTransfer/Soft (0.35s) --- SKIP: TestMaxTransfer/Cautious (0.35s) === RUN TestSyncConcurrentDelete run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:34:00 DEBUG : both0: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:34:00 DEBUG : both0: Unchanged skipping 2022/01/23 06:34:00 DEBUG : both1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:34:00 DEBUG : both1: Unchanged skipping 2022/01/23 06:34:00 DEBUG : both10: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:34:00 DEBUG : both10: Unchanged skipping 2022/01/23 06:34:00 DEBUG : both11: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:34:00 DEBUG : both11: Unchanged skipping 2022/01/23 06:34:00 DEBUG : both12: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:34:00 DEBUG : both12: Unchanged skipping 2022/01/23 06:34:00 DEBUG : both13: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:34:00 DEBUG : both13: Unchanged skipping 2022/01/23 06:34:00 DEBUG : both14: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:34:00 DEBUG : both14: Unchanged skipping 2022/01/23 06:34:00 DEBUG : both15: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:34:00 DEBUG : both15: Unchanged skipping 2022/01/23 06:34:00 DEBUG : both16: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:34:00 DEBUG : both16: Unchanged skipping 2022/01/23 06:34:00 DEBUG : both17: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:34:00 DEBUG : both17: Unchanged skipping 2022/01/23 06:34:00 DEBUG : both18: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:34:00 DEBUG : both18: Unchanged skipping 2022/01/23 06:34:00 DEBUG : both19: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:34:00 DEBUG : both19: Unchanged skipping 2022/01/23 06:34:00 DEBUG : both2: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:34:00 DEBUG : both2: Unchanged skipping 2022/01/23 06:34:00 DEBUG : both3: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:34:00 DEBUG : both3: Unchanged skipping 2022/01/23 06:34:00 DEBUG : both4: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:34:00 DEBUG : both4: Unchanged skipping 2022/01/23 06:34:00 DEBUG : both5: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:34:00 DEBUG : both5: Unchanged skipping 2022/01/23 06:34:00 DEBUG : both6: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:34:00 DEBUG : both6: Unchanged skipping 2022/01/23 06:34:00 DEBUG : both7: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:34:00 DEBUG : both7: Unchanged skipping 2022/01/23 06:34:00 DEBUG : both8: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:34:00 DEBUG : both8: Unchanged skipping 2022/01/23 06:34:00 DEBUG : both9: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:34:00 DEBUG : both9: Unchanged skipping 2022/01/23 06:34:00 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:34:00 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish 2022/01/23 06:34:00 DEBUG : Waiting for deletions to finish 2022/01/23 06:34:01 INFO : only1: Deleted 2022/01/23 06:34:01 INFO : only5: Deleted 2022/01/23 06:34:01 INFO : only17: Deleted 2022/01/23 06:34:01 INFO : only2: Deleted 2022/01/23 06:34:01 INFO : only4: Deleted 2022/01/23 06:34:02 INFO : only16: Deleted 2022/01/23 06:34:02 INFO : only12: Deleted 2022/01/23 06:34:02 INFO : only9: Deleted 2022/01/23 06:34:02 INFO : only10: Deleted 2022/01/23 06:34:02 INFO : only6: Deleted 2022/01/23 06:34:02 INFO : only0: Deleted 2022/01/23 06:34:03 INFO : only19: Deleted 2022/01/23 06:34:03 INFO : only14: Deleted 2022/01/23 06:34:03 INFO : only8: Deleted 2022/01/23 06:34:03 INFO : only7: Deleted 2022/01/23 06:34:03 INFO : only13: Deleted 2022/01/23 06:34:03 INFO : only18: Deleted 2022/01/23 06:34:04 INFO : only3: Deleted 2022/01/23 06:34:05 INFO : only11: Deleted 2022/01/23 06:34:05 INFO : only15: Deleted 2022/01/23 06:34:05 INFO : There was nothing to transfer --- PASS: TestSyncConcurrentDelete (62.58s) === RUN TestSyncConcurrentTruncate run.go:181: Remote "B2 bucket rclone-test-gugohed8fiwisur3dexixow1", Local "Local file system at /tmp/rclone3379925398", Modify Window "1ms" 2022/01/23 06:35:08 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for checks to finish 2022/01/23 06:35:08 DEBUG : both10: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:35:08 DEBUG : both10: Unchanged skipping 2022/01/23 06:35:08 DEBUG : both12: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:35:08 DEBUG : both12: Unchanged skipping 2022/01/23 06:35:08 DEBUG : both13: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:35:08 DEBUG : both13: Unchanged skipping 2022/01/23 06:35:08 DEBUG : both14: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:35:08 DEBUG : both14: Unchanged skipping 2022/01/23 06:35:08 DEBUG : both15: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:35:08 DEBUG : both15: Unchanged skipping 2022/01/23 06:35:08 DEBUG : both16: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:35:08 DEBUG : both16: Unchanged skipping 2022/01/23 06:35:08 DEBUG : both17: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:35:08 DEBUG : both17: Unchanged skipping 2022/01/23 06:35:08 DEBUG : both18: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:35:08 DEBUG : both18: Unchanged skipping 2022/01/23 06:35:08 DEBUG : both19: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:35:08 DEBUG : both19: Unchanged skipping 2022/01/23 06:35:08 DEBUG : both2: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:35:08 DEBUG : both2: Unchanged skipping 2022/01/23 06:35:08 DEBUG : both3: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:35:08 DEBUG : both3: Unchanged skipping 2022/01/23 06:35:08 DEBUG : both4: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:35:08 DEBUG : both4: Unchanged skipping 2022/01/23 06:35:08 DEBUG : both5: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:35:08 DEBUG : both5: Unchanged skipping 2022/01/23 06:35:08 DEBUG : both6: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:35:08 DEBUG : both6: Unchanged skipping 2022/01/23 06:35:08 DEBUG : both7: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:35:08 DEBUG : both7: Unchanged skipping 2022/01/23 06:35:08 DEBUG : both8: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:35:08 DEBUG : both8: Unchanged skipping 2022/01/23 06:35:08 DEBUG : both9: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:35:08 DEBUG : both9: Unchanged skipping 2022/01/23 06:35:08 DEBUG : only0: Sizes differ (src 0 vs dst 6) 2022/01/23 06:35:08 DEBUG : only1: Sizes differ (src 0 vs dst 6) 2022/01/23 06:35:08 DEBUG : both0: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:35:08 DEBUG : both0: Unchanged skipping 2022/01/23 06:35:08 DEBUG : only11: Sizes differ (src 0 vs dst 6) 2022/01/23 06:35:08 DEBUG : only12: Sizes differ (src 0 vs dst 6) 2022/01/23 06:35:08 DEBUG : only13: Sizes differ (src 0 vs dst 6) 2022/01/23 06:35:08 DEBUG : only14: Sizes differ (src 0 vs dst 6) 2022/01/23 06:35:08 DEBUG : only15: Sizes differ (src 0 vs dst 6) 2022/01/23 06:35:08 DEBUG : only16: Sizes differ (src 0 vs dst 6) 2022/01/23 06:35:08 DEBUG : only17: Sizes differ (src 0 vs dst 6) 2022/01/23 06:35:08 DEBUG : only18: Sizes differ (src 0 vs dst 6) 2022/01/23 06:35:08 DEBUG : only19: Sizes differ (src 0 vs dst 6) 2022/01/23 06:35:08 DEBUG : only2: Sizes differ (src 0 vs dst 6) 2022/01/23 06:35:08 DEBUG : only3: Sizes differ (src 0 vs dst 6) 2022/01/23 06:35:08 DEBUG : only4: Sizes differ (src 0 vs dst 6) 2022/01/23 06:35:08 DEBUG : only5: Sizes differ (src 0 vs dst 6) 2022/01/23 06:35:08 DEBUG : only6: Sizes differ (src 0 vs dst 6) 2022/01/23 06:35:08 DEBUG : only7: Sizes differ (src 0 vs dst 6) 2022/01/23 06:35:08 DEBUG : only8: Sizes differ (src 0 vs dst 6) 2022/01/23 06:35:08 DEBUG : only9: Sizes differ (src 0 vs dst 6) 2022/01/23 06:35:08 DEBUG : both1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:35:08 DEBUG : both1: Unchanged skipping 2022/01/23 06:35:08 DEBUG : both11: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/23 06:35:08 DEBUG : both11: Unchanged skipping 2022/01/23 06:35:08 DEBUG : only10: Sizes differ (src 0 vs dst 6) 2022/01/23 06:35:08 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Waiting for transfers to finish 2022/01/23 06:35:10 DEBUG : only0: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/01/23 06:35:10 INFO : only0: Copied (replaced existing) 2022/01/23 06:35:10 DEBUG : only11: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/01/23 06:35:10 INFO : only11: Copied (replaced existing) 2022/01/23 06:35:10 DEBUG : only1: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/01/23 06:35:10 INFO : only1: Copied (replaced existing) 2022/01/23 06:35:10 DEBUG : only14: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/01/23 06:35:10 INFO : only14: Copied (replaced existing) 2022/01/23 06:35:10 DEBUG : only13: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/01/23 06:35:10 INFO : only13: Copied (replaced existing) 2022/01/23 06:35:10 DEBUG : only15: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/01/23 06:35:10 INFO : only15: Copied (replaced existing) 2022/01/23 06:35:11 DEBUG : only16: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/01/23 06:35:11 INFO : only16: Copied (replaced existing) 2022/01/23 06:35:11 DEBUG : only18: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/01/23 06:35:11 INFO : only18: Copied (replaced existing) 2022/01/23 06:35:11 DEBUG : only2: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/01/23 06:35:11 INFO : only2: Copied (replaced existing) 2022/01/23 06:35:11 DEBUG : only19: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/01/23 06:35:11 INFO : only19: Copied (replaced existing) 2022/01/23 06:35:11 DEBUG : only3: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/01/23 06:35:11 INFO : only3: Copied (replaced existing) 2022/01/23 06:35:11 DEBUG : only12: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/01/23 06:35:11 INFO : only12: Copied (replaced existing) 2022/01/23 06:35:12 DEBUG : only5: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/01/23 06:35:12 INFO : only5: Copied (replaced existing) 2022/01/23 06:35:12 DEBUG : only17: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/01/23 06:35:12 INFO : only17: Copied (replaced existing) 2022/01/23 06:35:12 DEBUG : only4: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/01/23 06:35:12 INFO : only4: Copied (replaced existing) 2022/01/23 06:35:12 DEBUG : only9: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/01/23 06:35:12 INFO : only9: Copied (replaced existing) 2022/01/23 06:35:12 DEBUG : only7: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/01/23 06:35:12 INFO : only7: Copied (replaced existing) 2022/01/23 06:35:12 DEBUG : only8: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/01/23 06:35:12 INFO : only8: Copied (replaced existing) 2022/01/23 06:35:13 DEBUG : only6: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/01/23 06:35:13 INFO : only6: Copied (replaced existing) 2022/01/23 06:35:13 DEBUG : only10: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/01/23 06:35:13 INFO : only10: Copied (replaced existing) 2022/01/23 06:35:13 DEBUG : Waiting for deletions to finish --- PASS: TestSyncConcurrentTruncate (89.79s) PASS 2022/01/23 06:36:00 DEBUG : B2 bucket rclone-test-gugohed8fiwisur3dexixow1: Purge remote 2022/01/23 06:36:00 DEBUG : BackupDir/one: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f118a0a316cf7c016_d20220123_m063042_c001_v0001154_t0006") 2022/01/23 06:36:00 DEBUG : BackupDir/one: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1106fa00a9a786c0_d20220123_m063029_c001_v0001105_t0041") 2022/01/23 06:36:00 DEBUG : CompareDest/one: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f110275f0d845438a_d20220123_m063003_c001_v0001032_t0001") 2022/01/23 06:36:00 DEBUG : CompareDest/one: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f106d5ac11b29a91e_d20220123_m062957_c001_v0001109_t0048") 2022/01/23 06:36:00 DEBUG : CompareDest/two: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1177cf6c32656d9a_d20220123_m063005_c001_v0001044_t0054") 2022/01/23 06:36:00 DEBUG : CompareDest/two: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a205_d20220123_m062958_c001_v0001098_t0007") 2022/01/23 06:36:00 DEBUG : CopyDest/one: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1086658ca3911b64_d20220123_m063042_c001_v0001150_t0022") 2022/01/23 06:36:00 DEBUG : CopyDest/one: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a2c6_d20220123_m063026_c001_v0001098_t0057") 2022/01/23 06:36:00 DEBUG : CopyDest/three: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f11673ebd3cb2699f_d20220123_m063042_c001_v0001090_t0003") 2022/01/23 06:36:00 DEBUG : CopyDest/three: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a2fd_d20220123_m063037_c001_v0001098_t0054") 2022/01/23 06:36:01 DEBUG : CopyDest/two: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f106c1e5cea7d190e_d20220123_m063043_c001_v0001161_t0048") 2022/01/23 06:36:01 DEBUG : CopyDest/two: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f106d5ac11b29aa59_d20220123_m063032_c001_v0001109_t0034") 2022/01/23 06:36:01 DEBUG : EXISTING: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f105731b97ce1f7a5_d20220123_m063325_c001_v0001038_t0021") 2022/01/23 06:36:01 DEBUG : EXISTING: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a6ca_d20220123_m063324_c001_v0001098_t0051") 2022/01/23 06:36:01 DEBUG : Testêé: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f100efd3b50d25553_d20220123_m063318_c001_v0001008_t0020") 2022/01/23 06:36:01 DEBUG : Testêé: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a69d_d20220123_m063317_c001_v0001098_t0013") 2022/01/23 06:36:01 DEBUG : Testêé: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f106d5ac11b29b034_d20220123_m063316_c001_v0001109_t0022") 2022/01/23 06:36:01 DEBUG : a/potato2: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f117b59a55c123a16_d20220123_m062750_c001_v0001043_t0018") 2022/01/23 06:36:01 DEBUG : a/potato2: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108ed106e78f0562_d20220123_m062749_c001_v0001040_t0002") 2022/01/23 06:36:01 DEBUG : a/potato2: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f117c39c731f0a338_d20220123_m062736_c001_v0001109_t0051") 2022/01/23 06:36:01 DEBUG : a/potato2: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108ed106e78f0541_d20220123_m062735_c001_v0001040_t0001") 2022/01/23 06:36:01 DEBUG : a/potato2: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1021918c59ad531e_d20220123_m062701_c001_v0001137_t0035") 2022/01/23 06:36:01 DEBUG : a/potato2: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108ed106e78f04c6_d20220123_m062652_c001_v0001040_t0015") 2022/01/23 06:36:01 DEBUG : b/potato: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1008028a390bf827_d20220123_m062750_c001_v0001161_t0017") 2022/01/23 06:36:02 DEBUG : b/potato: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108ed106e78f054a_d20220123_m062738_c001_v0001040_t0039") 2022/01/23 06:36:02 DEBUG : b/potato: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f119c6fea564d2ad3_d20220123_m062735_c001_v0001041_t0024") 2022/01/23 06:36:02 DEBUG : b/potato: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108ed106e78f0538_d20220123_m062733_c001_v0001040_t0032") 2022/01/23 06:36:02 DEBUG : b/potato: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f106598f964480743_d20220123_m062700_c001_v0001097_t0020") 2022/01/23 06:36:02 DEBUG : b/potato: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108ed106e78f04bc_d20220123_m062650_c001_v0001040_t0034") 2022/01/23 06:36:02 DEBUG : backup/one: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1186d22bf833ff8b_d20220123_m063105_c001_v0001161_t0023") 2022/01/23 06:36:02 DEBUG : backup/one: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1069f8cf098036b9_d20220123_m063058_c001_v0001120_t0023") 2022/01/23 06:36:02 DEBUG : backup/one: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1047be00b574d2a5_d20220123_m063052_c001_v0001036_t0039") 2022/01/23 06:36:02 DEBUG : backup/one-2019-01-01: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f11870d5873b63971_d20220123_m063150_c001_v0001120_t0015") 2022/01/23 06:36:02 DEBUG : backup/one-2019-01-01: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1051ae497671a335_d20220123_m063146_c001_v0001018_t0043") 2022/01/23 06:36:02 DEBUG : backup/one-2019-01-01: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f106f7556b6682dbb_d20220123_m063140_c001_v0001165_t0044") 2022/01/23 06:36:02 DEBUG : backup/one.bak: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f110cb4734d6ee652_d20220123_m063127_c001_v0001042_t0038") 2022/01/23 06:36:02 DEBUG : backup/one.bak: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f11580e63d82547a7_d20220123_m063122_c001_v0001038_t0025") 2022/01/23 06:36:02 DEBUG : backup/one.bak: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f117a234a43a2a30c_d20220123_m063116_c001_v0001030_t0018") 2022/01/23 06:36:02 DEBUG : backup/three-2019-01-01.txt: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108f64c830cafd1a_d20220123_m063151_c001_v0001093_t0016") 2022/01/23 06:36:02 DEBUG : backup/three-2019-01-01.txt: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f114ab4dfb9f42a5a_d20220123_m063149_c001_v0001165_t0012") 2022/01/23 06:36:02 DEBUG : backup/three-2019-01-01.txt: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1049faa24df03839_d20220123_m063142_c001_v0001029_t0015") 2022/01/23 06:36:02 DEBUG : backup/three.txt: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1079fb80c005ddf6_d20220123_m063108_c001_v0001146_t0042") 2022/01/23 06:36:02 DEBUG : backup/three.txt: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1162dfc80e113ab7_d20220123_m063101_c001_v0001017_t0002") 2022/01/23 06:36:02 DEBUG : backup/three.txt: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f110785c89ad81f2d_d20220123_m063055_c001_v0001009_t0036") 2022/01/23 06:36:02 DEBUG : backup/three.txt.bak: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f109327c8a5a7b28c_d20220123_m063132_c001_v0001093_t0039") 2022/01/23 06:36:03 DEBUG : backup/three.txt.bak: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f109ec1d5daf9f7f4_d20220123_m063126_c001_v0001150_t0004") 2022/01/23 06:36:03 DEBUG : backup/three.txt.bak: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1076723393a880e5_d20220123_m063119_c001_v0001098_t0014") 2022/01/23 06:36:03 DEBUG : both0: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f112c23d27fed2ce2_d20220123_m063513_c001_v0001041_t0029") 2022/01/23 06:36:03 DEBUG : both0: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a8ed_d20220123_m063431_c001_v0001098_t0017") 2022/01/23 06:36:03 DEBUG : both0: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1154f46a8caaf8ed_d20220123_m063406_c001_v0001037_t0000") 2022/01/23 06:36:03 DEBUG : both0: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a6e7_d20220123_m063327_c001_v0001098_t0057") 2022/01/23 06:36:03 DEBUG : both1: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f119f78443ee1be77_d20220123_m063514_c001_v0001097_t0047") 2022/01/23 06:36:03 DEBUG : both1: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a90b_d20220123_m063435_c001_v0001098_t0024") 2022/01/23 06:36:03 DEBUG : both1: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f100950a9a7119103_d20220123_m063406_c001_v0001105_t0017") 2022/01/23 06:36:03 DEBUG : both1: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a6f2_d20220123_m063329_c001_v0001098_t0038") 2022/01/23 06:36:03 DEBUG : both10: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f115ea2657b5c47c8_d20220123_m063515_c001_v0001144_t0050") 2022/01/23 06:36:03 DEBUG : both10: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a97e_d20220123_m063446_c001_v0001098_t0030") 2022/01/23 06:36:03 DEBUG : both10: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f11380cd11403f7b8_d20220123_m063408_c001_v0001146_t0038") 2022/01/23 06:36:03 DEBUG : both10: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a794_d20220123_m063347_c001_v0001098_t0040") 2022/01/23 06:36:03 DEBUG : both11: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f103315d198ee0da8_d20220123_m063516_c001_v0001144_t0053") 2022/01/23 06:36:03 DEBUG : both11: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a983_d20220123_m063447_c001_v0001098_t0040") 2022/01/23 06:36:03 DEBUG : both11: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f100db8441fee16b8_d20220123_m063408_c001_v0001098_t0023") 2022/01/23 06:36:03 DEBUG : both11: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a79d_d20220123_m063348_c001_v0001098_t0028") 2022/01/23 06:36:03 DEBUG : both12: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1138344ab6624818_d20220123_m063516_c001_v0001022_t0034") 2022/01/23 06:36:03 DEBUG : both12: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a993_d20220123_m063449_c001_v0001098_t0040") 2022/01/23 06:36:04 DEBUG : both12: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1176662fc585924d_d20220123_m063408_c001_v0001153_t0003") 2022/01/23 06:36:04 DEBUG : both12: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a7b1_d20220123_m063350_c001_v0001098_t0009") 2022/01/23 06:36:04 DEBUG : both13: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f10648f466f4c53ea_d20220123_m063517_c001_v0001105_t0032") 2022/01/23 06:36:04 DEBUG : both13: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a9b2_d20220123_m063453_c001_v0001098_t0018") 2022/01/23 06:36:04 DEBUG : both13: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1190f6584154c3e1_d20220123_m063409_c001_v0001098_t0008") 2022/01/23 06:36:04 DEBUG : both13: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a7c7_d20220123_m063353_c001_v0001098_t0011") 2022/01/23 06:36:04 DEBUG : both14: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f110a333a2a73c3f9_d20220123_m063517_c001_v0001097_t0039") 2022/01/23 06:36:04 DEBUG : both14: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a9bb_d20220123_m063453_c001_v0001098_t0021") 2022/01/23 06:36:04 DEBUG : both14: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f119b852c544302c1_d20220123_m063409_c001_v0001109_t0004") 2022/01/23 06:36:04 DEBUG : both14: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a7cf_d20220123_m063353_c001_v0001098_t0025") 2022/01/23 06:36:04 DEBUG : both15: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1175147f661e9854_d20220123_m063518_c001_v0001032_t0008") 2022/01/23 06:36:04 DEBUG : both15: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a9c3_d20220123_m063454_c001_v0001098_t0054") 2022/01/23 06:36:04 DEBUG : both15: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f105b11a420088a4a_d20220123_m063410_c001_v0001043_t0033") 2022/01/23 06:36:04 DEBUG : both15: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a7d9_d20220123_m063355_c001_v0001098_t0006") 2022/01/23 06:36:04 DEBUG : both16: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1154234bd42386d2_d20220123_m063530_c001_v0001043_t0047") 2022/01/23 06:36:04 DEBUG : both16: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a9cc_d20220123_m063455_c001_v0001098_t0000") 2022/01/23 06:36:04 DEBUG : both16: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f10393b162ab43825_d20220123_m063413_c001_v0001150_t0005") 2022/01/23 06:36:04 DEBUG : both16: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a7df_d20220123_m063355_c001_v0001098_t0008") 2022/01/23 06:36:04 DEBUG : both17: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1076caf86667395a_d20220123_m063531_c001_v0001091_t0047") 2022/01/23 06:36:04 DEBUG : both17: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a9d2_d20220123_m063456_c001_v0001098_t0002") 2022/01/23 06:36:05 DEBUG : both17: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f111400204d027a31_d20220123_m063413_c001_v0001144_t0024") 2022/01/23 06:36:05 DEBUG : both17: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a7e6_d20220123_m063356_c001_v0001098_t0049") 2022/01/23 06:36:05 DEBUG : both18: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113ee9f44de23bd4_d20220123_m063532_c001_v0001030_t0032") 2022/01/23 06:36:05 DEBUG : both18: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a9e4_d20220123_m063459_c001_v0001098_t0046") 2022/01/23 06:36:05 DEBUG : both18: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f114ff3762c36a838_d20220123_m063414_c001_v0001023_t0031") 2022/01/23 06:36:05 DEBUG : both18: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a7f2_d20220123_m063357_c001_v0001098_t0012") 2022/01/23 06:36:05 DEBUG : both19: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1085207e2401966e_d20220123_m063533_c001_v0001157_t0039") 2022/01/23 06:36:05 DEBUG : both19: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a9fe_d20220123_m063503_c001_v0001098_t0037") 2022/01/23 06:36:05 DEBUG : both19: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1075ef6b4b87143a_d20220123_m063415_c001_v0001144_t0056") 2022/01/23 06:36:05 DEBUG : both19: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a7ff_d20220123_m063358_c001_v0001098_t0051") 2022/01/23 06:36:05 DEBUG : both2: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f118482becd089bd6_d20220123_m063535_c001_v0001041_t0059") 2022/01/23 06:36:05 DEBUG : both2: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a918_d20220123_m063436_c001_v0001098_t0041") 2022/01/23 06:36:05 DEBUG : both2: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f100950a9a71191ae_d20220123_m063415_c001_v0001105_t0042") 2022/01/23 06:36:05 DEBUG : both2: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a6fe_d20220123_m063330_c001_v0001098_t0044") 2022/01/23 06:36:05 DEBUG : both3: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1099dc1fb7740eaf_d20220123_m063537_c001_v0001146_t0041") 2022/01/23 06:36:05 DEBUG : both3: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a922_d20220123_m063438_c001_v0001098_t0017") 2022/01/23 06:36:05 DEBUG : both3: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f10708fb72aca716a_d20220123_m063417_c001_v0001019_t0027") 2022/01/23 06:36:05 DEBUG : both3: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a727_d20220123_m063336_c001_v0001098_t0009") 2022/01/23 06:36:05 DEBUG : both4: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f109794d4b820adbb_d20220123_m063538_c001_v0001044_t0029") 2022/01/23 06:36:06 DEBUG : both4: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a92c_d20220123_m063439_c001_v0001098_t0015") 2022/01/23 06:36:06 DEBUG : both4: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1069f8cf09803b92_d20220123_m063418_c001_v0001120_t0043") 2022/01/23 06:36:06 DEBUG : both4: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a73f_d20220123_m063338_c001_v0001098_t0029") 2022/01/23 06:36:06 DEBUG : both5: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f115094c01a1ab73c_d20220123_m063539_c001_v0001097_t0016") 2022/01/23 06:36:06 DEBUG : both5: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a942_d20220123_m063442_c001_v0001098_t0036") 2022/01/23 06:36:06 DEBUG : both5: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f101a5c1c3046c83f_d20220123_m063418_c001_v0001165_t0029") 2022/01/23 06:36:06 DEBUG : both5: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a757_d20220123_m063339_c001_v0001098_t0036") 2022/01/23 06:36:06 DEBUG : both6: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1184e3f67f7a2434_d20220123_m063539_c001_v0001134_t0013") 2022/01/23 06:36:06 DEBUG : both6: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a94d_d20220123_m063443_c001_v0001098_t0011") 2022/01/23 06:36:06 DEBUG : both6: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f116bc8c6f8a9d9fd_d20220123_m063418_c001_v0001120_t0034") 2022/01/23 06:36:06 DEBUG : both6: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a75f_d20220123_m063340_c001_v0001098_t0036") 2022/01/23 06:36:06 DEBUG : both7: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f10489bb95b3ccf4b_d20220123_m063540_c001_v0001165_t0059") 2022/01/23 06:36:06 DEBUG : both7: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a959_d20220123_m063444_c001_v0001098_t0017") 2022/01/23 06:36:06 DEBUG : both7: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f110ffc449463964d_d20220123_m063419_c001_v0001161_t0009") 2022/01/23 06:36:06 DEBUG : both7: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a765_d20220123_m063342_c001_v0001098_t0056") 2022/01/23 06:36:06 DEBUG : both8: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1186d22bf8340c0f_d20220123_m063540_c001_v0001161_t0027") 2022/01/23 06:36:06 DEBUG : both8: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a968_d20220123_m063444_c001_v0001098_t0048") 2022/01/23 06:36:06 DEBUG : both8: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f11183f46aea07a1c_d20220123_m063420_c001_v0001105_t0057") 2022/01/23 06:36:06 DEBUG : both8: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a77f_d20220123_m063344_c001_v0001098_t0014") 2022/01/23 06:36:06 DEBUG : both9: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1100d858c4238613_d20220123_m063541_c001_v0001015_t0042") 2022/01/23 06:36:07 DEBUG : both9: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a974_d20220123_m063445_c001_v0001098_t0021") 2022/01/23 06:36:07 DEBUG : both9: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1120001a2b40469f_d20220123_m063420_c001_v0001008_t0007") 2022/01/23 06:36:07 DEBUG : both9: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a789_d20220123_m063346_c001_v0001098_t0013") 2022/01/23 06:36:07 DEBUG : c/non empty space: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1099a8a656037414_d20220123_m062750_c001_v0001098_t0038") 2022/01/23 06:36:07 DEBUG : c/non empty space: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108ed106e78f0556_d20220123_m062741_c001_v0001040_t0036") 2022/01/23 06:36:07 DEBUG : c/non empty space: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f118209c93334e030_d20220123_m062737_c001_v0001097_t0021") 2022/01/23 06:36:07 DEBUG : c/non empty space: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108ed106e78f053c_d20220123_m062733_c001_v0001040_t0054") 2022/01/23 06:36:07 DEBUG : c/non empty space: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f10316510cbdfefe9_d20220123_m062702_c001_v0001009_t0033") 2022/01/23 06:36:07 DEBUG : c/non empty space: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108ed106e78f04c3_d20220123_m062651_c001_v0001040_t0000") 2022/01/23 06:36:07 DEBUG : check sum: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f109e54f5f4ef5938_d20220123_m062623_c001_v0001137_t0048") 2022/01/23 06:36:07 DEBUG : check sum: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108ed106e78f046e_d20220123_m062617_c001_v0001040_t0031") 2022/01/23 06:36:07 DEBUG : dest/3: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f100ed3fd16290d45_d20220123_m063016_c001_v0001009_t0029") 2022/01/23 06:36:07 DEBUG : dest/3: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f104bff9cb12b648a_d20220123_m063015_c001_v0001105_t0024") 2022/01/23 06:36:07 DEBUG : dst/one: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f103d1728351f4d7b_d20220123_m063313_c001_v0001011_t0027") 2022/01/23 06:36:07 DEBUG : dst/one: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1088eea0b2b335e4_d20220123_m063304_c001_v0001120_t0049") 2022/01/23 06:36:07 DEBUG : dst/one: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f11923c054a033090_d20220123_m063303_c001_v0001038_t0043") 2022/01/23 06:36:07 DEBUG : dst/one: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1088eea0b2b335c0_d20220123_m063258_c001_v0001120_t0028") 2022/01/23 06:36:07 DEBUG : dst/one: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f10961062a61e7eeb_d20220123_m063257_c001_v0001153_t0020") 2022/01/23 06:36:07 DEBUG : dst/one: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a5cd_d20220123_m063251_c001_v0001098_t0054") 2022/01/23 06:36:08 DEBUG : dst/one: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f111ffd8f6935759b_d20220123_m063246_c001_v0001003_t0010") 2022/01/23 06:36:08 DEBUG : dst/one: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1033dfab79773d31_d20220123_m063244_c001_v0001098_t0025") 2022/01/23 06:36:08 DEBUG : dst/one: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f105e9dbbaf474789_d20220123_m063243_c001_v0001157_t0001") 2022/01/23 06:36:08 DEBUG : dst/one: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1033dfab79773cdb_d20220123_m063237_c001_v0001098_t0014") 2022/01/23 06:36:08 DEBUG : dst/one: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108188635aae4b11_d20220123_m063236_c001_v0001097_t0050") 2022/01/23 06:36:08 DEBUG : dst/one: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f106d5ac11b29ae99_d20220123_m063230_c001_v0001109_t0043") 2022/01/23 06:36:08 DEBUG : dst/one: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1088eea0b2b334da_d20220123_m063208_c001_v0001120_t0055") 2022/01/23 06:36:08 DEBUG : dst/one: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1099828b1a098905_d20220123_m063207_c001_v0001003_t0027") 2022/01/23 06:36:08 DEBUG : dst/one: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f105a8177d0c0fd4c_d20220123_m063206_c001_v0001003_t0041") 2022/01/23 06:36:08 DEBUG : dst/one: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1099828b1a098902_d20220123_m063201_c001_v0001003_t0032") 2022/01/23 06:36:08 DEBUG : dst/one: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f105636fb413563ae_d20220123_m063200_c001_v0001098_t0052") 2022/01/23 06:36:08 DEBUG : dst/one: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f106d5ac11b29ad35_d20220123_m063153_c001_v0001109_t0040") 2022/01/23 06:36:08 DEBUG : dst/one: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f115ab0d175635604_d20220123_m063152_c001_v0001105_t0029") 2022/01/23 06:36:08 DEBUG : dst/one: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f105208e8eedfc070_d20220123_m063149_c001_v0001137_t0007") 2022/01/23 06:36:08 DEBUG : dst/one: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f111b1fd9f7aca43f_d20220123_m063147_c001_v0001006_t0028") 2022/01/23 06:36:08 DEBUG : dst/one: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f105208e8eedfc054_d20220123_m063141_c001_v0001137_t0010") 2022/01/23 06:36:08 DEBUG : dst/one: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f117be92ab86b7ca1_d20220123_m063140_c001_v0001157_t0055") 2022/01/23 06:36:08 DEBUG : dst/one: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f106d5ac11b29ac72_d20220123_m063133_c001_v0001109_t0023") 2022/01/23 06:36:08 DEBUG : dst/one: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1147f32f1cdfcca5_d20220123_m063132_c001_v0001032_t0046") 2022/01/23 06:36:08 DEBUG : dst/one: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f107a30597d868a3a_d20220123_m063126_c001_v0001165_t0029") 2022/01/23 06:36:08 DEBUG : dst/one: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1134b6ba21fd2632_d20220123_m063125_c001_v0001154_t0021") 2022/01/23 06:36:09 DEBUG : dst/one: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f107a30597d8689fd_d20220123_m063118_c001_v0001165_t0050") 2022/01/23 06:36:09 DEBUG : dst/one: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f109153a673820ac8_d20220123_m063117_c001_v0001128_t0013") 2022/01/23 06:36:09 DEBUG : dst/one: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f106d5ac11b29abbd_d20220123_m063111_c001_v0001109_t0027") 2022/01/23 06:36:09 DEBUG : dst/one: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f119c2b6e4c6eb7b2_d20220123_m063109_c001_v0001161_t0027") 2022/01/23 06:36:09 DEBUG : dst/one: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f11600227c62b8dbf_d20220123_m063100_c001_v0001105_t0000") 2022/01/23 06:36:09 DEBUG : dst/one: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f11033103e3590454_d20220123_m063059_c001_v0001109_t0028") 2022/01/23 06:36:09 DEBUG : dst/one: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f11600227c62b8d58_d20220123_m063054_c001_v0001105_t0038") 2022/01/23 06:36:09 DEBUG : dst/one: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f10213ebbbd33fda7_d20220123_m063053_c001_v0001165_t0051") 2022/01/23 06:36:09 DEBUG : dst/one: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f106d5ac11b29aae4_d20220123_m063046_c001_v0001109_t0027") 2022/01/23 06:36:09 DEBUG : dst/one: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f102df1dd2101f75a_d20220123_m063044_c001_v0001098_t0009") 2022/01/23 06:36:09 DEBUG : dst/one: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f109ef7b0dcc54b48_d20220123_m063031_c001_v0001097_t0001") 2022/01/23 06:36:09 DEBUG : dst/one: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1167fc6374a09f30_d20220123_m063029_c001_v0001032_t0050") 2022/01/23 06:36:09 DEBUG : dst/one: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f106d5ac11b29aa16_d20220123_m063025_c001_v0001109_t0034") 2022/01/23 06:36:09 DEBUG : dst/one: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f114b82c090ca37c6_d20220123_m063023_c001_v0001036_t0053") 2022/01/23 06:36:09 DEBUG : dst/one: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f114b82c090ca37c2_d20220123_m063021_c001_v0001036_t0057") 2022/01/23 06:36:09 DEBUG : dst/one: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f116bc8c6f8a9d445_d20220123_m063006_c001_v0001120_t0049") 2022/01/23 06:36:09 DEBUG : dst/one: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a1f7_d20220123_m062956_c001_v0001098_t0047") 2022/01/23 06:36:09 DEBUG : dst/one: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f107c5d7e0ff37c3e_d20220123_m062955_c001_v0001023_t0015") 2022/01/23 06:36:09 DEBUG : dst/one: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f107c5d7e0ff37c3a_d20220123_m062953_c001_v0001023_t0029") 2022/01/23 06:36:09 DEBUG : dst/one-2019-01-01: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f119e80c44c73a7f3_d20220123_m063313_c001_v0001005_t0012") 2022/01/23 06:36:09 DEBUG : dst/one-2019-01-01: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f102693152fdae260_d20220123_m063303_c001_v0001131_t0023") 2022/01/23 06:36:09 DEBUG : dst/one-2019-01-01: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f105ff8828edb6709_d20220123_m063256_c001_v0001005_t0013") 2022/01/23 06:36:10 DEBUG : dst/one.bak: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f105731b97ce1f749_d20220123_m063247_c001_v0001038_t0003") 2022/01/23 06:36:10 DEBUG : dst/one.bak: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113f1a163f8dceac_d20220123_m063242_c001_v0001109_t0026") 2022/01/23 06:36:10 DEBUG : dst/one.bak: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1199774b802a897b_d20220123_m063236_c001_v0001165_t0013") 2022/01/23 06:36:10 DEBUG : dst/one.bak: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f118209c93334e52a_d20220123_m063209_c001_v0001097_t0052") 2022/01/23 06:36:10 DEBUG : dst/one.bak: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1084c2e4a08dc2af_d20220123_m063205_c001_v0001105_t0031") 2022/01/23 06:36:10 DEBUG : dst/one.bak: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f112154033bebcf7b_d20220123_m063158_c001_v0001105_t0039") 2022/01/23 06:36:10 DEBUG : dst/three: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1165dca1fb4d6ec5_d20220123_m063044_c001_v0001011_t0032") 2022/01/23 06:36:10 DEBUG : dst/three: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f114b82c090ca37ef_d20220123_m063040_c001_v0001036_t0004") 2022/01/23 06:36:10 DEBUG : dst/three-2019-01-01.txt: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1104de563200b83d_d20220123_m063314_c001_v0001165_t0053") 2022/01/23 06:36:10 DEBUG : dst/three-2019-01-01.txt: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113cb844aa8a4b5f_d20220123_m063306_c001_v0001105_t0031") 2022/01/23 06:36:10 DEBUG : dst/three-2019-01-01.txt: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f10359b1fa1087acb_d20220123_m063259_c001_v0001154_t0003") 2022/01/23 06:36:10 DEBUG : dst/three.txt: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f101d4903b22442a0_d20220123_m063314_c001_v0001144_t0014") 2022/01/23 06:36:10 DEBUG : dst/three.txt: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1088eea0b2b33614_d20220123_m063312_c001_v0001120_t0036") 2022/01/23 06:36:10 DEBUG : dst/three.txt: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1039543172af0a76_d20220123_m063307_c001_v0001097_t0027") 2022/01/23 06:36:10 DEBUG : dst/three.txt: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1088eea0b2b335cf_d20220123_m063301_c001_v0001120_t0018") 2022/01/23 06:36:10 DEBUG : dst/three.txt: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f11033103e3590830_d20220123_m063300_c001_v0001109_t0015") 2022/01/23 06:36:10 DEBUG : dst/three.txt: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a5dc_d20220123_m063253_c001_v0001098_t0023") 2022/01/23 06:36:10 DEBUG : dst/three.txt: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1186d22bf83404c1_d20220123_m063248_c001_v0001161_t0007") 2022/01/23 06:36:10 DEBUG : dst/three.txt: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1033dfab79773d4a_d20220123_m063246_c001_v0001098_t0003") 2022/01/23 06:36:11 DEBUG : dst/three.txt: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f106d5ac11b29af2b_d20220123_m063245_c001_v0001109_t0031") 2022/01/23 06:36:11 DEBUG : dst/three.txt: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1033dfab79773cf5_d20220123_m063240_c001_v0001098_t0009") 2022/01/23 06:36:11 DEBUG : dst/three.txt: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f106f8408ab5e9438_d20220123_m063239_c001_v0001150_t0036") 2022/01/23 06:36:11 DEBUG : dst/three.txt: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f106d5ac11b29aeb7_d20220123_m063232_c001_v0001109_t0056") 2022/01/23 06:36:11 DEBUG : dst/three.txt: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f105f36dcca92d12f_d20220123_m063207_c001_v0001150_t0031") 2022/01/23 06:36:11 DEBUG : dst/three.txt: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a4d5_d20220123_m063204_c001_v0001098_t0038") 2022/01/23 06:36:11 DEBUG : dst/three.txt: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1148f37149e4f020_d20220123_m063203_c001_v0001109_t0043") 2022/01/23 06:36:11 DEBUG : dst/three.txt: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f106d5ac11b29ad4e_d20220123_m063155_c001_v0001109_t0029") 2022/01/23 06:36:11 DEBUG : dst/three.txt: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f103013e5ffb6b08a_d20220123_m063149_c001_v0001000_t0034") 2022/01/23 06:36:11 DEBUG : dst/three.txt: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a45b_d20220123_m063145_c001_v0001098_t0058") 2022/01/23 06:36:11 DEBUG : dst/three.txt: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f11380cd11403f636_d20220123_m063144_c001_v0001146_t0008") 2022/01/23 06:36:11 DEBUG : dst/three.txt: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f106d5ac11b29ac8c_d20220123_m063136_c001_v0001109_t0048") 2022/01/23 06:36:11 DEBUG : dst/three.txt: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f11673ee4653c6085_d20220123_m063127_c001_v0001131_t0012") 2022/01/23 06:36:11 DEBUG : dst/three.txt: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a3e5_d20220123_m063122_c001_v0001098_t0022") 2022/01/23 06:36:11 DEBUG : dst/three.txt: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f117038f90dd4c74e_d20220123_m063120_c001_v0001021_t0019") 2022/01/23 06:36:11 DEBUG : dst/three.txt: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f106d5ac11b29abd3_d20220123_m063112_c001_v0001109_t0031") 2022/01/23 06:36:11 DEBUG : dst/three.txt: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1085c96b37482a54_d20220123_m063104_c001_v0001001_t0044") 2022/01/23 06:36:11 DEBUG : dst/three.txt: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a373_d20220123_m063057_c001_v0001098_t0027") 2022/01/23 06:36:11 DEBUG : dst/three.txt: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f115a6936c26cdd4e_d20220123_m063055_c001_v0001098_t0055") 2022/01/23 06:36:11 DEBUG : dst/three.txt: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f106d5ac11b29aaf0_d20220123_m063048_c001_v0001109_t0017") 2022/01/23 06:36:11 DEBUG : dst/three.txt.bak: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f105712806cbf9c73_d20220123_m063249_c001_v0001131_t0034") 2022/01/23 06:36:12 DEBUG : dst/three.txt.bak: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f115ff2c20602ced7_d20220123_m063244_c001_v0001008_t0037") 2022/01/23 06:36:12 DEBUG : dst/three.txt.bak: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1196ea8da4e75e8f_d20220123_m063238_c001_v0001157_t0019") 2022/01/23 06:36:12 DEBUG : dst/three.txt.bak: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1060dced1770c813_d20220123_m063228_c001_v0001021_t0029") 2022/01/23 06:36:12 DEBUG : dst/three.txt.bak: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1117815917a7eb9b_d20220123_m063207_c001_v0001043_t0013") 2022/01/23 06:36:12 DEBUG : dst/three.txt.bak: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f111d6cc4dcca4adb_d20220123_m063202_c001_v0001120_t0028") 2022/01/23 06:36:12 DEBUG : dst/two: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f116909a3e2c950b7_d20220123_m063315_c001_v0001137_t0003") 2022/01/23 06:36:12 DEBUG : dst/two: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f106d5ac11b29af6f_d20220123_m063252_c001_v0001109_t0033") 2022/01/23 06:36:12 DEBUG : dst/two: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f10476c74bce0ad2e_d20220123_m063249_c001_v0001120_t0031") 2022/01/23 06:36:12 DEBUG : dst/two: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a54c_d20220123_m063232_c001_v0001098_t0010") 2022/01/23 06:36:12 DEBUG : dst/two: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f110034111f05b341_d20220123_m063229_c001_v0001040_t0044") 2022/01/23 06:36:12 DEBUG : dst/two: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a491_d20220123_m063154_c001_v0001098_t0044") 2022/01/23 06:36:12 DEBUG : dst/two: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f11380cd11403f65b_d20220123_m063152_c001_v0001146_t0037") 2022/01/23 06:36:12 DEBUG : dst/two: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a41e_d20220123_m063135_c001_v0001098_t0039") 2022/01/23 06:36:12 DEBUG : dst/two: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f114434eb80873831_d20220123_m063132_c001_v0001131_t0054") 2022/01/23 06:36:12 DEBUG : dst/two: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a3bc_d20220123_m063112_c001_v0001098_t0041") 2022/01/23 06:36:12 DEBUG : dst/two: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f11870d5873b63892_d20220123_m063109_c001_v0001120_t0021") 2022/01/23 06:36:12 DEBUG : dst/two: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a33a_d20220123_m063047_c001_v0001098_t0003") 2022/01/23 06:36:12 DEBUG : dst/two: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1177b478a981878c_d20220123_m063045_c001_v0001097_t0026") 2022/01/23 06:36:12 DEBUG : dst/two: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f10291eb27b6cad53_d20220123_m063034_c001_v0001120_t0009") 2022/01/23 06:36:12 DEBUG : dst/two: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f100798b0a04f88e4_d20220123_m063007_c001_v0001137_t0024") 2022/01/23 06:36:13 DEBUG : dst/two: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f107c5d7e0ff37c45_d20220123_m063002_c001_v0001023_t0022") 2022/01/23 06:36:13 DEBUG : empty space: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1072916338032358_d20220123_m062937_c001_v0001128_t0049") 2022/01/23 06:36:13 DEBUG : empty space: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f106d5ac11b29a849_d20220123_m062930_c001_v0001109_t0001") 2022/01/23 06:36:13 DEBUG : empty space: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f100bc4fabcd9d4b0_d20220123_m062925_c001_v0001109_t0059") 2022/01/23 06:36:13 DEBUG : empty space: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a13b_d20220123_m062916_c001_v0001098_t0027") 2022/01/23 06:36:13 DEBUG : empty space: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f11784b52d7da41e6_d20220123_m062905_c001_v0001037_t0009") 2022/01/23 06:36:13 DEBUG : empty space: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f106d5ac11b29a782_d20220123_m062901_c001_v0001109_t0031") 2022/01/23 06:36:13 DEBUG : empty space: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108cd212d604661e_d20220123_m062817_c001_v0001041_t0005") 2022/01/23 06:36:13 DEBUG : empty space: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108ed106e78f05b3_d20220123_m062812_c001_v0001040_t0025") 2022/01/23 06:36:13 DEBUG : empty space: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1032d239a3fed61a_d20220123_m062809_c001_v0001037_t0012") 2022/01/23 06:36:13 DEBUG : empty space: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108ed106e78f05a7_d20220123_m062807_c001_v0001040_t0051") 2022/01/23 06:36:13 DEBUG : empty space: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1101a4bd5a11bce2_d20220123_m062758_c001_v0001036_t0011") 2022/01/23 06:36:13 DEBUG : empty space: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108ed106e78f0582_d20220123_m062755_c001_v0001040_t0012") 2022/01/23 06:36:13 DEBUG : empty space: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1094fcedd6198ab1_d20220123_m062753_c001_v0001161_t0020") 2022/01/23 06:36:13 DEBUG : empty space: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108ed106e78f0572_d20220123_m062752_c001_v0001040_t0050") 2022/01/23 06:36:13 DEBUG : empty space: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113dccf9cc0203c6_d20220123_m062732_c001_v0001165_t0042") 2022/01/23 06:36:13 DEBUG : empty space: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108ed106e78f052e_d20220123_m062729_c001_v0001040_t0005") 2022/01/23 06:36:13 DEBUG : empty space: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f10673e698b71d545_d20220123_m062727_c001_v0001144_t0019") 2022/01/23 06:36:13 DEBUG : empty space: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108ed106e78f0522_d20220123_m062726_c001_v0001040_t0042") 2022/01/23 06:36:13 DEBUG : empty space: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f115a6936c26cd56a_d20220123_m062715_c001_v0001098_t0028") 2022/01/23 06:36:13 DEBUG : empty space: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108ed106e78f04f7_d20220123_m062713_c001_v0001040_t0049") 2022/01/23 06:36:14 DEBUG : empty space: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f109c69e81ef85572_d20220123_m062707_c001_v0001026_t0042") 2022/01/23 06:36:14 DEBUG : empty space: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108ed106e78f04e8_d20220123_m062706_c001_v0001040_t0030") 2022/01/23 06:36:14 DEBUG : empty space: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f116995c1dad34a59_d20220123_m062705_c001_v0001157_t0026") 2022/01/23 06:36:14 DEBUG : empty space: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f106e5e5a14e4e4a7_d20220123_m062704_c001_v0001091_t0029") 2022/01/23 06:36:14 DEBUG : empty space: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108ed106e78f04e3_d20220123_m062702_c001_v0001040_t0001") 2022/01/23 06:36:14 DEBUG : enormous: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f100950a9a7117e66_d20220123_m062814_c001_v0001105_t0051") 2022/01/23 06:36:14 DEBUG : enormous: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108ed106e78f05b7_d20220123_m062813_c001_v0001040_t0046") 2022/01/23 06:36:14 DEBUG : existing: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f11894313872676ed_d20220123_m063323_c001_v0001092_t0032") 2022/01/23 06:36:14 DEBUG : existing: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a6c0_d20220123_m063322_c001_v0001098_t0023") 2022/01/23 06:36:14 DEBUG : existing: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f100220cdc54ba926_d20220123_m062858_c001_v0001146_t0042") 2022/01/23 06:36:14 DEBUG : existing: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f106d5ac11b29a755_d20220123_m062856_c001_v0001109_t0017") 2022/01/23 06:36:14 DEBUG : existing: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f10304b623d42464c_d20220123_m062650_c001_v0001109_t0033") 2022/01/23 06:36:14 DEBUG : existing: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108ed106e78f04b9_d20220123_m062648_c001_v0001040_t0047") 2022/01/23 06:36:14 DEBUG : existing: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f115ff2c20602cdc6_d20220123_m062639_c001_v0001008_t0008") 2022/01/23 06:36:14 DEBUG : existing: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108ed106e78f04a4_d20220123_m062637_c001_v0001040_t0010") 2022/01/23 06:36:14 DEBUG : existing: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108ed106e78f049c_d20220123_m062634_c001_v0001040_t0039") 2022/01/23 06:36:15 DEBUG : existing-b: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f10071f7ffe27c349_d20220123_m062858_c001_v0001040_t0030") 2022/01/23 06:36:15 DEBUG : existing-b: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a0f0_d20220123_m062856_c001_v0001098_t0048") 2022/01/23 06:36:15 DEBUG : five: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f119b2fb2680e268a_d20220123_m062827_c001_v0001131_t0025") 2022/01/23 06:36:15 DEBUG : five: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a02e_d20220123_m062823_c001_v0001098_t0043") 2022/01/23 06:36:15 DEBUG : foo: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f115806cae718ab95_d20220123_m062711_c001_v0001042_t0044") 2022/01/23 06:36:15 DEBUG : foo: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108ed106e78f04f5_d20220123_m062709_c001_v0001040_t0050") 2022/01/23 06:36:15 DEBUG : foo: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108ed106e78f04f1_d20220123_m062708_c001_v0001040_t0017") 2022/01/23 06:36:15 DEBUG : four: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1031585c6760bf42_d20220123_m062827_c001_v0001161_t0034") 2022/01/23 06:36:15 DEBUG : four: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f106598f964480908_d20220123_m062824_c001_v0001097_t0047") 2022/01/23 06:36:15 DEBUG : four: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a02b_d20220123_m062823_c001_v0001098_t0037") 2022/01/23 06:36:15 DEBUG : hello world2: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f118914c810cf6fdc_d20220123_m062549_c001_v0001019_t0014") 2022/01/23 06:36:15 DEBUG : hello world2: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108ed106e78f042f_d20220123_m062548_c001_v0001040_t0017") 2022/01/23 06:36:15 DEBUG : ignore-size: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f117439e30674fcb6_d20220123_m062634_c001_v0001105_t0056") 2022/01/23 06:36:15 DEBUG : ignore-size: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108ed106e78f0488_d20220123_m062629_c001_v0001040_t0023") 2022/01/23 06:36:15 DEBUG : nested/sub dir/file: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f101d4903b2243fc4_d20220123_m062855_c001_v0001144_t0007") 2022/01/23 06:36:15 DEBUG : nested/sub dir/file: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f106d5ac11b29a72e_d20220123_m062852_c001_v0001109_t0058") 2022/01/23 06:36:15 DEBUG : nested/sub dir/file: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f118c209dcc245e99_d20220123_m062849_c001_v0001044_t0028") 2022/01/23 06:36:15 DEBUG : nested/sub dir/file: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f106d5ac11b29a707_d20220123_m062848_c001_v0001109_t0034") 2022/01/23 06:36:16 DEBUG : one: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f10648f466f4c3532_d20220123_m062828_c001_v0001105_t0030") 2022/01/23 06:36:16 DEBUG : one: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a01c_d20220123_m062821_c001_v0001098_t0036") 2022/01/23 06:36:16 DEBUG : only0: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1194ffe7e4a4d284_d20220123_m063541_c001_v0001105_t0016") 2022/01/23 06:36:16 DEBUG : only0: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7aa2c_d20220123_m063509_c001_v0001098_t0038") 2022/01/23 06:36:16 DEBUG : only0: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a8f9_d20220123_m063432_c001_v0001098_t0052") 2022/01/23 06:36:16 DEBUG : only0: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1022e5a17daa1283_d20220123_m063402_c001_v0001029_t0031") 2022/01/23 06:36:16 DEBUG : only0: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a6ee_d20220123_m063328_c001_v0001098_t0020") 2022/01/23 06:36:16 DEBUG : only1: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f11183f46aea0808a_d20220123_m063542_c001_v0001105_t0053") 2022/01/23 06:36:16 DEBUG : only1: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1186d22bf8340abc_d20220123_m063509_c001_v0001161_t0048") 2022/01/23 06:36:16 DEBUG : only1: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a910_d20220123_m063435_c001_v0001098_t0035") 2022/01/23 06:36:16 DEBUG : only1: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f11870d5873b63cb9_d20220123_m063400_c001_v0001120_t0005") 2022/01/23 06:36:16 DEBUG : only1: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a6f8_d20220123_m063329_c001_v0001098_t0036") 2022/01/23 06:36:16 DEBUG : only10: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f10192c76aa91748c_d20220123_m063543_c001_v0001091_t0042") 2022/01/23 06:36:16 DEBUG : only10: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f100ec8ff14e71e67_d20220123_m063512_c001_v0001134_t0055") 2022/01/23 06:36:16 DEBUG : only10: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a981_d20220123_m063447_c001_v0001098_t0052") 2022/01/23 06:36:16 DEBUG : only10: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1199774b802a8cce_d20220123_m063402_c001_v0001165_t0049") 2022/01/23 06:36:16 DEBUG : only10: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a79a_d20220123_m063347_c001_v0001098_t0052") 2022/01/23 06:36:16 DEBUG : only11: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1189431387267790_d20220123_m063543_c001_v0001092_t0053") 2022/01/23 06:36:16 DEBUG : only11: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f100ec8ff14e71e54_d20220123_m063509_c001_v0001134_t0031") 2022/01/23 06:36:16 DEBUG : only11: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a988_d20220123_m063448_c001_v0001098_t0034") 2022/01/23 06:36:16 DEBUG : only11: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1166be11469d18ca_d20220123_m063405_c001_v0001098_t0042") 2022/01/23 06:36:16 DEBUG : only11: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a7ac_d20220123_m063350_c001_v0001098_t0036") 2022/01/23 06:36:17 DEBUG : only12: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1064c3f4ba7159ff_d20220123_m063544_c001_v0001137_t0035") 2022/01/23 06:36:17 DEBUG : only12: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f116001793f8ae7b6_d20220123_m063510_c001_v0001000_t0037") 2022/01/23 06:36:17 DEBUG : only12: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a996_d20220123_m063449_c001_v0001098_t0058") 2022/01/23 06:36:17 DEBUG : only12: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1084c2e4a08dc9bb_d20220123_m063401_c001_v0001105_t0035") 2022/01/23 06:36:17 DEBUG : only12: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a7bd_d20220123_m063351_c001_v0001098_t0052") 2022/01/23 06:36:17 DEBUG : only13: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f11938ed011353c5d_d20220123_m063544_c001_v0001037_t0044") 2022/01/23 06:36:17 DEBUG : only13: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7aa35_d20220123_m063510_c001_v0001098_t0052") 2022/01/23 06:36:17 DEBUG : only13: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a9b5_d20220123_m063453_c001_v0001098_t0006") 2022/01/23 06:36:17 DEBUG : only13: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1047d94f422d3a2f_d20220123_m063403_c001_v0001134_t0023") 2022/01/23 06:36:17 DEBUG : only13: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a7ca_d20220123_m063353_c001_v0001098_t0057") 2022/01/23 06:36:17 DEBUG : only14: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f112154033bebde61_d20220123_m063546_c001_v0001105_t0048") 2022/01/23 06:36:17 DEBUG : only14: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f100ec8ff14e71e58_d20220123_m063510_c001_v0001134_t0004") 2022/01/23 06:36:17 DEBUG : only14: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a9c0_d20220123_m063454_c001_v0001098_t0035") 2022/01/23 06:36:17 DEBUG : only14: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f112dc509ca9b9b70_d20220123_m063402_c001_v0001120_t0019") 2022/01/23 06:36:17 DEBUG : only14: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a7d6_d20220123_m063354_c001_v0001098_t0013") 2022/01/23 06:36:17 DEBUG : only15: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1060739ec2c4ddda_d20220123_m063548_c001_v0001090_t0048") 2022/01/23 06:36:17 DEBUG : only15: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1186d22bf8340ac0_d20220123_m063510_c001_v0001161_t0045") 2022/01/23 06:36:17 DEBUG : only15: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a9ca_d20220123_m063455_c001_v0001098_t0016") 2022/01/23 06:36:18 DEBUG : only15: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1076723393a8845c_d20220123_m063405_c001_v0001098_t0054") 2022/01/23 06:36:18 DEBUG : only15: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a7dc_d20220123_m063355_c001_v0001098_t0014") 2022/01/23 06:36:18 DEBUG : only16: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f106f7556b668399b_d20220123_m063549_c001_v0001165_t0057") 2022/01/23 06:36:18 DEBUG : only16: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f100ec8ff14e71e5e_d20220123_m063510_c001_v0001134_t0001") 2022/01/23 06:36:18 DEBUG : only16: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a9d0_d20220123_m063456_c001_v0001098_t0042") 2022/01/23 06:36:18 DEBUG : only16: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1033779863050f95_d20220123_m063401_c001_v0001105_t0025") 2022/01/23 06:36:18 DEBUG : only16: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a7e1_d20220123_m063355_c001_v0001098_t0043") 2022/01/23 06:36:18 DEBUG : only17: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f112154033bebde95_d20220123_m063549_c001_v0001105_t0047") 2022/01/23 06:36:18 DEBUG : only17: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7aa3f_d20220123_m063510_c001_v0001098_t0051") 2022/01/23 06:36:18 DEBUG : only17: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a9db_d20220123_m063458_c001_v0001098_t0054") 2022/01/23 06:36:18 DEBUG : only17: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f11916a55b4ac7062_d20220123_m063401_c001_v0001025_t0029") 2022/01/23 06:36:18 DEBUG : only17: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a7ec_d20220123_m063357_c001_v0001098_t0038") 2022/01/23 06:36:18 DEBUG : only18: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f10975c8130c69b58_d20220123_m063549_c001_v0001041_t0053") 2022/01/23 06:36:18 DEBUG : only18: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1186d22bf8340ac4_d20220123_m063510_c001_v0001161_t0018") 2022/01/23 06:36:18 DEBUG : only18: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a9fa_d20220123_m063503_c001_v0001098_t0042") 2022/01/23 06:36:18 DEBUG : only18: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f11342511b3dde598_d20220123_m063403_c001_v0001161_t0023") 2022/01/23 06:36:18 DEBUG : only18: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a7f6_d20220123_m063357_c001_v0001098_t0003") 2022/01/23 06:36:18 DEBUG : only19: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f105c89926973e7d7_d20220123_m063552_c001_v0001161_t0017") 2022/01/23 06:36:18 DEBUG : only19: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f100ec8ff14e71e60_d20220123_m063511_c001_v0001134_t0022") 2022/01/23 06:36:18 DEBUG : only19: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7aa18_d20220123_m063507_c001_v0001098_t0059") 2022/01/23 06:36:19 DEBUG : only19: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f111b547dec93032d_d20220123_m063402_c001_v0001150_t0054") 2022/01/23 06:36:19 DEBUG : only19: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a808_d20220123_m063359_c001_v0001098_t0021") 2022/01/23 06:36:19 DEBUG : only2: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f107ad2e3e6537915_d20220123_m063553_c001_v0001044_t0008") 2022/01/23 06:36:19 DEBUG : only2: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1186d22bf8340ac6_d20220123_m063511_c001_v0001161_t0045") 2022/01/23 06:36:19 DEBUG : only2: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a91d_d20220123_m063437_c001_v0001098_t0025") 2022/01/23 06:36:19 DEBUG : only2: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1013c4af566e2b94_d20220123_m063401_c001_v0001131_t0045") 2022/01/23 06:36:19 DEBUG : only2: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a717_d20220123_m063334_c001_v0001098_t0053") 2022/01/23 06:36:19 DEBUG : only3: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f104e87e6a03b55a2_d20220123_m063554_c001_v0001097_t0027") 2022/01/23 06:36:19 DEBUG : only3: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1186d22bf8340acf_d20220123_m063511_c001_v0001161_t0055") 2022/01/23 06:36:19 DEBUG : only3: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a929_d20220123_m063438_c001_v0001098_t0031") 2022/01/23 06:36:19 DEBUG : only3: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1021c112e6a7d6d5_d20220123_m063403_c001_v0001040_t0001") 2022/01/23 06:36:19 DEBUG : only3: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a736_d20220123_m063337_c001_v0001098_t0002") 2022/01/23 06:36:19 DEBUG : only4: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1199774b802a91a4_d20220123_m063557_c001_v0001165_t0047") 2022/01/23 06:36:19 DEBUG : only4: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f100ec8ff14e71e62_d20220123_m063511_c001_v0001134_t0008") 2022/01/23 06:36:20 DEBUG : only4: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a93d_d20220123_m063441_c001_v0001098_t0025") 2022/01/23 06:36:20 DEBUG : only4: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f111cc8abc0551624_d20220123_m063401_c001_v0001134_t0053") 2022/01/23 06:36:20 DEBUG : only4: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a745_d20220123_m063338_c001_v0001098_t0018") 2022/01/23 06:36:20 DEBUG : only5: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1085f34332bb0fb9_d20220123_m063557_c001_v0001161_t0032") 2022/01/23 06:36:20 DEBUG : only5: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1186d22bf8340ad1_d20220123_m063511_c001_v0001161_t0058") 2022/01/23 06:36:20 DEBUG : only5: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a947_d20220123_m063442_c001_v0001098_t0003") 2022/01/23 06:36:20 DEBUG : only5: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f119c1ef7e450d16a_d20220123_m063401_c001_v0001137_t0033") 2022/01/23 06:36:20 DEBUG : only5: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a75d_d20220123_m063340_c001_v0001098_t0015") 2022/01/23 06:36:20 DEBUG : only6: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f117beeafcae02f1a_d20220123_m063557_c001_v0001154_t0007") 2022/01/23 06:36:20 DEBUG : only6: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f116001793f8ae7b8_d20220123_m063511_c001_v0001000_t0005") 2022/01/23 06:36:20 DEBUG : only6: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a953_d20220123_m063443_c001_v0001098_t0014") 2022/01/23 06:36:21 DEBUG : only6: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1186d22bf83407de_d20220123_m063401_c001_v0001161_t0054") 2022/01/23 06:36:21 DEBUG : only6: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a761_d20220123_m063341_c001_v0001098_t0043") 2022/01/23 06:36:21 DEBUG : only7: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1018d5a00fc074f9_d20220123_m063558_c001_v0001040_t0041") 2022/01/23 06:36:21 DEBUG : only7: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1186d22bf8340add_d20220123_m063512_c001_v0001161_t0040") 2022/01/23 06:36:21 DEBUG : only7: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a961_d20220123_m063444_c001_v0001098_t0055") 2022/01/23 06:36:21 DEBUG : only7: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f115a35a208ad82ef_d20220123_m063402_c001_v0001090_t0042") 2022/01/23 06:36:21 DEBUG : only7: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a779_d20220123_m063344_c001_v0001098_t0045") 2022/01/23 06:36:21 DEBUG : only8: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1028fcdf632cc64d_d20220123_m063559_c001_v0001090_t0004") 2022/01/23 06:36:21 DEBUG : only8: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7aa50_d20220123_m063512_c001_v0001098_t0037") 2022/01/23 06:36:21 DEBUG : only8: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a970_d20220123_m063445_c001_v0001098_t0030") 2022/01/23 06:36:21 DEBUG : only8: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f11776de5abca2509_d20220123_m063403_c001_v0001093_t0024") 2022/01/23 06:36:21 DEBUG : only8: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a782_d20220123_m063345_c001_v0001098_t0040") 2022/01/23 06:36:21 DEBUG : only9: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f11183f46aea081fc_d20220123_m063559_c001_v0001105_t0014") 2022/01/23 06:36:21 DEBUG : only9: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f100ec8ff14e71e65_d20220123_m063512_c001_v0001134_t0041") 2022/01/23 06:36:21 DEBUG : only9: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a977_d20220123_m063446_c001_v0001098_t0018") 2022/01/23 06:36:21 DEBUG : only9: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1084c2e4a08dc9be_d20220123_m063401_c001_v0001105_t0042") 2022/01/23 06:36:22 DEBUG : only9: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a78b_d20220123_m063346_c001_v0001098_t0024") 2022/01/23 06:36:22 DEBUG : potato: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f10393b162ab43301_d20220123_m062847_c001_v0001150_t0007") 2022/01/23 06:36:22 DEBUG : potato: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a0a3_d20220123_m062843_c001_v0001098_t0041") 2022/01/23 06:36:22 DEBUG : potato: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f10525bb2bb5afe75_d20220123_m062841_c001_v0001009_t0029") 2022/01/23 06:36:22 DEBUG : potato: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a085_d20220123_m062837_c001_v0001098_t0033") 2022/01/23 06:36:22 DEBUG : potato: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1049f4c165de1bc8_d20220123_m062834_c001_v0001019_t0038") 2022/01/23 06:36:22 DEBUG : potato: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f115a6936c26cd85f_d20220123_m062831_c001_v0001098_t0009") 2022/01/23 06:36:22 DEBUG : potato: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1052e4cdc589a986_d20220123_m062803_c001_v0001044_t0022") 2022/01/23 06:36:22 DEBUG : potato: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108ed106e78f0597_d20220123_m062800_c001_v0001040_t0007") 2022/01/23 06:36:22 DEBUG : potato: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f100b7aa29d9a0f67_d20220123_m062757_c001_v0001090_t0048") 2022/01/23 06:36:22 DEBUG : potato: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108ed106e78f057a_d20220123_m062755_c001_v0001040_t0034") 2022/01/23 06:36:22 DEBUG : potato: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1046325d13496000_d20220123_m062753_c001_v0001161_t0034") 2022/01/23 06:36:22 DEBUG : potato: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108ed106e78f056c_d20220123_m062751_c001_v0001040_t0049") 2022/01/23 06:36:22 DEBUG : potato: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f107084506f7acfc9_d20220123_m062731_c001_v0001153_t0024") 2022/01/23 06:36:22 DEBUG : potato: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108ed106e78f052a_d20220123_m062729_c001_v0001040_t0030") 2022/01/23 06:36:22 DEBUG : potato: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f10735a89cc9c5cf6_d20220123_m062728_c001_v0001161_t0053") 2022/01/23 06:36:23 DEBUG : potato: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108ed106e78f051d_d20220123_m062725_c001_v0001040_t0039") 2022/01/23 06:36:23 DEBUG : potato: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f112ebb1853f4108e_d20220123_m062724_c001_v0001098_t0029") 2022/01/23 06:36:23 DEBUG : potato: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108ed106e78f0514_d20220123_m062721_c001_v0001040_t0008") 2022/01/23 06:36:23 DEBUG : potato: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108ed106e78f050f_d20220123_m062720_c001_v0001040_t0046") 2022/01/23 06:36:23 DEBUG : potato: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f112548c73e3494ef_d20220123_m062719_c001_v0001161_t0049") 2022/01/23 06:36:23 DEBUG : potato: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108ed106e78f050a_d20220123_m062718_c001_v0001040_t0007") 2022/01/23 06:36:23 DEBUG : potato: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108ed106e78f0507_d20220123_m062717_c001_v0001040_t0007") 2022/01/23 06:36:23 DEBUG : potato: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113f1a163f8dc6a0_d20220123_m062716_c001_v0001109_t0019") 2022/01/23 06:36:23 DEBUG : potato: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108ed106e78f04fb_d20220123_m062714_c001_v0001040_t0008") 2022/01/23 06:36:23 DEBUG : potato2: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f106f7556b6682967_d20220123_m062950_c001_v0001165_t0005") 2022/01/23 06:36:23 DEBUG : potato2: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f106d5ac11b29a8ed_d20220123_m062949_c001_v0001109_t0026") 2022/01/23 06:36:23 DEBUG : potato2: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f114e97626a8107ef_d20220123_m062938_c001_v0001022_t0057") 2022/01/23 06:36:23 DEBUG : potato2: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a15b_d20220123_m062928_c001_v0001098_t0059") 2022/01/23 06:36:23 DEBUG : potato2: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f103d1728351f4d10_d20220123_m062919_c001_v0001011_t0015") 2022/01/23 06:36:23 DEBUG : potato2: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f106d5ac11b29a7e9_d20220123_m062914_c001_v0001109_t0021") 2022/01/23 06:36:23 DEBUG : potato2: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f116f5f247d6bc642_d20220123_m062906_c001_v0001109_t0050") 2022/01/23 06:36:23 DEBUG : potato2: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a10b_d20220123_m062900_c001_v0001098_t0026") 2022/01/23 06:36:23 DEBUG : potato2: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f11990a7adf62029e_d20220123_m062814_c001_v0001090_t0010") 2022/01/23 06:36:23 DEBUG : potato2: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108ed106e78f05b0_d20220123_m062810_c001_v0001040_t0019") 2022/01/23 06:36:24 DEBUG : potato2: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f10291eb27b6caab5_d20220123_m062809_c001_v0001120_t0009") 2022/01/23 06:36:24 DEBUG : potato2: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108ed106e78f05a1_d20220123_m062805_c001_v0001040_t0027") 2022/01/23 06:36:24 DEBUG : potato2: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f102dba6671027ff5_d20220123_m062804_c001_v0001105_t0009") 2022/01/23 06:36:24 DEBUG : potato2: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108ed106e78f059d_d20220123_m062802_c001_v0001040_t0026") 2022/01/23 06:36:24 DEBUG : potato2: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1114e39e2b41d0b0_d20220123_m062759_c001_v0001040_t0048") 2022/01/23 06:36:24 DEBUG : potato2: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108ed106e78f058f_d20220123_m062757_c001_v0001040_t0007") 2022/01/23 06:36:24 DEBUG : potato2: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1183cac198dd6ae3_d20220123_m062754_c001_v0001005_t0032") 2022/01/23 06:36:24 DEBUG : potato2: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108ed106e78f0574_d20220123_m062753_c001_v0001040_t0013") 2022/01/23 06:36:24 DEBUG : potato2: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1038c688e6608b85_d20220123_m062732_c001_v0001017_t0039") 2022/01/23 06:36:24 DEBUG : potato2: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108ed106e78f0534_d20220123_m062731_c001_v0001040_t0010") 2022/01/23 06:36:24 DEBUG : potato2: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f112b40c380f39482_d20220123_m062557_c001_v0001025_t0023") 2022/01/23 06:36:24 DEBUG : potato2: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108ed106e78f0439_d20220123_m062553_c001_v0001040_t0029") 2022/01/23 06:36:24 DEBUG : potato2: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f11098f3a455d98b0_d20220123_m062551_c001_v0001120_t0003") 2022/01/23 06:36:24 DEBUG : potato2: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108ed106e78f0437_d20220123_m062550_c001_v0001040_t0009") 2022/01/23 06:36:24 DEBUG : potato3: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1075c829c3ce0758_d20220123_m062937_c001_v0001157_t0006") 2022/01/23 06:36:24 DEBUG : potato3: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a16b_d20220123_m062930_c001_v0001098_t0043") 2022/01/23 06:36:24 DEBUG : potato3: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1107195227f7e8f8_d20220123_m062919_c001_v0001022_t0026") 2022/01/23 06:36:24 DEBUG : potato3: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f106d5ac11b29a7f4_d20220123_m062916_c001_v0001109_t0004") 2022/01/23 06:36:24 DEBUG : potato3: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f10213ebbbd33f866_d20220123_m062906_c001_v0001165_t0001") 2022/01/23 06:36:24 DEBUG : potato3: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a10f_d20220123_m062901_c001_v0001098_t0011") 2022/01/23 06:36:25 DEBUG : pre-dest1/1: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f101e06e29be80828_d20220123_m063017_c001_v0001105_t0024") 2022/01/23 06:36:25 DEBUG : pre-dest1/1: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f106d5ac11b29a994_d20220123_m063008_c001_v0001109_t0035") 2022/01/23 06:36:25 DEBUG : pre-dest2/2: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f112ec6603f2977ac_d20220123_m063018_c001_v0001097_t0039") 2022/01/23 06:36:25 DEBUG : pre-dest2/2: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a25e_d20220123_m063010_c001_v0001098_t0006") 2022/01/23 06:36:25 DEBUG : sizeonly: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1159aae0d1a49755_d20220123_m062626_c001_v0001025_t0015") 2022/01/23 06:36:25 DEBUG : sizeonly: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108ed106e78f0481_d20220123_m062624_c001_v0001040_t0010") 2022/01/23 06:36:25 DEBUG : sub dir/hello world: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f100623001ab6c700_d20220123_m062856_c001_v0001157_t0016") 2022/01/23 06:36:25 DEBUG : sub dir/hello world: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a0ce_d20220123_m062852_c001_v0001098_t0037") 2022/01/23 06:36:25 DEBUG : sub dir/hello world: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113dccf9cc0205c9_d20220123_m062851_c001_v0001165_t0008") 2022/01/23 06:36:25 DEBUG : sub dir/hello world: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a0bb_d20220123_m062848_c001_v0001098_t0013") 2022/01/23 06:36:25 DEBUG : sub dir/hello world: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f107a30597d86804a_d20220123_m062616_c001_v0001165_t0046") 2022/01/23 06:36:25 DEBUG : sub dir/hello world: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108ed106e78f0461_d20220123_m062614_c001_v0001040_t0015") 2022/01/23 06:36:25 DEBUG : sub dir/hello world: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f110a333a2a73b525_d20220123_m062613_c001_v0001097_t0046") 2022/01/23 06:36:25 DEBUG : sub dir/hello world: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108ed106e78f045a_d20220123_m062611_c001_v0001040_t0019") 2022/01/23 06:36:25 DEBUG : sub dir/hello world: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f11778ae494e26468_d20220123_m062610_c001_v0001165_t0001") 2022/01/23 06:36:25 DEBUG : sub dir/hello world: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108ed106e78f044d_d20220123_m062606_c001_v0001040_t0054") 2022/01/23 06:36:25 DEBUG : sub dir/hello world: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1106fa00a9a77742_d20220123_m062605_c001_v0001105_t0006") 2022/01/23 06:36:25 DEBUG : sub dir/hello world: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108ed106e78f044a_d20220123_m062604_c001_v0001040_t0012") 2022/01/23 06:36:25 DEBUG : sub dir/hello world: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f11188355f8e45067_d20220123_m062603_c001_v0001011_t0003") 2022/01/23 06:36:25 DEBUG : sub dir/hello world: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108ed106e78f0444_d20220123_m062600_c001_v0001040_t0053") 2022/01/23 06:36:26 DEBUG : sub dir/hello world: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f111121e9aa209090_d20220123_m062559_c001_v0001001_t0043") 2022/01/23 06:36:26 DEBUG : sub dir/hello world: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108ed106e78f0442_d20220123_m062558_c001_v0001040_t0052") 2022/01/23 06:36:26 DEBUG : sub dir/hello world: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1190f6584154b8d2_d20220123_m062547_c001_v0001098_t0033") 2022/01/23 06:36:26 DEBUG : sub dir/hello world: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108ed106e78f0429_d20220123_m062546_c001_v0001040_t0039") 2022/01/23 06:36:26 DEBUG : sub dir/hello world: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1003f6412d1db4ca_d20220123_m062544_c001_v0001120_t0003") 2022/01/23 06:36:26 DEBUG : sub dir/hello world: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108ed106e78f0420_d20220123_m062543_c001_v0001040_t0025") 2022/01/23 06:36:26 DEBUG : sub dir/hello world: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1106fa00a9a775d8_d20220123_m062542_c001_v0001105_t0019") 2022/01/23 06:36:26 DEBUG : sub dir/hello world: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108ed106e78f0415_d20220123_m062541_c001_v0001040_t0023") 2022/01/23 06:36:26 DEBUG : sub dir/hello world: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f107b45832465bf7c_d20220123_m062539_c001_v0001097_t0010") 2022/01/23 06:36:26 DEBUG : sub dir/hello world: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f108ed106e78f0404_d20220123_m062538_c001_v0001040_t0006") 2022/01/23 06:36:26 DEBUG : sub/yam: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f106f30186969d918_d20220123_m062846_c001_v0001128_t0036") 2022/01/23 06:36:26 DEBUG : sub/yam: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f106d5ac11b29a6d8_d20220123_m062843_c001_v0001109_t0043") 2022/01/23 06:36:26 DEBUG : three: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1008261f124646ad_d20220123_m062828_c001_v0001144_t0004") 2022/01/23 06:36:26 DEBUG : three: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a042_d20220123_m062826_c001_v0001098_t0042") 2022/01/23 06:36:26 DEBUG : three: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a027_d20220123_m062822_c001_v0001098_t0022") 2022/01/23 06:36:26 DEBUG : two: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f101786df9ae1b9d6_d20220123_m062829_c001_v0001120_t0016") 2022/01/23 06:36:26 DEBUG : two: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f115a6936c26cd81c_d20220123_m062824_c001_v0001098_t0007") 2022/01/23 06:36:26 DEBUG : two: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f113307adf3e7a021_d20220123_m062821_c001_v0001098_t0021") 2022/01/23 06:36:27 DEBUG : yam: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1142ac93fc6965dc_d20220123_m062847_c001_v0001144_t0056") 2022/01/23 06:36:27 DEBUG : yam: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1047be00b574d113_d20220123_m062845_c001_v0001036_t0045") 2022/01/23 06:36:27 DEBUG : yam: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f105f36dcca92cf1b_d20220123_m062840_c001_v0001150_t0056") 2022/01/23 06:36:27 DEBUG : yam: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1199774b802a80c3_d20220123_m062837_c001_v0001165_t0015") 2022/01/23 06:36:27 DEBUG : yam: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1077a519daa6ed36_d20220123_m062833_c001_v0001134_t0049") 2022/01/23 06:36:27 DEBUG : yam: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f106598f96448092e_d20220123_m062830_c001_v0001097_t0006") 2022/01/23 06:36:27 DEBUG : yaml: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f112c1b2e68035854_d20220123_m062842_c001_v0001137_t0035") 2022/01/23 06:36:27 DEBUG : yaml: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1008261f124646e3_d20220123_m062839_c001_v0001144_t0014") 2022/01/23 06:36:27 DEBUG : yaml: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1097f73c41a2717b_d20220123_m062836_c001_v0001131_t0043") 2022/01/23 06:36:27 DEBUG : yaml: Deleting (id "4_z2fa28da390cd8b5b7fe80e1b_f1151bd67c6f758be_d20220123_m062832_c001_v0001030_t0000") "./sync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -fast-list" - Finished OK in 10m54.344900708s (try 1/5)