"./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -fast-list" - Starting (try 1/5) 2025/11/22 02:31:08 DEBUG : Creating backend with remote "TestB2:rclone-test-vajayal2cato" 2025/11/22 02:31:08 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/22 02:31:08 DEBUG : Creating backend with remote "/tmp/rclone2639800977" === RUN TestDoMultiThreadCopy --- PASS: TestDoMultiThreadCopy (0.00s) === RUN TestMultithreadCalculateNumChunks === RUN TestMultithreadCalculateNumChunks/{size:1_chunkSize:65536_wantNumChunks:1} === RUN TestMultithreadCalculateNumChunks/{size:1048576_chunkSize:1_wantNumChunks:1048576} === RUN TestMultithreadCalculateNumChunks/{size:1048576_chunkSize:2_wantNumChunks:524288} === RUN TestMultithreadCalculateNumChunks/{size:1048577_chunkSize:2_wantNumChunks:524289} === RUN TestMultithreadCalculateNumChunks/{size:1048575_chunkSize:2_wantNumChunks:524288} --- PASS: TestMultithreadCalculateNumChunks (0.00s) --- PASS: TestMultithreadCalculateNumChunks/{size:1_chunkSize:65536_wantNumChunks:1} (0.00s) --- PASS: TestMultithreadCalculateNumChunks/{size:1048576_chunkSize:1_wantNumChunks:1048576} (0.00s) --- PASS: TestMultithreadCalculateNumChunks/{size:1048576_chunkSize:2_wantNumChunks:524288} (0.00s) --- PASS: TestMultithreadCalculateNumChunks/{size:1048577_chunkSize:2_wantNumChunks:524289} (0.00s) --- PASS: TestMultithreadCalculateNumChunks/{size:1048575_chunkSize:2_wantNumChunks:524288} (0.00s) === RUN TestMultithreadCopy run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" 2025/11/22 02:31:09 DEBUG : chunksize-probe: Cancelling large file upload === RUN TestMultithreadCopy/upload=false,size=16777215,streams=2 2025/11/22 02:31:14 DEBUG : test-multithread-copy-false-16777215-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2025/11/22 02:31:14 DEBUG : test-multithread-copy-false-16777215-2: multi-thread copy: write buffer set to 131072 2025/11/22 02:31:14 DEBUG : test-multithread-copy-false-16777215-2: multi-thread copy: chunk size 64Mi was bigger than source file size 16.000Mi 2025/11/22 02:31:14 DEBUG : test-multithread-copy-false-16777215-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2025/11/22 02:31:14 DEBUG : test-multithread-copy-false-16777215-2: multi-thread copy: number of streams 4 was bigger than number of chunks 1 2025/11/22 02:31:14 DEBUG : test-multithread-copy-false-16777215-2: Starting multi-thread copy with 1 chunks of size 16.000Mi with 1 parallel streams 2025/11/22 02:31:14 DEBUG : test-multithread-copy-false-16777215-2: multi-thread copy: chunk 1/1 (0-16777215) size 16.000Mi starting 2025/11/22 02:31:15 DEBUG : test-multithread-copy-false-16777215-2: writing chunk 0 2025/11/22 02:31:17 DEBUG : test-multithread-copy-false-16777215-2: multi-thread copy: chunk 1/1 (0-16777215) size 16.000Mi finished 2025/11/22 02:31:17 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/11/22 02:31:23 DEBUG : test-multithread-copy-false-16777216-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2025/11/22 02:31:23 DEBUG : test-multithread-copy-false-16777216-2: multi-thread copy: write buffer set to 131072 2025/11/22 02:31:23 DEBUG : test-multithread-copy-false-16777216-2: multi-thread copy: chunk size 64Mi was bigger than source file size 16Mi 2025/11/22 02:31:23 DEBUG : test-multithread-copy-false-16777216-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2025/11/22 02:31:23 DEBUG : test-multithread-copy-false-16777216-2: multi-thread copy: number of streams 4 was bigger than number of chunks 1 2025/11/22 02:31:23 DEBUG : test-multithread-copy-false-16777216-2: Starting multi-thread copy with 1 chunks of size 16Mi with 1 parallel streams 2025/11/22 02:31:23 DEBUG : test-multithread-copy-false-16777216-2: multi-thread copy: chunk 1/1 (0-16777216) size 16Mi starting 2025/11/22 02:31:23 DEBUG : test-multithread-copy-false-16777216-2: writing chunk 0 2025/11/22 02:31:25 DEBUG : test-multithread-copy-false-16777216-2: multi-thread copy: chunk 1/1 (0-16777216) size 16Mi finished 2025/11/22 02:31:25 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/11/22 02:31:31 DEBUG : test-multithread-copy-false-16777217-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2025/11/22 02:31:31 DEBUG : test-multithread-copy-false-16777217-2: multi-thread copy: write buffer set to 131072 2025/11/22 02:31:31 DEBUG : test-multithread-copy-false-16777217-2: multi-thread copy: chunk size 64Mi was bigger than source file size 16.000Mi 2025/11/22 02:31:31 DEBUG : test-multithread-copy-false-16777217-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2025/11/22 02:31:31 DEBUG : test-multithread-copy-false-16777217-2: multi-thread copy: number of streams 4 was bigger than number of chunks 1 2025/11/22 02:31:31 DEBUG : test-multithread-copy-false-16777217-2: Starting multi-thread copy with 1 chunks of size 16.000Mi with 1 parallel streams 2025/11/22 02:31:31 DEBUG : test-multithread-copy-false-16777217-2: multi-thread copy: chunk 1/1 (0-16777217) size 16.000Mi starting 2025/11/22 02:31:31 DEBUG : test-multithread-copy-false-16777217-2: writing chunk 0 2025/11/22 02:31:33 DEBUG : test-multithread-copy-false-16777217-2: multi-thread copy: chunk 1/1 (0-16777217) size 16.000Mi finished 2025/11/22 02:31:33 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/11/22 02:31:36 DEBUG : test-multithread-copy-true-16777215-2: multi-thread copy: disabling buffering because source is local disk 2025/11/22 02:31:37 DEBUG : test-multithread-copy-true-16777215-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2025/11/22 02:31:37 DEBUG : test-multithread-copy-true-16777215-2: multi-thread copy: number of streams 4 was bigger than number of chunks 2 2025/11/22 02:31:37 DEBUG : test-multithread-copy-true-16777215-2: Starting multi-thread copy with 2 chunks of size 8Mi with 2 parallel streams 2025/11/22 02:31:37 DEBUG : test-multithread-copy-true-16777215-2: multi-thread copy: chunk 2/2 (8388608-16777215) size 8.000Mi starting 2025/11/22 02:31:37 DEBUG : test-multithread-copy-true-16777215-2: Sending chunk 1 length 8388607 2025/11/22 02:31:37 DEBUG : test-multithread-copy-true-16777215-2: multi-thread copy: chunk 1/2 (0-8388608) size 8Mi starting 2025/11/22 02:31:37 DEBUG : test-multithread-copy-true-16777215-2: Sending chunk 0 length 8388608 2025/11/22 02:31:39 DEBUG : test-multithread-copy-true-16777215-2: Done sending chunk 0 2025/11/22 02:31:39 DEBUG : test-multithread-copy-true-16777215-2: multi-thread copy: chunk 1/2 (0-8388608) size 8Mi finished 2025/11/22 02:31:39 DEBUG : test-multithread-copy-true-16777215-2: Done sending chunk 1 2025/11/22 02:31:39 DEBUG : test-multithread-copy-true-16777215-2: multi-thread copy: chunk 2/2 (8388608-16777215) size 8.000Mi finished 2025/11/22 02:31:39 DEBUG : test-multithread-copy-true-16777215-2: Finishing large file upload with 2 parts 2025/11/22 02:31:40 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/11/22 02:31:43 DEBUG : test-multithread-copy-true-16777216-2: multi-thread copy: disabling buffering because source is local disk 2025/11/22 02:31:44 DEBUG : test-multithread-copy-true-16777216-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2025/11/22 02:31:44 DEBUG : test-multithread-copy-true-16777216-2: multi-thread copy: number of streams 4 was bigger than number of chunks 2 2025/11/22 02:31:44 DEBUG : test-multithread-copy-true-16777216-2: Starting multi-thread copy with 2 chunks of size 8Mi with 2 parallel streams 2025/11/22 02:31:44 DEBUG : test-multithread-copy-true-16777216-2: multi-thread copy: chunk 2/2 (8388608-16777216) size 8Mi starting 2025/11/22 02:31:44 DEBUG : test-multithread-copy-true-16777216-2: multi-thread copy: chunk 1/2 (0-8388608) size 8Mi starting 2025/11/22 02:31:44 DEBUG : test-multithread-copy-true-16777216-2: Sending chunk 1 length 8388608 2025/11/22 02:31:44 DEBUG : test-multithread-copy-true-16777216-2: Sending chunk 0 length 8388608 2025/11/22 02:31:46 DEBUG : test-multithread-copy-true-16777216-2: Done sending chunk 1 2025/11/22 02:31:46 DEBUG : test-multithread-copy-true-16777216-2: multi-thread copy: chunk 2/2 (8388608-16777216) size 8Mi finished 2025/11/22 02:31:47 DEBUG : test-multithread-copy-true-16777216-2: Done sending chunk 0 2025/11/22 02:31:47 DEBUG : test-multithread-copy-true-16777216-2: multi-thread copy: chunk 1/2 (0-8388608) size 8Mi finished 2025/11/22 02:31:47 DEBUG : test-multithread-copy-true-16777216-2: Finishing large file upload with 2 parts 2025/11/22 02:31:47 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/11/22 02:31:51 DEBUG : test-multithread-copy-true-16777217-2: multi-thread copy: disabling buffering because source is local disk 2025/11/22 02:31:51 DEBUG : test-multithread-copy-true-16777217-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2025/11/22 02:31:51 DEBUG : test-multithread-copy-true-16777217-2: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2025/11/22 02:31:51 DEBUG : test-multithread-copy-true-16777217-2: Starting multi-thread copy with 3 chunks of size 8Mi with 3 parallel streams 2025/11/22 02:31:51 DEBUG : test-multithread-copy-true-16777217-2: multi-thread copy: chunk 3/3 (16777216-16777217) size 1 starting 2025/11/22 02:31:51 DEBUG : test-multithread-copy-true-16777217-2: Sending chunk 2 length 1 2025/11/22 02:31:51 DEBUG : test-multithread-copy-true-16777217-2: multi-thread copy: chunk 1/3 (0-8388608) size 8Mi starting 2025/11/22 02:31:51 DEBUG : test-multithread-copy-true-16777217-2: multi-thread copy: chunk 2/3 (8388608-16777216) size 8Mi starting 2025/11/22 02:31:51 DEBUG : test-multithread-copy-true-16777217-2: Sending chunk 0 length 8388608 2025/11/22 02:31:51 DEBUG : test-multithread-copy-true-16777217-2: Sending chunk 1 length 8388608 2025/11/22 02:31:52 DEBUG : test-multithread-copy-true-16777217-2: Done sending chunk 2 2025/11/22 02:31:52 DEBUG : test-multithread-copy-true-16777217-2: multi-thread copy: chunk 3/3 (16777216-16777217) size 1 finished 2025/11/22 02:31:54 DEBUG : test-multithread-copy-true-16777217-2: Done sending chunk 0 2025/11/22 02:31:54 DEBUG : test-multithread-copy-true-16777217-2: multi-thread copy: chunk 1/3 (0-8388608) size 8Mi finished 2025/11/22 02:31:54 DEBUG : test-multithread-copy-true-16777217-2: Done sending chunk 1 2025/11/22 02:31:54 DEBUG : test-multithread-copy-true-16777217-2: multi-thread copy: chunk 2/3 (8388608-16777216) size 8Mi finished 2025/11/22 02:31:54 DEBUG : test-multithread-copy-true-16777217-2: Finishing large file upload with 3 parts 2025/11/22 02:31:54 DEBUG : test-multithread-copy-true-16777217-2: Finished multi-thread copy with 3 parts of size 8Mi --- PASS: TestMultithreadCopy (48.08s) --- PASS: TestMultithreadCopy/upload=false,size=16777215,streams=2 (8.54s) --- PASS: TestMultithreadCopy/upload=false,size=16777216,streams=2 (8.20s) --- PASS: TestMultithreadCopy/upload=false,size=16777217,streams=2 (8.23s) --- PASS: TestMultithreadCopy/upload=true,size=16777215,streams=2 (7.03s) --- PASS: TestMultithreadCopy/upload=true,size=16777216,streams=2 (7.01s) --- PASS: TestMultithreadCopy/upload=true,size=16777217,streams=2 (7.30s) === RUN TestMultithreadCopyAbort run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" 2025/11/22 02:31:56 DEBUG : chunksize-probe: Cancelling large file upload 2025/11/22 02:31:59 DEBUG : test-multithread-abort: multi-thread copy: disabling buffering because source is local disk 2025/11/22 02:32:00 DEBUG : test-multithread-abort: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 1 2025/11/22 02:32:00 DEBUG : test-multithread-abort: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2025/11/22 02:32:00 DEBUG : test-multithread-abort: Starting multi-thread copy with 3 chunks of size 8Mi with 3 parallel streams 2025/11/22 02:32:00 DEBUG : test-multithread-abort: multi-thread copy: chunk 3/3 (16777216-16777217) size 1 starting 2025/11/22 02:32:00 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2025/11/22 02:32:00 DEBUG : test-multithread-abort: multi-thread copy: chunk 2/3 (8388608-16777216) size 8Mi starting 2025/11/22 02:32:00 DEBUG : test-multithread-abort: multi-thread copy: chunk 1/3 (0-8388608) size 8Mi starting 2025/11/22 02:32:00 DEBUG : Open with options = [RangeOption(8388608,16777215)] 2025/11/22 02:32:00 DEBUG : Open with options = [RangeOption(0,8388607)] 2025/11/22 02:32:00 DEBUG : test-multithread-abort: Sending chunk 1 length 8388608 2025/11/22 02:32:00 DEBUG : test-multithread-abort: Sending chunk 0 length 8388608 2025/11/22 02:32:02 DEBUG : test-multithread-abort: Done sending chunk 1 2025/11/22 02:32:02 DEBUG : test-multithread-abort: multi-thread copy: chunk 2/3 (8388608-16777216) size 8Mi finished 2025/11/22 02:32:02 DEBUG : test-multithread-abort: Done sending chunk 0 2025/11/22 02:32:02 DEBUG : test-multithread-abort: multi-thread copy: chunk 1/3 (0-8388608) size 8Mi finished 2025/11/22 02:32:02 DEBUG : Returning error reader 2025/11/22 02:32:02 DEBUG : test-multithread-abort: Sending chunk 2 length 1 2025/11/22 02:32:02 DEBUG : BOOM: simulated read failure 2025/11/22 02:32:02 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 0/10: BOOM: simulated read failure 2025/11/22 02:32:02 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2025/11/22 02:32:03 DEBUG : Returning error reader 2025/11/22 02:32:03 DEBUG : BOOM: simulated read failure 2025/11/22 02:32:03 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 1/10: BOOM: simulated read failure 2025/11/22 02:32:03 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2025/11/22 02:32:04 DEBUG : Returning error reader 2025/11/22 02:32:04 DEBUG : BOOM: simulated read failure 2025/11/22 02:32:04 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 2/10: BOOM: simulated read failure 2025/11/22 02:32:04 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2025/11/22 02:32:05 DEBUG : Returning error reader 2025/11/22 02:32:05 DEBUG : BOOM: simulated read failure 2025/11/22 02:32:05 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 3/10: BOOM: simulated read failure 2025/11/22 02:32:05 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2025/11/22 02:32:06 DEBUG : Returning error reader 2025/11/22 02:32:06 DEBUG : BOOM: simulated read failure 2025/11/22 02:32:06 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 4/10: BOOM: simulated read failure 2025/11/22 02:32:06 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2025/11/22 02:32:07 DEBUG : Returning error reader 2025/11/22 02:32:07 DEBUG : BOOM: simulated read failure 2025/11/22 02:32:07 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 5/10: BOOM: simulated read failure 2025/11/22 02:32:07 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2025/11/22 02:32:08 DEBUG : Returning error reader 2025/11/22 02:32:08 DEBUG : BOOM: simulated read failure 2025/11/22 02:32:08 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 6/10: BOOM: simulated read failure 2025/11/22 02:32:08 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2025/11/22 02:32:09 DEBUG : Returning error reader 2025/11/22 02:32:09 DEBUG : BOOM: simulated read failure 2025/11/22 02:32:09 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 7/10: BOOM: simulated read failure 2025/11/22 02:32:09 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2025/11/22 02:32:10 DEBUG : Returning error reader 2025/11/22 02:32:10 DEBUG : BOOM: simulated read failure 2025/11/22 02:32:10 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 8/10: BOOM: simulated read failure 2025/11/22 02:32:10 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2025/11/22 02:32:11 DEBUG : Returning error reader 2025/11/22 02:32:11 DEBUG : BOOM: simulated read failure 2025/11/22 02:32:11 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 9/10: BOOM: simulated read failure 2025/11/22 02:32:11 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2025/11/22 02:32:12 DEBUG : Returning error reader 2025/11/22 02:32:12 DEBUG : BOOM: simulated read failure 2025/11/22 02:32:12 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 10/10: BOOM: simulated read failure 2025/11/22 02:32:12 DEBUG : test-multithread-abort: Reopen failed after offset 0 bytes read: failed to reopen: too many retries 2025/11/22 02:32:12 DEBUG : test-multithread-abort: Error sending chunk 2 (retry=false): Post "https://pod-000-1185-11.backblaze.com/b2api/v1/b2_upload_part/4_z7fa2ada3606dab9b9fa80e1b_f223e42c075ad1ab2_d20251122_m023159_c001_v0001185_t0033_u01763778719979/0014": BOOM: simulated read failure: &url.Error{Op:"Post", URL:"https://pod-000-1185-11.backblaze.com/b2api/v1/b2_upload_part/4_z7fa2ada3606dab9b9fa80e1b_f223e42c075ad1ab2_d20251122_m023159_c001_v0001185_t0033_u01763778719979/0014", Err:(*errors.errorString)(0xc000113490)} 2025/11/22 02:32:12 DEBUG : test-multithread-abort: Error sending chunk 2: Post "https://pod-000-1185-11.backblaze.com/b2api/v1/b2_upload_part/4_z7fa2ada3606dab9b9fa80e1b_f223e42c075ad1ab2_d20251122_m023159_c001_v0001185_t0033_u01763778719979/0014": BOOM: simulated read failure 2025/11/22 02:32:12 DEBUG : test-multithread-abort: multi-thread copy: chunk 3/3 failed: multi-thread copy: failed to write chunk: Post "https://pod-000-1185-11.backblaze.com/b2api/v1/b2_upload_part/4_z7fa2ada3606dab9b9fa80e1b_f223e42c075ad1ab2_d20251122_m023159_c001_v0001185_t0033_u01763778719979/0014": BOOM: simulated read failure 2025/11/22 02:32:12 DEBUG : test-multithread-abort: multi-thread copy: cancelling transfer on exit 2025/11/22 02:32:12 DEBUG : test-multithread-abort: Cancelling large file upload --- PASS: TestMultithreadCopyAbort (17.55s) === RUN TestSizeDiffers 2025/11/22 02:32:14 DEBUG : a: size = 0 OK 2025/11/22 02:32:14 DEBUG : a: size = 1 (memory) 2025/11/22 02:32:14 DEBUG : a: size = 2 (memory) --- PASS: TestSizeDiffers (0.00s) === RUN TestReOpen === RUN TestReOpen/Normal === RUN TestReOpen/Normal/Basics 2025/11/22 02:32:14 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/ErrorAtStart === RUN TestReOpen/Normal/WithErrors 2025/11/22 02:32:14 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2025/11/22 02:32:14 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2025/11/22 02:32:14 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/Normal/TooManyErrors 2025/11/22 02:32:14 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2025/11/22 02:32:14 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2025/11/22 02:32:14 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2025/11/22 02:32:14 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/Normal/ReadAt 2025/11/22 02:32:14 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/11/22 02:32:14 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/11/22 02:32:14 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/Normal/Seek 2025/11/22 02:32:14 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/11/22 02:32:14 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/11/22 02:32:14 DEBUG : potato: Seek from 5 to 2 === RUN TestReOpen/Normal/AccountRead === RUN TestReOpen/Normal/AccountReadDelay 2025/11/22 02:32:14 DEBUG : potato: Seek from 10 to 0 2025/11/22 02:32:14 DEBUG : potato: Seek from 10 to 0 2025/11/22 02:32:14 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/AccountReadError === RUN TestReOpen/WithRangeOption === RUN TestReOpen/WithRangeOption/Basics 2025/11/22 02:32:14 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/ErrorAtStart === RUN TestReOpen/WithRangeOption/WithErrors 2025/11/22 02:32:14 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2025/11/22 02:32:14 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2025/11/22 02:32:14 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithRangeOption/TooManyErrors 2025/11/22 02:32:14 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2025/11/22 02:32:14 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2025/11/22 02:32:14 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2025/11/22 02:32:14 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithRangeOption/ReadAt 2025/11/22 02:32:14 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/11/22 02:32:14 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/11/22 02:32:14 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/WithRangeOption/Seek 2025/11/22 02:32:14 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/11/22 02:32:14 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/11/22 02:32:14 DEBUG : potato: Seek from 5 to 2 2025/11/22 02:32:14 DEBUG : potato: Seek from 7 to 4 === RUN TestReOpen/WithRangeOption/AccountRead === RUN TestReOpen/WithRangeOption/AccountReadDelay 2025/11/22 02:32:14 DEBUG : potato: Seek from 7 to 0 2025/11/22 02:32:14 DEBUG : potato: Seek from 7 to 0 2025/11/22 02:32:14 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/AccountReadError === RUN TestReOpen/WithSeekOption === RUN TestReOpen/WithSeekOption/Basics 2025/11/22 02:32:14 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/ErrorAtStart === RUN TestReOpen/WithSeekOption/WithErrors 2025/11/22 02:32:14 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2025/11/22 02:32:14 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2025/11/22 02:32:14 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithSeekOption/TooManyErrors 2025/11/22 02:32:14 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2025/11/22 02:32:14 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2025/11/22 02:32:14 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2025/11/22 02:32:14 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithSeekOption/ReadAt 2025/11/22 02:32:14 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/11/22 02:32:14 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/11/22 02:32:14 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/WithSeekOption/Seek 2025/11/22 02:32:14 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/11/22 02:32:14 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/11/22 02:32:14 DEBUG : potato: Seek from 5 to 2 2025/11/22 02:32:14 DEBUG : potato: Seek from 7 to 5 === RUN TestReOpen/WithSeekOption/AccountRead === RUN TestReOpen/WithSeekOption/AccountReadDelay 2025/11/22 02:32:14 DEBUG : potato: Seek from 8 to 0 2025/11/22 02:32:14 DEBUG : potato: Seek from 8 to 0 2025/11/22 02:32:14 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/AccountReadError === RUN TestReOpen/UnknownSize === RUN TestReOpen/UnknownSize/Basics 2025/11/22 02:32:14 DEBUG : potato: Seek from 9 to 0 === RUN TestReOpen/UnknownSize/ErrorAtStart === RUN TestReOpen/UnknownSize/WithErrors 2025/11/22 02:32:14 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2025/11/22 02:32:14 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2025/11/22 02:32:14 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/UnknownSize/TooManyErrors 2025/11/22 02:32:14 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2025/11/22 02:32:14 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2025/11/22 02:32:14 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2025/11/22 02:32:14 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/UnknownSize/ReadAt 2025/11/22 02:32:14 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/11/22 02:32:14 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/11/22 02:32:14 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/UnknownSize/Seek 2025/11/22 02:32:14 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/11/22 02:32:14 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/11/22 02:32:14 DEBUG : potato: Seek from 5 to 2 2025/11/22 02:32:14 DEBUG : potato: Seek from 7 to 6 === RUN TestReOpen/UnknownSize/AccountRead === RUN TestReOpen/UnknownSize/AccountReadDelay 2025/11/22 02:32:14 DEBUG : potato: Seek from 9 to 0 2025/11/22 02:32:14 DEBUG : potato: Seek from 9 to 0 2025/11/22 02:32:14 DEBUG : potato: Seek from 9 to 0 === RUN TestReOpen/UnknownSize/AccountReadError --- PASS: TestReOpen (0.00s) --- PASS: TestReOpen/Normal (0.00s) --- PASS: TestReOpen/Normal/Basics (0.00s) --- PASS: TestReOpen/Normal/ErrorAtStart (0.00s) --- PASS: TestReOpen/Normal/WithErrors (0.00s) --- PASS: TestReOpen/Normal/TooManyErrors (0.00s) --- PASS: TestReOpen/Normal/ReadAt (0.00s) --- PASS: TestReOpen/Normal/Seek (0.00s) --- PASS: TestReOpen/Normal/AccountRead (0.00s) --- PASS: TestReOpen/Normal/AccountReadDelay (0.00s) --- PASS: TestReOpen/Normal/AccountReadError (0.00s) --- PASS: TestReOpen/WithRangeOption (0.00s) --- PASS: TestReOpen/WithRangeOption/Basics (0.00s) --- PASS: TestReOpen/WithRangeOption/ErrorAtStart (0.00s) --- PASS: TestReOpen/WithRangeOption/WithErrors (0.00s) --- PASS: TestReOpen/WithRangeOption/TooManyErrors (0.00s) --- PASS: TestReOpen/WithRangeOption/ReadAt (0.00s) --- PASS: TestReOpen/WithRangeOption/Seek (0.00s) --- PASS: TestReOpen/WithRangeOption/AccountRead (0.00s) --- PASS: TestReOpen/WithRangeOption/AccountReadDelay (0.00s) --- PASS: TestReOpen/WithRangeOption/AccountReadError (0.00s) --- PASS: TestReOpen/WithSeekOption (0.00s) --- PASS: TestReOpen/WithSeekOption/Basics (0.00s) --- PASS: TestReOpen/WithSeekOption/ErrorAtStart (0.00s) --- PASS: TestReOpen/WithSeekOption/WithErrors (0.00s) --- PASS: TestReOpen/WithSeekOption/TooManyErrors (0.00s) --- PASS: TestReOpen/WithSeekOption/ReadAt (0.00s) --- PASS: TestReOpen/WithSeekOption/Seek (0.00s) --- PASS: TestReOpen/WithSeekOption/AccountRead (0.00s) --- PASS: TestReOpen/WithSeekOption/AccountReadDelay (0.00s) --- PASS: TestReOpen/WithSeekOption/AccountReadError (0.00s) --- PASS: TestReOpen/UnknownSize (0.00s) --- PASS: TestReOpen/UnknownSize/Basics (0.00s) --- PASS: TestReOpen/UnknownSize/ErrorAtStart (0.00s) --- PASS: TestReOpen/UnknownSize/WithErrors (0.00s) --- PASS: TestReOpen/UnknownSize/TooManyErrors (0.00s) --- PASS: TestReOpen/UnknownSize/ReadAt (0.00s) --- PASS: TestReOpen/UnknownSize/Seek (0.00s) --- PASS: TestReOpen/UnknownSize/AccountRead (0.00s) --- PASS: TestReOpen/UnknownSize/AccountReadDelay (0.00s) --- PASS: TestReOpen/UnknownSize/AccountReadError (0.00s) === RUN TestCheck run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", 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.50s) --- PASS: TestCheck/1 (0.25s) --- PASS: TestCheck/2 (0.18s) --- PASS: TestCheck/3 (0.18s) --- PASS: TestCheck/4 (0.18s) --- PASS: TestCheck/5 (0.18s) --- PASS: TestCheck/6 (0.18s) --- PASS: TestCheck/7 (0.18s) === RUN TestCheckFsError 2025/11/22 02:32:19 DEBUG : Creating backend with remote "nonexistent" 2025/11/22 02:32:19 DEBUG : Creating backend with remote "nonexistent" 2025/11/22 02:32:19 DEBUG : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: Waiting for checks to finish 2025/11/22 02:32:19 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/11/22 02:32:19 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: 0 differences found 2025/11/22 02:32:19 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: 2 errors while checking --- PASS: TestCheckFsError (0.01s) === RUN TestCheckDownload run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" === RUN TestCheckDownload/1 === RUN TestCheckDownload/2 === RUN TestCheckDownload/3 === RUN TestCheckDownload/4 === RUN TestCheckDownload/5 === RUN TestCheckDownload/6 === RUN TestCheckDownload/7 --- PASS: TestCheckDownload (7.28s) --- PASS: TestCheckDownload/1 (0.73s) --- PASS: TestCheckDownload/2 (0.37s) --- PASS: TestCheckDownload/3 (0.36s) --- PASS: TestCheckDownload/4 (0.55s) --- PASS: TestCheckDownload/5 (0.42s) --- PASS: TestCheckDownload/6 (0.38s) --- PASS: TestCheckDownload/7 (0.37s) === RUN TestCheckSizeOnly run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", 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.85s) --- PASS: TestCheckSizeOnly/1 (0.18s) --- PASS: TestCheckSizeOnly/2 (0.18s) --- PASS: TestCheckSizeOnly/3 (0.18s) --- PASS: TestCheckSizeOnly/4 (0.18s) --- PASS: TestCheckSizeOnly/5 (0.18s) --- PASS: TestCheckSizeOnly/6 (0.18s) --- PASS: TestCheckSizeOnly/7 (0.18s) === RUN TestCheckEqualReaders --- PASS: TestCheckEqualReaders (0.00s) === RUN TestParseSumFile run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" 2025/11/22 02:32:33 NOTICE: test.sum: improperly formatted checksum line 4 2025/11/22 02:32:33 NOTICE: test.sum: improperly formatted checksum line 5 2025/11/22 02:32:33 NOTICE: test.sum: improperly formatted checksum line 6 2025/11/22 02:32:33 NOTICE: test.sum: 2 warning(s) suppressed... 2025/11/22 02:32:33 NOTICE: test.sum: improperly formatted checksum line 4 2025/11/22 02:32:33 NOTICE: test.sum: improperly formatted checksum line 5 2025/11/22 02:32:33 NOTICE: test.sum: improperly formatted checksum line 6 2025/11/22 02:32:33 NOTICE: test.sum: 2 warning(s) suppressed... --- PASS: TestParseSumFile (2.49s) === RUN TestCheckSum run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" 2025/11/22 02:32:34 DEBUG : Creating backend with remote "TestB2:rclone-test-vajayal2cato/data" check_test.go:350: B2 bucket rclone-test-vajayal2cato path data lacks md5, skipping --- SKIP: TestCheckSum (1.47s) === RUN TestCheckSumDownload run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" 2025/11/22 02:32:35 DEBUG : Creating backend with remote "TestB2:rclone-test-vajayal2cato/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.42s) --- PASS: TestCheckSumDownload/subtest1 (1.33s) --- PASS: TestCheckSumDownload/subtest2 (0.73s) --- PASS: TestCheckSumDownload/subtest3 (0.96s) --- PASS: TestCheckSumDownload/subtest4 (0.74s) --- PASS: TestCheckSumDownload/subtest5 (0.74s) --- PASS: TestCheckSumDownload/subtest6 (0.74s) --- PASS: TestCheckSumDownload/subtest7 (0.76s) === RUN TestApplyTransforms 2025/11/22 02:32:50 DEBUG : Creating backend with remote "TestB2:rclone-test-lavunog7yubo" 2025/11/22 02:32:50 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/22 02:32:50 DEBUG : Creating backend with remote "/tmp/rclone633843626" run.go:185: Remote "B2 bucket rclone-test-lavunog7yubo", Local "Local file system at /tmp/rclone633843626", Modify Window "1ms" upper checkfile vs. lower remote (without normalization) 2025/11/22 02:32:53 ERROR : hello, world!: sum not found 2025/11/22 02:32:53 ERROR : HELLO, WORLD!: file not in B2 bucket rclone-test-lavunog7yubo 2025/11/22 02:32:53 NOTICE: B2 bucket rclone-test-lavunog7yubo: 1 files missing 2025/11/22 02:32:53 NOTICE: 1 hashes missing 2025/11/22 02:32:53 NOTICE: B2 bucket rclone-test-lavunog7yubo: 1 differences found 2025/11/22 02:32:53 NOTICE: B2 bucket rclone-test-lavunog7yubo: 2 errors while checking upper checkfile vs. lower remote (with normalization) 2025/11/22 02:32:53 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/11/22 02:32:53 NOTICE: B2 bucket rclone-test-lavunog7yubo: 0 differences found 2025/11/22 02:32:53 NOTICE: B2 bucket rclone-test-lavunog7yubo: 1 matching files 2025/11/22 02:32:53 DEBUG : Creating backend with remote "TestB2:rclone-test-zewifom0doyo" 2025/11/22 02:32:53 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/22 02:32:54 DEBUG : Creating backend with remote "/tmp/rclone2867602395" run.go:185: Remote "B2 bucket rclone-test-zewifom0doyo", Local "Local file system at /tmp/rclone2867602395", Modify Window "1ms" lower checkfile vs. upper remote (without normalization) 2025/11/22 02:32:56 ERROR : HELLO, WORLD!: sum not found 2025/11/22 02:32:56 ERROR : hello, world!: file not in B2 bucket rclone-test-zewifom0doyo 2025/11/22 02:32:56 NOTICE: B2 bucket rclone-test-zewifom0doyo: 1 files missing 2025/11/22 02:32:56 NOTICE: 1 hashes missing 2025/11/22 02:32:56 NOTICE: B2 bucket rclone-test-zewifom0doyo: 1 differences found 2025/11/22 02:32:56 NOTICE: B2 bucket rclone-test-zewifom0doyo: 2 errors while checking lower checkfile vs. upper remote (with normalization) 2025/11/22 02:32:56 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/11/22 02:32:56 NOTICE: B2 bucket rclone-test-zewifom0doyo: 0 differences found 2025/11/22 02:32:56 NOTICE: B2 bucket rclone-test-zewifom0doyo: 1 matching files 2025/11/22 02:32:56 DEBUG : Creating backend with remote "TestB2:rclone-test-denijes4tutu" 2025/11/22 02:32:56 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/22 02:32:57 DEBUG : Creating backend with remote "/tmp/rclone1291124646" run.go:185: Remote "B2 bucket rclone-test-denijes4tutu", Local "Local file system at /tmp/rclone1291124646", Modify Window "1ms" lower checkfile vs. upperlowermixed remote (without normalization) 2025/11/22 02:32:59 ERROR : HeLlO, wOrLd!: sum not found 2025/11/22 02:32:59 ERROR : hello, world!: file not in B2 bucket rclone-test-denijes4tutu 2025/11/22 02:32:59 NOTICE: B2 bucket rclone-test-denijes4tutu: 1 files missing 2025/11/22 02:32:59 NOTICE: 1 hashes missing 2025/11/22 02:32:59 NOTICE: B2 bucket rclone-test-denijes4tutu: 1 differences found 2025/11/22 02:32:59 NOTICE: B2 bucket rclone-test-denijes4tutu: 2 errors while checking lower checkfile vs. upperlowermixed remote (with normalization) 2025/11/22 02:33:00 DEBUG : HeLlO, wOrLd!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/11/22 02:33:00 NOTICE: B2 bucket rclone-test-denijes4tutu: 0 differences found 2025/11/22 02:33:00 NOTICE: B2 bucket rclone-test-denijes4tutu: 1 matching files 2025/11/22 02:33:00 DEBUG : Creating backend with remote "TestB2:rclone-test-cateyic4sofe" 2025/11/22 02:33:00 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/22 02:33:00 DEBUG : Creating backend with remote "/tmp/rclone776061388" run.go:185: Remote "B2 bucket rclone-test-cateyic4sofe", Local "Local file system at /tmp/rclone776061388", Modify Window "1ms" upperlowermixed checkfile vs. upper remote (without normalization) 2025/11/22 02:33:02 ERROR : HELLO, WORLD!: sum not found 2025/11/22 02:33:02 ERROR : HeLlO, wOrLd!: file not in B2 bucket rclone-test-cateyic4sofe 2025/11/22 02:33:02 NOTICE: B2 bucket rclone-test-cateyic4sofe: 1 files missing 2025/11/22 02:33:02 NOTICE: 1 hashes missing 2025/11/22 02:33:02 NOTICE: B2 bucket rclone-test-cateyic4sofe: 1 differences found 2025/11/22 02:33:02 NOTICE: B2 bucket rclone-test-cateyic4sofe: 2 errors while checking upperlowermixed checkfile vs. upper remote (with normalization) 2025/11/22 02:33:03 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/11/22 02:33:03 NOTICE: B2 bucket rclone-test-cateyic4sofe: 0 differences found 2025/11/22 02:33:03 NOTICE: B2 bucket rclone-test-cateyic4sofe: 1 matching files 2025/11/22 02:33:03 DEBUG : Creating backend with remote "TestB2:rclone-test-favunuq2givi" 2025/11/22 02:33:03 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/22 02:33:04 DEBUG : Creating backend with remote "/tmp/rclone1100630602" run.go:185: Remote "B2 bucket rclone-test-favunuq2givi", Local "Local file system at /tmp/rclone1100630602", Modify Window "1ms" NFD checkfile vs. NFC remote (without normalization) 2025/11/22 02:33:06 ERROR : 測試_Русский___ě_áñ: sum not found 2025/11/22 02:33:06 ERROR : 測試_Русский___ě_áñ: file not in B2 bucket rclone-test-favunuq2givi 2025/11/22 02:33:06 NOTICE: B2 bucket rclone-test-favunuq2givi: 1 files missing 2025/11/22 02:33:06 NOTICE: 1 hashes missing 2025/11/22 02:33:06 NOTICE: B2 bucket rclone-test-favunuq2givi: 1 differences found 2025/11/22 02:33:06 NOTICE: B2 bucket rclone-test-favunuq2givi: 2 errors while checking NFD checkfile vs. NFC remote (with normalization) 2025/11/22 02:33:07 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/11/22 02:33:07 NOTICE: B2 bucket rclone-test-favunuq2givi: 0 differences found 2025/11/22 02:33:07 NOTICE: B2 bucket rclone-test-favunuq2givi: 1 matching files 2025/11/22 02:33:07 DEBUG : Creating backend with remote "TestB2:rclone-test-hepevox6lacu" 2025/11/22 02:33:07 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/22 02:33:07 DEBUG : Creating backend with remote "/tmp/rclone897803514" run.go:185: Remote "B2 bucket rclone-test-hepevox6lacu", Local "Local file system at /tmp/rclone897803514", Modify Window "1ms" NFC checkfile vs. NFD remote (without normalization) 2025/11/22 02:33:09 ERROR : 測試_Русский___ě_áñ: sum not found 2025/11/22 02:33:09 ERROR : 測試_Русский___ě_áñ: file not in B2 bucket rclone-test-hepevox6lacu 2025/11/22 02:33:09 NOTICE: B2 bucket rclone-test-hepevox6lacu: 1 files missing 2025/11/22 02:33:09 NOTICE: 1 hashes missing 2025/11/22 02:33:09 NOTICE: B2 bucket rclone-test-hepevox6lacu: 1 differences found 2025/11/22 02:33:09 NOTICE: B2 bucket rclone-test-hepevox6lacu: 2 errors while checking NFC checkfile vs. NFD remote (with normalization) 2025/11/22 02:33:10 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/11/22 02:33:10 NOTICE: B2 bucket rclone-test-hepevox6lacu: 0 differences found 2025/11/22 02:33:10 NOTICE: B2 bucket rclone-test-hepevox6lacu: 1 matching files 2025/11/22 02:33:10 DEBUG : Creating backend with remote "TestB2:rclone-test-nicosak9piwi" 2025/11/22 02:33:10 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/22 02:33:11 DEBUG : Creating backend with remote "/tmp/rclone287943447" run.go:185: Remote "B2 bucket rclone-test-nicosak9piwi", Local "Local file system at /tmp/rclone287943447", Modify Window "1ms" NFDx2 checkfile vs. both remote (without normalization) 2025/11/22 02:33:14 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2025/11/22 02:33:14 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-nicosak9piwi 2025/11/22 02:33:14 NOTICE: B2 bucket rclone-test-nicosak9piwi: 1 files missing 2025/11/22 02:33:14 NOTICE: 1 hashes missing 2025/11/22 02:33:14 NOTICE: B2 bucket rclone-test-nicosak9piwi: 1 differences found 2025/11/22 02:33:14 NOTICE: B2 bucket rclone-test-nicosak9piwi: 2 errors while checking NFDx2 checkfile vs. both remote (with normalization) 2025/11/22 02:33:14 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/11/22 02:33:14 NOTICE: B2 bucket rclone-test-nicosak9piwi: 0 differences found 2025/11/22 02:33:14 NOTICE: B2 bucket rclone-test-nicosak9piwi: 1 matching files 2025/11/22 02:33:14 DEBUG : Creating backend with remote "TestB2:rclone-test-tunopun1keri" 2025/11/22 02:33:14 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/22 02:33:15 DEBUG : Creating backend with remote "/tmp/rclone2156740387" run.go:185: Remote "B2 bucket rclone-test-tunopun1keri", Local "Local file system at /tmp/rclone2156740387", Modify Window "1ms" NFCx2 checkfile vs. both remote (without normalization) 2025/11/22 02:33:17 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2025/11/22 02:33:17 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-tunopun1keri 2025/11/22 02:33:17 NOTICE: B2 bucket rclone-test-tunopun1keri: 1 files missing 2025/11/22 02:33:17 NOTICE: 1 hashes missing 2025/11/22 02:33:17 NOTICE: B2 bucket rclone-test-tunopun1keri: 1 differences found 2025/11/22 02:33:17 NOTICE: B2 bucket rclone-test-tunopun1keri: 2 errors while checking NFCx2 checkfile vs. both remote (with normalization) 2025/11/22 02:33:17 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/11/22 02:33:17 NOTICE: B2 bucket rclone-test-tunopun1keri: 0 differences found 2025/11/22 02:33:17 NOTICE: B2 bucket rclone-test-tunopun1keri: 1 matching files 2025/11/22 02:33:17 DEBUG : Creating backend with remote "TestB2:rclone-test-duludid5cilo" 2025/11/22 02:33:17 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/22 02:33:18 DEBUG : Creating backend with remote "/tmp/rclone895820949" run.go:185: Remote "B2 bucket rclone-test-duludid5cilo", Local "Local file system at /tmp/rclone895820949", Modify Window "1ms" both checkfile vs. NFDx2 remote (without normalization) 2025/11/22 02:33:20 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2025/11/22 02:33:20 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-duludid5cilo 2025/11/22 02:33:20 NOTICE: B2 bucket rclone-test-duludid5cilo: 1 files missing 2025/11/22 02:33:20 NOTICE: 1 hashes missing 2025/11/22 02:33:20 NOTICE: B2 bucket rclone-test-duludid5cilo: 1 differences found 2025/11/22 02:33:20 NOTICE: B2 bucket rclone-test-duludid5cilo: 2 errors while checking both checkfile vs. NFDx2 remote (with normalization) 2025/11/22 02:33:21 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/11/22 02:33:21 NOTICE: B2 bucket rclone-test-duludid5cilo: 0 differences found 2025/11/22 02:33:21 NOTICE: B2 bucket rclone-test-duludid5cilo: 1 matching files 2025/11/22 02:33:21 DEBUG : Creating backend with remote "TestB2:rclone-test-xinenuj0giqe" 2025/11/22 02:33:21 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/22 02:33:21 DEBUG : Creating backend with remote "/tmp/rclone3533633533" run.go:185: Remote "B2 bucket rclone-test-xinenuj0giqe", Local "Local file system at /tmp/rclone3533633533", Modify Window "1ms" both checkfile vs. NFCx2 remote (without normalization) 2025/11/22 02:33:24 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2025/11/22 02:33:24 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-xinenuj0giqe 2025/11/22 02:33:24 NOTICE: B2 bucket rclone-test-xinenuj0giqe: 1 files missing 2025/11/22 02:33:24 NOTICE: 1 hashes missing 2025/11/22 02:33:24 NOTICE: B2 bucket rclone-test-xinenuj0giqe: 1 differences found 2025/11/22 02:33:24 NOTICE: B2 bucket rclone-test-xinenuj0giqe: 2 errors while checking both checkfile vs. NFCx2 remote (with normalization) 2025/11/22 02:33:24 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/11/22 02:33:24 NOTICE: B2 bucket rclone-test-xinenuj0giqe: 0 differences found 2025/11/22 02:33:24 NOTICE: B2 bucket rclone-test-xinenuj0giqe: 1 matching files 2025/11/22 02:33:24 DEBUG : B2 bucket rclone-test-xinenuj0giqe: Purge remote 2025/11/22 02:33:24 INFO : B2 bucket rclone-test-xinenuj0giqe: cleaning bucket "rclone-test-xinenuj0giqe" of all files 2025/11/22 02:33:25 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_zaf82bd23608dab9b9fa80e1b_f116350b7c023bd84_d20251122_m023323_c001_v0001161_t0032_u01763778803438") 2025/11/22 02:33:25 DEBUG : B2 bucket rclone-test-duludid5cilo: Purge remote 2025/11/22 02:33:25 INFO : B2 bucket rclone-test-duludid5cilo: cleaning bucket "rclone-test-duludid5cilo" of all files 2025/11/22 02:33:26 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_zbfd2ad53608dab9b9fa80e1b_f102170fb9fbf6da6_d20251122_m023319_c001_v0001185_t0033_u01763778799956") 2025/11/22 02:33:26 DEBUG : B2 bucket rclone-test-tunopun1keri: Purge remote 2025/11/22 02:33:26 INFO : B2 bucket rclone-test-tunopun1keri: cleaning bucket "rclone-test-tunopun1keri" of all files 2025/11/22 02:33:27 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_z3f729d93608dab9b9fa80e1b_f103b06732572d2f3_d20251122_m023316_c001_v0001182_t0059_u01763778796788") 2025/11/22 02:33:28 DEBUG : B2 bucket rclone-test-nicosak9piwi: Purge remote 2025/11/22 02:33:28 INFO : B2 bucket rclone-test-nicosak9piwi: cleaning bucket "rclone-test-nicosak9piwi" of all files 2025/11/22 02:33:28 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_z5f528d93608dab9b9fa80e1b_f10633971a846ba85_d20251122_m023312_c001_v0001042_t0038_u01763778792777") 2025/11/22 02:33:29 DEBUG : B2 bucket rclone-test-hepevox6lacu: Purge remote 2025/11/22 02:33:29 INFO : B2 bucket rclone-test-hepevox6lacu: cleaning bucket "rclone-test-hepevox6lacu" of all files 2025/11/22 02:33:30 DEBUG : 測試_Русский___ě_áñ: Deleting (id "4_z4fd27db3608dab9b9fa80e1b_f113c6eebafadd995_d20251122_m023309_c001_v0001104_t0044_u01763778789101") 2025/11/22 02:33:30 DEBUG : B2 bucket rclone-test-favunuq2givi: Purge remote 2025/11/22 02:33:30 INFO : B2 bucket rclone-test-favunuq2givi: cleaning bucket "rclone-test-favunuq2givi" of all files 2025/11/22 02:33:31 DEBUG : 測試_Русский___ě_áñ: Deleting (id "4_z0f926dd3608dab9b9fa80e1b_f103724046ec6fb6d_d20251122_m023305_c001_v0001157_t0011_u01763778785525") 2025/11/22 02:33:31 DEBUG : B2 bucket rclone-test-cateyic4sofe: Purge remote 2025/11/22 02:33:31 INFO : B2 bucket rclone-test-cateyic4sofe: cleaning bucket "rclone-test-cateyic4sofe" of all files 2025/11/22 02:33:32 DEBUG : HELLO, WORLD!: Deleting (id "4_z0fe26d03608dab9b9fa80e1b_f11871609a8243f58_d20251122_m023302_c001_v0001157_t0030_u01763778782129") 2025/11/22 02:33:33 DEBUG : B2 bucket rclone-test-denijes4tutu: Purge remote 2025/11/22 02:33:33 INFO : B2 bucket rclone-test-denijes4tutu: cleaning bucket "rclone-test-denijes4tutu" of all files 2025/11/22 02:33:33 DEBUG : HeLlO, wOrLd!: Deleting (id "4_zafa25d33608dab9b9fa80e1b_f1053282f509bd3d7_d20251122_m023258_c001_v0001183_t0051_u01763778778940") 2025/11/22 02:33:34 DEBUG : B2 bucket rclone-test-zewifom0doyo: Purge remote 2025/11/22 02:33:34 INFO : B2 bucket rclone-test-zewifom0doyo: cleaning bucket "rclone-test-zewifom0doyo" of all files 2025/11/22 02:33:34 DEBUG : HELLO, WORLD!: Deleting (id "4_z1f524d73608dab9b9fa80e1b_f100cc3e237226bb7_d20251122_m023255_c001_v0001185_t0036_u01763778775796") 2025/11/22 02:33:35 DEBUG : B2 bucket rclone-test-lavunog7yubo: Purge remote 2025/11/22 02:33:35 INFO : B2 bucket rclone-test-lavunog7yubo: cleaning bucket "rclone-test-lavunog7yubo" of all files 2025/11/22 02:33:35 DEBUG : hello, world!: Deleting (id "4_z2fa23da3608dab9b9fa80e1b_f10765987dbe19992_d20251122_m023252_c001_v0001185_t0013_u01763778772548") --- PASS: TestApplyTransforms (46.21s) === RUN TestTruncateString --- PASS: TestTruncateString (0.00s) === RUN TestCopyFile run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" 2025/11/22 02:33:37 DEBUG : file1: Need to transfer - File not found at Destination 2025/11/22 02:33:38 DEBUG : sub/file2: size = 14 OK 2025/11/22 02:33:38 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2025/11/22 02:33:38 INFO : file1: Copied (new) to: sub/file2 2025/11/22 02:33:38 DEBUG : sub/file2: size = 14 OK 2025/11/22 02:33:38 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/22 02:33:38 DEBUG : file1: Unchanged skipping 2025/11/22 02:33:38 DEBUG : B2 bucket rclone-test-vajayal2cato: don't need to copy/move sub/file2, it is already at target location --- PASS: TestCopyFile (3.16s) === RUN TestCopyLongFile run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" copy_test.go:154: Test only runs on local --- SKIP: TestCopyLongFile (0.36s) === RUN TestCopyFileBackupDir run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" 2025/11/22 02:33:40 DEBUG : Creating backend with remote "TestB2:rclone-test-vajayal2cato/backup" 2025/11/22 02:33:42 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone2639800977) 2025/11/22 02:33:42 DEBUG : dst/file1: size = 18 (B2 bucket rclone-test-vajayal2cato) 2025/11/22 02:33:42 DEBUG : dst/file1: Sizes differ 2025/11/22 02:33:43 DEBUG : dst/file1: size = 18 OK 2025/11/22 02:33:43 DEBUG : dst/file1: sha1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK 2025/11/22 02:33:43 INFO : dst/file1: Copied (server-side copy) 2025/11/22 02:33:43 INFO : dst/file1: Deleted 2025/11/22 02:33:43 DEBUG : dst/file1: size = 14 OK 2025/11/22 02:33:43 DEBUG : dst/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2025/11/22 02:33:43 INFO : dst/file1: Copied (new) --- PASS: TestCopyFileBackupDir (4.63s) === RUN TestCopyFileCompareDest run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" 2025/11/22 02:33:44 DEBUG : Creating backend with remote "TestB2:rclone-test-vajayal2cato/dst" 2025/11/22 02:33:46 DEBUG : Creating backend with remote "TestB2:rclone-test-vajayal2cato/CompareDest" 2025/11/22 02:33:47 DEBUG : one: Need to transfer - File not found at Destination 2025/11/22 02:33:48 DEBUG : one: size = 3 OK 2025/11/22 02:33:48 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2025/11/22 02:33:48 INFO : one: Copied (new) 2025/11/22 02:33:49 DEBUG : one: size = 5 (Local file system at /tmp/rclone2639800977) 2025/11/22 02:33:49 DEBUG : one: size = 3 (B2 bucket rclone-test-vajayal2cato path dst) 2025/11/22 02:33:49 DEBUG : one: Sizes differ 2025/11/22 02:33:49 DEBUG : one: size = 5 OK 2025/11/22 02:33:49 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2025/11/22 02:33:49 INFO : one: Copied (replaced existing) 2025/11/22 02:33:52 DEBUG : one: size = 5 (Local file system at /tmp/rclone2639800977) 2025/11/22 02:33:52 DEBUG : one: size = 3 (B2 bucket rclone-test-vajayal2cato path dst) 2025/11/22 02:33:52 DEBUG : one: Sizes differ 2025/11/22 02:33:52 DEBUG : one: size = 5 OK 2025/11/22 02:33:52 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/11/22 02:33:52 DEBUG : one: Destination found in --compare-dest, skipping 2025/11/22 02:33:53 DEBUG : two: Need to transfer - File not found at Destination 2025/11/22 02:33:53 DEBUG : two: size = 3 OK 2025/11/22 02:33:53 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/11/22 02:33:53 DEBUG : two: Destination found in --compare-dest, skipping 2025/11/22 02:33:54 DEBUG : two: Need to transfer - File not found at Destination 2025/11/22 02:33:54 DEBUG : two: size = 3 OK 2025/11/22 02:33:54 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/11/22 02:33:54 DEBUG : two: Destination found in --compare-dest, skipping 2025/11/22 02:33:54 DEBUG : two: Need to transfer - File not found at Destination 2025/11/22 02:33:54 DEBUG : two: size = 5 (Local file system at /tmp/rclone2639800977) 2025/11/22 02:33:54 DEBUG : two: size = 3 (B2 bucket rclone-test-vajayal2cato path CompareDest) 2025/11/22 02:33:54 DEBUG : two: Sizes differ 2025/11/22 02:33:55 DEBUG : two: size = 5 OK 2025/11/22 02:33:55 DEBUG : two: sha1 = a4be8877ad88f3109a6411985a1f359e10e2d930 OK 2025/11/22 02:33:55 INFO : two: Copied (new) --- PASS: TestCopyFileCompareDest (12.10s) === RUN TestCopyFileCopyDest run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" 2025/11/22 02:33:56 DEBUG : Creating backend with remote "TestB2:rclone-test-vajayal2cato/dst" 2025/11/22 02:33:58 DEBUG : Creating backend with remote "TestB2:rclone-test-vajayal2cato/CopyDest" 2025/11/22 02:33:59 DEBUG : one: Need to transfer - File not found at Destination 2025/11/22 02:34:01 DEBUG : one: size = 3 OK 2025/11/22 02:34:01 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2025/11/22 02:34:01 INFO : one: Copied (new) 2025/11/22 02:34:01 DEBUG : one: size = 5 (Local file system at /tmp/rclone2639800977) 2025/11/22 02:34:01 DEBUG : one: size = 3 (B2 bucket rclone-test-vajayal2cato path dst) 2025/11/22 02:34:01 DEBUG : one: Sizes differ 2025/11/22 02:34:02 DEBUG : one: size = 5 OK 2025/11/22 02:34:02 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2025/11/22 02:34:02 INFO : one: Copied (replaced existing) 2025/11/22 02:34:04 DEBUG : Creating backend with remote "TestB2:rclone-test-vajayal2cato/BackupDir" 2025/11/22 02:34:05 DEBUG : one: size = 5 (Local file system at /tmp/rclone2639800977) 2025/11/22 02:34:05 DEBUG : one: size = 3 (B2 bucket rclone-test-vajayal2cato path dst) 2025/11/22 02:34:05 DEBUG : one: Sizes differ 2025/11/22 02:34:05 DEBUG : one: size = 5 OK 2025/11/22 02:34:05 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/11/22 02:34:05 DEBUG : one: size = 5 (Local file system at /tmp/rclone2639800977) 2025/11/22 02:34:05 DEBUG : one: size = 3 (B2 bucket rclone-test-vajayal2cato path dst) 2025/11/22 02:34:05 DEBUG : one: Sizes differ 2025/11/22 02:34:06 DEBUG : one: size = 3 OK 2025/11/22 02:34:06 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2025/11/22 02:34:06 INFO : one: Copied (server-side copy) 2025/11/22 02:34:07 INFO : one: Deleted 2025/11/22 02:34:07 DEBUG : one: size = 5 OK 2025/11/22 02:34:07 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2025/11/22 02:34:07 INFO : one: Copied (server-side copy) 2025/11/22 02:34:07 DEBUG : one: Destination found in --copy-dest, using server-side copy 2025/11/22 02:34:08 DEBUG : two: Need to transfer - File not found at Destination 2025/11/22 02:34:08 DEBUG : two: size = 3 OK 2025/11/22 02:34:08 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/11/22 02:34:09 DEBUG : two: size = 3 OK 2025/11/22 02:34:09 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2025/11/22 02:34:09 INFO : two: Copied (server-side copy) 2025/11/22 02:34:09 DEBUG : two: Destination found in --copy-dest, using server-side copy 2025/11/22 02:34:09 DEBUG : two: size = 3 OK 2025/11/22 02:34:09 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/11/22 02:34:09 DEBUG : two: Unchanged skipping 2025/11/22 02:34:10 DEBUG : three: Need to transfer - File not found at Destination 2025/11/22 02:34:10 DEBUG : three: size = 7 (Local file system at /tmp/rclone2639800977) 2025/11/22 02:34:10 DEBUG : three: size = 5 (B2 bucket rclone-test-vajayal2cato path CopyDest) 2025/11/22 02:34:10 DEBUG : three: Sizes differ 2025/11/22 02:34:10 DEBUG : three: Destination not found in --copy-dest 2025/11/22 02:34:11 DEBUG : three: size = 7 OK 2025/11/22 02:34:11 DEBUG : three: sha1 = 370e613335f3cf2f19b2a2d35a86402f55c659f8 OK 2025/11/22 02:34:11 INFO : three: Copied (new) --- PASS: TestCopyFileCopyDest (16.75s) === RUN TestCopyInplace run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" copy_test.go:371: Partial uploads not supported --- SKIP: TestCopyInplace (0.37s) === RUN TestCopyLongFileName run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" copy_test.go:404: Partial uploads not supported --- SKIP: TestCopyLongFileName (0.37s) === RUN TestCopyLongFileNameCollision run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" copy_test.go:437: Partial uploads not supported --- SKIP: TestCopyLongFileNameCollision (0.37s) === RUN TestCopyFileMaxTransfer run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" 2025/11/22 02:34:15 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination 2025/11/22 02:34:15 DEBUG : TestCopyFileMaxTransfer/file1: size = 14 OK 2025/11/22 02:34:15 DEBUG : TestCopyFileMaxTransfer/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2025/11/22 02:34:15 INFO : TestCopyFileMaxTransfer/file1: Copied (new) 2025/11/22 02:34:16 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination 2025/11/22 02:34:16 ERROR : TestCopyFileMaxTransfer/file2: Failed to copy: Post "https://pod-000-1038-03.backblaze.com/b2api/v1/b2_upload_file/7fa2ada3606dab9b9fa80e1b/c001_v0001038_t0047": max transfer limit reached as set by --max-transfer 2025/11/22 02:34:16 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination 2025/11/22 02:34:17 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination 2025/11/22 02:34:17 DEBUG : TestCopyFileMaxTransfer/file4: size = 2062 OK 2025/11/22 02:34:17 DEBUG : TestCopyFileMaxTransfer/file4: sha1 = e97b7f62bea2a8f934c734892a9dee7cd0d2f1cb OK 2025/11/22 02:34:17 INFO : TestCopyFileMaxTransfer/file4: Copied (new) --- PASS: TestCopyFileMaxTransfer (4.13s) === RUN TestDeduplicateInteractive run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateInteractive (0.37s) === RUN TestDeduplicateSkip run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSkip (0.37s) === RUN TestDeduplicateSizeOnly run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSizeOnly (0.37s) === RUN TestDeduplicateFirst run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateFirst (0.37s) === RUN TestDeduplicateNewest run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateNewest (0.37s) === RUN TestDeduplicateNewestByHash run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" 2025/11/22 02:34:23 INFO : B2 bucket rclone-test-vajayal2cato: Looking for duplicate sha1 hashes using newest mode. 2025/11/22 02:34:23 NOTICE: 96b5e89d4b70ab52ffa98d97a8b4eb4cb6953104: Found 3 files with duplicate sha1 hashes 2025/11/22 02:34:23 INFO : one: Deleted 2025/11/22 02:34:23 INFO : also/one: Deleted 2025/11/22 02:34:23 NOTICE: 96b5e89d4b70ab52ffa98d97a8b4eb4cb6953104: Deleted 2 extra copies --- PASS: TestDeduplicateNewestByHash (3.99s) === RUN TestDeduplicateOldest run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateOldest (0.42s) === RUN TestDeduplicateLargest run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateLargest (0.37s) === RUN TestDeduplicateSmallest run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSmallest (0.37s) === RUN TestDeduplicateRename run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateRename (0.37s) === RUN TestMergeDirs run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" dedupe_test.go:256: Can't merge directories --- SKIP: TestMergeDirs (0.37s) === RUN TestListDirSorted run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" 2025/11/22 02:34:30 DEBUG : a.txt: Excluded (Size Filter) 2025/11/22 02:34:30 DEBUG : a.txt: Excluded 2025/11/22 02:34:30 DEBUG : sub dir/hello world: Excluded (Size Filter) 2025/11/22 02:34:30 DEBUG : sub dir/hello world: Excluded 2025/11/22 02:34:30 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2025/11/22 02:34:30 DEBUG : sub dir/hello world2: Excluded 2025/11/22 02:34:30 DEBUG : sub dir/hello world: Excluded (Size Filter) 2025/11/22 02:34:30 DEBUG : sub dir/hello world: Excluded 2025/11/22 02:34:30 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2025/11/22 02:34:30 DEBUG : sub dir/hello world2: Excluded 2025/11/22 02:34:31 DEBUG : sub dir/ignore dir: Excluded 2025/11/22 02:34:31 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSorted (7.56s) === RUN TestListDirSortedFn run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" 2025/11/22 02:34:37 DEBUG : a.txt: Excluded (Size Filter) 2025/11/22 02:34:37 DEBUG : a.txt: Excluded 2025/11/22 02:34:38 DEBUG : sub dir/hello world: Excluded (Size Filter) 2025/11/22 02:34:38 DEBUG : sub dir/hello world: Excluded 2025/11/22 02:34:38 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2025/11/22 02:34:38 DEBUG : sub dir/hello world2: Excluded 2025/11/22 02:34:38 DEBUG : sub dir/hello world: Excluded (Size Filter) 2025/11/22 02:34:38 DEBUG : sub dir/hello world: Excluded 2025/11/22 02:34:38 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2025/11/22 02:34:38 DEBUG : sub dir/hello world2: Excluded 2025/11/22 02:34:38 DEBUG : sub dir/ignore dir: Excluded 2025/11/22 02:34:39 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSortedFn (7.21s) === RUN TestListJSON run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", 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/11/22 02:34:43 ERROR : file1: Failed to read hash: hash type not supported === RUN TestListJSON/Metadata --- PASS: TestListJSON (3.59s) --- 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.18s) --- PASS: TestListJSON/NoMimeType (0.18s) --- PASS: TestListJSON/ShowHash (0.22s) --- PASS: TestListJSON/HashTypes (0.18s) --- PASS: TestListJSON/Metadata (0.18s) === RUN TestStatJSON run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", 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/11/22 02:34:48 DEBUG : Creating backend with remote "TestB2:rclone-test-vajayal2cato/notfound" --- PASS: TestStatJSON (6.64s) --- PASS: TestStatJSON/Root (0.18s) --- PASS: TestStatJSON/RootFilesOnly (0.00s) --- PASS: TestStatJSON/RootDirsOnly (0.18s) --- PASS: TestStatJSON/Dir (0.77s) --- PASS: TestStatJSON/DirWithTrailingSlash (0.18s) --- PASS: TestStatJSON/File (0.18s) --- PASS: TestStatJSON/NotFound (0.36s) --- PASS: TestStatJSON/DirFilesOnly (0.23s) --- PASS: TestStatJSON/FileFilesOnly (0.19s) --- PASS: TestStatJSON/NotFoundFilesOnly (0.18s) --- PASS: TestStatJSON/DirDirsOnly (0.18s) --- PASS: TestStatJSON/FileDirsOnly (0.18s) --- PASS: TestStatJSON/NotFoundDirsOnly (0.18s) --- PASS: TestStatJSON/RootNotFound (1.84s) === RUN TestMkdir run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" 2025/11/22 02:34:51 INFO : B2 bucket rclone-test-vajayal2cato: Making directory 2025/11/22 02:34:51 INFO : B2 bucket rclone-test-vajayal2cato: Making directory --- PASS: TestMkdir (0.54s) === RUN TestLsd run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" --- PASS: TestLsd (1.71s) === RUN TestLs run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" --- PASS: TestLs (2.04s) === RUN TestLsWithFilesFrom run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" 2025/11/22 02:34:57 DEBUG : empty space: Excluded (FilesFrom Filter) --- PASS: TestLsWithFilesFrom (2.67s) === RUN TestLsLong run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" --- PASS: TestLsLong (1.88s) === RUN TestHashSums run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" === RUN TestHashSums/Sha1 === RUN TestHashSums/Sha1Download --- PASS: TestHashSums (2.52s) --- PASS: TestHashSums/Sha1 (0.19s) --- PASS: TestHashSums/Sha1Download (0.38s) === RUN TestHashSumsWithErrors 2025/11/22 02:35:02 DEBUG : Creating backend with remote ":memory:" 2025/11/22 02:35:02 ERROR : file1: hash unsupported: hash type not supported --- PASS: TestHashSumsWithErrors (0.00s) === RUN TestHashStream 2025/11/22 02:35:02 DEBUG : Creating md5 hash of 0 bytes read from input stream 2025/11/22 02:35:02 DEBUG : Creating md5 hash of 0 bytes read from input stream 2025/11/22 02:35:02 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2025/11/22 02:35:02 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2025/11/22 02:35:02 DEBUG : Creating md5 hash of 12 bytes read from input stream 2025/11/22 02:35:02 DEBUG : Creating md5 hash of 12 bytes read from input stream 2025/11/22 02:35:02 DEBUG : Creating sha1 hash of 12 bytes read from input stream 2025/11/22 02:35:02 DEBUG : Creating sha1 hash of 12 bytes read from input stream --- PASS: TestHashStream (0.00s) === RUN TestSuffixName --- PASS: TestSuffixName (0.00s) === RUN TestCount run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" --- PASS: TestCount (2.96s) === RUN TestDelete run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" 2025/11/22 02:35:07 DEBUG : Waiting for deletions to finish 2025/11/22 02:35:07 DEBUG : large: Excluded (Size Filter) 2025/11/22 02:35:08 INFO : medium: Deleted 2025/11/22 02:35:08 INFO : small: Deleted --- PASS: TestDelete (3.33s) === RUN TestMaxDelete run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" 2025/11/22 02:35:10 DEBUG : Waiting for deletions to finish 2025/11/22 02:35:10 ERROR : small: Got fatal error on delete: --max-delete threshold reached 2025/11/22 02:35:11 INFO : large: Deleted 2025/11/22 02:35:11 INFO : medium: Deleted --- PASS: TestMaxDelete (3.12s) === RUN TestMaxDeleteSizeLargeFile run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" 2025/11/22 02:35:13 DEBUG : Waiting for deletions to finish 2025/11/22 02:35:13 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached 2025/11/22 02:35:14 INFO : small: Deleted 2025/11/22 02:35:14 INFO : medium: Deleted --- PASS: TestMaxDeleteSizeLargeFile (2.91s) === RUN TestMaxDeleteSize run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" 2025/11/22 02:35:16 DEBUG : Waiting for deletions to finish 2025/11/22 02:35:16 ERROR : small: Got fatal error on delete: --max-delete-size threshold reached 2025/11/22 02:35:16 INFO : large: Deleted 2025/11/22 02:35:16 INFO : medium: Deleted --- PASS: TestMaxDeleteSize (2.80s) === RUN TestReadFile run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" --- PASS: TestReadFile (2.16s) === RUN TestRetry 2025/11/22 02:35:20 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 1/5 2025/11/22 02:35:20 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 2/5 2025/11/22 02:35:20 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2025/11/22 02:35:20 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2025/11/22 02:35:20 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2025/11/22 02:35:20 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2025/11/22 02:35:20 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms --- PASS: TestRetry (0.05s) === RUN TestCat run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" --- PASS: TestCat (4.45s) === RUN TestPurge 2025/11/22 02:35:24 DEBUG : Creating backend with remote "TestB2:rclone-test-gekotag1volo" 2025/11/22 02:35:24 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/22 02:35:25 DEBUG : Creating backend with remote "/tmp/rclone1597452485" run.go:185: Remote "B2 bucket rclone-test-gekotag1volo", Local "Local file system at /tmp/rclone1597452485", Modify Window "1ms" 2025/11/22 02:35:26 INFO : A2: Making directory 2025/11/22 02:35:26 INFO : A1/B2: Making directory 2025/11/22 02:35:26 INFO : A1/B2/C2: Making directory 2025/11/22 02:35:26 INFO : A1/B1/C3: Making directory 2025/11/22 02:35:26 INFO : A3: Making directory 2025/11/22 02:35:26 INFO : A3/B3: Making directory 2025/11/22 02:35:26 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/11/22 02:35:27 INFO : B2 bucket rclone-test-gekotag1volo: cleaning bucket "rclone-test-gekotag1volo" of all files 2025/11/22 02:35:27 DEBUG : A1/B1/C1/one: Deleting (id "4_zbfc29d4360adab9b9fa80e1b_f102170fb9fbf80a6_d20251122_m023526_c001_v0001185_t0009_u01763778926695") 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/11/22 02:35:27 INFO : B2 bucket rclone-test-gekotag1volo: cleaning bucket "rclone-test-gekotag1volo" of all files 2025/11/22 02:35:27 DEBUG : A1/two: Deleting (id "4_zbfc29d4360adab9b9fa80e1b_f102170fb9fbf80b1_d20251122_m023526_c001_v0001185_t0041_u01763778926903") 2025/11/22 02:35:28 DEBUG : B2 bucket rclone-test-gekotag1volo: Purge remote 2025/11/22 02:35:28 INFO : B2 bucket rclone-test-gekotag1volo: cleaning bucket "rclone-test-gekotag1volo" of all files 2025/11/22 02:35:28 NOTICE: purge failed: directory not found --- PASS: TestPurge (4.23s) === RUN TestRmdirsNoLeaveRoot run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" 2025/11/22 02:35:29 INFO : A2: Making directory 2025/11/22 02:35:29 INFO : A1/B2: Making directory 2025/11/22 02:35:29 INFO : A1/B2/C2: Making directory 2025/11/22 02:35:29 INFO : A1/B1/C3: Making directory 2025/11/22 02:35:29 INFO : A3: Making directory 2025/11/22 02:35:29 INFO : A3/B3: Making directory 2025/11/22 02:35:29 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/11/22 02:35:30 DEBUG : removing 1 level 3 directories 2025/11/22 02:35:30 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/11/22 02:35:32 DEBUG : removing 1 level 0 directories 2025/11/22 02:35:32 INFO : B2 bucket rclone-test-vajayal2cato: Removing directory 2025/11/22 02:35:32 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:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" 2025/11/22 02:35:33 INFO : A1: Making directory 2025/11/22 02:35:33 INFO : A1/B1: Making directory 2025/11/22 02:35:33 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.91s) === RUN TestRmdirsWithFilter run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" 2025/11/22 02:35:34 INFO : A1: Making directory 2025/11/22 02:35:34 INFO : A1/B1: Making directory 2025/11/22 02:35:34 INFO : A1/B1/C1: Making directory fstest.go:250: Filtering empty directory "A1" fstest.go:250: Filtering empty directory "A1/B1" fstest.go:250: Filtering empty directory "A1/B1/C1" fstest.go:250: Filtering empty directory "A1" --- PASS: TestRmdirsWithFilter (0.91s) === RUN TestCopyURL run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" 2025/11/22 02:35:36 DEBUG : filename.txt: File name found in url 2025/11/22 02:35:36 DEBUG : headerfilename.txt: filename found in Content-Disposition header. --- PASS: TestCopyURL (3.46s) === RUN TestCopyURLToWriter --- PASS: TestCopyURLToWriter (0.00s) === RUN TestMoveFile run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" 2025/11/22 02:35:38 DEBUG : file1: Need to transfer - File not found at Destination 2025/11/22 02:35:39 DEBUG : sub/file2: size = 14 OK 2025/11/22 02:35:39 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2025/11/22 02:35:39 INFO : file1: Copied (new) to: sub/file2 2025/11/22 02:35:39 INFO : file1: Deleted 2025/11/22 02:35:39 DEBUG : sub/file2: size = 14 OK 2025/11/22 02:35:39 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/22 02:35:39 DEBUG : file1: Unchanged skipping 2025/11/22 02:35:39 INFO : file1: Deleted 2025/11/22 02:35:39 DEBUG : B2 bucket rclone-test-vajayal2cato: don't need to copy/move sub/file2, it is already at target location --- PASS: TestMoveFile (1.88s) === RUN TestMoveFileWithIgnoreExisting run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" 2025/11/22 02:35:40 DEBUG : file1: Need to transfer - File not found at Destination 2025/11/22 02:35:41 DEBUG : file1: size = 14 OK 2025/11/22 02:35:41 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2025/11/22 02:35:41 INFO : file1: Copied (new) 2025/11/22 02:35:41 INFO : file1: Deleted 2025/11/22 02:35:41 DEBUG : file1: Destination exists, skipping 2025/11/22 02:35:41 DEBUG : file1: Not removing source file as destination file exists and --ignore-existing is set --- PASS: TestMoveFileWithIgnoreExisting (1.91s) === RUN TestCaseInsensitiveMoveFile run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFile (0.37s) === RUN TestCaseInsensitiveMoveFileDryRun run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFileDryRun (0.36s) === RUN TestMoveFileBackupDir run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" 2025/11/22 02:35:44 DEBUG : Creating backend with remote "TestB2:rclone-test-vajayal2cato/backup" 2025/11/22 02:35:45 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone2639800977) 2025/11/22 02:35:45 DEBUG : dst/file1: size = 18 (B2 bucket rclone-test-vajayal2cato) 2025/11/22 02:35:45 DEBUG : dst/file1: Sizes differ 2025/11/22 02:35:46 DEBUG : dst/file1: size = 18 OK 2025/11/22 02:35:46 DEBUG : dst/file1: sha1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK 2025/11/22 02:35:46 INFO : dst/file1: Copied (server-side copy) 2025/11/22 02:35:46 INFO : dst/file1: Deleted 2025/11/22 02:35:46 DEBUG : dst/file1: size = 14 OK 2025/11/22 02:35:46 DEBUG : dst/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2025/11/22 02:35:46 INFO : dst/file1: Copied (new) 2025/11/22 02:35:46 INFO : dst/file1: Deleted --- PASS: TestMoveFileBackupDir (4.74s) === RUN TestSameConfig --- PASS: TestSameConfig (0.00s) === RUN TestSame --- PASS: TestSame (0.00s) === RUN TestOverlappingFilterCheckWithoutFilter --- PASS: TestOverlappingFilterCheckWithoutFilter (0.00s) === RUN TestOverlappingFilterCheckWithFilter --- PASS: TestOverlappingFilterCheckWithFilter (0.00s) === RUN TestListFormat --- PASS: TestListFormat (0.00s) === RUN TestDirMove run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" 2025/11/22 02:35:50 INFO : A1/B2: Making directory 2025/11/22 02:35:50 INFO : A1/B1/C3: Making directory fstest.go:250: Filtering empty directory "A1/B2" fstest.go:250: Filtering empty directory "A1/B1/C3" 2025/11/22 02:35:51 DEBUG : A2/B1/C2/five: size = 4 OK 2025/11/22 02:35:51 DEBUG : A1/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2025/11/22 02:35:51 INFO : A1/B1/C2/five: Copied (server-side copy) to: A2/B1/C2/five 2025/11/22 02:35:51 DEBUG : A2/two: size = 3 OK 2025/11/22 02:35:51 DEBUG : A1/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2025/11/22 02:35:51 INFO : A1/two: Copied (server-side copy) to: A2/two 2025/11/22 02:35:51 DEBUG : A2/one: size = 3 OK 2025/11/22 02:35:51 DEBUG : A1/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2025/11/22 02:35:51 INFO : A1/one: Copied (server-side copy) to: A2/one 2025/11/22 02:35:51 INFO : A1/B1/C2/five: Deleted 2025/11/22 02:35:52 INFO : A1/two: Deleted 2025/11/22 02:35:52 DEBUG : A2/B1/C1/four: size = 4 OK 2025/11/22 02:35:52 DEBUG : A1/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2025/11/22 02:35:52 INFO : A1/B1/C1/four: Copied (server-side copy) to: A2/B1/C1/four 2025/11/22 02:35:52 INFO : A1/one: Deleted 2025/11/22 02:35:52 DEBUG : A2/B1/three: size = 5 OK 2025/11/22 02:35:52 DEBUG : A1/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2025/11/22 02:35:52 INFO : A1/B1/three: Copied (server-side copy) to: A2/B1/three 2025/11/22 02:35:52 INFO : A1/B1/three: Deleted 2025/11/22 02:35:52 INFO : A1/B1/C1/four: Deleted fstest.go:250: Filtering empty directory "A2/B2" fstest.go:250: Filtering empty directory "A2/B1/C3" 2025/11/22 02:35:53 DEBUG : A3/one: size = 3 OK 2025/11/22 02:35:53 DEBUG : A2/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2025/11/22 02:35:53 INFO : A2/one: Copied (server-side copy) to: A3/one 2025/11/22 02:35:53 DEBUG : A3/B1/C1/four: size = 4 OK 2025/11/22 02:35:53 DEBUG : A2/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2025/11/22 02:35:53 INFO : A2/B1/C1/four: Copied (server-side copy) to: A3/B1/C1/four 2025/11/22 02:35:53 DEBUG : A3/B1/three: size = 5 OK 2025/11/22 02:35:53 DEBUG : A2/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2025/11/22 02:35:53 INFO : A2/B1/three: Copied (server-side copy) to: A3/B1/three 2025/11/22 02:35:53 INFO : A2/one: Deleted 2025/11/22 02:35:53 INFO : A2/B1/three: Deleted 2025/11/22 02:35:53 INFO : A2/B1/C1/four: Deleted 2025/11/22 02:35:53 DEBUG : A3/B1/C2/five: size = 4 OK 2025/11/22 02:35:53 DEBUG : A2/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2025/11/22 02:35:53 INFO : A2/B1/C2/five: Copied (server-side copy) to: A3/B1/C2/five 2025/11/22 02:35:53 DEBUG : A3/two: size = 3 OK 2025/11/22 02:35:53 DEBUG : A2/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2025/11/22 02:35:53 INFO : A2/two: Copied (server-side copy) to: A3/two 2025/11/22 02:35:53 INFO : A2/two: Deleted 2025/11/22 02:35:53 INFO : A2/B1/C2/five: Deleted fstest.go:250: Filtering empty directory "A3/B2" fstest.go:250: Filtering empty directory "A3/B1/C3" 2025/11/22 02:35:54 INFO : B2 bucket rclone-test-vajayal2cato: Can't DirMove - falling back to file moves: can't move directory - incompatible remotes 2025/11/22 02:35:54 DEBUG : A4/one: size = 3 OK 2025/11/22 02:35:54 DEBUG : A3/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2025/11/22 02:35:54 INFO : A3/one: Copied (server-side copy) to: A4/one 2025/11/22 02:35:54 DEBUG : A4/B1/C1/four: size = 4 OK 2025/11/22 02:35:54 DEBUG : A3/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2025/11/22 02:35:54 INFO : A3/B1/C1/four: Copied (server-side copy) to: A4/B1/C1/four 2025/11/22 02:35:54 DEBUG : A4/B1/C2/five: size = 4 OK 2025/11/22 02:35:54 DEBUG : A3/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2025/11/22 02:35:54 INFO : A3/B1/C2/five: Copied (server-side copy) to: A4/B1/C2/five 2025/11/22 02:35:54 DEBUG : A4/two: size = 3 OK 2025/11/22 02:35:54 DEBUG : A3/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2025/11/22 02:35:54 INFO : A3/two: Copied (server-side copy) to: A4/two 2025/11/22 02:35:54 INFO : A3/one: Deleted 2025/11/22 02:35:54 INFO : A3/B1/C1/four: Deleted 2025/11/22 02:35:54 DEBUG : A4/B1/three: size = 5 OK 2025/11/22 02:35:54 DEBUG : A3/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2025/11/22 02:35:54 INFO : A3/B1/three: Copied (server-side copy) to: A4/B1/three 2025/11/22 02:35:55 INFO : A3/B1/C2/five: Deleted 2025/11/22 02:35:55 INFO : A3/two: Deleted 2025/11/22 02:35:55 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.82s) === RUN TestGetFsInfo run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" --- PASS: TestGetFsInfo (0.37s) === RUN TestRcat === RUN TestRcat/withChecksum=false,ignoreChecksum=false run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" 2025/11/22 02:35:57 DEBUG : B2 bucket rclone-test-vajayal2cato: File to upload is small (34 bytes), uploading instead of streaming 2025/11/22 02:35:57 DEBUG : no_checksum_small_file_from_pipe: size = 34 OK 2025/11/22 02:35:57 DEBUG : no_checksum_small_file_from_pipe: sha1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK 2025/11/22 02:35:57 DEBUG : no_checksum_small_file_from_pipe: Size and sha1 of src and dst objects identical 2025/11/22 02:35:57 DEBUG : no_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2025/11/22 02:35:58 DEBUG : no_checksum_big_file_from_pipe: size = 102401 OK 2025/11/22 02:35:58 DEBUG : no_checksum_big_file_from_pipe: sha1 = d0080f73aa8b494570ad63df1beb25c01ea9eea2 OK 2025/11/22 02:35:58 DEBUG : no_checksum_big_file_from_pipe: Size and sha1 of src and dst objects identical === RUN TestRcat/withChecksum=true,ignoreChecksum=false run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" 2025/11/22 02:36:00 DEBUG : B2 bucket rclone-test-vajayal2cato: File to upload is small (34 bytes), uploading instead of streaming 2025/11/22 02:36:00 DEBUG : with_checksum_small_file_from_pipe: size = 34 OK 2025/11/22 02:36:00 DEBUG : with_checksum_small_file_from_pipe: sha1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK 2025/11/22 02:36:00 DEBUG : with_checksum_small_file_from_pipe: Size and sha1 of src and dst objects identical 2025/11/22 02:36:00 DEBUG : with_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2025/11/22 02:36:01 DEBUG : with_checksum_big_file_from_pipe: size = 102401 OK 2025/11/22 02:36:01 DEBUG : with_checksum_big_file_from_pipe: sha1 = d0080f73aa8b494570ad63df1beb25c01ea9eea2 OK 2025/11/22 02:36:01 DEBUG : with_checksum_big_file_from_pipe: Size and sha1 of src and dst objects identical === RUN TestRcat/withChecksum=false,ignoreChecksum=true run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" 2025/11/22 02:36:02 DEBUG : B2 bucket rclone-test-vajayal2cato: File to upload is small (34 bytes), uploading instead of streaming 2025/11/22 02:36:03 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK 2025/11/22 02:36:03 DEBUG : ignore_checksum_small_file_from_pipe: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/22 02:36:03 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2025/11/22 02:36:04 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK 2025/11/22 02:36:04 DEBUG : ignore_checksum_big_file_from_pipe: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) === RUN TestRcat/withChecksum=true,ignoreChecksum=true run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" 2025/11/22 02:36:05 DEBUG : B2 bucket rclone-test-vajayal2cato: File to upload is small (34 bytes), uploading instead of streaming 2025/11/22 02:36:05 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK 2025/11/22 02:36:05 DEBUG : ignore_checksum_small_file_from_pipe: Src hash empty - aborting Dst hash check 2025/11/22 02:36:05 DEBUG : ignore_checksum_small_file_from_pipe: Size of src and dst objects identical 2025/11/22 02:36:05 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2025/11/22 02:36:06 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK 2025/11/22 02:36:06 DEBUG : ignore_checksum_big_file_from_pipe: Src hash empty - aborting Dst hash check 2025/11/22 02:36:06 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical --- PASS: TestRcat (11.00s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (2.92s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (2.56s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (2.75s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (2.77s) === RUN TestRcatMetadata run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" operations_test.go:1540: Skipping as destination doesn't support user metadata --- SKIP: TestRcatMetadata (0.37s) === RUN TestRcatSize run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" 2025/11/22 02:36:08 DEBUG : B2 bucket rclone-test-vajayal2cato: File to upload is small (60 bytes), uploading instead of streaming 2025/11/22 02:36:09 DEBUG : potato2: size = 60 OK 2025/11/22 02:36:09 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2025/11/22 02:36:09 DEBUG : potato2: Size and sha1 of src and dst objects identical --- PASS: TestRcatSize (1.93s) === RUN TestRcatSizeMetadata run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" operations_test.go:1621: Skipping as destination doesn't support user metadata --- SKIP: TestRcatSizeMetadata (0.37s) === RUN TestTouchDir run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" 2025/11/22 02:36:12 DEBUG : B2 bucket rclone-test-vajayal2cato: Touching "sub dir/potato3" 2025/11/22 02:36:12 DEBUG : B2 bucket rclone-test-vajayal2cato: Touching "empty space" 2025/11/22 02:36:12 DEBUG : B2 bucket rclone-test-vajayal2cato: Touching "potato2" --- PASS: TestTouchDir (4.20s) === RUN TestMkdirMetadata run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" operations_test.go:1711: Skipping test as remote does not support MkdirMetadata --- SKIP: TestMkdirMetadata (0.40s) === RUN TestMkdirModTime run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" operations_test.go:1730: Skipping test as remote does not support DirSetModTime or MkdirMetadata --- SKIP: TestMkdirModTime (0.37s) === RUN TestCopyDirMetadata run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", 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:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" operations_test.go:1787: Skipping test as remote does not support DirSetModTime or WriteDirSetModTime --- SKIP: TestSetDirModTime (0.37s) === RUN TestDirsEqual run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", Modify Window "1ms" operations_test.go:1831: Skipping test as remote does not support WriteDirMetadata or MkdirMetadata --- SKIP: TestDirsEqual (0.37s) === RUN TestRemoveExisting run.go:185: Remote "B2 bucket rclone-test-vajayal2cato", Local "Local file system at /tmp/rclone2639800977", 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/11/22 02:36:17 DEBUG : B2 bucket rclone-test-vajayal2cato: Purge remote 2025/11/22 02:36:17 INFO : B2 bucket rclone-test-vajayal2cato: cleaning bucket "rclone-test-vajayal2cato" of all files 2025/11/22 02:36:17 DEBUG : A1/B1/C1/four: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f4162454a20eef7e9_d20251122_m023552_c001_v7007000_t0000_u01763778952214") 2025/11/22 02:36:17 DEBUG : A1/B1/C1/four: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73eea16_d20251122_m023549_c001_v0001038_t0054_u01763778949370") 2025/11/22 02:36:17 DEBUG : A1/B1/C1/one: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f4162454a20eef796_d20251122_m023532_c001_v7007000_t0000_u01763778932074") 2025/11/22 02:36:17 DEBUG : A1/B1/C1/one: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee9f6_d20251122_m023529_c001_v0001038_t0003_u01763778929363") 2025/11/22 02:36:17 DEBUG : A1/B1/C2/five: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f419d6c923f7d50c1_d20251122_m023551_c001_v7007000_t0000_u01763778951856") 2025/11/22 02:36:17 DEBUG : A1/B1/C2/five: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73eea18_d20251122_m023549_c001_v0001038_t0045_u01763778949923") 2025/11/22 02:36:17 DEBUG : A1/B1/three: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f405e6b8551a47e3c_d20251122_m023552_c001_v7007000_t0000_u01763778952225") 2025/11/22 02:36:17 DEBUG : A1/B1/three: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73eea12_d20251122_m023549_c001_v0001038_t0036_u01763778949011") 2025/11/22 02:36:17 DEBUG : A1/one: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f4162454a20eef7e7_d20251122_m023552_c001_v7007000_t0000_u01763778952005") 2025/11/22 02:36:17 DEBUG : A1/one: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73eea0d_d20251122_m023547_c001_v0001038_t0014_u01763778947914") 2025/11/22 02:36:18 DEBUG : A1/two: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f400e9e5b5fbc1e32_d20251122_m023551_c001_v7007000_t0000_u01763778951925") 2025/11/22 02:36:18 DEBUG : A1/two: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73eea0f_d20251122_m023548_c001_v0001038_t0032_u01763778948486") 2025/11/22 02:36:18 DEBUG : A1/two: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f4162454a20eef799_d20251122_m023532_c001_v7007000_t0000_u01763778932440") 2025/11/22 02:36:18 DEBUG : A1/two: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee9f8_d20251122_m023529_c001_v0001038_t0040_u01763778929827") 2025/11/22 02:36:18 DEBUG : A2/B1/C1/four: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f4162454a20eef7f1_d20251122_m023553_c001_v7007000_t0000_u01763778953243") 2025/11/22 02:36:18 DEBUG : A2/B1/C1/four: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f101207a474866c71_d20251122_m023551_c001_v0001106_t0018_u01763778951268") 2025/11/22 02:36:18 DEBUG : A2/B1/C2/five: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f400e9e5b5fbc1e39_d20251122_m023553_c001_v7007000_t0000_u01763778953834") 2025/11/22 02:36:18 DEBUG : A2/B1/C2/five: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f110c41c5bcf35868_d20251122_m023551_c001_v0001181_t0048_u01763778951635") 2025/11/22 02:36:18 DEBUG : A2/B1/three: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f419d6c923f7d50c8_d20251122_m023553_c001_v7007000_t0000_u01763778953255") 2025/11/22 02:36:18 DEBUG : A2/B1/three: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f106fa6a65eed7c78_d20251122_m023551_c001_v0001145_t0005_u01763778951664") 2025/11/22 02:36:18 DEBUG : A2/one: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f4162454a20eef7f0_d20251122_m023553_c001_v7007000_t0000_u01763778953202") 2025/11/22 02:36:18 DEBUG : A2/one: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f117db15bf71e2c55_d20251122_m023551_c001_v0001185_t0031_u01763778951658") 2025/11/22 02:36:18 DEBUG : A2/two: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f405e6b8551a47e3d_d20251122_m023553_c001_v7007000_t0000_u01763778953845") 2025/11/22 02:36:18 DEBUG : A2/two: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f11538e5be13ab26f_d20251122_m023551_c001_v0001145_t0029_u01763778951670") 2025/11/22 02:36:18 DEBUG : A3/B1/C1/four: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f419d6c923f7d50ca_d20251122_m023554_c001_v7007000_t0000_u01763778954868") 2025/11/22 02:36:18 DEBUG : A3/B1/C1/four: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1017b9b5c34c47f1_d20251122_m023553_c001_v0001185_t0020_u01763778953041") 2025/11/22 02:36:18 DEBUG : A3/B1/C2/five: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f4162454a20eef7fc_d20251122_m023554_c001_v7007000_t0000_u01763778954978") 2025/11/22 02:36:18 DEBUG : A3/B1/C2/five: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1091c70c80d873f1_d20251122_m023553_c001_v0001115_t0038_u01763778953045") 2025/11/22 02:36:18 DEBUG : A3/B1/three: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f400e9e5b5fbc1e3e_d20251122_m023555_c001_v7007000_t0000_u01763778955074") 2025/11/22 02:36:18 DEBUG : A3/B1/three: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f11429ceb8bf9a328_d20251122_m023553_c001_v0001185_t0046_u01763778953054") 2025/11/22 02:36:19 DEBUG : A3/one: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f405e6b8551a47e3e_d20251122_m023554_c001_v7007000_t0000_u01763778954834") 2025/11/22 02:36:19 DEBUG : A3/one: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1050ef7540645e5f_d20251122_m023553_c001_v0001185_t0052_u01763778953010") 2025/11/22 02:36:19 DEBUG : A3/two: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f4162454a20eef7fd_d20251122_m023554_c001_v7007000_t0000_u01763778954989") 2025/11/22 02:36:19 DEBUG : A3/two: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1017c932c6a0c54c_d20251122_m023553_c001_v0001144_t0032_u01763778953028") 2025/11/22 02:36:19 DEBUG : A4/B1/C1/four: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f400e9e5b5fbc1e40_d20251122_m023555_c001_v7007000_t0000_u01763778955635") 2025/11/22 02:36:19 DEBUG : A4/B1/C1/four: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1117ede616eaa9fb_d20251122_m023554_c001_v0001184_t0025_u01763778954655") 2025/11/22 02:36:19 DEBUG : A4/B1/C2/five: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f400e9e5b5fbc1e42_d20251122_m023555_c001_v7007000_t0000_u01763778955816") 2025/11/22 02:36:19 DEBUG : A4/B1/C2/five: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f11501b8d486e5543_d20251122_m023554_c001_v0001185_t0055_u01763778954671") 2025/11/22 02:36:19 DEBUG : A4/B1/three: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f400e9e5b5fbc1e44_d20251122_m023555_c001_v7007000_t0000_u01763778955999") 2025/11/22 02:36:19 DEBUG : A4/B1/three: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f116170d08f68151a_d20251122_m023554_c001_v0001032_t0051_u01763778954652") 2025/11/22 02:36:19 DEBUG : A4/one: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f400e9e5b5fbc1e45_d20251122_m023556_c001_v7007000_t0000_u01763778956181") 2025/11/22 02:36:19 DEBUG : A4/one: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f100cc3e237228f17_d20251122_m023554_c001_v0001185_t0012_u01763778954663") 2025/11/22 02:36:19 DEBUG : A4/two: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f400e9e5b5fbc1e48_d20251122_m023556_c001_v7007000_t0000_u01763778956363") 2025/11/22 02:36:19 DEBUG : A4/two: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1082cce92d0a415f_d20251122_m023554_c001_v0001157_t0044_u01763778954652") 2025/11/22 02:36:19 DEBUG : BackupDir/one: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f410f0b1035c04fce_d20251122_m023412_c001_v7007000_t0000_u01763778852142") 2025/11/22 02:36:19 DEBUG : BackupDir/one: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1166f222aaf94016_d20251122_m023406_c001_v0001044_t0041_u01763778846513") 2025/11/22 02:36:19 DEBUG : CompareDest/one: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f410f0b1035c04f88_d20251122_m023355_c001_v7007000_t0000_u01763778835993") 2025/11/22 02:36:19 DEBUG : CompareDest/one: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee8f8_d20251122_m023351_c001_v0001038_t0004_u01763778831194") 2025/11/22 02:36:19 DEBUG : CompareDest/two: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f410f0b1035c04f89_d20251122_m023356_c001_v7007000_t0000_u01763778836176") 2025/11/22 02:36:19 DEBUG : CompareDest/two: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee8fe_d20251122_m023352_c001_v0001038_t0031_u01763778832482") 2025/11/22 02:36:19 DEBUG : CopyDest/one: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f410f0b1035c04fd0_d20251122_m023412_c001_v7007000_t0000_u01763778852330") 2025/11/22 02:36:20 DEBUG : CopyDest/one: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee912_d20251122_m023403_c001_v0001038_t0044_u01763778843196") 2025/11/22 02:36:20 DEBUG : CopyDest/three: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f410f0b1035c04fd1_d20251122_m023412_c001_v7007000_t0000_u01763778852514") 2025/11/22 02:36:20 DEBUG : CopyDest/three: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee918_d20251122_m023409_c001_v0001038_t0053_u01763778849755") 2025/11/22 02:36:20 DEBUG : CopyDest/two: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f410f0b1035c04fd2_d20251122_m023412_c001_v7007000_t0000_u01763778852739") 2025/11/22 02:36:20 DEBUG : CopyDest/two: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee916_d20251122_m023407_c001_v0001038_t0017_u01763778847841") 2025/11/22 02:36:20 DEBUG : ReadFile: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f414f558eb102b989_d20251122_m023519_c001_v7007000_t0000_u01763778919526") 2025/11/22 02:36:20 DEBUG : ReadFile: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee9e4_d20251122_m023518_c001_v0001038_t0022_u01763778918096") 2025/11/22 02:36:20 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f410f0b1035c04fe8_d20251122_m023418_c001_v7007000_t0000_u01763778858336") 2025/11/22 02:36:20 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee922_d20251122_m023415_c001_v0001038_t0025_u01763778855676") 2025/11/22 02:36:20 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f410f0b1035c04fe9_d20251122_m023418_c001_v7007000_t0000_u01763778858520") 2025/11/22 02:36:20 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee926_d20251122_m023417_c001_v0001038_t0056_u01763778857608") 2025/11/22 02:36:20 DEBUG : a.txt: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f418bfe7714994fcc_d20251122_m023439_c001_v7007000_t0000_u01763778879863") 2025/11/22 02:36:20 DEBUG : a.txt: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee957_d20251122_m023434_c001_v0001038_t0040_u01763778874194") 2025/11/22 02:36:20 DEBUG : a.txt: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f418bfe7714994fb4_d20251122_m023432_c001_v7007000_t0000_u01763778872698") 2025/11/22 02:36:20 DEBUG : a.txt: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee93a_d20251122_m023426_c001_v0001038_t0045_u01763778866602") 2025/11/22 02:36:20 DEBUG : also/one: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f410f0b1035c04ffd_d20251122_m023423_c001_v7007000_t0000_u01763778863598") 2025/11/22 02:36:20 DEBUG : also/one: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee92e_d20251122_m023421_c001_v0001038_t0001_u01763778861208") 2025/11/22 02:36:20 DEBUG : another: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f410f0b1035c05003_d20251122_m023424_c001_v7007000_t0000_u01763778864152") 2025/11/22 02:36:20 DEBUG : another: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee930_d20251122_m023421_c001_v0001038_t0055_u01763778861837") 2025/11/22 02:36:20 DEBUG : backup/dst/file1: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f4162454a20eef7d2_d20251122_m023547_c001_v7007000_t0000_u01763778947338") 2025/11/22 02:36:21 DEBUG : backup/dst/file1: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f103458988546e92e_d20251122_m023546_c001_v0001185_t0055_u01763778946210") 2025/11/22 02:36:21 DEBUG : backup/dst/file1: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f410f0b1035c04f59_d20251122_m023344_c001_v7007000_t0000_u01763778824258") 2025/11/22 02:36:21 DEBUG : backup/dst/file1: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1091ffd84f8ce5d2_d20251122_m023343_c001_v0001185_t0047_u01763778823127") 2025/11/22 02:36:21 DEBUG : data/banana: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f406831504b3b5c38_d20251122_m023249_c001_v7007000_t0000_u01763778769720") 2025/11/22 02:36:21 DEBUG : data/banana: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee8a0_d20251122_m023247_c001_v0001038_t0036_u01763778767319") 2025/11/22 02:36:21 DEBUG : data/banana: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee886_d20251122_m023237_c001_v0001038_t0022_u01763778757188") 2025/11/22 02:36:21 DEBUG : data/potato: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f406831504b3b5c39_d20251122_m023249_c001_v7007000_t0000_u01763778769903") 2025/11/22 02:36:21 DEBUG : data/potato: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee8a2_d20251122_m023247_c001_v0001038_t0040_u01763778767670") 2025/11/22 02:36:21 DEBUG : data/potato: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee890_d20251122_m023239_c001_v0001038_t0011_u01763778759726") 2025/11/22 02:36:21 DEBUG : dst/file1: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f4162454a20eef7d4_d20251122_m023547_c001_v7007000_t0000_u01763778947546") 2025/11/22 02:36:21 DEBUG : dst/file1: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73eea0b_d20251122_m023546_c001_v0001038_t0036_u01763778946586") 2025/11/22 02:36:21 DEBUG : dst/file1: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f4162454a20eef7ce_d20251122_m023546_c001_v7007000_t0000_u01763778946402") 2025/11/22 02:36:21 DEBUG : dst/file1: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73eea08_d20251122_m023543_c001_v0001038_t0010_u01763778943213") 2025/11/22 02:36:21 DEBUG : dst/file1: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f410f0b1035c04f5a_d20251122_m023344_c001_v7007000_t0000_u01763778824441") 2025/11/22 02:36:21 DEBUG : dst/file1: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee8d8_d20251122_m023343_c001_v0001038_t0050_u01763778823551") 2025/11/22 02:36:21 DEBUG : dst/file1: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f410f0b1035c04f4b_d20251122_m023343_c001_v7007000_t0000_u01763778823359") 2025/11/22 02:36:21 DEBUG : dst/file1: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee8cc_d20251122_m023340_c001_v0001038_t0014_u01763778820193") 2025/11/22 02:36:21 DEBUG : dst/one: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f410f0b1035c04fd3_d20251122_m023412_c001_v7007000_t0000_u01763778852923") 2025/11/22 02:36:21 DEBUG : dst/one: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f116d5e644b234de1_d20251122_m023407_c001_v0001184_t0042_u01763778847443") 2025/11/22 02:36:21 DEBUG : dst/one: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f406831504b3b5de5_d20251122_m023407_c001_v7007000_t0000_u01763778847241") 2025/11/22 02:36:21 DEBUG : dst/one: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee910_d20251122_m023402_c001_v0001038_t0045_u01763778842813") 2025/11/22 02:36:21 DEBUG : dst/one: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f111d269507e63dc7_d20251122_m023401_c001_v0001146_t0039_u01763778841964") 2025/11/22 02:36:22 DEBUG : dst/one: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f111d269507e63dc5_d20251122_m023400_c001_v0001146_t0032_u01763778840796") 2025/11/22 02:36:22 DEBUG : dst/one: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f410f0b1035c04f8a_d20251122_m023356_c001_v7007000_t0000_u01763778836360") 2025/11/22 02:36:22 DEBUG : dst/one: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee8f6_d20251122_m023350_c001_v0001038_t0002_u01763778830529") 2025/11/22 02:36:22 DEBUG : dst/one: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f109fd2480f4c8b65_d20251122_m023349_c001_v0001179_t0009_u01763778829737") 2025/11/22 02:36:22 DEBUG : dst/one: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f109fd2480f4c8b63_d20251122_m023348_c001_v0001179_t0036_u01763778828797") 2025/11/22 02:36:22 DEBUG : dst/three: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f410f0b1035c04fd5_d20251122_m023413_c001_v7007000_t0000_u01763778853107") 2025/11/22 02:36:22 DEBUG : dst/three: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f111d269507e63de0_d20251122_m023411_c001_v0001146_t0010_u01763778851219") 2025/11/22 02:36:22 DEBUG : dst/two: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f410f0b1035c04fd6_d20251122_m023413_c001_v7007000_t0000_u01763778853291") 2025/11/22 02:36:22 DEBUG : dst/two: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f104a4f0d7b9bdc1b_d20251122_m023408_c001_v0001154_t0023_u01763778848837") 2025/11/22 02:36:22 DEBUG : dst/two: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f410f0b1035c04f8b_d20251122_m023356_c001_v7007000_t0000_u01763778836546") 2025/11/22 02:36:22 DEBUG : dst/two: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f109fd2480f4c8b6e_d20251122_m023355_c001_v0001179_t0042_u01763778835424") 2025/11/22 02:36:22 DEBUG : empty space: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f41780fe05e02251f_d20251122_m023614_c001_v7007000_t0000_u01763778974176") 2025/11/22 02:36:22 DEBUG : empty space: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f105e48ab7e23ba01_d20251122_m023613_c001_v0001134_t0026_u01763778973426") 2025/11/22 02:36:22 DEBUG : empty space: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73eea5d_d20251122_m023611_c001_v0001038_t0048_u01763778971125") 2025/11/22 02:36:22 DEBUG : empty space: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f418bfe77149950ac_d20251122_m023505_c001_v7007000_t0000_u01763778905179") 2025/11/22 02:36:22 DEBUG : empty space: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee9b5_d20251122_m023503_c001_v0001038_t0011_u01763778903625") 2025/11/22 02:36:22 DEBUG : empty space: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f418bfe7714995092_d20251122_m023502_c001_v7007000_t0000_u01763778902417") 2025/11/22 02:36:22 DEBUG : empty space: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee9a8_d20251122_m023500_c001_v0001038_t0055_u01763778900919") 2025/11/22 02:36:22 DEBUG : empty space: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f418bfe7714995075_d20251122_m023459_c001_v7007000_t0000_u01763778899886") 2025/11/22 02:36:22 DEBUG : empty space: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee99e_d20251122_m023458_c001_v0001038_t0044_u01763778898970") 2025/11/22 02:36:22 DEBUG : empty space: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f418bfe7714995063_d20251122_m023458_c001_v7007000_t0000_u01763778898021") 2025/11/22 02:36:22 DEBUG : empty space: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee991_d20251122_m023456_c001_v0001038_t0023_u01763778896382") 2025/11/22 02:36:23 DEBUG : empty space: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f418bfe771499504c_d20251122_m023455_c001_v7007000_t0000_u01763778895346") 2025/11/22 02:36:23 DEBUG : empty space: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee987_d20251122_m023454_c001_v0001038_t0026_u01763778894337") 2025/11/22 02:36:23 DEBUG : empty space: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f406831504b3b5b81_d20251122_m023231_c001_v7007000_t0000_u01763778751063") 2025/11/22 02:36:23 DEBUG : empty space: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee872_d20251122_m023228_c001_v0001038_t0011_u01763778748152") 2025/11/22 02:36:23 DEBUG : empty space: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f406831504b3b5b5e_d20251122_m023226_c001_v7007000_t0000_u01763778746243") 2025/11/22 02:36:23 DEBUG : empty space: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee868_d20251122_m023221_c001_v0001038_t0059_u01763778741698") 2025/11/22 02:36:23 DEBUG : empty space: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f406831504b3b5b40_d20251122_m023218_c001_v7007000_t0000_u01763778738993") 2025/11/22 02:36:23 DEBUG : empty space: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee85c_d20251122_m023215_c001_v0001038_t0009_u01763778735771") 2025/11/22 02:36:23 DEBUG : file1: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f4162454a20eef7c3_d20251122_m023542_c001_v7007000_t0000_u01763778942079") 2025/11/22 02:36:23 DEBUG : file1: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73eea06_d20251122_m023540_c001_v0001038_t0012_u01763778940771") 2025/11/22 02:36:23 DEBUG : file1: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f4162454a20eef7b2_d20251122_m023537_c001_v7007000_t0000_u01763778937742") 2025/11/22 02:36:23 DEBUG : file1: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f4035df2bd73ee9fc_d20251122_m023535_c001_v0001038_t0047_u01763778935750") 2025/11/22 02:36:23 DEBUG : file1: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f414f558eb102b9a3_d20251122_m023524_c001_v7007000_t0000_u01763778924217") 2025/11/22 02:36:23 DEBUG : file1: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee9e9_d20251122_m023520_c001_v0001038_t0054_u01763778920311") 2025/11/22 02:36:23 DEBUG : file1: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f418bfe7714995025_d20251122_m023451_c001_v7007000_t0000_u01763778891052") 2025/11/22 02:36:23 DEBUG : file1: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee975_d20251122_m023444_c001_v0001038_t0002_u01763778884954") 2025/11/22 02:36:23 DEBUG : file1: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f418bfe7714994fe9_d20251122_m023444_c001_v7007000_t0000_u01763778884399") 2025/11/22 02:36:23 DEBUG : file1: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee96f_d20251122_m023441_c001_v0001038_t0047_u01763778881367") 2025/11/22 02:36:23 DEBUG : file2: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f4162454a20eef7b4_d20251122_m023537_c001_v7007000_t0000_u01763778937924") 2025/11/22 02:36:23 DEBUG : file2: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f4035df2bd73eea02_d20251122_m023537_c001_v0001038_t0047_u01763778937196") 2025/11/22 02:36:24 DEBUG : file2: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f414f558eb102b9a4_d20251122_m023524_c001_v7007000_t0000_u01763778924400") 2025/11/22 02:36:24 DEBUG : file2: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee9ed_d20251122_m023520_c001_v0001038_t0051_u01763778920692") 2025/11/22 02:36:24 DEBUG : filename.txt: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f4162454a20eef7b5_d20251122_m023538_c001_v7007000_t0000_u01763778938105") 2025/11/22 02:36:24 DEBUG : filename.txt: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee9fe_d20251122_m023536_c001_v0001038_t0058_u01763778936298") 2025/11/22 02:36:24 DEBUG : headerfilename.txt: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f4162454a20eef7b6_d20251122_m023538_c001_v7007000_t0000_u01763778938285") 2025/11/22 02:36:24 DEBUG : headerfilename.txt: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73eea00_d20251122_m023536_c001_v0001038_t0002_u01763778936722") 2025/11/22 02:36:24 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f400e9e5b5fbc1e6e_d20251122_m023607_c001_v7007000_t0000_u01763778967512") 2025/11/22 02:36:24 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73eea4c_d20251122_m023606_c001_v0001038_t0013_u01763778966067") 2025/11/22 02:36:24 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f400e9e5b5fbc1e65_d20251122_m023604_c001_v7007000_t0000_u01763778964773") 2025/11/22 02:36:24 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73eea3e_d20251122_m023603_c001_v0001038_t0031_u01763778963401") 2025/11/22 02:36:24 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f400e9e5b5fbc1e70_d20251122_m023607_c001_v7007000_t0000_u01763778967704") 2025/11/22 02:36:24 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73eea4a_d20251122_m023605_c001_v0001038_t0002_u01763778965509") 2025/11/22 02:36:24 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f400e9e5b5fbc1e66_d20251122_m023604_c001_v7007000_t0000_u01763778964956") 2025/11/22 02:36:24 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73eea3c_d20251122_m023602_c001_v0001038_t0003_u01763778962762") 2025/11/22 02:36:24 DEBUG : large: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f416e2babfc6c7d9d_d20251122_m023516_c001_v7007000_t0000_u01763778916800") 2025/11/22 02:36:24 DEBUG : large: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee9dc_d20251122_m023516_c001_v0001038_t0052_u01763778916088") 2025/11/22 02:36:24 DEBUG : large: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f416e2babfc6c7d94_d20251122_m023514_c001_v7007000_t0000_u01763778914561") 2025/11/22 02:36:24 DEBUG : large: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee9ce_d20251122_m023513_c001_v0001038_t0044_u01763778913193") 2025/11/22 02:36:24 DEBUG : large: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f414f558eb102b95f_d20251122_m023510_c001_v7007000_t0000_u01763778910909") 2025/11/22 02:36:24 DEBUG : large: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee9c6_d20251122_m023510_c001_v0001038_t0023_u01763778910210") 2025/11/22 02:36:25 DEBUG : large: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f414f558eb102b950_d20251122_m023508_c001_v7007000_t0000_u01763778908894") 2025/11/22 02:36:25 DEBUG : large: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee9c0_d20251122_m023507_c001_v0001038_t0035_u01763778907253") 2025/11/22 02:36:25 DEBUG : medium: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f414f558eb102b978_d20251122_m023516_c001_v7007000_t0000_u01763778916814") 2025/11/22 02:36:25 DEBUG : medium: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee9d8_d20251122_m023515_c001_v0001038_t0050_u01763778915693") 2025/11/22 02:36:25 DEBUG : medium: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f416e2babfc6c7d92_d20251122_m023513_c001_v7007000_t0000_u01763778913992") 2025/11/22 02:36:25 DEBUG : medium: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee9cc_d20251122_m023512_c001_v0001038_t0027_u01763778912821") 2025/11/22 02:36:25 DEBUG : medium: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f414f558eb102b960_d20251122_m023511_c001_v7007000_t0000_u01763778911091") 2025/11/22 02:36:25 DEBUG : medium: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee9c4_d20251122_m023509_c001_v0001038_t0025_u01763778909748") 2025/11/22 02:36:25 DEBUG : medium: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f418bfe77149950be_d20251122_m023507_c001_v7007000_t0000_u01763778907963") 2025/11/22 02:36:25 DEBUG : medium: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee9be_d20251122_m023506_c001_v0001038_t0047_u01763778906402") 2025/11/22 02:36:25 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f400e9e5b5fbc1e4e_d20251122_m023559_c001_v7007000_t0000_u01763778959450") 2025/11/22 02:36:25 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73eea2e_d20251122_m023558_c001_v0001038_t0039_u01763778958016") 2025/11/22 02:36:25 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f400e9e5b5fbc1e50_d20251122_m023559_c001_v7007000_t0000_u01763778959632") 2025/11/22 02:36:25 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73eea2c_d20251122_m023557_c001_v0001038_t0044_u01763778957641") 2025/11/22 02:36:25 DEBUG : not-one: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f410f0b1035c05007_d20251122_m023424_c001_v7007000_t0000_u01763778864347") 2025/11/22 02:36:25 DEBUG : not-one: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee932_d20251122_m023422_c001_v0001038_t0017_u01763778862231") 2025/11/22 02:36:25 DEBUG : one: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f410f0b1035c04ffc_d20251122_m023423_c001_v7007000_t0000_u01763778863366") 2025/11/22 02:36:25 DEBUG : one: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee92c_d20251122_m023420_c001_v0001038_t0003_u01763778860728") 2025/11/22 02:36:25 DEBUG : potato1: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f400e9e5b5fbc1e83_d20251122_m023609_c001_v7007000_t0000_u01763778969839") 2025/11/22 02:36:25 DEBUG : potato1: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73eea53_d20251122_m023608_c001_v0001038_t0031_u01763778968458") 2025/11/22 02:36:25 DEBUG : potato2: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f41780fe05e022520_d20251122_m023614_c001_v7007000_t0000_u01763778974407") 2025/11/22 02:36:25 DEBUG : potato2: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f11172ff7c122ed6c_d20251122_m023613_c001_v0001185_t0056_u01763778973021") 2025/11/22 02:36:25 DEBUG : potato2: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73eea5b_d20251122_m023610_c001_v0001038_t0052_u01763778970756") 2025/11/22 02:36:26 DEBUG : potato2: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f400e9e5b5fbc1e85_d20251122_m023610_c001_v7007000_t0000_u01763778970021") 2025/11/22 02:36:26 DEBUG : potato2: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73eea55_d20251122_m023608_c001_v0001038_t0026_u01763778968984") 2025/11/22 02:36:26 DEBUG : potato2: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f418bfe77149950ae_d20251122_m023505_c001_v7007000_t0000_u01763778905363") 2025/11/22 02:36:26 DEBUG : potato2: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee9b3_d20251122_m023502_c001_v0001038_t0054_u01763778902969") 2025/11/22 02:36:26 DEBUG : potato2: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f418bfe7714995096_d20251122_m023502_c001_v7007000_t0000_u01763778902603") 2025/11/22 02:36:26 DEBUG : potato2: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee9a4_d20251122_m023500_c001_v0001038_t0031_u01763778900447") 2025/11/22 02:36:26 DEBUG : potato2: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f418bfe7714995077_d20251122_m023500_c001_v7007000_t0000_u01763778900083") 2025/11/22 02:36:26 DEBUG : potato2: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee99a_d20251122_m023458_c001_v0001038_t0031_u01763778898565") 2025/11/22 02:36:26 DEBUG : potato2: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f418bfe7714995066_d20251122_m023458_c001_v7007000_t0000_u01763778898202") 2025/11/22 02:36:26 DEBUG : potato2: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee98d_d20251122_m023455_c001_v0001038_t0042_u01763778895891") 2025/11/22 02:36:26 DEBUG : potato2: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f418bfe771499504e_d20251122_m023455_c001_v7007000_t0000_u01763778895527") 2025/11/22 02:36:26 DEBUG : potato2: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee985_d20251122_m023453_c001_v0001038_t0012_u01763778893854") 2025/11/22 02:36:26 DEBUG : potato2: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f406831504b3b5b82_d20251122_m023231_c001_v7007000_t0000_u01763778751246") 2025/11/22 02:36:26 DEBUG : potato2: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee874_d20251122_m023228_c001_v0001038_t0022_u01763778748991") 2025/11/22 02:36:26 DEBUG : potato2: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f406831504b3b5b5f_d20251122_m023226_c001_v7007000_t0000_u01763778746473") 2025/11/22 02:36:26 DEBUG : potato2: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee86c_d20251122_m023222_c001_v0001038_t0056_u01763778742739") 2025/11/22 02:36:26 DEBUG : potato2: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f406831504b3b5b42_d20251122_m023219_c001_v7007000_t0000_u01763778739192") 2025/11/22 02:36:26 DEBUG : potato2: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee85e_d20251122_m023216_c001_v0001038_t0019_u01763778736684") 2025/11/22 02:36:26 DEBUG : remotepotato: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f406831504b3b5b86_d20251122_m023231_c001_v7007000_t0000_u01763778751429") 2025/11/22 02:36:26 DEBUG : remotepotato: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee876_d20251122_m023229_c001_v0001038_t0035_u01763778749899") 2025/11/22 02:36:27 DEBUG : remotepotato: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f406831504b3b5b61_d20251122_m023226_c001_v7007000_t0000_u01763778746661") 2025/11/22 02:36:27 DEBUG : remotepotato: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee86e_d20251122_m023224_c001_v0001038_t0025_u01763778744387") 2025/11/22 02:36:27 DEBUG : remotepotato: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f406831504b3b5b46_d20251122_m023219_c001_v7007000_t0000_u01763778739388") 2025/11/22 02:36:27 DEBUG : remotepotato: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee860_d20251122_m023217_c001_v0001038_t0031_u01763778737609") 2025/11/22 02:36:27 DEBUG : rutabaga: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f406831504b3b5b88_d20251122_m023231_c001_v7007000_t0000_u01763778751676") 2025/11/22 02:36:27 DEBUG : rutabaga: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee870_d20251122_m023227_c001_v0001038_t0045_u01763778747221") 2025/11/22 02:36:27 DEBUG : rutabaga: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f406831504b3b5b64_d20251122_m023226_c001_v7007000_t0000_u01763778746844") 2025/11/22 02:36:27 DEBUG : rutabaga: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee866_d20251122_m023219_c001_v0001038_t0019_u01763778739942") 2025/11/22 02:36:27 DEBUG : rutabaga: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f406831504b3b5b47_d20251122_m023219_c001_v7007000_t0000_u01763778739570") 2025/11/22 02:36:27 DEBUG : rutabaga: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee858_d20251122_m023214_c001_v0001038_t0025_u01763778734797") 2025/11/22 02:36:27 DEBUG : small: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f414f558eb102b980_d20251122_m023517_c001_v7007000_t0000_u01763778917364") 2025/11/22 02:36:27 DEBUG : small: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee9d4_d20251122_m023515_c001_v0001038_t0054_u01763778915293") 2025/11/22 02:36:27 DEBUG : small: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f414f558eb102b96a_d20251122_m023513_c001_v7007000_t0000_u01763778913982") 2025/11/22 02:36:27 DEBUG : small: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee9ca_d20251122_m023512_c001_v0001038_t0035_u01763778912382") 2025/11/22 02:36:27 DEBUG : small: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f414f558eb102b963_d20251122_m023511_c001_v7007000_t0000_u01763778911647") 2025/11/22 02:36:27 DEBUG : small: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee9c2_d20251122_m023509_c001_v0001038_t0033_u01763778909294") 2025/11/22 02:36:27 DEBUG : small: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f414f558eb102b94e_d20251122_m023508_c001_v7007000_t0000_u01763778908339") 2025/11/22 02:36:27 DEBUG : small: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee9ba_d20251122_m023505_c001_v0001038_t0040_u01763778905931") 2025/11/22 02:36:27 DEBUG : sub dir/hello world: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f418bfe7714995037_d20251122_m023453_c001_v7007000_t0000_u01763778893492") 2025/11/22 02:36:27 DEBUG : sub dir/hello world: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee983_d20251122_m023452_c001_v0001038_t0052_u01763778892503") 2025/11/22 02:36:27 DEBUG : sub dir/hello world: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f418bfe7714994fcd_d20251122_m023440_c001_v7007000_t0000_u01763778880044") 2025/11/22 02:36:28 DEBUG : sub dir/hello world: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee95d_d20251122_m023435_c001_v0001038_t0027_u01763778875046") 2025/11/22 02:36:28 DEBUG : sub dir/hello world: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f418bfe7714994fb5_d20251122_m023432_c001_v7007000_t0000_u01763778872891") 2025/11/22 02:36:28 DEBUG : sub dir/hello world: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee93e_d20251122_m023427_c001_v0001038_t0000_u01763778867580") 2025/11/22 02:36:28 DEBUG : sub dir/hello world2: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f418bfe7714994fd1_d20251122_m023440_c001_v7007000_t0000_u01763778880225") 2025/11/22 02:36:28 DEBUG : sub dir/hello world2: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee95f_d20251122_m023435_c001_v0001038_t0036_u01763778875479") 2025/11/22 02:36:28 DEBUG : sub dir/hello world2: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f418bfe7714994fb6_d20251122_m023433_c001_v7007000_t0000_u01763778873072") 2025/11/22 02:36:28 DEBUG : sub dir/hello world2: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee942_d20251122_m023428_c001_v0001038_t0013_u01763778868180") 2025/11/22 02:36:28 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f418bfe7714994fd2_d20251122_m023440_c001_v7007000_t0000_u01763778880419") 2025/11/22 02:36:28 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee961_d20251122_m023435_c001_v0001038_t0020_u01763778875919") 2025/11/22 02:36:28 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f418bfe7714994fb7_d20251122_m023433_c001_v7007000_t0000_u01763778873252") 2025/11/22 02:36:28 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee946_d20251122_m023428_c001_v0001038_t0051_u01763778868687") 2025/11/22 02:36:28 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f418bfe7714994fd4_d20251122_m023440_c001_v7007000_t0000_u01763778880600") 2025/11/22 02:36:28 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee963_d20251122_m023436_c001_v0001038_t0019_u01763778876334") 2025/11/22 02:36:28 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f418bfe7714994fb8_d20251122_m023433_c001_v7007000_t0000_u01763778873433") 2025/11/22 02:36:28 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee94a_d20251122_m023429_c001_v0001038_t0021_u01763778869051") 2025/11/22 02:36:28 DEBUG : sub dir/potato3: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f41780fe05e022523_d20251122_m023614_c001_v7007000_t0000_u01763778974588") 2025/11/22 02:36:28 DEBUG : sub dir/potato3: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f119fe4226f18a4ff_d20251122_m023613_c001_v0001185_t0010_u01763778973270") 2025/11/22 02:36:28 DEBUG : sub dir/potato3: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73eea5f_d20251122_m023611_c001_v0001038_t0002_u01763778971470") 2025/11/22 02:36:28 DEBUG : sub dir/potato3: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f418bfe77149950b2_d20251122_m023505_c001_v7007000_t0000_u01763778905544") 2025/11/22 02:36:28 DEBUG : sub dir/potato3: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee9b8_d20251122_m023504_c001_v0001038_t0045_u01763778904080") 2025/11/22 02:36:29 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f418bfe7714994fd5_d20251122_m023440_c001_v7007000_t0000_u01763778880820") 2025/11/22 02:36:29 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee967_d20251122_m023436_c001_v0001038_t0007_u01763778876722") 2025/11/22 02:36:29 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f418bfe7714994fba_d20251122_m023433_c001_v7007000_t0000_u01763778873614") 2025/11/22 02:36:29 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee94d_d20251122_m023429_c001_v0001038_t0019_u01763778869368") 2025/11/22 02:36:29 DEBUG : sub/file2: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f4162454a20eef7bb_d20251122_m023540_c001_v7007000_t0000_u01763778940167") 2025/11/22 02:36:29 DEBUG : sub/file2: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73eea04_d20251122_m023538_c001_v0001038_t0051_u01763778938842") 2025/11/22 02:36:29 DEBUG : sub/file2: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f418bfe7714995026_d20251122_m023451_c001_v7007000_t0000_u01763778891233") 2025/11/22 02:36:29 DEBUG : sub/file2: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee978_d20251122_m023445_c001_v0001038_t0043_u01763778885437") 2025/11/22 02:36:29 DEBUG : sub/file2: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f418bfe7714994feb_d20251122_m023444_c001_v7007000_t0000_u01763778884580") 2025/11/22 02:36:29 DEBUG : sub/file2: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee971_d20251122_m023441_c001_v0001038_t0025_u01763778881695") 2025/11/22 02:36:29 DEBUG : sub/file2: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f410f0b1035c04f33_d20251122_m023339_c001_v7007000_t0000_u01763778819452") 2025/11/22 02:36:29 DEBUG : sub/file2: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee8ca_d20251122_m023337_c001_v0001038_t0011_u01763778817575") 2025/11/22 02:36:29 DEBUG : test-multithread-abort: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f406831504b3b5b2d_d20251122_m023213_c001_v7007000_t0000_u01763778733887") 2025/11/22 02:36:29 DEBUG : test-multithread-abort: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee842_d20251122_m023157_c001_v0001038_t0037_u01763778717650") 2025/11/22 02:36:29 DEBUG : test-multithread-copy-false-16777215-2: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f401731351c3f01e7_d20251122_m023118_c001_v7007000_t0000_u01763778678551") 2025/11/22 02:36:29 DEBUG : test-multithread-copy-false-16777215-2: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee80d_d20251122_m023111_c001_v0001038_t0034_u01763778671055") 2025/11/22 02:36:29 DEBUG : test-multithread-copy-false-16777216-2: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f401731351c3f0217_d20251122_m023126_c001_v7007000_t0000_u01763778686756") 2025/11/22 02:36:29 DEBUG : test-multithread-copy-false-16777216-2: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee81a_d20251122_m023119_c001_v0001038_t0052_u01763778679362") 2025/11/22 02:36:29 DEBUG : test-multithread-copy-false-16777217-2: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f401731351c3f0242_d20251122_m023134_c001_v7007000_t0000_u01763778694973") 2025/11/22 02:36:29 DEBUG : test-multithread-copy-false-16777217-2: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee828_d20251122_m023127_c001_v0001038_t0012_u01763778687544") 2025/11/22 02:36:29 DEBUG : test-multithread-copy-true-16777215-2: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f406831504b3b5ab2_d20251122_m023142_c001_v7007000_t0000_u01763778702025") 2025/11/22 02:36:29 DEBUG : test-multithread-copy-true-16777215-2: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f201731351c3f024c_d20251122_m023137_c001_v0001185_t0007_u01763778697121") 2025/11/22 02:36:30 DEBUG : test-multithread-copy-true-16777216-2: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f423e42c075ad1a88_d20251122_m023149_c001_v7007000_t0000_u01763778709036") 2025/11/22 02:36:30 DEBUG : test-multithread-copy-true-16777216-2: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f206831504b3b5aba_d20251122_m023144_c001_v0001185_t0025_u01763778704146") 2025/11/22 02:36:30 DEBUG : test-multithread-copy-true-16777217-2: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f423e42c075ad1a9f_d20251122_m023156_c001_v7007000_t0000_u01763778716274") 2025/11/22 02:36:30 DEBUG : test-multithread-copy-true-16777217-2: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f223e42c075ad1a95_d20251122_m023151_c001_v0001137_t0043_u01763778711187") 2025/11/22 02:36:30 DEBUG : test.sum: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f406831504b3b5c3a_d20251122_m023250_c001_v7007000_t0000_u01763778770086") 2025/11/22 02:36:30 DEBUG : test.sum: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee8a4_d20251122_m023248_c001_v0001038_t0034_u01763778768101") 2025/11/22 02:36:30 DEBUG : test.sum: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee89e_d20251122_m023245_c001_v0001038_t0032_u01763778765902") 2025/11/22 02:36:30 DEBUG : test.sum: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee89c_d20251122_m023244_c001_v0001038_t0014_u01763778764498") 2025/11/22 02:36:30 DEBUG : test.sum: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee898_d20251122_m023242_c001_v0001038_t0000_u01763778762966") 2025/11/22 02:36:30 DEBUG : test.sum: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee896_d20251122_m023241_c001_v0001038_t0046_u01763778761466") 2025/11/22 02:36:30 DEBUG : test.sum: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee894_d20251122_m023240_c001_v0001038_t0027_u01763778760168") 2025/11/22 02:36:30 DEBUG : test.sum: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee888_d20251122_m023237_c001_v0001038_t0058_u01763778757735") 2025/11/22 02:36:30 DEBUG : test.sum: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f406831504b3b5b95_d20251122_m023234_c001_v7007000_t0000_u01763778754198") 2025/11/22 02:36:30 DEBUG : test.sum: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee882_d20251122_m023233_c001_v0001038_t0028_u01763778753261") 2025/11/22 02:36:30 DEBUG : test.sum: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee880_d20251122_m023232_c001_v0001038_t0030_u01763778752107") 2025/11/22 02:36:30 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f400e9e5b5fbc1e59_d20251122_m023601_c001_v7007000_t0000_u01763778961972") 2025/11/22 02:36:30 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73eea37_d20251122_m023600_c001_v0001038_t0018_u01763778960650") 2025/11/22 02:36:30 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f400e9e5b5fbc1e5a_d20251122_m023602_c001_v7007000_t0000_u01763778962208") 2025/11/22 02:36:30 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73eea34_d20251122_m023600_c001_v0001038_t0034_u01763778960202") 2025/11/22 02:36:30 DEBUG : zend.txt: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f418bfe7714994fd9_d20251122_m023441_c001_v7007000_t0000_u01763778881001") 2025/11/22 02:36:31 DEBUG : zend.txt: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee95b_d20251122_m023434_c001_v0001038_t0019_u01763778874584") 2025/11/22 02:36:31 DEBUG : zend.txt: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f418bfe7714994fbc_d20251122_m023433_c001_v7007000_t0000_u01763778873794") 2025/11/22 02:36:31 DEBUG : zend.txt: Deleting (id "4_z7fa2ada3606dab9b9fa80e1b_f1035df2bd73ee93c_d20251122_m023427_c001_v0001038_t0034_u01763778867020") "./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -fast-list" - Finished OK in 5m23.862340209s (try 1/5)