"./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -fast-list" - Starting (try 1/5) 2025/10/25 03:53:36 DEBUG : Creating backend with remote "TestB2:rclone-test-moyalup4yuje" 2025/10/25 03:53:36 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/10/25 03:53:37 DEBUG : Creating backend with remote "/tmp/rclone3874582328" === 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-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" 2025/10/25 03:53:38 DEBUG : chunksize-probe: Cancelling large file upload === RUN TestMultithreadCopy/upload=false,size=16777215,streams=2 2025/10/25 03:53:43 DEBUG : test-multithread-copy-false-16777215-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2025/10/25 03:53:43 DEBUG : test-multithread-copy-false-16777215-2: multi-thread copy: write buffer set to 131072 2025/10/25 03:53:43 DEBUG : test-multithread-copy-false-16777215-2: multi-thread copy: chunk size 64Mi was bigger than source file size 16.000Mi 2025/10/25 03:53:43 DEBUG : test-multithread-copy-false-16777215-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2025/10/25 03:53:43 DEBUG : test-multithread-copy-false-16777215-2: multi-thread copy: number of streams 4 was bigger than number of chunks 1 2025/10/25 03:53:43 DEBUG : test-multithread-copy-false-16777215-2: Starting multi-thread copy with 1 chunks of size 16.000Mi with 1 parallel streams 2025/10/25 03:53:43 DEBUG : test-multithread-copy-false-16777215-2: multi-thread copy: chunk 1/1 (0-16777215) size 16.000Mi starting 2025/10/25 03:53:43 DEBUG : test-multithread-copy-false-16777215-2: writing chunk 0 2025/10/25 03:53:45 DEBUG : test-multithread-copy-false-16777215-2: multi-thread copy: chunk 1/1 (0-16777215) size 16.000Mi finished 2025/10/25 03:53:45 DEBUG : test-multithread-copy-false-16777215-2: Finished multi-thread copy with 1 parts of size 16.000Mi === RUN TestMultithreadCopy/upload=false,size=16777216,streams=2 2025/10/25 03:53:51 DEBUG : test-multithread-copy-false-16777216-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2025/10/25 03:53:51 DEBUG : test-multithread-copy-false-16777216-2: multi-thread copy: write buffer set to 131072 2025/10/25 03:53:51 DEBUG : test-multithread-copy-false-16777216-2: multi-thread copy: chunk size 64Mi was bigger than source file size 16Mi 2025/10/25 03:53:51 DEBUG : test-multithread-copy-false-16777216-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2025/10/25 03:53:51 DEBUG : test-multithread-copy-false-16777216-2: multi-thread copy: number of streams 4 was bigger than number of chunks 1 2025/10/25 03:53:51 DEBUG : test-multithread-copy-false-16777216-2: Starting multi-thread copy with 1 chunks of size 16Mi with 1 parallel streams 2025/10/25 03:53:51 DEBUG : test-multithread-copy-false-16777216-2: multi-thread copy: chunk 1/1 (0-16777216) size 16Mi starting 2025/10/25 03:53:52 DEBUG : test-multithread-copy-false-16777216-2: writing chunk 0 2025/10/25 03:53:54 DEBUG : test-multithread-copy-false-16777216-2: multi-thread copy: chunk 1/1 (0-16777216) size 16Mi finished 2025/10/25 03:53:54 DEBUG : test-multithread-copy-false-16777216-2: Finished multi-thread copy with 1 parts of size 16Mi === RUN TestMultithreadCopy/upload=false,size=16777217,streams=2 2025/10/25 03:54:00 DEBUG : test-multithread-copy-false-16777217-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2025/10/25 03:54:00 DEBUG : test-multithread-copy-false-16777217-2: multi-thread copy: write buffer set to 131072 2025/10/25 03:54:00 DEBUG : test-multithread-copy-false-16777217-2: multi-thread copy: chunk size 64Mi was bigger than source file size 16.000Mi 2025/10/25 03:54:00 DEBUG : test-multithread-copy-false-16777217-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2025/10/25 03:54:00 DEBUG : test-multithread-copy-false-16777217-2: multi-thread copy: number of streams 4 was bigger than number of chunks 1 2025/10/25 03:54:00 DEBUG : test-multithread-copy-false-16777217-2: Starting multi-thread copy with 1 chunks of size 16.000Mi with 1 parallel streams 2025/10/25 03:54:00 DEBUG : test-multithread-copy-false-16777217-2: multi-thread copy: chunk 1/1 (0-16777217) size 16.000Mi starting 2025/10/25 03:54:00 DEBUG : test-multithread-copy-false-16777217-2: writing chunk 0 2025/10/25 03:54:02 DEBUG : test-multithread-copy-false-16777217-2: multi-thread copy: chunk 1/1 (0-16777217) size 16.000Mi finished 2025/10/25 03:54:02 DEBUG : test-multithread-copy-false-16777217-2: Finished multi-thread copy with 1 parts of size 16.000Mi === RUN TestMultithreadCopy/upload=true,size=16777215,streams=2 2025/10/25 03:54:06 DEBUG : test-multithread-copy-true-16777215-2: multi-thread copy: disabling buffering because source is local disk 2025/10/25 03:54:06 DEBUG : test-multithread-copy-true-16777215-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2025/10/25 03:54:06 DEBUG : test-multithread-copy-true-16777215-2: multi-thread copy: number of streams 4 was bigger than number of chunks 2 2025/10/25 03:54:06 DEBUG : test-multithread-copy-true-16777215-2: Starting multi-thread copy with 2 chunks of size 8Mi with 2 parallel streams 2025/10/25 03:54:06 DEBUG : test-multithread-copy-true-16777215-2: multi-thread copy: chunk 1/2 (0-8388608) size 8Mi starting 2025/10/25 03:54:06 DEBUG : test-multithread-copy-true-16777215-2: Sending chunk 0 length 8388608 2025/10/25 03:54:06 DEBUG : test-multithread-copy-true-16777215-2: multi-thread copy: chunk 2/2 (8388608-16777215) size 8.000Mi starting 2025/10/25 03:54:06 DEBUG : test-multithread-copy-true-16777215-2: Sending chunk 1 length 8388607 2025/10/25 03:54:09 DEBUG : test-multithread-copy-true-16777215-2: Done sending chunk 0 2025/10/25 03:54:09 DEBUG : test-multithread-copy-true-16777215-2: multi-thread copy: chunk 1/2 (0-8388608) size 8Mi finished 2025/10/25 03:54:09 DEBUG : test-multithread-copy-true-16777215-2: Done sending chunk 1 2025/10/25 03:54:09 DEBUG : test-multithread-copy-true-16777215-2: multi-thread copy: chunk 2/2 (8388608-16777215) size 8.000Mi finished 2025/10/25 03:54:09 DEBUG : test-multithread-copy-true-16777215-2: Finishing large file upload with 2 parts 2025/10/25 03:54:09 DEBUG : test-multithread-copy-true-16777215-2: Finished multi-thread copy with 2 parts of size 8Mi === RUN TestMultithreadCopy/upload=true,size=16777216,streams=2 2025/10/25 03:54:13 DEBUG : test-multithread-copy-true-16777216-2: multi-thread copy: disabling buffering because source is local disk 2025/10/25 03:54:13 DEBUG : test-multithread-copy-true-16777216-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2025/10/25 03:54:13 DEBUG : test-multithread-copy-true-16777216-2: multi-thread copy: number of streams 4 was bigger than number of chunks 2 2025/10/25 03:54:13 DEBUG : test-multithread-copy-true-16777216-2: Starting multi-thread copy with 2 chunks of size 8Mi with 2 parallel streams 2025/10/25 03:54:13 DEBUG : test-multithread-copy-true-16777216-2: multi-thread copy: chunk 2/2 (8388608-16777216) size 8Mi starting 2025/10/25 03:54:13 DEBUG : test-multithread-copy-true-16777216-2: multi-thread copy: chunk 1/2 (0-8388608) size 8Mi starting 2025/10/25 03:54:13 DEBUG : test-multithread-copy-true-16777216-2: Sending chunk 1 length 8388608 2025/10/25 03:54:13 DEBUG : test-multithread-copy-true-16777216-2: Sending chunk 0 length 8388608 2025/10/25 03:54:16 DEBUG : test-multithread-copy-true-16777216-2: Done sending chunk 1 2025/10/25 03:54:16 DEBUG : test-multithread-copy-true-16777216-2: multi-thread copy: chunk 2/2 (8388608-16777216) size 8Mi finished 2025/10/25 03:54:16 DEBUG : test-multithread-copy-true-16777216-2: Done sending chunk 0 2025/10/25 03:54:16 DEBUG : test-multithread-copy-true-16777216-2: multi-thread copy: chunk 1/2 (0-8388608) size 8Mi finished 2025/10/25 03:54:16 DEBUG : test-multithread-copy-true-16777216-2: Finishing large file upload with 2 parts 2025/10/25 03:54:16 DEBUG : test-multithread-copy-true-16777216-2: Finished multi-thread copy with 2 parts of size 8Mi === RUN TestMultithreadCopy/upload=true,size=16777217,streams=2 2025/10/25 03:54:20 DEBUG : test-multithread-copy-true-16777217-2: multi-thread copy: disabling buffering because source is local disk 2025/10/25 03:54:20 DEBUG : test-multithread-copy-true-16777217-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2025/10/25 03:54:20 DEBUG : test-multithread-copy-true-16777217-2: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2025/10/25 03:54:20 DEBUG : test-multithread-copy-true-16777217-2: Starting multi-thread copy with 3 chunks of size 8Mi with 3 parallel streams 2025/10/25 03:54:20 DEBUG : test-multithread-copy-true-16777217-2: multi-thread copy: chunk 3/3 (16777216-16777217) size 1 starting 2025/10/25 03:54:20 DEBUG : test-multithread-copy-true-16777217-2: Sending chunk 2 length 1 2025/10/25 03:54:20 DEBUG : test-multithread-copy-true-16777217-2: multi-thread copy: chunk 1/3 (0-8388608) size 8Mi starting 2025/10/25 03:54:20 DEBUG : test-multithread-copy-true-16777217-2: multi-thread copy: chunk 2/3 (8388608-16777216) size 8Mi starting 2025/10/25 03:54:20 DEBUG : test-multithread-copy-true-16777217-2: Sending chunk 0 length 8388608 2025/10/25 03:54:20 DEBUG : test-multithread-copy-true-16777217-2: Sending chunk 1 length 8388608 2025/10/25 03:54:21 DEBUG : test-multithread-copy-true-16777217-2: Done sending chunk 2 2025/10/25 03:54:21 DEBUG : test-multithread-copy-true-16777217-2: multi-thread copy: chunk 3/3 (16777216-16777217) size 1 finished 2025/10/25 03:54:23 DEBUG : test-multithread-copy-true-16777217-2: Done sending chunk 0 2025/10/25 03:54:23 DEBUG : test-multithread-copy-true-16777217-2: multi-thread copy: chunk 1/3 (0-8388608) size 8Mi finished 2025/10/25 03:54:23 DEBUG : test-multithread-copy-true-16777217-2: Done sending chunk 1 2025/10/25 03:54:23 DEBUG : test-multithread-copy-true-16777217-2: multi-thread copy: chunk 2/3 (8388608-16777216) size 8Mi finished 2025/10/25 03:54:23 DEBUG : test-multithread-copy-true-16777217-2: Finishing large file upload with 3 parts 2025/10/25 03:54:24 DEBUG : test-multithread-copy-true-16777217-2: Finished multi-thread copy with 3 parts of size 8Mi --- PASS: TestMultithreadCopy (49.07s) --- PASS: TestMultithreadCopy/upload=false,size=16777215,streams=2 (8.52s) --- PASS: TestMultithreadCopy/upload=false,size=16777216,streams=2 (8.28s) --- PASS: TestMultithreadCopy/upload=false,size=16777217,streams=2 (8.57s) --- PASS: TestMultithreadCopy/upload=true,size=16777215,streams=2 (7.32s) --- PASS: TestMultithreadCopy/upload=true,size=16777216,streams=2 (7.05s) --- PASS: TestMultithreadCopy/upload=true,size=16777217,streams=2 (7.47s) === RUN TestMultithreadCopyAbort run.go:180: Remote "B2 bucket rclone-test-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" 2025/10/25 03:54:26 DEBUG : chunksize-probe: Cancelling large file upload 2025/10/25 03:54:29 DEBUG : test-multithread-abort: multi-thread copy: disabling buffering because source is local disk 2025/10/25 03:54:29 DEBUG : test-multithread-abort: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 1 2025/10/25 03:54:29 DEBUG : test-multithread-abort: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2025/10/25 03:54:29 DEBUG : test-multithread-abort: Starting multi-thread copy with 3 chunks of size 8Mi with 3 parallel streams 2025/10/25 03:54:29 DEBUG : test-multithread-abort: multi-thread copy: chunk 3/3 (16777216-16777217) size 1 starting 2025/10/25 03:54:29 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2025/10/25 03:54:29 DEBUG : test-multithread-abort: multi-thread copy: chunk 2/3 (8388608-16777216) size 8Mi starting 2025/10/25 03:54:29 DEBUG : test-multithread-abort: multi-thread copy: chunk 1/3 (0-8388608) size 8Mi starting 2025/10/25 03:54:29 DEBUG : Open with options = [RangeOption(8388608,16777215)] 2025/10/25 03:54:29 DEBUG : Open with options = [RangeOption(0,8388607)] 2025/10/25 03:54:29 DEBUG : test-multithread-abort: Sending chunk 1 length 8388608 2025/10/25 03:54:29 DEBUG : test-multithread-abort: Sending chunk 0 length 8388608 2025/10/25 03:54:32 DEBUG : test-multithread-abort: Done sending chunk 1 2025/10/25 03:54:32 DEBUG : test-multithread-abort: multi-thread copy: chunk 2/3 (8388608-16777216) size 8Mi finished 2025/10/25 03:54:32 DEBUG : test-multithread-abort: Done sending chunk 0 2025/10/25 03:54:32 DEBUG : test-multithread-abort: multi-thread copy: chunk 1/3 (0-8388608) size 8Mi finished 2025/10/25 03:54:32 DEBUG : Returning error reader 2025/10/25 03:54:32 DEBUG : test-multithread-abort: Sending chunk 2 length 1 2025/10/25 03:54:32 DEBUG : BOOM: simulated read failure 2025/10/25 03:54:32 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 0/10: BOOM: simulated read failure 2025/10/25 03:54:32 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2025/10/25 03:54:33 DEBUG : Returning error reader 2025/10/25 03:54:33 DEBUG : BOOM: simulated read failure 2025/10/25 03:54:33 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 1/10: BOOM: simulated read failure 2025/10/25 03:54:33 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2025/10/25 03:54:34 DEBUG : Returning error reader 2025/10/25 03:54:34 DEBUG : BOOM: simulated read failure 2025/10/25 03:54:34 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 2/10: BOOM: simulated read failure 2025/10/25 03:54:34 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2025/10/25 03:54:35 DEBUG : Returning error reader 2025/10/25 03:54:35 DEBUG : BOOM: simulated read failure 2025/10/25 03:54:35 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 3/10: BOOM: simulated read failure 2025/10/25 03:54:35 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2025/10/25 03:54:36 DEBUG : Returning error reader 2025/10/25 03:54:36 DEBUG : BOOM: simulated read failure 2025/10/25 03:54:36 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 4/10: BOOM: simulated read failure 2025/10/25 03:54:36 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2025/10/25 03:54:37 DEBUG : Returning error reader 2025/10/25 03:54:37 DEBUG : BOOM: simulated read failure 2025/10/25 03:54:37 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 5/10: BOOM: simulated read failure 2025/10/25 03:54:37 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2025/10/25 03:54:38 DEBUG : Returning error reader 2025/10/25 03:54:38 DEBUG : BOOM: simulated read failure 2025/10/25 03:54:38 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 6/10: BOOM: simulated read failure 2025/10/25 03:54:38 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2025/10/25 03:54:39 DEBUG : Returning error reader 2025/10/25 03:54:39 DEBUG : BOOM: simulated read failure 2025/10/25 03:54:39 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 7/10: BOOM: simulated read failure 2025/10/25 03:54:39 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2025/10/25 03:54:40 DEBUG : Returning error reader 2025/10/25 03:54:40 DEBUG : BOOM: simulated read failure 2025/10/25 03:54:40 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 8/10: BOOM: simulated read failure 2025/10/25 03:54:40 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2025/10/25 03:54:41 DEBUG : Returning error reader 2025/10/25 03:54:41 DEBUG : BOOM: simulated read failure 2025/10/25 03:54:41 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 9/10: BOOM: simulated read failure 2025/10/25 03:54:41 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2025/10/25 03:54:42 DEBUG : Returning error reader 2025/10/25 03:54:42 DEBUG : BOOM: simulated read failure 2025/10/25 03:54:42 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 10/10: BOOM: simulated read failure 2025/10/25 03:54:42 DEBUG : test-multithread-abort: Reopen failed after offset 0 bytes read: failed to reopen: too many retries 2025/10/25 03:54:42 DEBUG : test-multithread-abort: Error sending chunk 2 (retry=false): Post "https://pod-000-1154-17.backblaze.com/b2api/v1/b2_upload_part/4_zdf421dc3800d1b9b9fa80e1b_f216b2c496b85780d_d20251025_m035429_c001_v0001154_t0007_u01761364469678/0013": BOOM: simulated read failure: &url.Error{Op:"Post", URL:"https://pod-000-1154-17.backblaze.com/b2api/v1/b2_upload_part/4_zdf421dc3800d1b9b9fa80e1b_f216b2c496b85780d_d20251025_m035429_c001_v0001154_t0007_u01761364469678/0013", Err:(*errors.errorString)(0xc0007dc990)} 2025/10/25 03:54:42 DEBUG : test-multithread-abort: Error sending chunk 2: Post "https://pod-000-1154-17.backblaze.com/b2api/v1/b2_upload_part/4_zdf421dc3800d1b9b9fa80e1b_f216b2c496b85780d_d20251025_m035429_c001_v0001154_t0007_u01761364469678/0013": BOOM: simulated read failure 2025/10/25 03:54:42 DEBUG : test-multithread-abort: multi-thread copy: chunk 3/3 failed: multi-thread copy: failed to write chunk: Post "https://pod-000-1154-17.backblaze.com/b2api/v1/b2_upload_part/4_zdf421dc3800d1b9b9fa80e1b_f216b2c496b85780d_d20251025_m035429_c001_v0001154_t0007_u01761364469678/0013": BOOM: simulated read failure 2025/10/25 03:54:42 DEBUG : test-multithread-abort: multi-thread copy: cancelling transfer on exit 2025/10/25 03:54:42 DEBUG : test-multithread-abort: Cancelling large file upload --- PASS: TestMultithreadCopyAbort (17.59s) === RUN TestSizeDiffers --- PASS: TestSizeDiffers (0.00s) === RUN TestReOpen === RUN TestReOpen/Normal === RUN TestReOpen/Normal/Basics 2025/10/25 03:54:43 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/ErrorAtStart === RUN TestReOpen/Normal/WithErrors 2025/10/25 03:54:43 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2025/10/25 03:54:43 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2025/10/25 03:54:43 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/Normal/TooManyErrors 2025/10/25 03:54:43 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2025/10/25 03:54:43 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2025/10/25 03:54:43 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2025/10/25 03:54:43 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/Normal/Seek 2025/10/25 03:54:43 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/10/25 03:54:43 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/10/25 03:54:43 DEBUG : potato: Seek from 5 to 2 === RUN TestReOpen/Normal/AccountRead === RUN TestReOpen/Normal/AccountReadDelay 2025/10/25 03:54:43 DEBUG : potato: Seek from 10 to 0 2025/10/25 03:54:43 DEBUG : potato: Seek from 10 to 0 2025/10/25 03:54:43 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/AccountReadError === RUN TestReOpen/WithRangeOption === RUN TestReOpen/WithRangeOption/Basics 2025/10/25 03:54:43 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/ErrorAtStart === RUN TestReOpen/WithRangeOption/WithErrors 2025/10/25 03:54:43 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2025/10/25 03:54:43 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2025/10/25 03:54:43 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithRangeOption/TooManyErrors 2025/10/25 03:54:43 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2025/10/25 03:54:43 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2025/10/25 03:54:43 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2025/10/25 03:54:43 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithRangeOption/Seek 2025/10/25 03:54:43 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/10/25 03:54:43 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/10/25 03:54:43 DEBUG : potato: Seek from 5 to 2 2025/10/25 03:54:43 DEBUG : potato: Seek from 7 to 4 === RUN TestReOpen/WithRangeOption/AccountRead === RUN TestReOpen/WithRangeOption/AccountReadDelay 2025/10/25 03:54:43 DEBUG : potato: Seek from 7 to 0 2025/10/25 03:54:43 DEBUG : potato: Seek from 7 to 0 2025/10/25 03:54:43 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/AccountReadError === RUN TestReOpen/WithSeekOption === RUN TestReOpen/WithSeekOption/Basics 2025/10/25 03:54:43 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/ErrorAtStart === RUN TestReOpen/WithSeekOption/WithErrors 2025/10/25 03:54:43 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2025/10/25 03:54:43 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2025/10/25 03:54:43 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithSeekOption/TooManyErrors 2025/10/25 03:54:43 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2025/10/25 03:54:43 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2025/10/25 03:54:43 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2025/10/25 03:54:43 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithSeekOption/Seek 2025/10/25 03:54:43 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/10/25 03:54:43 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/10/25 03:54:43 DEBUG : potato: Seek from 5 to 2 2025/10/25 03:54:43 DEBUG : potato: Seek from 7 to 5 === RUN TestReOpen/WithSeekOption/AccountRead === RUN TestReOpen/WithSeekOption/AccountReadDelay 2025/10/25 03:54:43 DEBUG : potato: Seek from 8 to 0 2025/10/25 03:54:43 DEBUG : potato: Seek from 8 to 0 2025/10/25 03:54:43 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/AccountReadError === RUN TestReOpen/UnknownSize === RUN TestReOpen/UnknownSize/Basics 2025/10/25 03:54:43 DEBUG : potato: Seek from 9 to 0 === RUN TestReOpen/UnknownSize/ErrorAtStart === RUN TestReOpen/UnknownSize/WithErrors 2025/10/25 03:54:43 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2025/10/25 03:54:43 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2025/10/25 03:54:43 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/UnknownSize/TooManyErrors 2025/10/25 03:54:43 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2025/10/25 03:54:43 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2025/10/25 03:54:43 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2025/10/25 03:54:43 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/UnknownSize/Seek 2025/10/25 03:54:43 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/10/25 03:54:43 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/10/25 03:54:43 DEBUG : potato: Seek from 5 to 2 2025/10/25 03:54:43 DEBUG : potato: Seek from 7 to 6 === RUN TestReOpen/UnknownSize/AccountRead === RUN TestReOpen/UnknownSize/AccountReadDelay 2025/10/25 03:54:43 DEBUG : potato: Seek from 9 to 0 2025/10/25 03:54:43 DEBUG : potato: Seek from 9 to 0 2025/10/25 03:54:43 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-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" === RUN TestCheck/1 === RUN TestCheck/2 === RUN TestCheck/3 === RUN TestCheck/4 === RUN TestCheck/5 === RUN TestCheck/6 === RUN TestCheck/7 --- PASS: TestCheck (5.62s) --- PASS: TestCheck/1 (0.18s) --- PASS: TestCheck/2 (0.23s) --- PASS: TestCheck/3 (0.18s) --- PASS: TestCheck/4 (0.18s) --- PASS: TestCheck/5 (0.18s) --- PASS: TestCheck/6 (0.18s) --- PASS: TestCheck/7 (0.18s) === RUN TestCheckFsError 2025/10/25 03:54:49 DEBUG : Creating backend with remote "nonexistent" 2025/10/25 03:54:49 DEBUG : Creating backend with remote "nonexistent" 2025/10/25 03:54:49 DEBUG : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: Waiting for checks to finish 2025/10/25 03:54:49 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/10/25 03:54:49 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: 0 differences found 2025/10/25 03:54:49 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-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", 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.85s) --- PASS: TestCheckDownload/1 (0.73s) --- PASS: TestCheckDownload/2 (0.37s) --- PASS: TestCheckDownload/3 (0.36s) --- PASS: TestCheckDownload/4 (0.55s) --- PASS: TestCheckDownload/5 (0.38s) --- PASS: TestCheckDownload/6 (0.38s) --- PASS: TestCheckDownload/7 (0.38s) === RUN TestCheckSizeOnly run.go:180: Remote "B2 bucket rclone-test-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" === RUN TestCheckSizeOnly/1 === RUN TestCheckSizeOnly/2 === RUN TestCheckSizeOnly/3 === RUN TestCheckSizeOnly/4 === RUN TestCheckSizeOnly/5 === RUN TestCheckSizeOnly/6 === RUN TestCheckSizeOnly/7 --- PASS: TestCheckSizeOnly (4.98s) --- PASS: TestCheckSizeOnly/1 (0.18s) --- PASS: TestCheckSizeOnly/2 (0.19s) --- PASS: TestCheckSizeOnly/3 (0.20s) --- PASS: TestCheckSizeOnly/4 (0.18s) --- PASS: TestCheckSizeOnly/5 (0.18s) --- PASS: TestCheckSizeOnly/6 (0.18s) --- PASS: TestCheckSizeOnly/7 (0.18s) === RUN TestCheckEqualReaders --- PASS: TestCheckEqualReaders (0.00s) === RUN TestParseSumFile run.go:180: Remote "B2 bucket rclone-test-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" 2025/10/25 03:55:02 NOTICE: test.sum: improperly formatted checksum line 4 2025/10/25 03:55:02 NOTICE: test.sum: improperly formatted checksum line 5 2025/10/25 03:55:02 NOTICE: test.sum: improperly formatted checksum line 6 2025/10/25 03:55:02 NOTICE: test.sum: 2 warning(s) suppressed... 2025/10/25 03:55:03 NOTICE: test.sum: improperly formatted checksum line 4 2025/10/25 03:55:03 NOTICE: test.sum: improperly formatted checksum line 5 2025/10/25 03:55:03 NOTICE: test.sum: improperly formatted checksum line 6 2025/10/25 03:55:03 NOTICE: test.sum: 2 warning(s) suppressed... --- PASS: TestParseSumFile (2.53s) === RUN TestCheckSum run.go:180: Remote "B2 bucket rclone-test-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" 2025/10/25 03:55:03 DEBUG : Creating backend with remote "TestB2:rclone-test-moyalup4yuje/data" check_test.go:350: B2 bucket rclone-test-moyalup4yuje path data lacks md5, skipping --- SKIP: TestCheckSum (1.49s) === RUN TestCheckSumDownload run.go:180: Remote "B2 bucket rclone-test-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" 2025/10/25 03:55:05 DEBUG : Creating backend with remote "TestB2:rclone-test-moyalup4yuje/data" === RUN TestCheckSumDownload/subtest1 === RUN TestCheckSumDownload/subtest2 === RUN TestCheckSumDownload/subtest3 === RUN TestCheckSumDownload/subtest4 === RUN TestCheckSumDownload/subtest5 === RUN TestCheckSumDownload/subtest6 === RUN TestCheckSumDownload/subtest7 --- PASS: TestCheckSumDownload (14.20s) --- PASS: TestCheckSumDownload/subtest1 (1.29s) --- PASS: TestCheckSumDownload/subtest2 (0.73s) --- PASS: TestCheckSumDownload/subtest3 (0.92s) --- PASS: TestCheckSumDownload/subtest4 (0.82s) --- PASS: TestCheckSumDownload/subtest5 (0.74s) --- PASS: TestCheckSumDownload/subtest6 (0.76s) --- PASS: TestCheckSumDownload/subtest7 (0.75s) === RUN TestApplyTransforms 2025/10/25 03:55:19 DEBUG : Creating backend with remote "TestB2:rclone-test-futazol8wula" 2025/10/25 03:55:19 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/10/25 03:55:20 DEBUG : Creating backend with remote "/tmp/rclone2979781118" run.go:180: Remote "B2 bucket rclone-test-futazol8wula", Local "Local file system at /tmp/rclone2979781118", Modify Window "1ms" upper checkfile vs. lower remote (without normalization) 2025/10/25 03:55:22 ERROR : hello, world!: sum not found 2025/10/25 03:55:22 ERROR : HELLO, WORLD!: file not in B2 bucket rclone-test-futazol8wula 2025/10/25 03:55:22 NOTICE: B2 bucket rclone-test-futazol8wula: 1 files missing 2025/10/25 03:55:22 NOTICE: 1 hashes missing 2025/10/25 03:55:22 NOTICE: B2 bucket rclone-test-futazol8wula: 1 differences found 2025/10/25 03:55:22 NOTICE: B2 bucket rclone-test-futazol8wula: 2 errors while checking upper checkfile vs. lower remote (with normalization) 2025/10/25 03:55:22 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/10/25 03:55:22 NOTICE: B2 bucket rclone-test-futazol8wula: 0 differences found 2025/10/25 03:55:22 NOTICE: B2 bucket rclone-test-futazol8wula: 1 matching files 2025/10/25 03:55:22 DEBUG : Creating backend with remote "TestB2:rclone-test-qujareq3quho" 2025/10/25 03:55:22 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/10/25 03:55:23 DEBUG : Creating backend with remote "/tmp/rclone3105230427" run.go:180: Remote "B2 bucket rclone-test-qujareq3quho", Local "Local file system at /tmp/rclone3105230427", Modify Window "1ms" lower checkfile vs. upper remote (without normalization) 2025/10/25 03:55:25 ERROR : HELLO, WORLD!: sum not found 2025/10/25 03:55:25 ERROR : hello, world!: file not in B2 bucket rclone-test-qujareq3quho 2025/10/25 03:55:25 NOTICE: B2 bucket rclone-test-qujareq3quho: 1 files missing 2025/10/25 03:55:25 NOTICE: 1 hashes missing 2025/10/25 03:55:25 NOTICE: B2 bucket rclone-test-qujareq3quho: 1 differences found 2025/10/25 03:55:25 NOTICE: B2 bucket rclone-test-qujareq3quho: 2 errors while checking lower checkfile vs. upper remote (with normalization) 2025/10/25 03:55:26 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/10/25 03:55:26 NOTICE: B2 bucket rclone-test-qujareq3quho: 0 differences found 2025/10/25 03:55:26 NOTICE: B2 bucket rclone-test-qujareq3quho: 1 matching files 2025/10/25 03:55:26 DEBUG : Creating backend with remote "TestB2:rclone-test-semefun8luta" 2025/10/25 03:55:26 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/10/25 03:55:27 DEBUG : Creating backend with remote "/tmp/rclone2970248403" run.go:180: Remote "B2 bucket rclone-test-semefun8luta", Local "Local file system at /tmp/rclone2970248403", Modify Window "1ms" lower checkfile vs. upperlowermixed remote (without normalization) 2025/10/25 03:55:29 ERROR : HeLlO, wOrLd!: sum not found 2025/10/25 03:55:29 ERROR : hello, world!: file not in B2 bucket rclone-test-semefun8luta 2025/10/25 03:55:29 NOTICE: B2 bucket rclone-test-semefun8luta: 1 files missing 2025/10/25 03:55:29 NOTICE: 1 hashes missing 2025/10/25 03:55:29 NOTICE: B2 bucket rclone-test-semefun8luta: 1 differences found 2025/10/25 03:55:29 NOTICE: B2 bucket rclone-test-semefun8luta: 2 errors while checking lower checkfile vs. upperlowermixed remote (with normalization) 2025/10/25 03:55:29 DEBUG : HeLlO, wOrLd!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/10/25 03:55:29 NOTICE: B2 bucket rclone-test-semefun8luta: 0 differences found 2025/10/25 03:55:29 NOTICE: B2 bucket rclone-test-semefun8luta: 1 matching files 2025/10/25 03:55:29 DEBUG : Creating backend with remote "TestB2:rclone-test-nevurex0piba" 2025/10/25 03:55:29 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/10/25 03:55:30 DEBUG : Creating backend with remote "/tmp/rclone712692460" run.go:180: Remote "B2 bucket rclone-test-nevurex0piba", Local "Local file system at /tmp/rclone712692460", Modify Window "1ms" upperlowermixed checkfile vs. upper remote (without normalization) 2025/10/25 03:55:32 ERROR : HELLO, WORLD!: sum not found 2025/10/25 03:55:32 ERROR : HeLlO, wOrLd!: file not in B2 bucket rclone-test-nevurex0piba 2025/10/25 03:55:32 NOTICE: B2 bucket rclone-test-nevurex0piba: 1 files missing 2025/10/25 03:55:32 NOTICE: 1 hashes missing 2025/10/25 03:55:32 NOTICE: B2 bucket rclone-test-nevurex0piba: 1 differences found 2025/10/25 03:55:32 NOTICE: B2 bucket rclone-test-nevurex0piba: 2 errors while checking upperlowermixed checkfile vs. upper remote (with normalization) 2025/10/25 03:55:33 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/10/25 03:55:33 NOTICE: B2 bucket rclone-test-nevurex0piba: 0 differences found 2025/10/25 03:55:33 NOTICE: B2 bucket rclone-test-nevurex0piba: 1 matching files 2025/10/25 03:55:33 DEBUG : Creating backend with remote "TestB2:rclone-test-magoruy4wavo" 2025/10/25 03:55:33 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/10/25 03:55:33 DEBUG : Creating backend with remote "/tmp/rclone2113454440" run.go:180: Remote "B2 bucket rclone-test-magoruy4wavo", Local "Local file system at /tmp/rclone2113454440", Modify Window "1ms" NFD checkfile vs. NFC remote (without normalization) 2025/10/25 03:55:35 ERROR : 測試_Русский___ě_áñ: sum not found 2025/10/25 03:55:35 ERROR : 測試_Русский___ě_áñ: file not in B2 bucket rclone-test-magoruy4wavo 2025/10/25 03:55:35 NOTICE: B2 bucket rclone-test-magoruy4wavo: 1 files missing 2025/10/25 03:55:35 NOTICE: 1 hashes missing 2025/10/25 03:55:35 NOTICE: B2 bucket rclone-test-magoruy4wavo: 1 differences found 2025/10/25 03:55:35 NOTICE: B2 bucket rclone-test-magoruy4wavo: 2 errors while checking NFD checkfile vs. NFC remote (with normalization) 2025/10/25 03:55:36 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/10/25 03:55:36 NOTICE: B2 bucket rclone-test-magoruy4wavo: 0 differences found 2025/10/25 03:55:36 NOTICE: B2 bucket rclone-test-magoruy4wavo: 1 matching files 2025/10/25 03:55:36 DEBUG : Creating backend with remote "TestB2:rclone-test-wefosih9fode" 2025/10/25 03:55:36 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/10/25 03:55:37 DEBUG : Creating backend with remote "/tmp/rclone2036051452" run.go:180: Remote "B2 bucket rclone-test-wefosih9fode", Local "Local file system at /tmp/rclone2036051452", Modify Window "1ms" NFC checkfile vs. NFD remote (without normalization) 2025/10/25 03:55:39 ERROR : 測試_Русский___ě_áñ: sum not found 2025/10/25 03:55:39 ERROR : 測試_Русский___ě_áñ: file not in B2 bucket rclone-test-wefosih9fode 2025/10/25 03:55:39 NOTICE: B2 bucket rclone-test-wefosih9fode: 1 files missing 2025/10/25 03:55:39 NOTICE: 1 hashes missing 2025/10/25 03:55:39 NOTICE: B2 bucket rclone-test-wefosih9fode: 1 differences found 2025/10/25 03:55:39 NOTICE: B2 bucket rclone-test-wefosih9fode: 2 errors while checking NFC checkfile vs. NFD remote (with normalization) 2025/10/25 03:55:40 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/10/25 03:55:40 NOTICE: B2 bucket rclone-test-wefosih9fode: 0 differences found 2025/10/25 03:55:40 NOTICE: B2 bucket rclone-test-wefosih9fode: 1 matching files 2025/10/25 03:55:40 DEBUG : Creating backend with remote "TestB2:rclone-test-juwenir2luke" 2025/10/25 03:55:40 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/10/25 03:55:40 DEBUG : Creating backend with remote "/tmp/rclone4135107100" run.go:180: Remote "B2 bucket rclone-test-juwenir2luke", Local "Local file system at /tmp/rclone4135107100", Modify Window "1ms" NFDx2 checkfile vs. both remote (without normalization) 2025/10/25 03:55:42 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2025/10/25 03:55:42 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-juwenir2luke 2025/10/25 03:55:42 NOTICE: B2 bucket rclone-test-juwenir2luke: 1 files missing 2025/10/25 03:55:42 NOTICE: 1 hashes missing 2025/10/25 03:55:42 NOTICE: B2 bucket rclone-test-juwenir2luke: 1 differences found 2025/10/25 03:55:42 NOTICE: B2 bucket rclone-test-juwenir2luke: 2 errors while checking NFDx2 checkfile vs. both remote (with normalization) 2025/10/25 03:55:43 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/10/25 03:55:43 NOTICE: B2 bucket rclone-test-juwenir2luke: 0 differences found 2025/10/25 03:55:43 NOTICE: B2 bucket rclone-test-juwenir2luke: 1 matching files 2025/10/25 03:55:43 DEBUG : Creating backend with remote "TestB2:rclone-test-qawemuv4duhe" 2025/10/25 03:55:43 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/10/25 03:55:44 DEBUG : Creating backend with remote "/tmp/rclone303951924" run.go:180: Remote "B2 bucket rclone-test-qawemuv4duhe", Local "Local file system at /tmp/rclone303951924", Modify Window "1ms" NFCx2 checkfile vs. both remote (without normalization) 2025/10/25 03:55:46 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2025/10/25 03:55:46 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-qawemuv4duhe 2025/10/25 03:55:46 NOTICE: B2 bucket rclone-test-qawemuv4duhe: 1 files missing 2025/10/25 03:55:46 NOTICE: 1 hashes missing 2025/10/25 03:55:46 NOTICE: B2 bucket rclone-test-qawemuv4duhe: 1 differences found 2025/10/25 03:55:46 NOTICE: B2 bucket rclone-test-qawemuv4duhe: 2 errors while checking NFCx2 checkfile vs. both remote (with normalization) 2025/10/25 03:55:47 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/10/25 03:55:47 NOTICE: B2 bucket rclone-test-qawemuv4duhe: 0 differences found 2025/10/25 03:55:47 NOTICE: B2 bucket rclone-test-qawemuv4duhe: 1 matching files 2025/10/25 03:55:47 DEBUG : Creating backend with remote "TestB2:rclone-test-yulodob3raho" 2025/10/25 03:55:47 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/10/25 03:55:47 DEBUG : Creating backend with remote "/tmp/rclone1491743132" run.go:180: Remote "B2 bucket rclone-test-yulodob3raho", Local "Local file system at /tmp/rclone1491743132", Modify Window "1ms" both checkfile vs. NFDx2 remote (without normalization) 2025/10/25 03:55:49 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2025/10/25 03:55:49 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-yulodob3raho 2025/10/25 03:55:49 NOTICE: B2 bucket rclone-test-yulodob3raho: 1 files missing 2025/10/25 03:55:49 NOTICE: 1 hashes missing 2025/10/25 03:55:49 NOTICE: B2 bucket rclone-test-yulodob3raho: 1 differences found 2025/10/25 03:55:49 NOTICE: B2 bucket rclone-test-yulodob3raho: 2 errors while checking both checkfile vs. NFDx2 remote (with normalization) 2025/10/25 03:55:50 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/10/25 03:55:50 NOTICE: B2 bucket rclone-test-yulodob3raho: 0 differences found 2025/10/25 03:55:50 NOTICE: B2 bucket rclone-test-yulodob3raho: 1 matching files 2025/10/25 03:55:50 DEBUG : Creating backend with remote "TestB2:rclone-test-yesijik0gefe" 2025/10/25 03:55:50 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/10/25 03:55:50 DEBUG : Creating backend with remote "/tmp/rclone2654200662" run.go:180: Remote "B2 bucket rclone-test-yesijik0gefe", Local "Local file system at /tmp/rclone2654200662", Modify Window "1ms" both checkfile vs. NFCx2 remote (without normalization) 2025/10/25 03:55:53 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2025/10/25 03:55:53 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-yesijik0gefe 2025/10/25 03:55:53 NOTICE: B2 bucket rclone-test-yesijik0gefe: 1 files missing 2025/10/25 03:55:53 NOTICE: 1 hashes missing 2025/10/25 03:55:53 NOTICE: B2 bucket rclone-test-yesijik0gefe: 1 differences found 2025/10/25 03:55:53 NOTICE: B2 bucket rclone-test-yesijik0gefe: 2 errors while checking both checkfile vs. NFCx2 remote (with normalization) 2025/10/25 03:55:54 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/10/25 03:55:54 NOTICE: B2 bucket rclone-test-yesijik0gefe: 0 differences found 2025/10/25 03:55:54 NOTICE: B2 bucket rclone-test-yesijik0gefe: 1 matching files 2025/10/25 03:55:54 DEBUG : B2 bucket rclone-test-yesijik0gefe: Purge remote 2025/10/25 03:55:54 INFO : B2 bucket rclone-test-yesijik0gefe: cleaning bucket "rclone-test-yesijik0gefe" of all files 2025/10/25 03:55:54 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_z2f622d73802d1b9b9fa80e1b_f103e06213883c895_d20251025_m035552_c001_v0001093_t0010_u01761364552383") 2025/10/25 03:55:55 DEBUG : B2 bucket rclone-test-yulodob3raho: Purge remote 2025/10/25 03:55:55 INFO : B2 bucket rclone-test-yulodob3raho: cleaning bucket "rclone-test-yulodob3raho" of all files 2025/10/25 03:55:55 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_z0f821da3802d1b9b9fa80e1b_f1133551aa4d76561_d20251025_m035549_c001_v0001038_t0041_u01761364549023") 2025/10/25 03:55:56 DEBUG : B2 bucket rclone-test-qawemuv4duhe: Purge remote 2025/10/25 03:55:56 INFO : B2 bucket rclone-test-qawemuv4duhe: cleaning bucket "rclone-test-qawemuv4duhe" of all files 2025/10/25 03:55:57 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_z5f420dc3802d1b9b9fa80e1b_f1026b56aa62bbcb1_d20251025_m035545_c001_v0001032_t0020_u01761364545591") 2025/10/25 03:55:57 DEBUG : B2 bucket rclone-test-juwenir2luke: Purge remote 2025/10/25 03:55:57 INFO : B2 bucket rclone-test-juwenir2luke: cleaning bucket "rclone-test-juwenir2luke" of all files 2025/10/25 03:55:58 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_z1f32fdf3801d1b9b9fa80e1b_f114a690713a6d6e1_d20251025_m035542_c001_v0001098_t0010_u01761364542132") 2025/10/25 03:55:58 DEBUG : B2 bucket rclone-test-wefosih9fode: Purge remote 2025/10/25 03:55:58 INFO : B2 bucket rclone-test-wefosih9fode: cleaning bucket "rclone-test-wefosih9fode" of all files 2025/10/25 03:55:59 DEBUG : 測試_Русский___ě_áñ: Deleting (id "4_zaf22fd13801d1b9b9fa80e1b_f101e26e5ad4d63f4_d20251025_m035538_c001_v0001105_t0016_u01761364538776") 2025/10/25 03:56:00 DEBUG : B2 bucket rclone-test-magoruy4wavo: Purge remote 2025/10/25 03:56:00 INFO : B2 bucket rclone-test-magoruy4wavo: cleaning bucket "rclone-test-magoruy4wavo" of all files 2025/10/25 03:56:00 DEBUG : 測試_Русский___ě_áñ: Deleting (id "4_zef62ed33801d1b9b9fa80e1b_f11181787f39c00d9_d20251025_m035535_c001_v0001131_t0011_u01761364535233") 2025/10/25 03:56:01 DEBUG : B2 bucket rclone-test-nevurex0piba: Purge remote 2025/10/25 03:56:01 INFO : B2 bucket rclone-test-nevurex0piba: cleaning bucket "rclone-test-nevurex0piba" of all files 2025/10/25 03:56:01 DEBUG : HELLO, WORLD!: Deleting (id "4_zcf92dd63801d1b9b9fa80e1b_f104fdc0acc532cff_d20251025_m035531_c001_v0001041_t0049_u01761364531874") 2025/10/25 03:56:02 DEBUG : B2 bucket rclone-test-semefun8luta: Purge remote 2025/10/25 03:56:02 INFO : B2 bucket rclone-test-semefun8luta: cleaning bucket "rclone-test-semefun8luta" of all files 2025/10/25 03:56:02 DEBUG : HeLlO, wOrLd!: Deleting (id "4_zbfa2cd93801d1b9b9fa80e1b_f11047706eab9d423_d20251025_m035528_c001_v0001134_t0019_u01761364528468") 2025/10/25 03:56:03 DEBUG : B2 bucket rclone-test-qujareq3quho: Purge remote 2025/10/25 03:56:03 INFO : B2 bucket rclone-test-qujareq3quho: cleaning bucket "rclone-test-qujareq3quho" of all files 2025/10/25 03:56:03 DEBUG : HELLO, WORLD!: Deleting (id "4_zcfd2bdb3801d1b9b9fa80e1b_f1083b22dc0b52a42_d20251025_m035525_c001_v0001145_t0012_u01761364525017") 2025/10/25 03:56:04 DEBUG : B2 bucket rclone-test-futazol8wula: Purge remote 2025/10/25 03:56:04 INFO : B2 bucket rclone-test-futazol8wula: cleaning bucket "rclone-test-futazol8wula" of all files 2025/10/25 03:56:05 DEBUG : hello, world!: Deleting (id "4_zdf42ade3801d1b9b9fa80e1b_f10582f76fe435562_d20251025_m035521_c001_v0001180_t0053_u01761364521697") --- PASS: TestApplyTransforms (46.16s) === RUN TestTruncateString --- PASS: TestTruncateString (0.00s) === RUN TestCopyFile run.go:180: Remote "B2 bucket rclone-test-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" 2025/10/25 03:56:06 DEBUG : file1: Need to transfer - File not found at Destination 2025/10/25 03:56:07 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2025/10/25 03:56:07 INFO : file1: Copied (new) to: sub/file2 2025/10/25 03:56:08 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/10/25 03:56:08 DEBUG : file1: Unchanged skipping 2025/10/25 03:56:08 DEBUG : B2 bucket rclone-test-moyalup4yuje: don't need to copy/move sub/file2, it is already at target location --- PASS: TestCopyFile (3.18s) === RUN TestCopyLongFile run.go:180: Remote "B2 bucket rclone-test-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" copy_test.go:154: Test only runs on local --- SKIP: TestCopyLongFile (0.36s) === RUN TestCopyFileBackupDir run.go:180: Remote "B2 bucket rclone-test-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" 2025/10/25 03:56:10 DEBUG : Creating backend with remote "TestB2:rclone-test-moyalup4yuje/backup" 2025/10/25 03:56:11 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18) 2025/10/25 03:56:12 DEBUG : dst/file1: sha1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK 2025/10/25 03:56:12 INFO : dst/file1: Copied (server-side copy) 2025/10/25 03:56:12 INFO : dst/file1: Deleted 2025/10/25 03:56:13 DEBUG : dst/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2025/10/25 03:56:13 INFO : dst/file1: Copied (new) --- PASS: TestCopyFileBackupDir (4.70s) === RUN TestCopyFileCompareDest run.go:180: Remote "B2 bucket rclone-test-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" 2025/10/25 03:56:14 DEBUG : Creating backend with remote "TestB2:rclone-test-moyalup4yuje/dst" 2025/10/25 03:56:15 DEBUG : Creating backend with remote "TestB2:rclone-test-moyalup4yuje/CompareDest" 2025/10/25 03:56:16 DEBUG : one: Need to transfer - File not found at Destination 2025/10/25 03:56:18 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2025/10/25 03:56:18 INFO : one: Copied (new) 2025/10/25 03:56:18 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/10/25 03:56:19 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2025/10/25 03:56:19 INFO : one: Copied (replaced existing) 2025/10/25 03:56:20 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/10/25 03:56:21 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/10/25 03:56:21 DEBUG : one: Destination found in --compare-dest, skipping 2025/10/25 03:56:22 DEBUG : two: Need to transfer - File not found at Destination 2025/10/25 03:56:22 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/10/25 03:56:22 DEBUG : two: Destination found in --compare-dest, skipping 2025/10/25 03:56:22 DEBUG : two: Need to transfer - File not found at Destination 2025/10/25 03:56:22 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/10/25 03:56:22 DEBUG : two: Destination found in --compare-dest, skipping 2025/10/25 03:56:23 DEBUG : two: Need to transfer - File not found at Destination 2025/10/25 03:56:23 DEBUG : two: Sizes differ (src 5 vs dst 3) 2025/10/25 03:56:23 DEBUG : two: sha1 = a4be8877ad88f3109a6411985a1f359e10e2d930 OK 2025/10/25 03:56:23 INFO : two: Copied (new) --- PASS: TestCopyFileCompareDest (10.99s) === RUN TestCopyFileCopyDest run.go:180: Remote "B2 bucket rclone-test-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" 2025/10/25 03:56:25 DEBUG : Creating backend with remote "TestB2:rclone-test-moyalup4yuje/dst" 2025/10/25 03:56:26 DEBUG : Creating backend with remote "TestB2:rclone-test-moyalup4yuje/CopyDest" 2025/10/25 03:56:27 DEBUG : one: Need to transfer - File not found at Destination 2025/10/25 03:56:29 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2025/10/25 03:56:29 INFO : one: Copied (new) 2025/10/25 03:56:29 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/10/25 03:56:30 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2025/10/25 03:56:30 INFO : one: Copied (replaced existing) 2025/10/25 03:56:31 DEBUG : Creating backend with remote "TestB2:rclone-test-moyalup4yuje/BackupDir" 2025/10/25 03:56:32 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/10/25 03:56:33 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/10/25 03:56:33 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/10/25 03:56:34 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2025/10/25 03:56:34 INFO : one: Copied (server-side copy) 2025/10/25 03:56:35 INFO : one: Deleted 2025/10/25 03:56:35 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2025/10/25 03:56:35 INFO : one: Copied (server-side copy) 2025/10/25 03:56:35 DEBUG : one: Destination found in --copy-dest, using server-side copy 2025/10/25 03:56:36 DEBUG : two: Need to transfer - File not found at Destination 2025/10/25 03:56:36 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/10/25 03:56:36 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2025/10/25 03:56:36 INFO : two: Copied (server-side copy) 2025/10/25 03:56:36 DEBUG : two: Destination found in --copy-dest, using server-side copy 2025/10/25 03:56:37 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/10/25 03:56:37 DEBUG : two: Unchanged skipping 2025/10/25 03:56:38 DEBUG : three: Need to transfer - File not found at Destination 2025/10/25 03:56:38 DEBUG : three: Sizes differ (src 7 vs dst 5) 2025/10/25 03:56:38 DEBUG : three: Destination not found in --copy-dest 2025/10/25 03:56:39 DEBUG : three: sha1 = 370e613335f3cf2f19b2a2d35a86402f55c659f8 OK 2025/10/25 03:56:39 INFO : three: Copied (new) --- PASS: TestCopyFileCopyDest (16.08s) === RUN TestCopyInplace run.go:180: Remote "B2 bucket rclone-test-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" copy_test.go:371: Partial uploads not supported --- SKIP: TestCopyInplace (0.36s) === RUN TestCopyLongFileName run.go:180: Remote "B2 bucket rclone-test-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" copy_test.go:404: Partial uploads not supported --- SKIP: TestCopyLongFileName (0.36s) === RUN TestCopyLongFileNameCollision run.go:180: Remote "B2 bucket rclone-test-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" copy_test.go:437: Partial uploads not supported --- SKIP: TestCopyLongFileNameCollision (0.36s) === RUN TestCopyFileMaxTransfer run.go:180: Remote "B2 bucket rclone-test-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" 2025/10/25 03:56:42 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination 2025/10/25 03:56:43 DEBUG : TestCopyFileMaxTransfer/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2025/10/25 03:56:43 INFO : TestCopyFileMaxTransfer/file1: Copied (new) 2025/10/25 03:56:43 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination 2025/10/25 03:56:43 ERROR : TestCopyFileMaxTransfer/file2: Failed to copy: Post "https://pod-000-1029-09.backblaze.com/b2api/v1/b2_upload_file/df421dc3800d1b9b9fa80e1b/c001_v0001029_t0003": max transfer limit reached as set by --max-transfer 2025/10/25 03:56:44 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination 2025/10/25 03:56:44 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination 2025/10/25 03:56:45 DEBUG : TestCopyFileMaxTransfer/file4: sha1 = b1606a3c00a0584a17df5eca210bdce660e062b4 OK 2025/10/25 03:56:45 INFO : TestCopyFileMaxTransfer/file4: Copied (new) --- PASS: TestCopyFileMaxTransfer (4.27s) === RUN TestDeduplicateInteractive run.go:180: Remote "B2 bucket rclone-test-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", 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-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", 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-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", 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-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateFirst (0.36s) === RUN TestDeduplicateNewest run.go:180: Remote "B2 bucket rclone-test-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", 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-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" 2025/10/25 03:56:50 INFO : B2 bucket rclone-test-moyalup4yuje: Looking for duplicate sha1 hashes using newest mode. 2025/10/25 03:56:50 NOTICE: 6eef9ded653699917da3255c09d056cad69dd33c: Found 3 files with duplicate sha1 hashes 2025/10/25 03:56:50 INFO : one: Deleted 2025/10/25 03:56:50 INFO : also/one: Deleted 2025/10/25 03:56:50 NOTICE: 6eef9ded653699917da3255c09d056cad69dd33c: Deleted 2 extra copies --- PASS: TestDeduplicateNewestByHash (3.42s) === RUN TestDeduplicateOldest run.go:180: Remote "B2 bucket rclone-test-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateOldest (0.36s) === RUN TestDeduplicateLargest run.go:180: Remote "B2 bucket rclone-test-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", 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-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", 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-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", 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-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", 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-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" 2025/10/25 03:56:57 DEBUG : a.txt: Excluded (Size Filter) 2025/10/25 03:56:57 DEBUG : a.txt: Excluded 2025/10/25 03:56:57 DEBUG : sub dir/hello world: Excluded (Size Filter) 2025/10/25 03:56:57 DEBUG : sub dir/hello world: Excluded 2025/10/25 03:56:57 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2025/10/25 03:56:57 DEBUG : sub dir/hello world2: Excluded 2025/10/25 03:56:57 DEBUG : sub dir/hello world: Excluded (Size Filter) 2025/10/25 03:56:57 DEBUG : sub dir/hello world: Excluded 2025/10/25 03:56:57 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2025/10/25 03:56:57 DEBUG : sub dir/hello world2: Excluded 2025/10/25 03:56:58 DEBUG : sub dir/ignore dir: Excluded 2025/10/25 03:56:58 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSorted (7.70s) === RUN TestListDirSortedFn run.go:180: Remote "B2 bucket rclone-test-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" 2025/10/25 03:57:04 DEBUG : a.txt: Excluded (Size Filter) 2025/10/25 03:57:04 DEBUG : a.txt: Excluded 2025/10/25 03:57:05 DEBUG : sub dir/hello world: Excluded (Size Filter) 2025/10/25 03:57:05 DEBUG : sub dir/hello world: Excluded 2025/10/25 03:57:05 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2025/10/25 03:57:05 DEBUG : sub dir/hello world2: Excluded 2025/10/25 03:57:05 DEBUG : sub dir/hello world: Excluded (Size Filter) 2025/10/25 03:57:05 DEBUG : sub dir/hello world: Excluded 2025/10/25 03:57:05 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2025/10/25 03:57:05 DEBUG : sub dir/hello world2: Excluded 2025/10/25 03:57:05 DEBUG : sub dir/ignore dir: Excluded 2025/10/25 03:57:06 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSortedFn (7.04s) === RUN TestListJSON run.go:180: Remote "B2 bucket rclone-test-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", 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/10/25 03:57:10 ERROR : file1: Failed to read hash: hash type not supported === RUN TestListJSON/Metadata --- PASS: TestListJSON (3.97s) --- PASS: TestListJSON/Default (0.18s) --- PASS: TestListJSON/FilesOnly (0.18s) --- PASS: TestListJSON/DirsOnly (0.18s) --- PASS: TestListJSON/Recurse (0.18s) --- PASS: TestListJSON/SubDir (0.18s) --- PASS: TestListJSON/NoModTime (0.19s) --- PASS: TestListJSON/NoMimeType (0.19s) --- PASS: TestListJSON/ShowHash (0.19s) --- PASS: TestListJSON/HashTypes (0.19s) --- PASS: TestListJSON/Metadata (0.22s) === RUN TestStatJSON run.go:180: Remote "B2 bucket rclone-test-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", 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/10/25 03:57:16 DEBUG : Creating backend with remote "TestB2:rclone-test-moyalup4yuje/notfound" --- PASS: TestStatJSON (6.50s) --- PASS: TestStatJSON/Root (0.19s) --- PASS: TestStatJSON/RootFilesOnly (0.00s) --- PASS: TestStatJSON/RootDirsOnly (0.19s) --- PASS: TestStatJSON/Dir (0.79s) --- PASS: TestStatJSON/DirWithTrailingSlash (0.18s) --- PASS: TestStatJSON/File (0.18s) --- PASS: TestStatJSON/NotFound (0.36s) --- PASS: TestStatJSON/DirFilesOnly (0.18s) --- PASS: TestStatJSON/FileFilesOnly (0.18s) --- PASS: TestStatJSON/NotFoundFilesOnly (0.18s) --- PASS: TestStatJSON/DirDirsOnly (0.18s) --- PASS: TestStatJSON/FileDirsOnly (0.19s) --- PASS: TestStatJSON/NotFoundDirsOnly (0.18s) --- PASS: TestStatJSON/RootNotFound (1.82s) === RUN TestMkdir run.go:180: Remote "B2 bucket rclone-test-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" 2025/10/25 03:57:18 INFO : B2 bucket rclone-test-moyalup4yuje: Making directory 2025/10/25 03:57:18 INFO : B2 bucket rclone-test-moyalup4yuje: Making directory --- PASS: TestMkdir (0.55s) === RUN TestLsd run.go:180: Remote "B2 bucket rclone-test-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" --- PASS: TestLsd (1.69s) === RUN TestLs run.go:180: Remote "B2 bucket rclone-test-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" --- PASS: TestLs (2.08s) === RUN TestLsWithFilesFrom run.go:180: Remote "B2 bucket rclone-test-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" 2025/10/25 03:57:24 DEBUG : empty space: Excluded (FilesFrom Filter) --- PASS: TestLsWithFilesFrom (2.61s) === RUN TestLsLong run.go:180: Remote "B2 bucket rclone-test-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" --- PASS: TestLsLong (2.12s) === RUN TestHashSums run.go:180: Remote "B2 bucket rclone-test-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" === RUN TestHashSums/Sha1 === RUN TestHashSums/Sha1Download --- PASS: TestHashSums (2.33s) --- PASS: TestHashSums/Sha1 (0.18s) --- PASS: TestHashSums/Sha1Download (0.38s) === RUN TestHashSumsWithErrors 2025/10/25 03:57:30 DEBUG : Creating backend with remote ":memory:" 2025/10/25 03:57:30 ERROR : file1: hash unsupported: hash type not supported --- PASS: TestHashSumsWithErrors (0.00s) === RUN TestHashStream 2025/10/25 03:57:30 DEBUG : Creating md5 hash of 0 bytes read from input stream 2025/10/25 03:57:30 DEBUG : Creating md5 hash of 0 bytes read from input stream 2025/10/25 03:57:30 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2025/10/25 03:57:30 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2025/10/25 03:57:30 DEBUG : Creating md5 hash of 12 bytes read from input stream 2025/10/25 03:57:30 DEBUG : Creating md5 hash of 12 bytes read from input stream 2025/10/25 03:57:30 DEBUG : Creating sha1 hash of 12 bytes read from input stream 2025/10/25 03:57:30 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-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" --- PASS: TestCount (2.63s) === RUN TestDelete run.go:180: Remote "B2 bucket rclone-test-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" 2025/10/25 03:57:34 DEBUG : Waiting for deletions to finish 2025/10/25 03:57:34 DEBUG : large: Excluded (Size Filter) 2025/10/25 03:57:34 INFO : medium: Deleted 2025/10/25 03:57:35 INFO : small: Deleted --- PASS: TestDelete (3.09s) === RUN TestMaxDelete run.go:180: Remote "B2 bucket rclone-test-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" 2025/10/25 03:57:37 DEBUG : Waiting for deletions to finish 2025/10/25 03:57:37 ERROR : small: Got fatal error on delete: --max-delete threshold reached 2025/10/25 03:57:38 INFO : large: Deleted 2025/10/25 03:57:38 INFO : medium: Deleted --- PASS: TestMaxDelete (3.60s) === RUN TestMaxDeleteSizeLargeFile run.go:180: Remote "B2 bucket rclone-test-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" 2025/10/25 03:57:40 DEBUG : Waiting for deletions to finish 2025/10/25 03:57:40 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached 2025/10/25 03:57:41 INFO : medium: Deleted 2025/10/25 03:57:41 INFO : small: Deleted --- PASS: TestMaxDeleteSizeLargeFile (2.89s) === RUN TestMaxDeleteSize run.go:180: Remote "B2 bucket rclone-test-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" 2025/10/25 03:57:43 DEBUG : Waiting for deletions to finish 2025/10/25 03:57:43 ERROR : small: Got fatal error on delete: --max-delete-size threshold reached 2025/10/25 03:57:44 INFO : large: Deleted 2025/10/25 03:57:44 INFO : medium: Deleted --- PASS: TestMaxDeleteSize (3.01s) === RUN TestReadFile run.go:180: Remote "B2 bucket rclone-test-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" --- PASS: TestReadFile (2.27s) === RUN TestRetry 2025/10/25 03:57:47 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 1/5 2025/10/25 03:57:47 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 2/5 2025/10/25 03:57:47 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2025/10/25 03:57:47 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2025/10/25 03:57:47 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2025/10/25 03:57:47 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2025/10/25 03:57:47 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-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" --- PASS: TestCat (4.46s) === RUN TestPurge 2025/10/25 03:57:52 DEBUG : Creating backend with remote "TestB2:rclone-test-magujey0nive" 2025/10/25 03:57:52 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/10/25 03:57:52 DEBUG : Creating backend with remote "/tmp/rclone3577288525" run.go:180: Remote "B2 bucket rclone-test-magujey0nive", Local "Local file system at /tmp/rclone3577288525", Modify Window "1ms" 2025/10/25 03:57:54 INFO : A2: Making directory 2025/10/25 03:57:54 INFO : A1/B2: Making directory 2025/10/25 03:57:54 INFO : A1/B2/C2: Making directory 2025/10/25 03:57:54 INFO : A1/B1/C3: Making directory 2025/10/25 03:57:54 INFO : A3: Making directory 2025/10/25 03:57:54 INFO : A3/B3: Making directory 2025/10/25 03:57:54 INFO : A3/B3/C4: Making directory fstest.go:250: Filtering empty directory "A2" fstest.go:250: Filtering empty directory "A1/B2" fstest.go:250: Filtering empty directory "A1/B2/C2" fstest.go:250: Filtering empty directory "A1/B1/C3" fstest.go:250: Filtering empty directory "A3" fstest.go:250: Filtering empty directory "A3/B3" fstest.go:250: Filtering empty directory "A3/B3/C4" 2025/10/25 03:57:55 INFO : B2 bucket rclone-test-magujey0nive: cleaning bucket "rclone-test-magujey0nive" of all files 2025/10/25 03:57:55 DEBUG : A1/B1/C1/one: Deleting (id "4_z5ff20d23804d1b9b9fa80e1b_f1078c96691bb4362_d20251025_m035754_c001_v0001117_t0048_u01761364674037") fstest.go:250: Filtering empty directory "A2" fstest.go:250: Filtering empty directory "A1/B2" fstest.go:250: Filtering empty directory "A1/B2/C2" fstest.go:250: Filtering empty directory "A3" fstest.go:250: Filtering empty directory "A3/B3" fstest.go:250: Filtering empty directory "A3/B3/C4" 2025/10/25 03:57:56 INFO : B2 bucket rclone-test-magujey0nive: cleaning bucket "rclone-test-magujey0nive" of all files 2025/10/25 03:57:56 DEBUG : A1/two: Deleting (id "4_z5ff20d23804d1b9b9fa80e1b_f1078c96691bb4364_d20251025_m035754_c001_v0001117_t0049_u01761364674969") 2025/10/25 03:57:57 DEBUG : B2 bucket rclone-test-magujey0nive: Purge remote 2025/10/25 03:57:57 INFO : B2 bucket rclone-test-magujey0nive: cleaning bucket "rclone-test-magujey0nive" of all files 2025/10/25 03:57:57 NOTICE: purge failed: directory not found --- PASS: TestPurge (5.37s) === RUN TestRmdirsNoLeaveRoot run.go:180: Remote "B2 bucket rclone-test-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" 2025/10/25 03:57:58 INFO : A2: Making directory 2025/10/25 03:57:58 INFO : A1/B2: Making directory 2025/10/25 03:57:58 INFO : A1/B2/C2: Making directory 2025/10/25 03:57:58 INFO : A1/B1/C3: Making directory 2025/10/25 03:57:58 INFO : A3: Making directory 2025/10/25 03:57:58 INFO : A3/B3: Making directory 2025/10/25 03:57:58 INFO : A3/B3/C4: Making directory fstest.go:250: Filtering empty directory "A2" fstest.go:250: Filtering empty directory "A1/B2" fstest.go:250: Filtering empty directory "A1/B2/C2" fstest.go:250: Filtering empty directory "A1/B1/C3" fstest.go:250: Filtering empty directory "A3" fstest.go:250: Filtering empty directory "A3/B3" fstest.go:250: Filtering empty directory "A3/B3/C4" 2025/10/25 03:57:59 DEBUG : removing 1 level 3 directories 2025/10/25 03:57:59 INFO : A3/B3/C4: Removing directory fstest.go:250: Filtering empty directory "A2" fstest.go:250: Filtering empty directory "A1/B2" fstest.go:250: Filtering empty directory "A1/B2/C2" fstest.go:250: Filtering empty directory "A1/B1/C3" fstest.go:250: Filtering empty directory "A3" fstest.go:250: Filtering empty directory "A3/B3" 2025/10/25 03:58:01 DEBUG : removing 1 level 0 directories 2025/10/25 03:58:01 INFO : B2 bucket rclone-test-moyalup4yuje: Removing directory 2025/10/25 03:58:01 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.37s) === RUN TestRmdirsLeaveRoot run.go:180: Remote "B2 bucket rclone-test-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" 2025/10/25 03:58:01 INFO : A1: Making directory 2025/10/25 03:58:01 INFO : A1/B1: Making directory 2025/10/25 03:58:01 INFO : A1/B1/C1: Making directory fstest.go:250: Filtering empty directory "A1" fstest.go:250: Filtering empty directory "A1/B1" fstest.go:250: Filtering empty directory "A1/B1/C1" fstest.go:250: Filtering empty directory "A1" --- PASS: TestRmdirsLeaveRoot (0.93s) === RUN TestRmdirsWithFilter run.go:180: Remote "B2 bucket rclone-test-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" 2025/10/25 03:58:02 INFO : A1: Making directory 2025/10/25 03:58:02 INFO : A1/B1: Making directory 2025/10/25 03:58:02 INFO : A1/B1/C1: Making directory fstest.go:250: Filtering empty directory "A1" fstest.go:250: Filtering empty directory "A1/B1" fstest.go:250: Filtering empty directory "A1/B1/C1" fstest.go:250: Filtering empty directory "A1" --- PASS: TestRmdirsWithFilter (0.94s) === RUN TestCopyURL run.go:180: Remote "B2 bucket rclone-test-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" 2025/10/25 03:58:04 DEBUG : filename.txt: File name found in url 2025/10/25 03:58:05 DEBUG : headerfilename.txt: filename found in Content-Disposition header. --- PASS: TestCopyURL (3.58s) === RUN TestCopyURLToWriter --- PASS: TestCopyURLToWriter (0.00s) === RUN TestMoveFile run.go:180: Remote "B2 bucket rclone-test-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" 2025/10/25 03:58:07 DEBUG : file1: Need to transfer - File not found at Destination 2025/10/25 03:58:07 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2025/10/25 03:58:07 INFO : file1: Copied (new) to: sub/file2 2025/10/25 03:58:07 INFO : file1: Deleted 2025/10/25 03:58:08 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/10/25 03:58:08 DEBUG : file1: Unchanged skipping 2025/10/25 03:58:08 INFO : file1: Deleted 2025/10/25 03:58:08 DEBUG : B2 bucket rclone-test-moyalup4yuje: don't need to copy/move sub/file2, it is already at target location --- PASS: TestMoveFile (2.02s) === RUN TestMoveFileWithIgnoreExisting run.go:180: Remote "B2 bucket rclone-test-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" 2025/10/25 03:58:09 DEBUG : file1: Need to transfer - File not found at Destination 2025/10/25 03:58:09 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2025/10/25 03:58:09 INFO : file1: Copied (new) 2025/10/25 03:58:09 INFO : file1: Deleted 2025/10/25 03:58:10 DEBUG : file1: Destination exists, skipping 2025/10/25 03:58:10 DEBUG : file1: Not removing source file as destination file exists and --ignore-existing is set --- PASS: TestMoveFileWithIgnoreExisting (1.69s) === RUN TestCaseInsensitiveMoveFile run.go:180: Remote "B2 bucket rclone-test-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFile (0.38s) === RUN TestCaseInsensitiveMoveFileDryRun run.go:180: Remote "B2 bucket rclone-test-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFileDryRun (0.38s) === RUN TestMoveFileBackupDir run.go:180: Remote "B2 bucket rclone-test-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" 2025/10/25 03:58:12 DEBUG : Creating backend with remote "TestB2:rclone-test-moyalup4yuje/backup" 2025/10/25 03:58:13 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18) 2025/10/25 03:58:14 DEBUG : dst/file1: sha1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK 2025/10/25 03:58:14 INFO : dst/file1: Copied (server-side copy) 2025/10/25 03:58:15 INFO : dst/file1: Deleted 2025/10/25 03:58:15 DEBUG : dst/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2025/10/25 03:58:15 INFO : dst/file1: Copied (new) 2025/10/25 03:58:15 INFO : dst/file1: Deleted --- PASS: TestMoveFileBackupDir (4.60s) === 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-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" 2025/10/25 03:58:18 INFO : A1/B2: Making directory 2025/10/25 03:58:18 INFO : A1/B1/C3: Making directory fstest.go:250: Filtering empty directory "A1/B2" fstest.go:250: Filtering empty directory "A1/B1/C3" 2025/10/25 03:58:19 DEBUG : A1/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2025/10/25 03:58:19 INFO : A1/B1/C1/four: Copied (server-side copy) to: A2/B1/C1/four 2025/10/25 03:58:19 INFO : A1/B1/C1/four: Deleted 2025/10/25 03:58:19 DEBUG : A1/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2025/10/25 03:58:19 INFO : A1/B1/three: Copied (server-side copy) to: A2/B1/three 2025/10/25 03:58:19 DEBUG : A1/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2025/10/25 03:58:19 INFO : A1/two: Copied (server-side copy) to: A2/two 2025/10/25 03:58:19 INFO : A1/B1/three: Deleted 2025/10/25 03:58:19 INFO : A1/two: Deleted 2025/10/25 03:58:20 DEBUG : A1/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2025/10/25 03:58:20 INFO : A1/one: Copied (server-side copy) to: A2/one 2025/10/25 03:58:20 DEBUG : A1/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2025/10/25 03:58:20 INFO : A1/B1/C2/five: Copied (server-side copy) to: A2/B1/C2/five 2025/10/25 03:58:20 INFO : A1/one: Deleted 2025/10/25 03:58:20 INFO : A1/B1/C2/five: Deleted fstest.go:250: Filtering empty directory "A2/B2" fstest.go:250: Filtering empty directory "A2/B1/C3" 2025/10/25 03:58:21 DEBUG : A2/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2025/10/25 03:58:21 INFO : A2/B1/C2/five: Copied (server-side copy) to: A3/B1/C2/five 2025/10/25 03:58:21 DEBUG : A2/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2025/10/25 03:58:21 INFO : A2/B1/C1/four: Copied (server-side copy) to: A3/B1/C1/four 2025/10/25 03:58:21 DEBUG : A2/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2025/10/25 03:58:21 INFO : A2/two: Copied (server-side copy) to: A3/two 2025/10/25 03:58:21 DEBUG : A2/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2025/10/25 03:58:21 INFO : A2/one: Copied (server-side copy) to: A3/one 2025/10/25 03:58:21 INFO : A2/B1/C2/five: Deleted 2025/10/25 03:58:21 DEBUG : A2/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2025/10/25 03:58:21 INFO : A2/B1/three: Copied (server-side copy) to: A3/B1/three 2025/10/25 03:58:21 INFO : A2/B1/C1/four: Deleted 2025/10/25 03:58:21 INFO : A2/two: Deleted 2025/10/25 03:58:21 INFO : A2/one: Deleted 2025/10/25 03:58:21 INFO : A2/B1/three: Deleted fstest.go:250: Filtering empty directory "A3/B2" fstest.go:250: Filtering empty directory "A3/B1/C3" 2025/10/25 03:58:21 INFO : B2 bucket rclone-test-moyalup4yuje: Can't DirMove - falling back to file moves: can't move directory - incompatible remotes 2025/10/25 03:58:22 DEBUG : A3/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2025/10/25 03:58:22 INFO : A3/B1/C2/five: Copied (server-side copy) to: A4/B1/C2/five 2025/10/25 03:58:22 DEBUG : A3/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2025/10/25 03:58:22 INFO : A3/B1/C1/four: Copied (server-side copy) to: A4/B1/C1/four 2025/10/25 03:58:22 DEBUG : A3/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2025/10/25 03:58:22 INFO : A3/two: Copied (server-side copy) to: A4/two 2025/10/25 03:58:22 INFO : A3/B1/C2/five: Deleted 2025/10/25 03:58:22 DEBUG : A3/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2025/10/25 03:58:22 INFO : A3/one: Copied (server-side copy) to: A4/one 2025/10/25 03:58:22 INFO : A3/B1/C1/four: Deleted 2025/10/25 03:58:22 INFO : A3/two: Deleted 2025/10/25 03:58:22 INFO : A3/one: Deleted 2025/10/25 03:58:23 DEBUG : A3/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2025/10/25 03:58:23 INFO : A3/B1/three: Copied (server-side copy) to: A4/B1/three 2025/10/25 03:58:23 INFO : A3/B1/three: Deleted fstest.go:250: Filtering empty directory "A4/B2" fstest.go:250: Filtering empty directory "A4/B1/C3" --- PASS: TestDirMove (8.36s) === RUN TestGetFsInfo run.go:180: Remote "B2 bucket rclone-test-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" --- PASS: TestGetFsInfo (0.37s) === RUN TestRcat === RUN TestRcat/withChecksum=false,ignoreChecksum=false run.go:180: Remote "B2 bucket rclone-test-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" 2025/10/25 03:58:25 DEBUG : B2 bucket rclone-test-moyalup4yuje: File to upload is small (34 bytes), uploading instead of streaming 2025/10/25 03:58:26 DEBUG : no_checksum_small_file_from_pipe: sha1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK 2025/10/25 03:58:26 DEBUG : no_checksum_small_file_from_pipe: Size and sha1 of src and dst objects identical 2025/10/25 03:58:26 DEBUG : no_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2025/10/25 03:58:27 DEBUG : no_checksum_big_file_from_pipe: sha1 = d0080f73aa8b494570ad63df1beb25c01ea9eea2 OK 2025/10/25 03:58:27 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-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" 2025/10/25 03:58:28 DEBUG : B2 bucket rclone-test-moyalup4yuje: File to upload is small (34 bytes), uploading instead of streaming 2025/10/25 03:58:28 DEBUG : with_checksum_small_file_from_pipe: sha1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK 2025/10/25 03:58:28 DEBUG : with_checksum_small_file_from_pipe: Size and sha1 of src and dst objects identical 2025/10/25 03:58:28 DEBUG : with_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2025/10/25 03:58:29 DEBUG : with_checksum_big_file_from_pipe: sha1 = d0080f73aa8b494570ad63df1beb25c01ea9eea2 OK 2025/10/25 03:58:29 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-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" 2025/10/25 03:58:30 DEBUG : B2 bucket rclone-test-moyalup4yuje: File to upload is small (34 bytes), uploading instead of streaming 2025/10/25 03:58:31 DEBUG : ignore_checksum_small_file_from_pipe: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/10/25 03:58:31 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2025/10/25 03:58:32 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-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" 2025/10/25 03:58:33 DEBUG : B2 bucket rclone-test-moyalup4yuje: File to upload is small (34 bytes), uploading instead of streaming 2025/10/25 03:58:33 DEBUG : ignore_checksum_small_file_from_pipe: Src hash empty - aborting Dst hash check 2025/10/25 03:58:33 DEBUG : ignore_checksum_small_file_from_pipe: Size of src and dst objects identical 2025/10/25 03:58:33 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2025/10/25 03:58:34 DEBUG : ignore_checksum_big_file_from_pipe: Src hash empty - aborting Dst hash check 2025/10/25 03:58:34 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical --- PASS: TestRcat (10.47s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (2.96s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (2.52s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (2.54s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (2.44s) === RUN TestRcatMetadata run.go:180: Remote "B2 bucket rclone-test-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" operations_test.go:1540: Skipping as destination doesn't support user metadata --- SKIP: TestRcatMetadata (0.37s) === RUN TestRcatSize run.go:180: Remote "B2 bucket rclone-test-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" 2025/10/25 03:58:36 DEBUG : B2 bucket rclone-test-moyalup4yuje: File to upload is small (60 bytes), uploading instead of streaming 2025/10/25 03:58:36 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2025/10/25 03:58:36 DEBUG : potato2: Size and sha1 of src and dst objects identical --- PASS: TestRcatSize (1.69s) === RUN TestRcatSizeMetadata run.go:180: Remote "B2 bucket rclone-test-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" operations_test.go:1621: Skipping as destination doesn't support user metadata --- SKIP: TestRcatSizeMetadata (0.39s) === RUN TestTouchDir run.go:180: Remote "B2 bucket rclone-test-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" 2025/10/25 03:58:39 DEBUG : B2 bucket rclone-test-moyalup4yuje: Touching "empty space" 2025/10/25 03:58:39 DEBUG : B2 bucket rclone-test-moyalup4yuje: Touching "potato2" 2025/10/25 03:58:39 DEBUG : B2 bucket rclone-test-moyalup4yuje: Touching "sub dir/potato3" --- PASS: TestTouchDir (4.64s) === RUN TestMkdirMetadata run.go:180: Remote "B2 bucket rclone-test-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" operations_test.go:1711: Skipping test as remote does not support MkdirMetadata --- SKIP: TestMkdirMetadata (0.37s) === RUN TestMkdirModTime run.go:180: Remote "B2 bucket rclone-test-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" operations_test.go:1730: Skipping test as remote does not support DirSetModTime or MkdirMetadata --- SKIP: TestMkdirModTime (0.37s) === RUN TestCopyDirMetadata run.go:180: Remote "B2 bucket rclone-test-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" operations_test.go:1751: Skipping test as remote does not support WriteDirMetadata or MkdirMetadata --- SKIP: TestCopyDirMetadata (0.37s) === RUN TestSetDirModTime run.go:180: Remote "B2 bucket rclone-test-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" operations_test.go:1787: Skipping test as remote does not support DirSetModTime or WriteDirSetModTime --- SKIP: TestSetDirModTime (0.37s) === RUN TestDirsEqual run.go:180: Remote "B2 bucket rclone-test-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" operations_test.go:1831: Skipping test as remote does not support WriteDirMetadata or MkdirMetadata --- SKIP: TestDirsEqual (0.37s) === RUN TestRemoveExisting run.go:180: Remote "B2 bucket rclone-test-moyalup4yuje", Local "Local file system at /tmp/rclone3874582328", Modify Window "1ms" operations_test.go:1900: 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/10/25 03:58:44 DEBUG : B2 bucket rclone-test-moyalup4yuje: Purge remote 2025/10/25 03:58:44 INFO : B2 bucket rclone-test-moyalup4yuje: cleaning bucket "rclone-test-moyalup4yuje" of all files 2025/10/25 03:58:45 DEBUG : A1/B1/C1/four: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f405638df902cf1b8_d20251025_m035819_c001_v7007000_t0000_u01761364699661") 2025/10/25 03:58:45 DEBUG : A1/B1/C1/four: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd6830b_d20251025_m035817_c001_v0001029_t0011_u01761364697501") 2025/10/25 03:58:45 DEBUG : A1/B1/C1/one: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f4040107c98ab68fe_d20251025_m035800_c001_v7007000_t0000_u01761364680601") 2025/10/25 03:58:45 DEBUG : A1/B1/C1/one: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd682d7_d20251025_m035757_c001_v0001029_t0018_u01761364677921") 2025/10/25 03:58:45 DEBUG : A1/B1/C2/five: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f4040107c98ab694b_d20251025_m035820_c001_v7007000_t0000_u01761364700205") 2025/10/25 03:58:45 DEBUG : A1/B1/C2/five: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd6830d_d20251025_m035817_c001_v0001029_t0021_u01761364697907") 2025/10/25 03:58:45 DEBUG : A1/B1/three: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f407238d1373b677e_d20251025_m035819_c001_v7007000_t0000_u01761364699859") 2025/10/25 03:58:45 DEBUG : A1/B1/three: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd68309_d20251025_m035817_c001_v0001029_t0003_u01761364697111") 2025/10/25 03:58:45 DEBUG : A1/one: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f405638df902cf1b9_d20251025_m035820_c001_v7007000_t0000_u01761364700132") 2025/10/25 03:58:45 DEBUG : A1/one: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd68303_d20251025_m035816_c001_v0001029_t0029_u01761364696442") 2025/10/25 03:58:45 DEBUG : A1/two: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f4009d4288779e0c6_d20251025_m035819_c001_v7007000_t0000_u01761364699887") 2025/10/25 03:58:45 DEBUG : A1/two: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd68305_d20251025_m035816_c001_v0001029_t0028_u01761364696774") 2025/10/25 03:58:45 DEBUG : A1/two: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f4040107c98ab68ff_d20251025_m035800_c001_v7007000_t0000_u01761364680967") 2025/10/25 03:58:46 DEBUG : A1/two: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd682db_d20251025_m035758_c001_v0001029_t0001_u01761364678397") 2025/10/25 03:58:46 DEBUG : A2/B1/C1/four: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f4040107c98ab6953_d20251025_m035821_c001_v7007000_t0000_u01761364701333") 2025/10/25 03:58:46 DEBUG : A2/B1/C1/four: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f1090f29d4150f76d_d20251025_m035819_c001_v0001098_t0023_u01761364699282") 2025/10/25 03:58:46 DEBUG : A2/B1/C2/five: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f405638df902cf1bb_d20251025_m035821_c001_v7007000_t0000_u01761364701236") 2025/10/25 03:58:46 DEBUG : A2/B1/C2/five: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f1064b3db0731f6df_d20251025_m035819_c001_v0001144_t0010_u01761364699630") 2025/10/25 03:58:46 DEBUG : A2/B1/three: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f4009d4288779e0cb_d20251025_m035821_c001_v7007000_t0000_u01761364701448") 2025/10/25 03:58:46 DEBUG : A2/B1/three: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f10727a41deb2308e_d20251025_m035819_c001_v0001183_t0040_u01761364699649") 2025/10/25 03:58:46 DEBUG : A2/one: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f407238d1373b677f_d20251025_m035821_c001_v7007000_t0000_u01761364701405") 2025/10/25 03:58:46 DEBUG : A2/one: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f101e26e5ad4d65c4_d20251025_m035819_c001_v0001105_t0045_u01761364699660") 2025/10/25 03:58:46 DEBUG : A2/two: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f41308852a44bc183_d20251025_m035821_c001_v7007000_t0000_u01761364701349") 2025/10/25 03:58:46 DEBUG : A2/two: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f1111aa8df5d896dd_d20251025_m035819_c001_v0001181_t0003_u01761364699677") 2025/10/25 03:58:46 DEBUG : A3/B1/C1/four: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f4009d4288779e0cf_d20251025_m035822_c001_v7007000_t0000_u01761364702735") 2025/10/25 03:58:46 DEBUG : A3/B1/C1/four: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f10604929f09e60a1_d20251025_m035821_c001_v0001161_t0022_u01761364701009") 2025/10/25 03:58:46 DEBUG : A3/B1/C2/five: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f407238d1373b6780_d20251025_m035822_c001_v7007000_t0000_u01761364702633") 2025/10/25 03:58:46 DEBUG : A3/B1/C2/five: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f106f06247e4e3cab_d20251025_m035821_c001_v0001180_t0009_u01761364701011") 2025/10/25 03:58:46 DEBUG : A3/B1/three: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f41308852a44bc185_d20251025_m035823_c001_v7007000_t0000_u01761364703136") 2025/10/25 03:58:46 DEBUG : A3/B1/three: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f104856dcc8e62753_d20251025_m035821_c001_v0001117_t0024_u01761364701021") 2025/10/25 03:58:46 DEBUG : A3/one: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f4040107c98ab695e_d20251025_m035822_c001_v7007000_t0000_u01761364702816") 2025/10/25 03:58:46 DEBUG : A3/one: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f1183862769b9dd80_d20251025_m035821_c001_v0001171_t0018_u01761364701036") 2025/10/25 03:58:46 DEBUG : A3/two: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f405638df902cf1be_d20251025_m035822_c001_v7007000_t0000_u01761364702806") 2025/10/25 03:58:47 DEBUG : A3/two: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f118757c6b3c7ed16_d20251025_m035821_c001_v0001131_t0050_u01761364701043") 2025/10/25 03:58:47 DEBUG : A4/B1/C1/four: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f41308852a44bc186_d20251025_m035823_c001_v7007000_t0000_u01761364703714") 2025/10/25 03:58:47 DEBUG : A4/B1/C1/four: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f110a9cd204cca507_d20251025_m035822_c001_v0001040_t0050_u01761364702240") 2025/10/25 03:58:47 DEBUG : A4/B1/C2/five: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f41308852a44bc187_d20251025_m035823_c001_v7007000_t0000_u01761364703894") 2025/10/25 03:58:47 DEBUG : A4/B1/C2/five: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f11862b8e9b3ed24d_d20251025_m035822_c001_v0001091_t0037_u01761364702235") 2025/10/25 03:58:47 DEBUG : A4/B1/three: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f41308852a44bc188_d20251025_m035824_c001_v7007000_t0000_u01761364704075") 2025/10/25 03:58:47 DEBUG : A4/B1/three: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f107bd92f3317e246_d20251025_m035822_c001_v0001157_t0028_u01761364702263") 2025/10/25 03:58:47 DEBUG : A4/one: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f41308852a44bc189_d20251025_m035824_c001_v7007000_t0000_u01761364704258") 2025/10/25 03:58:47 DEBUG : A4/one: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f1101177e0e134275_d20251025_m035822_c001_v0001136_t0015_u01761364702279") 2025/10/25 03:58:47 DEBUG : A4/two: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f41308852a44bc18a_d20251025_m035824_c001_v7007000_t0000_u01761364704440") 2025/10/25 03:58:47 DEBUG : A4/two: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f11592d2b33b394c1_d20251025_m035822_c001_v0001120_t0003_u01761364702273") 2025/10/25 03:58:47 DEBUG : BackupDir/one: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f408d91f059ea9810_d20251025_m035639_c001_v7007000_t0000_u01761364599716") 2025/10/25 03:58:47 DEBUG : BackupDir/one: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f101c445319f3b930_d20251025_m035634_c001_v0001029_t0008_u01761364594124") 2025/10/25 03:58:47 DEBUG : CompareDest/one: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f408d91f059ea97bb_d20251025_m035624_c001_v7007000_t0000_u01761364584188") 2025/10/25 03:58:47 DEBUG : CompareDest/one: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd681bc_d20251025_m035620_c001_v0001029_t0020_u01761364580010") 2025/10/25 03:58:47 DEBUG : CompareDest/two: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f408d91f059ea97bc_d20251025_m035624_c001_v7007000_t0000_u01761364584370") 2025/10/25 03:58:47 DEBUG : CompareDest/two: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd681c0_d20251025_m035621_c001_v0001029_t0019_u01761364581313") 2025/10/25 03:58:47 DEBUG : CopyDest/one: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f408d91f059ea9812_d20251025_m035639_c001_v7007000_t0000_u01761364599899") 2025/10/25 03:58:47 DEBUG : CopyDest/one: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd681dc_d20251025_m035630_c001_v0001029_t0019_u01761364590973") 2025/10/25 03:58:47 DEBUG : CopyDest/three: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f408d91f059ea9814_d20251025_m035640_c001_v7007000_t0000_u01761364600081") 2025/10/25 03:58:48 DEBUG : CopyDest/three: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd681e4_d20251025_m035637_c001_v0001029_t0002_u01761364597590") 2025/10/25 03:58:48 DEBUG : CopyDest/two: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f408d91f059ea9815_d20251025_m035640_c001_v7007000_t0000_u01761364600263") 2025/10/25 03:58:48 DEBUG : CopyDest/two: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd681e2_d20251025_m035635_c001_v0001029_t0030_u01761364595932") 2025/10/25 03:58:48 DEBUG : ReadFile: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f404540c70586ab81_d20251025_m035746_c001_v7007000_t0000_u01761364666924") 2025/10/25 03:58:48 DEBUG : ReadFile: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd682c2_d20251025_m035745_c001_v0001029_t0011_u01761364665406") 2025/10/25 03:58:48 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f408d91f059ea9821_d20251025_m035645_c001_v7007000_t0000_u01761364605983") 2025/10/25 03:58:48 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd681fc_d20251025_m035642_c001_v0001029_t0026_u01761364602804") 2025/10/25 03:58:48 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f408d91f059ea9822_d20251025_m035646_c001_v7007000_t0000_u01761364606165") 2025/10/25 03:58:48 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd68204_d20251025_m035645_c001_v0001029_t0009_u01761364605082") 2025/10/25 03:58:48 DEBUG : a.txt: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f415e79b3fd781f6a_d20251025_m035706_c001_v7007000_t0000_u01761364626856") 2025/10/25 03:58:48 DEBUG : a.txt: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd6822b_d20251025_m035701_c001_v0001029_t0002_u01761364621284") 2025/10/25 03:58:48 DEBUG : a.txt: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f415e79b3fd781f5b_d20251025_m035659_c001_v7007000_t0000_u01761364619819") 2025/10/25 03:58:48 DEBUG : a.txt: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd68216_d20251025_m035653_c001_v0001029_t0027_u01761364613582") 2025/10/25 03:58:48 DEBUG : also/one: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f408d91f059ea9827_d20251025_m035650_c001_v7007000_t0000_u01761364610669") 2025/10/25 03:58:48 DEBUG : also/one: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd6820a_d20251025_m035648_c001_v0001029_t0003_u01761364608807") 2025/10/25 03:58:48 DEBUG : another: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f408d91f059ea9829_d20251025_m035651_c001_v7007000_t0000_u01761364611213") 2025/10/25 03:58:48 DEBUG : another: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd6820c_d20251025_m035649_c001_v0001029_t0009_u01761364609232") 2025/10/25 03:58:48 DEBUG : backup/dst/file1: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f4040107c98ab6931_d20251025_m035815_c001_v7007000_t0000_u01761364695882") 2025/10/25 03:58:48 DEBUG : backup/dst/file1: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f119cd32c44226e9e_d20251025_m035814_c001_v0001137_t0029_u01761364694654") 2025/10/25 03:58:48 DEBUG : backup/dst/file1: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f408d91f059ea97a3_d20251025_m035613_c001_v7007000_t0000_u01761364573558") 2025/10/25 03:58:48 DEBUG : backup/dst/file1: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f106068a8de419124_d20251025_m035612_c001_v0001165_t0034_u01761364572268") 2025/10/25 03:58:49 DEBUG : data/banana: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f416dece3f616a507_d20251025_m035518_c001_v7007000_t0000_u01761364518965") 2025/10/25 03:58:49 DEBUG : data/banana: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd68130_d20251025_m035516_c001_v0001029_t0036_u01761364516565") 2025/10/25 03:58:49 DEBUG : data/banana: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd6810c_d20251025_m035506_c001_v0001029_t0002_u01761364506628") 2025/10/25 03:58:49 DEBUG : data/potato: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f416dece3f616a508_d20251025_m035519_c001_v7007000_t0000_u01761364519149") 2025/10/25 03:58:49 DEBUG : data/potato: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd68132_d20251025_m035517_c001_v0001029_t0000_u01761364517052") 2025/10/25 03:58:49 DEBUG : data/potato: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd68114_d20251025_m035508_c001_v0001029_t0039_u01761364508973") 2025/10/25 03:58:49 DEBUG : dst/file1: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f4040107c98ab6933_d20251025_m035816_c001_v7007000_t0000_u01761364696068") 2025/10/25 03:58:49 DEBUG : dst/file1: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd682ff_d20251025_m035815_c001_v0001029_t0024_u01761364695157") 2025/10/25 03:58:49 DEBUG : dst/file1: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f4040107c98ab692c_d20251025_m035814_c001_v7007000_t0000_u01761364694970") 2025/10/25 03:58:49 DEBUG : dst/file1: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd682f5_d20251025_m035811_c001_v0001029_t0036_u01761364691846") 2025/10/25 03:58:49 DEBUG : dst/file1: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f408d91f059ea97a4_d20251025_m035613_c001_v7007000_t0000_u01761364573743") 2025/10/25 03:58:49 DEBUG : dst/file1: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd681a2_d20251025_m035612_c001_v0001029_t0003_u01761364572757") 2025/10/25 03:58:49 DEBUG : dst/file1: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f408d91f059ea97a1_d20251025_m035612_c001_v7007000_t0000_u01761364572571") 2025/10/25 03:58:49 DEBUG : dst/file1: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd6819e_d20251025_m035609_c001_v0001029_t0025_u01761364569412") 2025/10/25 03:58:49 DEBUG : dst/one: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f408d91f059ea9816_d20251025_m035640_c001_v7007000_t0000_u01761364600446") 2025/10/25 03:58:49 DEBUG : dst/one: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f102db2014c4c276a_d20251025_m035635_c001_v0001144_t0010_u01761364595182") 2025/10/25 03:58:49 DEBUG : dst/one: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f422f61662232607c_d20251025_m035634_c001_v7007000_t0000_u01761364594979") 2025/10/25 03:58:49 DEBUG : dst/one: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd681d4_d20251025_m035630_c001_v0001029_t0030_u01761364590637") 2025/10/25 03:58:49 DEBUG : dst/one: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f10249b68ea79dad3_d20251025_m035629_c001_v0001179_t0024_u01761364589892") 2025/10/25 03:58:49 DEBUG : dst/one: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f10249b68ea79dad1_d20251025_m035628_c001_v0001179_t0004_u01761364588947") 2025/10/25 03:58:49 DEBUG : dst/one: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f408d91f059ea97bd_d20251025_m035624_c001_v7007000_t0000_u01761364584551") 2025/10/25 03:58:49 DEBUG : dst/one: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd681b8_d20251025_m035619_c001_v0001029_t0021_u01761364579686") 2025/10/25 03:58:49 DEBUG : dst/one: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f100fc13108130dc5_d20251025_m035618_c001_v0001184_t0029_u01761364578935") 2025/10/25 03:58:50 DEBUG : dst/one: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f100fc13108130db9_d20251025_m035617_c001_v0001184_t0033_u01761364577970") 2025/10/25 03:58:50 DEBUG : dst/three: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f408d91f059ea9818_d20251025_m035640_c001_v7007000_t0000_u01761364600631") 2025/10/25 03:58:50 DEBUG : dst/three: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f10249b68ea79db08_d20251025_m035639_c001_v0001179_t0028_u01761364599107") 2025/10/25 03:58:50 DEBUG : dst/two: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f408d91f059ea9819_d20251025_m035640_c001_v7007000_t0000_u01761364600815") 2025/10/25 03:58:50 DEBUG : dst/two: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f110ae364d7da4ba5_d20251025_m035636_c001_v0001182_t0025_u01761364596811") 2025/10/25 03:58:50 DEBUG : dst/two: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f408d91f059ea97bf_d20251025_m035624_c001_v7007000_t0000_u01761364584733") 2025/10/25 03:58:50 DEBUG : dst/two: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f100fc13108130de4_d20251025_m035623_c001_v0001184_t0030_u01761364583578") 2025/10/25 03:58:50 DEBUG : empty space: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f41308852a44bc19f_d20251025_m035841_c001_v7007000_t0000_u01761364721999") 2025/10/25 03:58:50 DEBUG : empty space: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f105b94e1b1f5c210_d20251025_m035841_c001_v0001100_t0042_u01761364721324") 2025/10/25 03:58:50 DEBUG : empty space: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd6833c_d20251025_m035838_c001_v0001029_t0038_u01761364718405") 2025/10/25 03:58:50 DEBUG : empty space: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f415e79b3fd781fa2_d20251025_m035732_c001_v7007000_t0000_u01761364652074") 2025/10/25 03:58:50 DEBUG : empty space: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd68286_d20251025_m035730_c001_v0001029_t0024_u01761364650674") 2025/10/25 03:58:50 DEBUG : empty space: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f415e79b3fd781f9c_d20251025_m035729_c001_v7007000_t0000_u01761364649623") 2025/10/25 03:58:50 DEBUG : empty space: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd6827a_d20251025_m035728_c001_v0001029_t0020_u01761364648275") 2025/10/25 03:58:50 DEBUG : empty space: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f415e79b3fd781f96_d20251025_m035727_c001_v7007000_t0000_u01761364647282") 2025/10/25 03:58:50 DEBUG : empty space: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd68274_d20251025_m035726_c001_v0001029_t0005_u01761364646202") 2025/10/25 03:58:50 DEBUG : empty space: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f415e79b3fd781f93_d20251025_m035725_c001_v7007000_t0000_u01761364645162") 2025/10/25 03:58:50 DEBUG : empty space: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd6826a_d20251025_m035723_c001_v0001029_t0039_u01761364643502") 2025/10/25 03:58:50 DEBUG : empty space: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f415e79b3fd781f90_d20251025_m035722_c001_v7007000_t0000_u01761364642555") 2025/10/25 03:58:50 DEBUG : empty space: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd68264_d20251025_m035721_c001_v0001029_t0041_u01761364641655") 2025/10/25 03:58:51 DEBUG : empty space: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f416dece3f616a4f1_d20251025_m035500_c001_v7007000_t0000_u01761364500556") 2025/10/25 03:58:51 DEBUG : empty space: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd680ed_d20251025_m035457_c001_v0001029_t0017_u01761364497427") 2025/10/25 03:58:51 DEBUG : empty space: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f416dece3f616a4ea_d20251025_m035455_c001_v7007000_t0000_u01761364495578") 2025/10/25 03:58:51 DEBUG : empty space: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd680da_d20251025_m035451_c001_v0001029_t0020_u01761364491423") 2025/10/25 03:58:51 DEBUG : empty space: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f416dece3f616a4e3_d20251025_m035448_c001_v7007000_t0000_u01761364488721") 2025/10/25 03:58:51 DEBUG : empty space: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd680c4_d20251025_m035445_c001_v0001029_t0021_u01761364485534") 2025/10/25 03:58:51 DEBUG : file1: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f4040107c98ab6912_d20251025_m035810_c001_v7007000_t0000_u01761364690711") 2025/10/25 03:58:51 DEBUG : file1: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd682f1_d20251025_m035809_c001_v0001029_t0032_u01761364689583") 2025/10/25 03:58:51 DEBUG : file1: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f4040107c98ab6901_d20251025_m035806_c001_v7007000_t0000_u01761364686440") 2025/10/25 03:58:51 DEBUG : file1: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f409ec0dbadd682e3_d20251025_m035804_c001_v0001029_t0003_u01761364684345") 2025/10/25 03:58:51 DEBUG : file1: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f404540c70586ab85_d20251025_m035751_c001_v7007000_t0000_u01761364671624") 2025/10/25 03:58:51 DEBUG : file1: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd682c4_d20251025_m035747_c001_v0001029_t0003_u01761364667725") 2025/10/25 03:58:51 DEBUG : file1: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f415e79b3fd781f8b_d20251025_m035718_c001_v7007000_t0000_u01761364638174") 2025/10/25 03:58:51 DEBUG : file1: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd68251_d20251025_m035712_c001_v0001029_t0036_u01761364632288") 2025/10/25 03:58:51 DEBUG : file1: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f415e79b3fd781f80_d20251025_m035711_c001_v7007000_t0000_u01761364631660") 2025/10/25 03:58:51 DEBUG : file1: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd68245_d20251025_m035708_c001_v0001029_t0013_u01761364628322") 2025/10/25 03:58:51 DEBUG : file2: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f4040107c98ab6903_d20251025_m035806_c001_v7007000_t0000_u01761364686626") 2025/10/25 03:58:51 DEBUG : file2: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f409ec0dbadd682e9_d20251025_m035805_c001_v0001029_t0003_u01761364685880") 2025/10/25 03:58:51 DEBUG : file2: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f404540c70586ab86_d20251025_m035751_c001_v7007000_t0000_u01761364671810") 2025/10/25 03:58:51 DEBUG : file2: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd682c6_d20251025_m035748_c001_v0001029_t0039_u01761364668135") 2025/10/25 03:58:51 DEBUG : filename.txt: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f4040107c98ab6904_d20251025_m035806_c001_v7007000_t0000_u01761364686813") 2025/10/25 03:58:52 DEBUG : filename.txt: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd682e5_d20251025_m035804_c001_v0001029_t0043_u01761364684911") 2025/10/25 03:58:52 DEBUG : headerfilename.txt: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f4040107c98ab6905_d20251025_m035806_c001_v7007000_t0000_u01761364686999") 2025/10/25 03:58:52 DEBUG : headerfilename.txt: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd682e7_d20251025_m035805_c001_v0001029_t0000_u01761364685407") 2025/10/25 03:58:52 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f41308852a44bc195_d20251025_m035835_c001_v7007000_t0000_u01761364715094") 2025/10/25 03:58:52 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd6832f_d20251025_m035833_c001_v0001029_t0008_u01761364713696") 2025/10/25 03:58:52 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f41308852a44bc192_d20251025_m035832_c001_v7007000_t0000_u01761364712654") 2025/10/25 03:58:52 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd68323_d20251025_m035831_c001_v0001029_t0012_u01761364711175") 2025/10/25 03:58:52 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f41308852a44bc196_d20251025_m035835_c001_v7007000_t0000_u01761364715276") 2025/10/25 03:58:52 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd6832d_d20251025_m035833_c001_v0001029_t0038_u01761364713387") 2025/10/25 03:58:52 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f41308852a44bc193_d20251025_m035832_c001_v7007000_t0000_u01761364712836") 2025/10/25 03:58:52 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd68321_d20251025_m035830_c001_v0001029_t0033_u01761364710841") 2025/10/25 03:58:52 DEBUG : large: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f4205eaf0283cf5c3_d20251025_m035744_c001_v7007000_t0000_u01761364664083") 2025/10/25 03:58:52 DEBUG : large: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd682ba_d20251025_m035743_c001_v0001029_t0019_u01761364663199") 2025/10/25 03:58:52 DEBUG : large: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f4205eaf0283cf5bf_d20251025_m035741_c001_v7007000_t0000_u01761364661642") 2025/10/25 03:58:52 DEBUG : large: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd682ae_d20251025_m035740_c001_v0001029_t0012_u01761364660304") 2025/10/25 03:58:52 DEBUG : large: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f404540c70586ab70_d20251025_m035738_c001_v7007000_t0000_u01761364658003") 2025/10/25 03:58:52 DEBUG : large: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd682a0_d20251025_m035736_c001_v0001029_t0022_u01761364656867") 2025/10/25 03:58:52 DEBUG : large: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f404540c70586ab6a_d20251025_m035735_c001_v7007000_t0000_u01761364655528") 2025/10/25 03:58:52 DEBUG : large: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd68292_d20251025_m035733_c001_v0001029_t0004_u01761364653777") 2025/10/25 03:58:52 DEBUG : medium: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f404540c70586ab7a_d20251025_m035744_c001_v7007000_t0000_u01761364664094") 2025/10/25 03:58:52 DEBUG : medium: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd682b4_d20251025_m035742_c001_v0001029_t0010_u01761364662740") 2025/10/25 03:58:53 DEBUG : medium: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f404540c70586ab77_d20251025_m035741_c001_v7007000_t0000_u01761364661068") 2025/10/25 03:58:53 DEBUG : medium: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd682ac_d20251025_m035739_c001_v0001029_t0017_u01761364659936") 2025/10/25 03:58:53 DEBUG : medium: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f404540c70586ab72_d20251025_m035738_c001_v7007000_t0000_u01761364658189") 2025/10/25 03:58:53 DEBUG : medium: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd6829c_d20251025_m035736_c001_v0001029_t0031_u01761364656381") 2025/10/25 03:58:53 DEBUG : medium: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f415e79b3fd781fb6_d20251025_m035734_c001_v7007000_t0000_u01761364654594") 2025/10/25 03:58:53 DEBUG : medium: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd6828e_d20251025_m035733_c001_v0001029_t0000_u01761364653343") 2025/10/25 03:58:53 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f41308852a44bc18c_d20251025_m035827_c001_v7007000_t0000_u01761364707590") 2025/10/25 03:58:53 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd68319_d20251025_m035826_c001_v0001029_t0038_u01761364706296") 2025/10/25 03:58:53 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f41308852a44bc18d_d20251025_m035827_c001_v7007000_t0000_u01761364707770") 2025/10/25 03:58:53 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd68317_d20251025_m035825_c001_v0001029_t0002_u01761364705720") 2025/10/25 03:58:53 DEBUG : not-one: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f408d91f059ea982a_d20251025_m035651_c001_v7007000_t0000_u01761364611395") 2025/10/25 03:58:53 DEBUG : not-one: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd6820e_d20251025_m035649_c001_v0001029_t0012_u01761364609712") 2025/10/25 03:58:53 DEBUG : one: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f408d91f059ea9826_d20251025_m035650_c001_v7007000_t0000_u01761364610486") 2025/10/25 03:58:53 DEBUG : one: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd68206_d20251025_m035648_c001_v0001029_t0044_u01761364608343") 2025/10/25 03:58:53 DEBUG : potato1: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f41308852a44bc198_d20251025_m035837_c001_v7007000_t0000_u01761364717153") 2025/10/25 03:58:53 DEBUG : potato1: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd68334_d20251025_m035836_c001_v0001029_t0035_u01761364716014") 2025/10/25 03:58:53 DEBUG : potato2: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f41308852a44bc1a0_d20251025_m035842_c001_v7007000_t0000_u01761364722180") 2025/10/25 03:58:53 DEBUG : potato2: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f112a62d3c5857660_d20251025_m035840_c001_v0001178_t0043_u01761364720321") 2025/10/25 03:58:53 DEBUG : potato2: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd6833a_d20251025_m035838_c001_v0001029_t0043_u01761364718095") 2025/10/25 03:58:53 DEBUG : potato2: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f41308852a44bc199_d20251025_m035837_c001_v7007000_t0000_u01761364717334") 2025/10/25 03:58:53 DEBUG : potato2: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd68336_d20251025_m035836_c001_v0001029_t0029_u01761364716361") 2025/10/25 03:58:53 DEBUG : potato2: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f415e79b3fd781fa3_d20251025_m035732_c001_v7007000_t0000_u01761364652257") 2025/10/25 03:58:54 DEBUG : potato2: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd68284_d20251025_m035730_c001_v0001029_t0041_u01761364650177") 2025/10/25 03:58:54 DEBUG : potato2: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f415e79b3fd781f9e_d20251025_m035729_c001_v7007000_t0000_u01761364649807") 2025/10/25 03:58:54 DEBUG : potato2: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd68278_d20251025_m035727_c001_v0001029_t0023_u01761364647843") 2025/10/25 03:58:54 DEBUG : potato2: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f415e79b3fd781f97_d20251025_m035727_c001_v7007000_t0000_u01761364647475") 2025/10/25 03:58:54 DEBUG : potato2: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd68272_d20251025_m035725_c001_v0001029_t0040_u01761364645718") 2025/10/25 03:58:54 DEBUG : potato2: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f415e79b3fd781f94_d20251025_m035725_c001_v7007000_t0000_u01761364645347") 2025/10/25 03:58:54 DEBUG : potato2: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd68268_d20251025_m035723_c001_v0001029_t0024_u01761364643109") 2025/10/25 03:58:54 DEBUG : potato2: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f415e79b3fd781f91_d20251025_m035722_c001_v7007000_t0000_u01761364642739") 2025/10/25 03:58:54 DEBUG : potato2: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd68262_d20251025_m035721_c001_v0001029_t0009_u01761364641026") 2025/10/25 03:58:54 DEBUG : potato2: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f416dece3f616a4f2_d20251025_m035500_c001_v7007000_t0000_u01761364500738") 2025/10/25 03:58:54 DEBUG : potato2: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd680f1_d20251025_m035458_c001_v0001029_t0024_u01761364498347") 2025/10/25 03:58:54 DEBUG : potato2: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f416dece3f616a4eb_d20251025_m035455_c001_v7007000_t0000_u01761364495765") 2025/10/25 03:58:54 DEBUG : potato2: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd680dc_d20251025_m035452_c001_v0001029_t0018_u01761364492328") 2025/10/25 03:58:54 DEBUG : potato2: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f416dece3f616a4e4_d20251025_m035448_c001_v7007000_t0000_u01761364488905") 2025/10/25 03:58:54 DEBUG : potato2: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd680c6_d20251025_m035446_c001_v0001029_t0011_u01761364486289") 2025/10/25 03:58:54 DEBUG : remotepotato: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f416dece3f616a4f3_d20251025_m035500_c001_v7007000_t0000_u01761364500922") 2025/10/25 03:58:54 DEBUG : remotepotato: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd680f5_d20251025_m035459_c001_v0001029_t0033_u01761364499452") 2025/10/25 03:58:54 DEBUG : remotepotato: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f416dece3f616a4ec_d20251025_m035455_c001_v7007000_t0000_u01761364495948") 2025/10/25 03:58:54 DEBUG : remotepotato: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd680e0_d20251025_m035453_c001_v0001029_t0036_u01761364493916") 2025/10/25 03:58:54 DEBUG : remotepotato: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f416dece3f616a4e5_d20251025_m035449_c001_v7007000_t0000_u01761364489091") 2025/10/25 03:58:55 DEBUG : remotepotato: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd680ca_d20251025_m035447_c001_v0001029_t0004_u01761364487567") 2025/10/25 03:58:55 DEBUG : rutabaga: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f416dece3f616a4f4_d20251025_m035501_c001_v7007000_t0000_u01761364501108") 2025/10/25 03:58:55 DEBUG : rutabaga: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd680e9_d20251025_m035456_c001_v0001029_t0030_u01761364496496") 2025/10/25 03:58:55 DEBUG : rutabaga: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f416dece3f616a4ed_d20251025_m035456_c001_v7007000_t0000_u01761364496131") 2025/10/25 03:58:55 DEBUG : rutabaga: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd680d4_d20251025_m035449_c001_v0001029_t0008_u01761364489643") 2025/10/25 03:58:55 DEBUG : rutabaga: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f416dece3f616a4e6_d20251025_m035449_c001_v7007000_t0000_u01761364489274") 2025/10/25 03:58:55 DEBUG : rutabaga: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd680c2_d20251025_m035444_c001_v0001029_t0042_u01761364484388") 2025/10/25 03:58:55 DEBUG : small: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f404540c70586ab7c_d20251025_m035744_c001_v7007000_t0000_u01761364664652") 2025/10/25 03:58:55 DEBUG : small: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd682b2_d20251025_m035742_c001_v0001029_t0044_u01761364662393") 2025/10/25 03:58:55 DEBUG : small: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f4205eaf0283cf5be_d20251025_m035741_c001_v7007000_t0000_u01761364661082") 2025/10/25 03:58:55 DEBUG : small: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd682aa_d20251025_m035739_c001_v0001029_t0024_u01761364659502") 2025/10/25 03:58:55 DEBUG : small: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f404540c70586ab74_d20251025_m035738_c001_v7007000_t0000_u01761364658750") 2025/10/25 03:58:55 DEBUG : small: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd68298_d20251025_m035735_c001_v0001029_t0022_u01761364655904") 2025/10/25 03:58:55 DEBUG : small: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f404540c70586ab69_d20251025_m035734_c001_v7007000_t0000_u01761364654970") 2025/10/25 03:58:55 DEBUG : small: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd6828a_d20251025_m035732_c001_v0001029_t0020_u01761364652808") 2025/10/25 03:58:55 DEBUG : sub dir/hello world: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f415e79b3fd781f8f_d20251025_m035720_c001_v7007000_t0000_u01761364640659") 2025/10/25 03:58:55 DEBUG : sub dir/hello world: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd6825e_d20251025_m035719_c001_v0001029_t0000_u01761364639698") 2025/10/25 03:58:55 DEBUG : sub dir/hello world: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f415e79b3fd781f6c_d20251025_m035707_c001_v7007000_t0000_u01761364627039") 2025/10/25 03:58:55 DEBUG : sub dir/hello world: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd6822f_d20251025_m035702_c001_v0001029_t0012_u01761364622129") 2025/10/25 03:58:55 DEBUG : sub dir/hello world: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f415e79b3fd781f5c_d20251025_m035700_c001_v7007000_t0000_u01761364620004") 2025/10/25 03:58:55 DEBUG : sub dir/hello world: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd6821b_d20251025_m035654_c001_v0001029_t0023_u01761364614590") 2025/10/25 03:58:56 DEBUG : sub dir/hello world2: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f415e79b3fd781f6e_d20251025_m035707_c001_v7007000_t0000_u01761364627223") 2025/10/25 03:58:56 DEBUG : sub dir/hello world2: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd68231_d20251025_m035702_c001_v0001029_t0035_u01761364622487") 2025/10/25 03:58:56 DEBUG : sub dir/hello world2: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f415e79b3fd781f5e_d20251025_m035700_c001_v7007000_t0000_u01761364620187") 2025/10/25 03:58:56 DEBUG : sub dir/hello world2: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd6821d_d20251025_m035654_c001_v0001029_t0004_u01761364614969") 2025/10/25 03:58:56 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f415e79b3fd781f6f_d20251025_m035707_c001_v7007000_t0000_u01761364627408") 2025/10/25 03:58:56 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd68235_d20251025_m035702_c001_v0001029_t0031_u01761364622962") 2025/10/25 03:58:56 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f415e79b3fd781f5f_d20251025_m035700_c001_v7007000_t0000_u01761364620370") 2025/10/25 03:58:56 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd68221_d20251025_m035655_c001_v0001029_t0032_u01761364615478") 2025/10/25 03:58:56 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f415e79b3fd781f70_d20251025_m035707_c001_v7007000_t0000_u01761364627590") 2025/10/25 03:58:56 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd6823b_d20251025_m035703_c001_v0001029_t0035_u01761364623397") 2025/10/25 03:58:56 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f415e79b3fd781f60_d20251025_m035700_c001_v7007000_t0000_u01761364620553") 2025/10/25 03:58:56 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd68223_d20251025_m035655_c001_v0001029_t0040_u01761364615961") 2025/10/25 03:58:56 DEBUG : sub dir/potato3: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f41308852a44bc1a1_d20251025_m035842_c001_v7007000_t0000_u01761364722361") 2025/10/25 03:58:56 DEBUG : sub dir/potato3: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f100dd70f20117ae2_d20251025_m035840_c001_v0001098_t0045_u01761364720560") 2025/10/25 03:58:56 DEBUG : sub dir/potato3: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd6833e_d20251025_m035838_c001_v0001029_t0002_u01761364718760") 2025/10/25 03:58:56 DEBUG : sub dir/potato3: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f415e79b3fd781fa4_d20251025_m035732_c001_v7007000_t0000_u01761364652440") 2025/10/25 03:58:56 DEBUG : sub dir/potato3: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd68288_d20251025_m035731_c001_v0001029_t0044_u01761364651112") 2025/10/25 03:58:56 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f415e79b3fd781f72_d20251025_m035707_c001_v7007000_t0000_u01761364627774") 2025/10/25 03:58:56 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd6823d_d20251025_m035703_c001_v0001029_t0014_u01761364623851") 2025/10/25 03:58:56 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f415e79b3fd781f61_d20251025_m035700_c001_v7007000_t0000_u01761364620736") 2025/10/25 03:58:56 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd68225_d20251025_m035656_c001_v0001029_t0008_u01761364616345") 2025/10/25 03:58:56 DEBUG : sub/file2: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f4040107c98ab6907_d20251025_m035809_c001_v7007000_t0000_u01761364689023") 2025/10/25 03:58:57 DEBUG : sub/file2: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd682eb_d20251025_m035807_c001_v0001029_t0017_u01761364687564") 2025/10/25 03:58:57 DEBUG : sub/file2: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f415e79b3fd781f8c_d20251025_m035718_c001_v7007000_t0000_u01761364638357") 2025/10/25 03:58:57 DEBUG : sub/file2: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd68253_d20251025_m035712_c001_v0001029_t0032_u01761364632661") 2025/10/25 03:58:57 DEBUG : sub/file2: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f415e79b3fd781f81_d20251025_m035711_c001_v7007000_t0000_u01761364631923") 2025/10/25 03:58:57 DEBUG : sub/file2: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd68247_d20251025_m035708_c001_v0001029_t0034_u01761364628747") 2025/10/25 03:58:57 DEBUG : sub/file2: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f408d91f059ea979a_d20251025_m035608_c001_v7007000_t0000_u01761364568674") 2025/10/25 03:58:57 DEBUG : sub/file2: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd68194_d20251025_m035606_c001_v0001029_t0019_u01761364566783") 2025/10/25 03:58:57 DEBUG : test-multithread-abort: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f416dece3f616a4e1_d20251025_m035443_c001_v7007000_t0000_u01761364483473") 2025/10/25 03:58:57 DEBUG : test-multithread-abort: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd680a0_d20251025_m035427_c001_v0001029_t0001_u01761364467267") 2025/10/25 03:58:57 DEBUG : test-multithread-copy-false-16777215-2: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f423f3964a66bee06_d20251025_m035347_c001_v7007000_t0000_u01761364427213") 2025/10/25 03:58:57 DEBUG : test-multithread-copy-false-16777215-2: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd6803f_d20251025_m035339_c001_v0001029_t0016_u01761364419661") 2025/10/25 03:58:57 DEBUG : test-multithread-copy-false-16777216-2: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f423f3964a66bee20_d20251025_m035355_c001_v7007000_t0000_u01761364435487") 2025/10/25 03:58:57 DEBUG : test-multithread-copy-false-16777216-2: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd68057_d20251025_m035347_c001_v0001029_t0008_u01761364427990") 2025/10/25 03:58:57 DEBUG : test-multithread-copy-false-16777217-2: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f423f3964a66bee25_d20251025_m035404_c001_v7007000_t0000_u01761364444053") 2025/10/25 03:58:57 DEBUG : test-multithread-copy-false-16777217-2: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd68067_d20251025_m035356_c001_v0001029_t0017_u01761364436274") 2025/10/25 03:58:57 DEBUG : test-multithread-copy-true-16777215-2: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f4068ede49cb8f5df_d20251025_m035411_c001_v7007000_t0000_u01761364451375") 2025/10/25 03:58:57 DEBUG : test-multithread-copy-true-16777215-2: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f223f3964a66bee28_d20251025_m035406_c001_v0001093_t0004_u01761364446358") 2025/10/25 03:58:57 DEBUG : test-multithread-copy-true-16777216-2: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f416b2c496b857805_d20251025_m035418_c001_v7007000_t0000_u01761364458425") 2025/10/25 03:58:57 DEBUG : test-multithread-copy-true-16777216-2: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f2068ede49cb8f5e5_d20251025_m035413_c001_v0001183_t0034_u01761364453589") 2025/10/25 03:58:57 DEBUG : test-multithread-copy-true-16777217-2: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f416b2c496b85780b_d20251025_m035425_c001_v7007000_t0000_u01761364465892") 2025/10/25 03:58:57 DEBUG : test-multithread-copy-true-16777217-2: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f216b2c496b857807_d20251025_m035420_c001_v0001184_t0017_u01761364460778") 2025/10/25 03:58:58 DEBUG : test.sum: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f416dece3f616a50a_d20251025_m035519_c001_v7007000_t0000_u01761364519333") 2025/10/25 03:58:58 DEBUG : test.sum: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd68134_d20251025_m035517_c001_v0001029_t0042_u01761364517499") 2025/10/25 03:58:58 DEBUG : test.sum: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd6812e_d20251025_m035515_c001_v0001029_t0023_u01761364515237") 2025/10/25 03:58:58 DEBUG : test.sum: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd6812c_d20251025_m035513_c001_v0001029_t0042_u01761364513977") 2025/10/25 03:58:58 DEBUG : test.sum: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd68124_d20251025_m035512_c001_v0001029_t0037_u01761364512518") 2025/10/25 03:58:58 DEBUG : test.sum: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd6811e_d20251025_m035510_c001_v0001029_t0036_u01761364510974") 2025/10/25 03:58:58 DEBUG : test.sum: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd68118_d20251025_m035509_c001_v0001029_t0002_u01761364509537") 2025/10/25 03:58:58 DEBUG : test.sum: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd68110_d20251025_m035507_c001_v0001029_t0020_u01761364507016") 2025/10/25 03:58:58 DEBUG : test.sum: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f416dece3f616a4f7_d20251025_m035503_c001_v7007000_t0000_u01761364503643") 2025/10/25 03:58:58 DEBUG : test.sum: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd68103_d20251025_m035502_c001_v0001029_t0039_u01761364502769") 2025/10/25 03:58:58 DEBUG : test.sum: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd680fd_d20251025_m035501_c001_v0001029_t0003_u01761364501478") 2025/10/25 03:58:58 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f41308852a44bc18f_d20251025_m035830_c001_v7007000_t0000_u01761364710106") 2025/10/25 03:58:58 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd6831d_d20251025_m035828_c001_v0001029_t0014_u01761364708786") 2025/10/25 03:58:58 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f41308852a44bc190_d20251025_m035830_c001_v7007000_t0000_u01761364710287") 2025/10/25 03:58:58 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd6831b_d20251025_m035828_c001_v0001029_t0026_u01761364708325") 2025/10/25 03:58:58 DEBUG : zend.txt: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f415e79b3fd781f74_d20251025_m035707_c001_v7007000_t0000_u01761364627956") 2025/10/25 03:58:58 DEBUG : zend.txt: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd6822d_d20251025_m035701_c001_v0001029_t0024_u01761364621683") 2025/10/25 03:58:58 DEBUG : zend.txt: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f415e79b3fd781f62_d20251025_m035700_c001_v7007000_t0000_u01761364620920") 2025/10/25 03:58:58 DEBUG : zend.txt: Deleting (id "4_zdf421dc3800d1b9b9fa80e1b_f109ec0dbadd68219_d20251025_m035654_c001_v0001029_t0021_u01761364614138") "./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -fast-list" - Finished OK in 5m23.093099947s (try 1/5)