"./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -fast-list" - Starting (try 1/5)
2025/08/06 23:41:16 DEBUG : Creating backend with remote "TestB2:rclone-test-solamos7geti"
2025/08/06 23:41:16 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/08/06 23:41:17 DEBUG : Creating backend with remote "/tmp/rclone3246297378"
=== 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-solamos7geti", Local "Local file system at /tmp/rclone3246297378", Modify Window "1ms"
2025/08/06 23:41:18 DEBUG : chunksize-probe: Cancelling large file upload
=== RUN   TestMultithreadCopy/upload=false,size=201326591,streams=2
2025/08/06 23:41:44 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt
2025/08/06 23:41:44 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: write buffer set to 131072
2025/08/06 23:41:44 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2
2025/08/06 23:41:44 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: number of streams 4 was bigger than number of chunks 3
2025/08/06 23:41:44 DEBUG : test-multithread-copy-false-201326591-2: Starting multi-thread copy with 3 chunks of size 64Mi with 3 parallel streams
2025/08/06 23:41:44 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 3/3 (134217728-201326591) size 64.000Mi starting
2025/08/06 23:41:44 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 1/3 (0-67108864) size 64Mi starting
2025/08/06 23:41:44 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 2/3 (67108864-134217728) size 64Mi starting
2025/08/06 23:41:44 DEBUG : test-multithread-copy-false-201326591-2: writing chunk 2
2025/08/06 23:41:45 DEBUG : test-multithread-copy-false-201326591-2: writing chunk 0
2025/08/06 23:41:45 DEBUG : test-multithread-copy-false-201326591-2: writing chunk 1
2025/08/06 23:41:49 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 3/3 (134217728-201326591) size 64.000Mi finished
2025/08/06 23:41:49 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 1/3 (0-67108864) size 64Mi finished
2025/08/06 23:41:50 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 2/3 (67108864-134217728) size 64Mi finished
2025/08/06 23:41:50 DEBUG : test-multithread-copy-false-201326591-2: Finished multi-thread copy with 3 parts of size 64Mi
=== RUN   TestMultithreadCopy/upload=false,size=201326592,streams=2
2025/08/06 23:42:25 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt
2025/08/06 23:42:25 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: write buffer set to 131072
2025/08/06 23:42:25 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2
2025/08/06 23:42:25 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: number of streams 4 was bigger than number of chunks 3
2025/08/06 23:42:25 DEBUG : test-multithread-copy-false-201326592-2: Starting multi-thread copy with 3 chunks of size 64Mi with 3 parallel streams
2025/08/06 23:42:25 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 3/3 (134217728-201326592) size 64Mi starting
2025/08/06 23:42:25 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 1/3 (0-67108864) size 64Mi starting
2025/08/06 23:42:25 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 2/3 (67108864-134217728) size 64Mi starting
2025/08/06 23:42:25 DEBUG : test-multithread-copy-false-201326592-2: writing chunk 2
2025/08/06 23:42:25 DEBUG : test-multithread-copy-false-201326592-2: writing chunk 0
2025/08/06 23:42:25 DEBUG : test-multithread-copy-false-201326592-2: writing chunk 1
2025/08/06 23:42:29 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 3/3 (134217728-201326592) size 64Mi finished
2025/08/06 23:42:30 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 1/3 (0-67108864) size 64Mi finished
2025/08/06 23:42:30 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 2/3 (67108864-134217728) size 64Mi finished
2025/08/06 23:42:30 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 23:43:05 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt
2025/08/06 23:43:05 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: write buffer set to 131072
2025/08/06 23:43:05 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2
2025/08/06 23:43:05 DEBUG : test-multithread-copy-false-201326593-2: Starting multi-thread copy with 4 chunks of size 64Mi with 4 parallel streams
2025/08/06 23:43:05 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 4/4 (201326592-201326593) size 1 starting
2025/08/06 23:43:05 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 2/4 (67108864-134217728) size 64Mi starting
2025/08/06 23:43:05 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 1/4 (0-67108864) size 64Mi starting
2025/08/06 23:43:05 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 3/4 (134217728-201326592) size 64Mi starting
2025/08/06 23:43:06 DEBUG : test-multithread-copy-false-201326593-2: writing chunk 3
2025/08/06 23:43:06 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 4/4 (201326592-201326593) size 1 finished
2025/08/06 23:43:06 DEBUG : test-multithread-copy-false-201326593-2: writing chunk 1
2025/08/06 23:43:06 DEBUG : test-multithread-copy-false-201326593-2: writing chunk 0
2025/08/06 23:43:06 DEBUG : test-multithread-copy-false-201326593-2: writing chunk 2
2025/08/06 23:43:11 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 1/4 (0-67108864) size 64Mi finished
2025/08/06 23:43:11 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 2/4 (67108864-134217728) size 64Mi finished
2025/08/06 23:43:11 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 3/4 (134217728-201326592) size 64Mi finished
2025/08/06 23:43:11 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 23:43:42 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: disabling buffering because source is local disk
2025/08/06 23:43:43 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2
2025/08/06 23:43:43 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: number of streams 4 was bigger than number of chunks 2
2025/08/06 23:43:43 DEBUG : test-multithread-copy-true-201326591-2: Starting multi-thread copy with 2 chunks of size 96Mi with 2 parallel streams
2025/08/06 23:43:43 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: chunk 2/2 (100663296-201326591) size 96.000Mi starting
2025/08/06 23:43:43 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: chunk 1/2 (0-100663296) size 96Mi starting
2025/08/06 23:43:43 DEBUG : test-multithread-copy-true-201326591-2: Sending chunk 1 length 100663295
2025/08/06 23:43:43 DEBUG : test-multithread-copy-true-201326591-2: Sending chunk 0 length 100663296
2025/08/06 23:43:51 DEBUG : test-multithread-copy-true-201326591-2: Done sending chunk 1
2025/08/06 23:43:51 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: chunk 2/2 (100663296-201326591) size 96.000Mi finished
2025/08/06 23:43:51 DEBUG : test-multithread-copy-true-201326591-2: Done sending chunk 0
2025/08/06 23:43:51 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: chunk 1/2 (0-100663296) size 96Mi finished
2025/08/06 23:43:51 DEBUG : test-multithread-copy-true-201326591-2: Finishing large file upload with 2 parts
2025/08/06 23:43:52 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 23:44:22 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: disabling buffering because source is local disk
2025/08/06 23:44:23 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2
2025/08/06 23:44:23 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: number of streams 4 was bigger than number of chunks 2
2025/08/06 23:44:23 DEBUG : test-multithread-copy-true-201326592-2: Starting multi-thread copy with 2 chunks of size 96Mi with 2 parallel streams
2025/08/06 23:44:23 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: chunk 2/2 (100663296-201326592) size 96Mi starting
2025/08/06 23:44:23 DEBUG : test-multithread-copy-true-201326592-2: Sending chunk 1 length 100663296
2025/08/06 23:44:23 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: chunk 1/2 (0-100663296) size 96Mi starting
2025/08/06 23:44:23 DEBUG : test-multithread-copy-true-201326592-2: Sending chunk 0 length 100663296
2025/08/06 23:44:31 DEBUG : test-multithread-copy-true-201326592-2: Done sending chunk 1
2025/08/06 23:44:31 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: chunk 2/2 (100663296-201326592) size 96Mi finished
2025/08/06 23:44:31 DEBUG : test-multithread-copy-true-201326592-2: Done sending chunk 0
2025/08/06 23:44:31 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: chunk 1/2 (0-100663296) size 96Mi finished
2025/08/06 23:44:31 DEBUG : test-multithread-copy-true-201326592-2: Finishing large file upload with 2 parts
2025/08/06 23:44:32 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 23:45:03 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: disabling buffering because source is local disk
2025/08/06 23:45:05 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2
2025/08/06 23:45:05 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: number of streams 4 was bigger than number of chunks 3
2025/08/06 23:45:05 DEBUG : test-multithread-copy-true-201326593-2: Starting multi-thread copy with 3 chunks of size 96Mi with 3 parallel streams
2025/08/06 23:45:05 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 3/3 (201326592-201326593) size 1 starting
2025/08/06 23:45:05 DEBUG : test-multithread-copy-true-201326593-2: Sending chunk 2 length 1
2025/08/06 23:45:05 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 1/3 (0-100663296) size 96Mi starting
2025/08/06 23:45:05 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 2/3 (100663296-201326592) size 96Mi starting
2025/08/06 23:45:05 DEBUG : test-multithread-copy-true-201326593-2: Sending chunk 0 length 100663296
2025/08/06 23:45:05 DEBUG : test-multithread-copy-true-201326593-2: Sending chunk 1 length 100663296
2025/08/06 23:45:05 DEBUG : test-multithread-copy-true-201326593-2: Done sending chunk 2
2025/08/06 23:45:05 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 3/3 (201326592-201326593) size 1 finished
2025/08/06 23:45:12 DEBUG : test-multithread-copy-true-201326593-2: Done sending chunk 0
2025/08/06 23:45:12 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 1/3 (0-100663296) size 96Mi finished
2025/08/06 23:45:13 DEBUG : test-multithread-copy-true-201326593-2: Done sending chunk 1
2025/08/06 23:45:13 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 2/3 (100663296-201326592) size 96Mi finished
2025/08/06 23:45:13 DEBUG : test-multithread-copy-true-201326593-2: Finishing large file upload with 3 parts
2025/08/06 23:45:13 DEBUG : test-multithread-copy-true-201326593-2: Finished multi-thread copy with 3 parts of size 96Mi
--- PASS: TestMultithreadCopy (248.10s)
    --- PASS: TestMultithreadCopy/upload=false,size=201326591,streams=2 (42.06s)
    --- PASS: TestMultithreadCopy/upload=false,size=201326592,streams=2 (41.35s)
    --- PASS: TestMultithreadCopy/upload=false,size=201326593,streams=2 (40.28s)
    --- PASS: TestMultithreadCopy/upload=true,size=201326591,streams=2 (40.79s)
    --- PASS: TestMultithreadCopy/upload=true,size=201326592,streams=2 (40.88s)
    --- PASS: TestMultithreadCopy/upload=true,size=201326593,streams=2 (41.02s)
=== RUN   TestMultithreadCopyAbort
    run.go:180: Remote "B2 bucket rclone-test-solamos7geti", Local "Local file system at /tmp/rclone3246297378", Modify Window "1ms"
2025/08/06 23:45:25 DEBUG : chunksize-probe: Cancelling large file upload
2025/08/06 23:45:45 DEBUG : test-multithread-abort: multi-thread copy: disabling buffering because source is local disk
2025/08/06 23:45:46 DEBUG : test-multithread-abort: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 1
2025/08/06 23:45:46 DEBUG : test-multithread-abort: multi-thread copy: number of streams 4 was bigger than number of chunks 3
2025/08/06 23:45:46 DEBUG : test-multithread-abort: Starting multi-thread copy with 3 chunks of size 96Mi with 3 parallel streams
2025/08/06 23:45:46 DEBUG : test-multithread-abort: multi-thread copy: chunk 3/3 (201326592-201326593) size 1 starting
2025/08/06 23:45:46 DEBUG : Open with options = [RangeOption(201326592,201326592)]
2025/08/06 23:45:46 DEBUG : test-multithread-abort: multi-thread copy: chunk 2/3 (100663296-201326592) size 96Mi starting
2025/08/06 23:45:46 DEBUG : test-multithread-abort: multi-thread copy: chunk 1/3 (0-100663296) size 96Mi starting
2025/08/06 23:45:46 DEBUG : Open with options = [RangeOption(100663296,201326591)]
2025/08/06 23:45:46 DEBUG : Open with options = [RangeOption(0,100663295)]
2025/08/06 23:45:46 DEBUG : test-multithread-abort: Sending chunk 1 length 100663296
2025/08/06 23:45:46 DEBUG : test-multithread-abort: Sending chunk 0 length 100663296
2025/08/06 23:45:54 DEBUG : test-multithread-abort: Done sending chunk 1
2025/08/06 23:45:54 DEBUG : test-multithread-abort: multi-thread copy: chunk 2/3 (100663296-201326592) size 96Mi finished
2025/08/06 23:45:55 DEBUG : test-multithread-abort: Done sending chunk 0
2025/08/06 23:45:55 DEBUG : test-multithread-abort: multi-thread copy: chunk 1/3 (0-100663296) size 96Mi finished
2025/08/06 23:45:55 DEBUG : Returning error reader
2025/08/06 23:45:55 DEBUG : test-multithread-abort: Sending chunk 2 length 1
2025/08/06 23:45:55 DEBUG : BOOM: simulated read failure
2025/08/06 23:45:55 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 0/10: BOOM: simulated read failure
2025/08/06 23:45:55 DEBUG : Open with options = [RangeOption(201326592,201326592)]
2025/08/06 23:45:56 DEBUG : Returning error reader
2025/08/06 23:45:56 DEBUG : BOOM: simulated read failure
2025/08/06 23:45:56 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 1/10: BOOM: simulated read failure
2025/08/06 23:45:56 DEBUG : Open with options = [RangeOption(201326592,201326592)]
2025/08/06 23:45:57 DEBUG : Returning error reader
2025/08/06 23:45:57 DEBUG : BOOM: simulated read failure
2025/08/06 23:45:57 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 2/10: BOOM: simulated read failure
2025/08/06 23:45:57 DEBUG : Open with options = [RangeOption(201326592,201326592)]
2025/08/06 23:45:58 DEBUG : Returning error reader
2025/08/06 23:45:58 DEBUG : BOOM: simulated read failure
2025/08/06 23:45:58 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 3/10: BOOM: simulated read failure
2025/08/06 23:45:58 DEBUG : Open with options = [RangeOption(201326592,201326592)]
2025/08/06 23:45:59 DEBUG : Returning error reader
2025/08/06 23:45:59 DEBUG : BOOM: simulated read failure
2025/08/06 23:45:59 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 4/10: BOOM: simulated read failure
2025/08/06 23:45:59 DEBUG : Open with options = [RangeOption(201326592,201326592)]
2025/08/06 23:46:00 DEBUG : Returning error reader
2025/08/06 23:46:00 DEBUG : BOOM: simulated read failure
2025/08/06 23:46:00 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 5/10: BOOM: simulated read failure
2025/08/06 23:46:00 DEBUG : Open with options = [RangeOption(201326592,201326592)]
2025/08/06 23:46:01 DEBUG : Returning error reader
2025/08/06 23:46:01 DEBUG : BOOM: simulated read failure
2025/08/06 23:46:01 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 6/10: BOOM: simulated read failure
2025/08/06 23:46:01 DEBUG : Open with options = [RangeOption(201326592,201326592)]
2025/08/06 23:46:02 DEBUG : Returning error reader
2025/08/06 23:46:02 DEBUG : BOOM: simulated read failure
2025/08/06 23:46:02 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 7/10: BOOM: simulated read failure
2025/08/06 23:46:02 DEBUG : Open with options = [RangeOption(201326592,201326592)]
2025/08/06 23:46:03 DEBUG : Returning error reader
2025/08/06 23:46:03 DEBUG : BOOM: simulated read failure
2025/08/06 23:46:03 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 8/10: BOOM: simulated read failure
2025/08/06 23:46:03 DEBUG : Open with options = [RangeOption(201326592,201326592)]
2025/08/06 23:46:04 DEBUG : Returning error reader
2025/08/06 23:46:04 DEBUG : BOOM: simulated read failure
2025/08/06 23:46:04 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 9/10: BOOM: simulated read failure
2025/08/06 23:46:04 DEBUG : Open with options = [RangeOption(201326592,201326592)]
2025/08/06 23:46:05 DEBUG : Returning error reader
2025/08/06 23:46:05 DEBUG : BOOM: simulated read failure
2025/08/06 23:46:05 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 10/10: BOOM: simulated read failure
2025/08/06 23:46:05 DEBUG : test-multithread-abort: Reopen failed after offset 0 bytes read: failed to reopen: too many retries
2025/08/06 23:46:05 DEBUG : test-multithread-abort: Error sending chunk 2 (retry=false): Post "https://pod-000-1150-19.backblaze.com/b2api/v1/b2_upload_part/4_z2f22bd53c02d8b1b9f880e1b_f203a8c8012260147_d20250806_m234546_c001_v0001150_t0010_u01754523946637/0054": BOOM: simulated read failure: &url.Error{Op:"Post", URL:"https://pod-000-1150-19.backblaze.com/b2api/v1/b2_upload_part/4_z2f22bd53c02d8b1b9f880e1b_f203a8c8012260147_d20250806_m234546_c001_v0001150_t0010_u01754523946637/0054", Err:(*errors.errorString)(0xc0009465f0)}
2025/08/06 23:46:05 DEBUG : test-multithread-abort: Error sending chunk 2: Post "https://pod-000-1150-19.backblaze.com/b2api/v1/b2_upload_part/4_z2f22bd53c02d8b1b9f880e1b_f203a8c8012260147_d20250806_m234546_c001_v0001150_t0010_u01754523946637/0054": BOOM: simulated read failure
2025/08/06 23:46:05 DEBUG : test-multithread-abort: multi-thread copy: chunk 3/3 failed: multi-thread copy: failed to write chunk: Post "https://pod-000-1150-19.backblaze.com/b2api/v1/b2_upload_part/4_z2f22bd53c02d8b1b9f880e1b_f203a8c8012260147_d20250806_m234546_c001_v0001150_t0010_u01754523946637/0054": BOOM: simulated read failure
2025/08/06 23:46:05 DEBUG : test-multithread-abort: multi-thread copy: cancelling transfer on exit
2025/08/06 23:46:05 DEBUG : test-multithread-abort: Cancelling large file upload
--- PASS: TestMultithreadCopyAbort (41.01s)
=== RUN   TestSizeDiffers
--- PASS: TestSizeDiffers (0.00s)
=== RUN   TestReOpen
=== RUN   TestReOpen/Normal
=== RUN   TestReOpen/Normal/Basics
2025/08/06 23:46:06 DEBUG : potato: Seek from 10 to 0
=== RUN   TestReOpen/Normal/ErrorAtStart
=== RUN   TestReOpen/Normal/WithErrors
2025/08/06 23:46:06 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2025/08/06 23:46:06 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2025/08/06 23:46:06 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN   TestReOpen/Normal/TooManyErrors
2025/08/06 23:46:06 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2025/08/06 23:46:06 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2025/08/06 23:46:06 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2025/08/06 23:46:06 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN   TestReOpen/Normal/Seek
2025/08/06 23:46:06 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2025/08/06 23:46:06 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2025/08/06 23:46:06 DEBUG : potato: Seek from 5 to 2
=== RUN   TestReOpen/Normal/AccountRead
=== RUN   TestReOpen/Normal/AccountReadDelay
2025/08/06 23:46:06 DEBUG : potato: Seek from 10 to 0
2025/08/06 23:46:06 DEBUG : potato: Seek from 10 to 0
2025/08/06 23:46:06 DEBUG : potato: Seek from 10 to 0
=== RUN   TestReOpen/Normal/AccountReadError
=== RUN   TestReOpen/WithRangeOption
=== RUN   TestReOpen/WithRangeOption/Basics
2025/08/06 23:46:06 DEBUG : potato: Seek from 7 to 0
=== RUN   TestReOpen/WithRangeOption/ErrorAtStart
=== RUN   TestReOpen/WithRangeOption/WithErrors
2025/08/06 23:46:06 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2025/08/06 23:46:06 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2025/08/06 23:46:06 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN   TestReOpen/WithRangeOption/TooManyErrors
2025/08/06 23:46:06 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2025/08/06 23:46:06 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2025/08/06 23:46:06 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2025/08/06 23:46:06 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN   TestReOpen/WithRangeOption/Seek
2025/08/06 23:46:06 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2025/08/06 23:46:06 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2025/08/06 23:46:06 DEBUG : potato: Seek from 5 to 2
2025/08/06 23:46:06 DEBUG : potato: Seek from 7 to 4
=== RUN   TestReOpen/WithRangeOption/AccountRead
=== RUN   TestReOpen/WithRangeOption/AccountReadDelay
2025/08/06 23:46:06 DEBUG : potato: Seek from 7 to 0
2025/08/06 23:46:06 DEBUG : potato: Seek from 7 to 0
2025/08/06 23:46:06 DEBUG : potato: Seek from 7 to 0
=== RUN   TestReOpen/WithRangeOption/AccountReadError
=== RUN   TestReOpen/WithSeekOption
=== RUN   TestReOpen/WithSeekOption/Basics
2025/08/06 23:46:06 DEBUG : potato: Seek from 8 to 0
=== RUN   TestReOpen/WithSeekOption/ErrorAtStart
=== RUN   TestReOpen/WithSeekOption/WithErrors
2025/08/06 23:46:06 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2025/08/06 23:46:06 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2025/08/06 23:46:06 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN   TestReOpen/WithSeekOption/TooManyErrors
2025/08/06 23:46:06 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2025/08/06 23:46:06 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2025/08/06 23:46:06 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2025/08/06 23:46:06 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN   TestReOpen/WithSeekOption/Seek
2025/08/06 23:46:06 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2025/08/06 23:46:06 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2025/08/06 23:46:06 DEBUG : potato: Seek from 5 to 2
2025/08/06 23:46:06 DEBUG : potato: Seek from 7 to 5
=== RUN   TestReOpen/WithSeekOption/AccountRead
=== RUN   TestReOpen/WithSeekOption/AccountReadDelay
2025/08/06 23:46:06 DEBUG : potato: Seek from 8 to 0
2025/08/06 23:46:06 DEBUG : potato: Seek from 8 to 0
2025/08/06 23:46:06 DEBUG : potato: Seek from 8 to 0
=== RUN   TestReOpen/WithSeekOption/AccountReadError
=== RUN   TestReOpen/UnknownSize
=== RUN   TestReOpen/UnknownSize/Basics
2025/08/06 23:46:06 DEBUG : potato: Seek from 9 to 0
=== RUN   TestReOpen/UnknownSize/ErrorAtStart
=== RUN   TestReOpen/UnknownSize/WithErrors
2025/08/06 23:46:06 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2025/08/06 23:46:06 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2025/08/06 23:46:06 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN   TestReOpen/UnknownSize/TooManyErrors
2025/08/06 23:46:06 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2025/08/06 23:46:06 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2025/08/06 23:46:06 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2025/08/06 23:46:06 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN   TestReOpen/UnknownSize/Seek
2025/08/06 23:46:06 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2025/08/06 23:46:06 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2025/08/06 23:46:06 DEBUG : potato: Seek from 5 to 2
2025/08/06 23:46:06 DEBUG : potato: Seek from 7 to 6
=== RUN   TestReOpen/UnknownSize/AccountRead
=== RUN   TestReOpen/UnknownSize/AccountReadDelay
2025/08/06 23:46:06 DEBUG : potato: Seek from 9 to 0
2025/08/06 23:46:06 DEBUG : potato: Seek from 9 to 0
2025/08/06 23:46:06 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-solamos7geti", Local "Local file system at /tmp/rclone3246297378", Modify Window "1ms"
=== RUN   TestCheck/1
=== RUN   TestCheck/2
=== RUN   TestCheck/3
=== RUN   TestCheck/4
=== RUN   TestCheck/5
=== RUN   TestCheck/6
=== RUN   TestCheck/7
--- PASS: TestCheck (4.87s)
    --- PASS: TestCheck/1 (0.18s)
    --- PASS: TestCheck/2 (0.18s)
    --- PASS: TestCheck/3 (0.20s)
    --- 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 23:46:11 DEBUG : Creating backend with remote "nonexistent"
2025/08/06 23:46:11 DEBUG : Config file has changed externally - reloading
2025/08/06 23:46:11 DEBUG : Creating backend with remote "nonexistent"
2025/08/06 23:46:11 DEBUG : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: Waiting for checks to finish
2025/08/06 23:46:11 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 23:46:11 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: 0 differences found
2025/08/06 23:46:11 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-solamos7geti", Local "Local file system at /tmp/rclone3246297378", Modify Window "1ms"
=== RUN   TestCheckDownload/1
=== RUN   TestCheckDownload/2
=== RUN   TestCheckDownload/3
=== RUN   TestCheckDownload/4
=== RUN   TestCheckDownload/5
=== RUN   TestCheckDownload/6
=== RUN   TestCheckDownload/7
--- PASS: TestCheckDownload (6.55s)
    --- PASS: TestCheckDownload/1 (0.71s)
    --- PASS: TestCheckDownload/2 (0.35s)
    --- PASS: TestCheckDownload/3 (0.35s)
    --- PASS: TestCheckDownload/4 (0.53s)
    --- PASS: TestCheckDownload/5 (0.36s)
    --- PASS: TestCheckDownload/6 (0.44s)
    --- PASS: TestCheckDownload/7 (0.36s)
=== RUN   TestCheckSizeOnly
    run.go:180: Remote "B2 bucket rclone-test-solamos7geti", Local "Local file system at /tmp/rclone3246297378", Modify Window "1ms"
=== RUN   TestCheckSizeOnly/1
=== RUN   TestCheckSizeOnly/2
=== RUN   TestCheckSizeOnly/3
=== RUN   TestCheckSizeOnly/4
=== RUN   TestCheckSizeOnly/5
=== RUN   TestCheckSizeOnly/6
=== RUN   TestCheckSizeOnly/7
--- PASS: TestCheckSizeOnly (4.48s)
    --- 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.17s)
=== RUN   TestCheckEqualReaders
--- PASS: TestCheckEqualReaders (0.00s)
=== RUN   TestParseSumFile
    run.go:180: Remote "B2 bucket rclone-test-solamos7geti", Local "Local file system at /tmp/rclone3246297378", Modify Window "1ms"
2025/08/06 23:46:23 NOTICE: test.sum: improperly formatted checksum line 4
2025/08/06 23:46:23 NOTICE: test.sum: improperly formatted checksum line 5
2025/08/06 23:46:23 NOTICE: test.sum: improperly formatted checksum line 6
2025/08/06 23:46:23 NOTICE: test.sum: 2 warning(s) suppressed...
2025/08/06 23:46:24 NOTICE: test.sum: improperly formatted checksum line 4
2025/08/06 23:46:24 NOTICE: test.sum: improperly formatted checksum line 5
2025/08/06 23:46:24 NOTICE: test.sum: improperly formatted checksum line 6
2025/08/06 23:46:24 NOTICE: test.sum: 2 warning(s) suppressed...
--- PASS: TestParseSumFile (2.41s)
=== RUN   TestCheckSum
    run.go:180: Remote "B2 bucket rclone-test-solamos7geti", Local "Local file system at /tmp/rclone3246297378", Modify Window "1ms"
2025/08/06 23:46:24 DEBUG : Creating backend with remote "TestB2:rclone-test-solamos7geti/data"
    check_test.go:350: B2 bucket rclone-test-solamos7geti path data lacks md5, skipping
--- SKIP: TestCheckSum (1.42s)
=== RUN   TestCheckSumDownload
    run.go:180: Remote "B2 bucket rclone-test-solamos7geti", Local "Local file system at /tmp/rclone3246297378", Modify Window "1ms"
2025/08/06 23:46:26 DEBUG : Creating backend with remote "TestB2:rclone-test-solamos7geti/data"
=== RUN   TestCheckSumDownload/subtest1
=== RUN   TestCheckSumDownload/subtest2
=== RUN   TestCheckSumDownload/subtest3
=== RUN   TestCheckSumDownload/subtest4
=== RUN   TestCheckSumDownload/subtest5
=== RUN   TestCheckSumDownload/subtest6
=== RUN   TestCheckSumDownload/subtest7
--- PASS: TestCheckSumDownload (13.25s)
    --- PASS: TestCheckSumDownload/subtest1 (1.26s)
    --- PASS: TestCheckSumDownload/subtest2 (0.71s)
    --- PASS: TestCheckSumDownload/subtest3 (0.90s)
    --- PASS: TestCheckSumDownload/subtest4 (0.72s)
    --- PASS: TestCheckSumDownload/subtest5 (0.73s)
    --- PASS: TestCheckSumDownload/subtest6 (0.72s)
    --- PASS: TestCheckSumDownload/subtest7 (0.73s)
=== RUN   TestApplyTransforms
2025/08/06 23:46:39 DEBUG : Creating backend with remote "TestB2:rclone-test-xirujag9xote"
2025/08/06 23:46:39 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/08/06 23:46:40 DEBUG : Creating backend with remote "/tmp/rclone3201808736"
    run.go:180: Remote "B2 bucket rclone-test-xirujag9xote", Local "Local file system at /tmp/rclone3201808736", Modify Window "1ms"
upper checkfile vs. lower remote (without normalization)
2025/08/06 23:46:42 ERROR : hello, world!: sum not found
2025/08/06 23:46:42 ERROR : HELLO, WORLD!: file not in B2 bucket rclone-test-xirujag9xote
2025/08/06 23:46:42 NOTICE: B2 bucket rclone-test-xirujag9xote: 1 files missing
2025/08/06 23:46:42 NOTICE: 1 hashes missing
2025/08/06 23:46:42 NOTICE: B2 bucket rclone-test-xirujag9xote: 1 differences found
2025/08/06 23:46:42 NOTICE: B2 bucket rclone-test-xirujag9xote: 2 errors while checking
upper checkfile vs. lower remote (with normalization)
2025/08/06 23:46:43 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2025/08/06 23:46:43 NOTICE: B2 bucket rclone-test-xirujag9xote: 0 differences found
2025/08/06 23:46:43 NOTICE: B2 bucket rclone-test-xirujag9xote: 1 matching files
2025/08/06 23:46:43 DEBUG : Creating backend with remote "TestB2:rclone-test-yokekom1qifa"
2025/08/06 23:46:43 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/08/06 23:46:44 DEBUG : Creating backend with remote "/tmp/rclone4024468000"
    run.go:180: Remote "B2 bucket rclone-test-yokekom1qifa", Local "Local file system at /tmp/rclone4024468000", Modify Window "1ms"
lower checkfile vs. upper remote (without normalization)
2025/08/06 23:46:46 ERROR : HELLO, WORLD!: sum not found
2025/08/06 23:46:46 ERROR : hello, world!: file not in B2 bucket rclone-test-yokekom1qifa
2025/08/06 23:46:46 NOTICE: B2 bucket rclone-test-yokekom1qifa: 1 files missing
2025/08/06 23:46:46 NOTICE: 1 hashes missing
2025/08/06 23:46:46 NOTICE: B2 bucket rclone-test-yokekom1qifa: 1 differences found
2025/08/06 23:46:46 NOTICE: B2 bucket rclone-test-yokekom1qifa: 2 errors while checking
lower checkfile vs. upper remote (with normalization)
2025/08/06 23:46:47 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2025/08/06 23:46:47 NOTICE: B2 bucket rclone-test-yokekom1qifa: 0 differences found
2025/08/06 23:46:47 NOTICE: B2 bucket rclone-test-yokekom1qifa: 1 matching files
2025/08/06 23:46:47 DEBUG : Creating backend with remote "TestB2:rclone-test-gabirud2home"
2025/08/06 23:46:47 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/08/06 23:46:47 DEBUG : Creating backend with remote "/tmp/rclone801033789"
    run.go:180: Remote "B2 bucket rclone-test-gabirud2home", Local "Local file system at /tmp/rclone801033789", Modify Window "1ms"
lower checkfile vs. upperlowermixed remote (without normalization)
2025/08/06 23:46:50 ERROR : HeLlO, wOrLd!: sum not found
2025/08/06 23:46:50 ERROR : hello, world!: file not in B2 bucket rclone-test-gabirud2home
2025/08/06 23:46:50 NOTICE: B2 bucket rclone-test-gabirud2home: 1 files missing
2025/08/06 23:46:50 NOTICE: 1 hashes missing
2025/08/06 23:46:50 NOTICE: B2 bucket rclone-test-gabirud2home: 1 differences found
2025/08/06 23:46:50 NOTICE: B2 bucket rclone-test-gabirud2home: 2 errors while checking
lower checkfile vs. upperlowermixed remote (with normalization)
2025/08/06 23:46:50 DEBUG : HeLlO, wOrLd!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2025/08/06 23:46:50 NOTICE: B2 bucket rclone-test-gabirud2home: 0 differences found
2025/08/06 23:46:50 NOTICE: B2 bucket rclone-test-gabirud2home: 1 matching files
2025/08/06 23:46:50 DEBUG : Creating backend with remote "TestB2:rclone-test-miyufux3nore"
2025/08/06 23:46:50 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/08/06 23:46:51 DEBUG : Creating backend with remote "/tmp/rclone2274641066"
    run.go:180: Remote "B2 bucket rclone-test-miyufux3nore", Local "Local file system at /tmp/rclone2274641066", Modify Window "1ms"
upperlowermixed checkfile vs. upper remote (without normalization)
2025/08/06 23:46:53 ERROR : HELLO, WORLD!: sum not found
2025/08/06 23:46:53 ERROR : HeLlO, wOrLd!: file not in B2 bucket rclone-test-miyufux3nore
2025/08/06 23:46:53 NOTICE: B2 bucket rclone-test-miyufux3nore: 1 files missing
2025/08/06 23:46:53 NOTICE: 1 hashes missing
2025/08/06 23:46:53 NOTICE: B2 bucket rclone-test-miyufux3nore: 1 differences found
2025/08/06 23:46:53 NOTICE: B2 bucket rclone-test-miyufux3nore: 2 errors while checking
upperlowermixed checkfile vs. upper remote (with normalization)
2025/08/06 23:46:54 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2025/08/06 23:46:54 NOTICE: B2 bucket rclone-test-miyufux3nore: 0 differences found
2025/08/06 23:46:54 NOTICE: B2 bucket rclone-test-miyufux3nore: 1 matching files
2025/08/06 23:46:54 DEBUG : Creating backend with remote "TestB2:rclone-test-biguxic1ducu"
2025/08/06 23:46:54 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/08/06 23:46:55 DEBUG : Creating backend with remote "/tmp/rclone3234423268"
    run.go:180: Remote "B2 bucket rclone-test-biguxic1ducu", Local "Local file system at /tmp/rclone3234423268", Modify Window "1ms"
NFD checkfile vs. NFC remote (without normalization)
2025/08/06 23:46:57 ERROR : 測試_Русский___ě_áñ: sum not found
2025/08/06 23:46:57 ERROR : 測試_Русский___ě_áñ: file not in B2 bucket rclone-test-biguxic1ducu
2025/08/06 23:46:57 NOTICE: B2 bucket rclone-test-biguxic1ducu: 1 files missing
2025/08/06 23:46:57 NOTICE: 1 hashes missing
2025/08/06 23:46:57 NOTICE: B2 bucket rclone-test-biguxic1ducu: 1 differences found
2025/08/06 23:46:57 NOTICE: B2 bucket rclone-test-biguxic1ducu: 2 errors while checking
NFD checkfile vs. NFC remote (with normalization)
2025/08/06 23:46:57 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2025/08/06 23:46:57 NOTICE: B2 bucket rclone-test-biguxic1ducu: 0 differences found
2025/08/06 23:46:57 NOTICE: B2 bucket rclone-test-biguxic1ducu: 1 matching files
2025/08/06 23:46:57 DEBUG : Creating backend with remote "TestB2:rclone-test-jawibaf3gosa"
2025/08/06 23:46:57 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/08/06 23:46:58 DEBUG : Creating backend with remote "/tmp/rclone2676553631"
    run.go:180: Remote "B2 bucket rclone-test-jawibaf3gosa", Local "Local file system at /tmp/rclone2676553631", Modify Window "1ms"
NFC checkfile vs. NFD remote (without normalization)
2025/08/06 23:47:00 ERROR : 測試_Русский___ě_áñ: sum not found
2025/08/06 23:47:00 ERROR : 測試_Русский___ě_áñ: file not in B2 bucket rclone-test-jawibaf3gosa
2025/08/06 23:47:00 NOTICE: B2 bucket rclone-test-jawibaf3gosa: 1 files missing
2025/08/06 23:47:00 NOTICE: 1 hashes missing
2025/08/06 23:47:00 NOTICE: B2 bucket rclone-test-jawibaf3gosa: 1 differences found
2025/08/06 23:47:00 NOTICE: B2 bucket rclone-test-jawibaf3gosa: 2 errors while checking
NFC checkfile vs. NFD remote (with normalization)
2025/08/06 23:47:01 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2025/08/06 23:47:01 NOTICE: B2 bucket rclone-test-jawibaf3gosa: 0 differences found
2025/08/06 23:47:01 NOTICE: B2 bucket rclone-test-jawibaf3gosa: 1 matching files
2025/08/06 23:47:01 DEBUG : Creating backend with remote "TestB2:rclone-test-xexuliw3kiye"
2025/08/06 23:47:01 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/08/06 23:47:01 DEBUG : Creating backend with remote "/tmp/rclone2513856044"
    run.go:180: Remote "B2 bucket rclone-test-xexuliw3kiye", Local "Local file system at /tmp/rclone2513856044", Modify Window "1ms"
NFDx2 checkfile vs. both remote (without normalization)
2025/08/06 23:47:03 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found
2025/08/06 23:47:03 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-xexuliw3kiye
2025/08/06 23:47:03 NOTICE: B2 bucket rclone-test-xexuliw3kiye: 1 files missing
2025/08/06 23:47:03 NOTICE: 1 hashes missing
2025/08/06 23:47:03 NOTICE: B2 bucket rclone-test-xexuliw3kiye: 1 differences found
2025/08/06 23:47:03 NOTICE: B2 bucket rclone-test-xexuliw3kiye: 2 errors while checking
NFDx2 checkfile vs. both remote (with normalization)
2025/08/06 23:47:04 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2025/08/06 23:47:04 NOTICE: B2 bucket rclone-test-xexuliw3kiye: 0 differences found
2025/08/06 23:47:04 NOTICE: B2 bucket rclone-test-xexuliw3kiye: 1 matching files
2025/08/06 23:47:04 DEBUG : Creating backend with remote "TestB2:rclone-test-potewom8pase"
2025/08/06 23:47:04 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/08/06 23:47:05 DEBUG : Creating backend with remote "/tmp/rclone2144161675"
    run.go:180: Remote "B2 bucket rclone-test-potewom8pase", Local "Local file system at /tmp/rclone2144161675", Modify Window "1ms"
NFCx2 checkfile vs. both remote (without normalization)
2025/08/06 23:47:07 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found
2025/08/06 23:47:07 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-potewom8pase
2025/08/06 23:47:07 NOTICE: B2 bucket rclone-test-potewom8pase: 1 files missing
2025/08/06 23:47:07 NOTICE: 1 hashes missing
2025/08/06 23:47:07 NOTICE: B2 bucket rclone-test-potewom8pase: 1 differences found
2025/08/06 23:47:07 NOTICE: B2 bucket rclone-test-potewom8pase: 2 errors while checking
NFCx2 checkfile vs. both remote (with normalization)
2025/08/06 23:47:08 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2025/08/06 23:47:08 NOTICE: B2 bucket rclone-test-potewom8pase: 0 differences found
2025/08/06 23:47:08 NOTICE: B2 bucket rclone-test-potewom8pase: 1 matching files
2025/08/06 23:47:08 DEBUG : Creating backend with remote "TestB2:rclone-test-lucekuq7nodi"
2025/08/06 23:47:08 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/08/06 23:47:08 DEBUG : Creating backend with remote "/tmp/rclone2208657898"
    run.go:180: Remote "B2 bucket rclone-test-lucekuq7nodi", Local "Local file system at /tmp/rclone2208657898", Modify Window "1ms"
both checkfile vs. NFDx2 remote (without normalization)
2025/08/06 23:47:11 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found
2025/08/06 23:47:11 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-lucekuq7nodi
2025/08/06 23:47:11 NOTICE: B2 bucket rclone-test-lucekuq7nodi: 1 files missing
2025/08/06 23:47:11 NOTICE: 1 hashes missing
2025/08/06 23:47:11 NOTICE: B2 bucket rclone-test-lucekuq7nodi: 1 differences found
2025/08/06 23:47:11 NOTICE: B2 bucket rclone-test-lucekuq7nodi: 2 errors while checking
both checkfile vs. NFDx2 remote (with normalization)
2025/08/06 23:47:11 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2025/08/06 23:47:11 NOTICE: B2 bucket rclone-test-lucekuq7nodi: 0 differences found
2025/08/06 23:47:11 NOTICE: B2 bucket rclone-test-lucekuq7nodi: 1 matching files
2025/08/06 23:47:11 DEBUG : Creating backend with remote "TestB2:rclone-test-tiluqer0fepa"
2025/08/06 23:47:11 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/08/06 23:47:12 DEBUG : Creating backend with remote "/tmp/rclone966133495"
    run.go:180: Remote "B2 bucket rclone-test-tiluqer0fepa", Local "Local file system at /tmp/rclone966133495", Modify Window "1ms"
both checkfile vs. NFCx2 remote (without normalization)
2025/08/06 23:47:14 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found
2025/08/06 23:47:14 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-tiluqer0fepa
2025/08/06 23:47:14 NOTICE: B2 bucket rclone-test-tiluqer0fepa: 1 files missing
2025/08/06 23:47:14 NOTICE: 1 hashes missing
2025/08/06 23:47:14 NOTICE: B2 bucket rclone-test-tiluqer0fepa: 1 differences found
2025/08/06 23:47:14 NOTICE: B2 bucket rclone-test-tiluqer0fepa: 2 errors while checking
both checkfile vs. NFCx2 remote (with normalization)
2025/08/06 23:47:15 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2025/08/06 23:47:15 NOTICE: B2 bucket rclone-test-tiluqer0fepa: 0 differences found
2025/08/06 23:47:15 NOTICE: B2 bucket rclone-test-tiluqer0fepa: 1 matching files
2025/08/06 23:47:15 DEBUG : B2 bucket rclone-test-tiluqer0fepa: Purge remote
2025/08/06 23:47:15 INFO  : B2 bucket rclone-test-tiluqer0fepa: cleaning bucket "rclone-test-tiluqer0fepa" of all files
2025/08/06 23:47:15 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_z4f021df3c08d8b1b9f880e1b_f1021926995129f5c_d20250806_m234713_c001_v0001153_t0015_u01754524033839")
2025/08/06 23:47:16 DEBUG : B2 bucket rclone-test-lucekuq7nodi: Purge remote
2025/08/06 23:47:16 INFO  : B2 bucket rclone-test-lucekuq7nodi: cleaning bucket "rclone-test-lucekuq7nodi" of all files
2025/08/06 23:47:16 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_z6fe21d13c08d8b1b9f880e1b_f1183635e7afe8495_d20250806_m234710_c001_v0001032_t0017_u01754524030293")
2025/08/06 23:47:16 DEBUG : B2 bucket rclone-test-potewom8pase: Purge remote
2025/08/06 23:47:16 INFO  : B2 bucket rclone-test-potewom8pase: cleaning bucket "rclone-test-potewom8pase" of all files
2025/08/06 23:47:17 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_z8ff20d33c08d8b1b9f880e1b_f107ed32c9d4e9b21_d20250806_m234706_c001_v0001042_t0054_u01754524026797")
2025/08/06 23:47:18 DEBUG : B2 bucket rclone-test-xexuliw3kiye: Purge remote
2025/08/06 23:47:18 INFO  : B2 bucket rclone-test-xexuliw3kiye: cleaning bucket "rclone-test-xexuliw3kiye" of all files
2025/08/06 23:47:18 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_z7ff2fd63c07d8b1b9f880e1b_f112619ccc89076fd_d20250806_m234703_c001_v0001182_t0049_u01754524023370")
2025/08/06 23:47:19 DEBUG : B2 bucket rclone-test-jawibaf3gosa: Purge remote
2025/08/06 23:47:19 INFO  : B2 bucket rclone-test-jawibaf3gosa: cleaning bucket "rclone-test-jawibaf3gosa" of all files
2025/08/06 23:47:19 DEBUG : 測試_Русский___ě_áñ: Deleting (id "4_z8f82ed93c07d8b1b9f880e1b_f1171730f0574143c_d20250806_m234700_c001_v0001180_t0000_u01754524020086")
2025/08/06 23:47:20 DEBUG : B2 bucket rclone-test-biguxic1ducu: Purge remote
2025/08/06 23:47:20 INFO  : B2 bucket rclone-test-biguxic1ducu: cleaning bucket "rclone-test-biguxic1ducu" of all files
2025/08/06 23:47:21 DEBUG : 測試_Русский___ě_áñ: Deleting (id "4_z7f92ddc3c07d8b1b9f880e1b_f1016ed53500e8a1f_d20250806_m234656_c001_v0001177_t0021_u01754524016770")
2025/08/06 23:47:21 DEBUG : B2 bucket rclone-test-miyufux3nore: Purge remote
2025/08/06 23:47:21 INFO  : B2 bucket rclone-test-miyufux3nore: cleaning bucket "rclone-test-miyufux3nore" of all files
2025/08/06 23:47:22 DEBUG : HELLO, WORLD!: Deleting (id "4_z5f42cdd3c07d8b1b9f880e1b_f11961d3931b3ab42_d20250806_m234653_c001_v0001180_t0008_u01754524013532")
2025/08/06 23:47:22 DEBUG : B2 bucket rclone-test-gabirud2home: Purge remote
2025/08/06 23:47:22 INFO  : B2 bucket rclone-test-gabirud2home: cleaning bucket "rclone-test-gabirud2home" of all files
2025/08/06 23:47:23 DEBUG : HeLlO, wOrLd!: Deleting (id "4_z0f52cd03c07d8b1b9f880e1b_f108cea1dee02dbe5_d20250806_m234649_c001_v0001038_t0032_u01754524009422")
2025/08/06 23:47:24 DEBUG : B2 bucket rclone-test-yokekom1qifa: Purge remote
2025/08/06 23:47:24 INFO  : B2 bucket rclone-test-yokekom1qifa: cleaning bucket "rclone-test-yokekom1qifa" of all files
2025/08/06 23:47:24 DEBUG : HELLO, WORLD!: Deleting (id "4_z8f72bd13c07d8b1b9f880e1b_f115c1243ea830914_d20250806_m234645_c001_v0001115_t0006_u01754524005698")
2025/08/06 23:47:25 DEBUG : B2 bucket rclone-test-xirujag9xote: Purge remote
2025/08/06 23:47:25 INFO  : B2 bucket rclone-test-xirujag9xote: cleaning bucket "rclone-test-xirujag9xote" of all files
2025/08/06 23:47:25 DEBUG : hello, world!: Deleting (id "4_z9f42ad13c07d8b1b9f880e1b_f101436c23f9fd4b2_d20250806_m234642_c001_v0001144_t0036_u01754524002098")
--- PASS: TestApplyTransforms (46.73s)
=== RUN   TestTruncateString
--- PASS: TestTruncateString (0.00s)
=== RUN   TestCopyFile
    run.go:180: Remote "B2 bucket rclone-test-solamos7geti", Local "Local file system at /tmp/rclone3246297378", Modify Window "1ms"
2025/08/06 23:47:26 DEBUG : file1: Need to transfer - File not found at Destination
2025/08/06 23:47:27 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK
2025/08/06 23:47:27 INFO  : file1: Copied (new) to: sub/file2
2025/08/06 23:47:28 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2025/08/06 23:47:28 DEBUG : file1: Unchanged skipping
2025/08/06 23:47:28 DEBUG : B2 bucket rclone-test-solamos7geti: don't need to copy/move sub/file2, it is already at target location
--- PASS: TestCopyFile (2.84s)
=== RUN   TestCopyLongFile
    run.go:180: Remote "B2 bucket rclone-test-solamos7geti", Local "Local file system at /tmp/rclone3246297378", 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-solamos7geti", Local "Local file system at /tmp/rclone3246297378", Modify Window "1ms"
2025/08/06 23:47:30 DEBUG : Creating backend with remote "TestB2:rclone-test-solamos7geti/backup"
2025/08/06 23:47:31 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18)
2025/08/06 23:47:32 DEBUG : dst/file1: sha1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK
2025/08/06 23:47:32 INFO  : dst/file1: Copied (server-side copy)
2025/08/06 23:47:32 INFO  : dst/file1: Deleted
2025/08/06 23:47:33 DEBUG : dst/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK
2025/08/06 23:47:33 INFO  : dst/file1: Copied (new)
--- PASS: TestCopyFileBackupDir (4.43s)
=== RUN   TestCopyFileCompareDest
    run.go:180: Remote "B2 bucket rclone-test-solamos7geti", Local "Local file system at /tmp/rclone3246297378", Modify Window "1ms"
2025/08/06 23:47:33 DEBUG : Creating backend with remote "TestB2:rclone-test-solamos7geti/dst"
2025/08/06 23:47:35 DEBUG : Creating backend with remote "TestB2:rclone-test-solamos7geti/CompareDest"
2025/08/06 23:47:36 DEBUG : one: Need to transfer - File not found at Destination
2025/08/06 23:47:37 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK
2025/08/06 23:47:37 INFO  : one: Copied (new)
2025/08/06 23:47:38 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/08/06 23:47:39 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK
2025/08/06 23:47:39 INFO  : one: Copied (replaced existing)
2025/08/06 23:47:40 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/08/06 23:47:40 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2025/08/06 23:47:40 DEBUG : one: Destination found in --compare-dest, skipping
2025/08/06 23:47:41 DEBUG : two: Need to transfer - File not found at Destination
2025/08/06 23:47:41 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2025/08/06 23:47:41 DEBUG : two: Destination found in --compare-dest, skipping
2025/08/06 23:47:42 DEBUG : two: Need to transfer - File not found at Destination
2025/08/06 23:47:42 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2025/08/06 23:47:42 DEBUG : two: Destination found in --compare-dest, skipping
2025/08/06 23:47:42 DEBUG : two: Need to transfer - File not found at Destination
2025/08/06 23:47:43 DEBUG : two: Sizes differ (src 5 vs dst 3)
2025/08/06 23:47:43 DEBUG : two: sha1 = a4be8877ad88f3109a6411985a1f359e10e2d930 OK
2025/08/06 23:47:43 INFO  : two: Copied (new)
--- PASS: TestCopyFileCompareDest (10.92s)
=== RUN   TestCopyFileCopyDest
    run.go:180: Remote "B2 bucket rclone-test-solamos7geti", Local "Local file system at /tmp/rclone3246297378", Modify Window "1ms"
2025/08/06 23:47:44 DEBUG : Creating backend with remote "TestB2:rclone-test-solamos7geti/dst"
2025/08/06 23:47:46 DEBUG : Creating backend with remote "TestB2:rclone-test-solamos7geti/CopyDest"
2025/08/06 23:47:47 DEBUG : one: Need to transfer - File not found at Destination
2025/08/06 23:47:49 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK
2025/08/06 23:47:49 INFO  : one: Copied (new)
2025/08/06 23:47:49 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/08/06 23:47:50 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK
2025/08/06 23:47:50 INFO  : one: Copied (replaced existing)
2025/08/06 23:47:52 DEBUG : Creating backend with remote "TestB2:rclone-test-solamos7geti/BackupDir"
2025/08/06 23:47:53 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/08/06 23:47:53 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2025/08/06 23:47:53 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/08/06 23:47:54 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK
2025/08/06 23:47:54 INFO  : one: Copied (server-side copy)
2025/08/06 23:47:55 INFO  : one: Deleted
2025/08/06 23:47:55 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK
2025/08/06 23:47:55 INFO  : one: Copied (server-side copy)
2025/08/06 23:47:55 DEBUG : one: Destination found in --copy-dest, using server-side copy
2025/08/06 23:47:56 DEBUG : two: Need to transfer - File not found at Destination
2025/08/06 23:47:56 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2025/08/06 23:47:57 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK
2025/08/06 23:47:57 INFO  : two: Copied (server-side copy)
2025/08/06 23:47:57 DEBUG : two: Destination found in --copy-dest, using server-side copy
2025/08/06 23:47:57 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2025/08/06 23:47:57 DEBUG : two: Unchanged skipping
2025/08/06 23:47:58 DEBUG : three: Need to transfer - File not found at Destination
2025/08/06 23:47:58 DEBUG : three: Sizes differ (src 7 vs dst 5)
2025/08/06 23:47:58 DEBUG : three: Destination not found in --copy-dest
2025/08/06 23:47:59 DEBUG : three: sha1 = 370e613335f3cf2f19b2a2d35a86402f55c659f8 OK
2025/08/06 23:47:59 INFO  : three: Copied (new)
--- PASS: TestCopyFileCopyDest (16.59s)
=== RUN   TestCopyInplace
    run.go:180: Remote "B2 bucket rclone-test-solamos7geti", Local "Local file system at /tmp/rclone3246297378", Modify Window "1ms"
    copy_test.go:370: Partial uploads not supported
--- SKIP: TestCopyInplace (0.35s)
=== RUN   TestCopyLongFileName
    run.go:180: Remote "B2 bucket rclone-test-solamos7geti", Local "Local file system at /tmp/rclone3246297378", Modify Window "1ms"
    copy_test.go:403: Partial uploads not supported
--- SKIP: TestCopyLongFileName (0.35s)
=== RUN   TestCopyFileMaxTransfer
    run.go:180: Remote "B2 bucket rclone-test-solamos7geti", Local "Local file system at /tmp/rclone3246297378", Modify Window "1ms"
2025/08/06 23:48:02 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination
2025/08/06 23:48:03 DEBUG : TestCopyFileMaxTransfer/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK
2025/08/06 23:48:03 INFO  : TestCopyFileMaxTransfer/file1: Copied (new)
2025/08/06 23:48:03 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination
2025/08/06 23:48:03 ERROR : TestCopyFileMaxTransfer/file2: Failed to copy: Post "https://pod-000-1098-14.backblaze.com/b2api/v1/b2_upload_file/2f22bd53c02d8b1b9f880e1b/c001_v0001098_t0043": max transfer limit reached as set by --max-transfer
2025/08/06 23:48:03 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination
2025/08/06 23:48:04 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination
2025/08/06 23:48:05 DEBUG : TestCopyFileMaxTransfer/file4: sha1 = f0b0a1d60d92d4c1b9f1513d12738cc06f097656 OK
2025/08/06 23:48:05 INFO  : TestCopyFileMaxTransfer/file4: Copied (new)
--- PASS: TestCopyFileMaxTransfer (3.77s)
=== RUN   TestDeduplicateInteractive
    run.go:180: Remote "B2 bucket rclone-test-solamos7geti", Local "Local file system at /tmp/rclone3246297378", 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-solamos7geti", Local "Local file system at /tmp/rclone3246297378", 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-solamos7geti", Local "Local file system at /tmp/rclone3246297378", 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-solamos7geti", Local "Local file system at /tmp/rclone3246297378", 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-solamos7geti", Local "Local file system at /tmp/rclone3246297378", 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-solamos7geti", Local "Local file system at /tmp/rclone3246297378", Modify Window "1ms"
2025/08/06 23:48:09 INFO  : B2 bucket rclone-test-solamos7geti: Looking for duplicate sha1 hashes using newest mode.
2025/08/06 23:48:09 NOTICE: 3a45ea437035602f1d0a5b42d151f35c185385bb: Found 3 files with duplicate sha1 hashes
2025/08/06 23:48:09 INFO  : one: Deleted
2025/08/06 23:48:10 INFO  : also/one: Deleted
2025/08/06 23:48:10 NOTICE: 3a45ea437035602f1d0a5b42d151f35c185385bb: Deleted 2 extra copies
--- PASS: TestDeduplicateNewestByHash (3.18s)
=== RUN   TestDeduplicateOldest
    run.go:180: Remote "B2 bucket rclone-test-solamos7geti", Local "Local file system at /tmp/rclone3246297378", 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-solamos7geti", Local "Local file system at /tmp/rclone3246297378", 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-solamos7geti", Local "Local file system at /tmp/rclone3246297378", 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-solamos7geti", Local "Local file system at /tmp/rclone3246297378", 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-solamos7geti", Local "Local file system at /tmp/rclone3246297378", 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-solamos7geti", Local "Local file system at /tmp/rclone3246297378", Modify Window "1ms"
2025/08/06 23:48:15 DEBUG : a.txt: Excluded (Size Filter)
2025/08/06 23:48:15 DEBUG : a.txt: Excluded
2025/08/06 23:48:16 DEBUG : sub dir/hello world: Excluded (Size Filter)
2025/08/06 23:48:16 DEBUG : sub dir/hello world: Excluded
2025/08/06 23:48:16 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2025/08/06 23:48:16 DEBUG : sub dir/hello world2: Excluded
2025/08/06 23:48:16 DEBUG : sub dir/hello world: Excluded (Size Filter)
2025/08/06 23:48:16 DEBUG : sub dir/hello world: Excluded
2025/08/06 23:48:16 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2025/08/06 23:48:16 DEBUG : sub dir/hello world2: Excluded
2025/08/06 23:48:16 DEBUG : sub dir/ignore dir: Excluded
2025/08/06 23:48:17 DEBUG : sub dir/ignore dir: Excluded
--- PASS: TestListDirSorted (6.83s)
=== RUN   TestListDirSortedFn
    run.go:180: Remote "B2 bucket rclone-test-solamos7geti", Local "Local file system at /tmp/rclone3246297378", Modify Window "1ms"
2025/08/06 23:48:22 DEBUG : a.txt: Excluded (Size Filter)
2025/08/06 23:48:22 DEBUG : a.txt: Excluded
2025/08/06 23:48:22 DEBUG : sub dir/hello world: Excluded (Size Filter)
2025/08/06 23:48:22 DEBUG : sub dir/hello world: Excluded
2025/08/06 23:48:22 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2025/08/06 23:48:22 DEBUG : sub dir/hello world2: Excluded
2025/08/06 23:48:23 DEBUG : sub dir/hello world: Excluded (Size Filter)
2025/08/06 23:48:23 DEBUG : sub dir/hello world: Excluded
2025/08/06 23:48:23 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2025/08/06 23:48:23 DEBUG : sub dir/hello world2: Excluded
2025/08/06 23:48:23 DEBUG : sub dir/ignore dir: Excluded
2025/08/06 23:48:24 DEBUG : sub dir/ignore dir: Excluded
--- PASS: TestListDirSortedFn (6.45s)
=== RUN   TestListJSON
    run.go:180: Remote "B2 bucket rclone-test-solamos7geti", Local "Local file system at /tmp/rclone3246297378", 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 23:48:28 ERROR : file1: Failed to read hash: hash type not supported
=== RUN   TestListJSON/Metadata
--- PASS: TestListJSON (3.42s)
    --- PASS: TestListJSON/Default (0.18s)
    --- PASS: TestListJSON/FilesOnly (0.18s)
    --- PASS: TestListJSON/DirsOnly (0.18s)
    --- PASS: TestListJSON/Recurse (0.22s)
    --- PASS: TestListJSON/SubDir (0.18s)
    --- PASS: TestListJSON/NoModTime (0.18s)
    --- PASS: TestListJSON/NoMimeType (0.18s)
    --- PASS: TestListJSON/ShowHash (0.18s)
    --- PASS: TestListJSON/HashTypes (0.18s)
    --- PASS: TestListJSON/Metadata (0.18s)
=== RUN   TestStatJSON
    run.go:180: Remote "B2 bucket rclone-test-solamos7geti", Local "Local file system at /tmp/rclone3246297378", 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 23:48:33 DEBUG : Creating backend with remote "TestB2:rclone-test-solamos7geti/notfound"
--- PASS: TestStatJSON (6.16s)
    --- PASS: TestStatJSON/Root (0.18s)
    --- PASS: TestStatJSON/RootFilesOnly (0.00s)
    --- PASS: TestStatJSON/RootDirsOnly (0.18s)
    --- PASS: TestStatJSON/Dir (0.71s)
    --- PASS: TestStatJSON/DirWithTrailingSlash (0.18s)
    --- PASS: TestStatJSON/File (0.18s)
    --- PASS: TestStatJSON/NotFound (0.36s)
    --- PASS: TestStatJSON/DirFilesOnly (0.18s)
    --- PASS: TestStatJSON/FileFilesOnly (0.18s)
    --- PASS: TestStatJSON/NotFoundFilesOnly (0.18s)
    --- PASS: TestStatJSON/DirDirsOnly (0.18s)
    --- PASS: TestStatJSON/FileDirsOnly (0.18s)
    --- PASS: TestStatJSON/NotFoundDirsOnly (0.18s)
    --- PASS: TestStatJSON/RootNotFound (1.79s)
=== RUN   TestMkdir
    run.go:180: Remote "B2 bucket rclone-test-solamos7geti", Local "Local file system at /tmp/rclone3246297378", Modify Window "1ms"
2025/08/06 23:48:35 INFO  : B2 bucket rclone-test-solamos7geti: Making directory
2025/08/06 23:48:35 INFO  : B2 bucket rclone-test-solamos7geti: Making directory
--- PASS: TestMkdir (0.55s)
=== RUN   TestLsd
    run.go:180: Remote "B2 bucket rclone-test-solamos7geti", Local "Local file system at /tmp/rclone3246297378", Modify Window "1ms"
--- PASS: TestLsd (1.64s)
=== RUN   TestLs
    run.go:180: Remote "B2 bucket rclone-test-solamos7geti", Local "Local file system at /tmp/rclone3246297378", Modify Window "1ms"
--- PASS: TestLs (1.70s)
=== RUN   TestLsWithFilesFrom
    run.go:180: Remote "B2 bucket rclone-test-solamos7geti", Local "Local file system at /tmp/rclone3246297378", Modify Window "1ms"
2025/08/06 23:48:40 DEBUG : empty space: Excluded (FilesFrom Filter)
--- PASS: TestLsWithFilesFrom (2.42s)
=== RUN   TestLsLong
    run.go:180: Remote "B2 bucket rclone-test-solamos7geti", Local "Local file system at /tmp/rclone3246297378", Modify Window "1ms"
--- PASS: TestLsLong (1.82s)
=== RUN   TestHashSums
    run.go:180: Remote "B2 bucket rclone-test-solamos7geti", Local "Local file system at /tmp/rclone3246297378", Modify Window "1ms"
=== RUN   TestHashSums/Sha1
=== RUN   TestHashSums/Sha1Download
--- PASS: TestHashSums (2.03s)
    --- PASS: TestHashSums/Sha1 (0.18s)
    --- PASS: TestHashSums/Sha1Download (0.37s)
=== RUN   TestHashSumsWithErrors
2025/08/06 23:48:45 DEBUG : Creating backend with remote ":memory:"
2025/08/06 23:48:45 ERROR : file1: hash unsupported: hash type not supported
--- PASS: TestHashSumsWithErrors (0.00s)
=== RUN   TestHashStream
2025/08/06 23:48:45 DEBUG : Creating md5 hash of 0 bytes read from input stream
2025/08/06 23:48:45 DEBUG : Creating md5 hash of 0 bytes read from input stream
2025/08/06 23:48:45 DEBUG : Creating sha1 hash of 0 bytes read from input stream
2025/08/06 23:48:45 DEBUG : Creating sha1 hash of 0 bytes read from input stream
2025/08/06 23:48:45 DEBUG : Creating md5 hash of 12 bytes read from input stream
2025/08/06 23:48:45 DEBUG : Creating md5 hash of 12 bytes read from input stream
2025/08/06 23:48:45 DEBUG : Creating sha1 hash of 12 bytes read from input stream
2025/08/06 23:48:45 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-solamos7geti", Local "Local file system at /tmp/rclone3246297378", Modify Window "1ms"
--- PASS: TestCount (2.32s)
=== RUN   TestDelete
    run.go:180: Remote "B2 bucket rclone-test-solamos7geti", Local "Local file system at /tmp/rclone3246297378", Modify Window "1ms"
2025/08/06 23:48:49 DEBUG : Waiting for deletions to finish
2025/08/06 23:48:49 DEBUG : large: Excluded (Size Filter)
2025/08/06 23:48:49 INFO  : medium: Deleted
2025/08/06 23:48:49 INFO  : small: Deleted
--- PASS: TestDelete (2.55s)
=== RUN   TestMaxDelete
    run.go:180: Remote "B2 bucket rclone-test-solamos7geti", Local "Local file system at /tmp/rclone3246297378", Modify Window "1ms"
2025/08/06 23:48:51 DEBUG : Waiting for deletions to finish
2025/08/06 23:48:51 ERROR : small: Got fatal error on delete: --max-delete threshold reached
2025/08/06 23:48:52 INFO  : large: Deleted
2025/08/06 23:48:52 INFO  : medium: Deleted
--- PASS: TestMaxDelete (2.80s)
=== RUN   TestMaxDeleteSizeLargeFile
    run.go:180: Remote "B2 bucket rclone-test-solamos7geti", Local "Local file system at /tmp/rclone3246297378", Modify Window "1ms"
2025/08/06 23:48:54 DEBUG : Waiting for deletions to finish
2025/08/06 23:48:54 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached
2025/08/06 23:48:55 INFO  : medium: Deleted
2025/08/06 23:48:55 INFO  : small: Deleted
--- PASS: TestMaxDeleteSizeLargeFile (2.72s)
=== RUN   TestMaxDeleteSize
    run.go:180: Remote "B2 bucket rclone-test-solamos7geti", Local "Local file system at /tmp/rclone3246297378", Modify Window "1ms"
2025/08/06 23:48:57 DEBUG : Waiting for deletions to finish
2025/08/06 23:48:57 ERROR : small: Got fatal error on delete: --max-delete-size threshold reached
2025/08/06 23:48:57 INFO  : large: Deleted
2025/08/06 23:48:57 INFO  : medium: Deleted
--- PASS: TestMaxDeleteSize (2.67s)
=== RUN   TestReadFile
    run.go:180: Remote "B2 bucket rclone-test-solamos7geti", Local "Local file system at /tmp/rclone3246297378", Modify Window "1ms"
--- PASS: TestReadFile (2.13s)
=== RUN   TestRetry
2025/08/06 23:49:00 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 1/5
2025/08/06 23:49:00 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 2/5
2025/08/06 23:49:00 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms
2025/08/06 23:49:00 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms
2025/08/06 23:49:00 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms
2025/08/06 23:49:00 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms
2025/08/06 23:49:00 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-solamos7geti", Local "Local file system at /tmp/rclone3246297378", Modify Window "1ms"
--- PASS: TestCat (4.49s)
=== RUN   TestPurge
2025/08/06 23:49:05 DEBUG : Creating backend with remote "TestB2:rclone-test-ledituj0femi"
2025/08/06 23:49:05 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/08/06 23:49:06 DEBUG : Creating backend with remote "/tmp/rclone4212792651"
    run.go:180: Remote "B2 bucket rclone-test-ledituj0femi", Local "Local file system at /tmp/rclone4212792651", Modify Window "1ms"
2025/08/06 23:49:07 INFO  : A2: Making directory
2025/08/06 23:49:07 INFO  : A1/B2: Making directory
2025/08/06 23:49:07 INFO  : A1/B2/C2: Making directory
2025/08/06 23:49:07 INFO  : A1/B1/C3: Making directory
2025/08/06 23:49:07 INFO  : A3: Making directory
2025/08/06 23:49:07 INFO  : A3/B3: Making directory
2025/08/06 23:49:07 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 23:49:08 INFO  : B2 bucket rclone-test-ledituj0femi: cleaning bucket "rclone-test-ledituj0femi" of all files
2025/08/06 23:49:08 DEBUG : A1/B1/C1/one: Deleting (id "4_z3f02ddb3c09d8b1b9f880e1b_f105d43cae3d8936a_d20250806_m234907_c001_v0001161_t0007_u01754524147461")
    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 23:49:08 INFO  : B2 bucket rclone-test-ledituj0femi: cleaning bucket "rclone-test-ledituj0femi" of all files
2025/08/06 23:49:08 DEBUG : A1/two: Deleting (id "4_z3f02ddb3c09d8b1b9f880e1b_f105d43cae3d8936c_d20250806_m234907_c001_v0001161_t0058_u01754524147744")
2025/08/06 23:49:09 DEBUG : B2 bucket rclone-test-ledituj0femi: Purge remote
2025/08/06 23:49:09 INFO  : B2 bucket rclone-test-ledituj0femi: cleaning bucket "rclone-test-ledituj0femi" of all files
2025/08/06 23:49:09 NOTICE: purge failed: directory not found
--- PASS: TestPurge (4.49s)
=== RUN   TestRmdirsNoLeaveRoot
    run.go:180: Remote "B2 bucket rclone-test-solamos7geti", Local "Local file system at /tmp/rclone3246297378", Modify Window "1ms"
2025/08/06 23:49:10 INFO  : A2: Making directory
2025/08/06 23:49:10 INFO  : A1/B2: Making directory
2025/08/06 23:49:10 INFO  : A1/B2/C2: Making directory
2025/08/06 23:49:10 INFO  : A1/B1/C3: Making directory
2025/08/06 23:49:10 INFO  : A3: Making directory
2025/08/06 23:49:10 INFO  : A3/B3: Making directory
2025/08/06 23:49:10 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 23:49:11 DEBUG : removing 1 level 3 directories
2025/08/06 23:49:11 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 23:49:13 DEBUG : removing 1 level 0 directories
2025/08/06 23:49:13 INFO  : B2 bucket rclone-test-solamos7geti: Removing directory
2025/08/06 23:49:13 ERROR : Failed to rmdir: failed to delete bucket: Cannot delete non-empty bucket (400 cannot_delete_non_empty_bucket)
    operations_test.go:728: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:728
        	Error:      	Received unexpected error:
        	            	failed to remove directories: failed to delete bucket: Cannot delete non-empty bucket (400 cannot_delete_non_empty_bucket)
        	Test:       	TestRmdirsNoLeaveRoot
--- FAIL: TestRmdirsNoLeaveRoot (4.05s)
=== RUN   TestRmdirsLeaveRoot
    run.go:180: Remote "B2 bucket rclone-test-solamos7geti", Local "Local file system at /tmp/rclone3246297378", Modify Window "1ms"
2025/08/06 23:49:14 INFO  : A1: Making directory
2025/08/06 23:49:14 INFO  : A1/B1: Making directory
2025/08/06 23:49:14 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.91s)
=== RUN   TestRmdirsWithFilter
    run.go:180: Remote "B2 bucket rclone-test-solamos7geti", Local "Local file system at /tmp/rclone3246297378", Modify Window "1ms"
2025/08/06 23:49:14 INFO  : A1: Making directory
2025/08/06 23:49:14 INFO  : A1/B1: Making directory
2025/08/06 23:49:14 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.90s)
=== RUN   TestCopyURL
    run.go:180: Remote "B2 bucket rclone-test-solamos7geti", Local "Local file system at /tmp/rclone3246297378", Modify Window "1ms"
2025/08/06 23:49:16 DEBUG : filename.txt: File name found in url
2025/08/06 23:49:17 DEBUG : headerfilename.txt: filename found in Content-Disposition header.
--- PASS: TestCopyURL (3.31s)
=== RUN   TestCopyURLToWriter
--- PASS: TestCopyURLToWriter (0.00s)
=== RUN   TestMoveFile
    run.go:180: Remote "B2 bucket rclone-test-solamos7geti", Local "Local file system at /tmp/rclone3246297378", Modify Window "1ms"
2025/08/06 23:49:19 DEBUG : file1: Need to transfer - File not found at Destination
2025/08/06 23:49:19 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK
2025/08/06 23:49:19 INFO  : file1: Copied (new) to: sub/file2
2025/08/06 23:49:19 INFO  : file1: Deleted
2025/08/06 23:49:19 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2025/08/06 23:49:19 DEBUG : file1: Unchanged skipping
2025/08/06 23:49:19 INFO  : file1: Deleted
2025/08/06 23:49:20 DEBUG : B2 bucket rclone-test-solamos7geti: don't need to copy/move sub/file2, it is already at target location
--- PASS: TestMoveFile (1.74s)
=== RUN   TestMoveFileWithIgnoreExisting
    run.go:180: Remote "B2 bucket rclone-test-solamos7geti", Local "Local file system at /tmp/rclone3246297378", Modify Window "1ms"
2025/08/06 23:49:21 DEBUG : file1: Need to transfer - File not found at Destination
2025/08/06 23:49:21 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK
2025/08/06 23:49:21 INFO  : file1: Copied (new)
2025/08/06 23:49:21 INFO  : file1: Deleted
2025/08/06 23:49:21 DEBUG : file1: Destination exists, skipping
2025/08/06 23:49:21 DEBUG : file1: Not removing source file as destination file exists and --ignore-existing is set
--- PASS: TestMoveFileWithIgnoreExisting (1.64s)
=== RUN   TestCaseInsensitiveMoveFile
    run.go:180: Remote "B2 bucket rclone-test-solamos7geti", Local "Local file system at /tmp/rclone3246297378", Modify Window "1ms"
--- PASS: TestCaseInsensitiveMoveFile (0.37s)
=== RUN   TestCaseInsensitiveMoveFileDryRun
    run.go:180: Remote "B2 bucket rclone-test-solamos7geti", Local "Local file system at /tmp/rclone3246297378", Modify Window "1ms"
--- PASS: TestCaseInsensitiveMoveFileDryRun (0.36s)
=== RUN   TestMoveFileBackupDir
    run.go:180: Remote "B2 bucket rclone-test-solamos7geti", Local "Local file system at /tmp/rclone3246297378", Modify Window "1ms"
2025/08/06 23:49:23 DEBUG : Creating backend with remote "TestB2:rclone-test-solamos7geti/backup"
2025/08/06 23:49:24 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18)
2025/08/06 23:49:26 DEBUG : dst/file1: sha1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK
2025/08/06 23:49:26 INFO  : dst/file1: Copied (server-side copy)
2025/08/06 23:49:26 INFO  : dst/file1: Deleted
2025/08/06 23:49:26 DEBUG : dst/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK
2025/08/06 23:49:26 INFO  : dst/file1: Copied (new)
2025/08/06 23:49:26 INFO  : dst/file1: Deleted
--- PASS: TestMoveFileBackupDir (4.47s)
=== 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-solamos7geti", Local "Local file system at /tmp/rclone3246297378", Modify Window "1ms"
2025/08/06 23:49:29 INFO  : A1/B2: Making directory
2025/08/06 23:49:29 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 23:49:30 DEBUG : A1/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK
2025/08/06 23:49:30 INFO  : A1/B1/C1/four: Copied (server-side copy) to: A2/B1/C1/four
2025/08/06 23:49:31 INFO  : A1/B1/C1/four: Deleted
2025/08/06 23:49:31 DEBUG : A1/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK
2025/08/06 23:49:31 INFO  : A1/two: Copied (server-side copy) to: A2/two
2025/08/06 23:49:31 DEBUG : A1/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK
2025/08/06 23:49:31 INFO  : A1/B1/C2/five: Copied (server-side copy) to: A2/B1/C2/five
2025/08/06 23:49:31 DEBUG : A1/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK
2025/08/06 23:49:31 INFO  : A1/one: Copied (server-side copy) to: A2/one
2025/08/06 23:49:31 INFO  : A1/two: Deleted
2025/08/06 23:49:31 DEBUG : A1/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK
2025/08/06 23:49:31 INFO  : A1/B1/three: Copied (server-side copy) to: A2/B1/three
2025/08/06 23:49:31 INFO  : A1/B1/C2/five: Deleted
2025/08/06 23:49:31 INFO  : A1/one: Deleted
2025/08/06 23:49:31 INFO  : A1/B1/three: Deleted
    fstest.go:247: Filtering empty directory "A2/B2"
    fstest.go:247: Filtering empty directory "A2/B1/C3"
2025/08/06 23:49:32 DEBUG : A2/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK
2025/08/06 23:49:32 INFO  : A2/B1/C1/four: Copied (server-side copy) to: A3/B1/C1/four
2025/08/06 23:49:32 DEBUG : A2/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK
2025/08/06 23:49:32 INFO  : A2/one: Copied (server-side copy) to: A3/one
2025/08/06 23:49:32 DEBUG : A2/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK
2025/08/06 23:49:32 INFO  : A2/B1/C2/five: Copied (server-side copy) to: A3/B1/C2/five
2025/08/06 23:49:32 INFO  : A2/B1/C1/four: Deleted
2025/08/06 23:49:32 INFO  : A2/one: Deleted
2025/08/06 23:49:32 INFO  : A2/B1/C2/five: Deleted
2025/08/06 23:49:32 DEBUG : A2/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK
2025/08/06 23:49:32 INFO  : A2/B1/three: Copied (server-side copy) to: A3/B1/three
2025/08/06 23:49:33 DEBUG : A2/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK
2025/08/06 23:49:33 INFO  : A2/two: Copied (server-side copy) to: A3/two
2025/08/06 23:49:33 INFO  : A2/B1/three: Deleted
2025/08/06 23:49:33 INFO  : A2/two: Deleted
    fstest.go:247: Filtering empty directory "A3/B2"
    fstest.go:247: Filtering empty directory "A3/B1/C3"
2025/08/06 23:49:33 INFO  : B2 bucket rclone-test-solamos7geti: Can't DirMove - falling back to file moves: can't move directory - incompatible remotes
2025/08/06 23:49:33 DEBUG : A3/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK
2025/08/06 23:49:33 INFO  : A3/B1/C2/five: Copied (server-side copy) to: A4/B1/C2/five
2025/08/06 23:49:34 DEBUG : A3/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK
2025/08/06 23:49:34 INFO  : A3/B1/three: Copied (server-side copy) to: A4/B1/three
2025/08/06 23:49:34 DEBUG : A3/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK
2025/08/06 23:49:34 INFO  : A3/one: Copied (server-side copy) to: A4/one
2025/08/06 23:49:34 DEBUG : A3/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK
2025/08/06 23:49:34 INFO  : A3/B1/C1/four: Copied (server-side copy) to: A4/B1/C1/four
2025/08/06 23:49:34 INFO  : A3/B1/C2/five: Deleted
2025/08/06 23:49:34 INFO  : A3/B1/three: Deleted
2025/08/06 23:49:34 INFO  : A3/one: Deleted
2025/08/06 23:49:34 INFO  : A3/B1/C1/four: Deleted
2025/08/06 23:49:34 DEBUG : A3/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK
2025/08/06 23:49:34 INFO  : A3/two: Copied (server-side copy) to: A4/two
2025/08/06 23:49:34 INFO  : A3/two: Deleted
    fstest.go:247: Filtering empty directory "A4/B2"
    fstest.go:247: Filtering empty directory "A4/B1/C3"
--- PASS: TestDirMove (8.24s)
=== RUN   TestGetFsInfo
    run.go:180: Remote "B2 bucket rclone-test-solamos7geti", Local "Local file system at /tmp/rclone3246297378", Modify Window "1ms"
--- PASS: TestGetFsInfo (0.36s)
=== RUN   TestRcat
=== RUN   TestRcat/withChecksum=false,ignoreChecksum=false
    run.go:180: Remote "B2 bucket rclone-test-solamos7geti", Local "Local file system at /tmp/rclone3246297378", Modify Window "1ms"
2025/08/06 23:49:36 DEBUG : B2 bucket rclone-test-solamos7geti: File to upload is small (34 bytes), uploading instead of streaming
2025/08/06 23:49:37 DEBUG : no_checksum_small_file_from_pipe: sha1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK
2025/08/06 23:49:37 DEBUG : no_checksum_small_file_from_pipe: Size and sha1 of src and dst objects identical
2025/08/06 23:49:37 DEBUG : no_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload.
2025/08/06 23:49:38 DEBUG : no_checksum_big_file_from_pipe: sha1 = d0080f73aa8b494570ad63df1beb25c01ea9eea2 OK
2025/08/06 23:49:38 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-solamos7geti", Local "Local file system at /tmp/rclone3246297378", Modify Window "1ms"
2025/08/06 23:49:39 DEBUG : B2 bucket rclone-test-solamos7geti: File to upload is small (34 bytes), uploading instead of streaming
2025/08/06 23:49:39 DEBUG : with_checksum_small_file_from_pipe: sha1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK
2025/08/06 23:49:39 DEBUG : with_checksum_small_file_from_pipe: Size and sha1 of src and dst objects identical
2025/08/06 23:49:39 DEBUG : with_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload.
2025/08/06 23:49:40 DEBUG : with_checksum_big_file_from_pipe: sha1 = d0080f73aa8b494570ad63df1beb25c01ea9eea2 OK
2025/08/06 23:49:40 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-solamos7geti", Local "Local file system at /tmp/rclone3246297378", Modify Window "1ms"
2025/08/06 23:49:41 DEBUG : B2 bucket rclone-test-solamos7geti: File to upload is small (34 bytes), uploading instead of streaming
2025/08/06 23:49:42 DEBUG : ignore_checksum_small_file_from_pipe: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2025/08/06 23:49:42 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload.
2025/08/06 23:49:42 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-solamos7geti", Local "Local file system at /tmp/rclone3246297378", Modify Window "1ms"
2025/08/06 23:49:44 DEBUG : B2 bucket rclone-test-solamos7geti: File to upload is small (34 bytes), uploading instead of streaming
2025/08/06 23:49:44 DEBUG : ignore_checksum_small_file_from_pipe: Src hash empty - aborting Dst hash check
2025/08/06 23:49:44 DEBUG : ignore_checksum_small_file_from_pipe: Size of src and dst objects identical
2025/08/06 23:49:44 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload.
2025/08/06 23:49:45 DEBUG : ignore_checksum_big_file_from_pipe: Src hash empty - aborting Dst hash check
2025/08/06 23:49:45 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical
--- PASS: TestRcat (9.75s)
    --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (2.76s)
    --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (2.48s)
    --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (2.30s)
    --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (2.20s)
=== RUN   TestRcatMetadata
    run.go:180: Remote "B2 bucket rclone-test-solamos7geti", Local "Local file system at /tmp/rclone3246297378", 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-solamos7geti", Local "Local file system at /tmp/rclone3246297378", Modify Window "1ms"
2025/08/06 23:49:46 DEBUG : B2 bucket rclone-test-solamos7geti: File to upload is small (60 bytes), uploading instead of streaming
2025/08/06 23:49:47 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK
2025/08/06 23:49:47 DEBUG : potato2: Size and sha1 of src and dst objects identical
--- PASS: TestRcatSize (1.97s)
=== RUN   TestRcatSizeMetadata
    run.go:180: Remote "B2 bucket rclone-test-solamos7geti", Local "Local file system at /tmp/rclone3246297378", 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-solamos7geti", Local "Local file system at /tmp/rclone3246297378", Modify Window "1ms"
2025/08/06 23:49:50 DEBUG : B2 bucket rclone-test-solamos7geti: Touching "sub dir/potato3"
2025/08/06 23:49:50 DEBUG : B2 bucket rclone-test-solamos7geti: Touching "empty space"
2025/08/06 23:49:50 DEBUG : B2 bucket rclone-test-solamos7geti: Touching "potato2"
--- PASS: TestTouchDir (3.73s)
=== RUN   TestMkdirMetadata
    run.go:180: Remote "B2 bucket rclone-test-solamos7geti", Local "Local file system at /tmp/rclone3246297378", Modify Window "1ms"
    operations_test.go:1703: Skipping test as remote does not support MkdirMetadata
--- SKIP: TestMkdirMetadata (0.37s)
=== RUN   TestMkdirModTime
    run.go:180: Remote "B2 bucket rclone-test-solamos7geti", Local "Local file system at /tmp/rclone3246297378", Modify Window "1ms"
    operations_test.go:1722: Skipping test as remote does not support DirSetModTime or MkdirMetadata
--- SKIP: TestMkdirModTime (0.37s)
=== RUN   TestCopyDirMetadata
    run.go:180: Remote "B2 bucket rclone-test-solamos7geti", Local "Local file system at /tmp/rclone3246297378", Modify Window "1ms"
    operations_test.go:1743: Skipping test as remote does not support WriteDirMetadata or MkdirMetadata
--- SKIP: TestCopyDirMetadata (0.37s)
=== RUN   TestSetDirModTime
    run.go:180: Remote "B2 bucket rclone-test-solamos7geti", Local "Local file system at /tmp/rclone3246297378", 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-solamos7geti", Local "Local file system at /tmp/rclone3246297378", 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-solamos7geti", Local "Local file system at /tmp/rclone3246297378", Modify Window "1ms"
    operations_test.go:1892: Skipping as remote can't Move
--- SKIP: TestRemoveExisting (0.36s)
=== 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 23:49:54 DEBUG : B2 bucket rclone-test-solamos7geti: Purge remote
2025/08/06 23:49:54 INFO  : B2 bucket rclone-test-solamos7geti: cleaning bucket "rclone-test-solamos7geti" of all files
2025/08/06 23:49:55 DEBUG : A1/B1/C1/four: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f4086dd0977ebb76b_d20250806_m234931_c001_v7007000_t0000_u01754524171216")
2025/08/06 23:49:55 DEBUG : A1/B1/C1/four: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de327_d20250806_m234929_c001_v0001098_t0018_u01754524169133")
2025/08/06 23:49:55 DEBUG : A1/B1/C1/one: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfca3_d20250806_m234912_c001_v7007000_t0000_u01754524152824")
2025/08/06 23:49:55 DEBUG : A1/B1/C1/one: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de2ad_d20250806_m234910_c001_v0001098_t0023_u01754524150395")
2025/08/06 23:49:55 DEBUG : A1/B1/C2/five: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfd0c_d20250806_m234931_c001_v7007000_t0000_u01754524171513")
2025/08/06 23:49:55 DEBUG : A1/B1/C2/five: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de32b_d20250806_m234929_c001_v0001098_t0051_u01754524169475")
2025/08/06 23:49:55 DEBUG : A1/B1/three: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f403a8c8012261919_d20250806_m234931_c001_v7007000_t0000_u01754524171663")
2025/08/06 23:49:55 DEBUG : A1/B1/three: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de325_d20250806_m234928_c001_v0001098_t0039_u01754524168637")
2025/08/06 23:49:55 DEBUG : A1/one: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f420eba9bcbac941e_d20250806_m234931_c001_v7007000_t0000_u01754524171526")
2025/08/06 23:49:55 DEBUG : A1/one: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de31d_d20250806_m234927_c001_v0001098_t0044_u01754524167797")
2025/08/06 23:49:55 DEBUG : A1/two: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfd0a_d20250806_m234931_c001_v7007000_t0000_u01754524171447")
2025/08/06 23:49:55 DEBUG : A1/two: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de323_d20250806_m234928_c001_v0001098_t0036_u01754524168229")
2025/08/06 23:49:55 DEBUG : A1/two: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfca4_d20250806_m234913_c001_v7007000_t0000_u01754524153180")
2025/08/06 23:49:55 DEBUG : A1/two: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de2af_d20250806_m234910_c001_v0001098_t0022_u01754524150745")
2025/08/06 23:49:55 DEBUG : A2/B1/C1/four: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f410d31759faa5c2f_d20250806_m234932_c001_v7007000_t0000_u01754524172681")
2025/08/06 23:49:56 DEBUG : A2/B1/C1/four: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f109aad3b1af782cb_d20250806_m234930_c001_v0001178_t0004_u01754524170846")
2025/08/06 23:49:56 DEBUG : A2/B1/C2/five: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f403a8c801226191c_d20250806_m234932_c001_v7007000_t0000_u01754524172789")
2025/08/06 23:49:56 DEBUG : A2/B1/C2/five: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f1070be68c91a812b_d20250806_m234931_c001_v0001041_t0045_u01754524171078")
2025/08/06 23:49:56 DEBUG : A2/B1/three: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f420eba9bcbac9423_d20250806_m234933_c001_v7007000_t0000_u01754524173010")
2025/08/06 23:49:56 DEBUG : A2/B1/three: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11264959f7d50eda_d20250806_m234931_c001_v0001120_t0051_u01754524171204")
2025/08/06 23:49:56 DEBUG : A2/one: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfd14_d20250806_m234932_c001_v7007000_t0000_u01754524172768")
2025/08/06 23:49:56 DEBUG : A2/one: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f1009818b7bd80b05_d20250806_m234931_c001_v0001092_t0028_u01754524171225")
2025/08/06 23:49:56 DEBUG : A2/two: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfd17_d20250806_m234933_c001_v7007000_t0000_u01754524173095")
2025/08/06 23:49:56 DEBUG : A2/two: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f1097dd07bca299b4_d20250806_m234931_c001_v0001177_t0051_u01754524171237")
2025/08/06 23:49:56 DEBUG : A3/B1/C1/four: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfd1e_d20250806_m234934_c001_v7007000_t0000_u01754524174165")
2025/08/06 23:49:56 DEBUG : A3/B1/C1/four: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f1151c61639677725_d20250806_m234932_c001_v0001181_t0050_u01754524172471")
2025/08/06 23:49:56 DEBUG : A3/B1/C2/five: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f420eba9bcbac9428_d20250806_m234934_c001_v7007000_t0000_u01754524174079")
2025/08/06 23:49:56 DEBUG : A3/B1/C2/five: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f110a7117bf93fb5d_d20250806_m234932_c001_v0001037_t0011_u01754524172420")
2025/08/06 23:49:56 DEBUG : A3/B1/three: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f403a8c801226191e_d20250806_m234934_c001_v7007000_t0000_u01754524174091")
2025/08/06 23:49:56 DEBUG : A3/B1/three: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f10300f71cd6f94f4_d20250806_m234932_c001_v0001106_t0007_u01754524172429")
2025/08/06 23:49:56 DEBUG : A3/one: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f410d31759faa5c32_d20250806_m234934_c001_v7007000_t0000_u01754524174153")
2025/08/06 23:49:56 DEBUG : A3/one: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f114eb0345b8a8878_d20250806_m234932_c001_v0001161_t0042_u01754524172440")
2025/08/06 23:49:56 DEBUG : A3/two: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfd21_d20250806_m234934_c001_v7007000_t0000_u01754524174419")
2025/08/06 23:49:56 DEBUG : A3/two: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f1055c457f98f162f_d20250806_m234932_c001_v0001144_t0024_u01754524172451")
2025/08/06 23:49:56 DEBUG : A4/B1/C1/four: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfd25_d20250806_m234934_c001_v7007000_t0000_u01754524174972")
2025/08/06 23:49:56 DEBUG : A4/B1/C1/four: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f107cff1fb4cd9502_d20250806_m234933_c001_v0001091_t0029_u01754524173860")
2025/08/06 23:49:56 DEBUG : A4/B1/C2/five: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfd29_d20250806_m234935_c001_v7007000_t0000_u01754524175150")
2025/08/06 23:49:56 DEBUG : A4/B1/C2/five: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f105291a51f12c199_d20250806_m234933_c001_v0001181_t0001_u01754524173872")
2025/08/06 23:49:57 DEBUG : A4/B1/three: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfd2a_d20250806_m234935_c001_v7007000_t0000_u01754524175328")
2025/08/06 23:49:57 DEBUG : A4/B1/three: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f1179f3c69ce3598b_d20250806_m234933_c001_v0001179_t0044_u01754524173872")
2025/08/06 23:49:57 DEBUG : A4/one: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfd2d_d20250806_m234935_c001_v7007000_t0000_u01754524175506")
2025/08/06 23:49:57 DEBUG : A4/one: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f114c001ae66fbec6_d20250806_m234933_c001_v0001184_t0009_u01754524173901")
2025/08/06 23:49:57 DEBUG : A4/two: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfd2f_d20250806_m234935_c001_v7007000_t0000_u01754524175684")
2025/08/06 23:49:57 DEBUG : A4/two: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f10814313762ec6e6_d20250806_m234933_c001_v0001136_t0054_u01754524173889")
2025/08/06 23:49:57 DEBUG : BackupDir/one: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfb2d_d20250806_m234800_c001_v7007000_t0000_u01754524080021")
2025/08/06 23:49:57 DEBUG : BackupDir/one: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f100b4098eb923bae_d20250806_m234754_c001_v0001181_t0035_u01754524074715")
2025/08/06 23:49:57 DEBUG : CompareDest/one: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfb16_d20250806_m234744_c001_v7007000_t0000_u01754524064076")
2025/08/06 23:49:57 DEBUG : CompareDest/one: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de048_d20250806_m234739_c001_v0001098_t0038_u01754524059918")
2025/08/06 23:49:57 DEBUG : CompareDest/two: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfb17_d20250806_m234744_c001_v7007000_t0000_u01754524064256")
2025/08/06 23:49:57 DEBUG : CompareDest/two: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de04c_d20250806_m234741_c001_v0001098_t0047_u01754524061039")
2025/08/06 23:49:57 DEBUG : CopyDest/one: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfb2e_d20250806_m234800_c001_v7007000_t0000_u01754524080201")
2025/08/06 23:49:57 DEBUG : CopyDest/one: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de08a_d20250806_m234751_c001_v0001098_t0043_u01754524071681")
2025/08/06 23:49:57 DEBUG : CopyDest/three: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfb30_d20250806_m234800_c001_v7007000_t0000_u01754524080493")
2025/08/06 23:49:57 DEBUG : CopyDest/three: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de0ae_d20250806_m234757_c001_v0001098_t0046_u01754524077693")
2025/08/06 23:49:57 DEBUG : CopyDest/two: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfb31_d20250806_m234800_c001_v7007000_t0000_u01754524080671")
2025/08/06 23:49:57 DEBUG : CopyDest/two: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de09e_d20250806_m234755_c001_v0001098_t0019_u01754524075915")
2025/08/06 23:49:57 DEBUG : ReadFile: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f401bbe58f4379496_d20250806_m234900_c001_v7007000_t0000_u01754524140278")
2025/08/06 23:49:57 DEBUG : ReadFile: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de265_d20250806_m234858_c001_v0001098_t0039_u01754524138872")
2025/08/06 23:49:58 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfb3c_d20250806_m234805_c001_v7007000_t0000_u01754524085510")
2025/08/06 23:49:58 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de0d0_d20250806_m234802_c001_v0001098_t0004_u01754524082796")
2025/08/06 23:49:58 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfb3e_d20250806_m234805_c001_v7007000_t0000_u01754524085690")
2025/08/06 23:49:58 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de0da_d20250806_m234804_c001_v0001098_t0014_u01754524084810")
2025/08/06 23:49:58 DEBUG : a.txt: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfbf7_d20250806_m234824_c001_v7007000_t0000_u01754524104642")
2025/08/06 23:49:58 DEBUG : a.txt: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de140_d20250806_m234819_c001_v0001098_t0036_u01754524099620")
2025/08/06 23:49:58 DEBUG : a.txt: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfbb4_d20250806_m234818_c001_v7007000_t0000_u01754524098184")
2025/08/06 23:49:58 DEBUG : a.txt: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de112_d20250806_m234812_c001_v0001098_t0024_u01754524092787")
2025/08/06 23:49:58 DEBUG : also/one: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfb55_d20250806_m234809_c001_v7007000_t0000_u01754524089938")
2025/08/06 23:49:58 DEBUG : also/one: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de0f6_d20250806_m234808_c001_v0001098_t0007_u01754524088174")
2025/08/06 23:49:58 DEBUG : another: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfb5a_d20250806_m234810_c001_v7007000_t0000_u01754524090473")
2025/08/06 23:49:58 DEBUG : another: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de0fa_d20250806_m234808_c001_v0001098_t0014_u01754524088489")
2025/08/06 23:49:58 DEBUG : backup/dst/file1: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfce8_d20250806_m234927_c001_v7007000_t0000_u01754524167256")
2025/08/06 23:49:58 DEBUG : backup/dst/file1: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f1134ea1b5e7b4613_d20250806_m234925_c001_v0001099_t0026_u01754524165977")
2025/08/06 23:49:58 DEBUG : backup/dst/file1: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfadf_d20250806_m234733_c001_v7007000_t0000_u01754524053517")
2025/08/06 23:49:58 DEBUG : backup/dst/file1: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f1198e03c899a08c8_d20250806_m234732_c001_v0001090_t0025_u01754524052297")
2025/08/06 23:49:58 DEBUG : data/banana: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f4020b5c3d3c2cda2_d20250806_m234638_c001_v7007000_t0000_u01754523998948")
2025/08/06 23:49:58 DEBUG : data/banana: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4ddef3_d20250806_m234636_c001_v0001098_t0022_u01754523996579")
2025/08/06 23:49:58 DEBUG : data/banana: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4ddeab_d20250806_m234627_c001_v0001098_t0026_u01754523987582")
2025/08/06 23:49:58 DEBUG : data/potato: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f4020b5c3d3c2cda3_d20250806_m234639_c001_v7007000_t0000_u01754523999125")
2025/08/06 23:49:58 DEBUG : data/potato: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4ddef7_d20250806_m234637_c001_v0001098_t0050_u01754523997041")
2025/08/06 23:49:58 DEBUG : data/potato: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4ddebd_d20250806_m234629_c001_v0001098_t0022_u01754523989678")
2025/08/06 23:49:59 DEBUG : dst/file1: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfcea_d20250806_m234927_c001_v7007000_t0000_u01754524167435")
2025/08/06 23:49:59 DEBUG : dst/file1: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de313_d20250806_m234926_c001_v0001098_t0053_u01754524166483")
2025/08/06 23:49:59 DEBUG : dst/file1: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfce1_d20250806_m234926_c001_v7007000_t0000_u01754524166303")
2025/08/06 23:49:59 DEBUG : dst/file1: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de301_d20250806_m234923_c001_v0001098_t0023_u01754524163334")
2025/08/06 23:49:59 DEBUG : dst/file1: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfae1_d20250806_m234733_c001_v7007000_t0000_u01754524053696")
2025/08/06 23:49:59 DEBUG : dst/file1: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de020_d20250806_m234732_c001_v0001098_t0024_u01754524052825")
2025/08/06 23:49:59 DEBUG : dst/file1: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfad5_d20250806_m234732_c001_v7007000_t0000_u01754524052647")
2025/08/06 23:49:59 DEBUG : dst/file1: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de006_d20250806_m234729_c001_v0001098_t0032_u01754524049627")
2025/08/06 23:49:59 DEBUG : dst/one: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfb32_d20250806_m234800_c001_v7007000_t0000_u01754524080849")
2025/08/06 23:49:59 DEBUG : dst/one: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f1026a495f86f5ba5_d20250806_m234755_c001_v0001181_t0008_u01754524075531")
2025/08/06 23:49:59 DEBUG : dst/one: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f403a8c801226071b_d20250806_m234755_c001_v7007000_t0000_u01754524075269")
2025/08/06 23:49:59 DEBUG : dst/one: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de088_d20250806_m234751_c001_v0001098_t0021_u01754524071363")
2025/08/06 23:49:59 DEBUG : dst/one: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11918270e338b3d4_d20250806_m234750_c001_v0001115_t0040_u01754524070218")
2025/08/06 23:49:59 DEBUG : dst/one: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11918270e338b3cf_d20250806_m234748_c001_v0001115_t0050_u01754524068775")
2025/08/06 23:49:59 DEBUG : dst/one: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfb19_d20250806_m234744_c001_v7007000_t0000_u01754524064436")
2025/08/06 23:49:59 DEBUG : dst/one: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de046_d20250806_m234739_c001_v0001098_t0029_u01754524059645")
2025/08/06 23:49:59 DEBUG : dst/one: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f1194885969c7b78d_d20250806_m234738_c001_v0001120_t0017_u01754524058800")
2025/08/06 23:49:59 DEBUG : dst/one: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f1194885969c7b789_d20250806_m234737_c001_v0001120_t0014_u01754524057827")
2025/08/06 23:49:59 DEBUG : dst/three: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfb33_d20250806_m234801_c001_v7007000_t0000_u01754524081029")
2025/08/06 23:49:59 DEBUG : dst/three: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11918270e338b3ea_d20250806_m234759_c001_v0001115_t0044_u01754524079031")
2025/08/06 23:49:59 DEBUG : dst/two: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfb34_d20250806_m234801_c001_v7007000_t0000_u01754524081207")
2025/08/06 23:50:00 DEBUG : dst/two: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f1024f7e4051fa419_d20250806_m234756_c001_v0001098_t0044_u01754524076767")
2025/08/06 23:50:00 DEBUG : dst/two: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfb1a_d20250806_m234744_c001_v7007000_t0000_u01754524064613")
2025/08/06 23:50:00 DEBUG : dst/two: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f1194885969c7b793_d20250806_m234743_c001_v0001120_t0037_u01754524063236")
2025/08/06 23:50:00 DEBUG : empty space: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f40447bc2a26acf1b_d20250806_m234951_c001_v7007000_t0000_u01754524191865")
2025/08/06 23:50:00 DEBUG : empty space: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f107ce9605f099339_d20250806_m234951_c001_v0001130_t0035_u01754524191135")
2025/08/06 23:50:00 DEBUG : empty space: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de3c4_d20250806_m234949_c001_v0001098_t0029_u01754524189218")
2025/08/06 23:50:00 DEBUG : empty space: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfc4f_d20250806_m234847_c001_v7007000_t0000_u01754524127413")
2025/08/06 23:50:00 DEBUG : empty space: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de204_d20250806_m234846_c001_v0001098_t0032_u01754524126249")
2025/08/06 23:50:00 DEBUG : empty space: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfc45_d20250806_m234845_c001_v7007000_t0000_u01754524125281")
2025/08/06 23:50:00 DEBUG : empty space: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de1ea_d20250806_m234844_c001_v0001098_t0027_u01754524124078")
2025/08/06 23:50:00 DEBUG : empty space: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfc40_d20250806_m234843_c001_v7007000_t0000_u01754524123247")
2025/08/06 23:50:00 DEBUG : empty space: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de1da_d20250806_m234842_c001_v0001098_t0022_u01754524122444")
2025/08/06 23:50:00 DEBUG : empty space: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfc38_d20250806_m234841_c001_v7007000_t0000_u01754524121396")
2025/08/06 23:50:00 DEBUG : empty space: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de1ce_d20250806_m234839_c001_v0001098_t0036_u01754524119913")
2025/08/06 23:50:00 DEBUG : empty space: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfc2f_d20250806_m234838_c001_v7007000_t0000_u01754524118995")
2025/08/06 23:50:00 DEBUG : empty space: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de1c8_d20250806_m234838_c001_v0001098_t0043_u01754524118149")
2025/08/06 23:50:00 DEBUG : empty space: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f4020b5c3d3c2cd98_d20250806_m234621_c001_v7007000_t0000_u01754523981741")
2025/08/06 23:50:00 DEBUG : empty space: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4dde77_d20250806_m234619_c001_v0001098_t0040_u01754523979097")
2025/08/06 23:50:00 DEBUG : empty space: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f4020b5c3d3c2cd86_d20250806_m234617_c001_v7007000_t0000_u01754523977261")
2025/08/06 23:50:00 DEBUG : empty space: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4dde4b_d20250806_m234613_c001_v0001098_t0046_u01754523973115")
2025/08/06 23:50:00 DEBUG : empty space: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f4020b5c3d3c2cd67_d20250806_m234610_c001_v7007000_t0000_u01754523970709")
2025/08/06 23:50:00 DEBUG : empty space: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4dde27_d20250806_m234607_c001_v0001098_t0012_u01754523967964")
2025/08/06 23:50:01 DEBUG : file1: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfcc4_d20250806_m234922_c001_v7007000_t0000_u01754524162238")
2025/08/06 23:50:01 DEBUG : file1: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de2f5_d20250806_m234921_c001_v0001098_t0006_u01754524161131")
2025/08/06 23:50:01 DEBUG : file1: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfcae_d20250806_m234918_c001_v7007000_t0000_u01754524158284")
2025/08/06 23:50:01 DEBUG : file1: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f41414d4aee4de2bd_d20250806_m234916_c001_v0001098_t0043_u01754524156433")
2025/08/06 23:50:01 DEBUG : file1: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f401bbe58f437949f_d20250806_m234905_c001_v7007000_t0000_u01754524145007")
2025/08/06 23:50:01 DEBUG : file1: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de27c_d20250806_m234901_c001_v0001098_t0038_u01754524141055")
2025/08/06 23:50:01 DEBUG : file1: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfc22_d20250806_m234835_c001_v7007000_t0000_u01754524115115")
2025/08/06 23:50:01 DEBUG : file1: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de18c_d20250806_m234829_c001_v0001098_t0019_u01754524109486")
2025/08/06 23:50:01 DEBUG : file1: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfc13_d20250806_m234828_c001_v7007000_t0000_u01754524108953")
2025/08/06 23:50:01 DEBUG : file1: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de172_d20250806_m234826_c001_v0001098_t0001_u01754524106070")
2025/08/06 23:50:01 DEBUG : file2: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfcb0_d20250806_m234918_c001_v7007000_t0000_u01754524158464")
2025/08/06 23:50:01 DEBUG : file2: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f41414d4aee4de2cf_d20250806_m234917_c001_v0001098_t0043_u01754524157743")
2025/08/06 23:50:01 DEBUG : file2: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f401bbe58f43794a0_d20250806_m234905_c001_v7007000_t0000_u01754524145185")
2025/08/06 23:50:01 DEBUG : file2: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de284_d20250806_m234901_c001_v0001098_t0055_u01754524141478")
2025/08/06 23:50:01 DEBUG : filename.txt: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfcb2_d20250806_m234918_c001_v7007000_t0000_u01754524158674")
2025/08/06 23:50:01 DEBUG : filename.txt: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de2c3_d20250806_m234916_c001_v0001098_t0045_u01754524156985")
2025/08/06 23:50:01 DEBUG : headerfilename.txt: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfcb4_d20250806_m234918_c001_v7007000_t0000_u01754524158853")
2025/08/06 23:50:01 DEBUG : headerfilename.txt: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de2c7_d20250806_m234917_c001_v0001098_t0040_u01754524157335")
2025/08/06 23:50:01 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfd53_d20250806_m234945_c001_v7007000_t0000_u01754524185608")
2025/08/06 23:50:01 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de39d_d20250806_m234944_c001_v0001098_t0007_u01754524184429")
2025/08/06 23:50:01 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfd4a_d20250806_m234943_c001_v7007000_t0000_u01754524183413")
2025/08/06 23:50:02 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de391_d20250806_m234942_c001_v0001098_t0020_u01754524182176")
2025/08/06 23:50:02 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfd54_d20250806_m234945_c001_v7007000_t0000_u01754524185788")
2025/08/06 23:50:02 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de399_d20250806_m234944_c001_v0001098_t0011_u01754524184132")
2025/08/06 23:50:02 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfd4b_d20250806_m234943_c001_v7007000_t0000_u01754524183591")
2025/08/06 23:50:02 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de38f_d20250806_m234941_c001_v0001098_t0025_u01754524181833")
2025/08/06 23:50:02 DEBUG : large: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f4182b5d0435d4597_d20250806_m234857_c001_v7007000_t0000_u01754524137601")
2025/08/06 23:50:02 DEBUG : large: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de253_d20250806_m234856_c001_v0001098_t0015_u01754524136867")
2025/08/06 23:50:02 DEBUG : large: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f4182b5d0435d4594_d20250806_m234855_c001_v7007000_t0000_u01754524135477")
2025/08/06 23:50:02 DEBUG : large: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de245_d20250806_m234854_c001_v0001098_t0005_u01754524134215")
2025/08/06 23:50:02 DEBUG : large: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f401bbe58f4379484_d20250806_m234852_c001_v7007000_t0000_u01754524132029")
2025/08/06 23:50:02 DEBUG : large: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de237_d20250806_m234851_c001_v0001098_t0029_u01754524131364")
2025/08/06 23:50:02 DEBUG : large: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f401bbe58f4379480_d20250806_m234850_c001_v7007000_t0000_u01754524130320")
2025/08/06 23:50:02 DEBUG : large: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de21f_d20250806_m234848_c001_v0001098_t0013_u01754524128931")
2025/08/06 23:50:02 DEBUG : medium: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f401bbe58f4379490_d20250806_m234857_c001_v7007000_t0000_u01754524137609")
2025/08/06 23:50:02 DEBUG : medium: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de24f_d20250806_m234856_c001_v0001098_t0032_u01754524136486")
2025/08/06 23:50:02 DEBUG : medium: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f401bbe58f437948b_d20250806_m234854_c001_v7007000_t0000_u01754524134915")
2025/08/06 23:50:02 DEBUG : medium: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de243_d20250806_m234853_c001_v0001098_t0022_u01754524133768")
2025/08/06 23:50:02 DEBUG : medium: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f401bbe58f4379485_d20250806_m234852_c001_v7007000_t0000_u01754524132210")
2025/08/06 23:50:02 DEBUG : medium: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de231_d20250806_m234850_c001_v0001098_t0034_u01754524130993")
2025/08/06 23:50:02 DEBUG : medium: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfc59_d20250806_m234849_c001_v7007000_t0000_u01754524129589")
2025/08/06 23:50:02 DEBUG : medium: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de21b_d20250806_m234848_c001_v0001098_t0056_u01754524128429")
2025/08/06 23:50:02 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfd36_d20250806_m234938_c001_v7007000_t0000_u01754524178633")
2025/08/06 23:50:03 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de36f_d20250806_m234937_c001_v0001098_t0024_u01754524177400")
2025/08/06 23:50:03 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfd37_d20250806_m234938_c001_v7007000_t0000_u01754524178811")
2025/08/06 23:50:03 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de36b_d20250806_m234936_c001_v0001098_t0057_u01754524176942")
2025/08/06 23:50:03 DEBUG : not-one: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfb5b_d20250806_m234810_c001_v7007000_t0000_u01754524090652")
2025/08/06 23:50:03 DEBUG : not-one: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de0fe_d20250806_m234808_c001_v0001098_t0048_u01754524088811")
2025/08/06 23:50:03 DEBUG : one: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfb51_d20250806_m234809_c001_v7007000_t0000_u01754524089759")
2025/08/06 23:50:03 DEBUG : one: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de0f0_d20250806_m234807_c001_v0001098_t0043_u01754524087821")
2025/08/06 23:50:03 DEBUG : potato1: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfd58_d20250806_m234947_c001_v7007000_t0000_u01754524187602")
2025/08/06 23:50:03 DEBUG : potato1: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de3b3_d20250806_m234946_c001_v0001098_t0011_u01754524186517")
2025/08/06 23:50:03 DEBUG : potato2: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f40447bc2a26acf1c_d20250806_m234952_c001_v7007000_t0000_u01754524192045")
2025/08/06 23:50:03 DEBUG : potato2: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11838b97b32e8f48_d20250806_m234951_c001_v0001038_t0039_u01754524191137")
2025/08/06 23:50:03 DEBUG : potato2: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de3c2_d20250806_m234948_c001_v0001098_t0054_u01754524188857")
2025/08/06 23:50:03 DEBUG : potato2: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f400c3e48f946d70f_d20250806_m234948_c001_v7007000_t0000_u01754524188130")
2025/08/06 23:50:03 DEBUG : potato2: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de3b5_d20250806_m234946_c001_v0001098_t0024_u01754524186810")
2025/08/06 23:50:03 DEBUG : potato2: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfc50_d20250806_m234847_c001_v7007000_t0000_u01754524127593")
2025/08/06 23:50:03 DEBUG : potato2: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de200_d20250806_m234845_c001_v0001098_t0053_u01754524125814")
2025/08/06 23:50:03 DEBUG : potato2: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfc47_d20250806_m234845_c001_v7007000_t0000_u01754524125459")
2025/08/06 23:50:03 DEBUG : potato2: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de1e4_d20250806_m234843_c001_v0001098_t0022_u01754524123781")
2025/08/06 23:50:03 DEBUG : potato2: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfc41_d20250806_m234843_c001_v7007000_t0000_u01754524123426")
2025/08/06 23:50:03 DEBUG : potato2: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de1d6_d20250806_m234841_c001_v0001098_t0011_u01754524121959")
2025/08/06 23:50:03 DEBUG : potato2: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfc39_d20250806_m234841_c001_v7007000_t0000_u01754524121601")
2025/08/06 23:50:04 DEBUG : potato2: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de1cc_d20250806_m234839_c001_v0001098_t0045_u01754524119539")
2025/08/06 23:50:04 DEBUG : potato2: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfc30_d20250806_m234839_c001_v7007000_t0000_u01754524119174")
2025/08/06 23:50:04 DEBUG : potato2: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de1c4_d20250806_m234837_c001_v0001098_t0031_u01754524117839")
2025/08/06 23:50:04 DEBUG : potato2: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f4020b5c3d3c2cd99_d20250806_m234621_c001_v7007000_t0000_u01754523981918")
2025/08/06 23:50:04 DEBUG : potato2: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4dde7d_d20250806_m234619_c001_v0001098_t0016_u01754523979804")
2025/08/06 23:50:04 DEBUG : potato2: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f4020b5c3d3c2cd88_d20250806_m234617_c001_v7007000_t0000_u01754523977438")
2025/08/06 23:50:04 DEBUG : potato2: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4dde53_d20250806_m234614_c001_v0001098_t0050_u01754523974160")
2025/08/06 23:50:04 DEBUG : potato2: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f4020b5c3d3c2cd69_d20250806_m234610_c001_v7007000_t0000_u01754523970886")
2025/08/06 23:50:04 DEBUG : potato2: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4dde29_d20250806_m234608_c001_v0001098_t0040_u01754523968668")
2025/08/06 23:50:04 DEBUG : remotepotato: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f4020b5c3d3c2cd9a_d20250806_m234622_c001_v7007000_t0000_u01754523982094")
2025/08/06 23:50:04 DEBUG : remotepotato: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4dde85_d20250806_m234620_c001_v0001098_t0041_u01754523980683")
2025/08/06 23:50:04 DEBUG : remotepotato: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f4020b5c3d3c2cd8a_d20250806_m234617_c001_v7007000_t0000_u01754523977615")
2025/08/06 23:50:04 DEBUG : remotepotato: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4dde61_d20250806_m234615_c001_v0001098_t0033_u01754523975804")
2025/08/06 23:50:04 DEBUG : remotepotato: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f4020b5c3d3c2cd6a_d20250806_m234611_c001_v7007000_t0000_u01754523971062")
2025/08/06 23:50:04 DEBUG : remotepotato: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4dde31_d20250806_m234609_c001_v0001098_t0040_u01754523969647")
2025/08/06 23:50:04 DEBUG : rutabaga: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f4020b5c3d3c2cd9b_d20250806_m234622_c001_v7007000_t0000_u01754523982272")
2025/08/06 23:50:04 DEBUG : rutabaga: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4dde6f_d20250806_m234618_c001_v0001098_t0046_u01754523978145")
2025/08/06 23:50:04 DEBUG : rutabaga: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f4020b5c3d3c2cd8c_d20250806_m234617_c001_v7007000_t0000_u01754523977793")
2025/08/06 23:50:04 DEBUG : rutabaga: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4dde41_d20250806_m234611_c001_v0001098_t0007_u01754523971595")
2025/08/06 23:50:04 DEBUG : rutabaga: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f4020b5c3d3c2cd6d_d20250806_m234611_c001_v7007000_t0000_u01754523971240")
2025/08/06 23:50:05 DEBUG : rutabaga: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4dde23_d20250806_m234607_c001_v0001098_t0009_u01754523967080")
2025/08/06 23:50:05 DEBUG : small: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f401bbe58f4379493_d20250806_m234858_c001_v7007000_t0000_u01754524138151")
2025/08/06 23:50:05 DEBUG : small: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de24d_d20250806_m234856_c001_v0001098_t0027_u01754524136200")
2025/08/06 23:50:05 DEBUG : small: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f4182b5d0435d4593_d20250806_m234854_c001_v7007000_t0000_u01754524134932")
2025/08/06 23:50:05 DEBUG : small: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de241_d20250806_m234853_c001_v0001098_t0032_u01754524133479")
2025/08/06 23:50:05 DEBUG : small: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f401bbe58f4379487_d20250806_m234852_c001_v7007000_t0000_u01754524132754")
2025/08/06 23:50:05 DEBUG : small: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de22f_d20250806_m234850_c001_v0001098_t0040_u01754524130680")
2025/08/06 23:50:05 DEBUG : small: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfc5b_d20250806_m234849_c001_v7007000_t0000_u01754524129779")
2025/08/06 23:50:05 DEBUG : small: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de216_d20250806_m234848_c001_v0001098_t0010_u01754524128131")
2025/08/06 23:50:05 DEBUG : sub dir/hello world: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfc2a_d20250806_m234837_c001_v7007000_t0000_u01754524117484")
2025/08/06 23:50:05 DEBUG : sub dir/hello world: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de1be_d20250806_m234836_c001_v0001098_t0049_u01754524116552")
2025/08/06 23:50:05 DEBUG : sub dir/hello world: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfbfa_d20250806_m234824_c001_v7007000_t0000_u01754524104820")
2025/08/06 23:50:05 DEBUG : sub dir/hello world: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de148_d20250806_m234820_c001_v0001098_t0015_u01754524100337")
2025/08/06 23:50:05 DEBUG : sub dir/hello world: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfbb7_d20250806_m234818_c001_v7007000_t0000_u01754524098363")
2025/08/06 23:50:05 DEBUG : sub dir/hello world: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de11a_d20250806_m234813_c001_v0001098_t0029_u01754524093406")
2025/08/06 23:50:05 DEBUG : sub dir/hello world2: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfbfb_d20250806_m234824_c001_v7007000_t0000_u01754524104999")
2025/08/06 23:50:05 DEBUG : sub dir/hello world2: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de14e_d20250806_m234820_c001_v0001098_t0016_u01754524100668")
2025/08/06 23:50:05 DEBUG : sub dir/hello world2: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfbba_d20250806_m234818_c001_v7007000_t0000_u01754524098544")
2025/08/06 23:50:05 DEBUG : sub dir/hello world2: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de11e_d20250806_m234813_c001_v0001098_t0043_u01754524093700")
2025/08/06 23:50:05 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfbfe_d20250806_m234825_c001_v7007000_t0000_u01754524105177")
2025/08/06 23:50:05 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de152_d20250806_m234821_c001_v0001098_t0047_u01754524101043")
2025/08/06 23:50:06 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfbbd_d20250806_m234818_c001_v7007000_t0000_u01754524098727")
2025/08/06 23:50:06 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de122_d20250806_m234814_c001_v0001098_t0008_u01754524094080")
2025/08/06 23:50:06 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfc02_d20250806_m234825_c001_v7007000_t0000_u01754524105358")
2025/08/06 23:50:06 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de158_d20250806_m234821_c001_v0001098_t0030_u01754524101423")
2025/08/06 23:50:06 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfbc0_d20250806_m234818_c001_v7007000_t0000_u01754524098906")
2025/08/06 23:50:06 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de126_d20250806_m234814_c001_v0001098_t0046_u01754524094470")
2025/08/06 23:50:06 DEBUG : sub dir/potato3: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f40447bc2a26acf1d_d20250806_m234952_c001_v7007000_t0000_u01754524192225")
2025/08/06 23:50:06 DEBUG : sub dir/potato3: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f114b01c76754c973_d20250806_m234950_c001_v0001101_t0057_u01754524190756")
2025/08/06 23:50:06 DEBUG : sub dir/potato3: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de3c6_d20250806_m234949_c001_v0001098_t0045_u01754524189503")
2025/08/06 23:50:06 DEBUG : sub dir/potato3: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfc52_d20250806_m234847_c001_v7007000_t0000_u01754524127776")
2025/08/06 23:50:06 DEBUG : sub dir/potato3: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de20c_d20250806_m234846_c001_v0001098_t0010_u01754524126591")
2025/08/06 23:50:06 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfc05_d20250806_m234825_c001_v7007000_t0000_u01754524105536")
2025/08/06 23:50:06 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de15e_d20250806_m234821_c001_v0001098_t0037_u01754524101796")
2025/08/06 23:50:06 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfbc3_d20250806_m234819_c001_v7007000_t0000_u01754524099086")
2025/08/06 23:50:06 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de12a_d20250806_m234814_c001_v0001098_t0053_u01754524094829")
2025/08/06 23:50:06 DEBUG : sub/file2: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfcbd_d20250806_m234920_c001_v7007000_t0000_u01754524160589")
2025/08/06 23:50:06 DEBUG : sub/file2: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de2e1_d20250806_m234919_c001_v0001098_t0024_u01754524159394")
2025/08/06 23:50:06 DEBUG : sub/file2: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfc23_d20250806_m234835_c001_v7007000_t0000_u01754524115293")
2025/08/06 23:50:06 DEBUG : sub/file2: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de190_d20250806_m234829_c001_v0001098_t0043_u01754524109783")
2025/08/06 23:50:06 DEBUG : sub/file2: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfc14_d20250806_m234829_c001_v7007000_t0000_u01754524109131")
2025/08/06 23:50:06 DEBUG : sub/file2: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de178_d20250806_m234826_c001_v0001098_t0010_u01754524106429")
2025/08/06 23:50:07 DEBUG : sub/file2: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfab4_d20250806_m234728_c001_v7007000_t0000_u01754524048913")
2025/08/06 23:50:07 DEBUG : sub/file2: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4ddff2_d20250806_m234727_c001_v0001098_t0052_u01754524047323")
2025/08/06 23:50:07 DEBUG : test-multithread-abort: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f4020b5c3d3c2cd54_d20250806_m234606_c001_v7007000_t0000_u01754523966181")
2025/08/06 23:50:07 DEBUG : test-multithread-abort: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4ddd12_d20250806_m234526_c001_v0001098_t0021_u01754523926458")
2025/08/06 23:50:07 DEBUG : test-multithread-copy-false-201326591-2: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f4152d501fbc93f88_d20250806_m234200_c001_v7007000_t0000_u01754523720843")
2025/08/06 23:50:07 DEBUG : test-multithread-copy-false-201326591-2: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4dd8a9_d20250806_m234122_c001_v0001098_t0054_u01754523682025")
2025/08/06 23:50:07 DEBUG : test-multithread-copy-false-201326592-2: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f405dae8c8bf8fdde_d20250806_m234242_c001_v7007000_t0000_u01754523762192")
2025/08/06 23:50:07 DEBUG : test-multithread-copy-false-201326592-2: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4dd93c_d20250806_m234203_c001_v0001098_t0011_u01754523723878")
2025/08/06 23:50:07 DEBUG : test-multithread-copy-false-201326593-2: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f410d31759faa5295_d20250806_m234322_c001_v7007000_t0000_u01754523802465")
2025/08/06 23:50:07 DEBUG : test-multithread-copy-false-201326593-2: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4dd9ca_d20250806_m234245_c001_v0001098_t0016_u01754523765152")
2025/08/06 23:50:07 DEBUG : test-multithread-copy-true-201326591-2: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f406a7f0939779264_d20250806_m234403_c001_v7007000_t0000_u01754523843257")
2025/08/06 23:50:07 DEBUG : test-multithread-copy-true-201326591-2: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f20447bc2a26ac6d9_d20250806_m234343_c001_v0001117_t0052_u01754523823564")
2025/08/06 23:50:07 DEBUG : test-multithread-copy-true-201326592-2: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f4134a0094414e72e_d20250806_m234444_c001_v7007000_t0000_u01754523884142")
2025/08/06 23:50:07 DEBUG : test-multithread-copy-true-201326592-2: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f219d9c909522138e_d20250806_m234423_c001_v0001181_t0032_u01754523863787")
2025/08/06 23:50:07 DEBUG : test-multithread-copy-true-201326593-2: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f410d31759faa53b0_d20250806_m234525_c001_v7007000_t0000_u01754523925165")
2025/08/06 23:50:07 DEBUG : test-multithread-copy-true-201326593-2: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f205a2b473ad0a280_d20250806_m234504_c001_v0001184_t0008_u01754523904916")
2025/08/06 23:50:07 DEBUG : test.sum: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f4020b5c3d3c2cda4_d20250806_m234639_c001_v7007000_t0000_u01754523999344")
2025/08/06 23:50:07 DEBUG : test.sum: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4ddefb_d20250806_m234637_c001_v0001098_t0038_u01754523997425")
2025/08/06 23:50:07 DEBUG : test.sum: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4ddee9_d20250806_m234635_c001_v0001098_t0020_u01754523995341")
2025/08/06 23:50:07 DEBUG : test.sum: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4ddedf_d20250806_m234634_c001_v0001098_t0040_u01754523994102")
2025/08/06 23:50:07 DEBUG : test.sum: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4dded9_d20250806_m234632_c001_v0001098_t0029_u01754523992893")
2025/08/06 23:50:08 DEBUG : test.sum: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4ddecb_d20250806_m234631_c001_v0001098_t0036_u01754523991227")
2025/08/06 23:50:08 DEBUG : test.sum: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4ddec1_d20250806_m234630_c001_v0001098_t0035_u01754523989999")
2025/08/06 23:50:08 DEBUG : test.sum: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4ddeaf_d20250806_m234627_c001_v0001098_t0032_u01754523987920")
2025/08/06 23:50:08 DEBUG : test.sum: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f4020b5c3d3c2cd9c_d20250806_m234624_c001_v7007000_t0000_u01754523984680")
2025/08/06 23:50:08 DEBUG : test.sum: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4dde91_d20250806_m234623_c001_v0001098_t0015_u01754523983751")
2025/08/06 23:50:08 DEBUG : test.sum: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4dde8d_d20250806_m234622_c001_v0001098_t0009_u01754523982624")
2025/08/06 23:50:08 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfd3e_d20250806_m234941_c001_v7007000_t0000_u01754524181109")
2025/08/06 23:50:08 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de37f_d20250806_m234939_c001_v0001098_t0040_u01754524179838")
2025/08/06 23:50:08 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfd3f_d20250806_m234941_c001_v7007000_t0000_u01754524181292")
2025/08/06 23:50:08 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de37b_d20250806_m234939_c001_v0001098_t0050_u01754524179350")
2025/08/06 23:50:08 DEBUG : zend.txt: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfc08_d20250806_m234825_c001_v7007000_t0000_u01754524105714")
2025/08/06 23:50:08 DEBUG : zend.txt: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de144_d20250806_m234820_c001_v0001098_t0004_u01754524100001")
2025/08/06 23:50:08 DEBUG : zend.txt: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f409f11bb014bfbc7_d20250806_m234819_c001_v7007000_t0000_u01754524099265")
2025/08/06 23:50:08 DEBUG : zend.txt: Deleting (id "4_z2f22bd53c02d8b1b9f880e1b_f11414d4aee4de114_d20250806_m234813_c001_v0001098_t0029_u01754524093107")
"./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -fast-list" - Finished OK in 8m52.631330206s (try 1/5)