"./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -fast-list" - Starting (try 1/5) 2022/01/13 05:16:00 DEBUG : Creating backend with remote "TestB2:rclone-test-makubug2ferohor2huleciy4" 2022/01/13 05:16:00 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2022/01/13 05:16:01 DEBUG : Creating backend with remote "/tmp/rclone1059348291" === 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-makubug2ferohor2huleciy4", Local "Local file system at /tmp/rclone1059348291", Modify Window "1ms" === RUN TestMultithreadCopy/{size:131071_streams:2} 2022/01/13 05:16:04 DEBUG : file1: Starting multi-thread copy with 2 parts of size 64Ki 2022/01/13 05:16:04 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131071) size 63.999Ki starting 2022/01/13 05:16:04 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64Ki starting 2022/01/13 05:16:04 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131071) size 63.999Ki finished 2022/01/13 05:16:05 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64Ki finished 2022/01/13 05:16:05 DEBUG : file1: Finished multi-thread copy with 2 parts of size 64Ki === RUN TestMultithreadCopy/{size:131072_streams:2} 2022/01/13 05:16:07 DEBUG : file1: Starting multi-thread copy with 2 parts of size 64Ki 2022/01/13 05:16:07 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131072) size 64Ki starting 2022/01/13 05:16:07 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64Ki starting 2022/01/13 05:16:07 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131072) size 64Ki finished 2022/01/13 05:16:07 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64Ki finished 2022/01/13 05:16:07 DEBUG : file1: Finished multi-thread copy with 2 parts of size 64Ki === RUN TestMultithreadCopy/{size:131073_streams:2} 2022/01/13 05:16:08 DEBUG : file1: Starting multi-thread copy with 2 parts of size 128Ki 2022/01/13 05:16:08 DEBUG : file1: multi-thread copy: stream 2/2 (131072-131073) size 1 starting 2022/01/13 05:16:08 DEBUG : file1: multi-thread copy: stream 1/2 (0-131072) size 128Ki starting 2022/01/13 05:16:08 DEBUG : file1: multi-thread copy: stream 2/2 (131072-131073) size 1 finished 2022/01/13 05:16:08 DEBUG : file1: multi-thread copy: stream 1/2 (0-131072) size 128Ki finished 2022/01/13 05:16:08 DEBUG : file1: Finished multi-thread copy with 2 parts of size 128Ki --- PASS: TestMultithreadCopy (8.54s) --- PASS: TestMultithreadCopy/{size:131071_streams:2} (4.22s) --- PASS: TestMultithreadCopy/{size:131072_streams:2} (2.23s) --- PASS: TestMultithreadCopy/{size:131073_streams:2} (1.41s) === RUN TestSizeDiffers --- PASS: TestSizeDiffers (0.00s) === RUN TestReOpen === RUN TestReOpen/Seek === RUN TestReOpen/Seek/Basics === RUN TestReOpen/Seek/ErrorAtStart === RUN TestReOpen/Seek/WithErrors 2022/01/13 05:16:09 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/10: test error 2022/01/13 05:16:09 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/10: test error 2022/01/13 05:16:09 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/10: test error === RUN TestReOpen/Seek/TooManyErrors 2022/01/13 05:16:09 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/3: test error 2022/01/13 05:16:09 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/3: test error 2022/01/13 05:16:09 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/3: test error 2022/01/13 05:16:09 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/01/13 05:16:09 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/10: test error 2022/01/13 05:16:09 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/10: test error 2022/01/13 05:16:09 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/10: test error === RUN TestReOpen/Range/TooManyErrors 2022/01/13 05:16:09 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/3: test error 2022/01/13 05:16:09 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/3: test error 2022/01/13 05:16:09 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/3: test error 2022/01/13 05:16:09 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-makubug2ferohor2huleciy4", Local "Local file system at /tmp/rclone1059348291", 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 (7.08s) --- PASS: TestCheck/1 (0.17s) --- PASS: TestCheck/2 (0.17s) --- PASS: TestCheck/3 (0.18s) --- PASS: TestCheck/4 (0.18s) --- PASS: TestCheck/5 (0.17s) --- PASS: TestCheck/6 (0.17s) --- PASS: TestCheck/7 (0.17s) === RUN TestCheckFsError 2022/01/13 05:16:16 DEBUG : Creating backend with remote "non-existent" 2022/01/13 05:16:16 DEBUG : Creating backend with remote "non-existent" 2022/01/13 05:16:16 DEBUG : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/non-existent: Waiting for checks to finish 2022/01/13 05:16:16 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/01/13 05:16:16 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/non-existent: 2 differences found 2022/01/13 05:16:16 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-makubug2ferohor2huleciy4", Local "Local file system at /tmp/rclone1059348291", 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 (10.27s) --- PASS: TestCheckDownload/1 (0.88s) --- PASS: TestCheckDownload/2 (0.34s) --- PASS: TestCheckDownload/3 (0.34s) --- PASS: TestCheckDownload/4 (0.57s) --- PASS: TestCheckDownload/5 (0.35s) --- PASS: TestCheckDownload/6 (0.35s) --- PASS: TestCheckDownload/7 (0.35s) === RUN TestCheckSizeOnly run.go:181: Remote "B2 bucket rclone-test-makubug2ferohor2huleciy4", Local "Local file system at /tmp/rclone1059348291", 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 (10.68s) --- PASS: TestCheckSizeOnly/1 (0.24s) --- PASS: TestCheckSizeOnly/2 (0.17s) --- 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-makubug2ferohor2huleciy4", Local "Local file system at /tmp/rclone1059348291", Modify Window "1ms" 2022/01/13 05:16:39 NOTICE: test.sum: improperly formatted checksum line 4 2022/01/13 05:16:39 NOTICE: test.sum: improperly formatted checksum line 5 2022/01/13 05:16:39 NOTICE: test.sum: improperly formatted checksum line 6 2022/01/13 05:16:39 NOTICE: test.sum: 2 warning(s) suppressed... 2022/01/13 05:16:40 NOTICE: test.sum: improperly formatted checksum line 4 2022/01/13 05:16:40 NOTICE: test.sum: improperly formatted checksum line 5 2022/01/13 05:16:40 NOTICE: test.sum: improperly formatted checksum line 6 2022/01/13 05:16:40 NOTICE: test.sum: 2 warning(s) suppressed... --- PASS: TestParseSumFile (3.60s) === RUN TestCheckSum run.go:181: Remote "B2 bucket rclone-test-makubug2ferohor2huleciy4", Local "Local file system at /tmp/rclone1059348291", Modify Window "1ms" 2022/01/13 05:16:41 DEBUG : Creating backend with remote "TestB2:rclone-test-makubug2ferohor2huleciy4/data" 2022/01/13 05:16:42 DEBUG : Couldn't decode error response: EOF check_test.go:356: B2 bucket rclone-test-makubug2ferohor2huleciy4 path data lacks md5, skipping --- SKIP: TestCheckSum (1.31s) === RUN TestCheckSumDownload run.go:181: Remote "B2 bucket rclone-test-makubug2ferohor2huleciy4", Local "Local file system at /tmp/rclone1059348291", Modify Window "1ms" 2022/01/13 05:16:42 DEBUG : Creating backend with remote "TestB2:rclone-test-makubug2ferohor2huleciy4/data" 2022/01/13 05:16:42 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 2022/01/13 05:17:02 DEBUG : pacer: low level retry 1/10 (error Post "https://api001.backblazeb2.com/b2api/v1/b2_list_file_names": EOF) 2022/01/13 05:17:02 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2022/01/13 05:17:02 DEBUG : pacer: Reducing sleep to 10ms === RUN TestCheckSumDownload/subtest7 --- PASS: TestCheckSumDownload (23.12s) --- PASS: TestCheckSumDownload/subtest1 (1.01s) --- PASS: TestCheckSumDownload/subtest2 (0.80s) --- PASS: TestCheckSumDownload/subtest3 (0.95s) --- PASS: TestCheckSumDownload/subtest4 (0.70s) --- PASS: TestCheckSumDownload/subtest5 (0.72s) --- PASS: TestCheckSumDownload/subtest6 (0.70s) --- PASS: TestCheckSumDownload/subtest7 (1.39s) === RUN TestDeduplicateInteractive run.go:181: Remote "B2 bucket rclone-test-makubug2ferohor2huleciy4", Local "Local file system at /tmp/rclone1059348291", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateInteractive (0.34s) === RUN TestDeduplicateSkip run.go:181: Remote "B2 bucket rclone-test-makubug2ferohor2huleciy4", Local "Local file system at /tmp/rclone1059348291", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSkip (0.34s) === RUN TestDeduplicateSizeOnly run.go:181: Remote "B2 bucket rclone-test-makubug2ferohor2huleciy4", Local "Local file system at /tmp/rclone1059348291", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSizeOnly (0.34s) === RUN TestDeduplicateFirst run.go:181: Remote "B2 bucket rclone-test-makubug2ferohor2huleciy4", Local "Local file system at /tmp/rclone1059348291", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateFirst (0.34s) === RUN TestDeduplicateNewest run.go:181: Remote "B2 bucket rclone-test-makubug2ferohor2huleciy4", Local "Local file system at /tmp/rclone1059348291", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateNewest (0.34s) === RUN TestDeduplicateNewestByHash run.go:181: Remote "B2 bucket rclone-test-makubug2ferohor2huleciy4", Local "Local file system at /tmp/rclone1059348291", Modify Window "1ms" 2022/01/13 05:17:10 INFO : B2 bucket rclone-test-makubug2ferohor2huleciy4: Looking for duplicate sha1 hashes using newest mode. 2022/01/13 05:17:10 NOTICE: 5a6ffc70f04b488baf8aa90889b3ce0d80d7d447: Found 3 files with duplicate sha1 hashes 2022/01/13 05:17:11 INFO : one: Deleted 2022/01/13 05:17:11 INFO : also/one: Deleted 2022/01/13 05:17:11 NOTICE: 5a6ffc70f04b488baf8aa90889b3ce0d80d7d447: Deleted 2 extra copies --- PASS: TestDeduplicateNewestByHash (5.85s) === RUN TestDeduplicateOldest run.go:181: Remote "B2 bucket rclone-test-makubug2ferohor2huleciy4", Local "Local file system at /tmp/rclone1059348291", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateOldest (0.37s) === RUN TestDeduplicateLargest run.go:181: Remote "B2 bucket rclone-test-makubug2ferohor2huleciy4", Local "Local file system at /tmp/rclone1059348291", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateLargest (0.34s) === RUN TestDeduplicateSmallest run.go:181: Remote "B2 bucket rclone-test-makubug2ferohor2huleciy4", Local "Local file system at /tmp/rclone1059348291", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSmallest (0.34s) === RUN TestDeduplicateRename run.go:181: Remote "B2 bucket rclone-test-makubug2ferohor2huleciy4", Local "Local file system at /tmp/rclone1059348291", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateRename (0.34s) === RUN TestMergeDirs run.go:181: Remote "B2 bucket rclone-test-makubug2ferohor2huleciy4", Local "Local file system at /tmp/rclone1059348291", Modify Window "1ms" dedupe_test.go:267: Can't merge directories --- SKIP: TestMergeDirs (0.34s) === RUN TestListDirSorted run.go:181: Remote "B2 bucket rclone-test-makubug2ferohor2huleciy4", Local "Local file system at /tmp/rclone1059348291", Modify Window "1ms" 2022/01/13 05:17:24 DEBUG : a.txt: Excluded 2022/01/13 05:17:25 DEBUG : sub dir/hello world: Excluded 2022/01/13 05:17:25 DEBUG : sub dir/hello world2: Excluded 2022/01/13 05:17:25 DEBUG : sub dir/hello world: Excluded 2022/01/13 05:17:25 DEBUG : sub dir/hello world2: Excluded 2022/01/13 05:17:26 DEBUG : sub dir/ignore dir: Excluded 2022/01/13 05:17:26 DEBUG : Couldn't decode error response: EOF 2022/01/13 05:17:26 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSorted (16.69s) === RUN TestListJSON run.go:181: Remote "B2 bucket rclone-test-makubug2ferohor2huleciy4", Local "Local file system at /tmp/rclone1059348291", 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/01/13 05:17:34 ERROR : file1: Failed to read hash: hash type not supported --- PASS: TestListJSON (5.36s) --- PASS: TestListJSON/Default (0.17s) --- PASS: TestListJSON/FilesOnly (0.17s) --- PASS: TestListJSON/DirsOnly (0.17s) --- PASS: TestListJSON/Recurse (0.17s) --- PASS: TestListJSON/SubDir (0.17s) --- PASS: TestListJSON/NoModTime (0.17s) --- PASS: TestListJSON/NoMimeType (0.17s) --- PASS: TestListJSON/ShowHash (0.17s) --- PASS: TestListJSON/HashTypes (0.17s) === RUN TestStatJSON run.go:181: Remote "B2 bucket rclone-test-makubug2ferohor2huleciy4", Local "Local file system at /tmp/rclone1059348291", Modify Window "1ms" === RUN TestStatJSON/Root === RUN TestStatJSON/RootFilesOnly === RUN TestStatJSON/RootDirsOnly === RUN TestStatJSON/Dir 2022/01/13 05:17:42 DEBUG : Couldn't decode error response: EOF === RUN TestStatJSON/File === RUN TestStatJSON/NotFound 2022/01/13 05:17:43 DEBUG : Couldn't decode error response: EOF === RUN TestStatJSON/DirFilesOnly 2022/01/13 05:17:43 DEBUG : Couldn't decode error response: EOF === RUN TestStatJSON/FileFilesOnly === RUN TestStatJSON/NotFoundFilesOnly 2022/01/13 05:17:43 DEBUG : Couldn't decode error response: EOF === RUN TestStatJSON/DirDirsOnly === RUN TestStatJSON/FileDirsOnly === RUN TestStatJSON/NotFoundDirsOnly === RUN TestStatJSON/RootNotFound 2022/01/13 05:17:44 DEBUG : Creating backend with remote "TestB2:rclone-test-makubug2ferohor2huleciy4/notfound" 2022/01/13 05:17:45 DEBUG : Couldn't decode error response: EOF --- PASS: TestStatJSON (10.68s) --- PASS: TestStatJSON/Root (0.17s) --- PASS: TestStatJSON/RootFilesOnly (0.00s) --- PASS: TestStatJSON/RootDirsOnly (0.17s) --- PASS: TestStatJSON/Dir (0.85s) --- PASS: TestStatJSON/File (0.17s) --- PASS: TestStatJSON/NotFound (0.34s) --- PASS: TestStatJSON/DirFilesOnly (0.17s) --- PASS: TestStatJSON/FileFilesOnly (0.17s) --- PASS: TestStatJSON/NotFoundFilesOnly (0.17s) --- PASS: TestStatJSON/DirDirsOnly (0.17s) --- PASS: TestStatJSON/FileDirsOnly (0.17s) --- PASS: TestStatJSON/NotFoundDirsOnly (0.17s) --- PASS: TestStatJSON/RootNotFound (1.38s) === RUN TestMkdir run.go:181: Remote "B2 bucket rclone-test-makubug2ferohor2huleciy4", Local "Local file system at /tmp/rclone1059348291", Modify Window "1ms" 2022/01/13 05:17:47 DEBUG : B2 bucket rclone-test-makubug2ferohor2huleciy4: Making directory 2022/01/13 05:17:47 DEBUG : B2 bucket rclone-test-makubug2ferohor2huleciy4: Making directory --- PASS: TestMkdir (0.51s) === RUN TestLsd run.go:181: Remote "B2 bucket rclone-test-makubug2ferohor2huleciy4", Local "Local file system at /tmp/rclone1059348291", Modify Window "1ms" --- PASS: TestLsd (1.97s) === RUN TestLs run.go:181: Remote "B2 bucket rclone-test-makubug2ferohor2huleciy4", Local "Local file system at /tmp/rclone1059348291", Modify Window "1ms" --- PASS: TestLs (5.49s) === RUN TestLsWithFilesFrom run.go:181: Remote "B2 bucket rclone-test-makubug2ferohor2huleciy4", Local "Local file system at /tmp/rclone1059348291", Modify Window "1ms" 2022/01/13 05:17:57 DEBUG : empty space: Excluded from sync (and deletion) 2022/01/13 05:17:58 DEBUG : Couldn't decode error response: EOF --- PASS: TestLsWithFilesFrom (4.17s) === RUN TestLsLong run.go:181: Remote "B2 bucket rclone-test-makubug2ferohor2huleciy4", Local "Local file system at /tmp/rclone1059348291", Modify Window "1ms" --- PASS: TestLsLong (4.49s) === RUN TestHashSums run.go:181: Remote "B2 bucket rclone-test-makubug2ferohor2huleciy4", Local "Local file system at /tmp/rclone1059348291", Modify Window "1ms" === RUN TestHashSums/Sha1 === RUN TestHashSums/Sha1Download --- PASS: TestHashSums (6.87s) --- PASS: TestHashSums/Sha1 (0.18s) --- PASS: TestHashSums/Sha1Download (0.88s) === RUN TestHashSumsWithErrors 2022/01/13 05:18:11 DEBUG : Creating backend with remote ":memory:" 2022/01/13 05:18:11 ERROR : file1: hash unsupported: hash type not supported --- PASS: TestHashSumsWithErrors (0.00s) === RUN TestHashStream 2022/01/13 05:18:11 DEBUG : Creating md5 hash of 0 bytes read from input stream 2022/01/13 05:18:11 DEBUG : Creating md5 hash of 0 bytes read from input stream 2022/01/13 05:18:11 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2022/01/13 05:18:11 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2022/01/13 05:18:11 DEBUG : Creating md5 hash of 12 bytes read from input stream 2022/01/13 05:18:11 DEBUG : Creating md5 hash of 12 bytes read from input stream 2022/01/13 05:18:11 DEBUG : Creating sha1 hash of 12 bytes read from input stream 2022/01/13 05:18:11 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-makubug2ferohor2huleciy4", Local "Local file system at /tmp/rclone1059348291", Modify Window "1ms" --- PASS: TestCount (4.72s) === RUN TestDelete run.go:181: Remote "B2 bucket rclone-test-makubug2ferohor2huleciy4", Local "Local file system at /tmp/rclone1059348291", Modify Window "1ms" 2022/01/13 05:18:17 DEBUG : Waiting for deletions to finish 2022/01/13 05:18:17 DEBUG : large: Excluded from sync (and deletion) 2022/01/13 05:18:18 INFO : medium: Deleted 2022/01/13 05:18:18 INFO : small: Deleted --- PASS: TestDelete (4.19s) === RUN TestRetry 2022/01/13 05:18:20 DEBUG : Received error: EOF - low level retry 1/5 2022/01/13 05:18:20 DEBUG : Received error: EOF - low level retry 2/5 2022/01/13 05:18:20 DEBUG : Received error: EOF - low level retry 1/5 2022/01/13 05:18:20 DEBUG : Received error: EOF - low level retry 2/5 2022/01/13 05:18:20 DEBUG : Received error: EOF - low level retry 3/5 2022/01/13 05:18:20 DEBUG : Received error: EOF - low level retry 4/5 2022/01/13 05:18:20 DEBUG : Received error: EOF - low level retry 5/5 --- PASS: TestRetry (0.00s) === RUN TestCat run.go:181: Remote "B2 bucket rclone-test-makubug2ferohor2huleciy4", Local "Local file system at /tmp/rclone1059348291", Modify Window "1ms" --- PASS: TestCat (6.86s) === RUN TestPurge 2022/01/13 05:18:26 DEBUG : Creating backend with remote "TestB2:rclone-test-dudanom4cuhefoq0femagas9" 2022/01/13 05:18:26 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2022/01/13 05:18:27 DEBUG : Creating backend with remote "/tmp/rclone3687109421" run.go:181: Remote "B2 bucket rclone-test-dudanom4cuhefoq0femagas9", Local "Local file system at /tmp/rclone3687109421", Modify Window "1ms" 2022/01/13 05:18:31 DEBUG : A2: Making directory 2022/01/13 05:18:31 DEBUG : A1/B2: Making directory 2022/01/13 05:18:31 DEBUG : A1/B2/C2: Making directory 2022/01/13 05:18:31 DEBUG : A1/B1/C3: Making directory 2022/01/13 05:18:31 DEBUG : A3: Making directory 2022/01/13 05:18:31 DEBUG : A3/B3: Making directory 2022/01/13 05:18:31 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/01/13 05:18:32 DEBUG : A1/B1/C1/one: Deleting (id "4_zdf227db3d0fd5b1b7fe80e1b_f111e5579c322b206_d20220113_m051828_c001_v0001033_t0053") 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/01/13 05:18:33 DEBUG : A1/two: Deleting (id "4_zdf227db3d0fd5b1b7fe80e1b_f111e5579c322b20b_d20220113_m051831_c001_v0001033_t0056") 2022/01/13 05:18:33 DEBUG : B2 bucket rclone-test-dudanom4cuhefoq0femagas9: Purge remote 2022/01/13 05:18:33 purge failed: directory not found --- PASS: TestPurge (6.98s) === RUN TestRmdirsNoLeaveRoot run.go:181: Remote "B2 bucket rclone-test-makubug2ferohor2huleciy4", Local "Local file system at /tmp/rclone1059348291", Modify Window "1ms" 2022/01/13 05:18:35 DEBUG : A2: Making directory 2022/01/13 05:18:35 DEBUG : A1/B2: Making directory 2022/01/13 05:18:35 DEBUG : A1/B2/C2: Making directory 2022/01/13 05:18:35 DEBUG : A1/B1/C3: Making directory 2022/01/13 05:18:35 DEBUG : A3: Making directory 2022/01/13 05:18:35 DEBUG : A3/B3: Making directory 2022/01/13 05:18:35 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/01/13 05:18:36 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 (4.92s) === RUN TestRmdirsLeaveRoot run.go:181: Remote "B2 bucket rclone-test-makubug2ferohor2huleciy4", Local "Local file system at /tmp/rclone1059348291", Modify Window "1ms" 2022/01/13 05:18:38 DEBUG : A1: Making directory 2022/01/13 05:18:38 DEBUG : A1/B1: Making directory 2022/01/13 05:18:38 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.93s) === RUN TestRmdirsWithFilter run.go:181: Remote "B2 bucket rclone-test-makubug2ferohor2huleciy4", Local "Local file system at /tmp/rclone1059348291", Modify Window "1ms" 2022/01/13 05:18:39 DEBUG : A1: Making directory 2022/01/13 05:18:39 DEBUG : A1/B1: Making directory 2022/01/13 05:18:39 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.85s) === RUN TestCopyURL run.go:181: Remote "B2 bucket rclone-test-makubug2ferohor2huleciy4", Local "Local file system at /tmp/rclone1059348291", Modify Window "1ms" 2022/01/13 05:18:42 DEBUG : filename.txt: File name found in url --- PASS: TestCopyURL (9.41s) === RUN TestCopyURLToWriter --- PASS: TestCopyURLToWriter (0.00s) === RUN TestMoveFile run.go:181: Remote "B2 bucket rclone-test-makubug2ferohor2huleciy4", Local "Local file system at /tmp/rclone1059348291", Modify Window "1ms" 2022/01/13 05:18:50 DEBUG : Couldn't decode error response: EOF 2022/01/13 05:18:50 DEBUG : file1: Need to transfer - File not found at Destination 2022/01/13 05:18:51 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2022/01/13 05:18:51 INFO : file1: Copied (new) to: sub/file2 2022/01/13 05:18:51 INFO : file1: Deleted 2022/01/13 05:18:52 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/13 05:18:52 DEBUG : file1: Unchanged skipping 2022/01/13 05:18:52 INFO : file1: Deleted 2022/01/13 05:18:52 DEBUG : B2 bucket rclone-test-makubug2ferohor2huleciy4: don't need to copy/move sub/file2, it is already at target location --- PASS: TestMoveFile (3.17s) === RUN TestMoveFileWithIgnoreExisting run.go:181: Remote "B2 bucket rclone-test-makubug2ferohor2huleciy4", Local "Local file system at /tmp/rclone1059348291", Modify Window "1ms" 2022/01/13 05:18:53 DEBUG : Couldn't decode error response: EOF 2022/01/13 05:18:53 DEBUG : file1: Need to transfer - File not found at Destination 2022/01/13 05:18:56 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2022/01/13 05:18:56 INFO : file1: Copied (new) 2022/01/13 05:18:56 INFO : file1: Deleted 2022/01/13 05:18:56 DEBUG : file1: Destination exists, skipping 2022/01/13 05:18:56 DEBUG : file1: Not removing source file as destination file exists and --ignore-existing is set --- PASS: TestMoveFileWithIgnoreExisting (4.35s) === RUN TestCaseInsensitiveMoveFile run.go:181: Remote "B2 bucket rclone-test-makubug2ferohor2huleciy4", Local "Local file system at /tmp/rclone1059348291", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFile (0.34s) === RUN TestMoveFileBackupDir run.go:181: Remote "B2 bucket rclone-test-makubug2ferohor2huleciy4", Local "Local file system at /tmp/rclone1059348291", Modify Window "1ms" 2022/01/13 05:18:59 DEBUG : Creating backend with remote "TestB2:rclone-test-makubug2ferohor2huleciy4/backup" 2022/01/13 05:19:01 DEBUG : Couldn't decode error response: EOF 2022/01/13 05:19:01 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18) 2022/01/13 05:19:01 DEBUG : Couldn't decode error response: EOF 2022/01/13 05:19:03 DEBUG : dst/file1: sha1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK 2022/01/13 05:19:03 INFO : dst/file1: Copied (server-side copy) 2022/01/13 05:19:04 INFO : dst/file1: Deleted 2022/01/13 05:19:05 DEBUG : dst/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2022/01/13 05:19:05 INFO : dst/file1: Copied (new) 2022/01/13 05:19:05 INFO : dst/file1: Deleted --- PASS: TestMoveFileBackupDir (8.69s) === RUN TestCopyFile run.go:181: Remote "B2 bucket rclone-test-makubug2ferohor2huleciy4", Local "Local file system at /tmp/rclone1059348291", Modify Window "1ms" 2022/01/13 05:19:07 DEBUG : Couldn't decode error response: EOF 2022/01/13 05:19:07 DEBUG : file1: Need to transfer - File not found at Destination 2022/01/13 05:19:08 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2022/01/13 05:19:08 INFO : file1: Copied (new) to: sub/file2 2022/01/13 05:19:08 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/01/13 05:19:08 DEBUG : file1: Unchanged skipping 2022/01/13 05:19:08 DEBUG : B2 bucket rclone-test-makubug2ferohor2huleciy4: don't need to copy/move sub/file2, it is already at target location --- PASS: TestCopyFile (2.81s) === RUN TestCopyFileBackupDir run.go:181: Remote "B2 bucket rclone-test-makubug2ferohor2huleciy4", Local "Local file system at /tmp/rclone1059348291", Modify Window "1ms" 2022/01/13 05:19:10 DEBUG : Creating backend with remote "TestB2:rclone-test-makubug2ferohor2huleciy4/backup" 2022/01/13 05:19:11 DEBUG : Couldn't decode error response: EOF 2022/01/13 05:19:11 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18) 2022/01/13 05:19:12 DEBUG : Couldn't decode error response: EOF 2022/01/13 05:19:13 DEBUG : dst/file1: sha1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK 2022/01/13 05:19:13 INFO : dst/file1: Copied (server-side copy) 2022/01/13 05:19:14 INFO : dst/file1: Deleted 2022/01/13 05:19:15 DEBUG : dst/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2022/01/13 05:19:15 INFO : dst/file1: Copied (new) --- PASS: TestCopyFileBackupDir (7.47s) === RUN TestCopyFileCompareDest run.go:181: Remote "B2 bucket rclone-test-makubug2ferohor2huleciy4", Local "Local file system at /tmp/rclone1059348291", Modify Window "1ms" 2022/01/13 05:19:16 DEBUG : Creating backend with remote "TestB2:rclone-test-makubug2ferohor2huleciy4/dst" 2022/01/13 05:19:18 DEBUG : Couldn't decode error response: EOF 2022/01/13 05:19:18 DEBUG : Couldn't decode error response: EOF 2022/01/13 05:19:18 DEBUG : Creating backend with remote "TestB2:rclone-test-makubug2ferohor2huleciy4/CompareDest" 2022/01/13 05:19:18 DEBUG : Couldn't decode error response: EOF 2022/01/13 05:19:19 DEBUG : Couldn't decode error response: EOF 2022/01/13 05:19:19 DEBUG : one: Need to transfer - File not found at Destination 2022/01/13 05:19:21 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/01/13 05:19:21 INFO : one: Copied (new) 2022/01/13 05:19:22 DEBUG : Couldn't decode error response: EOF 2022/01/13 05:19:22 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/01/13 05:19:22 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2022/01/13 05:19:22 INFO : one: Copied (replaced existing) 2022/01/13 05:19:24 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/13 05:19:24 DEBUG : one: Destination found in --compare-dest, skipping 2022/01/13 05:19:25 DEBUG : Couldn't decode error response: EOF 2022/01/13 05:19:25 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/13 05:19:25 DEBUG : two: Destination found in --compare-dest, skipping 2022/01/13 05:19:26 DEBUG : Couldn't decode error response: EOF 2022/01/13 05:19:26 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/13 05:19:26 DEBUG : two: Destination found in --compare-dest, skipping 2022/01/13 05:19:26 DEBUG : Couldn't decode error response: EOF 2022/01/13 05:19:26 DEBUG : two: Sizes differ (src 5 vs dst 3) 2022/01/13 05:19:26 DEBUG : two: Need to transfer - File not found at Destination 2022/01/13 05:19:27 DEBUG : two: sha1 = a4be8877ad88f3109a6411985a1f359e10e2d930 OK 2022/01/13 05:19:27 INFO : two: Copied (new) --- PASS: TestCopyFileCompareDest (14.65s) === RUN TestCopyFileCopyDest run.go:181: Remote "B2 bucket rclone-test-makubug2ferohor2huleciy4", Local "Local file system at /tmp/rclone1059348291", Modify Window "1ms" 2022/01/13 05:19:31 DEBUG : Creating backend with remote "TestB2:rclone-test-makubug2ferohor2huleciy4/dst" 2022/01/13 05:19:32 DEBUG : Couldn't decode error response: EOF 2022/01/13 05:19:33 DEBUG : Couldn't decode error response: EOF 2022/01/13 05:19:33 DEBUG : Creating backend with remote "TestB2:rclone-test-makubug2ferohor2huleciy4/CopyDest" 2022/01/13 05:19:33 DEBUG : Couldn't decode error response: EOF 2022/01/13 05:19:33 DEBUG : Couldn't decode error response: EOF 2022/01/13 05:19:33 DEBUG : one: Need to transfer - File not found at Destination 2022/01/13 05:19:36 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/01/13 05:19:36 INFO : one: Copied (new) 2022/01/13 05:19:37 DEBUG : Couldn't decode error response: EOF 2022/01/13 05:19:37 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/01/13 05:19:39 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2022/01/13 05:19:39 INFO : one: Copied (replaced existing) 2022/01/13 05:19:41 DEBUG : Creating backend with remote "TestB2:rclone-test-makubug2ferohor2huleciy4/BackupDir" 2022/01/13 05:19:42 DEBUG : Couldn't decode error response: EOF 2022/01/13 05:19:42 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/13 05:19:42 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/01/13 05:19:42 DEBUG : Couldn't decode error response: EOF 2022/01/13 05:19:44 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/01/13 05:19:44 INFO : one: Copied (server-side copy) 2022/01/13 05:19:45 INFO : one: Deleted 2022/01/13 05:19:45 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2022/01/13 05:19:45 INFO : one: Copied (server-side copy) 2022/01/13 05:19:45 DEBUG : one: Destination found in --copy-dest, using server-side copy 2022/01/13 05:19:46 DEBUG : pacer: low level retry 1/10 (error Post "https://api001.backblazeb2.com/b2api/v1/b2_list_file_names": EOF) 2022/01/13 05:19:46 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2022/01/13 05:19:46 DEBUG : pacer: Reducing sleep to 10ms 2022/01/13 05:19:48 DEBUG : Couldn't decode error response: EOF 2022/01/13 05:19:48 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/13 05:19:49 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2022/01/13 05:19:49 INFO : two: Copied (server-side copy) 2022/01/13 05:19:49 DEBUG : two: Destination found in --copy-dest, using server-side copy 2022/01/13 05:19:50 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/13 05:19:50 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/01/13 05:19:50 DEBUG : two: Unchanged skipping 2022/01/13 05:19:54 DEBUG : Couldn't decode error response: EOF 2022/01/13 05:19:54 DEBUG : three: Sizes differ (src 7 vs dst 5) 2022/01/13 05:19:54 DEBUG : three: Destination not found in --copy-dest 2022/01/13 05:19:54 DEBUG : three: Need to transfer - File not found at Destination 2022/01/13 05:19:55 DEBUG : three: sha1 = 370e613335f3cf2f19b2a2d35a86402f55c659f8 OK 2022/01/13 05:19:55 INFO : three: Copied (new) --- PASS: TestCopyFileCopyDest (28.76s) === 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-makubug2ferohor2huleciy4", Local "Local file system at /tmp/rclone1059348291", Modify Window "1ms" 2022/01/13 05:20:04 DEBUG : A1/B2: Making directory 2022/01/13 05:20:04 DEBUG : A1/B1/C3: Making directory fstest.go:251: Filtering empty directory "A1/B2" fstest.go:251: Filtering empty directory "A1/B1/C3" 2022/01/13 05:20:05 DEBUG : Couldn't decode error response: EOF 2022/01/13 05:20:05 DEBUG : Couldn't decode error response: EOF 2022/01/13 05:20:05 DEBUG : Couldn't decode error response: EOF 2022/01/13 05:20:05 DEBUG : Couldn't decode error response: EOF 2022/01/13 05:20:06 DEBUG : A1/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2022/01/13 05:20:06 INFO : A1/B1/C1/four: Copied (server-side copy) to: A2/B1/C1/four 2022/01/13 05:20:06 DEBUG : A1/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2022/01/13 05:20:06 INFO : A1/two: Copied (server-side copy) to: A2/two 2022/01/13 05:20:06 DEBUG : A1/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/01/13 05:20:06 INFO : A1/one: Copied (server-side copy) to: A2/one 2022/01/13 05:20:07 INFO : A1/B1/C1/four: Deleted 2022/01/13 05:20:07 INFO : A1/two: Deleted 2022/01/13 05:20:07 DEBUG : Couldn't decode error response: EOF 2022/01/13 05:20:07 DEBUG : A1/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2022/01/13 05:20:07 INFO : A1/B1/C2/five: Copied (server-side copy) to: A2/B1/C2/five 2022/01/13 05:20:07 INFO : A1/one: Deleted 2022/01/13 05:20:07 INFO : A1/B1/C2/five: Deleted 2022/01/13 05:20:08 DEBUG : A1/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2022/01/13 05:20:08 INFO : A1/B1/three: Copied (server-side copy) to: A2/B1/three 2022/01/13 05:20:09 INFO : A1/B1/three: Deleted fstest.go:251: Filtering empty directory "A2/B2" fstest.go:251: Filtering empty directory "A2/B1/C3" 2022/01/13 05:20:09 DEBUG : Couldn't decode error response: EOF 2022/01/13 05:20:09 DEBUG : Couldn't decode error response: EOF 2022/01/13 05:20:09 DEBUG : Couldn't decode error response: EOF 2022/01/13 05:20:09 DEBUG : Couldn't decode error response: EOF 2022/01/13 05:20:09 DEBUG : A2/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2022/01/13 05:20:09 INFO : A2/B1/three: Copied (server-side copy) to: A3/B1/three 2022/01/13 05:20:10 DEBUG : A2/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2022/01/13 05:20:10 INFO : A2/B1/C1/four: Copied (server-side copy) to: A3/B1/C1/four 2022/01/13 05:20:10 DEBUG : A2/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2022/01/13 05:20:10 INFO : A2/B1/C2/five: Copied (server-side copy) to: A3/B1/C2/five 2022/01/13 05:20:10 DEBUG : A2/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/01/13 05:20:10 INFO : A2/one: Copied (server-side copy) to: A3/one 2022/01/13 05:20:10 INFO : A2/B1/three: Deleted 2022/01/13 05:20:10 INFO : A2/B1/C2/five: Deleted 2022/01/13 05:20:10 INFO : A2/B1/C1/four: Deleted 2022/01/13 05:20:10 DEBUG : Couldn't decode error response: EOF 2022/01/13 05:20:11 INFO : A2/one: Deleted 2022/01/13 05:20:12 DEBUG : A2/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2022/01/13 05:20:12 INFO : A2/two: Copied (server-side copy) to: A3/two 2022/01/13 05:20:13 INFO : A2/two: Deleted fstest.go:251: Filtering empty directory "A3/B2" fstest.go:251: Filtering empty directory "A3/B1/C3" --- PASS: TestDirMove (19.96s) === RUN TestGetFsInfo run.go:181: Remote "B2 bucket rclone-test-makubug2ferohor2huleciy4", Local "Local file system at /tmp/rclone1059348291", 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-makubug2ferohor2huleciy4", Local "Local file system at /tmp/rclone1059348291", Modify Window "1ms" 2022/01/13 05:20:20 DEBUG : B2 bucket rclone-test-makubug2ferohor2huleciy4: File to upload is small (34 bytes), uploading instead of streaming 2022/01/13 05:20:21 DEBUG : no_checksum_small_file_from_pipe: sha1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK 2022/01/13 05:20:21 INFO : no_checksum_small_file_from_pipe: Copied (new) 2022/01/13 05:20:21 DEBUG : no_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2022/01/13 05:20:24 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-makubug2ferohor2huleciy4", Local "Local file system at /tmp/rclone1059348291", Modify Window "1ms" 2022/01/13 05:20:26 DEBUG : B2 bucket rclone-test-makubug2ferohor2huleciy4: File to upload is small (34 bytes), uploading instead of streaming 2022/01/13 05:20:27 DEBUG : with_checksum_small_file_from_pipe: sha1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK 2022/01/13 05:20:27 INFO : with_checksum_small_file_from_pipe: Copied (new) 2022/01/13 05:20:27 DEBUG : with_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2022/01/13 05:20:28 DEBUG : with_checksum_big_file_from_pipe: sha1 = d0080f73aa8b494570ad63df1beb25c01ea9eea2 OK 2022/01/13 05:20:28 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-makubug2ferohor2huleciy4", Local "Local file system at /tmp/rclone1059348291", Modify Window "1ms" 2022/01/13 05:20:30 DEBUG : B2 bucket rclone-test-makubug2ferohor2huleciy4: File to upload is small (34 bytes), uploading instead of streaming 2022/01/13 05:20:30 INFO : ignore_checksum_small_file_from_pipe: Copied (new) 2022/01/13 05:20:30 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2022/01/13 05:20:31 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-makubug2ferohor2huleciy4", Local "Local file system at /tmp/rclone1059348291", Modify Window "1ms" 2022/01/13 05:20:33 DEBUG : B2 bucket rclone-test-makubug2ferohor2huleciy4: File to upload is small (34 bytes), uploading instead of streaming 2022/01/13 05:20:36 INFO : ignore_checksum_small_file_from_pipe: Copied (new) 2022/01/13 05:20:36 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2022/01/13 05:20:37 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical --- PASS: TestRcat (18.32s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (5.35s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (4.15s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (3.19s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (5.63s) === RUN TestRcatSize run.go:181: Remote "B2 bucket rclone-test-makubug2ferohor2huleciy4", Local "Local file system at /tmp/rclone1059348291", Modify Window "1ms" 2022/01/13 05:20:39 DEBUG : B2 bucket rclone-test-makubug2ferohor2huleciy4: File to upload is small (60 bytes), uploading instead of streaming 2022/01/13 05:20:40 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2022/01/13 05:20:40 INFO : potato2: Copied (new) --- PASS: TestRcatSize (3.31s) === RUN TestCopyFileMaxTransfer run.go:181: Remote "B2 bucket rclone-test-makubug2ferohor2huleciy4", Local "Local file system at /tmp/rclone1059348291", Modify Window "1ms" 2022/01/13 05:20:42 DEBUG : Couldn't decode error response: EOF 2022/01/13 05:20:42 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination 2022/01/13 05:20:43 DEBUG : TestCopyFileMaxTransfer/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2022/01/13 05:20:43 INFO : TestCopyFileMaxTransfer/file1: Copied (new) 2022/01/13 05:20:43 DEBUG : Couldn't decode error response: EOF 2022/01/13 05:20:43 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination 2022/01/13 05:20:43 ERROR : TestCopyFileMaxTransfer/file2: Failed to copy: Post "https://pod-000-1033-00.backblaze.com/b2api/v1/b2_upload_file/fff24d03d0dd5b1b7fe80e1b/c001_v0001033_t0008": Max transfer limit reached as set by --max-transfer 2022/01/13 05:20:44 DEBUG : Couldn't decode error response: EOF 2022/01/13 05:20:44 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination 2022/01/13 05:20:44 DEBUG : Couldn't decode error response: EOF 2022/01/13 05:20:44 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination 2022/01/13 05:20:45 DEBUG : TestCopyFileMaxTransfer/file4: sha1 = dc61542593e0ad5f8c0e899557b1f450972ba3e1 OK 2022/01/13 05:20:45 INFO : TestCopyFileMaxTransfer/file4: Copied (new) --- PASS: TestCopyFileMaxTransfer (5.88s) === RUN TestTouchDir run.go:181: Remote "B2 bucket rclone-test-makubug2ferohor2huleciy4", Local "Local file system at /tmp/rclone1059348291", Modify Window "1ms" 2022/01/13 05:20:52 DEBUG : B2 bucket rclone-test-makubug2ferohor2huleciy4: Touching "empty space" 2022/01/13 05:20:54 DEBUG : B2 bucket rclone-test-makubug2ferohor2huleciy4: Touching "potato2" 2022/01/13 05:20:55 DEBUG : B2 bucket rclone-test-makubug2ferohor2huleciy4: Touching "sub dir/potato3" --- PASS: TestTouchDir (10.15s) === 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/01/13 05:20:58 DEBUG : B2 bucket rclone-test-makubug2ferohor2huleciy4: Purge remote 2022/01/13 05:20:58 DEBUG : A1/B1/C1/four: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f111ad702e0248a07_d20220113_m052006_c001_v0001097_t0053") 2022/01/13 05:20:58 DEBUG : A1/B1/C1/four: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357ee8_d20220113_m052003_c001_v0001033_t0059") 2022/01/13 05:20:58 DEBUG : A1/B1/C1/one: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f101d48d12a34f2cd_d20220113_m051837_c001_v0001105_t0019") 2022/01/13 05:20:58 DEBUG : A1/B1/C1/one: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357daf_d20220113_m051834_c001_v0001033_t0020") 2022/01/13 05:20:58 DEBUG : A1/B1/C2/five: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f114072d592fe08ab_d20220113_m052007_c001_v0001131_t0030") 2022/01/13 05:20:58 DEBUG : A1/B1/C2/five: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357eee_d20220113_m052004_c001_v0001033_t0043") 2022/01/13 05:20:58 DEBUG : A1/B1/three: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1001b0d41edb2abd_d20220113_m052008_c001_v0001144_t0022") 2022/01/13 05:20:58 DEBUG : A1/B1/three: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357ee6_d20220113_m052002_c001_v0001033_t0018") 2022/01/13 05:20:58 DEBUG : A1/one: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f119ce1a3eaa71010_d20220113_m052006_c001_v0001105_t0013") 2022/01/13 05:20:58 DEBUG : A1/one: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357edd_d20220113_m052000_c001_v0001033_t0017") 2022/01/13 05:20:59 DEBUG : A1/two: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f10383e8dd14086f7_d20220113_m052006_c001_v0001134_t0055") 2022/01/13 05:20:59 DEBUG : A1/two: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357ee3_d20220113_m052002_c001_v0001033_t0000") 2022/01/13 05:20:59 DEBUG : A1/two: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f106bf992670fcb1f_d20220113_m051838_c001_v0001097_t0047") 2022/01/13 05:20:59 DEBUG : A1/two: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357db7_d20220113_m051835_c001_v0001033_t0040") 2022/01/13 05:20:59 DEBUG : A2/B1/C1/four: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f119ce1a3eaa71098_d20220113_m052010_c001_v0001105_t0047") 2022/01/13 05:20:59 DEBUG : A2/B1/C1/four: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f109fca4b51c47a7f_d20220113_m052006_c001_v0001154_t0019") 2022/01/13 05:20:59 DEBUG : A2/B1/C2/five: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f10843ba5c59a8cdc_d20220113_m052010_c001_v0001022_t0025") 2022/01/13 05:20:59 DEBUG : A2/B1/C2/five: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f115a1b2a80223d6b_d20220113_m052006_c001_v0001109_t0016") 2022/01/13 05:20:59 DEBUG : A2/B1/three: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1089deb5f411eb0d_d20220113_m052010_c001_v0001113_t0001") 2022/01/13 05:20:59 DEBUG : A2/B1/three: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1133f8d15b555159_d20220113_m052007_c001_v0001105_t0054") 2022/01/13 05:20:59 DEBUG : A2/one: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f11176959087c391e_d20220113_m052010_c001_v0001000_t0001") 2022/01/13 05:20:59 DEBUG : A2/one: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f115a12c87e803070_d20220113_m052006_c001_v0001044_t0001") 2022/01/13 05:20:59 DEBUG : A2/two: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1115f2f71a718ba7_d20220113_m052012_c001_v0001105_t0049") 2022/01/13 05:21:00 DEBUG : A2/two: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f111b55150ee55f81_d20220113_m052006_c001_v0001165_t0030") 2022/01/13 05:21:00 DEBUG : A3/B1/C1/four: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f11007ab1b8a7e7ca_d20220113_m052013_c001_v0001093_t0017") 2022/01/13 05:21:00 DEBUG : A3/B1/C1/four: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1103068ab82cdccf_d20220113_m052009_c001_v0001039_t0053") 2022/01/13 05:21:00 DEBUG : A3/B1/C2/five: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1092c880b0861dbc_d20220113_m052013_c001_v0001039_t0025") 2022/01/13 05:21:00 DEBUG : A3/B1/C2/five: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f109ae94d156f6dbb_d20220113_m052009_c001_v0001105_t0059") 2022/01/13 05:21:00 DEBUG : A3/B1/three: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f110880b245f73823_d20220113_m052014_c001_v0001134_t0033") 2022/01/13 05:21:00 DEBUG : A3/B1/three: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1171b4d40931f8da_d20220113_m052009_c001_v0001098_t0033") 2022/01/13 05:21:00 DEBUG : A3/one: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f113e0384a65558b5_d20220113_m052015_c001_v0001042_t0049") 2022/01/13 05:21:00 DEBUG : A3/one: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f107150c010c91619_d20220113_m052009_c001_v0001097_t0008") 2022/01/13 05:21:00 DEBUG : A3/two: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f106eb339080a9afc_d20220113_m052019_c001_v0001109_t0041") 2022/01/13 05:21:00 DEBUG : A3/two: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f112bfcaced908eca_d20220113_m052011_c001_v0001041_t0025") 2022/01/13 05:21:00 DEBUG : BackupDir/one: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f111ad702e024898c_d20220113_m051955_c001_v0001097_t0001") 2022/01/13 05:21:00 DEBUG : BackupDir/one: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f10293e72785690e6_d20220113_m051943_c001_v0001109_t0000") 2022/01/13 05:21:00 DEBUG : CompareDest/one: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f108e73366934ff94_d20220113_m051927_c001_v0001134_t0019") 2022/01/13 05:21:00 DEBUG : CompareDest/one: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357e5c_d20220113_m051923_c001_v0001033_t0004") 2022/01/13 05:21:00 DEBUG : CompareDest/two: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f11594ac9a0ec0c08_d20220113_m051928_c001_v0001137_t0029") 2022/01/13 05:21:00 DEBUG : CompareDest/two: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357e62_d20220113_m051924_c001_v0001033_t0038") 2022/01/13 05:21:00 DEBUG : CopyDest/one: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f100f0b4b2e209bfb_d20220113_m051956_c001_v0001037_t0033") 2022/01/13 05:21:00 DEBUG : CopyDest/one: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357ea1_d20220113_m051940_c001_v0001033_t0006") 2022/01/13 05:21:00 DEBUG : CopyDest/three: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1195a57531f21edd_d20220113_m051957_c001_v0001098_t0036") 2022/01/13 05:21:01 DEBUG : CopyDest/three: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357ebd_d20220113_m051950_c001_v0001033_t0032") 2022/01/13 05:21:01 DEBUG : CopyDest/two: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f109ae94d156f6c57_d20220113_m051957_c001_v0001105_t0018") 2022/01/13 05:21:01 DEBUG : CopyDest/two: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357eb6_d20220113_m051947_c001_v0001033_t0029") 2022/01/13 05:21:01 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f10875e538ab941f5_d20220113_m052046_c001_v0001105_t0003") 2022/01/13 05:21:01 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357f64_d20220113_m052042_c001_v0001033_t0012") 2022/01/13 05:21:01 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f10874d26d2c8f94e_d20220113_m052047_c001_v0001130_t0005") 2022/01/13 05:21:01 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357f6d_d20220113_m052045_c001_v0001033_t0018") 2022/01/13 05:21:01 DEBUG : a.txt: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f103deb101d4340f7_d20220113_m051726_c001_v0001165_t0037") 2022/01/13 05:21:01 DEBUG : a.txt: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357c28_d20220113_m051716_c001_v0001033_t0018") 2022/01/13 05:21:01 DEBUG : also/one: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f107fd94232309fa2_d20220113_m051711_c001_v0001109_t0047") 2022/01/13 05:21:01 DEBUG : also/one: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357c0c_d20220113_m051708_c001_v0001033_t0056") 2022/01/13 05:21:01 DEBUG : another: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1035defc481cb797_d20220113_m051712_c001_v0001150_t0047") 2022/01/13 05:21:01 DEBUG : another: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357c0f_d20220113_m051709_c001_v0001033_t0057") 2022/01/13 05:21:01 DEBUG : backup/dst/file1: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f101e67130aa09bb2_d20220113_m051915_c001_v0001097_t0031") 2022/01/13 05:21:01 DEBUG : backup/dst/file1: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f11776354f5f3822d_d20220113_m051913_c001_v0001128_t0017") 2022/01/13 05:21:01 DEBUG : backup/dst/file1: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1068c74d0d8aa2b1_d20220113_m051905_c001_v0001157_t0049") 2022/01/13 05:21:01 DEBUG : backup/dst/file1: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f109ae94d156f6680_d20220113_m051903_c001_v0001105_t0017") 2022/01/13 05:21:01 DEBUG : data/banana: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f112e73fe5dc8ab6a_d20220113_m051704_c001_v0001157_t0031") 2022/01/13 05:21:01 DEBUG : data/banana: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357bdb_d20220113_m051657_c001_v0001033_t0014") 2022/01/13 05:21:01 DEBUG : data/banana: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357ba2_d20220113_m051643_c001_v0001033_t0026") 2022/01/13 05:21:01 DEBUG : data/potato: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f106ae7f062539013_d20220113_m051704_c001_v0001161_t0001") 2022/01/13 05:21:01 DEBUG : data/potato: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357be9_d20220113_m051659_c001_v0001033_t0054") 2022/01/13 05:21:02 DEBUG : data/potato: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357bb0_d20220113_m051645_c001_v0001033_t0057") 2022/01/13 05:21:02 DEBUG : dst/file1: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f105926d518780fb5_d20220113_m051916_c001_v0001120_t0026") 2022/01/13 05:21:02 DEBUG : dst/file1: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357e34_d20220113_m051915_c001_v0001033_t0015") 2022/01/13 05:21:02 DEBUG : dst/file1: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f113472c8b8348dd5_d20220113_m051914_c001_v0001117_t0015") 2022/01/13 05:21:02 DEBUG : dst/file1: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357e1b_d20220113_m051909_c001_v0001033_t0029") 2022/01/13 05:21:02 DEBUG : dst/file1: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f103fce192ead9049_d20220113_m051905_c001_v0001098_t0005") 2022/01/13 05:21:02 DEBUG : dst/file1: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357e0e_d20220113_m051904_c001_v0001033_t0012") 2022/01/13 05:21:02 DEBUG : dst/file1: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f114bbb83c524ac70_d20220113_m051903_c001_v0001036_t0002") 2022/01/13 05:21:02 DEBUG : dst/file1: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357dff_d20220113_m051857_c001_v0001033_t0000") 2022/01/13 05:21:02 DEBUG : dst/one: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f11373f9598d4347e_d20220113_m051957_c001_v0001038_t0036") 2022/01/13 05:21:02 DEBUG : dst/one: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1131e5ead3b309a0_d20220113_m051945_c001_v0001026_t0029") 2022/01/13 05:21:02 DEBUG : dst/one: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1034757c24251739_d20220113_m051944_c001_v0001037_t0017") 2022/01/13 05:21:02 DEBUG : dst/one: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357e9d_d20220113_m051939_c001_v0001033_t0003") 2022/01/13 05:21:02 DEBUG : dst/one: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1142e508a76f01bf_d20220113_m051938_c001_v0001120_t0043") 2022/01/13 05:21:02 DEBUG : dst/one: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1142e508a76f01b9_d20220113_m051935_c001_v0001120_t0043") 2022/01/13 05:21:02 DEBUG : dst/one: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1133f8d15b554c31_d20220113_m051928_c001_v0001105_t0056") 2022/01/13 05:21:02 DEBUG : dst/one: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357e5a_d20220113_m051923_c001_v0001033_t0039") 2022/01/13 05:21:02 DEBUG : dst/one: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f11259a2a0a09648b_d20220113_m051922_c001_v0001150_t0003") 2022/01/13 05:21:02 DEBUG : dst/one: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f11259a2a0a096481_d20220113_m051921_c001_v0001150_t0000") 2022/01/13 05:21:02 DEBUG : dst/three: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1071dc6b9904e218_d20220113_m051958_c001_v0001137_t0057") 2022/01/13 05:21:02 DEBUG : dst/three: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1142e508a76f020e_d20220113_m051954_c001_v0001120_t0021") 2022/01/13 05:21:03 DEBUG : dst/two: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f112fdf1eb5484d06_d20220113_m051959_c001_v0001136_t0035") 2022/01/13 05:21:03 DEBUG : dst/two: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f101632b202d32c1e_d20220113_m051949_c001_v0001033_t0057") 2022/01/13 05:21:03 DEBUG : dst/two: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1054982d151c03fc_d20220113_m051930_c001_v0001117_t0016") 2022/01/13 05:21:03 DEBUG : dst/two: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f11259a2a0a096497_d20220113_m051926_c001_v0001150_t0007") 2022/01/13 05:21:03 DEBUG : empty space: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f115285fd1f0642bf_d20220113_m052057_c001_v0001130_t0004") 2022/01/13 05:21:03 DEBUG : empty space: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f10627b2d93da68ff_d20220113_m052053_c001_v0001117_t0010") 2022/01/13 05:21:03 DEBUG : empty space: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357f7c_d20220113_m052048_c001_v0001033_t0053") 2022/01/13 05:21:03 DEBUG : empty space: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f102bd20ccbcb7648_d20220113_m051813_c001_v0001130_t0015") 2022/01/13 05:21:03 DEBUG : empty space: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357d44_d20220113_m051811_c001_v0001033_t0050") 2022/01/13 05:21:03 DEBUG : empty space: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f10627b2d93da6143_d20220113_m051809_c001_v0001117_t0014") 2022/01/13 05:21:03 DEBUG : empty space: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357d28_d20220113_m051807_c001_v0001033_t0015") 2022/01/13 05:21:03 DEBUG : empty space: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1023be88de58f3d9_d20220113_m051802_c001_v0001105_t0012") 2022/01/13 05:21:03 DEBUG : empty space: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357d02_d20220113_m051801_c001_v0001033_t0013") 2022/01/13 05:21:03 DEBUG : empty space: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f118a6b0f6252283a_d20220113_m051758_c001_v0001097_t0049") 2022/01/13 05:21:03 DEBUG : empty space: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357ceb_d20220113_m051756_c001_v0001033_t0002") 2022/01/13 05:21:03 DEBUG : empty space: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f11230b3a901cd03b_d20220113_m051754_c001_v0001120_t0035") 2022/01/13 05:21:03 DEBUG : empty space: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357ccc_d20220113_m051750_c001_v0001033_t0012") 2022/01/13 05:21:03 DEBUG : empty space: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1193ae68f07bbf00_d20220113_m051633_c001_v0001044_t0032") 2022/01/13 05:21:03 DEBUG : empty space: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357b73_d20220113_m051629_c001_v0001033_t0044") 2022/01/13 05:21:04 DEBUG : empty space: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f106a4a33a075cc8d_d20220113_m051624_c001_v0001033_t0056") 2022/01/13 05:21:04 DEBUG : empty space: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357b50_d20220113_m051618_c001_v0001033_t0002") 2022/01/13 05:21:04 DEBUG : empty space: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f118066345ed9bd9d_d20220113_m051613_c001_v0001023_t0021") 2022/01/13 05:21:04 DEBUG : empty space: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357b1c_d20220113_m051610_c001_v0001033_t0056") 2022/01/13 05:21:04 DEBUG : file1: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f114590969a6b7894_d20220113_m051856_c001_v0001109_t0020") 2022/01/13 05:21:04 DEBUG : file1: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357df3_d20220113_m051853_c001_v0001033_t0007") 2022/01/13 05:21:04 DEBUG : file1: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f107a009d3a0deebd_d20220113_m051844_c001_v0001105_t0033") 2022/01/13 05:21:04 DEBUG : file1: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357dcb_d20220113_m051840_c001_v0001033_t0013") 2022/01/13 05:21:04 DEBUG : file1: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f10273d5e9cf7120c_d20220113_m051825_c001_v0001039_t0054") 2022/01/13 05:21:04 DEBUG : file1: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357d73_d20220113_m051820_c001_v0001033_t0048") 2022/01/13 05:21:04 DEBUG : file1: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1047a0e239113b5b_d20220113_m051746_c001_v0001029_t0042") 2022/01/13 05:21:04 DEBUG : file1: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357c7c_d20220113_m051737_c001_v0001033_t0018") 2022/01/13 05:21:04 DEBUG : file1: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f111847908fed0633_d20220113_m051735_c001_v0001001_t0031") 2022/01/13 05:21:04 DEBUG : file1: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357c58_d20220113_m051732_c001_v0001033_t0048") 2022/01/13 05:21:04 DEBUG : file1: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1167d6da4cde1e21_d20220113_m051609_c001_v0001150_t0021") 2022/01/13 05:21:04 DEBUG : file1: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357b0b_d20220113_m051607_c001_v0001033_t0048") 2022/01/13 05:21:05 DEBUG : file1: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357b01_d20220113_m051605_c001_v0001033_t0008") 2022/01/13 05:21:05 DEBUG : file1: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357af6_d20220113_m051602_c001_v0001033_t0015") 2022/01/13 05:21:05 DEBUG : file2: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1133f8d15b5546cd_d20220113_m051846_c001_v0001105_t0035") 2022/01/13 05:21:05 DEBUG : file2: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357dd5_d20220113_m051843_c001_v0001033_t0025") 2022/01/13 05:21:05 DEBUG : file2: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f10644aebc52de0a3_d20220113_m051826_c001_v0001120_t0018") 2022/01/13 05:21:05 DEBUG : file2: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357d77_d20220113_m051820_c001_v0001033_t0005") 2022/01/13 05:21:05 DEBUG : filename.txt: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f107a2c7ae4b6992b_d20220113_m051847_c001_v0001117_t0050") 2022/01/13 05:21:05 DEBUG : filename.txt: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357dd2_d20220113_m051842_c001_v0001033_t0054") 2022/01/13 05:21:05 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1041f45bb590aa2b_d20220113_m052037_c001_v0001161_t0017") 2022/01/13 05:21:05 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357f4b_d20220113_m052036_c001_v0001033_t0052") 2022/01/13 05:21:05 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f102655c71bf22dc2_d20220113_m052032_c001_v0001093_t0025") 2022/01/13 05:21:05 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357f3b_d20220113_m052030_c001_v0001033_t0028") 2022/01/13 05:21:05 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1034664ef52c414f_d20220113_m052038_c001_v0001157_t0035") 2022/01/13 05:21:05 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357f42_d20220113_m052033_c001_v0001033_t0041") 2022/01/13 05:21:05 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f101810ece187e81f_d20220113_m052032_c001_v0001113_t0040") 2022/01/13 05:21:05 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357f39_d20220113_m052030_c001_v0001033_t0057") 2022/01/13 05:21:05 DEBUG : large: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f109424515d979239_d20220113_m051819_c001_v0001097_t0003") 2022/01/13 05:21:05 DEBUG : large: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357d68_d20220113_m051817_c001_v0001033_t0020") 2022/01/13 05:21:06 DEBUG : medium: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f109abc233523b650_d20220113_m051817_c001_v0001036_t0034") 2022/01/13 05:21:06 DEBUG : medium: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357d5e_d20220113_m051816_c001_v0001033_t0000") 2022/01/13 05:21:06 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f111024e668a01895_d20220113_m052024_c001_v0001131_t0047") 2022/01/13 05:21:06 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357f1a_d20220113_m052021_c001_v0001033_t0014") 2022/01/13 05:21:06 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f102fdc275ca41bef_d20220113_m052025_c001_v0001146_t0058") 2022/01/13 05:21:06 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357f18_d20220113_m052021_c001_v0001033_t0003") 2022/01/13 05:21:06 DEBUG : not-one: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f102aa092ed856bc5_d20220113_m051712_c001_v0001097_t0028") 2022/01/13 05:21:06 DEBUG : not-one: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357c13_d20220113_m051709_c001_v0001033_t0055") 2022/01/13 05:21:06 DEBUG : one: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f116696faf382aef4_d20220113_m051710_c001_v0001109_t0042") 2022/01/13 05:21:06 DEBUG : one: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357c09_d20220113_m051707_c001_v0001033_t0050") 2022/01/13 05:21:06 DEBUG : potato1: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f119ce1a3eaa7145a_d20220113_m052040_c001_v0001105_t0053") 2022/01/13 05:21:06 DEBUG : potato1: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357f50_d20220113_m052039_c001_v0001033_t0009") 2022/01/13 05:21:06 DEBUG : potato2: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1152721dd83af40c_d20220113_m052057_c001_v0001157_t0022") 2022/01/13 05:21:06 DEBUG : potato2: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1056b8c9743848a5_d20220113_m052055_c001_v0001134_t0014") 2022/01/13 05:21:06 DEBUG : potato2: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357f7a_d20220113_m052048_c001_v0001033_t0030") 2022/01/13 05:21:06 DEBUG : potato2: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f118352c2842140e2_d20220113_m052041_c001_v0001161_t0052") 2022/01/13 05:21:07 DEBUG : potato2: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357f57_d20220113_m052039_c001_v0001033_t0012") 2022/01/13 05:21:07 DEBUG : potato2: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1012760b4ac73e0a_d20220113_m051814_c001_v0001093_t0034") 2022/01/13 05:21:07 DEBUG : potato2: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357d42_d20220113_m051811_c001_v0001033_t0023") 2022/01/13 05:21:07 DEBUG : potato2: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1023be88de58f4ad_d20220113_m051810_c001_v0001105_t0059") 2022/01/13 05:21:07 DEBUG : potato2: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357d16_d20220113_m051804_c001_v0001033_t0046") 2022/01/13 05:21:07 DEBUG : potato2: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f116ea032280c0aa6_d20220113_m051803_c001_v0001117_t0047") 2022/01/13 05:21:07 DEBUG : potato2: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357cfb_d20220113_m051800_c001_v0001033_t0008") 2022/01/13 05:21:07 DEBUG : potato2: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f10404e88077f60fa_d20220113_m051759_c001_v0001097_t0021") 2022/01/13 05:21:07 DEBUG : potato2: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357ce5_d20220113_m051756_c001_v0001033_t0040") 2022/01/13 05:21:07 DEBUG : potato2: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f102650764042db87_d20220113_m051755_c001_v0001091_t0011") 2022/01/13 05:21:07 DEBUG : potato2: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357cc6_d20220113_m051750_c001_v0001033_t0055") 2022/01/13 05:21:07 DEBUG : potato2: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f105ab655505b9abc_d20220113_m051635_c001_v0001161_t0048") 2022/01/13 05:21:07 DEBUG : potato2: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357b75_d20220113_m051630_c001_v0001033_t0030") 2022/01/13 05:21:07 DEBUG : potato2: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f101db73a8bbf05b7_d20220113_m051624_c001_v0001146_t0059") 2022/01/13 05:21:07 DEBUG : potato2: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357b58_d20220113_m051620_c001_v0001033_t0027") 2022/01/13 05:21:07 DEBUG : potato2: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f10875e538ab920ed_d20220113_m051614_c001_v0001105_t0005") 2022/01/13 05:21:07 DEBUG : potato2: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357b27_d20220113_m051611_c001_v0001033_t0009") 2022/01/13 05:21:07 DEBUG : remotepotato: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f114b722e2e56ebfd_d20220113_m051635_c001_v0001154_t0016") 2022/01/13 05:21:07 DEBUG : remotepotato: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357b81_d20220113_m051632_c001_v0001033_t0040") 2022/01/13 05:21:07 DEBUG : remotepotato: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f112496b4bc74b8ac_d20220113_m051625_c001_v0001145_t0016") 2022/01/13 05:21:07 DEBUG : remotepotato: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357b5c_d20220113_m051622_c001_v0001033_t0043") 2022/01/13 05:21:08 DEBUG : remotepotato: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f110f63a217e2fc4f_d20220113_m051615_c001_v0001131_t0045") 2022/01/13 05:21:08 DEBUG : remotepotato: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357b2e_d20220113_m051612_c001_v0001033_t0005") 2022/01/13 05:21:08 DEBUG : rutabaga: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f10191efa4b2fa555_d20220113_m051635_c001_v0001037_t0055") 2022/01/13 05:21:08 DEBUG : rutabaga: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357b6e_d20220113_m051627_c001_v0001033_t0023") 2022/01/13 05:21:08 DEBUG : rutabaga: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f118a6b0f62522384_d20220113_m051626_c001_v0001097_t0045") 2022/01/13 05:21:08 DEBUG : rutabaga: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357b45_d20220113_m051617_c001_v0001033_t0022") 2022/01/13 05:21:08 DEBUG : rutabaga: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f11948d8c5339f932_d20220113_m051616_c001_v0001137_t0009") 2022/01/13 05:21:08 DEBUG : rutabaga: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357b17_d20220113_m051609_c001_v0001033_t0021") 2022/01/13 05:21:08 DEBUG : small: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f109a844b9b29858e_d20220113_m051818_c001_v0001041_t0052") 2022/01/13 05:21:08 DEBUG : small: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357d5c_d20220113_m051815_c001_v0001033_t0049") 2022/01/13 05:21:08 DEBUG : sub dir/hello world: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f112bfcaced908d7a_d20220113_m051749_c001_v0001041_t0023") 2022/01/13 05:21:08 DEBUG : sub dir/hello world: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357cbe_d20220113_m051748_c001_v0001033_t0022") 2022/01/13 05:21:08 DEBUG : sub dir/hello world: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f106ae7f062539119_d20220113_m051727_c001_v0001161_t0008") 2022/01/13 05:21:08 DEBUG : sub dir/hello world: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357c31_d20220113_m051717_c001_v0001033_t0021") 2022/01/13 05:21:08 DEBUG : sub dir/hello world2: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f114d95aa939422f2_d20220113_m051727_c001_v0001134_t0031") 2022/01/13 05:21:08 DEBUG : sub dir/hello world2: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357c35_d20220113_m051717_c001_v0001033_t0050") 2022/01/13 05:21:08 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f103fce192ead8b08_d20220113_m051727_c001_v0001098_t0006") 2022/01/13 05:21:08 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357c3a_d20220113_m051719_c001_v0001033_t0023") 2022/01/13 05:21:08 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1054982d151bfefa_d20220113_m051728_c001_v0001117_t0056") 2022/01/13 05:21:08 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357c3e_d20220113_m051722_c001_v0001033_t0020") 2022/01/13 05:21:09 DEBUG : sub dir/potato3: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f11771abc8469c3aa_d20220113_m052057_c001_v0001150_t0039") 2022/01/13 05:21:09 DEBUG : sub dir/potato3: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f114e60b30e4ffd81_d20220113_m052056_c001_v0001117_t0045") 2022/01/13 05:21:09 DEBUG : sub dir/potato3: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357f80_d20220113_m052049_c001_v0001033_t0004") 2022/01/13 05:21:09 DEBUG : sub dir/potato3: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1117a9955182f3fa_d20220113_m051815_c001_v0001137_t0038") 2022/01/13 05:21:09 DEBUG : sub dir/potato3: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357d48_d20220113_m051812_c001_v0001033_t0055") 2022/01/13 05:21:09 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f102655c71bf22af0_d20220113_m051730_c001_v0001093_t0026") 2022/01/13 05:21:09 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357c42_d20220113_m051723_c001_v0001033_t0026") 2022/01/13 05:21:09 DEBUG : sub/file2: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f104a385dc2610443_d20220113_m051909_c001_v0001154_t0018") 2022/01/13 05:21:09 DEBUG : sub/file2: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357e14_d20220113_m051907_c001_v0001033_t0025") 2022/01/13 05:21:09 DEBUG : sub/file2: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f100f4414708ae7ba_d20220113_m051852_c001_v0001015_t0027") 2022/01/13 05:21:09 DEBUG : sub/file2: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357de6_d20220113_m051851_c001_v0001033_t0029") 2022/01/13 05:21:09 DEBUG : sub/file2: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f101d9a9db4f382d9_d20220113_m051747_c001_v0001165_t0049") 2022/01/13 05:21:09 DEBUG : sub/file2: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357c8d_d20220113_m051740_c001_v0001033_t0054") 2022/01/13 05:21:09 DEBUG : sub/file2: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f104e0a22742c0fcb_d20220113_m051735_c001_v0001105_t0043") 2022/01/13 05:21:09 DEBUG : sub/file2: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357c5b_d20220113_m051732_c001_v0001033_t0014") 2022/01/13 05:21:09 DEBUG : test.sum: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f106a1c92a3360f73_d20220113_m051705_c001_v0001137_t0024") 2022/01/13 05:21:09 DEBUG : test.sum: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357bf5_d20220113_m051700_c001_v0001033_t0036") 2022/01/13 05:21:09 DEBUG : test.sum: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357bd0_d20220113_m051654_c001_v0001033_t0052") 2022/01/13 05:21:09 DEBUG : test.sum: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357bc6_d20220113_m051650_c001_v0001033_t0032") 2022/01/13 05:21:09 DEBUG : test.sum: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357bbf_d20220113_m051649_c001_v0001033_t0047") 2022/01/13 05:21:09 DEBUG : test.sum: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357bba_d20220113_m051647_c001_v0001033_t0038") 2022/01/13 05:21:10 DEBUG : test.sum: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357bb3_d20220113_m051646_c001_v0001033_t0024") 2022/01/13 05:21:10 DEBUG : test.sum: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357ba7_d20220113_m051643_c001_v0001033_t0034") 2022/01/13 05:21:10 DEBUG : test.sum: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1137b0cfad0f07e3_d20220113_m051640_c001_v0001109_t0039") 2022/01/13 05:21:10 DEBUG : test.sum: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357b98_d20220113_m051639_c001_v0001033_t0032") 2022/01/13 05:21:10 DEBUG : test.sum: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357b92_d20220113_m051637_c001_v0001033_t0058") 2022/01/13 05:21:10 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f111b55150ee560b4_d20220113_m052028_c001_v0001165_t0022") 2022/01/13 05:21:10 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357f2c_d20220113_m052027_c001_v0001033_t0027") 2022/01/13 05:21:10 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f11797d79e1512a05_d20220113_m052029_c001_v0001105_t0026") 2022/01/13 05:21:10 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357f25_d20220113_m052026_c001_v0001033_t0055") 2022/01/13 05:21:10 DEBUG : zend.txt: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1067fb5d236131cc_d20220113_m051730_c001_v0001105_t0044") 2022/01/13 05:21:10 DEBUG : zend.txt: Deleting (id "4_zfff24d03d0dd5b1b7fe80e1b_f1009758cd7357c2f_d20220113_m051716_c001_v0001033_t0048") "./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -fast-list" - Finished OK in 5m11.061499172s (try 1/5)