"./sync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -fast-list" - Starting (try 1/5) 2021/03/23 05:43:52 DEBUG : Creating backend with remote "TestB2:rclone-test-hewakob7zaloweg0zaciyal7" 2021/03/23 05:43:53 DEBUG : Creating backend with remote "/tmp/rclone284180761" === RUN TestPipe --- PASS: TestPipe (0.00s) === RUN TestPipeConcurrent --- PASS: TestPipeConcurrent (0.14s) === 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-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" 2021/03/23 05:43:54 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) 2021/03/23 05:43:54 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for checks to finish 2021/03/23 05:43:54 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for transfers to finish --- PASS: TestCopyWithDryRun (1.35s) === RUN TestCopy run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" 2021/03/23 05:43:55 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for checks to finish 2021/03/23 05:43:55 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for transfers to finish 2021/03/23 05:43:55 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/03/23 05:43:55 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopy (1.73s) === RUN TestCopyMissingDirectory run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" 2021/03/23 05:43:56 DEBUG : Creating backend with remote "/non-existing" 2021/03/23 05:43:56 ERROR : : error reading source directory: directory not found 2021/03/23 05:43:56 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for checks to finish 2021/03/23 05:43:56 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for transfers to finish --- PASS: TestCopyMissingDirectory (0.41s) === RUN TestCopyNoTraverse run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" 2021/03/23 05:43:57 DEBUG : Couldn't decode error response: EOF 2021/03/23 05:43:57 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for checks to finish 2021/03/23 05:43:57 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for transfers to finish 2021/03/23 05:43:57 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/03/23 05:43:57 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoTraverse (1.98s) === RUN TestCopyCheckFirst run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" 2021/03/23 05:43:59 INFO : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Running all checks before starting transfers 2021/03/23 05:43:59 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for checks to finish 2021/03/23 05:43:59 INFO : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Checks finished, now starting transfers 2021/03/23 05:43:59 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for transfers to finish 2021/03/23 05:43:59 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/03/23 05:43:59 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyCheckFirst (1.56s) === RUN TestSyncNoTraverse run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" 2021/03/23 05:44:00 ERROR : Ignoring --no-traverse with sync 2021/03/23 05:44:00 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for checks to finish 2021/03/23 05:44:00 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for transfers to finish 2021/03/23 05:44:01 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/03/23 05:44:01 INFO : sub dir/hello world: Copied (new) 2021/03/23 05:44:01 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoTraverse (1.58s) === RUN TestCopyWithDepth run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" 2021/03/23 05:44:02 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for checks to finish 2021/03/23 05:44:02 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for transfers to finish 2021/03/23 05:44:03 DEBUG : hello world2: SHA-1 = c0bf117f9c93d02c894fe8ffd6e5cecf3545c209 OK 2021/03/23 05:44:03 INFO : hello world2: Copied (new) --- PASS: TestCopyWithDepth (1.74s) === RUN TestCopyWithFilesFrom run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" 2021/03/23 05:44:03 DEBUG : hello world2: Excluded 2021/03/23 05:44:04 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for checks to finish 2021/03/23 05:44:04 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for transfers to finish 2021/03/23 05:44:04 DEBUG : potato2: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/03/23 05:44:04 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFrom (2.53s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" 2021/03/23 05:44:07 DEBUG : Couldn't decode error response: EOF 2021/03/23 05:44:07 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for checks to finish 2021/03/23 05:44:07 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for transfers to finish 2021/03/23 05:44:08 DEBUG : potato2: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/03/23 05:44:08 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFromAndNoTraverse (3.22s) === RUN TestCopyEmptyDirectories run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" 2021/03/23 05:44:09 DEBUG : sub dir2: Making directory 2021/03/23 05:44:09 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for checks to finish 2021/03/23 05:44:09 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for transfers to finish 2021/03/23 05:44:10 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/03/23 05:44:10 INFO : sub dir/hello world: Copied (new) 2021/03/23 05:44:10 DEBUG : sub dir2: Making directory 2021/03/23 05:44:10 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: copied 1 directories fstest.go:251: Filtering empty directory "sub dir2" --- PASS: TestCopyEmptyDirectories (1.00s) === RUN TestMoveEmptyDirectories run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" 2021/03/23 05:44:10 DEBUG : sub dir2: Making directory 2021/03/23 05:44:10 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for checks to finish 2021/03/23 05:44:10 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for transfers to finish 2021/03/23 05:44:11 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/03/23 05:44:11 INFO : sub dir/hello world: Copied (new) 2021/03/23 05:44:11 INFO : sub dir/hello world: Deleted 2021/03/23 05:44:11 DEBUG : sub dir: Making directory 2021/03/23 05:44:11 DEBUG : sub dir2: Making directory 2021/03/23 05:44:11 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: copied 2 directories fstest.go:251: Filtering empty directory "sub dir2" --- PASS: TestMoveEmptyDirectories (9.87s) === RUN TestSyncEmptyDirectories run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" 2021/03/23 05:44:20 DEBUG : sub dir2: Making directory 2021/03/23 05:44:20 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for checks to finish 2021/03/23 05:44:20 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for transfers to finish 2021/03/23 05:44:21 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/03/23 05:44:21 INFO : sub dir/hello world: Copied (new) 2021/03/23 05:44:21 DEBUG : sub dir2: Making directory 2021/03/23 05:44:21 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: copied 1 directories 2021/03/23 05:44:21 DEBUG : Waiting for deletions to finish fstest.go:251: Filtering empty directory "sub dir2" --- PASS: TestSyncEmptyDirectories (2.36s) === RUN TestServerSideCopy run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" 2021/03/23 05:44:23 DEBUG : Creating backend with remote "TestB2:rclone-test-vejizac1gefuqix5texofuf9" sync_test.go:281: Server side copy (if possible) B2 bucket rclone-test-hewakob7zaloweg0zaciyal7 -> B2 bucket rclone-test-vejizac1gefuqix5texofuf9 2021/03/23 05:44:24 DEBUG : B2 bucket rclone-test-vejizac1gefuqix5texofuf9: Waiting for checks to finish 2021/03/23 05:44:24 DEBUG : B2 bucket rclone-test-vejizac1gefuqix5texofuf9: Waiting for transfers to finish 2021/03/23 05:44:25 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/03/23 05:44:25 INFO : sub dir/hello world: Copied (server-side copy) 2021/03/23 05:44:25 DEBUG : B2 bucket rclone-test-vejizac1gefuqix5texofuf9: Purge remote 2021/03/23 05:44:25 DEBUG : sub dir/hello world: Deleting (id "4_z8f02ddc390cd5bdb7f880e1b_f1187c65df02026da_d20210323_m054424_c001_v0001137_t0038") --- PASS: TestServerSideCopy (3.85s) === RUN TestCopyAfterDelete run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" 2021/03/23 05:44:27 ERROR : : error listing: directory not found 2021/03/23 05:44:27 DEBUG : Local file system at /tmp/rclone284180761: Making directory 2021/03/23 05:44:27 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for checks to finish 2021/03/23 05:44:27 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for transfers to finish --- PASS: TestCopyAfterDelete (1.60s) === RUN TestCopyRedownload run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" 2021/03/23 05:44:29 DEBUG : Local file system at /tmp/rclone284180761: Waiting for checks to finish 2021/03/23 05:44:29 DEBUG : Local file system at /tmp/rclone284180761: Waiting for transfers to finish 2021/03/23 05:44:29 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/03/23 05:44:29 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyRedownload (2.54s) === RUN TestSyncBasedOnCheckSum run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" 2021/03/23 05:44:31 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for checks to finish 2021/03/23 05:44:31 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for transfers to finish 2021/03/23 05:44:31 DEBUG : check sum: SHA-1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2021/03/23 05:44:31 INFO : check sum: Copied (new) 2021/03/23 05:44:31 DEBUG : Waiting for deletions to finish 2021/03/23 05:44:31 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for checks to finish 2021/03/23 05:44:31 DEBUG : check sum: SHA-1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2021/03/23 05:44:31 DEBUG : check sum: Size and SHA-1 of src and dst objects identical 2021/03/23 05:44:31 DEBUG : check sum: Unchanged skipping 2021/03/23 05:44:31 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for transfers to finish 2021/03/23 05:44:31 DEBUG : Waiting for deletions to finish 2021/03/23 05:44:31 INFO : There was nothing to transfer --- PASS: TestSyncBasedOnCheckSum (1.31s) === RUN TestSyncSizeOnly run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" 2021/03/23 05:44:32 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for checks to finish 2021/03/23 05:44:32 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for transfers to finish 2021/03/23 05:44:34 DEBUG : sizeonly: SHA-1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2021/03/23 05:44:34 INFO : sizeonly: Copied (new) 2021/03/23 05:44:34 DEBUG : Waiting for deletions to finish 2021/03/23 05:44:34 DEBUG : sizeonly: Sizes identical 2021/03/23 05:44:34 DEBUG : sizeonly: Unchanged skipping 2021/03/23 05:44:34 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for checks to finish 2021/03/23 05:44:34 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for transfers to finish 2021/03/23 05:44:34 DEBUG : Waiting for deletions to finish 2021/03/23 05:44:34 INFO : There was nothing to transfer --- PASS: TestSyncSizeOnly (3.09s) === RUN TestSyncIgnoreSize run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" 2021/03/23 05:44:35 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for checks to finish 2021/03/23 05:44:35 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for transfers to finish 2021/03/23 05:44:36 DEBUG : ignore-size: SHA-1 = 4a756ca07e9487f482465a99e8286abc86ba4dc7 OK 2021/03/23 05:44:36 INFO : ignore-size: Copied (new) 2021/03/23 05:44:36 DEBUG : Waiting for deletions to finish 2021/03/23 05:44:36 DEBUG : ignore-size: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/03/23 05:44:36 DEBUG : ignore-size: Unchanged skipping 2021/03/23 05:44:36 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for checks to finish 2021/03/23 05:44:36 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for transfers to finish 2021/03/23 05:44:36 DEBUG : Waiting for deletions to finish 2021/03/23 05:44:36 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreSize (1.89s) === RUN TestSyncIgnoreTimes run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" 2021/03/23 05:44:37 DEBUG : existing: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/03/23 05:44:37 DEBUG : existing: Unchanged skipping 2021/03/23 05:44:37 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for checks to finish 2021/03/23 05:44:37 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for transfers to finish 2021/03/23 05:44:37 DEBUG : Waiting for deletions to finish 2021/03/23 05:44:37 INFO : There was nothing to transfer 2021/03/23 05:44:37 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2021/03/23 05:44:37 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for checks to finish 2021/03/23 05:44:37 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for transfers to finish 2021/03/23 05:44:38 DEBUG : existing: SHA-1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2021/03/23 05:44:38 INFO : existing: Copied (replaced existing) 2021/03/23 05:44:38 DEBUG : Waiting for deletions to finish --- PASS: TestSyncIgnoreTimes (2.04s) === RUN TestSyncIgnoreExisting run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" 2021/03/23 05:44:39 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for checks to finish 2021/03/23 05:44:39 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for transfers to finish 2021/03/23 05:44:39 DEBUG : existing: SHA-1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2021/03/23 05:44:39 INFO : existing: Copied (new) 2021/03/23 05:44:39 DEBUG : Waiting for deletions to finish 2021/03/23 05:44:39 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for checks to finish 2021/03/23 05:44:39 DEBUG : existing: Destination exists, skipping 2021/03/23 05:44:39 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for transfers to finish 2021/03/23 05:44:39 DEBUG : Waiting for deletions to finish 2021/03/23 05:44:39 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreExisting (1.69s) === RUN TestSyncIgnoreErrors run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" 2021/03/23 05:44:41 DEBUG : d: Making directory fstest.go:251: Filtering empty directory "d" 2021/03/23 05:44:41 DEBUG : c/non empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/03/23 05:44:41 DEBUG : c/non empty space: Unchanged skipping 2021/03/23 05:44:41 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for checks to finish 2021/03/23 05:44:41 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for transfers to finish 2021/03/23 05:44:42 DEBUG : a/potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/03/23 05:44:42 INFO : a/potato2: Copied (new) 2021/03/23 05:44:42 DEBUG : Waiting for deletions to finish 2021/03/23 05:44:44 INFO : b/potato: Deleted --- PASS: TestSyncIgnoreErrors (4.72s) === RUN TestSyncAfterChangingModtimeOnly run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" 2021/03/23 05:44:46 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for checks to finish 2021/03/23 05:44:46 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/03/23 05:44:46 DEBUG : empty space: SHA-1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2021/03/23 05:44:46 NOTICE: empty space: Skipped update modification time as --dry-run is set (size 1) 2021/03/23 05:44:46 DEBUG : empty space: Unchanged skipping 2021/03/23 05:44:46 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for transfers to finish 2021/03/23 05:44:46 DEBUG : Waiting for deletions to finish 2021/03/23 05:44:46 INFO : There was nothing to transfer 2021/03/23 05:44:46 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for checks to finish 2021/03/23 05:44:46 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/03/23 05:44:46 DEBUG : empty space: SHA-1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2021/03/23 05:44:47 INFO : empty space: Updated modification time in destination 2021/03/23 05:44:47 DEBUG : empty space: Unchanged skipping 2021/03/23 05:44:47 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for transfers to finish 2021/03/23 05:44:47 DEBUG : Waiting for deletions to finish 2021/03/23 05:44:47 INFO : There was nothing to transfer --- PASS: TestSyncAfterChangingModtimeOnly (2.78s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" 2021/03/23 05:44:48 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for checks to finish 2021/03/23 05:44:48 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/03/23 05:44:48 DEBUG : empty space: SHA-1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2021/03/23 05:44:48 DEBUG : empty space: Unchanged skipping 2021/03/23 05:44:48 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for transfers to finish 2021/03/23 05:44:48 DEBUG : Waiting for deletions to finish 2021/03/23 05:44:48 INFO : There was nothing to transfer --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (1.54s) === RUN TestSyncDoesntUpdateModtime run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" 2021/03/23 05:44:51 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for checks to finish 2021/03/23 05:44:51 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/03/23 05:44:51 DEBUG : foo: SHA-1 = 0beec7b5ea3f0fdbc95d0dd47f3c5bc275da8a33 (Local file system at /tmp/rclone284180761) 2021/03/23 05:44:51 DEBUG : foo: SHA-1 = 62cdb7020ff920e5aa642c3d4066950dd1f01f4d (B2 bucket rclone-test-hewakob7zaloweg0zaciyal7) 2021/03/23 05:44:51 DEBUG : foo: SHA-1 differ 2021/03/23 05:44:51 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for transfers to finish 2021/03/23 05:44:51 DEBUG : foo: SHA-1 = 0beec7b5ea3f0fdbc95d0dd47f3c5bc275da8a33 OK 2021/03/23 05:44:51 INFO : foo: Copied (replaced existing) 2021/03/23 05:44:51 DEBUG : Waiting for deletions to finish --- PASS: TestSyncDoesntUpdateModtime (3.14s) === RUN TestSyncAfterAddingAFile run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" 2021/03/23 05:44:53 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/03/23 05:44:53 DEBUG : empty space: Unchanged skipping 2021/03/23 05:44:53 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for checks to finish 2021/03/23 05:44:53 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for transfers to finish 2021/03/23 05:44:54 DEBUG : potato: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/03/23 05:44:54 INFO : potato: Copied (new) 2021/03/23 05:44:54 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterAddingAFile (3.92s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" 2021/03/23 05:44:57 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for checks to finish 2021/03/23 05:44:57 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2021/03/23 05:44:57 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for transfers to finish 2021/03/23 05:44:58 DEBUG : potato: SHA-1 = 0fd321a1023ac4e1e1054fcc0236b9d0755f722f OK 2021/03/23 05:44:58 INFO : potato: Copied (replaced existing) 2021/03/23 05:44:58 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingFilesSizeOnly (1.97s) === RUN TestSyncAfterChangingContentsOnly run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" 2021/03/23 05:45:00 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for checks to finish 2021/03/23 05:45:00 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/03/23 05:45:00 DEBUG : potato: SHA-1 = 1ca5094b3b73f587249eabe179c40a3eec839b1f (Local file system at /tmp/rclone284180761) 2021/03/23 05:45:00 DEBUG : potato: SHA-1 = 0fd321a1023ac4e1e1054fcc0236b9d0755f722f (B2 bucket rclone-test-hewakob7zaloweg0zaciyal7) 2021/03/23 05:45:00 DEBUG : potato: SHA-1 differ 2021/03/23 05:45:00 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for transfers to finish 2021/03/23 05:45:00 DEBUG : potato: SHA-1 = 1ca5094b3b73f587249eabe179c40a3eec839b1f OK 2021/03/23 05:45:00 INFO : potato: Copied (replaced existing) 2021/03/23 05:45:00 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingContentsOnly (2.87s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" 2021/03/23 05:45:02 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2021/03/23 05:45:02 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/03/23 05:45:02 DEBUG : empty space: Unchanged skipping 2021/03/23 05:45:02 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for checks to finish 2021/03/23 05:45:02 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for transfers to finish 2021/03/23 05:45:02 DEBUG : Waiting for deletions to finish 2021/03/23 05:45:02 NOTICE: potato: Skipped delete as --dry-run is set (size 21) --- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (1.84s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" 2021/03/23 05:45:04 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/03/23 05:45:04 DEBUG : empty space: Unchanged skipping 2021/03/23 05:45:04 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for checks to finish 2021/03/23 05:45:04 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for transfers to finish 2021/03/23 05:45:05 DEBUG : potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/03/23 05:45:05 INFO : potato2: Copied (new) 2021/03/23 05:45:05 DEBUG : Waiting for deletions to finish 2021/03/23 05:45:05 INFO : potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (3.44s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" 2021/03/23 05:45:07 DEBUG : d: Making directory 2021/03/23 05:45:07 DEBUG : d/e: Making directory fstest.go:251: Filtering empty directory "d" fstest.go:251: Filtering empty directory "d/e" 2021/03/23 05:45:08 DEBUG : c/non empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/03/23 05:45:08 DEBUG : c/non empty space: Unchanged skipping 2021/03/23 05:45:08 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for checks to finish 2021/03/23 05:45:08 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for transfers to finish 2021/03/23 05:45:08 DEBUG : a/potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/03/23 05:45:08 INFO : a/potato2: Copied (new) 2021/03/23 05:45:08 DEBUG : Waiting for deletions to finish 2021/03/23 05:45:08 INFO : b/potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (4.72s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" 2021/03/23 05:45:12 DEBUG : d: Making directory fstest.go:251: Filtering empty directory "d" 2021/03/23 05:45:13 DEBUG : c/non empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/03/23 05:45:13 DEBUG : c/non empty space: Unchanged skipping 2021/03/23 05:45:13 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for checks to finish 2021/03/23 05:45:13 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for transfers to finish 2021/03/23 05:45:13 DEBUG : a/potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/03/23 05:45:13 INFO : a/potato2: Copied (new) 2021/03/23 05:45:13 ERROR : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: not deleting files as there were IO errors 2021/03/23 05:45:13 ERROR : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: not deleting directories as there were IO errors fstest.go:251: Filtering empty directory "d" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (3.54s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" 2021/03/23 05:45:16 DEBUG : Waiting for deletions to finish 2021/03/23 05:45:16 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/03/23 05:45:16 DEBUG : empty space: Unchanged skipping 2021/03/23 05:45:16 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for checks to finish 2021/03/23 05:45:16 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for transfers to finish 2021/03/23 05:45:17 DEBUG : potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/03/23 05:45:17 INFO : potato2: Copied (new) 2021/03/23 05:45:17 INFO : potato: Deleted --- PASS: TestSyncDeleteDuring (3.62s) === RUN TestSyncDeleteBefore run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" 2021/03/23 05:45:19 DEBUG : Waiting for deletions to finish 2021/03/23 05:45:19 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for checks to finish 2021/03/23 05:45:19 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for transfers to finish 2021/03/23 05:45:20 INFO : potato: Deleted 2021/03/23 05:45:20 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/03/23 05:45:20 DEBUG : empty space: Unchanged skipping 2021/03/23 05:45:20 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for checks to finish 2021/03/23 05:45:20 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for transfers to finish 2021/03/23 05:45:20 DEBUG : potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/03/23 05:45:20 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteBefore (3.56s) === RUN TestCopyDeleteBefore run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" 2021/03/23 05:45:23 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for checks to finish 2021/03/23 05:45:23 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for transfers to finish 2021/03/23 05:45:23 DEBUG : potato2: SHA-1 = 810274f0df19dab60a894a562b7a8ff04c072623 OK 2021/03/23 05:45:23 INFO : potato2: Copied (new) --- PASS: TestCopyDeleteBefore (2.42s) === RUN TestSyncWithExclude run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" 2021/03/23 05:45:25 DEBUG : enormous: Excluded 2021/03/23 05:45:25 DEBUG : potato2: Excluded 2021/03/23 05:45:25 DEBUG : potato2: Excluded from sync (and deletion) 2021/03/23 05:45:25 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for checks to finish 2021/03/23 05:45:25 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/03/23 05:45:25 DEBUG : empty space: Unchanged skipping 2021/03/23 05:45:25 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for transfers to finish 2021/03/23 05:45:25 DEBUG : Waiting for deletions to finish 2021/03/23 05:45:25 INFO : There was nothing to transfer 2021/03/23 05:45:26 DEBUG : enormous: Excluded 2021/03/23 05:45:26 DEBUG : potato2: Excluded 2021/03/23 05:45:26 DEBUG : potato2: Excluded from sync (and deletion) 2021/03/23 05:45:26 DEBUG : empty space: Size and modification time the same (differ by 456.789µs, within tolerance 1ms) 2021/03/23 05:45:26 DEBUG : empty space: Unchanged skipping 2021/03/23 05:45:26 DEBUG : Local file system at /tmp/rclone284180761: Waiting for checks to finish 2021/03/23 05:45:26 DEBUG : Local file system at /tmp/rclone284180761: Waiting for transfers to finish 2021/03/23 05:45:26 DEBUG : Waiting for deletions to finish 2021/03/23 05:45:26 INFO : There was nothing to transfer --- PASS: TestSyncWithExclude (2.72s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" 2021/03/23 05:45:28 DEBUG : enormous: Excluded 2021/03/23 05:45:28 DEBUG : potato2: Excluded 2021/03/23 05:45:28 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/03/23 05:45:28 DEBUG : empty space: Unchanged skipping 2021/03/23 05:45:28 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for checks to finish 2021/03/23 05:45:28 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for transfers to finish 2021/03/23 05:45:28 DEBUG : Waiting for deletions to finish 2021/03/23 05:45:29 INFO : enormous: Deleted 2021/03/23 05:45:29 INFO : potato2: Deleted 2021/03/23 05:45:29 INFO : There was nothing to transfer 2021/03/23 05:45:30 DEBUG : empty space: Size and modification time the same (differ by 456.789µs, within tolerance 1ms) 2021/03/23 05:45:30 DEBUG : empty space: Unchanged skipping 2021/03/23 05:45:30 DEBUG : Local file system at /tmp/rclone284180761: Waiting for checks to finish 2021/03/23 05:45:30 DEBUG : Local file system at /tmp/rclone284180761: Waiting for transfers to finish 2021/03/23 05:45:30 DEBUG : Waiting for deletions to finish 2021/03/23 05:45:30 INFO : enormous: Deleted 2021/03/23 05:45:30 INFO : potato2: Deleted 2021/03/23 05:45:30 INFO : There was nothing to transfer --- PASS: TestSyncWithExcludeAndDeleteExcluded (2.92s) === RUN TestSyncWithUpdateOlder run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" 2021/03/23 05:45:33 DEBUG : four: Sizes differ (src 4 vs dst 8) 2021/03/23 05:45:33 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2021/03/23 05:45:33 DEBUG : one: Destination is newer than source, skipping 2021/03/23 05:45:33 DEBUG : three: Sizes identical 2021/03/23 05:45:33 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2021/03/23 05:45:33 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for checks to finish 2021/03/23 05:45:33 DEBUG : two: SHA-1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b (Local file system at /tmp/rclone284180761) 2021/03/23 05:45:33 DEBUG : two: SHA-1 = b10e0da85e90399abfec36c7376b20aa4596461b (B2 bucket rclone-test-hewakob7zaloweg0zaciyal7) 2021/03/23 05:45:33 DEBUG : two: SHA-1 differ 2021/03/23 05:45:33 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for transfers to finish 2021/03/23 05:45:33 DEBUG : four: SHA-1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2021/03/23 05:45:33 INFO : four: Copied (replaced existing) 2021/03/23 05:45:33 DEBUG : five: SHA-1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2021/03/23 05:45:33 INFO : five: Copied (new) 2021/03/23 05:45:34 DEBUG : two: SHA-1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2021/03/23 05:45:34 INFO : two: Copied (replaced existing) 2021/03/23 05:45:34 DEBUG : Waiting for deletions to finish 2021/03/23 05:45:34 DEBUG : one: Destination is newer than source, skipping 2021/03/23 05:45:34 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for checks to finish 2021/03/23 05:45:34 DEBUG : four: SHA-1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2021/03/23 05:45:34 DEBUG : four: Size and SHA-1 of src and dst objects identical 2021/03/23 05:45:34 DEBUG : four: Destination mod time is within 1s of source and files identical, skipping 2021/03/23 05:45:34 DEBUG : five: SHA-1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2021/03/23 05:45:34 DEBUG : five: Size and SHA-1 of src and dst objects identical 2021/03/23 05:45:34 DEBUG : five: Destination mod time is within 1s of source and files identical, skipping 2021/03/23 05:45:34 DEBUG : two: SHA-1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2021/03/23 05:45:34 DEBUG : two: Size and SHA-1 of src and dst objects identical 2021/03/23 05:45:34 DEBUG : two: Destination mod time is within 1s of source and files identical, skipping 2021/03/23 05:45:34 DEBUG : three: SHA-1 = b802f384302cb24fbab0a44997e820bf2e8507bb (Local file system at /tmp/rclone284180761) 2021/03/23 05:45:34 DEBUG : three: SHA-1 = 03731cad13f4cf7b59703c77529381cdec1632e5 (B2 bucket rclone-test-hewakob7zaloweg0zaciyal7) 2021/03/23 05:45:34 DEBUG : three: SHA-1 differ 2021/03/23 05:45:34 DEBUG : three: Destination mod time is within 1s of source but files differ, transferring 2021/03/23 05:45:34 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for transfers to finish 2021/03/23 05:45:34 DEBUG : three: SHA-1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/03/23 05:45:34 INFO : three: Copied (replaced existing) 2021/03/23 05:45:34 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithUpdateOlder (11.46s) === 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-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" sync_test.go:1088: Can track renames: true 2021/03/23 05:45:42 INFO : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Making map for --track-renames 2021/03/23 05:45:42 INFO : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Finished making map for --track-renames 2021/03/23 05:45:42 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for checks to finish 2021/03/23 05:45:42 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for renames to finish 2021/03/23 05:45:42 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for transfers to finish 2021/03/23 05:45:42 DEBUG : potato: SHA-1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2021/03/23 05:45:42 INFO : potato: Copied (new) 2021/03/23 05:45:42 DEBUG : yam: SHA-1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2021/03/23 05:45:42 INFO : yam: Copied (new) 2021/03/23 05:45:42 DEBUG : Waiting for deletions to finish 2021/03/23 05:45:43 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/03/23 05:45:43 DEBUG : potato: Unchanged skipping 2021/03/23 05:45:43 INFO : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Making map for --track-renames 2021/03/23 05:45:43 INFO : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Finished making map for --track-renames 2021/03/23 05:45:43 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for checks to finish 2021/03/23 05:45:43 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for renames to finish 2021/03/23 05:45:43 DEBUG : Couldn't decode error response: EOF 2021/03/23 05:45:44 DEBUG : yam: SHA-1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2021/03/23 05:45:44 INFO : yam: Copied (server-side copy) to: yaml 2021/03/23 05:45:44 INFO : yam: Deleted 2021/03/23 05:45:44 INFO : yaml: Renamed from "yam" 2021/03/23 05:45:44 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for transfers to finish 2021/03/23 05:45:44 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenames (5.06s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" sync_test.go:1157: Can track renames: true 2021/03/23 05:45:47 INFO : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Making map for --track-renames 2021/03/23 05:45:47 INFO : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Finished making map for --track-renames 2021/03/23 05:45:47 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for checks to finish 2021/03/23 05:45:47 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for renames to finish 2021/03/23 05:45:47 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for transfers to finish 2021/03/23 05:45:47 DEBUG : yam: SHA-1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2021/03/23 05:45:47 INFO : yam: Copied (new) 2021/03/23 05:45:47 DEBUG : potato: SHA-1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2021/03/23 05:45:47 INFO : potato: Copied (new) 2021/03/23 05:45:47 DEBUG : Waiting for deletions to finish 2021/03/23 05:45:48 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/03/23 05:45:48 DEBUG : potato: Unchanged skipping 2021/03/23 05:45:48 INFO : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Making map for --track-renames 2021/03/23 05:45:48 INFO : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Finished making map for --track-renames 2021/03/23 05:45:48 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for checks to finish 2021/03/23 05:45:48 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for renames to finish 2021/03/23 05:45:48 DEBUG : Couldn't decode error response: EOF 2021/03/23 05:46:14 DEBUG : yam: SHA-1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2021/03/23 05:46:14 INFO : yam: Copied (server-side copy) to: yaml 2021/03/23 05:46:15 INFO : yam: Deleted 2021/03/23 05:46:15 INFO : yaml: Renamed from "yam" 2021/03/23 05:46:15 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for transfers to finish 2021/03/23 05:46:15 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenamesStrategyModtime (29.16s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" sync_test.go:1193: Can track renames: true 2021/03/23 05:46:16 INFO : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Making map for --track-renames 2021/03/23 05:46:16 INFO : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Finished making map for --track-renames 2021/03/23 05:46:16 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for checks to finish 2021/03/23 05:46:16 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for renames to finish 2021/03/23 05:46:16 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for transfers to finish 2021/03/23 05:46:17 DEBUG : sub/yam: SHA-1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2021/03/23 05:46:17 INFO : sub/yam: Copied (new) 2021/03/23 05:46:17 DEBUG : potato: SHA-1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2021/03/23 05:46:17 INFO : potato: Copied (new) 2021/03/23 05:46:17 DEBUG : Waiting for deletions to finish 2021/03/23 05:46:17 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/03/23 05:46:17 DEBUG : potato: Unchanged skipping 2021/03/23 05:46:17 INFO : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Making map for --track-renames 2021/03/23 05:46:17 INFO : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Finished making map for --track-renames 2021/03/23 05:46:17 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for checks to finish 2021/03/23 05:46:17 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for renames to finish 2021/03/23 05:46:18 DEBUG : Couldn't decode error response: EOF 2021/03/23 05:46:18 DEBUG : sub/yam: SHA-1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2021/03/23 05:46:18 INFO : sub/yam: Copied (server-side copy) to: yam 2021/03/23 05:46:18 INFO : sub/yam: Deleted 2021/03/23 05:46:18 INFO : yam: Renamed from "sub/yam" 2021/03/23 05:46:18 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for transfers to finish 2021/03/23 05:46:18 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenamesStrategyLeaf (3.84s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" 2021/03/23 05:46:20 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for checks to finish 2021/03/23 05:46:20 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for transfers to finish 2021/03/23 05:46:20 DEBUG : nested/sub dir/file: SHA-1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK 2021/03/23 05:46:20 INFO : nested/sub dir/file: Copied (new) 2021/03/23 05:46:20 INFO : nested/sub dir/file: Deleted 2021/03/23 05:46:21 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/03/23 05:46:21 INFO : sub dir/hello world: Copied (new) 2021/03/23 05:46:21 INFO : sub dir/hello world: Deleted 2021/03/23 05:46:21 DEBUG : sub dir: Removing directory 2021/03/23 05:46:21 DEBUG : nested/sub dir: Removing directory 2021/03/23 05:46:21 DEBUG : nested: Removing directory 2021/03/23 05:46:21 DEBUG : Local file system at /tmp/rclone284180761: deleted 3 directories --- PASS: TestMoveWithDeleteEmptySrcDirs (2.35s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" 2021/03/23 05:46:22 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for checks to finish 2021/03/23 05:46:22 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for transfers to finish 2021/03/23 05:46:22 DEBUG : nested/sub dir/file: SHA-1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK 2021/03/23 05:46:22 INFO : nested/sub dir/file: Copied (new) 2021/03/23 05:46:22 INFO : nested/sub dir/file: Deleted 2021/03/23 05:46:24 DEBUG : sub dir/hello world: SHA-1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2021/03/23 05:46:24 INFO : sub dir/hello world: Copied (new) 2021/03/23 05:46:24 INFO : sub dir/hello world: Deleted --- PASS: TestMoveWithoutDeleteEmptySrcDirs (5.43s) === RUN TestServerSideMove run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" 2021/03/23 05:46:27 DEBUG : Creating backend with remote "TestB2:rclone-test-lobixag9juxelub5kabayuh8" sync_test.go:1245: Server side move (if possible) B2 bucket rclone-test-hewakob7zaloweg0zaciyal7 -> B2 bucket rclone-test-lobixag9juxelub5kabayuh8 2021/03/23 05:46:33 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms) 2021/03/23 05:46:33 DEBUG : empty space: Unchanged skipping 2021/03/23 05:46:33 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2021/03/23 05:46:33 DEBUG : B2 bucket rclone-test-lobixag9juxelub5kabayuh8: Waiting for checks to finish 2021/03/23 05:46:33 INFO : empty space: Deleted 2021/03/23 05:46:33 DEBUG : B2 bucket rclone-test-lobixag9juxelub5kabayuh8: Waiting for transfers to finish 2021/03/23 05:46:33 DEBUG : potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/03/23 05:46:33 INFO : potato2: Copied (server-side copy) 2021/03/23 05:46:33 DEBUG : potato3: SHA-1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2021/03/23 05:46:33 INFO : potato3: Copied (server-side copy) 2021/03/23 05:46:34 INFO : potato3: Deleted 2021/03/23 05:46:34 INFO : potato2: Deleted 2021/03/23 05:46:34 DEBUG : Creating backend with remote "TestB2:rclone-test-fiwuzor0mizujav1dehuyal8" 2021/03/23 05:46:35 DEBUG : B2 bucket rclone-test-fiwuzor0mizujav1dehuyal8: Waiting for checks to finish 2021/03/23 05:46:35 DEBUG : B2 bucket rclone-test-fiwuzor0mizujav1dehuyal8: Waiting for transfers to finish 2021/03/23 05:46:36 DEBUG : empty space: SHA-1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2021/03/23 05:46:36 INFO : empty space: Copied (server-side copy) 2021/03/23 05:46:36 DEBUG : potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/03/23 05:46:36 INFO : potato2: Copied (server-side copy) 2021/03/23 05:46:36 INFO : potato2: Deleted 2021/03/23 05:46:36 DEBUG : potato3: SHA-1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2021/03/23 05:46:36 INFO : potato3: Copied (server-side copy) 2021/03/23 05:46:36 INFO : potato3: Deleted 2021/03/23 05:46:36 INFO : empty space: Deleted 2021/03/23 05:46:37 DEBUG : B2 bucket rclone-test-fiwuzor0mizujav1dehuyal8: Purge remote 2021/03/23 05:46:37 DEBUG : empty space: Deleting (id "4_zff12ddc390ed5bdb7f880e1b_f1123c13650a7a7cf_d20210323_m054636_c001_v0001154_t0003") 2021/03/23 05:46:37 DEBUG : potato2: Deleting (id "4_zff12ddc390ed5bdb7f880e1b_f117a9e7d7dcc637a_d20210323_m054636_c001_v0001153_t0021") 2021/03/23 05:46:37 DEBUG : potato3: Deleting (id "4_zff12ddc390ed5bdb7f880e1b_f1003832678fc1c06_d20210323_m054636_c001_v0001145_t0038") 2021/03/23 05:46:37 DEBUG : B2 bucket rclone-test-lobixag9juxelub5kabayuh8: Purge remote 2021/03/23 05:46:37 DEBUG : empty space: Deleting (id "4_z4f32cdb390ed5bdb7f880e1b_f1127029e6a4509f3_d20210323_m054636_c001_v0001144_t0051") 2021/03/23 05:46:37 DEBUG : empty space: Deleting (id "4_z4f32cdb390ed5bdb7f880e1b_f11811f733475993e_d20210323_m054632_c001_v0001113_t0045") 2021/03/23 05:46:37 DEBUG : potato2: Deleting (id "4_z4f32cdb390ed5bdb7f880e1b_f1167d070062e603f_d20210323_m054636_c001_v0001145_t0043") 2021/03/23 05:46:37 DEBUG : potato2: Deleting (id "4_z4f32cdb390ed5bdb7f880e1b_f1054fcd5845020f2_d20210323_m054633_c001_v0001154_t0009") 2021/03/23 05:46:37 DEBUG : potato3: Deleting (id "4_z4f32cdb390ed5bdb7f880e1b_f118894e141effad2_d20210323_m054636_c001_v0001146_t0008") 2021/03/23 05:46:37 DEBUG : potato3: Deleting (id "4_z4f32cdb390ed5bdb7f880e1b_f1098cc8b4f4da0a4_d20210323_m054633_c001_v0001091_t0047") 2021/03/23 05:46:37 DEBUG : potato3: Deleting (id "4_z4f32cdb390ed5bdb7f880e1b_f11811f7334759940_d20210323_m054632_c001_v0001113_t0045") --- PASS: TestServerSideMove (10.98s) === RUN TestServerSideMoveWithFilter run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" 2021/03/23 05:46:38 DEBUG : Creating backend with remote "TestB2:rclone-test-linitac7sopequc5sefener8" sync_test.go:1245: Server side move (if possible) B2 bucket rclone-test-hewakob7zaloweg0zaciyal7 -> B2 bucket rclone-test-linitac7sopequc5sefener8 2021/03/23 05:46:43 DEBUG : empty space: Excluded from sync (and deletion) 2021/03/23 05:46:43 DEBUG : empty space: Excluded from sync (and deletion) 2021/03/23 05:46:43 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2021/03/23 05:46:43 DEBUG : B2 bucket rclone-test-linitac7sopequc5sefener8: Waiting for checks to finish 2021/03/23 05:46:43 DEBUG : B2 bucket rclone-test-linitac7sopequc5sefener8: Waiting for transfers to finish 2021/03/23 05:46:44 DEBUG : potato3: SHA-1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2021/03/23 05:46:44 INFO : potato3: Copied (server-side copy) 2021/03/23 05:46:44 DEBUG : potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/03/23 05:46:44 INFO : potato2: Copied (server-side copy) 2021/03/23 05:46:44 INFO : potato2: Deleted 2021/03/23 05:46:45 INFO : potato3: Deleted 2021/03/23 05:46:45 DEBUG : Creating backend with remote "TestB2:rclone-test-wagutak6bixadij6paduqin4" 2021/03/23 05:46:46 DEBUG : empty space: Excluded from sync (and deletion) 2021/03/23 05:46:46 DEBUG : B2 bucket rclone-test-wagutak6bixadij6paduqin4: Waiting for checks to finish 2021/03/23 05:46:46 DEBUG : B2 bucket rclone-test-wagutak6bixadij6paduqin4: Waiting for transfers to finish 2021/03/23 05:46:46 DEBUG : potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/03/23 05:46:46 INFO : potato2: Copied (server-side copy) 2021/03/23 05:46:47 INFO : potato2: Deleted 2021/03/23 05:46:47 DEBUG : potato3: SHA-1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2021/03/23 05:46:47 INFO : potato3: Copied (server-side copy) 2021/03/23 05:46:49 INFO : potato3: Deleted 2021/03/23 05:46:49 DEBUG : B2 bucket rclone-test-wagutak6bixadij6paduqin4: Purge remote 2021/03/23 05:46:49 DEBUG : potato2: Deleting (id "4_z5f820d6390fd5bdb7f880e1b_f119984de93be5b19_d20210323_m054646_c001_v0001041_t0005") 2021/03/23 05:46:49 DEBUG : potato3: Deleting (id "4_z5f820d6390fd5bdb7f880e1b_f1151f3183434ae99_d20210323_m054646_c001_v0001021_t0023") 2021/03/23 05:46:50 DEBUG : B2 bucket rclone-test-linitac7sopequc5sefener8: Purge remote 2021/03/23 05:46:50 DEBUG : empty space: Deleting (id "4_z2fb2fd3390ed5bdb7f880e1b_f116e975013b60135_d20210323_m054642_c001_v0001137_t0036") 2021/03/23 05:46:50 DEBUG : potato2: Deleting (id "4_z2fb2fd3390ed5bdb7f880e1b_f1071953631b632af_d20210323_m054646_c001_v0001128_t0042") 2021/03/23 05:46:50 DEBUG : potato2: Deleting (id "4_z2fb2fd3390ed5bdb7f880e1b_f10838e65b6393f71_d20210323_m054643_c001_v0001144_t0008") 2021/03/23 05:46:50 DEBUG : potato3: Deleting (id "4_z2fb2fd3390ed5bdb7f880e1b_f1071a2e3bce2991a_d20210323_m054647_c001_v0001040_t0014") 2021/03/23 05:46:50 DEBUG : potato3: Deleting (id "4_z2fb2fd3390ed5bdb7f880e1b_f109ccba313f36a81_d20210323_m054643_c001_v0001144_t0014") 2021/03/23 05:46:50 DEBUG : potato3: Deleting (id "4_z2fb2fd3390ed5bdb7f880e1b_f116e975013b60137_d20210323_m054642_c001_v0001137_t0036") --- PASS: TestServerSideMoveWithFilter (13.11s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" 2021/03/23 05:46:51 DEBUG : Creating backend with remote "TestB2:rclone-test-hacawaw5vaqewiq4bewiwas0" 2021/03/23 05:46:54 DEBUG : tomatoDir: Making directory sync_test.go:1245: Server side move (if possible) B2 bucket rclone-test-hewakob7zaloweg0zaciyal7 -> B2 bucket rclone-test-hacawaw5vaqewiq4bewiwas0 2021/03/23 05:46:57 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms) 2021/03/23 05:46:57 DEBUG : empty space: Unchanged skipping 2021/03/23 05:46:57 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2021/03/23 05:46:57 DEBUG : B2 bucket rclone-test-hacawaw5vaqewiq4bewiwas0: Waiting for checks to finish 2021/03/23 05:46:57 DEBUG : potato3: SHA-1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2021/03/23 05:46:57 INFO : potato3: Copied (server-side copy) 2021/03/23 05:46:57 DEBUG : potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/03/23 05:46:57 INFO : potato2: Copied (server-side copy) 2021/03/23 05:46:57 INFO : empty space: Deleted 2021/03/23 05:46:57 DEBUG : B2 bucket rclone-test-hacawaw5vaqewiq4bewiwas0: Waiting for transfers to finish 2021/03/23 05:46:58 INFO : potato2: Deleted 2021/03/23 05:46:58 INFO : potato3: Deleted 2021/03/23 05:46:58 DEBUG : Creating backend with remote "TestB2:rclone-test-doyukod0lijixoq5delilaz5" 2021/03/23 05:46:59 DEBUG : tomatoDir: Making directory 2021/03/23 05:46:59 DEBUG : B2 bucket rclone-test-doyukod0lijixoq5delilaz5: Waiting for checks to finish 2021/03/23 05:46:59 DEBUG : B2 bucket rclone-test-doyukod0lijixoq5delilaz5: Waiting for transfers to finish 2021/03/23 05:47:00 DEBUG : potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/03/23 05:47:00 INFO : potato2: Copied (server-side copy) 2021/03/23 05:47:00 DEBUG : potato3: SHA-1 = 568f8850f3c1a384731dbe40c6d72e1dcae342a0 OK 2021/03/23 05:47:00 INFO : potato3: Copied (server-side copy) 2021/03/23 05:47:00 INFO : potato2: Deleted 2021/03/23 05:47:01 INFO : potato3: Deleted 2021/03/23 05:47:02 DEBUG : empty space: SHA-1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2021/03/23 05:47:02 INFO : empty space: Copied (server-side copy) 2021/03/23 05:47:03 INFO : empty space: Deleted 2021/03/23 05:47:03 DEBUG : B2 bucket rclone-test-doyukod0lijixoq5delilaz5: Purge remote 2021/03/23 05:47:03 DEBUG : empty space: Deleting (id "4_z6f623da390fd5bdb7f880e1b_f109d55946da8aac4_d20210323_m054701_c001_v0001044_t0030") 2021/03/23 05:47:03 DEBUG : potato2: Deleting (id "4_z6f623da390fd5bdb7f880e1b_f112be961d6dc2eee_d20210323_m054659_c001_v0001146_t0029") 2021/03/23 05:47:03 DEBUG : potato3: Deleting (id "4_z6f623da390fd5bdb7f880e1b_f1012c757932db7b1_d20210323_m054659_c001_v0001000_t0018") 2021/03/23 05:47:04 DEBUG : B2 bucket rclone-test-hacawaw5vaqewiq4bewiwas0: Purge remote 2021/03/23 05:47:04 DEBUG : empty space: Deleting (id "4_z1f922d8390fd5bdb7f880e1b_f107b2a945e513765_d20210323_m054702_c001_v0001017_t0000") 2021/03/23 05:47:04 DEBUG : empty space: Deleting (id "4_z1f922d8390fd5bdb7f880e1b_f11686153937a0bc0_d20210323_m054655_c001_v0001001_t0034") 2021/03/23 05:47:04 DEBUG : potato2: Deleting (id "4_z1f922d8390fd5bdb7f880e1b_f11449aaea059747d_d20210323_m054700_c001_v0001150_t0026") 2021/03/23 05:47:04 DEBUG : potato2: Deleting (id "4_z1f922d8390fd5bdb7f880e1b_f115398d626c50b62_d20210323_m054657_c001_v0001154_t0020") 2021/03/23 05:47:04 DEBUG : potato3: Deleting (id "4_z1f922d8390fd5bdb7f880e1b_f114a066648384628_d20210323_m054700_c001_v0001145_t0008") 2021/03/23 05:47:04 DEBUG : potato3: Deleting (id "4_z1f922d8390fd5bdb7f880e1b_f106a93f7a0915c9d_d20210323_m054657_c001_v0001036_t0029") 2021/03/23 05:47:04 DEBUG : potato3: Deleting (id "4_z1f922d8390fd5bdb7f880e1b_f11686153937a0bc4_d20210323_m054656_c001_v0001001_t0034") --- PASS: TestServerSideMoveDeleteEmptySourceDirs (13.42s) === RUN TestServerSideMoveOverlap run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" 2021/03/23 05:47:05 DEBUG : Creating backend with remote "TestB2:rclone-test-hewakob7zaloweg0zaciyal7/rclone-move-test" 2021/03/23 05:47:06 DEBUG : Couldn't decode error response: EOF --- PASS: TestServerSideMoveOverlap (33.48s) === RUN TestSyncOverlap run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" 2021/03/23 05:47:38 DEBUG : Creating backend with remote "TestB2:rclone-test-hewakob7zaloweg0zaciyal7/rclone-sync-test" 2021/03/23 05:47:38 DEBUG : Config file has changed externaly - reloading 2021/03/23 05:47:40 DEBUG : Couldn't decode error response: EOF --- PASS: TestSyncOverlap (1.50s) === RUN TestSyncCompareDest run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" 2021/03/23 05:47:40 DEBUG : Creating backend with remote "TestB2:rclone-test-hewakob7zaloweg0zaciyal7/dst" 2021/03/23 05:47:40 DEBUG : Couldn't decode error response: EOF 2021/03/23 05:47:40 DEBUG : Creating backend with remote "TestB2:rclone-test-hewakob7zaloweg0zaciyal7/CompareDest" 2021/03/23 05:47:41 DEBUG : Couldn't decode error response: EOF 2021/03/23 05:47:41 DEBUG : Couldn't decode error response: EOF 2021/03/23 05:47:41 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7 path dst: Waiting for checks to finish 2021/03/23 05:47:41 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7 path dst: Waiting for transfers to finish 2021/03/23 05:47:42 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/03/23 05:47:42 INFO : one: Copied (new) 2021/03/23 05:47:42 DEBUG : Waiting for deletions to finish 2021/03/23 05:47:42 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7 path dst: Waiting for checks to finish 2021/03/23 05:47:42 DEBUG : Couldn't decode error response: EOF 2021/03/23 05:47:42 DEBUG : one: Sizes differ (src 5 vs dst 3) 2021/03/23 05:47:42 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7 path dst: Waiting for transfers to finish 2021/03/23 05:47:43 DEBUG : one: SHA-1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2021/03/23 05:47:43 INFO : one: Copied (replaced existing) 2021/03/23 05:47:43 DEBUG : Waiting for deletions to finish 2021/03/23 05:48:19 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7 path dst: Waiting for checks to finish 2021/03/23 05:48:20 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/03/23 05:48:20 DEBUG : one: Destination found in --compare-dest, skipping 2021/03/23 05:48:20 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7 path dst: Waiting for transfers to finish 2021/03/23 05:48:20 DEBUG : Waiting for deletions to finish 2021/03/23 05:48:20 INFO : There was nothing to transfer 2021/03/23 05:48:21 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/03/23 05:48:21 DEBUG : two: Destination found in --compare-dest, skipping 2021/03/23 05:48:21 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7 path dst: Waiting for checks to finish 2021/03/23 05:48:21 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/03/23 05:48:21 DEBUG : one: Destination found in --compare-dest, skipping 2021/03/23 05:48:21 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7 path dst: Waiting for transfers to finish 2021/03/23 05:48:21 DEBUG : Waiting for deletions to finish 2021/03/23 05:48:21 INFO : There was nothing to transfer 2021/03/23 05:48:22 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/03/23 05:48:22 DEBUG : two: Destination found in --compare-dest, skipping 2021/03/23 05:48:22 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7 path dst: Waiting for checks to finish 2021/03/23 05:48:22 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/03/23 05:48:22 DEBUG : one: Destination found in --compare-dest, skipping 2021/03/23 05:48:22 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7 path dst: Waiting for transfers to finish 2021/03/23 05:48:22 DEBUG : Waiting for deletions to finish 2021/03/23 05:48:22 INFO : There was nothing to transfer 2021/03/23 05:48:22 DEBUG : two: Sizes differ (src 5 vs dst 3) 2021/03/23 05:48:22 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7 path dst: Waiting for checks to finish 2021/03/23 05:48:22 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/03/23 05:48:22 DEBUG : one: Destination found in --compare-dest, skipping 2021/03/23 05:48:22 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7 path dst: Waiting for transfers to finish 2021/03/23 05:48:23 DEBUG : two: SHA-1 = a4be8877ad88f3109a6411985a1f359e10e2d930 OK 2021/03/23 05:48:23 INFO : two: Copied (new) 2021/03/23 05:48:23 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCompareDest (44.61s) === RUN TestSyncMultipleCompareDest run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" 2021/03/23 05:48:26 DEBUG : Creating backend with remote "TestB2:rclone-test-hewakob7zaloweg0zaciyal7/dest" 2021/03/23 05:48:27 DEBUG : Couldn't decode error response: EOF 2021/03/23 05:48:27 DEBUG : Creating backend with remote "TestB2:rclone-test-hewakob7zaloweg0zaciyal7/pre-dest1" 2021/03/23 05:48:27 DEBUG : Couldn't decode error response: EOF 2021/03/23 05:48:27 DEBUG : Creating backend with remote "TestB2:rclone-test-hewakob7zaloweg0zaciyal7/pre-dest2" 2021/03/23 05:48:27 DEBUG : Couldn't decode error response: EOF 2021/03/23 05:48:28 DEBUG : 1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/03/23 05:48:28 DEBUG : 1: Destination found in --compare-dest, skipping 2021/03/23 05:48:28 DEBUG : Couldn't decode error response: EOF 2021/03/23 05:48:28 DEBUG : 2: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/03/23 05:48:28 DEBUG : 2: Destination found in --compare-dest, skipping 2021/03/23 05:48:28 DEBUG : Couldn't decode error response: EOF 2021/03/23 05:48:28 DEBUG : Couldn't decode error response: EOF 2021/03/23 05:48:28 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7 path dest: Waiting for checks to finish 2021/03/23 05:48:28 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7 path dest: Waiting for transfers to finish 2021/03/23 05:48:29 DEBUG : 3: SHA-1 = 77de68daecd823babbb58edb1c8e14d7106e83bb OK 2021/03/23 05:48:29 INFO : 3: Copied (new) 2021/03/23 05:48:29 DEBUG : Waiting for deletions to finish --- PASS: TestSyncMultipleCompareDest (7.43s) === RUN TestSyncCopyDest run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" 2021/03/23 05:48:32 DEBUG : Creating backend with remote "TestB2:rclone-test-hewakob7zaloweg0zaciyal7/dst" 2021/03/23 05:48:32 DEBUG : Couldn't decode error response: EOF 2021/03/23 05:48:32 DEBUG : Creating backend with remote "TestB2:rclone-test-hewakob7zaloweg0zaciyal7/CopyDest" 2021/03/23 05:48:33 DEBUG : Couldn't decode error response: EOF 2021/03/23 05:48:33 DEBUG : Couldn't decode error response: EOF 2021/03/23 05:48:33 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7 path dst: Waiting for checks to finish 2021/03/23 05:48:33 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7 path dst: Waiting for transfers to finish 2021/03/23 05:48:34 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/03/23 05:48:34 INFO : one: Copied (new) 2021/03/23 05:48:34 DEBUG : Waiting for deletions to finish 2021/03/23 05:48:34 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7 path dst: Waiting for checks to finish 2021/03/23 05:48:34 DEBUG : Couldn't decode error response: EOF 2021/03/23 05:48:34 DEBUG : one: Sizes differ (src 5 vs dst 3) 2021/03/23 05:48:34 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7 path dst: Waiting for transfers to finish 2021/03/23 05:48:35 DEBUG : one: SHA-1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2021/03/23 05:48:35 INFO : one: Copied (replaced existing) 2021/03/23 05:48:35 DEBUG : Waiting for deletions to finish 2021/03/23 05:48:37 DEBUG : Creating backend with remote "TestB2:rclone-test-hewakob7zaloweg0zaciyal7/BackupDir" 2021/03/23 05:48:37 DEBUG : Couldn't decode error response: EOF 2021/03/23 05:48:38 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7 path dst: Waiting for checks to finish 2021/03/23 05:48:38 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/03/23 05:48:38 DEBUG : one: Sizes differ (src 5 vs dst 3) 2021/03/23 05:48:38 DEBUG : Couldn't decode error response: EOF 2021/03/23 05:48:39 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/03/23 05:48:39 INFO : one: Copied (server-side copy) 2021/03/23 05:48:40 INFO : one: Deleted 2021/03/23 05:48:40 DEBUG : one: SHA-1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2021/03/23 05:48:40 INFO : one: Copied (server-side copy) 2021/03/23 05:48:40 DEBUG : one: Destination found in --copy-dest, using server-side copy 2021/03/23 05:48:40 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7 path dst: Waiting for transfers to finish 2021/03/23 05:48:40 DEBUG : Waiting for deletions to finish 2021/03/23 05:48:42 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/03/23 05:48:42 DEBUG : two: SHA-1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2021/03/23 05:48:42 INFO : two: Copied (server-side copy) 2021/03/23 05:48:42 DEBUG : two: Destination found in --copy-dest, using server-side copy 2021/03/23 05:48:42 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7 path dst: Waiting for checks to finish 2021/03/23 05:48:42 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/03/23 05:48:42 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/03/23 05:48:42 DEBUG : one: Unchanged skipping 2021/03/23 05:48:42 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7 path dst: Waiting for transfers to finish 2021/03/23 05:48:42 DEBUG : Waiting for deletions to finish 2021/03/23 05:48:43 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7 path dst: Waiting for checks to finish 2021/03/23 05:48:43 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/03/23 05:48:43 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/03/23 05:48:43 DEBUG : one: Unchanged skipping 2021/03/23 05:48:43 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/03/23 05:48:43 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/03/23 05:48:43 DEBUG : two: Unchanged skipping 2021/03/23 05:48:43 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7 path dst: Waiting for transfers to finish 2021/03/23 05:48:43 DEBUG : Waiting for deletions to finish 2021/03/23 05:48:43 INFO : There was nothing to transfer 2021/03/23 05:48:44 DEBUG : three: Sizes differ (src 7 vs dst 5) 2021/03/23 05:48:44 DEBUG : three: Destination not found in --copy-dest 2021/03/23 05:48:44 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7 path dst: Waiting for checks to finish 2021/03/23 05:48:44 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/03/23 05:48:44 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/03/23 05:48:44 DEBUG : one: Unchanged skipping 2021/03/23 05:48:44 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/03/23 05:48:44 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/03/23 05:48:44 DEBUG : two: Unchanged skipping 2021/03/23 05:48:44 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7 path dst: Waiting for transfers to finish 2021/03/23 05:48:45 DEBUG : three: SHA-1 = 370e613335f3cf2f19b2a2d35a86402f55c659f8 OK 2021/03/23 05:48:45 INFO : three: Copied (new) 2021/03/23 05:48:45 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCopyDest (19.52s) === RUN TestSyncBackupDir run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" 2021/03/23 05:48:54 DEBUG : Creating backend with remote "TestB2:rclone-test-hewakob7zaloweg0zaciyal7/dst" 2021/03/23 05:48:55 DEBUG : Couldn't decode error response: EOF 2021/03/23 05:48:55 DEBUG : Creating backend with remote "TestB2:rclone-test-hewakob7zaloweg0zaciyal7/backup" 2021/03/23 05:48:56 DEBUG : Couldn't decode error response: EOF 2021/03/23 05:48:56 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/03/23 05:48:56 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/03/23 05:48:56 DEBUG : two: Unchanged skipping 2021/03/23 05:48:56 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7 path dst: Waiting for checks to finish 2021/03/23 05:48:56 DEBUG : Couldn't decode error response: EOF 2021/03/23 05:48:57 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/03/23 05:48:57 INFO : one: Copied (server-side copy) 2021/03/23 05:48:58 INFO : one: Deleted 2021/03/23 05:48:58 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7 path dst: Waiting for transfers to finish 2021/03/23 05:48:59 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/03/23 05:48:59 INFO : one: Copied (new) 2021/03/23 05:48:59 DEBUG : Waiting for deletions to finish 2021/03/23 05:48:59 DEBUG : Couldn't decode error response: EOF 2021/03/23 05:49:00 DEBUG : three.txt: SHA-1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/03/23 05:49:00 INFO : three.txt: Copied (server-side copy) 2021/03/23 05:49:01 INFO : three.txt: Deleted 2021/03/23 05:49:01 INFO : three.txt: Moved into backup dir 2021/03/23 05:49:02 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/03/23 05:49:02 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/03/23 05:49:02 DEBUG : two: Unchanged skipping 2021/03/23 05:49:02 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7 path dst: Waiting for checks to finish 2021/03/23 05:49:03 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/03/23 05:49:03 INFO : one: Copied (server-side copy) 2021/03/23 05:49:03 INFO : one: Deleted 2021/03/23 05:49:03 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7 path dst: Waiting for transfers to finish 2021/03/23 05:49:04 DEBUG : one: SHA-1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2021/03/23 05:49:04 INFO : one: Copied (new) 2021/03/23 05:49:04 DEBUG : Waiting for deletions to finish 2021/03/23 05:49:04 DEBUG : three.txt: SHA-1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2021/03/23 05:49:04 INFO : three.txt: Copied (server-side copy) 2021/03/23 05:49:05 INFO : three.txt: Deleted 2021/03/23 05:49:05 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (16.80s) === RUN TestSyncBackupDirWithSuffix run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" 2021/03/23 05:49:15 DEBUG : Creating backend with remote "TestB2:rclone-test-hewakob7zaloweg0zaciyal7/dst" 2021/03/23 05:49:17 DEBUG : Couldn't decode error response: EOF 2021/03/23 05:49:17 DEBUG : Creating backend with remote "TestB2:rclone-test-hewakob7zaloweg0zaciyal7/backup" 2021/03/23 05:49:17 DEBUG : Couldn't decode error response: EOF 2021/03/23 05:49:17 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/03/23 05:49:17 DEBUG : two: Unchanged skipping 2021/03/23 05:49:17 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7 path dst: Waiting for checks to finish 2021/03/23 05:49:17 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/03/23 05:49:17 DEBUG : Couldn't decode error response: EOF 2021/03/23 05:49:18 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/03/23 05:49:18 INFO : one: Copied (server-side copy) to: one.bak 2021/03/23 05:49:19 INFO : one: Deleted 2021/03/23 05:49:19 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7 path dst: Waiting for transfers to finish 2021/03/23 05:49:20 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/03/23 05:49:20 INFO : one: Copied (new) 2021/03/23 05:49:20 DEBUG : Waiting for deletions to finish 2021/03/23 05:49:20 DEBUG : Couldn't decode error response: EOF 2021/03/23 05:49:20 DEBUG : three.txt: SHA-1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/03/23 05:49:20 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2021/03/23 05:49:21 INFO : three.txt: Deleted 2021/03/23 05:49:21 INFO : three.txt: Moved into backup dir 2021/03/23 05:49:23 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/03/23 05:49:23 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/03/23 05:49:23 DEBUG : two: Unchanged skipping 2021/03/23 05:49:23 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7 path dst: Waiting for checks to finish 2021/03/23 05:49:31 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/03/23 05:49:31 INFO : one: Copied (server-side copy) to: one.bak 2021/03/23 05:49:31 INFO : one: Deleted 2021/03/23 05:49:31 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7 path dst: Waiting for transfers to finish 2021/03/23 05:49:31 DEBUG : one: SHA-1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2021/03/23 05:49:31 INFO : one: Copied (new) 2021/03/23 05:49:31 DEBUG : Waiting for deletions to finish 2021/03/23 05:49:33 DEBUG : three.txt: SHA-1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2021/03/23 05:49:33 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2021/03/23 05:49:33 INFO : three.txt: Deleted 2021/03/23 05:49:33 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (29.68s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" 2021/03/23 05:49:40 DEBUG : Creating backend with remote "TestB2:rclone-test-hewakob7zaloweg0zaciyal7/dst" 2021/03/23 05:49:41 DEBUG : Couldn't decode error response: EOF 2021/03/23 05:49:41 DEBUG : Creating backend with remote "TestB2:rclone-test-hewakob7zaloweg0zaciyal7/backup" 2021/03/23 05:49:42 DEBUG : Couldn't decode error response: EOF 2021/03/23 05:49:42 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/03/23 05:49:42 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7 path dst: Waiting for checks to finish 2021/03/23 05:49:42 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/03/23 05:49:42 DEBUG : two: Unchanged skipping 2021/03/23 05:49:42 DEBUG : Couldn't decode error response: EOF 2021/03/23 05:49:46 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/03/23 05:49:46 INFO : one: Copied (server-side copy) to: one-2019-01-01 2021/03/23 05:49:47 INFO : one: Deleted 2021/03/23 05:49:47 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7 path dst: Waiting for transfers to finish 2021/03/23 05:49:48 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/03/23 05:49:48 INFO : one: Copied (new) 2021/03/23 05:49:48 DEBUG : Waiting for deletions to finish 2021/03/23 05:49:49 DEBUG : Couldn't decode error response: EOF 2021/03/23 05:49:49 DEBUG : three.txt: SHA-1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/03/23 05:49:49 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2021/03/23 05:49:50 INFO : three.txt: Deleted 2021/03/23 05:49:50 INFO : three.txt: Moved into backup dir 2021/03/23 05:49:51 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/03/23 05:49:51 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/03/23 05:49:51 DEBUG : two: Unchanged skipping 2021/03/23 05:49:51 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7 path dst: Waiting for checks to finish 2021/03/23 05:49:51 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/03/23 05:49:51 INFO : one: Copied (server-side copy) to: one-2019-01-01 2021/03/23 05:49:52 INFO : one: Deleted 2021/03/23 05:49:52 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7 path dst: Waiting for transfers to finish 2021/03/23 05:49:52 DEBUG : one: SHA-1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2021/03/23 05:49:52 INFO : one: Copied (new) 2021/03/23 05:49:52 DEBUG : Waiting for deletions to finish 2021/03/23 05:49:54 DEBUG : three.txt: SHA-1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2021/03/23 05:49:54 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2021/03/23 05:49:54 INFO : three.txt: Deleted 2021/03/23 05:49:54 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (24.91s) === RUN TestSyncBackupDirSuffixOnly run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" 2021/03/23 05:50:05 DEBUG : Creating backend with remote "TestB2:rclone-test-hewakob7zaloweg0zaciyal7/dst" 2021/03/23 05:50:06 DEBUG : Couldn't decode error response: EOF 2021/03/23 05:50:07 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/03/23 05:50:07 DEBUG : two: Unchanged skipping 2021/03/23 05:50:07 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7 path dst: Waiting for checks to finish 2021/03/23 05:50:07 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/03/23 05:50:07 DEBUG : Couldn't decode error response: EOF 2021/03/23 05:50:07 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/03/23 05:50:07 INFO : one: Copied (server-side copy) to: one.bak 2021/03/23 05:50:07 INFO : one: Deleted 2021/03/23 05:50:07 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7 path dst: Waiting for transfers to finish 2021/03/23 05:50:08 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/03/23 05:50:08 INFO : one: Copied (new) 2021/03/23 05:50:08 DEBUG : Waiting for deletions to finish 2021/03/23 05:50:08 DEBUG : Couldn't decode error response: EOF 2021/03/23 05:50:08 DEBUG : three.txt: SHA-1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/03/23 05:50:08 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2021/03/23 05:50:09 INFO : three.txt: Deleted 2021/03/23 05:50:09 INFO : three.txt: Moved into backup dir 2021/03/23 05:50:09 DEBUG : one.bak: Excluded from sync (and deletion) 2021/03/23 05:50:09 DEBUG : three.txt.bak: Excluded from sync (and deletion) 2021/03/23 05:50:09 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/03/23 05:50:09 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/03/23 05:50:09 DEBUG : two: Unchanged skipping 2021/03/23 05:50:09 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7 path dst: Waiting for checks to finish 2021/03/23 05:50:10 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/03/23 05:50:10 INFO : one: Copied (server-side copy) to: one.bak 2021/03/23 05:50:10 INFO : one: Deleted 2021/03/23 05:50:10 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7 path dst: Waiting for transfers to finish 2021/03/23 05:50:11 DEBUG : one: SHA-1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2021/03/23 05:50:11 INFO : one: Copied (new) 2021/03/23 05:50:11 DEBUG : Waiting for deletions to finish 2021/03/23 05:50:11 DEBUG : three.txt: SHA-1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2021/03/23 05:50:11 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2021/03/23 05:50:11 INFO : three.txt: Deleted 2021/03/23 05:50:11 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirSuffixOnly (11.24s) === RUN TestSyncSuffix run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" 2021/03/23 05:50:16 DEBUG : Creating backend with remote "TestB2:rclone-test-hewakob7zaloweg0zaciyal7/dst" 2021/03/23 05:50:17 DEBUG : Couldn't decode error response: EOF 2021/03/23 05:50:17 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/03/23 05:50:17 DEBUG : Couldn't decode error response: EOF 2021/03/23 05:50:18 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/03/23 05:50:18 INFO : one: Copied (server-side copy) to: one.bak 2021/03/23 05:50:19 INFO : one: Deleted 2021/03/23 05:50:21 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/03/23 05:50:21 INFO : one: Copied (new) 2021/03/23 05:50:21 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/03/23 05:50:21 DEBUG : two: Unchanged skipping 2021/03/23 05:50:21 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2021/03/23 05:50:21 DEBUG : Couldn't decode error response: EOF 2021/03/23 05:50:22 DEBUG : three.txt: SHA-1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/03/23 05:50:22 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2021/03/23 05:50:22 INFO : three.txt: Deleted 2021/03/23 05:50:23 DEBUG : three.txt: SHA-1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2021/03/23 05:50:23 INFO : three.txt: Copied (new) 2021/03/23 05:50:23 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/03/23 05:50:24 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/03/23 05:50:24 INFO : one: Copied (server-side copy) to: one.bak 2021/03/23 05:50:24 INFO : one: Deleted 2021/03/23 05:50:25 DEBUG : one: SHA-1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2021/03/23 05:50:25 INFO : one: Copied (new) 2021/03/23 05:50:25 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/03/23 05:50:25 DEBUG : two: Unchanged skipping 2021/03/23 05:50:25 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2021/03/23 05:50:26 DEBUG : three.txt: SHA-1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2021/03/23 05:50:26 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2021/03/23 05:50:27 INFO : three.txt: Deleted 2021/03/23 05:50:27 DEBUG : three.txt: SHA-1 = 476e1707cbd7f0497a7dc4d44f0b07b8566ffc30 OK 2021/03/23 05:50:27 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (17.94s) === RUN TestSyncSuffixKeepExtension run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" 2021/03/23 05:50:36 DEBUG : Creating backend with remote "TestB2:rclone-test-hewakob7zaloweg0zaciyal7/dst" 2021/03/23 05:50:37 DEBUG : Couldn't decode error response: EOF 2021/03/23 05:50:37 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/03/23 05:50:37 DEBUG : Couldn't decode error response: EOF 2021/03/23 05:50:38 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/03/23 05:50:38 INFO : one: Copied (server-side copy) to: one-2019-01-01 2021/03/23 05:50:39 INFO : one: Deleted 2021/03/23 05:50:40 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/03/23 05:50:40 INFO : one: Copied (new) 2021/03/23 05:50:40 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/03/23 05:50:40 DEBUG : two: Unchanged skipping 2021/03/23 05:50:40 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2021/03/23 05:50:40 DEBUG : Couldn't decode error response: EOF 2021/03/23 05:50:41 DEBUG : three.txt: SHA-1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/03/23 05:50:41 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2021/03/23 05:50:41 INFO : three.txt: Deleted 2021/03/23 05:50:42 DEBUG : three.txt: SHA-1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2021/03/23 05:50:42 INFO : three.txt: Copied (new) 2021/03/23 05:50:42 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/03/23 05:50:43 DEBUG : one: SHA-1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2021/03/23 05:50:43 INFO : one: Copied (server-side copy) to: one-2019-01-01 2021/03/23 05:50:44 INFO : one: Deleted 2021/03/23 05:50:45 DEBUG : one: SHA-1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2021/03/23 05:50:45 INFO : one: Copied (new) 2021/03/23 05:50:45 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/03/23 05:50:45 DEBUG : two: Unchanged skipping 2021/03/23 05:50:45 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2021/03/23 05:50:47 DEBUG : three.txt: SHA-1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2021/03/23 05:50:47 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2021/03/23 05:50:47 INFO : three.txt: Deleted 2021/03/23 05:50:48 DEBUG : three.txt: SHA-1 = 476e1707cbd7f0497a7dc4d44f0b07b8566ffc30 OK 2021/03/23 05:50:48 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (20.49s) === RUN TestSyncUTFNorm run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" 2021/03/23 05:50:54 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2021/03/23 05:50:54 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for checks to finish 2021/03/23 05:50:54 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for transfers to finish 2021/03/23 05:50:54 DEBUG : Testêé: SHA-1 = a54d88e06612d820bc3be72877c74f257b561b19 OK 2021/03/23 05:50:54 INFO : Testêé: Copied (replaced existing) to: Testêé 2021/03/23 05:50:54 DEBUG : Waiting for deletions to finish --- PASS: TestSyncUTFNorm (2.24s) === RUN TestSyncImmutable run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" 2021/03/23 05:50:55 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for checks to finish 2021/03/23 05:50:55 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for transfers to finish 2021/03/23 05:50:56 DEBUG : existing: SHA-1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2021/03/23 05:50:56 INFO : existing: Copied (new) 2021/03/23 05:50:56 DEBUG : Waiting for deletions to finish 2021/03/23 05:50:56 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2021/03/23 05:50:56 ERROR : existing: Source and destination exist but do not match: immutable file modified 2021/03/23 05:50:56 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for checks to finish 2021/03/23 05:50:56 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for transfers to finish 2021/03/23 05:50:56 ERROR : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: not deleting files as there were IO errors 2021/03/23 05:50:56 ERROR : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: not deleting directories as there were IO errors --- PASS: TestSyncImmutable (2.28s) === RUN TestSyncIgnoreCase run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" 2021/03/23 05:50:58 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for checks to finish 2021/03/23 05:50:58 DEBUG : existing: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/03/23 05:50:58 DEBUG : existing: Unchanged skipping 2021/03/23 05:50:58 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Waiting for transfers to finish 2021/03/23 05:50:58 DEBUG : Waiting for deletions to finish 2021/03/23 05:50:58 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreCase (1.38s) === RUN TestMaxTransfer === RUN TestMaxTransfer/Hard run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" sync_test.go:1941: This test only runs on local === RUN TestMaxTransfer/Soft run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" sync_test.go:1941: This test only runs on local === RUN TestMaxTransfer/Cautious run.go:176: Remote "B2 bucket rclone-test-hewakob7zaloweg0zaciyal7", Local "Local file system at /tmp/rclone284180761", Modify Window "1ms" sync_test.go:1941: This test only runs on local --- PASS: TestMaxTransfer (0.88s) --- SKIP: TestMaxTransfer/Hard (0.29s) --- SKIP: TestMaxTransfer/Soft (0.29s) --- SKIP: TestMaxTransfer/Cautious (0.29s) PASS 2021/03/23 05:50:59 DEBUG : B2 bucket rclone-test-hewakob7zaloweg0zaciyal7: Purge remote 2021/03/23 05:51:00 DEBUG : BackupDir/one: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1172811256d9d335_d20210323_m054846_c001_v0001136_t0029") 2021/03/23 05:51:00 DEBUG : BackupDir/one: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f113a781ec9a6d2cd_d20210323_m054839_c001_v0001113_t0019") 2021/03/23 05:51:00 DEBUG : CompareDest/one: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1172811256d9d2f2_d20210323_m054823_c001_v0001136_t0019") 2021/03/23 05:51:00 DEBUG : CompareDest/one: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1075db27773168c2_d20210323_m054818_c001_v0001092_t0047") 2021/03/23 05:51:00 DEBUG : CompareDest/two: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f10542b839ce8c0e9_d20210323_m054824_c001_v0001128_t0035") 2021/03/23 05:51:00 DEBUG : CompareDest/two: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc673e_d20210323_m054820_c001_v0001153_t0012") 2021/03/23 05:51:00 DEBUG : CopyDest/one: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f101911ed2cf267b6_d20210323_m054846_c001_v0001130_t0031") 2021/03/23 05:51:00 DEBUG : CopyDest/one: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1085b9f4676785ac_d20210323_m054837_c001_v0001134_t0015") 2021/03/23 05:51:00 DEBUG : CopyDest/three: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f10390b1aafae63c3_d20210323_m054846_c001_v0001134_t0050") 2021/03/23 05:51:00 DEBUG : CopyDest/three: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc6825_d20210323_m054844_c001_v0001153_t0012") 2021/03/23 05:51:00 DEBUG : CopyDest/two: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f104c013991128745_d20210323_m054847_c001_v0001000_t0033") 2021/03/23 05:51:00 DEBUG : CopyDest/two: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1075db27773168da_d20210323_m054841_c001_v0001092_t0047") 2021/03/23 05:51:00 DEBUG : EXISTING: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f109ae25dc9283952_d20210323_m055058_c001_v0001146_t0043") 2021/03/23 05:51:00 DEBUG : EXISTING: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1075db277731697b_d20210323_m055057_c001_v0001092_t0047") 2021/03/23 05:51:00 DEBUG : Testêé: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f113dd2c39ffdbcb2_d20210323_m055055_c001_v0001150_t0038") 2021/03/23 05:51:00 DEBUG : Testêé: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc6d7f_d20210323_m055054_c001_v0001153_t0012") 2021/03/23 05:51:01 DEBUG : Testêé: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1075db2777316975_d20210323_m055053_c001_v0001092_t0047") 2021/03/23 05:51:01 DEBUG : a/potato2: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f116ff81daa528825_d20210323_m054514_c001_v0001154_t0010") 2021/03/23 05:51:01 DEBUG : a/potato2: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc5fe8_d20210323_m054513_c001_v0001153_t0012") 2021/03/23 05:51:01 DEBUG : a/potato2: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f107188d0b3a390c0_d20210323_m054509_c001_v0001145_t0024") 2021/03/23 05:51:01 DEBUG : a/potato2: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc5fa7_d20210323_m054508_c001_v0001153_t0012") 2021/03/23 05:51:01 DEBUG : a/potato2: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1018fcab280ef658_d20210323_m054444_c001_v0001011_t0030") 2021/03/23 05:51:01 DEBUG : a/potato2: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc5e5e_d20210323_m054441_c001_v0001153_t0012") 2021/03/23 05:51:01 DEBUG : b/potato: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1062ad9e17ac8e6d_d20210323_m054514_c001_v0001008_t0015") 2021/03/23 05:51:01 DEBUG : b/potato: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc5fc8_d20210323_m054511_c001_v0001153_t0012") 2021/03/23 05:51:01 DEBUG : b/potato: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1165597508285c19_d20210323_m054508_c001_v0001128_t0040") 2021/03/23 05:51:01 DEBUG : b/potato: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc5f95_d20210323_m054507_c001_v0001153_t0012") 2021/03/23 05:51:01 DEBUG : b/potato: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f104ef8b9c28ce9b8_d20210323_m054442_c001_v0001040_t0042") 2021/03/23 05:51:01 DEBUG : b/potato: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc5e4c_d20210323_m054440_c001_v0001153_t0012") 2021/03/23 05:51:01 DEBUG : backup/one: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1186dac98d22e4ca_d20210323_m054905_c001_v0001036_t0032") 2021/03/23 05:51:01 DEBUG : backup/one: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f103fe609699f8e1e_d20210323_m054902_c001_v0001128_t0054") 2021/03/23 05:51:01 DEBUG : backup/one: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1054c88c8eb7708b_d20210323_m054857_c001_v0001030_t0021") 2021/03/23 05:51:01 DEBUG : backup/one-2019-01-01: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f118637ba5d2b3607_d20210323_m054955_c001_v0001038_t0003") 2021/03/23 05:51:01 DEBUG : backup/one-2019-01-01: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f116f4216b05c1e3a_d20210323_m054951_c001_v0001032_t0010") 2021/03/23 05:51:01 DEBUG : backup/one-2019-01-01: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1011b7cda97069b7_d20210323_m054943_c001_v0001012_t0012") 2021/03/23 05:51:01 DEBUG : backup/one.bak: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1106aba288104999_d20210323_m054933_c001_v0001029_t0024") 2021/03/23 05:51:01 DEBUG : backup/one.bak: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1109cfcfd8114f0f_d20210323_m054930_c001_v0001150_t0039") 2021/03/23 05:51:01 DEBUG : backup/one.bak: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f112b005cbed0138f_d20210323_m054918_c001_v0001153_t0016") 2021/03/23 05:51:02 DEBUG : backup/three-2019-01-01.txt: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1026d2904d2129b0_d20210323_m054955_c001_v0001145_t0003") 2021/03/23 05:51:02 DEBUG : backup/three-2019-01-01.txt: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1089b63378528f6f_d20210323_m054953_c001_v0001120_t0054") 2021/03/23 05:51:02 DEBUG : backup/three-2019-01-01.txt: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1062a055e359076f_d20210323_m054949_c001_v0001144_t0024") 2021/03/23 05:51:02 DEBUG : backup/three.txt: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1162d06fa69e93e2_d20210323_m054906_c001_v0001144_t0055") 2021/03/23 05:51:02 DEBUG : backup/three.txt: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1080832082fd73f7_d20210323_m054904_c001_v0001036_t0004") 2021/03/23 05:51:02 DEBUG : backup/three.txt: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f105931ac7805e8e2_d20210323_m054859_c001_v0001144_t0046") 2021/03/23 05:51:02 DEBUG : backup/three.txt.bak: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1138875e9dfbc538_d20210323_m054934_c001_v0001146_t0038") 2021/03/23 05:51:02 DEBUG : backup/three.txt.bak: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f102eaf0485ee503d_d20210323_m054932_c001_v0001144_t0011") 2021/03/23 05:51:02 DEBUG : backup/three.txt.bak: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f11423d1d57b05bd6_d20210323_m054920_c001_v0001017_t0009") 2021/03/23 05:51:02 DEBUG : c/non empty space: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f10836ef4d701dc86_d20210323_m054514_c001_v0001093_t0023") 2021/03/23 05:51:02 DEBUG : c/non empty space: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc5fd2_d20210323_m054512_c001_v0001153_t0012") 2021/03/23 05:51:02 DEBUG : c/non empty space: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f118a78446b1f9878_d20210323_m054511_c001_v0001153_t0000") 2021/03/23 05:51:02 DEBUG : c/non empty space: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc5f9d_d20210323_m054507_c001_v0001153_t0012") 2021/03/23 05:51:02 DEBUG : c/non empty space: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f110874bace5d4f8c_d20210323_m054445_c001_v0001000_t0016") 2021/03/23 05:51:02 DEBUG : c/non empty space: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc5e53_d20210323_m054441_c001_v0001153_t0012") 2021/03/23 05:51:02 DEBUG : check sum: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f10375e9541641692_d20210323_m054432_c001_v0001150_t0038") 2021/03/23 05:51:02 DEBUG : check sum: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc5dd1_d20210323_m054431_c001_v0001153_t0012") 2021/03/23 05:51:02 DEBUG : dest/3: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f116004b81d78c2fa_d20210323_m054830_c001_v0001042_t0036") 2021/03/23 05:51:02 DEBUG : dest/3: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f113bbfbf95d4a468_d20210323_m054829_c001_v0001131_t0041") 2021/03/23 05:51:02 DEBUG : dst/one: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1058a95e71306cd5_d20210323_m055050_c001_v0001092_t0034") 2021/03/23 05:51:02 DEBUG : dst/one: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1170a2919b648658_d20210323_m055044_c001_v0001145_t0003") 2021/03/23 05:51:02 DEBUG : dst/one: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f110f5ed18886cb88_d20210323_m055043_c001_v0001145_t0034") 2021/03/23 05:51:02 DEBUG : dst/one: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1170a2919b648638_d20210323_m055039_c001_v0001145_t0003") 2021/03/23 05:51:02 DEBUG : dst/one: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f109d8229e98e2aab_d20210323_m055038_c001_v0001015_t0013") 2021/03/23 05:51:02 DEBUG : dst/one: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1075db277731695f_d20210323_m055033_c001_v0001092_t0047") 2021/03/23 05:51:02 DEBUG : dst/one: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f116f4216b05c1e6e_d20210323_m055027_c001_v0001032_t0033") 2021/03/23 05:51:03 DEBUG : dst/one: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f112740f8515285a7_d20210323_m055025_c001_v0001029_t0044") 2021/03/23 05:51:03 DEBUG : dst/one: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f11385b12feee007a_d20210323_m055024_c001_v0001093_t0009") 2021/03/23 05:51:03 DEBUG : dst/one: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f112740f85152859e_d20210323_m055020_c001_v0001029_t0044") 2021/03/23 05:51:03 DEBUG : dst/one: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f111f5bab8bfbd97d_d20210323_m055019_c001_v0001134_t0045") 2021/03/23 05:51:03 DEBUG : dst/one: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1075db2777316937_d20210323_m055015_c001_v0001092_t0047") 2021/03/23 05:51:03 DEBUG : dst/one: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1133b0fde177c0ad_d20210323_m055012_c001_v0001144_t0005") 2021/03/23 05:51:03 DEBUG : dst/one: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1085b9f467678666_d20210323_m055011_c001_v0001134_t0029") 2021/03/23 05:51:03 DEBUG : dst/one: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f11357209058b661c_d20210323_m055010_c001_v0001128_t0029") 2021/03/23 05:51:03 DEBUG : dst/one: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1085b9f46767865c_d20210323_m055008_c001_v0001134_t0029") 2021/03/23 05:51:03 DEBUG : dst/one: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f110327b2c151cceb_d20210323_m055007_c001_v0001134_t0048") 2021/03/23 05:51:03 DEBUG : dst/one: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1085b9f467678652_d20210323_m055003_c001_v0001134_t0015") 2021/03/23 05:51:03 DEBUG : dst/one: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1026d2904d2129b3_d20210323_m054956_c001_v0001145_t0003") 2021/03/23 05:51:03 DEBUG : dst/one: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f11428a2e9341606d_d20210323_m054952_c001_v0001090_t0057") 2021/03/23 05:51:03 DEBUG : dst/one: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1158444164ca88fb_d20210323_m054951_c001_v0001090_t0055") 2021/03/23 05:51:03 DEBUG : dst/one: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f11428a2e9341605b_d20210323_m054948_c001_v0001090_t0057") 2021/03/23 05:51:03 DEBUG : dst/one: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1052cf8f1d4eef93_d20210323_m054947_c001_v0001012_t0020") 2021/03/23 05:51:03 DEBUG : dst/one: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc6a5f_d20210323_m054938_c001_v0001153_t0012") 2021/03/23 05:51:03 DEBUG : dst/one: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f109d62022781fbac_d20210323_m054937_c001_v0001154_t0025") 2021/03/23 05:51:03 DEBUG : dst/one: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1050f417da73d4ea_d20210323_m054932_c001_v0001113_t0053") 2021/03/23 05:51:03 DEBUG : dst/one: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f107432225d9d4b40_d20210323_m054931_c001_v0001154_t0004") 2021/03/23 05:51:03 DEBUG : dst/one: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1050f417da73d4da_d20210323_m054919_c001_v0001113_t0053") 2021/03/23 05:51:03 DEBUG : dst/one: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f10050b7cabf95617_d20210323_m054918_c001_v0001144_t0010") 2021/03/23 05:51:03 DEBUG : dst/one: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1075db27773168ed_d20210323_m054909_c001_v0001092_t0047") 2021/03/23 05:51:03 DEBUG : dst/one: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f10734f3b2df94fdf_d20210323_m054907_c001_v0001006_t0002") 2021/03/23 05:51:04 DEBUG : dst/one: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f10050b7cabf95563_d20210323_m054904_c001_v0001144_t0000") 2021/03/23 05:51:04 DEBUG : dst/one: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1192efd3cf680f69_d20210323_m054903_c001_v0001145_t0048") 2021/03/23 05:51:04 DEBUG : dst/one: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f10050b7cabf95520_d20210323_m054858_c001_v0001144_t0000") 2021/03/23 05:51:04 DEBUG : dst/one: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1150351d17ca3d71_d20210323_m054857_c001_v0001144_t0031") 2021/03/23 05:51:04 DEBUG : dst/one: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1085b9f4676785d3_d20210323_m054852_c001_v0001134_t0015") 2021/03/23 05:51:04 DEBUG : dst/one: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f118835e0c7297869_d20210323_m054847_c001_v0001040_t0058") 2021/03/23 05:51:04 DEBUG : dst/one: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f10556d722af4a19f_d20210323_m054840_c001_v0001146_t0034") 2021/03/23 05:51:04 DEBUG : dst/one: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1013c5c3e388f5ee_d20210323_m054839_c001_v0001022_t0018") 2021/03/23 05:51:04 DEBUG : dst/one: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc67de_d20210323_m054836_c001_v0001153_t0012") 2021/03/23 05:51:04 DEBUG : dst/one: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f11919c9e1bae0809_d20210323_m054835_c001_v0001033_t0003") 2021/03/23 05:51:04 DEBUG : dst/one: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f11919c9e1bae0807_d20210323_m054834_c001_v0001033_t0003") 2021/03/23 05:51:04 DEBUG : dst/one: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117b75ff78cfb5b2_d20210323_m054824_c001_v0001154_t0002") 2021/03/23 05:51:04 DEBUG : dst/one: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1085b9f467678525_d20210323_m054744_c001_v0001134_t0015") 2021/03/23 05:51:04 DEBUG : dst/one: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1049deb92b8e86f5_d20210323_m054743_c001_v0001136_t0008") 2021/03/23 05:51:04 DEBUG : dst/one: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1049deb92b8e86ef_d20210323_m054742_c001_v0001136_t0008") 2021/03/23 05:51:04 DEBUG : dst/one-2019-01-01: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f108770ae723d0605_d20210323_m055050_c001_v0001146_t0017") 2021/03/23 05:51:04 DEBUG : dst/one-2019-01-01: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f116035246a3e9330_d20210323_m055042_c001_v0001008_t0023") 2021/03/23 05:51:04 DEBUG : dst/one-2019-01-01: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f118b4949d6d3a38b_d20210323_m055038_c001_v0001150_t0041") 2021/03/23 05:51:04 DEBUG : dst/one.bak: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f102a082622c2a46a_d20210323_m055030_c001_v0001136_t0033") 2021/03/23 05:51:04 DEBUG : dst/one.bak: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f10095b9343e13835_d20210323_m055023_c001_v0001136_t0036") 2021/03/23 05:51:04 DEBUG : dst/one.bak: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f100e7b821ca4a5a7_d20210323_m055018_c001_v0001137_t0045") 2021/03/23 05:51:04 DEBUG : dst/one.bak: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f10873b6f43a3516f_d20210323_m055013_c001_v0001154_t0001") 2021/03/23 05:51:04 DEBUG : dst/one.bak: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f114339e7a4c4d860_d20210323_m055010_c001_v0001113_t0021") 2021/03/23 05:51:04 DEBUG : dst/one.bak: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1022bb06063891a0_d20210323_m055007_c001_v0001036_t0007") 2021/03/23 05:51:04 DEBUG : dst/three: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f116f4216b05c1dd9_d20210323_m054849_c001_v0001032_t0020") 2021/03/23 05:51:05 DEBUG : dst/three: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f11919c9e1bae0827_d20210323_m054845_c001_v0001033_t0003") 2021/03/23 05:51:05 DEBUG : dst/three-2019-01-01.txt: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1138875e9dfbc6a8_d20210323_m055050_c001_v0001146_t0008") 2021/03/23 05:51:05 DEBUG : dst/three-2019-01-01.txt: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f118711b7ae81ce9d_d20210323_m055046_c001_v0001144_t0040") 2021/03/23 05:51:05 DEBUG : dst/three-2019-01-01.txt: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f11425151a1c47287_d20210323_m055040_c001_v0001092_t0005") 2021/03/23 05:51:05 DEBUG : dst/three.txt: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f113171fe4aa3cfd1_d20210323_m055051_c001_v0001026_t0043") 2021/03/23 05:51:05 DEBUG : dst/three.txt: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1170a2919b648669_d20210323_m055048_c001_v0001145_t0003") 2021/03/23 05:51:05 DEBUG : dst/three.txt: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f10375e954164225d_d20210323_m055047_c001_v0001150_t0049") 2021/03/23 05:51:05 DEBUG : dst/three.txt: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1170a2919b648642_d20210323_m055042_c001_v0001145_t0003") 2021/03/23 05:51:05 DEBUG : dst/three.txt: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1006d559a9140f25_d20210323_m055041_c001_v0001146_t0045") 2021/03/23 05:51:05 DEBUG : dst/three.txt: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1085b9f4676786b4_d20210323_m055035_c001_v0001134_t0015") 2021/03/23 05:51:05 DEBUG : dst/three.txt: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f105125c2428cbbad_d20210323_m055031_c001_v0001090_t0050") 2021/03/23 05:51:05 DEBUG : dst/three.txt: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f112740f8515285ab_d20210323_m055027_c001_v0001029_t0044") 2021/03/23 05:51:05 DEBUG : dst/three.txt: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f113327735c77796b_d20210323_m055026_c001_v0001137_t0058") 2021/03/23 05:51:05 DEBUG : dst/three.txt: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f112740f8515285a1_d20210323_m055023_c001_v0001029_t0044") 2021/03/23 05:51:05 DEBUG : dst/three.txt: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1150351d17ca405f_d20210323_m055022_c001_v0001144_t0037") 2021/03/23 05:51:05 DEBUG : dst/three.txt: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1085b9f467678677_d20210323_m055015_c001_v0001134_t0015") 2021/03/23 05:51:05 DEBUG : dst/three.txt: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1117a89692fad503_d20210323_m055011_c001_v0001137_t0021") 2021/03/23 05:51:05 DEBUG : dst/three.txt: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1085b9f467678660_d20210323_m055009_c001_v0001134_t0015") 2021/03/23 05:51:05 DEBUG : dst/three.txt: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f105c4fdc1bc30073_d20210323_m055008_c001_v0001150_t0040") 2021/03/23 05:51:05 DEBUG : dst/three.txt: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc6b5d_d20210323_m055005_c001_v0001153_t0012") 2021/03/23 05:51:05 DEBUG : dst/three.txt: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f114309e36abb3bd1_d20210323_m054954_c001_v0001153_t0018") 2021/03/23 05:51:05 DEBUG : dst/three.txt: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc6ae2_d20210323_m054950_c001_v0001153_t0012") 2021/03/23 05:51:05 DEBUG : dst/three.txt: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f100997f0728fc103_d20210323_m054949_c001_v0001153_t0005") 2021/03/23 05:51:05 DEBUG : dst/three.txt: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1075db2777316915_d20210323_m054940_c001_v0001092_t0047") 2021/03/23 05:51:06 DEBUG : dst/three.txt: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f10197c823ce636dc_d20210323_m054933_c001_v0001131_t0018") 2021/03/23 05:51:06 DEBUG : dst/three.txt: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1075db27773168fc_d20210323_m054922_c001_v0001092_t0047") 2021/03/23 05:51:06 DEBUG : dst/three.txt: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f11449aaea0597780_d20210323_m054921_c001_v0001150_t0021") 2021/03/23 05:51:06 DEBUG : dst/three.txt: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1085b9f46767860b_d20210323_m054914_c001_v0001134_t0015") 2021/03/23 05:51:06 DEBUG : dst/three.txt: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f111145f8a0c31719_d20210323_m054904_c001_v0001044_t0026") 2021/03/23 05:51:06 DEBUG : dst/three.txt: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1085b9f4676785e8_d20210323_m054901_c001_v0001134_t0015") 2021/03/23 05:51:06 DEBUG : dst/three.txt: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1111e3717ac0b83e_d20210323_m054900_c001_v0001120_t0002") 2021/03/23 05:51:06 DEBUG : dst/three.txt: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc6881_d20210323_m054854_c001_v0001153_t0012") 2021/03/23 05:51:06 DEBUG : dst/three.txt.bak: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1176d9a750dfdef4_d20210323_m055031_c001_v0001037_t0016") 2021/03/23 05:51:06 DEBUG : dst/three.txt.bak: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f110f5ed18886cb10_d20210323_m055025_c001_v0001145_t0021") 2021/03/23 05:51:06 DEBUG : dst/three.txt.bak: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1005f35fd45e10d8_d20210323_m055021_c001_v0001006_t0019") 2021/03/23 05:51:06 DEBUG : dst/three.txt.bak: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f107ecc40a2090de9_d20210323_m055013_c001_v0001130_t0043") 2021/03/23 05:51:06 DEBUG : dst/three.txt.bak: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1002993a37c09426_d20210323_m055011_c001_v0001092_t0024") 2021/03/23 05:51:06 DEBUG : dst/three.txt.bak: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f10691bfbdbba1272_d20210323_m055008_c001_v0001033_t0019") 2021/03/23 05:51:06 DEBUG : dst/two: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f116c8fb04d7f6844_d20210323_m055052_c001_v0001150_t0021") 2021/03/23 05:51:06 DEBUG : dst/two: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc6ccb_d20210323_m055035_c001_v0001153_t0012") 2021/03/23 05:51:06 DEBUG : dst/two: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f108af6e52f0c0e47_d20210323_m055032_c001_v0001090_t0058") 2021/03/23 05:51:06 DEBUG : dst/two: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc6bf7_d20210323_m055015_c001_v0001153_t0012") 2021/03/23 05:51:06 DEBUG : dst/two: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f113f7bda525db964_d20210323_m055013_c001_v0001120_t0032") 2021/03/23 05:51:06 DEBUG : dst/two: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1075db277731692d_d20210323_m055004_c001_v0001092_t0047") 2021/03/23 05:51:06 DEBUG : dst/two: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f10846082ef386615_d20210323_m054956_c001_v0001033_t0033") 2021/03/23 05:51:06 DEBUG : dst/two: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1085b9f467678633_d20210323_m054939_c001_v0001134_t0015") 2021/03/23 05:51:06 DEBUG : dst/two: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f112fa89b49bddcf6_d20210323_m054938_c001_v0001092_t0052") 2021/03/23 05:51:06 DEBUG : dst/two: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc6937_d20210323_m054913_c001_v0001153_t0012") 2021/03/23 05:51:06 DEBUG : dst/two: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1084c02db5a8b393_d20210323_m054908_c001_v0001136_t0005") 2021/03/23 05:51:07 DEBUG : dst/two: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1075db27773168e5_d20210323_m054853_c001_v0001092_t0047") 2021/03/23 05:51:07 DEBUG : dst/two: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1152bd7703142e87_d20210323_m054851_c001_v0001120_t0002") 2021/03/23 05:51:07 DEBUG : dst/two: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1194e706a2a8494e_d20210323_m054842_c001_v0001128_t0052") 2021/03/23 05:51:07 DEBUG : dst/two: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f118f2e0700e5de3d_d20210323_m054824_c001_v0001134_t0002") 2021/03/23 05:51:07 DEBUG : dst/two: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1049deb92b8e875a_d20210323_m054823_c001_v0001136_t0008") 2021/03/23 05:51:07 DEBUG : empty space: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f119cd9b714fbf0fa_d20210323_m054657_c001_v0001146_t0002") 2021/03/23 05:51:07 DEBUG : empty space: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1085b9f4676784ca_d20210323_m054653_c001_v0001134_t0015") 2021/03/23 05:51:07 DEBUG : empty space: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f112b005cbed00fb2_d20210323_m054651_c001_v0001153_t0021") 2021/03/23 05:51:07 DEBUG : empty space: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1085b9f4676784ac_d20210323_m054640_c001_v0001134_t0015") 2021/03/23 05:51:07 DEBUG : empty space: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f109e3f51667da3d8_d20210323_m054633_c001_v0001032_t0041") 2021/03/23 05:51:07 DEBUG : empty space: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1085b9f467678486_d20210323_m054629_c001_v0001134_t0015") 2021/03/23 05:51:07 DEBUG : empty space: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f101672b04c384b9a_d20210323_m054530_c001_v0001006_t0038") 2021/03/23 05:51:07 DEBUG : empty space: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc60e4_d20210323_m054528_c001_v0001153_t0012") 2021/03/23 05:51:07 DEBUG : empty space: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f100fc559dc78391f_d20210323_m054526_c001_v0001022_t0026") 2021/03/23 05:51:07 DEBUG : empty space: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc60b1_d20210323_m054525_c001_v0001153_t0012") 2021/03/23 05:51:07 DEBUG : empty space: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f110ff7cce95248d2_d20210323_m054521_c001_v0001137_t0029") 2021/03/23 05:51:07 DEBUG : empty space: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc604b_d20210323_m054519_c001_v0001153_t0012") 2021/03/23 05:51:07 DEBUG : empty space: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f111145f8a0c315f4_d20210323_m054517_c001_v0001044_t0051") 2021/03/23 05:51:07 DEBUG : empty space: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc6019_d20210323_m054516_c001_v0001153_t0012") 2021/03/23 05:51:07 DEBUG : empty space: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f116ff81daa5287e4_d20210323_m054505_c001_v0001154_t0000") 2021/03/23 05:51:07 DEBUG : empty space: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc5f6e_d20210323_m054504_c001_v0001153_t0012") 2021/03/23 05:51:07 DEBUG : empty space: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1071811d6fefc119_d20210323_m054503_c001_v0001150_t0037") 2021/03/23 05:51:07 DEBUG : empty space: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc5f56_d20210323_m054502_c001_v0001153_t0012") 2021/03/23 05:51:07 DEBUG : empty space: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f118585d188646f6a_d20210323_m054454_c001_v0001033_t0017") 2021/03/23 05:51:07 DEBUG : empty space: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc5ee7_d20210323_m054453_c001_v0001153_t0012") 2021/03/23 05:51:07 DEBUG : empty space: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1017ed1998df7d85_d20210323_m054450_c001_v0001145_t0033") 2021/03/23 05:51:08 DEBUG : empty space: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc5eb3_d20210323_m054449_c001_v0001153_t0012") 2021/03/23 05:51:08 DEBUG : empty space: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1023c08a8b47b5e4_d20210323_m054448_c001_v0001150_t0024") 2021/03/23 05:51:08 DEBUG : empty space: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1196d6586bec34fa_d20210323_m054447_c001_v0001144_t0011") 2021/03/23 05:51:08 DEBUG : empty space: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc5e98_d20210323_m054445_c001_v0001153_t0012") 2021/03/23 05:51:08 DEBUG : enormous: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1148a27da2d42e20_d20210323_m054529_c001_v0001144_t0015") 2021/03/23 05:51:08 DEBUG : enormous: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc60e6_d20210323_m054528_c001_v0001153_t0012") 2021/03/23 05:51:08 DEBUG : existing: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117aeb519f2e8ba9_d20210323_m055057_c001_v0001004_t0026") 2021/03/23 05:51:08 DEBUG : existing: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1085b9f467678732_d20210323_m055056_c001_v0001134_t0015") 2021/03/23 05:51:08 DEBUG : existing: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f10050b7cabf9494e_d20210323_m054441_c001_v0001144_t0028") 2021/03/23 05:51:08 DEBUG : existing: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc5e3e_d20210323_m054440_c001_v0001153_t0012") 2021/03/23 05:51:08 DEBUG : existing: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f109744491b1b2158_d20210323_m054439_c001_v0001137_t0010") 2021/03/23 05:51:08 DEBUG : existing: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc5e27_d20210323_m054438_c001_v0001153_t0012") 2021/03/23 05:51:08 DEBUG : existing: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc5e1e_d20210323_m054437_c001_v0001153_t0012") 2021/03/23 05:51:08 DEBUG : five: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f109f8a894fa47394_d20210323_m054535_c001_v0001134_t0046") 2021/03/23 05:51:08 DEBUG : five: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1085b9f467678376_d20210323_m054533_c001_v0001134_t0015") 2021/03/23 05:51:08 DEBUG : foo: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1019d6b2c83152d9_d20210323_m054452_c001_v0001044_t0054") 2021/03/23 05:51:08 DEBUG : foo: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc5ed3_d20210323_m054451_c001_v0001153_t0012") 2021/03/23 05:51:08 DEBUG : foo: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc5ec8_d20210323_m054450_c001_v0001153_t0012") 2021/03/23 05:51:08 DEBUG : four: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f10809a150193660a_d20210323_m054535_c001_v0001113_t0041") 2021/03/23 05:51:08 DEBUG : four: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc611e_d20210323_m054533_c001_v0001153_t0012") 2021/03/23 05:51:08 DEBUG : four: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc6115_d20210323_m054532_c001_v0001153_t0012") 2021/03/23 05:51:08 DEBUG : hello world2: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f103136ba1502d02e_d20210323_m054403_c001_v0001146_t0055") 2021/03/23 05:51:08 DEBUG : hello world2: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc5cc0_d20210323_m054402_c001_v0001153_t0012") 2021/03/23 05:51:08 DEBUG : ignore-size: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f113408fd6bbbe2d3_d20210323_m054436_c001_v0001145_t0001") 2021/03/23 05:51:08 DEBUG : ignore-size: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc5e05_d20210323_m054435_c001_v0001153_t0012") 2021/03/23 05:51:08 DEBUG : nested/sub dir/file: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1061d152b0740fa6_d20210323_m054627_c001_v0001146_t0032") 2021/03/23 05:51:09 DEBUG : nested/sub dir/file: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1085b9f467678455_d20210323_m054622_c001_v0001134_t0015") 2021/03/23 05:51:09 DEBUG : nested/sub dir/file: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f108770ae723cffd2_d20210323_m054621_c001_v0001146_t0056") 2021/03/23 05:51:09 DEBUG : nested/sub dir/file: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1085b9f46767843d_d20210323_m054620_c001_v0001134_t0015") 2021/03/23 05:51:09 DEBUG : one: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f105b3fb8f67f7746_d20210323_m054537_c001_v0001145_t0059") 2021/03/23 05:51:09 DEBUG : one: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc60fb_d20210323_m054530_c001_v0001153_t0012") 2021/03/23 05:51:09 DEBUG : potato: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f118f9e39b0de100a_d20210323_m054619_c001_v0001005_t0002") 2021/03/23 05:51:09 DEBUG : potato: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1075db2777316838_d20210323_m054616_c001_v0001092_t0047") 2021/03/23 05:51:09 DEBUG : potato: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1071811d6fefc53d_d20210323_m054615_c001_v0001150_t0024") 2021/03/23 05:51:09 DEBUG : potato: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc61ac_d20210323_m054547_c001_v0001153_t0012") 2021/03/23 05:51:09 DEBUG : potato: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1111e3717ac0b645_d20210323_m054545_c001_v0001120_t0012") 2021/03/23 05:51:09 DEBUG : potato: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1085b9f467678387_d20210323_m054542_c001_v0001134_t0015") 2021/03/23 05:51:09 DEBUG : potato: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1117a89692facd8a_d20210323_m054523_c001_v0001137_t0054") 2021/03/23 05:51:09 DEBUG : potato: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc6081_d20210323_m054522_c001_v0001153_t0012") 2021/03/23 05:51:09 DEBUG : potato: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f10050b7cabf94b34_d20210323_m054520_c001_v0001144_t0038") 2021/03/23 05:51:09 DEBUG : potato: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc6047_d20210323_m054519_c001_v0001153_t0012") 2021/03/23 05:51:09 DEBUG : potato: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f105931ac7805e250_d20210323_m054516_c001_v0001144_t0050") 2021/03/23 05:51:09 DEBUG : potato: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc600f_d20210323_m054515_c001_v0001153_t0012") 2021/03/23 05:51:09 DEBUG : potato: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f112933499cda6a00_d20210323_m054506_c001_v0001025_t0037") 2021/03/23 05:51:09 DEBUG : potato: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc5f6a_d20210323_m054505_c001_v0001153_t0012") 2021/03/23 05:51:09 DEBUG : potato: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f100997f0728fb633_d20210323_m054504_c001_v0001153_t0005") 2021/03/23 05:51:09 DEBUG : potato: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc5f4e_d20210323_m054503_c001_v0001153_t0012") 2021/03/23 05:51:09 DEBUG : potato: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1109f0d3615908af_d20210323_m054502_c001_v0001144_t0028") 2021/03/23 05:51:09 DEBUG : potato: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc5f38_d20210323_m054501_c001_v0001153_t0012") 2021/03/23 05:51:09 DEBUG : potato: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc5f26_d20210323_m054500_c001_v0001153_t0012") 2021/03/23 05:51:10 DEBUG : potato: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1081524cdee6161e_d20210323_m054459_c001_v0001130_t0033") 2021/03/23 05:51:10 DEBUG : potato: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc5f1a_d20210323_m054458_c001_v0001153_t0012") 2021/03/23 05:51:10 DEBUG : potato: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc5f0f_d20210323_m054457_c001_v0001153_t0012") 2021/03/23 05:51:10 DEBUG : potato: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f115a840f7c915202_d20210323_m054456_c001_v0001039_t0053") 2021/03/23 05:51:10 DEBUG : potato: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc5eed_d20210323_m054453_c001_v0001153_t0012") 2021/03/23 05:51:10 DEBUG : potato2: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f10809a150193673e_d20210323_m054737_c001_v0001113_t0056") 2021/03/23 05:51:10 DEBUG : potato2: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc648e_d20210323_m054707_c001_v0001153_t0012") 2021/03/23 05:51:10 DEBUG : potato2: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1071811d6fefc790_d20210323_m054657_c001_v0001150_t0035") 2021/03/23 05:51:10 DEBUG : potato2: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc6403_d20210323_m054653_c001_v0001153_t0012") 2021/03/23 05:51:10 DEBUG : potato2: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f119b29ce15ca7e76_d20210323_m054644_c001_v0001040_t0008") 2021/03/23 05:51:10 DEBUG : potato2: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc6392_d20210323_m054639_c001_v0001153_t0012") 2021/03/23 05:51:10 DEBUG : potato2: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1170a2919b647fea_d20210323_m054633_c001_v0001145_t0013") 2021/03/23 05:51:10 DEBUG : potato2: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc6335_d20210323_m054629_c001_v0001153_t0012") 2021/03/23 05:51:10 DEBUG : potato2: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f112735831f4059a8_d20210323_m054529_c001_v0001145_t0052") 2021/03/23 05:51:10 DEBUG : potato2: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc60de_d20210323_m054527_c001_v0001153_t0012") 2021/03/23 05:51:10 DEBUG : potato2: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1192efd3cf680a72_d20210323_m054526_c001_v0001145_t0056") 2021/03/23 05:51:10 DEBUG : potato2: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc60a7_d20210323_m054525_c001_v0001153_t0012") 2021/03/23 05:51:10 DEBUG : potato2: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f105dc88b69e25da5_d20210323_m054524_c001_v0001038_t0024") 2021/03/23 05:51:10 DEBUG : potato2: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc608d_d20210323_m054523_c001_v0001153_t0012") 2021/03/23 05:51:10 DEBUG : potato2: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f107188d0b3a39125_d20210323_m054521_c001_v0001145_t0049") 2021/03/23 05:51:10 DEBUG : potato2: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc606b_d20210323_m054520_c001_v0001153_t0012") 2021/03/23 05:51:10 DEBUG : potato2: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f118d22ca94fb85b1_d20210323_m054518_c001_v0001145_t0005") 2021/03/23 05:51:10 DEBUG : potato2: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc6027_d20210323_m054516_c001_v0001153_t0012") 2021/03/23 05:51:10 DEBUG : potato2: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f10050b7cabf94a90_d20210323_m054506_c001_v0001144_t0055") 2021/03/23 05:51:11 DEBUG : potato2: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc5f78_d20210323_m054504_c001_v0001153_t0012") 2021/03/23 05:51:11 DEBUG : potato2: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f10976203e15487fb_d20210323_m054408_c001_v0001153_t0012") 2021/03/23 05:51:11 DEBUG : potato2: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc5ce4_d20210323_m054407_c001_v0001153_t0012") 2021/03/23 05:51:11 DEBUG : potato2: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1065c48a29db46d2_d20210323_m054405_c001_v0001029_t0021") 2021/03/23 05:51:11 DEBUG : potato2: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc5cd0_d20210323_m054404_c001_v0001153_t0012") 2021/03/23 05:51:11 DEBUG : potato3: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f11679405867dc45e_d20210323_m054657_c001_v0001146_t0039") 2021/03/23 05:51:11 DEBUG : potato3: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1075db2777316867_d20210323_m054654_c001_v0001092_t0047") 2021/03/23 05:51:11 DEBUG : potato3: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f107188d0b3a392ee_d20210323_m054644_c001_v0001145_t0030") 2021/03/23 05:51:11 DEBUG : potato3: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1075db2777316850_d20210323_m054640_c001_v0001092_t0047") 2021/03/23 05:51:11 DEBUG : potato3: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f102a0103bac5f0ac_d20210323_m054634_c001_v0001131_t0015") 2021/03/23 05:51:11 DEBUG : potato3: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1075db2777316844_d20210323_m054630_c001_v0001092_t0047") 2021/03/23 05:51:11 DEBUG : pre-dest1/1: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f109d62022781fa47_d20210323_m054831_c001_v0001154_t0015") 2021/03/23 05:51:11 DEBUG : pre-dest1/1: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1085b9f467678583_d20210323_m054825_c001_v0001134_t0015") 2021/03/23 05:51:11 DEBUG : pre-dest2/2: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f111bd41afff124fa_d20210323_m054831_c001_v0001146_t0030") 2021/03/23 05:51:11 DEBUG : pre-dest2/2: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1075db27773168ca_d20210323_m054826_c001_v0001092_t0047") 2021/03/23 05:51:11 DEBUG : sizeonly: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f112d14380545085b_d20210323_m054434_c001_v0001021_t0028") 2021/03/23 05:51:11 DEBUG : sizeonly: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc5de1_d20210323_m054432_c001_v0001153_t0012") 2021/03/23 05:51:11 DEBUG : sub dir/hello world: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f115398d626c50a2b_d20210323_m054627_c001_v0001154_t0000") 2021/03/23 05:51:11 DEBUG : sub dir/hello world: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1075db277731683e_d20210323_m054623_c001_v0001092_t0047") 2021/03/23 05:51:11 DEBUG : sub dir/hello world: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1040bef337db3793_d20210323_m054621_c001_v0001145_t0039") 2021/03/23 05:51:11 DEBUG : sub dir/hello world: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc62d8_d20210323_m054620_c001_v0001153_t0012") 2021/03/23 05:51:11 DEBUG : sub dir/hello world: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1108d405518fa59c_d20210323_m054430_c001_v0001153_t0021") 2021/03/23 05:51:11 DEBUG : sub dir/hello world: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc5db5_d20210323_m054429_c001_v0001153_t0012") 2021/03/23 05:51:11 DEBUG : sub dir/hello world: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f116f79b550dba2d9_d20210323_m054428_c001_v0001004_t0001") 2021/03/23 05:51:12 DEBUG : sub dir/hello world: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc5da2_d20210323_m054427_c001_v0001153_t0012") 2021/03/23 05:51:12 DEBUG : sub dir/hello world: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1127029e6a4505b0_d20210323_m054426_c001_v0001144_t0005") 2021/03/23 05:51:12 DEBUG : sub dir/hello world: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc5d6f_d20210323_m054423_c001_v0001153_t0012") 2021/03/23 05:51:12 DEBUG : sub dir/hello world: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f112b005cbed00c3d_d20210323_m054422_c001_v0001153_t0016") 2021/03/23 05:51:12 DEBUG : sub dir/hello world: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc5d5b_d20210323_m054421_c001_v0001153_t0012") 2021/03/23 05:51:12 DEBUG : sub dir/hello world: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1073f0eca1ad248a_d20210323_m054412_c001_v0001134_t0030") 2021/03/23 05:51:12 DEBUG : sub dir/hello world: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc5cfe_d20210323_m054411_c001_v0001153_t0012") 2021/03/23 05:51:12 DEBUG : sub dir/hello world: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f102a0103bac5ef59_d20210323_m054410_c001_v0001131_t0002") 2021/03/23 05:51:12 DEBUG : sub dir/hello world: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc5cf6_d20210323_m054409_c001_v0001153_t0012") 2021/03/23 05:51:12 DEBUG : sub dir/hello world: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f112c79508feb5212_d20210323_m054402_c001_v0001031_t0000") 2021/03/23 05:51:12 DEBUG : sub dir/hello world: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc5cb3_d20210323_m054401_c001_v0001153_t0012") 2021/03/23 05:51:12 DEBUG : sub dir/hello world: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f11212e36ea89232d_d20210323_m054400_c001_v0001040_t0013") 2021/03/23 05:51:12 DEBUG : sub dir/hello world: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc5ca6_d20210323_m054359_c001_v0001153_t0012") 2021/03/23 05:51:12 DEBUG : sub dir/hello world: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1040bef337db344e_d20210323_m054358_c001_v0001145_t0035") 2021/03/23 05:51:12 DEBUG : sub dir/hello world: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc5c98_d20210323_m054357_c001_v0001153_t0012") 2021/03/23 05:51:12 DEBUG : sub dir/hello world: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f11666013c0e2e5d3_d20210323_m054356_c001_v0001131_t0052") 2021/03/23 05:51:12 DEBUG : sub dir/hello world: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc5c8d_d20210323_m054355_c001_v0001153_t0012") 2021/03/23 05:51:12 DEBUG : sub/yam: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f10180639df537aa0_d20210323_m054618_c001_v0001144_t0005") 2021/03/23 05:51:12 DEBUG : sub/yam: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1085b9f467678426_d20210323_m054616_c001_v0001134_t0015") 2021/03/23 05:51:12 DEBUG : three: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1173b3c34f080d1e_d20210323_m054537_c001_v0001033_t0053") 2021/03/23 05:51:12 DEBUG : three: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc6128_d20210323_m054534_c001_v0001153_t0012") 2021/03/23 05:51:12 DEBUG : three: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc610b_d20210323_m054531_c001_v0001153_t0012") 2021/03/23 05:51:13 DEBUG : two: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f11816833d165c129_d20210323_m054541_c001_v0001154_t0017") 2021/03/23 05:51:13 DEBUG : two: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1075db27773167ee_d20210323_m054533_c001_v0001092_t0047") 2021/03/23 05:51:13 DEBUG : two: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f117a9e7d7dcc6105_d20210323_m054531_c001_v0001153_t0012") 2021/03/23 05:51:13 DEBUG : yam: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f100c5d791ea8b57e_d20210323_m054619_c001_v0001003_t0019") 2021/03/23 05:51:13 DEBUG : yam: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f105ca7d8d1ec4801_d20210323_m054618_c001_v0001131_t0049") 2021/03/23 05:51:13 DEBUG : yam: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f107188d0b3a39262_d20210323_m054614_c001_v0001145_t0018") 2021/03/23 05:51:13 DEBUG : yam: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1085b9f467678397_d20210323_m054547_c001_v0001134_t0015") 2021/03/23 05:51:13 DEBUG : yam: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f119ce80fc218bced_d20210323_m054544_c001_v0001130_t0009") 2021/03/23 05:51:13 DEBUG : yam: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1075db27773167f4_d20210323_m054542_c001_v0001092_t0047") 2021/03/23 05:51:13 DEBUG : yaml: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f11799b8b3e6cb5ca_d20210323_m054615_c001_v0001137_t0011") 2021/03/23 05:51:13 DEBUG : yaml: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f101a91a9f1a8ded5_d20210323_m054613_c001_v0001015_t0004") 2021/03/23 05:51:13 DEBUG : yaml: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f1114585d315668fb_d20210323_m054546_c001_v0001017_t0007") 2021/03/23 05:51:13 DEBUG : yaml: Deleting (id "4_zcf526d5390cd5bdb7f880e1b_f11025d41383a11fc_d20210323_m054543_c001_v0001131_t0051") "./sync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -fast-list" - Finished OK in 7m22.037485147s (try 1/5)