"./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -fast-list" - Starting (try 1/5) 2022/04/15 05:02:24 DEBUG : Creating backend with remote "TestB2:rclone-test-riqasom4qeyobab4tomomig7" 2022/04/15 05:02:24 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2022/04/15 05:02:25 DEBUG : Creating backend with remote "/tmp/rclone2735327350" === 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-riqasom4qeyobab4tomomig7", Local "Local file system at /tmp/rclone2735327350", Modify Window "1ms" === RUN TestMultithreadCopy/{size:131071_streams:2} 2022/04/15 05:02:30 DEBUG : file1: Starting multi-thread copy with 2 parts of size 64Ki 2022/04/15 05:02:30 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131071) size 63.999Ki starting 2022/04/15 05:02:30 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64Ki starting 2022/04/15 05:02:30 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131071) size 63.999Ki finished 2022/04/15 05:02:30 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64Ki finished 2022/04/15 05:02:30 DEBUG : file1: Finished multi-thread copy with 2 parts of size 64Ki === RUN TestMultithreadCopy/{size:131072_streams:2} 2022/04/15 05:02:33 DEBUG : file1: Starting multi-thread copy with 2 parts of size 64Ki 2022/04/15 05:02:33 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131072) size 64Ki starting 2022/04/15 05:02:33 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64Ki starting 2022/04/15 05:02:33 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131072) size 64Ki finished 2022/04/15 05:02:33 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64Ki finished 2022/04/15 05:02:33 DEBUG : file1: Finished multi-thread copy with 2 parts of size 64Ki === RUN TestMultithreadCopy/{size:131073_streams:2} 2022/04/15 05:02:35 DEBUG : file1: Starting multi-thread copy with 2 parts of size 128Ki 2022/04/15 05:02:35 DEBUG : file1: multi-thread copy: stream 2/2 (131072-131073) size 1 starting 2022/04/15 05:02:35 DEBUG : file1: multi-thread copy: stream 1/2 (0-131072) size 128Ki starting 2022/04/15 05:02:35 DEBUG : file1: multi-thread copy: stream 2/2 (131072-131073) size 1 finished 2022/04/15 05:02:35 DEBUG : file1: multi-thread copy: stream 1/2 (0-131072) size 128Ki finished 2022/04/15 05:02:35 DEBUG : file1: Finished multi-thread copy with 2 parts of size 128Ki --- PASS: TestMultithreadCopy (10.71s) --- PASS: TestMultithreadCopy/{size:131071_streams:2} (5.22s) --- PASS: TestMultithreadCopy/{size:131072_streams:2} (2.91s) --- PASS: TestMultithreadCopy/{size:131073_streams:2} (1.76s) === 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/15 05:02:36 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/10: test error 2022/04/15 05:02:36 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/10: test error 2022/04/15 05:02:36 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/10: test error === RUN TestReOpen/Seek/TooManyErrors 2022/04/15 05:02:36 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/3: test error 2022/04/15 05:02:36 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/3: test error 2022/04/15 05:02:36 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/3: test error 2022/04/15 05:02:36 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/15 05:02:36 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/10: test error 2022/04/15 05:02:36 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/10: test error 2022/04/15 05:02:36 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/10: test error === RUN TestReOpen/Range/TooManyErrors 2022/04/15 05:02:36 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/3: test error 2022/04/15 05:02:36 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/3: test error 2022/04/15 05:02:36 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/3: test error 2022/04/15 05:02:36 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-riqasom4qeyobab4tomomig7", Local "Local file system at /tmp/rclone2735327350", 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 (11.19s) --- PASS: TestCheck/1 (0.18s) --- PASS: TestCheck/2 (0.17s) --- PASS: TestCheck/3 (0.17s) --- PASS: TestCheck/4 (0.18s) --- PASS: TestCheck/5 (0.18s) --- PASS: TestCheck/6 (0.17s) --- PASS: TestCheck/7 (0.18s) === RUN TestCheckFsError 2022/04/15 05:02:47 DEBUG : Creating backend with remote "non-existent" 2022/04/15 05:02:47 DEBUG : Creating backend with remote "non-existent" 2022/04/15 05:02:47 DEBUG : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/non-existent: Waiting for checks to finish 2022/04/15 05:02:47 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/15 05:02:47 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/non-existent: 2 differences found 2022/04/15 05:02:47 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-riqasom4qeyobab4tomomig7", Local "Local file system at /tmp/rclone2735327350", 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 (17.89s) --- PASS: TestCheckDownload/1 (0.91s) --- PASS: TestCheckDownload/2 (0.40s) --- PASS: TestCheckDownload/3 (0.37s) --- PASS: TestCheckDownload/4 (0.55s) --- PASS: TestCheckDownload/5 (0.37s) --- PASS: TestCheckDownload/6 (0.37s) --- PASS: TestCheckDownload/7 (0.41s) === RUN TestCheckSizeOnly run.go:181: Remote "B2 bucket rclone-test-riqasom4qeyobab4tomomig7", Local "Local file system at /tmp/rclone2735327350", 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 (14.26s) --- PASS: TestCheckSizeOnly/1 (0.17s) --- PASS: TestCheckSizeOnly/2 (0.20s) --- 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-riqasom4qeyobab4tomomig7", Local "Local file system at /tmp/rclone2735327350", Modify Window "1ms" 2022/04/15 05:03:23 NOTICE: test.sum: improperly formatted checksum line 4 2022/04/15 05:03:23 NOTICE: test.sum: improperly formatted checksum line 5 2022/04/15 05:03:23 NOTICE: test.sum: improperly formatted checksum line 6 2022/04/15 05:03:23 NOTICE: test.sum: 2 warning(s) suppressed... 2022/04/15 05:03:26 NOTICE: test.sum: improperly formatted checksum line 4 2022/04/15 05:03:26 NOTICE: test.sum: improperly formatted checksum line 5 2022/04/15 05:03:26 NOTICE: test.sum: improperly formatted checksum line 6 2022/04/15 05:03:26 NOTICE: test.sum: 2 warning(s) suppressed... --- PASS: TestParseSumFile (7.95s) === RUN TestCheckSum run.go:181: Remote "B2 bucket rclone-test-riqasom4qeyobab4tomomig7", Local "Local file system at /tmp/rclone2735327350", Modify Window "1ms" 2022/04/15 05:03:27 DEBUG : Creating backend with remote "TestB2:rclone-test-riqasom4qeyobab4tomomig7/data" 2022/04/15 05:03:28 DEBUG : Couldn't decode error response: EOF check_test.go:356: B2 bucket rclone-test-riqasom4qeyobab4tomomig7 path data lacks md5, skipping --- SKIP: TestCheckSum (1.31s) === RUN TestCheckSumDownload run.go:181: Remote "B2 bucket rclone-test-riqasom4qeyobab4tomomig7", Local "Local file system at /tmp/rclone2735327350", Modify Window "1ms" 2022/04/15 05:03:28 DEBUG : Creating backend with remote "TestB2:rclone-test-riqasom4qeyobab4tomomig7/data" 2022/04/15 05:03:29 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 (28.42s) --- PASS: TestCheckSumDownload/subtest1 (0.98s) --- PASS: TestCheckSumDownload/subtest2 (0.77s) --- PASS: TestCheckSumDownload/subtest3 (0.98s) --- PASS: TestCheckSumDownload/subtest4 (0.72s) --- PASS: TestCheckSumDownload/subtest5 (0.76s) --- PASS: TestCheckSumDownload/subtest6 (0.89s) --- PASS: TestCheckSumDownload/subtest7 (0.75s) === RUN TestDeduplicateInteractive run.go:181: Remote "B2 bucket rclone-test-riqasom4qeyobab4tomomig7", Local "Local file system at /tmp/rclone2735327350", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateInteractive (0.35s) === RUN TestDeduplicateSkip run.go:181: Remote "B2 bucket rclone-test-riqasom4qeyobab4tomomig7", Local "Local file system at /tmp/rclone2735327350", 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-riqasom4qeyobab4tomomig7", Local "Local file system at /tmp/rclone2735327350", 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-riqasom4qeyobab4tomomig7", Local "Local file system at /tmp/rclone2735327350", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateFirst (0.35s) === RUN TestDeduplicateNewest run.go:181: Remote "B2 bucket rclone-test-riqasom4qeyobab4tomomig7", Local "Local file system at /tmp/rclone2735327350", 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-riqasom4qeyobab4tomomig7", Local "Local file system at /tmp/rclone2735327350", Modify Window "1ms" 2022/04/15 05:04:15 INFO : B2 bucket rclone-test-riqasom4qeyobab4tomomig7: Looking for duplicate sha1 hashes using newest mode. 2022/04/15 05:04:16 NOTICE: 76b038e5e21cf915904c46453880ca90a9619791: Found 3 files with duplicate sha1 hashes 2022/04/15 05:04:18 INFO : one: Deleted 2022/04/15 05:04:19 INFO : also/one: Deleted 2022/04/15 05:04:19 NOTICE: 76b038e5e21cf915904c46453880ca90a9619791: Deleted 2 extra copies --- PASS: TestDeduplicateNewestByHash (22.91s) === RUN TestDeduplicateOldest run.go:181: Remote "B2 bucket rclone-test-riqasom4qeyobab4tomomig7", Local "Local file system at /tmp/rclone2735327350", 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-riqasom4qeyobab4tomomig7", Local "Local file system at /tmp/rclone2735327350", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateLargest (0.37s) === RUN TestDeduplicateSmallest run.go:181: Remote "B2 bucket rclone-test-riqasom4qeyobab4tomomig7", Local "Local file system at /tmp/rclone2735327350", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSmallest (0.36s) === RUN TestDeduplicateRename run.go:181: Remote "B2 bucket rclone-test-riqasom4qeyobab4tomomig7", Local "Local file system at /tmp/rclone2735327350", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateRename (0.38s) === RUN TestMergeDirs run.go:181: Remote "B2 bucket rclone-test-riqasom4qeyobab4tomomig7", Local "Local file system at /tmp/rclone2735327350", 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-riqasom4qeyobab4tomomig7", Local "Local file system at /tmp/rclone2735327350", Modify Window "1ms" 2022/04/15 05:04:39 DEBUG : a.txt: Excluded 2022/04/15 05:04:39 DEBUG : sub dir/hello world: Excluded 2022/04/15 05:04:39 DEBUG : sub dir/hello world2: Excluded 2022/04/15 05:04:39 DEBUG : sub dir/hello world: Excluded 2022/04/15 05:04:39 DEBUG : sub dir/hello world2: Excluded 2022/04/15 05:04:40 DEBUG : sub dir/ignore dir: Excluded 2022/04/15 05:04:40 DEBUG : Couldn't decode error response: EOF 2022/04/15 05:04:40 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSorted (28.22s) === RUN TestListJSON run.go:181: Remote "B2 bucket rclone-test-riqasom4qeyobab4tomomig7", Local "Local file system at /tmp/rclone2735327350", 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/15 05:04:57 ERROR : file1: Failed to read hash: hash type not supported --- PASS: TestListJSON (8.31s) --- PASS: TestListJSON/Default (0.17s) --- PASS: TestListJSON/FilesOnly (0.18s) --- PASS: TestListJSON/DirsOnly (0.17s) --- PASS: TestListJSON/Recurse (0.17s) --- PASS: TestListJSON/SubDir (0.17s) --- PASS: TestListJSON/NoModTime (0.17s) --- PASS: TestListJSON/NoMimeType (0.17s) --- PASS: TestListJSON/ShowHash (0.17s) --- PASS: TestListJSON/HashTypes (0.17s) === RUN TestStatJSON run.go:181: Remote "B2 bucket rclone-test-riqasom4qeyobab4tomomig7", Local "Local file system at /tmp/rclone2735327350", Modify Window "1ms" === RUN TestStatJSON/Root === RUN TestStatJSON/RootFilesOnly === RUN TestStatJSON/RootDirsOnly === RUN TestStatJSON/Dir 2022/04/15 05:05:04 DEBUG : Couldn't decode error response: EOF === RUN TestStatJSON/File === RUN TestStatJSON/NotFound 2022/04/15 05:05:04 DEBUG : Couldn't decode error response: EOF === RUN TestStatJSON/DirFilesOnly 2022/04/15 05:05:04 DEBUG : Couldn't decode error response: EOF === RUN TestStatJSON/FileFilesOnly === RUN TestStatJSON/NotFoundFilesOnly 2022/04/15 05:05:05 DEBUG : Couldn't decode error response: EOF === RUN TestStatJSON/DirDirsOnly === RUN TestStatJSON/FileDirsOnly === RUN TestStatJSON/NotFoundDirsOnly === RUN TestStatJSON/RootNotFound 2022/04/15 05:05:05 DEBUG : Creating backend with remote "TestB2:rclone-test-riqasom4qeyobab4tomomig7/notfound" 2022/04/15 05:05:06 DEBUG : Couldn't decode error response: EOF --- PASS: TestStatJSON (8.68s) --- PASS: TestStatJSON/Root (0.24s) --- PASS: TestStatJSON/RootFilesOnly (0.00s) --- PASS: TestStatJSON/RootDirsOnly (0.17s) --- PASS: TestStatJSON/Dir (0.95s) --- PASS: TestStatJSON/File (0.17s) --- PASS: TestStatJSON/NotFound (0.35s) --- PASS: TestStatJSON/DirFilesOnly (0.21s) --- PASS: TestStatJSON/FileFilesOnly (0.18s) --- PASS: TestStatJSON/NotFoundFilesOnly (0.20s) --- PASS: TestStatJSON/DirDirsOnly (0.17s) --- PASS: TestStatJSON/FileDirsOnly (0.17s) --- PASS: TestStatJSON/NotFoundDirsOnly (0.18s) --- PASS: TestStatJSON/RootNotFound (1.37s) === RUN TestMkdir run.go:181: Remote "B2 bucket rclone-test-riqasom4qeyobab4tomomig7", Local "Local file system at /tmp/rclone2735327350", Modify Window "1ms" 2022/04/15 05:05:08 DEBUG : B2 bucket rclone-test-riqasom4qeyobab4tomomig7: Making directory 2022/04/15 05:05:09 DEBUG : B2 bucket rclone-test-riqasom4qeyobab4tomomig7: Making directory --- PASS: TestMkdir (0.54s) === RUN TestLsd run.go:181: Remote "B2 bucket rclone-test-riqasom4qeyobab4tomomig7", Local "Local file system at /tmp/rclone2735327350", Modify Window "1ms" --- PASS: TestLsd (9.29s) === RUN TestLs run.go:181: Remote "B2 bucket rclone-test-riqasom4qeyobab4tomomig7", Local "Local file system at /tmp/rclone2735327350", Modify Window "1ms" --- PASS: TestLs (7.09s) === RUN TestLsWithFilesFrom run.go:181: Remote "B2 bucket rclone-test-riqasom4qeyobab4tomomig7", Local "Local file system at /tmp/rclone2735327350", Modify Window "1ms" 2022/04/15 05:05:28 DEBUG : empty space: Excluded from sync (and deletion) 2022/04/15 05:05:29 DEBUG : Couldn't decode error response: EOF --- PASS: TestLsWithFilesFrom (5.48s) === RUN TestLsLong run.go:181: Remote "B2 bucket rclone-test-riqasom4qeyobab4tomomig7", Local "Local file system at /tmp/rclone2735327350", Modify Window "1ms" --- PASS: TestLsLong (6.89s) === RUN TestHashSums run.go:181: Remote "B2 bucket rclone-test-riqasom4qeyobab4tomomig7", Local "Local file system at /tmp/rclone2735327350", Modify Window "1ms" === RUN TestHashSums/Sha1 === RUN TestHashSums/Sha1Download --- PASS: TestHashSums (6.32s) --- PASS: TestHashSums/Sha1 (0.17s) --- PASS: TestHashSums/Sha1Download (0.87s) === RUN TestHashSumsWithErrors 2022/04/15 05:05:44 DEBUG : Creating backend with remote ":memory:" 2022/04/15 05:05:44 ERROR : file1: hash unsupported: hash type not supported --- PASS: TestHashSumsWithErrors (0.00s) === RUN TestHashStream 2022/04/15 05:05:44 DEBUG : Creating md5 hash of 0 bytes read from input stream 2022/04/15 05:05:44 DEBUG : Creating md5 hash of 0 bytes read from input stream 2022/04/15 05:05:44 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2022/04/15 05:05:44 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2022/04/15 05:05:44 DEBUG : Creating md5 hash of 12 bytes read from input stream 2022/04/15 05:05:44 DEBUG : Creating md5 hash of 12 bytes read from input stream 2022/04/15 05:05:44 DEBUG : Creating sha1 hash of 12 bytes read from input stream 2022/04/15 05:05:44 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-riqasom4qeyobab4tomomig7", Local "Local file system at /tmp/rclone2735327350", Modify Window "1ms" --- PASS: TestCount (13.99s) === RUN TestDelete run.go:181: Remote "B2 bucket rclone-test-riqasom4qeyobab4tomomig7", Local "Local file system at /tmp/rclone2735327350", Modify Window "1ms" 2022/04/15 05:06:06 DEBUG : Waiting for deletions to finish 2022/04/15 05:06:06 DEBUG : large: Excluded from sync (and deletion) 2022/04/15 05:06:07 INFO : medium: Deleted 2022/04/15 05:06:08 INFO : small: Deleted --- PASS: TestDelete (11.39s) === RUN TestRetry 2022/04/15 05:06:09 DEBUG : Received error: EOF - low level retry 1/5 2022/04/15 05:06:09 DEBUG : Received error: EOF - low level retry 2/5 2022/04/15 05:06:09 DEBUG : Received error: EOF - low level retry 1/5 2022/04/15 05:06:09 DEBUG : Received error: EOF - low level retry 2/5 2022/04/15 05:06:09 DEBUG : Received error: EOF - low level retry 3/5 2022/04/15 05:06:09 DEBUG : Received error: EOF - low level retry 4/5 2022/04/15 05:06:09 DEBUG : Received error: EOF - low level retry 5/5 --- PASS: TestRetry (0.00s) === RUN TestCat run.go:181: Remote "B2 bucket rclone-test-riqasom4qeyobab4tomomig7", Local "Local file system at /tmp/rclone2735327350", Modify Window "1ms" --- PASS: TestCat (8.60s) === RUN TestPurge 2022/04/15 05:06:18 DEBUG : Creating backend with remote "TestB2:rclone-test-fuxivat8kiyujoc7filucej2" 2022/04/15 05:06:18 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2022/04/15 05:06:19 DEBUG : Creating backend with remote "/tmp/rclone1043996390" run.go:181: Remote "B2 bucket rclone-test-fuxivat8kiyujoc7filucej2", Local "Local file system at /tmp/rclone1043996390", Modify Window "1ms" 2022/04/15 05:06:21 DEBUG : A2: Making directory 2022/04/15 05:06:21 DEBUG : A1/B2: Making directory 2022/04/15 05:06:21 DEBUG : A1/B2/C2: Making directory 2022/04/15 05:06:21 DEBUG : A1/B1/C3: Making directory 2022/04/15 05:06:21 DEBUG : A3: Making directory 2022/04/15 05:06:21 DEBUG : A3/B3: Making directory 2022/04/15 05:06:21 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/15 05:06:22 DEBUG : A1/B1/C1/one: Deleting (id "4_zbf126de390dd2bbb8f080e1b_f11899c1cde4ee268_d20220415_m050620_c001_v0001101_t0027_u01649999180632") 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/15 05:06:23 DEBUG : A1/two: Deleting (id "4_zbf126de390dd2bbb8f080e1b_f11899c1cde4ee26e_d20220415_m050621_c001_v0001101_t0034_u01649999181495") 2022/04/15 05:06:23 DEBUG : B2 bucket rclone-test-fuxivat8kiyujoc7filucej2: Purge remote 2022/04/15 05:06:24 purge failed: directory not found --- PASS: TestPurge (5.66s) === RUN TestRmdirsNoLeaveRoot run.go:181: Remote "B2 bucket rclone-test-riqasom4qeyobab4tomomig7", Local "Local file system at /tmp/rclone2735327350", Modify Window "1ms" 2022/04/15 05:06:25 DEBUG : A2: Making directory 2022/04/15 05:06:25 DEBUG : A1/B2: Making directory 2022/04/15 05:06:25 DEBUG : A1/B2/C2: Making directory 2022/04/15 05:06:25 DEBUG : A1/B1/C3: Making directory 2022/04/15 05:06:25 DEBUG : A3: Making directory 2022/04/15 05:06:25 DEBUG : A3/B3: Making directory 2022/04/15 05:06:25 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/15 05:06:26 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 (9.98s) === RUN TestRmdirsLeaveRoot run.go:181: Remote "B2 bucket rclone-test-riqasom4qeyobab4tomomig7", Local "Local file system at /tmp/rclone2735327350", Modify Window "1ms" 2022/04/15 05:06:34 DEBUG : A1: Making directory 2022/04/15 05:06:34 DEBUG : A1/B1: Making directory 2022/04/15 05:06:34 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.87s) === RUN TestRmdirsWithFilter run.go:181: Remote "B2 bucket rclone-test-riqasom4qeyobab4tomomig7", Local "Local file system at /tmp/rclone2735327350", Modify Window "1ms" 2022/04/15 05:06:35 DEBUG : A1: Making directory 2022/04/15 05:06:35 DEBUG : A1/B1: Making directory 2022/04/15 05:06:35 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-riqasom4qeyobab4tomomig7", Local "Local file system at /tmp/rclone2735327350", Modify Window "1ms" 2022/04/15 05:06:39 DEBUG : filename.txt: File name found in url --- PASS: TestCopyURL (8.28s) === RUN TestCopyURLToWriter --- PASS: TestCopyURLToWriter (0.00s) === RUN TestMoveFile run.go:181: Remote "B2 bucket rclone-test-riqasom4qeyobab4tomomig7", Local "Local file system at /tmp/rclone2735327350", Modify Window "1ms" 2022/04/15 05:06:44 DEBUG : Couldn't decode error response: EOF 2022/04/15 05:06:44 DEBUG : file1: Need to transfer - File not found at Destination 2022/04/15 05:06:45 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2022/04/15 05:06:45 INFO : file1: Copied (new) to: sub/file2 2022/04/15 05:06:45 INFO : file1: Deleted 2022/04/15 05:06:45 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/04/15 05:06:45 DEBUG : file1: Unchanged skipping 2022/04/15 05:06:45 INFO : file1: Deleted 2022/04/15 05:06:45 DEBUG : B2 bucket rclone-test-riqasom4qeyobab4tomomig7: don't need to copy/move sub/file2, it is already at target location --- PASS: TestMoveFile (3.94s) === RUN TestMoveFileWithIgnoreExisting run.go:181: Remote "B2 bucket rclone-test-riqasom4qeyobab4tomomig7", Local "Local file system at /tmp/rclone2735327350", Modify Window "1ms" 2022/04/15 05:06:48 DEBUG : Couldn't decode error response: EOF 2022/04/15 05:06:48 DEBUG : file1: Need to transfer - File not found at Destination 2022/04/15 05:06:49 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2022/04/15 05:06:49 INFO : file1: Copied (new) 2022/04/15 05:06:49 INFO : file1: Deleted 2022/04/15 05:06:49 DEBUG : file1: Destination exists, skipping 2022/04/15 05:06:49 DEBUG : file1: Not removing source file as destination file exists and --ignore-existing is set --- PASS: TestMoveFileWithIgnoreExisting (3.21s) === RUN TestCaseInsensitiveMoveFile run.go:181: Remote "B2 bucket rclone-test-riqasom4qeyobab4tomomig7", Local "Local file system at /tmp/rclone2735327350", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFile (0.34s) === RUN TestMoveFileBackupDir run.go:181: Remote "B2 bucket rclone-test-riqasom4qeyobab4tomomig7", Local "Local file system at /tmp/rclone2735327350", Modify Window "1ms" 2022/04/15 05:06:52 DEBUG : Creating backend with remote "TestB2:rclone-test-riqasom4qeyobab4tomomig7/backup" 2022/04/15 05:06:54 DEBUG : Couldn't decode error response: EOF 2022/04/15 05:06:54 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18) 2022/04/15 05:06:54 DEBUG : Couldn't decode error response: EOF 2022/04/15 05:06:56 DEBUG : dst/file1: sha1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK 2022/04/15 05:06:56 INFO : dst/file1: Copied (server-side copy) 2022/04/15 05:06:59 INFO : dst/file1: Deleted 2022/04/15 05:07:02 DEBUG : dst/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2022/04/15 05:07:02 INFO : dst/file1: Copied (new) 2022/04/15 05:07:02 INFO : dst/file1: Deleted --- PASS: TestMoveFileBackupDir (14.10s) === RUN TestCopyFile run.go:181: Remote "B2 bucket rclone-test-riqasom4qeyobab4tomomig7", Local "Local file system at /tmp/rclone2735327350", Modify Window "1ms" 2022/04/15 05:07:06 DEBUG : Couldn't decode error response: EOF 2022/04/15 05:07:06 DEBUG : file1: Need to transfer - File not found at Destination 2022/04/15 05:07:09 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2022/04/15 05:07:09 INFO : file1: Copied (new) to: sub/file2 2022/04/15 05:07:09 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/04/15 05:07:09 DEBUG : file1: Unchanged skipping 2022/04/15 05:07:09 DEBUG : B2 bucket rclone-test-riqasom4qeyobab4tomomig7: don't need to copy/move sub/file2, it is already at target location --- PASS: TestCopyFile (5.83s) === RUN TestCopyFileBackupDir run.go:181: Remote "B2 bucket rclone-test-riqasom4qeyobab4tomomig7", Local "Local file system at /tmp/rclone2735327350", Modify Window "1ms" 2022/04/15 05:07:13 DEBUG : Creating backend with remote "TestB2:rclone-test-riqasom4qeyobab4tomomig7/backup" 2022/04/15 05:07:14 DEBUG : Couldn't decode error response: EOF 2022/04/15 05:07:14 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18) 2022/04/15 05:07:14 DEBUG : Couldn't decode error response: EOF 2022/04/15 05:07:17 DEBUG : dst/file1: sha1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK 2022/04/15 05:07:17 INFO : dst/file1: Copied (server-side copy) 2022/04/15 05:07:17 DEBUG : pacer: low level retry 1/10 (error Post "https://api001.backblazeb2.com/b2api/v1/b2_hide_file": EOF) 2022/04/15 05:07:17 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2022/04/15 05:07:19 DEBUG : pacer: Reducing sleep to 10ms 2022/04/15 05:07:19 INFO : dst/file1: Deleted 2022/04/15 05:07:22 DEBUG : dst/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2022/04/15 05:07:22 INFO : dst/file1: Copied (new) --- PASS: TestCopyFileBackupDir (12.98s) === RUN TestCopyFileCompareDest run.go:181: Remote "B2 bucket rclone-test-riqasom4qeyobab4tomomig7", Local "Local file system at /tmp/rclone2735327350", Modify Window "1ms" 2022/04/15 05:07:24 DEBUG : Creating backend with remote "TestB2:rclone-test-riqasom4qeyobab4tomomig7/dst" 2022/04/15 05:07:25 DEBUG : Couldn't decode error response: EOF 2022/04/15 05:07:26 DEBUG : Couldn't decode error response: EOF 2022/04/15 05:07:26 DEBUG : Creating backend with remote "TestB2:rclone-test-riqasom4qeyobab4tomomig7/CompareDest" 2022/04/15 05:07:26 DEBUG : Couldn't decode error response: EOF 2022/04/15 05:07:26 DEBUG : Couldn't decode error response: EOF 2022/04/15 05:07:26 DEBUG : one: Need to transfer - File not found at Destination 2022/04/15 05:07:29 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/04/15 05:07:29 INFO : one: Copied (new) 2022/04/15 05:07:31 DEBUG : Couldn't decode error response: EOF 2022/04/15 05:07:31 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/04/15 05:07:31 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2022/04/15 05:07:31 INFO : one: Copied (replaced existing) 2022/04/15 05:07:37 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/04/15 05:07:37 DEBUG : one: Destination found in --compare-dest, skipping 2022/04/15 05:07:40 DEBUG : Couldn't decode error response: EOF 2022/04/15 05:07:40 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/04/15 05:07:40 DEBUG : two: Destination found in --compare-dest, skipping 2022/04/15 05:07:40 DEBUG : Couldn't decode error response: EOF 2022/04/15 05:07:40 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/04/15 05:07:40 DEBUG : two: Destination found in --compare-dest, skipping 2022/04/15 05:07:41 DEBUG : Couldn't decode error response: EOF 2022/04/15 05:07:41 DEBUG : two: Sizes differ (src 5 vs dst 3) 2022/04/15 05:07:41 DEBUG : two: Need to transfer - File not found at Destination 2022/04/15 05:07:43 DEBUG : two: sha1 = a4be8877ad88f3109a6411985a1f359e10e2d930 OK 2022/04/15 05:07:43 INFO : two: Copied (new) --- PASS: TestCopyFileCompareDest (24.75s) === RUN TestCopyFileCopyDest run.go:181: Remote "B2 bucket rclone-test-riqasom4qeyobab4tomomig7", Local "Local file system at /tmp/rclone2735327350", Modify Window "1ms" 2022/04/15 05:07:49 DEBUG : Creating backend with remote "TestB2:rclone-test-riqasom4qeyobab4tomomig7/dst" 2022/04/15 05:07:50 DEBUG : Couldn't decode error response: EOF 2022/04/15 05:07:50 DEBUG : Couldn't decode error response: EOF 2022/04/15 05:07:50 DEBUG : Creating backend with remote "TestB2:rclone-test-riqasom4qeyobab4tomomig7/CopyDest" 2022/04/15 05:07:51 DEBUG : Couldn't decode error response: EOF 2022/04/15 05:07:51 DEBUG : Couldn't decode error response: EOF 2022/04/15 05:07:51 DEBUG : one: Need to transfer - File not found at Destination 2022/04/15 05:07:54 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/04/15 05:07:54 INFO : one: Copied (new) 2022/04/15 05:07:55 DEBUG : Couldn't decode error response: EOF 2022/04/15 05:07:55 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/04/15 05:07:56 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2022/04/15 05:07:56 INFO : one: Copied (replaced existing) 2022/04/15 05:08:00 DEBUG : Creating backend with remote "TestB2:rclone-test-riqasom4qeyobab4tomomig7/BackupDir" 2022/04/15 05:08:01 DEBUG : Couldn't decode error response: EOF 2022/04/15 05:08:01 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/04/15 05:08:01 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/04/15 05:08:02 DEBUG : Couldn't decode error response: EOF 2022/04/15 05:08:04 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/04/15 05:08:04 INFO : one: Copied (server-side copy) 2022/04/15 05:08:05 INFO : one: Deleted 2022/04/15 05:08:06 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2022/04/15 05:08:06 INFO : one: Copied (server-side copy) 2022/04/15 05:08:06 DEBUG : one: Destination found in --copy-dest, using server-side copy 2022/04/15 05:08:10 DEBUG : Couldn't decode error response: EOF 2022/04/15 05:08:10 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/04/15 05:08:10 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2022/04/15 05:08:10 INFO : two: Copied (server-side copy) 2022/04/15 05:08:10 DEBUG : two: Destination found in --copy-dest, using server-side copy 2022/04/15 05:08:11 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/04/15 05:08:11 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/04/15 05:08:11 DEBUG : two: Unchanged skipping 2022/04/15 05:08:13 DEBUG : Couldn't decode error response: EOF 2022/04/15 05:08:13 DEBUG : three: Sizes differ (src 7 vs dst 5) 2022/04/15 05:08:13 DEBUG : three: Destination not found in --copy-dest 2022/04/15 05:08:13 DEBUG : three: Need to transfer - File not found at Destination 2022/04/15 05:08:15 DEBUG : three: sha1 = 370e613335f3cf2f19b2a2d35a86402f55c659f8 OK 2022/04/15 05:08:15 INFO : three: Copied (new) --- PASS: TestCopyFileCopyDest (35.11s) === 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-riqasom4qeyobab4tomomig7", Local "Local file system at /tmp/rclone2735327350", Modify Window "1ms" 2022/04/15 05:08:41 DEBUG : A1/B2: Making directory 2022/04/15 05:08:41 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/15 05:08:42 DEBUG : Couldn't decode error response: EOF 2022/04/15 05:08:42 DEBUG : Couldn't decode error response: EOF 2022/04/15 05:08:42 DEBUG : Couldn't decode error response: EOF 2022/04/15 05:08:42 DEBUG : Couldn't decode error response: EOF 2022/04/15 05:08:43 DEBUG : A1/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2022/04/15 05:08:43 INFO : A1/B1/C2/five: Copied (server-side copy) to: A2/B1/C2/five 2022/04/15 05:08:44 DEBUG : A1/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2022/04/15 05:08:44 INFO : A1/B1/C1/four: Copied (server-side copy) to: A2/B1/C1/four 2022/04/15 05:08:44 INFO : A1/B1/C2/five: Deleted 2022/04/15 05:08:44 DEBUG : Couldn't decode error response: EOF 2022/04/15 05:08:44 DEBUG : A1/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/04/15 05:08:44 INFO : A1/one: Copied (server-side copy) to: A2/one 2022/04/15 05:08:45 INFO : A1/B1/C1/four: Deleted 2022/04/15 05:08:46 DEBUG : A1/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2022/04/15 05:08:46 INFO : A1/B1/three: Copied (server-side copy) to: A2/B1/three 2022/04/15 05:08:47 DEBUG : A1/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2022/04/15 05:08:47 INFO : A1/two: Copied (server-side copy) to: A2/two 2022/04/15 05:08:47 INFO : A1/B1/three: Deleted 2022/04/15 05:08:48 INFO : A1/one: Deleted 2022/04/15 05:08:49 INFO : A1/two: Deleted fstest.go:251: Filtering empty directory "A2/B2" fstest.go:251: Filtering empty directory "A2/B1/C3" 2022/04/15 05:08:50 DEBUG : Couldn't decode error response: EOF 2022/04/15 05:08:50 DEBUG : Couldn't decode error response: EOF 2022/04/15 05:08:50 DEBUG : Couldn't decode error response: EOF 2022/04/15 05:08:50 DEBUG : Couldn't decode error response: EOF 2022/04/15 05:08:51 DEBUG : A2/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2022/04/15 05:08:51 INFO : A2/B1/C2/five: Copied (server-side copy) to: A3/B1/C2/five 2022/04/15 05:08:51 DEBUG : A2/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2022/04/15 05:08:51 INFO : A2/B1/C1/four: Copied (server-side copy) to: A3/B1/C1/four 2022/04/15 05:08:51 DEBUG : A2/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/04/15 05:08:51 INFO : A2/one: Copied (server-side copy) to: A3/one 2022/04/15 05:08:52 INFO : A2/B1/C2/five: Deleted 2022/04/15 05:08:52 DEBUG : Couldn't decode error response: EOF 2022/04/15 05:08:52 INFO : A2/one: Deleted 2022/04/15 05:08:52 DEBUG : A2/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2022/04/15 05:08:52 INFO : A2/two: Copied (server-side copy) to: A3/two 2022/04/15 05:08:52 INFO : A2/B1/C1/four: Deleted 2022/04/15 05:08:53 INFO : A2/two: Deleted 2022/04/15 05:08:57 DEBUG : A2/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2022/04/15 05:08:57 INFO : A2/B1/three: Copied (server-side copy) to: A3/B1/three 2022/04/15 05:08:59 INFO : A2/B1/three: Deleted fstest.go:251: Filtering empty directory "A3/B2" fstest.go:251: Filtering empty directory "A3/B1/C3" --- PASS: TestDirMove (39.59s) === RUN TestGetFsInfo run.go:181: Remote "B2 bucket rclone-test-riqasom4qeyobab4tomomig7", Local "Local file system at /tmp/rclone2735327350", Modify Window "1ms" --- PASS: TestGetFsInfo (0.41s) === RUN TestRcat === RUN TestRcat/withChecksum=false,ignoreChecksum=false === CONT TestRcat run.go:181: Remote "B2 bucket rclone-test-riqasom4qeyobab4tomomig7", Local "Local file system at /tmp/rclone2735327350", Modify Window "1ms" 2022/04/15 05:09:04 DEBUG : B2 bucket rclone-test-riqasom4qeyobab4tomomig7: File to upload is small (34 bytes), uploading instead of streaming 2022/04/15 05:09:05 DEBUG : no_checksum_small_file_from_pipe: sha1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK 2022/04/15 05:09:05 INFO : no_checksum_small_file_from_pipe: Copied (new) 2022/04/15 05:09:05 DEBUG : no_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2022/04/15 05:09:11 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-riqasom4qeyobab4tomomig7", Local "Local file system at /tmp/rclone2735327350", Modify Window "1ms" 2022/04/15 05:09:15 DEBUG : B2 bucket rclone-test-riqasom4qeyobab4tomomig7: File to upload is small (34 bytes), uploading instead of streaming 2022/04/15 05:09:16 DEBUG : with_checksum_small_file_from_pipe: sha1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK 2022/04/15 05:09:16 INFO : with_checksum_small_file_from_pipe: Copied (new) 2022/04/15 05:09:16 DEBUG : with_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2022/04/15 05:09:19 DEBUG : with_checksum_big_file_from_pipe: sha1 = d0080f73aa8b494570ad63df1beb25c01ea9eea2 OK 2022/04/15 05:09:19 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-riqasom4qeyobab4tomomig7", Local "Local file system at /tmp/rclone2735327350", Modify Window "1ms" 2022/04/15 05:09:24 DEBUG : B2 bucket rclone-test-riqasom4qeyobab4tomomig7: File to upload is small (34 bytes), uploading instead of streaming 2022/04/15 05:09:25 INFO : ignore_checksum_small_file_from_pipe: Copied (new) 2022/04/15 05:09:25 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2022/04/15 05:09:26 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-riqasom4qeyobab4tomomig7", Local "Local file system at /tmp/rclone2735327350", Modify Window "1ms" 2022/04/15 05:09:29 DEBUG : B2 bucket rclone-test-riqasom4qeyobab4tomomig7: File to upload is small (34 bytes), uploading instead of streaming 2022/04/15 05:09:30 INFO : ignore_checksum_small_file_from_pipe: Copied (new) 2022/04/15 05:09:30 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2022/04/15 05:09:33 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical --- PASS: TestRcat (33.99s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (10.75s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (9.17s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (4.90s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (9.18s) === RUN TestRcatSize run.go:181: Remote "B2 bucket rclone-test-riqasom4qeyobab4tomomig7", Local "Local file system at /tmp/rclone2735327350", Modify Window "1ms" 2022/04/15 05:09:41 DEBUG : B2 bucket rclone-test-riqasom4qeyobab4tomomig7: File to upload is small (60 bytes), uploading instead of streaming 2022/04/15 05:09:43 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2022/04/15 05:09:43 INFO : potato2: Copied (new) 2022/04/15 05:09:43 DEBUG : pacer: low level retry 1/10 (error Post "https://api001.backblazeb2.com/b2api/v1/b2_list_file_names": EOF) 2022/04/15 05:09:43 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2022/04/15 05:09:44 DEBUG : pacer: Reducing sleep to 10ms --- PASS: TestRcatSize (7.29s) === RUN TestCopyFileMaxTransfer run.go:181: Remote "B2 bucket rclone-test-riqasom4qeyobab4tomomig7", Local "Local file system at /tmp/rclone2735327350", Modify Window "1ms" 2022/04/15 05:09:46 DEBUG : Couldn't decode error response: EOF 2022/04/15 05:09:46 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination 2022/04/15 05:09:48 DEBUG : TestCopyFileMaxTransfer/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2022/04/15 05:09:48 INFO : TestCopyFileMaxTransfer/file1: Copied (new) 2022/04/15 05:09:48 DEBUG : Couldn't decode error response: EOF 2022/04/15 05:09:48 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination 2022/04/15 05:09:48 ERROR : TestCopyFileMaxTransfer/file2: Failed to copy: Post "https://pod-000-1103-11.backblaze.com/b2api/v1/b2_upload_file/0fc2ed03909d2bbb8f080e1b/c001_v0001103_t0055": Max transfer limit reached as set by --max-transfer 2022/04/15 05:09:49 DEBUG : Couldn't decode error response: EOF 2022/04/15 05:09:49 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination 2022/04/15 05:09:49 DEBUG : Couldn't decode error response: EOF 2022/04/15 05:09:49 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination 2022/04/15 05:09:50 DEBUG : TestCopyFileMaxTransfer/file4: sha1 = 9e9e41aa45c19b02677ad14e028ac279709ef7ef OK 2022/04/15 05:09:50 INFO : TestCopyFileMaxTransfer/file4: Copied (new) --- PASS: TestCopyFileMaxTransfer (6.71s) === RUN TestTouchDir run.go:181: Remote "B2 bucket rclone-test-riqasom4qeyobab4tomomig7", Local "Local file system at /tmp/rclone2735327350", Modify Window "1ms" 2022/04/15 05:09:56 DEBUG : B2 bucket rclone-test-riqasom4qeyobab4tomomig7: Touching "empty space" 2022/04/15 05:09:57 DEBUG : B2 bucket rclone-test-riqasom4qeyobab4tomomig7: Touching "potato2" 2022/04/15 05:09:59 DEBUG : B2 bucket rclone-test-riqasom4qeyobab4tomomig7: Touching "sub dir/potato3" --- PASS: TestTouchDir (14.20s) === 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/15 05:10:06 DEBUG : B2 bucket rclone-test-riqasom4qeyobab4tomomig7: Purge remote 2022/04/15 05:10:07 DEBUG : A1/B1/C1/four: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f118fded3cc7614f5_d20220415_m050844_c001_v0001115_t0036_u01649999324557") 2022/04/15 05:10:07 DEBUG : A1/B1/C1/four: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867d3b2_d20220415_m050837_c001_v0001103_t0011_u01649999317733") 2022/04/15 05:10:07 DEBUG : A1/B1/C1/one: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f119cb4761e61c23d_d20220415_m050627_c001_v0001100_t0019_u01649999187775") 2022/04/15 05:10:07 DEBUG : A1/B1/C1/one: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867ca1f_d20220415_m050624_c001_v0001103_t0028_u01649999184771") 2022/04/15 05:10:07 DEBUG : A1/B1/C2/five: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f1014f36ccf27774c_d20220415_m050843_c001_v0001003_t0028_u01649999323633") 2022/04/15 05:10:07 DEBUG : A1/B1/C2/five: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867d3cb_d20220415_m050839_c001_v0001103_t0012_u01649999319300") 2022/04/15 05:10:07 DEBUG : A1/B1/three: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f100dd5b15218cbab_d20220415_m050846_c001_v0001120_t0008_u01649999326996") 2022/04/15 05:10:07 DEBUG : A1/B1/three: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867d314_d20220415_m050830_c001_v0001103_t0050_u01649999310046") 2022/04/15 05:10:07 DEBUG : A1/one: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f105dcb865699e89b_d20220415_m050845_c001_v0001029_t0025_u01649999325092") 2022/04/15 05:10:07 DEBUG : A1/one: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867d2b5_d20220415_m050825_c001_v0001103_t0041_u01649999305025") 2022/04/15 05:10:07 DEBUG : A1/two: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f118752cc6dc6bead_d20220415_m050847_c001_v0001102_t0037_u01649999327444") 2022/04/15 05:10:07 DEBUG : A1/two: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867d2e0_d20220415_m050827_c001_v0001103_t0058_u01649999307393") 2022/04/15 05:10:07 DEBUG : A1/two: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f11914faa0d85594d_d20220415_m050631_c001_v0001104_t0055_u01649999191032") 2022/04/15 05:10:07 DEBUG : A1/two: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867ca2b_d20220415_m050625_c001_v0001103_t0041_u01649999185554") 2022/04/15 05:10:07 DEBUG : A2/B1/C1/four: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f11963a6414bce3b6_d20220415_m050851_c001_v0001115_t0017_u01649999331531") 2022/04/15 05:10:07 DEBUG : A2/B1/C1/four: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f1076831d7d6b0f01_d20220415_m050843_c001_v0001161_t0052_u01649999323856") 2022/04/15 05:10:08 DEBUG : A2/B1/C2/five: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f10300da1e4e29e35_d20220415_m050851_c001_v0001161_t0048_u01649999331523") 2022/04/15 05:10:08 DEBUG : A2/B1/C2/five: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f104bcc93c543cbe8_d20220415_m050843_c001_v0001101_t0014_u01649999323053") 2022/04/15 05:10:08 DEBUG : A2/B1/three: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f102858dc23117a30_d20220415_m050857_c001_v0001106_t0023_u01649999337934") 2022/04/15 05:10:08 DEBUG : A2/B1/three: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f1137038ed18e7e3f_d20220415_m050843_c001_v0001102_t0058_u01649999323803") 2022/04/15 05:10:08 DEBUG : A2/one: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f1087943560f085e8_d20220415_m050851_c001_v0001105_t0003_u01649999331743") 2022/04/15 05:10:08 DEBUG : A2/one: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f11651393609a8d8c_d20220415_m050844_c001_v0001115_t0053_u01649999324062") 2022/04/15 05:10:08 DEBUG : A2/two: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f10184a3cce31692f_d20220415_m050852_c001_v0001100_t0014_u01649999332882") 2022/04/15 05:10:08 DEBUG : A2/two: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f104970ad31f95ed2_d20220415_m050844_c001_v0001117_t0045_u01649999324905") 2022/04/15 05:10:08 DEBUG : A3/B1/C1/four: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f102dbf4ccce598d6_d20220415_m050859_c001_v0001146_t0030_u01649999339847") 2022/04/15 05:10:08 DEBUG : A3/B1/C1/four: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f11498ddd6bb51c87_d20220415_m050850_c001_v0001131_t0035_u01649999330864") 2022/04/15 05:10:08 DEBUG : A3/B1/C2/five: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f108e024ff3cf0dc2_d20220415_m050900_c001_v0001103_t0005_u01649999340307") 2022/04/15 05:10:08 DEBUG : A3/B1/C2/five: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f1164be29f5c94460_d20220415_m050850_c001_v0001100_t0011_u01649999330874") 2022/04/15 05:10:08 DEBUG : A3/B1/three: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f108637c970a4fc6a_d20220415_m050900_c001_v0001131_t0034_u01649999340884") 2022/04/15 05:10:08 DEBUG : A3/B1/three: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f1049d42f1f4df63b_d20220415_m050850_c001_v0001161_t0014_u01649999330911") 2022/04/15 05:10:08 DEBUG : A3/one: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f112931a71777f254_d20220415_m050901_c001_v0001099_t0045_u01649999341590") 2022/04/15 05:10:08 DEBUG : A3/one: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f11238b7f60246e3b_d20220415_m050851_c001_v0001165_t0039_u01649999331518") 2022/04/15 05:10:08 DEBUG : A3/two: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f1024476cedf174d4_d20220415_m050902_c001_v0001115_t0029_u01649999342736") 2022/04/15 05:10:08 DEBUG : A3/two: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f102b500d06d79f19_d20220415_m050852_c001_v0001137_t0033_u01649999332327") 2022/04/15 05:10:08 DEBUG : BackupDir/one: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f104da1e244ee7421_d20220415_m050815_c001_v0001165_t0027_u01649999295660") 2022/04/15 05:10:09 DEBUG : BackupDir/one: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111634ef7b8cd0b4_d20220415_m050803_c001_v0001106_t0038_u01649999283709") 2022/04/15 05:10:09 DEBUG : CompareDest/one: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f1090a4e8e35dd39b_d20220415_m050744_c001_v0001165_t0026_u01649999264271") 2022/04/15 05:10:09 DEBUG : CompareDest/one: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867cf06_d20220415_m050734_c001_v0001103_t0022_u01649999254778") 2022/04/15 05:10:09 DEBUG : CompareDest/two: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f1198946d4dae8d79_d20220415_m050744_c001_v0001117_t0043_u01649999264562") 2022/04/15 05:10:09 DEBUG : CompareDest/two: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867cf52_d20220415_m050737_c001_v0001103_t0005_u01649999257962") 2022/04/15 05:10:09 DEBUG : CopyDest/one: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f1169435adfd745f0_d20220415_m050816_c001_v0001137_t0011_u01649999296106") 2022/04/15 05:10:09 DEBUG : CopyDest/one: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867d0f3_d20220415_m050759_c001_v0001103_t0016_u01649999279378") 2022/04/15 05:10:09 DEBUG : CopyDest/three: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f1006939c7720deb0_d20220415_m050816_c001_v0001099_t0027_u01649999296552") 2022/04/15 05:10:09 DEBUG : CopyDest/three: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867d1d0_d20220415_m050811_c001_v0001103_t0040_u01649999291680") 2022/04/15 05:10:09 DEBUG : CopyDest/two: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f11369df4c1a94cf1_d20220415_m050817_c001_v0001103_t0007_u01649999297755") 2022/04/15 05:10:09 DEBUG : CopyDest/two: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867d189_d20220415_m050807_c001_v0001103_t0031_u01649999287787") 2022/04/15 05:10:09 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f112c0a9a526c002f_d20220415_m050951_c001_v0001130_t0033_u01649999391329") 2022/04/15 05:10:09 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867d87a_d20220415_m050946_c001_v0001103_t0004_u01649999386575") 2022/04/15 05:10:09 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f10379bb3d573ae1e_d20220415_m050951_c001_v0001146_t0051_u01649999391806") 2022/04/15 05:10:09 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867d8b3_d20220415_m050950_c001_v0001103_t0013_u01649999390018") 2022/04/15 05:10:09 DEBUG : a.txt: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f10694951ef7ed6ec_d20220415_m050441_c001_v0001115_t0025_u01649999081397") 2022/04/15 05:10:10 DEBUG : a.txt: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867c1a8_d20220415_m050424_c001_v0001103_t0031_u01649999064329") 2022/04/15 05:10:10 DEBUG : also/one: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f109d8d52f8503b5e_d20220415_m050418_c001_v0001104_t0019_u01649999058292") 2022/04/15 05:10:10 DEBUG : also/one: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867c03a_d20220415_m050400_c001_v0001103_t0050_u01649999040450") 2022/04/15 05:10:10 DEBUG : another: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f1151a8b268a4c745_d20220415_m050419_c001_v0001165_t0046_u01649999059648") 2022/04/15 05:10:10 DEBUG : another: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867c049_d20220415_m050401_c001_v0001103_t0039_u01649999041588") 2022/04/15 05:10:10 DEBUG : backup/dst/file1: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f110ef82869809d37_d20220415_m050722_c001_v0001157_t0036_u01649999242872") 2022/04/15 05:10:10 DEBUG : backup/dst/file1: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867cdb2_d20220415_m050716_c001_v0001103_t0042_u01649999236332") 2022/04/15 05:10:10 DEBUG : backup/dst/file1: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111d724747ff3cae_d20220415_m050703_c001_v0001041_t0032_u01649999223338") 2022/04/15 05:10:10 DEBUG : backup/dst/file1: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f106fa1a925ff0d4c_d20220415_m050655_c001_v0001106_t0051_u01649999215968") 2022/04/15 05:10:10 DEBUG : data/banana: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f1160f19030e5be73_d20220415_m050354_c001_v0001120_t0020_u01649999034405") 2022/04/15 05:10:10 DEBUG : data/banana: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867bf57_d20220415_m050349_c001_v0001103_t0013_u01649999029800") 2022/04/15 05:10:10 DEBUG : data/banana: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867bd9f_d20220415_m050329_c001_v0001103_t0025_u01649999009420") 2022/04/15 05:10:10 DEBUG : data/potato: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f119cb4761e61b8f4_d20220415_m050355_c001_v0001100_t0028_u01649999035545") 2022/04/15 05:10:10 DEBUG : data/potato: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867bf76_d20220415_m050351_c001_v0001103_t0029_u01649999031380") 2022/04/15 05:10:10 DEBUG : data/potato: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867bddf_d20220415_m050333_c001_v0001103_t0012_u01649999013530") 2022/04/15 05:10:10 DEBUG : dst/file1: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f113d5a60021fcbf9_d20220415_m050723_c001_v0001109_t0016_u01649999243567") 2022/04/15 05:10:10 DEBUG : dst/file1: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867cdef_d20220415_m050720_c001_v0001103_t0041_u01649999240067") 2022/04/15 05:10:10 DEBUG : dst/file1: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f1193109004d0aa6d_d20220415_m050718_c001_v0001101_t0017_u01649999238715") 2022/04/15 05:10:11 DEBUG : dst/file1: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867cd5a_d20220415_m050711_c001_v0001103_t0034_u01649999231698") 2022/04/15 05:10:11 DEBUG : dst/file1: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f109ae021d977318b_d20220415_m050705_c001_v0001101_t0057_u01649999225079") 2022/04/15 05:10:11 DEBUG : dst/file1: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867ccbe_d20220415_m050701_c001_v0001103_t0038_u01649999219877") 2022/04/15 05:10:11 DEBUG : dst/file1: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f118698fa84fc6f3c_d20220415_m050657_c001_v0001104_t0041_u01649999217127") 2022/04/15 05:10:11 DEBUG : dst/file1: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867cc22_d20220415_m050651_c001_v0001103_t0012_u01649999211781") 2022/04/15 05:10:11 DEBUG : dst/one: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f1153e08decc73a6b_d20220415_m050821_c001_v0001100_t0040_u01649999300701") 2022/04/15 05:10:11 DEBUG : dst/one: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f106ba4dd1165ba66_d20220415_m050805_c001_v0001103_t0007_u01649999285421") 2022/04/15 05:10:11 DEBUG : dst/one: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f112429e60938398e_d20220415_m050804_c001_v0001157_t0042_u01649999284865") 2022/04/15 05:10:11 DEBUG : dst/one: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867d0c3_d20220415_m050757_c001_v0001103_t0037_u01649999277033") 2022/04/15 05:10:11 DEBUG : dst/one: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f1085d381e1bd10d8_d20220415_m050755_c001_v0001100_t0034_u01649999275730") 2022/04/15 05:10:11 DEBUG : dst/one: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f1085d381e1bd10aa_d20220415_m050753_c001_v0001100_t0051_u01649999273846") 2022/04/15 05:10:11 DEBUG : dst/one: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f10472872ea45f53b_d20220415_m050746_c001_v0001100_t0030_u01649999266695") 2022/04/15 05:10:11 DEBUG : dst/one: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867cec1_d20220415_m050732_c001_v0001103_t0056_u01649999252430") 2022/04/15 05:10:11 DEBUG : dst/one: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f103b3458b7ff4208_d20220415_m050731_c001_v0001098_t0011_u01649999251221") 2022/04/15 05:10:11 DEBUG : dst/one: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f103b3458b7ff41eb_d20220415_m050728_c001_v0001098_t0003_u01649999248918") 2022/04/15 05:10:11 DEBUG : dst/three: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f1077484746517a3d_d20220415_m050822_c001_v0001102_t0040_u01649999302190") 2022/04/15 05:10:12 DEBUG : dst/three: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f1085d381e1bd1264_d20220415_m050814_c001_v0001100_t0036_u01649999294461") 2022/04/15 05:10:12 DEBUG : dst/two: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f1188d63e7836c832_d20220415_m050823_c001_v0001092_t0029_u01649999303370") 2022/04/15 05:10:12 DEBUG : dst/two: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f102dbf4ccce59826_d20220415_m050810_c001_v0001146_t0007_u01649999290518") 2022/04/15 05:10:12 DEBUG : dst/two: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f11914faa0d855dff_d20220415_m050748_c001_v0001104_t0004_u01649999268087") 2022/04/15 05:10:12 DEBUG : dst/two: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f103b3458b7ff4278_d20220415_m050742_c001_v0001098_t0002_u01649999262208") 2022/04/15 05:10:12 DEBUG : empty space: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f11369df4c1a9539d_d20220415_m051000_c001_v0001103_t0031_u01649999400304") 2022/04/15 05:10:12 DEBUG : empty space: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f11159bed05e1fea2_d20220415_m050957_c001_v0001165_t0034_u01649999397172") 2022/04/15 05:10:12 DEBUG : empty space: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867d8f9_d20220415_m050953_c001_v0001103_t0008_u01649999393592") 2022/04/15 05:10:12 DEBUG : empty space: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f1077484746516fae_d20220415_m050554_c001_v0001102_t0049_u01649999154185") 2022/04/15 05:10:12 DEBUG : empty space: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867c778_d20220415_m050548_c001_v0001103_t0014_u01649999148072") 2022/04/15 05:10:12 DEBUG : empty space: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f106f490c35bd500f_d20220415_m050542_c001_v0001021_t0008_u01649999142601") 2022/04/15 05:10:12 DEBUG : empty space: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867c6fd_d20220415_m050540_c001_v0001103_t0026_u01649999140579") 2022/04/15 05:10:12 DEBUG : empty space: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f1142a791800e15eb_d20220415_m050534_c001_v0001116_t0010_u01649999134691") 2022/04/15 05:10:12 DEBUG : empty space: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867c660_d20220415_m050532_c001_v0001103_t0001_u01649999132966") 2022/04/15 05:10:12 DEBUG : empty space: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f106ef4b6895375bd_d20220415_m050529_c001_v0001157_t0017_u01649999129354") 2022/04/15 05:10:12 DEBUG : empty space: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867c5e2_d20220415_m050526_c001_v0001103_t0011_u01649999126773") 2022/04/15 05:10:12 DEBUG : empty space: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f1028904f3b49d4f1_d20220415_m050523_c001_v0001101_t0056_u01649999123622") 2022/04/15 05:10:13 DEBUG : empty space: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867c56d_d20220415_m050520_c001_v0001103_t0050_u01649999120161") 2022/04/15 05:10:13 DEBUG : empty space: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f1132a86b1b3f2c94_d20220415_m050313_c001_v0001098_t0007_u01649998993982") 2022/04/15 05:10:13 DEBUG : empty space: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867bc35_d20220415_m050308_c001_v0001103_t0003_u01649998988029") 2022/04/15 05:10:13 DEBUG : empty space: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f116526fb4bccce70_d20220415_m050257_c001_v0001117_t0006_u01649998977991") 2022/04/15 05:10:13 DEBUG : empty space: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867baee_d20220415_m050250_c001_v0001103_t0046_u01649998970411") 2022/04/15 05:10:13 DEBUG : empty space: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f1162ce404c1cb555_d20220415_m050242_c001_v0001109_t0015_u01649998962903") 2022/04/15 05:10:13 DEBUG : empty space: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867ba26_d20220415_m050238_c001_v0001103_t0049_u01649998958003") 2022/04/15 05:10:13 DEBUG : file1: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f1024476cedf16b2e_d20220415_m050650_c001_v0001115_t0029_u01649999210043") 2022/04/15 05:10:13 DEBUG : file1: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867cbda_d20220415_m050648_c001_v0001103_t0028_u01649999208377") 2022/04/15 05:10:13 DEBUG : file1: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f11238b7f602465af_d20220415_m050641_c001_v0001165_t0051_u01649999201630") 2022/04/15 05:10:13 DEBUG : file1: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867caf0_d20220415_m050636_c001_v0001103_t0042_u01649999196702") 2022/04/15 05:10:13 DEBUG : file1: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f1145b8605a0a4342_d20220415_m050617_c001_v0001100_t0053_u01649999177577") 2022/04/15 05:10:13 DEBUG : file1: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867c915_d20220415_m050610_c001_v0001103_t0056_u01649999170017") 2022/04/15 05:10:13 DEBUG : file1: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f1057d9fcdf1f4e1e_d20220415_m050507_c001_v0001097_t0033_u01649999107550") 2022/04/15 05:10:13 DEBUG : file1: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867c3fd_d20220415_m050500_c001_v0001103_t0046_u01649999100355") 2022/04/15 05:10:13 DEBUG : file1: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f118f43769e3700a5_d20220415_m050457_c001_v0001038_t0038_u01649999097755") 2022/04/15 05:10:14 DEBUG : file1: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867c36b_d20220415_m050452_c001_v0001103_t0011_u01649999092575") 2022/04/15 05:10:14 DEBUG : file1: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f1049d42f1f4de58b_d20220415_m050235_c001_v0001161_t0054_u01649998955722") 2022/04/15 05:10:14 DEBUG : file1: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867b9f7_d20220415_m050233_c001_v0001103_t0018_u01649998953792") 2022/04/15 05:10:14 DEBUG : file1: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867b9c3_d20220415_m050230_c001_v0001103_t0002_u01649998950883") 2022/04/15 05:10:14 DEBUG : file1: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867b992_d20220415_m050228_c001_v0001103_t0057_u01649998948000") 2022/04/15 05:10:14 DEBUG : file2: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f114c63cbadd56a74_d20220415_m050642_c001_v0001099_t0042_u01649999202010") 2022/04/15 05:10:14 DEBUG : file2: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867cb41_d20220415_m050640_c001_v0001103_t0021_u01649999200241") 2022/04/15 05:10:14 DEBUG : file2: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f115519fc40fe2e96_d20220415_m050618_c001_v0001098_t0014_u01649999178023") 2022/04/15 05:10:14 DEBUG : file2: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867c954_d20220415_m050613_c001_v0001103_t0053_u01649999173794") 2022/04/15 05:10:14 DEBUG : filename.txt: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f11738b64bd34406e_d20220415_m050643_c001_v0001101_t0043_u01649999203065") 2022/04/15 05:10:14 DEBUG : filename.txt: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867cb36_d20220415_m050639_c001_v0001103_t0025_u01649999199645") 2022/04/15 05:10:14 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f1175b7bdaeb09d68_d20220415_m050933_c001_v0001109_t0030_u01649999373728") 2022/04/15 05:10:15 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867d76c_d20220415_m050930_c001_v0001103_t0035_u01649999370885") 2022/04/15 05:10:15 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f114c63cbadd5754c_d20220415_m050927_c001_v0001099_t0001_u01649999367299") 2022/04/15 05:10:15 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867d70d_d20220415_m050925_c001_v0001103_t0010_u01649999365814") 2022/04/15 05:10:15 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f10141652d6377da5_d20220415_m050935_c001_v0001115_t0044_u01649999375142") 2022/04/15 05:10:15 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867d756_d20220415_m050929_c001_v0001103_t0034_u01649999369505") 2022/04/15 05:10:15 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f11159bed05e1fdbb_d20220415_m050928_c001_v0001165_t0004_u01649999368883") 2022/04/15 05:10:15 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867d707_d20220415_m050925_c001_v0001103_t0058_u01649999365112") 2022/04/15 05:10:15 DEBUG : large: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111eef075cbe3262_d20220415_m050609_c001_v0001098_t0020_u01649999169375") 2022/04/15 05:10:15 DEBUG : large: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867c85d_d20220415_m050601_c001_v0001103_t0031_u01649999161250") 2022/04/15 05:10:15 DEBUG : medium: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f107d2d8866564166_d20220415_m050606_c001_v0001037_t0006_u01649999166575") 2022/04/15 05:10:16 DEBUG : medium: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867c84a_d20220415_m050600_c001_v0001103_t0054_u01649999160160") 2022/04/15 05:10:16 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f117b432924967bde_d20220415_m050912_c001_v0001103_t0030_u01649999352703") 2022/04/15 05:10:16 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867d587_d20220415_m050905_c001_v0001103_t0058_u01649999345943") 2022/04/15 05:10:16 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f119445c9de77f86e_d20220415_m050913_c001_v0001099_t0047_u01649999353477") 2022/04/15 05:10:16 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867d577_d20220415_m050905_c001_v0001103_t0000_u01649999345244") 2022/04/15 05:10:16 DEBUG : not-one: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f10529b78211ef552_d20220415_m050419_c001_v0001106_t0022_u01649999059879") 2022/04/15 05:10:16 DEBUG : not-one: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867c0eb_d20220415_m050412_c001_v0001103_t0031_u01649999052079") 2022/04/15 05:10:16 DEBUG : one: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f1046bd7c6d1be018_d20220415_m050416_c001_v0001103_t0001_u01649999056309") 2022/04/15 05:10:16 DEBUG : one: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867c020_d20220415_m050359_c001_v0001103_t0026_u01649999039635") 2022/04/15 05:10:16 DEBUG : potato1: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f1064fd4576c607f6_d20220415_m050944_c001_v0001101_t0042_u01649999384423") 2022/04/15 05:10:16 DEBUG : potato1: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867d805_d20220415_m050939_c001_v0001103_t0035_u01649999379009") 2022/04/15 05:10:16 DEBUG : potato2: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f1124451472611f7d_d20220415_m051002_c001_v0001038_t0043_u01649999402939") 2022/04/15 05:10:16 DEBUG : potato2: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f110df71a22196e85_d20220415_m050957_c001_v0001102_t0042_u01649999397750") 2022/04/15 05:10:16 DEBUG : potato2: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867d8e1_d20220415_m050952_c001_v0001103_t0004_u01649999392507") 2022/04/15 05:10:17 DEBUG : potato2: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f1167dc31b03d7af7_d20220415_m050945_c001_v0001005_t0032_u01649999385151") 2022/04/15 05:10:17 DEBUG : potato2: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867d845_d20220415_m050941_c001_v0001103_t0009_u01649999381698") 2022/04/15 05:10:17 DEBUG : potato2: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f11215fa0e381dd6c_d20220415_m050554_c001_v0001115_t0043_u01649999154793") 2022/04/15 05:10:17 DEBUG : potato2: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867c73e_d20220415_m050544_c001_v0001103_t0006_u01649999144629") 2022/04/15 05:10:17 DEBUG : potato2: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f110e372fe55c9a22_d20220415_m050543_c001_v0001165_t0004_u01649999143278") 2022/04/15 05:10:17 DEBUG : potato2: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867c6d0_d20220415_m050538_c001_v0001103_t0041_u01649999138310") 2022/04/15 05:10:17 DEBUG : potato2: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f1080ec29dec921b7_d20220415_m050536_c001_v0001120_t0018_u01649999136032") 2022/04/15 05:10:17 DEBUG : potato2: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867c63d_d20220415_m050531_c001_v0001103_t0002_u01649999131425") 2022/04/15 05:10:17 DEBUG : potato2: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f11546d9b4ae516d7_d20220415_m050529_c001_v0001115_t0026_u01649999129904") 2022/04/15 05:10:17 DEBUG : potato2: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867c5d1_d20220415_m050525_c001_v0001103_t0026_u01649999125950") 2022/04/15 05:10:17 DEBUG : potato2: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f118a67b9c06ec3dd_d20220415_m050524_c001_v0001099_t0055_u01649999124706") 2022/04/15 05:10:17 DEBUG : potato2: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867c55c_d20220415_m050518_c001_v0001103_t0050_u01649999118936") 2022/04/15 05:10:17 DEBUG : potato2: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f110970b8aba6a90a_d20220415_m050314_c001_v0001106_t0000_u01649998994413") 2022/04/15 05:10:17 DEBUG : potato2: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867bc50_d20220415_m050309_c001_v0001103_t0044_u01649998989192") 2022/04/15 05:10:17 DEBUG : potato2: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f106809b3acb07c80_d20220415_m050259_c001_v0001102_t0024_u01649998979163") 2022/04/15 05:10:17 DEBUG : potato2: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867bb13_d20220415_m050252_c001_v0001103_t0048_u01649998972096") 2022/04/15 05:10:17 DEBUG : potato2: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f1117adb549ea9c21_d20220415_m050244_c001_v0001117_t0044_u01649998964104") 2022/04/15 05:10:17 DEBUG : potato2: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867ba37_d20220415_m050239_c001_v0001103_t0048_u01649998959133") 2022/04/15 05:10:17 DEBUG : remotepotato: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111bc7734f1cd2be_d20220415_m050315_c001_v0001104_t0004_u01649998995722") 2022/04/15 05:10:18 DEBUG : remotepotato: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867bc68_d20220415_m050311_c001_v0001103_t0010_u01649998991474") 2022/04/15 05:10:18 DEBUG : remotepotato: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f109dede878ecb8df_d20220415_m050303_c001_v0001157_t0033_u01649998983727") 2022/04/15 05:10:18 DEBUG : remotepotato: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867bb34_d20220415_m050254_c001_v0001103_t0021_u01649998974953") 2022/04/15 05:10:18 DEBUG : remotepotato: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f100ad881ef73f924_d20220415_m050245_c001_v0001103_t0009_u01649998965094") 2022/04/15 05:10:18 DEBUG : remotepotato: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867ba51_d20220415_m050241_c001_v0001103_t0023_u01649998961090") 2022/04/15 05:10:18 DEBUG : rutabaga: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f11914faa0d854e7c_d20220415_m050318_c001_v0001104_t0000_u01649998998422") 2022/04/15 05:10:18 DEBUG : rutabaga: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867bbfc_d20220415_m050305_c001_v0001103_t0038_u01649998985968") 2022/04/15 05:10:18 DEBUG : rutabaga: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f116a223821ab32ff_d20220415_m050304_c001_v0001144_t0048_u01649998984299") 2022/04/15 05:10:18 DEBUG : rutabaga: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867bac5_d20220415_m050248_c001_v0001103_t0000_u01649998968286") 2022/04/15 05:10:18 DEBUG : rutabaga: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f1046073760473be6_d20220415_m050247_c001_v0001154_t0006_u01649998967132") 2022/04/15 05:10:18 DEBUG : rutabaga: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867ba15_d20220415_m050236_c001_v0001103_t0005_u01649998956369") 2022/04/15 05:10:18 DEBUG : small: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f1167d82ce7f54126_d20220415_m050606_c001_v0001104_t0047_u01649999166915") 2022/04/15 05:10:18 DEBUG : small: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867c83a_d20220415_m050559_c001_v0001103_t0001_u01649999159165") 2022/04/15 05:10:18 DEBUG : sub dir/hello world: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f104970ad31f95850_d20220415_m050516_c001_v0001117_t0027_u01649999116780") 2022/04/15 05:10:19 DEBUG : sub dir/hello world: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867c4c8_d20220415_m050511_c001_v0001103_t0016_u01649999110081") 2022/04/15 05:10:19 DEBUG : sub dir/hello world: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f10146d48e01b827c_d20220415_m050442_c001_v0001130_t0012_u01649999082609") 2022/04/15 05:10:19 DEBUG : sub dir/hello world: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867c1bf_d20220415_m050425_c001_v0001103_t0049_u01649999065671") 2022/04/15 05:10:19 DEBUG : sub dir/hello world2: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f11707700d4a367cc_d20220415_m050443_c001_v0001104_t0052_u01649999083128") 2022/04/15 05:10:19 DEBUG : sub dir/hello world2: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867c1cd_d20220415_m050426_c001_v0001103_t0002_u01649999066242") 2022/04/15 05:10:19 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f116526fb4bccd22f_d20220415_m050446_c001_v0001117_t0001_u01649999086337") 2022/04/15 05:10:19 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867c1e9_d20220415_m050428_c001_v0001103_t0006_u01649999068626") 2022/04/15 05:10:19 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f112d317438e50dc3_d20220415_m050447_c001_v0001103_t0036_u01649999087572") 2022/04/15 05:10:19 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867c207_d20220415_m050429_c001_v0001103_t0026_u01649999069471") 2022/04/15 05:10:19 DEBUG : sub dir/potato3: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f117b432924967fb9_d20220415_m051005_c001_v0001103_t0048_u01649999405464") 2022/04/15 05:10:19 DEBUG : sub dir/potato3: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f100b7c7efd898e84_d20220415_m050959_c001_v0001161_t0000_u01649999399538") 2022/04/15 05:10:19 DEBUG : sub dir/potato3: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867d90f_d20220415_m050954_c001_v0001103_t0002_u01649999394546") 2022/04/15 05:10:19 DEBUG : sub dir/potato3: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f11534d59b0e2be73_d20220415_m050556_c001_v0001090_t0038_u01649999156012") 2022/04/15 05:10:19 DEBUG : sub dir/potato3: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867c792_d20220415_m050549_c001_v0001103_t0027_u01649999149301") 2022/04/15 05:10:19 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f114553f4d8d48a36_d20220415_m050448_c001_v0001106_t0020_u01649999088313") 2022/04/15 05:10:19 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867c23c_d20220415_m050432_c001_v0001103_t0058_u01649999072432") 2022/04/15 05:10:19 DEBUG : sub/file2: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f118fded3cc760f35_d20220415_m050710_c001_v0001115_t0038_u01649999230261") 2022/04/15 05:10:20 DEBUG : sub/file2: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867cd10_d20220415_m050706_c001_v0001103_t0027_u01649999226561") 2022/04/15 05:10:20 DEBUG : sub/file2: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f1061a17fcbf1973c_d20220415_m050646_c001_v0001091_t0032_u01649999206043") 2022/04/15 05:10:20 DEBUG : sub/file2: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867cb8d_d20220415_m050644_c001_v0001103_t0029_u01649999204436") 2022/04/15 05:10:20 DEBUG : sub/file2: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f119da9b822d554c3_d20220415_m050508_c001_v0001098_t0030_u01649999108186") 2022/04/15 05:10:20 DEBUG : sub/file2: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867c40b_d20220415_m050501_c001_v0001103_t0050_u01649999101010") 2022/04/15 05:10:20 DEBUG : sub/file2: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f102e324750106608_d20220415_m050458_c001_v0001097_t0001_u01649999098505") 2022/04/15 05:10:20 DEBUG : sub/file2: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867c38f_d20220415_m050454_c001_v0001103_t0028_u01649999094163") 2022/04/15 05:10:20 DEBUG : test.sum: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f10042d8ad8e90731_d20220415_m050356_c001_v0001115_t0043_u01649999036161") 2022/04/15 05:10:20 DEBUG : test.sum: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867bf96_d20220415_m050352_c001_v0001103_t0035_u01649999032701") 2022/04/15 05:10:20 DEBUG : test.sum: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867bed5_d20220415_m050344_c001_v0001103_t0050_u01649999024317") 2022/04/15 05:10:20 DEBUG : test.sum: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867bec1_d20220415_m050342_c001_v0001103_t0049_u01649999022717") 2022/04/15 05:10:20 DEBUG : test.sum: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867be89_d20220415_m050340_c001_v0001103_t0006_u01649999020720") 2022/04/15 05:10:20 DEBUG : test.sum: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867be57_d20220415_m050338_c001_v0001103_t0056_u01649999018199") 2022/04/15 05:10:20 DEBUG : test.sum: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867be14_d20220415_m050335_c001_v0001103_t0029_u01649999015411") 2022/04/15 05:10:20 DEBUG : test.sum: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867bdb8_d20220415_m050330_c001_v0001103_t0058_u01649999010602") 2022/04/15 05:10:20 DEBUG : test.sum: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f106cfb8d26128b27_d20220415_m050326_c001_v0001134_t0029_u01649999006957") 2022/04/15 05:10:21 DEBUG : test.sum: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867bd50_d20220415_m050323_c001_v0001103_t0030_u01649999003832") 2022/04/15 05:10:21 DEBUG : test.sum: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867bd2a_d20220415_m050321_c001_v0001103_t0030_u01649999000245") 2022/04/15 05:10:21 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f1167d82ce7f54d98_d20220415_m050919_c001_v0001104_t0050_u01649999359975") 2022/04/15 05:10:21 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867d66f_d20220415_m050916_c001_v0001103_t0014_u01649999356722") 2022/04/15 05:10:21 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f103a11065d442a94_d20220415_m050922_c001_v0001106_t0029_u01649999362756") 2022/04/15 05:10:21 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867d655_d20220415_m050915_c001_v0001103_t0004_u01649999355437") 2022/04/15 05:10:21 DEBUG : zend.txt: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f10748c1944ac9281_d20220415_m050450_c001_v0001115_t0026_u01649999090400") 2022/04/15 05:10:21 DEBUG : zend.txt: Deleting (id "4_z0fc2ed03909d2bbb8f080e1b_f111216a3a867c1b7_d20220415_m050425_c001_v0001103_t0032_u01649999065147") "./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -fast-list" - Finished OK in 7m59.360148205s (try 1/5)