"./sync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose" - Starting (try 1/5) 2022/02/10 05:27:08 DEBUG : Creating backend with remote "TestB2:rclone-test-xodejoc8qikusog3paqacuf7" 2022/02/10 05:27:08 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2022/02/10 05:27:09 DEBUG : Creating backend with remote "/tmp/rclone700326362" === 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-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:27:10 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) 2022/02/10 05:27:10 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:27:10 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish --- PASS: TestCopyWithDryRun (1.71s) === RUN TestCopy run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:27:11 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:27:11 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish 2022/02/10 05:27:12 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2022/02/10 05:27:12 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopy (2.27s) === RUN TestCopyMissingDirectory run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:27:13 DEBUG : Creating backend with remote "/non-existing" 2022/02/10 05:27:13 ERROR : Local file system at /non-existing: error reading source root directory: directory not found 2022/02/10 05:27:13 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:27:13 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish --- PASS: TestCopyMissingDirectory (0.51s) === RUN TestCopyNoTraverse run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:27:14 DEBUG : Couldn't decode error response: EOF 2022/02/10 05:27:14 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:27:14 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish 2022/02/10 05:27:16 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2022/02/10 05:27:16 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoTraverse (2.86s) === RUN TestCopyCheckFirst run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:27:17 INFO : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Running all checks before starting transfers 2022/02/10 05:27:17 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:27:17 INFO : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Checks finished, now starting transfers 2022/02/10 05:27:17 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish 2022/02/10 05:27:17 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2022/02/10 05:27:17 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyCheckFirst (1.74s) === RUN TestSyncNoTraverse run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:27:18 ERROR : Ignoring --no-traverse with sync 2022/02/10 05:27:18 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:27:18 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish 2022/02/10 05:27:19 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2022/02/10 05:27:19 INFO : sub dir/hello world: Copied (new) 2022/02/10 05:27:19 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoTraverse (2.64s) === RUN TestCopyWithDepth run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:27:21 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:27:21 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish 2022/02/10 05:27:21 DEBUG : hello world2: sha1 = c0bf117f9c93d02c894fe8ffd6e5cecf3545c209 OK 2022/02/10 05:27:21 INFO : hello world2: Copied (new) --- PASS: TestCopyWithDepth (1.80s) === RUN TestCopyWithFilesFrom run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:27:23 DEBUG : hello world2: Excluded 2022/02/10 05:27:23 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:27:23 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish 2022/02/10 05:27:24 DEBUG : potato2: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2022/02/10 05:27:24 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFrom (2.18s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:27:26 DEBUG : Couldn't decode error response: EOF 2022/02/10 05:27:26 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:27:26 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish 2022/02/10 05:27:26 DEBUG : potato2: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2022/02/10 05:27:26 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFromAndNoTraverse (2.23s) === RUN TestCopyEmptyDirectories run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:27:27 DEBUG : sub dir2: Making directory 2022/02/10 05:27:27 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:27:27 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish 2022/02/10 05:27:28 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2022/02/10 05:27:28 INFO : sub dir/hello world: Copied (new) 2022/02/10 05:27:28 DEBUG : sub dir2: Making directory 2022/02/10 05:27:28 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: copied 1 directories fstest.go:251: Filtering empty directory "sub dir2" --- PASS: TestCopyEmptyDirectories (2.75s) === RUN TestMoveEmptyDirectories run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:27:30 DEBUG : sub dir2: Making directory 2022/02/10 05:27:30 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:27:30 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish 2022/02/10 05:27:31 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2022/02/10 05:27:31 INFO : sub dir/hello world: Copied (new) 2022/02/10 05:27:31 INFO : sub dir/hello world: Deleted 2022/02/10 05:27:31 DEBUG : sub dir: Making directory 2022/02/10 05:27:31 DEBUG : sub dir2: Making directory 2022/02/10 05:27:31 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: copied 2 directories fstest.go:251: Filtering empty directory "sub dir2" --- PASS: TestMoveEmptyDirectories (3.80s) === RUN TestSyncEmptyDirectories run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:27:34 DEBUG : sub dir2: Making directory 2022/02/10 05:27:34 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:27:34 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish 2022/02/10 05:27:34 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2022/02/10 05:27:34 INFO : sub dir/hello world: Copied (new) 2022/02/10 05:27:34 DEBUG : sub dir2: Making directory 2022/02/10 05:27:34 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: copied 1 directories 2022/02/10 05:27:34 DEBUG : Waiting for deletions to finish fstest.go:251: Filtering empty directory "sub dir2" --- PASS: TestSyncEmptyDirectories (2.18s) === RUN TestServerSideCopy run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:27:36 DEBUG : Creating backend with remote "TestB2:rclone-test-furedug7focitih1yiquyig5" sync_test.go:275: Server side copy (if possible) B2 bucket rclone-test-xodejoc8qikusog3paqacuf7 -> B2 bucket rclone-test-furedug7focitih1yiquyig5 2022/02/10 05:27:38 DEBUG : B2 bucket rclone-test-furedug7focitih1yiquyig5: Waiting for checks to finish 2022/02/10 05:27:38 DEBUG : B2 bucket rclone-test-furedug7focitih1yiquyig5: Waiting for transfers to finish 2022/02/10 05:27:39 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2022/02/10 05:27:39 INFO : sub dir/hello world: Copied (server-side copy) 2022/02/10 05:27:39 DEBUG : B2 bucket rclone-test-furedug7focitih1yiquyig5: Purge remote 2022/02/10 05:27:39 DEBUG : sub dir/hello world: Deleting (id "4_zcfc2fd13109deb2b7fe80e1b_f10143756fb709d69_d20220210_m052738_c001_v0001091_t0050") --- PASS: TestServerSideCopy (4.60s) === RUN TestCopyAfterDelete run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:27:41 ERROR : : error listing: directory not found 2022/02/10 05:27:41 DEBUG : Local file system at /tmp/rclone700326362: Making directory 2022/02/10 05:27:41 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:27:41 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish --- PASS: TestCopyAfterDelete (1.67s) === RUN TestCopyRedownload run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:27:43 DEBUG : Local file system at /tmp/rclone700326362: Waiting for checks to finish 2022/02/10 05:27:43 DEBUG : Local file system at /tmp/rclone700326362: Waiting for transfers to finish 2022/02/10 05:27:44 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2022/02/10 05:27:44 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyRedownload (2.89s) === RUN TestSyncBasedOnCheckSum run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:27:45 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:27:45 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish 2022/02/10 05:27:46 DEBUG : check sum: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2022/02/10 05:27:46 INFO : check sum: Copied (new) 2022/02/10 05:27:46 DEBUG : Waiting for deletions to finish 2022/02/10 05:27:46 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:27:46 DEBUG : check sum: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2022/02/10 05:27:46 DEBUG : check sum: Size and sha1 of src and dst objects identical 2022/02/10 05:27:46 DEBUG : check sum: Unchanged skipping 2022/02/10 05:27:46 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish 2022/02/10 05:27:46 DEBUG : Waiting for deletions to finish 2022/02/10 05:27:46 INFO : There was nothing to transfer --- PASS: TestSyncBasedOnCheckSum (2.44s) === RUN TestSyncSizeOnly run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:27:48 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:27:48 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish 2022/02/10 05:27:48 DEBUG : sizeonly: sha1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2022/02/10 05:27:48 INFO : sizeonly: Copied (new) 2022/02/10 05:27:48 DEBUG : Waiting for deletions to finish 2022/02/10 05:27:49 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:27:49 DEBUG : sizeonly: Sizes identical 2022/02/10 05:27:49 DEBUG : sizeonly: Unchanged skipping 2022/02/10 05:27:49 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish 2022/02/10 05:27:49 DEBUG : Waiting for deletions to finish 2022/02/10 05:27:49 INFO : There was nothing to transfer --- PASS: TestSyncSizeOnly (1.96s) === RUN TestSyncIgnoreSize run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:27:50 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:27:50 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish 2022/02/10 05:27:50 DEBUG : ignore-size: sha1 = 4a756ca07e9487f482465a99e8286abc86ba4dc7 OK 2022/02/10 05:27:50 INFO : ignore-size: Copied (new) 2022/02/10 05:27:50 DEBUG : Waiting for deletions to finish 2022/02/10 05:27:50 DEBUG : ignore-size: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:27:50 DEBUG : ignore-size: Unchanged skipping 2022/02/10 05:27:50 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:27:50 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish 2022/02/10 05:27:50 DEBUG : Waiting for deletions to finish 2022/02/10 05:27:50 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreSize (1.94s) === RUN TestSyncIgnoreTimes run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:27:52 DEBUG : existing: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:27:52 DEBUG : existing: Unchanged skipping 2022/02/10 05:27:52 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:27:52 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish 2022/02/10 05:27:52 DEBUG : Waiting for deletions to finish 2022/02/10 05:27:52 INFO : There was nothing to transfer 2022/02/10 05:27:53 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2022/02/10 05:27:53 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:27:53 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish 2022/02/10 05:27:53 DEBUG : existing: sha1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2022/02/10 05:27:53 INFO : existing: Copied (replaced existing) 2022/02/10 05:27:53 DEBUG : Waiting for deletions to finish --- PASS: TestSyncIgnoreTimes (4.36s) === RUN TestSyncIgnoreExisting run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:27:56 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:27:56 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish 2022/02/10 05:27:57 DEBUG : existing: sha1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2022/02/10 05:27:57 INFO : existing: Copied (new) 2022/02/10 05:27:57 DEBUG : Waiting for deletions to finish 2022/02/10 05:27:57 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:27:57 DEBUG : existing: Destination exists, skipping 2022/02/10 05:27:57 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish 2022/02/10 05:27:57 DEBUG : Waiting for deletions to finish 2022/02/10 05:27:57 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreExisting (2.15s) === RUN TestSyncIgnoreErrors run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:28:00 DEBUG : d: Making directory fstest.go:251: Filtering empty directory "d" 2022/02/10 05:28:00 DEBUG : c/non empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/02/10 05:28:00 DEBUG : c/non empty space: Unchanged skipping 2022/02/10 05:28:00 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:28:00 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish 2022/02/10 05:28:00 DEBUG : a/potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2022/02/10 05:28:00 INFO : a/potato2: Copied (new) 2022/02/10 05:28:00 DEBUG : Waiting for deletions to finish 2022/02/10 05:28:02 INFO : b/potato: Deleted --- PASS: TestSyncIgnoreErrors (6.70s) === RUN TestSyncAfterChangingModtimeOnly run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:28:06 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:28:06 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/02/10 05:28:06 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2022/02/10 05:28:06 NOTICE: empty space: Skipped update modification time as --dry-run is set (size 1) 2022/02/10 05:28:06 DEBUG : empty space: Unchanged skipping 2022/02/10 05:28:06 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish 2022/02/10 05:28:06 DEBUG : Waiting for deletions to finish 2022/02/10 05:28:06 INFO : There was nothing to transfer 2022/02/10 05:28:06 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:28:06 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/02/10 05:28:06 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2022/02/10 05:28:08 INFO : empty space: Updated modification time in destination 2022/02/10 05:28:08 DEBUG : empty space: Unchanged skipping 2022/02/10 05:28:08 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish 2022/02/10 05:28:08 DEBUG : Waiting for deletions to finish 2022/02/10 05:28:08 INFO : There was nothing to transfer --- PASS: TestSyncAfterChangingModtimeOnly (6.56s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:28:13 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:28:13 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/02/10 05:28:13 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2022/02/10 05:28:13 DEBUG : empty space: Unchanged skipping 2022/02/10 05:28:13 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish 2022/02/10 05:28:13 DEBUG : Waiting for deletions to finish 2022/02/10 05:28:13 INFO : There was nothing to transfer --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (2.29s) === RUN TestSyncDoesntUpdateModtime run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:28:14 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/02/10 05:28:14 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:28:14 DEBUG : foo: sha1 = 0beec7b5ea3f0fdbc95d0dd47f3c5bc275da8a33 (Local file system at /tmp/rclone700326362) 2022/02/10 05:28:14 DEBUG : foo: sha1 = 62cdb7020ff920e5aa642c3d4066950dd1f01f4d (B2 bucket rclone-test-xodejoc8qikusog3paqacuf7) 2022/02/10 05:28:14 DEBUG : foo: sha1 differ 2022/02/10 05:28:14 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish 2022/02/10 05:28:17 DEBUG : foo: sha1 = 0beec7b5ea3f0fdbc95d0dd47f3c5bc275da8a33 OK 2022/02/10 05:28:17 INFO : foo: Copied (replaced existing) 2022/02/10 05:28:17 DEBUG : Waiting for deletions to finish --- PASS: TestSyncDoesntUpdateModtime (4.28s) === RUN TestSyncAfterAddingAFile run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:28:19 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/02/10 05:28:19 DEBUG : empty space: Unchanged skipping 2022/02/10 05:28:19 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:28:19 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish 2022/02/10 05:28:19 DEBUG : potato: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2022/02/10 05:28:19 INFO : potato: Copied (new) 2022/02/10 05:28:19 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterAddingAFile (3.10s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:28:23 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:28:23 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2022/02/10 05:28:23 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish 2022/02/10 05:28:24 DEBUG : potato: sha1 = 0fd321a1023ac4e1e1054fcc0236b9d0755f722f OK 2022/02/10 05:28:24 INFO : potato: Copied (replaced existing) 2022/02/10 05:28:24 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingFilesSizeOnly (3.79s) === RUN TestSyncAfterChangingContentsOnly run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:28:26 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/02/10 05:28:26 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:28:26 DEBUG : potato: sha1 = 1ca5094b3b73f587249eabe179c40a3eec839b1f (Local file system at /tmp/rclone700326362) 2022/02/10 05:28:26 DEBUG : potato: sha1 = 0fd321a1023ac4e1e1054fcc0236b9d0755f722f (B2 bucket rclone-test-xodejoc8qikusog3paqacuf7) 2022/02/10 05:28:26 DEBUG : potato: sha1 differ 2022/02/10 05:28:26 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish 2022/02/10 05:28:27 DEBUG : potato: sha1 = 1ca5094b3b73f587249eabe179c40a3eec839b1f OK 2022/02/10 05:28:27 INFO : potato: Copied (replaced existing) 2022/02/10 05:28:27 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingContentsOnly (3.03s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:28:29 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2022/02/10 05:28:29 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/02/10 05:28:29 DEBUG : empty space: Unchanged skipping 2022/02/10 05:28:29 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:28:29 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish 2022/02/10 05:28:29 DEBUG : Waiting for deletions to finish 2022/02/10 05:28:29 NOTICE: potato: Skipped delete as --dry-run is set (size 21) --- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (2.59s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:28:32 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:28:32 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/02/10 05:28:32 DEBUG : empty space: Unchanged skipping 2022/02/10 05:28:32 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish 2022/02/10 05:28:32 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2022/02/10 05:28:32 INFO : potato2: Copied (new) 2022/02/10 05:28:32 DEBUG : Waiting for deletions to finish 2022/02/10 05:28:38 INFO : potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (10.32s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:28:45 DEBUG : d: Making directory 2022/02/10 05:28:45 DEBUG : d/e: Making directory fstest.go:251: Filtering empty directory "d" fstest.go:251: Filtering empty directory "d/e" 2022/02/10 05:28:46 DEBUG : c/non empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/02/10 05:28:46 DEBUG : c/non empty space: Unchanged skipping 2022/02/10 05:28:46 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:28:46 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish 2022/02/10 05:28:46 DEBUG : a/potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2022/02/10 05:28:46 INFO : a/potato2: Copied (new) 2022/02/10 05:28:46 DEBUG : Waiting for deletions to finish 2022/02/10 05:28:47 INFO : b/potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (8.05s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:28:50 DEBUG : d: Making directory fstest.go:251: Filtering empty directory "d" 2022/02/10 05:28:50 DEBUG : c/non empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/02/10 05:28:50 DEBUG : c/non empty space: Unchanged skipping 2022/02/10 05:28:50 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:28:50 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish 2022/02/10 05:28:50 DEBUG : a/potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2022/02/10 05:28:50 INFO : a/potato2: Copied (new) 2022/02/10 05:28:50 ERROR : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: not deleting files as there were IO errors 2022/02/10 05:28:50 ERROR : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: not deleting directories as there were IO errors fstest.go:251: Filtering empty directory "d" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (4.24s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:28:55 DEBUG : Waiting for deletions to finish 2022/02/10 05:28:55 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/02/10 05:28:55 DEBUG : empty space: Unchanged skipping 2022/02/10 05:28:55 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:28:55 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish 2022/02/10 05:28:55 INFO : potato: Deleted 2022/02/10 05:28:55 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2022/02/10 05:28:55 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteDuring (3.69s) === RUN TestSyncDeleteBefore run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:28:59 DEBUG : Waiting for deletions to finish 2022/02/10 05:28:59 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:28:59 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish 2022/02/10 05:29:02 INFO : potato: Deleted 2022/02/10 05:29:02 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/02/10 05:29:02 DEBUG : empty space: Unchanged skipping 2022/02/10 05:29:02 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:29:02 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish 2022/02/10 05:29:02 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2022/02/10 05:29:02 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteBefore (12.41s) === RUN TestCopyDeleteBefore run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:29:11 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:29:11 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish 2022/02/10 05:29:13 DEBUG : potato2: sha1 = 810274f0df19dab60a894a562b7a8ff04c072623 OK 2022/02/10 05:29:13 INFO : potato2: Copied (new) --- PASS: TestCopyDeleteBefore (6.39s) === RUN TestSyncWithExclude run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:29:18 DEBUG : enormous: Excluded 2022/02/10 05:29:18 DEBUG : potato2: Excluded 2022/02/10 05:29:18 DEBUG : potato2: Excluded 2022/02/10 05:29:18 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:29:18 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/02/10 05:29:18 DEBUG : empty space: Unchanged skipping 2022/02/10 05:29:18 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish 2022/02/10 05:29:18 DEBUG : Waiting for deletions to finish 2022/02/10 05:29:18 INFO : There was nothing to transfer 2022/02/10 05:29:18 DEBUG : enormous: Excluded 2022/02/10 05:29:18 DEBUG : potato2: Excluded 2022/02/10 05:29:18 DEBUG : potato2: Excluded 2022/02/10 05:29:18 DEBUG : empty space: Size and modification time the same (differ by 456.789µs, within tolerance 1ms) 2022/02/10 05:29:18 DEBUG : empty space: Unchanged skipping 2022/02/10 05:29:18 DEBUG : Local file system at /tmp/rclone700326362: Waiting for checks to finish 2022/02/10 05:29:18 DEBUG : Local file system at /tmp/rclone700326362: Waiting for transfers to finish 2022/02/10 05:29:18 DEBUG : Waiting for deletions to finish 2022/02/10 05:29:18 INFO : There was nothing to transfer --- PASS: TestSyncWithExclude (4.06s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:29:21 DEBUG : enormous: Excluded 2022/02/10 05:29:21 DEBUG : potato2: Excluded 2022/02/10 05:29:21 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/02/10 05:29:21 DEBUG : empty space: Unchanged skipping 2022/02/10 05:29:21 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:29:21 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish 2022/02/10 05:29:21 DEBUG : Waiting for deletions to finish 2022/02/10 05:29:22 INFO : enormous: Deleted 2022/02/10 05:29:22 INFO : potato2: Deleted 2022/02/10 05:29:22 INFO : There was nothing to transfer 2022/02/10 05:29:23 DEBUG : empty space: Size and modification time the same (differ by 456.789µs, within tolerance 1ms) 2022/02/10 05:29:23 DEBUG : empty space: Unchanged skipping 2022/02/10 05:29:23 DEBUG : Local file system at /tmp/rclone700326362: Waiting for checks to finish 2022/02/10 05:29:23 DEBUG : Local file system at /tmp/rclone700326362: Waiting for transfers to finish 2022/02/10 05:29:23 DEBUG : Waiting for deletions to finish 2022/02/10 05:29:23 INFO : enormous: Deleted 2022/02/10 05:29:23 INFO : potato2: Deleted 2022/02/10 05:29:23 INFO : There was nothing to transfer --- PASS: TestSyncWithExcludeAndDeleteExcluded (7.03s) === RUN TestSyncWithUpdateOlder run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:29:37 DEBUG : four: Sizes differ (src 4 vs dst 8) 2022/02/10 05:29:37 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2022/02/10 05:29:37 DEBUG : one: Destination is newer than source, skipping 2022/02/10 05:29:37 DEBUG : three: Sizes identical 2022/02/10 05:29:37 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2022/02/10 05:29:37 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:29:37 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b (Local file system at /tmp/rclone700326362) 2022/02/10 05:29:37 DEBUG : two: sha1 = b10e0da85e90399abfec36c7376b20aa4596461b (B2 bucket rclone-test-xodejoc8qikusog3paqacuf7) 2022/02/10 05:29:37 DEBUG : two: sha1 differ 2022/02/10 05:29:37 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish 2022/02/10 05:29:39 DEBUG : four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2022/02/10 05:29:39 INFO : four: Copied (replaced existing) 2022/02/10 05:29:39 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2022/02/10 05:29:39 INFO : two: Copied (replaced existing) 2022/02/10 05:29:40 DEBUG : five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2022/02/10 05:29:40 INFO : five: Copied (new) 2022/02/10 05:29:40 DEBUG : Waiting for deletions to finish 2022/02/10 05:29:40 DEBUG : one: Destination is newer than source, skipping 2022/02/10 05:29:40 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:29:40 DEBUG : four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2022/02/10 05:29:40 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2022/02/10 05:29:40 DEBUG : four: Size and sha1 of src and dst objects identical 2022/02/10 05:29:40 DEBUG : two: Size and sha1 of src and dst objects identical 2022/02/10 05:29:40 DEBUG : four: Destination mod time is within 1s of source and files identical, skipping 2022/02/10 05:29:40 DEBUG : two: Destination mod time is within 1s of source and files identical, skipping 2022/02/10 05:29:40 DEBUG : three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb (Local file system at /tmp/rclone700326362) 2022/02/10 05:29:40 DEBUG : five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2022/02/10 05:29:40 DEBUG : three: sha1 = 03731cad13f4cf7b59703c77529381cdec1632e5 (B2 bucket rclone-test-xodejoc8qikusog3paqacuf7) 2022/02/10 05:29:40 DEBUG : five: Size and sha1 of src and dst objects identical 2022/02/10 05:29:40 DEBUG : three: sha1 differ 2022/02/10 05:29:40 DEBUG : five: Destination mod time is within 1s of source and files identical, skipping 2022/02/10 05:29:40 DEBUG : three: Destination mod time is within 1s of source but files differ, transferring 2022/02/10 05:29:40 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish 2022/02/10 05:29:40 DEBUG : three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2022/02/10 05:29:40 INFO : three: Copied (replaced existing) 2022/02/10 05:29:40 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithUpdateOlder (20.75s) === 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-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" sync_test.go:1058: Can track renames: true 2022/02/10 05:29:47 INFO : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Making map for --track-renames 2022/02/10 05:29:47 INFO : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Finished making map for --track-renames 2022/02/10 05:29:47 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:29:47 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for renames to finish 2022/02/10 05:29:47 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish 2022/02/10 05:29:48 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2022/02/10 05:29:48 INFO : potato: Copied (new) 2022/02/10 05:29:49 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2022/02/10 05:29:49 INFO : yam: Copied (new) 2022/02/10 05:29:49 DEBUG : Waiting for deletions to finish 2022/02/10 05:29:50 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:29:50 DEBUG : potato: Unchanged skipping 2022/02/10 05:29:50 INFO : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Making map for --track-renames 2022/02/10 05:29:50 INFO : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Finished making map for --track-renames 2022/02/10 05:29:50 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:29:50 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for renames to finish 2022/02/10 05:29:50 DEBUG : Couldn't decode error response: EOF 2022/02/10 05:29:51 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2022/02/10 05:29:51 INFO : yam: Copied (server-side copy) to: yaml 2022/02/10 05:29:53 INFO : yam: Deleted 2022/02/10 05:29:53 INFO : yaml: Renamed from "yam" 2022/02/10 05:29:53 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish 2022/02/10 05:29:53 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenames (7.04s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" sync_test.go:1127: Can track renames: true 2022/02/10 05:29:54 INFO : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Making map for --track-renames 2022/02/10 05:29:54 INFO : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Finished making map for --track-renames 2022/02/10 05:29:54 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:29:54 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for renames to finish 2022/02/10 05:29:54 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish 2022/02/10 05:29:55 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2022/02/10 05:29:55 INFO : potato: Copied (new) 2022/02/10 05:29:56 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2022/02/10 05:29:56 INFO : yam: Copied (new) 2022/02/10 05:29:56 DEBUG : Waiting for deletions to finish 2022/02/10 05:29:56 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:29:56 DEBUG : potato: Unchanged skipping 2022/02/10 05:29:56 INFO : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Making map for --track-renames 2022/02/10 05:29:56 INFO : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Finished making map for --track-renames 2022/02/10 05:29:56 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:29:56 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for renames to finish 2022/02/10 05:29:57 DEBUG : Couldn't decode error response: EOF 2022/02/10 05:29:58 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2022/02/10 05:29:58 INFO : yam: Copied (server-side copy) to: yaml 2022/02/10 05:29:59 INFO : yam: Deleted 2022/02/10 05:29:59 INFO : yaml: Renamed from "yam" 2022/02/10 05:29:59 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish 2022/02/10 05:29:59 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenamesStrategyModtime (6.82s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" sync_test.go:1163: Can track renames: true 2022/02/10 05:30:01 INFO : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Making map for --track-renames 2022/02/10 05:30:01 INFO : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Finished making map for --track-renames 2022/02/10 05:30:01 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:30:01 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for renames to finish 2022/02/10 05:30:01 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish 2022/02/10 05:30:02 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2022/02/10 05:30:02 INFO : potato: Copied (new) 2022/02/10 05:30:03 DEBUG : sub/yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2022/02/10 05:30:03 INFO : sub/yam: Copied (new) 2022/02/10 05:30:03 DEBUG : Waiting for deletions to finish 2022/02/10 05:30:03 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:30:03 DEBUG : potato: Unchanged skipping 2022/02/10 05:30:03 INFO : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Making map for --track-renames 2022/02/10 05:30:03 INFO : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Finished making map for --track-renames 2022/02/10 05:30:03 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:30:03 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for renames to finish 2022/02/10 05:30:04 DEBUG : Couldn't decode error response: EOF 2022/02/10 05:30:07 DEBUG : sub/yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2022/02/10 05:30:07 INFO : sub/yam: Copied (server-side copy) to: yam 2022/02/10 05:30:07 INFO : sub/yam: Deleted 2022/02/10 05:30:07 INFO : yam: Renamed from "sub/yam" 2022/02/10 05:30:07 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish 2022/02/10 05:30:07 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenamesStrategyLeaf (8.89s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:30:10 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:30:10 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish 2022/02/10 05:30:11 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2022/02/10 05:30:11 INFO : sub dir/hello world: Copied (new) 2022/02/10 05:30:11 INFO : sub dir/hello world: Deleted 2022/02/10 05:30:12 DEBUG : nested/sub dir/file: sha1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK 2022/02/10 05:30:12 INFO : nested/sub dir/file: Copied (new) 2022/02/10 05:30:12 INFO : nested/sub dir/file: Deleted 2022/02/10 05:30:12 INFO : sub dir: Removing directory 2022/02/10 05:30:12 INFO : nested/sub dir: Removing directory 2022/02/10 05:30:12 INFO : nested: Removing directory 2022/02/10 05:30:12 DEBUG : Local file system at /tmp/rclone700326362: deleted 3 directories --- PASS: TestMoveWithDeleteEmptySrcDirs (4.45s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:30:15 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:30:15 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish 2022/02/10 05:30:15 DEBUG : nested/sub dir/file: sha1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK 2022/02/10 05:30:15 INFO : nested/sub dir/file: Copied (new) 2022/02/10 05:30:15 INFO : nested/sub dir/file: Deleted 2022/02/10 05:30:16 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2022/02/10 05:30:16 INFO : sub dir/hello world: Copied (new) 2022/02/10 05:30:16 INFO : sub dir/hello world: Deleted --- PASS: TestMoveWithoutDeleteEmptySrcDirs (2.80s) === RUN TestMoveWithIgnoreExisting run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:30:17 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:30:17 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish 2022/02/10 05:30:18 DEBUG : existing-b: sha1 = 321f6b7e8bf7f29aab89c5e41a555b1b0baa41a9 OK 2022/02/10 05:30:18 INFO : existing-b: Copied (new) 2022/02/10 05:30:18 INFO : existing-b: Deleted 2022/02/10 05:30:19 DEBUG : existing: sha1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2022/02/10 05:30:19 INFO : existing: Copied (new) 2022/02/10 05:30:19 INFO : existing: Deleted 2022/02/10 05:30:19 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:30:19 DEBUG : existing: Destination exists, skipping 2022/02/10 05:30:19 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set 2022/02/10 05:30:19 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish 2022/02/10 05:30:19 INFO : There was nothing to transfer --- PASS: TestMoveWithIgnoreExisting (3.25s) === RUN TestServerSideMove run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:30:20 DEBUG : Creating backend with remote "TestB2:rclone-test-cipafaw2wuqocag1peyipig1" sync_test.go:1215: Server side move (if possible) B2 bucket rclone-test-xodejoc8qikusog3paqacuf7 -> B2 bucket rclone-test-cipafaw2wuqocag1peyipig1 2022/02/10 05:30:34 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms) 2022/02/10 05:30:34 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2022/02/10 05:30:34 DEBUG : B2 bucket rclone-test-cipafaw2wuqocag1peyipig1: Waiting for checks to finish 2022/02/10 05:30:34 DEBUG : empty space: Unchanged skipping 2022/02/10 05:30:35 DEBUG : potato3: sha1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2022/02/10 05:30:35 INFO : potato3: Copied (server-side copy) 2022/02/10 05:30:35 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2022/02/10 05:30:35 INFO : potato2: Copied (server-side copy) 2022/02/10 05:30:35 INFO : potato2: Deleted 2022/02/10 05:30:36 INFO : empty space: Deleted 2022/02/10 05:30:36 DEBUG : B2 bucket rclone-test-cipafaw2wuqocag1peyipig1: Waiting for transfers to finish 2022/02/10 05:30:36 INFO : potato3: Deleted 2022/02/10 05:30:36 DEBUG : Creating backend with remote "TestB2:rclone-test-giyovap1qaqaqiw1kokizug0" 2022/02/10 05:30:37 DEBUG : B2 bucket rclone-test-giyovap1qaqaqiw1kokizug0: Waiting for checks to finish 2022/02/10 05:30:37 DEBUG : B2 bucket rclone-test-giyovap1qaqaqiw1kokizug0: Waiting for transfers to finish 2022/02/10 05:30:38 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2022/02/10 05:30:38 INFO : empty space: Copied (server-side copy) 2022/02/10 05:30:38 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2022/02/10 05:30:38 INFO : potato2: Copied (server-side copy) 2022/02/10 05:30:39 DEBUG : potato3: sha1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2022/02/10 05:30:39 INFO : potato3: Copied (server-side copy) 2022/02/10 05:30:39 INFO : potato3: Deleted 2022/02/10 05:30:40 INFO : empty space: Deleted 2022/02/10 05:30:40 INFO : potato2: Deleted 2022/02/10 05:30:41 DEBUG : B2 bucket rclone-test-giyovap1qaqaqiw1kokizug0: Purge remote 2022/02/10 05:30:41 DEBUG : empty space: Deleting (id "4_z9f82adf310cdeb2b7fe80e1b_f1188cae4bbab6889_d20220210_m053038_c001_v0001150_t0050") 2022/02/10 05:30:41 DEBUG : potato2: Deleting (id "4_z9f82adf310cdeb2b7fe80e1b_f112f80d91efba2c9_d20220210_m053038_c001_v0001137_t0053") 2022/02/10 05:30:41 DEBUG : potato3: Deleting (id "4_z9f82adf310cdeb2b7fe80e1b_f10069b8d775db000_d20220210_m053038_c001_v0001029_t0040") 2022/02/10 05:30:42 DEBUG : B2 bucket rclone-test-cipafaw2wuqocag1peyipig1: Purge remote 2022/02/10 05:30:42 DEBUG : empty space: Deleting (id "4_zaf729d6310cdeb2b7fe80e1b_f100d1e6da50a22bf_d20220210_m053038_c001_v0001130_t0036") 2022/02/10 05:30:42 DEBUG : empty space: Deleting (id "4_zaf729d6310cdeb2b7fe80e1b_f1012e8048da3113b_d20220210_m053032_c001_v0001105_t0017") 2022/02/10 05:30:42 DEBUG : potato2: Deleting (id "4_zaf729d6310cdeb2b7fe80e1b_f107b4ab389be4b5a_d20220210_m053039_c001_v0001090_t0057") 2022/02/10 05:30:42 DEBUG : potato2: Deleting (id "4_zaf729d6310cdeb2b7fe80e1b_f106fefbcb80d422b_d20220210_m053034_c001_v0001105_t0012") 2022/02/10 05:30:42 DEBUG : potato3: Deleting (id "4_zaf729d6310cdeb2b7fe80e1b_f11029d6b01cbf6c5_d20220210_m053039_c001_v0001130_t0052") 2022/02/10 05:30:42 DEBUG : potato3: Deleting (id "4_zaf729d6310cdeb2b7fe80e1b_f114f829f7c2057bc_d20220210_m053034_c001_v0001041_t0038") 2022/02/10 05:30:42 DEBUG : potato3: Deleting (id "4_zaf729d6310cdeb2b7fe80e1b_f1012e8048da3114f_d20220210_m053033_c001_v0001105_t0009") --- PASS: TestServerSideMove (22.52s) === RUN TestServerSideMoveWithFilter run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:30:43 DEBUG : Creating backend with remote "TestB2:rclone-test-fonidob1ragugam6kiyojuq6" sync_test.go:1215: Server side move (if possible) B2 bucket rclone-test-xodejoc8qikusog3paqacuf7 -> B2 bucket rclone-test-fonidob1ragugam6kiyojuq6 2022/02/10 05:30:52 DEBUG : empty space: Excluded 2022/02/10 05:30:53 DEBUG : empty space: Excluded 2022/02/10 05:30:53 DEBUG : B2 bucket rclone-test-fonidob1ragugam6kiyojuq6: Waiting for checks to finish 2022/02/10 05:30:53 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2022/02/10 05:30:53 DEBUG : B2 bucket rclone-test-fonidob1ragugam6kiyojuq6: Waiting for transfers to finish 2022/02/10 05:30:53 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2022/02/10 05:30:53 INFO : potato2: Copied (server-side copy) 2022/02/10 05:30:54 INFO : potato2: Deleted 2022/02/10 05:30:54 DEBUG : potato3: sha1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2022/02/10 05:30:54 INFO : potato3: Copied (server-side copy) 2022/02/10 05:30:55 INFO : potato3: Deleted 2022/02/10 05:30:55 DEBUG : Creating backend with remote "TestB2:rclone-test-nifaxob9muzocuq8quyutip1" 2022/02/10 05:30:56 DEBUG : empty space: Excluded 2022/02/10 05:30:56 DEBUG : B2 bucket rclone-test-nifaxob9muzocuq8quyutip1: Waiting for checks to finish 2022/02/10 05:30:56 DEBUG : B2 bucket rclone-test-nifaxob9muzocuq8quyutip1: Waiting for transfers to finish 2022/02/10 05:30:58 DEBUG : potato3: sha1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2022/02/10 05:30:58 INFO : potato3: Copied (server-side copy) 2022/02/10 05:30:59 INFO : potato3: Deleted 2022/02/10 05:31:00 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2022/02/10 05:31:00 INFO : potato2: Copied (server-side copy) 2022/02/10 05:31:01 INFO : potato2: Deleted 2022/02/10 05:31:01 DEBUG : B2 bucket rclone-test-nifaxob9muzocuq8quyutip1: Purge remote 2022/02/10 05:31:02 DEBUG : potato2: Deleting (id "4_zdfb2fd9310cdeb2b7fe80e1b_f108f62b0d55c74c7_d20220210_m053059_c001_v0001040_t0026") 2022/02/10 05:31:02 DEBUG : potato3: Deleting (id "4_zdfb2fd9310cdeb2b7fe80e1b_f114a2faaff7f97c8_d20220210_m053057_c001_v0001105_t0042") 2022/02/10 05:31:02 DEBUG : B2 bucket rclone-test-fonidob1ragugam6kiyojuq6: Purge remote 2022/02/10 05:31:02 DEBUG : empty space: Deleting (id "4_z8f52dde310cdeb2b7fe80e1b_f103f53d810bad583_d20220210_m053050_c001_v0001105_t0009") 2022/02/10 05:31:02 DEBUG : potato2: Deleting (id "4_z8f52dde310cdeb2b7fe80e1b_f101c58a5c1151942_d20220210_m053100_c001_v0001090_t0025") 2022/02/10 05:31:02 DEBUG : potato2: Deleting (id "4_z8f52dde310cdeb2b7fe80e1b_f1047dcedde59e979_d20220210_m053053_c001_v0001146_t0049") 2022/02/10 05:31:02 DEBUG : potato3: Deleting (id "4_z8f52dde310cdeb2b7fe80e1b_f1045cbeea691f401_d20220210_m053058_c001_v0001105_t0058") 2022/02/10 05:31:02 DEBUG : potato3: Deleting (id "4_z8f52dde310cdeb2b7fe80e1b_f101621d15913c1d8_d20220210_m053053_c001_v0001039_t0058") 2022/02/10 05:31:02 DEBUG : potato3: Deleting (id "4_z8f52dde310cdeb2b7fe80e1b_f103f53d810bad593_d20220210_m053051_c001_v0001105_t0041") --- PASS: TestServerSideMoveWithFilter (20.96s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:31:04 DEBUG : Creating backend with remote "TestB2:rclone-test-musofiq8denuzew3losazag9" 2022/02/10 05:31:29 DEBUG : tomatoDir: Making directory sync_test.go:1215: Server side move (if possible) B2 bucket rclone-test-xodejoc8qikusog3paqacuf7 -> B2 bucket rclone-test-musofiq8denuzew3losazag9 2022/02/10 05:31:32 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms) 2022/02/10 05:31:32 DEBUG : empty space: Unchanged skipping 2022/02/10 05:31:32 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2022/02/10 05:31:32 DEBUG : B2 bucket rclone-test-musofiq8denuzew3losazag9: Waiting for checks to finish 2022/02/10 05:31:33 INFO : empty space: Deleted 2022/02/10 05:31:33 DEBUG : B2 bucket rclone-test-musofiq8denuzew3losazag9: Waiting for transfers to finish 2022/02/10 05:31:33 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2022/02/10 05:31:33 INFO : potato2: Copied (server-side copy) 2022/02/10 05:31:33 DEBUG : potato3: sha1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2022/02/10 05:31:33 INFO : potato3: Copied (server-side copy) 2022/02/10 05:31:33 INFO : potato2: Deleted 2022/02/10 05:31:34 INFO : potato3: Deleted 2022/02/10 05:31:34 DEBUG : Creating backend with remote "TestB2:rclone-test-pofoyik3dejukuq2kivagec3" 2022/02/10 05:31:35 DEBUG : tomatoDir: Making directory 2022/02/10 05:31:35 DEBUG : B2 bucket rclone-test-pofoyik3dejukuq2kivagec3: Waiting for checks to finish 2022/02/10 05:31:35 DEBUG : B2 bucket rclone-test-pofoyik3dejukuq2kivagec3: Waiting for transfers to finish 2022/02/10 05:31:36 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2022/02/10 05:31:36 INFO : empty space: Copied (server-side copy) 2022/02/10 05:31:36 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2022/02/10 05:31:36 INFO : potato2: Copied (server-side copy) 2022/02/10 05:31:37 INFO : empty space: Deleted 2022/02/10 05:31:38 INFO : potato2: Deleted 2022/02/10 05:31:39 DEBUG : potato3: sha1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2022/02/10 05:31:39 INFO : potato3: Copied (server-side copy) 2022/02/10 05:31:40 INFO : potato3: Deleted 2022/02/10 05:31:40 DEBUG : B2 bucket rclone-test-pofoyik3dejukuq2kivagec3: Purge remote 2022/02/10 05:31:41 DEBUG : empty space: Deleting (id "4_z1f729d2310ddeb2b7fe80e1b_f1045cbeea691f8b2_d20220210_m053136_c001_v0001105_t0030") 2022/02/10 05:31:41 DEBUG : potato2: Deleting (id "4_z1f729d2310ddeb2b7fe80e1b_f113c4e17cc0531d3_d20220210_m053136_c001_v0001120_t0035") 2022/02/10 05:31:41 DEBUG : potato3: Deleting (id "4_z1f729d2310ddeb2b7fe80e1b_f110afc7d6943445e_d20220210_m053136_c001_v0001146_t0011") 2022/02/10 05:31:41 DEBUG : B2 bucket rclone-test-musofiq8denuzew3losazag9: Purge remote 2022/02/10 05:31:41 DEBUG : empty space: Deleting (id "4_zbf527dd310ddeb2b7fe80e1b_f11754990fb39721f_d20220210_m053136_c001_v0001130_t0045") 2022/02/10 05:31:41 DEBUG : empty space: Deleting (id "4_zbf527dd310ddeb2b7fe80e1b_f1181aea224d95da9_d20220210_m053131_c001_v0001131_t0054") 2022/02/10 05:31:41 DEBUG : potato2: Deleting (id "4_zbf527dd310ddeb2b7fe80e1b_f114e76faaa5cfc7c_d20220210_m053136_c001_v0001109_t0047") 2022/02/10 05:31:41 DEBUG : potato2: Deleting (id "4_zbf527dd310ddeb2b7fe80e1b_f102e18b84b0f2e3a_d20220210_m053133_c001_v0001165_t0038") 2022/02/10 05:31:41 DEBUG : potato3: Deleting (id "4_zbf527dd310ddeb2b7fe80e1b_f1170f7b7d510204b_d20220210_m053139_c001_v0001105_t0014") 2022/02/10 05:31:41 DEBUG : potato3: Deleting (id "4_zbf527dd310ddeb2b7fe80e1b_f1077c1c5b336d045_d20220210_m053133_c001_v0001130_t0033") 2022/02/10 05:31:41 DEBUG : potato3: Deleting (id "4_zbf527dd310ddeb2b7fe80e1b_f1181aea224d95dac_d20220210_m053132_c001_v0001131_t0007") --- PASS: TestServerSideMoveDeleteEmptySourceDirs (38.68s) === RUN TestServerSideMoveOverlap run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:31:43 DEBUG : Creating backend with remote "TestB2:rclone-test-xodejoc8qikusog3paqacuf7/rclone-move-test" 2022/02/10 05:31:44 DEBUG : Couldn't decode error response: EOF --- PASS: TestServerSideMoveOverlap (6.76s) === RUN TestSyncOverlap run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:31:49 DEBUG : Creating backend with remote "TestB2:rclone-test-xodejoc8qikusog3paqacuf7/rclone-sync-test" 2022/02/10 05:31:51 DEBUG : Couldn't decode error response: EOF --- PASS: TestSyncOverlap (1.77s) === RUN TestSyncCompareDest run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:31:51 DEBUG : Creating backend with remote "TestB2:rclone-test-xodejoc8qikusog3paqacuf7/dst" 2022/02/10 05:31:52 DEBUG : Couldn't decode error response: EOF 2022/02/10 05:31:52 DEBUG : Creating backend with remote "TestB2:rclone-test-xodejoc8qikusog3paqacuf7/CompareDest" 2022/02/10 05:31:52 DEBUG : Couldn't decode error response: EOF 2022/02/10 05:31:52 DEBUG : Couldn't decode error response: EOF 2022/02/10 05:31:52 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7 path dst: Waiting for checks to finish 2022/02/10 05:31:52 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7 path dst: Waiting for transfers to finish 2022/02/10 05:31:53 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/02/10 05:31:53 INFO : one: Copied (new) 2022/02/10 05:31:53 DEBUG : Waiting for deletions to finish 2022/02/10 05:31:54 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7 path dst: Waiting for checks to finish 2022/02/10 05:31:54 DEBUG : Couldn't decode error response: EOF 2022/02/10 05:31:54 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/02/10 05:31:54 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7 path dst: Waiting for transfers to finish 2022/02/10 05:31:55 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2022/02/10 05:31:55 INFO : one: Copied (replaced existing) 2022/02/10 05:31:55 DEBUG : Waiting for deletions to finish 2022/02/10 05:32:02 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7 path dst: Waiting for checks to finish 2022/02/10 05:32:03 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/02/10 05:32:03 DEBUG : one: Destination found in --compare-dest, skipping 2022/02/10 05:32:03 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7 path dst: Waiting for transfers to finish 2022/02/10 05:32:03 DEBUG : Waiting for deletions to finish 2022/02/10 05:32:03 INFO : There was nothing to transfer 2022/02/10 05:32:06 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/02/10 05:32:06 DEBUG : two: Destination found in --compare-dest, skipping 2022/02/10 05:32:06 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7 path dst: Waiting for checks to finish 2022/02/10 05:32:06 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/02/10 05:32:06 DEBUG : one: Destination found in --compare-dest, skipping 2022/02/10 05:32:06 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7 path dst: Waiting for transfers to finish 2022/02/10 05:32:06 DEBUG : Waiting for deletions to finish 2022/02/10 05:32:06 INFO : There was nothing to transfer 2022/02/10 05:32:07 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/02/10 05:32:07 DEBUG : two: Destination found in --compare-dest, skipping 2022/02/10 05:32:07 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7 path dst: Waiting for checks to finish 2022/02/10 05:32:07 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/02/10 05:32:07 DEBUG : one: Destination found in --compare-dest, skipping 2022/02/10 05:32:07 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7 path dst: Waiting for transfers to finish 2022/02/10 05:32:07 DEBUG : Waiting for deletions to finish 2022/02/10 05:32:07 INFO : There was nothing to transfer 2022/02/10 05:32:07 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/02/10 05:32:07 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2022/02/10 05:32:07 DEBUG : two: Destination found in --compare-dest, skipping 2022/02/10 05:32:07 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7 path dst: Waiting for checks to finish 2022/02/10 05:32:08 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/02/10 05:32:08 DEBUG : one: Destination found in --compare-dest, skipping 2022/02/10 05:32:08 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7 path dst: Waiting for transfers to finish 2022/02/10 05:32:08 DEBUG : Waiting for deletions to finish 2022/02/10 05:32:08 INFO : There was nothing to transfer 2022/02/10 05:32:08 DEBUG : two: Sizes differ (src 5 vs dst 3) 2022/02/10 05:32:08 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7 path dst: Waiting for checks to finish 2022/02/10 05:32:09 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/02/10 05:32:09 DEBUG : one: Destination found in --compare-dest, skipping 2022/02/10 05:32:09 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7 path dst: Waiting for transfers to finish 2022/02/10 05:32:09 DEBUG : two: sha1 = a4be8877ad88f3109a6411985a1f359e10e2d930 OK 2022/02/10 05:32:09 INFO : two: Copied (new) 2022/02/10 05:32:09 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCompareDest (24.22s) === RUN TestSyncMultipleCompareDest run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:32:19 DEBUG : Creating backend with remote "TestB2:rclone-test-xodejoc8qikusog3paqacuf7/dest" 2022/02/10 05:32:20 DEBUG : Couldn't decode error response: EOF 2022/02/10 05:32:20 DEBUG : Creating backend with remote "TestB2:rclone-test-xodejoc8qikusog3paqacuf7/pre-dest1" 2022/02/10 05:32:21 DEBUG : Couldn't decode error response: EOF 2022/02/10 05:32:21 DEBUG : Creating backend with remote "TestB2:rclone-test-xodejoc8qikusog3paqacuf7/pre-dest2" 2022/02/10 05:32:21 DEBUG : Couldn't decode error response: EOF 2022/02/10 05:32:22 DEBUG : 1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:32:22 DEBUG : 1: Destination found in --compare-dest, skipping 2022/02/10 05:32:22 DEBUG : Couldn't decode error response: EOF 2022/02/10 05:32:22 DEBUG : 2: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:32:22 DEBUG : 2: Destination found in --compare-dest, skipping 2022/02/10 05:32:22 DEBUG : Couldn't decode error response: EOF 2022/02/10 05:32:23 DEBUG : Couldn't decode error response: EOF 2022/02/10 05:32:23 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7 path dest: Waiting for checks to finish 2022/02/10 05:32:23 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7 path dest: Waiting for transfers to finish 2022/02/10 05:32:24 DEBUG : 3: sha1 = 77de68daecd823babbb58edb1c8e14d7106e83bb OK 2022/02/10 05:32:24 INFO : 3: Copied (new) 2022/02/10 05:32:24 DEBUG : Waiting for deletions to finish --- PASS: TestSyncMultipleCompareDest (12.83s) === RUN TestSyncCopyDest run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:32:28 DEBUG : Creating backend with remote "TestB2:rclone-test-xodejoc8qikusog3paqacuf7/dst" 2022/02/10 05:32:30 DEBUG : Couldn't decode error response: EOF 2022/02/10 05:32:30 DEBUG : Creating backend with remote "TestB2:rclone-test-xodejoc8qikusog3paqacuf7/CopyDest" 2022/02/10 05:32:30 DEBUG : Couldn't decode error response: EOF 2022/02/10 05:32:31 DEBUG : Couldn't decode error response: EOF 2022/02/10 05:32:31 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7 path dst: Waiting for checks to finish 2022/02/10 05:32:31 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7 path dst: Waiting for transfers to finish 2022/02/10 05:32:34 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/02/10 05:32:34 INFO : one: Copied (new) 2022/02/10 05:32:34 DEBUG : Waiting for deletions to finish 2022/02/10 05:32:35 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7 path dst: Waiting for checks to finish 2022/02/10 05:32:35 DEBUG : Couldn't decode error response: EOF 2022/02/10 05:32:35 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/02/10 05:32:35 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7 path dst: Waiting for transfers to finish 2022/02/10 05:32:35 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2022/02/10 05:32:35 INFO : one: Copied (replaced existing) 2022/02/10 05:32:35 DEBUG : Waiting for deletions to finish 2022/02/10 05:32:38 DEBUG : Creating backend with remote "TestB2:rclone-test-xodejoc8qikusog3paqacuf7/BackupDir" 2022/02/10 05:32:39 DEBUG : Couldn't decode error response: EOF 2022/02/10 05:32:39 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7 path dst: Waiting for checks to finish 2022/02/10 05:32:39 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/02/10 05:32:39 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/02/10 05:32:39 DEBUG : Couldn't decode error response: EOF 2022/02/10 05:32:41 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/02/10 05:32:41 INFO : one: Copied (server-side copy) 2022/02/10 05:32:42 INFO : one: Deleted 2022/02/10 05:32:43 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2022/02/10 05:32:43 INFO : one: Copied (server-side copy) 2022/02/10 05:32:43 DEBUG : one: Destination found in --copy-dest, using server-side copy 2022/02/10 05:32:43 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7 path dst: Waiting for transfers to finish 2022/02/10 05:32:43 DEBUG : Waiting for deletions to finish 2022/02/10 05:32:45 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/02/10 05:32:46 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2022/02/10 05:32:46 INFO : two: Copied (server-side copy) 2022/02/10 05:32:46 DEBUG : two: Destination found in --copy-dest, using server-side copy 2022/02/10 05:32:46 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7 path dst: Waiting for checks to finish 2022/02/10 05:32:46 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/02/10 05:32:46 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/02/10 05:32:46 DEBUG : one: Unchanged skipping 2022/02/10 05:32:46 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7 path dst: Waiting for transfers to finish 2022/02/10 05:32:46 DEBUG : Waiting for deletions to finish 2022/02/10 05:32:47 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7 path dst: Waiting for checks to finish 2022/02/10 05:32:47 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/02/10 05:32:47 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/02/10 05:32:47 DEBUG : one: Unchanged skipping 2022/02/10 05:32:47 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/02/10 05:32:47 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/02/10 05:32:47 DEBUG : two: Unchanged skipping 2022/02/10 05:32:47 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7 path dst: Waiting for transfers to finish 2022/02/10 05:32:47 DEBUG : Waiting for deletions to finish 2022/02/10 05:32:47 INFO : There was nothing to transfer 2022/02/10 05:32:50 DEBUG : three: Sizes differ (src 7 vs dst 5) 2022/02/10 05:32:50 DEBUG : three: Destination not found in --copy-dest 2022/02/10 05:32:50 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7 path dst: Waiting for checks to finish 2022/02/10 05:32:50 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/02/10 05:32:50 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/02/10 05:32:50 DEBUG : one: Unchanged skipping 2022/02/10 05:32:50 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/02/10 05:32:50 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/02/10 05:32:50 DEBUG : two: Unchanged skipping 2022/02/10 05:32:50 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7 path dst: Waiting for transfers to finish 2022/02/10 05:32:51 DEBUG : three: sha1 = 370e613335f3cf2f19b2a2d35a86402f55c659f8 OK 2022/02/10 05:32:51 INFO : three: Copied (new) 2022/02/10 05:32:51 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCopyDest (30.05s) === RUN TestSyncBackupDir run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:33:02 DEBUG : Creating backend with remote "TestB2:rclone-test-xodejoc8qikusog3paqacuf7/dst" 2022/02/10 05:33:03 DEBUG : Couldn't decode error response: EOF 2022/02/10 05:33:03 DEBUG : Creating backend with remote "TestB2:rclone-test-xodejoc8qikusog3paqacuf7/backup" 2022/02/10 05:33:03 DEBUG : Couldn't decode error response: EOF 2022/02/10 05:33:03 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/02/10 05:33:03 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7 path dst: Waiting for checks to finish 2022/02/10 05:33:03 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:33:03 DEBUG : two: Unchanged skipping 2022/02/10 05:33:04 DEBUG : Couldn't decode error response: EOF 2022/02/10 05:33:05 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/02/10 05:33:05 INFO : one: Copied (server-side copy) 2022/02/10 05:33:06 INFO : one: Deleted 2022/02/10 05:33:06 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7 path dst: Waiting for transfers to finish 2022/02/10 05:33:07 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2022/02/10 05:33:07 INFO : one: Copied (new) 2022/02/10 05:33:07 DEBUG : Waiting for deletions to finish 2022/02/10 05:33:07 DEBUG : Couldn't decode error response: EOF 2022/02/10 05:33:08 DEBUG : three.txt: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2022/02/10 05:33:08 INFO : three.txt: Copied (server-side copy) 2022/02/10 05:33:09 INFO : three.txt: Deleted 2022/02/10 05:33:09 INFO : three.txt: Moved into backup dir 2022/02/10 05:33:31 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/02/10 05:33:31 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:33:31 DEBUG : two: Unchanged skipping 2022/02/10 05:33:31 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7 path dst: Waiting for checks to finish 2022/02/10 05:33:33 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2022/02/10 05:33:33 INFO : one: Copied (server-side copy) 2022/02/10 05:33:33 INFO : one: Deleted 2022/02/10 05:33:33 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7 path dst: Waiting for transfers to finish 2022/02/10 05:33:34 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2022/02/10 05:33:34 INFO : one: Copied (new) 2022/02/10 05:33:34 DEBUG : Waiting for deletions to finish 2022/02/10 05:33:36 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2022/02/10 05:33:36 INFO : three.txt: Copied (server-side copy) 2022/02/10 05:33:36 INFO : three.txt: Deleted 2022/02/10 05:33:36 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (40.30s) === RUN TestSyncBackupDirWithSuffix run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:33:43 DEBUG : Creating backend with remote "TestB2:rclone-test-xodejoc8qikusog3paqacuf7/dst" 2022/02/10 05:33:45 DEBUG : Couldn't decode error response: EOF 2022/02/10 05:33:45 DEBUG : Creating backend with remote "TestB2:rclone-test-xodejoc8qikusog3paqacuf7/backup" 2022/02/10 05:33:45 DEBUG : Couldn't decode error response: EOF 2022/02/10 05:33:46 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/02/10 05:33:46 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:33:46 DEBUG : two: Unchanged skipping 2022/02/10 05:33:46 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7 path dst: Waiting for checks to finish 2022/02/10 05:33:46 DEBUG : Couldn't decode error response: EOF 2022/02/10 05:33:47 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/02/10 05:33:47 INFO : one: Copied (server-side copy) to: one.bak 2022/02/10 05:33:48 INFO : one: Deleted 2022/02/10 05:33:48 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7 path dst: Waiting for transfers to finish 2022/02/10 05:33:49 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2022/02/10 05:33:49 INFO : one: Copied (new) 2022/02/10 05:33:49 DEBUG : Waiting for deletions to finish 2022/02/10 05:33:50 DEBUG : Couldn't decode error response: EOF 2022/02/10 05:33:50 DEBUG : three.txt: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2022/02/10 05:33:50 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/02/10 05:33:52 INFO : three.txt: Deleted 2022/02/10 05:33:52 INFO : three.txt: Moved into backup dir 2022/02/10 05:33:53 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/02/10 05:33:53 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:33:53 DEBUG : two: Unchanged skipping 2022/02/10 05:33:53 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7 path dst: Waiting for checks to finish 2022/02/10 05:33:55 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2022/02/10 05:33:55 INFO : one: Copied (server-side copy) to: one.bak 2022/02/10 05:33:56 INFO : one: Deleted 2022/02/10 05:33:56 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7 path dst: Waiting for transfers to finish 2022/02/10 05:33:59 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2022/02/10 05:33:59 INFO : one: Copied (new) 2022/02/10 05:33:59 DEBUG : Waiting for deletions to finish 2022/02/10 05:34:02 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2022/02/10 05:34:02 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/02/10 05:34:02 INFO : three.txt: Deleted 2022/02/10 05:34:02 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (26.55s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:35:04 DEBUG : Creating backend with remote "TestB2:rclone-test-xodejoc8qikusog3paqacuf7/dst" 2022/02/10 05:35:05 DEBUG : Couldn't decode error response: EOF 2022/02/10 05:35:05 DEBUG : Creating backend with remote "TestB2:rclone-test-xodejoc8qikusog3paqacuf7/backup" 2022/02/10 05:35:05 DEBUG : Couldn't decode error response: EOF 2022/02/10 05:35:06 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/02/10 05:35:06 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:35:06 DEBUG : two: Unchanged skipping 2022/02/10 05:35:06 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7 path dst: Waiting for checks to finish 2022/02/10 05:35:06 DEBUG : Couldn't decode error response: EOF 2022/02/10 05:35:08 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/02/10 05:35:08 INFO : one: Copied (server-side copy) to: one-2019-01-01 2022/02/10 05:35:08 INFO : one: Deleted 2022/02/10 05:35:08 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7 path dst: Waiting for transfers to finish 2022/02/10 05:35:09 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2022/02/10 05:35:09 INFO : one: Copied (new) 2022/02/10 05:35:09 DEBUG : Waiting for deletions to finish 2022/02/10 05:35:09 DEBUG : Couldn't decode error response: EOF 2022/02/10 05:35:11 DEBUG : three.txt: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2022/02/10 05:35:11 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2022/02/10 05:35:12 INFO : three.txt: Deleted 2022/02/10 05:35:12 INFO : three.txt: Moved into backup dir 2022/02/10 05:35:14 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/02/10 05:35:14 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:35:14 DEBUG : two: Unchanged skipping 2022/02/10 05:35:14 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7 path dst: Waiting for checks to finish 2022/02/10 05:35:15 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2022/02/10 05:35:15 INFO : one: Copied (server-side copy) to: one-2019-01-01 2022/02/10 05:35:15 INFO : one: Deleted 2022/02/10 05:35:15 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7 path dst: Waiting for transfers to finish 2022/02/10 05:35:17 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2022/02/10 05:35:17 INFO : one: Copied (new) 2022/02/10 05:35:17 DEBUG : Waiting for deletions to finish 2022/02/10 05:35:18 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2022/02/10 05:35:18 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2022/02/10 05:35:19 INFO : three.txt: Deleted 2022/02/10 05:35:19 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (76.15s) === RUN TestSyncBackupDirSuffixOnly run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:35:28 DEBUG : Creating backend with remote "TestB2:rclone-test-xodejoc8qikusog3paqacuf7/dst" 2022/02/10 05:35:29 DEBUG : Couldn't decode error response: EOF 2022/02/10 05:35:30 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7 path dst: Waiting for checks to finish 2022/02/10 05:35:30 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/02/10 05:35:30 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:35:30 DEBUG : two: Unchanged skipping 2022/02/10 05:35:30 DEBUG : Couldn't decode error response: EOF 2022/02/10 05:35:31 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/02/10 05:35:31 INFO : one: Copied (server-side copy) to: one.bak 2022/02/10 05:35:31 INFO : one: Deleted 2022/02/10 05:35:31 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7 path dst: Waiting for transfers to finish 2022/02/10 05:35:33 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2022/02/10 05:35:33 INFO : one: Copied (new) 2022/02/10 05:35:33 DEBUG : Waiting for deletions to finish 2022/02/10 05:35:33 DEBUG : Couldn't decode error response: EOF 2022/02/10 05:35:34 DEBUG : three.txt: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2022/02/10 05:35:34 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/02/10 05:35:34 INFO : three.txt: Deleted 2022/02/10 05:35:34 INFO : three.txt: Moved into backup dir 2022/02/10 05:35:37 DEBUG : one.bak: Excluded 2022/02/10 05:35:37 DEBUG : three.txt.bak: Excluded 2022/02/10 05:35:37 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/02/10 05:35:37 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:35:37 DEBUG : two: Unchanged skipping 2022/02/10 05:35:37 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7 path dst: Waiting for checks to finish 2022/02/10 05:35:37 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2022/02/10 05:35:37 INFO : one: Copied (server-side copy) to: one.bak 2022/02/10 05:35:38 INFO : one: Deleted 2022/02/10 05:35:38 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7 path dst: Waiting for transfers to finish 2022/02/10 05:35:39 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2022/02/10 05:35:39 INFO : one: Copied (new) 2022/02/10 05:35:39 DEBUG : Waiting for deletions to finish 2022/02/10 05:35:40 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2022/02/10 05:35:40 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/02/10 05:35:41 INFO : three.txt: Deleted 2022/02/10 05:35:41 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirSuffixOnly (23.13s) === RUN TestSyncSuffix run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:35:48 DEBUG : Creating backend with remote "TestB2:rclone-test-xodejoc8qikusog3paqacuf7/dst" 2022/02/10 05:35:50 DEBUG : Couldn't decode error response: EOF 2022/02/10 05:35:50 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/02/10 05:35:50 DEBUG : Couldn't decode error response: EOF 2022/02/10 05:35:52 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/02/10 05:35:52 INFO : one: Copied (server-side copy) to: one.bak 2022/02/10 05:35:52 INFO : one: Deleted 2022/02/10 05:35:54 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2022/02/10 05:35:54 INFO : one: Copied (new) 2022/02/10 05:35:54 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:35:54 DEBUG : two: Unchanged skipping 2022/02/10 05:35:54 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2022/02/10 05:35:54 DEBUG : Couldn't decode error response: EOF 2022/02/10 05:35:55 DEBUG : three.txt: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2022/02/10 05:35:55 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/02/10 05:35:57 INFO : three.txt: Deleted 2022/02/10 05:35:58 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2022/02/10 05:35:58 INFO : three.txt: Copied (new) 2022/02/10 05:35:59 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/02/10 05:35:59 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2022/02/10 05:35:59 INFO : one: Copied (server-side copy) to: one.bak 2022/02/10 05:36:00 INFO : one: Deleted 2022/02/10 05:36:01 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2022/02/10 05:36:01 INFO : one: Copied (new) 2022/02/10 05:36:02 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:36:02 DEBUG : two: Unchanged skipping 2022/02/10 05:36:02 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2022/02/10 05:36:02 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2022/02/10 05:36:02 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/02/10 05:36:03 INFO : three.txt: Deleted 2022/02/10 05:36:04 DEBUG : three.txt: sha1 = 476e1707cbd7f0497a7dc4d44f0b07b8566ffc30 OK 2022/02/10 05:36:04 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (24.62s) === RUN TestSyncSuffixKeepExtension run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:36:13 DEBUG : Creating backend with remote "TestB2:rclone-test-xodejoc8qikusog3paqacuf7/dst" 2022/02/10 05:36:15 DEBUG : Couldn't decode error response: EOF 2022/02/10 05:36:15 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/02/10 05:36:15 DEBUG : Couldn't decode error response: EOF 2022/02/10 05:36:17 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/02/10 05:36:17 INFO : one: Copied (server-side copy) to: one-2019-01-01 2022/02/10 05:36:17 INFO : one: Deleted 2022/02/10 05:36:18 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2022/02/10 05:36:18 INFO : one: Copied (new) 2022/02/10 05:36:18 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:36:18 DEBUG : two: Unchanged skipping 2022/02/10 05:36:18 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2022/02/10 05:36:18 DEBUG : Couldn't decode error response: EOF 2022/02/10 05:36:20 DEBUG : three.txt: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2022/02/10 05:36:20 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2022/02/10 05:36:22 INFO : three.txt: Deleted 2022/02/10 05:36:24 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2022/02/10 05:36:24 INFO : three.txt: Copied (new) 2022/02/10 05:36:25 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/02/10 05:36:26 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2022/02/10 05:36:26 INFO : one: Copied (server-side copy) to: one-2019-01-01 2022/02/10 05:36:26 INFO : one: Deleted 2022/02/10 05:36:27 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2022/02/10 05:36:27 INFO : one: Copied (new) 2022/02/10 05:36:27 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:36:27 DEBUG : two: Unchanged skipping 2022/02/10 05:36:28 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2022/02/10 05:36:30 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2022/02/10 05:36:30 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2022/02/10 05:36:31 INFO : three.txt: Deleted 2022/02/10 05:36:32 DEBUG : three.txt: sha1 = 476e1707cbd7f0497a7dc4d44f0b07b8566ffc30 OK 2022/02/10 05:36:32 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (26.84s) === RUN TestSyncUTFNorm run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:36:37 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2022/02/10 05:36:37 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:36:37 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish 2022/02/10 05:36:40 DEBUG : Testêé: sha1 = a54d88e06612d820bc3be72877c74f257b561b19 OK 2022/02/10 05:36:40 INFO : Testêé: Copied (replaced existing) to: Testêé 2022/02/10 05:36:40 DEBUG : Waiting for deletions to finish --- PASS: TestSyncUTFNorm (5.22s) === RUN TestSyncImmutable run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:36:41 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:36:41 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish 2022/02/10 05:36:43 DEBUG : existing: sha1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2022/02/10 05:36:43 INFO : existing: Copied (new) 2022/02/10 05:36:43 DEBUG : Waiting for deletions to finish 2022/02/10 05:36:44 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2022/02/10 05:36:44 ERROR : existing: Source and destination exist but do not match: immutable file modified 2022/02/10 05:36:44 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:36:44 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish 2022/02/10 05:36:44 ERROR : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: not deleting files as there were IO errors 2022/02/10 05:36:44 ERROR : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: not deleting directories as there were IO errors --- PASS: TestSyncImmutable (3.87s) === RUN TestSyncIgnoreCase run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:36:46 DEBUG : existing: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:36:46 DEBUG : existing: Unchanged skipping 2022/02/10 05:36:46 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:36:46 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish 2022/02/10 05:36:46 DEBUG : Waiting for deletions to finish 2022/02/10 05:36:46 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreCase (2.51s) === RUN TestMaxTransfer === RUN TestMaxTransfer/Hard run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" sync_test.go:1989: This test only runs on local === RUN TestMaxTransfer/Soft run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" sync_test.go:1989: This test only runs on local === RUN TestMaxTransfer/Cautious run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", 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-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:37:28 DEBUG : both0: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:37:28 DEBUG : both1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:37:28 DEBUG : both1: Unchanged skipping 2022/02/10 05:37:28 DEBUG : both10: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:37:28 DEBUG : both10: Unchanged skipping 2022/02/10 05:37:28 DEBUG : both11: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:37:28 DEBUG : both11: Unchanged skipping 2022/02/10 05:37:28 DEBUG : both12: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:37:28 DEBUG : both13: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:37:28 DEBUG : both13: Unchanged skipping 2022/02/10 05:37:28 DEBUG : both14: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:37:28 DEBUG : both14: Unchanged skipping 2022/02/10 05:37:28 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:37:28 DEBUG : both15: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:37:28 DEBUG : both15: Unchanged skipping 2022/02/10 05:37:28 DEBUG : both17: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:37:28 DEBUG : both16: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:37:28 DEBUG : both0: Unchanged skipping 2022/02/10 05:37:28 DEBUG : both16: Unchanged skipping 2022/02/10 05:37:28 DEBUG : both18: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:37:28 DEBUG : both19: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:37:28 DEBUG : both19: Unchanged skipping 2022/02/10 05:37:28 DEBUG : both12: Unchanged skipping 2022/02/10 05:37:28 DEBUG : both2: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:37:28 DEBUG : both2: Unchanged skipping 2022/02/10 05:37:28 DEBUG : both3: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:37:28 DEBUG : both3: Unchanged skipping 2022/02/10 05:37:28 DEBUG : both4: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:37:28 DEBUG : both5: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:37:28 DEBUG : both4: Unchanged skipping 2022/02/10 05:37:28 DEBUG : both6: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:37:28 DEBUG : both6: Unchanged skipping 2022/02/10 05:37:28 DEBUG : both7: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:37:28 DEBUG : both7: Unchanged skipping 2022/02/10 05:37:28 DEBUG : both8: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:37:28 DEBUG : both8: Unchanged skipping 2022/02/10 05:37:28 DEBUG : both9: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:37:28 DEBUG : both9: Unchanged skipping 2022/02/10 05:37:28 DEBUG : both17: Unchanged skipping 2022/02/10 05:37:28 DEBUG : both5: Unchanged skipping 2022/02/10 05:37:28 DEBUG : both18: Unchanged skipping 2022/02/10 05:37:28 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish 2022/02/10 05:37:28 DEBUG : Waiting for deletions to finish 2022/02/10 05:37:28 INFO : only5: Deleted 2022/02/10 05:37:29 INFO : only1: Deleted 2022/02/10 05:37:29 INFO : only18: Deleted 2022/02/10 05:37:29 INFO : only14: Deleted 2022/02/10 05:37:29 INFO : only17: Deleted 2022/02/10 05:37:30 INFO : only19: Deleted 2022/02/10 05:37:30 INFO : only2: Deleted 2022/02/10 05:37:31 INFO : only10: Deleted 2022/02/10 05:37:31 INFO : only8: Deleted 2022/02/10 05:37:31 INFO : only16: Deleted 2022/02/10 05:37:32 INFO : only3: Deleted 2022/02/10 05:37:32 INFO : only6: Deleted 2022/02/10 05:37:32 INFO : only4: Deleted 2022/02/10 05:37:32 INFO : only0: Deleted 2022/02/10 05:37:32 INFO : only11: Deleted 2022/02/10 05:37:32 INFO : only7: Deleted 2022/02/10 05:37:32 INFO : only9: Deleted 2022/02/10 05:37:33 INFO : only13: Deleted 2022/02/10 05:37:33 INFO : only15: Deleted 2022/02/10 05:37:33 INFO : only12: Deleted 2022/02/10 05:37:33 INFO : There was nothing to transfer --- PASS: TestSyncConcurrentDelete (66.28s) === RUN TestSyncConcurrentTruncate run.go:181: Remote "B2 bucket rclone-test-xodejoc8qikusog3paqacuf7", Local "Local file system at /tmp/rclone700326362", Modify Window "1ms" 2022/02/10 05:38:29 DEBUG : both0: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:38:29 DEBUG : both0: Unchanged skipping 2022/02/10 05:38:29 DEBUG : both1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:38:29 DEBUG : both1: Unchanged skipping 2022/02/10 05:38:29 DEBUG : both10: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:38:29 DEBUG : both10: Unchanged skipping 2022/02/10 05:38:29 DEBUG : both11: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:38:29 DEBUG : both11: Unchanged skipping 2022/02/10 05:38:29 DEBUG : both12: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:38:29 DEBUG : both12: Unchanged skipping 2022/02/10 05:38:29 DEBUG : both13: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:38:29 DEBUG : both13: Unchanged skipping 2022/02/10 05:38:29 DEBUG : both14: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:38:29 DEBUG : both14: Unchanged skipping 2022/02/10 05:38:29 DEBUG : both15: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:38:29 DEBUG : both15: Unchanged skipping 2022/02/10 05:38:29 DEBUG : both16: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:38:29 DEBUG : both16: Unchanged skipping 2022/02/10 05:38:29 DEBUG : both17: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:38:29 DEBUG : both17: Unchanged skipping 2022/02/10 05:38:29 DEBUG : both18: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:38:29 DEBUG : both18: Unchanged skipping 2022/02/10 05:38:29 DEBUG : both19: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:38:29 DEBUG : both19: Unchanged skipping 2022/02/10 05:38:29 DEBUG : both2: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:38:29 DEBUG : both2: Unchanged skipping 2022/02/10 05:38:29 DEBUG : both3: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:38:29 DEBUG : both3: Unchanged skipping 2022/02/10 05:38:29 DEBUG : both4: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:38:29 DEBUG : both4: Unchanged skipping 2022/02/10 05:38:29 DEBUG : both5: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:38:29 DEBUG : both5: Unchanged skipping 2022/02/10 05:38:29 DEBUG : both6: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:38:29 DEBUG : both6: Unchanged skipping 2022/02/10 05:38:29 DEBUG : both7: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:38:29 DEBUG : both7: Unchanged skipping 2022/02/10 05:38:29 DEBUG : both8: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:38:29 DEBUG : both8: Unchanged skipping 2022/02/10 05:38:29 DEBUG : both9: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/02/10 05:38:29 DEBUG : both9: Unchanged skipping 2022/02/10 05:38:29 DEBUG : only0: Sizes differ (src 0 vs dst 6) 2022/02/10 05:38:29 DEBUG : only1: Sizes differ (src 0 vs dst 6) 2022/02/10 05:38:29 DEBUG : only10: Sizes differ (src 0 vs dst 6) 2022/02/10 05:38:29 DEBUG : only11: Sizes differ (src 0 vs dst 6) 2022/02/10 05:38:29 DEBUG : only12: Sizes differ (src 0 vs dst 6) 2022/02/10 05:38:29 DEBUG : only13: Sizes differ (src 0 vs dst 6) 2022/02/10 05:38:29 DEBUG : only14: Sizes differ (src 0 vs dst 6) 2022/02/10 05:38:29 DEBUG : only15: Sizes differ (src 0 vs dst 6) 2022/02/10 05:38:29 DEBUG : only16: Sizes differ (src 0 vs dst 6) 2022/02/10 05:38:29 DEBUG : only17: Sizes differ (src 0 vs dst 6) 2022/02/10 05:38:29 DEBUG : only18: Sizes differ (src 0 vs dst 6) 2022/02/10 05:38:29 DEBUG : only19: Sizes differ (src 0 vs dst 6) 2022/02/10 05:38:29 DEBUG : only2: Sizes differ (src 0 vs dst 6) 2022/02/10 05:38:29 DEBUG : only3: Sizes differ (src 0 vs dst 6) 2022/02/10 05:38:29 DEBUG : only4: Sizes differ (src 0 vs dst 6) 2022/02/10 05:38:29 DEBUG : only5: Sizes differ (src 0 vs dst 6) 2022/02/10 05:38:29 DEBUG : only6: Sizes differ (src 0 vs dst 6) 2022/02/10 05:38:29 DEBUG : only7: Sizes differ (src 0 vs dst 6) 2022/02/10 05:38:29 DEBUG : only8: Sizes differ (src 0 vs dst 6) 2022/02/10 05:38:29 DEBUG : only9: Sizes differ (src 0 vs dst 6) 2022/02/10 05:38:29 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for checks to finish 2022/02/10 05:38:29 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Waiting for transfers to finish 2022/02/10 05:38:29 DEBUG : only0: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/02/10 05:38:29 INFO : only0: Copied (replaced existing) 2022/02/10 05:38:29 DEBUG : only10: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/02/10 05:38:29 INFO : only10: Copied (replaced existing) 2022/02/10 05:38:29 DEBUG : only1: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/02/10 05:38:29 INFO : only1: Copied (replaced existing) 2022/02/10 05:38:29 DEBUG : only12: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/02/10 05:38:29 INFO : only12: Copied (replaced existing) 2022/02/10 05:38:30 DEBUG : only13: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/02/10 05:38:30 INFO : only13: Copied (replaced existing) 2022/02/10 05:38:30 DEBUG : only15: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/02/10 05:38:30 INFO : only15: Copied (replaced existing) 2022/02/10 05:38:30 DEBUG : only14: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/02/10 05:38:30 INFO : only14: Copied (replaced existing) 2022/02/10 05:38:30 DEBUG : only16: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/02/10 05:38:30 INFO : only16: Copied (replaced existing) 2022/02/10 05:38:31 DEBUG : only17: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/02/10 05:38:31 INFO : only17: Copied (replaced existing) 2022/02/10 05:38:31 DEBUG : only19: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/02/10 05:38:31 INFO : only19: Copied (replaced existing) 2022/02/10 05:38:31 DEBUG : only2: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/02/10 05:38:31 INFO : only2: Copied (replaced existing) 2022/02/10 05:38:31 DEBUG : only11: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/02/10 05:38:31 INFO : only11: Copied (replaced existing) 2022/02/10 05:38:31 DEBUG : only3: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/02/10 05:38:31 INFO : only3: Copied (replaced existing) 2022/02/10 05:38:32 DEBUG : only4: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/02/10 05:38:32 INFO : only4: Copied (replaced existing) 2022/02/10 05:38:32 DEBUG : only6: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/02/10 05:38:32 INFO : only6: Copied (replaced existing) 2022/02/10 05:38:32 DEBUG : only18: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/02/10 05:38:32 INFO : only18: Copied (replaced existing) 2022/02/10 05:38:32 DEBUG : only5: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/02/10 05:38:32 INFO : only5: Copied (replaced existing) 2022/02/10 05:38:32 DEBUG : only7: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/02/10 05:38:32 INFO : only7: Copied (replaced existing) 2022/02/10 05:38:32 DEBUG : only8: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/02/10 05:38:32 INFO : only8: Copied (replaced existing) 2022/02/10 05:38:33 DEBUG : only9: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/02/10 05:38:33 INFO : only9: Copied (replaced existing) 2022/02/10 05:38:33 DEBUG : Waiting for deletions to finish --- PASS: TestSyncConcurrentTruncate (70.18s) PASS 2022/02/10 05:39:05 DEBUG : B2 bucket rclone-test-xodejoc8qikusog3paqacuf7: Purge remote 2022/02/10 05:39:06 DEBUG : BackupDir/one: Deleting (id "4_zff828d43109deb2b7fe80e1b_f119ac3b2e8a3a03d_d20220210_m053252_c001_v0001120_t0005") 2022/02/10 05:39:06 DEBUG : BackupDir/one: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1170f7b7d51026fe_d20220210_m053240_c001_v0001105_t0056") 2022/02/10 05:39:06 DEBUG : CompareDest/one: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1156841bd2c3232d_d20220210_m053210_c001_v0001040_t0045") 2022/02/10 05:39:06 DEBUG : CompareDest/one: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1029bd6e47722807_d20220210_m053159_c001_v0001021_t0015") 2022/02/10 05:39:06 DEBUG : CompareDest/two: Deleting (id "4_zff828d43109deb2b7fe80e1b_f10505575a4fa61c5_d20220210_m053211_c001_v0001098_t0053") 2022/02/10 05:39:06 DEBUG : CompareDest/two: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1026383dfb30c4eb_d20220210_m053204_c001_v0001105_t0015") 2022/02/10 05:39:06 DEBUG : CopyDest/one: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1122bf25fc8bca65_d20220210_m053252_c001_v0001105_t0035") 2022/02/10 05:39:06 DEBUG : CopyDest/one: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd754_d20220210_m053237_c001_v0001022_t0011") 2022/02/10 05:39:06 DEBUG : CopyDest/three: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1099b9e4e0e8b3fb_d20220210_m053253_c001_v0001098_t0009") 2022/02/10 05:39:06 DEBUG : CopyDest/three: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1026383dfb30cab0_d20220210_m053248_c001_v0001105_t0003") 2022/02/10 05:39:06 DEBUG : CopyDest/two: Deleting (id "4_zff828d43109deb2b7fe80e1b_f106fefbcb80d53cc_d20220210_m053254_c001_v0001105_t0053") 2022/02/10 05:39:06 DEBUG : CopyDest/two: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1029bd6e4772281c_d20220210_m053244_c001_v0001021_t0004") 2022/02/10 05:39:06 DEBUG : EXISTING: Deleting (id "4_zff828d43109deb2b7fe80e1b_f115df8ac68a9ed4f_d20220210_m053647_c001_v0001041_t0047") 2022/02/10 05:39:06 DEBUG : EXISTING: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1029bd6e477228b7_d20220210_m053646_c001_v0001021_t0016") 2022/02/10 05:39:06 DEBUG : Testêé: Deleting (id "4_zff828d43109deb2b7fe80e1b_f107a412e413a8f7d_d20220210_m053640_c001_v0001144_t0044") 2022/02/10 05:39:06 DEBUG : Testêé: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1026383dfb30e876_d20220210_m053638_c001_v0001105_t0016") 2022/02/10 05:39:06 DEBUG : Testêé: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1029bd6e477228ab_d20220210_m053636_c001_v0001021_t0029") 2022/02/10 05:39:06 DEBUG : a/potato2: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1160be313b6389bf_d20220210_m052851_c001_v0001105_t0025") 2022/02/10 05:39:06 DEBUG : a/potato2: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd67c_d20220210_m052850_c001_v0001022_t0015") 2022/02/10 05:39:07 DEBUG : a/potato2: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11029d6b01cbf3d9_d20220210_m052848_c001_v0001130_t0047") 2022/02/10 05:39:07 DEBUG : a/potato2: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd66c_d20220210_m052846_c001_v0001022_t0031") 2022/02/10 05:39:07 DEBUG : a/potato2: Deleting (id "4_zff828d43109deb2b7fe80e1b_f109f52975a9205c4_d20220210_m052802_c001_v0001146_t0056") 2022/02/10 05:39:07 DEBUG : a/potato2: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd621_d20220210_m052800_c001_v0001022_t0001") 2022/02/10 05:39:07 DEBUG : b/potato: Deleting (id "4_zff828d43109deb2b7fe80e1b_f110bb9c78a47d1a0_d20220210_m052851_c001_v0001150_t0028") 2022/02/10 05:39:07 DEBUG : b/potato: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd678_d20220210_m052849_c001_v0001022_t0037") 2022/02/10 05:39:07 DEBUG : b/potato: Deleting (id "4_zff828d43109deb2b7fe80e1b_f10152b275894e8c6_d20220210_m052846_c001_v0001011_t0012") 2022/02/10 05:39:07 DEBUG : b/potato: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd665_d20220210_m052843_c001_v0001022_t0049") 2022/02/10 05:39:07 DEBUG : b/potato: Deleting (id "4_zff828d43109deb2b7fe80e1b_f10677aa6e579d936_d20220210_m052801_c001_v0001109_t0022") 2022/02/10 05:39:07 DEBUG : b/potato: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd61c_d20220210_m052758_c001_v0001022_t0006") 2022/02/10 05:39:07 DEBUG : backup/one: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1065112934bce402_d20220210_m053337_c001_v0001150_t0051") 2022/02/10 05:39:07 DEBUG : backup/one: Deleting (id "4_zff828d43109deb2b7fe80e1b_f110bc42d6413d8ce_d20220210_m053332_c001_v0001120_t0011") 2022/02/10 05:39:07 DEBUG : backup/one: Deleting (id "4_zff828d43109deb2b7fe80e1b_f112132a233d7bf76_d20220210_m053305_c001_v0001136_t0030") 2022/02/10 05:39:07 DEBUG : backup/one-2019-01-01: Deleting (id "4_zff828d43109deb2b7fe80e1b_f102e18b84b0f3c61_d20220210_m053520_c001_v0001165_t0038") 2022/02/10 05:39:07 DEBUG : backup/one-2019-01-01: Deleting (id "4_zff828d43109deb2b7fe80e1b_f110dbdd5245b0e27_d20220210_m053514_c001_v0001097_t0044") 2022/02/10 05:39:07 DEBUG : backup/one-2019-01-01: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1160be313b63b429_d20220210_m053507_c001_v0001105_t0022") 2022/02/10 05:39:07 DEBUG : backup/one.bak: Deleting (id "4_zff828d43109deb2b7fe80e1b_f116d3b2a0ba20dfc_d20220210_m053403_c001_v0001165_t0007") 2022/02/10 05:39:07 DEBUG : backup/one.bak: Deleting (id "4_zff828d43109deb2b7fe80e1b_f114e14fd12dfa228_d20220210_m053354_c001_v0001042_t0040") 2022/02/10 05:39:07 DEBUG : backup/one.bak: Deleting (id "4_zff828d43109deb2b7fe80e1b_f109f9e7066d907c9_d20220210_m053347_c001_v0001130_t0011") 2022/02/10 05:39:07 DEBUG : backup/three-2019-01-01.txt: Deleting (id "4_zff828d43109deb2b7fe80e1b_f116fcac622dbae9e_d20220210_m053520_c001_v0001146_t0038") 2022/02/10 05:39:08 DEBUG : backup/three-2019-01-01.txt: Deleting (id "4_zff828d43109deb2b7fe80e1b_f108f212568fc3aec_d20220210_m053518_c001_v0001131_t0059") 2022/02/10 05:39:08 DEBUG : backup/three-2019-01-01.txt: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1032dca960091e2c_d20220210_m053509_c001_v0001117_t0045") 2022/02/10 05:39:08 DEBUG : backup/three.txt: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1059bba75851ea6d_d20220210_m053337_c001_v0001146_t0042") 2022/02/10 05:39:08 DEBUG : backup/three.txt: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1140beab583b6f4e_d20220210_m053335_c001_v0001145_t0050") 2022/02/10 05:39:08 DEBUG : backup/three.txt: Deleting (id "4_zff828d43109deb2b7fe80e1b_f100a8977c524a304_d20220210_m053308_c001_v0001017_t0027") 2022/02/10 05:39:08 DEBUG : backup/three.txt.bak: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1054003749b723eb_d20220210_m053403_c001_v0001157_t0017") 2022/02/10 05:39:08 DEBUG : backup/three.txt.bak: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11138413adf94e54_d20220210_m053400_c001_v0001117_t0004") 2022/02/10 05:39:08 DEBUG : backup/three.txt.bak: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1100be7217a5c351_d20220210_m053350_c001_v0001030_t0019") 2022/02/10 05:39:08 DEBUG : both0: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1128edc9885d7a72_d20220210_m053833_c001_v0001150_t0022") 2022/02/10 05:39:08 DEBUG : both0: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd873_d20220210_m053755_c001_v0001022_t0049") 2022/02/10 05:39:08 DEBUG : both0: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1107b4844b2ac51b_d20220210_m053734_c001_v0001128_t0004") 2022/02/10 05:39:08 DEBUG : both0: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1026383dfb30e9dc_d20220210_m053649_c001_v0001105_t0048") 2022/02/10 05:39:08 DEBUG : both1: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1160be313b63cc2d_d20220210_m053833_c001_v0001105_t0033") 2022/02/10 05:39:08 DEBUG : both1: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1026383dfb30f377_d20220210_m053758_c001_v0001105_t0033") 2022/02/10 05:39:08 DEBUG : both1: Deleting (id "4_zff828d43109deb2b7fe80e1b_f114a2faaff7fc754_d20220210_m053734_c001_v0001105_t0053") 2022/02/10 05:39:08 DEBUG : both1: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1029bd6e477228bd_d20220210_m053652_c001_v0001021_t0012") 2022/02/10 05:39:08 DEBUG : both10: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1160be313b63cc45_d20220210_m053834_c001_v0001105_t0045") 2022/02/10 05:39:08 DEBUG : both10: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1026383dfb30f568_d20220210_m053812_c001_v0001105_t0040") 2022/02/10 05:39:09 DEBUG : both10: Deleting (id "4_zff828d43109deb2b7fe80e1b_f103743bdf1218dbb_d20220210_m053735_c001_v0001097_t0047") 2022/02/10 05:39:09 DEBUG : both10: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1029bd6e477228d4_d20220210_m053709_c001_v0001021_t0010") 2022/02/10 05:39:09 DEBUG : both11: Deleting (id "4_zff828d43109deb2b7fe80e1b_f116397d2c46e5973_d20220210_m053835_c001_v0001044_t0024") 2022/02/10 05:39:09 DEBUG : both11: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1029bd6e47722912_d20220210_m053813_c001_v0001021_t0014") 2022/02/10 05:39:09 DEBUG : both11: Deleting (id "4_zff828d43109deb2b7fe80e1b_f114c31c5dd94ebdd_d20220210_m053737_c001_v0001128_t0028") 2022/02/10 05:39:09 DEBUG : both11: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd83c_d20220210_m053710_c001_v0001022_t0006") 2022/02/10 05:39:09 DEBUG : both12: Deleting (id "4_zff828d43109deb2b7fe80e1b_f102f3de01ac04d08_d20220210_m053835_c001_v0001023_t0036") 2022/02/10 05:39:09 DEBUG : both12: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd895_d20220210_m053815_c001_v0001022_t0056") 2022/02/10 05:39:09 DEBUG : both12: Deleting (id "4_zff828d43109deb2b7fe80e1b_f111683de900ef04f_d20220210_m053738_c001_v0001105_t0053") 2022/02/10 05:39:09 DEBUG : both12: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1026383dfb30ed1a_d20220210_m053712_c001_v0001105_t0027") 2022/02/10 05:39:09 DEBUG : both13: Deleting (id "4_zff828d43109deb2b7fe80e1b_f116ee7f2cf6cac28_d20220210_m053836_c001_v0001117_t0054") 2022/02/10 05:39:09 DEBUG : both13: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1026383dfb30f5f9_d20220210_m053816_c001_v0001105_t0048") 2022/02/10 05:39:09 DEBUG : both13: Deleting (id "4_zff828d43109deb2b7fe80e1b_f115687c305e04955_d20220210_m053740_c001_v0001098_t0048") 2022/02/10 05:39:09 DEBUG : both13: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1029bd6e477228da_d20220210_m053714_c001_v0001021_t0039") 2022/02/10 05:39:09 DEBUG : both14: Deleting (id "4_zff828d43109deb2b7fe80e1b_f10622cdc4d4f2a18_d20220210_m053838_c001_v0001128_t0015") 2022/02/10 05:39:09 DEBUG : both14: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1029bd6e47722919_d20220210_m053817_c001_v0001021_t0005") 2022/02/10 05:39:09 DEBUG : both14: Deleting (id "4_zff828d43109deb2b7fe80e1b_f108f212568fc3e5a_d20220210_m053742_c001_v0001131_t0000") 2022/02/10 05:39:09 DEBUG : both14: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd845_d20220210_m053716_c001_v0001022_t0000") 2022/02/10 05:39:09 DEBUG : both15: Deleting (id "4_zff828d43109deb2b7fe80e1b_f10228878df2294cb_d20220210_m053838_c001_v0001137_t0012") 2022/02/10 05:39:10 DEBUG : both15: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd89a_d20220210_m053818_c001_v0001022_t0021") 2022/02/10 05:39:10 DEBUG : both15: Deleting (id "4_zff828d43109deb2b7fe80e1b_f116e0c2fb4c428d2_d20220210_m053742_c001_v0001157_t0057") 2022/02/10 05:39:10 DEBUG : both15: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1026383dfb30edca_d20220210_m053717_c001_v0001105_t0014") 2022/02/10 05:39:10 DEBUG : both16: Deleting (id "4_zff828d43109deb2b7fe80e1b_f115603c0e099cef5_d20220210_m053838_c001_v0001145_t0028") 2022/02/10 05:39:10 DEBUG : both16: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1026383dfb30f693_d20220210_m053819_c001_v0001105_t0051") 2022/02/10 05:39:10 DEBUG : both16: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1026383dfb30f196_d20220210_m053743_c001_v0001105_t0022") 2022/02/10 05:39:10 DEBUG : both16: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1029bd6e477228e6_d20220210_m053718_c001_v0001021_t0015") 2022/02/10 05:39:10 DEBUG : both17: Deleting (id "4_zff828d43109deb2b7fe80e1b_f107804bce8762468_d20220210_m053839_c001_v0001150_t0036") 2022/02/10 05:39:10 DEBUG : both17: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1029bd6e47722922_d20220210_m053822_c001_v0001021_t0043") 2022/02/10 05:39:10 DEBUG : both17: Deleting (id "4_zff828d43109deb2b7fe80e1b_f111683de900ef122_d20220210_m053744_c001_v0001105_t0054") 2022/02/10 05:39:10 DEBUG : both17: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd852_d20220210_m053721_c001_v0001022_t0022") 2022/02/10 05:39:10 DEBUG : both18: Deleting (id "4_zff828d43109deb2b7fe80e1b_f111b4a5c10284a57_d20220210_m053839_c001_v0001098_t0041") 2022/02/10 05:39:10 DEBUG : both18: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd8a2_d20220210_m053825_c001_v0001022_t0009") 2022/02/10 05:39:10 DEBUG : both18: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1067e9c0947bc546_d20220210_m053745_c001_v0001091_t0036") 2022/02/10 05:39:10 DEBUG : both18: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1026383dfb30eed1_d20220210_m053723_c001_v0001105_t0056") 2022/02/10 05:39:10 DEBUG : both19: Deleting (id "4_zff828d43109deb2b7fe80e1b_f118a6b75946b144f_d20220210_m053839_c001_v0001109_t0042") 2022/02/10 05:39:10 DEBUG : both19: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1026383dfb30f749_d20220210_m053826_c001_v0001105_t0038") 2022/02/10 05:39:11 DEBUG : both19: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11891f07e5aacb01_d20220210_m053746_c001_v0001120_t0044") 2022/02/10 05:39:11 DEBUG : both19: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1029bd6e477228ec_d20220210_m053726_c001_v0001021_t0040") 2022/02/10 05:39:11 DEBUG : both2: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1005fe259e4fb05b_d20220210_m053840_c001_v0001105_t0001") 2022/02/10 05:39:11 DEBUG : both2: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1029bd6e47722902_d20220210_m053800_c001_v0001021_t0007") 2022/02/10 05:39:11 DEBUG : both2: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1137d6fbc2af49f9_d20220210_m053747_c001_v0001098_t0012") 2022/02/10 05:39:11 DEBUG : both2: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd81c_d20220210_m053654_c001_v0001022_t0056") 2022/02/10 05:39:11 DEBUG : both3: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11654ac1626760d3_d20220210_m053841_c001_v0001019_t0035") 2022/02/10 05:39:11 DEBUG : both3: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd87e_d20220210_m053803_c001_v0001022_t0016") 2022/02/10 05:39:11 DEBUG : both3: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1149542d4b72f5c3_d20220210_m053747_c001_v0001117_t0036") 2022/02/10 05:39:11 DEBUG : both3: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1026383dfb30eacd_d20220210_m053655_c001_v0001105_t0036") 2022/02/10 05:39:11 DEBUG : both4: Deleting (id "4_zff828d43109deb2b7fe80e1b_f10092b99bd50db14_d20220210_m053841_c001_v0001036_t0009") 2022/02/10 05:39:11 DEBUG : both4: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1026383dfb30f430_d20220210_m053804_c001_v0001105_t0052") 2022/02/10 05:39:11 DEBUG : both4: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11543f3027eef06e_d20220210_m053749_c001_v0001117_t0044") 2022/02/10 05:39:11 DEBUG : both4: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1029bd6e477228c3_d20220210_m053657_c001_v0001021_t0032") 2022/02/10 05:39:11 DEBUG : both5: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1189ea76d28cdab8_d20220210_m053843_c001_v0001105_t0025") 2022/02/10 05:39:11 DEBUG : both5: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1029bd6e47722906_d20220210_m053806_c001_v0001021_t0040") 2022/02/10 05:39:11 DEBUG : both5: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1094d4b149e7655c_d20220210_m053751_c001_v0001120_t0046") 2022/02/10 05:39:11 DEBUG : both5: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd822_d20220210_m053659_c001_v0001022_t0040") 2022/02/10 05:39:11 DEBUG : both6: Deleting (id "4_zff828d43109deb2b7fe80e1b_f101e8be129e28adf_d20220210_m053844_c001_v0001161_t0042") 2022/02/10 05:39:11 DEBUG : both6: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd888_d20220210_m053808_c001_v0001022_t0033") 2022/02/10 05:39:12 DEBUG : both6: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1150151385b96365_d20220210_m053752_c001_v0001120_t0001") 2022/02/10 05:39:12 DEBUG : both6: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1026383dfb30eb76_d20220210_m053659_c001_v0001105_t0056") 2022/02/10 05:39:12 DEBUG : both7: Deleting (id "4_zff828d43109deb2b7fe80e1b_f114ebdb8ae1da8b2_d20220210_m053844_c001_v0001130_t0030") 2022/02/10 05:39:12 DEBUG : both7: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1026383dfb30f4ea_d20220210_m053809_c001_v0001105_t0052") 2022/02/10 05:39:12 DEBUG : both7: Deleting (id "4_zff828d43109deb2b7fe80e1b_f118809795bc765b4_d20220210_m053753_c001_v0001157_t0038") 2022/02/10 05:39:12 DEBUG : both7: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1029bd6e477228ce_d20220210_m053703_c001_v0001021_t0029") 2022/02/10 05:39:12 DEBUG : both8: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1085b3d6eb490904_d20220210_m053845_c001_v0001032_t0039") 2022/02/10 05:39:12 DEBUG : both8: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1029bd6e4772290d_d20220210_m053810_c001_v0001021_t0015") 2022/02/10 05:39:12 DEBUG : both8: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1150151385b9636b_d20220210_m053753_c001_v0001120_t0010") 2022/02/10 05:39:12 DEBUG : both8: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd82d_d20220210_m053704_c001_v0001022_t0009") 2022/02/10 05:39:12 DEBUG : both9: Deleting (id "4_zff828d43109deb2b7fe80e1b_f113b8830474bebc0_d20220210_m053845_c001_v0001012_t0037") 2022/02/10 05:39:12 DEBUG : both9: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd891_d20220210_m053811_c001_v0001022_t0035") 2022/02/10 05:39:12 DEBUG : both9: Deleting (id "4_zff828d43109deb2b7fe80e1b_f112dc5d675d09a2e_d20220210_m053754_c001_v0001098_t0054") 2022/02/10 05:39:12 DEBUG : both9: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1026383dfb30ec72_d20220210_m053706_c001_v0001105_t0026") 2022/02/10 05:39:12 DEBUG : c/non empty space: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1015c50cda9be913_d20220210_m052852_c001_v0001109_t0057") 2022/02/10 05:39:13 DEBUG : c/non empty space: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd67a_d20220210_m052849_c001_v0001022_t0007") 2022/02/10 05:39:13 DEBUG : c/non empty space: Deleting (id "4_zff828d43109deb2b7fe80e1b_f10278d51196ba07e_d20220210_m052848_c001_v0001098_t0023") 2022/02/10 05:39:13 DEBUG : c/non empty space: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd669_d20220210_m052844_c001_v0001022_t0006") 2022/02/10 05:39:13 DEBUG : c/non empty space: Deleting (id "4_zff828d43109deb2b7fe80e1b_f105ac9d8ef427eac_d20220210_m052803_c001_v0001029_t0016") 2022/02/10 05:39:13 DEBUG : c/non empty space: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd61e_d20220210_m052759_c001_v0001022_t0002") 2022/02/10 05:39:13 DEBUG : check sum: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11138413adf93b34_d20220210_m052747_c001_v0001117_t0002") 2022/02/10 05:39:13 DEBUG : check sum: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd605_d20220210_m052745_c001_v0001022_t0001") 2022/02/10 05:39:13 DEBUG : dest/3: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1045cbeea691ff23_d20220210_m053224_c001_v0001105_t0045") 2022/02/10 05:39:13 DEBUG : dest/3: Deleting (id "4_zff828d43109deb2b7fe80e1b_f10771d63c753132b_d20220210_m053223_c001_v0001105_t0022") 2022/02/10 05:39:13 DEBUG : dst/one: Deleting (id "4_zff828d43109deb2b7fe80e1b_f114515bb5909f587_d20220210_m053633_c001_v0001120_t0038") 2022/02/10 05:39:13 DEBUG : dst/one: Deleting (id "4_zff828d43109deb2b7fe80e1b_f105addda64f963ed_d20220210_m053627_c001_v0001021_t0030") 2022/02/10 05:39:13 DEBUG : dst/one: Deleting (id "4_zff828d43109deb2b7fe80e1b_f119ca7ebc948a90c_d20220210_m053626_c001_v0001136_t0024") 2022/02/10 05:39:13 DEBUG : dst/one: Deleting (id "4_zff828d43109deb2b7fe80e1b_f105addda64f963d7_d20220210_m053618_c001_v0001021_t0011") 2022/02/10 05:39:13 DEBUG : dst/one: Deleting (id "4_zff828d43109deb2b7fe80e1b_f110409a9b2277be7_d20220210_m053617_c001_v0001165_t0007") 2022/02/10 05:39:13 DEBUG : dst/one: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1029bd6e4772289a_d20220210_m053610_c001_v0001021_t0014") 2022/02/10 05:39:13 DEBUG : dst/one: Deleting (id "4_zff828d43109deb2b7fe80e1b_f10166b7bac406feb_d20220210_m053604_c001_v0001018_t0044") 2022/02/10 05:39:13 DEBUG : dst/one: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1050d565c6e39625_d20220210_m053601_c001_v0001105_t0057") 2022/02/10 05:39:13 DEBUG : dst/one: Deleting (id "4_zff828d43109deb2b7fe80e1b_f105f11e619dd7df4_d20220210_m053600_c001_v0001150_t0054") 2022/02/10 05:39:13 DEBUG : dst/one: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1050d565c6e39545_d20220210_m053553_c001_v0001105_t0019") 2022/02/10 05:39:13 DEBUG : dst/one: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11499cbf8d358e9c_d20220210_m053552_c001_v0001013_t0034") 2022/02/10 05:39:14 DEBUG : dst/one: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1029bd6e4772288b_d20220210_m053545_c001_v0001021_t0024") 2022/02/10 05:39:14 DEBUG : dst/one: Deleting (id "4_zff828d43109deb2b7fe80e1b_f113ee43390139b54_d20220210_m053542_c001_v0001092_t0038") 2022/02/10 05:39:14 DEBUG : dst/one: Deleting (id "4_zff828d43109deb2b7fe80e1b_f10228878df22923a_d20220210_m053539_c001_v0001137_t0048") 2022/02/10 05:39:14 DEBUG : dst/one: Deleting (id "4_zff828d43109deb2b7fe80e1b_f112583f5ec20f953_d20220210_m053537_c001_v0001031_t0031") 2022/02/10 05:39:14 DEBUG : dst/one: Deleting (id "4_zff828d43109deb2b7fe80e1b_f10228878df229222_d20220210_m053532_c001_v0001137_t0036") 2022/02/10 05:39:14 DEBUG : dst/one: Deleting (id "4_zff828d43109deb2b7fe80e1b_f110bb9c78a47dab5_d20220210_m053531_c001_v0001150_t0033") 2022/02/10 05:39:14 DEBUG : dst/one: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd7c1_d20220210_m053523_c001_v0001022_t0043") 2022/02/10 05:39:14 DEBUG : dst/one: Deleting (id "4_zff828d43109deb2b7fe80e1b_f113b207e95c8a887_d20220210_m053520_c001_v0001165_t0014") 2022/02/10 05:39:14 DEBUG : dst/one: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11143e283bfec40d_d20220210_m053516_c001_v0001041_t0015") 2022/02/10 05:39:14 DEBUG : dst/one: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1107b4844b2abd40_d20220210_m053515_c001_v0001128_t0021") 2022/02/10 05:39:14 DEBUG : dst/one: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11143e283bfec3f6_d20220210_m053509_c001_v0001041_t0013") 2022/02/10 05:39:14 DEBUG : dst/one: Deleting (id "4_zff828d43109deb2b7fe80e1b_f106fefbcb80d64c8_d20220210_m053508_c001_v0001105_t0026") 2022/02/10 05:39:14 DEBUG : dst/one: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1026383dfb30d546_d20220210_m053406_c001_v0001105_t0014") 2022/02/10 05:39:14 DEBUG : dst/one: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1168c541cb45c18d_d20220210_m053403_c001_v0001150_t0052") 2022/02/10 05:39:14 DEBUG : dst/one: Deleting (id "4_zff828d43109deb2b7fe80e1b_f108f212568fc391a_d20220210_m053359_c001_v0001131_t0006") 2022/02/10 05:39:14 DEBUG : dst/one: Deleting (id "4_zff828d43109deb2b7fe80e1b_f109d2e118beae5ab_d20220210_m053355_c001_v0001145_t0040") 2022/02/10 05:39:14 DEBUG : dst/one: Deleting (id "4_zff828d43109deb2b7fe80e1b_f108f212568fc38d8_d20220210_m053349_c001_v0001131_t0002") 2022/02/10 05:39:14 DEBUG : dst/one: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1005fe259e4f9244_d20220210_m053347_c001_v0001105_t0017") 2022/02/10 05:39:14 DEBUG : dst/one: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1029bd6e4772284a_d20220210_m053339_c001_v0001021_t0025") 2022/02/10 05:39:15 DEBUG : dst/one: Deleting (id "4_zff828d43109deb2b7fe80e1b_f114e76faaa5d0516_d20220210_m053338_c001_v0001109_t0038") 2022/02/10 05:39:15 DEBUG : dst/one: Deleting (id "4_zff828d43109deb2b7fe80e1b_f113ccfcbe5764973_d20220210_m053334_c001_v0001011_t0014") 2022/02/10 05:39:15 DEBUG : dst/one: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1047129294b26f79_d20220210_m053333_c001_v0001117_t0053") 2022/02/10 05:39:15 DEBUG : dst/one: Deleting (id "4_zff828d43109deb2b7fe80e1b_f113ccfcbe576495f_d20220210_m053307_c001_v0001011_t0033") 2022/02/10 05:39:15 DEBUG : dst/one: Deleting (id "4_zff828d43109deb2b7fe80e1b_f100ec51a29b98e81_d20220210_m053305_c001_v0001022_t0041") 2022/02/10 05:39:15 DEBUG : dst/one: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd75e_d20220210_m053259_c001_v0001022_t0027") 2022/02/10 05:39:15 DEBUG : dst/one: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1021b28554f6a4e8_d20220210_m053255_c001_v0001157_t0024") 2022/02/10 05:39:15 DEBUG : dst/one: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1013e54110c5f099_d20220210_m053242_c001_v0001117_t0056") 2022/02/10 05:39:15 DEBUG : dst/one: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1003339082ed0899_d20220210_m053241_c001_v0001120_t0000") 2022/02/10 05:39:15 DEBUG : dst/one: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1026383dfb30c94c_d20220210_m053236_c001_v0001105_t0017") 2022/02/10 05:39:15 DEBUG : dst/one: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1160be313b63a366_d20220210_m053235_c001_v0001105_t0050") 2022/02/10 05:39:15 DEBUG : dst/one: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1160be313b63a307_d20220210_m053232_c001_v0001105_t0015") 2022/02/10 05:39:15 DEBUG : dst/one: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1026383dfb30c603_d20220210_m053212_c001_v0001105_t0013") 2022/02/10 05:39:15 DEBUG : dst/one: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd735_d20220210_m053156_c001_v0001022_t0054") 2022/02/10 05:39:15 DEBUG : dst/one: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1135678ebc851536_d20220210_m053154_c001_v0001157_t0039") 2022/02/10 05:39:15 DEBUG : dst/one: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1135678ebc851529_d20220210_m053153_c001_v0001157_t0034") 2022/02/10 05:39:15 DEBUG : dst/one-2019-01-01: Deleting (id "4_zff828d43109deb2b7fe80e1b_f101535e180f9e382_d20220210_m053634_c001_v0001146_t0020") 2022/02/10 05:39:15 DEBUG : dst/one-2019-01-01: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1065051edd78a4b0_d20220210_m053625_c001_v0001120_t0055") 2022/02/10 05:39:15 DEBUG : dst/one-2019-01-01: Deleting (id "4_zff828d43109deb2b7fe80e1b_f104d706525db1f49_d20220210_m053616_c001_v0001038_t0034") 2022/02/10 05:39:16 DEBUG : dst/one.bak: Deleting (id "4_zff828d43109deb2b7fe80e1b_f116d9ae537a6eb45_d20220210_m053606_c001_v0001003_t0021") 2022/02/10 05:39:16 DEBUG : dst/one.bak: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1087c8cc1db802b1_d20220210_m053559_c001_v0001105_t0041") 2022/02/10 05:39:16 DEBUG : dst/one.bak: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1127878e8ae5813d_d20220210_m053551_c001_v0001144_t0027") 2022/02/10 05:39:16 DEBUG : dst/one.bak: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1005fe259e4f9d43_d20220210_m053542_c001_v0001105_t0043") 2022/02/10 05:39:16 DEBUG : dst/one.bak: Deleting (id "4_zff828d43109deb2b7fe80e1b_f106bfef2d2767c2d_d20220210_m053537_c001_v0001157_t0005") 2022/02/10 05:39:16 DEBUG : dst/one.bak: Deleting (id "4_zff828d43109deb2b7fe80e1b_f109b44a9b8a2232d_d20220210_m053530_c001_v0001144_t0025") 2022/02/10 05:39:16 DEBUG : dst/three: Deleting (id "4_zff828d43109deb2b7fe80e1b_f10677aa6e579e5b2_d20220210_m053256_c001_v0001109_t0041") 2022/02/10 05:39:16 DEBUG : dst/three: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1160be313b63a529_d20220210_m053250_c001_v0001105_t0041") 2022/02/10 05:39:16 DEBUG : dst/three-2019-01-01.txt: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1169dab9de6a1972_d20220210_m053634_c001_v0001006_t0003") 2022/02/10 05:39:16 DEBUG : dst/three-2019-01-01.txt: Deleting (id "4_zff828d43109deb2b7fe80e1b_f10245ef7f74a76c6_d20220210_m053628_c001_v0001039_t0040") 2022/02/10 05:39:16 DEBUG : dst/three-2019-01-01.txt: Deleting (id "4_zff828d43109deb2b7fe80e1b_f10771d63c7532d3d_d20220210_m053619_c001_v0001105_t0037") 2022/02/10 05:39:16 DEBUG : dst/three.txt: Deleting (id "4_zff828d43109deb2b7fe80e1b_f111a04078d4aa070_d20220210_m053635_c001_v0001109_t0048") 2022/02/10 05:39:16 DEBUG : dst/three.txt: Deleting (id "4_zff828d43109deb2b7fe80e1b_f105addda64f963fa_d20220210_m053632_c001_v0001021_t0009") 2022/02/10 05:39:16 DEBUG : dst/three.txt: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1101f52279fb4936_d20220210_m053630_c001_v0001145_t0014") 2022/02/10 05:39:16 DEBUG : dst/three.txt: Deleting (id "4_zff828d43109deb2b7fe80e1b_f105addda64f963e5_d20220210_m053622_c001_v0001021_t0004") 2022/02/10 05:39:16 DEBUG : dst/three.txt: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1098661e63b5d6e6_d20220210_m053620_c001_v0001117_t0001") 2022/02/10 05:39:16 DEBUG : dst/three.txt: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd7f3_d20220210_m053612_c001_v0001022_t0027") 2022/02/10 05:39:16 DEBUG : dst/three.txt: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1134e3489d7db088_d20220210_m053607_c001_v0001105_t0024") 2022/02/10 05:39:16 DEBUG : dst/three.txt: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1050d565c6e39672_d20220210_m053604_c001_v0001105_t0057") 2022/02/10 05:39:16 DEBUG : dst/three.txt: Deleting (id "4_zff828d43109deb2b7fe80e1b_f118a6b75946b0c84_d20220210_m053603_c001_v0001109_t0037") 2022/02/10 05:39:17 DEBUG : dst/three.txt: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1050d565c6e395bd_d20220210_m053557_c001_v0001105_t0047") 2022/02/10 05:39:17 DEBUG : dst/three.txt: Deleting (id "4_zff828d43109deb2b7fe80e1b_f10861535a66e16b5_d20220210_m053555_c001_v0001091_t0039") 2022/02/10 05:39:17 DEBUG : dst/three.txt: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd7df_d20220210_m053547_c001_v0001022_t0015") 2022/02/10 05:39:17 DEBUG : dst/three.txt: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1005fe259e4f9d13_d20220210_m053540_c001_v0001105_t0053") 2022/02/10 05:39:17 DEBUG : dst/three.txt: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd7ce_d20220210_m053535_c001_v0001022_t0053") 2022/02/10 05:39:17 DEBUG : dst/three.txt: Deleting (id "4_zff828d43109deb2b7fe80e1b_f108851172868783b_d20220210_m053534_c001_v0001041_t0021") 2022/02/10 05:39:17 DEBUG : dst/three.txt: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1026383dfb30df59_d20220210_m053527_c001_v0001105_t0029") 2022/02/10 05:39:17 DEBUG : dst/three.txt: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1054003749b72511_d20220210_m053518_c001_v0001157_t0005") 2022/02/10 05:39:17 DEBUG : dst/three.txt: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1026383dfb30dd3b_d20220210_m053512_c001_v0001105_t0038") 2022/02/10 05:39:17 DEBUG : dst/three.txt: Deleting (id "4_zff828d43109deb2b7fe80e1b_f105646d8f7e87159_d20220210_m053511_c001_v0001134_t0026") 2022/02/10 05:39:17 DEBUG : dst/three.txt: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1029bd6e4772286e_d20220210_m053502_c001_v0001021_t0015") 2022/02/10 05:39:17 DEBUG : dst/three.txt: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1170eb1f20f895d7_d20220210_m053402_c001_v0001134_t0036") 2022/02/10 05:39:17 DEBUG : dst/three.txt: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1029bd6e47722850_d20220210_m053352_c001_v0001021_t0026") 2022/02/10 05:39:17 DEBUG : dst/three.txt: Deleting (id "4_zff828d43109deb2b7fe80e1b_f114a2faaff7fae4c_d20220210_m053350_c001_v0001105_t0040") 2022/02/10 05:39:17 DEBUG : dst/three.txt: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd77a_d20220210_m053343_c001_v0001022_t0010") 2022/02/10 05:39:17 DEBUG : dst/three.txt: Deleting (id "4_zff828d43109deb2b7fe80e1b_f113b207e95c8a4b8_d20220210_m053336_c001_v0001165_t0004") 2022/02/10 05:39:17 DEBUG : dst/three.txt: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd771_d20220210_m053330_c001_v0001022_t0043") 2022/02/10 05:39:17 DEBUG : dst/three.txt: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1160be313b63a772_d20220210_m053308_c001_v0001105_t0002") 2022/02/10 05:39:17 DEBUG : dst/three.txt: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1026383dfb30cc6c_d20220210_m053301_c001_v0001105_t0027") 2022/02/10 05:39:17 DEBUG : dst/three.txt.bak: Deleting (id "4_zff828d43109deb2b7fe80e1b_f111c52a3e442ca66_d20220210_m053608_c001_v0001154_t0017") 2022/02/10 05:39:18 DEBUG : dst/three.txt.bak: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1031785ecd222339_d20220210_m053602_c001_v0001146_t0019") 2022/02/10 05:39:18 DEBUG : dst/three.txt.bak: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11559cd10546a6f8_d20220210_m053554_c001_v0001130_t0028") 2022/02/10 05:39:18 DEBUG : dst/three.txt.bak: Deleting (id "4_zff828d43109deb2b7fe80e1b_f113dac63a63dca76_d20220210_m053543_c001_v0001117_t0023") 2022/02/10 05:39:18 DEBUG : dst/three.txt.bak: Deleting (id "4_zff828d43109deb2b7fe80e1b_f113ec658ba22dfe8_d20220210_m053540_c001_v0001128_t0056") 2022/02/10 05:39:18 DEBUG : dst/three.txt.bak: Deleting (id "4_zff828d43109deb2b7fe80e1b_f113e88bfa7a3d7f5_d20220210_m053533_c001_v0001144_t0032") 2022/02/10 05:39:18 DEBUG : dst/two: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1170f7b7d51040dc_d20220210_m053635_c001_v0001105_t0049") 2022/02/10 05:39:18 DEBUG : dst/two: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1026383dfb30e4a1_d20220210_m053611_c001_v0001105_t0033") 2022/02/10 05:39:18 DEBUG : dst/two: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1050d565c6e396fb_d20220210_m053608_c001_v0001105_t0052") 2022/02/10 05:39:18 DEBUG : dst/two: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1026383dfb30e1ad_d20220210_m053546_c001_v0001105_t0043") 2022/02/10 05:39:18 DEBUG : dst/two: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1069ed3f57991b41_d20220210_m053544_c001_v0001146_t0046") 2022/02/10 05:39:18 DEBUG : dst/two: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1029bd6e4772287d_d20220210_m053526_c001_v0001021_t0037") 2022/02/10 05:39:18 DEBUG : dst/two: Deleting (id "4_zff828d43109deb2b7fe80e1b_f10727a3778807af4_d20220210_m053521_c001_v0001131_t0046") 2022/02/10 05:39:18 DEBUG : dst/two: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd794_d20220210_m053408_c001_v0001022_t0016") 2022/02/10 05:39:18 DEBUG : dst/two: Deleting (id "4_zff828d43109deb2b7fe80e1b_f119fb09cf7b3c9f3_d20220210_m053404_c001_v0001098_t0001") 2022/02/10 05:39:18 DEBUG : dst/two: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1026383dfb30d208_d20220210_m053340_c001_v0001105_t0000") 2022/02/10 05:39:18 DEBUG : dst/two: Deleting (id "4_zff828d43109deb2b7fe80e1b_f106fefbcb80d5a1d_d20220210_m053338_c001_v0001105_t0037") 2022/02/10 05:39:18 DEBUG : dst/two: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1029bd6e47722827_d20220210_m053300_c001_v0001021_t0000") 2022/02/10 05:39:18 DEBUG : dst/two: Deleting (id "4_zff828d43109deb2b7fe80e1b_f10771d63c75316b3_d20220210_m053256_c001_v0001105_t0035") 2022/02/10 05:39:19 DEBUG : dst/two: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1104019416d947f5_d20220210_m053246_c001_v0001105_t0055") 2022/02/10 05:39:19 DEBUG : dst/two: Deleting (id "4_zff828d43109deb2b7fe80e1b_f102af5541b0e6290_d20220210_m053213_c001_v0001117_t0004") 2022/02/10 05:39:19 DEBUG : dst/two: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1135678ebc8515a2_d20220210_m053209_c001_v0001157_t0025") 2022/02/10 05:39:19 DEBUG : empty space: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1157a0d5c0b3e46d_d20220210_m053133_c001_v0001165_t0041") 2022/02/10 05:39:19 DEBUG : empty space: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd71d_d20220210_m053128_c001_v0001022_t0044") 2022/02/10 05:39:19 DEBUG : empty space: Deleting (id "4_zff828d43109deb2b7fe80e1b_f10142c15bf16aa1f_d20220210_m053104_c001_v0001137_t0025") 2022/02/10 05:39:19 DEBUG : empty space: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd6fc_d20220210_m053046_c001_v0001022_t0057") 2022/02/10 05:39:19 DEBUG : empty space: Deleting (id "4_zff828d43109deb2b7fe80e1b_f108f4449bcade03c_d20220210_m053035_c001_v0001098_t0008") 2022/02/10 05:39:19 DEBUG : empty space: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd6ef_d20220210_m053026_c001_v0001022_t0023") 2022/02/10 05:39:19 DEBUG : empty space: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11404afab8f99af1_d20220210_m052923_c001_v0001150_t0052") 2022/02/10 05:39:19 DEBUG : empty space: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd6a9_d20220210_m052920_c001_v0001022_t0017") 2022/02/10 05:39:19 DEBUG : empty space: Deleting (id "4_zff828d43109deb2b7fe80e1b_f105ba3bb12b660e3_d20220210_m052918_c001_v0001161_t0011") 2022/02/10 05:39:19 DEBUG : empty space: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd6a3_d20220210_m052916_c001_v0001022_t0052") 2022/02/10 05:39:19 DEBUG : empty space: Deleting (id "4_zff828d43109deb2b7fe80e1b_f119fd160e0eb2a6b_d20220210_m052903_c001_v0001040_t0020") 2022/02/10 05:39:19 DEBUG : empty space: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd68c_d20220210_m052858_c001_v0001022_t0045") 2022/02/10 05:39:19 DEBUG : empty space: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1170b4d2676bbe0a_d20220210_m052856_c001_v0001165_t0000") 2022/02/10 05:39:19 DEBUG : empty space: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd682_d20220210_m052854_c001_v0001022_t0027") 2022/02/10 05:39:19 DEBUG : empty space: Deleting (id "4_zff828d43109deb2b7fe80e1b_f100556fce6b6da97_d20220210_m052839_c001_v0001131_t0006") 2022/02/10 05:39:19 DEBUG : empty space: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd65a_d20220210_m052831_c001_v0001022_t0008") 2022/02/10 05:39:20 DEBUG : empty space: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1036e0d3a18fd153_d20220210_m052829_c001_v0001109_t0020") 2022/02/10 05:39:20 DEBUG : empty space: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd653_d20220210_m052828_c001_v0001022_t0032") 2022/02/10 05:39:20 DEBUG : empty space: Deleting (id "4_zff828d43109deb2b7fe80e1b_f100bc2bfb6b76f3b_d20220210_m052819_c001_v0001003_t0032") 2022/02/10 05:39:20 DEBUG : empty space: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd639_d20220210_m052818_c001_v0001022_t0010") 2022/02/10 05:39:20 DEBUG : empty space: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11584f70db8c9871_d20220210_m052813_c001_v0001003_t0030") 2022/02/10 05:39:20 DEBUG : empty space: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd62b_d20220210_m052812_c001_v0001022_t0000") 2022/02/10 05:39:20 DEBUG : empty space: Deleting (id "4_zff828d43109deb2b7fe80e1b_f119646bfa0c6e78e_d20220210_m052808_c001_v0001042_t0016") 2022/02/10 05:39:20 DEBUG : empty space: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11593cbbed5d6f5e_d20220210_m052807_c001_v0001004_t0008") 2022/02/10 05:39:20 DEBUG : empty space: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd626_d20220210_m052805_c001_v0001022_t0031") 2022/02/10 05:39:20 DEBUG : enormous: Deleting (id "4_zff828d43109deb2b7fe80e1b_f10277801e5853ab4_d20220210_m052922_c001_v0001109_t0004") 2022/02/10 05:39:20 DEBUG : enormous: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd6ab_d20220210_m052921_c001_v0001022_t0048") 2022/02/10 05:39:20 DEBUG : existing: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1106aee37c71b7fc_d20220210_m053644_c001_v0001117_t0013") 2022/02/10 05:39:20 DEBUG : existing: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd80e_d20220210_m053642_c001_v0001022_t0037") 2022/02/10 05:39:20 DEBUG : existing: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1164665221cccaf7_d20220210_m053019_c001_v0001136_t0008") 2022/02/10 05:39:20 DEBUG : existing: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1029bd6e477227bf_d20220210_m053018_c001_v0001021_t0005") 2022/02/10 05:39:20 DEBUG : existing: Deleting (id "4_zff828d43109deb2b7fe80e1b_f118128e640a935bb_d20220210_m052757_c001_v0001130_t0042") 2022/02/10 05:39:21 DEBUG : existing: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd618_d20220210_m052756_c001_v0001022_t0035") 2022/02/10 05:39:21 DEBUG : existing: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1087c8cc1db7ced4_d20220210_m052754_c001_v0001105_t0032") 2022/02/10 05:39:21 DEBUG : existing: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd613_d20220210_m052753_c001_v0001022_t0003") 2022/02/10 05:39:21 DEBUG : existing: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd611_d20220210_m052751_c001_v0001022_t0027") 2022/02/10 05:39:21 DEBUG : existing-b: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1057a159f9f9b3eb_d20220210_m053020_c001_v0001130_t0040") 2022/02/10 05:39:21 DEBUG : existing-b: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd6e6_d20220210_m053017_c001_v0001022_t0055") 2022/02/10 05:39:21 DEBUG : five: Deleting (id "4_zff828d43109deb2b7fe80e1b_f112b356e6cbf4494_d20220210_m052941_c001_v0001145_t0042") 2022/02/10 05:39:21 DEBUG : five: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd6c2_d20220210_m052938_c001_v0001022_t0052") 2022/02/10 05:39:21 DEBUG : foo: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11754990fb396fd2_d20220210_m052817_c001_v0001130_t0034") 2022/02/10 05:39:21 DEBUG : foo: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd630_d20220210_m052815_c001_v0001022_t0048") 2022/02/10 05:39:21 DEBUG : foo: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd62d_d20220210_m052814_c001_v0001022_t0031") 2022/02/10 05:39:21 DEBUG : four: Deleting (id "4_zff828d43109deb2b7fe80e1b_f10142c15bf16a8db_d20220210_m052942_c001_v0001137_t0013") 2022/02/10 05:39:21 DEBUG : four: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1029bd6e47722799_d20220210_m052938_c001_v0001021_t0028") 2022/02/10 05:39:21 DEBUG : four: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd6be_d20220210_m052936_c001_v0001022_t0025") 2022/02/10 05:39:21 DEBUG : hello world2: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1164665221ccc477_d20220210_m052722_c001_v0001136_t0040") 2022/02/10 05:39:21 DEBUG : hello world2: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd5e9_d20220210_m052721_c001_v0001022_t0034") 2022/02/10 05:39:21 DEBUG : ignore-size: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1181aea224d957ac_d20220210_m052751_c001_v0001131_t0054") 2022/02/10 05:39:21 DEBUG : ignore-size: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd60c_d20220210_m052750_c001_v0001022_t0055") 2022/02/10 05:39:21 DEBUG : nested/sub dir/file: Deleting (id "4_zff828d43109deb2b7fe80e1b_f109fec3f326ca3f9_d20220210_m053016_c001_v0001134_t0026") 2022/02/10 05:39:21 DEBUG : nested/sub dir/file: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd6e4_d20220210_m053015_c001_v0001022_t0032") 2022/02/10 05:39:22 DEBUG : nested/sub dir/file: Deleting (id "4_zff828d43109deb2b7fe80e1b_f114b03512e5f2a30_d20220210_m053012_c001_v0001097_t0037") 2022/02/10 05:39:22 DEBUG : nested/sub dir/file: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1029bd6e477227b9_d20220210_m053011_c001_v0001021_t0001") 2022/02/10 05:39:22 DEBUG : one: Deleting (id "4_zff828d43109deb2b7fe80e1b_f10277801e5853b9b_d20220210_m052942_c001_v0001109_t0005") 2022/02/10 05:39:22 DEBUG : one: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd6b2_d20220210_m052927_c001_v0001022_t0025") 2022/02/10 05:39:22 DEBUG : only0: Deleting (id "4_zff828d43109deb2b7fe80e1b_f117e9a34eb0ff7af_d20220210_m053846_c001_v0001109_t0007") 2022/02/10 05:39:22 DEBUG : only0: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1029bd6e47722929_d20220210_m053829_c001_v0001021_t0017") 2022/02/10 05:39:22 DEBUG : only0: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1029bd6e47722900_d20220210_m053756_c001_v0001021_t0027") 2022/02/10 05:39:22 DEBUG : only0: Deleting (id "4_zff828d43109deb2b7fe80e1b_f117e9a34eb0ff457_d20220210_m053729_c001_v0001109_t0015") 2022/02/10 05:39:22 DEBUG : only0: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd816_d20220210_m053651_c001_v0001022_t0023") 2022/02/10 05:39:22 DEBUG : only1: Deleting (id "4_zff828d43109deb2b7fe80e1b_f110aee4d83742184_d20220210_m053847_c001_v0001036_t0041") 2022/02/10 05:39:22 DEBUG : only1: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1026383dfb30f7ad_d20220210_m053829_c001_v0001105_t0024") 2022/02/10 05:39:22 DEBUG : only1: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd879_d20220210_m053759_c001_v0001022_t0052") 2022/02/10 05:39:22 DEBUG : only1: Deleting (id "4_zff828d43109deb2b7fe80e1b_f115ca1d285b4bf9e_d20220210_m053728_c001_v0001092_t0000") 2022/02/10 05:39:22 DEBUG : only1: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1026383dfb30ea5e_d20220210_m053653_c001_v0001105_t0048") 2022/02/10 05:39:22 DEBUG : only10: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1114f4ec785c02da_d20220210_m053847_c001_v0001146_t0052") 2022/02/10 05:39:22 DEBUG : only10: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd8a6_d20220210_m053829_c001_v0001022_t0051") 2022/02/10 05:39:22 DEBUG : only10: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd893_d20220210_m053813_c001_v0001022_t0002") 2022/02/10 05:39:22 DEBUG : only10: Deleting (id "4_zff828d43109deb2b7fe80e1b_f112aae83b374dd14_d20220210_m053730_c001_v0001109_t0028") 2022/02/10 05:39:22 DEBUG : only10: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1026383dfb30ece9_d20220210_m053710_c001_v0001105_t0052") 2022/02/10 05:39:22 DEBUG : only11: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1154cd4f92f3a554_d20220210_m053848_c001_v0001023_t0015") 2022/02/10 05:39:22 DEBUG : only11: Deleting (id "4_zff828d43109deb2b7fe80e1b_f107e918fa004d5f5_d20220210_m053829_c001_v0001093_t0022") 2022/02/10 05:39:23 DEBUG : only11: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1026383dfb30f5b3_d20220210_m053814_c001_v0001105_t0032") 2022/02/10 05:39:23 DEBUG : only11: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1087c0cf74e69fa6_d20220210_m053730_c001_v0001029_t0005") 2022/02/10 05:39:23 DEBUG : only11: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1029bd6e477228d8_d20220210_m053711_c001_v0001021_t0013") 2022/02/10 05:39:23 DEBUG : only12: Deleting (id "4_zff828d43109deb2b7fe80e1b_f106fefbcb80d81da_d20220210_m053849_c001_v0001105_t0048") 2022/02/10 05:39:23 DEBUG : only12: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1029bd6e4772292b_d20220210_m053829_c001_v0001021_t0041") 2022/02/10 05:39:23 DEBUG : only12: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1029bd6e47722917_d20220210_m053815_c001_v0001021_t0009") 2022/02/10 05:39:23 DEBUG : only12: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1028dd1c3e633098_d20220210_m053732_c001_v0001043_t0001") 2022/02/10 05:39:23 DEBUG : only12: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd843_d20220210_m053714_c001_v0001022_t0024") 2022/02/10 05:39:23 DEBUG : only13: Deleting (id "4_zff828d43109deb2b7fe80e1b_f101830ff1e09e08f_d20220210_m053849_c001_v0001098_t0010") 2022/02/10 05:39:23 DEBUG : only13: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd8a8_d20220210_m053829_c001_v0001022_t0038") 2022/02/10 05:39:23 DEBUG : only13: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd898_d20220210_m053816_c001_v0001022_t0048") 2022/02/10 05:39:23 DEBUG : only13: Deleting (id "4_zff828d43109deb2b7fe80e1b_f119a1274d5332479_d20220210_m053732_c001_v0001109_t0033") 2022/02/10 05:39:23 DEBUG : only13: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1026383dfb30ed6e_d20220210_m053714_c001_v0001105_t0013") 2022/02/10 05:39:23 DEBUG : only14: Deleting (id "4_zff828d43109deb2b7fe80e1b_f104eab4c9c46e2aa_d20220210_m053850_c001_v0001097_t0042") 2022/02/10 05:39:23 DEBUG : only14: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1026383dfb30f7d0_d20220210_m053829_c001_v0001105_t0001") 2022/02/10 05:39:23 DEBUG : only14: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1026383dfb30f645_d20220210_m053818_c001_v0001105_t0047") 2022/02/10 05:39:23 DEBUG : only14: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1017ba53d2c27925_d20220210_m053729_c001_v0001145_t0040") 2022/02/10 05:39:23 DEBUG : only14: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1029bd6e477228e1_d20220210_m053716_c001_v0001021_t0036") 2022/02/10 05:39:24 DEBUG : only15: Deleting (id "4_zff828d43109deb2b7fe80e1b_f115f2bcfd67b9927_d20220210_m053851_c001_v0001150_t0000") 2022/02/10 05:39:24 DEBUG : only15: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1029bd6e4772292d_d20220210_m053830_c001_v0001021_t0034") 2022/02/10 05:39:24 DEBUG : only15: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1029bd6e4772291d_d20220210_m053819_c001_v0001021_t0000") 2022/02/10 05:39:24 DEBUG : only15: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1189ea76d28cd22c_d20220210_m053732_c001_v0001105_t0030") 2022/02/10 05:39:24 DEBUG : only15: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd84b_d20220210_m053718_c001_v0001022_t0011") 2022/02/10 05:39:24 DEBUG : only16: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1129e97c4cdfb7ad_d20220210_m053852_c001_v0001117_t0021") 2022/02/10 05:39:24 DEBUG : only16: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd8aa_d20220210_m053830_c001_v0001022_t0028") 2022/02/10 05:39:24 DEBUG : only16: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd8a0_d20220210_m053822_c001_v0001022_t0012") 2022/02/10 05:39:24 DEBUG : only16: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1170ccae1e9bbe4c_d20220210_m053731_c001_v0001146_t0005") 2022/02/10 05:39:24 DEBUG : only16: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1026383dfb30ee39_d20220210_m053720_c001_v0001105_t0024") 2022/02/10 05:39:24 DEBUG : only17: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1045cbeea6923283_d20220210_m053852_c001_v0001105_t0036") 2022/02/10 05:39:24 DEBUG : only17: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1029bd6e4772292f_d20220210_m053830_c001_v0001021_t0002") 2022/02/10 05:39:24 DEBUG : only17: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1026383dfb30f705_d20220210_m053823_c001_v0001105_t0045") 2022/02/10 05:39:24 DEBUG : only17: Deleting (id "4_zff828d43109deb2b7fe80e1b_f118332f2bcced3f7_d20220210_m053729_c001_v0001097_t0014") 2022/02/10 05:39:24 DEBUG : only17: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1029bd6e477228ea_d20220210_m053723_c001_v0001021_t0044") 2022/02/10 05:39:24 DEBUG : only18: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1104019416d9720f_d20220210_m053854_c001_v0001105_t0024") 2022/02/10 05:39:24 DEBUG : only18: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1026383dfb30f7f0_d20220210_m053830_c001_v0001105_t0003") 2022/02/10 05:39:24 DEBUG : only18: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1029bd6e47722925_d20220210_m053826_c001_v0001021_t0002") 2022/02/10 05:39:24 DEBUG : only18: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1187e60cf9e36ae7_d20220210_m053729_c001_v0001026_t0031") 2022/02/10 05:39:24 DEBUG : only18: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd860_d20220210_m053726_c001_v0001022_t0037") 2022/02/10 05:39:25 DEBUG : only19: Deleting (id "4_zff828d43109deb2b7fe80e1b_f112dc5d675d09e0b_d20220210_m053855_c001_v0001098_t0034") 2022/02/10 05:39:25 DEBUG : only19: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd8ac_d20220210_m053830_c001_v0001022_t0012") 2022/02/10 05:39:25 DEBUG : only19: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd8a4_d20220210_m053827_c001_v0001022_t0022") 2022/02/10 05:39:25 DEBUG : only19: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1142289dfc687f98_d20220210_m053729_c001_v0001098_t0058") 2022/02/10 05:39:25 DEBUG : only19: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1026383dfb30ef5b_d20220210_m053727_c001_v0001105_t0045") 2022/02/10 05:39:25 DEBUG : only2: Deleting (id "4_zff828d43109deb2b7fe80e1b_f109dbea9efb86f45_d20220210_m053856_c001_v0001128_t0001") 2022/02/10 05:39:25 DEBUG : only2: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1029bd6e47722932_d20220210_m053831_c001_v0001021_t0012") 2022/02/10 05:39:25 DEBUG : only2: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1026383dfb30f3db_d20220210_m053801_c001_v0001105_t0055") 2022/02/10 05:39:25 DEBUG : only2: Deleting (id "4_zff828d43109deb2b7fe80e1b_f119d635c02bd7810_d20220210_m053729_c001_v0001105_t0006") 2022/02/10 05:39:25 DEBUG : only2: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1029bd6e477228bf_d20220210_m053655_c001_v0001021_t0027") 2022/02/10 05:39:25 DEBUG : only3: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1130be14ebb5e8a3_d20220210_m053856_c001_v0001015_t0023") 2022/02/10 05:39:25 DEBUG : only3: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd8ae_d20220210_m053831_c001_v0001022_t0031") 2022/02/10 05:39:25 DEBUG : only3: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1029bd6e47722904_d20220210_m053804_c001_v0001021_t0020") 2022/02/10 05:39:25 DEBUG : only3: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11891f07e5aaca98_d20220210_m053731_c001_v0001120_t0040") 2022/02/10 05:39:25 DEBUG : only3: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd820_d20220210_m053657_c001_v0001022_t0057") 2022/02/10 05:39:26 DEBUG : only4: Deleting (id "4_zff828d43109deb2b7fe80e1b_f119646bfa0c6ef02_d20220210_m053859_c001_v0001042_t0056") 2022/02/10 05:39:26 DEBUG : only4: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1029bd6e47722935_d20220210_m053831_c001_v0001021_t0031") 2022/02/10 05:39:26 DEBUG : only4: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd880_d20220210_m053804_c001_v0001022_t0044") 2022/02/10 05:39:26 DEBUG : only4: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1059bba75851f329_d20220210_m053732_c001_v0001146_t0017") 2022/02/10 05:39:26 DEBUG : only4: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1026383dfb30eb23_d20220210_m053658_c001_v0001105_t0031") 2022/02/10 05:39:26 DEBUG : only5: Deleting (id "4_zff828d43109deb2b7fe80e1b_f114c6c31c7567f1b_d20220210_m053900_c001_v0001023_t0011") 2022/02/10 05:39:26 DEBUG : only5: Deleting (id "4_zff828d43109deb2b7fe80e1b_f107e918fa004d5fd_d20220210_m053831_c001_v0001093_t0041") 2022/02/10 05:39:26 DEBUG : only5: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1026383dfb30f49b_d20220210_m053807_c001_v0001105_t0013") 2022/02/10 05:39:26 DEBUG : only5: Deleting (id "4_zff828d43109deb2b7fe80e1b_f112f177f16f14acc_d20220210_m053728_c001_v0001153_t0000") 2022/02/10 05:39:26 DEBUG : only5: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1029bd6e477228c7_d20220210_m053659_c001_v0001021_t0039") 2022/02/10 05:39:26 DEBUG : only6: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1026383dfb30fc5b_d20220210_m053902_c001_v0001105_t0029") 2022/02/10 05:39:26 DEBUG : only6: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd8b0_d20220210_m053832_c001_v0001022_t0048") 2022/02/10 05:39:26 DEBUG : only6: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1029bd6e47722908_d20220210_m053809_c001_v0001021_t0038") 2022/02/10 05:39:26 DEBUG : only6: Deleting (id "4_zff828d43109deb2b7fe80e1b_f10771d63c7533630_d20220210_m053731_c001_v0001105_t0045") 2022/02/10 05:39:26 DEBUG : only6: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd827_d20220210_m053700_c001_v0001022_t0021") 2022/02/10 05:39:26 DEBUG : only7: Deleting (id "4_zff828d43109deb2b7fe80e1b_f104c1b8d2e1042f4_d20220210_m053902_c001_v0001165_t0009") 2022/02/10 05:39:26 DEBUG : only7: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1029bd6e47722937_d20220210_m053832_c001_v0001021_t0037") 2022/02/10 05:39:26 DEBUG : only7: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd88d_d20220210_m053810_c001_v0001022_t0042") 2022/02/10 05:39:26 DEBUG : only7: Deleting (id "4_zff828d43109deb2b7fe80e1b_f113e88bfa7a3dace_d20220210_m053732_c001_v0001144_t0046") 2022/02/10 05:39:26 DEBUG : only7: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1026383dfb30ebff_d20220210_m053703_c001_v0001105_t0006") 2022/02/10 05:39:27 DEBUG : only8: Deleting (id "4_zff828d43109deb2b7fe80e1b_f108e1e3d560a6362_d20220210_m053903_c001_v0001146_t0048") 2022/02/10 05:39:27 DEBUG : only8: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd8b5_d20220210_m053832_c001_v0001022_t0042") 2022/02/10 05:39:27 DEBUG : only8: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1026383dfb30f51a_d20220210_m053811_c001_v0001105_t0034") 2022/02/10 05:39:27 DEBUG : only8: Deleting (id "4_zff828d43109deb2b7fe80e1b_f105a490ab46cf4c6_d20220210_m053729_c001_v0001109_t0058") 2022/02/10 05:39:27 DEBUG : only8: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1029bd6e477228d1_d20220210_m053704_c001_v0001021_t0000") 2022/02/10 05:39:27 DEBUG : only9: Deleting (id "4_zff828d43109deb2b7fe80e1b_f104efcb0d9d236c0_d20220210_m053903_c001_v0001109_t0015") 2022/02/10 05:39:27 DEBUG : only9: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1026383dfb30f83c_d20220210_m053832_c001_v0001105_t0030") 2022/02/10 05:39:27 DEBUG : only9: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1029bd6e4772290f_d20220210_m053812_c001_v0001021_t0027") 2022/02/10 05:39:27 DEBUG : only9: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1114f4ec785bffea_d20220210_m053732_c001_v0001146_t0019") 2022/02/10 05:39:27 DEBUG : only9: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd837_d20220210_m053708_c001_v0001022_t0029") 2022/02/10 05:39:27 DEBUG : potato: Deleting (id "4_zff828d43109deb2b7fe80e1b_f10622cdc4d4f1c10_d20220210_m053009_c001_v0001128_t0026") 2022/02/10 05:39:27 DEBUG : potato: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1029bd6e477227b4_d20220210_m053002_c001_v0001021_t0040") 2022/02/10 05:39:27 DEBUG : potato: Deleting (id "4_zff828d43109deb2b7fe80e1b_f106fefbcb80d3e3b_d20220210_m053000_c001_v0001105_t0030") 2022/02/10 05:39:27 DEBUG : potato: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1029bd6e477227ac_d20220210_m052955_c001_v0001021_t0040") 2022/02/10 05:39:27 DEBUG : potato: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1057a159f9f9b388_d20220210_m052953_c001_v0001130_t0022") 2022/02/10 05:39:27 DEBUG : potato: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1026383dfb30b23d_d20220210_m052948_c001_v0001105_t0013") 2022/02/10 05:39:27 DEBUG : potato: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1045cbeea691e7cc_d20220210_m052913_c001_v0001105_t0032") 2022/02/10 05:39:27 DEBUG : potato: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd69d_d20220210_m052910_c001_v0001022_t0043") 2022/02/10 05:39:27 DEBUG : potato: Deleting (id "4_zff828d43109deb2b7fe80e1b_f106fefbcb80d37c4_d20220210_m052900_c001_v0001105_t0022") 2022/02/10 05:39:27 DEBUG : potato: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd688_d20220210_m052857_c001_v0001022_t0052") 2022/02/10 05:39:28 DEBUG : potato: Deleting (id "4_zff828d43109deb2b7fe80e1b_f118245c3fa2c7cc1_d20220210_m052855_c001_v0001146_t0013") 2022/02/10 05:39:28 DEBUG : potato: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd680_d20220210_m052853_c001_v0001022_t0002") 2022/02/10 05:39:28 DEBUG : potato: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11470e0b7048229a_d20220210_m052833_c001_v0001037_t0056") 2022/02/10 05:39:28 DEBUG : potato: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd656_d20220210_m052831_c001_v0001022_t0015") 2022/02/10 05:39:28 DEBUG : potato: Deleting (id "4_zff828d43109deb2b7fe80e1b_f115de4fceb720c17_d20220210_m052830_c001_v0001091_t0010") 2022/02/10 05:39:28 DEBUG : potato: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd651_d20220210_m052828_c001_v0001022_t0030") 2022/02/10 05:39:28 DEBUG : potato: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1162571fd14252e5_d20220210_m052827_c001_v0001120_t0021") 2022/02/10 05:39:28 DEBUG : potato: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd64d_d20220210_m052826_c001_v0001022_t0021") 2022/02/10 05:39:28 DEBUG : potato: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd64b_d20220210_m052825_c001_v0001022_t0032") 2022/02/10 05:39:28 DEBUG : potato: Deleting (id "4_zff828d43109deb2b7fe80e1b_f111bf72bbc832b32_d20220210_m052824_c001_v0001150_t0046") 2022/02/10 05:39:28 DEBUG : potato: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd645_d20220210_m052823_c001_v0001022_t0009") 2022/02/10 05:39:28 DEBUG : potato: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd642_d20220210_m052821_c001_v0001022_t0012") 2022/02/10 05:39:28 DEBUG : potato: Deleting (id "4_zff828d43109deb2b7fe80e1b_f111683de900eaa13_d20220210_m052820_c001_v0001105_t0031") 2022/02/10 05:39:28 DEBUG : potato: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd63c_d20220210_m052819_c001_v0001022_t0051") 2022/02/10 05:39:28 DEBUG : potato2: Deleting (id "4_zff828d43109deb2b7fe80e1b_f114b03512e5f2e95_d20220210_m053149_c001_v0001097_t0026") 2022/02/10 05:39:28 DEBUG : potato2: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1026383dfb30c2c5_d20220210_m053147_c001_v0001105_t0013") 2022/02/10 05:39:28 DEBUG : potato2: Deleting (id "4_zff828d43109deb2b7fe80e1b_f10152b275894e93d_d20220210_m053133_c001_v0001011_t0033") 2022/02/10 05:39:28 DEBUG : potato2: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1026383dfb30bd44_d20220210_m053105_c001_v0001105_t0039") 2022/02/10 05:39:28 DEBUG : potato2: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1104019416d93c0d_d20220210_m053053_c001_v0001105_t0032") 2022/02/10 05:39:28 DEBUG : potato2: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1026383dfb30ba38_d20220210_m053044_c001_v0001105_t0049") 2022/02/10 05:39:28 DEBUG : potato2: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1170b4d2676bc2c0_d20220210_m053035_c001_v0001165_t0015") 2022/02/10 05:39:29 DEBUG : potato2: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1026383dfb30b70d_d20220210_m053022_c001_v0001105_t0047") 2022/02/10 05:39:29 DEBUG : potato2: Deleting (id "4_zff828d43109deb2b7fe80e1b_f107820116d9775bb_d20220210_m052922_c001_v0001136_t0015") 2022/02/10 05:39:29 DEBUG : potato2: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd6a7_d20220210_m052920_c001_v0001022_t0002") 2022/02/10 05:39:29 DEBUG : potato2: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11916cd9c36bde59_d20220210_m052919_c001_v0001145_t0021") 2022/02/10 05:39:29 DEBUG : potato2: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd6a1_d20220210_m052916_c001_v0001022_t0032") 2022/02/10 05:39:29 DEBUG : potato2: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1026383dfb30ad6c_d20220210_m052915_c001_v0001105_t0031") 2022/02/10 05:39:29 DEBUG : potato2: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd69f_d20220210_m052911_c001_v0001022_t0017") 2022/02/10 05:39:29 DEBUG : potato2: Deleting (id "4_zff828d43109deb2b7fe80e1b_f104eab4c9c46cf6c_d20220210_m052907_c001_v0001097_t0030") 2022/02/10 05:39:29 DEBUG : potato2: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd692_d20220210_m052902_c001_v0001022_t0023") 2022/02/10 05:39:29 DEBUG : potato2: Deleting (id "4_zff828d43109deb2b7fe80e1b_f10095f0032069bae_d20220210_m052856_c001_v0001144_t0006") 2022/02/10 05:39:29 DEBUG : potato2: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd684_d20220210_m052855_c001_v0001022_t0001") 2022/02/10 05:39:29 DEBUG : potato2: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1137015fb3b5afcf_d20220210_m052839_c001_v0001109_t0002") 2022/02/10 05:39:29 DEBUG : potato2: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd65d_d20220210_m052832_c001_v0001022_t0004") 2022/02/10 05:39:29 DEBUG : potato2: Deleting (id "4_zff828d43109deb2b7fe80e1b_f117d900143c4e71c_d20220210_m052727_c001_v0001161_t0025") 2022/02/10 05:39:29 DEBUG : potato2: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd5ee_d20220210_m052726_c001_v0001022_t0023") 2022/02/10 05:39:29 DEBUG : potato2: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1140d15239574d74_d20220210_m052724_c001_v0001131_t0027") 2022/02/10 05:39:29 DEBUG : potato2: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd5eb_d20220210_m052723_c001_v0001022_t0021") 2022/02/10 05:39:29 DEBUG : potato3: Deleting (id "4_zff828d43109deb2b7fe80e1b_f10874ba63db16d09_d20220210_m053133_c001_v0001006_t0041") 2022/02/10 05:39:29 DEBUG : potato3: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1029bd6e477227ed_d20220210_m053129_c001_v0001021_t0001") 2022/02/10 05:39:29 DEBUG : potato3: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1188cae4bbab692b_d20220210_m053054_c001_v0001150_t0029") 2022/02/10 05:39:30 DEBUG : potato3: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1029bd6e477227d5_d20220210_m053047_c001_v0001021_t0002") 2022/02/10 05:39:30 DEBUG : potato3: Deleting (id "4_zff828d43109deb2b7fe80e1b_f10634a9fafe36a86_d20220210_m053035_c001_v0001145_t0015") 2022/02/10 05:39:30 DEBUG : potato3: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1029bd6e477227c7_d20220210_m053030_c001_v0001021_t0039") 2022/02/10 05:39:30 DEBUG : pre-dest1/1: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11164d10383a665d_d20220210_m053225_c001_v0001097_t0001") 2022/02/10 05:39:30 DEBUG : pre-dest1/1: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd745_d20220210_m053216_c001_v0001022_t0011") 2022/02/10 05:39:30 DEBUG : pre-dest2/2: Deleting (id "4_zff828d43109deb2b7fe80e1b_f113c4e17cc053396_d20220210_m053227_c001_v0001120_t0026") 2022/02/10 05:39:30 DEBUG : pre-dest2/2: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1029bd6e4772280f_d20220210_m053218_c001_v0001021_t0021") 2022/02/10 05:39:30 DEBUG : sizeonly: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1069745c122e607f_d20220210_m052749_c001_v0001154_t0011") 2022/02/10 05:39:30 DEBUG : sizeonly: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd608_d20220210_m052748_c001_v0001022_t0012") 2022/02/10 05:39:30 DEBUG : sub dir/hello world: Deleting (id "4_zff828d43109deb2b7fe80e1b_f108f4449bcaddf6e_d20220210_m053016_c001_v0001098_t0041") 2022/02/10 05:39:30 DEBUG : sub dir/hello world: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1026383dfb30b60e_d20220210_m053015_c001_v0001105_t0058") 2022/02/10 05:39:30 DEBUG : sub dir/hello world: Deleting (id "4_zff828d43109deb2b7fe80e1b_f119f7d23b72a4922_d20220210_m053014_c001_v0001161_t0034") 2022/02/10 05:39:30 DEBUG : sub dir/hello world: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1026383dfb30b597_d20220210_m053011_c001_v0001105_t0032") 2022/02/10 05:39:30 DEBUG : sub dir/hello world: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1073398abdf4c281_d20220210_m052744_c001_v0001128_t0049") 2022/02/10 05:39:30 DEBUG : sub dir/hello world: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd601_d20220210_m052743_c001_v0001022_t0000") 2022/02/10 05:39:30 DEBUG : sub dir/hello world: Deleting (id "4_zff828d43109deb2b7fe80e1b_f108683b7167c6669_d20220210_m052742_c001_v0001042_t0030") 2022/02/10 05:39:30 DEBUG : sub dir/hello world: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd5fd_d20220210_m052741_c001_v0001022_t0028") 2022/02/10 05:39:30 DEBUG : sub dir/hello world: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1066d398e9743a02_d20220210_m052740_c001_v0001090_t0021") 2022/02/10 05:39:30 DEBUG : sub dir/hello world: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd5fa_d20220210_m052736_c001_v0001022_t0016") 2022/02/10 05:39:30 DEBUG : sub dir/hello world: Deleting (id "4_zff828d43109deb2b7fe80e1b_f107b77239f6c005f_d20220210_m052735_c001_v0001137_t0026") 2022/02/10 05:39:30 DEBUG : sub dir/hello world: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd5f6_d20220210_m052734_c001_v0001022_t0056") 2022/02/10 05:39:30 DEBUG : sub dir/hello world: Deleting (id "4_zff828d43109deb2b7fe80e1b_f112a75abbbcb63ac_d20220210_m052731_c001_v0001039_t0054") 2022/02/10 05:39:31 DEBUG : sub dir/hello world: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd5f2_d20220210_m052730_c001_v0001022_t0040") 2022/02/10 05:39:31 DEBUG : sub dir/hello world: Deleting (id "4_zff828d43109deb2b7fe80e1b_f103d72beeb1e81e8_d20220210_m052728_c001_v0001031_t0028") 2022/02/10 05:39:31 DEBUG : sub dir/hello world: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd5f0_d20220210_m052727_c001_v0001022_t0056") 2022/02/10 05:39:31 DEBUG : sub dir/hello world: Deleting (id "4_zff828d43109deb2b7fe80e1b_f119576317c59da6f_d20220210_m052720_c001_v0001092_t0022") 2022/02/10 05:39:31 DEBUG : sub dir/hello world: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd5e5_d20220210_m052719_c001_v0001022_t0035") 2022/02/10 05:39:31 DEBUG : sub dir/hello world: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1193cedb6aa41c7a_d20220210_m052718_c001_v0001038_t0035") 2022/02/10 05:39:31 DEBUG : sub dir/hello world: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd5e3_d20220210_m052717_c001_v0001022_t0028") 2022/02/10 05:39:31 DEBUG : sub dir/hello world: Deleting (id "4_zff828d43109deb2b7fe80e1b_f119ca7ebc9489f52_d20220210_m052716_c001_v0001136_t0009") 2022/02/10 05:39:31 DEBUG : sub dir/hello world: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd5e0_d20220210_m052714_c001_v0001022_t0050") 2022/02/10 05:39:31 DEBUG : sub dir/hello world: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11007586e3262fae_d20220210_m052713_c001_v0001137_t0048") 2022/02/10 05:39:31 DEBUG : sub dir/hello world: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd5da_d20220210_m052712_c001_v0001022_t0051") 2022/02/10 05:39:31 DEBUG : sub/yam: Deleting (id "4_zff828d43109deb2b7fe80e1b_f105be0508c6c70ab_d20220210_m053007_c001_v0001165_t0040") 2022/02/10 05:39:31 DEBUG : sub/yam: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd6d8_d20220210_m053002_c001_v0001022_t0057") 2022/02/10 05:39:31 DEBUG : three: Deleting (id "4_zff828d43109deb2b7fe80e1b_f112f500d4f1dd615_d20220210_m052943_c001_v0001097_t0040") 2022/02/10 05:39:31 DEBUG : three: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1029bd6e4772279f_d20220210_m052940_c001_v0001021_t0044") 2022/02/10 05:39:31 DEBUG : three: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd6bc_d20220210_m052935_c001_v0001022_t0011") 2022/02/10 05:39:31 DEBUG : two: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1104019416d93452_d20220210_m052943_c001_v0001105_t0007") 2022/02/10 05:39:32 DEBUG : two: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1026383dfb30b0d3_d20220210_m052938_c001_v0001105_t0015") 2022/02/10 05:39:32 DEBUG : two: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd6b7_d20220210_m052928_c001_v0001022_t0036") 2022/02/10 05:39:32 DEBUG : yam: Deleting (id "4_zff828d43109deb2b7fe80e1b_f108a8ff21689032b_d20220210_m053009_c001_v0001030_t0018") 2022/02/10 05:39:32 DEBUG : yam: Deleting (id "4_zff828d43109deb2b7fe80e1b_f108f62b0d55c7467_d20220210_m053004_c001_v0001040_t0015") 2022/02/10 05:39:32 DEBUG : yam: Deleting (id "4_zff828d43109deb2b7fe80e1b_f117d371c11dc96a0_d20220210_m052958_c001_v0001136_t0031") 2022/02/10 05:39:32 DEBUG : yam: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1026383dfb30b357_d20220210_m052955_c001_v0001105_t0013") 2022/02/10 05:39:32 DEBUG : yam: Deleting (id "4_zff828d43109deb2b7fe80e1b_f110d428564aba29c_d20220210_m052951_c001_v0001044_t0042") 2022/02/10 05:39:32 DEBUG : yam: Deleting (id "4_zff828d43109deb2b7fe80e1b_f11787f0e75ddd6cd_d20220210_m052948_c001_v0001022_t0039") 2022/02/10 05:39:32 DEBUG : yaml: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1021824539811718_d20220210_m053000_c001_v0001019_t0024") 2022/02/10 05:39:32 DEBUG : yaml: Deleting (id "4_zff828d43109deb2b7fe80e1b_f101535e180f9d629_d20220210_m052958_c001_v0001146_t0028") 2022/02/10 05:39:32 DEBUG : yaml: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1170b4d2676bc019_d20220210_m052954_c001_v0001165_t0010") 2022/02/10 05:39:32 DEBUG : yaml: Deleting (id "4_zff828d43109deb2b7fe80e1b_f1100384de2f15bb0_d20220210_m052950_c001_v0001037_t0002") "./sync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose" - Finished OK in 12m24.629386986s (try 1/5)