"./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -fast-list" - Starting (try 1/5) 2022/04/09 05:23:29 DEBUG : Creating backend with remote "TestB2:rclone-test-hepijow5tibeyuz9civumib2" 2022/04/09 05:23:29 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2022/04/09 05:23:30 DEBUG : Creating backend with remote "/tmp/rclone2912821420" === 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-hepijow5tibeyuz9civumib2", Local "Local file system at /tmp/rclone2912821420", Modify Window "1ms" === RUN TestMultithreadCopy/{size:131071_streams:2} 2022/04/09 05:23:36 DEBUG : pacer: low level retry 1/10 (error Post "https://api001.backblazeb2.com/b2api/v1/b2_list_file_names": EOF) 2022/04/09 05:23:36 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2022/04/09 05:23:37 DEBUG : pacer: Reducing sleep to 10ms 2022/04/09 05:23:37 DEBUG : file1: Starting multi-thread copy with 2 parts of size 64Ki 2022/04/09 05:23:37 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131071) size 63.999Ki starting 2022/04/09 05:23:37 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64Ki starting 2022/04/09 05:23:38 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131071) size 63.999Ki finished 2022/04/09 05:23:38 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64Ki finished 2022/04/09 05:23:38 DEBUG : file1: Finished multi-thread copy with 2 parts of size 64Ki === RUN TestMultithreadCopy/{size:131072_streams:2} 2022/04/09 05:23:41 DEBUG : file1: Starting multi-thread copy with 2 parts of size 64Ki 2022/04/09 05:23:41 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131072) size 64Ki starting 2022/04/09 05:23:41 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64Ki starting 2022/04/09 05:23:41 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131072) size 64Ki finished 2022/04/09 05:23:41 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64Ki finished 2022/04/09 05:23:41 DEBUG : file1: Finished multi-thread copy with 2 parts of size 64Ki === RUN TestMultithreadCopy/{size:131073_streams:2} 2022/04/09 05:23:44 DEBUG : file1: Starting multi-thread copy with 2 parts of size 128Ki 2022/04/09 05:23:44 DEBUG : file1: multi-thread copy: stream 2/2 (131072-131073) size 1 starting 2022/04/09 05:23:44 DEBUG : file1: multi-thread copy: stream 1/2 (0-131072) size 128Ki starting 2022/04/09 05:23:45 DEBUG : file1: multi-thread copy: stream 2/2 (131072-131073) size 1 finished 2022/04/09 05:23:45 DEBUG : file1: multi-thread copy: stream 1/2 (0-131072) size 128Ki finished 2022/04/09 05:23:45 DEBUG : file1: Finished multi-thread copy with 2 parts of size 128Ki --- PASS: TestMultithreadCopy (19.72s) --- PASS: TestMultithreadCopy/{size:131071_streams:2} (8.19s) --- PASS: TestMultithreadCopy/{size:131072_streams:2} (3.29s) --- PASS: TestMultithreadCopy/{size:131073_streams:2} (3.29s) === RUN TestSizeDiffers --- PASS: TestSizeDiffers (0.00s) === RUN TestReOpen === RUN TestReOpen/Seek === RUN TestReOpen/Seek/Basics === RUN TestReOpen/Seek/ErrorAtStart === RUN TestReOpen/Seek/WithErrors 2022/04/09 05:23:50 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/10: test error 2022/04/09 05:23:50 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/10: test error 2022/04/09 05:23:50 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/10: test error === RUN TestReOpen/Seek/TooManyErrors 2022/04/09 05:23:50 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/3: test error 2022/04/09 05:23:50 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/3: test error 2022/04/09 05:23:50 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/3: test error 2022/04/09 05:23:50 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/04/09 05:23:50 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/10: test error 2022/04/09 05:23:50 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/10: test error 2022/04/09 05:23:50 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/10: test error === RUN TestReOpen/Range/TooManyErrors 2022/04/09 05:23:50 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/3: test error 2022/04/09 05:23:50 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/3: test error 2022/04/09 05:23:50 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/3: test error 2022/04/09 05:23:50 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-hepijow5tibeyuz9civumib2", Local "Local file system at /tmp/rclone2912821420", 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 (16.47s) --- 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.18s) --- PASS: TestCheck/7 (0.17s) === RUN TestCheckFsError 2022/04/09 05:24:06 DEBUG : Creating backend with remote "non-existent" 2022/04/09 05:24:06 DEBUG : Creating backend with remote "non-existent" 2022/04/09 05:24:06 DEBUG : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/non-existent: Waiting for checks to finish 2022/04/09 05:24:06 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/04/09 05:24:06 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/non-existent: 2 differences found 2022/04/09 05:24:06 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-hepijow5tibeyuz9civumib2", Local "Local file system at /tmp/rclone2912821420", 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 (16.04s) --- PASS: TestCheckDownload/1 (0.88s) --- PASS: TestCheckDownload/2 (0.34s) --- PASS: TestCheckDownload/3 (0.34s) --- PASS: TestCheckDownload/4 (0.53s) --- PASS: TestCheckDownload/5 (0.35s) --- PASS: TestCheckDownload/6 (0.36s) --- PASS: TestCheckDownload/7 (0.36s) === RUN TestCheckSizeOnly run.go:181: Remote "B2 bucket rclone-test-hepijow5tibeyuz9civumib2", Local "Local file system at /tmp/rclone2912821420", 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 (18.04s) --- PASS: TestCheckSizeOnly/1 (0.17s) --- 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-hepijow5tibeyuz9civumib2", Local "Local file system at /tmp/rclone2912821420", Modify Window "1ms" 2022/04/09 05:24:42 NOTICE: test.sum: improperly formatted checksum line 4 2022/04/09 05:24:42 NOTICE: test.sum: improperly formatted checksum line 5 2022/04/09 05:24:42 NOTICE: test.sum: improperly formatted checksum line 6 2022/04/09 05:24:42 NOTICE: test.sum: 2 warning(s) suppressed... 2022/04/09 05:24:43 NOTICE: test.sum: improperly formatted checksum line 4 2022/04/09 05:24:43 NOTICE: test.sum: improperly formatted checksum line 5 2022/04/09 05:24:43 NOTICE: test.sum: improperly formatted checksum line 6 2022/04/09 05:24:43 NOTICE: test.sum: 2 warning(s) suppressed... --- PASS: TestParseSumFile (6.22s) === RUN TestCheckSum run.go:181: Remote "B2 bucket rclone-test-hepijow5tibeyuz9civumib2", Local "Local file system at /tmp/rclone2912821420", Modify Window "1ms" 2022/04/09 05:24:46 DEBUG : Creating backend with remote "TestB2:rclone-test-hepijow5tibeyuz9civumib2/data" 2022/04/09 05:24:47 DEBUG : Couldn't decode error response: EOF check_test.go:356: B2 bucket rclone-test-hepijow5tibeyuz9civumib2 path data lacks md5, skipping --- SKIP: TestCheckSum (1.24s) === RUN TestCheckSumDownload run.go:181: Remote "B2 bucket rclone-test-hepijow5tibeyuz9civumib2", Local "Local file system at /tmp/rclone2912821420", Modify Window "1ms" 2022/04/09 05:24:48 DEBUG : Creating backend with remote "TestB2:rclone-test-hepijow5tibeyuz9civumib2/data" 2022/04/09 05:24:48 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 (35.69s) --- PASS: TestCheckSumDownload/subtest1 (0.94s) --- PASS: TestCheckSumDownload/subtest2 (0.83s) --- PASS: TestCheckSumDownload/subtest3 (1.39s) --- PASS: TestCheckSumDownload/subtest4 (0.72s) --- PASS: TestCheckSumDownload/subtest5 (0.70s) --- PASS: TestCheckSumDownload/subtest6 (0.72s) --- PASS: TestCheckSumDownload/subtest7 (1.39s) === RUN TestDeduplicateInteractive run.go:181: Remote "B2 bucket rclone-test-hepijow5tibeyuz9civumib2", Local "Local file system at /tmp/rclone2912821420", 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-hepijow5tibeyuz9civumib2", Local "Local file system at /tmp/rclone2912821420", 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-hepijow5tibeyuz9civumib2", Local "Local file system at /tmp/rclone2912821420", 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-hepijow5tibeyuz9civumib2", Local "Local file system at /tmp/rclone2912821420", 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-hepijow5tibeyuz9civumib2", Local "Local file system at /tmp/rclone2912821420", 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-hepijow5tibeyuz9civumib2", Local "Local file system at /tmp/rclone2912821420", Modify Window "1ms" 2022/04/09 05:25:33 INFO : B2 bucket rclone-test-hepijow5tibeyuz9civumib2: Looking for duplicate sha1 hashes using newest mode. 2022/04/09 05:25:33 NOTICE: 91123ea56c5cf8cd5923926e936011680472a18c: Found 3 files with duplicate sha1 hashes 2022/04/09 05:25:34 INFO : one: Deleted 2022/04/09 05:25:35 INFO : also/one: Deleted 2022/04/09 05:25:35 NOTICE: 91123ea56c5cf8cd5923926e936011680472a18c: Deleted 2 extra copies --- PASS: TestDeduplicateNewestByHash (12.04s) === RUN TestDeduplicateOldest run.go:181: Remote "B2 bucket rclone-test-hepijow5tibeyuz9civumib2", Local "Local file system at /tmp/rclone2912821420", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateOldest (0.34s) === RUN TestDeduplicateLargest run.go:181: Remote "B2 bucket rclone-test-hepijow5tibeyuz9civumib2", Local "Local file system at /tmp/rclone2912821420", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateLargest (0.39s) === RUN TestDeduplicateSmallest run.go:181: Remote "B2 bucket rclone-test-hepijow5tibeyuz9civumib2", Local "Local file system at /tmp/rclone2912821420", 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-hepijow5tibeyuz9civumib2", Local "Local file system at /tmp/rclone2912821420", 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-hepijow5tibeyuz9civumib2", Local "Local file system at /tmp/rclone2912821420", 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-hepijow5tibeyuz9civumib2", Local "Local file system at /tmp/rclone2912821420", Modify Window "1ms" 2022/04/09 05:25:57 DEBUG : a.txt: Excluded 2022/04/09 05:25:57 DEBUG : sub dir/hello world: Excluded 2022/04/09 05:25:57 DEBUG : sub dir/hello world2: Excluded 2022/04/09 05:25:57 DEBUG : sub dir/hello world: Excluded 2022/04/09 05:25:57 DEBUG : sub dir/hello world2: Excluded 2022/04/09 05:25:58 DEBUG : sub dir/ignore dir: Excluded 2022/04/09 05:25:58 DEBUG : Couldn't decode error response: EOF 2022/04/09 05:25:58 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSorted (27.88s) === RUN TestListJSON run.go:181: Remote "B2 bucket rclone-test-hepijow5tibeyuz9civumib2", Local "Local file system at /tmp/rclone2912821420", 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/04/09 05:26:11 ERROR : file1: Failed to read hash: hash type not supported --- PASS: TestListJSON (6.74s) --- 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.18s) --- 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-hepijow5tibeyuz9civumib2", Local "Local file system at /tmp/rclone2912821420", Modify Window "1ms" === RUN TestStatJSON/Root === RUN TestStatJSON/RootFilesOnly === RUN TestStatJSON/RootDirsOnly === RUN TestStatJSON/Dir 2022/04/09 05:26:16 DEBUG : Couldn't decode error response: EOF === RUN TestStatJSON/File === RUN TestStatJSON/NotFound 2022/04/09 05:26:17 DEBUG : Couldn't decode error response: EOF === RUN TestStatJSON/DirFilesOnly 2022/04/09 05:26:17 DEBUG : Couldn't decode error response: EOF === RUN TestStatJSON/FileFilesOnly === RUN TestStatJSON/NotFoundFilesOnly 2022/04/09 05:26:17 DEBUG : Couldn't decode error response: EOF === RUN TestStatJSON/DirDirsOnly === RUN TestStatJSON/FileDirsOnly === RUN TestStatJSON/NotFoundDirsOnly === RUN TestStatJSON/RootNotFound 2022/04/09 05:26:18 DEBUG : Creating backend with remote "TestB2:rclone-test-hepijow5tibeyuz9civumib2/notfound" 2022/04/09 05:26:19 DEBUG : Couldn't decode error response: EOF --- PASS: TestStatJSON (9.92s) --- 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.30s) === RUN TestMkdir run.go:181: Remote "B2 bucket rclone-test-hepijow5tibeyuz9civumib2", Local "Local file system at /tmp/rclone2912821420", Modify Window "1ms" 2022/04/09 05:26:23 DEBUG : B2 bucket rclone-test-hepijow5tibeyuz9civumib2: Making directory 2022/04/09 05:26:23 DEBUG : B2 bucket rclone-test-hepijow5tibeyuz9civumib2: Making directory --- PASS: TestMkdir (0.51s) === RUN TestLsd run.go:181: Remote "B2 bucket rclone-test-hepijow5tibeyuz9civumib2", Local "Local file system at /tmp/rclone2912821420", Modify Window "1ms" --- PASS: TestLsd (3.59s) === RUN TestLs run.go:181: Remote "B2 bucket rclone-test-hepijow5tibeyuz9civumib2", Local "Local file system at /tmp/rclone2912821420", Modify Window "1ms" --- PASS: TestLs (16.93s) === RUN TestLsWithFilesFrom run.go:181: Remote "B2 bucket rclone-test-hepijow5tibeyuz9civumib2", Local "Local file system at /tmp/rclone2912821420", Modify Window "1ms" 2022/04/09 05:26:48 DEBUG : empty space: Excluded from sync (and deletion) 2022/04/09 05:26:48 DEBUG : Couldn't decode error response: EOF --- PASS: TestLsWithFilesFrom (6.23s) === RUN TestLsLong run.go:181: Remote "B2 bucket rclone-test-hepijow5tibeyuz9civumib2", Local "Local file system at /tmp/rclone2912821420", Modify Window "1ms" --- PASS: TestLsLong (6.30s) === RUN TestHashSums run.go:181: Remote "B2 bucket rclone-test-hepijow5tibeyuz9civumib2", Local "Local file system at /tmp/rclone2912821420", Modify Window "1ms" === RUN TestHashSums/Sha1 === RUN TestHashSums/Sha1Download --- PASS: TestHashSums (7.69s) --- PASS: TestHashSums/Sha1 (0.17s) --- PASS: TestHashSums/Sha1Download (0.89s) === RUN TestHashSumsWithErrors 2022/04/09 05:27:05 DEBUG : Creating backend with remote ":memory:" 2022/04/09 05:27:05 ERROR : file1: hash unsupported: hash type not supported --- PASS: TestHashSumsWithErrors (0.00s) === RUN TestHashStream 2022/04/09 05:27:05 DEBUG : Creating md5 hash of 0 bytes read from input stream 2022/04/09 05:27:05 DEBUG : Creating md5 hash of 0 bytes read from input stream 2022/04/09 05:27:05 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2022/04/09 05:27:05 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2022/04/09 05:27:05 DEBUG : Creating md5 hash of 12 bytes read from input stream 2022/04/09 05:27:05 DEBUG : Creating md5 hash of 12 bytes read from input stream 2022/04/09 05:27:05 DEBUG : Creating sha1 hash of 12 bytes read from input stream 2022/04/09 05:27:05 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-hepijow5tibeyuz9civumib2", Local "Local file system at /tmp/rclone2912821420", Modify Window "1ms" --- PASS: TestCount (12.11s) === RUN TestDelete run.go:181: Remote "B2 bucket rclone-test-hepijow5tibeyuz9civumib2", Local "Local file system at /tmp/rclone2912821420", Modify Window "1ms" 2022/04/09 05:27:19 DEBUG : Waiting for deletions to finish 2022/04/09 05:27:20 DEBUG : large: Excluded from sync (and deletion) 2022/04/09 05:27:21 INFO : small: Deleted 2022/04/09 05:27:21 INFO : medium: Deleted --- PASS: TestDelete (5.89s) === RUN TestRetry 2022/04/09 05:27:23 DEBUG : Received error: EOF - low level retry 1/5 2022/04/09 05:27:23 DEBUG : Received error: EOF - low level retry 2/5 2022/04/09 05:27:23 DEBUG : Received error: EOF - low level retry 1/5 2022/04/09 05:27:23 DEBUG : Received error: EOF - low level retry 2/5 2022/04/09 05:27:23 DEBUG : Received error: EOF - low level retry 3/5 2022/04/09 05:27:23 DEBUG : Received error: EOF - low level retry 4/5 2022/04/09 05:27:23 DEBUG : Received error: EOF - low level retry 5/5 --- PASS: TestRetry (0.00s) === RUN TestCat run.go:181: Remote "B2 bucket rclone-test-hepijow5tibeyuz9civumib2", Local "Local file system at /tmp/rclone2912821420", Modify Window "1ms" --- PASS: TestCat (13.47s) === RUN TestPurge 2022/04/09 05:27:36 DEBUG : Creating backend with remote "TestB2:rclone-test-xunimat9xivumov5vohiwag7" 2022/04/09 05:27:36 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2022/04/09 05:27:37 DEBUG : Creating backend with remote "/tmp/rclone3353032665" run.go:181: Remote "B2 bucket rclone-test-xunimat9xivumov5vohiwag7", Local "Local file system at /tmp/rclone3353032665", Modify Window "1ms" 2022/04/09 05:27:39 DEBUG : A2: Making directory 2022/04/09 05:27:39 DEBUG : A1/B2: Making directory 2022/04/09 05:27:39 DEBUG : A1/B2/C2: Making directory 2022/04/09 05:27:39 DEBUG : A1/B1/C3: Making directory 2022/04/09 05:27:39 DEBUG : A3: Making directory 2022/04/09 05:27:39 DEBUG : A3/B3: Making directory 2022/04/09 05:27:39 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/04/09 05:27:39 DEBUG : A1/B1/C1/one: Deleting (id "4_z5f92cd63c0ad0bcb8f080e1b_f110bac93fa3d739d_d20220409_m052738_c001_v0001100_t0027_u01649482058428") 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/04/09 05:27:41 DEBUG : A1/two: Deleting (id "4_z5f92cd63c0ad0bcb8f080e1b_f110bac93fa3d73b6_d20220409_m052739_c001_v0001100_t0015_u01649482059273") 2022/04/09 05:27:41 DEBUG : B2 bucket rclone-test-xunimat9xivumov5vohiwag7: Purge remote 2022/04/09 05:27:42 purge failed: directory not found --- PASS: TestPurge (5.51s) === RUN TestRmdirsNoLeaveRoot run.go:181: Remote "B2 bucket rclone-test-hepijow5tibeyuz9civumib2", Local "Local file system at /tmp/rclone2912821420", Modify Window "1ms" 2022/04/09 05:27:43 DEBUG : A2: Making directory 2022/04/09 05:27:43 DEBUG : A1/B2: Making directory 2022/04/09 05:27:43 DEBUG : A1/B2/C2: Making directory 2022/04/09 05:27:43 DEBUG : A1/B1/C3: Making directory 2022/04/09 05:27:43 DEBUG : A3: Making directory 2022/04/09 05:27:43 DEBUG : A3/B3: Making directory 2022/04/09 05:27:43 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/04/09 05:27:43 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 (6.42s) === RUN TestRmdirsLeaveRoot run.go:181: Remote "B2 bucket rclone-test-hepijow5tibeyuz9civumib2", Local "Local file system at /tmp/rclone2912821420", Modify Window "1ms" 2022/04/09 05:27:48 DEBUG : A1: Making directory 2022/04/09 05:27:48 DEBUG : A1/B1: Making directory 2022/04/09 05:27:48 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-hepijow5tibeyuz9civumib2", Local "Local file system at /tmp/rclone2912821420", Modify Window "1ms" 2022/04/09 05:27:49 DEBUG : A1: Making directory 2022/04/09 05:27:49 DEBUG : A1/B1: Making directory 2022/04/09 05:27:49 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.86s) === RUN TestCopyURL run.go:181: Remote "B2 bucket rclone-test-hepijow5tibeyuz9civumib2", Local "Local file system at /tmp/rclone2912821420", Modify Window "1ms" 2022/04/09 05:27:54 DEBUG : filename.txt: File name found in url --- PASS: TestCopyURL (9.64s) === RUN TestCopyURLToWriter --- PASS: TestCopyURLToWriter (0.00s) === RUN TestMoveFile run.go:181: Remote "B2 bucket rclone-test-hepijow5tibeyuz9civumib2", Local "Local file system at /tmp/rclone2912821420", Modify Window "1ms" 2022/04/09 05:28:00 DEBUG : Couldn't decode error response: EOF 2022/04/09 05:28:00 DEBUG : file1: Need to transfer - File not found at Destination 2022/04/09 05:28:01 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2022/04/09 05:28:01 INFO : file1: Copied (new) to: sub/file2 2022/04/09 05:28:01 INFO : file1: Deleted 2022/04/09 05:28:01 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/04/09 05:28:01 DEBUG : file1: Unchanged skipping 2022/04/09 05:28:01 INFO : file1: Deleted 2022/04/09 05:28:01 DEBUG : B2 bucket rclone-test-hepijow5tibeyuz9civumib2: don't need to copy/move sub/file2, it is already at target location --- PASS: TestMoveFile (3.77s) === RUN TestMoveFileWithIgnoreExisting run.go:181: Remote "B2 bucket rclone-test-hepijow5tibeyuz9civumib2", Local "Local file system at /tmp/rclone2912821420", Modify Window "1ms" 2022/04/09 05:28:03 DEBUG : Couldn't decode error response: EOF 2022/04/09 05:28:03 DEBUG : file1: Need to transfer - File not found at Destination 2022/04/09 05:28:06 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2022/04/09 05:28:06 INFO : file1: Copied (new) 2022/04/09 05:28:06 INFO : file1: Deleted 2022/04/09 05:28:06 DEBUG : file1: Destination exists, skipping 2022/04/09 05:28:06 DEBUG : file1: Not removing source file as destination file exists and --ignore-existing is set --- PASS: TestMoveFileWithIgnoreExisting (4.29s) === RUN TestCaseInsensitiveMoveFile run.go:181: Remote "B2 bucket rclone-test-hepijow5tibeyuz9civumib2", Local "Local file system at /tmp/rclone2912821420", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFile (0.34s) === RUN TestMoveFileBackupDir run.go:181: Remote "B2 bucket rclone-test-hepijow5tibeyuz9civumib2", Local "Local file system at /tmp/rclone2912821420", Modify Window "1ms" 2022/04/09 05:28:13 DEBUG : Creating backend with remote "TestB2:rclone-test-hepijow5tibeyuz9civumib2/backup" 2022/04/09 05:28:14 DEBUG : Couldn't decode error response: EOF 2022/04/09 05:28:14 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18) 2022/04/09 05:28:15 DEBUG : Couldn't decode error response: EOF 2022/04/09 05:28:20 DEBUG : dst/file1: sha1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK 2022/04/09 05:28:20 INFO : dst/file1: Copied (server-side copy) 2022/04/09 05:28:22 INFO : dst/file1: Deleted 2022/04/09 05:28:25 DEBUG : dst/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2022/04/09 05:28:25 INFO : dst/file1: Copied (new) 2022/04/09 05:28:25 INFO : dst/file1: Deleted --- PASS: TestMoveFileBackupDir (20.30s) === RUN TestCopyFile run.go:181: Remote "B2 bucket rclone-test-hepijow5tibeyuz9civumib2", Local "Local file system at /tmp/rclone2912821420", Modify Window "1ms" 2022/04/09 05:28:29 DEBUG : Couldn't decode error response: EOF 2022/04/09 05:28:29 DEBUG : file1: Need to transfer - File not found at Destination 2022/04/09 05:28:29 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2022/04/09 05:28:29 INFO : file1: Copied (new) to: sub/file2 2022/04/09 05:28:29 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/04/09 05:28:29 DEBUG : file1: Unchanged skipping 2022/04/09 05:28:30 DEBUG : B2 bucket rclone-test-hepijow5tibeyuz9civumib2: don't need to copy/move sub/file2, it is already at target location --- PASS: TestCopyFile (4.19s) === RUN TestCopyFileBackupDir run.go:181: Remote "B2 bucket rclone-test-hepijow5tibeyuz9civumib2", Local "Local file system at /tmp/rclone2912821420", Modify Window "1ms" 2022/04/09 05:28:33 DEBUG : Creating backend with remote "TestB2:rclone-test-hepijow5tibeyuz9civumib2/backup" 2022/04/09 05:28:35 DEBUG : Couldn't decode error response: EOF 2022/04/09 05:28:35 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18) 2022/04/09 05:28:35 DEBUG : Couldn't decode error response: EOF 2022/04/09 05:28:37 DEBUG : dst/file1: sha1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK 2022/04/09 05:28:37 INFO : dst/file1: Copied (server-side copy) 2022/04/09 05:28:39 INFO : dst/file1: Deleted 2022/04/09 05:28:45 DEBUG : dst/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2022/04/09 05:28:45 INFO : dst/file1: Copied (new) --- PASS: TestCopyFileBackupDir (16.94s) === RUN TestCopyFileCompareDest run.go:181: Remote "B2 bucket rclone-test-hepijow5tibeyuz9civumib2", Local "Local file system at /tmp/rclone2912821420", Modify Window "1ms" 2022/04/09 05:28:49 DEBUG : Creating backend with remote "TestB2:rclone-test-hepijow5tibeyuz9civumib2/dst" 2022/04/09 05:28:51 DEBUG : Couldn't decode error response: EOF 2022/04/09 05:28:51 DEBUG : Couldn't decode error response: EOF 2022/04/09 05:28:51 DEBUG : Creating backend with remote "TestB2:rclone-test-hepijow5tibeyuz9civumib2/CompareDest" 2022/04/09 05:28:51 DEBUG : Couldn't decode error response: EOF 2022/04/09 05:28:51 DEBUG : Couldn't decode error response: EOF 2022/04/09 05:28:51 DEBUG : one: Need to transfer - File not found at Destination 2022/04/09 05:28:54 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/04/09 05:28:54 INFO : one: Copied (new) 2022/04/09 05:28:54 DEBUG : pacer: low level retry 1/10 (error Post "https://api001.backblazeb2.com/b2api/v1/b2_list_file_names": EOF) 2022/04/09 05:28:54 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2022/04/09 05:28:55 DEBUG : pacer: Reducing sleep to 10ms 2022/04/09 05:28:55 DEBUG : Couldn't decode error response: EOF 2022/04/09 05:28:55 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/04/09 05:28:56 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2022/04/09 05:28:56 INFO : one: Copied (replaced existing) 2022/04/09 05:29:09 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/04/09 05:29:09 DEBUG : one: Destination found in --compare-dest, skipping 2022/04/09 05:29:10 DEBUG : Couldn't decode error response: EOF 2022/04/09 05:29:10 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/04/09 05:29:10 DEBUG : two: Destination found in --compare-dest, skipping 2022/04/09 05:29:11 DEBUG : Couldn't decode error response: EOF 2022/04/09 05:29:11 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/04/09 05:29:11 DEBUG : two: Destination found in --compare-dest, skipping 2022/04/09 05:29:11 DEBUG : Couldn't decode error response: EOF 2022/04/09 05:29:12 DEBUG : two: Sizes differ (src 5 vs dst 3) 2022/04/09 05:29:12 DEBUG : two: Need to transfer - File not found at Destination 2022/04/09 05:29:14 DEBUG : two: sha1 = a4be8877ad88f3109a6411985a1f359e10e2d930 OK 2022/04/09 05:29:14 INFO : two: Copied (new) --- PASS: TestCopyFileCompareDest (29.92s) === RUN TestCopyFileCopyDest run.go:181: Remote "B2 bucket rclone-test-hepijow5tibeyuz9civumib2", Local "Local file system at /tmp/rclone2912821420", Modify Window "1ms" 2022/04/09 05:29:19 DEBUG : Creating backend with remote "TestB2:rclone-test-hepijow5tibeyuz9civumib2/dst" 2022/04/09 05:29:19 DEBUG : Config file has changed externaly - reloading 2022/04/09 05:29:20 DEBUG : Couldn't decode error response: EOF 2022/04/09 05:29:21 DEBUG : Couldn't decode error response: EOF 2022/04/09 05:29:21 DEBUG : Creating backend with remote "TestB2:rclone-test-hepijow5tibeyuz9civumib2/CopyDest" 2022/04/09 05:29:21 DEBUG : Couldn't decode error response: EOF 2022/04/09 05:29:21 DEBUG : Couldn't decode error response: EOF 2022/04/09 05:29:21 DEBUG : one: Need to transfer - File not found at Destination 2022/04/09 05:29:24 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/04/09 05:29:24 INFO : one: Copied (new) 2022/04/09 05:29:26 DEBUG : Couldn't decode error response: EOF 2022/04/09 05:29:26 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/04/09 05:29:26 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2022/04/09 05:29:26 INFO : one: Copied (replaced existing) 2022/04/09 05:29:33 DEBUG : Creating backend with remote "TestB2:rclone-test-hepijow5tibeyuz9civumib2/BackupDir" 2022/04/09 05:29:34 DEBUG : Couldn't decode error response: EOF 2022/04/09 05:29:34 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/04/09 05:29:34 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/04/09 05:29:35 DEBUG : Couldn't decode error response: EOF 2022/04/09 05:29:37 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/04/09 05:29:37 INFO : one: Copied (server-side copy) 2022/04/09 05:29:39 INFO : one: Deleted 2022/04/09 05:29:41 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2022/04/09 05:29:41 INFO : one: Copied (server-side copy) 2022/04/09 05:29:41 DEBUG : one: Destination found in --copy-dest, using server-side copy 2022/04/09 05:29:45 DEBUG : Couldn't decode error response: EOF 2022/04/09 05:29:45 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/04/09 05:29:46 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2022/04/09 05:29:46 INFO : two: Copied (server-side copy) 2022/04/09 05:29:46 DEBUG : two: Destination found in --copy-dest, using server-side copy 2022/04/09 05:29:46 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/04/09 05:29:46 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/04/09 05:29:46 DEBUG : two: Unchanged skipping 2022/04/09 05:29:50 DEBUG : Couldn't decode error response: EOF 2022/04/09 05:29:50 DEBUG : three: Sizes differ (src 7 vs dst 5) 2022/04/09 05:29:50 DEBUG : three: Destination not found in --copy-dest 2022/04/09 05:29:50 DEBUG : three: Need to transfer - File not found at Destination 2022/04/09 05:29:51 DEBUG : three: sha1 = 370e613335f3cf2f19b2a2d35a86402f55c659f8 OK 2022/04/09 05:29:51 INFO : three: Copied (new) --- PASS: TestCopyFileCopyDest (46.23s) === 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-hepijow5tibeyuz9civumib2", Local "Local file system at /tmp/rclone2912821420", Modify Window "1ms" 2022/04/09 05:30:24 DEBUG : A1/B2: Making directory 2022/04/09 05:30:24 DEBUG : A1/B1/C3: Making directory fstest.go:251: Filtering empty directory "A1/B2" fstest.go:251: Filtering empty directory "A1/B1/C3" 2022/04/09 05:30:26 DEBUG : Couldn't decode error response: EOF 2022/04/09 05:30:26 DEBUG : Couldn't decode error response: EOF 2022/04/09 05:30:26 DEBUG : Couldn't decode error response: EOF 2022/04/09 05:30:26 DEBUG : Couldn't decode error response: EOF 2022/04/09 05:30:27 DEBUG : A1/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2022/04/09 05:30:27 INFO : A1/B1/C2/five: Copied (server-side copy) to: A2/B1/C2/five 2022/04/09 05:30:27 INFO : A1/B1/C2/five: Deleted 2022/04/09 05:30:27 DEBUG : Couldn't decode error response: EOF 2022/04/09 05:30:28 DEBUG : A1/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/04/09 05:30:28 INFO : A1/one: Copied (server-side copy) to: A2/one 2022/04/09 05:30:28 DEBUG : A1/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2022/04/09 05:30:28 INFO : A1/B1/C1/four: Copied (server-side copy) to: A2/B1/C1/four 2022/04/09 05:30:28 DEBUG : A1/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2022/04/09 05:30:28 INFO : A1/B1/three: Copied (server-side copy) to: A2/B1/three 2022/04/09 05:30:28 INFO : A1/B1/C1/four: Deleted 2022/04/09 05:30:28 INFO : A1/one: Deleted 2022/04/09 05:30:29 INFO : A1/B1/three: Deleted 2022/04/09 05:30:29 DEBUG : A1/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2022/04/09 05:30:29 INFO : A1/two: Copied (server-side copy) to: A2/two 2022/04/09 05:30:30 INFO : A1/two: Deleted fstest.go:251: Filtering empty directory "A2/B2" fstest.go:251: Filtering empty directory "A2/B1/C3" 2022/04/09 05:30:31 DEBUG : Couldn't decode error response: EOF 2022/04/09 05:30:31 DEBUG : Couldn't decode error response: EOF 2022/04/09 05:30:31 DEBUG : Couldn't decode error response: EOF 2022/04/09 05:30:31 DEBUG : Couldn't decode error response: EOF 2022/04/09 05:30:31 DEBUG : A2/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2022/04/09 05:30:31 INFO : A2/B1/three: Copied (server-side copy) to: A3/B1/three 2022/04/09 05:30:32 DEBUG : A2/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/04/09 05:30:32 INFO : A2/one: Copied (server-side copy) to: A3/one 2022/04/09 05:30:32 DEBUG : A2/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2022/04/09 05:30:32 INFO : A2/B1/C1/four: Copied (server-side copy) to: A3/B1/C1/four 2022/04/09 05:30:33 INFO : A2/B1/C1/four: Deleted 2022/04/09 05:30:33 DEBUG : A2/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2022/04/09 05:30:33 INFO : A2/B1/C2/five: Copied (server-side copy) to: A3/B1/C2/five 2022/04/09 05:30:33 INFO : A2/one: Deleted 2022/04/09 05:30:33 DEBUG : Couldn't decode error response: EOF 2022/04/09 05:30:33 INFO : A2/B1/C2/five: Deleted 2022/04/09 05:30:34 DEBUG : A2/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2022/04/09 05:30:34 INFO : A2/two: Copied (server-side copy) to: A3/two 2022/04/09 05:30:34 INFO : A2/B1/three: Deleted 2022/04/09 05:30:35 INFO : A2/two: Deleted fstest.go:251: Filtering empty directory "A3/B2" fstest.go:251: Filtering empty directory "A3/B1/C3" --- PASS: TestDirMove (37.56s) === RUN TestGetFsInfo run.go:181: Remote "B2 bucket rclone-test-hepijow5tibeyuz9civumib2", Local "Local file system at /tmp/rclone2912821420", Modify Window "1ms" --- PASS: TestGetFsInfo (0.51s) === RUN TestRcat === RUN TestRcat/withChecksum=false,ignoreChecksum=false === CONT TestRcat run.go:181: Remote "B2 bucket rclone-test-hepijow5tibeyuz9civumib2", Local "Local file system at /tmp/rclone2912821420", Modify Window "1ms" 2022/04/09 05:30:44 DEBUG : B2 bucket rclone-test-hepijow5tibeyuz9civumib2: File to upload is small (34 bytes), uploading instead of streaming 2022/04/09 05:30:45 DEBUG : no_checksum_small_file_from_pipe: sha1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK 2022/04/09 05:30:45 INFO : no_checksum_small_file_from_pipe: Copied (new) 2022/04/09 05:30:45 DEBUG : no_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2022/04/09 05:30:46 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-hepijow5tibeyuz9civumib2", Local "Local file system at /tmp/rclone2912821420", Modify Window "1ms" 2022/04/09 05:30:52 DEBUG : B2 bucket rclone-test-hepijow5tibeyuz9civumib2: File to upload is small (34 bytes), uploading instead of streaming 2022/04/09 05:30:53 DEBUG : with_checksum_small_file_from_pipe: sha1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK 2022/04/09 05:30:53 INFO : with_checksum_small_file_from_pipe: Copied (new) 2022/04/09 05:30:53 DEBUG : with_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2022/04/09 05:30:56 DEBUG : with_checksum_big_file_from_pipe: sha1 = d0080f73aa8b494570ad63df1beb25c01ea9eea2 OK 2022/04/09 05:30:56 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-hepijow5tibeyuz9civumib2", Local "Local file system at /tmp/rclone2912821420", Modify Window "1ms" 2022/04/09 05:30:58 DEBUG : B2 bucket rclone-test-hepijow5tibeyuz9civumib2: File to upload is small (34 bytes), uploading instead of streaming 2022/04/09 05:30:58 INFO : ignore_checksum_small_file_from_pipe: Copied (new) 2022/04/09 05:30:58 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2022/04/09 05:31:00 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-hepijow5tibeyuz9civumib2", Local "Local file system at /tmp/rclone2912821420", Modify Window "1ms" 2022/04/09 05:31:04 DEBUG : B2 bucket rclone-test-hepijow5tibeyuz9civumib2: File to upload is small (34 bytes), uploading instead of streaming 2022/04/09 05:31:05 INFO : ignore_checksum_small_file_from_pipe: Copied (new) 2022/04/09 05:31:05 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2022/04/09 05:31:07 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical --- PASS: TestRcat (26.90s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (8.45s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (5.98s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (6.51s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (5.96s) === RUN TestRcatSize run.go:181: Remote "B2 bucket rclone-test-hepijow5tibeyuz9civumib2", Local "Local file system at /tmp/rclone2912821420", Modify Window "1ms" 2022/04/09 05:31:11 DEBUG : B2 bucket rclone-test-hepijow5tibeyuz9civumib2: File to upload is small (60 bytes), uploading instead of streaming 2022/04/09 05:31:12 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2022/04/09 05:31:12 INFO : potato2: Copied (new) --- PASS: TestRcatSize (4.69s) === RUN TestCopyFileMaxTransfer run.go:181: Remote "B2 bucket rclone-test-hepijow5tibeyuz9civumib2", Local "Local file system at /tmp/rclone2912821420", Modify Window "1ms" 2022/04/09 05:31:16 DEBUG : Couldn't decode error response: EOF 2022/04/09 05:31:16 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination 2022/04/09 05:31:16 DEBUG : TestCopyFileMaxTransfer/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2022/04/09 05:31:16 INFO : TestCopyFileMaxTransfer/file1: Copied (new) 2022/04/09 05:31:17 DEBUG : Couldn't decode error response: EOF 2022/04/09 05:31:17 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination 2022/04/09 05:31:17 ERROR : TestCopyFileMaxTransfer/file2: Failed to copy: Post "https://pod-000-1102-14.backblaze.com/b2api/v1/b2_upload_file/1fb20d43c07d0bcb8f080e1b/c001_v0001102_t0055": Max transfer limit reached as set by --max-transfer 2022/04/09 05:31:17 DEBUG : Couldn't decode error response: EOF 2022/04/09 05:31:17 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination 2022/04/09 05:31:17 DEBUG : Couldn't decode error response: EOF 2022/04/09 05:31:17 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination 2022/04/09 05:31:21 DEBUG : TestCopyFileMaxTransfer/file4: sha1 = 39c8cf73d341451c33ebbae0eefb639e73964b42 OK 2022/04/09 05:31:21 INFO : TestCopyFileMaxTransfer/file4: Copied (new) --- PASS: TestCopyFileMaxTransfer (8.27s) === RUN TestTouchDir run.go:181: Remote "B2 bucket rclone-test-hepijow5tibeyuz9civumib2", Local "Local file system at /tmp/rclone2912821420", Modify Window "1ms" 2022/04/09 05:31:28 DEBUG : B2 bucket rclone-test-hepijow5tibeyuz9civumib2: Touching "empty space" 2022/04/09 05:31:29 DEBUG : B2 bucket rclone-test-hepijow5tibeyuz9civumib2: Touching "potato2" 2022/04/09 05:31:30 DEBUG : B2 bucket rclone-test-hepijow5tibeyuz9civumib2: Touching "sub dir/potato3" --- PASS: TestTouchDir (13.63s) === 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/04/09 05:31:37 DEBUG : B2 bucket rclone-test-hepijow5tibeyuz9civumib2: Purge remote 2022/04/09 05:31:37 DEBUG : A1/B1/C1/four: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f100f03ec7ade95e4_d20220409_m053028_c001_v0001100_t0024_u01649482228215") 2022/04/09 05:31:37 DEBUG : A1/B1/C1/four: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62dbc7_d20220409_m053016_c001_v0001102_t0030_u01649482216348") 2022/04/09 05:31:37 DEBUG : A1/B1/C1/one: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f1011f56904a0da0e_d20220409_m052744_c001_v0001145_t0008_u01649482064721") 2022/04/09 05:31:37 DEBUG : A1/B1/C1/one: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62cefa_d20220409_m052742_c001_v0001102_t0038_u01649482062606") 2022/04/09 05:31:37 DEBUG : A1/B1/C2/five: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f107cfa0299aef5a7_d20220409_m053027_c001_v0001157_t0033_u01649482227398") 2022/04/09 05:31:37 DEBUG : A1/B1/C2/five: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62dbe8_d20220409_m053017_c001_v0001102_t0017_u01649482217988") 2022/04/09 05:31:37 DEBUG : A1/B1/three: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f117430d9eb4d600e_d20220409_m053028_c001_v0001098_t0052_u01649482228375") 2022/04/09 05:31:37 DEBUG : A1/B1/three: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62dbb9_d20220409_m053015_c001_v0001102_t0047_u01649482215504") 2022/04/09 05:31:37 DEBUG : A1/one: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f1015f24203869b61_d20220409_m053028_c001_v0001099_t0038_u01649482228170") 2022/04/09 05:31:38 DEBUG : A1/one: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62db06_d20220409_m053006_c001_v0001102_t0027_u01649482206364") 2022/04/09 05:31:38 DEBUG : A1/two: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f107c42de7842da40_d20220409_m053029_c001_v0001120_t0012_u01649482229890") 2022/04/09 05:31:38 DEBUG : A1/two: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62dba8_d20220409_m053014_c001_v0001102_t0048_u01649482214373") 2022/04/09 05:31:38 DEBUG : A1/two: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f107c0ec1bffcf94d_d20220409_m052745_c001_v0001105_t0045_u01649482065719") 2022/04/09 05:31:38 DEBUG : A1/two: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62cf1c_d20220409_m052743_c001_v0001102_t0005_u01649482063308") 2022/04/09 05:31:38 DEBUG : A2/B1/C1/four: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f10262d07e038a20c_d20220409_m053032_c001_v0001103_t0058_u01649482232889") 2022/04/09 05:31:38 DEBUG : A2/B1/C1/four: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f115c8b03f9a93f46_d20220409_m053026_c001_v0001115_t0000_u01649482226644") 2022/04/09 05:31:38 DEBUG : A2/B1/C2/five: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f113676dd5acf957f_d20220409_m053033_c001_v0001150_t0002_u01649482233376") 2022/04/09 05:31:38 DEBUG : A2/B1/C2/five: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f117080c706b501f6_d20220409_m053027_c001_v0001165_t0014_u01649482227157") 2022/04/09 05:31:38 DEBUG : A2/B1/three: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f1133b4e210ac33e8_d20220409_m053031_c001_v0001117_t0039_u01649482231890") 2022/04/09 05:31:38 DEBUG : A2/B1/three: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f1041002e1afc85d1_d20220409_m053027_c001_v0001145_t0005_u01649482227187") 2022/04/09 05:31:38 DEBUG : A2/one: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f117b8f7b05d289fa_d20220409_m053032_c001_v0001009_t0035_u01649482232202") 2022/04/09 05:31:38 DEBUG : A2/one: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f1039d50589b45a9e_d20220409_m053027_c001_v0001098_t0041_u01649482227188") 2022/04/09 05:31:38 DEBUG : A2/two: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f101ab026d99e0b0e_d20220409_m053034_c001_v0001103_t0040_u01649482234165") 2022/04/09 05:31:39 DEBUG : A2/two: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f110bac93fa3d7efe_d20220409_m053028_c001_v0001100_t0057_u01649482228134") 2022/04/09 05:31:39 DEBUG : A3/B1/C1/four: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f1070b6896469a54a_d20220409_m053035_c001_v0001100_t0008_u01649482235707") 2022/04/09 05:31:39 DEBUG : A3/B1/C1/four: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f1054ecca6363b7c7_d20220409_m053031_c001_v0001103_t0026_u01649482231447") 2022/04/09 05:31:39 DEBUG : A3/B1/C2/five: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f104e5cc1a1db4c07_d20220409_m053036_c001_v0001015_t0043_u01649482236644") 2022/04/09 05:31:39 DEBUG : A3/B1/C2/five: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f10735266e7fe2f98_d20220409_m053031_c001_v0001106_t0055_u01649482231409") 2022/04/09 05:31:39 DEBUG : A3/B1/three: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f105a6165bf7e1c55_d20220409_m053038_c001_v0001109_t0024_u01649482238512") 2022/04/09 05:31:39 DEBUG : A3/B1/three: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f106f659b568cb9d7_d20220409_m053031_c001_v0001101_t0022_u01649482231320") 2022/04/09 05:31:39 DEBUG : A3/one: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f119225d769c1151f_d20220409_m053039_c001_v0001116_t0004_u01649482239237") 2022/04/09 05:31:39 DEBUG : A3/one: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f115186acf35ec394_d20220409_m053031_c001_v0001100_t0035_u01649482231398") 2022/04/09 05:31:39 DEBUG : A3/two: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f107d5bd14a332ffc_d20220409_m053040_c001_v0001099_t0003_u01649482240451") 2022/04/09 05:31:39 DEBUG : A3/two: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f111b4941beb1ccf5_d20220409_m053033_c001_v0001157_t0047_u01649482233534") 2022/04/09 05:31:39 DEBUG : BackupDir/one: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f108d018405c4ab99_d20220409_m052952_c001_v0001145_t0006_u01649482192134") 2022/04/09 05:31:39 DEBUG : BackupDir/one: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f1105d9d712a68770_d20220409_m052936_c001_v0001116_t0039_u01649482176638") 2022/04/09 05:31:39 DEBUG : CompareDest/one: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f109bdebd96aed3d2_d20220409_m052914_c001_v0001116_t0022_u01649482154527") 2022/04/09 05:31:39 DEBUG : CompareDest/one: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62d604_d20220409_m052906_c001_v0001102_t0053_u01649482146765") 2022/04/09 05:31:39 DEBUG : CompareDest/two: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f101149f32cf43d6a_d20220409_m052915_c001_v0001115_t0015_u01649482155590") 2022/04/09 05:31:39 DEBUG : CompareDest/two: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62d647_d20220409_m052909_c001_v0001102_t0046_u01649482149531") 2022/04/09 05:31:39 DEBUG : CopyDest/one: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f107b9d073897bc06_d20220409_m052953_c001_v0001101_t0016_u01649482193685") 2022/04/09 05:31:39 DEBUG : CopyDest/one: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62d7ff_d20220409_m052931_c001_v0001102_t0055_u01649482169053") 2022/04/09 05:31:39 DEBUG : CopyDest/three: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f116f4cb3b5efa9d3_d20220409_m052954_c001_v0001102_t0033_u01649482194929") 2022/04/09 05:31:39 DEBUG : CopyDest/three: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62d96a_d20220409_m052948_c001_v0001102_t0055_u01649482188616") 2022/04/09 05:31:40 DEBUG : CopyDest/two: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f106cc5fcd5a8f269_d20220409_m052958_c001_v0001161_t0005_u01649482198462") 2022/04/09 05:31:40 DEBUG : CopyDest/two: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62d8fc_d20220409_m052942_c001_v0001102_t0042_u01649482182501") 2022/04/09 05:31:40 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f1143868002e543b1_d20220409_m053121_c001_v0001099_t0029_u01649482281981") 2022/04/09 05:31:40 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62e13d_d20220409_m053116_c001_v0001102_t0036_u01649482276204") 2022/04/09 05:31:40 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f109fdf634c085433_d20220409_m053123_c001_v0001157_t0052_u01649482283142") 2022/04/09 05:31:40 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62e17b_d20220409_m053118_c001_v0001102_t0043_u01649482278351") 2022/04/09 05:31:40 DEBUG : a.txt: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f104f2d82c7ed3cf3_d20220409_m052559_c001_v0001097_t0021_u01649481959554") 2022/04/09 05:31:40 DEBUG : a.txt: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62c594_d20220409_m052539_c001_v0001102_t0035_u01649481939861") 2022/04/09 05:31:40 DEBUG : also/one: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f1139c3c4804d82d3_d20220409_m052534_c001_v0001109_t0044_u01649481934099") 2022/04/09 05:31:40 DEBUG : also/one: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62c48a_d20220409_m052527_c001_v0001102_t0011_u01649481927949") 2022/04/09 05:31:40 DEBUG : another: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f101ab026d99df292_d20220409_m052535_c001_v0001103_t0048_u01649481935543") 2022/04/09 05:31:40 DEBUG : another: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62c4cd_d20220409_m052531_c001_v0001102_t0003_u01649481931589") 2022/04/09 05:31:40 DEBUG : backup/dst/file1: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f10083079b37949bb_d20220409_m052846_c001_v0001115_t0058_u01649482126185") 2022/04/09 05:31:40 DEBUG : backup/dst/file1: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f1029a237f3bd5817_d20220409_m052836_c001_v0001105_t0046_u01649482116683") 2022/04/09 05:31:40 DEBUG : backup/dst/file1: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f104a3a5e2da834ce_d20220409_m052826_c001_v0001115_t0058_u01649482106265") 2022/04/09 05:31:40 DEBUG : backup/dst/file1: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f100116ce5fb1c006_d20220409_m052818_c001_v0001104_t0019_u01649482098716") 2022/04/09 05:31:40 DEBUG : data/banana: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f116f4cb3b5ef9361_d20220409_m052519_c001_v0001102_t0009_u01649481919764") 2022/04/09 05:31:40 DEBUG : data/banana: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62c29f_d20220409_m052510_c001_v0001102_t0000_u01649481909585") 2022/04/09 05:31:40 DEBUG : data/banana: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62c111_d20220409_m052449_c001_v0001102_t0000_u01649481889050") 2022/04/09 05:31:40 DEBUG : data/potato: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f104a3a5e2da82801_d20220409_m052521_c001_v0001115_t0021_u01649481921529") 2022/04/09 05:31:40 DEBUG : data/potato: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62c2ca_d20220409_m052511_c001_v0001102_t0014_u01649481911594") 2022/04/09 05:31:41 DEBUG : data/potato: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62c12c_d20220409_m052451_c001_v0001102_t0024_u01649481891061") 2022/04/09 05:31:41 DEBUG : dst/file1: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f1104032e00b7b8bb_d20220409_m052847_c001_v0001099_t0005_u01649482127524") 2022/04/09 05:31:41 DEBUG : dst/file1: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62d3c2_d20220409_m052840_c001_v0001102_t0058_u01649482120275") 2022/04/09 05:31:41 DEBUG : dst/file1: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f1070005c43f96e40_d20220409_m052837_c001_v0001117_t0003_u01649482117604") 2022/04/09 05:31:41 DEBUG : dst/file1: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62d32f_d20220409_m052832_c001_v0001102_t0038_u01649482112766") 2022/04/09 05:31:41 DEBUG : dst/file1: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f119762f90fc3ad37_d20220409_m052827_c001_v0001104_t0059_u01649482107449") 2022/04/09 05:31:41 DEBUG : dst/file1: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62d27f_d20220409_m052823_c001_v0001102_t0035_u01649482103147") 2022/04/09 05:31:41 DEBUG : dst/file1: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f109df3de39b64ad2_d20220409_m052821_c001_v0001115_t0001_u01649482101312") 2022/04/09 05:31:41 DEBUG : dst/file1: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62d146_d20220409_m052808_c001_v0001102_t0024_u01649482088281") 2022/04/09 05:31:41 DEBUG : dst/one: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f10037e478619e35e_d20220409_m052958_c001_v0001103_t0004_u01649482198972") 2022/04/09 05:31:41 DEBUG : dst/one: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f103a76ea95b6920e_d20220409_m052939_c001_v0001099_t0005_u01649482179502") 2022/04/09 05:31:41 DEBUG : dst/one: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f113da6d980ae5524_d20220409_m052937_c001_v0001101_t0026_u01649482177849") 2022/04/09 05:31:41 DEBUG : dst/one: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62d7de_d20220409_m052928_c001_v0001102_t0041_u01649482167679") 2022/04/09 05:31:41 DEBUG : dst/one: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f104a3a5e2da83944_d20220409_m052926_c001_v0001115_t0004_u01649482166112") 2022/04/09 05:31:41 DEBUG : dst/one: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f104a3a5e2da8391b_d20220409_m052923_c001_v0001115_t0040_u01649482163817") 2022/04/09 05:31:41 DEBUG : dst/one: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f1165c2c75c9102dc_d20220409_m052917_c001_v0001101_t0037_u01649482157036") 2022/04/09 05:31:41 DEBUG : dst/one: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62d542_d20220409_m052857_c001_v0001102_t0019_u01649482137572") 2022/04/09 05:31:41 DEBUG : dst/one: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f109190a660610279_d20220409_m052855_c001_v0001105_t0027_u01649482135907") 2022/04/09 05:31:41 DEBUG : dst/one: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f109190a660610268_d20220409_m052853_c001_v0001105_t0035_u01649482133953") 2022/04/09 05:31:41 DEBUG : dst/three: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f116a71c3a5acdebe_d20220409_m052959_c001_v0001106_t0015_u01649482199904") 2022/04/09 05:31:42 DEBUG : dst/three: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f104a3a5e2da83ad3_d20220409_m052950_c001_v0001115_t0039_u01649482190802") 2022/04/09 05:31:42 DEBUG : dst/two: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f108694b8e83d2c6b_d20220409_m053001_c001_v0001105_t0007_u01649482201733") 2022/04/09 05:31:42 DEBUG : dst/two: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f1097a7a5054ef736_d20220409_m052945_c001_v0001001_t0023_u01649482185840") 2022/04/09 05:31:42 DEBUG : dst/two: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f100b40366945c35b_d20220409_m052918_c001_v0001099_t0024_u01649482158759") 2022/04/09 05:31:42 DEBUG : dst/two: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f109190a660610312_d20220409_m052912_c001_v0001105_t0028_u01649482152598") 2022/04/09 05:31:42 DEBUG : empty space: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f1032a538b8e841ec_d20220409_m053131_c001_v0001106_t0054_u01649482291991") 2022/04/09 05:31:42 DEBUG : empty space: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f1107e106dbf469d2_d20220409_m053129_c001_v0001161_t0002_u01649482289452") 2022/04/09 05:31:42 DEBUG : empty space: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62e207_d20220409_m053124_c001_v0001102_t0013_u01649482284965") 2022/04/09 05:31:42 DEBUG : empty space: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f117011031aa0f7bd_d20220409_m052712_c001_v0001038_t0026_u01649482032473") 2022/04/09 05:31:42 DEBUG : empty space: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62cc51_d20220409_m052705_c001_v0001102_t0001_u01649482025617") 2022/04/09 05:31:42 DEBUG : empty space: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f1109b421420819ef_d20220409_m052702_c001_v0001104_t0029_u01649482022896") 2022/04/09 05:31:42 DEBUG : empty space: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62cbad_d20220409_m052658_c001_v0001102_t0037_u01649482018979") 2022/04/09 05:31:42 DEBUG : empty space: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f10739dea3e60b299_d20220409_m052653_c001_v0001109_t0028_u01649482013358") 2022/04/09 05:31:42 DEBUG : empty space: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62cb37_d20220409_m052652_c001_v0001102_t0054_u01649482012122") 2022/04/09 05:31:42 DEBUG : empty space: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11080865f66f49d1_d20220409_m052649_c001_v0001165_t0041_u01649482009244") 2022/04/09 05:31:42 DEBUG : empty space: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62cad9_d20220409_m052646_c001_v0001102_t0018_u01649482006819") 2022/04/09 05:31:42 DEBUG : empty space: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f1071c8ff8472c2e3_d20220409_m052642_c001_v0001103_t0007_u01649482002375") 2022/04/09 05:31:42 DEBUG : empty space: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62c995_d20220409_m052631_c001_v0001102_t0040_u01649481991576") 2022/04/09 05:31:42 DEBUG : empty space: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f10884a54df64ec3d_d20220409_m052430_c001_v0001091_t0009_u01649481870330") 2022/04/09 05:31:42 DEBUG : empty space: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62bf6e_d20220409_m052425_c001_v0001102_t0027_u01649481865528") 2022/04/09 05:31:42 DEBUG : empty space: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f1022240e4f92860a_d20220409_m052417_c001_v0001106_t0024_u01649481857157") 2022/04/09 05:31:43 DEBUG : empty space: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62be3d_d20220409_m052410_c001_v0001102_t0047_u01649481850293") 2022/04/09 05:31:43 DEBUG : empty space: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f1024c451b63afcc2_d20220409_m052401_c001_v0001102_t0047_u01649481839339") 2022/04/09 05:31:43 DEBUG : empty space: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62bcc0_d20220409_m052352_c001_v0001102_t0007_u01649481832424") 2022/04/09 05:31:43 DEBUG : file1: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f109646263b7a0c53_d20220409_m052807_c001_v0001137_t0040_u01649482087325") 2022/04/09 05:31:43 DEBUG : file1: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62d0e6_d20220409_m052803_c001_v0001102_t0017_u01649482083814") 2022/04/09 05:31:43 DEBUG : file1: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f1125c09dd31a4e5f_d20220409_m052756_c001_v0001099_t0046_u01649482076660") 2022/04/09 05:31:43 DEBUG : file1: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62cfda_d20220409_m052751_c001_v0001102_t0025_u01649482071481") 2022/04/09 05:31:43 DEBUG : file1: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f100b02b2db603db4_d20220409_m052730_c001_v0001145_t0007_u01649482050190") 2022/04/09 05:31:43 DEBUG : file1: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62cd82_d20220409_m052723_c001_v0001102_t0011_u01649482043117") 2022/04/09 05:31:43 DEBUG : file1: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11858b433f31707c_d20220409_m052619_c001_v0001104_t0025_u01649481979992") 2022/04/09 05:31:43 DEBUG : file1: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62c84f_d20220409_m052613_c001_v0001102_t0043_u01649481973935") 2022/04/09 05:31:43 DEBUG : file1: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f109c772c6864f035_d20220409_m052611_c001_v0001093_t0046_u01649481971306") 2022/04/09 05:31:43 DEBUG : file1: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62c7ec_d20220409_m052607_c001_v0001102_t0046_u01649481967703") 2022/04/09 05:31:43 DEBUG : file1: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f104afdf606f5f73a_d20220409_m052345_c001_v0001100_t0050_u01649481825372") 2022/04/09 05:31:43 DEBUG : file1: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62bbeb_d20220409_m052341_c001_v0001102_t0003_u01649481821913") 2022/04/09 05:31:43 DEBUG : file1: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62bbb4_d20220409_m052338_c001_v0001102_t0000_u01649481818624") 2022/04/09 05:31:43 DEBUG : file1: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62bb26_d20220409_m052332_c001_v0001102_t0023_u01649481812066") 2022/04/09 05:31:43 DEBUG : file2: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f115c8b03f9a935c0_d20220409_m052758_c001_v0001115_t0052_u01649482078042") 2022/04/09 05:31:43 DEBUG : file2: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62d03f_d20220409_m052755_c001_v0001102_t0047_u01649482075446") 2022/04/09 05:31:43 DEBUG : file2: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f10124a054bf3d817_d20220409_m052730_c001_v0001102_t0046_u01649482050951") 2022/04/09 05:31:43 DEBUG : file2: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62cdce_d20220409_m052726_c001_v0001102_t0044_u01649482046269") 2022/04/09 05:31:44 DEBUG : filename.txt: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f119e0f3ff78e4ba1_d20220409_m052759_c001_v0001009_t0001_u01649482079171") 2022/04/09 05:31:44 DEBUG : filename.txt: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62d031_d20220409_m052754_c001_v0001102_t0011_u01649482074896") 2022/04/09 05:31:44 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f104e0dd206df97e0_d20220409_m053108_c001_v0001106_t0012_u01649482268199") 2022/04/09 05:31:44 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62e052_d20220409_m053105_c001_v0001102_t0015_u01649482265966") 2022/04/09 05:31:44 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f1006c1618d8451fa_d20220409_m053100_c001_v0001102_t0039_u01649482260858") 2022/04/09 05:31:44 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62dfc4_d20220409_m053058_c001_v0001102_t0046_u01649482258967") 2022/04/09 05:31:44 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f1021335d8a691fcd_d20220409_m053109_c001_v0001120_t0051_u01649482269701") 2022/04/09 05:31:44 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62e03c_d20220409_m053105_c001_v0001102_t0002_u01649482265048") 2022/04/09 05:31:44 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f106f29c870180578_d20220409_m053102_c001_v0001117_t0021_u01649482262061") 2022/04/09 05:31:44 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62dfb7_d20220409_m053058_c001_v0001102_t0036_u01649482258544") 2022/04/09 05:31:44 DEBUG : large: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f10943abf778dfd77_d20220409_m052722_c001_v0001145_t0058_u01649482042244") 2022/04/09 05:31:44 DEBUG : large: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62cd3c_d20220409_m052718_c001_v0001102_t0055_u01649482038981") 2022/04/09 05:31:44 DEBUG : medium: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f115b59835696b237_d20220409_m052720_c001_v0001103_t0043_u01649482040286") 2022/04/09 05:31:44 DEBUG : medium: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62cd32_d20220409_m052718_c001_v0001102_t0039_u01649482038332") 2022/04/09 05:31:44 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f111ad7feae77ff11_d20220409_m053047_c001_v0001103_t0028_u01649482247386") 2022/04/09 05:31:44 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62de96_d20220409_m053045_c001_v0001102_t0057_u01649482245987") 2022/04/09 05:31:44 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11412af1f339e08e_d20220409_m053051_c001_v0001105_t0011_u01649482251249") 2022/04/09 05:31:44 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62de70_d20220409_m053044_c001_v0001102_t0057_u01649482244620") 2022/04/09 05:31:44 DEBUG : not-one: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f1005896c3b454fa1_d20220409_m052536_c001_v0001161_t0044_u01649481936607") 2022/04/09 05:31:44 DEBUG : not-one: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62c4d5_d20220409_m052532_c001_v0001102_t0022_u01649481932125") 2022/04/09 05:31:45 DEBUG : one: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f114d0a1d139e7fab_d20220409_m052533_c001_v0001101_t0012_u01649481933541") 2022/04/09 05:31:45 DEBUG : one: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62c459_d20220409_m052526_c001_v0001102_t0059_u01649481926045") 2022/04/09 05:31:45 DEBUG : potato1: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f10863ff4c32840b8_d20220409_m053113_c001_v0001109_t0024_u01649482273051") 2022/04/09 05:31:45 DEBUG : potato1: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62e0ba_d20220409_m053110_c001_v0001102_t0029_u01649482270835") 2022/04/09 05:31:45 DEBUG : potato2: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11446f46a4420034_d20220409_m053134_c001_v0001136_t0003_u01649482294626") 2022/04/09 05:31:45 DEBUG : potato2: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f10635f54adb9c631_d20220409_m053130_c001_v0001098_t0049_u01649482290229") 2022/04/09 05:31:45 DEBUG : potato2: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62e1f3_d20220409_m053123_c001_v0001102_t0047_u01649482283800") 2022/04/09 05:31:45 DEBUG : potato2: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f114cf64ccd54c4da_d20220409_m053114_c001_v0001161_t0019_u01649482274770") 2022/04/09 05:31:45 DEBUG : potato2: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62e0e0_d20220409_m053111_c001_v0001102_t0044_u01649482271827") 2022/04/09 05:31:45 DEBUG : potato2: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f10299161de63ad1f_d20220409_m052714_c001_v0001115_t0017_u01649482034793") 2022/04/09 05:31:45 DEBUG : potato2: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62cc42_d20220409_m052705_c001_v0001102_t0015_u01649482025114") 2022/04/09 05:31:45 DEBUG : potato2: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f118034971780edd5_d20220409_m052704_c001_v0001101_t0037_u01649482024225") 2022/04/09 05:31:45 DEBUG : potato2: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62cb93_d20220409_m052657_c001_v0001102_t0010_u01649482017422") 2022/04/09 05:31:45 DEBUG : potato2: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f1156169846edcb3d_d20220409_m052656_c001_v0001106_t0017_u01649482016228") 2022/04/09 05:31:45 DEBUG : potato2: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62cb2b_d20220409_m052651_c001_v0001102_t0058_u01649482011124") 2022/04/09 05:31:45 DEBUG : potato2: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f116b8a6da20ed0fd_d20220409_m052650_c001_v0001136_t0019_u01649482009617") 2022/04/09 05:31:45 DEBUG : potato2: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62cabc_d20220409_m052644_c001_v0001102_t0025_u01649482004892") 2022/04/09 05:31:45 DEBUG : potato2: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f119762f90fc3a980_d20220409_m052643_c001_v0001104_t0056_u01649482003520") 2022/04/09 05:31:45 DEBUG : potato2: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62c94f_d20220409_m052627_c001_v0001102_t0030_u01649481987958") 2022/04/09 05:31:45 DEBUG : potato2: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f117e957e41d4a8dc_d20220409_m052431_c001_v0001041_t0028_u01649481871013") 2022/04/09 05:31:45 DEBUG : potato2: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62bf76_d20220409_m052426_c001_v0001102_t0051_u01649481866318") 2022/04/09 05:31:45 DEBUG : potato2: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f10299161de63a1c1_d20220409_m052418_c001_v0001115_t0048_u01649481858493") 2022/04/09 05:31:45 DEBUG : potato2: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62be5d_d20220409_m052411_c001_v0001102_t0003_u01649481851236") 2022/04/09 05:31:46 DEBUG : potato2: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f104c6bbb0c299bc2_d20220409_m052403_c001_v0001165_t0016_u01649481843257") 2022/04/09 05:31:46 DEBUG : potato2: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62bce5_d20220409_m052354_c001_v0001102_t0030_u01649481834004") 2022/04/09 05:31:46 DEBUG : remotepotato: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f104e0dd206df7c33_d20220409_m052436_c001_v0001106_t0020_u01649481876534") 2022/04/09 05:31:46 DEBUG : remotepotato: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62bf86_d20220409_m052427_c001_v0001102_t0046_u01649481867501") 2022/04/09 05:31:46 DEBUG : remotepotato: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f10104eea97a5192f_d20220409_m052419_c001_v0001157_t0004_u01649481859581") 2022/04/09 05:31:46 DEBUG : remotepotato: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62beb3_d20220409_m052415_c001_v0001102_t0015_u01649481855528") 2022/04/09 05:31:46 DEBUG : remotepotato: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f119d7dc4b254c33a_d20220409_m052403_c001_v0001102_t0003_u01649481843595") 2022/04/09 05:31:46 DEBUG : remotepotato: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62bd31_d20220409_m052358_c001_v0001102_t0047_u01649481838028") 2022/04/09 05:31:46 DEBUG : rutabaga: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11969587517c83b1_d20220409_m052438_c001_v0001098_t0044_u01649481878669") 2022/04/09 05:31:46 DEBUG : rutabaga: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62bf3d_d20220409_m052423_c001_v0001102_t0020_u01649481863161") 2022/04/09 05:31:46 DEBUG : rutabaga: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f1155d0bcf118b35c_d20220409_m052420_c001_v0001090_t0034_u01649481860132") 2022/04/09 05:31:46 DEBUG : rutabaga: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62bdee_d20220409_m052407_c001_v0001102_t0001_u01649481847123") 2022/04/09 05:31:46 DEBUG : rutabaga: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f1190e49d0af28f63_d20220409_m052405_c001_v0001103_t0052_u01649481845742") 2022/04/09 05:31:46 DEBUG : rutabaga: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62bc99_d20220409_m052350_c001_v0001102_t0009_u01649481830657") 2022/04/09 05:31:46 DEBUG : small: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f1156169846edccb7_d20220409_m052720_c001_v0001106_t0036_u01649482040727") 2022/04/09 05:31:46 DEBUG : small: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62cd2a_d20220409_m052717_c001_v0001102_t0027_u01649482037731") 2022/04/09 05:31:46 DEBUG : sub dir/hello world: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f111f4bb28e479a49_d20220409_m052626_c001_v0001101_t0036_u01649481986307") 2022/04/09 05:31:47 DEBUG : sub dir/hello world: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62c913_d20220409_m052624_c001_v0001102_t0047_u01649481984869") 2022/04/09 05:31:47 DEBUG : sub dir/hello world: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f1156169846edc77c_d20220409_m052600_c001_v0001106_t0032_u01649481960081") 2022/04/09 05:31:47 DEBUG : sub dir/hello world: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62c5af_d20220409_m052541_c001_v0001102_t0004_u01649481941199") 2022/04/09 05:31:47 DEBUG : sub dir/hello world2: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f101b26eaef68d903_d20220409_m052602_c001_v0001154_t0005_u01649481962752") 2022/04/09 05:31:47 DEBUG : sub dir/hello world2: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62c5d8_d20220409_m052543_c001_v0001102_t0049_u01649481943653") 2022/04/09 05:31:47 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f117f35e689a994fe_d20220409_m052603_c001_v0001146_t0050_u01649481963023") 2022/04/09 05:31:47 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62c647_d20220409_m052548_c001_v0001102_t0014_u01649481948161") 2022/04/09 05:31:47 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f116f4cb3b5ef96eb_d20220409_m052603_c001_v0001102_t0055_u01649481963411") 2022/04/09 05:31:47 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62c6af_d20220409_m052552_c001_v0001102_t0025_u01649481951753") 2022/04/09 05:31:47 DEBUG : sub dir/potato3: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f116595bd6835a46a_d20220409_m053135_c001_v0001099_t0018_u01649482295597") 2022/04/09 05:31:47 DEBUG : sub dir/potato3: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f113a6867449bc89b_d20220409_m053131_c001_v0001102_t0001_u01649482291145") 2022/04/09 05:31:47 DEBUG : sub dir/potato3: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62e22b_d20220409_m053127_c001_v0001102_t0044_u01649482287895") 2022/04/09 05:31:47 DEBUG : sub dir/potato3: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f1085905a4bf8c220_d20220409_m052716_c001_v0001099_t0053_u01649482036102") 2022/04/09 05:31:47 DEBUG : sub dir/potato3: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62cc5c_d20220409_m052706_c001_v0001102_t0054_u01649482026102") 2022/04/09 05:31:47 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f1095ba14b7655fe9_d20220409_m052603_c001_v0001101_t0059_u01649481963876") 2022/04/09 05:31:47 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62c6f8_d20220409_m052555_c001_v0001102_t0044_u01649481955125") 2022/04/09 05:31:47 DEBUG : sub/file2: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f113a6867449bb867_d20220409_m052830_c001_v0001102_t0024_u01649482110478") 2022/04/09 05:31:47 DEBUG : sub/file2: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62d2ef_d20220409_m052829_c001_v0001102_t0054_u01649482109248") 2022/04/09 05:31:47 DEBUG : sub/file2: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f101aa05efd657914_d20220409_m052802_c001_v0001100_t0043_u01649482082047") 2022/04/09 05:31:47 DEBUG : sub/file2: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62d097_d20220409_m052800_c001_v0001102_t0055_u01649482080575") 2022/04/09 05:31:47 DEBUG : sub/file2: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f101cb0e521bf1c68_d20220409_m052622_c001_v0001101_t0023_u01649481982685") 2022/04/09 05:31:48 DEBUG : sub/file2: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62c85d_d20220409_m052614_c001_v0001102_t0035_u01649481974632") 2022/04/09 05:31:48 DEBUG : sub/file2: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11840b9957fb7ecb_d20220409_m052611_c001_v0001037_t0052_u01649481971812") 2022/04/09 05:31:48 DEBUG : sub/file2: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62c7f8_d20220409_m052608_c001_v0001102_t0022_u01649481968208") 2022/04/09 05:31:48 DEBUG : test.sum: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f1031fbbea6d804f5_d20220409_m052523_c001_v0001157_t0053_u01649481923041") 2022/04/09 05:31:48 DEBUG : test.sum: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62c361_d20220409_m052517_c001_v0001102_t0019_u01649481917136") 2022/04/09 05:31:48 DEBUG : test.sum: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62c289_d20220409_m052508_c001_v0001102_t0028_u01649481908329") 2022/04/09 05:31:48 DEBUG : test.sum: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62c26b_d20220409_m052506_c001_v0001102_t0006_u01649481906872") 2022/04/09 05:31:48 DEBUG : test.sum: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62c221_d20220409_m052503_c001_v0001102_t0050_u01649481903107") 2022/04/09 05:31:48 DEBUG : test.sum: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62c161_d20220409_m052453_c001_v0001102_t0036_u01649481893415") 2022/04/09 05:31:48 DEBUG : test.sum: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62c13b_d20220409_m052451_c001_v0001102_t0046_u01649481891449") 2022/04/09 05:31:48 DEBUG : test.sum: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62c119_d20220409_m052449_c001_v0001102_t0019_u01649481889468") 2022/04/09 05:31:48 DEBUG : test.sum: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f104dfd9da16eb1eb_d20220409_m052443_c001_v0001103_t0006_u01649481883644") 2022/04/09 05:31:48 DEBUG : test.sum: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62c0a1_d20220409_m052442_c001_v0001102_t0030_u01649481882708") 2022/04/09 05:31:48 DEBUG : test.sum: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62c07c_d20220409_m052441_c001_v0001102_t0009_u01649481881242") 2022/04/09 05:31:48 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f1186d1aee5cced4f_d20220409_m053056_c001_v0001103_t0030_u01649482256941") 2022/04/09 05:31:48 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62df42_d20220409_m053053_c001_v0001102_t0019_u01649482253938") 2022/04/09 05:31:48 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f114ca51d55086647_d20220409_m053057_c001_v0001157_t0004_u01649482257685") 2022/04/09 05:31:48 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62df24_d20220409_m053053_c001_v0001102_t0029_u01649482253067") 2022/04/09 05:31:48 DEBUG : zend.txt: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f114bfc8877baca14_d20220409_m052605_c001_v0001103_t0016_u01649481965142") 2022/04/09 05:31:48 DEBUG : zend.txt: Deleting (id "4_z1fb20d43c07d0bcb8f080e1b_f11474c2dad62c5a4_d20220409_m052540_c001_v0001102_t0014_u01649481940521") "./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -fast-list" - Finished OK in 8m20.228359074s (try 1/5)