"./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -fast-list" - Starting (try 1/5) 2021/12/01 06:42:33 DEBUG : Creating backend with remote "TestB2:rclone-test-nawamit6qiladid4vovuzug2" 2021/12/01 06:42:33 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2021/12/01 06:42:34 DEBUG : Creating backend with remote "/tmp/rclone1776037373" === 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-nawamit6qiladid4vovuzug2", Local "Local file system at /tmp/rclone1776037373", Modify Window "1ms" === RUN TestMultithreadCopy/{size:131071_streams:2} 2021/12/01 06:42:38 DEBUG : file1: Starting multi-thread copy with 2 parts of size 64Ki 2021/12/01 06:42:38 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131071) size 63.999Ki starting 2021/12/01 06:42:38 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64Ki starting 2021/12/01 06:42:39 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131071) size 63.999Ki finished 2021/12/01 06:42:39 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64Ki finished 2021/12/01 06:42:39 DEBUG : file1: Finished multi-thread copy with 2 parts of size 64Ki === RUN TestMultithreadCopy/{size:131072_streams:2} 2021/12/01 06:42:40 DEBUG : file1: Starting multi-thread copy with 2 parts of size 64Ki 2021/12/01 06:42:40 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131072) size 64Ki starting 2021/12/01 06:42:40 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64Ki starting 2021/12/01 06:42:40 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131072) size 64Ki finished 2021/12/01 06:42:41 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64Ki finished 2021/12/01 06:42:41 DEBUG : file1: Finished multi-thread copy with 2 parts of size 64Ki === RUN TestMultithreadCopy/{size:131073_streams:2} 2021/12/01 06:42:43 DEBUG : file1: Starting multi-thread copy with 2 parts of size 128Ki 2021/12/01 06:42:43 DEBUG : file1: multi-thread copy: stream 2/2 (131072-131073) size 1 starting 2021/12/01 06:42:43 DEBUG : file1: multi-thread copy: stream 1/2 (0-131072) size 128Ki starting 2021/12/01 06:42:43 DEBUG : file1: multi-thread copy: stream 2/2 (131072-131073) size 1 finished 2021/12/01 06:42:43 DEBUG : file1: multi-thread copy: stream 1/2 (0-131072) size 128Ki finished 2021/12/01 06:42:43 DEBUG : file1: Finished multi-thread copy with 2 parts of size 128Ki --- PASS: TestMultithreadCopy (9.79s) --- PASS: TestMultithreadCopy/{size:131071_streams:2} (4.58s) --- PASS: TestMultithreadCopy/{size:131072_streams:2} (1.73s) --- PASS: TestMultithreadCopy/{size:131073_streams:2} (2.83s) === RUN TestSizeDiffers --- PASS: TestSizeDiffers (0.00s) === RUN TestReOpen === RUN TestReOpen/Seek === RUN TestReOpen/Seek/Basics === RUN TestReOpen/Seek/ErrorAtStart === RUN TestReOpen/Seek/WithErrors 2021/12/01 06:42:44 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/10: test error 2021/12/01 06:42:44 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/10: test error 2021/12/01 06:42:44 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/10: test error === RUN TestReOpen/Seek/TooManyErrors 2021/12/01 06:42:44 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/3: test error 2021/12/01 06:42:44 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/3: test error 2021/12/01 06:42:44 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/3: test error 2021/12/01 06:42:44 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 2021/12/01 06:42:44 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/10: test error 2021/12/01 06:42:44 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/10: test error 2021/12/01 06:42:44 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/10: test error === RUN TestReOpen/Range/TooManyErrors 2021/12/01 06:42:44 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/3: test error 2021/12/01 06:42:44 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/3: test error 2021/12/01 06:42:44 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/3: test error 2021/12/01 06:42:44 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-nawamit6qiladid4vovuzug2", Local "Local file system at /tmp/rclone1776037373", 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.68s) --- PASS: TestCheck/1 (0.17s) --- PASS: TestCheck/2 (0.17s) --- PASS: TestCheck/3 (0.17s) --- PASS: TestCheck/4 (0.33s) --- PASS: TestCheck/5 (0.18s) --- PASS: TestCheck/6 (0.17s) --- PASS: TestCheck/7 (0.17s) === RUN TestCheckFsError 2021/12/01 06:42:56 DEBUG : Creating backend with remote "non-existent" 2021/12/01 06:42:56 DEBUG : Creating backend with remote "non-existent" 2021/12/01 06:42:56 DEBUG : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/non-existent: Waiting for checks to finish 2021/12/01 06:42:56 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 2021/12/01 06:42:56 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/non-existent: 2 differences found 2021/12/01 06:42:56 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-nawamit6qiladid4vovuzug2", Local "Local file system at /tmp/rclone1776037373", Modify Window "1ms" === RUN TestCheckDownload/1 === RUN TestCheckDownload/2 === RUN TestCheckDownload/3 === RUN TestCheckDownload/4 === RUN TestCheckDownload/5 === RUN TestCheckDownload/6 === RUN TestCheckDownload/7 --- PASS: TestCheckDownload (16.37s) --- PASS: TestCheckDownload/1 (0.85s) --- PASS: TestCheckDownload/2 (0.34s) --- PASS: TestCheckDownload/3 (0.34s) --- PASS: TestCheckDownload/4 (0.52s) --- PASS: TestCheckDownload/5 (0.35s) --- PASS: TestCheckDownload/6 (0.35s) --- PASS: TestCheckDownload/7 (0.35s) === RUN TestCheckSizeOnly run.go:181: Remote "B2 bucket rclone-test-nawamit6qiladid4vovuzug2", Local "Local file system at /tmp/rclone1776037373", 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 (15.08s) --- PASS: TestCheckSizeOnly/1 (0.17s) --- PASS: TestCheckSizeOnly/2 (0.17s) --- PASS: TestCheckSizeOnly/3 (0.17s) --- PASS: TestCheckSizeOnly/4 (0.17s) --- PASS: TestCheckSizeOnly/5 (0.17s) --- PASS: TestCheckSizeOnly/6 (0.17s) --- PASS: TestCheckSizeOnly/7 (0.17s) === RUN TestCheckEqualReaders --- PASS: TestCheckEqualReaders (0.00s) === RUN TestParseSumFile run.go:181: Remote "B2 bucket rclone-test-nawamit6qiladid4vovuzug2", Local "Local file system at /tmp/rclone1776037373", Modify Window "1ms" 2021/12/01 06:43:29 NOTICE: test.sum: improperly formatted checksum line 4 2021/12/01 06:43:29 NOTICE: test.sum: improperly formatted checksum line 5 2021/12/01 06:43:29 NOTICE: test.sum: improperly formatted checksum line 6 2021/12/01 06:43:29 NOTICE: test.sum: 2 warning(s) suppressed... 2021/12/01 06:43:30 NOTICE: test.sum: improperly formatted checksum line 4 2021/12/01 06:43:30 NOTICE: test.sum: improperly formatted checksum line 5 2021/12/01 06:43:30 NOTICE: test.sum: improperly formatted checksum line 6 2021/12/01 06:43:30 NOTICE: test.sum: 2 warning(s) suppressed... --- PASS: TestParseSumFile (3.70s) === RUN TestCheckSum run.go:181: Remote "B2 bucket rclone-test-nawamit6qiladid4vovuzug2", Local "Local file system at /tmp/rclone1776037373", Modify Window "1ms" 2021/12/01 06:43:31 DEBUG : Creating backend with remote "TestB2:rclone-test-nawamit6qiladid4vovuzug2/data" 2021/12/01 06:43:32 DEBUG : Couldn't decode error response: EOF check_test.go:356: B2 bucket rclone-test-nawamit6qiladid4vovuzug2 path data lacks md5, skipping --- SKIP: TestCheckSum (1.24s) === RUN TestCheckSumDownload run.go:181: Remote "B2 bucket rclone-test-nawamit6qiladid4vovuzug2", Local "Local file system at /tmp/rclone1776037373", Modify Window "1ms" 2021/12/01 06:43:32 DEBUG : Creating backend with remote "TestB2:rclone-test-nawamit6qiladid4vovuzug2/data" 2021/12/01 06:43:33 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 (22.03s) --- PASS: TestCheckSumDownload/subtest1 (0.88s) --- PASS: TestCheckSumDownload/subtest2 (0.69s) --- PASS: TestCheckSumDownload/subtest3 (0.87s) --- PASS: TestCheckSumDownload/subtest4 (0.73s) --- PASS: TestCheckSumDownload/subtest5 (0.72s) --- PASS: TestCheckSumDownload/subtest6 (0.72s) --- PASS: TestCheckSumDownload/subtest7 (0.73s) === RUN TestDeduplicateInteractive run.go:181: Remote "B2 bucket rclone-test-nawamit6qiladid4vovuzug2", Local "Local file system at /tmp/rclone1776037373", 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-nawamit6qiladid4vovuzug2", Local "Local file system at /tmp/rclone1776037373", 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-nawamit6qiladid4vovuzug2", Local "Local file system at /tmp/rclone1776037373", 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-nawamit6qiladid4vovuzug2", Local "Local file system at /tmp/rclone1776037373", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateFirst (0.37s) === RUN TestDeduplicateNewest run.go:181: Remote "B2 bucket rclone-test-nawamit6qiladid4vovuzug2", Local "Local file system at /tmp/rclone1776037373", 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-nawamit6qiladid4vovuzug2", Local "Local file system at /tmp/rclone1776037373", Modify Window "1ms" 2021/12/01 06:44:03 INFO : B2 bucket rclone-test-nawamit6qiladid4vovuzug2: Looking for duplicate sha1 hashes using newest mode. 2021/12/01 06:44:03 NOTICE: a802765e5e2e5a69f2e8daebcd1ed98f0a6203a2: Found 3 files with duplicate sha1 hashes 2021/12/01 06:44:05 INFO : one: Deleted 2021/12/01 06:44:06 INFO : also/one: Deleted 2021/12/01 06:44:06 NOTICE: a802765e5e2e5a69f2e8daebcd1ed98f0a6203a2: Deleted 2 extra copies --- PASS: TestDeduplicateNewestByHash (11.46s) === RUN TestDeduplicateOldest run.go:181: Remote "B2 bucket rclone-test-nawamit6qiladid4vovuzug2", Local "Local file system at /tmp/rclone1776037373", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateOldest (0.38s) === RUN TestDeduplicateLargest run.go:181: Remote "B2 bucket rclone-test-nawamit6qiladid4vovuzug2", Local "Local file system at /tmp/rclone1776037373", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateLargest (0.34s) === RUN TestDeduplicateSmallest run.go:181: Remote "B2 bucket rclone-test-nawamit6qiladid4vovuzug2", Local "Local file system at /tmp/rclone1776037373", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSmallest (0.39s) === RUN TestDeduplicateRename run.go:181: Remote "B2 bucket rclone-test-nawamit6qiladid4vovuzug2", Local "Local file system at /tmp/rclone1776037373", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateRename (0.34s) === RUN TestMergeDirs run.go:181: Remote "B2 bucket rclone-test-nawamit6qiladid4vovuzug2", Local "Local file system at /tmp/rclone1776037373", 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-nawamit6qiladid4vovuzug2", Local "Local file system at /tmp/rclone1776037373", Modify Window "1ms" 2021/12/01 06:44:19 DEBUG : a.txt: Excluded 2021/12/01 06:44:19 DEBUG : sub dir/hello world: Excluded 2021/12/01 06:44:19 DEBUG : sub dir/hello world2: Excluded 2021/12/01 06:44:20 DEBUG : sub dir/hello world: Excluded 2021/12/01 06:44:20 DEBUG : sub dir/hello world2: Excluded 2021/12/01 06:44:20 DEBUG : sub dir/ignore dir: Excluded 2021/12/01 06:44:20 DEBUG : Couldn't decode error response: EOF 2021/12/01 06:44:21 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSorted (17.64s) === RUN TestListJSON run.go:181: Remote "B2 bucket rclone-test-nawamit6qiladid4vovuzug2", Local "Local file system at /tmp/rclone1776037373", 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 2021/12/01 06:44:30 ERROR : file1: Failed to read hash: hash type not supported --- PASS: TestListJSON (5.90s) --- PASS: TestListJSON/Default (0.17s) --- PASS: TestListJSON/FilesOnly (0.17s) --- PASS: TestListJSON/DirsOnly (0.17s) --- PASS: TestListJSON/Recurse (0.17s) --- PASS: TestListJSON/SubDir (0.17s) --- PASS: TestListJSON/NoModTime (0.18s) --- PASS: TestListJSON/NoMimeType (0.20s) --- PASS: TestListJSON/ShowHash (0.17s) --- PASS: TestListJSON/HashTypes (0.17s) === RUN TestStatJSON run.go:181: Remote "B2 bucket rclone-test-nawamit6qiladid4vovuzug2", Local "Local file system at /tmp/rclone1776037373", Modify Window "1ms" === RUN TestStatJSON/Root === RUN TestStatJSON/RootFilesOnly === RUN TestStatJSON/RootDirsOnly === RUN TestStatJSON/Dir 2021/12/01 06:44:36 DEBUG : Couldn't decode error response: EOF === RUN TestStatJSON/File === RUN TestStatJSON/NotFound 2021/12/01 06:44:37 DEBUG : Couldn't decode error response: EOF === RUN TestStatJSON/DirFilesOnly 2021/12/01 06:44:37 DEBUG : Couldn't decode error response: EOF === RUN TestStatJSON/FileFilesOnly === RUN TestStatJSON/NotFoundFilesOnly 2021/12/01 06:44:37 DEBUG : Couldn't decode error response: EOF === RUN TestStatJSON/DirDirsOnly === RUN TestStatJSON/FileDirsOnly === RUN TestStatJSON/NotFoundDirsOnly === RUN TestStatJSON/RootNotFound 2021/12/01 06:44:38 DEBUG : Creating backend with remote "TestB2:rclone-test-nawamit6qiladid4vovuzug2/notfound" 2021/12/01 06:44:39 DEBUG : Couldn't decode error response: EOF --- PASS: TestStatJSON (14.44s) --- PASS: TestStatJSON/Root (0.17s) --- PASS: TestStatJSON/RootFilesOnly (0.00s) --- PASS: TestStatJSON/RootDirsOnly (0.18s) --- PASS: TestStatJSON/Dir (0.85s) --- PASS: TestStatJSON/File (0.17s) --- PASS: TestStatJSON/NotFound (0.34s) --- PASS: TestStatJSON/DirFilesOnly (0.17s) --- PASS: TestStatJSON/FileFilesOnly (0.21s) --- PASS: TestStatJSON/NotFoundFilesOnly (0.17s) --- PASS: TestStatJSON/DirDirsOnly (0.17s) --- PASS: TestStatJSON/FileDirsOnly (0.20s) --- PASS: TestStatJSON/NotFoundDirsOnly (0.17s) --- PASS: TestStatJSON/RootNotFound (1.26s) === RUN TestMkdir run.go:181: Remote "B2 bucket rclone-test-nawamit6qiladid4vovuzug2", Local "Local file system at /tmp/rclone1776037373", Modify Window "1ms" 2021/12/01 06:44:47 DEBUG : B2 bucket rclone-test-nawamit6qiladid4vovuzug2: Making directory 2021/12/01 06:44:47 DEBUG : B2 bucket rclone-test-nawamit6qiladid4vovuzug2: Making directory --- PASS: TestMkdir (0.52s) === RUN TestLsd run.go:181: Remote "B2 bucket rclone-test-nawamit6qiladid4vovuzug2", Local "Local file system at /tmp/rclone1776037373", Modify Window "1ms" --- PASS: TestLsd (5.64s) === RUN TestLs run.go:181: Remote "B2 bucket rclone-test-nawamit6qiladid4vovuzug2", Local "Local file system at /tmp/rclone1776037373", Modify Window "1ms" --- PASS: TestLs (4.04s) === RUN TestLsWithFilesFrom run.go:181: Remote "B2 bucket rclone-test-nawamit6qiladid4vovuzug2", Local "Local file system at /tmp/rclone1776037373", Modify Window "1ms" 2021/12/01 06:45:05 DEBUG : empty space: Excluded from sync (and deletion) 2021/12/01 06:45:05 DEBUG : Couldn't decode error response: EOF --- PASS: TestLsWithFilesFrom (13.43s) === RUN TestLsLong run.go:181: Remote "B2 bucket rclone-test-nawamit6qiladid4vovuzug2", Local "Local file system at /tmp/rclone1776037373", Modify Window "1ms" --- PASS: TestLsLong (8.63s) === RUN TestHashSums run.go:181: Remote "B2 bucket rclone-test-nawamit6qiladid4vovuzug2", Local "Local file system at /tmp/rclone1776037373", Modify Window "1ms" === RUN TestHashSums/Sha1 === RUN TestHashSums/Sha1Download --- PASS: TestHashSums (6.39s) --- PASS: TestHashSums/Sha1 (0.17s) --- PASS: TestHashSums/Sha1Download (0.87s) === RUN TestHashSumsWithErrors 2021/12/01 06:45:26 DEBUG : Creating backend with remote ":memory:" 2021/12/01 06:45:26 ERROR : file1: hash unsupported: hash type not supported --- PASS: TestHashSumsWithErrors (0.00s) === RUN TestHashStream 2021/12/01 06:45:26 DEBUG : Creating md5 hash of 0 bytes read from input stream 2021/12/01 06:45:26 DEBUG : Creating md5 hash of 0 bytes read from input stream 2021/12/01 06:45:26 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2021/12/01 06:45:26 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2021/12/01 06:45:26 DEBUG : Creating md5 hash of 12 bytes read from input stream 2021/12/01 06:45:26 DEBUG : Creating md5 hash of 12 bytes read from input stream 2021/12/01 06:45:26 DEBUG : Creating sha1 hash of 12 bytes read from input stream 2021/12/01 06:45:26 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-nawamit6qiladid4vovuzug2", Local "Local file system at /tmp/rclone1776037373", Modify Window "1ms" --- PASS: TestCount (7.47s) === RUN TestDelete run.go:181: Remote "B2 bucket rclone-test-nawamit6qiladid4vovuzug2", Local "Local file system at /tmp/rclone1776037373", Modify Window "1ms" 2021/12/01 06:45:33 DEBUG : small: Clearing upload URL because of error: Post "https://pod-000-1038-17.backblaze.com/b2api/v1/b2_upload_file/4f220dd3b0bd7b4b7fd80e1b/c001_v0001038_t0020": EOF 2021/12/01 06:45:33 DEBUG : pacer: low level retry 1/1 (error Post "https://pod-000-1038-17.backblaze.com/b2api/v1/b2_upload_file/4f220dd3b0bd7b4b7fd80e1b/c001_v0001038_t0020": EOF) 2021/12/01 06:45:33 DEBUG : pacer: Rate limited, increasing sleep to 20ms run.go:283: Retry Put of "small" to B2 bucket rclone-test-nawamit6qiladid4vovuzug2: 1/10 (Post "https://pod-000-1038-17.backblaze.com/b2api/v1/b2_upload_file/4f220dd3b0bd7b4b7fd80e1b/c001_v0001038_t0020": EOF) 2021/12/01 06:45:36 DEBUG : pacer: Reducing sleep to 10ms 2021/12/01 06:45:38 DEBUG : Waiting for deletions to finish 2021/12/01 06:45:38 DEBUG : large: Excluded from sync (and deletion) 2021/12/01 06:45:39 INFO : small: Deleted 2021/12/01 06:45:40 INFO : medium: Deleted --- PASS: TestDelete (8.70s) === RUN TestRetry 2021/12/01 06:45:42 DEBUG : Received error: EOF - low level retry 1/5 2021/12/01 06:45:42 DEBUG : Received error: EOF - low level retry 2/5 2021/12/01 06:45:42 DEBUG : Received error: EOF - low level retry 1/5 2021/12/01 06:45:42 DEBUG : Received error: EOF - low level retry 2/5 2021/12/01 06:45:42 DEBUG : Received error: EOF - low level retry 3/5 2021/12/01 06:45:42 DEBUG : Received error: EOF - low level retry 4/5 2021/12/01 06:45:42 DEBUG : Received error: EOF - low level retry 5/5 --- PASS: TestRetry (0.00s) === RUN TestCat run.go:181: Remote "B2 bucket rclone-test-nawamit6qiladid4vovuzug2", Local "Local file system at /tmp/rclone1776037373", Modify Window "1ms" --- PASS: TestCat (8.57s) === RUN TestPurge 2021/12/01 06:45:51 DEBUG : Creating backend with remote "TestB2:rclone-test-vemiwih4dogayut5gesopoc4" 2021/12/01 06:45:51 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2021/12/01 06:45:51 DEBUG : Creating backend with remote "/tmp/rclone2750559727" run.go:181: Remote "B2 bucket rclone-test-vemiwih4dogayut5gesopoc4", Local "Local file system at /tmp/rclone2750559727", Modify Window "1ms" 2021/12/01 06:45:53 DEBUG : A2: Making directory 2021/12/01 06:45:53 DEBUG : A1/B2: Making directory 2021/12/01 06:45:53 DEBUG : A1/B2/C2: Making directory 2021/12/01 06:45:53 DEBUG : A1/B1/C3: Making directory 2021/12/01 06:45:53 DEBUG : A3: Making directory 2021/12/01 06:45:53 DEBUG : A3/B3: Making directory 2021/12/01 06:45:53 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" 2021/12/01 06:45:54 DEBUG : A1/B1/C1/one: Deleting (id "4_zdfb20dc3b0ed7b4b7fd80e1b_f102d9470a3562f36_d20211201_m064553_c001_v0001025_t0011") 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" 2021/12/01 06:45:54 DEBUG : A1/two: Deleting (id "4_zdfb20dc3b0ed7b4b7fd80e1b_f102d9470a3562f38_d20211201_m064553_c001_v0001025_t0034") 2021/12/01 06:45:55 DEBUG : B2 bucket rclone-test-vemiwih4dogayut5gesopoc4: Purge remote 2021/12/01 06:45:55 purge failed: directory not found --- PASS: TestPurge (4.73s) === RUN TestRmdirsNoLeaveRoot run.go:181: Remote "B2 bucket rclone-test-nawamit6qiladid4vovuzug2", Local "Local file system at /tmp/rclone1776037373", Modify Window "1ms" 2021/12/01 06:45:56 DEBUG : A2: Making directory 2021/12/01 06:45:56 DEBUG : A1/B2: Making directory 2021/12/01 06:45:56 DEBUG : A1/B2/C2: Making directory 2021/12/01 06:45:56 DEBUG : A1/B1/C3: Making directory 2021/12/01 06:45:56 DEBUG : A3: Making directory 2021/12/01 06:45:56 DEBUG : A3/B3: Making directory 2021/12/01 06:45:56 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" 2021/12/01 06:45:58 INFO : A3/B3/C4: Removing directory fstest.go:251: Filtering empty directory "A2" fstest.go:251: Filtering empty directory "A1/B2" fstest.go:251: Filtering empty directory "A1/B2/C2" fstest.go:251: Filtering empty directory "A1/B1/C3" fstest.go:251: Filtering empty directory "A3" fstest.go:251: Filtering empty directory "A3/B3" --- PASS: TestRmdirsNoLeaveRoot (5.27s) === RUN TestRmdirsLeaveRoot run.go:181: Remote "B2 bucket rclone-test-nawamit6qiladid4vovuzug2", Local "Local file system at /tmp/rclone1776037373", Modify Window "1ms" 2021/12/01 06:46:01 DEBUG : A1: Making directory 2021/12/01 06:46:01 DEBUG : A1/B1: Making directory 2021/12/01 06:46:01 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.85s) === RUN TestRmdirsWithFilter run.go:181: Remote "B2 bucket rclone-test-nawamit6qiladid4vovuzug2", Local "Local file system at /tmp/rclone1776037373", Modify Window "1ms" 2021/12/01 06:46:01 DEBUG : A1: Making directory 2021/12/01 06:46:01 DEBUG : A1/B1: Making directory 2021/12/01 06:46:01 DEBUG : A1/B1/C1: Making directory fstest.go:251: Filtering empty directory "A1" fstest.go:251: Filtering empty directory "A1/B1" fstest.go:251: Filtering empty directory "A1/B1/C1" fstest.go:251: Filtering empty directory "A1" --- PASS: TestRmdirsWithFilter (0.85s) === RUN TestCopyURL run.go:181: Remote "B2 bucket rclone-test-nawamit6qiladid4vovuzug2", Local "Local file system at /tmp/rclone1776037373", Modify Window "1ms" 2021/12/01 06:46:05 DEBUG : filename.txt: File name found in url --- PASS: TestCopyURL (7.52s) === RUN TestCopyURLToWriter --- PASS: TestCopyURLToWriter (0.00s) === RUN TestMoveFile run.go:181: Remote "B2 bucket rclone-test-nawamit6qiladid4vovuzug2", Local "Local file system at /tmp/rclone1776037373", Modify Window "1ms" 2021/12/01 06:46:11 DEBUG : Couldn't decode error response: EOF 2021/12/01 06:46:11 DEBUG : file1: Need to transfer - File not found at Destination 2021/12/01 06:46:11 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2021/12/01 06:46:11 INFO : file1: Copied (new) to: sub/file2 2021/12/01 06:46:11 INFO : file1: Deleted 2021/12/01 06:46:12 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/01 06:46:12 DEBUG : file1: Unchanged skipping 2021/12/01 06:46:12 INFO : file1: Deleted 2021/12/01 06:46:12 DEBUG : B2 bucket rclone-test-nawamit6qiladid4vovuzug2: don't need to copy/move sub/file2, it is already at target location --- PASS: TestMoveFile (3.79s) === RUN TestMoveFileWithIgnoreExisting run.go:181: Remote "B2 bucket rclone-test-nawamit6qiladid4vovuzug2", Local "Local file system at /tmp/rclone1776037373", Modify Window "1ms" 2021/12/01 06:46:14 DEBUG : Couldn't decode error response: EOF 2021/12/01 06:46:14 DEBUG : file1: Need to transfer - File not found at Destination 2021/12/01 06:46:14 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2021/12/01 06:46:14 INFO : file1: Copied (new) 2021/12/01 06:46:14 INFO : file1: Deleted 2021/12/01 06:46:15 DEBUG : file1: Destination exists, skipping 2021/12/01 06:46:15 DEBUG : file1: Not removing source file as destination file exists and --ignore-existing is set --- PASS: TestMoveFileWithIgnoreExisting (2.65s) === RUN TestCaseInsensitiveMoveFile run.go:181: Remote "B2 bucket rclone-test-nawamit6qiladid4vovuzug2", Local "Local file system at /tmp/rclone1776037373", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFile (0.34s) === RUN TestMoveFileBackupDir run.go:181: Remote "B2 bucket rclone-test-nawamit6qiladid4vovuzug2", Local "Local file system at /tmp/rclone1776037373", Modify Window "1ms" 2021/12/01 06:46:18 DEBUG : Creating backend with remote "TestB2:rclone-test-nawamit6qiladid4vovuzug2/backup" 2021/12/01 06:46:19 DEBUG : Couldn't decode error response: EOF 2021/12/01 06:46:19 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18) 2021/12/01 06:46:19 DEBUG : Couldn't decode error response: EOF 2021/12/01 06:46:23 DEBUG : dst/file1: sha1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK 2021/12/01 06:46:23 INFO : dst/file1: Copied (server-side copy) 2021/12/01 06:46:23 DEBUG : pacer: low level retry 1/10 (error Post "https://api001.backblazeb2.com/b2api/v1/b2_hide_file": EOF) 2021/12/01 06:46:23 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2021/12/01 06:46:23 DEBUG : pacer: Reducing sleep to 10ms 2021/12/01 06:46:23 INFO : dst/file1: Deleted 2021/12/01 06:46:24 DEBUG : dst/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2021/12/01 06:46:24 INFO : dst/file1: Copied (new) 2021/12/01 06:46:24 INFO : dst/file1: Deleted --- PASS: TestMoveFileBackupDir (10.30s) === RUN TestCopyFile run.go:181: Remote "B2 bucket rclone-test-nawamit6qiladid4vovuzug2", Local "Local file system at /tmp/rclone1776037373", Modify Window "1ms" 2021/12/01 06:46:28 DEBUG : Couldn't decode error response: EOF 2021/12/01 06:46:28 DEBUG : file1: Need to transfer - File not found at Destination 2021/12/01 06:46:28 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2021/12/01 06:46:28 INFO : file1: Copied (new) to: sub/file2 2021/12/01 06:46:28 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/01 06:46:28 DEBUG : file1: Unchanged skipping 2021/12/01 06:46:29 DEBUG : B2 bucket rclone-test-nawamit6qiladid4vovuzug2: don't need to copy/move sub/file2, it is already at target location --- PASS: TestCopyFile (3.06s) === RUN TestCopyFileBackupDir run.go:181: Remote "B2 bucket rclone-test-nawamit6qiladid4vovuzug2", Local "Local file system at /tmp/rclone1776037373", Modify Window "1ms" 2021/12/01 06:46:31 DEBUG : Creating backend with remote "TestB2:rclone-test-nawamit6qiladid4vovuzug2/backup" 2021/12/01 06:46:32 DEBUG : Couldn't decode error response: EOF 2021/12/01 06:46:32 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18) 2021/12/01 06:46:33 DEBUG : Couldn't decode error response: EOF 2021/12/01 06:46:35 DEBUG : dst/file1: sha1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK 2021/12/01 06:46:35 INFO : dst/file1: Copied (server-side copy) 2021/12/01 06:46:36 INFO : dst/file1: Deleted 2021/12/01 06:46:37 DEBUG : dst/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2021/12/01 06:46:37 INFO : dst/file1: Copied (new) --- PASS: TestCopyFileBackupDir (9.78s) === RUN TestCopyFileCompareDest run.go:181: Remote "B2 bucket rclone-test-nawamit6qiladid4vovuzug2", Local "Local file system at /tmp/rclone1776037373", Modify Window "1ms" 2021/12/01 06:46:40 DEBUG : Creating backend with remote "TestB2:rclone-test-nawamit6qiladid4vovuzug2/dst" 2021/12/01 06:46:41 DEBUG : Couldn't decode error response: EOF 2021/12/01 06:46:41 DEBUG : Couldn't decode error response: EOF 2021/12/01 06:46:41 DEBUG : Creating backend with remote "TestB2:rclone-test-nawamit6qiladid4vovuzug2/CompareDest" 2021/12/01 06:46:42 DEBUG : Couldn't decode error response: EOF 2021/12/01 06:46:42 DEBUG : Couldn't decode error response: EOF 2021/12/01 06:46:42 DEBUG : one: Need to transfer - File not found at Destination 2021/12/01 06:46:45 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/12/01 06:46:45 INFO : one: Copied (new) 2021/12/01 06:46:46 DEBUG : Couldn't decode error response: EOF 2021/12/01 06:46:46 DEBUG : one: Sizes differ (src 5 vs dst 3) 2021/12/01 06:46:47 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2021/12/01 06:46:47 INFO : one: Copied (replaced existing) 2021/12/01 06:46:52 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/12/01 06:46:52 DEBUG : one: Destination found in --compare-dest, skipping 2021/12/01 06:46:53 DEBUG : Couldn't decode error response: EOF 2021/12/01 06:46:53 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/12/01 06:46:53 DEBUG : two: Destination found in --compare-dest, skipping 2021/12/01 06:46:54 DEBUG : Couldn't decode error response: EOF 2021/12/01 06:46:54 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/12/01 06:46:54 DEBUG : two: Destination found in --compare-dest, skipping 2021/12/01 06:46:55 DEBUG : Couldn't decode error response: EOF 2021/12/01 06:46:55 DEBUG : two: Sizes differ (src 5 vs dst 3) 2021/12/01 06:46:55 DEBUG : two: Need to transfer - File not found at Destination 2021/12/01 06:46:56 DEBUG : two: sha1 = a4be8877ad88f3109a6411985a1f359e10e2d930 OK 2021/12/01 06:46:56 INFO : two: Copied (new) --- PASS: TestCopyFileCompareDest (21.67s) === RUN TestCopyFileCopyDest run.go:181: Remote "B2 bucket rclone-test-nawamit6qiladid4vovuzug2", Local "Local file system at /tmp/rclone1776037373", Modify Window "1ms" 2021/12/01 06:47:01 DEBUG : Creating backend with remote "TestB2:rclone-test-nawamit6qiladid4vovuzug2/dst" 2021/12/01 06:47:03 DEBUG : Couldn't decode error response: EOF 2021/12/01 06:47:03 DEBUG : Couldn't decode error response: EOF 2021/12/01 06:47:03 DEBUG : Creating backend with remote "TestB2:rclone-test-nawamit6qiladid4vovuzug2/CopyDest" 2021/12/01 06:47:03 DEBUG : Couldn't decode error response: EOF 2021/12/01 06:47:04 DEBUG : Couldn't decode error response: EOF 2021/12/01 06:47:04 DEBUG : one: Need to transfer - File not found at Destination 2021/12/01 06:47:06 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/12/01 06:47:06 INFO : one: Copied (new) 2021/12/01 06:47:07 DEBUG : Couldn't decode error response: EOF 2021/12/01 06:47:07 DEBUG : one: Sizes differ (src 5 vs dst 3) 2021/12/01 06:47:07 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2021/12/01 06:47:07 INFO : one: Copied (replaced existing) 2021/12/01 06:47:11 DEBUG : Creating backend with remote "TestB2:rclone-test-nawamit6qiladid4vovuzug2/BackupDir" 2021/12/01 06:47:12 DEBUG : Couldn't decode error response: EOF 2021/12/01 06:47:12 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/12/01 06:47:12 DEBUG : one: Sizes differ (src 5 vs dst 3) 2021/12/01 06:47:12 DEBUG : Couldn't decode error response: EOF 2021/12/01 06:47:14 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/12/01 06:47:14 INFO : one: Copied (server-side copy) 2021/12/01 06:47:15 INFO : one: Deleted 2021/12/01 06:47:17 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2021/12/01 06:47:17 INFO : one: Copied (server-side copy) 2021/12/01 06:47:17 DEBUG : one: Destination found in --copy-dest, using server-side copy 2021/12/01 06:47:20 DEBUG : Couldn't decode error response: EOF 2021/12/01 06:47:20 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/12/01 06:47:21 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2021/12/01 06:47:21 INFO : two: Copied (server-side copy) 2021/12/01 06:47:21 DEBUG : two: Destination found in --copy-dest, using server-side copy 2021/12/01 06:47:21 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/12/01 06:47:21 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/12/01 06:47:21 DEBUG : two: Unchanged skipping 2021/12/01 06:47:23 DEBUG : Couldn't decode error response: EOF 2021/12/01 06:47:23 DEBUG : three: Sizes differ (src 7 vs dst 5) 2021/12/01 06:47:23 DEBUG : three: Destination not found in --copy-dest 2021/12/01 06:47:23 DEBUG : three: Need to transfer - File not found at Destination 2021/12/01 06:47:24 DEBUG : three: sha1 = 370e613335f3cf2f19b2a2d35a86402f55c659f8 OK 2021/12/01 06:47:24 INFO : three: Copied (new) --- PASS: TestCopyFileCopyDest (30.98s) === 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-nawamit6qiladid4vovuzug2", Local "Local file system at /tmp/rclone1776037373", Modify Window "1ms" 2021/12/01 06:47:36 DEBUG : A1/B2: Making directory 2021/12/01 06:47:36 DEBUG : A1/B1/C3: Making directory fstest.go:251: Filtering empty directory "A1/B2" fstest.go:251: Filtering empty directory "A1/B1/C3" 2021/12/01 06:47:37 DEBUG : Couldn't decode error response: EOF 2021/12/01 06:47:37 DEBUG : Couldn't decode error response: EOF 2021/12/01 06:47:37 DEBUG : Couldn't decode error response: EOF 2021/12/01 06:47:37 DEBUG : Couldn't decode error response: EOF 2021/12/01 06:47:38 DEBUG : A1/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/12/01 06:47:38 INFO : A1/B1/three: Copied (server-side copy) to: A2/B1/three 2021/12/01 06:47:38 INFO : A1/B1/three: Deleted 2021/12/01 06:47:38 DEBUG : A1/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2021/12/01 06:47:38 INFO : A1/B1/C1/four: Copied (server-side copy) to: A2/B1/C1/four 2021/12/01 06:47:38 DEBUG : Couldn't decode error response: EOF 2021/12/01 06:47:38 DEBUG : A1/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/12/01 06:47:38 INFO : A1/one: Copied (server-side copy) to: A2/one 2021/12/01 06:47:38 INFO : A1/one: Deleted 2021/12/01 06:47:38 DEBUG : A1/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2021/12/01 06:47:38 INFO : A1/B1/C2/five: Copied (server-side copy) to: A2/B1/C2/five 2021/12/01 06:47:39 DEBUG : A1/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2021/12/01 06:47:39 INFO : A1/two: Copied (server-side copy) to: A2/two 2021/12/01 06:47:39 INFO : A1/B1/C2/five: Deleted 2021/12/01 06:47:39 INFO : A1/B1/C1/four: Deleted 2021/12/01 06:47:42 INFO : A1/two: Deleted fstest.go:251: Filtering empty directory "A2/B2" fstest.go:251: Filtering empty directory "A2/B1/C3" 2021/12/01 06:47:43 DEBUG : Couldn't decode error response: EOF 2021/12/01 06:47:43 DEBUG : Couldn't decode error response: EOF 2021/12/01 06:47:43 DEBUG : Couldn't decode error response: EOF 2021/12/01 06:47:43 DEBUG : A2/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2021/12/01 06:47:43 INFO : A2/B1/C1/four: Copied (server-side copy) to: A3/B1/C1/four 2021/12/01 06:47:43 DEBUG : Couldn't decode error response: EOF 2021/12/01 06:47:43 INFO : A2/B1/C1/four: Deleted 2021/12/01 06:47:43 DEBUG : A2/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/12/01 06:47:43 INFO : A2/one: Copied (server-side copy) to: A3/one 2021/12/01 06:47:43 DEBUG : Couldn't decode error response: EOF 2021/12/01 06:47:44 DEBUG : A2/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/12/01 06:47:44 INFO : A2/B1/three: Copied (server-side copy) to: A3/B1/three 2021/12/01 06:47:44 INFO : A2/one: Deleted 2021/12/01 06:47:44 INFO : A2/B1/three: Deleted 2021/12/01 06:47:45 DEBUG : A2/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2021/12/01 06:47:45 INFO : A2/B1/C2/five: Copied (server-side copy) to: A3/B1/C2/five 2021/12/01 06:47:45 INFO : A2/B1/C2/five: Deleted 2021/12/01 06:47:46 DEBUG : A2/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2021/12/01 06:47:46 INFO : A2/two: Copied (server-side copy) to: A3/two 2021/12/01 06:47:49 INFO : A2/two: Deleted fstest.go:251: Filtering empty directory "A3/B2" fstest.go:251: Filtering empty directory "A3/B1/C3" --- PASS: TestDirMove (20.90s) === RUN TestGetFsInfo run.go:181: Remote "B2 bucket rclone-test-nawamit6qiladid4vovuzug2", Local "Local file system at /tmp/rclone1776037373", Modify Window "1ms" --- PASS: TestGetFsInfo (0.35s) === RUN TestRcat === RUN TestRcat/withChecksum=false,ignoreChecksum=false === CONT TestRcat run.go:181: Remote "B2 bucket rclone-test-nawamit6qiladid4vovuzug2", Local "Local file system at /tmp/rclone1776037373", Modify Window "1ms" 2021/12/01 06:47:54 DEBUG : B2 bucket rclone-test-nawamit6qiladid4vovuzug2: File to upload is small (34 bytes), uploading instead of streaming 2021/12/01 06:47:57 DEBUG : no_checksum_small_file_from_pipe: sha1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK 2021/12/01 06:47:57 INFO : no_checksum_small_file_from_pipe: Copied (new) 2021/12/01 06:47:57 DEBUG : no_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2021/12/01 06:47:58 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-nawamit6qiladid4vovuzug2", Local "Local file system at /tmp/rclone1776037373", Modify Window "1ms" 2021/12/01 06:48:01 DEBUG : B2 bucket rclone-test-nawamit6qiladid4vovuzug2: File to upload is small (34 bytes), uploading instead of streaming 2021/12/01 06:48:02 DEBUG : with_checksum_small_file_from_pipe: sha1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK 2021/12/01 06:48:02 INFO : with_checksum_small_file_from_pipe: Copied (new) 2021/12/01 06:48:02 DEBUG : with_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2021/12/01 06:48:06 DEBUG : with_checksum_big_file_from_pipe: sha1 = d0080f73aa8b494570ad63df1beb25c01ea9eea2 OK 2021/12/01 06:48:06 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-nawamit6qiladid4vovuzug2", Local "Local file system at /tmp/rclone1776037373", Modify Window "1ms" 2021/12/01 06:48:07 DEBUG : B2 bucket rclone-test-nawamit6qiladid4vovuzug2: File to upload is small (34 bytes), uploading instead of streaming 2021/12/01 06:48:07 INFO : ignore_checksum_small_file_from_pipe: Copied (new) 2021/12/01 06:48:07 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2021/12/01 06:48:11 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-nawamit6qiladid4vovuzug2", Local "Local file system at /tmp/rclone1776037373", Modify Window "1ms" 2021/12/01 06:48:15 DEBUG : B2 bucket rclone-test-nawamit6qiladid4vovuzug2: File to upload is small (34 bytes), uploading instead of streaming 2021/12/01 06:48:16 INFO : ignore_checksum_small_file_from_pipe: Copied (new) 2021/12/01 06:48:16 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2021/12/01 06:48:19 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical --- PASS: TestRcat (28.22s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (7.58s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (5.52s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (7.92s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (7.21s) === RUN TestRcatSize run.go:181: Remote "B2 bucket rclone-test-nawamit6qiladid4vovuzug2", Local "Local file system at /tmp/rclone1776037373", Modify Window "1ms" 2021/12/01 06:48:22 DEBUG : B2 bucket rclone-test-nawamit6qiladid4vovuzug2: File to upload is small (60 bytes), uploading instead of streaming 2021/12/01 06:48:23 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/12/01 06:48:23 INFO : potato2: Copied (new) --- PASS: TestRcatSize (5.88s) === RUN TestCopyFileMaxTransfer run.go:181: Remote "B2 bucket rclone-test-nawamit6qiladid4vovuzug2", Local "Local file system at /tmp/rclone1776037373", Modify Window "1ms" 2021/12/01 06:48:28 DEBUG : Couldn't decode error response: EOF 2021/12/01 06:48:28 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination 2021/12/01 06:48:29 DEBUG : TestCopyFileMaxTransfer/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2021/12/01 06:48:29 INFO : TestCopyFileMaxTransfer/file1: Copied (new) 2021/12/01 06:48:30 DEBUG : Couldn't decode error response: EOF 2021/12/01 06:48:30 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination 2021/12/01 06:48:30 ERROR : TestCopyFileMaxTransfer/file2: Failed to copy: Post "https://pod-000-1036-02.backblaze.com/b2api/v1/b2_upload_file/4f220dd3b0bd7b4b7fd80e1b/c001_v0001036_t0048": Max transfer limit reached as set by --max-transfer 2021/12/01 06:48:30 DEBUG : Couldn't decode error response: EOF 2021/12/01 06:48:30 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination 2021/12/01 06:48:30 DEBUG : Couldn't decode error response: EOF 2021/12/01 06:48:30 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination 2021/12/01 06:48:32 DEBUG : TestCopyFileMaxTransfer/file4: sha1 = 60721e7668173e109728190ed28e8b04cc962b7b OK 2021/12/01 06:48:32 INFO : TestCopyFileMaxTransfer/file4: Copied (new) --- PASS: TestCopyFileMaxTransfer (6.05s) === RUN TestTouchDir run.go:181: Remote "B2 bucket rclone-test-nawamit6qiladid4vovuzug2", Local "Local file system at /tmp/rclone1776037373", Modify Window "1ms" 2021/12/01 06:48:41 DEBUG : B2 bucket rclone-test-nawamit6qiladid4vovuzug2: Touching "empty space" 2021/12/01 06:48:43 DEBUG : B2 bucket rclone-test-nawamit6qiladid4vovuzug2: Touching "potato2" 2021/12/01 06:48:44 DEBUG : B2 bucket rclone-test-nawamit6qiladid4vovuzug2: Touching "sub dir/potato3" --- PASS: TestTouchDir (12.49s) === 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 2021/12/01 06:48:46 DEBUG : B2 bucket rclone-test-nawamit6qiladid4vovuzug2: Purge remote 2021/12/01 06:48:47 DEBUG : A1/B1/C1/four: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f113e6c3fccbdaf06_d20211201_m064738_c001_v0001117_t0045") 2021/12/01 06:48:47 DEBUG : A1/B1/C1/four: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f102b5255c1be4e4f_d20211201_m064735_c001_v0001036_t0004") 2021/12/01 06:48:47 DEBUG : A1/B1/C1/one: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f108233f87a3e64a7_d20211201_m064559_c001_v0001097_t0052") 2021/12/01 06:48:47 DEBUG : A1/B1/C1/one: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f102b5255c1be4d6b_d20211201_m064556_c001_v0001036_t0009") 2021/12/01 06:48:47 DEBUG : A1/B1/C2/five: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f102417b0be12512c_d20211201_m064738_c001_v0001021_t0035") 2021/12/01 06:48:47 DEBUG : A1/B1/C2/five: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f102b5255c1be4e52_d20211201_m064735_c001_v0001036_t0028") 2021/12/01 06:48:47 DEBUG : A1/B1/three: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1112a968f93d58a0_d20211201_m064738_c001_v0001150_t0016") 2021/12/01 06:48:47 DEBUG : A1/B1/three: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f102b5255c1be4e4c_d20211201_m064734_c001_v0001036_t0041") 2021/12/01 06:48:47 DEBUG : A1/one: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f112e1a4fed5fa721_d20211201_m064738_c001_v0001153_t0023") 2021/12/01 06:48:47 DEBUG : A1/one: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f102b5255c1be4e46_d20211201_m064733_c001_v0001036_t0039") 2021/12/01 06:48:47 DEBUG : A1/two: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1194f6913945a77a_d20211201_m064739_c001_v0001117_t0002") 2021/12/01 06:48:47 DEBUG : A1/two: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f102b5255c1be4e49_d20211201_m064734_c001_v0001036_t0026") 2021/12/01 06:48:48 DEBUG : A1/two: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f11813fe1949beb72_d20211201_m064600_c001_v0001091_t0045") 2021/12/01 06:48:48 DEBUG : A1/two: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f102b5255c1be4d6e_d20211201_m064556_c001_v0001036_t0036") 2021/12/01 06:48:48 DEBUG : A2/B1/C1/four: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f11580828b264cb7c_d20211201_m064743_c001_v0001150_t0055") 2021/12/01 06:48:48 DEBUG : A2/B1/C1/four: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f107b90664010760f_d20211201_m064737_c001_v0001117_t0033") 2021/12/01 06:48:48 DEBUG : A2/B1/C2/five: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1107c58b09d11229_d20211201_m064745_c001_v0001137_t0044") 2021/12/01 06:48:48 DEBUG : A2/B1/C2/five: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f100a2e4a8940c878_d20211201_m064737_c001_v0001146_t0008") 2021/12/01 06:48:48 DEBUG : A2/B1/three: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f100b355ea1fd56a9_d20211201_m064744_c001_v0001015_t0006") 2021/12/01 06:48:48 DEBUG : A2/B1/three: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1067d9a6bcbe8bad_d20211201_m064737_c001_v0001146_t0025") 2021/12/01 06:48:48 DEBUG : A2/one: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f111e90221f0a2c5f_d20211201_m064744_c001_v0001040_t0026") 2021/12/01 06:48:48 DEBUG : A2/one: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f109191615d973140_d20211201_m064737_c001_v0001044_t0036") 2021/12/01 06:48:48 DEBUG : A2/two: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1045e5cdbb500885_d20211201_m064746_c001_v0001033_t0039") 2021/12/01 06:48:48 DEBUG : A2/two: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1026e68e0c843f5a_d20211201_m064738_c001_v0001044_t0002") 2021/12/01 06:48:48 DEBUG : A3/B1/C1/four: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f115e59d8f22e628f_d20211201_m064750_c001_v0001097_t0004") 2021/12/01 06:48:48 DEBUG : A3/B1/C1/four: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1071f3659c5f6aa0_d20211201_m064743_c001_v0001161_t0008") 2021/12/01 06:48:48 DEBUG : A3/B1/C2/five: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f113312722a63d535_d20211201_m064750_c001_v0001144_t0045") 2021/12/01 06:48:48 DEBUG : A3/B1/C2/five: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f103913ec2fa8edbd_d20211201_m064743_c001_v0001113_t0021") 2021/12/01 06:48:48 DEBUG : A3/B1/three: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f116111a55c9dc762_d20211201_m064752_c001_v0001043_t0012") 2021/12/01 06:48:48 DEBUG : A3/B1/three: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1158663e06b6981b_d20211201_m064743_c001_v0001001_t0033") 2021/12/01 06:48:48 DEBUG : A3/one: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1196bae8efda797e_d20211201_m064752_c001_v0001091_t0043") 2021/12/01 06:48:48 DEBUG : A3/one: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1052b144f4b3f3ad_d20211201_m064743_c001_v0001030_t0014") 2021/12/01 06:48:48 DEBUG : A3/two: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f10278772f141ecb5_d20211201_m064753_c001_v0001042_t0002") 2021/12/01 06:48:48 DEBUG : A3/two: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1101b65457349cf5_d20211201_m064744_c001_v0001117_t0029") 2021/12/01 06:48:48 DEBUG : BackupDir/one: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f108e747b7034d71b_d20211201_m064725_c001_v0001011_t0004") 2021/12/01 06:48:49 DEBUG : BackupDir/one: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f102f5ee8b19ab7ef_d20211201_m064714_c001_v0001093_t0034") 2021/12/01 06:48:49 DEBUG : CompareDest/one: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f10526d05db43ebc7_d20211201_m064657_c001_v0001117_t0058") 2021/12/01 06:48:49 DEBUG : CompareDest/one: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f102b5255c1be4df3_d20211201_m064651_c001_v0001036_t0001") 2021/12/01 06:48:49 DEBUG : CompareDest/two: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f100a0f51ccc9c57b_d20211201_m064658_c001_v0001090_t0048") 2021/12/01 06:48:49 DEBUG : CompareDest/two: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f102b5255c1be4df8_d20211201_m064652_c001_v0001036_t0029") 2021/12/01 06:48:49 DEBUG : CopyDest/one: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f116d94cc559e4d9a_d20211201_m064727_c001_v0001097_t0045") 2021/12/01 06:48:49 DEBUG : CopyDest/one: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f102b5255c1be4e18_d20211201_m064709_c001_v0001036_t0015") 2021/12/01 06:48:49 DEBUG : CopyDest/three: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1036b59ebfe6aa6b_d20211201_m064728_c001_v0001136_t0036") 2021/12/01 06:48:49 DEBUG : CopyDest/three: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f102b5255c1be4e32_d20211201_m064721_c001_v0001036_t0045") 2021/12/01 06:48:49 DEBUG : CopyDest/two: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f113a227326a97c9d_d20211201_m064729_c001_v0001120_t0012") 2021/12/01 06:48:49 DEBUG : CopyDest/two: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f102b5255c1be4e27_d20211201_m064718_c001_v0001036_t0015") 2021/12/01 06:48:49 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1100abe70a4a107f_d20211201_m064832_c001_v0001097_t0020") 2021/12/01 06:48:49 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f102b5255c1be4ed5_d20211201_m064829_c001_v0001036_t0029") 2021/12/01 06:48:49 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1149976d4e798a91_d20211201_m064833_c001_v0001022_t0032") 2021/12/01 06:48:49 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f102b5255c1be4edb_d20211201_m064831_c001_v0001036_t0008") 2021/12/01 06:48:49 DEBUG : a.txt: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f100ded582269d006_d20211201_m064421_c001_v0001097_t0022") 2021/12/01 06:48:49 DEBUG : a.txt: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1173d5ca1a47046d_d20211201_m064410_c001_v0001038_t0013") 2021/12/01 06:48:49 DEBUG : also/one: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f10526d05db43de81_d20211201_m064405_c001_v0001117_t0056") 2021/12/01 06:48:49 DEBUG : also/one: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1173d5ca1a470432_d20211201_m064357_c001_v0001038_t0057") 2021/12/01 06:48:49 DEBUG : another: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1182bc1fb3a8c564_d20211201_m064406_c001_v0001117_t0044") 2021/12/01 06:48:49 DEBUG : another: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1173d5ca1a470436_d20211201_m064358_c001_v0001038_t0002") 2021/12/01 06:48:50 DEBUG : backup/dst/file1: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f113a227326a97b55_d20211201_m064637_c001_v0001120_t0039") 2021/12/01 06:48:50 DEBUG : backup/dst/file1: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f114a09729d72640e_d20211201_m064634_c001_v0001097_t0035") 2021/12/01 06:48:50 DEBUG : backup/dst/file1: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1003872946cbbfd4_d20211201_m064625_c001_v0001120_t0004") 2021/12/01 06:48:50 DEBUG : backup/dst/file1: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f100b0c67b8cdad1b_d20211201_m064622_c001_v0001040_t0021") 2021/12/01 06:48:50 DEBUG : data/banana: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f112f1ab4fd9c622d_d20211201_m064349_c001_v0001131_t0025") 2021/12/01 06:48:50 DEBUG : data/banana: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1173d5ca1a470404_d20211201_m064346_c001_v0001038_t0007") 2021/12/01 06:48:50 DEBUG : data/banana: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1173d5ca1a4703d6_d20211201_m064333_c001_v0001038_t0056") 2021/12/01 06:48:50 DEBUG : data/potato: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f11089b36f32cebcb_d20211201_m064349_c001_v0001144_t0040") 2021/12/01 06:48:50 DEBUG : data/potato: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1173d5ca1a470406_d20211201_m064346_c001_v0001038_t0023") 2021/12/01 06:48:50 DEBUG : data/potato: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1173d5ca1a4703dd_d20211201_m064336_c001_v0001038_t0011") 2021/12/01 06:48:50 DEBUG : dst/file1: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f100b0c67b8cdad44_d20211201_m064638_c001_v0001040_t0008") 2021/12/01 06:48:50 DEBUG : dst/file1: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f102b5255c1be4dca_d20211201_m064636_c001_v0001036_t0057") 2021/12/01 06:48:50 DEBUG : dst/file1: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1095eaf82663a986_d20211201_m064635_c001_v0001134_t0044") 2021/12/01 06:48:50 DEBUG : dst/file1: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f102b5255c1be4dbd_d20211201_m064630_c001_v0001036_t0027") 2021/12/01 06:48:50 DEBUG : dst/file1: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f11499179f9147a4f_d20211201_m064626_c001_v0001145_t0023") 2021/12/01 06:48:50 DEBUG : dst/file1: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f102b5255c1be4db3_d20211201_m064624_c001_v0001036_t0032") 2021/12/01 06:48:50 DEBUG : dst/file1: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f11713f0e6421b128_d20211201_m064623_c001_v0001043_t0008") 2021/12/01 06:48:50 DEBUG : dst/file1: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f102b5255c1be4da4_d20211201_m064617_c001_v0001036_t0051") 2021/12/01 06:48:50 DEBUG : dst/one: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f115593f974f6d32b_d20211201_m064730_c001_v0001021_t0000") 2021/12/01 06:48:50 DEBUG : dst/one: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1026e68e0c843f32_d20211201_m064715_c001_v0001044_t0041") 2021/12/01 06:48:50 DEBUG : dst/one: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f10278772f141ec5d_d20211201_m064714_c001_v0001042_t0052") 2021/12/01 06:48:51 DEBUG : dst/one: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f102b5255c1be4e16_d20211201_m064708_c001_v0001036_t0022") 2021/12/01 06:48:51 DEBUG : dst/one: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f11859be188737f32_d20211201_m064707_c001_v0001012_t0035") 2021/12/01 06:48:51 DEBUG : dst/one: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f11859be188737f2f_d20211201_m064706_c001_v0001012_t0040") 2021/12/01 06:48:51 DEBUG : dst/one: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f108a694dcbb6bcb7_d20211201_m064658_c001_v0001117_t0028") 2021/12/01 06:48:51 DEBUG : dst/one: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f102b5255c1be4de8_d20211201_m064647_c001_v0001036_t0052") 2021/12/01 06:48:51 DEBUG : dst/one: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f117bbdcce7087baa_d20211201_m064646_c001_v0001113_t0009") 2021/12/01 06:48:51 DEBUG : dst/one: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f117bbdcce7087b9e_d20211201_m064644_c001_v0001113_t0021") 2021/12/01 06:48:51 DEBUG : dst/three: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f105df12963bff9e0_d20211201_m064730_c001_v0001005_t0043") 2021/12/01 06:48:51 DEBUG : dst/three: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f11859be188737f41_d20211201_m064724_c001_v0001012_t0021") 2021/12/01 06:48:51 DEBUG : dst/two: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1194f6913945a6d9_d20211201_m064731_c001_v0001117_t0048") 2021/12/01 06:48:51 DEBUG : dst/two: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1097e31eee69de31_d20211201_m064720_c001_v0001117_t0002") 2021/12/01 06:48:51 DEBUG : dst/two: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f104c3a9819755b6f_d20211201_m064701_c001_v0001137_t0041") 2021/12/01 06:48:51 DEBUG : dst/two: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f117bbdcce7087be7_d20211201_m064655_c001_v0001113_t0025") 2021/12/01 06:48:51 DEBUG : empty space: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f100c2d1e3a654632_d20211201_m064845_c001_v0001044_t0014") 2021/12/01 06:48:51 DEBUG : empty space: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f10210ad9f6e1566c_d20211201_m064842_c001_v0001022_t0009") 2021/12/01 06:48:51 DEBUG : empty space: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f102b5255c1be4ee9_d20211201_m064834_c001_v0001036_t0048") 2021/12/01 06:48:51 DEBUG : empty space: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f116e7ed8436acdff_d20211201_m064529_c001_v0001117_t0019") 2021/12/01 06:48:51 DEBUG : empty space: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1173d5ca1a47055b_d20211201_m064526_c001_v0001038_t0048") 2021/12/01 06:48:51 DEBUG : empty space: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f112cf8858987ea3d_d20211201_m064523_c001_v0001092_t0019") 2021/12/01 06:48:52 DEBUG : empty space: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1173d5ca1a470549_d20211201_m064521_c001_v0001038_t0000") 2021/12/01 06:48:52 DEBUG : empty space: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f101c987e39b5f5c4_d20211201_m064517_c001_v0001029_t0027") 2021/12/01 06:48:52 DEBUG : empty space: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1173d5ca1a470538_d20211201_m064515_c001_v0001038_t0046") 2021/12/01 06:48:52 DEBUG : empty space: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f107b4be53adc0e6b_d20211201_m064506_c001_v0001097_t0028") 2021/12/01 06:48:52 DEBUG : empty space: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1173d5ca1a470513_d20211201_m064500_c001_v0001038_t0027") 2021/12/01 06:48:52 DEBUG : empty space: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f11235be68f51edc3_d20211201_m064456_c001_v0001136_t0057") 2021/12/01 06:48:52 DEBUG : empty space: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1173d5ca1a470500_d20211201_m064454_c001_v0001038_t0050") 2021/12/01 06:48:52 DEBUG : empty space: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f103ac4badaedcc2a_d20211201_m064321_c001_v0001097_t0022") 2021/12/01 06:48:52 DEBUG : empty space: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1173d5ca1a4703a3_d20211201_m064314_c001_v0001038_t0035") 2021/12/01 06:48:52 DEBUG : empty space: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f10912ed92ba6ba7b_d20211201_m064306_c001_v0001097_t0038") 2021/12/01 06:48:52 DEBUG : empty space: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1173d5ca1a470373_d20211201_m064300_c001_v0001038_t0035") 2021/12/01 06:48:52 DEBUG : empty space: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1168bbb6b5aba964_d20211201_m064251_c001_v0001092_t0011") 2021/12/01 06:48:52 DEBUG : empty space: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1173d5ca1a470341_d20211201_m064246_c001_v0001038_t0057") 2021/12/01 06:48:52 DEBUG : file1: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f109d11e827fe5b88_d20211201_m064615_c001_v0001013_t0030") 2021/12/01 06:48:52 DEBUG : file1: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f102b5255c1be4da0_d20211201_m064614_c001_v0001036_t0040") 2021/12/01 06:48:52 DEBUG : file1: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f105c9081a92e1199_d20211201_m064606_c001_v0001136_t0022") 2021/12/01 06:48:52 DEBUG : file1: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f102b5255c1be4d7d_d20211201_m064603_c001_v0001036_t0020") 2021/12/01 06:48:52 DEBUG : file1: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f105d515fce5bc6b3_d20211201_m064547_c001_v0001037_t0022") 2021/12/01 06:48:52 DEBUG : file1: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f102b5255c1be4d4b_d20211201_m064542_c001_v0001036_t0059") 2021/12/01 06:48:52 DEBUG : file1: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1036b59ebfe6a829_d20211201_m064439_c001_v0001136_t0048") 2021/12/01 06:48:52 DEBUG : file1: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1173d5ca1a4704b6_d20211201_m064433_c001_v0001038_t0009") 2021/12/01 06:48:53 DEBUG : file1: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f115467bc5512abcd_d20211201_m064430_c001_v0001041_t0051") 2021/12/01 06:48:53 DEBUG : file1: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1173d5ca1a4704a8_d20211201_m064427_c001_v0001038_t0009") 2021/12/01 06:48:53 DEBUG : file1: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1112a968f93d5416_d20211201_m064244_c001_v0001150_t0033") 2021/12/01 06:48:53 DEBUG : file1: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1173d5ca1a47032e_d20211201_m064241_c001_v0001038_t0038") 2021/12/01 06:48:53 DEBUG : file1: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1173d5ca1a470329_d20211201_m064239_c001_v0001038_t0056") 2021/12/01 06:48:53 DEBUG : file1: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1173d5ca1a47031d_d20211201_m064236_c001_v0001038_t0055") 2021/12/01 06:48:53 DEBUG : file2: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f107e65d3dd144534_d20211201_m064608_c001_v0001029_t0025") 2021/12/01 06:48:53 DEBUG : file2: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f102b5255c1be4d82_d20211201_m064606_c001_v0001036_t0032") 2021/12/01 06:48:53 DEBUG : file2: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f117d23f462e3770b_d20211201_m064550_c001_v0001161_t0030") 2021/12/01 06:48:53 DEBUG : file2: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f102b5255c1be4d50_d20211201_m064543_c001_v0001036_t0052") 2021/12/01 06:48:53 DEBUG : filename.txt: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f116c851c85442949_d20211201_m064609_c001_v0001120_t0028") 2021/12/01 06:48:53 DEBUG : filename.txt: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f102b5255c1be4d7f_d20211201_m064605_c001_v0001036_t0023") 2021/12/01 06:48:53 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1068491a24a8ed4f_d20211201_m064819_c001_v0001113_t0026") 2021/12/01 06:48:53 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f102b5255c1be4eb4_d20211201_m064816_c001_v0001036_t0009") 2021/12/01 06:48:53 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f103ceb0a72f0b2b3_d20211201_m064811_c001_v0001117_t0001") 2021/12/01 06:48:53 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f102b5255c1be4e99_d20211201_m064807_c001_v0001036_t0059") 2021/12/01 06:48:53 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1162ba336c4d2181_d20211201_m064820_c001_v0001144_t0049") 2021/12/01 06:48:53 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f102b5255c1be4ead_d20211201_m064815_c001_v0001036_t0030") 2021/12/01 06:48:53 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1143f29a4445cd4a_d20211201_m064814_c001_v0001036_t0045") 2021/12/01 06:48:53 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f102b5255c1be4e96_d20211201_m064807_c001_v0001036_t0019") 2021/12/01 06:48:53 DEBUG : large: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f10336ecf3bc4f3ae_d20211201_m064540_c001_v0001144_t0043") 2021/12/01 06:48:54 DEBUG : large: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f102b5255c1be4d40_d20211201_m064538_c001_v0001036_t0030") 2021/12/01 06:48:54 DEBUG : medium: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f103ceb0a72f0a66c_d20211201_m064538_c001_v0001117_t0014") 2021/12/01 06:48:54 DEBUG : medium: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f102b5255c1be4d3e_d20211201_m064537_c001_v0001036_t0053") 2021/12/01 06:48:54 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f102bca8860b0ee73_d20211201_m064759_c001_v0001117_t0051") 2021/12/01 06:48:54 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f102b5255c1be4e7e_d20211201_m064757_c001_v0001036_t0016") 2021/12/01 06:48:54 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f102f5ee8b19ab84d_d20211201_m064801_c001_v0001093_t0049") 2021/12/01 06:48:54 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f102b5255c1be4e79_d20211201_m064754_c001_v0001036_t0059") 2021/12/01 06:48:54 DEBUG : not-one: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f100023a75e49d06f_d20211201_m064407_c001_v0001012_t0019") 2021/12/01 06:48:54 DEBUG : not-one: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1173d5ca1a470446_d20211201_m064401_c001_v0001038_t0020") 2021/12/01 06:48:54 DEBUG : one: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f110789a131a6c388_d20211201_m064404_c001_v0001044_t0022") 2021/12/01 06:48:54 DEBUG : one: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1173d5ca1a47042e_d20211201_m064357_c001_v0001038_t0043") 2021/12/01 06:48:54 DEBUG : potato1: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f117bf05cfec5f14b_d20211201_m064824_c001_v0001144_t0008") 2021/12/01 06:48:54 DEBUG : potato1: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f102b5255c1be4ebe_d20211201_m064822_c001_v0001036_t0041") 2021/12/01 06:48:54 DEBUG : potato2: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1108053f07eb0d84_d20211201_m064845_c001_v0001130_t0022") 2021/12/01 06:48:54 DEBUG : potato2: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f112884b891f2dba1_d20211201_m064843_c001_v0001117_t0045") 2021/12/01 06:48:54 DEBUG : potato2: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f102b5255c1be4ee5_d20211201_m064834_c001_v0001036_t0028") 2021/12/01 06:48:54 DEBUG : potato2: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f114a09729d726f98_d20211201_m064827_c001_v0001097_t0014") 2021/12/01 06:48:54 DEBUG : potato2: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f102b5255c1be4ec0_d20211201_m064822_c001_v0001036_t0020") 2021/12/01 06:48:54 DEBUG : potato2: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f114f19876c90a66c_d20211201_m064530_c001_v0001117_t0032") 2021/12/01 06:48:54 DEBUG : potato2: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1173d5ca1a470555_d20211201_m064526_c001_v0001038_t0040") 2021/12/01 06:48:54 DEBUG : potato2: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1133d58aecce52ef_d20211201_m064523_c001_v0001015_t0039") 2021/12/01 06:48:54 DEBUG : potato2: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1173d5ca1a470543_d20211201_m064520_c001_v0001038_t0053") 2021/12/01 06:48:55 DEBUG : potato2: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f108a694dcbb6b43a_d20211201_m064518_c001_v0001117_t0027") 2021/12/01 06:48:55 DEBUG : potato2: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1173d5ca1a47052f_d20211201_m064511_c001_v0001038_t0003") 2021/12/01 06:48:55 DEBUG : potato2: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f117bf05cfec5ea12_d20211201_m064508_c001_v0001144_t0041") 2021/12/01 06:48:55 DEBUG : potato2: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1173d5ca1a47050a_d20211201_m064458_c001_v0001038_t0024") 2021/12/01 06:48:55 DEBUG : potato2: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f100c89e5306f46ca_d20211201_m064457_c001_v0001131_t0016") 2021/12/01 06:48:55 DEBUG : potato2: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1173d5ca1a4704fd_d20211201_m064453_c001_v0001038_t0051") 2021/12/01 06:48:55 DEBUG : potato2: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1148a8a241fa0468_d20211201_m064324_c001_v0001033_t0000") 2021/12/01 06:48:55 DEBUG : potato2: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1173d5ca1a4703a9_d20211201_m064316_c001_v0001038_t0025") 2021/12/01 06:48:55 DEBUG : potato2: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1187afdd3e2507cb_d20211201_m064308_c001_v0001128_t0034") 2021/12/01 06:48:55 DEBUG : potato2: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1173d5ca1a47037d_d20211201_m064303_c001_v0001038_t0001") 2021/12/01 06:48:55 DEBUG : potato2: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f117f6da3e741d8de_d20211201_m064252_c001_v0001003_t0025") 2021/12/01 06:48:55 DEBUG : potato2: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1173d5ca1a470347_d20211201_m064247_c001_v0001038_t0016") 2021/12/01 06:48:55 DEBUG : remotepotato: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f114f19876c909d18_d20211201_m064326_c001_v0001117_t0026") 2021/12/01 06:48:55 DEBUG : remotepotato: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1173d5ca1a4703ac_d20211201_m064318_c001_v0001038_t0058") 2021/12/01 06:48:55 DEBUG : remotepotato: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f10981d2a0e81a15f_d20211201_m064308_c001_v0001032_t0041") 2021/12/01 06:48:55 DEBUG : remotepotato: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1173d5ca1a470385_d20211201_m064305_c001_v0001038_t0040") 2021/12/01 06:48:55 DEBUG : remotepotato: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f116e7ed8436ac10f_d20211201_m064253_c001_v0001117_t0043") 2021/12/01 06:48:55 DEBUG : remotepotato: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1173d5ca1a470356_d20211201_m064250_c001_v0001038_t0026") 2021/12/01 06:48:55 DEBUG : rutabaga: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1054ab25c1555f6e_d20211201_m064327_c001_v0001029_t0030") 2021/12/01 06:48:55 DEBUG : rutabaga: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1173d5ca1a47039e_d20211201_m064313_c001_v0001038_t0054") 2021/12/01 06:48:55 DEBUG : rutabaga: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1182975b2c375ccc_d20211201_m064310_c001_v0001144_t0016") 2021/12/01 06:48:56 DEBUG : rutabaga: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1173d5ca1a47036a_d20211201_m064257_c001_v0001038_t0048") 2021/12/01 06:48:56 DEBUG : rutabaga: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f109842c4434da591_d20211201_m064255_c001_v0001120_t0022") 2021/12/01 06:48:56 DEBUG : rutabaga: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1173d5ca1a47033c_d20211201_m064244_c001_v0001038_t0035") 2021/12/01 06:48:56 DEBUG : small: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f116b56f905d9e9c4_d20211201_m064539_c001_v0001165_t0025") 2021/12/01 06:48:56 DEBUG : small: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f102b5255c1be4d3b_d20211201_m064536_c001_v0001036_t0053") 2021/12/01 06:48:56 DEBUG : sub dir/hello world: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f109842c4434da82b_d20211201_m064452_c001_v0001120_t0059") 2021/12/01 06:48:56 DEBUG : sub dir/hello world: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1173d5ca1a4704e8_d20211201_m064448_c001_v0001038_t0046") 2021/12/01 06:48:56 DEBUG : sub dir/hello world: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1080129019845660_d20211201_m064422_c001_v0001136_t0027") 2021/12/01 06:48:56 DEBUG : sub dir/hello world: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1173d5ca1a470471_d20211201_m064411_c001_v0001038_t0008") 2021/12/01 06:48:56 DEBUG : sub dir/hello world2: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1151d26071cc8b04_d20211201_m064423_c001_v0001161_t0016") 2021/12/01 06:48:56 DEBUG : sub dir/hello world2: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1173d5ca1a47047d_d20211201_m064414_c001_v0001038_t0039") 2021/12/01 06:48:56 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f117bf05cfec5e844_d20211201_m064423_c001_v0001144_t0038") 2021/12/01 06:48:56 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1173d5ca1a470482_d20211201_m064416_c001_v0001038_t0021") 2021/12/01 06:48:56 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f101299dba4388fe6_d20211201_m064425_c001_v0001117_t0033") 2021/12/01 06:48:56 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1173d5ca1a470487_d20211201_m064416_c001_v0001038_t0011") 2021/12/01 06:48:56 DEBUG : sub dir/potato3: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1004af435bac0a8d_d20211201_m064846_c001_v0001037_t0049") 2021/12/01 06:48:56 DEBUG : sub dir/potato3: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1118b2e1f30c10b7_d20211201_m064844_c001_v0001093_t0019") 2021/12/01 06:48:56 DEBUG : sub dir/potato3: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f102b5255c1be4eef_d20211201_m064836_c001_v0001036_t0032") 2021/12/01 06:48:56 DEBUG : sub dir/potato3: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f10232d343e67c628_d20211201_m064532_c001_v0001097_t0003") 2021/12/01 06:48:56 DEBUG : sub dir/potato3: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1173d5ca1a47055d_d20211201_m064527_c001_v0001038_t0033") 2021/12/01 06:48:56 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f106d0a75fdddc34b_d20211201_m064426_c001_v0001131_t0031") 2021/12/01 06:48:57 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1173d5ca1a47048b_d20211201_m064417_c001_v0001038_t0038") 2021/12/01 06:48:57 DEBUG : sub/file2: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1174e483f504217b_d20211201_m064629_c001_v0001117_t0034") 2021/12/01 06:48:57 DEBUG : sub/file2: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f102b5255c1be4db7_d20211201_m064628_c001_v0001036_t0035") 2021/12/01 06:48:57 DEBUG : sub/file2: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1101ebcf0766f825_d20211201_m064612_c001_v0001033_t0021") 2021/12/01 06:48:57 DEBUG : sub/file2: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f102b5255c1be4d97_d20211201_m064611_c001_v0001036_t0015") 2021/12/01 06:48:57 DEBUG : sub/file2: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1162ba336c4d19b5_d20211201_m064440_c001_v0001144_t0058") 2021/12/01 06:48:57 DEBUG : sub/file2: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1173d5ca1a4704b8_d20211201_m064434_c001_v0001038_t0050") 2021/12/01 06:48:57 DEBUG : sub/file2: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1134d64bd74ca570_d20211201_m064431_c001_v0001037_t0039") 2021/12/01 06:48:57 DEBUG : sub/file2: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1173d5ca1a4704aa_d20211201_m064428_c001_v0001038_t0010") 2021/12/01 06:48:57 DEBUG : test.sum: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f11512b44e3c76d2b_d20211201_m064353_c001_v0001128_t0038") 2021/12/01 06:48:57 DEBUG : test.sum: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1173d5ca1a470409_d20211201_m064347_c001_v0001038_t0001") 2021/12/01 06:48:57 DEBUG : test.sum: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1173d5ca1a4703fa_d20211201_m064344_c001_v0001038_t0040") 2021/12/01 06:48:57 DEBUG : test.sum: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1173d5ca1a4703f6_d20211201_m064342_c001_v0001038_t0049") 2021/12/01 06:48:57 DEBUG : test.sum: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1173d5ca1a4703f0_d20211201_m064341_c001_v0001038_t0049") 2021/12/01 06:48:57 DEBUG : test.sum: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1173d5ca1a4703e6_d20211201_m064338_c001_v0001038_t0018") 2021/12/01 06:48:57 DEBUG : test.sum: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1173d5ca1a4703e1_d20211201_m064337_c001_v0001038_t0013") 2021/12/01 06:48:57 DEBUG : test.sum: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1173d5ca1a4703d9_d20211201_m064334_c001_v0001038_t0016") 2021/12/01 06:48:57 DEBUG : test.sum: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f11234a4a693875a0_d20211201_m064330_c001_v0001161_t0051") 2021/12/01 06:48:57 DEBUG : test.sum: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1173d5ca1a4703c6_d20211201_m064329_c001_v0001038_t0028") 2021/12/01 06:48:57 DEBUG : test.sum: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1173d5ca1a4703c1_d20211201_m064328_c001_v0001038_t0012") 2021/12/01 06:48:57 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1109318fc900c779_d20211201_m064806_c001_v0001165_t0053") 2021/12/01 06:48:58 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f102b5255c1be4e8b_d20211201_m064802_c001_v0001036_t0052") 2021/12/01 06:48:58 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f10372f757f7387cc_d20211201_m064806_c001_v0001092_t0021") 2021/12/01 06:48:58 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f102b5255c1be4e88_d20211201_m064801_c001_v0001036_t0024") 2021/12/01 06:48:58 DEBUG : zend.txt: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f11304355f7ca8e60_d20211201_m064427_c001_v0001150_t0003") 2021/12/01 06:48:58 DEBUG : zend.txt: Deleting (id "4_z4f220dd3b0bd7b4b7fd80e1b_f1173d5ca1a47046f_d20211201_m064410_c001_v0001038_t0047") "./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -fast-list" - Finished OK in 6m25.093169507s (try 1/5)