"./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose" - Starting (try 1/5) 2021/12/01 05:23:20 DEBUG : Creating backend with remote "TestB2:rclone-test-hibifav9coyomip0resotax0" 2021/12/01 05:23:20 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2021/12/01 05:23:21 DEBUG : Creating backend with remote "/tmp/rclone1263029806" === 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-hibifav9coyomip0resotax0", Local "Local file system at /tmp/rclone1263029806", Modify Window "1ms" === RUN TestMultithreadCopy/{size:131071_streams:2} 2021/12/01 05:23:24 DEBUG : file1: Starting multi-thread copy with 2 parts of size 64Ki 2021/12/01 05:23:24 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131071) size 63.999Ki starting 2021/12/01 05:23:24 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64Ki starting 2021/12/01 05:23:25 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131071) size 63.999Ki finished 2021/12/01 05:23:25 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64Ki finished 2021/12/01 05:23:25 DEBUG : file1: Finished multi-thread copy with 2 parts of size 64Ki === RUN TestMultithreadCopy/{size:131072_streams:2} 2021/12/01 05:23:26 DEBUG : file1: Starting multi-thread copy with 2 parts of size 64Ki 2021/12/01 05:23:26 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131072) size 64Ki starting 2021/12/01 05:23:26 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64Ki starting 2021/12/01 05:23:26 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131072) size 64Ki finished 2021/12/01 05:23:26 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64Ki finished 2021/12/01 05:23:26 DEBUG : file1: Finished multi-thread copy with 2 parts of size 64Ki === RUN TestMultithreadCopy/{size:131073_streams:2} 2021/12/01 05:23:27 DEBUG : file1: Starting multi-thread copy with 2 parts of size 128Ki 2021/12/01 05:23:27 DEBUG : file1: multi-thread copy: stream 2/2 (131072-131073) size 1 starting 2021/12/01 05:23:27 DEBUG : file1: multi-thread copy: stream 1/2 (0-131072) size 128Ki starting 2021/12/01 05:23:28 DEBUG : file1: multi-thread copy: stream 2/2 (131072-131073) size 1 finished 2021/12/01 05:23:28 DEBUG : file1: multi-thread copy: stream 1/2 (0-131072) size 128Ki finished 2021/12/01 05:23:28 DEBUG : file1: Finished multi-thread copy with 2 parts of size 128Ki --- PASS: TestMultithreadCopy (7.66s) --- PASS: TestMultithreadCopy/{size:131071_streams:2} (4.08s) --- PASS: TestMultithreadCopy/{size:131072_streams:2} (1.42s) --- PASS: TestMultithreadCopy/{size:131073_streams:2} (1.10s) === 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 05:23:29 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/10: test error 2021/12/01 05:23:29 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/10: test error 2021/12/01 05:23:29 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/10: test error === RUN TestReOpen/Seek/TooManyErrors 2021/12/01 05:23:29 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/3: test error 2021/12/01 05:23:29 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/3: test error 2021/12/01 05:23:29 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/3: test error 2021/12/01 05:23:29 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 05:23:29 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/10: test error 2021/12/01 05:23:29 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/10: test error 2021/12/01 05:23:29 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/10: test error === RUN TestReOpen/Range/TooManyErrors 2021/12/01 05:23:29 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/3: test error 2021/12/01 05:23:29 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/3: test error 2021/12/01 05:23:29 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/3: test error 2021/12/01 05:23:29 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-hibifav9coyomip0resotax0", Local "Local file system at /tmp/rclone1263029806", 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 (12.00s) --- PASS: TestCheck/1 (0.18s) --- PASS: TestCheck/2 (0.18s) --- PASS: TestCheck/3 (0.18s) --- PASS: TestCheck/4 (0.18s) --- PASS: TestCheck/5 (0.18s) --- PASS: TestCheck/6 (0.18s) --- PASS: TestCheck/7 (0.18s) === RUN TestCheckFsError 2021/12/01 05:23:41 DEBUG : Creating backend with remote "non-existent" 2021/12/01 05:23:41 DEBUG : Creating backend with remote "non-existent" 2021/12/01 05:23:41 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 05:23:41 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 05:23:41 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/non-existent: 2 differences found 2021/12/01 05:23:41 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-hibifav9coyomip0resotax0", Local "Local file system at /tmp/rclone1263029806", 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 (9.27s) --- PASS: TestCheckDownload/1 (0.87s) --- PASS: TestCheckDownload/2 (0.35s) --- PASS: TestCheckDownload/3 (0.41s) --- PASS: TestCheckDownload/4 (0.53s) --- PASS: TestCheckDownload/5 (0.37s) --- PASS: TestCheckDownload/6 (0.36s) --- PASS: TestCheckDownload/7 (0.37s) === RUN TestCheckSizeOnly run.go:181: Remote "B2 bucket rclone-test-hibifav9coyomip0resotax0", Local "Local file system at /tmp/rclone1263029806", Modify Window "1ms" === RUN TestCheckSizeOnly/1 === RUN TestCheckSizeOnly/2 === RUN TestCheckSizeOnly/3 === RUN TestCheckSizeOnly/4 === RUN TestCheckSizeOnly/5 === RUN TestCheckSizeOnly/6 === RUN TestCheckSizeOnly/7 --- PASS: TestCheckSizeOnly (9.57s) --- PASS: TestCheckSizeOnly/1 (0.18s) --- PASS: TestCheckSizeOnly/2 (0.28s) --- PASS: TestCheckSizeOnly/3 (0.18s) --- PASS: TestCheckSizeOnly/4 (0.18s) --- PASS: TestCheckSizeOnly/5 (0.18s) --- PASS: TestCheckSizeOnly/6 (0.19s) --- PASS: TestCheckSizeOnly/7 (0.18s) === RUN TestCheckEqualReaders --- PASS: TestCheckEqualReaders (0.00s) === RUN TestParseSumFile run.go:181: Remote "B2 bucket rclone-test-hibifav9coyomip0resotax0", Local "Local file system at /tmp/rclone1263029806", Modify Window "1ms" 2021/12/01 05:24:01 NOTICE: test.sum: improperly formatted checksum line 4 2021/12/01 05:24:01 NOTICE: test.sum: improperly formatted checksum line 5 2021/12/01 05:24:01 NOTICE: test.sum: improperly formatted checksum line 6 2021/12/01 05:24:01 NOTICE: test.sum: 2 warning(s) suppressed... 2021/12/01 05:24:02 NOTICE: test.sum: improperly formatted checksum line 4 2021/12/01 05:24:02 NOTICE: test.sum: improperly formatted checksum line 5 2021/12/01 05:24:02 NOTICE: test.sum: improperly formatted checksum line 6 2021/12/01 05:24:02 NOTICE: test.sum: 2 warning(s) suppressed... --- PASS: TestParseSumFile (2.92s) === RUN TestCheckSum run.go:181: Remote "B2 bucket rclone-test-hibifav9coyomip0resotax0", Local "Local file system at /tmp/rclone1263029806", Modify Window "1ms" 2021/12/01 05:24:02 DEBUG : Creating backend with remote "TestB2:rclone-test-hibifav9coyomip0resotax0/data" 2021/12/01 05:24:03 DEBUG : Couldn't decode error response: EOF check_test.go:356: B2 bucket rclone-test-hibifav9coyomip0resotax0 path data lacks md5, skipping --- SKIP: TestCheckSum (1.35s) === RUN TestCheckSumDownload run.go:181: Remote "B2 bucket rclone-test-hibifav9coyomip0resotax0", Local "Local file system at /tmp/rclone1263029806", Modify Window "1ms" 2021/12/01 05:24:04 DEBUG : Creating backend with remote "TestB2:rclone-test-hibifav9coyomip0resotax0/data" 2021/12/01 05:24:04 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 (11.63s) --- PASS: TestCheckSumDownload/subtest1 (0.96s) --- PASS: TestCheckSumDownload/subtest2 (0.72s) --- PASS: TestCheckSumDownload/subtest3 (0.91s) --- PASS: TestCheckSumDownload/subtest4 (0.75s) --- PASS: TestCheckSumDownload/subtest5 (0.71s) --- PASS: TestCheckSumDownload/subtest6 (0.72s) --- PASS: TestCheckSumDownload/subtest7 (0.83s) === RUN TestDeduplicateInteractive run.go:181: Remote "B2 bucket rclone-test-hibifav9coyomip0resotax0", Local "Local file system at /tmp/rclone1263029806", 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-hibifav9coyomip0resotax0", Local "Local file system at /tmp/rclone1263029806", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSkip (0.36s) === RUN TestDeduplicateSizeOnly run.go:181: Remote "B2 bucket rclone-test-hibifav9coyomip0resotax0", Local "Local file system at /tmp/rclone1263029806", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSizeOnly (0.35s) === RUN TestDeduplicateFirst run.go:181: Remote "B2 bucket rclone-test-hibifav9coyomip0resotax0", Local "Local file system at /tmp/rclone1263029806", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateFirst (0.36s) === RUN TestDeduplicateNewest run.go:181: Remote "B2 bucket rclone-test-hibifav9coyomip0resotax0", Local "Local file system at /tmp/rclone1263029806", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateNewest (0.35s) === RUN TestDeduplicateNewestByHash run.go:181: Remote "B2 bucket rclone-test-hibifav9coyomip0resotax0", Local "Local file system at /tmp/rclone1263029806", Modify Window "1ms" 2021/12/01 05:24:17 DEBUG : one: Clearing upload URL because of error: Post "https://pod-000-1165-19.backblaze.com/b2api/v1/b2_upload_file/6f828d53702d7b4b7fd80e1b/c001_v0001165_t0013": EOF 2021/12/01 05:24:17 DEBUG : pacer: low level retry 1/1 (error Post "https://pod-000-1165-19.backblaze.com/b2api/v1/b2_upload_file/6f828d53702d7b4b7fd80e1b/c001_v0001165_t0013": EOF) 2021/12/01 05:24:17 DEBUG : pacer: Rate limited, increasing sleep to 20ms run.go:283: Retry Put of "one" to B2 bucket rclone-test-hibifav9coyomip0resotax0: 1/10 (Post "https://pod-000-1165-19.backblaze.com/b2api/v1/b2_upload_file/6f828d53702d7b4b7fd80e1b/c001_v0001165_t0013": EOF) 2021/12/01 05:24:19 DEBUG : pacer: Reducing sleep to 10ms 2021/12/01 05:24:24 INFO : B2 bucket rclone-test-hibifav9coyomip0resotax0: Looking for duplicate sha1 hashes using newest mode. 2021/12/01 05:24:24 NOTICE: 4813a183e69afc6d5ada51b238c11c4b5a1b04f9: Found 3 files with duplicate sha1 hashes 2021/12/01 05:24:25 INFO : one: Deleted 2021/12/01 05:24:26 INFO : also/one: Deleted 2021/12/01 05:24:26 NOTICE: 4813a183e69afc6d5ada51b238c11c4b5a1b04f9: Deleted 2 extra copies --- PASS: TestDeduplicateNewestByHash (12.51s) === RUN TestDeduplicateOldest run.go:181: Remote "B2 bucket rclone-test-hibifav9coyomip0resotax0", Local "Local file system at /tmp/rclone1263029806", 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-hibifav9coyomip0resotax0", Local "Local file system at /tmp/rclone1263029806", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateLargest (0.36s) === RUN TestDeduplicateSmallest run.go:181: Remote "B2 bucket rclone-test-hibifav9coyomip0resotax0", Local "Local file system at /tmp/rclone1263029806", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSmallest (0.36s) === RUN TestDeduplicateRename run.go:181: Remote "B2 bucket rclone-test-hibifav9coyomip0resotax0", Local "Local file system at /tmp/rclone1263029806", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateRename (0.36s) === RUN TestMergeDirs run.go:181: Remote "B2 bucket rclone-test-hibifav9coyomip0resotax0", Local "Local file system at /tmp/rclone1263029806", Modify Window "1ms" dedupe_test.go:267: Can't merge directories --- SKIP: TestMergeDirs (0.40s) === RUN TestListDirSorted run.go:181: Remote "B2 bucket rclone-test-hibifav9coyomip0resotax0", Local "Local file system at /tmp/rclone1263029806", Modify Window "1ms" 2021/12/01 05:24:44 DEBUG : a.txt: Excluded 2021/12/01 05:24:44 DEBUG : sub dir/hello world: Excluded 2021/12/01 05:24:44 DEBUG : sub dir/hello world2: Excluded 2021/12/01 05:24:44 DEBUG : sub dir/hello world: Excluded 2021/12/01 05:24:44 DEBUG : sub dir/hello world2: Excluded 2021/12/01 05:24:45 DEBUG : sub dir/ignore dir: Excluded 2021/12/01 05:24:45 DEBUG : Couldn't decode error response: EOF 2021/12/01 05:24:45 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSorted (21.46s) === RUN TestListJSON run.go:181: Remote "B2 bucket rclone-test-hibifav9coyomip0resotax0", Local "Local file system at /tmp/rclone1263029806", 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 05:24:59 ERROR : file1: Failed to read hash: hash type not supported --- PASS: TestListJSON (7.42s) --- PASS: TestListJSON/Default (0.17s) --- PASS: TestListJSON/FilesOnly (0.17s) --- PASS: TestListJSON/DirsOnly (0.17s) --- PASS: TestListJSON/Recurse (0.17s) --- PASS: TestListJSON/SubDir (0.17s) --- PASS: TestListJSON/NoModTime (0.18s) --- PASS: TestListJSON/NoMimeType (0.17s) --- PASS: TestListJSON/ShowHash (0.17s) --- PASS: TestListJSON/HashTypes (0.17s) === RUN TestStatJSON run.go:181: Remote "B2 bucket rclone-test-hibifav9coyomip0resotax0", Local "Local file system at /tmp/rclone1263029806", Modify Window "1ms" === RUN TestStatJSON/Root === RUN TestStatJSON/RootFilesOnly === RUN TestStatJSON/RootDirsOnly === RUN TestStatJSON/Dir 2021/12/01 05:25:04 DEBUG : Couldn't decode error response: EOF === RUN TestStatJSON/File === RUN TestStatJSON/NotFound 2021/12/01 05:25:04 DEBUG : Couldn't decode error response: EOF === RUN TestStatJSON/DirFilesOnly 2021/12/01 05:25:05 DEBUG : Couldn't decode error response: EOF === RUN TestStatJSON/FileFilesOnly === RUN TestStatJSON/NotFoundFilesOnly 2021/12/01 05:25:05 DEBUG : Couldn't decode error response: EOF === RUN TestStatJSON/DirDirsOnly === RUN TestStatJSON/FileDirsOnly === RUN TestStatJSON/NotFoundDirsOnly === RUN TestStatJSON/RootNotFound 2021/12/01 05:25:06 DEBUG : Creating backend with remote "TestB2:rclone-test-hibifav9coyomip0resotax0/notfound" 2021/12/01 05:25:07 DEBUG : Couldn't decode error response: EOF --- PASS: TestStatJSON (8.01s) --- PASS: TestStatJSON/Root (0.18s) --- PASS: TestStatJSON/RootFilesOnly (0.00s) --- PASS: TestStatJSON/RootDirsOnly (0.17s) --- PASS: TestStatJSON/Dir (0.90s) --- PASS: TestStatJSON/File (0.18s) --- PASS: TestStatJSON/NotFound (0.34s) --- PASS: TestStatJSON/DirFilesOnly (0.17s) --- PASS: TestStatJSON/FileFilesOnly (0.22s) --- PASS: TestStatJSON/NotFoundFilesOnly (0.17s) --- PASS: TestStatJSON/DirDirsOnly (0.17s) --- PASS: TestStatJSON/FileDirsOnly (0.17s) --- PASS: TestStatJSON/NotFoundDirsOnly (0.20s) --- PASS: TestStatJSON/RootNotFound (1.34s) === RUN TestMkdir run.go:181: Remote "B2 bucket rclone-test-hibifav9coyomip0resotax0", Local "Local file system at /tmp/rclone1263029806", Modify Window "1ms" 2021/12/01 05:25:08 DEBUG : B2 bucket rclone-test-hibifav9coyomip0resotax0: Making directory 2021/12/01 05:25:09 DEBUG : B2 bucket rclone-test-hibifav9coyomip0resotax0: Making directory --- PASS: TestMkdir (0.51s) === RUN TestLsd run.go:181: Remote "B2 bucket rclone-test-hibifav9coyomip0resotax0", Local "Local file system at /tmp/rclone1263029806", Modify Window "1ms" --- PASS: TestLsd (14.84s) === RUN TestLs run.go:181: Remote "B2 bucket rclone-test-hibifav9coyomip0resotax0", Local "Local file system at /tmp/rclone1263029806", Modify Window "1ms" --- PASS: TestLs (3.56s) === RUN TestLsWithFilesFrom run.go:181: Remote "B2 bucket rclone-test-hibifav9coyomip0resotax0", Local "Local file system at /tmp/rclone1263029806", Modify Window "1ms" 2021/12/01 05:25:30 DEBUG : empty space: Excluded 2021/12/01 05:25:31 DEBUG : Couldn't decode error response: EOF --- PASS: TestLsWithFilesFrom (4.90s) === RUN TestLsLong run.go:181: Remote "B2 bucket rclone-test-hibifav9coyomip0resotax0", Local "Local file system at /tmp/rclone1263029806", Modify Window "1ms" --- PASS: TestLsLong (2.71s) === RUN TestHashSums run.go:181: Remote "B2 bucket rclone-test-hibifav9coyomip0resotax0", Local "Local file system at /tmp/rclone1263029806", Modify Window "1ms" === RUN TestHashSums/Sha1 === RUN TestHashSums/Sha1Download --- PASS: TestHashSums (5.87s) --- PASS: TestHashSums/Sha1 (0.17s) --- PASS: TestHashSums/Sha1Download (0.87s) === RUN TestHashSumsWithErrors 2021/12/01 05:25:41 DEBUG : Creating backend with remote ":memory:" 2021/12/01 05:25:41 ERROR : file1: hash unsupported: hash type not supported --- PASS: TestHashSumsWithErrors (0.00s) === RUN TestHashStream 2021/12/01 05:25:41 DEBUG : Creating md5 hash of 0 bytes read from input stream 2021/12/01 05:25:41 DEBUG : Creating md5 hash of 0 bytes read from input stream 2021/12/01 05:25:41 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2021/12/01 05:25:41 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2021/12/01 05:25:41 DEBUG : Creating md5 hash of 12 bytes read from input stream 2021/12/01 05:25:41 DEBUG : Creating md5 hash of 12 bytes read from input stream 2021/12/01 05:25:41 DEBUG : Creating sha1 hash of 12 bytes read from input stream 2021/12/01 05:25:41 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-hibifav9coyomip0resotax0", Local "Local file system at /tmp/rclone1263029806", Modify Window "1ms" --- PASS: TestCount (7.97s) === RUN TestDelete run.go:181: Remote "B2 bucket rclone-test-hibifav9coyomip0resotax0", Local "Local file system at /tmp/rclone1263029806", Modify Window "1ms" 2021/12/01 05:25:52 DEBUG : Waiting for deletions to finish 2021/12/01 05:25:52 DEBUG : large: Excluded from sync (and deletion) 2021/12/01 05:25:52 INFO : medium: Deleted 2021/12/01 05:25:54 INFO : small: Deleted --- PASS: TestDelete (6.14s) === RUN TestRetry 2021/12/01 05:25:55 DEBUG : Received error: EOF - low level retry 1/5 2021/12/01 05:25:55 DEBUG : Received error: EOF - low level retry 2/5 2021/12/01 05:25:55 DEBUG : Received error: EOF - low level retry 1/5 2021/12/01 05:25:55 DEBUG : Received error: EOF - low level retry 2/5 2021/12/01 05:25:55 DEBUG : Received error: EOF - low level retry 3/5 2021/12/01 05:25:55 DEBUG : Received error: EOF - low level retry 4/5 2021/12/01 05:25:55 DEBUG : Received error: EOF - low level retry 5/5 --- PASS: TestRetry (0.00s) === RUN TestCat run.go:181: Remote "B2 bucket rclone-test-hibifav9coyomip0resotax0", Local "Local file system at /tmp/rclone1263029806", Modify Window "1ms" --- PASS: TestCat (8.49s) === RUN TestPurge 2021/12/01 05:26:03 DEBUG : Creating backend with remote "TestB2:rclone-test-vudohav0kofepic1xiyolaz5" 2021/12/01 05:26:03 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2021/12/01 05:26:04 DEBUG : Creating backend with remote "/tmp/rclone3632635328" run.go:181: Remote "B2 bucket rclone-test-vudohav0kofepic1xiyolaz5", Local "Local file system at /tmp/rclone3632635328", Modify Window "1ms" 2021/12/01 05:26:06 DEBUG : A2: Making directory 2021/12/01 05:26:06 DEBUG : A1/B2: Making directory 2021/12/01 05:26:06 DEBUG : A1/B2/C2: Making directory 2021/12/01 05:26:06 DEBUG : A1/B1/C3: Making directory 2021/12/01 05:26:06 DEBUG : A3: Making directory 2021/12/01 05:26:06 DEBUG : A3/B3: Making directory 2021/12/01 05:26:06 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 05:26:08 DEBUG : A1/B1/C1/one: Deleting (id "4_zcfa20d03705d7b4b7fd80e1b_f10271ef4135f36dc_d20211201_m052605_c001_v0001040_t0001") 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 05:26:08 DEBUG : A1/two: Deleting (id "4_zcfa20d03705d7b4b7fd80e1b_f10271ef4135f36e1_d20211201_m052606_c001_v0001040_t0053") 2021/12/01 05:26:09 DEBUG : B2 bucket rclone-test-vudohav0kofepic1xiyolaz5: Purge remote 2021/12/01 05:26:10 purge failed: directory not found --- PASS: TestPurge (6.20s) === RUN TestRmdirsNoLeaveRoot run.go:181: Remote "B2 bucket rclone-test-hibifav9coyomip0resotax0", Local "Local file system at /tmp/rclone1263029806", Modify Window "1ms" 2021/12/01 05:26:14 DEBUG : A2: Making directory 2021/12/01 05:26:14 DEBUG : A1/B2: Making directory 2021/12/01 05:26:14 DEBUG : A1/B2/C2: Making directory 2021/12/01 05:26:14 DEBUG : A1/B1/C3: Making directory 2021/12/01 05:26:14 DEBUG : A3: Making directory 2021/12/01 05:26:14 DEBUG : A3/B3: Making directory 2021/12/01 05:26:14 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 05:26:15 DEBUG : pacer: low level retry 1/10 (error Post "https://api001.backblazeb2.com/b2api/v1/b2_list_file_names": EOF) 2021/12/01 05:26:15 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2021/12/01 05:26:15 DEBUG : pacer: Reducing sleep to 10ms 2021/12/01 05:26:15 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 (8.42s) === RUN TestRmdirsLeaveRoot run.go:181: Remote "B2 bucket rclone-test-hibifav9coyomip0resotax0", Local "Local file system at /tmp/rclone1263029806", Modify Window "1ms" 2021/12/01 05:26:18 DEBUG : A1: Making directory 2021/12/01 05:26:18 DEBUG : A1/B1: Making directory 2021/12/01 05:26:18 DEBUG : A1/B1/C1: Making directory fstest.go:251: Filtering empty directory "A1" fstest.go:251: Filtering empty directory "A1/B1" fstest.go:251: Filtering empty directory "A1/B1/C1" fstest.go:251: Filtering empty directory "A1" --- PASS: TestRmdirsLeaveRoot (0.86s) === RUN TestRmdirsWithFilter run.go:181: Remote "B2 bucket rclone-test-hibifav9coyomip0resotax0", Local "Local file system at /tmp/rclone1263029806", Modify Window "1ms" 2021/12/01 05:26:19 DEBUG : A1: Making directory 2021/12/01 05:26:19 DEBUG : A1/B1: Making directory 2021/12/01 05:26:19 DEBUG : A1/B1/C1: Making directory fstest.go:251: Filtering empty directory "A1" fstest.go:251: Filtering empty directory "A1/B1" fstest.go:251: Filtering empty directory "A1/B1/C1" fstest.go:251: Filtering empty directory "A1" --- PASS: TestRmdirsWithFilter (0.86s) === RUN TestCopyURL run.go:181: Remote "B2 bucket rclone-test-hibifav9coyomip0resotax0", Local "Local file system at /tmp/rclone1263029806", Modify Window "1ms" 2021/12/01 05:26:24 DEBUG : filename.txt: File name found in url 2021/12/01 05:26:28 DEBUG : pacer: low level retry 1/10 (error Post "https://api001.backblazeb2.com/b2api/v1/b2_list_file_names": EOF) 2021/12/01 05:26:28 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2021/12/01 05:26:29 DEBUG : pacer: Reducing sleep to 10ms --- PASS: TestCopyURL (11.21s) === RUN TestCopyURLToWriter --- PASS: TestCopyURLToWriter (0.00s) === RUN TestMoveFile run.go:181: Remote "B2 bucket rclone-test-hibifav9coyomip0resotax0", Local "Local file system at /tmp/rclone1263029806", Modify Window "1ms" 2021/12/01 05:26:32 DEBUG : Couldn't decode error response: EOF 2021/12/01 05:26:32 DEBUG : file1: Need to transfer - File not found at Destination 2021/12/01 05:26:33 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2021/12/01 05:26:33 INFO : file1: Copied (new) to: sub/file2 2021/12/01 05:26:33 INFO : file1: Deleted 2021/12/01 05:26:33 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/01 05:26:33 DEBUG : file1: Unchanged skipping 2021/12/01 05:26:33 INFO : file1: Deleted 2021/12/01 05:26:33 DEBUG : B2 bucket rclone-test-hibifav9coyomip0resotax0: don't need to copy/move sub/file2, it is already at target location --- PASS: TestMoveFile (4.45s) === RUN TestMoveFileWithIgnoreExisting run.go:181: Remote "B2 bucket rclone-test-hibifav9coyomip0resotax0", Local "Local file system at /tmp/rclone1263029806", Modify Window "1ms" 2021/12/01 05:26:36 DEBUG : Couldn't decode error response: EOF 2021/12/01 05:26:36 DEBUG : file1: Need to transfer - File not found at Destination 2021/12/01 05:26:36 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2021/12/01 05:26:36 INFO : file1: Copied (new) 2021/12/01 05:26:36 INFO : file1: Deleted 2021/12/01 05:26:36 DEBUG : file1: Destination exists, skipping 2021/12/01 05:26:36 DEBUG : file1: Not removing source file as destination file exists and --ignore-existing is set --- PASS: TestMoveFileWithIgnoreExisting (1.88s) === RUN TestCaseInsensitiveMoveFile run.go:181: Remote "B2 bucket rclone-test-hibifav9coyomip0resotax0", Local "Local file system at /tmp/rclone1263029806", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFile (0.34s) === RUN TestMoveFileBackupDir run.go:181: Remote "B2 bucket rclone-test-hibifav9coyomip0resotax0", Local "Local file system at /tmp/rclone1263029806", Modify Window "1ms" 2021/12/01 05:26:39 DEBUG : Creating backend with remote "TestB2:rclone-test-hibifav9coyomip0resotax0/backup" 2021/12/01 05:26:40 DEBUG : Couldn't decode error response: EOF 2021/12/01 05:26:40 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18) 2021/12/01 05:26:41 DEBUG : Couldn't decode error response: EOF 2021/12/01 05:26:42 DEBUG : dst/file1: sha1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK 2021/12/01 05:26:42 INFO : dst/file1: Copied (server-side copy) 2021/12/01 05:26:43 INFO : dst/file1: Deleted 2021/12/01 05:26:43 DEBUG : dst/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2021/12/01 05:26:43 INFO : dst/file1: Copied (new) 2021/12/01 05:26:43 INFO : dst/file1: Deleted --- PASS: TestMoveFileBackupDir (7.32s) === RUN TestCopyFile run.go:181: Remote "B2 bucket rclone-test-hibifav9coyomip0resotax0", Local "Local file system at /tmp/rclone1263029806", Modify Window "1ms" 2021/12/01 05:26:46 DEBUG : Couldn't decode error response: EOF 2021/12/01 05:26:46 DEBUG : file1: Need to transfer - File not found at Destination 2021/12/01 05:26:46 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2021/12/01 05:26:46 INFO : file1: Copied (new) to: sub/file2 2021/12/01 05:26:47 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/12/01 05:26:47 DEBUG : file1: Unchanged skipping 2021/12/01 05:26:47 DEBUG : B2 bucket rclone-test-hibifav9coyomip0resotax0: don't need to copy/move sub/file2, it is already at target location --- PASS: TestCopyFile (4.58s) === RUN TestCopyFileBackupDir run.go:181: Remote "B2 bucket rclone-test-hibifav9coyomip0resotax0", Local "Local file system at /tmp/rclone1263029806", Modify Window "1ms" 2021/12/01 05:26:51 DEBUG : Creating backend with remote "TestB2:rclone-test-hibifav9coyomip0resotax0/backup" 2021/12/01 05:26:52 DEBUG : Couldn't decode error response: EOF 2021/12/01 05:26:52 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18) 2021/12/01 05:26:52 DEBUG : Couldn't decode error response: EOF 2021/12/01 05:26:54 DEBUG : dst/file1: sha1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK 2021/12/01 05:26:54 INFO : dst/file1: Copied (server-side copy) 2021/12/01 05:26:55 INFO : dst/file1: Deleted 2021/12/01 05:26:55 DEBUG : dst/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2021/12/01 05:26:55 INFO : dst/file1: Copied (new) --- PASS: TestCopyFileBackupDir (7.41s) === RUN TestCopyFileCompareDest run.go:181: Remote "B2 bucket rclone-test-hibifav9coyomip0resotax0", Local "Local file system at /tmp/rclone1263029806", Modify Window "1ms" 2021/12/01 05:26:57 DEBUG : Creating backend with remote "TestB2:rclone-test-hibifav9coyomip0resotax0/dst" 2021/12/01 05:26:58 DEBUG : Couldn't decode error response: EOF 2021/12/01 05:26:59 DEBUG : Couldn't decode error response: EOF 2021/12/01 05:26:59 DEBUG : Creating backend with remote "TestB2:rclone-test-hibifav9coyomip0resotax0/CompareDest" 2021/12/01 05:26:59 DEBUG : Couldn't decode error response: EOF 2021/12/01 05:26:59 DEBUG : Couldn't decode error response: EOF 2021/12/01 05:26:59 DEBUG : one: Need to transfer - File not found at Destination 2021/12/01 05:26:59 DEBUG : pacer: low level retry 1/10 (error Post "https://api001.backblazeb2.com/b2api/v1/b2_create_bucket": EOF) 2021/12/01 05:26:59 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2021/12/01 05:27:00 DEBUG : pacer: Reducing sleep to 10ms 2021/12/01 05:27:03 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/12/01 05:27:03 INFO : one: Copied (new) 2021/12/01 05:27:04 DEBUG : Couldn't decode error response: EOF 2021/12/01 05:27:04 DEBUG : one: Sizes differ (src 5 vs dst 3) 2021/12/01 05:27:05 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2021/12/01 05:27:05 INFO : one: Copied (replaced existing) 2021/12/01 05:27:07 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/12/01 05:27:07 DEBUG : one: Destination found in --compare-dest, skipping 2021/12/01 05:27:10 DEBUG : Couldn't decode error response: EOF 2021/12/01 05:27:10 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/12/01 05:27:10 DEBUG : two: Destination found in --compare-dest, skipping 2021/12/01 05:27:10 DEBUG : Couldn't decode error response: EOF 2021/12/01 05:27:11 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/12/01 05:27:11 DEBUG : two: Destination found in --compare-dest, skipping 2021/12/01 05:27:11 DEBUG : Couldn't decode error response: EOF 2021/12/01 05:27:11 DEBUG : two: Sizes differ (src 5 vs dst 3) 2021/12/01 05:27:11 DEBUG : two: Need to transfer - File not found at Destination 2021/12/01 05:27:12 DEBUG : two: sha1 = a4be8877ad88f3109a6411985a1f359e10e2d930 OK 2021/12/01 05:27:12 INFO : two: Copied (new) --- PASS: TestCopyFileCompareDest (18.75s) === RUN TestCopyFileCopyDest run.go:181: Remote "B2 bucket rclone-test-hibifav9coyomip0resotax0", Local "Local file system at /tmp/rclone1263029806", Modify Window "1ms" 2021/12/01 05:27:16 DEBUG : Creating backend with remote "TestB2:rclone-test-hibifav9coyomip0resotax0/dst" 2021/12/01 05:27:17 DEBUG : Couldn't decode error response: EOF 2021/12/01 05:27:17 DEBUG : Couldn't decode error response: EOF 2021/12/01 05:27:17 DEBUG : Creating backend with remote "TestB2:rclone-test-hibifav9coyomip0resotax0/CopyDest" 2021/12/01 05:27:18 DEBUG : Couldn't decode error response: EOF 2021/12/01 05:27:18 DEBUG : Couldn't decode error response: EOF 2021/12/01 05:27:18 DEBUG : one: Need to transfer - File not found at Destination 2021/12/01 05:27:20 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/12/01 05:27:20 INFO : one: Copied (new) 2021/12/01 05:27:21 DEBUG : Couldn't decode error response: EOF 2021/12/01 05:27:21 DEBUG : one: Sizes differ (src 5 vs dst 3) 2021/12/01 05:27:22 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2021/12/01 05:27:22 INFO : one: Copied (replaced existing) 2021/12/01 05:27:25 DEBUG : Creating backend with remote "TestB2:rclone-test-hibifav9coyomip0resotax0/BackupDir" 2021/12/01 05:27:26 DEBUG : Couldn't decode error response: EOF 2021/12/01 05:27:26 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/12/01 05:27:26 DEBUG : one: Sizes differ (src 5 vs dst 3) 2021/12/01 05:27:26 DEBUG : Couldn't decode error response: EOF 2021/12/01 05:27:30 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/12/01 05:27:30 INFO : one: Copied (server-side copy) 2021/12/01 05:27:31 INFO : one: Deleted 2021/12/01 05:27:31 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2021/12/01 05:27:31 INFO : one: Copied (server-side copy) 2021/12/01 05:27:31 DEBUG : one: Destination found in --copy-dest, using server-side copy 2021/12/01 05:27:35 DEBUG : Couldn't decode error response: EOF 2021/12/01 05:27:35 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/12/01 05:27:35 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2021/12/01 05:27:35 INFO : two: Copied (server-side copy) 2021/12/01 05:27:35 DEBUG : two: Destination found in --copy-dest, using server-side copy 2021/12/01 05:27:36 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/12/01 05:27:36 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/12/01 05:27:36 DEBUG : two: Unchanged skipping 2021/12/01 05:27:36 DEBUG : CopyDest/three: Clearing upload URL because of error: no tomes available (503 service_unavailable) 2021/12/01 05:27:36 DEBUG : pacer: low level retry 1/1 (error no tomes available (503 service_unavailable)) 2021/12/01 05:27:36 DEBUG : pacer: Rate limited, increasing sleep to 1s run.go:283: Retry Put of "CopyDest/three" to B2 bucket rclone-test-hibifav9coyomip0resotax0: 1/10 (no tomes available (503 service_unavailable)) 2021/12/01 05:27:38 DEBUG : pacer: Reducing sleep to 500ms 2021/12/01 05:27:41 DEBUG : pacer: Reducing sleep to 250ms 2021/12/01 05:27:41 DEBUG : pacer: Reducing sleep to 125ms 2021/12/01 05:27:42 DEBUG : Couldn't decode error response: EOF 2021/12/01 05:27:42 DEBUG : three: Sizes differ (src 7 vs dst 5) 2021/12/01 05:27:42 DEBUG : three: Destination not found in --copy-dest 2021/12/01 05:27:42 DEBUG : three: Need to transfer - File not found at Destination 2021/12/01 05:27:43 DEBUG : three: sha1 = 370e613335f3cf2f19b2a2d35a86402f55c659f8 OK 2021/12/01 05:27:43 INFO : three: Copied (new) 2021/12/01 05:27:43 DEBUG : pacer: Reducing sleep to 62.5ms 2021/12/01 05:27:43 DEBUG : pacer: Reducing sleep to 31.25ms 2021/12/01 05:27:43 DEBUG : pacer: Reducing sleep to 15.625ms 2021/12/01 05:27:44 DEBUG : pacer: Reducing sleep to 10ms --- PASS: TestCopyFileCopyDest (36.22s) === 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-hibifav9coyomip0resotax0", Local "Local file system at /tmp/rclone1263029806", Modify Window "1ms" 2021/12/01 05:27:55 DEBUG : A1/B2: Making directory 2021/12/01 05:27:55 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 05:27:56 DEBUG : Couldn't decode error response: EOF 2021/12/01 05:27:56 DEBUG : Couldn't decode error response: EOF 2021/12/01 05:27:56 DEBUG : Couldn't decode error response: EOF 2021/12/01 05:27:56 DEBUG : Couldn't decode error response: EOF 2021/12/01 05:27:57 DEBUG : A1/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/12/01 05:27:57 INFO : A1/one: Copied (server-side copy) to: A2/one 2021/12/01 05:27:57 DEBUG : A1/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/12/01 05:27:57 INFO : A1/B1/three: Copied (server-side copy) to: A2/B1/three 2021/12/01 05:27:57 INFO : A1/one: Deleted 2021/12/01 05:27:57 DEBUG : A1/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2021/12/01 05:27:57 INFO : A1/B1/C1/four: Copied (server-side copy) to: A2/B1/C1/four 2021/12/01 05:27:57 DEBUG : A1/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2021/12/01 05:27:57 INFO : A1/B1/C2/five: Copied (server-side copy) to: A2/B1/C2/five 2021/12/01 05:27:57 DEBUG : Couldn't decode error response: EOF 2021/12/01 05:27:58 INFO : A1/B1/three: Deleted 2021/12/01 05:27:58 INFO : A1/B1/C1/four: Deleted 2021/12/01 05:27:59 DEBUG : A1/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2021/12/01 05:27:59 INFO : A1/two: Copied (server-side copy) to: A2/two 2021/12/01 05:27:59 INFO : A1/B1/C2/five: Deleted 2021/12/01 05:27:59 INFO : A1/two: Deleted fstest.go:251: Filtering empty directory "A2/B2" fstest.go:251: Filtering empty directory "A2/B1/C3" 2021/12/01 05:27:59 DEBUG : Couldn't decode error response: EOF 2021/12/01 05:27:59 DEBUG : Couldn't decode error response: EOF 2021/12/01 05:28:00 DEBUG : Couldn't decode error response: EOF 2021/12/01 05:28:00 DEBUG : Couldn't decode error response: EOF 2021/12/01 05:28:01 DEBUG : A2/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2021/12/01 05:28:01 INFO : A2/B1/C1/four: Copied (server-side copy) to: A3/B1/C1/four 2021/12/01 05:28:01 DEBUG : A2/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2021/12/01 05:28:01 INFO : A2/B1/C2/five: Copied (server-side copy) to: A3/B1/C2/five 2021/12/01 05:28:01 INFO : A2/B1/C2/five: Deleted 2021/12/01 05:28:01 INFO : A2/B1/C1/four: Deleted 2021/12/01 05:28:01 DEBUG : A2/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/12/01 05:28:01 INFO : A2/one: Copied (server-side copy) to: A3/one 2021/12/01 05:28:01 DEBUG : Couldn't decode error response: EOF 2021/12/01 05:28:02 DEBUG : A2/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2021/12/01 05:28:02 INFO : A2/two: Copied (server-side copy) to: A3/two 2021/12/01 05:28:03 INFO : A2/one: Deleted 2021/12/01 05:28:03 DEBUG : A2/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/12/01 05:28:03 INFO : A2/B1/three: Copied (server-side copy) to: A3/B1/three 2021/12/01 05:28:03 INFO : A2/B1/three: Deleted 2021/12/01 05:28:04 INFO : A2/two: Deleted fstest.go:251: Filtering empty directory "A3/B2" fstest.go:251: Filtering empty directory "A3/B1/C3" --- PASS: TestDirMove (15.35s) === RUN TestGetFsInfo run.go:181: Remote "B2 bucket rclone-test-hibifav9coyomip0resotax0", Local "Local file system at /tmp/rclone1263029806", Modify Window "1ms" --- PASS: TestGetFsInfo (0.34s) === RUN TestRcat === RUN TestRcat/withChecksum=false,ignoreChecksum=false === CONT TestRcat run.go:181: Remote "B2 bucket rclone-test-hibifav9coyomip0resotax0", Local "Local file system at /tmp/rclone1263029806", Modify Window "1ms" 2021/12/01 05:28:08 DEBUG : B2 bucket rclone-test-hibifav9coyomip0resotax0: File to upload is small (34 bytes), uploading instead of streaming 2021/12/01 05:28:09 DEBUG : no_checksum_small_file_from_pipe: sha1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK 2021/12/01 05:28:09 INFO : no_checksum_small_file_from_pipe: Copied (new) 2021/12/01 05:28:09 DEBUG : no_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2021/12/01 05:28:10 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-hibifav9coyomip0resotax0", Local "Local file system at /tmp/rclone1263029806", Modify Window "1ms" 2021/12/01 05:28:14 DEBUG : B2 bucket rclone-test-hibifav9coyomip0resotax0: File to upload is small (34 bytes), uploading instead of streaming 2021/12/01 05:28:15 DEBUG : with_checksum_small_file_from_pipe: sha1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK 2021/12/01 05:28:15 INFO : with_checksum_small_file_from_pipe: Copied (new) 2021/12/01 05:28:15 DEBUG : with_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2021/12/01 05:28:16 DEBUG : with_checksum_big_file_from_pipe: sha1 = d0080f73aa8b494570ad63df1beb25c01ea9eea2 OK 2021/12/01 05:28:16 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-hibifav9coyomip0resotax0", Local "Local file system at /tmp/rclone1263029806", Modify Window "1ms" 2021/12/01 05:28:19 DEBUG : B2 bucket rclone-test-hibifav9coyomip0resotax0: File to upload is small (34 bytes), uploading instead of streaming 2021/12/01 05:28:19 INFO : ignore_checksum_small_file_from_pipe: Copied (new) 2021/12/01 05:28:19 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2021/12/01 05:28:24 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-hibifav9coyomip0resotax0", Local "Local file system at /tmp/rclone1263029806", Modify Window "1ms" 2021/12/01 05:28:26 DEBUG : B2 bucket rclone-test-hibifav9coyomip0resotax0: File to upload is small (34 bytes), uploading instead of streaming 2021/12/01 05:28:26 INFO : ignore_checksum_small_file_from_pipe: Copied (new) 2021/12/01 05:28:26 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2021/12/01 05:28:28 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical --- PASS: TestRcat (23.06s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (6.72s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (4.06s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (7.46s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (4.81s) === RUN TestRcatSize run.go:181: Remote "B2 bucket rclone-test-hibifav9coyomip0resotax0", Local "Local file system at /tmp/rclone1263029806", Modify Window "1ms" 2021/12/01 05:28:31 DEBUG : B2 bucket rclone-test-hibifav9coyomip0resotax0: File to upload is small (60 bytes), uploading instead of streaming 2021/12/01 05:28:31 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/12/01 05:28:31 INFO : potato2: Copied (new) --- PASS: TestRcatSize (6.61s) === RUN TestCopyFileMaxTransfer run.go:181: Remote "B2 bucket rclone-test-hibifav9coyomip0resotax0", Local "Local file system at /tmp/rclone1263029806", Modify Window "1ms" 2021/12/01 05:28:38 DEBUG : Couldn't decode error response: EOF 2021/12/01 05:28:38 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination 2021/12/01 05:28:39 DEBUG : TestCopyFileMaxTransfer/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2021/12/01 05:28:39 INFO : TestCopyFileMaxTransfer/file1: Copied (new) 2021/12/01 05:28:39 DEBUG : Couldn't decode error response: EOF 2021/12/01 05:28:39 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination 2021/12/01 05:28:39 ERROR : TestCopyFileMaxTransfer/file2: Failed to copy: Post "https://pod-000-1012-09.backblaze.com/b2api/v1/b2_upload_file/6f828d53702d7b4b7fd80e1b/c001_v0001012_t0026": Max transfer limit reached as set by --max-transfer 2021/12/01 05:28:40 DEBUG : Couldn't decode error response: EOF 2021/12/01 05:28:40 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination 2021/12/01 05:28:40 DEBUG : Couldn't decode error response: EOF 2021/12/01 05:28:40 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination 2021/12/01 05:28:41 DEBUG : TestCopyFileMaxTransfer/file4: sha1 = 883e3e38c9f03909b00d8e2cec789aa4d4997ec4 OK 2021/12/01 05:28:41 INFO : TestCopyFileMaxTransfer/file4: Copied (new) --- PASS: TestCopyFileMaxTransfer (6.07s) === RUN TestTouchDir run.go:181: Remote "B2 bucket rclone-test-hibifav9coyomip0resotax0", Local "Local file system at /tmp/rclone1263029806", Modify Window "1ms" 2021/12/01 05:28:47 DEBUG : B2 bucket rclone-test-hibifav9coyomip0resotax0: Touching "empty space" 2021/12/01 05:28:48 DEBUG : B2 bucket rclone-test-hibifav9coyomip0resotax0: Touching "potato2" 2021/12/01 05:28:49 DEBUG : B2 bucket rclone-test-hibifav9coyomip0resotax0: Touching "sub dir/potato3" --- PASS: TestTouchDir (8.31s) === 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 05:28:52 DEBUG : B2 bucket rclone-test-hibifav9coyomip0resotax0: Purge remote 2021/12/01 05:28:52 DEBUG : A1/B1/C1/four: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f113778e2b493951c_d20211201_m052757_c001_v0001030_t0028") 2021/12/01 05:28:52 DEBUG : A1/B1/C1/four: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f1093f44805b88925_d20211201_m052754_c001_v0001012_t0028") 2021/12/01 05:28:52 DEBUG : A1/B1/C1/one: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f10965e24c3b70541_d20211201_m052617_c001_v0001043_t0033") 2021/12/01 05:28:52 DEBUG : A1/B1/C1/one: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f112727455d2f80a6_d20211201_m052613_c001_v0001097_t0021") 2021/12/01 05:28:52 DEBUG : A1/B1/C2/five: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f1012c4c2ec2cab90_d20211201_m052759_c001_v0001131_t0001") 2021/12/01 05:28:52 DEBUG : A1/B1/C2/five: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f1093f44805b88927_d20211201_m052755_c001_v0001012_t0001") 2021/12/01 05:28:52 DEBUG : A1/B1/three: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f111c93dbd91354f8_d20211201_m052757_c001_v0001097_t0047") 2021/12/01 05:28:52 DEBUG : A1/B1/three: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f1093f44805b88923_d20211201_m052754_c001_v0001012_t0005") 2021/12/01 05:28:52 DEBUG : A1/one: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f1067947654b0c17b_d20211201_m052757_c001_v0001165_t0021") 2021/12/01 05:28:52 DEBUG : A1/one: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f1093f44805b8891d_d20211201_m052753_c001_v0001012_t0041") 2021/12/01 05:28:53 DEBUG : A1/two: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f114ab0a96c082d51_d20211201_m052759_c001_v0001136_t0052") 2021/12/01 05:28:53 DEBUG : A1/two: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f1093f44805b88921_d20211201_m052753_c001_v0001012_t0040") 2021/12/01 05:28:53 DEBUG : A1/two: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f113b025f83c91629_d20211201_m052617_c001_v0001097_t0024") 2021/12/01 05:28:53 DEBUG : A1/two: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f112727455d2f80be_d20211201_m052614_c001_v0001097_t0035") 2021/12/01 05:28:53 DEBUG : A2/B1/C1/four: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f11333d0ed6037cc0_d20211201_m052801_c001_v0001157_t0045") 2021/12/01 05:28:53 DEBUG : A2/B1/C1/four: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f1017d64e85b04117_d20211201_m052757_c001_v0001136_t0059") 2021/12/01 05:28:53 DEBUG : A2/B1/C2/five: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f1103151487aca731_d20211201_m052801_c001_v0001146_t0011") 2021/12/01 05:28:53 DEBUG : A2/B1/C2/five: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f114a09729d70ec10_d20211201_m052757_c001_v0001097_t0005") 2021/12/01 05:28:53 DEBUG : A2/B1/three: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f119be3a3d872d76f_d20211201_m052803_c001_v0001131_t0021") 2021/12/01 05:28:53 DEBUG : A2/B1/three: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f1180133ba71f6ae5_d20211201_m052757_c001_v0001130_t0021") 2021/12/01 05:28:53 DEBUG : A2/one: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f112727455d2f8922_d20211201_m052801_c001_v0001097_t0030") 2021/12/01 05:28:53 DEBUG : A2/one: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f114f19876c8f817f_d20211201_m052756_c001_v0001117_t0058") 2021/12/01 05:28:53 DEBUG : A2/two: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f100a0f51ccc95b61_d20211201_m052802_c001_v0001090_t0040") 2021/12/01 05:28:53 DEBUG : A2/two: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f1194f69139446953_d20211201_m052757_c001_v0001117_t0004") 2021/12/01 05:28:53 DEBUG : A3/B1/C1/four: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f1181113b2b063946_d20211201_m052804_c001_v0001093_t0037") 2021/12/01 05:28:53 DEBUG : A3/B1/C1/four: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f1045b5ebff76e403_d20211201_m052800_c001_v0001113_t0049") 2021/12/01 05:28:54 DEBUG : A3/B1/C2/five: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f11573a396374f2e2_d20211201_m052805_c001_v0001157_t0049") 2021/12/01 05:28:54 DEBUG : A3/B1/C2/five: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f11839de438330989_d20211201_m052800_c001_v0001037_t0004") 2021/12/01 05:28:54 DEBUG : A3/B1/three: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f111ea7686033f943_d20211201_m052805_c001_v0001037_t0004") 2021/12/01 05:28:54 DEBUG : A3/B1/three: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f102dfde5a28d47cd_d20211201_m052800_c001_v0001031_t0013") 2021/12/01 05:28:54 DEBUG : A3/one: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f10148ec538503408_d20211201_m052806_c001_v0001113_t0036") 2021/12/01 05:28:54 DEBUG : A3/one: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f101299dba4376f0f_d20211201_m052800_c001_v0001117_t0025") 2021/12/01 05:28:54 DEBUG : A3/two: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f1112a968f93cf2fb_d20211201_m052807_c001_v0001150_t0046") 2021/12/01 05:28:54 DEBUG : A3/two: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f1156d099abccd4c2_d20211201_m052802_c001_v0001165_t0024") 2021/12/01 05:28:54 DEBUG : BackupDir/one: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f11413c6656a2180b_d20211201_m052743_c001_v0001092_t0013") 2021/12/01 05:28:54 DEBUG : BackupDir/one: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f1093e40434eeeb82_d20211201_m052728_c001_v0001144_t0014") 2021/12/01 05:28:54 DEBUG : CompareDest/one: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f109059ac73219747_d20211201_m052713_c001_v0001039_t0056") 2021/12/01 05:28:54 DEBUG : CompareDest/one: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f112727455d2f8530_d20211201_m052706_c001_v0001097_t0039") 2021/12/01 05:28:54 DEBUG : CompareDest/two: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f11512b44e3c74fd8_d20211201_m052714_c001_v0001128_t0030") 2021/12/01 05:28:54 DEBUG : CompareDest/two: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f112727455d2f8544_d20211201_m052708_c001_v0001097_t0009") 2021/12/01 05:28:54 DEBUG : CopyDest/one: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f118062ddfce1eca6_d20211201_m052743_c001_v0001038_t0002") 2021/12/01 05:28:54 DEBUG : CopyDest/one: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f112727455d2f865f_d20211201_m052724_c001_v0001097_t0010") 2021/12/01 05:28:54 DEBUG : CopyDest/three: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f113e6c3fccbc7581_d20211201_m052744_c001_v0001117_t0042") 2021/12/01 05:28:54 DEBUG : CopyDest/three: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f1093f44805b8890d_d20211201_m052740_c001_v0001012_t0013") 2021/12/01 05:28:54 DEBUG : CopyDest/two: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f10836220df1eda27_d20211201_m052745_c001_v0001022_t0059") 2021/12/01 05:28:55 DEBUG : CopyDest/two: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f112727455d2f86fd_d20211201_m052733_c001_v0001097_t0038") 2021/12/01 05:28:55 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f1059969531ce9b2d_d20211201_m052842_c001_v0001036_t0057") 2021/12/01 05:28:55 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f1093f44805b88964_d20211201_m052839_c001_v0001012_t0044") 2021/12/01 05:28:55 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f102e70ceddbaedab_d20211201_m052843_c001_v0001041_t0033") 2021/12/01 05:28:55 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f1093f44805b8896a_d20211201_m052841_c001_v0001012_t0010") 2021/12/01 05:28:55 DEBUG : a.txt: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f1032c1343dd306db_d20211201_m052446_c001_v0001008_t0031") 2021/12/01 05:28:55 DEBUG : a.txt: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f112727455d2f7929_d20211201_m052434_c001_v0001097_t0045") 2021/12/01 05:28:55 DEBUG : also/one: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f104e08b92cf08097_d20211201_m052425_c001_v0001161_t0049") 2021/12/01 05:28:55 DEBUG : also/one: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f112727455d2f7857_d20211201_m052421_c001_v0001097_t0042") 2021/12/01 05:28:55 DEBUG : another: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f1161fc8a538883a6_d20211201_m052426_c001_v0001131_t0033") 2021/12/01 05:28:55 DEBUG : another: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f112727455d2f7872_d20211201_m052422_c001_v0001097_t0023") 2021/12/01 05:28:55 DEBUG : backup/dst/file1: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f113608df9754fe7b_d20211201_m052656_c001_v0001092_t0023") 2021/12/01 05:28:55 DEBUG : backup/dst/file1: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f1052aba8cb065614_d20211201_m052654_c001_v0001134_t0014") 2021/12/01 05:28:55 DEBUG : backup/dst/file1: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f1101b65457335ed8_d20211201_m052644_c001_v0001117_t0006") 2021/12/01 05:28:55 DEBUG : backup/dst/file1: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f11304355f7ca45cf_d20211201_m052642_c001_v0001150_t0041") 2021/12/01 05:28:55 DEBUG : data/banana: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f104727c0640419a8_d20211201_m052414_c001_v0001093_t0052") 2021/12/01 05:28:55 DEBUG : data/banana: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f119ba93ba57e7813_d20211201_m052412_c001_v0001165_t0002") 2021/12/01 05:28:56 DEBUG : data/banana: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f119ba93ba57e77e3_d20211201_m052404_c001_v0001165_t0018") 2021/12/01 05:28:56 DEBUG : data/potato: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f111c93dbd91343a2_d20211201_m052414_c001_v0001097_t0001") 2021/12/01 05:28:56 DEBUG : data/potato: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f119ba93ba57e7818_d20211201_m052412_c001_v0001165_t0037") 2021/12/01 05:28:56 DEBUG : data/potato: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f119ba93ba57e77ee_d20211201_m052406_c001_v0001165_t0012") 2021/12/01 05:28:56 DEBUG : dst/file1: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f113ac7e0e5dd2aa5_d20211201_m052656_c001_v0001000_t0013") 2021/12/01 05:28:56 DEBUG : dst/file1: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f112727455d2f8445_d20211201_m052655_c001_v0001097_t0045") 2021/12/01 05:28:56 DEBUG : dst/file1: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f107680d6156c7c2f_d20211201_m052654_c001_v0001039_t0022") 2021/12/01 05:28:56 DEBUG : dst/file1: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f112727455d2f83c5_d20211201_m052650_c001_v0001097_t0044") 2021/12/01 05:28:56 DEBUG : dst/file1: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f10987f73eb4588d1_d20211201_m052644_c001_v0001161_t0045") 2021/12/01 05:28:56 DEBUG : dst/file1: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f112727455d2f831a_d20211201_m052643_c001_v0001097_t0003") 2021/12/01 05:28:56 DEBUG : dst/file1: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f1002cd7d0e6819a7_d20211201_m052642_c001_v0001161_t0041") 2021/12/01 05:28:56 DEBUG : dst/file1: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f112727455d2f82c0_d20211201_m052638_c001_v0001097_t0057") 2021/12/01 05:28:56 DEBUG : dst/one: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f1068c6636364ac71_d20211201_m052746_c001_v0001043_t0012") 2021/12/01 05:28:56 DEBUG : dst/one: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f111e73e4abbfbde2_d20211201_m052731_c001_v0001041_t0034") 2021/12/01 05:28:56 DEBUG : dst/one: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f118062ddfce1ec72_d20211201_m052730_c001_v0001038_t0012") 2021/12/01 05:28:56 DEBUG : dst/one: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f112727455d2f8656_d20211201_m052723_c001_v0001097_t0051") 2021/12/01 05:28:56 DEBUG : dst/one: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f102c1af8a6d86870_d20211201_m052721_c001_v0001043_t0019") 2021/12/01 05:28:57 DEBUG : dst/one: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f102c1af8a6d8686e_d20211201_m052720_c001_v0001043_t0036") 2021/12/01 05:28:57 DEBUG : dst/one: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f1104afb7dede1e68_d20211201_m052714_c001_v0001113_t0007") 2021/12/01 05:28:57 DEBUG : dst/one: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f112727455d2f851e_d20211201_m052706_c001_v0001097_t0026") 2021/12/01 05:28:57 DEBUG : dst/one: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f102774b0d44377d8_d20211201_m052704_c001_v0001033_t0050") 2021/12/01 05:28:57 DEBUG : dst/one: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f102774b0d44377cb_d20211201_m052701_c001_v0001033_t0053") 2021/12/01 05:28:57 DEBUG : dst/three: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f106aec86598cb081_d20211201_m052746_c001_v0001042_t0059") 2021/12/01 05:28:57 DEBUG : dst/three: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f102c1af8a6d86897_d20211201_m052742_c001_v0001043_t0055") 2021/12/01 05:28:57 DEBUG : dst/two: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f113312722a63324c_d20211201_m052747_c001_v0001144_t0008") 2021/12/01 05:28:57 DEBUG : dst/two: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f117bbdcce708052a_d20211201_m052735_c001_v0001113_t0031") 2021/12/01 05:28:57 DEBUG : dst/two: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f114fb12a357c3e5b_d20211201_m052715_c001_v0001130_t0054") 2021/12/01 05:28:57 DEBUG : dst/two: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f102774b0d44377f3_d20211201_m052712_c001_v0001033_t0001") 2021/12/01 05:28:57 DEBUG : empty space: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f1151589b28f2d022_d20211201_m052850_c001_v0001113_t0026") 2021/12/01 05:28:57 DEBUG : empty space: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f110cb3412f7de57d_d20211201_m052848_c001_v0001161_t0055") 2021/12/01 05:28:57 DEBUG : empty space: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f1093f44805b88975_d20211201_m052844_c001_v0001012_t0019") 2021/12/01 05:28:57 DEBUG : empty space: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f1029047ca83dcbfd_d20211201_m052544_c001_v0001153_t0004") 2021/12/01 05:28:57 DEBUG : empty space: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f112727455d2f7e7c_d20211201_m052542_c001_v0001097_t0040") 2021/12/01 05:28:57 DEBUG : empty space: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f113e6c3fccbc6e5f_d20211201_m052538_c001_v0001117_t0036") 2021/12/01 05:28:57 DEBUG : empty space: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f112727455d2f7e0b_d20211201_m052536_c001_v0001097_t0046") 2021/12/01 05:28:57 DEBUG : empty space: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f110c4caa492f23a3_d20211201_m052534_c001_v0001131_t0016") 2021/12/01 05:28:57 DEBUG : empty space: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f112727455d2f7dbf_d20211201_m052533_c001_v0001097_t0013") 2021/12/01 05:28:58 DEBUG : empty space: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f110741d7134faa9d_d20211201_m052531_c001_v0001026_t0036") 2021/12/01 05:28:58 DEBUG : empty space: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f112727455d2f7d46_d20211201_m052528_c001_v0001097_t0011") 2021/12/01 05:28:58 DEBUG : empty space: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f119ba93ba57e79d6_d20211201_m052527_c001_v0001165_t0044") 2021/12/01 05:28:58 DEBUG : empty space: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f112727455d2f7cfc_d20211201_m052525_c001_v0001097_t0004") 2021/12/01 05:28:58 DEBUG : empty space: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f107f8147cfdf9dc6_d20211201_m052353_c001_v0001130_t0021") 2021/12/01 05:28:58 DEBUG : empty space: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f119ba93ba57e778d_d20211201_m052351_c001_v0001165_t0023") 2021/12/01 05:28:58 DEBUG : empty space: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f102bca8860afa628_d20211201_m052346_c001_v0001117_t0025") 2021/12/01 05:28:58 DEBUG : empty space: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f119ba93ba57e774c_d20211201_m052343_c001_v0001165_t0009") 2021/12/01 05:28:58 DEBUG : empty space: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f106801a15d90af41_d20211201_m052332_c001_v0001097_t0054") 2021/12/01 05:28:58 DEBUG : empty space: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f119ba93ba57e76f4_d20211201_m052329_c001_v0001165_t0059") 2021/12/01 05:28:58 DEBUG : file1: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f107c834a00e240bc_d20211201_m052637_c001_v0001009_t0033") 2021/12/01 05:28:58 DEBUG : file1: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f112727455d2f829c_d20211201_m052636_c001_v0001097_t0057") 2021/12/01 05:28:58 DEBUG : file1: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f110ecec343cedad0_d20211201_m052630_c001_v0001041_t0033") 2021/12/01 05:28:58 DEBUG : file1: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f112727455d2f8159_d20211201_m052622_c001_v0001097_t0057") 2021/12/01 05:28:58 DEBUG : file1: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f113e6c3fccbc6f97_d20211201_m052600_c001_v0001117_t0024") 2021/12/01 05:28:58 DEBUG : file1: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f112727455d2f7f72_d20211201_m052555_c001_v0001097_t0055") 2021/12/01 05:28:58 DEBUG : file1: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f103e8bfb078323e2_d20211201_m052507_c001_v0001161_t0049") 2021/12/01 05:28:58 DEBUG : file1: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f112727455d2f7b4d_d20211201_m052500_c001_v0001097_t0021") 2021/12/01 05:28:58 DEBUG : file1: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f1121dd371da164c6_d20211201_m052459_c001_v0001145_t0026") 2021/12/01 05:28:58 DEBUG : file1: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f112727455d2f7ac0_d20211201_m052454_c001_v0001097_t0050") 2021/12/01 05:28:59 DEBUG : file1: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f108ad0d6979be0a7_d20211201_m052328_c001_v0001040_t0053") 2021/12/01 05:28:59 DEBUG : file1: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f119ba93ba57e76e0_d20211201_m052327_c001_v0001165_t0051") 2021/12/01 05:28:59 DEBUG : file1: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f119ba93ba57e76d4_d20211201_m052325_c001_v0001165_t0030") 2021/12/01 05:28:59 DEBUG : file1: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f119ba93ba57e76c2_d20211201_m052323_c001_v0001165_t0052") 2021/12/01 05:28:59 DEBUG : file2: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f103c12c1fc97b5f7_d20211201_m052630_c001_v0001154_t0011") 2021/12/01 05:28:59 DEBUG : file2: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f112727455d2f81ea_d20211201_m052627_c001_v0001097_t0053") 2021/12/01 05:28:59 DEBUG : file2: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f1087823737a68664_d20211201_m052601_c001_v0001144_t0016") 2021/12/01 05:28:59 DEBUG : file2: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f112727455d2f7f84_d20211201_m052556_c001_v0001097_t0032") 2021/12/01 05:28:59 DEBUG : filename.txt: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f116111a55c9d9276_d20211201_m052630_c001_v0001043_t0045") 2021/12/01 05:28:59 DEBUG : filename.txt: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f112727455d2f81a7_d20211201_m052624_c001_v0001097_t0050") 2021/12/01 05:28:59 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f1114199181457fb1_d20211201_m052828_c001_v0001117_t0003") 2021/12/01 05:28:59 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f1093f44805b88955_d20211201_m052827_c001_v0001012_t0002") 2021/12/01 05:28:59 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f1045e5cdbb4fc7b0_d20211201_m052825_c001_v0001033_t0000") 2021/12/01 05:28:59 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f1093f44805b8894c_d20211201_m052819_c001_v0001012_t0004") 2021/12/01 05:28:59 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f100c2d1e3a64f574_d20211201_m052829_c001_v0001044_t0011") 2021/12/01 05:28:59 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f1093f44805b88953_d20211201_m052826_c001_v0001012_t0039") 2021/12/01 05:28:59 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f119744cae6795afd_d20211201_m052825_c001_v0001146_t0041") 2021/12/01 05:28:59 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f1093f44805b88949_d20211201_m052819_c001_v0001012_t0015") 2021/12/01 05:28:59 DEBUG : large: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f112727455d2f7f5c_d20211201_m052554_c001_v0001097_t0031") 2021/12/01 05:28:59 DEBUG : large: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f112727455d2f7f0d_d20211201_m052550_c001_v0001097_t0043") 2021/12/01 05:29:00 DEBUG : medium: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f11009b1db9035c79_d20211201_m052552_c001_v0001128_t0046") 2021/12/01 05:29:00 DEBUG : medium: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f112727455d2f7eff_d20211201_m052550_c001_v0001097_t0013") 2021/12/01 05:29:00 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f1182bc1fb3a7a0a3_d20211201_m052810_c001_v0001117_t0035") 2021/12/01 05:29:00 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f1093f44805b88935_d20211201_m052809_c001_v0001012_t0030") 2021/12/01 05:29:00 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f116d94cc559cd640_d20211201_m052813_c001_v0001097_t0000") 2021/12/01 05:29:00 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f1093f44805b88933_d20211201_m052808_c001_v0001012_t0040") 2021/12/01 05:29:00 DEBUG : not-one: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f1183e10aa60b4d01_d20211201_m052429_c001_v0001090_t0024") 2021/12/01 05:29:00 DEBUG : not-one: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f112727455d2f7880_d20211201_m052423_c001_v0001097_t0032") 2021/12/01 05:29:00 DEBUG : one: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f10894c728cfe17c6_d20211201_m052424_c001_v0001113_t0024") 2021/12/01 05:29:00 DEBUG : one: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f112727455d2f783e_d20211201_m052420_c001_v0001097_t0017") 2021/12/01 05:29:00 DEBUG : potato1: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f1008ba5319fbce3e_d20211201_m052832_c001_v0001113_t0030") 2021/12/01 05:29:00 DEBUG : potato1: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f1093f44805b8895a_d20211201_m052831_c001_v0001012_t0012") 2021/12/01 05:29:00 DEBUG : potato2: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f114820e9f6a70d22_d20211201_m052851_c001_v0001131_t0032") 2021/12/01 05:29:00 DEBUG : potato2: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f11304355f7ca47c2_d20211201_m052849_c001_v0001150_t0041") 2021/12/01 05:29:00 DEBUG : potato2: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f1093f44805b88970_d20211201_m052843_c001_v0001012_t0002") 2021/12/01 05:29:00 DEBUG : potato2: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f11089b36f32c520d_d20211201_m052833_c001_v0001144_t0013") 2021/12/01 05:29:00 DEBUG : potato2: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f1093f44805b8895e_d20211201_m052831_c001_v0001012_t0044") 2021/12/01 05:29:00 DEBUG : potato2: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f111c97bf26f90569_d20211201_m052546_c001_v0001144_t0015") 2021/12/01 05:29:00 DEBUG : potato2: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f112727455d2f7e69_d20211201_m052541_c001_v0001097_t0059") 2021/12/01 05:29:00 DEBUG : potato2: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f1100abe70a487849_d20211201_m052540_c001_v0001097_t0014") 2021/12/01 05:29:01 DEBUG : potato2: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f112727455d2f7def_d20211201_m052535_c001_v0001097_t0000") 2021/12/01 05:29:01 DEBUG : potato2: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f104e08b92cf082b2_d20211201_m052534_c001_v0001161_t0026") 2021/12/01 05:29:01 DEBUG : potato2: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f112727455d2f7db7_d20211201_m052533_c001_v0001097_t0004") 2021/12/01 05:29:01 DEBUG : potato2: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f10693af486135f00_d20211201_m052532_c001_v0001161_t0029") 2021/12/01 05:29:01 DEBUG : potato2: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f112727455d2f7d37_d20211201_m052528_c001_v0001097_t0014") 2021/12/01 05:29:01 DEBUG : potato2: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f113e39487189819c_d20211201_m052527_c001_v0001154_t0008") 2021/12/01 05:29:01 DEBUG : potato2: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f112727455d2f7cef_d20211201_m052524_c001_v0001097_t0049") 2021/12/01 05:29:01 DEBUG : potato2: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f108a694dcbb5847b_d20211201_m052354_c001_v0001117_t0048") 2021/12/01 05:29:01 DEBUG : potato2: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f119ba93ba57e7794_d20211201_m052351_c001_v0001165_t0008") 2021/12/01 05:29:01 DEBUG : potato2: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f11995b10330202cf_d20211201_m052347_c001_v0001097_t0037") 2021/12/01 05:29:01 DEBUG : potato2: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f119ba93ba57e7752_d20211201_m052344_c001_v0001165_t0015") 2021/12/01 05:29:01 DEBUG : potato2: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f1181007f0293637f_d20211201_m052338_c001_v0001165_t0038") 2021/12/01 05:29:01 DEBUG : potato2: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f119ba93ba57e76f9_d20211201_m052330_c001_v0001165_t0019") 2021/12/01 05:29:01 DEBUG : remotepotato: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f10404480b2427b30_d20211201_m052354_c001_v0001039_t0047") 2021/12/01 05:29:01 DEBUG : remotepotato: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f119ba93ba57e779a_d20211201_m052352_c001_v0001165_t0032") 2021/12/01 05:29:01 DEBUG : remotepotato: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f11984a1e0ea02f61_d20211201_m052348_c001_v0001144_t0058") 2021/12/01 05:29:01 DEBUG : remotepotato: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f119ba93ba57e775f_d20211201_m052345_c001_v0001165_t0035") 2021/12/01 05:29:01 DEBUG : remotepotato: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f1108eea1e60bbeb3_d20211201_m052338_c001_v0001150_t0015") 2021/12/01 05:29:02 DEBUG : remotepotato: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f119ba93ba57e76fe_d20211201_m052331_c001_v0001165_t0009") 2021/12/01 05:29:02 DEBUG : rutabaga: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f117c2964756f1e05_d20211201_m052355_c001_v0001004_t0001") 2021/12/01 05:29:02 DEBUG : rutabaga: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f119ba93ba57e7788_d20211201_m052350_c001_v0001165_t0021") 2021/12/01 05:29:02 DEBUG : rutabaga: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f107fa15e9dc14eb4_d20211201_m052349_c001_v0001004_t0014") 2021/12/01 05:29:02 DEBUG : rutabaga: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f119ba93ba57e7740_d20211201_m052341_c001_v0001165_t0044") 2021/12/01 05:29:02 DEBUG : rutabaga: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f107005c58572f68b_d20211201_m052339_c001_v0001144_t0033") 2021/12/01 05:29:02 DEBUG : rutabaga: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f119ba93ba57e76ec_d20211201_m052329_c001_v0001165_t0046") 2021/12/01 05:29:02 DEBUG : small: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f1099e6a21546efbd_d20211201_m052552_c001_v0001041_t0038") 2021/12/01 05:29:02 DEBUG : small: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f112727455d2f7ef3_d20211201_m052549_c001_v0001097_t0009") 2021/12/01 05:29:02 DEBUG : sub dir/hello world: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f117b67bf4dc46da7_d20211201_m052512_c001_v0001033_t0005") 2021/12/01 05:29:02 DEBUG : sub dir/hello world: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f112727455d2f7be4_d20211201_m052509_c001_v0001097_t0049") 2021/12/01 05:29:02 DEBUG : sub dir/hello world: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f1054ab25c15535f4_d20211201_m052447_c001_v0001029_t0034") 2021/12/01 05:29:02 DEBUG : sub dir/hello world: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f112727455d2f795b_d20211201_m052438_c001_v0001097_t0013") 2021/12/01 05:29:02 DEBUG : sub dir/hello world2: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f10243b6b6d9c94ec_d20211201_m052447_c001_v0001032_t0053") 2021/12/01 05:29:02 DEBUG : sub dir/hello world2: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f112727455d2f7984_d20211201_m052439_c001_v0001097_t0001") 2021/12/01 05:29:02 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f115781c32872020f_d20211201_m052448_c001_v0001131_t0029") 2021/12/01 05:29:02 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f112727455d2f79a2_d20211201_m052441_c001_v0001097_t0047") 2021/12/01 05:29:02 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f117002690a5f9688_d20211201_m052448_c001_v0001097_t0014") 2021/12/01 05:29:02 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f112727455d2f79b8_d20211201_m052441_c001_v0001097_t0007") 2021/12/01 05:29:02 DEBUG : sub dir/potato3: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f103548753075e53e_d20211201_m052851_c001_v0001150_t0023") 2021/12/01 05:29:02 DEBUG : sub dir/potato3: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f10693af48613654d_d20211201_m052849_c001_v0001161_t0040") 2021/12/01 05:29:02 DEBUG : sub dir/potato3: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f1093f44805b88979_d20211201_m052846_c001_v0001012_t0013") 2021/12/01 05:29:02 DEBUG : sub dir/potato3: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f11873010397d4fcd_d20211201_m052548_c001_v0001025_t0027") 2021/12/01 05:29:03 DEBUG : sub dir/potato3: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f112727455d2f7e88_d20211201_m052542_c001_v0001097_t0010") 2021/12/01 05:29:03 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f1028c155c6ca81c9_d20211201_m052450_c001_v0001038_t0042") 2021/12/01 05:29:03 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f112727455d2f79c4_d20211201_m052442_c001_v0001097_t0017") 2021/12/01 05:29:03 DEBUG : sub/file2: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f1182bc1fb3a79b2b_d20211201_m052647_c001_v0001117_t0036") 2021/12/01 05:29:03 DEBUG : sub/file2: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f112727455d2f8361_d20211201_m052646_c001_v0001097_t0030") 2021/12/01 05:29:03 DEBUG : sub/file2: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f11995b1033021022_d20211201_m052634_c001_v0001097_t0046") 2021/12/01 05:29:03 DEBUG : sub/file2: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f112727455d2f8241_d20211201_m052632_c001_v0001097_t0057") 2021/12/01 05:29:03 DEBUG : sub/file2: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f114232b3d67a24f9_d20211201_m052508_c001_v0001026_t0012") 2021/12/01 05:29:03 DEBUG : sub/file2: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f112727455d2f7b79_d20211201_m052502_c001_v0001097_t0054") 2021/12/01 05:29:03 DEBUG : sub/file2: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f11289f97eca9e148_d20211201_m052500_c001_v0001001_t0019") 2021/12/01 05:29:03 DEBUG : sub/file2: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f112727455d2f7ade_d20211201_m052455_c001_v0001097_t0039") 2021/12/01 05:29:03 DEBUG : test.sum: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f101858cf49a660ec_d20211201_m052415_c001_v0001097_t0039") 2021/12/01 05:29:03 DEBUG : test.sum: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f119ba93ba57e781b_d20211201_m052412_c001_v0001165_t0052") 2021/12/01 05:29:03 DEBUG : test.sum: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f119ba93ba57e780e_d20211201_m052411_c001_v0001165_t0017") 2021/12/01 05:29:03 DEBUG : test.sum: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f119ba93ba57e7807_d20211201_m052410_c001_v0001165_t0057") 2021/12/01 05:29:03 DEBUG : test.sum: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f119ba93ba57e77fe_d20211201_m052408_c001_v0001165_t0024") 2021/12/01 05:29:03 DEBUG : test.sum: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f119ba93ba57e77f3_d20211201_m052407_c001_v0001165_t0021") 2021/12/01 05:29:04 DEBUG : test.sum: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f119ba93ba57e77f0_d20211201_m052406_c001_v0001165_t0006") 2021/12/01 05:29:04 DEBUG : test.sum: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f119ba93ba57e77e5_d20211201_m052404_c001_v0001165_t0049") 2021/12/01 05:29:04 DEBUG : test.sum: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f10800751db4b9343_d20211201_m052402_c001_v0001150_t0056") 2021/12/01 05:29:04 DEBUG : test.sum: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f119ba93ba57e77cb_d20211201_m052401_c001_v0001165_t0054") 2021/12/01 05:29:04 DEBUG : test.sum: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f119ba93ba57e77c2_d20211201_m052400_c001_v0001165_t0044") 2021/12/01 05:29:04 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f119526ede426f9c3_d20211201_m052817_c001_v0001120_t0054") 2021/12/01 05:29:04 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f1093f44805b8893f_d20211201_m052815_c001_v0001012_t0044") 2021/12/01 05:29:04 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f100ded5822686a02_d20211201_m052818_c001_v0001097_t0028") 2021/12/01 05:29:04 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f1093f44805b8893d_d20211201_m052815_c001_v0001012_t0009") 2021/12/01 05:29:04 DEBUG : zend.txt: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f1034ba693186add2_d20211201_m052451_c001_v0001037_t0055") 2021/12/01 05:29:04 DEBUG : zend.txt: Deleting (id "4_z6f828d53702d7b4b7fd80e1b_f112727455d2f793a_d20211201_m052436_c001_v0001097_t0015") "./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose" - Finished OK in 5m44.80545422s (try 1/5)