"./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose" - Starting (try 1/5) 2022/03/02 05:01:07 DEBUG : Creating backend with remote "TestB2:rclone-test-vuqepep8jarohem2hoqukev8" 2022/03/02 05:01:07 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2022/03/02 05:01:08 DEBUG : Creating backend with remote "/tmp/rclone1784282851" === 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-vuqepep8jarohem2hoqukev8", Local "Local file system at /tmp/rclone1784282851", Modify Window "1ms" === RUN TestMultithreadCopy/{size:131071_streams:2} 2022/03/02 05:01:13 DEBUG : file1: Starting multi-thread copy with 2 parts of size 64Ki 2022/03/02 05:01:13 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131071) size 63.999Ki starting 2022/03/02 05:01:13 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64Ki starting 2022/03/02 05:01:14 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131071) size 63.999Ki finished 2022/03/02 05:01:14 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64Ki finished 2022/03/02 05:01:14 DEBUG : file1: Finished multi-thread copy with 2 parts of size 64Ki === RUN TestMultithreadCopy/{size:131072_streams:2} 2022/03/02 05:01:16 DEBUG : file1: Starting multi-thread copy with 2 parts of size 64Ki 2022/03/02 05:01:16 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131072) size 64Ki starting 2022/03/02 05:01:16 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64Ki starting 2022/03/02 05:01:17 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131072) size 64Ki finished 2022/03/02 05:01:17 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64Ki finished 2022/03/02 05:01:17 DEBUG : file1: Finished multi-thread copy with 2 parts of size 64Ki === RUN TestMultithreadCopy/{size:131073_streams:2} 2022/03/02 05:01:18 DEBUG : file1: Starting multi-thread copy with 2 parts of size 128Ki 2022/03/02 05:01:18 DEBUG : file1: multi-thread copy: stream 2/2 (131072-131073) size 1 starting 2022/03/02 05:01:18 DEBUG : file1: multi-thread copy: stream 1/2 (0-131072) size 128Ki starting 2022/03/02 05:01:19 DEBUG : file1: multi-thread copy: stream 2/2 (131072-131073) size 1 finished 2022/03/02 05:01:19 DEBUG : file1: multi-thread copy: stream 1/2 (0-131072) size 128Ki finished 2022/03/02 05:01:19 DEBUG : file1: Finished multi-thread copy with 2 parts of size 128Ki --- PASS: TestMultithreadCopy (11.79s) --- PASS: TestMultithreadCopy/{size:131071_streams:2} (6.21s) --- PASS: TestMultithreadCopy/{size:131072_streams:2} (2.47s) --- PASS: TestMultithreadCopy/{size:131073_streams:2} (1.92s) === RUN TestSizeDiffers --- PASS: TestSizeDiffers (0.00s) === RUN TestReOpen === RUN TestReOpen/Seek === RUN TestReOpen/Seek/Basics === RUN TestReOpen/Seek/ErrorAtStart === RUN TestReOpen/Seek/WithErrors 2022/03/02 05:01:20 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/10: test error 2022/03/02 05:01:20 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/10: test error 2022/03/02 05:01:20 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/10: test error === RUN TestReOpen/Seek/TooManyErrors 2022/03/02 05:01:20 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/3: test error 2022/03/02 05:01:20 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/3: test error 2022/03/02 05:01:20 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/3: test error 2022/03/02 05:01:20 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/03/02 05:01:20 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/10: test error 2022/03/02 05:01:20 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/10: test error 2022/03/02 05:01:20 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/10: test error === RUN TestReOpen/Range/TooManyErrors 2022/03/02 05:01:20 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/3: test error 2022/03/02 05:01:20 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/3: test error 2022/03/02 05:01:20 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/3: test error 2022/03/02 05:01:20 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-vuqepep8jarohem2hoqukev8", Local "Local file system at /tmp/rclone1784282851", 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.88s) --- PASS: TestCheck/1 (0.17s) --- PASS: TestCheck/2 (0.19s) --- PASS: TestCheck/3 (0.17s) --- PASS: TestCheck/4 (0.17s) --- PASS: TestCheck/5 (0.17s) --- PASS: TestCheck/6 (0.17s) --- PASS: TestCheck/7 (0.17s) === RUN TestCheckFsError 2022/03/02 05:01:32 DEBUG : Creating backend with remote "non-existent" 2022/03/02 05:01:32 DEBUG : Creating backend with remote "non-existent" 2022/03/02 05:01:32 DEBUG : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/non-existent: Waiting for checks to finish 2022/03/02 05:01:32 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/03/02 05:01:32 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/non-existent: 2 differences found 2022/03/02 05:01:32 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-vuqepep8jarohem2hoqukev8", Local "Local file system at /tmp/rclone1784282851", 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 (13.73s) --- PASS: TestCheckDownload/1 (0.87s) --- PASS: TestCheckDownload/2 (0.36s) --- PASS: TestCheckDownload/3 (0.35s) --- PASS: TestCheckDownload/4 (0.59s) --- PASS: TestCheckDownload/5 (0.36s) --- PASS: TestCheckDownload/6 (0.36s) --- PASS: TestCheckDownload/7 (0.38s) === RUN TestCheckSizeOnly run.go:181: Remote "B2 bucket rclone-test-vuqepep8jarohem2hoqukev8", Local "Local file system at /tmp/rclone1784282851", 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 (8.71s) --- PASS: TestCheckSizeOnly/1 (0.19s) --- PASS: TestCheckSizeOnly/2 (0.17s) --- PASS: TestCheckSizeOnly/3 (0.18s) --- 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-vuqepep8jarohem2hoqukev8", Local "Local file system at /tmp/rclone1784282851", Modify Window "1ms" 2022/03/02 05:01:56 NOTICE: test.sum: improperly formatted checksum line 4 2022/03/02 05:01:56 NOTICE: test.sum: improperly formatted checksum line 5 2022/03/02 05:01:56 NOTICE: test.sum: improperly formatted checksum line 6 2022/03/02 05:01:56 NOTICE: test.sum: 2 warning(s) suppressed... 2022/03/02 05:01:57 NOTICE: test.sum: improperly formatted checksum line 4 2022/03/02 05:01:57 NOTICE: test.sum: improperly formatted checksum line 5 2022/03/02 05:01:57 NOTICE: test.sum: improperly formatted checksum line 6 2022/03/02 05:01:57 NOTICE: test.sum: 2 warning(s) suppressed... --- PASS: TestParseSumFile (5.66s) === RUN TestCheckSum run.go:181: Remote "B2 bucket rclone-test-vuqepep8jarohem2hoqukev8", Local "Local file system at /tmp/rclone1784282851", Modify Window "1ms" 2022/03/02 05:02:00 DEBUG : Creating backend with remote "TestB2:rclone-test-vuqepep8jarohem2hoqukev8/data" 2022/03/02 05:02:01 DEBUG : Couldn't decode error response: EOF check_test.go:356: B2 bucket rclone-test-vuqepep8jarohem2hoqukev8 path data lacks md5, skipping --- SKIP: TestCheckSum (1.29s) === RUN TestCheckSumDownload run.go:181: Remote "B2 bucket rclone-test-vuqepep8jarohem2hoqukev8", Local "Local file system at /tmp/rclone1784282851", Modify Window "1ms" 2022/03/02 05:02:01 DEBUG : Creating backend with remote "TestB2:rclone-test-vuqepep8jarohem2hoqukev8/data" 2022/03/02 05:02:02 DEBUG : Couldn't decode error response: EOF 2022/03/02 05:02:02 DEBUG : data/banana: Clearing upload URL because of error: Post "https://pod-000-1116-14.backblaze.com/b2api/v1/b2_upload_file/3f72ed43000d4b9b7ff80e1b/c001_v0001116_t0021": EOF 2022/03/02 05:02:02 DEBUG : pacer: low level retry 1/1 (error Post "https://pod-000-1116-14.backblaze.com/b2api/v1/b2_upload_file/3f72ed43000d4b9b7ff80e1b/c001_v0001116_t0021": EOF) 2022/03/02 05:02:02 DEBUG : pacer: Rate limited, increasing sleep to 20ms run.go:283: Retry Put of "data/banana" to B2 bucket rclone-test-vuqepep8jarohem2hoqukev8: 1/10 (Post "https://pod-000-1116-14.backblaze.com/b2api/v1/b2_upload_file/3f72ed43000d4b9b7ff80e1b/c001_v0001116_t0021": EOF) 2022/03/02 05:02:04 DEBUG : pacer: Reducing sleep to 10ms === RUN TestCheckSumDownload/subtest1 === RUN TestCheckSumDownload/subtest2 === RUN TestCheckSumDownload/subtest3 === RUN TestCheckSumDownload/subtest4 === RUN TestCheckSumDownload/subtest5 === RUN TestCheckSumDownload/subtest6 === RUN TestCheckSumDownload/subtest7 --- PASS: TestCheckSumDownload (20.75s) --- PASS: TestCheckSumDownload/subtest1 (1.04s) --- PASS: TestCheckSumDownload/subtest2 (0.76s) --- PASS: TestCheckSumDownload/subtest3 (1.05s) --- PASS: TestCheckSumDownload/subtest4 (0.82s) --- PASS: TestCheckSumDownload/subtest5 (0.74s) --- PASS: TestCheckSumDownload/subtest6 (0.72s) --- PASS: TestCheckSumDownload/subtest7 (0.75s) === RUN TestDeduplicateInteractive run.go:181: Remote "B2 bucket rclone-test-vuqepep8jarohem2hoqukev8", Local "Local file system at /tmp/rclone1784282851", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateInteractive (0.46s) === RUN TestDeduplicateSkip run.go:181: Remote "B2 bucket rclone-test-vuqepep8jarohem2hoqukev8", Local "Local file system at /tmp/rclone1784282851", 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-vuqepep8jarohem2hoqukev8", Local "Local file system at /tmp/rclone1784282851", 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-vuqepep8jarohem2hoqukev8", Local "Local file system at /tmp/rclone1784282851", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateFirst (0.34s) === RUN TestDeduplicateNewest run.go:181: Remote "B2 bucket rclone-test-vuqepep8jarohem2hoqukev8", Local "Local file system at /tmp/rclone1784282851", 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-vuqepep8jarohem2hoqukev8", Local "Local file system at /tmp/rclone1784282851", Modify Window "1ms" 2022/03/02 05:02:26 INFO : B2 bucket rclone-test-vuqepep8jarohem2hoqukev8: Looking for duplicate sha1 hashes using newest mode. 2022/03/02 05:02:26 NOTICE: 75b91004156b20a9e70ca0b9fc28e7919bb60de7: Found 3 files with duplicate sha1 hashes 2022/03/02 05:02:27 INFO : one: Deleted 2022/03/02 05:02:27 INFO : also/one: Deleted 2022/03/02 05:02:27 NOTICE: 75b91004156b20a9e70ca0b9fc28e7919bb60de7: Deleted 2 extra copies --- PASS: TestDeduplicateNewestByHash (6.13s) === RUN TestDeduplicateOldest run.go:181: Remote "B2 bucket rclone-test-vuqepep8jarohem2hoqukev8", Local "Local file system at /tmp/rclone1784282851", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateOldest (0.35s) === RUN TestDeduplicateLargest run.go:181: Remote "B2 bucket rclone-test-vuqepep8jarohem2hoqukev8", Local "Local file system at /tmp/rclone1784282851", 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-vuqepep8jarohem2hoqukev8", Local "Local file system at /tmp/rclone1784282851", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSmallest (0.34s) === RUN TestDeduplicateRename run.go:181: Remote "B2 bucket rclone-test-vuqepep8jarohem2hoqukev8", Local "Local file system at /tmp/rclone1784282851", 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-vuqepep8jarohem2hoqukev8", Local "Local file system at /tmp/rclone1784282851", Modify Window "1ms" dedupe_test.go:267: Can't merge directories --- SKIP: TestMergeDirs (0.35s) === RUN TestListDirSorted run.go:181: Remote "B2 bucket rclone-test-vuqepep8jarohem2hoqukev8", Local "Local file system at /tmp/rclone1784282851", Modify Window "1ms" 2022/03/02 05:02:41 DEBUG : a.txt: Excluded 2022/03/02 05:02:41 DEBUG : sub dir/hello world: Excluded 2022/03/02 05:02:41 DEBUG : sub dir/hello world2: Excluded 2022/03/02 05:02:41 DEBUG : sub dir/hello world: Excluded 2022/03/02 05:02:41 DEBUG : sub dir/hello world2: Excluded 2022/03/02 05:02:42 DEBUG : sub dir/ignore dir: Excluded 2022/03/02 05:02:42 DEBUG : Couldn't decode error response: EOF 2022/03/02 05:02:42 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSorted (15.97s) === RUN TestListJSON run.go:181: Remote "B2 bucket rclone-test-vuqepep8jarohem2hoqukev8", Local "Local file system at /tmp/rclone1784282851", 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/03/02 05:02:51 ERROR : file1: Failed to read hash: hash type not supported --- PASS: TestListJSON (4.84s) --- PASS: TestListJSON/Default (0.17s) --- PASS: TestListJSON/FilesOnly (0.17s) --- PASS: TestListJSON/DirsOnly (0.17s) --- PASS: TestListJSON/Recurse (0.17s) --- PASS: TestListJSON/SubDir (0.17s) --- PASS: TestListJSON/NoModTime (0.17s) --- PASS: TestListJSON/NoMimeType (0.17s) --- PASS: TestListJSON/ShowHash (0.17s) --- PASS: TestListJSON/HashTypes (0.17s) === RUN TestStatJSON run.go:181: Remote "B2 bucket rclone-test-vuqepep8jarohem2hoqukev8", Local "Local file system at /tmp/rclone1784282851", Modify Window "1ms" === RUN TestStatJSON/Root === RUN TestStatJSON/RootFilesOnly === RUN TestStatJSON/RootDirsOnly === RUN TestStatJSON/Dir 2022/03/02 05:02:55 DEBUG : Couldn't decode error response: EOF === RUN TestStatJSON/File === RUN TestStatJSON/NotFound 2022/03/02 05:02:55 DEBUG : Couldn't decode error response: EOF === RUN TestStatJSON/DirFilesOnly 2022/03/02 05:02:56 DEBUG : Couldn't decode error response: EOF === RUN TestStatJSON/FileFilesOnly === RUN TestStatJSON/NotFoundFilesOnly 2022/03/02 05:02:56 DEBUG : Couldn't decode error response: EOF === RUN TestStatJSON/DirDirsOnly === RUN TestStatJSON/FileDirsOnly === RUN TestStatJSON/NotFoundDirsOnly === RUN TestStatJSON/RootNotFound 2022/03/02 05:02:56 DEBUG : Creating backend with remote "TestB2:rclone-test-vuqepep8jarohem2hoqukev8/notfound" 2022/03/02 05:02:56 DEBUG : Config file has changed externaly - reloading 2022/03/02 05:02:57 DEBUG : Couldn't decode error response: EOF --- PASS: TestStatJSON (9.92s) --- PASS: TestStatJSON/Root (0.17s) --- PASS: TestStatJSON/RootFilesOnly (0.00s) --- PASS: TestStatJSON/RootDirsOnly (0.17s) --- PASS: TestStatJSON/Dir (0.94s) --- PASS: TestStatJSON/File (0.23s) --- PASS: TestStatJSON/NotFound (0.38s) --- PASS: TestStatJSON/DirFilesOnly (0.18s) --- PASS: TestStatJSON/FileFilesOnly (0.19s) --- PASS: TestStatJSON/NotFoundFilesOnly (0.20s) --- PASS: TestStatJSON/DirDirsOnly (0.22s) --- PASS: TestStatJSON/FileDirsOnly (0.17s) --- PASS: TestStatJSON/NotFoundDirsOnly (0.18s) --- PASS: TestStatJSON/RootNotFound (1.24s) === RUN TestMkdir run.go:181: Remote "B2 bucket rclone-test-vuqepep8jarohem2hoqukev8", Local "Local file system at /tmp/rclone1784282851", Modify Window "1ms" 2022/03/02 05:03:02 DEBUG : B2 bucket rclone-test-vuqepep8jarohem2hoqukev8: Making directory 2022/03/02 05:03:02 DEBUG : B2 bucket rclone-test-vuqepep8jarohem2hoqukev8: Making directory --- PASS: TestMkdir (0.51s) === RUN TestLsd run.go:181: Remote "B2 bucket rclone-test-vuqepep8jarohem2hoqukev8", Local "Local file system at /tmp/rclone1784282851", Modify Window "1ms" --- PASS: TestLsd (3.20s) === RUN TestLs run.go:181: Remote "B2 bucket rclone-test-vuqepep8jarohem2hoqukev8", Local "Local file system at /tmp/rclone1784282851", Modify Window "1ms" --- PASS: TestLs (6.40s) === RUN TestLsWithFilesFrom run.go:181: Remote "B2 bucket rclone-test-vuqepep8jarohem2hoqukev8", Local "Local file system at /tmp/rclone1784282851", Modify Window "1ms" 2022/03/02 05:03:17 DEBUG : empty space: Excluded 2022/03/02 05:03:17 DEBUG : Couldn't decode error response: EOF --- PASS: TestLsWithFilesFrom (7.42s) === RUN TestLsLong run.go:181: Remote "B2 bucket rclone-test-vuqepep8jarohem2hoqukev8", Local "Local file system at /tmp/rclone1784282851", Modify Window "1ms" --- PASS: TestLsLong (5.01s) === RUN TestHashSums run.go:181: Remote "B2 bucket rclone-test-vuqepep8jarohem2hoqukev8", Local "Local file system at /tmp/rclone1784282851", Modify Window "1ms" === RUN TestHashSums/Sha1 === RUN TestHashSums/Sha1Download --- PASS: TestHashSums (3.70s) --- PASS: TestHashSums/Sha1 (0.17s) --- PASS: TestHashSums/Sha1Download (0.88s) === RUN TestHashSumsWithErrors 2022/03/02 05:03:29 DEBUG : Creating backend with remote ":memory:" 2022/03/02 05:03:29 ERROR : file1: hash unsupported: hash type not supported --- PASS: TestHashSumsWithErrors (0.00s) === RUN TestHashStream 2022/03/02 05:03:29 DEBUG : Creating md5 hash of 0 bytes read from input stream 2022/03/02 05:03:29 DEBUG : Creating md5 hash of 0 bytes read from input stream 2022/03/02 05:03:29 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2022/03/02 05:03:29 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2022/03/02 05:03:29 DEBUG : Creating md5 hash of 12 bytes read from input stream 2022/03/02 05:03:29 DEBUG : Creating md5 hash of 12 bytes read from input stream 2022/03/02 05:03:29 DEBUG : Creating sha1 hash of 12 bytes read from input stream 2022/03/02 05:03:29 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-vuqepep8jarohem2hoqukev8", Local "Local file system at /tmp/rclone1784282851", Modify Window "1ms" --- PASS: TestCount (7.04s) === RUN TestDelete run.go:181: Remote "B2 bucket rclone-test-vuqepep8jarohem2hoqukev8", Local "Local file system at /tmp/rclone1784282851", Modify Window "1ms" 2022/03/02 05:03:37 DEBUG : Waiting for deletions to finish 2022/03/02 05:03:37 DEBUG : large: Excluded from sync (and deletion) 2022/03/02 05:03:38 INFO : medium: Deleted 2022/03/02 05:03:39 INFO : small: Deleted --- PASS: TestDelete (4.36s) === RUN TestRetry 2022/03/02 05:03:40 DEBUG : Received error: EOF - low level retry 1/5 2022/03/02 05:03:40 DEBUG : Received error: EOF - low level retry 2/5 2022/03/02 05:03:40 DEBUG : Received error: EOF - low level retry 1/5 2022/03/02 05:03:40 DEBUG : Received error: EOF - low level retry 2/5 2022/03/02 05:03:40 DEBUG : Received error: EOF - low level retry 3/5 2022/03/02 05:03:40 DEBUG : Received error: EOF - low level retry 4/5 2022/03/02 05:03:40 DEBUG : Received error: EOF - low level retry 5/5 --- PASS: TestRetry (0.00s) === RUN TestCat run.go:181: Remote "B2 bucket rclone-test-vuqepep8jarohem2hoqukev8", Local "Local file system at /tmp/rclone1784282851", Modify Window "1ms" --- PASS: TestCat (4.95s) === RUN TestPurge 2022/03/02 05:03:45 DEBUG : Creating backend with remote "TestB2:rclone-test-ceqiteb0kiwelix2conowah1" 2022/03/02 05:03:45 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2022/03/02 05:03:46 DEBUG : Creating backend with remote "/tmp/rclone728123527" run.go:181: Remote "B2 bucket rclone-test-ceqiteb0kiwelix2conowah1", Local "Local file system at /tmp/rclone728123527", Modify Window "1ms" 2022/03/02 05:03:49 DEBUG : A2: Making directory 2022/03/02 05:03:49 DEBUG : A1/B2: Making directory 2022/03/02 05:03:49 DEBUG : A1/B2/C2: Making directory 2022/03/02 05:03:49 DEBUG : A1/B1/C3: Making directory 2022/03/02 05:03:49 DEBUG : A3: Making directory 2022/03/02 05:03:49 DEBUG : A3/B3: Making directory 2022/03/02 05:03:49 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/03/02 05:03:50 DEBUG : A1/B1/C1/one: Deleting (id "4_z5ff24d83003d4b9b7ff80e1b_f1009a7f62097e489_d20220302_m050347_c001_v0001104_t0035") 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/03/02 05:03:51 DEBUG : A1/two: Deleting (id "4_z5ff24d83003d4b9b7ff80e1b_f1009a7f62097e4b9_d20220302_m050349_c001_v0001104_t0052") 2022/03/02 05:03:52 DEBUG : B2 bucket rclone-test-ceqiteb0kiwelix2conowah1: Purge remote 2022/03/02 05:03:52 purge failed: directory not found --- PASS: TestPurge (6.92s) === RUN TestRmdirsNoLeaveRoot run.go:181: Remote "B2 bucket rclone-test-vuqepep8jarohem2hoqukev8", Local "Local file system at /tmp/rclone1784282851", Modify Window "1ms" 2022/03/02 05:03:54 DEBUG : A2: Making directory 2022/03/02 05:03:54 DEBUG : A1/B2: Making directory 2022/03/02 05:03:54 DEBUG : A1/B2/C2: Making directory 2022/03/02 05:03:54 DEBUG : A1/B1/C3: Making directory 2022/03/02 05:03:54 DEBUG : A3: Making directory 2022/03/02 05:03:54 DEBUG : A3/B3: Making directory 2022/03/02 05:03:54 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/03/02 05:03:55 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.93s) === RUN TestRmdirsLeaveRoot run.go:181: Remote "B2 bucket rclone-test-vuqepep8jarohem2hoqukev8", Local "Local file system at /tmp/rclone1784282851", Modify Window "1ms" 2022/03/02 05:03:58 DEBUG : A1: Making directory 2022/03/02 05:03:58 DEBUG : A1/B1: Making directory 2022/03/02 05:03:58 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.92s) === RUN TestRmdirsWithFilter run.go:181: Remote "B2 bucket rclone-test-vuqepep8jarohem2hoqukev8", Local "Local file system at /tmp/rclone1784282851", Modify Window "1ms" 2022/03/02 05:03:59 DEBUG : A1: Making directory 2022/03/02 05:03:59 DEBUG : A1/B1: Making directory 2022/03/02 05:03:59 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-vuqepep8jarohem2hoqukev8", Local "Local file system at /tmp/rclone1784282851", Modify Window "1ms" 2022/03/02 05:04:02 DEBUG : filename.txt: File name found in url --- PASS: TestCopyURL (6.32s) === RUN TestCopyURLToWriter --- PASS: TestCopyURLToWriter (0.00s) === RUN TestMoveFile run.go:181: Remote "B2 bucket rclone-test-vuqepep8jarohem2hoqukev8", Local "Local file system at /tmp/rclone1784282851", Modify Window "1ms" 2022/03/02 05:04:06 DEBUG : Couldn't decode error response: EOF 2022/03/02 05:04:06 DEBUG : file1: Need to transfer - File not found at Destination 2022/03/02 05:04:08 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2022/03/02 05:04:08 INFO : file1: Copied (new) to: sub/file2 2022/03/02 05:04:08 INFO : file1: Deleted 2022/03/02 05:04:08 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/02 05:04:08 DEBUG : file1: Unchanged skipping 2022/03/02 05:04:08 INFO : file1: Deleted 2022/03/02 05:04:08 DEBUG : B2 bucket rclone-test-vuqepep8jarohem2hoqukev8: don't need to copy/move sub/file2, it is already at target location --- PASS: TestMoveFile (3.75s) === RUN TestMoveFileWithIgnoreExisting run.go:181: Remote "B2 bucket rclone-test-vuqepep8jarohem2hoqukev8", Local "Local file system at /tmp/rclone1784282851", Modify Window "1ms" 2022/03/02 05:04:10 DEBUG : Couldn't decode error response: EOF 2022/03/02 05:04:10 DEBUG : file1: Need to transfer - File not found at Destination 2022/03/02 05:04:10 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2022/03/02 05:04:10 INFO : file1: Copied (new) 2022/03/02 05:04:10 INFO : file1: Deleted 2022/03/02 05:04:11 DEBUG : file1: Destination exists, skipping 2022/03/02 05:04:11 DEBUG : file1: Not removing source file as destination file exists and --ignore-existing is set --- PASS: TestMoveFileWithIgnoreExisting (3.68s) === RUN TestCaseInsensitiveMoveFile run.go:181: Remote "B2 bucket rclone-test-vuqepep8jarohem2hoqukev8", Local "Local file system at /tmp/rclone1784282851", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFile (0.34s) === RUN TestMoveFileBackupDir run.go:181: Remote "B2 bucket rclone-test-vuqepep8jarohem2hoqukev8", Local "Local file system at /tmp/rclone1784282851", Modify Window "1ms" 2022/03/02 05:04:15 DEBUG : Creating backend with remote "TestB2:rclone-test-vuqepep8jarohem2hoqukev8/backup" 2022/03/02 05:04:16 DEBUG : Couldn't decode error response: EOF 2022/03/02 05:04:16 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18) 2022/03/02 05:04:16 DEBUG : Couldn't decode error response: EOF 2022/03/02 05:04:18 DEBUG : dst/file1: sha1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK 2022/03/02 05:04:18 INFO : dst/file1: Copied (server-side copy) 2022/03/02 05:04:20 INFO : dst/file1: Deleted 2022/03/02 05:04:20 DEBUG : dst/file1: Clearing upload URL because of error: Post "https://pod-000-1090-12.backblaze.com/b2api/v1/b2_upload_file/3f72ed43000d4b9b7ff80e1b/c001_v0001090_t0046": EOF 2022/03/02 05:04:20 DEBUG : pacer: low level retry 1/1 (error Post "https://pod-000-1090-12.backblaze.com/b2api/v1/b2_upload_file/3f72ed43000d4b9b7ff80e1b/c001_v0001090_t0046": EOF) 2022/03/02 05:04:20 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2022/03/02 05:04:20 DEBUG : dst/file1: Received error: Post "https://pod-000-1090-12.backblaze.com/b2api/v1/b2_upload_file/3f72ed43000d4b9b7ff80e1b/c001_v0001090_t0046": EOF - low level retry 1/10 2022/03/02 05:04:20 DEBUG : pacer: Reducing sleep to 10ms 2022/03/02 05:04:22 DEBUG : dst/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2022/03/02 05:04:22 INFO : dst/file1: Copied (new) 2022/03/02 05:04:22 INFO : dst/file1: Deleted --- PASS: TestMoveFileBackupDir (10.31s) === RUN TestCopyFile run.go:181: Remote "B2 bucket rclone-test-vuqepep8jarohem2hoqukev8", Local "Local file system at /tmp/rclone1784282851", Modify Window "1ms" 2022/03/02 05:04:25 DEBUG : Couldn't decode error response: EOF 2022/03/02 05:04:25 DEBUG : file1: Need to transfer - File not found at Destination 2022/03/02 05:04:27 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2022/03/02 05:04:27 INFO : file1: Copied (new) to: sub/file2 2022/03/02 05:04:27 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2022/03/02 05:04:27 DEBUG : file1: Unchanged skipping 2022/03/02 05:04:27 DEBUG : B2 bucket rclone-test-vuqepep8jarohem2hoqukev8: don't need to copy/move sub/file2, it is already at target location --- PASS: TestCopyFile (4.49s) === RUN TestCopyFileBackupDir run.go:181: Remote "B2 bucket rclone-test-vuqepep8jarohem2hoqukev8", Local "Local file system at /tmp/rclone1784282851", Modify Window "1ms" 2022/03/02 05:04:29 DEBUG : Creating backend with remote "TestB2:rclone-test-vuqepep8jarohem2hoqukev8/backup" 2022/03/02 05:04:31 DEBUG : Couldn't decode error response: EOF 2022/03/02 05:04:31 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18) 2022/03/02 05:04:31 DEBUG : Couldn't decode error response: EOF 2022/03/02 05:04:35 DEBUG : dst/file1: sha1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK 2022/03/02 05:04:35 INFO : dst/file1: Copied (server-side copy) 2022/03/02 05:04:37 INFO : dst/file1: Deleted 2022/03/02 05:04:38 DEBUG : dst/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2022/03/02 05:04:38 INFO : dst/file1: Copied (new) --- PASS: TestCopyFileBackupDir (12.67s) === RUN TestCopyFileCompareDest run.go:181: Remote "B2 bucket rclone-test-vuqepep8jarohem2hoqukev8", Local "Local file system at /tmp/rclone1784282851", Modify Window "1ms" 2022/03/02 05:04:41 DEBUG : Creating backend with remote "TestB2:rclone-test-vuqepep8jarohem2hoqukev8/dst" 2022/03/02 05:04:43 DEBUG : Couldn't decode error response: EOF 2022/03/02 05:04:43 DEBUG : Couldn't decode error response: EOF 2022/03/02 05:04:43 DEBUG : Creating backend with remote "TestB2:rclone-test-vuqepep8jarohem2hoqukev8/CompareDest" 2022/03/02 05:04:43 DEBUG : Couldn't decode error response: EOF 2022/03/02 05:04:43 DEBUG : Couldn't decode error response: EOF 2022/03/02 05:04:43 DEBUG : one: Need to transfer - File not found at Destination 2022/03/02 05:04:51 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/03/02 05:04:51 INFO : one: Copied (new) 2022/03/02 05:04:53 DEBUG : Couldn't decode error response: EOF 2022/03/02 05:04:53 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/03/02 05:04:55 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2022/03/02 05:04:55 INFO : one: Copied (replaced existing) 2022/03/02 05:05:00 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/03/02 05:05:00 DEBUG : one: Destination found in --compare-dest, skipping 2022/03/02 05:05:03 DEBUG : Couldn't decode error response: EOF 2022/03/02 05:05:03 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/03/02 05:05:03 DEBUG : two: Destination found in --compare-dest, skipping 2022/03/02 05:05:03 DEBUG : Couldn't decode error response: EOF 2022/03/02 05:05:03 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/03/02 05:05:03 DEBUG : two: Destination found in --compare-dest, skipping 2022/03/02 05:05:04 DEBUG : Couldn't decode error response: EOF 2022/03/02 05:05:04 DEBUG : two: Sizes differ (src 5 vs dst 3) 2022/03/02 05:05:04 DEBUG : two: Need to transfer - File not found at Destination 2022/03/02 05:05:06 DEBUG : two: sha1 = a4be8877ad88f3109a6411985a1f359e10e2d930 OK 2022/03/02 05:05:06 INFO : two: Copied (new) --- PASS: TestCopyFileCompareDest (29.56s) === RUN TestCopyFileCopyDest run.go:181: Remote "B2 bucket rclone-test-vuqepep8jarohem2hoqukev8", Local "Local file system at /tmp/rclone1784282851", Modify Window "1ms" 2022/03/02 05:05:11 DEBUG : Creating backend with remote "TestB2:rclone-test-vuqepep8jarohem2hoqukev8/dst" 2022/03/02 05:05:11 DEBUG : Config file has changed externaly - reloading 2022/03/02 05:05:12 DEBUG : Couldn't decode error response: EOF 2022/03/02 05:05:12 DEBUG : Couldn't decode error response: EOF 2022/03/02 05:05:12 DEBUG : Creating backend with remote "TestB2:rclone-test-vuqepep8jarohem2hoqukev8/CopyDest" 2022/03/02 05:05:13 DEBUG : Couldn't decode error response: EOF 2022/03/02 05:05:13 DEBUG : Couldn't decode error response: EOF 2022/03/02 05:05:13 DEBUG : one: Need to transfer - File not found at Destination 2022/03/02 05:05:15 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/03/02 05:05:15 INFO : one: Copied (new) 2022/03/02 05:05:16 DEBUG : Couldn't decode error response: EOF 2022/03/02 05:05:16 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/03/02 05:05:18 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2022/03/02 05:05:18 INFO : one: Copied (replaced existing) 2022/03/02 05:05:21 DEBUG : Creating backend with remote "TestB2:rclone-test-vuqepep8jarohem2hoqukev8/BackupDir" 2022/03/02 05:05:22 DEBUG : Couldn't decode error response: EOF 2022/03/02 05:05:22 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/03/02 05:05:22 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/03/02 05:05:22 DEBUG : Couldn't decode error response: EOF 2022/03/02 05:05:25 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/03/02 05:05:25 INFO : one: Copied (server-side copy) 2022/03/02 05:05:26 INFO : one: Deleted 2022/03/02 05:05:27 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2022/03/02 05:05:27 INFO : one: Copied (server-side copy) 2022/03/02 05:05:27 DEBUG : one: Destination found in --copy-dest, using server-side copy 2022/03/02 05:05:30 DEBUG : Couldn't decode error response: EOF 2022/03/02 05:05:30 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/03/02 05:05:31 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2022/03/02 05:05:31 INFO : two: Copied (server-side copy) 2022/03/02 05:05:31 DEBUG : two: Destination found in --copy-dest, using server-side copy 2022/03/02 05:05:31 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/03/02 05:05:31 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2022/03/02 05:05:31 DEBUG : two: Unchanged skipping 2022/03/02 05:05:33 DEBUG : Couldn't decode error response: EOF 2022/03/02 05:05:33 DEBUG : three: Sizes differ (src 7 vs dst 5) 2022/03/02 05:05:33 DEBUG : three: Destination not found in --copy-dest 2022/03/02 05:05:33 DEBUG : three: Need to transfer - File not found at Destination 2022/03/02 05:05:36 DEBUG : three: sha1 = 370e613335f3cf2f19b2a2d35a86402f55c659f8 OK 2022/03/02 05:05:36 INFO : three: Copied (new) --- PASS: TestCopyFileCopyDest (33.28s) === 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-vuqepep8jarohem2hoqukev8", Local "Local file system at /tmp/rclone1784282851", Modify Window "1ms" 2022/03/02 05:05:54 DEBUG : A1/B2: Making directory 2022/03/02 05:05:54 DEBUG : A1/B1/C3: Making directory fstest.go:251: Filtering empty directory "A1/B2" fstest.go:251: Filtering empty directory "A1/B1/C3" 2022/03/02 05:05:55 DEBUG : Couldn't decode error response: EOF 2022/03/02 05:05:55 DEBUG : Couldn't decode error response: EOF 2022/03/02 05:05:55 DEBUG : Couldn't decode error response: EOF 2022/03/02 05:05:55 DEBUG : Couldn't decode error response: EOF 2022/03/02 05:05:57 DEBUG : A1/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2022/03/02 05:05:57 INFO : A1/B1/C2/five: Copied (server-side copy) to: A2/B1/C2/five 2022/03/02 05:05:57 DEBUG : A1/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/03/02 05:05:57 INFO : A1/one: Copied (server-side copy) to: A2/one 2022/03/02 05:05:57 DEBUG : A1/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2022/03/02 05:05:57 INFO : A1/B1/C1/four: Copied (server-side copy) to: A2/B1/C1/four 2022/03/02 05:05:57 DEBUG : A1/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2022/03/02 05:05:57 INFO : A1/two: Copied (server-side copy) to: A2/two 2022/03/02 05:05:58 INFO : A1/one: Deleted 2022/03/02 05:05:58 DEBUG : Couldn't decode error response: EOF 2022/03/02 05:05:58 INFO : A1/B1/C2/five: Deleted 2022/03/02 05:05:58 INFO : A1/two: Deleted 2022/03/02 05:05:58 INFO : A1/B1/C1/four: Deleted 2022/03/02 05:06:00 DEBUG : A1/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2022/03/02 05:06:00 INFO : A1/B1/three: Copied (server-side copy) to: A2/B1/three 2022/03/02 05:06:01 INFO : A1/B1/three: Deleted fstest.go:251: Filtering empty directory "A2/B2" fstest.go:251: Filtering empty directory "A2/B1/C3" 2022/03/02 05:06:01 DEBUG : Couldn't decode error response: EOF 2022/03/02 05:06:01 DEBUG : Couldn't decode error response: EOF 2022/03/02 05:06:02 DEBUG : Couldn't decode error response: EOF 2022/03/02 05:06:02 DEBUG : Couldn't decode error response: EOF 2022/03/02 05:06:02 DEBUG : A2/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2022/03/02 05:06:02 INFO : A2/B1/C1/four: Copied (server-side copy) to: A3/B1/C1/four 2022/03/02 05:06:02 DEBUG : A2/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2022/03/02 05:06:02 INFO : A2/B1/C2/five: Copied (server-side copy) to: A3/B1/C2/five 2022/03/02 05:06:02 INFO : A2/B1/C1/four: Deleted 2022/03/02 05:06:03 DEBUG : A2/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2022/03/02 05:06:03 INFO : A2/one: Copied (server-side copy) to: A3/one 2022/03/02 05:06:03 DEBUG : Couldn't decode error response: EOF 2022/03/02 05:06:04 DEBUG : A2/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2022/03/02 05:06:04 INFO : A2/two: Copied (server-side copy) to: A3/two 2022/03/02 05:06:04 INFO : A2/one: Deleted 2022/03/02 05:06:04 INFO : A2/B1/C2/five: Deleted 2022/03/02 05:06:04 INFO : A2/two: Deleted 2022/03/02 05:06:13 DEBUG : A2/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2022/03/02 05:06:13 INFO : A2/B1/three: Copied (server-side copy) to: A3/B1/three 2022/03/02 05:06:13 INFO : A2/B1/three: Deleted fstest.go:251: Filtering empty directory "A3/B2" fstest.go:251: Filtering empty directory "A3/B1/C3" --- PASS: TestDirMove (34.55s) === RUN TestGetFsInfo run.go:181: Remote "B2 bucket rclone-test-vuqepep8jarohem2hoqukev8", Local "Local file system at /tmp/rclone1784282851", 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-vuqepep8jarohem2hoqukev8", Local "Local file system at /tmp/rclone1784282851", Modify Window "1ms" 2022/03/02 05:06:19 DEBUG : B2 bucket rclone-test-vuqepep8jarohem2hoqukev8: File to upload is small (34 bytes), uploading instead of streaming 2022/03/02 05:06:21 DEBUG : no_checksum_small_file_from_pipe: sha1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK 2022/03/02 05:06:21 INFO : no_checksum_small_file_from_pipe: Copied (new) 2022/03/02 05:06:21 DEBUG : no_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2022/03/02 05:06:23 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-vuqepep8jarohem2hoqukev8", Local "Local file system at /tmp/rclone1784282851", Modify Window "1ms" 2022/03/02 05:06:26 DEBUG : B2 bucket rclone-test-vuqepep8jarohem2hoqukev8: File to upload is small (34 bytes), uploading instead of streaming 2022/03/02 05:06:27 DEBUG : with_checksum_small_file_from_pipe: sha1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK 2022/03/02 05:06:27 INFO : with_checksum_small_file_from_pipe: Copied (new) 2022/03/02 05:06:27 DEBUG : with_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2022/03/02 05:06:29 DEBUG : with_checksum_big_file_from_pipe: sha1 = d0080f73aa8b494570ad63df1beb25c01ea9eea2 OK 2022/03/02 05:06:29 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-vuqepep8jarohem2hoqukev8", Local "Local file system at /tmp/rclone1784282851", Modify Window "1ms" 2022/03/02 05:06:34 DEBUG : B2 bucket rclone-test-vuqepep8jarohem2hoqukev8: File to upload is small (34 bytes), uploading instead of streaming 2022/03/02 05:06:34 INFO : ignore_checksum_small_file_from_pipe: Copied (new) 2022/03/02 05:06:34 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2022/03/02 05:06:36 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-vuqepep8jarohem2hoqukev8", Local "Local file system at /tmp/rclone1784282851", Modify Window "1ms" 2022/03/02 05:06:41 DEBUG : B2 bucket rclone-test-vuqepep8jarohem2hoqukev8: File to upload is small (34 bytes), uploading instead of streaming 2022/03/02 05:06:43 INFO : ignore_checksum_small_file_from_pipe: Copied (new) 2022/03/02 05:06:43 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2022/03/02 05:06:44 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical --- PASS: TestRcat (26.91s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (6.83s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (7.97s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (7.63s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (4.48s) === RUN TestRcatSize run.go:181: Remote "B2 bucket rclone-test-vuqepep8jarohem2hoqukev8", Local "Local file system at /tmp/rclone1784282851", Modify Window "1ms" 2022/03/02 05:06:47 DEBUG : B2 bucket rclone-test-vuqepep8jarohem2hoqukev8: File to upload is small (60 bytes), uploading instead of streaming 2022/03/02 05:06:54 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2022/03/02 05:06:54 INFO : potato2: Copied (new) --- PASS: TestRcatSize (11.61s) === RUN TestCopyFileMaxTransfer run.go:181: Remote "B2 bucket rclone-test-vuqepep8jarohem2hoqukev8", Local "Local file system at /tmp/rclone1784282851", Modify Window "1ms" 2022/03/02 05:06:58 DEBUG : Couldn't decode error response: EOF 2022/03/02 05:06:58 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination 2022/03/02 05:06:59 DEBUG : TestCopyFileMaxTransfer/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2022/03/02 05:06:59 INFO : TestCopyFileMaxTransfer/file1: Copied (new) 2022/03/02 05:07:00 DEBUG : Couldn't decode error response: EOF 2022/03/02 05:07:00 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination 2022/03/02 05:07:00 ERROR : TestCopyFileMaxTransfer/file2: Failed to copy: Post "https://pod-000-1101-06.backblaze.com/b2api/v1/b2_upload_file/3f72ed43000d4b9b7ff80e1b/c001_v0001101_t0054": Max transfer limit reached as set by --max-transfer 2022/03/02 05:07:00 DEBUG : Couldn't decode error response: EOF 2022/03/02 05:07:00 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination 2022/03/02 05:07:00 DEBUG : Couldn't decode error response: EOF 2022/03/02 05:07:00 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination 2022/03/02 05:07:02 DEBUG : TestCopyFileMaxTransfer/file4: sha1 = f1d6c0f187e9ad8ddada4e8a685c99e357c644d9 OK 2022/03/02 05:07:02 INFO : TestCopyFileMaxTransfer/file4: Copied (new) --- PASS: TestCopyFileMaxTransfer (6.17s) === RUN TestTouchDir run.go:181: Remote "B2 bucket rclone-test-vuqepep8jarohem2hoqukev8", Local "Local file system at /tmp/rclone1784282851", Modify Window "1ms" 2022/03/02 05:07:08 DEBUG : B2 bucket rclone-test-vuqepep8jarohem2hoqukev8: Touching "empty space" 2022/03/02 05:07:11 DEBUG : B2 bucket rclone-test-vuqepep8jarohem2hoqukev8: Touching "potato2" 2022/03/02 05:07:13 DEBUG : B2 bucket rclone-test-vuqepep8jarohem2hoqukev8: Touching "sub dir/potato3" --- PASS: TestTouchDir (13.25s) === 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/03/02 05:07:17 DEBUG : B2 bucket rclone-test-vuqepep8jarohem2hoqukev8: Purge remote 2022/03/02 05:07:17 DEBUG : A1/B1/C1/four: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f101d3445ab5e860d_d20220302_m050557_c001_v0001104_t0026") 2022/03/02 05:07:17 DEBUG : A1/B1/C1/four: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f106610c5dfbaee95_d20220302_m050551_c001_v0001101_t0023") 2022/03/02 05:07:17 DEBUG : A1/B1/C1/one: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f111cc97c8f5e3f18_d20220302_m050356_c001_v0001116_t0035") 2022/03/02 05:07:17 DEBUG : A1/B1/C1/one: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1120815d2a06857d_d20220302_m050352_c001_v0001090_t0032") 2022/03/02 05:07:17 DEBUG : A1/B1/C2/five: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f11046be95861197c_d20220302_m050557_c001_v0001106_t0010") 2022/03/02 05:07:17 DEBUG : A1/B1/C2/five: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f106610c5dfbaeeb5_d20220302_m050552_c001_v0001101_t0034") 2022/03/02 05:07:17 DEBUG : A1/B1/three: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f108d07d0ea3bb999_d20220302_m050600_c001_v0001115_t0012") 2022/03/02 05:07:17 DEBUG : A1/B1/three: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f106610c5dfbaee38_d20220302_m050547_c001_v0001101_t0036") 2022/03/02 05:07:17 DEBUG : A1/one: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1157146be4a71756_d20220302_m050557_c001_v0001030_t0010") 2022/03/02 05:07:17 DEBUG : A1/one: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f106610c5dfbaede7_d20220302_m050545_c001_v0001101_t0020") 2022/03/02 05:07:18 DEBUG : A1/two: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1184c5202312e382_d20220302_m050558_c001_v0001144_t0038") 2022/03/02 05:07:18 DEBUG : A1/two: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f106610c5dfbaee13_d20220302_m050546_c001_v0001101_t0019") 2022/03/02 05:07:18 DEBUG : A1/two: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1101fbff40b45d55_d20220302_m050357_c001_v0001099_t0057") 2022/03/02 05:07:18 DEBUG : A1/two: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1120815d2a068581_d20220302_m050354_c001_v0001090_t0020") 2022/03/02 05:07:18 DEBUG : A2/B1/C1/four: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1074ba26601ce231_d20220302_m050602_c001_v0001161_t0017") 2022/03/02 05:07:18 DEBUG : A2/B1/C1/four: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f116af4745b05947c_d20220302_m050557_c001_v0001100_t0054") 2022/03/02 05:07:18 DEBUG : A2/B1/C2/five: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1140ba08d13ff60d_d20220302_m050602_c001_v0001098_t0031") 2022/03/02 05:07:18 DEBUG : A2/B1/C2/five: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f11374a58bc401436_d20220302_m050556_c001_v0001105_t0010") 2022/03/02 05:07:18 DEBUG : A2/B1/three: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f115f89d57873f0c3_d20220302_m050613_c001_v0001103_t0030") 2022/03/02 05:07:18 DEBUG : A2/B1/three: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f11296c7ffb08cc40_d20220302_m050558_c001_v0001104_t0045") 2022/03/02 05:07:18 DEBUG : A2/one: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1042db56550995cb_d20220302_m050603_c001_v0001106_t0055") 2022/03/02 05:07:19 DEBUG : A2/one: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f108030c7a45ac59d_d20220302_m050556_c001_v0001037_t0011") 2022/03/02 05:07:19 DEBUG : A2/two: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1062bfe46e9f7250_d20220302_m050604_c001_v0001039_t0011") 2022/03/02 05:07:19 DEBUG : A2/two: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1156829a549aa0e4_d20220302_m050556_c001_v0001104_t0014") 2022/03/02 05:07:19 DEBUG : A3/B1/C1/four: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1102574958cf5070_d20220302_m050614_c001_v0001105_t0017") 2022/03/02 05:07:19 DEBUG : A3/B1/C1/four: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f107e44ef320a9b5d_d20220302_m050601_c001_v0001104_t0014") 2022/03/02 05:07:19 DEBUG : A3/B1/C2/five: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f111650a2113a9626_d20220302_m050615_c001_v0001091_t0040") 2022/03/02 05:07:19 DEBUG : A3/B1/C2/five: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f11374a58bc40147c_d20220302_m050602_c001_v0001105_t0034") 2022/03/02 05:07:19 DEBUG : A3/B1/three: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1059ccc5e48e1ce1_d20220302_m050615_c001_v0001097_t0044") 2022/03/02 05:07:19 DEBUG : A3/B1/three: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1120f9107d941f62_d20220302_m050602_c001_v0001101_t0024") 2022/03/02 05:07:19 DEBUG : A3/one: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f105e76392a3af9aa_d20220302_m050616_c001_v0001105_t0018") 2022/03/02 05:07:19 DEBUG : A3/one: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1184c4a9ffcabe50_d20220302_m050602_c001_v0001042_t0016") 2022/03/02 05:07:19 DEBUG : A3/two: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f107e44ef320a9cd3_d20220302_m050617_c001_v0001104_t0026") 2022/03/02 05:07:19 DEBUG : A3/two: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f10749f9b531fa317_d20220302_m050603_c001_v0001116_t0011") 2022/03/02 05:07:19 DEBUG : BackupDir/one: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f118dd3df64f2c354_d20220302_m050537_c001_v0001104_t0033") 2022/03/02 05:07:19 DEBUG : BackupDir/one: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1008d7928b879fef_d20220302_m050523_c001_v0001101_t0023") 2022/03/02 05:07:19 DEBUG : CompareDest/one: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f10438a831d08763b_d20220302_m050506_c001_v0001105_t0021") 2022/03/02 05:07:19 DEBUG : CompareDest/one: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f106610c5dfbae82d_d20220302_m050457_c001_v0001101_t0036") 2022/03/02 05:07:19 DEBUG : CompareDest/two: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1072492d74328d25_d20220302_m050509_c001_v0001145_t0023") 2022/03/02 05:07:19 DEBUG : CompareDest/two: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f106610c5dfbae882_d20220302_m050500_c001_v0001101_t0046") 2022/03/02 05:07:19 DEBUG : CopyDest/one: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f11916bbe8e4f1400_d20220302_m050539_c001_v0001115_t0017") 2022/03/02 05:07:20 DEBUG : CopyDest/one: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f106610c5dfbaeadb_d20220302_m050519_c001_v0001101_t0012") 2022/03/02 05:07:20 DEBUG : CopyDest/three: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1067354bc78401da_d20220302_m050541_c001_v0001041_t0053") 2022/03/02 05:07:20 DEBUG : CopyDest/three: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f106610c5dfbaec67_d20220302_m050532_c001_v0001101_t0031") 2022/03/02 05:07:20 DEBUG : CopyDest/two: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f10537b9c58c19703_d20220302_m050541_c001_v0001106_t0008") 2022/03/02 05:07:20 DEBUG : CopyDest/two: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f106610c5dfbaebfd_d20220302_m050528_c001_v0001101_t0044") 2022/03/02 05:07:20 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f112bb6e69aca42a2_d20220302_m050702_c001_v0001098_t0004") 2022/03/02 05:07:20 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f106610c5dfbaf5c2_d20220302_m050658_c001_v0001101_t0049") 2022/03/02 05:07:20 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f11191a8c5adbabf8_d20220302_m050703_c001_v0001101_t0025") 2022/03/02 05:07:20 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f106610c5dfbaf60e_d20220302_m050701_c001_v0001101_t0049") 2022/03/02 05:07:20 DEBUG : a.txt: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f104f3886d0f32849_d20220302_m050243_c001_v0001100_t0058") 2022/03/02 05:07:20 DEBUG : a.txt: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1120815d2a0684f7_d20220302_m050232_c001_v0001090_t0052") 2022/03/02 05:07:20 DEBUG : also/one: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f102d8c2d4399895a_d20220302_m050227_c001_v0001165_t0029") 2022/03/02 05:07:20 DEBUG : also/one: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1120815d2a0684ee_d20220302_m050224_c001_v0001090_t0051") 2022/03/02 05:07:20 DEBUG : another: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1014e0f4e236158d_d20220302_m050228_c001_v0001101_t0044") 2022/03/02 05:07:20 DEBUG : another: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1120815d2a0684f1_d20220302_m050225_c001_v0001090_t0045") 2022/03/02 05:07:20 DEBUG : backup/dst/file1: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f116548d0cc458868_d20220302_m050439_c001_v0001117_t0019") 2022/03/02 05:07:20 DEBUG : backup/dst/file1: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1043927525651033_d20220302_m050432_c001_v0001115_t0041") 2022/03/02 05:07:20 DEBUG : backup/dst/file1: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f115d5a3e62fbc6ea_d20220302_m050423_c001_v0001100_t0023") 2022/03/02 05:07:20 DEBUG : backup/dst/file1: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f11853f6c8582780f_d20220302_m050418_c001_v0001161_t0030") 2022/03/02 05:07:20 DEBUG : data/banana: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f115d15bfcc2a5082_d20220302_m050220_c001_v0001145_t0016") 2022/03/02 05:07:21 DEBUG : data/banana: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1120815d2a0684e2_d20220302_m050217_c001_v0001090_t0020") 2022/03/02 05:07:21 DEBUG : data/banana: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1120815d2a0684ca_d20220302_m050205_c001_v0001090_t0045") 2022/03/02 05:07:21 DEBUG : data/potato: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f110a4bad2fcaf84e_d20220302_m050221_c001_v0001131_t0012") 2022/03/02 05:07:21 DEBUG : data/potato: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1120815d2a0684e4_d20220302_m050218_c001_v0001090_t0023") 2022/03/02 05:07:21 DEBUG : data/potato: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1120815d2a0684cf_d20220302_m050207_c001_v0001090_t0006") 2022/03/02 05:07:21 DEBUG : dst/file1: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1143f44e0916ff02_d20220302_m050440_c001_v0001145_t0020") 2022/03/02 05:07:21 DEBUG : dst/file1: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f106610c5dfbae5f3_d20220302_m050438_c001_v0001101_t0010") 2022/03/02 05:07:21 DEBUG : dst/file1: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f11191a8c5adb9a1d_d20220302_m050435_c001_v0001101_t0043") 2022/03/02 05:07:21 DEBUG : dst/file1: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f106610c5dfbae4e1_d20220302_m050429_c001_v0001101_t0027") 2022/03/02 05:07:21 DEBUG : dst/file1: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1050a3e3fd470ea7_d20220302_m050423_c001_v0001117_t0030") 2022/03/02 05:07:21 DEBUG : dst/file1: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f106610c5dfbae3f9_d20220302_m050420_c001_v0001101_t0057") 2022/03/02 05:07:21 DEBUG : dst/file1: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1009a7f62097e6e7_d20220302_m050419_c001_v0001104_t0034") 2022/03/02 05:07:21 DEBUG : dst/file1: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1120815d2a06859d_d20220302_m050414_c001_v0001090_t0029") 2022/03/02 05:07:21 DEBUG : dst/one: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1119f999d599feb0_d20220302_m050542_c001_v0001009_t0026") 2022/03/02 05:07:21 DEBUG : dst/one: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1057a1810541e019_d20220302_m050526_c001_v0001032_t0006") 2022/03/02 05:07:21 DEBUG : dst/one: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1174c6f236aa2c1d_d20220302_m050525_c001_v0001099_t0019") 2022/03/02 05:07:21 DEBUG : dst/one: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f106610c5dfbaeac1_d20220302_m050519_c001_v0001101_t0003") 2022/03/02 05:07:22 DEBUG : dst/one: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f106d45ceb3d40166_d20220302_m050516_c001_v0001106_t0055") 2022/03/02 05:07:22 DEBUG : dst/one: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f106d45ceb3d4015b_d20220302_m050515_c001_v0001106_t0016") 2022/03/02 05:07:22 DEBUG : dst/one: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1040dcfbd5625584_d20220302_m050510_c001_v0001153_t0019") 2022/03/02 05:07:22 DEBUG : dst/one: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f106610c5dfbae7f4_d20220302_m050456_c001_v0001101_t0016") 2022/03/02 05:07:22 DEBUG : dst/one: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f107761c8a86b6ec8_d20220302_m050453_c001_v0001101_t0021") 2022/03/02 05:07:22 DEBUG : dst/one: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f107761c8a86b6dcc_d20220302_m050446_c001_v0001101_t0010") 2022/03/02 05:07:22 DEBUG : dst/three: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1170f1c0d825373a_d20220302_m050542_c001_v0001115_t0039") 2022/03/02 05:07:22 DEBUG : dst/three: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f106d45ceb3d401fc_d20220302_m050533_c001_v0001106_t0033") 2022/03/02 05:07:22 DEBUG : dst/two: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f11638d9b1ce4c40f_d20220302_m050543_c001_v0001104_t0024") 2022/03/02 05:07:22 DEBUG : dst/two: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f100e24c2ab328fd9_d20220302_m050530_c001_v0001098_t0050") 2022/03/02 05:07:22 DEBUG : dst/two: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1074ba26601ce005_d20220302_m050510_c001_v0001161_t0053") 2022/03/02 05:07:22 DEBUG : dst/two: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f107761c8a86b706f_d20220302_m050505_c001_v0001101_t0021") 2022/03/02 05:07:22 DEBUG : empty space: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f113555da16ce6ab1_d20220302_m050715_c001_v0001040_t0044") 2022/03/02 05:07:22 DEBUG : empty space: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f11794f1366375eeb_d20220302_m050708_c001_v0001036_t0055") 2022/03/02 05:07:22 DEBUG : empty space: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f106610c5dfbaf672_d20220302_m050705_c001_v0001101_t0000") 2022/03/02 05:07:22 DEBUG : empty space: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f106c8d53a7e7f998_d20220302_m050333_c001_v0001116_t0057") 2022/03/02 05:07:22 DEBUG : empty space: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1120815d2a068552_d20220302_m050329_c001_v0001090_t0050") 2022/03/02 05:07:22 DEBUG : empty space: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f109442d86ffc97b3_d20220302_m050327_c001_v0001117_t0040") 2022/03/02 05:07:23 DEBUG : empty space: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1120815d2a06854c_d20220302_m050325_c001_v0001090_t0059") 2022/03/02 05:07:23 DEBUG : empty space: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1098bff4d2626c4a_d20220302_m050323_c001_v0001104_t0029") 2022/03/02 05:07:23 DEBUG : empty space: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1120815d2a068545_d20220302_m050320_c001_v0001090_t0038") 2022/03/02 05:07:23 DEBUG : empty space: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f100ab962b2dc53db_d20220302_m050318_c001_v0001102_t0057") 2022/03/02 05:07:23 DEBUG : empty space: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1120815d2a06853b_d20220302_m050315_c001_v0001090_t0042") 2022/03/02 05:07:23 DEBUG : empty space: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1196586ec46918e1_d20220302_m050311_c001_v0001105_t0030") 2022/03/02 05:07:23 DEBUG : empty space: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1120815d2a068531_d20220302_m050309_c001_v0001090_t0037") 2022/03/02 05:07:23 DEBUG : empty space: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f110948c03b765034_d20220302_m050151_c001_v0001104_t0031") 2022/03/02 05:07:23 DEBUG : empty space: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f114785a26742543f_d20220302_m050147_c001_v0001116_t0054") 2022/03/02 05:07:23 DEBUG : empty space: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f104ae1b333008b83_d20220302_m050140_c001_v0001040_t0004") 2022/03/02 05:07:23 DEBUG : empty space: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f114785a267425332_d20220302_m050135_c001_v0001116_t0024") 2022/03/02 05:07:23 DEBUG : empty space: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f11740ffef250bb2e_d20220302_m050125_c001_v0001102_t0013") 2022/03/02 05:07:23 DEBUG : empty space: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f114785a267425218_d20220302_m050121_c001_v0001116_t0054") 2022/03/02 05:07:23 DEBUG : file1: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f10478c0bd0f6f7f5_d20220302_m050411_c001_v0001104_t0002") 2022/03/02 05:07:23 DEBUG : file1: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1120815d2a068598_d20220302_m050410_c001_v0001090_t0003") 2022/03/02 05:07:23 DEBUG : file1: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f11301625c7de4748_d20220302_m050403_c001_v0001115_t0046") 2022/03/02 05:07:23 DEBUG : file1: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1120815d2a068589_d20220302_m050400_c001_v0001090_t0050") 2022/03/02 05:07:23 DEBUG : file1: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f118c7c68e0aa2b31_d20220302_m050344_c001_v0001154_t0017") 2022/03/02 05:07:23 DEBUG : file1: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1120815d2a068561_d20220302_m050340_c001_v0001090_t0043") 2022/03/02 05:07:23 DEBUG : file1: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f118e835765763cf5_d20220302_m050258_c001_v0001113_t0028") 2022/03/02 05:07:24 DEBUG : file1: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1120815d2a06851d_d20220302_m050253_c001_v0001090_t0025") 2022/03/02 05:07:24 DEBUG : file1: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1055223056b37550_d20220302_m050251_c001_v0001161_t0025") 2022/03/02 05:07:24 DEBUG : file1: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1120815d2a068516_d20220302_m050249_c001_v0001090_t0029") 2022/03/02 05:07:24 DEBUG : file1: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f11891b52fb8b336e_d20220302_m050119_c001_v0001099_t0044") 2022/03/02 05:07:24 DEBUG : file1: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f114785a2674251bd_d20220302_m050117_c001_v0001116_t0012") 2022/03/02 05:07:24 DEBUG : file1: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f114785a267425180_d20220302_m050114_c001_v0001116_t0013") 2022/03/02 05:07:24 DEBUG : file1: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f114785a26742513f_d20220302_m050111_c001_v0001116_t0057") 2022/03/02 05:07:24 DEBUG : file2: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f116ac0cc5d394e71_d20220302_m050404_c001_v0001106_t0007") 2022/03/02 05:07:24 DEBUG : file2: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1120815d2a06858f_d20220302_m050403_c001_v0001090_t0024") 2022/03/02 05:07:24 DEBUG : file2: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f100920fd3e37fd19_d20220302_m050344_c001_v0001117_t0002") 2022/03/02 05:07:24 DEBUG : file2: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1120815d2a068565_d20220302_m050341_c001_v0001090_t0043") 2022/03/02 05:07:24 DEBUG : filename.txt: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f107e719cf4b3edf8_d20220302_m050405_c001_v0001106_t0050") 2022/03/02 05:07:24 DEBUG : filename.txt: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1120815d2a06858b_d20220302_m050402_c001_v0001090_t0023") 2022/03/02 05:07:24 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1169d157c4791b75_d20220302_m050644_c001_v0001115_t0023") 2022/03/02 05:07:24 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f106610c5dfbaf417_d20220302_m050643_c001_v0001101_t0035") 2022/03/02 05:07:24 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f11077364dd6ef5ce_d20220302_m050637_c001_v0001101_t0027") 2022/03/02 05:07:24 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f106610c5dfbaf32d_d20220302_m050634_c001_v0001101_t0001") 2022/03/02 05:07:24 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f11669fcadfdac75c_d20220302_m050645_c001_v0001134_t0015") 2022/03/02 05:07:24 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f106610c5dfbaf40d_d20220302_m050642_c001_v0001101_t0059") 2022/03/02 05:07:25 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f109433cd8a36db96_d20220302_m050641_c001_v0001099_t0003") 2022/03/02 05:07:25 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f106610c5dfbaf322_d20220302_m050634_c001_v0001101_t0042") 2022/03/02 05:07:25 DEBUG : large: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f104835659ec8731a_d20220302_m050339_c001_v0001128_t0023") 2022/03/02 05:07:25 DEBUG : large: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1120815d2a06855a_d20220302_m050337_c001_v0001090_t0008") 2022/03/02 05:07:25 DEBUG : medium: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f116167776f614815_d20220302_m050338_c001_v0001116_t0045") 2022/03/02 05:07:25 DEBUG : medium: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1120815d2a068558_d20220302_m050336_c001_v0001090_t0046") 2022/03/02 05:07:25 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f113c5a70f9a259bf_d20220302_m050623_c001_v0001130_t0058") 2022/03/02 05:07:25 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f106610c5dfbaf1e2_d20220302_m050621_c001_v0001101_t0017") 2022/03/02 05:07:25 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1164b9b00e7f2df4_d20220302_m050624_c001_v0001039_t0058") 2022/03/02 05:07:25 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f106610c5dfbaf1ae_d20220302_m050620_c001_v0001101_t0055") 2022/03/02 05:07:25 DEBUG : not-one: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1194e51ecbc5384f_d20220302_m050229_c001_v0001103_t0028") 2022/03/02 05:07:25 DEBUG : not-one: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1120815d2a0684f3_d20220302_m050226_c001_v0001090_t0026") 2022/03/02 05:07:25 DEBUG : one: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f11259e81990f4fe9_d20220302_m050227_c001_v0001117_t0047") 2022/03/02 05:07:25 DEBUG : one: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1120815d2a0684eb_d20220302_m050224_c001_v0001090_t0039") 2022/03/02 05:07:25 DEBUG : potato1: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f103bd9113c7d5ac6_d20220302_m050655_c001_v0001101_t0003") 2022/03/02 05:07:25 DEBUG : potato1: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f106610c5dfbaf45e_d20220302_m050646_c001_v0001101_t0051") 2022/03/02 05:07:25 DEBUG : potato2: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f10550e3804dbabd3_d20220302_m050716_c001_v0001165_t0002") 2022/03/02 05:07:25 DEBUG : potato2: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f115d86a8a6e35537_d20220302_m050711_c001_v0001106_t0046") 2022/03/02 05:07:25 DEBUG : potato2: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f106610c5dfbaf650_d20220302_m050704_c001_v0001101_t0047") 2022/03/02 05:07:25 DEBUG : potato2: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1009a7f62097f2f8_d20220302_m050656_c001_v0001104_t0045") 2022/03/02 05:07:26 DEBUG : potato2: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f106610c5dfbaf475_d20220302_m050647_c001_v0001101_t0020") 2022/03/02 05:07:26 DEBUG : potato2: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f101ea3c96f449256_d20220302_m050334_c001_v0001115_t0016") 2022/03/02 05:07:26 DEBUG : potato2: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1120815d2a068550_d20220302_m050329_c001_v0001090_t0004") 2022/03/02 05:07:26 DEBUG : potato2: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f112af2fc52f12ea9_d20220302_m050328_c001_v0001100_t0047") 2022/03/02 05:07:26 DEBUG : potato2: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1120815d2a06854a_d20220302_m050325_c001_v0001090_t0036") 2022/03/02 05:07:26 DEBUG : potato2: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f11143751bd71e1eb_d20220302_m050324_c001_v0001100_t0039") 2022/03/02 05:07:26 DEBUG : potato2: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1120815d2a068541_d20220302_m050320_c001_v0001090_t0031") 2022/03/02 05:07:26 DEBUG : potato2: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1101fbff40b45baa_d20220302_m050318_c001_v0001099_t0059") 2022/03/02 05:07:26 DEBUG : potato2: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1120815d2a068536_d20220302_m050313_c001_v0001090_t0017") 2022/03/02 05:07:26 DEBUG : potato2: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f116cc350dd473f0e_d20220302_m050312_c001_v0001144_t0019") 2022/03/02 05:07:26 DEBUG : potato2: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1120815d2a06852f_d20220302_m050306_c001_v0001090_t0012") 2022/03/02 05:07:26 DEBUG : potato2: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f117797e08c4d76b4_d20220302_m050152_c001_v0001100_t0058") 2022/03/02 05:07:26 DEBUG : potato2: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f114785a267425457_d20220302_m050149_c001_v0001116_t0059") 2022/03/02 05:07:26 DEBUG : potato2: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1101446f02289845_d20220302_m050141_c001_v0001100_t0012") 2022/03/02 05:07:26 DEBUG : potato2: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f114785a26742534c_d20220302_m050136_c001_v0001116_t0055") 2022/03/02 05:07:26 DEBUG : potato2: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f107df0ce3cb12b4f_d20220302_m050126_c001_v0001103_t0009") 2022/03/02 05:07:27 DEBUG : potato2: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f114785a267425233_d20220302_m050122_c001_v0001116_t0042") 2022/03/02 05:07:27 DEBUG : remotepotato: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f109a143cd8b32856_d20220302_m050153_c001_v0001102_t0033") 2022/03/02 05:07:27 DEBUG : remotepotato: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f114785a267425482_d20220302_m050150_c001_v0001116_t0004") 2022/03/02 05:07:27 DEBUG : remotepotato: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f107e44ef320a864f_d20220302_m050144_c001_v0001104_t0007") 2022/03/02 05:07:27 DEBUG : remotepotato: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f114785a267425365_d20220302_m050138_c001_v0001116_t0015") 2022/03/02 05:07:27 DEBUG : remotepotato: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f109240d8e1d28364_d20220302_m050128_c001_v0001038_t0053") 2022/03/02 05:07:27 DEBUG : remotepotato: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f114785a26742524c_d20220302_m050124_c001_v0001116_t0041") 2022/03/02 05:07:27 DEBUG : rutabaga: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f113315cfd544634b_d20220302_m050153_c001_v0001116_t0002") 2022/03/02 05:07:27 DEBUG : rutabaga: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f114785a267425417_d20220302_m050146_c001_v0001116_t0018") 2022/03/02 05:07:27 DEBUG : rutabaga: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f11638d9b1ce4b39c_d20220302_m050145_c001_v0001104_t0000") 2022/03/02 05:07:27 DEBUG : rutabaga: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f114785a2674252fb_d20220302_m050132_c001_v0001116_t0038") 2022/03/02 05:07:27 DEBUG : rutabaga: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f104b9771bbad559c_d20220302_m050131_c001_v0001103_t0013") 2022/03/02 05:07:27 DEBUG : rutabaga: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f114785a267425203_d20220302_m050120_c001_v0001116_t0013") 2022/03/02 05:07:27 DEBUG : small: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f113eb31c3b27b02f_d20220302_m050338_c001_v0001101_t0015") 2022/03/02 05:07:27 DEBUG : small: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1120815d2a068556_d20220302_m050336_c001_v0001090_t0045") 2022/03/02 05:07:27 DEBUG : sub dir/hello world: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1169d157c4790d50_d20220302_m050305_c001_v0001115_t0057") 2022/03/02 05:07:27 DEBUG : sub dir/hello world: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1120815d2a06852a_d20220302_m050303_c001_v0001090_t0005") 2022/03/02 05:07:27 DEBUG : sub dir/hello world: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1061c4d2eca65b6d_d20220302_m050243_c001_v0001120_t0021") 2022/03/02 05:07:27 DEBUG : sub dir/hello world: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1120815d2a0684fb_d20220302_m050234_c001_v0001090_t0011") 2022/03/02 05:07:27 DEBUG : sub dir/hello world2: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f106b9ce75f6b0805_d20220302_m050244_c001_v0001161_t0043") 2022/03/02 05:07:28 DEBUG : sub dir/hello world2: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1120815d2a0684fd_d20220302_m050234_c001_v0001090_t0052") 2022/03/02 05:07:28 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1111f9e7fa33a6b8_d20220302_m050246_c001_v0001104_t0043") 2022/03/02 05:07:28 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1120815d2a0684ff_d20220302_m050236_c001_v0001090_t0019") 2022/03/02 05:07:28 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1012fdc3f8c03fc6_d20220302_m050246_c001_v0001165_t0029") 2022/03/02 05:07:28 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1120815d2a068505_d20220302_m050238_c001_v0001090_t0021") 2022/03/02 05:07:28 DEBUG : sub dir/potato3: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f100b5b1da5d98ef2_d20220302_m050716_c001_v0001041_t0055") 2022/03/02 05:07:28 DEBUG : sub dir/potato3: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f10900af12f99ebf5_d20220302_m050714_c001_v0001144_t0038") 2022/03/02 05:07:28 DEBUG : sub dir/potato3: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f106610c5dfbaf695_d20220302_m050706_c001_v0001101_t0039") 2022/03/02 05:07:28 DEBUG : sub dir/potato3: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1083eaa47d15acf9_d20220302_m050335_c001_v0001036_t0004") 2022/03/02 05:07:28 DEBUG : sub dir/potato3: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1120815d2a068554_d20220302_m050329_c001_v0001090_t0058") 2022/03/02 05:07:28 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f114818a449162aed_d20220302_m050247_c001_v0001100_t0022") 2022/03/02 05:07:28 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1120815d2a068507_d20220302_m050238_c001_v0001090_t0014") 2022/03/02 05:07:28 DEBUG : sub/file2: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1038ac11add1d6c3_d20220302_m050428_c001_v0001145_t0020") 2022/03/02 05:07:28 DEBUG : sub/file2: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f106610c5dfbae477_d20220302_m050425_c001_v0001101_t0054") 2022/03/02 05:07:28 DEBUG : sub/file2: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f112118c2af099561_d20220302_m050409_c001_v0001091_t0046") 2022/03/02 05:07:28 DEBUG : sub/file2: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1120815d2a068594_d20220302_m050406_c001_v0001090_t0017") 2022/03/02 05:07:28 DEBUG : sub/file2: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f11191a8c5adb8eb3_d20220302_m050259_c001_v0001101_t0059") 2022/03/02 05:07:29 DEBUG : sub/file2: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1120815d2a068520_d20220302_m050253_c001_v0001090_t0030") 2022/03/02 05:07:29 DEBUG : sub/file2: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1180cf612762dce8_d20220302_m050252_c001_v0001120_t0047") 2022/03/02 05:07:29 DEBUG : sub/file2: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1120815d2a068518_d20220302_m050249_c001_v0001090_t0024") 2022/03/02 05:07:29 DEBUG : test.sum: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f111ebf993d12245f_d20220302_m050221_c001_v0001161_t0054") 2022/03/02 05:07:29 DEBUG : test.sum: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1120815d2a0684e6_d20220302_m050218_c001_v0001090_t0049") 2022/03/02 05:07:29 DEBUG : test.sum: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1120815d2a0684df_d20220302_m050216_c001_v0001090_t0053") 2022/03/02 05:07:29 DEBUG : test.sum: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1120815d2a0684db_d20220302_m050214_c001_v0001090_t0058") 2022/03/02 05:07:29 DEBUG : test.sum: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1120815d2a0684d8_d20220302_m050213_c001_v0001090_t0034") 2022/03/02 05:07:29 DEBUG : test.sum: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1120815d2a0684d4_d20220302_m050211_c001_v0001090_t0028") 2022/03/02 05:07:29 DEBUG : test.sum: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1120815d2a0684d2_d20220302_m050207_c001_v0001090_t0016") 2022/03/02 05:07:29 DEBUG : test.sum: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1120815d2a0684cc_d20220302_m050205_c001_v0001090_t0020") 2022/03/02 05:07:29 DEBUG : test.sum: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f11296c7ffb08b6ea_d20220302_m050157_c001_v0001104_t0056") 2022/03/02 05:07:29 DEBUG : test.sum: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f114785a26742550f_d20220302_m050156_c001_v0001116_t0003") 2022/03/02 05:07:29 DEBUG : test.sum: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f114785a2674254e6_d20220302_m050155_c001_v0001116_t0006") 2022/03/02 05:07:29 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f11060c2c0417494f_d20220302_m050630_c001_v0001144_t0023") 2022/03/02 05:07:29 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f106610c5dfbaf28b_d20220302_m050627_c001_v0001101_t0026") 2022/03/02 05:07:29 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f103690a2bbef0f81_d20220302_m050631_c001_v0001099_t0041") 2022/03/02 05:07:30 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f106610c5dfbaf265_d20220302_m050626_c001_v0001101_t0026") 2022/03/02 05:07:30 DEBUG : zend.txt: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f10550e3804dba5b1_d20220302_m050247_c001_v0001165_t0050") 2022/03/02 05:07:30 DEBUG : zend.txt: Deleting (id "4_z3f72ed43000d4b9b7ff80e1b_f1120815d2a0684f9_d20220302_m050233_c001_v0001090_t0000") "./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose" - Finished OK in 6m23.810279979s (try 1/5)