"./sync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose" - Starting (try 1/5) 2021/05/09 05:16:46 DEBUG : Creating backend with remote "TestB2:rclone-test-laqusil4rehosuq7bezemup2" 2021/05/09 05:16:46 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2021/05/09 05:16:47 DEBUG : Creating backend with remote "/tmp/rclone047191132" === RUN TestPipe --- PASS: TestPipe (0.00s) === RUN TestPipeConcurrent --- PASS: TestPipeConcurrent (0.16s) === 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-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" 2021/05/09 05:16:48 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) 2021/05/09 05:16:48 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for checks to finish 2021/05/09 05:16:48 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for transfers to finish --- PASS: TestCopyWithDryRun (1.50s) === RUN TestCopy run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" 2021/05/09 05:16:49 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for checks to finish 2021/05/09 05:16:49 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for transfers to finish 2021/05/09 05:16:50 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/05/09 05:16:50 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopy (2.11s) === RUN TestCopyMissingDirectory run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" 2021/05/09 05:16:50 DEBUG : Creating backend with remote "/non-existing" 2021/05/09 05:16:51 ERROR : : error reading source directory: directory not found 2021/05/09 05:16:51 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for checks to finish 2021/05/09 05:16:51 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for transfers to finish --- PASS: TestCopyMissingDirectory (0.42s) === RUN TestCopyNoTraverse run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" 2021/05/09 05:16:51 DEBUG : Couldn't decode error response: EOF 2021/05/09 05:16:51 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for checks to finish 2021/05/09 05:16:51 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for transfers to finish 2021/05/09 05:16:52 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/05/09 05:16:52 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoTraverse (1.87s) === RUN TestCopyCheckFirst run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" 2021/05/09 05:16:53 INFO : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Running all checks before starting transfers 2021/05/09 05:16:53 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for checks to finish 2021/05/09 05:16:53 INFO : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Checks finished, now starting transfers 2021/05/09 05:16:53 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for transfers to finish 2021/05/09 05:16:53 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/05/09 05:16:53 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyCheckFirst (1.31s) === RUN TestSyncNoTraverse run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" 2021/05/09 05:16:54 ERROR : Ignoring --no-traverse with sync 2021/05/09 05:16:54 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for checks to finish 2021/05/09 05:16:54 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for transfers to finish 2021/05/09 05:16:55 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/05/09 05:16:55 INFO : sub dir/hello world: Copied (new) 2021/05/09 05:16:55 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoTraverse (1.46s) === RUN TestCopyWithDepth run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" 2021/05/09 05:16:56 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for checks to finish 2021/05/09 05:16:56 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for transfers to finish 2021/05/09 05:16:56 DEBUG : hello world2: SHA-1 = c0bf117f9c93d02c894fe8ffd6e5cecf3545c209 OK 2021/05/09 05:16:56 INFO : hello world2: Copied (new) --- PASS: TestCopyWithDepth (1.35s) === RUN TestCopyWithFilesFrom run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" 2021/05/09 05:16:57 DEBUG : hello world2: Excluded 2021/05/09 05:16:57 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for checks to finish 2021/05/09 05:16:57 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for transfers to finish 2021/05/09 05:16:57 DEBUG : potato2: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/05/09 05:16:57 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFrom (1.25s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" 2021/05/09 05:16:59 DEBUG : Couldn't decode error response: EOF 2021/05/09 05:16:59 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for checks to finish 2021/05/09 05:16:59 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for transfers to finish 2021/05/09 05:16:59 DEBUG : potato2: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/05/09 05:16:59 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFromAndNoTraverse (2.15s) === RUN TestCopyEmptyDirectories run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" 2021/05/09 05:17:00 DEBUG : sub dir2: Making directory 2021/05/09 05:17:00 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for checks to finish 2021/05/09 05:17:00 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for transfers to finish 2021/05/09 05:17:01 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/05/09 05:17:01 INFO : sub dir/hello world: Copied (new) 2021/05/09 05:17:01 DEBUG : sub dir2: Making directory 2021/05/09 05:17:01 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: copied 1 directories fstest.go:251: Filtering empty directory "sub dir2" --- PASS: TestCopyEmptyDirectories (1.68s) === RUN TestMoveEmptyDirectories run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" 2021/05/09 05:17:02 DEBUG : sub dir2: Making directory 2021/05/09 05:17:02 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for checks to finish 2021/05/09 05:17:02 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for transfers to finish 2021/05/09 05:17:03 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/05/09 05:17:03 INFO : sub dir/hello world: Copied (new) 2021/05/09 05:17:03 INFO : sub dir/hello world: Deleted 2021/05/09 05:17:03 DEBUG : sub dir: Making directory 2021/05/09 05:17:03 DEBUG : sub dir2: Making directory 2021/05/09 05:17:03 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: copied 2 directories fstest.go:251: Filtering empty directory "sub dir2" --- PASS: TestMoveEmptyDirectories (2.20s) === RUN TestSyncEmptyDirectories run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" 2021/05/09 05:17:04 DEBUG : sub dir2: Making directory 2021/05/09 05:17:04 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for checks to finish 2021/05/09 05:17:04 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for transfers to finish 2021/05/09 05:17:05 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/05/09 05:17:05 INFO : sub dir/hello world: Copied (new) 2021/05/09 05:17:05 DEBUG : sub dir2: Making directory 2021/05/09 05:17:05 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: copied 1 directories 2021/05/09 05:17:05 DEBUG : Waiting for deletions to finish fstest.go:251: Filtering empty directory "sub dir2" --- PASS: TestSyncEmptyDirectories (1.23s) === RUN TestServerSideCopy run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" 2021/05/09 05:17:08 DEBUG : Creating backend with remote "TestB2:rclone-test-yujizuc9kizovem4dezecef2" sync_test.go:281: Server side copy (if possible) B2 bucket rclone-test-laqusil4rehosuq7bezemup2 -> B2 bucket rclone-test-yujizuc9kizovem4dezecef2 2021/05/09 05:17:10 DEBUG : B2 bucket rclone-test-yujizuc9kizovem4dezecef2: Waiting for checks to finish 2021/05/09 05:17:10 DEBUG : B2 bucket rclone-test-yujizuc9kizovem4dezecef2: Waiting for transfers to finish 2021/05/09 05:17:10 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/05/09 05:17:10 INFO : sub dir/hello world: Copied (server-side copy) 2021/05/09 05:17:10 DEBUG : B2 bucket rclone-test-yujizuc9kizovem4dezecef2: Purge remote 2021/05/09 05:17:10 DEBUG : sub dir/hello world: Deleting (id "4_z4fb2dd0380ed4bfb7f980e1b_f100ad0ecd4ae31ea_d20210509_m051710_c001_v0001030_t0008") --- PASS: TestServerSideCopy (6.70s) === RUN TestCopyAfterDelete run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" 2021/05/09 05:17:13 ERROR : : error listing: directory not found 2021/05/09 05:17:13 DEBUG : Local file system at /tmp/rclone047191132: Making directory 2021/05/09 05:17:13 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for checks to finish 2021/05/09 05:17:13 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for transfers to finish --- PASS: TestCopyAfterDelete (1.63s) === RUN TestCopyRedownload run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" 2021/05/09 05:17:15 DEBUG : Local file system at /tmp/rclone047191132: Waiting for checks to finish 2021/05/09 05:17:15 DEBUG : Local file system at /tmp/rclone047191132: Waiting for transfers to finish 2021/05/09 05:17:15 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/05/09 05:17:15 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyRedownload (2.38s) === RUN TestSyncBasedOnCheckSum run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" 2021/05/09 05:17:16 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for checks to finish 2021/05/09 05:17:16 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for transfers to finish 2021/05/09 05:17:17 DEBUG : check sum: SHA-1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2021/05/09 05:17:17 INFO : check sum: Copied (new) 2021/05/09 05:17:17 DEBUG : Waiting for deletions to finish 2021/05/09 05:17:17 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for checks to finish 2021/05/09 05:17:17 DEBUG : check sum: SHA-1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2021/05/09 05:17:17 DEBUG : check sum: Size and SHA-1 of src and dst objects identical 2021/05/09 05:17:17 DEBUG : check sum: Unchanged skipping 2021/05/09 05:17:17 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for transfers to finish 2021/05/09 05:17:17 DEBUG : Waiting for deletions to finish 2021/05/09 05:17:17 INFO : There was nothing to transfer --- PASS: TestSyncBasedOnCheckSum (2.45s) === RUN TestSyncSizeOnly run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" 2021/05/09 05:17:19 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for checks to finish 2021/05/09 05:17:19 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for transfers to finish 2021/05/09 05:17:19 DEBUG : sizeonly: SHA-1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2021/05/09 05:17:19 INFO : sizeonly: Copied (new) 2021/05/09 05:17:19 DEBUG : Waiting for deletions to finish 2021/05/09 05:17:19 DEBUG : sizeonly: Sizes identical 2021/05/09 05:17:19 DEBUG : sizeonly: Unchanged skipping 2021/05/09 05:17:19 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for checks to finish 2021/05/09 05:17:19 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for transfers to finish 2021/05/09 05:17:19 DEBUG : Waiting for deletions to finish 2021/05/09 05:17:19 INFO : There was nothing to transfer --- PASS: TestSyncSizeOnly (2.03s) === RUN TestSyncIgnoreSize run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" 2021/05/09 05:17:21 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for checks to finish 2021/05/09 05:17:21 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for transfers to finish 2021/05/09 05:17:22 DEBUG : ignore-size: SHA-1 = 4a756ca07e9487f482465a99e8286abc86ba4dc7 OK 2021/05/09 05:17:22 INFO : ignore-size: Copied (new) 2021/05/09 05:17:22 DEBUG : Waiting for deletions to finish 2021/05/09 05:17:22 DEBUG : ignore-size: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/05/09 05:17:22 DEBUG : ignore-size: Unchanged skipping 2021/05/09 05:17:22 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for checks to finish 2021/05/09 05:17:22 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for transfers to finish 2021/05/09 05:17:22 DEBUG : Waiting for deletions to finish 2021/05/09 05:17:22 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreSize (2.03s) === RUN TestSyncIgnoreTimes run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" 2021/05/09 05:17:23 DEBUG : existing: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/05/09 05:17:23 DEBUG : existing: Unchanged skipping 2021/05/09 05:17:23 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for checks to finish 2021/05/09 05:17:23 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for transfers to finish 2021/05/09 05:17:23 DEBUG : Waiting for deletions to finish 2021/05/09 05:17:23 INFO : There was nothing to transfer 2021/05/09 05:17:24 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2021/05/09 05:17:24 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for checks to finish 2021/05/09 05:17:24 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for transfers to finish 2021/05/09 05:17:27 DEBUG : existing: SHA-1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2021/05/09 05:17:27 INFO : existing: Copied (replaced existing) 2021/05/09 05:17:27 DEBUG : Waiting for deletions to finish --- PASS: TestSyncIgnoreTimes (6.17s) === RUN TestSyncIgnoreExisting run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" 2021/05/09 05:17:29 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for checks to finish 2021/05/09 05:17:29 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for transfers to finish 2021/05/09 05:17:29 DEBUG : existing: SHA-1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2021/05/09 05:17:29 INFO : existing: Copied (new) 2021/05/09 05:17:29 DEBUG : Waiting for deletions to finish 2021/05/09 05:17:30 DEBUG : existing: Destination exists, skipping 2021/05/09 05:17:30 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for checks to finish 2021/05/09 05:17:30 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for transfers to finish 2021/05/09 05:17:30 DEBUG : Waiting for deletions to finish 2021/05/09 05:17:30 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreExisting (1.53s) === RUN TestSyncIgnoreErrors run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" 2021/05/09 05:17:33 DEBUG : d: Making directory fstest.go:251: Filtering empty directory "d" 2021/05/09 05:17:34 DEBUG : c/non empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/05/09 05:17:34 DEBUG : c/non empty space: Unchanged skipping 2021/05/09 05:17:34 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for checks to finish 2021/05/09 05:17:34 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for transfers to finish 2021/05/09 05:17:34 DEBUG : a/potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/05/09 05:17:34 INFO : a/potato2: Copied (new) 2021/05/09 05:17:34 DEBUG : Waiting for deletions to finish 2021/05/09 05:17:34 INFO : b/potato: Deleted --- PASS: TestSyncIgnoreErrors (6.12s) === RUN TestSyncAfterChangingModtimeOnly run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" 2021/05/09 05:17:39 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/05/09 05:17:39 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for checks to finish 2021/05/09 05:17:39 DEBUG : empty space: SHA-1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2021/05/09 05:17:39 NOTICE: empty space: Skipped update modification time as --dry-run is set (size 1) 2021/05/09 05:17:39 DEBUG : empty space: Unchanged skipping 2021/05/09 05:17:39 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for transfers to finish 2021/05/09 05:17:39 DEBUG : Waiting for deletions to finish 2021/05/09 05:17:39 INFO : There was nothing to transfer 2021/05/09 05:17:39 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/05/09 05:17:39 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for checks to finish 2021/05/09 05:17:39 DEBUG : empty space: SHA-1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2021/05/09 05:17:40 INFO : empty space: Updated modification time in destination 2021/05/09 05:17:40 DEBUG : empty space: Unchanged skipping 2021/05/09 05:17:40 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for transfers to finish 2021/05/09 05:17:40 DEBUG : Waiting for deletions to finish 2021/05/09 05:17:40 INFO : There was nothing to transfer --- PASS: TestSyncAfterChangingModtimeOnly (4.29s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" 2021/05/09 05:17:41 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/05/09 05:17:41 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for checks to finish 2021/05/09 05:17:41 DEBUG : empty space: SHA-1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2021/05/09 05:17:41 DEBUG : empty space: Unchanged skipping 2021/05/09 05:17:41 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for transfers to finish 2021/05/09 05:17:41 DEBUG : Waiting for deletions to finish 2021/05/09 05:17:41 INFO : There was nothing to transfer --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (1.37s) === RUN TestSyncDoesntUpdateModtime run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" 2021/05/09 05:17:43 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/05/09 05:17:43 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for checks to finish 2021/05/09 05:17:43 DEBUG : foo: SHA-1 = 0beec7b5ea3f0fdbc95d0dd47f3c5bc275da8a33 (Local file system at /tmp/rclone047191132) 2021/05/09 05:17:43 DEBUG : foo: SHA-1 = 62cdb7020ff920e5aa642c3d4066950dd1f01f4d (B2 bucket rclone-test-laqusil4rehosuq7bezemup2) 2021/05/09 05:17:43 DEBUG : foo: SHA-1 differ 2021/05/09 05:17:43 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for transfers to finish 2021/05/09 05:17:43 DEBUG : foo: SHA-1 = 0beec7b5ea3f0fdbc95d0dd47f3c5bc275da8a33 OK 2021/05/09 05:17:43 INFO : foo: Copied (replaced existing) 2021/05/09 05:17:43 DEBUG : Waiting for deletions to finish --- PASS: TestSyncDoesntUpdateModtime (2.60s) === RUN TestSyncAfterAddingAFile run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" 2021/05/09 05:17:45 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/05/09 05:17:45 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for checks to finish 2021/05/09 05:17:45 DEBUG : empty space: Unchanged skipping 2021/05/09 05:17:45 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for transfers to finish 2021/05/09 05:17:46 DEBUG : potato: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/05/09 05:17:46 INFO : potato: Copied (new) 2021/05/09 05:17:46 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterAddingAFile (2.72s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" 2021/05/09 05:17:48 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2021/05/09 05:17:48 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for checks to finish 2021/05/09 05:17:48 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for transfers to finish 2021/05/09 05:17:49 DEBUG : potato: SHA-1 = 0fd321a1023ac4e1e1054fcc0236b9d0755f722f OK 2021/05/09 05:17:49 INFO : potato: Copied (replaced existing) 2021/05/09 05:17:49 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingFilesSizeOnly (2.59s) === RUN TestSyncAfterChangingContentsOnly run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" 2021/05/09 05:17:51 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/05/09 05:17:51 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for checks to finish 2021/05/09 05:17:51 DEBUG : potato: SHA-1 = 1ca5094b3b73f587249eabe179c40a3eec839b1f (Local file system at /tmp/rclone047191132) 2021/05/09 05:17:51 DEBUG : potato: SHA-1 = 0fd321a1023ac4e1e1054fcc0236b9d0755f722f (B2 bucket rclone-test-laqusil4rehosuq7bezemup2) 2021/05/09 05:17:51 DEBUG : potato: SHA-1 differ 2021/05/09 05:17:51 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for transfers to finish 2021/05/09 05:17:52 DEBUG : potato: SHA-1 = 1ca5094b3b73f587249eabe179c40a3eec839b1f OK 2021/05/09 05:17:52 INFO : potato: Copied (replaced existing) 2021/05/09 05:17:52 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingContentsOnly (2.91s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" 2021/05/09 05:17:54 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/05/09 05:17:54 DEBUG : empty space: Unchanged skipping 2021/05/09 05:17:54 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2021/05/09 05:17:54 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for checks to finish 2021/05/09 05:17:54 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for transfers to finish 2021/05/09 05:17:54 DEBUG : Waiting for deletions to finish 2021/05/09 05:17:54 NOTICE: potato: Skipped delete as --dry-run is set (size 21) --- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (2.70s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" 2021/05/09 05:17:57 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/05/09 05:17:57 DEBUG : empty space: Unchanged skipping 2021/05/09 05:17:57 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for checks to finish 2021/05/09 05:17:57 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for transfers to finish 2021/05/09 05:17:57 DEBUG : potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/05/09 05:17:57 INFO : potato2: Copied (new) 2021/05/09 05:17:57 DEBUG : Waiting for deletions to finish 2021/05/09 05:17:58 INFO : potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (3.88s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" 2021/05/09 05:18:01 DEBUG : d: Making directory 2021/05/09 05:18:01 DEBUG : d/e: Making directory fstest.go:251: Filtering empty directory "d" fstest.go:251: Filtering empty directory "d/e" 2021/05/09 05:18:01 DEBUG : c/non empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/05/09 05:18:01 DEBUG : c/non empty space: Unchanged skipping 2021/05/09 05:18:01 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for checks to finish 2021/05/09 05:18:01 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for transfers to finish 2021/05/09 05:18:02 DEBUG : a/potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/05/09 05:18:02 INFO : a/potato2: Copied (new) 2021/05/09 05:18:02 DEBUG : Waiting for deletions to finish 2021/05/09 05:18:04 INFO : b/potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (5.70s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" 2021/05/09 05:18:06 DEBUG : d: Making directory fstest.go:251: Filtering empty directory "d" 2021/05/09 05:18:07 DEBUG : c/non empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/05/09 05:18:07 DEBUG : c/non empty space: Unchanged skipping 2021/05/09 05:18:07 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for checks to finish 2021/05/09 05:18:07 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for transfers to finish 2021/05/09 05:18:07 DEBUG : a/potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/05/09 05:18:07 INFO : a/potato2: Copied (new) 2021/05/09 05:18:07 ERROR : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: not deleting files as there were IO errors 2021/05/09 05:18:07 ERROR : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: not deleting directories as there were IO errors fstest.go:251: Filtering empty directory "d" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (8.22s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" 2021/05/09 05:18:17 DEBUG : Waiting for deletions to finish 2021/05/09 05:18:17 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/05/09 05:18:17 DEBUG : empty space: Unchanged skipping 2021/05/09 05:18:17 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for checks to finish 2021/05/09 05:18:17 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for transfers to finish 2021/05/09 05:18:18 DEBUG : potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/05/09 05:18:18 INFO : potato2: Copied (new) 2021/05/09 05:18:18 INFO : potato: Deleted --- PASS: TestSyncDeleteDuring (6.68s) === RUN TestSyncDeleteBefore run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" 2021/05/09 05:18:21 DEBUG : Waiting for deletions to finish 2021/05/09 05:18:21 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for checks to finish 2021/05/09 05:18:21 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for transfers to finish 2021/05/09 05:18:22 INFO : potato: Deleted 2021/05/09 05:18:22 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/05/09 05:18:22 DEBUG : empty space: Unchanged skipping 2021/05/09 05:18:22 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for checks to finish 2021/05/09 05:18:22 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for transfers to finish 2021/05/09 05:18:22 DEBUG : potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/05/09 05:18:22 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteBefore (3.43s) === RUN TestCopyDeleteBefore run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" 2021/05/09 05:18:24 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for checks to finish 2021/05/09 05:18:24 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for transfers to finish 2021/05/09 05:18:26 DEBUG : potato2: SHA-1 = 810274f0df19dab60a894a562b7a8ff04c072623 OK 2021/05/09 05:18:26 INFO : potato2: Copied (new) --- PASS: TestCopyDeleteBefore (3.61s) === RUN TestSyncWithExclude run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" 2021/05/09 05:18:28 DEBUG : enormous: Excluded 2021/05/09 05:18:28 DEBUG : potato2: Excluded 2021/05/09 05:18:28 DEBUG : potato2: Excluded 2021/05/09 05:18:28 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/05/09 05:18:28 DEBUG : empty space: Unchanged skipping 2021/05/09 05:18:28 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for checks to finish 2021/05/09 05:18:28 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for transfers to finish 2021/05/09 05:18:28 DEBUG : Waiting for deletions to finish 2021/05/09 05:18:28 INFO : There was nothing to transfer 2021/05/09 05:18:29 DEBUG : enormous: Excluded 2021/05/09 05:18:29 DEBUG : potato2: Excluded 2021/05/09 05:18:29 DEBUG : potato2: Excluded 2021/05/09 05:18:29 DEBUG : empty space: Size and modification time the same (differ by 456.789µs, within tolerance 1ms) 2021/05/09 05:18:29 DEBUG : empty space: Unchanged skipping 2021/05/09 05:18:29 DEBUG : Local file system at /tmp/rclone047191132: Waiting for checks to finish 2021/05/09 05:18:29 DEBUG : Local file system at /tmp/rclone047191132: Waiting for transfers to finish 2021/05/09 05:18:29 DEBUG : Waiting for deletions to finish 2021/05/09 05:18:29 INFO : There was nothing to transfer --- PASS: TestSyncWithExclude (2.53s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" 2021/05/09 05:18:31 DEBUG : enormous: Excluded 2021/05/09 05:18:31 DEBUG : potato2: Excluded 2021/05/09 05:18:31 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for checks to finish 2021/05/09 05:18:31 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/05/09 05:18:31 DEBUG : empty space: Unchanged skipping 2021/05/09 05:18:31 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for transfers to finish 2021/05/09 05:18:31 DEBUG : Waiting for deletions to finish 2021/05/09 05:18:32 INFO : enormous: Deleted 2021/05/09 05:18:32 INFO : potato2: Deleted 2021/05/09 05:18:32 INFO : There was nothing to transfer 2021/05/09 05:18:33 DEBUG : Local file system at /tmp/rclone047191132: Waiting for checks to finish 2021/05/09 05:18:33 DEBUG : empty space: Size and modification time the same (differ by 456.789µs, within tolerance 1ms) 2021/05/09 05:18:33 DEBUG : empty space: Unchanged skipping 2021/05/09 05:18:33 DEBUG : Local file system at /tmp/rclone047191132: Waiting for transfers to finish 2021/05/09 05:18:33 DEBUG : Waiting for deletions to finish 2021/05/09 05:18:33 INFO : enormous: Deleted 2021/05/09 05:18:33 INFO : potato2: Deleted 2021/05/09 05:18:33 INFO : There was nothing to transfer --- PASS: TestSyncWithExcludeAndDeleteExcluded (3.67s) === RUN TestSyncWithUpdateOlder run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" 2021/05/09 05:18:36 DEBUG : four: Sizes differ (src 4 vs dst 8) 2021/05/09 05:18:36 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2021/05/09 05:18:36 DEBUG : one: Destination is newer than source, skipping 2021/05/09 05:18:36 DEBUG : three: Sizes identical 2021/05/09 05:18:36 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2021/05/09 05:18:36 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for checks to finish 2021/05/09 05:18:36 DEBUG : two: SHA-1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b (Local file system at /tmp/rclone047191132) 2021/05/09 05:18:36 DEBUG : two: SHA-1 = b10e0da85e90399abfec36c7376b20aa4596461b (B2 bucket rclone-test-laqusil4rehosuq7bezemup2) 2021/05/09 05:18:36 DEBUG : two: SHA-1 differ 2021/05/09 05:18:36 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for transfers to finish 2021/05/09 05:18:36 DEBUG : four: SHA-1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2021/05/09 05:18:36 INFO : four: Copied (replaced existing) 2021/05/09 05:18:37 DEBUG : two: SHA-1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2021/05/09 05:18:37 INFO : two: Copied (replaced existing) 2021/05/09 05:18:37 DEBUG : five: SHA-1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2021/05/09 05:18:37 INFO : five: Copied (new) 2021/05/09 05:18:37 DEBUG : Waiting for deletions to finish 2021/05/09 05:18:38 DEBUG : one: Destination is newer than source, skipping 2021/05/09 05:18:38 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for checks to finish 2021/05/09 05:18:38 DEBUG : five: SHA-1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2021/05/09 05:18:38 DEBUG : five: Size and SHA-1 of src and dst objects identical 2021/05/09 05:18:38 DEBUG : five: Destination mod time is within 1s of source and files identical, skipping 2021/05/09 05:18:38 DEBUG : two: SHA-1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2021/05/09 05:18:38 DEBUG : two: Size and SHA-1 of src and dst objects identical 2021/05/09 05:18:38 DEBUG : two: Destination mod time is within 1s of source and files identical, skipping 2021/05/09 05:18:38 DEBUG : three: SHA-1 = b802f384302cb24fbab0a44997e820bf2e8507bb (Local file system at /tmp/rclone047191132) 2021/05/09 05:18:38 DEBUG : three: SHA-1 = 03731cad13f4cf7b59703c77529381cdec1632e5 (B2 bucket rclone-test-laqusil4rehosuq7bezemup2) 2021/05/09 05:18:38 DEBUG : three: SHA-1 differ 2021/05/09 05:18:38 DEBUG : three: Destination mod time is within 1s of source but files differ, transferring 2021/05/09 05:18:38 DEBUG : four: SHA-1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2021/05/09 05:18:38 DEBUG : four: Size and SHA-1 of src and dst objects identical 2021/05/09 05:18:38 DEBUG : four: Destination mod time is within 1s of source and files identical, skipping 2021/05/09 05:18:38 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for transfers to finish 2021/05/09 05:18:38 DEBUG : three: SHA-1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/05/09 05:18:38 INFO : three: Copied (replaced existing) 2021/05/09 05:18:38 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithUpdateOlder (8.26s) === 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-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" sync_test.go:1088: Can track renames: true 2021/05/09 05:18:42 INFO : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Making map for --track-renames 2021/05/09 05:18:42 INFO : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Finished making map for --track-renames 2021/05/09 05:18:42 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for checks to finish 2021/05/09 05:18:42 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for renames to finish 2021/05/09 05:18:42 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for transfers to finish 2021/05/09 05:18:42 DEBUG : yam: SHA-1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2021/05/09 05:18:42 INFO : yam: Copied (new) 2021/05/09 05:18:42 DEBUG : potato: SHA-1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2021/05/09 05:18:42 INFO : potato: Copied (new) 2021/05/09 05:18:42 DEBUG : Waiting for deletions to finish 2021/05/09 05:18:43 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/05/09 05:18:43 DEBUG : potato: Unchanged skipping 2021/05/09 05:18:43 INFO : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Making map for --track-renames 2021/05/09 05:18:43 INFO : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Finished making map for --track-renames 2021/05/09 05:18:43 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for checks to finish 2021/05/09 05:18:43 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for renames to finish 2021/05/09 05:18:43 DEBUG : Couldn't decode error response: EOF 2021/05/09 05:18:44 DEBUG : yam: SHA-1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2021/05/09 05:18:44 INFO : yam: Copied (server-side copy) to: yaml 2021/05/09 05:18:44 INFO : yam: Deleted 2021/05/09 05:18:44 INFO : yaml: Renamed from "yam" 2021/05/09 05:18:44 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for transfers to finish 2021/05/09 05:18:44 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenames (4.50s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" sync_test.go:1157: Can track renames: true 2021/05/09 05:18:46 INFO : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Making map for --track-renames 2021/05/09 05:18:46 INFO : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Finished making map for --track-renames 2021/05/09 05:18:46 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for checks to finish 2021/05/09 05:18:46 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for renames to finish 2021/05/09 05:18:46 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for transfers to finish 2021/05/09 05:18:47 DEBUG : yam: SHA-1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2021/05/09 05:18:47 INFO : yam: Copied (new) 2021/05/09 05:18:47 DEBUG : potato: SHA-1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2021/05/09 05:18:47 INFO : potato: Copied (new) 2021/05/09 05:18:47 DEBUG : Waiting for deletions to finish 2021/05/09 05:18:48 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/05/09 05:18:48 DEBUG : potato: Unchanged skipping 2021/05/09 05:18:48 INFO : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Making map for --track-renames 2021/05/09 05:18:48 INFO : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Finished making map for --track-renames 2021/05/09 05:18:48 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for checks to finish 2021/05/09 05:18:48 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for renames to finish 2021/05/09 05:18:48 DEBUG : Couldn't decode error response: EOF 2021/05/09 05:18:49 DEBUG : yam: SHA-1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2021/05/09 05:18:49 INFO : yam: Copied (server-side copy) to: yaml 2021/05/09 05:18:49 INFO : yam: Deleted 2021/05/09 05:18:49 INFO : yaml: Renamed from "yam" 2021/05/09 05:18:49 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for transfers to finish 2021/05/09 05:18:49 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenamesStrategyModtime (5.18s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" sync_test.go:1193: Can track renames: true 2021/05/09 05:18:51 INFO : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Making map for --track-renames 2021/05/09 05:18:51 INFO : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Finished making map for --track-renames 2021/05/09 05:18:51 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for checks to finish 2021/05/09 05:18:51 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for renames to finish 2021/05/09 05:18:51 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for transfers to finish 2021/05/09 05:18:52 DEBUG : sub/yam: SHA-1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2021/05/09 05:18:52 INFO : sub/yam: Copied (new) 2021/05/09 05:18:52 DEBUG : potato: SHA-1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2021/05/09 05:18:52 INFO : potato: Copied (new) 2021/05/09 05:18:52 DEBUG : Waiting for deletions to finish 2021/05/09 05:18:52 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/05/09 05:18:52 DEBUG : potato: Unchanged skipping 2021/05/09 05:18:53 INFO : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Making map for --track-renames 2021/05/09 05:18:53 INFO : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Finished making map for --track-renames 2021/05/09 05:18:53 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for checks to finish 2021/05/09 05:18:53 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for renames to finish 2021/05/09 05:18:53 DEBUG : Couldn't decode error response: EOF 2021/05/09 05:18:53 DEBUG : sub/yam: SHA-1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2021/05/09 05:18:53 INFO : sub/yam: Copied (server-side copy) to: yam 2021/05/09 05:18:54 INFO : sub/yam: Deleted 2021/05/09 05:18:54 INFO : yam: Renamed from "sub/yam" 2021/05/09 05:18:54 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for transfers to finish 2021/05/09 05:18:54 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenamesStrategyLeaf (5.98s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" 2021/05/09 05:18:57 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for checks to finish 2021/05/09 05:18:57 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for transfers to finish 2021/05/09 05:18:58 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/05/09 05:18:58 INFO : sub dir/hello world: Copied (new) 2021/05/09 05:18:58 INFO : sub dir/hello world: Deleted 2021/05/09 05:18:59 DEBUG : nested/sub dir/file: SHA-1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK 2021/05/09 05:18:59 INFO : nested/sub dir/file: Copied (new) 2021/05/09 05:18:59 INFO : nested/sub dir/file: Deleted 2021/05/09 05:18:59 DEBUG : sub dir: Removing directory 2021/05/09 05:18:59 DEBUG : nested/sub dir: Removing directory 2021/05/09 05:18:59 DEBUG : nested: Removing directory 2021/05/09 05:18:59 DEBUG : Local file system at /tmp/rclone047191132: deleted 3 directories --- PASS: TestMoveWithDeleteEmptySrcDirs (3.18s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" 2021/05/09 05:19:01 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for checks to finish 2021/05/09 05:19:01 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for transfers to finish 2021/05/09 05:19:01 DEBUG : nested/sub dir/file: SHA-1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK 2021/05/09 05:19:01 INFO : nested/sub dir/file: Copied (new) 2021/05/09 05:19:01 INFO : nested/sub dir/file: Deleted 2021/05/09 05:19:01 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/05/09 05:19:01 INFO : sub dir/hello world: Copied (new) 2021/05/09 05:19:01 INFO : sub dir/hello world: Deleted --- PASS: TestMoveWithoutDeleteEmptySrcDirs (3.32s) === RUN TestServerSideMove run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" 2021/05/09 05:19:04 DEBUG : Creating backend with remote "TestB2:rclone-test-hubufug9rumekum0yisuzif0" sync_test.go:1245: Server side move (if possible) B2 bucket rclone-test-laqusil4rehosuq7bezemup2 -> B2 bucket rclone-test-hubufug9rumekum0yisuzif0 2021/05/09 05:19:09 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms) 2021/05/09 05:19:09 DEBUG : empty space: Unchanged skipping 2021/05/09 05:19:09 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2021/05/09 05:19:09 DEBUG : B2 bucket rclone-test-hubufug9rumekum0yisuzif0: Waiting for checks to finish 2021/05/09 05:19:10 DEBUG : potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/05/09 05:19:10 INFO : potato2: Copied (server-side copy) 2021/05/09 05:19:10 INFO : empty space: Deleted 2021/05/09 05:19:10 DEBUG : B2 bucket rclone-test-hubufug9rumekum0yisuzif0: Waiting for transfers to finish 2021/05/09 05:19:10 DEBUG : potato3: SHA-1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2021/05/09 05:19:10 INFO : potato3: Copied (server-side copy) 2021/05/09 05:19:11 INFO : potato3: Deleted 2021/05/09 05:19:12 INFO : potato2: Deleted 2021/05/09 05:19:12 DEBUG : Creating backend with remote "TestB2:rclone-test-tusidic9cifuxum1kovanut7" 2021/05/09 05:19:13 DEBUG : B2 bucket rclone-test-tusidic9cifuxum1kovanut7: Waiting for checks to finish 2021/05/09 05:19:13 DEBUG : B2 bucket rclone-test-tusidic9cifuxum1kovanut7: Waiting for transfers to finish 2021/05/09 05:19:14 DEBUG : potato3: SHA-1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2021/05/09 05:19:14 INFO : potato3: Copied (server-side copy) 2021/05/09 05:19:14 DEBUG : empty space: SHA-1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2021/05/09 05:19:14 INFO : empty space: Copied (server-side copy) 2021/05/09 05:19:14 DEBUG : potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/05/09 05:19:14 INFO : potato2: Copied (server-side copy) 2021/05/09 05:19:14 INFO : potato3: Deleted 2021/05/09 05:19:14 INFO : empty space: Deleted 2021/05/09 05:19:15 INFO : potato2: Deleted 2021/05/09 05:19:15 DEBUG : B2 bucket rclone-test-tusidic9cifuxum1kovanut7: Purge remote 2021/05/09 05:19:15 DEBUG : empty space: Deleting (id "4_z7f62bd33900d4bfb7f980e1b_f109b85fffa4cbdf2_d20210509_m051914_c001_v0001042_t0029") 2021/05/09 05:19:15 DEBUG : potato2: Deleting (id "4_z7f62bd33900d4bfb7f980e1b_f1149fe1042ec931f_d20210509_m051914_c001_v0001134_t0016") 2021/05/09 05:19:15 DEBUG : potato3: Deleting (id "4_z7f62bd33900d4bfb7f980e1b_f101c6a3ad9adc75c_d20210509_m051914_c001_v0001090_t0005") 2021/05/09 05:19:16 DEBUG : B2 bucket rclone-test-hubufug9rumekum0yisuzif0: Purge remote 2021/05/09 05:19:16 DEBUG : empty space: Deleting (id "4_zbf629d73900d4bfb7f980e1b_f100ab3bb65ca47c8_d20210509_m051914_c001_v0001134_t0017") 2021/05/09 05:19:16 DEBUG : empty space: Deleting (id "4_zbf629d73900d4bfb7f980e1b_f1097c58b4b81c8fb_d20210509_m051907_c001_v0001144_t0040") 2021/05/09 05:19:16 DEBUG : potato2: Deleting (id "4_zbf629d73900d4bfb7f980e1b_f11059bc1752b7635_d20210509_m051914_c001_v0001131_t0055") 2021/05/09 05:19:16 DEBUG : potato2: Deleting (id "4_zbf629d73900d4bfb7f980e1b_f1000c810289d8336_d20210509_m051909_c001_v0001131_t0056") 2021/05/09 05:19:16 DEBUG : potato3: Deleting (id "4_zbf629d73900d4bfb7f980e1b_f100563444f996550_d20210509_m051914_c001_v0001041_t0002") 2021/05/09 05:19:16 DEBUG : potato3: Deleting (id "4_zbf629d73900d4bfb7f980e1b_f1064c68a43977390_d20210509_m051910_c001_v0001157_t0055") 2021/05/09 05:19:16 DEBUG : potato3: Deleting (id "4_zbf629d73900d4bfb7f980e1b_f1097c58b4b81c905_d20210509_m051908_c001_v0001144_t0040") --- PASS: TestServerSideMove (13.05s) === RUN TestServerSideMoveWithFilter run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" 2021/05/09 05:19:17 DEBUG : Creating backend with remote "TestB2:rclone-test-citibow7qonameq6necepew9" sync_test.go:1245: Server side move (if possible) B2 bucket rclone-test-laqusil4rehosuq7bezemup2 -> B2 bucket rclone-test-citibow7qonameq6necepew9 2021/05/09 05:19:22 DEBUG : empty space: Excluded 2021/05/09 05:19:22 DEBUG : empty space: Excluded 2021/05/09 05:19:22 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2021/05/09 05:19:22 DEBUG : B2 bucket rclone-test-citibow7qonameq6necepew9: Waiting for checks to finish 2021/05/09 05:19:22 DEBUG : B2 bucket rclone-test-citibow7qonameq6necepew9: Waiting for transfers to finish 2021/05/09 05:19:23 DEBUG : potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/05/09 05:19:23 INFO : potato2: Copied (server-side copy) 2021/05/09 05:19:23 DEBUG : potato3: SHA-1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2021/05/09 05:19:23 INFO : potato3: Copied (server-side copy) 2021/05/09 05:19:24 INFO : potato3: Deleted 2021/05/09 05:19:26 INFO : potato2: Deleted 2021/05/09 05:19:26 DEBUG : Creating backend with remote "TestB2:rclone-test-tucifey4qizexuy4xiwunof0" 2021/05/09 05:19:27 DEBUG : empty space: Excluded 2021/05/09 05:19:27 DEBUG : B2 bucket rclone-test-tucifey4qizexuy4xiwunof0: Waiting for checks to finish 2021/05/09 05:19:27 DEBUG : B2 bucket rclone-test-tucifey4qizexuy4xiwunof0: Waiting for transfers to finish 2021/05/09 05:19:28 DEBUG : potato3: SHA-1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2021/05/09 05:19:28 INFO : potato3: Copied (server-side copy) 2021/05/09 05:19:28 DEBUG : potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/05/09 05:19:28 INFO : potato2: Copied (server-side copy) 2021/05/09 05:19:28 INFO : potato3: Deleted 2021/05/09 05:19:29 INFO : potato2: Deleted 2021/05/09 05:19:29 DEBUG : B2 bucket rclone-test-tucifey4qizexuy4xiwunof0: Purge remote 2021/05/09 05:19:29 DEBUG : potato2: Deleting (id "4_zffd2ed83900d4bfb7f980e1b_f11440f20d31ae446_d20210509_m051927_c001_v0001144_t0001") 2021/05/09 05:19:29 DEBUG : potato3: Deleting (id "4_zffd2ed83900d4bfb7f980e1b_f10691b9fe207a25b_d20210509_m051927_c001_v0001146_t0030") 2021/05/09 05:19:30 DEBUG : B2 bucket rclone-test-citibow7qonameq6necepew9: Purge remote 2021/05/09 05:19:30 DEBUG : empty space: Deleting (id "4_z4f02cdf3900d4bfb7f980e1b_f119dc18f2f8872d8_d20210509_m051921_c001_v0001157_t0003") 2021/05/09 05:19:30 DEBUG : potato2: Deleting (id "4_z4f02cdf3900d4bfb7f980e1b_f1136a4f5ae15994c_d20210509_m051928_c001_v0001131_t0028") 2021/05/09 05:19:30 DEBUG : potato2: Deleting (id "4_z4f02cdf3900d4bfb7f980e1b_f10283878bc30e455_d20210509_m051923_c001_v0001146_t0051") 2021/05/09 05:19:30 DEBUG : potato3: Deleting (id "4_z4f02cdf3900d4bfb7f980e1b_f1172f3a2208fff78_d20210509_m051928_c001_v0001134_t0024") 2021/05/09 05:19:30 DEBUG : potato3: Deleting (id "4_z4f02cdf3900d4bfb7f980e1b_f11024aad9901303d_d20210509_m051923_c001_v0001145_t0038") 2021/05/09 05:19:30 DEBUG : potato3: Deleting (id "4_z4f02cdf3900d4bfb7f980e1b_f119dc18f2f8872e7_d20210509_m051922_c001_v0001157_t0003") --- PASS: TestServerSideMoveWithFilter (14.77s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" 2021/05/09 05:19:32 DEBUG : Creating backend with remote "TestB2:rclone-test-dezutad7paziqit8wixolap5" 2021/05/09 05:19:34 DEBUG : tomatoDir: Making directory sync_test.go:1245: Server side move (if possible) B2 bucket rclone-test-laqusil4rehosuq7bezemup2 -> B2 bucket rclone-test-dezutad7paziqit8wixolap5 2021/05/09 05:19:37 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms) 2021/05/09 05:19:37 DEBUG : empty space: Unchanged skipping 2021/05/09 05:19:37 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2021/05/09 05:19:37 DEBUG : B2 bucket rclone-test-dezutad7paziqit8wixolap5: Waiting for checks to finish 2021/05/09 05:19:38 INFO : empty space: Deleted 2021/05/09 05:19:38 DEBUG : B2 bucket rclone-test-dezutad7paziqit8wixolap5: Waiting for transfers to finish 2021/05/09 05:19:38 DEBUG : potato3: SHA-1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2021/05/09 05:19:38 INFO : potato3: Copied (server-side copy) 2021/05/09 05:19:38 DEBUG : potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/05/09 05:19:38 INFO : potato2: Copied (server-side copy) 2021/05/09 05:19:38 INFO : potato3: Deleted 2021/05/09 05:19:39 INFO : potato2: Deleted 2021/05/09 05:19:39 DEBUG : Creating backend with remote "TestB2:rclone-test-kaxitop1koyuvuw0sizixus7" 2021/05/09 05:19:40 DEBUG : tomatoDir: Making directory 2021/05/09 05:19:40 DEBUG : B2 bucket rclone-test-kaxitop1koyuvuw0sizixus7: Waiting for checks to finish 2021/05/09 05:19:40 DEBUG : B2 bucket rclone-test-kaxitop1koyuvuw0sizixus7: Waiting for transfers to finish 2021/05/09 05:19:41 DEBUG : potato3: SHA-1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2021/05/09 05:19:41 INFO : potato3: Copied (server-side copy) 2021/05/09 05:19:41 DEBUG : potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/05/09 05:19:41 INFO : potato2: Copied (server-side copy) 2021/05/09 05:19:41 INFO : potato2: Deleted 2021/05/09 05:19:41 INFO : potato3: Deleted 2021/05/09 05:19:43 DEBUG : empty space: SHA-1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2021/05/09 05:19:43 INFO : empty space: Copied (server-side copy) 2021/05/09 05:19:43 INFO : empty space: Deleted 2021/05/09 05:19:44 DEBUG : B2 bucket rclone-test-kaxitop1koyuvuw0sizixus7: Purge remote 2021/05/09 05:19:44 DEBUG : empty space: Deleting (id "4_z1f621dc3901d4bfb7f980e1b_f1157c39bd0502c78_d20210509_m051941_c001_v0001134_t0034") 2021/05/09 05:19:44 DEBUG : potato2: Deleting (id "4_z1f621dc3901d4bfb7f980e1b_f110dd23e8fb4ecff_d20210509_m051941_c001_v0001092_t0038") 2021/05/09 05:19:44 DEBUG : potato3: Deleting (id "4_z1f621dc3901d4bfb7f980e1b_f119395f2d4114a87_d20210509_m051941_c001_v0001153_t0003") 2021/05/09 05:19:45 DEBUG : B2 bucket rclone-test-dezutad7paziqit8wixolap5: Purge remote 2021/05/09 05:19:45 DEBUG : empty space: Deleting (id "4_z9f220d63901d4bfb7f980e1b_f113698831abbbe01_d20210509_m051943_c001_v0001120_t0057") 2021/05/09 05:19:45 DEBUG : empty space: Deleting (id "4_z9f220d63901d4bfb7f980e1b_f10242060c3e0af43_d20210509_m051936_c001_v0001145_t0010") 2021/05/09 05:19:45 DEBUG : potato2: Deleting (id "4_z9f220d63901d4bfb7f980e1b_f113694b78053ee19_d20210509_m051941_c001_v0001128_t0057") 2021/05/09 05:19:45 DEBUG : potato2: Deleting (id "4_z9f220d63901d4bfb7f980e1b_f1087f73a841c791c_d20210509_m051938_c001_v0001092_t0041") 2021/05/09 05:19:45 DEBUG : potato3: Deleting (id "4_z9f220d63901d4bfb7f980e1b_f119dc18f2f88759b_d20210509_m051941_c001_v0001157_t0022") 2021/05/09 05:19:45 DEBUG : potato3: Deleting (id "4_z9f220d63901d4bfb7f980e1b_f109de062fd6c056c_d20210509_m051938_c001_v0001153_t0018") 2021/05/09 05:19:45 DEBUG : potato3: Deleting (id "4_z9f220d63901d4bfb7f980e1b_f10242060c3e0af4e_d20210509_m051936_c001_v0001145_t0010") --- PASS: TestServerSideMoveDeleteEmptySourceDirs (14.29s) === RUN TestServerSideMoveOverlap run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" 2021/05/09 05:19:46 DEBUG : Creating backend with remote "TestB2:rclone-test-laqusil4rehosuq7bezemup2/rclone-move-test" 2021/05/09 05:19:47 DEBUG : Couldn't decode error response: EOF --- PASS: TestServerSideMoveOverlap (2.94s) === RUN TestSyncOverlap run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" 2021/05/09 05:19:49 DEBUG : Creating backend with remote "TestB2:rclone-test-laqusil4rehosuq7bezemup2/rclone-sync-test" 2021/05/09 05:19:49 DEBUG : Couldn't decode error response: EOF --- PASS: TestSyncOverlap (0.72s) === RUN TestSyncCompareDest run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" 2021/05/09 05:19:50 DEBUG : Creating backend with remote "TestB2:rclone-test-laqusil4rehosuq7bezemup2/dst" 2021/05/09 05:19:50 DEBUG : Couldn't decode error response: EOF 2021/05/09 05:19:50 DEBUG : Creating backend with remote "TestB2:rclone-test-laqusil4rehosuq7bezemup2/CompareDest" 2021/05/09 05:19:50 DEBUG : Couldn't decode error response: EOF 2021/05/09 05:19:51 DEBUG : Couldn't decode error response: EOF 2021/05/09 05:19:51 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2 path dst: Waiting for checks to finish 2021/05/09 05:19:51 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2 path dst: Waiting for transfers to finish 2021/05/09 05:19:52 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/05/09 05:19:52 INFO : one: Copied (new) 2021/05/09 05:19:52 DEBUG : Waiting for deletions to finish 2021/05/09 05:19:52 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2 path dst: Waiting for checks to finish 2021/05/09 05:19:52 DEBUG : Couldn't decode error response: EOF 2021/05/09 05:19:52 DEBUG : one: Sizes differ (src 5 vs dst 3) 2021/05/09 05:19:52 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2 path dst: Waiting for transfers to finish 2021/05/09 05:19:53 DEBUG : one: SHA-1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2021/05/09 05:19:53 INFO : one: Copied (replaced existing) 2021/05/09 05:19:53 DEBUG : Waiting for deletions to finish 2021/05/09 05:19:55 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2 path dst: Waiting for checks to finish 2021/05/09 05:19:55 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/05/09 05:19:55 DEBUG : one: Destination found in --compare-dest, skipping 2021/05/09 05:19:55 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2 path dst: Waiting for transfers to finish 2021/05/09 05:19:55 DEBUG : Waiting for deletions to finish 2021/05/09 05:19:55 INFO : There was nothing to transfer 2021/05/09 05:19:58 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/05/09 05:19:58 DEBUG : two: Destination found in --compare-dest, skipping 2021/05/09 05:19:58 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2 path dst: Waiting for checks to finish 2021/05/09 05:19:59 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/05/09 05:19:59 DEBUG : one: Destination found in --compare-dest, skipping 2021/05/09 05:19:59 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2 path dst: Waiting for transfers to finish 2021/05/09 05:19:59 DEBUG : Waiting for deletions to finish 2021/05/09 05:19:59 INFO : There was nothing to transfer 2021/05/09 05:19:59 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/05/09 05:19:59 DEBUG : two: Destination found in --compare-dest, skipping 2021/05/09 05:19:59 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2 path dst: Waiting for checks to finish 2021/05/09 05:19:59 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/05/09 05:19:59 DEBUG : one: Destination found in --compare-dest, skipping 2021/05/09 05:19:59 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2 path dst: Waiting for transfers to finish 2021/05/09 05:19:59 DEBUG : Waiting for deletions to finish 2021/05/09 05:19:59 INFO : There was nothing to transfer 2021/05/09 05:20:00 DEBUG : two: Sizes differ (src 5 vs dst 3) 2021/05/09 05:20:00 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2 path dst: Waiting for checks to finish 2021/05/09 05:20:00 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/05/09 05:20:00 DEBUG : one: Destination found in --compare-dest, skipping 2021/05/09 05:20:00 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2 path dst: Waiting for transfers to finish 2021/05/09 05:20:01 DEBUG : two: SHA-1 = a4be8877ad88f3109a6411985a1f359e10e2d930 OK 2021/05/09 05:20:01 INFO : two: Copied (new) 2021/05/09 05:20:01 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCompareDest (16.45s) === RUN TestSyncMultipleCompareDest run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" 2021/05/09 05:20:08 DEBUG : Creating backend with remote "TestB2:rclone-test-laqusil4rehosuq7bezemup2/dest" 2021/05/09 05:20:09 DEBUG : Couldn't decode error response: EOF 2021/05/09 05:20:09 DEBUG : Creating backend with remote "TestB2:rclone-test-laqusil4rehosuq7bezemup2/pre-dest1" 2021/05/09 05:20:09 DEBUG : Couldn't decode error response: EOF 2021/05/09 05:20:09 DEBUG : Creating backend with remote "TestB2:rclone-test-laqusil4rehosuq7bezemup2/pre-dest2" 2021/05/09 05:20:10 DEBUG : Couldn't decode error response: EOF 2021/05/09 05:20:10 DEBUG : 1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/05/09 05:20:10 DEBUG : 1: Destination found in --compare-dest, skipping 2021/05/09 05:20:10 DEBUG : Couldn't decode error response: EOF 2021/05/09 05:20:10 DEBUG : 2: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/05/09 05:20:10 DEBUG : 2: Destination found in --compare-dest, skipping 2021/05/09 05:20:10 DEBUG : Couldn't decode error response: EOF 2021/05/09 05:20:11 DEBUG : Couldn't decode error response: EOF 2021/05/09 05:20:11 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2 path dest: Waiting for checks to finish 2021/05/09 05:20:11 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2 path dest: Waiting for transfers to finish 2021/05/09 05:20:12 DEBUG : 3: SHA-1 = 77de68daecd823babbb58edb1c8e14d7106e83bb OK 2021/05/09 05:20:12 INFO : 3: Copied (new) 2021/05/09 05:20:12 DEBUG : Waiting for deletions to finish --- PASS: TestSyncMultipleCompareDest (7.67s) === RUN TestSyncCopyDest run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" 2021/05/09 05:20:14 DEBUG : Creating backend with remote "TestB2:rclone-test-laqusil4rehosuq7bezemup2/dst" 2021/05/09 05:20:14 DEBUG : Couldn't decode error response: EOF 2021/05/09 05:20:14 DEBUG : Creating backend with remote "TestB2:rclone-test-laqusil4rehosuq7bezemup2/CopyDest" 2021/05/09 05:20:14 DEBUG : Couldn't decode error response: EOF 2021/05/09 05:20:15 DEBUG : Couldn't decode error response: EOF 2021/05/09 05:20:15 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2 path dst: Waiting for checks to finish 2021/05/09 05:20:15 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2 path dst: Waiting for transfers to finish 2021/05/09 05:20:16 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/05/09 05:20:16 INFO : one: Copied (new) 2021/05/09 05:20:16 DEBUG : Waiting for deletions to finish 2021/05/09 05:20:16 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2 path dst: Waiting for checks to finish 2021/05/09 05:20:17 DEBUG : Couldn't decode error response: EOF 2021/05/09 05:20:17 DEBUG : one: Sizes differ (src 5 vs dst 3) 2021/05/09 05:20:17 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2 path dst: Waiting for transfers to finish 2021/05/09 05:20:17 DEBUG : one: SHA-1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2021/05/09 05:20:17 INFO : one: Copied (replaced existing) 2021/05/09 05:20:17 DEBUG : Waiting for deletions to finish 2021/05/09 05:20:19 DEBUG : Creating backend with remote "TestB2:rclone-test-laqusil4rehosuq7bezemup2/BackupDir" 2021/05/09 05:20:19 DEBUG : Couldn't decode error response: EOF 2021/05/09 05:20:19 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2 path dst: Waiting for checks to finish 2021/05/09 05:20:20 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/05/09 05:20:20 DEBUG : one: Sizes differ (src 5 vs dst 3) 2021/05/09 05:20:20 DEBUG : Couldn't decode error response: EOF 2021/05/09 05:20:21 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/05/09 05:20:21 INFO : one: Copied (server-side copy) 2021/05/09 05:20:22 INFO : one: Deleted 2021/05/09 05:20:22 DEBUG : one: SHA-1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2021/05/09 05:20:22 INFO : one: Copied (server-side copy) 2021/05/09 05:20:22 DEBUG : one: Destination found in --copy-dest, using server-side copy 2021/05/09 05:20:22 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2 path dst: Waiting for transfers to finish 2021/05/09 05:20:22 DEBUG : Waiting for deletions to finish 2021/05/09 05:20:23 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/05/09 05:20:25 DEBUG : two: SHA-1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2021/05/09 05:20:25 INFO : two: Copied (server-side copy) 2021/05/09 05:20:25 DEBUG : two: Destination found in --copy-dest, using server-side copy 2021/05/09 05:20:25 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2 path dst: Waiting for checks to finish 2021/05/09 05:20:25 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/05/09 05:20:25 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/05/09 05:20:25 DEBUG : one: Unchanged skipping 2021/05/09 05:20:25 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2 path dst: Waiting for transfers to finish 2021/05/09 05:20:25 DEBUG : Waiting for deletions to finish 2021/05/09 05:20:25 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2 path dst: Waiting for checks to finish 2021/05/09 05:20:25 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/05/09 05:20:25 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/05/09 05:20:25 DEBUG : one: Unchanged skipping 2021/05/09 05:20:25 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/05/09 05:20:25 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/05/09 05:20:25 DEBUG : two: Unchanged skipping 2021/05/09 05:20:25 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2 path dst: Waiting for transfers to finish 2021/05/09 05:20:25 DEBUG : Waiting for deletions to finish 2021/05/09 05:20:25 INFO : There was nothing to transfer 2021/05/09 05:20:27 DEBUG : three: Sizes differ (src 7 vs dst 5) 2021/05/09 05:20:27 DEBUG : three: Destination not found in --copy-dest 2021/05/09 05:20:27 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2 path dst: Waiting for checks to finish 2021/05/09 05:20:27 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/05/09 05:20:27 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/05/09 05:20:27 DEBUG : one: Unchanged skipping 2021/05/09 05:20:27 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/05/09 05:20:27 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/05/09 05:20:27 DEBUG : two: Unchanged skipping 2021/05/09 05:20:27 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2 path dst: Waiting for transfers to finish 2021/05/09 05:20:28 DEBUG : three: SHA-1 = 370e613335f3cf2f19b2a2d35a86402f55c659f8 OK 2021/05/09 05:20:28 INFO : three: Copied (new) 2021/05/09 05:20:28 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCopyDest (18.77s) === RUN TestSyncBackupDir run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" 2021/05/09 05:20:37 DEBUG : pacer: low level retry 1/10 (error Post "https://api001.backblazeb2.com/b2api/v1/b2_list_file_names": EOF) 2021/05/09 05:20:37 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2021/05/09 05:20:38 DEBUG : pacer: Reducing sleep to 10ms 2021/05/09 05:20:38 DEBUG : Creating backend with remote "TestB2:rclone-test-laqusil4rehosuq7bezemup2/dst" 2021/05/09 05:20:39 DEBUG : Couldn't decode error response: EOF 2021/05/09 05:20:39 DEBUG : Creating backend with remote "TestB2:rclone-test-laqusil4rehosuq7bezemup2/backup" 2021/05/09 05:20:40 DEBUG : Couldn't decode error response: EOF 2021/05/09 05:20:40 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/05/09 05:20:40 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/05/09 05:20:40 DEBUG : two: Unchanged skipping 2021/05/09 05:20:40 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2 path dst: Waiting for checks to finish 2021/05/09 05:20:40 DEBUG : Couldn't decode error response: EOF 2021/05/09 05:20:41 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/05/09 05:20:41 INFO : one: Copied (server-side copy) 2021/05/09 05:20:41 INFO : one: Deleted 2021/05/09 05:20:41 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2 path dst: Waiting for transfers to finish 2021/05/09 05:20:43 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/05/09 05:20:43 INFO : one: Copied (new) 2021/05/09 05:20:43 DEBUG : Waiting for deletions to finish 2021/05/09 05:20:43 DEBUG : Couldn't decode error response: EOF 2021/05/09 05:20:43 DEBUG : three.txt: SHA-1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/05/09 05:20:43 INFO : three.txt: Copied (server-side copy) 2021/05/09 05:20:44 INFO : three.txt: Deleted 2021/05/09 05:20:44 INFO : three.txt: Moved into backup dir 2021/05/09 05:20:46 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/05/09 05:20:46 DEBUG : two: Unchanged skipping 2021/05/09 05:20:46 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/05/09 05:20:46 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2 path dst: Waiting for checks to finish 2021/05/09 05:20:46 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/05/09 05:20:46 INFO : one: Copied (server-side copy) 2021/05/09 05:20:47 INFO : one: Deleted 2021/05/09 05:20:47 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2 path dst: Waiting for transfers to finish 2021/05/09 05:20:47 DEBUG : one: SHA-1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2021/05/09 05:20:47 INFO : one: Copied (new) 2021/05/09 05:20:47 DEBUG : Waiting for deletions to finish 2021/05/09 05:20:48 DEBUG : three.txt: SHA-1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2021/05/09 05:20:48 INFO : three.txt: Copied (server-side copy) 2021/05/09 05:20:51 INFO : three.txt: Deleted 2021/05/09 05:20:51 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (22.00s) === RUN TestSyncBackupDirWithSuffix run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" 2021/05/09 05:20:58 DEBUG : Creating backend with remote "TestB2:rclone-test-laqusil4rehosuq7bezemup2/dst" 2021/05/09 05:20:59 DEBUG : Couldn't decode error response: EOF 2021/05/09 05:20:59 DEBUG : Creating backend with remote "TestB2:rclone-test-laqusil4rehosuq7bezemup2/backup" 2021/05/09 05:20:59 DEBUG : Config file has changed externaly - reloading 2021/05/09 05:21:00 DEBUG : Couldn't decode error response: EOF 2021/05/09 05:21:00 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/05/09 05:21:00 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/05/09 05:21:00 DEBUG : two: Unchanged skipping 2021/05/09 05:21:00 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2 path dst: Waiting for checks to finish 2021/05/09 05:21:00 DEBUG : Couldn't decode error response: EOF 2021/05/09 05:21:01 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/05/09 05:21:01 INFO : one: Copied (server-side copy) to: one.bak 2021/05/09 05:21:02 INFO : one: Deleted 2021/05/09 05:21:02 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2 path dst: Waiting for transfers to finish 2021/05/09 05:21:03 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/05/09 05:21:03 INFO : one: Copied (new) 2021/05/09 05:21:03 DEBUG : Waiting for deletions to finish 2021/05/09 05:21:03 DEBUG : Couldn't decode error response: EOF 2021/05/09 05:21:04 DEBUG : three.txt: SHA-1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/05/09 05:21:04 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2021/05/09 05:21:04 INFO : three.txt: Deleted 2021/05/09 05:21:04 INFO : three.txt: Moved into backup dir 2021/05/09 05:21:06 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/05/09 05:21:06 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/05/09 05:21:06 DEBUG : two: Unchanged skipping 2021/05/09 05:21:06 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2 path dst: Waiting for checks to finish 2021/05/09 05:21:06 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/05/09 05:21:06 INFO : one: Copied (server-side copy) to: one.bak 2021/05/09 05:21:07 INFO : one: Deleted 2021/05/09 05:21:07 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2 path dst: Waiting for transfers to finish 2021/05/09 05:21:08 DEBUG : one: SHA-1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2021/05/09 05:21:08 INFO : one: Copied (new) 2021/05/09 05:21:08 DEBUG : Waiting for deletions to finish 2021/05/09 05:21:09 DEBUG : three.txt: SHA-1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2021/05/09 05:21:09 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2021/05/09 05:21:10 INFO : three.txt: Deleted 2021/05/09 05:21:10 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (17.29s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" 2021/05/09 05:21:15 DEBUG : Creating backend with remote "TestB2:rclone-test-laqusil4rehosuq7bezemup2/dst" 2021/05/09 05:21:15 DEBUG : Config file has changed externaly - reloading 2021/05/09 05:21:16 DEBUG : Couldn't decode error response: EOF 2021/05/09 05:21:16 DEBUG : Creating backend with remote "TestB2:rclone-test-laqusil4rehosuq7bezemup2/backup" 2021/05/09 05:21:16 DEBUG : Couldn't decode error response: EOF 2021/05/09 05:21:17 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/05/09 05:21:17 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/05/09 05:21:17 DEBUG : two: Unchanged skipping 2021/05/09 05:21:17 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2 path dst: Waiting for checks to finish 2021/05/09 05:21:17 DEBUG : Couldn't decode error response: EOF 2021/05/09 05:21:18 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/05/09 05:21:18 INFO : one: Copied (server-side copy) to: one-2019-01-01 2021/05/09 05:21:18 INFO : one: Deleted 2021/05/09 05:21:18 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2 path dst: Waiting for transfers to finish 2021/05/09 05:21:19 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/05/09 05:21:19 INFO : one: Copied (new) 2021/05/09 05:21:19 DEBUG : Waiting for deletions to finish 2021/05/09 05:21:20 DEBUG : Couldn't decode error response: EOF 2021/05/09 05:21:20 DEBUG : three.txt: SHA-1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/05/09 05:21:20 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2021/05/09 05:21:20 INFO : three.txt: Deleted 2021/05/09 05:21:20 INFO : three.txt: Moved into backup dir 2021/05/09 05:21:22 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/05/09 05:21:22 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/05/09 05:21:22 DEBUG : two: Unchanged skipping 2021/05/09 05:21:22 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2 path dst: Waiting for checks to finish 2021/05/09 05:21:22 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/05/09 05:21:22 INFO : one: Copied (server-side copy) to: one-2019-01-01 2021/05/09 05:21:23 INFO : one: Deleted 2021/05/09 05:21:23 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2 path dst: Waiting for transfers to finish 2021/05/09 05:21:23 DEBUG : one: SHA-1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2021/05/09 05:21:23 INFO : one: Copied (new) 2021/05/09 05:21:23 DEBUG : Waiting for deletions to finish 2021/05/09 05:21:24 DEBUG : three.txt: SHA-1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2021/05/09 05:21:24 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2021/05/09 05:21:24 INFO : three.txt: Deleted 2021/05/09 05:21:24 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (14.99s) === RUN TestSyncBackupDirSuffixOnly run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" 2021/05/09 05:21:29 DEBUG : Creating backend with remote "TestB2:rclone-test-laqusil4rehosuq7bezemup2/dst" 2021/05/09 05:21:30 DEBUG : Couldn't decode error response: EOF 2021/05/09 05:21:31 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/05/09 05:21:31 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/05/09 05:21:31 DEBUG : two: Unchanged skipping 2021/05/09 05:21:31 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2 path dst: Waiting for checks to finish 2021/05/09 05:21:31 DEBUG : Couldn't decode error response: EOF 2021/05/09 05:21:31 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/05/09 05:21:31 INFO : one: Copied (server-side copy) to: one.bak 2021/05/09 05:21:32 INFO : one: Deleted 2021/05/09 05:21:32 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2 path dst: Waiting for transfers to finish 2021/05/09 05:21:33 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/05/09 05:21:33 INFO : one: Copied (new) 2021/05/09 05:21:33 DEBUG : Waiting for deletions to finish 2021/05/09 05:21:33 DEBUG : Couldn't decode error response: EOF 2021/05/09 05:21:33 DEBUG : three.txt: SHA-1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/05/09 05:21:33 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2021/05/09 05:21:34 INFO : three.txt: Deleted 2021/05/09 05:21:34 INFO : three.txt: Moved into backup dir 2021/05/09 05:21:35 DEBUG : one.bak: Excluded 2021/05/09 05:21:35 DEBUG : three.txt.bak: Excluded 2021/05/09 05:21:35 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/05/09 05:21:35 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2 path dst: Waiting for checks to finish 2021/05/09 05:21:35 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/05/09 05:21:35 DEBUG : two: Unchanged skipping 2021/05/09 05:21:35 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/05/09 05:21:35 INFO : one: Copied (server-side copy) to: one.bak 2021/05/09 05:21:36 INFO : one: Deleted 2021/05/09 05:21:36 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2 path dst: Waiting for transfers to finish 2021/05/09 05:21:36 DEBUG : one: SHA-1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2021/05/09 05:21:36 INFO : one: Copied (new) 2021/05/09 05:21:36 DEBUG : Waiting for deletions to finish 2021/05/09 05:21:36 DEBUG : three.txt: SHA-1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2021/05/09 05:21:36 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2021/05/09 05:21:37 INFO : three.txt: Deleted 2021/05/09 05:21:37 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirSuffixOnly (13.06s) === RUN TestSyncSuffix run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" 2021/05/09 05:21:44 DEBUG : Creating backend with remote "TestB2:rclone-test-laqusil4rehosuq7bezemup2/dst" 2021/05/09 05:21:46 DEBUG : Couldn't decode error response: EOF 2021/05/09 05:21:46 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/05/09 05:21:46 DEBUG : Couldn't decode error response: EOF 2021/05/09 05:21:52 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/05/09 05:21:52 INFO : one: Copied (server-side copy) to: one.bak 2021/05/09 05:21:52 INFO : one: Deleted 2021/05/09 05:21:53 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/05/09 05:21:53 INFO : one: Copied (new) 2021/05/09 05:21:54 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/05/09 05:21:54 DEBUG : two: Unchanged skipping 2021/05/09 05:21:54 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2021/05/09 05:21:54 DEBUG : Couldn't decode error response: EOF 2021/05/09 05:21:55 DEBUG : three.txt: SHA-1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/05/09 05:21:55 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2021/05/09 05:21:55 INFO : three.txt: Deleted 2021/05/09 05:21:56 DEBUG : three.txt: SHA-1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2021/05/09 05:21:56 INFO : three.txt: Copied (new) 2021/05/09 05:21:56 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/05/09 05:21:57 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/05/09 05:21:57 INFO : one: Copied (server-side copy) to: one.bak 2021/05/09 05:21:57 INFO : one: Deleted 2021/05/09 05:21:58 DEBUG : one: SHA-1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2021/05/09 05:21:58 INFO : one: Copied (new) 2021/05/09 05:21:58 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/05/09 05:21:58 DEBUG : two: Unchanged skipping 2021/05/09 05:21:58 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2021/05/09 05:21:59 DEBUG : three.txt: SHA-1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2021/05/09 05:21:59 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2021/05/09 05:21:59 INFO : three.txt: Deleted 2021/05/09 05:22:01 DEBUG : three.txt: SHA-1 = 476e1707cbd7f0497a7dc4d44f0b07b8566ffc30 OK 2021/05/09 05:22:01 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (24.66s) === RUN TestSyncSuffixKeepExtension run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" 2021/05/09 05:22:08 DEBUG : Creating backend with remote "TestB2:rclone-test-laqusil4rehosuq7bezemup2/dst" 2021/05/09 05:22:09 DEBUG : Couldn't decode error response: EOF 2021/05/09 05:22:09 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/05/09 05:22:10 DEBUG : Couldn't decode error response: EOF 2021/05/09 05:22:11 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/05/09 05:22:11 INFO : one: Copied (server-side copy) to: one-2019-01-01 2021/05/09 05:22:11 INFO : one: Deleted 2021/05/09 05:22:12 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/05/09 05:22:12 INFO : one: Copied (new) 2021/05/09 05:22:12 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/05/09 05:22:12 DEBUG : two: Unchanged skipping 2021/05/09 05:22:13 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2021/05/09 05:22:13 DEBUG : Couldn't decode error response: EOF 2021/05/09 05:22:13 DEBUG : three.txt: SHA-1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/05/09 05:22:13 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2021/05/09 05:22:14 INFO : three.txt: Deleted 2021/05/09 05:22:14 DEBUG : three.txt: SHA-1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2021/05/09 05:22:14 INFO : three.txt: Copied (new) 2021/05/09 05:22:14 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/05/09 05:22:15 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/05/09 05:22:15 INFO : one: Copied (server-side copy) to: one-2019-01-01 2021/05/09 05:22:15 INFO : one: Deleted 2021/05/09 05:22:16 DEBUG : one: SHA-1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2021/05/09 05:22:16 INFO : one: Copied (new) 2021/05/09 05:22:16 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/05/09 05:22:16 DEBUG : two: Unchanged skipping 2021/05/09 05:22:16 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2021/05/09 05:22:17 DEBUG : three.txt: SHA-1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2021/05/09 05:22:17 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2021/05/09 05:22:17 INFO : three.txt: Deleted 2021/05/09 05:22:17 DEBUG : three.txt: SHA-1 = 476e1707cbd7f0497a7dc4d44f0b07b8566ffc30 OK 2021/05/09 05:22:17 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (16.44s) === RUN TestSyncUTFNorm run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" 2021/05/09 05:22:22 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2021/05/09 05:22:22 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for checks to finish 2021/05/09 05:22:22 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for transfers to finish 2021/05/09 05:22:23 DEBUG : Testêé: SHA-1 = a54d88e06612d820bc3be72877c74f257b561b19 OK 2021/05/09 05:22:23 INFO : Testêé: Copied (replaced existing) to: Testêé 2021/05/09 05:22:23 DEBUG : Waiting for deletions to finish --- PASS: TestSyncUTFNorm (3.15s) === RUN TestSyncImmutable run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" 2021/05/09 05:22:24 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for checks to finish 2021/05/09 05:22:24 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for transfers to finish 2021/05/09 05:22:25 DEBUG : existing: SHA-1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2021/05/09 05:22:25 INFO : existing: Copied (new) 2021/05/09 05:22:25 DEBUG : Waiting for deletions to finish 2021/05/09 05:22:26 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2021/05/09 05:22:26 ERROR : existing: Source and destination exist but do not match: immutable file modified 2021/05/09 05:22:26 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for checks to finish 2021/05/09 05:22:26 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for transfers to finish 2021/05/09 05:22:26 ERROR : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: not deleting files as there were IO errors 2021/05/09 05:22:26 ERROR : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: not deleting directories as there were IO errors --- PASS: TestSyncImmutable (3.10s) === RUN TestSyncIgnoreCase run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" 2021/05/09 05:22:33 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for checks to finish 2021/05/09 05:22:33 DEBUG : existing: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/05/09 05:22:33 DEBUG : existing: Unchanged skipping 2021/05/09 05:22:33 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Waiting for transfers to finish 2021/05/09 05:22:33 DEBUG : Waiting for deletions to finish 2021/05/09 05:22:33 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreCase (6.87s) === RUN TestMaxTransfer === RUN TestMaxTransfer/Hard run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" sync_test.go:1941: This test only runs on local === RUN TestMaxTransfer/Soft run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" sync_test.go:1941: This test only runs on local === RUN TestMaxTransfer/Cautious run.go:176: Remote "B2 bucket rclone-test-laqusil4rehosuq7bezemup2", Local "Local file system at /tmp/rclone047191132", Modify Window "1ms" sync_test.go:1941: This test only runs on local --- PASS: TestMaxTransfer (0.95s) --- SKIP: TestMaxTransfer/Hard (0.29s) --- SKIP: TestMaxTransfer/Soft (0.30s) --- SKIP: TestMaxTransfer/Cautious (0.35s) PASS 2021/05/09 05:22:35 DEBUG : B2 bucket rclone-test-laqusil4rehosuq7bezemup2: Purge remote 2021/05/09 05:22:35 DEBUG : BackupDir/one: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f104988a290d3c72d_d20210509_m052028_c001_v0001130_t0039") 2021/05/09 05:22:35 DEBUG : BackupDir/one: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1099624fb5e5d4f4_d20210509_m052021_c001_v0001157_t0028") 2021/05/09 05:22:35 DEBUG : CompareDest/one: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1154c1da08f82d06_d20210509_m052002_c001_v0001032_t0025") 2021/05/09 05:22:35 DEBUG : CompareDest/one: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f105ce2097ea62300_d20210509_m051954_c001_v0001036_t0015") 2021/05/09 05:22:35 DEBUG : CompareDest/two: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f115d3bfe726b3e43_d20210509_m052004_c001_v0001033_t0010") 2021/05/09 05:22:35 DEBUG : CompareDest/two: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccca568_d20210509_m051958_c001_v0001157_t0014") 2021/05/09 05:22:35 DEBUG : CopyDest/one: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f116ccb491b70b140_d20210509_m052028_c001_v0001015_t0038") 2021/05/09 05:22:35 DEBUG : CopyDest/one: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f110ae15129d112e6_d20210509_m052018_c001_v0001130_t0034") 2021/05/09 05:22:35 DEBUG : CopyDest/three: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1053944fc485983c_d20210509_m052029_c001_v0001042_t0040") 2021/05/09 05:22:36 DEBUG : CopyDest/three: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccca8bf_d20210509_m052026_c001_v0001157_t0014") 2021/05/09 05:22:36 DEBUG : CopyDest/two: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f112250fad0dcf58a_d20210509_m052030_c001_v0001015_t0004") 2021/05/09 05:22:36 DEBUG : CopyDest/two: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f105ce2097ea62346_d20210509_m052023_c001_v0001036_t0015") 2021/05/09 05:22:36 DEBUG : EXISTING: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f103f18d5646359eb_d20210509_m052233_c001_v0001146_t0032") 2021/05/09 05:22:36 DEBUG : EXISTING: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f105ce2097ea62460_d20210509_m052228_c001_v0001036_t0015") 2021/05/09 05:22:36 DEBUG : Testêé: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f11697539ee7a7ecc_d20210509_m052224_c001_v0001008_t0002") 2021/05/09 05:22:36 DEBUG : Testêé: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3cccb595_d20210509_m052223_c001_v0001157_t0014") 2021/05/09 05:22:36 DEBUG : Testêé: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f105ce2097ea6244a_d20210509_m052221_c001_v0001036_t0015") 2021/05/09 05:22:36 DEBUG : a/potato2: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f11787eb3f368af5c_d20210509_m051808_c001_v0001032_t0048") 2021/05/09 05:22:36 DEBUG : a/potato2: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc9847_d20210509_m051807_c001_v0001157_t0014") 2021/05/09 05:22:36 DEBUG : a/potato2: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f117b33aa415ac410_d20210509_m051804_c001_v0001150_t0021") 2021/05/09 05:22:36 DEBUG : a/potato2: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc97a5_d20210509_m051801_c001_v0001157_t0014") 2021/05/09 05:22:36 DEBUG : a/potato2: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f100129b180f00c1f_d20210509_m051735_c001_v0001012_t0023") 2021/05/09 05:22:36 DEBUG : a/potato2: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc93ef_d20210509_m051734_c001_v0001157_t0014") 2021/05/09 05:22:36 DEBUG : b/potato: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1102f43baea5c295_d20210509_m051808_c001_v0001090_t0029") 2021/05/09 05:22:36 DEBUG : b/potato: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc9826_d20210509_m051805_c001_v0001157_t0014") 2021/05/09 05:22:36 DEBUG : b/potato: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f108ae6de068fe905_d20210509_m051803_c001_v0001029_t0031") 2021/05/09 05:22:37 DEBUG : b/potato: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc977c_d20210509_m051800_c001_v0001157_t0014") 2021/05/09 05:22:37 DEBUG : b/potato: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f110e73ae4207fa61_d20210509_m051734_c001_v0001015_t0001") 2021/05/09 05:22:37 DEBUG : b/potato: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc9395_d20210509_m051730_c001_v0001157_t0014") 2021/05/09 05:22:37 DEBUG : backup/one: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1037fe5f2408a258_d20210509_m052051_c001_v0001113_t0040") 2021/05/09 05:22:37 DEBUG : backup/one: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f114cbb3aee60e72a_d20210509_m052046_c001_v0001153_t0010") 2021/05/09 05:22:37 DEBUG : backup/one: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3cccaa91_d20210509_m052041_c001_v0001157_t0009") 2021/05/09 05:22:37 DEBUG : backup/one-2019-01-01: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f114274c21fb6939b_d20210509_m052125_c001_v0001022_t0008") 2021/05/09 05:22:37 DEBUG : backup/one-2019-01-01: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1030db35df0ab685_d20210509_m052122_c001_v0001120_t0053") 2021/05/09 05:22:37 DEBUG : backup/one-2019-01-01: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f10748d54e30a6fe4_d20210509_m052118_c001_v0001144_t0040") 2021/05/09 05:22:37 DEBUG : backup/one.bak: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1020eb62ee7e00ae_d20210509_m052110_c001_v0001128_t0013") 2021/05/09 05:22:37 DEBUG : backup/one.bak: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f10884abbe98b2b2a_d20210509_m052106_c001_v0001157_t0006") 2021/05/09 05:22:37 DEBUG : backup/one.bak: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1158c5e14c92690f_d20210509_m052101_c001_v0001150_t0038") 2021/05/09 05:22:37 DEBUG : backup/three-2019-01-01.txt: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3cccafd0_d20210509_m052125_c001_v0001157_t0030") 2021/05/09 05:22:37 DEBUG : backup/three-2019-01-01.txt: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f109de062fd6c08a6_d20210509_m052123_c001_v0001153_t0015") 2021/05/09 05:22:37 DEBUG : backup/three-2019-01-01.txt: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f114b07ccb6670882_d20210509_m052120_c001_v0001157_t0036") 2021/05/09 05:22:37 DEBUG : backup/three.txt: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f116efa5c39bfb46f_d20210509_m052053_c001_v0001144_t0059") 2021/05/09 05:22:37 DEBUG : backup/three.txt: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1136d46adeb0828b_d20210509_m052047_c001_v0001022_t0000") 2021/05/09 05:22:37 DEBUG : backup/three.txt: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f106a8a512615b25c_d20210509_m052043_c001_v0001044_t0024") 2021/05/09 05:22:37 DEBUG : backup/three.txt.bak: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1009f5c7ee848a40_d20210509_m052110_c001_v0001093_t0015") 2021/05/09 05:22:37 DEBUG : backup/three.txt.bak: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1184650ed38aa875_d20210509_m052109_c001_v0001038_t0009") 2021/05/09 05:22:37 DEBUG : backup/three.txt.bak: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1122782719ee2296_d20210509_m052103_c001_v0001137_t0036") 2021/05/09 05:22:37 DEBUG : c/non empty space: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1040a6f335b57c26_d20210509_m051813_c001_v0001017_t0019") 2021/05/09 05:22:37 DEBUG : c/non empty space: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc9833_d20210509_m051806_c001_v0001157_t0014") 2021/05/09 05:22:37 DEBUG : c/non empty space: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f10481b11897d86b5_d20210509_m051805_c001_v0001157_t0021") 2021/05/09 05:22:37 DEBUG : c/non empty space: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc978e_d20210509_m051800_c001_v0001157_t0014") 2021/05/09 05:22:38 DEBUG : c/non empty space: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1119cc957ab36635_d20210509_m051736_c001_v0001144_t0053") 2021/05/09 05:22:38 DEBUG : c/non empty space: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc93d2_d20210509_m051733_c001_v0001157_t0014") 2021/05/09 05:22:38 DEBUG : check sum: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f104b5111acf7dacb_d20210509_m051718_c001_v0001153_t0013") 2021/05/09 05:22:38 DEBUG : check sum: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc9233_d20210509_m051716_c001_v0001157_t0014") 2021/05/09 05:22:38 DEBUG : dest/3: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f118f57eb6d24d2a5_d20210509_m052012_c001_v0001157_t0012") 2021/05/09 05:22:38 DEBUG : dest/3: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f10787c92ab3bd2a5_d20210509_m052011_c001_v0001041_t0022") 2021/05/09 05:22:38 DEBUG : dst/one: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1119cc957ab37259_d20210509_m052218_c001_v0001144_t0027") 2021/05/09 05:22:38 DEBUG : dst/one: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1152f69e4b5510e0_d20210509_m052216_c001_v0001146_t0016") 2021/05/09 05:22:38 DEBUG : dst/one: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f106f7731b85f94b7_d20210509_m052215_c001_v0001154_t0006") 2021/05/09 05:22:38 DEBUG : dst/one: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1152f69e4b5510be_d20210509_m052212_c001_v0001146_t0016") 2021/05/09 05:22:38 DEBUG : dst/one: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f10159a0c8fe6b768_d20210509_m052211_c001_v0001137_t0001") 2021/05/09 05:22:38 DEBUG : dst/one: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f105ce2097ea6242b_d20210509_m052205_c001_v0001036_t0015") 2021/05/09 05:22:38 DEBUG : dst/one: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1118d91f671b311c_d20210509_m052201_c001_v0001145_t0043") 2021/05/09 05:22:38 DEBUG : dst/one: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1037fe5f2408a30c_d20210509_m052158_c001_v0001113_t0026") 2021/05/09 05:22:38 DEBUG : dst/one: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f110e4f5c22ab3fde_d20210509_m052157_c001_v0001150_t0013") 2021/05/09 05:22:38 DEBUG : dst/one: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1037fe5f2408a2fb_d20210509_m052153_c001_v0001113_t0026") 2021/05/09 05:22:38 DEBUG : dst/one: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1152f69e4b550fe6_d20210509_m052152_c001_v0001146_t0016") 2021/05/09 05:22:38 DEBUG : dst/one: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f105ce2097ea623ec_d20210509_m052140_c001_v0001036_t0015") 2021/05/09 05:22:38 DEBUG : dst/one: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f100b9646161ca9d9_d20210509_m052138_c001_v0001037_t0054") 2021/05/09 05:22:38 DEBUG : dst/one: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f112b9be4b27a14f9_d20210509_m052136_c001_v0001131_t0051") 2021/05/09 05:22:38 DEBUG : dst/one: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f114c1932c488c1f2_d20210509_m052135_c001_v0001012_t0007") 2021/05/09 05:22:38 DEBUG : dst/one: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f112b9be4b27a14f4_d20210509_m052133_c001_v0001131_t0051") 2021/05/09 05:22:38 DEBUG : dst/one: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f10154e0eb970fd9e_d20210509_m052131_c001_v0001136_t0030") 2021/05/09 05:22:38 DEBUG : dst/one: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f110ae15129d113eb_d20210509_m052127_c001_v0001130_t0034") 2021/05/09 05:22:38 DEBUG : dst/one: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f11184471e1c9180f_d20210509_m052126_c001_v0001157_t0027") 2021/05/09 05:22:39 DEBUG : dst/one: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f106950d356485f80_d20210509_m052123_c001_v0001150_t0026") 2021/05/09 05:22:39 DEBUG : dst/one: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f11184471e1c917bd_d20210509_m052122_c001_v0001157_t0016") 2021/05/09 05:22:39 DEBUG : dst/one: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f106950d356485f65_d20210509_m052119_c001_v0001150_t0026") 2021/05/09 05:22:39 DEBUG : dst/one: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f11201ee2606ec73e_d20210509_m052118_c001_v0001017_t0010") 2021/05/09 05:22:39 DEBUG : dst/one: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3cccae28_d20210509_m052112_c001_v0001157_t0014") 2021/05/09 05:22:39 DEBUG : dst/one: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f115fc3c957aba957_d20210509_m052111_c001_v0001157_t0010") 2021/05/09 05:22:39 DEBUG : dst/one: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f110dd6f83648a81b_d20210509_m052108_c001_v0001136_t0013") 2021/05/09 05:22:39 DEBUG : dst/one: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f103a75fcd30b5133_d20210509_m052106_c001_v0001157_t0021") 2021/05/09 05:22:39 DEBUG : dst/one: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f110dd6f83648a7fe_d20210509_m052103_c001_v0001136_t0013") 2021/05/09 05:22:39 DEBUG : dst/one: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f107f44966977053c_d20210509_m052101_c001_v0001157_t0017") 2021/05/09 05:22:39 DEBUG : dst/one: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f105ce2097ea6238e_d20210509_m052055_c001_v0001036_t0015") 2021/05/09 05:22:39 DEBUG : dst/one: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f11369866c8edd19a_d20210509_m052053_c001_v0001157_t0010") 2021/05/09 05:22:39 DEBUG : dst/one: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f10362a738c06aa5e_d20210509_m052047_c001_v0001145_t0027") 2021/05/09 05:22:39 DEBUG : dst/one: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f101a493b44324624_d20210509_m052046_c001_v0001157_t0001") 2021/05/09 05:22:39 DEBUG : dst/one: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f10362a738c06aa22_d20210509_m052042_c001_v0001145_t0027") 2021/05/09 05:22:39 DEBUG : dst/one: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f11607b6fc732d11a_d20210509_m052041_c001_v0001150_t0055") 2021/05/09 05:22:39 DEBUG : dst/one: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f110ae15129d11323_d20210509_m052033_c001_v0001130_t0034") 2021/05/09 05:22:39 DEBUG : dst/one: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccca966_d20210509_m052031_c001_v0001157_t0036") 2021/05/09 05:22:39 DEBUG : dst/one: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1175975d300cb86b_d20210509_m052022_c001_v0001154_t0022") 2021/05/09 05:22:39 DEBUG : dst/one: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f10362a738c06a924_d20210509_m052021_c001_v0001145_t0056") 2021/05/09 05:22:39 DEBUG : dst/one: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccca7ae_d20210509_m052018_c001_v0001157_t0014") 2021/05/09 05:22:39 DEBUG : dst/one: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f109c5f3182f8bd02_d20210509_m052017_c001_v0001136_t0011") 2021/05/09 05:22:39 DEBUG : dst/one: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f109c5f3182f8bcff_d20210509_m052015_c001_v0001136_t0011") 2021/05/09 05:22:39 DEBUG : dst/one: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1078433ed375e4bd_d20210509_m052004_c001_v0001023_t0043") 2021/05/09 05:22:40 DEBUG : dst/one: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f110ae15129d11283_d20210509_m051953_c001_v0001130_t0034") 2021/05/09 05:22:40 DEBUG : dst/one: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1183fb34006ba401_d20210509_m051952_c001_v0001128_t0002") 2021/05/09 05:22:40 DEBUG : dst/one: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1183fb34006ba3f9_d20210509_m051951_c001_v0001128_t0002") 2021/05/09 05:22:40 DEBUG : dst/one-2019-01-01: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f110ddde819f2eb10_d20210509_m052219_c001_v0001128_t0009") 2021/05/09 05:22:40 DEBUG : dst/one-2019-01-01: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f10758fe515fb7466_d20210509_m052215_c001_v0001120_t0037") 2021/05/09 05:22:40 DEBUG : dst/one-2019-01-01: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f107f449669770c7e_d20210509_m052210_c001_v0001157_t0059") 2021/05/09 05:22:40 DEBUG : dst/one.bak: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1087fb13bac5780b_d20210509_m052202_c001_v0001131_t0001") 2021/05/09 05:22:40 DEBUG : dst/one.bak: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1171f758ce18038a_d20210509_m052157_c001_v0001137_t0034") 2021/05/09 05:22:40 DEBUG : dst/one.bak: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f118c39da95365fad_d20210509_m052152_c001_v0001031_t0011") 2021/05/09 05:22:40 DEBUG : dst/one.bak: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f112bc74a9783072a_d20210509_m052138_c001_v0001113_t0045") 2021/05/09 05:22:40 DEBUG : dst/one.bak: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f100b385f8c6cbf03_d20210509_m052135_c001_v0001032_t0008") 2021/05/09 05:22:40 DEBUG : dst/one.bak: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f11159a51e9f8828d_d20210509_m052131_c001_v0001009_t0016") 2021/05/09 05:22:40 DEBUG : dst/three: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f11855fabdd3aa4d6_d20210509_m052031_c001_v0001040_t0042") 2021/05/09 05:22:40 DEBUG : dst/three: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f109c5f3182f8bd3f_d20210509_m052027_c001_v0001136_t0011") 2021/05/09 05:22:40 DEBUG : dst/three-2019-01-01.txt: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1135e0caae740eb4_d20210509_m052219_c001_v0001150_t0026") 2021/05/09 05:22:40 DEBUG : dst/three-2019-01-01.txt: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f107af000d3daebbb_d20210509_m052216_c001_v0001044_t0025") 2021/05/09 05:22:40 DEBUG : dst/three-2019-01-01.txt: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1097c8ede11c41b0_d20210509_m052213_c001_v0001005_t0006") 2021/05/09 05:22:40 DEBUG : dst/three.txt: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f115d273189383e3e_d20210509_m052220_c001_v0001145_t0009") 2021/05/09 05:22:40 DEBUG : dst/three.txt: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1152f69e4b5510ee_d20210509_m052218_c001_v0001146_t0016") 2021/05/09 05:22:40 DEBUG : dst/three.txt: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1165919dc290c03a_d20210509_m052217_c001_v0001154_t0020") 2021/05/09 05:22:40 DEBUG : dst/three.txt: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1152f69e4b5510cb_d20210509_m052214_c001_v0001146_t0016") 2021/05/09 05:22:40 DEBUG : dst/three.txt: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f119edc359647dc62_d20210509_m052213_c001_v0001090_t0046") 2021/05/09 05:22:40 DEBUG : dst/three.txt: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f110ae15129d11470_d20210509_m052208_c001_v0001130_t0034") 2021/05/09 05:22:40 DEBUG : dst/three.txt: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3cccb386_d20210509_m052202_c001_v0001157_t0007") 2021/05/09 05:22:40 DEBUG : dst/three.txt: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1037fe5f2408a318_d20210509_m052200_c001_v0001113_t0026") 2021/05/09 05:22:41 DEBUG : dst/three.txt: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f108e28fb69c2a8cd_d20210509_m052159_c001_v0001039_t0020") 2021/05/09 05:22:41 DEBUG : dst/three.txt: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1037fe5f2408a301_d20210509_m052156_c001_v0001113_t0026") 2021/05/09 05:22:41 DEBUG : dst/three.txt: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f119dc18f2f8887a5_d20210509_m052155_c001_v0001157_t0003") 2021/05/09 05:22:41 DEBUG : dst/three.txt: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f110ae15129d1141c_d20210509_m052144_c001_v0001130_t0034") 2021/05/09 05:22:41 DEBUG : dst/three.txt: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f11617e7b2eb724a6_d20210509_m052137_c001_v0001131_t0005") 2021/05/09 05:22:41 DEBUG : dst/three.txt: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f110ae15129d11400_d20210509_m052134_c001_v0001130_t0034") 2021/05/09 05:22:41 DEBUG : dst/three.txt: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f100b9646161ca9d1_d20210509_m052133_c001_v0001037_t0047") 2021/05/09 05:22:41 DEBUG : dst/three.txt: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3cccb026_d20210509_m052129_c001_v0001157_t0014") 2021/05/09 05:22:41 DEBUG : dst/three.txt: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f118de097cada7bd8_d20210509_m052124_c001_v0001032_t0059") 2021/05/09 05:22:41 DEBUG : dst/three.txt: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3cccaf4c_d20210509_m052121_c001_v0001157_t0014") 2021/05/09 05:22:41 DEBUG : dst/three.txt: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f11369866c8edd48c_d20210509_m052120_c001_v0001157_t0032") 2021/05/09 05:22:41 DEBUG : dst/three.txt: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f105ce2097ea623b8_d20210509_m052114_c001_v0001036_t0015") 2021/05/09 05:22:41 DEBUG : dst/three.txt: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f108c8567fe4cb9a7_d20210509_m052109_c001_v0001128_t0021") 2021/05/09 05:22:41 DEBUG : dst/three.txt: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f105ce2097ea623a0_d20210509_m052105_c001_v0001036_t0015") 2021/05/09 05:22:41 DEBUG : dst/three.txt: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1000c810289d85b6_d20210509_m052104_c001_v0001131_t0010") 2021/05/09 05:22:41 DEBUG : dst/three.txt: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f110ae15129d1137c_d20210509_m052058_c001_v0001130_t0034") 2021/05/09 05:22:41 DEBUG : dst/three.txt: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1053944fc4859869_d20210509_m052048_c001_v0001042_t0015") 2021/05/09 05:22:41 DEBUG : dst/three.txt: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f110ae15129d11357_d20210509_m052045_c001_v0001130_t0034") 2021/05/09 05:22:41 DEBUG : dst/three.txt: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f116efa5c39bfb3f9_d20210509_m052044_c001_v0001144_t0052") 2021/05/09 05:22:41 DEBUG : dst/three.txt: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccca9db_d20210509_m052034_c001_v0001157_t0014") 2021/05/09 05:22:41 DEBUG : dst/three.txt.bak: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f118b0bbef691a31d_d20210509_m052203_c001_v0001029_t0020") 2021/05/09 05:22:41 DEBUG : dst/three.txt.bak: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f118f57eb6d24e042_d20210509_m052158_c001_v0001157_t0048") 2021/05/09 05:22:41 DEBUG : dst/three.txt.bak: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f113698831abbc11d_d20210509_m052154_c001_v0001120_t0050") 2021/05/09 05:22:41 DEBUG : dst/three.txt.bak: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f10550bf6ea2ccb38_d20210509_m052139_c001_v0001150_t0058") 2021/05/09 05:22:42 DEBUG : dst/three.txt.bak: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1040a69bafa5e4f5_d20210509_m052136_c001_v0001146_t0023") 2021/05/09 05:22:42 DEBUG : dst/three.txt.bak: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f10945c76e2f64ae1_d20210509_m052133_c001_v0001145_t0048") 2021/05/09 05:22:42 DEBUG : dst/two: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f10884abbe98b3272_d20210509_m052220_c001_v0001157_t0034") 2021/05/09 05:22:42 DEBUG : dst/two: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3cccb3ff_d20210509_m052207_c001_v0001157_t0014") 2021/05/09 05:22:42 DEBUG : dst/two: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f11369866c8edd8cb_d20210509_m052204_c001_v0001157_t0005") 2021/05/09 05:22:42 DEBUG : dst/two: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3cccb156_d20210509_m052143_c001_v0001157_t0014") 2021/05/09 05:22:42 DEBUG : dst/two: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f11416990a7b7b3a3_d20210509_m052139_c001_v0001146_t0052") 2021/05/09 05:22:42 DEBUG : dst/two: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f105ce2097ea623d2_d20210509_m052128_c001_v0001036_t0015") 2021/05/09 05:22:42 DEBUG : dst/two: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f11271b205ed43c45_d20210509_m052126_c001_v0001146_t0044") 2021/05/09 05:22:42 DEBUG : dst/two: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f110ae15129d113b4_d20210509_m052113_c001_v0001130_t0034") 2021/05/09 05:22:42 DEBUG : dst/two: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f101137aba8a272a8_d20210509_m052111_c001_v0001144_t0004") 2021/05/09 05:22:42 DEBUG : dst/two: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3cccac40_d20210509_m052056_c001_v0001157_t0014") 2021/05/09 05:22:42 DEBUG : dst/two: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f105e6c881d00c9ba_d20210509_m052054_c001_v0001157_t0052") 2021/05/09 05:22:42 DEBUG : dst/two: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f105ce2097ea6235e_d20210509_m052034_c001_v0001036_t0015") 2021/05/09 05:22:42 DEBUG : dst/two: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1158c5e14c926831_d20210509_m052032_c001_v0001150_t0045") 2021/05/09 05:22:42 DEBUG : dst/two: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f109c6839bcfcecdc_d20210509_m052024_c001_v0001091_t0023") 2021/05/09 05:22:42 DEBUG : dst/two: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f104fe15fed7638fe_d20210509_m052006_c001_v0001025_t0009") 2021/05/09 05:22:42 DEBUG : dst/two: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1183fb34006ba431_d20210509_m052001_c001_v0001128_t0002") 2021/05/09 05:22:42 DEBUG : empty space: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f110ddde819f2e8cc_d20210509_m051937_c001_v0001128_t0024") 2021/05/09 05:22:42 DEBUG : empty space: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f110ae15129d11237_d20210509_m051933_c001_v0001130_t0034") 2021/05/09 05:22:42 DEBUG : empty space: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f106d0e8bc07d6e68_d20210509_m051931_c001_v0001019_t0019") 2021/05/09 05:22:42 DEBUG : empty space: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f110ae15129d111f5_d20210509_m051918_c001_v0001130_t0034") 2021/05/09 05:22:42 DEBUG : empty space: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f108da3f5c6cfc1c4_d20210509_m051909_c001_v0001041_t0014") 2021/05/09 05:22:42 DEBUG : empty space: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f110ae15129d111bd_d20210509_m051905_c001_v0001130_t0034") 2021/05/09 05:22:42 DEBUG : empty space: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f105a9063fdadad48_d20210509_m051833_c001_v0001131_t0015") 2021/05/09 05:22:43 DEBUG : empty space: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc9ae5_d20210509_m051830_c001_v0001157_t0014") 2021/05/09 05:22:43 DEBUG : empty space: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1156d1bd6e865b87_d20210509_m051829_c001_v0001153_t0004") 2021/05/09 05:22:43 DEBUG : empty space: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc9aa5_d20210509_m051828_c001_v0001157_t0014") 2021/05/09 05:22:43 DEBUG : empty space: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f10399422953d7ccb_d20210509_m051823_c001_v0001032_t0048") 2021/05/09 05:22:43 DEBUG : empty space: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc99e0_d20210509_m051821_c001_v0001157_t0014") 2021/05/09 05:22:43 DEBUG : empty space: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f11138ce7ca1702a0_d20210509_m051819_c001_v0001091_t0016") 2021/05/09 05:22:43 DEBUG : empty space: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc993a_d20210509_m051814_c001_v0001157_t0014") 2021/05/09 05:22:43 DEBUG : empty space: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f108328155d92d364_d20210509_m051758_c001_v0001154_t0021") 2021/05/09 05:22:43 DEBUG : empty space: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc9705_d20210509_m051756_c001_v0001157_t0014") 2021/05/09 05:22:43 DEBUG : empty space: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1156d1bd6e865a53_d20210509_m051755_c001_v0001153_t0002") 2021/05/09 05:22:43 DEBUG : empty space: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc96ad_d20210509_m051754_c001_v0001157_t0014") 2021/05/09 05:22:43 DEBUG : empty space: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1099624fb5e5c40c_d20210509_m051746_c001_v0001157_t0015") 2021/05/09 05:22:43 DEBUG : empty space: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc9570_d20210509_m051745_c001_v0001157_t0014") 2021/05/09 05:22:43 DEBUG : empty space: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1191bc4c20609811_d20210509_m051743_c001_v0001130_t0032") 2021/05/09 05:22:43 DEBUG : empty space: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc94f1_d20210509_m051742_c001_v0001157_t0014") 2021/05/09 05:22:43 DEBUG : empty space: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1193138cf9738e9d_d20210509_m051741_c001_v0001042_t0041") 2021/05/09 05:22:43 DEBUG : empty space: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1168f63c9006ad39_d20210509_m051740_c001_v0001004_t0027") 2021/05/09 05:22:43 DEBUG : empty space: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc945c_d20210509_m051737_c001_v0001157_t0014") 2021/05/09 05:22:43 DEBUG : enormous: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1004b40643b6c042_d20210509_m051832_c001_v0001026_t0039") 2021/05/09 05:22:43 DEBUG : enormous: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc9aea_d20210509_m051831_c001_v0001157_t0014") 2021/05/09 05:22:43 DEBUG : existing: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f11501b594ecae39c_d20210509_m052226_c001_v0001144_t0025") 2021/05/09 05:22:43 DEBUG : existing: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f110ae15129d114b7_d20210509_m052225_c001_v0001130_t0034") 2021/05/09 05:22:43 DEBUG : existing: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1194c90b4cb51be0_d20210509_m051730_c001_v0001093_t0018") 2021/05/09 05:22:43 DEBUG : existing: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc9373_d20210509_m051729_c001_v0001157_t0014") 2021/05/09 05:22:44 DEBUG : existing: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f117788403b7144f3_d20210509_m051728_c001_v0001090_t0058") 2021/05/09 05:22:44 DEBUG : existing: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc92f9_d20210509_m051724_c001_v0001157_t0014") 2021/05/09 05:22:44 DEBUG : existing: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc92e6_d20210509_m051723_c001_v0001157_t0014") 2021/05/09 05:22:44 DEBUG : five: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1195a190443ea1ce_d20210509_m051838_c001_v0001008_t0035") 2021/05/09 05:22:44 DEBUG : five: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f105ce2097ea62240_d20210509_m051836_c001_v0001036_t0015") 2021/05/09 05:22:44 DEBUG : foo: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f107364537c30cfff_d20210509_m051744_c001_v0001029_t0012") 2021/05/09 05:22:44 DEBUG : foo: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc952f_d20210509_m051743_c001_v0001157_t0014") 2021/05/09 05:22:44 DEBUG : foo: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc9518_d20210509_m051742_c001_v0001157_t0014") 2021/05/09 05:22:44 DEBUG : four: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102f4b14505b6b55_d20210509_m051840_c001_v0001150_t0015") 2021/05/09 05:22:44 DEBUG : four: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc9b81_d20210509_m051836_c001_v0001157_t0014") 2021/05/09 05:22:44 DEBUG : four: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc9b6a_d20210509_m051835_c001_v0001157_t0014") 2021/05/09 05:22:44 DEBUG : hello world2: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f118fd7e7192b7356_d20210509_m051657_c001_v0001150_t0045") 2021/05/09 05:22:44 DEBUG : hello world2: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc8fd9_d20210509_m051656_c001_v0001157_t0014") 2021/05/09 05:22:44 DEBUG : ignore-size: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f10481b11897d8241_d20210509_m051722_c001_v0001157_t0031") 2021/05/09 05:22:44 DEBUG : ignore-size: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc92b4_d20210509_m051721_c001_v0001157_t0014") 2021/05/09 05:22:44 DEBUG : nested/sub dir/file: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f112c3e5a0d4e96d0_d20210509_m051903_c001_v0001042_t0014") 2021/05/09 05:22:44 DEBUG : nested/sub dir/file: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f110ae15129d111b0_d20210509_m051901_c001_v0001130_t0034") 2021/05/09 05:22:44 DEBUG : nested/sub dir/file: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1194c90b4cb51ceb_d20210509_m051859_c001_v0001093_t0007") 2021/05/09 05:22:44 DEBUG : nested/sub dir/file: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc9e37_d20210509_m051858_c001_v0001157_t0014") 2021/05/09 05:22:44 DEBUG : one: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f100f798ec70a9231_d20210509_m051841_c001_v0001136_t0053") 2021/05/09 05:22:44 DEBUG : one: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc9b33_d20210509_m051833_c001_v0001157_t0014") 2021/05/09 05:22:44 DEBUG : potato: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f11501b594ecada96_d20210509_m051854_c001_v0001144_t0058") 2021/05/09 05:22:44 DEBUG : potato: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f110ae15129d11195_d20210509_m051852_c001_v0001130_t0034") 2021/05/09 05:22:44 DEBUG : potato: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f118f14e14f886070_d20210509_m051850_c001_v0001144_t0038") 2021/05/09 05:22:44 DEBUG : potato: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f105ce2097ea62254_d20210509_m051846_c001_v0001036_t0015") 2021/05/09 05:22:44 DEBUG : potato: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f11353bfd6d085c61_d20210509_m051844_c001_v0001113_t0045") 2021/05/09 05:22:45 DEBUG : potato: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f110ae15129d1116d_d20210509_m051842_c001_v0001130_t0034") 2021/05/09 05:22:45 DEBUG : potato: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f113bd924de7f11dd_d20210509_m051826_c001_v0001130_t0007") 2021/05/09 05:22:45 DEBUG : potato: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc9a3c_d20210509_m051824_c001_v0001157_t0014") 2021/05/09 05:22:45 DEBUG : potato: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102678e825343b66_d20210509_m051821_c001_v0001015_t0007") 2021/05/09 05:22:45 DEBUG : potato: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc99cd_d20210509_m051820_c001_v0001157_t0014") 2021/05/09 05:22:45 DEBUG : potato: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1122782719ee1eaa_d20210509_m051817_c001_v0001137_t0003") 2021/05/09 05:22:45 DEBUG : potato: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc991f_d20210509_m051814_c001_v0001157_t0014") 2021/05/09 05:22:45 DEBUG : potato: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1113b62167f39afd_d20210509_m051757_c001_v0001032_t0035") 2021/05/09 05:22:45 DEBUG : potato: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc96f7_d20210509_m051756_c001_v0001157_t0014") 2021/05/09 05:22:45 DEBUG : potato: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f10772de68a399f32_d20210509_m051755_c001_v0001131_t0029") 2021/05/09 05:22:45 DEBUG : potato: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc9698_d20210509_m051754_c001_v0001157_t0014") 2021/05/09 05:22:45 DEBUG : potato: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1060c28b09ea006b_d20210509_m051753_c001_v0001012_t0039") 2021/05/09 05:22:45 DEBUG : potato: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc9657_d20210509_m051752_c001_v0001157_t0014") 2021/05/09 05:22:45 DEBUG : potato: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc9631_d20210509_m051751_c001_v0001157_t0014") 2021/05/09 05:22:45 DEBUG : potato: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1099624fb5e5c45e_d20210509_m051750_c001_v0001157_t0016") 2021/05/09 05:22:45 DEBUG : potato: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc95f0_d20210509_m051749_c001_v0001157_t0014") 2021/05/09 05:22:45 DEBUG : potato: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc95c4_d20210509_m051748_c001_v0001157_t0014") 2021/05/09 05:22:45 DEBUG : potato: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f117ef610e4890f2a_d20210509_m051747_c001_v0001145_t0028") 2021/05/09 05:22:45 DEBUG : potato: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc9590_d20210509_m051746_c001_v0001157_t0014") 2021/05/09 05:22:45 DEBUG : potato2: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f115353ac3c2d0184_d20210509_m051949_c001_v0001037_t0042") 2021/05/09 05:22:45 DEBUG : potato2: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccca441_d20210509_m051948_c001_v0001157_t0014") 2021/05/09 05:22:45 DEBUG : potato2: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1174203d7c23388a_d20210509_m051938_c001_v0001128_t0048") 2021/05/09 05:22:45 DEBUG : potato2: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccca253_d20210509_m051932_c001_v0001157_t0014") 2021/05/09 05:22:45 DEBUG : potato2: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f11184471e1c90b7e_d20210509_m051923_c001_v0001157_t0028") 2021/05/09 05:22:45 DEBUG : potato2: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccca0aa_d20210509_m051917_c001_v0001157_t0014") 2021/05/09 05:22:46 DEBUG : potato2: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f11926322eee6af4c_d20210509_m051911_c001_v0001038_t0052") 2021/05/09 05:22:46 DEBUG : potato2: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc9ef9_d20210509_m051905_c001_v0001157_t0014") 2021/05/09 05:22:46 DEBUG : potato2: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f118f14e14f885fbc_d20210509_m051832_c001_v0001144_t0042") 2021/05/09 05:22:46 DEBUG : potato2: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc9adb_d20210509_m051830_c001_v0001157_t0014") 2021/05/09 05:22:46 DEBUG : potato2: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f113cf7b98fc654dd_d20210509_m051829_c001_v0001153_t0000") 2021/05/09 05:22:46 DEBUG : potato2: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc9a94_d20210509_m051827_c001_v0001157_t0014") 2021/05/09 05:22:46 DEBUG : potato2: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1160921d06075b68_d20210509_m051826_c001_v0001120_t0001") 2021/05/09 05:22:46 DEBUG : potato2: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc9a4b_d20210509_m051824_c001_v0001157_t0014") 2021/05/09 05:22:46 DEBUG : potato2: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1016e189a6b34897_d20210509_m051823_c001_v0001036_t0051") 2021/05/09 05:22:46 DEBUG : potato2: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc9a10_d20210509_m051822_c001_v0001157_t0014") 2021/05/09 05:22:46 DEBUG : potato2: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f116efa5c39bfac9a_d20210509_m051819_c001_v0001144_t0021") 2021/05/09 05:22:46 DEBUG : potato2: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc9976_d20210509_m051817_c001_v0001157_t0014") 2021/05/09 05:22:46 DEBUG : potato2: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f10248c1784b8078c_d20210509_m051759_c001_v0001131_t0056") 2021/05/09 05:22:46 DEBUG : potato2: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc972a_d20210509_m051757_c001_v0001157_t0014") 2021/05/09 05:22:46 DEBUG : potato2: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f103680736d22cb04_d20210509_m051700_c001_v0001137_t0056") 2021/05/09 05:22:46 DEBUG : potato2: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc9033_d20210509_m051659_c001_v0001157_t0014") 2021/05/09 05:22:46 DEBUG : potato2: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f113bd924de7f1091_d20210509_m051658_c001_v0001130_t0020") 2021/05/09 05:22:46 DEBUG : potato2: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc9003_d20210509_m051657_c001_v0001157_t0014") 2021/05/09 05:22:46 DEBUG : potato3: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f101b1ffe9483dbec_d20210509_m051938_c001_v0001021_t0033") 2021/05/09 05:22:46 DEBUG : potato3: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f105ce2097ea622d4_d20210509_m051934_c001_v0001036_t0015") 2021/05/09 05:22:46 DEBUG : potato3: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f10748d54e30a6a23_d20210509_m051923_c001_v0001144_t0020") 2021/05/09 05:22:46 DEBUG : potato3: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f105ce2097ea622b1_d20210509_m051920_c001_v0001036_t0015") 2021/05/09 05:22:46 DEBUG : potato3: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1168b3099758fd7a_d20210509_m051910_c001_v0001157_t0005") 2021/05/09 05:22:46 DEBUG : potato3: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f105ce2097ea62288_d20210509_m051906_c001_v0001036_t0015") 2021/05/09 05:22:47 DEBUG : pre-dest1/1: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f11443c86a31d19f4_d20210509_m052013_c001_v0001137_t0038") 2021/05/09 05:22:47 DEBUG : pre-dest1/1: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f110ae15129d112b9_d20210509_m052006_c001_v0001130_t0034") 2021/05/09 05:22:47 DEBUG : pre-dest2/2: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f107f44966976ff14_d20210509_m052013_c001_v0001157_t0059") 2021/05/09 05:22:47 DEBUG : pre-dest2/2: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f105ce2097ea6231a_d20210509_m052007_c001_v0001036_t0015") 2021/05/09 05:22:47 DEBUG : sizeonly: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f101137aba8a268d6_d20210509_m051720_c001_v0001144_t0033") 2021/05/09 05:22:47 DEBUG : sizeonly: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc927b_d20210509_m051719_c001_v0001157_t0014") 2021/05/09 05:22:47 DEBUG : sub dir/hello world: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1184650ed38aa6b9_d20210509_m051903_c001_v0001038_t0044") 2021/05/09 05:22:47 DEBUG : sub dir/hello world: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f105ce2097ea6227f_d20210509_m051901_c001_v0001036_t0015") 2021/05/09 05:22:47 DEBUG : sub dir/hello world: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102eac066e497172_d20210509_m051900_c001_v0001042_t0045") 2021/05/09 05:22:47 DEBUG : sub dir/hello world: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f105ce2097ea62273_d20210509_m051858_c001_v0001036_t0015") 2021/05/09 05:22:47 DEBUG : sub dir/hello world: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f11243f245495b12a_d20210509_m051715_c001_v0001154_t0016") 2021/05/09 05:22:47 DEBUG : sub dir/hello world: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc91d3_d20210509_m051714_c001_v0001157_t0014") 2021/05/09 05:22:47 DEBUG : sub dir/hello world: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f10283878bc30ddde_d20210509_m051713_c001_v0001146_t0024") 2021/05/09 05:22:47 DEBUG : sub dir/hello world: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc919b_d20210509_m051712_c001_v0001157_t0014") 2021/05/09 05:22:47 DEBUG : sub dir/hello world: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f11166fd0d560a781_d20210509_m051711_c001_v0001120_t0058") 2021/05/09 05:22:47 DEBUG : sub dir/hello world: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc90ef_d20210509_m051707_c001_v0001157_t0014") 2021/05/09 05:22:47 DEBUG : sub dir/hello world: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f118e556587f1d045_d20210509_m051706_c001_v0001134_t0059") 2021/05/09 05:22:47 DEBUG : sub dir/hello world: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc90cf_d20210509_m051705_c001_v0001157_t0014") 2021/05/09 05:22:47 DEBUG : sub dir/hello world: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f101b3c3716a00103_d20210509_m051704_c001_v0001040_t0051") 2021/05/09 05:22:47 DEBUG : sub dir/hello world: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc908e_d20210509_m051703_c001_v0001157_t0014") 2021/05/09 05:22:47 DEBUG : sub dir/hello world: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1118d91f671b265d_d20210509_m051702_c001_v0001145_t0044") 2021/05/09 05:22:47 DEBUG : sub dir/hello world: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc9063_d20210509_m051701_c001_v0001157_t0014") 2021/05/09 05:22:47 DEBUG : sub dir/hello world: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f105480fc13dafdc9_d20210509_m051657_c001_v0001134_t0043") 2021/05/09 05:22:47 DEBUG : sub dir/hello world: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc8fa1_d20210509_m051656_c001_v0001157_t0014") 2021/05/09 05:22:47 DEBUG : sub dir/hello world: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f111ec04e71b85da7_d20210509_m051655_c001_v0001154_t0020") 2021/05/09 05:22:48 DEBUG : sub dir/hello world: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc8f7e_d20210509_m051654_c001_v0001157_t0014") 2021/05/09 05:22:48 DEBUG : sub dir/hello world: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1198aad63e927ea7_d20210509_m051653_c001_v0001040_t0028") 2021/05/09 05:22:48 DEBUG : sub dir/hello world: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc8f50_d20210509_m051652_c001_v0001157_t0014") 2021/05/09 05:22:48 DEBUG : sub dir/hello world: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1092040d9d5f0305_d20210509_m051650_c001_v0001130_t0049") 2021/05/09 05:22:48 DEBUG : sub dir/hello world: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc8f00_d20210509_m051649_c001_v0001157_t0014") 2021/05/09 05:22:48 DEBUG : sub/yam: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f10518d391be0435f_d20210509_m051853_c001_v0001144_t0032") 2021/05/09 05:22:48 DEBUG : sub/yam: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc9d60_d20210509_m051851_c001_v0001157_t0014") 2021/05/09 05:22:48 DEBUG : three: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1099624fb5e5ca08_d20210509_m051841_c001_v0001157_t0054") 2021/05/09 05:22:48 DEBUG : three: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc9bc7_d20210509_m051838_c001_v0001157_t0014") 2021/05/09 05:22:48 DEBUG : three: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc9b56_d20210509_m051834_c001_v0001157_t0014") 2021/05/09 05:22:48 DEBUG : two: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f103fa179af296fc9_d20210509_m051841_c001_v0001134_t0033") 2021/05/09 05:22:48 DEBUG : two: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f110ae15129d11159_d20210509_m051836_c001_v0001130_t0034") 2021/05/09 05:22:48 DEBUG : two: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc9b43_d20210509_m051834_c001_v0001157_t0014") 2021/05/09 05:22:48 DEBUG : yam: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f10748d54e30a68c2_d20210509_m051855_c001_v0001144_t0014") 2021/05/09 05:22:48 DEBUG : yam: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f11607b6fc732cdaf_d20210509_m051853_c001_v0001150_t0054") 2021/05/09 05:22:48 DEBUG : yam: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f103c6863ba17015a_d20210509_m051849_c001_v0001131_t0004") 2021/05/09 05:22:48 DEBUG : yam: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f102a0b9d3ccc9cd1_d20210509_m051847_c001_v0001157_t0014") 2021/05/09 05:22:48 DEBUG : yam: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f1009f5c7ee848862_d20210509_m051844_c001_v0001093_t0028") 2021/05/09 05:22:48 DEBUG : yam: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f105ce2097ea6224d_d20210509_m051842_c001_v0001036_t0015") 2021/05/09 05:22:48 DEBUG : yaml: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f105e6c881d00b9f2_d20210509_m051851_c001_v0001157_t0040") 2021/05/09 05:22:48 DEBUG : yaml: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f11501b594ecada47_d20210509_m051848_c001_v0001144_t0021") 2021/05/09 05:22:48 DEBUG : yaml: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f10959541350bbda5_d20210509_m051845_c001_v0001006_t0020") 2021/05/09 05:22:48 DEBUG : yaml: Deleting (id "4_z8fc27d9380ed4bfb7f980e1b_f114cbb3aee60e452_d20210509_m051843_c001_v0001153_t0023") "./sync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose" - Finished OK in 6m3.478403061s (try 1/5)