"./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -fast-list" - Starting (try 1/5) 2022/03/15 07:01:00 DEBUG : Creating backend with remote "TestB2:rclone-test-fuyeluy3xifunot5bobewef3" 2022/03/15 07:01:00 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2022/03/15 07:01:00 DEBUG : Creating backend with remote "/tmp/rclone3911360152" === RUN TestDoMultiThreadCopy --- PASS: TestDoMultiThreadCopy (0.00s) === RUN TestMultithreadCalculateChunks === RUN TestMultithreadCalculateChunks/{size:1_streams:10_wantPartSize:65536_wantStreams:1} === RUN TestMultithreadCalculateChunks/{size:1048576_streams:1_wantPartSize:1048576_wantStreams:1} === RUN TestMultithreadCalculateChunks/{size:1048576_streams:2_wantPartSize:524288_wantStreams:2} === RUN TestMultithreadCalculateChunks/{size:1048577_streams:2_wantPartSize:589824_wantStreams:2} === RUN TestMultithreadCalculateChunks/{size:1048575_streams:2_wantPartSize:524288_wantStreams:2} --- PASS: TestMultithreadCalculateChunks (0.00s) --- PASS: TestMultithreadCalculateChunks/{size:1_streams:10_wantPartSize:65536_wantStreams:1} (0.00s) --- PASS: TestMultithreadCalculateChunks/{size:1048576_streams:1_wantPartSize:1048576_wantStreams:1} (0.00s) --- PASS: TestMultithreadCalculateChunks/{size:1048576_streams:2_wantPartSize:524288_wantStreams:2} (0.00s) --- PASS: TestMultithreadCalculateChunks/{size:1048577_streams:2_wantPartSize:589824_wantStreams:2} (0.00s) --- PASS: TestMultithreadCalculateChunks/{size:1048575_streams:2_wantPartSize:524288_wantStreams:2} (0.00s) === RUN TestMultithreadCopy run.go:181: Remote "B2 bucket rclone-test-fuyeluy3xifunot5bobewef3", Local "Local file system at /tmp/rclone3911360152", Modify Window "1ms" === RUN TestMultithreadCopy/{size:131071_streams:2} 2022/03/15 07:01:06 DEBUG : file1: Starting multi-thread copy with 2 parts of size 64Ki 2022/03/15 07:01:06 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131071) size 63.999Ki starting 2022/03/15 07:01:06 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64Ki starting 2022/03/15 07:01:07 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131071) size 63.999Ki finished 2022/03/15 07:01:07 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64Ki finished 2022/03/15 07:01:07 DEBUG : file1: Finished multi-thread copy with 2 parts of size 64Ki === RUN TestMultithreadCopy/{size:131072_streams:2} 2022/03/15 07:01:09 DEBUG : file1: Starting multi-thread copy with 2 parts of size 64Ki 2022/03/15 07:01:09 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64Ki starting 2022/03/15 07:01:09 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131072) size 64Ki starting 2022/03/15 07:01:09 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64Ki finished 2022/03/15 07:01:09 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131072) size 64Ki finished 2022/03/15 07:01:09 DEBUG : file1: Finished multi-thread copy with 2 parts of size 64Ki === RUN TestMultithreadCopy/{size:131073_streams:2} 2022/03/15 07:01:10 DEBUG : file1: Starting multi-thread copy with 2 parts of size 128Ki 2022/03/15 07:01:10 DEBUG : file1: multi-thread copy: stream 2/2 (131072-131073) size 1 starting 2022/03/15 07:01:10 DEBUG : file1: multi-thread copy: stream 1/2 (0-131072) size 128Ki starting 2022/03/15 07:01:10 DEBUG : file1: multi-thread copy: stream 1/2 (0-131072) size 128Ki finished 2022/03/15 07:01:10 DEBUG : file1: multi-thread copy: stream 2/2 (131072-131073) size 1 finished 2022/03/15 07:01:10 DEBUG : file1: Finished multi-thread copy with 2 parts of size 128Ki --- PASS: TestMultithreadCopy (13.87s) --- PASS: TestMultithreadCopy/{size:131071_streams:2} (6.77s) --- PASS: TestMultithreadCopy/{size:131072_streams:2} (1.49s) --- PASS: TestMultithreadCopy/{size:131073_streams:2} (1.71s) === RUN TestSizeDiffers --- PASS: TestSizeDiffers (0.00s) === RUN TestReOpen === RUN TestReOpen/Seek === RUN TestReOpen/Seek/Basics === RUN TestReOpen/Seek/ErrorAtStart === RUN TestReOpen/Seek/WithErrors 2022/03/15 07:01:14 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/10: test error 2022/03/15 07:01:14 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/10: test error 2022/03/15 07:01:14 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/10: test error === RUN TestReOpen/Seek/TooManyErrors 2022/03/15 07:01:14 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/3: test error 2022/03/15 07:01:14 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/3: test error 2022/03/15 07:01:14 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/3: test error 2022/03/15 07:01:14 DEBUG : potato: Reopen failed after 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/Range === RUN TestReOpen/Range/Basics === RUN TestReOpen/Range/ErrorAtStart === RUN TestReOpen/Range/WithErrors 2022/03/15 07:01:14 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/10: test error 2022/03/15 07:01:14 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/10: test error 2022/03/15 07:01:14 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/10: test error === RUN TestReOpen/Range/TooManyErrors 2022/03/15 07:01:14 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/3: test error 2022/03/15 07:01:14 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/3: test error 2022/03/15 07:01:14 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/3: test error 2022/03/15 07:01:14 DEBUG : potato: Reopen failed after 6 bytes read: failed to reopen: too many retries --- PASS: TestReOpen (0.00s) --- PASS: TestReOpen/Seek (0.00s) --- PASS: TestReOpen/Seek/Basics (0.00s) --- PASS: TestReOpen/Seek/ErrorAtStart (0.00s) --- PASS: TestReOpen/Seek/WithErrors (0.00s) --- PASS: TestReOpen/Seek/TooManyErrors (0.00s) --- PASS: TestReOpen/Range (0.00s) --- PASS: TestReOpen/Range/Basics (0.00s) --- PASS: TestReOpen/Range/ErrorAtStart (0.00s) --- PASS: TestReOpen/Range/WithErrors (0.00s) --- PASS: TestReOpen/Range/TooManyErrors (0.00s) === RUN TestCheck run.go:181: Remote "B2 bucket rclone-test-fuyeluy3xifunot5bobewef3", Local "Local file system at /tmp/rclone3911360152", Modify Window "1ms" === RUN TestCheck/1 === RUN TestCheck/2 === RUN TestCheck/3 === RUN TestCheck/4 === RUN TestCheck/5 === RUN TestCheck/6 === RUN TestCheck/7 --- PASS: TestCheck (8.34s) --- PASS: TestCheck/1 (0.17s) --- PASS: TestCheck/2 (0.17s) --- PASS: TestCheck/3 (0.17s) --- PASS: TestCheck/4 (0.17s) --- PASS: TestCheck/5 (0.17s) --- PASS: TestCheck/6 (0.17s) --- PASS: TestCheck/7 (0.17s) === RUN TestCheckFsError 2022/03/15 07:01:23 DEBUG : Creating backend with remote "non-existent" 2022/03/15 07:01:23 DEBUG : Creating backend with remote "non-existent" 2022/03/15 07:01:23 DEBUG : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/non-existent: Waiting for checks to finish 2022/03/15 07:01:23 ERROR : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/non-existent: error reading source root directory: directory not found 2022/03/15 07:01:23 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/non-existent: 2 differences found 2022/03/15 07:01:23 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/non-existent: 2 errors while checking --- PASS: TestCheckFsError (0.00s) === RUN TestCheckDownload run.go:181: Remote "B2 bucket rclone-test-fuyeluy3xifunot5bobewef3", Local "Local file system at /tmp/rclone3911360152", Modify Window "1ms" === RUN TestCheckDownload/1 === RUN TestCheckDownload/2 === RUN TestCheckDownload/3 === RUN TestCheckDownload/4 === RUN TestCheckDownload/5 === RUN TestCheckDownload/6 === RUN TestCheckDownload/7 --- PASS: TestCheckDownload (8.52s) --- PASS: TestCheckDownload/1 (0.86s) --- PASS: TestCheckDownload/2 (0.35s) --- PASS: TestCheckDownload/3 (0.34s) --- PASS: TestCheckDownload/4 (0.52s) --- PASS: TestCheckDownload/5 (0.36s) --- PASS: TestCheckDownload/6 (0.39s) --- PASS: TestCheckDownload/7 (0.39s) === RUN TestCheckSizeOnly run.go:181: Remote "B2 bucket rclone-test-fuyeluy3xifunot5bobewef3", Local "Local file system at /tmp/rclone3911360152", Modify Window "1ms" === RUN TestCheckSizeOnly/1 === RUN TestCheckSizeOnly/2 === RUN TestCheckSizeOnly/3 === RUN TestCheckSizeOnly/4 === RUN TestCheckSizeOnly/5 === RUN TestCheckSizeOnly/6 === RUN TestCheckSizeOnly/7 --- PASS: TestCheckSizeOnly (11.94s) --- PASS: TestCheckSizeOnly/1 (0.17s) --- PASS: TestCheckSizeOnly/2 (0.19s) --- PASS: TestCheckSizeOnly/3 (0.17s) --- PASS: TestCheckSizeOnly/4 (0.17s) --- PASS: TestCheckSizeOnly/5 (0.17s) --- PASS: TestCheckSizeOnly/6 (0.17s) --- PASS: TestCheckSizeOnly/7 (0.17s) === RUN TestCheckEqualReaders --- PASS: TestCheckEqualReaders (0.00s) === RUN TestParseSumFile run.go:181: Remote "B2 bucket rclone-test-fuyeluy3xifunot5bobewef3", Local "Local file system at /tmp/rclone3911360152", Modify Window "1ms" 2022/03/15 07:01:46 NOTICE: test.sum: improperly formatted checksum line 4 2022/03/15 07:01:46 NOTICE: test.sum: improperly formatted checksum line 5 2022/03/15 07:01:46 NOTICE: test.sum: improperly formatted checksum line 6 2022/03/15 07:01:46 NOTICE: test.sum: 2 warning(s) suppressed... 2022/03/15 07:01:50 NOTICE: test.sum: improperly formatted checksum line 4 2022/03/15 07:01:50 NOTICE: test.sum: improperly formatted checksum line 5 2022/03/15 07:01:50 NOTICE: test.sum: improperly formatted checksum line 6 2022/03/15 07:01:50 NOTICE: test.sum: 2 warning(s) suppressed... --- PASS: TestParseSumFile (8.61s) === RUN TestCheckSum run.go:181: Remote "B2 bucket rclone-test-fuyeluy3xifunot5bobewef3", Local "Local file system at /tmp/rclone3911360152", Modify Window "1ms" 2022/03/15 07:01:52 DEBUG : Creating backend with remote "TestB2:rclone-test-fuyeluy3xifunot5bobewef3/data" 2022/03/15 07:01:53 DEBUG : Couldn't decode error response: EOF check_test.go:356: B2 bucket rclone-test-fuyeluy3xifunot5bobewef3 path data lacks md5, skipping --- SKIP: TestCheckSum (1.28s) === RUN TestCheckSumDownload run.go:181: Remote "B2 bucket rclone-test-fuyeluy3xifunot5bobewef3", Local "Local file system at /tmp/rclone3911360152", Modify Window "1ms" 2022/03/15 07:01:53 DEBUG : Creating backend with remote "TestB2:rclone-test-fuyeluy3xifunot5bobewef3/data" 2022/03/15 07:01:53 DEBUG : Couldn't decode error response: EOF === RUN TestCheckSumDownload/subtest1 === RUN TestCheckSumDownload/subtest2 === RUN TestCheckSumDownload/subtest3 === RUN TestCheckSumDownload/subtest4 === RUN TestCheckSumDownload/subtest5 === RUN TestCheckSumDownload/subtest6 === RUN TestCheckSumDownload/subtest7 --- PASS: TestCheckSumDownload (23.59s) --- PASS: TestCheckSumDownload/subtest1 (1.10s) --- PASS: TestCheckSumDownload/subtest2 (0.72s) --- PASS: TestCheckSumDownload/subtest3 (1.18s) --- PASS: TestCheckSumDownload/subtest4 (0.73s) --- PASS: TestCheckSumDownload/subtest5 (0.77s) --- PASS: TestCheckSumDownload/subtest6 (0.72s) --- PASS: TestCheckSumDownload/subtest7 (0.73s) === RUN TestDeduplicateInteractive run.go:181: Remote "B2 bucket rclone-test-fuyeluy3xifunot5bobewef3", Local "Local file system at /tmp/rclone3911360152", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateInteractive (0.37s) === RUN TestDeduplicateSkip run.go:181: Remote "B2 bucket rclone-test-fuyeluy3xifunot5bobewef3", Local "Local file system at /tmp/rclone3911360152", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSkip (0.36s) === RUN TestDeduplicateSizeOnly run.go:181: Remote "B2 bucket rclone-test-fuyeluy3xifunot5bobewef3", Local "Local file system at /tmp/rclone3911360152", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSizeOnly (0.37s) === RUN TestDeduplicateFirst run.go:181: Remote "B2 bucket rclone-test-fuyeluy3xifunot5bobewef3", Local "Local file system at /tmp/rclone3911360152", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateFirst (0.35s) === RUN TestDeduplicateNewest run.go:181: Remote "B2 bucket rclone-test-fuyeluy3xifunot5bobewef3", Local "Local file system at /tmp/rclone3911360152", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateNewest (0.36s) === RUN TestDeduplicateNewestByHash run.go:181: Remote "B2 bucket rclone-test-fuyeluy3xifunot5bobewef3", Local "Local file system at /tmp/rclone3911360152", Modify Window "1ms" 2022/03/15 07:02:22 INFO : B2 bucket rclone-test-fuyeluy3xifunot5bobewef3: Looking for duplicate sha1 hashes using newest mode. 2022/03/15 07:02:22 NOTICE: 5206b75e074cc174211ab50de6bcf8262ba15e68: Found 3 files with duplicate sha1 hashes 2022/03/15 07:02:22 INFO : one: Deleted 2022/03/15 07:02:23 INFO : also/one: Deleted 2022/03/15 07:02:23 NOTICE: 5206b75e074cc174211ab50de6bcf8262ba15e68: Deleted 2 extra copies --- PASS: TestDeduplicateNewestByHash (6.37s) === RUN TestDeduplicateOldest run.go:181: Remote "B2 bucket rclone-test-fuyeluy3xifunot5bobewef3", Local "Local file system at /tmp/rclone3911360152", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateOldest (0.39s) === RUN TestDeduplicateLargest run.go:181: Remote "B2 bucket rclone-test-fuyeluy3xifunot5bobewef3", Local "Local file system at /tmp/rclone3911360152", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateLargest (0.36s) === RUN TestDeduplicateSmallest run.go:181: Remote "B2 bucket rclone-test-fuyeluy3xifunot5bobewef3", Local "Local file system at /tmp/rclone3911360152", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSmallest (0.36s) === RUN TestDeduplicateRename run.go:181: Remote "B2 bucket rclone-test-fuyeluy3xifunot5bobewef3", Local "Local file system at /tmp/rclone3911360152", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateRename (0.36s) === RUN TestMergeDirs run.go:181: Remote "B2 bucket rclone-test-fuyeluy3xifunot5bobewef3", Local "Local file system at /tmp/rclone3911360152", Modify Window "1ms" dedupe_test.go:267: Can't merge directories --- SKIP: TestMergeDirs (0.37s) === RUN TestListDirSorted run.go:181: Remote "B2 bucket rclone-test-fuyeluy3xifunot5bobewef3", Local "Local file system at /tmp/rclone3911360152", Modify Window "1ms" 2022/03/15 07:02:36 DEBUG : a.txt: Excluded 2022/03/15 07:02:36 DEBUG : sub dir/hello world: Excluded 2022/03/15 07:02:36 DEBUG : sub dir/hello world2: Excluded 2022/03/15 07:02:37 DEBUG : sub dir/hello world: Excluded 2022/03/15 07:02:37 DEBUG : sub dir/hello world2: Excluded 2022/03/15 07:02:37 DEBUG : sub dir/ignore dir: Excluded 2022/03/15 07:02:38 DEBUG : Couldn't decode error response: EOF 2022/03/15 07:02:38 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSorted (47.01s) === RUN TestListJSON run.go:181: Remote "B2 bucket rclone-test-fuyeluy3xifunot5bobewef3", Local "Local file system at /tmp/rclone3911360152", Modify Window "1ms" === RUN TestListJSON/Default === RUN TestListJSON/FilesOnly === RUN TestListJSON/DirsOnly === RUN TestListJSON/Recurse === RUN TestListJSON/SubDir === RUN TestListJSON/NoModTime === RUN TestListJSON/NoMimeType === RUN TestListJSON/ShowHash === RUN TestListJSON/HashTypes 2022/03/15 07:03:18 ERROR : file1: Failed to read hash: hash type not supported --- PASS: TestListJSON (5.87s) --- PASS: TestListJSON/Default (0.18s) --- PASS: TestListJSON/FilesOnly (0.18s) --- PASS: TestListJSON/DirsOnly (0.18s) --- PASS: TestListJSON/Recurse (0.18s) --- PASS: TestListJSON/SubDir (0.18s) --- PASS: TestListJSON/NoModTime (0.18s) --- PASS: TestListJSON/NoMimeType (0.18s) --- PASS: TestListJSON/ShowHash (0.18s) --- PASS: TestListJSON/HashTypes (0.18s) === RUN TestStatJSON run.go:181: Remote "B2 bucket rclone-test-fuyeluy3xifunot5bobewef3", Local "Local file system at /tmp/rclone3911360152", Modify Window "1ms" === RUN TestStatJSON/Root === RUN TestStatJSON/RootFilesOnly === RUN TestStatJSON/RootDirsOnly === RUN TestStatJSON/Dir 2022/03/15 07:03:22 DEBUG : Couldn't decode error response: EOF === RUN TestStatJSON/File === RUN TestStatJSON/NotFound 2022/03/15 07:03:23 DEBUG : Couldn't decode error response: EOF === RUN TestStatJSON/DirFilesOnly 2022/03/15 07:03:23 DEBUG : Couldn't decode error response: EOF === RUN TestStatJSON/FileFilesOnly === RUN TestStatJSON/NotFoundFilesOnly 2022/03/15 07:03:24 DEBUG : Couldn't decode error response: EOF === RUN TestStatJSON/DirDirsOnly === RUN TestStatJSON/FileDirsOnly === RUN TestStatJSON/NotFoundDirsOnly === RUN TestStatJSON/RootNotFound 2022/03/15 07:03:24 DEBUG : Creating backend with remote "TestB2:rclone-test-fuyeluy3xifunot5bobewef3/notfound" 2022/03/15 07:03:25 DEBUG : Couldn't decode error response: EOF --- PASS: TestStatJSON (7.58s) --- PASS: TestStatJSON/Root (0.18s) --- PASS: TestStatJSON/RootFilesOnly (0.00s) --- PASS: TestStatJSON/RootDirsOnly (0.18s) --- PASS: TestStatJSON/Dir (0.90s) --- PASS: TestStatJSON/File (0.18s) --- PASS: TestStatJSON/NotFound (0.35s) --- PASS: TestStatJSON/DirFilesOnly (0.22s) --- PASS: TestStatJSON/FileFilesOnly (0.18s) --- PASS: TestStatJSON/NotFoundFilesOnly (0.21s) --- PASS: TestStatJSON/DirDirsOnly (0.18s) --- PASS: TestStatJSON/FileDirsOnly (0.18s) --- PASS: TestStatJSON/NotFoundDirsOnly (0.18s) --- PASS: TestStatJSON/RootNotFound (1.36s) === RUN TestMkdir run.go:181: Remote "B2 bucket rclone-test-fuyeluy3xifunot5bobewef3", Local "Local file system at /tmp/rclone3911360152", Modify Window "1ms" 2022/03/15 07:03:27 DEBUG : B2 bucket rclone-test-fuyeluy3xifunot5bobewef3: Making directory 2022/03/15 07:03:27 DEBUG : B2 bucket rclone-test-fuyeluy3xifunot5bobewef3: Making directory --- PASS: TestMkdir (0.54s) === RUN TestLsd run.go:181: Remote "B2 bucket rclone-test-fuyeluy3xifunot5bobewef3", Local "Local file system at /tmp/rclone3911360152", Modify Window "1ms" --- PASS: TestLsd (2.96s) === RUN TestLs run.go:181: Remote "B2 bucket rclone-test-fuyeluy3xifunot5bobewef3", Local "Local file system at /tmp/rclone3911360152", Modify Window "1ms" --- PASS: TestLs (4.29s) === RUN TestLsWithFilesFrom run.go:181: Remote "B2 bucket rclone-test-fuyeluy3xifunot5bobewef3", Local "Local file system at /tmp/rclone3911360152", Modify Window "1ms" 2022/03/15 07:03:36 DEBUG : empty space: Excluded from sync (and deletion) 2022/03/15 07:03:37 DEBUG : Couldn't decode error response: EOF --- PASS: TestLsWithFilesFrom (6.14s) === RUN TestLsLong run.go:181: Remote "B2 bucket rclone-test-fuyeluy3xifunot5bobewef3", Local "Local file system at /tmp/rclone3911360152", Modify Window "1ms" 2022/03/15 07:03:41 DEBUG : potato2: Clearing upload URL because of error: Post "https://pod-000-1039-01.backblaze.com/b2api/v1/b2_upload_file/3f224de3601d8bcb7ff80e1b/c001_v0001039_t0045": EOF 2022/03/15 07:03:41 DEBUG : pacer: low level retry 1/1 (error Post "https://pod-000-1039-01.backblaze.com/b2api/v1/b2_upload_file/3f224de3601d8bcb7ff80e1b/c001_v0001039_t0045": EOF) 2022/03/15 07:03:41 DEBUG : pacer: Rate limited, increasing sleep to 20ms run.go:283: Retry Put of "potato2" to B2 bucket rclone-test-fuyeluy3xifunot5bobewef3: 1/10 (Post "https://pod-000-1039-01.backblaze.com/b2api/v1/b2_upload_file/3f224de3601d8bcb7ff80e1b/c001_v0001039_t0045": EOF) 2022/03/15 07:03:43 DEBUG : pacer: Reducing sleep to 10ms --- PASS: TestLsLong (21.51s) === RUN TestHashSums run.go:181: Remote "B2 bucket rclone-test-fuyeluy3xifunot5bobewef3", Local "Local file system at /tmp/rclone3911360152", Modify Window "1ms" === RUN TestHashSums/Sha1 === RUN TestHashSums/Sha1Download --- PASS: TestHashSums (4.38s) --- PASS: TestHashSums/Sha1 (0.18s) --- PASS: TestHashSums/Sha1Download (0.87s) === RUN TestHashSumsWithErrors 2022/03/15 07:04:07 DEBUG : Creating backend with remote ":memory:" 2022/03/15 07:04:07 ERROR : file1: hash unsupported: hash type not supported --- PASS: TestHashSumsWithErrors (0.00s) === RUN TestHashStream 2022/03/15 07:04:07 DEBUG : Creating md5 hash of 0 bytes read from input stream 2022/03/15 07:04:07 DEBUG : Creating md5 hash of 0 bytes read from input stream 2022/03/15 07:04:07 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2022/03/15 07:04:07 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2022/03/15 07:04:07 DEBUG : Creating md5 hash of 12 bytes read from input stream 2022/03/15 07:04:07 DEBUG : Creating md5 hash of 12 bytes read from input stream 2022/03/15 07:04:07 DEBUG : Creating sha1 hash of 12 bytes read from input stream 2022/03/15 07:04:07 DEBUG : Creating sha1 hash of 12 bytes read from input stream --- PASS: TestHashStream (0.00s) === RUN TestSuffixName --- PASS: TestSuffixName (0.00s) === RUN TestCount run.go:181: Remote "B2 bucket rclone-test-fuyeluy3xifunot5bobewef3", Local "Local file system at /tmp/rclone3911360152", Modify Window "1ms" --- PASS: TestCount (5.03s) === RUN TestDelete run.go:181: Remote "B2 bucket rclone-test-fuyeluy3xifunot5bobewef3", Local "Local file system at /tmp/rclone3911360152", Modify Window "1ms" 2022/03/15 07:04:14 DEBUG : Waiting for deletions to finish 2022/03/15 07:04:15 DEBUG : large: Excluded from sync (and deletion) 2022/03/15 07:04:17 INFO : small: Deleted 2022/03/15 07:04:20 INFO : medium: Deleted --- PASS: TestDelete (9.13s) === RUN TestRetry 2022/03/15 07:04:21 DEBUG : Received error: EOF - low level retry 1/5 2022/03/15 07:04:21 DEBUG : Received error: EOF - low level retry 2/5 2022/03/15 07:04:21 DEBUG : Received error: EOF - low level retry 1/5 2022/03/15 07:04:21 DEBUG : Received error: EOF - low level retry 2/5 2022/03/15 07:04:21 DEBUG : Received error: EOF - low level retry 3/5 2022/03/15 07:04:21 DEBUG : Received error: EOF - low level retry 4/5 2022/03/15 07:04:21 DEBUG : Received error: EOF - low level retry 5/5 --- PASS: TestRetry (0.00s) === RUN TestCat run.go:181: Remote "B2 bucket rclone-test-fuyeluy3xifunot5bobewef3", Local "Local file system at /tmp/rclone3911360152", Modify Window "1ms" --- PASS: TestCat (8.40s) === RUN TestPurge 2022/03/15 07:04:29 DEBUG : Creating backend with remote "TestB2:rclone-test-maminey6tihojet6kefobew8" 2022/03/15 07:04:29 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2022/03/15 07:04:30 DEBUG : Creating backend with remote "/tmp/rclone796091866" run.go:181: Remote "B2 bucket rclone-test-maminey6tihojet6kefobew8", Local "Local file system at /tmp/rclone796091866", Modify Window "1ms" 2022/03/15 07:04:33 DEBUG : A2: Making directory 2022/03/15 07:04:33 DEBUG : A1/B2: Making directory 2022/03/15 07:04:33 DEBUG : A1/B2/C2: Making directory 2022/03/15 07:04:33 DEBUG : A1/B1/C3: Making directory 2022/03/15 07:04:33 DEBUG : A3: Making directory 2022/03/15 07:04:33 DEBUG : A3/B3: Making directory 2022/03/15 07:04:33 DEBUG : A3/B3/C4: Making directory fstest.go:251: Filtering empty directory "A2" fstest.go:251: Filtering empty directory "A1/B2" fstest.go:251: Filtering empty directory "A1/B2/C2" fstest.go:251: Filtering empty directory "A1/B1/C3" fstest.go:251: Filtering empty directory "A3" fstest.go:251: Filtering empty directory "A3/B3" fstest.go:251: Filtering empty directory "A3/B3/C4" 2022/03/15 07:04:33 DEBUG : A1/B1/C1/one: Deleting (id "4_z4f427df3604d8bcb7ff80e1b_f1011a9ca4f878b82_d20220315_m070432_c001_v0001102_t0039") fstest.go:251: Filtering empty directory "A2" fstest.go:251: Filtering empty directory "A1/B2" fstest.go:251: Filtering empty directory "A1/B2/C2" fstest.go:251: Filtering empty directory "A3" fstest.go:251: Filtering empty directory "A3/B3" fstest.go:251: Filtering empty directory "A3/B3/C4" 2022/03/15 07:04:34 DEBUG : A1/two: Deleting (id "4_z4f427df3604d8bcb7ff80e1b_f1011a9ca4f878b88_d20220315_m070433_c001_v0001102_t0026") 2022/03/15 07:04:35 DEBUG : B2 bucket rclone-test-maminey6tihojet6kefobew8: Purge remote 2022/03/15 07:04:35 purge failed: directory not found --- PASS: TestPurge (5.30s) === RUN TestRmdirsNoLeaveRoot run.go:181: Remote "B2 bucket rclone-test-fuyeluy3xifunot5bobewef3", Local "Local file system at /tmp/rclone3911360152", Modify Window "1ms" 2022/03/15 07:04:37 DEBUG : A2: Making directory 2022/03/15 07:04:37 DEBUG : A1/B2: Making directory 2022/03/15 07:04:37 DEBUG : A1/B2/C2: Making directory 2022/03/15 07:04:37 DEBUG : A1/B1/C3: Making directory 2022/03/15 07:04:37 DEBUG : A3: Making directory 2022/03/15 07:04:37 DEBUG : A3/B3: Making directory 2022/03/15 07:04:37 DEBUG : A3/B3/C4: Making directory fstest.go:251: Filtering empty directory "A2" fstest.go:251: Filtering empty directory "A1/B2" fstest.go:251: Filtering empty directory "A1/B2/C2" fstest.go:251: Filtering empty directory "A1/B1/C3" fstest.go:251: Filtering empty directory "A3" fstest.go:251: Filtering empty directory "A3/B3" fstest.go:251: Filtering empty directory "A3/B3/C4" 2022/03/15 07:04:38 INFO : A3/B3/C4: Removing directory fstest.go:251: Filtering empty directory "A2" fstest.go:251: Filtering empty directory "A1/B2" fstest.go:251: Filtering empty directory "A1/B2/C2" fstest.go:251: Filtering empty directory "A1/B1/C3" fstest.go:251: Filtering empty directory "A3" fstest.go:251: Filtering empty directory "A3/B3" --- PASS: TestRmdirsNoLeaveRoot (5.51s) === RUN TestRmdirsLeaveRoot run.go:181: Remote "B2 bucket rclone-test-fuyeluy3xifunot5bobewef3", Local "Local file system at /tmp/rclone3911360152", Modify Window "1ms" 2022/03/15 07:04:40 DEBUG : A1: Making directory 2022/03/15 07:04:40 DEBUG : A1/B1: Making directory 2022/03/15 07:04:40 DEBUG : A1/B1/C1: Making directory fstest.go:251: Filtering empty directory "A1" fstest.go:251: Filtering empty directory "A1/B1" fstest.go:251: Filtering empty directory "A1/B1/C1" fstest.go:251: Filtering empty directory "A1" --- PASS: TestRmdirsLeaveRoot (0.86s) === RUN TestRmdirsWithFilter run.go:181: Remote "B2 bucket rclone-test-fuyeluy3xifunot5bobewef3", Local "Local file system at /tmp/rclone3911360152", Modify Window "1ms" 2022/03/15 07:04:41 DEBUG : A1: Making directory 2022/03/15 07:04:41 DEBUG : A1/B1: Making directory 2022/03/15 07:04:41 DEBUG : A1/B1/C1: Making directory fstest.go:251: Filtering empty directory "A1" fstest.go:251: Filtering empty directory "A1/B1" fstest.go:251: Filtering empty directory "A1/B1/C1" fstest.go:251: Filtering empty directory "A1" --- PASS: TestRmdirsWithFilter (0.88s) === RUN TestCopyURL run.go:181: Remote "B2 bucket rclone-test-fuyeluy3xifunot5bobewef3", Local "Local file system at /tmp/rclone3911360152", Modify Window "1ms" 2022/03/15 07:04:43 DEBUG : filename.txt: File name found in url --- PASS: TestCopyURL (6.63s) === RUN TestCopyURLToWriter --- PASS: TestCopyURLToWriter (0.00s) === RUN TestMoveFile run.go:181: Remote "B2 bucket rclone-test-fuyeluy3xifunot5bobewef3", Local "Local file system at /tmp/rclone3911360152", Modify Window "1ms" 2022/03/15 07:04:49 DEBUG : Couldn't decode error response: EOF 2022/03/15 07:04:49 DEBUG : file1: Need to transfer - File not found at Destination 2022/03/15 07:04:50 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2022/03/15 07:04:50 INFO : file1: Copied (new) to: sub/file2 2022/03/15 07:04:50 INFO : file1: Deleted 2022/03/15 07:04:50 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/15 07:04:50 DEBUG : file1: Unchanged skipping 2022/03/15 07:04:50 INFO : file1: Deleted 2022/03/15 07:04:51 DEBUG : B2 bucket rclone-test-fuyeluy3xifunot5bobewef3: don't need to copy/move sub/file2, it is already at target location --- PASS: TestMoveFile (5.23s) === RUN TestMoveFileWithIgnoreExisting run.go:181: Remote "B2 bucket rclone-test-fuyeluy3xifunot5bobewef3", Local "Local file system at /tmp/rclone3911360152", Modify Window "1ms" 2022/03/15 07:04:54 DEBUG : Couldn't decode error response: EOF 2022/03/15 07:04:54 DEBUG : file1: Need to transfer - File not found at Destination 2022/03/15 07:04:54 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2022/03/15 07:04:54 INFO : file1: Copied (new) 2022/03/15 07:04:54 INFO : file1: Deleted 2022/03/15 07:04:55 DEBUG : file1: Destination exists, skipping 2022/03/15 07:04:55 DEBUG : file1: Not removing source file as destination file exists and --ignore-existing is set --- PASS: TestMoveFileWithIgnoreExisting (2.03s) === RUN TestCaseInsensitiveMoveFile run.go:181: Remote "B2 bucket rclone-test-fuyeluy3xifunot5bobewef3", Local "Local file system at /tmp/rclone3911360152", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFile (0.34s) === RUN TestMoveFileBackupDir run.go:181: Remote "B2 bucket rclone-test-fuyeluy3xifunot5bobewef3", Local "Local file system at /tmp/rclone3911360152", Modify Window "1ms" 2022/03/15 07:05:02 DEBUG : Creating backend with remote "TestB2:rclone-test-fuyeluy3xifunot5bobewef3/backup" 2022/03/15 07:05:03 DEBUG : Couldn't decode error response: EOF 2022/03/15 07:05:03 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18) 2022/03/15 07:05:04 DEBUG : Couldn't decode error response: EOF 2022/03/15 07:05:05 DEBUG : dst/file1: sha1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK 2022/03/15 07:05:05 INFO : dst/file1: Copied (server-side copy) 2022/03/15 07:05:06 INFO : dst/file1: Deleted 2022/03/15 07:05:06 DEBUG : dst/file1: Clearing upload URL because of error: Post "https://pod-000-1102-17.backblaze.com/b2api/v1/b2_upload_file/3f224de3601d8bcb7ff80e1b/c001_v0001102_t0058": EOF 2022/03/15 07:05:06 DEBUG : pacer: low level retry 1/1 (error Post "https://pod-000-1102-17.backblaze.com/b2api/v1/b2_upload_file/3f224de3601d8bcb7ff80e1b/c001_v0001102_t0058": EOF) 2022/03/15 07:05:06 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2022/03/15 07:05:06 DEBUG : dst/file1: Received error: Post "https://pod-000-1102-17.backblaze.com/b2api/v1/b2_upload_file/3f224de3601d8bcb7ff80e1b/c001_v0001102_t0058": EOF - low level retry 1/10 2022/03/15 07:05:06 DEBUG : pacer: Reducing sleep to 10ms 2022/03/15 07:05:07 DEBUG : dst/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2022/03/15 07:05:07 INFO : dst/file1: Copied (new) 2022/03/15 07:05:07 INFO : dst/file1: Deleted --- PASS: TestMoveFileBackupDir (13.46s) === RUN TestCopyFile run.go:181: Remote "B2 bucket rclone-test-fuyeluy3xifunot5bobewef3", Local "Local file system at /tmp/rclone3911360152", Modify Window "1ms" 2022/03/15 07:05:10 DEBUG : Couldn't decode error response: EOF 2022/03/15 07:05:10 DEBUG : file1: Need to transfer - File not found at Destination 2022/03/15 07:05:11 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2022/03/15 07:05:11 INFO : file1: Copied (new) to: sub/file2 2022/03/15 07:05:11 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/15 07:05:11 DEBUG : file1: Unchanged skipping 2022/03/15 07:05:11 DEBUG : B2 bucket rclone-test-fuyeluy3xifunot5bobewef3: don't need to copy/move sub/file2, it is already at target location --- PASS: TestCopyFile (2.82s) === RUN TestCopyFileBackupDir run.go:181: Remote "B2 bucket rclone-test-fuyeluy3xifunot5bobewef3", Local "Local file system at /tmp/rclone3911360152", Modify Window "1ms" 2022/03/15 07:05:13 DEBUG : Creating backend with remote "TestB2:rclone-test-fuyeluy3xifunot5bobewef3/backup" 2022/03/15 07:05:15 DEBUG : Couldn't decode error response: EOF 2022/03/15 07:05:15 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18) 2022/03/15 07:05:15 DEBUG : Couldn't decode error response: EOF 2022/03/15 07:05:17 DEBUG : dst/file1: sha1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK 2022/03/15 07:05:17 INFO : dst/file1: Copied (server-side copy) 2022/03/15 07:05:19 INFO : dst/file1: Deleted 2022/03/15 07:05:20 DEBUG : dst/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2022/03/15 07:05:20 INFO : dst/file1: Copied (new) --- PASS: TestCopyFileBackupDir (11.63s) === RUN TestCopyFileCompareDest run.go:181: Remote "B2 bucket rclone-test-fuyeluy3xifunot5bobewef3", Local "Local file system at /tmp/rclone3911360152", Modify Window "1ms" 2022/03/15 07:05:24 DEBUG : Creating backend with remote "TestB2:rclone-test-fuyeluy3xifunot5bobewef3/dst" 2022/03/15 07:05:26 DEBUG : Couldn't decode error response: EOF 2022/03/15 07:05:26 DEBUG : Couldn't decode error response: EOF 2022/03/15 07:05:26 DEBUG : Creating backend with remote "TestB2:rclone-test-fuyeluy3xifunot5bobewef3/CompareDest" 2022/03/15 07:05:26 DEBUG : Couldn't decode error response: EOF 2022/03/15 07:05:26 DEBUG : Couldn't decode error response: EOF 2022/03/15 07:05:26 DEBUG : one: Need to transfer - File not found at Destination 2022/03/15 07:05:29 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/03/15 07:05:29 INFO : one: Copied (new) 2022/03/15 07:05:30 DEBUG : Couldn't decode error response: EOF 2022/03/15 07:05:30 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/03/15 07:05:30 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2022/03/15 07:05:30 INFO : one: Copied (replaced existing) 2022/03/15 07:05:32 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/03/15 07:05:32 DEBUG : one: Destination found in --compare-dest, skipping 2022/03/15 07:05:33 DEBUG : Couldn't decode error response: EOF 2022/03/15 07:05:33 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/03/15 07:05:33 DEBUG : two: Destination found in --compare-dest, skipping 2022/03/15 07:05:34 DEBUG : Couldn't decode error response: EOF 2022/03/15 07:05:34 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/03/15 07:05:34 DEBUG : two: Destination found in --compare-dest, skipping 2022/03/15 07:05:34 DEBUG : Couldn't decode error response: EOF 2022/03/15 07:05:34 DEBUG : two: Sizes differ (src 5 vs dst 3) 2022/03/15 07:05:34 DEBUG : two: Need to transfer - File not found at Destination 2022/03/15 07:05:35 DEBUG : two: sha1 = a4be8877ad88f3109a6411985a1f359e10e2d930 OK 2022/03/15 07:05:35 INFO : two: Copied (new) --- PASS: TestCopyFileCompareDest (15.67s) === RUN TestCopyFileCopyDest run.go:181: Remote "B2 bucket rclone-test-fuyeluy3xifunot5bobewef3", Local "Local file system at /tmp/rclone3911360152", Modify Window "1ms" 2022/03/15 07:05:40 DEBUG : Creating backend with remote "TestB2:rclone-test-fuyeluy3xifunot5bobewef3/dst" 2022/03/15 07:05:41 DEBUG : Couldn't decode error response: EOF 2022/03/15 07:05:41 DEBUG : Couldn't decode error response: EOF 2022/03/15 07:05:41 DEBUG : Creating backend with remote "TestB2:rclone-test-fuyeluy3xifunot5bobewef3/CopyDest" 2022/03/15 07:05:42 DEBUG : Couldn't decode error response: EOF 2022/03/15 07:05:42 DEBUG : Couldn't decode error response: EOF 2022/03/15 07:05:42 DEBUG : one: Need to transfer - File not found at Destination 2022/03/15 07:05:45 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/03/15 07:05:45 INFO : one: Copied (new) 2022/03/15 07:05:45 DEBUG : pacer: low level retry 1/10 (error Post "https://api001.backblazeb2.com/b2api/v1/b2_list_file_names": EOF) 2022/03/15 07:05:45 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2022/03/15 07:05:46 DEBUG : pacer: Reducing sleep to 10ms 2022/03/15 07:05:46 DEBUG : Couldn't decode error response: EOF 2022/03/15 07:05:46 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/03/15 07:05:47 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2022/03/15 07:05:47 INFO : one: Copied (replaced existing) 2022/03/15 07:05:49 DEBUG : Creating backend with remote "TestB2:rclone-test-fuyeluy3xifunot5bobewef3/BackupDir" 2022/03/15 07:05:50 DEBUG : Couldn't decode error response: EOF 2022/03/15 07:05:50 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/03/15 07:05:50 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/03/15 07:05:50 DEBUG : Couldn't decode error response: EOF 2022/03/15 07:05:52 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/03/15 07:05:52 INFO : one: Copied (server-side copy) 2022/03/15 07:05:53 INFO : one: Deleted 2022/03/15 07:05:54 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2022/03/15 07:05:54 INFO : one: Copied (server-side copy) 2022/03/15 07:05:54 DEBUG : one: Destination found in --copy-dest, using server-side copy 2022/03/15 07:05:57 DEBUG : Couldn't decode error response: EOF 2022/03/15 07:05:57 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/03/15 07:05:57 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2022/03/15 07:05:57 INFO : two: Copied (server-side copy) 2022/03/15 07:05:57 DEBUG : two: Destination found in --copy-dest, using server-side copy 2022/03/15 07:05:58 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/03/15 07:05:58 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/03/15 07:05:58 DEBUG : two: Unchanged skipping 2022/03/15 07:05:58 DEBUG : Couldn't decode error response: EOF 2022/03/15 07:05:59 DEBUG : three: Sizes differ (src 7 vs dst 5) 2022/03/15 07:05:59 DEBUG : three: Destination not found in --copy-dest 2022/03/15 07:05:59 DEBUG : three: Need to transfer - File not found at Destination 2022/03/15 07:06:00 DEBUG : three: sha1 = 370e613335f3cf2f19b2a2d35a86402f55c659f8 OK 2022/03/15 07:06:00 INFO : three: Copied (new) --- PASS: TestCopyFileCopyDest (28.15s) === RUN TestSameConfig --- PASS: TestSameConfig (0.00s) === RUN TestSame --- PASS: TestSame (0.00s) === RUN TestOverlapping --- PASS: TestOverlapping (0.00s) === RUN TestListFormat --- PASS: TestListFormat (0.00s) === RUN TestDirMove run.go:181: Remote "B2 bucket rclone-test-fuyeluy3xifunot5bobewef3", Local "Local file system at /tmp/rclone3911360152", Modify Window "1ms" 2022/03/15 07:06:11 DEBUG : A1/B2: Making directory 2022/03/15 07:06:11 DEBUG : A1/B1/C3: Making directory fstest.go:251: Filtering empty directory "A1/B2" fstest.go:251: Filtering empty directory "A1/B1/C3" 2022/03/15 07:06:12 DEBUG : Couldn't decode error response: EOF 2022/03/15 07:06:12 DEBUG : Couldn't decode error response: EOF 2022/03/15 07:06:12 DEBUG : Couldn't decode error response: EOF 2022/03/15 07:06:12 DEBUG : Couldn't decode error response: EOF 2022/03/15 07:06:12 DEBUG : A1/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2022/03/15 07:06:12 INFO : A1/B1/C1/four: Copied (server-side copy) to: A2/B1/C1/four 2022/03/15 07:06:13 INFO : A1/B1/C1/four: Deleted 2022/03/15 07:06:13 DEBUG : A1/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2022/03/15 07:06:13 INFO : A1/B1/three: Copied (server-side copy) to: A2/B1/three 2022/03/15 07:06:13 DEBUG : A1/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2022/03/15 07:06:13 INFO : A1/B1/C2/five: Copied (server-side copy) to: A2/B1/C2/five 2022/03/15 07:06:13 DEBUG : Couldn't decode error response: EOF 2022/03/15 07:06:13 DEBUG : A1/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/03/15 07:06:13 INFO : A1/one: Copied (server-side copy) to: A2/one 2022/03/15 07:06:13 DEBUG : A1/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2022/03/15 07:06:13 INFO : A1/two: Copied (server-side copy) to: A2/two 2022/03/15 07:06:14 INFO : A1/two: Deleted 2022/03/15 07:06:14 INFO : A1/one: Deleted 2022/03/15 07:06:14 INFO : A1/B1/C2/five: Deleted 2022/03/15 07:06:15 INFO : A1/B1/three: Deleted fstest.go:251: Filtering empty directory "A2/B2" fstest.go:251: Filtering empty directory "A2/B1/C3" 2022/03/15 07:06:15 DEBUG : Couldn't decode error response: EOF 2022/03/15 07:06:15 DEBUG : Couldn't decode error response: EOF 2022/03/15 07:06:15 DEBUG : Couldn't decode error response: EOF 2022/03/15 07:06:15 DEBUG : Couldn't decode error response: EOF 2022/03/15 07:06:15 DEBUG : A2/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2022/03/15 07:06:15 INFO : A2/B1/C1/four: Copied (server-side copy) to: A3/B1/C1/four 2022/03/15 07:06:16 DEBUG : A2/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/03/15 07:06:16 INFO : A2/one: Copied (server-side copy) to: A3/one 2022/03/15 07:06:16 DEBUG : A2/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2022/03/15 07:06:16 INFO : A2/B1/C2/five: Copied (server-side copy) to: A3/B1/C2/five 2022/03/15 07:06:16 INFO : A2/B1/C1/four: Deleted 2022/03/15 07:06:16 DEBUG : Couldn't decode error response: EOF 2022/03/15 07:06:16 INFO : A2/one: Deleted 2022/03/15 07:06:17 INFO : A2/B1/C2/five: Deleted 2022/03/15 07:06:17 DEBUG : A2/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2022/03/15 07:06:17 INFO : A2/B1/three: Copied (server-side copy) to: A3/B1/three 2022/03/15 07:06:17 DEBUG : A2/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2022/03/15 07:06:17 INFO : A2/two: Copied (server-side copy) to: A3/two 2022/03/15 07:06:17 INFO : A2/B1/three: Deleted 2022/03/15 07:06:18 INFO : A2/two: Deleted fstest.go:251: Filtering empty directory "A3/B2" fstest.go:251: Filtering empty directory "A3/B1/C3" --- PASS: TestDirMove (13.07s) === RUN TestGetFsInfo run.go:181: Remote "B2 bucket rclone-test-fuyeluy3xifunot5bobewef3", Local "Local file system at /tmp/rclone3911360152", Modify Window "1ms" --- PASS: TestGetFsInfo (0.34s) === RUN TestRcat === RUN TestRcat/withChecksum=false,ignoreChecksum=false === CONT TestRcat run.go:181: Remote "B2 bucket rclone-test-fuyeluy3xifunot5bobewef3", Local "Local file system at /tmp/rclone3911360152", Modify Window "1ms" 2022/03/15 07:06:22 DEBUG : B2 bucket rclone-test-fuyeluy3xifunot5bobewef3: File to upload is small (34 bytes), uploading instead of streaming 2022/03/15 07:06:23 DEBUG : no_checksum_small_file_from_pipe: sha1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK 2022/03/15 07:06:23 INFO : no_checksum_small_file_from_pipe: Copied (new) 2022/03/15 07:06:23 DEBUG : no_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2022/03/15 07:06:23 DEBUG : no_checksum_big_file_from_pipe: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) === RUN TestRcat/withChecksum=true,ignoreChecksum=false === CONT TestRcat run.go:181: Remote "B2 bucket rclone-test-fuyeluy3xifunot5bobewef3", Local "Local file system at /tmp/rclone3911360152", Modify Window "1ms" 2022/03/15 07:06:25 DEBUG : B2 bucket rclone-test-fuyeluy3xifunot5bobewef3: File to upload is small (34 bytes), uploading instead of streaming 2022/03/15 07:06:27 DEBUG : with_checksum_small_file_from_pipe: sha1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK 2022/03/15 07:06:27 INFO : with_checksum_small_file_from_pipe: Copied (new) 2022/03/15 07:06:27 DEBUG : with_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2022/03/15 07:06:29 DEBUG : with_checksum_big_file_from_pipe: sha1 = d0080f73aa8b494570ad63df1beb25c01ea9eea2 OK 2022/03/15 07:06:29 DEBUG : with_checksum_big_file_from_pipe: Size and sha1 of src and dst objects identical === RUN TestRcat/withChecksum=false,ignoreChecksum=true === CONT TestRcat run.go:181: Remote "B2 bucket rclone-test-fuyeluy3xifunot5bobewef3", Local "Local file system at /tmp/rclone3911360152", Modify Window "1ms" 2022/03/15 07:06:33 DEBUG : B2 bucket rclone-test-fuyeluy3xifunot5bobewef3: File to upload is small (34 bytes), uploading instead of streaming 2022/03/15 07:06:34 INFO : ignore_checksum_small_file_from_pipe: Copied (new) 2022/03/15 07:06:34 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2022/03/15 07:06:34 DEBUG : ignore_checksum_big_file_from_pipe: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) === RUN TestRcat/withChecksum=true,ignoreChecksum=true === CONT TestRcat run.go:181: Remote "B2 bucket rclone-test-fuyeluy3xifunot5bobewef3", Local "Local file system at /tmp/rclone3911360152", Modify Window "1ms" 2022/03/15 07:06:36 DEBUG : B2 bucket rclone-test-fuyeluy3xifunot5bobewef3: File to upload is small (34 bytes), uploading instead of streaming 2022/03/15 07:06:37 INFO : ignore_checksum_small_file_from_pipe: Copied (new) 2022/03/15 07:06:37 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2022/03/15 07:06:37 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical --- PASS: TestRcat (17.59s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (3.54s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (8.18s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (2.89s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (2.98s) === RUN TestRcatSize run.go:181: Remote "B2 bucket rclone-test-fuyeluy3xifunot5bobewef3", Local "Local file system at /tmp/rclone3911360152", Modify Window "1ms" 2022/03/15 07:06:39 DEBUG : B2 bucket rclone-test-fuyeluy3xifunot5bobewef3: File to upload is small (60 bytes), uploading instead of streaming 2022/03/15 07:06:40 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2022/03/15 07:06:40 INFO : potato2: Copied (new) --- PASS: TestRcatSize (4.43s) === RUN TestCopyFileMaxTransfer run.go:181: Remote "B2 bucket rclone-test-fuyeluy3xifunot5bobewef3", Local "Local file system at /tmp/rclone3911360152", Modify Window "1ms" 2022/03/15 07:06:44 DEBUG : Couldn't decode error response: EOF 2022/03/15 07:06:44 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination 2022/03/15 07:06:44 DEBUG : TestCopyFileMaxTransfer/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2022/03/15 07:06:44 INFO : TestCopyFileMaxTransfer/file1: Copied (new) 2022/03/15 07:06:45 DEBUG : Couldn't decode error response: EOF 2022/03/15 07:06:45 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination 2022/03/15 07:06:45 ERROR : TestCopyFileMaxTransfer/file2: Failed to copy: Post "https://pod-000-1130-16.backblaze.com/b2api/v1/b2_upload_file/3f224de3601d8bcb7ff80e1b/c001_v0001130_t0000": Max transfer limit reached as set by --max-transfer 2022/03/15 07:06:45 DEBUG : Couldn't decode error response: EOF 2022/03/15 07:06:45 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination 2022/03/15 07:06:46 DEBUG : Couldn't decode error response: EOF 2022/03/15 07:06:46 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination 2022/03/15 07:06:46 DEBUG : TestCopyFileMaxTransfer/file4: sha1 = c9e9c2af3dfdf96e0862ac415d0b4995f3dd1936 OK 2022/03/15 07:06:46 INFO : TestCopyFileMaxTransfer/file4: Copied (new) --- PASS: TestCopyFileMaxTransfer (4.78s) === RUN TestTouchDir run.go:181: Remote "B2 bucket rclone-test-fuyeluy3xifunot5bobewef3", Local "Local file system at /tmp/rclone3911360152", Modify Window "1ms" 2022/03/15 07:06:50 DEBUG : B2 bucket rclone-test-fuyeluy3xifunot5bobewef3: Touching "empty space" 2022/03/15 07:06:50 DEBUG : B2 bucket rclone-test-fuyeluy3xifunot5bobewef3: Touching "potato2" 2022/03/15 07:06:51 DEBUG : B2 bucket rclone-test-fuyeluy3xifunot5bobewef3: Touching "sub dir/potato3" --- PASS: TestTouchDir (8.18s) === RUN TestRcAbout rc_test.go:25: Skipping test on non local remote --- SKIP: TestRcAbout (0.00s) === RUN TestRcCleanup rc_test.go:25: Skipping test on non local remote --- SKIP: TestRcCleanup (0.00s) === RUN TestRcCopyfile rc_test.go:25: Skipping test on non local remote --- SKIP: TestRcCopyfile (0.00s) === RUN TestRcCopyurl rc_test.go:25: Skipping test on non local remote --- SKIP: TestRcCopyurl (0.00s) === RUN TestRcDelete rc_test.go:25: Skipping test on non local remote --- SKIP: TestRcDelete (0.00s) === RUN TestRcDeletefile rc_test.go:25: Skipping test on non local remote --- SKIP: TestRcDeletefile (0.00s) === RUN TestRcList rc_test.go:25: Skipping test on non local remote --- SKIP: TestRcList (0.00s) === RUN TestRcStat rc_test.go:25: Skipping test on non local remote --- SKIP: TestRcStat (0.00s) === RUN TestRcMkdir rc_test.go:25: Skipping test on non local remote --- SKIP: TestRcMkdir (0.00s) === RUN TestRcMovefile rc_test.go:25: Skipping test on non local remote --- SKIP: TestRcMovefile (0.00s) === RUN TestRcPurge rc_test.go:25: Skipping test on non local remote --- SKIP: TestRcPurge (0.00s) === RUN TestRcRmdir rc_test.go:25: Skipping test on non local remote --- SKIP: TestRcRmdir (0.00s) === RUN TestRcRmdirs rc_test.go:25: Skipping test on non local remote --- SKIP: TestRcRmdirs (0.00s) === RUN TestRcSize rc_test.go:25: Skipping test on non local remote --- SKIP: TestRcSize (0.00s) === RUN TestRcPublicLink rc_test.go:25: Skipping test on non local remote --- SKIP: TestRcPublicLink (0.00s) === RUN TestRcFsInfo rc_test.go:25: Skipping test on non local remote --- SKIP: TestRcFsInfo (0.00s) === RUN TestUploadFile rc_test.go:25: Skipping test on non local remote --- SKIP: TestUploadFile (0.00s) === RUN TestRcCommand rc_test.go:25: Skipping test on non local remote --- SKIP: TestRcCommand (0.00s) PASS 2022/03/15 07:06:56 DEBUG : B2 bucket rclone-test-fuyeluy3xifunot5bobewef3: Purge remote 2022/03/15 07:06:57 DEBUG : A1/B1/C1/four: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1119d1afefe77ffb_d20220315_m070612_c001_v0001099_t0008") 2022/03/15 07:06:57 DEBUG : A1/B1/C1/four: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1160e30a25b8e4da_d20220315_m070610_c001_v0001130_t0039") 2022/03/15 07:06:57 DEBUG : A1/B1/C1/one: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f113e70f41940408d_d20220315_m070439_c001_v0001115_t0034") 2022/03/15 07:06:57 DEBUG : A1/B1/C1/one: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f11726227037e0c1e_d20220315_m070435_c001_v0001102_t0019") 2022/03/15 07:06:57 DEBUG : A1/B1/C2/five: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1083970cc8463158_d20220315_m070613_c001_v0001106_t0020") 2022/03/15 07:06:57 DEBUG : A1/B1/C2/five: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1160e30a25b8e4dc_d20220315_m070610_c001_v0001130_t0047") 2022/03/15 07:06:57 DEBUG : A1/B1/three: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f109a9239dc6e3343_d20220315_m070613_c001_v0001104_t0057") 2022/03/15 07:06:57 DEBUG : A1/B1/three: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1160e30a25b8e4d6_d20220315_m070610_c001_v0001130_t0017") 2022/03/15 07:06:57 DEBUG : A1/one: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f105be30fbf0fec7a_d20220315_m070613_c001_v0001161_t0021") 2022/03/15 07:06:57 DEBUG : A1/one: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1160e30a25b8e4cc_d20220315_m070609_c001_v0001130_t0058") 2022/03/15 07:06:57 DEBUG : A1/two: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f113b1d6ad41bbea4_d20220315_m070613_c001_v0001037_t0051") 2022/03/15 07:06:58 DEBUG : A1/two: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1160e30a25b8e4ce_d20220315_m070609_c001_v0001130_t0049") 2022/03/15 07:06:58 DEBUG : A1/two: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f101fcb4fc15f3c3d_d20220315_m070440_c001_v0001093_t0008") 2022/03/15 07:06:58 DEBUG : A1/two: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f11726227037e0c40_d20220315_m070437_c001_v0001102_t0028") 2022/03/15 07:06:58 DEBUG : A2/B1/C1/four: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f101adfaa0a604625_d20220315_m070616_c001_v0001104_t0030") 2022/03/15 07:06:58 DEBUG : A2/B1/C1/four: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f106ccd5a987ea1f9_d20220315_m070612_c001_v0001165_t0042") 2022/03/15 07:06:58 DEBUG : A2/B1/C2/five: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f102b6764a85fd17e_d20220315_m070616_c001_v0001097_t0024") 2022/03/15 07:06:58 DEBUG : A2/B1/C2/five: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1195bb43d47cf3a9_d20220315_m070612_c001_v0001130_t0026") 2022/03/15 07:06:58 DEBUG : A2/B1/three: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f10824867c06258c7_d20220315_m070617_c001_v0001145_t0050") 2022/03/15 07:06:58 DEBUG : A2/B1/three: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f11515b3a912d95b5_d20220315_m070612_c001_v0001105_t0039") 2022/03/15 07:06:58 DEBUG : A2/one: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f11151dfd5db0f6db_d20220315_m070616_c001_v0001109_t0041") 2022/03/15 07:06:58 DEBUG : A2/one: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f119b218a890660c1_d20220315_m070612_c001_v0001117_t0053") 2022/03/15 07:06:58 DEBUG : A2/two: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f111114c24e950ff9_d20220315_m070617_c001_v0001116_t0047") 2022/03/15 07:06:58 DEBUG : A2/two: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f11260f2691b6211e_d20220315_m070613_c001_v0001098_t0025") 2022/03/15 07:06:58 DEBUG : A3/B1/C1/four: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f106bc7110ef18f29_d20220315_m070618_c001_v0001115_t0014") 2022/03/15 07:06:58 DEBUG : A3/B1/C1/four: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f106d628ac39339b8_d20220315_m070615_c001_v0001131_t0037") 2022/03/15 07:06:58 DEBUG : A3/B1/C2/five: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f10539754b821c3ae_d20220315_m070619_c001_v0001109_t0014") 2022/03/15 07:06:58 DEBUG : A3/B1/C2/five: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1167a0f7120bbc65_d20220315_m070615_c001_v0001104_t0035") 2022/03/15 07:06:58 DEBUG : A3/B1/three: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1134536faaa5216f_d20220315_m070619_c001_v0001150_t0028") 2022/03/15 07:06:58 DEBUG : A3/B1/three: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f10390e5c7b163daf_d20220315_m070615_c001_v0001116_t0048") 2022/03/15 07:06:58 DEBUG : A3/one: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f10477205e29e8b9f_d20220315_m070620_c001_v0001115_t0006") 2022/03/15 07:06:59 DEBUG : A3/one: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f103e578eb371a145_d20220315_m070615_c001_v0001165_t0014") 2022/03/15 07:06:59 DEBUG : A3/two: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f117e1f706d7b1860_d20220315_m070621_c001_v0001093_t0025") 2022/03/15 07:06:59 DEBUG : A3/two: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f118a7cb71706d470_d20220315_m070616_c001_v0001098_t0012") 2022/03/15 07:06:59 DEBUG : BackupDir/one: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f112c4aad64378395_d20220315_m070601_c001_v0001115_t0019") 2022/03/15 07:06:59 DEBUG : BackupDir/one: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f100a7ab882b00908_d20220315_m070552_c001_v0001104_t0027") 2022/03/15 07:06:59 DEBUG : CompareDest/one: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f113e97ba5a657796_d20220315_m070535_c001_v0001104_t0038") 2022/03/15 07:06:59 DEBUG : CompareDest/one: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1160e30a25b8e3f8_d20220315_m070531_c001_v0001130_t0019") 2022/03/15 07:06:59 DEBUG : CompareDest/two: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f100a7ab882b00724_d20220315_m070536_c001_v0001104_t0052") 2022/03/15 07:06:59 DEBUG : CompareDest/two: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1160e30a25b8e401_d20220315_m070532_c001_v0001130_t0017") 2022/03/15 07:06:59 DEBUG : CopyDest/one: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f119c1e3cfc29efbc_d20220315_m070602_c001_v0001104_t0006") 2022/03/15 07:06:59 DEBUG : CopyDest/one: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1160e30a25b8e458_d20220315_m070548_c001_v0001130_t0056") 2022/03/15 07:06:59 DEBUG : CopyDest/three: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f103b8e19fd7184ce_d20220315_m070602_c001_v0001109_t0032") 2022/03/15 07:06:59 DEBUG : CopyDest/three: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1160e30a25b8e491_d20220315_m070558_c001_v0001130_t0027") 2022/03/15 07:06:59 DEBUG : CopyDest/two: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1066240ed3fc0396_d20220315_m070603_c001_v0001134_t0056") 2022/03/15 07:06:59 DEBUG : CopyDest/two: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1160e30a25b8e484_d20220315_m070555_c001_v0001130_t0000") 2022/03/15 07:06:59 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1063857ddc83532f_d20220315_m070647_c001_v0001116_t0008") 2022/03/15 07:06:59 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1160e30a25b8e5b9_d20220315_m070644_c001_v0001130_t0035") 2022/03/15 07:06:59 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f110217bb39781fa7_d20220315_m070648_c001_v0001113_t0000") 2022/03/15 07:06:59 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1160e30a25b8e5c3_d20220315_m070646_c001_v0001130_t0026") 2022/03/15 07:06:59 DEBUG : a.txt: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f113a9cb8626d6306_d20220315_m070238_c001_v0001032_t0057") 2022/03/15 07:07:00 DEBUG : a.txt: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1014c11be5f646eb_d20220315_m070227_c001_v0001039_t0009") 2022/03/15 07:07:00 DEBUG : also/one: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f118eb32e2c7489bf_d20220315_m070223_c001_v0001165_t0032") 2022/03/15 07:07:00 DEBUG : also/one: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1014c11be5f646d1_d20220315_m070219_c001_v0001039_t0059") 2022/03/15 07:07:00 DEBUG : another: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f119723eae4302dd7_d20220315_m070223_c001_v0001120_t0048") 2022/03/15 07:07:00 DEBUG : another: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1014c11be5f646d5_d20220315_m070220_c001_v0001039_t0057") 2022/03/15 07:07:00 DEBUG : backup/dst/file1: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f118096d1516bf73c_d20220315_m070521_c001_v0001120_t0012") 2022/03/15 07:07:00 DEBUG : backup/dst/file1: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f107d5895040a6f6b_d20220315_m070517_c001_v0001100_t0011") 2022/03/15 07:07:00 DEBUG : backup/dst/file1: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1152b03eac616e49_d20220315_m070508_c001_v0001120_t0048") 2022/03/15 07:07:00 DEBUG : backup/dst/file1: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1154e479725b2fe9_d20220315_m070505_c001_v0001137_t0044") 2022/03/15 07:07:00 DEBUG : data/banana: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f105f6ba54c8edee5_d20220315_m070215_c001_v0001099_t0002") 2022/03/15 07:07:00 DEBUG : data/banana: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1014c11be5f646b8_d20220315_m070211_c001_v0001039_t0051") 2022/03/15 07:07:00 DEBUG : data/banana: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1014c11be5f64675_d20220315_m070154_c001_v0001039_t0036") 2022/03/15 07:07:00 DEBUG : data/potato: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f104039b043ac15cc_d20220315_m070216_c001_v0001136_t0025") 2022/03/15 07:07:00 DEBUG : data/potato: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1014c11be5f646bc_d20220315_m070212_c001_v0001039_t0052") 2022/03/15 07:07:00 DEBUG : data/potato: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1014c11be5f64686_d20220315_m070157_c001_v0001039_t0001") 2022/03/15 07:07:00 DEBUG : dst/file1: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1045dcd4e0a0cc82_d20220315_m070522_c001_v0001104_t0016") 2022/03/15 07:07:00 DEBUG : dst/file1: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1160e30a25b8e3c5_d20220315_m070520_c001_v0001130_t0032") 2022/03/15 07:07:00 DEBUG : dst/file1: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1077fdeb1f38da81_d20220315_m070517_c001_v0001097_t0033") 2022/03/15 07:07:00 DEBUG : dst/file1: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1160e30a25b8e3a4_d20220315_m070513_c001_v0001130_t0017") 2022/03/15 07:07:00 DEBUG : dst/file1: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1150d0d2705b8adf_d20220315_m070509_c001_v0001000_t0040") 2022/03/15 07:07:01 DEBUG : dst/file1: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1160e30a25b8e377_d20220315_m070507_c001_v0001130_t0050") 2022/03/15 07:07:01 DEBUG : dst/file1: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f108f167de8ba46f1_d20220315_m070506_c001_v0001105_t0001") 2022/03/15 07:07:01 DEBUG : dst/file1: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f11726227037e0d88_d20220315_m070456_c001_v0001102_t0019") 2022/03/15 07:07:01 DEBUG : dst/one: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1057c827b9ee8485_d20220315_m070604_c001_v0001036_t0059") 2022/03/15 07:07:01 DEBUG : dst/one: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f118fa56ae8231b1e_d20220315_m070553_c001_v0001092_t0057") 2022/03/15 07:07:01 DEBUG : dst/one: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f104dd270a87435e2_d20220315_m070552_c001_v0001144_t0035") 2022/03/15 07:07:01 DEBUG : dst/one: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1160e30a25b8e452_d20220315_m070548_c001_v0001130_t0015") 2022/03/15 07:07:01 DEBUG : dst/one: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1027c633a0a4c1f8_d20220315_m070547_c001_v0001100_t0037") 2022/03/15 07:07:01 DEBUG : dst/one: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1027c633a0a4c1ce_d20220315_m070544_c001_v0001100_t0031") 2022/03/15 07:07:01 DEBUG : dst/one: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f11064a915aa4c175_d20220315_m070538_c001_v0001040_t0037") 2022/03/15 07:07:01 DEBUG : dst/one: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1160e30a25b8e3f5_d20220315_m070531_c001_v0001130_t0004") 2022/03/15 07:07:01 DEBUG : dst/one: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1008430dd474f888_d20220315_m070530_c001_v0001157_t0003") 2022/03/15 07:07:01 DEBUG : dst/one: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1008430dd474f882_d20220315_m070528_c001_v0001157_t0022") 2022/03/15 07:07:01 DEBUG : dst/three: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f101309b0e1bb493c_d20220315_m070606_c001_v0001040_t0031") 2022/03/15 07:07:01 DEBUG : dst/three: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1027c633a0a4c2e7_d20220315_m070559_c001_v0001100_t0000") 2022/03/15 07:07:01 DEBUG : dst/two: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f11123c8ad82c0fe5_d20220315_m070607_c001_v0001120_t0054") 2022/03/15 07:07:01 DEBUG : dst/two: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f10429abe2d6fc42b_d20220315_m070557_c001_v0001154_t0010") 2022/03/15 07:07:01 DEBUG : dst/two: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1077fdeb1f38db40_d20220315_m070538_c001_v0001097_t0016") 2022/03/15 07:07:01 DEBUG : dst/two: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1008430dd474f8a2_d20220315_m070535_c001_v0001157_t0054") 2022/03/15 07:07:01 DEBUG : empty space: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f111c2a643e264ddd_d20220315_m070652_c001_v0001090_t0056") 2022/03/15 07:07:01 DEBUG : empty space: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f114febc5cc9cb0ff_d20220315_m070650_c001_v0001137_t0059") 2022/03/15 07:07:01 DEBUG : empty space: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1160e30a25b8e5d8_d20220315_m070649_c001_v0001130_t0054") 2022/03/15 07:07:01 DEBUG : empty space: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1030af598de8bc25_d20220315_m070409_c001_v0001032_t0020") 2022/03/15 07:07:02 DEBUG : empty space: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f11726227037e0a57_d20220315_m070407_c001_v0001102_t0037") 2022/03/15 07:07:02 DEBUG : empty space: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1158857a172f4efd_d20220315_m070406_c001_v0001117_t0010") 2022/03/15 07:07:02 DEBUG : empty space: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f11726227037e0a1d_d20220315_m070403_c001_v0001102_t0050") 2022/03/15 07:07:02 DEBUG : empty space: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f102f5c18cbab09dd_d20220315_m070359_c001_v0001134_t0041") 2022/03/15 07:07:02 DEBUG : empty space: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f11726227037e09d8_d20220315_m070357_c001_v0001102_t0031") 2022/03/15 07:07:02 DEBUG : empty space: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f102ffcee01fa8bbc_d20220315_m070338_c001_v0001117_t0050") 2022/03/15 07:07:02 DEBUG : empty space: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1014c11be5f647c6_d20220315_m070336_c001_v0001039_t0018") 2022/03/15 07:07:02 DEBUG : empty space: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f10851a75c02c4214_d20220315_m070334_c001_v0001102_t0057") 2022/03/15 07:07:02 DEBUG : empty space: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1014c11be5f647b5_d20220315_m070331_c001_v0001039_t0040") 2022/03/15 07:07:02 DEBUG : empty space: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1104228579a1f0b7_d20220315_m070141_c001_v0001101_t0022") 2022/03/15 07:07:02 DEBUG : empty space: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1014c11be5f64633_d20220315_m070136_c001_v0001039_t0049") 2022/03/15 07:07:02 DEBUG : empty space: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f109f4b7c574228f3_d20220315_m070129_c001_v0001144_t0059") 2022/03/15 07:07:02 DEBUG : empty space: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1014c11be5f6460c_d20220315_m070125_c001_v0001039_t0015") 2022/03/15 07:07:02 DEBUG : empty space: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1089bb5922ff95db_d20220315_m070120_c001_v0001090_t0015") 2022/03/15 07:07:02 DEBUG : empty space: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1014c11be5f645e6_d20220315_m070116_c001_v0001039_t0008") 2022/03/15 07:07:02 DEBUG : file1: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1199076fea6ba8c7_d20220315_m070455_c001_v0001109_t0024") 2022/03/15 07:07:02 DEBUG : file1: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f11726227037e0d6b_d20220315_m070454_c001_v0001102_t0011") 2022/03/15 07:07:02 DEBUG : file1: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f118df5a59e66f490_d20220315_m070446_c001_v0001100_t0005") 2022/03/15 07:07:02 DEBUG : file1: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f11726227037e0c94_d20220315_m070442_c001_v0001102_t0048") 2022/03/15 07:07:03 DEBUG : file1: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f108cc0a1f4e9714f_d20220315_m070429_c001_v0001137_t0006") 2022/03/15 07:07:03 DEBUG : file1: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f11726227037e0b68_d20220315_m070422_c001_v0001102_t0053") 2022/03/15 07:07:03 DEBUG : file1: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f114dab8f91b84071_d20220315_m070326_c001_v0001098_t0005") 2022/03/15 07:07:03 DEBUG : file1: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1014c11be5f64789_d20220315_m070320_c001_v0001039_t0035") 2022/03/15 07:07:03 DEBUG : file1: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f104ff0a5eb84bfc0_d20220315_m070319_c001_v0001134_t0019") 2022/03/15 07:07:03 DEBUG : file1: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1014c11be5f64772_d20220315_m070314_c001_v0001039_t0022") 2022/03/15 07:07:03 DEBUG : file1: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1102e026042ed170_d20220315_m070113_c001_v0001104_t0057") 2022/03/15 07:07:03 DEBUG : file1: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1014c11be5f645ce_d20220315_m070109_c001_v0001039_t0042") 2022/03/15 07:07:03 DEBUG : file1: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1014c11be5f645c6_d20220315_m070107_c001_v0001039_t0046") 2022/03/15 07:07:03 DEBUG : file1: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1014c11be5f645b8_d20220315_m070102_c001_v0001039_t0043") 2022/03/15 07:07:03 DEBUG : file2: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f10477205e29e7fd6_d20220315_m070447_c001_v0001115_t0024") 2022/03/15 07:07:03 DEBUG : file2: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f11726227037e0caf_d20220315_m070444_c001_v0001102_t0006") 2022/03/15 07:07:03 DEBUG : file2: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1031f5aabc25b0fb_d20220315_m070429_c001_v0001103_t0000") 2022/03/15 07:07:03 DEBUG : file2: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f11726227037e0b6c_d20220315_m070422_c001_v0001102_t0019") 2022/03/15 07:07:03 DEBUG : filename.txt: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f10265c3284d7cafc_d20220315_m070447_c001_v0001116_t0015") 2022/03/15 07:07:03 DEBUG : filename.txt: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f11726227037e0ca7_d20220315_m070444_c001_v0001102_t0025") 2022/03/15 07:07:03 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1072e857c2fa8773_d20220315_m070638_c001_v0001090_t0001") 2022/03/15 07:07:03 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1160e30a25b8e58d_d20220315_m070637_c001_v0001130_t0021") 2022/03/15 07:07:03 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f103ca09ed39b5a38_d20220315_m070635_c001_v0001025_t0035") 2022/03/15 07:07:04 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1160e30a25b8e574_d20220315_m070634_c001_v0001130_t0026") 2022/03/15 07:07:04 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f107d6bf9737e8ca4_d20220315_m070638_c001_v0001130_t0031") 2022/03/15 07:07:04 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1160e30a25b8e587_d20220315_m070636_c001_v0001130_t0026") 2022/03/15 07:07:04 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f11396228ef0d0d54_d20220315_m070635_c001_v0001116_t0030") 2022/03/15 07:07:04 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1160e30a25b8e572_d20220315_m070633_c001_v0001130_t0026") 2022/03/15 07:07:04 DEBUG : large: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1151d7a99bdfd225_d20220315_m070420_c001_v0001134_t0059") 2022/03/15 07:07:04 DEBUG : large: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f11726227037e0add_d20220315_m070413_c001_v0001102_t0017") 2022/03/15 07:07:04 DEBUG : medium: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f103891451c2b0b41_d20220315_m070415_c001_v0001044_t0043") 2022/03/15 07:07:04 DEBUG : medium: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f11726227037e0ad4_d20220315_m070413_c001_v0001102_t0049") 2022/03/15 07:07:04 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f10810795869f641f_d20220315_m070624_c001_v0001039_t0010") 2022/03/15 07:07:04 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1160e30a25b8e52e_d20220315_m070623_c001_v0001130_t0008") 2022/03/15 07:07:04 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f117e16fe05dbdaae_d20220315_m070624_c001_v0001128_t0023") 2022/03/15 07:07:04 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1160e30a25b8e520_d20220315_m070622_c001_v0001130_t0049") 2022/03/15 07:07:04 DEBUG : not-one: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f10562a8113b5ca40_d20220315_m070224_c001_v0001115_t0049") 2022/03/15 07:07:04 DEBUG : not-one: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1014c11be5f646d9_d20220315_m070221_c001_v0001039_t0000") 2022/03/15 07:07:04 DEBUG : one: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1066240ed3fbfb99_d20220315_m070222_c001_v0001134_t0047") 2022/03/15 07:07:05 DEBUG : one: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1014c11be5f646cf_d20220315_m070219_c001_v0001039_t0029") 2022/03/15 07:07:05 DEBUG : potato1: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f11846e11db855fda_d20220315_m070641_c001_v0001105_t0006") 2022/03/15 07:07:05 DEBUG : potato1: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1160e30a25b8e598_d20220315_m070639_c001_v0001130_t0004") 2022/03/15 07:07:05 DEBUG : potato2: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1011276529cac9d3_d20220315_m070653_c001_v0001134_t0058") 2022/03/15 07:07:05 DEBUG : potato2: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f118415152f67888c_d20220315_m070651_c001_v0001131_t0052") 2022/03/15 07:07:05 DEBUG : potato2: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1160e30a25b8e5d4_d20220315_m070648_c001_v0001130_t0010") 2022/03/15 07:07:05 DEBUG : potato2: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1090fc061db6a76a_d20220315_m070643_c001_v0001136_t0027") 2022/03/15 07:07:05 DEBUG : potato2: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1160e30a25b8e59b_d20220315_m070639_c001_v0001130_t0005") 2022/03/15 07:07:05 DEBUG : potato2: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f112769b05ce51761_d20220315_m070409_c001_v0001104_t0055") 2022/03/15 07:07:05 DEBUG : potato2: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f11726227037e0a4e_d20220315_m070407_c001_v0001102_t0006") 2022/03/15 07:07:05 DEBUG : potato2: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f119aab3195c4d43d_d20220315_m070406_c001_v0001091_t0046") 2022/03/15 07:07:05 DEBUG : potato2: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f11726227037e0a0d_d20220315_m070403_c001_v0001102_t0025") 2022/03/15 07:07:05 DEBUG : potato2: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1035e8782098f1fb_d20220315_m070400_c001_v0001104_t0013") 2022/03/15 07:07:05 DEBUG : potato2: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f11726227037e090b_d20220315_m070344_c001_v0001102_t0047") 2022/03/15 07:07:06 DEBUG : potato2: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1088143c05430ba3_d20220315_m070339_c001_v0001100_t0023") 2022/03/15 07:07:06 DEBUG : potato2: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1014c11be5f647c2_d20220315_m070335_c001_v0001039_t0034") 2022/03/15 07:07:06 DEBUG : potato2: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f11574a2736d9e216_d20220315_m070334_c001_v0001100_t0031") 2022/03/15 07:07:06 DEBUG : potato2: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1014c11be5f647b2_d20220315_m070331_c001_v0001039_t0009") 2022/03/15 07:07:06 DEBUG : potato2: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f10793a41646cbd15_d20220315_m070142_c001_v0001157_t0050") 2022/03/15 07:07:06 DEBUG : potato2: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1014c11be5f64644_d20220315_m070139_c001_v0001039_t0001") 2022/03/15 07:07:06 DEBUG : potato2: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f107a5d0212d8e4b3_d20220315_m070130_c001_v0001017_t0026") 2022/03/15 07:07:06 DEBUG : potato2: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1014c11be5f64612_d20220315_m070126_c001_v0001039_t0010") 2022/03/15 07:07:06 DEBUG : potato2: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f116ed131ae120820_d20220315_m070120_c001_v0001116_t0058") 2022/03/15 07:07:06 DEBUG : potato2: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1014c11be5f645ee_d20220315_m070117_c001_v0001039_t0051") 2022/03/15 07:07:06 DEBUG : remotepotato: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f105405a92f928268_d20220315_m070142_c001_v0001105_t0013") 2022/03/15 07:07:06 DEBUG : remotepotato: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1014c11be5f64646_d20220315_m070140_c001_v0001039_t0036") 2022/03/15 07:07:06 DEBUG : remotepotato: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1145e7f18e6d15a8_d20220315_m070130_c001_v0001146_t0051") 2022/03/15 07:07:06 DEBUG : remotepotato: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1014c11be5f64617_d20220315_m070128_c001_v0001039_t0023") 2022/03/15 07:07:06 DEBUG : remotepotato: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f104adc59f7ae79da_d20220315_m070121_c001_v0001105_t0038") 2022/03/15 07:07:06 DEBUG : remotepotato: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1014c11be5f645f6_d20220315_m070118_c001_v0001039_t0047") 2022/03/15 07:07:06 DEBUG : rutabaga: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f100fd38188b74001_d20220315_m070143_c001_v0001102_t0038") 2022/03/15 07:07:06 DEBUG : rutabaga: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1014c11be5f64620_d20220315_m070132_c001_v0001039_t0037") 2022/03/15 07:07:06 DEBUG : rutabaga: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f10848eeff0b09c45_d20220315_m070131_c001_v0001150_t0001") 2022/03/15 07:07:06 DEBUG : rutabaga: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1014c11be5f64606_d20220315_m070123_c001_v0001039_t0012") 2022/03/15 07:07:07 DEBUG : rutabaga: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f112bfd5bd0410b04_d20220315_m070121_c001_v0001105_t0040") 2022/03/15 07:07:07 DEBUG : rutabaga: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1014c11be5f645e2_d20220315_m070114_c001_v0001039_t0023") 2022/03/15 07:07:07 DEBUG : small: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f115bdbdb48f75723_d20220315_m070415_c001_v0001104_t0044") 2022/03/15 07:07:07 DEBUG : small: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f11726227037e0abf_d20220315_m070412_c001_v0001102_t0038") 2022/03/15 07:07:07 DEBUG : sub dir/hello world: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f114dab3e89b816cc_d20220315_m070330_c001_v0001109_t0038") 2022/03/15 07:07:07 DEBUG : sub dir/hello world: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1014c11be5f647ab_d20220315_m070328_c001_v0001039_t0007") 2022/03/15 07:07:07 DEBUG : sub dir/hello world: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1093f5e9aaea9f75_d20220315_m070239_c001_v0001157_t0049") 2022/03/15 07:07:07 DEBUG : sub dir/hello world: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1014c11be5f646f3_d20220315_m070230_c001_v0001039_t0003") 2022/03/15 07:07:07 DEBUG : sub dir/hello world2: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f11288d6b4657022f_d20220315_m070239_c001_v0001116_t0041") 2022/03/15 07:07:07 DEBUG : sub dir/hello world2: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1014c11be5f646f8_d20220315_m070232_c001_v0001039_t0004") 2022/03/15 07:07:07 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1025a5b03c3c96e8_d20220315_m070242_c001_v0001137_t0021") 2022/03/15 07:07:07 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1014c11be5f646fb_d20220315_m070233_c001_v0001039_t0052") 2022/03/15 07:07:07 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f119adcae3212004e_d20220315_m070242_c001_v0001105_t0023") 2022/03/15 07:07:07 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1014c11be5f64704_d20220315_m070234_c001_v0001039_t0037") 2022/03/15 07:07:07 DEBUG : sub dir/potato3: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f108f167de8ba4ebd_d20220315_m070654_c001_v0001105_t0059") 2022/03/15 07:07:07 DEBUG : sub dir/potato3: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f119dcafdd4a4e3cd_d20220315_m070651_c001_v0001099_t0030") 2022/03/15 07:07:07 DEBUG : sub dir/potato3: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1160e30a25b8e5da_d20220315_m070649_c001_v0001130_t0004") 2022/03/15 07:07:07 DEBUG : sub dir/potato3: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f10810795869f62c5_d20220315_m070411_c001_v0001039_t0010") 2022/03/15 07:07:07 DEBUG : sub dir/potato3: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f11726227037e0a61_d20220315_m070408_c001_v0001102_t0043") 2022/03/15 07:07:07 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f104d32868be8dd71_d20220315_m070243_c001_v0001161_t0046") 2022/03/15 07:07:08 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1014c11be5f64708_d20220315_m070234_c001_v0001039_t0001") 2022/03/15 07:07:08 DEBUG : sub/file2: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f116802b3f28a7c49_d20220315_m070512_c001_v0001109_t0013") 2022/03/15 07:07:08 DEBUG : sub/file2: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1160e30a25b8e38b_d20220315_m070511_c001_v0001130_t0009") 2022/03/15 07:07:08 DEBUG : sub/file2: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f101b1e8d0d494409_d20220315_m070453_c001_v0001115_t0023") 2022/03/15 07:07:08 DEBUG : sub/file2: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f11726227037e0d1f_d20220315_m070449_c001_v0001102_t0027") 2022/03/15 07:07:08 DEBUG : sub/file2: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f108129c660856abc_d20220315_m070326_c001_v0001000_t0022") 2022/03/15 07:07:08 DEBUG : sub/file2: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1014c11be5f6478b_d20220315_m070320_c001_v0001039_t0053") 2022/03/15 07:07:08 DEBUG : sub/file2: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1073ce2a41e001b3_d20220315_m070319_c001_v0001101_t0036") 2022/03/15 07:07:08 DEBUG : sub/file2: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1014c11be5f6477a_d20220315_m070316_c001_v0001039_t0007") 2022/03/15 07:07:08 DEBUG : test.sum: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f100fb94ef013860b_d20220315_m070216_c001_v0001032_t0003") 2022/03/15 07:07:08 DEBUG : test.sum: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1014c11be5f646be_d20220315_m070213_c001_v0001039_t0009") 2022/03/15 07:07:08 DEBUG : test.sum: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1014c11be5f646b3_d20220315_m070209_c001_v0001039_t0045") 2022/03/15 07:07:08 DEBUG : test.sum: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1014c11be5f646ae_d20220315_m070206_c001_v0001039_t0041") 2022/03/15 07:07:08 DEBUG : test.sum: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1014c11be5f646a4_d20220315_m070203_c001_v0001039_t0036") 2022/03/15 07:07:08 DEBUG : test.sum: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1014c11be5f64699_d20220315_m070201_c001_v0001039_t0027") 2022/03/15 07:07:08 DEBUG : test.sum: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1014c11be5f64692_d20220315_m070159_c001_v0001039_t0020") 2022/03/15 07:07:08 DEBUG : test.sum: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1014c11be5f64679_d20220315_m070154_c001_v0001039_t0050") 2022/03/15 07:07:08 DEBUG : test.sum: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1077f9123d87da96_d20220315_m070151_c001_v0001044_t0047") 2022/03/15 07:07:08 DEBUG : test.sum: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1014c11be5f6465e_d20220315_m070147_c001_v0001039_t0000") 2022/03/15 07:07:09 DEBUG : test.sum: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1014c11be5f6464e_d20220315_m070143_c001_v0001039_t0034") 2022/03/15 07:07:09 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f11321ce9c79e68ec_d20220315_m070629_c001_v0001021_t0028") 2022/03/15 07:07:09 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1160e30a25b8e549_d20220315_m070627_c001_v0001130_t0058") 2022/03/15 07:07:09 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1006276c239c9a40_d20220315_m070630_c001_v0001115_t0055") 2022/03/15 07:07:09 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1160e30a25b8e53f_d20220315_m070627_c001_v0001130_t0011") 2022/03/15 07:07:09 DEBUG : zend.txt: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f104ecb3480402041_d20220315_m070313_c001_v0001145_t0024") 2022/03/15 07:07:09 DEBUG : zend.txt: Deleting (id "4_z3f224de3601d8bcb7ff80e1b_f1014c11be5f646f0_d20220315_m070228_c001_v0001039_t0020") "./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -fast-list" - Finished OK in 6m10.247186106s (try 1/5)