"./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose" - Starting (try 1/5) 2025/04/02 01:30:20 DEBUG : Creating backend with remote "TestB2:rclone-test-bimamaz9saja" 2025/04/02 01:30:20 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/04/02 01:30:20 DEBUG : Creating backend with remote "/tmp/rclone170065548" === 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-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" 2025/04/02 01:30:22 DEBUG : chunksize-probe: Cancelling large file upload === RUN TestMultithreadCopy/upload=false,size=201326591,streams=2 2025/04/02 01:30:48 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2025/04/02 01:30:48 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: write buffer set to 131072 2025/04/02 01:30:48 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2025/04/02 01:30:48 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2025/04/02 01:30:48 DEBUG : test-multithread-copy-false-201326591-2: Starting multi-thread copy with 3 chunks of size 64Mi with 3 parallel streams 2025/04/02 01:30:48 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 3/3 (134217728-201326591) size 64.000Mi starting 2025/04/02 01:30:48 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 1/3 (0-67108864) size 64Mi starting 2025/04/02 01:30:48 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 2/3 (67108864-134217728) size 64Mi starting 2025/04/02 01:30:48 DEBUG : test-multithread-copy-false-201326591-2: writing chunk 2 2025/04/02 01:30:49 DEBUG : test-multithread-copy-false-201326591-2: writing chunk 0 2025/04/02 01:30:49 DEBUG : test-multithread-copy-false-201326591-2: writing chunk 1 2025/04/02 01:30:54 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 1/3 (0-67108864) size 64Mi finished 2025/04/02 01:30:58 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 2/3 (67108864-134217728) size 64Mi finished 2025/04/02 01:31:00 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 3/3 (134217728-201326591) size 64.000Mi finished 2025/04/02 01:31:00 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/04/02 01:31:39 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2025/04/02 01:31:39 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: write buffer set to 131072 2025/04/02 01:31:39 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2025/04/02 01:31:39 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2025/04/02 01:31:39 DEBUG : test-multithread-copy-false-201326592-2: Starting multi-thread copy with 3 chunks of size 64Mi with 3 parallel streams 2025/04/02 01:31:39 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 3/3 (134217728-201326592) size 64Mi starting 2025/04/02 01:31:39 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 1/3 (0-67108864) size 64Mi starting 2025/04/02 01:31:39 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 2/3 (67108864-134217728) size 64Mi starting 2025/04/02 01:31:40 DEBUG : test-multithread-copy-false-201326592-2: writing chunk 2 2025/04/02 01:31:40 DEBUG : test-multithread-copy-false-201326592-2: writing chunk 1 2025/04/02 01:31:40 DEBUG : test-multithread-copy-false-201326592-2: writing chunk 0 2025/04/02 01:31:45 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 2/3 (67108864-134217728) size 64Mi finished 2025/04/02 01:31:47 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 3/3 (134217728-201326592) size 64Mi finished 2025/04/02 01:31:49 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 1/3 (0-67108864) size 64Mi finished 2025/04/02 01:31:49 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/04/02 01:32:57 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2025/04/02 01:32:57 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: write buffer set to 131072 2025/04/02 01:32:57 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2025/04/02 01:32:57 DEBUG : test-multithread-copy-false-201326593-2: Starting multi-thread copy with 4 chunks of size 64Mi with 4 parallel streams 2025/04/02 01:32:57 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 4/4 (201326592-201326593) size 1 starting 2025/04/02 01:32:57 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 2/4 (67108864-134217728) size 64Mi starting 2025/04/02 01:32:57 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 3/4 (134217728-201326592) size 64Mi starting 2025/04/02 01:32:57 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 1/4 (0-67108864) size 64Mi starting 2025/04/02 01:32:57 DEBUG : test-multithread-copy-false-201326593-2: writing chunk 3 2025/04/02 01:32:57 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 4/4 (201326592-201326593) size 1 finished 2025/04/02 01:32:58 DEBUG : test-multithread-copy-false-201326593-2: writing chunk 2 2025/04/02 01:32:58 DEBUG : test-multithread-copy-false-201326593-2: writing chunk 0 2025/04/02 01:32:58 DEBUG : test-multithread-copy-false-201326593-2: writing chunk 1 2025/04/02 01:33:03 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 3/4 (134217728-201326592) size 64Mi finished 2025/04/02 01:33:03 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 1/4 (0-67108864) size 64Mi finished 2025/04/02 01:33:03 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 2/4 (67108864-134217728) size 64Mi finished 2025/04/02 01:33:03 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/04/02 01:33:31 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: disabling buffering because source is local disk 2025/04/02 01:33:32 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2025/04/02 01:33:32 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: number of streams 4 was bigger than number of chunks 2 2025/04/02 01:33:32 DEBUG : test-multithread-copy-true-201326591-2: Starting multi-thread copy with 2 chunks of size 96Mi with 2 parallel streams 2025/04/02 01:33:32 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: chunk 2/2 (100663296-201326591) size 96.000Mi starting 2025/04/02 01:33:32 DEBUG : test-multithread-copy-true-201326591-2: Sending chunk 1 length 100663295 2025/04/02 01:33:32 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: chunk 1/2 (0-100663296) size 96Mi starting 2025/04/02 01:33:32 DEBUG : test-multithread-copy-true-201326591-2: Sending chunk 0 length 100663296 2025/04/02 01:33:41 DEBUG : test-multithread-copy-true-201326591-2: Done sending chunk 1 2025/04/02 01:33:41 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: chunk 2/2 (100663296-201326591) size 96.000Mi finished 2025/04/02 01:33:41 DEBUG : test-multithread-copy-true-201326591-2: Done sending chunk 0 2025/04/02 01:33:41 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: chunk 1/2 (0-100663296) size 96Mi finished 2025/04/02 01:33:41 DEBUG : test-multithread-copy-true-201326591-2: Finishing large file upload with 2 parts 2025/04/02 01:33:42 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/04/02 01:34:10 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: disabling buffering because source is local disk 2025/04/02 01:34:11 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2025/04/02 01:34:11 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: number of streams 4 was bigger than number of chunks 2 2025/04/02 01:34:11 DEBUG : test-multithread-copy-true-201326592-2: Starting multi-thread copy with 2 chunks of size 96Mi with 2 parallel streams 2025/04/02 01:34:11 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: chunk 2/2 (100663296-201326592) size 96Mi starting 2025/04/02 01:34:11 DEBUG : test-multithread-copy-true-201326592-2: Sending chunk 1 length 100663296 2025/04/02 01:34:11 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: chunk 1/2 (0-100663296) size 96Mi starting 2025/04/02 01:34:11 DEBUG : test-multithread-copy-true-201326592-2: Sending chunk 0 length 100663296 2025/04/02 01:34:20 DEBUG : test-multithread-copy-true-201326592-2: Done sending chunk 1 2025/04/02 01:34:20 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: chunk 2/2 (100663296-201326592) size 96Mi finished 2025/04/02 01:34:21 DEBUG : test-multithread-copy-true-201326592-2: Done sending chunk 0 2025/04/02 01:34:21 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: chunk 1/2 (0-100663296) size 96Mi finished 2025/04/02 01:34:21 DEBUG : test-multithread-copy-true-201326592-2: Finishing large file upload with 2 parts 2025/04/02 01:34:22 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/04/02 01:34:50 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: disabling buffering because source is local disk 2025/04/02 01:34:51 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2025/04/02 01:34:51 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2025/04/02 01:34:51 DEBUG : test-multithread-copy-true-201326593-2: Starting multi-thread copy with 3 chunks of size 96Mi with 3 parallel streams 2025/04/02 01:34:51 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 3/3 (201326592-201326593) size 1 starting 2025/04/02 01:34:51 DEBUG : test-multithread-copy-true-201326593-2: Sending chunk 2 length 1 2025/04/02 01:34:51 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 1/3 (0-100663296) size 96Mi starting 2025/04/02 01:34:51 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 2/3 (100663296-201326592) size 96Mi starting 2025/04/02 01:34:51 DEBUG : test-multithread-copy-true-201326593-2: Sending chunk 0 length 100663296 2025/04/02 01:34:51 DEBUG : test-multithread-copy-true-201326593-2: Sending chunk 1 length 100663296 2025/04/02 01:34:52 DEBUG : test-multithread-copy-true-201326593-2: Done sending chunk 2 2025/04/02 01:34:52 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 3/3 (201326592-201326593) size 1 finished 2025/04/02 01:35:17 DEBUG : test-multithread-copy-true-201326593-2: Done sending chunk 0 2025/04/02 01:35:17 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 1/3 (0-100663296) size 96Mi finished 2025/04/02 01:35:31 DEBUG : test-multithread-copy-true-201326593-2: Done sending chunk 1 2025/04/02 01:35:31 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 2/3 (100663296-201326592) size 96Mi finished 2025/04/02 01:35:31 DEBUG : test-multithread-copy-true-201326593-2: Finishing large file upload with 3 parts 2025/04/02 01:35:32 DEBUG : test-multithread-copy-true-201326593-2: Finished multi-thread copy with 3 parts of size 96Mi --- PASS: TestMultithreadCopy (321.70s) --- PASS: TestMultithreadCopy/upload=false,size=201326591,streams=2 (47.74s) --- PASS: TestMultithreadCopy/upload=false,size=201326592,streams=2 (49.07s) --- PASS: TestMultithreadCopy/upload=false,size=201326593,streams=2 (74.12s) --- PASS: TestMultithreadCopy/upload=true,size=201326591,streams=2 (39.97s) --- PASS: TestMultithreadCopy/upload=true,size=201326592,streams=2 (39.45s) --- PASS: TestMultithreadCopy/upload=true,size=201326593,streams=2 (69.27s) === RUN TestMultithreadCopyAbort run.go:180: Remote "B2 bucket rclone-test-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" 2025/04/02 01:35:42 DEBUG : chunksize-probe: Cancelling large file upload 2025/04/02 01:36:00 DEBUG : test-multithread-abort: multi-thread copy: disabling buffering because source is local disk 2025/04/02 01:36:01 DEBUG : test-multithread-abort: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 1 2025/04/02 01:36:01 DEBUG : test-multithread-abort: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2025/04/02 01:36:01 DEBUG : test-multithread-abort: Starting multi-thread copy with 3 chunks of size 96Mi with 3 parallel streams 2025/04/02 01:36:01 DEBUG : test-multithread-abort: multi-thread copy: chunk 3/3 (201326592-201326593) size 1 starting 2025/04/02 01:36:01 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2025/04/02 01:36:01 DEBUG : test-multithread-abort: multi-thread copy: chunk 2/3 (100663296-201326592) size 96Mi starting 2025/04/02 01:36:01 DEBUG : Open with options = [RangeOption(100663296,201326591)] 2025/04/02 01:36:01 DEBUG : test-multithread-abort: multi-thread copy: chunk 1/3 (0-100663296) size 96Mi starting 2025/04/02 01:36:01 DEBUG : Open with options = [RangeOption(0,100663295)] 2025/04/02 01:36:01 DEBUG : test-multithread-abort: Sending chunk 1 length 100663296 2025/04/02 01:36:01 DEBUG : test-multithread-abort: Sending chunk 0 length 100663296 2025/04/02 01:36:09 DEBUG : test-multithread-abort: Done sending chunk 1 2025/04/02 01:36:09 DEBUG : test-multithread-abort: multi-thread copy: chunk 2/3 (100663296-201326592) size 96Mi finished 2025/04/02 01:36:10 DEBUG : test-multithread-abort: Done sending chunk 0 2025/04/02 01:36:10 DEBUG : test-multithread-abort: multi-thread copy: chunk 1/3 (0-100663296) size 96Mi finished 2025/04/02 01:36:10 DEBUG : Returning error reader 2025/04/02 01:36:10 DEBUG : test-multithread-abort: Sending chunk 2 length 1 2025/04/02 01:36:10 DEBUG : BOOM: simulated read failure 2025/04/02 01:36:10 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 0/10: BOOM: simulated read failure 2025/04/02 01:36:10 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2025/04/02 01:36:11 DEBUG : Returning error reader 2025/04/02 01:36:11 DEBUG : BOOM: simulated read failure 2025/04/02 01:36:11 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 1/10: BOOM: simulated read failure 2025/04/02 01:36:11 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2025/04/02 01:36:12 DEBUG : Returning error reader 2025/04/02 01:36:12 DEBUG : BOOM: simulated read failure 2025/04/02 01:36:12 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 2/10: BOOM: simulated read failure 2025/04/02 01:36:12 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2025/04/02 01:36:13 DEBUG : Returning error reader 2025/04/02 01:36:13 DEBUG : BOOM: simulated read failure 2025/04/02 01:36:13 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 3/10: BOOM: simulated read failure 2025/04/02 01:36:13 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2025/04/02 01:36:14 DEBUG : Returning error reader 2025/04/02 01:36:14 DEBUG : BOOM: simulated read failure 2025/04/02 01:36:14 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 4/10: BOOM: simulated read failure 2025/04/02 01:36:14 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2025/04/02 01:36:15 DEBUG : Returning error reader 2025/04/02 01:36:15 DEBUG : BOOM: simulated read failure 2025/04/02 01:36:15 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 5/10: BOOM: simulated read failure 2025/04/02 01:36:15 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2025/04/02 01:36:16 DEBUG : Returning error reader 2025/04/02 01:36:16 DEBUG : BOOM: simulated read failure 2025/04/02 01:36:16 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 6/10: BOOM: simulated read failure 2025/04/02 01:36:16 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2025/04/02 01:36:17 DEBUG : Returning error reader 2025/04/02 01:36:17 DEBUG : BOOM: simulated read failure 2025/04/02 01:36:17 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 7/10: BOOM: simulated read failure 2025/04/02 01:36:17 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2025/04/02 01:36:18 DEBUG : Returning error reader 2025/04/02 01:36:18 DEBUG : BOOM: simulated read failure 2025/04/02 01:36:18 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 8/10: BOOM: simulated read failure 2025/04/02 01:36:18 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2025/04/02 01:36:19 DEBUG : Returning error reader 2025/04/02 01:36:19 DEBUG : BOOM: simulated read failure 2025/04/02 01:36:19 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 9/10: BOOM: simulated read failure 2025/04/02 01:36:19 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2025/04/02 01:36:20 DEBUG : Returning error reader 2025/04/02 01:36:20 DEBUG : BOOM: simulated read failure 2025/04/02 01:36:20 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 10/10: BOOM: simulated read failure 2025/04/02 01:36:20 DEBUG : test-multithread-abort: Reopen failed after offset 0 bytes read: failed to reopen: too many retries 2025/04/02 01:36:20 DEBUG : test-multithread-abort: Error sending chunk 2 (retry=false): Post "https://pod-000-1145-00.backblaze.com/b2api/v1/b2_upload_part/4_zefb2eda310edfb4b9f580e1b_f216caf4237860ebd_d20250402_m013601_c001_v0001145_t0029_u01743557761757/0035": BOOM: simulated read failure: &url.Error{Op:"Post", URL:"https://pod-000-1145-00.backblaze.com/b2api/v1/b2_upload_part/4_zefb2eda310edfb4b9f580e1b_f216caf4237860ebd_d20250402_m013601_c001_v0001145_t0029_u01743557761757/0035", Err:(*errors.errorString)(0xc000354f50)} 2025/04/02 01:36:20 DEBUG : test-multithread-abort: Error sending chunk 2: Post "https://pod-000-1145-00.backblaze.com/b2api/v1/b2_upload_part/4_zefb2eda310edfb4b9f580e1b_f216caf4237860ebd_d20250402_m013601_c001_v0001145_t0029_u01743557761757/0035": BOOM: simulated read failure 2025/04/02 01:36:20 DEBUG : test-multithread-abort: multi-thread copy: chunk 3/3 failed: multi-thread copy: failed to write chunk: Post "https://pod-000-1145-00.backblaze.com/b2api/v1/b2_upload_part/4_zefb2eda310edfb4b9f580e1b_f216caf4237860ebd_d20250402_m013601_c001_v0001145_t0029_u01743557761757/0035": BOOM: simulated read failure 2025/04/02 01:36:20 DEBUG : test-multithread-abort: multi-thread copy: cancelling transfer on exit 2025/04/02 01:36:20 DEBUG : test-multithread-abort: Cancelling large file upload --- PASS: TestMultithreadCopyAbort (39.10s) === RUN TestSizeDiffers --- PASS: TestSizeDiffers (0.00s) === RUN TestReOpen === RUN TestReOpen/Normal === RUN TestReOpen/Normal/Basics 2025/04/02 01:36:21 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/ErrorAtStart === RUN TestReOpen/Normal/WithErrors 2025/04/02 01:36:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2025/04/02 01:36:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2025/04/02 01:36:21 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/Normal/TooManyErrors 2025/04/02 01:36:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2025/04/02 01:36:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2025/04/02 01:36:21 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2025/04/02 01:36:21 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/Normal/Seek 2025/04/02 01:36:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/04/02 01:36:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/04/02 01:36:21 DEBUG : potato: Seek from 5 to 2 === RUN TestReOpen/Normal/AccountRead === RUN TestReOpen/Normal/AccountReadDelay 2025/04/02 01:36:21 DEBUG : potato: Seek from 10 to 0 2025/04/02 01:36:21 DEBUG : potato: Seek from 10 to 0 2025/04/02 01:36:21 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/AccountReadError === RUN TestReOpen/WithRangeOption === RUN TestReOpen/WithRangeOption/Basics 2025/04/02 01:36:21 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/ErrorAtStart === RUN TestReOpen/WithRangeOption/WithErrors 2025/04/02 01:36:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2025/04/02 01:36:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2025/04/02 01:36:21 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithRangeOption/TooManyErrors 2025/04/02 01:36:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2025/04/02 01:36:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2025/04/02 01:36:21 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2025/04/02 01:36:21 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithRangeOption/Seek 2025/04/02 01:36:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/04/02 01:36:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/04/02 01:36:21 DEBUG : potato: Seek from 5 to 2 2025/04/02 01:36:21 DEBUG : potato: Seek from 7 to 4 === RUN TestReOpen/WithRangeOption/AccountRead === RUN TestReOpen/WithRangeOption/AccountReadDelay 2025/04/02 01:36:21 DEBUG : potato: Seek from 7 to 0 2025/04/02 01:36:21 DEBUG : potato: Seek from 7 to 0 2025/04/02 01:36:21 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/AccountReadError === RUN TestReOpen/WithSeekOption === RUN TestReOpen/WithSeekOption/Basics 2025/04/02 01:36:21 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/ErrorAtStart === RUN TestReOpen/WithSeekOption/WithErrors 2025/04/02 01:36:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2025/04/02 01:36:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2025/04/02 01:36:21 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithSeekOption/TooManyErrors 2025/04/02 01:36:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2025/04/02 01:36:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2025/04/02 01:36:21 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2025/04/02 01:36:21 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithSeekOption/Seek 2025/04/02 01:36:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/04/02 01:36:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/04/02 01:36:21 DEBUG : potato: Seek from 5 to 2 2025/04/02 01:36:21 DEBUG : potato: Seek from 7 to 5 === RUN TestReOpen/WithSeekOption/AccountRead === RUN TestReOpen/WithSeekOption/AccountReadDelay 2025/04/02 01:36:21 DEBUG : potato: Seek from 8 to 0 2025/04/02 01:36:21 DEBUG : potato: Seek from 8 to 0 2025/04/02 01:36:21 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/AccountReadError === RUN TestReOpen/UnknownSize === RUN TestReOpen/UnknownSize/Basics 2025/04/02 01:36:21 DEBUG : potato: Seek from 9 to 0 === RUN TestReOpen/UnknownSize/ErrorAtStart === RUN TestReOpen/UnknownSize/WithErrors 2025/04/02 01:36:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2025/04/02 01:36:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2025/04/02 01:36:21 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/UnknownSize/TooManyErrors 2025/04/02 01:36:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2025/04/02 01:36:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2025/04/02 01:36:21 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2025/04/02 01:36:21 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/UnknownSize/Seek 2025/04/02 01:36:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/04/02 01:36:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/04/02 01:36:21 DEBUG : potato: Seek from 5 to 2 2025/04/02 01:36:21 DEBUG : potato: Seek from 7 to 6 === RUN TestReOpen/UnknownSize/AccountRead === RUN TestReOpen/UnknownSize/AccountReadDelay 2025/04/02 01:36:21 DEBUG : potato: Seek from 9 to 0 2025/04/02 01:36:21 DEBUG : potato: Seek from 9 to 0 2025/04/02 01:36:21 DEBUG : potato: Seek from 9 to 0 === RUN TestReOpen/UnknownSize/AccountReadError --- PASS: TestReOpen (0.00s) --- PASS: TestReOpen/Normal (0.00s) --- PASS: TestReOpen/Normal/Basics (0.00s) --- PASS: TestReOpen/Normal/ErrorAtStart (0.00s) --- PASS: TestReOpen/Normal/WithErrors (0.00s) --- PASS: TestReOpen/Normal/TooManyErrors (0.00s) --- PASS: TestReOpen/Normal/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-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" === RUN TestCheck/1 === RUN TestCheck/2 === RUN TestCheck/3 === RUN TestCheck/4 === RUN TestCheck/5 === RUN TestCheck/6 === RUN TestCheck/7 --- PASS: TestCheck (4.92s) --- PASS: TestCheck/1 (0.18s) --- PASS: TestCheck/2 (0.18s) --- PASS: TestCheck/3 (0.18s) --- PASS: TestCheck/4 (0.19s) --- PASS: TestCheck/5 (0.18s) --- PASS: TestCheck/6 (0.18s) --- PASS: TestCheck/7 (0.19s) === RUN TestCheckFsError 2025/04/02 01:36:26 DEBUG : Creating backend with remote "nonexistent" 2025/04/02 01:36:26 DEBUG : Config file has changed externally - reloading 2025/04/02 01:36:26 DEBUG : Creating backend with remote "nonexistent" 2025/04/02 01:36:26 DEBUG : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: Waiting for checks to finish 2025/04/02 01:36:26 ERROR : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: error reading source root directory: directory not found 2025/04/02 01:36:26 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: 2 differences found 2025/04/02 01:36:26 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: 2 errors while checking --- PASS: TestCheckFsError (0.00s) === RUN TestCheckDownload run.go:180: Remote "B2 bucket rclone-test-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" === RUN TestCheckDownload/1 === RUN TestCheckDownload/2 === RUN TestCheckDownload/3 === RUN TestCheckDownload/4 === RUN TestCheckDownload/5 === RUN TestCheckDownload/6 === RUN TestCheckDownload/7 --- PASS: TestCheckDownload (6.80s) --- PASS: TestCheckDownload/1 (0.75s) --- PASS: TestCheckDownload/2 (0.42s) --- PASS: TestCheckDownload/3 (0.37s) --- PASS: TestCheckDownload/4 (0.57s) --- PASS: TestCheckDownload/5 (0.55s) --- PASS: TestCheckDownload/6 (0.38s) --- PASS: TestCheckDownload/7 (0.39s) === RUN TestCheckSizeOnly run.go:180: Remote "B2 bucket rclone-test-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", 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.71s) --- PASS: TestCheckSizeOnly/1 (0.20s) --- PASS: TestCheckSizeOnly/2 (0.18s) --- PASS: TestCheckSizeOnly/3 (0.20s) --- PASS: TestCheckSizeOnly/4 (0.19s) --- PASS: TestCheckSizeOnly/5 (0.19s) --- 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-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" 2025/04/02 01:36:39 NOTICE: test.sum: improperly formatted checksum line 4 2025/04/02 01:36:39 NOTICE: test.sum: improperly formatted checksum line 5 2025/04/02 01:36:39 NOTICE: test.sum: improperly formatted checksum line 6 2025/04/02 01:36:39 NOTICE: test.sum: 2 warning(s) suppressed... 2025/04/02 01:36:40 NOTICE: test.sum: improperly formatted checksum line 4 2025/04/02 01:36:40 NOTICE: test.sum: improperly formatted checksum line 5 2025/04/02 01:36:40 NOTICE: test.sum: improperly formatted checksum line 6 2025/04/02 01:36:40 NOTICE: test.sum: 2 warning(s) suppressed... --- PASS: TestParseSumFile (3.01s) === RUN TestCheckSum run.go:180: Remote "B2 bucket rclone-test-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" 2025/04/02 01:36:41 DEBUG : Creating backend with remote "TestB2:rclone-test-bimamaz9saja/data" check_test.go:353: B2 bucket rclone-test-bimamaz9saja path data lacks md5, skipping --- SKIP: TestCheckSum (1.64s) === RUN TestCheckSumDownload run.go:180: Remote "B2 bucket rclone-test-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" 2025/04/02 01:36:42 DEBUG : Creating backend with remote "TestB2:rclone-test-bimamaz9saja/data" 2025/04/02 01:36:44 DEBUG : data/banana: Clearing upload URL because of error: Post "https://pod-000-1101-12.backblaze.com/b2api/v1/b2_upload_file/efb2eda310edfb4b9f580e1b/c001_v0001101_t0005": EOF 2025/04/02 01:36:44 DEBUG : pacer: low level retry 1/1 (error Post "https://pod-000-1101-12.backblaze.com/b2api/v1/b2_upload_file/efb2eda310edfb4b9f580e1b/c001_v0001101_t0005": EOF) 2025/04/02 01:36:44 DEBUG : pacer: Rate limited, increasing sleep to 20ms run.go:281: Retry Put of "data/banana" to B2 bucket rclone-test-bimamaz9saja: 1/10 (Post "https://pod-000-1101-12.backblaze.com/b2api/v1/b2_upload_file/efb2eda310edfb4b9f580e1b/c001_v0001101_t0005": EOF) 2025/04/02 01:36:46 DEBUG : pacer: Reducing sleep to 10ms === RUN TestCheckSumDownload/subtest1 === RUN TestCheckSumDownload/subtest2 === RUN TestCheckSumDownload/subtest3 === RUN TestCheckSumDownload/subtest4 === RUN TestCheckSumDownload/subtest5 === RUN TestCheckSumDownload/subtest6 === RUN TestCheckSumDownload/subtest7 --- PASS: TestCheckSumDownload (18.13s) --- PASS: TestCheckSumDownload/subtest1 (2.27s) --- PASS: TestCheckSumDownload/subtest2 (0.75s) --- PASS: TestCheckSumDownload/subtest3 (1.03s) --- PASS: TestCheckSumDownload/subtest4 (0.81s) --- PASS: TestCheckSumDownload/subtest5 (0.77s) --- PASS: TestCheckSumDownload/subtest6 (0.76s) --- PASS: TestCheckSumDownload/subtest7 (0.76s) === RUN TestApplyTransforms 2025/04/02 01:37:00 DEBUG : Creating backend with remote "TestB2:rclone-test-rehadid4husi" 2025/04/02 01:37:00 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/04/02 01:37:01 DEBUG : Creating backend with remote "/tmp/rclone1458830608" run.go:180: Remote "B2 bucket rclone-test-rehadid4husi", Local "Local file system at /tmp/rclone1458830608", Modify Window "1ms" upper checkfile vs. lower remote (without normalization) 2025/04/02 01:37:03 ERROR : hello, world!: sum not found 2025/04/02 01:37:03 ERROR : HELLO, WORLD!: file not in B2 bucket rclone-test-rehadid4husi 2025/04/02 01:37:03 NOTICE: B2 bucket rclone-test-rehadid4husi: 1 files missing 2025/04/02 01:37:03 NOTICE: 1 hashes missing 2025/04/02 01:37:03 NOTICE: B2 bucket rclone-test-rehadid4husi: 2 differences found 2025/04/02 01:37:03 NOTICE: B2 bucket rclone-test-rehadid4husi: 2 errors while checking upper checkfile vs. lower remote (with normalization) 2025/04/02 01:37:04 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/04/02 01:37:04 NOTICE: B2 bucket rclone-test-rehadid4husi: 0 differences found 2025/04/02 01:37:04 NOTICE: B2 bucket rclone-test-rehadid4husi: 1 matching files 2025/04/02 01:37:04 DEBUG : Creating backend with remote "TestB2:rclone-test-cukipim1goxo" 2025/04/02 01:37:04 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/04/02 01:37:05 DEBUG : Creating backend with remote "/tmp/rclone4006919205" run.go:180: Remote "B2 bucket rclone-test-cukipim1goxo", Local "Local file system at /tmp/rclone4006919205", Modify Window "1ms" lower checkfile vs. upper remote (without normalization) 2025/04/02 01:37:08 ERROR : HELLO, WORLD!: sum not found 2025/04/02 01:37:08 ERROR : hello, world!: file not in B2 bucket rclone-test-cukipim1goxo 2025/04/02 01:37:08 NOTICE: B2 bucket rclone-test-cukipim1goxo: 1 files missing 2025/04/02 01:37:08 NOTICE: 1 hashes missing 2025/04/02 01:37:08 NOTICE: B2 bucket rclone-test-cukipim1goxo: 2 differences found 2025/04/02 01:37:08 NOTICE: B2 bucket rclone-test-cukipim1goxo: 2 errors while checking lower checkfile vs. upper remote (with normalization) 2025/04/02 01:37:10 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/04/02 01:37:10 NOTICE: B2 bucket rclone-test-cukipim1goxo: 0 differences found 2025/04/02 01:37:10 NOTICE: B2 bucket rclone-test-cukipim1goxo: 1 matching files 2025/04/02 01:37:10 DEBUG : Creating backend with remote "TestB2:rclone-test-jekusay3vohi" 2025/04/02 01:37:10 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/04/02 01:37:10 DEBUG : Creating backend with remote "/tmp/rclone2473590427" run.go:180: Remote "B2 bucket rclone-test-jekusay3vohi", Local "Local file system at /tmp/rclone2473590427", Modify Window "1ms" lower checkfile vs. upperlowermixed remote (without normalization) 2025/04/02 01:37:12 ERROR : HeLlO, wOrLd!: sum not found 2025/04/02 01:37:12 ERROR : hello, world!: file not in B2 bucket rclone-test-jekusay3vohi 2025/04/02 01:37:12 NOTICE: B2 bucket rclone-test-jekusay3vohi: 1 files missing 2025/04/02 01:37:12 NOTICE: 1 hashes missing 2025/04/02 01:37:12 NOTICE: B2 bucket rclone-test-jekusay3vohi: 2 differences found 2025/04/02 01:37:12 NOTICE: B2 bucket rclone-test-jekusay3vohi: 2 errors while checking lower checkfile vs. upperlowermixed remote (with normalization) 2025/04/02 01:37:13 DEBUG : HeLlO, wOrLd!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/04/02 01:37:13 NOTICE: B2 bucket rclone-test-jekusay3vohi: 0 differences found 2025/04/02 01:37:13 NOTICE: B2 bucket rclone-test-jekusay3vohi: 1 matching files 2025/04/02 01:37:13 DEBUG : Creating backend with remote "TestB2:rclone-test-cadubat8luli" 2025/04/02 01:37:13 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/04/02 01:37:14 DEBUG : Creating backend with remote "/tmp/rclone2057651384" run.go:180: Remote "B2 bucket rclone-test-cadubat8luli", Local "Local file system at /tmp/rclone2057651384", Modify Window "1ms" upperlowermixed checkfile vs. upper remote (without normalization) 2025/04/02 01:37:16 ERROR : HELLO, WORLD!: sum not found 2025/04/02 01:37:16 ERROR : HeLlO, wOrLd!: file not in B2 bucket rclone-test-cadubat8luli 2025/04/02 01:37:16 NOTICE: B2 bucket rclone-test-cadubat8luli: 1 files missing 2025/04/02 01:37:16 NOTICE: 1 hashes missing 2025/04/02 01:37:16 NOTICE: B2 bucket rclone-test-cadubat8luli: 2 differences found 2025/04/02 01:37:16 NOTICE: B2 bucket rclone-test-cadubat8luli: 2 errors while checking upperlowermixed checkfile vs. upper remote (with normalization) 2025/04/02 01:37:17 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/04/02 01:37:17 NOTICE: B2 bucket rclone-test-cadubat8luli: 0 differences found 2025/04/02 01:37:17 NOTICE: B2 bucket rclone-test-cadubat8luli: 1 matching files 2025/04/02 01:37:17 DEBUG : Creating backend with remote "TestB2:rclone-test-neteper9dugo" 2025/04/02 01:37:17 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/04/02 01:37:18 DEBUG : Creating backend with remote "/tmp/rclone3034263491" run.go:180: Remote "B2 bucket rclone-test-neteper9dugo", Local "Local file system at /tmp/rclone3034263491", Modify Window "1ms" NFD checkfile vs. NFC remote (without normalization) 2025/04/02 01:37:20 ERROR : 測試_Русский___ě_áñ: sum not found 2025/04/02 01:37:20 ERROR : 測試_Русский___ě_áñ: file not in B2 bucket rclone-test-neteper9dugo 2025/04/02 01:37:20 NOTICE: B2 bucket rclone-test-neteper9dugo: 1 files missing 2025/04/02 01:37:20 NOTICE: 1 hashes missing 2025/04/02 01:37:20 NOTICE: B2 bucket rclone-test-neteper9dugo: 2 differences found 2025/04/02 01:37:20 NOTICE: B2 bucket rclone-test-neteper9dugo: 2 errors while checking NFD checkfile vs. NFC remote (with normalization) 2025/04/02 01:37:21 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/04/02 01:37:21 NOTICE: B2 bucket rclone-test-neteper9dugo: 0 differences found 2025/04/02 01:37:21 NOTICE: B2 bucket rclone-test-neteper9dugo: 1 matching files 2025/04/02 01:37:21 DEBUG : Creating backend with remote "TestB2:rclone-test-bologat1sucu" 2025/04/02 01:37:21 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/04/02 01:37:22 DEBUG : Creating backend with remote "/tmp/rclone1226242721" run.go:180: Remote "B2 bucket rclone-test-bologat1sucu", Local "Local file system at /tmp/rclone1226242721", Modify Window "1ms" NFC checkfile vs. NFD remote (without normalization) 2025/04/02 01:37:25 ERROR : 測試_Русский___ě_áñ: sum not found 2025/04/02 01:37:25 ERROR : 測試_Русский___ě_áñ: file not in B2 bucket rclone-test-bologat1sucu 2025/04/02 01:37:25 NOTICE: B2 bucket rclone-test-bologat1sucu: 1 files missing 2025/04/02 01:37:25 NOTICE: 1 hashes missing 2025/04/02 01:37:25 NOTICE: B2 bucket rclone-test-bologat1sucu: 2 differences found 2025/04/02 01:37:25 NOTICE: B2 bucket rclone-test-bologat1sucu: 2 errors while checking NFC checkfile vs. NFD remote (with normalization) 2025/04/02 01:37:25 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/04/02 01:37:25 NOTICE: B2 bucket rclone-test-bologat1sucu: 0 differences found 2025/04/02 01:37:25 NOTICE: B2 bucket rclone-test-bologat1sucu: 1 matching files 2025/04/02 01:37:25 DEBUG : Creating backend with remote "TestB2:rclone-test-sawigid5nira" 2025/04/02 01:37:25 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/04/02 01:37:26 DEBUG : Creating backend with remote "/tmp/rclone3104557781" run.go:180: Remote "B2 bucket rclone-test-sawigid5nira", Local "Local file system at /tmp/rclone3104557781", Modify Window "1ms" NFDx2 checkfile vs. both remote (without normalization) 2025/04/02 01:37:28 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2025/04/02 01:37:28 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-sawigid5nira 2025/04/02 01:37:28 NOTICE: B2 bucket rclone-test-sawigid5nira: 1 files missing 2025/04/02 01:37:28 NOTICE: 1 hashes missing 2025/04/02 01:37:28 NOTICE: B2 bucket rclone-test-sawigid5nira: 2 differences found 2025/04/02 01:37:28 NOTICE: B2 bucket rclone-test-sawigid5nira: 2 errors while checking NFDx2 checkfile vs. both remote (with normalization) 2025/04/02 01:37:29 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/04/02 01:37:29 NOTICE: B2 bucket rclone-test-sawigid5nira: 0 differences found 2025/04/02 01:37:29 NOTICE: B2 bucket rclone-test-sawigid5nira: 1 matching files 2025/04/02 01:37:29 DEBUG : Creating backend with remote "TestB2:rclone-test-pusemej8coca" 2025/04/02 01:37:29 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/04/02 01:37:30 DEBUG : Creating backend with remote "/tmp/rclone2953443557" run.go:180: Remote "B2 bucket rclone-test-pusemej8coca", Local "Local file system at /tmp/rclone2953443557", Modify Window "1ms" NFCx2 checkfile vs. both remote (without normalization) 2025/04/02 01:37:32 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2025/04/02 01:37:32 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-pusemej8coca 2025/04/02 01:37:32 NOTICE: B2 bucket rclone-test-pusemej8coca: 1 files missing 2025/04/02 01:37:32 NOTICE: 1 hashes missing 2025/04/02 01:37:32 NOTICE: B2 bucket rclone-test-pusemej8coca: 2 differences found 2025/04/02 01:37:32 NOTICE: B2 bucket rclone-test-pusemej8coca: 2 errors while checking NFCx2 checkfile vs. both remote (with normalization) 2025/04/02 01:37:34 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/04/02 01:37:34 NOTICE: B2 bucket rclone-test-pusemej8coca: 0 differences found 2025/04/02 01:37:34 NOTICE: B2 bucket rclone-test-pusemej8coca: 1 matching files 2025/04/02 01:37:34 DEBUG : Creating backend with remote "TestB2:rclone-test-popezef3sumu" 2025/04/02 01:37:34 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/04/02 01:37:34 DEBUG : Creating backend with remote "/tmp/rclone695703313" run.go:180: Remote "B2 bucket rclone-test-popezef3sumu", Local "Local file system at /tmp/rclone695703313", Modify Window "1ms" both checkfile vs. NFDx2 remote (without normalization) 2025/04/02 01:37:37 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2025/04/02 01:37:37 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-popezef3sumu 2025/04/02 01:37:37 NOTICE: B2 bucket rclone-test-popezef3sumu: 1 files missing 2025/04/02 01:37:37 NOTICE: 1 hashes missing 2025/04/02 01:37:37 NOTICE: B2 bucket rclone-test-popezef3sumu: 2 differences found 2025/04/02 01:37:37 NOTICE: B2 bucket rclone-test-popezef3sumu: 2 errors while checking both checkfile vs. NFDx2 remote (with normalization) 2025/04/02 01:37:38 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/04/02 01:37:38 NOTICE: B2 bucket rclone-test-popezef3sumu: 0 differences found 2025/04/02 01:37:38 NOTICE: B2 bucket rclone-test-popezef3sumu: 1 matching files 2025/04/02 01:37:38 DEBUG : Creating backend with remote "TestB2:rclone-test-hikozaf7mubi" 2025/04/02 01:37:38 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/04/02 01:37:39 DEBUG : Creating backend with remote "/tmp/rclone3995688852" run.go:180: Remote "B2 bucket rclone-test-hikozaf7mubi", Local "Local file system at /tmp/rclone3995688852", Modify Window "1ms" both checkfile vs. NFCx2 remote (without normalization) 2025/04/02 01:37:42 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2025/04/02 01:37:42 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-hikozaf7mubi 2025/04/02 01:37:42 NOTICE: B2 bucket rclone-test-hikozaf7mubi: 1 files missing 2025/04/02 01:37:42 NOTICE: 1 hashes missing 2025/04/02 01:37:42 NOTICE: B2 bucket rclone-test-hikozaf7mubi: 2 differences found 2025/04/02 01:37:42 NOTICE: B2 bucket rclone-test-hikozaf7mubi: 2 errors while checking both checkfile vs. NFCx2 remote (with normalization) 2025/04/02 01:37:43 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/04/02 01:37:43 NOTICE: B2 bucket rclone-test-hikozaf7mubi: 0 differences found 2025/04/02 01:37:43 NOTICE: B2 bucket rclone-test-hikozaf7mubi: 1 matching files 2025/04/02 01:37:43 DEBUG : B2 bucket rclone-test-hikozaf7mubi: Purge remote 2025/04/02 01:37:43 INFO : B2 bucket rclone-test-hikozaf7mubi: cleaning bucket "rclone-test-hikozaf7mubi" of all files 2025/04/02 01:37:43 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_z1fe29db3205dfb4b9f580e1b_f1028ddd1cff238ee_d20250402_m013741_c001_v0001130_t0017_u01743557861269") 2025/04/02 01:37:44 DEBUG : B2 bucket rclone-test-popezef3sumu: Purge remote 2025/04/02 01:37:44 INFO : B2 bucket rclone-test-popezef3sumu: cleaning bucket "rclone-test-popezef3sumu" of all files 2025/04/02 01:37:44 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_z8f428d83205dfb4b9f580e1b_f10157b2a42633b3a_d20250402_m013736_c001_v0001116_t0000_u01743557856380") 2025/04/02 01:37:45 DEBUG : B2 bucket rclone-test-pusemej8coca: Purge remote 2025/04/02 01:37:45 INFO : B2 bucket rclone-test-pusemej8coca: cleaning bucket "rclone-test-pusemej8coca" of all files 2025/04/02 01:37:46 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_z8f327d73205dfb4b9f580e1b_f104f8adf4fec167e_d20250402_m013731_c001_v0001105_t0036_u01743557851930") 2025/04/02 01:37:48 DEBUG : B2 bucket rclone-test-sawigid5nira: Purge remote 2025/04/02 01:37:48 INFO : B2 bucket rclone-test-sawigid5nira: cleaning bucket "rclone-test-sawigid5nira" of all files 2025/04/02 01:37:48 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_zaf426d73205dfb4b9f580e1b_f109671f8a59195fe_d20250402_m013727_c001_v0001098_t0001_u01743557847991") 2025/04/02 01:37:49 DEBUG : B2 bucket rclone-test-bologat1sucu: Purge remote 2025/04/02 01:37:49 INFO : B2 bucket rclone-test-bologat1sucu: cleaning bucket "rclone-test-bologat1sucu" of all files 2025/04/02 01:37:49 DEBUG : 測試_Русский___ě_áñ: Deleting (id "4_zef825d63205dfb4b9f580e1b_f112a81a7df43bb55_d20250402_m013724_c001_v0001178_t0014_u01743557844663") 2025/04/02 01:37:50 DEBUG : B2 bucket rclone-test-neteper9dugo: Purge remote 2025/04/02 01:37:50 INFO : B2 bucket rclone-test-neteper9dugo: cleaning bucket "rclone-test-neteper9dugo" of all files 2025/04/02 01:37:51 DEBUG : 測試_Русский___ě_áñ: Deleting (id "4_zff424d83205dfb4b9f580e1b_f110b074ff77cafcb_d20250402_m013720_c001_v0001153_t0000_u01743557840150") 2025/04/02 01:37:52 DEBUG : B2 bucket rclone-test-cadubat8luli: Purge remote 2025/04/02 01:37:52 INFO : B2 bucket rclone-test-cadubat8luli: cleaning bucket "rclone-test-cadubat8luli" of all files 2025/04/02 01:37:53 DEBUG : HELLO, WORLD!: Deleting (id "4_z6fe23d83205dfb4b9f580e1b_f111b31b5ef3edecf_d20250402_m013715_c001_v0001040_t0046_u01743557835831") 2025/04/02 01:37:54 DEBUG : B2 bucket rclone-test-jekusay3vohi: Purge remote 2025/04/02 01:37:54 INFO : B2 bucket rclone-test-jekusay3vohi: cleaning bucket "rclone-test-jekusay3vohi" of all files 2025/04/02 01:37:54 DEBUG : HeLlO, wOrLd!: Deleting (id "4_zdf622d93205dfb4b9f580e1b_f114002d1fa7479fd_d20250402_m013712_c001_v0001037_t0050_u01743557832242") 2025/04/02 01:37:55 DEBUG : B2 bucket rclone-test-cukipim1goxo: Purge remote 2025/04/02 01:37:55 INFO : B2 bucket rclone-test-cukipim1goxo: cleaning bucket "rclone-test-cukipim1goxo" of all files 2025/04/02 01:37:56 DEBUG : HELLO, WORLD!: Deleting (id "4_zef221d63205dfb4b9f580e1b_f11053bfea4f4dd23_d20250402_m013707_c001_v0001117_t0017_u01743557827776") 2025/04/02 01:37:56 DEBUG : B2 bucket rclone-test-rehadid4husi: Purge remote 2025/04/02 01:37:56 INFO : B2 bucket rclone-test-rehadid4husi: cleaning bucket "rclone-test-rehadid4husi" of all files 2025/04/02 01:37:57 DEBUG : hello, world!: Deleting (id "4_z3f720d73205dfb4b9f580e1b_f11787f0289976cc3_d20250402_m013703_c001_v0001172_t0011_u01743557823238") --- PASS: TestApplyTransforms (57.56s) === RUN TestTruncateString --- PASS: TestTruncateString (0.00s) === RUN TestCopyFile run.go:180: Remote "B2 bucket rclone-test-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" 2025/04/02 01:37:59 DEBUG : file1: Need to transfer - File not found at Destination 2025/04/02 01:37:59 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2025/04/02 01:37:59 INFO : file1: Copied (new) to: sub/file2 2025/04/02 01:38:00 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/04/02 01:38:00 DEBUG : file1: Unchanged skipping 2025/04/02 01:38:00 DEBUG : B2 bucket rclone-test-bimamaz9saja: don't need to copy/move sub/file2, it is already at target location --- PASS: TestCopyFile (3.08s) === RUN TestCopyLongFile run.go:180: Remote "B2 bucket rclone-test-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", 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-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" 2025/04/02 01:38:02 DEBUG : Creating backend with remote "TestB2:rclone-test-bimamaz9saja/backup" 2025/04/02 01:38:03 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18) 2025/04/02 01:38:05 DEBUG : dst/file1: sha1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK 2025/04/02 01:38:05 INFO : dst/file1: Copied (server-side copy) 2025/04/02 01:38:05 INFO : dst/file1: Deleted 2025/04/02 01:38:05 DEBUG : dst/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2025/04/02 01:38:05 INFO : dst/file1: Copied (new) --- PASS: TestCopyFileBackupDir (4.79s) === RUN TestCopyFileCompareDest run.go:180: Remote "B2 bucket rclone-test-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" 2025/04/02 01:38:06 DEBUG : Creating backend with remote "TestB2:rclone-test-bimamaz9saja/dst" 2025/04/02 01:38:10 DEBUG : Creating backend with remote "TestB2:rclone-test-bimamaz9saja/CompareDest" 2025/04/02 01:38:11 DEBUG : one: Need to transfer - File not found at Destination 2025/04/02 01:38:15 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2025/04/02 01:38:15 INFO : one: Copied (new) 2025/04/02 01:38:16 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/04/02 01:38:18 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2025/04/02 01:38:18 INFO : one: Copied (replaced existing) 2025/04/02 01:38:19 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/04/02 01:38:20 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/04/02 01:38:20 DEBUG : one: Destination found in --compare-dest, skipping 2025/04/02 01:38:21 DEBUG : two: Need to transfer - File not found at Destination 2025/04/02 01:38:21 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/04/02 01:38:21 DEBUG : two: Destination found in --compare-dest, skipping 2025/04/02 01:38:21 DEBUG : two: Need to transfer - File not found at Destination 2025/04/02 01:38:22 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/04/02 01:38:22 DEBUG : two: Destination found in --compare-dest, skipping 2025/04/02 01:38:22 DEBUG : two: Need to transfer - File not found at Destination 2025/04/02 01:38:22 DEBUG : two: Sizes differ (src 5 vs dst 3) 2025/04/02 01:38:23 DEBUG : two: sha1 = a4be8877ad88f3109a6411985a1f359e10e2d930 OK 2025/04/02 01:38:23 INFO : two: Copied (new) --- PASS: TestCopyFileCompareDest (18.10s) === RUN TestCopyFileCopyDest run.go:180: Remote "B2 bucket rclone-test-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" 2025/04/02 01:38:24 DEBUG : Creating backend with remote "TestB2:rclone-test-bimamaz9saja/dst" 2025/04/02 01:38:27 DEBUG : Creating backend with remote "TestB2:rclone-test-bimamaz9saja/CopyDest" 2025/04/02 01:38:29 DEBUG : one: Need to transfer - File not found at Destination 2025/04/02 01:38:31 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2025/04/02 01:38:31 INFO : one: Copied (new) 2025/04/02 01:38:33 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/04/02 01:38:33 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2025/04/02 01:38:33 INFO : one: Copied (replaced existing) 2025/04/02 01:38:36 DEBUG : Creating backend with remote "TestB2:rclone-test-bimamaz9saja/BackupDir" 2025/04/02 01:38:38 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/04/02 01:38:39 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/04/02 01:38:39 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/04/02 01:38:40 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2025/04/02 01:38:40 INFO : one: Copied (server-side copy) 2025/04/02 01:38:40 INFO : one: Deleted 2025/04/02 01:38:41 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2025/04/02 01:38:41 INFO : one: Copied (server-side copy) 2025/04/02 01:38:41 DEBUG : one: Destination found in --copy-dest, using server-side copy 2025/04/02 01:38:43 DEBUG : two: Need to transfer - File not found at Destination 2025/04/02 01:38:43 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/04/02 01:38:43 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2025/04/02 01:38:43 INFO : two: Copied (server-side copy) 2025/04/02 01:38:43 DEBUG : two: Destination found in --copy-dest, using server-side copy 2025/04/02 01:38:44 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/04/02 01:38:44 DEBUG : two: Unchanged skipping 2025/04/02 01:38:45 DEBUG : three: Need to transfer - File not found at Destination 2025/04/02 01:38:46 DEBUG : three: Sizes differ (src 7 vs dst 5) 2025/04/02 01:38:46 DEBUG : three: Destination not found in --copy-dest 2025/04/02 01:38:47 DEBUG : three: sha1 = 370e613335f3cf2f19b2a2d35a86402f55c659f8 OK 2025/04/02 01:38:47 INFO : three: Copied (new) --- PASS: TestCopyFileCopyDest (24.59s) === RUN TestCopyInplace run.go:180: Remote "B2 bucket rclone-test-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" copy_test.go:370: Partial uploads not supported --- SKIP: TestCopyInplace (0.37s) === RUN TestCopyLongFileName run.go:180: Remote "B2 bucket rclone-test-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", 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-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" 2025/04/02 01:38:51 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination 2025/04/02 01:38:52 DEBUG : TestCopyFileMaxTransfer/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2025/04/02 01:38:52 INFO : TestCopyFileMaxTransfer/file1: Copied (new) 2025/04/02 01:38:53 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination 2025/04/02 01:38:53 ERROR : TestCopyFileMaxTransfer/file2: Failed to copy: Post "https://pod-000-1093-15.backblaze.com/b2api/v1/b2_upload_file/efb2eda310edfb4b9f580e1b/c001_v0001093_t0024": max transfer limit reached as set by --max-transfer 2025/04/02 01:38:53 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination 2025/04/02 01:38:54 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination 2025/04/02 01:38:55 DEBUG : TestCopyFileMaxTransfer/file4: sha1 = f13277898bcb3b3ade7966486d19b129baad200c OK 2025/04/02 01:38:55 INFO : TestCopyFileMaxTransfer/file4: Copied (new) --- PASS: TestCopyFileMaxTransfer (6.57s) === RUN TestDeduplicateInteractive run.go:180: Remote "B2 bucket rclone-test-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateInteractive (0.37s) === RUN TestDeduplicateSkip run.go:180: Remote "B2 bucket rclone-test-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSkip (0.37s) === RUN TestDeduplicateSizeOnly run.go:180: Remote "B2 bucket rclone-test-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", 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-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", 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-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateNewest (0.38s) === RUN TestDeduplicateNewestByHash run.go:180: Remote "B2 bucket rclone-test-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" 2025/04/02 01:39:00 INFO : B2 bucket rclone-test-bimamaz9saja: Looking for duplicate sha1 hashes using newest mode. 2025/04/02 01:39:01 NOTICE: 95cb98262a645896c28c21fc6d5b882f33ded5c5: Found 3 files with duplicate sha1 hashes 2025/04/02 01:39:01 INFO : one: Deleted 2025/04/02 01:39:01 INFO : also/one: Deleted 2025/04/02 01:39:01 NOTICE: 95cb98262a645896c28c21fc6d5b882f33ded5c5: Deleted 2 extra copies --- PASS: TestDeduplicateNewestByHash (3.73s) === RUN TestDeduplicateOldest run.go:180: Remote "B2 bucket rclone-test-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", 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-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateLargest (0.38s) === RUN TestDeduplicateSmallest run.go:180: Remote "B2 bucket rclone-test-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSmallest (0.37s) === RUN TestDeduplicateRename run.go:180: Remote "B2 bucket rclone-test-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateRename (0.38s) === RUN TestMergeDirs run.go:180: Remote "B2 bucket rclone-test-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" dedupe_test.go:256: Can't merge directories --- SKIP: TestMergeDirs (0.37s) === RUN TestListDirSorted run.go:180: Remote "B2 bucket rclone-test-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" 2025/04/02 01:39:08 DEBUG : a.txt: Excluded (Size Filter) 2025/04/02 01:39:08 DEBUG : a.txt: Excluded 2025/04/02 01:39:09 DEBUG : sub dir/hello world: Excluded (Size Filter) 2025/04/02 01:39:09 DEBUG : sub dir/hello world: Excluded 2025/04/02 01:39:09 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2025/04/02 01:39:09 DEBUG : sub dir/hello world2: Excluded 2025/04/02 01:39:09 DEBUG : sub dir/hello world: Excluded (Size Filter) 2025/04/02 01:39:09 DEBUG : sub dir/hello world: Excluded 2025/04/02 01:39:09 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2025/04/02 01:39:09 DEBUG : sub dir/hello world2: Excluded 2025/04/02 01:39:10 DEBUG : sub dir/ignore dir: Excluded 2025/04/02 01:39:10 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSorted (8.22s) === RUN TestListJSON run.go:180: Remote "B2 bucket rclone-test-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", 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/04/02 01:39:15 ERROR : file1: Failed to read hash: hash type not supported === RUN TestListJSON/Metadata --- PASS: TestListJSON (4.01s) --- PASS: TestListJSON/Default (0.19s) --- PASS: TestListJSON/FilesOnly (0.19s) --- PASS: TestListJSON/DirsOnly (0.20s) --- PASS: TestListJSON/Recurse (0.19s) --- PASS: TestListJSON/SubDir (0.20s) --- PASS: TestListJSON/NoModTime (0.19s) --- PASS: TestListJSON/NoMimeType (0.19s) --- PASS: TestListJSON/ShowHash (0.20s) --- PASS: TestListJSON/HashTypes (0.19s) --- PASS: TestListJSON/Metadata (0.19s) === RUN TestStatJSON run.go:180: Remote "B2 bucket rclone-test-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", 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/04/02 01:39:21 DEBUG : Creating backend with remote "TestB2:rclone-test-bimamaz9saja/notfound" --- PASS: TestStatJSON (9.76s) --- PASS: TestStatJSON/Root (0.22s) --- PASS: TestStatJSON/RootFilesOnly (0.00s) --- PASS: TestStatJSON/RootDirsOnly (0.20s) --- PASS: TestStatJSON/Dir (0.77s) --- PASS: TestStatJSON/DirWithTrailingSlash (0.21s) --- PASS: TestStatJSON/File (0.19s) --- PASS: TestStatJSON/NotFound (0.39s) --- PASS: TestStatJSON/DirFilesOnly (0.18s) --- PASS: TestStatJSON/FileFilesOnly (0.19s) --- PASS: TestStatJSON/NotFoundFilesOnly (0.18s) --- PASS: TestStatJSON/DirDirsOnly (0.27s) --- PASS: TestStatJSON/FileDirsOnly (1.15s) --- PASS: TestStatJSON/NotFoundDirsOnly (0.37s) --- PASS: TestStatJSON/RootNotFound (3.02s) === RUN TestMkdir run.go:180: Remote "B2 bucket rclone-test-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" 2025/04/02 01:39:26 INFO : B2 bucket rclone-test-bimamaz9saja: Making directory 2025/04/02 01:39:26 INFO : B2 bucket rclone-test-bimamaz9saja: Making directory --- PASS: TestMkdir (0.94s) === RUN TestLsd run.go:180: Remote "B2 bucket rclone-test-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" --- PASS: TestLsd (2.76s) === RUN TestLs run.go:180: Remote "B2 bucket rclone-test-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" --- PASS: TestLs (2.78s) === RUN TestLsWithFilesFrom run.go:180: Remote "B2 bucket rclone-test-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" 2025/04/02 01:39:34 DEBUG : empty space: Excluded (FilesFrom Filter) 2025/04/02 01:39:34 DEBUG : empty space: Excluded --- PASS: TestLsWithFilesFrom (3.50s) === RUN TestLsLong run.go:180: Remote "B2 bucket rclone-test-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" --- PASS: TestLsLong (3.21s) === RUN TestHashSums run.go:180: Remote "B2 bucket rclone-test-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" === RUN TestHashSums/Sha1 === RUN TestHashSums/Sha1Download --- PASS: TestHashSums (4.14s) --- PASS: TestHashSums/Sha1 (0.35s) --- PASS: TestHashSums/Sha1Download (1.01s) === RUN TestHashSumsWithErrors 2025/04/02 01:39:43 DEBUG : Creating backend with remote ":memory:" 2025/04/02 01:39:43 ERROR : file1: hash unsupported: hash type not supported --- PASS: TestHashSumsWithErrors (0.00s) === RUN TestHashStream 2025/04/02 01:39:43 DEBUG : Creating md5 hash of 0 bytes read from input stream 2025/04/02 01:39:43 DEBUG : Creating md5 hash of 0 bytes read from input stream 2025/04/02 01:39:43 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2025/04/02 01:39:43 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2025/04/02 01:39:43 DEBUG : Creating md5 hash of 12 bytes read from input stream 2025/04/02 01:39:43 DEBUG : Creating md5 hash of 12 bytes read from input stream 2025/04/02 01:39:43 DEBUG : Creating sha1 hash of 12 bytes read from input stream 2025/04/02 01:39:43 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-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" --- PASS: TestCount (4.18s) === RUN TestDelete run.go:180: Remote "B2 bucket rclone-test-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" 2025/04/02 01:39:49 DEBUG : Waiting for deletions to finish 2025/04/02 01:39:49 DEBUG : large: Excluded (Size Filter) 2025/04/02 01:39:50 INFO : small: Deleted 2025/04/02 01:39:50 INFO : medium: Deleted --- PASS: TestDelete (4.04s) === RUN TestMaxDelete run.go:180: Remote "B2 bucket rclone-test-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" 2025/04/02 01:39:53 DEBUG : Waiting for deletions to finish 2025/04/02 01:39:53 ERROR : small: Got fatal error on delete: --max-delete threshold reached 2025/04/02 01:39:54 INFO : medium: Deleted 2025/04/02 01:39:54 INFO : large: Deleted --- PASS: TestMaxDelete (4.53s) === RUN TestMaxDeleteSizeLargeFile run.go:180: Remote "B2 bucket rclone-test-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" 2025/04/02 01:39:58 DEBUG : Waiting for deletions to finish 2025/04/02 01:39:58 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached 2025/04/02 01:39:58 INFO : small: Deleted 2025/04/02 01:39:58 INFO : medium: Deleted --- PASS: TestMaxDeleteSizeLargeFile (4.60s) === RUN TestMaxDeleteSize run.go:180: Remote "B2 bucket rclone-test-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" 2025/04/02 01:40:02 DEBUG : Waiting for deletions to finish 2025/04/02 01:40:03 ERROR : small: Got fatal error on delete: --max-delete-size threshold reached 2025/04/02 01:40:03 INFO : medium: Deleted 2025/04/02 01:40:03 INFO : large: Deleted --- PASS: TestMaxDeleteSize (5.48s) === RUN TestReadFile run.go:180: Remote "B2 bucket rclone-test-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" --- PASS: TestReadFile (2.98s) === RUN TestRetry 2025/04/02 01:40:09 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 1/5 2025/04/02 01:40:09 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 2/5 2025/04/02 01:40:09 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG 2025/04/02 01:40:09 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG 2025/04/02 01:40:09 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG 2025/04/02 01:40:09 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG 2025/04/02 01:40:09 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG --- PASS: TestRetry (0.05s) === RUN TestCat run.go:180: Remote "B2 bucket rclone-test-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" --- PASS: TestCat (5.29s) === RUN TestPurge 2025/04/02 01:40:14 DEBUG : Creating backend with remote "TestB2:rclone-test-jotoxew3bedu" 2025/04/02 01:40:14 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/04/02 01:40:15 DEBUG : Creating backend with remote "/tmp/rclone1357071020" run.go:180: Remote "B2 bucket rclone-test-jotoxew3bedu", Local "Local file system at /tmp/rclone1357071020", Modify Window "1ms" 2025/04/02 01:40:17 INFO : A2: Making directory 2025/04/02 01:40:17 INFO : A1/B2: Making directory 2025/04/02 01:40:17 INFO : A1/B2/C2: Making directory 2025/04/02 01:40:17 INFO : A1/B1/C3: Making directory 2025/04/02 01:40:17 INFO : A3: Making directory 2025/04/02 01:40:17 INFO : A3/B3: Making directory 2025/04/02 01:40:17 INFO : A3/B3/C4: Making directory fstest.go:244: Filtering empty directory "A2" fstest.go:244: Filtering empty directory "A1/B2" fstest.go:244: Filtering empty directory "A1/B2/C2" fstest.go:244: Filtering empty directory "A1/B1/C3" fstest.go:244: Filtering empty directory "A3" fstest.go:244: Filtering empty directory "A3/B3" fstest.go:244: Filtering empty directory "A3/B3/C4" 2025/04/02 01:40:17 INFO : B2 bucket rclone-test-jotoxew3bedu: cleaning bucket "rclone-test-jotoxew3bedu" of all files 2025/04/02 01:40:18 DEBUG : A1/B1/C1/one: Deleting (id "4_zffb2fdc3207dfb4b9f580e1b_f1044b5f1890b274e_d20250402_m014017_c001_v0001182_t0025_u01743558017327") fstest.go:244: Filtering empty directory "A2" fstest.go:244: Filtering empty directory "A1/B2" fstest.go:244: Filtering empty directory "A1/B2/C2" fstest.go:244: Filtering empty directory "A3" fstest.go:244: Filtering empty directory "A3/B3" fstest.go:244: Filtering empty directory "A3/B3/C4" 2025/04/02 01:40:18 INFO : B2 bucket rclone-test-jotoxew3bedu: cleaning bucket "rclone-test-jotoxew3bedu" of all files 2025/04/02 01:40:19 DEBUG : A1/two: Deleting (id "4_zffb2fdc3207dfb4b9f580e1b_f1044b5f1890b2750_d20250402_m014017_c001_v0001182_t0029_u01743558017552") 2025/04/02 01:40:20 DEBUG : B2 bucket rclone-test-jotoxew3bedu: Purge remote 2025/04/02 01:40:20 INFO : B2 bucket rclone-test-jotoxew3bedu: cleaning bucket "rclone-test-jotoxew3bedu" of all files 2025/04/02 01:40:21 NOTICE: purge failed: directory not found --- PASS: TestPurge (6.35s) === RUN TestRmdirsNoLeaveRoot run.go:180: Remote "B2 bucket rclone-test-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" 2025/04/02 01:40:22 INFO : A2: Making directory 2025/04/02 01:40:22 INFO : A1/B2: Making directory 2025/04/02 01:40:22 INFO : A1/B2/C2: Making directory 2025/04/02 01:40:22 INFO : A1/B1/C3: Making directory 2025/04/02 01:40:22 INFO : A3: Making directory 2025/04/02 01:40:22 INFO : A3/B3: Making directory 2025/04/02 01:40:22 INFO : A3/B3/C4: Making directory fstest.go:244: Filtering empty directory "A2" fstest.go:244: Filtering empty directory "A1/B2" fstest.go:244: Filtering empty directory "A1/B2/C2" fstest.go:244: Filtering empty directory "A1/B1/C3" fstest.go:244: Filtering empty directory "A3" fstest.go:244: Filtering empty directory "A3/B3" fstest.go:244: Filtering empty directory "A3/B3/C4" 2025/04/02 01:40:23 DEBUG : removing 1 level 3 directories 2025/04/02 01:40:23 INFO : A3/B3/C4: Removing directory fstest.go:244: Filtering empty directory "A2" fstest.go:244: Filtering empty directory "A1/B2" fstest.go:244: Filtering empty directory "A1/B2/C2" fstest.go:244: Filtering empty directory "A1/B1/C3" fstest.go:244: Filtering empty directory "A3" fstest.go:244: Filtering empty directory "A3/B3" 2025/04/02 01:40:25 DEBUG : removing 1 level 0 directories 2025/04/02 01:40:25 INFO : B2 bucket rclone-test-bimamaz9saja: Removing directory 2025/04/02 01:40:26 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 (5.46s) === RUN TestRmdirsLeaveRoot run.go:180: Remote "B2 bucket rclone-test-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" 2025/04/02 01:40:26 INFO : A1: Making directory 2025/04/02 01:40:26 INFO : A1/B1: Making directory 2025/04/02 01:40:26 INFO : A1/B1/C1: Making directory fstest.go:244: Filtering empty directory "A1" fstest.go:244: Filtering empty directory "A1/B1" fstest.go:244: Filtering empty directory "A1/B1/C1" fstest.go:244: Filtering empty directory "A1" --- PASS: TestRmdirsLeaveRoot (0.95s) === RUN TestRmdirsWithFilter run.go:180: Remote "B2 bucket rclone-test-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" 2025/04/02 01:40:27 INFO : A1: Making directory 2025/04/02 01:40:27 INFO : A1/B1: Making directory 2025/04/02 01:40:27 INFO : A1/B1/C1: Making directory fstest.go:244: Filtering empty directory "A1" fstest.go:244: Filtering empty directory "A1/B1" fstest.go:244: Filtering empty directory "A1/B1/C1" fstest.go:244: Filtering empty directory "A1" --- PASS: TestRmdirsWithFilter (0.94s) === RUN TestCopyURL run.go:180: Remote "B2 bucket rclone-test-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" 2025/04/02 01:40:29 DEBUG : filename.txt: File name found in url 2025/04/02 01:40:30 DEBUG : headerfilename.txt: filename found in Content-Disposition header. --- PASS: TestCopyURL (3.97s) === RUN TestCopyURLToWriter --- PASS: TestCopyURLToWriter (0.00s) === RUN TestMoveFile run.go:180: Remote "B2 bucket rclone-test-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" 2025/04/02 01:40:32 DEBUG : file1: Need to transfer - File not found at Destination 2025/04/02 01:40:32 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2025/04/02 01:40:32 INFO : file1: Copied (new) to: sub/file2 2025/04/02 01:40:32 INFO : file1: Deleted 2025/04/02 01:40:33 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/04/02 01:40:33 DEBUG : file1: Unchanged skipping 2025/04/02 01:40:33 INFO : file1: Deleted 2025/04/02 01:40:33 DEBUG : B2 bucket rclone-test-bimamaz9saja: don't need to copy/move sub/file2, it is already at target location --- PASS: TestMoveFile (1.87s) === RUN TestMoveFileWithIgnoreExisting run.go:180: Remote "B2 bucket rclone-test-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" 2025/04/02 01:40:34 DEBUG : file1: Need to transfer - File not found at Destination 2025/04/02 01:40:34 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2025/04/02 01:40:34 INFO : file1: Copied (new) 2025/04/02 01:40:34 INFO : file1: Deleted 2025/04/02 01:40:35 DEBUG : file1: Destination exists, skipping 2025/04/02 01:40:35 DEBUG : file1: Not removing source file as destination file exists and --ignore-existing is set --- PASS: TestMoveFileWithIgnoreExisting (1.80s) === RUN TestCaseInsensitiveMoveFile run.go:180: Remote "B2 bucket rclone-test-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFile (0.37s) === RUN TestCaseInsensitiveMoveFileDryRun run.go:180: Remote "B2 bucket rclone-test-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFileDryRun (0.38s) === RUN TestMoveFileBackupDir run.go:180: Remote "B2 bucket rclone-test-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" 2025/04/02 01:40:37 DEBUG : Creating backend with remote "TestB2:rclone-test-bimamaz9saja/backup" 2025/04/02 01:40:38 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18) 2025/04/02 01:40:40 DEBUG : dst/file1: sha1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK 2025/04/02 01:40:40 INFO : dst/file1: Copied (server-side copy) 2025/04/02 01:40:40 INFO : dst/file1: Deleted 2025/04/02 01:40:40 DEBUG : dst/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2025/04/02 01:40:40 INFO : dst/file1: Copied (new) 2025/04/02 01:40:40 INFO : dst/file1: Deleted --- PASS: TestMoveFileBackupDir (5.02s) === 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-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" 2025/04/02 01:40:43 INFO : A1/B2: Making directory 2025/04/02 01:40:43 INFO : A1/B1/C3: Making directory fstest.go:244: Filtering empty directory "A1/B2" fstest.go:244: Filtering empty directory "A1/B1/C3" 2025/04/02 01:40:45 DEBUG : A1/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2025/04/02 01:40:45 INFO : A1/B1/C1/four: Copied (server-side copy) to: A2/B1/C1/four 2025/04/02 01:40:45 DEBUG : A1/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2025/04/02 01:40:45 INFO : A1/one: Copied (server-side copy) to: A2/one 2025/04/02 01:40:45 INFO : A1/B1/C1/four: Deleted 2025/04/02 01:40:45 INFO : A1/one: Deleted 2025/04/02 01:40:45 DEBUG : A1/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2025/04/02 01:40:45 INFO : A1/B1/C2/five: Copied (server-side copy) to: A2/B1/C2/five 2025/04/02 01:40:45 DEBUG : A1/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2025/04/02 01:40:45 INFO : A1/B1/three: Copied (server-side copy) to: A2/B1/three 2025/04/02 01:40:46 INFO : A1/B1/three: Deleted 2025/04/02 01:40:46 INFO : A1/B1/C2/five: Deleted 2025/04/02 01:40:46 DEBUG : A1/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2025/04/02 01:40:46 INFO : A1/two: Copied (server-side copy) to: A2/two 2025/04/02 01:40:46 INFO : A1/two: Deleted fstest.go:244: Filtering empty directory "A2/B2" fstest.go:244: Filtering empty directory "A2/B1/C3" 2025/04/02 01:40:47 DEBUG : A2/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2025/04/02 01:40:47 INFO : A2/B1/three: Copied (server-side copy) to: A3/B1/three 2025/04/02 01:40:47 DEBUG : A2/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2025/04/02 01:40:47 INFO : A2/B1/C2/five: Copied (server-side copy) to: A3/B1/C2/five 2025/04/02 01:40:47 DEBUG : A2/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2025/04/02 01:40:47 INFO : A2/B1/C1/four: Copied (server-side copy) to: A3/B1/C1/four 2025/04/02 01:40:47 INFO : A2/B1/three: Deleted 2025/04/02 01:40:47 INFO : A2/B1/C2/five: Deleted 2025/04/02 01:40:47 DEBUG : A2/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2025/04/02 01:40:47 INFO : A2/one: Copied (server-side copy) to: A3/one 2025/04/02 01:40:47 INFO : A2/B1/C1/four: Deleted 2025/04/02 01:40:47 DEBUG : A2/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2025/04/02 01:40:47 INFO : A2/two: Copied (server-side copy) to: A3/two 2025/04/02 01:40:48 INFO : A2/one: Deleted 2025/04/02 01:40:48 INFO : A2/two: Deleted fstest.go:244: Filtering empty directory "A3/B2" fstest.go:244: Filtering empty directory "A3/B1/C3" 2025/04/02 01:40:48 INFO : B2 bucket rclone-test-bimamaz9saja: Can't DirMove - falling back to file moves: can't move directory - incompatible remotes 2025/04/02 01:40:49 DEBUG : A3/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2025/04/02 01:40:49 INFO : A3/one: Copied (server-side copy) to: A4/one 2025/04/02 01:40:49 DEBUG : A3/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2025/04/02 01:40:49 INFO : A3/two: Copied (server-side copy) to: A4/two 2025/04/02 01:40:49 DEBUG : A3/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2025/04/02 01:40:49 INFO : A3/B1/three: Copied (server-side copy) to: A4/B1/three 2025/04/02 01:40:49 INFO : A3/one: Deleted 2025/04/02 01:40:49 DEBUG : A3/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2025/04/02 01:40:49 INFO : A3/B1/C1/four: Copied (server-side copy) to: A4/B1/C1/four 2025/04/02 01:40:49 INFO : A3/two: Deleted 2025/04/02 01:40:49 INFO : A3/B1/C1/four: Deleted 2025/04/02 01:40:49 DEBUG : A3/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2025/04/02 01:40:49 INFO : A3/B1/C2/five: Copied (server-side copy) to: A4/B1/C2/five 2025/04/02 01:40:49 INFO : A3/B1/three: Deleted 2025/04/02 01:40:50 INFO : A3/B1/C2/five: Deleted fstest.go:244: Filtering empty directory "A4/B2" fstest.go:244: Filtering empty directory "A4/B1/C3" --- PASS: TestDirMove (10.30s) === RUN TestGetFsInfo run.go:180: Remote "B2 bucket rclone-test-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" --- PASS: TestGetFsInfo (0.58s) === RUN TestRcat === RUN TestRcat/withChecksum=false,ignoreChecksum=false run.go:180: Remote "B2 bucket rclone-test-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" 2025/04/02 01:40:52 DEBUG : B2 bucket rclone-test-bimamaz9saja: File to upload is small (34 bytes), uploading instead of streaming 2025/04/02 01:40:53 DEBUG : no_checksum_small_file_from_pipe: sha1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK 2025/04/02 01:40:53 DEBUG : no_checksum_small_file_from_pipe: Size and sha1 of src and dst objects identical 2025/04/02 01:40:53 DEBUG : no_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2025/04/02 01:40:55 DEBUG : no_checksum_big_file_from_pipe: sha1 = d0080f73aa8b494570ad63df1beb25c01ea9eea2 OK 2025/04/02 01:40:55 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-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" 2025/04/02 01:40:57 DEBUG : B2 bucket rclone-test-bimamaz9saja: File to upload is small (34 bytes), uploading instead of streaming 2025/04/02 01:40:57 DEBUG : with_checksum_small_file_from_pipe: sha1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK 2025/04/02 01:40:57 DEBUG : with_checksum_small_file_from_pipe: Size and sha1 of src and dst objects identical 2025/04/02 01:40:57 DEBUG : with_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2025/04/02 01:40:58 DEBUG : with_checksum_big_file_from_pipe: sha1 = d0080f73aa8b494570ad63df1beb25c01ea9eea2 OK 2025/04/02 01:40:58 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-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" 2025/04/02 01:41:00 DEBUG : B2 bucket rclone-test-bimamaz9saja: File to upload is small (34 bytes), uploading instead of streaming 2025/04/02 01:41:01 DEBUG : ignore_checksum_small_file_from_pipe: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/04/02 01:41:01 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2025/04/02 01:41:02 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-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" 2025/04/02 01:41:04 DEBUG : B2 bucket rclone-test-bimamaz9saja: File to upload is small (34 bytes), uploading instead of streaming 2025/04/02 01:41:04 DEBUG : ignore_checksum_small_file_from_pipe: Src hash empty - aborting Dst hash check 2025/04/02 01:41:04 DEBUG : ignore_checksum_small_file_from_pipe: Size of src and dst objects identical 2025/04/02 01:41:04 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2025/04/02 01:41:06 DEBUG : ignore_checksum_big_file_from_pipe: Src hash empty - aborting Dst hash check 2025/04/02 01:41:06 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical --- PASS: TestRcat (14.94s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (4.32s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (3.24s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (3.82s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (3.57s) === RUN TestRcatMetadata run.go:180: Remote "B2 bucket rclone-test-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" operations_test.go:1532: Skipping as destination doesn't support user metadata --- SKIP: TestRcatMetadata (0.43s) === RUN TestRcatSize run.go:180: Remote "B2 bucket rclone-test-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" 2025/04/02 01:41:08 DEBUG : B2 bucket rclone-test-bimamaz9saja: File to upload is small (60 bytes), uploading instead of streaming 2025/04/02 01:41:09 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2025/04/02 01:41:09 DEBUG : potato2: Size and sha1 of src and dst objects identical --- PASS: TestRcatSize (2.84s) === RUN TestRcatSizeMetadata run.go:180: Remote "B2 bucket rclone-test-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" operations_test.go:1613: Skipping as destination doesn't support user metadata --- SKIP: TestRcatSizeMetadata (0.57s) === RUN TestTouchDir run.go:180: Remote "B2 bucket rclone-test-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" 2025/04/02 01:41:13 DEBUG : B2 bucket rclone-test-bimamaz9saja: Touching "sub dir/potato3" 2025/04/02 01:41:13 DEBUG : B2 bucket rclone-test-bimamaz9saja: Touching "empty space" 2025/04/02 01:41:13 DEBUG : B2 bucket rclone-test-bimamaz9saja: Touching "potato2" --- PASS: TestTouchDir (6.76s) === RUN TestMkdirMetadata run.go:180: Remote "B2 bucket rclone-test-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" operations_test.go:1703: Skipping test as remote does not support MkdirMetadata --- SKIP: TestMkdirMetadata (0.78s) === RUN TestMkdirModTime run.go:180: Remote "B2 bucket rclone-test-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" operations_test.go:1722: Skipping test as remote does not support DirSetModTime or MkdirMetadata --- SKIP: TestMkdirModTime (0.72s) === RUN TestCopyDirMetadata run.go:180: Remote "B2 bucket rclone-test-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" operations_test.go:1743: Skipping test as remote does not support WriteDirMetadata or MkdirMetadata --- SKIP: TestCopyDirMetadata (0.81s) === RUN TestSetDirModTime run.go:180: Remote "B2 bucket rclone-test-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" operations_test.go:1779: Skipping test as remote does not support DirSetModTime or WriteDirSetModTime --- SKIP: TestSetDirModTime (0.83s) === RUN TestDirsEqual run.go:180: Remote "B2 bucket rclone-test-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" operations_test.go:1823: Skipping test as remote does not support WriteDirMetadata or MkdirMetadata --- SKIP: TestDirsEqual (0.83s) === RUN TestRemoveExisting run.go:180: Remote "B2 bucket rclone-test-bimamaz9saja", Local "Local file system at /tmp/rclone170065548", Modify Window "1ms" operations_test.go:1892: Skipping as remote can't Move --- SKIP: TestRemoveExisting (0.74s) === 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/04/02 01:41:22 DEBUG : B2 bucket rclone-test-bimamaz9saja: Purge remote 2025/04/02 01:41:22 INFO : B2 bucket rclone-test-bimamaz9saja: cleaning bucket "rclone-test-bimamaz9saja" of all files 2025/04/02 01:41:24 DEBUG : A1/B1/C1/four: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f4077d795fee62549_d20250402_m014045_c001_v7007000_t0000_u01743558045489") 2025/04/02 01:41:24 DEBUG : A1/B1/C1/four: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cbbd3_d20250402_m014043_c001_v0001093_t0000_u01743558043040") 2025/04/02 01:41:24 DEBUG : A1/B1/C1/one: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f4077d795fee611a9_d20250402_m014025_c001_v7007000_t0000_u01743558025134") 2025/04/02 01:41:24 DEBUG : A1/B1/C1/one: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cbb61_d20250402_m014021_c001_v0001093_t0033_u01743558021598") 2025/04/02 01:41:24 DEBUG : A1/B1/C2/five: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f416051d139c225f0_d20250402_m014045_c001_v7007000_t0000_u01743558045930") 2025/04/02 01:41:24 DEBUG : A1/B1/C2/five: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cbbd7_d20250402_m014043_c001_v0001093_t0032_u01743558043589") 2025/04/02 01:41:24 DEBUG : A1/B1/three: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f41839514bbf7d34c_d20250402_m014045_c001_v7007000_t0000_u01743558045912") 2025/04/02 01:41:24 DEBUG : A1/B1/three: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cbbcf_d20250402_m014042_c001_v0001093_t0008_u01743558042661") 2025/04/02 01:41:24 DEBUG : A1/one: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f41814cf373b06b5b_d20250402_m014045_c001_v7007000_t0000_u01743558045627") 2025/04/02 01:41:24 DEBUG : A1/one: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cbbc9_d20250402_m014041_c001_v0001093_t0058_u01743558041896") 2025/04/02 01:41:24 DEBUG : A1/two: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f403016e1548df23d_d20250402_m014046_c001_v7007000_t0000_u01743558046428") 2025/04/02 01:41:24 DEBUG : A1/two: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cbbcd_d20250402_m014042_c001_v0001093_t0000_u01743558042201") 2025/04/02 01:41:25 DEBUG : A1/two: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f4077d795fee611ab_d20250402_m014025_c001_v7007000_t0000_u01743558025611") 2025/04/02 01:41:25 DEBUG : A1/two: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cbb67_d20250402_m014022_c001_v0001093_t0009_u01743558022306") 2025/04/02 01:41:25 DEBUG : A2/B1/C1/four: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f403016e1548df23e_d20250402_m014047_c001_v7007000_t0000_u01743558047737") 2025/04/02 01:41:25 DEBUG : A2/B1/C1/four: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f115a986a6ee93dd5_d20250402_m014044_c001_v0001146_t0019_u01743558044969") 2025/04/02 01:41:25 DEBUG : A2/B1/C2/five: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f416051d139c2277c_d20250402_m014047_c001_v7007000_t0000_u01743558047603") 2025/04/02 01:41:25 DEBUG : A2/B1/C2/five: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f111995d3106fd9dc_d20250402_m014045_c001_v0001036_t0030_u01743558045481") 2025/04/02 01:41:25 DEBUG : A2/B1/three: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f41839514bbf7d34e_d20250402_m014047_c001_v7007000_t0000_u01743558047505") 2025/04/02 01:41:25 DEBUG : A2/B1/three: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f10305fc3971d1a63_d20250402_m014045_c001_v0001103_t0045_u01743558045526") 2025/04/02 01:41:25 DEBUG : A2/one: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f411a5cb406801b06_d20250402_m014047_c001_v7007000_t0000_u01743558047920") 2025/04/02 01:41:25 DEBUG : A2/one: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f119195f5eb6d462a_d20250402_m014045_c001_v0001165_t0018_u01743558045286") 2025/04/02 01:41:25 DEBUG : A2/two: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f4089356119f75457_d20250402_m014048_c001_v7007000_t0000_u01743558048121") 2025/04/02 01:41:25 DEBUG : A2/two: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f11537ac6c2651d1c_d20250402_m014045_c001_v0001115_t0042_u01743558045517") 2025/04/02 01:41:25 DEBUG : A3/B1/C1/four: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f41839514bbf7d356_d20250402_m014049_c001_v7007000_t0000_u01743558049583") 2025/04/02 01:41:25 DEBUG : A3/B1/C1/four: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f11611bc59f0f1e29_d20250402_m014047_c001_v0001131_t0048_u01743558047309") 2025/04/02 01:41:25 DEBUG : A3/B1/C2/five: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f416051d139c2277e_d20250402_m014049_c001_v7007000_t0000_u01743558049938") 2025/04/02 01:41:25 DEBUG : A3/B1/C2/five: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f10057f26002e6c50_d20250402_m014047_c001_v0001150_t0023_u01743558047321") 2025/04/02 01:41:25 DEBUG : A3/B1/three: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f4089356119f75458_d20250402_m014049_c001_v7007000_t0000_u01743558049720") 2025/04/02 01:41:26 DEBUG : A3/B1/three: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1166755a4c434cdf_d20250402_m014047_c001_v0001183_t0008_u01743558047305") 2025/04/02 01:41:26 DEBUG : A3/one: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f411a5cb406801b07_d20250402_m014049_c001_v7007000_t0000_u01743558049397") 2025/04/02 01:41:26 DEBUG : A3/one: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f103c9c9de4003e87_d20250402_m014047_c001_v0001113_t0043_u01743558047469") 2025/04/02 01:41:26 DEBUG : A3/two: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f403016e1548df242_d20250402_m014049_c001_v7007000_t0000_u01743558049467") 2025/04/02 01:41:26 DEBUG : A3/two: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f11404da3a234597c_d20250402_m014047_c001_v0001040_t0047_u01743558047556") 2025/04/02 01:41:26 DEBUG : A4/B1/C1/four: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f416051d139c2277f_d20250402_m014050_c001_v7007000_t0000_u01743558050648") 2025/04/02 01:41:26 DEBUG : A4/B1/C1/four: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f113b47bb726e5d99_d20250402_m014049_c001_v0001181_t0015_u01743558049364") 2025/04/02 01:41:26 DEBUG : A4/B1/C2/five: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f416051d139c22780_d20250402_m014050_c001_v7007000_t0000_u01743558050913") 2025/04/02 01:41:26 DEBUG : A4/B1/C2/five: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1002d24d7125d507_d20250402_m014049_c001_v0001117_t0041_u01743558049305") 2025/04/02 01:41:26 DEBUG : A4/B1/three: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f416051d139c22781_d20250402_m014051_c001_v7007000_t0000_u01743558051206") 2025/04/02 01:41:26 DEBUG : A4/B1/three: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1095b19c1d9af3b6_d20250402_m014049_c001_v0001179_t0034_u01743558049326") 2025/04/02 01:41:26 DEBUG : A4/one: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f416051d139c22782_d20250402_m014051_c001_v7007000_t0000_u01743558051504") 2025/04/02 01:41:26 DEBUG : A4/one: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f114d7cb9597919fb_d20250402_m014049_c001_v0001184_t0025_u01743558049194") 2025/04/02 01:41:26 DEBUG : A4/two: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f416051d139c22783_d20250402_m014051_c001_v7007000_t0000_u01743558051773") 2025/04/02 01:41:26 DEBUG : A4/two: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f103edbb94540b6e9_d20250402_m014049_c001_v0001181_t0011_u01743558049211") 2025/04/02 01:41:26 DEBUG : BackupDir/one: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f4154fa61e7cb9756_d20250402_m013848_c001_v7007000_t0000_u01743557928067") 2025/04/02 01:41:27 DEBUG : BackupDir/one: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f11915238205d4a9c_d20250402_m013840_c001_v0001184_t0052_u01743557920114") 2025/04/02 01:41:27 DEBUG : CompareDest/one: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f409e2a983c19fd58_d20250402_m013824_c001_v7007000_t0000_u01743557904030") 2025/04/02 01:41:27 DEBUG : CompareDest/one: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cb922_d20250402_m013819_c001_v0001093_t0005_u01743557899173") 2025/04/02 01:41:27 DEBUG : CompareDest/two: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f409e2a983c19fd59_d20250402_m013824_c001_v7007000_t0000_u01743557904216") 2025/04/02 01:41:27 DEBUG : CompareDest/two: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cb92e_d20250402_m013820_c001_v0001093_t0033_u01743557900311") 2025/04/02 01:41:27 DEBUG : CopyDest/one: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f4154fa61e7cb9757_d20250402_m013848_c001_v7007000_t0000_u01743557928251") 2025/04/02 01:41:27 DEBUG : CopyDest/one: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cb978_d20250402_m013835_c001_v0001093_t0034_u01743557915771") 2025/04/02 01:41:27 DEBUG : CopyDest/three: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f4154fa61e7cb9758_d20250402_m013848_c001_v7007000_t0000_u01743557928438") 2025/04/02 01:41:27 DEBUG : CopyDest/three: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cb9a8_d20250402_m013844_c001_v0001093_t0026_u01743557924667") 2025/04/02 01:41:27 DEBUG : CopyDest/two: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f4154fa61e7cb975a_d20250402_m013848_c001_v7007000_t0000_u01743557928627") 2025/04/02 01:41:27 DEBUG : CopyDest/two: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cb99a_d20250402_m013841_c001_v0001093_t0054_u01743557921917") 2025/04/02 01:41:27 DEBUG : ReadFile: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f41727ba3ec604f47_d20250402_m014008_c001_v7007000_t0000_u01743558008445") 2025/04/02 01:41:27 DEBUG : ReadFile: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cbb27_d20250402_m014006_c001_v0001093_t0057_u01743558006611") 2025/04/02 01:41:27 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f4154fa61e7cb9764_d20250402_m013856_c001_v7007000_t0000_u01743557936299") 2025/04/02 01:41:27 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cb9cb_d20250402_m013852_c001_v0001093_t0031_u01743557932451") 2025/04/02 01:41:27 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f4154fa61e7cb9765_d20250402_m013856_c001_v7007000_t0000_u01743557936483") 2025/04/02 01:41:27 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cb9d9_d20250402_m013855_c001_v0001093_t0008_u01743557935370") 2025/04/02 01:41:27 DEBUG : a.txt: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f4154fa61e7cb9777_d20250402_m013911_c001_v7007000_t0000_u01743557951052") 2025/04/02 01:41:28 DEBUG : a.txt: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cba05_d20250402_m013904_c001_v0001093_t0033_u01743557944416") 2025/04/02 01:41:28 DEBUG : also/one: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f4154fa61e7cb976b_d20250402_m013901_c001_v7007000_t0000_u01743557941333") 2025/04/02 01:41:28 DEBUG : also/one: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cb9ed_d20250402_m013859_c001_v0001093_t0011_u01743557939253") 2025/04/02 01:41:28 DEBUG : another: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f4154fa61e7cb976c_d20250402_m013901_c001_v7007000_t0000_u01743557941895") 2025/04/02 01:41:28 DEBUG : another: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cb9f1_d20250402_m013859_c001_v0001093_t0012_u01743557939644") 2025/04/02 01:41:28 DEBUG : backup/dst/file1: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f4077d795fee62543_d20250402_m014041_c001_v7007000_t0000_u01743558041328") 2025/04/02 01:41:28 DEBUG : backup/dst/file1: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f115ffadc5c736246_d20250402_m014040_c001_v0001182_t0023_u01743558040108") 2025/04/02 01:41:28 DEBUG : backup/dst/file1: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f400e3be44231201b_d20250402_m013806_c001_v7007000_t0000_u01743557886317") 2025/04/02 01:41:28 DEBUG : backup/dst/file1: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f100f011cafa04539_d20250402_m013805_c001_v0001157_t0039_u01743557885034") 2025/04/02 01:41:28 DEBUG : data/banana: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f415d0c953d913464_d20250402_m013700_c001_v7007000_t0000_u01743557820316") 2025/04/02 01:41:28 DEBUG : data/banana: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cb7a2_d20250402_m013657_c001_v0001093_t0045_u01743557817841") 2025/04/02 01:41:28 DEBUG : data/banana: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cb75c_d20250402_m013647_c001_v0001093_t0056_u01743557807337") 2025/04/02 01:41:28 DEBUG : data/potato: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f415d0c953d913465_d20250402_m013700_c001_v7007000_t0000_u01743557820503") 2025/04/02 01:41:28 DEBUG : data/potato: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cb7a6_d20250402_m013658_c001_v0001093_t0024_u01743557818192") 2025/04/02 01:41:28 DEBUG : data/potato: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cb76e_d20250402_m013650_c001_v0001093_t0055_u01743557810551") 2025/04/02 01:41:28 DEBUG : dst/file1: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f4077d795fee62544_d20250402_m014041_c001_v7007000_t0000_u01743558041513") 2025/04/02 01:41:28 DEBUG : dst/file1: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cbbc5_d20250402_m014040_c001_v0001093_t0037_u01743558040516") 2025/04/02 01:41:29 DEBUG : dst/file1: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f4077d795fee62542_d20250402_m014040_c001_v7007000_t0000_u01743558040306") 2025/04/02 01:41:29 DEBUG : dst/file1: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cbbb3_d20250402_m014036_c001_v0001093_t0036_u01743558036878") 2025/04/02 01:41:29 DEBUG : dst/file1: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f400e3be44231201c_d20250402_m013806_c001_v7007000_t0000_u01743557886502") 2025/04/02 01:41:29 DEBUG : dst/file1: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cb8e6_d20250402_m013805_c001_v0001093_t0013_u01743557885633") 2025/04/02 01:41:29 DEBUG : dst/file1: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f400e3be442312019_d20250402_m013805_c001_v7007000_t0000_u01743557885442") 2025/04/02 01:41:29 DEBUG : dst/file1: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cb8d4_d20250402_m013802_c001_v0001093_t0009_u01743557882085") 2025/04/02 01:41:29 DEBUG : dst/one: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f4154fa61e7cb975b_d20250402_m013848_c001_v7007000_t0000_u01743557928816") 2025/04/02 01:41:29 DEBUG : dst/one: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f107b4f95d30ac7be_d20250402_m013840_c001_v0001181_t0054_u01743557920913") 2025/04/02 01:41:29 DEBUG : dst/one: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f4131805ad35b56d4_d20250402_m013840_c001_v7007000_t0000_u01743557920710") 2025/04/02 01:41:29 DEBUG : dst/one: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cb970_d20250402_m013834_c001_v0001093_t0031_u01743557914540") 2025/04/02 01:41:29 DEBUG : dst/one: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f11094d7201dce989_d20250402_m013833_c001_v0001101_t0021_u01743557913622") 2025/04/02 01:41:29 DEBUG : dst/one: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f11094d7201dce987_d20250402_m013831_c001_v0001101_t0024_u01743557911245") 2025/04/02 01:41:29 DEBUG : dst/one: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f409e2a983c19fd5a_d20250402_m013824_c001_v7007000_t0000_u01743557904402") 2025/04/02 01:41:29 DEBUG : dst/one: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cb91e_d20250402_m013818_c001_v0001093_t0024_u01743557898755") 2025/04/02 01:41:29 DEBUG : dst/one: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1122b2d4fdb2d2c0_d20250402_m013817_c001_v0001106_t0023_u01743557897549") 2025/04/02 01:41:29 DEBUG : dst/one: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1122b2d4fdb2d2b4_d20250402_m013814_c001_v0001106_t0001_u01743557894857") 2025/04/02 01:41:29 DEBUG : dst/three: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f4154fa61e7cb975c_d20250402_m013849_c001_v7007000_t0000_u01743557929001") 2025/04/02 01:41:30 DEBUG : dst/three: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f11094d7201dce9b7_d20250402_m013847_c001_v0001101_t0048_u01743557927082") 2025/04/02 01:41:30 DEBUG : dst/two: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f4154fa61e7cb975d_d20250402_m013849_c001_v7007000_t0000_u01743557929188") 2025/04/02 01:41:30 DEBUG : dst/two: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f108bc9439771797c_d20250402_m013843_c001_v0001120_t0055_u01743557923766") 2025/04/02 01:41:30 DEBUG : dst/two: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f409e2a983c19fd5b_d20250402_m013824_c001_v7007000_t0000_u01743557904598") 2025/04/02 01:41:30 DEBUG : dst/two: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1122b2d4fdb2d2d8_d20250402_m013822_c001_v0001106_t0015_u01743557902916") 2025/04/02 01:41:30 DEBUG : empty space: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f411a5cb4068024b1_d20250402_m014117_c001_v7007000_t0000_u01743558077149") 2025/04/02 01:41:30 DEBUG : empty space: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f10589de143ae2894_d20250402_m014114_c001_v0001109_t0054_u01743558074681") 2025/04/02 01:41:30 DEBUG : empty space: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cbc5f_d20250402_m014112_c001_v0001093_t0029_u01743558072138") 2025/04/02 01:41:30 DEBUG : empty space: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f41727ba3ec604b41_d20250402_m013946_c001_v7007000_t0000_u01743557986706") 2025/04/02 01:41:30 DEBUG : empty space: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cbabf_d20250402_m013944_c001_v0001093_t0044_u01743557984380") 2025/04/02 01:41:30 DEBUG : empty space: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f41727ba3ec604b3f_d20250402_m013942_c001_v7007000_t0000_u01743557982687") 2025/04/02 01:41:30 DEBUG : empty space: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cbab1_d20250402_m013940_c001_v0001093_t0035_u01743557980007") 2025/04/02 01:41:30 DEBUG : empty space: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f41727ba3ec604b3a_d20250402_m013938_c001_v7007000_t0000_u01743557978612") 2025/04/02 01:41:30 DEBUG : empty space: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cbaa1_d20250402_m013936_c001_v0001093_t0021_u01743557976809") 2025/04/02 01:41:30 DEBUG : empty space: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f41727ba3ec604b35_d20250402_m013935_c001_v7007000_t0000_u01743557975381") 2025/04/02 01:41:30 DEBUG : empty space: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cba8b_d20250402_m013933_c001_v0001093_t0008_u01743557973350") 2025/04/02 01:41:30 DEBUG : empty space: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f41727ba3ec604b32_d20250402_m013931_c001_v7007000_t0000_u01743557971995") 2025/04/02 01:41:31 DEBUG : empty space: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cba7b_d20250402_m013930_c001_v0001093_t0036_u01743557970555") 2025/04/02 01:41:31 DEBUG : empty space: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f415d0c953d913456_d20250402_m013637_c001_v7007000_t0000_u01743557797306") 2025/04/02 01:41:31 DEBUG : empty space: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f112626a06569f6e8_d20250402_m013634_c001_v0001101_t0026_u01743557794474") 2025/04/02 01:41:31 DEBUG : empty space: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f415d0c953d913452_d20250402_m013632_c001_v7007000_t0000_u01743557792662") 2025/04/02 01:41:31 DEBUG : empty space: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f112626a06569f6ca_d20250402_m013628_c001_v0001101_t0034_u01743557788512") 2025/04/02 01:41:31 DEBUG : empty space: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f415d0c953d91344a_d20250402_m013625_c001_v7007000_t0000_u01743557785826") 2025/04/02 01:41:31 DEBUG : empty space: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f112626a06569f6b6_d20250402_m013623_c001_v0001101_t0007_u01743557783227") 2025/04/02 01:41:31 DEBUG : file1: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f4077d795fee61d6e_d20250402_m014035_c001_v7007000_t0000_u01743558035739") 2025/04/02 01:41:31 DEBUG : file1: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cbba1_d20250402_m014034_c001_v0001093_t0010_u01743558034527") 2025/04/02 01:41:31 DEBUG : file1: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f4077d795fee61d67_d20250402_m014031_c001_v7007000_t0000_u01743558031516") 2025/04/02 01:41:31 DEBUG : file1: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f4156bc3aed2cbb89_d20250402_m014029_c001_v0001093_t0024_u01743558029109") 2025/04/02 01:41:31 DEBUG : file1: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f41727ba3ec605334_d20250402_m014014_c001_v7007000_t0000_u01743558014020") 2025/04/02 01:41:31 DEBUG : file1: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cbb31_d20250402_m014009_c001_v0001093_t0046_u01743558009547") 2025/04/02 01:41:31 DEBUG : file1: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f41727ba3ec604b2a_d20250402_m013925_c001_v7007000_t0000_u01743557965466") 2025/04/02 01:41:31 DEBUG : file1: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cba3f_d20250402_m013916_c001_v0001093_t0008_u01743557956677") 2025/04/02 01:41:31 DEBUG : file1: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f4154fa61e7cb9781_d20250402_m013915_c001_v7007000_t0000_u01743557955894") 2025/04/02 01:41:31 DEBUG : file1: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cba31_d20250402_m013912_c001_v0001093_t0056_u01743557952680") 2025/04/02 01:41:32 DEBUG : file2: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f4077d795fee61d69_d20250402_m014031_c001_v7007000_t0000_u01743558031700") 2025/04/02 01:41:32 DEBUG : file2: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f4156bc3aed2cbb91_d20250402_m014030_c001_v0001093_t0024_u01743558030959") 2025/04/02 01:41:32 DEBUG : file2: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f41727ba3ec605335_d20250402_m014014_c001_v7007000_t0000_u01743558014268") 2025/04/02 01:41:32 DEBUG : file2: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cbb33_d20250402_m014010_c001_v0001093_t0012_u01743558010057") 2025/04/02 01:41:32 DEBUG : filename.txt: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f4077d795fee61d6b_d20250402_m014031_c001_v7007000_t0000_u01743558031885") 2025/04/02 01:41:32 DEBUG : filename.txt: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cbb8b_d20250402_m014029_c001_v0001093_t0000_u01743558029763") 2025/04/02 01:41:32 DEBUG : headerfilename.txt: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f4077d795fee61d6c_d20250402_m014032_c001_v7007000_t0000_u01743558032069") 2025/04/02 01:41:32 DEBUG : headerfilename.txt: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cbb8d_d20250402_m014030_c001_v0001093_t0019_u01743558030218") 2025/04/02 01:41:32 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f416051d139c22791_d20250402_m014106_c001_v7007000_t0000_u01743558066970") 2025/04/02 01:41:32 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cbc33_d20250402_m014105_c001_v0001093_t0013_u01743558065051") 2025/04/02 01:41:32 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f416051d139c2278e_d20250402_m014103_c001_v7007000_t0000_u01743558063446") 2025/04/02 01:41:32 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cbc23_d20250402_m014101_c001_v0001093_t0025_u01743558061400") 2025/04/02 01:41:32 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f416051d139c22792_d20250402_m014107_c001_v7007000_t0000_u01743558067306") 2025/04/02 01:41:32 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cbc2f_d20250402_m014104_c001_v0001093_t0030_u01743558064465") 2025/04/02 01:41:32 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f416051d139c2278f_d20250402_m014103_c001_v7007000_t0000_u01743558063702") 2025/04/02 01:41:32 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cbc21_d20250402_m014100_c001_v0001093_t0052_u01743558060686") 2025/04/02 01:41:32 DEBUG : large: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f41727ba3ec604f42_d20250402_m014003_c001_v7007000_t0000_u01743558003593") 2025/04/02 01:41:33 DEBUG : large: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cbb15_d20250402_m014002_c001_v0001093_t0001_u01743558002099") 2025/04/02 01:41:33 DEBUG : large: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f41727ba3ec604b56_d20250402_m013959_c001_v7007000_t0000_u01743557999776") 2025/04/02 01:41:33 DEBUG : large: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cbb01_d20250402_m013957_c001_v0001093_t0054_u01743557997417") 2025/04/02 01:41:33 DEBUG : large: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f41727ba3ec604b50_d20250402_m013954_c001_v7007000_t0000_u01743557994258") 2025/04/02 01:41:33 DEBUG : large: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cbae9_d20250402_m013953_c001_v0001093_t0055_u01743557993095") 2025/04/02 01:41:33 DEBUG : large: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f41727ba3ec604b4b_d20250402_m013951_c001_v7007000_t0000_u01743557991308") 2025/04/02 01:41:33 DEBUG : large: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cbad3_d20250402_m013948_c001_v0001093_t0023_u01743557988803") 2025/04/02 01:41:33 DEBUG : medium: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f415d0c953d9138af_d20250402_m014003_c001_v7007000_t0000_u01743558003425") 2025/04/02 01:41:33 DEBUG : medium: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cbb13_d20250402_m014001_c001_v0001093_t0055_u01743558001691") 2025/04/02 01:41:33 DEBUG : medium: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f41727ba3ec604b55_d20250402_m013958_c001_v7007000_t0000_u01743557998759") 2025/04/02 01:41:33 DEBUG : medium: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cbaf9_d20250402_m013957_c001_v0001093_t0008_u01743557997036") 2025/04/02 01:41:33 DEBUG : medium: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f415d0c953d9134c2_d20250402_m013954_c001_v7007000_t0000_u01743557994080") 2025/04/02 01:41:33 DEBUG : medium: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cbae3_d20250402_m013952_c001_v0001093_t0029_u01743557992382") 2025/04/02 01:41:33 DEBUG : medium: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f41727ba3ec604b49_d20250402_m013950_c001_v7007000_t0000_u01743557990277") 2025/04/02 01:41:33 DEBUG : medium: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cbad1_d20250402_m013948_c001_v0001093_t0047_u01743557988252") 2025/04/02 01:41:33 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f416051d139c22787_d20250402_m014056_c001_v7007000_t0000_u01743558056354") 2025/04/02 01:41:33 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cbc09_d20250402_m014054_c001_v0001093_t0051_u01743558054156") 2025/04/02 01:41:33 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f416051d139c22788_d20250402_m014056_c001_v7007000_t0000_u01743558056648") 2025/04/02 01:41:34 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cbc07_d20250402_m014053_c001_v0001093_t0015_u01743558053686") 2025/04/02 01:41:34 DEBUG : not-one: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f4154fa61e7cb976d_d20250402_m013902_c001_v7007000_t0000_u01743557942081") 2025/04/02 01:41:34 DEBUG : not-one: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cb9f5_d20250402_m013900_c001_v0001093_t0029_u01743557940101") 2025/04/02 01:41:34 DEBUG : one: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f4154fa61e7cb976a_d20250402_m013901_c001_v7007000_t0000_u01743557941146") 2025/04/02 01:41:34 DEBUG : one: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cb9eb_d20250402_m013858_c001_v0001093_t0056_u01743557938866") 2025/04/02 01:41:34 DEBUG : potato1: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f416051d139c22795_d20250402_m014110_c001_v7007000_t0000_u01743558070149") 2025/04/02 01:41:34 DEBUG : potato1: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cbc43_d20250402_m014108_c001_v0001093_t0050_u01743558068331") 2025/04/02 01:41:34 DEBUG : potato2: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f411a5cb4068024b3_d20250402_m014117_c001_v7007000_t0000_u01743558077477") 2025/04/02 01:41:34 DEBUG : potato2: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f102333e1918ea4bf_d20250402_m014115_c001_v0001109_t0050_u01743558075841") 2025/04/02 01:41:34 DEBUG : potato2: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cbc59_d20250402_m014111_c001_v0001093_t0035_u01743558071653") 2025/04/02 01:41:34 DEBUG : potato2: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f416051d139c22796_d20250402_m014110_c001_v7007000_t0000_u01743558070497") 2025/04/02 01:41:34 DEBUG : potato2: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cbc49_d20250402_m014109_c001_v0001093_t0022_u01743558069056") 2025/04/02 01:41:34 DEBUG : potato2: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f41727ba3ec604b43_d20250402_m013947_c001_v7007000_t0000_u01743557987011") 2025/04/02 01:41:34 DEBUG : potato2: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cbabd_d20250402_m013943_c001_v0001093_t0033_u01743557983696") 2025/04/02 01:41:34 DEBUG : potato2: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f41727ba3ec604b40_d20250402_m013943_c001_v7007000_t0000_u01743557983080") 2025/04/02 01:41:34 DEBUG : potato2: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cbaaf_d20250402_m013939_c001_v0001093_t0020_u01743557979574") 2025/04/02 01:41:34 DEBUG : potato2: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f41727ba3ec604b3b_d20250402_m013938_c001_v7007000_t0000_u01743557978972") 2025/04/02 01:41:35 DEBUG : potato2: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cba9d_d20250402_m013936_c001_v0001093_t0035_u01743557976339") 2025/04/02 01:41:35 DEBUG : potato2: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f41727ba3ec604b37_d20250402_m013935_c001_v7007000_t0000_u01743557975720") 2025/04/02 01:41:35 DEBUG : potato2: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cba87_d20250402_m013932_c001_v0001093_t0056_u01743557972891") 2025/04/02 01:41:35 DEBUG : potato2: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f41727ba3ec604b33_d20250402_m013932_c001_v7007000_t0000_u01743557972302") 2025/04/02 01:41:35 DEBUG : potato2: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cba79_d20250402_m013930_c001_v0001093_t0024_u01743557970088") 2025/04/02 01:41:35 DEBUG : potato2: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f415d0c953d913457_d20250402_m013637_c001_v7007000_t0000_u01743557797505") 2025/04/02 01:41:35 DEBUG : potato2: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f112626a06569f6ec_d20250402_m013635_c001_v0001101_t0050_u01743557795314") 2025/04/02 01:41:35 DEBUG : potato2: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f415d0c953d913453_d20250402_m013632_c001_v7007000_t0000_u01743557792846") 2025/04/02 01:41:35 DEBUG : potato2: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f112626a06569f6ce_d20250402_m013629_c001_v0001101_t0003_u01743557789396") 2025/04/02 01:41:35 DEBUG : potato2: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f415d0c953d91344b_d20250402_m013626_c001_v7007000_t0000_u01743557786011") 2025/04/02 01:41:35 DEBUG : potato2: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f112626a06569f6ba_d20250402_m013623_c001_v0001101_t0001_u01743557783927") 2025/04/02 01:41:35 DEBUG : remotepotato: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f415d0c953d913458_d20250402_m013637_c001_v7007000_t0000_u01743557797690") 2025/04/02 01:41:35 DEBUG : remotepotato: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f112626a06569f6ee_d20250402_m013636_c001_v0001101_t0053_u01743557796248") 2025/04/02 01:41:35 DEBUG : remotepotato: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f415d0c953d913454_d20250402_m013633_c001_v7007000_t0000_u01743557793031") 2025/04/02 01:41:35 DEBUG : remotepotato: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f112626a06569f6d6_d20250402_m013631_c001_v0001101_t0028_u01743557791089") 2025/04/02 01:41:35 DEBUG : remotepotato: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f415d0c953d91344c_d20250402_m013626_c001_v7007000_t0000_u01743557786204") 2025/04/02 01:41:35 DEBUG : remotepotato: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f112626a06569f6bc_d20250402_m013624_c001_v0001101_t0022_u01743557784777") 2025/04/02 01:41:36 DEBUG : rutabaga: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f415d0c953d913459_d20250402_m013637_c001_v7007000_t0000_u01743557797876") 2025/04/02 01:41:36 DEBUG : rutabaga: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f112626a06569f6e2_d20250402_m013633_c001_v0001101_t0025_u01743557793587") 2025/04/02 01:41:36 DEBUG : rutabaga: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f415d0c953d913455_d20250402_m013633_c001_v7007000_t0000_u01743557793217") 2025/04/02 01:41:36 DEBUG : rutabaga: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f112626a06569f6c2_d20250402_m013626_c001_v0001101_t0014_u01743557786780") 2025/04/02 01:41:36 DEBUG : rutabaga: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f415d0c953d91344d_d20250402_m013626_c001_v7007000_t0000_u01743557786389") 2025/04/02 01:41:36 DEBUG : rutabaga: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f112626a06569f6b2_d20250402_m013622_c001_v0001101_t0058_u01743557782263") 2025/04/02 01:41:36 DEBUG : small: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f41727ba3ec604f44_d20250402_m014005_c001_v7007000_t0000_u01743558005284") 2025/04/02 01:41:36 DEBUG : small: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cbb0f_d20250402_m014001_c001_v0001093_t0029_u01743558001064") 2025/04/02 01:41:36 DEBUG : small: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f415d0c953d9134c5_d20250402_m013958_c001_v7007000_t0000_u01743557998615") 2025/04/02 01:41:36 DEBUG : small: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cbaf5_d20250402_m013956_c001_v0001093_t0054_u01743557996441") 2025/04/02 01:41:36 DEBUG : small: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f41727ba3ec604b53_d20250402_m013955_c001_v7007000_t0000_u01743557995228") 2025/04/02 01:41:36 DEBUG : small: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cbae1_d20250402_m013951_c001_v0001093_t0034_u01743557991905") 2025/04/02 01:41:36 DEBUG : small: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f41727ba3ec604b47_d20250402_m013949_c001_v7007000_t0000_u01743557989940") 2025/04/02 01:41:36 DEBUG : small: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cbacf_d20250402_m013947_c001_v0001093_t0013_u01743557987863") 2025/04/02 01:41:36 DEBUG : sub dir/hello world: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f41727ba3ec604b2f_d20250402_m013929_c001_v7007000_t0000_u01743557969514") 2025/04/02 01:41:36 DEBUG : sub dir/hello world: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cba6b_d20250402_m013927_c001_v0001093_t0035_u01743557967849") 2025/04/02 01:41:36 DEBUG : sub dir/hello world: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f4154fa61e7cb9779_d20250402_m013911_c001_v7007000_t0000_u01743557951236") 2025/04/02 01:41:37 DEBUG : sub dir/hello world: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cba0d_d20250402_m013905_c001_v0001093_t0020_u01743557945615") 2025/04/02 01:41:37 DEBUG : sub dir/hello world2: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f4154fa61e7cb977a_d20250402_m013911_c001_v7007000_t0000_u01743557951423") 2025/04/02 01:41:37 DEBUG : sub dir/hello world2: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cba11_d20250402_m013906_c001_v0001093_t0032_u01743557946147") 2025/04/02 01:41:37 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f4154fa61e7cb977b_d20250402_m013911_c001_v7007000_t0000_u01743557951609") 2025/04/02 01:41:37 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cba15_d20250402_m013906_c001_v0001093_t0020_u01743557946665") 2025/04/02 01:41:37 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f4154fa61e7cb977c_d20250402_m013911_c001_v7007000_t0000_u01743557951793") 2025/04/02 01:41:37 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cba1d_d20250402_m013907_c001_v0001093_t0015_u01743557947374") 2025/04/02 01:41:37 DEBUG : sub dir/potato3: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f411a5cb4068024b4_d20250402_m014117_c001_v7007000_t0000_u01743558077782") 2025/04/02 01:41:37 DEBUG : sub dir/potato3: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f10617e4574477468_d20250402_m014114_c001_v0001178_t0025_u01743558074329") 2025/04/02 01:41:37 DEBUG : sub dir/potato3: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cbc61_d20250402_m014112_c001_v0001093_t0049_u01743558072707") 2025/04/02 01:41:37 DEBUG : sub dir/potato3: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f41727ba3ec604b44_d20250402_m013947_c001_v7007000_t0000_u01743557987303") 2025/04/02 01:41:37 DEBUG : sub dir/potato3: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cbac3_d20250402_m013944_c001_v0001093_t0000_u01743557984893") 2025/04/02 01:41:37 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f4154fa61e7cb977d_d20250402_m013911_c001_v7007000_t0000_u01743557951984") 2025/04/02 01:41:37 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cba1f_d20250402_m013907_c001_v0001093_t0007_u01743557947935") 2025/04/02 01:41:37 DEBUG : sub/file2: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f4077d795fee61d6d_d20250402_m014033_c001_v7007000_t0000_u01743558033944") 2025/04/02 01:41:37 DEBUG : sub/file2: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cbb97_d20250402_m014032_c001_v0001093_t0007_u01743558032668") 2025/04/02 01:41:38 DEBUG : sub/file2: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f41727ba3ec604b2b_d20250402_m013925_c001_v7007000_t0000_u01743557965810") 2025/04/02 01:41:38 DEBUG : sub/file2: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cba41_d20250402_m013917_c001_v0001093_t0022_u01743557957127") 2025/04/02 01:41:38 DEBUG : sub/file2: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f4154fa61e7cb9782_d20250402_m013916_c001_v7007000_t0000_u01743557956126") 2025/04/02 01:41:38 DEBUG : sub/file2: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cba35_d20250402_m013913_c001_v0001093_t0032_u01743557953255") 2025/04/02 01:41:38 DEBUG : sub/file2: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f400e3be442312013_d20250402_m013801_c001_v7007000_t0000_u01743557881338") 2025/04/02 01:41:38 DEBUG : sub/file2: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cb8c8_d20250402_m013759_c001_v0001093_t0041_u01743557879572") 2025/04/02 01:41:38 DEBUG : test-multithread-abort: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f415d0c953d913446_d20250402_m013621_c001_v7007000_t0000_u01743557781308") 2025/04/02 01:41:38 DEBUG : test-multithread-abort: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f112626a06569f632_d20250402_m013543_c001_v0001101_t0045_u01743557743561") 2025/04/02 01:41:38 DEBUG : test-multithread-copy-false-201326591-2: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f403016e1548de432_d20250402_m013110_c001_v7007000_t0000_u01743557470321") 2025/04/02 01:41:38 DEBUG : test-multithread-copy-false-201326591-2: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f112626a06569f2c9_d20250402_m013025_c001_v0001101_t0019_u01743557425597") 2025/04/02 01:41:38 DEBUG : test-multithread-copy-false-201326592-2: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f402137e355481e8c_d20250402_m013159_c001_v7007000_t0000_u01743557519396") 2025/04/02 01:41:38 DEBUG : test-multithread-copy-false-201326592-2: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f112626a06569f33e_d20250402_m013113_c001_v0001101_t0016_u01743557473206") 2025/04/02 01:41:38 DEBUG : test-multithread-copy-false-201326593-2: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f419b182e6915d5fd_d20250402_m013313_c001_v7007000_t0000_u01743557593516") 2025/04/02 01:41:38 DEBUG : test-multithread-copy-false-201326593-2: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f112626a06569f3ad_d20250402_m013202_c001_v0001101_t0041_u01743557522368") 2025/04/02 01:41:38 DEBUG : test-multithread-copy-true-201326591-2: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f409e2a983c19fd09_d20250402_m013353_c001_v7007000_t0000_u01743557633479") 2025/04/02 01:41:38 DEBUG : test-multithread-copy-true-201326591-2: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f204ca036d9a8d833_d20250402_m013332_c001_v0001041_t0014_u01743557612858") 2025/04/02 01:41:38 DEBUG : test-multithread-copy-true-201326592-2: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f408bd287d0c22ab7_d20250402_m013432_c001_v7007000_t0000_u01743557672938") 2025/04/02 01:41:39 DEBUG : test-multithread-copy-true-201326592-2: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f219b182e6915d647_d20250402_m013411_c001_v0001184_t0053_u01743557651837") 2025/04/02 01:41:39 DEBUG : test-multithread-copy-true-201326593-2: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f410bed6b496ce68d_d20250402_m013542_c001_v7007000_t0000_u01743557742201") 2025/04/02 01:41:39 DEBUG : test-multithread-copy-true-201326593-2: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f216caf4237860e83_d20250402_m013451_c001_v0001181_t0039_u01743557691706") 2025/04/02 01:41:39 DEBUG : test.sum: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f415d0c953d913466_d20250402_m013700_c001_v7007000_t0000_u01743557820703") 2025/04/02 01:41:39 DEBUG : test.sum: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cb7a8_d20250402_m013658_c001_v0001093_t0042_u01743557818544") 2025/04/02 01:41:39 DEBUG : test.sum: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cb79a_d20250402_m013656_c001_v0001093_t0055_u01743557816559") 2025/04/02 01:41:39 DEBUG : test.sum: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cb78e_d20250402_m013655_c001_v0001093_t0044_u01743557815173") 2025/04/02 01:41:39 DEBUG : test.sum: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cb786_d20250402_m013653_c001_v0001093_t0037_u01743557813818") 2025/04/02 01:41:39 DEBUG : test.sum: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cb778_d20250402_m013652_c001_v0001093_t0023_u01743557812264") 2025/04/02 01:41:39 DEBUG : test.sum: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cb772_d20250402_m013650_c001_v0001093_t0033_u01743557810944") 2025/04/02 01:41:39 DEBUG : test.sum: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cb760_d20250402_m013647_c001_v0001093_t0007_u01743557807650") 2025/04/02 01:41:39 DEBUG : test.sum: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f415d0c953d91345a_d20250402_m013640_c001_v7007000_t0000_u01743557800931") 2025/04/02 01:41:39 DEBUG : test.sum: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f112626a06569f6fa_d20250402_m013639_c001_v0001101_t0000_u01743557799459") 2025/04/02 01:41:39 DEBUG : test.sum: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f112626a06569f6f6_d20250402_m013638_c001_v0001101_t0028_u01743557798296") 2025/04/02 01:41:39 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f416051d139c2278a_d20250402_m014059_c001_v7007000_t0000_u01743558059642") 2025/04/02 01:41:39 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cbc15_d20250402_m014057_c001_v0001093_t0035_u01743558057938") 2025/04/02 01:41:39 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f416051d139c2278b_d20250402_m014059_c001_v7007000_t0000_u01743558059896") 2025/04/02 01:41:39 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cbc13_d20250402_m014057_c001_v0001093_t0038_u01743558057438") 2025/04/02 01:41:40 DEBUG : zend.txt: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f4154fa61e7cb977e_d20250402_m013912_c001_v7007000_t0000_u01743557952169") 2025/04/02 01:41:40 DEBUG : zend.txt: Deleting (id "4_zefb2eda310edfb4b9f580e1b_f1156bc3aed2cba0b_d20250402_m013904_c001_v0001093_t0051_u01743557944895") "./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose" - Finished OK in 11m22.045333148s (try 1/5)