"./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -fast-list" - Starting (try 1/5) 2025/11/18 03:23:39 DEBUG : Creating backend with remote "TestB2:rclone-test-maranad2rige" 2025/11/18 03:23:39 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/18 03:23:40 DEBUG : Creating backend with remote "/tmp/rclone4675142" === 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-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" 2025/11/18 03:23:41 DEBUG : chunksize-probe: Cancelling large file upload === RUN TestMultithreadCopy/upload=false,size=16777215,streams=2 2025/11/18 03:23:46 DEBUG : test-multithread-copy-false-16777215-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2025/11/18 03:23:46 DEBUG : test-multithread-copy-false-16777215-2: multi-thread copy: write buffer set to 131072 2025/11/18 03:23:46 DEBUG : test-multithread-copy-false-16777215-2: multi-thread copy: chunk size 64Mi was bigger than source file size 16.000Mi 2025/11/18 03:23:46 DEBUG : test-multithread-copy-false-16777215-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2025/11/18 03:23:46 DEBUG : test-multithread-copy-false-16777215-2: multi-thread copy: number of streams 4 was bigger than number of chunks 1 2025/11/18 03:23:46 DEBUG : test-multithread-copy-false-16777215-2: Starting multi-thread copy with 1 chunks of size 16.000Mi with 1 parallel streams 2025/11/18 03:23:46 DEBUG : test-multithread-copy-false-16777215-2: multi-thread copy: chunk 1/1 (0-16777215) size 16.000Mi starting 2025/11/18 03:23:46 DEBUG : test-multithread-copy-false-16777215-2: writing chunk 0 2025/11/18 03:23:48 DEBUG : test-multithread-copy-false-16777215-2: multi-thread copy: chunk 1/1 (0-16777215) size 16.000Mi finished 2025/11/18 03:23:48 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/18 03:23:54 DEBUG : test-multithread-copy-false-16777216-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2025/11/18 03:23:54 DEBUG : test-multithread-copy-false-16777216-2: multi-thread copy: write buffer set to 131072 2025/11/18 03:23:54 DEBUG : test-multithread-copy-false-16777216-2: multi-thread copy: chunk size 64Mi was bigger than source file size 16Mi 2025/11/18 03:23:54 DEBUG : test-multithread-copy-false-16777216-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2025/11/18 03:23:54 DEBUG : test-multithread-copy-false-16777216-2: multi-thread copy: number of streams 4 was bigger than number of chunks 1 2025/11/18 03:23:54 DEBUG : test-multithread-copy-false-16777216-2: Starting multi-thread copy with 1 chunks of size 16Mi with 1 parallel streams 2025/11/18 03:23:54 DEBUG : test-multithread-copy-false-16777216-2: multi-thread copy: chunk 1/1 (0-16777216) size 16Mi starting 2025/11/18 03:23:54 DEBUG : test-multithread-copy-false-16777216-2: writing chunk 0 2025/11/18 03:23:56 DEBUG : test-multithread-copy-false-16777216-2: multi-thread copy: chunk 1/1 (0-16777216) size 16Mi finished 2025/11/18 03:23:56 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/18 03:24:02 DEBUG : test-multithread-copy-false-16777217-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2025/11/18 03:24:02 DEBUG : test-multithread-copy-false-16777217-2: multi-thread copy: write buffer set to 131072 2025/11/18 03:24:02 DEBUG : test-multithread-copy-false-16777217-2: multi-thread copy: chunk size 64Mi was bigger than source file size 16.000Mi 2025/11/18 03:24:02 DEBUG : test-multithread-copy-false-16777217-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2025/11/18 03:24:02 DEBUG : test-multithread-copy-false-16777217-2: multi-thread copy: number of streams 4 was bigger than number of chunks 1 2025/11/18 03:24:02 DEBUG : test-multithread-copy-false-16777217-2: Starting multi-thread copy with 1 chunks of size 16.000Mi with 1 parallel streams 2025/11/18 03:24:02 DEBUG : test-multithread-copy-false-16777217-2: multi-thread copy: chunk 1/1 (0-16777217) size 16.000Mi starting 2025/11/18 03:24:03 DEBUG : test-multithread-copy-false-16777217-2: writing chunk 0 2025/11/18 03:24:05 DEBUG : test-multithread-copy-false-16777217-2: multi-thread copy: chunk 1/1 (0-16777217) size 16.000Mi finished 2025/11/18 03:24:05 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/18 03:24:08 DEBUG : test-multithread-copy-true-16777215-2: multi-thread copy: disabling buffering because source is local disk 2025/11/18 03:24:08 DEBUG : test-multithread-copy-true-16777215-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2025/11/18 03:24:08 DEBUG : test-multithread-copy-true-16777215-2: multi-thread copy: number of streams 4 was bigger than number of chunks 2 2025/11/18 03:24:08 DEBUG : test-multithread-copy-true-16777215-2: Starting multi-thread copy with 2 chunks of size 8Mi with 2 parallel streams 2025/11/18 03:24:08 DEBUG : test-multithread-copy-true-16777215-2: multi-thread copy: chunk 2/2 (8388608-16777215) size 8.000Mi starting 2025/11/18 03:24:08 DEBUG : test-multithread-copy-true-16777215-2: multi-thread copy: chunk 1/2 (0-8388608) size 8Mi starting 2025/11/18 03:24:08 DEBUG : test-multithread-copy-true-16777215-2: Sending chunk 1 length 8388607 2025/11/18 03:24:08 DEBUG : test-multithread-copy-true-16777215-2: Sending chunk 0 length 8388608 2025/11/18 03:24:11 DEBUG : test-multithread-copy-true-16777215-2: Done sending chunk 1 2025/11/18 03:24:11 DEBUG : test-multithread-copy-true-16777215-2: multi-thread copy: chunk 2/2 (8388608-16777215) size 8.000Mi finished 2025/11/18 03:24:12 DEBUG : test-multithread-copy-true-16777215-2: Done sending chunk 0 2025/11/18 03:24:12 DEBUG : test-multithread-copy-true-16777215-2: multi-thread copy: chunk 1/2 (0-8388608) size 8Mi finished 2025/11/18 03:24:12 DEBUG : test-multithread-copy-true-16777215-2: Finishing large file upload with 2 parts 2025/11/18 03:24:12 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/18 03:24:16 DEBUG : test-multithread-copy-true-16777216-2: multi-thread copy: disabling buffering because source is local disk 2025/11/18 03:24:16 DEBUG : test-multithread-copy-true-16777216-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2025/11/18 03:24:16 DEBUG : test-multithread-copy-true-16777216-2: multi-thread copy: number of streams 4 was bigger than number of chunks 2 2025/11/18 03:24:16 DEBUG : test-multithread-copy-true-16777216-2: Starting multi-thread copy with 2 chunks of size 8Mi with 2 parallel streams 2025/11/18 03:24:16 DEBUG : test-multithread-copy-true-16777216-2: multi-thread copy: chunk 2/2 (8388608-16777216) size 8Mi starting 2025/11/18 03:24:16 DEBUG : test-multithread-copy-true-16777216-2: multi-thread copy: chunk 1/2 (0-8388608) size 8Mi starting 2025/11/18 03:24:16 DEBUG : test-multithread-copy-true-16777216-2: Sending chunk 1 length 8388608 2025/11/18 03:24:16 DEBUG : test-multithread-copy-true-16777216-2: Sending chunk 0 length 8388608 2025/11/18 03:24:19 DEBUG : test-multithread-copy-true-16777216-2: Done sending chunk 1 2025/11/18 03:24:19 DEBUG : test-multithread-copy-true-16777216-2: multi-thread copy: chunk 2/2 (8388608-16777216) size 8Mi finished 2025/11/18 03:24:19 DEBUG : test-multithread-copy-true-16777216-2: Done sending chunk 0 2025/11/18 03:24:19 DEBUG : test-multithread-copy-true-16777216-2: multi-thread copy: chunk 1/2 (0-8388608) size 8Mi finished 2025/11/18 03:24:19 DEBUG : test-multithread-copy-true-16777216-2: Finishing large file upload with 2 parts 2025/11/18 03:24:20 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/18 03:24:23 DEBUG : test-multithread-copy-true-16777217-2: multi-thread copy: disabling buffering because source is local disk 2025/11/18 03:24:24 DEBUG : test-multithread-copy-true-16777217-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2025/11/18 03:24:24 DEBUG : test-multithread-copy-true-16777217-2: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2025/11/18 03:24:24 DEBUG : test-multithread-copy-true-16777217-2: Starting multi-thread copy with 3 chunks of size 8Mi with 3 parallel streams 2025/11/18 03:24:24 DEBUG : test-multithread-copy-true-16777217-2: multi-thread copy: chunk 3/3 (16777216-16777217) size 1 starting 2025/11/18 03:24:24 DEBUG : test-multithread-copy-true-16777217-2: multi-thread copy: chunk 1/3 (0-8388608) size 8Mi starting 2025/11/18 03:24:24 DEBUG : test-multithread-copy-true-16777217-2: Sending chunk 0 length 8388608 2025/11/18 03:24:24 DEBUG : test-multithread-copy-true-16777217-2: multi-thread copy: chunk 2/3 (8388608-16777216) size 8Mi starting 2025/11/18 03:24:24 DEBUG : test-multithread-copy-true-16777217-2: Sending chunk 2 length 1 2025/11/18 03:24:24 DEBUG : test-multithread-copy-true-16777217-2: Sending chunk 1 length 8388608 2025/11/18 03:24:24 DEBUG : test-multithread-copy-true-16777217-2: Done sending chunk 2 2025/11/18 03:24:24 DEBUG : test-multithread-copy-true-16777217-2: multi-thread copy: chunk 3/3 (16777216-16777217) size 1 finished 2025/11/18 03:24:26 DEBUG : test-multithread-copy-true-16777217-2: Done sending chunk 0 2025/11/18 03:24:26 DEBUG : test-multithread-copy-true-16777217-2: multi-thread copy: chunk 1/3 (0-8388608) size 8Mi finished 2025/11/18 03:24:26 DEBUG : test-multithread-copy-true-16777217-2: Done sending chunk 1 2025/11/18 03:24:26 DEBUG : test-multithread-copy-true-16777217-2: multi-thread copy: chunk 2/3 (8388608-16777216) size 8Mi finished 2025/11/18 03:24:26 DEBUG : test-multithread-copy-true-16777217-2: Finishing large file upload with 3 parts 2025/11/18 03:24:27 DEBUG : test-multithread-copy-true-16777217-2: Finished multi-thread copy with 3 parts of size 8Mi --- PASS: TestMultithreadCopy (49.18s) --- PASS: TestMultithreadCopy/upload=false,size=16777215,streams=2 (8.45s) --- PASS: TestMultithreadCopy/upload=false,size=16777216,streams=2 (8.48s) --- PASS: TestMultithreadCopy/upload=false,size=16777217,streams=2 (8.35s) --- PASS: TestMultithreadCopy/upload=true,size=16777215,streams=2 (7.62s) --- PASS: TestMultithreadCopy/upload=true,size=16777216,streams=2 (7.48s) --- PASS: TestMultithreadCopy/upload=true,size=16777217,streams=2 (7.23s) === RUN TestMultithreadCopyAbort run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" 2025/11/18 03:24:29 DEBUG : chunksize-probe: Cancelling large file upload 2025/11/18 03:24:32 DEBUG : test-multithread-abort: multi-thread copy: disabling buffering because source is local disk 2025/11/18 03:24:32 DEBUG : test-multithread-abort: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 1 2025/11/18 03:24:32 DEBUG : test-multithread-abort: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2025/11/18 03:24:32 DEBUG : test-multithread-abort: Starting multi-thread copy with 3 chunks of size 8Mi with 3 parallel streams 2025/11/18 03:24:32 DEBUG : test-multithread-abort: multi-thread copy: chunk 3/3 (16777216-16777217) size 1 starting 2025/11/18 03:24:32 DEBUG : test-multithread-abort: multi-thread copy: chunk 2/3 (8388608-16777216) size 8Mi starting 2025/11/18 03:24:32 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2025/11/18 03:24:32 DEBUG : Open with options = [RangeOption(8388608,16777215)] 2025/11/18 03:24:32 DEBUG : test-multithread-abort: Sending chunk 1 length 8388608 2025/11/18 03:24:32 DEBUG : test-multithread-abort: multi-thread copy: chunk 1/3 (0-8388608) size 8Mi starting 2025/11/18 03:24:32 DEBUG : Open with options = [RangeOption(0,8388607)] 2025/11/18 03:24:32 DEBUG : test-multithread-abort: Sending chunk 0 length 8388608 2025/11/18 03:24:35 DEBUG : test-multithread-abort: Done sending chunk 1 2025/11/18 03:24:35 DEBUG : test-multithread-abort: multi-thread copy: chunk 2/3 (8388608-16777216) size 8Mi finished 2025/11/18 03:24:35 DEBUG : test-multithread-abort: Done sending chunk 0 2025/11/18 03:24:35 DEBUG : test-multithread-abort: multi-thread copy: chunk 1/3 (0-8388608) size 8Mi finished 2025/11/18 03:24:35 DEBUG : Returning error reader 2025/11/18 03:24:35 DEBUG : test-multithread-abort: Sending chunk 2 length 1 2025/11/18 03:24:35 DEBUG : BOOM: simulated read failure 2025/11/18 03:24:35 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 0/10: BOOM: simulated read failure 2025/11/18 03:24:35 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2025/11/18 03:24:36 DEBUG : Returning error reader 2025/11/18 03:24:36 DEBUG : BOOM: simulated read failure 2025/11/18 03:24:36 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 1/10: BOOM: simulated read failure 2025/11/18 03:24:36 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2025/11/18 03:24:37 DEBUG : Returning error reader 2025/11/18 03:24:37 DEBUG : BOOM: simulated read failure 2025/11/18 03:24:37 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 2/10: BOOM: simulated read failure 2025/11/18 03:24:37 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2025/11/18 03:24:38 DEBUG : Returning error reader 2025/11/18 03:24:38 DEBUG : BOOM: simulated read failure 2025/11/18 03:24:38 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 3/10: BOOM: simulated read failure 2025/11/18 03:24:38 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2025/11/18 03:24:39 DEBUG : Returning error reader 2025/11/18 03:24:39 DEBUG : BOOM: simulated read failure 2025/11/18 03:24:39 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 4/10: BOOM: simulated read failure 2025/11/18 03:24:39 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2025/11/18 03:24:40 DEBUG : Returning error reader 2025/11/18 03:24:40 DEBUG : BOOM: simulated read failure 2025/11/18 03:24:40 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 5/10: BOOM: simulated read failure 2025/11/18 03:24:40 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2025/11/18 03:24:41 DEBUG : Returning error reader 2025/11/18 03:24:41 DEBUG : BOOM: simulated read failure 2025/11/18 03:24:41 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 6/10: BOOM: simulated read failure 2025/11/18 03:24:41 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2025/11/18 03:24:42 DEBUG : Returning error reader 2025/11/18 03:24:42 DEBUG : BOOM: simulated read failure 2025/11/18 03:24:42 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 7/10: BOOM: simulated read failure 2025/11/18 03:24:42 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2025/11/18 03:24:43 DEBUG : Returning error reader 2025/11/18 03:24:43 DEBUG : BOOM: simulated read failure 2025/11/18 03:24:43 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 8/10: BOOM: simulated read failure 2025/11/18 03:24:43 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2025/11/18 03:24:44 DEBUG : Returning error reader 2025/11/18 03:24:44 DEBUG : BOOM: simulated read failure 2025/11/18 03:24:44 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 9/10: BOOM: simulated read failure 2025/11/18 03:24:44 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2025/11/18 03:24:45 DEBUG : Returning error reader 2025/11/18 03:24:45 DEBUG : BOOM: simulated read failure 2025/11/18 03:24:45 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 10/10: BOOM: simulated read failure 2025/11/18 03:24:45 DEBUG : test-multithread-abort: Reopen failed after offset 0 bytes read: failed to reopen: too many retries 2025/11/18 03:24:45 DEBUG : test-multithread-abort: Error sending chunk 2 (retry=false): Post "https://pod-000-1044-01.backblaze.com/b2api/v1/b2_upload_part/4_z0f925d13f0dd9b4b9fa80e1b_f2196b5bd76f94ca5_d20251118_m032432_c001_v0001044_t0016_u01763436272823/0045": BOOM: simulated read failure: &url.Error{Op:"Post", URL:"https://pod-000-1044-01.backblaze.com/b2api/v1/b2_upload_part/4_z0f925d13f0dd9b4b9fa80e1b_f2196b5bd76f94ca5_d20251118_m032432_c001_v0001044_t0016_u01763436272823/0045", Err:(*errors.errorString)(0xc0003e8a30)} 2025/11/18 03:24:45 DEBUG : test-multithread-abort: Error sending chunk 2: Post "https://pod-000-1044-01.backblaze.com/b2api/v1/b2_upload_part/4_z0f925d13f0dd9b4b9fa80e1b_f2196b5bd76f94ca5_d20251118_m032432_c001_v0001044_t0016_u01763436272823/0045": BOOM: simulated read failure 2025/11/18 03:24:45 DEBUG : test-multithread-abort: multi-thread copy: chunk 3/3 failed: multi-thread copy: failed to write chunk: Post "https://pod-000-1044-01.backblaze.com/b2api/v1/b2_upload_part/4_z0f925d13f0dd9b4b9fa80e1b_f2196b5bd76f94ca5_d20251118_m032432_c001_v0001044_t0016_u01763436272823/0045": BOOM: simulated read failure 2025/11/18 03:24:45 DEBUG : test-multithread-abort: multi-thread copy: cancelling transfer on exit 2025/11/18 03:24:45 DEBUG : test-multithread-abort: Cancelling large file upload --- PASS: TestMultithreadCopyAbort (17.85s) === RUN TestSizeDiffers 2025/11/18 03:24:47 DEBUG : a: size = 0 OK 2025/11/18 03:24:47 DEBUG : a: size = 1 (memory) 2025/11/18 03:24:47 DEBUG : a: size = 2 (memory) --- PASS: TestSizeDiffers (0.00s) === RUN TestReOpen === RUN TestReOpen/Normal === RUN TestReOpen/Normal/Basics 2025/11/18 03:24:47 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/ErrorAtStart === RUN TestReOpen/Normal/WithErrors 2025/11/18 03:24:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2025/11/18 03:24:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2025/11/18 03:24:47 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/Normal/TooManyErrors 2025/11/18 03:24:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2025/11/18 03:24:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2025/11/18 03:24:47 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2025/11/18 03:24:47 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/Normal/ReadAt 2025/11/18 03:24:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/11/18 03:24:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/11/18 03:24:47 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/Normal/Seek 2025/11/18 03:24:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/11/18 03:24:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/11/18 03:24:47 DEBUG : potato: Seek from 5 to 2 === RUN TestReOpen/Normal/AccountRead === RUN TestReOpen/Normal/AccountReadDelay 2025/11/18 03:24:47 DEBUG : potato: Seek from 10 to 0 2025/11/18 03:24:47 DEBUG : potato: Seek from 10 to 0 2025/11/18 03:24:47 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/AccountReadError === RUN TestReOpen/WithRangeOption === RUN TestReOpen/WithRangeOption/Basics 2025/11/18 03:24:47 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/ErrorAtStart === RUN TestReOpen/WithRangeOption/WithErrors 2025/11/18 03:24:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2025/11/18 03:24:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2025/11/18 03:24:47 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithRangeOption/TooManyErrors 2025/11/18 03:24:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2025/11/18 03:24:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2025/11/18 03:24:47 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2025/11/18 03:24:47 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithRangeOption/ReadAt 2025/11/18 03:24:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/11/18 03:24:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/11/18 03:24:47 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/WithRangeOption/Seek 2025/11/18 03:24:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/11/18 03:24:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/11/18 03:24:47 DEBUG : potato: Seek from 5 to 2 2025/11/18 03:24:47 DEBUG : potato: Seek from 7 to 4 === RUN TestReOpen/WithRangeOption/AccountRead === RUN TestReOpen/WithRangeOption/AccountReadDelay 2025/11/18 03:24:47 DEBUG : potato: Seek from 7 to 0 2025/11/18 03:24:47 DEBUG : potato: Seek from 7 to 0 2025/11/18 03:24:47 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/AccountReadError === RUN TestReOpen/WithSeekOption === RUN TestReOpen/WithSeekOption/Basics 2025/11/18 03:24:47 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/ErrorAtStart === RUN TestReOpen/WithSeekOption/WithErrors 2025/11/18 03:24:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2025/11/18 03:24:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2025/11/18 03:24:47 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithSeekOption/TooManyErrors 2025/11/18 03:24:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2025/11/18 03:24:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2025/11/18 03:24:47 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2025/11/18 03:24:47 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithSeekOption/ReadAt 2025/11/18 03:24:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/11/18 03:24:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/11/18 03:24:47 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/WithSeekOption/Seek 2025/11/18 03:24:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/11/18 03:24:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/11/18 03:24:47 DEBUG : potato: Seek from 5 to 2 2025/11/18 03:24:47 DEBUG : potato: Seek from 7 to 5 === RUN TestReOpen/WithSeekOption/AccountRead === RUN TestReOpen/WithSeekOption/AccountReadDelay 2025/11/18 03:24:47 DEBUG : potato: Seek from 8 to 0 2025/11/18 03:24:47 DEBUG : potato: Seek from 8 to 0 2025/11/18 03:24:47 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/AccountReadError === RUN TestReOpen/UnknownSize === RUN TestReOpen/UnknownSize/Basics 2025/11/18 03:24:47 DEBUG : potato: Seek from 9 to 0 === RUN TestReOpen/UnknownSize/ErrorAtStart === RUN TestReOpen/UnknownSize/WithErrors 2025/11/18 03:24:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2025/11/18 03:24:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2025/11/18 03:24:47 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/UnknownSize/TooManyErrors 2025/11/18 03:24:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2025/11/18 03:24:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2025/11/18 03:24:47 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2025/11/18 03:24:47 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/UnknownSize/ReadAt 2025/11/18 03:24:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/11/18 03:24:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/11/18 03:24:47 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/UnknownSize/Seek 2025/11/18 03:24:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/11/18 03:24:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/11/18 03:24:47 DEBUG : potato: Seek from 5 to 2 2025/11/18 03:24:47 DEBUG : potato: Seek from 7 to 6 === RUN TestReOpen/UnknownSize/AccountRead === RUN TestReOpen/UnknownSize/AccountReadDelay 2025/11/18 03:24:47 DEBUG : potato: Seek from 9 to 0 2025/11/18 03:24:47 DEBUG : potato: Seek from 9 to 0 2025/11/18 03:24:47 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-maranad2rige", Local "Local file system at /tmp/rclone4675142", 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.05s) --- PASS: TestCheck/1 (0.18s) --- PASS: TestCheck/2 (0.18s) --- PASS: TestCheck/3 (0.18s) --- PASS: TestCheck/4 (0.18s) --- PASS: TestCheck/5 (0.18s) --- PASS: TestCheck/6 (0.18s) --- PASS: TestCheck/7 (0.18s) === RUN TestCheckFsError 2025/11/18 03:24:52 DEBUG : Creating backend with remote "nonexistent" 2025/11/18 03:24:52 DEBUG : Config file has changed externally - reloading 2025/11/18 03:24:52 DEBUG : Creating backend with remote "nonexistent" 2025/11/18 03:24:52 DEBUG : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: Waiting for checks to finish 2025/11/18 03:24:52 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/18 03:24:52 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: 0 differences found 2025/11/18 03:24:52 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: 2 errors while checking --- PASS: TestCheckFsError (0.00s) === RUN TestCheckDownload run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" === RUN TestCheckDownload/1 === RUN TestCheckDownload/2 === RUN TestCheckDownload/3 === RUN TestCheckDownload/4 === RUN TestCheckDownload/5 === RUN TestCheckDownload/6 === RUN TestCheckDownload/7 --- PASS: TestCheckDownload (6.56s) --- PASS: TestCheckDownload/1 (0.73s) --- PASS: TestCheckDownload/2 (0.38s) --- PASS: TestCheckDownload/3 (0.36s) --- PASS: TestCheckDownload/4 (0.55s) --- PASS: TestCheckDownload/5 (0.40s) --- PASS: TestCheckDownload/6 (0.38s) --- PASS: TestCheckDownload/7 (0.37s) === RUN TestCheckSizeOnly run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", 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.45s) --- PASS: TestCheckSizeOnly/1 (0.19s) --- 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-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" 2025/11/18 03:25:05 NOTICE: test.sum: improperly formatted checksum line 4 2025/11/18 03:25:05 NOTICE: test.sum: improperly formatted checksum line 5 2025/11/18 03:25:05 NOTICE: test.sum: improperly formatted checksum line 6 2025/11/18 03:25:05 NOTICE: test.sum: 2 warning(s) suppressed... 2025/11/18 03:25:05 NOTICE: test.sum: improperly formatted checksum line 4 2025/11/18 03:25:05 NOTICE: test.sum: improperly formatted checksum line 5 2025/11/18 03:25:05 NOTICE: test.sum: improperly formatted checksum line 6 2025/11/18 03:25:05 NOTICE: test.sum: 2 warning(s) suppressed... --- PASS: TestParseSumFile (3.00s) === RUN TestCheckSum run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" 2025/11/18 03:25:06 DEBUG : Creating backend with remote "TestB2:rclone-test-maranad2rige/data" check_test.go:350: B2 bucket rclone-test-maranad2rige path data lacks md5, skipping --- SKIP: TestCheckSum (1.43s) === RUN TestCheckSumDownload run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" 2025/11/18 03:25:07 DEBUG : Creating backend with remote "TestB2:rclone-test-maranad2rige/data" === RUN TestCheckSumDownload/subtest1 === RUN TestCheckSumDownload/subtest2 === RUN TestCheckSumDownload/subtest3 === RUN TestCheckSumDownload/subtest4 === RUN TestCheckSumDownload/subtest5 === RUN TestCheckSumDownload/subtest6 === RUN TestCheckSumDownload/subtest7 --- PASS: TestCheckSumDownload (13.51s) --- PASS: TestCheckSumDownload/subtest1 (1.31s) --- PASS: TestCheckSumDownload/subtest2 (0.73s) --- PASS: TestCheckSumDownload/subtest3 (0.91s) --- PASS: TestCheckSumDownload/subtest4 (0.74s) --- PASS: TestCheckSumDownload/subtest5 (0.75s) --- PASS: TestCheckSumDownload/subtest6 (0.82s) --- PASS: TestCheckSumDownload/subtest7 (0.74s) === RUN TestApplyTransforms 2025/11/18 03:25:21 DEBUG : Creating backend with remote "TestB2:rclone-test-hagerix4zawu" 2025/11/18 03:25:21 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/18 03:25:21 DEBUG : Creating backend with remote "/tmp/rclone4213161398" run.go:185: Remote "B2 bucket rclone-test-hagerix4zawu", Local "Local file system at /tmp/rclone4213161398", Modify Window "1ms" upper checkfile vs. lower remote (without normalization) 2025/11/18 03:25:24 ERROR : hello, world!: sum not found 2025/11/18 03:25:24 ERROR : HELLO, WORLD!: file not in B2 bucket rclone-test-hagerix4zawu 2025/11/18 03:25:24 NOTICE: B2 bucket rclone-test-hagerix4zawu: 1 files missing 2025/11/18 03:25:24 NOTICE: 1 hashes missing 2025/11/18 03:25:24 NOTICE: B2 bucket rclone-test-hagerix4zawu: 1 differences found 2025/11/18 03:25:24 NOTICE: B2 bucket rclone-test-hagerix4zawu: 2 errors while checking upper checkfile vs. lower remote (with normalization) 2025/11/18 03:25:25 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/11/18 03:25:25 NOTICE: B2 bucket rclone-test-hagerix4zawu: 0 differences found 2025/11/18 03:25:25 NOTICE: B2 bucket rclone-test-hagerix4zawu: 1 matching files 2025/11/18 03:25:25 DEBUG : Creating backend with remote "TestB2:rclone-test-radeyoh6difa" 2025/11/18 03:25:25 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/18 03:25:25 DEBUG : Creating backend with remote "/tmp/rclone3964221674" run.go:185: Remote "B2 bucket rclone-test-radeyoh6difa", Local "Local file system at /tmp/rclone3964221674", Modify Window "1ms" lower checkfile vs. upper remote (without normalization) 2025/11/18 03:25:27 ERROR : HELLO, WORLD!: sum not found 2025/11/18 03:25:27 ERROR : hello, world!: file not in B2 bucket rclone-test-radeyoh6difa 2025/11/18 03:25:27 NOTICE: B2 bucket rclone-test-radeyoh6difa: 1 files missing 2025/11/18 03:25:27 NOTICE: 1 hashes missing 2025/11/18 03:25:27 NOTICE: B2 bucket rclone-test-radeyoh6difa: 1 differences found 2025/11/18 03:25:27 NOTICE: B2 bucket rclone-test-radeyoh6difa: 2 errors while checking lower checkfile vs. upper remote (with normalization) 2025/11/18 03:25:28 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/11/18 03:25:28 NOTICE: B2 bucket rclone-test-radeyoh6difa: 0 differences found 2025/11/18 03:25:28 NOTICE: B2 bucket rclone-test-radeyoh6difa: 1 matching files 2025/11/18 03:25:28 DEBUG : Creating backend with remote "TestB2:rclone-test-hovokiq7qawo" 2025/11/18 03:25:28 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/18 03:25:29 DEBUG : Creating backend with remote "/tmp/rclone515615163" run.go:185: Remote "B2 bucket rclone-test-hovokiq7qawo", Local "Local file system at /tmp/rclone515615163", Modify Window "1ms" lower checkfile vs. upperlowermixed remote (without normalization) 2025/11/18 03:25:31 ERROR : HeLlO, wOrLd!: sum not found 2025/11/18 03:25:31 ERROR : hello, world!: file not in B2 bucket rclone-test-hovokiq7qawo 2025/11/18 03:25:31 NOTICE: B2 bucket rclone-test-hovokiq7qawo: 1 files missing 2025/11/18 03:25:31 NOTICE: 1 hashes missing 2025/11/18 03:25:31 NOTICE: B2 bucket rclone-test-hovokiq7qawo: 1 differences found 2025/11/18 03:25:31 NOTICE: B2 bucket rclone-test-hovokiq7qawo: 2 errors while checking lower checkfile vs. upperlowermixed remote (with normalization) 2025/11/18 03:25:31 DEBUG : HeLlO, wOrLd!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/11/18 03:25:31 NOTICE: B2 bucket rclone-test-hovokiq7qawo: 0 differences found 2025/11/18 03:25:31 NOTICE: B2 bucket rclone-test-hovokiq7qawo: 1 matching files 2025/11/18 03:25:31 DEBUG : Creating backend with remote "TestB2:rclone-test-zegased8fixa" 2025/11/18 03:25:31 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/18 03:25:32 DEBUG : Creating backend with remote "/tmp/rclone2675123605" run.go:185: Remote "B2 bucket rclone-test-zegased8fixa", Local "Local file system at /tmp/rclone2675123605", Modify Window "1ms" upperlowermixed checkfile vs. upper remote (without normalization) 2025/11/18 03:25:34 ERROR : HELLO, WORLD!: sum not found 2025/11/18 03:25:34 ERROR : HeLlO, wOrLd!: file not in B2 bucket rclone-test-zegased8fixa 2025/11/18 03:25:34 NOTICE: B2 bucket rclone-test-zegased8fixa: 1 files missing 2025/11/18 03:25:34 NOTICE: 1 hashes missing 2025/11/18 03:25:34 NOTICE: B2 bucket rclone-test-zegased8fixa: 1 differences found 2025/11/18 03:25:34 NOTICE: B2 bucket rclone-test-zegased8fixa: 2 errors while checking upperlowermixed checkfile vs. upper remote (with normalization) 2025/11/18 03:25:35 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/11/18 03:25:35 NOTICE: B2 bucket rclone-test-zegased8fixa: 0 differences found 2025/11/18 03:25:35 NOTICE: B2 bucket rclone-test-zegased8fixa: 1 matching files 2025/11/18 03:25:35 DEBUG : Creating backend with remote "TestB2:rclone-test-fudepol6hosa" 2025/11/18 03:25:35 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/18 03:25:35 DEBUG : Creating backend with remote "/tmp/rclone1838513519" run.go:185: Remote "B2 bucket rclone-test-fudepol6hosa", Local "Local file system at /tmp/rclone1838513519", Modify Window "1ms" NFD checkfile vs. NFC remote (without normalization) 2025/11/18 03:25:37 ERROR : 測試_Русский___ě_áñ: sum not found 2025/11/18 03:25:37 ERROR : 測試_Русский___ě_áñ: file not in B2 bucket rclone-test-fudepol6hosa 2025/11/18 03:25:37 NOTICE: B2 bucket rclone-test-fudepol6hosa: 1 files missing 2025/11/18 03:25:37 NOTICE: 1 hashes missing 2025/11/18 03:25:37 NOTICE: B2 bucket rclone-test-fudepol6hosa: 1 differences found 2025/11/18 03:25:37 NOTICE: B2 bucket rclone-test-fudepol6hosa: 2 errors while checking NFD checkfile vs. NFC remote (with normalization) 2025/11/18 03:25:38 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/11/18 03:25:38 NOTICE: B2 bucket rclone-test-fudepol6hosa: 0 differences found 2025/11/18 03:25:38 NOTICE: B2 bucket rclone-test-fudepol6hosa: 1 matching files 2025/11/18 03:25:38 DEBUG : Creating backend with remote "TestB2:rclone-test-weqiwuj1sexu" 2025/11/18 03:25:38 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/18 03:25:38 DEBUG : Creating backend with remote "/tmp/rclone338772885" run.go:185: Remote "B2 bucket rclone-test-weqiwuj1sexu", Local "Local file system at /tmp/rclone338772885", Modify Window "1ms" NFC checkfile vs. NFD remote (without normalization) 2025/11/18 03:25:41 ERROR : 測試_Русский___ě_áñ: sum not found 2025/11/18 03:25:41 ERROR : 測試_Русский___ě_áñ: file not in B2 bucket rclone-test-weqiwuj1sexu 2025/11/18 03:25:41 NOTICE: B2 bucket rclone-test-weqiwuj1sexu: 1 files missing 2025/11/18 03:25:41 NOTICE: 1 hashes missing 2025/11/18 03:25:41 NOTICE: B2 bucket rclone-test-weqiwuj1sexu: 1 differences found 2025/11/18 03:25:41 NOTICE: B2 bucket rclone-test-weqiwuj1sexu: 2 errors while checking NFC checkfile vs. NFD remote (with normalization) 2025/11/18 03:25:41 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/11/18 03:25:41 NOTICE: B2 bucket rclone-test-weqiwuj1sexu: 0 differences found 2025/11/18 03:25:41 NOTICE: B2 bucket rclone-test-weqiwuj1sexu: 1 matching files 2025/11/18 03:25:41 DEBUG : Creating backend with remote "TestB2:rclone-test-dixomuc2yixe" 2025/11/18 03:25:41 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/18 03:25:42 DEBUG : Creating backend with remote "/tmp/rclone1473309863" run.go:185: Remote "B2 bucket rclone-test-dixomuc2yixe", Local "Local file system at /tmp/rclone1473309863", Modify Window "1ms" NFDx2 checkfile vs. both remote (without normalization) 2025/11/18 03:25:44 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2025/11/18 03:25:44 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-dixomuc2yixe 2025/11/18 03:25:44 NOTICE: B2 bucket rclone-test-dixomuc2yixe: 1 files missing 2025/11/18 03:25:44 NOTICE: 1 hashes missing 2025/11/18 03:25:44 NOTICE: B2 bucket rclone-test-dixomuc2yixe: 1 differences found 2025/11/18 03:25:44 NOTICE: B2 bucket rclone-test-dixomuc2yixe: 2 errors while checking NFDx2 checkfile vs. both remote (with normalization) 2025/11/18 03:25:45 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/11/18 03:25:45 NOTICE: B2 bucket rclone-test-dixomuc2yixe: 0 differences found 2025/11/18 03:25:45 NOTICE: B2 bucket rclone-test-dixomuc2yixe: 1 matching files 2025/11/18 03:25:45 DEBUG : Creating backend with remote "TestB2:rclone-test-dezipow3rewo" 2025/11/18 03:25:45 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/18 03:25:45 DEBUG : Creating backend with remote "/tmp/rclone1764123817" run.go:185: Remote "B2 bucket rclone-test-dezipow3rewo", Local "Local file system at /tmp/rclone1764123817", Modify Window "1ms" NFCx2 checkfile vs. both remote (without normalization) 2025/11/18 03:25:47 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2025/11/18 03:25:47 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-dezipow3rewo 2025/11/18 03:25:47 NOTICE: B2 bucket rclone-test-dezipow3rewo: 1 files missing 2025/11/18 03:25:47 NOTICE: 1 hashes missing 2025/11/18 03:25:47 NOTICE: B2 bucket rclone-test-dezipow3rewo: 1 differences found 2025/11/18 03:25:47 NOTICE: B2 bucket rclone-test-dezipow3rewo: 2 errors while checking NFCx2 checkfile vs. both remote (with normalization) 2025/11/18 03:25:48 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/11/18 03:25:48 NOTICE: B2 bucket rclone-test-dezipow3rewo: 0 differences found 2025/11/18 03:25:48 NOTICE: B2 bucket rclone-test-dezipow3rewo: 1 matching files 2025/11/18 03:25:48 DEBUG : Creating backend with remote "TestB2:rclone-test-nepifah3boya" 2025/11/18 03:25:48 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/18 03:25:50 DEBUG : Creating backend with remote "/tmp/rclone4014174600" run.go:185: Remote "B2 bucket rclone-test-nepifah3boya", Local "Local file system at /tmp/rclone4014174600", Modify Window "1ms" both checkfile vs. NFDx2 remote (without normalization) 2025/11/18 03:25:52 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2025/11/18 03:25:52 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-nepifah3boya 2025/11/18 03:25:52 NOTICE: B2 bucket rclone-test-nepifah3boya: 1 files missing 2025/11/18 03:25:52 NOTICE: 1 hashes missing 2025/11/18 03:25:52 NOTICE: B2 bucket rclone-test-nepifah3boya: 1 differences found 2025/11/18 03:25:52 NOTICE: B2 bucket rclone-test-nepifah3boya: 2 errors while checking both checkfile vs. NFDx2 remote (with normalization) 2025/11/18 03:25:52 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/11/18 03:25:52 NOTICE: B2 bucket rclone-test-nepifah3boya: 0 differences found 2025/11/18 03:25:52 NOTICE: B2 bucket rclone-test-nepifah3boya: 1 matching files 2025/11/18 03:25:52 DEBUG : Creating backend with remote "TestB2:rclone-test-xeqiyas7duzo" 2025/11/18 03:25:52 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/18 03:25:53 DEBUG : Creating backend with remote "/tmp/rclone1978797483" run.go:185: Remote "B2 bucket rclone-test-xeqiyas7duzo", Local "Local file system at /tmp/rclone1978797483", Modify Window "1ms" both checkfile vs. NFCx2 remote (without normalization) 2025/11/18 03:25:55 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2025/11/18 03:25:55 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-xeqiyas7duzo 2025/11/18 03:25:55 NOTICE: B2 bucket rclone-test-xeqiyas7duzo: 1 files missing 2025/11/18 03:25:55 NOTICE: 1 hashes missing 2025/11/18 03:25:55 NOTICE: B2 bucket rclone-test-xeqiyas7duzo: 1 differences found 2025/11/18 03:25:55 NOTICE: B2 bucket rclone-test-xeqiyas7duzo: 2 errors while checking both checkfile vs. NFCx2 remote (with normalization) 2025/11/18 03:25:56 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/11/18 03:25:56 NOTICE: B2 bucket rclone-test-xeqiyas7duzo: 0 differences found 2025/11/18 03:25:56 NOTICE: B2 bucket rclone-test-xeqiyas7duzo: 1 matching files 2025/11/18 03:25:56 DEBUG : B2 bucket rclone-test-xeqiyas7duzo: Purge remote 2025/11/18 03:25:56 INFO : B2 bucket rclone-test-xeqiyas7duzo: cleaning bucket "rclone-test-xeqiyas7duzo" of all files 2025/11/18 03:25:56 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_zdf225d93f0fd9b4b9fa80e1b_f10076dbff815867f_d20251118_m032554_c001_v0001182_t0013_u01763436354903") 2025/11/18 03:25:57 DEBUG : B2 bucket rclone-test-nepifah3boya: Purge remote 2025/11/18 03:25:57 INFO : B2 bucket rclone-test-nepifah3boya: cleaning bucket "rclone-test-nepifah3boya" of all files 2025/11/18 03:25:57 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_zcf724dc3f0fd9b4b9fa80e1b_f110a501c2c4e46ec_d20251118_m032551_c001_v0001176_t0009_u01763436351694") 2025/11/18 03:25:57 DEBUG : B2 bucket rclone-test-dezipow3rewo: Purge remote 2025/11/18 03:25:57 INFO : B2 bucket rclone-test-dezipow3rewo: cleaning bucket "rclone-test-dezipow3rewo" of all files 2025/11/18 03:25:58 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_zef523da3f0fd9b4b9fa80e1b_f1056130c9d11c234_d20251118_m032546_c001_v0001116_t0057_u01763436346977") 2025/11/18 03:25:59 DEBUG : B2 bucket rclone-test-dixomuc2yixe: Purge remote 2025/11/18 03:25:59 INFO : B2 bucket rclone-test-dixomuc2yixe: cleaning bucket "rclone-test-dixomuc2yixe" of all files 2025/11/18 03:25:59 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_z1fe22de3f0fd9b4b9fa80e1b_f1079f0df6cc80203_d20251118_m032543_c001_v0001165_t0018_u01763436343802") 2025/11/18 03:26:00 DEBUG : B2 bucket rclone-test-weqiwuj1sexu: Purge remote 2025/11/18 03:26:00 INFO : B2 bucket rclone-test-weqiwuj1sexu: cleaning bucket "rclone-test-weqiwuj1sexu" of all files 2025/11/18 03:26:00 DEBUG : 測試_Русский___ě_áñ: Deleting (id "4_zff522d03f0fd9b4b9fa80e1b_f1008e2119377338d_d20251118_m032540_c001_v0001120_t0014_u01763436340487") 2025/11/18 03:26:01 DEBUG : B2 bucket rclone-test-fudepol6hosa: Purge remote 2025/11/18 03:26:01 INFO : B2 bucket rclone-test-fudepol6hosa: cleaning bucket "rclone-test-fudepol6hosa" of all files 2025/11/18 03:26:02 DEBUG : 測試_Русский___ě_áñ: Deleting (id "4_z7f421d43f0fd9b4b9fa80e1b_f1126ec1a08983ce8_d20251118_m032537_c001_v0001180_t0014_u01763436337138") 2025/11/18 03:26:02 DEBUG : B2 bucket rclone-test-zegased8fixa: Purge remote 2025/11/18 03:26:02 INFO : B2 bucket rclone-test-zegased8fixa: cleaning bucket "rclone-test-zegased8fixa" of all files 2025/11/18 03:26:03 DEBUG : HELLO, WORLD!: Deleting (id "4_z1f320d73f0fd9b4b9fa80e1b_f111fd138f3f28c70_d20251118_m032533_c001_v0001098_t0040_u01763436333756") 2025/11/18 03:26:03 DEBUG : B2 bucket rclone-test-hovokiq7qawo: Purge remote 2025/11/18 03:26:03 INFO : B2 bucket rclone-test-hovokiq7qawo: cleaning bucket "rclone-test-hovokiq7qawo" of all files 2025/11/18 03:26:04 DEBUG : HeLlO, wOrLd!: Deleting (id "4_z2fa2fda3f0ed9b4b9fa80e1b_f119512e887d9c152_d20251118_m032530_c001_v0001184_t0019_u01763436330600") 2025/11/18 03:26:04 DEBUG : B2 bucket rclone-test-radeyoh6difa: Purge remote 2025/11/18 03:26:04 INFO : B2 bucket rclone-test-radeyoh6difa: cleaning bucket "rclone-test-radeyoh6difa" of all files 2025/11/18 03:26:05 DEBUG : HELLO, WORLD!: Deleting (id "4_zcf82edc3f0ed9b4b9fa80e1b_f11319d4827555dec_d20251118_m032527_c001_v0001098_t0056_u01763436327046") 2025/11/18 03:26:06 DEBUG : B2 bucket rclone-test-hagerix4zawu: Purge remote 2025/11/18 03:26:06 INFO : B2 bucket rclone-test-hagerix4zawu: cleaning bucket "rclone-test-hagerix4zawu" of all files 2025/11/18 03:26:06 DEBUG : hello, world!: Deleting (id "4_z0fa2dde3f0ed9b4b9fa80e1b_f1046ce8d5c50d271_d20251118_m032523_c001_v0001117_t0031_u01763436323331") --- PASS: TestApplyTransforms (46.06s) === RUN TestTruncateString --- PASS: TestTruncateString (0.00s) === RUN TestCopyFile run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" 2025/11/18 03:26:07 DEBUG : file1: Need to transfer - File not found at Destination 2025/11/18 03:26:08 DEBUG : sub/file2: size = 14 OK 2025/11/18 03:26:08 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2025/11/18 03:26:08 INFO : file1: Copied (new) to: sub/file2 2025/11/18 03:26:09 DEBUG : sub/file2: size = 14 OK 2025/11/18 03:26:09 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/18 03:26:09 DEBUG : file1: Unchanged skipping 2025/11/18 03:26:09 DEBUG : B2 bucket rclone-test-maranad2rige: don't need to copy/move sub/file2, it is already at target location --- PASS: TestCopyFile (2.83s) === RUN TestCopyLongFile run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", 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-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" 2025/11/18 03:26:11 DEBUG : Creating backend with remote "TestB2:rclone-test-maranad2rige/backup" 2025/11/18 03:26:12 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone4675142) 2025/11/18 03:26:12 DEBUG : dst/file1: size = 18 (B2 bucket rclone-test-maranad2rige) 2025/11/18 03:26:12 DEBUG : dst/file1: Sizes differ 2025/11/18 03:26:13 DEBUG : dst/file1: size = 18 OK 2025/11/18 03:26:13 DEBUG : dst/file1: sha1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK 2025/11/18 03:26:13 INFO : dst/file1: Copied (server-side copy) 2025/11/18 03:26:13 INFO : dst/file1: Deleted 2025/11/18 03:26:14 DEBUG : dst/file1: size = 14 OK 2025/11/18 03:26:14 DEBUG : dst/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2025/11/18 03:26:14 INFO : dst/file1: Copied (new) --- PASS: TestCopyFileBackupDir (4.53s) === RUN TestCopyFileCompareDest run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" 2025/11/18 03:26:15 DEBUG : Creating backend with remote "TestB2:rclone-test-maranad2rige/dst" 2025/11/18 03:26:16 DEBUG : Creating backend with remote "TestB2:rclone-test-maranad2rige/CompareDest" 2025/11/18 03:26:17 DEBUG : one: Need to transfer - File not found at Destination 2025/11/18 03:26:19 DEBUG : one: size = 3 OK 2025/11/18 03:26:19 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2025/11/18 03:26:19 INFO : one: Copied (new) 2025/11/18 03:26:19 DEBUG : one: size = 5 (Local file system at /tmp/rclone4675142) 2025/11/18 03:26:19 DEBUG : one: size = 3 (B2 bucket rclone-test-maranad2rige path dst) 2025/11/18 03:26:19 DEBUG : one: Sizes differ 2025/11/18 03:26:20 DEBUG : one: size = 5 OK 2025/11/18 03:26:20 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2025/11/18 03:26:20 INFO : one: Copied (replaced existing) 2025/11/18 03:26:22 DEBUG : one: size = 5 (Local file system at /tmp/rclone4675142) 2025/11/18 03:26:22 DEBUG : one: size = 3 (B2 bucket rclone-test-maranad2rige path dst) 2025/11/18 03:26:22 DEBUG : one: Sizes differ 2025/11/18 03:26:22 DEBUG : one: size = 5 OK 2025/11/18 03:26:22 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/11/18 03:26:22 DEBUG : one: Destination found in --compare-dest, skipping 2025/11/18 03:26:23 DEBUG : two: Need to transfer - File not found at Destination 2025/11/18 03:26:23 DEBUG : two: size = 3 OK 2025/11/18 03:26:23 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/11/18 03:26:23 DEBUG : two: Destination found in --compare-dest, skipping 2025/11/18 03:26:23 DEBUG : two: Need to transfer - File not found at Destination 2025/11/18 03:26:23 DEBUG : two: size = 3 OK 2025/11/18 03:26:23 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/11/18 03:26:23 DEBUG : two: Destination found in --compare-dest, skipping 2025/11/18 03:26:24 DEBUG : two: Need to transfer - File not found at Destination 2025/11/18 03:26:24 DEBUG : two: size = 5 (Local file system at /tmp/rclone4675142) 2025/11/18 03:26:24 DEBUG : two: size = 3 (B2 bucket rclone-test-maranad2rige path CompareDest) 2025/11/18 03:26:24 DEBUG : two: Sizes differ 2025/11/18 03:26:25 DEBUG : two: size = 5 OK 2025/11/18 03:26:25 DEBUG : two: sha1 = a4be8877ad88f3109a6411985a1f359e10e2d930 OK 2025/11/18 03:26:25 INFO : two: Copied (new) --- PASS: TestCopyFileCompareDest (11.54s) === RUN TestCopyFileCopyDest run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" 2025/11/18 03:26:26 DEBUG : Creating backend with remote "TestB2:rclone-test-maranad2rige/dst" 2025/11/18 03:26:27 DEBUG : Creating backend with remote "TestB2:rclone-test-maranad2rige/CopyDest" 2025/11/18 03:26:29 DEBUG : one: Need to transfer - File not found at Destination 2025/11/18 03:26:31 DEBUG : one: size = 3 OK 2025/11/18 03:26:31 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2025/11/18 03:26:31 INFO : one: Copied (new) 2025/11/18 03:26:31 DEBUG : one: size = 5 (Local file system at /tmp/rclone4675142) 2025/11/18 03:26:31 DEBUG : one: size = 3 (B2 bucket rclone-test-maranad2rige path dst) 2025/11/18 03:26:31 DEBUG : one: Sizes differ 2025/11/18 03:26:32 DEBUG : one: size = 5 OK 2025/11/18 03:26:32 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2025/11/18 03:26:32 INFO : one: Copied (replaced existing) 2025/11/18 03:26:34 DEBUG : Creating backend with remote "TestB2:rclone-test-maranad2rige/BackupDir" 2025/11/18 03:26:35 DEBUG : one: size = 5 (Local file system at /tmp/rclone4675142) 2025/11/18 03:26:35 DEBUG : one: size = 3 (B2 bucket rclone-test-maranad2rige path dst) 2025/11/18 03:26:35 DEBUG : one: Sizes differ 2025/11/18 03:26:35 DEBUG : one: size = 5 OK 2025/11/18 03:26:35 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/11/18 03:26:35 DEBUG : one: size = 5 (Local file system at /tmp/rclone4675142) 2025/11/18 03:26:35 DEBUG : one: size = 3 (B2 bucket rclone-test-maranad2rige path dst) 2025/11/18 03:26:35 DEBUG : one: Sizes differ 2025/11/18 03:26:37 DEBUG : one: size = 3 OK 2025/11/18 03:26:37 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2025/11/18 03:26:37 INFO : one: Copied (server-side copy) 2025/11/18 03:26:37 INFO : one: Deleted 2025/11/18 03:26:38 DEBUG : one: size = 5 OK 2025/11/18 03:26:38 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2025/11/18 03:26:38 INFO : one: Copied (server-side copy) 2025/11/18 03:26:38 DEBUG : one: Destination found in --copy-dest, using server-side copy 2025/11/18 03:26:38 DEBUG : two: Need to transfer - File not found at Destination 2025/11/18 03:26:39 DEBUG : two: size = 3 OK 2025/11/18 03:26:39 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/11/18 03:26:39 DEBUG : two: size = 3 OK 2025/11/18 03:26:39 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2025/11/18 03:26:39 INFO : two: Copied (server-side copy) 2025/11/18 03:26:39 DEBUG : two: Destination found in --copy-dest, using server-side copy 2025/11/18 03:26:39 DEBUG : two: size = 3 OK 2025/11/18 03:26:39 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/11/18 03:26:39 DEBUG : two: Unchanged skipping 2025/11/18 03:26:40 DEBUG : three: Need to transfer - File not found at Destination 2025/11/18 03:26:41 DEBUG : three: size = 7 (Local file system at /tmp/rclone4675142) 2025/11/18 03:26:41 DEBUG : three: size = 5 (B2 bucket rclone-test-maranad2rige path CopyDest) 2025/11/18 03:26:41 DEBUG : three: Sizes differ 2025/11/18 03:26:41 DEBUG : three: Destination not found in --copy-dest 2025/11/18 03:26:42 DEBUG : three: size = 7 OK 2025/11/18 03:26:42 DEBUG : three: sha1 = 370e613335f3cf2f19b2a2d35a86402f55c659f8 OK 2025/11/18 03:26:42 INFO : three: Copied (new) --- PASS: TestCopyFileCopyDest (17.33s) === RUN TestCopyInplace run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" copy_test.go:371: Partial uploads not supported --- SKIP: TestCopyInplace (0.36s) === RUN TestCopyLongFileName run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" copy_test.go:404: Partial uploads not supported --- SKIP: TestCopyLongFileName (0.36s) === RUN TestCopyLongFileNameCollision run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" copy_test.go:437: Partial uploads not supported --- SKIP: TestCopyLongFileNameCollision (0.36s) === RUN TestCopyFileMaxTransfer run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" 2025/11/18 03:26:45 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination 2025/11/18 03:26:46 DEBUG : TestCopyFileMaxTransfer/file1: size = 14 OK 2025/11/18 03:26:46 DEBUG : TestCopyFileMaxTransfer/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2025/11/18 03:26:46 INFO : TestCopyFileMaxTransfer/file1: Copied (new) 2025/11/18 03:26:46 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination 2025/11/18 03:26:46 ERROR : TestCopyFileMaxTransfer/file2: Failed to copy: Post "https://pod-000-1120-10.backblaze.com/b2api/v1/b2_upload_file/0f925d13f0dd9b4b9fa80e1b/c001_v0001120_t0026": max transfer limit reached as set by --max-transfer 2025/11/18 03:26:47 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination 2025/11/18 03:26:47 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination 2025/11/18 03:26:48 DEBUG : TestCopyFileMaxTransfer/file4: size = 2062 OK 2025/11/18 03:26:48 DEBUG : TestCopyFileMaxTransfer/file4: sha1 = 0cc531991abef66ae2d1f713b7820c34526f41af OK 2025/11/18 03:26:48 INFO : TestCopyFileMaxTransfer/file4: Copied (new) --- PASS: TestCopyFileMaxTransfer (4.08s) === RUN TestDeduplicateInteractive run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateInteractive (0.36s) === RUN TestDeduplicateSkip run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSkip (0.36s) === RUN TestDeduplicateSizeOnly run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", 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-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateFirst (0.36s) === RUN TestDeduplicateNewest run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateNewest (0.36s) === RUN TestDeduplicateNewestByHash run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" 2025/11/18 03:26:52 INFO : B2 bucket rclone-test-maranad2rige: Looking for duplicate sha1 hashes using newest mode. 2025/11/18 03:26:52 NOTICE: 573df3b070ac1ecfb9bdd9adbba640ade62b3757: Found 3 files with duplicate sha1 hashes 2025/11/18 03:26:52 INFO : one: Deleted 2025/11/18 03:26:53 INFO : also/one: Deleted 2025/11/18 03:26:53 NOTICE: 573df3b070ac1ecfb9bdd9adbba640ade62b3757: Deleted 2 extra copies --- PASS: TestDeduplicateNewestByHash (3.11s) === RUN TestDeduplicateOldest run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateOldest (0.36s) === RUN TestDeduplicateLargest run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateLargest (0.36s) === RUN TestDeduplicateSmallest run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSmallest (0.36s) === RUN TestDeduplicateRename run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateRename (0.36s) === RUN TestMergeDirs run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" dedupe_test.go:256: Can't merge directories --- SKIP: TestMergeDirs (0.36s) === RUN TestListDirSorted run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" 2025/11/18 03:26:59 DEBUG : a.txt: Excluded (Size Filter) 2025/11/18 03:26:59 DEBUG : a.txt: Excluded 2025/11/18 03:26:59 DEBUG : sub dir/hello world: Excluded (Size Filter) 2025/11/18 03:26:59 DEBUG : sub dir/hello world: Excluded 2025/11/18 03:26:59 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2025/11/18 03:26:59 DEBUG : sub dir/hello world2: Excluded 2025/11/18 03:27:00 DEBUG : sub dir/hello world: Excluded (Size Filter) 2025/11/18 03:27:00 DEBUG : sub dir/hello world: Excluded 2025/11/18 03:27:00 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2025/11/18 03:27:00 DEBUG : sub dir/hello world2: Excluded 2025/11/18 03:27:00 DEBUG : sub dir/ignore dir: Excluded 2025/11/18 03:27:00 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSorted (7.47s) === RUN TestListDirSortedFn run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" 2025/11/18 03:27:06 DEBUG : a.txt: Excluded (Size Filter) 2025/11/18 03:27:06 DEBUG : a.txt: Excluded 2025/11/18 03:27:07 DEBUG : sub dir/hello world: Excluded (Size Filter) 2025/11/18 03:27:07 DEBUG : sub dir/hello world: Excluded 2025/11/18 03:27:07 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2025/11/18 03:27:07 DEBUG : sub dir/hello world2: Excluded 2025/11/18 03:27:07 DEBUG : sub dir/hello world: Excluded (Size Filter) 2025/11/18 03:27:07 DEBUG : sub dir/hello world: Excluded 2025/11/18 03:27:07 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2025/11/18 03:27:07 DEBUG : sub dir/hello world2: Excluded 2025/11/18 03:27:07 DEBUG : sub dir/ignore dir: Excluded 2025/11/18 03:27:08 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSortedFn (6.89s) === RUN TestListJSON run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", 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/18 03:27:12 ERROR : file1: Failed to read hash: hash type not supported === RUN TestListJSON/Metadata --- PASS: TestListJSON (3.47s) --- 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.18s) --- PASS: TestListJSON/HashTypes (0.18s) --- PASS: TestListJSON/Metadata (0.18s) === RUN TestStatJSON run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", 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/18 03:27:17 DEBUG : Creating backend with remote "TestB2:rclone-test-maranad2rige/notfound" --- PASS: TestStatJSON (6.45s) --- PASS: TestStatJSON/Root (0.18s) --- PASS: TestStatJSON/RootFilesOnly (0.00s) --- PASS: TestStatJSON/RootDirsOnly (0.18s) --- PASS: TestStatJSON/Dir (0.73s) --- PASS: TestStatJSON/DirWithTrailingSlash (0.18s) --- PASS: TestStatJSON/File (0.18s) --- PASS: TestStatJSON/NotFound (0.36s) --- PASS: TestStatJSON/DirFilesOnly (0.18s) --- PASS: TestStatJSON/FileFilesOnly (0.18s) --- PASS: TestStatJSON/NotFoundFilesOnly (0.18s) --- PASS: TestStatJSON/DirDirsOnly (0.18s) --- PASS: TestStatJSON/FileDirsOnly (0.18s) --- PASS: TestStatJSON/NotFoundDirsOnly (0.18s) --- PASS: TestStatJSON/RootNotFound (1.83s) === RUN TestMkdir run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" 2025/11/18 03:27:20 INFO : B2 bucket rclone-test-maranad2rige: Making directory 2025/11/18 03:27:20 INFO : B2 bucket rclone-test-maranad2rige: Making directory --- PASS: TestMkdir (0.54s) === RUN TestLsd run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" --- PASS: TestLsd (1.89s) === RUN TestLs run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" --- PASS: TestLs (1.87s) === RUN TestLsWithFilesFrom run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" 2025/11/18 03:27:25 DEBUG : empty space: Excluded (FilesFrom Filter) --- PASS: TestLsWithFilesFrom (2.55s) === RUN TestLsLong run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" --- PASS: TestLsLong (1.77s) === RUN TestHashSums run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" === RUN TestHashSums/Sha1 === RUN TestHashSums/Sha1Download --- PASS: TestHashSums (2.29s) --- PASS: TestHashSums/Sha1 (0.18s) --- PASS: TestHashSums/Sha1Download (0.38s) === RUN TestHashSumsWithErrors 2025/11/18 03:27:31 DEBUG : Creating backend with remote ":memory:" 2025/11/18 03:27:31 ERROR : file1: hash unsupported: hash type not supported --- PASS: TestHashSumsWithErrors (0.00s) === RUN TestHashStream 2025/11/18 03:27:31 DEBUG : Creating md5 hash of 0 bytes read from input stream 2025/11/18 03:27:31 DEBUG : Creating md5 hash of 0 bytes read from input stream 2025/11/18 03:27:31 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2025/11/18 03:27:31 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2025/11/18 03:27:31 DEBUG : Creating md5 hash of 12 bytes read from input stream 2025/11/18 03:27:31 DEBUG : Creating md5 hash of 12 bytes read from input stream 2025/11/18 03:27:31 DEBUG : Creating sha1 hash of 12 bytes read from input stream 2025/11/18 03:27:31 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-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" --- PASS: TestCount (2.13s) === RUN TestDelete run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" 2025/11/18 03:27:34 DEBUG : Waiting for deletions to finish 2025/11/18 03:27:35 DEBUG : large: Excluded (Size Filter) 2025/11/18 03:27:35 INFO : medium: Deleted 2025/11/18 03:27:35 INFO : small: Deleted --- PASS: TestDelete (3.24s) === RUN TestMaxDelete run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" 2025/11/18 03:27:37 DEBUG : Waiting for deletions to finish 2025/11/18 03:27:37 ERROR : small: Got fatal error on delete: --max-delete threshold reached 2025/11/18 03:27:38 INFO : large: Deleted 2025/11/18 03:27:38 INFO : medium: Deleted --- PASS: TestMaxDelete (3.03s) === RUN TestMaxDeleteSizeLargeFile run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" 2025/11/18 03:27:40 DEBUG : Waiting for deletions to finish 2025/11/18 03:27:40 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached 2025/11/18 03:27:40 INFO : medium: Deleted 2025/11/18 03:27:40 INFO : small: Deleted --- PASS: TestMaxDeleteSizeLargeFile (2.63s) === RUN TestMaxDeleteSize run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" 2025/11/18 03:27:43 DEBUG : Waiting for deletions to finish 2025/11/18 03:27:43 ERROR : small: Got fatal error on delete: --max-delete-size threshold reached 2025/11/18 03:27:43 INFO : medium: Deleted 2025/11/18 03:27:43 INFO : large: Deleted --- PASS: TestMaxDeleteSize (2.83s) === RUN TestReadFile run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" --- PASS: TestReadFile (2.30s) === RUN TestRetry 2025/11/18 03:27:47 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 1/5 2025/11/18 03:27:47 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 2/5 2025/11/18 03:27:47 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2025/11/18 03:27:47 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2025/11/18 03:27:47 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2025/11/18 03:27:47 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2025/11/18 03:27:47 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms --- PASS: TestRetry (0.05s) === RUN TestCat run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" --- PASS: TestCat (4.40s) === RUN TestPurge 2025/11/18 03:27:51 DEBUG : Creating backend with remote "TestB2:rclone-test-wisecub9nilo" 2025/11/18 03:27:51 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/18 03:27:52 DEBUG : Creating backend with remote "/tmp/rclone2442596784" run.go:185: Remote "B2 bucket rclone-test-wisecub9nilo", Local "Local file system at /tmp/rclone2442596784", Modify Window "1ms" 2025/11/18 03:27:54 INFO : A2: Making directory 2025/11/18 03:27:54 INFO : A1/B2: Making directory 2025/11/18 03:27:54 INFO : A1/B2/C2: Making directory 2025/11/18 03:27:54 INFO : A1/B1/C3: Making directory 2025/11/18 03:27:54 INFO : A3: Making directory 2025/11/18 03:27:54 INFO : A3/B3: Making directory 2025/11/18 03:27:54 INFO : A3/B3/C4: Making directory fstest.go:250: Filtering empty directory "A2" fstest.go:250: Filtering empty directory "A1/B2" fstest.go:250: Filtering empty directory "A1/B2/C2" fstest.go:250: Filtering empty directory "A1/B1/C3" fstest.go:250: Filtering empty directory "A3" fstest.go:250: Filtering empty directory "A3/B3" fstest.go:250: Filtering empty directory "A3/B3/C4" 2025/11/18 03:27:54 INFO : B2 bucket rclone-test-wisecub9nilo: cleaning bucket "rclone-test-wisecub9nilo" of all files 2025/11/18 03:27:55 DEBUG : A1/B1/C1/one: Deleting (id "4_z5f922d93001d9b5b9fa80e1b_f1160726910aec804_d20251118_m032753_c001_v0001104_t0010_u01763436473638") 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/18 03:27:55 INFO : B2 bucket rclone-test-wisecub9nilo: cleaning bucket "rclone-test-wisecub9nilo" of all files 2025/11/18 03:27:55 DEBUG : A1/two: Deleting (id "4_z5f922d93001d9b5b9fa80e1b_f1160726910aec808_d20251118_m032754_c001_v0001104_t0022_u01763436474231") 2025/11/18 03:27:56 DEBUG : B2 bucket rclone-test-wisecub9nilo: Purge remote 2025/11/18 03:27:56 INFO : B2 bucket rclone-test-wisecub9nilo: cleaning bucket "rclone-test-wisecub9nilo" of all files 2025/11/18 03:27:56 NOTICE: purge failed: directory not found --- PASS: TestPurge (5.12s) === RUN TestRmdirsNoLeaveRoot run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" 2025/11/18 03:27:57 INFO : A2: Making directory 2025/11/18 03:27:57 INFO : A1/B2: Making directory 2025/11/18 03:27:57 INFO : A1/B2/C2: Making directory 2025/11/18 03:27:57 INFO : A1/B1/C3: Making directory 2025/11/18 03:27:57 INFO : A3: Making directory 2025/11/18 03:27:57 INFO : A3/B3: Making directory 2025/11/18 03:27:57 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/18 03:27:58 DEBUG : removing 1 level 3 directories 2025/11/18 03:27:58 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/18 03:28:00 DEBUG : removing 1 level 0 directories 2025/11/18 03:28:00 INFO : B2 bucket rclone-test-maranad2rige: Removing directory 2025/11/18 03:28:00 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.29s) === RUN TestRmdirsLeaveRoot run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" 2025/11/18 03:28:01 INFO : A1: Making directory 2025/11/18 03:28:01 INFO : A1/B1: Making directory 2025/11/18 03:28:01 INFO : A1/B1/C1: Making directory fstest.go:250: Filtering empty directory "A1" fstest.go:250: Filtering empty directory "A1/B1" fstest.go:250: Filtering empty directory "A1/B1/C1" fstest.go:250: Filtering empty directory "A1" --- PASS: TestRmdirsLeaveRoot (0.93s) === RUN TestRmdirsWithFilter run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" 2025/11/18 03:28:01 INFO : A1: Making directory 2025/11/18 03:28:01 INFO : A1/B1: Making directory 2025/11/18 03:28:01 INFO : A1/B1/C1: Making directory fstest.go:250: Filtering empty directory "A1" fstest.go:250: Filtering empty directory "A1/B1" fstest.go:250: Filtering empty directory "A1/B1/C1" fstest.go:250: Filtering empty directory "A1" --- PASS: TestRmdirsWithFilter (0.94s) === RUN TestCopyURL run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" 2025/11/18 03:28:03 DEBUG : filename.txt: File name found in url 2025/11/18 03:28:04 DEBUG : headerfilename.txt: filename found in Content-Disposition header. --- PASS: TestCopyURL (3.49s) === RUN TestCopyURLToWriter --- PASS: TestCopyURLToWriter (0.00s) === RUN TestMoveFile run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" 2025/11/18 03:28:06 DEBUG : file1: Need to transfer - File not found at Destination 2025/11/18 03:28:07 DEBUG : sub/file2: size = 14 OK 2025/11/18 03:28:07 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2025/11/18 03:28:07 INFO : file1: Copied (new) to: sub/file2 2025/11/18 03:28:07 INFO : file1: Deleted 2025/11/18 03:28:07 DEBUG : sub/file2: size = 14 OK 2025/11/18 03:28:07 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/18 03:28:07 DEBUG : file1: Unchanged skipping 2025/11/18 03:28:07 INFO : file1: Deleted 2025/11/18 03:28:07 DEBUG : B2 bucket rclone-test-maranad2rige: don't need to copy/move sub/file2, it is already at target location --- PASS: TestMoveFile (1.95s) === RUN TestMoveFileWithIgnoreExisting run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" 2025/11/18 03:28:08 DEBUG : file1: Need to transfer - File not found at Destination 2025/11/18 03:28:09 DEBUG : file1: size = 14 OK 2025/11/18 03:28:09 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2025/11/18 03:28:09 INFO : file1: Copied (new) 2025/11/18 03:28:09 INFO : file1: Deleted 2025/11/18 03:28:09 DEBUG : file1: Destination exists, skipping 2025/11/18 03:28:09 DEBUG : file1: Not removing source file as destination file exists and --ignore-existing is set --- PASS: TestMoveFileWithIgnoreExisting (1.80s) === RUN TestCaseInsensitiveMoveFile run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFile (0.37s) === RUN TestCaseInsensitiveMoveFileDryRun run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFileDryRun (0.37s) === RUN TestMoveFileBackupDir run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" 2025/11/18 03:28:11 DEBUG : Creating backend with remote "TestB2:rclone-test-maranad2rige/backup" 2025/11/18 03:28:12 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone4675142) 2025/11/18 03:28:12 DEBUG : dst/file1: size = 18 (B2 bucket rclone-test-maranad2rige) 2025/11/18 03:28:12 DEBUG : dst/file1: Sizes differ 2025/11/18 03:28:14 DEBUG : dst/file1: size = 18 OK 2025/11/18 03:28:14 DEBUG : dst/file1: sha1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK 2025/11/18 03:28:14 INFO : dst/file1: Copied (server-side copy) 2025/11/18 03:28:14 INFO : dst/file1: Deleted 2025/11/18 03:28:14 DEBUG : dst/file1: size = 14 OK 2025/11/18 03:28:14 DEBUG : dst/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2025/11/18 03:28:14 INFO : dst/file1: Copied (new) 2025/11/18 03:28:14 INFO : dst/file1: Deleted --- PASS: TestMoveFileBackupDir (4.72s) === 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-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" 2025/11/18 03:28:17 INFO : A1/B2: Making directory 2025/11/18 03:28:17 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/18 03:28:19 DEBUG : A2/two: size = 3 OK 2025/11/18 03:28:19 DEBUG : A1/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2025/11/18 03:28:19 INFO : A1/two: Copied (server-side copy) to: A2/two 2025/11/18 03:28:19 DEBUG : A2/B1/C1/four: size = 4 OK 2025/11/18 03:28:19 DEBUG : A1/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2025/11/18 03:28:19 INFO : A1/B1/C1/four: Copied (server-side copy) to: A2/B1/C1/four 2025/11/18 03:28:19 INFO : A1/two: Deleted 2025/11/18 03:28:19 DEBUG : A2/B1/C2/five: size = 4 OK 2025/11/18 03:28:19 DEBUG : A1/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2025/11/18 03:28:19 INFO : A1/B1/C2/five: Copied (server-side copy) to: A2/B1/C2/five 2025/11/18 03:28:19 INFO : A1/B1/C1/four: Deleted 2025/11/18 03:28:19 INFO : A1/B1/C2/five: Deleted 2025/11/18 03:28:19 DEBUG : A2/B1/three: size = 5 OK 2025/11/18 03:28:19 DEBUG : A1/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2025/11/18 03:28:19 INFO : A1/B1/three: Copied (server-side copy) to: A2/B1/three 2025/11/18 03:28:19 DEBUG : A2/one: size = 3 OK 2025/11/18 03:28:19 DEBUG : A1/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2025/11/18 03:28:19 INFO : A1/one: Copied (server-side copy) to: A2/one 2025/11/18 03:28:19 INFO : A1/B1/three: Deleted 2025/11/18 03:28:19 INFO : A1/one: Deleted fstest.go:250: Filtering empty directory "A2/B2" fstest.go:250: Filtering empty directory "A2/B1/C3" 2025/11/18 03:28:20 DEBUG : A3/B1/C1/four: size = 4 OK 2025/11/18 03:28:20 DEBUG : A2/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2025/11/18 03:28:20 INFO : A2/B1/C1/four: Copied (server-side copy) to: A3/B1/C1/four 2025/11/18 03:28:20 DEBUG : A3/two: size = 3 OK 2025/11/18 03:28:20 DEBUG : A2/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2025/11/18 03:28:20 INFO : A2/two: Copied (server-side copy) to: A3/two 2025/11/18 03:28:20 DEBUG : A3/one: size = 3 OK 2025/11/18 03:28:20 DEBUG : A2/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2025/11/18 03:28:20 INFO : A2/one: Copied (server-side copy) to: A3/one 2025/11/18 03:28:20 DEBUG : A3/B1/C2/five: size = 4 OK 2025/11/18 03:28:20 DEBUG : A2/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2025/11/18 03:28:20 INFO : A2/B1/C2/five: Copied (server-side copy) to: A3/B1/C2/five 2025/11/18 03:28:20 INFO : A2/B1/C1/four: Deleted 2025/11/18 03:28:20 DEBUG : A3/B1/three: size = 5 OK 2025/11/18 03:28:20 DEBUG : A2/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2025/11/18 03:28:20 INFO : A2/B1/three: Copied (server-side copy) to: A3/B1/three 2025/11/18 03:28:20 INFO : A2/one: Deleted 2025/11/18 03:28:20 INFO : A2/two: Deleted 2025/11/18 03:28:20 INFO : A2/B1/C2/five: Deleted 2025/11/18 03:28:21 INFO : A2/B1/three: Deleted fstest.go:250: Filtering empty directory "A3/B2" fstest.go:250: Filtering empty directory "A3/B1/C3" 2025/11/18 03:28:21 INFO : B2 bucket rclone-test-maranad2rige: Can't DirMove - falling back to file moves: can't move directory - incompatible remotes 2025/11/18 03:28:21 DEBUG : A4/one: size = 3 OK 2025/11/18 03:28:21 DEBUG : A3/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2025/11/18 03:28:21 INFO : A3/one: Copied (server-side copy) to: A4/one 2025/11/18 03:28:22 INFO : A3/one: Deleted 2025/11/18 03:28:22 DEBUG : A4/B1/three: size = 5 OK 2025/11/18 03:28:22 DEBUG : A3/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2025/11/18 03:28:22 INFO : A3/B1/three: Copied (server-side copy) to: A4/B1/three 2025/11/18 03:28:22 DEBUG : A4/two: size = 3 OK 2025/11/18 03:28:22 DEBUG : A3/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2025/11/18 03:28:22 INFO : A3/two: Copied (server-side copy) to: A4/two 2025/11/18 03:28:22 DEBUG : A4/B1/C1/four: size = 4 OK 2025/11/18 03:28:22 DEBUG : A3/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2025/11/18 03:28:22 INFO : A3/B1/C1/four: Copied (server-side copy) to: A4/B1/C1/four 2025/11/18 03:28:22 INFO : A3/B1/three: Deleted 2025/11/18 03:28:22 INFO : A3/two: Deleted 2025/11/18 03:28:22 INFO : A3/B1/C1/four: Deleted 2025/11/18 03:28:22 DEBUG : A4/B1/C2/five: size = 4 OK 2025/11/18 03:28:22 DEBUG : A3/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2025/11/18 03:28:22 INFO : A3/B1/C2/five: Copied (server-side copy) to: A4/B1/C2/five 2025/11/18 03:28:23 INFO : A3/B1/C2/five: Deleted fstest.go:250: Filtering empty directory "A4/B2" fstest.go:250: Filtering empty directory "A4/B1/C3" --- PASS: TestDirMove (9.08s) === RUN TestGetFsInfo run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" --- PASS: TestGetFsInfo (0.38s) === RUN TestRcat === RUN TestRcat/withChecksum=false,ignoreChecksum=false run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" 2025/11/18 03:28:25 DEBUG : B2 bucket rclone-test-maranad2rige: File to upload is small (34 bytes), uploading instead of streaming 2025/11/18 03:28:26 DEBUG : no_checksum_small_file_from_pipe: size = 34 OK 2025/11/18 03:28:26 DEBUG : no_checksum_small_file_from_pipe: sha1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK 2025/11/18 03:28:26 DEBUG : no_checksum_small_file_from_pipe: Size and sha1 of src and dst objects identical 2025/11/18 03:28:26 DEBUG : no_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2025/11/18 03:28:27 DEBUG : no_checksum_big_file_from_pipe: size = 102401 OK 2025/11/18 03:28:27 DEBUG : no_checksum_big_file_from_pipe: sha1 = d0080f73aa8b494570ad63df1beb25c01ea9eea2 OK 2025/11/18 03:28:27 DEBUG : no_checksum_big_file_from_pipe: Size and sha1 of src and dst objects identical === RUN TestRcat/withChecksum=true,ignoreChecksum=false run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" 2025/11/18 03:28:28 DEBUG : B2 bucket rclone-test-maranad2rige: File to upload is small (34 bytes), uploading instead of streaming 2025/11/18 03:28:28 DEBUG : with_checksum_small_file_from_pipe: size = 34 OK 2025/11/18 03:28:28 DEBUG : with_checksum_small_file_from_pipe: sha1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK 2025/11/18 03:28:28 DEBUG : with_checksum_small_file_from_pipe: Size and sha1 of src and dst objects identical 2025/11/18 03:28:28 DEBUG : with_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2025/11/18 03:28:29 DEBUG : with_checksum_big_file_from_pipe: size = 102401 OK 2025/11/18 03:28:29 DEBUG : with_checksum_big_file_from_pipe: sha1 = d0080f73aa8b494570ad63df1beb25c01ea9eea2 OK 2025/11/18 03:28:29 DEBUG : with_checksum_big_file_from_pipe: Size and sha1 of src and dst objects identical === RUN TestRcat/withChecksum=false,ignoreChecksum=true run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" 2025/11/18 03:28:30 DEBUG : B2 bucket rclone-test-maranad2rige: File to upload is small (34 bytes), uploading instead of streaming 2025/11/18 03:28:31 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK 2025/11/18 03:28:31 DEBUG : ignore_checksum_small_file_from_pipe: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/18 03:28:31 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2025/11/18 03:28:32 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK 2025/11/18 03:28:32 DEBUG : ignore_checksum_big_file_from_pipe: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) === RUN TestRcat/withChecksum=true,ignoreChecksum=true run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" 2025/11/18 03:28:33 DEBUG : B2 bucket rclone-test-maranad2rige: File to upload is small (34 bytes), uploading instead of streaming 2025/11/18 03:28:33 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK 2025/11/18 03:28:33 DEBUG : ignore_checksum_small_file_from_pipe: Src hash empty - aborting Dst hash check 2025/11/18 03:28:33 DEBUG : ignore_checksum_small_file_from_pipe: Size of src and dst objects identical 2025/11/18 03:28:33 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2025/11/18 03:28:34 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK 2025/11/18 03:28:34 DEBUG : ignore_checksum_big_file_from_pipe: Src hash empty - aborting Dst hash check 2025/11/18 03:28:34 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical --- PASS: TestRcat (10.32s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (3.14s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (2.47s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (2.37s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (2.34s) === RUN TestRcatMetadata run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" operations_test.go:1540: Skipping as destination doesn't support user metadata --- SKIP: TestRcatMetadata (0.38s) === RUN TestRcatSize run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" 2025/11/18 03:28:36 DEBUG : B2 bucket rclone-test-maranad2rige: File to upload is small (60 bytes), uploading instead of streaming 2025/11/18 03:28:36 DEBUG : potato2: size = 60 OK 2025/11/18 03:28:36 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2025/11/18 03:28:36 DEBUG : potato2: Size and sha1 of src and dst objects identical --- PASS: TestRcatSize (1.60s) === RUN TestRcatSizeMetadata run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" operations_test.go:1621: Skipping as destination doesn't support user metadata --- SKIP: TestRcatSizeMetadata (0.38s) === RUN TestTouchDir run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" 2025/11/18 03:28:38 DEBUG : B2 bucket rclone-test-maranad2rige: Touching "sub dir/potato3" 2025/11/18 03:28:38 DEBUG : B2 bucket rclone-test-maranad2rige: Touching "empty space" 2025/11/18 03:28:38 DEBUG : B2 bucket rclone-test-maranad2rige: Touching "potato2" --- PASS: TestTouchDir (3.91s) === RUN TestMkdirMetadata run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" operations_test.go:1711: Skipping test as remote does not support MkdirMetadata --- SKIP: TestMkdirMetadata (0.37s) === RUN TestMkdirModTime run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", 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-maranad2rige", Local "Local file system at /tmp/rclone4675142", 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-maranad2rige", Local "Local file system at /tmp/rclone4675142", Modify Window "1ms" operations_test.go:1787: Skipping test as remote does not support DirSetModTime or WriteDirSetModTime --- SKIP: TestSetDirModTime (0.38s) === RUN TestDirsEqual run.go:185: Remote "B2 bucket rclone-test-maranad2rige", Local "Local file system at /tmp/rclone4675142", 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-maranad2rige", Local "Local file system at /tmp/rclone4675142", 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/18 03:28:43 DEBUG : B2 bucket rclone-test-maranad2rige: Purge remote 2025/11/18 03:28:43 INFO : B2 bucket rclone-test-maranad2rige: cleaning bucket "rclone-test-maranad2rige" of all files 2025/11/18 03:28:44 DEBUG : A1/B1/C1/four: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f41523415b862d864_d20251118_m032819_c001_v7007000_t0000_u01763436499173") 2025/11/18 03:28:44 DEBUG : A1/B1/C1/four: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b967_d20251118_m032816_c001_v0001120_t0058_u01763436496866") 2025/11/18 03:28:44 DEBUG : A1/B1/C1/one: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f41523415b862d83f_d20251118_m032759_c001_v7007000_t0000_u01763436479809") 2025/11/18 03:28:44 DEBUG : A1/B1/C1/one: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b8e3_d20251118_m032757_c001_v0001120_t0004_u01763436477178") 2025/11/18 03:28:44 DEBUG : A1/B1/C2/five: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f41096641a447bbb3_d20251118_m032819_c001_v7007000_t0000_u01763436499351") 2025/11/18 03:28:44 DEBUG : A1/B1/C2/five: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b96b_d20251118_m032817_c001_v0001120_t0020_u01763436497159") 2025/11/18 03:28:44 DEBUG : A1/B1/three: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f4076efd2d5931e60_d20251118_m032819_c001_v7007000_t0000_u01763436499595") 2025/11/18 03:28:44 DEBUG : A1/B1/three: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b965_d20251118_m032816_c001_v0001120_t0003_u01763436496572") 2025/11/18 03:28:44 DEBUG : A1/one: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f423d2aea607fe239_d20251118_m032819_c001_v7007000_t0000_u01763436499727") 2025/11/18 03:28:44 DEBUG : A1/one: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b959_d20251118_m032815_c001_v0001120_t0026_u01763436495674") 2025/11/18 03:28:44 DEBUG : A1/two: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f421c9953e4b8b5ee_d20251118_m032819_c001_v7007000_t0000_u01763436499153") 2025/11/18 03:28:44 DEBUG : A1/two: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b95f_d20251118_m032816_c001_v0001120_t0009_u01763436496144") 2025/11/18 03:28:44 DEBUG : A1/two: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f41523415b862d840_d20251118_m032800_c001_v7007000_t0000_u01763436480170") 2025/11/18 03:28:45 DEBUG : A1/two: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b8e7_d20251118_m032757_c001_v0001120_t0015_u01763436477587") 2025/11/18 03:28:45 DEBUG : A2/B1/C1/four: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f41523415b862d865_d20251118_m032820_c001_v7007000_t0000_u01763436500725") 2025/11/18 03:28:45 DEBUG : A2/B1/C1/four: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f118df27936da7a96_d20251118_m032818_c001_v0001037_t0043_u01763436498557") 2025/11/18 03:28:45 DEBUG : A2/B1/C2/five: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f423d2aea607fe23c_d20251118_m032820_c001_v7007000_t0000_u01763436500832") 2025/11/18 03:28:45 DEBUG : A2/B1/C2/five: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1077477efb020cd2_d20251118_m032818_c001_v0001145_t0031_u01763436498921") 2025/11/18 03:28:45 DEBUG : A2/B1/three: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f4076efd2d5931e64_d20251118_m032820_c001_v7007000_t0000_u01763436500968") 2025/11/18 03:28:45 DEBUG : A2/B1/three: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1160fada4640e1b8_d20251118_m032818_c001_v0001036_t0034_u01763436498905") 2025/11/18 03:28:45 DEBUG : A2/one: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f421c9953e4b8b5f0_d20251118_m032820_c001_v7007000_t0000_u01763436500820") 2025/11/18 03:28:45 DEBUG : A2/one: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f105536b9654081a9_d20251118_m032818_c001_v0001104_t0048_u01763436498900") 2025/11/18 03:28:45 DEBUG : A2/two: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f41096641a447bbb6_d20251118_m032820_c001_v7007000_t0000_u01763436500812") 2025/11/18 03:28:45 DEBUG : A2/two: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f100a758942a7f112_d20251118_m032818_c001_v0001177_t0051_u01763436498924") 2025/11/18 03:28:45 DEBUG : A3/B1/C1/four: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f41523415b862d868_d20251118_m032822_c001_v7007000_t0000_u01763436502359") 2025/11/18 03:28:45 DEBUG : A3/B1/C1/four: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f101bd3656f1f7046_d20251118_m032820_c001_v0001184_t0024_u01763436500532") 2025/11/18 03:28:45 DEBUG : A3/B1/C2/five: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f4076efd2d5931e67_d20251118_m032822_c001_v7007000_t0000_u01763436502988") 2025/11/18 03:28:45 DEBUG : A3/B1/C2/five: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1005de080b5fdfe4_d20251118_m032820_c001_v0001090_t0057_u01763436500496") 2025/11/18 03:28:45 DEBUG : A3/B1/three: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f423d2aea607fe23d_d20251118_m032822_c001_v7007000_t0000_u01763436502236") 2025/11/18 03:28:45 DEBUG : A3/B1/three: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1067c214cd44b111_d20251118_m032820_c001_v0001145_t0039_u01763436500510") 2025/11/18 03:28:45 DEBUG : A3/one: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f41096641a447bbb7_d20251118_m032821_c001_v7007000_t0000_u01763436501978") 2025/11/18 03:28:45 DEBUG : A3/one: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1192d2bf2c4fc21f_d20251118_m032820_c001_v0001182_t0048_u01763436500545") 2025/11/18 03:28:45 DEBUG : A3/two: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f421c9953e4b8b5f1_d20251118_m032822_c001_v7007000_t0000_u01763436502322") 2025/11/18 03:28:46 DEBUG : A3/two: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1074b04aeac0f4a0_d20251118_m032820_c001_v0001102_t0048_u01763436500520") 2025/11/18 03:28:46 DEBUG : A4/B1/C1/four: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f4076efd2d5931e69_d20251118_m032823_c001_v7007000_t0000_u01763436503561") 2025/11/18 03:28:46 DEBUG : A4/B1/C1/four: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f115201b31a7c7794_d20251118_m032821_c001_v0001042_t0051_u01763436501783") 2025/11/18 03:28:46 DEBUG : A4/B1/C2/five: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f4076efd2d5931e6a_d20251118_m032823_c001_v7007000_t0000_u01763436503806") 2025/11/18 03:28:46 DEBUG : A4/B1/C2/five: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f112bf6e1bff04165_d20251118_m032822_c001_v0001182_t0018_u01763436502780") 2025/11/18 03:28:46 DEBUG : A4/B1/three: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f4076efd2d5931e6b_d20251118_m032823_c001_v7007000_t0000_u01763436503991") 2025/11/18 03:28:46 DEBUG : A4/B1/three: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f104adb077633a79c_d20251118_m032821_c001_v0001090_t0040_u01763436501747") 2025/11/18 03:28:46 DEBUG : A4/one: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f4076efd2d5931e6c_d20251118_m032824_c001_v7007000_t0000_u01763436504193") 2025/11/18 03:28:46 DEBUG : A4/one: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f114b75af2bbc327b_d20251118_m032821_c001_v0001182_t0024_u01763436501765") 2025/11/18 03:28:46 DEBUG : A4/two: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f4076efd2d5931e6e_d20251118_m032824_c001_v7007000_t0000_u01763436504380") 2025/11/18 03:28:46 DEBUG : A4/two: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f103921f27b5f6af2_d20251118_m032821_c001_v0001131_t0020_u01763436501785") 2025/11/18 03:28:46 DEBUG : BackupDir/one: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f417e7f7511729431_d20251118_m032642_c001_v7007000_t0000_u01763436402546") 2025/11/18 03:28:46 DEBUG : BackupDir/one: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f112af6e4c19b56e5_d20251118_m032636_c001_v0001145_t0052_u01763436396731") 2025/11/18 03:28:46 DEBUG : CompareDest/one: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f417e7f7511729419_d20251118_m032625_c001_v7007000_t0000_u01763436385740") 2025/11/18 03:28:46 DEBUG : CompareDest/one: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b6a3_d20251118_m032621_c001_v0001120_t0057_u01763436381384") 2025/11/18 03:28:46 DEBUG : CompareDest/two: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f417e7f751172941b_d20251118_m032625_c001_v7007000_t0000_u01763436385925") 2025/11/18 03:28:46 DEBUG : CompareDest/two: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b6a7_d20251118_m032622_c001_v0001120_t0054_u01763436382470") 2025/11/18 03:28:46 DEBUG : CopyDest/one: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f417e7f7511729432_d20251118_m032642_c001_v7007000_t0000_u01763436402727") 2025/11/18 03:28:46 DEBUG : CopyDest/one: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b6df_d20251118_m032633_c001_v0001120_t0033_u01763436393581") 2025/11/18 03:28:46 DEBUG : CopyDest/three: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f417e7f7511729433_d20251118_m032642_c001_v7007000_t0000_u01763436402909") 2025/11/18 03:28:46 DEBUG : CopyDest/three: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b6f5_d20251118_m032640_c001_v0001120_t0017_u01763436400117") 2025/11/18 03:28:46 DEBUG : CopyDest/two: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f417e7f7511729434_d20251118_m032643_c001_v7007000_t0000_u01763436403090") 2025/11/18 03:28:47 DEBUG : CopyDest/two: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b6ed_d20251118_m032638_c001_v0001120_t0015_u01763436398304") 2025/11/18 03:28:47 DEBUG : ReadFile: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f421c9953e4b8b5c2_d20251118_m032746_c001_v7007000_t0000_u01763436466487") 2025/11/18 03:28:47 DEBUG : ReadFile: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b8ad_d20251118_m032744_c001_v0001120_t0053_u01763436464955") 2025/11/18 03:28:47 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f417e7f7511729439_d20251118_m032648_c001_v7007000_t0000_u01763436408623") 2025/11/18 03:28:47 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b715_d20251118_m032646_c001_v0001120_t0053_u01763436406015") 2025/11/18 03:28:47 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f417e7f751172943a_d20251118_m032648_c001_v7007000_t0000_u01763436408805") 2025/11/18 03:28:47 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b723_d20251118_m032647_c001_v0001120_t0054_u01763436407942") 2025/11/18 03:28:47 DEBUG : a.txt: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f417e7f751172944c_d20251118_m032708_c001_v7007000_t0000_u01763436428789") 2025/11/18 03:28:47 DEBUG : a.txt: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b783_d20251118_m032703_c001_v0001120_t0034_u01763436423380") 2025/11/18 03:28:47 DEBUG : a.txt: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f417e7f7511729443_d20251118_m032701_c001_v7007000_t0000_u01763436421928") 2025/11/18 03:28:47 DEBUG : a.txt: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b74d_d20251118_m032655_c001_v0001120_t0059_u01763436415913") 2025/11/18 03:28:47 DEBUG : also/one: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f417e7f751172943d_d20251118_m032653_c001_v7007000_t0000_u01763436413014") 2025/11/18 03:28:47 DEBUG : also/one: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b733_d20251118_m032651_c001_v0001120_t0050_u01763436411248") 2025/11/18 03:28:47 DEBUG : another: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f417e7f751172943e_d20251118_m032653_c001_v7007000_t0000_u01763436413558") 2025/11/18 03:28:47 DEBUG : another: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b739_d20251118_m032651_c001_v0001120_t0022_u01763436411615") 2025/11/18 03:28:47 DEBUG : backup/dst/file1: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f41523415b862d85d_d20251118_m032815_c001_v7007000_t0000_u01763436495127") 2025/11/18 03:28:47 DEBUG : backup/dst/file1: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1185f1d6b540188b_d20251118_m032813_c001_v0001036_t0050_u01763436493801") 2025/11/18 03:28:47 DEBUG : backup/dst/file1: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f417e7f751172940d_d20251118_m032614_c001_v7007000_t0000_u01763436374578") 2025/11/18 03:28:47 DEBUG : backup/dst/file1: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f11196d546e4adfd2_d20251118_m032613_c001_v0001180_t0037_u01763436373358") 2025/11/18 03:28:47 DEBUG : data/banana: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f4196b5bd76f94ccc_d20251118_m032520_c001_v7007000_t0000_u01763436320627") 2025/11/18 03:28:47 DEBUG : data/banana: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b566_d20251118_m032518_c001_v0001120_t0047_u01763436318410") 2025/11/18 03:28:48 DEBUG : data/banana: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b52d_d20251118_m032508_c001_v0001120_t0006_u01763436308947") 2025/11/18 03:28:48 DEBUG : data/potato: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f4196b5bd76f94ccd_d20251118_m032520_c001_v7007000_t0000_u01763436320808") 2025/11/18 03:28:48 DEBUG : data/potato: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b568_d20251118_m032518_c001_v0001120_t0021_u01763436318745") 2025/11/18 03:28:48 DEBUG : data/potato: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b539_d20251118_m032511_c001_v0001120_t0036_u01763436311227") 2025/11/18 03:28:48 DEBUG : dst/file1: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f41523415b862d85e_d20251118_m032815_c001_v7007000_t0000_u01763436495308") 2025/11/18 03:28:48 DEBUG : dst/file1: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b951_d20251118_m032814_c001_v0001120_t0040_u01763436494328") 2025/11/18 03:28:48 DEBUG : dst/file1: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f41523415b862d859_d20251118_m032814_c001_v7007000_t0000_u01763436494139") 2025/11/18 03:28:48 DEBUG : dst/file1: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b939_d20251118_m032810_c001_v0001120_t0010_u01763436490955") 2025/11/18 03:28:48 DEBUG : dst/file1: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f417e7f751172940e_d20251118_m032614_c001_v7007000_t0000_u01763436374760") 2025/11/18 03:28:48 DEBUG : dst/file1: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b683_d20251118_m032613_c001_v0001120_t0024_u01763436373797") 2025/11/18 03:28:48 DEBUG : dst/file1: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f417e7f751172940a_d20251118_m032613_c001_v7007000_t0000_u01763436373614") 2025/11/18 03:28:48 DEBUG : dst/file1: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b66d_d20251118_m032610_c001_v0001120_t0053_u01763436370612") 2025/11/18 03:28:48 DEBUG : dst/one: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f417e7f7511729435_d20251118_m032643_c001_v7007000_t0000_u01763436403272") 2025/11/18 03:28:48 DEBUG : dst/one: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f111fbf01dc3fa4d3_d20251118_m032637_c001_v0001032_t0006_u01763436397666") 2025/11/18 03:28:48 DEBUG : dst/one: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f4187174175667612_d20251118_m032637_c001_v7007000_t0000_u01763436397462") 2025/11/18 03:28:48 DEBUG : dst/one: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b6d9_d20251118_m032633_c001_v0001120_t0023_u01763436393184") 2025/11/18 03:28:48 DEBUG : dst/one: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f110558b5399cee68_d20251118_m032631_c001_v0001106_t0058_u01763436391906") 2025/11/18 03:28:48 DEBUG : dst/one: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f110558b5399cee64_d20251118_m032630_c001_v0001106_t0048_u01763436390623") 2025/11/18 03:28:48 DEBUG : dst/one: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f417e7f751172941c_d20251118_m032626_c001_v7007000_t0000_u01763436386106") 2025/11/18 03:28:48 DEBUG : dst/one: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b69f_d20251118_m032621_c001_v0001120_t0006_u01763436381033") 2025/11/18 03:28:48 DEBUG : dst/one: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f110d17812fb232bd_d20251118_m032620_c001_v0001171_t0057_u01763436380209") 2025/11/18 03:28:48 DEBUG : dst/one: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f110d17812fb232b9_d20251118_m032619_c001_v0001171_t0038_u01763436379130") 2025/11/18 03:28:49 DEBUG : dst/three: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f417e7f7511729436_d20251118_m032643_c001_v7007000_t0000_u01763436403454") 2025/11/18 03:28:49 DEBUG : dst/three: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f110558b5399cee90_d20251118_m032641_c001_v0001106_t0019_u01763436401485") 2025/11/18 03:28:49 DEBUG : dst/two: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f417e7f7511729437_d20251118_m032643_c001_v7007000_t0000_u01763436403635") 2025/11/18 03:28:49 DEBUG : dst/two: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f117ad6f54cb18b1e_d20251118_m032639_c001_v0001172_t0014_u01763436399258") 2025/11/18 03:28:49 DEBUG : dst/two: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f417e7f751172941d_d20251118_m032626_c001_v7007000_t0000_u01763436386309") 2025/11/18 03:28:49 DEBUG : dst/two: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f110d17812fb232c7_d20251118_m032624_c001_v0001171_t0055_u01763436384652") 2025/11/18 03:28:49 DEBUG : empty space: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f4196b5bd76f94e12_d20251118_m032840_c001_v7007000_t0000_u01763436520981") 2025/11/18 03:28:49 DEBUG : empty space: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f119c54b79ec69e5b_d20251118_m032840_c001_v0001106_t0015_u01763436520054") 2025/11/18 03:28:49 DEBUG : empty space: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b9dd_d20251118_m032838_c001_v0001120_t0011_u01763436518096") 2025/11/18 03:28:49 DEBUG : empty space: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f417e7f751172946a_d20251118_m032732_c001_v7007000_t0000_u01763436452485") 2025/11/18 03:28:49 DEBUG : empty space: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b835_d20251118_m032731_c001_v0001120_t0003_u01763436451367") 2025/11/18 03:28:49 DEBUG : empty space: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f417e7f7511729467_d20251118_m032730_c001_v7007000_t0000_u01763436450488") 2025/11/18 03:28:49 DEBUG : empty space: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b827_d20251118_m032729_c001_v0001120_t0010_u01763436449315") 2025/11/18 03:28:49 DEBUG : empty space: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f417e7f7511729464_d20251118_m032728_c001_v7007000_t0000_u01763436448264") 2025/11/18 03:28:49 DEBUG : empty space: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b81b_d20251118_m032727_c001_v0001120_t0043_u01763436447317") 2025/11/18 03:28:49 DEBUG : empty space: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f417e7f7511729462_d20251118_m032726_c001_v7007000_t0000_u01763436446480") 2025/11/18 03:28:49 DEBUG : empty space: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b80d_d20251118_m032725_c001_v0001120_t0013_u01763436445082") 2025/11/18 03:28:49 DEBUG : empty space: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f417e7f751172945f_d20251118_m032723_c001_v7007000_t0000_u01763436443929") 2025/11/18 03:28:49 DEBUG : empty space: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b7fd_d20251118_m032723_c001_v0001120_t0014_u01763436443104") 2025/11/18 03:28:49 DEBUG : empty space: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f4196b5bd76f94cbc_d20251118_m032502_c001_v7007000_t0000_u01763436302492") 2025/11/18 03:28:49 DEBUG : empty space: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b4f5_d20251118_m032459_c001_v0001120_t0018_u01763436299774") 2025/11/18 03:28:50 DEBUG : empty space: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f4196b5bd76f94cb3_d20251118_m032458_c001_v7007000_t0000_u01763436298055") 2025/11/18 03:28:50 DEBUG : empty space: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b4d3_d20251118_m032454_c001_v0001120_t0056_u01763436294133") 2025/11/18 03:28:50 DEBUG : empty space: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f4196b5bd76f94cac_d20251118_m032451_c001_v7007000_t0000_u01763436291489") 2025/11/18 03:28:50 DEBUG : empty space: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b49f_d20251118_m032448_c001_v0001120_t0031_u01763436288637") 2025/11/18 03:28:50 DEBUG : file1: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f41523415b862d853_d20251118_m032809_c001_v7007000_t0000_u01763436489853") 2025/11/18 03:28:50 DEBUG : file1: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b92f_d20251118_m032808_c001_v0001120_t0030_u01763436488609") 2025/11/18 03:28:50 DEBUG : file1: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f41523415b862d84a_d20251118_m032805_c001_v7007000_t0000_u01763436485560") 2025/11/18 03:28:50 DEBUG : file1: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f4104aa8f94d2b903_d20251118_m032803_c001_v0001120_t0026_u01763436483516") 2025/11/18 03:28:50 DEBUG : file1: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f421c9953e4b8b5c8_d20251118_m032751_c001_v7007000_t0000_u01763436471145") 2025/11/18 03:28:50 DEBUG : file1: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b8b7_d20251118_m032747_c001_v0001120_t0003_u01763436467302") 2025/11/18 03:28:50 DEBUG : file1: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f417e7f751172945c_d20251118_m032719_c001_v7007000_t0000_u01763436439641") 2025/11/18 03:28:50 DEBUG : file1: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b7cb_d20251118_m032713_c001_v0001120_t0032_u01763436433734") 2025/11/18 03:28:50 DEBUG : file1: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f417e7f7511729455_d20251118_m032713_c001_v7007000_t0000_u01763436433149") 2025/11/18 03:28:50 DEBUG : file1: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b7b7_d20251118_m032710_c001_v0001120_t0006_u01763436430267") 2025/11/18 03:28:50 DEBUG : file2: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f41523415b862d84b_d20251118_m032805_c001_v7007000_t0000_u01763436485741") 2025/11/18 03:28:50 DEBUG : file2: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f4104aa8f94d2b909_d20251118_m032805_c001_v0001120_t0026_u01763436485011") 2025/11/18 03:28:50 DEBUG : file2: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f421c9953e4b8b5c9_d20251118_m032751_c001_v7007000_t0000_u01763436471330") 2025/11/18 03:28:50 DEBUG : file2: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b8bd_d20251118_m032747_c001_v0001120_t0012_u01763436467590") 2025/11/18 03:28:50 DEBUG : filename.txt: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f41523415b862d84d_d20251118_m032805_c001_v7007000_t0000_u01763436485923") 2025/11/18 03:28:50 DEBUG : filename.txt: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b905_d20251118_m032804_c001_v0001120_t0021_u01763436484064") 2025/11/18 03:28:51 DEBUG : headerfilename.txt: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f41523415b862d84e_d20251118_m032806_c001_v7007000_t0000_u01763436486103") 2025/11/18 03:28:51 DEBUG : headerfilename.txt: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b907_d20251118_m032804_c001_v0001120_t0006_u01763436484519") 2025/11/18 03:28:51 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f4076efd2d5931e7e_d20251118_m032834_c001_v7007000_t0000_u01763436514895") 2025/11/18 03:28:51 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b9b9_d20251118_m032833_c001_v0001120_t0053_u01763436513634") 2025/11/18 03:28:51 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f4076efd2d5931e7a_d20251118_m032832_c001_v7007000_t0000_u01763436512547") 2025/11/18 03:28:51 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b9b1_d20251118_m032831_c001_v0001120_t0052_u01763436511324") 2025/11/18 03:28:51 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f4076efd2d5931e7f_d20251118_m032835_c001_v7007000_t0000_u01763436515081") 2025/11/18 03:28:51 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b9b7_d20251118_m032833_c001_v0001120_t0007_u01763436513299") 2025/11/18 03:28:51 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f4076efd2d5931e7b_d20251118_m032832_c001_v7007000_t0000_u01763436512736") 2025/11/18 03:28:51 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b9af_d20251118_m032830_c001_v0001120_t0013_u01763436510931") 2025/11/18 03:28:51 DEBUG : large: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f421c9953e4b8b5bd_d20251118_m032743_c001_v7007000_t0000_u01763436463666") 2025/11/18 03:28:51 DEBUG : large: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b89f_d20251118_m032742_c001_v0001120_t0032_u01763436462851") 2025/11/18 03:28:51 DEBUG : large: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f422ebe3dcd090c17_d20251118_m032741_c001_v7007000_t0000_u01763436461381") 2025/11/18 03:28:51 DEBUG : large: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b893_d20251118_m032740_c001_v0001120_t0048_u01763436460165") 2025/11/18 03:28:51 DEBUG : large: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f421c9953e4b8b5ae_d20251118_m032738_c001_v7007000_t0000_u01763436458014") 2025/11/18 03:28:51 DEBUG : large: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b86b_d20251118_m032737_c001_v0001120_t0045_u01763436457286") 2025/11/18 03:28:51 DEBUG : large: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f421c9953e4b8b5ac_d20251118_m032736_c001_v7007000_t0000_u01763436456093") 2025/11/18 03:28:51 DEBUG : large: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b84f_d20251118_m032734_c001_v0001120_t0007_u01763436454146") 2025/11/18 03:28:51 DEBUG : medium: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f422ebe3dcd090c1a_d20251118_m032743_c001_v7007000_t0000_u01763436463657") 2025/11/18 03:28:51 DEBUG : medium: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b89d_d20251118_m032742_c001_v0001120_t0055_u01763436462499") 2025/11/18 03:28:52 DEBUG : medium: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f421c9953e4b8b5b4_d20251118_m032740_c001_v7007000_t0000_u01763436460813") 2025/11/18 03:28:52 DEBUG : medium: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b888_d20251118_m032739_c001_v0001120_t0037_u01763436459744") 2025/11/18 03:28:52 DEBUG : medium: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f421c9953e4b8b5af_d20251118_m032738_c001_v7007000_t0000_u01763436458198") 2025/11/18 03:28:52 DEBUG : medium: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b863_d20251118_m032736_c001_v0001120_t0028_u01763436456810") 2025/11/18 03:28:52 DEBUG : medium: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f417e7f751172946d_d20251118_m032735_c001_v7007000_t0000_u01763436455131") 2025/11/18 03:28:52 DEBUG : medium: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b84b_d20251118_m032733_c001_v0001120_t0023_u01763436453710") 2025/11/18 03:28:52 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f4076efd2d5931e76_d20251118_m032827_c001_v7007000_t0000_u01763436507706") 2025/11/18 03:28:52 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b99b_d20251118_m032826_c001_v0001120_t0027_u01763436506206") 2025/11/18 03:28:52 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f4076efd2d5931e77_d20251118_m032827_c001_v7007000_t0000_u01763436507893") 2025/11/18 03:28:52 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b995_d20251118_m032825_c001_v0001120_t0028_u01763436505683") 2025/11/18 03:28:52 DEBUG : not-one: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f417e7f751172943f_d20251118_m032653_c001_v7007000_t0000_u01763436413740") 2025/11/18 03:28:52 DEBUG : not-one: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b73b_d20251118_m032652_c001_v0001120_t0015_u01763436412040") 2025/11/18 03:28:52 DEBUG : one: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f417e7f751172943c_d20251118_m032652_c001_v7007000_t0000_u01763436412831") 2025/11/18 03:28:52 DEBUG : one: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b731_d20251118_m032650_c001_v0001120_t0048_u01763436410993") 2025/11/18 03:28:52 DEBUG : potato1: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f4076efd2d5931e80_d20251118_m032836_c001_v7007000_t0000_u01763436516873") 2025/11/18 03:28:52 DEBUG : potato1: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b9c9_d20251118_m032835_c001_v0001120_t0047_u01763436515833") 2025/11/18 03:28:52 DEBUG : potato2: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f4196b5bd76f94e13_d20251118_m032841_c001_v7007000_t0000_u01763436521163") 2025/11/18 03:28:52 DEBUG : potato2: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f111bf520ed05c55b_d20251118_m032839_c001_v0001183_t0023_u01763436519884") 2025/11/18 03:28:52 DEBUG : potato2: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b9d5_d20251118_m032837_c001_v0001120_t0000_u01763436517805") 2025/11/18 03:28:52 DEBUG : potato2: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f4076efd2d5931e81_d20251118_m032837_c001_v7007000_t0000_u01763436517057") 2025/11/18 03:28:52 DEBUG : potato2: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b9cb_d20251118_m032836_c001_v0001120_t0056_u01763436516103") 2025/11/18 03:28:52 DEBUG : potato2: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f417e7f751172946b_d20251118_m032732_c001_v7007000_t0000_u01763436452681") 2025/11/18 03:28:53 DEBUG : potato2: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b833_d20251118_m032731_c001_v0001120_t0054_u01763436451100") 2025/11/18 03:28:53 DEBUG : potato2: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f417e7f7511729468_d20251118_m032730_c001_v7007000_t0000_u01763436450668") 2025/11/18 03:28:53 DEBUG : potato2: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b81f_d20251118_m032728_c001_v0001120_t0043_u01763436448807") 2025/11/18 03:28:53 DEBUG : potato2: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f417e7f7511729465_d20251118_m032728_c001_v7007000_t0000_u01763436448444") 2025/11/18 03:28:53 DEBUG : potato2: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b819_d20251118_m032727_c001_v0001120_t0014_u01763436447041") 2025/11/18 03:28:53 DEBUG : potato2: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f417e7f7511729463_d20251118_m032726_c001_v7007000_t0000_u01763436446674") 2025/11/18 03:28:53 DEBUG : potato2: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b807_d20251118_m032724_c001_v0001120_t0030_u01763436444488") 2025/11/18 03:28:53 DEBUG : potato2: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f417e7f7511729460_d20251118_m032724_c001_v7007000_t0000_u01763436444120") 2025/11/18 03:28:53 DEBUG : potato2: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b7fb_d20251118_m032722_c001_v0001120_t0022_u01763436442638") 2025/11/18 03:28:53 DEBUG : potato2: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f4196b5bd76f94cbd_d20251118_m032502_c001_v7007000_t0000_u01763436302679") 2025/11/18 03:28:53 DEBUG : potato2: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b4f9_d20251118_m032500_c001_v0001120_t0034_u01763436300497") 2025/11/18 03:28:53 DEBUG : potato2: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f4196b5bd76f94cb5_d20251118_m032458_c001_v7007000_t0000_u01763436298235") 2025/11/18 03:28:53 DEBUG : potato2: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b4d7_d20251118_m032454_c001_v0001120_t0023_u01763436294964") 2025/11/18 03:28:53 DEBUG : potato2: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f4196b5bd76f94cad_d20251118_m032451_c001_v7007000_t0000_u01763436291670") 2025/11/18 03:28:53 DEBUG : potato2: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b4a9_d20251118_m032449_c001_v0001120_t0051_u01763436289465") 2025/11/18 03:28:53 DEBUG : remotepotato: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f4196b5bd76f94cbe_d20251118_m032502_c001_v7007000_t0000_u01763436302859") 2025/11/18 03:28:53 DEBUG : remotepotato: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b505_d20251118_m032501_c001_v0001120_t0052_u01763436301478") 2025/11/18 03:28:53 DEBUG : remotepotato: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f4196b5bd76f94cb6_d20251118_m032458_c001_v7007000_t0000_u01763436298415") 2025/11/18 03:28:53 DEBUG : remotepotato: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b4dd_d20251118_m032456_c001_v0001120_t0009_u01763436296546") 2025/11/18 03:28:53 DEBUG : remotepotato: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f4196b5bd76f94cae_d20251118_m032451_c001_v7007000_t0000_u01763436291850") 2025/11/18 03:28:53 DEBUG : remotepotato: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b4b3_d20251118_m032450_c001_v0001120_t0041_u01763436290306") 2025/11/18 03:28:54 DEBUG : rutabaga: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f4196b5bd76f94cbf_d20251118_m032503_c001_v7007000_t0000_u01763436303045") 2025/11/18 03:28:54 DEBUG : rutabaga: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b4f1_d20251118_m032458_c001_v0001120_t0013_u01763436298960") 2025/11/18 03:28:54 DEBUG : rutabaga: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f4196b5bd76f94cb7_d20251118_m032458_c001_v7007000_t0000_u01763436298596") 2025/11/18 03:28:54 DEBUG : rutabaga: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b4c3_d20251118_m032452_c001_v0001120_t0019_u01763436292399") 2025/11/18 03:28:54 DEBUG : rutabaga: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f4196b5bd76f94caf_d20251118_m032452_c001_v7007000_t0000_u01763436292030") 2025/11/18 03:28:54 DEBUG : rutabaga: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b493_d20251118_m032447_c001_v0001120_t0050_u01763436287712") 2025/11/18 03:28:54 DEBUG : small: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f421c9953e4b8b5bf_d20251118_m032744_c001_v7007000_t0000_u01763436464219") 2025/11/18 03:28:54 DEBUG : small: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b89b_d20251118_m032742_c001_v0001120_t0019_u01763436462123") 2025/11/18 03:28:54 DEBUG : small: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f422ebe3dcd090c16_d20251118_m032740_c001_v7007000_t0000_u01763436460828") 2025/11/18 03:28:54 DEBUG : small: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b881_d20251118_m032739_c001_v0001120_t0002_u01763436459490") 2025/11/18 03:28:54 DEBUG : small: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f421c9953e4b8b5b1_d20251118_m032738_c001_v7007000_t0000_u01763436458752") 2025/11/18 03:28:54 DEBUG : small: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b85d_d20251118_m032736_c001_v0001120_t0043_u01763436456464") 2025/11/18 03:28:54 DEBUG : small: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f421c9953e4b8b5ab_d20251118_m032735_c001_v7007000_t0000_u01763436455540") 2025/11/18 03:28:54 DEBUG : small: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b847_d20251118_m032733_c001_v0001120_t0018_u01763436453228") 2025/11/18 03:28:54 DEBUG : sub dir/hello world: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f417e7f751172945e_d20251118_m032722_c001_v7007000_t0000_u01763436442254") 2025/11/18 03:28:54 DEBUG : sub dir/hello world: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b7f3_d20251118_m032721_c001_v0001120_t0033_u01763436441092") 2025/11/18 03:28:54 DEBUG : sub dir/hello world: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f417e7f751172944d_d20251118_m032708_c001_v7007000_t0000_u01763436428972") 2025/11/18 03:28:54 DEBUG : sub dir/hello world: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b789_d20251118_m032704_c001_v0001120_t0029_u01763436424112") 2025/11/18 03:28:54 DEBUG : sub dir/hello world: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f417e7f7511729444_d20251118_m032702_c001_v7007000_t0000_u01763436422111") 2025/11/18 03:28:54 DEBUG : sub dir/hello world: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b751_d20251118_m032656_c001_v0001120_t0059_u01763436416633") 2025/11/18 03:28:54 DEBUG : sub dir/hello world2: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f417e7f751172944e_d20251118_m032709_c001_v7007000_t0000_u01763436429153") 2025/11/18 03:28:55 DEBUG : sub dir/hello world2: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b78d_d20251118_m032704_c001_v0001120_t0029_u01763436424599") 2025/11/18 03:28:55 DEBUG : sub dir/hello world2: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f417e7f7511729445_d20251118_m032702_c001_v7007000_t0000_u01763436422292") 2025/11/18 03:28:55 DEBUG : sub dir/hello world2: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b755_d20251118_m032656_c001_v0001120_t0050_u01763436416953") 2025/11/18 03:28:55 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f417e7f751172944f_d20251118_m032709_c001_v7007000_t0000_u01763436429351") 2025/11/18 03:28:55 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b791_d20251118_m032705_c001_v0001120_t0007_u01763436425020") 2025/11/18 03:28:55 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f417e7f7511729446_d20251118_m032702_c001_v7007000_t0000_u01763436422473") 2025/11/18 03:28:55 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b759_d20251118_m032657_c001_v0001120_t0028_u01763436417287") 2025/11/18 03:28:55 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f417e7f7511729451_d20251118_m032709_c001_v7007000_t0000_u01763436429532") 2025/11/18 03:28:55 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b795_d20251118_m032705_c001_v0001120_t0029_u01763436425398") 2025/11/18 03:28:55 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f417e7f7511729447_d20251118_m032702_c001_v7007000_t0000_u01763436422655") 2025/11/18 03:28:55 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b763_d20251118_m032658_c001_v0001120_t0022_u01763436418004") 2025/11/18 03:28:55 DEBUG : sub dir/potato3: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f4196b5bd76f94e14_d20251118_m032841_c001_v7007000_t0000_u01763436521345") 2025/11/18 03:28:55 DEBUG : sub dir/potato3: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f118f126362589700_d20251118_m032839_c001_v0001177_t0047_u01763436519639") 2025/11/18 03:28:55 DEBUG : sub dir/potato3: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b9e3_d20251118_m032838_c001_v0001120_t0005_u01763436518393") 2025/11/18 03:28:55 DEBUG : sub dir/potato3: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f417e7f751172946c_d20251118_m032732_c001_v7007000_t0000_u01763436452863") 2025/11/18 03:28:55 DEBUG : sub dir/potato3: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b83b_d20251118_m032731_c001_v0001120_t0046_u01763436451685") 2025/11/18 03:28:55 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f417e7f7511729452_d20251118_m032709_c001_v7007000_t0000_u01763436429723") 2025/11/18 03:28:55 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b797_d20251118_m032705_c001_v0001120_t0043_u01763436425809") 2025/11/18 03:28:55 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f417e7f7511729448_d20251118_m032702_c001_v7007000_t0000_u01763436422840") 2025/11/18 03:28:55 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b767_d20251118_m032658_c001_v0001120_t0058_u01763436418462") 2025/11/18 03:28:56 DEBUG : sub/file2: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f41523415b862d851_d20251118_m032808_c001_v7007000_t0000_u01763436488056") 2025/11/18 03:28:56 DEBUG : sub/file2: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b917_d20251118_m032806_c001_v0001120_t0053_u01763436486658") 2025/11/18 03:28:56 DEBUG : sub/file2: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f417e7f751172945d_d20251118_m032719_c001_v7007000_t0000_u01763436439822") 2025/11/18 03:28:56 DEBUG : sub/file2: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b7cd_d20251118_m032714_c001_v0001120_t0000_u01763436434171") 2025/11/18 03:28:56 DEBUG : sub/file2: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f417e7f7511729456_d20251118_m032713_c001_v7007000_t0000_u01763436433333") 2025/11/18 03:28:56 DEBUG : sub/file2: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b7b9_d20251118_m032710_c001_v0001120_t0051_u01763436430615") 2025/11/18 03:28:56 DEBUG : sub/file2: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f417e7f75117293ed_d20251118_m032609_c001_v7007000_t0000_u01763436369880") 2025/11/18 03:28:56 DEBUG : sub/file2: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b65f_d20251118_m032608_c001_v0001120_t0045_u01763436368333") 2025/11/18 03:28:56 DEBUG : test-multithread-abort: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f4196b5bd76f94ca9_d20251118_m032446_c001_v7007000_t0000_u01763436286791") 2025/11/18 03:28:56 DEBUG : test-multithread-abort: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b42f_d20251118_m032430_c001_v0001120_t0021_u01763436270236") 2025/11/18 03:28:56 DEBUG : test-multithread-copy-false-16777215-2: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f421c9953e4b879f9_d20251118_m032349_c001_v7007000_t0000_u01763436229783") 2025/11/18 03:28:56 DEBUG : test-multithread-copy-false-16777215-2: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b33d_d20251118_m032342_c001_v0001120_t0027_u01763436222301") 2025/11/18 03:28:56 DEBUG : test-multithread-copy-false-16777216-2: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f421c9953e4b879fa_d20251118_m032358_c001_v7007000_t0000_u01763436238261") 2025/11/18 03:28:56 DEBUG : test-multithread-copy-false-16777216-2: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b365_d20251118_m032350_c001_v0001120_t0024_u01763436230596") 2025/11/18 03:28:56 DEBUG : test-multithread-copy-false-16777217-2: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f421c9953e4b879fe_d20251118_m032406_c001_v7007000_t0000_u01763436246611") 2025/11/18 03:28:56 DEBUG : test-multithread-copy-false-16777217-2: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b38b_d20251118_m032359_c001_v0001120_t0043_u01763436239058") 2025/11/18 03:28:56 DEBUG : test-multithread-copy-true-16777215-2: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f422ebe3dcd090b18_d20251118_m032414_c001_v7007000_t0000_u01763436254235") 2025/11/18 03:28:56 DEBUG : test-multithread-copy-true-16777215-2: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f221c9953e4b87a01_d20251118_m032408_c001_v0001117_t0003_u01763436248778") 2025/11/18 03:28:56 DEBUG : test-multithread-copy-true-16777216-2: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f422ebe3dcd090b28_d20251118_m032421_c001_v7007000_t0000_u01763436261715") 2025/11/18 03:28:56 DEBUG : test-multithread-copy-true-16777216-2: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f222ebe3dcd090b1f_d20251118_m032416_c001_v0001136_t0054_u01763436256517") 2025/11/18 03:28:57 DEBUG : test-multithread-copy-true-16777217-2: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f4196b5bd76f94c9d_d20251118_m032428_c001_v7007000_t0000_u01763436268938") 2025/11/18 03:28:57 DEBUG : test-multithread-copy-true-16777217-2: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f222ebe3dcd090b2c_d20251118_m032424_c001_v0001184_t0034_u01763436264006") 2025/11/18 03:28:57 DEBUG : test.sum: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f4196b5bd76f94ccf_d20251118_m032520_c001_v7007000_t0000_u01763436320988") 2025/11/18 03:28:57 DEBUG : test.sum: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b56c_d20251118_m032519_c001_v0001120_t0056_u01763436319148") 2025/11/18 03:28:57 DEBUG : test.sum: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b560_d20251118_m032517_c001_v0001120_t0033_u01763436317016") 2025/11/18 03:28:57 DEBUG : test.sum: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b55e_d20251118_m032515_c001_v0001120_t0015_u01763436315766") 2025/11/18 03:28:57 DEBUG : test.sum: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b54f_d20251118_m032514_c001_v0001120_t0033_u01763436314367") 2025/11/18 03:28:57 DEBUG : test.sum: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b541_d20251118_m032512_c001_v0001120_t0003_u01763436312856") 2025/11/18 03:28:57 DEBUG : test.sum: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b53b_d20251118_m032511_c001_v0001120_t0053_u01763436311691") 2025/11/18 03:28:57 DEBUG : test.sum: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b531_d20251118_m032509_c001_v0001120_t0011_u01763436309413") 2025/11/18 03:28:57 DEBUG : test.sum: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f4196b5bd76f94cc0_d20251118_m032506_c001_v7007000_t0000_u01763436306049") 2025/11/18 03:28:57 DEBUG : test.sum: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b50d_d20251118_m032505_c001_v0001120_t0057_u01763436305224") 2025/11/18 03:28:57 DEBUG : test.sum: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b509_d20251118_m032503_c001_v0001120_t0042_u01763436303410") 2025/11/18 03:28:57 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f4076efd2d5931e78_d20251118_m032830_c001_v7007000_t0000_u01763436510185") 2025/11/18 03:28:57 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b9a7_d20251118_m032828_c001_v0001120_t0055_u01763436508933") 2025/11/18 03:28:57 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f4076efd2d5931e79_d20251118_m032830_c001_v7007000_t0000_u01763436510370") 2025/11/18 03:28:57 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b9a3_d20251118_m032828_c001_v0001120_t0039_u01763436508461") 2025/11/18 03:28:57 DEBUG : zend.txt: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f417e7f7511729453_d20251118_m032709_c001_v7007000_t0000_u01763436429904") 2025/11/18 03:28:57 DEBUG : zend.txt: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b787_d20251118_m032703_c001_v0001120_t0056_u01763436423830") 2025/11/18 03:28:57 DEBUG : zend.txt: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f417e7f7511729449_d20251118_m032703_c001_v7007000_t0000_u01763436423020") 2025/11/18 03:28:58 DEBUG : zend.txt: Deleting (id "4_z0f925d13f0dd9b4b9fa80e1b_f1104aa8f94d2b74f_d20251118_m032656_c001_v0001120_t0017_u01763436416250") "./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -fast-list" - Finished OK in 5m19.533275126s (try 1/5)