"./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose" - Starting (try 1/5) 2026/02/22 01:43:14 DEBUG : Creating backend with remote "TestB2:rclone-test-zuxafas4qeho" 2026/02/22 01:43:14 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/22 01:43:14 DEBUG : Creating backend with remote "/tmp/rclone1368031187" === 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:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" 2026/02/22 01:43:15 DEBUG : chunksize-probe: Cancelling large file upload === RUN TestMultithreadCopy/upload=false,size=16777215,streams=2 2026/02/22 01:43:20 DEBUG : test-multithread-copy-false-16777215-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2026/02/22 01:43:20 DEBUG : test-multithread-copy-false-16777215-2: multi-thread copy: write buffer set to 131072 2026/02/22 01:43:20 DEBUG : test-multithread-copy-false-16777215-2: multi-thread copy: chunk size 64Mi was bigger than source file size 16.000Mi 2026/02/22 01:43:20 DEBUG : test-multithread-copy-false-16777215-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2026/02/22 01:43:20 DEBUG : test-multithread-copy-false-16777215-2: multi-thread copy: number of streams 4 was bigger than number of chunks 1 2026/02/22 01:43:20 DEBUG : test-multithread-copy-false-16777215-2: Starting multi-thread copy with 1 chunks of size 16.000Mi with 1 parallel streams 2026/02/22 01:43:20 DEBUG : test-multithread-copy-false-16777215-2: multi-thread copy: chunk 1/1 (0-16777215) size 16.000Mi starting 2026/02/22 01:43:20 DEBUG : test-multithread-copy-false-16777215-2: writing chunk 0 2026/02/22 01:43:22 DEBUG : test-multithread-copy-false-16777215-2: multi-thread copy: chunk 1/1 (0-16777215) size 16.000Mi finished 2026/02/22 01:43:22 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/02/22 01:43:24 DEBUG : test-multithread-copy-false-16777216-2: Clearing upload URL because of error: Post "https://pod-000-1103-18.backblaze.com/b2api/v1/b2_upload_file/5f42ed03003d8b3b9fc80e1b/c001_v0001103_t0013": write tcp 65.108.87.141:53638->206.190.209.149:443: use of closed network connection 2026/02/22 01:43:24 DEBUG : pacer: low level retry 1/1 (error Post "https://pod-000-1103-18.backblaze.com/b2api/v1/b2_upload_file/5f42ed03003d8b3b9fc80e1b/c001_v0001103_t0013": write tcp 65.108.87.141:53638->206.190.209.149:443: use of closed network connection) 2026/02/22 01:43:24 DEBUG : pacer: Rate limited, increasing sleep to 20ms === NAME TestMultithreadCopy run.go:286: Retry Put of "test-multithread-copy-false-16777216-2" to B2 bucket rclone-test-zuxafas4qeho: 1/10 (Post "https://pod-000-1103-18.backblaze.com/b2api/v1/b2_upload_file/5f42ed03003d8b3b9fc80e1b/c001_v0001103_t0013": write tcp 65.108.87.141:53638->206.190.209.149:443: use of closed network connection) 2026/02/22 01:43:26 DEBUG : pacer: Reducing sleep to 10ms 2026/02/22 01:43:30 DEBUG : test-multithread-copy-false-16777216-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2026/02/22 01:43:30 DEBUG : test-multithread-copy-false-16777216-2: multi-thread copy: write buffer set to 131072 2026/02/22 01:43:30 DEBUG : test-multithread-copy-false-16777216-2: multi-thread copy: chunk size 64Mi was bigger than source file size 16Mi 2026/02/22 01:43:30 DEBUG : test-multithread-copy-false-16777216-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2026/02/22 01:43:30 DEBUG : test-multithread-copy-false-16777216-2: multi-thread copy: number of streams 4 was bigger than number of chunks 1 2026/02/22 01:43:30 DEBUG : test-multithread-copy-false-16777216-2: Starting multi-thread copy with 1 chunks of size 16Mi with 1 parallel streams 2026/02/22 01:43:30 DEBUG : test-multithread-copy-false-16777216-2: multi-thread copy: chunk 1/1 (0-16777216) size 16Mi starting 2026/02/22 01:43:30 DEBUG : test-multithread-copy-false-16777216-2: writing chunk 0 2026/02/22 01:43:32 DEBUG : test-multithread-copy-false-16777216-2: multi-thread copy: chunk 1/1 (0-16777216) size 16Mi finished 2026/02/22 01:43:32 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/02/22 01:43:34 DEBUG : test-multithread-copy-false-16777217-2: Clearing upload URL because of error: Post "https://pod-000-1157-06.backblaze.com/b2api/v1/b2_upload_file/5f42ed03003d8b3b9fc80e1b/c001_v0001157_t0055": write tcp 65.108.87.141:38916->104.153.234.136:443: use of closed network connection 2026/02/22 01:43:34 DEBUG : pacer: low level retry 1/1 (error Post "https://pod-000-1157-06.backblaze.com/b2api/v1/b2_upload_file/5f42ed03003d8b3b9fc80e1b/c001_v0001157_t0055": write tcp 65.108.87.141:38916->104.153.234.136:443: use of closed network connection) 2026/02/22 01:43:34 DEBUG : pacer: Rate limited, increasing sleep to 20ms === NAME TestMultithreadCopy run.go:286: Retry Put of "test-multithread-copy-false-16777217-2" to B2 bucket rclone-test-zuxafas4qeho: 1/10 (Post "https://pod-000-1157-06.backblaze.com/b2api/v1/b2_upload_file/5f42ed03003d8b3b9fc80e1b/c001_v0001157_t0055": write tcp 65.108.87.141:38916->104.153.234.136:443: use of closed network connection) 2026/02/22 01:43:36 DEBUG : pacer: Reducing sleep to 10ms 2026/02/22 01:43:40 DEBUG : test-multithread-copy-false-16777217-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2026/02/22 01:43:40 DEBUG : test-multithread-copy-false-16777217-2: multi-thread copy: write buffer set to 131072 2026/02/22 01:43:40 DEBUG : test-multithread-copy-false-16777217-2: multi-thread copy: chunk size 64Mi was bigger than source file size 16.000Mi 2026/02/22 01:43:40 DEBUG : test-multithread-copy-false-16777217-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2026/02/22 01:43:40 DEBUG : test-multithread-copy-false-16777217-2: multi-thread copy: number of streams 4 was bigger than number of chunks 1 2026/02/22 01:43:40 DEBUG : test-multithread-copy-false-16777217-2: Starting multi-thread copy with 1 chunks of size 16.000Mi with 1 parallel streams 2026/02/22 01:43:40 DEBUG : test-multithread-copy-false-16777217-2: multi-thread copy: chunk 1/1 (0-16777217) size 16.000Mi starting 2026/02/22 01:43:40 DEBUG : test-multithread-copy-false-16777217-2: writing chunk 0 2026/02/22 01:43:42 DEBUG : test-multithread-copy-false-16777217-2: multi-thread copy: chunk 1/1 (0-16777217) size 16.000Mi finished 2026/02/22 01:43:42 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/02/22 01:43:45 DEBUG : test-multithread-copy-true-16777215-2: multi-thread copy: disabling buffering because source is local disk 2026/02/22 01:43:45 DEBUG : test-multithread-copy-true-16777215-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2026/02/22 01:43:45 DEBUG : test-multithread-copy-true-16777215-2: multi-thread copy: number of streams 4 was bigger than number of chunks 2 2026/02/22 01:43:45 DEBUG : test-multithread-copy-true-16777215-2: Starting multi-thread copy with 2 chunks of size 8Mi with 2 parallel streams 2026/02/22 01:43:45 DEBUG : test-multithread-copy-true-16777215-2: multi-thread copy: chunk 2/2 (8388608-16777215) size 8.000Mi starting 2026/02/22 01:43:45 DEBUG : test-multithread-copy-true-16777215-2: Sending chunk 1 length 8388607 2026/02/22 01:43:45 DEBUG : test-multithread-copy-true-16777215-2: multi-thread copy: chunk 1/2 (0-8388608) size 8Mi starting 2026/02/22 01:43:45 DEBUG : test-multithread-copy-true-16777215-2: Sending chunk 0 length 8388608 2026/02/22 01:43:48 DEBUG : test-multithread-copy-true-16777215-2: Done sending chunk 1 2026/02/22 01:43:48 DEBUG : test-multithread-copy-true-16777215-2: multi-thread copy: chunk 2/2 (8388608-16777215) size 8.000Mi finished 2026/02/22 01:43:48 DEBUG : test-multithread-copy-true-16777215-2: Done sending chunk 0 2026/02/22 01:43:48 DEBUG : test-multithread-copy-true-16777215-2: multi-thread copy: chunk 1/2 (0-8388608) size 8Mi finished 2026/02/22 01:43:48 DEBUG : test-multithread-copy-true-16777215-2: Finishing large file upload with 2 parts 2026/02/22 01:43:49 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/02/22 01:43:52 DEBUG : test-multithread-copy-true-16777216-2: multi-thread copy: disabling buffering because source is local disk 2026/02/22 01:43:52 DEBUG : test-multithread-copy-true-16777216-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2026/02/22 01:43:52 DEBUG : test-multithread-copy-true-16777216-2: multi-thread copy: number of streams 4 was bigger than number of chunks 2 2026/02/22 01:43:52 DEBUG : test-multithread-copy-true-16777216-2: Starting multi-thread copy with 2 chunks of size 8Mi with 2 parallel streams 2026/02/22 01:43:52 DEBUG : test-multithread-copy-true-16777216-2: multi-thread copy: chunk 2/2 (8388608-16777216) size 8Mi starting 2026/02/22 01:43:52 DEBUG : test-multithread-copy-true-16777216-2: multi-thread copy: chunk 1/2 (0-8388608) size 8Mi starting 2026/02/22 01:43:52 DEBUG : test-multithread-copy-true-16777216-2: Sending chunk 1 length 8388608 2026/02/22 01:43:52 DEBUG : test-multithread-copy-true-16777216-2: Sending chunk 0 length 8388608 2026/02/22 01:43:54 DEBUG : test-multithread-copy-true-16777216-2: Done sending chunk 1 2026/02/22 01:43:54 DEBUG : test-multithread-copy-true-16777216-2: multi-thread copy: chunk 2/2 (8388608-16777216) size 8Mi finished 2026/02/22 01:43:55 DEBUG : test-multithread-copy-true-16777216-2: Done sending chunk 0 2026/02/22 01:43:55 DEBUG : test-multithread-copy-true-16777216-2: multi-thread copy: chunk 1/2 (0-8388608) size 8Mi finished 2026/02/22 01:43:55 DEBUG : test-multithread-copy-true-16777216-2: Finishing large file upload with 2 parts 2026/02/22 01:43:56 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/02/22 01:43:58 DEBUG : test-multithread-copy-true-16777217-2: multi-thread copy: disabling buffering because source is local disk 2026/02/22 01:43:59 DEBUG : test-multithread-copy-true-16777217-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2026/02/22 01:43:59 DEBUG : test-multithread-copy-true-16777217-2: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2026/02/22 01:43:59 DEBUG : test-multithread-copy-true-16777217-2: Starting multi-thread copy with 3 chunks of size 8Mi with 3 parallel streams 2026/02/22 01:43:59 DEBUG : test-multithread-copy-true-16777217-2: multi-thread copy: chunk 3/3 (16777216-16777217) size 1 starting 2026/02/22 01:43:59 DEBUG : test-multithread-copy-true-16777217-2: multi-thread copy: chunk 1/3 (0-8388608) size 8Mi starting 2026/02/22 01:43:59 DEBUG : test-multithread-copy-true-16777217-2: Sending chunk 2 length 1 2026/02/22 01:43:59 DEBUG : test-multithread-copy-true-16777217-2: multi-thread copy: chunk 2/3 (8388608-16777216) size 8Mi starting 2026/02/22 01:43:59 DEBUG : test-multithread-copy-true-16777217-2: Sending chunk 0 length 8388608 2026/02/22 01:43:59 DEBUG : test-multithread-copy-true-16777217-2: Sending chunk 1 length 8388608 2026/02/22 01:44:00 DEBUG : test-multithread-copy-true-16777217-2: Done sending chunk 2 2026/02/22 01:44:00 DEBUG : test-multithread-copy-true-16777217-2: multi-thread copy: chunk 3/3 (16777216-16777217) size 1 finished 2026/02/22 01:44:02 DEBUG : test-multithread-copy-true-16777217-2: Done sending chunk 1 2026/02/22 01:44:02 DEBUG : test-multithread-copy-true-16777217-2: multi-thread copy: chunk 2/3 (8388608-16777216) size 8Mi finished 2026/02/22 01:44:02 DEBUG : test-multithread-copy-true-16777217-2: Done sending chunk 0 2026/02/22 01:44:02 DEBUG : test-multithread-copy-true-16777217-2: multi-thread copy: chunk 1/3 (0-8388608) size 8Mi finished 2026/02/22 01:44:02 DEBUG : test-multithread-copy-true-16777217-2: Finishing large file upload with 3 parts 2026/02/22 01:44:03 DEBUG : test-multithread-copy-true-16777217-2: Finished multi-thread copy with 3 parts of size 8Mi --- PASS: TestMultithreadCopy (49.74s) --- PASS: TestMultithreadCopy/upload=false,size=16777215,streams=2 (8.06s) --- PASS: TestMultithreadCopy/upload=false,size=16777216,streams=2 (10.01s) --- PASS: TestMultithreadCopy/upload=false,size=16777217,streams=2 (9.93s) --- PASS: TestMultithreadCopy/upload=true,size=16777215,streams=2 (6.61s) --- PASS: TestMultithreadCopy/upload=true,size=16777216,streams=2 (6.56s) --- PASS: TestMultithreadCopy/upload=true,size=16777217,streams=2 (7.04s) === RUN TestMultithreadCopyAbort run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" 2026/02/22 01:44:04 DEBUG : chunksize-probe: Cancelling large file upload 2026/02/22 01:44:07 DEBUG : test-multithread-abort: multi-thread copy: disabling buffering because source is local disk 2026/02/22 01:44:07 DEBUG : test-multithread-abort: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 1 2026/02/22 01:44:07 DEBUG : test-multithread-abort: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2026/02/22 01:44:07 DEBUG : test-multithread-abort: Starting multi-thread copy with 3 chunks of size 8Mi with 3 parallel streams 2026/02/22 01:44:07 DEBUG : test-multithread-abort: multi-thread copy: chunk 3/3 (16777216-16777217) size 1 starting 2026/02/22 01:44:07 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2026/02/22 01:44:07 DEBUG : test-multithread-abort: multi-thread copy: chunk 1/3 (0-8388608) size 8Mi starting 2026/02/22 01:44:07 DEBUG : Open with options = [RangeOption(0,8388607)] 2026/02/22 01:44:07 DEBUG : test-multithread-abort: multi-thread copy: chunk 2/3 (8388608-16777216) size 8Mi starting 2026/02/22 01:44:07 DEBUG : Open with options = [RangeOption(8388608,16777215)] 2026/02/22 01:44:07 DEBUG : test-multithread-abort: Sending chunk 0 length 8388608 2026/02/22 01:44:07 DEBUG : test-multithread-abort: Sending chunk 1 length 8388608 2026/02/22 01:44:10 DEBUG : test-multithread-abort: Done sending chunk 0 2026/02/22 01:44:10 DEBUG : test-multithread-abort: multi-thread copy: chunk 1/3 (0-8388608) size 8Mi finished 2026/02/22 01:44:10 DEBUG : test-multithread-abort: Done sending chunk 1 2026/02/22 01:44:10 DEBUG : test-multithread-abort: multi-thread copy: chunk 2/3 (8388608-16777216) size 8Mi finished 2026/02/22 01:44:10 DEBUG : Returning error reader 2026/02/22 01:44:10 DEBUG : test-multithread-abort: Sending chunk 2 length 1 2026/02/22 01:44:10 DEBUG : BOOM: simulated read failure 2026/02/22 01:44:10 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 0/10: BOOM: simulated read failure 2026/02/22 01:44:10 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2026/02/22 01:44:11 DEBUG : Returning error reader 2026/02/22 01:44:11 DEBUG : BOOM: simulated read failure 2026/02/22 01:44:11 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 1/10: BOOM: simulated read failure 2026/02/22 01:44:11 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2026/02/22 01:44:12 DEBUG : Returning error reader 2026/02/22 01:44:12 DEBUG : BOOM: simulated read failure 2026/02/22 01:44:12 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 2/10: BOOM: simulated read failure 2026/02/22 01:44:12 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2026/02/22 01:44:13 DEBUG : Returning error reader 2026/02/22 01:44:13 DEBUG : BOOM: simulated read failure 2026/02/22 01:44:13 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 3/10: BOOM: simulated read failure 2026/02/22 01:44:13 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2026/02/22 01:44:14 DEBUG : Returning error reader 2026/02/22 01:44:14 DEBUG : BOOM: simulated read failure 2026/02/22 01:44:14 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 4/10: BOOM: simulated read failure 2026/02/22 01:44:14 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2026/02/22 01:44:15 DEBUG : Returning error reader 2026/02/22 01:44:15 DEBUG : BOOM: simulated read failure 2026/02/22 01:44:15 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 5/10: BOOM: simulated read failure 2026/02/22 01:44:15 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2026/02/22 01:44:16 DEBUG : Returning error reader 2026/02/22 01:44:16 DEBUG : BOOM: simulated read failure 2026/02/22 01:44:16 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 6/10: BOOM: simulated read failure 2026/02/22 01:44:16 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2026/02/22 01:44:17 DEBUG : Returning error reader 2026/02/22 01:44:17 DEBUG : BOOM: simulated read failure 2026/02/22 01:44:17 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 7/10: BOOM: simulated read failure 2026/02/22 01:44:17 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2026/02/22 01:44:18 DEBUG : Returning error reader 2026/02/22 01:44:18 DEBUG : BOOM: simulated read failure 2026/02/22 01:44:18 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 8/10: BOOM: simulated read failure 2026/02/22 01:44:18 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2026/02/22 01:44:19 DEBUG : Returning error reader 2026/02/22 01:44:19 DEBUG : BOOM: simulated read failure 2026/02/22 01:44:19 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 9/10: BOOM: simulated read failure 2026/02/22 01:44:19 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2026/02/22 01:44:20 DEBUG : Returning error reader 2026/02/22 01:44:20 DEBUG : BOOM: simulated read failure 2026/02/22 01:44:20 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 10/10: BOOM: simulated read failure 2026/02/22 01:44:20 DEBUG : test-multithread-abort: Reopen failed after offset 0 bytes read: failed to reopen: too many retries 2026/02/22 01:44:20 DEBUG : test-multithread-abort: Error sending chunk 2 (retry=false): Post "https://pod-000-1167-13.backblaze.com/b2api/v1/b2_upload_part/4_z5f42ed03003d8b3b9fc80e1b_f221ed05a9705db75_d20260222_m014407_c001_v0001167_t0058_u01771724647296/0017": BOOM: simulated read failure: &url.Error{Op:"Post", URL:"https://pod-000-1167-13.backblaze.com/b2api/v1/b2_upload_part/4_z5f42ed03003d8b3b9fc80e1b_f221ed05a9705db75_d20260222_m014407_c001_v0001167_t0058_u01771724647296/0017", Err:(*errors.errorString)(0xc0009827e0)} 2026/02/22 01:44:20 DEBUG : test-multithread-abort: Error sending chunk 2: Post "https://pod-000-1167-13.backblaze.com/b2api/v1/b2_upload_part/4_z5f42ed03003d8b3b9fc80e1b_f221ed05a9705db75_d20260222_m014407_c001_v0001167_t0058_u01771724647296/0017": BOOM: simulated read failure 2026/02/22 01:44:20 DEBUG : test-multithread-abort: multi-thread copy: chunk 3/3 failed: multi-thread copy: failed to write chunk: Post "https://pod-000-1167-13.backblaze.com/b2api/v1/b2_upload_part/4_z5f42ed03003d8b3b9fc80e1b_f221ed05a9705db75_d20260222_m014407_c001_v0001167_t0058_u01771724647296/0017": BOOM: simulated read failure 2026/02/22 01:44:20 DEBUG : test-multithread-abort: multi-thread copy: cancelling transfer on exit 2026/02/22 01:44:20 DEBUG : test-multithread-abort: Cancelling large file upload --- PASS: TestMultithreadCopyAbort (17.41s) === RUN TestSizeDiffers 2026/02/22 01:44:21 DEBUG : a: size = 0 OK 2026/02/22 01:44:21 DEBUG : a: size = 1 (memory) 2026/02/22 01:44:21 DEBUG : a: size = 2 (memory) --- PASS: TestSizeDiffers (0.00s) === RUN TestReOpen === RUN TestReOpen/Normal === RUN TestReOpen/Normal/Basics 2026/02/22 01:44:21 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/ErrorAtStart === RUN TestReOpen/Normal/WithErrors 2026/02/22 01:44:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/02/22 01:44:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/02/22 01:44:21 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/Normal/TooManyErrors 2026/02/22 01:44:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/02/22 01:44:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/02/22 01:44:21 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/02/22 01:44:21 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/Normal/ReadAt 2026/02/22 01:44:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/02/22 01:44:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/02/22 01:44:21 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/Normal/Seek 2026/02/22 01:44:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/02/22 01:44:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/02/22 01:44:21 DEBUG : potato: Seek from 5 to 2 === RUN TestReOpen/Normal/AccountRead === RUN TestReOpen/Normal/AccountReadDelay 2026/02/22 01:44:21 DEBUG : potato: Seek from 10 to 0 2026/02/22 01:44:21 DEBUG : potato: Seek from 10 to 0 2026/02/22 01:44:21 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/AccountReadError === RUN TestReOpen/WithRangeOption === RUN TestReOpen/WithRangeOption/Basics 2026/02/22 01:44:21 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/ErrorAtStart === RUN TestReOpen/WithRangeOption/WithErrors 2026/02/22 01:44:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/02/22 01:44:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/02/22 01:44:21 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithRangeOption/TooManyErrors 2026/02/22 01:44:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/02/22 01:44:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/02/22 01:44:21 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/02/22 01:44:21 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithRangeOption/ReadAt 2026/02/22 01:44:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/02/22 01:44:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/02/22 01:44:21 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/WithRangeOption/Seek 2026/02/22 01:44:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/02/22 01:44:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/02/22 01:44:21 DEBUG : potato: Seek from 5 to 2 2026/02/22 01:44:21 DEBUG : potato: Seek from 7 to 4 === RUN TestReOpen/WithRangeOption/AccountRead === RUN TestReOpen/WithRangeOption/AccountReadDelay 2026/02/22 01:44:21 DEBUG : potato: Seek from 7 to 0 2026/02/22 01:44:21 DEBUG : potato: Seek from 7 to 0 2026/02/22 01:44:21 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/AccountReadError === RUN TestReOpen/WithSeekOption === RUN TestReOpen/WithSeekOption/Basics 2026/02/22 01:44:21 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/ErrorAtStart === RUN TestReOpen/WithSeekOption/WithErrors 2026/02/22 01:44:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/02/22 01:44:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/02/22 01:44:21 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithSeekOption/TooManyErrors 2026/02/22 01:44:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/02/22 01:44:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/02/22 01:44:21 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/02/22 01:44:21 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithSeekOption/ReadAt 2026/02/22 01:44:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/02/22 01:44:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/02/22 01:44:21 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/WithSeekOption/Seek 2026/02/22 01:44:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/02/22 01:44:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/02/22 01:44:21 DEBUG : potato: Seek from 5 to 2 2026/02/22 01:44:21 DEBUG : potato: Seek from 7 to 5 === RUN TestReOpen/WithSeekOption/AccountRead === RUN TestReOpen/WithSeekOption/AccountReadDelay 2026/02/22 01:44:21 DEBUG : potato: Seek from 8 to 0 2026/02/22 01:44:21 DEBUG : potato: Seek from 8 to 0 2026/02/22 01:44:21 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/AccountReadError === RUN TestReOpen/UnknownSize === RUN TestReOpen/UnknownSize/Basics 2026/02/22 01:44:21 DEBUG : potato: Seek from 9 to 0 === RUN TestReOpen/UnknownSize/ErrorAtStart === RUN TestReOpen/UnknownSize/WithErrors 2026/02/22 01:44:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/02/22 01:44:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/02/22 01:44:21 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/UnknownSize/TooManyErrors 2026/02/22 01:44:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/02/22 01:44:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/02/22 01:44:21 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/02/22 01:44:21 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/UnknownSize/ReadAt 2026/02/22 01:44:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/02/22 01:44:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/02/22 01:44:21 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/UnknownSize/Seek 2026/02/22 01:44:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/02/22 01:44:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/02/22 01:44:21 DEBUG : potato: Seek from 5 to 2 2026/02/22 01:44:21 DEBUG : potato: Seek from 7 to 6 === RUN TestReOpen/UnknownSize/AccountRead === RUN TestReOpen/UnknownSize/AccountReadDelay 2026/02/22 01:44:21 DEBUG : potato: Seek from 9 to 0 2026/02/22 01:44:21 DEBUG : potato: Seek from 9 to 0 2026/02/22 01:44:21 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:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", 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.42s) --- PASS: TestCheck/1 (0.18s) --- PASS: TestCheck/2 (0.18s) --- PASS: TestCheck/3 (0.18s) --- PASS: TestCheck/4 (0.18s) --- PASS: TestCheck/5 (0.18s) --- PASS: TestCheck/6 (0.18s) --- PASS: TestCheck/7 (0.18s) === RUN TestCheckFsError 2026/02/22 01:44:26 DEBUG : Creating backend with remote "nonexistent" 2026/02/22 01:44:26 DEBUG : Creating backend with remote "nonexistent" 2026/02/22 01:44:26 DEBUG : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: Waiting for checks to finish 2026/02/22 01:44:26 ERROR : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: error reading source root directory: directory not found 2026/02/22 01:44:26 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: 0 differences found 2026/02/22 01:44:26 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: 2 errors while checking --- PASS: TestCheckFsError (0.00s) === RUN TestCheckDownload run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", 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 (5.95s) --- PASS: TestCheckDownload/1 (0.72s) --- PASS: TestCheckDownload/2 (0.37s) --- PASS: TestCheckDownload/3 (0.42s) --- PASS: TestCheckDownload/4 (0.55s) --- PASS: TestCheckDownload/5 (0.39s) --- PASS: TestCheckDownload/6 (0.45s) --- PASS: TestCheckDownload/7 (0.37s) === RUN TestCheckSizeOnly run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", 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 (3.99s) --- PASS: TestCheckSizeOnly/1 (0.18s) --- PASS: TestCheckSizeOnly/2 (0.18s) --- PASS: TestCheckSizeOnly/3 (0.19s) --- 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:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" 2026/02/22 01:44:37 NOTICE: test.sum: improperly formatted checksum line 4 2026/02/22 01:44:37 NOTICE: test.sum: improperly formatted checksum line 5 2026/02/22 01:44:37 NOTICE: test.sum: improperly formatted checksum line 6 2026/02/22 01:44:37 NOTICE: test.sum: 2 warning(s) suppressed... 2026/02/22 01:44:37 NOTICE: test.sum: improperly formatted checksum line 4 2026/02/22 01:44:37 NOTICE: test.sum: improperly formatted checksum line 5 2026/02/22 01:44:37 NOTICE: test.sum: improperly formatted checksum line 6 2026/02/22 01:44:37 NOTICE: test.sum: 2 warning(s) suppressed... --- PASS: TestParseSumFile (2.11s) === RUN TestCheckSum run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" 2026/02/22 01:44:38 DEBUG : Creating backend with remote "TestB2:rclone-test-zuxafas4qeho/data" check_test.go:350: B2 bucket rclone-test-zuxafas4qeho path data lacks md5, skipping --- SKIP: TestCheckSum (1.46s) === RUN TestCheckSumDownload run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" 2026/02/22 01:44:39 DEBUG : Creating backend with remote "TestB2:rclone-test-zuxafas4qeho/data" === RUN TestCheckSumDownload/subtest1 === RUN TestCheckSumDownload/subtest2 === RUN TestCheckSumDownload/subtest3 === RUN TestCheckSumDownload/subtest4 === RUN TestCheckSumDownload/subtest5 === RUN TestCheckSumDownload/subtest6 === RUN TestCheckSumDownload/subtest7 --- PASS: TestCheckSumDownload (11.83s) --- PASS: TestCheckSumDownload/subtest1 (1.29s) --- PASS: TestCheckSumDownload/subtest2 (0.73s) --- PASS: TestCheckSumDownload/subtest3 (0.91s) --- PASS: TestCheckSumDownload/subtest4 (0.74s) --- PASS: TestCheckSumDownload/subtest5 (0.75s) --- PASS: TestCheckSumDownload/subtest6 (0.74s) --- PASS: TestCheckSumDownload/subtest7 (0.74s) === RUN TestApplyTransforms 2026/02/22 01:44:51 DEBUG : Creating backend with remote "TestB2:rclone-test-vepudaz7lami" 2026/02/22 01:44:51 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/22 01:44:52 DEBUG : Creating backend with remote "/tmp/rclone4018972097" run.go:185: Remote "B2 bucket rclone-test-vepudaz7lami", Local "Local file system at /tmp/rclone4018972097", Modify Window "1ms" upper checkfile vs. lower remote (without normalization) 2026/02/22 01:44:54 ERROR : hello, world!: sum not found 2026/02/22 01:44:54 ERROR : HELLO, WORLD!: file not in B2 bucket rclone-test-vepudaz7lami 2026/02/22 01:44:54 NOTICE: B2 bucket rclone-test-vepudaz7lami: 1 files missing 2026/02/22 01:44:54 NOTICE: 1 hashes missing 2026/02/22 01:44:54 NOTICE: B2 bucket rclone-test-vepudaz7lami: 1 differences found 2026/02/22 01:44:54 NOTICE: B2 bucket rclone-test-vepudaz7lami: 2 errors while checking upper checkfile vs. lower remote (with normalization) 2026/02/22 01:44:54 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/02/22 01:44:54 NOTICE: B2 bucket rclone-test-vepudaz7lami: 0 differences found 2026/02/22 01:44:54 NOTICE: B2 bucket rclone-test-vepudaz7lami: 1 matching files 2026/02/22 01:44:54 DEBUG : Creating backend with remote "TestB2:rclone-test-lusivar7tosi" 2026/02/22 01:44:54 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/22 01:44:55 DEBUG : Creating backend with remote "/tmp/rclone2360825217" run.go:185: Remote "B2 bucket rclone-test-lusivar7tosi", Local "Local file system at /tmp/rclone2360825217", Modify Window "1ms" lower checkfile vs. upper remote (without normalization) 2026/02/22 01:44:58 ERROR : HELLO, WORLD!: sum not found 2026/02/22 01:44:58 ERROR : hello, world!: file not in B2 bucket rclone-test-lusivar7tosi 2026/02/22 01:44:58 NOTICE: B2 bucket rclone-test-lusivar7tosi: 1 files missing 2026/02/22 01:44:58 NOTICE: 1 hashes missing 2026/02/22 01:44:58 NOTICE: B2 bucket rclone-test-lusivar7tosi: 1 differences found 2026/02/22 01:44:58 NOTICE: B2 bucket rclone-test-lusivar7tosi: 2 errors while checking lower checkfile vs. upper remote (with normalization) 2026/02/22 01:44:58 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/02/22 01:44:58 NOTICE: B2 bucket rclone-test-lusivar7tosi: 0 differences found 2026/02/22 01:44:58 NOTICE: B2 bucket rclone-test-lusivar7tosi: 1 matching files 2026/02/22 01:44:58 DEBUG : Creating backend with remote "TestB2:rclone-test-nadajek6rugo" 2026/02/22 01:44:58 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/22 01:44:59 DEBUG : Creating backend with remote "/tmp/rclone1344909353" run.go:185: Remote "B2 bucket rclone-test-nadajek6rugo", Local "Local file system at /tmp/rclone1344909353", Modify Window "1ms" lower checkfile vs. upperlowermixed remote (without normalization) 2026/02/22 01:45:01 ERROR : HeLlO, wOrLd!: sum not found 2026/02/22 01:45:01 ERROR : hello, world!: file not in B2 bucket rclone-test-nadajek6rugo 2026/02/22 01:45:01 NOTICE: B2 bucket rclone-test-nadajek6rugo: 1 files missing 2026/02/22 01:45:01 NOTICE: 1 hashes missing 2026/02/22 01:45:01 NOTICE: B2 bucket rclone-test-nadajek6rugo: 1 differences found 2026/02/22 01:45:01 NOTICE: B2 bucket rclone-test-nadajek6rugo: 2 errors while checking lower checkfile vs. upperlowermixed remote (with normalization) 2026/02/22 01:45:02 DEBUG : HeLlO, wOrLd!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/02/22 01:45:02 NOTICE: B2 bucket rclone-test-nadajek6rugo: 0 differences found 2026/02/22 01:45:02 NOTICE: B2 bucket rclone-test-nadajek6rugo: 1 matching files 2026/02/22 01:45:02 DEBUG : Creating backend with remote "TestB2:rclone-test-jotusim6qeze" 2026/02/22 01:45:02 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/22 01:45:02 DEBUG : Creating backend with remote "/tmp/rclone3991338830" run.go:185: Remote "B2 bucket rclone-test-jotusim6qeze", Local "Local file system at /tmp/rclone3991338830", Modify Window "1ms" upperlowermixed checkfile vs. upper remote (without normalization) 2026/02/22 01:45:05 ERROR : HELLO, WORLD!: sum not found 2026/02/22 01:45:05 ERROR : HeLlO, wOrLd!: file not in B2 bucket rclone-test-jotusim6qeze 2026/02/22 01:45:05 NOTICE: B2 bucket rclone-test-jotusim6qeze: 1 files missing 2026/02/22 01:45:05 NOTICE: 1 hashes missing 2026/02/22 01:45:05 NOTICE: B2 bucket rclone-test-jotusim6qeze: 1 differences found 2026/02/22 01:45:05 NOTICE: B2 bucket rclone-test-jotusim6qeze: 2 errors while checking upperlowermixed checkfile vs. upper remote (with normalization) 2026/02/22 01:45:06 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/02/22 01:45:06 NOTICE: B2 bucket rclone-test-jotusim6qeze: 0 differences found 2026/02/22 01:45:06 NOTICE: B2 bucket rclone-test-jotusim6qeze: 1 matching files 2026/02/22 01:45:06 DEBUG : Creating backend with remote "TestB2:rclone-test-jurinaw4teja" 2026/02/22 01:45:06 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/22 01:45:06 DEBUG : Creating backend with remote "/tmp/rclone2764772004" run.go:185: Remote "B2 bucket rclone-test-jurinaw4teja", Local "Local file system at /tmp/rclone2764772004", Modify Window "1ms" NFD checkfile vs. NFC remote (without normalization) 2026/02/22 01:45:08 ERROR : 測試_Русский___ě_áñ: sum not found 2026/02/22 01:45:08 ERROR : 測試_Русский___ě_áñ: file not in B2 bucket rclone-test-jurinaw4teja 2026/02/22 01:45:08 NOTICE: B2 bucket rclone-test-jurinaw4teja: 1 files missing 2026/02/22 01:45:08 NOTICE: 1 hashes missing 2026/02/22 01:45:08 NOTICE: B2 bucket rclone-test-jurinaw4teja: 1 differences found 2026/02/22 01:45:08 NOTICE: B2 bucket rclone-test-jurinaw4teja: 2 errors while checking NFD checkfile vs. NFC remote (with normalization) 2026/02/22 01:45:09 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/02/22 01:45:09 NOTICE: B2 bucket rclone-test-jurinaw4teja: 0 differences found 2026/02/22 01:45:09 NOTICE: B2 bucket rclone-test-jurinaw4teja: 1 matching files 2026/02/22 01:45:09 DEBUG : Creating backend with remote "TestB2:rclone-test-durudem2bepa" 2026/02/22 01:45:09 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/22 01:45:10 DEBUG : Creating backend with remote "/tmp/rclone2967128017" run.go:185: Remote "B2 bucket rclone-test-durudem2bepa", Local "Local file system at /tmp/rclone2967128017", Modify Window "1ms" NFC checkfile vs. NFD remote (without normalization) 2026/02/22 01:45:12 ERROR : 測試_Русский___ě_áñ: sum not found 2026/02/22 01:45:12 ERROR : 測試_Русский___ě_áñ: file not in B2 bucket rclone-test-durudem2bepa 2026/02/22 01:45:12 NOTICE: B2 bucket rclone-test-durudem2bepa: 1 files missing 2026/02/22 01:45:12 NOTICE: 1 hashes missing 2026/02/22 01:45:12 NOTICE: B2 bucket rclone-test-durudem2bepa: 1 differences found 2026/02/22 01:45:12 NOTICE: B2 bucket rclone-test-durudem2bepa: 2 errors while checking NFC checkfile vs. NFD remote (with normalization) 2026/02/22 01:45:13 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/02/22 01:45:13 NOTICE: B2 bucket rclone-test-durudem2bepa: 0 differences found 2026/02/22 01:45:13 NOTICE: B2 bucket rclone-test-durudem2bepa: 1 matching files 2026/02/22 01:45:13 DEBUG : Creating backend with remote "TestB2:rclone-test-tabedek1vugo" 2026/02/22 01:45:13 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/22 01:45:13 DEBUG : Creating backend with remote "/tmp/rclone3379748731" run.go:185: Remote "B2 bucket rclone-test-tabedek1vugo", Local "Local file system at /tmp/rclone3379748731", Modify Window "1ms" NFDx2 checkfile vs. both remote (without normalization) 2026/02/22 01:45:16 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/02/22 01:45:16 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-tabedek1vugo 2026/02/22 01:45:16 NOTICE: B2 bucket rclone-test-tabedek1vugo: 1 files missing 2026/02/22 01:45:16 NOTICE: 1 hashes missing 2026/02/22 01:45:16 NOTICE: B2 bucket rclone-test-tabedek1vugo: 1 differences found 2026/02/22 01:45:16 NOTICE: B2 bucket rclone-test-tabedek1vugo: 2 errors while checking NFDx2 checkfile vs. both remote (with normalization) 2026/02/22 01:45:16 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/02/22 01:45:16 NOTICE: B2 bucket rclone-test-tabedek1vugo: 0 differences found 2026/02/22 01:45:16 NOTICE: B2 bucket rclone-test-tabedek1vugo: 1 matching files 2026/02/22 01:45:16 DEBUG : Creating backend with remote "TestB2:rclone-test-giwimer7pifa" 2026/02/22 01:45:16 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/22 01:45:17 DEBUG : Creating backend with remote "/tmp/rclone304374722" run.go:185: Remote "B2 bucket rclone-test-giwimer7pifa", Local "Local file system at /tmp/rclone304374722", Modify Window "1ms" NFCx2 checkfile vs. both remote (without normalization) 2026/02/22 01:45:19 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/02/22 01:45:19 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-giwimer7pifa 2026/02/22 01:45:19 NOTICE: B2 bucket rclone-test-giwimer7pifa: 1 files missing 2026/02/22 01:45:19 NOTICE: 1 hashes missing 2026/02/22 01:45:19 NOTICE: B2 bucket rclone-test-giwimer7pifa: 1 differences found 2026/02/22 01:45:19 NOTICE: B2 bucket rclone-test-giwimer7pifa: 2 errors while checking NFCx2 checkfile vs. both remote (with normalization) 2026/02/22 01:45:20 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/02/22 01:45:20 NOTICE: B2 bucket rclone-test-giwimer7pifa: 0 differences found 2026/02/22 01:45:20 NOTICE: B2 bucket rclone-test-giwimer7pifa: 1 matching files 2026/02/22 01:45:20 DEBUG : Creating backend with remote "TestB2:rclone-test-deraval2nedi" 2026/02/22 01:45:20 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/22 01:45:20 DEBUG : Creating backend with remote "/tmp/rclone278028122" run.go:185: Remote "B2 bucket rclone-test-deraval2nedi", Local "Local file system at /tmp/rclone278028122", Modify Window "1ms" both checkfile vs. NFDx2 remote (without normalization) 2026/02/22 01:45:22 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/02/22 01:45:22 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-deraval2nedi 2026/02/22 01:45:22 NOTICE: B2 bucket rclone-test-deraval2nedi: 1 files missing 2026/02/22 01:45:22 NOTICE: 1 hashes missing 2026/02/22 01:45:22 NOTICE: B2 bucket rclone-test-deraval2nedi: 1 differences found 2026/02/22 01:45:22 NOTICE: B2 bucket rclone-test-deraval2nedi: 2 errors while checking both checkfile vs. NFDx2 remote (with normalization) 2026/02/22 01:45:23 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/02/22 01:45:23 NOTICE: B2 bucket rclone-test-deraval2nedi: 0 differences found 2026/02/22 01:45:23 NOTICE: B2 bucket rclone-test-deraval2nedi: 1 matching files 2026/02/22 01:45:23 DEBUG : Creating backend with remote "TestB2:rclone-test-tixuxac7cale" 2026/02/22 01:45:23 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/22 01:45:24 DEBUG : Creating backend with remote "/tmp/rclone2820215310" run.go:185: Remote "B2 bucket rclone-test-tixuxac7cale", Local "Local file system at /tmp/rclone2820215310", Modify Window "1ms" both checkfile vs. NFCx2 remote (without normalization) 2026/02/22 01:45:26 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/02/22 01:45:26 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-tixuxac7cale 2026/02/22 01:45:26 NOTICE: B2 bucket rclone-test-tixuxac7cale: 1 files missing 2026/02/22 01:45:26 NOTICE: 1 hashes missing 2026/02/22 01:45:26 NOTICE: B2 bucket rclone-test-tixuxac7cale: 1 differences found 2026/02/22 01:45:26 NOTICE: B2 bucket rclone-test-tixuxac7cale: 2 errors while checking both checkfile vs. NFCx2 remote (with normalization) 2026/02/22 01:45:26 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/02/22 01:45:26 NOTICE: B2 bucket rclone-test-tixuxac7cale: 0 differences found 2026/02/22 01:45:26 NOTICE: B2 bucket rclone-test-tixuxac7cale: 1 matching files 2026/02/22 01:45:26 DEBUG : B2 bucket rclone-test-tixuxac7cale: Purge remote 2026/02/22 01:45:26 INFO : B2 bucket rclone-test-tixuxac7cale: cleaning bucket "rclone-test-tixuxac7cale" of all files 2026/02/22 01:45:27 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_z3f12dd93005d8b3b9fc80e1b_f1192ff7ea369f3dc_d20260222_m014525_c001_v0001093_t0038_u01771724725589") 2026/02/22 01:45:27 DEBUG : B2 bucket rclone-test-deraval2nedi: Purge remote 2026/02/22 01:45:27 INFO : B2 bucket rclone-test-deraval2nedi: cleaning bucket "rclone-test-deraval2nedi" of all files 2026/02/22 01:45:27 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_z1f72cdc3005d8b3b9fc80e1b_f11666fa17c2173e5_d20260222_m014522_c001_v0001184_t0052_u01771724722299") 2026/02/22 01:45:28 DEBUG : B2 bucket rclone-test-giwimer7pifa: Purge remote 2026/02/22 01:45:28 INFO : B2 bucket rclone-test-giwimer7pifa: cleaning bucket "rclone-test-giwimer7pifa" of all files 2026/02/22 01:45:29 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_zef42bde3005d8b3b9fc80e1b_f111d3e0f457804d6_d20260222_m014518_c001_v0001157_t0025_u01771724718775") 2026/02/22 01:45:29 DEBUG : B2 bucket rclone-test-tabedek1vugo: Purge remote 2026/02/22 01:45:29 INFO : B2 bucket rclone-test-tabedek1vugo: cleaning bucket "rclone-test-tabedek1vugo" of all files 2026/02/22 01:45:30 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_z8fd2bd13005d8b3b9fc80e1b_f1163b70ca4bfa7b2_d20260222_m014515_c001_v0001101_t0052_u01771724715369") 2026/02/22 01:45:30 DEBUG : B2 bucket rclone-test-durudem2bepa: Purge remote 2026/02/22 01:45:30 INFO : B2 bucket rclone-test-durudem2bepa: cleaning bucket "rclone-test-durudem2bepa" of all files 2026/02/22 01:45:31 DEBUG : 測試_Русский___ě_áñ: Deleting (id "4_zbf52ad23005d8b3b9fc80e1b_f103cb1af7267633e_d20260222_m014511_c001_v0001033_t0019_u01771724711618") 2026/02/22 01:45:32 DEBUG : B2 bucket rclone-test-jurinaw4teja: Purge remote 2026/02/22 01:45:32 INFO : B2 bucket rclone-test-jurinaw4teja: cleaning bucket "rclone-test-jurinaw4teja" of all files 2026/02/22 01:45:32 DEBUG : 測試_Русский___ě_áñ: Deleting (id "4_z9f429d53005d8b3b9fc80e1b_f1161dbc4fad2d36b_d20260222_m014508_c001_v0001161_t0056_u01771724708244") 2026/02/22 01:45:33 DEBUG : B2 bucket rclone-test-jotusim6qeze: Purge remote 2026/02/22 01:45:33 INFO : B2 bucket rclone-test-jotusim6qeze: cleaning bucket "rclone-test-jotusim6qeze" of all files 2026/02/22 01:45:33 DEBUG : HELLO, WORLD!: Deleting (id "4_z2fa28d63005d8b3b9fc80e1b_f11032b20f33d8144_d20260222_m014504_c001_v0001167_t0033_u01771724704264") 2026/02/22 01:45:34 DEBUG : B2 bucket rclone-test-nadajek6rugo: Purge remote 2026/02/22 01:45:34 INFO : B2 bucket rclone-test-nadajek6rugo: cleaning bucket "rclone-test-nadajek6rugo" of all files 2026/02/22 01:45:35 DEBUG : HeLlO, wOrLd!: Deleting (id "4_z4fe27d93005d8b3b9fc80e1b_f118fd441ffc1b6d1_d20260222_m014500_c001_v0001105_t0030_u01771724700923") 2026/02/22 01:45:35 DEBUG : B2 bucket rclone-test-lusivar7tosi: Purge remote 2026/02/22 01:45:35 INFO : B2 bucket rclone-test-lusivar7tosi: cleaning bucket "rclone-test-lusivar7tosi" of all files 2026/02/22 01:45:36 DEBUG : HELLO, WORLD!: Deleting (id "4_zcf126d93005d8b3b9fc80e1b_f1095abe6fb8fc0e4_d20260222_m014457_c001_v0001173_t0057_u01771724697081") 2026/02/22 01:45:37 DEBUG : B2 bucket rclone-test-vepudaz7lami: Purge remote 2026/02/22 01:45:37 INFO : B2 bucket rclone-test-vepudaz7lami: cleaning bucket "rclone-test-vepudaz7lami" of all files 2026/02/22 01:45:37 DEBUG : hello, world!: Deleting (id "4_z0fc25dd3005d8b3b9fc80e1b_f111aa99ca6cffa63_d20260222_m014453_c001_v0001184_t0001_u01771724693735") --- PASS: TestApplyTransforms (46.45s) === RUN TestTruncateString --- PASS: TestTruncateString (0.00s) === RUN TestCopyFile run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" 2026/02/22 01:45:38 DEBUG : file1: Need to transfer - File not found at Destination 2026/02/22 01:45:39 DEBUG : sub/file2: size = 14 OK 2026/02/22 01:45:39 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2026/02/22 01:45:39 INFO : file1: Copied (new) to: sub/file2 2026/02/22 01:45:40 DEBUG : sub/file2: size = 14 OK 2026/02/22 01:45:40 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/02/22 01:45:40 DEBUG : file1: Unchanged skipping 2026/02/22 01:45:40 DEBUG : B2 bucket rclone-test-zuxafas4qeho: don't need to copy/move sub/file2, it is already at target location --- PASS: TestCopyFile (3.00s) === RUN TestCopyLongFile run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" copy_test.go:154: Test only runs on local --- SKIP: TestCopyLongFile (0.36s) === RUN TestCopyFileBackupDir run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" 2026/02/22 01:45:42 DEBUG : Creating backend with remote "TestB2:rclone-test-zuxafas4qeho/backup" 2026/02/22 01:45:43 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone1368031187) 2026/02/22 01:45:43 DEBUG : dst/file1: size = 18 (B2 bucket rclone-test-zuxafas4qeho) 2026/02/22 01:45:43 DEBUG : dst/file1: Sizes differ 2026/02/22 01:45:44 DEBUG : dst/file1: size = 18 OK 2026/02/22 01:45:44 DEBUG : dst/file1: sha1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK 2026/02/22 01:45:44 INFO : dst/file1: Copied (server-side copy) 2026/02/22 01:45:44 INFO : dst/file1: Deleted 2026/02/22 01:45:44 DEBUG : dst/file1: size = 14 OK 2026/02/22 01:45:44 DEBUG : dst/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2026/02/22 01:45:44 INFO : dst/file1: Copied (new) --- PASS: TestCopyFileBackupDir (4.23s) === RUN TestCopyFileCompareDest run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" 2026/02/22 01:45:45 DEBUG : Creating backend with remote "TestB2:rclone-test-zuxafas4qeho/dst" 2026/02/22 01:45:47 DEBUG : Creating backend with remote "TestB2:rclone-test-zuxafas4qeho/CompareDest" 2026/02/22 01:45:48 DEBUG : one: Need to transfer - File not found at Destination 2026/02/22 01:45:49 DEBUG : one: size = 3 OK 2026/02/22 01:45:49 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2026/02/22 01:45:49 INFO : one: Copied (new) 2026/02/22 01:45:50 DEBUG : one: size = 5 (Local file system at /tmp/rclone1368031187) 2026/02/22 01:45:50 DEBUG : one: size = 3 (B2 bucket rclone-test-zuxafas4qeho path dst) 2026/02/22 01:45:50 DEBUG : one: Sizes differ 2026/02/22 01:45:51 DEBUG : one: size = 5 OK 2026/02/22 01:45:51 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2026/02/22 01:45:51 INFO : one: Copied (replaced existing) 2026/02/22 01:45:52 DEBUG : one: size = 5 (Local file system at /tmp/rclone1368031187) 2026/02/22 01:45:52 DEBUG : one: size = 3 (B2 bucket rclone-test-zuxafas4qeho path dst) 2026/02/22 01:45:52 DEBUG : one: Sizes differ 2026/02/22 01:45:52 DEBUG : one: size = 5 OK 2026/02/22 01:45:52 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/02/22 01:45:52 DEBUG : one: Destination found in --compare-dest, skipping 2026/02/22 01:45:53 DEBUG : two: Need to transfer - File not found at Destination 2026/02/22 01:45:53 DEBUG : two: size = 3 OK 2026/02/22 01:45:53 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/02/22 01:45:53 DEBUG : two: Destination found in --compare-dest, skipping 2026/02/22 01:45:54 DEBUG : two: Need to transfer - File not found at Destination 2026/02/22 01:45:54 DEBUG : two: size = 3 OK 2026/02/22 01:45:54 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/02/22 01:45:54 DEBUG : two: Destination found in --compare-dest, skipping 2026/02/22 01:45:54 DEBUG : two: Need to transfer - File not found at Destination 2026/02/22 01:45:55 DEBUG : two: size = 5 (Local file system at /tmp/rclone1368031187) 2026/02/22 01:45:55 DEBUG : two: size = 3 (B2 bucket rclone-test-zuxafas4qeho path CompareDest) 2026/02/22 01:45:55 DEBUG : two: Sizes differ 2026/02/22 01:45:55 DEBUG : two: size = 5 OK 2026/02/22 01:45:55 DEBUG : two: sha1 = a4be8877ad88f3109a6411985a1f359e10e2d930 OK 2026/02/22 01:45:55 INFO : two: Copied (new) --- PASS: TestCopyFileCompareDest (10.82s) === RUN TestCopyFileCopyDest run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" 2026/02/22 01:45:56 DEBUG : Creating backend with remote "TestB2:rclone-test-zuxafas4qeho/dst" 2026/02/22 01:45:57 DEBUG : Creating backend with remote "TestB2:rclone-test-zuxafas4qeho/CopyDest" 2026/02/22 01:45:58 DEBUG : one: Need to transfer - File not found at Destination 2026/02/22 01:46:00 DEBUG : one: size = 3 OK 2026/02/22 01:46:00 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2026/02/22 01:46:00 INFO : one: Copied (new) 2026/02/22 01:46:01 DEBUG : one: size = 5 (Local file system at /tmp/rclone1368031187) 2026/02/22 01:46:01 DEBUG : one: size = 3 (B2 bucket rclone-test-zuxafas4qeho path dst) 2026/02/22 01:46:01 DEBUG : one: Sizes differ 2026/02/22 01:46:02 DEBUG : one: size = 5 OK 2026/02/22 01:46:02 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2026/02/22 01:46:02 INFO : one: Copied (replaced existing) 2026/02/22 01:46:03 DEBUG : Creating backend with remote "TestB2:rclone-test-zuxafas4qeho/BackupDir" 2026/02/22 01:46:04 DEBUG : one: size = 5 (Local file system at /tmp/rclone1368031187) 2026/02/22 01:46:04 DEBUG : one: size = 3 (B2 bucket rclone-test-zuxafas4qeho path dst) 2026/02/22 01:46:04 DEBUG : one: Sizes differ 2026/02/22 01:46:04 DEBUG : one: size = 5 OK 2026/02/22 01:46:04 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/02/22 01:46:04 DEBUG : one: size = 5 (Local file system at /tmp/rclone1368031187) 2026/02/22 01:46:04 DEBUG : one: size = 3 (B2 bucket rclone-test-zuxafas4qeho path dst) 2026/02/22 01:46:04 DEBUG : one: Sizes differ 2026/02/22 01:46:06 DEBUG : one: size = 3 OK 2026/02/22 01:46:06 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2026/02/22 01:46:06 INFO : one: Copied (server-side copy) 2026/02/22 01:46:06 INFO : one: Deleted 2026/02/22 01:46:06 DEBUG : one: size = 5 OK 2026/02/22 01:46:06 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2026/02/22 01:46:06 INFO : one: Copied (server-side copy) 2026/02/22 01:46:06 DEBUG : one: Destination found in --copy-dest, using server-side copy 2026/02/22 01:46:07 DEBUG : two: Need to transfer - File not found at Destination 2026/02/22 01:46:07 DEBUG : two: size = 3 OK 2026/02/22 01:46:07 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/02/22 01:46:07 DEBUG : two: size = 3 OK 2026/02/22 01:46:07 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2026/02/22 01:46:07 INFO : two: Copied (server-side copy) 2026/02/22 01:46:07 DEBUG : two: Destination found in --copy-dest, using server-side copy 2026/02/22 01:46:08 DEBUG : two: size = 3 OK 2026/02/22 01:46:08 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/02/22 01:46:08 DEBUG : two: Unchanged skipping 2026/02/22 01:46:09 DEBUG : three: Need to transfer - File not found at Destination 2026/02/22 01:46:09 DEBUG : three: size = 7 (Local file system at /tmp/rclone1368031187) 2026/02/22 01:46:09 DEBUG : three: size = 5 (B2 bucket rclone-test-zuxafas4qeho path CopyDest) 2026/02/22 01:46:09 DEBUG : three: Sizes differ 2026/02/22 01:46:09 DEBUG : three: Destination not found in --copy-dest 2026/02/22 01:46:10 DEBUG : three: size = 7 OK 2026/02/22 01:46:10 DEBUG : three: sha1 = 370e613335f3cf2f19b2a2d35a86402f55c659f8 OK 2026/02/22 01:46:10 INFO : three: Copied (new) --- PASS: TestCopyFileCopyDest (15.26s) === RUN TestCopyInplace run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" copy_test.go:371: Partial uploads not supported --- SKIP: TestCopyInplace (0.36s) === RUN TestCopyLongFileName run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" copy_test.go:404: Partial uploads not supported --- SKIP: TestCopyLongFileName (0.36s) === RUN TestCopyLongFileNameCollision run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" copy_test.go:437: Partial uploads not supported --- SKIP: TestCopyLongFileNameCollision (0.36s) === RUN TestCopyFileMaxTransfer run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" 2026/02/22 01:46:13 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination 2026/02/22 01:46:13 DEBUG : TestCopyFileMaxTransfer/file1: size = 14 OK 2026/02/22 01:46:13 DEBUG : TestCopyFileMaxTransfer/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2026/02/22 01:46:13 INFO : TestCopyFileMaxTransfer/file1: Copied (new) 2026/02/22 01:46:14 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination 2026/02/22 01:46:14 ERROR : TestCopyFileMaxTransfer/file2: Failed to copy: Post "https://pod-000-1179-10.backblaze.com/b2api/v1/b2_upload_file/5f42ed03003d8b3b9fc80e1b/c001_v0001179_t0041": max transfer limit reached as set by --max-transfer 2026/02/22 01:46:14 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination 2026/02/22 01:46:14 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination 2026/02/22 01:46:15 DEBUG : TestCopyFileMaxTransfer/file4: size = 2062 OK 2026/02/22 01:46:15 DEBUG : TestCopyFileMaxTransfer/file4: sha1 = 1e68494e87692d1521d3fc8d91e137d2050999ab OK 2026/02/22 01:46:15 INFO : TestCopyFileMaxTransfer/file4: Copied (new) --- PASS: TestCopyFileMaxTransfer (3.37s) === RUN TestDeduplicateInteractive run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateInteractive (0.36s) === RUN TestDeduplicateSkip run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSkip (0.36s) === RUN TestDeduplicateSizeOnly run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSizeOnly (0.36s) === RUN TestDeduplicateFirst run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateFirst (0.36s) === RUN TestDeduplicateNewest run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateNewest (0.38s) === RUN TestDeduplicateNewestByHash run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" 2026/02/22 01:46:19 INFO : B2 bucket rclone-test-zuxafas4qeho: Looking for duplicate sha1 hashes using newest mode. 2026/02/22 01:46:19 NOTICE: a9f6beb249cdff3cf710420114cab71b3316bfa9: Found 3 files with duplicate sha1 hashes 2026/02/22 01:46:19 INFO : one: Deleted 2026/02/22 01:46:19 INFO : also/one: Deleted 2026/02/22 01:46:19 NOTICE: a9f6beb249cdff3cf710420114cab71b3316bfa9: Deleted 2 extra copies --- PASS: TestDeduplicateNewestByHash (2.54s) === RUN TestDeduplicateOldest run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateOldest (0.36s) === RUN TestDeduplicateLargest run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateLargest (0.36s) === RUN TestDeduplicateSmallest run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSmallest (0.36s) === RUN TestDeduplicateRename run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateRename (0.36s) === RUN TestMergeDirs run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" dedupe_test.go:256: Can't merge directories --- SKIP: TestMergeDirs (0.36s) === RUN TestListDirSorted run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" 2026/02/22 01:46:24 DEBUG : a.txt: Excluded (Size Filter) 2026/02/22 01:46:24 DEBUG : a.txt: Excluded 2026/02/22 01:46:24 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/02/22 01:46:24 DEBUG : sub dir/hello world: Excluded 2026/02/22 01:46:24 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/02/22 01:46:24 DEBUG : sub dir/hello world2: Excluded 2026/02/22 01:46:25 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/02/22 01:46:25 DEBUG : sub dir/hello world: Excluded 2026/02/22 01:46:25 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/02/22 01:46:25 DEBUG : sub dir/hello world2: Excluded 2026/02/22 01:46:25 DEBUG : sub dir/ignore dir: Excluded 2026/02/22 01:46:26 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSorted (5.95s) === RUN TestListDirSortedFn run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" 2026/02/22 01:46:30 DEBUG : a.txt: Excluded (Size Filter) 2026/02/22 01:46:30 DEBUG : a.txt: Excluded 2026/02/22 01:46:30 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/02/22 01:46:30 DEBUG : sub dir/hello world: Excluded 2026/02/22 01:46:30 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/02/22 01:46:30 DEBUG : sub dir/hello world2: Excluded 2026/02/22 01:46:31 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/02/22 01:46:31 DEBUG : sub dir/hello world: Excluded 2026/02/22 01:46:31 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/02/22 01:46:31 DEBUG : sub dir/hello world2: Excluded 2026/02/22 01:46:31 DEBUG : sub dir/ignore dir: Excluded 2026/02/22 01:46:31 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSortedFn (5.52s) === RUN TestListJSON run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", 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/02/22 01:46:36 ERROR : file1: Failed to read hash: hash type not supported === RUN TestListJSON/Metadata --- PASS: TestListJSON (3.18s) --- PASS: TestListJSON/Default (0.18s) --- PASS: TestListJSON/FilesOnly (0.18s) --- PASS: TestListJSON/DirsOnly (0.19s) --- PASS: TestListJSON/Recurse (0.18s) --- PASS: TestListJSON/SubDir (0.18s) --- PASS: TestListJSON/NoModTime (0.18s) --- PASS: TestListJSON/NoMimeType (0.18s) --- PASS: TestListJSON/ShowHash (0.18s) --- PASS: TestListJSON/HashTypes (0.18s) --- PASS: TestListJSON/Metadata (0.18s) === RUN TestStatJSON run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", 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/02/22 01:46:40 DEBUG : Creating backend with remote "TestB2:rclone-test-zuxafas4qeho/notfound" --- PASS: TestStatJSON (6.11s) --- PASS: TestStatJSON/Root (0.18s) --- PASS: TestStatJSON/RootFilesOnly (0.00s) --- PASS: TestStatJSON/RootDirsOnly (0.18s) --- PASS: TestStatJSON/Dir (0.73s) --- PASS: TestStatJSON/DirWithTrailingSlash (0.18s) --- PASS: TestStatJSON/File (0.18s) --- PASS: TestStatJSON/NotFound (0.36s) --- PASS: TestStatJSON/DirFilesOnly (0.18s) --- PASS: TestStatJSON/FileFilesOnly (0.18s) --- PASS: TestStatJSON/NotFoundFilesOnly (0.18s) --- PASS: TestStatJSON/DirDirsOnly (0.18s) --- PASS: TestStatJSON/FileDirsOnly (0.18s) --- PASS: TestStatJSON/NotFoundDirsOnly (0.18s) --- PASS: TestStatJSON/RootNotFound (1.81s) === RUN TestMkdir run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" 2026/02/22 01:46:43 INFO : B2 bucket rclone-test-zuxafas4qeho: Making directory 2026/02/22 01:46:43 INFO : B2 bucket rclone-test-zuxafas4qeho: Making directory --- PASS: TestMkdir (0.54s) === RUN TestLsd run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" --- PASS: TestLsd (1.50s) === RUN TestLs run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" --- PASS: TestLs (1.55s) === RUN TestLsWithFilesFrom run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" 2026/02/22 01:46:47 DEBUG : empty space: Excluded (FilesFrom Filter) 2026/02/22 01:46:47 DEBUG : empty space: Excluded --- PASS: TestLsWithFilesFrom (2.06s) === RUN TestLsLong run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" --- PASS: TestLsLong (1.50s) === RUN TestHashSums run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" === RUN TestHashSums/Sha1 === RUN TestHashSums/Sha1Download --- PASS: TestHashSums (1.95s) --- PASS: TestHashSums/Sha1 (0.18s) --- PASS: TestHashSums/Sha1Download (0.37s) === RUN TestHashSumsWithErrors 2026/02/22 01:46:52 DEBUG : Creating backend with remote ":memory:" 2026/02/22 01:46:52 ERROR : file1: hash unsupported: hash type not supported --- PASS: TestHashSumsWithErrors (0.00s) === RUN TestHashStream 2026/02/22 01:46:52 DEBUG : Creating md5 hash of 0 bytes read from input stream 2026/02/22 01:46:52 DEBUG : Creating md5 hash of 0 bytes read from input stream 2026/02/22 01:46:52 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2026/02/22 01:46:52 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2026/02/22 01:46:52 DEBUG : Creating md5 hash of 12 bytes read from input stream 2026/02/22 01:46:52 DEBUG : Creating md5 hash of 12 bytes read from input stream 2026/02/22 01:46:52 DEBUG : Creating sha1 hash of 12 bytes read from input stream 2026/02/22 01:46:52 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:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" --- PASS: TestCount (1.90s) === RUN TestDelete run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" 2026/02/22 01:46:55 DEBUG : Waiting for deletions to finish 2026/02/22 01:46:55 DEBUG : large: Excluded (Size Filter) 2026/02/22 01:46:55 INFO : medium: Deleted 2026/02/22 01:46:55 INFO : small: Deleted --- PASS: TestDelete (2.28s) === RUN TestMaxDelete run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" 2026/02/22 01:46:57 DEBUG : Waiting for deletions to finish 2026/02/22 01:46:57 ERROR : large: Got fatal error on delete: --max-delete threshold reached 2026/02/22 01:46:57 INFO : medium: Deleted 2026/02/22 01:46:57 INFO : small: Deleted --- PASS: TestMaxDelete (2.44s) === RUN TestMaxDeleteSizeLargeFile run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" 2026/02/22 01:46:59 DEBUG : Waiting for deletions to finish 2026/02/22 01:47:00 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached 2026/02/22 01:47:00 INFO : medium: Deleted 2026/02/22 01:47:00 INFO : small: Deleted --- PASS: TestMaxDeleteSizeLargeFile (2.43s) === RUN TestMaxDeleteSize run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" 2026/02/22 01:47:02 DEBUG : Waiting for deletions to finish 2026/02/22 01:47:02 ERROR : medium: Got fatal error on delete: --max-delete-size threshold reached 2026/02/22 01:47:02 INFO : large: Deleted 2026/02/22 01:47:02 INFO : small: Deleted --- PASS: TestMaxDeleteSize (2.38s) === RUN TestReadFile run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" --- PASS: TestReadFile (2.26s) === RUN TestRetry 2026/02/22 01:47:06 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 1/5 2026/02/22 01:47:06 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 2/5 2026/02/22 01:47:06 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2026/02/22 01:47:06 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2026/02/22 01:47:06 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2026/02/22 01:47:06 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2026/02/22 01:47:06 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:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" --- PASS: TestCat (4.10s) === RUN TestPurge 2026/02/22 01:47:10 DEBUG : Creating backend with remote "TestB2:rclone-test-xotuxil3waso" 2026/02/22 01:47:10 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/22 01:47:10 DEBUG : Creating backend with remote "/tmp/rclone1710772567" run.go:185: Remote "B2 bucket rclone-test-xotuxil3waso", Local "Local file system at /tmp/rclone1710772567", Modify Window "1ms" 2026/02/22 01:47:12 INFO : A2: Making directory 2026/02/22 01:47:12 INFO : A1/B2: Making directory 2026/02/22 01:47:12 INFO : A1/B2/C2: Making directory 2026/02/22 01:47:12 INFO : A1/B1/C3: Making directory 2026/02/22 01:47:12 INFO : A3: Making directory 2026/02/22 01:47:12 INFO : A3/B3: Making directory 2026/02/22 01:47:12 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/02/22 01:47:12 INFO : B2 bucket rclone-test-xotuxil3waso: cleaning bucket "rclone-test-xotuxil3waso" of all files 2026/02/22 01:47:13 DEBUG : A1/B1/C1/one: Deleting (id "4_zdfb27d93007d8b3b9fc80e1b_f10799b189b618ff6_d20260222_m014712_c001_v0001134_t0042_u01771724832280") 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/02/22 01:47:13 INFO : B2 bucket rclone-test-xotuxil3waso: cleaning bucket "rclone-test-xotuxil3waso" of all files 2026/02/22 01:47:13 DEBUG : A1/two: Deleting (id "4_zdfb27d93007d8b3b9fc80e1b_f10799b189b618ff8_d20260222_m014712_c001_v0001134_t0053_u01771724832583") 2026/02/22 01:47:14 DEBUG : B2 bucket rclone-test-xotuxil3waso: Purge remote 2026/02/22 01:47:14 INFO : B2 bucket rclone-test-xotuxil3waso: cleaning bucket "rclone-test-xotuxil3waso" of all files 2026/02/22 01:47:14 NOTICE: purge failed: directory not found --- PASS: TestPurge (4.51s) === RUN TestRmdirsNoLeaveRoot run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" 2026/02/22 01:47:15 INFO : A2: Making directory 2026/02/22 01:47:15 INFO : A1/B2: Making directory 2026/02/22 01:47:15 INFO : A1/B2/C2: Making directory 2026/02/22 01:47:15 INFO : A1/B1/C3: Making directory 2026/02/22 01:47:15 INFO : A3: Making directory 2026/02/22 01:47:15 INFO : A3/B3: Making directory 2026/02/22 01:47:15 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/02/22 01:47:16 DEBUG : removing 1 level 3 directories 2026/02/22 01:47:16 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/02/22 01:47:18 DEBUG : removing 1 level 0 directories 2026/02/22 01:47:18 INFO : B2 bucket rclone-test-zuxafas4qeho: Removing directory 2026/02/22 01:47:18 ERROR : Failed to rmdir: failed to delete bucket: Cannot delete non-empty bucket (400 cannot_delete_non_empty_bucket) operations_test.go:728: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:728 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 --- FAIL: TestRmdirsNoLeaveRoot (4.57s) === RUN TestRmdirsLeaveRoot run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" 2026/02/22 01:47:19 INFO : A1: Making directory 2026/02/22 01:47:19 INFO : A1/B1: Making directory 2026/02/22 01:47:19 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.92s) === RUN TestRmdirsWithFilter run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" 2026/02/22 01:47:20 INFO : A1: Making directory 2026/02/22 01:47:20 INFO : A1/B1: Making directory 2026/02/22 01:47:20 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.91s) === RUN TestCopyURL run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" 2026/02/22 01:47:22 DEBUG : filename.txt: File name found in url 2026/02/22 01:47:22 DEBUG : headerfilename.txt: filename found in Content-Disposition header. --- PASS: TestCopyURL (3.05s) === RUN TestCopyURLToWriter --- PASS: TestCopyURLToWriter (0.00s) === RUN TestMoveFile run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" 2026/02/22 01:47:24 DEBUG : file1: Need to transfer - File not found at Destination 2026/02/22 01:47:24 DEBUG : sub/file2: size = 14 OK 2026/02/22 01:47:24 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2026/02/22 01:47:24 INFO : file1: Copied (new) to: sub/file2 2026/02/22 01:47:24 INFO : file1: Deleted 2026/02/22 01:47:24 DEBUG : sub/file2: size = 14 OK 2026/02/22 01:47:24 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/02/22 01:47:24 DEBUG : file1: Unchanged skipping 2026/02/22 01:47:24 INFO : file1: Deleted 2026/02/22 01:47:25 DEBUG : B2 bucket rclone-test-zuxafas4qeho: don't need to copy/move sub/file2, it is already at target location --- PASS: TestMoveFile (1.68s) === RUN TestMoveFileWithIgnoreExisting run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" 2026/02/22 01:47:25 DEBUG : file1: Need to transfer - File not found at Destination 2026/02/22 01:47:26 DEBUG : file1: size = 14 OK 2026/02/22 01:47:26 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2026/02/22 01:47:26 INFO : file1: Copied (new) 2026/02/22 01:47:26 INFO : file1: Deleted 2026/02/22 01:47:27 DEBUG : file1: Destination exists, skipping 2026/02/22 01:47:27 DEBUG : file1: Not removing source file as destination file exists and --ignore-existing is set --- PASS: TestMoveFileWithIgnoreExisting (1.95s) === RUN TestCaseInsensitiveMoveFile run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFile (0.37s) === RUN TestCaseInsensitiveMoveFileDryRun run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFileDryRun (0.37s) === RUN TestMoveFileBackupDir run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" 2026/02/22 01:47:29 DEBUG : Creating backend with remote "TestB2:rclone-test-zuxafas4qeho/backup" 2026/02/22 01:47:30 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone1368031187) 2026/02/22 01:47:30 DEBUG : dst/file1: size = 18 (B2 bucket rclone-test-zuxafas4qeho) 2026/02/22 01:47:30 DEBUG : dst/file1: Sizes differ 2026/02/22 01:47:32 DEBUG : dst/file1: size = 18 OK 2026/02/22 01:47:32 DEBUG : dst/file1: sha1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK 2026/02/22 01:47:32 INFO : dst/file1: Copied (server-side copy) 2026/02/22 01:47:32 INFO : dst/file1: Deleted 2026/02/22 01:47:32 DEBUG : dst/file1: size = 14 OK 2026/02/22 01:47:32 DEBUG : dst/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2026/02/22 01:47:32 INFO : dst/file1: Copied (new) 2026/02/22 01:47:32 INFO : dst/file1: Deleted --- PASS: TestMoveFileBackupDir (4.95s) === 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:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" 2026/02/22 01:47:34 INFO : A1/B2: Making directory 2026/02/22 01:47:34 INFO : A1/B1/C3: Making directory fstest.go:250: Filtering empty directory "A1/B2" fstest.go:250: Filtering empty directory "A1/B1/C3" 2026/02/22 01:47:35 DEBUG : A2/B1/C1/four: size = 4 OK 2026/02/22 01:47:35 DEBUG : A1/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2026/02/22 01:47:35 INFO : A1/B1/C1/four: Copied (server-side copy) to: A2/B1/C1/four 2026/02/22 01:47:36 INFO : A1/B1/C1/four: Deleted 2026/02/22 01:47:36 DEBUG : A2/two: size = 3 OK 2026/02/22 01:47:36 DEBUG : A1/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2026/02/22 01:47:36 INFO : A1/two: Copied (server-side copy) to: A2/two 2026/02/22 01:47:36 DEBUG : A2/B1/three: size = 5 OK 2026/02/22 01:47:36 DEBUG : A1/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2026/02/22 01:47:36 INFO : A1/B1/three: Copied (server-side copy) to: A2/B1/three 2026/02/22 01:47:36 DEBUG : A2/B1/C2/five: size = 4 OK 2026/02/22 01:47:36 DEBUG : A1/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2026/02/22 01:47:36 INFO : A1/B1/C2/five: Copied (server-side copy) to: A2/B1/C2/five 2026/02/22 01:47:36 INFO : A1/two: Deleted 2026/02/22 01:47:36 INFO : A1/B1/three: Deleted 2026/02/22 01:47:36 INFO : A1/B1/C2/five: Deleted 2026/02/22 01:47:36 DEBUG : A2/one: size = 3 OK 2026/02/22 01:47:36 DEBUG : A1/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2026/02/22 01:47:36 INFO : A1/one: Copied (server-side copy) to: A2/one 2026/02/22 01:47:36 INFO : A1/one: Deleted fstest.go:250: Filtering empty directory "A2/B2" fstest.go:250: Filtering empty directory "A2/B1/C3" 2026/02/22 01:47:37 DEBUG : A3/B1/C2/five: size = 4 OK 2026/02/22 01:47:37 DEBUG : A2/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2026/02/22 01:47:37 INFO : A2/B1/C2/five: Copied (server-side copy) to: A3/B1/C2/five 2026/02/22 01:47:37 DEBUG : A3/two: size = 3 OK 2026/02/22 01:47:37 DEBUG : A2/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2026/02/22 01:47:37 INFO : A2/two: Copied (server-side copy) to: A3/two 2026/02/22 01:47:37 DEBUG : A3/one: size = 3 OK 2026/02/22 01:47:37 DEBUG : A2/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2026/02/22 01:47:37 INFO : A2/one: Copied (server-side copy) to: A3/one 2026/02/22 01:47:37 INFO : A2/B1/C2/five: Deleted 2026/02/22 01:47:37 INFO : A2/two: Deleted 2026/02/22 01:47:37 INFO : A2/one: Deleted 2026/02/22 01:47:38 DEBUG : A3/B1/three: size = 5 OK 2026/02/22 01:47:38 DEBUG : A2/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2026/02/22 01:47:38 INFO : A2/B1/three: Copied (server-side copy) to: A3/B1/three 2026/02/22 01:47:38 INFO : A2/B1/three: Deleted 2026/02/22 01:47:39 DEBUG : A3/B1/C1/four: size = 4 OK 2026/02/22 01:47:39 DEBUG : A2/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2026/02/22 01:47:39 INFO : A2/B1/C1/four: Copied (server-side copy) to: A3/B1/C1/four 2026/02/22 01:47:39 INFO : A2/B1/C1/four: Deleted fstest.go:250: Filtering empty directory "A3/B2" fstest.go:250: Filtering empty directory "A3/B1/C3" 2026/02/22 01:47:40 INFO : B2 bucket rclone-test-zuxafas4qeho: Can't DirMove - falling back to file moves: can't move directory - incompatible remotes 2026/02/22 01:47:40 DEBUG : A4/B1/three: size = 5 OK 2026/02/22 01:47:40 DEBUG : A3/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2026/02/22 01:47:40 INFO : A3/B1/three: Copied (server-side copy) to: A4/B1/three 2026/02/22 01:47:40 DEBUG : A4/two: size = 3 OK 2026/02/22 01:47:40 DEBUG : A3/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2026/02/22 01:47:40 INFO : A3/two: Copied (server-side copy) to: A4/two 2026/02/22 01:47:40 DEBUG : A4/one: size = 3 OK 2026/02/22 01:47:40 DEBUG : A3/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2026/02/22 01:47:40 INFO : A3/one: Copied (server-side copy) to: A4/one 2026/02/22 01:47:40 DEBUG : A4/B1/C1/four: size = 4 OK 2026/02/22 01:47:40 DEBUG : A3/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2026/02/22 01:47:40 INFO : A3/B1/C1/four: Copied (server-side copy) to: A4/B1/C1/four 2026/02/22 01:47:40 DEBUG : A4/B1/C2/five: size = 4 OK 2026/02/22 01:47:40 DEBUG : A3/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2026/02/22 01:47:40 INFO : A3/B1/C2/five: Copied (server-side copy) to: A4/B1/C2/five 2026/02/22 01:47:40 INFO : A3/B1/three: Deleted 2026/02/22 01:47:40 INFO : A3/two: Deleted 2026/02/22 01:47:41 INFO : A3/one: Deleted 2026/02/22 01:47:41 INFO : A3/B1/C1/four: Deleted 2026/02/22 01:47:41 INFO : A3/B1/C2/five: Deleted fstest.go:250: Filtering empty directory "A4/B2" fstest.go:250: Filtering empty directory "A4/B1/C3" --- PASS: TestDirMove (9.15s) === RUN TestGetFsInfo run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" --- PASS: TestGetFsInfo (0.36s) === RUN TestRcat === RUN TestRcat/withChecksum=false,ignoreChecksum=false run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" 2026/02/22 01:47:43 DEBUG : B2 bucket rclone-test-zuxafas4qeho: File to upload is small (34 bytes), uploading instead of streaming 2026/02/22 01:47:43 DEBUG : no_checksum_small_file_from_pipe: size = 34 OK 2026/02/22 01:47:43 DEBUG : no_checksum_small_file_from_pipe: sha1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK 2026/02/22 01:47:43 DEBUG : no_checksum_small_file_from_pipe: Size and sha1 of src and dst objects identical 2026/02/22 01:47:43 DEBUG : no_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2026/02/22 01:47:44 DEBUG : no_checksum_big_file_from_pipe: size = 102401 OK 2026/02/22 01:47:44 DEBUG : no_checksum_big_file_from_pipe: sha1 = d0080f73aa8b494570ad63df1beb25c01ea9eea2 OK 2026/02/22 01:47:44 DEBUG : no_checksum_big_file_from_pipe: Size and sha1 of src and dst objects identical === RUN TestRcat/withChecksum=true,ignoreChecksum=false run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" 2026/02/22 01:47:45 DEBUG : B2 bucket rclone-test-zuxafas4qeho: File to upload is small (34 bytes), uploading instead of streaming 2026/02/22 01:47:45 DEBUG : with_checksum_small_file_from_pipe: size = 34 OK 2026/02/22 01:47:45 DEBUG : with_checksum_small_file_from_pipe: sha1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK 2026/02/22 01:47:45 DEBUG : with_checksum_small_file_from_pipe: Size and sha1 of src and dst objects identical 2026/02/22 01:47:45 DEBUG : with_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2026/02/22 01:47:46 DEBUG : with_checksum_big_file_from_pipe: size = 102401 OK 2026/02/22 01:47:46 DEBUG : with_checksum_big_file_from_pipe: sha1 = d0080f73aa8b494570ad63df1beb25c01ea9eea2 OK 2026/02/22 01:47:46 DEBUG : with_checksum_big_file_from_pipe: Size and sha1 of src and dst objects identical === RUN TestRcat/withChecksum=false,ignoreChecksum=true run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" 2026/02/22 01:47:47 DEBUG : B2 bucket rclone-test-zuxafas4qeho: File to upload is small (34 bytes), uploading instead of streaming 2026/02/22 01:47:47 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK 2026/02/22 01:47:47 DEBUG : ignore_checksum_small_file_from_pipe: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/02/22 01:47:47 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2026/02/22 01:47:48 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK 2026/02/22 01:47:48 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:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" 2026/02/22 01:47:49 DEBUG : B2 bucket rclone-test-zuxafas4qeho: File to upload is small (34 bytes), uploading instead of streaming 2026/02/22 01:47:50 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK 2026/02/22 01:47:50 DEBUG : ignore_checksum_small_file_from_pipe: Src hash empty - aborting Dst hash check 2026/02/22 01:47:50 DEBUG : ignore_checksum_small_file_from_pipe: Size of src and dst objects identical 2026/02/22 01:47:50 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2026/02/22 01:47:50 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK 2026/02/22 01:47:50 DEBUG : ignore_checksum_big_file_from_pipe: Src hash empty - aborting Dst hash check 2026/02/22 01:47:50 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical --- PASS: TestRcat (8.81s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (2.50s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (2.07s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (2.17s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (2.07s) === RUN TestRcatMetadata run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" operations_test.go:1540: Skipping as destination doesn't support user metadata --- SKIP: TestRcatMetadata (0.37s) === RUN TestRcatSize run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" 2026/02/22 01:47:52 DEBUG : B2 bucket rclone-test-zuxafas4qeho: File to upload is small (60 bytes), uploading instead of streaming 2026/02/22 01:47:52 DEBUG : potato2: size = 60 OK 2026/02/22 01:47:52 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2026/02/22 01:47:52 DEBUG : potato2: Size and sha1 of src and dst objects identical --- PASS: TestRcatSize (1.37s) === RUN TestRcatSizeMetadata run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" operations_test.go:1621: Skipping as destination doesn't support user metadata --- SKIP: TestRcatSizeMetadata (0.36s) === RUN TestTouchDir run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" 2026/02/22 01:47:54 DEBUG : B2 bucket rclone-test-zuxafas4qeho: Touching "sub dir/potato3" 2026/02/22 01:47:54 DEBUG : B2 bucket rclone-test-zuxafas4qeho: Touching "empty space" 2026/02/22 01:47:54 DEBUG : B2 bucket rclone-test-zuxafas4qeho: Touching "potato2" --- PASS: TestTouchDir (3.61s) === RUN TestMkdirMetadata run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" operations_test.go:1711: Skipping test as remote does not support MkdirMetadata --- SKIP: TestMkdirMetadata (0.37s) === RUN TestMkdirModTime run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" operations_test.go:1730: Skipping test as remote does not support DirSetModTime or MkdirMetadata --- SKIP: TestMkdirModTime (0.37s) === RUN TestCopyDirMetadata run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" operations_test.go:1751: Skipping test as remote does not support WriteDirMetadata or MkdirMetadata --- SKIP: TestCopyDirMetadata (0.36s) === RUN TestSetDirModTime run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" operations_test.go:1787: Skipping test as remote does not support DirSetModTime or WriteDirSetModTime --- SKIP: TestSetDirModTime (0.37s) === RUN TestDirsEqual run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" operations_test.go:1831: Skipping test as remote does not support WriteDirMetadata or MkdirMetadata --- SKIP: TestDirsEqual (0.37s) === RUN TestRemoveExisting run.go:185: Remote "B2 bucket rclone-test-zuxafas4qeho", Local "Local file system at /tmp/rclone1368031187", Modify Window "1ms" operations_test.go:1900: Skipping as remote can't Move --- SKIP: TestRemoveExisting (0.36s) === 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/02/22 01:47:59 DEBUG : B2 bucket rclone-test-zuxafas4qeho: Purge remote 2026/02/22 01:47:59 INFO : B2 bucket rclone-test-zuxafas4qeho: cleaning bucket "rclone-test-zuxafas4qeho" of all files 2026/02/22 01:48:00 DEBUG : A1/B1/C1/four: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f4107ddafe194e9ca_d20260222_m014736_c001_v7007000_t0000_u01771724856056") 2026/02/22 01:48:00 DEBUG : A1/B1/C1/four: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e526_d20260222_m014734_c001_v0001179_t0027_u01771724854278") 2026/02/22 01:48:00 DEBUG : A1/B1/C1/one: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f412a7966ba32b6cf_d20260222_m014717_c001_v7007000_t0000_u01771724837984") 2026/02/22 01:48:00 DEBUG : A1/B1/C1/one: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e4ee_d20260222_m014715_c001_v0001179_t0053_u01771724835134") 2026/02/22 01:48:00 DEBUG : A1/B1/C2/five: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f412a7966ba32b708_d20260222_m014736_c001_v7007000_t0000_u01771724856335") 2026/02/22 01:48:00 DEBUG : A1/B1/C2/five: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e528_d20260222_m014734_c001_v0001179_t0021_u01771724854483") 2026/02/22 01:48:00 DEBUG : A1/B1/three: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f4177be04fe84f922_d20260222_m014736_c001_v7007000_t0000_u01771724856280") 2026/02/22 01:48:00 DEBUG : A1/B1/three: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e524_d20260222_m014733_c001_v0001179_t0021_u01771724853951") 2026/02/22 01:48:00 DEBUG : A1/one: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f407d2dd40b20dba1_d20260222_m014736_c001_v7007000_t0000_u01771724856676") 2026/02/22 01:48:00 DEBUG : A1/one: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e520_d20260222_m014733_c001_v0001179_t0005_u01771724853532") 2026/02/22 01:48:00 DEBUG : A1/two: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f421ed05a9705dcfe_d20260222_m014736_c001_v7007000_t0000_u01771724856273") 2026/02/22 01:48:00 DEBUG : A1/two: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e522_d20260222_m014733_c001_v0001179_t0033_u01771724853735") 2026/02/22 01:48:00 DEBUG : A1/two: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f412a7966ba32b6d0_d20260222_m014718_c001_v7007000_t0000_u01771724838346") 2026/02/22 01:48:00 DEBUG : A1/two: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e4f2_d20260222_m014715_c001_v0001179_t0057_u01771724835337") 2026/02/22 01:48:00 DEBUG : A2/B1/C1/four: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f407d2dd40b20dba2_d20260222_m014739_c001_v7007000_t0000_u01771724859779") 2026/02/22 01:48:00 DEBUG : A2/B1/C1/four: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f108d5522ad0eb708_d20260222_m014735_c001_v0001157_t0050_u01771724855664") 2026/02/22 01:48:00 DEBUG : A2/B1/C2/five: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f412a7966ba32b712_d20260222_m014737_c001_v7007000_t0000_u01771724857706") 2026/02/22 01:48:00 DEBUG : A2/B1/C2/five: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f113c12142eec63b5_d20260222_m014736_c001_v0001099_t0058_u01771724856002") 2026/02/22 01:48:00 DEBUG : A2/B1/three: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f4177be04fe84fd0b_d20260222_m014738_c001_v7007000_t0000_u01771724858363") 2026/02/22 01:48:01 DEBUG : A2/B1/three: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f11080131808ba5ac_d20260222_m014736_c001_v0001105_t0055_u01771724856035") 2026/02/22 01:48:01 DEBUG : A2/one: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f421ed05a9705dcff_d20260222_m014737_c001_v7007000_t0000_u01771724857861") 2026/02/22 01:48:01 DEBUG : A2/one: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f1000737dae7ad0c2_d20260222_m014736_c001_v0001117_t0027_u01771724856053") 2026/02/22 01:48:01 DEBUG : A2/two: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f420a36f44d58f19c_d20260222_m014737_c001_v7007000_t0000_u01771724857721") 2026/02/22 01:48:01 DEBUG : A2/two: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f11722a2322c0d275_d20260222_m014736_c001_v0001179_t0058_u01771724856060") 2026/02/22 01:48:01 DEBUG : A3/B1/C1/four: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f421ed05a9705dd01_d20260222_m014740_c001_v7007000_t0000_u01771724860959") 2026/02/22 01:48:01 DEBUG : A3/B1/C1/four: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f1136bb9a39c0fe16_d20260222_m014739_c001_v0001157_t0028_u01771724859471") 2026/02/22 01:48:01 DEBUG : A3/B1/C2/five: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f420a36f44d58f19e_d20260222_m014741_c001_v7007000_t0000_u01771724861039") 2026/02/22 01:48:01 DEBUG : A3/B1/C2/five: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f104d45b806ca6407_d20260222_m014737_c001_v0001120_t0037_u01771724857451") 2026/02/22 01:48:01 DEBUG : A3/B1/three: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f412a7966ba32b72c_d20260222_m014740_c001_v7007000_t0000_u01771724860884") 2026/02/22 01:48:01 DEBUG : A3/B1/three: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f104482907223569f_d20260222_m014737_c001_v0001116_t0027_u01771724857463") 2026/02/22 01:48:01 DEBUG : A3/one: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f4177be04fe84fd0c_d20260222_m014740_c001_v7007000_t0000_u01771724860928") 2026/02/22 01:48:01 DEBUG : A3/one: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f1144e8308de290ec_d20260222_m014737_c001_v0001036_t0040_u01771724857508") 2026/02/22 01:48:01 DEBUG : A3/two: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f407d2dd40b20dba3_d20260222_m014740_c001_v7007000_t0000_u01771724860894") 2026/02/22 01:48:01 DEBUG : A3/two: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f118aa7958374b9e0_d20260222_m014737_c001_v0001179_t0020_u01771724857489") 2026/02/22 01:48:01 DEBUG : A4/B1/C1/four: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f420a36f44d58f19f_d20260222_m014741_c001_v7007000_t0000_u01771724861599") 2026/02/22 01:48:01 DEBUG : A4/B1/C1/four: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f102b3b4e159a2e0f_d20260222_m014740_c001_v0001098_t0000_u01771724860585") 2026/02/22 01:48:01 DEBUG : A4/B1/C2/five: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f420a36f44d58f1a0_d20260222_m014741_c001_v7007000_t0000_u01771724861779") 2026/02/22 01:48:01 DEBUG : A4/B1/C2/five: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f1031595e8a72c0d7_d20260222_m014740_c001_v0001039_t0044_u01771724860603") 2026/02/22 01:48:01 DEBUG : A4/B1/three: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f420a36f44d58f1a1_d20260222_m014741_c001_v7007000_t0000_u01771724861959") 2026/02/22 01:48:02 DEBUG : A4/B1/three: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f111170ae8e7194cd_d20260222_m014740_c001_v0001165_t0013_u01771724860598") 2026/02/22 01:48:02 DEBUG : A4/one: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f420a36f44d58f1a2_d20260222_m014742_c001_v7007000_t0000_u01771724862140") 2026/02/22 01:48:02 DEBUG : A4/one: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f117a59ffcc9eebd8_d20260222_m014740_c001_v0001038_t0039_u01771724860585") 2026/02/22 01:48:02 DEBUG : A4/two: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f420a36f44d58f1a3_d20260222_m014742_c001_v7007000_t0000_u01771724862321") 2026/02/22 01:48:02 DEBUG : A4/two: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f1082ce8b689be070_d20260222_m014740_c001_v0001165_t0017_u01771724860590") 2026/02/22 01:48:02 DEBUG : BackupDir/one: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f4177be04fe84f81c_d20260222_m014610_c001_v7007000_t0000_u01771724770482") 2026/02/22 01:48:02 DEBUG : BackupDir/one: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f119ad6622fccf5b1_d20260222_m014605_c001_v0001097_t0051_u01771724765723") 2026/02/22 01:48:02 DEBUG : CompareDest/one: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f4177be04fe84f80f_d20260222_m014555_c001_v7007000_t0000_u01771724755762") 2026/02/22 01:48:02 DEBUG : CompareDest/one: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e408_d20260222_m014551_c001_v0001179_t0025_u01771724751996") 2026/02/22 01:48:02 DEBUG : CompareDest/two: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f4177be04fe84f810_d20260222_m014555_c001_v7007000_t0000_u01771724755948") 2026/02/22 01:48:02 DEBUG : CompareDest/two: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e40a_d20260222_m014553_c001_v0001179_t0051_u01771724753058") 2026/02/22 01:48:02 DEBUG : CopyDest/one: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f4177be04fe84f81d_d20260222_m014610_c001_v7007000_t0000_u01771724770663") 2026/02/22 01:48:02 DEBUG : CopyDest/one: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e41c_d20260222_m014602_c001_v0001179_t0005_u01771724762920") 2026/02/22 01:48:02 DEBUG : CopyDest/three: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f4177be04fe84f81e_d20260222_m014610_c001_v7007000_t0000_u01771724770845") 2026/02/22 01:48:02 DEBUG : CopyDest/three: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e428_d20260222_m014608_c001_v0001179_t0016_u01771724768564") 2026/02/22 01:48:02 DEBUG : CopyDest/two: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f4177be04fe84f820_d20260222_m014611_c001_v7007000_t0000_u01771724771027") 2026/02/22 01:48:02 DEBUG : CopyDest/two: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e424_d20260222_m014607_c001_v0001179_t0013_u01771724767057") 2026/02/22 01:48:02 DEBUG : ReadFile: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f415e77126fc43b61_d20260222_m014705_c001_v7007000_t0000_u01771724825294") 2026/02/22 01:48:02 DEBUG : ReadFile: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e4e4_d20260222_m014703_c001_v0001179_t0021_u01771724823852") 2026/02/22 01:48:02 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f4177be04fe84f82c_d20260222_m014615_c001_v7007000_t0000_u01771724775847") 2026/02/22 01:48:02 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e42c_d20260222_m014613_c001_v0001179_t0009_u01771724773565") 2026/02/22 01:48:02 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f4177be04fe84f82e_d20260222_m014616_c001_v7007000_t0000_u01771724776028") 2026/02/22 01:48:03 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e434_d20260222_m014615_c001_v0001179_t0028_u01771724775274") 2026/02/22 01:48:03 DEBUG : a.txt: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f402f7c30cf52dda1_d20260222_m014632_c001_v7007000_t0000_u01771724792568") 2026/02/22 01:48:03 DEBUG : a.txt: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e468_d20260222_m014628_c001_v0001179_t0017_u01771724788520") 2026/02/22 01:48:03 DEBUG : a.txt: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f402f7c30cf52dd96_d20260222_m014627_c001_v7007000_t0000_u01771724787037") 2026/02/22 01:48:03 DEBUG : a.txt: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e44a_d20260222_m014622_c001_v0001179_t0048_u01771724782559") 2026/02/22 01:48:03 DEBUG : also/one: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f4177be04fe84f831_d20260222_m014619_c001_v7007000_t0000_u01771724779665") 2026/02/22 01:48:03 DEBUG : also/one: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e440_d20260222_m014618_c001_v0001179_t0001_u01771724778420") 2026/02/22 01:48:03 DEBUG : another: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f4177be04fe84f833_d20260222_m014620_c001_v7007000_t0000_u01771724780206") 2026/02/22 01:48:03 DEBUG : another: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e442_d20260222_m014618_c001_v0001179_t0043_u01771724778629") 2026/02/22 01:48:03 DEBUG : backup/dst/file1: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f412a7966ba32b6ef_d20260222_m014732_c001_v7007000_t0000_u01771724852982") 2026/02/22 01:48:03 DEBUG : backup/dst/file1: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f1186e18a4f984435_d20260222_m014731_c001_v0001167_t0045_u01771724851731") 2026/02/22 01:48:03 DEBUG : backup/dst/file1: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f4177be04fe84f80b_d20260222_m014545_c001_v7007000_t0000_u01771724745307") 2026/02/22 01:48:03 DEBUG : backup/dst/file1: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f1152188a1de11356_d20260222_m014544_c001_v0001182_t0057_u01771724744300") 2026/02/22 01:48:03 DEBUG : data/banana: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f4146521755cb9102_d20260222_m014451_c001_v7007000_t0000_u01771724691084") 2026/02/22 01:48:03 DEBUG : data/banana: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e3b9_d20260222_m014449_c001_v0001179_t0022_u01771724689090") 2026/02/22 01:48:03 DEBUG : data/banana: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e3a7_d20260222_m014441_c001_v0001179_t0000_u01771724681076") 2026/02/22 01:48:03 DEBUG : data/potato: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f4146521755cb9104_d20260222_m014451_c001_v7007000_t0000_u01771724691267") 2026/02/22 01:48:03 DEBUG : data/potato: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e3bb_d20260222_m014449_c001_v0001179_t0031_u01771724689555") 2026/02/22 01:48:03 DEBUG : data/potato: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e3ad_d20260222_m014442_c001_v0001179_t0008_u01771724682971") 2026/02/22 01:48:03 DEBUG : dst/file1: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f412a7966ba32b6f1_d20260222_m014733_c001_v7007000_t0000_u01771724853166") 2026/02/22 01:48:04 DEBUG : dst/file1: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e51c_d20260222_m014732_c001_v0001179_t0056_u01771724852371") 2026/02/22 01:48:04 DEBUG : dst/file1: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f412a7966ba32b6e9_d20260222_m014732_c001_v7007000_t0000_u01771724852188") 2026/02/22 01:48:04 DEBUG : dst/file1: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e512_d20260222_m014728_c001_v0001179_t0019_u01771724848588") 2026/02/22 01:48:04 DEBUG : dst/file1: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f4177be04fe84f80c_d20260222_m014545_c001_v7007000_t0000_u01771724745488") 2026/02/22 01:48:04 DEBUG : dst/file1: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e400_d20260222_m014544_c001_v0001179_t0023_u01771724744696") 2026/02/22 01:48:04 DEBUG : dst/file1: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f4177be04fe84f80a_d20260222_m014544_c001_v7007000_t0000_u01771724744512") 2026/02/22 01:48:04 DEBUG : dst/file1: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e3fe_d20260222_m014541_c001_v0001179_t0025_u01771724741627") 2026/02/22 01:48:04 DEBUG : dst/one: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f4177be04fe84f821_d20260222_m014611_c001_v7007000_t0000_u01771724771209") 2026/02/22 01:48:04 DEBUG : dst/one: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f113039d9b03b36bf_d20260222_m014606_c001_v0001183_t0054_u01771724766673") 2026/02/22 01:48:04 DEBUG : dst/one: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f4223ab44618d9d01_d20260222_m014606_c001_v7007000_t0000_u01771724766475") 2026/02/22 01:48:04 DEBUG : dst/one: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e41a_d20260222_m014602_c001_v0001179_t0034_u01771724762680") 2026/02/22 01:48:04 DEBUG : dst/one: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f109097e0418822fa_d20260222_m014601_c001_v0001097_t0034_u01771724761714") 2026/02/22 01:48:04 DEBUG : dst/one: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f109097e0418822f4_d20260222_m014600_c001_v0001097_t0042_u01771724760525") 2026/02/22 01:48:04 DEBUG : dst/one: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f4177be04fe84f812_d20260222_m014556_c001_v7007000_t0000_u01771724756129") 2026/02/22 01:48:04 DEBUG : dst/one: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e406_d20260222_m014551_c001_v0001179_t0002_u01771724751782") 2026/02/22 01:48:04 DEBUG : dst/one: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f1133b9e571aecd28_d20260222_m014550_c001_v0001131_t0019_u01771724750782") 2026/02/22 01:48:04 DEBUG : dst/one: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f1133b9e571aecd26_d20260222_m014549_c001_v0001131_t0052_u01771724749763") 2026/02/22 01:48:04 DEBUG : dst/three: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f4177be04fe84f822_d20260222_m014611_c001_v7007000_t0000_u01771724771390") 2026/02/22 01:48:04 DEBUG : dst/three: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f109097e04188233e_d20260222_m014609_c001_v0001097_t0035_u01771724769744") 2026/02/22 01:48:04 DEBUG : dst/two: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f4177be04fe84f824_d20260222_m014611_c001_v7007000_t0000_u01771724771574") 2026/02/22 01:48:04 DEBUG : dst/two: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f1028003d5d7b4739_d20260222_m014607_c001_v0001185_t0023_u01771724767815") 2026/02/22 01:48:04 DEBUG : dst/two: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f4177be04fe84f813_d20260222_m014556_c001_v7007000_t0000_u01771724756311") 2026/02/22 01:48:04 DEBUG : dst/two: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f1133b9e571aecd2c_d20260222_m014555_c001_v0001131_t0007_u01771724755091") 2026/02/22 01:48:05 DEBUG : empty space: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f420a36f44d58f1b7_d20260222_m014756_c001_v7007000_t0000_u01771724876844") 2026/02/22 01:48:05 DEBUG : empty space: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f105754a91e7445b9_d20260222_m014755_c001_v0001097_t0011_u01771724875830") 2026/02/22 01:48:05 DEBUG : empty space: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e550_d20260222_m014754_c001_v0001179_t0037_u01771724874161") 2026/02/22 01:48:05 DEBUG : empty space: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f402f7c30cf52ddd5_d20260222_m014653_c001_v7007000_t0000_u01771724813596") 2026/02/22 01:48:05 DEBUG : empty space: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e4ae_d20260222_m014652_c001_v0001179_t0046_u01771724812642") 2026/02/22 01:48:05 DEBUG : empty space: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f402f7c30cf52ddd3_d20260222_m014651_c001_v7007000_t0000_u01771724811879") 2026/02/22 01:48:05 DEBUG : empty space: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e4a6_d20260222_m014650_c001_v0001179_t0019_u01771724810676") 2026/02/22 01:48:05 DEBUG : empty space: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f402f7c30cf52ddcf_d20260222_m014649_c001_v7007000_t0000_u01771724809925") 2026/02/22 01:48:05 DEBUG : empty space: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e49c_d20260222_m014649_c001_v0001179_t0034_u01771724809176") 2026/02/22 01:48:05 DEBUG : empty space: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f402f7c30cf52ddcc_d20260222_m014648_c001_v7007000_t0000_u01771724808426") 2026/02/22 01:48:05 DEBUG : empty space: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e496_d20260222_m014647_c001_v0001179_t0031_u01771724807109") 2026/02/22 01:48:05 DEBUG : empty space: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f402f7c30cf52ddc9_d20260222_m014646_c001_v7007000_t0000_u01771724806316") 2026/02/22 01:48:05 DEBUG : empty space: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e492_d20260222_m014645_c001_v0001179_t0053_u01771724805574") 2026/02/22 01:48:05 DEBUG : empty space: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f4146521755cb90be_d20260222_m014435_c001_v7007000_t0000_u01771724675480") 2026/02/22 01:48:05 DEBUG : empty space: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e393_d20260222_m014433_c001_v0001179_t0001_u01771724673186") 2026/02/22 01:48:05 DEBUG : empty space: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f4146521755cb9098_d20260222_m014431_c001_v7007000_t0000_u01771724671501") 2026/02/22 01:48:05 DEBUG : empty space: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e385_d20260222_m014427_c001_v0001179_t0049_u01771724667954") 2026/02/22 01:48:05 DEBUG : empty space: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f4146521755cb9062_d20260222_m014425_c001_v7007000_t0000_u01771724665550") 2026/02/22 01:48:05 DEBUG : empty space: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e379_d20260222_m014423_c001_v0001179_t0007_u01771724663224") 2026/02/22 01:48:05 DEBUG : file1: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f412a7966ba32b6dc_d20260222_m014727_c001_v7007000_t0000_u01771724847478") 2026/02/22 01:48:06 DEBUG : file1: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e50e_d20260222_m014726_c001_v0001179_t0038_u01771724846099") 2026/02/22 01:48:06 DEBUG : file1: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f412a7966ba32b6d5_d20260222_m014723_c001_v7007000_t0000_u01771724843308") 2026/02/22 01:48:06 DEBUG : file1: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f410dd49787c2e4fc_d20260222_m014721_c001_v0001179_t0041_u01771724841758") 2026/02/22 01:48:06 DEBUG : file1: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f415e77126fc43b6b_d20260222_m014709_c001_v7007000_t0000_u01771724829727") 2026/02/22 01:48:06 DEBUG : file1: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e4e8_d20260222_m014706_c001_v0001179_t0017_u01771724826164") 2026/02/22 01:48:06 DEBUG : file1: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f402f7c30cf52ddb4_d20260222_m014642_c001_v7007000_t0000_u01771724802771") 2026/02/22 01:48:06 DEBUG : file1: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e484_d20260222_m014637_c001_v0001179_t0035_u01771724797204") 2026/02/22 01:48:06 DEBUG : file1: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f402f7c30cf52ddaf_d20260222_m014636_c001_v7007000_t0000_u01771724796617") 2026/02/22 01:48:06 DEBUG : file1: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e47c_d20260222_m014634_c001_v0001179_t0002_u01771724794023") 2026/02/22 01:48:06 DEBUG : file2: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f412a7966ba32b6d6_d20260222_m014723_c001_v7007000_t0000_u01771724843490") 2026/02/22 01:48:06 DEBUG : file2: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f410dd49787c2e506_d20260222_m014722_c001_v0001179_t0041_u01771724842761") 2026/02/22 01:48:06 DEBUG : file2: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f415e77126fc43b6c_d20260222_m014709_c001_v7007000_t0000_u01771724829910") 2026/02/22 01:48:06 DEBUG : file2: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e4ea_d20260222_m014706_c001_v0001179_t0048_u01771724826369") 2026/02/22 01:48:06 DEBUG : filename.txt: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f412a7966ba32b6d7_d20260222_m014723_c001_v7007000_t0000_u01771724843672") 2026/02/22 01:48:06 DEBUG : filename.txt: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e500_d20260222_m014722_c001_v0001179_t0022_u01771724842305") 2026/02/22 01:48:06 DEBUG : headerfilename.txt: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f412a7966ba32b6d8_d20260222_m014723_c001_v7007000_t0000_u01771724843854") 2026/02/22 01:48:06 DEBUG : headerfilename.txt: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e504_d20260222_m014722_c001_v0001179_t0057_u01771724842540") 2026/02/22 01:48:06 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f420a36f44d58f1b1_d20260222_m014751_c001_v7007000_t0000_u01771724871313") 2026/02/22 01:48:06 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e546_d20260222_m014750_c001_v0001179_t0046_u01771724870204") 2026/02/22 01:48:06 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f420a36f44d58f1ae_d20260222_m014749_c001_v7007000_t0000_u01771724869171") 2026/02/22 01:48:06 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e540_d20260222_m014748_c001_v0001179_t0058_u01771724868008") 2026/02/22 01:48:07 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f420a36f44d58f1b2_d20260222_m014751_c001_v7007000_t0000_u01771724871493") 2026/02/22 01:48:07 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e542_d20260222_m014749_c001_v0001179_t0056_u01771724869970") 2026/02/22 01:48:07 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f420a36f44d58f1af_d20260222_m014749_c001_v7007000_t0000_u01771724869423") 2026/02/22 01:48:07 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e53e_d20260222_m014747_c001_v0001179_t0041_u01771724867800") 2026/02/22 01:48:07 DEBUG : large: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f40865cef0b57d245_d20260222_m014702_c001_v7007000_t0000_u01771724822561") 2026/02/22 01:48:07 DEBUG : large: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e4dc_d20260222_m014701_c001_v0001179_t0035_u01771724821996") 2026/02/22 01:48:07 DEBUG : large: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f40865cef0b57d241_d20260222_m014700_c001_v7007000_t0000_u01771724820739") 2026/02/22 01:48:07 DEBUG : large: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e4ce_d20260222_m014659_c001_v0001179_t0041_u01771724819526") 2026/02/22 01:48:07 DEBUG : large: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f415e77126fc43b4f_d20260222_m014658_c001_v7007000_t0000_u01771724818331") 2026/02/22 01:48:07 DEBUG : large: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e4c6_d20260222_m014657_c001_v0001179_t0008_u01771724817013") 2026/02/22 01:48:07 DEBUG : large: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f415e77126fc43b49_d20260222_m014656_c001_v7007000_t0000_u01771724816251") 2026/02/22 01:48:07 DEBUG : large: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e4bc_d20260222_m014654_c001_v0001179_t0037_u01771724814754") 2026/02/22 01:48:07 DEBUG : medium: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f415e77126fc43b5d_d20260222_m014703_c001_v7007000_t0000_u01771724823136") 2026/02/22 01:48:07 DEBUG : medium: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e4d6_d20260222_m014701_c001_v0001179_t0030_u01771724821690") 2026/02/22 01:48:07 DEBUG : medium: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f415e77126fc43b54_d20260222_m014700_c001_v7007000_t0000_u01771724820197") 2026/02/22 01:48:07 DEBUG : medium: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e4cc_d20260222_m014659_c001_v0001179_t0051_u01771724819338") 2026/02/22 01:48:07 DEBUG : medium: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f415e77126fc43b4c_d20260222_m014657_c001_v7007000_t0000_u01771724817598") 2026/02/22 01:48:07 DEBUG : medium: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e4c4_d20260222_m014656_c001_v0001179_t0042_u01771724816809") 2026/02/22 01:48:07 DEBUG : medium: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f402f7c30cf52ddd9_d20260222_m014655_c001_v7007000_t0000_u01771724815311") 2026/02/22 01:48:07 DEBUG : medium: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e4ba_d20260222_m014654_c001_v0001179_t0045_u01771724814550") 2026/02/22 01:48:07 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f420a36f44d58f1a7_d20260222_m014745_c001_v7007000_t0000_u01771724865006") 2026/02/22 01:48:08 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e534_d20260222_m014743_c001_v0001179_t0017_u01771724863886") 2026/02/22 01:48:08 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f420a36f44d58f1a8_d20260222_m014745_c001_v7007000_t0000_u01771724865186") 2026/02/22 01:48:08 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e532_d20260222_m014743_c001_v0001179_t0025_u01771724863595") 2026/02/22 01:48:08 DEBUG : not-one: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f4177be04fe84f835_d20260222_m014620_c001_v7007000_t0000_u01771724780387") 2026/02/22 01:48:08 DEBUG : not-one: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e444_d20260222_m014618_c001_v0001179_t0000_u01771724778882") 2026/02/22 01:48:08 DEBUG : one: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f4177be04fe84f830_d20260222_m014619_c001_v7007000_t0000_u01771724779485") 2026/02/22 01:48:08 DEBUG : one: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e43e_d20260222_m014618_c001_v0001179_t0047_u01771724778216") 2026/02/22 01:48:08 DEBUG : potato1: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f420a36f44d58f1b3_d20260222_m014753_c001_v7007000_t0000_u01771724873051") 2026/02/22 01:48:08 DEBUG : potato1: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e54a_d20260222_m014752_c001_v0001179_t0041_u01771724872227") 2026/02/22 01:48:08 DEBUG : potato2: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f420a36f44d58f1b8_d20260222_m014757_c001_v7007000_t0000_u01771724877026") 2026/02/22 01:48:08 DEBUG : potato2: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f11272b76abf63382_d20260222_m014755_c001_v0001183_t0051_u01771724875907") 2026/02/22 01:48:08 DEBUG : potato2: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e54e_d20260222_m014753_c001_v0001179_t0005_u01771724873962") 2026/02/22 01:48:08 DEBUG : potato2: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f420a36f44d58f1b4_d20260222_m014753_c001_v7007000_t0000_u01771724873231") 2026/02/22 01:48:08 DEBUG : potato2: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e54c_d20260222_m014752_c001_v0001179_t0025_u01771724872453") 2026/02/22 01:48:08 DEBUG : potato2: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f402f7c30cf52ddd6_d20260222_m014653_c001_v7007000_t0000_u01771724813777") 2026/02/22 01:48:08 DEBUG : potato2: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e4aa_d20260222_m014652_c001_v0001179_t0016_u01771724812425") 2026/02/22 01:48:08 DEBUG : potato2: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f402f7c30cf52ddd4_d20260222_m014652_c001_v7007000_t0000_u01771724812059") 2026/02/22 01:48:08 DEBUG : potato2: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e4a4_d20260222_m014650_c001_v0001179_t0043_u01771724810471") 2026/02/22 01:48:08 DEBUG : potato2: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f402f7c30cf52ddd0_d20260222_m014650_c001_v7007000_t0000_u01771724810106") 2026/02/22 01:48:08 DEBUG : potato2: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e49a_d20260222_m014648_c001_v0001179_t0052_u01771724808972") 2026/02/22 01:48:08 DEBUG : potato2: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f402f7c30cf52ddcd_d20260222_m014648_c001_v7007000_t0000_u01771724808607") 2026/02/22 01:48:08 DEBUG : potato2: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e494_d20260222_m014646_c001_v0001179_t0039_u01771724806910") 2026/02/22 01:48:08 DEBUG : potato2: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f402f7c30cf52ddca_d20260222_m014646_c001_v7007000_t0000_u01771724806497") 2026/02/22 01:48:09 DEBUG : potato2: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e490_d20260222_m014645_c001_v0001179_t0058_u01771724805360") 2026/02/22 01:48:09 DEBUG : potato2: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f4146521755cb90c1_d20260222_m014435_c001_v7007000_t0000_u01771724675665") 2026/02/22 01:48:09 DEBUG : potato2: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e395_d20260222_m014433_c001_v0001179_t0015_u01771724673768") 2026/02/22 01:48:09 DEBUG : potato2: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f4146521755cb909a_d20260222_m014431_c001_v7007000_t0000_u01771724671686") 2026/02/22 01:48:09 DEBUG : potato2: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e387_d20260222_m014428_c001_v0001179_t0033_u01771724668759") 2026/02/22 01:48:09 DEBUG : potato2: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f4146521755cb9064_d20260222_m014425_c001_v7007000_t0000_u01771724665732") 2026/02/22 01:48:09 DEBUG : potato2: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e37b_d20260222_m014423_c001_v0001179_t0009_u01771724663790") 2026/02/22 01:48:09 DEBUG : remotepotato: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f4146521755cb90c3_d20260222_m014435_c001_v7007000_t0000_u01771724675848") 2026/02/22 01:48:09 DEBUG : remotepotato: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e397_d20260222_m014434_c001_v0001179_t0044_u01771724674553") 2026/02/22 01:48:09 DEBUG : remotepotato: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f4146521755cb909d_d20260222_m014431_c001_v7007000_t0000_u01771724671868") 2026/02/22 01:48:09 DEBUG : remotepotato: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e38d_d20260222_m014430_c001_v0001179_t0037_u01771724670102") 2026/02/22 01:48:09 DEBUG : remotepotato: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f4146521755cb9066_d20260222_m014425_c001_v7007000_t0000_u01771724665916") 2026/02/22 01:48:09 DEBUG : remotepotato: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e37f_d20260222_m014424_c001_v0001179_t0005_u01771724664580") 2026/02/22 01:48:09 DEBUG : rutabaga: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f4146521755cb90c5_d20260222_m014436_c001_v7007000_t0000_u01771724676033") 2026/02/22 01:48:09 DEBUG : rutabaga: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e391_d20260222_m014432_c001_v0001179_t0002_u01771724672418") 2026/02/22 01:48:09 DEBUG : rutabaga: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f4146521755cb90a0_d20260222_m014432_c001_v7007000_t0000_u01771724672052") 2026/02/22 01:48:09 DEBUG : rutabaga: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e381_d20260222_m014426_c001_v0001179_t0042_u01771724666467") 2026/02/22 01:48:09 DEBUG : rutabaga: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f4146521755cb9068_d20260222_m014426_c001_v7007000_t0000_u01771724666099") 2026/02/22 01:48:09 DEBUG : rutabaga: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e377_d20260222_m014422_c001_v0001179_t0002_u01771724662453") 2026/02/22 01:48:09 DEBUG : small: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f415e77126fc43b5b_d20260222_m014702_c001_v7007000_t0000_u01771724822588") 2026/02/22 01:48:10 DEBUG : small: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e4d4_d20260222_m014701_c001_v0001179_t0027_u01771724821472") 2026/02/22 01:48:10 DEBUG : small: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f40865cef0b57d240_d20260222_m014700_c001_v7007000_t0000_u01771724820194") 2026/02/22 01:48:10 DEBUG : small: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e4c8_d20260222_m014659_c001_v0001179_t0024_u01771724819045") 2026/02/22 01:48:10 DEBUG : small: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f415e77126fc43b4d_d20260222_m014657_c001_v7007000_t0000_u01771724817781") 2026/02/22 01:48:10 DEBUG : small: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e4c2_d20260222_m014656_c001_v0001179_t0053_u01771724816603") 2026/02/22 01:48:10 DEBUG : small: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f415e77126fc43b47_d20260222_m014655_c001_v7007000_t0000_u01771724815703") 2026/02/22 01:48:10 DEBUG : small: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e4b6_d20260222_m014654_c001_v0001179_t0005_u01771724814322") 2026/02/22 01:48:10 DEBUG : sub dir/hello world: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f402f7c30cf52ddbb_d20260222_m014644_c001_v7007000_t0000_u01771724804995") 2026/02/22 01:48:10 DEBUG : sub dir/hello world: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e48e_d20260222_m014644_c001_v0001179_t0020_u01771724804216") 2026/02/22 01:48:10 DEBUG : sub dir/hello world: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f402f7c30cf52dda3_d20260222_m014632_c001_v7007000_t0000_u01771724792749") 2026/02/22 01:48:10 DEBUG : sub dir/hello world: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e46c_d20260222_m014628_c001_v0001179_t0020_u01771724788964") 2026/02/22 01:48:10 DEBUG : sub dir/hello world: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f402f7c30cf52dd98_d20260222_m014627_c001_v7007000_t0000_u01771724787233") 2026/02/22 01:48:10 DEBUG : sub dir/hello world: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e44e_d20260222_m014622_c001_v0001179_t0022_u01771724782992") 2026/02/22 01:48:10 DEBUG : sub dir/hello world2: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f402f7c30cf52dda5_d20260222_m014632_c001_v7007000_t0000_u01771724792933") 2026/02/22 01:48:10 DEBUG : sub dir/hello world2: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e46e_d20260222_m014629_c001_v0001179_t0023_u01771724789186") 2026/02/22 01:48:10 DEBUG : sub dir/hello world2: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f402f7c30cf52dd99_d20260222_m014627_c001_v7007000_t0000_u01771724787413") 2026/02/22 01:48:10 DEBUG : sub dir/hello world2: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e452_d20260222_m014623_c001_v0001179_t0035_u01771724783239") 2026/02/22 01:48:10 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f402f7c30cf52dda6_d20260222_m014633_c001_v7007000_t0000_u01771724793115") 2026/02/22 01:48:10 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e470_d20260222_m014629_c001_v0001179_t0059_u01771724789426") 2026/02/22 01:48:10 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f402f7c30cf52dd9a_d20260222_m014627_c001_v7007000_t0000_u01771724787594") 2026/02/22 01:48:11 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e456_d20260222_m014623_c001_v0001179_t0021_u01771724783444") 2026/02/22 01:48:11 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f402f7c30cf52dda8_d20260222_m014633_c001_v7007000_t0000_u01771724793295") 2026/02/22 01:48:11 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e472_d20260222_m014629_c001_v0001179_t0047_u01771724789626") 2026/02/22 01:48:11 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f402f7c30cf52dd9b_d20260222_m014627_c001_v7007000_t0000_u01771724787773") 2026/02/22 01:48:11 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e458_d20260222_m014623_c001_v0001179_t0036_u01771724783709") 2026/02/22 01:48:11 DEBUG : sub dir/potato3: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f420a36f44d58f1b9_d20260222_m014757_c001_v7007000_t0000_u01771724877205") 2026/02/22 01:48:11 DEBUG : sub dir/potato3: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f10237aa40e784615_d20260222_m014755_c001_v0001161_t0001_u01771724875531") 2026/02/22 01:48:11 DEBUG : sub dir/potato3: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e552_d20260222_m014754_c001_v0001179_t0057_u01771724874368") 2026/02/22 01:48:11 DEBUG : sub dir/potato3: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f402f7c30cf52ddd7_d20260222_m014653_c001_v7007000_t0000_u01771724813958") 2026/02/22 01:48:11 DEBUG : sub dir/potato3: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e4b2_d20260222_m014652_c001_v0001179_t0032_u01771724812848") 2026/02/22 01:48:11 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f402f7c30cf52dda9_d20260222_m014633_c001_v7007000_t0000_u01771724793476") 2026/02/22 01:48:11 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e474_d20260222_m014629_c001_v0001179_t0051_u01771724789832") 2026/02/22 01:48:11 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f402f7c30cf52dd9c_d20260222_m014627_c001_v7007000_t0000_u01771724787953") 2026/02/22 01:48:11 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e45a_d20260222_m014623_c001_v0001179_t0010_u01771724783900") 2026/02/22 01:48:11 DEBUG : sub/file2: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f412a7966ba32b6da_d20260222_m014725_c001_v7007000_t0000_u01771724845530") 2026/02/22 01:48:11 DEBUG : sub/file2: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e50a_d20260222_m014724_c001_v0001179_t0002_u01771724844402") 2026/02/22 01:48:11 DEBUG : sub/file2: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f402f7c30cf52ddb5_d20260222_m014642_c001_v7007000_t0000_u01771724802952") 2026/02/22 01:48:11 DEBUG : sub/file2: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e488_d20260222_m014637_c001_v0001179_t0023_u01771724797430") 2026/02/22 01:48:11 DEBUG : sub/file2: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f402f7c30cf52ddb0_d20260222_m014636_c001_v7007000_t0000_u01771724796799") 2026/02/22 01:48:11 DEBUG : sub/file2: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e47e_d20260222_m014634_c001_v0001179_t0048_u01771724794225") 2026/02/22 01:48:11 DEBUG : sub/file2: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f4177be04fe84f808_d20260222_m014540_c001_v7007000_t0000_u01771724740899") 2026/02/22 01:48:11 DEBUG : sub/file2: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e3f8_d20260222_m014539_c001_v0001179_t0000_u01771724739421") 2026/02/22 01:48:11 DEBUG : test-multithread-abort: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f4146521755cb9056_d20260222_m014421_c001_v7007000_t0000_u01771724661501") 2026/02/22 01:48:11 DEBUG : test-multithread-abort: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e351_d20260222_m014405_c001_v0001179_t0020_u01771724645449") 2026/02/22 01:48:12 DEBUG : test-multithread-copy-false-16777215-2: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f420a36f44d58f0c5_d20260222_m014323_c001_v7007000_t0000_u01771724603939") 2026/02/22 01:48:12 DEBUG : test-multithread-copy-false-16777215-2: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f11826de6386db515_d20260222_m014316_c001_v0001103_t0052_u01771724596802") 2026/02/22 01:48:12 DEBUG : test-multithread-copy-false-16777216-2: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f420a36f44d58f0cd_d20260222_m014333_c001_v7007000_t0000_u01771724613949") 2026/02/22 01:48:12 DEBUG : test-multithread-copy-false-16777216-2: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f1068ffd59fc22793_d20260222_m014326_c001_v0001157_t0031_u01771724606904") 2026/02/22 01:48:12 DEBUG : test-multithread-copy-false-16777217-2: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f420a36f44d58f0d2_d20260222_m014343_c001_v7007000_t0000_u01771724623879") 2026/02/22 01:48:12 DEBUG : test-multithread-copy-false-16777217-2: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e321_d20260222_m014336_c001_v0001179_t0017_u01771724616950") 2026/02/22 01:48:12 DEBUG : test-multithread-copy-true-16777215-2: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f419b1845f4f9829b_d20260222_m014350_c001_v7007000_t0000_u01771724630480") 2026/02/22 01:48:12 DEBUG : test-multithread-copy-true-16777215-2: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f220a36f44d58f0d5_d20260222_m014345_c001_v0001161_t0012_u01771724625757") 2026/02/22 01:48:12 DEBUG : test-multithread-copy-true-16777216-2: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f419b1845f4f982d3_d20260222_m014357_c001_v7007000_t0000_u01771724637051") 2026/02/22 01:48:12 DEBUG : test-multithread-copy-true-16777216-2: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f219b1845f4f982ac_d20260222_m014352_c001_v0001183_t0009_u01771724632328") 2026/02/22 01:48:12 DEBUG : test-multithread-copy-true-16777217-2: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f421ed05a9705db6c_d20260222_m014404_c001_v7007000_t0000_u01771724644096") 2026/02/22 01:48:12 DEBUG : test-multithread-copy-true-16777217-2: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f219b1845f4f982e7_d20260222_m014358_c001_v0001039_t0053_u01771724638956") 2026/02/22 01:48:12 DEBUG : test.sum: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f4146521755cb9105_d20260222_m014451_c001_v7007000_t0000_u01771724691449") 2026/02/22 01:48:12 DEBUG : test.sum: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e3bd_d20260222_m014449_c001_v0001179_t0047_u01771724689767") 2026/02/22 01:48:12 DEBUG : test.sum: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e3b7_d20260222_m014447_c001_v0001179_t0012_u01771724687937") 2026/02/22 01:48:12 DEBUG : test.sum: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e3b5_d20260222_m014446_c001_v0001179_t0039_u01771724686816") 2026/02/22 01:48:12 DEBUG : test.sum: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e3b3_d20260222_m014445_c001_v0001179_t0052_u01771724685684") 2026/02/22 01:48:12 DEBUG : test.sum: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e3b1_d20260222_m014444_c001_v0001179_t0027_u01771724684388") 2026/02/22 01:48:12 DEBUG : test.sum: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e3af_d20260222_m014443_c001_v0001179_t0002_u01771724683250") 2026/02/22 01:48:13 DEBUG : test.sum: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e3ab_d20260222_m014441_c001_v0001179_t0016_u01771724681282") 2026/02/22 01:48:13 DEBUG : test.sum: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f4146521755cb90d9_d20260222_m014438_c001_v7007000_t0000_u01771724678161") 2026/02/22 01:48:13 DEBUG : test.sum: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e39f_d20260222_m014437_c001_v0001179_t0035_u01771724677411") 2026/02/22 01:48:13 DEBUG : test.sum: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e39b_d20260222_m014436_c001_v0001179_t0051_u01771724676414") 2026/02/22 01:48:13 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f420a36f44d58f1a9_d20260222_m014747_c001_v7007000_t0000_u01771724867070") 2026/02/22 01:48:13 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e538_d20260222_m014745_c001_v0001179_t0034_u01771724865964") 2026/02/22 01:48:13 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f420a36f44d58f1aa_d20260222_m014747_c001_v7007000_t0000_u01771724867252") 2026/02/22 01:48:13 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e536_d20260222_m014745_c001_v0001179_t0037_u01771724865733") 2026/02/22 01:48:13 DEBUG : zend.txt: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f402f7c30cf52ddaa_d20260222_m014633_c001_v7007000_t0000_u01771724793659") 2026/02/22 01:48:13 DEBUG : zend.txt: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e46a_d20260222_m014628_c001_v0001179_t0010_u01771724788728") 2026/02/22 01:48:13 DEBUG : zend.txt: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f402f7c30cf52dd9e_d20260222_m014628_c001_v7007000_t0000_u01771724788133") 2026/02/22 01:48:13 DEBUG : zend.txt: Deleting (id "4_z5f42ed03003d8b3b9fc80e1b_f110dd49787c2e44c_d20260222_m014622_c001_v0001179_t0043_u01771724782792") "./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose" - Finished OK in 5m0.111167363s (try 1/5)