"./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -fast-list" - Starting (try 1/5) 2026/08/14 02:27:15 DEBUG : Creating backend with remote "TestB2:rclone-test-qiqequr8wunu" 2026/08/14 02:27:15 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/08/14 02:27:16 DEBUG : Creating backend with remote "/tmp/rclone2108479853" === RUN TestDoMultiThreadCopy --- PASS: TestDoMultiThreadCopy (0.00s) === RUN TestMultithreadCalculateNumChunks === RUN TestMultithreadCalculateNumChunks/{size:1_chunkSize:65536_wantNumChunks:1} === RUN TestMultithreadCalculateNumChunks/{size:1048576_chunkSize:1_wantNumChunks:1048576} === RUN TestMultithreadCalculateNumChunks/{size:1048576_chunkSize:2_wantNumChunks:524288} === RUN TestMultithreadCalculateNumChunks/{size:1048577_chunkSize:2_wantNumChunks:524289} === RUN TestMultithreadCalculateNumChunks/{size:1048575_chunkSize:2_wantNumChunks:524288} --- PASS: TestMultithreadCalculateNumChunks (0.00s) --- PASS: TestMultithreadCalculateNumChunks/{size:1_chunkSize:65536_wantNumChunks:1} (0.00s) --- PASS: TestMultithreadCalculateNumChunks/{size:1048576_chunkSize:1_wantNumChunks:1048576} (0.00s) --- PASS: TestMultithreadCalculateNumChunks/{size:1048576_chunkSize:2_wantNumChunks:524288} (0.00s) --- PASS: TestMultithreadCalculateNumChunks/{size:1048577_chunkSize:2_wantNumChunks:524289} (0.00s) --- PASS: TestMultithreadCalculateNumChunks/{size:1048575_chunkSize:2_wantNumChunks:524288} (0.00s) === RUN TestMultithreadCopy run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" 2026/08/14 02:27:17 DEBUG : chunksize-probe: Cancelling large file upload === RUN TestMultithreadCopy/upload=false,size=16777215,streams=2 2026/08/14 02:27:22 DEBUG : test-multithread-copy-false-16777215-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2026/08/14 02:27:22 DEBUG : test-multithread-copy-false-16777215-2: multi-thread copy: write buffer set to 131072 2026/08/14 02:27:22 DEBUG : test-multithread-copy-false-16777215-2: multi-thread copy: chunk size 64Mi was bigger than source file size 16.000Mi 2026/08/14 02:27:22 DEBUG : test-multithread-copy-false-16777215-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2026/08/14 02:27:22 DEBUG : test-multithread-copy-false-16777215-2: multi-thread copy: number of streams 4 was bigger than number of chunks 1 2026/08/14 02:27:22 DEBUG : test-multithread-copy-false-16777215-2: Starting multi-thread copy with 1 chunks of size 16.000Mi with 1 parallel streams 2026/08/14 02:27:22 DEBUG : test-multithread-copy-false-16777215-2: multi-thread copy: chunk 1/1 (0-16777215) size 16.000Mi starting 2026/08/14 02:27:23 DEBUG : test-multithread-copy-false-16777215-2: writing chunk 0 2026/08/14 02:27:25 DEBUG : test-multithread-copy-false-16777215-2: multi-thread copy: chunk 1/1 (0-16777215) size 16.000Mi finished 2026/08/14 02:27:25 DEBUG : test-multithread-copy-false-16777215-2: Finished multi-thread copy with 1 parts of size 16.000Mi === RUN TestMultithreadCopy/upload=false,size=16777216,streams=2 2026/08/14 02:27:26 DEBUG : test-multithread-copy-false-16777216-2: Clearing upload URL because of error: Post "https://pod-000-1115-13.backblaze.com/b2api/v1/b2_upload_file/bfa25da3108dfbeb9ff80e1b/c001_v0001115_t0025": write tcp 65.108.87.141:49198->206.190.210.24:443: use of closed network connection 2026/08/14 02:27:26 DEBUG : pacer: low level retry 1/1 (error Post "https://pod-000-1115-13.backblaze.com/b2api/v1/b2_upload_file/bfa25da3108dfbeb9ff80e1b/c001_v0001115_t0025": write tcp 65.108.87.141:49198->206.190.210.24:443: use of closed network connection) 2026/08/14 02:27:26 DEBUG : pacer: Rate limited, increasing sleep to 20ms === NAME TestMultithreadCopy run.go:299: Retry Put of "test-multithread-copy-false-16777216-2" to B2 bucket rclone-test-qiqequr8wunu: 1/10 (Post "https://pod-000-1115-13.backblaze.com/b2api/v1/b2_upload_file/bfa25da3108dfbeb9ff80e1b/c001_v0001115_t0025": write tcp 65.108.87.141:49198->206.190.210.24:443: use of closed network connection) 2026/08/14 02:27:28 DEBUG : pacer: Reducing sleep to 10ms 2026/08/14 02:27:34 DEBUG : test-multithread-copy-false-16777216-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2026/08/14 02:27:34 DEBUG : test-multithread-copy-false-16777216-2: multi-thread copy: write buffer set to 131072 2026/08/14 02:27:34 DEBUG : test-multithread-copy-false-16777216-2: multi-thread copy: chunk size 64Mi was bigger than source file size 16Mi 2026/08/14 02:27:34 DEBUG : test-multithread-copy-false-16777216-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2026/08/14 02:27:34 DEBUG : test-multithread-copy-false-16777216-2: multi-thread copy: number of streams 4 was bigger than number of chunks 1 2026/08/14 02:27:34 DEBUG : test-multithread-copy-false-16777216-2: Starting multi-thread copy with 1 chunks of size 16Mi with 1 parallel streams 2026/08/14 02:27:34 DEBUG : test-multithread-copy-false-16777216-2: multi-thread copy: chunk 1/1 (0-16777216) size 16Mi starting 2026/08/14 02:27:34 DEBUG : test-multithread-copy-false-16777216-2: writing chunk 0 2026/08/14 02:27:36 DEBUG : test-multithread-copy-false-16777216-2: multi-thread copy: chunk 1/1 (0-16777216) size 16Mi finished 2026/08/14 02:27:36 DEBUG : test-multithread-copy-false-16777216-2: Finished multi-thread copy with 1 parts of size 16Mi === RUN TestMultithreadCopy/upload=false,size=16777217,streams=2 2026/08/14 02:27:37 DEBUG : test-multithread-copy-false-16777217-2: Clearing upload URL because of error: Post "https://pod-000-1044-10.backblaze.com/b2api/v1/b2_upload_file/bfa25da3108dfbeb9ff80e1b/c001_v0001044_t0014": write tcp 65.108.87.141:52170->206.190.208.161:443: use of closed network connection 2026/08/14 02:27:37 DEBUG : pacer: low level retry 1/1 (error Post "https://pod-000-1044-10.backblaze.com/b2api/v1/b2_upload_file/bfa25da3108dfbeb9ff80e1b/c001_v0001044_t0014": write tcp 65.108.87.141:52170->206.190.208.161:443: use of closed network connection) 2026/08/14 02:27:37 DEBUG : pacer: Rate limited, increasing sleep to 20ms === NAME TestMultithreadCopy run.go:299: Retry Put of "test-multithread-copy-false-16777217-2" to B2 bucket rclone-test-qiqequr8wunu: 1/10 (Post "https://pod-000-1044-10.backblaze.com/b2api/v1/b2_upload_file/bfa25da3108dfbeb9ff80e1b/c001_v0001044_t0014": write tcp 65.108.87.141:52170->206.190.208.161:443: use of closed network connection) 2026/08/14 02:27:39 DEBUG : pacer: Reducing sleep to 10ms 2026/08/14 02:27:44 DEBUG : test-multithread-copy-false-16777217-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2026/08/14 02:27:44 DEBUG : test-multithread-copy-false-16777217-2: multi-thread copy: write buffer set to 131072 2026/08/14 02:27:44 DEBUG : test-multithread-copy-false-16777217-2: multi-thread copy: chunk size 64Mi was bigger than source file size 16.000Mi 2026/08/14 02:27:44 DEBUG : test-multithread-copy-false-16777217-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2026/08/14 02:27:44 DEBUG : test-multithread-copy-false-16777217-2: multi-thread copy: number of streams 4 was bigger than number of chunks 1 2026/08/14 02:27:44 DEBUG : test-multithread-copy-false-16777217-2: Starting multi-thread copy with 1 chunks of size 16.000Mi with 1 parallel streams 2026/08/14 02:27:44 DEBUG : test-multithread-copy-false-16777217-2: multi-thread copy: chunk 1/1 (0-16777217) size 16.000Mi starting 2026/08/14 02:27:45 DEBUG : test-multithread-copy-false-16777217-2: writing chunk 0 2026/08/14 02:27:47 DEBUG : test-multithread-copy-false-16777217-2: multi-thread copy: chunk 1/1 (0-16777217) size 16.000Mi finished 2026/08/14 02:27:47 DEBUG : test-multithread-copy-false-16777217-2: Finished multi-thread copy with 1 parts of size 16.000Mi === RUN TestMultithreadCopy/upload=true,size=16777215,streams=2 2026/08/14 02:27:49 DEBUG : test-multithread-copy-true-16777215-2: multi-thread copy: disabling buffering because source is local disk 2026/08/14 02:27:49 DEBUG : test-multithread-copy-true-16777215-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2026/08/14 02:27:49 DEBUG : test-multithread-copy-true-16777215-2: multi-thread copy: number of streams 4 was bigger than number of chunks 2 2026/08/14 02:27:49 DEBUG : test-multithread-copy-true-16777215-2: Starting multi-thread copy with 2 chunks of size 8Mi with 2 parallel streams 2026/08/14 02:27:49 DEBUG : test-multithread-copy-true-16777215-2: multi-thread copy: chunk 2/2 (8388608-16777215) size 8.000Mi starting 2026/08/14 02:27:49 DEBUG : test-multithread-copy-true-16777215-2: Sending chunk 1 length 8388607 2026/08/14 02:27:49 DEBUG : test-multithread-copy-true-16777215-2: multi-thread copy: chunk 1/2 (0-8388608) size 8Mi starting 2026/08/14 02:27:49 DEBUG : test-multithread-copy-true-16777215-2: Sending chunk 0 length 8388608 2026/08/14 02:27:52 DEBUG : test-multithread-copy-true-16777215-2: Done sending chunk 1 2026/08/14 02:27:52 DEBUG : test-multithread-copy-true-16777215-2: multi-thread copy: chunk 2/2 (8388608-16777215) size 8.000Mi finished 2026/08/14 02:27:53 DEBUG : test-multithread-copy-true-16777215-2: Done sending chunk 0 2026/08/14 02:27:53 DEBUG : test-multithread-copy-true-16777215-2: multi-thread copy: chunk 1/2 (0-8388608) size 8Mi finished 2026/08/14 02:27:53 DEBUG : test-multithread-copy-true-16777215-2: Finishing large file upload with 2 parts 2026/08/14 02:27:53 DEBUG : test-multithread-copy-true-16777215-2: Finished multi-thread copy with 2 parts of size 8Mi === RUN TestMultithreadCopy/upload=true,size=16777216,streams=2 2026/08/14 02:27:56 DEBUG : test-multithread-copy-true-16777216-2: multi-thread copy: disabling buffering because source is local disk 2026/08/14 02:27:56 DEBUG : test-multithread-copy-true-16777216-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2026/08/14 02:27:56 DEBUG : test-multithread-copy-true-16777216-2: multi-thread copy: number of streams 4 was bigger than number of chunks 2 2026/08/14 02:27:56 DEBUG : test-multithread-copy-true-16777216-2: Starting multi-thread copy with 2 chunks of size 8Mi with 2 parallel streams 2026/08/14 02:27:56 DEBUG : test-multithread-copy-true-16777216-2: multi-thread copy: chunk 2/2 (8388608-16777216) size 8Mi starting 2026/08/14 02:27:56 DEBUG : test-multithread-copy-true-16777216-2: Sending chunk 1 length 8388608 2026/08/14 02:27:56 DEBUG : test-multithread-copy-true-16777216-2: multi-thread copy: chunk 1/2 (0-8388608) size 8Mi starting 2026/08/14 02:27:56 DEBUG : test-multithread-copy-true-16777216-2: Sending chunk 0 length 8388608 2026/08/14 02:27:59 DEBUG : test-multithread-copy-true-16777216-2: Done sending chunk 1 2026/08/14 02:27:59 DEBUG : test-multithread-copy-true-16777216-2: multi-thread copy: chunk 2/2 (8388608-16777216) size 8Mi finished 2026/08/14 02:27:59 DEBUG : test-multithread-copy-true-16777216-2: Done sending chunk 0 2026/08/14 02:27:59 DEBUG : test-multithread-copy-true-16777216-2: multi-thread copy: chunk 1/2 (0-8388608) size 8Mi finished 2026/08/14 02:27:59 DEBUG : test-multithread-copy-true-16777216-2: Finishing large file upload with 2 parts 2026/08/14 02:28:00 DEBUG : test-multithread-copy-true-16777216-2: Finished multi-thread copy with 2 parts of size 8Mi === RUN TestMultithreadCopy/upload=true,size=16777217,streams=2 2026/08/14 02:28:03 DEBUG : test-multithread-copy-true-16777217-2: multi-thread copy: disabling buffering because source is local disk 2026/08/14 02:28:03 DEBUG : test-multithread-copy-true-16777217-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2026/08/14 02:28:03 DEBUG : test-multithread-copy-true-16777217-2: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2026/08/14 02:28:03 DEBUG : test-multithread-copy-true-16777217-2: Starting multi-thread copy with 3 chunks of size 8Mi with 3 parallel streams 2026/08/14 02:28:03 DEBUG : test-multithread-copy-true-16777217-2: multi-thread copy: chunk 3/3 (16777216-16777217) size 1 starting 2026/08/14 02:28:03 DEBUG : test-multithread-copy-true-16777217-2: Sending chunk 2 length 1 2026/08/14 02:28:03 DEBUG : test-multithread-copy-true-16777217-2: multi-thread copy: chunk 1/3 (0-8388608) size 8Mi starting 2026/08/14 02:28:03 DEBUG : test-multithread-copy-true-16777217-2: multi-thread copy: chunk 2/3 (8388608-16777216) size 8Mi starting 2026/08/14 02:28:03 DEBUG : test-multithread-copy-true-16777217-2: Sending chunk 0 length 8388608 2026/08/14 02:28:03 DEBUG : test-multithread-copy-true-16777217-2: Sending chunk 1 length 8388608 2026/08/14 02:28:04 DEBUG : test-multithread-copy-true-16777217-2: Done sending chunk 2 2026/08/14 02:28:04 DEBUG : test-multithread-copy-true-16777217-2: multi-thread copy: chunk 3/3 (16777216-16777217) size 1 finished 2026/08/14 02:28:06 DEBUG : test-multithread-copy-true-16777217-2: Done sending chunk 0 2026/08/14 02:28:06 DEBUG : test-multithread-copy-true-16777217-2: multi-thread copy: chunk 1/3 (0-8388608) size 8Mi finished 2026/08/14 02:28:06 DEBUG : test-multithread-copy-true-16777217-2: Done sending chunk 1 2026/08/14 02:28:06 DEBUG : test-multithread-copy-true-16777217-2: multi-thread copy: chunk 2/3 (8388608-16777216) size 8Mi finished 2026/08/14 02:28:06 DEBUG : test-multithread-copy-true-16777217-2: Finishing large file upload with 3 parts 2026/08/14 02:28:07 DEBUG : test-multithread-copy-true-16777217-2: Finished multi-thread copy with 3 parts of size 8Mi --- PASS: TestMultithreadCopy (52.22s) --- PASS: TestMultithreadCopy/upload=false,size=16777215,streams=2 (8.64s) --- PASS: TestMultithreadCopy/upload=false,size=16777216,streams=2 (11.18s) --- PASS: TestMultithreadCopy/upload=false,size=16777217,streams=2 (10.68s) --- PASS: TestMultithreadCopy/upload=true,size=16777215,streams=2 (6.72s) --- PASS: TestMultithreadCopy/upload=true,size=16777216,streams=2 (6.87s) --- PASS: TestMultithreadCopy/upload=true,size=16777217,streams=2 (6.47s) === RUN TestMultithreadCopyAbort run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" 2026/08/14 02:28:08 DEBUG : chunksize-probe: Cancelling large file upload 2026/08/14 02:28:11 DEBUG : test-multithread-abort: multi-thread copy: disabling buffering because source is local disk 2026/08/14 02:28:11 DEBUG : test-multithread-abort: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 1 2026/08/14 02:28:11 DEBUG : test-multithread-abort: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2026/08/14 02:28:11 DEBUG : test-multithread-abort: Starting multi-thread copy with 3 chunks of size 8Mi with 3 parallel streams 2026/08/14 02:28:11 DEBUG : test-multithread-abort: multi-thread copy: chunk 3/3 (16777216-16777217) size 1 starting 2026/08/14 02:28:11 DEBUG : test-multithread-abort: multi-thread copy: chunk 1/3 (0-8388608) size 8Mi starting 2026/08/14 02:28:11 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2026/08/14 02:28:11 DEBUG : Open with options = [RangeOption(0,8388607)] 2026/08/14 02:28:11 DEBUG : test-multithread-abort: multi-thread copy: chunk 2/3 (8388608-16777216) size 8Mi starting 2026/08/14 02:28:11 DEBUG : Open with options = [RangeOption(8388608,16777215)] 2026/08/14 02:28:11 DEBUG : test-multithread-abort: Sending chunk 0 length 8388608 2026/08/14 02:28:11 DEBUG : test-multithread-abort: Sending chunk 1 length 8388608 2026/08/14 02:28:13 DEBUG : test-multithread-abort: Done sending chunk 1 2026/08/14 02:28:13 DEBUG : test-multithread-abort: multi-thread copy: chunk 2/3 (8388608-16777216) size 8Mi finished 2026/08/14 02:28:13 DEBUG : test-multithread-abort: Done sending chunk 0 2026/08/14 02:28:13 DEBUG : test-multithread-abort: multi-thread copy: chunk 1/3 (0-8388608) size 8Mi finished 2026/08/14 02:28:13 DEBUG : Returning error reader 2026/08/14 02:28:13 DEBUG : test-multithread-abort: Sending chunk 2 length 1 2026/08/14 02:28:13 DEBUG : BOOM: simulated read failure 2026/08/14 02:28:13 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 0/10: BOOM: simulated read failure 2026/08/14 02:28:13 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2026/08/14 02:28:14 DEBUG : Returning error reader 2026/08/14 02:28:14 DEBUG : BOOM: simulated read failure 2026/08/14 02:28:14 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 1/10: BOOM: simulated read failure 2026/08/14 02:28:14 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2026/08/14 02:28:15 DEBUG : Returning error reader 2026/08/14 02:28:15 DEBUG : BOOM: simulated read failure 2026/08/14 02:28:15 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 2/10: BOOM: simulated read failure 2026/08/14 02:28:15 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2026/08/14 02:28:16 DEBUG : Returning error reader 2026/08/14 02:28:16 DEBUG : BOOM: simulated read failure 2026/08/14 02:28:16 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 3/10: BOOM: simulated read failure 2026/08/14 02:28:16 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2026/08/14 02:28:17 DEBUG : Returning error reader 2026/08/14 02:28:17 DEBUG : BOOM: simulated read failure 2026/08/14 02:28:17 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 4/10: BOOM: simulated read failure 2026/08/14 02:28:17 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2026/08/14 02:28:18 DEBUG : Returning error reader 2026/08/14 02:28:18 DEBUG : BOOM: simulated read failure 2026/08/14 02:28:18 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 5/10: BOOM: simulated read failure 2026/08/14 02:28:18 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2026/08/14 02:28:19 DEBUG : Returning error reader 2026/08/14 02:28:19 DEBUG : BOOM: simulated read failure 2026/08/14 02:28:19 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 6/10: BOOM: simulated read failure 2026/08/14 02:28:19 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2026/08/14 02:28:20 DEBUG : Returning error reader 2026/08/14 02:28:20 DEBUG : BOOM: simulated read failure 2026/08/14 02:28:20 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 7/10: BOOM: simulated read failure 2026/08/14 02:28:20 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2026/08/14 02:28:21 DEBUG : Returning error reader 2026/08/14 02:28:21 DEBUG : BOOM: simulated read failure 2026/08/14 02:28:21 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 8/10: BOOM: simulated read failure 2026/08/14 02:28:21 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2026/08/14 02:28:22 DEBUG : Returning error reader 2026/08/14 02:28:22 DEBUG : BOOM: simulated read failure 2026/08/14 02:28:22 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 9/10: BOOM: simulated read failure 2026/08/14 02:28:22 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2026/08/14 02:28:23 DEBUG : Returning error reader 2026/08/14 02:28:23 DEBUG : BOOM: simulated read failure 2026/08/14 02:28:23 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 10/10: BOOM: simulated read failure 2026/08/14 02:28:23 DEBUG : test-multithread-abort: Reopen failed after offset 0 bytes read: failed to reopen: too many retries 2026/08/14 02:28:23 DEBUG : test-multithread-abort: Error sending chunk 2 (retry=false): Post "https://pod-000-1182-02.backblaze.com/b2api/v1/b2_upload_part/4_zbfa25da3108dfbeb9ff80e1b_f20122dc1719af863_d20260814_m022811_c001_v0001182_t0039_u01786674491361/0013": BOOM: simulated read failure: &url.Error{Op:"Post", URL:"https://pod-000-1182-02.backblaze.com/b2api/v1/b2_upload_part/4_zbfa25da3108dfbeb9ff80e1b_f20122dc1719af863_d20260814_m022811_c001_v0001182_t0039_u01786674491361/0013", Err:(*errors.errorString)(0x1eb2ea46af20)} 2026/08/14 02:28:23 DEBUG : test-multithread-abort: Error sending chunk 2: Post "https://pod-000-1182-02.backblaze.com/b2api/v1/b2_upload_part/4_zbfa25da3108dfbeb9ff80e1b_f20122dc1719af863_d20260814_m022811_c001_v0001182_t0039_u01786674491361/0013": BOOM: simulated read failure 2026/08/14 02:28:23 DEBUG : test-multithread-abort: multi-thread copy: chunk 3/3 failed: multi-thread copy: failed to write chunk: Post "https://pod-000-1182-02.backblaze.com/b2api/v1/b2_upload_part/4_zbfa25da3108dfbeb9ff80e1b_f20122dc1719af863_d20260814_m022811_c001_v0001182_t0039_u01786674491361/0013": BOOM: simulated read failure 2026/08/14 02:28:23 DEBUG : test-multithread-abort: multi-thread copy: cancelling transfer on exit 2026/08/14 02:28:23 DEBUG : test-multithread-abort: Cancelling large file upload --- PASS: TestMultithreadCopyAbort (16.90s) === RUN TestSizeDiffers 2026/08/14 02:28:25 DEBUG : a: size = 0 OK 2026/08/14 02:28:25 DEBUG : a: size = 1 (memory) 2026/08/14 02:28:25 DEBUG : a: size = 2 (memory) --- PASS: TestSizeDiffers (0.00s) === RUN TestReOpen === RUN TestReOpen/Normal === RUN TestReOpen/Normal/Basics 2026/08/14 02:28:25 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/ErrorAtStart === RUN TestReOpen/Normal/WithErrors 2026/08/14 02:28:25 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/08/14 02:28:25 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/08/14 02:28:25 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/Normal/TooManyErrors 2026/08/14 02:28:25 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/08/14 02:28:25 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/08/14 02:28:25 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/08/14 02:28:25 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/Normal/ReadAt 2026/08/14 02:28:25 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/08/14 02:28:25 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/08/14 02:28:25 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/Normal/Seek 2026/08/14 02:28:25 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/08/14 02:28:25 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/08/14 02:28:25 DEBUG : potato: Seek from 5 to 2 === RUN TestReOpen/Normal/AccountRead === RUN TestReOpen/Normal/AccountReadDelay 2026/08/14 02:28:25 DEBUG : potato: Seek from 10 to 0 2026/08/14 02:28:25 DEBUG : potato: Seek from 10 to 0 2026/08/14 02:28:25 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/AccountReadError === RUN TestReOpen/WithRangeOption === RUN TestReOpen/WithRangeOption/Basics 2026/08/14 02:28:25 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/ErrorAtStart === RUN TestReOpen/WithRangeOption/WithErrors 2026/08/14 02:28:25 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/08/14 02:28:25 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/08/14 02:28:25 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithRangeOption/TooManyErrors 2026/08/14 02:28:25 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/08/14 02:28:25 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/08/14 02:28:25 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/08/14 02:28:25 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithRangeOption/ReadAt 2026/08/14 02:28:25 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/08/14 02:28:25 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/08/14 02:28:25 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/WithRangeOption/Seek 2026/08/14 02:28:25 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/08/14 02:28:25 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/08/14 02:28:25 DEBUG : potato: Seek from 5 to 2 2026/08/14 02:28:25 DEBUG : potato: Seek from 7 to 4 === RUN TestReOpen/WithRangeOption/AccountRead === RUN TestReOpen/WithRangeOption/AccountReadDelay 2026/08/14 02:28:25 DEBUG : potato: Seek from 7 to 0 2026/08/14 02:28:25 DEBUG : potato: Seek from 7 to 0 2026/08/14 02:28:25 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/AccountReadError === RUN TestReOpen/WithSeekOption === RUN TestReOpen/WithSeekOption/Basics 2026/08/14 02:28:25 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/ErrorAtStart === RUN TestReOpen/WithSeekOption/WithErrors 2026/08/14 02:28:25 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/08/14 02:28:25 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/08/14 02:28:25 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithSeekOption/TooManyErrors 2026/08/14 02:28:25 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/08/14 02:28:25 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/08/14 02:28:25 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/08/14 02:28:25 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithSeekOption/ReadAt 2026/08/14 02:28:25 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/08/14 02:28:25 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/08/14 02:28:25 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/WithSeekOption/Seek 2026/08/14 02:28:25 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/08/14 02:28:25 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/08/14 02:28:25 DEBUG : potato: Seek from 5 to 2 2026/08/14 02:28:25 DEBUG : potato: Seek from 7 to 5 === RUN TestReOpen/WithSeekOption/AccountRead === RUN TestReOpen/WithSeekOption/AccountReadDelay 2026/08/14 02:28:25 DEBUG : potato: Seek from 8 to 0 2026/08/14 02:28:25 DEBUG : potato: Seek from 8 to 0 2026/08/14 02:28:25 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/AccountReadError === RUN TestReOpen/UnknownSize === RUN TestReOpen/UnknownSize/Basics 2026/08/14 02:28:25 DEBUG : potato: Seek from 9 to 0 === RUN TestReOpen/UnknownSize/ErrorAtStart === RUN TestReOpen/UnknownSize/WithErrors 2026/08/14 02:28:25 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/08/14 02:28:25 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/08/14 02:28:25 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/UnknownSize/TooManyErrors 2026/08/14 02:28:25 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/08/14 02:28:25 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/08/14 02:28:25 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/08/14 02:28:25 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/UnknownSize/ReadAt 2026/08/14 02:28:25 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/08/14 02:28:25 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/08/14 02:28:25 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/UnknownSize/Seek 2026/08/14 02:28:25 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/08/14 02:28:25 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/08/14 02:28:25 DEBUG : potato: Seek from 5 to 2 2026/08/14 02:28:25 DEBUG : potato: Seek from 7 to 6 === RUN TestReOpen/UnknownSize/AccountRead === RUN TestReOpen/UnknownSize/AccountReadDelay 2026/08/14 02:28:25 DEBUG : potato: Seek from 9 to 0 2026/08/14 02:28:25 DEBUG : potato: Seek from 9 to 0 2026/08/14 02:28:25 DEBUG : potato: Seek from 9 to 0 === RUN TestReOpen/UnknownSize/AccountReadError --- PASS: TestReOpen (0.00s) --- PASS: TestReOpen/Normal (0.00s) --- PASS: TestReOpen/Normal/Basics (0.00s) --- PASS: TestReOpen/Normal/ErrorAtStart (0.00s) --- PASS: TestReOpen/Normal/WithErrors (0.00s) --- PASS: TestReOpen/Normal/TooManyErrors (0.00s) --- PASS: TestReOpen/Normal/ReadAt (0.00s) --- PASS: TestReOpen/Normal/Seek (0.00s) --- PASS: TestReOpen/Normal/AccountRead (0.00s) --- PASS: TestReOpen/Normal/AccountReadDelay (0.00s) --- PASS: TestReOpen/Normal/AccountReadError (0.00s) --- PASS: TestReOpen/WithRangeOption (0.00s) --- PASS: TestReOpen/WithRangeOption/Basics (0.00s) --- PASS: TestReOpen/WithRangeOption/ErrorAtStart (0.00s) --- PASS: TestReOpen/WithRangeOption/WithErrors (0.00s) --- PASS: TestReOpen/WithRangeOption/TooManyErrors (0.00s) --- PASS: TestReOpen/WithRangeOption/ReadAt (0.00s) --- PASS: TestReOpen/WithRangeOption/Seek (0.00s) --- PASS: TestReOpen/WithRangeOption/AccountRead (0.00s) --- PASS: TestReOpen/WithRangeOption/AccountReadDelay (0.00s) --- PASS: TestReOpen/WithRangeOption/AccountReadError (0.00s) --- PASS: TestReOpen/WithSeekOption (0.00s) --- PASS: TestReOpen/WithSeekOption/Basics (0.00s) --- PASS: TestReOpen/WithSeekOption/ErrorAtStart (0.00s) --- PASS: TestReOpen/WithSeekOption/WithErrors (0.00s) --- PASS: TestReOpen/WithSeekOption/TooManyErrors (0.00s) --- PASS: TestReOpen/WithSeekOption/ReadAt (0.00s) --- PASS: TestReOpen/WithSeekOption/Seek (0.00s) --- PASS: TestReOpen/WithSeekOption/AccountRead (0.00s) --- PASS: TestReOpen/WithSeekOption/AccountReadDelay (0.00s) --- PASS: TestReOpen/WithSeekOption/AccountReadError (0.00s) --- PASS: TestReOpen/UnknownSize (0.00s) --- PASS: TestReOpen/UnknownSize/Basics (0.00s) --- PASS: TestReOpen/UnknownSize/ErrorAtStart (0.00s) --- PASS: TestReOpen/UnknownSize/WithErrors (0.00s) --- PASS: TestReOpen/UnknownSize/TooManyErrors (0.00s) --- PASS: TestReOpen/UnknownSize/ReadAt (0.00s) --- PASS: TestReOpen/UnknownSize/Seek (0.00s) --- PASS: TestReOpen/UnknownSize/AccountRead (0.00s) --- PASS: TestReOpen/UnknownSize/AccountReadDelay (0.00s) --- PASS: TestReOpen/UnknownSize/AccountReadError (0.00s) === RUN TestCheck run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", 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 (4.75s) --- 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.23s) --- PASS: TestCheck/7 (0.18s) === RUN TestCheckFsError 2026/08/14 02:28:30 DEBUG : Creating backend with remote "nonexistent" 2026/08/14 02:28:30 DEBUG : Creating backend with remote "nonexistent" 2026/08/14 02:28:30 DEBUG : Local file system at nonexistent: Waiting for checks to finish 2026/08/14 02:28:30 ERROR : Local file system at nonexistent: error reading source root directory: directory not found 2026/08/14 02:28:30 NOTICE: Local file system at nonexistent: 0 differences found 2026/08/14 02:28:30 NOTICE: Local file system at nonexistent: 2 errors while checking --- PASS: TestCheckFsError (0.00s) === RUN TestCheckDownload run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", 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 (6.78s) --- PASS: TestCheckDownload/1 (0.72s) --- PASS: TestCheckDownload/2 (0.36s) --- PASS: TestCheckDownload/3 (0.36s) --- PASS: TestCheckDownload/4 (0.62s) --- PASS: TestCheckDownload/5 (0.46s) --- PASS: TestCheckDownload/6 (0.37s) --- PASS: TestCheckDownload/7 (0.37s) === RUN TestCheckSizeOnly run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", 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 (4.49s) --- PASS: TestCheckSizeOnly/1 (0.18s) --- PASS: TestCheckSizeOnly/2 (0.18s) --- PASS: TestCheckSizeOnly/3 (0.18s) --- PASS: TestCheckSizeOnly/4 (0.18s) --- PASS: TestCheckSizeOnly/5 (0.18s) --- PASS: TestCheckSizeOnly/6 (0.18s) --- PASS: TestCheckSizeOnly/7 (0.18s) === RUN TestCheckEqualReaders --- PASS: TestCheckEqualReaders (0.00s) === RUN TestParseSumFile run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" 2026/08/14 02:28:42 NOTICE: test.sum: improperly formatted checksum line 4 2026/08/14 02:28:42 NOTICE: test.sum: improperly formatted checksum line 5 2026/08/14 02:28:42 NOTICE: test.sum: improperly formatted checksum line 6 2026/08/14 02:28:42 NOTICE: test.sum: 2 warning(s) suppressed... 2026/08/14 02:28:43 NOTICE: test.sum: improperly formatted checksum line 4 2026/08/14 02:28:43 NOTICE: test.sum: improperly formatted checksum line 5 2026/08/14 02:28:43 NOTICE: test.sum: improperly formatted checksum line 6 2026/08/14 02:28:43 NOTICE: test.sum: 2 warning(s) suppressed... --- PASS: TestParseSumFile (2.60s) === RUN TestCheckSum run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" 2026/08/14 02:28:44 DEBUG : Creating backend with remote "TestB2:rclone-test-qiqequr8wunu/data" check_test.go:354: B2 bucket rclone-test-qiqequr8wunu path data lacks md5, skipping --- SKIP: TestCheckSum (1.46s) === RUN TestCheckSumDownload run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" 2026/08/14 02:28:45 DEBUG : Creating backend with remote "TestB2:rclone-test-qiqequr8wunu/data" === RUN TestCheckSumDownload/subtest1 === RUN TestCheckSumDownload/subtest2 === RUN TestCheckSumDownload/subtest3 === RUN TestCheckSumDownload/subtest4 === RUN TestCheckSumDownload/subtest5 === RUN TestCheckSumDownload/subtest6 === RUN TestCheckSumDownload/subtest7 --- PASS: TestCheckSumDownload (12.86s) --- PASS: TestCheckSumDownload/subtest1 (1.32s) --- PASS: TestCheckSumDownload/subtest2 (0.73s) --- PASS: TestCheckSumDownload/subtest3 (0.97s) --- PASS: TestCheckSumDownload/subtest4 (0.75s) --- PASS: TestCheckSumDownload/subtest5 (0.75s) --- PASS: TestCheckSumDownload/subtest6 (0.75s) --- PASS: TestCheckSumDownload/subtest7 (0.75s) === RUN TestCheckSumConcurrency 2026/08/14 02:28:58 DEBUG : file0: md5 = 022eb0c0085c731f335df970fafa7446 OK 2026/08/14 02:28:58 DEBUG : file2: md5 = a58f09ae3db8ae4b943fdbaace096072 OK 2026/08/14 02:28:58 DEBUG : file1: md5 = 17fbabbccbdea0e6be9eb64c8c6fea41 OK 2026/08/14 02:28:58 DEBUG : file3: md5 = e0639b17e0744aca9a3ba29c049e59ee OK 2026/08/14 02:28:58 NOTICE: Mock file system at checkSum: 0 differences found 2026/08/14 02:28:58 NOTICE: Mock file system at checkSum: 4 matching files --- PASS: TestCheckSumConcurrency (0.00s) === RUN TestCheckSumDownloadConcurrency 2026/08/14 02:28:58 DEBUG : file0: md5 = 022eb0c0085c731f335df970fafa7446 OK 2026/08/14 02:28:58 DEBUG : file2: md5 = a58f09ae3db8ae4b943fdbaace096072 OK 2026/08/14 02:28:58 DEBUG : file1: md5 = 17fbabbccbdea0e6be9eb64c8c6fea41 OK 2026/08/14 02:28:58 DEBUG : file3: md5 = e0639b17e0744aca9a3ba29c049e59ee OK 2026/08/14 02:28:58 NOTICE: Mock file system at checkSum: 0 differences found 2026/08/14 02:28:58 NOTICE: Mock file system at checkSum: 4 matching files --- PASS: TestCheckSumDownloadConcurrency (0.00s) === RUN TestApplyTransforms 2026/08/14 02:28:58 DEBUG : Creating backend with remote "TestB2:rclone-test-nubekob9maya" 2026/08/14 02:28:58 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/08/14 02:28:58 DEBUG : Creating backend with remote "/tmp/rclone320664299" run.go:198: Remote "B2 bucket rclone-test-nubekob9maya", Local "Local file system at /tmp/rclone320664299", Modify Window "1ms" upper checkfile vs. lower remote (without normalization) 2026/08/14 02:29:00 ERROR : hello, world!: sum not found 2026/08/14 02:29:00 ERROR : HELLO, WORLD!: file not in B2 bucket rclone-test-nubekob9maya 2026/08/14 02:29:00 NOTICE: B2 bucket rclone-test-nubekob9maya: 1 files missing 2026/08/14 02:29:00 NOTICE: 1 hashes missing 2026/08/14 02:29:00 NOTICE: B2 bucket rclone-test-nubekob9maya: 1 differences found 2026/08/14 02:29:00 NOTICE: B2 bucket rclone-test-nubekob9maya: 2 errors while checking upper checkfile vs. lower remote (with normalization) 2026/08/14 02:29:01 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/08/14 02:29:01 NOTICE: B2 bucket rclone-test-nubekob9maya: 0 differences found 2026/08/14 02:29:01 NOTICE: B2 bucket rclone-test-nubekob9maya: 1 matching files 2026/08/14 02:29:01 DEBUG : Creating backend with remote "TestB2:rclone-test-funukaf1kuje" 2026/08/14 02:29:01 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/08/14 02:29:02 DEBUG : Creating backend with remote "/tmp/rclone2464970705" run.go:198: Remote "B2 bucket rclone-test-funukaf1kuje", Local "Local file system at /tmp/rclone2464970705", Modify Window "1ms" lower checkfile vs. upper remote (without normalization) 2026/08/14 02:29:03 ERROR : HELLO, WORLD!: sum not found 2026/08/14 02:29:03 ERROR : hello, world!: file not in B2 bucket rclone-test-funukaf1kuje 2026/08/14 02:29:03 NOTICE: B2 bucket rclone-test-funukaf1kuje: 1 files missing 2026/08/14 02:29:03 NOTICE: 1 hashes missing 2026/08/14 02:29:03 NOTICE: B2 bucket rclone-test-funukaf1kuje: 1 differences found 2026/08/14 02:29:03 NOTICE: B2 bucket rclone-test-funukaf1kuje: 2 errors while checking lower checkfile vs. upper remote (with normalization) 2026/08/14 02:29:04 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/08/14 02:29:04 NOTICE: B2 bucket rclone-test-funukaf1kuje: 0 differences found 2026/08/14 02:29:04 NOTICE: B2 bucket rclone-test-funukaf1kuje: 1 matching files 2026/08/14 02:29:04 DEBUG : Creating backend with remote "TestB2:rclone-test-yubunol2joxi" 2026/08/14 02:29:04 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/08/14 02:29:05 DEBUG : Creating backend with remote "/tmp/rclone2253802278" run.go:198: Remote "B2 bucket rclone-test-yubunol2joxi", Local "Local file system at /tmp/rclone2253802278", Modify Window "1ms" 2026/08/14 02:29:26 DEBUG : pacer: low level retry 1/10 (error Post "https://api001.backblazeb2.com/b2api/v1/b2_create_bucket": EOF) 2026/08/14 02:29:26 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/08/14 02:29:26 DEBUG : pacer: Reducing sleep to 10ms lower checkfile vs. upperlowermixed remote (without normalization) 2026/08/14 02:29:28 ERROR : HeLlO, wOrLd!: sum not found 2026/08/14 02:29:28 ERROR : hello, world!: file not in B2 bucket rclone-test-yubunol2joxi 2026/08/14 02:29:28 NOTICE: B2 bucket rclone-test-yubunol2joxi: 1 files missing 2026/08/14 02:29:28 NOTICE: 1 hashes missing 2026/08/14 02:29:28 NOTICE: B2 bucket rclone-test-yubunol2joxi: 1 differences found 2026/08/14 02:29:28 NOTICE: B2 bucket rclone-test-yubunol2joxi: 2 errors while checking lower checkfile vs. upperlowermixed remote (with normalization) 2026/08/14 02:29:28 DEBUG : HeLlO, wOrLd!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/08/14 02:29:28 NOTICE: B2 bucket rclone-test-yubunol2joxi: 0 differences found 2026/08/14 02:29:28 NOTICE: B2 bucket rclone-test-yubunol2joxi: 1 matching files 2026/08/14 02:29:28 DEBUG : Creating backend with remote "TestB2:rclone-test-qokuteg0mica" 2026/08/14 02:29:28 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/08/14 02:29:29 DEBUG : Creating backend with remote "/tmp/rclone2408910967" run.go:198: Remote "B2 bucket rclone-test-qokuteg0mica", Local "Local file system at /tmp/rclone2408910967", Modify Window "1ms" upperlowermixed checkfile vs. upper remote (without normalization) 2026/08/14 02:29:31 ERROR : HELLO, WORLD!: sum not found 2026/08/14 02:29:31 ERROR : HeLlO, wOrLd!: file not in B2 bucket rclone-test-qokuteg0mica 2026/08/14 02:29:31 NOTICE: B2 bucket rclone-test-qokuteg0mica: 1 files missing 2026/08/14 02:29:31 NOTICE: 1 hashes missing 2026/08/14 02:29:31 NOTICE: B2 bucket rclone-test-qokuteg0mica: 1 differences found 2026/08/14 02:29:31 NOTICE: B2 bucket rclone-test-qokuteg0mica: 2 errors while checking upperlowermixed checkfile vs. upper remote (with normalization) 2026/08/14 02:29:32 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/08/14 02:29:32 NOTICE: B2 bucket rclone-test-qokuteg0mica: 0 differences found 2026/08/14 02:29:32 NOTICE: B2 bucket rclone-test-qokuteg0mica: 1 matching files 2026/08/14 02:29:32 DEBUG : Creating backend with remote "TestB2:rclone-test-jisetor2vuxo" 2026/08/14 02:29:32 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/08/14 02:29:32 DEBUG : Creating backend with remote "/tmp/rclone2801662834" run.go:198: Remote "B2 bucket rclone-test-jisetor2vuxo", Local "Local file system at /tmp/rclone2801662834", Modify Window "1ms" NFD checkfile vs. NFC remote (without normalization) 2026/08/14 02:29:34 ERROR : 測試_Русский___ě_áñ: sum not found 2026/08/14 02:29:34 ERROR : 測試_Русский___ě_áñ: file not in B2 bucket rclone-test-jisetor2vuxo 2026/08/14 02:29:34 NOTICE: B2 bucket rclone-test-jisetor2vuxo: 1 files missing 2026/08/14 02:29:34 NOTICE: 1 hashes missing 2026/08/14 02:29:34 NOTICE: B2 bucket rclone-test-jisetor2vuxo: 1 differences found 2026/08/14 02:29:34 NOTICE: B2 bucket rclone-test-jisetor2vuxo: 2 errors while checking NFD checkfile vs. NFC remote (with normalization) 2026/08/14 02:29:35 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/08/14 02:29:35 NOTICE: B2 bucket rclone-test-jisetor2vuxo: 0 differences found 2026/08/14 02:29:35 NOTICE: B2 bucket rclone-test-jisetor2vuxo: 1 matching files 2026/08/14 02:29:35 DEBUG : Creating backend with remote "TestB2:rclone-test-domibun0nozo" 2026/08/14 02:29:35 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/08/14 02:29:35 DEBUG : Creating backend with remote "/tmp/rclone2942514280" run.go:198: Remote "B2 bucket rclone-test-domibun0nozo", Local "Local file system at /tmp/rclone2942514280", Modify Window "1ms" NFC checkfile vs. NFD remote (without normalization) 2026/08/14 02:29:37 ERROR : 測試_Русский___ě_áñ: sum not found 2026/08/14 02:29:37 ERROR : 測試_Русский___ě_áñ: file not in B2 bucket rclone-test-domibun0nozo 2026/08/14 02:29:37 NOTICE: B2 bucket rclone-test-domibun0nozo: 1 files missing 2026/08/14 02:29:37 NOTICE: 1 hashes missing 2026/08/14 02:29:37 NOTICE: B2 bucket rclone-test-domibun0nozo: 1 differences found 2026/08/14 02:29:37 NOTICE: B2 bucket rclone-test-domibun0nozo: 2 errors while checking NFC checkfile vs. NFD remote (with normalization) 2026/08/14 02:29:38 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/08/14 02:29:38 NOTICE: B2 bucket rclone-test-domibun0nozo: 0 differences found 2026/08/14 02:29:38 NOTICE: B2 bucket rclone-test-domibun0nozo: 1 matching files 2026/08/14 02:29:38 DEBUG : Creating backend with remote "TestB2:rclone-test-pilicaf2bira" 2026/08/14 02:29:38 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/08/14 02:29:39 DEBUG : Creating backend with remote "/tmp/rclone2035181681" run.go:198: Remote "B2 bucket rclone-test-pilicaf2bira", Local "Local file system at /tmp/rclone2035181681", Modify Window "1ms" NFDx2 checkfile vs. both remote (without normalization) 2026/08/14 02:29:41 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/08/14 02:29:41 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-pilicaf2bira 2026/08/14 02:29:41 NOTICE: B2 bucket rclone-test-pilicaf2bira: 1 files missing 2026/08/14 02:29:41 NOTICE: 1 hashes missing 2026/08/14 02:29:41 NOTICE: B2 bucket rclone-test-pilicaf2bira: 1 differences found 2026/08/14 02:29:41 NOTICE: B2 bucket rclone-test-pilicaf2bira: 2 errors while checking NFDx2 checkfile vs. both remote (with normalization) 2026/08/14 02:29:41 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/08/14 02:29:41 NOTICE: B2 bucket rclone-test-pilicaf2bira: 0 differences found 2026/08/14 02:29:41 NOTICE: B2 bucket rclone-test-pilicaf2bira: 1 matching files 2026/08/14 02:29:41 DEBUG : Creating backend with remote "TestB2:rclone-test-tababub2doki" 2026/08/14 02:29:41 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/08/14 02:29:42 DEBUG : Creating backend with remote "/tmp/rclone3801753612" run.go:198: Remote "B2 bucket rclone-test-tababub2doki", Local "Local file system at /tmp/rclone3801753612", Modify Window "1ms" NFCx2 checkfile vs. both remote (without normalization) 2026/08/14 02:29:44 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/08/14 02:29:44 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-tababub2doki 2026/08/14 02:29:44 NOTICE: B2 bucket rclone-test-tababub2doki: 1 files missing 2026/08/14 02:29:44 NOTICE: 1 hashes missing 2026/08/14 02:29:44 NOTICE: B2 bucket rclone-test-tababub2doki: 1 differences found 2026/08/14 02:29:44 NOTICE: B2 bucket rclone-test-tababub2doki: 2 errors while checking NFCx2 checkfile vs. both remote (with normalization) 2026/08/14 02:29:45 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/08/14 02:29:45 NOTICE: B2 bucket rclone-test-tababub2doki: 0 differences found 2026/08/14 02:29:45 NOTICE: B2 bucket rclone-test-tababub2doki: 1 matching files 2026/08/14 02:29:45 DEBUG : Creating backend with remote "TestB2:rclone-test-pefivey4yegi" 2026/08/14 02:29:45 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/08/14 02:29:45 DEBUG : Creating backend with remote "/tmp/rclone1530321604" run.go:198: Remote "B2 bucket rclone-test-pefivey4yegi", Local "Local file system at /tmp/rclone1530321604", Modify Window "1ms" both checkfile vs. NFDx2 remote (without normalization) 2026/08/14 02:29:47 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/08/14 02:29:47 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-pefivey4yegi 2026/08/14 02:29:47 NOTICE: B2 bucket rclone-test-pefivey4yegi: 1 files missing 2026/08/14 02:29:47 NOTICE: 1 hashes missing 2026/08/14 02:29:47 NOTICE: B2 bucket rclone-test-pefivey4yegi: 1 differences found 2026/08/14 02:29:47 NOTICE: B2 bucket rclone-test-pefivey4yegi: 2 errors while checking both checkfile vs. NFDx2 remote (with normalization) 2026/08/14 02:29:48 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/08/14 02:29:48 NOTICE: B2 bucket rclone-test-pefivey4yegi: 0 differences found 2026/08/14 02:29:48 NOTICE: B2 bucket rclone-test-pefivey4yegi: 1 matching files 2026/08/14 02:29:48 DEBUG : Creating backend with remote "TestB2:rclone-test-duketoy8muwu" 2026/08/14 02:29:48 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/08/14 02:29:49 DEBUG : Creating backend with remote "/tmp/rclone1367018774" run.go:198: Remote "B2 bucket rclone-test-duketoy8muwu", Local "Local file system at /tmp/rclone1367018774", Modify Window "1ms" both checkfile vs. NFCx2 remote (without normalization) 2026/08/14 02:29:51 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/08/14 02:29:51 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-duketoy8muwu 2026/08/14 02:29:51 NOTICE: B2 bucket rclone-test-duketoy8muwu: 1 files missing 2026/08/14 02:29:51 NOTICE: 1 hashes missing 2026/08/14 02:29:51 NOTICE: B2 bucket rclone-test-duketoy8muwu: 1 differences found 2026/08/14 02:29:51 NOTICE: B2 bucket rclone-test-duketoy8muwu: 2 errors while checking both checkfile vs. NFCx2 remote (with normalization) 2026/08/14 02:29:51 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/08/14 02:29:51 NOTICE: B2 bucket rclone-test-duketoy8muwu: 0 differences found 2026/08/14 02:29:51 NOTICE: B2 bucket rclone-test-duketoy8muwu: 1 matching files 2026/08/14 02:29:51 DEBUG : B2 bucket rclone-test-duketoy8muwu: Purge remote 2026/08/14 02:29:51 INFO : B2 bucket rclone-test-duketoy8muwu: cleaning bucket "rclone-test-duketoy8muwu" of all files 2026/08/14 02:29:51 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_zdf82adf310adfbeb9ff80e1b_f10658dbfb2dc7e49_d20260814_m022950_c001_v0001180_t0021_u01786674590585") 2026/08/14 02:29:52 DEBUG : B2 bucket rclone-test-pefivey4yegi: Purge remote 2026/08/14 02:29:52 INFO : B2 bucket rclone-test-pefivey4yegi: cleaning bucket "rclone-test-pefivey4yegi" of all files 2026/08/14 02:29:52 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_zefd2ad1310adfbeb9ff80e1b_f1176ab08a52ba168_d20260814_m022947_c001_v0001146_t0025_u01786674587019") 2026/08/14 02:29:53 DEBUG : B2 bucket rclone-test-tababub2doki: Purge remote 2026/08/14 02:29:53 INFO : B2 bucket rclone-test-tababub2doki: cleaning bucket "rclone-test-tababub2doki" of all files 2026/08/14 02:29:53 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_z1fa29d5310adfbeb9ff80e1b_f10190f0203d03f21_d20260814_m022943_c001_v0001174_t0027_u01786674583697") 2026/08/14 02:29:54 DEBUG : B2 bucket rclone-test-pilicaf2bira: Purge remote 2026/08/14 02:29:54 INFO : B2 bucket rclone-test-pilicaf2bira: cleaning bucket "rclone-test-pilicaf2bira" of all files 2026/08/14 02:29:55 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_zcf528d8310adfbeb9ff80e1b_f109e89e192a8cd0b_d20260814_m022940_c001_v0001179_t0016_u01786674580554") 2026/08/14 02:29:55 DEBUG : B2 bucket rclone-test-domibun0nozo: Purge remote 2026/08/14 02:29:55 INFO : B2 bucket rclone-test-domibun0nozo: cleaning bucket "rclone-test-domibun0nozo" of all files 2026/08/14 02:29:56 DEBUG : 測試_Русский___ě_áñ: Deleting (id "4_zaf827db310adfbeb9ff80e1b_f114168b96f8594af_d20260814_m022937_c001_v0001183_t0013_u01786674577290") 2026/08/14 02:29:56 DEBUG : B2 bucket rclone-test-jisetor2vuxo: Purge remote 2026/08/14 02:29:56 INFO : B2 bucket rclone-test-jisetor2vuxo: cleaning bucket "rclone-test-jisetor2vuxo" of all files 2026/08/14 02:29:57 DEBUG : 測試_Русский___ě_áñ: Deleting (id "4_z5f026df310adfbeb9ff80e1b_f4042aa68e40c3126_d20260814_m022934_c001_v0001161_t0047_u01786674574024") 2026/08/14 02:29:57 DEBUG : B2 bucket rclone-test-qokuteg0mica: Purge remote 2026/08/14 02:29:57 INFO : B2 bucket rclone-test-qokuteg0mica: cleaning bucket "rclone-test-qokuteg0mica" of all files 2026/08/14 02:29:58 DEBUG : HELLO, WORLD!: Deleting (id "4_zcfc26d2310adfbeb9ff80e1b_f414661cc287f6069_d20260814_m022930_c001_v0001184_t0039_u01786674570839") 2026/08/14 02:29:59 DEBUG : B2 bucket rclone-test-yubunol2joxi: Purge remote 2026/08/14 02:29:59 INFO : B2 bucket rclone-test-yubunol2joxi: cleaning bucket "rclone-test-yubunol2joxi" of all files 2026/08/14 02:29:59 DEBUG : HeLlO, wOrLd!: Deleting (id "4_z8fc25d5310adfbeb9ff80e1b_f402fafcc512d54d6_d20260814_m022927_c001_v0001176_t0014_u01786674567622") 2026/08/14 02:30:00 DEBUG : B2 bucket rclone-test-funukaf1kuje: Purge remote 2026/08/14 02:30:00 INFO : B2 bucket rclone-test-funukaf1kuje: cleaning bucket "rclone-test-funukaf1kuje" of all files 2026/08/14 02:30:00 DEBUG : HELLO, WORLD!: Deleting (id "4_zff02fd73109dfbeb9ff80e1b_f4135e08ea000495a_d20260814_m022903_c001_v0001183_t0059_u01786674543457") 2026/08/14 02:30:01 DEBUG : B2 bucket rclone-test-nubekob9maya: Purge remote 2026/08/14 02:30:01 INFO : B2 bucket rclone-test-nubekob9maya: cleaning bucket "rclone-test-nubekob9maya" of all files 2026/08/14 02:30:02 DEBUG : hello, world!: Deleting (id "4_zaff2edb3109dfbeb9ff80e1b_f416b774908efbadc_d20260814_m022900_c001_v0001177_t0011_u01786674540318") --- PASS: TestApplyTransforms (64.26s) === RUN TestTruncateString --- PASS: TestTruncateString (0.00s) === RUN TestCopyFile run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" 2026/08/14 02:30:03 DEBUG : file1: Need to transfer - File not found at Destination 2026/08/14 02:30:03 DEBUG : sub/file2: size = 14 OK 2026/08/14 02:30:03 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2026/08/14 02:30:03 INFO : file1: Copied (new) to: sub/file2 2026/08/14 02:30:04 DEBUG : sub/file2: size = 14 OK 2026/08/14 02:30:04 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/08/14 02:30:04 DEBUG : file1: Unchanged skipping 2026/08/14 02:30:04 DEBUG : B2 bucket rclone-test-qiqequr8wunu: don't need to copy/move sub/file2, it is already at target location --- PASS: TestCopyFile (2.83s) === RUN TestCopyLongFile run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" copy_test.go:154: Test only runs on local --- SKIP: TestCopyLongFile (0.36s) === RUN TestCopyFileBackupDir run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" 2026/08/14 02:30:06 DEBUG : Creating backend with remote "TestB2:rclone-test-qiqequr8wunu/backup" 2026/08/14 02:30:07 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone2108479853) 2026/08/14 02:30:07 DEBUG : dst/file1: size = 18 (B2 bucket rclone-test-qiqequr8wunu) 2026/08/14 02:30:07 DEBUG : dst/file1: Sizes differ 2026/08/14 02:30:09 DEBUG : dst/file1: size = 18 OK 2026/08/14 02:30:09 DEBUG : dst/file1: sha1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK 2026/08/14 02:30:09 INFO : dst/file1: Copied (server-side copy) 2026/08/14 02:30:09 INFO : dst/file1: Deleted 2026/08/14 02:30:09 DEBUG : dst/file1: size = 14 OK 2026/08/14 02:30:09 DEBUG : dst/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2026/08/14 02:30:09 INFO : dst/file1: Copied (new) --- PASS: TestCopyFileBackupDir (4.71s) === RUN TestCopyFileCompareDest run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" 2026/08/14 02:30:10 DEBUG : Creating backend with remote "TestB2:rclone-test-qiqequr8wunu/dst" 2026/08/14 02:30:11 DEBUG : Creating backend with remote "TestB2:rclone-test-qiqequr8wunu/CompareDest" 2026/08/14 02:30:12 DEBUG : one: Need to transfer - File not found at Destination 2026/08/14 02:30:15 DEBUG : one: size = 3 OK 2026/08/14 02:30:15 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2026/08/14 02:30:15 INFO : one: Copied (new) 2026/08/14 02:30:15 DEBUG : one: size = 5 (Local file system at /tmp/rclone2108479853) 2026/08/14 02:30:15 DEBUG : one: size = 3 (B2 bucket rclone-test-qiqequr8wunu path dst) 2026/08/14 02:30:15 DEBUG : one: Sizes differ 2026/08/14 02:30:16 DEBUG : one: size = 5 OK 2026/08/14 02:30:16 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2026/08/14 02:30:16 INFO : one: Copied (replaced existing) 2026/08/14 02:30:17 DEBUG : one: size = 5 (Local file system at /tmp/rclone2108479853) 2026/08/14 02:30:17 DEBUG : one: size = 3 (B2 bucket rclone-test-qiqequr8wunu path dst) 2026/08/14 02:30:17 DEBUG : one: Sizes differ 2026/08/14 02:30:17 DEBUG : one: size = 5 OK 2026/08/14 02:30:17 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/08/14 02:30:17 DEBUG : one: Destination found in --compare-dest, skipping 2026/08/14 02:30:18 DEBUG : two: Need to transfer - File not found at Destination 2026/08/14 02:30:18 DEBUG : two: size = 3 OK 2026/08/14 02:30:18 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/08/14 02:30:18 DEBUG : two: Destination found in --compare-dest, skipping 2026/08/14 02:30:18 DEBUG : two: Need to transfer - File not found at Destination 2026/08/14 02:30:18 DEBUG : two: size = 3 OK 2026/08/14 02:30:18 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/08/14 02:30:18 DEBUG : two: Destination found in --compare-dest, skipping 2026/08/14 02:30:19 DEBUG : two: Need to transfer - File not found at Destination 2026/08/14 02:30:19 DEBUG : two: size = 5 (Local file system at /tmp/rclone2108479853) 2026/08/14 02:30:19 DEBUG : two: size = 3 (B2 bucket rclone-test-qiqequr8wunu path CompareDest) 2026/08/14 02:30:19 DEBUG : two: Sizes differ 2026/08/14 02:30:19 DEBUG : two: size = 5 OK 2026/08/14 02:30:19 DEBUG : two: sha1 = a4be8877ad88f3109a6411985a1f359e10e2d930 OK 2026/08/14 02:30:19 INFO : two: Copied (new) --- PASS: TestCopyFileCompareDest (10.66s) === RUN TestCopyFileCopyDest run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" 2026/08/14 02:30:21 DEBUG : Creating backend with remote "TestB2:rclone-test-qiqequr8wunu/dst" 2026/08/14 02:30:22 DEBUG : Creating backend with remote "TestB2:rclone-test-qiqequr8wunu/CopyDest" 2026/08/14 02:30:23 DEBUG : one: Need to transfer - File not found at Destination 2026/08/14 02:30:25 DEBUG : one: size = 3 OK 2026/08/14 02:30:25 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2026/08/14 02:30:25 INFO : one: Copied (new) 2026/08/14 02:30:26 DEBUG : one: size = 5 (Local file system at /tmp/rclone2108479853) 2026/08/14 02:30:26 DEBUG : one: size = 3 (B2 bucket rclone-test-qiqequr8wunu path dst) 2026/08/14 02:30:26 DEBUG : one: Sizes differ 2026/08/14 02:30:26 DEBUG : one: size = 5 OK 2026/08/14 02:30:26 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2026/08/14 02:30:26 INFO : one: Copied (replaced existing) 2026/08/14 02:30:27 DEBUG : Creating backend with remote "TestB2:rclone-test-qiqequr8wunu/BackupDir" 2026/08/14 02:30:29 DEBUG : one: size = 5 (Local file system at /tmp/rclone2108479853) 2026/08/14 02:30:29 DEBUG : one: size = 3 (B2 bucket rclone-test-qiqequr8wunu path dst) 2026/08/14 02:30:29 DEBUG : one: Sizes differ 2026/08/14 02:30:29 DEBUG : one: size = 5 OK 2026/08/14 02:30:29 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/08/14 02:30:29 DEBUG : one: size = 5 (Local file system at /tmp/rclone2108479853) 2026/08/14 02:30:29 DEBUG : one: size = 3 (B2 bucket rclone-test-qiqequr8wunu path dst) 2026/08/14 02:30:29 DEBUG : one: Sizes differ 2026/08/14 02:30:30 DEBUG : one: size = 3 OK 2026/08/14 02:30:30 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2026/08/14 02:30:30 INFO : one: Copied (server-side copy) 2026/08/14 02:30:31 INFO : one: Deleted 2026/08/14 02:30:31 DEBUG : one: size = 5 OK 2026/08/14 02:30:31 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2026/08/14 02:30:31 INFO : one: Copied (server-side copy) 2026/08/14 02:30:31 DEBUG : one: Destination found in --copy-dest, using server-side copy 2026/08/14 02:30:32 DEBUG : two: Need to transfer - File not found at Destination 2026/08/14 02:30:32 DEBUG : two: size = 3 OK 2026/08/14 02:30:32 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/08/14 02:30:32 DEBUG : two: size = 3 OK 2026/08/14 02:30:32 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2026/08/14 02:30:32 INFO : two: Copied (server-side copy) 2026/08/14 02:30:32 DEBUG : two: Destination found in --copy-dest, using server-side copy 2026/08/14 02:30:33 DEBUG : two: size = 3 OK 2026/08/14 02:30:33 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/08/14 02:30:33 DEBUG : two: Unchanged skipping 2026/08/14 02:30:33 DEBUG : three: Need to transfer - File not found at Destination 2026/08/14 02:30:34 DEBUG : three: size = 7 (Local file system at /tmp/rclone2108479853) 2026/08/14 02:30:34 DEBUG : three: size = 5 (B2 bucket rclone-test-qiqequr8wunu path CopyDest) 2026/08/14 02:30:34 DEBUG : three: Sizes differ 2026/08/14 02:30:34 DEBUG : three: Destination not found in --copy-dest 2026/08/14 02:30:34 DEBUG : three: size = 7 OK 2026/08/14 02:30:34 DEBUG : three: sha1 = 370e613335f3cf2f19b2a2d35a86402f55c659f8 OK 2026/08/14 02:30:34 INFO : three: Copied (new) --- PASS: TestCopyFileCopyDest (15.32s) === RUN TestCopyInplace run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" copy_test.go:371: Partial uploads not supported --- SKIP: TestCopyInplace (0.36s) === RUN TestCopyLongFileName run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" copy_test.go:404: Partial uploads not supported --- SKIP: TestCopyLongFileName (0.36s) === RUN TestCopyLongFileNameCollision run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" copy_test.go:437: Partial uploads not supported --- SKIP: TestCopyLongFileNameCollision (0.37s) === RUN TestCopyFileMaxTransfer run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" 2026/08/14 02:30:38 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination 2026/08/14 02:30:38 DEBUG : TestCopyFileMaxTransfer/file1: size = 14 OK 2026/08/14 02:30:38 DEBUG : TestCopyFileMaxTransfer/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2026/08/14 02:30:38 INFO : TestCopyFileMaxTransfer/file1: Copied (new) 2026/08/14 02:30:38 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination 2026/08/14 02:30:38 ERROR : TestCopyFileMaxTransfer/file2: Failed to copy: Post "https://pod-000-1161-18.backblaze.com/b2api/v1/b2_upload_file/bfa25da3108dfbeb9ff80e1b/c001_v0001161_t0021": max transfer limit reached as set by --max-transfer 2026/08/14 02:30:39 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination 2026/08/14 02:30:39 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination 2026/08/14 02:30:40 DEBUG : TestCopyFileMaxTransfer/file4: size = 2062 OK 2026/08/14 02:30:40 DEBUG : TestCopyFileMaxTransfer/file4: sha1 = 0c44072b408faae63bf522f981a7b217262a5a79 OK 2026/08/14 02:30:40 INFO : TestCopyFileMaxTransfer/file4: Copied (new) --- PASS: TestCopyFileMaxTransfer (4.25s) === RUN TestDeduplicateInteractive run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateInteractive (0.36s) === RUN TestDeduplicateSkip run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSkip (0.36s) === RUN TestDeduplicateSizeOnly run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSizeOnly (0.36s) === RUN TestDeduplicateFirst run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateFirst (0.36s) === RUN TestDeduplicateNewest run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateNewest (0.37s) === RUN TestDeduplicateNewestByHash run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" 2026/08/14 02:30:45 INFO : B2 bucket rclone-test-qiqequr8wunu: Looking for duplicate sha1 hashes using newest mode. 2026/08/14 02:30:45 NOTICE: 3f0d18ff2dfdb227ea62ce0cdfc934bf0826c4ff: Found 3 files with duplicate sha1 hashes 2026/08/14 02:30:46 INFO : one: Deleted 2026/08/14 02:30:46 INFO : also/one: Deleted 2026/08/14 02:30:46 NOTICE: 3f0d18ff2dfdb227ea62ce0cdfc934bf0826c4ff: Deleted 2 extra copies --- PASS: TestDeduplicateNewestByHash (3.38s) === RUN TestDeduplicateOldest run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateOldest (0.37s) === RUN TestDeduplicateLargest run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateLargest (0.36s) === RUN TestDeduplicateSmallest run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSmallest (0.36s) === RUN TestDeduplicateRename run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateRename (0.36s) === RUN TestMergeDirs run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" dedupe_test.go:256: Can't merge directories --- SKIP: TestMergeDirs (0.45s) === RUN TestListDirSorted run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" 2026/08/14 02:30:51 DEBUG : a.txt: Excluded (Size Filter) 2026/08/14 02:30:51 DEBUG : a.txt: Excluded 2026/08/14 02:30:51 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/08/14 02:30:51 DEBUG : sub dir/hello world: Excluded 2026/08/14 02:30:51 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/08/14 02:30:51 DEBUG : sub dir/hello world2: Excluded 2026/08/14 02:30:51 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/08/14 02:30:51 DEBUG : sub dir/hello world: Excluded 2026/08/14 02:30:51 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/08/14 02:30:51 DEBUG : sub dir/hello world2: Excluded 2026/08/14 02:30:52 DEBUG : sub dir/ignore dir: Excluded 2026/08/14 02:30:52 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSorted (6.18s) === RUN TestListDirSortedFn run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" 2026/08/14 02:30:57 DEBUG : a.txt: Excluded (Size Filter) 2026/08/14 02:30:57 DEBUG : a.txt: Excluded 2026/08/14 02:30:57 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/08/14 02:30:57 DEBUG : sub dir/hello world: Excluded 2026/08/14 02:30:57 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/08/14 02:30:57 DEBUG : sub dir/hello world2: Excluded 2026/08/14 02:30:58 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/08/14 02:30:58 DEBUG : sub dir/hello world: Excluded 2026/08/14 02:30:58 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/08/14 02:30:58 DEBUG : sub dir/hello world2: Excluded 2026/08/14 02:30:58 DEBUG : sub dir/ignore dir: Excluded 2026/08/14 02:30:59 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSortedFn (5.96s) === RUN TestListJSON run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", 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 2026/08/14 02:31:03 ERROR : file1: Failed to read hash: hash type not supported === RUN TestListJSON/Metadata --- PASS: TestListJSON (3.11s) --- PASS: TestListJSON/Default (0.18s) --- PASS: TestListJSON/FilesOnly (0.20s) --- PASS: TestListJSON/DirsOnly (0.18s) --- PASS: TestListJSON/Recurse (0.18s) --- PASS: TestListJSON/SubDir (0.18s) --- PASS: TestListJSON/NoModTime (0.18s) --- PASS: TestListJSON/NoMimeType (0.18s) --- PASS: TestListJSON/ShowHash (0.19s) --- PASS: TestListJSON/HashTypes (0.18s) --- PASS: TestListJSON/Metadata (0.18s) === RUN TestStatJSON run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" === RUN TestStatJSON/Root === RUN TestStatJSON/RootFilesOnly === RUN TestStatJSON/RootDirsOnly === RUN TestStatJSON/Dir === RUN TestStatJSON/DirWithTrailingSlash === RUN TestStatJSON/File === RUN TestStatJSON/NotFound === RUN TestStatJSON/DirFilesOnly === RUN TestStatJSON/FileFilesOnly === RUN TestStatJSON/NotFoundFilesOnly === RUN TestStatJSON/DirDirsOnly === RUN TestStatJSON/FileDirsOnly === RUN TestStatJSON/NotFoundDirsOnly === RUN TestStatJSON/RootNotFound 2026/08/14 02:31:08 DEBUG : Creating backend with remote "TestB2:rclone-test-qiqequr8wunu/notfound" 2026/08/14 02:31:30 DEBUG : pacer: low level retry 1/10 (error Post "https://api001.backblazeb2.com/b2api/v1/b2_list_buckets": EOF) 2026/08/14 02:31:30 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/08/14 02:31:31 DEBUG : pacer: Reducing sleep to 10ms --- PASS: TestStatJSON (28.25s) --- PASS: TestStatJSON/Root (0.18s) --- PASS: TestStatJSON/RootFilesOnly (0.00s) --- PASS: TestStatJSON/RootDirsOnly (0.18s) --- PASS: TestStatJSON/Dir (0.72s) --- PASS: TestStatJSON/DirWithTrailingSlash (0.18s) --- PASS: TestStatJSON/File (0.18s) --- PASS: TestStatJSON/NotFound (0.54s) --- PASS: TestStatJSON/DirFilesOnly (0.18s) --- PASS: TestStatJSON/FileFilesOnly (0.18s) --- PASS: TestStatJSON/NotFoundFilesOnly (0.18s) --- PASS: TestStatJSON/DirDirsOnly (0.18s) --- PASS: TestStatJSON/FileDirsOnly (0.37s) --- PASS: TestStatJSON/NotFoundDirsOnly (0.36s) --- PASS: TestStatJSON/RootNotFound (23.16s) === RUN TestStatJSONMemory 2026/08/14 02:31:32 DEBUG : Creating backend with remote ":memory:" 2026/08/14 02:31:32 DEBUG : Memory root '': File to upload is small (5 bytes), uploading instead of streaming 2026/08/14 02:31:32 DEBUG : sub/file1: size = 5 OK 2026/08/14 02:31:32 DEBUG : sub/file1: md5 = 5d41402abc4b2a76b9719d911017c592 OK 2026/08/14 02:31:32 DEBUG : sub/file1: Size and md5 of src and dst objects identical === RUN TestStatJSONMemory/Dir === RUN TestStatJSONMemory/DirWithTrailingSlash === RUN TestStatJSONMemory/File === RUN TestStatJSONMemory/NotFound --- PASS: TestStatJSONMemory (0.00s) --- PASS: TestStatJSONMemory/Dir (0.00s) --- PASS: TestStatJSONMemory/DirWithTrailingSlash (0.00s) --- PASS: TestStatJSONMemory/File (0.00s) --- PASS: TestStatJSONMemory/NotFound (0.00s) === RUN TestMkdir run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" 2026/08/14 02:31:32 INFO : B2 bucket rclone-test-qiqequr8wunu: Making directory 2026/08/14 02:31:32 INFO : B2 bucket rclone-test-qiqequr8wunu: Making directory --- PASS: TestMkdir (0.55s) === RUN TestLsd run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" --- PASS: TestLsd (1.46s) === RUN TestLs run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" --- PASS: TestLs (1.57s) === RUN TestLsWithFilesFrom run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" 2026/08/14 02:31:36 DEBUG : empty space: Excluded (FilesFrom Filter) --- PASS: TestLsWithFilesFrom (2.14s) === RUN TestLsLong run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" --- PASS: TestLsLong (1.57s) === RUN TestHashSums run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" === RUN TestHashSums/Sha1 === RUN TestHashSums/Sha1Download --- PASS: TestHashSums (2.01s) --- PASS: TestHashSums/Sha1 (0.18s) --- PASS: TestHashSums/Sha1Download (0.38s) === RUN TestHashSumsWithErrors 2026/08/14 02:31:41 DEBUG : Creating backend with remote ":memory:" 2026/08/14 02:31:41 ERROR : sub/file1: hash unsupported: hash type not supported 2026/08/14 02:31:41 ERROR : file1: hash unsupported: hash type not supported 2026/08/14 02:31:41 ERROR : file1: hash unsupported: hash type not supported --- PASS: TestHashSumsWithErrors (0.00s) === RUN TestHashStream 2026/08/14 02:31:41 DEBUG : Creating md5 hash of 0 bytes read from input stream 2026/08/14 02:31:41 DEBUG : Creating md5 hash of 0 bytes read from input stream 2026/08/14 02:31:41 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2026/08/14 02:31:41 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2026/08/14 02:31:41 DEBUG : Creating md5 hash of 12 bytes read from input stream 2026/08/14 02:31:41 DEBUG : Creating md5 hash of 12 bytes read from input stream 2026/08/14 02:31:41 DEBUG : Creating sha1 hash of 12 bytes read from input stream 2026/08/14 02:31: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:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" --- PASS: TestCount (2.09s) === RUN TestDelete run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" 2026/08/14 02:31:44 DEBUG : Waiting for deletions to finish 2026/08/14 02:31:45 DEBUG : large: Excluded (Size Filter) 2026/08/14 02:31:45 INFO : medium: Deleted 2026/08/14 02:31:45 INFO : small: Deleted --- PASS: TestDelete (2.36s) === RUN TestMaxDelete run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" 2026/08/14 02:31:47 DEBUG : Waiting for deletions to finish 2026/08/14 02:31:47 ERROR : small: Got fatal error on delete: --max-delete threshold reached 2026/08/14 02:31:48 INFO : large: Deleted 2026/08/14 02:31:48 INFO : medium: Deleted --- PASS: TestMaxDelete (2.86s) === RUN TestMaxDeleteSizeLargeFile run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" 2026/08/14 02:31:50 DEBUG : Waiting for deletions to finish 2026/08/14 02:31:50 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached 2026/08/14 02:31:50 INFO : medium: Deleted 2026/08/14 02:31:50 INFO : small: Deleted --- PASS: TestMaxDeleteSizeLargeFile (2.81s) === RUN TestMaxDeleteSize run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" 2026/08/14 02:31:53 DEBUG : Waiting for deletions to finish 2026/08/14 02:31:53 ERROR : small: Got fatal error on delete: --max-delete-size threshold reached 2026/08/14 02:31:53 INFO : large: Deleted 2026/08/14 02:31:53 INFO : medium: Deleted --- PASS: TestMaxDeleteSize (2.85s) === RUN TestReadFile run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" --- PASS: TestReadFile (2.13s) === RUN TestRetry 2026/08/14 02:31:56 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 1/5 2026/08/14 02:31:56 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 2/5 2026/08/14 02:31:56 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2026/08/14 02:31:56 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2026/08/14 02:31:56 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2026/08/14 02:31:56 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2026/08/14 02:31:56 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms --- PASS: TestRetry (0.05s) === RUN TestCat run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" --- PASS: TestCat (4.09s) === RUN TestPurge 2026/08/14 02:32:01 DEBUG : Creating backend with remote "TestB2:rclone-test-zacozic2mayo" 2026/08/14 02:32:01 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/08/14 02:32:01 DEBUG : Creating backend with remote "/tmp/rclone1386540510" run.go:198: Remote "B2 bucket rclone-test-zacozic2mayo", Local "Local file system at /tmp/rclone1386540510", Modify Window "1ms" 2026/08/14 02:32:03 INFO : A2: Making directory 2026/08/14 02:32:03 INFO : A1/B2: Making directory 2026/08/14 02:32:03 INFO : A1/B2/C2: Making directory 2026/08/14 02:32:03 INFO : A1/B1/C3: Making directory 2026/08/14 02:32:03 INFO : A3: Making directory 2026/08/14 02:32:03 INFO : A3/B3: Making directory 2026/08/14 02:32:03 INFO : A3/B3/C4: Making directory fstest.go:250: Filtering empty directory "A2" fstest.go:250: Filtering empty directory "A1/B2" fstest.go:250: Filtering empty directory "A1/B2/C2" fstest.go:250: Filtering empty directory "A1/B1/C3" fstest.go:250: Filtering empty directory "A3" fstest.go:250: Filtering empty directory "A3/B3" fstest.go:250: Filtering empty directory "A3/B3/C4" 2026/08/14 02:32:03 INFO : B2 bucket rclone-test-zacozic2mayo: cleaning bucket "rclone-test-zacozic2mayo" of all files 2026/08/14 02:32:03 DEBUG : A1/B1/C1/one: Deleting (id "4_z1f22bd5310cdfbeb9ff80e1b_f413913db17d0e506_d20260814_m023202_c001_v0001173_t0015_u01786674722982") fstest.go:250: Filtering empty directory "A2" fstest.go:250: Filtering empty directory "A1/B2" fstest.go:250: Filtering empty directory "A1/B2/C2" fstest.go:250: Filtering empty directory "A3" fstest.go:250: Filtering empty directory "A3/B3" fstest.go:250: Filtering empty directory "A3/B3/C4" 2026/08/14 02:32:04 INFO : B2 bucket rclone-test-zacozic2mayo: cleaning bucket "rclone-test-zacozic2mayo" of all files 2026/08/14 02:32:04 DEBUG : A1/two: Deleting (id "4_z1f22bd5310cdfbeb9ff80e1b_f413913db17d0e508_d20260814_m023203_c001_v0001173_t0015_u01786674723167") 2026/08/14 02:32:04 DEBUG : B2 bucket rclone-test-zacozic2mayo: Purge remote 2026/08/14 02:32:04 INFO : B2 bucket rclone-test-zacozic2mayo: cleaning bucket "rclone-test-zacozic2mayo" of all files 2026/08/14 02:32:05 NOTICE: purge failed: directory not found --- PASS: TestPurge (4.15s) === RUN TestRmdirsNoLeaveRoot run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" 2026/08/14 02:32:05 INFO : A2: Making directory 2026/08/14 02:32:05 INFO : A1/B2: Making directory 2026/08/14 02:32:05 INFO : A1/B2/C2: Making directory 2026/08/14 02:32:05 INFO : A1/B1/C3: Making directory 2026/08/14 02:32:05 INFO : A3: Making directory 2026/08/14 02:32:05 INFO : A3/B3: Making directory 2026/08/14 02:32:05 INFO : A3/B3/C4: Making directory fstest.go:250: Filtering empty directory "A2" fstest.go:250: Filtering empty directory "A1/B2" fstest.go:250: Filtering empty directory "A1/B2/C2" fstest.go:250: Filtering empty directory "A1/B1/C3" fstest.go:250: Filtering empty directory "A3" fstest.go:250: Filtering empty directory "A3/B3" fstest.go:250: Filtering empty directory "A3/B3/C4" 2026/08/14 02:32:06 DEBUG : removing 1 level 3 directories 2026/08/14 02:32:06 INFO : A3/B3/C4: Removing directory fstest.go:250: Filtering empty directory "A2" fstest.go:250: Filtering empty directory "A1/B2" fstest.go:250: Filtering empty directory "A1/B2/C2" fstest.go:250: Filtering empty directory "A1/B1/C3" fstest.go:250: Filtering empty directory "A3" fstest.go:250: Filtering empty directory "A3/B3" 2026/08/14 02:32:08 DEBUG : removing 1 level 0 directories 2026/08/14 02:32:08 INFO : B2 bucket rclone-test-qiqequr8wunu: Removing directory 2026/08/14 02:32:08 ERROR : Failed to rmdir: failed to delete bucket: Cannot delete non-empty bucket (400 cannot_delete_non_empty_bucket) operations_test.go:729: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:729 Error: Received unexpected error: failed to remove directories: failed to delete bucket: Cannot delete non-empty bucket (400 cannot_delete_non_empty_bucket) Test: TestRmdirsNoLeaveRoot 2026/08/14 02:32:29 DEBUG : pacer: low level retry 1/10 (error Post "https://api001.backblazeb2.com/b2api/v1/b2_list_file_names": EOF) 2026/08/14 02:32:29 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/08/14 02:32:29 DEBUG : pacer: Reducing sleep to 10ms --- FAIL: TestRmdirsNoLeaveRoot (24.83s) === RUN TestRmdirsLeaveRoot run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" 2026/08/14 02:32:30 INFO : A1: Making directory 2026/08/14 02:32:30 INFO : A1/B1: Making directory 2026/08/14 02:32:30 INFO : A1/B1/C1: Making directory fstest.go:250: Filtering empty directory "A1" fstest.go:250: Filtering empty directory "A1/B1" fstest.go:250: Filtering empty directory "A1/B1/C1" fstest.go:250: Filtering empty directory "A1" --- PASS: TestRmdirsLeaveRoot (0.96s) === RUN TestRmdirsWithFilter run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" 2026/08/14 02:32:30 INFO : A1: Making directory 2026/08/14 02:32:30 INFO : A1/B1: Making directory 2026/08/14 02:32:30 INFO : A1/B1/C1: Making directory fstest.go:250: Filtering empty directory "A1" fstest.go:250: Filtering empty directory "A1/B1" fstest.go:250: Filtering empty directory "A1/B1/C1" fstest.go:250: Filtering empty directory "A1" --- PASS: TestRmdirsWithFilter (0.92s) === RUN TestCopyURL run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" 2026/08/14 02:32:32 DEBUG : file1: size = 14 OK 2026/08/14 02:32:32 DEBUG : file1: sha1 = 3c3771c1f3304bdb052b101cc97a23df1ab9531f OK 2026/08/14 02:32:33 DEBUG : filename.txt: File name found in url 2026/08/14 02:32:33 DEBUG : filename.txt: size = 14 OK 2026/08/14 02:32:33 DEBUG : filename.txt: sha1 = 3c3771c1f3304bdb052b101cc97a23df1ab9531f OK 2026/08/14 02:32:33 DEBUG : headerfilename.txt: filename found in Content-Disposition header. 2026/08/14 02:32:33 DEBUG : headerfilename.txt: size = 14 OK 2026/08/14 02:32:33 DEBUG : headerfilename.txt: sha1 = 3c3771c1f3304bdb052b101cc97a23df1ab9531f OK 2026/08/14 02:32:33 DEBUG : file2: size = 14 OK 2026/08/14 02:32:33 DEBUG : file2: sha1 = 3c3771c1f3304bdb052b101cc97a23df1ab9531f OK --- PASS: TestCopyURL (3.33s) === RUN TestCopyURLDownloadHeaders run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" 2026/08/14 02:32:35 DEBUG : file1: size = 14 OK 2026/08/14 02:32:35 DEBUG : file1: sha1 = 3c3771c1f3304bdb052b101cc97a23df1ab9531f OK --- PASS: TestCopyURLDownloadHeaders (0.73s) === RUN TestCopyURLToWriter --- PASS: TestCopyURLToWriter (0.00s) === RUN TestMoveFile run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" 2026/08/14 02:32:36 DEBUG : file1: Need to transfer - File not found at Destination 2026/08/14 02:32:36 DEBUG : sub/file2: size = 14 OK 2026/08/14 02:32:36 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2026/08/14 02:32:36 INFO : file1: Copied (new) to: sub/file2 2026/08/14 02:32:36 INFO : file1: Deleted 2026/08/14 02:32:36 DEBUG : sub/file2: size = 14 OK 2026/08/14 02:32:36 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/08/14 02:32:36 DEBUG : file1: Unchanged skipping 2026/08/14 02:32:36 INFO : file1: Deleted 2026/08/14 02:32:36 DEBUG : B2 bucket rclone-test-qiqequr8wunu: don't need to copy/move sub/file2, it is already at target location --- PASS: TestMoveFile (1.71s) === RUN TestMoveFileWithIgnoreExisting run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" 2026/08/14 02:32:37 DEBUG : file1: Need to transfer - File not found at Destination 2026/08/14 02:32:38 DEBUG : file1: size = 14 OK 2026/08/14 02:32:38 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2026/08/14 02:32:38 INFO : file1: Copied (new) 2026/08/14 02:32:38 INFO : file1: Deleted 2026/08/14 02:32:38 DEBUG : file1: Destination exists, skipping 2026/08/14 02:32:38 DEBUG : file1: Not removing source file as destination file exists and --ignore-existing is set --- PASS: TestMoveFileWithIgnoreExisting (1.46s) === RUN TestCaseInsensitiveMoveFile run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFile (0.41s) === RUN TestCaseInsensitiveMoveFileDryRun run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFileDryRun (0.37s) === RUN TestMoveFileBackupDir run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" 2026/08/14 02:32:40 DEBUG : Creating backend with remote "TestB2:rclone-test-qiqequr8wunu/backup" 2026/08/14 02:32:40 DEBUG : Config file has changed externally - reloading 2026/08/14 02:32:41 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone2108479853) 2026/08/14 02:32:41 DEBUG : dst/file1: size = 18 (B2 bucket rclone-test-qiqequr8wunu) 2026/08/14 02:32:41 DEBUG : dst/file1: Sizes differ 2026/08/14 02:32:43 DEBUG : dst/file1: size = 18 OK 2026/08/14 02:32:43 DEBUG : dst/file1: sha1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK 2026/08/14 02:32:43 INFO : dst/file1: Copied (server-side copy) 2026/08/14 02:32:43 INFO : dst/file1: Deleted 2026/08/14 02:32:43 DEBUG : dst/file1: size = 14 OK 2026/08/14 02:32:43 DEBUG : dst/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2026/08/14 02:32:43 INFO : dst/file1: Copied (new) 2026/08/14 02:32:43 INFO : dst/file1: Deleted --- PASS: TestMoveFileBackupDir (4.48s) === RUN TestSameConfig --- PASS: TestSameConfig (0.00s) === RUN TestSame --- PASS: TestSame (0.00s) === RUN TestOverlappingFilterCheckWithoutFilter --- PASS: TestOverlappingFilterCheckWithoutFilter (0.00s) === RUN TestOverlappingFilterCheckWithFilter --- PASS: TestOverlappingFilterCheckWithFilter (0.00s) === RUN TestListFormat --- PASS: TestListFormat (0.00s) === RUN TestDirMove run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" 2026/08/14 02:32:45 INFO : A1/B2: Making directory 2026/08/14 02:32:45 INFO : A1/B1/C3: Making directory fstest.go:250: Filtering empty directory "A1/B2" fstest.go:250: Filtering empty directory "A1/B1/C3" 2026/08/14 02:32:46 DEBUG : A2/B1/C2/five: size = 4 OK 2026/08/14 02:32:46 DEBUG : A1/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2026/08/14 02:32:46 INFO : A1/B1/C2/five: Copied (server-side copy) to: A2/B1/C2/five 2026/08/14 02:32:46 DEBUG : A2/B1/three: size = 5 OK 2026/08/14 02:32:46 DEBUG : A1/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2026/08/14 02:32:46 INFO : A1/B1/three: Copied (server-side copy) to: A2/B1/three 2026/08/14 02:32:46 DEBUG : A2/one: size = 3 OK 2026/08/14 02:32:46 DEBUG : A1/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2026/08/14 02:32:46 INFO : A1/one: Copied (server-side copy) to: A2/one 2026/08/14 02:32:46 INFO : A1/B1/C2/five: Deleted 2026/08/14 02:32:46 INFO : A1/B1/three: Deleted 2026/08/14 02:32:46 DEBUG : A2/two: size = 3 OK 2026/08/14 02:32:46 DEBUG : A1/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2026/08/14 02:32:46 INFO : A1/two: Copied (server-side copy) to: A2/two 2026/08/14 02:32:46 DEBUG : A2/B1/C1/four: size = 4 OK 2026/08/14 02:32:46 DEBUG : A1/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2026/08/14 02:32:46 INFO : A1/B1/C1/four: Copied (server-side copy) to: A2/B1/C1/four 2026/08/14 02:32:46 INFO : A1/one: Deleted 2026/08/14 02:32:47 INFO : A1/two: Deleted 2026/08/14 02:32:47 INFO : A1/B1/C1/four: Deleted fstest.go:250: Filtering empty directory "A2/B2" fstest.go:250: Filtering empty directory "A2/B1/C3" 2026/08/14 02:32:47 DEBUG : A3/B1/C2/five: size = 4 OK 2026/08/14 02:32:47 DEBUG : A2/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2026/08/14 02:32:47 INFO : A2/B1/C2/five: Copied (server-side copy) to: A3/B1/C2/five 2026/08/14 02:32:47 DEBUG : A3/one: size = 3 OK 2026/08/14 02:32:47 DEBUG : A2/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2026/08/14 02:32:47 INFO : A2/one: Copied (server-side copy) to: A3/one 2026/08/14 02:32:47 DEBUG : A3/B1/three: size = 5 OK 2026/08/14 02:32:47 DEBUG : A2/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2026/08/14 02:32:47 INFO : A2/B1/three: Copied (server-side copy) to: A3/B1/three 2026/08/14 02:32:47 DEBUG : A3/B1/C1/four: size = 4 OK 2026/08/14 02:32:47 DEBUG : A2/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2026/08/14 02:32:47 INFO : A2/B1/C1/four: Copied (server-side copy) to: A3/B1/C1/four 2026/08/14 02:32:47 DEBUG : A3/two: size = 3 OK 2026/08/14 02:32:47 DEBUG : A2/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2026/08/14 02:32:47 INFO : A2/two: Copied (server-side copy) to: A3/two 2026/08/14 02:32:48 INFO : A2/B1/C2/five: Deleted 2026/08/14 02:32:48 INFO : A2/one: Deleted 2026/08/14 02:32:48 INFO : A2/B1/three: Deleted 2026/08/14 02:32:48 INFO : A2/B1/C1/four: Deleted 2026/08/14 02:32:48 INFO : A2/two: Deleted fstest.go:250: Filtering empty directory "A3/B2" fstest.go:250: Filtering empty directory "A3/B1/C3" 2026/08/14 02:32:48 INFO : B2 bucket rclone-test-qiqequr8wunu: Can't DirMove - falling back to file moves: can't move directory - incompatible remotes 2026/08/14 02:32:48 DEBUG : A4/B1/C1/four: size = 4 OK 2026/08/14 02:32:48 DEBUG : A3/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2026/08/14 02:32:48 INFO : A3/B1/C1/four: Copied (server-side copy) to: A4/B1/C1/four 2026/08/14 02:32:48 DEBUG : A4/two: size = 3 OK 2026/08/14 02:32:48 DEBUG : A3/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2026/08/14 02:32:48 INFO : A3/two: Copied (server-side copy) to: A4/two 2026/08/14 02:32:48 DEBUG : A4/B1/three: size = 5 OK 2026/08/14 02:32:48 DEBUG : A3/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2026/08/14 02:32:48 INFO : A3/B1/three: Copied (server-side copy) to: A4/B1/three 2026/08/14 02:32:48 DEBUG : A4/B1/C2/five: size = 4 OK 2026/08/14 02:32:48 DEBUG : A3/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2026/08/14 02:32:48 INFO : A3/B1/C2/five: Copied (server-side copy) to: A4/B1/C2/five 2026/08/14 02:32:48 DEBUG : A4/one: size = 3 OK 2026/08/14 02:32:48 DEBUG : A3/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2026/08/14 02:32:48 INFO : A3/one: Copied (server-side copy) to: A4/one 2026/08/14 02:32:49 INFO : A3/B1/C1/four: Deleted 2026/08/14 02:32:49 INFO : A3/two: Deleted 2026/08/14 02:32:49 INFO : A3/B1/three: Deleted 2026/08/14 02:32:49 INFO : A3/B1/C2/five: Deleted 2026/08/14 02:32:49 INFO : A3/one: Deleted fstest.go:250: Filtering empty directory "A4/B2" fstest.go:250: Filtering empty directory "A4/B1/C3" --- PASS: TestDirMove (6.23s) === RUN TestGetFsInfo run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" --- PASS: TestGetFsInfo (0.37s) === RUN TestRcat === RUN TestRcat/withChecksum=false,ignoreChecksum=false run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" 2026/08/14 02:32:51 DEBUG : B2 bucket rclone-test-qiqequr8wunu: File to upload is small (34 bytes), uploading instead of streaming 2026/08/14 02:32:52 DEBUG : no_checksum_small_file_from_pipe: size = 34 OK 2026/08/14 02:32:52 DEBUG : no_checksum_small_file_from_pipe: sha1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK 2026/08/14 02:32:52 DEBUG : no_checksum_small_file_from_pipe: Size and sha1 of src and dst objects identical 2026/08/14 02:32:52 DEBUG : no_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2026/08/14 02:32:53 DEBUG : no_checksum_big_file_from_pipe: size = 102401 OK 2026/08/14 02:32:53 DEBUG : no_checksum_big_file_from_pipe: sha1 = d0080f73aa8b494570ad63df1beb25c01ea9eea2 OK 2026/08/14 02:32:53 DEBUG : no_checksum_big_file_from_pipe: Size and sha1 of src and dst objects identical === RUN TestRcat/withChecksum=true,ignoreChecksum=false run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" 2026/08/14 02:32:54 DEBUG : B2 bucket rclone-test-qiqequr8wunu: File to upload is small (34 bytes), uploading instead of streaming 2026/08/14 02:32:54 DEBUG : with_checksum_small_file_from_pipe: size = 34 OK 2026/08/14 02:32:54 DEBUG : with_checksum_small_file_from_pipe: sha1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK 2026/08/14 02:32:54 DEBUG : with_checksum_small_file_from_pipe: Size and sha1 of src and dst objects identical 2026/08/14 02:32:54 DEBUG : with_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2026/08/14 02:32:55 DEBUG : with_checksum_big_file_from_pipe: size = 102401 OK 2026/08/14 02:32:55 DEBUG : with_checksum_big_file_from_pipe: sha1 = d0080f73aa8b494570ad63df1beb25c01ea9eea2 OK 2026/08/14 02:32:55 DEBUG : with_checksum_big_file_from_pipe: Size and sha1 of src and dst objects identical === RUN TestRcat/withChecksum=false,ignoreChecksum=true run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" 2026/08/14 02:32:57 DEBUG : B2 bucket rclone-test-qiqequr8wunu: File to upload is small (34 bytes), uploading instead of streaming 2026/08/14 02:32:57 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK 2026/08/14 02:32:57 DEBUG : ignore_checksum_small_file_from_pipe: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/08/14 02:32:57 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2026/08/14 02:32:58 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK 2026/08/14 02:32:58 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 run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" 2026/08/14 02:32:59 DEBUG : B2 bucket rclone-test-qiqequr8wunu: File to upload is small (34 bytes), uploading instead of streaming 2026/08/14 02:32:59 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK 2026/08/14 02:32:59 DEBUG : ignore_checksum_small_file_from_pipe: Src hash empty - aborting Dst hash check 2026/08/14 02:32:59 DEBUG : ignore_checksum_small_file_from_pipe: Size of src and dst objects identical 2026/08/14 02:32:59 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2026/08/14 02:33:00 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK 2026/08/14 02:33:00 DEBUG : ignore_checksum_big_file_from_pipe: Src hash empty - aborting Dst hash check 2026/08/14 02:33:00 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical --- PASS: TestRcat (10.93s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (3.19s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (2.63s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (2.65s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (2.45s) === RUN TestRcatMetadata run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" operations_test.go:1564: Skipping as destination doesn't support user metadata --- SKIP: TestRcatMetadata (0.37s) === RUN TestRcatSize run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" 2026/08/14 02:33:02 DEBUG : potato1: size = 60 OK 2026/08/14 02:33:02 DEBUG : potato1: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2026/08/14 02:33:02 DEBUG : B2 bucket rclone-test-qiqequr8wunu: File to upload is small (60 bytes), uploading instead of streaming 2026/08/14 02:33:02 DEBUG : potato2: size = 60 OK 2026/08/14 02:33:02 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2026/08/14 02:33:02 DEBUG : potato2: Size and sha1 of src and dst objects identical --- PASS: TestRcatSize (1.95s) === RUN TestRcatSizeShortEOF run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" 2026/08/14 02:33:04 DEBUG : potato1: Clearing upload URL because of error: Post "https://pod-000-1161-18.backblaze.com/b2api/v1/b2_upload_file/bfa25da3108dfbeb9ff80e1b/c001_v0001161_t0021": net/http: HTTP/1.x transport connection broken: http: ContentLength=160 with Body length 100 2026/08/14 02:33:04 DEBUG : pacer: low level retry 1/1 (error Post "https://pod-000-1161-18.backblaze.com/b2api/v1/b2_upload_file/bfa25da3108dfbeb9ff80e1b/c001_v0001161_t0021": net/http: HTTP/1.x transport connection broken: http: ContentLength=160 with Body length 100) 2026/08/14 02:33:04 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/08/14 02:33:04 ERROR : potato1: Post request put error: Post "https://pod-000-1161-18.backblaze.com/b2api/v1/b2_upload_file/bfa25da3108dfbeb9ff80e1b/c001_v0001161_t0021": net/http: HTTP/1.x transport connection broken: http: ContentLength=160 with Body length 100 2026/08/14 02:33:04 DEBUG : pacer: Reducing sleep to 10ms --- PASS: TestRcatSizeShortEOF (0.38s) === RUN TestRcatSizeMetadata run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" operations_test.go:1657: Skipping as destination doesn't support user metadata --- SKIP: TestRcatSizeMetadata (0.37s) === RUN TestRcatSizeUploadHeaders run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" 2026/08/14 02:33:05 DEBUG : potato1: size = 60 OK 2026/08/14 02:33:05 DEBUG : potato1: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK --- PASS: TestRcatSizeUploadHeaders (1.28s) === RUN TestRcatSizeChecksum === RUN TestRcatSizeChecksum/Corrupted run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" 2026/08/14 02:33:06 DEBUG : potato1: size = 60 OK 2026/08/14 02:33:06 DEBUG : potato1: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d (B2 bucket rclone-test-qiqequr8wunu) 2026/08/14 02:33:06 DEBUG : potato1: sha1 = 3639923d60907789b4155daea94152f89e4fb69c (B2 bucket rclone-test-qiqequr8wunu) 2026/08/14 02:33:06 ERROR : potato1: corrupted on transfer: sha1 hashes differ src "9dc7f7d3279715991a22853f5981df582b7f9f6d" vs dst(B2 bucket rclone-test-qiqequr8wunu) "3639923d60907789b4155daea94152f89e4fb69c" 2026/08/14 02:33:06 INFO : potato1: Removing failed copy === RUN TestRcatSizeChecksum/SizeDiffers run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" 2026/08/14 02:33:07 DEBUG : potato4: size = 60 (B2 bucket rclone-test-qiqequr8wunu) 2026/08/14 02:33:07 DEBUG : potato4: size = 59 (B2 bucket rclone-test-qiqequr8wunu) 2026/08/14 02:33:07 ERROR : potato4: corrupted on transfer: sizes differ src 60 vs dst(B2 bucket rclone-test-qiqequr8wunu) 59 2026/08/14 02:33:07 INFO : potato4: Removing failed copy === RUN TestRcatSizeChecksum/IgnoreChecksum run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" 2026/08/14 02:33:08 DEBUG : potato2: size = 60 OK === RUN TestRcatSizeChecksum/NoHashes run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" 2026/08/14 02:33:09 DEBUG : potato3: size = 60 OK --- PASS: TestRcatSizeChecksum (3.32s) --- PASS: TestRcatSizeChecksum/Corrupted (0.91s) --- PASS: TestRcatSizeChecksum/SizeDiffers (0.92s) --- PASS: TestRcatSizeChecksum/IgnoreChecksum (0.73s) --- PASS: TestRcatSizeChecksum/NoHashes (0.77s) === RUN TestTouchDir run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" 2026/08/14 02:33:10 DEBUG : B2 bucket rclone-test-qiqequr8wunu: Touching "sub dir/potato3" 2026/08/14 02:33:10 DEBUG : B2 bucket rclone-test-qiqequr8wunu: Touching "empty space" 2026/08/14 02:33:10 DEBUG : B2 bucket rclone-test-qiqequr8wunu: Touching "potato2" --- PASS: TestTouchDir (3.29s) === RUN TestMkdirMetadata run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" operations_test.go:1877: Skipping test as remote does not support MkdirMetadata --- SKIP: TestMkdirMetadata (0.37s) === RUN TestMkdirModTime run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" operations_test.go:1896: Skipping test as remote does not support DirSetModTime or MkdirMetadata --- SKIP: TestMkdirModTime (0.37s) === RUN TestCopyDirMetadata run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" operations_test.go:1917: Skipping test as remote does not support WriteDirMetadata or MkdirMetadata --- SKIP: TestCopyDirMetadata (0.42s) === RUN TestSetDirModTime run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" operations_test.go:1953: Skipping test as remote does not support DirSetModTime or WriteDirSetModTime --- SKIP: TestSetDirModTime (0.37s) === RUN TestDirsEqual run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" operations_test.go:1997: Skipping test as remote does not support WriteDirMetadata or MkdirMetadata --- SKIP: TestDirsEqual (0.37s) === RUN TestRemoveExisting run.go:198: Remote "B2 bucket rclone-test-qiqequr8wunu", Local "Local file system at /tmp/rclone2108479853", Modify Window "1ms" operations_test.go:2066: Skipping as remote can't Move --- SKIP: TestRemoveExisting (0.37s) === RUN TestRcAbout rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcAbout (0.00s) === RUN TestRcCleanup rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcCleanup (0.00s) === RUN TestRcCopyfile rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcCopyfile (0.00s) === RUN TestRcCopyurl rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcCopyurl (0.00s) === RUN TestRcDelete rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcDelete (0.00s) === RUN TestRcDeletefile rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcDeletefile (0.00s) === RUN TestRcList rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcList (0.00s) === RUN TestRcStat rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcStat (0.00s) === RUN TestRcSetTier rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcSetTier (0.00s) === RUN TestRcSetTierFile rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcSetTierFile (0.00s) === RUN TestRcMkdir rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcMkdir (0.00s) === RUN TestRcMovefile rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcMovefile (0.00s) === RUN TestRcPurge rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcPurge (0.00s) === RUN TestRcRmdir rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcRmdir (0.00s) === RUN TestRcRmdirs rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcRmdirs (0.00s) === RUN TestRcSize rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcSize (0.00s) === RUN TestRcPublicLink rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcPublicLink (0.00s) === RUN TestRcFsInfo rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcFsInfo (0.00s) === RUN TestUploadFile rc_test.go:30: Skipping test on non local remote --- SKIP: TestUploadFile (0.00s) === RUN TestRcCommand rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcCommand (0.00s) === RUN TestRcDu rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcDu (0.00s) === RUN TestRcCheck rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcCheck (0.00s) === RUN TestRcHashsum rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcHashsum (0.00s) === RUN TestRcHashsumSingleFile rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcHashsumSingleFile (0.00s) === RUN TestRcHashsumFile rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcHashsumFile (0.00s) FAIL 2026/08/14 02:33:15 DEBUG : B2 bucket rclone-test-qiqequr8wunu: Purge remote 2026/08/14 02:33:15 INFO : B2 bucket rclone-test-qiqequr8wunu: cleaning bucket "rclone-test-qiqequr8wunu" of all files 2026/08/14 02:33:15 DEBUG : A1/B1/C1/four: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f42640cede50cf6d3_d20260814_m023246_c001_v7007000_t0000_u01786674766941") 2026/08/14 02:33:15 DEBUG : A1/B1/C1/four: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc324b_d20260814_m023245_c001_v0001161_t0021_u01786674765030") 2026/08/14 02:33:15 DEBUG : A1/B1/C1/one: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418854082532ee52_d20260814_m023207_c001_v7007000_t0000_u01786674727466") 2026/08/14 02:33:15 DEBUG : A1/B1/C1/one: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc31c4_d20260814_m023205_c001_v0001161_t0021_u01786674725645") 2026/08/14 02:33:15 DEBUG : A1/B1/C2/five: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f40122dc1719afb56_d20260814_m023246_c001_v7007000_t0000_u01786674766740") 2026/08/14 02:33:15 DEBUG : A1/B1/C2/five: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc324d_d20260814_m023245_c001_v0001161_t0021_u01786674765214") 2026/08/14 02:33:15 DEBUG : A1/B1/three: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f408f8b86196d892b_d20260814_m023246_c001_v7007000_t0000_u01786674766741") 2026/08/14 02:33:15 DEBUG : A1/B1/three: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc3249_d20260814_m023244_c001_v0001161_t0021_u01786674764847") 2026/08/14 02:33:15 DEBUG : A1/one: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f417ee634609e9863_d20260814_m023246_c001_v7007000_t0000_u01786674766903") 2026/08/14 02:33:16 DEBUG : A1/one: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc3245_d20260814_m023244_c001_v0001161_t0021_u01786674764481") 2026/08/14 02:33:16 DEBUG : A1/two: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f4085766727b66615_d20260814_m023246_c001_v7007000_t0000_u01786674766930") 2026/08/14 02:33:16 DEBUG : A1/two: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc3247_d20260814_m023244_c001_v0001161_t0021_u01786674764664") 2026/08/14 02:33:16 DEBUG : A1/two: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418854082532ee58_d20260814_m023207_c001_v7007000_t0000_u01786674727827") 2026/08/14 02:33:16 DEBUG : A1/two: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc31c6_d20260814_m023205_c001_v0001161_t0021_u01786674725831") 2026/08/14 02:33:16 DEBUG : A2/B1/C1/four: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f4085766727b6661e_d20260814_m023247_c001_v7007000_t0000_u01786674767994") 2026/08/14 02:33:16 DEBUG : A2/B1/C1/four: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f407c917582abd6f0_d20260814_m023246_c001_v0001091_t0017_u01786674766748") 2026/08/14 02:33:16 DEBUG : A2/B1/C2/five: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f408f8b86196d892c_d20260814_m023247_c001_v7007000_t0000_u01786674767962") 2026/08/14 02:33:16 DEBUG : A2/B1/C2/five: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f4196ca41a07c96a4_d20260814_m023246_c001_v0001179_t0007_u01786674766346") 2026/08/14 02:33:16 DEBUG : A2/B1/three: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f417ee634609e9872_d20260814_m023247_c001_v7007000_t0000_u01786674767983") 2026/08/14 02:33:16 DEBUG : A2/B1/three: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f417a51e8a58bf916_d20260814_m023246_c001_v0001174_t0033_u01786674766554") 2026/08/14 02:33:16 DEBUG : A2/one: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f40122dc1719afb58_d20260814_m023247_c001_v7007000_t0000_u01786674767974") 2026/08/14 02:33:16 DEBUG : A2/one: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f414eeb39a0f18c6c_d20260814_m023246_c001_v0001029_t0034_u01786674766720") 2026/08/14 02:33:16 DEBUG : A2/two: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f42640cede50cf6d6_d20260814_m023248_c001_v7007000_t0000_u01786674768005") 2026/08/14 02:33:16 DEBUG : A2/two: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f4097dc7c3ec32fac_d20260814_m023246_c001_v0001174_t0039_u01786674766741") 2026/08/14 02:33:16 DEBUG : A3/B1/C1/four: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f42640cede50cf6da_d20260814_m023248_c001_v7007000_t0000_u01786674768991") 2026/08/14 02:33:16 DEBUG : A3/B1/C1/four: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f419b22748dc6324a_d20260814_m023247_c001_v0001146_t0044_u01786674767787") 2026/08/14 02:33:16 DEBUG : A3/B1/C2/five: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f40122dc1719afb5a_d20260814_m023249_c001_v7007000_t0000_u01786674769040") 2026/08/14 02:33:16 DEBUG : A3/B1/C2/five: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f40373b706d8a0f0b_d20260814_m023247_c001_v0001037_t0017_u01786674767778") 2026/08/14 02:33:16 DEBUG : A3/B1/three: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f417ee634609e9874_d20260814_m023249_c001_v7007000_t0000_u01786674769014") 2026/08/14 02:33:17 DEBUG : A3/B1/three: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f4126f9c5870f8e28_d20260814_m023247_c001_v0001178_t0038_u01786674767781") 2026/08/14 02:33:17 DEBUG : A3/one: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f408f8b86196d892d_d20260814_m023249_c001_v7007000_t0000_u01786674769050") 2026/08/14 02:33:17 DEBUG : A3/one: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f40250a3d34dc6fd7_d20260814_m023247_c001_v0001172_t0044_u01786674767782") 2026/08/14 02:33:17 DEBUG : A3/two: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f4085766727b66628_d20260814_m023249_c001_v7007000_t0000_u01786674769004") 2026/08/14 02:33:17 DEBUG : A3/two: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f403882885c2b432e_d20260814_m023247_c001_v0001113_t0018_u01786674767784") 2026/08/14 02:33:17 DEBUG : A4/B1/C1/four: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f408f8b86196d892e_d20260814_m023249_c001_v7007000_t0000_u01786674769612") 2026/08/14 02:33:17 DEBUG : A4/B1/C1/four: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f40141557aa7f5d7f_d20260814_m023248_c001_v0001181_t0059_u01786674768805") 2026/08/14 02:33:17 DEBUG : A4/B1/C2/five: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f408f8b86196d892f_d20260814_m023249_c001_v7007000_t0000_u01786674769794") 2026/08/14 02:33:17 DEBUG : A4/B1/C2/five: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f4105463495fd0b93_d20260814_m023248_c001_v0001090_t0048_u01786674768853") 2026/08/14 02:33:17 DEBUG : A4/B1/three: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f408f8b86196d8930_d20260814_m023249_c001_v7007000_t0000_u01786674769977") 2026/08/14 02:33:17 DEBUG : A4/B1/three: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f4071b8687392e32b_d20260814_m023248_c001_v0001136_t0049_u01786674768830") 2026/08/14 02:33:17 DEBUG : A4/one: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f408f8b86196d8931_d20260814_m023250_c001_v7007000_t0000_u01786674770159") 2026/08/14 02:33:17 DEBUG : A4/one: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f403483c99886997a_d20260814_m023248_c001_v0001101_t0052_u01786674768855") 2026/08/14 02:33:17 DEBUG : A4/two: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f408f8b86196d8932_d20260814_m023250_c001_v7007000_t0000_u01786674770341") 2026/08/14 02:33:17 DEBUG : A4/two: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f41502dfc05191706_d20260814_m023248_c001_v0001102_t0026_u01786674768819") 2026/08/14 02:33:17 DEBUG : BackupDir/one: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f417ee634609e903e_d20260814_m023035_c001_v7007000_t0000_u01786674635176") 2026/08/14 02:33:17 DEBUG : BackupDir/one: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f41852623ee3c4274_d20260814_m023030_c001_v0001183_t0046_u01786674630785") 2026/08/14 02:33:17 DEBUG : CompareDest/one: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f417ee634609e8c33_d20260814_m023020_c001_v7007000_t0000_u01786674620337") 2026/08/14 02:33:17 DEBUG : CompareDest/one: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc2f85_d20260814_m023016_c001_v0001161_t0021_u01786674616836") 2026/08/14 02:33:17 DEBUG : CompareDest/two: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f417ee634609e8c35_d20260814_m023020_c001_v7007000_t0000_u01786674620519") 2026/08/14 02:33:18 DEBUG : CompareDest/two: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc2f8d_d20260814_m023017_c001_v0001161_t0021_u01786674617746") 2026/08/14 02:33:18 DEBUG : CopyDest/one: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f417ee634609e903f_d20260814_m023035_c001_v7007000_t0000_u01786674635358") 2026/08/14 02:33:18 DEBUG : CopyDest/one: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc2faf_d20260814_m023027_c001_v0001161_t0021_u01786674627507") 2026/08/14 02:33:18 DEBUG : CopyDest/three: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f417ee634609e9040_d20260814_m023035_c001_v7007000_t0000_u01786674635541") 2026/08/14 02:33:18 DEBUG : CopyDest/three: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc2fc8_d20260814_m023033_c001_v0001161_t0021_u01786674633510") 2026/08/14 02:33:18 DEBUG : CopyDest/two: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f417ee634609e9041_d20260814_m023035_c001_v7007000_t0000_u01786674635723") 2026/08/14 02:33:18 DEBUG : CopyDest/two: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc2fbf_d20260814_m023031_c001_v0001161_t0021_u01786674631939") 2026/08/14 02:33:18 DEBUG : ReadFile: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418854082532ee34_d20260814_m023156_c001_v7007000_t0000_u01786674716248") 2026/08/14 02:33:18 DEBUG : ReadFile: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc3180_d20260814_m023154_c001_v0001161_t0021_u01786674714861") 2026/08/14 02:33:18 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f417ee634609e9054_d20260814_m023041_c001_v7007000_t0000_u01786674641443") 2026/08/14 02:33:18 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f118b6c5b8ddc2fdc_d20260814_m023038_c001_v0001161_t0034_u01786674638279") 2026/08/14 02:33:18 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f417ee634609e9055_d20260814_m023041_c001_v7007000_t0000_u01786674641625") 2026/08/14 02:33:18 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f118b6c5b8ddc2fe6_d20260814_m023040_c001_v0001161_t0007_u01786674640150") 2026/08/14 02:33:18 DEBUG : a.txt: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f42640cede50cf58a_d20260814_m023059_c001_v7007000_t0000_u01786674659777") 2026/08/14 02:33:18 DEBUG : a.txt: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc3034_d20260814_m023055_c001_v0001161_t0021_u01786674655275") 2026/08/14 02:33:18 DEBUG : a.txt: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f42640cede50cf555_d20260814_m023053_c001_v7007000_t0000_u01786674653796") 2026/08/14 02:33:18 DEBUG : a.txt: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc3008_d20260814_m023049_c001_v0001161_t0021_u01786674649096") 2026/08/14 02:33:18 DEBUG : also/one: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f417ee634609e9059_d20260814_m023046_c001_v7007000_t0000_u01786674646093") 2026/08/14 02:33:18 DEBUG : also/one: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f118b6c5b8ddc2ff4_d20260814_m023044_c001_v0001161_t0057_u01786674644310") 2026/08/14 02:33:18 DEBUG : another: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f417ee634609e905a_d20260814_m023046_c001_v7007000_t0000_u01786674646642") 2026/08/14 02:33:18 DEBUG : another: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f118b6c5b8ddc2ff6_d20260814_m023044_c001_v0001161_t0015_u01786674644831") 2026/08/14 02:33:19 DEBUG : backup/dst/file1: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f408f8b86196d8929_d20260814_m023243_c001_v7007000_t0000_u01786674763904") 2026/08/14 02:33:19 DEBUG : backup/dst/file1: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f401744b64159b2e5_d20260814_m023242_c001_v0001033_t0001_u01786674762986") 2026/08/14 02:33:19 DEBUG : backup/dst/file1: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f417ee634609e8c1d_d20260814_m023010_c001_v7007000_t0000_u01786674610125") 2026/08/14 02:33:19 DEBUG : backup/dst/file1: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f408f49345902c91e_d20260814_m023009_c001_v0001181_t0043_u01786674609177") 2026/08/14 02:33:19 DEBUG : data/banana: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f41546a0aeb2bc647_d20260814_m022857_c001_v7007000_t0000_u01786674537774") 2026/08/14 02:33:19 DEBUG : data/banana: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc2e76_d20260814_m022855_c001_v0001161_t0021_u01786674535850") 2026/08/14 02:33:19 DEBUG : data/banana: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc2e38_d20260814_m022846_c001_v0001161_t0021_u01786674526730") 2026/08/14 02:33:19 DEBUG : data/potato: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f41546a0aeb2bc648_d20260814_m022857_c001_v7007000_t0000_u01786674537956") 2026/08/14 02:33:19 DEBUG : data/potato: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc2e78_d20260814_m022856_c001_v0001161_t0021_u01786674536033") 2026/08/14 02:33:19 DEBUG : data/potato: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc2e4c_d20260814_m022848_c001_v0001161_t0021_u01786674528713") 2026/08/14 02:33:19 DEBUG : dst/file1: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f408f8b86196d892a_d20260814_m023244_c001_v7007000_t0000_u01786674764086") 2026/08/14 02:33:19 DEBUG : dst/file1: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc3243_d20260814_m023243_c001_v0001161_t0021_u01786674763354") 2026/08/14 02:33:19 DEBUG : dst/file1: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f408f8b86196d8928_d20260814_m023243_c001_v7007000_t0000_u01786674763173") 2026/08/14 02:33:19 DEBUG : dst/file1: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc3235_d20260814_m023240_c001_v0001161_t0021_u01786674760004") 2026/08/14 02:33:19 DEBUG : dst/file1: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f417ee634609e8c1e_d20260814_m023010_c001_v7007000_t0000_u01786674610307") 2026/08/14 02:33:19 DEBUG : dst/file1: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc2f71_d20260814_m023009_c001_v0001161_t0021_u01786674609548") 2026/08/14 02:33:19 DEBUG : dst/file1: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f417ee634609e8c1a_d20260814_m023009_c001_v7007000_t0000_u01786674609362") 2026/08/14 02:33:19 DEBUG : dst/file1: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc2f65_d20260814_m023005_c001_v0001161_t0021_u01786674605962") 2026/08/14 02:33:19 DEBUG : dst/one: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f417ee634609e9042_d20260814_m023035_c001_v7007000_t0000_u01786674635906") 2026/08/14 02:33:19 DEBUG : dst/one: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f408cd14c75c0e102_d20260814_m023031_c001_v0001037_t0037_u01786674631527") 2026/08/14 02:33:19 DEBUG : dst/one: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f407d372a7c73eb31_d20260814_m023031_c001_v7007000_t0000_u01786674631334") 2026/08/14 02:33:19 DEBUG : dst/one: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc2fab_d20260814_m023027_c001_v0001161_t0021_u01786674627320") 2026/08/14 02:33:19 DEBUG : dst/one: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f413913db17d0e2e7_d20260814_m023026_c001_v0001173_t0030_u01786674626584") 2026/08/14 02:33:20 DEBUG : dst/one: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f413913db17d0e2db_d20260814_m023025_c001_v0001173_t0030_u01786674625666") 2026/08/14 02:33:20 DEBUG : dst/one: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f417ee634609e8c37_d20260814_m023020_c001_v7007000_t0000_u01786674620702") 2026/08/14 02:33:20 DEBUG : dst/one: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc2f83_d20260814_m023016_c001_v0001161_t0021_u01786674616652") 2026/08/14 02:33:20 DEBUG : dst/one: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f407bd9b31e91a0e6_d20260814_m023015_c001_v0001181_t0045_u01786674615931") 2026/08/14 02:33:20 DEBUG : dst/one: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f407bd9b31e91a0e2_d20260814_m023014_c001_v0001181_t0045_u01786674614957") 2026/08/14 02:33:20 DEBUG : dst/three: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f417ee634609e9043_d20260814_m023036_c001_v7007000_t0000_u01786674636089") 2026/08/14 02:33:20 DEBUG : dst/three: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f413913db17d0e30f_d20260814_m023034_c001_v0001173_t0030_u01786674634615") 2026/08/14 02:33:20 DEBUG : dst/two: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f417ee634609e9044_d20260814_m023036_c001_v7007000_t0000_u01786674636271") 2026/08/14 02:33:20 DEBUG : dst/two: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f410a8f71b96400b4_d20260814_m023032_c001_v0001173_t0020_u01786674632750") 2026/08/14 02:33:20 DEBUG : dst/two: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f417ee634609e8c3a_d20260814_m023020_c001_v7007000_t0000_u01786674620963") 2026/08/14 02:33:20 DEBUG : dst/two: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f407bd9b31e91a0f4_d20260814_m023019_c001_v0001181_t0045_u01786674619787") 2026/08/14 02:33:20 DEBUG : empty space: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f4270390dca35873a_d20260814_m023312_c001_v7007000_t0000_u01786674792239") 2026/08/14 02:33:20 DEBUG : empty space: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f403b1dd3d4961c1d_d20260814_m023311_c001_v0001145_t0022_u01786674791439") 2026/08/14 02:33:20 DEBUG : empty space: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f4065c14c94fcb8f8_d20260814_m023309_c001_v0001176_t0022_u01786674789911") 2026/08/14 02:33:20 DEBUG : empty space: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f404d41a128819745_d20260814_m023143_c001_v7007000_t0000_u01786674703263") 2026/08/14 02:33:20 DEBUG : empty space: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc3142_d20260814_m023142_c001_v0001161_t0021_u01786674702341") 2026/08/14 02:33:20 DEBUG : empty space: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f404d41a128819742_d20260814_m023141_c001_v7007000_t0000_u01786674701351") 2026/08/14 02:33:21 DEBUG : empty space: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc3138_d20260814_m023140_c001_v0001161_t0021_u01786674700239") 2026/08/14 02:33:21 DEBUG : empty space: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f404d41a128819740_d20260814_m023139_c001_v7007000_t0000_u01786674699345") 2026/08/14 02:33:21 DEBUG : empty space: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc3128_d20260814_m023138_c001_v0001161_t0021_u01786674698613") 2026/08/14 02:33:21 DEBUG : empty space: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f404d41a12881973e_d20260814_m023137_c001_v7007000_t0000_u01786674697773") 2026/08/14 02:33:21 DEBUG : empty space: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc311a_d20260814_m023136_c001_v0001161_t0021_u01786674696497") 2026/08/14 02:33:21 DEBUG : empty space: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f404d41a12881973b_d20260814_m023135_c001_v7007000_t0000_u01786674695631") 2026/08/14 02:33:21 DEBUG : empty space: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc310c_d20260814_m023134_c001_v0001161_t0021_u01786674694903") 2026/08/14 02:33:21 DEBUG : empty space: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f41546a0aeb2bc63b_d20260814_m022840_c001_v7007000_t0000_u01786674520645") 2026/08/14 02:33:21 DEBUG : empty space: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc2e0e_d20260814_m022837_c001_v0001161_t0021_u01786674517810") 2026/08/14 02:33:21 DEBUG : empty space: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f41546a0aeb2bc636_d20260814_m022836_c001_v7007000_t0000_u01786674516176") 2026/08/14 02:33:21 DEBUG : empty space: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc2df8_d20260814_m022831_c001_v0001161_t0021_u01786674511753") 2026/08/14 02:33:21 DEBUG : empty space: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f41546a0aeb2bc632_d20260814_m022829_c001_v7007000_t0000_u01786674509389") 2026/08/14 02:33:21 DEBUG : empty space: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc2de0_d20260814_m022826_c001_v0001161_t0021_u01786674506642") 2026/08/14 02:33:21 DEBUG : file1: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f408f8b86196d8927_d20260814_m023238_c001_v7007000_t0000_u01786674758865") 2026/08/14 02:33:21 DEBUG : file1: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc3231_d20260814_m023237_c001_v0001161_t0021_u01786674757949") 2026/08/14 02:33:21 DEBUG : file1: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f408f8b86196d8925_d20260814_m023235_c001_v7007000_t0000_u01786674755695") 2026/08/14 02:33:21 DEBUG : file1: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc3225_d20260814_m023235_c001_v0001161_t0021_u01786674755328") 2026/08/14 02:33:21 DEBUG : file1: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f408f8b86196d8920_d20260814_m023234_c001_v7007000_t0000_u01786674754412") 2026/08/14 02:33:21 DEBUG : file1: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc3219_d20260814_m023232_c001_v0001161_t0021_u01786674752540") 2026/08/14 02:33:21 DEBUG : file1: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418854082532ee44_d20260814_m023200_c001_v7007000_t0000_u01786674720586") 2026/08/14 02:33:21 DEBUG : file1: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc3188_d20260814_m023157_c001_v0001161_t0021_u01786674717039") 2026/08/14 02:33:21 DEBUG : file1: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f404d41a128819736_d20260814_m023132_c001_v7007000_t0000_u01786674692048") 2026/08/14 02:33:22 DEBUG : file1: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc3072_d20260814_m023104_c001_v0001161_t0021_u01786674664349") 2026/08/14 02:33:22 DEBUG : file1: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f42640cede50cf592_d20260814_m023103_c001_v7007000_t0000_u01786674663802") 2026/08/14 02:33:22 DEBUG : file1: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc3060_d20260814_m023101_c001_v0001161_t0021_u01786674661236") 2026/08/14 02:33:22 DEBUG : file2: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f408f8b86196d8921_d20260814_m023234_c001_v7007000_t0000_u01786674754594") 2026/08/14 02:33:22 DEBUG : file2: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc3221_d20260814_m023233_c001_v0001161_t0021_u01786674753825") 2026/08/14 02:33:22 DEBUG : file2: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418854082532ee45_d20260814_m023200_c001_v7007000_t0000_u01786674720767") 2026/08/14 02:33:22 DEBUG : file2: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc318e_d20260814_m023157_c001_v0001161_t0021_u01786674717224") 2026/08/14 02:33:22 DEBUG : filename.txt: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f408f8b86196d8922_d20260814_m023234_c001_v7007000_t0000_u01786674754779") 2026/08/14 02:33:22 DEBUG : filename.txt: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc321d_d20260814_m023233_c001_v0001161_t0021_u01786674753448") 2026/08/14 02:33:22 DEBUG : headerfilename.txt: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f408f8b86196d8923_d20260814_m023234_c001_v7007000_t0000_u01786674754961") 2026/08/14 02:33:22 DEBUG : headerfilename.txt: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc321f_d20260814_m023233_c001_v0001161_t0021_u01786674753636") 2026/08/14 02:33:22 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f408f8b86196d8980_d20260814_m023301_c001_v7007000_t0000_u01786674781461") 2026/08/14 02:33:22 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f118b6c5b8ddc328f_d20260814_m023300_c001_v0001161_t0003_u01786674780078") 2026/08/14 02:33:22 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f408f8b86196d8962_d20260814_m023259_c001_v7007000_t0000_u01786674779011") 2026/08/14 02:33:22 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f118b6c5b8ddc327f_d20260814_m023257_c001_v0001161_t0030_u01786674777736") 2026/08/14 02:33:22 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f408f8b86196d8983_d20260814_m023301_c001_v7007000_t0000_u01786674781642") 2026/08/14 02:33:22 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f118b6c5b8ddc328b_d20260814_m023259_c001_v0001161_t0035_u01786674779747") 2026/08/14 02:33:22 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f408f8b86196d8963_d20260814_m023259_c001_v7007000_t0000_u01786674779192") 2026/08/14 02:33:22 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f118b6c5b8ddc327d_d20260814_m023257_c001_v0001161_t0034_u01786674777182") 2026/08/14 02:33:22 DEBUG : large: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f404d41a128819794_d20260814_m023153_c001_v7007000_t0000_u01786674713536") 2026/08/14 02:33:23 DEBUG : large: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f118b6c5b8ddc3170_d20260814_m023152_c001_v0001161_t0058_u01786674712605") 2026/08/14 02:33:23 DEBUG : large: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f404d41a12881977a_d20260814_m023151_c001_v7007000_t0000_u01786674711286") 2026/08/14 02:33:23 DEBUG : large: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f118b6c5b8ddc3168_d20260814_m023149_c001_v0001161_t0045_u01786674709918") 2026/08/14 02:33:23 DEBUG : large: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f404d41a128819753_d20260814_m023147_c001_v7007000_t0000_u01786674707924") 2026/08/14 02:33:23 DEBUG : large: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f118b6c5b8ddc315e_d20260814_m023146_c001_v0001161_t0007_u01786674706990") 2026/08/14 02:33:23 DEBUG : large: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f404d41a12881974a_d20260814_m023145_c001_v7007000_t0000_u01786674705985") 2026/08/14 02:33:23 DEBUG : large: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f118b6c5b8ddc3150_d20260814_m023144_c001_v0001161_t0024_u01786674704554") 2026/08/14 02:33:23 DEBUG : medium: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418854082532edff_d20260814_m023153_c001_v7007000_t0000_u01786674713546") 2026/08/14 02:33:23 DEBUG : medium: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f118b6c5b8ddc316e_d20260814_m023152_c001_v0001161_t0018_u01786674712201") 2026/08/14 02:33:23 DEBUG : medium: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418854082532eddd_d20260814_m023150_c001_v7007000_t0000_u01786674710726") 2026/08/14 02:33:23 DEBUG : medium: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f118b6c5b8ddc3164_d20260814_m023149_c001_v0001161_t0032_u01786674709476") 2026/08/14 02:33:23 DEBUG : medium: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418854082532edd4_d20260814_m023147_c001_v7007000_t0000_u01786674707939") 2026/08/14 02:33:23 DEBUG : medium: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f118b6c5b8ddc315a_d20260814_m023146_c001_v0001161_t0019_u01786674706539") 2026/08/14 02:33:23 DEBUG : medium: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f404d41a128819748_d20260814_m023145_c001_v7007000_t0000_u01786674705255") 2026/08/14 02:33:23 DEBUG : medium: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f118b6c5b8ddc314c_d20260814_m023144_c001_v0001161_t0026_u01786674704176") 2026/08/14 02:33:23 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f408f8b86196d8933_d20260814_m023253_c001_v7007000_t0000_u01786674773715") 2026/08/14 02:33:23 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f118b6c5b8ddc3269_d20260814_m023252_c001_v0001161_t0032_u01786674772161") 2026/08/14 02:33:23 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f408f8b86196d8934_d20260814_m023253_c001_v7007000_t0000_u01786674773898") 2026/08/14 02:33:23 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f118b6c5b8ddc3267_d20260814_m023251_c001_v0001161_t0043_u01786674771632") 2026/08/14 02:33:23 DEBUG : not-one: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f417ee634609e905b_d20260814_m023046_c001_v7007000_t0000_u01786674646825") 2026/08/14 02:33:23 DEBUG : not-one: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc2ffc_d20260814_m023045_c001_v0001161_t0021_u01786674645359") 2026/08/14 02:33:23 DEBUG : one: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f417ee634609e9058_d20260814_m023045_c001_v7007000_t0000_u01786674645910") 2026/08/14 02:33:24 DEBUG : one: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f118b6c5b8ddc2ff2_d20260814_m023043_c001_v0001161_t0057_u01786674643813") 2026/08/14 02:33:24 DEBUG : potato1: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f405774f6203f173d_d20260814_m023306_c001_v7007000_t0000_u01786674786545") 2026/08/14 02:33:24 DEBUG : potato1: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f4065c14c94fcb8e2_d20260814_m023306_c001_v0001176_t0022_u01786674786363") 2026/08/14 02:33:24 DEBUG : potato1: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f405774f6203f173c_d20260814_m023305_c001_v7007000_t0000_u01786674785994") 2026/08/14 02:33:24 DEBUG : potato1: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f4065c14c94fcb8de_d20260814_m023305_c001_v0001176_t0022_u01786674785630") 2026/08/14 02:33:24 DEBUG : potato1: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f408f8b86196d899e_d20260814_m023303_c001_v7007000_t0000_u01786674783416") 2026/08/14 02:33:24 DEBUG : potato1: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc3293_d20260814_m023302_c001_v0001161_t0021_u01786674782385") 2026/08/14 02:33:24 DEBUG : potato2: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f4270390dca35873b_d20260814_m023312_c001_v7007000_t0000_u01786674792422") 2026/08/14 02:33:24 DEBUG : potato2: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f1095be309b0a30cb_d20260814_m023311_c001_v0001185_t0034_u01786674791671") 2026/08/14 02:33:24 DEBUG : potato2: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f1065c14c94fcb8f6_d20260814_m023309_c001_v0001176_t0035_u01786674789690") 2026/08/14 02:33:24 DEBUG : potato2: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f405774f6203f173f_d20260814_m023308_c001_v7007000_t0000_u01786674788554") 2026/08/14 02:33:24 DEBUG : potato2: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f4065c14c94fcb8ee_d20260814_m023308_c001_v0001176_t0022_u01786674788189") 2026/08/14 02:33:24 DEBUG : potato2: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f408f8b86196d899f_d20260814_m023303_c001_v7007000_t0000_u01786674783731") 2026/08/14 02:33:24 DEBUG : potato2: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f118b6c5b8ddc3295_d20260814_m023302_c001_v0001161_t0047_u01786674782568") 2026/08/14 02:33:24 DEBUG : potato2: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f404d41a128819746_d20260814_m023143_c001_v7007000_t0000_u01786674703445") 2026/08/14 02:33:24 DEBUG : potato2: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f118b6c5b8ddc313e_d20260814_m023141_c001_v0001161_t0043_u01786674701902") 2026/08/14 02:33:24 DEBUG : potato2: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f404d41a128819743_d20260814_m023141_c001_v7007000_t0000_u01786674701533") 2026/08/14 02:33:24 DEBUG : potato2: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f118b6c5b8ddc3132_d20260814_m023139_c001_v0001161_t0034_u01786674699892") 2026/08/14 02:33:24 DEBUG : potato2: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f404d41a128819741_d20260814_m023139_c001_v7007000_t0000_u01786674699527") 2026/08/14 02:33:24 DEBUG : potato2: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f118b6c5b8ddc3124_d20260814_m023138_c001_v0001161_t0018_u01786674698322") 2026/08/14 02:33:24 DEBUG : potato2: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f404d41a12881973f_d20260814_m023137_c001_v7007000_t0000_u01786674697955") 2026/08/14 02:33:24 DEBUG : potato2: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f118b6c5b8ddc3116_d20260814_m023136_c001_v0001161_t0034_u01786674696183") 2026/08/14 02:33:25 DEBUG : potato2: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f404d41a12881973d_d20260814_m023135_c001_v7007000_t0000_u01786674695814") 2026/08/14 02:33:25 DEBUG : potato2: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f118b6c5b8ddc310a_d20260814_m023134_c001_v0001161_t0020_u01786674694612") 2026/08/14 02:33:25 DEBUG : potato2: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f41546a0aeb2bc63c_d20260814_m022840_c001_v7007000_t0000_u01786674520826") 2026/08/14 02:33:25 DEBUG : potato2: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f118b6c5b8ddc2e10_d20260814_m022838_c001_v0001161_t0014_u01786674518355") 2026/08/14 02:33:25 DEBUG : potato2: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f41546a0aeb2bc637_d20260814_m022836_c001_v7007000_t0000_u01786674516358") 2026/08/14 02:33:25 DEBUG : potato2: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f118b6c5b8ddc2dfe_d20260814_m022832_c001_v0001161_t0033_u01786674512477") 2026/08/14 02:33:25 DEBUG : potato2: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f41546a0aeb2bc633_d20260814_m022829_c001_v7007000_t0000_u01786674509570") 2026/08/14 02:33:25 DEBUG : potato2: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f118b6c5b8ddc2de4_d20260814_m022827_c001_v0001161_t0054_u01786674507185") 2026/08/14 02:33:25 DEBUG : potato3: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f405774f6203f1740_d20260814_m023309_c001_v7007000_t0000_u01786674789283") 2026/08/14 02:33:25 DEBUG : potato3: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f4065c14c94fcb8f4_d20260814_m023308_c001_v0001176_t0022_u01786674788919") 2026/08/14 02:33:25 DEBUG : potato4: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f405774f6203f173e_d20260814_m023307_c001_v7007000_t0000_u01786674787458") 2026/08/14 02:33:25 DEBUG : potato4: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f4065c14c94fcb8ea_d20260814_m023307_c001_v0001176_t0022_u01786674787275") 2026/08/14 02:33:25 DEBUG : remotepotato: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f41546a0aeb2bc63d_d20260814_m022841_c001_v7007000_t0000_u01786674521018") 2026/08/14 02:33:25 DEBUG : remotepotato: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f118b6c5b8ddc2e1a_d20260814_m022839_c001_v0001161_t0046_u01786674519403") 2026/08/14 02:33:25 DEBUG : remotepotato: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f41546a0aeb2bc639_d20260814_m022836_c001_v7007000_t0000_u01786674516539") 2026/08/14 02:33:25 DEBUG : remotepotato: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f118b6c5b8ddc2e00_d20260814_m022834_c001_v0001161_t0048_u01786674514457") 2026/08/14 02:33:25 DEBUG : remotepotato: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f41546a0aeb2bc634_d20260814_m022829_c001_v7007000_t0000_u01786674509751") 2026/08/14 02:33:25 DEBUG : remotepotato: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f118b6c5b8ddc2de8_d20260814_m022828_c001_v0001161_t0017_u01786674508231") 2026/08/14 02:33:25 DEBUG : rutabaga: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f41546a0aeb2bc63e_d20260814_m022841_c001_v7007000_t0000_u01786674521211") 2026/08/14 02:33:25 DEBUG : rutabaga: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc2e06_d20260814_m022837_c001_v0001161_t0021_u01786674517083") 2026/08/14 02:33:26 DEBUG : rutabaga: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f41546a0aeb2bc63a_d20260814_m022836_c001_v7007000_t0000_u01786674516720") 2026/08/14 02:33:26 DEBUG : rutabaga: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc2df4_d20260814_m022830_c001_v0001161_t0021_u01786674510304") 2026/08/14 02:33:26 DEBUG : rutabaga: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f41546a0aeb2bc635_d20260814_m022829_c001_v7007000_t0000_u01786674509938") 2026/08/14 02:33:26 DEBUG : rutabaga: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc2ddc_d20260814_m022825_c001_v0001161_t0021_u01786674505910") 2026/08/14 02:33:26 DEBUG : small: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418854082532ee0e_d20260814_m023154_c001_v7007000_t0000_u01786674714097") 2026/08/14 02:33:26 DEBUG : small: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc316c_d20260814_m023152_c001_v0001161_t0021_u01786674712016") 2026/08/14 02:33:26 DEBUG : small: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f404d41a128819773_d20260814_m023150_c001_v7007000_t0000_u01786674710738") 2026/08/14 02:33:26 DEBUG : small: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc3162_d20260814_m023149_c001_v0001161_t0021_u01786674709209") 2026/08/14 02:33:26 DEBUG : small: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418854082532edd8_d20260814_m023148_c001_v7007000_t0000_u01786674708483") 2026/08/14 02:33:26 DEBUG : small: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc3156_d20260814_m023146_c001_v0001161_t0021_u01786674706351") 2026/08/14 02:33:26 DEBUG : small: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f404d41a128819749_d20260814_m023145_c001_v7007000_t0000_u01786674705436") 2026/08/14 02:33:26 DEBUG : small: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc314a_d20260814_m023143_c001_v0001161_t0021_u01786674703992") 2026/08/14 02:33:26 DEBUG : sub dir/hello world: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f404d41a12881973a_d20260814_m023134_c001_v7007000_t0000_u01786674694247") 2026/08/14 02:33:26 DEBUG : sub dir/hello world: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc3106_d20260814_m023133_c001_v0001161_t0021_u01786674693512") 2026/08/14 02:33:26 DEBUG : sub dir/hello world: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f42640cede50cf58b_d20260814_m023059_c001_v7007000_t0000_u01786674659960") 2026/08/14 02:33:26 DEBUG : sub dir/hello world: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc3038_d20260814_m023055_c001_v0001161_t0021_u01786674655641") 2026/08/14 02:33:26 DEBUG : sub dir/hello world: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f42640cede50cf557_d20260814_m023053_c001_v7007000_t0000_u01786674653978") 2026/08/14 02:33:26 DEBUG : sub dir/hello world: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc300e_d20260814_m023049_c001_v0001161_t0021_u01786674649464") 2026/08/14 02:33:26 DEBUG : sub dir/hello world2: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f42640cede50cf58c_d20260814_m023100_c001_v7007000_t0000_u01786674660141") 2026/08/14 02:33:26 DEBUG : sub dir/hello world2: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc303c_d20260814_m023055_c001_v0001161_t0021_u01786674655824") 2026/08/14 02:33:27 DEBUG : sub dir/hello world2: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f42640cede50cf558_d20260814_m023054_c001_v7007000_t0000_u01786674654159") 2026/08/14 02:33:27 DEBUG : sub dir/hello world2: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc3012_d20260814_m023049_c001_v0001161_t0021_u01786674649647") 2026/08/14 02:33:27 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f42640cede50cf58d_d20260814_m023100_c001_v7007000_t0000_u01786674660323") 2026/08/14 02:33:27 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc3040_d20260814_m023056_c001_v0001161_t0021_u01786674656009") 2026/08/14 02:33:27 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f42640cede50cf55a_d20260814_m023054_c001_v7007000_t0000_u01786674654340") 2026/08/14 02:33:27 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc3014_d20260814_m023049_c001_v0001161_t0021_u01786674649830") 2026/08/14 02:33:27 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f42640cede50cf58f_d20260814_m023100_c001_v7007000_t0000_u01786674660505") 2026/08/14 02:33:27 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f118b6c5b8ddc3046_d20260814_m023056_c001_v0001161_t0028_u01786674656191") 2026/08/14 02:33:27 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f42640cede50cf55c_d20260814_m023054_c001_v7007000_t0000_u01786674654521") 2026/08/14 02:33:27 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f118b6c5b8ddc3016_d20260814_m023050_c001_v0001161_t0053_u01786674650027") 2026/08/14 02:33:27 DEBUG : sub dir/potato3: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f4270390dca35873c_d20260814_m023312_c001_v7007000_t0000_u01786674792605") 2026/08/14 02:33:27 DEBUG : sub dir/potato3: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f417c2e222495fb65_d20260814_m023311_c001_v0001099_t0055_u01786674791235") 2026/08/14 02:33:27 DEBUG : sub dir/potato3: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f4065c14c94fcb8fa_d20260814_m023310_c001_v0001176_t0022_u01786674790094") 2026/08/14 02:33:27 DEBUG : sub dir/potato3: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f404d41a128819747_d20260814_m023143_c001_v7007000_t0000_u01786674703626") 2026/08/14 02:33:27 DEBUG : sub dir/potato3: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc3144_d20260814_m023142_c001_v0001161_t0021_u01786674702529") 2026/08/14 02:33:27 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f42640cede50cf590_d20260814_m023100_c001_v7007000_t0000_u01786674660686") 2026/08/14 02:33:27 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f118b6c5b8ddc3048_d20260814_m023056_c001_v0001161_t0009_u01786674656576") 2026/08/14 02:33:27 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f42640cede50cf55d_d20260814_m023054_c001_v7007000_t0000_u01786674654703") 2026/08/14 02:33:27 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f118b6c5b8ddc3018_d20260814_m023050_c001_v0001161_t0036_u01786674650504") 2026/08/14 02:33:27 DEBUG : sub/file2: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f408f8b86196d8926_d20260814_m023237_c001_v7007000_t0000_u01786674757404") 2026/08/14 02:33:27 DEBUG : sub/file2: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc3229_d20260814_m023236_c001_v0001161_t0021_u01786674756243") 2026/08/14 02:33:27 DEBUG : sub/file2: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f404d41a128819737_d20260814_m023132_c001_v7007000_t0000_u01786674692233") 2026/08/14 02:33:27 DEBUG : sub/file2: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc3076_d20260814_m023104_c001_v0001161_t0021_u01786674664533") 2026/08/14 02:33:27 DEBUG : sub/file2: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f42640cede50cf593_d20260814_m023103_c001_v7007000_t0000_u01786674663983") 2026/08/14 02:33:28 DEBUG : sub/file2: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc3062_d20260814_m023101_c001_v0001161_t0021_u01786674661418") 2026/08/14 02:33:28 DEBUG : sub/file2: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f417ee634609e8c00_d20260814_m023005_c001_v7007000_t0000_u01786674605231") 2026/08/14 02:33:28 DEBUG : sub/file2: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc2f5b_d20260814_m023003_c001_v0001161_t0021_u01786674603760") 2026/08/14 02:33:28 DEBUG : test-multithread-abort: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f41546a0aeb2bc630_d20260814_m022824_c001_v7007000_t0000_u01786674504998") 2026/08/14 02:33:28 DEBUG : test-multithread-abort: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f118b6c5b8ddc2d9e_d20260814_m022809_c001_v0001161_t0018_u01786674489388") 2026/08/14 02:33:28 DEBUG : test-multithread-copy-false-16777215-2: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f41009e268e7f97e9_d20260814_m022726_c001_v7007000_t0000_u01786674446180") 2026/08/14 02:33:28 DEBUG : test-multithread-copy-false-16777215-2: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f113b7de9e214f0db_d20260814_m022718_c001_v0001115_t0035_u01786674438476") 2026/08/14 02:33:28 DEBUG : test-multithread-copy-false-16777216-2: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f41009e268e7f97f0_d20260814_m022737_c001_v7007000_t0000_u01786674457363") 2026/08/14 02:33:28 DEBUG : test-multithread-copy-false-16777216-2: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f110c114a5d20bd93_d20260814_m022729_c001_v0001044_t0021_u01786674449181") 2026/08/14 02:33:28 DEBUG : test-multithread-copy-false-16777217-2: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f41009e268e7f9805_d20260814_m022748_c001_v7007000_t0000_u01786674468037") 2026/08/14 02:33:28 DEBUG : test-multithread-copy-false-16777217-2: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f118b6c5b8ddc2d31_d20260814_m022740_c001_v0001161_t0058_u01786674460339") 2026/08/14 02:33:28 DEBUG : test-multithread-copy-true-16777215-2: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f41546a0aeb2bc628_d20260814_m022754_c001_v7007000_t0000_u01786674474756") 2026/08/14 02:33:28 DEBUG : test-multithread-copy-true-16777215-2: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f21009e268e7f9813_d20260814_m022749_c001_v0001033_t0020_u01786674469816") 2026/08/14 02:33:28 DEBUG : test-multithread-copy-true-16777216-2: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f40122dc1719af85c_d20260814_m022801_c001_v7007000_t0000_u01786674481625") 2026/08/14 02:33:28 DEBUG : test-multithread-copy-true-16777216-2: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f21546a0aeb2bc629_d20260814_m022756_c001_v0001097_t0001_u01786674476564") 2026/08/14 02:33:28 DEBUG : test-multithread-copy-true-16777217-2: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f40122dc1719af860_d20260814_m022808_c001_v7007000_t0000_u01786674488091") 2026/08/14 02:33:28 DEBUG : test-multithread-copy-true-16777217-2: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f20122dc1719af85d_d20260814_m022803_c001_v0001183_t0001_u01786674483491") 2026/08/14 02:33:28 DEBUG : test.sum: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f41546a0aeb2bc649_d20260814_m022858_c001_v7007000_t0000_u01786674538136") 2026/08/14 02:33:28 DEBUG : test.sum: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f118b6c5b8ddc2e7a_d20260814_m022856_c001_v0001161_t0045_u01786674536217") 2026/08/14 02:33:28 DEBUG : test.sum: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f118b6c5b8ddc2e6e_d20260814_m022854_c001_v0001161_t0038_u01786674534623") 2026/08/14 02:33:29 DEBUG : test.sum: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f118b6c5b8ddc2e62_d20260814_m022853_c001_v0001161_t0054_u01786674533152") 2026/08/14 02:33:29 DEBUG : test.sum: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f118b6c5b8ddc2e58_d20260814_m022851_c001_v0001161_t0003_u01786674531673") 2026/08/14 02:33:29 DEBUG : test.sum: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f118b6c5b8ddc2e50_d20260814_m022849_c001_v0001161_t0048_u01786674529992") 2026/08/14 02:33:29 DEBUG : test.sum: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc2e4e_d20260814_m022848_c001_v0001161_t0021_u01786674528898") 2026/08/14 02:33:29 DEBUG : test.sum: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc2e3a_d20260814_m022847_c001_v0001161_t0021_u01786674527028") 2026/08/14 02:33:29 DEBUG : test.sum: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f41546a0aeb2bc641_d20260814_m022843_c001_v7007000_t0000_u01786674523815") 2026/08/14 02:33:29 DEBUG : test.sum: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f118b6c5b8ddc2e2a_d20260814_m022842_c001_v0001161_t0044_u01786674522835") 2026/08/14 02:33:29 DEBUG : test.sum: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f118b6c5b8ddc2e26_d20260814_m022841_c001_v0001161_t0053_u01786674521575") 2026/08/14 02:33:29 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f408f8b86196d8935_d20260814_m023256_c001_v7007000_t0000_u01786674776246") 2026/08/14 02:33:29 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f118b6c5b8ddc3275_d20260814_m023254_c001_v0001161_t0034_u01786674774932") 2026/08/14 02:33:29 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f408f8b86196d8937_d20260814_m023256_c001_v7007000_t0000_u01786674776543") 2026/08/14 02:33:29 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f118b6c5b8ddc3271_d20260814_m023254_c001_v0001161_t0040_u01786674774484") 2026/08/14 02:33:29 DEBUG : zend.txt: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f42640cede50cf591_d20260814_m023100_c001_v7007000_t0000_u01786674660868") 2026/08/14 02:33:29 DEBUG : zend.txt: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc3036_d20260814_m023055_c001_v0001161_t0021_u01786674655458") 2026/08/14 02:33:29 DEBUG : zend.txt: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f42640cede50cf55e_d20260814_m023054_c001_v7007000_t0000_u01786674654898") 2026/08/14 02:33:29 DEBUG : zend.txt: Deleting (id "4_zbfa25da3108dfbeb9ff80e1b_f418b6c5b8ddc300c_d20260814_m023049_c001_v0001161_t0021_u01786674649280") "./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -fast-list" - Finished OK in 6m14.844852094s (try 1/5)