"./sync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -fast-list" - Starting (try 1/5) 2021/06/01 06:15:14 DEBUG : Creating backend with remote "TestB2:rclone-test-xedunix8facujup4nasavof3" 2021/06/01 06:15:14 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2021/06/01 06:15:15 DEBUG : Creating backend with remote "/tmp/rclone500016083" === RUN TestPipe --- PASS: TestPipe (0.00s) === RUN TestPipeConcurrent --- PASS: TestPipeConcurrent (0.10s) === 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:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" 2021/06/01 06:15:16 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) 2021/06/01 06:15:16 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for checks to finish 2021/06/01 06:15:16 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for transfers to finish --- PASS: TestCopyWithDryRun (1.35s) === RUN TestCopy run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" 2021/06/01 06:15:17 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for checks to finish 2021/06/01 06:15:17 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for transfers to finish 2021/06/01 06:15:17 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/06/01 06:15:17 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopy (1.69s) === RUN TestCopyMissingDirectory run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" 2021/06/01 06:15:18 DEBUG : Creating backend with remote "/non-existing" 2021/06/01 06:15:18 ERROR : Local file system at /non-existing: error reading source root directory: directory not found 2021/06/01 06:15:18 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for checks to finish 2021/06/01 06:15:18 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for transfers to finish --- PASS: TestCopyMissingDirectory (0.43s) === RUN TestCopyNoTraverse run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" 2021/06/01 06:15:19 DEBUG : Couldn't decode error response: EOF 2021/06/01 06:15:19 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for checks to finish 2021/06/01 06:15:19 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for transfers to finish 2021/06/01 06:15:19 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/06/01 06:15:19 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoTraverse (3.60s) === RUN TestCopyCheckFirst run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" 2021/06/01 06:15:22 INFO : B2 bucket rclone-test-xedunix8facujup4nasavof3: Running all checks before starting transfers 2021/06/01 06:15:22 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for checks to finish 2021/06/01 06:15:22 INFO : B2 bucket rclone-test-xedunix8facujup4nasavof3: Checks finished, now starting transfers 2021/06/01 06:15:22 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for transfers to finish 2021/06/01 06:15:23 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/06/01 06:15:23 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyCheckFirst (1.41s) === RUN TestSyncNoTraverse run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" 2021/06/01 06:15:24 ERROR : Ignoring --no-traverse with sync 2021/06/01 06:15:24 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for checks to finish 2021/06/01 06:15:24 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for transfers to finish 2021/06/01 06:15:24 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/06/01 06:15:24 INFO : sub dir/hello world: Copied (new) 2021/06/01 06:15:24 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoTraverse (2.20s) === RUN TestCopyWithDepth run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" 2021/06/01 06:15:26 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for checks to finish 2021/06/01 06:15:26 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for transfers to finish 2021/06/01 06:15:26 DEBUG : hello world2: sha1 = c0bf117f9c93d02c894fe8ffd6e5cecf3545c209 OK 2021/06/01 06:15:26 INFO : hello world2: Copied (new) --- PASS: TestCopyWithDepth (1.11s) === RUN TestCopyWithFilesFrom run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" 2021/06/01 06:15:27 DEBUG : hello world2: Excluded 2021/06/01 06:15:27 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for checks to finish 2021/06/01 06:15:27 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for transfers to finish 2021/06/01 06:15:27 DEBUG : potato2: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/06/01 06:15:27 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFrom (3.75s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" 2021/06/01 06:15:31 DEBUG : Couldn't decode error response: EOF 2021/06/01 06:15:31 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for checks to finish 2021/06/01 06:15:31 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for transfers to finish 2021/06/01 06:15:32 DEBUG : potato2: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/06/01 06:15:32 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFromAndNoTraverse (3.10s) === RUN TestCopyEmptyDirectories run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" 2021/06/01 06:15:34 DEBUG : sub dir2: Making directory 2021/06/01 06:15:34 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for checks to finish 2021/06/01 06:15:34 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for transfers to finish 2021/06/01 06:15:34 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/06/01 06:15:34 INFO : sub dir/hello world: Copied (new) 2021/06/01 06:15:34 DEBUG : sub dir2: Making directory 2021/06/01 06:15:34 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: copied 1 directories fstest.go:251: Filtering empty directory "sub dir2" --- PASS: TestCopyEmptyDirectories (1.18s) === RUN TestMoveEmptyDirectories run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" 2021/06/01 06:15:35 DEBUG : sub dir2: Making directory 2021/06/01 06:15:35 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for checks to finish 2021/06/01 06:15:35 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for transfers to finish 2021/06/01 06:15:35 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/06/01 06:15:35 INFO : sub dir/hello world: Copied (new) 2021/06/01 06:15:35 INFO : sub dir/hello world: Deleted 2021/06/01 06:15:35 DEBUG : sub dir: Making directory 2021/06/01 06:15:35 DEBUG : sub dir2: Making directory 2021/06/01 06:15:35 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: copied 2 directories fstest.go:251: Filtering empty directory "sub dir2" --- PASS: TestMoveEmptyDirectories (1.32s) === RUN TestSyncEmptyDirectories run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" 2021/06/01 06:15:36 DEBUG : sub dir2: Making directory 2021/06/01 06:15:36 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for checks to finish 2021/06/01 06:15:36 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for transfers to finish 2021/06/01 06:15:37 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/06/01 06:15:37 INFO : sub dir/hello world: Copied (new) 2021/06/01 06:15:37 DEBUG : sub dir2: Making directory 2021/06/01 06:15:37 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: copied 1 directories 2021/06/01 06:15:37 DEBUG : Waiting for deletions to finish fstest.go:251: Filtering empty directory "sub dir2" --- PASS: TestSyncEmptyDirectories (3.38s) === RUN TestServerSideCopy run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" 2021/06/01 06:15:40 DEBUG : Creating backend with remote "TestB2:rclone-test-cezuviw2noxufah9korinom1" sync_test.go:281: Server side copy (if possible) B2 bucket rclone-test-xedunix8facujup4nasavof3 -> B2 bucket rclone-test-cezuviw2noxufah9korinom1 2021/06/01 06:15:41 DEBUG : B2 bucket rclone-test-cezuviw2noxufah9korinom1: Waiting for checks to finish 2021/06/01 06:15:41 DEBUG : B2 bucket rclone-test-cezuviw2noxufah9korinom1: Waiting for transfers to finish 2021/06/01 06:15:42 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/06/01 06:15:42 INFO : sub dir/hello world: Copied (server-side copy) 2021/06/01 06:15:42 DEBUG : B2 bucket rclone-test-cezuviw2noxufah9korinom1: Purge remote 2021/06/01 06:15:42 DEBUG : sub dir/hello world: Deleting (id "4_z0fd2ad93306dcb6b7f980e1b_f1004204349267ddb_d20210601_m061541_c001_v0001137_t0025") --- PASS: TestServerSideCopy (5.69s) === RUN TestCopyAfterDelete run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" 2021/06/01 06:15:46 ERROR : : error listing: directory not found 2021/06/01 06:15:46 DEBUG : Local file system at /tmp/rclone500016083: Making directory 2021/06/01 06:15:46 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for checks to finish 2021/06/01 06:15:46 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for transfers to finish --- PASS: TestCopyAfterDelete (1.87s) === RUN TestCopyRedownload run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" 2021/06/01 06:15:48 DEBUG : Local file system at /tmp/rclone500016083: Waiting for checks to finish 2021/06/01 06:15:48 DEBUG : Local file system at /tmp/rclone500016083: Waiting for transfers to finish 2021/06/01 06:15:48 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/06/01 06:15:48 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyRedownload (1.81s) === RUN TestSyncBasedOnCheckSum run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" 2021/06/01 06:15:49 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for checks to finish 2021/06/01 06:15:49 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for transfers to finish 2021/06/01 06:15:49 DEBUG : check sum: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2021/06/01 06:15:49 INFO : check sum: Copied (new) 2021/06/01 06:15:49 DEBUG : Waiting for deletions to finish 2021/06/01 06:15:50 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for checks to finish 2021/06/01 06:15:50 DEBUG : check sum: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2021/06/01 06:15:50 DEBUG : check sum: Size and sha1 of src and dst objects identical 2021/06/01 06:15:50 DEBUG : check sum: Unchanged skipping 2021/06/01 06:15:50 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for transfers to finish 2021/06/01 06:15:50 DEBUG : Waiting for deletions to finish 2021/06/01 06:15:50 INFO : There was nothing to transfer --- PASS: TestSyncBasedOnCheckSum (1.68s) === RUN TestSyncSizeOnly run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" 2021/06/01 06:15:51 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for checks to finish 2021/06/01 06:15:51 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for transfers to finish 2021/06/01 06:15:51 DEBUG : sizeonly: sha1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2021/06/01 06:15:51 INFO : sizeonly: Copied (new) 2021/06/01 06:15:51 DEBUG : Waiting for deletions to finish 2021/06/01 06:15:51 DEBUG : sizeonly: Sizes identical 2021/06/01 06:15:51 DEBUG : sizeonly: Unchanged skipping 2021/06/01 06:15:51 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for checks to finish 2021/06/01 06:15:51 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for transfers to finish 2021/06/01 06:15:51 DEBUG : Waiting for deletions to finish 2021/06/01 06:15:51 INFO : There was nothing to transfer --- PASS: TestSyncSizeOnly (2.74s) === RUN TestSyncIgnoreSize run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" 2021/06/01 06:15:54 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for checks to finish 2021/06/01 06:15:54 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for transfers to finish 2021/06/01 06:15:54 DEBUG : ignore-size: sha1 = 4a756ca07e9487f482465a99e8286abc86ba4dc7 OK 2021/06/01 06:15:54 INFO : ignore-size: Copied (new) 2021/06/01 06:15:54 DEBUG : Waiting for deletions to finish 2021/06/01 06:15:54 DEBUG : ignore-size: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/06/01 06:15:54 DEBUG : ignore-size: Unchanged skipping 2021/06/01 06:15:54 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for checks to finish 2021/06/01 06:15:54 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for transfers to finish 2021/06/01 06:15:54 DEBUG : Waiting for deletions to finish 2021/06/01 06:15:54 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreSize (1.62s) === RUN TestSyncIgnoreTimes run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" 2021/06/01 06:15:56 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for checks to finish 2021/06/01 06:15:56 DEBUG : existing: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/06/01 06:15:56 DEBUG : existing: Unchanged skipping 2021/06/01 06:15:56 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for transfers to finish 2021/06/01 06:15:56 DEBUG : Waiting for deletions to finish 2021/06/01 06:15:56 INFO : There was nothing to transfer 2021/06/01 06:15:56 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2021/06/01 06:15:56 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for checks to finish 2021/06/01 06:15:56 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for transfers to finish 2021/06/01 06:15:56 DEBUG : existing: sha1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2021/06/01 06:15:56 INFO : existing: Copied (replaced existing) 2021/06/01 06:15:56 DEBUG : Waiting for deletions to finish --- PASS: TestSyncIgnoreTimes (1.85s) === RUN TestSyncIgnoreExisting run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" 2021/06/01 06:15:57 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for checks to finish 2021/06/01 06:15:57 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for transfers to finish 2021/06/01 06:16:01 DEBUG : existing: sha1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2021/06/01 06:16:01 INFO : existing: Copied (new) 2021/06/01 06:16:01 DEBUG : Waiting for deletions to finish 2021/06/01 06:16:01 DEBUG : existing: Destination exists, skipping 2021/06/01 06:16:01 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for checks to finish 2021/06/01 06:16:01 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for transfers to finish 2021/06/01 06:16:01 DEBUG : Waiting for deletions to finish 2021/06/01 06:16:01 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreExisting (4.82s) === RUN TestSyncIgnoreErrors run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" 2021/06/01 06:16:02 DEBUG : d: Making directory fstest.go:251: Filtering empty directory "d" 2021/06/01 06:16:03 DEBUG : c/non empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/06/01 06:16:03 DEBUG : c/non empty space: Unchanged skipping 2021/06/01 06:16:03 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for checks to finish 2021/06/01 06:16:03 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for transfers to finish 2021/06/01 06:16:03 DEBUG : a/potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/06/01 06:16:03 INFO : a/potato2: Copied (new) 2021/06/01 06:16:03 DEBUG : Waiting for deletions to finish 2021/06/01 06:16:03 INFO : b/potato: Deleted --- PASS: TestSyncIgnoreErrors (4.28s) === RUN TestSyncAfterChangingModtimeOnly run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" 2021/06/01 06:16:07 DEBUG : empty space: Modification times differ by -95456h54m52.624456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499 +0000 UTC 2021/06/01 06:16:07 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for checks to finish 2021/06/01 06:16:07 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2021/06/01 06:16:07 NOTICE: empty space: Skipped update modification time as --dry-run is set (size 1) 2021/06/01 06:16:07 DEBUG : empty space: Unchanged skipping 2021/06/01 06:16:07 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for transfers to finish 2021/06/01 06:16:07 DEBUG : Waiting for deletions to finish 2021/06/01 06:16:07 INFO : There was nothing to transfer 2021/06/01 06:16:07 DEBUG : empty space: Modification times differ by -95456h54m52.624456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499 +0000 UTC 2021/06/01 06:16:07 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for checks to finish 2021/06/01 06:16:07 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2021/06/01 06:16:10 INFO : empty space: Updated modification time in destination 2021/06/01 06:16:10 DEBUG : empty space: Unchanged skipping 2021/06/01 06:16:10 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for transfers to finish 2021/06/01 06:16:10 DEBUG : Waiting for deletions to finish 2021/06/01 06:16:10 INFO : There was nothing to transfer --- PASS: TestSyncAfterChangingModtimeOnly (4.68s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" 2021/06/01 06:16:11 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for checks to finish 2021/06/01 06:16:11 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/06/01 06:16:11 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2021/06/01 06:16:11 DEBUG : empty space: Unchanged skipping 2021/06/01 06:16:11 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for transfers to finish 2021/06/01 06:16:11 DEBUG : Waiting for deletions to finish 2021/06/01 06:16:11 INFO : There was nothing to transfer --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (1.41s) === RUN TestSyncDoesntUpdateModtime run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" 2021/06/01 06:16:13 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for checks to finish 2021/06/01 06:16:13 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/06/01 06:16:13 DEBUG : foo: sha1 = 0beec7b5ea3f0fdbc95d0dd47f3c5bc275da8a33 (Local file system at /tmp/rclone500016083) 2021/06/01 06:16:13 DEBUG : foo: sha1 = 62cdb7020ff920e5aa642c3d4066950dd1f01f4d (B2 bucket rclone-test-xedunix8facujup4nasavof3) 2021/06/01 06:16:13 DEBUG : foo: sha1 differ 2021/06/01 06:16:13 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for transfers to finish 2021/06/01 06:16:13 DEBUG : foo: sha1 = 0beec7b5ea3f0fdbc95d0dd47f3c5bc275da8a33 OK 2021/06/01 06:16:13 INFO : foo: Copied (replaced existing) 2021/06/01 06:16:13 DEBUG : Waiting for deletions to finish --- PASS: TestSyncDoesntUpdateModtime (2.47s) === RUN TestSyncAfterAddingAFile run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" 2021/06/01 06:16:15 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/06/01 06:16:15 DEBUG : empty space: Unchanged skipping 2021/06/01 06:16:15 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for checks to finish 2021/06/01 06:16:15 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for transfers to finish 2021/06/01 06:16:15 DEBUG : potato: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/06/01 06:16:15 INFO : potato: Copied (new) 2021/06/01 06:16:15 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterAddingAFile (5.64s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" 2021/06/01 06:16:21 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2021/06/01 06:16:21 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for checks to finish 2021/06/01 06:16:21 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for transfers to finish 2021/06/01 06:16:21 DEBUG : potato: sha1 = 0fd321a1023ac4e1e1054fcc0236b9d0755f722f OK 2021/06/01 06:16:21 INFO : potato: Copied (replaced existing) 2021/06/01 06:16:21 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingFilesSizeOnly (1.66s) === RUN TestSyncAfterChangingContentsOnly run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" 2021/06/01 06:16:22 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/06/01 06:16:22 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for checks to finish 2021/06/01 06:16:22 DEBUG : potato: sha1 = 1ca5094b3b73f587249eabe179c40a3eec839b1f (Local file system at /tmp/rclone500016083) 2021/06/01 06:16:22 DEBUG : potato: sha1 = 0fd321a1023ac4e1e1054fcc0236b9d0755f722f (B2 bucket rclone-test-xedunix8facujup4nasavof3) 2021/06/01 06:16:22 DEBUG : potato: sha1 differ 2021/06/01 06:16:22 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for transfers to finish 2021/06/01 06:16:23 DEBUG : potato: sha1 = 1ca5094b3b73f587249eabe179c40a3eec839b1f OK 2021/06/01 06:16:23 INFO : potato: Copied (replaced existing) 2021/06/01 06:16:23 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingContentsOnly (1.58s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" 2021/06/01 06:16:24 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2021/06/01 06:16:24 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/06/01 06:16:24 DEBUG : empty space: Unchanged skipping 2021/06/01 06:16:24 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for checks to finish 2021/06/01 06:16:24 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for transfers to finish 2021/06/01 06:16:24 DEBUG : Waiting for deletions to finish 2021/06/01 06:16:24 NOTICE: potato: Skipped delete as --dry-run is set (size 21) --- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (1.66s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" 2021/06/01 06:16:26 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/06/01 06:16:26 DEBUG : empty space: Unchanged skipping 2021/06/01 06:16:26 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for checks to finish 2021/06/01 06:16:26 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for transfers to finish 2021/06/01 06:16:26 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/06/01 06:16:26 INFO : potato2: Copied (new) 2021/06/01 06:16:26 DEBUG : Waiting for deletions to finish 2021/06/01 06:16:27 INFO : potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (4.18s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" 2021/06/01 06:16:30 DEBUG : d: Making directory 2021/06/01 06:16:30 DEBUG : d/e: Making directory fstest.go:251: Filtering empty directory "d" fstest.go:251: Filtering empty directory "d/e" 2021/06/01 06:16:30 DEBUG : c/non empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/06/01 06:16:30 DEBUG : c/non empty space: Unchanged skipping 2021/06/01 06:16:30 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for checks to finish 2021/06/01 06:16:30 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for transfers to finish 2021/06/01 06:16:30 DEBUG : a/potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/06/01 06:16:30 INFO : a/potato2: Copied (new) 2021/06/01 06:16:30 DEBUG : Waiting for deletions to finish 2021/06/01 06:16:31 INFO : b/potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (6.76s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" 2021/06/01 06:16:37 DEBUG : d: Making directory fstest.go:251: Filtering empty directory "d" 2021/06/01 06:16:37 DEBUG : c/non empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/06/01 06:16:37 DEBUG : c/non empty space: Unchanged skipping 2021/06/01 06:16:37 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for checks to finish 2021/06/01 06:16:37 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for transfers to finish 2021/06/01 06:16:38 DEBUG : a/potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/06/01 06:16:38 INFO : a/potato2: Copied (new) 2021/06/01 06:16:38 ERROR : B2 bucket rclone-test-xedunix8facujup4nasavof3: not deleting files as there were IO errors 2021/06/01 06:16:38 ERROR : B2 bucket rclone-test-xedunix8facujup4nasavof3: not deleting directories as there were IO errors fstest.go:251: Filtering empty directory "d" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (12.93s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" 2021/06/01 06:16:50 DEBUG : Waiting for deletions to finish 2021/06/01 06:16:50 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/06/01 06:16:50 DEBUG : empty space: Unchanged skipping 2021/06/01 06:16:50 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for checks to finish 2021/06/01 06:16:50 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for transfers to finish 2021/06/01 06:16:50 INFO : potato: Deleted 2021/06/01 06:16:51 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/06/01 06:16:51 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteDuring (2.63s) === RUN TestSyncDeleteBefore run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" 2021/06/01 06:16:53 DEBUG : Waiting for deletions to finish 2021/06/01 06:16:53 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for checks to finish 2021/06/01 06:16:53 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for transfers to finish 2021/06/01 06:16:53 INFO : potato: Deleted 2021/06/01 06:16:53 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/06/01 06:16:53 DEBUG : empty space: Unchanged skipping 2021/06/01 06:16:53 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for checks to finish 2021/06/01 06:16:53 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for transfers to finish 2021/06/01 06:16:54 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/06/01 06:16:54 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteBefore (3.04s) === RUN TestCopyDeleteBefore run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" 2021/06/01 06:16:55 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for checks to finish 2021/06/01 06:16:55 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for transfers to finish 2021/06/01 06:16:56 DEBUG : potato2: sha1 = 810274f0df19dab60a894a562b7a8ff04c072623 OK 2021/06/01 06:16:56 INFO : potato2: Copied (new) --- PASS: TestCopyDeleteBefore (2.02s) === RUN TestSyncWithExclude run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" 2021/06/01 06:16:57 DEBUG : enormous: Excluded 2021/06/01 06:16:57 DEBUG : potato2: Excluded 2021/06/01 06:16:58 DEBUG : potato2: Excluded from sync (and deletion) 2021/06/01 06:16:58 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/06/01 06:16:58 DEBUG : empty space: Unchanged skipping 2021/06/01 06:16:58 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for checks to finish 2021/06/01 06:16:58 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for transfers to finish 2021/06/01 06:16:58 DEBUG : Waiting for deletions to finish 2021/06/01 06:16:58 INFO : There was nothing to transfer 2021/06/01 06:16:58 DEBUG : enormous: Excluded 2021/06/01 06:16:58 DEBUG : potato2: Excluded 2021/06/01 06:16:58 DEBUG : potato2: Excluded from sync (and deletion) 2021/06/01 06:16:58 DEBUG : empty space: Size and modification time the same (differ by 456.789µs, within tolerance 1ms) 2021/06/01 06:16:58 DEBUG : empty space: Unchanged skipping 2021/06/01 06:16:58 DEBUG : Local file system at /tmp/rclone500016083: Waiting for checks to finish 2021/06/01 06:16:58 DEBUG : Local file system at /tmp/rclone500016083: Waiting for transfers to finish 2021/06/01 06:16:58 DEBUG : Waiting for deletions to finish 2021/06/01 06:16:58 INFO : There was nothing to transfer --- PASS: TestSyncWithExclude (2.58s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" 2021/06/01 06:17:00 DEBUG : enormous: Excluded 2021/06/01 06:17:00 DEBUG : potato2: Excluded 2021/06/01 06:17:00 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/06/01 06:17:00 DEBUG : empty space: Unchanged skipping 2021/06/01 06:17:00 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for checks to finish 2021/06/01 06:17:00 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for transfers to finish 2021/06/01 06:17:00 DEBUG : Waiting for deletions to finish 2021/06/01 06:17:01 INFO : enormous: Deleted 2021/06/01 06:17:02 INFO : potato2: Deleted 2021/06/01 06:17:02 INFO : There was nothing to transfer 2021/06/01 06:17:02 DEBUG : empty space: Size and modification time the same (differ by 456.789µs, within tolerance 1ms) 2021/06/01 06:17:02 DEBUG : empty space: Unchanged skipping 2021/06/01 06:17:02 DEBUG : Local file system at /tmp/rclone500016083: Waiting for checks to finish 2021/06/01 06:17:02 DEBUG : Local file system at /tmp/rclone500016083: Waiting for transfers to finish 2021/06/01 06:17:02 DEBUG : Waiting for deletions to finish 2021/06/01 06:17:02 INFO : enormous: Deleted 2021/06/01 06:17:02 INFO : potato2: Deleted 2021/06/01 06:17:02 INFO : There was nothing to transfer --- PASS: TestSyncWithExcludeAndDeleteExcluded (3.62s) === RUN TestSyncWithUpdateOlder run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" 2021/06/01 06:17:04 DEBUG : four: Sizes differ (src 4 vs dst 8) 2021/06/01 06:17:04 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2021/06/01 06:17:04 DEBUG : one: Destination is newer than source, skipping 2021/06/01 06:17:04 DEBUG : three: Sizes identical 2021/06/01 06:17:04 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2021/06/01 06:17:04 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for checks to finish 2021/06/01 06:17:04 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b (Local file system at /tmp/rclone500016083) 2021/06/01 06:17:04 DEBUG : two: sha1 = b10e0da85e90399abfec36c7376b20aa4596461b (B2 bucket rclone-test-xedunix8facujup4nasavof3) 2021/06/01 06:17:04 DEBUG : two: sha1 differ 2021/06/01 06:17:04 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for transfers to finish 2021/06/01 06:17:05 DEBUG : five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2021/06/01 06:17:05 INFO : five: Copied (new) 2021/06/01 06:17:06 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2021/06/01 06:17:06 INFO : two: Copied (replaced existing) 2021/06/01 06:17:09 DEBUG : four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2021/06/01 06:17:09 INFO : four: Copied (replaced existing) 2021/06/01 06:17:09 DEBUG : Waiting for deletions to finish 2021/06/01 06:17:10 DEBUG : one: Destination is newer than source, skipping 2021/06/01 06:17:10 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for checks to finish 2021/06/01 06:17:10 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2021/06/01 06:17:10 DEBUG : two: Size and sha1 of src and dst objects identical 2021/06/01 06:17:10 DEBUG : two: Destination mod time is within 1s of source and files identical, skipping 2021/06/01 06:17:10 DEBUG : five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2021/06/01 06:17:10 DEBUG : five: Size and sha1 of src and dst objects identical 2021/06/01 06:17:10 DEBUG : five: Destination mod time is within 1s of source and files identical, skipping 2021/06/01 06:17:10 DEBUG : four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2021/06/01 06:17:10 DEBUG : four: Size and sha1 of src and dst objects identical 2021/06/01 06:17:10 DEBUG : four: Destination mod time is within 1s of source and files identical, skipping 2021/06/01 06:17:10 DEBUG : three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb (Local file system at /tmp/rclone500016083) 2021/06/01 06:17:10 DEBUG : three: sha1 = 03731cad13f4cf7b59703c77529381cdec1632e5 (B2 bucket rclone-test-xedunix8facujup4nasavof3) 2021/06/01 06:17:10 DEBUG : three: sha1 differ 2021/06/01 06:17:10 DEBUG : three: Destination mod time is within 1s of source but files differ, transferring 2021/06/01 06:17:10 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for transfers to finish 2021/06/01 06:17:10 DEBUG : three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/06/01 06:17:10 INFO : three: Copied (replaced existing) 2021/06/01 06:17:10 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithUpdateOlder (9.49s) === RUN TestSyncWithMaxDuration sync_test.go:1039: Skipping test on non local remote --- SKIP: TestSyncWithMaxDuration (0.00s) === RUN TestSyncWithTrackRenames run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" sync_test.go:1088: Can track renames: true 2021/06/01 06:17:12 INFO : B2 bucket rclone-test-xedunix8facujup4nasavof3: Making map for --track-renames 2021/06/01 06:17:12 INFO : B2 bucket rclone-test-xedunix8facujup4nasavof3: Finished making map for --track-renames 2021/06/01 06:17:12 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for checks to finish 2021/06/01 06:17:12 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for renames to finish 2021/06/01 06:17:12 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for transfers to finish 2021/06/01 06:17:13 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2021/06/01 06:17:13 INFO : yam: Copied (new) 2021/06/01 06:17:13 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2021/06/01 06:17:13 INFO : potato: Copied (new) 2021/06/01 06:17:13 DEBUG : Waiting for deletions to finish 2021/06/01 06:17:14 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/06/01 06:17:14 DEBUG : potato: Unchanged skipping 2021/06/01 06:17:14 INFO : B2 bucket rclone-test-xedunix8facujup4nasavof3: Making map for --track-renames 2021/06/01 06:17:14 INFO : B2 bucket rclone-test-xedunix8facujup4nasavof3: Finished making map for --track-renames 2021/06/01 06:17:14 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for checks to finish 2021/06/01 06:17:14 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for renames to finish 2021/06/01 06:17:14 DEBUG : Couldn't decode error response: EOF 2021/06/01 06:17:15 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2021/06/01 06:17:15 INFO : yam: Copied (server-side copy) to: yaml 2021/06/01 06:17:15 INFO : yam: Deleted 2021/06/01 06:17:15 INFO : yaml: Renamed from "yam" 2021/06/01 06:17:15 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for transfers to finish 2021/06/01 06:17:15 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenames (4.13s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" sync_test.go:1157: Can track renames: true 2021/06/01 06:17:17 INFO : B2 bucket rclone-test-xedunix8facujup4nasavof3: Making map for --track-renames 2021/06/01 06:17:17 INFO : B2 bucket rclone-test-xedunix8facujup4nasavof3: Finished making map for --track-renames 2021/06/01 06:17:17 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for checks to finish 2021/06/01 06:17:17 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for renames to finish 2021/06/01 06:17:17 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for transfers to finish 2021/06/01 06:17:17 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2021/06/01 06:17:17 INFO : yam: Copied (new) 2021/06/01 06:17:17 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2021/06/01 06:17:17 INFO : potato: Copied (new) 2021/06/01 06:17:17 DEBUG : Waiting for deletions to finish 2021/06/01 06:17:18 INFO : B2 bucket rclone-test-xedunix8facujup4nasavof3: Making map for --track-renames 2021/06/01 06:17:18 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/06/01 06:17:18 DEBUG : potato: Unchanged skipping 2021/06/01 06:17:18 INFO : B2 bucket rclone-test-xedunix8facujup4nasavof3: Finished making map for --track-renames 2021/06/01 06:17:18 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for checks to finish 2021/06/01 06:17:18 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for renames to finish 2021/06/01 06:17:18 DEBUG : Couldn't decode error response: EOF 2021/06/01 06:17:19 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2021/06/01 06:17:19 INFO : yam: Copied (server-side copy) to: yaml 2021/06/01 06:17:20 INFO : yam: Deleted 2021/06/01 06:17:20 INFO : yaml: Renamed from "yam" 2021/06/01 06:17:20 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for transfers to finish 2021/06/01 06:17:20 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenamesStrategyModtime (7.75s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" sync_test.go:1193: Can track renames: true 2021/06/01 06:17:24 INFO : B2 bucket rclone-test-xedunix8facujup4nasavof3: Making map for --track-renames 2021/06/01 06:17:24 INFO : B2 bucket rclone-test-xedunix8facujup4nasavof3: Finished making map for --track-renames 2021/06/01 06:17:24 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for checks to finish 2021/06/01 06:17:24 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for renames to finish 2021/06/01 06:17:24 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for transfers to finish 2021/06/01 06:17:25 DEBUG : sub/yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2021/06/01 06:17:25 INFO : sub/yam: Copied (new) 2021/06/01 06:17:25 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2021/06/01 06:17:25 INFO : potato: Copied (new) 2021/06/01 06:17:25 DEBUG : Waiting for deletions to finish 2021/06/01 06:17:26 INFO : B2 bucket rclone-test-xedunix8facujup4nasavof3: Making map for --track-renames 2021/06/01 06:17:26 INFO : B2 bucket rclone-test-xedunix8facujup4nasavof3: Finished making map for --track-renames 2021/06/01 06:17:26 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for checks to finish 2021/06/01 06:17:26 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/06/01 06:17:26 DEBUG : potato: Unchanged skipping 2021/06/01 06:17:26 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for renames to finish 2021/06/01 06:17:26 DEBUG : Couldn't decode error response: EOF 2021/06/01 06:17:27 DEBUG : sub/yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2021/06/01 06:17:27 INFO : sub/yam: Copied (server-side copy) to: yam 2021/06/01 06:17:27 INFO : sub/yam: Deleted 2021/06/01 06:17:27 INFO : yam: Renamed from "sub/yam" 2021/06/01 06:17:27 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for transfers to finish 2021/06/01 06:17:27 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenamesStrategyLeaf (4.14s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" 2021/06/01 06:17:28 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for checks to finish 2021/06/01 06:17:28 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for transfers to finish 2021/06/01 06:17:29 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/06/01 06:17:29 INFO : sub dir/hello world: Copied (new) 2021/06/01 06:17:29 INFO : sub dir/hello world: Deleted 2021/06/01 06:17:29 DEBUG : nested/sub dir/file: sha1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK 2021/06/01 06:17:29 INFO : nested/sub dir/file: Copied (new) 2021/06/01 06:17:29 INFO : nested/sub dir/file: Deleted 2021/06/01 06:17:29 DEBUG : sub dir: Removing directory 2021/06/01 06:17:29 DEBUG : nested/sub dir: Removing directory 2021/06/01 06:17:29 DEBUG : nested: Removing directory 2021/06/01 06:17:29 DEBUG : Local file system at /tmp/rclone500016083: deleted 3 directories --- PASS: TestMoveWithDeleteEmptySrcDirs (2.65s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" 2021/06/01 06:17:31 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for checks to finish 2021/06/01 06:17:31 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for transfers to finish 2021/06/01 06:17:31 DEBUG : nested/sub dir/file: sha1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK 2021/06/01 06:17:31 INFO : nested/sub dir/file: Copied (new) 2021/06/01 06:17:31 INFO : nested/sub dir/file: Deleted 2021/06/01 06:17:32 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/06/01 06:17:32 INFO : sub dir/hello world: Copied (new) 2021/06/01 06:17:32 INFO : sub dir/hello world: Deleted --- PASS: TestMoveWithoutDeleteEmptySrcDirs (1.97s) === RUN TestServerSideMove run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" 2021/06/01 06:17:33 DEBUG : Creating backend with remote "TestB2:rclone-test-meguwuj8hihujon0hifonin7" sync_test.go:1245: Server side move (if possible) B2 bucket rclone-test-xedunix8facujup4nasavof3 -> B2 bucket rclone-test-meguwuj8hihujon0hifonin7 2021/06/01 06:17:37 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms) 2021/06/01 06:17:37 DEBUG : empty space: Unchanged skipping 2021/06/01 06:17:37 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2021/06/01 06:17:37 DEBUG : B2 bucket rclone-test-meguwuj8hihujon0hifonin7: Waiting for checks to finish 2021/06/01 06:17:37 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/06/01 06:17:37 INFO : potato2: Copied (server-side copy) 2021/06/01 06:17:38 INFO : potato2: Deleted 2021/06/01 06:17:38 DEBUG : potato3: sha1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2021/06/01 06:17:38 INFO : potato3: Copied (server-side copy) 2021/06/01 06:17:38 INFO : potato3: Deleted 2021/06/01 06:17:38 INFO : empty space: Deleted 2021/06/01 06:17:38 DEBUG : B2 bucket rclone-test-meguwuj8hihujon0hifonin7: Waiting for transfers to finish 2021/06/01 06:17:38 DEBUG : Creating backend with remote "TestB2:rclone-test-nopuror7wevurul1suxafoh8" 2021/06/01 06:17:39 DEBUG : B2 bucket rclone-test-nopuror7wevurul1suxafoh8: Waiting for checks to finish 2021/06/01 06:17:39 DEBUG : B2 bucket rclone-test-nopuror7wevurul1suxafoh8: Waiting for transfers to finish 2021/06/01 06:17:39 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2021/06/01 06:17:39 INFO : empty space: Copied (server-side copy) 2021/06/01 06:17:39 DEBUG : potato3: sha1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2021/06/01 06:17:39 INFO : potato3: Copied (server-side copy) 2021/06/01 06:17:40 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/06/01 06:17:40 INFO : potato2: Copied (server-side copy) 2021/06/01 06:17:40 INFO : potato2: Deleted 2021/06/01 06:17:42 INFO : empty space: Deleted 2021/06/01 06:17:44 INFO : potato3: Deleted 2021/06/01 06:17:44 DEBUG : B2 bucket rclone-test-nopuror7wevurul1suxafoh8: Purge remote 2021/06/01 06:17:45 DEBUG : empty space: Deleting (id "4_z2fd27d53308dcb6b7f980e1b_f102e02d1203175f1_d20210601_m061739_c001_v0001137_t0049") 2021/06/01 06:17:45 DEBUG : potato2: Deleting (id "4_z2fd27d53308dcb6b7f980e1b_f102e4527c06f4182_d20210601_m061739_c001_v0001033_t0047") 2021/06/01 06:17:45 DEBUG : potato3: Deleting (id "4_z2fd27d53308dcb6b7f980e1b_f1143a5ebfc6f4d4a_d20210601_m061739_c001_v0001128_t0047") 2021/06/01 06:17:45 DEBUG : B2 bucket rclone-test-meguwuj8hihujon0hifonin7: Purge remote 2021/06/01 06:17:45 DEBUG : empty space: Deleting (id "4_zffc26d43308dcb6b7f980e1b_f110fc55f82e502e6_d20210601_m061739_c001_v0001144_t0010") 2021/06/01 06:17:45 DEBUG : empty space: Deleting (id "4_zffc26d43308dcb6b7f980e1b_f118a8cdd069c8842_d20210601_m061736_c001_v0001154_t0021") 2021/06/01 06:17:45 DEBUG : potato2: Deleting (id "4_zffc26d43308dcb6b7f980e1b_f1153a927429ec337_d20210601_m061740_c001_v0001036_t0054") 2021/06/01 06:17:45 DEBUG : potato2: Deleting (id "4_zffc26d43308dcb6b7f980e1b_f113194f09cb93167_d20210601_m061737_c001_v0001042_t0054") 2021/06/01 06:17:45 DEBUG : potato3: Deleting (id "4_zffc26d43308dcb6b7f980e1b_f1049711b6fc636de_d20210601_m061740_c001_v0001144_t0014") 2021/06/01 06:17:45 DEBUG : potato3: Deleting (id "4_zffc26d43308dcb6b7f980e1b_f119f2190c358bba5_d20210601_m061737_c001_v0001145_t0007") 2021/06/01 06:17:45 DEBUG : potato3: Deleting (id "4_zffc26d43308dcb6b7f980e1b_f118a8cdd069c8846_d20210601_m061736_c001_v0001154_t0021") --- PASS: TestServerSideMove (13.30s) === RUN TestServerSideMoveWithFilter run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" 2021/06/01 06:17:46 DEBUG : Creating backend with remote "TestB2:rclone-test-qimetiq2rukagep7caqawop3" sync_test.go:1245: Server side move (if possible) B2 bucket rclone-test-xedunix8facujup4nasavof3 -> B2 bucket rclone-test-qimetiq2rukagep7caqawop3 2021/06/01 06:17:57 DEBUG : empty space: Excluded from sync (and deletion) 2021/06/01 06:17:57 DEBUG : empty space: Excluded from sync (and deletion) 2021/06/01 06:17:57 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2021/06/01 06:17:57 DEBUG : B2 bucket rclone-test-qimetiq2rukagep7caqawop3: Waiting for checks to finish 2021/06/01 06:17:57 DEBUG : B2 bucket rclone-test-qimetiq2rukagep7caqawop3: Waiting for transfers to finish 2021/06/01 06:17:58 DEBUG : potato3: sha1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2021/06/01 06:17:58 INFO : potato3: Copied (server-side copy) 2021/06/01 06:17:58 INFO : potato3: Deleted 2021/06/01 06:18:03 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/06/01 06:18:03 INFO : potato2: Copied (server-side copy) 2021/06/01 06:18:04 INFO : potato2: Deleted 2021/06/01 06:18:04 DEBUG : Creating backend with remote "TestB2:rclone-test-melemum9tixiran5sijarih1" 2021/06/01 06:18:05 DEBUG : empty space: Excluded from sync (and deletion) 2021/06/01 06:18:05 DEBUG : B2 bucket rclone-test-melemum9tixiran5sijarih1: Waiting for checks to finish 2021/06/01 06:18:05 DEBUG : B2 bucket rclone-test-melemum9tixiran5sijarih1: Waiting for transfers to finish 2021/06/01 06:18:06 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/06/01 06:18:06 INFO : potato2: Copied (server-side copy) 2021/06/01 06:18:06 DEBUG : potato3: sha1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2021/06/01 06:18:06 INFO : potato3: Copied (server-side copy) 2021/06/01 06:18:06 INFO : potato2: Deleted 2021/06/01 06:18:09 INFO : potato3: Deleted 2021/06/01 06:18:09 DEBUG : B2 bucket rclone-test-melemum9tixiran5sijarih1: Purge remote 2021/06/01 06:18:09 DEBUG : potato2: Deleting (id "4_z4fe2ddc3308dcb6b7f980e1b_f102e4527c06f41b7_d20210601_m061806_c001_v0001033_t0017") 2021/06/01 06:18:09 DEBUG : potato3: Deleting (id "4_z4fe2ddc3308dcb6b7f980e1b_f109fccb540c1cf5b_d20210601_m061806_c001_v0001005_t0002") 2021/06/01 06:18:10 DEBUG : B2 bucket rclone-test-qimetiq2rukagep7caqawop3: Purge remote 2021/06/01 06:18:10 DEBUG : empty space: Deleting (id "4_zff429df3308dcb6b7f980e1b_f11241bc663066598_d20210601_m061751_c001_v0001144_t0043") 2021/06/01 06:18:10 DEBUG : potato2: Deleting (id "4_zff429df3308dcb6b7f980e1b_f113258b9e8a50e75_d20210601_m061806_c001_v0001128_t0039") 2021/06/01 06:18:10 DEBUG : potato2: Deleting (id "4_zff429df3308dcb6b7f980e1b_f103674da1fc82431_d20210601_m061757_c001_v0001144_t0003") 2021/06/01 06:18:10 DEBUG : potato3: Deleting (id "4_zff429df3308dcb6b7f980e1b_f1049711b6fc63778_d20210601_m061806_c001_v0001144_t0030") 2021/06/01 06:18:10 DEBUG : potato3: Deleting (id "4_zff429df3308dcb6b7f980e1b_f114510e647f1cab5_d20210601_m061757_c001_v0001022_t0052") 2021/06/01 06:18:10 DEBUG : potato3: Deleting (id "4_zff429df3308dcb6b7f980e1b_f11241bc6630665ac_d20210601_m061753_c001_v0001144_t0043") --- PASS: TestServerSideMoveWithFilter (25.66s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" 2021/06/01 06:18:12 DEBUG : Creating backend with remote "TestB2:rclone-test-qehikef9qopiyat6sarepab2" 2021/06/01 06:18:15 DEBUG : tomatoDir: Making directory sync_test.go:1245: Server side move (if possible) B2 bucket rclone-test-xedunix8facujup4nasavof3 -> B2 bucket rclone-test-qehikef9qopiyat6sarepab2 2021/06/01 06:18:17 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2021/06/01 06:18:17 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms) 2021/06/01 06:18:17 DEBUG : empty space: Unchanged skipping 2021/06/01 06:18:17 DEBUG : B2 bucket rclone-test-qehikef9qopiyat6sarepab2: Waiting for checks to finish 2021/06/01 06:18:17 DEBUG : potato3: sha1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2021/06/01 06:18:17 INFO : potato3: Copied (server-side copy) 2021/06/01 06:18:18 INFO : potato3: Deleted 2021/06/01 06:18:19 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/06/01 06:18:19 INFO : potato2: Copied (server-side copy) 2021/06/01 06:18:19 INFO : potato2: Deleted 2021/06/01 06:18:21 INFO : empty space: Deleted 2021/06/01 06:18:21 DEBUG : B2 bucket rclone-test-qehikef9qopiyat6sarepab2: Waiting for transfers to finish 2021/06/01 06:18:21 DEBUG : Creating backend with remote "TestB2:rclone-test-lewidap9tasahax4neselew6" 2021/06/01 06:18:22 DEBUG : tomatoDir: Making directory 2021/06/01 06:18:22 DEBUG : B2 bucket rclone-test-lewidap9tasahax4neselew6: Waiting for checks to finish 2021/06/01 06:18:22 DEBUG : B2 bucket rclone-test-lewidap9tasahax4neselew6: Waiting for transfers to finish 2021/06/01 06:18:23 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/06/01 06:18:23 INFO : potato2: Copied (server-side copy) 2021/06/01 06:18:23 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2021/06/01 06:18:23 INFO : empty space: Copied (server-side copy) 2021/06/01 06:18:23 DEBUG : potato3: sha1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2021/06/01 06:18:23 INFO : potato3: Copied (server-side copy) 2021/06/01 06:18:23 INFO : potato2: Deleted 2021/06/01 06:18:23 INFO : empty space: Deleted 2021/06/01 06:18:25 INFO : potato3: Deleted 2021/06/01 06:18:25 DEBUG : B2 bucket rclone-test-lewidap9tasahax4neselew6: Purge remote 2021/06/01 06:18:25 DEBUG : empty space: Deleting (id "4_z0fa21de3309dcb6b7f980e1b_f1191df7c8ac7ab2c_d20210601_m061823_c001_v0001011_t0032") 2021/06/01 06:18:25 DEBUG : potato2: Deleting (id "4_z0fa21de3309dcb6b7f980e1b_f1163567ec2d4f132_d20210601_m061823_c001_v0001040_t0035") 2021/06/01 06:18:25 DEBUG : potato3: Deleting (id "4_z0fa21de3309dcb6b7f980e1b_f114c53e63b833481_d20210601_m061823_c001_v0001039_t0005") 2021/06/01 06:18:26 DEBUG : B2 bucket rclone-test-qehikef9qopiyat6sarepab2: Purge remote 2021/06/01 06:18:26 DEBUG : empty space: Deleting (id "4_z9f820d33309dcb6b7f980e1b_f114c53e63b833482_d20210601_m061823_c001_v0001039_t0015") 2021/06/01 06:18:26 DEBUG : empty space: Deleting (id "4_z9f820d33309dcb6b7f980e1b_f11865cf06e603d01_d20210601_m061816_c001_v0001131_t0025") 2021/06/01 06:18:26 DEBUG : potato2: Deleting (id "4_z9f820d33309dcb6b7f980e1b_f1007db4d88413fc5_d20210601_m061823_c001_v0001130_t0040") 2021/06/01 06:18:26 DEBUG : potato2: Deleting (id "4_z9f820d33309dcb6b7f980e1b_f11787bb91aab7c23_d20210601_m061817_c001_v0001144_t0056") 2021/06/01 06:18:26 DEBUG : potato3: Deleting (id "4_z9f820d33309dcb6b7f980e1b_f106419cadda20cfc_d20210601_m061823_c001_v0001144_t0023") 2021/06/01 06:18:26 DEBUG : potato3: Deleting (id "4_z9f820d33309dcb6b7f980e1b_f105d447f92beaeb8_d20210601_m061817_c001_v0001134_t0048") 2021/06/01 06:18:26 DEBUG : potato3: Deleting (id "4_z9f820d33309dcb6b7f980e1b_f11865cf06e603d03_d20210601_m061816_c001_v0001131_t0025") --- PASS: TestServerSideMoveDeleteEmptySourceDirs (15.03s) === RUN TestServerSideMoveOverlap run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" 2021/06/01 06:18:27 DEBUG : Creating backend with remote "TestB2:rclone-test-xedunix8facujup4nasavof3/rclone-move-test" 2021/06/01 06:18:28 DEBUG : Couldn't decode error response: EOF --- PASS: TestServerSideMoveOverlap (3.07s) === RUN TestSyncOverlap run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" 2021/06/01 06:18:30 DEBUG : Creating backend with remote "TestB2:rclone-test-xedunix8facujup4nasavof3/rclone-sync-test" 2021/06/01 06:18:30 DEBUG : Couldn't decode error response: EOF --- PASS: TestSyncOverlap (0.65s) === RUN TestSyncCompareDest run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" 2021/06/01 06:18:31 DEBUG : Creating backend with remote "TestB2:rclone-test-xedunix8facujup4nasavof3/dst" 2021/06/01 06:18:31 DEBUG : Couldn't decode error response: EOF 2021/06/01 06:18:31 DEBUG : Creating backend with remote "TestB2:rclone-test-xedunix8facujup4nasavof3/CompareDest" 2021/06/01 06:18:31 DEBUG : Couldn't decode error response: EOF 2021/06/01 06:18:32 DEBUG : Couldn't decode error response: EOF 2021/06/01 06:18:32 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3 path dst: Waiting for checks to finish 2021/06/01 06:18:32 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3 path dst: Waiting for transfers to finish 2021/06/01 06:18:33 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/06/01 06:18:33 INFO : one: Copied (new) 2021/06/01 06:18:33 DEBUG : Waiting for deletions to finish 2021/06/01 06:18:33 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3 path dst: Waiting for checks to finish 2021/06/01 06:18:33 DEBUG : Couldn't decode error response: EOF 2021/06/01 06:18:33 DEBUG : one: Sizes differ (src 5 vs dst 3) 2021/06/01 06:18:33 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3 path dst: Waiting for transfers to finish 2021/06/01 06:18:34 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2021/06/01 06:18:34 INFO : one: Copied (replaced existing) 2021/06/01 06:18:34 DEBUG : Waiting for deletions to finish 2021/06/01 06:18:38 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3 path dst: Waiting for checks to finish 2021/06/01 06:18:38 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/06/01 06:18:38 DEBUG : one: Destination found in --compare-dest, skipping 2021/06/01 06:18:38 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3 path dst: Waiting for transfers to finish 2021/06/01 06:18:38 DEBUG : Waiting for deletions to finish 2021/06/01 06:18:38 INFO : There was nothing to transfer 2021/06/01 06:18:39 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/06/01 06:18:39 DEBUG : two: Destination found in --compare-dest, skipping 2021/06/01 06:18:39 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3 path dst: Waiting for checks to finish 2021/06/01 06:18:39 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/06/01 06:18:39 DEBUG : one: Destination found in --compare-dest, skipping 2021/06/01 06:18:39 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3 path dst: Waiting for transfers to finish 2021/06/01 06:18:39 DEBUG : Waiting for deletions to finish 2021/06/01 06:18:39 INFO : There was nothing to transfer 2021/06/01 06:18:40 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/06/01 06:18:40 DEBUG : two: Destination found in --compare-dest, skipping 2021/06/01 06:18:40 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3 path dst: Waiting for checks to finish 2021/06/01 06:18:40 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/06/01 06:18:40 DEBUG : one: Destination found in --compare-dest, skipping 2021/06/01 06:18:40 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3 path dst: Waiting for transfers to finish 2021/06/01 06:18:40 DEBUG : Waiting for deletions to finish 2021/06/01 06:18:40 INFO : There was nothing to transfer 2021/06/01 06:18:40 DEBUG : two: Sizes differ (src 5 vs dst 3) 2021/06/01 06:18:40 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3 path dst: Waiting for checks to finish 2021/06/01 06:18:40 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/06/01 06:18:40 DEBUG : one: Destination found in --compare-dest, skipping 2021/06/01 06:18:40 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3 path dst: Waiting for transfers to finish 2021/06/01 06:18:41 DEBUG : two: sha1 = a4be8877ad88f3109a6411985a1f359e10e2d930 OK 2021/06/01 06:18:41 INFO : two: Copied (new) 2021/06/01 06:18:41 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCompareDest (12.86s) === RUN TestSyncMultipleCompareDest run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" 2021/06/01 06:18:45 DEBUG : Creating backend with remote "TestB2:rclone-test-xedunix8facujup4nasavof3/dest" 2021/06/01 06:18:46 DEBUG : Couldn't decode error response: EOF 2021/06/01 06:18:46 DEBUG : Creating backend with remote "TestB2:rclone-test-xedunix8facujup4nasavof3/pre-dest1" 2021/06/01 06:18:47 DEBUG : Couldn't decode error response: EOF 2021/06/01 06:18:47 DEBUG : Creating backend with remote "TestB2:rclone-test-xedunix8facujup4nasavof3/pre-dest2" 2021/06/01 06:18:47 DEBUG : Couldn't decode error response: EOF 2021/06/01 06:18:48 DEBUG : 1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/06/01 06:18:48 DEBUG : 1: Destination found in --compare-dest, skipping 2021/06/01 06:18:48 DEBUG : Couldn't decode error response: EOF 2021/06/01 06:18:48 DEBUG : 2: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/06/01 06:18:48 DEBUG : 2: Destination found in --compare-dest, skipping 2021/06/01 06:18:48 DEBUG : Couldn't decode error response: EOF 2021/06/01 06:18:48 DEBUG : Couldn't decode error response: EOF 2021/06/01 06:18:48 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3 path dest: Waiting for checks to finish 2021/06/01 06:18:48 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3 path dest: Waiting for transfers to finish 2021/06/01 06:18:49 DEBUG : 3: sha1 = 77de68daecd823babbb58edb1c8e14d7106e83bb OK 2021/06/01 06:18:49 INFO : 3: Copied (new) 2021/06/01 06:18:49 DEBUG : Waiting for deletions to finish --- PASS: TestSyncMultipleCompareDest (7.52s) === RUN TestSyncCopyDest run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" 2021/06/01 06:18:51 DEBUG : Creating backend with remote "TestB2:rclone-test-xedunix8facujup4nasavof3/dst" 2021/06/01 06:18:51 DEBUG : Couldn't decode error response: EOF 2021/06/01 06:18:51 DEBUG : Creating backend with remote "TestB2:rclone-test-xedunix8facujup4nasavof3/CopyDest" 2021/06/01 06:18:52 DEBUG : Couldn't decode error response: EOF 2021/06/01 06:18:52 DEBUG : Couldn't decode error response: EOF 2021/06/01 06:18:52 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3 path dst: Waiting for checks to finish 2021/06/01 06:18:52 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3 path dst: Waiting for transfers to finish 2021/06/01 06:18:53 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/06/01 06:18:53 INFO : one: Copied (new) 2021/06/01 06:18:53 DEBUG : Waiting for deletions to finish 2021/06/01 06:18:53 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3 path dst: Waiting for checks to finish 2021/06/01 06:18:54 DEBUG : Couldn't decode error response: EOF 2021/06/01 06:18:54 DEBUG : one: Sizes differ (src 5 vs dst 3) 2021/06/01 06:18:54 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3 path dst: Waiting for transfers to finish 2021/06/01 06:18:54 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2021/06/01 06:18:54 INFO : one: Copied (replaced existing) 2021/06/01 06:18:54 DEBUG : Waiting for deletions to finish 2021/06/01 06:18:56 DEBUG : Creating backend with remote "TestB2:rclone-test-xedunix8facujup4nasavof3/BackupDir" 2021/06/01 06:18:56 DEBUG : Couldn't decode error response: EOF 2021/06/01 06:18:56 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3 path dst: Waiting for checks to finish 2021/06/01 06:18:56 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/06/01 06:18:56 DEBUG : one: Sizes differ (src 5 vs dst 3) 2021/06/01 06:18:56 DEBUG : Couldn't decode error response: EOF 2021/06/01 06:18:57 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/06/01 06:18:57 INFO : one: Copied (server-side copy) 2021/06/01 06:18:58 INFO : one: Deleted 2021/06/01 06:18:58 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2021/06/01 06:18:58 INFO : one: Copied (server-side copy) 2021/06/01 06:18:58 DEBUG : one: Destination found in --copy-dest, using server-side copy 2021/06/01 06:18:58 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3 path dst: Waiting for transfers to finish 2021/06/01 06:18:58 DEBUG : Waiting for deletions to finish 2021/06/01 06:19:00 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/06/01 06:19:02 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2021/06/01 06:19:02 INFO : two: Copied (server-side copy) 2021/06/01 06:19:02 DEBUG : two: Destination found in --copy-dest, using server-side copy 2021/06/01 06:19:02 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3 path dst: Waiting for checks to finish 2021/06/01 06:19:02 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/06/01 06:19:02 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/06/01 06:19:02 DEBUG : one: Unchanged skipping 2021/06/01 06:19:02 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3 path dst: Waiting for transfers to finish 2021/06/01 06:19:02 DEBUG : Waiting for deletions to finish 2021/06/01 06:19:03 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3 path dst: Waiting for checks to finish 2021/06/01 06:19:03 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/06/01 06:19:03 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/06/01 06:19:03 DEBUG : one: Unchanged skipping 2021/06/01 06:19:03 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/06/01 06:19:03 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/06/01 06:19:03 DEBUG : two: Unchanged skipping 2021/06/01 06:19:03 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3 path dst: Waiting for transfers to finish 2021/06/01 06:19:03 DEBUG : Waiting for deletions to finish 2021/06/01 06:19:03 INFO : There was nothing to transfer 2021/06/01 06:19:04 DEBUG : three: Sizes differ (src 7 vs dst 5) 2021/06/01 06:19:04 DEBUG : three: Destination not found in --copy-dest 2021/06/01 06:19:04 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3 path dst: Waiting for checks to finish 2021/06/01 06:19:04 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/06/01 06:19:04 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/06/01 06:19:04 DEBUG : one: Unchanged skipping 2021/06/01 06:19:04 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/06/01 06:19:04 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/06/01 06:19:04 DEBUG : two: Unchanged skipping 2021/06/01 06:19:04 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3 path dst: Waiting for transfers to finish 2021/06/01 06:19:05 DEBUG : three: sha1 = 370e613335f3cf2f19b2a2d35a86402f55c659f8 OK 2021/06/01 06:19:05 INFO : three: Copied (new) 2021/06/01 06:19:05 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCopyDest (27.50s) === RUN TestSyncBackupDir run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" 2021/06/01 06:19:22 DEBUG : Creating backend with remote "TestB2:rclone-test-xedunix8facujup4nasavof3/dst" 2021/06/01 06:19:24 DEBUG : Couldn't decode error response: EOF 2021/06/01 06:19:24 DEBUG : Creating backend with remote "TestB2:rclone-test-xedunix8facujup4nasavof3/backup" 2021/06/01 06:19:24 DEBUG : Couldn't decode error response: EOF 2021/06/01 06:19:24 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/06/01 06:19:24 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/06/01 06:19:24 DEBUG : two: Unchanged skipping 2021/06/01 06:19:24 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3 path dst: Waiting for checks to finish 2021/06/01 06:19:24 DEBUG : Couldn't decode error response: EOF 2021/06/01 06:19:27 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/06/01 06:19:27 INFO : one: Copied (server-side copy) 2021/06/01 06:19:27 INFO : one: Deleted 2021/06/01 06:19:27 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3 path dst: Waiting for transfers to finish 2021/06/01 06:19:28 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/06/01 06:19:28 INFO : one: Copied (new) 2021/06/01 06:19:28 DEBUG : Waiting for deletions to finish 2021/06/01 06:19:29 DEBUG : Couldn't decode error response: EOF 2021/06/01 06:19:29 DEBUG : three.txt: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/06/01 06:19:29 INFO : three.txt: Copied (server-side copy) 2021/06/01 06:19:29 INFO : three.txt: Deleted 2021/06/01 06:19:29 INFO : three.txt: Moved into backup dir 2021/06/01 06:19:30 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/06/01 06:19:30 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/06/01 06:19:30 DEBUG : two: Unchanged skipping 2021/06/01 06:19:30 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3 path dst: Waiting for checks to finish 2021/06/01 06:19:31 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/06/01 06:19:31 INFO : one: Copied (server-side copy) 2021/06/01 06:19:31 INFO : one: Deleted 2021/06/01 06:19:31 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3 path dst: Waiting for transfers to finish 2021/06/01 06:19:32 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2021/06/01 06:19:32 INFO : one: Copied (new) 2021/06/01 06:19:32 DEBUG : Waiting for deletions to finish 2021/06/01 06:19:32 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2021/06/01 06:19:32 INFO : three.txt: Copied (server-side copy) 2021/06/01 06:19:33 INFO : three.txt: Deleted 2021/06/01 06:19:33 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (16.05s) === RUN TestSyncBackupDirWithSuffix run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" 2021/06/01 06:19:37 DEBUG : Creating backend with remote "TestB2:rclone-test-xedunix8facujup4nasavof3/dst" 2021/06/01 06:19:38 DEBUG : Couldn't decode error response: EOF 2021/06/01 06:19:38 DEBUG : Creating backend with remote "TestB2:rclone-test-xedunix8facujup4nasavof3/backup" 2021/06/01 06:19:39 DEBUG : Couldn't decode error response: EOF 2021/06/01 06:19:39 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/06/01 06:19:39 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3 path dst: Waiting for checks to finish 2021/06/01 06:19:39 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/06/01 06:19:39 DEBUG : two: Unchanged skipping 2021/06/01 06:19:39 DEBUG : Couldn't decode error response: EOF 2021/06/01 06:19:40 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/06/01 06:19:40 INFO : one: Copied (server-side copy) to: one.bak 2021/06/01 06:19:41 INFO : one: Deleted 2021/06/01 06:19:41 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3 path dst: Waiting for transfers to finish 2021/06/01 06:19:41 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/06/01 06:19:41 INFO : one: Copied (new) 2021/06/01 06:19:41 DEBUG : Waiting for deletions to finish 2021/06/01 06:19:42 DEBUG : Couldn't decode error response: EOF 2021/06/01 06:19:43 DEBUG : three.txt: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/06/01 06:19:43 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2021/06/01 06:19:44 INFO : three.txt: Deleted 2021/06/01 06:19:44 INFO : three.txt: Moved into backup dir 2021/06/01 06:19:45 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/06/01 06:19:45 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/06/01 06:19:45 DEBUG : two: Unchanged skipping 2021/06/01 06:19:45 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3 path dst: Waiting for checks to finish 2021/06/01 06:19:45 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/06/01 06:19:45 INFO : one: Copied (server-side copy) to: one.bak 2021/06/01 06:19:47 INFO : one: Deleted 2021/06/01 06:19:47 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3 path dst: Waiting for transfers to finish 2021/06/01 06:19:48 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2021/06/01 06:19:48 INFO : one: Copied (new) 2021/06/01 06:19:48 DEBUG : Waiting for deletions to finish 2021/06/01 06:19:49 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2021/06/01 06:19:49 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2021/06/01 06:19:49 INFO : three.txt: Deleted 2021/06/01 06:19:49 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (15.99s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" 2021/06/01 06:19:53 DEBUG : Creating backend with remote "TestB2:rclone-test-xedunix8facujup4nasavof3/dst" 2021/06/01 06:19:55 DEBUG : Couldn't decode error response: EOF 2021/06/01 06:19:55 DEBUG : Creating backend with remote "TestB2:rclone-test-xedunix8facujup4nasavof3/backup" 2021/06/01 06:19:55 DEBUG : Couldn't decode error response: EOF 2021/06/01 06:19:55 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/06/01 06:19:55 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/06/01 06:19:55 DEBUG : two: Unchanged skipping 2021/06/01 06:19:55 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3 path dst: Waiting for checks to finish 2021/06/01 06:19:55 DEBUG : Couldn't decode error response: EOF 2021/06/01 06:19:57 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/06/01 06:19:57 INFO : one: Copied (server-side copy) to: one-2019-01-01 2021/06/01 06:19:58 INFO : one: Deleted 2021/06/01 06:19:58 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3 path dst: Waiting for transfers to finish 2021/06/01 06:19:58 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/06/01 06:19:58 INFO : one: Copied (new) 2021/06/01 06:19:58 DEBUG : Waiting for deletions to finish 2021/06/01 06:19:59 DEBUG : Couldn't decode error response: EOF 2021/06/01 06:19:59 DEBUG : three.txt: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/06/01 06:19:59 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2021/06/01 06:20:00 INFO : three.txt: Deleted 2021/06/01 06:20:00 INFO : three.txt: Moved into backup dir 2021/06/01 06:20:01 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/06/01 06:20:01 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3 path dst: Waiting for checks to finish 2021/06/01 06:20:01 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/06/01 06:20:01 DEBUG : two: Unchanged skipping 2021/06/01 06:20:02 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/06/01 06:20:02 INFO : one: Copied (server-side copy) to: one-2019-01-01 2021/06/01 06:20:04 INFO : one: Deleted 2021/06/01 06:20:04 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3 path dst: Waiting for transfers to finish 2021/06/01 06:20:05 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2021/06/01 06:20:05 INFO : one: Copied (new) 2021/06/01 06:20:05 DEBUG : Waiting for deletions to finish 2021/06/01 06:20:05 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2021/06/01 06:20:05 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2021/06/01 06:20:05 INFO : three.txt: Deleted 2021/06/01 06:20:05 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (17.58s) === RUN TestSyncBackupDirSuffixOnly run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" 2021/06/01 06:20:11 DEBUG : Creating backend with remote "TestB2:rclone-test-xedunix8facujup4nasavof3/dst" 2021/06/01 06:20:12 DEBUG : Couldn't decode error response: EOF 2021/06/01 06:20:12 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/06/01 06:20:12 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/06/01 06:20:12 DEBUG : two: Unchanged skipping 2021/06/01 06:20:12 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3 path dst: Waiting for checks to finish 2021/06/01 06:20:12 DEBUG : Couldn't decode error response: EOF 2021/06/01 06:20:15 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/06/01 06:20:15 INFO : one: Copied (server-side copy) to: one.bak 2021/06/01 06:20:15 INFO : one: Deleted 2021/06/01 06:20:15 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3 path dst: Waiting for transfers to finish 2021/06/01 06:20:16 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/06/01 06:20:16 INFO : one: Copied (new) 2021/06/01 06:20:16 DEBUG : Waiting for deletions to finish 2021/06/01 06:20:16 DEBUG : Couldn't decode error response: EOF 2021/06/01 06:20:17 DEBUG : three.txt: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/06/01 06:20:17 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2021/06/01 06:20:18 INFO : three.txt: Deleted 2021/06/01 06:20:18 INFO : three.txt: Moved into backup dir 2021/06/01 06:20:20 DEBUG : one.bak: Excluded from sync (and deletion) 2021/06/01 06:20:20 DEBUG : three.txt.bak: Excluded from sync (and deletion) 2021/06/01 06:20:20 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/06/01 06:20:20 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/06/01 06:20:20 DEBUG : two: Unchanged skipping 2021/06/01 06:20:20 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3 path dst: Waiting for checks to finish 2021/06/01 06:20:20 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/06/01 06:20:20 INFO : one: Copied (server-side copy) to: one.bak 2021/06/01 06:20:21 INFO : one: Deleted 2021/06/01 06:20:21 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3 path dst: Waiting for transfers to finish 2021/06/01 06:20:21 DEBUG : one: Clearing upload URL because of error: Post "https://pod-000-1157-10.backblaze.com/b2api/v1/b2_upload_file/7fe24d53306dcb6b7f980e1b/c001_v0001157_t0012": EOF 2021/06/01 06:20:21 DEBUG : pacer: low level retry 1/1 (error Post "https://pod-000-1157-10.backblaze.com/b2api/v1/b2_upload_file/7fe24d53306dcb6b7f980e1b/c001_v0001157_t0012": EOF) 2021/06/01 06:20:21 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2021/06/01 06:20:21 DEBUG : one: Received error: Post "https://pod-000-1157-10.backblaze.com/b2api/v1/b2_upload_file/7fe24d53306dcb6b7f980e1b/c001_v0001157_t0012": EOF - low level retry 1/10 2021/06/01 06:20:21 DEBUG : pacer: Reducing sleep to 10ms 2021/06/01 06:20:22 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2021/06/01 06:20:22 INFO : one: Copied (new) 2021/06/01 06:20:22 DEBUG : Waiting for deletions to finish 2021/06/01 06:20:23 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2021/06/01 06:20:23 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2021/06/01 06:20:26 INFO : three.txt: Deleted 2021/06/01 06:20:26 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirSuffixOnly (20.32s) === RUN TestSyncSuffix run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" 2021/06/01 06:20:31 DEBUG : Creating backend with remote "TestB2:rclone-test-xedunix8facujup4nasavof3/dst" 2021/06/01 06:20:33 DEBUG : Couldn't decode error response: EOF 2021/06/01 06:20:33 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/06/01 06:20:33 DEBUG : Couldn't decode error response: EOF 2021/06/01 06:20:34 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/06/01 06:20:34 INFO : one: Copied (server-side copy) to: one.bak 2021/06/01 06:20:34 INFO : one: Deleted 2021/06/01 06:20:35 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/06/01 06:20:35 INFO : one: Copied (new) 2021/06/01 06:20:35 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/06/01 06:20:35 DEBUG : two: Unchanged skipping 2021/06/01 06:20:35 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2021/06/01 06:20:36 DEBUG : Couldn't decode error response: EOF 2021/06/01 06:20:36 DEBUG : three.txt: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/06/01 06:20:36 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2021/06/01 06:20:37 INFO : three.txt: Deleted 2021/06/01 06:20:37 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2021/06/01 06:20:37 INFO : three.txt: Copied (new) 2021/06/01 06:20:38 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/06/01 06:20:38 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/06/01 06:20:38 INFO : one: Copied (server-side copy) to: one.bak 2021/06/01 06:20:39 INFO : one: Deleted 2021/06/01 06:20:39 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2021/06/01 06:20:39 INFO : one: Copied (new) 2021/06/01 06:20:39 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/06/01 06:20:39 DEBUG : two: Unchanged skipping 2021/06/01 06:20:39 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2021/06/01 06:20:40 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2021/06/01 06:20:40 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2021/06/01 06:20:40 INFO : three.txt: Deleted 2021/06/01 06:20:40 DEBUG : three.txt: sha1 = 476e1707cbd7f0497a7dc4d44f0b07b8566ffc30 OK 2021/06/01 06:20:40 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (14.33s) === RUN TestSyncSuffixKeepExtension run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" 2021/06/01 06:20:45 DEBUG : Creating backend with remote "TestB2:rclone-test-xedunix8facujup4nasavof3/dst" 2021/06/01 06:20:47 DEBUG : Couldn't decode error response: EOF 2021/06/01 06:20:47 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/06/01 06:20:47 DEBUG : Couldn't decode error response: EOF 2021/06/01 06:20:48 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/06/01 06:20:48 INFO : one: Copied (server-side copy) to: one-2019-01-01 2021/06/01 06:20:48 INFO : one: Deleted 2021/06/01 06:20:49 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/06/01 06:20:49 INFO : one: Copied (new) 2021/06/01 06:20:49 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/06/01 06:20:49 DEBUG : two: Unchanged skipping 2021/06/01 06:20:49 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2021/06/01 06:20:50 DEBUG : Couldn't decode error response: EOF 2021/06/01 06:20:50 DEBUG : three.txt: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/06/01 06:20:50 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2021/06/01 06:20:52 INFO : three.txt: Deleted 2021/06/01 06:20:53 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2021/06/01 06:20:53 INFO : three.txt: Copied (new) 2021/06/01 06:20:53 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/06/01 06:20:54 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/06/01 06:20:54 INFO : one: Copied (server-side copy) to: one-2019-01-01 2021/06/01 06:20:55 INFO : one: Deleted 2021/06/01 06:20:55 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2021/06/01 06:20:55 INFO : one: Copied (new) 2021/06/01 06:20:55 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/06/01 06:20:55 DEBUG : two: Unchanged skipping 2021/06/01 06:20:55 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2021/06/01 06:21:03 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2021/06/01 06:21:03 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2021/06/01 06:21:03 INFO : three.txt: Deleted 2021/06/01 06:21:04 DEBUG : three.txt: sha1 = 476e1707cbd7f0497a7dc4d44f0b07b8566ffc30 OK 2021/06/01 06:21:04 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (26.62s) === RUN TestSyncUTFNorm run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" 2021/06/01 06:21:11 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2021/06/01 06:21:11 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for checks to finish 2021/06/01 06:21:11 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for transfers to finish 2021/06/01 06:21:12 DEBUG : Testêé: sha1 = a54d88e06612d820bc3be72877c74f257b561b19 OK 2021/06/01 06:21:12 INFO : Testêé: Copied (replaced existing) to: Testêé 2021/06/01 06:21:12 DEBUG : Waiting for deletions to finish --- PASS: TestSyncUTFNorm (3.80s) === RUN TestSyncImmutable run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" 2021/06/01 06:21:14 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for checks to finish 2021/06/01 06:21:14 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for transfers to finish 2021/06/01 06:21:14 DEBUG : existing: sha1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2021/06/01 06:21:14 INFO : existing: Copied (new) 2021/06/01 06:21:14 DEBUG : Waiting for deletions to finish 2021/06/01 06:21:15 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for checks to finish 2021/06/01 06:21:15 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2021/06/01 06:21:15 ERROR : existing: Source and destination exist but do not match: immutable file modified 2021/06/01 06:21:15 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for transfers to finish 2021/06/01 06:21:15 ERROR : B2 bucket rclone-test-xedunix8facujup4nasavof3: not deleting files as there were IO errors 2021/06/01 06:21:15 ERROR : B2 bucket rclone-test-xedunix8facujup4nasavof3: not deleting directories as there were IO errors --- PASS: TestSyncImmutable (2.37s) === RUN TestSyncIgnoreCase run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" 2021/06/01 06:21:16 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for checks to finish 2021/06/01 06:21:16 DEBUG : existing: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/06/01 06:21:16 DEBUG : existing: Unchanged skipping 2021/06/01 06:21:16 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Waiting for transfers to finish 2021/06/01 06:21:16 DEBUG : Waiting for deletions to finish 2021/06/01 06:21:16 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreCase (1.97s) === RUN TestMaxTransfer === RUN TestMaxTransfer/Hard run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" sync_test.go:1941: This test only runs on local === RUN TestMaxTransfer/Soft run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" sync_test.go:1941: This test only runs on local === RUN TestMaxTransfer/Cautious run.go:176: Remote "B2 bucket rclone-test-xedunix8facujup4nasavof3", Local "Local file system at /tmp/rclone500016083", Modify Window "1ms" sync_test.go:1941: This test only runs on local --- PASS: TestMaxTransfer (0.88s) --- SKIP: TestMaxTransfer/Hard (0.30s) --- SKIP: TestMaxTransfer/Soft (0.29s) --- SKIP: TestMaxTransfer/Cautious (0.29s) PASS 2021/06/01 06:21:18 DEBUG : B2 bucket rclone-test-xedunix8facujup4nasavof3: Purge remote 2021/06/01 06:21:19 DEBUG : BackupDir/one: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1159430eb51c6cda_d20210601_m061905_c001_v0001150_t0057") 2021/06/01 06:21:19 DEBUG : BackupDir/one: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f103d7b80cf7165da_d20210601_m061857_c001_v0001113_t0008") 2021/06/01 06:21:19 DEBUG : CompareDest/one: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117aa85975358e58_d20210601_m061841_c001_v0001120_t0009") 2021/06/01 06:21:19 DEBUG : CompareDest/one: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f11189818a9d25974_d20210601_m061835_c001_v0001044_t0009") 2021/06/01 06:21:19 DEBUG : CompareDest/two: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f104b3678dcbf2dbb_d20210601_m061842_c001_v0001044_t0048") 2021/06/01 06:21:19 DEBUG : CompareDest/two: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f114da636bd83e380_d20210601_m061838_c001_v0001092_t0049") 2021/06/01 06:21:19 DEBUG : CopyDest/one: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f109c6bfd15fc0881_d20210601_m061906_c001_v0001145_t0000") 2021/06/01 06:21:19 DEBUG : CopyDest/one: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db5577db_d20210601_m061855_c001_v0001128_t0021") 2021/06/01 06:21:19 DEBUG : CopyDest/three: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f110abbedd21b7e7d_d20210601_m061907_c001_v0001015_t0029") 2021/06/01 06:21:19 DEBUG : CopyDest/three: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f114da636bd83e3a6_d20210601_m061904_c001_v0001092_t0049") 2021/06/01 06:21:19 DEBUG : CopyDest/two: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f11683bf4dfbc9f00_d20210601_m061908_c001_v0001041_t0055") 2021/06/01 06:21:19 DEBUG : CopyDest/two: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f11189818a9d25988_d20210601_m061859_c001_v0001044_t0009") 2021/06/01 06:21:19 DEBUG : EXISTING: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f100b87cd2c2d904f_d20210601_m062117_c001_v0001157_t0017") 2021/06/01 06:21:20 DEBUG : EXISTING: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f11189818a9d25a13_d20210601_m062116_c001_v0001044_t0009") 2021/06/01 06:21:20 DEBUG : Testêé: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1153aab8e9fd251a_d20210601_m062113_c001_v0001153_t0020") 2021/06/01 06:21:20 DEBUG : Testêé: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f114da636bd83e3f4_d20210601_m062112_c001_v0001092_t0049") 2021/06/01 06:21:20 DEBUG : Testêé: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f11189818a9d25a0c_d20210601_m062110_c001_v0001044_t0009") 2021/06/01 06:21:20 DEBUG : a/potato2: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f114e12e6c3fe2254_d20210601_m061639_c001_v0001144_t0040") 2021/06/01 06:21:20 DEBUG : a/potato2: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db5576d2_d20210601_m061638_c001_v0001128_t0021") 2021/06/01 06:21:20 DEBUG : a/potato2: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f116484c26e991899_d20210601_m061631_c001_v0001145_t0030") 2021/06/01 06:21:20 DEBUG : a/potato2: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db5576c6_d20210601_m061630_c001_v0001128_t0021") 2021/06/01 06:21:20 DEBUG : a/potato2: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f100f4b296728b9ae_d20210601_m061604_c001_v0001145_t0003") 2021/06/01 06:21:20 DEBUG : a/potato2: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db55767c_d20210601_m061603_c001_v0001128_t0021") 2021/06/01 06:21:20 DEBUG : b/potato: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f110b86df3308ee26_d20210601_m061646_c001_v0001146_t0003") 2021/06/01 06:21:20 DEBUG : b/potato: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db5576cc_d20210601_m061636_c001_v0001128_t0021") 2021/06/01 06:21:20 DEBUG : b/potato: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f108875a8fdf59012_d20210601_m061630_c001_v0001120_t0026") 2021/06/01 06:21:20 DEBUG : b/potato: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db5576c2_d20210601_m061629_c001_v0001128_t0021") 2021/06/01 06:21:20 DEBUG : b/potato: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f102c0e53d33af6e7_d20210601_m061603_c001_v0001120_t0037") 2021/06/01 06:21:20 DEBUG : b/potato: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db557676_d20210601_m061602_c001_v0001128_t0021") 2021/06/01 06:21:20 DEBUG : backup/one: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f10493fa7d58a2496_d20210601_m061933_c001_v0001000_t0011") 2021/06/01 06:21:20 DEBUG : backup/one: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f11901497949a2c91_d20210601_m061931_c001_v0001137_t0022") 2021/06/01 06:21:20 DEBUG : backup/one: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f10756cd5c90f7eff_d20210601_m061925_c001_v0001144_t0044") 2021/06/01 06:21:20 DEBUG : backup/one-2019-01-01: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f115012e38bf98958_d20210601_m062006_c001_v0001093_t0027") 2021/06/01 06:21:20 DEBUG : backup/one-2019-01-01: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f102468d92116f85f_d20210601_m062001_c001_v0001134_t0035") 2021/06/01 06:21:20 DEBUG : backup/one-2019-01-01: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f11899f3461974e4f_d20210601_m061956_c001_v0001144_t0055") 2021/06/01 06:21:20 DEBUG : backup/one.bak: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f11285f39b724fd69_d20210601_m061949_c001_v0001128_t0040") 2021/06/01 06:21:20 DEBUG : backup/one.bak: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f10550509862092bd_d20210601_m061945_c001_v0001150_t0057") 2021/06/01 06:21:20 DEBUG : backup/one.bak: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f114c84943d959779_d20210601_m061940_c001_v0001153_t0000") 2021/06/01 06:21:21 DEBUG : backup/three-2019-01-01.txt: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f10484a1ddf6b291a_d20210601_m062006_c001_v0001154_t0008") 2021/06/01 06:21:21 DEBUG : backup/three-2019-01-01.txt: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1026e8fab11c3923_d20210601_m062005_c001_v0001136_t0009") 2021/06/01 06:21:21 DEBUG : backup/three-2019-01-01.txt: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f11172ff9073c2567_d20210601_m061959_c001_v0001145_t0040") 2021/06/01 06:21:21 DEBUG : backup/three.txt: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1177fe326814d591_d20210601_m061934_c001_v0001146_t0003") 2021/06/01 06:21:21 DEBUG : backup/three.txt: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1170fadd3c8528c8_d20210601_m061932_c001_v0001038_t0042") 2021/06/01 06:21:21 DEBUG : backup/three.txt: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1143a5ebfc6f4e42_d20210601_m061929_c001_v0001128_t0006") 2021/06/01 06:21:21 DEBUG : backup/three.txt.bak: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f11799fb3e9128e5d_d20210601_m061950_c001_v0001136_t0039") 2021/06/01 06:21:21 DEBUG : backup/three.txt.bak: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f104404110d43d3c9_d20210601_m061948_c001_v0001040_t0023") 2021/06/01 06:21:21 DEBUG : backup/three.txt.bak: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1152f71344035dae_d20210601_m061942_c001_v0001041_t0010") 2021/06/01 06:21:21 DEBUG : c/non empty space: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f109cc1a84ae402c7_d20210601_m061646_c001_v0001144_t0029") 2021/06/01 06:21:21 DEBUG : c/non empty space: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db5576ce_d20210601_m061637_c001_v0001128_t0021") 2021/06/01 06:21:21 DEBUG : c/non empty space: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1032591d5987364b_d20210601_m061633_c001_v0001038_t0023") 2021/06/01 06:21:21 DEBUG : c/non empty space: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db5576c4_d20210601_m061630_c001_v0001128_t0021") 2021/06/01 06:21:21 DEBUG : c/non empty space: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f116800e3c7648867_d20210601_m061604_c001_v0001144_t0000") 2021/06/01 06:21:21 DEBUG : c/non empty space: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db557678_d20210601_m061602_c001_v0001128_t0021") 2021/06/01 06:21:21 DEBUG : check sum: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1187752e4d8f45ed_d20210601_m061550_c001_v0001040_t0009") 2021/06/01 06:21:21 DEBUG : check sum: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db55764e_d20210601_m061549_c001_v0001128_t0021") 2021/06/01 06:21:21 DEBUG : dest/3: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f106939e807b44733_d20210601_m061850_c001_v0001120_t0045") 2021/06/01 06:21:21 DEBUG : dest/3: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f114acf1d5da49f35_d20210601_m061849_c001_v0001038_t0002") 2021/06/01 06:21:21 DEBUG : dst/one: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117ec24f1dbee7a3_d20210601_m062105_c001_v0001009_t0006") 2021/06/01 06:21:21 DEBUG : dst/one: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f107228ffbfe7a03a_d20210601_m062055_c001_v0001030_t0031") 2021/06/01 06:21:21 DEBUG : dst/one: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f111943b30ef89389_d20210601_m062054_c001_v0001157_t0044") 2021/06/01 06:21:22 DEBUG : dst/one: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f107228ffbfe7a034_d20210601_m062049_c001_v0001030_t0031") 2021/06/01 06:21:22 DEBUG : dst/one: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1177068fb5e89314_d20210601_m062048_c001_v0001153_t0010") 2021/06/01 06:21:22 DEBUG : dst/one: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f11189818a9d259f1_d20210601_m062043_c001_v0001044_t0009") 2021/06/01 06:21:22 DEBUG : dst/one: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f114c84943d959991_d20210601_m062041_c001_v0001153_t0025") 2021/06/01 06:21:22 DEBUG : dst/one: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f10301e9ca1345bd1_d20210601_m062039_c001_v0001036_t0005") 2021/06/01 06:21:22 DEBUG : dst/one: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1008db63cd7195e5_d20210601_m062038_c001_v0001146_t0012") 2021/06/01 06:21:22 DEBUG : dst/one: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f10301e9ca1345bcd_d20210601_m062035_c001_v0001036_t0005") 2021/06/01 06:21:22 DEBUG : dst/one: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f100b87cd2c2d8edd_d20210601_m062034_c001_v0001157_t0035") 2021/06/01 06:21:22 DEBUG : dst/one: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f11189818a9d259e8_d20210601_m062029_c001_v0001044_t0009") 2021/06/01 06:21:22 DEBUG : dst/one: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117dd21aad264a0e_d20210601_m062026_c001_v0001154_t0006") 2021/06/01 06:21:22 DEBUG : dst/one: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1125776d94af65f6_d20210601_m062021_c001_v0001120_t0048") 2021/06/01 06:21:22 DEBUG : dst/one: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f10497d76ab217164_d20210601_m062020_c001_v0001120_t0014") 2021/06/01 06:21:22 DEBUG : dst/one: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1107b89dec18a668_d20210601_m062016_c001_v0001157_t0012") 2021/06/01 06:21:22 DEBUG : dst/one: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1101273eb18408d9_d20210601_m062015_c001_v0001001_t0006") 2021/06/01 06:21:22 DEBUG : dst/one: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db557867_d20210601_m062009_c001_v0001128_t0021") 2021/06/01 06:21:22 DEBUG : dst/one: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f101f82cc97f2565a_d20210601_m062007_c001_v0001153_t0003") 2021/06/01 06:21:22 DEBUG : dst/one: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f109a0458b50ee4d1_d20210601_m062004_c001_v0001134_t0036") 2021/06/01 06:21:22 DEBUG : dst/one: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f106c1330ee0fbe86_d20210601_m062002_c001_v0001032_t0059") 2021/06/01 06:21:22 DEBUG : dst/one: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f109a0458b50ee4c2_d20210601_m061958_c001_v0001134_t0036") 2021/06/01 06:21:22 DEBUG : dst/one: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f10409d54c18e7d19_d20210601_m061957_c001_v0001157_t0045") 2021/06/01 06:21:23 DEBUG : dst/one: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f114da636bd83e3c9_d20210601_m061951_c001_v0001092_t0049") 2021/06/01 06:21:23 DEBUG : dst/one: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f10594c7ee60e4453_d20210601_m061950_c001_v0001146_t0016") 2021/06/01 06:21:23 DEBUG : dst/one: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f108a555fc5cc4194_d20210601_m061948_c001_v0001150_t0035") 2021/06/01 06:21:23 DEBUG : dst/one: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f112f255fb0802295_d20210601_m061946_c001_v0001039_t0046") 2021/06/01 06:21:23 DEBUG : dst/one: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f108a555fc5cc417d_d20210601_m061941_c001_v0001150_t0035") 2021/06/01 06:21:23 DEBUG : dst/one: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1138f02db54524bb_d20210601_m061940_c001_v0001029_t0004") 2021/06/01 06:21:23 DEBUG : dst/one: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f11189818a9d259b6_d20210601_m061935_c001_v0001044_t0009") 2021/06/01 06:21:23 DEBUG : dst/one: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f118fd4e57f26a7bc_d20210601_m061934_c001_v0001157_t0008") 2021/06/01 06:21:23 DEBUG : dst/one: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f11301995c0859df6_d20210601_m061932_c001_v0001033_t0001") 2021/06/01 06:21:23 DEBUG : dst/one: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1041cdc21c0751fa_d20210601_m061931_c001_v0001137_t0015") 2021/06/01 06:21:23 DEBUG : dst/one: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f11301995c0859df4_d20210601_m061928_c001_v0001033_t0001") 2021/06/01 06:21:23 DEBUG : dst/one: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f10304f472d000a40_d20210601_m061927_c001_v0001026_t0015") 2021/06/01 06:21:23 DEBUG : dst/one: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db557804_d20210601_m061920_c001_v0001128_t0021") 2021/06/01 06:21:23 DEBUG : dst/one: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1030fecc592bfd23_d20210601_m061909_c001_v0001146_t0047") 2021/06/01 06:21:23 DEBUG : dst/one: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f112fc8c483a8c335_d20210601_m061859_c001_v0001023_t0007") 2021/06/01 06:21:23 DEBUG : dst/one: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f10809ee56e9a269d_d20210601_m061858_c001_v0001130_t0016") 2021/06/01 06:21:23 DEBUG : dst/one: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f114da636bd83e395_d20210601_m061855_c001_v0001092_t0049") 2021/06/01 06:21:23 DEBUG : dst/one: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1148168728f47362_d20210601_m061854_c001_v0001150_t0059") 2021/06/01 06:21:23 DEBUG : dst/one: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1148168728f4735a_d20210601_m061853_c001_v0001150_t0059") 2021/06/01 06:21:23 DEBUG : dst/one: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f10004d1b98ef0d7a_d20210601_m061842_c001_v0001136_t0020") 2021/06/01 06:21:23 DEBUG : dst/one: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db5577ba_d20210601_m061834_c001_v0001128_t0021") 2021/06/01 06:21:23 DEBUG : dst/one: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1084b69cba39afc6_d20210601_m061833_c001_v0001033_t0030") 2021/06/01 06:21:23 DEBUG : dst/one: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1084b69cba39afc2_d20210601_m061832_c001_v0001033_t0030") 2021/06/01 06:21:23 DEBUG : dst/one-2019-01-01: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f109a31223b5f64c9_d20210601_m062106_c001_v0001131_t0019") 2021/06/01 06:21:24 DEBUG : dst/one-2019-01-01: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f116d1b50ec918b3e_d20210601_m062054_c001_v0001044_t0045") 2021/06/01 06:21:24 DEBUG : dst/one-2019-01-01: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1059a6438a20a9d3_d20210601_m062048_c001_v0001092_t0058") 2021/06/01 06:21:24 DEBUG : dst/one.bak: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f118fd4e57f26ab73_d20210601_m062041_c001_v0001157_t0040") 2021/06/01 06:21:24 DEBUG : dst/one.bak: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f107d0537e238b32e_d20210601_m062038_c001_v0001150_t0017") 2021/06/01 06:21:24 DEBUG : dst/one.bak: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f103859025e0d28f2_d20210601_m062034_c001_v0001009_t0008") 2021/06/01 06:21:24 DEBUG : dst/one.bak: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f100f4b296728bf1a_d20210601_m062026_c001_v0001145_t0012") 2021/06/01 06:21:24 DEBUG : dst/one.bak: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1144723a69f305c4_d20210601_m062020_c001_v0001145_t0045") 2021/06/01 06:21:24 DEBUG : dst/one.bak: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f106bb377c5dc617c_d20210601_m062013_c001_v0001153_t0024") 2021/06/01 06:21:24 DEBUG : dst/three: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f103674da1fc8261d_d20210601_m061909_c001_v0001144_t0047") 2021/06/01 06:21:24 DEBUG : dst/three: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1148168728f47386_d20210601_m061905_c001_v0001150_t0059") 2021/06/01 06:21:24 DEBUG : dst/three-2019-01-01.txt: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f113eb42cfae42801_d20210601_m062106_c001_v0001150_t0019") 2021/06/01 06:21:24 DEBUG : dst/three-2019-01-01.txt: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f103674da1fc82896_d20210601_m062057_c001_v0001144_t0050") 2021/06/01 06:21:24 DEBUG : dst/three-2019-01-01.txt: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f11760d7266feb812_d20210601_m062050_c001_v0001157_t0020") 2021/06/01 06:21:24 DEBUG : dst/three.txt: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f116800e3c764901a_d20210601_m062106_c001_v0001144_t0049") 2021/06/01 06:21:24 DEBUG : dst/three.txt: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f107228ffbfe7a03e_d20210601_m062104_c001_v0001030_t0031") 2021/06/01 06:21:24 DEBUG : dst/three.txt: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f118ef1ec475f6310_d20210601_m062103_c001_v0001136_t0056") 2021/06/01 06:21:24 DEBUG : dst/three.txt: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f107228ffbfe7a038_d20210601_m062053_c001_v0001030_t0031") 2021/06/01 06:21:24 DEBUG : dst/three.txt: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f102cae7a944165e8_d20210601_m062050_c001_v0001144_t0046") 2021/06/01 06:21:24 DEBUG : dst/three.txt: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db5578a4_d20210601_m062045_c001_v0001128_t0021") 2021/06/01 06:21:24 DEBUG : dst/three.txt: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f103faa6b1af9c1a1_d20210601_m062042_c001_v0001154_t0015") 2021/06/01 06:21:24 DEBUG : dst/three.txt: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f10301e9ca1345bd5_d20210601_m062041_c001_v0001036_t0005") 2021/06/01 06:21:24 DEBUG : dst/three.txt: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1148168728f474d9_d20210601_m062040_c001_v0001150_t0015") 2021/06/01 06:21:24 DEBUG : dst/three.txt: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f10301e9ca1345bcf_d20210601_m062037_c001_v0001036_t0005") 2021/06/01 06:21:24 DEBUG : dst/three.txt: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f10538e50ee5d476b_d20210601_m062036_c001_v0001036_t0038") 2021/06/01 06:21:25 DEBUG : dst/three.txt: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db557890_d20210601_m062031_c001_v0001128_t0021") 2021/06/01 06:21:25 DEBUG : dst/three.txt: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1198a204cacafe6b_d20210601_m062023_c001_v0001144_t0010") 2021/06/01 06:21:25 DEBUG : dst/three.txt: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db557881_d20210601_m062019_c001_v0001128_t0021") 2021/06/01 06:21:25 DEBUG : dst/three.txt: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f116691e24201d824_d20210601_m062017_c001_v0001093_t0026") 2021/06/01 06:21:25 DEBUG : dst/three.txt: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f114da636bd83e3da_d20210601_m062010_c001_v0001092_t0049") 2021/06/01 06:21:25 DEBUG : dst/three.txt: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1170b2d0b3df4cc0_d20210601_m062005_c001_v0001150_t0043") 2021/06/01 06:21:25 DEBUG : dst/three.txt: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f114da636bd83e3d1_d20210601_m062000_c001_v0001092_t0049") 2021/06/01 06:21:25 DEBUG : dst/three.txt: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1041c78260180fef_d20210601_m061959_c001_v0001031_t0038") 2021/06/01 06:21:25 DEBUG : dst/three.txt: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f11189818a9d259ca_d20210601_m061953_c001_v0001044_t0009") 2021/06/01 06:21:25 DEBUG : dst/three.txt: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f111f83ce3a14e618_d20210601_m061949_c001_v0001136_t0045") 2021/06/01 06:21:25 DEBUG : dst/three.txt: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f11189818a9d259c0_d20210601_m061944_c001_v0001044_t0009") 2021/06/01 06:21:25 DEBUG : dst/three.txt: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f113d95c174a82631_d20210601_m061943_c001_v0001157_t0048") 2021/06/01 06:21:25 DEBUG : dst/three.txt: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db55781e_d20210601_m061937_c001_v0001128_t0021") 2021/06/01 06:21:25 DEBUG : dst/three.txt: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1185384f9ff3b97e_d20210601_m061932_c001_v0001032_t0009") 2021/06/01 06:21:25 DEBUG : dst/three.txt: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db557817_d20210601_m061930_c001_v0001128_t0021") 2021/06/01 06:21:25 DEBUG : dst/three.txt: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f102749a29f233667_d20210601_m061929_c001_v0001130_t0046") 2021/06/01 06:21:25 DEBUG : dst/three.txt: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f114da636bd83e3af_d20210601_m061922_c001_v0001092_t0049") 2021/06/01 06:21:25 DEBUG : dst/three.txt.bak: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f11165eff4ea695a6_d20210601_m062042_c001_v0001113_t0047") 2021/06/01 06:21:25 DEBUG : dst/three.txt.bak: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f11172ff9073c2651_d20210601_m062039_c001_v0001145_t0014") 2021/06/01 06:21:25 DEBUG : dst/three.txt.bak: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f11860de5354d22d2_d20210601_m062036_c001_v0001093_t0022") 2021/06/01 06:21:25 DEBUG : dst/three.txt.bak: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1020519aa33f31f6_d20210601_m062027_c001_v0001003_t0000") 2021/06/01 06:21:25 DEBUG : dst/three.txt.bak: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1076226c5f706401_d20210601_m062022_c001_v0001120_t0009") 2021/06/01 06:21:25 DEBUG : dst/three.txt.bak: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f103d7b80cf71668b_d20210601_m062016_c001_v0001113_t0015") 2021/06/01 06:21:26 DEBUG : dst/two: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f11865d1e884c0a90_d20210601_m062109_c001_v0001012_t0026") 2021/06/01 06:21:26 DEBUG : dst/two: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f114da636bd83e3e6_d20210601_m062044_c001_v0001092_t0049") 2021/06/01 06:21:26 DEBUG : dst/two: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f104c922478609e27_d20210601_m062042_c001_v0001041_t0049") 2021/06/01 06:21:26 DEBUG : dst/two: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f114da636bd83e3df_d20210601_m062030_c001_v0001092_t0049") 2021/06/01 06:21:26 DEBUG : dst/two: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1119ee9798fa3350_d20210601_m062028_c001_v0001130_t0040") 2021/06/01 06:21:26 DEBUG : dst/two: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f11189818a9d259db_d20210601_m062010_c001_v0001044_t0009") 2021/06/01 06:21:26 DEBUG : dst/two: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f119d1a8ae1346c35_d20210601_m062008_c001_v0001044_t0050") 2021/06/01 06:21:26 DEBUG : dst/two: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db557851_d20210601_m061952_c001_v0001128_t0021") 2021/06/01 06:21:26 DEBUG : dst/two: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f113f36eac7495d5b_d20210601_m061950_c001_v0001146_t0020") 2021/06/01 06:21:26 DEBUG : dst/two: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f114da636bd83e3bd_d20210601_m061936_c001_v0001092_t0049") 2021/06/01 06:21:26 DEBUG : dst/two: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1072a5ee6c048a4d_d20210601_m061934_c001_v0001009_t0006") 2021/06/01 06:21:26 DEBUG : dst/two: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f11189818a9d259a7_d20210601_m061921_c001_v0001044_t0009") 2021/06/01 06:21:26 DEBUG : dst/two: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f112cec7b2249b5bf_d20210601_m061918_c001_v0001157_t0013") 2021/06/01 06:21:26 DEBUG : dst/two: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f112826b73d291663_d20210601_m061900_c001_v0001146_t0014") 2021/06/01 06:21:26 DEBUG : dst/two: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f10671c0a9551e07d_d20210601_m061843_c001_v0001092_t0047") 2021/06/01 06:21:26 DEBUG : dst/two: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1084b69cba39afd8_d20210601_m061841_c001_v0001033_t0030") 2021/06/01 06:21:26 DEBUG : empty space: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f110fc55f82e50393_d20210601_m061817_c001_v0001144_t0002") 2021/06/01 06:21:26 DEBUG : empty space: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db55779b_d20210601_m061814_c001_v0001128_t0021") 2021/06/01 06:21:26 DEBUG : empty space: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1016be9ad3d83106_d20210601_m061811_c001_v0001013_t0041") 2021/06/01 06:21:26 DEBUG : empty space: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db557777_d20210601_m061748_c001_v0001128_t0021") 2021/06/01 06:21:26 DEBUG : empty space: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f100723022400c845_d20210601_m061737_c001_v0001091_t0010") 2021/06/01 06:21:26 DEBUG : empty space: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db557764_d20210601_m061734_c001_v0001128_t0021") 2021/06/01 06:21:26 DEBUG : empty space: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f10505c87621d8495_d20210601_m061703_c001_v0001157_t0024") 2021/06/01 06:21:26 DEBUG : empty space: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db557705_d20210601_m061700_c001_v0001128_t0021") 2021/06/01 06:21:27 DEBUG : empty space: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1076226c5f706295_d20210601_m061658_c001_v0001120_t0031") 2021/06/01 06:21:27 DEBUG : empty space: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db5576ff_d20210601_m061657_c001_v0001128_t0021") 2021/06/01 06:21:27 DEBUG : empty space: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1154cc56e149b352_d20210601_m061654_c001_v0001146_t0020") 2021/06/01 06:21:27 DEBUG : empty space: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db5576f3_d20210601_m061652_c001_v0001128_t0021") 2021/06/01 06:21:27 DEBUG : empty space: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1107b89dec189768_d20210601_m061651_c001_v0001157_t0018") 2021/06/01 06:21:27 DEBUG : empty space: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db5576e6_d20210601_m061650_c001_v0001128_t0021") 2021/06/01 06:21:27 DEBUG : empty space: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f118ef1ec475f6174_d20210601_m061627_c001_v0001136_t0026") 2021/06/01 06:21:27 DEBUG : empty space: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db5576b5_d20210601_m061626_c001_v0001128_t0021") 2021/06/01 06:21:27 DEBUG : empty space: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f110118b9a8e296a1_d20210601_m061625_c001_v0001128_t0032") 2021/06/01 06:21:27 DEBUG : empty space: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db5576ae_d20210601_m061624_c001_v0001128_t0021") 2021/06/01 06:21:27 DEBUG : empty space: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f110e13f2c6afcf17_d20210601_m061616_c001_v0001154_t0009") 2021/06/01 06:21:27 DEBUG : empty space: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db557698_d20210601_m061615_c001_v0001128_t0021") 2021/06/01 06:21:27 DEBUG : empty space: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f115cf87a54aec714_d20210601_m061612_c001_v0001029_t0043") 2021/06/01 06:21:27 DEBUG : empty space: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db55768e_d20210601_m061611_c001_v0001128_t0021") 2021/06/01 06:21:27 DEBUG : empty space: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1154cc56e149b2b8_d20210601_m061610_c001_v0001146_t0036") 2021/06/01 06:21:27 DEBUG : empty space: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1070494f247bc435_d20210601_m061608_c001_v0001012_t0015") 2021/06/01 06:21:27 DEBUG : empty space: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db557686_d20210601_m061606_c001_v0001128_t0021") 2021/06/01 06:21:27 DEBUG : enormous: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f104b5d19f0b2a237_d20210601_m061701_c001_v0001145_t0038") 2021/06/01 06:21:27 DEBUG : enormous: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db557707_d20210601_m061700_c001_v0001128_t0021") 2021/06/01 06:21:27 DEBUG : existing: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1045512a158bda20_d20210601_m062115_c001_v0001011_t0030") 2021/06/01 06:21:27 DEBUG : existing: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db5578e2_d20210601_m062114_c001_v0001128_t0021") 2021/06/01 06:21:27 DEBUG : existing: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f116e309de668af91_d20210601_m061601_c001_v0001154_t0019") 2021/06/01 06:21:27 DEBUG : existing: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db557669_d20210601_m061559_c001_v0001128_t0021") 2021/06/01 06:21:28 DEBUG : existing: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f11865cf06e603bca_d20210601_m061557_c001_v0001131_t0025") 2021/06/01 06:21:28 DEBUG : existing: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db557664_d20210601_m061556_c001_v0001128_t0021") 2021/06/01 06:21:28 DEBUG : existing: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db55765f_d20210601_m061555_c001_v0001128_t0021") 2021/06/01 06:21:28 DEBUG : five: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1119ee9798fa3215_d20210601_m061710_c001_v0001130_t0007") 2021/06/01 06:21:28 DEBUG : five: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db557718_d20210601_m061704_c001_v0001128_t0021") 2021/06/01 06:21:28 DEBUG : foo: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1020f07fc0ac8880_d20210601_m061614_c001_v0001040_t0034") 2021/06/01 06:21:28 DEBUG : foo: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db557696_d20210601_m061613_c001_v0001128_t0021") 2021/06/01 06:21:28 DEBUG : foo: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db557690_d20210601_m061612_c001_v0001128_t0021") 2021/06/01 06:21:28 DEBUG : four: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f108875a8fdf59054_d20210601_m061711_c001_v0001120_t0001") 2021/06/01 06:21:28 DEBUG : four: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f114da636bd83e33e_d20210601_m061705_c001_v0001092_t0049") 2021/06/01 06:21:28 DEBUG : four: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db557714_d20210601_m061704_c001_v0001128_t0021") 2021/06/01 06:21:28 DEBUG : hello world2: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f10116d41b3fda084_d20210601_m061527_c001_v0001146_t0018") 2021/06/01 06:21:28 DEBUG : hello world2: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db557622_d20210601_m061526_c001_v0001128_t0021") 2021/06/01 06:21:28 DEBUG : ignore-size: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f110391b0fada634c_d20210601_m061555_c001_v0001006_t0030") 2021/06/01 06:21:28 DEBUG : ignore-size: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db557658_d20210601_m061554_c001_v0001128_t0021") 2021/06/01 06:21:28 DEBUG : nested/sub dir/file: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1059a6438a20a929_d20210601_m061732_c001_v0001092_t0013") 2021/06/01 06:21:28 DEBUG : nested/sub dir/file: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db55775e_d20210601_m061731_c001_v0001128_t0021") 2021/06/01 06:21:28 DEBUG : nested/sub dir/file: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f10004d1b98ef0cff_d20210601_m061730_c001_v0001136_t0019") 2021/06/01 06:21:28 DEBUG : nested/sub dir/file: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f114da636bd83e34d_d20210601_m061729_c001_v0001092_t0049") 2021/06/01 06:21:28 DEBUG : one: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117dd21aad26480b_d20210601_m061711_c001_v0001154_t0016") 2021/06/01 06:21:28 DEBUG : one: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db55770e_d20210601_m061703_c001_v0001128_t0021") 2021/06/01 06:21:28 DEBUG : potato: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1108880754036afa_d20210601_m061728_c001_v0001136_t0034") 2021/06/01 06:21:28 DEBUG : potato: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f11189818a9d25927_d20210601_m061725_c001_v0001044_t0009") 2021/06/01 06:21:28 DEBUG : potato: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f106c1330ee0fbe27_d20210601_m061720_c001_v0001032_t0023") 2021/06/01 06:21:29 DEBUG : potato: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db55773f_d20210601_m061717_c001_v0001128_t0021") 2021/06/01 06:21:29 DEBUG : potato: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f108a1d5bb32b2c06_d20210601_m061716_c001_v0001157_t0025") 2021/06/01 06:21:29 DEBUG : potato: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f11189818a9d25907_d20210601_m061713_c001_v0001044_t0009") 2021/06/01 06:21:29 DEBUG : potato: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f11907cd2c069797a_d20210601_m061656_c001_v0001157_t0003") 2021/06/01 06:21:29 DEBUG : potato: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db5576f7_d20210601_m061655_c001_v0001128_t0021") 2021/06/01 06:21:29 DEBUG : potato: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1078c6376de7b743_d20210601_m061653_c001_v0001146_t0011") 2021/06/01 06:21:29 DEBUG : potato: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db5576ed_d20210601_m061652_c001_v0001128_t0021") 2021/06/01 06:21:29 DEBUG : potato: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f110357506365d497_d20210601_m061650_c001_v0001150_t0035") 2021/06/01 06:21:29 DEBUG : potato: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db5576e2_d20210601_m061649_c001_v0001128_t0021") 2021/06/01 06:21:29 DEBUG : potato: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f10107d65cafc8efb_d20210601_m061630_c001_v0001039_t0055") 2021/06/01 06:21:29 DEBUG : potato: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db5576b2_d20210601_m061629_c001_v0001128_t0021") 2021/06/01 06:21:29 DEBUG : potato: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1165d8e69983b405_d20210601_m061628_c001_v0001136_t0018") 2021/06/01 06:21:29 DEBUG : potato: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db5576ac_d20210601_m061627_c001_v0001128_t0021") 2021/06/01 06:21:29 DEBUG : potato: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f107d61c738d9d1a7_d20210601_m061626_c001_v0001137_t0022") 2021/06/01 06:21:29 DEBUG : potato: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db5576aa_d20210601_m061625_c001_v0001128_t0021") 2021/06/01 06:21:29 DEBUG : potato: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db5576a6_d20210601_m061624_c001_v0001128_t0021") 2021/06/01 06:21:29 DEBUG : potato: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1065b5047221ee16_d20210601_m061623_c001_v0001039_t0033") 2021/06/01 06:21:29 DEBUG : potato: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db5576a4_d20210601_m061622_c001_v0001128_t0021") 2021/06/01 06:21:29 DEBUG : potato: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db5576a2_d20210601_m061621_c001_v0001128_t0021") 2021/06/01 06:21:29 DEBUG : potato: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f10809ee56e9a25f8_d20210601_m061620_c001_v0001130_t0052") 2021/06/01 06:21:29 DEBUG : potato: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db55769a_d20210601_m061615_c001_v0001128_t0021") 2021/06/01 06:21:29 DEBUG : potato2: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f109e0397ecce27e8_d20210601_m061830_c001_v0001157_t0048") 2021/06/01 06:21:29 DEBUG : potato2: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f114da636bd83e376_d20210601_m061829_c001_v0001092_t0049") 2021/06/01 06:21:29 DEBUG : potato2: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1076226c5f706334_d20210601_m061819_c001_v0001120_t0030") 2021/06/01 06:21:30 DEBUG : potato2: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f114da636bd83e370_d20210601_m061813_c001_v0001092_t0049") 2021/06/01 06:21:30 DEBUG : potato2: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f115e9d18e9136e4e_d20210601_m061804_c001_v0001157_t0018") 2021/06/01 06:21:30 DEBUG : potato2: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f114da636bd83e35a_d20210601_m061747_c001_v0001092_t0049") 2021/06/01 06:21:30 DEBUG : potato2: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1129c5b6b51c1ab1_d20210601_m061737_c001_v0001150_t0004") 2021/06/01 06:21:30 DEBUG : potato2: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f114da636bd83e34f_d20210601_m061734_c001_v0001092_t0049") 2021/06/01 06:21:30 DEBUG : potato2: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f11743cda8fc8070e_d20210601_m061701_c001_v0001091_t0034") 2021/06/01 06:21:30 DEBUG : potato2: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db557703_d20210601_m061700_c001_v0001128_t0021") 2021/06/01 06:21:30 DEBUG : potato2: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f119d95baa560ab7a_d20210601_m061659_c001_v0001033_t0042") 2021/06/01 06:21:30 DEBUG : potato2: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db5576fd_d20210601_m061657_c001_v0001128_t0021") 2021/06/01 06:21:30 DEBUG : potato2: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f106d03f5cb25e98d_d20210601_m061656_c001_v0001157_t0030") 2021/06/01 06:21:30 DEBUG : potato2: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db5576fb_d20210601_m061655_c001_v0001128_t0021") 2021/06/01 06:21:30 DEBUG : potato2: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1088cb8d15744828_d20210601_m061654_c001_v0001137_t0002") 2021/06/01 06:21:30 DEBUG : potato2: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db5576f5_d20210601_m061653_c001_v0001128_t0021") 2021/06/01 06:21:30 DEBUG : potato2: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1171ac7fba493550_d20210601_m061651_c001_v0001134_t0055") 2021/06/01 06:21:30 DEBUG : potato2: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db5576e8_d20210601_m061650_c001_v0001128_t0021") 2021/06/01 06:21:30 DEBUG : potato2: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f11235a8bf80d6bd1_d20210601_m061628_c001_v0001006_t0042") 2021/06/01 06:21:30 DEBUG : potato2: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db5576ba_d20210601_m061626_c001_v0001128_t0021") 2021/06/01 06:21:30 DEBUG : potato2: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f108efb890c3aea3a_d20210601_m061532_c001_v0001041_t0028") 2021/06/01 06:21:30 DEBUG : potato2: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db55762b_d20210601_m061531_c001_v0001128_t0021") 2021/06/01 06:21:30 DEBUG : potato2: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f116800e3c7648793_d20210601_m061528_c001_v0001144_t0007") 2021/06/01 06:21:30 DEBUG : potato2: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db557624_d20210601_m061527_c001_v0001128_t0021") 2021/06/01 06:21:30 DEBUG : potato3: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f116ff5a508d05e8c_d20210601_m061818_c001_v0001137_t0057") 2021/06/01 06:21:30 DEBUG : potato3: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f11189818a9d25966_d20210601_m061815_c001_v0001044_t0009") 2021/06/01 06:21:30 DEBUG : potato3: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f104626b456af9732_d20210601_m061758_c001_v0001009_t0009") 2021/06/01 06:21:31 DEBUG : potato3: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f11189818a9d25950_d20210601_m061749_c001_v0001044_t0009") 2021/06/01 06:21:31 DEBUG : potato3: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f11246695f71ad59f_d20210601_m061738_c001_v0001130_t0033") 2021/06/01 06:21:31 DEBUG : potato3: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f11189818a9d2593d_d20210601_m061734_c001_v0001044_t0009") 2021/06/01 06:21:31 DEBUG : pre-dest1/1: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f11980ae1c96bcb22_d20210601_m061850_c001_v0001150_t0019") 2021/06/01 06:21:31 DEBUG : pre-dest1/1: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db5577c7_d20210601_m061844_c001_v0001128_t0021") 2021/06/01 06:21:31 DEBUG : pre-dest2/2: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1154cc56e149b484_d20210601_m061851_c001_v0001146_t0051") 2021/06/01 06:21:31 DEBUG : pre-dest2/2: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f11189818a9d2597c_d20210601_m061845_c001_v0001044_t0009") 2021/06/01 06:21:31 DEBUG : sizeonly: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f106419cadda20926_d20210601_m061552_c001_v0001144_t0026") 2021/06/01 06:21:31 DEBUG : sizeonly: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db557652_d20210601_m061551_c001_v0001128_t0021") 2021/06/01 06:21:31 DEBUG : sub dir/hello world: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f10679d16a47fa6a3_d20210601_m061733_c001_v0001041_t0029") 2021/06/01 06:21:31 DEBUG : sub dir/hello world: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f11189818a9d25939_d20210601_m061732_c001_v0001044_t0009") 2021/06/01 06:21:31 DEBUG : sub dir/hello world: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1066e5c6854e9f63_d20210601_m061730_c001_v0001145_t0001") 2021/06/01 06:21:31 DEBUG : sub dir/hello world: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db557758_d20210601_m061729_c001_v0001128_t0021") 2021/06/01 06:21:31 DEBUG : sub dir/hello world: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f111943b30ef88140_d20210601_m061549_c001_v0001157_t0018") 2021/06/01 06:21:31 DEBUG : sub dir/hello world: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db55764a_d20210601_m061548_c001_v0001128_t0021") 2021/06/01 06:21:31 DEBUG : sub dir/hello world: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f10400c9d016b7562_d20210601_m061547_c001_v0001090_t0044") 2021/06/01 06:21:31 DEBUG : sub dir/hello world: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db557645_d20210601_m061546_c001_v0001128_t0021") 2021/06/01 06:21:31 DEBUG : sub dir/hello world: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1049711b6fc633d0_d20210601_m061543_c001_v0001144_t0052") 2021/06/01 06:21:31 DEBUG : sub dir/hello world: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db557639_d20210601_m061540_c001_v0001128_t0021") 2021/06/01 06:21:31 DEBUG : sub dir/hello world: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f109cc1a84ae40095_d20210601_m061539_c001_v0001144_t0053") 2021/06/01 06:21:31 DEBUG : sub dir/hello world: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db557637_d20210601_m061538_c001_v0001128_t0021") 2021/06/01 06:21:31 DEBUG : sub dir/hello world: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f119c092f079a9f4e_d20210601_m061537_c001_v0001037_t0044") 2021/06/01 06:21:31 DEBUG : sub dir/hello world: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db557635_d20210601_m061536_c001_v0001128_t0021") 2021/06/01 06:21:31 DEBUG : sub dir/hello world: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f10561a59069e6573_d20210601_m061535_c001_v0001154_t0024") 2021/06/01 06:21:32 DEBUG : sub dir/hello world: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db55762f_d20210601_m061534_c001_v0001128_t0021") 2021/06/01 06:21:32 DEBUG : sub dir/hello world: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1131be0f5e7fc72d_d20210601_m061525_c001_v0001144_t0030") 2021/06/01 06:21:32 DEBUG : sub dir/hello world: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db55761e_d20210601_m061524_c001_v0001128_t0021") 2021/06/01 06:21:32 DEBUG : sub dir/hello world: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f113a5bf53b76ec78_d20210601_m061523_c001_v0001044_t0058") 2021/06/01 06:21:32 DEBUG : sub dir/hello world: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db55761c_d20210601_m061522_c001_v0001128_t0021") 2021/06/01 06:21:32 DEBUG : sub dir/hello world: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1118ceb3b346dd36_d20210601_m061520_c001_v0001039_t0041") 2021/06/01 06:21:32 DEBUG : sub dir/hello world: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db557619_d20210601_m061519_c001_v0001128_t0021") 2021/06/01 06:21:32 DEBUG : sub dir/hello world: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1039fa44a61cbea9_d20210601_m061518_c001_v0001131_t0019") 2021/06/01 06:21:32 DEBUG : sub dir/hello world: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db557617_d20210601_m061517_c001_v0001128_t0021") 2021/06/01 06:21:32 DEBUG : sub/yam: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f101801b514885b0f_d20210601_m061727_c001_v0001157_t0023") 2021/06/01 06:21:32 DEBUG : sub/yam: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f114da636bd83e34b_d20210601_m061725_c001_v0001092_t0049") 2021/06/01 06:21:32 DEBUG : three: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f115f7630f935a228_d20210601_m061711_c001_v0001145_t0011") 2021/06/01 06:21:32 DEBUG : three: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db557720_d20210601_m061710_c001_v0001128_t0021") 2021/06/01 06:21:32 DEBUG : three: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db557712_d20210601_m061704_c001_v0001128_t0021") 2021/06/01 06:21:32 DEBUG : two: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1075316b07f3660e_d20210601_m061712_c001_v0001134_t0033") 2021/06/01 06:21:32 DEBUG : two: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f11189818a9d258f8_d20210601_m061705_c001_v0001044_t0009") 2021/06/01 06:21:32 DEBUG : two: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117fd0a3db557710_d20210601_m061703_c001_v0001128_t0021") 2021/06/01 06:21:32 DEBUG : yam: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f109e0397ecce2536_d20210601_m061728_c001_v0001157_t0015") 2021/06/01 06:21:32 DEBUG : yam: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f1132f5f456ba4cc0_d20210601_m061726_c001_v0001023_t0017") 2021/06/01 06:21:32 DEBUG : yam: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f110135c14ded072d_d20210601_m061719_c001_v0001044_t0019") 2021/06/01 06:21:32 DEBUG : yam: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f114da636bd83e345_d20210601_m061717_c001_v0001092_t0049") 2021/06/01 06:21:32 DEBUG : yam: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f11860de5354d1f94_d20210601_m061715_c001_v0001093_t0003") 2021/06/01 06:21:32 DEBUG : yam: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f114da636bd83e343_d20210601_m061713_c001_v0001092_t0049") 2021/06/01 06:21:32 DEBUG : yaml: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f10409d54c18e758a_d20210601_m061724_c001_v0001157_t0002") 2021/06/01 06:21:32 DEBUG : yaml: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f11492defdd3b2abd_d20210601_m061718_c001_v0001120_t0024") 2021/06/01 06:21:33 DEBUG : yaml: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f117824f363c4a2f9_d20210601_m061716_c001_v0001137_t0058") 2021/06/01 06:21:33 DEBUG : yaml: Deleting (id "4_z7fe24d53306dcb6b7f980e1b_f11008947eb6f5927_d20210601_m061714_c001_v0001040_t0034") "./sync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -fast-list" - Finished OK in 6m19.190676978s (try 1/5)