"./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose" - Starting (try 1/5)
2025/08/06 21:49:10 DEBUG : Creating backend with remote "TestB2:rclone-test-tuyayoq4pija"
2025/08/06 21:49:10 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/08/06 21:49:10 DEBUG : Creating backend with remote "/tmp/rclone710753555"
2025/08/06 21:49:10 DEBUG : Config file has changed externally - reloading
=== RUN   TestDoMultiThreadCopy
--- PASS: TestDoMultiThreadCopy (0.00s)
=== RUN   TestMultithreadCalculateNumChunks
=== RUN   TestMultithreadCalculateNumChunks/{size:1_chunkSize:65536_wantNumChunks:1}
=== RUN   TestMultithreadCalculateNumChunks/{size:1048576_chunkSize:1_wantNumChunks:1048576}
=== RUN   TestMultithreadCalculateNumChunks/{size:1048576_chunkSize:2_wantNumChunks:524288}
=== RUN   TestMultithreadCalculateNumChunks/{size:1048577_chunkSize:2_wantNumChunks:524289}
=== RUN   TestMultithreadCalculateNumChunks/{size:1048575_chunkSize:2_wantNumChunks:524288}
--- PASS: TestMultithreadCalculateNumChunks (0.00s)
    --- PASS: TestMultithreadCalculateNumChunks/{size:1_chunkSize:65536_wantNumChunks:1} (0.00s)
    --- PASS: TestMultithreadCalculateNumChunks/{size:1048576_chunkSize:1_wantNumChunks:1048576} (0.00s)
    --- PASS: TestMultithreadCalculateNumChunks/{size:1048576_chunkSize:2_wantNumChunks:524288} (0.00s)
    --- PASS: TestMultithreadCalculateNumChunks/{size:1048577_chunkSize:2_wantNumChunks:524289} (0.00s)
    --- PASS: TestMultithreadCalculateNumChunks/{size:1048575_chunkSize:2_wantNumChunks:524288} (0.00s)
=== RUN   TestMultithreadCopy
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
2025/08/06 21:49:15 DEBUG : chunksize-probe: Cancelling large file upload
=== RUN   TestMultithreadCopy/upload=false,size=201326591,streams=2
2025/08/06 21:49:41 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt
2025/08/06 21:49:41 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: write buffer set to 131072
2025/08/06 21:49:41 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2
2025/08/06 21:49:41 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: number of streams 4 was bigger than number of chunks 3
2025/08/06 21:49:41 DEBUG : test-multithread-copy-false-201326591-2: Starting multi-thread copy with 3 chunks of size 64Mi with 3 parallel streams
2025/08/06 21:49:41 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 3/3 (134217728-201326591) size 64.000Mi starting
2025/08/06 21:49:41 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 1/3 (0-67108864) size 64Mi starting
2025/08/06 21:49:41 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 2/3 (67108864-134217728) size 64Mi starting
2025/08/06 21:49:41 DEBUG : test-multithread-copy-false-201326591-2: writing chunk 2
2025/08/06 21:49:42 DEBUG : test-multithread-copy-false-201326591-2: writing chunk 0
2025/08/06 21:49:42 DEBUG : test-multithread-copy-false-201326591-2: writing chunk 1
2025/08/06 21:49:46 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 3/3 (134217728-201326591) size 64.000Mi finished
2025/08/06 21:49:46 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 1/3 (0-67108864) size 64Mi finished
2025/08/06 21:49:47 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 2/3 (67108864-134217728) size 64Mi finished
2025/08/06 21:49:47 DEBUG : test-multithread-copy-false-201326591-2: Finished multi-thread copy with 3 parts of size 64Mi
=== RUN   TestMultithreadCopy/upload=false,size=201326592,streams=2
2025/08/06 21:50:22 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt
2025/08/06 21:50:22 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: write buffer set to 131072
2025/08/06 21:50:22 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2
2025/08/06 21:50:22 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: number of streams 4 was bigger than number of chunks 3
2025/08/06 21:50:22 DEBUG : test-multithread-copy-false-201326592-2: Starting multi-thread copy with 3 chunks of size 64Mi with 3 parallel streams
2025/08/06 21:50:22 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 3/3 (134217728-201326592) size 64Mi starting
2025/08/06 21:50:22 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 1/3 (0-67108864) size 64Mi starting
2025/08/06 21:50:22 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 2/3 (67108864-134217728) size 64Mi starting
2025/08/06 21:50:23 DEBUG : test-multithread-copy-false-201326592-2: writing chunk 2
2025/08/06 21:50:23 DEBUG : test-multithread-copy-false-201326592-2: writing chunk 0
2025/08/06 21:50:23 DEBUG : test-multithread-copy-false-201326592-2: writing chunk 1
2025/08/06 21:50:28 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 3/3 (134217728-201326592) size 64Mi finished
2025/08/06 21:50:28 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 2/3 (67108864-134217728) size 64Mi finished
2025/08/06 21:50:28 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 1/3 (0-67108864) size 64Mi finished
2025/08/06 21:50:28 DEBUG : test-multithread-copy-false-201326592-2: Finished multi-thread copy with 3 parts of size 64Mi
=== RUN   TestMultithreadCopy/upload=false,size=201326593,streams=2
2025/08/06 21:51:08 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt
2025/08/06 21:51:08 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: write buffer set to 131072
2025/08/06 21:51:08 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2
2025/08/06 21:51:08 DEBUG : test-multithread-copy-false-201326593-2: Starting multi-thread copy with 4 chunks of size 64Mi with 4 parallel streams
2025/08/06 21:51:08 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 1/4 (0-67108864) size 64Mi starting
2025/08/06 21:51:08 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 2/4 (67108864-134217728) size 64Mi starting
2025/08/06 21:51:08 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 3/4 (134217728-201326592) size 64Mi starting
2025/08/06 21:51:08 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 4/4 (201326592-201326593) size 1 starting
2025/08/06 21:51:08 DEBUG : test-multithread-copy-false-201326593-2: writing chunk 0
2025/08/06 21:51:08 DEBUG : test-multithread-copy-false-201326593-2: writing chunk 3
2025/08/06 21:51:08 DEBUG : test-multithread-copy-false-201326593-2: writing chunk 1
2025/08/06 21:51:08 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 4/4 (201326592-201326593) size 1 finished
2025/08/06 21:51:08 DEBUG : test-multithread-copy-false-201326593-2: writing chunk 2
2025/08/06 21:51:13 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 1/4 (0-67108864) size 64Mi finished
2025/08/06 21:51:13 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 2/4 (67108864-134217728) size 64Mi finished
2025/08/06 21:51:13 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 3/4 (134217728-201326592) size 64Mi finished
2025/08/06 21:51:13 DEBUG : test-multithread-copy-false-201326593-2: Finished multi-thread copy with 4 parts of size 64Mi
=== RUN   TestMultithreadCopy/upload=true,size=201326591,streams=2
2025/08/06 21:51:49 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: disabling buffering because source is local disk
2025/08/06 21:51:50 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2
2025/08/06 21:51:50 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: number of streams 4 was bigger than number of chunks 2
2025/08/06 21:51:50 DEBUG : test-multithread-copy-true-201326591-2: Starting multi-thread copy with 2 chunks of size 96Mi with 2 parallel streams
2025/08/06 21:51:50 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: chunk 2/2 (100663296-201326591) size 96.000Mi starting
2025/08/06 21:51:50 DEBUG : test-multithread-copy-true-201326591-2: Sending chunk 1 length 100663295
2025/08/06 21:51:50 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: chunk 1/2 (0-100663296) size 96Mi starting
2025/08/06 21:51:50 DEBUG : test-multithread-copy-true-201326591-2: Sending chunk 0 length 100663296
2025/08/06 21:51:58 DEBUG : test-multithread-copy-true-201326591-2: Done sending chunk 0
2025/08/06 21:51:58 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: chunk 1/2 (0-100663296) size 96Mi finished
2025/08/06 21:51:59 DEBUG : test-multithread-copy-true-201326591-2: Done sending chunk 1
2025/08/06 21:51:59 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: chunk 2/2 (100663296-201326591) size 96.000Mi finished
2025/08/06 21:51:59 DEBUG : test-multithread-copy-true-201326591-2: Finishing large file upload with 2 parts
2025/08/06 21:51:59 DEBUG : test-multithread-copy-true-201326591-2: Finished multi-thread copy with 2 parts of size 96Mi
=== RUN   TestMultithreadCopy/upload=true,size=201326592,streams=2
2025/08/06 21:52:30 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: disabling buffering because source is local disk
2025/08/06 21:52:31 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2
2025/08/06 21:52:31 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: number of streams 4 was bigger than number of chunks 2
2025/08/06 21:52:31 DEBUG : test-multithread-copy-true-201326592-2: Starting multi-thread copy with 2 chunks of size 96Mi with 2 parallel streams
2025/08/06 21:52:31 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: chunk 2/2 (100663296-201326592) size 96Mi starting
2025/08/06 21:52:31 DEBUG : test-multithread-copy-true-201326592-2: Sending chunk 1 length 100663296
2025/08/06 21:52:31 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: chunk 1/2 (0-100663296) size 96Mi starting
2025/08/06 21:52:31 DEBUG : test-multithread-copy-true-201326592-2: Sending chunk 0 length 100663296
2025/08/06 21:52:39 DEBUG : test-multithread-copy-true-201326592-2: Done sending chunk 1
2025/08/06 21:52:39 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: chunk 2/2 (100663296-201326592) size 96Mi finished
2025/08/06 21:52:39 DEBUG : test-multithread-copy-true-201326592-2: Done sending chunk 0
2025/08/06 21:52:39 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: chunk 1/2 (0-100663296) size 96Mi finished
2025/08/06 21:52:39 DEBUG : test-multithread-copy-true-201326592-2: Finishing large file upload with 2 parts
2025/08/06 21:52:40 DEBUG : test-multithread-copy-true-201326592-2: Finished multi-thread copy with 2 parts of size 96Mi
=== RUN   TestMultithreadCopy/upload=true,size=201326593,streams=2
2025/08/06 21:53:11 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: disabling buffering because source is local disk
2025/08/06 21:53:12 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2
2025/08/06 21:53:12 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: number of streams 4 was bigger than number of chunks 3
2025/08/06 21:53:12 DEBUG : test-multithread-copy-true-201326593-2: Starting multi-thread copy with 3 chunks of size 96Mi with 3 parallel streams
2025/08/06 21:53:12 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 3/3 (201326592-201326593) size 1 starting
2025/08/06 21:53:12 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 1/3 (0-100663296) size 96Mi starting
2025/08/06 21:53:12 DEBUG : test-multithread-copy-true-201326593-2: Sending chunk 0 length 100663296
2025/08/06 21:53:12 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 2/3 (100663296-201326592) size 96Mi starting
2025/08/06 21:53:12 DEBUG : test-multithread-copy-true-201326593-2: Sending chunk 1 length 100663296
2025/08/06 21:53:12 DEBUG : test-multithread-copy-true-201326593-2: Sending chunk 2 length 1
2025/08/06 21:53:13 DEBUG : test-multithread-copy-true-201326593-2: Done sending chunk 2
2025/08/06 21:53:13 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 3/3 (201326592-201326593) size 1 finished
2025/08/06 21:53:20 DEBUG : test-multithread-copy-true-201326593-2: Done sending chunk 0
2025/08/06 21:53:20 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 1/3 (0-100663296) size 96Mi finished
2025/08/06 21:53:20 DEBUG : test-multithread-copy-true-201326593-2: Done sending chunk 1
2025/08/06 21:53:20 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 2/3 (100663296-201326592) size 96Mi finished
2025/08/06 21:53:20 DEBUG : test-multithread-copy-true-201326593-2: Finishing large file upload with 3 parts
2025/08/06 21:53:21 DEBUG : test-multithread-copy-true-201326593-2: Finished multi-thread copy with 3 parts of size 96Mi
--- PASS: TestMultithreadCopy (261.16s)
    --- PASS: TestMultithreadCopy/upload=false,size=201326591,streams=2 (42.59s)
    --- PASS: TestMultithreadCopy/upload=false,size=201326592,streams=2 (43.92s)
    --- PASS: TestMultithreadCopy/upload=false,size=201326593,streams=2 (44.71s)
    --- PASS: TestMultithreadCopy/upload=true,size=201326591,streams=2 (44.63s)
    --- PASS: TestMultithreadCopy/upload=true,size=201326592,streams=2 (40.36s)
    --- PASS: TestMultithreadCopy/upload=true,size=201326593,streams=2 (39.92s)
=== RUN   TestMultithreadCopyAbort
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
2025/08/06 21:53:32 DEBUG : chunksize-probe: Cancelling large file upload
2025/08/06 21:53:52 DEBUG : test-multithread-abort: multi-thread copy: disabling buffering because source is local disk
2025/08/06 21:53:53 DEBUG : test-multithread-abort: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 1
2025/08/06 21:53:53 DEBUG : test-multithread-abort: multi-thread copy: number of streams 4 was bigger than number of chunks 3
2025/08/06 21:53:53 DEBUG : test-multithread-abort: Starting multi-thread copy with 3 chunks of size 96Mi with 3 parallel streams
2025/08/06 21:53:53 DEBUG : test-multithread-abort: multi-thread copy: chunk 3/3 (201326592-201326593) size 1 starting
2025/08/06 21:53:53 DEBUG : Open with options = [RangeOption(201326592,201326592)]
2025/08/06 21:53:53 DEBUG : test-multithread-abort: multi-thread copy: chunk 1/3 (0-100663296) size 96Mi starting
2025/08/06 21:53:53 DEBUG : Open with options = [RangeOption(0,100663295)]
2025/08/06 21:53:53 DEBUG : test-multithread-abort: multi-thread copy: chunk 2/3 (100663296-201326592) size 96Mi starting
2025/08/06 21:53:53 DEBUG : test-multithread-abort: Sending chunk 0 length 100663296
2025/08/06 21:53:53 DEBUG : Open with options = [RangeOption(100663296,201326591)]
2025/08/06 21:53:53 DEBUG : test-multithread-abort: Sending chunk 1 length 100663296
2025/08/06 21:54:02 DEBUG : test-multithread-abort: Done sending chunk 1
2025/08/06 21:54:02 DEBUG : test-multithread-abort: multi-thread copy: chunk 2/3 (100663296-201326592) size 96Mi finished
2025/08/06 21:54:03 DEBUG : test-multithread-abort: Done sending chunk 0
2025/08/06 21:54:03 DEBUG : test-multithread-abort: multi-thread copy: chunk 1/3 (0-100663296) size 96Mi finished
2025/08/06 21:54:03 DEBUG : Returning error reader
2025/08/06 21:54:03 DEBUG : test-multithread-abort: Sending chunk 2 length 1
2025/08/06 21:54:03 DEBUG : BOOM: simulated read failure
2025/08/06 21:54:03 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 0/10: BOOM: simulated read failure
2025/08/06 21:54:03 DEBUG : Open with options = [RangeOption(201326592,201326592)]
2025/08/06 21:54:04 DEBUG : Returning error reader
2025/08/06 21:54:04 DEBUG : BOOM: simulated read failure
2025/08/06 21:54:04 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 1/10: BOOM: simulated read failure
2025/08/06 21:54:04 DEBUG : Open with options = [RangeOption(201326592,201326592)]
2025/08/06 21:54:05 DEBUG : Returning error reader
2025/08/06 21:54:05 DEBUG : BOOM: simulated read failure
2025/08/06 21:54:05 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 2/10: BOOM: simulated read failure
2025/08/06 21:54:05 DEBUG : Open with options = [RangeOption(201326592,201326592)]
2025/08/06 21:54:06 DEBUG : Returning error reader
2025/08/06 21:54:06 DEBUG : BOOM: simulated read failure
2025/08/06 21:54:06 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 3/10: BOOM: simulated read failure
2025/08/06 21:54:06 DEBUG : Open with options = [RangeOption(201326592,201326592)]
2025/08/06 21:54:07 DEBUG : Returning error reader
2025/08/06 21:54:07 DEBUG : BOOM: simulated read failure
2025/08/06 21:54:07 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 4/10: BOOM: simulated read failure
2025/08/06 21:54:07 DEBUG : Open with options = [RangeOption(201326592,201326592)]
2025/08/06 21:54:08 DEBUG : Returning error reader
2025/08/06 21:54:08 DEBUG : BOOM: simulated read failure
2025/08/06 21:54:08 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 5/10: BOOM: simulated read failure
2025/08/06 21:54:08 DEBUG : Open with options = [RangeOption(201326592,201326592)]
2025/08/06 21:54:09 DEBUG : Returning error reader
2025/08/06 21:54:09 DEBUG : BOOM: simulated read failure
2025/08/06 21:54:09 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 6/10: BOOM: simulated read failure
2025/08/06 21:54:09 DEBUG : Open with options = [RangeOption(201326592,201326592)]
2025/08/06 21:54:10 DEBUG : Returning error reader
2025/08/06 21:54:10 DEBUG : BOOM: simulated read failure
2025/08/06 21:54:10 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 7/10: BOOM: simulated read failure
2025/08/06 21:54:10 DEBUG : Open with options = [RangeOption(201326592,201326592)]
2025/08/06 21:54:11 DEBUG : Returning error reader
2025/08/06 21:54:11 DEBUG : BOOM: simulated read failure
2025/08/06 21:54:11 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 8/10: BOOM: simulated read failure
2025/08/06 21:54:11 DEBUG : Open with options = [RangeOption(201326592,201326592)]
2025/08/06 21:54:12 DEBUG : Returning error reader
2025/08/06 21:54:12 DEBUG : BOOM: simulated read failure
2025/08/06 21:54:12 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 9/10: BOOM: simulated read failure
2025/08/06 21:54:12 DEBUG : Open with options = [RangeOption(201326592,201326592)]
2025/08/06 21:54:13 DEBUG : Returning error reader
2025/08/06 21:54:13 DEBUG : BOOM: simulated read failure
2025/08/06 21:54:13 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 10/10: BOOM: simulated read failure
2025/08/06 21:54:13 DEBUG : test-multithread-abort: Reopen failed after offset 0 bytes read: failed to reopen: too many retries
2025/08/06 21:54:13 DEBUG : test-multithread-abort: Error sending chunk 2 (retry=false): Post "https://pod-000-1180-15.backblaze.com/b2api/v1/b2_upload_part/4_z9f921d4350cd8b1b9f880e1b_f2134a0094413ece5_d20250806_m215353_c001_v0001180_t0035_u01754517233221/0057": BOOM: simulated read failure: &url.Error{Op:"Post", URL:"https://pod-000-1180-15.backblaze.com/b2api/v1/b2_upload_part/4_z9f921d4350cd8b1b9f880e1b_f2134a0094413ece5_d20250806_m215353_c001_v0001180_t0035_u01754517233221/0057", Err:(*errors.errorString)(0xc000a5aa10)}
2025/08/06 21:54:13 DEBUG : test-multithread-abort: Error sending chunk 2: Post "https://pod-000-1180-15.backblaze.com/b2api/v1/b2_upload_part/4_z9f921d4350cd8b1b9f880e1b_f2134a0094413ece5_d20250806_m215353_c001_v0001180_t0035_u01754517233221/0057": BOOM: simulated read failure
2025/08/06 21:54:13 DEBUG : test-multithread-abort: multi-thread copy: chunk 3/3 failed: multi-thread copy: failed to write chunk: Post "https://pod-000-1180-15.backblaze.com/b2api/v1/b2_upload_part/4_z9f921d4350cd8b1b9f880e1b_f2134a0094413ece5_d20250806_m215353_c001_v0001180_t0035_u01754517233221/0057": BOOM: simulated read failure
2025/08/06 21:54:13 DEBUG : test-multithread-abort: multi-thread copy: cancelling transfer on exit
2025/08/06 21:54:13 DEBUG : test-multithread-abort: Cancelling large file upload
--- PASS: TestMultithreadCopyAbort (42.69s)
=== RUN   TestSizeDiffers
--- PASS: TestSizeDiffers (0.00s)
=== RUN   TestReOpen
=== RUN   TestReOpen/Normal
=== RUN   TestReOpen/Normal/Basics
2025/08/06 21:54:14 DEBUG : potato: Seek from 10 to 0
=== RUN   TestReOpen/Normal/ErrorAtStart
=== RUN   TestReOpen/Normal/WithErrors
2025/08/06 21:54:14 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2025/08/06 21:54:14 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2025/08/06 21:54:14 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN   TestReOpen/Normal/TooManyErrors
2025/08/06 21:54:14 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2025/08/06 21:54:14 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2025/08/06 21:54:14 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2025/08/06 21:54:14 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN   TestReOpen/Normal/Seek
2025/08/06 21:54:14 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2025/08/06 21:54:14 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2025/08/06 21:54:14 DEBUG : potato: Seek from 5 to 2
=== RUN   TestReOpen/Normal/AccountRead
=== RUN   TestReOpen/Normal/AccountReadDelay
2025/08/06 21:54:14 DEBUG : potato: Seek from 10 to 0
2025/08/06 21:54:14 DEBUG : potato: Seek from 10 to 0
2025/08/06 21:54:14 DEBUG : potato: Seek from 10 to 0
=== RUN   TestReOpen/Normal/AccountReadError
=== RUN   TestReOpen/WithRangeOption
=== RUN   TestReOpen/WithRangeOption/Basics
2025/08/06 21:54:14 DEBUG : potato: Seek from 7 to 0
=== RUN   TestReOpen/WithRangeOption/ErrorAtStart
=== RUN   TestReOpen/WithRangeOption/WithErrors
2025/08/06 21:54:14 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2025/08/06 21:54:14 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2025/08/06 21:54:14 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN   TestReOpen/WithRangeOption/TooManyErrors
2025/08/06 21:54:14 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2025/08/06 21:54:14 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2025/08/06 21:54:14 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2025/08/06 21:54:14 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN   TestReOpen/WithRangeOption/Seek
2025/08/06 21:54:14 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2025/08/06 21:54:14 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2025/08/06 21:54:14 DEBUG : potato: Seek from 5 to 2
2025/08/06 21:54:14 DEBUG : potato: Seek from 7 to 4
=== RUN   TestReOpen/WithRangeOption/AccountRead
=== RUN   TestReOpen/WithRangeOption/AccountReadDelay
2025/08/06 21:54:14 DEBUG : potato: Seek from 7 to 0
2025/08/06 21:54:14 DEBUG : potato: Seek from 7 to 0
2025/08/06 21:54:14 DEBUG : potato: Seek from 7 to 0
=== RUN   TestReOpen/WithRangeOption/AccountReadError
=== RUN   TestReOpen/WithSeekOption
=== RUN   TestReOpen/WithSeekOption/Basics
2025/08/06 21:54:14 DEBUG : potato: Seek from 8 to 0
=== RUN   TestReOpen/WithSeekOption/ErrorAtStart
=== RUN   TestReOpen/WithSeekOption/WithErrors
2025/08/06 21:54:14 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2025/08/06 21:54:14 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2025/08/06 21:54:14 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN   TestReOpen/WithSeekOption/TooManyErrors
2025/08/06 21:54:14 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2025/08/06 21:54:14 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2025/08/06 21:54:14 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2025/08/06 21:54:14 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN   TestReOpen/WithSeekOption/Seek
2025/08/06 21:54:14 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2025/08/06 21:54:14 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2025/08/06 21:54:14 DEBUG : potato: Seek from 5 to 2
2025/08/06 21:54:14 DEBUG : potato: Seek from 7 to 5
=== RUN   TestReOpen/WithSeekOption/AccountRead
=== RUN   TestReOpen/WithSeekOption/AccountReadDelay
2025/08/06 21:54:14 DEBUG : potato: Seek from 8 to 0
2025/08/06 21:54:14 DEBUG : potato: Seek from 8 to 0
2025/08/06 21:54:14 DEBUG : potato: Seek from 8 to 0
=== RUN   TestReOpen/WithSeekOption/AccountReadError
=== RUN   TestReOpen/UnknownSize
=== RUN   TestReOpen/UnknownSize/Basics
2025/08/06 21:54:14 DEBUG : potato: Seek from 9 to 0
=== RUN   TestReOpen/UnknownSize/ErrorAtStart
=== RUN   TestReOpen/UnknownSize/WithErrors
2025/08/06 21:54:14 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2025/08/06 21:54:14 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2025/08/06 21:54:14 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN   TestReOpen/UnknownSize/TooManyErrors
2025/08/06 21:54:14 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2025/08/06 21:54:14 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2025/08/06 21:54:14 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2025/08/06 21:54:14 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN   TestReOpen/UnknownSize/Seek
2025/08/06 21:54:14 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2025/08/06 21:54:14 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2025/08/06 21:54:14 DEBUG : potato: Seek from 5 to 2
2025/08/06 21:54:14 DEBUG : potato: Seek from 7 to 6
=== RUN   TestReOpen/UnknownSize/AccountRead
=== RUN   TestReOpen/UnknownSize/AccountReadDelay
2025/08/06 21:54:14 DEBUG : potato: Seek from 9 to 0
2025/08/06 21:54:14 DEBUG : potato: Seek from 9 to 0
2025/08/06 21:54:14 DEBUG : potato: Seek from 9 to 0
=== RUN   TestReOpen/UnknownSize/AccountReadError
--- PASS: TestReOpen (0.00s)
    --- PASS: TestReOpen/Normal (0.00s)
        --- PASS: TestReOpen/Normal/Basics (0.00s)
        --- PASS: TestReOpen/Normal/ErrorAtStart (0.00s)
        --- PASS: TestReOpen/Normal/WithErrors (0.00s)
        --- PASS: TestReOpen/Normal/TooManyErrors (0.00s)
        --- PASS: TestReOpen/Normal/Seek (0.00s)
        --- PASS: TestReOpen/Normal/AccountRead (0.00s)
        --- PASS: TestReOpen/Normal/AccountReadDelay (0.00s)
        --- PASS: TestReOpen/Normal/AccountReadError (0.00s)
    --- PASS: TestReOpen/WithRangeOption (0.00s)
        --- PASS: TestReOpen/WithRangeOption/Basics (0.00s)
        --- PASS: TestReOpen/WithRangeOption/ErrorAtStart (0.00s)
        --- PASS: TestReOpen/WithRangeOption/WithErrors (0.00s)
        --- PASS: TestReOpen/WithRangeOption/TooManyErrors (0.00s)
        --- PASS: TestReOpen/WithRangeOption/Seek (0.00s)
        --- PASS: TestReOpen/WithRangeOption/AccountRead (0.00s)
        --- PASS: TestReOpen/WithRangeOption/AccountReadDelay (0.00s)
        --- PASS: TestReOpen/WithRangeOption/AccountReadError (0.00s)
    --- PASS: TestReOpen/WithSeekOption (0.00s)
        --- PASS: TestReOpen/WithSeekOption/Basics (0.00s)
        --- PASS: TestReOpen/WithSeekOption/ErrorAtStart (0.00s)
        --- PASS: TestReOpen/WithSeekOption/WithErrors (0.00s)
        --- PASS: TestReOpen/WithSeekOption/TooManyErrors (0.00s)
        --- PASS: TestReOpen/WithSeekOption/Seek (0.00s)
        --- PASS: TestReOpen/WithSeekOption/AccountRead (0.00s)
        --- PASS: TestReOpen/WithSeekOption/AccountReadDelay (0.00s)
        --- PASS: TestReOpen/WithSeekOption/AccountReadError (0.00s)
    --- PASS: TestReOpen/UnknownSize (0.00s)
        --- PASS: TestReOpen/UnknownSize/Basics (0.00s)
        --- PASS: TestReOpen/UnknownSize/ErrorAtStart (0.00s)
        --- PASS: TestReOpen/UnknownSize/WithErrors (0.00s)
        --- PASS: TestReOpen/UnknownSize/TooManyErrors (0.00s)
        --- PASS: TestReOpen/UnknownSize/Seek (0.00s)
        --- PASS: TestReOpen/UnknownSize/AccountRead (0.00s)
        --- PASS: TestReOpen/UnknownSize/AccountReadDelay (0.00s)
        --- PASS: TestReOpen/UnknownSize/AccountReadError (0.00s)
=== RUN   TestCheck
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", 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.52s)
    --- 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/08/06 21:54:20 DEBUG : Creating backend with remote "nonexistent"
2025/08/06 21:54:20 DEBUG : Config file has changed externally - reloading
2025/08/06 21:54:20 DEBUG : Creating backend with remote "nonexistent"
2025/08/06 21:54:20 DEBUG : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: Waiting for checks to finish
2025/08/06 21:54:20 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/08/06 21:54:20 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: 0 differences found
2025/08/06 21:54:20 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: 2 errors while checking
--- PASS: TestCheckFsError (0.00s)
=== RUN   TestCheckDownload
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
=== RUN   TestCheckDownload/1
=== RUN   TestCheckDownload/2
=== RUN   TestCheckDownload/3
=== RUN   TestCheckDownload/4
=== RUN   TestCheckDownload/5
=== RUN   TestCheckDownload/6
=== RUN   TestCheckDownload/7
--- PASS: TestCheckDownload (7.44s)
    --- PASS: TestCheckDownload/1 (0.73s)
    --- PASS: TestCheckDownload/2 (0.36s)
    --- PASS: TestCheckDownload/3 (0.37s)
    --- PASS: TestCheckDownload/4 (0.55s)
    --- PASS: TestCheckDownload/5 (0.38s)
    --- PASS: TestCheckDownload/6 (0.38s)
    --- PASS: TestCheckDownload/7 (0.38s)
=== RUN   TestCheckSizeOnly
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
=== RUN   TestCheckSizeOnly/1
=== RUN   TestCheckSizeOnly/2
=== RUN   TestCheckSizeOnly/3
=== RUN   TestCheckSizeOnly/4
=== RUN   TestCheckSizeOnly/5
=== RUN   TestCheckSizeOnly/6
=== RUN   TestCheckSizeOnly/7
--- PASS: TestCheckSizeOnly (5.61s)
    --- PASS: TestCheckSizeOnly/1 (0.18s)
    --- PASS: TestCheckSizeOnly/2 (0.18s)
    --- PASS: TestCheckSizeOnly/3 (0.18s)
    --- PASS: TestCheckSizeOnly/4 (0.18s)
    --- PASS: TestCheckSizeOnly/5 (0.18s)
    --- PASS: TestCheckSizeOnly/6 (0.18s)
    --- PASS: TestCheckSizeOnly/7 (0.18s)
=== RUN   TestCheckEqualReaders
--- PASS: TestCheckEqualReaders (0.00s)
=== RUN   TestParseSumFile
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
2025/08/06 21:54:34 NOTICE: test.sum: improperly formatted checksum line 4
2025/08/06 21:54:34 NOTICE: test.sum: improperly formatted checksum line 5
2025/08/06 21:54:34 NOTICE: test.sum: improperly formatted checksum line 6
2025/08/06 21:54:34 NOTICE: test.sum: 2 warning(s) suppressed...
2025/08/06 21:54:35 NOTICE: test.sum: improperly formatted checksum line 4
2025/08/06 21:54:35 NOTICE: test.sum: improperly formatted checksum line 5
2025/08/06 21:54:35 NOTICE: test.sum: improperly formatted checksum line 6
2025/08/06 21:54:35 NOTICE: test.sum: 2 warning(s) suppressed...
--- PASS: TestParseSumFile (2.79s)
=== RUN   TestCheckSum
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
2025/08/06 21:54:36 DEBUG : Creating backend with remote "TestB2:rclone-test-tuyayoq4pija/data"
2025/08/06 21:54:36 DEBUG : Config file has changed externally - reloading
    check_test.go:350: B2 bucket rclone-test-tuyayoq4pija path data lacks md5, skipping
--- SKIP: TestCheckSum (1.47s)
=== RUN   TestCheckSumDownload
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
2025/08/06 21:54:37 DEBUG : Creating backend with remote "TestB2:rclone-test-tuyayoq4pija/data"
=== RUN   TestCheckSumDownload/subtest1
=== RUN   TestCheckSumDownload/subtest2
=== RUN   TestCheckSumDownload/subtest3
=== RUN   TestCheckSumDownload/subtest4
=== RUN   TestCheckSumDownload/subtest5
=== RUN   TestCheckSumDownload/subtest6
=== RUN   TestCheckSumDownload/subtest7
--- PASS: TestCheckSumDownload (15.56s)
    --- PASS: TestCheckSumDownload/subtest1 (1.32s)
    --- PASS: TestCheckSumDownload/subtest2 (0.73s)
    --- PASS: TestCheckSumDownload/subtest3 (0.94s)
    --- PASS: TestCheckSumDownload/subtest4 (0.75s)
    --- PASS: TestCheckSumDownload/subtest5 (0.74s)
    --- PASS: TestCheckSumDownload/subtest6 (0.77s)
    --- PASS: TestCheckSumDownload/subtest7 (0.81s)
=== RUN   TestApplyTransforms
2025/08/06 21:54:53 DEBUG : Creating backend with remote "TestB2:rclone-test-nonahit7pevo"
2025/08/06 21:54:53 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/08/06 21:54:53 DEBUG : Creating backend with remote "/tmp/rclone794224644"
    run.go:180: Remote "B2 bucket rclone-test-nonahit7pevo", Local "Local file system at /tmp/rclone794224644", Modify Window "1ms"
upper checkfile vs. lower remote (without normalization)
2025/08/06 21:54:56 ERROR : hello, world!: sum not found
2025/08/06 21:54:56 ERROR : HELLO, WORLD!: file not in B2 bucket rclone-test-nonahit7pevo
2025/08/06 21:54:56 NOTICE: B2 bucket rclone-test-nonahit7pevo: 1 files missing
2025/08/06 21:54:56 NOTICE: 1 hashes missing
2025/08/06 21:54:56 NOTICE: B2 bucket rclone-test-nonahit7pevo: 1 differences found
2025/08/06 21:54:56 NOTICE: B2 bucket rclone-test-nonahit7pevo: 2 errors while checking
upper checkfile vs. lower remote (with normalization)
2025/08/06 21:54:57 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2025/08/06 21:54:57 NOTICE: B2 bucket rclone-test-nonahit7pevo: 0 differences found
2025/08/06 21:54:57 NOTICE: B2 bucket rclone-test-nonahit7pevo: 1 matching files
2025/08/06 21:54:57 DEBUG : Creating backend with remote "TestB2:rclone-test-pirisud3wine"
2025/08/06 21:54:57 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/08/06 21:54:57 DEBUG : Creating backend with remote "/tmp/rclone3402521450"
    run.go:180: Remote "B2 bucket rclone-test-pirisud3wine", Local "Local file system at /tmp/rclone3402521450", Modify Window "1ms"
lower checkfile vs. upper remote (without normalization)
2025/08/06 21:54:59 ERROR : HELLO, WORLD!: sum not found
2025/08/06 21:54:59 ERROR : hello, world!: file not in B2 bucket rclone-test-pirisud3wine
2025/08/06 21:54:59 NOTICE: B2 bucket rclone-test-pirisud3wine: 1 files missing
2025/08/06 21:54:59 NOTICE: 1 hashes missing
2025/08/06 21:54:59 NOTICE: B2 bucket rclone-test-pirisud3wine: 1 differences found
2025/08/06 21:54:59 NOTICE: B2 bucket rclone-test-pirisud3wine: 2 errors while checking
lower checkfile vs. upper remote (with normalization)
2025/08/06 21:55:00 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2025/08/06 21:55:00 NOTICE: B2 bucket rclone-test-pirisud3wine: 0 differences found
2025/08/06 21:55:00 NOTICE: B2 bucket rclone-test-pirisud3wine: 1 matching files
2025/08/06 21:55:00 DEBUG : Creating backend with remote "TestB2:rclone-test-begepuj5yohe"
2025/08/06 21:55:00 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/08/06 21:55:00 DEBUG : Creating backend with remote "/tmp/rclone1966943194"
    run.go:180: Remote "B2 bucket rclone-test-begepuj5yohe", Local "Local file system at /tmp/rclone1966943194", Modify Window "1ms"
lower checkfile vs. upperlowermixed remote (without normalization)
2025/08/06 21:55:03 ERROR : HeLlO, wOrLd!: sum not found
2025/08/06 21:55:03 ERROR : hello, world!: file not in B2 bucket rclone-test-begepuj5yohe
2025/08/06 21:55:03 NOTICE: B2 bucket rclone-test-begepuj5yohe: 1 files missing
2025/08/06 21:55:03 NOTICE: 1 hashes missing
2025/08/06 21:55:03 NOTICE: B2 bucket rclone-test-begepuj5yohe: 1 differences found
2025/08/06 21:55:03 NOTICE: B2 bucket rclone-test-begepuj5yohe: 2 errors while checking
lower checkfile vs. upperlowermixed remote (with normalization)
2025/08/06 21:55:04 DEBUG : HeLlO, wOrLd!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2025/08/06 21:55:04 NOTICE: B2 bucket rclone-test-begepuj5yohe: 0 differences found
2025/08/06 21:55:04 NOTICE: B2 bucket rclone-test-begepuj5yohe: 1 matching files
2025/08/06 21:55:04 DEBUG : Creating backend with remote "TestB2:rclone-test-kuzexej2munu"
2025/08/06 21:55:04 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/08/06 21:55:04 DEBUG : Creating backend with remote "/tmp/rclone1585458"
    run.go:180: Remote "B2 bucket rclone-test-kuzexej2munu", Local "Local file system at /tmp/rclone1585458", Modify Window "1ms"
upperlowermixed checkfile vs. upper remote (without normalization)
2025/08/06 21:55:07 ERROR : HELLO, WORLD!: sum not found
2025/08/06 21:55:07 ERROR : HeLlO, wOrLd!: file not in B2 bucket rclone-test-kuzexej2munu
2025/08/06 21:55:07 NOTICE: B2 bucket rclone-test-kuzexej2munu: 1 files missing
2025/08/06 21:55:07 NOTICE: 1 hashes missing
2025/08/06 21:55:07 NOTICE: B2 bucket rclone-test-kuzexej2munu: 1 differences found
2025/08/06 21:55:07 NOTICE: B2 bucket rclone-test-kuzexej2munu: 2 errors while checking
upperlowermixed checkfile vs. upper remote (with normalization)
2025/08/06 21:55:07 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2025/08/06 21:55:07 NOTICE: B2 bucket rclone-test-kuzexej2munu: 0 differences found
2025/08/06 21:55:07 NOTICE: B2 bucket rclone-test-kuzexej2munu: 1 matching files
2025/08/06 21:55:07 DEBUG : Creating backend with remote "TestB2:rclone-test-cisumoh6koja"
2025/08/06 21:55:07 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/08/06 21:55:08 DEBUG : Creating backend with remote "/tmp/rclone1034574171"
    run.go:180: Remote "B2 bucket rclone-test-cisumoh6koja", Local "Local file system at /tmp/rclone1034574171", Modify Window "1ms"
NFD checkfile vs. NFC remote (without normalization)
2025/08/06 21:55:10 ERROR : 測試_Русский___ě_áñ: sum not found
2025/08/06 21:55:10 ERROR : 測試_Русский___ě_áñ: file not in B2 bucket rclone-test-cisumoh6koja
2025/08/06 21:55:10 NOTICE: B2 bucket rclone-test-cisumoh6koja: 1 files missing
2025/08/06 21:55:10 NOTICE: 1 hashes missing
2025/08/06 21:55:10 NOTICE: B2 bucket rclone-test-cisumoh6koja: 1 differences found
2025/08/06 21:55:10 NOTICE: B2 bucket rclone-test-cisumoh6koja: 2 errors while checking
NFD checkfile vs. NFC remote (with normalization)
2025/08/06 21:55:11 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2025/08/06 21:55:11 NOTICE: B2 bucket rclone-test-cisumoh6koja: 0 differences found
2025/08/06 21:55:11 NOTICE: B2 bucket rclone-test-cisumoh6koja: 1 matching files
2025/08/06 21:55:11 DEBUG : Creating backend with remote "TestB2:rclone-test-hugabip9yaka"
2025/08/06 21:55:11 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/08/06 21:55:12 DEBUG : Creating backend with remote "/tmp/rclone2397928626"
    run.go:180: Remote "B2 bucket rclone-test-hugabip9yaka", Local "Local file system at /tmp/rclone2397928626", Modify Window "1ms"
NFC checkfile vs. NFD remote (without normalization)
2025/08/06 21:55:14 ERROR : 測試_Русский___ě_áñ: sum not found
2025/08/06 21:55:14 ERROR : 測試_Русский___ě_áñ: file not in B2 bucket rclone-test-hugabip9yaka
2025/08/06 21:55:14 NOTICE: B2 bucket rclone-test-hugabip9yaka: 1 files missing
2025/08/06 21:55:14 NOTICE: 1 hashes missing
2025/08/06 21:55:14 NOTICE: B2 bucket rclone-test-hugabip9yaka: 1 differences found
2025/08/06 21:55:14 NOTICE: B2 bucket rclone-test-hugabip9yaka: 2 errors while checking
NFC checkfile vs. NFD remote (with normalization)
2025/08/06 21:55:14 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2025/08/06 21:55:14 NOTICE: B2 bucket rclone-test-hugabip9yaka: 0 differences found
2025/08/06 21:55:14 NOTICE: B2 bucket rclone-test-hugabip9yaka: 1 matching files
2025/08/06 21:55:14 DEBUG : Creating backend with remote "TestB2:rclone-test-safirih3rofu"
2025/08/06 21:55:14 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/08/06 21:55:15 DEBUG : Creating backend with remote "/tmp/rclone1283517987"
    run.go:180: Remote "B2 bucket rclone-test-safirih3rofu", Local "Local file system at /tmp/rclone1283517987", Modify Window "1ms"
NFDx2 checkfile vs. both remote (without normalization)
2025/08/06 21:55:17 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found
2025/08/06 21:55:17 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-safirih3rofu
2025/08/06 21:55:17 NOTICE: B2 bucket rclone-test-safirih3rofu: 1 files missing
2025/08/06 21:55:17 NOTICE: 1 hashes missing
2025/08/06 21:55:17 NOTICE: B2 bucket rclone-test-safirih3rofu: 1 differences found
2025/08/06 21:55:17 NOTICE: B2 bucket rclone-test-safirih3rofu: 2 errors while checking
NFDx2 checkfile vs. both remote (with normalization)
2025/08/06 21:55:18 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2025/08/06 21:55:18 NOTICE: B2 bucket rclone-test-safirih3rofu: 0 differences found
2025/08/06 21:55:18 NOTICE: B2 bucket rclone-test-safirih3rofu: 1 matching files
2025/08/06 21:55:18 DEBUG : Creating backend with remote "TestB2:rclone-test-fiwebod2nobu"
2025/08/06 21:55:18 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/08/06 21:55:18 DEBUG : Creating backend with remote "/tmp/rclone1188320445"
    run.go:180: Remote "B2 bucket rclone-test-fiwebod2nobu", Local "Local file system at /tmp/rclone1188320445", Modify Window "1ms"
NFCx2 checkfile vs. both remote (without normalization)
2025/08/06 21:55:21 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found
2025/08/06 21:55:21 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-fiwebod2nobu
2025/08/06 21:55:21 NOTICE: B2 bucket rclone-test-fiwebod2nobu: 1 files missing
2025/08/06 21:55:21 NOTICE: 1 hashes missing
2025/08/06 21:55:21 NOTICE: B2 bucket rclone-test-fiwebod2nobu: 1 differences found
2025/08/06 21:55:21 NOTICE: B2 bucket rclone-test-fiwebod2nobu: 2 errors while checking
NFCx2 checkfile vs. both remote (with normalization)
2025/08/06 21:55:22 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2025/08/06 21:55:22 NOTICE: B2 bucket rclone-test-fiwebod2nobu: 0 differences found
2025/08/06 21:55:22 NOTICE: B2 bucket rclone-test-fiwebod2nobu: 1 matching files
2025/08/06 21:55:22 DEBUG : Creating backend with remote "TestB2:rclone-test-nezunac4capa"
2025/08/06 21:55:22 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/08/06 21:55:22 DEBUG : Creating backend with remote "/tmp/rclone4274358669"
    run.go:180: Remote "B2 bucket rclone-test-nezunac4capa", Local "Local file system at /tmp/rclone4274358669", Modify Window "1ms"
both checkfile vs. NFDx2 remote (without normalization)
2025/08/06 21:55:25 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found
2025/08/06 21:55:25 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-nezunac4capa
2025/08/06 21:55:25 NOTICE: B2 bucket rclone-test-nezunac4capa: 1 files missing
2025/08/06 21:55:25 NOTICE: 1 hashes missing
2025/08/06 21:55:25 NOTICE: B2 bucket rclone-test-nezunac4capa: 1 differences found
2025/08/06 21:55:25 NOTICE: B2 bucket rclone-test-nezunac4capa: 2 errors while checking
both checkfile vs. NFDx2 remote (with normalization)
2025/08/06 21:55:25 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2025/08/06 21:55:25 NOTICE: B2 bucket rclone-test-nezunac4capa: 0 differences found
2025/08/06 21:55:25 NOTICE: B2 bucket rclone-test-nezunac4capa: 1 matching files
2025/08/06 21:55:25 DEBUG : Creating backend with remote "TestB2:rclone-test-guxidek2duxu"
2025/08/06 21:55:25 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/08/06 21:55:26 DEBUG : Creating backend with remote "/tmp/rclone3736318534"
    run.go:180: Remote "B2 bucket rclone-test-guxidek2duxu", Local "Local file system at /tmp/rclone3736318534", Modify Window "1ms"
both checkfile vs. NFCx2 remote (without normalization)
2025/08/06 21:55:28 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found
2025/08/06 21:55:28 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-guxidek2duxu
2025/08/06 21:55:28 NOTICE: B2 bucket rclone-test-guxidek2duxu: 1 files missing
2025/08/06 21:55:28 NOTICE: 1 hashes missing
2025/08/06 21:55:28 NOTICE: B2 bucket rclone-test-guxidek2duxu: 1 differences found
2025/08/06 21:55:28 NOTICE: B2 bucket rclone-test-guxidek2duxu: 2 errors while checking
both checkfile vs. NFCx2 remote (with normalization)
2025/08/06 21:55:29 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2025/08/06 21:55:29 NOTICE: B2 bucket rclone-test-guxidek2duxu: 0 differences found
2025/08/06 21:55:29 NOTICE: B2 bucket rclone-test-guxidek2duxu: 1 matching files
2025/08/06 21:55:29 DEBUG : B2 bucket rclone-test-guxidek2duxu: Purge remote
2025/08/06 21:55:29 INFO  : B2 bucket rclone-test-guxidek2duxu: cleaning bucket "rclone-test-guxidek2duxu" of all files
2025/08/06 21:55:29 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_zef82cdb3601d8b1b9f880e1b_f1112c2fe77e22951_d20250806_m215527_c001_v0001172_t0008_u01754517327782")
2025/08/06 21:55:29 DEBUG : B2 bucket rclone-test-nezunac4capa: Purge remote
2025/08/06 21:55:29 INFO  : B2 bucket rclone-test-nezunac4capa: cleaning bucket "rclone-test-nezunac4capa" of all files
2025/08/06 21:55:30 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_z8fe2bdd3601d8b1b9f880e1b_f1129c9a250d2eda6_d20250806_m215524_c001_v0001146_t0051_u01754517324146")
2025/08/06 21:55:30 DEBUG : B2 bucket rclone-test-fiwebod2nobu: Purge remote
2025/08/06 21:55:30 INFO  : B2 bucket rclone-test-fiwebod2nobu: cleaning bucket "rclone-test-fiwebod2nobu" of all files
2025/08/06 21:55:31 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_z9f32adf3601d8b1b9f880e1b_f10087e72d3f9783c_d20250806_m215520_c001_v0001184_t0052_u01754517320880")
2025/08/06 21:55:31 DEBUG : B2 bucket rclone-test-safirih3rofu: Purge remote
2025/08/06 21:55:31 INFO  : B2 bucket rclone-test-safirih3rofu: cleaning bucket "rclone-test-safirih3rofu" of all files
2025/08/06 21:55:32 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_z7f82ad13601d8b1b9f880e1b_f102541d19f1d3822_d20250806_m215517_c001_v0001101_t0039_u01754517317027")
2025/08/06 21:55:33 DEBUG : B2 bucket rclone-test-hugabip9yaka: Purge remote
2025/08/06 21:55:33 INFO  : B2 bucket rclone-test-hugabip9yaka: cleaning bucket "rclone-test-hugabip9yaka" of all files
2025/08/06 21:55:33 DEBUG : 測試_Русский___ě_áñ: Deleting (id "4_z3f429d43601d8b1b9f880e1b_f11406c1f924d59f8_d20250806_m215513_c001_v0001178_t0009_u01754517313689")
2025/08/06 21:55:34 DEBUG : B2 bucket rclone-test-cisumoh6koja: Purge remote
2025/08/06 21:55:34 INFO  : B2 bucket rclone-test-cisumoh6koja: cleaning bucket "rclone-test-cisumoh6koja" of all files
2025/08/06 21:55:34 DEBUG : 測試_Русский___ě_áñ: Deleting (id "4_z1fe28d63601d8b1b9f880e1b_f11307e0d38e9503f_d20250806_m215509_c001_v0001161_t0051_u01754517309961")
2025/08/06 21:55:35 DEBUG : B2 bucket rclone-test-kuzexej2munu: Purge remote
2025/08/06 21:55:35 INFO  : B2 bucket rclone-test-kuzexej2munu: cleaning bucket "rclone-test-kuzexej2munu" of all files
2025/08/06 21:55:36 DEBUG : HELLO, WORLD!: Deleting (id "4_zcf027d73601d8b1b9f880e1b_f1042c7491ecc9df0_d20250806_m215506_c001_v0001106_t0056_u01754517306319")
2025/08/06 21:55:36 DEBUG : B2 bucket rclone-test-begepuj5yohe: Purge remote
2025/08/06 21:55:36 INFO  : B2 bucket rclone-test-begepuj5yohe: cleaning bucket "rclone-test-begepuj5yohe" of all files
2025/08/06 21:55:37 DEBUG : HeLlO, wOrLd!: Deleting (id "4_z9ff26d83601d8b1b9f880e1b_f10808cff085ed20a_d20250806_m215502_c001_v0001104_t0004_u01754517302345")
2025/08/06 21:55:37 DEBUG : B2 bucket rclone-test-pirisud3wine: Purge remote
2025/08/06 21:55:37 INFO  : B2 bucket rclone-test-pirisud3wine: cleaning bucket "rclone-test-pirisud3wine" of all files
2025/08/06 21:55:38 DEBUG : HELLO, WORLD!: Deleting (id "4_z0f325dc3601d8b1b9f880e1b_f107705283afd5081_d20250806_m215459_c001_v0001179_t0001_u01754517299144")
2025/08/06 21:55:39 DEBUG : B2 bucket rclone-test-nonahit7pevo: Purge remote
2025/08/06 21:55:39 INFO  : B2 bucket rclone-test-nonahit7pevo: cleaning bucket "rclone-test-nonahit7pevo" of all files
2025/08/06 21:55:39 DEBUG : hello, world!: Deleting (id "4_zffe24dc3601d8b1b9f880e1b_f116d071364b710bf_d20250806_m215455_c001_v0001029_t0043_u01754517295434")
--- PASS: TestApplyTransforms (47.18s)
=== RUN   TestTruncateString
--- PASS: TestTruncateString (0.00s)
=== RUN   TestCopyFile
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
2025/08/06 21:55:40 DEBUG : file1: Need to transfer - File not found at Destination
2025/08/06 21:55:41 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK
2025/08/06 21:55:41 INFO  : file1: Copied (new) to: sub/file2
2025/08/06 21:55:42 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2025/08/06 21:55:42 DEBUG : file1: Unchanged skipping
2025/08/06 21:55:42 DEBUG : B2 bucket rclone-test-tuyayoq4pija: don't need to copy/move sub/file2, it is already at target location
--- PASS: TestCopyFile (3.25s)
=== RUN   TestCopyLongFile
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
    copy_test.go:153: Test only runs on local
--- SKIP: TestCopyLongFile (0.36s)
=== RUN   TestCopyFileBackupDir
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
2025/08/06 21:55:44 DEBUG : Creating backend with remote "TestB2:rclone-test-tuyayoq4pija/backup"
2025/08/06 21:55:45 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18)
2025/08/06 21:55:47 DEBUG : dst/file1: sha1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK
2025/08/06 21:55:47 INFO  : dst/file1: Copied (server-side copy)
2025/08/06 21:55:47 INFO  : dst/file1: Deleted
2025/08/06 21:55:48 DEBUG : dst/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK
2025/08/06 21:55:48 INFO  : dst/file1: Copied (new)
--- PASS: TestCopyFileBackupDir (5.17s)
=== RUN   TestCopyFileCompareDest
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
2025/08/06 21:55:49 DEBUG : Creating backend with remote "TestB2:rclone-test-tuyayoq4pija/dst"
2025/08/06 21:55:50 DEBUG : Creating backend with remote "TestB2:rclone-test-tuyayoq4pija/CompareDest"
2025/08/06 21:55:51 DEBUG : one: Need to transfer - File not found at Destination
2025/08/06 21:55:53 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK
2025/08/06 21:55:53 INFO  : one: Copied (new)
2025/08/06 21:55:53 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/08/06 21:55:54 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK
2025/08/06 21:55:54 INFO  : one: Copied (replaced existing)
2025/08/06 21:55:56 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/08/06 21:55:56 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2025/08/06 21:55:56 DEBUG : one: Destination found in --compare-dest, skipping
2025/08/06 21:55:57 DEBUG : two: Need to transfer - File not found at Destination
2025/08/06 21:55:57 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2025/08/06 21:55:57 DEBUG : two: Destination found in --compare-dest, skipping
2025/08/06 21:55:58 DEBUG : two: Need to transfer - File not found at Destination
2025/08/06 21:55:58 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2025/08/06 21:55:58 DEBUG : two: Destination found in --compare-dest, skipping
2025/08/06 21:55:58 DEBUG : two: Need to transfer - File not found at Destination
2025/08/06 21:55:59 DEBUG : two: Sizes differ (src 5 vs dst 3)
2025/08/06 21:55:59 DEBUG : two: Clearing upload URL because of error: Post "https://pod-000-1182-02.backblaze.com/b2api/v1/b2_upload_file/9f921d4350cd8b1b9f880e1b/c001_v0001182_t0052": EOF
2025/08/06 21:55:59 DEBUG : pacer: low level retry 1/1 (error Post "https://pod-000-1182-02.backblaze.com/b2api/v1/b2_upload_file/9f921d4350cd8b1b9f880e1b/c001_v0001182_t0052": EOF)
2025/08/06 21:55:59 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2025/08/06 21:55:59 DEBUG : two: Received error: Post "https://pod-000-1182-02.backblaze.com/b2api/v1/b2_upload_file/9f921d4350cd8b1b9f880e1b/c001_v0001182_t0052": EOF - low level retry 0/10
2025/08/06 21:55:59 DEBUG : pacer: Reducing sleep to 10ms
2025/08/06 21:56:00 DEBUG : two: sha1 = a4be8877ad88f3109a6411985a1f359e10e2d930 OK
2025/08/06 21:56:00 INFO  : two: Copied (new)
--- PASS: TestCopyFileCompareDest (12.64s)
=== RUN   TestCopyFileCopyDest
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
2025/08/06 21:56:01 DEBUG : Creating backend with remote "TestB2:rclone-test-tuyayoq4pija/dst"
2025/08/06 21:56:03 DEBUG : Creating backend with remote "TestB2:rclone-test-tuyayoq4pija/CopyDest"
2025/08/06 21:56:04 DEBUG : one: Need to transfer - File not found at Destination
2025/08/06 21:56:06 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK
2025/08/06 21:56:06 INFO  : one: Copied (new)
2025/08/06 21:56:06 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/08/06 21:56:07 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK
2025/08/06 21:56:07 INFO  : one: Copied (replaced existing)
2025/08/06 21:56:09 DEBUG : Creating backend with remote "TestB2:rclone-test-tuyayoq4pija/BackupDir"
2025/08/06 21:56:10 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/08/06 21:56:10 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2025/08/06 21:56:10 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/08/06 21:56:12 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK
2025/08/06 21:56:12 INFO  : one: Copied (server-side copy)
2025/08/06 21:56:13 INFO  : one: Deleted
2025/08/06 21:56:13 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK
2025/08/06 21:56:13 INFO  : one: Copied (server-side copy)
2025/08/06 21:56:13 DEBUG : one: Destination found in --copy-dest, using server-side copy
2025/08/06 21:56:14 DEBUG : two: Need to transfer - File not found at Destination
2025/08/06 21:56:14 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2025/08/06 21:56:14 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK
2025/08/06 21:56:14 INFO  : two: Copied (server-side copy)
2025/08/06 21:56:14 DEBUG : two: Destination found in --copy-dest, using server-side copy
2025/08/06 21:56:15 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2025/08/06 21:56:15 DEBUG : two: Unchanged skipping
2025/08/06 21:56:16 DEBUG : three: Need to transfer - File not found at Destination
2025/08/06 21:56:16 DEBUG : three: Sizes differ (src 7 vs dst 5)
2025/08/06 21:56:16 DEBUG : three: Destination not found in --copy-dest
2025/08/06 21:56:17 DEBUG : three: sha1 = 370e613335f3cf2f19b2a2d35a86402f55c659f8 OK
2025/08/06 21:56:17 INFO  : three: Copied (new)
--- PASS: TestCopyFileCopyDest (17.56s)
=== RUN   TestCopyInplace
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
    copy_test.go:370: Partial uploads not supported
--- SKIP: TestCopyInplace (0.36s)
=== RUN   TestCopyLongFileName
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
    copy_test.go:403: Partial uploads not supported
--- SKIP: TestCopyLongFileName (0.36s)
=== RUN   TestCopyFileMaxTransfer
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
2025/08/06 21:56:20 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination
2025/08/06 21:56:21 DEBUG : TestCopyFileMaxTransfer/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK
2025/08/06 21:56:21 INFO  : TestCopyFileMaxTransfer/file1: Copied (new)
2025/08/06 21:56:21 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination
2025/08/06 21:56:21 ERROR : TestCopyFileMaxTransfer/file2: Failed to copy: Post "https://pod-000-1106-14.backblaze.com/b2api/v1/b2_upload_file/9f921d4350cd8b1b9f880e1b/c001_v0001106_t0009": max transfer limit reached as set by --max-transfer
2025/08/06 21:56:21 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination
2025/08/06 21:56:22 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination
2025/08/06 21:56:23 DEBUG : TestCopyFileMaxTransfer/file4: sha1 = 16725705b5cc89e412da43f7d02c9350ec4d96e6 OK
2025/08/06 21:56:23 INFO  : TestCopyFileMaxTransfer/file4: Copied (new)
--- PASS: TestCopyFileMaxTransfer (4.01s)
=== RUN   TestDeduplicateInteractive
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
    dedupe_test.go:24: Can't test deduplicate - no duplicate files possible
--- SKIP: TestDeduplicateInteractive (0.36s)
=== RUN   TestDeduplicateSkip
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
    dedupe_test.go:24: Can't test deduplicate - no duplicate files possible
--- SKIP: TestDeduplicateSkip (0.36s)
=== RUN   TestDeduplicateSizeOnly
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
    dedupe_test.go:24: Can't test deduplicate - no duplicate files possible
--- SKIP: TestDeduplicateSizeOnly (0.36s)
=== RUN   TestDeduplicateFirst
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
    dedupe_test.go:24: Can't test deduplicate - no duplicate files possible
--- SKIP: TestDeduplicateFirst (0.36s)
=== RUN   TestDeduplicateNewest
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
    dedupe_test.go:24: Can't test deduplicate - no duplicate files possible
--- SKIP: TestDeduplicateNewest (0.36s)
=== RUN   TestDeduplicateNewestByHash
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
2025/08/06 21:56:28 INFO  : B2 bucket rclone-test-tuyayoq4pija: Looking for duplicate sha1 hashes using newest mode.
2025/08/06 21:56:28 NOTICE: 91b0f9f25061de7e4d1ac09bb7035001b83c89fa: Found 3 files with duplicate sha1 hashes
2025/08/06 21:56:28 INFO  : one: Deleted
2025/08/06 21:56:28 INFO  : also/one: Deleted
2025/08/06 21:56:28 NOTICE: 91b0f9f25061de7e4d1ac09bb7035001b83c89fa: Deleted 2 extra copies
--- PASS: TestDeduplicateNewestByHash (3.73s)
=== RUN   TestDeduplicateOldest
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
    dedupe_test.go:24: Can't test deduplicate - no duplicate files possible
--- SKIP: TestDeduplicateOldest (0.36s)
=== RUN   TestDeduplicateLargest
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
    dedupe_test.go:24: Can't test deduplicate - no duplicate files possible
--- SKIP: TestDeduplicateLargest (0.36s)
=== RUN   TestDeduplicateSmallest
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
    dedupe_test.go:24: Can't test deduplicate - no duplicate files possible
--- SKIP: TestDeduplicateSmallest (0.36s)
=== RUN   TestDeduplicateRename
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
    dedupe_test.go:24: Can't test deduplicate - no duplicate files possible
--- SKIP: TestDeduplicateRename (0.36s)
=== RUN   TestMergeDirs
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
    dedupe_test.go:256: Can't merge directories
--- SKIP: TestMergeDirs (0.36s)
=== RUN   TestListDirSorted
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
2025/08/06 21:56:35 DEBUG : a.txt: Excluded (Size Filter)
2025/08/06 21:56:35 DEBUG : a.txt: Excluded
2025/08/06 21:56:35 DEBUG : sub dir/hello world: Excluded (Size Filter)
2025/08/06 21:56:35 DEBUG : sub dir/hello world: Excluded
2025/08/06 21:56:35 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2025/08/06 21:56:35 DEBUG : sub dir/hello world2: Excluded
2025/08/06 21:56:36 DEBUG : sub dir/hello world: Excluded (Size Filter)
2025/08/06 21:56:36 DEBUG : sub dir/hello world: Excluded
2025/08/06 21:56:36 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2025/08/06 21:56:36 DEBUG : sub dir/hello world2: Excluded
2025/08/06 21:56:36 DEBUG : sub dir/ignore dir: Excluded
2025/08/06 21:56:36 DEBUG : sub dir/ignore dir: Excluded
--- PASS: TestListDirSorted (7.91s)
=== RUN   TestListDirSortedFn
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
2025/08/06 21:56:43 DEBUG : a.txt: Excluded (Size Filter)
2025/08/06 21:56:43 DEBUG : a.txt: Excluded
2025/08/06 21:56:43 DEBUG : sub dir/hello world: Excluded (Size Filter)
2025/08/06 21:56:43 DEBUG : sub dir/hello world: Excluded
2025/08/06 21:56:43 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2025/08/06 21:56:43 DEBUG : sub dir/hello world2: Excluded
2025/08/06 21:56:44 DEBUG : sub dir/hello world: Excluded (Size Filter)
2025/08/06 21:56:44 DEBUG : sub dir/hello world: Excluded
2025/08/06 21:56:44 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2025/08/06 21:56:44 DEBUG : sub dir/hello world2: Excluded
2025/08/06 21:56:44 DEBUG : sub dir/ignore dir: Excluded
2025/08/06 21:56:45 DEBUG : sub dir/ignore dir: Excluded
--- PASS: TestListDirSortedFn (7.71s)
=== RUN   TestListJSON
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", 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/08/06 21:56:50 ERROR : file1: Failed to read hash: hash type not supported
=== RUN   TestListJSON/Metadata
--- PASS: TestListJSON (4.08s)
    --- PASS: TestListJSON/Default (0.19s)
    --- PASS: TestListJSON/FilesOnly (0.19s)
    --- PASS: TestListJSON/DirsOnly (0.19s)
    --- PASS: TestListJSON/Recurse (0.18s)
    --- PASS: TestListJSON/SubDir (0.18s)
    --- PASS: TestListJSON/NoModTime (0.19s)
    --- PASS: TestListJSON/NoMimeType (0.19s)
    --- PASS: TestListJSON/ShowHash (0.19s)
    --- PASS: TestListJSON/HashTypes (0.23s)
    --- PASS: TestListJSON/Metadata (0.25s)
=== RUN   TestStatJSON
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", 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/08/06 21:56:55 DEBUG : Creating backend with remote "TestB2:rclone-test-tuyayoq4pija/notfound"
--- PASS: TestStatJSON (6.76s)
    --- PASS: TestStatJSON/Root (0.19s)
    --- PASS: TestStatJSON/RootFilesOnly (0.00s)
    --- PASS: TestStatJSON/RootDirsOnly (0.19s)
    --- PASS: TestStatJSON/Dir (0.73s)
    --- PASS: TestStatJSON/DirWithTrailingSlash (0.20s)
    --- PASS: TestStatJSON/File (0.18s)
    --- PASS: TestStatJSON/NotFound (0.37s)
    --- PASS: TestStatJSON/DirFilesOnly (0.18s)
    --- PASS: TestStatJSON/FileFilesOnly (0.18s)
    --- PASS: TestStatJSON/NotFoundFilesOnly (0.18s)
    --- PASS: TestStatJSON/DirDirsOnly (0.18s)
    --- PASS: TestStatJSON/FileDirsOnly (0.19s)
    --- PASS: TestStatJSON/NotFoundDirsOnly (0.19s)
    --- PASS: TestStatJSON/RootNotFound (1.86s)
=== RUN   TestMkdir
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
2025/08/06 21:56:57 INFO  : B2 bucket rclone-test-tuyayoq4pija: Making directory
2025/08/06 21:56:58 INFO  : B2 bucket rclone-test-tuyayoq4pija: Making directory
--- PASS: TestMkdir (0.55s)
=== RUN   TestLsd
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
--- PASS: TestLsd (1.81s)
=== RUN   TestLs
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
--- PASS: TestLs (2.50s)
=== RUN   TestLsWithFilesFrom
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
2025/08/06 21:57:04 DEBUG : empty space: Excluded (FilesFrom Filter)
2025/08/06 21:57:04 DEBUG : empty space: Excluded
--- PASS: TestLsWithFilesFrom (2.75s)
=== RUN   TestLsLong
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
--- PASS: TestLsLong (2.00s)
=== RUN   TestHashSums
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
=== RUN   TestHashSums/Sha1
=== RUN   TestHashSums/Sha1Download
--- PASS: TestHashSums (2.78s)
    --- PASS: TestHashSums/Sha1 (0.18s)
    --- PASS: TestHashSums/Sha1Download (0.38s)
=== RUN   TestHashSumsWithErrors
2025/08/06 21:57:10 DEBUG : Creating backend with remote ":memory:"
2025/08/06 21:57:10 ERROR : file1: hash unsupported: hash type not supported
--- PASS: TestHashSumsWithErrors (0.00s)
=== RUN   TestHashStream
2025/08/06 21:57:10 DEBUG : Creating md5 hash of 0 bytes read from input stream
2025/08/06 21:57:10 DEBUG : Creating md5 hash of 0 bytes read from input stream
2025/08/06 21:57:10 DEBUG : Creating sha1 hash of 0 bytes read from input stream
2025/08/06 21:57:10 DEBUG : Creating sha1 hash of 0 bytes read from input stream
2025/08/06 21:57:10 DEBUG : Creating md5 hash of 12 bytes read from input stream
2025/08/06 21:57:10 DEBUG : Creating md5 hash of 12 bytes read from input stream
2025/08/06 21:57:10 DEBUG : Creating sha1 hash of 12 bytes read from input stream
2025/08/06 21:57:10 DEBUG : Creating sha1 hash of 12 bytes read from input stream
--- PASS: TestHashStream (0.00s)
=== RUN   TestSuffixName
--- PASS: TestSuffixName (0.00s)
=== RUN   TestCount
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
--- PASS: TestCount (3.17s)
=== RUN   TestDelete
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
2025/08/06 21:57:15 DEBUG : Waiting for deletions to finish
2025/08/06 21:57:15 DEBUG : large: Excluded (Size Filter)
2025/08/06 21:57:15 INFO  : medium: Deleted
2025/08/06 21:57:16 INFO  : small: Deleted
--- PASS: TestDelete (3.43s)
=== RUN   TestMaxDelete
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
2025/08/06 21:57:18 DEBUG : Waiting for deletions to finish
2025/08/06 21:57:18 ERROR : medium: Got fatal error on delete: --max-delete threshold reached
2025/08/06 21:57:18 INFO  : large: Deleted
2025/08/06 21:57:19 INFO  : small: Deleted
--- PASS: TestMaxDelete (3.38s)
=== RUN   TestMaxDeleteSizeLargeFile
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
2025/08/06 21:57:22 DEBUG : Waiting for deletions to finish
2025/08/06 21:57:22 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached
2025/08/06 21:57:22 INFO  : small: Deleted
2025/08/06 21:57:22 INFO  : medium: Deleted
--- PASS: TestMaxDeleteSizeLargeFile (3.70s)
=== RUN   TestMaxDeleteSize
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
2025/08/06 21:57:25 DEBUG : Waiting for deletions to finish
2025/08/06 21:57:25 ERROR : small: Got fatal error on delete: --max-delete-size threshold reached
2025/08/06 21:57:26 INFO  : large: Deleted
2025/08/06 21:57:26 INFO  : medium: Deleted
--- PASS: TestMaxDeleteSize (3.26s)
=== RUN   TestReadFile
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
--- PASS: TestReadFile (2.40s)
=== RUN   TestRetry
2025/08/06 21:57:29 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 1/5
2025/08/06 21:57:29 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 2/5
2025/08/06 21:57:29 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms
2025/08/06 21:57:29 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms
2025/08/06 21:57:29 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms
2025/08/06 21:57:29 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms
2025/08/06 21:57:29 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms
--- PASS: TestRetry (0.05s)
=== RUN   TestCat
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
--- PASS: TestCat (4.82s)
=== RUN   TestPurge
2025/08/06 21:57:34 DEBUG : Creating backend with remote "TestB2:rclone-test-ranefov7divo"
2025/08/06 21:57:34 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/08/06 21:57:35 DEBUG : Creating backend with remote "/tmp/rclone405148150"
    run.go:180: Remote "B2 bucket rclone-test-ranefov7divo", Local "Local file system at /tmp/rclone405148150", Modify Window "1ms"
2025/08/06 21:57:36 INFO  : A2: Making directory
2025/08/06 21:57:36 INFO  : A1/B2: Making directory
2025/08/06 21:57:36 INFO  : A1/B2/C2: Making directory
2025/08/06 21:57:36 INFO  : A1/B1/C3: Making directory
2025/08/06 21:57:36 INFO  : A3: Making directory
2025/08/06 21:57:36 INFO  : A3/B3: Making directory
2025/08/06 21:57:36 INFO  : A3/B3/C4: Making directory
    fstest.go:247: Filtering empty directory "A2"
    fstest.go:247: Filtering empty directory "A1/B2"
    fstest.go:247: Filtering empty directory "A1/B2/C2"
    fstest.go:247: Filtering empty directory "A1/B1/C3"
    fstest.go:247: Filtering empty directory "A3"
    fstest.go:247: Filtering empty directory "A3/B3"
    fstest.go:247: Filtering empty directory "A3/B3/C4"
2025/08/06 21:57:37 INFO  : B2 bucket rclone-test-ranefov7divo: cleaning bucket "rclone-test-ranefov7divo" of all files
2025/08/06 21:57:37 DEBUG : A1/B1/C1/one: Deleting (id "4_zbf92cd23603d8b1b9f880e1b_f1129c9a250d2ee61_d20250806_m215736_c001_v0001146_t0058_u01754517456492")
    fstest.go:247: Filtering empty directory "A2"
    fstest.go:247: Filtering empty directory "A1/B2"
    fstest.go:247: Filtering empty directory "A1/B2/C2"
    fstest.go:247: Filtering empty directory "A3"
    fstest.go:247: Filtering empty directory "A3/B3"
    fstest.go:247: Filtering empty directory "A3/B3/C4"
2025/08/06 21:57:38 INFO  : B2 bucket rclone-test-ranefov7divo: cleaning bucket "rclone-test-ranefov7divo" of all files
2025/08/06 21:57:39 DEBUG : A1/two: Deleting (id "4_zbf92cd23603d8b1b9f880e1b_f1129c9a250d2ee63_d20250806_m215736_c001_v0001146_t0059_u01754517456859")
2025/08/06 21:57:39 DEBUG : B2 bucket rclone-test-ranefov7divo: Purge remote
2025/08/06 21:57:39 INFO  : B2 bucket rclone-test-ranefov7divo: cleaning bucket "rclone-test-ranefov7divo" of all files
2025/08/06 21:57:40 NOTICE: purge failed: directory not found
--- PASS: TestPurge (5.59s)
=== RUN   TestRmdirsNoLeaveRoot
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
2025/08/06 21:57:40 INFO  : A2: Making directory
2025/08/06 21:57:40 INFO  : A1/B2: Making directory
2025/08/06 21:57:40 INFO  : A1/B2/C2: Making directory
2025/08/06 21:57:40 INFO  : A1/B1/C3: Making directory
2025/08/06 21:57:40 INFO  : A3: Making directory
2025/08/06 21:57:40 INFO  : A3/B3: Making directory
2025/08/06 21:57:40 INFO  : A3/B3/C4: Making directory
    fstest.go:247: Filtering empty directory "A2"
    fstest.go:247: Filtering empty directory "A1/B2"
    fstest.go:247: Filtering empty directory "A1/B2/C2"
    fstest.go:247: Filtering empty directory "A1/B1/C3"
    fstest.go:247: Filtering empty directory "A3"
    fstest.go:247: Filtering empty directory "A3/B3"
    fstest.go:247: Filtering empty directory "A3/B3/C4"
2025/08/06 21:57:42 DEBUG : removing 1 level 3 directories
2025/08/06 21:57:42 INFO  : A3/B3/C4: Removing directory
    fstest.go:247: Filtering empty directory "A2"
    fstest.go:247: Filtering empty directory "A1/B2"
    fstest.go:247: Filtering empty directory "A1/B2/C2"
    fstest.go:247: Filtering empty directory "A1/B1/C3"
    fstest.go:247: Filtering empty directory "A3"
    fstest.go:247: Filtering empty directory "A3/B3"
2025/08/06 21:57:44 DEBUG : removing 1 level 0 directories
2025/08/06 21:57:44 INFO  : B2 bucket rclone-test-tuyayoq4pija: Removing directory
2025/08/06 21:57:44 ERROR : Failed to rmdir: failed to delete bucket: Cannot delete non-empty bucket (400 cannot_delete_non_empty_bucket)
    operations_test.go:728: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:728
        	Error:      	Received unexpected error:
        	            	failed to remove directories: failed to delete bucket: Cannot delete non-empty bucket (400 cannot_delete_non_empty_bucket)
        	Test:       	TestRmdirsNoLeaveRoot
--- FAIL: TestRmdirsNoLeaveRoot (5.28s)
=== RUN   TestRmdirsLeaveRoot
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
2025/08/06 21:57:45 INFO  : A1: Making directory
2025/08/06 21:57:45 INFO  : A1/B1: Making directory
2025/08/06 21:57:45 INFO  : A1/B1/C1: Making directory
    fstest.go:247: Filtering empty directory "A1"
    fstest.go:247: Filtering empty directory "A1/B1"
    fstest.go:247: Filtering empty directory "A1/B1/C1"
    fstest.go:247: Filtering empty directory "A1"
--- PASS: TestRmdirsLeaveRoot (0.94s)
=== RUN   TestRmdirsWithFilter
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
2025/08/06 21:57:46 INFO  : A1: Making directory
2025/08/06 21:57:46 INFO  : A1/B1: Making directory
2025/08/06 21:57:46 INFO  : A1/B1/C1: Making directory
    fstest.go:247: Filtering empty directory "A1"
    fstest.go:247: Filtering empty directory "A1/B1"
    fstest.go:247: Filtering empty directory "A1/B1/C1"
    fstest.go:247: Filtering empty directory "A1"
--- PASS: TestRmdirsWithFilter (0.94s)
=== RUN   TestCopyURL
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
2025/08/06 21:57:48 DEBUG : filename.txt: File name found in url
2025/08/06 21:57:48 DEBUG : headerfilename.txt: filename found in Content-Disposition header.
--- PASS: TestCopyURL (3.58s)
=== RUN   TestCopyURLToWriter
--- PASS: TestCopyURLToWriter (0.00s)
=== RUN   TestMoveFile
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
2025/08/06 21:57:51 DEBUG : file1: Need to transfer - File not found at Destination
2025/08/06 21:57:51 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK
2025/08/06 21:57:51 INFO  : file1: Copied (new) to: sub/file2
2025/08/06 21:57:51 INFO  : file1: Deleted
2025/08/06 21:57:51 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2025/08/06 21:57:51 DEBUG : file1: Unchanged skipping
2025/08/06 21:57:51 INFO  : file1: Deleted
2025/08/06 21:57:52 DEBUG : B2 bucket rclone-test-tuyayoq4pija: don't need to copy/move sub/file2, it is already at target location
--- PASS: TestMoveFile (2.07s)
=== RUN   TestMoveFileWithIgnoreExisting
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
2025/08/06 21:57:53 DEBUG : file1: Need to transfer - File not found at Destination
2025/08/06 21:57:53 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK
2025/08/06 21:57:53 INFO  : file1: Copied (new)
2025/08/06 21:57:53 INFO  : file1: Deleted
2025/08/06 21:57:54 DEBUG : file1: Destination exists, skipping
2025/08/06 21:57:54 DEBUG : file1: Not removing source file as destination file exists and --ignore-existing is set
--- PASS: TestMoveFileWithIgnoreExisting (1.88s)
=== RUN   TestCaseInsensitiveMoveFile
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
--- PASS: TestCaseInsensitiveMoveFile (0.38s)
=== RUN   TestCaseInsensitiveMoveFileDryRun
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
--- PASS: TestCaseInsensitiveMoveFileDryRun (0.38s)
=== RUN   TestMoveFileBackupDir
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
2025/08/06 21:57:56 DEBUG : Creating backend with remote "TestB2:rclone-test-tuyayoq4pija/backup"
2025/08/06 21:57:57 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18)
2025/08/06 21:57:58 DEBUG : dst/file1: sha1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK
2025/08/06 21:57:58 INFO  : dst/file1: Copied (server-side copy)
2025/08/06 21:57:58 INFO  : dst/file1: Deleted
2025/08/06 21:57:59 DEBUG : dst/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK
2025/08/06 21:57:59 INFO  : dst/file1: Copied (new)
2025/08/06 21:57:59 INFO  : dst/file1: Deleted
--- PASS: TestMoveFileBackupDir (5.03s)
=== RUN   TestSameConfig
--- PASS: TestSameConfig (0.00s)
=== RUN   TestSame
--- PASS: TestSame (0.00s)
=== RUN   TestOverlappingFilterCheckWithoutFilter
--- PASS: TestOverlappingFilterCheckWithoutFilter (0.00s)
=== RUN   TestOverlappingFilterCheckWithFilter
--- PASS: TestOverlappingFilterCheckWithFilter (0.00s)
=== RUN   TestListFormat
--- PASS: TestListFormat (0.00s)
=== RUN   TestDirMove
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
2025/08/06 21:58:03 INFO  : A1/B2: Making directory
2025/08/06 21:58:03 INFO  : A1/B1/C3: Making directory
    fstest.go:247: Filtering empty directory "A1/B2"
    fstest.go:247: Filtering empty directory "A1/B1/C3"
2025/08/06 21:58:04 DEBUG : A1/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK
2025/08/06 21:58:04 INFO  : A1/B1/three: Copied (server-side copy) to: A2/B1/three
2025/08/06 21:58:05 DEBUG : A1/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK
2025/08/06 21:58:05 INFO  : A1/one: Copied (server-side copy) to: A2/one
2025/08/06 21:58:05 INFO  : A1/B1/three: Deleted
2025/08/06 21:58:05 DEBUG : A1/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK
2025/08/06 21:58:05 INFO  : A1/B1/C1/four: Copied (server-side copy) to: A2/B1/C1/four
2025/08/06 21:58:05 DEBUG : A1/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK
2025/08/06 21:58:05 INFO  : A1/two: Copied (server-side copy) to: A2/two
2025/08/06 21:58:05 INFO  : A1/one: Deleted
2025/08/06 21:58:05 INFO  : A1/B1/C1/four: Deleted
2025/08/06 21:58:05 DEBUG : A1/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK
2025/08/06 21:58:05 INFO  : A1/B1/C2/five: Copied (server-side copy) to: A2/B1/C2/five
2025/08/06 21:58:05 INFO  : A1/two: Deleted
2025/08/06 21:58:05 INFO  : A1/B1/C2/five: Deleted
    fstest.go:247: Filtering empty directory "A2/B2"
    fstest.go:247: Filtering empty directory "A2/B1/C3"
2025/08/06 21:58:06 DEBUG : A2/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK
2025/08/06 21:58:06 INFO  : A2/B1/C1/four: Copied (server-side copy) to: A3/B1/C1/four
2025/08/06 21:58:06 DEBUG : A2/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK
2025/08/06 21:58:06 INFO  : A2/one: Copied (server-side copy) to: A3/one
2025/08/06 21:58:06 DEBUG : A2/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK
2025/08/06 21:58:06 INFO  : A2/B1/C2/five: Copied (server-side copy) to: A3/B1/C2/five
2025/08/06 21:58:06 INFO  : A2/B1/C1/four: Deleted
2025/08/06 21:58:06 INFO  : A2/one: Deleted
2025/08/06 21:58:06 DEBUG : A2/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK
2025/08/06 21:58:06 INFO  : A2/two: Copied (server-side copy) to: A3/two
2025/08/06 21:58:06 INFO  : A2/B1/C2/five: Deleted
2025/08/06 21:58:06 DEBUG : A2/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK
2025/08/06 21:58:06 INFO  : A2/B1/three: Copied (server-side copy) to: A3/B1/three
2025/08/06 21:58:06 INFO  : A2/two: Deleted
2025/08/06 21:58:06 INFO  : A2/B1/three: Deleted
    fstest.go:247: Filtering empty directory "A3/B2"
    fstest.go:247: Filtering empty directory "A3/B1/C3"
2025/08/06 21:58:07 INFO  : B2 bucket rclone-test-tuyayoq4pija: Can't DirMove - falling back to file moves: can't move directory - incompatible remotes
2025/08/06 21:58:07 DEBUG : A3/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK
2025/08/06 21:58:07 INFO  : A3/B1/C2/five: Copied (server-side copy) to: A4/B1/C2/five
2025/08/06 21:58:07 DEBUG : A3/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK
2025/08/06 21:58:07 INFO  : A3/B1/three: Copied (server-side copy) to: A4/B1/three
2025/08/06 21:58:07 DEBUG : A3/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK
2025/08/06 21:58:07 INFO  : A3/two: Copied (server-side copy) to: A4/two
2025/08/06 21:58:07 INFO  : A3/B1/C2/five: Deleted
2025/08/06 21:58:07 DEBUG : A3/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK
2025/08/06 21:58:07 INFO  : A3/one: Copied (server-side copy) to: A4/one
2025/08/06 21:58:07 INFO  : A3/B1/three: Deleted
2025/08/06 21:58:08 DEBUG : A3/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK
2025/08/06 21:58:08 INFO  : A3/B1/C1/four: Copied (server-side copy) to: A4/B1/C1/four
2025/08/06 21:58:08 INFO  : A3/two: Deleted
2025/08/06 21:58:08 INFO  : A3/one: Deleted
2025/08/06 21:58:08 INFO  : A3/B1/C1/four: Deleted
    fstest.go:247: Filtering empty directory "A4/B2"
    fstest.go:247: Filtering empty directory "A4/B1/C3"
--- PASS: TestDirMove (9.10s)
=== RUN   TestGetFsInfo
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
--- PASS: TestGetFsInfo (0.37s)
=== RUN   TestRcat
=== RUN   TestRcat/withChecksum=false,ignoreChecksum=false
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
2025/08/06 21:58:10 DEBUG : B2 bucket rclone-test-tuyayoq4pija: File to upload is small (34 bytes), uploading instead of streaming
2025/08/06 21:58:11 DEBUG : no_checksum_small_file_from_pipe: sha1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK
2025/08/06 21:58:11 DEBUG : no_checksum_small_file_from_pipe: Size and sha1 of src and dst objects identical
2025/08/06 21:58:11 DEBUG : no_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload.
2025/08/06 21:58:12 DEBUG : no_checksum_big_file_from_pipe: sha1 = d0080f73aa8b494570ad63df1beb25c01ea9eea2 OK
2025/08/06 21:58:12 DEBUG : no_checksum_big_file_from_pipe: Size and sha1 of src and dst objects identical
=== RUN   TestRcat/withChecksum=true,ignoreChecksum=false
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
2025/08/06 21:58:13 DEBUG : B2 bucket rclone-test-tuyayoq4pija: File to upload is small (34 bytes), uploading instead of streaming
2025/08/06 21:58:14 DEBUG : with_checksum_small_file_from_pipe: sha1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK
2025/08/06 21:58:14 DEBUG : with_checksum_small_file_from_pipe: Size and sha1 of src and dst objects identical
2025/08/06 21:58:14 DEBUG : with_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload.
2025/08/06 21:58:15 DEBUG : with_checksum_big_file_from_pipe: sha1 = d0080f73aa8b494570ad63df1beb25c01ea9eea2 OK
2025/08/06 21:58:15 DEBUG : with_checksum_big_file_from_pipe: Size and sha1 of src and dst objects identical
=== RUN   TestRcat/withChecksum=false,ignoreChecksum=true
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
2025/08/06 21:58:16 DEBUG : B2 bucket rclone-test-tuyayoq4pija: File to upload is small (34 bytes), uploading instead of streaming
2025/08/06 21:58:16 DEBUG : ignore_checksum_small_file_from_pipe: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2025/08/06 21:58:16 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload.
2025/08/06 21:58:17 DEBUG : ignore_checksum_big_file_from_pipe: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
=== RUN   TestRcat/withChecksum=true,ignoreChecksum=true
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
2025/08/06 21:58:18 DEBUG : B2 bucket rclone-test-tuyayoq4pija: File to upload is small (34 bytes), uploading instead of streaming
2025/08/06 21:58:19 DEBUG : ignore_checksum_small_file_from_pipe: Src hash empty - aborting Dst hash check
2025/08/06 21:58:19 DEBUG : ignore_checksum_small_file_from_pipe: Size of src and dst objects identical
2025/08/06 21:58:19 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload.
2025/08/06 21:58:20 DEBUG : ignore_checksum_big_file_from_pipe: Src hash empty - aborting Dst hash check
2025/08/06 21:58:20 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical
--- PASS: TestRcat (11.84s)
    --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (3.08s)
    --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (2.91s)
    --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (2.76s)
    --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (3.08s)
=== RUN   TestRcatMetadata
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
    operations_test.go:1532: Skipping as destination doesn't support user metadata
--- SKIP: TestRcatMetadata (0.37s)
=== RUN   TestRcatSize
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
2025/08/06 21:58:22 DEBUG : B2 bucket rclone-test-tuyayoq4pija: File to upload is small (60 bytes), uploading instead of streaming
2025/08/06 21:58:23 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK
2025/08/06 21:58:23 DEBUG : potato2: Size and sha1 of src and dst objects identical
--- PASS: TestRcatSize (2.39s)
=== RUN   TestRcatSizeMetadata
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
    operations_test.go:1613: Skipping as destination doesn't support user metadata
--- SKIP: TestRcatSizeMetadata (0.37s)
=== RUN   TestTouchDir
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
2025/08/06 21:58:27 DEBUG : B2 bucket rclone-test-tuyayoq4pija: Touching "sub dir/potato3"
2025/08/06 21:58:27 DEBUG : B2 bucket rclone-test-tuyayoq4pija: Touching "empty space"
2025/08/06 21:58:27 DEBUG : B2 bucket rclone-test-tuyayoq4pija: Touching "potato2"
--- PASS: TestTouchDir (4.23s)
=== RUN   TestMkdirMetadata
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
    operations_test.go:1703: Skipping test as remote does not support MkdirMetadata
--- SKIP: TestMkdirMetadata (0.38s)
=== RUN   TestMkdirModTime
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
    operations_test.go:1722: Skipping test as remote does not support DirSetModTime or MkdirMetadata
--- SKIP: TestMkdirModTime (0.43s)
=== RUN   TestCopyDirMetadata
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
    operations_test.go:1743: Skipping test as remote does not support WriteDirMetadata or MkdirMetadata
--- SKIP: TestCopyDirMetadata (0.38s)
=== RUN   TestSetDirModTime
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
    operations_test.go:1779: Skipping test as remote does not support DirSetModTime or WriteDirSetModTime
--- SKIP: TestSetDirModTime (0.37s)
=== RUN   TestDirsEqual
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
    operations_test.go:1823: Skipping test as remote does not support WriteDirMetadata or MkdirMetadata
--- SKIP: TestDirsEqual (0.37s)
=== RUN   TestRemoveExisting
    run.go:180: Remote "B2 bucket rclone-test-tuyayoq4pija", Local "Local file system at /tmp/rclone710753555", Modify Window "1ms"
    operations_test.go:1892: Skipping as remote can't Move
--- SKIP: TestRemoveExisting (0.37s)
=== RUN   TestRcAbout
    rc_test.go:30: Skipping test on non local remote
--- SKIP: TestRcAbout (0.00s)
=== RUN   TestRcCleanup
    rc_test.go:30: Skipping test on non local remote
--- SKIP: TestRcCleanup (0.00s)
=== RUN   TestRcCopyfile
    rc_test.go:30: Skipping test on non local remote
--- SKIP: TestRcCopyfile (0.00s)
=== RUN   TestRcCopyurl
    rc_test.go:30: Skipping test on non local remote
--- SKIP: TestRcCopyurl (0.00s)
=== RUN   TestRcDelete
    rc_test.go:30: Skipping test on non local remote
--- SKIP: TestRcDelete (0.00s)
=== RUN   TestRcDeletefile
    rc_test.go:30: Skipping test on non local remote
--- SKIP: TestRcDeletefile (0.00s)
=== RUN   TestRcList
    rc_test.go:30: Skipping test on non local remote
--- SKIP: TestRcList (0.00s)
=== RUN   TestRcStat
    rc_test.go:30: Skipping test on non local remote
--- SKIP: TestRcStat (0.00s)
=== RUN   TestRcSetTier
    rc_test.go:30: Skipping test on non local remote
--- SKIP: TestRcSetTier (0.00s)
=== RUN   TestRcSetTierFile
    rc_test.go:30: Skipping test on non local remote
--- SKIP: TestRcSetTierFile (0.00s)
=== RUN   TestRcMkdir
    rc_test.go:30: Skipping test on non local remote
--- SKIP: TestRcMkdir (0.00s)
=== RUN   TestRcMovefile
    rc_test.go:30: Skipping test on non local remote
--- SKIP: TestRcMovefile (0.00s)
=== RUN   TestRcPurge
    rc_test.go:30: Skipping test on non local remote
--- SKIP: TestRcPurge (0.00s)
=== RUN   TestRcRmdir
    rc_test.go:30: Skipping test on non local remote
--- SKIP: TestRcRmdir (0.00s)
=== RUN   TestRcRmdirs
    rc_test.go:30: Skipping test on non local remote
--- SKIP: TestRcRmdirs (0.00s)
=== RUN   TestRcSize
    rc_test.go:30: Skipping test on non local remote
--- SKIP: TestRcSize (0.00s)
=== RUN   TestRcPublicLink
    rc_test.go:30: Skipping test on non local remote
--- SKIP: TestRcPublicLink (0.00s)
=== RUN   TestRcFsInfo
    rc_test.go:30: Skipping test on non local remote
--- SKIP: TestRcFsInfo (0.00s)
=== RUN   TestUploadFile
    rc_test.go:30: Skipping test on non local remote
--- SKIP: TestUploadFile (0.00s)
=== RUN   TestRcCommand
    rc_test.go:30: Skipping test on non local remote
--- SKIP: TestRcCommand (0.00s)
=== RUN   TestRcDu
    rc_test.go:30: Skipping test on non local remote
--- SKIP: TestRcDu (0.00s)
=== RUN   TestRcCheck
    rc_test.go:30: Skipping test on non local remote
--- SKIP: TestRcCheck (0.00s)
=== RUN   TestRcHashsum
    rc_test.go:30: Skipping test on non local remote
--- SKIP: TestRcHashsum (0.00s)
=== RUN   TestRcHashsumFile
    rc_test.go:30: Skipping test on non local remote
--- SKIP: TestRcHashsumFile (0.00s)
FAIL
2025/08/06 21:58:31 DEBUG : B2 bucket rclone-test-tuyayoq4pija: Purge remote
2025/08/06 21:58:31 INFO  : B2 bucket rclone-test-tuyayoq4pija: cleaning bucket "rclone-test-tuyayoq4pija" of all files
2025/08/06 21:58:32 DEBUG : A1/B1/C1/four: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f401bbe58f4370bf4_d20250806_m215805_c001_v7007000_t0000_u01754517485319")
2025/08/06 21:58:32 DEBUG : A1/B1/C1/four: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327ea2_d20250806_m215802_c001_v0001106_t0004_u01754517482634")
2025/08/06 21:58:32 DEBUG : A1/B1/C1/one: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f4086dd0977ead5fe_d20250806_m215744_c001_v7007000_t0000_u01754517464141")
2025/08/06 21:58:32 DEBUG : A1/B1/C1/one: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327e76_d20250806_m215740_c001_v0001106_t0018_u01754517460545")
2025/08/06 21:58:32 DEBUG : A1/B1/C2/five: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f412dc033f4cbb6d1_d20250806_m215805_c001_v7007000_t0000_u01754517485554")
2025/08/06 21:58:32 DEBUG : A1/B1/C2/five: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327ea4_d20250806_m215803_c001_v0001106_t0042_u01754517483048")
2025/08/06 21:58:32 DEBUG : A1/B1/three: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f4020b5c3d3c1cac6_d20250806_m215805_c001_v7007000_t0000_u01754517485124")
2025/08/06 21:58:32 DEBUG : A1/B1/three: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327ea0_d20250806_m215802_c001_v0001106_t0000_u01754517482191")
2025/08/06 21:58:32 DEBUG : A1/one: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f4086dd0977ead629_d20250806_m215805_c001_v7007000_t0000_u01754517485238")
2025/08/06 21:58:32 DEBUG : A1/one: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327e9a_d20250806_m215800_c001_v0001106_t0033_u01754517480661")
2025/08/06 21:58:32 DEBUG : A1/two: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f4182b5d0435c8c3e_d20250806_m215805_c001_v7007000_t0000_u01754517485369")
2025/08/06 21:58:32 DEBUG : A1/two: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327e9c_d20250806_m215801_c001_v0001106_t0051_u01754517481203")
2025/08/06 21:58:32 DEBUG : A1/two: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f4086dd0977ead600_d20250806_m215744_c001_v7007000_t0000_u01754517464506")
2025/08/06 21:58:32 DEBUG : A1/two: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327e78_d20250806_m215741_c001_v0001106_t0025_u01754517461003")
2025/08/06 21:58:32 DEBUG : A2/B1/C1/four: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f412dc033f4cbb6d9_d20250806_m215806_c001_v7007000_t0000_u01754517486533")
2025/08/06 21:58:32 DEBUG : A2/B1/C1/four: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114b0c21173b4a84_d20250806_m215805_c001_v0001183_t0052_u01754517485117")
2025/08/06 21:58:32 DEBUG : A2/B1/C2/five: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f4182b5d0435c8c45_d20250806_m215806_c001_v7007000_t0000_u01754517486642")
2025/08/06 21:58:32 DEBUG : A2/B1/C2/five: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f1146bd3920afd950_d20250806_m215805_c001_v0001176_t0013_u01754517485126")
2025/08/06 21:58:32 DEBUG : A2/B1/three: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f401bbe58f4370bfb_d20250806_m215806_c001_v7007000_t0000_u01754517486865")
2025/08/06 21:58:33 DEBUG : A2/B1/three: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f102b6bed3d4cc2f0_d20250806_m215804_c001_v0001178_t0022_u01754517484728")
2025/08/06 21:58:33 DEBUG : A2/one: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f4086dd0977ead62b_d20250806_m215806_c001_v7007000_t0000_u01754517486589")
2025/08/06 21:58:33 DEBUG : A2/one: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f10865bdcaff4034f_d20250806_m215804_c001_v0001120_t0029_u01754517484968")
2025/08/06 21:58:33 DEBUG : A2/two: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f4020b5c3d3c1cad3_d20250806_m215806_c001_v7007000_t0000_u01754517486779")
2025/08/06 21:58:33 DEBUG : A2/two: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f1171f67967638c7b_d20250806_m215805_c001_v0001172_t0013_u01754517485101")
2025/08/06 21:58:33 DEBUG : A3/B1/C1/four: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f401bbe58f4370bfe_d20250806_m215808_c001_v7007000_t0000_u01754517488092")
2025/08/06 21:58:33 DEBUG : A3/B1/C1/four: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f10053b33aa0c56e0_d20250806_m215806_c001_v0001183_t0000_u01754517486332")
2025/08/06 21:58:33 DEBUG : A3/B1/C2/five: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f4020b5c3d3c1cadd_d20250806_m215807_c001_v7007000_t0000_u01754517487856")
2025/08/06 21:58:33 DEBUG : A3/B1/C2/five: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f11378b9b6f244cbe_d20250806_m215806_c001_v0001093_t0021_u01754517486343")
2025/08/06 21:58:33 DEBUG : A3/B1/three: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f4182b5d0435c8c4a_d20250806_m215807_c001_v7007000_t0000_u01754517487882")
2025/08/06 21:58:33 DEBUG : A3/B1/three: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f11097bbd7b6b974b_d20250806_m215806_c001_v0001113_t0048_u01754517486347")
2025/08/06 21:58:33 DEBUG : A3/one: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f4086dd0977ead633_d20250806_m215808_c001_v7007000_t0000_u01754517488050")
2025/08/06 21:58:33 DEBUG : A3/one: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f1022034b593b2210_d20250806_m215806_c001_v0001184_t0032_u01754517486367")
2025/08/06 21:58:33 DEBUG : A3/two: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f412dc033f4cbb6eb_d20250806_m215808_c001_v7007000_t0000_u01754517488014")
2025/08/06 21:58:33 DEBUG : A3/two: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f1137148f38cce5db_d20250806_m215806_c001_v0001044_t0005_u01754517486372")
2025/08/06 21:58:33 DEBUG : A4/B1/C1/four: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f401bbe58f4370bff_d20250806_m215808_c001_v7007000_t0000_u01754517488659")
2025/08/06 21:58:33 DEBUG : A4/B1/C1/four: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f103036f96f71a022_d20250806_m215807_c001_v0001102_t0041_u01754517487643")
2025/08/06 21:58:33 DEBUG : A4/B1/C2/five: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f401bbe58f4370c00_d20250806_m215808_c001_v7007000_t0000_u01754517488842")
2025/08/06 21:58:33 DEBUG : A4/B1/C2/five: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f119cd515deb6c28d_d20250806_m215807_c001_v0001183_t0034_u01754517487657")
2025/08/06 21:58:33 DEBUG : A4/B1/three: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f401bbe58f4370c01_d20250806_m215809_c001_v7007000_t0000_u01754517489028")
2025/08/06 21:58:33 DEBUG : A4/B1/three: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f10514a789bb9776a_d20250806_m215807_c001_v0001177_t0011_u01754517487665")
2025/08/06 21:58:33 DEBUG : A4/one: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f401bbe58f4370c02_d20250806_m215809_c001_v7007000_t0000_u01754517489212")
2025/08/06 21:58:33 DEBUG : A4/one: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f1187ce9181210430_d20250806_m215807_c001_v0001041_t0041_u01754517487693")
2025/08/06 21:58:34 DEBUG : A4/two: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f401bbe58f4370c03_d20250806_m215809_c001_v7007000_t0000_u01754517489394")
2025/08/06 21:58:34 DEBUG : A4/two: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f103498b3fc6a20fe_d20250806_m215807_c001_v0001098_t0009_u01754517487684")
2025/08/06 21:58:34 DEBUG : BackupDir/one: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f412dc033f4cbaf19_d20250806_m215617_c001_v7007000_t0000_u01754517377996")
2025/08/06 21:58:34 DEBUG : BackupDir/one: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f10005682f1932232_d20250806_m215611_c001_v0001106_t0016_u01754517371976")
2025/08/06 21:58:34 DEBUG : CompareDest/one: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f412dc033f4cbae22_d20250806_m215600_c001_v7007000_t0000_u01754517360983")
2025/08/06 21:58:34 DEBUG : CompareDest/one: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327dbb_d20250806_m215555_c001_v0001106_t0055_u01754517355358")
2025/08/06 21:58:34 DEBUG : CompareDest/two: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f412dc033f4cbae25_d20250806_m215601_c001_v7007000_t0000_u01754517361165")
2025/08/06 21:58:34 DEBUG : CompareDest/two: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327dc3_d20250806_m215556_c001_v0001106_t0044_u01754517356638")
2025/08/06 21:58:34 DEBUG : CopyDest/one: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f412dc033f4cbaf1d_d20250806_m215618_c001_v7007000_t0000_u01754517378177")
2025/08/06 21:58:34 DEBUG : CopyDest/one: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327dd1_d20250806_m215608_c001_v0001106_t0010_u01754517368881")
2025/08/06 21:58:34 DEBUG : CopyDest/three: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f412dc033f4cbaf1f_d20250806_m215618_c001_v7007000_t0000_u01754517378361")
2025/08/06 21:58:34 DEBUG : CopyDest/three: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327ddb_d20250806_m215615_c001_v0001106_t0021_u01754517375630")
2025/08/06 21:58:34 DEBUG : CopyDest/two: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f412dc033f4cbaf21_d20250806_m215618_c001_v7007000_t0000_u01754517378541")
2025/08/06 21:58:34 DEBUG : CopyDest/two: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327dd7_d20250806_m215613_c001_v0001106_t0028_u01754517373782")
2025/08/06 21:58:34 DEBUG : ReadFile: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f418ed661d1afb71c_d20250806_m215728_c001_v7007000_t0000_u01754517448986")
2025/08/06 21:58:34 DEBUG : ReadFile: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327e6a_d20250806_m215727_c001_v0001106_t0042_u01754517447323")
2025/08/06 21:58:34 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f412dc033f4cbaf71_d20250806_m215623_c001_v7007000_t0000_u01754517383630")
2025/08/06 21:58:34 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327de0_d20250806_m215620_c001_v0001106_t0024_u01754517380717")
2025/08/06 21:58:34 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f412dc033f4cbaf72_d20250806_m215623_c001_v7007000_t0000_u01754517383814")
2025/08/06 21:58:34 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327de4_d20250806_m215622_c001_v0001106_t0057_u01754517382733")
2025/08/06 21:58:34 DEBUG : a.txt: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f409f11bb014a06e8_d20250806_m215645_c001_v7007000_t0000_u01754517405681")
2025/08/06 21:58:35 DEBUG : a.txt: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327e07_d20250806_m215639_c001_v0001106_t0048_u01754517399443")
2025/08/06 21:58:35 DEBUG : a.txt: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f409f11bb014a0674_d20250806_m215637_c001_v7007000_t0000_u01754517397970")
2025/08/06 21:58:35 DEBUG : a.txt: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327df5_d20250806_m215631_c001_v0001106_t0004_u01754517391537")
2025/08/06 21:58:35 DEBUG : also/one: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f412dc033f4cbafc9_d20250806_m215628_c001_v7007000_t0000_u01754517388629")
2025/08/06 21:58:35 DEBUG : also/one: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327dea_d20250806_m215626_c001_v0001106_t0047_u01754517386451")
2025/08/06 21:58:35 DEBUG : another: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f412dc033f4cbafd3_d20250806_m215629_c001_v7007000_t0000_u01754517389176")
2025/08/06 21:58:35 DEBUG : another: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327dec_d20250806_m215627_c001_v0001106_t0000_u01754517387050")
2025/08/06 21:58:35 DEBUG : backup/dst/file1: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f4086dd0977ead620_d20250806_m215800_c001_v7007000_t0000_u01754517480107")
2025/08/06 21:58:35 DEBUG : backup/dst/file1: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f10153059d3bc7f02_d20250806_m215758_c001_v0001183_t0050_u01754517478662")
2025/08/06 21:58:35 DEBUG : backup/dst/file1: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f412dc033f4cbadbe_d20250806_m215548_c001_v7007000_t0000_u01754517348709")
2025/08/06 21:58:35 DEBUG : backup/dst/file1: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f10913010e95eb094_d20250806_m215547_c001_v0001041_t0026_u01754517347176")
2025/08/06 21:58:35 DEBUG : data/banana: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f4210f345730b42ef_d20250806_m215452_c001_v7007000_t0000_u01754517292553")
2025/08/06 21:58:35 DEBUG : data/banana: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327d7c_d20250806_m215450_c001_v0001106_t0024_u01754517290043")
2025/08/06 21:58:35 DEBUG : data/banana: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327d5d_d20250806_m215438_c001_v0001106_t0005_u01754517278843")
2025/08/06 21:58:35 DEBUG : data/potato: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f4210f345730b42f6_d20250806_m215452_c001_v7007000_t0000_u01754517292737")
2025/08/06 21:58:35 DEBUG : data/potato: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327d7e_d20250806_m215450_c001_v0001106_t0017_u01754517290465")
2025/08/06 21:58:35 DEBUG : data/potato: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327d69_d20250806_m215441_c001_v0001106_t0046_u01754517281335")
2025/08/06 21:58:35 DEBUG : dst/file1: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f4086dd0977ead621_d20250806_m215800_c001_v7007000_t0000_u01754517480293")
2025/08/06 21:58:35 DEBUG : dst/file1: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327e96_d20250806_m215759_c001_v0001106_t0055_u01754517479055")
2025/08/06 21:58:35 DEBUG : dst/file1: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f4086dd0977ead61c_d20250806_m215758_c001_v7007000_t0000_u01754517478877")
2025/08/06 21:58:36 DEBUG : dst/file1: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327e94_d20250806_m215755_c001_v0001106_t0043_u01754517475637")
2025/08/06 21:58:36 DEBUG : dst/file1: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f412dc033f4cbadc1_d20250806_m215548_c001_v7007000_t0000_u01754517348891")
2025/08/06 21:58:36 DEBUG : dst/file1: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327db1_d20250806_m215547_c001_v0001106_t0012_u01754517347937")
2025/08/06 21:58:36 DEBUG : dst/file1: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f412dc033f4cbadb3_d20250806_m215547_c001_v7007000_t0000_u01754517347753")
2025/08/06 21:58:36 DEBUG : dst/file1: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327dad_d20250806_m215544_c001_v0001106_t0035_u01754517344087")
2025/08/06 21:58:36 DEBUG : dst/one: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f412dc033f4cbaf24_d20250806_m215618_c001_v7007000_t0000_u01754517378723")
2025/08/06 21:58:36 DEBUG : dst/one: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f102541d19f1d387e_d20250806_m215613_c001_v0001101_t0006_u01754517373267")
2025/08/06 21:58:36 DEBUG : dst/one: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f412dc033f4cbaef6_d20250806_m215613_c001_v7007000_t0000_u01754517373065")
2025/08/06 21:58:36 DEBUG : dst/one: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327dcf_d20250806_m215608_c001_v0001106_t0059_u01754517368168")
2025/08/06 21:58:36 DEBUG : dst/one: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f1117f5cc92fa2d81_d20250806_m215607_c001_v0001042_t0015_u01754517367259")
2025/08/06 21:58:36 DEBUG : dst/one: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f1117f5cc92fa2d7d_d20250806_m215605_c001_v0001042_t0000_u01754517365941")
2025/08/06 21:58:36 DEBUG : dst/one: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f412dc033f4cbae28_d20250806_m215601_c001_v7007000_t0000_u01754517361348")
2025/08/06 21:58:36 DEBUG : dst/one: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327db8_d20250806_m215554_c001_v0001106_t0021_u01754517354786")
2025/08/06 21:58:36 DEBUG : dst/one: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f102091a7cae19406_d20250806_m215554_c001_v0001182_t0000_u01754517354044")
2025/08/06 21:58:36 DEBUG : dst/one: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f102091a7cae19402_d20250806_m215553_c001_v0001182_t0045_u01754517353118")
2025/08/06 21:58:36 DEBUG : dst/three: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f412dc033f4cbaf28_d20250806_m215618_c001_v7007000_t0000_u01754517378905")
2025/08/06 21:58:36 DEBUG : dst/three: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f1117f5cc92fa2d8b_d20250806_m215617_c001_v0001042_t0030_u01754517377113")
2025/08/06 21:58:36 DEBUG : dst/two: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f412dc033f4cbaf2c_d20250806_m215619_c001_v7007000_t0000_u01754517379086")
2025/08/06 21:58:36 DEBUG : dst/two: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f11592f91c4c213cd_d20250806_m215614_c001_v0001183_t0001_u01754517374858")
2025/08/06 21:58:36 DEBUG : dst/two: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f412dc033f4cbae2c_d20250806_m215601_c001_v7007000_t0000_u01754517361530")
2025/08/06 21:58:36 DEBUG : dst/two: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f1000bea4c8f452fe_d20250806_m215600_c001_v0001161_t0040_u01754517360159")
2025/08/06 21:58:36 DEBUG : empty space: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f401bbe58f4370c22_d20250806_m215828_c001_v7007000_t0000_u01754517508597")
2025/08/06 21:58:37 DEBUG : empty space: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f11697420b63ab0e9_d20250806_m215827_c001_v0001120_t0055_u01754517507705")
2025/08/06 21:58:37 DEBUG : empty space: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327ecb_d20250806_m215825_c001_v0001106_t0052_u01754517505537")
2025/08/06 21:58:37 DEBUG : empty space: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f409f11bb014a091f_d20250806_m215712_c001_v7007000_t0000_u01754517432820")
2025/08/06 21:58:37 DEBUG : empty space: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327e44_d20250806_m215710_c001_v0001106_t0052_u01754517430953")
2025/08/06 21:58:37 DEBUG : empty space: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f409f11bb014a090e_d20250806_m215709_c001_v7007000_t0000_u01754517429835")
2025/08/06 21:58:37 DEBUG : empty space: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327e40_d20250806_m215708_c001_v0001106_t0004_u01754517428356")
2025/08/06 21:58:37 DEBUG : empty space: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f409f11bb014a0907_d20250806_m215707_c001_v7007000_t0000_u01754517427053")
2025/08/06 21:58:37 DEBUG : empty space: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327e38_d20250806_m215706_c001_v0001106_t0021_u01754517426016")
2025/08/06 21:58:37 DEBUG : empty space: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f409f11bb014a08f1_d20250806_m215705_c001_v7007000_t0000_u01754517425054")
2025/08/06 21:58:37 DEBUG : empty space: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327e34_d20250806_m215703_c001_v0001106_t0006_u01754517423356")
2025/08/06 21:58:37 DEBUG : empty space: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f409f11bb014a08b6_d20250806_m215702_c001_v7007000_t0000_u01754517422297")
2025/08/06 21:58:37 DEBUG : empty space: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327e30_d20250806_m215701_c001_v0001106_t0057_u01754517421190")
2025/08/06 21:58:37 DEBUG : empty space: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f4210f345730b4094_d20250806_m215432_c001_v7007000_t0000_u01754517272520")
2025/08/06 21:58:37 DEBUG : empty space: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327d4b_d20250806_m215429_c001_v0001106_t0015_u01754517269050")
2025/08/06 21:58:37 DEBUG : empty space: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f4210f345730b3ff7_d20250806_m215426_c001_v7007000_t0000_u01754517266942")
2025/08/06 21:58:37 DEBUG : empty space: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327d3f_d20250806_m215422_c001_v0001106_t0052_u01754517262312")
2025/08/06 21:58:37 DEBUG : empty space: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f4210f345730b3f1c_d20250806_m215419_c001_v7007000_t0000_u01754517259450")
2025/08/06 21:58:37 DEBUG : empty space: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327d2d_d20250806_m215416_c001_v0001106_t0056_u01754517256317")
2025/08/06 21:58:37 DEBUG : file1: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f4086dd0977ead615_d20250806_m215754_c001_v7007000_t0000_u01754517474507")
2025/08/06 21:58:37 DEBUG : file1: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327e90_d20250806_m215753_c001_v0001106_t0044_u01754517473177")
2025/08/06 21:58:37 DEBUG : file1: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f4086dd0977ead60b_d20250806_m215749_c001_v7007000_t0000_u01754517469991")
2025/08/06 21:58:37 DEBUG : file1: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f414a891a05327e7e_d20250806_m215747_c001_v0001106_t0009_u01754517467887")
2025/08/06 21:58:38 DEBUG : file1: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f418ed661d1afb71f_d20250806_m215734_c001_v7007000_t0000_u01754517454041")
2025/08/06 21:58:38 DEBUG : file1: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327e6c_d20250806_m215729_c001_v0001106_t0008_u01754517449774")
2025/08/06 21:58:38 DEBUG : file1: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f409f11bb014a07fe_d20250806_m215657_c001_v7007000_t0000_u01754517417439")
2025/08/06 21:58:38 DEBUG : file1: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327e26_d20250806_m215651_c001_v0001106_t0035_u01754517411232")
2025/08/06 21:58:38 DEBUG : file1: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f409f11bb014a073b_d20250806_m215650_c001_v7007000_t0000_u01754517410683")
2025/08/06 21:58:38 DEBUG : file1: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327e1e_d20250806_m215647_c001_v0001106_t0047_u01754517407150")
2025/08/06 21:58:38 DEBUG : file2: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f4086dd0977ead60c_d20250806_m215750_c001_v7007000_t0000_u01754517470178")
2025/08/06 21:58:38 DEBUG : file2: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f414a891a05327e88_d20250806_m215749_c001_v0001106_t0009_u01754517469419")
2025/08/06 21:58:38 DEBUG : file2: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f418ed661d1afb721_d20250806_m215734_c001_v7007000_t0000_u01754517454225")
2025/08/06 21:58:38 DEBUG : file2: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327e6e_d20250806_m215730_c001_v0001106_t0004_u01754517450244")
2025/08/06 21:58:38 DEBUG : filename.txt: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f4086dd0977ead60d_d20250806_m215750_c001_v7007000_t0000_u01754517470364")
2025/08/06 21:58:38 DEBUG : filename.txt: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327e82_d20250806_m215748_c001_v0001106_t0022_u01754517468441")
2025/08/06 21:58:38 DEBUG : headerfilename.txt: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f4086dd0977ead60e_d20250806_m215750_c001_v7007000_t0000_u01754517470549")
2025/08/06 21:58:38 DEBUG : headerfilename.txt: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327e86_d20250806_m215748_c001_v0001106_t0007_u01754517468917")
2025/08/06 21:58:38 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f401bbe58f4370c11_d20250806_m215821_c001_v7007000_t0000_u01754517501421")
2025/08/06 21:58:38 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327ec1_d20250806_m215820_c001_v0001106_t0048_u01754517500007")
2025/08/06 21:58:38 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f401bbe58f4370c0c_d20250806_m215818_c001_v7007000_t0000_u01754517498338")
2025/08/06 21:58:38 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327eb9_d20250806_m215816_c001_v0001106_t0059_u01754517496900")
2025/08/06 21:58:38 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f401bbe58f4370c12_d20250806_m215821_c001_v7007000_t0000_u01754517501606")
2025/08/06 21:58:38 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327ebf_d20250806_m215819_c001_v0001106_t0046_u01754517499075")
2025/08/06 21:58:39 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f401bbe58f4370c0d_d20250806_m215818_c001_v7007000_t0000_u01754517498520")
2025/08/06 21:58:39 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327eb7_d20250806_m215816_c001_v0001106_t0059_u01754517496312")
2025/08/06 21:58:39 DEBUG : large: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f4210f345730b4f54_d20250806_m215726_c001_v7007000_t0000_u01754517446035")
2025/08/06 21:58:39 DEBUG : large: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327e66_d20250806_m215725_c001_v0001106_t0011_u01754517445223")
2025/08/06 21:58:39 DEBUG : large: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f4210f345730b4f2c_d20250806_m215723_c001_v7007000_t0000_u01754517443333")
2025/08/06 21:58:39 DEBUG : large: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327e60_d20250806_m215721_c001_v0001106_t0018_u01754517441651")
2025/08/06 21:58:39 DEBUG : large: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f418ed661d1afb666_d20250806_m215718_c001_v7007000_t0000_u01754517438890")
2025/08/06 21:58:39 DEBUG : large: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327e58_d20250806_m215718_c001_v0001106_t0013_u01754517438062")
2025/08/06 21:58:39 DEBUG : large: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f418ed661d1afb61d_d20250806_m215716_c001_v7007000_t0000_u01754517436621")
2025/08/06 21:58:39 DEBUG : large: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327e4e_d20250806_m215714_c001_v0001106_t0037_u01754517434904")
2025/08/06 21:58:39 DEBUG : medium: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f418ed661d1afb6ee_d20250806_m215726_c001_v7007000_t0000_u01754517446045")
2025/08/06 21:58:39 DEBUG : medium: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327e64_d20250806_m215724_c001_v0001106_t0059_u01754517444579")
2025/08/06 21:58:39 DEBUG : medium: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f4210f345730b4f2a_d20250806_m215722_c001_v7007000_t0000_u01754517442777")
2025/08/06 21:58:39 DEBUG : medium: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327e5e_d20250806_m215720_c001_v0001106_t0005_u01754517440935")
2025/08/06 21:58:39 DEBUG : medium: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f418ed661d1afb673_d20250806_m215719_c001_v7007000_t0000_u01754517439625")
2025/08/06 21:58:39 DEBUG : medium: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327e56_d20250806_m215717_c001_v0001106_t0038_u01754517437533")
2025/08/06 21:58:39 DEBUG : medium: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f409f11bb014a096d_d20250806_m215715_c001_v7007000_t0000_u01754517435796")
2025/08/06 21:58:39 DEBUG : medium: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327e4c_d20250806_m215714_c001_v0001106_t0024_u01754517434400")
2025/08/06 21:58:39 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f401bbe58f4370c04_d20250806_m215812_c001_v7007000_t0000_u01754517492664")
2025/08/06 21:58:39 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327ead_d20250806_m215811_c001_v0001106_t0035_u01754517491222")
2025/08/06 21:58:39 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f401bbe58f4370c05_d20250806_m215812_c001_v7007000_t0000_u01754517492847")
2025/08/06 21:58:39 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327eab_d20250806_m215810_c001_v0001106_t0031_u01754517490679")
2025/08/06 21:58:40 DEBUG : not-one: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f412dc033f4cbafd5_d20250806_m215629_c001_v7007000_t0000_u01754517389357")
2025/08/06 21:58:40 DEBUG : not-one: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327dee_d20250806_m215627_c001_v0001106_t0003_u01754517387564")
2025/08/06 21:58:40 DEBUG : one: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f412dc033f4cbafc5_d20250806_m215628_c001_v7007000_t0000_u01754517388447")
2025/08/06 21:58:40 DEBUG : one: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327de8_d20250806_m215625_c001_v0001106_t0031_u01754517385990")
2025/08/06 21:58:40 DEBUG : potato1: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f401bbe58f4370c15_d20250806_m215824_c001_v7007000_t0000_u01754517504178")
2025/08/06 21:58:40 DEBUG : potato1: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327ec3_d20250806_m215822_c001_v0001106_t0041_u01754517502343")
2025/08/06 21:58:40 DEBUG : potato2: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f401bbe58f4370c23_d20250806_m215828_c001_v7007000_t0000_u01754517508779")
2025/08/06 21:58:40 DEBUG : potato2: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114b0c21173b4ab1_d20250806_m215828_c001_v0001183_t0042_u01754517508019")
2025/08/06 21:58:40 DEBUG : potato2: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327ec9_d20250806_m215825_c001_v0001106_t0011_u01754517505102")
2025/08/06 21:58:40 DEBUG : potato2: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f401bbe58f4370c17_d20250806_m215824_c001_v7007000_t0000_u01754517504363")
2025/08/06 21:58:40 DEBUG : potato2: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327ec5_d20250806_m215822_c001_v0001106_t0035_u01754517502832")
2025/08/06 21:58:40 DEBUG : potato2: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f409f11bb014a0921_d20250806_m215713_c001_v7007000_t0000_u01754517433003")
2025/08/06 21:58:40 DEBUG : potato2: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327e42_d20250806_m215710_c001_v0001106_t0035_u01754517430386")
2025/08/06 21:58:40 DEBUG : potato2: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f409f11bb014a090f_d20250806_m215710_c001_v7007000_t0000_u01754517430019")
2025/08/06 21:58:40 DEBUG : potato2: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327e3e_d20250806_m215707_c001_v0001106_t0009_u01754517427604")
2025/08/06 21:58:40 DEBUG : potato2: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f409f11bb014a0908_d20250806_m215707_c001_v7007000_t0000_u01754517427239")
2025/08/06 21:58:40 DEBUG : potato2: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327e36_d20250806_m215705_c001_v0001106_t0013_u01754517425602")
2025/08/06 21:58:40 DEBUG : potato2: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f409f11bb014a08f4_d20250806_m215705_c001_v7007000_t0000_u01754517425238")
2025/08/06 21:58:40 DEBUG : potato2: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327e32_d20250806_m215702_c001_v0001106_t0045_u01754517422849")
2025/08/06 21:58:40 DEBUG : potato2: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f409f11bb014a08bb_d20250806_m215702_c001_v7007000_t0000_u01754517422481")
2025/08/06 21:58:40 DEBUG : potato2: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327e2e_d20250806_m215700_c001_v0001106_t0051_u01754517420353")
2025/08/06 21:58:41 DEBUG : potato2: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f4210f345730b409f_d20250806_m215432_c001_v7007000_t0000_u01754517272702")
2025/08/06 21:58:41 DEBUG : potato2: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327d4f_d20250806_m215429_c001_v0001106_t0053_u01754517269878")
2025/08/06 21:58:41 DEBUG : potato2: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f4210f345730b3ffd_d20250806_m215427_c001_v7007000_t0000_u01754517267125")
2025/08/06 21:58:41 DEBUG : potato2: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327d41_d20250806_m215423_c001_v0001106_t0023_u01754517263486")
2025/08/06 21:58:41 DEBUG : potato2: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f4210f345730b3f23_d20250806_m215419_c001_v7007000_t0000_u01754517259633")
2025/08/06 21:58:41 DEBUG : potato2: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327d2f_d20250806_m215417_c001_v0001106_t0013_u01754517257170")
2025/08/06 21:58:41 DEBUG : remotepotato: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f4210f345730b40a7_d20250806_m215432_c001_v7007000_t0000_u01754517272886")
2025/08/06 21:58:41 DEBUG : remotepotato: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327d53_d20250806_m215431_c001_v0001106_t0036_u01754517271215")
2025/08/06 21:58:41 DEBUG : remotepotato: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f4210f345730b4003_d20250806_m215427_c001_v7007000_t0000_u01754517267311")
2025/08/06 21:58:41 DEBUG : remotepotato: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327d45_d20250806_m215425_c001_v0001106_t0059_u01754517265177")
2025/08/06 21:58:41 DEBUG : remotepotato: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f4210f345730b3f28_d20250806_m215419_c001_v7007000_t0000_u01754517259869")
2025/08/06 21:58:41 DEBUG : remotepotato: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327d35_d20250806_m215418_c001_v0001106_t0026_u01754517258206")
2025/08/06 21:58:41 DEBUG : rutabaga: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f4210f345730b40b1_d20250806_m215433_c001_v7007000_t0000_u01754517273105")
2025/08/06 21:58:41 DEBUG : rutabaga: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327d47_d20250806_m215427_c001_v0001106_t0011_u01754517267865")
2025/08/06 21:58:41 DEBUG : rutabaga: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f4210f345730b4006_d20250806_m215427_c001_v7007000_t0000_u01754517267496")
2025/08/06 21:58:41 DEBUG : rutabaga: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327d39_d20250806_m215420_c001_v0001106_t0049_u01754517260420")
2025/08/06 21:58:41 DEBUG : rutabaga: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f4210f345730b3f30_d20250806_m215420_c001_v7007000_t0000_u01754517260051")
2025/08/06 21:58:41 DEBUG : rutabaga: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327d25_d20250806_m215415_c001_v0001106_t0036_u01754517255265")
2025/08/06 21:58:41 DEBUG : small: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f418ed661d1afb6fd_d20250806_m215726_c001_v7007000_t0000_u01754517446595")
2025/08/06 21:58:41 DEBUG : small: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327e62_d20250806_m215724_c001_v0001106_t0026_u01754517444062")
2025/08/06 21:58:41 DEBUG : small: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f418ed661d1afb6ac_d20250806_m215722_c001_v7007000_t0000_u01754517442762")
2025/08/06 21:58:41 DEBUG : small: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327e5c_d20250806_m215720_c001_v0001106_t0050_u01754517440365")
2025/08/06 21:58:42 DEBUG : small: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f418ed661d1afb669_d20250806_m215719_c001_v7007000_t0000_u01754517439071")
2025/08/06 21:58:42 DEBUG : small: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327e54_d20250806_m215716_c001_v0001106_t0056_u01754517436986")
2025/08/06 21:58:42 DEBUG : small: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f409f11bb014a0971_d20250806_m215716_c001_v7007000_t0000_u01754517436060")
2025/08/06 21:58:42 DEBUG : small: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327e4a_d20250806_m215713_c001_v0001106_t0044_u01754517433552")
2025/08/06 21:58:42 DEBUG : sub dir/hello world: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f409f11bb014a0870_d20250806_m215659_c001_v7007000_t0000_u01754517419988")
2025/08/06 21:58:42 DEBUG : sub dir/hello world: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327e2c_d20250806_m215658_c001_v0001106_t0009_u01754517418898")
2025/08/06 21:58:42 DEBUG : sub dir/hello world: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f409f11bb014a06ec_d20250806_m215645_c001_v7007000_t0000_u01754517405864")
2025/08/06 21:58:42 DEBUG : sub dir/hello world: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327e0c_d20250806_m215640_c001_v0001106_t0021_u01754517400451")
2025/08/06 21:58:42 DEBUG : sub dir/hello world: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f409f11bb014a0677_d20250806_m215638_c001_v7007000_t0000_u01754517398154")
2025/08/06 21:58:42 DEBUG : sub dir/hello world: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327df9_d20250806_m215632_c001_v0001106_t0036_u01754517392368")
2025/08/06 21:58:42 DEBUG : sub dir/hello world2: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f409f11bb014a06f0_d20250806_m215646_c001_v7007000_t0000_u01754517406047")
2025/08/06 21:58:42 DEBUG : sub dir/hello world2: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327e0e_d20250806_m215640_c001_v0001106_t0055_u01754517400943")
2025/08/06 21:58:42 DEBUG : sub dir/hello world2: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f409f11bb014a067a_d20250806_m215638_c001_v7007000_t0000_u01754517398344")
2025/08/06 21:58:42 DEBUG : sub dir/hello world2: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327dfb_d20250806_m215632_c001_v0001106_t0042_u01754517392895")
2025/08/06 21:58:42 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f409f11bb014a06f5_d20250806_m215646_c001_v7007000_t0000_u01754517406231")
2025/08/06 21:58:42 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327e10_d20250806_m215641_c001_v0001106_t0013_u01754517401711")
2025/08/06 21:58:42 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f409f11bb014a067e_d20250806_m215638_c001_v7007000_t0000_u01754517398526")
2025/08/06 21:58:42 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327dfd_d20250806_m215633_c001_v0001106_t0050_u01754517393495")
2025/08/06 21:58:42 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f409f11bb014a06f9_d20250806_m215646_c001_v7007000_t0000_u01754517406415")
2025/08/06 21:58:42 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327e12_d20250806_m215642_c001_v0001106_t0011_u01754517402214")
2025/08/06 21:58:42 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f409f11bb014a0681_d20250806_m215638_c001_v7007000_t0000_u01754517398710")
2025/08/06 21:58:43 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327dff_d20250806_m215634_c001_v0001106_t0007_u01754517394114")
2025/08/06 21:58:43 DEBUG : sub dir/potato3: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f401bbe58f4370c24_d20250806_m215828_c001_v7007000_t0000_u01754517508962")
2025/08/06 21:58:43 DEBUG : sub dir/potato3: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f1031ef20c4c228de_d20250806_m215827_c001_v0001161_t0007_u01754517507694")
2025/08/06 21:58:43 DEBUG : sub dir/potato3: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327ecd_d20250806_m215826_c001_v0001106_t0020_u01754517506138")
2025/08/06 21:58:43 DEBUG : sub dir/potato3: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f409f11bb014a0924_d20250806_m215713_c001_v7007000_t0000_u01754517433189")
2025/08/06 21:58:43 DEBUG : sub dir/potato3: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327e46_d20250806_m215711_c001_v0001106_t0037_u01754517431669")
2025/08/06 21:58:43 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f409f11bb014a06fe_d20250806_m215646_c001_v7007000_t0000_u01754517406601")
2025/08/06 21:58:43 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327e14_d20250806_m215642_c001_v0001106_t0022_u01754517402641")
2025/08/06 21:58:43 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f409f11bb014a0684_d20250806_m215638_c001_v7007000_t0000_u01754517398893")
2025/08/06 21:58:43 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327e03_d20250806_m215634_c001_v0001106_t0039_u01754517394512")
2025/08/06 21:58:43 DEBUG : sub/file2: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f4086dd0977ead612_d20250806_m215752_c001_v7007000_t0000_u01754517472627")
2025/08/06 21:58:43 DEBUG : sub/file2: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327e8c_d20250806_m215751_c001_v0001106_t0032_u01754517471101")
2025/08/06 21:58:43 DEBUG : sub/file2: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f409f11bb014a0806_d20250806_m215657_c001_v7007000_t0000_u01754517417623")
2025/08/06 21:58:43 DEBUG : sub/file2: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327e28_d20250806_m215651_c001_v0001106_t0047_u01754517411753")
2025/08/06 21:58:43 DEBUG : sub/file2: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f409f11bb014a073f_d20250806_m215650_c001_v7007000_t0000_u01754517410867")
2025/08/06 21:58:43 DEBUG : sub/file2: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327e20_d20250806_m215647_c001_v0001106_t0049_u01754517407721")
2025/08/06 21:58:43 DEBUG : sub/file2: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f412dc033f4cbad6e_d20250806_m215543_c001_v7007000_t0000_u01754517343360")
2025/08/06 21:58:43 DEBUG : sub/file2: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327daa_d20250806_m215541_c001_v0001106_t0051_u01754517341384")
2025/08/06 21:58:43 DEBUG : test-multithread-abort: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f4210f345730b3ede_d20250806_m215414_c001_v7007000_t0000_u01754517254332")
2025/08/06 21:58:43 DEBUG : test-multithread-abort: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327cf6_d20250806_m215332_c001_v0001106_t0008_u01754517212955")
2025/08/06 21:58:44 DEBUG : test-multithread-copy-false-201326591-2: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f401bbe58f436903c_d20250806_m214958_c001_v7007000_t0000_u01754516998121")
2025/08/06 21:58:44 DEBUG : test-multithread-copy-false-201326591-2: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327c39_d20250806_m214918_c001_v0001106_t0005_u01754516958634")
2025/08/06 21:58:44 DEBUG : test-multithread-copy-false-201326592-2: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f4182b5d0435c6aeb_d20250806_m215042_c001_v7007000_t0000_u01754517042039")
2025/08/06 21:58:44 DEBUG : test-multithread-copy-false-201326592-2: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327c61_d20250806_m215001_c001_v0001106_t0044_u01754517001018")
2025/08/06 21:58:44 DEBUG : test-multithread-copy-false-201326593-2: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f419046b64f225e5e_d20250806_m215126_c001_v7007000_t0000_u01754517086751")
2025/08/06 21:58:44 DEBUG : test-multithread-copy-false-201326593-2: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327c81_d20250806_m215045_c001_v0001106_t0055_u01754517045062")
2025/08/06 21:58:44 DEBUG : test-multithread-copy-true-201326591-2: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f4210f345730b3605_d20250806_m215211_c001_v7007000_t0000_u01754517131380")
2025/08/06 21:58:44 DEBUG : test-multithread-copy-true-201326591-2: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f218ed661d1af9d79_d20250806_m215150_c001_v0001179_t0040_u01754517110137")
2025/08/06 21:58:44 DEBUG : test-multithread-copy-true-201326592-2: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f405dae8c8bf82c34_d20250806_m215251_c001_v7007000_t0000_u01754517171739")
2025/08/06 21:58:44 DEBUG : test-multithread-copy-true-201326592-2: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f201bbe58f436f599_d20250806_m215231_c001_v0001184_t0010_u01754517151870")
2025/08/06 21:58:44 DEBUG : test-multithread-copy-true-201326593-2: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f420eba9bcbababf1_d20250806_m215331_c001_v7007000_t0000_u01754517211661")
2025/08/06 21:58:44 DEBUG : test-multithread-copy-true-201326593-2: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f209f985e1375b277_d20250806_m215312_c001_v0001184_t0051_u01754517192565")
2025/08/06 21:58:44 DEBUG : test.sum: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f4210f345730b42fd_d20250806_m215452_c001_v7007000_t0000_u01754517292921")
2025/08/06 21:58:44 DEBUG : test.sum: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327d80_d20250806_m215450_c001_v0001106_t0006_u01754517290858")
2025/08/06 21:58:44 DEBUG : test.sum: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327d78_d20250806_m215448_c001_v0001106_t0050_u01754517288476")
2025/08/06 21:58:44 DEBUG : test.sum: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327d73_d20250806_m215446_c001_v0001106_t0015_u01754517286820")
2025/08/06 21:58:44 DEBUG : test.sum: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327d71_d20250806_m215445_c001_v0001106_t0016_u01754517285276")
2025/08/06 21:58:44 DEBUG : test.sum: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327d6f_d20250806_m215443_c001_v0001106_t0042_u01754517283320")
2025/08/06 21:58:44 DEBUG : test.sum: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327d6b_d20250806_m215441_c001_v0001106_t0056_u01754517281848")
2025/08/06 21:58:44 DEBUG : test.sum: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327d5f_d20250806_m215439_c001_v0001106_t0002_u01754517279324")
2025/08/06 21:58:44 DEBUG : test.sum: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f4210f345730b40ff_d20250806_m215435_c001_v7007000_t0000_u01754517275895")
2025/08/06 21:58:44 DEBUG : test.sum: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327d59_d20250806_m215434_c001_v0001106_t0043_u01754517274838")
2025/08/06 21:58:45 DEBUG : test.sum: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327d55_d20250806_m215433_c001_v0001106_t0033_u01754517273469")
2025/08/06 21:58:45 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f401bbe58f4370c09_d20250806_m215815_c001_v7007000_t0000_u01754517495572")
2025/08/06 21:58:45 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327eb1_d20250806_m215814_c001_v0001106_t0042_u01754517494098")
2025/08/06 21:58:45 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f401bbe58f4370c0a_d20250806_m215815_c001_v7007000_t0000_u01754517495756")
2025/08/06 21:58:45 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327eaf_d20250806_m215813_c001_v0001106_t0029_u01754517493397")
2025/08/06 21:58:45 DEBUG : zend.txt: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f409f11bb014a0702_d20250806_m215646_c001_v7007000_t0000_u01754517406784")
2025/08/06 21:58:45 DEBUG : zend.txt: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327e09_d20250806_m215640_c001_v0001106_t0022_u01754517400011")
2025/08/06 21:58:45 DEBUG : zend.txt: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f409f11bb014a0687_d20250806_m215639_c001_v7007000_t0000_u01754517399079")
2025/08/06 21:58:45 DEBUG : zend.txt: Deleting (id "4_z9f921d4350cd8b1b9f880e1b_f114a891a05327df7_d20250806_m215631_c001_v0001106_t0010_u01754517391964")
"./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose" - Finished OK in 9m35.98177996s (try 1/5)