"./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose" - Starting (try 1/5) 2022/04/20 05:46:31 DEBUG : Creating backend with remote "TestB2:rclone-test-dafadow4sigoniy5bimeriy9" 2022/04/20 05:46:31 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2022/04/20 05:46:32 DEBUG : Creating backend with remote "/tmp/rclone1808404942" === 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-dafadow4sigoniy5bimeriy9", Local "Local file system at /tmp/rclone1808404942", Modify Window "1ms" === RUN TestMultithreadCopy/{size:131071_streams:2} 2022/04/20 05:46:36 DEBUG : file1: Starting multi-thread copy with 2 parts of size 64Ki 2022/04/20 05:46:36 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131071) size 63.999Ki starting 2022/04/20 05:46:36 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64Ki starting 2022/04/20 05:46:36 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131071) size 63.999Ki finished 2022/04/20 05:46:36 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64Ki finished 2022/04/20 05:46:36 DEBUG : file1: Finished multi-thread copy with 2 parts of size 64Ki === RUN TestMultithreadCopy/{size:131072_streams:2} 2022/04/20 05:46:44 DEBUG : file1: Starting multi-thread copy with 2 parts of size 64Ki 2022/04/20 05:46:44 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131072) size 64Ki starting 2022/04/20 05:46:44 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64Ki starting 2022/04/20 05:46:45 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131072) size 64Ki finished 2022/04/20 05:46:45 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64Ki finished 2022/04/20 05:46:45 DEBUG : file1: Finished multi-thread copy with 2 parts of size 64Ki === RUN TestMultithreadCopy/{size:131073_streams:2} 2022/04/20 05:46:48 DEBUG : file1: Starting multi-thread copy with 2 parts of size 128Ki 2022/04/20 05:46:48 DEBUG : file1: multi-thread copy: stream 2/2 (131072-131073) size 1 starting 2022/04/20 05:46:48 DEBUG : file1: multi-thread copy: stream 1/2 (0-131072) size 128Ki starting 2022/04/20 05:46:49 DEBUG : file1: multi-thread copy: stream 2/2 (131072-131073) size 1 finished 2022/04/20 05:46:49 DEBUG : file1: multi-thread copy: stream 1/2 (0-131072) size 128Ki finished 2022/04/20 05:46:49 DEBUG : file1: Finished multi-thread copy with 2 parts of size 128Ki --- PASS: TestMultithreadCopy (18.28s) --- PASS: TestMultithreadCopy/{size:131071_streams:2} (4.40s) --- PASS: TestMultithreadCopy/{size:131072_streams:2} (8.75s) --- PASS: TestMultithreadCopy/{size:131073_streams:2} (3.87s) === 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/20 05:46:50 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/10: test error 2022/04/20 05:46:50 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/10: test error 2022/04/20 05:46:50 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/10: test error === RUN TestReOpen/Seek/TooManyErrors 2022/04/20 05:46:50 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/3: test error 2022/04/20 05:46:50 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/3: test error 2022/04/20 05:46:50 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/3: test error 2022/04/20 05:46: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/20 05:46:50 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/10: test error 2022/04/20 05:46:50 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/10: test error 2022/04/20 05:46:50 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/10: test error === RUN TestReOpen/Range/TooManyErrors 2022/04/20 05:46:50 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/3: test error 2022/04/20 05:46:50 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/3: test error 2022/04/20 05:46:50 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/3: test error 2022/04/20 05:46: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-dafadow4sigoniy5bimeriy9", Local "Local file system at /tmp/rclone1808404942", 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 (10.03s) --- PASS: TestCheck/1 (0.16s) --- PASS: TestCheck/2 (0.17s) --- PASS: TestCheck/3 (0.17s) --- PASS: TestCheck/4 (0.16s) --- PASS: TestCheck/5 (0.16s) --- PASS: TestCheck/6 (0.16s) --- PASS: TestCheck/7 (0.16s) === RUN TestCheckFsError 2022/04/20 05:47:00 DEBUG : Creating backend with remote "non-existent" 2022/04/20 05:47:00 DEBUG : Creating backend with remote "non-existent" 2022/04/20 05:47:00 DEBUG : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/non-existent: Waiting for checks to finish 2022/04/20 05:47:00 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/20 05:47:00 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/non-existent: 2 differences found 2022/04/20 05:47:00 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-dafadow4sigoniy5bimeriy9", Local "Local file system at /tmp/rclone1808404942", 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 (14.19s) --- PASS: TestCheckDownload/1 (0.85s) --- PASS: TestCheckDownload/2 (0.33s) --- PASS: TestCheckDownload/3 (0.33s) --- PASS: TestCheckDownload/4 (0.60s) --- PASS: TestCheckDownload/5 (0.34s) --- PASS: TestCheckDownload/6 (0.34s) --- PASS: TestCheckDownload/7 (0.36s) === RUN TestCheckSizeOnly run.go:181: Remote "B2 bucket rclone-test-dafadow4sigoniy5bimeriy9", Local "Local file system at /tmp/rclone1808404942", 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 (9.35s) --- PASS: TestCheckSizeOnly/1 (0.16s) --- PASS: TestCheckSizeOnly/2 (0.16s) --- PASS: TestCheckSizeOnly/3 (0.16s) --- PASS: TestCheckSizeOnly/4 (0.16s) --- PASS: TestCheckSizeOnly/5 (0.16s) --- PASS: TestCheckSizeOnly/6 (0.16s) --- PASS: TestCheckSizeOnly/7 (0.16s) === RUN TestCheckEqualReaders --- PASS: TestCheckEqualReaders (0.00s) === RUN TestParseSumFile run.go:181: Remote "B2 bucket rclone-test-dafadow4sigoniy5bimeriy9", Local "Local file system at /tmp/rclone1808404942", Modify Window "1ms" 2022/04/20 05:47:27 NOTICE: test.sum: improperly formatted checksum line 4 2022/04/20 05:47:27 NOTICE: test.sum: improperly formatted checksum line 5 2022/04/20 05:47:27 NOTICE: test.sum: improperly formatted checksum line 6 2022/04/20 05:47:27 NOTICE: test.sum: 2 warning(s) suppressed... 2022/04/20 05:47:28 NOTICE: test.sum: improperly formatted checksum line 4 2022/04/20 05:47:28 NOTICE: test.sum: improperly formatted checksum line 5 2022/04/20 05:47:28 NOTICE: test.sum: improperly formatted checksum line 6 2022/04/20 05:47:28 NOTICE: test.sum: 2 warning(s) suppressed... --- PASS: TestParseSumFile (5.95s) === RUN TestCheckSum run.go:181: Remote "B2 bucket rclone-test-dafadow4sigoniy5bimeriy9", Local "Local file system at /tmp/rclone1808404942", Modify Window "1ms" 2022/04/20 05:47:30 DEBUG : Creating backend with remote "TestB2:rclone-test-dafadow4sigoniy5bimeriy9/data" 2022/04/20 05:47:31 DEBUG : Couldn't decode error response: EOF check_test.go:356: B2 bucket rclone-test-dafadow4sigoniy5bimeriy9 path data lacks md5, skipping --- SKIP: TestCheckSum (1.21s) === RUN TestCheckSumDownload run.go:181: Remote "B2 bucket rclone-test-dafadow4sigoniy5bimeriy9", Local "Local file system at /tmp/rclone1808404942", Modify Window "1ms" 2022/04/20 05:47:31 DEBUG : Creating backend with remote "TestB2:rclone-test-dafadow4sigoniy5bimeriy9/data" 2022/04/20 05:47:31 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 (52.91s) --- PASS: TestCheckSumDownload/subtest1 (0.83s) --- PASS: TestCheckSumDownload/subtest2 (0.77s) --- PASS: TestCheckSumDownload/subtest3 (0.84s) --- PASS: TestCheckSumDownload/subtest4 (1.19s) --- PASS: TestCheckSumDownload/subtest5 (0.68s) --- PASS: TestCheckSumDownload/subtest6 (0.67s) --- PASS: TestCheckSumDownload/subtest7 (10.25s) === RUN TestDeduplicateInteractive run.go:181: Remote "B2 bucket rclone-test-dafadow4sigoniy5bimeriy9", Local "Local file system at /tmp/rclone1808404942", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateInteractive (0.32s) === RUN TestDeduplicateSkip run.go:181: Remote "B2 bucket rclone-test-dafadow4sigoniy5bimeriy9", Local "Local file system at /tmp/rclone1808404942", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSkip (0.32s) === RUN TestDeduplicateSizeOnly run.go:181: Remote "B2 bucket rclone-test-dafadow4sigoniy5bimeriy9", Local "Local file system at /tmp/rclone1808404942", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSizeOnly (0.32s) === RUN TestDeduplicateFirst run.go:181: Remote "B2 bucket rclone-test-dafadow4sigoniy5bimeriy9", Local "Local file system at /tmp/rclone1808404942", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateFirst (0.32s) === RUN TestDeduplicateNewest run.go:181: Remote "B2 bucket rclone-test-dafadow4sigoniy5bimeriy9", Local "Local file system at /tmp/rclone1808404942", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateNewest (0.33s) === RUN TestDeduplicateNewestByHash run.go:181: Remote "B2 bucket rclone-test-dafadow4sigoniy5bimeriy9", Local "Local file system at /tmp/rclone1808404942", Modify Window "1ms" 2022/04/20 05:48:31 INFO : B2 bucket rclone-test-dafadow4sigoniy5bimeriy9: Looking for duplicate sha1 hashes using newest mode. 2022/04/20 05:48:31 NOTICE: b859b3a92bb40f040b5b53bdbd83b7af2e2af2ae: Found 3 files with duplicate sha1 hashes 2022/04/20 05:48:32 INFO : one: Deleted 2022/04/20 05:48:33 INFO : also/one: Deleted 2022/04/20 05:48:33 NOTICE: b859b3a92bb40f040b5b53bdbd83b7af2e2af2ae: Deleted 2 extra copies --- PASS: TestDeduplicateNewestByHash (10.11s) === RUN TestDeduplicateOldest run.go:181: Remote "B2 bucket rclone-test-dafadow4sigoniy5bimeriy9", Local "Local file system at /tmp/rclone1808404942", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateOldest (0.32s) === RUN TestDeduplicateLargest run.go:181: Remote "B2 bucket rclone-test-dafadow4sigoniy5bimeriy9", Local "Local file system at /tmp/rclone1808404942", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateLargest (0.33s) === RUN TestDeduplicateSmallest run.go:181: Remote "B2 bucket rclone-test-dafadow4sigoniy5bimeriy9", Local "Local file system at /tmp/rclone1808404942", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSmallest (0.42s) === RUN TestDeduplicateRename run.go:181: Remote "B2 bucket rclone-test-dafadow4sigoniy5bimeriy9", Local "Local file system at /tmp/rclone1808404942", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateRename (0.32s) === RUN TestMergeDirs run.go:181: Remote "B2 bucket rclone-test-dafadow4sigoniy5bimeriy9", Local "Local file system at /tmp/rclone1808404942", Modify Window "1ms" dedupe_test.go:267: Can't merge directories --- SKIP: TestMergeDirs (0.32s) === RUN TestListDirSorted run.go:181: Remote "B2 bucket rclone-test-dafadow4sigoniy5bimeriy9", Local "Local file system at /tmp/rclone1808404942", Modify Window "1ms" 2022/04/20 05:48:46 DEBUG : a.txt: Excluded 2022/04/20 05:48:46 DEBUG : sub dir/hello world: Excluded 2022/04/20 05:48:46 DEBUG : sub dir/hello world2: Excluded 2022/04/20 05:48:46 DEBUG : sub dir/hello world: Excluded 2022/04/20 05:48:46 DEBUG : sub dir/hello world2: Excluded 2022/04/20 05:48:47 DEBUG : sub dir/ignore dir: Excluded 2022/04/20 05:48:47 DEBUG : Couldn't decode error response: EOF 2022/04/20 05:48:47 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSorted (17.26s) === RUN TestListJSON run.go:181: Remote "B2 bucket rclone-test-dafadow4sigoniy5bimeriy9", Local "Local file system at /tmp/rclone1808404942", 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/20 05:48:58 ERROR : file1: Failed to read hash: hash type not supported --- PASS: TestListJSON (4.95s) --- PASS: TestListJSON/Default (0.16s) --- PASS: TestListJSON/FilesOnly (0.16s) --- PASS: TestListJSON/DirsOnly (0.16s) --- PASS: TestListJSON/Recurse (0.16s) --- PASS: TestListJSON/SubDir (0.16s) --- PASS: TestListJSON/NoModTime (0.16s) --- PASS: TestListJSON/NoMimeType (0.16s) --- PASS: TestListJSON/ShowHash (0.17s) --- PASS: TestListJSON/HashTypes (0.16s) === RUN TestStatJSON run.go:181: Remote "B2 bucket rclone-test-dafadow4sigoniy5bimeriy9", Local "Local file system at /tmp/rclone1808404942", Modify Window "1ms" === RUN TestStatJSON/Root === RUN TestStatJSON/RootFilesOnly === RUN TestStatJSON/RootDirsOnly === RUN TestStatJSON/Dir 2022/04/20 05:49:02 DEBUG : Couldn't decode error response: EOF === RUN TestStatJSON/File === RUN TestStatJSON/NotFound 2022/04/20 05:49:02 DEBUG : Couldn't decode error response: EOF === RUN TestStatJSON/DirFilesOnly 2022/04/20 05:49:03 DEBUG : Couldn't decode error response: EOF === RUN TestStatJSON/FileFilesOnly === RUN TestStatJSON/NotFoundFilesOnly 2022/04/20 05:49:03 DEBUG : Couldn't decode error response: EOF === RUN TestStatJSON/DirDirsOnly === RUN TestStatJSON/FileDirsOnly === RUN TestStatJSON/NotFoundDirsOnly === RUN TestStatJSON/RootNotFound 2022/04/20 05:49:04 DEBUG : Creating backend with remote "TestB2:rclone-test-dafadow4sigoniy5bimeriy9/notfound" 2022/04/20 05:49:04 DEBUG : Couldn't decode error response: EOF --- PASS: TestStatJSON (6.58s) --- PASS: TestStatJSON/Root (0.16s) --- PASS: TestStatJSON/RootFilesOnly (0.00s) --- PASS: TestStatJSON/RootDirsOnly (0.17s) --- PASS: TestStatJSON/Dir (0.83s) --- PASS: TestStatJSON/File (0.18s) --- PASS: TestStatJSON/NotFound (0.33s) --- PASS: TestStatJSON/DirFilesOnly (0.16s) --- PASS: TestStatJSON/FileFilesOnly (0.17s) --- PASS: TestStatJSON/NotFoundFilesOnly (0.17s) --- PASS: TestStatJSON/DirDirsOnly (0.16s) --- PASS: TestStatJSON/FileDirsOnly (0.17s) --- PASS: TestStatJSON/NotFoundDirsOnly (0.16s) --- PASS: TestStatJSON/RootNotFound (1.23s) === RUN TestMkdir run.go:181: Remote "B2 bucket rclone-test-dafadow4sigoniy5bimeriy9", Local "Local file system at /tmp/rclone1808404942", Modify Window "1ms" 2022/04/20 05:49:06 DEBUG : B2 bucket rclone-test-dafadow4sigoniy5bimeriy9: Making directory 2022/04/20 05:49:06 DEBUG : B2 bucket rclone-test-dafadow4sigoniy5bimeriy9: Making directory --- PASS: TestMkdir (0.49s) === RUN TestLsd run.go:181: Remote "B2 bucket rclone-test-dafadow4sigoniy5bimeriy9", Local "Local file system at /tmp/rclone1808404942", Modify Window "1ms" --- PASS: TestLsd (5.47s) === RUN TestLs run.go:181: Remote "B2 bucket rclone-test-dafadow4sigoniy5bimeriy9", Local "Local file system at /tmp/rclone1808404942", Modify Window "1ms" --- PASS: TestLs (4.03s) === RUN TestLsWithFilesFrom run.go:181: Remote "B2 bucket rclone-test-dafadow4sigoniy5bimeriy9", Local "Local file system at /tmp/rclone1808404942", Modify Window "1ms" 2022/04/20 05:49:18 DEBUG : empty space: Excluded 2022/04/20 05:49:19 DEBUG : Couldn't decode error response: EOF --- PASS: TestLsWithFilesFrom (5.49s) === RUN TestLsLong run.go:181: Remote "B2 bucket rclone-test-dafadow4sigoniy5bimeriy9", Local "Local file system at /tmp/rclone1808404942", Modify Window "1ms" --- PASS: TestLsLong (4.51s) === RUN TestHashSums run.go:181: Remote "B2 bucket rclone-test-dafadow4sigoniy5bimeriy9", Local "Local file system at /tmp/rclone1808404942", Modify Window "1ms" === RUN TestHashSums/Sha1 === RUN TestHashSums/Sha1Download --- PASS: TestHashSums (13.99s) --- PASS: TestHashSums/Sha1 (0.16s) --- PASS: TestHashSums/Sha1Download (0.83s) === RUN TestHashSumsWithErrors 2022/04/20 05:49:40 DEBUG : Creating backend with remote ":memory:" 2022/04/20 05:49:40 ERROR : file1: hash unsupported: hash type not supported --- PASS: TestHashSumsWithErrors (0.00s) === RUN TestHashStream 2022/04/20 05:49:40 DEBUG : Creating md5 hash of 0 bytes read from input stream 2022/04/20 05:49:40 DEBUG : Creating md5 hash of 0 bytes read from input stream 2022/04/20 05:49:40 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2022/04/20 05:49:40 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2022/04/20 05:49:40 DEBUG : Creating md5 hash of 12 bytes read from input stream 2022/04/20 05:49:40 DEBUG : Creating md5 hash of 12 bytes read from input stream 2022/04/20 05:49:40 DEBUG : Creating sha1 hash of 12 bytes read from input stream 2022/04/20 05:49:40 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-dafadow4sigoniy5bimeriy9", Local "Local file system at /tmp/rclone1808404942", Modify Window "1ms" --- PASS: TestCount (17.40s) === RUN TestDelete run.go:181: Remote "B2 bucket rclone-test-dafadow4sigoniy5bimeriy9", Local "Local file system at /tmp/rclone1808404942", Modify Window "1ms" 2022/04/20 05:50:02 DEBUG : Waiting for deletions to finish 2022/04/20 05:50:02 DEBUG : large: Excluded from sync (and deletion) 2022/04/20 05:50:02 INFO : medium: Deleted 2022/04/20 05:50:04 INFO : small: Deleted --- PASS: TestDelete (7.93s) === RUN TestRetry 2022/04/20 05:50:05 DEBUG : Received error: EOF - low level retry 1/5 2022/04/20 05:50:05 DEBUG : Received error: EOF - low level retry 2/5 2022/04/20 05:50:05 DEBUG : Received error: EOF - low level retry 1/5 2022/04/20 05:50:05 DEBUG : Received error: EOF - low level retry 2/5 2022/04/20 05:50:05 DEBUG : Received error: EOF - low level retry 3/5 2022/04/20 05:50:05 DEBUG : Received error: EOF - low level retry 4/5 2022/04/20 05:50:05 DEBUG : Received error: EOF - low level retry 5/5 --- PASS: TestRetry (0.00s) === RUN TestCat run.go:181: Remote "B2 bucket rclone-test-dafadow4sigoniy5bimeriy9", Local "Local file system at /tmp/rclone1808404942", Modify Window "1ms" --- PASS: TestCat (10.14s) === RUN TestPurge 2022/04/20 05:50:16 DEBUG : Creating backend with remote "TestB2:rclone-test-sufomun3dogumam8bohoxiz0" 2022/04/20 05:50:16 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2022/04/20 05:50:16 DEBUG : Creating backend with remote "/tmp/rclone2761465632" run.go:181: Remote "B2 bucket rclone-test-sufomun3dogumam8bohoxiz0", Local "Local file system at /tmp/rclone2761465632", Modify Window "1ms" 2022/04/20 05:50:19 DEBUG : A2: Making directory 2022/04/20 05:50:19 DEBUG : A1/B2: Making directory 2022/04/20 05:50:19 DEBUG : A1/B2/C2: Making directory 2022/04/20 05:50:19 DEBUG : A1/B1/C3: Making directory 2022/04/20 05:50:19 DEBUG : A3: Making directory 2022/04/20 05:50:19 DEBUG : A3/B3: Making directory 2022/04/20 05:50:19 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/20 05:50:21 DEBUG : A1/B1/C1/one: Deleting (id "4_zffc27d83805d4b5b8f080e1b_f1117fc7fc896236d_d20220420_m055018_c001_v0001109_t0045_u01650433818051") 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/20 05:50:21 DEBUG : A1/two: Deleting (id "4_zffc27d83805d4b5b8f080e1b_f1117fc7fc8962391_d20220420_m055020_c001_v0001109_t0006_u01650433820035") 2022/04/20 05:50:22 DEBUG : B2 bucket rclone-test-sufomun3dogumam8bohoxiz0: Purge remote 2022/04/20 05:50:22 purge failed: directory not found --- PASS: TestPurge (6.80s) === RUN TestRmdirsNoLeaveRoot run.go:181: Remote "B2 bucket rclone-test-dafadow4sigoniy5bimeriy9", Local "Local file system at /tmp/rclone1808404942", Modify Window "1ms" 2022/04/20 05:50:23 DEBUG : A2: Making directory 2022/04/20 05:50:23 DEBUG : A1/B2: Making directory 2022/04/20 05:50:23 DEBUG : A1/B2/C2: Making directory 2022/04/20 05:50:23 DEBUG : A1/B1/C3: Making directory 2022/04/20 05:50:23 DEBUG : A3: Making directory 2022/04/20 05:50:23 DEBUG : A3/B3: Making directory 2022/04/20 05:50:23 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/20 05:50:24 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 (7.45s) === RUN TestRmdirsLeaveRoot run.go:181: Remote "B2 bucket rclone-test-dafadow4sigoniy5bimeriy9", Local "Local file system at /tmp/rclone1808404942", Modify Window "1ms" 2022/04/20 05:50:30 DEBUG : A1: Making directory 2022/04/20 05:50:30 DEBUG : A1/B1: Making directory 2022/04/20 05:50:30 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.82s) === RUN TestRmdirsWithFilter run.go:181: Remote "B2 bucket rclone-test-dafadow4sigoniy5bimeriy9", Local "Local file system at /tmp/rclone1808404942", Modify Window "1ms" 2022/04/20 05:50:31 DEBUG : A1: Making directory 2022/04/20 05:50:31 DEBUG : A1/B1: Making directory 2022/04/20 05:50:31 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.82s) === RUN TestCopyURL run.go:181: Remote "B2 bucket rclone-test-dafadow4sigoniy5bimeriy9", Local "Local file system at /tmp/rclone1808404942", Modify Window "1ms" 2022/04/20 05:50:34 DEBUG : filename.txt: File name found in url --- PASS: TestCopyURL (8.96s) === RUN TestCopyURLToWriter --- PASS: TestCopyURLToWriter (0.00s) === RUN TestMoveFile run.go:181: Remote "B2 bucket rclone-test-dafadow4sigoniy5bimeriy9", Local "Local file system at /tmp/rclone1808404942", Modify Window "1ms" 2022/04/20 05:50:41 DEBUG : Couldn't decode error response: EOF 2022/04/20 05:50:41 DEBUG : file1: Need to transfer - File not found at Destination 2022/04/20 05:50:41 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2022/04/20 05:50:41 INFO : file1: Copied (new) to: sub/file2 2022/04/20 05:50:41 INFO : file1: Deleted 2022/04/20 05:50:42 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/04/20 05:50:42 DEBUG : file1: Unchanged skipping 2022/04/20 05:50:42 INFO : file1: Deleted 2022/04/20 05:50:42 DEBUG : B2 bucket rclone-test-dafadow4sigoniy5bimeriy9: don't need to copy/move sub/file2, it is already at target location --- PASS: TestMoveFile (2.65s) === RUN TestMoveFileWithIgnoreExisting run.go:181: Remote "B2 bucket rclone-test-dafadow4sigoniy5bimeriy9", Local "Local file system at /tmp/rclone1808404942", Modify Window "1ms" 2022/04/20 05:50:43 DEBUG : Couldn't decode error response: EOF 2022/04/20 05:50:43 DEBUG : file1: Need to transfer - File not found at Destination 2022/04/20 05:50:44 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2022/04/20 05:50:44 INFO : file1: Copied (new) 2022/04/20 05:50:44 INFO : file1: Deleted 2022/04/20 05:50:44 DEBUG : file1: Destination exists, skipping 2022/04/20 05:50:44 DEBUG : file1: Not removing source file as destination file exists and --ignore-existing is set --- PASS: TestMoveFileWithIgnoreExisting (2.50s) === RUN TestCaseInsensitiveMoveFile run.go:181: Remote "B2 bucket rclone-test-dafadow4sigoniy5bimeriy9", Local "Local file system at /tmp/rclone1808404942", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFile (0.33s) === RUN TestMoveFileBackupDir run.go:181: Remote "B2 bucket rclone-test-dafadow4sigoniy5bimeriy9", Local "Local file system at /tmp/rclone1808404942", Modify Window "1ms" 2022/04/20 05:50:47 DEBUG : Creating backend with remote "TestB2:rclone-test-dafadow4sigoniy5bimeriy9/backup" 2022/04/20 05:50:49 DEBUG : Couldn't decode error response: EOF 2022/04/20 05:50:49 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18) 2022/04/20 05:50:49 DEBUG : Couldn't decode error response: EOF 2022/04/20 05:50:53 DEBUG : dst/file1: sha1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK 2022/04/20 05:50:53 INFO : dst/file1: Copied (server-side copy) 2022/04/20 05:50:54 INFO : dst/file1: Deleted 2022/04/20 05:50:55 DEBUG : dst/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2022/04/20 05:50:55 INFO : dst/file1: Copied (new) 2022/04/20 05:50:55 INFO : dst/file1: Deleted --- PASS: TestMoveFileBackupDir (11.62s) === RUN TestCopyFile run.go:181: Remote "B2 bucket rclone-test-dafadow4sigoniy5bimeriy9", Local "Local file system at /tmp/rclone1808404942", Modify Window "1ms" 2022/04/20 05:50:58 DEBUG : Couldn't decode error response: EOF 2022/04/20 05:50:58 DEBUG : file1: Need to transfer - File not found at Destination 2022/04/20 05:51:07 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2022/04/20 05:51:07 INFO : file1: Copied (new) to: sub/file2 2022/04/20 05:51:08 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/04/20 05:51:08 DEBUG : file1: Unchanged skipping 2022/04/20 05:51:09 DEBUG : B2 bucket rclone-test-dafadow4sigoniy5bimeriy9: don't need to copy/move sub/file2, it is already at target location --- PASS: TestCopyFile (12.24s) === RUN TestCopyFileBackupDir run.go:181: Remote "B2 bucket rclone-test-dafadow4sigoniy5bimeriy9", Local "Local file system at /tmp/rclone1808404942", Modify Window "1ms" 2022/04/20 05:51:13 DEBUG : Creating backend with remote "TestB2:rclone-test-dafadow4sigoniy5bimeriy9/backup" 2022/04/20 05:51:14 DEBUG : Couldn't decode error response: EOF 2022/04/20 05:51:14 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18) 2022/04/20 05:51:14 DEBUG : Couldn't decode error response: EOF 2022/04/20 05:51:19 DEBUG : dst/file1: sha1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK 2022/04/20 05:51:19 INFO : dst/file1: Copied (server-side copy) 2022/04/20 05:51:21 INFO : dst/file1: Deleted 2022/04/20 05:51:23 DEBUG : dst/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2022/04/20 05:51:23 INFO : dst/file1: Copied (new) --- PASS: TestCopyFileBackupDir (16.13s) === RUN TestCopyFileCompareDest run.go:181: Remote "B2 bucket rclone-test-dafadow4sigoniy5bimeriy9", Local "Local file system at /tmp/rclone1808404942", Modify Window "1ms" 2022/04/20 05:51:26 DEBUG : Creating backend with remote "TestB2:rclone-test-dafadow4sigoniy5bimeriy9/dst" 2022/04/20 05:51:27 DEBUG : Couldn't decode error response: EOF 2022/04/20 05:51:27 DEBUG : Couldn't decode error response: EOF 2022/04/20 05:51:27 DEBUG : Creating backend with remote "TestB2:rclone-test-dafadow4sigoniy5bimeriy9/CompareDest" 2022/04/20 05:51:28 DEBUG : Couldn't decode error response: EOF 2022/04/20 05:51:28 DEBUG : Couldn't decode error response: EOF 2022/04/20 05:51:28 DEBUG : one: Need to transfer - File not found at Destination 2022/04/20 05:51:30 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/04/20 05:51:30 INFO : one: Copied (new) 2022/04/20 05:51:31 DEBUG : Couldn't decode error response: EOF 2022/04/20 05:51:31 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/04/20 05:51:31 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2022/04/20 05:51:31 INFO : one: Copied (replaced existing) 2022/04/20 05:51:36 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/04/20 05:51:36 DEBUG : one: Destination found in --compare-dest, skipping 2022/04/20 05:51:39 DEBUG : Couldn't decode error response: EOF 2022/04/20 05:51:39 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/04/20 05:51:39 DEBUG : two: Destination found in --compare-dest, skipping 2022/04/20 05:51:40 DEBUG : Couldn't decode error response: EOF 2022/04/20 05:51:40 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/04/20 05:51:40 DEBUG : two: Destination found in --compare-dest, skipping 2022/04/20 05:51:40 DEBUG : Couldn't decode error response: EOF 2022/04/20 05:51:40 DEBUG : two: Sizes differ (src 5 vs dst 3) 2022/04/20 05:51:40 DEBUG : two: Need to transfer - File not found at Destination 2022/04/20 05:51:41 DEBUG : two: sha1 = a4be8877ad88f3109a6411985a1f359e10e2d930 OK 2022/04/20 05:51:41 INFO : two: Copied (new) --- PASS: TestCopyFileCompareDest (20.10s) === RUN TestCopyFileCopyDest run.go:181: Remote "B2 bucket rclone-test-dafadow4sigoniy5bimeriy9", Local "Local file system at /tmp/rclone1808404942", Modify Window "1ms" 2022/04/20 05:51:46 DEBUG : Creating backend with remote "TestB2:rclone-test-dafadow4sigoniy5bimeriy9/dst" 2022/04/20 05:51:47 DEBUG : Couldn't decode error response: EOF 2022/04/20 05:51:47 DEBUG : Couldn't decode error response: EOF 2022/04/20 05:51:47 DEBUG : Creating backend with remote "TestB2:rclone-test-dafadow4sigoniy5bimeriy9/CopyDest" 2022/04/20 05:51:48 DEBUG : Couldn't decode error response: EOF 2022/04/20 05:51:48 DEBUG : Couldn't decode error response: EOF 2022/04/20 05:51:48 DEBUG : one: Need to transfer - File not found at Destination 2022/04/20 05:51:51 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/04/20 05:51:51 INFO : one: Copied (new) 2022/04/20 05:51:53 DEBUG : Couldn't decode error response: EOF 2022/04/20 05:51:53 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/04/20 05:51:53 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2022/04/20 05:51:53 INFO : one: Copied (replaced existing) 2022/04/20 05:51:56 DEBUG : Creating backend with remote "TestB2:rclone-test-dafadow4sigoniy5bimeriy9/BackupDir" 2022/04/20 05:51:57 DEBUG : Couldn't decode error response: EOF 2022/04/20 05:51:57 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/04/20 05:51:57 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/04/20 05:51:57 DEBUG : Couldn't decode error response: EOF 2022/04/20 05:52:01 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/04/20 05:52:01 INFO : one: Copied (server-side copy) 2022/04/20 05:52:05 INFO : one: Deleted 2022/04/20 05:52:06 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2022/04/20 05:52:06 INFO : one: Copied (server-side copy) 2022/04/20 05:52:06 DEBUG : one: Destination found in --copy-dest, using server-side copy 2022/04/20 05:52:10 DEBUG : Couldn't decode error response: EOF 2022/04/20 05:52:10 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/04/20 05:52:10 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2022/04/20 05:52:10 INFO : two: Copied (server-side copy) 2022/04/20 05:52:10 DEBUG : two: Destination found in --copy-dest, using server-side copy 2022/04/20 05:52:11 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/04/20 05:52:11 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/04/20 05:52:11 DEBUG : two: Unchanged skipping 2022/04/20 05:52:14 DEBUG : Couldn't decode error response: EOF 2022/04/20 05:52:14 DEBUG : three: Sizes differ (src 7 vs dst 5) 2022/04/20 05:52:14 DEBUG : three: Destination not found in --copy-dest 2022/04/20 05:52:14 DEBUG : three: Need to transfer - File not found at Destination 2022/04/20 05:52:15 DEBUG : three: sha1 = 370e613335f3cf2f19b2a2d35a86402f55c659f8 OK 2022/04/20 05:52:15 INFO : three: Copied (new) --- PASS: TestCopyFileCopyDest (37.04s) === 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-dafadow4sigoniy5bimeriy9", Local "Local file system at /tmp/rclone1808404942", Modify Window "1ms" 2022/04/20 05:52:29 DEBUG : A1/B2: Making directory 2022/04/20 05:52:29 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/20 05:52:31 DEBUG : Couldn't decode error response: EOF 2022/04/20 05:52:31 DEBUG : Couldn't decode error response: EOF 2022/04/20 05:52:31 DEBUG : Couldn't decode error response: EOF 2022/04/20 05:52:31 DEBUG : Couldn't decode error response: EOF 2022/04/20 05:52:32 DEBUG : A1/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2022/04/20 05:52:32 INFO : A1/B1/C2/five: Copied (server-side copy) to: A2/B1/C2/five 2022/04/20 05:52:32 DEBUG : A1/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/04/20 05:52:32 INFO : A1/one: Copied (server-side copy) to: A2/one 2022/04/20 05:52:32 INFO : A1/B1/C2/five: Deleted 2022/04/20 05:52:32 DEBUG : Couldn't decode error response: EOF 2022/04/20 05:52:32 DEBUG : A1/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2022/04/20 05:52:32 INFO : A1/two: Copied (server-side copy) to: A2/two 2022/04/20 05:52:33 INFO : A1/one: Deleted 2022/04/20 05:52:33 DEBUG : A1/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2022/04/20 05:52:33 INFO : A1/B1/C1/four: Copied (server-side copy) to: A2/B1/C1/four 2022/04/20 05:52:34 INFO : A1/B1/C1/four: Deleted 2022/04/20 05:52:35 INFO : A1/two: Deleted 2022/04/20 05:52:36 DEBUG : A1/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2022/04/20 05:52:36 INFO : A1/B1/three: Copied (server-side copy) to: A2/B1/three 2022/04/20 05:52:38 INFO : A1/B1/three: Deleted fstest.go:251: Filtering empty directory "A2/B2" fstest.go:251: Filtering empty directory "A2/B1/C3" 2022/04/20 05:52:39 DEBUG : Couldn't decode error response: EOF 2022/04/20 05:52:39 DEBUG : Couldn't decode error response: EOF 2022/04/20 05:52:39 DEBUG : Couldn't decode error response: EOF 2022/04/20 05:52:39 DEBUG : Couldn't decode error response: EOF 2022/04/20 05:52:40 DEBUG : A2/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/04/20 05:52:40 INFO : A2/one: Copied (server-side copy) to: A3/one 2022/04/20 05:52:40 DEBUG : A2/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2022/04/20 05:52:40 INFO : A2/B1/C2/five: Copied (server-side copy) to: A3/B1/C2/five 2022/04/20 05:52:41 INFO : A2/B1/C2/five: Deleted 2022/04/20 05:52:41 DEBUG : A2/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2022/04/20 05:52:41 INFO : A2/B1/C1/four: Copied (server-side copy) to: A3/B1/C1/four 2022/04/20 05:52:41 DEBUG : Couldn't decode error response: EOF 2022/04/20 05:52:41 DEBUG : A2/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2022/04/20 05:52:41 INFO : A2/two: Copied (server-side copy) to: A3/two 2022/04/20 05:52:41 INFO : A2/one: Deleted 2022/04/20 05:52:42 INFO : A2/two: Deleted 2022/04/20 05:52:44 DEBUG : A2/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2022/04/20 05:52:44 INFO : A2/B1/three: Copied (server-side copy) to: A3/B1/three 2022/04/20 05:52:45 INFO : A2/B1/C1/four: Deleted 2022/04/20 05:52:45 INFO : A2/B1/three: Deleted fstest.go:251: Filtering empty directory "A3/B2" fstest.go:251: Filtering empty directory "A3/B1/C3" --- PASS: TestDirMove (27.32s) === RUN TestGetFsInfo run.go:181: Remote "B2 bucket rclone-test-dafadow4sigoniy5bimeriy9", Local "Local file system at /tmp/rclone1808404942", Modify Window "1ms" --- PASS: TestGetFsInfo (0.33s) === RUN TestRcat === RUN TestRcat/withChecksum=false,ignoreChecksum=false === CONT TestRcat run.go:181: Remote "B2 bucket rclone-test-dafadow4sigoniy5bimeriy9", Local "Local file system at /tmp/rclone1808404942", Modify Window "1ms" 2022/04/20 05:52:51 DEBUG : B2 bucket rclone-test-dafadow4sigoniy5bimeriy9: File to upload is small (34 bytes), uploading instead of streaming 2022/04/20 05:52:52 DEBUG : no_checksum_small_file_from_pipe: sha1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK 2022/04/20 05:52:52 INFO : no_checksum_small_file_from_pipe: Copied (new) 2022/04/20 05:52:52 DEBUG : no_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2022/04/20 05:52:53 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-dafadow4sigoniy5bimeriy9", Local "Local file system at /tmp/rclone1808404942", Modify Window "1ms" 2022/04/20 05:52:56 DEBUG : B2 bucket rclone-test-dafadow4sigoniy5bimeriy9: File to upload is small (34 bytes), uploading instead of streaming 2022/04/20 05:52:58 DEBUG : with_checksum_small_file_from_pipe: sha1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK 2022/04/20 05:52:58 INFO : with_checksum_small_file_from_pipe: Copied (new) 2022/04/20 05:52:58 DEBUG : with_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2022/04/20 05:52:59 DEBUG : with_checksum_big_file_from_pipe: sha1 = d0080f73aa8b494570ad63df1beb25c01ea9eea2 OK 2022/04/20 05:52:59 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-dafadow4sigoniy5bimeriy9", Local "Local file system at /tmp/rclone1808404942", Modify Window "1ms" 2022/04/20 05:53:02 DEBUG : B2 bucket rclone-test-dafadow4sigoniy5bimeriy9: File to upload is small (34 bytes), uploading instead of streaming 2022/04/20 05:53:06 INFO : ignore_checksum_small_file_from_pipe: Copied (new) 2022/04/20 05:53:06 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2022/04/20 05:53:07 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-dafadow4sigoniy5bimeriy9", Local "Local file system at /tmp/rclone1808404942", Modify Window "1ms" 2022/04/20 05:53:10 DEBUG : B2 bucket rclone-test-dafadow4sigoniy5bimeriy9: File to upload is small (34 bytes), uploading instead of streaming 2022/04/20 05:53:11 INFO : ignore_checksum_small_file_from_pipe: Copied (new) 2022/04/20 05:53:11 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2022/04/20 05:53:13 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical --- PASS: TestRcat (23.74s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (5.58s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (5.23s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (8.19s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (4.75s) === RUN TestRcatSize run.go:181: Remote "B2 bucket rclone-test-dafadow4sigoniy5bimeriy9", Local "Local file system at /tmp/rclone1808404942", Modify Window "1ms" 2022/04/20 05:53:19 DEBUG : B2 bucket rclone-test-dafadow4sigoniy5bimeriy9: File to upload is small (60 bytes), uploading instead of streaming 2022/04/20 05:53:23 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2022/04/20 05:53:23 INFO : potato2: Copied (new) --- PASS: TestRcatSize (11.35s) === RUN TestCopyFileMaxTransfer run.go:181: Remote "B2 bucket rclone-test-dafadow4sigoniy5bimeriy9", Local "Local file system at /tmp/rclone1808404942", Modify Window "1ms" 2022/04/20 05:53:26 DEBUG : Couldn't decode error response: EOF 2022/04/20 05:53:26 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination 2022/04/20 05:53:27 DEBUG : TestCopyFileMaxTransfer/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2022/04/20 05:53:27 INFO : TestCopyFileMaxTransfer/file1: Copied (new) 2022/04/20 05:53:27 DEBUG : Couldn't decode error response: EOF 2022/04/20 05:53:27 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination 2022/04/20 05:53:27 ERROR : TestCopyFileMaxTransfer/file2: Failed to copy: Post "https://pod-000-1102-04.backblaze.com/b2api/v1/b2_upload_file/9f020de3802d4b5b8f080e1b/c001_v0001102_t0043": Max transfer limit reached as set by --max-transfer 2022/04/20 05:53:27 DEBUG : Couldn't decode error response: EOF 2022/04/20 05:53:27 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination 2022/04/20 05:53:28 DEBUG : Couldn't decode error response: EOF 2022/04/20 05:53:28 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination 2022/04/20 05:53:29 DEBUG : TestCopyFileMaxTransfer/file4: sha1 = d1616fd1ac4d47459ab291d9c4c7aed8ed44dfb3 OK 2022/04/20 05:53:29 INFO : TestCopyFileMaxTransfer/file4: Copied (new) --- PASS: TestCopyFileMaxTransfer (6.04s) === RUN TestTouchDir run.go:181: Remote "B2 bucket rclone-test-dafadow4sigoniy5bimeriy9", Local "Local file system at /tmp/rclone1808404942", Modify Window "1ms" 2022/04/20 05:53:39 DEBUG : B2 bucket rclone-test-dafadow4sigoniy5bimeriy9: Touching "empty space" 2022/04/20 05:53:40 DEBUG : B2 bucket rclone-test-dafadow4sigoniy5bimeriy9: Touching "potato2" 2022/04/20 05:53:41 DEBUG : B2 bucket rclone-test-dafadow4sigoniy5bimeriy9: Touching "sub dir/potato3" --- PASS: TestTouchDir (14.68s) === 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/20 05:53:46 DEBUG : B2 bucket rclone-test-dafadow4sigoniy5bimeriy9: Purge remote 2022/04/20 05:53:47 DEBUG : A1/B1/C1/four: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f110b575db4366898_d20220420_m055234_c001_v0001009_t0034_u01650433954046") 2022/04/20 05:53:47 DEBUG : A1/B1/C1/four: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629e6b1_d20220420_m055226_c001_v0001102_t0056_u01650433946463") 2022/04/20 05:53:47 DEBUG : A1/B1/C1/one: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f118b9250cd966a81_d20220420_m055026_c001_v0001117_t0034_u01650433826014") 2022/04/20 05:53:47 DEBUG : A1/B1/C1/one: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629dde9_d20220420_m055023_c001_v0001102_t0013_u01650433823368") 2022/04/20 05:53:47 DEBUG : A1/B1/C2/five: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f111e8a9a0e6f80e2_d20220420_m055232_c001_v0001165_t0027_u01650433952329") 2022/04/20 05:53:47 DEBUG : A1/B1/C2/five: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629e6d9_d20220420_m055227_c001_v0001102_t0037_u01650433947772") 2022/04/20 05:53:47 DEBUG : A1/B1/three: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f102921e02b3f3aac_d20220420_m055236_c001_v0001103_t0013_u01650433956768") 2022/04/20 05:53:47 DEBUG : A1/B1/three: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629e695_d20220420_m055225_c001_v0001102_t0009_u01650433945619") 2022/04/20 05:53:47 DEBUG : A1/one: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f112d229259ac1291_d20220420_m055232_c001_v0001103_t0025_u01650433952466") 2022/04/20 05:53:47 DEBUG : A1/one: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629e67c_d20220420_m055224_c001_v0001102_t0043_u01650433944038") 2022/04/20 05:53:47 DEBUG : A1/two: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f1162fb7e6ae86bbf_d20220420_m055232_c001_v0001104_t0029_u01650433952979") 2022/04/20 05:53:48 DEBUG : A1/two: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629e688_d20220420_m055224_c001_v0001102_t0046_u01650433944736") 2022/04/20 05:53:48 DEBUG : A1/two: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f111f960de2e52099_d20220420_m055029_c001_v0001104_t0049_u01650433829172") 2022/04/20 05:53:48 DEBUG : A1/two: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629ddef_d20220420_m055023_c001_v0001102_t0012_u01650433823997") 2022/04/20 05:53:48 DEBUG : A2/B1/C1/four: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f1149b749e2876ddb_d20220420_m055241_c001_v0001109_t0019_u01650433961168") 2022/04/20 05:53:48 DEBUG : A2/B1/C1/four: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f1044c0a7cbf3909e_d20220420_m055232_c001_v0001144_t0040_u01650433952865") 2022/04/20 05:53:48 DEBUG : A2/B1/C2/five: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f107ae75be14e08f3_d20220420_m055240_c001_v0001137_t0016_u01650433960693") 2022/04/20 05:53:48 DEBUG : A2/B1/C2/five: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f103db865921f7947_d20220420_m055231_c001_v0001116_t0043_u01650433951345") 2022/04/20 05:53:48 DEBUG : A2/B1/three: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f119cff36a51cbc68_d20220420_m055244_c001_v0001097_t0046_u01650433964680") 2022/04/20 05:53:48 DEBUG : A2/B1/three: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f1145d4c1567e825f_d20220420_m055231_c001_v0001106_t0032_u01650433951843") 2022/04/20 05:53:48 DEBUG : A2/one: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f1143593d2636659a_d20220420_m055240_c001_v0001117_t0016_u01650433960224") 2022/04/20 05:53:48 DEBUG : A2/one: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f1055e289f3ce9244_d20220420_m055231_c001_v0001098_t0055_u01650433951857") 2022/04/20 05:53:48 DEBUG : A2/two: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f117ba0ab1a71f876_d20220420_m055242_c001_v0001100_t0003_u01650433962000") 2022/04/20 05:53:48 DEBUG : A2/two: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f107c1fe94c2c18bd_d20220420_m055231_c001_v0001145_t0001_u01650433951852") 2022/04/20 05:53:48 DEBUG : A3/B1/C1/four: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f107b54c4d775ddbb_d20220420_m055245_c001_v0001100_t0043_u01650433965468") 2022/04/20 05:53:48 DEBUG : A3/B1/C1/four: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f101fd2d26e0aa6cb_d20220420_m055240_c001_v0001120_t0023_u01650433960207") 2022/04/20 05:53:48 DEBUG : A3/B1/C2/five: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e7b3cdf738706_d20220420_m055246_c001_v0001103_t0025_u01650433966096") 2022/04/20 05:53:48 DEBUG : A3/B1/C2/five: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f109b4c1c202041be_d20220420_m055240_c001_v0001099_t0045_u01650433960224") 2022/04/20 05:53:48 DEBUG : A3/B1/three: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f100bd55dd64f42ca_d20220420_m055248_c001_v0001098_t0018_u01650433968090") 2022/04/20 05:53:48 DEBUG : A3/B1/three: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f105252839c47c332_d20220420_m055241_c001_v0001115_t0013_u01650433961311") 2022/04/20 05:53:49 DEBUG : A3/one: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f118d8392da7019e8_d20220420_m055248_c001_v0001106_t0055_u01650433968718") 2022/04/20 05:53:49 DEBUG : A3/one: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f1112ffb312865357_d20220420_m055239_c001_v0001137_t0009_u01650433959737") 2022/04/20 05:53:49 DEBUG : A3/two: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f10050b5a880726df_d20220420_m055249_c001_v0001144_t0042_u01650433969798") 2022/04/20 05:53:49 DEBUG : A3/two: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f117f6b9125e782ec_d20220420_m055239_c001_v0001103_t0050_u01650433959728") 2022/04/20 05:53:49 DEBUG : BackupDir/one: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f115687364acf1b8d_d20220420_m055216_c001_v0001103_t0023_u01650433936269") 2022/04/20 05:53:49 DEBUG : BackupDir/one: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f113c1d596f0a2978_d20220420_m055159_c001_v0001117_t0004_u01650433919102") 2022/04/20 05:53:49 DEBUG : CompareDest/one: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f1053be97a5d0662b_d20220420_m055142_c001_v0001165_t0015_u01650433902231") 2022/04/20 05:53:49 DEBUG : CompareDest/one: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629e322_d20220420_m055133_c001_v0001102_t0037_u01650433892841") 2022/04/20 05:53:49 DEBUG : CompareDest/two: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f1022bc128f036063_d20220420_m055142_c001_v0001013_t0037_u01650433902479") 2022/04/20 05:53:49 DEBUG : CompareDest/two: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629e34d_d20220420_m055136_c001_v0001102_t0058_u01650433896468") 2022/04/20 05:53:49 DEBUG : CopyDest/one: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f114772f639a6e01f_d20220420_m055218_c001_v0001103_t0041_u01650433938845") 2022/04/20 05:53:49 DEBUG : CopyDest/one: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629e47a_d20220420_m055155_c001_v0001102_t0010_u01650433915172") 2022/04/20 05:53:49 DEBUG : CopyDest/three: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f1159015e55e285d9_d20220420_m055219_c001_v0001097_t0035_u01650433939361") 2022/04/20 05:53:49 DEBUG : CopyDest/three: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629e59d_d20220420_m055211_c001_v0001102_t0000_u01650433931544") 2022/04/20 05:53:49 DEBUG : CopyDest/two: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f1025ee52d3076f91_d20220420_m055221_c001_v0001116_t0040_u01650433941480") 2022/04/20 05:53:49 DEBUG : CopyDest/two: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629e557_d20220420_m055207_c001_v0001102_t0047_u01650433927420") 2022/04/20 05:53:49 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f1012db2ea8f124f8_d20220420_m055329_c001_v0001103_t0017_u01650434009734") 2022/04/20 05:53:50 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629eaf6_d20220420_m055326_c001_v0001102_t0036_u01650434006953") 2022/04/20 05:53:50 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f107bab429b1f0831_d20220420_m055330_c001_v0001109_t0016_u01650434010696") 2022/04/20 05:53:50 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629eb1c_d20220420_m055328_c001_v0001102_t0047_u01650434008891") 2022/04/20 05:53:50 DEBUG : a.txt: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f1062fdd2420eeef5_d20220420_m054848_c001_v0001145_t0036_u01650433728231") 2022/04/20 05:53:50 DEBUG : a.txt: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629d679_d20220420_m054838_c001_v0001102_t0044_u01650433718386") 2022/04/20 05:53:50 DEBUG : also/one: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f1016e28ed1c83bd4_d20220420_m054832_c001_v0001145_t0045_u01650433712726") 2022/04/20 05:53:50 DEBUG : also/one: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629d588_d20220420_m054827_c001_v0001102_t0019_u01650433707379") 2022/04/20 05:53:50 DEBUG : another: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f1015fe9bb8fe26ef_d20220420_m054834_c001_v0001109_t0026_u01650433714041") 2022/04/20 05:53:50 DEBUG : another: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629d5bb_d20220420_m054829_c001_v0001102_t0005_u01650433709852") 2022/04/20 05:53:50 DEBUG : backup/dst/file1: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f10053b278dccbe53_d20220420_m055124_c001_v0001103_t0030_u01650433884254") 2022/04/20 05:53:50 DEBUG : backup/dst/file1: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f1064d6ca2c8b3b78_d20220420_m055116_c001_v0001106_t0042_u01650433876198") 2022/04/20 05:53:50 DEBUG : backup/dst/file1: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f1046cb9adddac08c_d20220420_m055055_c001_v0001098_t0001_u01650433855800") 2022/04/20 05:53:50 DEBUG : backup/dst/file1: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f113547a72a6a8096_d20220420_m055050_c001_v0001115_t0017_u01650433850778") 2022/04/20 05:53:50 DEBUG : data/banana: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f11767915697d6b34_d20220420_m054817_c001_v0001104_t0023_u01650433697653") 2022/04/20 05:53:50 DEBUG : data/banana: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629d3ca_d20220420_m054802_c001_v0001102_t0011_u01650433682441") 2022/04/20 05:53:50 DEBUG : data/banana: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629d192_d20220420_m054731_c001_v0001102_t0043_u01650433651907") 2022/04/20 05:53:50 DEBUG : data/potato: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f1193d800a8438272_d20220420_m054821_c001_v0001042_t0017_u01650433701868") 2022/04/20 05:53:50 DEBUG : data/potato: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629d3e3_d20220420_m054804_c001_v0001102_t0007_u01650433684083") 2022/04/20 05:53:50 DEBUG : data/potato: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629d1b8_d20220420_m054734_c001_v0001102_t0010_u01650433654170") 2022/04/20 05:53:51 DEBUG : dst/file1: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f117b8189427e96f5_d20220420_m055125_c001_v0001115_t0058_u01650433885231") 2022/04/20 05:53:51 DEBUG : dst/file1: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629e252_d20220420_m055122_c001_v0001102_t0004_u01650433882519") 2022/04/20 05:53:51 DEBUG : dst/file1: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f11625c2615294c39_d20220420_m055120_c001_v0001100_t0040_u01650433880084") 2022/04/20 05:53:51 DEBUG : dst/file1: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629e170_d20220420_m055110_c001_v0001102_t0019_u01650433870316") 2022/04/20 05:53:51 DEBUG : dst/file1: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f1089100878fc60c0_d20220420_m055056_c001_v0001015_t0021_u01650433856505") 2022/04/20 05:53:51 DEBUG : dst/file1: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629e03b_d20220420_m055055_c001_v0001102_t0001_u01650433855077") 2022/04/20 05:53:51 DEBUG : dst/file1: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f103bb38e92851850_d20220420_m055054_c001_v0001165_t0056_u01650433854369") 2022/04/20 05:53:51 DEBUG : dst/file1: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629dfaf_d20220420_m055046_c001_v0001102_t0003_u01650433846454") 2022/04/20 05:53:51 DEBUG : dst/one: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f1053be97a5d067d6_d20220420_m055222_c001_v0001165_t0050_u01650433942409") 2022/04/20 05:53:51 DEBUG : dst/one: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f105ff446268ae622_d20220420_m055205_c001_v0001106_t0058_u01650433925262") 2022/04/20 05:53:51 DEBUG : dst/one: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f110c866f213764d2_d20220420_m055201_c001_v0001102_t0010_u01650433921282") 2022/04/20 05:53:51 DEBUG : dst/one: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629e46c_d20220420_m055154_c001_v0001102_t0009_u01650433914654") 2022/04/20 05:53:51 DEBUG : dst/one: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f1125259f7b734914_d20220420_m055153_c001_v0001017_t0031_u01650433913341") 2022/04/20 05:53:51 DEBUG : dst/one: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f1125259f7b734912_d20220420_m055150_c001_v0001017_t0029_u01650433910484") 2022/04/20 05:53:51 DEBUG : dst/one: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f10691399c457d559_d20220420_m055143_c001_v0001100_t0059_u01650433903398") 2022/04/20 05:53:51 DEBUG : dst/one: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629e310_d20220420_m055132_c001_v0001102_t0024_u01650433892380") 2022/04/20 05:53:51 DEBUG : dst/one: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f1045f65856e1267f_d20220420_m055131_c001_v0001165_t0016_u01650433891438") 2022/04/20 05:53:51 DEBUG : dst/one: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f1045f65856e12672_d20220420_m055130_c001_v0001165_t0001_u01650433890492") 2022/04/20 05:53:51 DEBUG : dst/three: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f112f13720f9df1b4_d20220420_m055222_c001_v0001099_t0002_u01650433942616") 2022/04/20 05:53:52 DEBUG : dst/three: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f1125259f7b73491c_d20220420_m055215_c001_v0001017_t0032_u01650433935500") 2022/04/20 05:53:52 DEBUG : dst/two: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f108c432032792ddf_d20220420_m055223_c001_v0001165_t0045_u01650433943178") 2022/04/20 05:53:52 DEBUG : dst/two: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f119ad370b52187bf_d20220420_m055210_c001_v0001105_t0045_u01650433930459") 2022/04/20 05:53:52 DEBUG : dst/two: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f118249ecbd91c064_d20220420_m055145_c001_v0001102_t0008_u01650433905730") 2022/04/20 05:53:52 DEBUG : dst/two: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f1045f65856e126d5_d20220420_m055141_c001_v0001165_t0040_u01650433901572") 2022/04/20 05:53:52 DEBUG : empty space: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f116e73f53c05fea0_d20220420_m055344_c001_v0001103_t0004_u01650434024367") 2022/04/20 05:53:52 DEBUG : empty space: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f115499c035401089_d20220420_m055339_c001_v0001100_t0001_u01650434019994") 2022/04/20 05:53:52 DEBUG : empty space: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629eb63_d20220420_m055333_c001_v0001102_t0038_u01650434013037") 2022/04/20 05:53:52 DEBUG : empty space: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f105ff446268adcd6_d20220420_m054952_c001_v0001106_t0031_u01650433792248") 2022/04/20 05:53:52 DEBUG : empty space: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629db65_d20220420_m054949_c001_v0001102_t0039_u01650433789379") 2022/04/20 05:53:52 DEBUG : empty space: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f1028f5ee04e0218c_d20220420_m054934_c001_v0001104_t0042_u01650433774122") 2022/04/20 05:53:52 DEBUG : empty space: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629da40_d20220420_m054930_c001_v0001102_t0014_u01650433770498") 2022/04/20 05:53:52 DEBUG : empty space: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f11536647d57a23ce_d20220420_m054924_c001_v0001115_t0059_u01650433764341") 2022/04/20 05:53:52 DEBUG : empty space: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629d9bf_d20220420_m054923_c001_v0001102_t0002_u01650433763273") 2022/04/20 05:53:52 DEBUG : empty space: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f1111af9a701b6583_d20220420_m054919_c001_v0001115_t0047_u01650433759608") 2022/04/20 05:53:52 DEBUG : empty space: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629d959_d20220420_m054917_c001_v0001102_t0004_u01650433757901") 2022/04/20 05:53:52 DEBUG : empty space: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f1025ee52d3076b09_d20220420_m054914_c001_v0001116_t0046_u01650433754910") 2022/04/20 05:53:52 DEBUG : empty space: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629d906_d20220420_m054912_c001_v0001102_t0046_u01650433752943") 2022/04/20 05:53:52 DEBUG : empty space: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f107eb881191da540_d20220420_m054719_c001_v0001117_t0035_u01650433639407") 2022/04/20 05:53:53 DEBUG : empty space: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629d06d_d20220420_m054716_c001_v0001102_t0041_u01650433636029") 2022/04/20 05:53:53 DEBUG : empty space: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f100a70b12def4d97_d20220420_m054712_c001_v0001029_t0009_u01650433632535") 2022/04/20 05:53:53 DEBUG : empty space: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629cf86_d20220420_m054703_c001_v0001102_t0035_u01650433623448") 2022/04/20 05:53:53 DEBUG : empty space: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f1054f3c75e58f9a0_d20220420_m054657_c001_v0001105_t0012_u01650433617565") 2022/04/20 05:53:53 DEBUG : empty space: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629ceac_d20220420_m054651_c001_v0001102_t0023_u01650433611730") 2022/04/20 05:53:53 DEBUG : file1: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f116ad57f56a40d38_d20220420_m055045_c001_v0001145_t0009_u01650433845002") 2022/04/20 05:53:53 DEBUG : file1: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629df7b_d20220420_m055043_c001_v0001102_t0008_u01650433843762") 2022/04/20 05:53:53 DEBUG : file1: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f1012db2ea8f1191c_d20220420_m055038_c001_v0001103_t0037_u01650433838530") 2022/04/20 05:53:53 DEBUG : file1: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629de89_d20220420_m055032_c001_v0001102_t0026_u01650433832653") 2022/04/20 05:53:53 DEBUG : file1: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f11963e19cc122b42_d20220420_m055014_c001_v0001109_t0059_u01650433814334") 2022/04/20 05:53:53 DEBUG : file1: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629dc9e_d20220420_m055005_c001_v0001102_t0041_u01650433805978") 2022/04/20 05:53:53 DEBUG : file1: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f11291066f24c67cf_d20220420_m054905_c001_v0001102_t0041_u01650433745527") 2022/04/20 05:53:53 DEBUG : file1: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629d802_d20220420_m054900_c001_v0001102_t0047_u01650433740063") 2022/04/20 05:53:53 DEBUG : file1: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f1086c1cd4e61c81b_d20220420_m054858_c001_v0001150_t0006_u01650433738711") 2022/04/20 05:53:53 DEBUG : file1: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629d7c1_d20220420_m054855_c001_v0001102_t0034_u01650433735633") 2022/04/20 05:53:53 DEBUG : file1: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f114a6decfd260e5d_d20220420_m054649_c001_v0001116_t0001_u01650433609682") 2022/04/20 05:53:53 DEBUG : file1: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629ce67_d20220420_m054645_c001_v0001102_t0022_u01650433605699") 2022/04/20 05:53:53 DEBUG : file1: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629cdb6_d20220420_m054636_c001_v0001102_t0029_u01650433596887") 2022/04/20 05:53:53 DEBUG : file1: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629cd84_d20220420_m054634_c001_v0001102_t0051_u01650433594185") 2022/04/20 05:53:53 DEBUG : file2: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f1045f65856e12475_d20220420_m055039_c001_v0001165_t0056_u01650433839167") 2022/04/20 05:53:53 DEBUG : file2: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629dec1_d20220420_m055035_c001_v0001102_t0021_u01650433835047") 2022/04/20 05:53:54 DEBUG : file2: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f1166a31f26c94781_d20220420_m055015_c001_v0001097_t0023_u01650433815303") 2022/04/20 05:53:54 DEBUG : file2: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629dcd2_d20220420_m055008_c001_v0001102_t0035_u01650433808403") 2022/04/20 05:53:54 DEBUG : filename.txt: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f11425a120273a325_d20220420_m055039_c001_v0001115_t0046_u01650433839448") 2022/04/20 05:53:54 DEBUG : filename.txt: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629debb_d20220420_m055034_c001_v0001102_t0035_u01650433834287") 2022/04/20 05:53:54 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f1055e289f3ce942b_d20220420_m055313_c001_v0001098_t0058_u01650433993614") 2022/04/20 05:53:54 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629e9d1_d20220420_m055311_c001_v0001102_t0057_u01650433991235") 2022/04/20 05:53:54 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f11170e409c4f9dcb_d20220420_m055308_c001_v0001091_t0005_u01650433988326") 2022/04/20 05:53:54 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629e968_d20220420_m055306_c001_v0001102_t0023_u01650433986136") 2022/04/20 05:53:54 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f107f1037c218aaf3_d20220420_m055314_c001_v0001157_t0026_u01650433994308") 2022/04/20 05:53:54 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629e9bb_d20220420_m055310_c001_v0001102_t0053_u01650433990368") 2022/04/20 05:53:54 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f1151cd21b88de4dd_d20220420_m055308_c001_v0001116_t0035_u01650433988929") 2022/04/20 05:53:54 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629e938_d20220420_m055302_c001_v0001102_t0046_u01650433982183") 2022/04/20 05:53:54 DEBUG : large: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f1025ee52d3076c02_d20220420_m055004_c001_v0001116_t0015_u01650433804634") 2022/04/20 05:53:54 DEBUG : large: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629dc57_d20220420_m055001_c001_v0001102_t0046_u01650433801634") 2022/04/20 05:53:54 DEBUG : medium: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f115efaab82aea318_d20220420_m055002_c001_v0001145_t0008_u01650433802473") 2022/04/20 05:53:54 DEBUG : medium: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629dc46_d20220420_m055000_c001_v0001102_t0000_u01650433800564") 2022/04/20 05:53:54 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f1025ee52d3077053_d20220420_m055254_c001_v0001116_t0002_u01650433974178") 2022/04/20 05:53:54 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629e88e_d20220420_m055252_c001_v0001102_t0026_u01650433972384") 2022/04/20 05:53:54 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f1111af9a701b71fb_d20220420_m055255_c001_v0001115_t0033_u01650433975132") 2022/04/20 05:53:55 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629e880_d20220420_m055251_c001_v0001102_t0012_u01650433971878") 2022/04/20 05:53:55 DEBUG : not-one: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f116ed6938aaa9a79_d20220420_m054835_c001_v0001105_t0004_u01650433715325") 2022/04/20 05:53:55 DEBUG : not-one: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629d5c5_d20220420_m054830_c001_v0001102_t0012_u01650433710355") 2022/04/20 05:53:55 DEBUG : one: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f1013968d7071bccf_d20220420_m054832_c001_v0001100_t0011_u01650433712133") 2022/04/20 05:53:55 DEBUG : one: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629d577_d20220420_m054826_c001_v0001102_t0012_u01650433706529") 2022/04/20 05:53:55 DEBUG : potato1: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f10178565274cde15_d20220420_m055324_c001_v0001106_t0043_u01650434004595") 2022/04/20 05:53:55 DEBUG : potato1: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629ea21_d20220420_m055314_c001_v0001102_t0008_u01650433994949") 2022/04/20 05:53:55 DEBUG : potato2: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f1188387c5f08f14b_d20220420_m055345_c001_v0001093_t0011_u01650434025681") 2022/04/20 05:53:55 DEBUG : potato2: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f1100a2460b4108ee_d20220420_m055340_c001_v0001023_t0004_u01650434020836") 2022/04/20 05:53:55 DEBUG : potato2: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629eb4b_d20220420_m055332_c001_v0001102_t0017_u01650434012349") 2022/04/20 05:53:55 DEBUG : potato2: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f111814de646c1895_d20220420_m055325_c001_v0001146_t0049_u01650434005708") 2022/04/20 05:53:55 DEBUG : potato2: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629ea75_d20220420_m055319_c001_v0001102_t0033_u01650433999387") 2022/04/20 05:53:55 DEBUG : potato2: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f11380704a1d4889a_d20220420_m054955_c001_v0001100_t0023_u01650433795594") 2022/04/20 05:53:55 DEBUG : potato2: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629dafc_d20220420_m054941_c001_v0001102_t0012_u01650433781103") 2022/04/20 05:53:55 DEBUG : potato2: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f109281d2d10e4d6d_d20220420_m054939_c001_v0001145_t0019_u01650433779758") 2022/04/20 05:53:55 DEBUG : potato2: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629da02_d20220420_m054926_c001_v0001102_t0010_u01650433766631") 2022/04/20 05:53:55 DEBUG : potato2: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f113a0a78ceb18b76_d20220420_m054925_c001_v0001019_t0040_u01650433765828") 2022/04/20 05:53:55 DEBUG : potato2: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629d9a7_d20220420_m054922_c001_v0001102_t0009_u01650433762116") 2022/04/20 05:53:55 DEBUG : potato2: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f117b7b178c8799e9_d20220420_m054920_c001_v0001006_t0021_u01650433760938") 2022/04/20 05:53:56 DEBUG : potato2: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629d942_d20220420_m054916_c001_v0001102_t0022_u01650433756629") 2022/04/20 05:53:56 DEBUG : potato2: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f1008f4a11f896def_d20220420_m054915_c001_v0001161_t0015_u01650433755839") 2022/04/20 05:53:56 DEBUG : potato2: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629d8fc_d20220420_m054912_c001_v0001102_t0000_u01650433752602") 2022/04/20 05:53:56 DEBUG : potato2: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f11879d7309a326a7_d20220420_m054720_c001_v0001116_t0048_u01650433640526") 2022/04/20 05:53:56 DEBUG : potato2: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629d082_d20220420_m054717_c001_v0001102_t0041_u01650433637025") 2022/04/20 05:53:56 DEBUG : potato2: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f1094006673ef7ccc_d20220420_m054713_c001_v0001154_t0008_u01650433633580") 2022/04/20 05:53:56 DEBUG : potato2: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629cf92_d20220420_m054704_c001_v0001102_t0000_u01650433624599") 2022/04/20 05:53:56 DEBUG : potato2: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f112d241deeda77df_d20220420_m054658_c001_v0001097_t0055_u01650433618307") 2022/04/20 05:53:56 DEBUG : potato2: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629cee3_d20220420_m054654_c001_v0001102_t0059_u01650433614542") 2022/04/20 05:53:56 DEBUG : remotepotato: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104919713bca255d_d20220420_m054721_c001_v0001106_t0018_u01650433641485") 2022/04/20 05:53:56 DEBUG : remotepotato: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629d096_d20220420_m054718_c001_v0001102_t0013_u01650433638353") 2022/04/20 05:53:56 DEBUG : remotepotato: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f118b872ddf5ec8f3_d20220420_m054713_c001_v0001113_t0059_u01650433633774") 2022/04/20 05:53:56 DEBUG : remotepotato: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629cfc3_d20220420_m054707_c001_v0001102_t0034_u01650433627342") 2022/04/20 05:53:56 DEBUG : remotepotato: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f114d6c4fd6260375_d20220420_m054658_c001_v0001145_t0056_u01650433618714") 2022/04/20 05:53:56 DEBUG : remotepotato: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629cef9_d20220420_m054655_c001_v0001102_t0005_u01650433615961") 2022/04/20 05:53:56 DEBUG : rutabaga: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f1113a1b9ce38a0c9_d20220420_m054723_c001_v0001145_t0029_u01650433643188") 2022/04/20 05:53:56 DEBUG : rutabaga: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629d057_d20220420_m054714_c001_v0001102_t0046_u01650433634982") 2022/04/20 05:53:56 DEBUG : rutabaga: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f1103a2ffa1ff9660_d20220420_m054714_c001_v0001099_t0034_u01650433634302") 2022/04/20 05:53:57 DEBUG : rutabaga: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629cf50_d20220420_m054700_c001_v0001102_t0002_u01650433620794") 2022/04/20 05:53:57 DEBUG : rutabaga: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f115e418db7abdea6_d20220420_m054659_c001_v0001102_t0039_u01650433619365") 2022/04/20 05:53:57 DEBUG : rutabaga: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629cea0_d20220420_m054650_c001_v0001102_t0007_u01650433610763") 2022/04/20 05:53:57 DEBUG : small: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f11232052fd3c01c5_d20220420_m055003_c001_v0001115_t0031_u01650433803001") 2022/04/20 05:53:57 DEBUG : small: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629dc29_d20220420_m054958_c001_v0001102_t0059_u01650433798501") 2022/04/20 05:53:57 DEBUG : sub dir/hello world: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f1026f9ecaeff0305_d20220420_m054908_c001_v0001109_t0027_u01650433748671") 2022/04/20 05:53:57 DEBUG : sub dir/hello world: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629d89d_d20220420_m054907_c001_v0001102_t0010_u01650433747617") 2022/04/20 05:53:57 DEBUG : sub dir/hello world: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f1150f04df10caf89_d20220420_m054849_c001_v0001117_t0051_u01650433729396") 2022/04/20 05:53:57 DEBUG : sub dir/hello world: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629d6a4_d20220420_m054840_c001_v0001102_t0016_u01650433720386") 2022/04/20 05:53:57 DEBUG : sub dir/hello world2: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f1103a2ffa1ff9c53_d20220420_m054850_c001_v0001099_t0049_u01650433730696") 2022/04/20 05:53:57 DEBUG : sub dir/hello world2: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629d6c4_d20220420_m054841_c001_v0001102_t0017_u01650433721372") 2022/04/20 05:53:57 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f119d90845ee91123_d20220420_m054851_c001_v0001144_t0000_u01650433731301") 2022/04/20 05:53:57 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629d6d9_d20220420_m054842_c001_v0001102_t0022_u01650433722454") 2022/04/20 05:53:57 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f109d6210e9fdfc2c_d20220420_m054852_c001_v0001117_t0030_u01650433732385") 2022/04/20 05:53:57 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629d6e6_d20220420_m054843_c001_v0001102_t0006_u01650433723069") 2022/04/20 05:53:57 DEBUG : sub dir/potato3: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f1003358601359254_d20220420_m055346_c001_v0001146_t0002_u01650434026345") 2022/04/20 05:53:57 DEBUG : sub dir/potato3: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f1021ad9722841ce0_d20220420_m055341_c001_v0001106_t0004_u01650434021915") 2022/04/20 05:53:57 DEBUG : sub dir/potato3: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629eba6_d20220420_m055336_c001_v0001102_t0058_u01650434016468") 2022/04/20 05:53:57 DEBUG : sub dir/potato3: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f1018f34f143eb1c0_d20220420_m054956_c001_v0001117_t0031_u01650433796815") 2022/04/20 05:53:57 DEBUG : sub dir/potato3: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629db8e_d20220420_m054950_c001_v0001102_t0031_u01650433790746") 2022/04/20 05:53:58 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f106264c22bcdf8c4_d20220420_m054853_c001_v0001099_t0054_u01650433733809") 2022/04/20 05:53:58 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629d6f7_d20220420_m054843_c001_v0001102_t0047_u01650433723691") 2022/04/20 05:53:58 DEBUG : sub/file2: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f1012e18ce8b24c10_d20220420_m055109_c001_v0001161_t0033_u01650433869492") 2022/04/20 05:53:58 DEBUG : sub/file2: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629e083_d20220420_m055058_c001_v0001102_t0023_u01650433858693") 2022/04/20 05:53:58 DEBUG : sub/file2: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f115499c035400684_d20220420_m055042_c001_v0001100_t0006_u01650433842864") 2022/04/20 05:53:58 DEBUG : sub/file2: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629df47_d20220420_m055041_c001_v0001102_t0022_u01650433841589") 2022/04/20 05:53:58 DEBUG : sub/file2: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f114460335655acdc_d20220420_m054905_c001_v0001099_t0038_u01650433745888") 2022/04/20 05:53:58 DEBUG : sub/file2: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629d810_d20220420_m054900_c001_v0001102_t0003_u01650433740767") 2022/04/20 05:53:58 DEBUG : sub/file2: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f110861afbf071502_d20220420_m054859_c001_v0001120_t0032_u01650433739248") 2022/04/20 05:53:58 DEBUG : sub/file2: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629d7cd_d20220420_m054856_c001_v0001102_t0035_u01650433736165") 2022/04/20 05:53:58 DEBUG : test.sum: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f11626d9f0f1d4aad_d20220420_m054822_c001_v0001115_t0007_u01650433702904") 2022/04/20 05:53:58 DEBUG : test.sum: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629d3f9_d20220420_m054804_c001_v0001102_t0028_u01650433684855") 2022/04/20 05:53:58 DEBUG : test.sum: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629d3b4_d20220420_m054801_c001_v0001102_t0032_u01650433681065") 2022/04/20 05:53:58 DEBUG : test.sum: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629d389_d20220420_m054759_c001_v0001102_t0025_u01650433679455") 2022/04/20 05:53:58 DEBUG : test.sum: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629d256_d20220420_m054741_c001_v0001102_t0011_u01650433661020") 2022/04/20 05:53:58 DEBUG : test.sum: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629d21b_d20220420_m054738_c001_v0001102_t0003_u01650433658220") 2022/04/20 05:53:58 DEBUG : test.sum: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629d1f2_d20220420_m054736_c001_v0001102_t0000_u01650433656487") 2022/04/20 05:53:59 DEBUG : test.sum: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629d19b_d20220420_m054732_c001_v0001102_t0005_u01650433652374") 2022/04/20 05:53:59 DEBUG : test.sum: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f107b061889663d61_d20220420_m054729_c001_v0001102_t0012_u01650433649213") 2022/04/20 05:53:59 DEBUG : test.sum: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629d140_d20220420_m054727_c001_v0001102_t0016_u01650433647170") 2022/04/20 05:53:59 DEBUG : test.sum: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629d11e_d20220420_m054724_c001_v0001102_t0031_u01650433644830") 2022/04/20 05:53:59 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f11159e7e3bf15599_d20220420_m055300_c001_v0001103_t0000_u01650433980064") 2022/04/20 05:53:59 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629e907_d20220420_m055258_c001_v0001102_t0022_u01650433978624") 2022/04/20 05:53:59 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f1044c0a7cbf3917b_d20220420_m055301_c001_v0001144_t0038_u01650433981053") 2022/04/20 05:53:59 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629e8e8_d20220420_m055256_c001_v0001102_t0023_u01650433976961") 2022/04/20 05:53:59 DEBUG : zend.txt: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f105e1e0a26f3dfa9_d20220420_m054854_c001_v0001019_t0044_u01650433734355") 2022/04/20 05:53:59 DEBUG : zend.txt: Deleting (id "4_z9f020de3802d4b5b8f080e1b_f104e2a748629d695_d20220420_m054839_c001_v0001102_t0005_u01650433719682") "./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose" - Finished OK in 7m29.196176177s (try 1/5)