"./sync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose" - Starting (try 1/5) 2022/01/12 05:00:15 DEBUG : Creating backend with remote "TestB2:rclone-test-vosukix1xoyubug3josesif8" 2022/01/12 05:00:15 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2022/01/12 05:00:16 DEBUG : Creating backend with remote "/tmp/rclone1348176003" 2022/01/12 05:00:16 DEBUG : Config file has changed externaly - reloading === RUN TestPipe --- PASS: TestPipe (0.00s) === RUN TestPipeConcurrent --- PASS: TestPipeConcurrent (0.02s) === RUN TestPipeOrderBy === RUN TestPipeOrderBy/#00 === RUN TestPipeOrderBy/size === RUN TestPipeOrderBy/name === RUN TestPipeOrderBy/modtime === RUN TestPipeOrderBy/size,ascending === RUN TestPipeOrderBy/name,asc === RUN TestPipeOrderBy/modtime,ascending === RUN TestPipeOrderBy/size,descending === RUN TestPipeOrderBy/name,desc === RUN TestPipeOrderBy/modtime,descending === RUN TestPipeOrderBy/size,mixed,50 === RUN TestPipeOrderBy/size,mixed,51 --- PASS: TestPipeOrderBy (0.01s) --- PASS: TestPipeOrderBy/#00 (0.00s) --- PASS: TestPipeOrderBy/size (0.00s) --- PASS: TestPipeOrderBy/name (0.00s) --- PASS: TestPipeOrderBy/modtime (0.00s) --- PASS: TestPipeOrderBy/size,ascending (0.00s) --- PASS: TestPipeOrderBy/name,asc (0.00s) --- PASS: TestPipeOrderBy/modtime,ascending (0.00s) --- PASS: TestPipeOrderBy/size,descending (0.00s) --- PASS: TestPipeOrderBy/name,desc (0.00s) --- PASS: TestPipeOrderBy/modtime,descending (0.00s) --- PASS: TestPipeOrderBy/size,mixed,50 (0.00s) --- PASS: TestPipeOrderBy/size,mixed,51 (0.00s) === RUN TestNewLess === RUN TestNewLess/blankOK === RUN TestNewLess/tooManyParts === RUN TestNewLess/tooManyParts2 === RUN TestNewLess/badMixed === RUN TestNewLess/unknownComparison === RUN TestNewLess/unknownSortDirection === RUN TestNewLess/size === RUN TestNewLess/name === RUN TestNewLess/modtime === RUN TestNewLess/size,ascending === RUN TestNewLess/name,asc === RUN TestNewLess/modtime,ascending === RUN TestNewLess/size,descending === RUN TestNewLess/name,desc === RUN TestNewLess/modtime,descending === RUN TestNewLess/modtime,mixed === RUN TestNewLess/modtime,mixed,30 --- PASS: TestNewLess (0.00s) --- PASS: TestNewLess/blankOK (0.00s) --- PASS: TestNewLess/tooManyParts (0.00s) --- PASS: TestNewLess/tooManyParts2 (0.00s) --- PASS: TestNewLess/badMixed (0.00s) --- PASS: TestNewLess/unknownComparison (0.00s) --- PASS: TestNewLess/unknownSortDirection (0.00s) --- PASS: TestNewLess/size (0.00s) --- PASS: TestNewLess/name (0.00s) --- PASS: TestNewLess/modtime (0.00s) --- PASS: TestNewLess/size,ascending (0.00s) --- PASS: TestNewLess/name,asc (0.00s) --- PASS: TestNewLess/modtime,ascending (0.00s) --- PASS: TestNewLess/size,descending (0.00s) --- PASS: TestNewLess/name,desc (0.00s) --- PASS: TestNewLess/modtime,descending (0.00s) --- PASS: TestNewLess/modtime,mixed (0.00s) --- PASS: TestNewLess/modtime,mixed,30 (0.00s) === RUN TestRcCopy rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcCopy (0.00s) === RUN TestRcMove rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcMove (0.00s) === RUN TestRcSync rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcSync (0.00s) === RUN TestCopyWithDryRun run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:00:17 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) 2022/01/12 05:00:17 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:00:17 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish --- PASS: TestCopyWithDryRun (1.81s) === RUN TestCopy run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:00:18 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:00:18 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish 2022/01/12 05:00:19 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2022/01/12 05:00:19 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopy (6.19s) === RUN TestCopyMissingDirectory run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:00:24 DEBUG : Creating backend with remote "/non-existing" 2022/01/12 05:00:24 ERROR : Local file system at /non-existing: error reading source root directory: directory not found 2022/01/12 05:00:24 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:00:24 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish --- PASS: TestCopyMissingDirectory (0.59s) === RUN TestCopyNoTraverse run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:00:25 DEBUG : Couldn't decode error response: EOF 2022/01/12 05:00:25 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:00:25 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish 2022/01/12 05:00:26 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2022/01/12 05:00:26 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoTraverse (2.84s) === RUN TestCopyCheckFirst run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:00:27 INFO : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Running all checks before starting transfers 2022/01/12 05:00:27 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:00:27 INFO : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Checks finished, now starting transfers 2022/01/12 05:00:27 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish 2022/01/12 05:00:28 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2022/01/12 05:00:28 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyCheckFirst (2.70s) === RUN TestSyncNoTraverse run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:00:30 ERROR : Ignoring --no-traverse with sync 2022/01/12 05:00:30 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:00:30 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish 2022/01/12 05:00:31 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2022/01/12 05:00:31 INFO : sub dir/hello world: Copied (new) 2022/01/12 05:00:31 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoTraverse (2.47s) === RUN TestCopyWithDepth run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:00:32 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:00:32 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish 2022/01/12 05:00:33 DEBUG : hello world2: sha1 = c0bf117f9c93d02c894fe8ffd6e5cecf3545c209 OK 2022/01/12 05:00:33 INFO : hello world2: Copied (new) --- PASS: TestCopyWithDepth (2.66s) === RUN TestCopyWithFilesFrom run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:00:35 DEBUG : hello world2: Excluded 2022/01/12 05:00:35 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:00:35 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish 2022/01/12 05:00:36 DEBUG : potato2: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2022/01/12 05:00:36 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFrom (2.07s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:00:38 DEBUG : Couldn't decode error response: EOF 2022/01/12 05:00:38 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:00:38 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish 2022/01/12 05:00:38 DEBUG : potato2: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2022/01/12 05:00:38 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFromAndNoTraverse (2.55s) === RUN TestCopyEmptyDirectories run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:00:40 DEBUG : sub dir2: Making directory 2022/01/12 05:00:40 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:00:40 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish 2022/01/12 05:00:40 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2022/01/12 05:00:40 INFO : sub dir/hello world: Copied (new) 2022/01/12 05:00:40 DEBUG : sub dir2: Making directory 2022/01/12 05:00:40 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: copied 1 directories fstest.go:251: Filtering empty directory "sub dir2" --- PASS: TestCopyEmptyDirectories (2.25s) === RUN TestMoveEmptyDirectories run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:00:42 DEBUG : sub dir2: Making directory 2022/01/12 05:00:42 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:00:42 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish 2022/01/12 05:00:43 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2022/01/12 05:00:43 INFO : sub dir/hello world: Copied (new) 2022/01/12 05:00:43 INFO : sub dir/hello world: Deleted 2022/01/12 05:00:43 DEBUG : sub dir: Making directory 2022/01/12 05:00:43 DEBUG : sub dir2: Making directory 2022/01/12 05:00:43 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: copied 2 directories fstest.go:251: Filtering empty directory "sub dir2" --- PASS: TestMoveEmptyDirectories (2.52s) === RUN TestSyncEmptyDirectories run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:00:44 DEBUG : sub dir2: Making directory 2022/01/12 05:00:44 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:00:44 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish 2022/01/12 05:00:45 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2022/01/12 05:00:45 INFO : sub dir/hello world: Copied (new) 2022/01/12 05:00:45 DEBUG : sub dir2: Making directory 2022/01/12 05:00:45 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: copied 1 directories 2022/01/12 05:00:45 DEBUG : Waiting for deletions to finish fstest.go:251: Filtering empty directory "sub dir2" --- PASS: TestSyncEmptyDirectories (1.37s) === RUN TestServerSideCopy run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:00:47 DEBUG : Creating backend with remote "TestB2:rclone-test-gecunup6vonebux3necipej6" sync_test.go:275: Server side copy (if possible) B2 bucket rclone-test-vosukix1xoyubug3josesif8 -> B2 bucket rclone-test-gecunup6vonebux3necipej6 2022/01/12 05:00:49 DEBUG : B2 bucket rclone-test-gecunup6vonebux3necipej6: Waiting for checks to finish 2022/01/12 05:00:49 DEBUG : B2 bucket rclone-test-gecunup6vonebux3necipej6: Waiting for transfers to finish 2022/01/12 05:00:50 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2022/01/12 05:00:50 INFO : sub dir/hello world: Copied (server-side copy) 2022/01/12 05:00:50 DEBUG : B2 bucket rclone-test-gecunup6vonebux3necipej6: Purge remote 2022/01/12 05:00:51 DEBUG : sub dir/hello world: Deleting (id "4_z9fd2fd83a08d4bcb7fe80e1b_f11733c708709e2c7_d20220112_m050049_c001_v0001113_t0014") --- PASS: TestServerSideCopy (7.18s) === RUN TestCopyAfterDelete run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:00:54 ERROR : : error listing: directory not found 2022/01/12 05:00:54 DEBUG : Local file system at /tmp/rclone1348176003: Making directory 2022/01/12 05:00:54 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:00:54 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish --- PASS: TestCopyAfterDelete (3.49s) === RUN TestCopyRedownload run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:00:58 DEBUG : Local file system at /tmp/rclone1348176003: Waiting for checks to finish 2022/01/12 05:00:58 DEBUG : Local file system at /tmp/rclone1348176003: Waiting for transfers to finish 2022/01/12 05:00:59 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2022/01/12 05:00:59 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyRedownload (3.14s) === RUN TestSyncBasedOnCheckSum run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:01:00 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:01:00 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish 2022/01/12 05:01:00 DEBUG : check sum: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2022/01/12 05:01:00 INFO : check sum: Copied (new) 2022/01/12 05:01:00 DEBUG : Waiting for deletions to finish 2022/01/12 05:01:01 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:01:01 DEBUG : check sum: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2022/01/12 05:01:01 DEBUG : check sum: Size and sha1 of src and dst objects identical 2022/01/12 05:01:01 DEBUG : check sum: Unchanged skipping 2022/01/12 05:01:01 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish 2022/01/12 05:01:01 DEBUG : Waiting for deletions to finish 2022/01/12 05:01:01 INFO : There was nothing to transfer --- PASS: TestSyncBasedOnCheckSum (1.99s) === RUN TestSyncSizeOnly run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:01:02 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:01:02 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish 2022/01/12 05:01:03 DEBUG : sizeonly: sha1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2022/01/12 05:01:03 INFO : sizeonly: Copied (new) 2022/01/12 05:01:03 DEBUG : Waiting for deletions to finish 2022/01/12 05:01:03 DEBUG : sizeonly: Sizes identical 2022/01/12 05:01:03 DEBUG : sizeonly: Unchanged skipping 2022/01/12 05:01:03 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:01:03 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish 2022/01/12 05:01:03 DEBUG : Waiting for deletions to finish 2022/01/12 05:01:03 INFO : There was nothing to transfer --- PASS: TestSyncSizeOnly (2.83s) === RUN TestSyncIgnoreSize run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:01:04 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:01:04 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish 2022/01/12 05:01:05 DEBUG : ignore-size: sha1 = 4a756ca07e9487f482465a99e8286abc86ba4dc7 OK 2022/01/12 05:01:05 INFO : ignore-size: Copied (new) 2022/01/12 05:01:05 DEBUG : Waiting for deletions to finish 2022/01/12 05:01:06 DEBUG : ignore-size: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:01:06 DEBUG : ignore-size: Unchanged skipping 2022/01/12 05:01:06 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:01:06 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish 2022/01/12 05:01:06 DEBUG : Waiting for deletions to finish 2022/01/12 05:01:06 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreSize (2.42s) === RUN TestSyncIgnoreTimes run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:01:08 DEBUG : existing: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:01:08 DEBUG : existing: Unchanged skipping 2022/01/12 05:01:08 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:01:08 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish 2022/01/12 05:01:08 DEBUG : Waiting for deletions to finish 2022/01/12 05:01:08 INFO : There was nothing to transfer 2022/01/12 05:01:08 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2022/01/12 05:01:08 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:01:08 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish 2022/01/12 05:01:12 DEBUG : existing: sha1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2022/01/12 05:01:12 INFO : existing: Copied (replaced existing) 2022/01/12 05:01:12 DEBUG : Waiting for deletions to finish --- PASS: TestSyncIgnoreTimes (8.24s) === RUN TestSyncIgnoreExisting run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:01:15 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:01:15 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish 2022/01/12 05:01:16 DEBUG : existing: sha1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2022/01/12 05:01:16 INFO : existing: Copied (new) 2022/01/12 05:01:16 DEBUG : Waiting for deletions to finish 2022/01/12 05:01:16 DEBUG : existing: Destination exists, skipping 2022/01/12 05:01:16 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:01:16 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish 2022/01/12 05:01:16 DEBUG : Waiting for deletions to finish 2022/01/12 05:01:16 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreExisting (3.41s) === RUN TestSyncIgnoreErrors run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:01:19 DEBUG : d: Making directory fstest.go:251: Filtering empty directory "d" 2022/01/12 05:01:20 DEBUG : a/potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2022/01/12 05:01:20 INFO : a/potato2: Copied (new) 2022/01/12 05:01:21 DEBUG : c/non empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/12 05:01:21 DEBUG : c/non empty space: Unchanged skipping 2022/01/12 05:01:21 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:01:21 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish 2022/01/12 05:01:21 DEBUG : Waiting for deletions to finish 2022/01/12 05:01:22 INFO : b/potato: Deleted --- PASS: TestSyncIgnoreErrors (8.85s) === RUN TestSyncAfterChangingModtimeOnly run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:01:29 DEBUG : empty space: Modification times differ by -95456h54m52.624456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499 +0000 UTC 2022/01/12 05:01:29 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:01:29 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2022/01/12 05:01:29 NOTICE: empty space: Skipped update modification time as --dry-run is set (size 1) 2022/01/12 05:01:29 DEBUG : empty space: Unchanged skipping 2022/01/12 05:01:29 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish 2022/01/12 05:01:29 DEBUG : Waiting for deletions to finish 2022/01/12 05:01:29 INFO : There was nothing to transfer 2022/01/12 05:01:29 DEBUG : empty space: Modification times differ by -95456h54m52.624456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499 +0000 UTC 2022/01/12 05:01:29 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:01:29 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2022/01/12 05:01:32 INFO : empty space: Updated modification time in destination 2022/01/12 05:01:32 DEBUG : empty space: Unchanged skipping 2022/01/12 05:01:32 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish 2022/01/12 05:01:32 DEBUG : Waiting for deletions to finish 2022/01/12 05:01:32 INFO : There was nothing to transfer --- PASS: TestSyncAfterChangingModtimeOnly (6.48s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:01:36 DEBUG : empty space: Modification times differ by -95456h54m52.624456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499 +0000 UTC 2022/01/12 05:01:36 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:01:36 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2022/01/12 05:01:36 DEBUG : empty space: Unchanged skipping 2022/01/12 05:01:36 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish 2022/01/12 05:01:36 DEBUG : Waiting for deletions to finish 2022/01/12 05:01:36 INFO : There was nothing to transfer --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (4.17s) === RUN TestSyncDoesntUpdateModtime run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:01:40 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:01:40 DEBUG : foo: Modification times differ by -95456h54m52.624456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499 +0000 UTC 2022/01/12 05:01:40 DEBUG : foo: sha1 = 0beec7b5ea3f0fdbc95d0dd47f3c5bc275da8a33 (Local file system at /tmp/rclone1348176003) 2022/01/12 05:01:40 DEBUG : foo: sha1 = 62cdb7020ff920e5aa642c3d4066950dd1f01f4d (B2 bucket rclone-test-vosukix1xoyubug3josesif8) 2022/01/12 05:01:40 DEBUG : foo: sha1 differ 2022/01/12 05:01:40 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish 2022/01/12 05:01:40 DEBUG : foo: sha1 = 0beec7b5ea3f0fdbc95d0dd47f3c5bc275da8a33 OK 2022/01/12 05:01:40 INFO : foo: Copied (replaced existing) 2022/01/12 05:01:40 DEBUG : Waiting for deletions to finish --- PASS: TestSyncDoesntUpdateModtime (3.82s) === RUN TestSyncAfterAddingAFile run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:01:43 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/12 05:01:43 DEBUG : empty space: Unchanged skipping 2022/01/12 05:01:43 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:01:43 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish 2022/01/12 05:01:43 DEBUG : potato: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2022/01/12 05:01:43 INFO : potato: Copied (new) 2022/01/12 05:01:43 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterAddingAFile (6.10s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:01:49 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:01:49 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2022/01/12 05:01:49 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish 2022/01/12 05:01:49 DEBUG : potato: sha1 = 0fd321a1023ac4e1e1054fcc0236b9d0755f722f OK 2022/01/12 05:01:49 INFO : potato: Copied (replaced existing) 2022/01/12 05:01:49 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingFilesSizeOnly (3.71s) === RUN TestSyncAfterChangingContentsOnly run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:01:52 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:01:52 DEBUG : potato: Modification times differ by 119h59m59.876543211s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2011-12-30 12:59:59 +0000 UTC 2022/01/12 05:01:52 DEBUG : potato: sha1 = 1ca5094b3b73f587249eabe179c40a3eec839b1f (Local file system at /tmp/rclone1348176003) 2022/01/12 05:01:52 DEBUG : potato: sha1 = 0fd321a1023ac4e1e1054fcc0236b9d0755f722f (B2 bucket rclone-test-vosukix1xoyubug3josesif8) 2022/01/12 05:01:52 DEBUG : potato: sha1 differ 2022/01/12 05:01:52 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish 2022/01/12 05:01:53 DEBUG : potato: sha1 = 1ca5094b3b73f587249eabe179c40a3eec839b1f OK 2022/01/12 05:01:53 INFO : potato: Copied (replaced existing) 2022/01/12 05:01:53 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingContentsOnly (4.53s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:01:59 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:01:59 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/12 05:01:59 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2022/01/12 05:01:59 DEBUG : empty space: Unchanged skipping 2022/01/12 05:01:59 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish 2022/01/12 05:01:59 DEBUG : Waiting for deletions to finish 2022/01/12 05:01:59 NOTICE: potato: Skipped delete as --dry-run is set (size 21) --- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (9.40s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:02:10 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:02:10 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/12 05:02:10 DEBUG : empty space: Unchanged skipping 2022/01/12 05:02:10 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish 2022/01/12 05:02:10 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2022/01/12 05:02:10 INFO : potato2: Copied (new) 2022/01/12 05:02:10 DEBUG : Waiting for deletions to finish 2022/01/12 05:02:11 INFO : potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (9.88s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:02:17 DEBUG : d: Making directory 2022/01/12 05:02:17 DEBUG : d/e: Making directory fstest.go:251: Filtering empty directory "d" fstest.go:251: Filtering empty directory "d/e" 2022/01/12 05:02:19 DEBUG : a/potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2022/01/12 05:02:19 INFO : a/potato2: Copied (new) 2022/01/12 05:02:20 DEBUG : c/non empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/12 05:02:20 DEBUG : c/non empty space: Unchanged skipping 2022/01/12 05:02:21 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:02:21 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish 2022/01/12 05:02:21 DEBUG : Waiting for deletions to finish 2022/01/12 05:02:22 INFO : b/potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (14.25s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:02:32 DEBUG : d: Making directory fstest.go:251: Filtering empty directory "d" 2022/01/12 05:02:36 DEBUG : a/potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2022/01/12 05:02:36 INFO : a/potato2: Copied (new) 2022/01/12 05:02:36 DEBUG : c/non empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/12 05:02:36 DEBUG : c/non empty space: Unchanged skipping 2022/01/12 05:02:37 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:02:37 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish 2022/01/12 05:02:37 ERROR : B2 bucket rclone-test-vosukix1xoyubug3josesif8: not deleting files as there were IO errors 2022/01/12 05:02:37 ERROR : B2 bucket rclone-test-vosukix1xoyubug3josesif8: not deleting directories as there were IO errors fstest.go:251: Filtering empty directory "d" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (21.12s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:02:58 DEBUG : Waiting for deletions to finish 2022/01/12 05:03:00 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:03:00 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/12 05:03:00 DEBUG : empty space: Unchanged skipping 2022/01/12 05:03:00 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish 2022/01/12 05:03:01 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2022/01/12 05:03:01 INFO : potato2: Copied (new) 2022/01/12 05:03:03 INFO : potato: Deleted --- PASS: TestSyncDeleteDuring (19.93s) === RUN TestSyncDeleteBefore run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:03:15 DEBUG : Waiting for deletions to finish 2022/01/12 05:03:16 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:03:16 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish 2022/01/12 05:03:20 INFO : potato: Deleted 2022/01/12 05:03:20 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/12 05:03:20 DEBUG : empty space: Unchanged skipping 2022/01/12 05:03:20 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:03:20 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish 2022/01/12 05:03:21 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2022/01/12 05:03:21 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteBefore (16.96s) === RUN TestCopyDeleteBefore run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:03:34 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:03:34 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish 2022/01/12 05:03:34 DEBUG : potato2: Clearing upload URL because of error: Post "https://pod-000-1093-06.backblaze.com/b2api/v1/b2_upload_file/ff427d93a08d4bcb7fe80e1b/c001_v0001093_t0035": EOF 2022/01/12 05:03:34 DEBUG : pacer: low level retry 1/1 (error Post "https://pod-000-1093-06.backblaze.com/b2api/v1/b2_upload_file/ff427d93a08d4bcb7fe80e1b/c001_v0001093_t0035": EOF) 2022/01/12 05:03:34 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2022/01/12 05:03:34 DEBUG : potato2: Received error: Post "https://pod-000-1093-06.backblaze.com/b2api/v1/b2_upload_file/ff427d93a08d4bcb7fe80e1b/c001_v0001093_t0035": EOF - low level retry 1/10 2022/01/12 05:03:39 DEBUG : pacer: Reducing sleep to 10ms 2022/01/12 05:03:40 DEBUG : potato2: sha1 = 810274f0df19dab60a894a562b7a8ff04c072623 OK 2022/01/12 05:03:40 INFO : potato2: Copied (new) --- PASS: TestCopyDeleteBefore (29.35s) === RUN TestSyncWithExclude run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:03:59 DEBUG : enormous: Excluded 2022/01/12 05:03:59 DEBUG : potato2: Excluded 2022/01/12 05:04:00 DEBUG : potato2: Excluded 2022/01/12 05:04:00 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/12 05:04:00 DEBUG : empty space: Unchanged skipping 2022/01/12 05:04:00 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:04:00 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish 2022/01/12 05:04:00 DEBUG : Waiting for deletions to finish 2022/01/12 05:04:00 INFO : There was nothing to transfer 2022/01/12 05:04:00 DEBUG : enormous: Excluded 2022/01/12 05:04:00 DEBUG : potato2: Excluded 2022/01/12 05:04:01 DEBUG : potato2: Excluded 2022/01/12 05:04:01 DEBUG : empty space: Size and modification time the same (differ by 456.789µs, within tolerance 1ms) 2022/01/12 05:04:01 DEBUG : empty space: Unchanged skipping 2022/01/12 05:04:01 DEBUG : Local file system at /tmp/rclone1348176003: Waiting for checks to finish 2022/01/12 05:04:01 DEBUG : Local file system at /tmp/rclone1348176003: Waiting for transfers to finish 2022/01/12 05:04:01 DEBUG : Waiting for deletions to finish 2022/01/12 05:04:01 INFO : There was nothing to transfer --- PASS: TestSyncWithExclude (15.18s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:04:19 DEBUG : enormous: Excluded 2022/01/12 05:04:19 DEBUG : potato2: Excluded 2022/01/12 05:04:19 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:04:19 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/12 05:04:19 DEBUG : empty space: Unchanged skipping 2022/01/12 05:04:19 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish 2022/01/12 05:04:19 DEBUG : Waiting for deletions to finish 2022/01/12 05:04:23 INFO : potato2: Deleted 2022/01/12 05:04:28 INFO : enormous: Deleted 2022/01/12 05:04:28 INFO : There was nothing to transfer 2022/01/12 05:04:33 DEBUG : empty space: Size and modification time the same (differ by 456.789µs, within tolerance 1ms) 2022/01/12 05:04:33 DEBUG : empty space: Unchanged skipping 2022/01/12 05:04:33 DEBUG : Local file system at /tmp/rclone1348176003: Waiting for checks to finish 2022/01/12 05:04:33 DEBUG : Local file system at /tmp/rclone1348176003: Waiting for transfers to finish 2022/01/12 05:04:33 DEBUG : Waiting for deletions to finish 2022/01/12 05:04:33 INFO : enormous: Deleted 2022/01/12 05:04:33 INFO : potato2: Deleted 2022/01/12 05:04:33 INFO : There was nothing to transfer --- PASS: TestSyncWithExcludeAndDeleteExcluded (27.94s) === RUN TestSyncWithUpdateOlder run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:04:54 DEBUG : four: Sizes differ (src 4 vs dst 8) 2022/01/12 05:04:54 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2022/01/12 05:04:54 DEBUG : one: Destination is newer than source, skipping 2022/01/12 05:04:54 DEBUG : three: Sizes identical 2022/01/12 05:04:54 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2022/01/12 05:04:54 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:04:54 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b (Local file system at /tmp/rclone1348176003) 2022/01/12 05:04:54 DEBUG : two: sha1 = b10e0da85e90399abfec36c7376b20aa4596461b (B2 bucket rclone-test-vosukix1xoyubug3josesif8) 2022/01/12 05:04:54 DEBUG : two: sha1 differ 2022/01/12 05:04:54 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish 2022/01/12 05:04:57 DEBUG : five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2022/01/12 05:04:57 INFO : five: Copied (new) 2022/01/12 05:04:58 DEBUG : four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2022/01/12 05:04:58 INFO : four: Copied (replaced existing) 2022/01/12 05:04:58 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2022/01/12 05:04:58 INFO : two: Copied (replaced existing) 2022/01/12 05:04:58 DEBUG : Waiting for deletions to finish 2022/01/12 05:05:01 DEBUG : one: Destination is newer than source, skipping 2022/01/12 05:05:01 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:05:01 DEBUG : five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2022/01/12 05:05:01 DEBUG : five: Size and sha1 of src and dst objects identical 2022/01/12 05:05:01 DEBUG : five: Destination mod time is within 1s of source and files identical, skipping 2022/01/12 05:05:01 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2022/01/12 05:05:01 DEBUG : two: Size and sha1 of src and dst objects identical 2022/01/12 05:05:01 DEBUG : two: Destination mod time is within 1s of source and files identical, skipping 2022/01/12 05:05:01 DEBUG : three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb (Local file system at /tmp/rclone1348176003) 2022/01/12 05:05:01 DEBUG : three: sha1 = 03731cad13f4cf7b59703c77529381cdec1632e5 (B2 bucket rclone-test-vosukix1xoyubug3josesif8) 2022/01/12 05:05:01 DEBUG : three: sha1 differ 2022/01/12 05:05:01 DEBUG : three: Destination mod time is within 1s of source but files differ, transferring 2022/01/12 05:05:01 DEBUG : four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2022/01/12 05:05:01 DEBUG : four: Size and sha1 of src and dst objects identical 2022/01/12 05:05:01 DEBUG : four: Destination mod time is within 1s of source and files identical, skipping 2022/01/12 05:05:01 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish 2022/01/12 05:05:02 DEBUG : three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2022/01/12 05:05:02 INFO : three: Copied (replaced existing) 2022/01/12 05:05:02 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithUpdateOlder (59.76s) === 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-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" sync_test.go:1058: Can track renames: true 2022/01/12 05:05:40 INFO : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Making map for --track-renames 2022/01/12 05:05:40 INFO : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Finished making map for --track-renames 2022/01/12 05:05:40 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:05:40 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for renames to finish 2022/01/12 05:05:40 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish 2022/01/12 05:05:41 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2022/01/12 05:05:41 INFO : yam: Copied (new) 2022/01/12 05:05:44 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2022/01/12 05:05:44 INFO : potato: Copied (new) 2022/01/12 05:05:44 DEBUG : Waiting for deletions to finish 2022/01/12 05:05:48 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:05:48 DEBUG : potato: Unchanged skipping 2022/01/12 05:05:48 INFO : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Making map for --track-renames 2022/01/12 05:05:48 INFO : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Finished making map for --track-renames 2022/01/12 05:05:48 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:05:48 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for renames to finish 2022/01/12 05:05:48 DEBUG : Couldn't decode error response: EOF 2022/01/12 05:06:06 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2022/01/12 05:06:06 INFO : yam: Copied (server-side copy) to: yaml 2022/01/12 05:06:12 INFO : yam: Deleted 2022/01/12 05:06:12 INFO : yaml: Renamed from "yam" 2022/01/12 05:06:12 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish 2022/01/12 05:06:12 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenames (42.10s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" sync_test.go:1127: Can track renames: true 2022/01/12 05:06:23 INFO : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Making map for --track-renames 2022/01/12 05:06:23 INFO : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Finished making map for --track-renames 2022/01/12 05:06:23 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:06:23 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for renames to finish 2022/01/12 05:06:23 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish 2022/01/12 05:06:23 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2022/01/12 05:06:23 INFO : yam: Copied (new) 2022/01/12 05:06:23 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2022/01/12 05:06:23 INFO : potato: Copied (new) 2022/01/12 05:06:23 DEBUG : Waiting for deletions to finish 2022/01/12 05:06:26 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:06:26 DEBUG : potato: Unchanged skipping 2022/01/12 05:06:26 INFO : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Making map for --track-renames 2022/01/12 05:06:26 INFO : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Finished making map for --track-renames 2022/01/12 05:06:26 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:06:26 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for renames to finish 2022/01/12 05:06:43 DEBUG : Couldn't decode error response: EOF 2022/01/12 05:06:54 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2022/01/12 05:06:54 INFO : yam: Copied (server-side copy) to: yaml 2022/01/12 05:06:55 INFO : yam: Deleted 2022/01/12 05:06:55 INFO : yaml: Renamed from "yam" 2022/01/12 05:06:55 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish 2022/01/12 05:06:55 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenamesStrategyModtime (37.25s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" sync_test.go:1163: Can track renames: true 2022/01/12 05:06:59 INFO : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Making map for --track-renames 2022/01/12 05:06:59 INFO : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Finished making map for --track-renames 2022/01/12 05:06:59 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:06:59 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for renames to finish 2022/01/12 05:06:59 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish 2022/01/12 05:07:00 DEBUG : potato: Clearing upload URL because of error: no tomes available (503 service_unavailable) 2022/01/12 05:07:00 DEBUG : pacer: low level retry 1/1 (error no tomes available (503 service_unavailable)) 2022/01/12 05:07:00 DEBUG : pacer: Rate limited, increasing sleep to 1s 2022/01/12 05:07:00 DEBUG : potato: Received error: no tomes available (503 service_unavailable) - low level retry 1/10 2022/01/12 05:07:01 DEBUG : pacer: Reducing sleep to 500ms 2022/01/12 05:07:01 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2022/01/12 05:07:01 INFO : potato: Copied (new) 2022/01/12 05:07:02 DEBUG : pacer: Reducing sleep to 250ms 2022/01/12 05:07:02 DEBUG : sub/yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2022/01/12 05:07:02 INFO : sub/yam: Copied (new) 2022/01/12 05:07:02 DEBUG : Waiting for deletions to finish 2022/01/12 05:07:02 DEBUG : pacer: Reducing sleep to 125ms 2022/01/12 05:07:03 DEBUG : pacer: Reducing sleep to 62.5ms 2022/01/12 05:07:03 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:07:03 DEBUG : potato: Unchanged skipping 2022/01/12 05:07:04 DEBUG : pacer: Reducing sleep to 31.25ms 2022/01/12 05:07:04 INFO : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Making map for --track-renames 2022/01/12 05:07:04 INFO : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Finished making map for --track-renames 2022/01/12 05:07:04 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:07:04 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for renames to finish 2022/01/12 05:07:04 DEBUG : Couldn't decode error response: EOF 2022/01/12 05:07:04 DEBUG : pacer: Reducing sleep to 15.625ms 2022/01/12 05:07:06 DEBUG : pacer: Reducing sleep to 10ms 2022/01/12 05:07:06 DEBUG : sub/yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2022/01/12 05:07:06 INFO : sub/yam: Copied (server-side copy) to: yam 2022/01/12 05:07:09 INFO : sub/yam: Deleted 2022/01/12 05:07:09 INFO : yam: Renamed from "sub/yam" 2022/01/12 05:07:09 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish 2022/01/12 05:07:09 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenamesStrategyLeaf (13.96s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:07:13 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:07:13 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish 2022/01/12 05:07:14 DEBUG : nested/sub dir/file: sha1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK 2022/01/12 05:07:14 INFO : nested/sub dir/file: Copied (new) 2022/01/12 05:07:14 INFO : nested/sub dir/file: Deleted 2022/01/12 05:07:14 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2022/01/12 05:07:14 INFO : sub dir/hello world: Copied (new) 2022/01/12 05:07:14 INFO : sub dir/hello world: Deleted 2022/01/12 05:07:14 INFO : sub dir: Removing directory 2022/01/12 05:07:14 INFO : nested/sub dir: Removing directory 2022/01/12 05:07:14 INFO : nested: Removing directory 2022/01/12 05:07:14 DEBUG : Local file system at /tmp/rclone1348176003: deleted 3 directories --- PASS: TestMoveWithDeleteEmptySrcDirs (4.61s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:07:18 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:07:18 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish 2022/01/12 05:07:19 DEBUG : nested/sub dir/file: sha1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK 2022/01/12 05:07:19 INFO : nested/sub dir/file: Copied (new) 2022/01/12 05:07:19 INFO : nested/sub dir/file: Deleted 2022/01/12 05:07:19 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2022/01/12 05:07:19 INFO : sub dir/hello world: Copied (new) 2022/01/12 05:07:19 INFO : sub dir/hello world: Deleted --- PASS: TestMoveWithoutDeleteEmptySrcDirs (4.21s) === RUN TestMoveWithIgnoreExisting run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:07:22 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:07:22 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish 2022/01/12 05:07:23 DEBUG : existing: sha1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2022/01/12 05:07:23 INFO : existing: Copied (new) 2022/01/12 05:07:23 INFO : existing: Deleted 2022/01/12 05:07:23 DEBUG : existing-b: sha1 = 321f6b7e8bf7f29aab89c5e41a555b1b0baa41a9 OK 2022/01/12 05:07:23 INFO : existing-b: Copied (new) 2022/01/12 05:07:23 INFO : existing-b: Deleted 2022/01/12 05:07:24 DEBUG : existing: Destination exists, skipping 2022/01/12 05:07:24 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set 2022/01/12 05:07:24 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:07:24 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish 2022/01/12 05:07:24 INFO : There was nothing to transfer --- PASS: TestMoveWithIgnoreExisting (4.02s) === RUN TestServerSideMove run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:07:26 DEBUG : Creating backend with remote "TestB2:rclone-test-yixosap5fiwoyer9numewom4" 2022/01/12 05:07:26 DEBUG : Config file has changed externaly - reloading sync_test.go:1215: Server side move (if possible) B2 bucket rclone-test-vosukix1xoyubug3josesif8 -> B2 bucket rclone-test-yixosap5fiwoyer9numewom4 2022/01/12 05:07:39 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms) 2022/01/12 05:07:39 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2022/01/12 05:07:39 DEBUG : B2 bucket rclone-test-yixosap5fiwoyer9numewom4: Waiting for checks to finish 2022/01/12 05:07:39 DEBUG : empty space: Unchanged skipping 2022/01/12 05:07:39 INFO : empty space: Deleted 2022/01/12 05:07:39 DEBUG : B2 bucket rclone-test-yixosap5fiwoyer9numewom4: Waiting for transfers to finish 2022/01/12 05:07:40 DEBUG : potato3: sha1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2022/01/12 05:07:40 INFO : potato3: Copied (server-side copy) 2022/01/12 05:07:40 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2022/01/12 05:07:40 INFO : potato2: Copied (server-side copy) 2022/01/12 05:07:41 INFO : potato3: Deleted 2022/01/12 05:07:41 INFO : potato2: Deleted 2022/01/12 05:07:42 DEBUG : Creating backend with remote "TestB2:rclone-test-vodapok1rowasor4quwefis4" 2022/01/12 05:07:45 DEBUG : B2 bucket rclone-test-vodapok1rowasor4quwefis4: Waiting for checks to finish 2022/01/12 05:07:45 DEBUG : B2 bucket rclone-test-vodapok1rowasor4quwefis4: Waiting for transfers to finish 2022/01/12 05:07:46 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2022/01/12 05:07:46 INFO : potato2: Copied (server-side copy) 2022/01/12 05:07:48 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2022/01/12 05:07:48 INFO : empty space: Copied (server-side copy) 2022/01/12 05:07:48 INFO : potato2: Deleted 2022/01/12 05:07:49 DEBUG : potato3: sha1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2022/01/12 05:07:49 INFO : potato3: Copied (server-side copy) 2022/01/12 05:07:51 INFO : empty space: Deleted 2022/01/12 05:07:51 INFO : potato3: Deleted 2022/01/12 05:07:51 DEBUG : B2 bucket rclone-test-vodapok1rowasor4quwefis4: Purge remote 2022/01/12 05:07:52 DEBUG : empty space: Deleting (id "4_z0f925d53a0fd4bcb7fe80e1b_f1171b4d4092a9de5_d20220112_m050748_c001_v0001098_t0040") 2022/01/12 05:07:52 DEBUG : potato2: Deleting (id "4_z0f925d53a0fd4bcb7fe80e1b_f100ef97a4adb6dbf_d20220112_m050746_c001_v0001150_t0038") 2022/01/12 05:07:52 DEBUG : potato3: Deleting (id "4_z0f925d53a0fd4bcb7fe80e1b_f116696faf378c526_d20220112_m050749_c001_v0001109_t0034") 2022/01/12 05:07:54 DEBUG : B2 bucket rclone-test-yixosap5fiwoyer9numewom4: Purge remote 2022/01/12 05:07:55 DEBUG : empty space: Deleting (id "4_z4f621d23a0fd4bcb7fe80e1b_f115ac477b75f25b7_d20220112_m050749_c001_v0001105_t0014") 2022/01/12 05:07:55 DEBUG : empty space: Deleting (id "4_z4f621d23a0fd4bcb7fe80e1b_f104e0a22740ba19c_d20220112_m050730_c001_v0001105_t0005") 2022/01/12 05:07:55 DEBUG : potato2: Deleting (id "4_z4f621d23a0fd4bcb7fe80e1b_f116a8b224ddb41ca_d20220112_m050748_c001_v0001130_t0043") 2022/01/12 05:07:55 DEBUG : potato2: Deleting (id "4_z4f621d23a0fd4bcb7fe80e1b_f108da012fcc69738_d20220112_m050740_c001_v0001165_t0002") 2022/01/12 05:07:55 DEBUG : potato3: Deleting (id "4_z4f621d23a0fd4bcb7fe80e1b_f109c766b9c374290_d20220112_m050750_c001_v0001144_t0038") 2022/01/12 05:07:55 DEBUG : potato3: Deleting (id "4_z4f621d23a0fd4bcb7fe80e1b_f1098f5e74ff6af19_d20220112_m050739_c001_v0001117_t0054") 2022/01/12 05:07:55 DEBUG : potato3: Deleting (id "4_z4f621d23a0fd4bcb7fe80e1b_f104e0a22740ba1e5_d20220112_m050734_c001_v0001105_t0010") --- PASS: TestServerSideMove (33.69s) === RUN TestServerSideMoveWithFilter run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:08:00 DEBUG : Creating backend with remote "TestB2:rclone-test-luhatan6camuhoz3becukax5" sync_test.go:1215: Server side move (if possible) B2 bucket rclone-test-vosukix1xoyubug3josesif8 -> B2 bucket rclone-test-luhatan6camuhoz3becukax5 2022/01/12 05:08:09 DEBUG : empty space: Excluded 2022/01/12 05:08:10 DEBUG : empty space: Excluded 2022/01/12 05:08:10 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2022/01/12 05:08:10 DEBUG : B2 bucket rclone-test-luhatan6camuhoz3becukax5: Waiting for checks to finish 2022/01/12 05:08:10 DEBUG : B2 bucket rclone-test-luhatan6camuhoz3becukax5: Waiting for transfers to finish 2022/01/12 05:08:10 DEBUG : potato3: sha1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2022/01/12 05:08:10 INFO : potato3: Copied (server-side copy) 2022/01/12 05:08:12 INFO : potato3: Deleted 2022/01/12 05:08:12 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2022/01/12 05:08:12 INFO : potato2: Copied (server-side copy) 2022/01/12 05:08:15 INFO : potato2: Deleted 2022/01/12 05:08:16 DEBUG : Creating backend with remote "TestB2:rclone-test-tacutuf8mudulod4varuvud3" 2022/01/12 05:08:16 DEBUG : Config file has changed externaly - reloading 2022/01/12 05:08:17 DEBUG : empty space: Excluded 2022/01/12 05:08:17 DEBUG : B2 bucket rclone-test-tacutuf8mudulod4varuvud3: Waiting for checks to finish 2022/01/12 05:08:17 DEBUG : B2 bucket rclone-test-tacutuf8mudulod4varuvud3: Waiting for transfers to finish 2022/01/12 05:08:20 DEBUG : potato3: sha1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2022/01/12 05:08:20 INFO : potato3: Copied (server-side copy) 2022/01/12 05:08:21 INFO : potato3: Deleted 2022/01/12 05:08:23 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2022/01/12 05:08:23 INFO : potato2: Copied (server-side copy) 2022/01/12 05:08:25 INFO : potato2: Deleted 2022/01/12 05:08:27 DEBUG : B2 bucket rclone-test-tacutuf8mudulod4varuvud3: Purge remote 2022/01/12 05:08:29 DEBUG : potato2: Deleting (id "4_z1f82dd33a0fd4bcb7fe80e1b_f11099568688d06eb_d20220112_m050822_c001_v0001038_t0013") 2022/01/12 05:08:29 DEBUG : potato3: Deleting (id "4_z1f82dd33a0fd4bcb7fe80e1b_f111c95d772aa6413_d20220112_m050818_c001_v0001039_t0012") 2022/01/12 05:08:33 DEBUG : B2 bucket rclone-test-luhatan6camuhoz3becukax5: Purge remote 2022/01/12 05:08:36 DEBUG : empty space: Deleting (id "4_z8f42ad03a0fd4bcb7fe80e1b_f110529361de983d7_d20220112_m050807_c001_v0001130_t0000") 2022/01/12 05:08:36 DEBUG : potato2: Deleting (id "4_z8f42ad03a0fd4bcb7fe80e1b_f102cd52567e08115_d20220112_m050823_c001_v0001117_t0047") 2022/01/12 05:08:36 DEBUG : potato2: Deleting (id "4_z8f42ad03a0fd4bcb7fe80e1b_f102ad4c1842f77c1_d20220112_m050812_c001_v0001137_t0038") 2022/01/12 05:08:36 DEBUG : potato3: Deleting (id "4_z8f42ad03a0fd4bcb7fe80e1b_f104a04737633dca0_d20220112_m050820_c001_v0001093_t0003") 2022/01/12 05:08:36 DEBUG : potato3: Deleting (id "4_z8f42ad03a0fd4bcb7fe80e1b_f104a04737633dc5b_d20220112_m050810_c001_v0001093_t0050") 2022/01/12 05:08:36 DEBUG : potato3: Deleting (id "4_z8f42ad03a0fd4bcb7fe80e1b_f110529361de983dd_d20220112_m050808_c001_v0001130_t0050") --- PASS: TestServerSideMoveWithFilter (40.28s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:08:40 DEBUG : Creating backend with remote "TestB2:rclone-test-juhusef8mexehis9lidotoz1" 2022/01/12 05:08:43 DEBUG : tomatoDir: Making directory sync_test.go:1215: Server side move (if possible) B2 bucket rclone-test-vosukix1xoyubug3josesif8 -> B2 bucket rclone-test-juhusef8mexehis9lidotoz1 2022/01/12 05:08:57 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms) 2022/01/12 05:08:57 DEBUG : empty space: Unchanged skipping 2022/01/12 05:08:57 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2022/01/12 05:08:57 DEBUG : B2 bucket rclone-test-juhusef8mexehis9lidotoz1: Waiting for checks to finish 2022/01/12 05:08:57 DEBUG : potato3: sha1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2022/01/12 05:08:57 INFO : potato3: Copied (server-side copy) 2022/01/12 05:08:57 INFO : potato3: Deleted 2022/01/12 05:08:59 INFO : empty space: Deleted 2022/01/12 05:08:59 DEBUG : B2 bucket rclone-test-juhusef8mexehis9lidotoz1: Waiting for transfers to finish 2022/01/12 05:09:09 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2022/01/12 05:09:09 INFO : potato2: Copied (server-side copy) 2022/01/12 05:09:16 INFO : potato2: Deleted 2022/01/12 05:09:20 DEBUG : Creating backend with remote "TestB2:rclone-test-mojawan6dowedox0petehec5" 2022/01/12 05:09:20 DEBUG : tomatoDir: Making directory 2022/01/12 05:09:21 DEBUG : B2 bucket rclone-test-mojawan6dowedox0petehec5: Waiting for checks to finish 2022/01/12 05:09:21 DEBUG : B2 bucket rclone-test-mojawan6dowedox0petehec5: Waiting for transfers to finish 2022/01/12 05:09:22 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2022/01/12 05:09:22 INFO : empty space: Copied (server-side copy) 2022/01/12 05:09:23 DEBUG : potato3: sha1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2022/01/12 05:09:23 INFO : potato3: Copied (server-side copy) 2022/01/12 05:09:23 INFO : potato3: Deleted 2022/01/12 05:09:24 INFO : empty space: Deleted 2022/01/12 05:10:08 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2022/01/12 05:10:08 INFO : potato2: Copied (server-side copy) 2022/01/12 05:10:15 INFO : potato2: Deleted 2022/01/12 05:10:17 DEBUG : B2 bucket rclone-test-mojawan6dowedox0petehec5: Purge remote 2022/01/12 05:10:18 DEBUG : empty space: Deleting (id "4_z2fd2cda3b00d4bcb7fe80e1b_f1133baf70642b06a_d20220112_m050922_c001_v0001157_t0043") 2022/01/12 05:10:18 DEBUG : potato2: Deleting (id "4_z2fd2cda3b00d4bcb7fe80e1b_f10136a2ec05fdf74_d20220112_m051007_c001_v0001154_t0022") 2022/01/12 05:10:18 DEBUG : potato3: Deleting (id "4_z2fd2cda3b00d4bcb7fe80e1b_f115a1b2a8018d5d9_d20220112_m050922_c001_v0001109_t0025") 2022/01/12 05:10:20 DEBUG : B2 bucket rclone-test-juhusef8mexehis9lidotoz1: Purge remote 2022/01/12 05:10:20 DEBUG : empty space: Deleting (id "4_zffa24d53b00d4bcb7fe80e1b_f11145f3723f896da_d20220112_m050922_c001_v0001144_t0042") 2022/01/12 05:10:20 DEBUG : empty space: Deleting (id "4_zffa24d53b00d4bcb7fe80e1b_f107fd9423227ad3f_d20220112_m050849_c001_v0001109_t0044") 2022/01/12 05:10:20 DEBUG : potato2: Deleting (id "4_zffa24d53b00d4bcb7fe80e1b_f100ce1ab60750f9f_d20220112_m051013_c001_v0001105_t0056") 2022/01/12 05:10:20 DEBUG : potato2: Deleting (id "4_zffa24d53b00d4bcb7fe80e1b_f106688e579673c81_d20220112_m050909_c001_v0001165_t0037") 2022/01/12 05:10:20 DEBUG : potato3: Deleting (id "4_zffa24d53b00d4bcb7fe80e1b_f101d469c615f2791_d20220112_m050923_c001_v0001157_t0032") 2022/01/12 05:10:20 DEBUG : potato3: Deleting (id "4_zffa24d53b00d4bcb7fe80e1b_f119d07f6eccc164d_d20220112_m050857_c001_v0001150_t0004") 2022/01/12 05:10:20 DEBUG : potato3: Deleting (id "4_zffa24d53b00d4bcb7fe80e1b_f107fd9423227ad4e_d20220112_m050850_c001_v0001109_t0038") --- PASS: TestServerSideMoveDeleteEmptySourceDirs (104.65s) === RUN TestServerSideMoveOverlap run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:10:25 DEBUG : Creating backend with remote "TestB2:rclone-test-vosukix1xoyubug3josesif8/rclone-move-test" 2022/01/12 05:10:26 DEBUG : Couldn't decode error response: EOF --- PASS: TestServerSideMoveOverlap (13.18s) === RUN TestSyncOverlap run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:10:38 DEBUG : Creating backend with remote "TestB2:rclone-test-vosukix1xoyubug3josesif8/rclone-sync-test" 2022/01/12 05:10:40 DEBUG : Couldn't decode error response: EOF --- PASS: TestSyncOverlap (2.61s) === RUN TestSyncCompareDest run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:10:40 DEBUG : Creating backend with remote "TestB2:rclone-test-vosukix1xoyubug3josesif8/dst" 2022/01/12 05:10:41 DEBUG : Couldn't decode error response: EOF 2022/01/12 05:10:41 DEBUG : Creating backend with remote "TestB2:rclone-test-vosukix1xoyubug3josesif8/CompareDest" 2022/01/12 05:10:41 DEBUG : Couldn't decode error response: EOF 2022/01/12 05:10:43 DEBUG : Couldn't decode error response: EOF 2022/01/12 05:10:43 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8 path dst: Waiting for checks to finish 2022/01/12 05:10:43 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8 path dst: Waiting for transfers to finish 2022/01/12 05:10:44 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/01/12 05:10:44 INFO : one: Copied (new) 2022/01/12 05:10:44 DEBUG : Waiting for deletions to finish 2022/01/12 05:10:45 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8 path dst: Waiting for checks to finish 2022/01/12 05:10:46 DEBUG : Couldn't decode error response: EOF 2022/01/12 05:10:46 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/01/12 05:10:46 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8 path dst: Waiting for transfers to finish 2022/01/12 05:10:47 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2022/01/12 05:10:47 INFO : one: Copied (replaced existing) 2022/01/12 05:10:47 DEBUG : Waiting for deletions to finish 2022/01/12 05:10:50 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8 path dst: Waiting for checks to finish 2022/01/12 05:10:51 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/12 05:10:51 DEBUG : one: Destination found in --compare-dest, skipping 2022/01/12 05:10:51 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8 path dst: Waiting for transfers to finish 2022/01/12 05:10:51 DEBUG : Waiting for deletions to finish 2022/01/12 05:10:51 INFO : There was nothing to transfer 2022/01/12 05:10:52 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/12 05:10:52 DEBUG : two: Destination found in --compare-dest, skipping 2022/01/12 05:10:52 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8 path dst: Waiting for checks to finish 2022/01/12 05:10:53 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/12 05:10:53 DEBUG : one: Destination found in --compare-dest, skipping 2022/01/12 05:10:53 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8 path dst: Waiting for transfers to finish 2022/01/12 05:10:53 DEBUG : Waiting for deletions to finish 2022/01/12 05:10:53 INFO : There was nothing to transfer 2022/01/12 05:10:54 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/12 05:10:54 DEBUG : two: Destination found in --compare-dest, skipping 2022/01/12 05:10:54 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8 path dst: Waiting for checks to finish 2022/01/12 05:10:55 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/12 05:10:55 DEBUG : one: Destination found in --compare-dest, skipping 2022/01/12 05:10:55 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8 path dst: Waiting for transfers to finish 2022/01/12 05:10:55 DEBUG : Waiting for deletions to finish 2022/01/12 05:10:55 INFO : There was nothing to transfer 2022/01/12 05:10:57 DEBUG : two: Modification times differ by -119h59m59.877s: 2011-12-30 12:59:59 +0000 UTC, 2011-12-25 12:59:59.123 +0000 UTC 2022/01/12 05:10:57 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2022/01/12 05:10:57 DEBUG : two: Destination found in --compare-dest, skipping 2022/01/12 05:10:57 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8 path dst: Waiting for checks to finish 2022/01/12 05:10:58 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/12 05:10:58 DEBUG : one: Destination found in --compare-dest, skipping 2022/01/12 05:10:58 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8 path dst: Waiting for transfers to finish 2022/01/12 05:10:58 DEBUG : Waiting for deletions to finish 2022/01/12 05:10:58 INFO : There was nothing to transfer 2022/01/12 05:10:59 DEBUG : two: Sizes differ (src 5 vs dst 3) 2022/01/12 05:10:59 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8 path dst: Waiting for checks to finish 2022/01/12 05:11:00 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/12 05:11:00 DEBUG : one: Destination found in --compare-dest, skipping 2022/01/12 05:11:00 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8 path dst: Waiting for transfers to finish 2022/01/12 05:11:02 DEBUG : two: sha1 = a4be8877ad88f3109a6411985a1f359e10e2d930 OK 2022/01/12 05:11:02 INFO : two: Copied (new) 2022/01/12 05:11:02 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCompareDest (24.97s) === RUN TestSyncMultipleCompareDest run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:11:07 DEBUG : Creating backend with remote "TestB2:rclone-test-vosukix1xoyubug3josesif8/dest" 2022/01/12 05:11:09 DEBUG : Couldn't decode error response: EOF 2022/01/12 05:11:09 DEBUG : Creating backend with remote "TestB2:rclone-test-vosukix1xoyubug3josesif8/pre-dest1" 2022/01/12 05:11:09 DEBUG : Couldn't decode error response: EOF 2022/01/12 05:11:09 DEBUG : Creating backend with remote "TestB2:rclone-test-vosukix1xoyubug3josesif8/pre-dest2" 2022/01/12 05:11:11 DEBUG : Couldn't decode error response: EOF 2022/01/12 05:11:11 DEBUG : 1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:11:11 DEBUG : 1: Destination found in --compare-dest, skipping 2022/01/12 05:11:12 DEBUG : Couldn't decode error response: EOF 2022/01/12 05:11:12 DEBUG : 2: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:11:12 DEBUG : 2: Destination found in --compare-dest, skipping 2022/01/12 05:11:12 DEBUG : Couldn't decode error response: EOF 2022/01/12 05:11:12 DEBUG : Couldn't decode error response: EOF 2022/01/12 05:11:12 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8 path dest: Waiting for checks to finish 2022/01/12 05:11:12 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8 path dest: Waiting for transfers to finish 2022/01/12 05:11:16 DEBUG : 3: sha1 = 77de68daecd823babbb58edb1c8e14d7106e83bb OK 2022/01/12 05:11:16 INFO : 3: Copied (new) 2022/01/12 05:11:16 DEBUG : Waiting for deletions to finish --- PASS: TestSyncMultipleCompareDest (13.67s) === RUN TestSyncCopyDest run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:11:19 DEBUG : Creating backend with remote "TestB2:rclone-test-vosukix1xoyubug3josesif8/dst" 2022/01/12 05:11:20 DEBUG : Couldn't decode error response: EOF 2022/01/12 05:11:20 DEBUG : Creating backend with remote "TestB2:rclone-test-vosukix1xoyubug3josesif8/CopyDest" 2022/01/12 05:11:21 DEBUG : Couldn't decode error response: EOF 2022/01/12 05:11:21 DEBUG : Couldn't decode error response: EOF 2022/01/12 05:11:21 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8 path dst: Waiting for checks to finish 2022/01/12 05:11:21 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8 path dst: Waiting for transfers to finish 2022/01/12 05:11:23 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/01/12 05:11:23 INFO : one: Copied (new) 2022/01/12 05:11:23 DEBUG : Waiting for deletions to finish 2022/01/12 05:11:23 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8 path dst: Waiting for checks to finish 2022/01/12 05:11:24 DEBUG : Couldn't decode error response: EOF 2022/01/12 05:11:24 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/01/12 05:11:24 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8 path dst: Waiting for transfers to finish 2022/01/12 05:11:25 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2022/01/12 05:11:25 INFO : one: Copied (replaced existing) 2022/01/12 05:11:25 DEBUG : Waiting for deletions to finish 2022/01/12 05:11:29 DEBUG : Creating backend with remote "TestB2:rclone-test-vosukix1xoyubug3josesif8/BackupDir" 2022/01/12 05:11:39 DEBUG : Couldn't decode error response: EOF 2022/01/12 05:11:40 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8 path dst: Waiting for checks to finish 2022/01/12 05:11:42 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/12 05:11:42 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/01/12 05:11:43 DEBUG : Couldn't decode error response: EOF 2022/01/12 05:11:45 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/01/12 05:11:45 INFO : one: Copied (server-side copy) 2022/01/12 05:11:45 INFO : one: Deleted 2022/01/12 05:11:46 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2022/01/12 05:11:46 INFO : one: Copied (server-side copy) 2022/01/12 05:11:46 DEBUG : one: Destination found in --copy-dest, using server-side copy 2022/01/12 05:11:46 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8 path dst: Waiting for transfers to finish 2022/01/12 05:11:46 DEBUG : Waiting for deletions to finish 2022/01/12 05:11:49 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/12 05:11:53 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2022/01/12 05:11:53 INFO : two: Copied (server-side copy) 2022/01/12 05:11:53 DEBUG : two: Destination found in --copy-dest, using server-side copy 2022/01/12 05:11:53 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8 path dst: Waiting for checks to finish 2022/01/12 05:11:54 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/12 05:11:54 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/12 05:11:54 DEBUG : one: Unchanged skipping 2022/01/12 05:11:54 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8 path dst: Waiting for transfers to finish 2022/01/12 05:11:54 DEBUG : Waiting for deletions to finish 2022/01/12 05:11:55 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8 path dst: Waiting for checks to finish 2022/01/12 05:11:55 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/12 05:11:55 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/12 05:11:55 DEBUG : one: Unchanged skipping 2022/01/12 05:11:55 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/12 05:11:55 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/12 05:11:55 DEBUG : two: Unchanged skipping 2022/01/12 05:11:55 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8 path dst: Waiting for transfers to finish 2022/01/12 05:11:55 DEBUG : Waiting for deletions to finish 2022/01/12 05:11:55 INFO : There was nothing to transfer 2022/01/12 05:12:08 DEBUG : three: Sizes differ (src 7 vs dst 5) 2022/01/12 05:12:08 DEBUG : three: Destination not found in --copy-dest 2022/01/12 05:12:08 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8 path dst: Waiting for checks to finish 2022/01/12 05:12:08 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/12 05:12:08 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/12 05:12:08 DEBUG : one: Unchanged skipping 2022/01/12 05:12:09 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/12 05:12:09 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/12 05:12:09 DEBUG : two: Unchanged skipping 2022/01/12 05:12:09 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8 path dst: Waiting for transfers to finish 2022/01/12 05:12:10 DEBUG : three: sha1 = 370e613335f3cf2f19b2a2d35a86402f55c659f8 OK 2022/01/12 05:12:10 INFO : three: Copied (new) 2022/01/12 05:12:10 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCopyDest (60.13s) === RUN TestSyncBackupDir run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:12:23 DEBUG : Creating backend with remote "TestB2:rclone-test-vosukix1xoyubug3josesif8/dst" 2022/01/12 05:12:24 DEBUG : Couldn't decode error response: EOF 2022/01/12 05:12:24 DEBUG : Creating backend with remote "TestB2:rclone-test-vosukix1xoyubug3josesif8/backup" 2022/01/12 05:12:25 DEBUG : Couldn't decode error response: EOF 2022/01/12 05:12:25 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/01/12 05:12:25 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:12:25 DEBUG : two: Unchanged skipping 2022/01/12 05:12:25 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8 path dst: Waiting for checks to finish 2022/01/12 05:12:25 DEBUG : Couldn't decode error response: EOF 2022/01/12 05:12:27 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/01/12 05:12:27 INFO : one: Copied (server-side copy) 2022/01/12 05:12:27 INFO : one: Deleted 2022/01/12 05:12:27 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8 path dst: Waiting for transfers to finish 2022/01/12 05:12:28 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2022/01/12 05:12:28 INFO : one: Copied (new) 2022/01/12 05:12:28 DEBUG : Waiting for deletions to finish 2022/01/12 05:12:28 DEBUG : Couldn't decode error response: EOF 2022/01/12 05:12:29 DEBUG : three.txt: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2022/01/12 05:12:29 INFO : three.txt: Copied (server-side copy) 2022/01/12 05:12:30 INFO : three.txt: Deleted 2022/01/12 05:12:30 INFO : three.txt: Moved into backup dir 2022/01/12 05:12:33 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8 path dst: Waiting for checks to finish 2022/01/12 05:12:33 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/01/12 05:12:33 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:12:33 DEBUG : two: Unchanged skipping 2022/01/12 05:12:33 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2022/01/12 05:12:33 INFO : one: Copied (server-side copy) 2022/01/12 05:12:34 INFO : one: Deleted 2022/01/12 05:12:34 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8 path dst: Waiting for transfers to finish 2022/01/12 05:12:35 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2022/01/12 05:12:35 INFO : one: Copied (new) 2022/01/12 05:12:35 DEBUG : Waiting for deletions to finish 2022/01/12 05:12:38 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2022/01/12 05:12:38 INFO : three.txt: Copied (server-side copy) 2022/01/12 05:12:43 INFO : three.txt: Deleted 2022/01/12 05:12:43 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (27.41s) === RUN TestSyncBackupDirWithSuffix run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:12:49 DEBUG : Creating backend with remote "TestB2:rclone-test-vosukix1xoyubug3josesif8/dst" 2022/01/12 05:12:51 DEBUG : Couldn't decode error response: EOF 2022/01/12 05:12:51 DEBUG : Creating backend with remote "TestB2:rclone-test-vosukix1xoyubug3josesif8/backup" 2022/01/12 05:12:51 DEBUG : Couldn't decode error response: EOF 2022/01/12 05:12:52 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/01/12 05:12:52 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:12:52 DEBUG : two: Unchanged skipping 2022/01/12 05:12:52 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8 path dst: Waiting for checks to finish 2022/01/12 05:12:52 DEBUG : Couldn't decode error response: EOF 2022/01/12 05:12:53 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/01/12 05:12:53 INFO : one: Copied (server-side copy) to: one.bak 2022/01/12 05:12:54 INFO : one: Deleted 2022/01/12 05:12:54 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8 path dst: Waiting for transfers to finish 2022/01/12 05:12:58 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2022/01/12 05:12:58 INFO : one: Copied (new) 2022/01/12 05:12:58 DEBUG : Waiting for deletions to finish 2022/01/12 05:12:59 DEBUG : Couldn't decode error response: EOF 2022/01/12 05:13:01 DEBUG : three.txt: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2022/01/12 05:13:01 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/01/12 05:13:02 INFO : three.txt: Deleted 2022/01/12 05:13:02 INFO : three.txt: Moved into backup dir 2022/01/12 05:13:03 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/01/12 05:13:03 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:13:03 DEBUG : two: Unchanged skipping 2022/01/12 05:13:03 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8 path dst: Waiting for checks to finish 2022/01/12 05:13:04 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2022/01/12 05:13:04 INFO : one: Copied (server-side copy) to: one.bak 2022/01/12 05:13:05 INFO : one: Deleted 2022/01/12 05:13:05 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8 path dst: Waiting for transfers to finish 2022/01/12 05:13:06 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2022/01/12 05:13:06 INFO : one: Copied (new) 2022/01/12 05:13:06 DEBUG : Waiting for deletions to finish 2022/01/12 05:13:07 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2022/01/12 05:13:07 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/01/12 05:13:08 INFO : three.txt: Deleted 2022/01/12 05:13:08 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (24.85s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:13:14 DEBUG : Creating backend with remote "TestB2:rclone-test-vosukix1xoyubug3josesif8/dst" 2022/01/12 05:13:16 DEBUG : Couldn't decode error response: EOF 2022/01/12 05:13:16 DEBUG : Creating backend with remote "TestB2:rclone-test-vosukix1xoyubug3josesif8/backup" 2022/01/12 05:13:16 DEBUG : Couldn't decode error response: EOF 2022/01/12 05:13:16 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/01/12 05:13:16 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:13:16 DEBUG : two: Unchanged skipping 2022/01/12 05:13:16 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8 path dst: Waiting for checks to finish 2022/01/12 05:13:16 DEBUG : Couldn't decode error response: EOF 2022/01/12 05:13:20 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/01/12 05:13:20 INFO : one: Copied (server-side copy) to: one-2019-01-01 2022/01/12 05:13:23 INFO : one: Deleted 2022/01/12 05:13:23 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8 path dst: Waiting for transfers to finish 2022/01/12 05:13:24 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2022/01/12 05:13:24 INFO : one: Copied (new) 2022/01/12 05:13:24 DEBUG : Waiting for deletions to finish 2022/01/12 05:13:25 DEBUG : Couldn't decode error response: EOF 2022/01/12 05:13:27 DEBUG : three.txt: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2022/01/12 05:13:27 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2022/01/12 05:13:28 INFO : three.txt: Deleted 2022/01/12 05:13:28 INFO : three.txt: Moved into backup dir 2022/01/12 05:13:29 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8 path dst: Waiting for checks to finish 2022/01/12 05:13:29 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/01/12 05:13:29 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:13:29 DEBUG : two: Unchanged skipping 2022/01/12 05:13:30 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2022/01/12 05:13:30 INFO : one: Copied (server-side copy) to: one-2019-01-01 2022/01/12 05:13:31 INFO : one: Deleted 2022/01/12 05:13:31 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8 path dst: Waiting for transfers to finish 2022/01/12 05:13:32 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2022/01/12 05:13:32 INFO : one: Copied (new) 2022/01/12 05:13:32 DEBUG : Waiting for deletions to finish 2022/01/12 05:13:35 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2022/01/12 05:13:35 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2022/01/12 05:13:36 INFO : three.txt: Deleted 2022/01/12 05:13:36 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (31.25s) === RUN TestSyncBackupDirSuffixOnly run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:13:45 DEBUG : Creating backend with remote "TestB2:rclone-test-vosukix1xoyubug3josesif8/dst" 2022/01/12 05:13:48 DEBUG : Couldn't decode error response: EOF 2022/01/12 05:13:48 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8 path dst: Waiting for checks to finish 2022/01/12 05:13:48 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/01/12 05:13:48 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:13:48 DEBUG : two: Unchanged skipping 2022/01/12 05:13:49 DEBUG : Couldn't decode error response: EOF 2022/01/12 05:13:49 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/01/12 05:13:49 INFO : one: Copied (server-side copy) to: one.bak 2022/01/12 05:13:50 INFO : one: Deleted 2022/01/12 05:13:50 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8 path dst: Waiting for transfers to finish 2022/01/12 05:13:51 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2022/01/12 05:13:51 INFO : one: Copied (new) 2022/01/12 05:13:51 DEBUG : Waiting for deletions to finish 2022/01/12 05:13:52 DEBUG : Couldn't decode error response: EOF 2022/01/12 05:13:52 DEBUG : three.txt: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2022/01/12 05:13:52 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/01/12 05:13:53 INFO : three.txt: Deleted 2022/01/12 05:13:53 INFO : three.txt: Moved into backup dir 2022/01/12 05:13:54 DEBUG : one.bak: Excluded 2022/01/12 05:13:54 DEBUG : three.txt.bak: Excluded 2022/01/12 05:13:54 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/01/12 05:13:54 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8 path dst: Waiting for checks to finish 2022/01/12 05:13:54 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:13:54 DEBUG : two: Unchanged skipping 2022/01/12 05:13:56 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2022/01/12 05:13:56 INFO : one: Copied (server-side copy) to: one.bak 2022/01/12 05:13:57 INFO : one: Deleted 2022/01/12 05:13:57 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8 path dst: Waiting for transfers to finish 2022/01/12 05:13:58 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2022/01/12 05:13:58 INFO : one: Copied (new) 2022/01/12 05:13:58 DEBUG : Waiting for deletions to finish 2022/01/12 05:13:59 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2022/01/12 05:13:59 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/01/12 05:14:00 INFO : three.txt: Deleted 2022/01/12 05:14:00 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirSuffixOnly (21.49s) === RUN TestSyncSuffix run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:14:07 DEBUG : Creating backend with remote "TestB2:rclone-test-vosukix1xoyubug3josesif8/dst" 2022/01/12 05:14:08 DEBUG : Couldn't decode error response: EOF 2022/01/12 05:14:09 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/01/12 05:14:09 DEBUG : Couldn't decode error response: EOF 2022/01/12 05:14:11 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/01/12 05:14:11 INFO : one: Copied (server-side copy) to: one.bak 2022/01/12 05:14:12 INFO : one: Deleted 2022/01/12 05:14:14 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2022/01/12 05:14:14 INFO : one: Copied (new) 2022/01/12 05:14:14 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:14:14 DEBUG : two: Unchanged skipping 2022/01/12 05:14:15 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2022/01/12 05:14:15 DEBUG : Couldn't decode error response: EOF 2022/01/12 05:14:16 DEBUG : three.txt: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2022/01/12 05:14:16 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/01/12 05:14:17 INFO : three.txt: Deleted 2022/01/12 05:14:17 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2022/01/12 05:14:17 INFO : three.txt: Copied (new) 2022/01/12 05:14:18 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/01/12 05:14:20 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2022/01/12 05:14:20 INFO : one: Copied (server-side copy) to: one.bak 2022/01/12 05:14:21 INFO : one: Deleted 2022/01/12 05:14:25 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2022/01/12 05:14:25 INFO : one: Copied (new) 2022/01/12 05:14:25 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:14:25 DEBUG : two: Unchanged skipping 2022/01/12 05:14:26 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2022/01/12 05:14:27 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2022/01/12 05:14:27 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/01/12 05:14:28 INFO : three.txt: Deleted 2022/01/12 05:14:31 DEBUG : three.txt: sha1 = 476e1707cbd7f0497a7dc4d44f0b07b8566ffc30 OK 2022/01/12 05:14:31 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (36.74s) === RUN TestSyncSuffixKeepExtension run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:14:44 DEBUG : Creating backend with remote "TestB2:rclone-test-vosukix1xoyubug3josesif8/dst" 2022/01/12 05:14:45 DEBUG : Couldn't decode error response: EOF 2022/01/12 05:14:45 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/01/12 05:14:45 DEBUG : Couldn't decode error response: EOF 2022/01/12 05:14:47 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/01/12 05:14:47 INFO : one: Copied (server-side copy) to: one-2019-01-01 2022/01/12 05:14:47 INFO : one: Deleted 2022/01/12 05:14:49 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2022/01/12 05:14:49 INFO : one: Copied (new) 2022/01/12 05:14:49 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:14:49 DEBUG : two: Unchanged skipping 2022/01/12 05:14:49 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2022/01/12 05:14:49 DEBUG : Couldn't decode error response: EOF 2022/01/12 05:14:50 DEBUG : three.txt: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2022/01/12 05:14:50 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2022/01/12 05:14:50 INFO : three.txt: Deleted 2022/01/12 05:14:51 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2022/01/12 05:14:51 INFO : three.txt: Copied (new) 2022/01/12 05:14:52 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/01/12 05:14:52 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2022/01/12 05:14:52 INFO : one: Copied (server-side copy) to: one-2019-01-01 2022/01/12 05:14:53 INFO : one: Deleted 2022/01/12 05:14:54 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2022/01/12 05:14:54 INFO : one: Copied (new) 2022/01/12 05:14:54 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:14:54 DEBUG : two: Unchanged skipping 2022/01/12 05:14:54 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2022/01/12 05:14:57 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2022/01/12 05:14:57 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2022/01/12 05:14:57 INFO : three.txt: Deleted 2022/01/12 05:14:59 DEBUG : three.txt: sha1 = 476e1707cbd7f0497a7dc4d44f0b07b8566ffc30 OK 2022/01/12 05:14:59 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (24.47s) === RUN TestSyncUTFNorm run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:15:07 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2022/01/12 05:15:07 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:15:07 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish 2022/01/12 05:15:08 DEBUG : Testêé: sha1 = a54d88e06612d820bc3be72877c74f257b561b19 OK 2022/01/12 05:15:08 INFO : Testêé: Copied (replaced existing) to: Testêé 2022/01/12 05:15:08 DEBUG : Waiting for deletions to finish --- PASS: TestSyncUTFNorm (3.72s) === RUN TestSyncImmutable run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:15:09 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:15:09 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish 2022/01/12 05:15:10 DEBUG : existing: sha1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2022/01/12 05:15:10 INFO : existing: Copied (new) 2022/01/12 05:15:10 DEBUG : Waiting for deletions to finish 2022/01/12 05:15:10 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2022/01/12 05:15:10 ERROR : existing: Source and destination exist but do not match: immutable file modified 2022/01/12 05:15:10 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:15:10 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish 2022/01/12 05:15:10 ERROR : B2 bucket rclone-test-vosukix1xoyubug3josesif8: not deleting files as there were IO errors 2022/01/12 05:15:10 ERROR : B2 bucket rclone-test-vosukix1xoyubug3josesif8: not deleting directories as there were IO errors --- PASS: TestSyncImmutable (2.75s) === RUN TestSyncIgnoreCase run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:15:13 DEBUG : existing: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:15:13 DEBUG : existing: Unchanged skipping 2022/01/12 05:15:13 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:15:13 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish 2022/01/12 05:15:13 DEBUG : Waiting for deletions to finish 2022/01/12 05:15:13 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreCase (2.82s) === RUN TestMaxTransfer === RUN TestMaxTransfer/Hard run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" sync_test.go:1989: This test only runs on local === RUN TestMaxTransfer/Soft run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" sync_test.go:1989: This test only runs on local === RUN TestMaxTransfer/Cautious run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" sync_test.go:1989: This test only runs on local --- PASS: TestMaxTransfer (1.10s) --- SKIP: TestMaxTransfer/Hard (0.35s) --- SKIP: TestMaxTransfer/Soft (0.39s) --- SKIP: TestMaxTransfer/Cautious (0.36s) === RUN TestSyncConcurrentDelete run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:15:41 DEBUG : both0: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:15:41 DEBUG : both0: Unchanged skipping 2022/01/12 05:15:41 DEBUG : both10: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:15:41 DEBUG : both10: Unchanged skipping 2022/01/12 05:15:41 DEBUG : both11: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:15:41 DEBUG : both11: Unchanged skipping 2022/01/12 05:15:41 DEBUG : both13: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:15:41 DEBUG : both13: Unchanged skipping 2022/01/12 05:15:41 DEBUG : both12: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:15:41 DEBUG : both15: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:15:41 DEBUG : both15: Unchanged skipping 2022/01/12 05:15:41 DEBUG : both12: Unchanged skipping 2022/01/12 05:15:41 DEBUG : both16: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:15:41 DEBUG : both16: Unchanged skipping 2022/01/12 05:15:41 DEBUG : both17: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:15:41 DEBUG : both17: Unchanged skipping 2022/01/12 05:15:41 DEBUG : both19: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:15:41 DEBUG : both19: Unchanged skipping 2022/01/12 05:15:41 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:15:41 DEBUG : both2: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:15:41 DEBUG : both14: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:15:41 DEBUG : both14: Unchanged skipping 2022/01/12 05:15:41 DEBUG : both3: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:15:41 DEBUG : both3: Unchanged skipping 2022/01/12 05:15:41 DEBUG : both4: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:15:41 DEBUG : both4: Unchanged skipping 2022/01/12 05:15:41 DEBUG : both5: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:15:41 DEBUG : both5: Unchanged skipping 2022/01/12 05:15:41 DEBUG : both6: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:15:41 DEBUG : both6: Unchanged skipping 2022/01/12 05:15:41 DEBUG : both7: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:15:41 DEBUG : both7: Unchanged skipping 2022/01/12 05:15:41 DEBUG : both8: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:15:41 DEBUG : both8: Unchanged skipping 2022/01/12 05:15:41 DEBUG : both9: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:15:41 DEBUG : both9: Unchanged skipping 2022/01/12 05:15:41 DEBUG : both18: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:15:41 DEBUG : both18: Unchanged skipping 2022/01/12 05:15:41 DEBUG : both1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:15:41 DEBUG : both1: Unchanged skipping 2022/01/12 05:15:41 DEBUG : both2: Unchanged skipping 2022/01/12 05:15:41 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish 2022/01/12 05:15:41 DEBUG : Waiting for deletions to finish 2022/01/12 05:15:42 INFO : only1: Deleted 2022/01/12 05:15:42 INFO : only16: Deleted 2022/01/12 05:15:42 INFO : only3: Deleted 2022/01/12 05:15:42 INFO : only11: Deleted 2022/01/12 05:15:42 INFO : only9: Deleted 2022/01/12 05:15:43 INFO : only5: Deleted 2022/01/12 05:15:43 INFO : only12: Deleted 2022/01/12 05:15:43 INFO : only4: Deleted 2022/01/12 05:15:43 INFO : only7: Deleted 2022/01/12 05:15:43 INFO : only2: Deleted 2022/01/12 05:15:43 INFO : only13: Deleted 2022/01/12 05:15:44 INFO : only19: Deleted 2022/01/12 05:15:44 INFO : only0: Deleted 2022/01/12 05:15:44 INFO : only15: Deleted 2022/01/12 05:15:45 INFO : only17: Deleted 2022/01/12 05:15:45 INFO : only8: Deleted 2022/01/12 05:15:46 INFO : only18: Deleted 2022/01/12 05:15:46 INFO : only6: Deleted 2022/01/12 05:15:47 INFO : only10: Deleted 2022/01/12 05:15:47 INFO : only14: Deleted 2022/01/12 05:15:47 INFO : There was nothing to transfer --- PASS: TestSyncConcurrentDelete (55.62s) === RUN TestSyncConcurrentTruncate run.go:181: Remote "B2 bucket rclone-test-vosukix1xoyubug3josesif8", Local "Local file system at /tmp/rclone1348176003", Modify Window "1ms" 2022/01/12 05:16:31 DEBUG : both0: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:16:31 DEBUG : both1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:16:31 DEBUG : both1: Unchanged skipping 2022/01/12 05:16:31 DEBUG : both11: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:16:31 DEBUG : both11: Unchanged skipping 2022/01/12 05:16:31 DEBUG : both13: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:16:31 DEBUG : both13: Unchanged skipping 2022/01/12 05:16:31 DEBUG : both14: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:16:31 DEBUG : both14: Unchanged skipping 2022/01/12 05:16:31 DEBUG : both15: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:16:31 DEBUG : both15: Unchanged skipping 2022/01/12 05:16:31 DEBUG : both16: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:16:31 DEBUG : both16: Unchanged skipping 2022/01/12 05:16:31 DEBUG : both17: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:16:31 DEBUG : both17: Unchanged skipping 2022/01/12 05:16:31 DEBUG : both18: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:16:31 DEBUG : both18: Unchanged skipping 2022/01/12 05:16:31 DEBUG : both19: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:16:31 DEBUG : both19: Unchanged skipping 2022/01/12 05:16:31 DEBUG : both2: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:16:31 DEBUG : both2: Unchanged skipping 2022/01/12 05:16:31 DEBUG : both3: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:16:31 DEBUG : both3: Unchanged skipping 2022/01/12 05:16:31 DEBUG : both4: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:16:31 DEBUG : both4: Unchanged skipping 2022/01/12 05:16:31 DEBUG : both5: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:16:31 DEBUG : both5: Unchanged skipping 2022/01/12 05:16:31 DEBUG : both6: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:16:31 DEBUG : both6: Unchanged skipping 2022/01/12 05:16:31 DEBUG : both7: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:16:31 DEBUG : both7: Unchanged skipping 2022/01/12 05:16:31 DEBUG : both8: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:16:31 DEBUG : both8: Unchanged skipping 2022/01/12 05:16:31 DEBUG : both9: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:16:31 DEBUG : both9: Unchanged skipping 2022/01/12 05:16:31 DEBUG : only0: Sizes differ (src 0 vs dst 6) 2022/01/12 05:16:31 DEBUG : only1: Sizes differ (src 0 vs dst 6) 2022/01/12 05:16:31 DEBUG : only10: Sizes differ (src 0 vs dst 6) 2022/01/12 05:16:31 DEBUG : only11: Sizes differ (src 0 vs dst 6) 2022/01/12 05:16:31 DEBUG : only12: Sizes differ (src 0 vs dst 6) 2022/01/12 05:16:31 DEBUG : both0: Unchanged skipping 2022/01/12 05:16:31 DEBUG : only14: Sizes differ (src 0 vs dst 6) 2022/01/12 05:16:31 DEBUG : only15: Sizes differ (src 0 vs dst 6) 2022/01/12 05:16:31 DEBUG : only16: Sizes differ (src 0 vs dst 6) 2022/01/12 05:16:31 DEBUG : only17: Sizes differ (src 0 vs dst 6) 2022/01/12 05:16:31 DEBUG : only18: Sizes differ (src 0 vs dst 6) 2022/01/12 05:16:31 DEBUG : only19: Sizes differ (src 0 vs dst 6) 2022/01/12 05:16:31 DEBUG : only2: Sizes differ (src 0 vs dst 6) 2022/01/12 05:16:31 DEBUG : only3: Sizes differ (src 0 vs dst 6) 2022/01/12 05:16:31 DEBUG : only4: Sizes differ (src 0 vs dst 6) 2022/01/12 05:16:31 DEBUG : only5: Sizes differ (src 0 vs dst 6) 2022/01/12 05:16:31 DEBUG : only6: Sizes differ (src 0 vs dst 6) 2022/01/12 05:16:31 DEBUG : only7: Sizes differ (src 0 vs dst 6) 2022/01/12 05:16:31 DEBUG : only8: Sizes differ (src 0 vs dst 6) 2022/01/12 05:16:31 DEBUG : only9: Sizes differ (src 0 vs dst 6) 2022/01/12 05:16:31 DEBUG : both12: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:16:31 DEBUG : both12: Unchanged skipping 2022/01/12 05:16:31 DEBUG : both10: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/12 05:16:31 DEBUG : both10: Unchanged skipping 2022/01/12 05:16:31 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for checks to finish 2022/01/12 05:16:31 DEBUG : only13: Sizes differ (src 0 vs dst 6) 2022/01/12 05:16:31 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Waiting for transfers to finish 2022/01/12 05:16:31 DEBUG : only1: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/01/12 05:16:31 INFO : only1: Copied (replaced existing) 2022/01/12 05:16:31 DEBUG : only10: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/01/12 05:16:31 INFO : only10: Copied (replaced existing) 2022/01/12 05:16:32 DEBUG : only12: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/01/12 05:16:32 INFO : only12: Copied (replaced existing) 2022/01/12 05:16:32 DEBUG : only14: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/01/12 05:16:32 INFO : only14: Copied (replaced existing) 2022/01/12 05:16:32 DEBUG : only0: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/01/12 05:16:32 INFO : only0: Copied (replaced existing) 2022/01/12 05:16:32 DEBUG : only11: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/01/12 05:16:32 INFO : only11: Copied (replaced existing) 2022/01/12 05:16:32 DEBUG : only15: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/01/12 05:16:32 INFO : only15: Copied (replaced existing) 2022/01/12 05:16:33 DEBUG : only16: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/01/12 05:16:33 INFO : only16: Copied (replaced existing) 2022/01/12 05:16:33 DEBUG : only17: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/01/12 05:16:33 INFO : only17: Copied (replaced existing) 2022/01/12 05:16:33 DEBUG : only19: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/01/12 05:16:33 INFO : only19: Copied (replaced existing) 2022/01/12 05:16:33 DEBUG : only4: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/01/12 05:16:33 INFO : only4: Copied (replaced existing) 2022/01/12 05:16:33 DEBUG : only2: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/01/12 05:16:33 INFO : only2: Copied (replaced existing) 2022/01/12 05:16:33 DEBUG : only3: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/01/12 05:16:33 INFO : only3: Copied (replaced existing) 2022/01/12 05:16:33 DEBUG : only5: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/01/12 05:16:33 INFO : only5: Copied (replaced existing) 2022/01/12 05:16:33 DEBUG : only18: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/01/12 05:16:33 INFO : only18: Copied (replaced existing) 2022/01/12 05:16:34 DEBUG : only8: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/01/12 05:16:34 INFO : only8: Copied (replaced existing) 2022/01/12 05:16:34 DEBUG : only6: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/01/12 05:16:34 INFO : only6: Copied (replaced existing) 2022/01/12 05:16:34 DEBUG : only7: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/01/12 05:16:34 INFO : only7: Copied (replaced existing) 2022/01/12 05:16:34 DEBUG : only13: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/01/12 05:16:34 INFO : only13: Copied (replaced existing) 2022/01/12 05:16:34 DEBUG : only9: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2022/01/12 05:16:34 INFO : only9: Copied (replaced existing) 2022/01/12 05:16:34 DEBUG : Waiting for deletions to finish --- PASS: TestSyncConcurrentTruncate (69.91s) PASS 2022/01/12 05:17:21 DEBUG : B2 bucket rclone-test-vosukix1xoyubug3josesif8: Purge remote 2022/01/12 05:17:22 DEBUG : BackupDir/one: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1054982d150fb6ee_d20220112_m051211_c001_v0001117_t0042") 2022/01/12 05:17:22 DEBUG : BackupDir/one: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1002d75f74469fdc_d20220112_m051144_c001_v0001117_t0052") 2022/01/12 05:17:22 DEBUG : CompareDest/one: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f104966f8bdfbcd33_d20220112_m051103_c001_v0001042_t0020") 2022/01/12 05:17:22 DEBUG : CompareDest/one: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae95be0_d20220112_m051049_c001_v0001161_t0038") 2022/01/12 05:17:22 DEBUG : CompareDest/two: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f109f4a2f6857c312_d20220112_m051104_c001_v0001165_t0014") 2022/01/12 05:17:22 DEBUG : CompareDest/two: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa8736c9_d20220112_m051051_c001_v0001131_t0058") 2022/01/12 05:17:22 DEBUG : CopyDest/one: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f101e67130a99ae0c_d20220112_m051211_c001_v0001097_t0017") 2022/01/12 05:17:22 DEBUG : CopyDest/one: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa873777_d20220112_m051129_c001_v0001131_t0016") 2022/01/12 05:17:22 DEBUG : CopyDest/three: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f112bfcaced8e8646_d20220112_m051213_c001_v0001041_t0028") 2022/01/12 05:17:22 DEBUG : CopyDest/three: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa8737e9_d20220112_m051158_c001_v0001131_t0023") 2022/01/12 05:17:22 DEBUG : CopyDest/two: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f11715959287a9c94_d20220112_m051216_c001_v0001090_t0032") 2022/01/12 05:17:22 DEBUG : CopyDest/two: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae95e35_d20220112_m051147_c001_v0001161_t0024") 2022/01/12 05:17:23 DEBUG : EXISTING: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f11012dfc3c3845ae_d20220112_m051513_c001_v0001105_t0049") 2022/01/12 05:17:23 DEBUG : EXISTING: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa873d70_d20220112_m051512_c001_v0001131_t0011") 2022/01/12 05:17:23 DEBUG : Testêé: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1117bd029299d1bb_d20220112_m051509_c001_v0001136_t0008") 2022/01/12 05:17:23 DEBUG : Testêé: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa873d50_d20220112_m051508_c001_v0001131_t0031") 2022/01/12 05:17:23 DEBUG : Testêé: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae96507_d20220112_m051506_c001_v0001161_t0032") 2022/01/12 05:17:23 DEBUG : a/potato2: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1122cd0bb61890f8_d20220112_m050240_c001_v0001097_t0050") 2022/01/12 05:17:23 DEBUG : a/potato2: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f106c1a18973f58b9_d20220112_m050235_c001_v0001093_t0014") 2022/01/12 05:17:23 DEBUG : a/potato2: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f10673c09c8ea68df_d20220112_m050227_c001_v0001136_t0029") 2022/01/12 05:17:23 DEBUG : a/potato2: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f106c1a18973f587c_d20220112_m050218_c001_v0001093_t0032") 2022/01/12 05:17:23 DEBUG : a/potato2: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f118296fa5517018b_d20220112_m050123_c001_v0001130_t0012") 2022/01/12 05:17:23 DEBUG : a/potato2: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f106c1a18973f578b_d20220112_m050120_c001_v0001093_t0023") 2022/01/12 05:17:23 DEBUG : b/potato: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f105a0a22fadf19a8_d20220112_m050245_c001_v0001136_t0044") 2022/01/12 05:17:23 DEBUG : b/potato: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f106c1a18973f58a9_d20220112_m050230_c001_v0001093_t0048") 2022/01/12 05:17:23 DEBUG : b/potato: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1158e7a8cb827fb4_d20220112_m050222_c001_v0001165_t0027") 2022/01/12 05:17:23 DEBUG : b/potato: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f106c1a18973f5871_d20220112_m050216_c001_v0001093_t0030") 2022/01/12 05:17:23 DEBUG : b/potato: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f115a47e7b6db0aac_d20220112_m050121_c001_v0001098_t0031") 2022/01/12 05:17:23 DEBUG : b/potato: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f106c1a18973f577d_d20220112_m050118_c001_v0001093_t0053") 2022/01/12 05:17:24 DEBUG : backup/one: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f112ce198d13263e6_d20220112_m051243_c001_v0001146_t0055") 2022/01/12 05:17:24 DEBUG : backup/one: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f114071b6f6266bd7_d20220112_m051233_c001_v0001091_t0052") 2022/01/12 05:17:24 DEBUG : backup/one: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f110bbea8a9d5e919_d20220112_m051226_c001_v0001097_t0008") 2022/01/12 05:17:24 DEBUG : backup/one-2019-01-01: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f10627b2d93cd02d4_d20220112_m051336_c001_v0001117_t0017") 2022/01/12 05:17:24 DEBUG : backup/one-2019-01-01: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f100d42226e33b997_d20220112_m051330_c001_v0001031_t0040") 2022/01/12 05:17:24 DEBUG : backup/one-2019-01-01: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111b55150edc8bf6_d20220112_m051319_c001_v0001165_t0017") 2022/01/12 05:17:24 DEBUG : backup/one.bak: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f115ac3eaba12bb11_d20220112_m051308_c001_v0001037_t0039") 2022/01/12 05:17:24 DEBUG : backup/one.bak: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117c2a3cb4fd0173_d20220112_m051303_c001_v0001157_t0055") 2022/01/12 05:17:24 DEBUG : backup/one.bak: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f108a7f8ad0bec0f0_d20220112_m051253_c001_v0001128_t0014") 2022/01/12 05:17:24 DEBUG : backup/three-2019-01-01.txt: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f101d48d12a175221_d20220112_m051339_c001_v0001105_t0054") 2022/01/12 05:17:24 DEBUG : backup/three-2019-01-01.txt: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1122cd0bb618ab94_d20220112_m051334_c001_v0001097_t0014") 2022/01/12 05:17:24 DEBUG : backup/three-2019-01-01.txt: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1103e6f7bef3dfe3_d20220112_m051325_c001_v0001044_t0029") 2022/01/12 05:17:24 DEBUG : backup/three.txt: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f10404e8807798936_d20220112_m051244_c001_v0001097_t0059") 2022/01/12 05:17:24 DEBUG : backup/three.txt: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f104b697152eb9c47_d20220112_m051238_c001_v0001145_t0002") 2022/01/12 05:17:24 DEBUG : backup/three.txt: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f108cfc4a44d65478_d20220112_m051229_c001_v0001120_t0050") 2022/01/12 05:17:24 DEBUG : backup/three.txt.bak: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f101d48d12a174db1_d20220112_m051309_c001_v0001105_t0032") 2022/01/12 05:17:25 DEBUG : backup/three.txt.bak: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1002d75f7446a4b4_d20220112_m051306_c001_v0001117_t0046") 2022/01/12 05:17:25 DEBUG : backup/three.txt.bak: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f114e60b30e431345_d20220112_m051300_c001_v0001117_t0047") 2022/01/12 05:17:25 DEBUG : both0: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f114e60b30e4320a0_d20220112_m051635_c001_v0001117_t0044") 2022/01/12 05:17:25 DEBUG : both0: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae967f9_d20220112_m051612_c001_v0001161_t0042") 2022/01/12 05:17:25 DEBUG : both0: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1193b538eb10bbbe_d20220112_m051548_c001_v0001006_t0002") 2022/01/12 05:17:25 DEBUG : both0: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae9656d_d20220112_m051516_c001_v0001161_t0054") 2022/01/12 05:17:25 DEBUG : both1: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f113d48f5052b2ac4_d20220112_m051635_c001_v0001161_t0050") 2022/01/12 05:17:25 DEBUG : both1: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae96804_d20220112_m051613_c001_v0001161_t0028") 2022/01/12 05:17:25 DEBUG : both1: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f10200b2bb8890978_d20220112_m051548_c001_v0001161_t0040") 2022/01/12 05:17:25 DEBUG : both1: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae96572_d20220112_m051518_c001_v0001161_t0028") 2022/01/12 05:17:25 DEBUG : both10: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f101db73a8bbb3450_d20220112_m051636_c001_v0001146_t0021") 2022/01/12 05:17:25 DEBUG : both10: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae9686d_d20220112_m051622_c001_v0001161_t0013") 2022/01/12 05:17:25 DEBUG : both10: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1186e43dff5f5602_d20220112_m051551_c001_v0001105_t0021") 2022/01/12 05:17:25 DEBUG : both10: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae965e6_d20220112_m051527_c001_v0001161_t0040") 2022/01/12 05:17:25 DEBUG : both11: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1129b03e7aa784e0_d20220112_m051637_c001_v0001105_t0058") 2022/01/12 05:17:26 DEBUG : both11: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae9687c_d20220112_m051623_c001_v0001161_t0023") 2022/01/12 05:17:26 DEBUG : both11: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f10404e880779903f_d20220112_m051553_c001_v0001097_t0045") 2022/01/12 05:17:26 DEBUG : both11: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae965fb_d20220112_m051528_c001_v0001161_t0057") 2022/01/12 05:17:26 DEBUG : both12: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f11257374836e4be9_d20220112_m051637_c001_v0001153_t0022") 2022/01/12 05:17:26 DEBUG : both12: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae96891_d20220112_m051624_c001_v0001161_t0041") 2022/01/12 05:17:26 DEBUG : both12: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f109c125be8287d9b_d20220112_m051555_c001_v0001109_t0048") 2022/01/12 05:17:26 DEBUG : both12: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae96608_d20220112_m051529_c001_v0001161_t0036") 2022/01/12 05:17:26 DEBUG : both13: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1137019be062f08e_d20220112_m051637_c001_v0001097_t0030") 2022/01/12 05:17:26 DEBUG : both13: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae968a1_d20220112_m051625_c001_v0001161_t0002") 2022/01/12 05:17:26 DEBUG : both13: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f114072d592fafd81_d20220112_m051556_c001_v0001131_t0014") 2022/01/12 05:17:26 DEBUG : both13: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae96618_d20220112_m051530_c001_v0001161_t0021") 2022/01/12 05:17:26 DEBUG : both14: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1195a57531ea5865_d20220112_m051638_c001_v0001098_t0053") 2022/01/12 05:17:26 DEBUG : both14: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae968a9_d20220112_m051626_c001_v0001161_t0044") 2022/01/12 05:17:26 DEBUG : both14: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f11090b2f6d83e234_d20220112_m051556_c001_v0001109_t0034") 2022/01/12 05:17:26 DEBUG : both14: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae96625_d20220112_m051530_c001_v0001161_t0014") 2022/01/12 05:17:26 DEBUG : both15: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f104966f8bdfbcfab_d20220112_m051640_c001_v0001042_t0034") 2022/01/12 05:17:27 DEBUG : both15: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae968b9_d20220112_m051627_c001_v0001161_t0025") 2022/01/12 05:17:27 DEBUG : both15: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f115bf35c4bac63f7_d20220112_m051558_c001_v0001161_t0047") 2022/01/12 05:17:27 DEBUG : both15: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae9662d_d20220112_m051531_c001_v0001161_t0015") 2022/01/12 05:17:27 DEBUG : both16: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f103c1999387f77fa_d20220112_m051641_c001_v0001105_t0058") 2022/01/12 05:17:27 DEBUG : both16: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae968c1_d20220112_m051627_c001_v0001161_t0018") 2022/01/12 05:17:27 DEBUG : both16: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f107a009d39ef0848_d20220112_m051558_c001_v0001105_t0055") 2022/01/12 05:17:27 DEBUG : both16: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae9663e_d20220112_m051532_c001_v0001161_t0039") 2022/01/12 05:17:27 DEBUG : both17: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae96970_d20220112_m051642_c001_v0001161_t0053") 2022/01/12 05:17:27 DEBUG : both17: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae968cd_d20220112_m051628_c001_v0001161_t0020") 2022/01/12 05:17:27 DEBUG : both17: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f116809e7a3c9f1a6_d20220112_m051600_c001_v0001131_t0052") 2022/01/12 05:17:27 DEBUG : both17: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae9664c_d20220112_m051533_c001_v0001161_t0016") 2022/01/12 05:17:27 DEBUG : both18: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f10644aebc52b2721_d20220112_m051642_c001_v0001120_t0015") 2022/01/12 05:17:27 DEBUG : both18: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae968d7_d20220112_m051628_c001_v0001161_t0001") 2022/01/12 05:17:27 DEBUG : both18: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f116e1aa155da9e3f_d20220112_m051602_c001_v0001090_t0007") 2022/01/12 05:17:27 DEBUG : both18: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae96655_d20220112_m051534_c001_v0001161_t0050") 2022/01/12 05:17:27 DEBUG : both19: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f10875e538a9cce54_d20220112_m051643_c001_v0001105_t0021") 2022/01/12 05:17:27 DEBUG : both19: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae968e0_d20220112_m051629_c001_v0001161_t0047") 2022/01/12 05:17:27 DEBUG : both19: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1004f92e0197d6e9_d20220112_m051603_c001_v0001154_t0017") 2022/01/12 05:17:28 DEBUG : both19: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae96695_d20220112_m051539_c001_v0001161_t0045") 2022/01/12 05:17:28 DEBUG : both2: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1122cd0bb618b2f2_d20220112_m051644_c001_v0001097_t0052") 2022/01/12 05:17:28 DEBUG : both2: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae9680c_d20220112_m051614_c001_v0001161_t0054") 2022/01/12 05:17:28 DEBUG : both2: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f109abc2335226b1c_d20220112_m051604_c001_v0001036_t0001") 2022/01/12 05:17:28 DEBUG : both2: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae9657e_d20220112_m051518_c001_v0001161_t0035") 2022/01/12 05:17:28 DEBUG : both3: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f10081dd545b0c977_d20220112_m051644_c001_v0001109_t0039") 2022/01/12 05:17:28 DEBUG : both3: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae96813_d20220112_m051615_c001_v0001161_t0057") 2022/01/12 05:17:28 DEBUG : both3: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f118352c284133306_d20220112_m051605_c001_v0001161_t0009") 2022/01/12 05:17:28 DEBUG : both3: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae9658d_d20220112_m051519_c001_v0001161_t0048") 2022/01/12 05:17:28 DEBUG : both4: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f104576c6f2dcb508_d20220112_m051646_c001_v0001117_t0032") 2022/01/12 05:17:28 DEBUG : both4: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae9681b_d20220112_m051616_c001_v0001161_t0006") 2022/01/12 05:17:28 DEBUG : both4: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f100faef61cd5f451_d20220112_m051606_c001_v0001137_t0049") 2022/01/12 05:17:28 DEBUG : both4: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae96596_d20220112_m051520_c001_v0001161_t0053") 2022/01/12 05:17:28 DEBUG : both5: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f115a47e7b6db25e7_d20220112_m051648_c001_v0001098_t0018") 2022/01/12 05:17:28 DEBUG : both5: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae96828_d20220112_m051617_c001_v0001161_t0015") 2022/01/12 05:17:28 DEBUG : both5: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f114887de0b588438_d20220112_m051606_c001_v0001008_t0002") 2022/01/12 05:17:29 DEBUG : both5: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae965a0_d20220112_m051521_c001_v0001161_t0042") 2022/01/12 05:17:29 DEBUG : both6: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f11835a5b25e9badb_d20220112_m051648_c001_v0001117_t0042") 2022/01/12 05:17:29 DEBUG : both6: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae96836_d20220112_m051618_c001_v0001161_t0007") 2022/01/12 05:17:29 DEBUG : both6: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1023be88de3be2af_d20220112_m051607_c001_v0001105_t0046") 2022/01/12 05:17:29 DEBUG : both6: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae965a8_d20220112_m051522_c001_v0001161_t0055") 2022/01/12 05:17:29 DEBUG : both7: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1047a0e2391081b6_d20220112_m051651_c001_v0001029_t0020") 2022/01/12 05:17:29 DEBUG : both7: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae96843_d20220112_m051619_c001_v0001161_t0053") 2022/01/12 05:17:29 DEBUG : both7: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1054e5d6faaf14bd_d20220112_m051607_c001_v0001005_t0026") 2022/01/12 05:17:29 DEBUG : both7: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae965ad_d20220112_m051523_c001_v0001161_t0040") 2022/01/12 05:17:29 DEBUG : both8: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1023be88de3be85d_d20220112_m051652_c001_v0001105_t0038") 2022/01/12 05:17:29 DEBUG : both8: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae9684c_d20220112_m051620_c001_v0001161_t0025") 2022/01/12 05:17:29 DEBUG : both8: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1067fb5d2342cc9a_d20220112_m051607_c001_v0001105_t0019") 2022/01/12 05:17:29 DEBUG : both8: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae965b9_d20220112_m051523_c001_v0001161_t0010") 2022/01/12 05:17:29 DEBUG : both9: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1009088e4cf7442d_d20220112_m051654_c001_v0001011_t0027") 2022/01/12 05:17:30 DEBUG : both9: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae96865_d20220112_m051621_c001_v0001161_t0055") 2022/01/12 05:17:30 DEBUG : both9: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f10271f1a20fba1af_d20220112_m051609_c001_v0001032_t0041") 2022/01/12 05:17:30 DEBUG : both9: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae965c3_d20220112_m051524_c001_v0001161_t0010") 2022/01/12 05:17:30 DEBUG : c/non empty space: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f102fd32a03488565_d20220112_m050249_c001_v0001134_t0048") 2022/01/12 05:17:30 DEBUG : c/non empty space: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f106c1a18973f58af_d20220112_m050232_c001_v0001093_t0012") 2022/01/12 05:17:30 DEBUG : c/non empty space: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f11860e867a8c8d52_d20220112_m050229_c001_v0001098_t0016") 2022/01/12 05:17:30 DEBUG : c/non empty space: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f106c1a18973f5875_d20220112_m050217_c001_v0001093_t0006") 2022/01/12 05:17:30 DEBUG : c/non empty space: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f114eb92a222d4c8a_d20220112_m050124_c001_v0001033_t0010") 2022/01/12 05:17:30 DEBUG : c/non empty space: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f106c1a18973f5786_d20220112_m050119_c001_v0001093_t0032") 2022/01/12 05:17:30 DEBUG : check sum: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f102bb1c81e3bda13_d20220112_m050101_c001_v0001165_t0021") 2022/01/12 05:17:30 DEBUG : check sum: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f106c1a18973f5740_d20220112_m050100_c001_v0001093_t0044") 2022/01/12 05:17:30 DEBUG : dest/3: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f11803d0dc21e08a4_d20220112_m051117_c001_v0001040_t0045") 2022/01/12 05:17:30 DEBUG : dest/3: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1006f70844753ac6_d20220112_m051115_c001_v0001040_t0056") 2022/01/12 05:17:30 DEBUG : dst/one: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1063c506e66e0fd9_d20220112_m051500_c001_v0001037_t0023") 2022/01/12 05:17:30 DEBUG : dst/one: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f119947a12e998840_d20220112_m051453_c001_v0001117_t0032") 2022/01/12 05:17:30 DEBUG : dst/one: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1186e43dff5f4d96_d20220112_m051452_c001_v0001105_t0015") 2022/01/12 05:17:31 DEBUG : dst/one: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f119947a12e998807_d20220112_m051448_c001_v0001117_t0033") 2022/01/12 05:17:31 DEBUG : dst/one: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f108c4605324bb54c_d20220112_m051447_c001_v0001032_t0051") 2022/01/12 05:17:31 DEBUG : dst/one: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa873c9d_d20220112_m051441_c001_v0001131_t0024") 2022/01/12 05:17:31 DEBUG : dst/one: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f11797d79e1324e8d_d20220112_m051432_c001_v0001105_t0046") 2022/01/12 05:17:31 DEBUG : dst/one: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f112a722199955a34_d20220112_m051422_c001_v0001090_t0054") 2022/01/12 05:17:31 DEBUG : dst/one: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1006f70844753be0_d20220112_m051420_c001_v0001040_t0001") 2022/01/12 05:17:31 DEBUG : dst/one: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f112a722199955a29_d20220112_m051413_c001_v0001090_t0032") 2022/01/12 05:17:31 DEBUG : dst/one: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1130195366a05e39_d20220112_m051411_c001_v0001113_t0041") 2022/01/12 05:17:31 DEBUG : dst/one: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae9630d_d20220112_m051405_c001_v0001161_t0024") 2022/01/12 05:17:31 DEBUG : dst/one: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f118fde16652ed7a3_d20220112_m051401_c001_v0001092_t0029") 2022/01/12 05:17:31 DEBUG : dst/one: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1149e9675807b83c_d20220112_m051358_c001_v0001098_t0035") 2022/01/12 05:17:31 DEBUG : dst/one: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117aca2479c5f34b_d20220112_m051356_c001_v0001145_t0005") 2022/01/12 05:17:31 DEBUG : dst/one: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1149e9675807b7c8_d20220112_m051351_c001_v0001098_t0025") 2022/01/12 05:17:31 DEBUG : dst/one: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1060def7cf6b056d_d20220112_m051350_c001_v0001145_t0009") 2022/01/12 05:17:31 DEBUG : dst/one: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae96238_d20220112_m051343_c001_v0001161_t0038") 2022/01/12 05:17:31 DEBUG : dst/one: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1151c5bef82fc837_d20220112_m051342_c001_v0001131_t0034") 2022/01/12 05:17:31 DEBUG : dst/one: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1137b0cfad0634f8_d20220112_m051332_c001_v0001109_t0058") 2022/01/12 05:17:32 DEBUG : dst/one: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f103a7adf63031297_d20220112_m051330_c001_v0001161_t0052") 2022/01/12 05:17:32 DEBUG : dst/one: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1137b0cfad063497_d20220112_m051324_c001_v0001109_t0047") 2022/01/12 05:17:32 DEBUG : dst/one: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f109044257eda189e_d20220112_m051322_c001_v0001001_t0035") 2022/01/12 05:17:32 DEBUG : dst/one: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae96154_d20220112_m051312_c001_v0001161_t0017") 2022/01/12 05:17:32 DEBUG : dst/one: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1068c74d0d8309c8_d20220112_m051310_c001_v0001157_t0051") 2022/01/12 05:17:32 DEBUG : dst/one: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1031e1d8e08a04a9_d20220112_m051305_c001_v0001117_t0009") 2022/01/12 05:17:32 DEBUG : dst/one: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f119947a12e99814c_d20220112_m051304_c001_v0001117_t0017") 2022/01/12 05:17:32 DEBUG : dst/one: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1031e1d8e08a03fb_d20220112_m051257_c001_v0001117_t0058") 2022/01/12 05:17:32 DEBUG : dst/one: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1056fafa89daaea7_d20220112_m051253_c001_v0001150_t0031") 2022/01/12 05:17:32 DEBUG : dst/one: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae96066_d20220112_m051247_c001_v0001161_t0049") 2022/01/12 05:17:32 DEBUG : dst/one: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1073e75c8e4cc56f_d20220112_m051245_c001_v0001044_t0012") 2022/01/12 05:17:32 DEBUG : dst/one: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1026c860e795b763_d20220112_m051235_c001_v0001153_t0000") 2022/01/12 05:17:32 DEBUG : dst/one: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f114590969a610435_d20220112_m051233_c001_v0001109_t0012") 2022/01/12 05:17:32 DEBUG : dst/one: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1026c860e795b757_d20220112_m051228_c001_v0001153_t0025") 2022/01/12 05:17:32 DEBUG : dst/one: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f11600f323f696341_d20220112_m051227_c001_v0001105_t0003") 2022/01/12 05:17:32 DEBUG : dst/one: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae95f78_d20220112_m051220_c001_v0001161_t0052") 2022/01/12 05:17:32 DEBUG : dst/one: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f114ee64f3c4adf88_d20220112_m051217_c001_v0001105_t0013") 2022/01/12 05:17:32 DEBUG : dst/one: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f116aa4b9c0871e99_d20220112_m051146_c001_v0001029_t0039") 2022/01/12 05:17:33 DEBUG : dst/one: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1002d75f74469fee_d20220112_m051145_c001_v0001117_t0024") 2022/01/12 05:17:33 DEBUG : dst/one: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae95d4b_d20220112_m051126_c001_v0001161_t0053") 2022/01/12 05:17:33 DEBUG : dst/one: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f104f8d3cbd98a887_d20220112_m051124_c001_v0001109_t0016") 2022/01/12 05:17:33 DEBUG : dst/one: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f104f8d3cbd98a867_d20220112_m051122_c001_v0001109_t0046") 2022/01/12 05:17:33 DEBUG : dst/one: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f118e819f45fda0f8_d20220112_m051104_c001_v0001137_t0045") 2022/01/12 05:17:33 DEBUG : dst/one: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa8736bb_d20220112_m051048_c001_v0001131_t0026") 2022/01/12 05:17:33 DEBUG : dst/one: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f11797d79e1322e56_d20220112_m051046_c001_v0001105_t0006") 2022/01/12 05:17:33 DEBUG : dst/one: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f11797d79e1322e18_d20220112_m051044_c001_v0001105_t0010") 2022/01/12 05:17:33 DEBUG : dst/one-2019-01-01: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f103deb101d379837_d20220112_m051501_c001_v0001165_t0016") 2022/01/12 05:17:33 DEBUG : dst/one-2019-01-01: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f116ff9b2d3d3ef3b_d20220112_m051452_c001_v0001097_t0027") 2022/01/12 05:17:33 DEBUG : dst/one-2019-01-01: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f119baa77ee874da0_d20220112_m051446_c001_v0001001_t0041") 2022/01/12 05:17:33 DEBUG : dst/one.bak: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1023be88de3bd8cb_d20220112_m051433_c001_v0001105_t0041") 2022/01/12 05:17:33 DEBUG : dst/one.bak: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f10875e538a9cbcb4_d20220112_m051418_c001_v0001105_t0041") 2022/01/12 05:17:33 DEBUG : dst/one.bak: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1165bf6e79efe833_d20220112_m051410_c001_v0001092_t0047") 2022/01/12 05:17:33 DEBUG : dst/one.bak: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1056fafa89dab10d_d20220112_m051401_c001_v0001150_t0044") 2022/01/12 05:17:33 DEBUG : dst/one.bak: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f10885c7182b5e240_d20220112_m051355_c001_v0001144_t0019") 2022/01/12 05:17:34 DEBUG : dst/one.bak: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1067fb5d2342b9a2_d20220112_m051349_c001_v0001105_t0008") 2022/01/12 05:17:34 DEBUG : dst/three: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1031e1d8e08a011a_d20220112_m051217_c001_v0001117_t0027") 2022/01/12 05:17:34 DEBUG : dst/three: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f104f8d3cbd98ab1d_d20220112_m051209_c001_v0001109_t0055") 2022/01/12 05:17:34 DEBUG : dst/three-2019-01-01.txt: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1031e1d8e08a0ded_d20220112_m051501_c001_v0001117_t0024") 2022/01/12 05:17:34 DEBUG : dst/three-2019-01-01.txt: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f11835a5b25e9b451_d20220112_m051455_c001_v0001117_t0036") 2022/01/12 05:17:34 DEBUG : dst/three-2019-01-01.txt: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f10618d30f43e4bb8_d20220112_m051449_c001_v0001150_t0039") 2022/01/12 05:17:34 DEBUG : dst/three.txt: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1054982d150fc236_d20220112_m051502_c001_v0001117_t0023") 2022/01/12 05:17:34 DEBUG : dst/three.txt: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f119947a12e998865_d20220112_m051458_c001_v0001117_t0027") 2022/01/12 05:17:34 DEBUG : dst/three.txt: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f113c568b48fecf16_d20220112_m051457_c001_v0001038_t0007") 2022/01/12 05:17:34 DEBUG : dst/three.txt: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f119947a12e99881f_d20220112_m051451_c001_v0001117_t0051") 2022/01/12 05:17:34 DEBUG : dst/three.txt: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f11797d79e13250aa_d20220112_m051450_c001_v0001105_t0014") 2022/01/12 05:17:34 DEBUG : dst/three.txt: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa873caa_d20220112_m051443_c001_v0001131_t0019") 2022/01/12 05:17:34 DEBUG : dst/three.txt: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f11458eeaf5939d4c_d20220112_m051434_c001_v0001041_t0031") 2022/01/12 05:17:34 DEBUG : dst/three.txt: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f112a722199955a3d_d20220112_m051428_c001_v0001090_t0001") 2022/01/12 05:17:34 DEBUG : dst/three.txt: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f100ce1ab6075362d_d20220112_m051427_c001_v0001105_t0018") 2022/01/12 05:17:34 DEBUG : dst/three.txt: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f112a722199955a30_d20220112_m051417_c001_v0001090_t0026") 2022/01/12 05:17:34 DEBUG : dst/three.txt: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1067fb5d2342be92_d20220112_m051416_c001_v0001105_t0022") 2022/01/12 05:17:35 DEBUG : dst/three.txt: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae9631a_d20220112_m051406_c001_v0001161_t0029") 2022/01/12 05:17:35 DEBUG : dst/three.txt: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f107a2c7ae4aa0700_d20220112_m051359_c001_v0001117_t0007") 2022/01/12 05:17:35 DEBUG : dst/three.txt: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa873b1a_d20220112_m051354_c001_v0001131_t0059") 2022/01/12 05:17:35 DEBUG : dst/three.txt: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f113ea5ba526596ee_d20220112_m051353_c001_v0001131_t0004") 2022/01/12 05:17:35 DEBUG : dst/three.txt: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae96249_d20220112_m051345_c001_v0001161_t0039") 2022/01/12 05:17:35 DEBUG : dst/three.txt: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1002b0bb21717468_d20220112_m051335_c001_v0001039_t0005") 2022/01/12 05:17:35 DEBUG : dst/three.txt: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa873a3b_d20220112_m051328_c001_v0001131_t0029") 2022/01/12 05:17:35 DEBUG : dst/three.txt: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f10479b044867e569_d20220112_m051327_c001_v0001098_t0001") 2022/01/12 05:17:35 DEBUG : dst/three.txt: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae9615d_d20220112_m051313_c001_v0001161_t0018") 2022/01/12 05:17:35 DEBUG : dst/three.txt: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f112fdf1eb54680bc_d20220112_m051307_c001_v0001136_t0048") 2022/01/12 05:17:35 DEBUG : dst/three.txt: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa873930_d20220112_m051302_c001_v0001131_t0003") 2022/01/12 05:17:35 DEBUG : dst/three.txt: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f100a023d055ac4bd_d20220112_m051301_c001_v0001041_t0014") 2022/01/12 05:17:35 DEBUG : dst/three.txt: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae96079_d20220112_m051249_c001_v0001161_t0035") 2022/01/12 05:17:35 DEBUG : dst/three.txt: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f115f47f82dbd1c23_d20220112_m051240_c001_v0001040_t0009") 2022/01/12 05:17:36 DEBUG : dst/three.txt: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa873891_d20220112_m051232_c001_v0001131_t0003") 2022/01/12 05:17:36 DEBUG : dst/three.txt: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1061ad52d400178e_d20220112_m051230_c001_v0001042_t0043") 2022/01/12 05:17:36 DEBUG : dst/three.txt: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae95f95_d20220112_m051223_c001_v0001161_t0022") 2022/01/12 05:17:36 DEBUG : dst/three.txt.bak: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f109ae94d1552a43a_d20220112_m051440_c001_v0001105_t0017") 2022/01/12 05:17:36 DEBUG : dst/three.txt.bak: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1195a57531ea550a_d20220112_m051426_c001_v0001098_t0018") 2022/01/12 05:17:36 DEBUG : dst/three.txt.bak: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1002d75f7446a954_d20220112_m051415_c001_v0001117_t0050") 2022/01/12 05:17:36 DEBUG : dst/three.txt.bak: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f10875e538a9cba60_d20220112_m051402_c001_v0001105_t0046") 2022/01/12 05:17:36 DEBUG : dst/three.txt.bak: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1073ffb5ee3dd5d0_d20220112_m051359_c001_v0001165_t0038") 2022/01/12 05:17:36 DEBUG : dst/three.txt.bak: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1005e9753cba5d72_d20220112_m051352_c001_v0001165_t0014") 2022/01/12 05:17:36 DEBUG : dst/two: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f10633108a45c5f2b_d20220112_m051503_c001_v0001029_t0043") 2022/01/12 05:17:36 DEBUG : dst/two: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae96446_d20220112_m051442_c001_v0001161_t0014") 2022/01/12 05:17:36 DEBUG : dst/two: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f10383e8dd13d2c77_d20220112_m051440_c001_v0001134_t0045") 2022/01/12 05:17:36 DEBUG : dst/two: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa873b94_d20220112_m051406_c001_v0001131_t0044") 2022/01/12 05:17:37 DEBUG : dst/two: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f11600f323f69715b_d20220112_m051403_c001_v0001105_t0059") 2022/01/12 05:17:37 DEBUG : dst/two: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa873abc_d20220112_m051344_c001_v0001131_t0038") 2022/01/12 05:17:37 DEBUG : dst/two: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f10715390fe044b20_d20220112_m051342_c001_v0001144_t0055") 2022/01/12 05:17:37 DEBUG : dst/two: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa873995_d20220112_m051313_c001_v0001131_t0046") 2022/01/12 05:17:37 DEBUG : dst/two: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f11090b2f6d83dbf0_d20220112_m051311_c001_v0001109_t0012") 2022/01/12 05:17:37 DEBUG : dst/two: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa8738d8_d20220112_m051248_c001_v0001131_t0026") 2022/01/12 05:17:37 DEBUG : dst/two: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f11626095c9b40929_d20220112_m051246_c001_v0001165_t0041") 2022/01/12 05:17:37 DEBUG : dst/two: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa873851_d20220112_m051222_c001_v0001131_t0041") 2022/01/12 05:17:37 DEBUG : dst/two: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f110ba2daac9c61df_d20220112_m051219_c001_v0001136_t0056") 2022/01/12 05:17:37 DEBUG : dst/two: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f112a72219995597e_d20220112_m051153_c001_v0001090_t0023") 2022/01/12 05:17:37 DEBUG : dst/two: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f108ba39d63fb96d9_d20220112_m051105_c001_v0001038_t0004") 2022/01/12 05:17:37 DEBUG : dst/two: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f11797d79e1323002_d20220112_m051059_c001_v0001105_t0029") 2022/01/12 05:17:37 DEBUG : empty space: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f11835a5b25e9a2bb_d20220112_m050858_c001_v0001117_t0030") 2022/01/12 05:17:37 DEBUG : empty space: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae95837_d20220112_m050842_c001_v0001161_t0027") 2022/01/12 05:17:38 DEBUG : empty space: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f10984cd89a6fff47_d20220112_m050839_c001_v0001137_t0021") 2022/01/12 05:17:38 DEBUG : empty space: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa8732e1_d20220112_m050802_c001_v0001131_t0045") 2022/01/12 05:17:38 DEBUG : empty space: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f106688e5796738aa_d20220112_m050739_c001_v0001165_t0057") 2022/01/12 05:17:38 DEBUG : empty space: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae95530_d20220112_m050727_c001_v0001161_t0003") 2022/01/12 05:17:38 DEBUG : empty space: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f109ae94d15526478_d20220112_m050439_c001_v0001105_t0055") 2022/01/12 05:17:38 DEBUG : empty space: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa872d74_d20220112_m050413_c001_v0001131_t0052") 2022/01/12 05:17:38 DEBUG : empty space: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f11421c9c479b90c1_d20220112_m050403_c001_v0001093_t0024") 2022/01/12 05:17:38 DEBUG : empty space: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa872d23_d20220112_m050358_c001_v0001131_t0005") 2022/01/12 05:17:38 DEBUG : empty space: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f10433be0208cbdec_d20220112_m050323_c001_v0001146_t0057") 2022/01/12 05:17:38 DEBUG : empty space: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f106c1a18973f5950_d20220112_m050312_c001_v0001093_t0040") 2022/01/12 05:17:38 DEBUG : empty space: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f109ae94d15525855_d20220112_m050304_c001_v0001105_t0026") 2022/01/12 05:17:38 DEBUG : empty space: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f106c1a18973f5901_d20220112_m050252_c001_v0001093_t0020") 2022/01/12 05:17:38 DEBUG : empty space: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1058101fe1f4f59b_d20220112_m050212_c001_v0001113_t0028") 2022/01/12 05:17:38 DEBUG : empty space: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f106c1a18973f5846_d20220112_m050207_c001_v0001093_t0056") 2022/01/12 05:17:38 DEBUG : empty space: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f108b43b979f58d86_d20220112_m050201_c001_v0001042_t0025") 2022/01/12 05:17:39 DEBUG : empty space: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f106c1a18973f582d_d20220112_m050158_c001_v0001093_t0049") 2022/01/12 05:17:39 DEBUG : empty space: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f10875e538a9c5983_d20220112_m050145_c001_v0001105_t0050") 2022/01/12 05:17:39 DEBUG : empty space: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f106c1a18973f57e1_d20220112_m050142_c001_v0001093_t0031") 2022/01/12 05:17:39 DEBUG : empty space: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f101d5933e74ac982_d20220112_m050137_c001_v0001134_t0022") 2022/01/12 05:17:39 DEBUG : empty space: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f106c1a18973f57c9_d20220112_m050134_c001_v0001093_t0031") 2022/01/12 05:17:39 DEBUG : empty space: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f106b681d9b3dd73d_d20220112_m050133_c001_v0001098_t0026") 2022/01/12 05:17:39 DEBUG : empty space: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae93fcf_d20220112_m050131_c001_v0001161_t0036") 2022/01/12 05:17:39 DEBUG : empty space: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f106c1a18973f57a6_d20220112_m050128_c001_v0001093_t0031") 2022/01/12 05:17:39 DEBUG : enormous: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f10294e50ccaf3bfe_d20220112_m050426_c001_v0001025_t0044") 2022/01/12 05:17:39 DEBUG : enormous: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa872d76_d20220112_m050413_c001_v0001131_t0008") 2022/01/12 05:17:39 DEBUG : existing: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f112dd91e813d84e6_d20220112_m051511_c001_v0001109_t0011") 2022/01/12 05:17:39 DEBUG : existing: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae9652e_d20220112_m051509_c001_v0001161_t0053") 2022/01/12 05:17:39 DEBUG : existing: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1031e1d8e089ef4b_d20220112_m050724_c001_v0001117_t0034") 2022/01/12 05:17:39 DEBUG : existing: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa8731ca_d20220112_m050723_c001_v0001131_t0058") 2022/01/12 05:17:39 DEBUG : existing: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1113956c5264403e_d20220112_m050117_c001_v0001044_t0042") 2022/01/12 05:17:39 DEBUG : existing: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f106c1a18973f576f_d20220112_m050115_c001_v0001093_t0019") 2022/01/12 05:17:39 DEBUG : existing: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f106a1c92a33107c7_d20220112_m050114_c001_v0001137_t0017") 2022/01/12 05:17:40 DEBUG : existing: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f106c1a18973f5763_d20220112_m050109_c001_v0001093_t0045") 2022/01/12 05:17:40 DEBUG : existing: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f106c1a18973f575c_d20220112_m050107_c001_v0001093_t0048") 2022/01/12 05:17:40 DEBUG : existing-b: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f10688b04020f8ca6_d20220112_m050725_c001_v0001043_t0007") 2022/01/12 05:17:40 DEBUG : existing-b: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae954ff_d20220112_m050723_c001_v0001161_t0048") 2022/01/12 05:17:40 DEBUG : five: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117442b89e006087_d20220112_m050505_c001_v0001134_t0057") 2022/01/12 05:17:40 DEBUG : five: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa872e4f_d20220112_m050454_c001_v0001131_t0007") 2022/01/12 05:17:40 DEBUG : foo: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f11376b2350c18ff0_d20220112_m050141_c001_v0001090_t0059") 2022/01/12 05:17:40 DEBUG : foo: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f106c1a18973f57db_d20220112_m050140_c001_v0001093_t0043") 2022/01/12 05:17:40 DEBUG : foo: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f106c1a18973f57d9_d20220112_m050138_c001_v0001093_t0001") 2022/01/12 05:17:40 DEBUG : four: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f115a769bb2c3393b_d20220112_m050521_c001_v0001117_t0058") 2022/01/12 05:17:40 DEBUG : four: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f11539374ab4e8848_d20220112_m050455_c001_v0001032_t0015") 2022/01/12 05:17:40 DEBUG : four: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa872e0c_d20220112_m050442_c001_v0001131_t0010") 2022/01/12 05:17:40 DEBUG : hello world2: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f100ce1ab6074bea1_d20220112_m050034_c001_v0001105_t0003") 2022/01/12 05:17:40 DEBUG : hello world2: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f106c1a18973f56f2_d20220112_m050033_c001_v0001093_t0040") 2022/01/12 05:17:40 DEBUG : ignore-size: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f103c1999387efc36_d20220112_m050106_c001_v0001105_t0032") 2022/01/12 05:17:40 DEBUG : ignore-size: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f106c1a18973f5752_d20220112_m050105_c001_v0001093_t0055") 2022/01/12 05:17:40 DEBUG : nested/sub dir/file: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f10057e8082f5e0af_d20220112_m050721_c001_v0001098_t0001") 2022/01/12 05:17:40 DEBUG : nested/sub dir/file: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae954da_d20220112_m050718_c001_v0001161_t0018") 2022/01/12 05:17:41 DEBUG : nested/sub dir/file: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f106688e5796737b5_d20220112_m050716_c001_v0001165_t0035") 2022/01/12 05:17:41 DEBUG : nested/sub dir/file: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae954af_d20220112_m050714_c001_v0001161_t0039") 2022/01/12 05:17:41 DEBUG : one: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f10567ad75849bab1_d20220112_m050532_c001_v0001105_t0035") 2022/01/12 05:17:41 DEBUG : one: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa872dfe_d20220112_m050441_c001_v0001131_t0011") 2022/01/12 05:17:41 DEBUG : only0: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f11626095c9b41152_d20220112_m051654_c001_v0001165_t0058") 2022/01/12 05:17:41 DEBUG : only0: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1109c22ed2d934a3_d20220112_m051632_c001_v0001043_t0009") 2022/01/12 05:17:41 DEBUG : only0: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa873fba_d20220112_m051613_c001_v0001131_t0024") 2022/01/12 05:17:41 DEBUG : only0: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f11145f3723f8a363_d20220112_m051543_c001_v0001144_t0043") 2022/01/12 05:17:41 DEBUG : only0: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa873d90_d20220112_m051517_c001_v0001131_t0047") 2022/01/12 05:17:41 DEBUG : only1: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f11252536aa284c37_d20220112_m051656_c001_v0001117_t0039") 2022/01/12 05:17:41 DEBUG : only1: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae968f5_d20220112_m051631_c001_v0001161_t0011") 2022/01/12 05:17:41 DEBUG : only1: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa873fc4_d20220112_m051614_c001_v0001131_t0019") 2022/01/12 05:17:41 DEBUG : only1: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f104ced6360d54095_d20220112_m051541_c001_v0001092_t0014") 2022/01/12 05:17:41 DEBUG : only1: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa873d99_d20220112_m051518_c001_v0001131_t0032") 2022/01/12 05:17:41 DEBUG : only10: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1193ae68f07932dc_d20220112_m051658_c001_v0001044_t0033") 2022/01/12 05:17:41 DEBUG : only10: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa874078_d20220112_m051631_c001_v0001131_t0000") 2022/01/12 05:17:41 DEBUG : only10: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa874021_d20220112_m051622_c001_v0001131_t0055") 2022/01/12 05:17:42 DEBUG : only10: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f11361f67256a3f61_d20220112_m051543_c001_v0001032_t0041") 2022/01/12 05:17:42 DEBUG : only10: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa873dde_d20220112_m051527_c001_v0001131_t0024") 2022/01/12 05:17:42 DEBUG : only11: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f115a12c87e7e7c5a_d20220112_m051700_c001_v0001044_t0052") 2022/01/12 05:17:42 DEBUG : only11: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f11835a5b25e9b9aa_d20220112_m051632_c001_v0001117_t0057") 2022/01/12 05:17:42 DEBUG : only11: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa874027_d20220112_m051623_c001_v0001131_t0013") 2022/01/12 05:17:42 DEBUG : only11: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f110ef4122bb7d4cd_d20220112_m051542_c001_v0001117_t0001") 2022/01/12 05:17:42 DEBUG : only11: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa873ded_d20220112_m051528_c001_v0001131_t0030") 2022/01/12 05:17:42 DEBUG : only12: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1080c7d51efae827_d20220112_m051702_c001_v0001161_t0000") 2022/01/12 05:17:42 DEBUG : only12: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa87407e_d20220112_m051631_c001_v0001131_t0004") 2022/01/12 05:17:42 DEBUG : only12: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa874031_d20220112_m051624_c001_v0001131_t0018") 2022/01/12 05:17:42 DEBUG : only12: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1023be88de3bdffe_d20220112_m051542_c001_v0001105_t0053") 2022/01/12 05:17:42 DEBUG : only12: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa873dfd_d20220112_m051529_c001_v0001131_t0000") 2022/01/12 05:17:42 DEBUG : only13: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f11099568688d0c60_d20220112_m051703_c001_v0001038_t0046") 2022/01/12 05:17:42 DEBUG : only13: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa8740a2_d20220112_m051634_c001_v0001131_t0017") 2022/01/12 05:17:42 DEBUG : only13: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa874039_d20220112_m051625_c001_v0001131_t0023") 2022/01/12 05:17:43 DEBUG : only13: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1026c860e795b8c5_d20220112_m051543_c001_v0001153_t0023") 2022/01/12 05:17:43 DEBUG : only13: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa873e0a_d20220112_m051530_c001_v0001131_t0033") 2022/01/12 05:17:43 DEBUG : only14: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1188d7d591999d5c_d20220112_m051704_c001_v0001042_t0058") 2022/01/12 05:17:43 DEBUG : only14: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae968f8_d20220112_m051631_c001_v0001161_t0038") 2022/01/12 05:17:43 DEBUG : only14: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa874043_d20220112_m051626_c001_v0001131_t0036") 2022/01/12 05:17:43 DEBUG : only14: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f110fef1b371768c7_d20220112_m051544_c001_v0001040_t0017") 2022/01/12 05:17:43 DEBUG : only14: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa873e12_d20220112_m051531_c001_v0001131_t0007") 2022/01/12 05:17:43 DEBUG : only15: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1106597e4d93683b_d20220112_m051709_c001_v0001026_t0026") 2022/01/12 05:17:43 DEBUG : only15: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa874080_d20220112_m051632_c001_v0001131_t0037") 2022/01/12 05:17:43 DEBUG : only15: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa874048_d20220112_m051627_c001_v0001131_t0014") 2022/01/12 05:17:43 DEBUG : only15: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1180c2581f2eedf3_d20220112_m051544_c001_v0001165_t0045") 2022/01/12 05:17:43 DEBUG : only15: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa873e1d_d20220112_m051532_c001_v0001131_t0053") 2022/01/12 05:17:43 DEBUG : only16: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f10975136ed4ba213_d20220112_m051711_c001_v0001044_t0057") 2022/01/12 05:17:43 DEBUG : only16: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae968fc_d20220112_m051632_c001_v0001161_t0004") 2022/01/12 05:17:43 DEBUG : only16: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa87404c_d20220112_m051627_c001_v0001131_t0004") 2022/01/12 05:17:43 DEBUG : only16: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f118841a234c57a05_d20220112_m051541_c001_v0001043_t0045") 2022/01/12 05:17:44 DEBUG : only16: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa873e2f_d20220112_m051533_c001_v0001131_t0041") 2022/01/12 05:17:44 DEBUG : only17: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f119ce1a3ea8a813d_d20220112_m051713_c001_v0001105_t0053") 2022/01/12 05:17:44 DEBUG : only17: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1109c22ed2d934a5_d20220112_m051632_c001_v0001043_t0048") 2022/01/12 05:17:44 DEBUG : only17: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa874053_d20220112_m051628_c001_v0001131_t0052") 2022/01/12 05:17:44 DEBUG : only17: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1133e670550ba212_d20220112_m051545_c001_v0001120_t0024") 2022/01/12 05:17:44 DEBUG : only17: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa873e3b_d20220112_m051533_c001_v0001131_t0012") 2022/01/12 05:17:44 DEBUG : only18: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f11460888107478fc_d20220112_m051714_c001_v0001137_t0042") 2022/01/12 05:17:44 DEBUG : only18: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f11835a5b25e9b9b5_d20220112_m051632_c001_v0001117_t0013") 2022/01/12 05:17:44 DEBUG : only18: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa87405b_d20220112_m051629_c001_v0001131_t0024") 2022/01/12 05:17:44 DEBUG : only18: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1182dbcf56ada020_d20220112_m051545_c001_v0001041_t0000") 2022/01/12 05:17:44 DEBUG : only18: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa873e48_d20220112_m051534_c001_v0001131_t0048") 2022/01/12 05:17:44 DEBUG : only19: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f104a080fe21d073f_d20220112_m051715_c001_v0001131_t0047") 2022/01/12 05:17:44 DEBUG : only19: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa874087_d20220112_m051632_c001_v0001131_t0054") 2022/01/12 05:17:44 DEBUG : only19: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa874068_d20220112_m051630_c001_v0001131_t0040") 2022/01/12 05:17:44 DEBUG : only19: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f110050575ff165ff_d20220112_m051543_c001_v0001161_t0054") 2022/01/12 05:17:44 DEBUG : only19: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa873e70_d20220112_m051539_c001_v0001131_t0021") 2022/01/12 05:17:44 DEBUG : only2: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1182dbcf56ada0e9_d20220112_m051715_c001_v0001041_t0044") 2022/01/12 05:17:45 DEBUG : only2: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae96907_d20220112_m051633_c001_v0001161_t0058") 2022/01/12 05:17:45 DEBUG : only2: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa873fcd_d20220112_m051615_c001_v0001131_t0006") 2022/01/12 05:17:45 DEBUG : only2: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f115ac477b75f6188_d20220112_m051542_c001_v0001105_t0013") 2022/01/12 05:17:45 DEBUG : only2: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa873d9f_d20220112_m051519_c001_v0001131_t0001") 2022/01/12 05:17:45 DEBUG : only3: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1010175e6208a08d_d20220112_m051716_c001_v0001043_t0005") 2022/01/12 05:17:45 DEBUG : only3: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1109c22ed2d934a7_d20220112_m051633_c001_v0001043_t0011") 2022/01/12 05:17:45 DEBUG : only3: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa873fda_d20220112_m051616_c001_v0001131_t0027") 2022/01/12 05:17:45 DEBUG : only3: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f10476005560c6f9f_d20220112_m051541_c001_v0001091_t0023") 2022/01/12 05:17:45 DEBUG : only3: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa873da6_d20220112_m051520_c001_v0001131_t0017") 2022/01/12 05:17:45 DEBUG : only4: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f119947a12e999030_d20220112_m051717_c001_v0001117_t0009") 2022/01/12 05:17:45 DEBUG : only4: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa874092_d20220112_m051633_c001_v0001131_t0054") 2022/01/12 05:17:45 DEBUG : only4: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa873fe0_d20220112_m051617_c001_v0001131_t0045") 2022/01/12 05:17:45 DEBUG : only4: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f101e67130a99b5b5_d20220112_m051542_c001_v0001097_t0043") 2022/01/12 05:17:45 DEBUG : only4: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa873dad_d20220112_m051521_c001_v0001131_t0002") 2022/01/12 05:17:45 DEBUG : only5: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f11012dfc3c385549_d20220112_m051717_c001_v0001105_t0027") 2022/01/12 05:17:45 DEBUG : only5: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa874097_d20220112_m051633_c001_v0001131_t0024") 2022/01/12 05:17:45 DEBUG : only5: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa873fe9_d20220112_m051618_c001_v0001131_t0041") 2022/01/12 05:17:46 DEBUG : only5: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f104ccde4958afed3_d20220112_m051542_c001_v0001157_t0018") 2022/01/12 05:17:46 DEBUG : only5: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa873db6_d20220112_m051522_c001_v0001131_t0004") 2022/01/12 05:17:46 DEBUG : only6: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f118a3c373b0d66cc_d20220112_m051718_c001_v0001157_t0032") 2022/01/12 05:17:46 DEBUG : only6: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae9690f_d20220112_m051633_c001_v0001161_t0008") 2022/01/12 05:17:46 DEBUG : only6: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa873ff2_d20220112_m051619_c001_v0001131_t0044") 2022/01/12 05:17:46 DEBUG : only6: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f11860e867a8ca3b3_d20220112_m051545_c001_v0001098_t0039") 2022/01/12 05:17:46 DEBUG : only6: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa873dba_d20220112_m051522_c001_v0001131_t0035") 2022/01/12 05:17:46 DEBUG : only7: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f107a2c7ae4aa119c_d20220112_m051719_c001_v0001117_t0021") 2022/01/12 05:17:46 DEBUG : only7: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1109c22ed2d934ab_d20220112_m051633_c001_v0001043_t0054") 2022/01/12 05:17:46 DEBUG : only7: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa873fff_d20220112_m051620_c001_v0001131_t0050") 2022/01/12 05:17:46 DEBUG : only7: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f106b681d9b3dee68_d20220112_m051543_c001_v0001098_t0011") 2022/01/12 05:17:46 DEBUG : only7: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa873dc4_d20220112_m051523_c001_v0001131_t0058") 2022/01/12 05:17:46 DEBUG : only8: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f104e0a22740bec49_d20220112_m051719_c001_v0001105_t0011") 2022/01/12 05:17:46 DEBUG : only8: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa87409b_d20220112_m051633_c001_v0001131_t0026") 2022/01/12 05:17:46 DEBUG : only8: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa874010_d20220112_m051620_c001_v0001131_t0002") 2022/01/12 05:17:47 DEBUG : only8: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f100ce1ab60754129_d20220112_m051544_c001_v0001105_t0051") 2022/01/12 05:17:47 DEBUG : only8: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa873dcb_d20220112_m051524_c001_v0001131_t0012") 2022/01/12 05:17:47 DEBUG : only9: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1010c460bcb00c93_d20220112_m051720_c001_v0001144_t0032") 2022/01/12 05:17:47 DEBUG : only9: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f11835a5b25e9b9cc_d20220112_m051633_c001_v0001117_t0015") 2022/01/12 05:17:47 DEBUG : only9: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa874018_d20220112_m051621_c001_v0001131_t0002") 2022/01/12 05:17:47 DEBUG : only9: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f105d74121388b482_d20220112_m051541_c001_v0001032_t0035") 2022/01/12 05:17:47 DEBUG : only9: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa873dd2_d20220112_m051526_c001_v0001131_t0040") 2022/01/12 05:17:47 DEBUG : potato: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f110e9ec12bc7e560_d20220112_m050709_c001_v0001098_t0036") 2022/01/12 05:17:47 DEBUG : potato: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa87311e_d20220112_m050701_c001_v0001131_t0018") 2022/01/12 05:17:47 DEBUG : potato: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f10688b04020f8c58_d20220112_m050656_c001_v0001043_t0037") 2022/01/12 05:17:47 DEBUG : potato: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa873047_d20220112_m050623_c001_v0001131_t0046") 2022/01/12 05:17:47 DEBUG : potato: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f114bbb83c52285db_d20220112_m050618_c001_v0001036_t0004") 2022/01/12 05:17:47 DEBUG : potato: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f11539374ab4e88e5_d20220112_m050541_c001_v0001032_t0052") 2022/01/12 05:17:48 DEBUG : potato: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f112fdacbbfd0eed4_d20220112_m050348_c001_v0001008_t0016") 2022/01/12 05:17:48 DEBUG : potato: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f106c1a18973f59a3_d20220112_m050328_c001_v0001093_t0055") 2022/01/12 05:17:48 DEBUG : potato: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f11733c708709e56c_d20220112_m050319_c001_v0001113_t0045") 2022/01/12 05:17:48 DEBUG : potato: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f106c1a18973f594d_d20220112_m050311_c001_v0001093_t0023") 2022/01/12 05:17:48 DEBUG : potato: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f11934228c13082a7_d20220112_m050301_c001_v0001093_t0044") 2022/01/12 05:17:48 DEBUG : potato: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f106c1a18973f58fb_d20220112_m050251_c001_v0001093_t0042") 2022/01/12 05:17:48 DEBUG : potato: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1031e1d8e089da79_d20220112_m050210_c001_v0001117_t0045") 2022/01/12 05:17:48 DEBUG : potato: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f106c1a18973f5844_d20220112_m050206_c001_v0001093_t0026") 2022/01/12 05:17:48 DEBUG : potato: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f107a2c7ae4a9e586_d20220112_m050204_c001_v0001117_t0050") 2022/01/12 05:17:48 DEBUG : potato: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f106c1a18973f5821_d20220112_m050156_c001_v0001093_t0029") 2022/01/12 05:17:48 DEBUG : potato: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f102aa092ed7d655a_d20220112_m050155_c001_v0001097_t0047") 2022/01/12 05:17:48 DEBUG : potato: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f106c1a18973f580d_d20220112_m050153_c001_v0001093_t0039") 2022/01/12 05:17:48 DEBUG : potato: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f106c1a18973f5809_d20220112_m050152_c001_v0001093_t0030") 2022/01/12 05:17:48 DEBUG : potato: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f10715390fe0440f1_d20220112_m050150_c001_v0001144_t0029") 2022/01/12 05:17:48 DEBUG : potato: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f106c1a18973f57ff_d20220112_m050149_c001_v0001093_t0018") 2022/01/12 05:17:48 DEBUG : potato: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f106c1a18973f57fc_d20220112_m050148_c001_v0001093_t0036") 2022/01/12 05:17:49 DEBUG : potato: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f110ec70802db98cc_d20220112_m050147_c001_v0001145_t0029") 2022/01/12 05:17:49 DEBUG : potato: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f106c1a18973f57e6_d20220112_m050143_c001_v0001093_t0042") 2022/01/12 05:17:49 DEBUG : potato2: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f101e67130a99ab69_d20220112_m051035_c001_v0001097_t0029") 2022/01/12 05:17:49 DEBUG : potato2: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae95b33_d20220112_m051027_c001_v0001161_t0048") 2022/01/12 05:17:49 DEBUG : potato2: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1042848dbf166d38_d20220112_m050913_c001_v0001033_t0032") 2022/01/12 05:17:49 DEBUG : potato2: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa8733e4_d20220112_m050841_c001_v0001131_t0007") 2022/01/12 05:17:49 DEBUG : potato2: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f116523f1473d0402_d20220112_m050813_c001_v0001091_t0010") 2022/01/12 05:17:49 DEBUG : potato2: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae95670_d20220112_m050801_c001_v0001161_t0018") 2022/01/12 05:17:49 DEBUG : potato2: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f119d07f6eccc14e7_d20220112_m050741_c001_v0001150_t0019") 2022/01/12 05:17:49 DEBUG : potato2: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa8731f3_d20220112_m050727_c001_v0001131_t0038") 2022/01/12 05:17:49 DEBUG : potato2: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1098f5e74ff6a4a8_d20220112_m050421_c001_v0001117_t0047") 2022/01/12 05:17:49 DEBUG : potato2: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa872d6c_d20220112_m050413_c001_v0001131_t0049") 2022/01/12 05:17:49 DEBUG : potato2: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f116787268060ddf5_d20220112_m050409_c001_v0001136_t0051") 2022/01/12 05:17:49 DEBUG : potato2: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa872d1b_d20220112_m050358_c001_v0001131_t0030") 2022/01/12 05:17:49 DEBUG : potato2: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111db2d9c7b2020c_d20220112_m050355_c001_v0001017_t0031") 2022/01/12 05:17:50 DEBUG : potato2: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa872cb5_d20220112_m050340_c001_v0001131_t0017") 2022/01/12 05:17:50 DEBUG : potato2: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1166a21dfd8d4c2d_d20220112_m050325_c001_v0001113_t0005") 2022/01/12 05:17:50 DEBUG : potato2: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f106c1a18973f5972_d20220112_m050321_c001_v0001093_t0013") 2022/01/12 05:17:50 DEBUG : potato2: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f10343e46b5b7af7b_d20220112_m050307_c001_v0001144_t0013") 2022/01/12 05:17:50 DEBUG : potato2: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f106c1a18973f5926_d20220112_m050301_c001_v0001093_t0005") 2022/01/12 05:17:50 DEBUG : potato2: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1144e6788a6a6c46_d20220112_m050214_c001_v0001161_t0013") 2022/01/12 05:17:50 DEBUG : potato2: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f106c1a18973f5856_d20220112_m050210_c001_v0001093_t0058") 2022/01/12 05:17:50 DEBUG : potato2: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f107a2c7ae4a9e13c_d20220112_m050039_c001_v0001117_t0045") 2022/01/12 05:17:50 DEBUG : potato2: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f106c1a18973f5704_d20220112_m050038_c001_v0001093_t0046") 2022/01/12 05:17:50 DEBUG : potato2: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f101c4d3451d890ae_d20220112_m050037_c001_v0001153_t0023") 2022/01/12 05:17:50 DEBUG : potato2: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f106c1a18973f56fa_d20220112_m050035_c001_v0001093_t0029") 2022/01/12 05:17:50 DEBUG : potato3: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1123a08f95a69105_d20220112_m050857_c001_v0001165_t0028") 2022/01/12 05:17:50 DEBUG : potato3: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa8733f6_d20220112_m050843_c001_v0001131_t0039") 2022/01/12 05:17:50 DEBUG : potato3: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f109ae94d15527a07_d20220112_m050810_c001_v0001105_t0019") 2022/01/12 05:17:51 DEBUG : potato3: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae95684_d20220112_m050802_c001_v0001161_t0049") 2022/01/12 05:17:51 DEBUG : potato3: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f10875e538a9c8ab4_d20220112_m050740_c001_v0001105_t0023") 2022/01/12 05:17:51 DEBUG : potato3: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa8731fd_d20220112_m050728_c001_v0001131_t0000") 2022/01/12 05:17:51 DEBUG : pre-dest1/1: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f103c1999387f4aa5_d20220112_m051118_c001_v0001105_t0046") 2022/01/12 05:17:51 DEBUG : pre-dest1/1: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae95c77_d20220112_m051106_c001_v0001161_t0050") 2022/01/12 05:17:51 DEBUG : pre-dest2/2: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1180c2581f2ed2e8_d20220112_m051119_c001_v0001165_t0042") 2022/01/12 05:17:51 DEBUG : pre-dest2/2: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa87370a_d20220112_m051107_c001_v0001131_t0036") 2022/01/12 05:17:51 DEBUG : sizeonly: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f116cc99dc266ef86_d20220112_m050104_c001_v0001098_t0046") 2022/01/12 05:17:51 DEBUG : sizeonly: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f106c1a18973f5747_d20220112_m050102_c001_v0001093_t0055") 2022/01/12 05:17:51 DEBUG : sub dir/hello world: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1080c7d51efacb4e_d20220112_m050721_c001_v0001161_t0051") 2022/01/12 05:17:51 DEBUG : sub dir/hello world: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa8731a2_d20220112_m050718_c001_v0001131_t0012") 2022/01/12 05:17:51 DEBUG : sub dir/hello world: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f118a6b0f624aa15d_d20220112_m050717_c001_v0001097_t0013") 2022/01/12 05:17:51 DEBUG : sub dir/hello world: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa87318f_d20220112_m050714_c001_v0001131_t0038") 2022/01/12 05:17:51 DEBUG : sub dir/hello world: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1005e9753cba3373_d20220112_m050059_c001_v0001165_t0039") 2022/01/12 05:17:51 DEBUG : sub dir/hello world: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f106c1a18973f573b_d20220112_m050056_c001_v0001093_t0042") 2022/01/12 05:17:51 DEBUG : sub dir/hello world: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1186e43dff5ed042_d20220112_m050055_c001_v0001105_t0004") 2022/01/12 05:17:52 DEBUG : sub dir/hello world: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f106c1a18973f572f_d20220112_m050053_c001_v0001093_t0045") 2022/01/12 05:17:52 DEBUG : sub dir/hello world: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1144e6788a6a6a56_d20220112_m050052_c001_v0001161_t0021") 2022/01/12 05:17:52 DEBUG : sub dir/hello world: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f106c1a18973f571c_d20220112_m050047_c001_v0001093_t0057") 2022/01/12 05:17:52 DEBUG : sub dir/hello world: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f11984a6429329c75_d20220112_m050046_c001_v0001165_t0019") 2022/01/12 05:17:52 DEBUG : sub dir/hello world: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f106c1a18973f5717_d20220112_m050045_c001_v0001093_t0048") 2022/01/12 05:17:52 DEBUG : sub dir/hello world: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1115f2f71a5335a1_d20220112_m050044_c001_v0001105_t0030") 2022/01/12 05:17:52 DEBUG : sub dir/hello world: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f106c1a18973f570d_d20220112_m050042_c001_v0001093_t0048") 2022/01/12 05:17:52 DEBUG : sub dir/hello world: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f10479b044867b238_d20220112_m050041_c001_v0001098_t0039") 2022/01/12 05:17:52 DEBUG : sub dir/hello world: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f106c1a18973f570b_d20220112_m050040_c001_v0001093_t0020") 2022/01/12 05:17:52 DEBUG : sub dir/hello world: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1078abd7df536da5_d20220112_m050032_c001_v0001146_t0030") 2022/01/12 05:17:52 DEBUG : sub dir/hello world: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f106c1a18973f56ec_d20220112_m050030_c001_v0001093_t0010") 2022/01/12 05:17:52 DEBUG : sub dir/hello world: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f109b4c8745395554_d20220112_m050029_c001_v0001090_t0055") 2022/01/12 05:17:52 DEBUG : sub dir/hello world: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f106c1a18973f56e2_d20220112_m050028_c001_v0001093_t0017") 2022/01/12 05:17:52 DEBUG : sub dir/hello world: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1058101fe1f4f414_d20220112_m050027_c001_v0001113_t0000") 2022/01/12 05:17:52 DEBUG : sub dir/hello world: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f106c1a18973f56dd_d20220112_m050026_c001_v0001093_t0056") 2022/01/12 05:17:52 DEBUG : sub dir/hello world: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f108a7d305edae7ad_d20220112_m050020_c001_v0001036_t0055") 2022/01/12 05:17:53 DEBUG : sub dir/hello world: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f106c1a18973f56d5_d20220112_m050019_c001_v0001093_t0005") 2022/01/12 05:17:53 DEBUG : sub/yam: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f112bfcaced8e83a8_d20220112_m050707_c001_v0001041_t0025") 2022/01/12 05:17:53 DEBUG : sub/yam: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae9540c_d20220112_m050700_c001_v0001161_t0014") 2022/01/12 05:17:53 DEBUG : three: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f11252536aa282772_d20220112_m050533_c001_v0001117_t0009") 2022/01/12 05:17:53 DEBUG : three: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa872e8b_d20220112_m050501_c001_v0001131_t0012") 2022/01/12 05:17:53 DEBUG : three: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa872e09_d20220112_m050442_c001_v0001131_t0043") 2022/01/12 05:17:53 DEBUG : two: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1105bf6edee0767e_d20220112_m050537_c001_v0001090_t0029") 2022/01/12 05:17:53 DEBUG : two: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae94c93_d20220112_m050457_c001_v0001161_t0018") 2022/01/12 05:17:53 DEBUG : two: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f117a1dcefa872e04_d20220112_m050441_c001_v0001131_t0018") 2022/01/12 05:17:53 DEBUG : yam: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f113609b5ecb91be2_d20220112_m050711_c001_v0001134_t0033") 2022/01/12 05:17:53 DEBUG : yam: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1144596458b2fc32_d20220112_m050706_c001_v0001000_t0017") 2022/01/12 05:17:53 DEBUG : yam: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1115f2f71a5362e3_d20220112_m050654_c001_v0001105_t0016") 2022/01/12 05:17:53 DEBUG : yam: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae9526d_d20220112_m050623_c001_v0001161_t0045") 2022/01/12 05:17:53 DEBUG : yam: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f1050b710db5e8e13_d20220112_m050611_c001_v0001097_t0053") 2022/01/12 05:17:53 DEBUG : yam: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f111c63a3cae94fc5_d20220112_m050541_c001_v0001161_t0051") 2022/01/12 05:17:54 DEBUG : yaml: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f108fceb1ef780d96_d20220112_m050657_c001_v0001117_t0005") 2022/01/12 05:17:54 DEBUG : yaml: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f10567ad75849c419_d20220112_m050649_c001_v0001105_t0054") 2022/01/12 05:17:54 DEBUG : yaml: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f113472c8b826d92a_d20220112_m050620_c001_v0001117_t0011") 2022/01/12 05:17:54 DEBUG : yaml: Deleting (id "4_zff427d93a08d4bcb7fe80e1b_f107a2c7ae4a9f0d5_d20220112_m050558_c001_v0001117_t0046") "./sync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose" - Finished OK in 17m40.037622785s (try 1/5)