"./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose" - Starting (try 1/5) 2025/07/10 01:29:15 DEBUG : Creating backend with remote "TestB2:rclone-test-jasibur1wagu" 2025/07/10 01:29:15 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/07/10 01:29:16 DEBUG : Creating backend with remote "/tmp/rclone1985956025" === 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-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" 2025/07/10 01:29:17 DEBUG : chunksize-probe: Cancelling large file upload === RUN TestMultithreadCopy/upload=false,size=201326591,streams=2 2025/07/10 01:29:44 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2025/07/10 01:29:44 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: write buffer set to 131072 2025/07/10 01:29:44 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2025/07/10 01:29:44 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2025/07/10 01:29:44 DEBUG : test-multithread-copy-false-201326591-2: Starting multi-thread copy with 3 chunks of size 64Mi with 3 parallel streams 2025/07/10 01:29:44 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 3/3 (134217728-201326591) size 64.000Mi starting 2025/07/10 01:29:44 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 1/3 (0-67108864) size 64Mi starting 2025/07/10 01:29:44 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 2/3 (67108864-134217728) size 64Mi starting 2025/07/10 01:29:45 DEBUG : test-multithread-copy-false-201326591-2: writing chunk 2 2025/07/10 01:29:45 DEBUG : test-multithread-copy-false-201326591-2: writing chunk 0 2025/07/10 01:29:45 DEBUG : test-multithread-copy-false-201326591-2: writing chunk 1 2025/07/10 01:29:50 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 3/3 (134217728-201326591) size 64.000Mi finished 2025/07/10 01:29:50 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 1/3 (0-67108864) size 64Mi finished 2025/07/10 01:29:50 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 2/3 (67108864-134217728) size 64Mi finished 2025/07/10 01:29:50 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/07/10 01:30:29 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2025/07/10 01:30:29 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: write buffer set to 131072 2025/07/10 01:30:29 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2025/07/10 01:30:29 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2025/07/10 01:30:29 DEBUG : test-multithread-copy-false-201326592-2: Starting multi-thread copy with 3 chunks of size 64Mi with 3 parallel streams 2025/07/10 01:30:29 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 3/3 (134217728-201326592) size 64Mi starting 2025/07/10 01:30:29 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 1/3 (0-67108864) size 64Mi starting 2025/07/10 01:30:29 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 2/3 (67108864-134217728) size 64Mi starting 2025/07/10 01:30:29 DEBUG : test-multithread-copy-false-201326592-2: writing chunk 2 2025/07/10 01:30:29 DEBUG : test-multithread-copy-false-201326592-2: writing chunk 0 2025/07/10 01:30:29 DEBUG : test-multithread-copy-false-201326592-2: writing chunk 1 2025/07/10 01:30:34 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 3/3 (134217728-201326592) size 64Mi finished 2025/07/10 01:30:34 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 1/3 (0-67108864) size 64Mi finished 2025/07/10 01:30:34 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 2/3 (67108864-134217728) size 64Mi finished 2025/07/10 01:30:34 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/07/10 01:31:10 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2025/07/10 01:31:10 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: write buffer set to 131072 2025/07/10 01:31:10 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2025/07/10 01:31:10 DEBUG : test-multithread-copy-false-201326593-2: Starting multi-thread copy with 4 chunks of size 64Mi with 4 parallel streams 2025/07/10 01:31:10 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 4/4 (201326592-201326593) size 1 starting 2025/07/10 01:31:10 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 1/4 (0-67108864) size 64Mi starting 2025/07/10 01:31:10 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 2/4 (67108864-134217728) size 64Mi starting 2025/07/10 01:31:10 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 3/4 (134217728-201326592) size 64Mi starting 2025/07/10 01:31:10 DEBUG : test-multithread-copy-false-201326593-2: writing chunk 3 2025/07/10 01:31:10 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 4/4 (201326592-201326593) size 1 finished 2025/07/10 01:31:10 DEBUG : test-multithread-copy-false-201326593-2: writing chunk 0 2025/07/10 01:31:10 DEBUG : test-multithread-copy-false-201326593-2: writing chunk 1 2025/07/10 01:31:10 DEBUG : test-multithread-copy-false-201326593-2: writing chunk 2 2025/07/10 01:31:15 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 1/4 (0-67108864) size 64Mi finished 2025/07/10 01:31:15 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 2/4 (67108864-134217728) size 64Mi finished 2025/07/10 01:31:15 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 3/4 (134217728-201326592) size 64Mi finished 2025/07/10 01:31:15 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/07/10 01:31:46 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: disabling buffering because source is local disk 2025/07/10 01:31:47 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2025/07/10 01:31:47 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: number of streams 4 was bigger than number of chunks 2 2025/07/10 01:31:47 DEBUG : test-multithread-copy-true-201326591-2: Starting multi-thread copy with 2 chunks of size 96Mi with 2 parallel streams 2025/07/10 01:31:47 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: chunk 2/2 (100663296-201326591) size 96.000Mi starting 2025/07/10 01:31:47 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: chunk 1/2 (0-100663296) size 96Mi starting 2025/07/10 01:31:47 DEBUG : test-multithread-copy-true-201326591-2: Sending chunk 1 length 100663295 2025/07/10 01:31:47 DEBUG : test-multithread-copy-true-201326591-2: Sending chunk 0 length 100663296 2025/07/10 01:31:55 DEBUG : test-multithread-copy-true-201326591-2: Done sending chunk 1 2025/07/10 01:31:55 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: chunk 2/2 (100663296-201326591) size 96.000Mi finished 2025/07/10 01:31:55 DEBUG : test-multithread-copy-true-201326591-2: Done sending chunk 0 2025/07/10 01:31:55 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: chunk 1/2 (0-100663296) size 96Mi finished 2025/07/10 01:31:55 DEBUG : test-multithread-copy-true-201326591-2: Finishing large file upload with 2 parts 2025/07/10 01:31:56 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/07/10 01:32:26 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: disabling buffering because source is local disk 2025/07/10 01:32:28 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2025/07/10 01:32:28 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: number of streams 4 was bigger than number of chunks 2 2025/07/10 01:32:28 DEBUG : test-multithread-copy-true-201326592-2: Starting multi-thread copy with 2 chunks of size 96Mi with 2 parallel streams 2025/07/10 01:32:28 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: chunk 2/2 (100663296-201326592) size 96Mi starting 2025/07/10 01:32:28 DEBUG : test-multithread-copy-true-201326592-2: Sending chunk 1 length 100663296 2025/07/10 01:32:28 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: chunk 1/2 (0-100663296) size 96Mi starting 2025/07/10 01:32:28 DEBUG : test-multithread-copy-true-201326592-2: Sending chunk 0 length 100663296 2025/07/10 01:32:35 DEBUG : test-multithread-copy-true-201326592-2: Done sending chunk 1 2025/07/10 01:32:35 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: chunk 2/2 (100663296-201326592) size 96Mi finished 2025/07/10 01:32:36 DEBUG : test-multithread-copy-true-201326592-2: Done sending chunk 0 2025/07/10 01:32:36 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: chunk 1/2 (0-100663296) size 96Mi finished 2025/07/10 01:32:36 DEBUG : test-multithread-copy-true-201326592-2: Finishing large file upload with 2 parts 2025/07/10 01:32:36 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/07/10 01:33:08 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: disabling buffering because source is local disk 2025/07/10 01:33:10 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2025/07/10 01:33:10 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2025/07/10 01:33:10 DEBUG : test-multithread-copy-true-201326593-2: Starting multi-thread copy with 3 chunks of size 96Mi with 3 parallel streams 2025/07/10 01:33:10 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 3/3 (201326592-201326593) size 1 starting 2025/07/10 01:33:10 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 1/3 (0-100663296) size 96Mi starting 2025/07/10 01:33:10 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 2/3 (100663296-201326592) size 96Mi starting 2025/07/10 01:33:10 DEBUG : test-multithread-copy-true-201326593-2: Sending chunk 2 length 1 2025/07/10 01:33:10 DEBUG : test-multithread-copy-true-201326593-2: Sending chunk 1 length 100663296 2025/07/10 01:33:10 DEBUG : test-multithread-copy-true-201326593-2: Sending chunk 0 length 100663296 2025/07/10 01:33:10 DEBUG : test-multithread-copy-true-201326593-2: Done sending chunk 2 2025/07/10 01:33:10 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 3/3 (201326592-201326593) size 1 finished 2025/07/10 01:33:19 DEBUG : test-multithread-copy-true-201326593-2: Done sending chunk 0 2025/07/10 01:33:19 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 1/3 (0-100663296) size 96Mi finished 2025/07/10 01:33:20 DEBUG : test-multithread-copy-true-201326593-2: Done sending chunk 1 2025/07/10 01:33:20 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 2/3 (100663296-201326592) size 96Mi finished 2025/07/10 01:33:20 DEBUG : test-multithread-copy-true-201326593-2: Finishing large file upload with 3 parts 2025/07/10 01:33:21 DEBUG : test-multithread-copy-true-201326593-2: Finished multi-thread copy with 3 parts of size 96Mi --- PASS: TestMultithreadCopy (258.08s) --- PASS: TestMultithreadCopy/upload=false,size=201326591,streams=2 (44.80s) --- PASS: TestMultithreadCopy/upload=false,size=201326592,streams=2 (43.63s) --- PASS: TestMultithreadCopy/upload=false,size=201326593,streams=2 (41.20s) --- PASS: TestMultithreadCopy/upload=true,size=201326591,streams=2 (40.55s) --- PASS: TestMultithreadCopy/upload=true,size=201326592,streams=2 (41.53s) --- PASS: TestMultithreadCopy/upload=true,size=201326593,streams=2 (44.85s) === RUN TestMultithreadCopyAbort run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" 2025/07/10 01:33:34 DEBUG : chunksize-probe: Cancelling large file upload 2025/07/10 01:33:55 DEBUG : test-multithread-abort: multi-thread copy: disabling buffering because source is local disk 2025/07/10 01:33:56 DEBUG : test-multithread-abort: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 1 2025/07/10 01:33:56 DEBUG : test-multithread-abort: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2025/07/10 01:33:56 DEBUG : test-multithread-abort: Starting multi-thread copy with 3 chunks of size 96Mi with 3 parallel streams 2025/07/10 01:33:56 DEBUG : test-multithread-abort: multi-thread copy: chunk 3/3 (201326592-201326593) size 1 starting 2025/07/10 01:33:56 DEBUG : test-multithread-abort: multi-thread copy: chunk 1/3 (0-100663296) size 96Mi starting 2025/07/10 01:33:56 DEBUG : Open with options = [RangeOption(0,100663295)] 2025/07/10 01:33:56 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2025/07/10 01:33:56 DEBUG : test-multithread-abort: multi-thread copy: chunk 2/3 (100663296-201326592) size 96Mi starting 2025/07/10 01:33:56 DEBUG : Open with options = [RangeOption(100663296,201326591)] 2025/07/10 01:33:56 DEBUG : test-multithread-abort: Sending chunk 0 length 100663296 2025/07/10 01:33:56 DEBUG : test-multithread-abort: Sending chunk 1 length 100663296 2025/07/10 01:34:04 DEBUG : test-multithread-abort: Done sending chunk 0 2025/07/10 01:34:04 DEBUG : test-multithread-abort: multi-thread copy: chunk 1/3 (0-100663296) size 96Mi finished 2025/07/10 01:34:04 DEBUG : test-multithread-abort: Done sending chunk 1 2025/07/10 01:34:04 DEBUG : test-multithread-abort: multi-thread copy: chunk 2/3 (100663296-201326592) size 96Mi finished 2025/07/10 01:34:04 DEBUG : Returning error reader 2025/07/10 01:34:04 DEBUG : test-multithread-abort: Sending chunk 2 length 1 2025/07/10 01:34:04 DEBUG : BOOM: simulated read failure 2025/07/10 01:34:04 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 0/10: BOOM: simulated read failure 2025/07/10 01:34:04 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2025/07/10 01:34:05 DEBUG : Returning error reader 2025/07/10 01:34:05 DEBUG : BOOM: simulated read failure 2025/07/10 01:34:05 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 1/10: BOOM: simulated read failure 2025/07/10 01:34:05 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2025/07/10 01:34:06 DEBUG : Returning error reader 2025/07/10 01:34:06 DEBUG : BOOM: simulated read failure 2025/07/10 01:34:06 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 2/10: BOOM: simulated read failure 2025/07/10 01:34:06 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2025/07/10 01:34:07 DEBUG : Returning error reader 2025/07/10 01:34:07 DEBUG : BOOM: simulated read failure 2025/07/10 01:34:07 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 3/10: BOOM: simulated read failure 2025/07/10 01:34:07 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2025/07/10 01:34:08 DEBUG : Returning error reader 2025/07/10 01:34:08 DEBUG : BOOM: simulated read failure 2025/07/10 01:34:08 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 4/10: BOOM: simulated read failure 2025/07/10 01:34:08 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2025/07/10 01:34:09 DEBUG : Returning error reader 2025/07/10 01:34:09 DEBUG : BOOM: simulated read failure 2025/07/10 01:34:09 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 5/10: BOOM: simulated read failure 2025/07/10 01:34:09 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2025/07/10 01:34:10 DEBUG : Returning error reader 2025/07/10 01:34:10 DEBUG : BOOM: simulated read failure 2025/07/10 01:34:10 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 6/10: BOOM: simulated read failure 2025/07/10 01:34:10 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2025/07/10 01:34:11 DEBUG : Returning error reader 2025/07/10 01:34:11 DEBUG : BOOM: simulated read failure 2025/07/10 01:34:11 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 7/10: BOOM: simulated read failure 2025/07/10 01:34:11 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2025/07/10 01:34:12 DEBUG : Returning error reader 2025/07/10 01:34:12 DEBUG : BOOM: simulated read failure 2025/07/10 01:34:12 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 8/10: BOOM: simulated read failure 2025/07/10 01:34:12 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2025/07/10 01:34:13 DEBUG : Returning error reader 2025/07/10 01:34:13 DEBUG : BOOM: simulated read failure 2025/07/10 01:34:13 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 9/10: BOOM: simulated read failure 2025/07/10 01:34:13 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2025/07/10 01:34:14 DEBUG : Returning error reader 2025/07/10 01:34:14 DEBUG : BOOM: simulated read failure 2025/07/10 01:34:14 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 10/10: BOOM: simulated read failure 2025/07/10 01:34:14 DEBUG : test-multithread-abort: Reopen failed after offset 0 bytes read: failed to reopen: too many retries 2025/07/10 01:34:14 DEBUG : test-multithread-abort: Error sending chunk 2 (retry=false): Post "https://pod-000-1183-15.backblaze.com/b2api/v1/b2_upload_part/4_z7f028d03f03dfb1b9f780e1b_f2024a9f405223253_d20250710_m013356_c001_v0001183_t0059_u01752111236526/0012": BOOM: simulated read failure: &url.Error{Op:"Post", URL:"https://pod-000-1183-15.backblaze.com/b2api/v1/b2_upload_part/4_z7f028d03f03dfb1b9f780e1b_f2024a9f405223253_d20250710_m013356_c001_v0001183_t0059_u01752111236526/0012", Err:(*errors.errorString)(0xc000842920)} 2025/07/10 01:34:14 DEBUG : test-multithread-abort: Error sending chunk 2: Post "https://pod-000-1183-15.backblaze.com/b2api/v1/b2_upload_part/4_z7f028d03f03dfb1b9f780e1b_f2024a9f405223253_d20250710_m013356_c001_v0001183_t0059_u01752111236526/0012": BOOM: simulated read failure 2025/07/10 01:34:14 DEBUG : test-multithread-abort: multi-thread copy: chunk 3/3 failed: multi-thread copy: failed to write chunk: Post "https://pod-000-1183-15.backblaze.com/b2api/v1/b2_upload_part/4_z7f028d03f03dfb1b9f780e1b_f2024a9f405223253_d20250710_m013356_c001_v0001183_t0059_u01752111236526/0012": BOOM: simulated read failure 2025/07/10 01:34:14 DEBUG : test-multithread-abort: multi-thread copy: cancelling transfer on exit 2025/07/10 01:34:14 DEBUG : test-multithread-abort: Cancelling large file upload --- PASS: TestMultithreadCopyAbort (42.03s) === RUN TestSizeDiffers --- PASS: TestSizeDiffers (0.00s) === RUN TestReOpen === RUN TestReOpen/Normal === RUN TestReOpen/Normal/Basics 2025/07/10 01:34:16 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/ErrorAtStart === RUN TestReOpen/Normal/WithErrors 2025/07/10 01:34:16 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2025/07/10 01:34:16 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2025/07/10 01:34:16 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/Normal/TooManyErrors 2025/07/10 01:34:16 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2025/07/10 01:34:16 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2025/07/10 01:34:16 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2025/07/10 01:34:16 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/Normal/Seek 2025/07/10 01:34:16 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/07/10 01:34:16 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/07/10 01:34:16 DEBUG : potato: Seek from 5 to 2 === RUN TestReOpen/Normal/AccountRead === RUN TestReOpen/Normal/AccountReadDelay 2025/07/10 01:34:16 DEBUG : potato: Seek from 10 to 0 2025/07/10 01:34:16 DEBUG : potato: Seek from 10 to 0 2025/07/10 01:34:16 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/AccountReadError === RUN TestReOpen/WithRangeOption === RUN TestReOpen/WithRangeOption/Basics 2025/07/10 01:34:16 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/ErrorAtStart === RUN TestReOpen/WithRangeOption/WithErrors 2025/07/10 01:34:16 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2025/07/10 01:34:16 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2025/07/10 01:34:16 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithRangeOption/TooManyErrors 2025/07/10 01:34:16 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2025/07/10 01:34:16 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2025/07/10 01:34:16 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2025/07/10 01:34:16 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithRangeOption/Seek 2025/07/10 01:34:16 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/07/10 01:34:16 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/07/10 01:34:16 DEBUG : potato: Seek from 5 to 2 2025/07/10 01:34:16 DEBUG : potato: Seek from 7 to 4 === RUN TestReOpen/WithRangeOption/AccountRead === RUN TestReOpen/WithRangeOption/AccountReadDelay 2025/07/10 01:34:16 DEBUG : potato: Seek from 7 to 0 2025/07/10 01:34:16 DEBUG : potato: Seek from 7 to 0 2025/07/10 01:34:16 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/AccountReadError === RUN TestReOpen/WithSeekOption === RUN TestReOpen/WithSeekOption/Basics 2025/07/10 01:34:16 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/ErrorAtStart === RUN TestReOpen/WithSeekOption/WithErrors 2025/07/10 01:34:16 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2025/07/10 01:34:16 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2025/07/10 01:34:16 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithSeekOption/TooManyErrors 2025/07/10 01:34:16 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2025/07/10 01:34:16 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2025/07/10 01:34:16 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2025/07/10 01:34:16 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithSeekOption/Seek 2025/07/10 01:34:16 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/07/10 01:34:16 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/07/10 01:34:16 DEBUG : potato: Seek from 5 to 2 2025/07/10 01:34:16 DEBUG : potato: Seek from 7 to 5 === RUN TestReOpen/WithSeekOption/AccountRead === RUN TestReOpen/WithSeekOption/AccountReadDelay 2025/07/10 01:34:16 DEBUG : potato: Seek from 8 to 0 2025/07/10 01:34:16 DEBUG : potato: Seek from 8 to 0 2025/07/10 01:34:16 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/AccountReadError === RUN TestReOpen/UnknownSize === RUN TestReOpen/UnknownSize/Basics 2025/07/10 01:34:16 DEBUG : potato: Seek from 9 to 0 === RUN TestReOpen/UnknownSize/ErrorAtStart === RUN TestReOpen/UnknownSize/WithErrors 2025/07/10 01:34:16 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2025/07/10 01:34:16 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2025/07/10 01:34:16 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/UnknownSize/TooManyErrors 2025/07/10 01:34:16 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2025/07/10 01:34:16 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2025/07/10 01:34:16 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2025/07/10 01:34:16 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/UnknownSize/Seek 2025/07/10 01:34:16 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/07/10 01:34:16 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/07/10 01:34:16 DEBUG : potato: Seek from 5 to 2 2025/07/10 01:34:16 DEBUG : potato: Seek from 7 to 6 === RUN TestReOpen/UnknownSize/AccountRead === RUN TestReOpen/UnknownSize/AccountReadDelay 2025/07/10 01:34:16 DEBUG : potato: Seek from 9 to 0 2025/07/10 01:34:16 DEBUG : potato: Seek from 9 to 0 2025/07/10 01:34:16 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-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", 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.99s) --- PASS: TestCheck/1 (0.18s) --- PASS: TestCheck/2 (0.18s) --- PASS: TestCheck/3 (0.18s) --- PASS: TestCheck/4 (0.20s) --- PASS: TestCheck/5 (0.18s) --- PASS: TestCheck/6 (0.19s) --- PASS: TestCheck/7 (0.19s) === RUN TestCheckFsError 2025/07/10 01:34:21 DEBUG : Creating backend with remote "nonexistent" 2025/07/10 01:34:21 DEBUG : Config file has changed externally - reloading 2025/07/10 01:34:21 DEBUG : Creating backend with remote "nonexistent" 2025/07/10 01:34:21 DEBUG : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: Waiting for checks to finish 2025/07/10 01:34:21 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/07/10 01:34:21 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: 0 differences found 2025/07/10 01:34:21 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-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", 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.60s) --- PASS: TestCheckDownload/1 (0.74s) --- PASS: TestCheckDownload/2 (0.37s) --- PASS: TestCheckDownload/3 (0.37s) --- PASS: TestCheckDownload/4 (0.56s) --- PASS: TestCheckDownload/5 (0.38s) --- PASS: TestCheckDownload/6 (0.38s) --- PASS: TestCheckDownload/7 (0.37s) === RUN TestCheckSizeOnly run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", 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 (5.08s) --- PASS: TestCheckSizeOnly/1 (0.18s) --- PASS: TestCheckSizeOnly/2 (0.18s) --- PASS: TestCheckSizeOnly/3 (0.18s) --- PASS: TestCheckSizeOnly/4 (0.21s) --- PASS: TestCheckSizeOnly/5 (0.18s) --- PASS: TestCheckSizeOnly/6 (0.18s) --- PASS: TestCheckSizeOnly/7 (0.18s) === RUN TestCheckEqualReaders --- PASS: TestCheckEqualReaders (0.00s) === RUN TestParseSumFile run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" 2025/07/10 01:34:34 NOTICE: test.sum: improperly formatted checksum line 4 2025/07/10 01:34:34 NOTICE: test.sum: improperly formatted checksum line 5 2025/07/10 01:34:34 NOTICE: test.sum: improperly formatted checksum line 6 2025/07/10 01:34:34 NOTICE: test.sum: 2 warning(s) suppressed... 2025/07/10 01:34:34 NOTICE: test.sum: improperly formatted checksum line 4 2025/07/10 01:34:34 NOTICE: test.sum: improperly formatted checksum line 5 2025/07/10 01:34:34 NOTICE: test.sum: improperly formatted checksum line 6 2025/07/10 01:34:34 NOTICE: test.sum: 2 warning(s) suppressed... --- PASS: TestParseSumFile (2.44s) === RUN TestCheckSum run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" 2025/07/10 01:34:35 DEBUG : Creating backend with remote "TestB2:rclone-test-jasibur1wagu/data" check_test.go:350: B2 bucket rclone-test-jasibur1wagu path data lacks md5, skipping --- SKIP: TestCheckSum (1.49s) === RUN TestCheckSumDownload run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" 2025/07/10 01:34:36 DEBUG : Creating backend with remote "TestB2:rclone-test-jasibur1wagu/data" === RUN TestCheckSumDownload/subtest1 === RUN TestCheckSumDownload/subtest2 === RUN TestCheckSumDownload/subtest3 === RUN TestCheckSumDownload/subtest4 === RUN TestCheckSumDownload/subtest5 === RUN TestCheckSumDownload/subtest6 === RUN TestCheckSumDownload/subtest7 --- PASS: TestCheckSumDownload (13.61s) --- PASS: TestCheckSumDownload/subtest1 (1.27s) --- PASS: TestCheckSumDownload/subtest2 (0.72s) --- PASS: TestCheckSumDownload/subtest3 (0.91s) --- PASS: TestCheckSumDownload/subtest4 (0.78s) --- PASS: TestCheckSumDownload/subtest5 (0.74s) --- PASS: TestCheckSumDownload/subtest6 (0.75s) --- PASS: TestCheckSumDownload/subtest7 (0.74s) === RUN TestApplyTransforms 2025/07/10 01:34:50 DEBUG : Creating backend with remote "TestB2:rclone-test-tegisik6rezu" 2025/07/10 01:34:50 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/07/10 01:34:51 DEBUG : Creating backend with remote "/tmp/rclone493415768" run.go:180: Remote "B2 bucket rclone-test-tegisik6rezu", Local "Local file system at /tmp/rclone493415768", Modify Window "1ms" upper checkfile vs. lower remote (without normalization) 2025/07/10 01:34:53 ERROR : hello, world!: sum not found 2025/07/10 01:34:53 ERROR : HELLO, WORLD!: file not in B2 bucket rclone-test-tegisik6rezu 2025/07/10 01:34:53 NOTICE: B2 bucket rclone-test-tegisik6rezu: 1 files missing 2025/07/10 01:34:53 NOTICE: 1 hashes missing 2025/07/10 01:34:53 NOTICE: B2 bucket rclone-test-tegisik6rezu: 1 differences found 2025/07/10 01:34:53 NOTICE: B2 bucket rclone-test-tegisik6rezu: 2 errors while checking upper checkfile vs. lower remote (with normalization) 2025/07/10 01:34:53 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/07/10 01:34:53 NOTICE: B2 bucket rclone-test-tegisik6rezu: 0 differences found 2025/07/10 01:34:53 NOTICE: B2 bucket rclone-test-tegisik6rezu: 1 matching files 2025/07/10 01:34:53 DEBUG : Creating backend with remote "TestB2:rclone-test-filaxol1coro" 2025/07/10 01:34:53 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/07/10 01:34:54 DEBUG : Creating backend with remote "/tmp/rclone953801697" run.go:180: Remote "B2 bucket rclone-test-filaxol1coro", Local "Local file system at /tmp/rclone953801697", Modify Window "1ms" lower checkfile vs. upper remote (without normalization) 2025/07/10 01:34:56 ERROR : HELLO, WORLD!: sum not found 2025/07/10 01:34:56 ERROR : hello, world!: file not in B2 bucket rclone-test-filaxol1coro 2025/07/10 01:34:56 NOTICE: B2 bucket rclone-test-filaxol1coro: 1 files missing 2025/07/10 01:34:56 NOTICE: 1 hashes missing 2025/07/10 01:34:56 NOTICE: B2 bucket rclone-test-filaxol1coro: 1 differences found 2025/07/10 01:34:56 NOTICE: B2 bucket rclone-test-filaxol1coro: 2 errors while checking lower checkfile vs. upper remote (with normalization) 2025/07/10 01:34:57 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/07/10 01:34:57 NOTICE: B2 bucket rclone-test-filaxol1coro: 0 differences found 2025/07/10 01:34:57 NOTICE: B2 bucket rclone-test-filaxol1coro: 1 matching files 2025/07/10 01:34:57 DEBUG : Creating backend with remote "TestB2:rclone-test-xotuhad7viva" 2025/07/10 01:34:57 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/07/10 01:34:57 DEBUG : Creating backend with remote "/tmp/rclone2025597524" run.go:180: Remote "B2 bucket rclone-test-xotuhad7viva", Local "Local file system at /tmp/rclone2025597524", Modify Window "1ms" lower checkfile vs. upperlowermixed remote (without normalization) 2025/07/10 01:34:59 ERROR : HeLlO, wOrLd!: sum not found 2025/07/10 01:34:59 ERROR : hello, world!: file not in B2 bucket rclone-test-xotuhad7viva 2025/07/10 01:34:59 NOTICE: B2 bucket rclone-test-xotuhad7viva: 1 files missing 2025/07/10 01:34:59 NOTICE: 1 hashes missing 2025/07/10 01:34:59 NOTICE: B2 bucket rclone-test-xotuhad7viva: 1 differences found 2025/07/10 01:34:59 NOTICE: B2 bucket rclone-test-xotuhad7viva: 2 errors while checking lower checkfile vs. upperlowermixed remote (with normalization) 2025/07/10 01:35:00 DEBUG : HeLlO, wOrLd!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/07/10 01:35:00 NOTICE: B2 bucket rclone-test-xotuhad7viva: 0 differences found 2025/07/10 01:35:00 NOTICE: B2 bucket rclone-test-xotuhad7viva: 1 matching files 2025/07/10 01:35:00 DEBUG : Creating backend with remote "TestB2:rclone-test-xedenef9wuke" 2025/07/10 01:35:00 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/07/10 01:35:01 DEBUG : Creating backend with remote "/tmp/rclone4168216677" run.go:180: Remote "B2 bucket rclone-test-xedenef9wuke", Local "Local file system at /tmp/rclone4168216677", Modify Window "1ms" upperlowermixed checkfile vs. upper remote (without normalization) 2025/07/10 01:35:03 ERROR : HELLO, WORLD!: sum not found 2025/07/10 01:35:03 ERROR : HeLlO, wOrLd!: file not in B2 bucket rclone-test-xedenef9wuke 2025/07/10 01:35:03 NOTICE: B2 bucket rclone-test-xedenef9wuke: 1 files missing 2025/07/10 01:35:03 NOTICE: 1 hashes missing 2025/07/10 01:35:03 NOTICE: B2 bucket rclone-test-xedenef9wuke: 1 differences found 2025/07/10 01:35:03 NOTICE: B2 bucket rclone-test-xedenef9wuke: 2 errors while checking upperlowermixed checkfile vs. upper remote (with normalization) 2025/07/10 01:35:04 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/07/10 01:35:04 NOTICE: B2 bucket rclone-test-xedenef9wuke: 0 differences found 2025/07/10 01:35:04 NOTICE: B2 bucket rclone-test-xedenef9wuke: 1 matching files 2025/07/10 01:35:04 DEBUG : Creating backend with remote "TestB2:rclone-test-tivuqif2jefe" 2025/07/10 01:35:04 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/07/10 01:35:04 DEBUG : Creating backend with remote "/tmp/rclone591367854" run.go:180: Remote "B2 bucket rclone-test-tivuqif2jefe", Local "Local file system at /tmp/rclone591367854", Modify Window "1ms" NFD checkfile vs. NFC remote (without normalization) 2025/07/10 01:35:06 ERROR : 測試_Русский___ě_áñ: sum not found 2025/07/10 01:35:06 ERROR : 測試_Русский___ě_áñ: file not in B2 bucket rclone-test-tivuqif2jefe 2025/07/10 01:35:06 NOTICE: B2 bucket rclone-test-tivuqif2jefe: 1 files missing 2025/07/10 01:35:06 NOTICE: 1 hashes missing 2025/07/10 01:35:06 NOTICE: B2 bucket rclone-test-tivuqif2jefe: 1 differences found 2025/07/10 01:35:06 NOTICE: B2 bucket rclone-test-tivuqif2jefe: 2 errors while checking NFD checkfile vs. NFC remote (with normalization) 2025/07/10 01:35:07 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/07/10 01:35:07 NOTICE: B2 bucket rclone-test-tivuqif2jefe: 0 differences found 2025/07/10 01:35:07 NOTICE: B2 bucket rclone-test-tivuqif2jefe: 1 matching files 2025/07/10 01:35:07 DEBUG : Creating backend with remote "TestB2:rclone-test-bugamek9lela" 2025/07/10 01:35:07 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/07/10 01:35:08 DEBUG : Creating backend with remote "/tmp/rclone560573355" run.go:180: Remote "B2 bucket rclone-test-bugamek9lela", Local "Local file system at /tmp/rclone560573355", Modify Window "1ms" NFC checkfile vs. NFD remote (without normalization) 2025/07/10 01:35:10 ERROR : 測試_Русский___ě_áñ: sum not found 2025/07/10 01:35:10 ERROR : 測試_Русский___ě_áñ: file not in B2 bucket rclone-test-bugamek9lela 2025/07/10 01:35:10 NOTICE: B2 bucket rclone-test-bugamek9lela: 1 files missing 2025/07/10 01:35:10 NOTICE: 1 hashes missing 2025/07/10 01:35:10 NOTICE: B2 bucket rclone-test-bugamek9lela: 1 differences found 2025/07/10 01:35:10 NOTICE: B2 bucket rclone-test-bugamek9lela: 2 errors while checking NFC checkfile vs. NFD remote (with normalization) 2025/07/10 01:35:10 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/07/10 01:35:10 NOTICE: B2 bucket rclone-test-bugamek9lela: 0 differences found 2025/07/10 01:35:10 NOTICE: B2 bucket rclone-test-bugamek9lela: 1 matching files 2025/07/10 01:35:10 DEBUG : Creating backend with remote "TestB2:rclone-test-hotapoj5boqa" 2025/07/10 01:35:10 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/07/10 01:35:11 DEBUG : Creating backend with remote "/tmp/rclone3746560329" run.go:180: Remote "B2 bucket rclone-test-hotapoj5boqa", Local "Local file system at /tmp/rclone3746560329", Modify Window "1ms" NFDx2 checkfile vs. both remote (without normalization) 2025/07/10 01:35:13 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2025/07/10 01:35:13 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-hotapoj5boqa 2025/07/10 01:35:13 NOTICE: B2 bucket rclone-test-hotapoj5boqa: 1 files missing 2025/07/10 01:35:13 NOTICE: 1 hashes missing 2025/07/10 01:35:13 NOTICE: B2 bucket rclone-test-hotapoj5boqa: 1 differences found 2025/07/10 01:35:13 NOTICE: B2 bucket rclone-test-hotapoj5boqa: 2 errors while checking NFDx2 checkfile vs. both remote (with normalization) 2025/07/10 01:35:14 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/07/10 01:35:14 NOTICE: B2 bucket rclone-test-hotapoj5boqa: 0 differences found 2025/07/10 01:35:14 NOTICE: B2 bucket rclone-test-hotapoj5boqa: 1 matching files 2025/07/10 01:35:14 DEBUG : Creating backend with remote "TestB2:rclone-test-gubesad7habu" 2025/07/10 01:35:14 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/07/10 01:35:14 DEBUG : Creating backend with remote "/tmp/rclone2472294740" run.go:180: Remote "B2 bucket rclone-test-gubesad7habu", Local "Local file system at /tmp/rclone2472294740", Modify Window "1ms" NFCx2 checkfile vs. both remote (without normalization) 2025/07/10 01:35:17 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2025/07/10 01:35:17 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-gubesad7habu 2025/07/10 01:35:17 NOTICE: B2 bucket rclone-test-gubesad7habu: 1 files missing 2025/07/10 01:35:17 NOTICE: 1 hashes missing 2025/07/10 01:35:17 NOTICE: B2 bucket rclone-test-gubesad7habu: 1 differences found 2025/07/10 01:35:17 NOTICE: B2 bucket rclone-test-gubesad7habu: 2 errors while checking NFCx2 checkfile vs. both remote (with normalization) 2025/07/10 01:35:18 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/07/10 01:35:18 NOTICE: B2 bucket rclone-test-gubesad7habu: 0 differences found 2025/07/10 01:35:18 NOTICE: B2 bucket rclone-test-gubesad7habu: 1 matching files 2025/07/10 01:35:18 DEBUG : Creating backend with remote "TestB2:rclone-test-howeqev3tiju" 2025/07/10 01:35:18 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/07/10 01:35:18 DEBUG : Creating backend with remote "/tmp/rclone3883725517" run.go:180: Remote "B2 bucket rclone-test-howeqev3tiju", Local "Local file system at /tmp/rclone3883725517", Modify Window "1ms" both checkfile vs. NFDx2 remote (without normalization) 2025/07/10 01:35:20 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2025/07/10 01:35:20 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-howeqev3tiju 2025/07/10 01:35:20 NOTICE: B2 bucket rclone-test-howeqev3tiju: 1 files missing 2025/07/10 01:35:20 NOTICE: 1 hashes missing 2025/07/10 01:35:20 NOTICE: B2 bucket rclone-test-howeqev3tiju: 1 differences found 2025/07/10 01:35:20 NOTICE: B2 bucket rclone-test-howeqev3tiju: 2 errors while checking both checkfile vs. NFDx2 remote (with normalization) 2025/07/10 01:35:21 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/07/10 01:35:21 NOTICE: B2 bucket rclone-test-howeqev3tiju: 0 differences found 2025/07/10 01:35:21 NOTICE: B2 bucket rclone-test-howeqev3tiju: 1 matching files 2025/07/10 01:35:21 DEBUG : Creating backend with remote "TestB2:rclone-test-larupah5nipi" 2025/07/10 01:35:21 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/07/10 01:35:22 DEBUG : Creating backend with remote "/tmp/rclone1600071497" run.go:180: Remote "B2 bucket rclone-test-larupah5nipi", Local "Local file system at /tmp/rclone1600071497", Modify Window "1ms" both checkfile vs. NFCx2 remote (without normalization) 2025/07/10 01:35:24 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2025/07/10 01:35:24 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-larupah5nipi 2025/07/10 01:35:24 NOTICE: B2 bucket rclone-test-larupah5nipi: 1 files missing 2025/07/10 01:35:24 NOTICE: 1 hashes missing 2025/07/10 01:35:24 NOTICE: B2 bucket rclone-test-larupah5nipi: 1 differences found 2025/07/10 01:35:24 NOTICE: B2 bucket rclone-test-larupah5nipi: 2 errors while checking both checkfile vs. NFCx2 remote (with normalization) 2025/07/10 01:35:25 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/07/10 01:35:25 NOTICE: B2 bucket rclone-test-larupah5nipi: 0 differences found 2025/07/10 01:35:25 NOTICE: B2 bucket rclone-test-larupah5nipi: 1 matching files 2025/07/10 01:35:25 DEBUG : B2 bucket rclone-test-larupah5nipi: Purge remote 2025/07/10 01:35:25 INFO : B2 bucket rclone-test-larupah5nipi: cleaning bucket "rclone-test-larupah5nipi" of all files 2025/07/10 01:35:25 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_z2fa21d63f09dfb1b9f780e1b_f117461e806426f2b_d20250710_m013523_c001_v0001037_t0006_u01752111323576") 2025/07/10 01:35:25 DEBUG : B2 bucket rclone-test-howeqev3tiju: Purge remote 2025/07/10 01:35:25 INFO : B2 bucket rclone-test-howeqev3tiju: cleaning bucket "rclone-test-howeqev3tiju" of all files 2025/07/10 01:35:26 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_z7f420d83f09dfb1b9f780e1b_f114a91723a4f90cf_d20250710_m013520_c001_v0001130_t0052_u01752111320190") 2025/07/10 01:35:26 DEBUG : B2 bucket rclone-test-gubesad7habu: Purge remote 2025/07/10 01:35:26 INFO : B2 bucket rclone-test-gubesad7habu: cleaning bucket "rclone-test-gubesad7habu" of all files 2025/07/10 01:35:27 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_z0f92fd93f08dfb1b9f780e1b_f1018ddf34cee24ee_d20250710_m013516_c001_v0001116_t0020_u01752111316323") 2025/07/10 01:35:27 DEBUG : B2 bucket rclone-test-hotapoj5boqa: Purge remote 2025/07/10 01:35:27 INFO : B2 bucket rclone-test-hotapoj5boqa: cleaning bucket "rclone-test-hotapoj5boqa" of all files 2025/07/10 01:35:28 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_zef32edb3f08dfb1b9f780e1b_f116422f546e7c046_d20250710_m013512_c001_v0001154_t0000_u01752111312792") 2025/07/10 01:35:29 DEBUG : B2 bucket rclone-test-bugamek9lela: Purge remote 2025/07/10 01:35:29 INFO : B2 bucket rclone-test-bugamek9lela: cleaning bucket "rclone-test-bugamek9lela" of all files 2025/07/10 01:35:29 DEBUG : 測試_Русский___ě_áñ: Deleting (id "4_zff02dde3f08dfb1b9f780e1b_f108bc406afb6237d_d20250710_m013509_c001_v0001131_t0036_u01752111309438") 2025/07/10 01:35:30 DEBUG : B2 bucket rclone-test-tivuqif2jefe: Purge remote 2025/07/10 01:35:30 INFO : B2 bucket rclone-test-tivuqif2jefe: cleaning bucket "rclone-test-tivuqif2jefe" of all files 2025/07/10 01:35:30 DEBUG : 測試_Русский___ě_áñ: Deleting (id "4_z8f82dd13f08dfb1b9f780e1b_f1162c92cf0ac8cb9_d20250710_m013506_c001_v0001042_t0006_u01752111306028") 2025/07/10 01:35:31 DEBUG : B2 bucket rclone-test-xedenef9wuke: Purge remote 2025/07/10 01:35:31 INFO : B2 bucket rclone-test-xedenef9wuke: cleaning bucket "rclone-test-xedenef9wuke" of all files 2025/07/10 01:35:32 DEBUG : HELLO, WORLD!: Deleting (id "4_z6f52cd43f08dfb1b9f780e1b_f108a3f9999475f4d_d20250710_m013502_c001_v0001171_t0015_u01752111302671") 2025/07/10 01:35:32 DEBUG : B2 bucket rclone-test-xotuhad7viva: Purge remote 2025/07/10 01:35:32 INFO : B2 bucket rclone-test-xotuhad7viva: cleaning bucket "rclone-test-xotuhad7viva" of all files 2025/07/10 01:35:33 DEBUG : HeLlO, wOrLd!: Deleting (id "4_z9f42bd63f08dfb1b9f780e1b_f113cc0580d07978c_d20250710_m013459_c001_v0001099_t0037_u01752111299190") 2025/07/10 01:35:34 DEBUG : B2 bucket rclone-test-filaxol1coro: Purge remote 2025/07/10 01:35:34 INFO : B2 bucket rclone-test-filaxol1coro: cleaning bucket "rclone-test-filaxol1coro" of all files 2025/07/10 01:35:34 DEBUG : HELLO, WORLD!: Deleting (id "4_z9fa2ad93f08dfb1b9f780e1b_f1029c398b8d2d2ac_d20250710_m013455_c001_v0001181_t0056_u01752111295852") 2025/07/10 01:35:35 DEBUG : B2 bucket rclone-test-tegisik6rezu: Purge remote 2025/07/10 01:35:35 INFO : B2 bucket rclone-test-tegisik6rezu: cleaning bucket "rclone-test-tegisik6rezu" of all files 2025/07/10 01:35:35 DEBUG : hello, world!: Deleting (id "4_z7f129dc3f08dfb1b9f780e1b_f106729a8e4f61ba0_d20250710_m013452_c001_v0001150_t0000_u01752111292443") --- PASS: TestApplyTransforms (45.94s) === RUN TestTruncateString --- PASS: TestTruncateString (0.00s) === RUN TestCopyFile run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" 2025/07/10 01:35:36 DEBUG : file1: Need to transfer - File not found at Destination 2025/07/10 01:35:37 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2025/07/10 01:35:37 INFO : file1: Copied (new) to: sub/file2 2025/07/10 01:35:38 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/07/10 01:35:38 DEBUG : file1: Unchanged skipping 2025/07/10 01:35:38 DEBUG : B2 bucket rclone-test-jasibur1wagu: 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-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" copy_test.go:153: Test only runs on local --- SKIP: TestCopyLongFile (0.36s) === RUN TestCopyFileBackupDir run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" 2025/07/10 01:35:40 DEBUG : Creating backend with remote "TestB2:rclone-test-jasibur1wagu/backup" 2025/07/10 01:35:41 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18) 2025/07/10 01:35:43 DEBUG : dst/file1: sha1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK 2025/07/10 01:35:43 INFO : dst/file1: Copied (server-side copy) 2025/07/10 01:35:43 INFO : dst/file1: Deleted 2025/07/10 01:35:43 DEBUG : dst/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2025/07/10 01:35:43 INFO : dst/file1: Copied (new) --- PASS: TestCopyFileBackupDir (4.62s) === RUN TestCopyFileCompareDest run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" 2025/07/10 01:35:44 DEBUG : Creating backend with remote "TestB2:rclone-test-jasibur1wagu/dst" 2025/07/10 01:35:45 DEBUG : Creating backend with remote "TestB2:rclone-test-jasibur1wagu/CompareDest" 2025/07/10 01:35:46 DEBUG : one: Need to transfer - File not found at Destination 2025/07/10 01:35:48 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2025/07/10 01:35:48 INFO : one: Copied (new) 2025/07/10 01:35:49 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/07/10 01:35:50 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2025/07/10 01:35:50 INFO : one: Copied (replaced existing) 2025/07/10 01:35:51 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/07/10 01:35:51 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/07/10 01:35:51 DEBUG : one: Destination found in --compare-dest, skipping 2025/07/10 01:35:52 DEBUG : two: Need to transfer - File not found at Destination 2025/07/10 01:35:53 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/07/10 01:35:53 DEBUG : two: Destination found in --compare-dest, skipping 2025/07/10 01:35:53 DEBUG : two: Need to transfer - File not found at Destination 2025/07/10 01:35:53 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/07/10 01:35:53 DEBUG : two: Destination found in --compare-dest, skipping 2025/07/10 01:35:54 DEBUG : two: Need to transfer - File not found at Destination 2025/07/10 01:35:54 DEBUG : two: Sizes differ (src 5 vs dst 3) 2025/07/10 01:35:54 DEBUG : two: sha1 = a4be8877ad88f3109a6411985a1f359e10e2d930 OK 2025/07/10 01:35:54 INFO : two: Copied (new) --- PASS: TestCopyFileCompareDest (11.52s) === RUN TestCopyFileCopyDest run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" 2025/07/10 01:35:56 DEBUG : Creating backend with remote "TestB2:rclone-test-jasibur1wagu/dst" 2025/07/10 01:35:57 DEBUG : Creating backend with remote "TestB2:rclone-test-jasibur1wagu/CopyDest" 2025/07/10 01:35:58 DEBUG : one: Need to transfer - File not found at Destination 2025/07/10 01:36:00 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2025/07/10 01:36:00 INFO : one: Copied (new) 2025/07/10 01:36:00 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/07/10 01:36:01 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2025/07/10 01:36:01 INFO : one: Copied (replaced existing) 2025/07/10 01:36:02 DEBUG : Creating backend with remote "TestB2:rclone-test-jasibur1wagu/BackupDir" 2025/07/10 01:36:03 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/07/10 01:36:04 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/07/10 01:36:04 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/07/10 01:36:05 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2025/07/10 01:36:05 INFO : one: Copied (server-side copy) 2025/07/10 01:36:05 INFO : one: Deleted 2025/07/10 01:36:06 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2025/07/10 01:36:06 INFO : one: Copied (server-side copy) 2025/07/10 01:36:06 DEBUG : one: Destination found in --copy-dest, using server-side copy 2025/07/10 01:36:07 DEBUG : two: Need to transfer - File not found at Destination 2025/07/10 01:36:07 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/07/10 01:36:08 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2025/07/10 01:36:08 INFO : two: Copied (server-side copy) 2025/07/10 01:36:08 DEBUG : two: Destination found in --copy-dest, using server-side copy 2025/07/10 01:36:08 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/07/10 01:36:08 DEBUG : two: Unchanged skipping 2025/07/10 01:36:09 DEBUG : three: Need to transfer - File not found at Destination 2025/07/10 01:36:10 DEBUG : three: Sizes differ (src 7 vs dst 5) 2025/07/10 01:36:10 DEBUG : three: Destination not found in --copy-dest 2025/07/10 01:36:10 DEBUG : three: sha1 = 370e613335f3cf2f19b2a2d35a86402f55c659f8 OK 2025/07/10 01:36:10 INFO : three: Copied (new) --- PASS: TestCopyFileCopyDest (16.61s) === RUN TestCopyInplace run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" copy_test.go:370: Partial uploads not supported --- SKIP: TestCopyInplace (0.36s) === RUN TestCopyLongFileName run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" copy_test.go:403: Partial uploads not supported --- SKIP: TestCopyLongFileName (0.36s) === RUN TestCopyFileMaxTransfer run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" 2025/07/10 01:36:13 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination 2025/07/10 01:36:14 DEBUG : TestCopyFileMaxTransfer/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2025/07/10 01:36:14 INFO : TestCopyFileMaxTransfer/file1: Copied (new) 2025/07/10 01:36:15 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination 2025/07/10 01:36:15 ERROR : TestCopyFileMaxTransfer/file2: Failed to copy: Post "https://pod-000-1098-18.backblaze.com/b2api/v1/b2_upload_file/7f028d03f03dfb1b9f780e1b/c001_v0001098_t0046": max transfer limit reached as set by --max-transfer 2025/07/10 01:36:15 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination 2025/07/10 01:36:15 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination 2025/07/10 01:36:16 DEBUG : TestCopyFileMaxTransfer/file4: sha1 = 3fa58776eae5c7c245c52740fac3582e35517cd5 OK 2025/07/10 01:36:16 INFO : TestCopyFileMaxTransfer/file4: Copied (new) --- PASS: TestCopyFileMaxTransfer (4.36s) === RUN TestDeduplicateInteractive run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateInteractive (0.36s) === RUN TestDeduplicateSkip run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSkip (0.36s) === RUN TestDeduplicateSizeOnly run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSizeOnly (0.36s) === RUN TestDeduplicateFirst run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateFirst (0.41s) === RUN TestDeduplicateNewest run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateNewest (0.36s) === RUN TestDeduplicateNewestByHash run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" 2025/07/10 01:36:21 INFO : B2 bucket rclone-test-jasibur1wagu: Looking for duplicate sha1 hashes using newest mode. 2025/07/10 01:36:21 NOTICE: ad78e810ec78e5b5c46d6a8c876fa249bfb28ed8: Found 3 files with duplicate sha1 hashes 2025/07/10 01:36:21 INFO : one: Deleted 2025/07/10 01:36:21 INFO : also/one: Deleted 2025/07/10 01:36:21 NOTICE: ad78e810ec78e5b5c46d6a8c876fa249bfb28ed8: Deleted 2 extra copies --- PASS: TestDeduplicateNewestByHash (3.28s) === RUN TestDeduplicateOldest run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", 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-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateLargest (0.36s) === RUN TestDeduplicateSmallest run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSmallest (0.36s) === RUN TestDeduplicateRename run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateRename (0.36s) === RUN TestMergeDirs run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" dedupe_test.go:256: Can't merge directories --- SKIP: TestMergeDirs (0.36s) === RUN TestListDirSorted run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" 2025/07/10 01:36:28 DEBUG : a.txt: Excluded (Size Filter) 2025/07/10 01:36:28 DEBUG : a.txt: Excluded 2025/07/10 01:36:28 DEBUG : sub dir/hello world: Excluded (Size Filter) 2025/07/10 01:36:28 DEBUG : sub dir/hello world: Excluded 2025/07/10 01:36:28 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2025/07/10 01:36:28 DEBUG : sub dir/hello world2: Excluded 2025/07/10 01:36:28 DEBUG : sub dir/hello world: Excluded (Size Filter) 2025/07/10 01:36:28 DEBUG : sub dir/hello world: Excluded 2025/07/10 01:36:28 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2025/07/10 01:36:28 DEBUG : sub dir/hello world2: Excluded 2025/07/10 01:36:29 DEBUG : sub dir/ignore dir: Excluded 2025/07/10 01:36:29 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSorted (7.63s) === RUN TestListDirSortedFn run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" 2025/07/10 01:36:35 DEBUG : a.txt: Excluded (Size Filter) 2025/07/10 01:36:35 DEBUG : a.txt: Excluded 2025/07/10 01:36:35 DEBUG : sub dir/hello world: Excluded (Size Filter) 2025/07/10 01:36:35 DEBUG : sub dir/hello world: Excluded 2025/07/10 01:36:35 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2025/07/10 01:36:35 DEBUG : sub dir/hello world2: Excluded 2025/07/10 01:36:35 DEBUG : sub dir/hello world: Excluded (Size Filter) 2025/07/10 01:36:35 DEBUG : sub dir/hello world: Excluded 2025/07/10 01:36:35 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2025/07/10 01:36:35 DEBUG : sub dir/hello world2: Excluded 2025/07/10 01:36:36 DEBUG : sub dir/ignore dir: Excluded 2025/07/10 01:36:36 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSortedFn (6.66s) === RUN TestListJSON run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", 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/07/10 01:36:41 ERROR : file1: Failed to read hash: hash type not supported === RUN TestListJSON/Metadata --- PASS: TestListJSON (3.72s) --- PASS: TestListJSON/Default (0.19s) --- PASS: TestListJSON/FilesOnly (0.18s) --- PASS: TestListJSON/DirsOnly (0.19s) --- PASS: TestListJSON/Recurse (0.21s) --- PASS: TestListJSON/SubDir (0.19s) --- PASS: TestListJSON/NoModTime (0.19s) --- PASS: TestListJSON/NoMimeType (0.19s) --- PASS: TestListJSON/ShowHash (0.19s) --- PASS: TestListJSON/HashTypes (0.19s) --- PASS: TestListJSON/Metadata (0.18s) === RUN TestStatJSON run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", 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/07/10 01:36:46 DEBUG : Creating backend with remote "TestB2:rclone-test-jasibur1wagu/notfound" 2025/07/10 01:36:46 DEBUG : Config file has changed externally - reloading --- PASS: TestStatJSON (6.53s) --- PASS: TestStatJSON/Root (0.19s) --- PASS: TestStatJSON/RootFilesOnly (0.00s) --- PASS: TestStatJSON/RootDirsOnly (0.19s) --- PASS: TestStatJSON/Dir (0.73s) --- PASS: TestStatJSON/DirWithTrailingSlash (0.19s) --- PASS: TestStatJSON/File (0.18s) --- PASS: TestStatJSON/NotFound (0.37s) --- PASS: TestStatJSON/DirFilesOnly (0.18s) --- PASS: TestStatJSON/FileFilesOnly (0.18s) --- PASS: TestStatJSON/NotFoundFilesOnly (0.18s) --- PASS: TestStatJSON/DirDirsOnly (0.19s) --- PASS: TestStatJSON/FileDirsOnly (0.19s) --- PASS: TestStatJSON/NotFoundDirsOnly (0.19s) --- PASS: TestStatJSON/RootNotFound (1.84s) === RUN TestMkdir run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" 2025/07/10 01:36:49 INFO : B2 bucket rclone-test-jasibur1wagu: Making directory 2025/07/10 01:36:49 INFO : B2 bucket rclone-test-jasibur1wagu: Making directory --- PASS: TestMkdir (0.55s) === RUN TestLsd run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" --- PASS: TestLsd (1.74s) === RUN TestLs run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" --- PASS: TestLs (1.85s) === RUN TestLsWithFilesFrom run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" 2025/07/10 01:36:54 DEBUG : empty space: Excluded (FilesFrom Filter) 2025/07/10 01:36:54 DEBUG : empty space: Excluded --- PASS: TestLsWithFilesFrom (2.40s) === RUN TestLsLong run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" --- PASS: TestLsLong (1.84s) === RUN TestHashSums run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" === RUN TestHashSums/Sha1 === RUN TestHashSums/Sha1Download --- PASS: TestHashSums (2.16s) --- PASS: TestHashSums/Sha1 (0.19s) --- PASS: TestHashSums/Sha1Download (0.38s) === RUN TestHashSumsWithErrors 2025/07/10 01:36:59 DEBUG : Creating backend with remote ":memory:" 2025/07/10 01:36:59 ERROR : file1: hash unsupported: hash type not supported --- PASS: TestHashSumsWithErrors (0.00s) === RUN TestHashStream 2025/07/10 01:36:59 DEBUG : Creating md5 hash of 0 bytes read from input stream 2025/07/10 01:36:59 DEBUG : Creating md5 hash of 0 bytes read from input stream 2025/07/10 01:36:59 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2025/07/10 01:36:59 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2025/07/10 01:36:59 DEBUG : Creating md5 hash of 12 bytes read from input stream 2025/07/10 01:36:59 DEBUG : Creating md5 hash of 12 bytes read from input stream 2025/07/10 01:36:59 DEBUG : Creating sha1 hash of 12 bytes read from input stream 2025/07/10 01:36:59 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-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" --- PASS: TestCount (2.71s) === RUN TestDelete run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" 2025/07/10 01:37:03 DEBUG : Waiting for deletions to finish 2025/07/10 01:37:04 DEBUG : large: Excluded (Size Filter) 2025/07/10 01:37:04 INFO : medium: Deleted 2025/07/10 01:37:04 INFO : small: Deleted --- PASS: TestDelete (2.71s) === RUN TestMaxDelete run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" 2025/07/10 01:37:06 DEBUG : Waiting for deletions to finish 2025/07/10 01:37:06 ERROR : small: Got fatal error on delete: --max-delete threshold reached 2025/07/10 01:37:06 INFO : large: Deleted 2025/07/10 01:37:07 INFO : medium: Deleted --- PASS: TestMaxDelete (3.08s) === RUN TestMaxDeleteSizeLargeFile run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" 2025/07/10 01:37:09 DEBUG : Waiting for deletions to finish 2025/07/10 01:37:09 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached 2025/07/10 01:37:09 INFO : small: Deleted 2025/07/10 01:37:09 INFO : medium: Deleted --- PASS: TestMaxDeleteSizeLargeFile (2.90s) === RUN TestMaxDeleteSize run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" 2025/07/10 01:37:12 DEBUG : Waiting for deletions to finish 2025/07/10 01:37:12 ERROR : small: Got fatal error on delete: --max-delete-size threshold reached 2025/07/10 01:37:13 INFO : large: Deleted 2025/07/10 01:37:13 INFO : medium: Deleted --- PASS: TestMaxDeleteSize (3.06s) === RUN TestReadFile run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" --- PASS: TestReadFile (2.21s) === RUN TestRetry 2025/07/10 01:37:16 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 1/5 2025/07/10 01:37:16 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 2/5 2025/07/10 01:37:16 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2025/07/10 01:37:16 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2025/07/10 01:37:16 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2025/07/10 01:37:16 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2025/07/10 01:37:16 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms --- PASS: TestRetry (0.05s) === RUN TestCat run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" --- PASS: TestCat (4.43s) === RUN TestPurge 2025/07/10 01:37:20 DEBUG : Creating backend with remote "TestB2:rclone-test-gunojez4foce" 2025/07/10 01:37:20 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/07/10 01:37:21 DEBUG : Creating backend with remote "/tmp/rclone1502089877" run.go:180: Remote "B2 bucket rclone-test-gunojez4foce", Local "Local file system at /tmp/rclone1502089877", Modify Window "1ms" 2025/07/10 01:37:23 INFO : A2: Making directory 2025/07/10 01:37:23 INFO : A1/B2: Making directory 2025/07/10 01:37:23 INFO : A1/B2/C2: Making directory 2025/07/10 01:37:23 INFO : A1/B1/C3: Making directory 2025/07/10 01:37:23 INFO : A3: Making directory 2025/07/10 01:37:23 INFO : A3/B3: Making directory 2025/07/10 01:37:23 INFO : A3/B3/C4: Making directory fstest.go:247: Filtering empty directory "A2" fstest.go:247: Filtering empty directory "A1/B2" fstest.go:247: Filtering empty directory "A1/B2/C2" fstest.go:247: Filtering empty directory "A1/B1/C3" fstest.go:247: Filtering empty directory "A3" fstest.go:247: Filtering empty directory "A3/B3" fstest.go:247: Filtering empty directory "A3/B3/C4" 2025/07/10 01:37:23 INFO : B2 bucket rclone-test-gunojez4foce: cleaning bucket "rclone-test-gunojez4foce" of all files 2025/07/10 01:37:24 DEBUG : A1/B1/C1/one: Deleting (id "4_z8f52ed83f0adfb1b9f780e1b_f117114edc69e90b6_d20250710_m013723_c001_v0001161_t0040_u01752111443006") fstest.go:247: Filtering empty directory "A2" fstest.go:247: Filtering empty directory "A1/B2" fstest.go:247: Filtering empty directory "A1/B2/C2" fstest.go:247: Filtering empty directory "A3" fstest.go:247: Filtering empty directory "A3/B3" fstest.go:247: Filtering empty directory "A3/B3/C4" 2025/07/10 01:37:24 INFO : B2 bucket rclone-test-gunojez4foce: cleaning bucket "rclone-test-gunojez4foce" of all files 2025/07/10 01:37:24 DEBUG : A1/two: Deleting (id "4_z8f52ed83f0adfb1b9f780e1b_f117114edc69e90ba_d20250710_m013723_c001_v0001161_t0045_u01752111443307") 2025/07/10 01:37:25 DEBUG : B2 bucket rclone-test-gunojez4foce: Purge remote 2025/07/10 01:37:25 INFO : B2 bucket rclone-test-gunojez4foce: cleaning bucket "rclone-test-gunojez4foce" of all files 2025/07/10 01:37:25 NOTICE: purge failed: directory not found --- PASS: TestPurge (4.76s) === RUN TestRmdirsNoLeaveRoot run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" 2025/07/10 01:37:26 INFO : A2: Making directory 2025/07/10 01:37:26 INFO : A1/B2: Making directory 2025/07/10 01:37:26 INFO : A1/B2/C2: Making directory 2025/07/10 01:37:26 INFO : A1/B1/C3: Making directory 2025/07/10 01:37:26 INFO : A3: Making directory 2025/07/10 01:37:26 INFO : A3/B3: Making directory 2025/07/10 01:37:26 INFO : A3/B3/C4: Making directory fstest.go:247: Filtering empty directory "A2" fstest.go:247: Filtering empty directory "A1/B2" fstest.go:247: Filtering empty directory "A1/B2/C2" fstest.go:247: Filtering empty directory "A1/B1/C3" fstest.go:247: Filtering empty directory "A3" fstest.go:247: Filtering empty directory "A3/B3" fstest.go:247: Filtering empty directory "A3/B3/C4" 2025/07/10 01:37:27 DEBUG : removing 1 level 3 directories 2025/07/10 01:37:27 INFO : A3/B3/C4: Removing directory fstest.go:247: Filtering empty directory "A2" fstest.go:247: Filtering empty directory "A1/B2" fstest.go:247: Filtering empty directory "A1/B2/C2" fstest.go:247: Filtering empty directory "A1/B1/C3" fstest.go:247: Filtering empty directory "A3" fstest.go:247: Filtering empty directory "A3/B3" 2025/07/10 01:37:29 DEBUG : removing 1 level 0 directories 2025/07/10 01:37:29 INFO : B2 bucket rclone-test-jasibur1wagu: Removing directory 2025/07/10 01:37:30 ERROR : Failed to rmdir: failed to delete bucket: Cannot delete non-empty bucket (400 cannot_delete_non_empty_bucket) operations_test.go:728: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:728 Error: Received unexpected error: failed to remove directories: failed to delete bucket: Cannot delete non-empty bucket (400 cannot_delete_non_empty_bucket) Test: TestRmdirsNoLeaveRoot --- FAIL: TestRmdirsNoLeaveRoot (4.92s) === RUN TestRmdirsLeaveRoot run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" 2025/07/10 01:37:30 INFO : A1: Making directory 2025/07/10 01:37:30 INFO : A1/B1: Making directory 2025/07/10 01:37:30 INFO : A1/B1/C1: Making directory fstest.go:247: Filtering empty directory "A1" fstest.go:247: Filtering empty directory "A1/B1" fstest.go:247: Filtering empty directory "A1/B1/C1" fstest.go:247: Filtering empty directory "A1" --- PASS: TestRmdirsLeaveRoot (0.93s) === RUN TestRmdirsWithFilter run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" 2025/07/10 01:37:31 INFO : A1: Making directory 2025/07/10 01:37:31 INFO : A1/B1: Making directory 2025/07/10 01:37:31 INFO : A1/B1/C1: Making directory fstest.go:247: Filtering empty directory "A1" fstest.go:247: Filtering empty directory "A1/B1" fstest.go:247: Filtering empty directory "A1/B1/C1" fstest.go:247: Filtering empty directory "A1" --- PASS: TestRmdirsWithFilter (0.92s) === RUN TestCopyURL run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" 2025/07/10 01:37:33 DEBUG : filename.txt: File name found in url 2025/07/10 01:37:33 DEBUG : headerfilename.txt: filename found in Content-Disposition header. --- PASS: TestCopyURL (3.25s) === RUN TestCopyURLToWriter --- PASS: TestCopyURLToWriter (0.00s) === RUN TestMoveFile run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" 2025/07/10 01:37:35 DEBUG : file1: Need to transfer - File not found at Destination 2025/07/10 01:37:36 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2025/07/10 01:37:36 INFO : file1: Copied (new) to: sub/file2 2025/07/10 01:37:36 INFO : file1: Deleted 2025/07/10 01:37:36 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/07/10 01:37:36 DEBUG : file1: Unchanged skipping 2025/07/10 01:37:36 INFO : file1: Deleted 2025/07/10 01:37:36 DEBUG : B2 bucket rclone-test-jasibur1wagu: don't need to copy/move sub/file2, it is already at target location --- PASS: TestMoveFile (1.92s) === RUN TestMoveFileWithIgnoreExisting run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" 2025/07/10 01:37:37 DEBUG : file1: Need to transfer - File not found at Destination 2025/07/10 01:37:38 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2025/07/10 01:37:38 INFO : file1: Copied (new) 2025/07/10 01:37:38 INFO : file1: Deleted 2025/07/10 01:37:38 DEBUG : file1: Destination exists, skipping 2025/07/10 01:37:38 DEBUG : file1: Not removing source file as destination file exists and --ignore-existing is set --- PASS: TestMoveFileWithIgnoreExisting (1.59s) === RUN TestCaseInsensitiveMoveFile run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFile (0.37s) === RUN TestCaseInsensitiveMoveFileDryRun run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFileDryRun (0.37s) === RUN TestMoveFileBackupDir run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" 2025/07/10 01:37:40 DEBUG : Creating backend with remote "TestB2:rclone-test-jasibur1wagu/backup" 2025/07/10 01:37:41 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18) 2025/07/10 01:37:42 DEBUG : dst/file1: sha1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK 2025/07/10 01:37:42 INFO : dst/file1: Copied (server-side copy) 2025/07/10 01:37:43 INFO : dst/file1: Deleted 2025/07/10 01:37:43 DEBUG : dst/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2025/07/10 01:37:43 INFO : dst/file1: Copied (new) 2025/07/10 01:37:43 INFO : dst/file1: Deleted --- PASS: TestMoveFileBackupDir (4.44s) === 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-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" 2025/07/10 01:37:46 INFO : A1/B2: Making directory 2025/07/10 01:37:46 INFO : A1/B1/C3: Making directory fstest.go:247: Filtering empty directory "A1/B2" fstest.go:247: Filtering empty directory "A1/B1/C3" 2025/07/10 01:37:47 DEBUG : A1/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2025/07/10 01:37:47 INFO : A1/B1/C2/five: Copied (server-side copy) to: A2/B1/C2/five 2025/07/10 01:37:47 DEBUG : A1/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2025/07/10 01:37:47 INFO : A1/two: Copied (server-side copy) to: A2/two 2025/07/10 01:37:47 INFO : A1/B1/C2/five: Deleted 2025/07/10 01:37:47 DEBUG : A1/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2025/07/10 01:37:47 INFO : A1/B1/C1/four: Copied (server-side copy) to: A2/B1/C1/four 2025/07/10 01:37:48 DEBUG : A1/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2025/07/10 01:37:48 INFO : A1/B1/three: Copied (server-side copy) to: A2/B1/three 2025/07/10 01:37:48 INFO : A1/two: Deleted 2025/07/10 01:37:48 DEBUG : A1/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2025/07/10 01:37:48 INFO : A1/one: Copied (server-side copy) to: A2/one 2025/07/10 01:37:48 INFO : A1/B1/C1/four: Deleted 2025/07/10 01:37:48 INFO : A1/B1/three: Deleted 2025/07/10 01:37:48 INFO : A1/one: Deleted fstest.go:247: Filtering empty directory "A2/B2" fstest.go:247: Filtering empty directory "A2/B1/C3" 2025/07/10 01:37:49 DEBUG : A2/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2025/07/10 01:37:49 INFO : A2/one: Copied (server-side copy) to: A3/one 2025/07/10 01:37:49 INFO : A2/one: Deleted 2025/07/10 01:37:49 DEBUG : A2/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2025/07/10 01:37:49 INFO : A2/B1/three: Copied (server-side copy) to: A3/B1/three 2025/07/10 01:37:49 DEBUG : A2/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2025/07/10 01:37:49 INFO : A2/B1/C1/four: Copied (server-side copy) to: A3/B1/C1/four 2025/07/10 01:37:49 DEBUG : A2/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2025/07/10 01:37:49 INFO : A2/B1/C2/five: Copied (server-side copy) to: A3/B1/C2/five 2025/07/10 01:37:49 INFO : A2/B1/three: Deleted 2025/07/10 01:37:49 DEBUG : A2/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2025/07/10 01:37:49 INFO : A2/two: Copied (server-side copy) to: A3/two 2025/07/10 01:37:49 INFO : A2/B1/C1/four: Deleted 2025/07/10 01:37:49 INFO : A2/B1/C2/five: Deleted 2025/07/10 01:37:49 INFO : A2/two: Deleted fstest.go:247: Filtering empty directory "A3/B2" fstest.go:247: Filtering empty directory "A3/B1/C3" 2025/07/10 01:37:49 INFO : B2 bucket rclone-test-jasibur1wagu: Can't DirMove - falling back to file moves: can't move directory - incompatible remotes 2025/07/10 01:37:50 DEBUG : A3/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2025/07/10 01:37:50 INFO : A3/B1/C1/four: Copied (server-side copy) to: A4/B1/C1/four 2025/07/10 01:37:50 DEBUG : A3/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2025/07/10 01:37:50 INFO : A3/B1/C2/five: Copied (server-side copy) to: A4/B1/C2/five 2025/07/10 01:37:50 DEBUG : A3/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2025/07/10 01:37:50 INFO : A3/one: Copied (server-side copy) to: A4/one 2025/07/10 01:37:50 INFO : A3/B1/C1/four: Deleted 2025/07/10 01:37:50 INFO : A3/B1/C2/five: Deleted 2025/07/10 01:37:50 DEBUG : A3/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2025/07/10 01:37:50 INFO : A3/B1/three: Copied (server-side copy) to: A4/B1/three 2025/07/10 01:37:50 INFO : A3/one: Deleted 2025/07/10 01:37:50 INFO : A3/B1/three: Deleted 2025/07/10 01:37:51 DEBUG : A3/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2025/07/10 01:37:51 INFO : A3/two: Copied (server-side copy) to: A4/two 2025/07/10 01:37:51 INFO : A3/two: Deleted fstest.go:247: Filtering empty directory "A4/B2" fstest.go:247: Filtering empty directory "A4/B1/C3" --- PASS: TestDirMove (8.42s) === RUN TestGetFsInfo run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" --- PASS: TestGetFsInfo (0.39s) === RUN TestRcat === RUN TestRcat/withChecksum=false,ignoreChecksum=false run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" 2025/07/10 01:37:53 DEBUG : B2 bucket rclone-test-jasibur1wagu: File to upload is small (34 bytes), uploading instead of streaming 2025/07/10 01:37:54 DEBUG : no_checksum_small_file_from_pipe: sha1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK 2025/07/10 01:37:54 DEBUG : no_checksum_small_file_from_pipe: Size and sha1 of src and dst objects identical 2025/07/10 01:37:54 DEBUG : no_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2025/07/10 01:37:55 DEBUG : no_checksum_big_file_from_pipe: sha1 = d0080f73aa8b494570ad63df1beb25c01ea9eea2 OK 2025/07/10 01:37: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-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" 2025/07/10 01:37:56 DEBUG : B2 bucket rclone-test-jasibur1wagu: File to upload is small (34 bytes), uploading instead of streaming 2025/07/10 01:37:56 DEBUG : with_checksum_small_file_from_pipe: sha1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK 2025/07/10 01:37:56 DEBUG : with_checksum_small_file_from_pipe: Size and sha1 of src and dst objects identical 2025/07/10 01:37:56 DEBUG : with_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2025/07/10 01:37:57 DEBUG : with_checksum_big_file_from_pipe: sha1 = d0080f73aa8b494570ad63df1beb25c01ea9eea2 OK 2025/07/10 01:37:57 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-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" 2025/07/10 01:37:59 DEBUG : B2 bucket rclone-test-jasibur1wagu: File to upload is small (34 bytes), uploading instead of streaming 2025/07/10 01:37:59 DEBUG : ignore_checksum_small_file_from_pipe: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/07/10 01:37:59 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2025/07/10 01:38:00 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-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" 2025/07/10 01:38:01 DEBUG : B2 bucket rclone-test-jasibur1wagu: File to upload is small (34 bytes), uploading instead of streaming 2025/07/10 01:38:01 DEBUG : ignore_checksum_small_file_from_pipe: Src hash empty - aborting Dst hash check 2025/07/10 01:38:01 DEBUG : ignore_checksum_small_file_from_pipe: Size of src and dst objects identical 2025/07/10 01:38:01 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2025/07/10 01:38:02 DEBUG : ignore_checksum_big_file_from_pipe: Src hash empty - aborting Dst hash check 2025/07/10 01:38:02 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical --- PASS: TestRcat (10.49s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (3.15s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (2.57s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (2.40s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (2.37s) === RUN TestRcatMetadata run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" operations_test.go:1532: Skipping as destination doesn't support user metadata --- SKIP: TestRcatMetadata (0.37s) === RUN TestRcatSize run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" 2025/07/10 01:38:04 DEBUG : B2 bucket rclone-test-jasibur1wagu: File to upload is small (60 bytes), uploading instead of streaming 2025/07/10 01:38:04 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2025/07/10 01:38:04 DEBUG : potato2: Size and sha1 of src and dst objects identical --- PASS: TestRcatSize (1.81s) === RUN TestRcatSizeMetadata run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" operations_test.go:1613: Skipping as destination doesn't support user metadata --- SKIP: TestRcatSizeMetadata (0.37s) === RUN TestTouchDir run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" 2025/07/10 01:38:07 DEBUG : B2 bucket rclone-test-jasibur1wagu: Touching "sub dir/potato3" 2025/07/10 01:38:07 DEBUG : B2 bucket rclone-test-jasibur1wagu: Touching "empty space" 2025/07/10 01:38:07 DEBUG : B2 bucket rclone-test-jasibur1wagu: Touching "potato2" --- PASS: TestTouchDir (4.59s) === RUN TestMkdirMetadata run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" operations_test.go:1703: Skipping test as remote does not support MkdirMetadata --- SKIP: TestMkdirMetadata (0.37s) === RUN TestMkdirModTime run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" operations_test.go:1722: Skipping test as remote does not support DirSetModTime or MkdirMetadata --- SKIP: TestMkdirModTime (0.37s) === RUN TestCopyDirMetadata run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" operations_test.go:1743: Skipping test as remote does not support WriteDirMetadata or MkdirMetadata --- SKIP: TestCopyDirMetadata (0.37s) === RUN TestSetDirModTime run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" operations_test.go:1779: Skipping test as remote does not support DirSetModTime or WriteDirSetModTime --- SKIP: TestSetDirModTime (0.37s) === RUN TestDirsEqual run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" operations_test.go:1823: Skipping test as remote does not support WriteDirMetadata or MkdirMetadata --- SKIP: TestDirsEqual (0.37s) === RUN TestRemoveExisting run.go:180: Remote "B2 bucket rclone-test-jasibur1wagu", Local "Local file system at /tmp/rclone1985956025", Modify Window "1ms" operations_test.go:1892: Skipping as remote can't Move --- SKIP: TestRemoveExisting (0.37s) === RUN TestRcAbout rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcAbout (0.00s) === RUN TestRcCleanup rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcCleanup (0.00s) === RUN TestRcCopyfile rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcCopyfile (0.00s) === RUN TestRcCopyurl rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcCopyurl (0.00s) === RUN TestRcDelete rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcDelete (0.00s) === RUN TestRcDeletefile rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcDeletefile (0.00s) === RUN TestRcList rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcList (0.00s) === RUN TestRcStat rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcStat (0.00s) === RUN TestRcSetTier rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcSetTier (0.00s) === RUN TestRcSetTierFile rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcSetTierFile (0.00s) === RUN TestRcMkdir rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcMkdir (0.00s) === RUN TestRcMovefile rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcMovefile (0.00s) === RUN TestRcPurge rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcPurge (0.00s) === RUN TestRcRmdir rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcRmdir (0.00s) === RUN TestRcRmdirs rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcRmdirs (0.00s) === RUN TestRcSize rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcSize (0.00s) === RUN TestRcPublicLink rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcPublicLink (0.00s) === RUN TestRcFsInfo rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcFsInfo (0.00s) === RUN TestUploadFile rc_test.go:30: Skipping test on non local remote --- SKIP: TestUploadFile (0.00s) === RUN TestRcCommand rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcCommand (0.00s) === RUN TestRcDu rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcDu (0.00s) === RUN TestRcCheck rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcCheck (0.00s) === RUN TestRcHashsum rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcHashsum (0.00s) === RUN TestRcHashsumFile rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcHashsumFile (0.00s) FAIL 2025/07/10 01:38:12 DEBUG : B2 bucket rclone-test-jasibur1wagu: Purge remote 2025/07/10 01:38:12 INFO : B2 bucket rclone-test-jasibur1wagu: cleaning bucket "rclone-test-jasibur1wagu" of all files 2025/07/10 01:38:13 DEBUG : A1/B1/C1/four: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f417693e64a4ef9aa_d20250710_m013748_c001_v7007000_t0000_u01752111468085") 2025/07/10 01:38:13 DEBUG : A1/B1/C1/four: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4fa5_d20250710_m013745_c001_v0001098_t0057_u01752111465493") 2025/07/10 01:38:13 DEBUG : A1/B1/C1/one: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f40459ab87947a9d6_d20250710_m013729_c001_v7007000_t0000_u01752111449312") 2025/07/10 01:38:13 DEBUG : A1/B1/C1/one: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4f2b_d20250710_m013726_c001_v0001098_t0015_u01752111446103") 2025/07/10 01:38:13 DEBUG : A1/B1/C2/five: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f403021f69381291b_d20250710_m013747_c001_v7007000_t0000_u01752111467868") 2025/07/10 01:38:13 DEBUG : A1/B1/C2/five: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4fa9_d20250710_m013745_c001_v0001098_t0054_u01752111465863") 2025/07/10 01:38:13 DEBUG : A1/B1/three: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f40459ab87947a9eb_d20250710_m013748_c001_v7007000_t0000_u01752111468122") 2025/07/10 01:38:13 DEBUG : A1/B1/three: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4fa3_d20250710_m013745_c001_v0001098_t0041_u01752111465179") 2025/07/10 01:38:13 DEBUG : A1/one: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f422f3d942eda2c3b_d20250710_m013748_c001_v7007000_t0000_u01752111468221") 2025/07/10 01:38:13 DEBUG : A1/one: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4f9b_d20250710_m013744_c001_v0001098_t0045_u01752111464446") 2025/07/10 01:38:13 DEBUG : A1/two: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4078888abea501cf_d20250710_m013747_c001_v7007000_t0000_u01752111467985") 2025/07/10 01:38:13 DEBUG : A1/two: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4f9d_d20250710_m013744_c001_v0001098_t0042_u01752111464828") 2025/07/10 01:38:14 DEBUG : A1/two: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f40459ab87947a9d7_d20250710_m013729_c001_v7007000_t0000_u01752111449684") 2025/07/10 01:38:14 DEBUG : A1/two: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4f2d_d20250710_m013726_c001_v0001098_t0059_u01752111446429") 2025/07/10 01:38:14 DEBUG : A2/B1/C1/four: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f422f3d942eda2c3e_d20250710_m013749_c001_v7007000_t0000_u01752111469480") 2025/07/10 01:38:14 DEBUG : A2/B1/C1/four: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f111a6d6836662b88_d20250710_m013747_c001_v0001157_t0051_u01752111467683") 2025/07/10 01:38:14 DEBUG : A2/B1/C2/five: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f40459ab87947a9ef_d20250710_m013749_c001_v7007000_t0000_u01752111469490") 2025/07/10 01:38:14 DEBUG : A2/B1/C2/five: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f1093b8c8ea57d00c_d20250710_m013747_c001_v0001183_t0012_u01752111467669") 2025/07/10 01:38:14 DEBUG : A2/B1/three: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f417693e64a4ef9ac_d20250710_m013749_c001_v7007000_t0000_u01752111469434") 2025/07/10 01:38:14 DEBUG : A2/B1/three: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f1045d9794e2e0ce9_d20250710_m013747_c001_v0001106_t0021_u01752111467288") 2025/07/10 01:38:14 DEBUG : A2/one: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4078888abea501d0_d20250710_m013749_c001_v7007000_t0000_u01752111469234") 2025/07/10 01:38:14 DEBUG : A2/one: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f1155d932cfe059c3_d20250710_m013747_c001_v0001157_t0000_u01752111467664") 2025/07/10 01:38:14 DEBUG : A2/two: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f403021f69381291c_d20250710_m013749_c001_v7007000_t0000_u01752111469640") 2025/07/10 01:38:14 DEBUG : A2/two: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f101beb6c7619882c_d20250710_m013747_c001_v0001145_t0029_u01752111467688") 2025/07/10 01:38:14 DEBUG : A3/B1/C1/four: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f403021f69381291d_d20250710_m013750_c001_v7007000_t0000_u01752111470638") 2025/07/10 01:38:14 DEBUG : A3/B1/C1/four: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f117331ee942c5238_d20250710_m013748_c001_v0001109_t0008_u01752111468982") 2025/07/10 01:38:14 DEBUG : A3/B1/C2/five: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f40459ab87947a9f0_d20250710_m013750_c001_v7007000_t0000_u01752111470691") 2025/07/10 01:38:14 DEBUG : A3/B1/C2/five: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11037af726745e58_d20250710_m013748_c001_v0001171_t0027_u01752111468996") 2025/07/10 01:38:14 DEBUG : A3/B1/three: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f422f3d942eda2c3f_d20250710_m013750_c001_v7007000_t0000_u01752111470898") 2025/07/10 01:38:14 DEBUG : A3/B1/three: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f103a5341f2ae6987_d20250710_m013749_c001_v0001150_t0053_u01752111469010") 2025/07/10 01:38:14 DEBUG : A3/one: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f417693e64a4ef9ad_d20250710_m013750_c001_v7007000_t0000_u01752111470797") 2025/07/10 01:38:15 DEBUG : A3/one: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f1197c83808a068dc_d20250710_m013749_c001_v0001181_t0026_u01752111469020") 2025/07/10 01:38:15 DEBUG : A3/two: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4078888abea501d1_d20250710_m013751_c001_v7007000_t0000_u01752111471177") 2025/07/10 01:38:15 DEBUG : A3/two: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f102dae87d125ed35_d20250710_m013749_c001_v0001043_t0052_u01752111469038") 2025/07/10 01:38:15 DEBUG : A4/B1/C1/four: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4078888abea501d2_d20250710_m013751_c001_v7007000_t0000_u01752111471745") 2025/07/10 01:38:15 DEBUG : A4/B1/C1/four: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f110b81c3a6f25387_d20250710_m013750_c001_v0001177_t0051_u01752111470425") 2025/07/10 01:38:15 DEBUG : A4/B1/C2/five: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4078888abea501d3_d20250710_m013751_c001_v7007000_t0000_u01752111471927") 2025/07/10 01:38:15 DEBUG : A4/B1/C2/five: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f118e987009a8b851_d20250710_m013750_c001_v0001171_t0038_u01752111470428") 2025/07/10 01:38:15 DEBUG : A4/B1/three: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4078888abea501d4_d20250710_m013752_c001_v7007000_t0000_u01752111472109") 2025/07/10 01:38:15 DEBUG : A4/B1/three: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f113afc7d907b2cbc_d20250710_m013750_c001_v0001106_t0021_u01752111470437") 2025/07/10 01:38:15 DEBUG : A4/one: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4078888abea501d5_d20250710_m013752_c001_v7007000_t0000_u01752111472309") 2025/07/10 01:38:15 DEBUG : A4/one: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f114e8925361a3a88_d20250710_m013750_c001_v0001134_t0040_u01752111470450") 2025/07/10 01:38:15 DEBUG : A4/two: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4078888abea501d6_d20250710_m013752_c001_v7007000_t0000_u01752111472491") 2025/07/10 01:38:15 DEBUG : A4/two: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f104a9ec30d389440_d20250710_m013750_c001_v0001117_t0029_u01752111470461") 2025/07/10 01:38:15 DEBUG : BackupDir/one: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f416b1689df3cc005_d20250710_m013611_c001_v7007000_t0000_u01752111371223") 2025/07/10 01:38:15 DEBUG : BackupDir/one: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f107d6b770d9860a5_d20250710_m013605_c001_v0001181_t0032_u01752111365222") 2025/07/10 01:38:15 DEBUG : CompareDest/one: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f416b1689df3cbffd_d20250710_m013555_c001_v7007000_t0000_u01752111355140") 2025/07/10 01:38:15 DEBUG : CompareDest/one: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4c0b_d20250710_m013551_c001_v0001098_t0052_u01752111351014") 2025/07/10 01:38:15 DEBUG : CompareDest/two: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f416b1689df3cbffe_d20250710_m013555_c001_v7007000_t0000_u01752111355321") 2025/07/10 01:38:15 DEBUG : CompareDest/two: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4c1d_d20250710_m013552_c001_v0001098_t0041_u01752111352209") 2025/07/10 01:38:15 DEBUG : CopyDest/one: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f416b1689df3cc007_d20250710_m013611_c001_v7007000_t0000_u01752111371405") 2025/07/10 01:38:16 DEBUG : CopyDest/one: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4c65_d20250710_m013602_c001_v0001098_t0040_u01752111362253") 2025/07/10 01:38:16 DEBUG : CopyDest/three: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f416b1689df3cc008_d20250710_m013611_c001_v7007000_t0000_u01752111371600") 2025/07/10 01:38:16 DEBUG : CopyDest/three: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4c9e_d20250710_m013609_c001_v0001098_t0053_u01752111369003") 2025/07/10 01:38:16 DEBUG : CopyDest/two: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f416b1689df3cc009_d20250710_m013611_c001_v7007000_t0000_u01752111371784") 2025/07/10 01:38:16 DEBUG : CopyDest/two: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4c91_d20250710_m013606_c001_v0001098_t0013_u01752111366820") 2025/07/10 01:38:16 DEBUG : ReadFile: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f419ffae81cf6445e_d20250710_m013715_c001_v7007000_t0000_u01752111435738") 2025/07/10 01:38:16 DEBUG : ReadFile: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4ecb_d20250710_m013714_c001_v0001098_t0034_u01752111434278") 2025/07/10 01:38:16 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f416b1689df3cc00d_d20250710_m013617_c001_v7007000_t0000_u01752111377239") 2025/07/10 01:38:16 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4cc6_d20250710_m013613_c001_v0001098_t0046_u01752111373974") 2025/07/10 01:38:16 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f416b1689df3cc00e_d20250710_m013617_c001_v7007000_t0000_u01752111377421") 2025/07/10 01:38:16 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4cde_d20250710_m013616_c001_v0001098_t0020_u01752111376266") 2025/07/10 01:38:16 DEBUG : a.txt: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4024a9f405223285_d20250710_m013637_c001_v7007000_t0000_u01752111397533") 2025/07/10 01:38:16 DEBUG : a.txt: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4d4c_d20250710_m013632_c001_v0001098_t0020_u01752111392366") 2025/07/10 01:38:16 DEBUG : a.txt: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4024a9f40522327b_d20250710_m013630_c001_v7007000_t0000_u01752111390883") 2025/07/10 01:38:16 DEBUG : a.txt: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4d0e_d20250710_m013624_c001_v0001098_t0053_u01752111384738") 2025/07/10 01:38:16 DEBUG : also/one: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f416b1689df3cc010_d20250710_m013621_c001_v7007000_t0000_u01752111381832") 2025/07/10 01:38:16 DEBUG : also/one: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4cfa_d20250710_m013619_c001_v0001098_t0016_u01752111379975") 2025/07/10 01:38:16 DEBUG : another: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f416b1689df3cc011_d20250710_m013622_c001_v7007000_t0000_u01752111382375") 2025/07/10 01:38:16 DEBUG : another: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4cfc_d20250710_m013620_c001_v0001098_t0056_u01752111380317") 2025/07/10 01:38:16 DEBUG : backup/dst/file1: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f40459ab87947a9e9_d20250710_m013743_c001_v7007000_t0000_u01752111463891") 2025/07/10 01:38:16 DEBUG : backup/dst/file1: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f117cfd6ade3aaa47_d20250710_m013742_c001_v0001183_t0041_u01752111462766") 2025/07/10 01:38:16 DEBUG : backup/dst/file1: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f416b1689df3cbff9_d20250710_m013544_c001_v7007000_t0000_u01752111344026") 2025/07/10 01:38:17 DEBUG : backup/dst/file1: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f1191680abf17d5c2_d20250710_m013542_c001_v0001178_t0018_u01752111342866") 2025/07/10 01:38:17 DEBUG : data/banana: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4085bdfc9dc6788e_d20250710_m013449_c001_v7007000_t0000_u01752111289812") 2025/07/10 01:38:17 DEBUG : data/banana: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db49ff_d20250710_m013447_c001_v0001098_t0045_u01752111287325") 2025/07/10 01:38:17 DEBUG : data/banana: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db499f_d20250710_m013438_c001_v0001098_t0004_u01752111278065") 2025/07/10 01:38:17 DEBUG : data/potato: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4085bdfc9dc6788f_d20250710_m013449_c001_v7007000_t0000_u01752111289996") 2025/07/10 01:38:17 DEBUG : data/potato: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4a05_d20250710_m013447_c001_v0001098_t0004_u01752111287795") 2025/07/10 01:38:17 DEBUG : data/potato: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db49b3_d20250710_m013440_c001_v0001098_t0021_u01752111280457") 2025/07/10 01:38:17 DEBUG : dst/file1: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f40459ab87947a9ea_d20250710_m013744_c001_v7007000_t0000_u01752111464075") 2025/07/10 01:38:17 DEBUG : dst/file1: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4f91_d20250710_m013743_c001_v0001098_t0029_u01752111463156") 2025/07/10 01:38:17 DEBUG : dst/file1: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f40459ab87947a9e7_d20250710_m013742_c001_v7007000_t0000_u01752111462969") 2025/07/10 01:38:17 DEBUG : dst/file1: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4f8d_d20250710_m013740_c001_v0001098_t0033_u01752111460013") 2025/07/10 01:38:17 DEBUG : dst/file1: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f416b1689df3cbffa_d20250710_m013544_c001_v7007000_t0000_u01752111344209") 2025/07/10 01:38:17 DEBUG : dst/file1: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4bd5_d20250710_m013543_c001_v0001098_t0005_u01752111343310") 2025/07/10 01:38:17 DEBUG : dst/file1: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f416b1689df3cbff8_d20250710_m013543_c001_v7007000_t0000_u01752111343122") 2025/07/10 01:38:17 DEBUG : dst/file1: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4bb1_d20250710_m013539_c001_v0001098_t0045_u01752111339963") 2025/07/10 01:38:17 DEBUG : dst/one: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f416b1689df3cc00a_d20250710_m013611_c001_v7007000_t0000_u01752111371966") 2025/07/10 01:38:17 DEBUG : dst/one: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f1196e88b4a3cf2c5_d20250710_m013605_c001_v0001116_t0058_u01752111365995") 2025/07/10 01:38:17 DEBUG : dst/one: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f422f3d942eda282d_d20250710_m013605_c001_v7007000_t0000_u01752111365792") 2025/07/10 01:38:17 DEBUG : dst/one: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4c5d_d20250710_m013601_c001_v0001098_t0056_u01752111361691") 2025/07/10 01:38:17 DEBUG : dst/one: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f107cddcc765b6360_d20250710_m013600_c001_v0001180_t0046_u01752111360910") 2025/07/10 01:38:17 DEBUG : dst/one: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f107cddcc765b6354_d20250710_m013559_c001_v0001180_t0051_u01752111359977") 2025/07/10 01:38:18 DEBUG : dst/one: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f416b1689df3cbfff_d20250710_m013555_c001_v7007000_t0000_u01752111355504") 2025/07/10 01:38:18 DEBUG : dst/one: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4c05_d20250710_m013550_c001_v0001098_t0015_u01752111350681") 2025/07/10 01:38:18 DEBUG : dst/one: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11187f35e4975090_d20250710_m013549_c001_v0001036_t0022_u01752111349621") 2025/07/10 01:38:18 DEBUG : dst/one: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11187f35e497508a_d20250710_m013548_c001_v0001036_t0017_u01752111348460") 2025/07/10 01:38:18 DEBUG : dst/three: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f416b1689df3cc00b_d20250710_m013612_c001_v7007000_t0000_u01752111372148") 2025/07/10 01:38:18 DEBUG : dst/three: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f107cddcc765b63a8_d20250710_m013610_c001_v0001180_t0056_u01752111370577") 2025/07/10 01:38:18 DEBUG : dst/two: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f416b1689df3cc00c_d20250710_m013612_c001_v7007000_t0000_u01752111372330") 2025/07/10 01:38:18 DEBUG : dst/two: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f115a3d98559d1dd6_d20250710_m013607_c001_v0001037_t0051_u01752111367955") 2025/07/10 01:38:18 DEBUG : dst/two: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f416b1689df3cc000_d20250710_m013555_c001_v7007000_t0000_u01752111355726") 2025/07/10 01:38:18 DEBUG : dst/two: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11187f35e49750a6_d20250710_m013554_c001_v0001036_t0013_u01752111354391") 2025/07/10 01:38:18 DEBUG : empty space: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4112e27c5940d230_d20250710_m013810_c001_v7007000_t0000_u01752111490146") 2025/07/10 01:38:18 DEBUG : empty space: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f1107ad797c1698d7_d20250710_m013808_c001_v0001184_t0056_u01752111488856") 2025/07/10 01:38:18 DEBUG : empty space: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db5075_d20250710_m013806_c001_v0001098_t0040_u01752111486681") 2025/07/10 01:38:18 DEBUG : empty space: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4024a9f40522329e_d20250710_m013701_c001_v7007000_t0000_u01752111421754") 2025/07/10 01:38:18 DEBUG : empty space: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4e3f_d20250710_m013700_c001_v0001098_t0015_u01752111420180") 2025/07/10 01:38:18 DEBUG : empty space: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4024a9f40522329c_d20250710_m013659_c001_v7007000_t0000_u01752111419259") 2025/07/10 01:38:18 DEBUG : empty space: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4e2d_d20250710_m013658_c001_v0001098_t0030_u01752111418021") 2025/07/10 01:38:18 DEBUG : empty space: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4024a9f405223297_d20250710_m013657_c001_v7007000_t0000_u01752111417077") 2025/07/10 01:38:18 DEBUG : empty space: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4e1f_d20250710_m013656_c001_v0001098_t0032_u01752111416231") 2025/07/10 01:38:18 DEBUG : empty space: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4024a9f405223295_d20250710_m013655_c001_v7007000_t0000_u01752111415268") 2025/07/10 01:38:18 DEBUG : empty space: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4e0f_d20250710_m013653_c001_v0001098_t0057_u01752111413746") 2025/07/10 01:38:19 DEBUG : empty space: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4024a9f405223293_d20250710_m013652_c001_v7007000_t0000_u01752111412857") 2025/07/10 01:38:19 DEBUG : empty space: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4e01_d20250710_m013651_c001_v0001098_t0035_u01752111411989") 2025/07/10 01:38:19 DEBUG : empty space: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4085bdfc9dc67887_d20250710_m013432_c001_v7007000_t0000_u01752111272072") 2025/07/10 01:38:19 DEBUG : empty space: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4951_d20250710_m013428_c001_v0001098_t0004_u01752111268838") 2025/07/10 01:38:19 DEBUG : empty space: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4085bdfc9dc67882_d20250710_m013427_c001_v7007000_t0000_u01752111267029") 2025/07/10 01:38:19 DEBUG : empty space: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4921_d20250710_m013423_c001_v0001098_t0013_u01752111263066") 2025/07/10 01:38:19 DEBUG : empty space: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4085bdfc9dc67878_d20250710_m013420_c001_v7007000_t0000_u01752111260424") 2025/07/10 01:38:19 DEBUG : empty space: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db48e9_d20250710_m013417_c001_v0001098_t0040_u01752111257656") 2025/07/10 01:38:19 DEBUG : file1: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f40459ab87947a9e6_d20250710_m013738_c001_v7007000_t0000_u01752111458896") 2025/07/10 01:38:19 DEBUG : file1: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4f75_d20250710_m013737_c001_v0001098_t0027_u01752111457860") 2025/07/10 01:38:19 DEBUG : file1: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f40459ab87947a9dd_d20250710_m013734_c001_v7007000_t0000_u01752111454835") 2025/07/10 01:38:19 DEBUG : file1: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f41833f0155db4f49_d20250710_m013733_c001_v0001098_t0046_u01752111453045") 2025/07/10 01:38:19 DEBUG : file1: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f419ffae81cf64462_d20250710_m013720_c001_v7007000_t0000_u01752111440411") 2025/07/10 01:38:19 DEBUG : file1: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4ed5_d20250710_m013716_c001_v0001098_t0011_u01752111436546") 2025/07/10 01:38:19 DEBUG : file1: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4024a9f405223290_d20250710_m013648_c001_v7007000_t0000_u01752111408723") 2025/07/10 01:38:19 DEBUG : file1: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4daf_d20250710_m013642_c001_v0001098_t0033_u01752111402750") 2025/07/10 01:38:19 DEBUG : file1: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4024a9f40522328e_d20250710_m013642_c001_v7007000_t0000_u01752111402174") 2025/07/10 01:38:19 DEBUG : file1: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4d93_d20250710_m013639_c001_v0001098_t0010_u01752111399031") 2025/07/10 01:38:19 DEBUG : file2: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f40459ab87947a9de_d20250710_m013735_c001_v7007000_t0000_u01752111455017") 2025/07/10 01:38:19 DEBUG : file2: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f41833f0155db4f55_d20250710_m013734_c001_v0001098_t0046_u01752111454284") 2025/07/10 01:38:19 DEBUG : file2: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f419ffae81cf64463_d20250710_m013720_c001_v7007000_t0000_u01752111440595") 2025/07/10 01:38:20 DEBUG : file2: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4ed7_d20250710_m013716_c001_v0001098_t0034_u01752111436880") 2025/07/10 01:38:20 DEBUG : filename.txt: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f40459ab87947a9df_d20250710_m013735_c001_v7007000_t0000_u01752111455200") 2025/07/10 01:38:20 DEBUG : filename.txt: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4f4f_d20250710_m013733_c001_v0001098_t0019_u01752111453600") 2025/07/10 01:38:20 DEBUG : headerfilename.txt: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f40459ab87947a9e1_d20250710_m013735_c001_v7007000_t0000_u01752111455384") 2025/07/10 01:38:20 DEBUG : headerfilename.txt: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4f51_d20250710_m013733_c001_v0001098_t0032_u01752111453914") 2025/07/10 01:38:20 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4078888abea501df_d20250710_m013803_c001_v7007000_t0000_u01752111483185") 2025/07/10 01:38:20 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db5045_d20250710_m013801_c001_v0001098_t0031_u01752111481882") 2025/07/10 01:38:20 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4078888abea501dc_d20250710_m013800_c001_v7007000_t0000_u01752111480802") 2025/07/10 01:38:20 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db501f_d20250710_m013759_c001_v0001098_t0052_u01752111479462") 2025/07/10 01:38:20 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4078888abea501e0_d20250710_m013803_c001_v7007000_t0000_u01752111483367") 2025/07/10 01:38:20 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db503f_d20250710_m013801_c001_v0001098_t0008_u01752111481553") 2025/07/10 01:38:20 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4078888abea501dd_d20250710_m013800_c001_v7007000_t0000_u01752111480983") 2025/07/10 01:38:20 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db501b_d20250710_m013759_c001_v0001098_t0039_u01752111479153") 2025/07/10 01:38:20 DEBUG : large: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f422f3d942eda2c2d_d20250710_m013712_c001_v7007000_t0000_u01752111432970") 2025/07/10 01:38:20 DEBUG : large: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4eb9_d20250710_m013712_c001_v0001098_t0057_u01752111432093") 2025/07/10 01:38:20 DEBUG : large: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f422f3d942eda2c2c_d20250710_m013710_c001_v7007000_t0000_u01752111430485") 2025/07/10 01:38:20 DEBUG : large: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4e97_d20250710_m013709_c001_v0001098_t0022_u01752111429230") 2025/07/10 01:38:20 DEBUG : large: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f419ffae81cf6444e_d20250710_m013706_c001_v7007000_t0000_u01752111426839") 2025/07/10 01:38:20 DEBUG : large: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4e81_d20250710_m013706_c001_v0001098_t0001_u01752111426122") 2025/07/10 01:38:20 DEBUG : large: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f419ffae81cf6444d_d20250710_m013704_c001_v7007000_t0000_u01752111424868") 2025/07/10 01:38:21 DEBUG : large: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4e67_d20250710_m013703_c001_v0001098_t0011_u01752111423404") 2025/07/10 01:38:21 DEBUG : medium: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f419ffae81cf64457_d20250710_m013712_c001_v7007000_t0000_u01752111432978") 2025/07/10 01:38:21 DEBUG : medium: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4eb1_d20250710_m013711_c001_v0001098_t0034_u01752111431770") 2025/07/10 01:38:21 DEBUG : medium: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f422f3d942eda2c2b_d20250710_m013709_c001_v7007000_t0000_u01752111429903") 2025/07/10 01:38:21 DEBUG : medium: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4e93_d20250710_m013708_c001_v0001098_t0048_u01752111428666") 2025/07/10 01:38:21 DEBUG : medium: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f419ffae81cf6444f_d20250710_m013707_c001_v7007000_t0000_u01752111427034") 2025/07/10 01:38:21 DEBUG : medium: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4e75_d20250710_m013705_c001_v0001098_t0037_u01752111425627") 2025/07/10 01:38:21 DEBUG : medium: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4024a9f4052232a1_d20250710_m013704_c001_v7007000_t0000_u01752111424117") 2025/07/10 01:38:21 DEBUG : medium: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4e63_d20250710_m013702_c001_v0001098_t0050_u01752111422994") 2025/07/10 01:38:21 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4078888abea501d7_d20250710_m013755_c001_v7007000_t0000_u01752111475847") 2025/07/10 01:38:21 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4ffb_d20250710_m013754_c001_v0001098_t0029_u01752111474432") 2025/07/10 01:38:21 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4078888abea501d8_d20250710_m013756_c001_v7007000_t0000_u01752111476028") 2025/07/10 01:38:21 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4ff5_d20250710_m013753_c001_v0001098_t0054_u01752111473798") 2025/07/10 01:38:21 DEBUG : not-one: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f416b1689df3cc012_d20250710_m013622_c001_v7007000_t0000_u01752111382556") 2025/07/10 01:38:21 DEBUG : not-one: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4d02_d20250710_m013620_c001_v0001098_t0006_u01752111380901") 2025/07/10 01:38:21 DEBUG : one: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f416b1689df3cc00f_d20250710_m013621_c001_v7007000_t0000_u01752111381650") 2025/07/10 01:38:21 DEBUG : one: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4cf8_d20250710_m013619_c001_v0001098_t0018_u01752111379647") 2025/07/10 01:38:21 DEBUG : potato1: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4078888abea501e2_d20250710_m013805_c001_v7007000_t0000_u01752111485361") 2025/07/10 01:38:21 DEBUG : potato1: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db505d_d20250710_m013804_c001_v0001098_t0052_u01752111484106") 2025/07/10 01:38:21 DEBUG : potato2: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4112e27c5940d231_d20250710_m013810_c001_v7007000_t0000_u01752111490327") 2025/07/10 01:38:21 DEBUG : potato2: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f106c0289c24c13c3_d20250710_m013808_c001_v0001106_t0043_u01752111488916") 2025/07/10 01:38:22 DEBUG : potato2: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db5073_d20250710_m013806_c001_v0001098_t0009_u01752111486283") 2025/07/10 01:38:22 DEBUG : potato2: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4078888abea501e3_d20250710_m013805_c001_v7007000_t0000_u01752111485542") 2025/07/10 01:38:22 DEBUG : potato2: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db5063_d20250710_m013804_c001_v0001098_t0041_u01752111484555") 2025/07/10 01:38:22 DEBUG : potato2: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4024a9f40522329f_d20250710_m013701_c001_v7007000_t0000_u01752111421956") 2025/07/10 01:38:22 DEBUG : potato2: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4e3b_d20250710_m013659_c001_v0001098_t0021_u01752111419822") 2025/07/10 01:38:22 DEBUG : potato2: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4024a9f40522329d_d20250710_m013659_c001_v7007000_t0000_u01752111419444") 2025/07/10 01:38:22 DEBUG : potato2: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4e25_d20250710_m013657_c001_v0001098_t0021_u01752111417665") 2025/07/10 01:38:22 DEBUG : potato2: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4024a9f405223298_d20250710_m013657_c001_v7007000_t0000_u01752111417285") 2025/07/10 01:38:22 DEBUG : potato2: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4e1d_d20250710_m013655_c001_v0001098_t0049_u01752111415831") 2025/07/10 01:38:22 DEBUG : potato2: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4024a9f405223296_d20250710_m013655_c001_v7007000_t0000_u01752111415453") 2025/07/10 01:38:22 DEBUG : potato2: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4e0d_d20250710_m013653_c001_v0001098_t0023_u01752111413426") 2025/07/10 01:38:22 DEBUG : potato2: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4024a9f405223294_d20250710_m013653_c001_v7007000_t0000_u01752111413043") 2025/07/10 01:38:22 DEBUG : potato2: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4dff_d20250710_m013651_c001_v0001098_t0056_u01752111411575") 2025/07/10 01:38:22 DEBUG : potato2: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4085bdfc9dc67888_d20250710_m013432_c001_v7007000_t0000_u01752111272256") 2025/07/10 01:38:22 DEBUG : potato2: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4957_d20250710_m013429_c001_v0001098_t0053_u01752111269692") 2025/07/10 01:38:22 DEBUG : potato2: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4085bdfc9dc67883_d20250710_m013427_c001_v7007000_t0000_u01752111267214") 2025/07/10 01:38:22 DEBUG : potato2: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db492b_d20250710_m013424_c001_v0001098_t0011_u01752111264042") 2025/07/10 01:38:22 DEBUG : potato2: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4085bdfc9dc67879_d20250710_m013420_c001_v7007000_t0000_u01752111260608") 2025/07/10 01:38:22 DEBUG : potato2: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db48f5_d20250710_m013418_c001_v0001098_t0051_u01752111258306") 2025/07/10 01:38:22 DEBUG : remotepotato: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4085bdfc9dc67889_d20250710_m013432_c001_v7007000_t0000_u01752111272467") 2025/07/10 01:38:22 DEBUG : remotepotato: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4967_d20250710_m013430_c001_v0001098_t0040_u01752111270997") 2025/07/10 01:38:22 DEBUG : remotepotato: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4085bdfc9dc67884_d20250710_m013427_c001_v7007000_t0000_u01752111267396") 2025/07/10 01:38:23 DEBUG : remotepotato: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4933_d20250710_m013425_c001_v0001098_t0028_u01752111265584") 2025/07/10 01:38:23 DEBUG : remotepotato: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4085bdfc9dc6787a_d20250710_m013420_c001_v7007000_t0000_u01752111260792") 2025/07/10 01:38:23 DEBUG : remotepotato: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4903_d20250710_m013419_c001_v0001098_t0018_u01752111259400") 2025/07/10 01:38:23 DEBUG : rutabaga: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4085bdfc9dc6788a_d20250710_m013432_c001_v7007000_t0000_u01752111272654") 2025/07/10 01:38:23 DEBUG : rutabaga: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4943_d20250710_m013427_c001_v0001098_t0019_u01752111267965") 2025/07/10 01:38:23 DEBUG : rutabaga: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4085bdfc9dc67885_d20250710_m013427_c001_v7007000_t0000_u01752111267580") 2025/07/10 01:38:23 DEBUG : rutabaga: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4915_d20250710_m013421_c001_v0001098_t0036_u01752111261362") 2025/07/10 01:38:23 DEBUG : rutabaga: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4085bdfc9dc6787b_d20250710_m013420_c001_v7007000_t0000_u01752111260975") 2025/07/10 01:38:23 DEBUG : rutabaga: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db48df_d20250710_m013416_c001_v0001098_t0035_u01752111256747") 2025/07/10 01:38:23 DEBUG : small: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f419ffae81cf64458_d20250710_m013713_c001_v7007000_t0000_u01752111433533") 2025/07/10 01:38:23 DEBUG : small: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4eab_d20250710_m013711_c001_v0001098_t0012_u01752111431230") 2025/07/10 01:38:23 DEBUG : small: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f419ffae81cf64454_d20250710_m013709_c001_v7007000_t0000_u01752111429888") 2025/07/10 01:38:23 DEBUG : small: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4e8f_d20250710_m013708_c001_v0001098_t0028_u01752111428329") 2025/07/10 01:38:23 DEBUG : small: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f419ffae81cf64451_d20250710_m013707_c001_v7007000_t0000_u01752111427585") 2025/07/10 01:38:23 DEBUG : small: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4e71_d20250710_m013705_c001_v0001098_t0054_u01752111425241") 2025/07/10 01:38:23 DEBUG : small: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4024a9f4052232a2_d20250710_m013704_c001_v7007000_t0000_u01752111424302") 2025/07/10 01:38:23 DEBUG : small: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4e5b_d20250710_m013702_c001_v0001098_t0001_u01752111422534") 2025/07/10 01:38:23 DEBUG : sub dir/hello world: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4024a9f405223292_d20250710_m013651_c001_v7007000_t0000_u01752111411199") 2025/07/10 01:38:23 DEBUG : sub dir/hello world: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4def_d20250710_m013650_c001_v0001098_t0059_u01752111410195") 2025/07/10 01:38:23 DEBUG : sub dir/hello world: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4024a9f405223286_d20250710_m013637_c001_v7007000_t0000_u01752111397720") 2025/07/10 01:38:24 DEBUG : sub dir/hello world: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4d56_d20250710_m013633_c001_v0001098_t0052_u01752111393040") 2025/07/10 01:38:24 DEBUG : sub dir/hello world: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4024a9f40522327c_d20250710_m013631_c001_v7007000_t0000_u01752111391066") 2025/07/10 01:38:24 DEBUG : sub dir/hello world: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4d1c_d20250710_m013625_c001_v0001098_t0005_u01752111385666") 2025/07/10 01:38:24 DEBUG : sub dir/hello world2: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4024a9f405223287_d20250710_m013637_c001_v7007000_t0000_u01752111397904") 2025/07/10 01:38:24 DEBUG : sub dir/hello world2: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4d58_d20250710_m013633_c001_v0001098_t0027_u01752111393437") 2025/07/10 01:38:24 DEBUG : sub dir/hello world2: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4024a9f40522327d_d20250710_m013631_c001_v7007000_t0000_u01752111391249") 2025/07/10 01:38:24 DEBUG : sub dir/hello world2: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4d20_d20250710_m013626_c001_v0001098_t0012_u01752111386107") 2025/07/10 01:38:24 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4024a9f405223288_d20250710_m013638_c001_v7007000_t0000_u01752111398101") 2025/07/10 01:38:24 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4d5c_d20250710_m013633_c001_v0001098_t0024_u01752111393930") 2025/07/10 01:38:24 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4024a9f40522327f_d20250710_m013631_c001_v7007000_t0000_u01752111391435") 2025/07/10 01:38:24 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4d24_d20250710_m013626_c001_v0001098_t0035_u01752111386588") 2025/07/10 01:38:24 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4024a9f405223289_d20250710_m013638_c001_v7007000_t0000_u01752111398284") 2025/07/10 01:38:24 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4d60_d20250710_m013634_c001_v0001098_t0019_u01752111394290") 2025/07/10 01:38:24 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4024a9f405223280_d20250710_m013631_c001_v7007000_t0000_u01752111391620") 2025/07/10 01:38:24 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4d28_d20250710_m013626_c001_v0001098_t0030_u01752111386882") 2025/07/10 01:38:24 DEBUG : sub dir/potato3: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4112e27c5940d232_d20250710_m013810_c001_v7007000_t0000_u01752111490508") 2025/07/10 01:38:24 DEBUG : sub dir/potato3: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f10760c8c8d4daaf7_d20250710_m013808_c001_v0001100_t0011_u01752111488532") 2025/07/10 01:38:24 DEBUG : sub dir/potato3: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db5077_d20250710_m013807_c001_v0001098_t0015_u01752111487181") 2025/07/10 01:38:24 DEBUG : sub dir/potato3: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4024a9f4052232a0_d20250710_m013702_c001_v7007000_t0000_u01752111422156") 2025/07/10 01:38:24 DEBUG : sub dir/potato3: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4e47_d20250710_m013700_c001_v0001098_t0014_u01752111420643") 2025/07/10 01:38:25 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4024a9f40522328a_d20250710_m013638_c001_v7007000_t0000_u01752111398468") 2025/07/10 01:38:25 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4d62_d20250710_m013634_c001_v0001098_t0041_u01752111394579") 2025/07/10 01:38:25 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4024a9f405223281_d20250710_m013631_c001_v7007000_t0000_u01752111391805") 2025/07/10 01:38:25 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4d30_d20250710_m013627_c001_v0001098_t0029_u01752111387189") 2025/07/10 01:38:25 DEBUG : sub/file2: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f40459ab87947a9e4_d20250710_m013737_c001_v7007000_t0000_u01752111457303") 2025/07/10 01:38:25 DEBUG : sub/file2: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4f67_d20250710_m013735_c001_v0001098_t0005_u01752111455941") 2025/07/10 01:38:25 DEBUG : sub/file2: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4024a9f405223291_d20250710_m013648_c001_v7007000_t0000_u01752111408908") 2025/07/10 01:38:25 DEBUG : sub/file2: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4db9_d20250710_m013643_c001_v0001098_t0027_u01752111403169") 2025/07/10 01:38:25 DEBUG : sub/file2: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4024a9f40522328f_d20250710_m013642_c001_v7007000_t0000_u01752111402368") 2025/07/10 01:38:25 DEBUG : sub/file2: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4d99_d20250710_m013639_c001_v0001098_t0023_u01752111399565") 2025/07/10 01:38:25 DEBUG : sub/file2: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f416b1689df3cbff7_d20250710_m013539_c001_v7007000_t0000_u01752111339212") 2025/07/10 01:38:25 DEBUG : sub/file2: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4b99_d20250710_m013537_c001_v0001098_t0020_u01752111337423") 2025/07/10 01:38:25 DEBUG : test-multithread-abort: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4085bdfc9dc67874_d20250710_m013415_c001_v7007000_t0000_u01752111255802") 2025/07/10 01:38:25 DEBUG : test-multithread-abort: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db47b1_d20250710_m013335_c001_v0001098_t0011_u01752111215102") 2025/07/10 01:38:25 DEBUG : test-multithread-copy-false-201326591-2: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f402b0539e5975f21_d20250710_m013002_c001_v7007000_t0000_u01752111002035") 2025/07/10 01:38:25 DEBUG : test-multithread-copy-false-201326591-2: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4304_d20250710_m012920_c001_v0001098_t0005_u01752110960509") 2025/07/10 01:38:25 DEBUG : test-multithread-copy-false-201326592-2: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f41454ec2927f503a_d20250710_m013045_c001_v7007000_t0000_u01752111045665") 2025/07/10 01:38:25 DEBUG : test-multithread-copy-false-201326592-2: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db43ba_d20250710_m013005_c001_v0001098_t0011_u01752111005141") 2025/07/10 01:38:25 DEBUG : test-multithread-copy-false-201326593-2: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f40165088f147eba8_d20250710_m013126_c001_v7007000_t0000_u01752111086861") 2025/07/10 01:38:25 DEBUG : test-multithread-copy-false-201326593-2: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db444a_d20250710_m013048_c001_v0001098_t0014_u01752111048690") 2025/07/10 01:38:25 DEBUG : test-multithread-copy-true-201326591-2: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f420e3e9208e06d6d_d20250710_m013207_c001_v7007000_t0000_u01752111127409") 2025/07/10 01:38:26 DEBUG : test-multithread-copy-true-201326591-2: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f2185defaa3c7fbc5_d20250710_m013147_c001_v0001100_t0035_u01752111107344") 2025/07/10 01:38:26 DEBUG : test-multithread-copy-true-201326592-2: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f416473a715d1ac8b_d20250710_m013248_c001_v7007000_t0000_u01752111168930") 2025/07/10 01:38:26 DEBUG : test-multithread-copy-true-201326592-2: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f2180e40eeba2373b_d20250710_m013227_c001_v0001184_t0052_u01752111147940") 2025/07/10 01:38:26 DEBUG : test-multithread-copy-true-201326593-2: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f422f3d942eda23ce_d20250710_m013333_c001_v7007000_t0000_u01752111213792") 2025/07/10 01:38:26 DEBUG : test-multithread-copy-true-201326593-2: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f20165088f147ebcc_d20250710_m013310_c001_v0001165_t0016_u01752111190080") 2025/07/10 01:38:26 DEBUG : test.sum: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4085bdfc9dc67890_d20250710_m013450_c001_v7007000_t0000_u01752111290180") 2025/07/10 01:38:26 DEBUG : test.sum: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4a09_d20250710_m013448_c001_v0001098_t0000_u01752111288345") 2025/07/10 01:38:26 DEBUG : test.sum: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db49f7_d20250710_m013446_c001_v0001098_t0041_u01752111286030") 2025/07/10 01:38:26 DEBUG : test.sum: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db49eb_d20250710_m013444_c001_v0001098_t0020_u01752111284795") 2025/07/10 01:38:26 DEBUG : test.sum: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db49e1_d20250710_m013443_c001_v0001098_t0027_u01752111283540") 2025/07/10 01:38:26 DEBUG : test.sum: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db49d1_d20250710_m013442_c001_v0001098_t0009_u01752111282087") 2025/07/10 01:38:26 DEBUG : test.sum: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db49bb_d20250710_m013440_c001_v0001098_t0023_u01752111280833") 2025/07/10 01:38:26 DEBUG : test.sum: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db49a7_d20250710_m013438_c001_v0001098_t0036_u01752111278561") 2025/07/10 01:38:26 DEBUG : test.sum: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4085bdfc9dc6788b_d20250710_m013435_c001_v7007000_t0000_u01752111275045") 2025/07/10 01:38:26 DEBUG : test.sum: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4985_d20250710_m013434_c001_v0001098_t0024_u01752111274180") 2025/07/10 01:38:26 DEBUG : test.sum: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db497d_d20250710_m013433_c001_v0001098_t0050_u01752111273044") 2025/07/10 01:38:26 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4078888abea501da_d20250710_m013758_c001_v7007000_t0000_u01752111478404") 2025/07/10 01:38:26 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db5005_d20250710_m013756_c001_v0001098_t0011_u01752111476964") 2025/07/10 01:38:26 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4078888abea501db_d20250710_m013758_c001_v7007000_t0000_u01752111478586") 2025/07/10 01:38:26 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4fff_d20250710_m013756_c001_v0001098_t0005_u01752111476584") 2025/07/10 01:38:26 DEBUG : zend.txt: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4024a9f40522328b_d20250710_m013638_c001_v7007000_t0000_u01752111398655") 2025/07/10 01:38:27 DEBUG : zend.txt: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4d50_d20250710_m013632_c001_v0001098_t0036_u01752111392683") 2025/07/10 01:38:27 DEBUG : zend.txt: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f4024a9f405223282_d20250710_m013631_c001_v7007000_t0000_u01752111391991") 2025/07/10 01:38:27 DEBUG : zend.txt: Deleting (id "4_z7f028d03f03dfb1b9f780e1b_f11833f0155db4d16_d20250710_m013625_c001_v0001098_t0028_u01752111385314") "./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose" - Finished OK in 9m12.420971237s (try 1/5)