"./sync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose" - Starting (try 1/5) 2021/12/13 05:00:14 DEBUG : Creating backend with remote "TestB2:rclone-test-baqoxax8mawoseg9hefexef9" 2021/12/13 05:00:14 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2021/12/13 05:00:14 DEBUG : Creating backend with remote "/tmp/rclone1166295492" === RUN TestPipe --- PASS: TestPipe (0.00s) === RUN TestPipeConcurrent --- PASS: TestPipeConcurrent (0.02s) === RUN TestPipeOrderBy === RUN TestPipeOrderBy/#00 === RUN TestPipeOrderBy/size === RUN TestPipeOrderBy/name === RUN TestPipeOrderBy/modtime === RUN TestPipeOrderBy/size,ascending === RUN TestPipeOrderBy/name,asc === RUN TestPipeOrderBy/modtime,ascending === RUN TestPipeOrderBy/size,descending === RUN TestPipeOrderBy/name,desc === RUN TestPipeOrderBy/modtime,descending === RUN TestPipeOrderBy/size,mixed,50 === RUN TestPipeOrderBy/size,mixed,51 --- PASS: TestPipeOrderBy (0.00s) --- PASS: TestPipeOrderBy/#00 (0.00s) --- PASS: TestPipeOrderBy/size (0.00s) --- PASS: TestPipeOrderBy/name (0.00s) --- PASS: TestPipeOrderBy/modtime (0.00s) --- PASS: TestPipeOrderBy/size,ascending (0.00s) --- PASS: TestPipeOrderBy/name,asc (0.00s) --- PASS: TestPipeOrderBy/modtime,ascending (0.00s) --- PASS: TestPipeOrderBy/size,descending (0.00s) --- PASS: TestPipeOrderBy/name,desc (0.00s) --- PASS: TestPipeOrderBy/modtime,descending (0.00s) --- PASS: TestPipeOrderBy/size,mixed,50 (0.00s) --- PASS: TestPipeOrderBy/size,mixed,51 (0.00s) === RUN TestNewLess === RUN TestNewLess/blankOK === RUN TestNewLess/tooManyParts === RUN TestNewLess/tooManyParts2 === RUN TestNewLess/badMixed === RUN TestNewLess/unknownComparison === RUN TestNewLess/unknownSortDirection === RUN TestNewLess/size === RUN TestNewLess/name === RUN TestNewLess/modtime === RUN TestNewLess/size,ascending === RUN TestNewLess/name,asc === RUN TestNewLess/modtime,ascending === RUN TestNewLess/size,descending === RUN TestNewLess/name,desc === RUN TestNewLess/modtime,descending === RUN TestNewLess/modtime,mixed === RUN TestNewLess/modtime,mixed,30 --- PASS: TestNewLess (0.00s) --- PASS: TestNewLess/blankOK (0.00s) --- PASS: TestNewLess/tooManyParts (0.00s) --- PASS: TestNewLess/tooManyParts2 (0.00s) --- PASS: TestNewLess/badMixed (0.00s) --- PASS: TestNewLess/unknownComparison (0.00s) --- PASS: TestNewLess/unknownSortDirection (0.00s) --- PASS: TestNewLess/size (0.00s) --- PASS: TestNewLess/name (0.00s) --- PASS: TestNewLess/modtime (0.00s) --- PASS: TestNewLess/size,ascending (0.00s) --- PASS: TestNewLess/name,asc (0.00s) --- PASS: TestNewLess/modtime,ascending (0.00s) --- PASS: TestNewLess/size,descending (0.00s) --- PASS: TestNewLess/name,desc (0.00s) --- PASS: TestNewLess/modtime,descending (0.00s) --- PASS: TestNewLess/modtime,mixed (0.00s) --- PASS: TestNewLess/modtime,mixed,30 (0.00s) === RUN TestRcCopy rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcCopy (0.00s) === RUN TestRcMove rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcMove (0.00s) === RUN TestRcSync rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcSync (0.00s) === RUN TestCopyWithDryRun run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:00:15 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) 2021/12/13 05:00:15 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:00:15 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish --- PASS: TestCopyWithDryRun (1.65s) === RUN TestCopy run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:00:16 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:00:16 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish 2021/12/13 05:00:22 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/12/13 05:00:22 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopy (7.01s) === RUN TestCopyMissingDirectory run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:00:23 DEBUG : Creating backend with remote "/non-existing" 2021/12/13 05:00:23 ERROR : Local file system at /non-existing: error reading source root directory: directory not found 2021/12/13 05:00:23 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:00:23 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish --- PASS: TestCopyMissingDirectory (0.53s) === RUN TestCopyNoTraverse run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:00:24 DEBUG : Couldn't decode error response: EOF 2021/12/13 05:00:24 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:00:24 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish 2021/12/13 05:00:25 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/12/13 05:00:25 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoTraverse (5.29s) === RUN TestCopyCheckFirst run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:00:29 INFO : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Running all checks before starting transfers 2021/12/13 05:00:29 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:00:29 INFO : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Checks finished, now starting transfers 2021/12/13 05:00:29 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish 2021/12/13 05:00:32 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/12/13 05:00:32 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyCheckFirst (4.18s) === RUN TestSyncNoTraverse run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:00:33 ERROR : Ignoring --no-traverse with sync 2021/12/13 05:00:33 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:00:33 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish 2021/12/13 05:00:34 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/12/13 05:00:34 INFO : sub dir/hello world: Copied (new) 2021/12/13 05:00:34 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoTraverse (1.99s) === RUN TestCopyWithDepth run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:00:35 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:00:35 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish 2021/12/13 05:00:36 DEBUG : hello world2: sha1 = c0bf117f9c93d02c894fe8ffd6e5cecf3545c209 OK 2021/12/13 05:00:36 INFO : hello world2: Copied (new) --- PASS: TestCopyWithDepth (2.90s) === RUN TestCopyWithFilesFrom run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:00:38 DEBUG : hello world2: Excluded 2021/12/13 05:00:38 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:00:38 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish 2021/12/13 05:00:38 DEBUG : potato2: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/12/13 05:00:38 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFrom (4.61s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:00:43 DEBUG : Couldn't decode error response: EOF 2021/12/13 05:00:43 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:00:43 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish 2021/12/13 05:00:44 DEBUG : potato2: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/12/13 05:00:44 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFromAndNoTraverse (1.99s) === RUN TestCopyEmptyDirectories run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:00:44 DEBUG : sub dir2: Making directory 2021/12/13 05:00:45 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:00:45 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish 2021/12/13 05:00:46 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/12/13 05:00:46 INFO : sub dir/hello world: Copied (new) 2021/12/13 05:00:46 DEBUG : sub dir2: Making directory 2021/12/13 05:00:46 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: copied 1 directories fstest.go:251: Filtering empty directory "sub dir2" --- PASS: TestCopyEmptyDirectories (2.11s) === RUN TestMoveEmptyDirectories run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:00:47 DEBUG : sub dir2: Making directory 2021/12/13 05:00:47 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:00:47 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish 2021/12/13 05:00:47 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/12/13 05:00:47 INFO : sub dir/hello world: Copied (new) 2021/12/13 05:00:47 INFO : sub dir/hello world: Deleted 2021/12/13 05:00:47 DEBUG : sub dir: Making directory 2021/12/13 05:00:47 DEBUG : sub dir2: Making directory 2021/12/13 05:00:47 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: copied 2 directories fstest.go:251: Filtering empty directory "sub dir2" --- PASS: TestMoveEmptyDirectories (2.90s) === RUN TestSyncEmptyDirectories run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:00:49 DEBUG : sub dir2: Making directory 2021/12/13 05:00:50 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:00:50 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish 2021/12/13 05:00:51 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/12/13 05:00:51 INFO : sub dir/hello world: Copied (new) 2021/12/13 05:00:51 DEBUG : sub dir2: Making directory 2021/12/13 05:00:51 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: copied 1 directories 2021/12/13 05:00:51 DEBUG : Waiting for deletions to finish fstest.go:251: Filtering empty directory "sub dir2" --- PASS: TestSyncEmptyDirectories (3.40s) === RUN TestServerSideCopy run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:00:54 DEBUG : Creating backend with remote "TestB2:rclone-test-ducohij9wadizit0tutemah3" sync_test.go:275: Server side copy (if possible) B2 bucket rclone-test-baqoxax8mawoseg9hefexef9 -> B2 bucket rclone-test-ducohij9wadizit0tutemah3 2021/12/13 05:00:55 DEBUG : B2 bucket rclone-test-ducohij9wadizit0tutemah3: Waiting for checks to finish 2021/12/13 05:00:55 DEBUG : B2 bucket rclone-test-ducohij9wadizit0tutemah3: Waiting for transfers to finish 2021/12/13 05:00:57 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/12/13 05:00:57 INFO : sub dir/hello world: Copied (server-side copy) 2021/12/13 05:00:58 DEBUG : B2 bucket rclone-test-ducohij9wadizit0tutemah3: Purge remote 2021/12/13 05:00:58 DEBUG : sub dir/hello world: Deleting (id "4_zafd24d8320adbb2b7fd80e1b_f11597f3e252d708e_d20211213_m050057_c001_v0001120_t0032") --- PASS: TestServerSideCopy (9.01s) === RUN TestCopyAfterDelete run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:01:03 ERROR : : error listing: directory not found 2021/12/13 05:01:04 DEBUG : Local file system at /tmp/rclone1166295492: Making directory 2021/12/13 05:01:04 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:01:04 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish --- PASS: TestCopyAfterDelete (2.99s) === RUN TestCopyRedownload run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:01:06 DEBUG : Local file system at /tmp/rclone1166295492: Waiting for checks to finish 2021/12/13 05:01:06 DEBUG : Local file system at /tmp/rclone1166295492: Waiting for transfers to finish 2021/12/13 05:01:07 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/12/13 05:01:07 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyRedownload (5.09s) === RUN TestSyncBasedOnCheckSum run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:01:10 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:01:10 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish 2021/12/13 05:01:11 DEBUG : check sum: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2021/12/13 05:01:11 INFO : check sum: Copied (new) 2021/12/13 05:01:11 DEBUG : Waiting for deletions to finish 2021/12/13 05:01:11 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:01:11 DEBUG : check sum: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2021/12/13 05:01:11 DEBUG : check sum: Size and sha1 of src and dst objects identical 2021/12/13 05:01:11 DEBUG : check sum: Unchanged skipping 2021/12/13 05:01:11 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish 2021/12/13 05:01:11 DEBUG : Waiting for deletions to finish 2021/12/13 05:01:11 INFO : There was nothing to transfer --- PASS: TestSyncBasedOnCheckSum (2.32s) === RUN TestSyncSizeOnly run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:01:12 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:01:12 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish 2021/12/13 05:01:14 DEBUG : sizeonly: sha1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2021/12/13 05:01:14 INFO : sizeonly: Copied (new) 2021/12/13 05:01:14 DEBUG : Waiting for deletions to finish 2021/12/13 05:01:14 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:01:14 DEBUG : sizeonly: Sizes identical 2021/12/13 05:01:14 DEBUG : sizeonly: Unchanged skipping 2021/12/13 05:01:14 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish 2021/12/13 05:01:14 DEBUG : Waiting for deletions to finish 2021/12/13 05:01:14 INFO : There was nothing to transfer --- PASS: TestSyncSizeOnly (2.88s) === RUN TestSyncIgnoreSize run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:01:15 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:01:15 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish 2021/12/13 05:01:17 DEBUG : ignore-size: sha1 = 4a756ca07e9487f482465a99e8286abc86ba4dc7 OK 2021/12/13 05:01:17 INFO : ignore-size: Copied (new) 2021/12/13 05:01:17 DEBUG : Waiting for deletions to finish 2021/12/13 05:01:17 DEBUG : ignore-size: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:01:17 DEBUG : ignore-size: Unchanged skipping 2021/12/13 05:01:17 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:01:17 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish 2021/12/13 05:01:17 DEBUG : Waiting for deletions to finish 2021/12/13 05:01:17 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreSize (3.05s) === RUN TestSyncIgnoreTimes run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:01:19 DEBUG : existing: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:01:19 DEBUG : existing: Unchanged skipping 2021/12/13 05:01:19 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:01:19 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish 2021/12/13 05:01:19 DEBUG : Waiting for deletions to finish 2021/12/13 05:01:19 INFO : There was nothing to transfer 2021/12/13 05:01:19 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2021/12/13 05:01:19 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:01:19 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish 2021/12/13 05:01:20 DEBUG : existing: sha1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2021/12/13 05:01:20 INFO : existing: Copied (replaced existing) 2021/12/13 05:01:20 DEBUG : Waiting for deletions to finish --- PASS: TestSyncIgnoreTimes (3.42s) === RUN TestSyncIgnoreExisting run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:01:22 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:01:22 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish 2021/12/13 05:01:24 DEBUG : existing: sha1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2021/12/13 05:01:24 INFO : existing: Copied (new) 2021/12/13 05:01:24 DEBUG : Waiting for deletions to finish 2021/12/13 05:01:24 DEBUG : existing: Destination exists, skipping 2021/12/13 05:01:24 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:01:24 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish 2021/12/13 05:01:24 DEBUG : Waiting for deletions to finish 2021/12/13 05:01:24 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreExisting (4.12s) === RUN TestSyncIgnoreErrors run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:01:28 DEBUG : d: Making directory fstest.go:251: Filtering empty directory "d" 2021/12/13 05:01:28 DEBUG : c/non empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/12/13 05:01:28 DEBUG : c/non empty space: Unchanged skipping 2021/12/13 05:01:28 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:01:28 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish 2021/12/13 05:01:29 DEBUG : a/potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/12/13 05:01:29 INFO : a/potato2: Copied (new) 2021/12/13 05:01:29 DEBUG : Waiting for deletions to finish 2021/12/13 05:01:31 INFO : b/potato: Deleted --- PASS: TestSyncIgnoreErrors (6.54s) === RUN TestSyncAfterChangingModtimeOnly run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:01:34 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:01:34 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 2021/12/13 05:01:34 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2021/12/13 05:01:34 NOTICE: empty space: Skipped update modification time as --dry-run is set (size 1) 2021/12/13 05:01:34 DEBUG : empty space: Unchanged skipping 2021/12/13 05:01:34 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish 2021/12/13 05:01:34 DEBUG : Waiting for deletions to finish 2021/12/13 05:01:34 INFO : There was nothing to transfer 2021/12/13 05:01:34 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:01:34 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 2021/12/13 05:01:34 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2021/12/13 05:01:39 INFO : empty space: Updated modification time in destination 2021/12/13 05:01:39 DEBUG : empty space: Unchanged skipping 2021/12/13 05:01:39 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish 2021/12/13 05:01:39 DEBUG : Waiting for deletions to finish 2021/12/13 05:01:39 INFO : There was nothing to transfer --- PASS: TestSyncAfterChangingModtimeOnly (7.05s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:01:43 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 2021/12/13 05:01:43 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:01:43 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2021/12/13 05:01:43 DEBUG : empty space: Unchanged skipping 2021/12/13 05:01:43 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish 2021/12/13 05:01:43 DEBUG : Waiting for deletions to finish 2021/12/13 05:01:43 INFO : There was nothing to transfer --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (4.31s) === RUN TestSyncDoesntUpdateModtime run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:01:45 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:01:45 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 2021/12/13 05:01:45 DEBUG : foo: sha1 = 0beec7b5ea3f0fdbc95d0dd47f3c5bc275da8a33 (Local file system at /tmp/rclone1166295492) 2021/12/13 05:01:45 DEBUG : foo: sha1 = 62cdb7020ff920e5aa642c3d4066950dd1f01f4d (B2 bucket rclone-test-baqoxax8mawoseg9hefexef9) 2021/12/13 05:01:45 DEBUG : foo: sha1 differ 2021/12/13 05:01:45 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish 2021/12/13 05:01:47 DEBUG : foo: sha1 = 0beec7b5ea3f0fdbc95d0dd47f3c5bc275da8a33 OK 2021/12/13 05:01:47 INFO : foo: Copied (replaced existing) 2021/12/13 05:01:47 DEBUG : Waiting for deletions to finish --- PASS: TestSyncDoesntUpdateModtime (4.90s) === RUN TestSyncAfterAddingAFile run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:01:53 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/12/13 05:01:53 DEBUG : empty space: Unchanged skipping 2021/12/13 05:01:53 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:01:53 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish 2021/12/13 05:01:56 DEBUG : potato: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/12/13 05:01:56 INFO : potato: Copied (new) 2021/12/13 05:01:56 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterAddingAFile (14.63s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:02:05 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:02:05 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2021/12/13 05:02:05 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish 2021/12/13 05:02:05 DEBUG : potato: sha1 = 0fd321a1023ac4e1e1054fcc0236b9d0755f722f OK 2021/12/13 05:02:05 INFO : potato: Copied (replaced existing) 2021/12/13 05:02:05 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingFilesSizeOnly (5.90s) === RUN TestSyncAfterChangingContentsOnly run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:02:11 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:02:11 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 2021/12/13 05:02:11 DEBUG : potato: sha1 = 1ca5094b3b73f587249eabe179c40a3eec839b1f (Local file system at /tmp/rclone1166295492) 2021/12/13 05:02:11 DEBUG : potato: sha1 = 0fd321a1023ac4e1e1054fcc0236b9d0755f722f (B2 bucket rclone-test-baqoxax8mawoseg9hefexef9) 2021/12/13 05:02:11 DEBUG : potato: sha1 differ 2021/12/13 05:02:11 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish 2021/12/13 05:02:13 DEBUG : potato: sha1 = 1ca5094b3b73f587249eabe179c40a3eec839b1f OK 2021/12/13 05:02:13 INFO : potato: Copied (replaced existing) 2021/12/13 05:02:13 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingContentsOnly (5.13s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:02:21 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2021/12/13 05:02:21 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/12/13 05:02:21 DEBUG : empty space: Unchanged skipping 2021/12/13 05:02:21 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:02:21 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish 2021/12/13 05:02:21 DEBUG : Waiting for deletions to finish 2021/12/13 05:02:21 NOTICE: potato: Skipped delete as --dry-run is set (size 21) --- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (19.45s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:02:36 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/12/13 05:02:36 DEBUG : empty space: Unchanged skipping 2021/12/13 05:02:36 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:02:36 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish 2021/12/13 05:02:37 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/12/13 05:02:37 INFO : potato2: Copied (new) 2021/12/13 05:02:37 DEBUG : Waiting for deletions to finish 2021/12/13 05:02:37 INFO : potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (13.75s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:02:49 DEBUG : d: Making directory 2021/12/13 05:02:49 DEBUG : d/e: Making directory fstest.go:251: Filtering empty directory "d" fstest.go:251: Filtering empty directory "d/e" 2021/12/13 05:02:50 DEBUG : c/non empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/12/13 05:02:50 DEBUG : c/non empty space: Unchanged skipping 2021/12/13 05:02:50 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:02:50 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish 2021/12/13 05:02:50 DEBUG : a/potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/12/13 05:02:50 INFO : a/potato2: Copied (new) 2021/12/13 05:02:50 DEBUG : Waiting for deletions to finish 2021/12/13 05:02:54 INFO : b/potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (10.71s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:03:01 DEBUG : d: Making directory fstest.go:251: Filtering empty directory "d" 2021/12/13 05:03:02 DEBUG : c/non empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/12/13 05:03:02 DEBUG : c/non empty space: Unchanged skipping 2021/12/13 05:03:02 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:03:02 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish 2021/12/13 05:03:02 DEBUG : a/potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/12/13 05:03:02 INFO : a/potato2: Copied (new) 2021/12/13 05:03:02 ERROR : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: not deleting files as there were IO errors 2021/12/13 05:03:02 ERROR : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: not deleting directories as there were IO errors fstest.go:251: Filtering empty directory "d" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (5.99s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:03:07 DEBUG : Waiting for deletions to finish 2021/12/13 05:03:07 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/12/13 05:03:07 DEBUG : empty space: Unchanged skipping 2021/12/13 05:03:07 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:03:07 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish 2021/12/13 05:03:07 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/12/13 05:03:07 INFO : potato2: Copied (new) 2021/12/13 05:03:08 INFO : potato: Deleted --- PASS: TestSyncDeleteDuring (14.11s) === RUN TestSyncDeleteBefore run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:03:25 DEBUG : Waiting for deletions to finish 2021/12/13 05:03:25 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:03:25 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish 2021/12/13 05:03:26 INFO : potato: Deleted 2021/12/13 05:03:27 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/12/13 05:03:27 DEBUG : empty space: Unchanged skipping 2021/12/13 05:03:27 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:03:27 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish 2021/12/13 05:03:28 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/12/13 05:03:28 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteBefore (12.14s) === RUN TestCopyDeleteBefore run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:03:32 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:03:32 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish 2021/12/13 05:03:33 DEBUG : potato2: sha1 = 810274f0df19dab60a894a562b7a8ff04c072623 OK 2021/12/13 05:03:33 INFO : potato2: Copied (new) 2021/12/13 05:03:34 DEBUG : pacer: low level retry 1/10 (error no tomes available (503 service_unavailable)) 2021/12/13 05:03:34 DEBUG : pacer: Rate limited, increasing sleep to 1s 2021/12/13 05:03:36 DEBUG : pacer: Reducing sleep to 500ms 2021/12/13 05:03:36 DEBUG : pacer: Reducing sleep to 250ms --- PASS: TestCopyDeleteBefore (5.96s) === RUN TestSyncWithExclude run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:03:37 DEBUG : pacer: Reducing sleep to 125ms 2021/12/13 05:03:38 DEBUG : pacer: Reducing sleep to 62.5ms 2021/12/13 05:03:38 DEBUG : pacer: Reducing sleep to 31.25ms 2021/12/13 05:03:38 DEBUG : enormous: Excluded 2021/12/13 05:03:38 DEBUG : potato2: Excluded 2021/12/13 05:03:38 DEBUG : pacer: Reducing sleep to 15.625ms 2021/12/13 05:03:38 DEBUG : potato2: Excluded 2021/12/13 05:03:38 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:03:38 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/12/13 05:03:38 DEBUG : empty space: Unchanged skipping 2021/12/13 05:03:38 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish 2021/12/13 05:03:38 DEBUG : Waiting for deletions to finish 2021/12/13 05:03:38 INFO : There was nothing to transfer 2021/12/13 05:03:38 DEBUG : pacer: Reducing sleep to 10ms 2021/12/13 05:03:38 DEBUG : enormous: Excluded 2021/12/13 05:03:38 DEBUG : potato2: Excluded 2021/12/13 05:03:39 DEBUG : potato2: Excluded 2021/12/13 05:03:39 DEBUG : empty space: Size and modification time the same (differ by 456.789µs, within tolerance 1ms) 2021/12/13 05:03:39 DEBUG : empty space: Unchanged skipping 2021/12/13 05:03:39 DEBUG : Local file system at /tmp/rclone1166295492: Waiting for checks to finish 2021/12/13 05:03:39 DEBUG : Local file system at /tmp/rclone1166295492: Waiting for transfers to finish 2021/12/13 05:03:39 DEBUG : Waiting for deletions to finish 2021/12/13 05:03:39 INFO : There was nothing to transfer --- PASS: TestSyncWithExclude (4.02s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:03:44 DEBUG : enormous: Excluded 2021/12/13 05:03:44 DEBUG : potato2: Excluded 2021/12/13 05:03:44 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/12/13 05:03:44 DEBUG : empty space: Unchanged skipping 2021/12/13 05:03:44 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:03:44 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish 2021/12/13 05:03:44 DEBUG : Waiting for deletions to finish 2021/12/13 05:03:44 INFO : enormous: Deleted 2021/12/13 05:03:45 INFO : potato2: Deleted 2021/12/13 05:03:45 INFO : There was nothing to transfer 2021/12/13 05:03:45 DEBUG : empty space: Size and modification time the same (differ by 456.789µs, within tolerance 1ms) 2021/12/13 05:03:45 DEBUG : empty space: Unchanged skipping 2021/12/13 05:03:45 DEBUG : Local file system at /tmp/rclone1166295492: Waiting for checks to finish 2021/12/13 05:03:45 DEBUG : Local file system at /tmp/rclone1166295492: Waiting for transfers to finish 2021/12/13 05:03:45 DEBUG : Waiting for deletions to finish 2021/12/13 05:03:45 INFO : potato2: Deleted 2021/12/13 05:03:45 INFO : enormous: Deleted 2021/12/13 05:03:45 INFO : There was nothing to transfer --- PASS: TestSyncWithExcludeAndDeleteExcluded (6.47s) === RUN TestSyncWithUpdateOlder run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:03:51 DEBUG : four: Sizes differ (src 4 vs dst 8) 2021/12/13 05:03:51 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2021/12/13 05:03:51 DEBUG : three: Sizes identical 2021/12/13 05:03:51 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2021/12/13 05:03:51 DEBUG : one: Destination is newer than source, skipping 2021/12/13 05:03:51 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:03:51 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b (Local file system at /tmp/rclone1166295492) 2021/12/13 05:03:51 DEBUG : two: sha1 = b10e0da85e90399abfec36c7376b20aa4596461b (B2 bucket rclone-test-baqoxax8mawoseg9hefexef9) 2021/12/13 05:03:51 DEBUG : two: sha1 differ 2021/12/13 05:03:51 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish 2021/12/13 05:03:52 DEBUG : four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2021/12/13 05:03:52 INFO : four: Copied (replaced existing) 2021/12/13 05:03:52 DEBUG : five: Clearing upload URL because of error: no tomes available (503 service_unavailable) 2021/12/13 05:03:52 DEBUG : pacer: low level retry 1/1 (error no tomes available (503 service_unavailable)) 2021/12/13 05:03:52 DEBUG : pacer: Rate limited, increasing sleep to 1s 2021/12/13 05:03:52 DEBUG : five: Received error: no tomes available (503 service_unavailable) - low level retry 1/10 2021/12/13 05:03:52 DEBUG : pacer: Reducing sleep to 500ms 2021/12/13 05:03:52 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2021/12/13 05:03:52 INFO : two: Copied (replaced existing) 2021/12/13 05:03:53 DEBUG : pacer: Reducing sleep to 250ms 2021/12/13 05:03:53 DEBUG : five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2021/12/13 05:03:53 INFO : five: Copied (new) 2021/12/13 05:03:53 DEBUG : Waiting for deletions to finish 2021/12/13 05:03:53 DEBUG : pacer: Reducing sleep to 125ms 2021/12/13 05:03:54 DEBUG : pacer: Reducing sleep to 62.5ms 2021/12/13 05:03:54 DEBUG : one: Destination is newer than source, skipping 2021/12/13 05:03:54 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:03:54 DEBUG : five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2021/12/13 05:03:54 DEBUG : five: Size and sha1 of src and dst objects identical 2021/12/13 05:03:54 DEBUG : five: Destination mod time is within 1s of source and files identical, skipping 2021/12/13 05:03:54 DEBUG : three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb (Local file system at /tmp/rclone1166295492) 2021/12/13 05:03:54 DEBUG : three: sha1 = 03731cad13f4cf7b59703c77529381cdec1632e5 (B2 bucket rclone-test-baqoxax8mawoseg9hefexef9) 2021/12/13 05:03:54 DEBUG : three: sha1 differ 2021/12/13 05:03:54 DEBUG : three: Destination mod time is within 1s of source but files differ, transferring 2021/12/13 05:03:54 DEBUG : four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2021/12/13 05:03:54 DEBUG : four: Size and sha1 of src and dst objects identical 2021/12/13 05:03:54 DEBUG : four: Destination mod time is within 1s of source and files identical, skipping 2021/12/13 05:03:54 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2021/12/13 05:03:54 DEBUG : two: Size and sha1 of src and dst objects identical 2021/12/13 05:03:54 DEBUG : two: Destination mod time is within 1s of source and files identical, skipping 2021/12/13 05:03:54 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish 2021/12/13 05:03:55 DEBUG : pacer: Reducing sleep to 31.25ms 2021/12/13 05:03:55 DEBUG : three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/12/13 05:03:55 INFO : three: Copied (replaced existing) 2021/12/13 05:03:55 DEBUG : Waiting for deletions to finish 2021/12/13 05:03:55 DEBUG : pacer: Reducing sleep to 15.625ms 2021/12/13 05:03:55 DEBUG : pacer: Reducing sleep to 10ms --- PASS: TestSyncWithUpdateOlder (12.19s) === 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-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" sync_test.go:1058: Can track renames: true 2021/12/13 05:03:59 INFO : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Making map for --track-renames 2021/12/13 05:03:59 INFO : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Finished making map for --track-renames 2021/12/13 05:03:59 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:03:59 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for renames to finish 2021/12/13 05:03:59 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish 2021/12/13 05:04:00 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2021/12/13 05:04:00 INFO : potato: Copied (new) 2021/12/13 05:04:01 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2021/12/13 05:04:01 INFO : yam: Copied (new) 2021/12/13 05:04:01 DEBUG : Waiting for deletions to finish 2021/12/13 05:04:01 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:04:01 DEBUG : potato: Unchanged skipping 2021/12/13 05:04:01 INFO : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Making map for --track-renames 2021/12/13 05:04:01 INFO : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Finished making map for --track-renames 2021/12/13 05:04:01 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:04:01 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for renames to finish 2021/12/13 05:04:02 DEBUG : Couldn't decode error response: EOF 2021/12/13 05:04:02 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2021/12/13 05:04:02 INFO : yam: Copied (server-side copy) to: yaml 2021/12/13 05:04:03 INFO : yam: Deleted 2021/12/13 05:04:03 INFO : yaml: Renamed from "yam" 2021/12/13 05:04:03 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish 2021/12/13 05:04:03 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenames (5.57s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" sync_test.go:1127: Can track renames: true 2021/12/13 05:04:05 INFO : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Making map for --track-renames 2021/12/13 05:04:05 INFO : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Finished making map for --track-renames 2021/12/13 05:04:05 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:04:05 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for renames to finish 2021/12/13 05:04:05 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish 2021/12/13 05:04:05 DEBUG : yam: Clearing upload URL because of error: Post "https://pod-000-1038-13.backblaze.com/b2api/v1/b2_upload_file/df02adc3209dbb2b7fd80e1b/c001_v0001038_t0049": EOF 2021/12/13 05:04:05 DEBUG : pacer: low level retry 1/1 (error Post "https://pod-000-1038-13.backblaze.com/b2api/v1/b2_upload_file/df02adc3209dbb2b7fd80e1b/c001_v0001038_t0049": EOF) 2021/12/13 05:04:05 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2021/12/13 05:04:05 DEBUG : yam: Received error: Post "https://pod-000-1038-13.backblaze.com/b2api/v1/b2_upload_file/df02adc3209dbb2b7fd80e1b/c001_v0001038_t0049": EOF - low level retry 1/10 2021/12/13 05:04:05 DEBUG : pacer: Reducing sleep to 10ms 2021/12/13 05:04:05 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2021/12/13 05:04:05 INFO : potato: Copied (new) 2021/12/13 05:04:06 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2021/12/13 05:04:06 INFO : yam: Copied (new) 2021/12/13 05:04:06 DEBUG : Waiting for deletions to finish 2021/12/13 05:04:07 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:04:07 DEBUG : potato: Unchanged skipping 2021/12/13 05:04:07 INFO : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Making map for --track-renames 2021/12/13 05:04:07 INFO : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Finished making map for --track-renames 2021/12/13 05:04:07 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:04:07 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for renames to finish 2021/12/13 05:04:07 DEBUG : Couldn't decode error response: EOF 2021/12/13 05:04:08 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2021/12/13 05:04:08 INFO : yam: Copied (server-side copy) to: yaml 2021/12/13 05:04:09 INFO : yam: Deleted 2021/12/13 05:04:09 INFO : yaml: Renamed from "yam" 2021/12/13 05:04:09 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish 2021/12/13 05:04:09 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenamesStrategyModtime (5.66s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" sync_test.go:1163: Can track renames: true 2021/12/13 05:04:10 INFO : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Making map for --track-renames 2021/12/13 05:04:10 INFO : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Finished making map for --track-renames 2021/12/13 05:04:10 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:04:10 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for renames to finish 2021/12/13 05:04:10 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish 2021/12/13 05:04:12 DEBUG : sub/yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2021/12/13 05:04:12 INFO : sub/yam: Copied (new) 2021/12/13 05:04:12 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2021/12/13 05:04:12 INFO : potato: Copied (new) 2021/12/13 05:04:12 DEBUG : Waiting for deletions to finish 2021/12/13 05:04:12 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:04:12 DEBUG : potato: Unchanged skipping 2021/12/13 05:04:13 INFO : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Making map for --track-renames 2021/12/13 05:04:13 INFO : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Finished making map for --track-renames 2021/12/13 05:04:13 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:04:13 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for renames to finish 2021/12/13 05:04:13 DEBUG : Couldn't decode error response: EOF 2021/12/13 05:04:20 DEBUG : sub/yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2021/12/13 05:04:20 INFO : sub/yam: Copied (server-side copy) to: yam 2021/12/13 05:04:21 INFO : sub/yam: Deleted 2021/12/13 05:04:21 INFO : yam: Renamed from "sub/yam" 2021/12/13 05:04:21 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish 2021/12/13 05:04:21 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenamesStrategyLeaf (13.04s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:04:23 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:04:23 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish 2021/12/13 05:04:25 DEBUG : nested/sub dir/file: sha1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK 2021/12/13 05:04:25 INFO : nested/sub dir/file: Copied (new) 2021/12/13 05:04:25 INFO : nested/sub dir/file: Deleted 2021/12/13 05:04:25 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/12/13 05:04:25 INFO : sub dir/hello world: Copied (new) 2021/12/13 05:04:25 INFO : sub dir/hello world: Deleted 2021/12/13 05:04:25 INFO : sub dir: Removing directory 2021/12/13 05:04:25 INFO : nested/sub dir: Removing directory 2021/12/13 05:04:25 INFO : nested: Removing directory 2021/12/13 05:04:25 DEBUG : Local file system at /tmp/rclone1166295492: deleted 3 directories --- PASS: TestMoveWithDeleteEmptySrcDirs (7.39s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:04:31 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:04:31 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish 2021/12/13 05:04:32 DEBUG : nested/sub dir/file: sha1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK 2021/12/13 05:04:32 INFO : nested/sub dir/file: Copied (new) 2021/12/13 05:04:32 INFO : nested/sub dir/file: Deleted 2021/12/13 05:04:32 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/12/13 05:04:32 INFO : sub dir/hello world: Copied (new) 2021/12/13 05:04:32 INFO : sub dir/hello world: Deleted --- PASS: TestMoveWithoutDeleteEmptySrcDirs (2.77s) === RUN TestMoveWithIgnoreExisting run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:04:34 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:04:34 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish 2021/12/13 05:04:34 DEBUG : existing: sha1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2021/12/13 05:04:34 INFO : existing: Copied (new) 2021/12/13 05:04:34 INFO : existing: Deleted 2021/12/13 05:04:35 DEBUG : existing-b: sha1 = 321f6b7e8bf7f29aab89c5e41a555b1b0baa41a9 OK 2021/12/13 05:04:35 INFO : existing-b: Copied (new) 2021/12/13 05:04:35 INFO : existing-b: Deleted 2021/12/13 05:04:35 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:04:35 DEBUG : existing: Destination exists, skipping 2021/12/13 05:04:35 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set 2021/12/13 05:04:35 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish 2021/12/13 05:04:35 INFO : There was nothing to transfer --- PASS: TestMoveWithIgnoreExisting (3.53s) === RUN TestServerSideMove run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:04:37 DEBUG : Creating backend with remote "TestB2:rclone-test-cixahag6qovarec1xujicun8" 2021/12/13 05:04:37 DEBUG : Config file has changed externaly - reloading sync_test.go:1215: Server side move (if possible) B2 bucket rclone-test-baqoxax8mawoseg9hefexef9 -> B2 bucket rclone-test-cixahag6qovarec1xujicun8 2021/12/13 05:04:46 DEBUG : B2 bucket rclone-test-cixahag6qovarec1xujicun8: Waiting for checks to finish 2021/12/13 05:04:46 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms) 2021/12/13 05:04:46 DEBUG : empty space: Unchanged skipping 2021/12/13 05:04:46 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2021/12/13 05:04:46 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/12/13 05:04:46 INFO : potato2: Copied (server-side copy) 2021/12/13 05:04:46 INFO : empty space: Deleted 2021/12/13 05:04:46 DEBUG : B2 bucket rclone-test-cixahag6qovarec1xujicun8: Waiting for transfers to finish 2021/12/13 05:04:47 INFO : potato2: Deleted 2021/12/13 05:04:48 DEBUG : potato3: sha1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2021/12/13 05:04:48 INFO : potato3: Copied (server-side copy) 2021/12/13 05:04:49 INFO : potato3: Deleted 2021/12/13 05:04:49 DEBUG : Creating backend with remote "TestB2:rclone-test-gaxojut9kizisuz5coqoluc4" 2021/12/13 05:04:51 DEBUG : B2 bucket rclone-test-gaxojut9kizisuz5coqoluc4: Waiting for checks to finish 2021/12/13 05:04:51 DEBUG : B2 bucket rclone-test-gaxojut9kizisuz5coqoluc4: Waiting for transfers to finish 2021/12/13 05:04:52 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2021/12/13 05:04:52 INFO : empty space: Copied (server-side copy) 2021/12/13 05:04:52 DEBUG : potato3: sha1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2021/12/13 05:04:52 INFO : potato3: Copied (server-side copy) 2021/12/13 05:04:52 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/12/13 05:04:52 INFO : potato2: Copied (server-side copy) 2021/12/13 05:04:53 INFO : potato3: Deleted 2021/12/13 05:04:53 INFO : potato2: Deleted 2021/12/13 05:04:56 INFO : empty space: Deleted 2021/12/13 05:04:56 DEBUG : B2 bucket rclone-test-gaxojut9kizisuz5coqoluc4: Purge remote 2021/12/13 05:04:56 DEBUG : empty space: Deleting (id "4_zeff2ed2320ddbb2b7fd80e1b_f117f19fde63c0193_d20211213_m050452_c001_v0001131_t0001") 2021/12/13 05:04:56 DEBUG : potato2: Deleting (id "4_zeff2ed2320ddbb2b7fd80e1b_f10482ebc001c3ca9_d20211213_m050452_c001_v0001144_t0022") 2021/12/13 05:04:56 DEBUG : potato3: Deleting (id "4_zeff2ed2320ddbb2b7fd80e1b_f1006f4ee8eafa017_d20211213_m050452_c001_v0001131_t0017") 2021/12/13 05:04:58 DEBUG : B2 bucket rclone-test-cixahag6qovarec1xujicun8: Purge remote 2021/12/13 05:04:58 DEBUG : empty space: Deleting (id "4_zff12cd3320ddbb2b7fd80e1b_f118af69500dfa63b_d20211213_m050452_c001_v0001130_t0053") 2021/12/13 05:04:58 DEBUG : empty space: Deleting (id "4_zff12cd3320ddbb2b7fd80e1b_f105d1fd013cb9485_d20211213_m050444_c001_v0001017_t0038") 2021/12/13 05:04:58 DEBUG : potato2: Deleting (id "4_zff12cd3320ddbb2b7fd80e1b_f10821d313da8e52e_d20211213_m050452_c001_v0001120_t0052") 2021/12/13 05:04:58 DEBUG : potato2: Deleting (id "4_zff12cd3320ddbb2b7fd80e1b_f1185a909d86ab12b_d20211213_m050446_c001_v0001153_t0014") 2021/12/13 05:04:58 DEBUG : potato3: Deleting (id "4_zff12cd3320ddbb2b7fd80e1b_f1192d8399ce9805b_d20211213_m050452_c001_v0001144_t0004") 2021/12/13 05:04:58 DEBUG : potato3: Deleting (id "4_zff12cd3320ddbb2b7fd80e1b_f113a9cd6c0552795_d20211213_m050448_c001_v0001044_t0056") 2021/12/13 05:04:58 DEBUG : potato3: Deleting (id "4_zff12cd3320ddbb2b7fd80e1b_f105d1fd013cb9488_d20211213_m050445_c001_v0001017_t0038") --- PASS: TestServerSideMove (22.02s) === RUN TestServerSideMoveWithFilter run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:04:59 DEBUG : Creating backend with remote "TestB2:rclone-test-xinexay7nugamop6dusovud2" sync_test.go:1215: Server side move (if possible) B2 bucket rclone-test-baqoxax8mawoseg9hefexef9 -> B2 bucket rclone-test-xinexay7nugamop6dusovud2 2021/12/13 05:05:09 DEBUG : empty space: Clearing upload URL because of error: no tomes available (503 service_unavailable) 2021/12/13 05:05:09 DEBUG : pacer: low level retry 1/1 (error no tomes available (503 service_unavailable)) 2021/12/13 05:05:09 DEBUG : pacer: Rate limited, increasing sleep to 1s run.go:283: Retry Put of "empty space" to B2 bucket rclone-test-xinexay7nugamop6dusovud2: 1/10 (no tomes available (503 service_unavailable)) 2021/12/13 05:05:11 DEBUG : pacer: Reducing sleep to 500ms 2021/12/13 05:05:13 DEBUG : pacer: Reducing sleep to 250ms 2021/12/13 05:05:14 DEBUG : pacer: Reducing sleep to 125ms 2021/12/13 05:05:14 DEBUG : pacer: Reducing sleep to 62.5ms 2021/12/13 05:05:14 DEBUG : pacer: Reducing sleep to 31.25ms 2021/12/13 05:05:14 DEBUG : empty space: Excluded 2021/12/13 05:05:14 DEBUG : empty space: Excluded 2021/12/13 05:05:14 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2021/12/13 05:05:14 DEBUG : B2 bucket rclone-test-xinexay7nugamop6dusovud2: Waiting for checks to finish 2021/12/13 05:05:14 DEBUG : B2 bucket rclone-test-xinexay7nugamop6dusovud2: Waiting for transfers to finish 2021/12/13 05:05:15 DEBUG : pacer: Reducing sleep to 15.625ms 2021/12/13 05:05:15 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/12/13 05:05:15 INFO : potato2: Copied (server-side copy) 2021/12/13 05:05:15 INFO : potato2: Deleted 2021/12/13 05:05:18 DEBUG : pacer: Reducing sleep to 10ms 2021/12/13 05:05:18 DEBUG : potato3: sha1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2021/12/13 05:05:18 INFO : potato3: Copied (server-side copy) 2021/12/13 05:05:18 INFO : potato3: Deleted 2021/12/13 05:05:18 DEBUG : Creating backend with remote "TestB2:rclone-test-cereviz4malodez2jatasel9" 2021/12/13 05:05:19 DEBUG : empty space: Excluded 2021/12/13 05:05:19 DEBUG : B2 bucket rclone-test-cereviz4malodez2jatasel9: Waiting for checks to finish 2021/12/13 05:05:19 DEBUG : B2 bucket rclone-test-cereviz4malodez2jatasel9: Waiting for transfers to finish 2021/12/13 05:05:21 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/12/13 05:05:21 INFO : potato2: Copied (server-side copy) 2021/12/13 05:05:21 INFO : potato2: Deleted 2021/12/13 05:05:23 DEBUG : potato3: sha1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2021/12/13 05:05:23 INFO : potato3: Copied (server-side copy) 2021/12/13 05:05:24 INFO : potato3: Deleted 2021/12/13 05:05:24 DEBUG : B2 bucket rclone-test-cereviz4malodez2jatasel9: Purge remote 2021/12/13 05:05:24 DEBUG : potato2: Deleting (id "4_z9fb25d2320edbb2b7fd80e1b_f10482ebc001c3d22_d20211213_m050520_c001_v0001144_t0034") 2021/12/13 05:05:24 DEBUG : potato3: Deleting (id "4_z9fb25d2320edbb2b7fd80e1b_f102cd5318be59f26_d20211213_m050521_c001_v0001117_t0027") 2021/12/13 05:05:25 DEBUG : B2 bucket rclone-test-xinexay7nugamop6dusovud2: Purge remote 2021/12/13 05:05:25 DEBUG : empty space: Deleting (id "4_z4f222d3320edbb2b7fd80e1b_f11637c63c41a8787_d20211213_m050513_c001_v0001097_t0035") 2021/12/13 05:05:25 DEBUG : potato2: Deleting (id "4_z4f222d3320edbb2b7fd80e1b_f118e218fffdafea8_d20211213_m050521_c001_v0001145_t0013") 2021/12/13 05:05:25 DEBUG : potato2: Deleting (id "4_z4f222d3320edbb2b7fd80e1b_f1077360dfe974cbe_d20211213_m050514_c001_v0001137_t0007") 2021/12/13 05:05:25 DEBUG : potato3: Deleting (id "4_z4f222d3320edbb2b7fd80e1b_f1180075a9147d757_d20211213_m050523_c001_v0001013_t0015") 2021/12/13 05:05:25 DEBUG : potato3: Deleting (id "4_z4f222d3320edbb2b7fd80e1b_f11910e3ee89ea64f_d20211213_m050517_c001_v0001097_t0022") 2021/12/13 05:05:25 DEBUG : potato3: Deleting (id "4_z4f222d3320edbb2b7fd80e1b_f11637c63c41a87a5_d20211213_m050513_c001_v0001097_t0042") --- PASS: TestServerSideMoveWithFilter (30.16s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:05:29 DEBUG : Creating backend with remote "TestB2:rclone-test-mutekir7xomeqef0labemur1" 2021/12/13 05:05:35 DEBUG : tomatoDir: Making directory sync_test.go:1215: Server side move (if possible) B2 bucket rclone-test-baqoxax8mawoseg9hefexef9 -> B2 bucket rclone-test-mutekir7xomeqef0labemur1 2021/12/13 05:05:38 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms) 2021/12/13 05:05:38 DEBUG : empty space: Unchanged skipping 2021/12/13 05:05:38 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2021/12/13 05:05:38 DEBUG : B2 bucket rclone-test-mutekir7xomeqef0labemur1: Waiting for checks to finish 2021/12/13 05:05:38 INFO : empty space: Deleted 2021/12/13 05:05:38 DEBUG : B2 bucket rclone-test-mutekir7xomeqef0labemur1: Waiting for transfers to finish 2021/12/13 05:05:38 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/12/13 05:05:38 INFO : potato2: Copied (server-side copy) 2021/12/13 05:05:39 DEBUG : potato3: sha1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2021/12/13 05:05:39 INFO : potato3: Copied (server-side copy) 2021/12/13 05:05:39 INFO : potato2: Deleted 2021/12/13 05:05:40 INFO : potato3: Deleted 2021/12/13 05:05:40 DEBUG : Creating backend with remote "TestB2:rclone-test-suwivos1nogebob1sopawoj3" 2021/12/13 05:05:41 DEBUG : tomatoDir: Making directory 2021/12/13 05:05:41 DEBUG : B2 bucket rclone-test-suwivos1nogebob1sopawoj3: Waiting for checks to finish 2021/12/13 05:05:41 DEBUG : B2 bucket rclone-test-suwivos1nogebob1sopawoj3: Waiting for transfers to finish 2021/12/13 05:05:42 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/12/13 05:05:42 INFO : potato2: Copied (server-side copy) 2021/12/13 05:05:42 DEBUG : potato3: sha1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2021/12/13 05:05:42 INFO : potato3: Copied (server-side copy) 2021/12/13 05:05:43 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2021/12/13 05:05:43 INFO : empty space: Copied (server-side copy) 2021/12/13 05:05:43 INFO : potato3: Deleted 2021/12/13 05:05:43 INFO : potato2: Deleted 2021/12/13 05:05:44 INFO : empty space: Deleted 2021/12/13 05:05:45 DEBUG : B2 bucket rclone-test-suwivos1nogebob1sopawoj3: Purge remote 2021/12/13 05:05:45 DEBUG : empty space: Deleting (id "4_z0f32ad7320edbb2b7fd80e1b_f103221ffd59c076e_d20211213_m050542_c001_v0001042_t0031") 2021/12/13 05:05:45 DEBUG : potato2: Deleting (id "4_z0f32ad7320edbb2b7fd80e1b_f11720efe148880e1_d20211213_m050542_c001_v0001013_t0003") 2021/12/13 05:05:45 DEBUG : potato3: Deleting (id "4_z0f32ad7320edbb2b7fd80e1b_f1173d9995e1bbd64_d20211213_m050542_c001_v0001033_t0042") 2021/12/13 05:05:45 DEBUG : B2 bucket rclone-test-mutekir7xomeqef0labemur1: Purge remote 2021/12/13 05:05:46 DEBUG : empty space: Deleting (id "4_z8f729d0320edbb2b7fd80e1b_f114e005284d22b75_d20211213_m050543_c001_v0001144_t0032") 2021/12/13 05:05:46 DEBUG : empty space: Deleting (id "4_z8f729d0320edbb2b7fd80e1b_f10460dc858719f8a_d20211213_m050537_c001_v0001134_t0038") 2021/12/13 05:05:46 DEBUG : potato2: Deleting (id "4_z8f729d0320edbb2b7fd80e1b_f114f9b1a0f2324cd_d20211213_m050542_c001_v0001019_t0008") 2021/12/13 05:05:46 DEBUG : potato2: Deleting (id "4_z8f729d0320edbb2b7fd80e1b_f10971e7b5d6b6344_d20211213_m050538_c001_v0001117_t0017") 2021/12/13 05:05:46 DEBUG : potato3: Deleting (id "4_z8f729d0320edbb2b7fd80e1b_f103a70243bfe60ab_d20211213_m050542_c001_v0001003_t0029") 2021/12/13 05:05:46 DEBUG : potato3: Deleting (id "4_z8f729d0320edbb2b7fd80e1b_f110c1b5451b0f5a0_d20211213_m050538_c001_v0001013_t0009") 2021/12/13 05:05:46 DEBUG : potato3: Deleting (id "4_z8f729d0320edbb2b7fd80e1b_f10460dc858719f8c_d20211213_m050537_c001_v0001134_t0018") --- PASS: TestServerSideMoveDeleteEmptySourceDirs (17.88s) === RUN TestServerSideMoveOverlap run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:05:47 DEBUG : Creating backend with remote "TestB2:rclone-test-baqoxax8mawoseg9hefexef9/rclone-move-test" 2021/12/13 05:05:48 DEBUG : Couldn't decode error response: EOF --- PASS: TestServerSideMoveOverlap (3.26s) === RUN TestSyncOverlap run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:05:50 DEBUG : Creating backend with remote "TestB2:rclone-test-baqoxax8mawoseg9hefexef9/rclone-sync-test" 2021/12/13 05:05:51 DEBUG : Couldn't decode error response: EOF --- PASS: TestSyncOverlap (0.72s) === RUN TestSyncCompareDest run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:05:51 DEBUG : Creating backend with remote "TestB2:rclone-test-baqoxax8mawoseg9hefexef9/dst" 2021/12/13 05:05:51 DEBUG : Couldn't decode error response: EOF 2021/12/13 05:05:51 DEBUG : Creating backend with remote "TestB2:rclone-test-baqoxax8mawoseg9hefexef9/CompareDest" 2021/12/13 05:05:52 DEBUG : Couldn't decode error response: EOF 2021/12/13 05:05:52 DEBUG : Couldn't decode error response: EOF 2021/12/13 05:05:52 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9 path dst: Waiting for checks to finish 2021/12/13 05:05:52 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9 path dst: Waiting for transfers to finish 2021/12/13 05:05:54 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/12/13 05:05:54 INFO : one: Copied (new) 2021/12/13 05:05:54 DEBUG : Waiting for deletions to finish 2021/12/13 05:05:54 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9 path dst: Waiting for checks to finish 2021/12/13 05:05:54 DEBUG : Couldn't decode error response: EOF 2021/12/13 05:05:54 DEBUG : one: Sizes differ (src 5 vs dst 3) 2021/12/13 05:05:54 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9 path dst: Waiting for transfers to finish 2021/12/13 05:05:55 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2021/12/13 05:05:55 INFO : one: Copied (replaced existing) 2021/12/13 05:05:55 DEBUG : Waiting for deletions to finish 2021/12/13 05:06:00 DEBUG : pacer: low level retry 1/10 (error Post "https://api001.backblazeb2.com/b2api/v1/b2_list_file_names": EOF) 2021/12/13 05:06:00 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2021/12/13 05:06:00 DEBUG : pacer: Reducing sleep to 10ms 2021/12/13 05:06:01 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9 path dst: Waiting for checks to finish 2021/12/13 05:06:01 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/12/13 05:06:01 DEBUG : one: Destination found in --compare-dest, skipping 2021/12/13 05:06:01 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9 path dst: Waiting for transfers to finish 2021/12/13 05:06:01 DEBUG : Waiting for deletions to finish 2021/12/13 05:06:01 INFO : There was nothing to transfer 2021/12/13 05:06:03 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/12/13 05:06:03 DEBUG : two: Destination found in --compare-dest, skipping 2021/12/13 05:06:03 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9 path dst: Waiting for checks to finish 2021/12/13 05:06:03 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/12/13 05:06:03 DEBUG : one: Destination found in --compare-dest, skipping 2021/12/13 05:06:03 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9 path dst: Waiting for transfers to finish 2021/12/13 05:06:03 DEBUG : Waiting for deletions to finish 2021/12/13 05:06:03 INFO : There was nothing to transfer 2021/12/13 05:06:04 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/12/13 05:06:04 DEBUG : two: Destination found in --compare-dest, skipping 2021/12/13 05:06:04 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9 path dst: Waiting for checks to finish 2021/12/13 05:06:04 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/12/13 05:06:04 DEBUG : one: Destination found in --compare-dest, skipping 2021/12/13 05:06:04 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9 path dst: Waiting for transfers to finish 2021/12/13 05:06:04 DEBUG : Waiting for deletions to finish 2021/12/13 05:06:04 INFO : There was nothing to transfer 2021/12/13 05:06:05 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 2021/12/13 05:06:05 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2021/12/13 05:06:05 DEBUG : two: Destination found in --compare-dest, skipping 2021/12/13 05:06:05 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9 path dst: Waiting for checks to finish 2021/12/13 05:06:05 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/12/13 05:06:05 DEBUG : one: Destination found in --compare-dest, skipping 2021/12/13 05:06:05 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9 path dst: Waiting for transfers to finish 2021/12/13 05:06:05 DEBUG : Waiting for deletions to finish 2021/12/13 05:06:05 INFO : There was nothing to transfer 2021/12/13 05:06:06 DEBUG : two: Sizes differ (src 5 vs dst 3) 2021/12/13 05:06:06 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9 path dst: Waiting for checks to finish 2021/12/13 05:06:06 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/12/13 05:06:06 DEBUG : one: Destination found in --compare-dest, skipping 2021/12/13 05:06:06 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9 path dst: Waiting for transfers to finish 2021/12/13 05:06:07 DEBUG : two: sha1 = a4be8877ad88f3109a6411985a1f359e10e2d930 OK 2021/12/13 05:06:07 INFO : two: Copied (new) 2021/12/13 05:06:07 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCompareDest (19.12s) === RUN TestSyncMultipleCompareDest run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:06:14 DEBUG : Creating backend with remote "TestB2:rclone-test-baqoxax8mawoseg9hefexef9/dest" 2021/12/13 05:06:15 DEBUG : Couldn't decode error response: EOF 2021/12/13 05:06:15 DEBUG : Creating backend with remote "TestB2:rclone-test-baqoxax8mawoseg9hefexef9/pre-dest1" 2021/12/13 05:06:16 DEBUG : Couldn't decode error response: EOF 2021/12/13 05:06:16 DEBUG : Creating backend with remote "TestB2:rclone-test-baqoxax8mawoseg9hefexef9/pre-dest2" 2021/12/13 05:06:16 DEBUG : Couldn't decode error response: EOF 2021/12/13 05:06:17 DEBUG : 1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:06:17 DEBUG : 1: Destination found in --compare-dest, skipping 2021/12/13 05:06:17 DEBUG : Couldn't decode error response: EOF 2021/12/13 05:06:17 DEBUG : 2: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:06:17 DEBUG : 2: Destination found in --compare-dest, skipping 2021/12/13 05:06:17 DEBUG : Couldn't decode error response: EOF 2021/12/13 05:06:17 DEBUG : Couldn't decode error response: EOF 2021/12/13 05:06:17 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9 path dest: Waiting for checks to finish 2021/12/13 05:06:17 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9 path dest: Waiting for transfers to finish 2021/12/13 05:06:19 DEBUG : 3: sha1 = 77de68daecd823babbb58edb1c8e14d7106e83bb OK 2021/12/13 05:06:19 INFO : 3: Copied (new) 2021/12/13 05:06:19 DEBUG : Waiting for deletions to finish --- PASS: TestSyncMultipleCompareDest (12.76s) === RUN TestSyncCopyDest run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:06:23 DEBUG : Creating backend with remote "TestB2:rclone-test-baqoxax8mawoseg9hefexef9/dst" 2021/12/13 05:06:24 DEBUG : Couldn't decode error response: EOF 2021/12/13 05:06:24 DEBUG : Creating backend with remote "TestB2:rclone-test-baqoxax8mawoseg9hefexef9/CopyDest" 2021/12/13 05:06:25 DEBUG : Couldn't decode error response: EOF 2021/12/13 05:06:25 DEBUG : Couldn't decode error response: EOF 2021/12/13 05:06:25 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9 path dst: Waiting for checks to finish 2021/12/13 05:06:25 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9 path dst: Waiting for transfers to finish 2021/12/13 05:06:27 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/12/13 05:06:27 INFO : one: Copied (new) 2021/12/13 05:06:27 DEBUG : Waiting for deletions to finish 2021/12/13 05:06:27 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9 path dst: Waiting for checks to finish 2021/12/13 05:06:27 DEBUG : Couldn't decode error response: EOF 2021/12/13 05:06:27 DEBUG : one: Sizes differ (src 5 vs dst 3) 2021/12/13 05:06:27 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9 path dst: Waiting for transfers to finish 2021/12/13 05:06:28 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2021/12/13 05:06:28 INFO : one: Copied (replaced existing) 2021/12/13 05:06:28 DEBUG : Waiting for deletions to finish 2021/12/13 05:06:32 DEBUG : Creating backend with remote "TestB2:rclone-test-baqoxax8mawoseg9hefexef9/BackupDir" 2021/12/13 05:06:34 DEBUG : Couldn't decode error response: EOF 2021/12/13 05:06:34 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9 path dst: Waiting for checks to finish 2021/12/13 05:06:34 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/12/13 05:06:34 DEBUG : one: Sizes differ (src 5 vs dst 3) 2021/12/13 05:06:34 DEBUG : Couldn't decode error response: EOF 2021/12/13 05:06:36 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/12/13 05:06:36 INFO : one: Copied (server-side copy) 2021/12/13 05:06:36 INFO : one: Deleted 2021/12/13 05:06:37 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2021/12/13 05:06:37 INFO : one: Copied (server-side copy) 2021/12/13 05:06:37 DEBUG : one: Destination found in --copy-dest, using server-side copy 2021/12/13 05:06:37 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9 path dst: Waiting for transfers to finish 2021/12/13 05:06:37 DEBUG : Waiting for deletions to finish 2021/12/13 05:06:38 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/12/13 05:06:39 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2021/12/13 05:06:39 INFO : two: Copied (server-side copy) 2021/12/13 05:06:39 DEBUG : two: Destination found in --copy-dest, using server-side copy 2021/12/13 05:06:39 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9 path dst: Waiting for checks to finish 2021/12/13 05:06:40 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/12/13 05:06:40 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/12/13 05:06:40 DEBUG : one: Unchanged skipping 2021/12/13 05:06:40 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9 path dst: Waiting for transfers to finish 2021/12/13 05:06:40 DEBUG : Waiting for deletions to finish 2021/12/13 05:06:40 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9 path dst: Waiting for checks to finish 2021/12/13 05:06:40 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/12/13 05:06:40 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/12/13 05:06:40 DEBUG : one: Unchanged skipping 2021/12/13 05:06:40 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/12/13 05:06:40 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/12/13 05:06:40 DEBUG : two: Unchanged skipping 2021/12/13 05:06:40 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9 path dst: Waiting for transfers to finish 2021/12/13 05:06:40 DEBUG : Waiting for deletions to finish 2021/12/13 05:06:40 INFO : There was nothing to transfer 2021/12/13 05:06:42 DEBUG : three: Sizes differ (src 7 vs dst 5) 2021/12/13 05:06:42 DEBUG : three: Destination not found in --copy-dest 2021/12/13 05:06:42 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9 path dst: Waiting for checks to finish 2021/12/13 05:06:42 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/12/13 05:06:42 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/12/13 05:06:42 DEBUG : two: Unchanged skipping 2021/12/13 05:06:43 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/12/13 05:06:43 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/12/13 05:06:43 DEBUG : one: Unchanged skipping 2021/12/13 05:06:43 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9 path dst: Waiting for transfers to finish 2021/12/13 05:06:44 DEBUG : three: sha1 = 370e613335f3cf2f19b2a2d35a86402f55c659f8 OK 2021/12/13 05:06:44 INFO : three: Copied (new) 2021/12/13 05:06:44 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCopyDest (29.79s) === RUN TestSyncBackupDir run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:07:00 DEBUG : Creating backend with remote "TestB2:rclone-test-baqoxax8mawoseg9hefexef9/dst" 2021/12/13 05:07:01 DEBUG : Couldn't decode error response: EOF 2021/12/13 05:07:01 DEBUG : Creating backend with remote "TestB2:rclone-test-baqoxax8mawoseg9hefexef9/backup" 2021/12/13 05:07:01 DEBUG : Couldn't decode error response: EOF 2021/12/13 05:07:02 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/12/13 05:07:02 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:07:02 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9 path dst: Waiting for checks to finish 2021/12/13 05:07:02 DEBUG : two: Unchanged skipping 2021/12/13 05:07:02 DEBUG : Couldn't decode error response: EOF 2021/12/13 05:07:03 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/12/13 05:07:03 INFO : one: Copied (server-side copy) 2021/12/13 05:07:04 INFO : one: Deleted 2021/12/13 05:07:04 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9 path dst: Waiting for transfers to finish 2021/12/13 05:07:06 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/12/13 05:07:06 INFO : one: Copied (new) 2021/12/13 05:07:06 DEBUG : Waiting for deletions to finish 2021/12/13 05:07:06 DEBUG : Couldn't decode error response: EOF 2021/12/13 05:07:07 DEBUG : three.txt: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/12/13 05:07:07 INFO : three.txt: Copied (server-side copy) 2021/12/13 05:07:07 INFO : three.txt: Deleted 2021/12/13 05:07:07 INFO : three.txt: Moved into backup dir 2021/12/13 05:07:11 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/12/13 05:07:11 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:07:11 DEBUG : two: Unchanged skipping 2021/12/13 05:07:11 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9 path dst: Waiting for checks to finish 2021/12/13 05:07:12 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/12/13 05:07:12 INFO : one: Copied (server-side copy) 2021/12/13 05:07:13 INFO : one: Deleted 2021/12/13 05:07:13 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9 path dst: Waiting for transfers to finish 2021/12/13 05:07:15 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2021/12/13 05:07:15 INFO : one: Copied (new) 2021/12/13 05:07:15 DEBUG : Waiting for deletions to finish 2021/12/13 05:07:20 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2021/12/13 05:07:20 INFO : three.txt: Copied (server-side copy) 2021/12/13 05:07:22 INFO : three.txt: Deleted 2021/12/13 05:07:22 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (32.95s) === RUN TestSyncBackupDirWithSuffix run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:07:29 DEBUG : Creating backend with remote "TestB2:rclone-test-baqoxax8mawoseg9hefexef9/dst" 2021/12/13 05:07:31 DEBUG : Couldn't decode error response: EOF 2021/12/13 05:07:31 DEBUG : Creating backend with remote "TestB2:rclone-test-baqoxax8mawoseg9hefexef9/backup" 2021/12/13 05:07:31 DEBUG : Couldn't decode error response: EOF 2021/12/13 05:07:32 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/12/13 05:07:32 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9 path dst: Waiting for checks to finish 2021/12/13 05:07:32 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:07:32 DEBUG : two: Unchanged skipping 2021/12/13 05:07:32 DEBUG : Couldn't decode error response: EOF 2021/12/13 05:07:34 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/12/13 05:07:34 INFO : one: Copied (server-side copy) to: one.bak 2021/12/13 05:07:34 INFO : one: Deleted 2021/12/13 05:07:34 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9 path dst: Waiting for transfers to finish 2021/12/13 05:07:36 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/12/13 05:07:36 INFO : one: Copied (new) 2021/12/13 05:07:36 DEBUG : Waiting for deletions to finish 2021/12/13 05:07:36 DEBUG : Couldn't decode error response: EOF 2021/12/13 05:07:37 DEBUG : three.txt: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/12/13 05:07:37 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2021/12/13 05:07:38 INFO : three.txt: Deleted 2021/12/13 05:07:38 INFO : three.txt: Moved into backup dir 2021/12/13 05:07:40 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9 path dst: Waiting for checks to finish 2021/12/13 05:07:40 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/12/13 05:07:40 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:07:40 DEBUG : two: Unchanged skipping 2021/12/13 05:07:41 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/12/13 05:07:41 INFO : one: Copied (server-side copy) to: one.bak 2021/12/13 05:07:41 INFO : one: Deleted 2021/12/13 05:07:41 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9 path dst: Waiting for transfers to finish 2021/12/13 05:07:44 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2021/12/13 05:07:44 INFO : one: Copied (new) 2021/12/13 05:07:44 DEBUG : Waiting for deletions to finish 2021/12/13 05:07:45 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2021/12/13 05:07:45 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2021/12/13 05:07:46 INFO : three.txt: Deleted 2021/12/13 05:07:46 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (23.85s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:07:53 DEBUG : Creating backend with remote "TestB2:rclone-test-baqoxax8mawoseg9hefexef9/dst" 2021/12/13 05:07:54 DEBUG : Couldn't decode error response: EOF 2021/12/13 05:07:54 DEBUG : Creating backend with remote "TestB2:rclone-test-baqoxax8mawoseg9hefexef9/backup" 2021/12/13 05:07:55 DEBUG : Couldn't decode error response: EOF 2021/12/13 05:07:55 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/12/13 05:07:55 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9 path dst: Waiting for checks to finish 2021/12/13 05:07:55 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:07:55 DEBUG : two: Unchanged skipping 2021/12/13 05:07:55 DEBUG : Couldn't decode error response: EOF 2021/12/13 05:07:56 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/12/13 05:07:56 INFO : one: Copied (server-side copy) to: one-2019-01-01 2021/12/13 05:08:00 INFO : one: Deleted 2021/12/13 05:08:00 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9 path dst: Waiting for transfers to finish 2021/12/13 05:08:02 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/12/13 05:08:02 INFO : one: Copied (new) 2021/12/13 05:08:02 DEBUG : Waiting for deletions to finish 2021/12/13 05:08:03 DEBUG : Couldn't decode error response: EOF 2021/12/13 05:08:05 DEBUG : three.txt: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/12/13 05:08:05 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2021/12/13 05:08:06 INFO : three.txt: Deleted 2021/12/13 05:08:06 INFO : three.txt: Moved into backup dir 2021/12/13 05:08:08 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/12/13 05:08:08 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:08:08 DEBUG : two: Unchanged skipping 2021/12/13 05:08:08 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9 path dst: Waiting for checks to finish 2021/12/13 05:08:09 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/12/13 05:08:09 INFO : one: Copied (server-side copy) to: one-2019-01-01 2021/12/13 05:08:10 INFO : one: Deleted 2021/12/13 05:08:10 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9 path dst: Waiting for transfers to finish 2021/12/13 05:08:11 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2021/12/13 05:08:11 INFO : one: Copied (new) 2021/12/13 05:08:11 DEBUG : Waiting for deletions to finish 2021/12/13 05:08:11 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2021/12/13 05:08:11 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2021/12/13 05:08:22 INFO : three.txt: Deleted 2021/12/13 05:08:22 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (39.59s) === RUN TestSyncBackupDirSuffixOnly run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:08:33 DEBUG : Creating backend with remote "TestB2:rclone-test-baqoxax8mawoseg9hefexef9/dst" 2021/12/13 05:08:34 DEBUG : Couldn't decode error response: EOF 2021/12/13 05:08:35 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/12/13 05:08:35 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:08:35 DEBUG : two: Unchanged skipping 2021/12/13 05:08:35 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9 path dst: Waiting for checks to finish 2021/12/13 05:08:35 DEBUG : Couldn't decode error response: EOF 2021/12/13 05:08:37 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/12/13 05:08:37 INFO : one: Copied (server-side copy) to: one.bak 2021/12/13 05:08:39 INFO : one: Deleted 2021/12/13 05:08:39 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9 path dst: Waiting for transfers to finish 2021/12/13 05:08:41 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/12/13 05:08:41 INFO : one: Copied (new) 2021/12/13 05:08:41 DEBUG : Waiting for deletions to finish 2021/12/13 05:08:41 DEBUG : Couldn't decode error response: EOF 2021/12/13 05:08:42 DEBUG : three.txt: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/12/13 05:08:42 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2021/12/13 05:08:43 INFO : three.txt: Deleted 2021/12/13 05:08:43 INFO : three.txt: Moved into backup dir 2021/12/13 05:08:46 DEBUG : one.bak: Excluded 2021/12/13 05:08:46 DEBUG : three.txt.bak: Excluded 2021/12/13 05:08:46 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9 path dst: Waiting for checks to finish 2021/12/13 05:08:46 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/12/13 05:08:46 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:08:46 DEBUG : two: Unchanged skipping 2021/12/13 05:08:46 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/12/13 05:08:46 INFO : one: Copied (server-side copy) to: one.bak 2021/12/13 05:08:46 INFO : one: Deleted 2021/12/13 05:08:46 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9 path dst: Waiting for transfers to finish 2021/12/13 05:08:48 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2021/12/13 05:08:48 INFO : one: Copied (new) 2021/12/13 05:08:48 DEBUG : Waiting for deletions to finish 2021/12/13 05:08:48 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2021/12/13 05:08:48 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2021/12/13 05:08:49 INFO : three.txt: Deleted 2021/12/13 05:08:49 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirSuffixOnly (34.82s) === RUN TestSyncSuffix run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:09:07 DEBUG : Creating backend with remote "TestB2:rclone-test-baqoxax8mawoseg9hefexef9/dst" 2021/12/13 05:09:09 DEBUG : Couldn't decode error response: EOF 2021/12/13 05:09:09 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/12/13 05:09:09 DEBUG : Couldn't decode error response: EOF 2021/12/13 05:09:11 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/12/13 05:09:11 INFO : one: Copied (server-side copy) to: one.bak 2021/12/13 05:09:12 INFO : one: Deleted 2021/12/13 05:09:13 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/12/13 05:09:13 INFO : one: Copied (new) 2021/12/13 05:09:13 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:09:13 DEBUG : two: Unchanged skipping 2021/12/13 05:09:13 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2021/12/13 05:09:13 DEBUG : Couldn't decode error response: EOF 2021/12/13 05:09:14 DEBUG : three.txt: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/12/13 05:09:14 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2021/12/13 05:09:15 INFO : three.txt: Deleted 2021/12/13 05:09:15 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2021/12/13 05:09:15 INFO : three.txt: Copied (new) 2021/12/13 05:09:16 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/12/13 05:09:17 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/12/13 05:09:17 INFO : one: Copied (server-side copy) to: one.bak 2021/12/13 05:09:18 INFO : one: Deleted 2021/12/13 05:09:19 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2021/12/13 05:09:19 INFO : one: Copied (new) 2021/12/13 05:09:19 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:09:19 DEBUG : two: Unchanged skipping 2021/12/13 05:09:19 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2021/12/13 05:09:20 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2021/12/13 05:09:20 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2021/12/13 05:09:20 INFO : three.txt: Deleted 2021/12/13 05:09:20 DEBUG : three.txt: sha1 = 476e1707cbd7f0497a7dc4d44f0b07b8566ffc30 OK 2021/12/13 05:09:20 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (19.60s) === RUN TestSyncSuffixKeepExtension run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:09:27 DEBUG : Creating backend with remote "TestB2:rclone-test-baqoxax8mawoseg9hefexef9/dst" 2021/12/13 05:09:28 DEBUG : Couldn't decode error response: EOF 2021/12/13 05:09:28 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/12/13 05:09:29 DEBUG : Couldn't decode error response: EOF 2021/12/13 05:09:30 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/12/13 05:09:30 INFO : one: Copied (server-side copy) to: one-2019-01-01 2021/12/13 05:09:31 INFO : one: Deleted 2021/12/13 05:09:32 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/12/13 05:09:32 INFO : one: Copied (new) 2021/12/13 05:09:32 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:09:32 DEBUG : two: Unchanged skipping 2021/12/13 05:09:32 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2021/12/13 05:09:32 DEBUG : Couldn't decode error response: EOF 2021/12/13 05:09:33 DEBUG : three.txt: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/12/13 05:09:33 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2021/12/13 05:09:33 INFO : three.txt: Deleted 2021/12/13 05:09:34 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2021/12/13 05:09:34 INFO : three.txt: Copied (new) 2021/12/13 05:09:34 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/12/13 05:09:35 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/12/13 05:09:35 INFO : one: Copied (server-side copy) to: one-2019-01-01 2021/12/13 05:09:36 INFO : one: Deleted 2021/12/13 05:09:36 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2021/12/13 05:09:36 INFO : one: Copied (new) 2021/12/13 05:09:36 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:09:36 DEBUG : two: Unchanged skipping 2021/12/13 05:09:37 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2021/12/13 05:09:38 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2021/12/13 05:09:38 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2021/12/13 05:09:38 INFO : three.txt: Deleted 2021/12/13 05:09:39 DEBUG : three.txt: sha1 = 476e1707cbd7f0497a7dc4d44f0b07b8566ffc30 OK 2021/12/13 05:09:39 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (18.58s) === RUN TestSyncUTFNorm run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:09:43 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:09:43 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2021/12/13 05:09:43 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish 2021/12/13 05:09:44 DEBUG : Testêé: sha1 = a54d88e06612d820bc3be72877c74f257b561b19 OK 2021/12/13 05:09:44 INFO : Testêé: Copied (replaced existing) to: Testêé 2021/12/13 05:09:44 DEBUG : Waiting for deletions to finish --- PASS: TestSyncUTFNorm (3.73s) === RUN TestSyncImmutable run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:09:46 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:09:46 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish 2021/12/13 05:09:47 DEBUG : existing: sha1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2021/12/13 05:09:47 INFO : existing: Copied (new) 2021/12/13 05:09:47 DEBUG : Waiting for deletions to finish 2021/12/13 05:09:47 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2021/12/13 05:09:47 ERROR : existing: Source and destination exist but do not match: immutable file modified 2021/12/13 05:09:47 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:09:47 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish 2021/12/13 05:09:47 ERROR : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: not deleting files as there were IO errors 2021/12/13 05:09:47 ERROR : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: not deleting directories as there were IO errors --- PASS: TestSyncImmutable (2.95s) === RUN TestSyncIgnoreCase run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:09:50 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:09:50 DEBUG : existing: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:09:50 DEBUG : existing: Unchanged skipping 2021/12/13 05:09:50 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish 2021/12/13 05:09:50 DEBUG : Waiting for deletions to finish 2021/12/13 05:09:50 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreCase (2.67s) === RUN TestMaxTransfer === RUN TestMaxTransfer/Hard run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" sync_test.go:1989: This test only runs on local === RUN TestMaxTransfer/Soft run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" sync_test.go:1989: This test only runs on local === RUN TestMaxTransfer/Cautious run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" sync_test.go:1989: This test only runs on local --- PASS: TestMaxTransfer (1.11s) --- SKIP: TestMaxTransfer/Hard (0.38s) --- SKIP: TestMaxTransfer/Soft (0.36s) --- SKIP: TestMaxTransfer/Cautious (0.37s) === RUN TestSyncConcurrentDelete run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:10:29 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:10:29 DEBUG : both0: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:10:29 DEBUG : both10: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:10:29 DEBUG : both1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:10:29 DEBUG : both11: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:10:29 DEBUG : both11: Unchanged skipping 2021/12/13 05:10:29 DEBUG : both10: Unchanged skipping 2021/12/13 05:10:29 DEBUG : both12: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:10:29 DEBUG : both12: Unchanged skipping 2021/12/13 05:10:29 DEBUG : both13: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:10:29 DEBUG : both14: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:10:29 DEBUG : both14: Unchanged skipping 2021/12/13 05:10:29 DEBUG : both15: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:10:29 DEBUG : both15: Unchanged skipping 2021/12/13 05:10:29 DEBUG : both16: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:10:29 DEBUG : both16: Unchanged skipping 2021/12/13 05:10:29 DEBUG : both17: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:10:29 DEBUG : both17: Unchanged skipping 2021/12/13 05:10:29 DEBUG : both18: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:10:29 DEBUG : both18: Unchanged skipping 2021/12/13 05:10:29 DEBUG : both19: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:10:29 DEBUG : both19: Unchanged skipping 2021/12/13 05:10:29 DEBUG : both2: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:10:29 DEBUG : both2: Unchanged skipping 2021/12/13 05:10:29 DEBUG : both3: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:10:29 DEBUG : both3: Unchanged skipping 2021/12/13 05:10:29 DEBUG : both4: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:10:29 DEBUG : both4: Unchanged skipping 2021/12/13 05:10:29 DEBUG : both5: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:10:29 DEBUG : both5: Unchanged skipping 2021/12/13 05:10:29 DEBUG : both6: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:10:29 DEBUG : both6: Unchanged skipping 2021/12/13 05:10:29 DEBUG : both7: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:10:29 DEBUG : both7: Unchanged skipping 2021/12/13 05:10:29 DEBUG : both1: Unchanged skipping 2021/12/13 05:10:29 DEBUG : both8: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:10:29 DEBUG : both8: Unchanged skipping 2021/12/13 05:10:29 DEBUG : both13: Unchanged skipping 2021/12/13 05:10:29 DEBUG : both9: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:10:29 DEBUG : both9: Unchanged skipping 2021/12/13 05:10:29 DEBUG : both0: Unchanged skipping 2021/12/13 05:10:29 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish 2021/12/13 05:10:29 DEBUG : Waiting for deletions to finish 2021/12/13 05:10:29 INFO : only0: Deleted 2021/12/13 05:10:30 INFO : only9: Deleted 2021/12/13 05:10:30 INFO : only11: Deleted 2021/12/13 05:10:31 INFO : only2: Deleted 2021/12/13 05:10:32 INFO : only4: Deleted 2021/12/13 05:10:33 INFO : only7: Deleted 2021/12/13 05:10:33 INFO : only3: Deleted 2021/12/13 05:10:33 INFO : only12: Deleted 2021/12/13 05:10:34 INFO : only1: Deleted 2021/12/13 05:10:34 INFO : only13: Deleted 2021/12/13 05:10:34 INFO : only14: Deleted 2021/12/13 05:10:34 INFO : only15: Deleted 2021/12/13 05:10:35 INFO : only8: Deleted 2021/12/13 05:10:36 INFO : only10: Deleted 2021/12/13 05:10:37 INFO : only17: Deleted 2021/12/13 05:10:38 INFO : only18: Deleted 2021/12/13 05:10:39 INFO : only5: Deleted 2021/12/13 05:10:40 INFO : only6: Deleted 2021/12/13 05:10:42 INFO : only16: Deleted 2021/12/13 05:10:46 INFO : only19: Deleted 2021/12/13 05:10:46 INFO : There was nothing to transfer --- PASS: TestSyncConcurrentDelete (67.17s) === RUN TestSyncConcurrentTruncate run.go:181: Remote "B2 bucket rclone-test-baqoxax8mawoseg9hefexef9", Local "Local file system at /tmp/rclone1166295492", Modify Window "1ms" 2021/12/13 05:11:40 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for checks to finish 2021/12/13 05:11:40 DEBUG : both1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:11:40 DEBUG : both1: Unchanged skipping 2021/12/13 05:11:40 DEBUG : both11: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:11:40 DEBUG : both11: Unchanged skipping 2021/12/13 05:11:40 DEBUG : both0: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:11:40 DEBUG : both0: Unchanged skipping 2021/12/13 05:11:40 DEBUG : both13: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:11:40 DEBUG : both13: Unchanged skipping 2021/12/13 05:11:40 DEBUG : both14: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:11:40 DEBUG : both15: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:11:40 DEBUG : both15: Unchanged skipping 2021/12/13 05:11:40 DEBUG : both16: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:11:40 DEBUG : both16: Unchanged skipping 2021/12/13 05:11:40 DEBUG : both17: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:11:40 DEBUG : both17: Unchanged skipping 2021/12/13 05:11:40 DEBUG : both18: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:11:40 DEBUG : both18: Unchanged skipping 2021/12/13 05:11:40 DEBUG : both19: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:11:40 DEBUG : both19: Unchanged skipping 2021/12/13 05:11:40 DEBUG : both2: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:11:40 DEBUG : both2: Unchanged skipping 2021/12/13 05:11:40 DEBUG : both3: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:11:40 DEBUG : both3: Unchanged skipping 2021/12/13 05:11:40 DEBUG : both4: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:11:40 DEBUG : both4: Unchanged skipping 2021/12/13 05:11:40 DEBUG : both5: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:11:40 DEBUG : both5: Unchanged skipping 2021/12/13 05:11:40 DEBUG : both6: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:11:40 DEBUG : both6: Unchanged skipping 2021/12/13 05:11:40 DEBUG : both7: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:11:40 DEBUG : both7: Unchanged skipping 2021/12/13 05:11:40 DEBUG : both8: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:11:40 DEBUG : both8: Unchanged skipping 2021/12/13 05:11:40 DEBUG : both9: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:11:40 DEBUG : both9: Unchanged skipping 2021/12/13 05:11:40 DEBUG : only0: Sizes differ (src 0 vs dst 6) 2021/12/13 05:11:40 DEBUG : only1: Sizes differ (src 0 vs dst 6) 2021/12/13 05:11:40 DEBUG : only10: Sizes differ (src 0 vs dst 6) 2021/12/13 05:11:40 DEBUG : only11: Sizes differ (src 0 vs dst 6) 2021/12/13 05:11:40 DEBUG : only12: Sizes differ (src 0 vs dst 6) 2021/12/13 05:11:40 DEBUG : only13: Sizes differ (src 0 vs dst 6) 2021/12/13 05:11:40 DEBUG : only14: Sizes differ (src 0 vs dst 6) 2021/12/13 05:11:40 DEBUG : only15: Sizes differ (src 0 vs dst 6) 2021/12/13 05:11:40 DEBUG : only16: Sizes differ (src 0 vs dst 6) 2021/12/13 05:11:40 DEBUG : only17: Sizes differ (src 0 vs dst 6) 2021/12/13 05:11:40 DEBUG : only18: Sizes differ (src 0 vs dst 6) 2021/12/13 05:11:40 DEBUG : only19: Sizes differ (src 0 vs dst 6) 2021/12/13 05:11:40 DEBUG : only2: Sizes differ (src 0 vs dst 6) 2021/12/13 05:11:40 DEBUG : only3: Sizes differ (src 0 vs dst 6) 2021/12/13 05:11:40 DEBUG : only4: Sizes differ (src 0 vs dst 6) 2021/12/13 05:11:40 DEBUG : only5: Sizes differ (src 0 vs dst 6) 2021/12/13 05:11:40 DEBUG : only6: Sizes differ (src 0 vs dst 6) 2021/12/13 05:11:40 DEBUG : only7: Sizes differ (src 0 vs dst 6) 2021/12/13 05:11:40 DEBUG : only8: Sizes differ (src 0 vs dst 6) 2021/12/13 05:11:40 DEBUG : only9: Sizes differ (src 0 vs dst 6) 2021/12/13 05:11:40 DEBUG : both14: Unchanged skipping 2021/12/13 05:11:40 DEBUG : both10: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:11:40 DEBUG : both10: Unchanged skipping 2021/12/13 05:11:40 DEBUG : both12: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/13 05:11:40 DEBUG : both12: Unchanged skipping 2021/12/13 05:11:40 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Waiting for transfers to finish 2021/12/13 05:11:41 DEBUG : only0: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2021/12/13 05:11:41 INFO : only0: Copied (replaced existing) 2021/12/13 05:11:41 DEBUG : only10: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2021/12/13 05:11:41 INFO : only10: Copied (replaced existing) 2021/12/13 05:11:41 DEBUG : only11: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2021/12/13 05:11:41 INFO : only11: Copied (replaced existing) 2021/12/13 05:11:41 DEBUG : only13: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2021/12/13 05:11:41 INFO : only13: Copied (replaced existing) 2021/12/13 05:11:41 DEBUG : only1: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2021/12/13 05:11:41 INFO : only1: Copied (replaced existing) 2021/12/13 05:11:41 DEBUG : only12: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2021/12/13 05:11:41 INFO : only12: Copied (replaced existing) 2021/12/13 05:11:41 DEBUG : only15: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2021/12/13 05:11:41 INFO : only15: Copied (replaced existing) 2021/12/13 05:11:42 DEBUG : only14: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2021/12/13 05:11:42 INFO : only14: Copied (replaced existing) 2021/12/13 05:11:42 DEBUG : only18: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2021/12/13 05:11:42 INFO : only18: Copied (replaced existing) 2021/12/13 05:11:42 DEBUG : only2: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2021/12/13 05:11:42 INFO : only2: Copied (replaced existing) 2021/12/13 05:11:42 DEBUG : only3: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2021/12/13 05:11:42 INFO : only3: Copied (replaced existing) 2021/12/13 05:11:42 DEBUG : only16: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2021/12/13 05:11:42 INFO : only16: Copied (replaced existing) 2021/12/13 05:11:43 DEBUG : only4: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2021/12/13 05:11:43 INFO : only4: Copied (replaced existing) 2021/12/13 05:11:43 DEBUG : only6: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2021/12/13 05:11:43 INFO : only6: Copied (replaced existing) 2021/12/13 05:11:43 DEBUG : only5: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2021/12/13 05:11:43 INFO : only5: Copied (replaced existing) 2021/12/13 05:11:43 DEBUG : only7: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2021/12/13 05:11:43 INFO : only7: Copied (replaced existing) 2021/12/13 05:11:43 DEBUG : only17: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2021/12/13 05:11:43 INFO : only17: Copied (replaced existing) 2021/12/13 05:11:43 DEBUG : only9: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2021/12/13 05:11:43 INFO : only9: Copied (replaced existing) 2021/12/13 05:11:44 DEBUG : only19: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2021/12/13 05:11:44 INFO : only19: Copied (replaced existing) 2021/12/13 05:11:44 DEBUG : only8: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2021/12/13 05:11:44 INFO : only8: Copied (replaced existing) 2021/12/13 05:11:44 DEBUG : Waiting for deletions to finish --- PASS: TestSyncConcurrentTruncate (76.64s) PASS 2021/12/13 05:12:16 DEBUG : B2 bucket rclone-test-baqoxax8mawoseg9hefexef9: Purge remote 2021/12/13 05:12:17 DEBUG : BackupDir/one: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f114cea06861c09b8_d20211213_m050645_c001_v0001130_t0035") 2021/12/13 05:12:17 DEBUG : BackupDir/one: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f118a359fb7e80e40_d20211213_m050635_c001_v0001097_t0042") 2021/12/13 05:12:17 DEBUG : CompareDest/one: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f102e89225c2916e6_d20211213_m050607_c001_v0001018_t0038") 2021/12/13 05:12:17 DEBUG : CompareDest/one: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb7427_d20211213_m050557_c001_v0001030_t0040") 2021/12/13 05:12:17 DEBUG : CompareDest/two: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1176a22d14165f00_d20211213_m050608_c001_v0001043_t0055") 2021/12/13 05:12:17 DEBUG : CompareDest/two: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf3805_d20211213_m050602_c001_v0001005_t0030") 2021/12/13 05:12:17 DEBUG : CopyDest/one: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f10422b1cd6a9230d_d20211213_m050645_c001_v0001117_t0011") 2021/12/13 05:12:17 DEBUG : CopyDest/one: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf3835_d20211213_m050631_c001_v0001005_t0032") 2021/12/13 05:12:17 DEBUG : CopyDest/three: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1124b8da6f2030e9_d20211213_m050647_c001_v0001113_t0046") 2021/12/13 05:12:17 DEBUG : CopyDest/three: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf3846_d20211213_m050641_c001_v0001005_t0001") 2021/12/13 05:12:17 DEBUG : CopyDest/two: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f11489da21bfadacd_d20211213_m050650_c001_v0001091_t0056") 2021/12/13 05:12:17 DEBUG : CopyDest/two: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb7460_d20211213_m050638_c001_v0001030_t0005") 2021/12/13 05:12:17 DEBUG : EXISTING: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f10125130be76aad2_d20211213_m050950_c001_v0001117_t0050") 2021/12/13 05:12:17 DEBUG : EXISTING: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf3914_d20211213_m050949_c001_v0001005_t0027") 2021/12/13 05:12:17 DEBUG : Testêé: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f118a359fb7e819b2_d20211213_m050945_c001_v0001097_t0015") 2021/12/13 05:12:18 DEBUG : Testêé: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf390e_d20211213_m050944_c001_v0001005_t0019") 2021/12/13 05:12:18 DEBUG : Testêé: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb757c_d20211213_m050943_c001_v0001030_t0007") 2021/12/13 05:12:18 DEBUG : a/potato2: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f102aff3d74325f98_d20211213_m050303_c001_v0001044_t0050") 2021/12/13 05:12:18 DEBUG : a/potato2: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb7309_d20211213_m050302_c001_v0001030_t0037") 2021/12/13 05:12:18 DEBUG : a/potato2: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1145b906a5854602_d20211213_m050255_c001_v0001039_t0058") 2021/12/13 05:12:18 DEBUG : a/potato2: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb72f8_d20211213_m050250_c001_v0001030_t0003") 2021/12/13 05:12:18 DEBUG : a/potato2: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f107520296348cbd7_d20211213_m050131_c001_v0001039_t0039") 2021/12/13 05:12:18 DEBUG : a/potato2: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb729d_d20211213_m050128_c001_v0001030_t0040") 2021/12/13 05:12:18 DEBUG : b/potato: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f10672878556dddd4_d20211213_m050303_c001_v0001146_t0049") 2021/12/13 05:12:18 DEBUG : b/potato: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb7302_d20211213_m050259_c001_v0001030_t0040") 2021/12/13 05:12:18 DEBUG : b/potato: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f11409ebd4d7b710f_d20211213_m050251_c001_v0001117_t0004") 2021/12/13 05:12:18 DEBUG : b/potato: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb72f2_d20211213_m050248_c001_v0001030_t0004") 2021/12/13 05:12:18 DEBUG : b/potato: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f113e26a33a611cd7_d20211213_m050129_c001_v0001117_t0026") 2021/12/13 05:12:18 DEBUG : b/potato: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb7298_d20211213_m050126_c001_v0001030_t0009") 2021/12/13 05:12:18 DEBUG : backup/one: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1010911da545abd7_d20211213_m050722_c001_v0001022_t0004") 2021/12/13 05:12:18 DEBUG : backup/one: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1180a2bce8fef5fd_d20211213_m050712_c001_v0001165_t0050") 2021/12/13 05:12:18 DEBUG : backup/one: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f118c66c153dce28c_d20211213_m050703_c001_v0001033_t0027") 2021/12/13 05:12:18 DEBUG : backup/one-2019-01-01: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f117592d80eb027f7_d20211213_m050823_c001_v0001130_t0050") 2021/12/13 05:12:18 DEBUG : backup/one-2019-01-01: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110437195063bde1_d20211213_m050809_c001_v0001090_t0054") 2021/12/13 05:12:18 DEBUG : backup/one-2019-01-01: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f107458016443245b_d20211213_m050756_c001_v0001009_t0004") 2021/12/13 05:12:18 DEBUG : backup/one.bak: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f102a9b817b7636fe_d20211213_m050746_c001_v0001134_t0004") 2021/12/13 05:12:19 DEBUG : backup/one.bak: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1117fff2c96f8550_d20211213_m050740_c001_v0001015_t0007") 2021/12/13 05:12:19 DEBUG : backup/one.bak: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1020e008c9a65c5e_d20211213_m050733_c001_v0001113_t0047") 2021/12/13 05:12:19 DEBUG : backup/three-2019-01-01.txt: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f104d8e80a7e8c4c4_d20211213_m050823_c001_v0001041_t0048") 2021/12/13 05:12:19 DEBUG : backup/three-2019-01-01.txt: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1193597cf2f87609_d20211213_m050811_c001_v0001130_t0012") 2021/12/13 05:12:19 DEBUG : backup/three-2019-01-01.txt: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110594e0151b8d29_d20211213_m050803_c001_v0001092_t0040") 2021/12/13 05:12:19 DEBUG : backup/three.txt: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f112beda380882e62_d20211213_m050723_c001_v0001039_t0013") 2021/12/13 05:12:19 DEBUG : backup/three.txt: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1122c633fd021c9b_d20211213_m050717_c001_v0001117_t0023") 2021/12/13 05:12:19 DEBUG : backup/three.txt: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1094088b1c2b2807_d20211213_m050706_c001_v0001144_t0010") 2021/12/13 05:12:19 DEBUG : backup/three.txt.bak: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f11008e121310c89c_d20211213_m050747_c001_v0001146_t0000") 2021/12/13 05:12:19 DEBUG : backup/three.txt.bak: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f102537d4c1de39b1_d20211213_m050744_c001_v0001146_t0053") 2021/12/13 05:12:19 DEBUG : backup/three.txt.bak: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f112ec0cec56196ad_d20211213_m050736_c001_v0001097_t0004") 2021/12/13 05:12:19 DEBUG : both0: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f11853f8dde8ad1f3_d20211213_m051144_c001_v0001154_t0007") 2021/12/13 05:12:19 DEBUG : both0: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb75de_d20211213_m051100_c001_v0001030_t0013") 2021/12/13 05:12:19 DEBUG : both0: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f104bbef521d76ed4_d20211213_m051047_c001_v0001040_t0043") 2021/12/13 05:12:20 DEBUG : both0: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb7588_d20211213_m050953_c001_v0001030_t0042") 2021/12/13 05:12:20 DEBUG : both1: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1126ed7d552bb735_d20211213_m051145_c001_v0001145_t0001") 2021/12/13 05:12:20 DEBUG : both1: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb75e2_d20211213_m051103_c001_v0001030_t0044") 2021/12/13 05:12:20 DEBUG : both1: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f111d51982087ddaa_d20211213_m051048_c001_v0001150_t0002") 2021/12/13 05:12:20 DEBUG : both1: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb758d_d20211213_m050955_c001_v0001030_t0014") 2021/12/13 05:12:20 DEBUG : both10: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1136aa0e578ed05d_d20211213_m051145_c001_v0001144_t0019") 2021/12/13 05:12:20 DEBUG : both10: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb75fd_d20211213_m051120_c001_v0001030_t0002") 2021/12/13 05:12:20 DEBUG : both10: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f115f66f09c966de7_d20211213_m051048_c001_v0001145_t0006") 2021/12/13 05:12:20 DEBUG : both10: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb75aa_d20211213_m051008_c001_v0001030_t0013") 2021/12/13 05:12:20 DEBUG : both11: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f109723f23e93cd51_d20211213_m051146_c001_v0001000_t0024") 2021/12/13 05:12:20 DEBUG : both11: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb75ff_d20211213_m051121_c001_v0001030_t0017") 2021/12/13 05:12:20 DEBUG : both11: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f10855fa25476f763_d20211213_m051049_c001_v0001131_t0006") 2021/12/13 05:12:20 DEBUG : both11: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb75ac_d20211213_m051011_c001_v0001030_t0010") 2021/12/13 05:12:20 DEBUG : both12: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f10438fcc1e1c8765_d20211213_m051147_c001_v0001097_t0054") 2021/12/13 05:12:20 DEBUG : both12: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb7603_d20211213_m051123_c001_v0001030_t0026") 2021/12/13 05:12:20 DEBUG : both12: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1026d22be3b70727_d20211213_m051049_c001_v0001144_t0020") 2021/12/13 05:12:20 DEBUG : both12: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb75b0_d20211213_m051016_c001_v0001030_t0016") 2021/12/13 05:12:20 DEBUG : both13: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1074c7af93a5dc44_d20211213_m051147_c001_v0001117_t0043") 2021/12/13 05:12:20 DEBUG : both13: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb760b_d20211213_m051127_c001_v0001030_t0030") 2021/12/13 05:12:20 DEBUG : both13: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1014611af3bdcccc_d20211213_m051050_c001_v0001113_t0001") 2021/12/13 05:12:20 DEBUG : both13: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb75b6_d20211213_m051018_c001_v0001030_t0014") 2021/12/13 05:12:21 DEBUG : both14: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1077360dfe9752d7_d20211213_m051148_c001_v0001137_t0023") 2021/12/13 05:12:21 DEBUG : both14: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb760e_d20211213_m051128_c001_v0001030_t0024") 2021/12/13 05:12:21 DEBUG : both14: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116c1175330d130d_d20211213_m051051_c001_v0001009_t0042") 2021/12/13 05:12:21 DEBUG : both14: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb75b9_d20211213_m051022_c001_v0001030_t0003") 2021/12/13 05:12:21 DEBUG : both15: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f100d7ba0819a5f06_d20211213_m051148_c001_v0001033_t0002") 2021/12/13 05:12:21 DEBUG : both15: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb7615_d20211213_m051130_c001_v0001030_t0042") 2021/12/13 05:12:21 DEBUG : both15: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f106ce34d16f9306e_d20211213_m051051_c001_v0001092_t0005") 2021/12/13 05:12:21 DEBUG : both15: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb75bb_d20211213_m051023_c001_v0001030_t0015") 2021/12/13 05:12:21 DEBUG : both16: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1003a3ec10bf074e_d20211213_m051149_c001_v0001032_t0017") 2021/12/13 05:12:21 DEBUG : both16: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb761b_d20211213_m051131_c001_v0001030_t0040") 2021/12/13 05:12:21 DEBUG : both16: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f10460dc85871a557_d20211213_m051052_c001_v0001134_t0045") 2021/12/13 05:12:21 DEBUG : both16: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb75be_d20211213_m051024_c001_v0001030_t0001") 2021/12/13 05:12:21 DEBUG : both17: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1020dc2ae26fe77c_d20211213_m051150_c001_v0001026_t0015") 2021/12/13 05:12:21 DEBUG : both17: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb761f_d20211213_m051133_c001_v0001030_t0024") 2021/12/13 05:12:21 DEBUG : both17: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f10228ac3075351f8_d20211213_m051052_c001_v0001137_t0046") 2021/12/13 05:12:21 DEBUG : both17: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb75c0_d20211213_m051025_c001_v0001030_t0033") 2021/12/13 05:12:21 DEBUG : both18: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f10931af4834b281c_d20211213_m051151_c001_v0001145_t0039") 2021/12/13 05:12:21 DEBUG : both18: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb7628_d20211213_m051135_c001_v0001030_t0040") 2021/12/13 05:12:21 DEBUG : both18: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1173693484326a02_d20211213_m051053_c001_v0001136_t0024") 2021/12/13 05:12:21 DEBUG : both18: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb75c3_d20211213_m051026_c001_v0001030_t0037") 2021/12/13 05:12:22 DEBUG : both19: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f119a02d4ec2871ba_d20211213_m051152_c001_v0001005_t0040") 2021/12/13 05:12:22 DEBUG : both19: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb762f_d20211213_m051136_c001_v0001030_t0009") 2021/12/13 05:12:22 DEBUG : both19: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f111db45eb5dc3d39_d20211213_m051053_c001_v0001040_t0032") 2021/12/13 05:12:22 DEBUG : both19: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb75c5_d20211213_m051027_c001_v0001030_t0011") 2021/12/13 05:12:22 DEBUG : both2: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f114f9b1a0f232643_d20211213_m051152_c001_v0001019_t0000") 2021/12/13 05:12:22 DEBUG : both2: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb75e4_d20211213_m051105_c001_v0001030_t0000") 2021/12/13 05:12:22 DEBUG : both2: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f10900580404c9fd7_d20211213_m051053_c001_v0001134_t0026") 2021/12/13 05:12:22 DEBUG : both2: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb758f_d20211213_m050956_c001_v0001030_t0023") 2021/12/13 05:12:22 DEBUG : both3: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f11910e3ee89ebfb0_d20211213_m051153_c001_v0001097_t0030") 2021/12/13 05:12:22 DEBUG : both3: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb75e8_d20211213_m051106_c001_v0001030_t0004") 2021/12/13 05:12:22 DEBUG : both3: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f10326671cc7c9b8c_d20211213_m051054_c001_v0001120_t0055") 2021/12/13 05:12:22 DEBUG : both3: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb7591_d20211213_m050957_c001_v0001030_t0000") 2021/12/13 05:12:22 DEBUG : both4: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f114a8573bad0408f_d20211213_m051154_c001_v0001029_t0011") 2021/12/13 05:12:22 DEBUG : both4: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb75ed_d20211213_m051110_c001_v0001030_t0039") 2021/12/13 05:12:22 DEBUG : both4: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f106528df521b1b04_d20211213_m051055_c001_v0001004_t0018") 2021/12/13 05:12:22 DEBUG : both4: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb7595_d20211213_m050958_c001_v0001030_t0022") 2021/12/13 05:12:22 DEBUG : both5: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f119c6d64e7ba4f85_d20211213_m051154_c001_v0001117_t0051") 2021/12/13 05:12:22 DEBUG : both5: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb75f0_d20211213_m051112_c001_v0001030_t0044") 2021/12/13 05:12:23 DEBUG : both5: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f10763279e23a5785_d20211213_m051055_c001_v0001013_t0017") 2021/12/13 05:12:23 DEBUG : both5: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb7599_d20211213_m050959_c001_v0001030_t0039") 2021/12/13 05:12:23 DEBUG : both6: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f115e6aa75fccd4bc_d20211213_m051155_c001_v0001017_t0014") 2021/12/13 05:12:23 DEBUG : both6: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb75f2_d20211213_m051113_c001_v0001030_t0015") 2021/12/13 05:12:23 DEBUG : both6: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f101201c18327c503_d20211213_m051056_c001_v0001097_t0049") 2021/12/13 05:12:23 DEBUG : both6: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb759d_d20211213_m051001_c001_v0001030_t0022") 2021/12/13 05:12:23 DEBUG : both7: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1050452f3ae4985d_d20211213_m051156_c001_v0001097_t0034") 2021/12/13 05:12:23 DEBUG : both7: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb75f4_d20211213_m051115_c001_v0001030_t0001") 2021/12/13 05:12:23 DEBUG : both7: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1058bf37684b12e2_d20211213_m051058_c001_v0001131_t0028") 2021/12/13 05:12:23 DEBUG : both7: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb759f_d20211213_m051002_c001_v0001030_t0024") 2021/12/13 05:12:23 DEBUG : both8: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f100e1ff1d823acfb_d20211213_m051157_c001_v0001117_t0045") 2021/12/13 05:12:23 DEBUG : both8: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb75f6_d20211213_m051116_c001_v0001030_t0039") 2021/12/13 05:12:23 DEBUG : both8: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f115fde9e3c80f44e_d20211213_m051059_c001_v0001117_t0001") 2021/12/13 05:12:23 DEBUG : both8: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb75a3_d20211213_m051004_c001_v0001030_t0021") 2021/12/13 05:12:23 DEBUG : both9: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f11179d10597c4ee7_d20211213_m051159_c001_v0001117_t0036") 2021/12/13 05:12:23 DEBUG : both9: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb75fa_d20211213_m051117_c001_v0001030_t0007") 2021/12/13 05:12:23 DEBUG : both9: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f113e08a8f71e3309_d20211213_m051059_c001_v0001136_t0044") 2021/12/13 05:12:23 DEBUG : both9: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb75a8_d20211213_m051006_c001_v0001030_t0002") 2021/12/13 05:12:23 DEBUG : c/non empty space: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f10612c8f74c0c43a_d20211213_m050304_c001_v0001134_t0042") 2021/12/13 05:12:23 DEBUG : c/non empty space: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb7306_d20211213_m050300_c001_v0001030_t0033") 2021/12/13 05:12:23 DEBUG : c/non empty space: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f111cee02c7fb71df_d20211213_m050257_c001_v0001030_t0023") 2021/12/13 05:12:23 DEBUG : c/non empty space: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb72f4_d20211213_m050248_c001_v0001030_t0013") 2021/12/13 05:12:24 DEBUG : c/non empty space: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f103b50ab998afa30_d20211213_m050132_c001_v0001030_t0034") 2021/12/13 05:12:24 DEBUG : c/non empty space: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb729b_d20211213_m050127_c001_v0001030_t0028") 2021/12/13 05:12:24 DEBUG : check sum: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1185e9f3785434ba_d20211213_m050111_c001_v0001117_t0004") 2021/12/13 05:12:24 DEBUG : check sum: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb7282_d20211213_m050110_c001_v0001030_t0044") 2021/12/13 05:12:24 DEBUG : dest/3: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1025c3755484aabf_d20211213_m050619_c001_v0001120_t0012") 2021/12/13 05:12:24 DEBUG : dest/3: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f10612c8f74c0c596_d20211213_m050618_c001_v0001134_t0012") 2021/12/13 05:12:24 DEBUG : dst/one: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f11119a764b2239b3_d20211213_m050939_c001_v0001154_t0017") 2021/12/13 05:12:24 DEBUG : dst/one: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110b7a4d97b81b7d_d20211213_m050936_c001_v0001091_t0032") 2021/12/13 05:12:24 DEBUG : dst/one: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f101201c18327c09b_d20211213_m050935_c001_v0001097_t0033") 2021/12/13 05:12:24 DEBUG : dst/one: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110b7a4d97b81b74_d20211213_m050931_c001_v0001091_t0009") 2021/12/13 05:12:24 DEBUG : dst/one: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f10860b456dd808d9_d20211213_m050930_c001_v0001146_t0044") 2021/12/13 05:12:24 DEBUG : dst/one: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf38f3_d20211213_m050924_c001_v0001005_t0006") 2021/12/13 05:12:24 DEBUG : dst/one: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f104441de3c9aac37_d20211213_m050921_c001_v0001130_t0023") 2021/12/13 05:12:24 DEBUG : dst/one: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f100ed37c44d7ddb6_d20211213_m050918_c001_v0001146_t0050") 2021/12/13 05:12:24 DEBUG : dst/one: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f107df5ba89566afb_d20211213_m050917_c001_v0001097_t0036") 2021/12/13 05:12:24 DEBUG : dst/one: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f100ed37c44d7dd9b_d20211213_m050912_c001_v0001146_t0051") 2021/12/13 05:12:24 DEBUG : dst/one: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f109545e39d608ca4_d20211213_m050911_c001_v0001019_t0009") 2021/12/13 05:12:24 DEBUG : dst/one: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb7536_d20211213_m050904_c001_v0001030_t0042") 2021/12/13 05:12:24 DEBUG : dst/one: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f118d3917596a5962_d20211213_m050849_c001_v0001000_t0004") 2021/12/13 05:12:24 DEBUG : dst/one: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f10931af4834b243e_d20211213_m050847_c001_v0001145_t0013") 2021/12/13 05:12:24 DEBUG : dst/one: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f11541b30d6106d61_d20211213_m050846_c001_v0001128_t0014") 2021/12/13 05:12:25 DEBUG : dst/one: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f10931af4834b2424_d20211213_m050840_c001_v0001145_t0013") 2021/12/13 05:12:25 DEBUG : dst/one: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f10338b4f884dfdb6_d20211213_m050837_c001_v0001021_t0020") 2021/12/13 05:12:25 DEBUG : dst/one: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb750e_d20211213_m050830_c001_v0001030_t0024") 2021/12/13 05:12:25 DEBUG : dst/one: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f105e40ffbdeefee9_d20211213_m050824_c001_v0001040_t0031") 2021/12/13 05:12:25 DEBUG : dst/one: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f10553dcc92d33531_d20211213_m050811_c001_v0001038_t0017") 2021/12/13 05:12:25 DEBUG : dst/one: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1137a33dabab2abd_d20211213_m050810_c001_v0001161_t0022") 2021/12/13 05:12:25 DEBUG : dst/one: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f10553dcc92d33518_d20211213_m050801_c001_v0001038_t0010") 2021/12/13 05:12:25 DEBUG : dst/one: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f100cf0f5376a85a4_d20211213_m050757_c001_v0001097_t0012") 2021/12/13 05:12:25 DEBUG : dst/one: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb74ce_d20211213_m050750_c001_v0001030_t0011") 2021/12/13 05:12:25 DEBUG : dst/one: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1061d74d23326429_d20211213_m050747_c001_v0001120_t0010") 2021/12/13 05:12:25 DEBUG : dst/one: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f11036fd68d2b397c_d20211213_m050742_c001_v0001017_t0032") 2021/12/13 05:12:25 DEBUG : dst/one: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f103f47d6c34f7692_d20211213_m050741_c001_v0001097_t0027") 2021/12/13 05:12:25 DEBUG : dst/one: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f11036fd68d2b3971_d20211213_m050735_c001_v0001017_t0020") 2021/12/13 05:12:25 DEBUG : dst/one: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f115f66f09c96697e_d20211213_m050734_c001_v0001145_t0012") 2021/12/13 05:12:25 DEBUG : dst/one: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb749e_d20211213_m050726_c001_v0001030_t0009") 2021/12/13 05:12:25 DEBUG : dst/one: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f10385328745748c7_d20211213_m050723_c001_v0001040_t0009") 2021/12/13 05:12:25 DEBUG : dst/one: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1162a078afb4bf72_d20211213_m050713_c001_v0001004_t0002") 2021/12/13 05:12:25 DEBUG : dst/one: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f102e89225c291720_d20211213_m050712_c001_v0001018_t0011") 2021/12/13 05:12:25 DEBUG : dst/one: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1162a078afb4bf65_d20211213_m050705_c001_v0001004_t0026") 2021/12/13 05:12:25 DEBUG : dst/one: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f101201c18327b922_d20211213_m050703_c001_v0001097_t0026") 2021/12/13 05:12:26 DEBUG : dst/one: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb7477_d20211213_m050655_c001_v0001030_t0013") 2021/12/13 05:12:26 DEBUG : dst/one: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f10855fa25476f1fc_d20211213_m050650_c001_v0001131_t0030") 2021/12/13 05:12:26 DEBUG : dst/one: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1115fab8ff40b9d5_d20211213_m050637_c001_v0001153_t0019") 2021/12/13 05:12:26 DEBUG : dst/one: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f11858fc6ce9fc0f7_d20211213_m050636_c001_v0001022_t0014") 2021/12/13 05:12:26 DEBUG : dst/one: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb7457_d20211213_m050629_c001_v0001030_t0011") 2021/12/13 05:12:26 DEBUG : dst/one: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1124b8da6f203092_d20211213_m050628_c001_v0001113_t0010") 2021/12/13 05:12:26 DEBUG : dst/one: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1124b8da6f20308f_d20211213_m050626_c001_v0001113_t0041") 2021/12/13 05:12:26 DEBUG : dst/one: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f100ed37c44d7db3b_d20211213_m050608_c001_v0001146_t0001") 2021/12/13 05:12:26 DEBUG : dst/one: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf37fe_d20211213_m050556_c001_v0001005_t0029") 2021/12/13 05:12:26 DEBUG : dst/one: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1034e251e46f57b2_d20211213_m050554_c001_v0001025_t0009") 2021/12/13 05:12:26 DEBUG : dst/one: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1034e251e46f57ac_d20211213_m050553_c001_v0001025_t0001") 2021/12/13 05:12:26 DEBUG : dst/one-2019-01-01: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f118b034a70b73a56_d20211213_m050939_c001_v0001131_t0006") 2021/12/13 05:12:26 DEBUG : dst/one-2019-01-01: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f115a1cdf7e0448a5_d20211213_m050934_c001_v0001146_t0000") 2021/12/13 05:12:26 DEBUG : dst/one-2019-01-01: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f107a5728ff4f0065_d20211213_m050930_c001_v0001113_t0055") 2021/12/13 05:12:26 DEBUG : dst/one.bak: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f11078e664ecb8fef_d20211213_m050921_c001_v0001043_t0024") 2021/12/13 05:12:26 DEBUG : dst/one.bak: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f101f3519d3d3972b_d20211213_m050916_c001_v0001036_t0030") 2021/12/13 05:12:26 DEBUG : dst/one.bak: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f106ebf0f179fedae_d20211213_m050910_c001_v0001036_t0032") 2021/12/13 05:12:26 DEBUG : dst/one.bak: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1061d74d233265c3_d20211213_m050850_c001_v0001120_t0005") 2021/12/13 05:12:26 DEBUG : dst/one.bak: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f10659e5d6febe589_d20211213_m050846_c001_v0001153_t0014") 2021/12/13 05:12:26 DEBUG : dst/one.bak: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116b02b72ec49d18_d20211213_m050837_c001_v0001134_t0026") 2021/12/13 05:12:27 DEBUG : dst/three: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f11399af0e5939141_d20211213_m050651_c001_v0001039_t0030") 2021/12/13 05:12:27 DEBUG : dst/three: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1124b8da6f2030d9_d20211213_m050644_c001_v0001113_t0044") 2021/12/13 05:12:27 DEBUG : dst/three-2019-01-01.txt: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f118e8226b06de175_d20211213_m050940_c001_v0001090_t0013") 2021/12/13 05:12:27 DEBUG : dst/three-2019-01-01.txt: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1136a8a4fcbac5e3_d20211213_m050937_c001_v0001120_t0048") 2021/12/13 05:12:27 DEBUG : dst/three-2019-01-01.txt: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f106bd56c379eef17_d20211213_m050932_c001_v0001117_t0026") 2021/12/13 05:12:27 DEBUG : dst/three.txt: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f118a359fb7e8196d_d20211213_m050940_c001_v0001097_t0033") 2021/12/13 05:12:27 DEBUG : dst/three.txt: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110b7a4d97b81b81_d20211213_m050939_c001_v0001091_t0011") 2021/12/13 05:12:27 DEBUG : dst/three.txt: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f107d8878e629609f_d20211213_m050938_c001_v0001000_t0036") 2021/12/13 05:12:27 DEBUG : dst/three.txt: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110b7a4d97b81b78_d20211213_m050934_c001_v0001091_t0049") 2021/12/13 05:12:27 DEBUG : dst/three.txt: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f10189d2bdcf7d5c3_d20211213_m050933_c001_v0001090_t0021") 2021/12/13 05:12:27 DEBUG : dst/three.txt: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf38f9_d20211213_m050926_c001_v0001005_t0012") 2021/12/13 05:12:27 DEBUG : dst/three.txt: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f106181086f0c6868_d20211213_m050922_c001_v0001090_t0021") 2021/12/13 05:12:27 DEBUG : dst/three.txt: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f100ed37c44d7ddba_d20211213_m050921_c001_v0001146_t0046") 2021/12/13 05:12:27 DEBUG : dst/three.txt: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1072088925866a2c_d20211213_m050920_c001_v0001165_t0019") 2021/12/13 05:12:27 DEBUG : dst/three.txt: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f100ed37c44d7ddac_d20211213_m050915_c001_v0001146_t0051") 2021/12/13 05:12:27 DEBUG : dst/three.txt: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f108e1a781327ace7_d20211213_m050914_c001_v0001025_t0025") 2021/12/13 05:12:27 DEBUG : dst/three.txt: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb753a_d20211213_m050906_c001_v0001030_t0042") 2021/12/13 05:12:27 DEBUG : dst/three.txt: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f107155cc09a74ef6_d20211213_m050848_c001_v0001033_t0031") 2021/12/13 05:12:28 DEBUG : dst/three.txt: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf38d2_d20211213_m050845_c001_v0001005_t0002") 2021/12/13 05:12:28 DEBUG : dst/three.txt: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f111bbfa2affd6548_d20211213_m050842_c001_v0001092_t0023") 2021/12/13 05:12:28 DEBUG : dst/three.txt: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb7512_d20211213_m050832_c001_v0001030_t0027") 2021/12/13 05:12:28 DEBUG : dst/three.txt: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1122c633fd021f6b_d20211213_m050815_c001_v0001117_t0046") 2021/12/13 05:12:28 DEBUG : dst/three.txt: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf38ad_d20211213_m050807_c001_v0001005_t0013") 2021/12/13 05:12:28 DEBUG : dst/three.txt: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1058bbd1314aa104_d20211213_m050805_c001_v0001025_t0002") 2021/12/13 05:12:28 DEBUG : dst/three.txt: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb74d5_d20211213_m050752_c001_v0001030_t0003") 2021/12/13 05:12:28 DEBUG : dst/three.txt: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f10574b542e0bf57c_d20211213_m050745_c001_v0001021_t0043") 2021/12/13 05:12:28 DEBUG : dst/three.txt: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf3881_d20211213_m050739_c001_v0001005_t0006") 2021/12/13 05:12:28 DEBUG : dst/three.txt: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f10129c226621ee2c_d20211213_m050737_c001_v0001145_t0043") 2021/12/13 05:12:28 DEBUG : dst/three.txt: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb74a3_d20211213_m050728_c001_v0001030_t0001") 2021/12/13 05:12:28 DEBUG : dst/three.txt: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1074c7af93a5cf6f_d20211213_m050720_c001_v0001117_t0003") 2021/12/13 05:12:29 DEBUG : dst/three.txt: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf385f_d20211213_m050710_c001_v0001005_t0001") 2021/12/13 05:12:29 DEBUG : dst/three.txt: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f101201c18327b954_d20211213_m050707_c001_v0001097_t0053") 2021/12/13 05:12:29 DEBUG : dst/three.txt: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb747d_d20211213_m050658_c001_v0001030_t0029") 2021/12/13 05:12:29 DEBUG : dst/three.txt.bak: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1094088b1c2b2afc_d20211213_m050922_c001_v0001144_t0037") 2021/12/13 05:12:29 DEBUG : dst/three.txt.bak: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f108df365a8fe65c5_d20211213_m050919_c001_v0001117_t0044") 2021/12/13 05:12:29 DEBUG : dst/three.txt.bak: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f11459f117f155719_d20211213_m050913_c001_v0001145_t0032") 2021/12/13 05:12:29 DEBUG : dst/three.txt.bak: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f115e8d7d438b75f2_d20211213_m050851_c001_v0001091_t0058") 2021/12/13 05:12:29 DEBUG : dst/three.txt.bak: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f106a471a98e5184b_d20211213_m050848_c001_v0001044_t0022") 2021/12/13 05:12:29 DEBUG : dst/three.txt.bak: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f103e24e4842b5e7d_d20211213_m050841_c001_v0001039_t0032") 2021/12/13 05:12:29 DEBUG : dst/two: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f118a359fb7e81979_d20211213_m050941_c001_v0001097_t0030") 2021/12/13 05:12:29 DEBUG : dst/two: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb7565_d20211213_m050925_c001_v0001030_t0020") 2021/12/13 05:12:29 DEBUG : dst/two: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f10316ebf66231a3b_d20211213_m050923_c001_v0001041_t0020") 2021/12/13 05:12:29 DEBUG : dst/two: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf38e3_d20211213_m050906_c001_v0001005_t0018") 2021/12/13 05:12:30 DEBUG : dst/two: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f119c6d64e7ba46ac_d20211213_m050853_c001_v0001117_t0015") 2021/12/13 05:12:30 DEBUG : dst/two: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf38c9_d20211213_m050831_c001_v0001005_t0041") 2021/12/13 05:12:30 DEBUG : dst/two: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f109547f50508243e_d20211213_m050828_c001_v0001097_t0013") 2021/12/13 05:12:30 DEBUG : dst/two: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf3894_d20211213_m050752_c001_v0001005_t0036") 2021/12/13 05:12:30 DEBUG : dst/two: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f105e3e7dc4107afa_d20211213_m050748_c001_v0001032_t0016") 2021/12/13 05:12:30 DEBUG : dst/two: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf3878_d20211213_m050728_c001_v0001005_t0027") 2021/12/13 05:12:30 DEBUG : dst/two: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1103242f95453f46_d20211213_m050725_c001_v0001097_t0043") 2021/12/13 05:12:30 DEBUG : dst/two: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf3853_d20211213_m050656_c001_v0001005_t0024") 2021/12/13 05:12:30 DEBUG : dst/two: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f100ed37c44d7dbe5_d20211213_m050652_c001_v0001146_t0054") 2021/12/13 05:12:30 DEBUG : dst/two: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f10438fcc1e1c77cf_d20211213_m050639_c001_v0001097_t0006") 2021/12/13 05:12:30 DEBUG : dst/two: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f104b5ad31428efdb_d20211213_m050609_c001_v0001018_t0000") 2021/12/13 05:12:30 DEBUG : dst/two: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1034e251e46f57d7_d20211213_m050606_c001_v0001025_t0000") 2021/12/13 05:12:30 DEBUG : empty space: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f118dc6f124aabe9b_d20211213_m050538_c001_v0001003_t0027") 2021/12/13 05:12:30 DEBUG : empty space: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb7403_d20211213_m050531_c001_v0001030_t0029") 2021/12/13 05:12:30 DEBUG : empty space: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1081be1f682c076b_d20211213_m050528_c001_v0001097_t0053") 2021/12/13 05:12:30 DEBUG : empty space: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf37a3_d20211213_m050501_c001_v0001005_t0037") 2021/12/13 05:12:30 DEBUG : empty space: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1102af865bd4883b_d20211213_m050446_c001_v0001157_t0059") 2021/12/13 05:12:30 DEBUG : empty space: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb73ae_d20211213_m050439_c001_v0001030_t0025") 2021/12/13 05:12:31 DEBUG : empty space: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f118e1379cacf0645_d20211213_m050346_c001_v0001017_t0029") 2021/12/13 05:12:31 DEBUG : empty space: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb735d_d20211213_m050341_c001_v0001030_t0029") 2021/12/13 05:12:31 DEBUG : empty space: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f10626c90220bc1a6_d20211213_m050339_c001_v0001144_t0059") 2021/12/13 05:12:31 DEBUG : empty space: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb7352_d20211213_m050337_c001_v0001030_t0003") 2021/12/13 05:12:31 DEBUG : empty space: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f11179d10597c3046_d20211213_m050328_c001_v0001117_t0046") 2021/12/13 05:12:31 DEBUG : empty space: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb732f_d20211213_m050321_c001_v0001030_t0041") 2021/12/13 05:12:31 DEBUG : empty space: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1102acf37b03618e_d20211213_m050311_c001_v0001117_t0013") 2021/12/13 05:12:31 DEBUG : empty space: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb730f_d20211213_m050305_c001_v0001030_t0041") 2021/12/13 05:12:31 DEBUG : empty space: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f117744c7ac5655a9_d20211213_m050237_c001_v0001117_t0001") 2021/12/13 05:12:31 DEBUG : empty space: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb72e8_d20211213_m050235_c001_v0001030_t0013") 2021/12/13 05:12:31 DEBUG : empty space: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f112361f673b18957_d20211213_m050221_c001_v0001040_t0058") 2021/12/13 05:12:31 DEBUG : empty space: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb72e3_d20211213_m050220_c001_v0001030_t0014") 2021/12/13 05:12:31 DEBUG : empty space: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f103a9421a64928ce_d20211213_m050156_c001_v0001136_t0026") 2021/12/13 05:12:31 DEBUG : empty space: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb72bc_d20211213_m050149_c001_v0001030_t0034") 2021/12/13 05:12:31 DEBUG : empty space: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f115d95d591f9cd15_d20211213_m050143_c001_v0001042_t0021") 2021/12/13 05:12:31 DEBUG : empty space: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb72ac_d20211213_m050140_c001_v0001030_t0026") 2021/12/13 05:12:31 DEBUG : empty space: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f111d35f8956d33fc_d20211213_m050139_c001_v0001165_t0049") 2021/12/13 05:12:31 DEBUG : empty space: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1102acf37b035611_d20211213_m050137_c001_v0001117_t0034") 2021/12/13 05:12:31 DEBUG : empty space: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb72a5_d20211213_m050132_c001_v0001030_t0033") 2021/12/13 05:12:31 DEBUG : enormous: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f112350288c398d85_d20211213_m050344_c001_v0001134_t0003") 2021/12/13 05:12:31 DEBUG : enormous: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb7364_d20211213_m050343_c001_v0001030_t0022") 2021/12/13 05:12:32 DEBUG : existing: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f10900580404c9e75_d20211213_m050948_c001_v0001134_t0011") 2021/12/13 05:12:32 DEBUG : existing: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb7583_d20211213_m050946_c001_v0001030_t0043") 2021/12/13 05:12:32 DEBUG : existing: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f11399af0e5938ee1_d20211213_m050436_c001_v0001039_t0027") 2021/12/13 05:12:32 DEBUG : existing: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf3786_d20211213_m050434_c001_v0001005_t0008") 2021/12/13 05:12:32 DEBUG : existing: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f11910e3ee89e97fd_d20211213_m050124_c001_v0001097_t0028") 2021/12/13 05:12:32 DEBUG : existing: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb7294_d20211213_m050122_c001_v0001030_t0017") 2021/12/13 05:12:32 DEBUG : existing: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f100d3f6168ccf29d_d20211213_m050121_c001_v0001093_t0055") 2021/12/13 05:12:32 DEBUG : existing: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb7292_d20211213_m050119_c001_v0001030_t0010") 2021/12/13 05:12:32 DEBUG : existing: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb7290_d20211213_m050118_c001_v0001030_t0001") 2021/12/13 05:12:32 DEBUG : existing-b: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f11110e3f580b2155_d20211213_m050436_c001_v0001131_t0047") 2021/12/13 05:12:32 DEBUG : existing-b: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb73aa_d20211213_m050434_c001_v0001030_t0033") 2021/12/13 05:12:32 DEBUG : five: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110b15366ec41b7f_d20211213_m050355_c001_v0001120_t0012") 2021/12/13 05:12:32 DEBUG : five: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb7381_d20211213_m050352_c001_v0001030_t0027") 2021/12/13 05:12:32 DEBUG : foo: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f115fde9e3c80ce70_d20211213_m050147_c001_v0001117_t0006") 2021/12/13 05:12:32 DEBUG : foo: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb72b6_d20211213_m050145_c001_v0001030_t0023") 2021/12/13 05:12:32 DEBUG : foo: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb72b1_d20211213_m050144_c001_v0001030_t0007") 2021/12/13 05:12:32 DEBUG : four: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f112aa923eef52bb6_d20211213_m050356_c001_v0001093_t0025") 2021/12/13 05:12:32 DEBUG : four: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb737f_d20211213_m050351_c001_v0001030_t0034") 2021/12/13 05:12:32 DEBUG : four: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb737b_d20211213_m050350_c001_v0001030_t0001") 2021/12/13 05:12:33 DEBUG : hello world2: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1081be1f682bf3c2_d20211213_m050037_c001_v0001097_t0023") 2021/12/13 05:12:33 DEBUG : hello world2: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb724c_d20211213_m050035_c001_v0001030_t0021") 2021/12/13 05:12:33 DEBUG : ignore-size: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1010b0d72127dbe9_d20211213_m050118_c001_v0001026_t0033") 2021/12/13 05:12:33 DEBUG : ignore-size: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb728c_d20211213_m050115_c001_v0001030_t0013") 2021/12/13 05:12:33 DEBUG : nested/sub dir/file: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f102b8d154413af2e_d20211213_m050433_c001_v0001128_t0050") 2021/12/13 05:12:33 DEBUG : nested/sub dir/file: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf377d_d20211213_m050431_c001_v0001005_t0015") 2021/12/13 05:12:33 DEBUG : nested/sub dir/file: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f113e26a33a6130f3_d20211213_m050425_c001_v0001117_t0042") 2021/12/13 05:12:33 DEBUG : nested/sub dir/file: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb73a1_d20211213_m050424_c001_v0001030_t0024") 2021/12/13 05:12:33 DEBUG : one: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f11179d10597c3396_d20211213_m050357_c001_v0001117_t0052") 2021/12/13 05:12:33 DEBUG : one: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb736e_d20211213_m050347_c001_v0001030_t0009") 2021/12/13 05:12:33 DEBUG : only0: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f10247528e2fc7eb3_d20211213_m051201_c001_v0001033_t0002") 2021/12/13 05:12:33 DEBUG : only0: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb7639_d20211213_m051140_c001_v0001030_t0005") 2021/12/13 05:12:33 DEBUG : only0: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf3993_d20211213_m051102_c001_v0001005_t0005") 2021/12/13 05:12:33 DEBUG : only0: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110fcafb573eb985_d20211213_m051029_c001_v0001128_t0008") 2021/12/13 05:12:33 DEBUG : only0: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf391a_d20211213_m050954_c001_v0001005_t0018") 2021/12/13 05:12:33 DEBUG : only1: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1074c7af93a5dd30_d20211213_m051204_c001_v0001117_t0008") 2021/12/13 05:12:34 DEBUG : only1: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f117839c8c39be279_d20211213_m051141_c001_v0001038_t0013") 2021/12/13 05:12:34 DEBUG : only1: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf3995_d20211213_m051104_c001_v0001005_t0032") 2021/12/13 05:12:34 DEBUG : only1: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1028656cb2427a72_d20211213_m051032_c001_v0001091_t0024") 2021/12/13 05:12:34 DEBUG : only1: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf391d_d20211213_m050955_c001_v0001005_t0019") 2021/12/13 05:12:34 DEBUG : only10: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f10137e568aae4204_d20211213_m051205_c001_v0001019_t0007") 2021/12/13 05:12:34 DEBUG : only10: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1195fdf1dcb0852e_d20211213_m051141_c001_v0001150_t0021") 2021/12/13 05:12:34 DEBUG : only10: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf39bb_d20211213_m051121_c001_v0001005_t0005") 2021/12/13 05:12:34 DEBUG : only10: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f114f6f67c0546fca_d20211213_m051035_c001_v0001005_t0013") 2021/12/13 05:12:34 DEBUG : only10: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf3941_d20211213_m051009_c001_v0001005_t0028") 2021/12/13 05:12:34 DEBUG : only11: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f11008e121310cc1a_d20211213_m051206_c001_v0001146_t0032") 2021/12/13 05:12:34 DEBUG : only11: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf39ee_d20211213_m051140_c001_v0001005_t0017") 2021/12/13 05:12:34 DEBUG : only11: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf39c0_d20211213_m051122_c001_v0001005_t0035") 2021/12/13 05:12:34 DEBUG : only11: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1185e9f378545f21_d20211213_m051029_c001_v0001117_t0019") 2021/12/13 05:12:35 DEBUG : only11: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf3947_d20211213_m051011_c001_v0001005_t0041") 2021/12/13 05:12:35 DEBUG : only12: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f10460dc85871a6c4_d20211213_m051206_c001_v0001134_t0031") 2021/12/13 05:12:35 DEBUG : only12: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb763b_d20211213_m051141_c001_v0001030_t0032") 2021/12/13 05:12:35 DEBUG : only12: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf39c3_d20211213_m051123_c001_v0001005_t0000") 2021/12/13 05:12:35 DEBUG : only12: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1048f353082ac080_d20211213_m051033_c001_v0001003_t0007") 2021/12/13 05:12:35 DEBUG : only12: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf394c_d20211213_m051017_c001_v0001005_t0004") 2021/12/13 05:12:35 DEBUG : only13: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1087f8e1efa162ce_d20211213_m051207_c001_v0001022_t0039") 2021/12/13 05:12:35 DEBUG : only13: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1195fdf1dcb08530_d20211213_m051141_c001_v0001150_t0010") 2021/12/13 05:12:35 DEBUG : only13: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf39ca_d20211213_m051127_c001_v0001005_t0017") 2021/12/13 05:12:35 DEBUG : only13: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f111c94f5b96c8123_d20211213_m051034_c001_v0001097_t0016") 2021/12/13 05:12:35 DEBUG : only13: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf3957_d20211213_m051021_c001_v0001005_t0033") 2021/12/13 05:12:35 DEBUG : only14: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f108df365a8fe6ed1_d20211213_m051207_c001_v0001117_t0040") 2021/12/13 05:12:35 DEBUG : only14: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf39f2_d20211213_m051141_c001_v0001005_t0024") 2021/12/13 05:12:35 DEBUG : only14: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf39d1_d20211213_m051129_c001_v0001005_t0005") 2021/12/13 05:12:35 DEBUG : only14: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1139d58ca715e4ad_d20211213_m051034_c001_v0001165_t0054") 2021/12/13 05:12:35 DEBUG : only14: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf3959_d20211213_m051022_c001_v0001005_t0030") 2021/12/13 05:12:35 DEBUG : only15: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f100917d28b941b4b_d20211213_m051208_c001_v0001165_t0057") 2021/12/13 05:12:35 DEBUG : only15: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1195fdf1dcb08536_d20211213_m051141_c001_v0001150_t0052") 2021/12/13 05:12:35 DEBUG : only15: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf39d6_d20211213_m051131_c001_v0001005_t0027") 2021/12/13 05:12:35 DEBUG : only15: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f113ede6e42279104_d20211213_m051033_c001_v0001097_t0021") 2021/12/13 05:12:35 DEBUG : only15: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf395d_d20211213_m051023_c001_v0001005_t0027") 2021/12/13 05:12:36 DEBUG : only16: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1136aa0e578ed0dd_d20211213_m051209_c001_v0001144_t0005") 2021/12/13 05:12:36 DEBUG : only16: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f117839c8c39be27e_d20211213_m051141_c001_v0001038_t0004") 2021/12/13 05:12:36 DEBUG : only16: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf39d9_d20211213_m051132_c001_v0001005_t0004") 2021/12/13 05:12:36 DEBUG : only16: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110cba7bf5132b0a_d20211213_m051035_c001_v0001042_t0048") 2021/12/13 05:12:36 DEBUG : only16: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf3960_d20211213_m051025_c001_v0001005_t0007") 2021/12/13 05:12:36 DEBUG : only17: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f11900cc2210973a6_d20211213_m051209_c001_v0001001_t0036") 2021/12/13 05:12:36 DEBUG : only17: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb763d_d20211213_m051141_c001_v0001030_t0022") 2021/12/13 05:12:36 DEBUG : only17: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf39db_d20211213_m051133_c001_v0001005_t0009") 2021/12/13 05:12:36 DEBUG : only17: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110e4322436bb42f_d20211213_m051037_c001_v0001136_t0049") 2021/12/13 05:12:36 DEBUG : only17: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf3963_d20211213_m051025_c001_v0001005_t0001") 2021/12/13 05:12:36 DEBUG : only18: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1100d5dbb58c959b_d20211213_m051210_c001_v0001015_t0008") 2021/12/13 05:12:36 DEBUG : only18: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1195fdf1dcb0853a_d20211213_m051141_c001_v0001150_t0010") 2021/12/13 05:12:36 DEBUG : only18: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf39e4_d20211213_m051136_c001_v0001005_t0025") 2021/12/13 05:12:36 DEBUG : only18: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f114a3a716048d4de_d20211213_m051038_c001_v0001025_t0031") 2021/12/13 05:12:36 DEBUG : only18: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf3965_d20211213_m051026_c001_v0001005_t0041") 2021/12/13 05:12:36 DEBUG : only19: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f106811dddf152fb8_d20211213_m051210_c001_v0001165_t0023") 2021/12/13 05:12:36 DEBUG : only19: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf39f4_d20211213_m051142_c001_v0001005_t0041") 2021/12/13 05:12:36 DEBUG : only19: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf39ec_d20211213_m051138_c001_v0001005_t0032") 2021/12/13 05:12:36 DEBUG : only19: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f115b14a29c4c2788_d20211213_m051046_c001_v0001015_t0008") 2021/12/13 05:12:36 DEBUG : only19: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf3969_d20211213_m051027_c001_v0001005_t0024") 2021/12/13 05:12:36 DEBUG : only2: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f10460dc85871a6d3_d20211213_m051210_c001_v0001134_t0000") 2021/12/13 05:12:36 DEBUG : only2: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1195fdf1dcb0853c_d20211213_m051142_c001_v0001150_t0002") 2021/12/13 05:12:37 DEBUG : only2: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf3999_d20211213_m051105_c001_v0001005_t0019") 2021/12/13 05:12:37 DEBUG : only2: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1025c3755484b11e_d20211213_m051030_c001_v0001120_t0010") 2021/12/13 05:12:37 DEBUG : only2: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf391f_d20211213_m050956_c001_v0001005_t0029") 2021/12/13 05:12:37 DEBUG : only3: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1185e9f378546497_d20211213_m051211_c001_v0001117_t0046") 2021/12/13 05:12:37 DEBUG : only3: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1195fdf1dcb08540_d20211213_m051142_c001_v0001150_t0005") 2021/12/13 05:12:37 DEBUG : only3: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf399f_d20211213_m051109_c001_v0001005_t0029") 2021/12/13 05:12:37 DEBUG : only3: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1110135f98b46e34_d20211213_m051029_c001_v0001128_t0024") 2021/12/13 05:12:37 DEBUG : only3: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf3925_d20211213_m050958_c001_v0001005_t0009") 2021/12/13 05:12:37 DEBUG : only4: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1156b2963b983dba_d20211213_m051212_c001_v0001032_t0005") 2021/12/13 05:12:37 DEBUG : only4: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1195fdf1dcb08545_d20211213_m051142_c001_v0001150_t0021") 2021/12/13 05:12:37 DEBUG : only4: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf39a3_d20211213_m051110_c001_v0001005_t0020") 2021/12/13 05:12:37 DEBUG : only4: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f115f66f09c966d8c_d20211213_m051031_c001_v0001145_t0036") 2021/12/13 05:12:37 DEBUG : only4: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf3929_d20211213_m050959_c001_v0001005_t0020") 2021/12/13 05:12:37 DEBUG : only5: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f11986c1b92f4792d_d20211213_m051214_c001_v0001042_t0008") 2021/12/13 05:12:37 DEBUG : only5: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f117839c8c39be280_d20211213_m051143_c001_v0001038_t0005") 2021/12/13 05:12:37 DEBUG : only5: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf39a6_d20211213_m051113_c001_v0001005_t0002") 2021/12/13 05:12:37 DEBUG : only5: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f102e47406c327fdd_d20211213_m051037_c001_v0001037_t0024") 2021/12/13 05:12:37 DEBUG : only5: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf392c_d20211213_m051000_c001_v0001005_t0026") 2021/12/13 05:12:38 DEBUG : only6: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1158d1c1f88a2613_d20211213_m051214_c001_v0001144_t0010") 2021/12/13 05:12:38 DEBUG : only6: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1195fdf1dcb08548_d20211213_m051143_c001_v0001150_t0033") 2021/12/13 05:12:38 DEBUG : only6: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf39a9_d20211213_m051114_c001_v0001005_t0035") 2021/12/13 05:12:38 DEBUG : only6: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f10013cb3ace5ab9e_d20211213_m051039_c001_v0001137_t0040") 2021/12/13 05:12:38 DEBUG : only6: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf392e_d20211213_m051001_c001_v0001005_t0022") 2021/12/13 05:12:38 DEBUG : only7: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f118c84269c883c3c_d20211213_m051215_c001_v0001019_t0022") 2021/12/13 05:12:38 DEBUG : only7: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1195fdf1dcb0854b_d20211213_m051143_c001_v0001150_t0045") 2021/12/13 05:12:38 DEBUG : only7: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf39ad_d20211213_m051115_c001_v0001005_t0016") 2021/12/13 05:12:38 DEBUG : only7: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f11409ebd4d7b8f73_d20211213_m051030_c001_v0001117_t0024") 2021/12/13 05:12:38 DEBUG : only7: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf3934_d20211213_m051004_c001_v0001005_t0026") 2021/12/13 05:12:38 DEBUG : only8: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1040def3c5afba22_d20211213_m051215_c001_v0001120_t0013") 2021/12/13 05:12:38 DEBUG : only8: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f117839c8c39be282_d20211213_m051143_c001_v0001038_t0001") 2021/12/13 05:12:38 DEBUG : only8: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf39b1_d20211213_m051117_c001_v0001005_t0012") 2021/12/13 05:12:38 DEBUG : only8: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1094e651760b340a_d20211213_m051035_c001_v0001029_t0037") 2021/12/13 05:12:38 DEBUG : only8: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf3937_d20211213_m051005_c001_v0001005_t0021") 2021/12/13 05:12:38 DEBUG : only9: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1072088925866d59_d20211213_m051216_c001_v0001165_t0037") 2021/12/13 05:12:38 DEBUG : only9: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1195fdf1dcb0854d_d20211213_m051143_c001_v0001150_t0049") 2021/12/13 05:12:38 DEBUG : only9: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf39b6_d20211213_m051119_c001_v0001005_t0004") 2021/12/13 05:12:38 DEBUG : only9: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1111955421a71501_d20211213_m051029_c001_v0001145_t0013") 2021/12/13 05:12:39 DEBUG : only9: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf393b_d20211213_m051007_c001_v0001005_t0001") 2021/12/13 05:12:39 DEBUG : potato: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f118568d77b39ff4c_d20211213_m050422_c001_v0001150_t0012") 2021/12/13 05:12:39 DEBUG : potato: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb739f_d20211213_m050411_c001_v0001030_t0005") 2021/12/13 05:12:39 DEBUG : potato: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1154aacf75025d34_d20211213_m050409_c001_v0001136_t0035") 2021/12/13 05:12:39 DEBUG : potato: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb739b_d20211213_m050405_c001_v0001030_t0042") 2021/12/13 05:12:39 DEBUG : potato: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f103221ffd59c0556_d20211213_m050403_c001_v0001042_t0042") 2021/12/13 05:12:39 DEBUG : potato: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f113fed3cb75133bc_d20211213_m050359_c001_v0001038_t0036") 2021/12/13 05:12:39 DEBUG : potato: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1157c4ab0285f8a0_d20211213_m050333_c001_v0001097_t0037") 2021/12/13 05:12:39 DEBUG : potato: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb7340_d20211213_m050330_c001_v0001030_t0002") 2021/12/13 05:12:39 DEBUG : potato: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1122c633fd020acb_d20211213_m050326_c001_v0001117_t0004") 2021/12/13 05:12:39 DEBUG : potato: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb7328_d20211213_m050319_c001_v0001030_t0042") 2021/12/13 05:12:39 DEBUG : potato: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f103f47d6c34f6643_d20211213_m050307_c001_v0001097_t0027") 2021/12/13 05:12:39 DEBUG : potato: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb730d_d20211213_m050304_c001_v0001030_t0001") 2021/12/13 05:12:39 DEBUG : potato: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f114cea06861c0792_d20211213_m050237_c001_v0001130_t0037") 2021/12/13 05:12:39 DEBUG : potato: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb72e6_d20211213_m050234_c001_v0001030_t0038") 2021/12/13 05:12:39 DEBUG : potato: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f102cd5318be58d11_d20211213_m050226_c001_v0001117_t0045") 2021/12/13 05:12:39 DEBUG : potato: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb72df_d20211213_m050215_c001_v0001030_t0008") 2021/12/13 05:12:39 DEBUG : potato: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1097dd1f9e154451_d20211213_m050214_c001_v0001090_t0005") 2021/12/13 05:12:39 DEBUG : potato: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb72d9_d20211213_m050211_c001_v0001030_t0041") 2021/12/13 05:12:39 DEBUG : potato: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb72d5_d20211213_m050209_c001_v0001030_t0037") 2021/12/13 05:12:40 DEBUG : potato: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f103f47d6c34f632c_d20211213_m050208_c001_v0001097_t0048") 2021/12/13 05:12:40 DEBUG : potato: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb72cd_d20211213_m050205_c001_v0001030_t0015") 2021/12/13 05:12:40 DEBUG : potato: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb72cb_d20211213_m050204_c001_v0001030_t0038") 2021/12/13 05:12:40 DEBUG : potato: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f105da58a1f3e0b32_d20211213_m050201_c001_v0001117_t0027") 2021/12/13 05:12:40 DEBUG : potato: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb72c0_d20211213_m050155_c001_v0001030_t0014") 2021/12/13 05:12:40 DEBUG : potato2: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f10438fcc1e1c7594_d20211213_m050550_c001_v0001097_t0056") 2021/12/13 05:12:40 DEBUG : potato2: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb7418_d20211213_m050549_c001_v0001030_t0034") 2021/12/13 05:12:40 DEBUG : potato2: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f10821d313da8e636_d20211213_m050539_c001_v0001120_t0055") 2021/12/13 05:12:40 DEBUG : potato2: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf37d3_d20211213_m050530_c001_v0001005_t0012") 2021/12/13 05:12:40 DEBUG : potato2: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f106482b319886e87_d20211213_m050515_c001_v0001025_t0036") 2021/12/13 05:12:40 DEBUG : potato2: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb73cc_d20211213_m050500_c001_v0001030_t0034") 2021/12/13 05:12:40 DEBUG : potato2: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1051cd3522e6d5d5_d20211213_m050446_c001_v0001036_t0057") 2021/12/13 05:12:40 DEBUG : potato2: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf378e_d20211213_m050438_c001_v0001005_t0032") 2021/12/13 05:12:40 DEBUG : potato2: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f11459f117f15501d_d20211213_m050345_c001_v0001145_t0012") 2021/12/13 05:12:40 DEBUG : potato2: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb735b_d20211213_m050341_c001_v0001030_t0007") 2021/12/13 05:12:40 DEBUG : potato2: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f10900580404c9644_d20211213_m050340_c001_v0001134_t0001") 2021/12/13 05:12:40 DEBUG : potato2: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb734c_d20211213_m050337_c001_v0001030_t0021") 2021/12/13 05:12:40 DEBUG : potato2: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1103242f95453159_d20211213_m050335_c001_v0001097_t0021") 2021/12/13 05:12:40 DEBUG : potato2: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb7343_d20211213_m050332_c001_v0001030_t0010") 2021/12/13 05:12:40 DEBUG : potato2: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f109fd3ea5581a136_d20211213_m050330_c001_v0001042_t0036") 2021/12/13 05:12:41 DEBUG : potato2: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb733b_d20211213_m050327_c001_v0001030_t0013") 2021/12/13 05:12:41 DEBUG : potato2: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f11637c63c41a7fb7_d20211213_m050317_c001_v0001097_t0027") 2021/12/13 05:12:41 DEBUG : potato2: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb7311_d20211213_m050307_c001_v0001030_t0042") 2021/12/13 05:12:41 DEBUG : potato2: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f108df365a8fe4a67_d20211213_m050243_c001_v0001117_t0008") 2021/12/13 05:12:41 DEBUG : potato2: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb72ea_d20211213_m050236_c001_v0001030_t0037") 2021/12/13 05:12:41 DEBUG : potato2: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1090f286a7885ace_d20211213_m050044_c001_v0001092_t0033") 2021/12/13 05:12:41 DEBUG : potato2: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb725c_d20211213_m050043_c001_v0001030_t0027") 2021/12/13 05:12:41 DEBUG : potato2: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f117cfccb69b0815d_d20211213_m050041_c001_v0001097_t0013") 2021/12/13 05:12:41 DEBUG : potato2: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb7254_d20211213_m050038_c001_v0001030_t0037") 2021/12/13 05:12:41 DEBUG : potato3: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1103242f95453813_d20211213_m050539_c001_v0001097_t0040") 2021/12/13 05:12:41 DEBUG : potato3: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf37da_d20211213_m050533_c001_v0001005_t0017") 2021/12/13 05:12:41 DEBUG : potato3: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f117839c8c39bde2d_d20211213_m050518_c001_v0001038_t0054") 2021/12/13 05:12:41 DEBUG : potato3: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb73d0_d20211213_m050503_c001_v0001030_t0009") 2021/12/13 05:12:41 DEBUG : potato3: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f101765a4844da911_d20211213_m050448_c001_v0001134_t0004") 2021/12/13 05:12:41 DEBUG : potato3: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf3792_d20211213_m050439_c001_v0001005_t0041") 2021/12/13 05:12:41 DEBUG : pre-dest1/1: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1122c633fd0219dc_d20211213_m050620_c001_v0001117_t0048") 2021/12/13 05:12:41 DEBUG : pre-dest1/1: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb743e_d20211213_m050611_c001_v0001030_t0021") 2021/12/13 05:12:41 DEBUG : pre-dest2/2: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f109e67628d61585c_d20211213_m050622_c001_v0001113_t0017") 2021/12/13 05:12:41 DEBUG : pre-dest2/2: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf3812_d20211213_m050613_c001_v0001005_t0040") 2021/12/13 05:12:41 DEBUG : sizeonly: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f10900580404c933a_d20211213_m050115_c001_v0001134_t0038") 2021/12/13 05:12:42 DEBUG : sizeonly: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb7289_d20211213_m050113_c001_v0001030_t0037") 2021/12/13 05:12:42 DEBUG : sub dir/hello world: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1174fa7f72751a3b_d20211213_m050433_c001_v0001018_t0039") 2021/12/13 05:12:42 DEBUG : sub dir/hello world: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb73a5_d20211213_m050431_c001_v0001030_t0021") 2021/12/13 05:12:42 DEBUG : sub dir/hello world: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1031ee3ab0994a84_d20211213_m050430_c001_v0001033_t0003") 2021/12/13 05:12:42 DEBUG : sub dir/hello world: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf3777_d20211213_m050424_c001_v0001005_t0032") 2021/12/13 05:12:42 DEBUG : sub dir/hello world: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1103242f95452834_d20211213_m050107_c001_v0001097_t0018") 2021/12/13 05:12:42 DEBUG : sub dir/hello world: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb727f_d20211213_m050105_c001_v0001030_t0031") 2021/12/13 05:12:42 DEBUG : sub dir/hello world: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1175508509584134_d20211213_m050104_c001_v0001137_t0028") 2021/12/13 05:12:42 DEBUG : sub dir/hello world: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb727d_d20211213_m050102_c001_v0001030_t0005") 2021/12/13 05:12:42 DEBUG : sub dir/hello world: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1192ef2122a9bb8c_d20211213_m050059_c001_v0001039_t0043") 2021/12/13 05:12:42 DEBUG : sub dir/hello world: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb7274_d20211213_m050053_c001_v0001030_t0009") 2021/12/13 05:12:42 DEBUG : sub dir/hello world: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f112ec0cec5617fda_d20211213_m050051_c001_v0001097_t0009") 2021/12/13 05:12:42 DEBUG : sub dir/hello world: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb726d_d20211213_m050050_c001_v0001030_t0006") 2021/12/13 05:12:42 DEBUG : sub dir/hello world: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f109547f50508066b_d20211213_m050048_c001_v0001097_t0034") 2021/12/13 05:12:42 DEBUG : sub dir/hello world: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb7269_d20211213_m050047_c001_v0001030_t0015") 2021/12/13 05:12:42 DEBUG : sub dir/hello world: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1114425bd6ee19ad_d20211213_m050046_c001_v0001043_t0004") 2021/12/13 05:12:42 DEBUG : sub dir/hello world: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb7264_d20211213_m050045_c001_v0001030_t0013") 2021/12/13 05:12:42 DEBUG : sub dir/hello world: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f11061e10c153576b_d20211213_m050034_c001_v0001137_t0035") 2021/12/13 05:12:42 DEBUG : sub dir/hello world: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb724a_d20211213_m050033_c001_v0001030_t0030") 2021/12/13 05:12:42 DEBUG : sub dir/hello world: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f101fdb77b84d12d8_d20211213_m050032_c001_v0001120_t0052") 2021/12/13 05:12:42 DEBUG : sub dir/hello world: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb7244_d20211213_m050031_c001_v0001030_t0030") 2021/12/13 05:12:42 DEBUG : sub dir/hello world: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f11128359dda94bc5_d20211213_m050025_c001_v0001041_t0008") 2021/12/13 05:12:42 DEBUG : sub dir/hello world: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb723d_d20211213_m050024_c001_v0001030_t0032") 2021/12/13 05:12:43 DEBUG : sub dir/hello world: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f102035789fd39ad2_d20211213_m050023_c001_v0001150_t0003") 2021/12/13 05:12:43 DEBUG : sub dir/hello world: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb7235_d20211213_m050019_c001_v0001030_t0014") 2021/12/13 05:12:43 DEBUG : sub/yam: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f113e26a33a613062_d20211213_m050420_c001_v0001117_t0005") 2021/12/13 05:12:43 DEBUG : sub/yam: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf3767_d20211213_m050411_c001_v0001005_t0002") 2021/12/13 05:12:43 DEBUG : three: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1123c7c3f11d5c55_d20211213_m050358_c001_v0001038_t0048") 2021/12/13 05:12:43 DEBUG : three: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f113fed3cb75133a1_d20211213_m050354_c001_v0001038_t0020") 2021/12/13 05:12:43 DEBUG : three: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb7378_d20211213_m050350_c001_v0001030_t0000") 2021/12/13 05:12:43 DEBUG : two: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110fa15208645707_d20211213_m050358_c001_v0001040_t0007") 2021/12/13 05:12:43 DEBUG : two: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f113fed3cb7513399_d20211213_m050352_c001_v0001038_t0058") 2021/12/13 05:12:43 DEBUG : two: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb7376_d20211213_m050349_c001_v0001030_t0038") 2021/12/13 05:12:43 DEBUG : yam: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f115fde9e3c80dfe9_d20211213_m050422_c001_v0001117_t0012") 2021/12/13 05:12:43 DEBUG : yam: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f100e1ff1d8239417_d20211213_m050414_c001_v0001117_t0030") 2021/12/13 05:12:43 DEBUG : yam: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f113240f4efb1dbdf_d20211213_m050408_c001_v0001113_t0050") 2021/12/13 05:12:43 DEBUG : yam: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116ec022d0cf375d_d20211213_m050406_c001_v0001005_t0039") 2021/12/13 05:12:43 DEBUG : yam: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f10697c2c0cc3d578_d20211213_m050402_c001_v0001091_t0002") 2021/12/13 05:12:43 DEBUG : yam: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f110823944abb7395_d20211213_m050400_c001_v0001030_t0012") 2021/12/13 05:12:43 DEBUG : yaml: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f11910e3ee89ea1dd_d20211213_m050409_c001_v0001097_t0040") 2021/12/13 05:12:43 DEBUG : yaml: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f116a7dde6ad6fe1e_d20211213_m050407_c001_v0001117_t0008") 2021/12/13 05:12:43 DEBUG : yaml: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f114993642b0158dc_d20211213_m050404_c001_v0001033_t0009") 2021/12/13 05:12:43 DEBUG : yaml: Deleting (id "4_zdf02adc3209dbb2b7fd80e1b_f1006f4ee8eaf9faf_d20211213_m050402_c001_v0001131_t0052") "./sync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose" - Finished OK in 12m30.761948477s (try 1/5)