"./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -fast-list" - Starting (try 1/5) 2025/08/05 01:22:47 DEBUG : Creating backend with remote "TestB2:rclone-test-wuwasic8suhe" 2025/08/05 01:22:47 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/08/05 01:22:47 DEBUG : Creating backend with remote "/tmp/rclone1897192953" === 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:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" 2025/08/05 01:22:51 DEBUG : chunksize-probe: Cancelling large file upload === RUN TestMultithreadCopy/upload=false,size=201326591,streams=2 2025/08/05 01:23:17 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2025/08/05 01:23:17 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: write buffer set to 131072 2025/08/05 01:23:17 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2025/08/05 01:23:17 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2025/08/05 01:23:17 DEBUG : test-multithread-copy-false-201326591-2: Starting multi-thread copy with 3 chunks of size 64Mi with 3 parallel streams 2025/08/05 01:23:17 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 3/3 (134217728-201326591) size 64.000Mi starting 2025/08/05 01:23:17 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 1/3 (0-67108864) size 64Mi starting 2025/08/05 01:23:17 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 2/3 (67108864-134217728) size 64Mi starting 2025/08/05 01:23:17 DEBUG : test-multithread-copy-false-201326591-2: writing chunk 2 2025/08/05 01:23:17 DEBUG : test-multithread-copy-false-201326591-2: writing chunk 0 2025/08/05 01:23:18 DEBUG : test-multithread-copy-false-201326591-2: writing chunk 1 2025/08/05 01:23:23 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 2/3 (67108864-134217728) size 64Mi finished 2025/08/05 01:23:23 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 1/3 (0-67108864) size 64Mi finished 2025/08/05 01:23:23 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 3/3 (134217728-201326591) size 64.000Mi finished 2025/08/05 01:23:23 DEBUG : test-multithread-copy-false-201326591-2: Finished multi-thread copy with 3 parts of size 64Mi === RUN TestMultithreadCopy/upload=false,size=201326592,streams=2 2025/08/05 01:24:01 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2025/08/05 01:24:01 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: write buffer set to 131072 2025/08/05 01:24:01 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2025/08/05 01:24:01 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2025/08/05 01:24:01 DEBUG : test-multithread-copy-false-201326592-2: Starting multi-thread copy with 3 chunks of size 64Mi with 3 parallel streams 2025/08/05 01:24:01 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 1/3 (0-67108864) size 64Mi starting 2025/08/05 01:24:01 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 2/3 (67108864-134217728) size 64Mi starting 2025/08/05 01:24:01 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 3/3 (134217728-201326592) size 64Mi starting 2025/08/05 01:24:02 DEBUG : test-multithread-copy-false-201326592-2: writing chunk 0 2025/08/05 01:24:02 DEBUG : test-multithread-copy-false-201326592-2: writing chunk 1 2025/08/05 01:24:02 DEBUG : test-multithread-copy-false-201326592-2: writing chunk 2 2025/08/05 01:24:07 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 2/3 (67108864-134217728) size 64Mi finished 2025/08/05 01:24:08 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 1/3 (0-67108864) size 64Mi finished 2025/08/05 01:24:08 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 3/3 (134217728-201326592) size 64Mi finished 2025/08/05 01:24:08 DEBUG : test-multithread-copy-false-201326592-2: Finished multi-thread copy with 3 parts of size 64Mi === RUN TestMultithreadCopy/upload=false,size=201326593,streams=2 2025/08/05 01:24:44 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2025/08/05 01:24:44 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: write buffer set to 131072 2025/08/05 01:24:44 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2025/08/05 01:24:44 DEBUG : test-multithread-copy-false-201326593-2: Starting multi-thread copy with 4 chunks of size 64Mi with 4 parallel streams 2025/08/05 01:24:44 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 4/4 (201326592-201326593) size 1 starting 2025/08/05 01:24:44 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 1/4 (0-67108864) size 64Mi starting 2025/08/05 01:24:44 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 2/4 (67108864-134217728) size 64Mi starting 2025/08/05 01:24:44 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 3/4 (134217728-201326592) size 64Mi starting 2025/08/05 01:24:44 DEBUG : test-multithread-copy-false-201326593-2: writing chunk 3 2025/08/05 01:24:44 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 4/4 (201326592-201326593) size 1 finished 2025/08/05 01:24:45 DEBUG : test-multithread-copy-false-201326593-2: writing chunk 0 2025/08/05 01:24:45 DEBUG : test-multithread-copy-false-201326593-2: writing chunk 2 2025/08/05 01:24:45 DEBUG : test-multithread-copy-false-201326593-2: writing chunk 1 2025/08/05 01:24:50 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 1/4 (0-67108864) size 64Mi finished 2025/08/05 01:24:50 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 3/4 (134217728-201326592) size 64Mi finished 2025/08/05 01:24:50 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 2/4 (67108864-134217728) size 64Mi finished 2025/08/05 01:24:50 DEBUG : test-multithread-copy-false-201326593-2: Finished multi-thread copy with 4 parts of size 64Mi === RUN TestMultithreadCopy/upload=true,size=201326591,streams=2 2025/08/05 01:25:23 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: disabling buffering because source is local disk 2025/08/05 01:25:24 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2025/08/05 01:25:24 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: number of streams 4 was bigger than number of chunks 2 2025/08/05 01:25:24 DEBUG : test-multithread-copy-true-201326591-2: Starting multi-thread copy with 2 chunks of size 96Mi with 2 parallel streams 2025/08/05 01:25:24 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: chunk 2/2 (100663296-201326591) size 96.000Mi starting 2025/08/05 01:25:24 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: chunk 1/2 (0-100663296) size 96Mi starting 2025/08/05 01:25:24 DEBUG : test-multithread-copy-true-201326591-2: Sending chunk 1 length 100663295 2025/08/05 01:25:24 DEBUG : test-multithread-copy-true-201326591-2: Sending chunk 0 length 100663296 2025/08/05 01:25:32 DEBUG : test-multithread-copy-true-201326591-2: Done sending chunk 1 2025/08/05 01:25:32 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: chunk 2/2 (100663296-201326591) size 96.000Mi finished 2025/08/05 01:25:32 DEBUG : test-multithread-copy-true-201326591-2: Done sending chunk 0 2025/08/05 01:25:32 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: chunk 1/2 (0-100663296) size 96Mi finished 2025/08/05 01:25:32 DEBUG : test-multithread-copy-true-201326591-2: Finishing large file upload with 2 parts 2025/08/05 01:25:33 DEBUG : test-multithread-copy-true-201326591-2: Finished multi-thread copy with 2 parts of size 96Mi === RUN TestMultithreadCopy/upload=true,size=201326592,streams=2 2025/08/05 01:26:05 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: disabling buffering because source is local disk 2025/08/05 01:26:06 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2025/08/05 01:26:06 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: number of streams 4 was bigger than number of chunks 2 2025/08/05 01:26:06 DEBUG : test-multithread-copy-true-201326592-2: Starting multi-thread copy with 2 chunks of size 96Mi with 2 parallel streams 2025/08/05 01:26:06 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: chunk 2/2 (100663296-201326592) size 96Mi starting 2025/08/05 01:26:06 DEBUG : test-multithread-copy-true-201326592-2: Sending chunk 1 length 100663296 2025/08/05 01:26:06 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: chunk 1/2 (0-100663296) size 96Mi starting 2025/08/05 01:26:06 DEBUG : test-multithread-copy-true-201326592-2: Sending chunk 0 length 100663296 2025/08/05 01:26:14 DEBUG : test-multithread-copy-true-201326592-2: Done sending chunk 1 2025/08/05 01:26:14 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: chunk 2/2 (100663296-201326592) size 96Mi finished 2025/08/05 01:26:14 DEBUG : test-multithread-copy-true-201326592-2: Done sending chunk 0 2025/08/05 01:26:14 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: chunk 1/2 (0-100663296) size 96Mi finished 2025/08/05 01:26:14 DEBUG : test-multithread-copy-true-201326592-2: Finishing large file upload with 2 parts 2025/08/05 01:26:15 DEBUG : test-multithread-copy-true-201326592-2: Finished multi-thread copy with 2 parts of size 96Mi === RUN TestMultithreadCopy/upload=true,size=201326593,streams=2 2025/08/05 01:26:49 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: disabling buffering because source is local disk 2025/08/05 01:26:50 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2025/08/05 01:26:50 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2025/08/05 01:26:50 DEBUG : test-multithread-copy-true-201326593-2: Starting multi-thread copy with 3 chunks of size 96Mi with 3 parallel streams 2025/08/05 01:26:50 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 3/3 (201326592-201326593) size 1 starting 2025/08/05 01:26:50 DEBUG : test-multithread-copy-true-201326593-2: Sending chunk 2 length 1 2025/08/05 01:26:50 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 2/3 (100663296-201326592) size 96Mi starting 2025/08/05 01:26:50 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 1/3 (0-100663296) size 96Mi starting 2025/08/05 01:26:50 DEBUG : test-multithread-copy-true-201326593-2: Sending chunk 1 length 100663296 2025/08/05 01:26:50 DEBUG : test-multithread-copy-true-201326593-2: Sending chunk 0 length 100663296 2025/08/05 01:26:51 DEBUG : test-multithread-copy-true-201326593-2: Done sending chunk 2 2025/08/05 01:26:51 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 3/3 (201326592-201326593) size 1 finished 2025/08/05 01:26:58 DEBUG : test-multithread-copy-true-201326593-2: Done sending chunk 1 2025/08/05 01:26:58 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 2/3 (100663296-201326592) size 96Mi finished 2025/08/05 01:26:58 DEBUG : test-multithread-copy-true-201326593-2: Done sending chunk 0 2025/08/05 01:26:58 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 1/3 (0-100663296) size 96Mi finished 2025/08/05 01:26:58 DEBUG : test-multithread-copy-true-201326593-2: Finishing large file upload with 3 parts 2025/08/05 01:26:59 DEBUG : test-multithread-copy-true-201326593-2: Finished multi-thread copy with 3 parts of size 96Mi --- PASS: TestMultithreadCopy (264.52s) --- PASS: TestMultithreadCopy/upload=false,size=201326591,streams=2 (43.33s) --- PASS: TestMultithreadCopy/upload=false,size=201326592,streams=2 (44.87s) --- PASS: TestMultithreadCopy/upload=false,size=201326593,streams=2 (42.39s) --- PASS: TestMultithreadCopy/upload=true,size=201326591,streams=2 (42.49s) --- PASS: TestMultithreadCopy/upload=true,size=201326592,streams=2 (43.22s) --- PASS: TestMultithreadCopy/upload=true,size=201326593,streams=2 (43.49s) === RUN TestMultithreadCopyAbort run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" 2025/08/05 01:27:12 DEBUG : chunksize-probe: Cancelling large file upload 2025/08/05 01:27:34 DEBUG : test-multithread-abort: multi-thread copy: disabling buffering because source is local disk 2025/08/05 01:27:35 DEBUG : test-multithread-abort: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 1 2025/08/05 01:27:35 DEBUG : test-multithread-abort: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2025/08/05 01:27:35 DEBUG : test-multithread-abort: Starting multi-thread copy with 3 chunks of size 96Mi with 3 parallel streams 2025/08/05 01:27:35 DEBUG : test-multithread-abort: multi-thread copy: chunk 3/3 (201326592-201326593) size 1 starting 2025/08/05 01:27:35 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2025/08/05 01:27:35 DEBUG : test-multithread-abort: multi-thread copy: chunk 1/3 (0-100663296) size 96Mi starting 2025/08/05 01:27:35 DEBUG : test-multithread-abort: multi-thread copy: chunk 2/3 (100663296-201326592) size 96Mi starting 2025/08/05 01:27:35 DEBUG : Open with options = [RangeOption(0,100663295)] 2025/08/05 01:27:35 DEBUG : Open with options = [RangeOption(100663296,201326591)] 2025/08/05 01:27:35 DEBUG : test-multithread-abort: Sending chunk 0 length 100663296 2025/08/05 01:27:35 DEBUG : test-multithread-abort: Sending chunk 1 length 100663296 2025/08/05 01:27:43 DEBUG : test-multithread-abort: Done sending chunk 0 2025/08/05 01:27:43 DEBUG : test-multithread-abort: multi-thread copy: chunk 1/3 (0-100663296) size 96Mi finished 2025/08/05 01:27:43 DEBUG : test-multithread-abort: Done sending chunk 1 2025/08/05 01:27:43 DEBUG : test-multithread-abort: multi-thread copy: chunk 2/3 (100663296-201326592) size 96Mi finished 2025/08/05 01:27:43 DEBUG : Returning error reader 2025/08/05 01:27:43 DEBUG : test-multithread-abort: Sending chunk 2 length 1 2025/08/05 01:27:43 DEBUG : BOOM: simulated read failure 2025/08/05 01:27:43 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 0/10: BOOM: simulated read failure 2025/08/05 01:27:43 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2025/08/05 01:27:44 DEBUG : Returning error reader 2025/08/05 01:27:44 DEBUG : BOOM: simulated read failure 2025/08/05 01:27:44 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 1/10: BOOM: simulated read failure 2025/08/05 01:27:44 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2025/08/05 01:27:45 DEBUG : Returning error reader 2025/08/05 01:27:45 DEBUG : BOOM: simulated read failure 2025/08/05 01:27:45 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 2/10: BOOM: simulated read failure 2025/08/05 01:27:45 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2025/08/05 01:27:46 DEBUG : Returning error reader 2025/08/05 01:27:46 DEBUG : BOOM: simulated read failure 2025/08/05 01:27:46 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 3/10: BOOM: simulated read failure 2025/08/05 01:27:46 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2025/08/05 01:27:47 DEBUG : Returning error reader 2025/08/05 01:27:47 DEBUG : BOOM: simulated read failure 2025/08/05 01:27:47 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 4/10: BOOM: simulated read failure 2025/08/05 01:27:47 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2025/08/05 01:27:48 DEBUG : Returning error reader 2025/08/05 01:27:48 DEBUG : BOOM: simulated read failure 2025/08/05 01:27:48 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 5/10: BOOM: simulated read failure 2025/08/05 01:27:48 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2025/08/05 01:27:49 DEBUG : Returning error reader 2025/08/05 01:27:49 DEBUG : BOOM: simulated read failure 2025/08/05 01:27:49 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 6/10: BOOM: simulated read failure 2025/08/05 01:27:49 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2025/08/05 01:27:50 DEBUG : Returning error reader 2025/08/05 01:27:50 DEBUG : BOOM: simulated read failure 2025/08/05 01:27:50 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 7/10: BOOM: simulated read failure 2025/08/05 01:27:50 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2025/08/05 01:27:51 DEBUG : Returning error reader 2025/08/05 01:27:51 DEBUG : BOOM: simulated read failure 2025/08/05 01:27:51 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 8/10: BOOM: simulated read failure 2025/08/05 01:27:51 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2025/08/05 01:27:52 DEBUG : Returning error reader 2025/08/05 01:27:52 DEBUG : BOOM: simulated read failure 2025/08/05 01:27:52 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 9/10: BOOM: simulated read failure 2025/08/05 01:27:52 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2025/08/05 01:27:53 DEBUG : Returning error reader 2025/08/05 01:27:53 DEBUG : BOOM: simulated read failure 2025/08/05 01:27:53 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 10/10: BOOM: simulated read failure 2025/08/05 01:27:53 DEBUG : test-multithread-abort: Reopen failed after offset 0 bytes read: failed to reopen: too many retries 2025/08/05 01:27:53 DEBUG : test-multithread-abort: Error sending chunk 2 (retry=false): Post "https://pod-000-1043-17.backblaze.com/b2api/v1/b2_upload_part/4_z9fe2edb3d02d7b7b9f880e1b_f207935410ab5a979_d20250805_m012735_c001_v0001043_t0006_u01754357255153/0026": BOOM: simulated read failure: &url.Error{Op:"Post", URL:"https://pod-000-1043-17.backblaze.com/b2api/v1/b2_upload_part/4_z9fe2edb3d02d7b7b9f880e1b_f207935410ab5a979_d20250805_m012735_c001_v0001043_t0006_u01754357255153/0026", Err:(*errors.errorString)(0xc00047d060)} 2025/08/05 01:27:53 DEBUG : test-multithread-abort: Error sending chunk 2: Post "https://pod-000-1043-17.backblaze.com/b2api/v1/b2_upload_part/4_z9fe2edb3d02d7b7b9f880e1b_f207935410ab5a979_d20250805_m012735_c001_v0001043_t0006_u01754357255153/0026": BOOM: simulated read failure 2025/08/05 01:27:53 DEBUG : test-multithread-abort: multi-thread copy: chunk 3/3 failed: multi-thread copy: failed to write chunk: Post "https://pod-000-1043-17.backblaze.com/b2api/v1/b2_upload_part/4_z9fe2edb3d02d7b7b9f880e1b_f207935410ab5a979_d20250805_m012735_c001_v0001043_t0006_u01754357255153/0026": BOOM: simulated read failure 2025/08/05 01:27:53 DEBUG : test-multithread-abort: multi-thread copy: cancelling transfer on exit 2025/08/05 01:27:53 DEBUG : test-multithread-abort: Cancelling large file upload --- PASS: TestMultithreadCopyAbort (42.87s) === RUN TestSizeDiffers --- PASS: TestSizeDiffers (0.00s) === RUN TestReOpen === RUN TestReOpen/Normal === RUN TestReOpen/Normal/Basics 2025/08/05 01:27:55 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/ErrorAtStart === RUN TestReOpen/Normal/WithErrors 2025/08/05 01:27:55 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2025/08/05 01:27:55 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2025/08/05 01:27:55 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/Normal/TooManyErrors 2025/08/05 01:27:55 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2025/08/05 01:27:55 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2025/08/05 01:27:55 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2025/08/05 01:27:55 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/Normal/Seek 2025/08/05 01:27:55 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/08/05 01:27:55 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/08/05 01:27:55 DEBUG : potato: Seek from 5 to 2 === RUN TestReOpen/Normal/AccountRead === RUN TestReOpen/Normal/AccountReadDelay 2025/08/05 01:27:55 DEBUG : potato: Seek from 10 to 0 2025/08/05 01:27:55 DEBUG : potato: Seek from 10 to 0 2025/08/05 01:27:55 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/AccountReadError === RUN TestReOpen/WithRangeOption === RUN TestReOpen/WithRangeOption/Basics 2025/08/05 01:27:55 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/ErrorAtStart === RUN TestReOpen/WithRangeOption/WithErrors 2025/08/05 01:27:55 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2025/08/05 01:27:55 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2025/08/05 01:27:55 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithRangeOption/TooManyErrors 2025/08/05 01:27:55 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2025/08/05 01:27:55 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2025/08/05 01:27:55 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2025/08/05 01:27:55 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithRangeOption/Seek 2025/08/05 01:27:55 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/08/05 01:27:55 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/08/05 01:27:55 DEBUG : potato: Seek from 5 to 2 2025/08/05 01:27:55 DEBUG : potato: Seek from 7 to 4 === RUN TestReOpen/WithRangeOption/AccountRead === RUN TestReOpen/WithRangeOption/AccountReadDelay 2025/08/05 01:27:55 DEBUG : potato: Seek from 7 to 0 2025/08/05 01:27:55 DEBUG : potato: Seek from 7 to 0 2025/08/05 01:27:55 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/AccountReadError === RUN TestReOpen/WithSeekOption === RUN TestReOpen/WithSeekOption/Basics 2025/08/05 01:27:55 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/ErrorAtStart === RUN TestReOpen/WithSeekOption/WithErrors 2025/08/05 01:27:55 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2025/08/05 01:27:55 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2025/08/05 01:27:55 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithSeekOption/TooManyErrors 2025/08/05 01:27:55 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2025/08/05 01:27:55 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2025/08/05 01:27:55 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2025/08/05 01:27:55 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithSeekOption/Seek 2025/08/05 01:27:55 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/08/05 01:27:55 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/08/05 01:27:55 DEBUG : potato: Seek from 5 to 2 2025/08/05 01:27:55 DEBUG : potato: Seek from 7 to 5 === RUN TestReOpen/WithSeekOption/AccountRead === RUN TestReOpen/WithSeekOption/AccountReadDelay 2025/08/05 01:27:55 DEBUG : potato: Seek from 8 to 0 2025/08/05 01:27:55 DEBUG : potato: Seek from 8 to 0 2025/08/05 01:27:55 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/AccountReadError === RUN TestReOpen/UnknownSize === RUN TestReOpen/UnknownSize/Basics 2025/08/05 01:27:55 DEBUG : potato: Seek from 9 to 0 === RUN TestReOpen/UnknownSize/ErrorAtStart === RUN TestReOpen/UnknownSize/WithErrors 2025/08/05 01:27:55 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2025/08/05 01:27:55 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2025/08/05 01:27:55 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/UnknownSize/TooManyErrors 2025/08/05 01:27:55 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2025/08/05 01:27:55 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2025/08/05 01:27:55 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2025/08/05 01:27:55 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/UnknownSize/Seek 2025/08/05 01:27:55 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/08/05 01:27:55 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/08/05 01:27:55 DEBUG : potato: Seek from 5 to 2 2025/08/05 01:27:55 DEBUG : potato: Seek from 7 to 6 === RUN TestReOpen/UnknownSize/AccountRead === RUN TestReOpen/UnknownSize/AccountReadDelay 2025/08/05 01:27:55 DEBUG : potato: Seek from 9 to 0 2025/08/05 01:27:55 DEBUG : potato: Seek from 9 to 0 2025/08/05 01:27:55 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/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/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/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/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:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", 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 (5.24s) --- 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 2025/08/05 01:28:00 DEBUG : Creating backend with remote "nonexistent" 2025/08/05 01:28:00 DEBUG : Config file has changed externally - reloading 2025/08/05 01:28:00 DEBUG : Creating backend with remote "nonexistent" 2025/08/05 01:28:00 DEBUG : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: Waiting for checks to finish 2025/08/05 01:28:00 ERROR : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: error reading source root directory: directory not found 2025/08/05 01:28:00 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: 0 differences found 2025/08/05 01:28:00 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: 2 errors while checking --- PASS: TestCheckFsError (0.01s) === RUN TestCheckDownload run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", 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 (7.11s) --- PASS: TestCheckDownload/1 (0.74s) --- PASS: TestCheckDownload/2 (0.38s) --- PASS: TestCheckDownload/3 (0.36s) --- PASS: TestCheckDownload/4 (0.55s) --- PASS: TestCheckDownload/5 (0.37s) --- PASS: TestCheckDownload/6 (0.38s) --- PASS: TestCheckDownload/7 (0.39s) === RUN TestCheckSizeOnly run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" === RUN TestCheckSizeOnly/1 === RUN TestCheckSizeOnly/2 === RUN TestCheckSizeOnly/3 === RUN TestCheckSizeOnly/4 === RUN TestCheckSizeOnly/5 === RUN TestCheckSizeOnly/6 === RUN TestCheckSizeOnly/7 --- PASS: TestCheckSizeOnly (4.88s) --- PASS: TestCheckSizeOnly/1 (0.18s) --- PASS: TestCheckSizeOnly/2 (0.18s) --- PASS: TestCheckSizeOnly/3 (0.18s) --- PASS: TestCheckSizeOnly/4 (0.19s) --- 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:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" 2025/08/05 01:28:13 NOTICE: test.sum: improperly formatted checksum line 4 2025/08/05 01:28:13 NOTICE: test.sum: improperly formatted checksum line 5 2025/08/05 01:28:13 NOTICE: test.sum: improperly formatted checksum line 6 2025/08/05 01:28:13 NOTICE: test.sum: 2 warning(s) suppressed... 2025/08/05 01:28:14 NOTICE: test.sum: improperly formatted checksum line 4 2025/08/05 01:28:14 NOTICE: test.sum: improperly formatted checksum line 5 2025/08/05 01:28:14 NOTICE: test.sum: improperly formatted checksum line 6 2025/08/05 01:28:14 NOTICE: test.sum: 2 warning(s) suppressed... --- PASS: TestParseSumFile (2.36s) === RUN TestCheckSum run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" 2025/08/05 01:28:14 DEBUG : Creating backend with remote "TestB2:rclone-test-wuwasic8suhe/data" check_test.go:350: B2 bucket rclone-test-wuwasic8suhe path data lacks md5, skipping --- SKIP: TestCheckSum (1.49s) === RUN TestCheckSumDownload run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" 2025/08/05 01:28:16 DEBUG : Creating backend with remote "TestB2:rclone-test-wuwasic8suhe/data" === RUN TestCheckSumDownload/subtest1 === RUN TestCheckSumDownload/subtest2 === RUN TestCheckSumDownload/subtest3 === RUN TestCheckSumDownload/subtest4 === RUN TestCheckSumDownload/subtest5 === RUN TestCheckSumDownload/subtest6 === RUN TestCheckSumDownload/subtest7 --- PASS: TestCheckSumDownload (12.92s) --- PASS: TestCheckSumDownload/subtest1 (1.29s) --- PASS: TestCheckSumDownload/subtest2 (0.77s) --- PASS: TestCheckSumDownload/subtest3 (0.91s) --- PASS: TestCheckSumDownload/subtest4 (0.74s) --- PASS: TestCheckSumDownload/subtest5 (0.75s) --- PASS: TestCheckSumDownload/subtest6 (0.75s) --- PASS: TestCheckSumDownload/subtest7 (0.76s) === RUN TestApplyTransforms 2025/08/05 01:28:29 DEBUG : Creating backend with remote "TestB2:rclone-test-zazokov9neqo" 2025/08/05 01:28:29 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/08/05 01:28:29 DEBUG : Creating backend with remote "/tmp/rclone3520625157" run.go:180: Remote "B2 bucket rclone-test-zazokov9neqo", Local "Local file system at /tmp/rclone3520625157", Modify Window "1ms" upper checkfile vs. lower remote (without normalization) 2025/08/05 01:28:35 ERROR : hello, world!: sum not found 2025/08/05 01:28:35 ERROR : HELLO, WORLD!: file not in B2 bucket rclone-test-zazokov9neqo 2025/08/05 01:28:35 NOTICE: B2 bucket rclone-test-zazokov9neqo: 1 files missing 2025/08/05 01:28:35 NOTICE: 1 hashes missing 2025/08/05 01:28:35 NOTICE: B2 bucket rclone-test-zazokov9neqo: 1 differences found 2025/08/05 01:28:35 NOTICE: B2 bucket rclone-test-zazokov9neqo: 2 errors while checking upper checkfile vs. lower remote (with normalization) 2025/08/05 01:28:35 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/08/05 01:28:35 NOTICE: B2 bucket rclone-test-zazokov9neqo: 0 differences found 2025/08/05 01:28:35 NOTICE: B2 bucket rclone-test-zazokov9neqo: 1 matching files 2025/08/05 01:28:35 DEBUG : Creating backend with remote "TestB2:rclone-test-piniqaq2baxi" 2025/08/05 01:28:35 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/08/05 01:28:36 DEBUG : Creating backend with remote "/tmp/rclone4102178189" run.go:180: Remote "B2 bucket rclone-test-piniqaq2baxi", Local "Local file system at /tmp/rclone4102178189", Modify Window "1ms" lower checkfile vs. upper remote (without normalization) 2025/08/05 01:28:41 ERROR : HELLO, WORLD!: sum not found 2025/08/05 01:28:41 ERROR : hello, world!: file not in B2 bucket rclone-test-piniqaq2baxi 2025/08/05 01:28:41 NOTICE: B2 bucket rclone-test-piniqaq2baxi: 1 files missing 2025/08/05 01:28:41 NOTICE: 1 hashes missing 2025/08/05 01:28:41 NOTICE: B2 bucket rclone-test-piniqaq2baxi: 1 differences found 2025/08/05 01:28:41 NOTICE: B2 bucket rclone-test-piniqaq2baxi: 2 errors while checking lower checkfile vs. upper remote (with normalization) 2025/08/05 01:28:42 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/08/05 01:28:42 NOTICE: B2 bucket rclone-test-piniqaq2baxi: 0 differences found 2025/08/05 01:28:42 NOTICE: B2 bucket rclone-test-piniqaq2baxi: 1 matching files 2025/08/05 01:28:42 DEBUG : Creating backend with remote "TestB2:rclone-test-gagubod8leye" 2025/08/05 01:28:42 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/08/05 01:28:42 DEBUG : Creating backend with remote "/tmp/rclone3600638007" run.go:180: Remote "B2 bucket rclone-test-gagubod8leye", Local "Local file system at /tmp/rclone3600638007", Modify Window "1ms" lower checkfile vs. upperlowermixed remote (without normalization) 2025/08/05 01:28:47 ERROR : HeLlO, wOrLd!: sum not found 2025/08/05 01:28:47 ERROR : hello, world!: file not in B2 bucket rclone-test-gagubod8leye 2025/08/05 01:28:47 NOTICE: B2 bucket rclone-test-gagubod8leye: 1 files missing 2025/08/05 01:28:47 NOTICE: 1 hashes missing 2025/08/05 01:28:47 NOTICE: B2 bucket rclone-test-gagubod8leye: 1 differences found 2025/08/05 01:28:47 NOTICE: B2 bucket rclone-test-gagubod8leye: 2 errors while checking lower checkfile vs. upperlowermixed remote (with normalization) 2025/08/05 01:28:48 DEBUG : HeLlO, wOrLd!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/08/05 01:28:48 NOTICE: B2 bucket rclone-test-gagubod8leye: 0 differences found 2025/08/05 01:28:48 NOTICE: B2 bucket rclone-test-gagubod8leye: 1 matching files 2025/08/05 01:28:48 DEBUG : Creating backend with remote "TestB2:rclone-test-riwusew0tadi" 2025/08/05 01:28:48 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/08/05 01:28:49 DEBUG : Creating backend with remote "/tmp/rclone2025964384" run.go:180: Remote "B2 bucket rclone-test-riwusew0tadi", Local "Local file system at /tmp/rclone2025964384", Modify Window "1ms" upperlowermixed checkfile vs. upper remote (without normalization) 2025/08/05 01:28:54 ERROR : HELLO, WORLD!: sum not found 2025/08/05 01:28:54 ERROR : HeLlO, wOrLd!: file not in B2 bucket rclone-test-riwusew0tadi 2025/08/05 01:28:54 NOTICE: B2 bucket rclone-test-riwusew0tadi: 1 files missing 2025/08/05 01:28:54 NOTICE: 1 hashes missing 2025/08/05 01:28:54 NOTICE: B2 bucket rclone-test-riwusew0tadi: 1 differences found 2025/08/05 01:28:54 NOTICE: B2 bucket rclone-test-riwusew0tadi: 2 errors while checking upperlowermixed checkfile vs. upper remote (with normalization) 2025/08/05 01:28:54 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/08/05 01:28:54 NOTICE: B2 bucket rclone-test-riwusew0tadi: 0 differences found 2025/08/05 01:28:54 NOTICE: B2 bucket rclone-test-riwusew0tadi: 1 matching files 2025/08/05 01:28:54 DEBUG : Creating backend with remote "TestB2:rclone-test-pikibob3cede" 2025/08/05 01:28:54 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/08/05 01:28:55 DEBUG : Creating backend with remote "/tmp/rclone2412380925" run.go:180: Remote "B2 bucket rclone-test-pikibob3cede", Local "Local file system at /tmp/rclone2412380925", Modify Window "1ms" NFD checkfile vs. NFC remote (without normalization) 2025/08/05 01:29:00 ERROR : 測試_Русский___ě_áñ: sum not found 2025/08/05 01:29:00 ERROR : 測試_Русский___ě_áñ: file not in B2 bucket rclone-test-pikibob3cede 2025/08/05 01:29:00 NOTICE: B2 bucket rclone-test-pikibob3cede: 1 files missing 2025/08/05 01:29:00 NOTICE: 1 hashes missing 2025/08/05 01:29:00 NOTICE: B2 bucket rclone-test-pikibob3cede: 1 differences found 2025/08/05 01:29:00 NOTICE: B2 bucket rclone-test-pikibob3cede: 2 errors while checking NFD checkfile vs. NFC remote (with normalization) 2025/08/05 01:29:01 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/08/05 01:29:01 NOTICE: B2 bucket rclone-test-pikibob3cede: 0 differences found 2025/08/05 01:29:01 NOTICE: B2 bucket rclone-test-pikibob3cede: 1 matching files 2025/08/05 01:29:01 DEBUG : Creating backend with remote "TestB2:rclone-test-loyukom0kozo" 2025/08/05 01:29:01 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/08/05 01:29:01 DEBUG : Creating backend with remote "/tmp/rclone653641592" run.go:180: Remote "B2 bucket rclone-test-loyukom0kozo", Local "Local file system at /tmp/rclone653641592", Modify Window "1ms" NFC checkfile vs. NFD remote (without normalization) 2025/08/05 01:29:06 ERROR : 測試_Русский___ě_áñ: sum not found 2025/08/05 01:29:06 ERROR : 測試_Русский___ě_áñ: file not in B2 bucket rclone-test-loyukom0kozo 2025/08/05 01:29:06 NOTICE: B2 bucket rclone-test-loyukom0kozo: 1 files missing 2025/08/05 01:29:06 NOTICE: 1 hashes missing 2025/08/05 01:29:06 NOTICE: B2 bucket rclone-test-loyukom0kozo: 1 differences found 2025/08/05 01:29:06 NOTICE: B2 bucket rclone-test-loyukom0kozo: 2 errors while checking NFC checkfile vs. NFD remote (with normalization) 2025/08/05 01:29:07 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/08/05 01:29:07 NOTICE: B2 bucket rclone-test-loyukom0kozo: 0 differences found 2025/08/05 01:29:07 NOTICE: B2 bucket rclone-test-loyukom0kozo: 1 matching files 2025/08/05 01:29:07 DEBUG : Creating backend with remote "TestB2:rclone-test-zaxigul8rica" 2025/08/05 01:29:07 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/08/05 01:29:08 DEBUG : Creating backend with remote "/tmp/rclone3855160431" run.go:180: Remote "B2 bucket rclone-test-zaxigul8rica", Local "Local file system at /tmp/rclone3855160431", Modify Window "1ms" NFDx2 checkfile vs. both remote (without normalization) 2025/08/05 01:29:13 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2025/08/05 01:29:13 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-zaxigul8rica 2025/08/05 01:29:13 NOTICE: B2 bucket rclone-test-zaxigul8rica: 1 files missing 2025/08/05 01:29:13 NOTICE: 1 hashes missing 2025/08/05 01:29:13 NOTICE: B2 bucket rclone-test-zaxigul8rica: 1 differences found 2025/08/05 01:29:13 NOTICE: B2 bucket rclone-test-zaxigul8rica: 2 errors while checking NFDx2 checkfile vs. both remote (with normalization) 2025/08/05 01:29:14 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/08/05 01:29:14 NOTICE: B2 bucket rclone-test-zaxigul8rica: 0 differences found 2025/08/05 01:29:14 NOTICE: B2 bucket rclone-test-zaxigul8rica: 1 matching files 2025/08/05 01:29:14 DEBUG : Creating backend with remote "TestB2:rclone-test-zekokeb1moco" 2025/08/05 01:29:14 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/08/05 01:29:14 DEBUG : Creating backend with remote "/tmp/rclone2583341473" run.go:180: Remote "B2 bucket rclone-test-zekokeb1moco", Local "Local file system at /tmp/rclone2583341473", Modify Window "1ms" NFCx2 checkfile vs. both remote (without normalization) 2025/08/05 01:29:19 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2025/08/05 01:29:19 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-zekokeb1moco 2025/08/05 01:29:19 NOTICE: B2 bucket rclone-test-zekokeb1moco: 1 files missing 2025/08/05 01:29:19 NOTICE: 1 hashes missing 2025/08/05 01:29:19 NOTICE: B2 bucket rclone-test-zekokeb1moco: 1 differences found 2025/08/05 01:29:19 NOTICE: B2 bucket rclone-test-zekokeb1moco: 2 errors while checking NFCx2 checkfile vs. both remote (with normalization) 2025/08/05 01:29:20 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/08/05 01:29:20 NOTICE: B2 bucket rclone-test-zekokeb1moco: 0 differences found 2025/08/05 01:29:20 NOTICE: B2 bucket rclone-test-zekokeb1moco: 1 matching files 2025/08/05 01:29:20 DEBUG : Creating backend with remote "TestB2:rclone-test-kiqikiq3zewe" 2025/08/05 01:29:20 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/08/05 01:29:21 DEBUG : Creating backend with remote "/tmp/rclone2413091371" run.go:180: Remote "B2 bucket rclone-test-kiqikiq3zewe", Local "Local file system at /tmp/rclone2413091371", Modify Window "1ms" both checkfile vs. NFDx2 remote (without normalization) 2025/08/05 01:29:25 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2025/08/05 01:29:25 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-kiqikiq3zewe 2025/08/05 01:29:25 NOTICE: B2 bucket rclone-test-kiqikiq3zewe: 1 files missing 2025/08/05 01:29:25 NOTICE: 1 hashes missing 2025/08/05 01:29:25 NOTICE: B2 bucket rclone-test-kiqikiq3zewe: 1 differences found 2025/08/05 01:29:25 NOTICE: B2 bucket rclone-test-kiqikiq3zewe: 2 errors while checking both checkfile vs. NFDx2 remote (with normalization) 2025/08/05 01:29:26 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/08/05 01:29:26 NOTICE: B2 bucket rclone-test-kiqikiq3zewe: 0 differences found 2025/08/05 01:29:26 NOTICE: B2 bucket rclone-test-kiqikiq3zewe: 1 matching files 2025/08/05 01:29:26 DEBUG : Creating backend with remote "TestB2:rclone-test-risabuq0lifu" 2025/08/05 01:29:26 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/08/05 01:29:27 DEBUG : Creating backend with remote "/tmp/rclone3204785306" run.go:180: Remote "B2 bucket rclone-test-risabuq0lifu", Local "Local file system at /tmp/rclone3204785306", Modify Window "1ms" both checkfile vs. NFCx2 remote (without normalization) 2025/08/05 01:29:32 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2025/08/05 01:29:32 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-risabuq0lifu 2025/08/05 01:29:32 NOTICE: B2 bucket rclone-test-risabuq0lifu: 1 files missing 2025/08/05 01:29:32 NOTICE: 1 hashes missing 2025/08/05 01:29:32 NOTICE: B2 bucket rclone-test-risabuq0lifu: 1 differences found 2025/08/05 01:29:32 NOTICE: B2 bucket rclone-test-risabuq0lifu: 2 errors while checking both checkfile vs. NFCx2 remote (with normalization) 2025/08/05 01:29:32 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/08/05 01:29:32 NOTICE: B2 bucket rclone-test-risabuq0lifu: 0 differences found 2025/08/05 01:29:32 NOTICE: B2 bucket rclone-test-risabuq0lifu: 1 matching files 2025/08/05 01:29:32 DEBUG : B2 bucket rclone-test-risabuq0lifu: Purge remote 2025/08/05 01:29:32 INFO : B2 bucket rclone-test-risabuq0lifu: cleaning bucket "rclone-test-risabuq0lifu" of all files 2025/08/05 01:29:33 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_zcf220d33d09d7b7b9f880e1b_f10103123ac170bd2_d20250805_m012931_c001_v0001116_t0048_u01754357371483") 2025/08/05 01:29:34 DEBUG : B2 bucket rclone-test-kiqikiq3zewe: Purge remote 2025/08/05 01:29:34 INFO : B2 bucket rclone-test-kiqikiq3zewe: cleaning bucket "rclone-test-kiqikiq3zewe" of all files 2025/08/05 01:29:34 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_z9fa2edb3d08d7b7b9f880e1b_f11160491ab4d012a_d20250805_m012925_c001_v0001102_t0056_u01754357365351") 2025/08/05 01:29:38 DEBUG : B2 bucket rclone-test-zekokeb1moco: Purge remote 2025/08/05 01:29:38 INFO : B2 bucket rclone-test-zekokeb1moco: cleaning bucket "rclone-test-zekokeb1moco" of all files 2025/08/05 01:29:38 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_zaff2dd23d08d7b7b9f880e1b_f1039de49c710cc6e_d20250805_m012919_c001_v0001171_t0044_u01754357359199") 2025/08/05 01:29:41 DEBUG : B2 bucket rclone-test-zaxigul8rica: Purge remote 2025/08/05 01:29:41 INFO : B2 bucket rclone-test-zaxigul8rica: cleaning bucket "rclone-test-zaxigul8rica" of all files 2025/08/05 01:29:41 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_zdf12bd83d08d7b7b9f880e1b_f1043bb70f8a21de6_d20250805_m012912_c001_v0001090_t0025_u01754357352613") 2025/08/05 01:29:44 DEBUG : B2 bucket rclone-test-loyukom0kozo: Purge remote 2025/08/05 01:29:44 INFO : B2 bucket rclone-test-loyukom0kozo: cleaning bucket "rclone-test-loyukom0kozo" of all files 2025/08/05 01:29:45 DEBUG : 測試_Русский___ě_áñ: Deleting (id "4_z7f22ad03d08d7b7b9f880e1b_f1016d3c7d3da9eaa_d20250805_m012906_c001_v0001183_t0007_u01754357346304") 2025/08/05 01:29:47 DEBUG : B2 bucket rclone-test-pikibob3cede: Purge remote 2025/08/05 01:29:47 INFO : B2 bucket rclone-test-pikibob3cede: cleaning bucket "rclone-test-pikibob3cede" of all files 2025/08/05 01:29:48 DEBUG : 測試_Русский___ě_áñ: Deleting (id "4_z8f328d73d08d7b7b9f880e1b_f101bdd6dc7312ec4_d20250805_m012859_c001_v0001090_t0028_u01754357339907") 2025/08/05 01:29:50 DEBUG : B2 bucket rclone-test-riwusew0tadi: Purge remote 2025/08/05 01:29:50 INFO : B2 bucket rclone-test-riwusew0tadi: cleaning bucket "rclone-test-riwusew0tadi" of all files 2025/08/05 01:29:51 DEBUG : HELLO, WORLD!: Deleting (id "4_zdf926de3d08d7b7b9f880e1b_f113ac94716b394cd_d20250805_m012853_c001_v0001177_t0025_u01754357333641") 2025/08/05 01:29:53 DEBUG : B2 bucket rclone-test-gagubod8leye: Purge remote 2025/08/05 01:29:53 INFO : B2 bucket rclone-test-gagubod8leye: cleaning bucket "rclone-test-gagubod8leye" of all files 2025/08/05 01:29:54 DEBUG : HeLlO, wOrLd!: Deleting (id "4_z2ff25d63d08d7b7b9f880e1b_f115d5a5eb6373c00_d20250805_m012847_c001_v0001150_t0056_u01754357327300") 2025/08/05 01:29:56 DEBUG : B2 bucket rclone-test-piniqaq2baxi: Purge remote 2025/08/05 01:29:56 INFO : B2 bucket rclone-test-piniqaq2baxi: cleaning bucket "rclone-test-piniqaq2baxi" of all files 2025/08/05 01:29:57 DEBUG : HELLO, WORLD!: Deleting (id "4_zefd23dc3d08d7b7b9f880e1b_f1034ed0adbf852e2_d20250805_m012840_c001_v0001093_t0005_u01754357320803") 2025/08/05 01:29:59 DEBUG : B2 bucket rclone-test-zazokov9neqo: Purge remote 2025/08/05 01:29:59 INFO : B2 bucket rclone-test-zazokov9neqo: cleaning bucket "rclone-test-zazokov9neqo" of all files 2025/08/05 01:30:00 DEBUG : hello, world!: Deleting (id "4_z6fa22d33d08d7b7b9f880e1b_f1057dd748bcd3a0c_d20250805_m012834_c001_v0001137_t0033_u01754357314335") --- PASS: TestApplyTransforms (93.61s) === RUN TestTruncateString --- PASS: TestTruncateString (0.00s) === RUN TestCopyFile run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" 2025/08/05 01:30:03 DEBUG : file1: Need to transfer - File not found at Destination 2025/08/05 01:30:04 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2025/08/05 01:30:04 INFO : file1: Copied (new) to: sub/file2 2025/08/05 01:30:04 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/08/05 01:30:04 DEBUG : file1: Unchanged skipping 2025/08/05 01:30:04 DEBUG : B2 bucket rclone-test-wuwasic8suhe: don't need to copy/move sub/file2, it is already at target location --- PASS: TestCopyFile (2.82s) === RUN TestCopyLongFile run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" copy_test.go:153: Test only runs on local --- SKIP: TestCopyLongFile (0.37s) === RUN TestCopyFileBackupDir run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" 2025/08/05 01:30:06 DEBUG : Creating backend with remote "TestB2:rclone-test-wuwasic8suhe/backup" 2025/08/05 01:30:07 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18) 2025/08/05 01:30:09 DEBUG : dst/file1: sha1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK 2025/08/05 01:30:09 INFO : dst/file1: Copied (server-side copy) 2025/08/05 01:30:09 INFO : dst/file1: Deleted 2025/08/05 01:30:09 DEBUG : dst/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2025/08/05 01:30:09 INFO : dst/file1: Copied (new) --- PASS: TestCopyFileBackupDir (4.60s) === RUN TestCopyFileCompareDest run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" 2025/08/05 01:30:10 DEBUG : Creating backend with remote "TestB2:rclone-test-wuwasic8suhe/dst" 2025/08/05 01:30:11 DEBUG : Creating backend with remote "TestB2:rclone-test-wuwasic8suhe/CompareDest" 2025/08/05 01:30:13 DEBUG : one: Need to transfer - File not found at Destination 2025/08/05 01:30:14 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2025/08/05 01:30:14 INFO : one: Copied (new) 2025/08/05 01:30:15 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/08/05 01:30:15 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2025/08/05 01:30:15 INFO : one: Copied (replaced existing) 2025/08/05 01:30:17 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/08/05 01:30:17 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/08/05 01:30:17 DEBUG : one: Destination found in --compare-dest, skipping 2025/08/05 01:30:18 DEBUG : two: Need to transfer - File not found at Destination 2025/08/05 01:30:18 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/08/05 01:30:18 DEBUG : two: Destination found in --compare-dest, skipping 2025/08/05 01:30:18 DEBUG : two: Need to transfer - File not found at Destination 2025/08/05 01:30:19 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/08/05 01:30:19 DEBUG : two: Destination found in --compare-dest, skipping 2025/08/05 01:30:19 DEBUG : two: Need to transfer - File not found at Destination 2025/08/05 01:30:19 DEBUG : two: Sizes differ (src 5 vs dst 3) 2025/08/05 01:30:20 DEBUG : two: sha1 = a4be8877ad88f3109a6411985a1f359e10e2d930 OK 2025/08/05 01:30:20 INFO : two: Copied (new) --- PASS: TestCopyFileCompareDest (10.68s) === RUN TestCopyFileCopyDest run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" 2025/08/05 01:30:21 DEBUG : Creating backend with remote "TestB2:rclone-test-wuwasic8suhe/dst" 2025/08/05 01:30:22 DEBUG : Creating backend with remote "TestB2:rclone-test-wuwasic8suhe/CopyDest" 2025/08/05 01:30:23 DEBUG : one: Need to transfer - File not found at Destination 2025/08/05 01:30:25 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2025/08/05 01:30:25 INFO : one: Copied (new) 2025/08/05 01:30:26 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/08/05 01:30:26 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2025/08/05 01:30:26 INFO : one: Copied (replaced existing) 2025/08/05 01:30:28 DEBUG : Creating backend with remote "TestB2:rclone-test-wuwasic8suhe/BackupDir" 2025/08/05 01:30:29 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/08/05 01:30:29 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/08/05 01:30:29 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/08/05 01:30:30 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2025/08/05 01:30:30 INFO : one: Copied (server-side copy) 2025/08/05 01:30:31 INFO : one: Deleted 2025/08/05 01:30:31 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2025/08/05 01:30:31 INFO : one: Copied (server-side copy) 2025/08/05 01:30:31 DEBUG : one: Destination found in --copy-dest, using server-side copy 2025/08/05 01:30:32 DEBUG : two: Need to transfer - File not found at Destination 2025/08/05 01:30:32 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/08/05 01:30:33 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2025/08/05 01:30:33 INFO : two: Copied (server-side copy) 2025/08/05 01:30:33 DEBUG : two: Destination found in --copy-dest, using server-side copy 2025/08/05 01:30:33 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/08/05 01:30:33 DEBUG : two: Unchanged skipping 2025/08/05 01:30:34 DEBUG : three: Need to transfer - File not found at Destination 2025/08/05 01:30:34 DEBUG : three: Sizes differ (src 7 vs dst 5) 2025/08/05 01:30:34 DEBUG : three: Destination not found in --copy-dest 2025/08/05 01:30:35 DEBUG : three: sha1 = 370e613335f3cf2f19b2a2d35a86402f55c659f8 OK 2025/08/05 01:30:35 INFO : three: Copied (new) --- PASS: TestCopyFileCopyDest (16.07s) === RUN TestCopyInplace run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" copy_test.go:370: Partial uploads not supported --- SKIP: TestCopyInplace (0.36s) === RUN TestCopyLongFileName run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" copy_test.go:403: Partial uploads not supported --- SKIP: TestCopyLongFileName (0.37s) === RUN TestCopyFileMaxTransfer run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" 2025/08/05 01:30:38 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination 2025/08/05 01:30:39 DEBUG : TestCopyFileMaxTransfer/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2025/08/05 01:30:39 INFO : TestCopyFileMaxTransfer/file1: Copied (new) 2025/08/05 01:30:39 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination 2025/08/05 01:30:39 ERROR : TestCopyFileMaxTransfer/file2: Failed to copy: Post "https://pod-000-1172-06.backblaze.com/b2api/v1/b2_upload_file/9fe2edb3d02d7b7b9f880e1b/c001_v0001172_t0059": max transfer limit reached as set by --max-transfer 2025/08/05 01:30:40 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination 2025/08/05 01:30:40 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination 2025/08/05 01:30:41 DEBUG : TestCopyFileMaxTransfer/file4: sha1 = 4857457d0482fce374e3c6c9775e359ef895c505 OK 2025/08/05 01:30:41 INFO : TestCopyFileMaxTransfer/file4: Copied (new) --- PASS: TestCopyFileMaxTransfer (3.88s) === RUN TestDeduplicateInteractive run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateInteractive (0.36s) === RUN TestDeduplicateSkip run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSkip (0.36s) === RUN TestDeduplicateSizeOnly run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSizeOnly (0.37s) === RUN TestDeduplicateFirst run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateFirst (0.37s) === RUN TestDeduplicateNewest run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateNewest (0.37s) === RUN TestDeduplicateNewestByHash run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" 2025/08/05 01:30:45 INFO : B2 bucket rclone-test-wuwasic8suhe: Looking for duplicate sha1 hashes using newest mode. 2025/08/05 01:30:45 NOTICE: 689c22acfcae77acb230e1781c21f3b277995517: Found 3 files with duplicate sha1 hashes 2025/08/05 01:30:46 INFO : one: Deleted 2025/08/05 01:30:46 INFO : also/one: Deleted 2025/08/05 01:30:46 NOTICE: 689c22acfcae77acb230e1781c21f3b277995517: Deleted 2 extra copies --- PASS: TestDeduplicateNewestByHash (3.35s) === RUN TestDeduplicateOldest run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateOldest (0.37s) === RUN TestDeduplicateLargest run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateLargest (0.36s) === RUN TestDeduplicateSmallest run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSmallest (0.36s) === RUN TestDeduplicateRename run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateRename (0.41s) === RUN TestMergeDirs run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" dedupe_test.go:256: Can't merge directories --- SKIP: TestMergeDirs (0.42s) === RUN TestListDirSorted run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" 2025/08/05 01:30:52 DEBUG : a.txt: Excluded (Size Filter) 2025/08/05 01:30:52 DEBUG : a.txt: Excluded 2025/08/05 01:30:52 DEBUG : sub dir/hello world: Excluded (Size Filter) 2025/08/05 01:30:52 DEBUG : sub dir/hello world: Excluded 2025/08/05 01:30:52 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2025/08/05 01:30:52 DEBUG : sub dir/hello world2: Excluded 2025/08/05 01:30:52 DEBUG : sub dir/hello world: Excluded (Size Filter) 2025/08/05 01:30:52 DEBUG : sub dir/hello world: Excluded 2025/08/05 01:30:52 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2025/08/05 01:30:52 DEBUG : sub dir/hello world2: Excluded 2025/08/05 01:30:53 DEBUG : sub dir/ignore dir: Excluded 2025/08/05 01:30:53 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSorted (7.01s) === RUN TestListDirSortedFn run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" 2025/08/05 01:30:58 DEBUG : a.txt: Excluded (Size Filter) 2025/08/05 01:30:58 DEBUG : a.txt: Excluded 2025/08/05 01:30:59 DEBUG : sub dir/hello world: Excluded (Size Filter) 2025/08/05 01:30:59 DEBUG : sub dir/hello world: Excluded 2025/08/05 01:30:59 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2025/08/05 01:30:59 DEBUG : sub dir/hello world2: Excluded 2025/08/05 01:30:59 DEBUG : sub dir/hello world: Excluded (Size Filter) 2025/08/05 01:30:59 DEBUG : sub dir/hello world: Excluded 2025/08/05 01:30:59 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2025/08/05 01:30:59 DEBUG : sub dir/hello world2: Excluded 2025/08/05 01:31:00 DEBUG : sub dir/ignore dir: Excluded 2025/08/05 01:31:00 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSortedFn (6.35s) === RUN TestListJSON run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", 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 2025/08/05 01:31:05 ERROR : file1: Failed to read hash: hash type not supported === RUN TestListJSON/Metadata --- PASS: TestListJSON (3.66s) --- PASS: TestListJSON/Default (0.19s) --- PASS: TestListJSON/FilesOnly (0.26s) --- PASS: TestListJSON/DirsOnly (0.19s) --- PASS: TestListJSON/Recurse (0.19s) --- PASS: TestListJSON/SubDir (0.18s) --- PASS: TestListJSON/NoModTime (0.19s) --- PASS: TestListJSON/NoMimeType (0.19s) --- PASS: TestListJSON/ShowHash (0.19s) --- PASS: TestListJSON/HashTypes (0.19s) --- PASS: TestListJSON/Metadata (0.19s) === RUN TestStatJSON run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", 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 2025/08/05 01:31:09 DEBUG : Creating backend with remote "TestB2:rclone-test-wuwasic8suhe/notfound" 2025/08/05 01:31:09 DEBUG : Config file has changed externally - reloading --- PASS: TestStatJSON (6.43s) --- PASS: TestStatJSON/Root (0.19s) --- PASS: TestStatJSON/RootFilesOnly (0.00s) --- PASS: TestStatJSON/RootDirsOnly (0.19s) --- PASS: TestStatJSON/Dir (0.73s) --- PASS: TestStatJSON/DirWithTrailingSlash (0.18s) --- PASS: TestStatJSON/File (0.18s) --- PASS: TestStatJSON/NotFound (0.37s) --- PASS: TestStatJSON/DirFilesOnly (0.18s) --- PASS: TestStatJSON/FileFilesOnly (0.18s) --- PASS: TestStatJSON/NotFoundFilesOnly (0.18s) --- PASS: TestStatJSON/DirDirsOnly (0.19s) --- PASS: TestStatJSON/FileDirsOnly (0.18s) --- PASS: TestStatJSON/NotFoundDirsOnly (0.19s) --- PASS: TestStatJSON/RootNotFound (1.82s) === RUN TestMkdir run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" 2025/08/05 01:31:12 INFO : B2 bucket rclone-test-wuwasic8suhe: Making directory 2025/08/05 01:31:12 INFO : B2 bucket rclone-test-wuwasic8suhe: Making directory --- PASS: TestMkdir (0.55s) === RUN TestLsd run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" --- PASS: TestLsd (1.60s) === RUN TestLs run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" --- PASS: TestLs (1.86s) === RUN TestLsWithFilesFrom run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" 2025/08/05 01:31:17 DEBUG : empty space: Excluded (FilesFrom Filter) --- PASS: TestLsWithFilesFrom (2.34s) === RUN TestLsLong run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" --- PASS: TestLsLong (1.97s) === RUN TestHashSums run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" === RUN TestHashSums/Sha1 === RUN TestHashSums/Sha1Download --- PASS: TestHashSums (2.10s) --- PASS: TestHashSums/Sha1 (0.18s) --- PASS: TestHashSums/Sha1Download (0.38s) === RUN TestHashSumsWithErrors 2025/08/05 01:31:22 DEBUG : Creating backend with remote ":memory:" 2025/08/05 01:31:22 ERROR : file1: hash unsupported: hash type not supported --- PASS: TestHashSumsWithErrors (0.00s) === RUN TestHashStream 2025/08/05 01:31:22 DEBUG : Creating md5 hash of 0 bytes read from input stream 2025/08/05 01:31:22 DEBUG : Creating md5 hash of 0 bytes read from input stream 2025/08/05 01:31:22 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2025/08/05 01:31:22 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2025/08/05 01:31:22 DEBUG : Creating md5 hash of 12 bytes read from input stream 2025/08/05 01:31:22 DEBUG : Creating md5 hash of 12 bytes read from input stream 2025/08/05 01:31:22 DEBUG : Creating sha1 hash of 12 bytes read from input stream 2025/08/05 01:31:22 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:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" --- PASS: TestCount (2.23s) === RUN TestDelete run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" 2025/08/05 01:31:26 DEBUG : Waiting for deletions to finish 2025/08/05 01:31:26 DEBUG : large: Excluded (Size Filter) 2025/08/05 01:31:27 INFO : medium: Deleted 2025/08/05 01:31:27 INFO : small: Deleted --- PASS: TestDelete (2.85s) === RUN TestMaxDelete run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" 2025/08/05 01:31:29 DEBUG : Waiting for deletions to finish 2025/08/05 01:31:29 ERROR : small: Got fatal error on delete: --max-delete threshold reached 2025/08/05 01:31:29 INFO : large: Deleted 2025/08/05 01:31:29 INFO : medium: Deleted --- PASS: TestMaxDelete (2.91s) === RUN TestMaxDeleteSizeLargeFile run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" 2025/08/05 01:31:32 DEBUG : Waiting for deletions to finish 2025/08/05 01:31:32 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached 2025/08/05 01:31:33 INFO : medium: Deleted 2025/08/05 01:31:33 INFO : small: Deleted --- PASS: TestMaxDeleteSizeLargeFile (3.16s) === RUN TestMaxDeleteSize run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" 2025/08/05 01:31:35 DEBUG : Waiting for deletions to finish 2025/08/05 01:31:35 ERROR : small: Got fatal error on delete: --max-delete-size threshold reached 2025/08/05 01:31:35 INFO : large: Deleted 2025/08/05 01:31:35 INFO : medium: Deleted --- PASS: TestMaxDeleteSize (2.61s) === RUN TestReadFile run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" --- PASS: TestReadFile (2.14s) === RUN TestRetry 2025/08/05 01:31:38 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 1/5 2025/08/05 01:31:38 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 2/5 2025/08/05 01:31:38 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2025/08/05 01:31:38 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2025/08/05 01:31:38 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2025/08/05 01:31:38 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2025/08/05 01:31:38 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:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" --- PASS: TestCat (4.53s) === RUN TestPurge 2025/08/05 01:31:43 DEBUG : Creating backend with remote "TestB2:rclone-test-haweyaz5yuwu" 2025/08/05 01:31:43 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/08/05 01:31:44 DEBUG : Creating backend with remote "/tmp/rclone595718617" run.go:180: Remote "B2 bucket rclone-test-haweyaz5yuwu", Local "Local file system at /tmp/rclone595718617", Modify Window "1ms" 2025/08/05 01:31:48 INFO : A2: Making directory 2025/08/05 01:31:48 INFO : A1/B2: Making directory 2025/08/05 01:31:48 INFO : A1/B2/C2: Making directory 2025/08/05 01:31:48 INFO : A1/B1/C3: Making directory 2025/08/05 01:31:48 INFO : A3: Making directory 2025/08/05 01:31:48 INFO : A3/B3: Making directory 2025/08/05 01:31:48 INFO : A3/B3/C4: Making directory fstest.go:247: Filtering empty directory "A2" fstest.go:247: Filtering empty directory "A1/B2" fstest.go:247: Filtering empty directory "A1/B2/C2" fstest.go:247: Filtering empty directory "A1/B1/C3" fstest.go:247: Filtering empty directory "A3" fstest.go:247: Filtering empty directory "A3/B3" fstest.go:247: Filtering empty directory "A3/B3/C4" 2025/08/05 01:31:48 INFO : B2 bucket rclone-test-haweyaz5yuwu: cleaning bucket "rclone-test-haweyaz5yuwu" of all files 2025/08/05 01:31:48 DEBUG : A1/B1/C1/one: Deleting (id "4_z3f621da3d0bd7b7b9f880e1b_f11521028bc32bdf2_d20250805_m013147_c001_v0001157_t0014_u01754357507901") fstest.go:247: Filtering empty directory "A2" fstest.go:247: Filtering empty directory "A1/B2" fstest.go:247: Filtering empty directory "A1/B2/C2" fstest.go:247: Filtering empty directory "A3" fstest.go:247: Filtering empty directory "A3/B3" fstest.go:247: Filtering empty directory "A3/B3/C4" 2025/08/05 01:31:49 INFO : B2 bucket rclone-test-haweyaz5yuwu: cleaning bucket "rclone-test-haweyaz5yuwu" of all files 2025/08/05 01:31:49 DEBUG : A1/two: Deleting (id "4_z3f621da3d0bd7b7b9f880e1b_f11521028bc32bdf4_d20250805_m013148_c001_v0001157_t0029_u01754357508210") 2025/08/05 01:31:50 DEBUG : B2 bucket rclone-test-haweyaz5yuwu: Purge remote 2025/08/05 01:31:50 INFO : B2 bucket rclone-test-haweyaz5yuwu: cleaning bucket "rclone-test-haweyaz5yuwu" of all files 2025/08/05 01:31:50 NOTICE: purge failed: directory not found --- PASS: TestPurge (7.35s) === RUN TestRmdirsNoLeaveRoot run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" 2025/08/05 01:31:51 INFO : A2: Making directory 2025/08/05 01:31:51 INFO : A1/B2: Making directory 2025/08/05 01:31:51 INFO : A1/B2/C2: Making directory 2025/08/05 01:31:51 INFO : A1/B1/C3: Making directory 2025/08/05 01:31:51 INFO : A3: Making directory 2025/08/05 01:31:51 INFO : A3/B3: Making directory 2025/08/05 01:31:51 INFO : A3/B3/C4: Making directory fstest.go:247: Filtering empty directory "A2" fstest.go:247: Filtering empty directory "A1/B2" fstest.go:247: Filtering empty directory "A1/B2/C2" fstest.go:247: Filtering empty directory "A1/B1/C3" fstest.go:247: Filtering empty directory "A3" fstest.go:247: Filtering empty directory "A3/B3" fstest.go:247: Filtering empty directory "A3/B3/C4" 2025/08/05 01:31:52 DEBUG : removing 1 level 3 directories 2025/08/05 01:31:52 INFO : A3/B3/C4: Removing directory fstest.go:247: Filtering empty directory "A2" fstest.go:247: Filtering empty directory "A1/B2" fstest.go:247: Filtering empty directory "A1/B2/C2" fstest.go:247: Filtering empty directory "A1/B1/C3" fstest.go:247: Filtering empty directory "A3" fstest.go:247: Filtering empty directory "A3/B3" 2025/08/05 01:31:54 DEBUG : removing 1 level 0 directories 2025/08/05 01:31:54 INFO : B2 bucket rclone-test-wuwasic8suhe: Removing directory 2025/08/05 01:31:54 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.31s) === RUN TestRmdirsLeaveRoot run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" 2025/08/05 01:31:55 INFO : A1: Making directory 2025/08/05 01:31:55 INFO : A1/B1: Making directory 2025/08/05 01:31:55 INFO : A1/B1/C1: Making directory fstest.go:247: Filtering empty directory "A1" fstest.go:247: Filtering empty directory "A1/B1" fstest.go:247: Filtering empty directory "A1/B1/C1" fstest.go:247: Filtering empty directory "A1" --- PASS: TestRmdirsLeaveRoot (0.92s) === RUN TestRmdirsWithFilter run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" 2025/08/05 01:31:56 INFO : A1: Making directory 2025/08/05 01:31:56 INFO : A1/B1: Making directory 2025/08/05 01:31:56 INFO : A1/B1/C1: Making directory fstest.go:247: Filtering empty directory "A1" fstest.go:247: Filtering empty directory "A1/B1" fstest.go:247: Filtering empty directory "A1/B1/C1" fstest.go:247: Filtering empty directory "A1" --- PASS: TestRmdirsWithFilter (0.93s) === RUN TestCopyURL run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" 2025/08/05 01:31:58 DEBUG : filename.txt: File name found in url 2025/08/05 01:31:58 DEBUG : headerfilename.txt: filename found in Content-Disposition header. --- PASS: TestCopyURL (3.55s) === RUN TestCopyURLToWriter --- PASS: TestCopyURLToWriter (0.00s) === RUN TestMoveFile run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" 2025/08/05 01:32:00 DEBUG : file1: Need to transfer - File not found at Destination 2025/08/05 01:32:01 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2025/08/05 01:32:01 INFO : file1: Copied (new) to: sub/file2 2025/08/05 01:32:01 INFO : file1: Deleted 2025/08/05 01:32:01 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/08/05 01:32:01 DEBUG : file1: Unchanged skipping 2025/08/05 01:32:01 INFO : file1: Deleted 2025/08/05 01:32:01 DEBUG : B2 bucket rclone-test-wuwasic8suhe: don't need to copy/move sub/file2, it is already at target location --- PASS: TestMoveFile (1.98s) === RUN TestMoveFileWithIgnoreExisting run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" 2025/08/05 01:32:02 DEBUG : file1: Need to transfer - File not found at Destination 2025/08/05 01:32:03 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2025/08/05 01:32:03 INFO : file1: Copied (new) 2025/08/05 01:32:03 INFO : file1: Deleted 2025/08/05 01:32:03 DEBUG : file1: Destination exists, skipping 2025/08/05 01:32:03 DEBUG : file1: Not removing source file as destination file exists and --ignore-existing is set --- PASS: TestMoveFileWithIgnoreExisting (1.62s) === RUN TestCaseInsensitiveMoveFile run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFile (0.37s) === RUN TestCaseInsensitiveMoveFileDryRun run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFileDryRun (0.37s) === RUN TestMoveFileBackupDir run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" 2025/08/05 01:32:05 DEBUG : Creating backend with remote "TestB2:rclone-test-wuwasic8suhe/backup" 2025/08/05 01:32:06 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18) 2025/08/05 01:32:08 DEBUG : dst/file1: sha1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK 2025/08/05 01:32:08 INFO : dst/file1: Copied (server-side copy) 2025/08/05 01:32:08 INFO : dst/file1: Deleted 2025/08/05 01:32:08 DEBUG : dst/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2025/08/05 01:32:08 INFO : dst/file1: Copied (new) 2025/08/05 01:32:08 INFO : dst/file1: Deleted --- PASS: TestMoveFileBackupDir (4.89s) === 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:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" 2025/08/05 01:32:12 INFO : A1/B2: Making directory 2025/08/05 01:32:12 INFO : A1/B1/C3: Making directory fstest.go:247: Filtering empty directory "A1/B2" fstest.go:247: Filtering empty directory "A1/B1/C3" 2025/08/05 01:32:13 DEBUG : A1/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2025/08/05 01:32:13 INFO : A1/B1/C1/four: Copied (server-side copy) to: A2/B1/C1/four 2025/08/05 01:32:13 DEBUG : A1/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2025/08/05 01:32:13 INFO : A1/B1/three: Copied (server-side copy) to: A2/B1/three 2025/08/05 01:32:13 INFO : A1/B1/C1/four: Deleted 2025/08/05 01:32:13 DEBUG : A1/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2025/08/05 01:32:13 INFO : A1/one: Copied (server-side copy) to: A2/one 2025/08/05 01:32:13 INFO : A1/B1/three: Deleted 2025/08/05 01:32:14 INFO : A1/one: Deleted 2025/08/05 01:32:14 DEBUG : A1/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2025/08/05 01:32:14 INFO : A1/two: Copied (server-side copy) to: A2/two 2025/08/05 01:32:14 DEBUG : A1/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2025/08/05 01:32:14 INFO : A1/B1/C2/five: Copied (server-side copy) to: A2/B1/C2/five 2025/08/05 01:32:14 INFO : A1/two: Deleted 2025/08/05 01:32:14 INFO : A1/B1/C2/five: Deleted fstest.go:247: Filtering empty directory "A2/B2" fstest.go:247: Filtering empty directory "A2/B1/C3" 2025/08/05 01:32:15 DEBUG : A2/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2025/08/05 01:32:15 INFO : A2/B1/C2/five: Copied (server-side copy) to: A3/B1/C2/five 2025/08/05 01:32:15 DEBUG : A2/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2025/08/05 01:32:15 INFO : A2/B1/C1/four: Copied (server-side copy) to: A3/B1/C1/four 2025/08/05 01:32:15 DEBUG : A2/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2025/08/05 01:32:15 INFO : A2/B1/three: Copied (server-side copy) to: A3/B1/three 2025/08/05 01:32:15 DEBUG : A2/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2025/08/05 01:32:15 INFO : A2/one: Copied (server-side copy) to: A3/one 2025/08/05 01:32:15 INFO : A2/B1/C2/five: Deleted 2025/08/05 01:32:15 INFO : A2/B1/C1/four: Deleted 2025/08/05 01:32:15 DEBUG : A2/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2025/08/05 01:32:15 INFO : A2/two: Copied (server-side copy) to: A3/two 2025/08/05 01:32:15 INFO : A2/B1/three: Deleted 2025/08/05 01:32:15 INFO : A2/one: Deleted 2025/08/05 01:32:15 INFO : A2/two: Deleted fstest.go:247: Filtering empty directory "A3/B2" fstest.go:247: Filtering empty directory "A3/B1/C3" 2025/08/05 01:32:15 INFO : B2 bucket rclone-test-wuwasic8suhe: Can't DirMove - falling back to file moves: can't move directory - incompatible remotes 2025/08/05 01:32:16 DEBUG : A3/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2025/08/05 01:32:16 INFO : A3/B1/C2/five: Copied (server-side copy) to: A4/B1/C2/five 2025/08/05 01:32:16 DEBUG : A3/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2025/08/05 01:32:16 INFO : A3/B1/three: Copied (server-side copy) to: A4/B1/three 2025/08/05 01:32:16 DEBUG : A3/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2025/08/05 01:32:16 INFO : A3/B1/C1/four: Copied (server-side copy) to: A4/B1/C1/four 2025/08/05 01:32:16 INFO : A3/B1/C2/five: Deleted 2025/08/05 01:32:16 INFO : A3/B1/three: Deleted 2025/08/05 01:32:16 DEBUG : A3/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2025/08/05 01:32:16 INFO : A3/two: Copied (server-side copy) to: A4/two 2025/08/05 01:32:16 DEBUG : A3/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2025/08/05 01:32:16 INFO : A3/one: Copied (server-side copy) to: A4/one 2025/08/05 01:32:16 INFO : A3/B1/C1/four: Deleted 2025/08/05 01:32:16 INFO : A3/two: Deleted 2025/08/05 01:32:16 INFO : A3/one: Deleted fstest.go:247: Filtering empty directory "A4/B2" fstest.go:247: Filtering empty directory "A4/B1/C3" --- PASS: TestDirMove (8.48s) === RUN TestGetFsInfo run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" --- PASS: TestGetFsInfo (0.37s) === RUN TestRcat === RUN TestRcat/withChecksum=false,ignoreChecksum=false run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" 2025/08/05 01:32:18 DEBUG : B2 bucket rclone-test-wuwasic8suhe: File to upload is small (34 bytes), uploading instead of streaming 2025/08/05 01:32:19 DEBUG : no_checksum_small_file_from_pipe: sha1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK 2025/08/05 01:32:19 DEBUG : no_checksum_small_file_from_pipe: Size and sha1 of src and dst objects identical 2025/08/05 01:32:19 DEBUG : no_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2025/08/05 01:32:20 DEBUG : no_checksum_big_file_from_pipe: sha1 = d0080f73aa8b494570ad63df1beb25c01ea9eea2 OK 2025/08/05 01:32:20 DEBUG : no_checksum_big_file_from_pipe: Size and sha1 of src and dst objects identical === RUN TestRcat/withChecksum=true,ignoreChecksum=false run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" 2025/08/05 01:32:21 DEBUG : B2 bucket rclone-test-wuwasic8suhe: File to upload is small (34 bytes), uploading instead of streaming 2025/08/05 01:32:21 DEBUG : with_checksum_small_file_from_pipe: sha1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK 2025/08/05 01:32:21 DEBUG : with_checksum_small_file_from_pipe: Size and sha1 of src and dst objects identical 2025/08/05 01:32:21 DEBUG : with_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2025/08/05 01:32:22 DEBUG : with_checksum_big_file_from_pipe: sha1 = d0080f73aa8b494570ad63df1beb25c01ea9eea2 OK 2025/08/05 01:32:22 DEBUG : with_checksum_big_file_from_pipe: Size and sha1 of src and dst objects identical === RUN TestRcat/withChecksum=false,ignoreChecksum=true run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" 2025/08/05 01:32:24 DEBUG : B2 bucket rclone-test-wuwasic8suhe: File to upload is small (34 bytes), uploading instead of streaming 2025/08/05 01:32:24 DEBUG : ignore_checksum_small_file_from_pipe: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/08/05 01:32:24 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2025/08/05 01:32:25 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:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" 2025/08/05 01:32:26 DEBUG : B2 bucket rclone-test-wuwasic8suhe: File to upload is small (34 bytes), uploading instead of streaming 2025/08/05 01:32:26 DEBUG : ignore_checksum_small_file_from_pipe: Src hash empty - aborting Dst hash check 2025/08/05 01:32:26 DEBUG : ignore_checksum_small_file_from_pipe: Size of src and dst objects identical 2025/08/05 01:32:26 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2025/08/05 01:32:27 DEBUG : ignore_checksum_big_file_from_pipe: Src hash empty - aborting Dst hash check 2025/08/05 01:32:27 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical --- PASS: TestRcat (9.89s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (2.83s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (2.35s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (2.29s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (2.42s) === RUN TestRcatMetadata run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" operations_test.go:1532: Skipping as destination doesn't support user metadata --- SKIP: TestRcatMetadata (0.38s) === RUN TestRcatSize run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" 2025/08/05 01:32:29 DEBUG : B2 bucket rclone-test-wuwasic8suhe: File to upload is small (60 bytes), uploading instead of streaming 2025/08/05 01:32:29 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2025/08/05 01:32:29 DEBUG : potato2: Size and sha1 of src and dst objects identical --- PASS: TestRcatSize (1.54s) === RUN TestRcatSizeMetadata run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" operations_test.go:1613: Skipping as destination doesn't support user metadata --- SKIP: TestRcatSizeMetadata (0.37s) === RUN TestTouchDir run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" 2025/08/05 01:32:32 DEBUG : B2 bucket rclone-test-wuwasic8suhe: Touching "sub dir/potato3" 2025/08/05 01:32:32 DEBUG : B2 bucket rclone-test-wuwasic8suhe: Touching "empty space" 2025/08/05 01:32:32 DEBUG : B2 bucket rclone-test-wuwasic8suhe: Touching "potato2" --- PASS: TestTouchDir (4.23s) === RUN TestMkdirMetadata run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" operations_test.go:1703: Skipping test as remote does not support MkdirMetadata --- SKIP: TestMkdirMetadata (0.38s) === RUN TestMkdirModTime run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" operations_test.go:1722: Skipping test as remote does not support DirSetModTime or MkdirMetadata --- SKIP: TestMkdirModTime (0.38s) === RUN TestCopyDirMetadata run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" operations_test.go:1743: Skipping test as remote does not support WriteDirMetadata or MkdirMetadata --- SKIP: TestCopyDirMetadata (0.38s) === RUN TestSetDirModTime run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" operations_test.go:1779: Skipping test as remote does not support DirSetModTime or WriteDirSetModTime --- SKIP: TestSetDirModTime (0.38s) === RUN TestDirsEqual run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" operations_test.go:1823: Skipping test as remote does not support WriteDirMetadata or MkdirMetadata --- SKIP: TestDirsEqual (0.38s) === RUN TestRemoveExisting run.go:180: Remote "B2 bucket rclone-test-wuwasic8suhe", Local "Local file system at /tmp/rclone1897192953", Modify Window "1ms" operations_test.go:1892: Skipping as remote can't Move --- SKIP: TestRemoveExisting (0.37s) === RUN TestRcAbout rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcAbout (0.00s) === RUN TestRcCleanup rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcCleanup (0.00s) === RUN TestRcCopyfile rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcCopyfile (0.00s) === RUN TestRcCopyurl rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcCopyurl (0.00s) === RUN TestRcDelete rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcDelete (0.00s) === RUN TestRcDeletefile rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcDeletefile (0.00s) === RUN TestRcList rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcList (0.00s) === RUN TestRcStat rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcStat (0.00s) === RUN TestRcSetTier rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcSetTier (0.00s) === RUN TestRcSetTierFile rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcSetTierFile (0.00s) === RUN TestRcMkdir rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcMkdir (0.00s) === RUN TestRcMovefile rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcMovefile (0.00s) === RUN TestRcPurge rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcPurge (0.00s) === RUN TestRcRmdir rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcRmdir (0.00s) === RUN TestRcRmdirs rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcRmdirs (0.00s) === RUN TestRcSize rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcSize (0.00s) === RUN TestRcPublicLink rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcPublicLink (0.00s) === RUN TestRcFsInfo rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcFsInfo (0.00s) === RUN TestUploadFile rc_test.go:30: Skipping test on non local remote --- SKIP: TestUploadFile (0.00s) === RUN TestRcCommand rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcCommand (0.00s) === RUN TestRcDu rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcDu (0.00s) === RUN TestRcCheck rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcCheck (0.00s) === RUN TestRcHashsum rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcHashsum (0.00s) === RUN TestRcHashsumFile rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcHashsumFile (0.00s) FAIL 2025/08/05 01:32:37 DEBUG : B2 bucket rclone-test-wuwasic8suhe: Purge remote 2025/08/05 01:32:37 INFO : B2 bucket rclone-test-wuwasic8suhe: cleaning bucket "rclone-test-wuwasic8suhe" of all files 2025/08/05 01:32:38 DEBUG : A1/B1/C1/four: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f419046b64f080bc4_d20250805_m013213_c001_v7007000_t0000_u01754357533703") 2025/08/05 01:32:38 DEBUG : A1/B1/C1/four: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470ca3_d20250805_m013210_c001_v0001172_t0016_u01754357530914") 2025/08/05 01:32:38 DEBUG : A1/B1/C1/one: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4152d501fbb1e301_d20250805_m013153_c001_v7007000_t0000_u01754357513860") 2025/08/05 01:32:38 DEBUG : A1/B1/C1/one: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470c1d_d20250805_m013151_c001_v0001172_t0025_u01754357511284") 2025/08/05 01:32:38 DEBUG : A1/B1/C2/five: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4152d501fbb1e31f_d20250805_m013214_c001_v7007000_t0000_u01754357534119") 2025/08/05 01:32:38 DEBUG : A1/B1/C2/five: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470ca5_d20250805_m013211_c001_v0001172_t0017_u01754357531614") 2025/08/05 01:32:38 DEBUG : A1/B1/three: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f414ab92361dcef0b_d20250805_m013213_c001_v7007000_t0000_u01754357533889") 2025/08/05 01:32:38 DEBUG : A1/B1/three: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470c9f_d20250805_m013210_c001_v0001172_t0027_u01754357530534") 2025/08/05 01:32:38 DEBUG : A1/one: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4020b5c3d3a541b7_d20250805_m013213_c001_v7007000_t0000_u01754357533922") 2025/08/05 01:32:38 DEBUG : A1/one: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470c95_d20250805_m013209_c001_v0001172_t0048_u01754357529875") 2025/08/05 01:32:38 DEBUG : A1/two: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4134a00943f0a800_d20250805_m013214_c001_v7007000_t0000_u01754357534109") 2025/08/05 01:32:38 DEBUG : A1/two: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470c9b_d20250805_m013210_c001_v0001172_t0009_u01754357530217") 2025/08/05 01:32:38 DEBUG : A1/two: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4152d501fbb1e303_d20250805_m013154_c001_v7007000_t0000_u01754357514226") 2025/08/05 01:32:38 DEBUG : A1/two: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470c1f_d20250805_m013151_c001_v0001172_t0034_u01754357511550") 2025/08/05 01:32:38 DEBUG : A2/B1/C1/four: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4020b5c3d3a541ba_d20250805_m013215_c001_v7007000_t0000_u01754357535201") 2025/08/05 01:32:38 DEBUG : A2/B1/C1/four: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f108299c4850daa71_d20250805_m013213_c001_v0001183_t0037_u01754357533321") 2025/08/05 01:32:38 DEBUG : A2/B1/C2/five: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4134a00943f0a804_d20250805_m013215_c001_v7007000_t0000_u01754357535190") 2025/08/05 01:32:38 DEBUG : A2/B1/C2/five: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f11500e658e3928e0_d20250805_m013213_c001_v0001136_t0040_u01754357533540") 2025/08/05 01:32:38 DEBUG : A2/B1/three: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4152d501fbb1e320_d20250805_m013215_c001_v7007000_t0000_u01754357535212") 2025/08/05 01:32:38 DEBUG : A2/B1/three: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f118e5a0c76684479_d20250805_m013213_c001_v0001181_t0029_u01754357533683") 2025/08/05 01:32:38 DEBUG : A2/one: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f414ab92361dcef0d_d20250805_m013215_c001_v7007000_t0000_u01754357535272") 2025/08/05 01:32:38 DEBUG : A2/one: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f118e5a0c7668447b_d20250805_m013213_c001_v0001181_t0013_u01754357533702") 2025/08/05 01:32:38 DEBUG : A2/two: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4020b5c3d3a541bb_d20250805_m013215_c001_v7007000_t0000_u01754357535384") 2025/08/05 01:32:38 DEBUG : A2/two: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f111e29e22328d43f_d20250805_m013213_c001_v0001043_t0049_u01754357533709") 2025/08/05 01:32:38 DEBUG : A3/B1/C1/four: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4020b5c3d3a541bf_d20250805_m013216_c001_v7007000_t0000_u01754357536527") 2025/08/05 01:32:38 DEBUG : A3/B1/C1/four: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f11813643ca2dda35_d20250805_m013214_c001_v0001134_t0010_u01754357534895") 2025/08/05 01:32:39 DEBUG : A3/B1/C2/five: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f414ab92361dcef0f_d20250805_m013216_c001_v7007000_t0000_u01754357536376") 2025/08/05 01:32:39 DEBUG : A3/B1/C2/five: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f1085ed791717b00e_d20250805_m013214_c001_v0001137_t0021_u01754357534904") 2025/08/05 01:32:39 DEBUG : A3/B1/three: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4152d501fbb1e321_d20250805_m013216_c001_v7007000_t0000_u01754357536389") 2025/08/05 01:32:39 DEBUG : A3/B1/three: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f107d1f4d41db180c_d20250805_m013214_c001_v0001161_t0024_u01754357534925") 2025/08/05 01:32:39 DEBUG : A3/one: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f419046b64f080bd4_d20250805_m013216_c001_v7007000_t0000_u01754357536680") 2025/08/05 01:32:39 DEBUG : A3/one: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f113ed32fe6ee669a_d20250805_m013214_c001_v0001137_t0053_u01754357534922") 2025/08/05 01:32:39 DEBUG : A3/two: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4134a00943f0a805_d20250805_m013216_c001_v7007000_t0000_u01754357536667") 2025/08/05 01:32:39 DEBUG : A3/two: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f1117fe62b24629c4_d20250805_m013214_c001_v0001116_t0044_u01754357534945") 2025/08/05 01:32:39 DEBUG : A4/B1/C1/four: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f419046b64f080bd5_d20250805_m013217_c001_v7007000_t0000_u01754357537250") 2025/08/05 01:32:39 DEBUG : A4/B1/C1/four: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f118607dbe6380607_d20250805_m013216_c001_v0001120_t0018_u01754357536159") 2025/08/05 01:32:39 DEBUG : A4/B1/C2/five: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f419046b64f080bd7_d20250805_m013217_c001_v7007000_t0000_u01754357537436") 2025/08/05 01:32:39 DEBUG : A4/B1/C2/five: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f1161d220099fd622_d20250805_m013216_c001_v0001184_t0018_u01754357536167") 2025/08/05 01:32:39 DEBUG : A4/B1/three: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f419046b64f080bd8_d20250805_m013217_c001_v7007000_t0000_u01754357537620") 2025/08/05 01:32:39 DEBUG : A4/B1/three: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f100216862d8308e1_d20250805_m013216_c001_v0001182_t0024_u01754357536184") 2025/08/05 01:32:39 DEBUG : A4/one: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f419046b64f080bd9_d20250805_m013217_c001_v7007000_t0000_u01754357537805") 2025/08/05 01:32:39 DEBUG : A4/one: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f1021c989f5e8a389_d20250805_m013216_c001_v0001130_t0016_u01754357536192") 2025/08/05 01:32:39 DEBUG : A4/two: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f419046b64f080bda_d20250805_m013217_c001_v7007000_t0000_u01754357537989") 2025/08/05 01:32:39 DEBUG : A4/two: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f109c2f412e301d84_d20250805_m013216_c001_v0001136_t0010_u01754357536199") 2025/08/05 01:32:39 DEBUG : BackupDir/one: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4081b645c52f8255_d20250805_m013036_c001_v7007000_t0000_u01754357436008") 2025/08/05 01:32:39 DEBUG : BackupDir/one: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f1012e563d61ada51_d20250805_m013030_c001_v0001032_t0034_u01754357430566") 2025/08/05 01:32:39 DEBUG : CompareDest/one: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4081b645c52f8247_d20250805_m013020_c001_v7007000_t0000_u01754357420486") 2025/08/05 01:32:40 DEBUG : CompareDest/one: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed4709d5_d20250805_m013016_c001_v0001172_t0047_u01754357416646") 2025/08/05 01:32:40 DEBUG : CompareDest/two: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4081b645c52f8248_d20250805_m013020_c001_v7007000_t0000_u01754357420669") 2025/08/05 01:32:40 DEBUG : CompareDest/two: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed4709db_d20250805_m013017_c001_v0001172_t0006_u01754357417667") 2025/08/05 01:32:40 DEBUG : CopyDest/one: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4081b645c52f8256_d20250805_m013036_c001_v7007000_t0000_u01754357436191") 2025/08/05 01:32:40 DEBUG : CopyDest/one: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470a19_d20250805_m013027_c001_v0001172_t0030_u01754357427365") 2025/08/05 01:32:40 DEBUG : CopyDest/three: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4081b645c52f8257_d20250805_m013036_c001_v7007000_t0000_u01754357436373") 2025/08/05 01:32:40 DEBUG : CopyDest/three: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470a4d_d20250805_m013034_c001_v0001172_t0040_u01754357434087") 2025/08/05 01:32:40 DEBUG : CopyDest/two: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4081b645c52f8258_d20250805_m013036_c001_v7007000_t0000_u01754357436560") 2025/08/05 01:32:40 DEBUG : CopyDest/two: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470a3b_d20250805_m013032_c001_v0001172_t0040_u01754357432128") 2025/08/05 01:32:40 DEBUG : ReadFile: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4210f34572f1a8a5_d20250805_m013138_c001_v7007000_t0000_u01754357498256") 2025/08/05 01:32:40 DEBUG : ReadFile: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470bc8_d20250805_m013136_c001_v0001172_t0011_u01754357496854") 2025/08/05 01:32:40 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4081b645c52f8263_d20250805_m013041_c001_v7007000_t0000_u01754357441534") 2025/08/05 01:32:40 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470a6f_d20250805_m013038_c001_v0001172_t0036_u01754357438756") 2025/08/05 01:32:40 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4081b645c52f8264_d20250805_m013041_c001_v7007000_t0000_u01754357441716") 2025/08/05 01:32:40 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470a7d_d20250805_m013040_c001_v0001172_t0054_u01754357440862") 2025/08/05 01:32:40 DEBUG : a.txt: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f400c3e48f9265fa1_d20250805_m013101_c001_v7007000_t0000_u01754357461074") 2025/08/05 01:32:40 DEBUG : a.txt: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470adb_d20250805_m013056_c001_v0001172_t0003_u01754357456206") 2025/08/05 01:32:40 DEBUG : a.txt: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f400c3e48f9265790_d20250805_m013054_c001_v7007000_t0000_u01754357454727") 2025/08/05 01:32:40 DEBUG : a.txt: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470aaf_d20250805_m013049_c001_v0001172_t0043_u01754357449200") 2025/08/05 01:32:40 DEBUG : also/one: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4081b645c52f826d_d20250805_m013046_c001_v7007000_t0000_u01754357446166") 2025/08/05 01:32:41 DEBUG : also/one: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470a97_d20250805_m013044_c001_v0001172_t0038_u01754357444381") 2025/08/05 01:32:41 DEBUG : another: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4081b645c52f8270_d20250805_m013046_c001_v7007000_t0000_u01754357446713") 2025/08/05 01:32:41 DEBUG : another: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470a9b_d20250805_m013044_c001_v0001172_t0008_u01754357444815") 2025/08/05 01:32:41 DEBUG : backup/dst/file1: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4152d501fbb1e31c_d20250805_m013209_c001_v7007000_t0000_u01754357529319") 2025/08/05 01:32:41 DEBUG : backup/dst/file1: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f1184fa6e1be746b5_d20250805_m013207_c001_v0001144_t0018_u01754357527788") 2025/08/05 01:32:41 DEBUG : backup/dst/file1: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4081b645c52f8243_d20250805_m013010_c001_v7007000_t0000_u01754357410162") 2025/08/05 01:32:41 DEBUG : backup/dst/file1: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f11647a4e0e844a68_d20250805_m013008_c001_v0001179_t0038_u01754357408843") 2025/08/05 01:32:41 DEBUG : data/banana: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4020b5c3d3a53a98_d20250805_m012828_c001_v7007000_t0000_u01754357308590") 2025/08/05 01:32:41 DEBUG : data/banana: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed4707d9_d20250805_m012826_c001_v0001172_t0001_u01754357306580") 2025/08/05 01:32:41 DEBUG : data/banana: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed4707ad_d20250805_m012817_c001_v0001172_t0006_u01754357297500") 2025/08/05 01:32:41 DEBUG : data/potato: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4020b5c3d3a53a9a_d20250805_m012828_c001_v7007000_t0000_u01754357308773") 2025/08/05 01:32:41 DEBUG : data/potato: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed4707db_d20250805_m012826_c001_v0001172_t0025_u01754357306860") 2025/08/05 01:32:41 DEBUG : data/potato: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed4707b5_d20250805_m012819_c001_v0001172_t0019_u01754357299615") 2025/08/05 01:32:41 DEBUG : dst/file1: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4152d501fbb1e31d_d20250805_m013209_c001_v7007000_t0000_u01754357529503") 2025/08/05 01:32:41 DEBUG : dst/file1: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470c8d_d20250805_m013208_c001_v0001172_t0034_u01754357528596") 2025/08/05 01:32:41 DEBUG : dst/file1: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4152d501fbb1e31a_d20250805_m013208_c001_v7007000_t0000_u01754357528411") 2025/08/05 01:32:41 DEBUG : dst/file1: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470c73_d20250805_m013204_c001_v0001172_t0035_u01754357524997") 2025/08/05 01:32:41 DEBUG : dst/file1: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4081b645c52f8244_d20250805_m013010_c001_v7007000_t0000_u01754357410353") 2025/08/05 01:32:41 DEBUG : dst/file1: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed4709ad_d20250805_m013009_c001_v0001172_t0028_u01754357409515") 2025/08/05 01:32:41 DEBUG : dst/file1: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4081b645c52f8242_d20250805_m013009_c001_v7007000_t0000_u01754357409331") 2025/08/05 01:32:41 DEBUG : dst/file1: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed47098d_d20250805_m013006_c001_v0001172_t0058_u01754357406141") 2025/08/05 01:32:42 DEBUG : dst/one: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4081b645c52f825a_d20250805_m013036_c001_v7007000_t0000_u01754357436743") 2025/08/05 01:32:42 DEBUG : dst/one: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f113c18aaea27f6c3_d20250805_m013031_c001_v0001092_t0045_u01754357431554") 2025/08/05 01:32:42 DEBUG : dst/one: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f401bbe58f419f5df_d20250805_m013031_c001_v7007000_t0000_u01754357431350") 2025/08/05 01:32:42 DEBUG : dst/one: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470a13_d20250805_m013027_c001_v0001172_t0010_u01754357427057") 2025/08/05 01:32:42 DEBUG : dst/one: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f112fae870a40829d_d20250805_m013026_c001_v0001184_t0053_u01754357426314") 2025/08/05 01:32:42 DEBUG : dst/one: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f112fae870a40828d_d20250805_m013025_c001_v0001184_t0027_u01754357425344") 2025/08/05 01:32:42 DEBUG : dst/one: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4081b645c52f8249_d20250805_m013020_c001_v7007000_t0000_u01754357420853") 2025/08/05 01:32:42 DEBUG : dst/one: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed4709d3_d20250805_m013016_c001_v0001172_t0052_u01754357416286") 2025/08/05 01:32:42 DEBUG : dst/one: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f1172632b8535974d_d20250805_m013015_c001_v0001182_t0051_u01754357415544") 2025/08/05 01:32:42 DEBUG : dst/one: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f1172632b85359743_d20250805_m013014_c001_v0001182_t0039_u01754357414590") 2025/08/05 01:32:42 DEBUG : dst/three: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4081b645c52f825b_d20250805_m013036_c001_v7007000_t0000_u01754357436925") 2025/08/05 01:32:42 DEBUG : dst/three: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f112fae870a4082ff_d20250805_m013035_c001_v0001184_t0052_u01754357435418") 2025/08/05 01:32:42 DEBUG : dst/two: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4081b645c52f825c_d20250805_m013037_c001_v7007000_t0000_u01754357437108") 2025/08/05 01:32:42 DEBUG : dst/two: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106528cfbdaaafa4_d20250805_m013033_c001_v0001115_t0013_u01754357433058") 2025/08/05 01:32:42 DEBUG : dst/two: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4081b645c52f824a_d20250805_m013021_c001_v7007000_t0000_u01754357421037") 2025/08/05 01:32:42 DEBUG : dst/two: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f1172632b8535975d_d20250805_m013019_c001_v0001182_t0007_u01754357419906") 2025/08/05 01:32:42 DEBUG : empty space: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f419d9c9094fe96df_d20250805_m013234_c001_v7007000_t0000_u01754357554398") 2025/08/05 01:32:42 DEBUG : empty space: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f112ea1a5a5a8db8d_d20250805_m013233_c001_v0001092_t0000_u01754357553244") 2025/08/05 01:32:42 DEBUG : empty space: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470d09_d20250805_m013231_c001_v0001172_t0026_u01754357551256") 2025/08/05 01:32:42 DEBUG : empty space: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f400c3e48f9266006_d20250805_m013124_c001_v7007000_t0000_u01754357484565") 2025/08/05 01:32:42 DEBUG : empty space: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470b6c_d20250805_m013123_c001_v0001172_t0054_u01754357483401") 2025/08/05 01:32:42 DEBUG : empty space: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f400c3e48f9265ffe_d20250805_m013122_c001_v7007000_t0000_u01754357482530") 2025/08/05 01:32:43 DEBUG : empty space: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470b5e_d20250805_m013121_c001_v0001172_t0028_u01754357481318") 2025/08/05 01:32:43 DEBUG : empty space: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f400c3e48f9265ff6_d20250805_m013120_c001_v7007000_t0000_u01754357480393") 2025/08/05 01:32:43 DEBUG : empty space: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470b50_d20250805_m013119_c001_v0001172_t0012_u01754357479418") 2025/08/05 01:32:43 DEBUG : empty space: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f400c3e48f9265fed_d20250805_m013118_c001_v7007000_t0000_u01754357478454") 2025/08/05 01:32:43 DEBUG : empty space: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470b41_d20250805_m013116_c001_v0001172_t0044_u01754357476946") 2025/08/05 01:32:43 DEBUG : empty space: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f400c3e48f9265fe4_d20250805_m013116_c001_v7007000_t0000_u01754357476122") 2025/08/05 01:32:43 DEBUG : empty space: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470b35_d20250805_m013115_c001_v0001172_t0020_u01754357475192") 2025/08/05 01:32:43 DEBUG : empty space: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4020b5c3d3a53a7e_d20250805_m012811_c001_v7007000_t0000_u01754357291641") 2025/08/05 01:32:43 DEBUG : empty space: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed47077a_d20250805_m012808_c001_v0001172_t0042_u01754357288598") 2025/08/05 01:32:43 DEBUG : empty space: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4020b5c3d3a53a76_d20250805_m012806_c001_v7007000_t0000_u01754357286687") 2025/08/05 01:32:43 DEBUG : empty space: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470762_d20250805_m012802_c001_v0001172_t0012_u01754357282394") 2025/08/05 01:32:43 DEBUG : empty space: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4020b5c3d3a53a69_d20250805_m012759_c001_v7007000_t0000_u01754357279640") 2025/08/05 01:32:43 DEBUG : empty space: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470748_d20250805_m012756_c001_v0001172_t0029_u01754357276696") 2025/08/05 01:32:43 DEBUG : file1: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4152d501fbb1e315_d20250805_m013203_c001_v7007000_t0000_u01754357523871") 2025/08/05 01:32:43 DEBUG : file1: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470c61_d20250805_m013202_c001_v0001172_t0015_u01754357522803") 2025/08/05 01:32:43 DEBUG : file1: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4152d501fbb1e30d_d20250805_m013159_c001_v7007000_t0000_u01754357519713") 2025/08/05 01:32:43 DEBUG : file1: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f406549efed470c37_d20250805_m013157_c001_v0001172_t0059_u01754357517635") 2025/08/05 01:32:43 DEBUG : file1: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4210f34572f1a8a9_d20250805_m013143_c001_v7007000_t0000_u01754357503022") 2025/08/05 01:32:43 DEBUG : file1: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470bda_d20250805_m013139_c001_v0001172_t0004_u01754357499049") 2025/08/05 01:32:43 DEBUG : file1: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f400c3e48f9265fd4_d20250805_m013112_c001_v7007000_t0000_u01754357472092") 2025/08/05 01:32:43 DEBUG : file1: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470b11_d20250805_m013106_c001_v0001172_t0006_u01754357466221") 2025/08/05 01:32:44 DEBUG : file1: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f400c3e48f9265fbe_d20250805_m013105_c001_v7007000_t0000_u01754357465667") 2025/08/05 01:32:44 DEBUG : file1: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470b05_d20250805_m013102_c001_v0001172_t0044_u01754357462558") 2025/08/05 01:32:44 DEBUG : file2: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4152d501fbb1e30e_d20250805_m013159_c001_v7007000_t0000_u01754357519897") 2025/08/05 01:32:44 DEBUG : file2: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f406549efed470c41_d20250805_m013159_c001_v0001172_t0059_u01754357519150") 2025/08/05 01:32:44 DEBUG : file2: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4210f34572f1a8ab_d20250805_m013143_c001_v7007000_t0000_u01754357503208") 2025/08/05 01:32:44 DEBUG : file2: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470bde_d20250805_m013139_c001_v0001172_t0031_u01754357499381") 2025/08/05 01:32:44 DEBUG : filename.txt: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4152d501fbb1e30f_d20250805_m013200_c001_v7007000_t0000_u01754357520081") 2025/08/05 01:32:44 DEBUG : filename.txt: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470c3d_d20250805_m013158_c001_v0001172_t0023_u01754357518193") 2025/08/05 01:32:44 DEBUG : headerfilename.txt: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4152d501fbb1e310_d20250805_m013200_c001_v7007000_t0000_u01754357520266") 2025/08/05 01:32:44 DEBUG : headerfilename.txt: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470c3f_d20250805_m013158_c001_v0001172_t0043_u01754357518469") 2025/08/05 01:32:44 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f419046b64f080bee_d20250805_m013228_c001_v7007000_t0000_u01754357548064") 2025/08/05 01:32:44 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470ce7_d20250805_m013226_c001_v0001172_t0016_u01754357546782") 2025/08/05 01:32:44 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f419046b64f080be8_d20250805_m013225_c001_v7007000_t0000_u01754357545643") 2025/08/05 01:32:44 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470cd7_d20250805_m013224_c001_v0001172_t0035_u01754357544367") 2025/08/05 01:32:44 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f419046b64f080bf0_d20250805_m013228_c001_v7007000_t0000_u01754357548248") 2025/08/05 01:32:44 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470ce3_d20250805_m013226_c001_v0001172_t0031_u01754357546390") 2025/08/05 01:32:44 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f419046b64f080be9_d20250805_m013225_c001_v7007000_t0000_u01754357545827") 2025/08/05 01:32:44 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470cd5_d20250805_m013224_c001_v0001172_t0028_u01754357544101") 2025/08/05 01:32:44 DEBUG : large: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f419046b64f080b7e_d20250805_m013135_c001_v7007000_t0000_u01754357495526") 2025/08/05 01:32:44 DEBUG : large: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470bc0_d20250805_m013134_c001_v0001172_t0025_u01754357494875") 2025/08/05 01:32:45 DEBUG : large: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f419046b64f080b63_d20250805_m013133_c001_v7007000_t0000_u01754357493502") 2025/08/05 01:32:45 DEBUG : large: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470ba2_d20250805_m013132_c001_v0001172_t0020_u01754357492188") 2025/08/05 01:32:45 DEBUG : large: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4210f34572f1a856_d20250805_m013129_c001_v7007000_t0000_u01754357489598") 2025/08/05 01:32:45 DEBUG : large: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470b8c_d20250805_m013128_c001_v0001172_t0005_u01754357488854") 2025/08/05 01:32:45 DEBUG : large: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4210f34572f1a854_d20250805_m013127_c001_v7007000_t0000_u01754357487791") 2025/08/05 01:32:45 DEBUG : large: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470b7a_d20250805_m013126_c001_v0001172_t0040_u01754357486138") 2025/08/05 01:32:45 DEBUG : medium: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4210f34572f1a8a2_d20250805_m013135_c001_v7007000_t0000_u01754357495535") 2025/08/05 01:32:45 DEBUG : medium: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470bbc_d20250805_m013134_c001_v0001172_t0004_u01754357494521") 2025/08/05 01:32:45 DEBUG : medium: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4210f34572f1a897_d20250805_m013132_c001_v7007000_t0000_u01754357492922") 2025/08/05 01:32:45 DEBUG : medium: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470ba0_d20250805_m013131_c001_v0001172_t0038_u01754357491769") 2025/08/05 01:32:45 DEBUG : medium: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4210f34572f1a859_d20250805_m013129_c001_v7007000_t0000_u01754357489781") 2025/08/05 01:32:45 DEBUG : medium: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470b88_d20250805_m013128_c001_v0001172_t0029_u01754357488530") 2025/08/05 01:32:45 DEBUG : medium: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f400c3e48f926600e_d20250805_m013127_c001_v7007000_t0000_u01754357487044") 2025/08/05 01:32:45 DEBUG : medium: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470b76_d20250805_m013125_c001_v0001172_t0018_u01754357485766") 2025/08/05 01:32:45 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f419046b64f080bdf_d20250805_m013221_c001_v7007000_t0000_u01754357541000") 2025/08/05 01:32:45 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470cbd_d20250805_m013219_c001_v0001172_t0019_u01754357539655") 2025/08/05 01:32:45 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f419046b64f080be1_d20250805_m013221_c001_v7007000_t0000_u01754357541184") 2025/08/05 01:32:45 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470cb9_d20250805_m013219_c001_v0001172_t0002_u01754357539279") 2025/08/05 01:32:45 DEBUG : not-one: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4081b645c52f8271_d20250805_m013046_c001_v7007000_t0000_u01754357446897") 2025/08/05 01:32:45 DEBUG : not-one: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470a9f_d20250805_m013045_c001_v0001172_t0047_u01754357445193") 2025/08/05 01:32:45 DEBUG : one: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4081b645c52f826c_d20250805_m013045_c001_v7007000_t0000_u01754357445982") 2025/08/05 01:32:45 DEBUG : one: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470a8b_d20250805_m013043_c001_v0001172_t0036_u01754357443911") 2025/08/05 01:32:46 DEBUG : potato1: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f419046b64f080bf2_d20250805_m013229_c001_v7007000_t0000_u01754357549983") 2025/08/05 01:32:46 DEBUG : potato1: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470cf1_d20250805_m013229_c001_v0001172_t0017_u01754357548999") 2025/08/05 01:32:46 DEBUG : potato2: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f419d9c9094fe96e1_d20250805_m013234_c001_v7007000_t0000_u01754357554582") 2025/08/05 01:32:46 DEBUG : potato2: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f1186cfc8c98292d2_d20250805_m013233_c001_v0001106_t0011_u01754357553463") 2025/08/05 01:32:46 DEBUG : potato2: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470d03_d20250805_m013230_c001_v0001172_t0016_u01754357550914") 2025/08/05 01:32:46 DEBUG : potato2: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f419046b64f080bf3_d20250805_m013230_c001_v7007000_t0000_u01754357550171") 2025/08/05 01:32:46 DEBUG : potato2: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470cf3_d20250805_m013229_c001_v0001172_t0019_u01754357549312") 2025/08/05 01:32:46 DEBUG : potato2: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f400c3e48f9266007_d20250805_m013124_c001_v7007000_t0000_u01754357484750") 2025/08/05 01:32:46 DEBUG : potato2: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470b68_d20250805_m013123_c001_v0001172_t0046_u01754357483082") 2025/08/05 01:32:46 DEBUG : potato2: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f400c3e48f9265fff_d20250805_m013122_c001_v7007000_t0000_u01754357482714") 2025/08/05 01:32:46 DEBUG : potato2: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470b5c_d20250805_m013120_c001_v0001172_t0000_u01754357480978") 2025/08/05 01:32:46 DEBUG : potato2: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f400c3e48f9265ff7_d20250805_m013120_c001_v7007000_t0000_u01754357480610") 2025/08/05 01:32:46 DEBUG : potato2: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470b4e_d20250805_m013119_c001_v0001172_t0032_u01754357479009") 2025/08/05 01:32:46 DEBUG : potato2: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f400c3e48f9265fef_d20250805_m013118_c001_v7007000_t0000_u01754357478639") 2025/08/05 01:32:46 DEBUG : potato2: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470b3d_d20250805_m013116_c001_v0001172_t0045_u01754357476674") 2025/08/05 01:32:46 DEBUG : potato2: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f400c3e48f9265fe5_d20250805_m013116_c001_v7007000_t0000_u01754357476305") 2025/08/05 01:32:46 DEBUG : potato2: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470b31_d20250805_m013114_c001_v0001172_t0046_u01754357474814") 2025/08/05 01:32:46 DEBUG : potato2: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4020b5c3d3a53a7f_d20250805_m012811_c001_v7007000_t0000_u01754357291824") 2025/08/05 01:32:46 DEBUG : potato2: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470780_d20250805_m012809_c001_v0001172_t0039_u01754357289565") 2025/08/05 01:32:46 DEBUG : potato2: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4020b5c3d3a53a77_d20250805_m012806_c001_v7007000_t0000_u01754357286869") 2025/08/05 01:32:46 DEBUG : potato2: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470764_d20250805_m012803_c001_v0001172_t0045_u01754357283508") 2025/08/05 01:32:46 DEBUG : potato2: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4020b5c3d3a53a6a_d20250805_m012759_c001_v7007000_t0000_u01754357279824") 2025/08/05 01:32:47 DEBUG : potato2: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470752_d20250805_m012757_c001_v0001172_t0010_u01754357277517") 2025/08/05 01:32:47 DEBUG : remotepotato: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4020b5c3d3a53a80_d20250805_m012812_c001_v7007000_t0000_u01754357292008") 2025/08/05 01:32:47 DEBUG : remotepotato: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470787_d20250805_m012810_c001_v0001172_t0021_u01754357290457") 2025/08/05 01:32:47 DEBUG : remotepotato: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4020b5c3d3a53a78_d20250805_m012807_c001_v7007000_t0000_u01754357287051") 2025/08/05 01:32:47 DEBUG : remotepotato: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed47076a_d20250805_m012804_c001_v0001172_t0003_u01754357284970") 2025/08/05 01:32:47 DEBUG : remotepotato: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4020b5c3d3a53a6b_d20250805_m012800_c001_v7007000_t0000_u01754357280008") 2025/08/05 01:32:47 DEBUG : remotepotato: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470758_d20250805_m012758_c001_v0001172_t0014_u01754357278372") 2025/08/05 01:32:47 DEBUG : rutabaga: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4020b5c3d3a53a81_d20250805_m012812_c001_v7007000_t0000_u01754357292191") 2025/08/05 01:32:47 DEBUG : rutabaga: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470776_d20250805_m012807_c001_v0001172_t0048_u01754357287677") 2025/08/05 01:32:47 DEBUG : rutabaga: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4020b5c3d3a53a79_d20250805_m012807_c001_v7007000_t0000_u01754357287299") 2025/08/05 01:32:47 DEBUG : rutabaga: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed47075e_d20250805_m012800_c001_v0001172_t0032_u01754357280563") 2025/08/05 01:32:47 DEBUG : rutabaga: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4020b5c3d3a53a6c_d20250805_m012800_c001_v7007000_t0000_u01754357280192") 2025/08/05 01:32:47 DEBUG : rutabaga: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470746_d20250805_m012755_c001_v0001172_t0030_u01754357275687") 2025/08/05 01:32:47 DEBUG : small: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4210f34572f1a8a3_d20250805_m013136_c001_v7007000_t0000_u01754357496113") 2025/08/05 01:32:47 DEBUG : small: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470bb8_d20250805_m013134_c001_v0001172_t0020_u01754357494237") 2025/08/05 01:32:47 DEBUG : small: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f419046b64f080b37_d20250805_m013132_c001_v7007000_t0000_u01754357492940") 2025/08/05 01:32:47 DEBUG : small: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470b98_d20250805_m013131_c001_v0001172_t0015_u01754357491074") 2025/08/05 01:32:47 DEBUG : small: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4210f34572f1a85d_d20250805_m013130_c001_v7007000_t0000_u01754357490330") 2025/08/05 01:32:47 DEBUG : small: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470b82_d20250805_m013128_c001_v0001172_t0054_u01754357488162") 2025/08/05 01:32:47 DEBUG : small: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f400c3e48f926600f_d20250805_m013127_c001_v7007000_t0000_u01754357487233") 2025/08/05 01:32:48 DEBUG : small: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470b74_d20250805_m013125_c001_v0001172_t0004_u01754357485311") 2025/08/05 01:32:48 DEBUG : sub dir/hello world: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f400c3e48f9265fde_d20250805_m013114_c001_v7007000_t0000_u01754357474448") 2025/08/05 01:32:48 DEBUG : sub dir/hello world: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470b2f_d20250805_m013113_c001_v0001172_t0025_u01754357473570") 2025/08/05 01:32:48 DEBUG : sub dir/hello world: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f400c3e48f9265fa4_d20250805_m013101_c001_v7007000_t0000_u01754357461257") 2025/08/05 01:32:48 DEBUG : sub dir/hello world: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470adf_d20250805_m013056_c001_v0001172_t0052_u01754357456753") 2025/08/05 01:32:48 DEBUG : sub dir/hello world: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f400c3e48f9265791_d20250805_m013054_c001_v7007000_t0000_u01754357454912") 2025/08/05 01:32:48 DEBUG : sub dir/hello world: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470ab7_d20250805_m013049_c001_v0001172_t0048_u01754357449932") 2025/08/05 01:32:48 DEBUG : sub dir/hello world2: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f400c3e48f9265fa7_d20250805_m013101_c001_v7007000_t0000_u01754357461440") 2025/08/05 01:32:48 DEBUG : sub dir/hello world2: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470ae3_d20250805_m013057_c001_v0001172_t0032_u01754357457137") 2025/08/05 01:32:48 DEBUG : sub dir/hello world2: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f400c3e48f9265b7a_d20250805_m013055_c001_v7007000_t0000_u01754357455096") 2025/08/05 01:32:48 DEBUG : sub dir/hello world2: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470ab9_d20250805_m013050_c001_v0001172_t0017_u01754357450288") 2025/08/05 01:32:48 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f400c3e48f9265fa8_d20250805_m013101_c001_v7007000_t0000_u01754357461624") 2025/08/05 01:32:48 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470ae5_d20250805_m013057_c001_v0001172_t0049_u01754357457544") 2025/08/05 01:32:48 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f400c3e48f9265f64_d20250805_m013055_c001_v7007000_t0000_u01754357455287") 2025/08/05 01:32:48 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470abb_d20250805_m013050_c001_v0001172_t0009_u01754357450580") 2025/08/05 01:32:48 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f400c3e48f9265faa_d20250805_m013101_c001_v7007000_t0000_u01754357461818") 2025/08/05 01:32:48 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470ae7_d20250805_m013057_c001_v0001172_t0007_u01754357457865") 2025/08/05 01:32:48 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f400c3e48f9265f8f_d20250805_m013055_c001_v7007000_t0000_u01754357455471") 2025/08/05 01:32:48 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470abf_d20250805_m013051_c001_v0001172_t0003_u01754357451115") 2025/08/05 01:32:48 DEBUG : sub dir/potato3: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f419d9c9094fe96e2_d20250805_m013234_c001_v7007000_t0000_u01754357554774") 2025/08/05 01:32:49 DEBUG : sub dir/potato3: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f116af01e8a359dcd_d20250805_m013232_c001_v0001106_t0028_u01754357552868") 2025/08/05 01:32:49 DEBUG : sub dir/potato3: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470d15_d20250805_m013231_c001_v0001172_t0050_u01754357551620") 2025/08/05 01:32:49 DEBUG : sub dir/potato3: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f400c3e48f9266008_d20250805_m013124_c001_v7007000_t0000_u01754357484942") 2025/08/05 01:32:49 DEBUG : sub dir/potato3: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470b6e_d20250805_m013123_c001_v0001172_t0031_u01754357483678") 2025/08/05 01:32:49 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f400c3e48f9265fab_d20250805_m013102_c001_v7007000_t0000_u01754357462001") 2025/08/05 01:32:49 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470ae9_d20250805_m013058_c001_v0001172_t0003_u01754357458219") 2025/08/05 01:32:49 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f400c3e48f9265f91_d20250805_m013055_c001_v7007000_t0000_u01754357455656") 2025/08/05 01:32:49 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470ac1_d20250805_m013051_c001_v0001172_t0055_u01754357451442") 2025/08/05 01:32:49 DEBUG : sub/file2: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4152d501fbb1e313_d20250805_m013202_c001_v7007000_t0000_u01754357522250") 2025/08/05 01:32:49 DEBUG : sub/file2: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470c4d_d20250805_m013200_c001_v0001172_t0007_u01754357520828") 2025/08/05 01:32:49 DEBUG : sub/file2: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f400c3e48f9265fd5_d20250805_m013112_c001_v7007000_t0000_u01754357472277") 2025/08/05 01:32:49 DEBUG : sub/file2: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470b13_d20250805_m013106_c001_v0001172_t0041_u01754357466522") 2025/08/05 01:32:49 DEBUG : sub/file2: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f400c3e48f9265fbf_d20250805_m013105_c001_v7007000_t0000_u01754357465851") 2025/08/05 01:32:49 DEBUG : sub/file2: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470b07_d20250805_m013103_c001_v0001172_t0004_u01754357463012") 2025/08/05 01:32:49 DEBUG : sub/file2: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4081b645c52f823f_d20250805_m013005_c001_v7007000_t0000_u01754357405383") 2025/08/05 01:32:49 DEBUG : sub/file2: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470981_d20250805_m013003_c001_v0001172_t0024_u01754357403837") 2025/08/05 01:32:49 DEBUG : test-multithread-abort: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4020b5c3d3a53a65_d20250805_m012754_c001_v7007000_t0000_u01754357274767") 2025/08/05 01:32:49 DEBUG : test-multithread-abort: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed4706ac_d20250805_m012713_c001_v0001172_t0035_u01754357233211") 2025/08/05 01:32:49 DEBUG : test-multithread-copy-false-201326591-2: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4020b5c3d3a532f1_d20250805_m012335_c001_v7007000_t0000_u01754357015404") 2025/08/05 01:32:49 DEBUG : test-multithread-copy-false-201326591-2: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed4702a3_d20250805_m012255_c001_v0001172_t0058_u01754356975343") 2025/08/05 01:32:49 DEBUG : test-multithread-copy-false-201326592-2: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f418ed661d1903089_d20250805_m012420_c001_v7007000_t0000_u01754357060286") 2025/08/05 01:32:49 DEBUG : test-multithread-copy-false-201326592-2: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470366_d20250805_m012338_c001_v0001172_t0058_u01754357018402") 2025/08/05 01:32:50 DEBUG : test-multithread-copy-false-201326593-2: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4182b5d0433cb8a3_d20250805_m012502_c001_v7007000_t0000_u01754357102685") 2025/08/05 01:32:50 DEBUG : test-multithread-copy-false-201326593-2: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed47040f_d20250805_m012422_c001_v0001172_t0049_u01754357062987") 2025/08/05 01:32:50 DEBUG : test-multithread-copy-true-201326591-2: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4020b5c3d3a535c8_d20250805_m012545_c001_v7007000_t0000_u01754357145172") 2025/08/05 01:32:50 DEBUG : test-multithread-copy-true-201326591-2: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f205dae8c8bdece95_d20250805_m012524_c001_v0001179_t0032_u01754357124712") 2025/08/05 01:32:50 DEBUG : test-multithread-copy-true-201326592-2: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f418ed661d1903167_d20250805_m012628_c001_v7007000_t0000_u01754357188393") 2025/08/05 01:32:50 DEBUG : test-multithread-copy-true-201326592-2: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f220eba9bcb86e9eb_d20250805_m012606_c001_v0001137_t0028_u01754357166392") 2025/08/05 01:32:50 DEBUG : test-multithread-copy-true-201326593-2: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f412dc033f4aa2039_d20250805_m012711_c001_v7007000_t0000_u01754357231878") 2025/08/05 01:32:50 DEBUG : test-multithread-copy-true-201326593-2: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f2020b5c3d3a53a01_d20250805_m012650_c001_v0001176_t0049_u01754357210696") 2025/08/05 01:32:50 DEBUG : test.sum: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4020b5c3d3a53a9b_d20250805_m012828_c001_v7007000_t0000_u01754357308955") 2025/08/05 01:32:50 DEBUG : test.sum: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed4707e3_d20250805_m012827_c001_v0001172_t0049_u01754357307127") 2025/08/05 01:32:50 DEBUG : test.sum: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed4707d5_d20250805_m012825_c001_v0001172_t0057_u01754357305260") 2025/08/05 01:32:50 DEBUG : test.sum: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed4707d1_d20250805_m012824_c001_v0001172_t0006_u01754357304001") 2025/08/05 01:32:50 DEBUG : test.sum: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed4707cb_d20250805_m012822_c001_v0001172_t0043_u01754357302737") 2025/08/05 01:32:50 DEBUG : test.sum: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed4707c3_d20250805_m012821_c001_v0001172_t0035_u01754357301180") 2025/08/05 01:32:50 DEBUG : test.sum: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed4707b7_d20250805_m012819_c001_v0001172_t0015_u01754357299940") 2025/08/05 01:32:50 DEBUG : test.sum: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed4707b1_d20250805_m012817_c001_v0001172_t0045_u01754357297871") 2025/08/05 01:32:50 DEBUG : test.sum: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f4020b5c3d3a53a86_d20250805_m012814_c001_v7007000_t0000_u01754357294551") 2025/08/05 01:32:50 DEBUG : test.sum: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470795_d20250805_m012813_c001_v0001172_t0025_u01754357293644") 2025/08/05 01:32:50 DEBUG : test.sum: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed47078b_d20250805_m012812_c001_v0001172_t0059_u01754357292557") 2025/08/05 01:32:50 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f419046b64f080be6_d20250805_m013223_c001_v7007000_t0000_u01754357543353") 2025/08/05 01:32:51 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470cc9_d20250805_m013222_c001_v0001172_t0020_u01754357542016") 2025/08/05 01:32:51 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f419046b64f080be7_d20250805_m013223_c001_v7007000_t0000_u01754357543538") 2025/08/05 01:32:51 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470cc3_d20250805_m013221_c001_v0001172_t0026_u01754357541746") 2025/08/05 01:32:51 DEBUG : zend.txt: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f400c3e48f9265fac_d20250805_m013102_c001_v7007000_t0000_u01754357462185") 2025/08/05 01:32:51 DEBUG : zend.txt: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470add_d20250805_m013056_c001_v0001172_t0002_u01754357456479") 2025/08/05 01:32:51 DEBUG : zend.txt: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f400c3e48f9265f93_d20250805_m013055_c001_v7007000_t0000_u01754357455839") 2025/08/05 01:32:51 DEBUG : zend.txt: Deleting (id "4_z9fe2edb3d02d7b7b9f880e1b_f106549efed470ab1_d20250805_m013049_c001_v0001172_t0027_u01754357449495") "./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -fast-list" - Finished OK in 10m8.007620228s (try 1/5)