"./sync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -fast-list" - Starting (try 1/5) 2022/03/14 06:37:20 DEBUG : Creating backend with remote "TestB2:rclone-test-kebeher6sewitiq0deporak9" 2022/03/14 06:37:20 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2022/03/14 06:37:21 DEBUG : Creating backend with remote "/tmp/rclone1331303431" === RUN TestPipe --- PASS: TestPipe (0.00s) === RUN TestPipeConcurrent --- PASS: TestPipeConcurrent (0.04s) === 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-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:37:22 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:37:22 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish 2022/03/14 06:37:22 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) --- PASS: TestCopyWithDryRun (1.91s) === RUN TestCopy run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:37:23 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:37:23 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish 2022/03/14 06:37:25 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2022/03/14 06:37:25 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopy (2.54s) === RUN TestCopyMissingDirectory run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:37:25 DEBUG : Creating backend with remote "/non-existing" 2022/03/14 06:37:26 ERROR : Local file system at /non-existing: error reading source root directory: directory not found 2022/03/14 06:37:26 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:37:26 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish --- PASS: TestCopyMissingDirectory (0.50s) === RUN TestCopyNoTraverse run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:37:27 DEBUG : Couldn't decode error response: EOF 2022/03/14 06:37:27 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:37:27 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish 2022/03/14 06:37:28 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2022/03/14 06:37:28 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoTraverse (2.97s) === RUN TestCopyCheckFirst run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:37:29 INFO : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Running all checks before starting transfers 2022/03/14 06:37:29 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:37:29 INFO : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Checks finished, now starting transfers 2022/03/14 06:37:29 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish 2022/03/14 06:37:30 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2022/03/14 06:37:30 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyCheckFirst (2.33s) === RUN TestSyncNoTraverse run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:37:31 ERROR : Ignoring --no-traverse with sync 2022/03/14 06:37:31 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:37:31 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish 2022/03/14 06:37:32 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2022/03/14 06:37:32 INFO : sub dir/hello world: Copied (new) 2022/03/14 06:37:32 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoTraverse (2.76s) === RUN TestCopyWithDepth run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:37:34 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:37:34 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish 2022/03/14 06:37:35 DEBUG : hello world2: sha1 = c0bf117f9c93d02c894fe8ffd6e5cecf3545c209 OK 2022/03/14 06:37:35 INFO : hello world2: Copied (new) --- PASS: TestCopyWithDepth (1.74s) === RUN TestCopyWithFilesFrom run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:37:36 DEBUG : hello world2: Excluded 2022/03/14 06:37:36 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:37:36 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish 2022/03/14 06:37:37 DEBUG : potato2: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2022/03/14 06:37:37 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFrom (2.27s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:37:39 DEBUG : Couldn't decode error response: EOF 2022/03/14 06:37:39 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:37:39 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish 2022/03/14 06:37:39 DEBUG : potato2: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2022/03/14 06:37:39 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFromAndNoTraverse (2.77s) === RUN TestCopyEmptyDirectories run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:37:41 DEBUG : sub dir2: Making directory 2022/03/14 06:37:41 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:37:41 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish 2022/03/14 06:37:42 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2022/03/14 06:37:42 INFO : sub dir/hello world: Copied (new) 2022/03/14 06:37:42 DEBUG : sub dir2: Making directory 2022/03/14 06:37:42 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: copied 1 directories fstest.go:251: Filtering empty directory "sub dir2" --- PASS: TestCopyEmptyDirectories (2.13s) === RUN TestMoveEmptyDirectories run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:37:43 DEBUG : sub dir2: Making directory 2022/03/14 06:37:43 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:37:43 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish 2022/03/14 06:37:44 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2022/03/14 06:37:44 INFO : sub dir/hello world: Copied (new) 2022/03/14 06:37:44 INFO : sub dir/hello world: Deleted 2022/03/14 06:37:44 DEBUG : sub dir: Making directory 2022/03/14 06:37:44 DEBUG : sub dir2: Making directory 2022/03/14 06:37:44 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: copied 2 directories fstest.go:251: Filtering empty directory "sub dir2" --- PASS: TestMoveEmptyDirectories (2.18s) === RUN TestSyncEmptyDirectories run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:37:45 DEBUG : sub dir2: Making directory 2022/03/14 06:37:45 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:37:45 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish 2022/03/14 06:37:46 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2022/03/14 06:37:46 INFO : sub dir/hello world: Copied (new) 2022/03/14 06:37:46 DEBUG : sub dir2: Making directory 2022/03/14 06:37:46 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: copied 1 directories 2022/03/14 06:37:46 DEBUG : Waiting for deletions to finish fstest.go:251: Filtering empty directory "sub dir2" --- PASS: TestSyncEmptyDirectories (1.97s) === RUN TestServerSideCopy run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:37:48 DEBUG : Creating backend with remote "TestB2:rclone-test-poqozij9tesupip7mubadur1" 2022/03/14 06:37:48 DEBUG : Config file has changed externaly - reloading sync_test.go:275: Server side copy (if possible) B2 bucket rclone-test-kebeher6sewitiq0deporak9 -> B2 bucket rclone-test-poqozij9tesupip7mubadur1 2022/03/14 06:37:49 DEBUG : B2 bucket rclone-test-poqozij9tesupip7mubadur1: Waiting for checks to finish 2022/03/14 06:37:49 DEBUG : B2 bucket rclone-test-poqozij9tesupip7mubadur1: Waiting for transfers to finish 2022/03/14 06:37:51 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2022/03/14 06:37:51 INFO : sub dir/hello world: Copied (server-side copy) 2022/03/14 06:37:51 DEBUG : B2 bucket rclone-test-poqozij9tesupip7mubadur1: Purge remote 2022/03/14 06:37:51 DEBUG : sub dir/hello world: Deleting (id "4_zafd2bd43205d8b7b7ff80e1b_f1066c80a0a8c747c_d20220314_m063750_c001_v0001101_t0024") --- PASS: TestServerSideCopy (5.69s) === RUN TestCopyAfterDelete run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:37:53 DEBUG : sub dir/hello world: Clearing upload URL because of error: Post "https://pod-000-1116-06.backblaze.com/b2api/v1/b2_upload_file/4f224d93205d8b7b7ff80e1b/c001_v0001116_t0018": EOF 2022/03/14 06:37:53 DEBUG : pacer: low level retry 1/1 (error Post "https://pod-000-1116-06.backblaze.com/b2api/v1/b2_upload_file/4f224d93205d8b7b7ff80e1b/c001_v0001116_t0018": EOF) 2022/03/14 06:37:53 DEBUG : pacer: Rate limited, increasing sleep to 20ms run.go:283: Retry Put of "sub dir/hello world" to B2 bucket rclone-test-kebeher6sewitiq0deporak9: 1/10 (Post "https://pod-000-1116-06.backblaze.com/b2api/v1/b2_upload_file/4f224d93205d8b7b7ff80e1b/c001_v0001116_t0018": EOF) 2022/03/14 06:37:55 DEBUG : pacer: Reducing sleep to 10ms 2022/03/14 06:37:56 ERROR : : error listing: directory not found 2022/03/14 06:37:56 DEBUG : Local file system at /tmp/rclone1331303431: Making directory 2022/03/14 06:37:57 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:37:57 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish --- PASS: TestCopyAfterDelete (4.64s) === RUN TestCopyRedownload run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:37:58 DEBUG : Local file system at /tmp/rclone1331303431: Waiting for checks to finish 2022/03/14 06:37:58 DEBUG : Local file system at /tmp/rclone1331303431: Waiting for transfers to finish 2022/03/14 06:37:59 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2022/03/14 06:37:59 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyRedownload (2.69s) === RUN TestSyncBasedOnCheckSum run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:38:00 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:38:00 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish 2022/03/14 06:38:03 DEBUG : check sum: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2022/03/14 06:38:03 INFO : check sum: Copied (new) 2022/03/14 06:38:03 DEBUG : Waiting for deletions to finish 2022/03/14 06:38:04 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:38:04 DEBUG : check sum: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2022/03/14 06:38:04 DEBUG : check sum: Size and sha1 of src and dst objects identical 2022/03/14 06:38:04 DEBUG : check sum: Unchanged skipping 2022/03/14 06:38:04 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish 2022/03/14 06:38:04 DEBUG : Waiting for deletions to finish 2022/03/14 06:38:04 INFO : There was nothing to transfer --- PASS: TestSyncBasedOnCheckSum (4.97s) === RUN TestSyncSizeOnly run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:38:05 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:38:05 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish 2022/03/14 06:38:08 DEBUG : sizeonly: sha1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2022/03/14 06:38:08 INFO : sizeonly: Copied (new) 2022/03/14 06:38:08 DEBUG : Waiting for deletions to finish 2022/03/14 06:38:08 DEBUG : sizeonly: Sizes identical 2022/03/14 06:38:08 DEBUG : sizeonly: Unchanged skipping 2022/03/14 06:38:08 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:38:08 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish 2022/03/14 06:38:08 DEBUG : Waiting for deletions to finish 2022/03/14 06:38:08 INFO : There was nothing to transfer --- PASS: TestSyncSizeOnly (5.05s) === RUN TestSyncIgnoreSize run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:38:10 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:38:10 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish 2022/03/14 06:38:11 DEBUG : ignore-size: sha1 = 4a756ca07e9487f482465a99e8286abc86ba4dc7 OK 2022/03/14 06:38:11 INFO : ignore-size: Copied (new) 2022/03/14 06:38:11 DEBUG : Waiting for deletions to finish 2022/03/14 06:38:12 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:38:12 DEBUG : ignore-size: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:38:12 DEBUG : ignore-size: Unchanged skipping 2022/03/14 06:38:12 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish 2022/03/14 06:38:12 DEBUG : Waiting for deletions to finish 2022/03/14 06:38:12 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreSize (2.99s) === RUN TestSyncIgnoreTimes run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:38:14 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:38:14 DEBUG : existing: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:38:14 DEBUG : existing: Unchanged skipping 2022/03/14 06:38:14 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish 2022/03/14 06:38:14 DEBUG : Waiting for deletions to finish 2022/03/14 06:38:14 INFO : There was nothing to transfer 2022/03/14 06:38:14 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2022/03/14 06:38:14 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:38:14 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish 2022/03/14 06:38:15 DEBUG : existing: sha1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2022/03/14 06:38:15 INFO : existing: Copied (replaced existing) 2022/03/14 06:38:15 DEBUG : Waiting for deletions to finish --- PASS: TestSyncIgnoreTimes (3.35s) === RUN TestSyncIgnoreExisting run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:38:17 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:38:17 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish 2022/03/14 06:38:21 DEBUG : existing: sha1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2022/03/14 06:38:21 INFO : existing: Copied (new) 2022/03/14 06:38:21 DEBUG : Waiting for deletions to finish 2022/03/14 06:38:21 DEBUG : existing: Destination exists, skipping 2022/03/14 06:38:21 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:38:21 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish 2022/03/14 06:38:21 DEBUG : Waiting for deletions to finish 2022/03/14 06:38:21 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreExisting (5.82s) === RUN TestSyncIgnoreErrors run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:38:24 DEBUG : d: Making directory fstest.go:251: Filtering empty directory "d" 2022/03/14 06:38:24 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:38:24 DEBUG : c/non empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/03/14 06:38:24 DEBUG : c/non empty space: Unchanged skipping 2022/03/14 06:38:24 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish 2022/03/14 06:38:24 DEBUG : a/potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2022/03/14 06:38:24 INFO : a/potato2: Copied (new) 2022/03/14 06:38:24 DEBUG : Waiting for deletions to finish 2022/03/14 06:38:25 INFO : b/potato: Deleted --- PASS: TestSyncIgnoreErrors (7.04s) === RUN TestSyncAfterChangingModtimeOnly run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:38:31 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:38:31 DEBUG : empty space: Modification times differ by -95456h54m52.624456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499 +0000 UTC 2022/03/14 06:38:31 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2022/03/14 06:38:31 NOTICE: empty space: Skipped update modification time as --dry-run is set (size 1) 2022/03/14 06:38:31 DEBUG : empty space: Unchanged skipping 2022/03/14 06:38:31 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish 2022/03/14 06:38:31 DEBUG : Waiting for deletions to finish 2022/03/14 06:38:31 INFO : There was nothing to transfer 2022/03/14 06:38:31 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:38:31 DEBUG : empty space: Modification times differ by -95456h54m52.624456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499 +0000 UTC 2022/03/14 06:38:31 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2022/03/14 06:38:32 INFO : empty space: Updated modification time in destination 2022/03/14 06:38:32 DEBUG : empty space: Unchanged skipping 2022/03/14 06:38:32 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish 2022/03/14 06:38:32 DEBUG : Waiting for deletions to finish 2022/03/14 06:38:32 INFO : There was nothing to transfer --- PASS: TestSyncAfterChangingModtimeOnly (4.41s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:38:36 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:38:36 DEBUG : empty space: Modification times differ by -95456h54m52.624456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499 +0000 UTC 2022/03/14 06:38:36 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2022/03/14 06:38:36 DEBUG : empty space: Unchanged skipping 2022/03/14 06:38:36 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish 2022/03/14 06:38:36 DEBUG : Waiting for deletions to finish 2022/03/14 06:38:36 INFO : There was nothing to transfer --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (3.81s) === RUN TestSyncDoesntUpdateModtime run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:38:39 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:38:39 DEBUG : foo: Modification times differ by -95456h54m52.624456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499 +0000 UTC 2022/03/14 06:38:39 DEBUG : foo: sha1 = 0beec7b5ea3f0fdbc95d0dd47f3c5bc275da8a33 (Local file system at /tmp/rclone1331303431) 2022/03/14 06:38:39 DEBUG : foo: sha1 = 62cdb7020ff920e5aa642c3d4066950dd1f01f4d (B2 bucket rclone-test-kebeher6sewitiq0deporak9) 2022/03/14 06:38:39 DEBUG : foo: sha1 differ 2022/03/14 06:38:39 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish 2022/03/14 06:38:40 DEBUG : foo: sha1 = 0beec7b5ea3f0fdbc95d0dd47f3c5bc275da8a33 OK 2022/03/14 06:38:40 INFO : foo: Copied (replaced existing) 2022/03/14 06:38:40 DEBUG : Waiting for deletions to finish --- PASS: TestSyncDoesntUpdateModtime (3.82s) === RUN TestSyncAfterAddingAFile run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:38:43 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:38:43 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/03/14 06:38:43 DEBUG : empty space: Unchanged skipping 2022/03/14 06:38:43 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish 2022/03/14 06:38:43 DEBUG : potato: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2022/03/14 06:38:43 INFO : potato: Copied (new) 2022/03/14 06:38:43 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterAddingAFile (4.45s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:38:47 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:38:47 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2022/03/14 06:38:47 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish 2022/03/14 06:38:48 DEBUG : potato: sha1 = 0fd321a1023ac4e1e1054fcc0236b9d0755f722f OK 2022/03/14 06:38:48 INFO : potato: Copied (replaced existing) 2022/03/14 06:38:48 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingFilesSizeOnly (4.23s) === RUN TestSyncAfterChangingContentsOnly run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:38:52 DEBUG : potato: Modification times differ by 119h59m59.876543211s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2011-12-30 12:59:59 +0000 UTC 2022/03/14 06:38:52 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:38:52 DEBUG : potato: sha1 = 1ca5094b3b73f587249eabe179c40a3eec839b1f (Local file system at /tmp/rclone1331303431) 2022/03/14 06:38:52 DEBUG : potato: sha1 = 0fd321a1023ac4e1e1054fcc0236b9d0755f722f (B2 bucket rclone-test-kebeher6sewitiq0deporak9) 2022/03/14 06:38:52 DEBUG : potato: sha1 differ 2022/03/14 06:38:52 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish 2022/03/14 06:38:54 DEBUG : potato: sha1 = 1ca5094b3b73f587249eabe179c40a3eec839b1f OK 2022/03/14 06:38:54 INFO : potato: Copied (replaced existing) 2022/03/14 06:38:54 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingContentsOnly (4.44s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:39:01 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2022/03/14 06:39:01 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:39:01 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/03/14 06:39:01 DEBUG : empty space: Unchanged skipping 2022/03/14 06:39:01 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish 2022/03/14 06:39:01 DEBUG : Waiting for deletions to finish 2022/03/14 06:39:01 NOTICE: potato: Skipped delete as --dry-run is set (size 21) --- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (7.70s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:39:04 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:39:04 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/03/14 06:39:04 DEBUG : empty space: Unchanged skipping 2022/03/14 06:39:04 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish 2022/03/14 06:39:05 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2022/03/14 06:39:05 INFO : potato2: Copied (new) 2022/03/14 06:39:05 DEBUG : Waiting for deletions to finish 2022/03/14 06:39:05 INFO : potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (5.23s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:39:08 DEBUG : d: Making directory 2022/03/14 06:39:08 DEBUG : d/e: Making directory fstest.go:251: Filtering empty directory "d" fstest.go:251: Filtering empty directory "d/e" 2022/03/14 06:39:09 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:39:09 DEBUG : c/non empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/03/14 06:39:09 DEBUG : c/non empty space: Unchanged skipping 2022/03/14 06:39:09 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish 2022/03/14 06:39:09 DEBUG : a/potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2022/03/14 06:39:09 INFO : a/potato2: Copied (new) 2022/03/14 06:39:09 DEBUG : Waiting for deletions to finish 2022/03/14 06:39:10 INFO : b/potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (5.16s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:39:14 DEBUG : d: Making directory fstest.go:251: Filtering empty directory "d" 2022/03/14 06:39:14 DEBUG : c/non empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/03/14 06:39:14 DEBUG : c/non empty space: Unchanged skipping 2022/03/14 06:39:14 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:39:14 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish 2022/03/14 06:39:16 DEBUG : a/potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2022/03/14 06:39:16 INFO : a/potato2: Copied (new) 2022/03/14 06:39:16 ERROR : B2 bucket rclone-test-kebeher6sewitiq0deporak9: not deleting files as there were IO errors 2022/03/14 06:39:16 ERROR : B2 bucket rclone-test-kebeher6sewitiq0deporak9: not deleting directories as there were IO errors fstest.go:251: Filtering empty directory "d" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (13.35s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:39:28 DEBUG : Waiting for deletions to finish 2022/03/14 06:39:28 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/03/14 06:39:28 DEBUG : empty space: Unchanged skipping 2022/03/14 06:39:28 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:39:28 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish 2022/03/14 06:39:30 INFO : potato: Deleted 2022/03/14 06:39:30 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2022/03/14 06:39:30 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteDuring (7.36s) === RUN TestSyncDeleteBefore run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:39:35 DEBUG : Waiting for deletions to finish 2022/03/14 06:39:35 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:39:35 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish 2022/03/14 06:39:36 INFO : potato: Deleted 2022/03/14 06:39:36 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:39:36 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/03/14 06:39:36 DEBUG : empty space: Unchanged skipping 2022/03/14 06:39:36 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish 2022/03/14 06:39:37 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2022/03/14 06:39:37 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteBefore (5.53s) === RUN TestCopyDeleteBefore run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:39:40 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:39:40 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish 2022/03/14 06:39:42 DEBUG : potato2: sha1 = 810274f0df19dab60a894a562b7a8ff04c072623 OK 2022/03/14 06:39:42 INFO : potato2: Copied (new) --- PASS: TestCopyDeleteBefore (4.60s) === RUN TestSyncWithExclude run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:39:45 DEBUG : enormous: Excluded 2022/03/14 06:39:45 DEBUG : potato2: Excluded 2022/03/14 06:39:45 DEBUG : potato2: Excluded from sync (and deletion) 2022/03/14 06:39:45 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/03/14 06:39:45 DEBUG : empty space: Unchanged skipping 2022/03/14 06:39:45 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:39:45 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish 2022/03/14 06:39:45 DEBUG : Waiting for deletions to finish 2022/03/14 06:39:45 INFO : There was nothing to transfer 2022/03/14 06:39:45 DEBUG : enormous: Excluded 2022/03/14 06:39:45 DEBUG : potato2: Excluded 2022/03/14 06:39:46 DEBUG : potato2: Excluded from sync (and deletion) 2022/03/14 06:39:46 DEBUG : Local file system at /tmp/rclone1331303431: Waiting for checks to finish 2022/03/14 06:39:46 DEBUG : empty space: Size and modification time the same (differ by 456.789µs, within tolerance 1ms) 2022/03/14 06:39:46 DEBUG : empty space: Unchanged skipping 2022/03/14 06:39:46 DEBUG : Local file system at /tmp/rclone1331303431: Waiting for transfers to finish 2022/03/14 06:39:46 DEBUG : Waiting for deletions to finish 2022/03/14 06:39:46 INFO : There was nothing to transfer --- PASS: TestSyncWithExclude (5.25s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:39:56 DEBUG : enormous: Excluded 2022/03/14 06:39:56 DEBUG : potato2: Excluded 2022/03/14 06:39:56 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:39:56 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/03/14 06:39:56 DEBUG : empty space: Unchanged skipping 2022/03/14 06:39:56 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish 2022/03/14 06:39:56 DEBUG : Waiting for deletions to finish 2022/03/14 06:39:57 INFO : enormous: Deleted 2022/03/14 06:39:58 INFO : potato2: Deleted 2022/03/14 06:39:58 INFO : There was nothing to transfer 2022/03/14 06:39:58 DEBUG : Local file system at /tmp/rclone1331303431: Waiting for checks to finish 2022/03/14 06:39:58 DEBUG : empty space: Size and modification time the same (differ by 456.789µs, within tolerance 1ms) 2022/03/14 06:39:58 DEBUG : empty space: Unchanged skipping 2022/03/14 06:39:58 DEBUG : Local file system at /tmp/rclone1331303431: Waiting for transfers to finish 2022/03/14 06:39:58 DEBUG : Waiting for deletions to finish 2022/03/14 06:39:58 INFO : enormous: Deleted 2022/03/14 06:39:58 INFO : potato2: Deleted 2022/03/14 06:39:58 INFO : There was nothing to transfer --- PASS: TestSyncWithExcludeAndDeleteExcluded (11.56s) === RUN TestSyncWithUpdateOlder run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:40:05 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:40:05 DEBUG : four: Sizes differ (src 4 vs dst 8) 2022/03/14 06:40:05 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2022/03/14 06:40:05 DEBUG : one: Destination is newer than source, skipping 2022/03/14 06:40:05 DEBUG : three: Sizes identical 2022/03/14 06:40:05 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2022/03/14 06:40:05 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b (Local file system at /tmp/rclone1331303431) 2022/03/14 06:40:05 DEBUG : two: sha1 = b10e0da85e90399abfec36c7376b20aa4596461b (B2 bucket rclone-test-kebeher6sewitiq0deporak9) 2022/03/14 06:40:05 DEBUG : two: sha1 differ 2022/03/14 06:40:05 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish 2022/03/14 06:40:06 DEBUG : five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2022/03/14 06:40:06 INFO : five: Copied (new) 2022/03/14 06:40:06 DEBUG : four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2022/03/14 06:40:06 INFO : four: Copied (replaced existing) 2022/03/14 06:40:08 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2022/03/14 06:40:08 INFO : two: Copied (replaced existing) 2022/03/14 06:40:08 DEBUG : Waiting for deletions to finish 2022/03/14 06:40:08 DEBUG : one: Destination is newer than source, skipping 2022/03/14 06:40:08 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:40:08 DEBUG : four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2022/03/14 06:40:08 DEBUG : four: Size and sha1 of src and dst objects identical 2022/03/14 06:40:08 DEBUG : four: Destination mod time is within 1s of source and files identical, skipping 2022/03/14 06:40:08 DEBUG : three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb (Local file system at /tmp/rclone1331303431) 2022/03/14 06:40:08 DEBUG : three: sha1 = 03731cad13f4cf7b59703c77529381cdec1632e5 (B2 bucket rclone-test-kebeher6sewitiq0deporak9) 2022/03/14 06:40:08 DEBUG : five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2022/03/14 06:40:08 DEBUG : three: sha1 differ 2022/03/14 06:40:08 DEBUG : five: Size and sha1 of src and dst objects identical 2022/03/14 06:40:08 DEBUG : three: Destination mod time is within 1s of source but files differ, transferring 2022/03/14 06:40:08 DEBUG : five: Destination mod time is within 1s of source and files identical, skipping 2022/03/14 06:40:08 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2022/03/14 06:40:08 DEBUG : two: Size and sha1 of src and dst objects identical 2022/03/14 06:40:08 DEBUG : two: Destination mod time is within 1s of source and files identical, skipping 2022/03/14 06:40:08 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish 2022/03/14 06:40:10 DEBUG : three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2022/03/14 06:40:10 INFO : three: Copied (replaced existing) 2022/03/14 06:40:10 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithUpdateOlder (21.64s) === 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-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" sync_test.go:1058: Can track renames: true 2022/03/14 06:40:22 INFO : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Making map for --track-renames 2022/03/14 06:40:22 INFO : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Finished making map for --track-renames 2022/03/14 06:40:22 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:40:22 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for renames to finish 2022/03/14 06:40:22 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish 2022/03/14 06:40:23 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2022/03/14 06:40:23 INFO : potato: Copied (new) 2022/03/14 06:40:25 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2022/03/14 06:40:25 INFO : yam: Copied (new) 2022/03/14 06:40:25 DEBUG : Waiting for deletions to finish 2022/03/14 06:40:26 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:40:26 DEBUG : potato: Unchanged skipping 2022/03/14 06:40:26 INFO : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Making map for --track-renames 2022/03/14 06:40:26 INFO : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Finished making map for --track-renames 2022/03/14 06:40:26 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:40:26 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for renames to finish 2022/03/14 06:40:26 DEBUG : Couldn't decode error response: EOF 2022/03/14 06:40:27 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2022/03/14 06:40:27 INFO : yam: Copied (server-side copy) to: yaml 2022/03/14 06:40:29 INFO : yam: Deleted 2022/03/14 06:40:29 INFO : yaml: Renamed from "yam" 2022/03/14 06:40:29 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish 2022/03/14 06:40:29 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenames (9.26s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" sync_test.go:1127: Can track renames: true 2022/03/14 06:40:31 INFO : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Making map for --track-renames 2022/03/14 06:40:31 INFO : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Finished making map for --track-renames 2022/03/14 06:40:31 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:40:31 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for renames to finish 2022/03/14 06:40:31 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish 2022/03/14 06:40:32 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2022/03/14 06:40:32 INFO : yam: Copied (new) 2022/03/14 06:40:32 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2022/03/14 06:40:32 INFO : potato: Copied (new) 2022/03/14 06:40:32 DEBUG : Waiting for deletions to finish 2022/03/14 06:40:33 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:40:33 DEBUG : potato: Unchanged skipping 2022/03/14 06:40:33 INFO : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Making map for --track-renames 2022/03/14 06:40:33 INFO : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Finished making map for --track-renames 2022/03/14 06:40:33 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:40:33 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for renames to finish 2022/03/14 06:40:33 DEBUG : Couldn't decode error response: EOF 2022/03/14 06:40:34 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2022/03/14 06:40:34 INFO : yam: Copied (server-side copy) to: yaml 2022/03/14 06:40:35 INFO : yam: Deleted 2022/03/14 06:40:35 INFO : yaml: Renamed from "yam" 2022/03/14 06:40:35 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish 2022/03/14 06:40:35 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenamesStrategyModtime (5.56s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" sync_test.go:1163: Can track renames: true 2022/03/14 06:40:37 INFO : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Making map for --track-renames 2022/03/14 06:40:37 INFO : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Finished making map for --track-renames 2022/03/14 06:40:37 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:40:37 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for renames to finish 2022/03/14 06:40:37 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish 2022/03/14 06:40:37 DEBUG : sub/yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2022/03/14 06:40:37 INFO : sub/yam: Copied (new) 2022/03/14 06:40:41 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2022/03/14 06:40:41 INFO : potato: Copied (new) 2022/03/14 06:40:41 DEBUG : Waiting for deletions to finish 2022/03/14 06:40:41 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:40:41 DEBUG : potato: Unchanged skipping 2022/03/14 06:40:41 INFO : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Making map for --track-renames 2022/03/14 06:40:41 INFO : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Finished making map for --track-renames 2022/03/14 06:40:41 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:40:41 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for renames to finish 2022/03/14 06:40:42 DEBUG : Couldn't decode error response: EOF 2022/03/14 06:40:43 DEBUG : sub/yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2022/03/14 06:40:43 INFO : sub/yam: Copied (server-side copy) to: yam 2022/03/14 06:40:43 INFO : sub/yam: Deleted 2022/03/14 06:40:43 INFO : yam: Renamed from "sub/yam" 2022/03/14 06:40:43 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish 2022/03/14 06:40:43 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenamesStrategyLeaf (9.94s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:40:47 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:40:47 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish 2022/03/14 06:40:48 DEBUG : nested/sub dir/file: sha1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK 2022/03/14 06:40:48 INFO : nested/sub dir/file: Copied (new) 2022/03/14 06:40:48 INFO : nested/sub dir/file: Deleted 2022/03/14 06:40:48 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2022/03/14 06:40:48 INFO : sub dir/hello world: Copied (new) 2022/03/14 06:40:48 INFO : sub dir/hello world: Deleted 2022/03/14 06:40:48 INFO : sub dir: Removing directory 2022/03/14 06:40:48 INFO : nested/sub dir: Removing directory 2022/03/14 06:40:48 INFO : nested: Removing directory 2022/03/14 06:40:48 DEBUG : Local file system at /tmp/rclone1331303431: deleted 3 directories --- PASS: TestMoveWithDeleteEmptySrcDirs (3.32s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:40:50 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:40:50 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish 2022/03/14 06:40:51 DEBUG : nested/sub dir/file: sha1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK 2022/03/14 06:40:51 INFO : nested/sub dir/file: Copied (new) 2022/03/14 06:40:51 INFO : nested/sub dir/file: Deleted 2022/03/14 06:40:52 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2022/03/14 06:40:52 INFO : sub dir/hello world: Copied (new) 2022/03/14 06:40:52 INFO : sub dir/hello world: Deleted --- PASS: TestMoveWithoutDeleteEmptySrcDirs (5.16s) === RUN TestMoveWithIgnoreExisting run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:40:55 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:40:55 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish 2022/03/14 06:40:56 DEBUG : existing: sha1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2022/03/14 06:40:56 INFO : existing: Copied (new) 2022/03/14 06:40:56 INFO : existing: Deleted 2022/03/14 06:40:57 DEBUG : existing-b: sha1 = 321f6b7e8bf7f29aab89c5e41a555b1b0baa41a9 OK 2022/03/14 06:40:57 INFO : existing-b: Copied (new) 2022/03/14 06:40:57 INFO : existing-b: Deleted 2022/03/14 06:40:57 DEBUG : existing: Destination exists, skipping 2022/03/14 06:40:57 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set 2022/03/14 06:40:57 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:40:57 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish 2022/03/14 06:40:57 INFO : There was nothing to transfer --- PASS: TestMoveWithIgnoreExisting (4.20s) === RUN TestServerSideMove run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:40:59 DEBUG : Creating backend with remote "TestB2:rclone-test-tiluzuq3fokerub2huwozev1" sync_test.go:1215: Server side move (if possible) B2 bucket rclone-test-kebeher6sewitiq0deporak9 -> B2 bucket rclone-test-tiluzuq3fokerub2huwozev1 2022/03/14 06:41:17 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms) 2022/03/14 06:41:17 DEBUG : empty space: Unchanged skipping 2022/03/14 06:41:17 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2022/03/14 06:41:17 DEBUG : B2 bucket rclone-test-tiluzuq3fokerub2huwozev1: Waiting for checks to finish 2022/03/14 06:41:18 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2022/03/14 06:41:18 INFO : potato2: Copied (server-side copy) 2022/03/14 06:41:18 INFO : empty space: Deleted 2022/03/14 06:41:18 DEBUG : B2 bucket rclone-test-tiluzuq3fokerub2huwozev1: Waiting for transfers to finish 2022/03/14 06:41:18 DEBUG : potato3: sha1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2022/03/14 06:41:18 INFO : potato3: Copied (server-side copy) 2022/03/14 06:41:19 INFO : potato2: Deleted 2022/03/14 06:41:19 INFO : potato3: Deleted 2022/03/14 06:41:20 DEBUG : Creating backend with remote "TestB2:rclone-test-vucopur6cidikaw8wajamis5" 2022/03/14 06:41:21 DEBUG : B2 bucket rclone-test-vucopur6cidikaw8wajamis5: Waiting for checks to finish 2022/03/14 06:41:21 DEBUG : B2 bucket rclone-test-vucopur6cidikaw8wajamis5: Waiting for transfers to finish 2022/03/14 06:41:22 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2022/03/14 06:41:22 INFO : empty space: Copied (server-side copy) 2022/03/14 06:41:23 DEBUG : potato3: sha1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2022/03/14 06:41:23 INFO : potato3: Copied (server-side copy) 2022/03/14 06:41:23 INFO : potato3: Deleted 2022/03/14 06:41:24 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2022/03/14 06:41:24 INFO : potato2: Copied (server-side copy) 2022/03/14 06:41:24 INFO : potato2: Deleted 2022/03/14 06:41:25 INFO : empty space: Deleted 2022/03/14 06:41:25 DEBUG : B2 bucket rclone-test-vucopur6cidikaw8wajamis5: Purge remote 2022/03/14 06:41:25 DEBUG : empty space: Deleting (id "4_z4f92edf3208d8b7b7ff80e1b_f1196b73089869d67_d20220314_m064121_c001_v0001131_t0037") 2022/03/14 06:41:25 DEBUG : potato2: Deleting (id "4_z4f92edf3208d8b7b7ff80e1b_f1053945046666d1e_d20220314_m064123_c001_v0001100_t0054") 2022/03/14 06:41:25 DEBUG : potato3: Deleting (id "4_z4f92edf3208d8b7b7ff80e1b_f10343fddc8d93a53_d20220314_m064122_c001_v0001115_t0029") 2022/03/14 06:41:26 DEBUG : B2 bucket rclone-test-tiluzuq3fokerub2huwozev1: Purge remote 2022/03/14 06:41:26 DEBUG : empty space: Deleting (id "4_zbf42bd83208d8b7b7ff80e1b_f109a9239dc44b0a0_d20220314_m064122_c001_v0001104_t0057") 2022/03/14 06:41:26 DEBUG : empty space: Deleting (id "4_zbf42bd83208d8b7b7ff80e1b_f112769b05cbb5a7c_d20220314_m064111_c001_v0001104_t0034") 2022/03/14 06:41:26 DEBUG : potato2: Deleting (id "4_zbf42bd83208d8b7b7ff80e1b_f100fd38188a02f8b_d20220314_m064124_c001_v0001102_t0052") 2022/03/14 06:41:26 DEBUG : potato2: Deleting (id "4_zbf42bd83208d8b7b7ff80e1b_f1162f37ffb813f8d_d20220314_m064117_c001_v0001040_t0019") 2022/03/14 06:41:26 DEBUG : potato3: Deleting (id "4_zbf42bd83208d8b7b7ff80e1b_f101df85965044907_d20220314_m064123_c001_v0001130_t0019") 2022/03/14 06:41:26 DEBUG : potato3: Deleting (id "4_zbf42bd83208d8b7b7ff80e1b_f1163c9b90cdd1f9c_d20220314_m064117_c001_v0001146_t0020") 2022/03/14 06:41:26 DEBUG : potato3: Deleting (id "4_zbf42bd83208d8b7b7ff80e1b_f112769b05cbb5acb_d20220314_m064112_c001_v0001104_t0029") --- PASS: TestServerSideMove (28.03s) === RUN TestServerSideMoveWithFilter run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:41:27 DEBUG : Creating backend with remote "TestB2:rclone-test-siwukad4cikupol3vilutod8" sync_test.go:1215: Server side move (if possible) B2 bucket rclone-test-kebeher6sewitiq0deporak9 -> B2 bucket rclone-test-siwukad4cikupol3vilutod8 2022/03/14 06:41:38 DEBUG : empty space: Excluded from sync (and deletion) 2022/03/14 06:41:38 DEBUG : empty space: Excluded from sync (and deletion) 2022/03/14 06:41:38 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2022/03/14 06:41:38 DEBUG : B2 bucket rclone-test-siwukad4cikupol3vilutod8: Waiting for checks to finish 2022/03/14 06:41:38 DEBUG : B2 bucket rclone-test-siwukad4cikupol3vilutod8: Waiting for transfers to finish 2022/03/14 06:41:38 DEBUG : potato3: sha1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2022/03/14 06:41:38 INFO : potato3: Copied (server-side copy) 2022/03/14 06:41:39 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2022/03/14 06:41:39 INFO : potato2: Copied (server-side copy) 2022/03/14 06:41:42 INFO : potato3: Deleted 2022/03/14 06:41:43 INFO : potato2: Deleted 2022/03/14 06:41:43 DEBUG : Creating backend with remote "TestB2:rclone-test-yumedut3paxaduz1rixihas3" 2022/03/14 06:41:44 DEBUG : empty space: Excluded from sync (and deletion) 2022/03/14 06:41:44 DEBUG : B2 bucket rclone-test-yumedut3paxaduz1rixihas3: Waiting for checks to finish 2022/03/14 06:41:44 DEBUG : B2 bucket rclone-test-yumedut3paxaduz1rixihas3: Waiting for transfers to finish 2022/03/14 06:41:45 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2022/03/14 06:41:45 INFO : potato2: Copied (server-side copy) 2022/03/14 06:41:46 INFO : potato2: Deleted 2022/03/14 06:42:01 DEBUG : potato3: sha1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2022/03/14 06:42:01 INFO : potato3: Copied (server-side copy) 2022/03/14 06:42:01 INFO : potato3: Deleted 2022/03/14 06:42:02 DEBUG : B2 bucket rclone-test-yumedut3paxaduz1rixihas3: Purge remote 2022/03/14 06:42:02 DEBUG : potato2: Deleting (id "4_zdf024da3209d8b7b7ff80e1b_f1132198363915037_d20220314_m064145_c001_v0001098_t0002") 2022/03/14 06:42:02 DEBUG : potato3: Deleting (id "4_zdf024da3209d8b7b7ff80e1b_f117e34bdffad12d6_d20220314_m064200_c001_v0001098_t0052") 2022/03/14 06:42:03 DEBUG : B2 bucket rclone-test-siwukad4cikupol3vilutod8: Purge remote 2022/03/14 06:42:04 DEBUG : empty space: Deleting (id "4_zefa22d93209d8b7b7ff80e1b_f118aa2d45d570c71_d20220314_m064137_c001_v0001130_t0052") 2022/03/14 06:42:04 DEBUG : potato2: Deleting (id "4_zefa22d93209d8b7b7ff80e1b_f110892068f5d8f12_d20220314_m064145_c001_v0001145_t0000") 2022/03/14 06:42:04 DEBUG : potato2: Deleting (id "4_zefa22d93209d8b7b7ff80e1b_f118712df3085904b_d20220314_m064139_c001_v0001102_t0023") 2022/03/14 06:42:04 DEBUG : potato3: Deleting (id "4_zefa22d93209d8b7b7ff80e1b_f1031f5aabc10b9a9_d20220314_m064201_c001_v0001103_t0046") 2022/03/14 06:42:04 DEBUG : potato3: Deleting (id "4_zefa22d93209d8b7b7ff80e1b_f1088143c052e1cfc_d20220314_m064138_c001_v0001100_t0041") 2022/03/14 06:42:04 DEBUG : potato3: Deleting (id "4_zefa22d93209d8b7b7ff80e1b_f118aa2d45d570c75_d20220314_m064137_c001_v0001130_t0038") --- PASS: TestServerSideMoveWithFilter (39.61s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:42:07 DEBUG : Creating backend with remote "TestB2:rclone-test-reharoz0hudusoq6kigexuy8" 2022/03/14 06:42:13 DEBUG : tomatoDir: Making directory sync_test.go:1215: Server side move (if possible) B2 bucket rclone-test-kebeher6sewitiq0deporak9 -> B2 bucket rclone-test-reharoz0hudusoq6kigexuy8 2022/03/14 06:42:22 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms) 2022/03/14 06:42:22 DEBUG : empty space: Unchanged skipping 2022/03/14 06:42:22 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2022/03/14 06:42:22 DEBUG : B2 bucket rclone-test-reharoz0hudusoq6kigexuy8: Waiting for checks to finish 2022/03/14 06:42:23 DEBUG : potato3: sha1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2022/03/14 06:42:23 INFO : potato3: Copied (server-side copy) 2022/03/14 06:42:23 INFO : potato3: Deleted 2022/03/14 06:42:23 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2022/03/14 06:42:23 INFO : potato2: Copied (server-side copy) 2022/03/14 06:42:23 INFO : empty space: Deleted 2022/03/14 06:42:23 DEBUG : B2 bucket rclone-test-reharoz0hudusoq6kigexuy8: Waiting for transfers to finish 2022/03/14 06:42:27 INFO : potato2: Deleted 2022/03/14 06:42:28 DEBUG : Creating backend with remote "TestB2:rclone-test-kubejam7fekixon3qodaxub0" 2022/03/14 06:42:28 DEBUG : tomatoDir: Making directory 2022/03/14 06:42:28 DEBUG : pacer: low level retry 1/10 (error Post "https://api001.backblazeb2.com/b2api/v1/b2_list_file_names": EOF) 2022/03/14 06:42:28 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2022/03/14 06:42:29 DEBUG : pacer: Reducing sleep to 10ms 2022/03/14 06:42:29 DEBUG : B2 bucket rclone-test-kubejam7fekixon3qodaxub0: Waiting for checks to finish 2022/03/14 06:42:29 DEBUG : B2 bucket rclone-test-kubejam7fekixon3qodaxub0: Waiting for transfers to finish 2022/03/14 06:42:30 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2022/03/14 06:42:30 INFO : potato2: Copied (server-side copy) 2022/03/14 06:42:30 DEBUG : potato3: sha1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2022/03/14 06:42:30 INFO : potato3: Copied (server-side copy) 2022/03/14 06:42:31 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2022/03/14 06:42:31 INFO : empty space: Copied (server-side copy) 2022/03/14 06:42:31 INFO : potato3: Deleted 2022/03/14 06:42:31 INFO : potato2: Deleted 2022/03/14 06:42:32 INFO : empty space: Deleted 2022/03/14 06:42:33 DEBUG : B2 bucket rclone-test-kubejam7fekixon3qodaxub0: Purge remote 2022/03/14 06:42:33 DEBUG : empty space: Deleting (id "4_zdf22fd83209d8b7b7ff80e1b_f116009d9f5f9f64c_d20220314_m064229_c001_v0001106_t0025") 2022/03/14 06:42:33 DEBUG : potato2: Deleting (id "4_zdf22fd83209d8b7b7ff80e1b_f1025995420d41311_d20220314_m064229_c001_v0001150_t0032") 2022/03/14 06:42:33 DEBUG : potato3: Deleting (id "4_zdf22fd83209d8b7b7ff80e1b_f110a84eeb4e865bd_d20220314_m064230_c001_v0001116_t0007") 2022/03/14 06:42:34 DEBUG : B2 bucket rclone-test-reharoz0hudusoq6kigexuy8: Purge remote 2022/03/14 06:42:34 DEBUG : empty space: Deleting (id "4_z0ff2bdd3209d8b7b7ff80e1b_f1167a0f711e1ae9d_d20220314_m064231_c001_v0001104_t0008") 2022/03/14 06:42:34 DEBUG : empty space: Deleting (id "4_z0ff2bdd3209d8b7b7ff80e1b_f1166b4ec7e1e3bd1_d20220314_m064215_c001_v0001099_t0033") 2022/03/14 06:42:34 DEBUG : potato2: Deleting (id "4_z0ff2bdd3209d8b7b7ff80e1b_f1102e02604053e3b_d20220314_m064230_c001_v0001104_t0056") 2022/03/14 06:42:34 DEBUG : potato2: Deleting (id "4_z0ff2bdd3209d8b7b7ff80e1b_f1003be2b668e3b2a_d20220314_m064223_c001_v0001116_t0042") 2022/03/14 06:42:34 DEBUG : potato3: Deleting (id "4_z0ff2bdd3209d8b7b7ff80e1b_f1041bdc59c8b4bdd_d20220314_m064230_c001_v0001100_t0008") 2022/03/14 06:42:34 DEBUG : potato3: Deleting (id "4_z0ff2bdd3209d8b7b7ff80e1b_f118ad8faaa767411_d20220314_m064223_c001_v0001032_t0053") 2022/03/14 06:42:34 DEBUG : potato3: Deleting (id "4_z0ff2bdd3209d8b7b7ff80e1b_f1166b4ec7e1e3c42_d20220314_m064220_c001_v0001099_t0023") --- PASS: TestServerSideMoveDeleteEmptySourceDirs (28.09s) === RUN TestServerSideMoveOverlap run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:42:35 DEBUG : Creating backend with remote "TestB2:rclone-test-kebeher6sewitiq0deporak9/rclone-move-test" 2022/03/14 06:42:36 DEBUG : Couldn't decode error response: EOF --- PASS: TestServerSideMoveOverlap (5.08s) === RUN TestSyncOverlap run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:42:40 DEBUG : Creating backend with remote "TestB2:rclone-test-kebeher6sewitiq0deporak9/rclone-sync-test" 2022/03/14 06:42:41 DEBUG : Couldn't decode error response: EOF --- PASS: TestSyncOverlap (0.75s) === RUN TestSyncCompareDest run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:42:41 DEBUG : Creating backend with remote "TestB2:rclone-test-kebeher6sewitiq0deporak9/dst" 2022/03/14 06:42:41 DEBUG : Couldn't decode error response: EOF 2022/03/14 06:42:41 DEBUG : Creating backend with remote "TestB2:rclone-test-kebeher6sewitiq0deporak9/CompareDest" 2022/03/14 06:42:42 DEBUG : Couldn't decode error response: EOF 2022/03/14 06:42:42 DEBUG : Couldn't decode error response: EOF 2022/03/14 06:42:42 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9 path dst: Waiting for checks to finish 2022/03/14 06:42:42 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9 path dst: Waiting for transfers to finish 2022/03/14 06:42:45 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/03/14 06:42:45 INFO : one: Copied (new) 2022/03/14 06:42:45 DEBUG : Waiting for deletions to finish 2022/03/14 06:42:46 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9 path dst: Waiting for checks to finish 2022/03/14 06:42:46 DEBUG : Couldn't decode error response: EOF 2022/03/14 06:42:46 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/03/14 06:42:46 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9 path dst: Waiting for transfers to finish 2022/03/14 06:42:48 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2022/03/14 06:42:48 INFO : one: Copied (replaced existing) 2022/03/14 06:42:48 DEBUG : Waiting for deletions to finish 2022/03/14 06:42:58 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9 path dst: Waiting for checks to finish 2022/03/14 06:42:58 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/03/14 06:42:58 DEBUG : one: Destination found in --compare-dest, skipping 2022/03/14 06:42:58 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9 path dst: Waiting for transfers to finish 2022/03/14 06:42:58 DEBUG : Waiting for deletions to finish 2022/03/14 06:42:58 INFO : There was nothing to transfer 2022/03/14 06:43:03 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/03/14 06:43:03 DEBUG : two: Destination found in --compare-dest, skipping 2022/03/14 06:43:03 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9 path dst: Waiting for checks to finish 2022/03/14 06:43:03 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/03/14 06:43:03 DEBUG : one: Destination found in --compare-dest, skipping 2022/03/14 06:43:03 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9 path dst: Waiting for transfers to finish 2022/03/14 06:43:03 DEBUG : Waiting for deletions to finish 2022/03/14 06:43:03 INFO : There was nothing to transfer 2022/03/14 06:43:04 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/03/14 06:43:04 DEBUG : two: Destination found in --compare-dest, skipping 2022/03/14 06:43:04 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9 path dst: Waiting for checks to finish 2022/03/14 06:43:04 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/03/14 06:43:04 DEBUG : one: Destination found in --compare-dest, skipping 2022/03/14 06:43:04 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9 path dst: Waiting for transfers to finish 2022/03/14 06:43:04 DEBUG : Waiting for deletions to finish 2022/03/14 06:43:04 INFO : There was nothing to transfer 2022/03/14 06:43:04 DEBUG : two: Modification times differ by -119h59m59.877s: 2011-12-30 12:59:59 +0000 UTC, 2011-12-25 12:59:59.123 +0000 UTC 2022/03/14 06:43:04 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2022/03/14 06:43:04 DEBUG : two: Destination found in --compare-dest, skipping 2022/03/14 06:43:04 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9 path dst: Waiting for checks to finish 2022/03/14 06:43:05 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/03/14 06:43:05 DEBUG : one: Destination found in --compare-dest, skipping 2022/03/14 06:43:05 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9 path dst: Waiting for transfers to finish 2022/03/14 06:43:05 DEBUG : Waiting for deletions to finish 2022/03/14 06:43:05 INFO : There was nothing to transfer 2022/03/14 06:43:05 DEBUG : two: Sizes differ (src 5 vs dst 3) 2022/03/14 06:43:05 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9 path dst: Waiting for checks to finish 2022/03/14 06:43:06 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/03/14 06:43:06 DEBUG : one: Destination found in --compare-dest, skipping 2022/03/14 06:43:06 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9 path dst: Waiting for transfers to finish 2022/03/14 06:43:08 DEBUG : two: sha1 = a4be8877ad88f3109a6411985a1f359e10e2d930 OK 2022/03/14 06:43:08 INFO : two: Copied (new) 2022/03/14 06:43:08 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCompareDest (32.93s) === RUN TestSyncMultipleCompareDest run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:43:16 DEBUG : Creating backend with remote "TestB2:rclone-test-kebeher6sewitiq0deporak9/dest" 2022/03/14 06:43:17 DEBUG : Couldn't decode error response: EOF 2022/03/14 06:43:17 DEBUG : Creating backend with remote "TestB2:rclone-test-kebeher6sewitiq0deporak9/pre-dest1" 2022/03/14 06:43:18 DEBUG : Couldn't decode error response: EOF 2022/03/14 06:43:18 DEBUG : Creating backend with remote "TestB2:rclone-test-kebeher6sewitiq0deporak9/pre-dest2" 2022/03/14 06:43:18 DEBUG : Couldn't decode error response: EOF 2022/03/14 06:43:19 DEBUG : 1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:43:19 DEBUG : 1: Destination found in --compare-dest, skipping 2022/03/14 06:43:19 DEBUG : Couldn't decode error response: EOF 2022/03/14 06:43:19 DEBUG : 2: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:43:19 DEBUG : 2: Destination found in --compare-dest, skipping 2022/03/14 06:43:19 DEBUG : Couldn't decode error response: EOF 2022/03/14 06:43:19 DEBUG : Couldn't decode error response: EOF 2022/03/14 06:43:19 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9 path dest: Waiting for checks to finish 2022/03/14 06:43:19 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9 path dest: Waiting for transfers to finish 2022/03/14 06:43:21 DEBUG : 3: sha1 = 77de68daecd823babbb58edb1c8e14d7106e83bb OK 2022/03/14 06:43:21 INFO : 3: Copied (new) 2022/03/14 06:43:21 DEBUG : Waiting for deletions to finish --- PASS: TestSyncMultipleCompareDest (9.38s) === RUN TestSyncCopyDest run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:43:23 DEBUG : Creating backend with remote "TestB2:rclone-test-kebeher6sewitiq0deporak9/dst" 2022/03/14 06:43:24 DEBUG : Couldn't decode error response: EOF 2022/03/14 06:43:24 DEBUG : Creating backend with remote "TestB2:rclone-test-kebeher6sewitiq0deporak9/CopyDest" 2022/03/14 06:43:24 DEBUG : Couldn't decode error response: EOF 2022/03/14 06:43:25 DEBUG : Couldn't decode error response: EOF 2022/03/14 06:43:25 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9 path dst: Waiting for checks to finish 2022/03/14 06:43:25 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9 path dst: Waiting for transfers to finish 2022/03/14 06:43:28 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/03/14 06:43:28 INFO : one: Copied (new) 2022/03/14 06:43:28 DEBUG : Waiting for deletions to finish 2022/03/14 06:43:28 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9 path dst: Waiting for checks to finish 2022/03/14 06:43:29 DEBUG : Couldn't decode error response: EOF 2022/03/14 06:43:29 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/03/14 06:43:29 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9 path dst: Waiting for transfers to finish 2022/03/14 06:43:30 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2022/03/14 06:43:30 INFO : one: Copied (replaced existing) 2022/03/14 06:43:30 DEBUG : Waiting for deletions to finish 2022/03/14 06:43:37 DEBUG : Creating backend with remote "TestB2:rclone-test-kebeher6sewitiq0deporak9/BackupDir" 2022/03/14 06:43:39 DEBUG : Couldn't decode error response: EOF 2022/03/14 06:43:39 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9 path dst: Waiting for checks to finish 2022/03/14 06:43:39 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/03/14 06:43:39 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/03/14 06:43:39 DEBUG : Couldn't decode error response: EOF 2022/03/14 06:43:41 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/03/14 06:43:41 INFO : one: Copied (server-side copy) 2022/03/14 06:43:44 INFO : one: Deleted 2022/03/14 06:43:46 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2022/03/14 06:43:46 INFO : one: Copied (server-side copy) 2022/03/14 06:43:46 DEBUG : one: Destination found in --copy-dest, using server-side copy 2022/03/14 06:43:46 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9 path dst: Waiting for transfers to finish 2022/03/14 06:43:46 DEBUG : Waiting for deletions to finish 2022/03/14 06:43:48 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/03/14 06:43:50 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2022/03/14 06:43:50 INFO : two: Copied (server-side copy) 2022/03/14 06:43:50 DEBUG : two: Destination found in --copy-dest, using server-side copy 2022/03/14 06:43:50 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9 path dst: Waiting for checks to finish 2022/03/14 06:43:50 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/03/14 06:43:50 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/03/14 06:43:50 DEBUG : one: Unchanged skipping 2022/03/14 06:43:50 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9 path dst: Waiting for transfers to finish 2022/03/14 06:43:50 DEBUG : Waiting for deletions to finish 2022/03/14 06:43:50 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9 path dst: Waiting for checks to finish 2022/03/14 06:43:51 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/03/14 06:43:51 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/03/14 06:43:51 DEBUG : one: Unchanged skipping 2022/03/14 06:43:51 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/03/14 06:43:51 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/03/14 06:43:51 DEBUG : two: Unchanged skipping 2022/03/14 06:43:51 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9 path dst: Waiting for transfers to finish 2022/03/14 06:43:51 DEBUG : Waiting for deletions to finish 2022/03/14 06:43:51 INFO : There was nothing to transfer 2022/03/14 06:43:54 DEBUG : three: Sizes differ (src 7 vs dst 5) 2022/03/14 06:43:54 DEBUG : three: Destination not found in --copy-dest 2022/03/14 06:43:54 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9 path dst: Waiting for checks to finish 2022/03/14 06:43:54 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/03/14 06:43:54 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/03/14 06:43:54 DEBUG : one: Unchanged skipping 2022/03/14 06:43:54 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/03/14 06:43:54 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/03/14 06:43:54 DEBUG : two: Unchanged skipping 2022/03/14 06:43:54 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9 path dst: Waiting for transfers to finish 2022/03/14 06:43:54 DEBUG : three: sha1 = 370e613335f3cf2f19b2a2d35a86402f55c659f8 OK 2022/03/14 06:43:54 INFO : three: Copied (new) 2022/03/14 06:43:54 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCopyDest (38.26s) === RUN TestSyncBackupDir run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:44:10 DEBUG : Creating backend with remote "TestB2:rclone-test-kebeher6sewitiq0deporak9/dst" 2022/03/14 06:44:11 DEBUG : Couldn't decode error response: EOF 2022/03/14 06:44:11 DEBUG : Creating backend with remote "TestB2:rclone-test-kebeher6sewitiq0deporak9/backup" 2022/03/14 06:44:11 DEBUG : Couldn't decode error response: EOF 2022/03/14 06:44:12 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/03/14 06:44:12 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9 path dst: Waiting for checks to finish 2022/03/14 06:44:12 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:44:12 DEBUG : two: Unchanged skipping 2022/03/14 06:44:12 DEBUG : Couldn't decode error response: EOF 2022/03/14 06:44:16 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/03/14 06:44:16 INFO : one: Copied (server-side copy) 2022/03/14 06:44:16 INFO : one: Deleted 2022/03/14 06:44:16 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9 path dst: Waiting for transfers to finish 2022/03/14 06:44:19 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2022/03/14 06:44:19 INFO : one: Copied (new) 2022/03/14 06:44:19 DEBUG : Waiting for deletions to finish 2022/03/14 06:44:20 DEBUG : Couldn't decode error response: EOF 2022/03/14 06:44:21 DEBUG : three.txt: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2022/03/14 06:44:21 INFO : three.txt: Copied (server-side copy) 2022/03/14 06:44:21 INFO : three.txt: Deleted 2022/03/14 06:44:21 INFO : three.txt: Moved into backup dir 2022/03/14 06:44:23 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/03/14 06:44:23 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:44:23 DEBUG : two: Unchanged skipping 2022/03/14 06:44:23 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9 path dst: Waiting for checks to finish 2022/03/14 06:44:24 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2022/03/14 06:44:24 INFO : one: Copied (server-side copy) 2022/03/14 06:44:26 INFO : one: Deleted 2022/03/14 06:44:26 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9 path dst: Waiting for transfers to finish 2022/03/14 06:44:28 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2022/03/14 06:44:28 INFO : one: Copied (new) 2022/03/14 06:44:28 DEBUG : Waiting for deletions to finish 2022/03/14 06:44:29 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2022/03/14 06:44:29 INFO : three.txt: Copied (server-side copy) 2022/03/14 06:44:29 INFO : three.txt: Deleted 2022/03/14 06:44:29 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (34.72s) === RUN TestSyncBackupDirWithSuffix run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:44:41 DEBUG : Creating backend with remote "TestB2:rclone-test-kebeher6sewitiq0deporak9/dst" 2022/03/14 06:44:42 DEBUG : Couldn't decode error response: EOF 2022/03/14 06:44:42 DEBUG : Creating backend with remote "TestB2:rclone-test-kebeher6sewitiq0deporak9/backup" 2022/03/14 06:44:43 DEBUG : Couldn't decode error response: EOF 2022/03/14 06:44:43 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/03/14 06:44:43 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9 path dst: Waiting for checks to finish 2022/03/14 06:44:43 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:44:43 DEBUG : two: Unchanged skipping 2022/03/14 06:44:43 DEBUG : Couldn't decode error response: EOF 2022/03/14 06:44:44 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/03/14 06:44:44 INFO : one: Copied (server-side copy) to: one.bak 2022/03/14 06:44:45 INFO : one: Deleted 2022/03/14 06:44:45 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9 path dst: Waiting for transfers to finish 2022/03/14 06:44:46 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2022/03/14 06:44:46 INFO : one: Copied (new) 2022/03/14 06:44:46 DEBUG : Waiting for deletions to finish 2022/03/14 06:44:47 DEBUG : Couldn't decode error response: EOF 2022/03/14 06:44:47 DEBUG : three.txt: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2022/03/14 06:44:47 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/03/14 06:44:47 INFO : three.txt: Deleted 2022/03/14 06:44:47 INFO : three.txt: Moved into backup dir 2022/03/14 06:44:50 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9 path dst: Waiting for checks to finish 2022/03/14 06:44:50 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/03/14 06:44:50 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:44:50 DEBUG : two: Unchanged skipping 2022/03/14 06:44:51 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2022/03/14 06:44:51 INFO : one: Copied (server-side copy) to: one.bak 2022/03/14 06:44:52 INFO : one: Deleted 2022/03/14 06:44:52 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9 path dst: Waiting for transfers to finish 2022/03/14 06:44:53 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2022/03/14 06:44:53 INFO : one: Copied (new) 2022/03/14 06:44:53 DEBUG : Waiting for deletions to finish 2022/03/14 06:44:54 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2022/03/14 06:44:54 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/03/14 06:44:54 INFO : three.txt: Deleted 2022/03/14 06:44:54 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (23.38s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:45:06 DEBUG : Creating backend with remote "TestB2:rclone-test-kebeher6sewitiq0deporak9/dst" 2022/03/14 06:45:08 DEBUG : Couldn't decode error response: EOF 2022/03/14 06:45:08 DEBUG : Creating backend with remote "TestB2:rclone-test-kebeher6sewitiq0deporak9/backup" 2022/03/14 06:45:08 DEBUG : Couldn't decode error response: EOF 2022/03/14 06:45:09 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9 path dst: Waiting for checks to finish 2022/03/14 06:45:09 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/03/14 06:45:09 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:45:09 DEBUG : two: Unchanged skipping 2022/03/14 06:45:09 DEBUG : Couldn't decode error response: EOF 2022/03/14 06:45:10 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/03/14 06:45:10 INFO : one: Copied (server-side copy) to: one-2019-01-01 2022/03/14 06:45:11 INFO : one: Deleted 2022/03/14 06:45:11 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9 path dst: Waiting for transfers to finish 2022/03/14 06:45:12 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2022/03/14 06:45:12 INFO : one: Copied (new) 2022/03/14 06:45:12 DEBUG : Waiting for deletions to finish 2022/03/14 06:45:12 DEBUG : Couldn't decode error response: EOF 2022/03/14 06:45:15 DEBUG : three.txt: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2022/03/14 06:45:15 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2022/03/14 06:45:16 INFO : three.txt: Deleted 2022/03/14 06:45:16 INFO : three.txt: Moved into backup dir 2022/03/14 06:45:18 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9 path dst: Waiting for checks to finish 2022/03/14 06:45:18 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:45:18 DEBUG : two: Unchanged skipping 2022/03/14 06:45:18 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/03/14 06:45:19 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2022/03/14 06:45:19 INFO : one: Copied (server-side copy) to: one-2019-01-01 2022/03/14 06:45:20 INFO : one: Deleted 2022/03/14 06:45:20 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9 path dst: Waiting for transfers to finish 2022/03/14 06:45:21 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2022/03/14 06:45:21 INFO : one: Copied (new) 2022/03/14 06:45:21 DEBUG : Waiting for deletions to finish 2022/03/14 06:45:25 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2022/03/14 06:45:25 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2022/03/14 06:45:26 INFO : three.txt: Deleted 2022/03/14 06:45:26 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (29.65s) === RUN TestSyncBackupDirSuffixOnly run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:45:36 DEBUG : Creating backend with remote "TestB2:rclone-test-kebeher6sewitiq0deporak9/dst" 2022/03/14 06:45:37 DEBUG : Couldn't decode error response: EOF 2022/03/14 06:45:38 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/03/14 06:45:38 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:45:38 DEBUG : two: Unchanged skipping 2022/03/14 06:45:38 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9 path dst: Waiting for checks to finish 2022/03/14 06:45:38 DEBUG : Couldn't decode error response: EOF 2022/03/14 06:45:39 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/03/14 06:45:39 INFO : one: Copied (server-side copy) to: one.bak 2022/03/14 06:45:40 INFO : one: Deleted 2022/03/14 06:45:40 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9 path dst: Waiting for transfers to finish 2022/03/14 06:45:41 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2022/03/14 06:45:41 INFO : one: Copied (new) 2022/03/14 06:45:41 DEBUG : Waiting for deletions to finish 2022/03/14 06:45:41 DEBUG : Couldn't decode error response: EOF 2022/03/14 06:45:42 DEBUG : three.txt: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2022/03/14 06:45:42 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/03/14 06:45:43 INFO : three.txt: Deleted 2022/03/14 06:45:43 INFO : three.txt: Moved into backup dir 2022/03/14 06:45:45 DEBUG : one.bak: Excluded from sync (and deletion) 2022/03/14 06:45:45 DEBUG : three.txt.bak: Excluded from sync (and deletion) 2022/03/14 06:45:45 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/03/14 06:45:45 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9 path dst: Waiting for checks to finish 2022/03/14 06:45:45 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:45:45 DEBUG : two: Unchanged skipping 2022/03/14 06:45:47 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2022/03/14 06:45:47 INFO : one: Copied (server-side copy) to: one.bak 2022/03/14 06:45:47 INFO : one: Deleted 2022/03/14 06:45:47 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9 path dst: Waiting for transfers to finish 2022/03/14 06:45:48 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2022/03/14 06:45:48 INFO : one: Copied (new) 2022/03/14 06:45:48 DEBUG : Waiting for deletions to finish 2022/03/14 06:45:49 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2022/03/14 06:45:49 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/03/14 06:45:50 INFO : three.txt: Deleted 2022/03/14 06:45:50 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirSuffixOnly (26.02s) === RUN TestSyncSuffix run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:46:03 DEBUG : Creating backend with remote "TestB2:rclone-test-kebeher6sewitiq0deporak9/dst" 2022/03/14 06:46:04 DEBUG : Couldn't decode error response: EOF 2022/03/14 06:46:04 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/03/14 06:46:04 DEBUG : Couldn't decode error response: EOF 2022/03/14 06:46:06 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/03/14 06:46:06 INFO : one: Copied (server-side copy) to: one.bak 2022/03/14 06:46:07 INFO : one: Deleted 2022/03/14 06:46:08 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2022/03/14 06:46:08 INFO : one: Copied (new) 2022/03/14 06:46:08 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:46:08 DEBUG : two: Unchanged skipping 2022/03/14 06:46:08 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2022/03/14 06:46:08 DEBUG : Couldn't decode error response: EOF 2022/03/14 06:46:09 DEBUG : three.txt: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2022/03/14 06:46:09 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/03/14 06:46:11 INFO : three.txt: Deleted 2022/03/14 06:46:12 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2022/03/14 06:46:12 INFO : three.txt: Copied (new) 2022/03/14 06:46:12 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/03/14 06:46:20 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2022/03/14 06:46:20 INFO : one: Copied (server-side copy) to: one.bak 2022/03/14 06:46:21 INFO : one: Deleted 2022/03/14 06:46:22 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2022/03/14 06:46:22 INFO : one: Copied (new) 2022/03/14 06:46:23 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:46:23 DEBUG : two: Unchanged skipping 2022/03/14 06:46:23 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2022/03/14 06:46:23 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2022/03/14 06:46:23 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/03/14 06:46:24 INFO : three.txt: Deleted 2022/03/14 06:46:25 DEBUG : three.txt: sha1 = 476e1707cbd7f0497a7dc4d44f0b07b8566ffc30 OK 2022/03/14 06:46:25 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (35.90s) === RUN TestSyncSuffixKeepExtension run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:46:37 DEBUG : Creating backend with remote "TestB2:rclone-test-kebeher6sewitiq0deporak9/dst" 2022/03/14 06:46:39 DEBUG : Couldn't decode error response: EOF 2022/03/14 06:46:39 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/03/14 06:46:39 DEBUG : Couldn't decode error response: EOF 2022/03/14 06:46:41 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/03/14 06:46:41 INFO : one: Copied (server-side copy) to: one-2019-01-01 2022/03/14 06:46:46 INFO : one: Deleted 2022/03/14 06:46:48 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2022/03/14 06:46:48 INFO : one: Copied (new) 2022/03/14 06:46:49 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:46:49 DEBUG : two: Unchanged skipping 2022/03/14 06:46:49 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2022/03/14 06:46:49 DEBUG : Couldn't decode error response: EOF 2022/03/14 06:46:50 DEBUG : three.txt: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2022/03/14 06:46:50 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2022/03/14 06:46:51 INFO : three.txt: Deleted 2022/03/14 06:46:52 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2022/03/14 06:46:52 INFO : three.txt: Copied (new) 2022/03/14 06:46:53 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/03/14 06:46:54 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2022/03/14 06:46:54 INFO : one: Copied (server-side copy) to: one-2019-01-01 2022/03/14 06:46:54 INFO : one: Deleted 2022/03/14 06:46:56 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2022/03/14 06:46:56 INFO : one: Copied (new) 2022/03/14 06:46:56 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:46:56 DEBUG : two: Unchanged skipping 2022/03/14 06:46:56 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2022/03/14 06:46:57 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2022/03/14 06:46:57 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2022/03/14 06:46:58 INFO : three.txt: Deleted 2022/03/14 06:47:00 DEBUG : three.txt: sha1 = 476e1707cbd7f0497a7dc4d44f0b07b8566ffc30 OK 2022/03/14 06:47:00 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (33.02s) === RUN TestSyncUTFNorm run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:47:05 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2022/03/14 06:47:05 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:47:05 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish 2022/03/14 06:47:08 DEBUG : Testêé: sha1 = a54d88e06612d820bc3be72877c74f257b561b19 OK 2022/03/14 06:47:08 INFO : Testêé: Copied (replaced existing) to: Testêé 2022/03/14 06:47:08 DEBUG : Waiting for deletions to finish --- PASS: TestSyncUTFNorm (5.00s) === RUN TestSyncImmutable run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:47:09 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:47:09 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish 2022/03/14 06:47:10 DEBUG : existing: sha1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2022/03/14 06:47:10 INFO : existing: Copied (new) 2022/03/14 06:47:10 DEBUG : Waiting for deletions to finish 2022/03/14 06:47:11 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2022/03/14 06:47:11 ERROR : existing: Source and destination exist but do not match: immutable file modified 2022/03/14 06:47:11 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:47:11 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish 2022/03/14 06:47:11 ERROR : B2 bucket rclone-test-kebeher6sewitiq0deporak9: not deleting files as there were IO errors 2022/03/14 06:47:11 ERROR : B2 bucket rclone-test-kebeher6sewitiq0deporak9: not deleting directories as there were IO errors --- PASS: TestSyncImmutable (3.53s) === RUN TestSyncIgnoreCase run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:47:14 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:47:14 DEBUG : existing: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:47:14 DEBUG : existing: Unchanged skipping 2022/03/14 06:47:14 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish 2022/03/14 06:47:14 DEBUG : Waiting for deletions to finish 2022/03/14 06:47:14 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreCase (2.84s) === RUN TestMaxTransfer === RUN TestMaxTransfer/Hard run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" sync_test.go:1989: This test only runs on local === RUN TestMaxTransfer/Soft run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" sync_test.go:1989: This test only runs on local === RUN TestMaxTransfer/Cautious run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", 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-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:47:46 DEBUG : both7: Clearing upload URL because of error: Post "https://pod-000-1101-17.backblaze.com/b2api/v1/b2_upload_file/4f224d93205d8b7b7ff80e1b/c001_v0001101_t0029": EOF 2022/03/14 06:47:46 DEBUG : pacer: low level retry 1/1 (error Post "https://pod-000-1101-17.backblaze.com/b2api/v1/b2_upload_file/4f224d93205d8b7b7ff80e1b/c001_v0001101_t0029": EOF) 2022/03/14 06:47:46 DEBUG : pacer: Rate limited, increasing sleep to 20ms run.go:283: Retry Put of "both7" to B2 bucket rclone-test-kebeher6sewitiq0deporak9: 1/10 (Post "https://pod-000-1101-17.backblaze.com/b2api/v1/b2_upload_file/4f224d93205d8b7b7ff80e1b/c001_v0001101_t0029": EOF) 2022/03/14 06:47:51 DEBUG : pacer: Reducing sleep to 10ms 2022/03/14 06:47:51 DEBUG : only7: Clearing upload URL because of error: Post "https://pod-000-1113-16.backblaze.com/b2api/v1/b2_upload_file/4f224d93205d8b7b7ff80e1b/c001_v0001113_t0044": EOF 2022/03/14 06:47:51 DEBUG : pacer: low level retry 1/1 (error Post "https://pod-000-1113-16.backblaze.com/b2api/v1/b2_upload_file/4f224d93205d8b7b7ff80e1b/c001_v0001113_t0044": EOF) 2022/03/14 06:47:51 DEBUG : pacer: Rate limited, increasing sleep to 20ms run.go:283: Retry Put of "only7" to B2 bucket rclone-test-kebeher6sewitiq0deporak9: 1/10 (Post "https://pod-000-1113-16.backblaze.com/b2api/v1/b2_upload_file/4f224d93205d8b7b7ff80e1b/c001_v0001113_t0044": EOF) 2022/03/14 06:47:56 DEBUG : pacer: Reducing sleep to 10ms 2022/03/14 06:49:05 DEBUG : both1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:49:05 DEBUG : both1: Unchanged skipping 2022/03/14 06:49:05 DEBUG : both12: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:49:05 DEBUG : both12: Unchanged skipping 2022/03/14 06:49:05 DEBUG : both13: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:49:05 DEBUG : both13: Unchanged skipping 2022/03/14 06:49:05 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:49:05 DEBUG : both14: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:49:05 DEBUG : both14: Unchanged skipping 2022/03/14 06:49:05 DEBUG : both15: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:49:05 DEBUG : both15: Unchanged skipping 2022/03/14 06:49:05 DEBUG : both16: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:49:05 DEBUG : both16: Unchanged skipping 2022/03/14 06:49:05 DEBUG : both17: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:49:05 DEBUG : both17: Unchanged skipping 2022/03/14 06:49:05 DEBUG : both18: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:49:05 DEBUG : both18: Unchanged skipping 2022/03/14 06:49:05 DEBUG : both11: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:49:05 DEBUG : both11: Unchanged skipping 2022/03/14 06:49:05 DEBUG : both2: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:49:05 DEBUG : both2: Unchanged skipping 2022/03/14 06:49:05 DEBUG : both3: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:49:05 DEBUG : both3: Unchanged skipping 2022/03/14 06:49:05 DEBUG : both4: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:49:05 DEBUG : both4: Unchanged skipping 2022/03/14 06:49:05 DEBUG : both5: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:49:05 DEBUG : both5: Unchanged skipping 2022/03/14 06:49:05 DEBUG : both6: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:49:05 DEBUG : both6: Unchanged skipping 2022/03/14 06:49:05 DEBUG : both7: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:49:05 DEBUG : both7: Unchanged skipping 2022/03/14 06:49:05 DEBUG : both19: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:49:05 DEBUG : both8: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:49:05 DEBUG : both8: Unchanged skipping 2022/03/14 06:49:05 DEBUG : both19: Unchanged skipping 2022/03/14 06:49:05 DEBUG : both9: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:49:05 DEBUG : both9: Unchanged skipping 2022/03/14 06:49:05 DEBUG : both0: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:49:05 DEBUG : both0: Unchanged skipping 2022/03/14 06:49:05 DEBUG : both10: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:49:05 DEBUG : both10: Unchanged skipping 2022/03/14 06:49:05 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish 2022/03/14 06:49:05 DEBUG : Waiting for deletions to finish 2022/03/14 06:49:05 INFO : only2: Deleted 2022/03/14 06:49:06 INFO : only13: Deleted 2022/03/14 06:49:06 INFO : only16: Deleted 2022/03/14 06:49:06 INFO : only19: Deleted 2022/03/14 06:49:07 INFO : only5: Deleted 2022/03/14 06:49:07 INFO : only3: Deleted 2022/03/14 06:49:08 INFO : only9: Deleted 2022/03/14 06:49:08 INFO : only0: Deleted 2022/03/14 06:49:08 INFO : only10: Deleted 2022/03/14 06:49:08 INFO : only6: Deleted 2022/03/14 06:49:08 INFO : only12: Deleted 2022/03/14 06:49:09 INFO : only11: Deleted 2022/03/14 06:49:09 INFO : only17: Deleted 2022/03/14 06:49:09 INFO : only15: Deleted 2022/03/14 06:49:09 INFO : only18: Deleted 2022/03/14 06:49:09 INFO : only7: Deleted 2022/03/14 06:49:10 INFO : only4: Deleted 2022/03/14 06:49:11 INFO : only8: Deleted 2022/03/14 06:49:12 INFO : only14: Deleted 2022/03/14 06:49:12 INFO : only1: Deleted 2022/03/14 06:49:12 INFO : There was nothing to transfer --- PASS: TestSyncConcurrentDelete (140.60s) === RUN TestSyncConcurrentTruncate run.go:181: Remote "B2 bucket rclone-test-kebeher6sewitiq0deporak9", Local "Local file system at /tmp/rclone1331303431", Modify Window "1ms" 2022/03/14 06:50:22 DEBUG : only9: Clearing upload URL because of error: no tomes available (503 service_unavailable) 2022/03/14 06:50:22 DEBUG : pacer: low level retry 1/1 (error no tomes available (503 service_unavailable)) 2022/03/14 06:50:22 DEBUG : pacer: Rate limited, increasing sleep to 1s run.go:283: Retry Put of "only9" to B2 bucket rclone-test-kebeher6sewitiq0deporak9: 1/10 (no tomes available (503 service_unavailable)) 2022/03/14 06:50:24 DEBUG : pacer: Reducing sleep to 500ms 2022/03/14 06:50:26 DEBUG : pacer: Reducing sleep to 250ms 2022/03/14 06:50:27 DEBUG : pacer: Reducing sleep to 125ms 2022/03/14 06:50:27 DEBUG : pacer: Reducing sleep to 62.5ms 2022/03/14 06:50:28 DEBUG : pacer: Reducing sleep to 31.25ms 2022/03/14 06:50:28 DEBUG : pacer: Reducing sleep to 15.625ms 2022/03/14 06:50:29 DEBUG : pacer: Reducing sleep to 10ms 2022/03/14 06:50:48 DEBUG : both10: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:50:48 DEBUG : both1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:50:48 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for checks to finish 2022/03/14 06:50:48 DEBUG : both1: Unchanged skipping 2022/03/14 06:50:48 DEBUG : both12: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:50:48 DEBUG : both12: Unchanged skipping 2022/03/14 06:50:48 DEBUG : both13: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:50:48 DEBUG : both13: Unchanged skipping 2022/03/14 06:50:48 DEBUG : both11: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:50:48 DEBUG : both14: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:50:48 DEBUG : both11: Unchanged skipping 2022/03/14 06:50:48 DEBUG : both10: Unchanged skipping 2022/03/14 06:50:48 DEBUG : both15: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:50:48 DEBUG : both0: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:50:48 DEBUG : both15: Unchanged skipping 2022/03/14 06:50:48 DEBUG : both0: Unchanged skipping 2022/03/14 06:50:48 DEBUG : both17: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:50:48 DEBUG : both18: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:50:48 DEBUG : both17: Unchanged skipping 2022/03/14 06:50:48 DEBUG : both18: Unchanged skipping 2022/03/14 06:50:48 DEBUG : both2: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:50:48 DEBUG : both2: Unchanged skipping 2022/03/14 06:50:48 DEBUG : both3: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:50:48 DEBUG : both3: Unchanged skipping 2022/03/14 06:50:48 DEBUG : both4: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:50:48 DEBUG : both4: Unchanged skipping 2022/03/14 06:50:48 DEBUG : both5: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:50:48 DEBUG : both5: Unchanged skipping 2022/03/14 06:50:48 DEBUG : both6: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:50:48 DEBUG : both6: Unchanged skipping 2022/03/14 06:50:48 DEBUG : both7: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:50:48 DEBUG : both7: Unchanged skipping 2022/03/14 06:50:48 DEBUG : both8: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:50:48 DEBUG : both8: Unchanged skipping 2022/03/14 06:50:48 DEBUG : both9: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:50:48 DEBUG : both9: Unchanged skipping 2022/03/14 06:50:48 DEBUG : only0: Sizes differ (src 0 vs dst 6) 2022/03/14 06:50:48 DEBUG : only1: Sizes differ (src 0 vs dst 6) 2022/03/14 06:50:48 DEBUG : only10: Sizes differ (src 0 vs dst 6) 2022/03/14 06:50:48 DEBUG : only11: Sizes differ (src 0 vs dst 6) 2022/03/14 06:50:48 DEBUG : only12: Sizes differ (src 0 vs dst 6) 2022/03/14 06:50:48 DEBUG : only13: Sizes differ (src 0 vs dst 6) 2022/03/14 06:50:48 DEBUG : only14: Sizes differ (src 0 vs dst 6) 2022/03/14 06:50:48 DEBUG : only15: Sizes differ (src 0 vs dst 6) 2022/03/14 06:50:48 DEBUG : only16: Sizes differ (src 0 vs dst 6) 2022/03/14 06:50:48 DEBUG : only17: Sizes differ (src 0 vs dst 6) 2022/03/14 06:50:48 DEBUG : only18: Sizes differ (src 0 vs dst 6) 2022/03/14 06:50:48 DEBUG : only19: Sizes differ (src 0 vs dst 6) 2022/03/14 06:50:48 DEBUG : only2: Sizes differ (src 0 vs dst 6) 2022/03/14 06:50:48 DEBUG : only3: Sizes differ (src 0 vs dst 6) 2022/03/14 06:50:48 DEBUG : only4: Sizes differ (src 0 vs dst 6) 2022/03/14 06:50:48 DEBUG : only5: Sizes differ (src 0 vs dst 6) 2022/03/14 06:50:48 DEBUG : only6: Sizes differ (src 0 vs dst 6) 2022/03/14 06:50:48 DEBUG : only7: Sizes differ (src 0 vs dst 6) 2022/03/14 06:50:48 DEBUG : only8: Sizes differ (src 0 vs dst 6) 2022/03/14 06:50:48 DEBUG : only9: Sizes differ (src 0 vs dst 6) 2022/03/14 06:50:48 DEBUG : both14: Unchanged skipping 2022/03/14 06:50:48 DEBUG : both16: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:50:48 DEBUG : both16: Unchanged skipping 2022/03/14 06:50:48 DEBUG : both19: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/14 06:50:48 DEBUG : both19: Unchanged skipping 2022/03/14 06:50:48 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Waiting for transfers to finish 2022/03/14 06:50:48 DEBUG : only0: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/03/14 06:50:48 INFO : only0: Copied (replaced existing) 2022/03/14 06:50:49 DEBUG : only12: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/03/14 06:50:49 INFO : only12: Copied (replaced existing) 2022/03/14 06:50:49 DEBUG : only11: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/03/14 06:50:49 INFO : only11: Copied (replaced existing) 2022/03/14 06:50:49 DEBUG : only1: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/03/14 06:50:49 INFO : only1: Copied (replaced existing) 2022/03/14 06:50:49 DEBUG : only14: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/03/14 06:50:49 INFO : only14: Copied (replaced existing) 2022/03/14 06:50:49 DEBUG : only16: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/03/14 06:50:49 INFO : only16: Copied (replaced existing) 2022/03/14 06:50:50 DEBUG : only17: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/03/14 06:50:50 INFO : only17: Copied (replaced existing) 2022/03/14 06:50:50 DEBUG : only13: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/03/14 06:50:50 INFO : only13: Copied (replaced existing) 2022/03/14 06:50:50 DEBUG : only15: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/03/14 06:50:50 INFO : only15: Copied (replaced existing) 2022/03/14 06:50:50 DEBUG : only18: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/03/14 06:50:50 INFO : only18: Copied (replaced existing) 2022/03/14 06:50:50 DEBUG : only3: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/03/14 06:50:50 INFO : only3: Copied (replaced existing) 2022/03/14 06:50:50 DEBUG : only2: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/03/14 06:50:50 INFO : only2: Copied (replaced existing) 2022/03/14 06:50:51 DEBUG : only4: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/03/14 06:50:51 INFO : only4: Copied (replaced existing) 2022/03/14 06:50:51 DEBUG : only5: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/03/14 06:50:51 INFO : only5: Copied (replaced existing) 2022/03/14 06:50:51 DEBUG : only19: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/03/14 06:50:51 INFO : only19: Copied (replaced existing) 2022/03/14 06:50:51 DEBUG : only6: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/03/14 06:50:51 INFO : only6: Copied (replaced existing) 2022/03/14 06:50:52 DEBUG : only8: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/03/14 06:50:52 INFO : only8: Copied (replaced existing) 2022/03/14 06:50:52 DEBUG : only9: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/03/14 06:50:52 INFO : only9: Copied (replaced existing) 2022/03/14 06:50:52 DEBUG : only10: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/03/14 06:50:52 INFO : only10: Copied (replaced existing) 2022/03/14 06:50:53 DEBUG : only7: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/03/14 06:50:53 INFO : only7: Copied (replaced existing) 2022/03/14 06:50:53 DEBUG : Waiting for deletions to finish --- PASS: TestSyncConcurrentTruncate (121.66s) PASS 2022/03/14 06:51:39 DEBUG : B2 bucket rclone-test-kebeher6sewitiq0deporak9: Purge remote 2022/03/14 06:51:39 DEBUG : BackupDir/one: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116f793924af90d2_d20220314_m064355_c001_v0001101_t0038") 2022/03/14 06:51:39 DEBUG : BackupDir/one: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f11402182b1bcdbed_d20220314_m064341_c001_v0001161_t0039") 2022/03/14 06:51:39 DEBUG : CompareDest/one: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f118352c2278e33cb_d20220314_m064308_c001_v0001103_t0038") 2022/03/14 06:51:39 DEBUG : CompareDest/one: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f117458308dc24112_d20220314_m064255_c001_v0001101_t0051") 2022/03/14 06:51:39 DEBUG : CompareDest/two: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f112769b05cbb6d90_d20220314_m064309_c001_v0001104_t0040") 2022/03/14 06:51:39 DEBUG : CompareDest/two: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab882867d27_d20220314_m064301_c001_v0001104_t0007") 2022/03/14 06:51:39 DEBUG : CopyDest/one: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1160ea276535fe58_d20220314_m064356_c001_v0001157_t0015") 2022/03/14 06:51:39 DEBUG : CopyDest/one: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c89c37_d20220314_m064336_c001_v0001113_t0012") 2022/03/14 06:51:39 DEBUG : CopyDest/three: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f11429ad1930c90f7_d20220314_m064356_c001_v0001165_t0049") 2022/03/14 06:51:40 DEBUG : CopyDest/three: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab882868582_d20220314_m064352_c001_v0001104_t0036") 2022/03/14 06:51:40 DEBUG : CopyDest/two: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f111fb9222d3b4310_d20220314_m064357_c001_v0001098_t0027") 2022/03/14 06:51:40 DEBUG : CopyDest/two: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f117458308dc244b5_d20220314_m064347_c001_v0001101_t0052") 2022/03/14 06:51:40 DEBUG : EXISTING: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f10562a8113910817_d20220314_m064715_c001_v0001115_t0054") 2022/03/14 06:51:40 DEBUG : EXISTING: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f117458308dc25440_d20220314_m064713_c001_v0001101_t0018") 2022/03/14 06:51:40 DEBUG : Testêé: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f10609a706bb6c16c_d20220314_m064708_c001_v0001106_t0024") 2022/03/14 06:51:40 DEBUG : Testêé: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab88286a502_d20220314_m064706_c001_v0001104_t0026") 2022/03/14 06:51:40 DEBUG : Testêé: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f117458308dc25389_d20220314_m064705_c001_v0001101_t0001") 2022/03/14 06:51:40 DEBUG : a/potato2: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f10212709d53de932_d20220314_m063916_c001_v0001115_t0021") 2022/03/14 06:51:40 DEBUG : a/potato2: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c89644_d20220314_m063915_c001_v0001113_t0010") 2022/03/14 06:51:40 DEBUG : a/potato2: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f117d3ad7e5561d2b_d20220314_m063911_c001_v0001113_t0012") 2022/03/14 06:51:40 DEBUG : a/potato2: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c8962e_d20220314_m063909_c001_v0001113_t0039") 2022/03/14 06:51:40 DEBUG : a/potato2: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1049f11d3c961bfd_d20220314_m063825_c001_v0001109_t0007") 2022/03/14 06:51:40 DEBUG : a/potato2: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c89510_d20220314_m063824_c001_v0001113_t0003") 2022/03/14 06:51:41 DEBUG : b/potato: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f110150b9d3697aec_d20220314_m063917_c001_v0001157_t0015") 2022/03/14 06:51:41 DEBUG : b/potato: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c8963c_d20220314_m063913_c001_v0001113_t0036") 2022/03/14 06:51:41 DEBUG : b/potato: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f113d41c715d63769_d20220314_m063910_c001_v0001101_t0052") 2022/03/14 06:51:41 DEBUG : b/potato: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c8961f_d20220314_m063907_c001_v0001113_t0034") 2022/03/14 06:51:41 DEBUG : b/potato: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1083bd2a05d62f45_d20220314_m063825_c001_v0001136_t0004") 2022/03/14 06:51:41 DEBUG : b/potato: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c89501_d20220314_m063822_c001_v0001113_t0034") 2022/03/14 06:51:41 DEBUG : backup/one: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f10212709d53e1381_d20220314_m064429_c001_v0001115_t0042") 2022/03/14 06:51:41 DEBUG : backup/one: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1154e4797251bc1c_d20220314_m064423_c001_v0001137_t0004") 2022/03/14 06:51:41 DEBUG : backup/one: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1102e02604054df7_d20220314_m064413_c001_v0001104_t0015") 2022/03/14 06:51:41 DEBUG : backup/one-2019-01-01: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116df43bb1886770_d20220314_m064527_c001_v0001154_t0011") 2022/03/14 06:51:41 DEBUG : backup/one-2019-01-01: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f119723eae426463b_d20220314_m064519_c001_v0001120_t0046") 2022/03/14 06:51:41 DEBUG : backup/one-2019-01-01: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1041bdc59c8b5737_d20220314_m064510_c001_v0001100_t0016") 2022/03/14 06:51:41 DEBUG : backup/one.bak: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1075322d520374b3_d20220314_m064455_c001_v0001137_t0048") 2022/03/14 06:51:41 DEBUG : backup/one.bak: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f10384f32f0122418_d20220314_m064451_c001_v0001099_t0029") 2022/03/14 06:51:41 DEBUG : backup/one.bak: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1148a10de4745510_d20220314_m064444_c001_v0001103_t0015") 2022/03/14 06:51:41 DEBUG : backup/three-2019-01-01.txt: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f11611e5f3b32a943_d20220314_m064527_c001_v0001100_t0004") 2022/03/14 06:51:41 DEBUG : backup/three-2019-01-01.txt: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1013d307d2d656b5_d20220314_m064521_c001_v0001032_t0018") 2022/03/14 06:51:41 DEBUG : backup/three-2019-01-01.txt: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1045dcd4e076f032_d20220314_m064512_c001_v0001104_t0036") 2022/03/14 06:51:41 DEBUG : backup/three.txt: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1125e831ff410213_d20220314_m064433_c001_v0001030_t0040") 2022/03/14 06:51:42 DEBUG : backup/three.txt: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1053de68b0881864_d20220314_m064428_c001_v0001098_t0023") 2022/03/14 06:51:42 DEBUG : backup/three.txt: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f10390e5c7af7792b_d20220314_m064420_c001_v0001116_t0001") 2022/03/14 06:51:42 DEBUG : backup/three.txt.bak: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116009d9f5f9ff9b_d20220314_m064455_c001_v0001106_t0030") 2022/03/14 06:51:42 DEBUG : backup/three.txt.bak: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116105cd8f0da78c_d20220314_m064454_c001_v0001136_t0052") 2022/03/14 06:51:42 DEBUG : backup/three.txt.bak: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f111fb9222d3b4670_d20220314_m064447_c001_v0001098_t0059") 2022/03/14 06:51:42 DEBUG : both0: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f108890d6eace8f9c_d20220314_m065053_c001_v0001120_t0027") 2022/03/14 06:51:42 DEBUG : both0: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab88286bf5f_d20220314_m064938_c001_v0001104_t0035") 2022/03/14 06:51:42 DEBUG : both0: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116f3fc57a0a91f5_d20220314_m064913_c001_v0001098_t0005") 2022/03/14 06:51:42 DEBUG : both0: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab88286a702_d20220314_m064717_c001_v0001104_t0056") 2022/03/14 06:51:42 DEBUG : both1: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f106d5f17243cbd03_d20220314_m065054_c001_v0001105_t0035") 2022/03/14 06:51:42 DEBUG : both1: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab88286c041_d20220314_m064943_c001_v0001104_t0038") 2022/03/14 06:51:42 DEBUG : both1: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f10562c4bff84395e_d20220314_m064913_c001_v0001117_t0048") 2022/03/14 06:51:42 DEBUG : both1: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f117458308dc254de_d20220314_m064722_c001_v0001101_t0037") 2022/03/14 06:51:42 DEBUG : both10: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f115d7b608eb9ded1_d20220314_m065054_c001_v0001021_t0003") 2022/03/14 06:51:42 DEBUG : both10: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f104a2a6a62098938_d20220314_m065026_c001_v0001113_t0035") 2022/03/14 06:51:42 DEBUG : both10: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f105bac42f68667b3_d20220314_m064914_c001_v0001104_t0053") 2022/03/14 06:51:42 DEBUG : both10: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab88286af3e_d20220314_m064804_c001_v0001104_t0000") 2022/03/14 06:51:42 DEBUG : both11: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f110b2d0a33cb7879_d20220314_m065055_c001_v0001144_t0045") 2022/03/14 06:51:42 DEBUG : both11: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f104a2a6a62098940_d20220314_m065027_c001_v0001113_t0021") 2022/03/14 06:51:42 DEBUG : both11: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f111d08e789bfa27b_d20220314_m064917_c001_v0001023_t0034") 2022/03/14 06:51:42 DEBUG : both11: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab88286b098_d20220314_m064812_c001_v0001104_t0022") 2022/03/14 06:51:43 DEBUG : both12: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f108c0217e2fb9a29_d20220314_m065058_c001_v0001023_t0012") 2022/03/14 06:51:43 DEBUG : both12: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f104a2a6a62098956_d20220314_m065028_c001_v0001113_t0045") 2022/03/14 06:51:43 DEBUG : both12: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1093f5e9aae1504d_d20220314_m064918_c001_v0001157_t0033") 2022/03/14 06:51:43 DEBUG : both12: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab88286b1aa_d20220314_m064818_c001_v0001104_t0041") 2022/03/14 06:51:43 DEBUG : both13: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f11718bcd5fc7ec6f_d20220314_m065059_c001_v0001137_t0059") 2022/03/14 06:51:43 DEBUG : both13: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f104a2a6a62098963_d20220314_m065032_c001_v0001113_t0013") 2022/03/14 06:51:43 DEBUG : both13: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1179597cc5592ace_d20220314_m064918_c001_v0001043_t0038") 2022/03/14 06:51:43 DEBUG : both13: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab88286b2cc_d20220314_m064824_c001_v0001104_t0026") 2022/03/14 06:51:43 DEBUG : both14: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1167a0f711e20488_d20220314_m065059_c001_v0001104_t0045") 2022/03/14 06:51:43 DEBUG : both14: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f104a2a6a6209896f_d20220314_m065033_c001_v0001113_t0034") 2022/03/14 06:51:43 DEBUG : both14: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100537e21c81120b_d20220314_m064919_c001_v0001099_t0009") 2022/03/14 06:51:43 DEBUG : both14: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab88286b470_d20220314_m064834_c001_v0001104_t0013") 2022/03/14 06:51:43 DEBUG : both15: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f11977b6ad0f0c872_d20220314_m065101_c001_v0001161_t0059") 2022/03/14 06:51:43 DEBUG : both15: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f104a2a6a6209897d_d20220314_m065034_c001_v0001113_t0002") 2022/03/14 06:51:43 DEBUG : both15: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100fd38188a05306_d20220314_m064919_c001_v0001102_t0054") 2022/03/14 06:51:43 DEBUG : both15: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab88286b4f5_d20220314_m064837_c001_v0001104_t0025") 2022/03/14 06:51:43 DEBUG : both16: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f105057e6012bcd98_d20220314_m065102_c001_v0001103_t0054") 2022/03/14 06:51:43 DEBUG : both16: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f104a2a6a62098989_d20220314_m065036_c001_v0001113_t0044") 2022/03/14 06:51:43 DEBUG : both16: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f112129129f36396a_d20220314_m064920_c001_v0001102_t0027") 2022/03/14 06:51:43 DEBUG : both16: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab88286b5d0_d20220314_m064841_c001_v0001104_t0000") 2022/03/14 06:51:44 DEBUG : both17: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f119cf50098d24bf6_d20220314_m065105_c001_v0001134_t0056") 2022/03/14 06:51:44 DEBUG : both17: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f104a2a6a6209899c_d20220314_m065039_c001_v0001113_t0040") 2022/03/14 06:51:44 DEBUG : both17: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f112129129f36397c_d20220314_m064921_c001_v0001102_t0012") 2022/03/14 06:51:44 DEBUG : both17: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab88286b6d7_d20220314_m064847_c001_v0001104_t0024") 2022/03/14 06:51:44 DEBUG : both18: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1150c5eb642d19fe_d20220314_m065105_c001_v0001103_t0014") 2022/03/14 06:51:44 DEBUG : both18: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f104a2a6a620989a8_d20220314_m065041_c001_v0001113_t0005") 2022/03/14 06:51:44 DEBUG : both18: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f10327a89334ca0fd_d20220314_m064921_c001_v0001102_t0048") 2022/03/14 06:51:44 DEBUG : both18: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab88286b7f4_d20220314_m064854_c001_v0001104_t0012") 2022/03/14 06:51:44 DEBUG : both19: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1187bc4fe19a82b1_d20220314_m065108_c001_v0001117_t0032") 2022/03/14 06:51:44 DEBUG : both19: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f104a2a6a620989be_d20220314_m065045_c001_v0001113_t0003") 2022/03/14 06:51:44 DEBUG : both19: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1058e62169db6d0c_d20220314_m064922_c001_v0001090_t0052") 2022/03/14 06:51:44 DEBUG : both19: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab88286b899_d20220314_m064858_c001_v0001104_t0036") 2022/03/14 06:51:44 DEBUG : both2: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1167a0f711e20651_d20220314_m065109_c001_v0001104_t0000") 2022/03/14 06:51:44 DEBUG : both2: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab88286c135_d20220314_m064948_c001_v0001104_t0034") 2022/03/14 06:51:44 DEBUG : both2: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116009d9f5fa1042_d20220314_m064922_c001_v0001106_t0056") 2022/03/14 06:51:45 DEBUG : both2: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c8a122_d20220314_m064727_c001_v0001113_t0050") 2022/03/14 06:51:45 DEBUG : both3: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f112d088daa5699d0_d20220314_m065111_c001_v0001041_t0058") 2022/03/14 06:51:45 DEBUG : both3: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab88286c221_d20220314_m064954_c001_v0001104_t0027") 2022/03/14 06:51:45 DEBUG : both3: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f103e9a306d3e38d5_d20220314_m064924_c001_v0001098_t0056") 2022/03/14 06:51:45 DEBUG : both3: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab88286a8df_d20220314_m064728_c001_v0001104_t0011") 2022/03/14 06:51:45 DEBUG : both4: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1020c562e9eb4a56_d20220314_m065112_c001_v0001131_t0014") 2022/03/14 06:51:45 DEBUG : both4: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab88286c2e1_d20220314_m064959_c001_v0001104_t0048") 2022/03/14 06:51:45 DEBUG : both4: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f113d41c715d66210_d20220314_m064925_c001_v0001101_t0047") 2022/03/14 06:51:45 DEBUG : both4: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f117458308dc255ac_d20220314_m064735_c001_v0001101_t0059") 2022/03/14 06:51:45 DEBUG : both5: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f111fb9222d3b61b7_d20220314_m065112_c001_v0001098_t0029") 2022/03/14 06:51:45 DEBUG : both5: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab88286c364_d20220314_m065002_c001_v0001104_t0020") 2022/03/14 06:51:45 DEBUG : both5: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f117bedce5d785039_d20220314_m064926_c001_v0001104_t0033") 2022/03/14 06:51:45 DEBUG : both5: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c8a161_d20220314_m064738_c001_v0001113_t0053") 2022/03/14 06:51:45 DEBUG : both6: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1141d4ba14b113a6_d20220314_m065113_c001_v0001115_t0015") 2022/03/14 06:51:45 DEBUG : both6: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab88286c42f_d20220314_m065006_c001_v0001104_t0008") 2022/03/14 06:51:45 DEBUG : both6: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f10883bffcc9ec57d_d20220314_m064928_c001_v0001104_t0034") 2022/03/14 06:51:45 DEBUG : both6: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab88286ab4c_d20220314_m064741_c001_v0001104_t0046") 2022/03/14 06:51:45 DEBUG : both7: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f117f280b688e2f4b_d20220314_m065114_c001_v0001042_t0015") 2022/03/14 06:51:45 DEBUG : both7: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab88286c489_d20220314_m065009_c001_v0001104_t0054") 2022/03/14 06:51:45 DEBUG : both7: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f111779e0c37149fc_d20220314_m064929_c001_v0001101_t0018") 2022/03/14 06:51:45 DEBUG : both7: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab88286ac71_d20220314_m064748_c001_v0001104_t0015") 2022/03/14 06:51:45 DEBUG : both8: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f107c316a12b297a3_d20220314_m065118_c001_v0001116_t0007") 2022/03/14 06:51:45 DEBUG : both8: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab88286c503_d20220314_m065011_c001_v0001104_t0016") 2022/03/14 06:51:46 DEBUG : both8: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f110264068db84d35_d20220314_m064932_c001_v0001099_t0059") 2022/03/14 06:51:46 DEBUG : both8: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab88286adb8_d20220314_m064756_c001_v0001104_t0025") 2022/03/14 06:51:46 DEBUG : both9: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f113e97ba5a3bc421_d20220314_m065119_c001_v0001104_t0000") 2022/03/14 06:51:46 DEBUG : both9: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab88286c62f_d20220314_m065020_c001_v0001104_t0037") 2022/03/14 06:51:46 DEBUG : both9: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f119adcae31f79d82_d20220314_m064934_c001_v0001105_t0019") 2022/03/14 06:51:46 DEBUG : both9: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab88286ae46_d20220314_m064759_c001_v0001104_t0052") 2022/03/14 06:51:46 DEBUG : c/non empty space: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f117bedce5d77f26d_d20220314_m063922_c001_v0001104_t0035") 2022/03/14 06:51:46 DEBUG : c/non empty space: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c89642_d20220314_m063913_c001_v0001113_t0016") 2022/03/14 06:51:46 DEBUG : c/non empty space: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f117bedce5d77f0dc_d20220314_m063911_c001_v0001104_t0027") 2022/03/14 06:51:46 DEBUG : c/non empty space: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c89629_d20220314_m063908_c001_v0001113_t0013") 2022/03/14 06:51:46 DEBUG : c/non empty space: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1106f88ab409016f_d20220314_m063827_c001_v0001100_t0018") 2022/03/14 06:51:46 DEBUG : c/non empty space: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c89505_d20220314_m063823_c001_v0001113_t0046") 2022/03/14 06:51:46 DEBUG : check sum: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f11850b2248c9889e_d20220314_m063804_c001_v0001044_t0046") 2022/03/14 06:51:46 DEBUG : check sum: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c89490_d20220314_m063802_c001_v0001113_t0006") 2022/03/14 06:51:46 DEBUG : dest/3: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f104bd50e6fcf279a_d20220314_m064321_c001_v0001165_t0058") 2022/03/14 06:51:46 DEBUG : dest/3: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f103e9a306d3e1dff_d20220314_m064320_c001_v0001098_t0002") 2022/03/14 06:51:46 DEBUG : dst/one: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1108d31b5dcdcb33_d20220314_m064701_c001_v0001150_t0004") 2022/03/14 06:51:46 DEBUG : dst/one: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f10212709d53e2645_d20220314_m064655_c001_v0001115_t0015") 2022/03/14 06:51:46 DEBUG : dst/one: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116f3fc57a0a872e_d20220314_m064654_c001_v0001098_t0032") 2022/03/14 06:51:46 DEBUG : dst/one: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f10212709d53e2538_d20220314_m064647_c001_v0001115_t0025") 2022/03/14 06:51:47 DEBUG : dst/one: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f11456464ce840302_d20220314_m064641_c001_v0001104_t0041") 2022/03/14 06:51:47 DEBUG : dst/one: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f117458308dc250ea_d20220314_m064632_c001_v0001101_t0040") 2022/03/14 06:51:47 DEBUG : dst/one: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f117e2c8f6b422ab7_d20220314_m064625_c001_v0001120_t0006") 2022/03/14 06:51:47 DEBUG : dst/one: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f110a84eeb4e8793a_d20220314_m064621_c001_v0001116_t0041") 2022/03/14 06:51:47 DEBUG : dst/one: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f10940af7bbaeb399_d20220314_m064620_c001_v0001103_t0005") 2022/03/14 06:51:47 DEBUG : dst/one: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f110a84eeb4e87831_d20220314_m064607_c001_v0001116_t0039") 2022/03/14 06:51:47 DEBUG : dst/one: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1145a4cf9cb1a390_d20220314_m064606_c001_v0001043_t0053") 2022/03/14 06:51:47 DEBUG : dst/one: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f117458308dc24e0e_d20220314_m064556_c001_v0001101_t0002") 2022/03/14 06:51:47 DEBUG : dst/one: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f107146851da7c5ae_d20220314_m064551_c001_v0001109_t0037") 2022/03/14 06:51:47 DEBUG : dst/one: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f106acbfa19aee760_d20220314_m064548_c001_v0001039_t0040") 2022/03/14 06:51:47 DEBUG : dst/one: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f11515b3a9114752c_d20220314_m064547_c001_v0001105_t0018") 2022/03/14 06:51:47 DEBUG : dst/one: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f106acbfa19aee754_d20220314_m064541_c001_v0001039_t0050") 2022/03/14 06:51:47 DEBUG : dst/one: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1189c3f6eb3cdf06_d20220314_m064539_c001_v0001097_t0019") 2022/03/14 06:51:47 DEBUG : dst/one: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c89eae_d20220314_m064530_c001_v0001113_t0042") 2022/03/14 06:51:47 DEBUG : dst/one: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f107146851da7c46e_d20220314_m064527_c001_v0001109_t0007") 2022/03/14 06:51:47 DEBUG : dst/one: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f10585cc490bc0d73_d20220314_m064521_c001_v0001157_t0057") 2022/03/14 06:51:47 DEBUG : dst/one: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f108a9c4e46e829e8_d20220314_m064520_c001_v0001130_t0023") 2022/03/14 06:51:47 DEBUG : dst/one: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f10585cc490bc0d43_d20220314_m064512_c001_v0001157_t0042") 2022/03/14 06:51:47 DEBUG : dst/one: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f117254a54c058ded_d20220314_m064510_c001_v0001025_t0040") 2022/03/14 06:51:47 DEBUG : dst/one: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab882869050_d20220314_m064500_c001_v0001104_t0012") 2022/03/14 06:51:48 DEBUG : dst/one: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1088143c052e2a16_d20220314_m064456_c001_v0001100_t0052") 2022/03/14 06:51:48 DEBUG : dst/one: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1100dd8b2f30917f_d20220314_m064453_c001_v0001105_t0009") 2022/03/14 06:51:48 DEBUG : dst/one: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1006276c2377c004_d20220314_m064451_c001_v0001115_t0015") 2022/03/14 06:51:48 DEBUG : dst/one: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1100dd8b2f30911b_d20220314_m064446_c001_v0001105_t0017") 2022/03/14 06:51:48 DEBUG : dst/one: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f10851a75c017bec6_d20220314_m064444_c001_v0001102_t0053") 2022/03/14 06:51:48 DEBUG : dst/one: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f117458308dc24895_d20220314_m064437_c001_v0001101_t0010") 2022/03/14 06:51:48 DEBUG : dst/one: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f11900452fff04e0e_d20220314_m064434_c001_v0001102_t0029") 2022/03/14 06:51:48 DEBUG : dst/one: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f102f53ffa7ffa9f8_d20220314_m064427_c001_v0001104_t0058") 2022/03/14 06:51:48 DEBUG : dst/one: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f10883bffcc9e947c_d20220314_m064424_c001_v0001104_t0014") 2022/03/14 06:51:48 DEBUG : dst/one: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f102f53ffa7ffa87c_d20220314_m064417_c001_v0001104_t0013") 2022/03/14 06:51:48 DEBUG : dst/one: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f118eb32e2c67ac18_d20220314_m064416_c001_v0001165_t0040") 2022/03/14 06:51:48 DEBUG : dst/one: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c89cce_d20220314_m064402_c001_v0001113_t0013") 2022/03/14 06:51:48 DEBUG : dst/one: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f118d4ab78c222bf8_d20220314_m064358_c001_v0001101_t0050") 2022/03/14 06:51:48 DEBUG : dst/one: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f11878615936978c3_d20220314_m064345_c001_v0001109_t0013") 2022/03/14 06:51:48 DEBUG : dst/one: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f117a53ef27497362_d20220314_m064341_c001_v0001032_t0030") 2022/03/14 06:51:48 DEBUG : dst/one: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab882868216_d20220314_m064331_c001_v0001104_t0012") 2022/03/14 06:51:48 DEBUG : dst/one: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f10983ede798cd320_d20220314_m064329_c001_v0001037_t0036") 2022/03/14 06:51:48 DEBUG : dst/one: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f10983ede798cd318_d20220314_m064326_c001_v0001037_t0029") 2022/03/14 06:51:48 DEBUG : dst/one: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f111fab4210473e55_d20220314_m064311_c001_v0001104_t0006") 2022/03/14 06:51:48 DEBUG : dst/one: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c89b45_d20220314_m064249_c001_v0001113_t0045") 2022/03/14 06:51:49 DEBUG : dst/one: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f113e97ba5a3b6fe3_d20220314_m064246_c001_v0001104_t0020") 2022/03/14 06:51:49 DEBUG : dst/one: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f113e97ba5a3b6f6c_d20220314_m064243_c001_v0001104_t0014") 2022/03/14 06:51:49 DEBUG : dst/one-2019-01-01: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1113a84b850930c7_d20220314_m064701_c001_v0001106_t0040") 2022/03/14 06:51:49 DEBUG : dst/one-2019-01-01: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f111779e0c3713db8_d20220314_m064653_c001_v0001101_t0000") 2022/03/14 06:51:49 DEBUG : dst/one-2019-01-01: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f118349de27b6002f_d20220314_m064640_c001_v0001115_t0028") 2022/03/14 06:51:49 DEBUG : dst/one.bak: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f102e57fba3ce7786_d20220314_m064627_c001_v0001161_t0051") 2022/03/14 06:51:49 DEBUG : dst/one.bak: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f11116736fdf6dc4c_d20220314_m064615_c001_v0001115_t0016") 2022/03/14 06:51:49 DEBUG : dst/one.bak: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1102b7956f88eae3_d20220314_m064605_c001_v0001115_t0005") 2022/03/14 06:51:49 DEBUG : dst/one.bak: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f106bc7110eccd211_d20220314_m064553_c001_v0001115_t0049") 2022/03/14 06:51:49 DEBUG : dst/one.bak: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f11047742132ab942_d20220314_m064546_c001_v0001109_t0005") 2022/03/14 06:51:49 DEBUG : dst/one.bak: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f113e97ba5a3b8b00_d20220314_m064538_c001_v0001104_t0019") 2022/03/14 06:51:49 DEBUG : dst/three: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f113f13fd234e1cd8_d20220314_m064359_c001_v0001117_t0019") 2022/03/14 06:51:49 DEBUG : dst/three: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f10983ede798cd36a_d20220314_m064354_c001_v0001037_t0002") 2022/03/14 06:51:49 DEBUG : dst/three-2019-01-01.txt: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f117458308dc25355_d20220314_m064702_c001_v0001101_t0056") 2022/03/14 06:51:49 DEBUG : dst/three-2019-01-01.txt: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f10727e927631a582_d20220314_m064656_c001_v0001144_t0010") 2022/03/14 06:51:49 DEBUG : dst/three-2019-01-01.txt: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1150fcfc99b66fe4_d20220314_m064650_c001_v0001145_t0005") 2022/03/14 06:51:49 DEBUG : dst/three.txt: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f104adc59f7960fe3_d20220314_m064703_c001_v0001105_t0027") 2022/03/14 06:51:49 DEBUG : dst/three.txt: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f10212709d53e26bf_d20220314_m064658_c001_v0001115_t0038") 2022/03/14 06:51:49 DEBUG : dst/three.txt: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f115bdbdb48cd8b37_d20220314_m064657_c001_v0001104_t0029") 2022/03/14 06:51:49 DEBUG : dst/three.txt: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f10212709d53e25d7_d20220314_m064651_c001_v0001115_t0017") 2022/03/14 06:51:49 DEBUG : dst/three.txt: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f106f3379a8d86e02_d20220314_m064650_c001_v0001117_t0054") 2022/03/14 06:51:49 DEBUG : dst/three.txt: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c89ff2_d20220314_m064636_c001_v0001113_t0046") 2022/03/14 06:51:50 DEBUG : dst/three.txt: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f11648e7279d038e0_d20220314_m064627_c001_v0001115_t0007") 2022/03/14 06:51:50 DEBUG : dst/three.txt: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f110a84eeb4e87982_d20220314_m064625_c001_v0001116_t0048") 2022/03/14 06:51:50 DEBUG : dst/three.txt: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f10609a706bb6be32_d20220314_m064624_c001_v0001106_t0020") 2022/03/14 06:51:50 DEBUG : dst/three.txt: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f110a84eeb4e87885_d20220314_m064611_c001_v0001116_t0054") 2022/03/14 06:51:50 DEBUG : dst/three.txt: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f105057e6012bb74f_d20220314_m064610_c001_v0001103_t0027") 2022/03/14 06:51:50 DEBUG : dst/three.txt: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c89f48_d20220314_m064601_c001_v0001113_t0024") 2022/03/14 06:51:50 DEBUG : dst/three.txt: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f10290b7499dea218_d20220314_m064549_c001_v0001090_t0056") 2022/03/14 06:51:50 DEBUG : dst/three.txt: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c89ef1_d20220314_m064544_c001_v0001113_t0019") 2022/03/14 06:51:50 DEBUG : dst/three.txt: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f107126728f1f8686_d20220314_m064542_c001_v0001105_t0022") 2022/03/14 06:51:50 DEBUG : dst/three.txt: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab882869595_d20220314_m064532_c001_v0001104_t0012") 2022/03/14 06:51:50 DEBUG : dst/three.txt: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f101adfaa0a361fd3_d20220314_m064525_c001_v0001104_t0016") 2022/03/14 06:51:50 DEBUG : dst/three.txt: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab8828692d0_d20220314_m064516_c001_v0001104_t0055") 2022/03/14 06:51:50 DEBUG : dst/three.txt: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f114dab8f91a1eef3_d20220314_m064515_c001_v0001098_t0039") 2022/03/14 06:51:50 DEBUG : dst/three.txt: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f117458308dc24a4d_d20220314_m064505_c001_v0001101_t0055") 2022/03/14 06:51:50 DEBUG : dst/three.txt: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f111973405611deca_d20220314_m064454_c001_v0001146_t0035") 2022/03/14 06:51:50 DEBUG : dst/three.txt: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f117458308dc24951_d20220314_m064448_c001_v0001101_t0036") 2022/03/14 06:51:50 DEBUG : dst/three.txt: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f119308b5911513b0_d20220314_m064447_c001_v0001038_t0046") 2022/03/14 06:51:50 DEBUG : dst/three.txt: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c89da3_d20220314_m064440_c001_v0001113_t0053") 2022/03/14 06:51:50 DEBUG : dst/three.txt: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f103f731730a41c1c_d20220314_m064429_c001_v0001001_t0040") 2022/03/14 06:51:50 DEBUG : dst/three.txt: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c89d37_d20220314_m064422_c001_v0001113_t0034") 2022/03/14 06:51:51 DEBUG : dst/three.txt: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1174f5a35d0ab34c_d20220314_m064421_c001_v0001165_t0030") 2022/03/14 06:51:51 DEBUG : dst/three.txt: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab8828687f9_d20220314_m064406_c001_v0001104_t0002") 2022/03/14 06:51:51 DEBUG : dst/three.txt.bak: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f101b1e8d0d24a263_d20220314_m064629_c001_v0001115_t0000") 2022/03/14 06:51:51 DEBUG : dst/three.txt.bak: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1180cf7a805f8279_d20220314_m064623_c001_v0001137_t0043") 2022/03/14 06:51:51 DEBUG : dst/three.txt.bak: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1148a10de4745a59_d20220314_m064609_c001_v0001103_t0051") 2022/03/14 06:51:51 DEBUG : dst/three.txt.bak: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f107b3503724d3db7_d20220314_m064554_c001_v0001115_t0033") 2022/03/14 06:51:51 DEBUG : dst/three.txt.bak: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1038af24db284515_d20220314_m064549_c001_v0001134_t0035") 2022/03/14 06:51:51 DEBUG : dst/three.txt.bak: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f10327a89334c92c0_d20220314_m064542_c001_v0001102_t0046") 2022/03/14 06:51:51 DEBUG : dst/two: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1141d4ff97d6395d_d20220314_m064703_c001_v0001120_t0023") 2022/03/14 06:51:51 DEBUG : dst/two: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab882869f7a_d20220314_m064633_c001_v0001104_t0020") 2022/03/14 06:51:51 DEBUG : dst/two: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f113219836391651c_d20220314_m064630_c001_v0001098_t0006") 2022/03/14 06:51:51 DEBUG : dst/two: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab88286999c_d20220314_m064558_c001_v0001104_t0021") 2022/03/14 06:51:51 DEBUG : dst/two: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f10884401258b7a3e_d20220314_m064554_c001_v0001116_t0038") 2022/03/14 06:51:51 DEBUG : dst/two: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f117458308dc24c5f_d20220314_m064531_c001_v0001101_t0012") 2022/03/14 06:51:51 DEBUG : dst/two: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f11658b995544176e_d20220314_m064528_c001_v0001103_t0007") 2022/03/14 06:51:51 DEBUG : dst/two: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c89e33_d20220314_m064503_c001_v0001113_t0057") 2022/03/14 06:51:52 DEBUG : dst/two: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f112769b05cbb7eba_d20220314_m064456_c001_v0001104_t0003") 2022/03/14 06:51:52 DEBUG : dst/two: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab882868ceb_d20220314_m064439_c001_v0001104_t0019") 2022/03/14 06:51:52 DEBUG : dst/two: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1098b25971660059_d20220314_m064435_c001_v0001102_t0055") 2022/03/14 06:51:52 DEBUG : dst/two: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f117458308dc24606_d20220314_m064405_c001_v0001101_t0040") 2022/03/14 06:51:52 DEBUG : dst/two: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f10931e92eb0fefaf_d20220314_m064401_c001_v0001115_t0030") 2022/03/14 06:51:52 DEBUG : dst/two: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f111047f5c782a97d_d20220314_m064349_c001_v0001040_t0002") 2022/03/14 06:51:52 DEBUG : dst/two: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f112f221b684558b5_d20220314_m064313_c001_v0001109_t0040") 2022/03/14 06:51:52 DEBUG : dst/two: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f113e97ba5a3b72f4_d20220314_m064306_c001_v0001104_t0014") 2022/03/14 06:51:52 DEBUG : empty space: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f117a3fbf00bec2ac_d20220314_m064222_c001_v0001100_t0007") 2022/03/14 06:51:52 DEBUG : empty space: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c89a46_d20220314_m064210_c001_v0001113_t0047") 2022/03/14 06:51:52 DEBUG : empty space: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1141d4ff97d63230_d20220314_m064205_c001_v0001120_t0009") 2022/03/14 06:51:52 DEBUG : empty space: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c89974_d20220314_m064131_c001_v0001113_t0049") 2022/03/14 06:51:52 DEBUG : empty space: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f118d4ab78c222130_d20220314_m064117_c001_v0001101_t0035") 2022/03/14 06:51:52 DEBUG : empty space: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c898c2_d20220314_m064103_c001_v0001113_t0037") 2022/03/14 06:51:52 DEBUG : empty space: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f104b26d1a420199f_d20220314_m063958_c001_v0001099_t0027") 2022/03/14 06:51:52 DEBUG : empty space: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c89740_d20220314_m063953_c001_v0001113_t0012") 2022/03/14 06:51:53 DEBUG : empty space: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f11082abebdf5be5e_d20220314_m063946_c001_v0001098_t0051") 2022/03/14 06:51:53 DEBUG : empty space: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c8970d_d20220314_m063944_c001_v0001113_t0040") 2022/03/14 06:51:53 DEBUG : empty space: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100fd38188a027be_d20220314_m063937_c001_v0001102_t0050") 2022/03/14 06:51:53 DEBUG : empty space: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c896cb_d20220314_m063934_c001_v0001113_t0006") 2022/03/14 06:51:53 DEBUG : empty space: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f11038390493f6f29_d20220314_m063930_c001_v0001106_t0010") 2022/03/14 06:51:53 DEBUG : empty space: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c8968e_d20220314_m063927_c001_v0001113_t0033") 2022/03/14 06:51:53 DEBUG : empty space: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1154541910cd0a17_d20220314_m063906_c001_v0001144_t0003") 2022/03/14 06:51:53 DEBUG : empty space: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c895fe_d20220314_m063903_c001_v0001113_t0023") 2022/03/14 06:51:53 DEBUG : empty space: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f105f6ba54c77c136_d20220314_m063901_c001_v0001099_t0005") 2022/03/14 06:51:53 DEBUG : empty space: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c895dd_d20220314_m063857_c001_v0001113_t0007") 2022/03/14 06:51:53 DEBUG : empty space: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f110a84eeb4e8521f_d20220314_m063844_c001_v0001116_t0053") 2022/03/14 06:51:53 DEBUG : empty space: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c89570_d20220314_m063841_c001_v0001113_t0026") 2022/03/14 06:51:53 DEBUG : empty space: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1102b7956f88af7d_d20220314_m063836_c001_v0001115_t0023") 2022/03/14 06:51:53 DEBUG : empty space: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c89541_d20220314_m063834_c001_v0001113_t0038") 2022/03/14 06:51:53 DEBUG : empty space: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f119a1ae0f29cd44a_d20220314_m063833_c001_v0001115_t0027") 2022/03/14 06:51:54 DEBUG : empty space: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f114bde8226730a36_d20220314_m063832_c001_v0001101_t0003") 2022/03/14 06:51:54 DEBUG : empty space: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c8952e_d20220314_m063829_c001_v0001113_t0047") 2022/03/14 06:51:54 DEBUG : enormous: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f105be30fbf057d73_d20220314_m063956_c001_v0001161_t0058") 2022/03/14 06:51:54 DEBUG : enormous: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c89749_d20220314_m063955_c001_v0001113_t0040") 2022/03/14 06:51:54 DEBUG : existing: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1083a23bd053ec39_d20220314_m064711_c001_v0001101_t0022") 2022/03/14 06:51:54 DEBUG : existing: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c8a0bd_d20220314_m064710_c001_v0001113_t0053") 2022/03/14 06:51:54 DEBUG : existing: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f111e3ef8a023d686_d20220314_m064058_c001_v0001131_t0050") 2022/03/14 06:51:54 DEBUG : existing: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c89891_d20220314_m064056_c001_v0001113_t0047") 2022/03/14 06:51:54 DEBUG : existing: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f114d53439afb3fbb_d20220314_m063822_c001_v0001134_t0025") 2022/03/14 06:51:54 DEBUG : existing: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c894e2_d20220314_m063817_c001_v0001113_t0045") 2022/03/14 06:51:54 DEBUG : existing: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f119dcafdd48f5587_d20220314_m063815_c001_v0001099_t0052") 2022/03/14 06:51:54 DEBUG : existing: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c894d9_d20220314_m063814_c001_v0001113_t0011") 2022/03/14 06:51:54 DEBUG : existing: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c894cf_d20220314_m063813_c001_v0001113_t0017") 2022/03/14 06:51:54 DEBUG : existing-b: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f111114c24e778c64_d20220314_m064058_c001_v0001116_t0039") 2022/03/14 06:51:54 DEBUG : existing-b: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f117458308dc2383a_d20220314_m064056_c001_v0001101_t0041") 2022/03/14 06:51:54 DEBUG : five: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f102f53ffa7ff82cc_d20220314_m064011_c001_v0001104_t0035") 2022/03/14 06:51:54 DEBUG : five: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c89781_d20220314_m064005_c001_v0001113_t0039") 2022/03/14 06:51:54 DEBUG : foo: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1010f3dc677e7d4d_d20220314_m063841_c001_v0001101_t0025") 2022/03/14 06:51:54 DEBUG : foo: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c89561_d20220314_m063839_c001_v0001113_t0058") 2022/03/14 06:51:55 DEBUG : foo: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c89552_d20220314_m063838_c001_v0001113_t0027") 2022/03/14 06:51:55 DEBUG : four: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f111fab42104721ef_d20220314_m064014_c001_v0001104_t0050") 2022/03/14 06:51:55 DEBUG : four: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f117458308dc2349d_d20220314_m064006_c001_v0001101_t0053") 2022/03/14 06:51:55 DEBUG : four: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c89776_d20220314_m064004_c001_v0001113_t0007") 2022/03/14 06:51:55 DEBUG : hello world2: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1161c8b7db80a234_d20220314_m063735_c001_v0001091_t0002") 2022/03/14 06:51:55 DEBUG : hello world2: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1063857ddc65aaf9_d20220314_m063734_c001_v0001116_t0041") 2022/03/14 06:51:55 DEBUG : ignore-size: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1013cdc31f81df07_d20220314_m063812_c001_v0001019_t0003") 2022/03/14 06:51:55 DEBUG : ignore-size: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c894bc_d20220314_m063810_c001_v0001113_t0019") 2022/03/14 06:51:55 DEBUG : nested/sub dir/file: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f106d7c28c689c056_d20220314_m064053_c001_v0001099_t0035") 2022/03/14 06:51:55 DEBUG : nested/sub dir/file: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f117458308dc237ca_d20220314_m064050_c001_v0001101_t0010") 2022/03/14 06:51:55 DEBUG : nested/sub dir/file: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1017e156dead7fac_d20220314_m064048_c001_v0001036_t0053") 2022/03/14 06:51:55 DEBUG : nested/sub dir/file: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f117458308dc2378b_d20220314_m064047_c001_v0001101_t0012") 2022/03/14 06:51:55 DEBUG : one: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f112a7b51131cb82e_d20220314_m064015_c001_v0001117_t0050") 2022/03/14 06:51:55 DEBUG : one: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c8975e_d20220314_m064000_c001_v0001113_t0013") 2022/03/14 06:51:55 DEBUG : only0: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1075e7409674e59a_d20220314_m065121_c001_v0001041_t0059") 2022/03/14 06:51:55 DEBUG : only0: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f104a2a6a620989ca_d20220314_m065048_c001_v0001113_t0001") 2022/03/14 06:51:55 DEBUG : only0: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab88286bfc3_d20220314_m064940_c001_v0001104_t0011") 2022/03/14 06:51:55 DEBUG : only0: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f118df5a59e525fc1_d20220314_m064908_c001_v0001100_t0049") 2022/03/14 06:51:55 DEBUG : only0: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c8a0ff_d20220314_m064720_c001_v0001113_t0039") 2022/03/14 06:51:55 DEBUG : only1: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1002b0462eb95a47_d20220314_m065123_c001_v0001154_t0012") 2022/03/14 06:51:56 DEBUG : only1: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1150c5eb642d18f8_d20220314_m065048_c001_v0001103_t0019") 2022/03/14 06:51:56 DEBUG : only1: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab88286c0b1_d20220314_m064945_c001_v0001104_t0011") 2022/03/14 06:51:56 DEBUG : only1: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f11577430ccc5016c_d20220314_m064909_c001_v0001106_t0003") 2022/03/14 06:51:56 DEBUG : only1: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab88286a833_d20220314_m064724_c001_v0001104_t0007") 2022/03/14 06:51:56 DEBUG : only10: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1115b31f24e592ff_d20220314_m065123_c001_v0001041_t0006") 2022/03/14 06:51:56 DEBUG : only10: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f101adfaa0a36567d_d20220314_m065049_c001_v0001104_t0035") 2022/03/14 06:51:56 DEBUG : only10: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f104a2a6a6209893e_d20220314_m065027_c001_v0001113_t0000") 2022/03/14 06:51:56 DEBUG : only10: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f10883bffcc9ec1c6_d20220314_m064905_c001_v0001104_t0007") 2022/03/14 06:51:56 DEBUG : only10: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab88286aff2_d20220314_m064808_c001_v0001104_t0006") 2022/03/14 06:51:56 DEBUG : only11: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f10883bffcc9ed897_d20220314_m065124_c001_v0001104_t0055") 2022/03/14 06:51:56 DEBUG : only11: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f109748c471a2f351_d20220314_m065048_c001_v0001161_t0011") 2022/03/14 06:51:56 DEBUG : only11: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f104a2a6a62098949_d20220314_m065028_c001_v0001113_t0053") 2022/03/14 06:51:56 DEBUG : only11: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1008430dd46b9569_d20220314_m064909_c001_v0001157_t0001") 2022/03/14 06:51:56 DEBUG : only11: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab88286b0fd_d20220314_m064814_c001_v0001104_t0041") 2022/03/14 06:51:56 DEBUG : only12: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1141d4ba14b1158e_d20220314_m065125_c001_v0001115_t0013") 2022/03/14 06:51:56 DEBUG : only12: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f104a2a6a620989cc_d20220314_m065048_c001_v0001113_t0057") 2022/03/14 06:51:56 DEBUG : only12: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f104a2a6a6209895a_d20220314_m065029_c001_v0001113_t0045") 2022/03/14 06:51:56 DEBUG : only12: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f11174ab7cce17b0c_d20220314_m064908_c001_v0001097_t0008") 2022/03/14 06:51:56 DEBUG : only12: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab88286b249_d20220314_m064821_c001_v0001104_t0002") 2022/03/14 06:51:56 DEBUG : only13: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f101b1e8d0d24ca7d_d20220314_m065126_c001_v0001115_t0013") 2022/03/14 06:51:56 DEBUG : only13: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f104a2a6a620989ce_d20220314_m065049_c001_v0001113_t0056") 2022/03/14 06:51:57 DEBUG : only13: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f104a2a6a62098965_d20220314_m065032_c001_v0001113_t0007") 2022/03/14 06:51:57 DEBUG : only13: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f113cd5f69133ba44_d20220314_m064905_c001_v0001031_t0033") 2022/03/14 06:51:57 DEBUG : only13: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab88286b3cd_d20220314_m064830_c001_v0001104_t0058") 2022/03/14 06:51:57 DEBUG : only14: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f110b2d0a33cb7926_d20220314_m065127_c001_v0001144_t0004") 2022/03/14 06:51:57 DEBUG : only14: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f109748c471a2f355_d20220314_m065049_c001_v0001161_t0033") 2022/03/14 06:51:57 DEBUG : only14: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f104a2a6a62098975_d20220314_m065034_c001_v0001113_t0037") 2022/03/14 06:51:57 DEBUG : only14: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab88286ba8c_d20220314_m064909_c001_v0001104_t0032") 2022/03/14 06:51:57 DEBUG : only14: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab88286b4c7_d20220314_m064836_c001_v0001104_t0005") 2022/03/14 06:51:57 DEBUG : only15: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1035e878206f9424_d20220314_m065127_c001_v0001104_t0042") 2022/03/14 06:51:57 DEBUG : only15: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1150c5eb642d18ff_d20220314_m065049_c001_v0001103_t0016") 2022/03/14 06:51:57 DEBUG : only15: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f104a2a6a62098983_d20220314_m065036_c001_v0001113_t0038") 2022/03/14 06:51:57 DEBUG : only15: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f118712df3085af57_d20220314_m064909_c001_v0001102_t0057") 2022/03/14 06:51:57 DEBUG : only15: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab88286b545_d20220314_m064839_c001_v0001104_t0043") 2022/03/14 06:51:57 DEBUG : only16: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f112cad4c6271c72e_d20220314_m065129_c001_v0001099_t0049") 2022/03/14 06:51:57 DEBUG : only16: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f109748c471a2f35c_d20220314_m065049_c001_v0001161_t0038") 2022/03/14 06:51:57 DEBUG : only16: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f104a2a6a62098997_d20220314_m065038_c001_v0001113_t0034") 2022/03/14 06:51:57 DEBUG : only16: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1141ccedb5c8378e_d20220314_m064905_c001_v0001015_t0042") 2022/03/14 06:51:57 DEBUG : only16: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab88286b679_d20220314_m064845_c001_v0001104_t0027") 2022/03/14 06:51:57 DEBUG : only17: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f118da6cb5248fad5_d20220314_m065129_c001_v0001150_t0036") 2022/03/14 06:51:57 DEBUG : only17: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f109748c471a2f360_d20220314_m065049_c001_v0001161_t0004") 2022/03/14 06:51:58 DEBUG : only17: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f104a2a6a620989a0_d20220314_m065039_c001_v0001113_t0012") 2022/03/14 06:51:58 DEBUG : only17: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f11042285798ca0d5_d20220314_m064909_c001_v0001101_t0028") 2022/03/14 06:51:58 DEBUG : only17: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab88286b75f_d20220314_m064850_c001_v0001104_t0018") 2022/03/14 06:51:58 DEBUG : only18: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f115f01534bbd94d2_d20220314_m065130_c001_v0001044_t0053") 2022/03/14 06:51:58 DEBUG : only18: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f109748c471a2f365_d20220314_m065050_c001_v0001161_t0053") 2022/03/14 06:51:58 DEBUG : only18: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f104a2a6a620989b3_d20220314_m065043_c001_v0001113_t0033") 2022/03/14 06:51:58 DEBUG : only18: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f10662cfbe4b8e38f_d20220314_m064909_c001_v0001097_t0041") 2022/03/14 06:51:58 DEBUG : only18: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab88286b814_d20220314_m064855_c001_v0001104_t0002") 2022/03/14 06:51:58 DEBUG : only19: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1068b8191f27b62f_d20220314_m065131_c001_v0001120_t0049") 2022/03/14 06:51:58 DEBUG : only19: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f104a2a6a620989d4_d20220314_m065050_c001_v0001113_t0046") 2022/03/14 06:51:58 DEBUG : only19: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f104a2a6a620989c1_d20220314_m065046_c001_v0001113_t0046") 2022/03/14 06:51:58 DEBUG : only19: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f10343fddc8d976e9_d20220314_m064905_c001_v0001115_t0031") 2022/03/14 06:51:58 DEBUG : only19: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab88286b943_d20220314_m064902_c001_v0001104_t0032") 2022/03/14 06:51:58 DEBUG : only2: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1098b2597166205c_d20220314_m065132_c001_v0001102_t0021") 2022/03/14 06:51:58 DEBUG : only2: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1150c5eb642d1910_d20220314_m065050_c001_v0001103_t0021") 2022/03/14 06:51:58 DEBUG : only2: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab88286c19f_d20220314_m064951_c001_v0001104_t0040") 2022/03/14 06:51:58 DEBUG : only2: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f115ae34e2fe7ce07_d20220314_m064905_c001_v0001101_t0044") 2022/03/14 06:51:58 DEBUG : only2: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f117458308dc25544_d20220314_m064727_c001_v0001101_t0046") 2022/03/14 06:51:58 DEBUG : only3: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1042488a550d0347_d20220314_m065132_c001_v0001106_t0050") 2022/03/14 06:51:58 DEBUG : only3: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f109748c471a2f36b_d20220314_m065050_c001_v0001161_t0040") 2022/03/14 06:51:59 DEBUG : only3: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab88286c26f_d20220314_m064956_c001_v0001104_t0008") 2022/03/14 06:51:59 DEBUG : only3: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f119a1ae0f29d27da_d20220314_m064906_c001_v0001115_t0049") 2022/03/14 06:51:59 DEBUG : only3: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c8a138_d20220314_m064731_c001_v0001113_t0044") 2022/03/14 06:51:59 DEBUG : only4: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f10390e5c7af79f9c_d20220314_m065133_c001_v0001116_t0054") 2022/03/14 06:51:59 DEBUG : only4: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f109748c471a2f36e_d20220314_m065050_c001_v0001161_t0011") 2022/03/14 06:51:59 DEBUG : only4: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab88286c327_d20220314_m065001_c001_v0001104_t0046") 2022/03/14 06:51:59 DEBUG : only4: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116f793924afa6b4_d20220314_m064909_c001_v0001101_t0035") 2022/03/14 06:51:59 DEBUG : only4: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab88286aa5e_d20220314_m064737_c001_v0001104_t0000") 2022/03/14 06:51:59 DEBUG : only5: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f104bd50e6fcf36df_d20220314_m065134_c001_v0001165_t0052") 2022/03/14 06:51:59 DEBUG : only5: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1150c5eb642d1916_d20220314_m065050_c001_v0001103_t0046") 2022/03/14 06:51:59 DEBUG : only5: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab88286c3f7_d20220314_m065005_c001_v0001104_t0057") 2022/03/14 06:51:59 DEBUG : only5: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1053945046668d88_d20220314_m064906_c001_v0001100_t0021") 2022/03/14 06:51:59 DEBUG : only5: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f117458308dc2560a_d20220314_m064739_c001_v0001101_t0015") 2022/03/14 06:51:59 DEBUG : only6: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1045dcd4e0773178_d20220314_m065134_c001_v0001104_t0022") 2022/03/14 06:51:59 DEBUG : only6: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f109748c471a2f374_d20220314_m065051_c001_v0001161_t0002") 2022/03/14 06:51:59 DEBUG : only6: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab88286c46c_d20220314_m065008_c001_v0001104_t0054") 2022/03/14 06:51:59 DEBUG : only6: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f106d7c28c689e1db_d20220314_m064906_c001_v0001099_t0008") 2022/03/14 06:51:59 DEBUG : only6: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c8a180_d20220314_m064745_c001_v0001113_t0055") 2022/03/14 06:51:59 DEBUG : only7: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f119b205d23efbb21_d20220314_m065137_c001_v0001136_t0007") 2022/03/14 06:52:00 DEBUG : only7: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1150c5eb642d1925_d20220314_m065051_c001_v0001103_t0054") 2022/03/14 06:52:00 DEBUG : only7: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab88286c4ba_d20220314_m065010_c001_v0001104_t0033") 2022/03/14 06:52:00 DEBUG : only7: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1167a0f711e1f0c1_d20220314_m064907_c001_v0001104_t0035") 2022/03/14 06:52:00 DEBUG : only7: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab88286ad46_d20220314_m064753_c001_v0001104_t0053") 2022/03/14 06:52:00 DEBUG : only8: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f115ad673a72992c4_d20220314_m065138_c001_v0001102_t0004") 2022/03/14 06:52:00 DEBUG : only8: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f104a2a6a620989de_d20220314_m065051_c001_v0001113_t0048") 2022/03/14 06:52:00 DEBUG : only8: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab88286c599_d20220314_m065015_c001_v0001104_t0032") 2022/03/14 06:52:00 DEBUG : only8: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f115bdbdb48cda0cf_d20220314_m064909_c001_v0001104_t0009") 2022/03/14 06:52:00 DEBUG : only8: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab88286ae14_d20220314_m064758_c001_v0001104_t0029") 2022/03/14 06:52:00 DEBUG : only9: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f110468fc8e28f285_d20220314_m065138_c001_v0001146_t0044") 2022/03/14 06:52:00 DEBUG : only9: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f109748c471a2f378_d20220314_m065051_c001_v0001161_t0006") 2022/03/14 06:52:00 DEBUG : only9: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f104a2a6a62098936_d20220314_m065025_c001_v0001113_t0016") 2022/03/14 06:52:00 DEBUG : only9: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f10824867c055cd20_d20220314_m064907_c001_v0001145_t0032") 2022/03/14 06:52:00 DEBUG : only9: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab88286aec8_d20220314_m064802_c001_v0001104_t0055") 2022/03/14 06:52:00 DEBUG : potato: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f11293f44571b2e7d_d20220314_m064044_c001_v0001103_t0017") 2022/03/14 06:52:00 DEBUG : potato: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab882866794_d20220314_m064038_c001_v0001104_t0001") 2022/03/14 06:52:00 DEBUG : potato: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100b3dcbb258750e_d20220314_m064036_c001_v0001128_t0042") 2022/03/14 06:52:00 DEBUG : potato: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c89826_d20220314_m064032_c001_v0001113_t0059") 2022/03/14 06:52:00 DEBUG : potato: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1108e2b32edd6b02_d20220314_m064030_c001_v0001117_t0031") 2022/03/14 06:52:00 DEBUG : potato: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f117458308dc235ed_d20220314_m064023_c001_v0001101_t0053") 2022/03/14 06:52:00 DEBUG : potato: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f11400fcc27dc4a37_d20220314_m063943_c001_v0001097_t0028") 2022/03/14 06:52:01 DEBUG : potato: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c896ee_d20220314_m063939_c001_v0001113_t0040") 2022/03/14 06:52:01 DEBUG : potato: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f104407f5016d23a1_d20220314_m063935_c001_v0001041_t0014") 2022/03/14 06:52:01 DEBUG : potato: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c896c7_d20220314_m063933_c001_v0001113_t0008") 2022/03/14 06:52:01 DEBUG : potato: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f111fab4210471aec_d20220314_m063928_c001_v0001104_t0034") 2022/03/14 06:52:01 DEBUG : potato: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c89688_d20220314_m063927_c001_v0001113_t0051") 2022/03/14 06:52:01 DEBUG : potato: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f102c59f569370f8d_d20220314_m063905_c001_v0001146_t0054") 2022/03/14 06:52:01 DEBUG : potato: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c895f9_d20220314_m063902_c001_v0001113_t0046") 2022/03/14 06:52:01 DEBUG : potato: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1102b7956f88b32b_d20220314_m063901_c001_v0001115_t0005") 2022/03/14 06:52:01 DEBUG : potato: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c895cc_d20220314_m063855_c001_v0001113_t0046") 2022/03/14 06:52:01 DEBUG : potato: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f10678bbf85909730_d20220314_m063854_c001_v0001157_t0006") 2022/03/14 06:52:01 DEBUG : potato: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c895af_d20220314_m063852_c001_v0001113_t0039") 2022/03/14 06:52:01 DEBUG : potato: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c895a3_d20220314_m063850_c001_v0001113_t0020") 2022/03/14 06:52:01 DEBUG : potato: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f115ec713a179f555_d20220314_m063848_c001_v0001115_t0048") 2022/03/14 06:52:01 DEBUG : potato: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c89594_d20220314_m063847_c001_v0001113_t0038") 2022/03/14 06:52:01 DEBUG : potato: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c8958e_d20220314_m063846_c001_v0001113_t0045") 2022/03/14 06:52:01 DEBUG : potato: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f115483b79480c85d_d20220314_m063845_c001_v0001039_t0019") 2022/03/14 06:52:01 DEBUG : potato: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c8957a_d20220314_m063843_c001_v0001113_t0057") 2022/03/14 06:52:01 DEBUG : potato2: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f106fd9519074c9f2_d20220314_m064239_c001_v0001109_t0050") 2022/03/14 06:52:01 DEBUG : potato2: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab8828679d7_d20220314_m064237_c001_v0001104_t0025") 2022/03/14 06:52:01 DEBUG : potato2: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f106bc7110eccb6a9_d20220314_m064223_c001_v0001115_t0002") 2022/03/14 06:52:01 DEBUG : potato2: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab882867511_d20220314_m064208_c001_v0001104_t0052") 2022/03/14 06:52:01 DEBUG : potato2: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f105bac42f6861cbf_d20220314_m064139_c001_v0001104_t0013") 2022/03/14 06:52:02 DEBUG : potato2: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab882866f16_d20220314_m064129_c001_v0001104_t0044") 2022/03/14 06:52:02 DEBUG : potato2: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1113d66e41898505_d20220314_m064118_c001_v0001044_t0008") 2022/03/14 06:52:02 DEBUG : potato2: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab882866ae2_d20220314_m064101_c001_v0001104_t0040") 2022/03/14 06:52:02 DEBUG : potato2: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f110a84eeb4e858f2_d20220314_m063957_c001_v0001116_t0010") 2022/03/14 06:52:02 DEBUG : potato2: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c89732_d20220314_m063949_c001_v0001113_t0051") 2022/03/14 06:52:02 DEBUG : potato2: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f101adfaa0a35ebcf_d20220314_m063947_c001_v0001104_t0025") 2022/03/14 06:52:02 DEBUG : potato2: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c89709_d20220314_m063944_c001_v0001113_t0035") 2022/03/14 06:52:02 DEBUG : potato2: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f107af20613f9e401_d20220314_m063943_c001_v0001165_t0015") 2022/03/14 06:52:02 DEBUG : potato2: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c896f8_d20220314_m063940_c001_v0001113_t0047") 2022/03/14 06:52:02 DEBUG : potato2: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1029b142f1dbec50_d20220314_m063938_c001_v0001136_t0032") 2022/03/14 06:52:02 DEBUG : potato2: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c896d9_d20220314_m063936_c001_v0001113_t0020") 2022/03/14 06:52:02 DEBUG : potato2: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1083970cc8316160_d20220314_m063932_c001_v0001106_t0049") 2022/03/14 06:52:02 DEBUG : potato2: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c8969c_d20220314_m063928_c001_v0001113_t0037") 2022/03/14 06:52:02 DEBUG : potato2: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1098b2597165e6ec_d20220314_m063907_c001_v0001102_t0050") 2022/03/14 06:52:02 DEBUG : potato2: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c89606_d20220314_m063904_c001_v0001113_t0056") 2022/03/14 06:52:02 DEBUG : potato2: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f105a09f37d29633f_d20220314_m063740_c001_v0001106_t0028") 2022/03/14 06:52:02 DEBUG : potato2: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1063857ddc65ab5c_d20220314_m063739_c001_v0001116_t0046") 2022/03/14 06:52:02 DEBUG : potato2: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f10265c3284b9f306_d20220314_m063737_c001_v0001116_t0050") 2022/03/14 06:52:03 DEBUG : potato2: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1063857ddc65ab16_d20220314_m063736_c001_v0001116_t0019") 2022/03/14 06:52:03 DEBUG : potato3: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f11977f7178521eeb_d20220314_m064223_c001_v0001165_t0045") 2022/03/14 06:52:03 DEBUG : potato3: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f117458308dc23dd1_d20220314_m064213_c001_v0001101_t0027") 2022/03/14 06:52:03 DEBUG : potato3: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f11515b3a9114624c_d20220314_m064138_c001_v0001105_t0025") 2022/03/14 06:52:03 DEBUG : potato3: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f117458308dc23b1c_d20220314_m064133_c001_v0001101_t0059") 2022/03/14 06:52:03 DEBUG : potato3: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1006276c2377a460_d20220314_m064118_c001_v0001115_t0028") 2022/03/14 06:52:03 DEBUG : potato3: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f117458308dc23900_d20220314_m064106_c001_v0001101_t0056") 2022/03/14 06:52:03 DEBUG : pre-dest1/1: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f11380531532a0ac3_d20220314_m064322_c001_v0001130_t0016") 2022/03/14 06:52:03 DEBUG : pre-dest1/1: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c89bb2_d20220314_m064314_c001_v0001113_t0000") 2022/03/14 06:52:03 DEBUG : pre-dest2/2: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f104c93ff09bcfae7_d20220314_m064322_c001_v0001103_t0053") 2022/03/14 06:52:03 DEBUG : pre-dest2/2: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f117458308dc24275_d20220314_m064316_c001_v0001101_t0005") 2022/03/14 06:52:03 DEBUG : sizeonly: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1180e10d5e514765_d20220314_m063809_c001_v0001093_t0013") 2022/03/14 06:52:03 DEBUG : sizeonly: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c894a1_d20220314_m063805_c001_v0001113_t0009") 2022/03/14 06:52:03 DEBUG : sub dir/hello world: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f106f3379a8d861b4_d20220314_m064054_c001_v0001117_t0027") 2022/03/14 06:52:03 DEBUG : sub dir/hello world: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab88286698f_d20220314_m064051_c001_v0001104_t0021") 2022/03/14 06:52:03 DEBUG : sub dir/hello world: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1003a131abe2b464_d20220314_m064049_c001_v0001137_t0023") 2022/03/14 06:52:03 DEBUG : sub dir/hello world: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c8986a_d20220314_m064047_c001_v0001113_t0032") 2022/03/14 06:52:03 DEBUG : sub dir/hello world: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1011a9ca4f7178f3_d20220314_m063759_c001_v0001102_t0013") 2022/03/14 06:52:03 DEBUG : sub dir/hello world: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c8947d_d20220314_m063758_c001_v0001113_t0029") 2022/03/14 06:52:03 DEBUG : sub dir/hello world: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f11478c849069a6b5_d20220314_m063757_c001_v0001102_t0015") 2022/03/14 06:52:04 DEBUG : sub dir/hello world: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c89472_d20220314_m063756_c001_v0001113_t0004") 2022/03/14 06:52:04 DEBUG : sub dir/hello world: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f112cad4c62718e01_d20220314_m063752_c001_v0001099_t0012") 2022/03/14 06:52:04 DEBUG : sub dir/hello world: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1063857ddc65ac3c_d20220314_m063748_c001_v0001116_t0051") 2022/03/14 06:52:04 DEBUG : sub dir/hello world: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116691977eb256e7_d20220314_m063747_c001_v0001097_t0027") 2022/03/14 06:52:04 DEBUG : sub dir/hello world: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1063857ddc65ac18_d20220314_m063745_c001_v0001116_t0059") 2022/03/14 06:52:04 DEBUG : sub dir/hello world: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f117a50217d5e68dc_d20220314_m063744_c001_v0001109_t0054") 2022/03/14 06:52:04 DEBUG : sub dir/hello world: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1063857ddc65abc6_d20220314_m063743_c001_v0001116_t0049") 2022/03/14 06:52:04 DEBUG : sub dir/hello world: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1180f2938b96e5bb_d20220314_m063742_c001_v0001134_t0042") 2022/03/14 06:52:04 DEBUG : sub dir/hello world: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1063857ddc65ab98_d20220314_m063741_c001_v0001116_t0019") 2022/03/14 06:52:04 DEBUG : sub dir/hello world: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1077c5bac0acaaae_d20220314_m063732_c001_v0001104_t0002") 2022/03/14 06:52:04 DEBUG : sub dir/hello world: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1063857ddc65aaba_d20220314_m063731_c001_v0001116_t0017") 2022/03/14 06:52:04 DEBUG : sub dir/hello world: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f113d41c715d6304b_d20220314_m063730_c001_v0001101_t0036") 2022/03/14 06:52:04 DEBUG : sub dir/hello world: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1063857ddc65aa71_d20220314_m063729_c001_v0001116_t0047") 2022/03/14 06:52:04 DEBUG : sub dir/hello world: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1066c6992855a6a1_d20220314_m063728_c001_v0001113_t0020") 2022/03/14 06:52:04 DEBUG : sub dir/hello world: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1063857ddc65aa35_d20220314_m063727_c001_v0001116_t0044") 2022/03/14 06:52:04 DEBUG : sub dir/hello world: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1145cf9f6a35787a_d20220314_m063725_c001_v0001150_t0011") 2022/03/14 06:52:04 DEBUG : sub dir/hello world: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1063857ddc65a9db_d20220314_m063724_c001_v0001116_t0010") 2022/03/14 06:52:04 DEBUG : sub/yam: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f10251093e703de84_d20220314_m064043_c001_v0001145_t0014") 2022/03/14 06:52:04 DEBUG : sub/yam: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f117458308dc236d2_d20220314_m064037_c001_v0001101_t0052") 2022/03/14 06:52:04 DEBUG : three: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1113a84b85091493_d20220314_m064018_c001_v0001106_t0047") 2022/03/14 06:52:04 DEBUG : three: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c8978f_d20220314_m064009_c001_v0001113_t0055") 2022/03/14 06:52:05 DEBUG : three: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c89774_d20220314_m064004_c001_v0001113_t0052") 2022/03/14 06:52:05 DEBUG : two: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f115bdbdb48cd4eaa_d20220314_m064019_c001_v0001104_t0009") 2022/03/14 06:52:05 DEBUG : two: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab8828662d6_d20220314_m064006_c001_v0001104_t0009") 2022/03/14 06:52:05 DEBUG : two: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f116ff5f976c8976c_d20220314_m064002_c001_v0001113_t0024") 2022/03/14 06:52:05 DEBUG : yam: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f11486bd9f45989f5_d20220314_m064045_c001_v0001100_t0009") 2022/03/14 06:52:05 DEBUG : yam: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f106bc7110ecca9c3_d20220314_m064042_c001_v0001115_t0036") 2022/03/14 06:52:05 DEBUG : yam: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f112769b05cbb553c_d20220314_m064034_c001_v0001104_t0030") 2022/03/14 06:52:05 DEBUG : yam: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f117458308dc2368a_d20220314_m064032_c001_v0001101_t0003") 2022/03/14 06:52:05 DEBUG : yam: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f102f53ffa7ff8545_d20220314_m064027_c001_v0001104_t0035") 2022/03/14 06:52:05 DEBUG : yam: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f100a7ab88286653f_d20220314_m064023_c001_v0001104_t0013") 2022/03/14 06:52:05 DEBUG : yaml: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f105405a92f7a808f_d20220314_m064036_c001_v0001105_t0036") 2022/03/14 06:52:05 DEBUG : yaml: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f118f6aab55c2b2d5_d20220314_m064033_c001_v0001157_t0056") 2022/03/14 06:52:05 DEBUG : yaml: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1134496410d84ff9_d20220314_m064030_c001_v0001106_t0005") 2022/03/14 06:52:05 DEBUG : yaml: Deleting (id "4_z4f224d93205d8b7b7ff80e1b_f1044ad2c1e002324_d20220314_m064027_c001_v0001097_t0001") "./sync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -fast-list" - Finished OK in 14m45.881237929s (try 1/5)