"./sync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose" - Starting (try 1/5) 2021/06/01 05:54:49 DEBUG : Creating backend with remote "TestB2:rclone-test-wukowuh7ruwejug9mutatoz2" 2021/06/01 05:54:49 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2021/06/01 05:54:50 DEBUG : Creating backend with remote "/tmp/rclone914568077" === RUN TestPipe --- PASS: TestPipe (0.00s) === RUN TestPipeConcurrent --- PASS: TestPipeConcurrent (0.06s) === 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-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" 2021/06/01 05:54:51 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for checks to finish 2021/06/01 05:54:51 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for transfers to finish 2021/06/01 05:54:51 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) --- PASS: TestCopyWithDryRun (1.32s) === RUN TestCopy run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" 2021/06/01 05:54:51 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for checks to finish 2021/06/01 05:54:51 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for transfers to finish 2021/06/01 05:54:52 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/06/01 05:54:52 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopy (2.22s) === RUN TestCopyMissingDirectory run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" 2021/06/01 05:54:53 DEBUG : Creating backend with remote "/non-existing" 2021/06/01 05:54:53 ERROR : Local file system at /non-existing: error reading source root directory: directory not found 2021/06/01 05:54:53 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for checks to finish 2021/06/01 05:54:53 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for transfers to finish --- PASS: TestCopyMissingDirectory (0.44s) === RUN TestCopyNoTraverse run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" 2021/06/01 05:54:54 DEBUG : Couldn't decode error response: EOF 2021/06/01 05:54:54 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for checks to finish 2021/06/01 05:54:54 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for transfers to finish 2021/06/01 05:54:55 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/06/01 05:54:55 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoTraverse (2.50s) === RUN TestCopyCheckFirst run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" 2021/06/01 05:54:56 INFO : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Running all checks before starting transfers 2021/06/01 05:54:56 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for checks to finish 2021/06/01 05:54:56 INFO : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Checks finished, now starting transfers 2021/06/01 05:54:56 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for transfers to finish 2021/06/01 05:54:57 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/06/01 05:54:57 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyCheckFirst (1.51s) === RUN TestSyncNoTraverse run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" 2021/06/01 05:54:58 ERROR : Ignoring --no-traverse with sync 2021/06/01 05:54:58 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for checks to finish 2021/06/01 05:54:58 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for transfers to finish 2021/06/01 05:54:58 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/06/01 05:54:58 INFO : sub dir/hello world: Copied (new) 2021/06/01 05:54:58 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoTraverse (1.32s) === RUN TestCopyWithDepth run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" 2021/06/01 05:54:59 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for checks to finish 2021/06/01 05:54:59 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for transfers to finish 2021/06/01 05:55:00 DEBUG : hello world2: sha1 = c0bf117f9c93d02c894fe8ffd6e5cecf3545c209 OK 2021/06/01 05:55:00 INFO : hello world2: Copied (new) --- PASS: TestCopyWithDepth (1.21s) === RUN TestCopyWithFilesFrom run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" 2021/06/01 05:55:00 DEBUG : hello world2: Excluded 2021/06/01 05:55:00 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for checks to finish 2021/06/01 05:55:00 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for transfers to finish 2021/06/01 05:55:01 DEBUG : potato2: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/06/01 05:55:01 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFrom (1.61s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" 2021/06/01 05:55:02 DEBUG : Couldn't decode error response: EOF 2021/06/01 05:55:02 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for checks to finish 2021/06/01 05:55:02 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for transfers to finish 2021/06/01 05:55:03 DEBUG : potato2: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/06/01 05:55:03 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFromAndNoTraverse (2.74s) === RUN TestCopyEmptyDirectories run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" 2021/06/01 05:55:05 DEBUG : sub dir2: Making directory 2021/06/01 05:55:05 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for checks to finish 2021/06/01 05:55:05 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for transfers to finish 2021/06/01 05:55:06 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/06/01 05:55:06 INFO : sub dir/hello world: Copied (new) 2021/06/01 05:55:06 DEBUG : sub dir2: Making directory 2021/06/01 05:55:06 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: copied 1 directories fstest.go:251: Filtering empty directory "sub dir2" --- PASS: TestCopyEmptyDirectories (2.26s) === RUN TestMoveEmptyDirectories run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" 2021/06/01 05:55:07 DEBUG : sub dir2: Making directory 2021/06/01 05:55:07 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for checks to finish 2021/06/01 05:55:07 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for transfers to finish 2021/06/01 05:55:08 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/06/01 05:55:08 INFO : sub dir/hello world: Copied (new) 2021/06/01 05:55:08 INFO : sub dir/hello world: Deleted 2021/06/01 05:55:08 DEBUG : sub dir2: Making directory 2021/06/01 05:55:08 DEBUG : sub dir: Making directory 2021/06/01 05:55:08 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: copied 2 directories fstest.go:251: Filtering empty directory "sub dir2" --- PASS: TestMoveEmptyDirectories (1.77s) === RUN TestSyncEmptyDirectories run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" 2021/06/01 05:55:09 DEBUG : sub dir2: Making directory 2021/06/01 05:55:09 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for checks to finish 2021/06/01 05:55:09 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for transfers to finish 2021/06/01 05:55:09 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/06/01 05:55:09 INFO : sub dir/hello world: Copied (new) 2021/06/01 05:55:09 DEBUG : sub dir2: Making directory 2021/06/01 05:55:09 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: copied 1 directories 2021/06/01 05:55:09 DEBUG : Waiting for deletions to finish fstest.go:251: Filtering empty directory "sub dir2" --- PASS: TestSyncEmptyDirectories (1.47s) === RUN TestServerSideCopy run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" 2021/06/01 05:55:11 DEBUG : Creating backend with remote "TestB2:rclone-test-paviquw1geratuh7bovijec5" sync_test.go:281: Server side copy (if possible) B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2 -> B2 bucket rclone-test-paviquw1geratuh7bovijec5 2021/06/01 05:55:12 DEBUG : B2 bucket rclone-test-paviquw1geratuh7bovijec5: Waiting for checks to finish 2021/06/01 05:55:12 DEBUG : B2 bucket rclone-test-paviquw1geratuh7bovijec5: Waiting for transfers to finish 2021/06/01 05:55:12 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/06/01 05:55:12 INFO : sub dir/hello world: Copied (server-side copy) 2021/06/01 05:55:13 DEBUG : B2 bucket rclone-test-paviquw1geratuh7bovijec5: Purge remote 2021/06/01 05:55:13 DEBUG : sub dir/hello world: Deleting (id "4_z3fd2ed73203dcb6b7f980e1b_f105db4d44bfc0534_d20210601_m055512_c001_v0001093_t0034") --- PASS: TestServerSideCopy (3.96s) === RUN TestCopyAfterDelete run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" 2021/06/01 05:55:15 ERROR : : error listing: directory not found 2021/06/01 05:55:15 DEBUG : Local file system at /tmp/rclone914568077: Making directory 2021/06/01 05:55:15 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for checks to finish 2021/06/01 05:55:15 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for transfers to finish --- PASS: TestCopyAfterDelete (1.70s) === RUN TestCopyRedownload run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" 2021/06/01 05:55:17 DEBUG : Local file system at /tmp/rclone914568077: Waiting for checks to finish 2021/06/01 05:55:17 DEBUG : Local file system at /tmp/rclone914568077: Waiting for transfers to finish 2021/06/01 05:55:17 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/06/01 05:55:17 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyRedownload (1.96s) === RUN TestSyncBasedOnCheckSum run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" 2021/06/01 05:55:18 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for checks to finish 2021/06/01 05:55:18 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for transfers to finish 2021/06/01 05:55:19 DEBUG : check sum: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2021/06/01 05:55:19 INFO : check sum: Copied (new) 2021/06/01 05:55:19 DEBUG : Waiting for deletions to finish 2021/06/01 05:55:19 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for checks to finish 2021/06/01 05:55:19 DEBUG : check sum: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2021/06/01 05:55:19 DEBUG : check sum: Size and sha1 of src and dst objects identical 2021/06/01 05:55:19 DEBUG : check sum: Unchanged skipping 2021/06/01 05:55:19 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for transfers to finish 2021/06/01 05:55:19 DEBUG : Waiting for deletions to finish 2021/06/01 05:55:19 INFO : There was nothing to transfer --- PASS: TestSyncBasedOnCheckSum (2.23s) === RUN TestSyncSizeOnly run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" 2021/06/01 05:55:20 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for checks to finish 2021/06/01 05:55:20 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for transfers to finish 2021/06/01 05:55:21 DEBUG : sizeonly: sha1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2021/06/01 05:55:21 INFO : sizeonly: Copied (new) 2021/06/01 05:55:21 DEBUG : Waiting for deletions to finish 2021/06/01 05:55:21 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for checks to finish 2021/06/01 05:55:21 DEBUG : sizeonly: Sizes identical 2021/06/01 05:55:21 DEBUG : sizeonly: Unchanged skipping 2021/06/01 05:55:21 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for transfers to finish 2021/06/01 05:55:21 DEBUG : Waiting for deletions to finish 2021/06/01 05:55:21 INFO : There was nothing to transfer --- PASS: TestSyncSizeOnly (1.58s) === RUN TestSyncIgnoreSize run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" 2021/06/01 05:55:22 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for checks to finish 2021/06/01 05:55:22 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for transfers to finish 2021/06/01 05:55:22 DEBUG : ignore-size: sha1 = 4a756ca07e9487f482465a99e8286abc86ba4dc7 OK 2021/06/01 05:55:22 INFO : ignore-size: Copied (new) 2021/06/01 05:55:22 DEBUG : Waiting for deletions to finish 2021/06/01 05:55:22 DEBUG : ignore-size: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/06/01 05:55:22 DEBUG : ignore-size: Unchanged skipping 2021/06/01 05:55:22 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for checks to finish 2021/06/01 05:55:22 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for transfers to finish 2021/06/01 05:55:22 DEBUG : Waiting for deletions to finish 2021/06/01 05:55:22 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreSize (1.59s) === RUN TestSyncIgnoreTimes run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" 2021/06/01 05:55:24 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for checks to finish 2021/06/01 05:55:24 DEBUG : existing: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/06/01 05:55:24 DEBUG : existing: Unchanged skipping 2021/06/01 05:55:24 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for transfers to finish 2021/06/01 05:55:24 DEBUG : Waiting for deletions to finish 2021/06/01 05:55:24 INFO : There was nothing to transfer 2021/06/01 05:55:24 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2021/06/01 05:55:24 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for checks to finish 2021/06/01 05:55:24 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for transfers to finish 2021/06/01 05:55:25 DEBUG : existing: sha1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2021/06/01 05:55:25 INFO : existing: Copied (replaced existing) 2021/06/01 05:55:25 DEBUG : Waiting for deletions to finish --- PASS: TestSyncIgnoreTimes (2.13s) === RUN TestSyncIgnoreExisting run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" 2021/06/01 05:55:25 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for checks to finish 2021/06/01 05:55:25 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for transfers to finish 2021/06/01 05:55:26 DEBUG : existing: sha1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2021/06/01 05:55:26 INFO : existing: Copied (new) 2021/06/01 05:55:26 DEBUG : Waiting for deletions to finish 2021/06/01 05:55:26 DEBUG : existing: Destination exists, skipping 2021/06/01 05:55:26 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for checks to finish 2021/06/01 05:55:26 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for transfers to finish 2021/06/01 05:55:26 DEBUG : Waiting for deletions to finish 2021/06/01 05:55:26 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreExisting (1.77s) === RUN TestSyncIgnoreErrors run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" 2021/06/01 05:55:28 DEBUG : d: Making directory fstest.go:251: Filtering empty directory "d" 2021/06/01 05:55:28 DEBUG : c/non empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/06/01 05:55:28 DEBUG : c/non empty space: Unchanged skipping 2021/06/01 05:55:28 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for checks to finish 2021/06/01 05:55:28 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for transfers to finish 2021/06/01 05:55:29 DEBUG : a/potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/06/01 05:55:29 INFO : a/potato2: Copied (new) 2021/06/01 05:55:29 DEBUG : Waiting for deletions to finish 2021/06/01 05:55:29 INFO : b/potato: Deleted --- PASS: TestSyncIgnoreErrors (3.80s) === RUN TestSyncAfterChangingModtimeOnly run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" 2021/06/01 05:55:32 DEBUG : empty space: Modification times differ by -95456h54m52.624456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499 +0000 UTC 2021/06/01 05:55:32 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for checks to finish 2021/06/01 05:55:32 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2021/06/01 05:55:32 NOTICE: empty space: Skipped update modification time as --dry-run is set (size 1) 2021/06/01 05:55:32 DEBUG : empty space: Unchanged skipping 2021/06/01 05:55:32 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for transfers to finish 2021/06/01 05:55:32 DEBUG : Waiting for deletions to finish 2021/06/01 05:55:32 INFO : There was nothing to transfer 2021/06/01 05:55:32 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for checks to finish 2021/06/01 05:55:32 DEBUG : empty space: Modification times differ by -95456h54m52.624456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499 +0000 UTC 2021/06/01 05:55:32 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2021/06/01 05:55:33 INFO : empty space: Updated modification time in destination 2021/06/01 05:55:33 DEBUG : empty space: Unchanged skipping 2021/06/01 05:55:33 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for transfers to finish 2021/06/01 05:55:33 DEBUG : Waiting for deletions to finish 2021/06/01 05:55:33 INFO : There was nothing to transfer --- PASS: TestSyncAfterChangingModtimeOnly (3.00s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" 2021/06/01 05:55:35 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for checks to finish 2021/06/01 05:55:35 DEBUG : empty space: Modification times differ by -95456h54m52.624456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499 +0000 UTC 2021/06/01 05:55:35 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2021/06/01 05:55:35 DEBUG : empty space: Unchanged skipping 2021/06/01 05:55:35 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for transfers to finish 2021/06/01 05:55:35 DEBUG : Waiting for deletions to finish 2021/06/01 05:55:35 INFO : There was nothing to transfer --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (1.36s) === RUN TestSyncDoesntUpdateModtime run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" 2021/06/01 05:55:36 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for checks to finish 2021/06/01 05:55:36 DEBUG : foo: Modification times differ by -95456h54m52.624456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499 +0000 UTC 2021/06/01 05:55:36 DEBUG : foo: sha1 = 0beec7b5ea3f0fdbc95d0dd47f3c5bc275da8a33 (Local file system at /tmp/rclone914568077) 2021/06/01 05:55:36 DEBUG : foo: sha1 = 62cdb7020ff920e5aa642c3d4066950dd1f01f4d (B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2) 2021/06/01 05:55:36 DEBUG : foo: sha1 differ 2021/06/01 05:55:36 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for transfers to finish 2021/06/01 05:55:37 DEBUG : foo: sha1 = 0beec7b5ea3f0fdbc95d0dd47f3c5bc275da8a33 OK 2021/06/01 05:55:37 INFO : foo: Copied (replaced existing) 2021/06/01 05:55:37 DEBUG : Waiting for deletions to finish --- PASS: TestSyncDoesntUpdateModtime (1.94s) === RUN TestSyncAfterAddingAFile run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" 2021/06/01 05:55:38 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/06/01 05:55:38 DEBUG : empty space: Unchanged skipping 2021/06/01 05:55:38 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for checks to finish 2021/06/01 05:55:38 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for transfers to finish 2021/06/01 05:55:39 DEBUG : potato: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/06/01 05:55:39 INFO : potato: Copied (new) 2021/06/01 05:55:39 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterAddingAFile (3.22s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" 2021/06/01 05:55:41 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for checks to finish 2021/06/01 05:55:41 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2021/06/01 05:55:41 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for transfers to finish 2021/06/01 05:55:41 DEBUG : potato: sha1 = 0fd321a1023ac4e1e1054fcc0236b9d0755f722f OK 2021/06/01 05:55:41 INFO : potato: Copied (replaced existing) 2021/06/01 05:55:41 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingFilesSizeOnly (4.57s) === RUN TestSyncAfterChangingContentsOnly run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" 2021/06/01 05:55:46 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for checks to finish 2021/06/01 05:55:46 DEBUG : potato: Modification times differ by 119h59m59.876543211s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2011-12-30 12:59:59 +0000 UTC 2021/06/01 05:55:46 DEBUG : potato: sha1 = 1ca5094b3b73f587249eabe179c40a3eec839b1f (Local file system at /tmp/rclone914568077) 2021/06/01 05:55:46 DEBUG : potato: sha1 = 0fd321a1023ac4e1e1054fcc0236b9d0755f722f (B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2) 2021/06/01 05:55:46 DEBUG : potato: sha1 differ 2021/06/01 05:55:46 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for transfers to finish 2021/06/01 05:55:46 DEBUG : potato: sha1 = 1ca5094b3b73f587249eabe179c40a3eec839b1f OK 2021/06/01 05:55:46 INFO : potato: Copied (replaced existing) 2021/06/01 05:55:46 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingContentsOnly (1.86s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" 2021/06/01 05:55:48 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2021/06/01 05:55:48 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/06/01 05:55:48 DEBUG : empty space: Unchanged skipping 2021/06/01 05:55:48 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for checks to finish 2021/06/01 05:55:48 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for transfers to finish 2021/06/01 05:55:48 DEBUG : Waiting for deletions to finish 2021/06/01 05:55:48 NOTICE: potato: Skipped delete as --dry-run is set (size 21) --- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (2.82s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" 2021/06/01 05:55:51 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/06/01 05:55:51 DEBUG : empty space: Unchanged skipping 2021/06/01 05:55:51 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for checks to finish 2021/06/01 05:55:51 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for transfers to finish 2021/06/01 05:55:51 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/06/01 05:55:51 INFO : potato2: Copied (new) 2021/06/01 05:55:51 DEBUG : Waiting for deletions to finish 2021/06/01 05:55:52 INFO : potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (4.92s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" 2021/06/01 05:55:56 DEBUG : d: Making directory 2021/06/01 05:55:56 DEBUG : d/e: Making directory fstest.go:251: Filtering empty directory "d" fstest.go:251: Filtering empty directory "d/e" 2021/06/01 05:55:56 DEBUG : c/non empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/06/01 05:55:56 DEBUG : c/non empty space: Unchanged skipping 2021/06/01 05:55:56 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for checks to finish 2021/06/01 05:55:56 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for transfers to finish 2021/06/01 05:55:57 DEBUG : a/potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/06/01 05:55:57 INFO : a/potato2: Copied (new) 2021/06/01 05:55:57 DEBUG : Waiting for deletions to finish 2021/06/01 05:55:57 INFO : b/potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (3.49s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" 2021/06/01 05:55:59 DEBUG : d: Making directory fstest.go:251: Filtering empty directory "d" 2021/06/01 05:55:59 DEBUG : c/non empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/06/01 05:55:59 DEBUG : c/non empty space: Unchanged skipping 2021/06/01 05:55:59 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for checks to finish 2021/06/01 05:55:59 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for transfers to finish 2021/06/01 05:56:00 DEBUG : a/potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/06/01 05:56:00 INFO : a/potato2: Copied (new) 2021/06/01 05:56:00 ERROR : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: not deleting files as there were IO errors 2021/06/01 05:56:00 ERROR : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: not deleting directories as there were IO errors fstest.go:251: Filtering empty directory "d" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (6.91s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" 2021/06/01 05:56:07 DEBUG : Waiting for deletions to finish 2021/06/01 05:56:07 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/06/01 05:56:07 DEBUG : empty space: Unchanged skipping 2021/06/01 05:56:07 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for checks to finish 2021/06/01 05:56:07 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for transfers to finish 2021/06/01 05:56:08 INFO : potato: Deleted 2021/06/01 05:56:08 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/06/01 05:56:08 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteDuring (5.68s) === RUN TestSyncDeleteBefore run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" 2021/06/01 05:56:12 DEBUG : Waiting for deletions to finish 2021/06/01 05:56:12 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for checks to finish 2021/06/01 05:56:12 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for transfers to finish 2021/06/01 05:56:12 INFO : potato: Deleted 2021/06/01 05:56:12 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/06/01 05:56:12 DEBUG : empty space: Unchanged skipping 2021/06/01 05:56:12 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for checks to finish 2021/06/01 05:56:12 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for transfers to finish 2021/06/01 05:56:13 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/06/01 05:56:13 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteBefore (3.16s) === RUN TestCopyDeleteBefore run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" 2021/06/01 05:56:15 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for checks to finish 2021/06/01 05:56:15 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for transfers to finish 2021/06/01 05:56:15 DEBUG : potato2: sha1 = 810274f0df19dab60a894a562b7a8ff04c072623 OK 2021/06/01 05:56:15 INFO : potato2: Copied (new) --- PASS: TestCopyDeleteBefore (3.47s) === RUN TestSyncWithExclude run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" 2021/06/01 05:56:18 DEBUG : enormous: Excluded 2021/06/01 05:56:18 DEBUG : potato2: Excluded 2021/06/01 05:56:18 DEBUG : potato2: Excluded 2021/06/01 05:56:18 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/06/01 05:56:18 DEBUG : empty space: Unchanged skipping 2021/06/01 05:56:18 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for checks to finish 2021/06/01 05:56:18 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for transfers to finish 2021/06/01 05:56:18 DEBUG : Waiting for deletions to finish 2021/06/01 05:56:18 INFO : There was nothing to transfer 2021/06/01 05:56:19 DEBUG : enormous: Excluded 2021/06/01 05:56:19 DEBUG : potato2: Excluded 2021/06/01 05:56:19 DEBUG : potato2: Excluded 2021/06/01 05:56:19 DEBUG : empty space: Size and modification time the same (differ by 456.789µs, within tolerance 1ms) 2021/06/01 05:56:19 DEBUG : empty space: Unchanged skipping 2021/06/01 05:56:19 DEBUG : Local file system at /tmp/rclone914568077: Waiting for checks to finish 2021/06/01 05:56:19 DEBUG : Local file system at /tmp/rclone914568077: Waiting for transfers to finish 2021/06/01 05:56:19 DEBUG : Waiting for deletions to finish 2021/06/01 05:56:19 INFO : There was nothing to transfer --- PASS: TestSyncWithExclude (2.96s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" 2021/06/01 05:56:22 DEBUG : enormous: Excluded 2021/06/01 05:56:22 DEBUG : potato2: Excluded 2021/06/01 05:56:22 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for checks to finish 2021/06/01 05:56:22 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/06/01 05:56:22 DEBUG : empty space: Unchanged skipping 2021/06/01 05:56:22 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for transfers to finish 2021/06/01 05:56:22 DEBUG : Waiting for deletions to finish 2021/06/01 05:56:22 INFO : potato2: Deleted 2021/06/01 05:56:23 INFO : enormous: Deleted 2021/06/01 05:56:23 INFO : There was nothing to transfer 2021/06/01 05:56:23 DEBUG : empty space: Size and modification time the same (differ by 456.789µs, within tolerance 1ms) 2021/06/01 05:56:23 DEBUG : empty space: Unchanged skipping 2021/06/01 05:56:23 DEBUG : Local file system at /tmp/rclone914568077: Waiting for checks to finish 2021/06/01 05:56:23 DEBUG : Local file system at /tmp/rclone914568077: Waiting for transfers to finish 2021/06/01 05:56:23 DEBUG : Waiting for deletions to finish 2021/06/01 05:56:23 INFO : potato2: Deleted 2021/06/01 05:56:23 INFO : enormous: Deleted 2021/06/01 05:56:23 INFO : There was nothing to transfer --- PASS: TestSyncWithExcludeAndDeleteExcluded (3.29s) === RUN TestSyncWithUpdateOlder run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" 2021/06/01 05:56:28 DEBUG : four: Sizes differ (src 4 vs dst 8) 2021/06/01 05:56:28 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2021/06/01 05:56:28 DEBUG : one: Destination is newer than source, skipping 2021/06/01 05:56:28 DEBUG : three: Sizes identical 2021/06/01 05:56:28 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2021/06/01 05:56:28 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for checks to finish 2021/06/01 05:56:28 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b (Local file system at /tmp/rclone914568077) 2021/06/01 05:56:28 DEBUG : two: sha1 = b10e0da85e90399abfec36c7376b20aa4596461b (B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2) 2021/06/01 05:56:28 DEBUG : two: sha1 differ 2021/06/01 05:56:28 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for transfers to finish 2021/06/01 05:56:28 DEBUG : five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2021/06/01 05:56:28 INFO : five: Copied (new) 2021/06/01 05:56:29 DEBUG : four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2021/06/01 05:56:29 INFO : four: Copied (replaced existing) 2021/06/01 05:56:29 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2021/06/01 05:56:29 INFO : two: Copied (replaced existing) 2021/06/01 05:56:29 DEBUG : Waiting for deletions to finish 2021/06/01 05:56:29 DEBUG : one: Destination is newer than source, skipping 2021/06/01 05:56:29 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for checks to finish 2021/06/01 05:56:29 DEBUG : five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2021/06/01 05:56:29 DEBUG : five: Size and sha1 of src and dst objects identical 2021/06/01 05:56:29 DEBUG : five: Destination mod time is within 1s of source and files identical, skipping 2021/06/01 05:56:29 DEBUG : four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2021/06/01 05:56:29 DEBUG : four: Size and sha1 of src and dst objects identical 2021/06/01 05:56:29 DEBUG : four: Destination mod time is within 1s of source and files identical, skipping 2021/06/01 05:56:29 DEBUG : three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb (Local file system at /tmp/rclone914568077) 2021/06/01 05:56:29 DEBUG : three: sha1 = 03731cad13f4cf7b59703c77529381cdec1632e5 (B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2) 2021/06/01 05:56:29 DEBUG : three: sha1 differ 2021/06/01 05:56:29 DEBUG : three: Destination mod time is within 1s of source but files differ, transferring 2021/06/01 05:56:29 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2021/06/01 05:56:29 DEBUG : two: Size and sha1 of src and dst objects identical 2021/06/01 05:56:29 DEBUG : two: Destination mod time is within 1s of source and files identical, skipping 2021/06/01 05:56:29 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for transfers to finish 2021/06/01 05:56:29 DEBUG : three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/06/01 05:56:29 INFO : three: Copied (replaced existing) 2021/06/01 05:56:29 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithUpdateOlder (16.36s) === 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-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" sync_test.go:1088: Can track renames: true 2021/06/01 05:56:40 INFO : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Making map for --track-renames 2021/06/01 05:56:40 INFO : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Finished making map for --track-renames 2021/06/01 05:56:40 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for checks to finish 2021/06/01 05:56:40 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for renames to finish 2021/06/01 05:56:40 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for transfers to finish 2021/06/01 05:56:41 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2021/06/01 05:56:41 INFO : potato: Copied (new) 2021/06/01 05:56:41 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2021/06/01 05:56:41 INFO : yam: Copied (new) 2021/06/01 05:56:41 DEBUG : Waiting for deletions to finish 2021/06/01 05:56:41 INFO : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Making map for --track-renames 2021/06/01 05:56:41 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/06/01 05:56:41 DEBUG : potato: Unchanged skipping 2021/06/01 05:56:41 INFO : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Finished making map for --track-renames 2021/06/01 05:56:41 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for checks to finish 2021/06/01 05:56:41 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for renames to finish 2021/06/01 05:56:42 DEBUG : Couldn't decode error response: EOF 2021/06/01 05:56:44 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2021/06/01 05:56:44 INFO : yam: Copied (server-side copy) to: yaml 2021/06/01 05:56:44 INFO : yam: Deleted 2021/06/01 05:56:44 INFO : yaml: Renamed from "yam" 2021/06/01 05:56:44 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for transfers to finish 2021/06/01 05:56:44 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenames (5.14s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" sync_test.go:1157: Can track renames: true 2021/06/01 05:56:45 INFO : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Making map for --track-renames 2021/06/01 05:56:45 INFO : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Finished making map for --track-renames 2021/06/01 05:56:45 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for checks to finish 2021/06/01 05:56:45 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for renames to finish 2021/06/01 05:56:45 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for transfers to finish 2021/06/01 05:56:45 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2021/06/01 05:56:45 INFO : potato: Copied (new) 2021/06/01 05:56:46 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2021/06/01 05:56:46 INFO : yam: Copied (new) 2021/06/01 05:56:46 DEBUG : Waiting for deletions to finish 2021/06/01 05:56:46 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/06/01 05:56:46 DEBUG : potato: Unchanged skipping 2021/06/01 05:56:46 INFO : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Making map for --track-renames 2021/06/01 05:56:46 INFO : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Finished making map for --track-renames 2021/06/01 05:56:46 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for checks to finish 2021/06/01 05:56:46 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for renames to finish 2021/06/01 05:56:46 DEBUG : Couldn't decode error response: EOF 2021/06/01 05:56:47 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2021/06/01 05:56:47 INFO : yam: Copied (server-side copy) to: yaml 2021/06/01 05:56:47 INFO : yam: Deleted 2021/06/01 05:56:47 INFO : yaml: Renamed from "yam" 2021/06/01 05:56:47 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for transfers to finish 2021/06/01 05:56:47 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenamesStrategyModtime (3.06s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" sync_test.go:1193: Can track renames: true 2021/06/01 05:56:48 INFO : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Making map for --track-renames 2021/06/01 05:56:48 INFO : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Finished making map for --track-renames 2021/06/01 05:56:48 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for checks to finish 2021/06/01 05:56:48 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for renames to finish 2021/06/01 05:56:48 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for transfers to finish 2021/06/01 05:56:48 DEBUG : sub/yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2021/06/01 05:56:48 INFO : sub/yam: Copied (new) 2021/06/01 05:56:49 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2021/06/01 05:56:49 INFO : potato: Copied (new) 2021/06/01 05:56:49 DEBUG : Waiting for deletions to finish 2021/06/01 05:56:49 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/06/01 05:56:49 DEBUG : potato: Unchanged skipping 2021/06/01 05:56:49 INFO : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Making map for --track-renames 2021/06/01 05:56:49 INFO : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Finished making map for --track-renames 2021/06/01 05:56:49 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for checks to finish 2021/06/01 05:56:49 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for renames to finish 2021/06/01 05:56:49 DEBUG : Couldn't decode error response: EOF 2021/06/01 05:56:50 DEBUG : sub/yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2021/06/01 05:56:50 INFO : sub/yam: Copied (server-side copy) to: yam 2021/06/01 05:56:51 INFO : sub/yam: Deleted 2021/06/01 05:56:51 INFO : yam: Renamed from "sub/yam" 2021/06/01 05:56:51 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for transfers to finish 2021/06/01 05:56:51 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenamesStrategyLeaf (4.76s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" 2021/06/01 05:56:53 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for checks to finish 2021/06/01 05:56:53 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for transfers to finish 2021/06/01 05:56:53 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/06/01 05:56:53 INFO : sub dir/hello world: Copied (new) 2021/06/01 05:56:53 INFO : sub dir/hello world: Deleted 2021/06/01 05:56:54 DEBUG : nested/sub dir/file: sha1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK 2021/06/01 05:56:54 INFO : nested/sub dir/file: Copied (new) 2021/06/01 05:56:54 INFO : nested/sub dir/file: Deleted 2021/06/01 05:56:54 DEBUG : sub dir: Removing directory 2021/06/01 05:56:54 DEBUG : nested/sub dir: Removing directory 2021/06/01 05:56:54 DEBUG : nested: Removing directory 2021/06/01 05:56:54 DEBUG : Local file system at /tmp/rclone914568077: deleted 3 directories --- PASS: TestMoveWithDeleteEmptySrcDirs (2.76s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" 2021/06/01 05:56:56 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for checks to finish 2021/06/01 05:56:56 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for transfers to finish 2021/06/01 05:56:56 DEBUG : nested/sub dir/file: sha1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK 2021/06/01 05:56:56 INFO : nested/sub dir/file: Copied (new) 2021/06/01 05:56:56 INFO : nested/sub dir/file: Deleted 2021/06/01 05:56:56 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/06/01 05:56:56 INFO : sub dir/hello world: Copied (new) 2021/06/01 05:56:56 INFO : sub dir/hello world: Deleted --- PASS: TestMoveWithoutDeleteEmptySrcDirs (1.87s) === RUN TestServerSideMove run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" 2021/06/01 05:56:58 DEBUG : Creating backend with remote "TestB2:rclone-test-xupegis6kawifin7rufimap1" 2021/06/01 05:56:58 DEBUG : Config file has changed externaly - reloading sync_test.go:1245: Server side move (if possible) B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2 -> B2 bucket rclone-test-xupegis6kawifin7rufimap1 2021/06/01 05:57:02 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms) 2021/06/01 05:57:02 DEBUG : empty space: Unchanged skipping 2021/06/01 05:57:02 DEBUG : B2 bucket rclone-test-xupegis6kawifin7rufimap1: Waiting for checks to finish 2021/06/01 05:57:02 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2021/06/01 05:57:03 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/06/01 05:57:03 INFO : potato2: Copied (server-side copy) 2021/06/01 05:57:03 INFO : empty space: Deleted 2021/06/01 05:57:03 DEBUG : B2 bucket rclone-test-xupegis6kawifin7rufimap1: Waiting for transfers to finish 2021/06/01 05:57:03 DEBUG : potato3: sha1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2021/06/01 05:57:03 INFO : potato3: Copied (server-side copy) 2021/06/01 05:57:04 INFO : potato3: Deleted 2021/06/01 05:57:04 INFO : potato2: Deleted 2021/06/01 05:57:04 DEBUG : Creating backend with remote "TestB2:rclone-test-zokekol0moyuyuh3fiwakef7" 2021/06/01 05:57:05 DEBUG : B2 bucket rclone-test-zokekol0moyuyuh3fiwakef7: Waiting for checks to finish 2021/06/01 05:57:05 DEBUG : B2 bucket rclone-test-zokekol0moyuyuh3fiwakef7: Waiting for transfers to finish 2021/06/01 05:57:06 DEBUG : potato3: sha1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2021/06/01 05:57:06 INFO : potato3: Copied (server-side copy) 2021/06/01 05:57:06 INFO : potato3: Deleted 2021/06/01 05:57:06 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2021/06/01 05:57:06 INFO : empty space: Copied (server-side copy) 2021/06/01 05:57:07 INFO : empty space: Deleted 2021/06/01 05:57:12 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/06/01 05:57:12 INFO : potato2: Copied (server-side copy) 2021/06/01 05:57:12 INFO : potato2: Deleted 2021/06/01 05:57:12 DEBUG : B2 bucket rclone-test-zokekol0moyuyuh3fiwakef7: Purge remote 2021/06/01 05:57:12 DEBUG : empty space: Deleting (id "4_zcf82ad13205dcb6b7f980e1b_f11860d681fd1923a_d20210601_m055706_c001_v0001042_t0013") 2021/06/01 05:57:12 DEBUG : potato2: Deleting (id "4_zcf82ad13205dcb6b7f980e1b_f113194f09cb929b3_d20210601_m055706_c001_v0001042_t0028") 2021/06/01 05:57:12 DEBUG : potato3: Deleting (id "4_zcf82ad13205dcb6b7f980e1b_f1021c44fb9ecbcf2_d20210601_m055706_c001_v0001150_t0031") 2021/06/01 05:57:13 DEBUG : B2 bucket rclone-test-xupegis6kawifin7rufimap1: Purge remote 2021/06/01 05:57:13 DEBUG : empty space: Deleting (id "4_zcf728db3205dcb6b7f980e1b_f10735253579a5747_d20210601_m055706_c001_v0001092_t0005") 2021/06/01 05:57:13 DEBUG : empty space: Deleting (id "4_zcf728db3205dcb6b7f980e1b_f1193aa08b7fb8b25_d20210601_m055700_c001_v0001120_t0037") 2021/06/01 05:57:13 DEBUG : potato2: Deleting (id "4_zcf728db3205dcb6b7f980e1b_f1067845c30eb2189_d20210601_m055712_c001_v0001150_t0055") 2021/06/01 05:57:13 DEBUG : potato2: Deleting (id "4_zcf728db3205dcb6b7f980e1b_f102c0e53d33ae287_d20210601_m055703_c001_v0001120_t0007") 2021/06/01 05:57:13 DEBUG : potato3: Deleting (id "4_zcf728db3205dcb6b7f980e1b_f1021c44fb9ecbcf3_d20210601_m055706_c001_v0001150_t0049") 2021/06/01 05:57:13 DEBUG : potato3: Deleting (id "4_zcf728db3205dcb6b7f980e1b_f10032c3102ce10e2_d20210601_m055703_c001_v0001150_t0024") 2021/06/01 05:57:13 DEBUG : potato3: Deleting (id "4_zcf728db3205dcb6b7f980e1b_f1193aa08b7fb8b29_d20210601_m055701_c001_v0001120_t0037") --- PASS: TestServerSideMove (16.69s) === RUN TestServerSideMoveWithFilter run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" 2021/06/01 05:57:14 DEBUG : Creating backend with remote "TestB2:rclone-test-puqojaq7camumuh5jaxurav1" sync_test.go:1245: Server side move (if possible) B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2 -> B2 bucket rclone-test-puqojaq7camumuh5jaxurav1 2021/06/01 05:57:32 DEBUG : empty space: Excluded 2021/06/01 05:57:32 DEBUG : empty space: Excluded 2021/06/01 05:57:32 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2021/06/01 05:57:32 DEBUG : B2 bucket rclone-test-puqojaq7camumuh5jaxurav1: Waiting for checks to finish 2021/06/01 05:57:32 DEBUG : B2 bucket rclone-test-puqojaq7camumuh5jaxurav1: Waiting for transfers to finish 2021/06/01 05:57:33 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/06/01 05:57:33 INFO : potato2: Copied (server-side copy) 2021/06/01 05:57:33 INFO : potato2: Deleted 2021/06/01 05:57:33 DEBUG : potato3: sha1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2021/06/01 05:57:33 INFO : potato3: Copied (server-side copy) 2021/06/01 05:57:34 INFO : potato3: Deleted 2021/06/01 05:57:34 DEBUG : Creating backend with remote "TestB2:rclone-test-sufuzep6puxetit6vawivuh9" 2021/06/01 05:57:35 DEBUG : empty space: Excluded 2021/06/01 05:57:35 DEBUG : B2 bucket rclone-test-sufuzep6puxetit6vawivuh9: Waiting for checks to finish 2021/06/01 05:57:35 DEBUG : B2 bucket rclone-test-sufuzep6puxetit6vawivuh9: Waiting for transfers to finish 2021/06/01 05:57:36 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/06/01 05:57:36 INFO : potato2: Copied (server-side copy) 2021/06/01 05:57:36 DEBUG : potato3: sha1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2021/06/01 05:57:36 INFO : potato3: Copied (server-side copy) 2021/06/01 05:57:36 INFO : potato2: Deleted 2021/06/01 05:57:39 INFO : potato3: Deleted 2021/06/01 05:57:39 DEBUG : B2 bucket rclone-test-sufuzep6puxetit6vawivuh9: Purge remote 2021/06/01 05:57:39 DEBUG : potato2: Deleting (id "4_zdf721d63206dcb6b7f980e1b_f1170070dd7e6496f_d20210601_m055735_c001_v0001131_t0030") 2021/06/01 05:57:39 DEBUG : potato3: Deleting (id "4_zdf721d63206dcb6b7f980e1b_f110c8fb8a1c5710f_d20210601_m055735_c001_v0001153_t0008") 2021/06/01 05:57:40 DEBUG : B2 bucket rclone-test-puqojaq7camumuh5jaxurav1: Purge remote 2021/06/01 05:57:40 DEBUG : empty space: Deleting (id "4_z3ff2dd13205dcb6b7f980e1b_f116abff6f00e1951_d20210601_m055718_c001_v0001120_t0020") 2021/06/01 05:57:40 DEBUG : potato2: Deleting (id "4_z3ff2dd13205dcb6b7f980e1b_f11760d7266fe732d_d20210601_m055736_c001_v0001157_t0013") 2021/06/01 05:57:40 DEBUG : potato2: Deleting (id "4_z3ff2dd13205dcb6b7f980e1b_f11555b3cb10a5ee1_d20210601_m055732_c001_v0001128_t0052") 2021/06/01 05:57:40 DEBUG : potato3: Deleting (id "4_z3ff2dd13205dcb6b7f980e1b_f11748631e03c7d90_d20210601_m055737_c001_v0001037_t0057") 2021/06/01 05:57:40 DEBUG : potato3: Deleting (id "4_z3ff2dd13205dcb6b7f980e1b_f100f4b2967289ee7_d20210601_m055732_c001_v0001145_t0034") 2021/06/01 05:57:40 DEBUG : potato3: Deleting (id "4_z3ff2dd13205dcb6b7f980e1b_f116abff6f00e195d_d20210601_m055725_c001_v0001120_t0020") --- PASS: TestServerSideMoveWithFilter (26.96s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" 2021/06/01 05:57:41 DEBUG : Creating backend with remote "TestB2:rclone-test-quyured8genuqat6zitocuv6" 2021/06/01 05:57:45 DEBUG : tomatoDir: Making directory sync_test.go:1245: Server side move (if possible) B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2 -> B2 bucket rclone-test-quyured8genuqat6zitocuv6 2021/06/01 05:57:48 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms) 2021/06/01 05:57:48 DEBUG : empty space: Unchanged skipping 2021/06/01 05:57:48 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2021/06/01 05:57:48 DEBUG : B2 bucket rclone-test-quyured8genuqat6zitocuv6: Waiting for checks to finish 2021/06/01 05:57:48 INFO : empty space: Deleted 2021/06/01 05:57:48 DEBUG : B2 bucket rclone-test-quyured8genuqat6zitocuv6: Waiting for transfers to finish 2021/06/01 05:57:48 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/06/01 05:57:48 INFO : potato2: Copied (server-side copy) 2021/06/01 05:57:48 DEBUG : potato3: sha1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2021/06/01 05:57:48 INFO : potato3: Copied (server-side copy) 2021/06/01 05:57:48 INFO : potato2: Deleted 2021/06/01 05:57:49 INFO : potato3: Deleted 2021/06/01 05:57:49 DEBUG : Creating backend with remote "TestB2:rclone-test-dalotas0tifehom6qitecul6" 2021/06/01 05:57:50 DEBUG : tomatoDir: Making directory 2021/06/01 05:57:50 DEBUG : B2 bucket rclone-test-dalotas0tifehom6qitecul6: Waiting for checks to finish 2021/06/01 05:57:50 DEBUG : B2 bucket rclone-test-dalotas0tifehom6qitecul6: Waiting for transfers to finish 2021/06/01 05:57:51 DEBUG : potato3: sha1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2021/06/01 05:57:51 INFO : potato3: Copied (server-side copy) 2021/06/01 05:57:51 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2021/06/01 05:57:51 INFO : empty space: Copied (server-side copy) 2021/06/01 05:57:51 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/06/01 05:57:51 INFO : potato2: Copied (server-side copy) 2021/06/01 05:57:51 INFO : potato3: Deleted 2021/06/01 05:57:51 INFO : potato2: Deleted 2021/06/01 05:57:51 INFO : empty space: Deleted 2021/06/01 05:57:52 DEBUG : B2 bucket rclone-test-dalotas0tifehom6qitecul6: Purge remote 2021/06/01 05:57:52 DEBUG : empty space: Deleting (id "4_z7f825d13206dcb6b7f980e1b_f1041c78260180e60_d20210601_m055750_c001_v0001031_t0030") 2021/06/01 05:57:52 DEBUG : potato2: Deleting (id "4_z7f825d13206dcb6b7f980e1b_f1029958406065c09_d20210601_m055750_c001_v0001036_t0011") 2021/06/01 05:57:52 DEBUG : potato3: Deleting (id "4_z7f825d13206dcb6b7f980e1b_f1165d8e6998390df_d20210601_m055750_c001_v0001136_t0053") 2021/06/01 05:57:53 DEBUG : B2 bucket rclone-test-quyured8genuqat6zitocuv6: Purge remote 2021/06/01 05:57:53 DEBUG : empty space: Deleting (id "4_z0f223dd3206dcb6b7f980e1b_f101801b514881863_d20210601_m055751_c001_v0001157_t0054") 2021/06/01 05:57:53 DEBUG : empty space: Deleting (id "4_z0f223dd3206dcb6b7f980e1b_f116d1b50ec91835a_d20210601_m055746_c001_v0001044_t0014") 2021/06/01 05:57:53 DEBUG : potato2: Deleting (id "4_z0f223dd3206dcb6b7f980e1b_f1171ac7fba492c4a_d20210601_m055751_c001_v0001134_t0031") 2021/06/01 05:57:53 DEBUG : potato2: Deleting (id "4_z0f223dd3206dcb6b7f980e1b_f1052935dc5f0aada_d20210601_m055748_c001_v0001022_t0014") 2021/06/01 05:57:53 DEBUG : potato3: Deleting (id "4_z0f223dd3206dcb6b7f980e1b_f111f657999fc99d5_d20210601_m055751_c001_v0001153_t0020") 2021/06/01 05:57:53 DEBUG : potato3: Deleting (id "4_z0f223dd3206dcb6b7f980e1b_f111f657999fc99c0_d20210601_m055748_c001_v0001153_t0007") 2021/06/01 05:57:53 DEBUG : potato3: Deleting (id "4_z0f223dd3206dcb6b7f980e1b_f116d1b50ec91835c_d20210601_m055746_c001_v0001044_t0014") --- PASS: TestServerSideMoveDeleteEmptySourceDirs (12.44s) === RUN TestServerSideMoveOverlap run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" 2021/06/01 05:57:54 DEBUG : Creating backend with remote "TestB2:rclone-test-wukowuh7ruwejug9mutatoz2/rclone-move-test" 2021/06/01 05:57:54 DEBUG : Couldn't decode error response: EOF --- PASS: TestServerSideMoveOverlap (2.93s) === RUN TestSyncOverlap run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" 2021/06/01 05:57:57 DEBUG : Creating backend with remote "TestB2:rclone-test-wukowuh7ruwejug9mutatoz2/rclone-sync-test" 2021/06/01 05:57:57 DEBUG : Couldn't decode error response: EOF --- PASS: TestSyncOverlap (0.63s) === RUN TestSyncCompareDest run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" 2021/06/01 05:57:57 DEBUG : Creating backend with remote "TestB2:rclone-test-wukowuh7ruwejug9mutatoz2/dst" 2021/06/01 05:57:57 DEBUG : Couldn't decode error response: EOF 2021/06/01 05:57:58 DEBUG : Creating backend with remote "TestB2:rclone-test-wukowuh7ruwejug9mutatoz2/CompareDest" 2021/06/01 05:57:58 DEBUG : Couldn't decode error response: EOF 2021/06/01 05:57:58 DEBUG : Couldn't decode error response: EOF 2021/06/01 05:57:58 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2 path dst: Waiting for checks to finish 2021/06/01 05:57:58 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2 path dst: Waiting for transfers to finish 2021/06/01 05:57:59 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/06/01 05:57:59 INFO : one: Copied (new) 2021/06/01 05:57:59 DEBUG : Waiting for deletions to finish 2021/06/01 05:57:59 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2 path dst: Waiting for checks to finish 2021/06/01 05:58:00 DEBUG : Couldn't decode error response: EOF 2021/06/01 05:58:00 DEBUG : one: Sizes differ (src 5 vs dst 3) 2021/06/01 05:58:00 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2 path dst: Waiting for transfers to finish 2021/06/01 05:58:00 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2021/06/01 05:58:00 INFO : one: Copied (replaced existing) 2021/06/01 05:58:00 DEBUG : Waiting for deletions to finish 2021/06/01 05:58:02 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2 path dst: Waiting for checks to finish 2021/06/01 05:58:02 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/06/01 05:58:02 DEBUG : one: Destination found in --compare-dest, skipping 2021/06/01 05:58:02 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2 path dst: Waiting for transfers to finish 2021/06/01 05:58:02 DEBUG : Waiting for deletions to finish 2021/06/01 05:58:02 INFO : There was nothing to transfer 2021/06/01 05:58:05 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/06/01 05:58:05 DEBUG : two: Destination found in --compare-dest, skipping 2021/06/01 05:58:05 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2 path dst: Waiting for checks to finish 2021/06/01 05:58:05 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/06/01 05:58:05 DEBUG : one: Destination found in --compare-dest, skipping 2021/06/01 05:58:05 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2 path dst: Waiting for transfers to finish 2021/06/01 05:58:05 DEBUG : Waiting for deletions to finish 2021/06/01 05:58:05 INFO : There was nothing to transfer 2021/06/01 05:58:05 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/06/01 05:58:05 DEBUG : two: Destination found in --compare-dest, skipping 2021/06/01 05:58:05 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2 path dst: Waiting for checks to finish 2021/06/01 05:58:05 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/06/01 05:58:05 DEBUG : one: Destination found in --compare-dest, skipping 2021/06/01 05:58:05 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2 path dst: Waiting for transfers to finish 2021/06/01 05:58:05 DEBUG : Waiting for deletions to finish 2021/06/01 05:58:05 INFO : There was nothing to transfer 2021/06/01 05:58:06 DEBUG : two: Sizes differ (src 5 vs dst 3) 2021/06/01 05:58:06 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2 path dst: Waiting for checks to finish 2021/06/01 05:58:06 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/06/01 05:58:06 DEBUG : one: Destination found in --compare-dest, skipping 2021/06/01 05:58:06 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2 path dst: Waiting for transfers to finish 2021/06/01 05:58:07 DEBUG : two: sha1 = a4be8877ad88f3109a6411985a1f359e10e2d930 OK 2021/06/01 05:58:07 INFO : two: Copied (new) 2021/06/01 05:58:07 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCompareDest (12.63s) === RUN TestSyncMultipleCompareDest run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" 2021/06/01 05:58:11 DEBUG : Creating backend with remote "TestB2:rclone-test-wukowuh7ruwejug9mutatoz2/dest" 2021/06/01 05:58:12 DEBUG : Couldn't decode error response: EOF 2021/06/01 05:58:12 DEBUG : Creating backend with remote "TestB2:rclone-test-wukowuh7ruwejug9mutatoz2/pre-dest1" 2021/06/01 05:58:13 DEBUG : Couldn't decode error response: EOF 2021/06/01 05:58:13 DEBUG : Creating backend with remote "TestB2:rclone-test-wukowuh7ruwejug9mutatoz2/pre-dest2" 2021/06/01 05:58:13 DEBUG : Couldn't decode error response: EOF 2021/06/01 05:58:14 DEBUG : 1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/06/01 05:58:14 DEBUG : 1: Destination found in --compare-dest, skipping 2021/06/01 05:58:14 DEBUG : Couldn't decode error response: EOF 2021/06/01 05:58:14 DEBUG : 2: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/06/01 05:58:14 DEBUG : 2: Destination found in --compare-dest, skipping 2021/06/01 05:58:14 DEBUG : Couldn't decode error response: EOF 2021/06/01 05:58:14 DEBUG : Couldn't decode error response: EOF 2021/06/01 05:58:14 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2 path dest: Waiting for checks to finish 2021/06/01 05:58:14 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2 path dest: Waiting for transfers to finish 2021/06/01 05:58:17 DEBUG : 3: sha1 = 77de68daecd823babbb58edb1c8e14d7106e83bb OK 2021/06/01 05:58:17 INFO : 3: Copied (new) 2021/06/01 05:58:17 DEBUG : Waiting for deletions to finish --- PASS: TestSyncMultipleCompareDest (8.29s) === RUN TestSyncCopyDest run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" 2021/06/01 05:58:18 DEBUG : Creating backend with remote "TestB2:rclone-test-wukowuh7ruwejug9mutatoz2/dst" 2021/06/01 05:58:19 DEBUG : Couldn't decode error response: EOF 2021/06/01 05:58:19 DEBUG : Creating backend with remote "TestB2:rclone-test-wukowuh7ruwejug9mutatoz2/CopyDest" 2021/06/01 05:58:19 DEBUG : Couldn't decode error response: EOF 2021/06/01 05:58:20 DEBUG : Couldn't decode error response: EOF 2021/06/01 05:58:20 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2 path dst: Waiting for checks to finish 2021/06/01 05:58:20 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2 path dst: Waiting for transfers to finish 2021/06/01 05:58:21 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/06/01 05:58:21 INFO : one: Copied (new) 2021/06/01 05:58:21 DEBUG : Waiting for deletions to finish 2021/06/01 05:58:21 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2 path dst: Waiting for checks to finish 2021/06/01 05:58:21 DEBUG : Couldn't decode error response: EOF 2021/06/01 05:58:21 DEBUG : one: Sizes differ (src 5 vs dst 3) 2021/06/01 05:58:21 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2 path dst: Waiting for transfers to finish 2021/06/01 05:58:21 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2021/06/01 05:58:21 INFO : one: Copied (replaced existing) 2021/06/01 05:58:21 DEBUG : Waiting for deletions to finish 2021/06/01 05:58:23 DEBUG : Creating backend with remote "TestB2:rclone-test-wukowuh7ruwejug9mutatoz2/BackupDir" 2021/06/01 05:58:24 DEBUG : Couldn't decode error response: EOF 2021/06/01 05:58:24 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2 path dst: Waiting for checks to finish 2021/06/01 05:58:24 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/06/01 05:58:24 DEBUG : one: Sizes differ (src 5 vs dst 3) 2021/06/01 05:58:24 DEBUG : Couldn't decode error response: EOF 2021/06/01 05:58:28 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/06/01 05:58:28 INFO : one: Copied (server-side copy) 2021/06/01 05:58:28 INFO : one: Deleted 2021/06/01 05:58:29 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2021/06/01 05:58:29 INFO : one: Copied (server-side copy) 2021/06/01 05:58:29 DEBUG : one: Destination found in --copy-dest, using server-side copy 2021/06/01 05:58:29 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2 path dst: Waiting for transfers to finish 2021/06/01 05:58:29 DEBUG : Waiting for deletions to finish 2021/06/01 05:58:30 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/06/01 05:58:31 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2021/06/01 05:58:31 INFO : two: Copied (server-side copy) 2021/06/01 05:58:31 DEBUG : two: Destination found in --copy-dest, using server-side copy 2021/06/01 05:58:31 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2 path dst: Waiting for checks to finish 2021/06/01 05:58:31 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/06/01 05:58:31 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/06/01 05:58:31 DEBUG : one: Unchanged skipping 2021/06/01 05:58:31 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2 path dst: Waiting for transfers to finish 2021/06/01 05:58:31 DEBUG : Waiting for deletions to finish 2021/06/01 05:58:31 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2 path dst: Waiting for checks to finish 2021/06/01 05:58:31 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/06/01 05:58:31 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/06/01 05:58:31 DEBUG : one: Unchanged skipping 2021/06/01 05:58:32 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/06/01 05:58:32 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/06/01 05:58:32 DEBUG : two: Unchanged skipping 2021/06/01 05:58:32 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2 path dst: Waiting for transfers to finish 2021/06/01 05:58:32 DEBUG : Waiting for deletions to finish 2021/06/01 05:58:32 INFO : There was nothing to transfer 2021/06/01 05:58:33 DEBUG : three: Sizes differ (src 7 vs dst 5) 2021/06/01 05:58:33 DEBUG : three: Destination not found in --copy-dest 2021/06/01 05:58:33 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2 path dst: Waiting for checks to finish 2021/06/01 05:58:33 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/06/01 05:58:33 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/06/01 05:58:33 DEBUG : one: Unchanged skipping 2021/06/01 05:58:33 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/06/01 05:58:33 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/06/01 05:58:33 DEBUG : two: Unchanged skipping 2021/06/01 05:58:33 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2 path dst: Waiting for transfers to finish 2021/06/01 05:58:34 DEBUG : three: sha1 = 370e613335f3cf2f19b2a2d35a86402f55c659f8 OK 2021/06/01 05:58:34 INFO : three: Copied (new) 2021/06/01 05:58:34 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCopyDest (23.11s) === RUN TestSyncBackupDir run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" 2021/06/01 05:58:44 DEBUG : Creating backend with remote "TestB2:rclone-test-wukowuh7ruwejug9mutatoz2/dst" 2021/06/01 05:58:45 DEBUG : Couldn't decode error response: EOF 2021/06/01 05:58:45 DEBUG : Creating backend with remote "TestB2:rclone-test-wukowuh7ruwejug9mutatoz2/backup" 2021/06/01 05:58:45 DEBUG : Couldn't decode error response: EOF 2021/06/01 05:58:45 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/06/01 05:58:45 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/06/01 05:58:45 DEBUG : two: Unchanged skipping 2021/06/01 05:58:45 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2 path dst: Waiting for checks to finish 2021/06/01 05:58:46 DEBUG : Couldn't decode error response: EOF 2021/06/01 05:58:47 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/06/01 05:58:47 INFO : one: Copied (server-side copy) 2021/06/01 05:58:47 INFO : one: Deleted 2021/06/01 05:58:47 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2 path dst: Waiting for transfers to finish 2021/06/01 05:58:48 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/06/01 05:58:48 INFO : one: Copied (new) 2021/06/01 05:58:48 DEBUG : Waiting for deletions to finish 2021/06/01 05:58:48 DEBUG : Couldn't decode error response: EOF 2021/06/01 05:58:49 DEBUG : three.txt: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/06/01 05:58:49 INFO : three.txt: Copied (server-side copy) 2021/06/01 05:58:49 INFO : three.txt: Deleted 2021/06/01 05:58:49 INFO : three.txt: Moved into backup dir 2021/06/01 05:58:50 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/06/01 05:58:50 DEBUG : two: Unchanged skipping 2021/06/01 05:58:50 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/06/01 05:58:50 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2 path dst: Waiting for checks to finish 2021/06/01 05:58:51 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/06/01 05:58:51 INFO : one: Copied (server-side copy) 2021/06/01 05:58:51 INFO : one: Deleted 2021/06/01 05:58:51 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2 path dst: Waiting for transfers to finish 2021/06/01 05:58:51 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2021/06/01 05:58:51 INFO : one: Copied (new) 2021/06/01 05:58:51 DEBUG : Waiting for deletions to finish 2021/06/01 05:58:52 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2021/06/01 05:58:52 INFO : three.txt: Copied (server-side copy) 2021/06/01 05:58:52 INFO : three.txt: Deleted 2021/06/01 05:58:52 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (13.67s) === RUN TestSyncBackupDirWithSuffix run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" 2021/06/01 05:58:58 DEBUG : Creating backend with remote "TestB2:rclone-test-wukowuh7ruwejug9mutatoz2/dst" 2021/06/01 05:58:59 DEBUG : Couldn't decode error response: EOF 2021/06/01 05:58:59 DEBUG : Creating backend with remote "TestB2:rclone-test-wukowuh7ruwejug9mutatoz2/backup" 2021/06/01 05:58:59 DEBUG : Couldn't decode error response: EOF 2021/06/01 05:59:00 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/06/01 05:59:00 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2 path dst: Waiting for checks to finish 2021/06/01 05:59:00 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/06/01 05:59:00 DEBUG : two: Unchanged skipping 2021/06/01 05:59:00 DEBUG : Couldn't decode error response: EOF 2021/06/01 05:59:02 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/06/01 05:59:02 INFO : one: Copied (server-side copy) to: one.bak 2021/06/01 05:59:02 INFO : one: Deleted 2021/06/01 05:59:02 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2 path dst: Waiting for transfers to finish 2021/06/01 05:59:03 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/06/01 05:59:03 INFO : one: Copied (new) 2021/06/01 05:59:03 DEBUG : Waiting for deletions to finish 2021/06/01 05:59:03 DEBUG : Couldn't decode error response: EOF 2021/06/01 05:59:04 DEBUG : three.txt: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/06/01 05:59:04 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2021/06/01 05:59:05 INFO : three.txt: Deleted 2021/06/01 05:59:05 INFO : three.txt: Moved into backup dir 2021/06/01 05:59:06 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2 path dst: Waiting for checks to finish 2021/06/01 05:59:06 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/06/01 05:59:06 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/06/01 05:59:06 DEBUG : two: Unchanged skipping 2021/06/01 05:59:07 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/06/01 05:59:07 INFO : one: Copied (server-side copy) to: one.bak 2021/06/01 05:59:07 INFO : one: Deleted 2021/06/01 05:59:07 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2 path dst: Waiting for transfers to finish 2021/06/01 05:59:07 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2021/06/01 05:59:07 INFO : one: Copied (new) 2021/06/01 05:59:07 DEBUG : Waiting for deletions to finish 2021/06/01 05:59:08 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2021/06/01 05:59:08 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2021/06/01 05:59:09 INFO : three.txt: Deleted 2021/06/01 05:59:09 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (16.49s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" 2021/06/01 05:59:14 DEBUG : Creating backend with remote "TestB2:rclone-test-wukowuh7ruwejug9mutatoz2/dst" 2021/06/01 05:59:15 DEBUG : Couldn't decode error response: EOF 2021/06/01 05:59:15 DEBUG : Creating backend with remote "TestB2:rclone-test-wukowuh7ruwejug9mutatoz2/backup" 2021/06/01 05:59:15 DEBUG : Couldn't decode error response: EOF 2021/06/01 05:59:16 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/06/01 05:59:16 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/06/01 05:59:16 DEBUG : two: Unchanged skipping 2021/06/01 05:59:16 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2 path dst: Waiting for checks to finish 2021/06/01 05:59:16 DEBUG : Couldn't decode error response: EOF 2021/06/01 05:59:17 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/06/01 05:59:17 INFO : one: Copied (server-side copy) to: one-2019-01-01 2021/06/01 05:59:18 INFO : one: Deleted 2021/06/01 05:59:18 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2 path dst: Waiting for transfers to finish 2021/06/01 05:59:19 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/06/01 05:59:19 INFO : one: Copied (new) 2021/06/01 05:59:19 DEBUG : Waiting for deletions to finish 2021/06/01 05:59:19 DEBUG : Couldn't decode error response: EOF 2021/06/01 05:59:20 DEBUG : three.txt: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/06/01 05:59:20 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2021/06/01 05:59:25 INFO : three.txt: Deleted 2021/06/01 05:59:25 INFO : three.txt: Moved into backup dir 2021/06/01 05:59:26 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/06/01 05:59:26 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/06/01 05:59:26 DEBUG : two: Unchanged skipping 2021/06/01 05:59:26 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2 path dst: Waiting for checks to finish 2021/06/01 05:59:27 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/06/01 05:59:27 INFO : one: Copied (server-side copy) to: one-2019-01-01 2021/06/01 05:59:27 INFO : one: Deleted 2021/06/01 05:59:27 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2 path dst: Waiting for transfers to finish 2021/06/01 05:59:28 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2021/06/01 05:59:28 INFO : one: Copied (new) 2021/06/01 05:59:28 DEBUG : Waiting for deletions to finish 2021/06/01 05:59:30 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2021/06/01 05:59:30 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2021/06/01 05:59:31 INFO : three.txt: Deleted 2021/06/01 05:59:31 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (22.13s) === RUN TestSyncBackupDirSuffixOnly run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" 2021/06/01 05:59:36 DEBUG : Creating backend with remote "TestB2:rclone-test-wukowuh7ruwejug9mutatoz2/dst" 2021/06/01 05:59:37 DEBUG : Couldn't decode error response: EOF 2021/06/01 05:59:37 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/06/01 05:59:37 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/06/01 05:59:37 DEBUG : two: Unchanged skipping 2021/06/01 05:59:37 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2 path dst: Waiting for checks to finish 2021/06/01 05:59:37 DEBUG : Couldn't decode error response: EOF 2021/06/01 05:59:38 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/06/01 05:59:38 INFO : one: Copied (server-side copy) to: one.bak 2021/06/01 05:59:39 INFO : one: Deleted 2021/06/01 05:59:39 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2 path dst: Waiting for transfers to finish 2021/06/01 05:59:40 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/06/01 05:59:40 INFO : one: Copied (new) 2021/06/01 05:59:40 DEBUG : Waiting for deletions to finish 2021/06/01 05:59:40 DEBUG : Couldn't decode error response: EOF 2021/06/01 05:59:40 DEBUG : three.txt: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/06/01 05:59:40 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2021/06/01 05:59:41 INFO : three.txt: Deleted 2021/06/01 05:59:41 INFO : three.txt: Moved into backup dir 2021/06/01 05:59:42 DEBUG : one.bak: Excluded 2021/06/01 05:59:42 DEBUG : three.txt.bak: Excluded 2021/06/01 05:59:42 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/06/01 05:59:42 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/06/01 05:59:42 DEBUG : two: Unchanged skipping 2021/06/01 05:59:42 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2 path dst: Waiting for checks to finish 2021/06/01 05:59:42 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/06/01 05:59:42 INFO : one: Copied (server-side copy) to: one.bak 2021/06/01 05:59:43 INFO : one: Deleted 2021/06/01 05:59:43 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2 path dst: Waiting for transfers to finish 2021/06/01 05:59:43 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2021/06/01 05:59:43 INFO : one: Copied (new) 2021/06/01 05:59:43 DEBUG : Waiting for deletions to finish 2021/06/01 05:59:45 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2021/06/01 05:59:45 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2021/06/01 05:59:45 INFO : three.txt: Deleted 2021/06/01 05:59:45 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirSuffixOnly (28.39s) === RUN TestSyncSuffix run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" 2021/06/01 06:00:04 DEBUG : Creating backend with remote "TestB2:rclone-test-wukowuh7ruwejug9mutatoz2/dst" 2021/06/01 06:00:06 DEBUG : Couldn't decode error response: EOF 2021/06/01 06:00:06 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/06/01 06:00:06 DEBUG : Couldn't decode error response: EOF 2021/06/01 06:00:07 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/06/01 06:00:07 INFO : one: Copied (server-side copy) to: one.bak 2021/06/01 06:00:08 INFO : one: Deleted 2021/06/01 06:00:09 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/06/01 06:00:09 INFO : one: Copied (new) 2021/06/01 06:00:09 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/06/01 06:00:09 DEBUG : two: Unchanged skipping 2021/06/01 06:00:09 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2021/06/01 06:00:09 DEBUG : Couldn't decode error response: EOF 2021/06/01 06:00:12 DEBUG : three.txt: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/06/01 06:00:12 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2021/06/01 06:00:13 INFO : three.txt: Deleted 2021/06/01 06:00:13 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2021/06/01 06:00:13 INFO : three.txt: Copied (new) 2021/06/01 06:00:14 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/06/01 06:00:14 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/06/01 06:00:14 INFO : one: Copied (server-side copy) to: one.bak 2021/06/01 06:00:16 INFO : one: Deleted 2021/06/01 06:00:16 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2021/06/01 06:00:16 INFO : one: Copied (new) 2021/06/01 06:00:16 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/06/01 06:00:16 DEBUG : two: Unchanged skipping 2021/06/01 06:00:16 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2021/06/01 06:00:22 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2021/06/01 06:00:22 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2021/06/01 06:00:22 INFO : three.txt: Deleted 2021/06/01 06:00:23 DEBUG : three.txt: sha1 = 476e1707cbd7f0497a7dc4d44f0b07b8566ffc30 OK 2021/06/01 06:00:23 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (23.68s) === RUN TestSyncSuffixKeepExtension run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" 2021/06/01 06:00:28 DEBUG : Creating backend with remote "TestB2:rclone-test-wukowuh7ruwejug9mutatoz2/dst" 2021/06/01 06:00:29 DEBUG : Couldn't decode error response: EOF 2021/06/01 06:00:29 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/06/01 06:00:29 DEBUG : Couldn't decode error response: EOF 2021/06/01 06:00:33 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/06/01 06:00:33 INFO : one: Copied (server-side copy) to: one-2019-01-01 2021/06/01 06:00:33 INFO : one: Deleted 2021/06/01 06:00:36 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/06/01 06:00:36 INFO : one: Copied (new) 2021/06/01 06:00:36 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/06/01 06:00:36 DEBUG : two: Unchanged skipping 2021/06/01 06:00:36 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2021/06/01 06:00:37 DEBUG : Couldn't decode error response: EOF 2021/06/01 06:00:37 DEBUG : three.txt: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/06/01 06:00:37 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2021/06/01 06:00:38 INFO : three.txt: Deleted 2021/06/01 06:00:40 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2021/06/01 06:00:40 INFO : three.txt: Copied (new) 2021/06/01 06:00:41 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/06/01 06:00:44 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/06/01 06:00:44 INFO : one: Copied (server-side copy) to: one-2019-01-01 2021/06/01 06:00:44 INFO : one: Deleted 2021/06/01 06:00:48 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2021/06/01 06:00:48 INFO : one: Copied (new) 2021/06/01 06:00:48 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/06/01 06:00:48 DEBUG : two: Unchanged skipping 2021/06/01 06:00:49 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2021/06/01 06:00:50 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2021/06/01 06:00:50 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2021/06/01 06:00:50 INFO : three.txt: Deleted 2021/06/01 06:00:57 DEBUG : three.txt: sha1 = 476e1707cbd7f0497a7dc4d44f0b07b8566ffc30 OK 2021/06/01 06:00:57 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (40.94s) === RUN TestSyncUTFNorm run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" 2021/06/01 06:01:07 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2021/06/01 06:01:07 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for checks to finish 2021/06/01 06:01:07 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for transfers to finish 2021/06/01 06:01:09 DEBUG : Testêé: sha1 = a54d88e06612d820bc3be72877c74f257b561b19 OK 2021/06/01 06:01:09 INFO : Testêé: Copied (replaced existing) to: Testêé 2021/06/01 06:01:09 DEBUG : Waiting for deletions to finish --- PASS: TestSyncUTFNorm (3.15s) === RUN TestSyncImmutable run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" 2021/06/01 06:01:10 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for checks to finish 2021/06/01 06:01:10 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for transfers to finish 2021/06/01 06:01:11 DEBUG : existing: sha1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2021/06/01 06:01:11 INFO : existing: Copied (new) 2021/06/01 06:01:11 DEBUG : Waiting for deletions to finish 2021/06/01 06:01:11 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for checks to finish 2021/06/01 06:01:11 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2021/06/01 06:01:11 ERROR : existing: Source and destination exist but do not match: immutable file modified 2021/06/01 06:01:11 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for transfers to finish 2021/06/01 06:01:11 ERROR : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: not deleting files as there were IO errors 2021/06/01 06:01:11 ERROR : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: not deleting directories as there were IO errors --- PASS: TestSyncImmutable (2.15s) === RUN TestSyncIgnoreCase run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" 2021/06/01 06:01:12 DEBUG : existing: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/06/01 06:01:12 DEBUG : existing: Unchanged skipping 2021/06/01 06:01:12 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for checks to finish 2021/06/01 06:01:12 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Waiting for transfers to finish 2021/06/01 06:01:12 DEBUG : Waiting for deletions to finish 2021/06/01 06:01:12 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreCase (1.52s) === RUN TestMaxTransfer === RUN TestMaxTransfer/Hard run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" sync_test.go:1941: This test only runs on local === RUN TestMaxTransfer/Soft run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" sync_test.go:1941: This test only runs on local === RUN TestMaxTransfer/Cautious run.go:176: Remote "B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2", Local "Local file system at /tmp/rclone914568077", Modify Window "1ms" sync_test.go:1941: This test only runs on local --- PASS: TestMaxTransfer (0.89s) --- SKIP: TestMaxTransfer/Hard (0.29s) --- SKIP: TestMaxTransfer/Soft (0.30s) --- SKIP: TestMaxTransfer/Cautious (0.29s) PASS 2021/06/01 06:01:14 DEBUG : B2 bucket rclone-test-wukowuh7ruwejug9mutatoz2: Purge remote 2021/06/01 06:01:15 DEBUG : BackupDir/one: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f10505db8767b9f0c_d20210601_m055834_c001_v0001040_t0013") 2021/06/01 06:01:15 DEBUG : BackupDir/one: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1198a204cacad85b_d20210601_m055825_c001_v0001144_t0046") 2021/06/01 06:01:15 DEBUG : CompareDest/one: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1087b398bbcab6ef_d20210601_m055807_c001_v0001144_t0027") 2021/06/01 06:01:15 DEBUG : CompareDest/one: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f113eb42cfae41082_d20210601_m055802_c001_v0001150_t0000") 2021/06/01 06:01:15 DEBUG : CompareDest/two: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f10913212fc3d9ac5_d20210601_m055808_c001_v0001041_t0035") 2021/06/01 06:01:15 DEBUG : CompareDest/two: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e454_d20210601_m055803_c001_v0001015_t0044") 2021/06/01 06:01:15 DEBUG : CopyDest/one: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f119fad611cd7537c_d20210601_m055835_c001_v0001041_t0005") 2021/06/01 06:01:15 DEBUG : CopyDest/one: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1019218443134b88_d20210601_m055823_c001_v0001150_t0013") 2021/06/01 06:01:15 DEBUG : CopyDest/three: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1170fadd3c851e86_d20210601_m055835_c001_v0001038_t0045") 2021/06/01 06:01:15 DEBUG : CopyDest/three: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e467_d20210601_m055832_c001_v0001015_t0044") 2021/06/01 06:01:15 DEBUG : CopyDest/two: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f110d3557f74173c9_d20210601_m055836_c001_v0001038_t0005") 2021/06/01 06:01:15 DEBUG : CopyDest/two: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f113eb42cfae41147_d20210601_m055829_c001_v0001150_t0000") 2021/06/01 06:01:15 DEBUG : EXISTING: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f113b9550f97a2ed1_d20210601_m060113_c001_v0001134_t0058") 2021/06/01 06:01:15 DEBUG : EXISTING: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f113eb42cfae4153c_d20210601_m060112_c001_v0001150_t0000") 2021/06/01 06:01:15 DEBUG : Testêé: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1068c15d5d5cc6cf_d20210601_m060109_c001_v0001023_t0006") 2021/06/01 06:01:16 DEBUG : Testêé: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e4be_d20210601_m060108_c001_v0001015_t0044") 2021/06/01 06:01:16 DEBUG : Testêé: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f113eb42cfae4151d_d20210601_m060107_c001_v0001150_t0000") 2021/06/01 06:01:16 DEBUG : a/potato2: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f11246695f71acf3e_d20210601_m055600_c001_v0001130_t0053") 2021/06/01 06:01:16 DEBUG : a/potato2: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e3e5_d20210601_m055559_c001_v0001015_t0044") 2021/06/01 06:01:16 DEBUG : a/potato2: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1148168728f4575b_d20210601_m055557_c001_v0001150_t0025") 2021/06/01 06:01:16 DEBUG : a/potato2: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e3df_d20210601_m055556_c001_v0001015_t0044") 2021/06/01 06:01:16 DEBUG : a/potato2: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1078f92bcacb903c_d20210601_m055529_c001_v0001042_t0002") 2021/06/01 06:01:16 DEBUG : a/potato2: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e3a9_d20210601_m055528_c001_v0001015_t0044") 2021/06/01 06:01:16 DEBUG : b/potato: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1085920e354cf517_d20210601_m055600_c001_v0001005_t0029") 2021/06/01 06:01:16 DEBUG : b/potato: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e3e1_d20210601_m055558_c001_v0001015_t0044") 2021/06/01 06:01:16 DEBUG : b/potato: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f10337e4970dffd14_d20210601_m055557_c001_v0001145_t0057") 2021/06/01 06:01:16 DEBUG : b/potato: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e3d9_d20210601_m055555_c001_v0001015_t0044") 2021/06/01 06:01:16 DEBUG : b/potato: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f107d0537e23872fd_d20210601_m055529_c001_v0001150_t0049") 2021/06/01 06:01:16 DEBUG : b/potato: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e3a5_d20210601_m055527_c001_v0001015_t0044") 2021/06/01 06:01:16 DEBUG : backup/one: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1144723a69f2e907_d20210601_m055852_c001_v0001145_t0041") 2021/06/01 06:01:16 DEBUG : backup/one: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f103844ec329f6b2c_d20210601_m055850_c001_v0001134_t0046") 2021/06/01 06:01:16 DEBUG : backup/one: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f110c8fb8a1c571dc_d20210601_m055846_c001_v0001153_t0000") 2021/06/01 06:01:16 DEBUG : backup/one-2019-01-01: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1025af4b9faebc51_d20210601_m055931_c001_v0001157_t0053") 2021/06/01 06:01:16 DEBUG : backup/one-2019-01-01: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f117fd0a3db556ec7_d20210601_m055927_c001_v0001128_t0042") 2021/06/01 06:01:16 DEBUG : backup/one-2019-01-01: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f118cd01a3e908990_d20210601_m055917_c001_v0001006_t0003") 2021/06/01 06:01:16 DEBUG : backup/one.bak: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1025af4b9faebb6f_d20210601_m055909_c001_v0001157_t0014") 2021/06/01 06:01:16 DEBUG : backup/one.bak: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1001e30516df4250_d20210601_m055906_c001_v0001044_t0006") 2021/06/01 06:01:16 DEBUG : backup/one.bak: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f115f7630f9356f10_d20210601_m055901_c001_v0001145_t0037") 2021/06/01 06:01:16 DEBUG : backup/three-2019-01-01.txt: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f100e0952734b8540_d20210601_m055931_c001_v0001153_t0023") 2021/06/01 06:01:16 DEBUG : backup/three-2019-01-01.txt: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f10744d61d453655d_d20210601_m055929_c001_v0001153_t0025") 2021/06/01 06:01:17 DEBUG : backup/three-2019-01-01.txt: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f111d3197d70b579e_d20210601_m055919_c001_v0001032_t0002") 2021/06/01 06:01:17 DEBUG : backup/three.txt: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f101202f2aa086e14_d20210601_m055853_c001_v0001041_t0030") 2021/06/01 06:01:17 DEBUG : backup/three.txt: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f115012e38bf9841b_d20210601_m055852_c001_v0001093_t0014") 2021/06/01 06:01:17 DEBUG : backup/three.txt: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f116dadbe526aa64e_d20210601_m055848_c001_v0001146_t0015") 2021/06/01 06:01:17 DEBUG : backup/three.txt.bak: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f10004d1b98ef05cf_d20210601_m055910_c001_v0001136_t0033") 2021/06/01 06:01:17 DEBUG : backup/three.txt.bak: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1189f9139b285c25_d20210601_m055908_c001_v0001090_t0029") 2021/06/01 06:01:17 DEBUG : backup/three.txt.bak: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f10723922ba7f1347_d20210601_m055903_c001_v0001145_t0025") 2021/06/01 06:01:17 DEBUG : c/non empty space: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1087b398bbcab305_d20210601_m055601_c001_v0001144_t0005") 2021/06/01 06:01:17 DEBUG : c/non empty space: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e3e3_d20210601_m055559_c001_v0001015_t0044") 2021/06/01 06:01:17 DEBUG : c/non empty space: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1136fdc6dbe62b13_d20210601_m055558_c001_v0001040_t0043") 2021/06/01 06:01:17 DEBUG : c/non empty space: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e3db_d20210601_m055555_c001_v0001015_t0044") 2021/06/01 06:01:17 DEBUG : c/non empty space: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f10821e047a1259f8_d20210601_m055530_c001_v0001092_t0023") 2021/06/01 06:01:17 DEBUG : c/non empty space: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e3a7_d20210601_m055528_c001_v0001015_t0044") 2021/06/01 06:01:17 DEBUG : check sum: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108875a8fdf586c2_d20210601_m055519_c001_v0001120_t0042") 2021/06/01 06:01:17 DEBUG : check sum: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e38f_d20210601_m055518_c001_v0001015_t0044") 2021/06/01 06:01:17 DEBUG : dest/3: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1144c36c7ba24d17_d20210601_m055817_c001_v0001134_t0002") 2021/06/01 06:01:17 DEBUG : dest/3: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f103cb96c07669b4f_d20210601_m055816_c001_v0001157_t0004") 2021/06/01 06:01:17 DEBUG : dst/one: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f109cc1a84ae3e6f0_d20210601_m060058_c001_v0001144_t0052") 2021/06/01 06:01:17 DEBUG : dst/one: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f111add39493ce91d_d20210601_m060045_c001_v0001144_t0009") 2021/06/01 06:01:17 DEBUG : dst/one: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f103c584fa8c30eb7_d20210601_m060044_c001_v0001000_t0030") 2021/06/01 06:01:17 DEBUG : dst/one: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f111add39493ce8aa_d20210601_m060034_c001_v0001144_t0009") 2021/06/01 06:01:17 DEBUG : dst/one: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1013abd7bc527cbf_d20210601_m060033_c001_v0001033_t0009") 2021/06/01 06:01:17 DEBUG : dst/one: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f113eb42cfae4146a_d20210601_m060026_c001_v0001150_t0000") 2021/06/01 06:01:18 DEBUG : dst/one: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f11241bc663064988_d20210601_m060023_c001_v0001144_t0059") 2021/06/01 06:01:18 DEBUG : dst/one: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f112cec7b22498b71_d20210601_m060016_c001_v0001157_t0056") 2021/06/01 06:01:18 DEBUG : dst/one: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f10066daa4dd58440_d20210601_m060014_c001_v0001005_t0043") 2021/06/01 06:01:18 DEBUG : dst/one: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f112cec7b22498b3b_d20210601_m060009_c001_v0001157_t0056") 2021/06/01 06:01:18 DEBUG : dst/one: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f10427b6fbef500a7_d20210601_m060007_c001_v0001093_t0002") 2021/06/01 06:01:18 DEBUG : dst/one: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f113eb42cfae413b1_d20210601_m060002_c001_v0001150_t0000") 2021/06/01 06:01:18 DEBUG : dst/one: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f106419cadda1e8c8_d20210601_m055946_c001_v0001144_t0019") 2021/06/01 06:01:18 DEBUG : dst/one: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f103cb96c07669e79_d20210601_m055944_c001_v0001157_t0003") 2021/06/01 06:01:18 DEBUG : dst/one: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f117ab4ea98492ec9_d20210601_m055943_c001_v0001008_t0028") 2021/06/01 06:01:18 DEBUG : dst/one: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f103cb96c07669e5f_d20210601_m055940_c001_v0001157_t0003") 2021/06/01 06:01:18 DEBUG : dst/one: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f11799fb3e912856b_d20210601_m055939_c001_v0001136_t0056") 2021/06/01 06:01:18 DEBUG : dst/one: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1019218443134ec6_d20210601_m055934_c001_v0001150_t0013") 2021/06/01 06:01:18 DEBUG : dst/one: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f11884da0c0b8dcb1_d20210601_m055932_c001_v0001031_t0016") 2021/06/01 06:01:18 DEBUG : dst/one: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1148b6c133697668_d20210601_m055928_c001_v0001091_t0050") 2021/06/01 06:01:18 DEBUG : dst/one: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f113eb42cfae412d0_d20210601_m055927_c001_v0001150_t0022") 2021/06/01 06:01:18 DEBUG : dst/one: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1148b6c133697660_d20210601_m055919_c001_v0001091_t0050") 2021/06/01 06:01:18 DEBUG : dst/one: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f110d3557f7417419_d20210601_m055918_c001_v0001038_t0015") 2021/06/01 06:01:18 DEBUG : dst/one: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e480_d20210601_m055912_c001_v0001015_t0044") 2021/06/01 06:01:18 DEBUG : dst/one: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f109fb1307253c73b_d20210601_m055910_c001_v0001039_t0038") 2021/06/01 06:01:18 DEBUG : dst/one: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f105d7b689e83ed55_d20210601_m055908_c001_v0001128_t0049") 2021/06/01 06:01:18 DEBUG : dst/one: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f114bb7eb40197bbd_d20210601_m055907_c001_v0001137_t0021") 2021/06/01 06:01:19 DEBUG : dst/one: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f105d7b689e83ed43_d20210601_m055903_c001_v0001128_t0049") 2021/06/01 06:01:19 DEBUG : dst/one: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f10740edb45bc6c75_d20210601_m055902_c001_v0001090_t0025") 2021/06/01 06:01:19 DEBUG : dst/one: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f113eb42cfae411e9_d20210601_m055855_c001_v0001150_t0000") 2021/06/01 06:01:19 DEBUG : dst/one: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f10561a59069e5990_d20210601_m055853_c001_v0001154_t0024") 2021/06/01 06:01:19 DEBUG : dst/one: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f102e09cf3f1c02e8_d20210601_m055852_c001_v0001154_t0018") 2021/06/01 06:01:19 DEBUG : dst/one: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f102477a2199d5142_d20210601_m055851_c001_v0001044_t0030") 2021/06/01 06:01:19 DEBUG : dst/one: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f102e09cf3f1c02e1_d20210601_m055848_c001_v0001154_t0018") 2021/06/01 06:01:19 DEBUG : dst/one: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f111943b30ef83d41_d20210601_m055847_c001_v0001157_t0040") 2021/06/01 06:01:19 DEBUG : dst/one: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1019218443134c57_d20210601_m055842_c001_v0001150_t0013") 2021/06/01 06:01:19 DEBUG : dst/one: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f101d64eeb095c515_d20210601_m055837_c001_v0001145_t0016") 2021/06/01 06:01:19 DEBUG : dst/one: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1075316b07f359a6_d20210601_m055829_c001_v0001134_t0021") 2021/06/01 06:01:19 DEBUG : dst/one: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f101f82cc97f2371d_d20210601_m055828_c001_v0001153_t0003") 2021/06/01 06:01:19 DEBUG : dst/one: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e45f_d20210601_m055822_c001_v0001015_t0044") 2021/06/01 06:01:19 DEBUG : dst/one: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f114f090dd4858172_d20210601_m055821_c001_v0001040_t0009") 2021/06/01 06:01:19 DEBUG : dst/one: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f114f090dd4858170_d20210601_m055820_c001_v0001040_t0009") 2021/06/01 06:01:19 DEBUG : dst/one: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f102ef30b3ade897a_d20210601_m055809_c001_v0001005_t0034") 2021/06/01 06:01:19 DEBUG : dst/one: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1019218443134a99_d20210601_m055801_c001_v0001150_t0013") 2021/06/01 06:01:19 DEBUG : dst/one: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f102749a29f232120_d20210601_m055800_c001_v0001130_t0031") 2021/06/01 06:01:19 DEBUG : dst/one: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f102749a29f23211c_d20210601_m055759_c001_v0001130_t0031") 2021/06/01 06:01:19 DEBUG : dst/one-2019-01-01: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f116484c26e9903bd_d20210601_m060104_c001_v0001145_t0046") 2021/06/01 06:01:19 DEBUG : dst/one-2019-01-01: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1198a204cacadc4c_d20210601_m060041_c001_v0001144_t0002") 2021/06/01 06:01:19 DEBUG : dst/one-2019-01-01: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f10121e8bc5f60b3e_d20210601_m060030_c001_v0001144_t0037") 2021/06/01 06:01:19 DEBUG : dst/one.bak: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108145dec1e93b19_d20210601_m060024_c001_v0001153_t0023") 2021/06/01 06:01:19 DEBUG : dst/one.bak: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1039fa44a61cb93f_d20210601_m060014_c001_v0001131_t0008") 2021/06/01 06:01:20 DEBUG : dst/one.bak: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f11246695f71ad059_d20210601_m060007_c001_v0001130_t0006") 2021/06/01 06:01:20 DEBUG : dst/one.bak: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f11899f3461972946_d20210601_m055947_c001_v0001144_t0002") 2021/06/01 06:01:20 DEBUG : dst/one.bak: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f116dadbe526aa72a_d20210601_m055942_c001_v0001146_t0052") 2021/06/01 06:01:20 DEBUG : dst/one.bak: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f112f255fb0801e65_d20210601_m055938_c001_v0001039_t0015") 2021/06/01 06:01:20 DEBUG : dst/three: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1021c44fb9ecbe72_d20210601_m055838_c001_v0001150_t0055") 2021/06/01 06:01:20 DEBUG : dst/three: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f114f090dd485817f_d20210601_m055833_c001_v0001040_t0009") 2021/06/01 06:01:20 DEBUG : dst/three-2019-01-01.txt: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f116765c2390c72d9_d20210601_m060105_c001_v0001038_t0049") 2021/06/01 06:01:20 DEBUG : dst/three-2019-01-01.txt: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f10723922ba7f1574_d20210601_m060049_c001_v0001145_t0042") 2021/06/01 06:01:20 DEBUG : dst/three-2019-01-01.txt: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f109c6bfd15fbf156_d20210601_m060037_c001_v0001145_t0047") 2021/06/01 06:01:20 DEBUG : dst/three.txt: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1089f920d35352c3_d20210601_m060105_c001_v0001040_t0055") 2021/06/01 06:01:20 DEBUG : dst/three.txt: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f111add39493ce945_d20210601_m060051_c001_v0001144_t0009") 2021/06/01 06:01:20 DEBUG : dst/three.txt: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f109efcb52666a424_d20210601_m060050_c001_v0001150_t0002") 2021/06/01 06:01:20 DEBUG : dst/three.txt: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f111add39493ce8e2_d20210601_m060038_c001_v0001144_t0009") 2021/06/01 06:01:20 DEBUG : dst/three.txt: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1041a0f57216717b_d20210601_m060037_c001_v0001036_t0036") 2021/06/01 06:01:20 DEBUG : dst/three.txt: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1019218443135144_d20210601_m060027_c001_v0001150_t0013") 2021/06/01 06:01:20 DEBUG : dst/three.txt: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f102468d92116f1dd_d20210601_m060024_c001_v0001134_t0018") 2021/06/01 06:01:20 DEBUG : dst/three.txt: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f112cec7b22498b95_d20210601_m060023_c001_v0001157_t0056") 2021/06/01 06:01:20 DEBUG : dst/three.txt: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f104c9224786099aa_d20210601_m060022_c001_v0001041_t0044") 2021/06/01 06:01:20 DEBUG : dst/three.txt: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f112cec7b22498b61_d20210601_m060013_c001_v0001157_t0056") 2021/06/01 06:01:20 DEBUG : dst/three.txt: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f109c6bfd15fbf0c4_d20210601_m060012_c001_v0001145_t0026") 2021/06/01 06:01:20 DEBUG : dst/three.txt: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f101921844313503e_d20210601_m060004_c001_v0001150_t0013") 2021/06/01 06:01:20 DEBUG : dst/three.txt: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f10821e047a125b89_d20210601_m055945_c001_v0001092_t0010") 2021/06/01 06:01:20 DEBUG : dst/three.txt: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1019218443134f1f_d20210601_m055942_c001_v0001150_t0013") 2021/06/01 06:01:20 DEBUG : dst/three.txt: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1163567ec2d4ebfd_d20210601_m055940_c001_v0001040_t0030") 2021/06/01 06:01:21 DEBUG : dst/three.txt: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e48a_d20210601_m055935_c001_v0001015_t0044") 2021/06/01 06:01:21 DEBUG : dst/three.txt: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1198a204cacada10_d20210601_m055930_c001_v0001144_t0013") 2021/06/01 06:01:21 DEBUG : dst/three.txt: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e485_d20210601_m055926_c001_v0001015_t0044") 2021/06/01 06:01:21 DEBUG : dst/three.txt: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f105ac373822c6e18_d20210601_m055920_c001_v0001144_t0055") 2021/06/01 06:01:21 DEBUG : dst/three.txt: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f113eb42cfae41263_d20210601_m055914_c001_v0001150_t0000") 2021/06/01 06:01:21 DEBUG : dst/three.txt: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f100dc41eccd595ed_d20210601_m055908_c001_v0001040_t0053") 2021/06/01 06:01:21 DEBUG : dst/three.txt: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f113eb42cfae41229_d20210601_m055906_c001_v0001150_t0000") 2021/06/01 06:01:21 DEBUG : dst/three.txt: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f10048fa46ebe0574_d20210601_m055904_c001_v0001120_t0025") 2021/06/01 06:01:21 DEBUG : dst/three.txt: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1019218443134d18_d20210601_m055858_c001_v0001150_t0013") 2021/06/01 06:01:21 DEBUG : dst/three.txt: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f115000aeda84c548_d20210601_m055852_c001_v0001134_t0027") 2021/06/01 06:01:21 DEBUG : dst/three.txt: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1019218443134cb8_d20210601_m055850_c001_v0001150_t0013") 2021/06/01 06:01:21 DEBUG : dst/three.txt: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f116abff6f00e1a22_d20210601_m055849_c001_v0001120_t0013") 2021/06/01 06:01:21 DEBUG : dst/three.txt: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e46f_d20210601_m055843_c001_v0001015_t0044") 2021/06/01 06:01:21 DEBUG : dst/three.txt.bak: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1144723a69f2eb21_d20210601_m060025_c001_v0001145_t0007") 2021/06/01 06:01:21 DEBUG : dst/three.txt.bak: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f103d7b80cf715cb9_d20210601_m060016_c001_v0001113_t0004") 2021/06/01 06:01:21 DEBUG : dst/three.txt.bak: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f10756cd5c90f618b_d20210601_m060010_c001_v0001144_t0026") 2021/06/01 06:01:21 DEBUG : dst/three.txt.bak: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f115f4f8f5c0bb981_d20210601_m055949_c001_v0001144_t0014") 2021/06/01 06:01:21 DEBUG : dst/three.txt.bak: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1002999fb3217a4c_d20210601_m055944_c001_v0001144_t0053") 2021/06/01 06:01:21 DEBUG : dst/three.txt.bak: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1144203d878abeaf_d20210601_m055940_c001_v0001037_t0018") 2021/06/01 06:01:21 DEBUG : dst/two: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f10032c3102ce1522_d20210601_m060106_c001_v0001150_t0039") 2021/06/01 06:01:21 DEBUG : dst/two: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e49a_d20210601_m060027_c001_v0001015_t0044") 2021/06/01 06:01:21 DEBUG : dst/two: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1039fa44a61cb95b_d20210601_m060025_c001_v0001131_t0024") 2021/06/01 06:01:21 DEBUG : dst/two: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e496_d20210601_m060003_c001_v0001015_t0044") 2021/06/01 06:01:21 DEBUG : dst/two: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1144c36c7ba24df0_d20210601_m060001_c001_v0001134_t0032") 2021/06/01 06:01:22 DEBUG : dst/two: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f113eb42cfae41302_d20210601_m055935_c001_v0001150_t0000") 2021/06/01 06:01:22 DEBUG : dst/two: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1066e5c6854e7765_d20210601_m055933_c001_v0001145_t0028") 2021/06/01 06:01:22 DEBUG : dst/two: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1019218443134dd0_d20210601_m055913_c001_v0001150_t0013") 2021/06/01 06:01:22 DEBUG : dst/two: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f109f1e07953df336_d20210601_m055911_c001_v0001032_t0022") 2021/06/01 06:01:22 DEBUG : dst/two: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e478_d20210601_m055856_c001_v0001015_t0044") 2021/06/01 06:01:22 DEBUG : dst/two: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f101801b514881ba0_d20210601_m055854_c001_v0001157_t0020") 2021/06/01 06:01:22 DEBUG : dst/two: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f113eb42cfae411a7_d20210601_m055842_c001_v0001150_t0000") 2021/06/01 06:01:22 DEBUG : dst/two: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f106419cadda1e681_d20210601_m055838_c001_v0001144_t0024") 2021/06/01 06:01:22 DEBUG : dst/two: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f116dadbe526aa607_d20210601_m055830_c001_v0001146_t0034") 2021/06/01 06:01:22 DEBUG : dst/two: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f104494239ea55d03_d20210601_m055809_c001_v0001006_t0008") 2021/06/01 06:01:22 DEBUG : dst/two: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f102749a29f232146_d20210601_m055806_c001_v0001130_t0031") 2021/06/01 06:01:22 DEBUG : empty space: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f110d418fe5d8810d_d20210601_m055748_c001_v0001005_t0030") 2021/06/01 06:01:22 DEBUG : empty space: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f10192184431349e1_d20210601_m055744_c001_v0001150_t0013") 2021/06/01 06:01:22 DEBUG : empty space: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f10505c87621d406d_d20210601_m055741_c001_v0001157_t0053") 2021/06/01 06:01:22 DEBUG : empty space: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f10192184431348e4_d20210601_m055716_c001_v0001150_t0013") 2021/06/01 06:01:22 DEBUG : empty space: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1071da01216e3fdf_d20210601_m055703_c001_v0001113_t0029") 2021/06/01 06:01:22 DEBUG : empty space: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1019218443134833_d20210601_m055659_c001_v0001150_t0013") 2021/06/01 06:01:22 DEBUG : empty space: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1119ee9798fa27f3_d20210601_m055623_c001_v0001130_t0003") 2021/06/01 06:01:22 DEBUG : empty space: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e408_d20210601_m055621_c001_v0001015_t0044") 2021/06/01 06:01:22 DEBUG : empty space: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f118361b220ee6f91_d20210601_m055619_c001_v0001017_t0008") 2021/06/01 06:01:22 DEBUG : empty space: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e404_d20210601_m055618_c001_v0001015_t0044") 2021/06/01 06:01:22 DEBUG : empty space: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f100562b997f58ee9_d20210601_m055613_c001_v0001131_t0054") 2021/06/01 06:01:23 DEBUG : empty space: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e3f6_d20210601_m055611_c001_v0001015_t0044") 2021/06/01 06:01:23 DEBUG : empty space: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1071da01216e3f52_d20210601_m055608_c001_v0001113_t0023") 2021/06/01 06:01:23 DEBUG : empty space: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e3ee_d20210601_m055606_c001_v0001015_t0044") 2021/06/01 06:01:23 DEBUG : empty space: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f10472a12101bcb84_d20210601_m055553_c001_v0001023_t0016") 2021/06/01 06:01:23 DEBUG : empty space: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e3d3_d20210601_m055550_c001_v0001015_t0044") 2021/06/01 06:01:23 DEBUG : empty space: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f105301bd3856e21a_d20210601_m055548_c001_v0001113_t0044") 2021/06/01 06:01:23 DEBUG : empty space: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e3cb_d20210601_m055547_c001_v0001015_t0044") 2021/06/01 06:01:23 DEBUG : empty space: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f113c9ee813c2c61b_d20210601_m055539_c001_v0001004_t0002") 2021/06/01 06:01:23 DEBUG : empty space: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e3b7_d20210601_m055537_c001_v0001015_t0044") 2021/06/01 06:01:23 DEBUG : empty space: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f10184f953ce8d2ac_d20210601_m055536_c001_v0001137_t0001") 2021/06/01 06:01:23 DEBUG : empty space: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e3b0_d20210601_m055535_c001_v0001015_t0044") 2021/06/01 06:01:23 DEBUG : empty space: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1117766ecba04c78_d20210601_m055534_c001_v0001150_t0011") 2021/06/01 06:01:23 DEBUG : empty space: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f11760d7266fe6db3_d20210601_m055533_c001_v0001157_t0056") 2021/06/01 06:01:23 DEBUG : empty space: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e3ad_d20210601_m055531_c001_v0001015_t0044") 2021/06/01 06:01:23 DEBUG : enormous: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1043248387d9799a_d20210601_m055623_c001_v0001042_t0008") 2021/06/01 06:01:23 DEBUG : enormous: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e40a_d20210601_m055621_c001_v0001015_t0044") 2021/06/01 06:01:23 DEBUG : existing: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f113d95c174a80026_d20210601_m060111_c001_v0001157_t0039") 2021/06/01 06:01:23 DEBUG : existing: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1019218443135303_d20210601_m060110_c001_v0001150_t0013") 2021/06/01 06:01:23 DEBUG : existing: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f110622dbf8eb6d8e_d20210601_m055527_c001_v0001032_t0024") 2021/06/01 06:01:23 DEBUG : existing: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e3a2_d20210601_m055526_c001_v0001015_t0044") 2021/06/01 06:01:23 DEBUG : existing: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f100b3fdef96dcd7b_d20210601_m055525_c001_v0001128_t0007") 2021/06/01 06:01:23 DEBUG : existing: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e39e_d20210601_m055524_c001_v0001015_t0044") 2021/06/01 06:01:23 DEBUG : existing: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e39a_d20210601_m055523_c001_v0001015_t0044") 2021/06/01 06:01:23 DEBUG : five: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f11899f3461972376_d20210601_m055630_c001_v0001144_t0028") 2021/06/01 06:01:24 DEBUG : five: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e416_d20210601_m055628_c001_v0001015_t0044") 2021/06/01 06:01:24 DEBUG : foo: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f115403bec96c39b6_d20210601_m055537_c001_v0001131_t0033") 2021/06/01 06:01:24 DEBUG : foo: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e3b5_d20210601_m055536_c001_v0001015_t0044") 2021/06/01 06:01:24 DEBUG : foo: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e3b2_d20210601_m055535_c001_v0001015_t0044") 2021/06/01 06:01:24 DEBUG : four: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f118fd4e57f265298_d20210601_m055634_c001_v0001157_t0038") 2021/06/01 06:01:24 DEBUG : four: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f10192184431346c9_d20210601_m055628_c001_v0001150_t0013") 2021/06/01 06:01:24 DEBUG : four: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e414_d20210601_m055627_c001_v0001015_t0044") 2021/06/01 06:01:24 DEBUG : hello world2: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1055050986206139_d20210601_m055500_c001_v0001150_t0018") 2021/06/01 06:01:24 DEBUG : hello world2: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e367_d20210601_m055459_c001_v0001015_t0044") 2021/06/01 06:01:24 DEBUG : ignore-size: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f116eb18a615b460f_d20210601_m055523_c001_v0001131_t0040") 2021/06/01 06:01:24 DEBUG : ignore-size: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e395_d20210601_m055522_c001_v0001015_t0044") 2021/06/01 06:01:24 DEBUG : nested/sub dir/file: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f119229031ec03bc6_d20210601_m055657_c001_v0001146_t0058") 2021/06/01 06:01:24 DEBUG : nested/sub dir/file: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f101921844313480c_d20210601_m055656_c001_v0001150_t0013") 2021/06/01 06:01:24 DEBUG : nested/sub dir/file: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1022f3a98cf3fb38_d20210601_m055655_c001_v0001038_t0032") 2021/06/01 06:01:24 DEBUG : nested/sub dir/file: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e428_d20210601_m055654_c001_v0001015_t0044") 2021/06/01 06:01:24 DEBUG : one: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f116e35845fd6fa3c_d20210601_m055636_c001_v0001013_t0016") 2021/06/01 06:01:24 DEBUG : one: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e40e_d20210601_m055624_c001_v0001015_t0044") 2021/06/01 06:01:24 DEBUG : potato: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f112d53e19427232b_d20210601_m055652_c001_v0001003_t0014") 2021/06/01 06:01:24 DEBUG : potato: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f113eb42cfae40f46_d20210601_m055649_c001_v0001150_t0000") 2021/06/01 06:01:24 DEBUG : potato: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f102e02d12031694c_d20210601_m055647_c001_v0001137_t0001") 2021/06/01 06:01:24 DEBUG : potato: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1019218443134784_d20210601_m055645_c001_v0001150_t0013") 2021/06/01 06:01:24 DEBUG : potato: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f100723022400bc31_d20210601_m055644_c001_v0001091_t0049") 2021/06/01 06:01:24 DEBUG : potato: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f101921844313474b_d20210601_m055641_c001_v0001150_t0013") 2021/06/01 06:01:24 DEBUG : potato: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f115fae86e7a5e6c5_d20210601_m055615_c001_v0001001_t0003") 2021/06/01 06:01:25 DEBUG : potato: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e3fe_d20210601_m055614_c001_v0001015_t0044") 2021/06/01 06:01:25 DEBUG : potato: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1159430eb51c368a_d20210601_m055612_c001_v0001150_t0034") 2021/06/01 06:01:25 DEBUG : potato: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e3f4_d20210601_m055611_c001_v0001015_t0044") 2021/06/01 06:01:25 DEBUG : potato: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f10337e4970dffd41_d20210601_m055607_c001_v0001145_t0036") 2021/06/01 06:01:25 DEBUG : potato: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e3ec_d20210601_m055605_c001_v0001015_t0044") 2021/06/01 06:01:25 DEBUG : potato: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f10834587d3e939d2_d20210601_m055551_c001_v0001145_t0022") 2021/06/01 06:01:25 DEBUG : potato: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e3d0_d20210601_m055550_c001_v0001015_t0044") 2021/06/01 06:01:25 DEBUG : potato: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1159da1367a5f97a_d20210601_m055549_c001_v0001120_t0034") 2021/06/01 06:01:25 DEBUG : potato: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e3c9_d20210601_m055548_c001_v0001015_t0044") 2021/06/01 06:01:25 DEBUG : potato: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1033107822ddd204_d20210601_m055547_c001_v0001037_t0026") 2021/06/01 06:01:25 DEBUG : potato: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e3c7_d20210601_m055546_c001_v0001015_t0044") 2021/06/01 06:01:25 DEBUG : potato: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e3c1_d20210601_m055545_c001_v0001015_t0044") 2021/06/01 06:01:25 DEBUG : potato: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f102cae7a94413bf3_d20210601_m055543_c001_v0001144_t0018") 2021/06/01 06:01:25 DEBUG : potato: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e3bf_d20210601_m055542_c001_v0001015_t0044") 2021/06/01 06:01:25 DEBUG : potato: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e3bc_d20210601_m055541_c001_v0001015_t0044") 2021/06/01 06:01:25 DEBUG : potato: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f114b12152d8f2288_d20210601_m055540_c001_v0001031_t0011") 2021/06/01 06:01:25 DEBUG : potato: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e3b9_d20210601_m055538_c001_v0001015_t0044") 2021/06/01 06:01:25 DEBUG : potato2: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f10723922ba7f11df_d20210601_m055756_c001_v0001145_t0051") 2021/06/01 06:01:25 DEBUG : potato2: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e451_d20210601_m055755_c001_v0001015_t0044") 2021/06/01 06:01:25 DEBUG : potato2: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1061d05c40ffc2a6_d20210601_m055748_c001_v0001131_t0027") 2021/06/01 06:01:25 DEBUG : potato2: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e44a_d20210601_m055742_c001_v0001015_t0044") 2021/06/01 06:01:25 DEBUG : potato2: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f110c8fb8a1c570ff_d20210601_m055733_c001_v0001153_t0013") 2021/06/01 06:01:26 DEBUG : potato2: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e43b_d20210601_m055715_c001_v0001015_t0044") 2021/06/01 06:01:26 DEBUG : potato2: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1103098b37b31ba9_d20210601_m055703_c001_v0001145_t0017") 2021/06/01 06:01:26 DEBUG : potato2: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e42d_d20210601_m055658_c001_v0001015_t0044") 2021/06/01 06:01:26 DEBUG : potato2: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f10280ebf6de3e52f_d20210601_m055622_c001_v0001153_t0011") 2021/06/01 06:01:26 DEBUG : potato2: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e406_d20210601_m055620_c001_v0001015_t0044") 2021/06/01 06:01:26 DEBUG : potato2: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1125f98dd595733f_d20210601_m055619_c001_v0001037_t0051") 2021/06/01 06:01:26 DEBUG : potato2: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e402_d20210601_m055617_c001_v0001015_t0044") 2021/06/01 06:01:26 DEBUG : potato2: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f107bb4331f18fc4c_d20210601_m055616_c001_v0001026_t0023") 2021/06/01 06:01:26 DEBUG : potato2: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e400_d20210601_m055615_c001_v0001015_t0044") 2021/06/01 06:01:26 DEBUG : potato2: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1035234abe430c72_d20210601_m055613_c001_v0001033_t0039") 2021/06/01 06:01:26 DEBUG : potato2: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e3f8_d20210601_m055612_c001_v0001015_t0044") 2021/06/01 06:01:26 DEBUG : potato2: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1087b398bbcab33e_d20210601_m055608_c001_v0001144_t0012") 2021/06/01 06:01:26 DEBUG : potato2: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e3f2_d20210601_m055607_c001_v0001015_t0044") 2021/06/01 06:01:26 DEBUG : potato2: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f118ba30650286bb5_d20210601_m055553_c001_v0001009_t0006") 2021/06/01 06:01:26 DEBUG : potato2: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e3d5_d20210601_m055551_c001_v0001015_t0044") 2021/06/01 06:01:26 DEBUG : potato2: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f106ce5bfce8a3aa4_d20210601_m055504_c001_v0001031_t0009") 2021/06/01 06:01:26 DEBUG : potato2: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e36f_d20210601_m055503_c001_v0001015_t0044") 2021/06/01 06:01:26 DEBUG : potato2: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1030fecc592be8a5_d20210601_m055502_c001_v0001146_t0040") 2021/06/01 06:01:26 DEBUG : potato2: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e36b_d20210601_m055501_c001_v0001015_t0044") 2021/06/01 06:01:26 DEBUG : potato3: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f109c6bfd15fbed9a_d20210601_m055748_c001_v0001145_t0016") 2021/06/01 06:01:26 DEBUG : potato3: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f113eb42cfae41033_d20210601_m055744_c001_v0001150_t0000") 2021/06/01 06:01:26 DEBUG : potato3: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f103b302349ad3665_d20210601_m055733_c001_v0001001_t0003") 2021/06/01 06:01:26 DEBUG : potato3: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f113eb42cfae40fc7_d20210601_m055717_c001_v0001150_t0000") 2021/06/01 06:01:26 DEBUG : potato3: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f11222503cd5bdb9c_d20210601_m055703_c001_v0001026_t0028") 2021/06/01 06:01:27 DEBUG : potato3: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f113eb42cfae40f7b_d20210601_m055659_c001_v0001150_t0000") 2021/06/01 06:01:27 DEBUG : pre-dest1/1: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f100723022400bd12_d20210601_m055817_c001_v0001091_t0002") 2021/06/01 06:01:27 DEBUG : pre-dest1/1: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1019218443134af6_d20210601_m055810_c001_v0001150_t0013") 2021/06/01 06:01:27 DEBUG : pre-dest2/2: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f107f63e4b3844f69_d20210601_m055818_c001_v0001136_t0049") 2021/06/01 06:01:27 DEBUG : pre-dest2/2: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f113eb42cfae410cc_d20210601_m055811_c001_v0001150_t0000") 2021/06/01 06:01:27 DEBUG : sizeonly: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f105505098620623a_d20210601_m055521_c001_v0001150_t0016") 2021/06/01 06:01:27 DEBUG : sizeonly: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e393_d20210601_m055520_c001_v0001015_t0044") 2021/06/01 06:01:27 DEBUG : sub dir/hello world: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f111943b30ef83690_d20210601_m055657_c001_v0001157_t0002") 2021/06/01 06:01:27 DEBUG : sub dir/hello world: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f113eb42cfae40f65_d20210601_m055656_c001_v0001150_t0000") 2021/06/01 06:01:27 DEBUG : sub dir/hello world: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1103098b37b31b8d_d20210601_m055655_c001_v0001145_t0030") 2021/06/01 06:01:27 DEBUG : sub dir/hello world: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f10192184431347ed_d20210601_m055653_c001_v0001150_t0013") 2021/06/01 06:01:27 DEBUG : sub dir/hello world: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f109efcb526669afe_d20210601_m055518_c001_v0001150_t0037") 2021/06/01 06:01:27 DEBUG : sub dir/hello world: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e38d_d20210601_m055517_c001_v0001015_t0044") 2021/06/01 06:01:27 DEBUG : sub dir/hello world: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f111fc06c865e3f1d_d20210601_m055516_c001_v0001120_t0031") 2021/06/01 06:01:27 DEBUG : sub dir/hello world: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e38a_d20210601_m055515_c001_v0001015_t0044") 2021/06/01 06:01:27 DEBUG : sub dir/hello world: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f111e7fb5d1b08937_d20210601_m055514_c001_v0001017_t0016") 2021/06/01 06:01:27 DEBUG : sub dir/hello world: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e383_d20210601_m055512_c001_v0001015_t0044") 2021/06/01 06:01:27 DEBUG : sub dir/hello world: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f10505c87621d3709_d20210601_m055511_c001_v0001157_t0002") 2021/06/01 06:01:27 DEBUG : sub dir/hello world: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e37f_d20210601_m055510_c001_v0001015_t0044") 2021/06/01 06:01:27 DEBUG : sub dir/hello world: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1032070bef18fbc9_d20210601_m055509_c001_v0001120_t0008") 2021/06/01 06:01:27 DEBUG : sub dir/hello world: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e379_d20210601_m055508_c001_v0001015_t0044") 2021/06/01 06:01:27 DEBUG : sub dir/hello world: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1170b2d0b3df3a58_d20210601_m055507_c001_v0001150_t0036") 2021/06/01 06:01:27 DEBUG : sub dir/hello world: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e376_d20210601_m055505_c001_v0001015_t0044") 2021/06/01 06:01:27 DEBUG : sub dir/hello world: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f11883f48ccf1dd70_d20210601_m055500_c001_v0001037_t0002") 2021/06/01 06:01:28 DEBUG : sub dir/hello world: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e363_d20210601_m055459_c001_v0001015_t0044") 2021/06/01 06:01:28 DEBUG : sub dir/hello world: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f112d232bc0356aa0_d20210601_m055458_c001_v0001040_t0002") 2021/06/01 06:01:28 DEBUG : sub dir/hello world: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e361_d20210601_m055457_c001_v0001015_t0044") 2021/06/01 06:01:28 DEBUG : sub dir/hello world: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f11834dcda2165082_d20210601_m055455_c001_v0001145_t0014") 2021/06/01 06:01:28 DEBUG : sub dir/hello world: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e35d_d20210601_m055454_c001_v0001015_t0044") 2021/06/01 06:01:28 DEBUG : sub dir/hello world: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1025af4b9faeb154_d20210601_m055453_c001_v0001157_t0012") 2021/06/01 06:01:28 DEBUG : sub dir/hello world: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e359_d20210601_m055452_c001_v0001015_t0044") 2021/06/01 06:01:28 DEBUG : sub/yam: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1079c36517c4ffcf_d20210601_m055650_c001_v0001013_t0025") 2021/06/01 06:01:28 DEBUG : sub/yam: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f10192184431347b4_d20210601_m055648_c001_v0001150_t0013") 2021/06/01 06:01:28 DEBUG : three: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f11980ae1c96ba80b_d20210601_m055637_c001_v0001150_t0001") 2021/06/01 06:01:28 DEBUG : three: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e41c_d20210601_m055629_c001_v0001015_t0044") 2021/06/01 06:01:28 DEBUG : three: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e412_d20210601_m055625_c001_v0001015_t0044") 2021/06/01 06:01:28 DEBUG : two: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1123c146e839c3ac_d20210601_m055637_c001_v0001134_t0047") 2021/06/01 06:01:28 DEBUG : two: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f113eb42cfae40f02_d20210601_m055629_c001_v0001150_t0000") 2021/06/01 06:01:28 DEBUG : two: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e410_d20210601_m055624_c001_v0001015_t0044") 2021/06/01 06:01:28 DEBUG : yam: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f105d66ffab9fbbdf_d20210601_m055653_c001_v0001136_t0036") 2021/06/01 06:01:28 DEBUG : yam: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1105a982a7859c0c_d20210601_m055650_c001_v0001113_t0037") 2021/06/01 06:01:28 DEBUG : yam: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f117c348f334b28f9_d20210601_m055647_c001_v0001092_t0024") 2021/06/01 06:01:28 DEBUG : yam: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f108a50b9c797e425_d20210601_m055646_c001_v0001015_t0044") 2021/06/01 06:01:28 DEBUG : yam: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1061d05c40ffc22f_d20210601_m055644_c001_v0001131_t0019") 2021/06/01 06:01:28 DEBUG : yam: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f113eb42cfae40f28_d20210601_m055641_c001_v0001150_t0000") 2021/06/01 06:01:28 DEBUG : yaml: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1016555ae08a4a03_d20210601_m055648_c001_v0001015_t0013") 2021/06/01 06:01:28 DEBUG : yaml: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f11865cf06e60305b_d20210601_m055647_c001_v0001131_t0035") 2021/06/01 06:01:28 DEBUG : yaml: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f10821e047a125a88_d20210601_m055645_c001_v0001092_t0001") 2021/06/01 06:01:29 DEBUG : yaml: Deleting (id "4_z4fb29d33203dcb6b7f980e1b_f1131be0f5e7fa81c_d20210601_m055642_c001_v0001144_t0030") "./sync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose" - Finished OK in 6m40.438916845s (try 1/5)