"./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose" - Starting (try 1/5) 2026/09/25 01:34:24 DEBUG : Creating backend with remote "TestB2:rclone-test-hedekel9dubi" 2026/09/25 01:34:24 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/25 01:34:24 DEBUG : Creating backend with remote "/tmp/rclone2832011420" === 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:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" 2026/09/25 01:34:25 DEBUG : chunksize-probe: Cancelling large file upload === RUN TestMultithreadCopy/upload=false,size=16777215,streams=2 2026/09/25 01:34:31 DEBUG : test-multithread-copy-false-16777215-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2026/09/25 01:34:31 DEBUG : test-multithread-copy-false-16777215-2: multi-thread copy: write buffer set to 131072 2026/09/25 01:34:31 DEBUG : test-multithread-copy-false-16777215-2: multi-thread copy: chunk size 64Mi was bigger than source file size 16.000Mi 2026/09/25 01:34:31 DEBUG : test-multithread-copy-false-16777215-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2026/09/25 01:34:31 DEBUG : test-multithread-copy-false-16777215-2: multi-thread copy: number of streams 4 was bigger than number of chunks 1 2026/09/25 01:34:31 DEBUG : test-multithread-copy-false-16777215-2: Starting multi-thread copy with 1 chunks of size 16.000Mi with 1 parallel streams 2026/09/25 01:34:31 DEBUG : test-multithread-copy-false-16777215-2: multi-thread copy: chunk 1/1 (0-16777215) size 16.000Mi starting 2026/09/25 01:34:31 DEBUG : test-multithread-copy-false-16777215-2: writing chunk 0 2026/09/25 01:34:33 DEBUG : test-multithread-copy-false-16777215-2: multi-thread copy: chunk 1/1 (0-16777215) size 16.000Mi finished 2026/09/25 01:34:33 DEBUG : test-multithread-copy-false-16777215-2: Finished multi-thread copy with 1 parts of size 16.000Mi === RUN TestMultithreadCopy/upload=false,size=16777216,streams=2 2026/09/25 01:34:34 DEBUG : test-multithread-copy-false-16777216-2: Clearing upload URL because of error: Post "https://pod-000-1101-17.backblaze.com/b2api/v1/b2_upload_file/0fd20de3303ddb6baf080e1b/c001_v0001101_t0050": write tcp 65.108.87.141:55442->206.190.209.108:443: use of closed network connection 2026/09/25 01:34:34 DEBUG : pacer: low level retry 1/1 (error Post "https://pod-000-1101-17.backblaze.com/b2api/v1/b2_upload_file/0fd20de3303ddb6baf080e1b/c001_v0001101_t0050": write tcp 65.108.87.141:55442->206.190.209.108:443: use of closed network connection) 2026/09/25 01:34:34 DEBUG : pacer: Rate limited, increasing sleep to 20ms === NAME TestMultithreadCopy run.go:299: Retry Put of "test-multithread-copy-false-16777216-2" to B2 bucket rclone-test-hedekel9dubi: 1/10 (Post "https://pod-000-1101-17.backblaze.com/b2api/v1/b2_upload_file/0fd20de3303ddb6baf080e1b/c001_v0001101_t0050": write tcp 65.108.87.141:55442->206.190.209.108:443: use of closed network connection) 2026/09/25 01:34:37 DEBUG : pacer: Reducing sleep to 10ms 2026/09/25 01:34:41 DEBUG : test-multithread-copy-false-16777216-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2026/09/25 01:34:41 DEBUG : test-multithread-copy-false-16777216-2: multi-thread copy: write buffer set to 131072 2026/09/25 01:34:41 DEBUG : test-multithread-copy-false-16777216-2: multi-thread copy: chunk size 64Mi was bigger than source file size 16Mi 2026/09/25 01:34:41 DEBUG : test-multithread-copy-false-16777216-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2026/09/25 01:34:41 DEBUG : test-multithread-copy-false-16777216-2: multi-thread copy: number of streams 4 was bigger than number of chunks 1 2026/09/25 01:34:41 DEBUG : test-multithread-copy-false-16777216-2: Starting multi-thread copy with 1 chunks of size 16Mi with 1 parallel streams 2026/09/25 01:34:41 DEBUG : test-multithread-copy-false-16777216-2: multi-thread copy: chunk 1/1 (0-16777216) size 16Mi starting 2026/09/25 01:34:41 DEBUG : test-multithread-copy-false-16777216-2: writing chunk 0 2026/09/25 01:34:43 DEBUG : test-multithread-copy-false-16777216-2: multi-thread copy: chunk 1/1 (0-16777216) size 16Mi finished 2026/09/25 01:34:43 DEBUG : test-multithread-copy-false-16777216-2: Finished multi-thread copy with 1 parts of size 16Mi === RUN TestMultithreadCopy/upload=false,size=16777217,streams=2 2026/09/25 01:34:48 DEBUG : test-multithread-copy-false-16777217-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2026/09/25 01:34:48 DEBUG : test-multithread-copy-false-16777217-2: multi-thread copy: write buffer set to 131072 2026/09/25 01:34:48 DEBUG : test-multithread-copy-false-16777217-2: multi-thread copy: chunk size 64Mi was bigger than source file size 16.000Mi 2026/09/25 01:34:48 DEBUG : test-multithread-copy-false-16777217-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2026/09/25 01:34:48 DEBUG : test-multithread-copy-false-16777217-2: multi-thread copy: number of streams 4 was bigger than number of chunks 1 2026/09/25 01:34:48 DEBUG : test-multithread-copy-false-16777217-2: Starting multi-thread copy with 1 chunks of size 16.000Mi with 1 parallel streams 2026/09/25 01:34:48 DEBUG : test-multithread-copy-false-16777217-2: multi-thread copy: chunk 1/1 (0-16777217) size 16.000Mi starting 2026/09/25 01:34:48 DEBUG : test-multithread-copy-false-16777217-2: writing chunk 0 2026/09/25 01:34:49 DEBUG : test-multithread-copy-false-16777217-2: multi-thread copy: chunk 1/1 (0-16777217) size 16.000Mi finished 2026/09/25 01:34:49 DEBUG : test-multithread-copy-false-16777217-2: Finished multi-thread copy with 1 parts of size 16.000Mi === RUN TestMultithreadCopy/upload=true,size=16777215,streams=2 2026/09/25 01:34:52 DEBUG : test-multithread-copy-true-16777215-2: multi-thread copy: disabling buffering because source is local disk 2026/09/25 01:34:52 DEBUG : test-multithread-copy-true-16777215-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2026/09/25 01:34:52 DEBUG : test-multithread-copy-true-16777215-2: multi-thread copy: number of streams 4 was bigger than number of chunks 2 2026/09/25 01:34:52 DEBUG : test-multithread-copy-true-16777215-2: Starting multi-thread copy with 2 chunks of size 8Mi with 2 parallel streams 2026/09/25 01:34:52 DEBUG : test-multithread-copy-true-16777215-2: multi-thread copy: chunk 2/2 (8388608-16777215) size 8.000Mi starting 2026/09/25 01:34:52 DEBUG : test-multithread-copy-true-16777215-2: Sending chunk 1 length 8388607 2026/09/25 01:34:52 DEBUG : test-multithread-copy-true-16777215-2: multi-thread copy: chunk 1/2 (0-8388608) size 8Mi starting 2026/09/25 01:34:52 DEBUG : test-multithread-copy-true-16777215-2: Sending chunk 0 length 8388608 2026/09/25 01:34:59 DEBUG : test-multithread-copy-true-16777215-2: Error sending chunk 0 (retry=true): incident id 79d5bceab39e-af52fbc1a0b285ff (500 internal_error): &api.Error{Status:500, Code:"internal_error", Message:"incident id 79d5bceab39e-af52fbc1a0b285ff"} 2026/09/25 01:34:59 DEBUG : test-multithread-copy-true-16777215-2: Clearing part upload URL because of error: incident id 79d5bceab39e-af52fbc1a0b285ff (500 internal_error) 2026/09/25 01:34:59 DEBUG : pacer: low level retry 1/10 (error incident id 79d5bceab39e-af52fbc1a0b285ff (500 internal_error)) 2026/09/25 01:34:59 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/09/25 01:34:59 DEBUG : test-multithread-copy-true-16777215-2: Sending chunk 0 length 8388608 2026/09/25 01:34:59 DEBUG : pacer: Reducing sleep to 10ms 2026/09/25 01:35:00 DEBUG : test-multithread-copy-true-16777215-2: Seek from 8388608 to 0 2026/09/25 01:35:00 DEBUG : test-multithread-copy-true-16777215-2: Error sending chunk 1 (retry=true): incident id 79d5bceab39e-7fb72bf223662062 (500 internal_error): &api.Error{Status:500, Code:"internal_error", Message:"incident id 79d5bceab39e-7fb72bf223662062"} 2026/09/25 01:35:00 DEBUG : test-multithread-copy-true-16777215-2: Clearing part upload URL because of error: incident id 79d5bceab39e-7fb72bf223662062 (500 internal_error) 2026/09/25 01:35:00 DEBUG : pacer: low level retry 1/10 (error incident id 79d5bceab39e-7fb72bf223662062 (500 internal_error)) 2026/09/25 01:35:00 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/09/25 01:35:00 DEBUG : test-multithread-copy-true-16777215-2: Sending chunk 1 length 8388607 2026/09/25 01:35:00 DEBUG : pacer: Reducing sleep to 10ms 2026/09/25 01:35:01 DEBUG : test-multithread-copy-true-16777215-2: Seek from 8388607 to 0 2026/09/25 01:35:07 DEBUG : test-multithread-copy-true-16777215-2: Error sending chunk 0 (retry=true): incident id 79d5bceab39e-b423ef650c782c4b (500 internal_error): &api.Error{Status:500, Code:"internal_error", Message:"incident id 79d5bceab39e-b423ef650c782c4b"} 2026/09/25 01:35:07 DEBUG : test-multithread-copy-true-16777215-2: Clearing part upload URL because of error: incident id 79d5bceab39e-b423ef650c782c4b (500 internal_error) 2026/09/25 01:35:07 DEBUG : pacer: low level retry 2/10 (error incident id 79d5bceab39e-b423ef650c782c4b (500 internal_error)) 2026/09/25 01:35:07 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/09/25 01:35:07 DEBUG : test-multithread-copy-true-16777215-2: Sending chunk 0 length 8388608 2026/09/25 01:35:08 DEBUG : pacer: Reducing sleep to 10ms 2026/09/25 01:35:08 DEBUG : test-multithread-copy-true-16777215-2: Seek from 8388608 to 0 2026/09/25 01:35:08 DEBUG : test-multithread-copy-true-16777215-2: Error sending chunk 1 (retry=true): incident id 79d5bceab39e-9d310b9c19e3019d (500 internal_error): &api.Error{Status:500, Code:"internal_error", Message:"incident id 79d5bceab39e-9d310b9c19e3019d"} 2026/09/25 01:35:08 DEBUG : test-multithread-copy-true-16777215-2: Clearing part upload URL because of error: incident id 79d5bceab39e-9d310b9c19e3019d (500 internal_error) 2026/09/25 01:35:08 DEBUG : pacer: low level retry 2/10 (error incident id 79d5bceab39e-9d310b9c19e3019d (500 internal_error)) 2026/09/25 01:35:08 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/09/25 01:35:08 DEBUG : test-multithread-copy-true-16777215-2: Sending chunk 1 length 8388607 2026/09/25 01:35:08 DEBUG : pacer: Reducing sleep to 10ms 2026/09/25 01:35:09 DEBUG : test-multithread-copy-true-16777215-2: Seek from 8388607 to 0 2026/09/25 01:35:15 DEBUG : test-multithread-copy-true-16777215-2: Error sending chunk 0 (retry=true): incident id 79d5bceab39e-305caa27d9fff3f7 (500 internal_error): &api.Error{Status:500, Code:"internal_error", Message:"incident id 79d5bceab39e-305caa27d9fff3f7"} 2026/09/25 01:35:15 DEBUG : test-multithread-copy-true-16777215-2: Clearing part upload URL because of error: incident id 79d5bceab39e-305caa27d9fff3f7 (500 internal_error) 2026/09/25 01:35:15 DEBUG : pacer: low level retry 3/10 (error incident id 79d5bceab39e-305caa27d9fff3f7 (500 internal_error)) 2026/09/25 01:35:15 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/09/25 01:35:15 DEBUG : test-multithread-copy-true-16777215-2: Sending chunk 0 length 8388608 2026/09/25 01:35:15 DEBUG : pacer: Reducing sleep to 10ms 2026/09/25 01:35:16 DEBUG : test-multithread-copy-true-16777215-2: Seek from 8388608 to 0 2026/09/25 01:35:16 DEBUG : test-multithread-copy-true-16777215-2: Error sending chunk 1 (retry=true): incident id 79d5bceab39e-50d6f6731522107b (500 internal_error): &api.Error{Status:500, Code:"internal_error", Message:"incident id 79d5bceab39e-50d6f6731522107b"} 2026/09/25 01:35:16 DEBUG : test-multithread-copy-true-16777215-2: Clearing part upload URL because of error: incident id 79d5bceab39e-50d6f6731522107b (500 internal_error) 2026/09/25 01:35:16 DEBUG : pacer: low level retry 3/10 (error incident id 79d5bceab39e-50d6f6731522107b (500 internal_error)) 2026/09/25 01:35:16 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/09/25 01:35:16 DEBUG : test-multithread-copy-true-16777215-2: Sending chunk 1 length 8388607 2026/09/25 01:35:16 DEBUG : pacer: Reducing sleep to 10ms 2026/09/25 01:35:16 DEBUG : test-multithread-copy-true-16777215-2: Seek from 8388607 to 0 2026/09/25 01:35:23 DEBUG : test-multithread-copy-true-16777215-2: Error sending chunk 1 (retry=true): incident id 79d5bceab39e-8c2d3823af783b59 (500 internal_error): &api.Error{Status:500, Code:"internal_error", Message:"incident id 79d5bceab39e-8c2d3823af783b59"} 2026/09/25 01:35:23 DEBUG : test-multithread-copy-true-16777215-2: Clearing part upload URL because of error: incident id 79d5bceab39e-8c2d3823af783b59 (500 internal_error) 2026/09/25 01:35:23 DEBUG : pacer: low level retry 4/10 (error incident id 79d5bceab39e-8c2d3823af783b59 (500 internal_error)) 2026/09/25 01:35:23 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/09/25 01:35:23 DEBUG : test-multithread-copy-true-16777215-2: Sending chunk 1 length 8388607 2026/09/25 01:35:24 DEBUG : test-multithread-copy-true-16777215-2: Error sending chunk 0 (retry=true): incident id 79d5bceab39e-fab4497ba875f3cb (500 internal_error): &api.Error{Status:500, Code:"internal_error", Message:"incident id 79d5bceab39e-fab4497ba875f3cb"} 2026/09/25 01:35:24 DEBUG : test-multithread-copy-true-16777215-2: Clearing part upload URL because of error: incident id 79d5bceab39e-fab4497ba875f3cb (500 internal_error) 2026/09/25 01:35:24 DEBUG : pacer: low level retry 4/10 (error incident id 79d5bceab39e-fab4497ba875f3cb (500 internal_error)) 2026/09/25 01:35:24 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/09/25 01:35:24 DEBUG : test-multithread-copy-true-16777215-2: Sending chunk 0 length 8388608 2026/09/25 01:35:24 DEBUG : pacer: Reducing sleep to 20ms 2026/09/25 01:35:24 DEBUG : pacer: Reducing sleep to 10ms 2026/09/25 01:35:24 DEBUG : test-multithread-copy-true-16777215-2: Seek from 8388607 to 0 2026/09/25 01:35:25 DEBUG : test-multithread-copy-true-16777215-2: Seek from 8388608 to 0 2026/09/25 01:35:31 DEBUG : test-multithread-copy-true-16777215-2: Error sending chunk 1 (retry=true): incident id 79d5bceab39e-39f1760fb71df86d (500 internal_error): &api.Error{Status:500, Code:"internal_error", Message:"incident id 79d5bceab39e-39f1760fb71df86d"} 2026/09/25 01:35:31 DEBUG : test-multithread-copy-true-16777215-2: Clearing part upload URL because of error: incident id 79d5bceab39e-39f1760fb71df86d (500 internal_error) 2026/09/25 01:35:31 DEBUG : pacer: low level retry 5/10 (error incident id 79d5bceab39e-39f1760fb71df86d (500 internal_error)) 2026/09/25 01:35:31 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/09/25 01:35:31 DEBUG : test-multithread-copy-true-16777215-2: Sending chunk 1 length 8388607 2026/09/25 01:35:31 DEBUG : pacer: Reducing sleep to 10ms 2026/09/25 01:35:32 DEBUG : test-multithread-copy-true-16777215-2: Seek from 8388607 to 0 2026/09/25 01:35:32 DEBUG : test-multithread-copy-true-16777215-2: Error sending chunk 0 (retry=true): incident id 79d5bceab39e-23f8cca30094080d (500 internal_error): &api.Error{Status:500, Code:"internal_error", Message:"incident id 79d5bceab39e-23f8cca30094080d"} 2026/09/25 01:35:32 DEBUG : test-multithread-copy-true-16777215-2: Clearing part upload URL because of error: incident id 79d5bceab39e-23f8cca30094080d (500 internal_error) 2026/09/25 01:35:32 DEBUG : pacer: low level retry 5/10 (error incident id 79d5bceab39e-23f8cca30094080d (500 internal_error)) 2026/09/25 01:35:32 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/09/25 01:35:32 DEBUG : test-multithread-copy-true-16777215-2: Sending chunk 0 length 8388608 2026/09/25 01:35:32 DEBUG : pacer: Reducing sleep to 10ms 2026/09/25 01:35:33 DEBUG : test-multithread-copy-true-16777215-2: Seek from 8388608 to 0 2026/09/25 01:35:39 DEBUG : test-multithread-copy-true-16777215-2: Error sending chunk 0 (retry=true): incident id 79d5bceab39e-33f147109668d59d (500 internal_error): &api.Error{Status:500, Code:"internal_error", Message:"incident id 79d5bceab39e-33f147109668d59d"} 2026/09/25 01:35:39 DEBUG : test-multithread-copy-true-16777215-2: Clearing part upload URL because of error: incident id 79d5bceab39e-33f147109668d59d (500 internal_error) 2026/09/25 01:35:39 DEBUG : pacer: low level retry 6/10 (error incident id 79d5bceab39e-33f147109668d59d (500 internal_error)) 2026/09/25 01:35:39 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/09/25 01:35:39 DEBUG : test-multithread-copy-true-16777215-2: Sending chunk 0 length 8388608 2026/09/25 01:35:40 DEBUG : test-multithread-copy-true-16777215-2: Error sending chunk 1 (retry=true): incident id 79d5bceab39e-11c20ced2cdd4ba4 (500 internal_error): &api.Error{Status:500, Code:"internal_error", Message:"incident id 79d5bceab39e-11c20ced2cdd4ba4"} 2026/09/25 01:35:40 DEBUG : test-multithread-copy-true-16777215-2: Clearing part upload URL because of error: incident id 79d5bceab39e-11c20ced2cdd4ba4 (500 internal_error) 2026/09/25 01:35:40 DEBUG : pacer: low level retry 6/10 (error incident id 79d5bceab39e-11c20ced2cdd4ba4 (500 internal_error)) 2026/09/25 01:35:40 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/09/25 01:35:40 DEBUG : test-multithread-copy-true-16777215-2: Sending chunk 1 length 8388607 2026/09/25 01:35:40 DEBUG : pacer: Reducing sleep to 20ms 2026/09/25 01:35:40 DEBUG : pacer: Reducing sleep to 10ms 2026/09/25 01:35:40 DEBUG : test-multithread-copy-true-16777215-2: Seek from 8388608 to 0 2026/09/25 01:35:41 DEBUG : test-multithread-copy-true-16777215-2: Seek from 8388607 to 0 2026/09/25 01:35:48 DEBUG : test-multithread-copy-true-16777215-2: Error sending chunk 0 (retry=true): incident id 79d5bceab39e-b9378e92077ef746 (500 internal_error): &api.Error{Status:500, Code:"internal_error", Message:"incident id 79d5bceab39e-b9378e92077ef746"} 2026/09/25 01:35:48 DEBUG : test-multithread-copy-true-16777215-2: Clearing part upload URL because of error: incident id 79d5bceab39e-b9378e92077ef746 (500 internal_error) 2026/09/25 01:35:48 DEBUG : pacer: low level retry 7/10 (error incident id 79d5bceab39e-b9378e92077ef746 (500 internal_error)) 2026/09/25 01:35:48 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/09/25 01:35:48 DEBUG : test-multithread-copy-true-16777215-2: Sending chunk 0 length 8388608 2026/09/25 01:35:48 DEBUG : pacer: Reducing sleep to 10ms 2026/09/25 01:35:48 DEBUG : test-multithread-copy-true-16777215-2: Error sending chunk 1 (retry=true): incident id 79d5bceab39e-067d55a8ab38399d (500 internal_error): &api.Error{Status:500, Code:"internal_error", Message:"incident id 79d5bceab39e-067d55a8ab38399d"} 2026/09/25 01:35:48 DEBUG : test-multithread-copy-true-16777215-2: Clearing part upload URL because of error: incident id 79d5bceab39e-067d55a8ab38399d (500 internal_error) 2026/09/25 01:35:48 DEBUG : pacer: low level retry 7/10 (error incident id 79d5bceab39e-067d55a8ab38399d (500 internal_error)) 2026/09/25 01:35:48 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/09/25 01:35:48 DEBUG : test-multithread-copy-true-16777215-2: Sending chunk 1 length 8388607 2026/09/25 01:35:48 DEBUG : pacer: Reducing sleep to 10ms 2026/09/25 01:35:48 DEBUG : test-multithread-copy-true-16777215-2: Seek from 8388608 to 0 2026/09/25 01:35:48 DEBUG : test-multithread-copy-true-16777215-2: Seek from 8388607 to 0 2026/09/25 01:35:55 DEBUG : test-multithread-copy-true-16777215-2: Error sending chunk 1 (retry=true): incident id 79d5bceab39e-7e65ac1b3b3a7255 (500 internal_error): &api.Error{Status:500, Code:"internal_error", Message:"incident id 79d5bceab39e-7e65ac1b3b3a7255"} 2026/09/25 01:35:55 DEBUG : test-multithread-copy-true-16777215-2: Clearing part upload URL because of error: incident id 79d5bceab39e-7e65ac1b3b3a7255 (500 internal_error) 2026/09/25 01:35:55 DEBUG : pacer: low level retry 8/10 (error incident id 79d5bceab39e-7e65ac1b3b3a7255 (500 internal_error)) 2026/09/25 01:35:55 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/09/25 01:35:55 DEBUG : test-multithread-copy-true-16777215-2: Sending chunk 1 length 8388607 2026/09/25 01:35:56 DEBUG : test-multithread-copy-true-16777215-2: Error sending chunk 0 (retry=true): incident id 79d5bceab39e-017e16e30a9a45d8 (500 internal_error): &api.Error{Status:500, Code:"internal_error", Message:"incident id 79d5bceab39e-017e16e30a9a45d8"} 2026/09/25 01:35:56 DEBUG : test-multithread-copy-true-16777215-2: Clearing part upload URL because of error: incident id 79d5bceab39e-017e16e30a9a45d8 (500 internal_error) 2026/09/25 01:35:56 DEBUG : pacer: low level retry 8/10 (error incident id 79d5bceab39e-017e16e30a9a45d8 (500 internal_error)) 2026/09/25 01:35:56 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/09/25 01:35:56 DEBUG : test-multithread-copy-true-16777215-2: Sending chunk 0 length 8388608 2026/09/25 01:35:56 DEBUG : pacer: Reducing sleep to 20ms 2026/09/25 01:35:56 DEBUG : pacer: Reducing sleep to 10ms 2026/09/25 01:35:56 DEBUG : test-multithread-copy-true-16777215-2: Seek from 8388607 to 0 2026/09/25 01:35:57 DEBUG : test-multithread-copy-true-16777215-2: Seek from 8388608 to 0 2026/09/25 01:36:03 DEBUG : test-multithread-copy-true-16777215-2: Error sending chunk 1 (retry=true): incident id 79d5bceab39e-0476ac850ce7274f (500 internal_error): &api.Error{Status:500, Code:"internal_error", Message:"incident id 79d5bceab39e-0476ac850ce7274f"} 2026/09/25 01:36:03 DEBUG : test-multithread-copy-true-16777215-2: Clearing part upload URL because of error: incident id 79d5bceab39e-0476ac850ce7274f (500 internal_error) 2026/09/25 01:36:03 DEBUG : pacer: low level retry 9/10 (error incident id 79d5bceab39e-0476ac850ce7274f (500 internal_error)) 2026/09/25 01:36:03 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/09/25 01:36:03 DEBUG : test-multithread-copy-true-16777215-2: Sending chunk 1 length 8388607 2026/09/25 01:36:03 DEBUG : pacer: Reducing sleep to 10ms 2026/09/25 01:36:04 DEBUG : test-multithread-copy-true-16777215-2: Seek from 8388607 to 0 2026/09/25 01:36:04 DEBUG : test-multithread-copy-true-16777215-2: Error sending chunk 0 (retry=true): incident id 79d5bceab39e-324e9503ab6cef06 (500 internal_error): &api.Error{Status:500, Code:"internal_error", Message:"incident id 79d5bceab39e-324e9503ab6cef06"} 2026/09/25 01:36:04 DEBUG : test-multithread-copy-true-16777215-2: Clearing part upload URL because of error: incident id 79d5bceab39e-324e9503ab6cef06 (500 internal_error) 2026/09/25 01:36:04 DEBUG : pacer: low level retry 9/10 (error incident id 79d5bceab39e-324e9503ab6cef06 (500 internal_error)) 2026/09/25 01:36:04 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/09/25 01:36:04 DEBUG : test-multithread-copy-true-16777215-2: Sending chunk 0 length 8388608 2026/09/25 01:36:04 DEBUG : pacer: Reducing sleep to 10ms 2026/09/25 01:36:05 DEBUG : test-multithread-copy-true-16777215-2: Seek from 8388608 to 0 2026/09/25 01:36:10 DEBUG : test-multithread-copy-true-16777215-2: Error sending chunk 1 (retry=true): incident id 79d5bceab39e-54a438afed1db4b9 (500 internal_error): &api.Error{Status:500, Code:"internal_error", Message:"incident id 79d5bceab39e-54a438afed1db4b9"} 2026/09/25 01:36:10 DEBUG : test-multithread-copy-true-16777215-2: Clearing part upload URL because of error: incident id 79d5bceab39e-54a438afed1db4b9 (500 internal_error) 2026/09/25 01:36:10 DEBUG : pacer: low level retry 10/10 (error incident id 79d5bceab39e-54a438afed1db4b9 (500 internal_error)) 2026/09/25 01:36:10 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/09/25 01:36:10 DEBUG : test-multithread-copy-true-16777215-2: Error sending chunk 1: incident id 79d5bceab39e-54a438afed1db4b9 (500 internal_error) 2026/09/25 01:36:10 DEBUG : test-multithread-copy-true-16777215-2: multi-thread copy: chunk 2/2 failed: multi-thread copy: failed to write chunk: incident id 79d5bceab39e-54a438afed1db4b9 (500 internal_error) 2026/09/25 01:36:10 DEBUG : test-multithread-copy-true-16777215-2: Error sending chunk 0 (retry=false): Post "https://pod-000-1115-08.backblaze.com/b2api/v1/b2_upload_part/4_z0fd20de3303ddb6baf080e1b_f215a7f71ed2154b3_d20260925_m013452_c001_v0001115_t0037_u01790300092131/0024": multi-thread copy: failed to write chunk: incident id 79d5bceab39e-54a438afed1db4b9 (500 internal_error): &url.Error{Op:"Post", URL:"https://pod-000-1115-08.backblaze.com/b2api/v1/b2_upload_part/4_z0fd20de3303ddb6baf080e1b_f215a7f71ed2154b3_d20260925_m013452_c001_v0001115_t0037_u01790300092131/0024", Err:(*fmt.wrapError)(0x39baab909540)} 2026/09/25 01:36:10 DEBUG : pacer: Reducing sleep to 10ms 2026/09/25 01:36:10 DEBUG : test-multithread-copy-true-16777215-2: Error sending chunk 0: Post "https://pod-000-1115-08.backblaze.com/b2api/v1/b2_upload_part/4_z0fd20de3303ddb6baf080e1b_f215a7f71ed2154b3_d20260925_m013452_c001_v0001115_t0037_u01790300092131/0024": multi-thread copy: failed to write chunk: incident id 79d5bceab39e-54a438afed1db4b9 (500 internal_error) 2026/09/25 01:36:10 DEBUG : test-multithread-copy-true-16777215-2: multi-thread copy: chunk 1/2 failed: multi-thread copy: failed to write chunk: Post "https://pod-000-1115-08.backblaze.com/b2api/v1/b2_upload_part/4_z0fd20de3303ddb6baf080e1b_f215a7f71ed2154b3_d20260925_m013452_c001_v0001115_t0037_u01790300092131/0024": multi-thread copy: failed to write chunk: incident id 79d5bceab39e-54a438afed1db4b9 (500 internal_error) 2026/09/25 01:36:10 DEBUG : test-multithread-copy-true-16777215-2: multi-thread copy: cancelling transfer on exit 2026/09/25 01:36:10 DEBUG : test-multithread-copy-true-16777215-2: Cancelling large file upload multithread_test.go:229: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:229 Error: Received unexpected error: multi-thread copy: failed to write chunk: incident id 79d5bceab39e-54a438afed1db4b9 (500 internal_error) Test: TestMultithreadCopy/upload=true,size=16777215,streams=2 === RUN TestMultithreadCopy/upload=true,size=16777216,streams=2 fstest.go:299: Sleeping for 1s for list eventual consistency: 1/5 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/5 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/5 fstest.go:299: Sleeping for 8s for list eventual consistency: 4/5 fstest.go:299: Sleeping for 16s for list eventual consistency: 5/5 fstest.go:306: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:306 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:339 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:351 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:352 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:194 Error: Should be true Test: TestMultithreadCopy/upload=true,size=16777216,streams=2 Messages: listing wrong, want test-multithread-copy-true-16777216-2 (16777216) got test-multithread-copy-true-16777215-2 (16777215), test-multithread-copy-true-16777216-2 (16777216) fstest.go:192: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:192 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:309 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:339 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:351 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:352 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:194 /usr/local/go/src/runtime/asm_amd64.s:1264 Error: Should be true Test: TestMultithreadCopy/upload=true,size=16777216,streams=2 Messages: Unexpected file "test-multithread-copy-true-16777215-2" 2026/09/25 01:36:43 DEBUG : test-multithread-copy-true-16777216-2: multi-thread copy: disabling buffering because source is local disk 2026/09/25 01:36:44 DEBUG : test-multithread-copy-true-16777216-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2026/09/25 01:36:44 DEBUG : test-multithread-copy-true-16777216-2: multi-thread copy: number of streams 4 was bigger than number of chunks 2 2026/09/25 01:36:44 DEBUG : test-multithread-copy-true-16777216-2: Starting multi-thread copy with 2 chunks of size 8Mi with 2 parallel streams 2026/09/25 01:36:44 DEBUG : test-multithread-copy-true-16777216-2: multi-thread copy: chunk 2/2 (8388608-16777216) size 8Mi starting 2026/09/25 01:36:44 DEBUG : test-multithread-copy-true-16777216-2: Sending chunk 1 length 8388608 2026/09/25 01:36:44 DEBUG : test-multithread-copy-true-16777216-2: multi-thread copy: chunk 1/2 (0-8388608) size 8Mi starting 2026/09/25 01:36:44 DEBUG : test-multithread-copy-true-16777216-2: Sending chunk 0 length 8388608 2026/09/25 01:36:47 DEBUG : test-multithread-copy-true-16777216-2: Done sending chunk 1 2026/09/25 01:36:47 DEBUG : test-multithread-copy-true-16777216-2: multi-thread copy: chunk 2/2 (8388608-16777216) size 8Mi finished 2026/09/25 01:36:47 DEBUG : test-multithread-copy-true-16777216-2: Done sending chunk 0 2026/09/25 01:36:47 DEBUG : test-multithread-copy-true-16777216-2: multi-thread copy: chunk 1/2 (0-8388608) size 8Mi finished 2026/09/25 01:36:47 DEBUG : test-multithread-copy-true-16777216-2: Finishing large file upload with 2 parts 2026/09/25 01:36:48 DEBUG : test-multithread-copy-true-16777216-2: Finished multi-thread copy with 2 parts of size 8Mi fstest.go:299: Sleeping for 1s for list eventual consistency: 1/5 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/5 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/5 fstest.go:299: Sleeping for 8s for list eventual consistency: 4/5 fstest.go:299: Sleeping for 16s for list eventual consistency: 5/5 fstest.go:306: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:306 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:339 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:233 Error: Should be true Test: TestMultithreadCopy/upload=true,size=16777216,streams=2 Messages: listing wrong, want test-multithread-copy-true-16777216-2 (16777216) got test-multithread-copy-true-16777215-2 (16777215), test-multithread-copy-true-16777216-2 (16777216) fstest.go:192: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:192 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:309 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:339 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:233 Error: Should be true Test: TestMultithreadCopy/upload=true,size=16777216,streams=2 Messages: Unexpected file "test-multithread-copy-true-16777215-2" === RUN TestMultithreadCopy/upload=true,size=16777217,streams=2 fstest.go:299: Sleeping for 1s for list eventual consistency: 1/5 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/5 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/5 fstest.go:299: Sleeping for 8s for list eventual consistency: 4/5 fstest.go:299: Sleeping for 16s for list eventual consistency: 5/5 fstest.go:306: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:306 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:339 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:351 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:352 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:194 Error: Should be true Test: TestMultithreadCopy/upload=true,size=16777217,streams=2 Messages: listing wrong, want test-multithread-copy-true-16777217-2 (16777217) got test-multithread-copy-true-16777215-2 (16777215), test-multithread-copy-true-16777217-2 (16777217) fstest.go:192: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:192 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:309 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:339 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:351 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:352 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:194 /usr/local/go/src/runtime/asm_amd64.s:1264 Error: Should be true Test: TestMultithreadCopy/upload=true,size=16777217,streams=2 Messages: Unexpected file "test-multithread-copy-true-16777215-2" 2026/09/25 01:37:53 DEBUG : test-multithread-copy-true-16777217-2: multi-thread copy: disabling buffering because source is local disk 2026/09/25 01:37:53 DEBUG : test-multithread-copy-true-16777217-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2026/09/25 01:37:53 DEBUG : test-multithread-copy-true-16777217-2: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2026/09/25 01:37:53 DEBUG : test-multithread-copy-true-16777217-2: Starting multi-thread copy with 3 chunks of size 8Mi with 3 parallel streams 2026/09/25 01:37:53 DEBUG : test-multithread-copy-true-16777217-2: multi-thread copy: chunk 2/3 (8388608-16777216) size 8Mi starting 2026/09/25 01:37:53 DEBUG : test-multithread-copy-true-16777217-2: Sending chunk 1 length 8388608 2026/09/25 01:37:53 DEBUG : test-multithread-copy-true-16777217-2: multi-thread copy: chunk 1/3 (0-8388608) size 8Mi starting 2026/09/25 01:37:53 DEBUG : test-multithread-copy-true-16777217-2: multi-thread copy: chunk 3/3 (16777216-16777217) size 1 starting 2026/09/25 01:37:53 DEBUG : test-multithread-copy-true-16777217-2: Sending chunk 0 length 8388608 2026/09/25 01:37:53 DEBUG : test-multithread-copy-true-16777217-2: Sending chunk 2 length 1 2026/09/25 01:37:55 DEBUG : test-multithread-copy-true-16777217-2: Done sending chunk 2 2026/09/25 01:37:55 DEBUG : test-multithread-copy-true-16777217-2: multi-thread copy: chunk 3/3 (16777216-16777217) size 1 finished 2026/09/25 01:37:56 DEBUG : test-multithread-copy-true-16777217-2: Done sending chunk 0 2026/09/25 01:37:56 DEBUG : test-multithread-copy-true-16777217-2: multi-thread copy: chunk 1/3 (0-8388608) size 8Mi finished 2026/09/25 01:37:56 DEBUG : test-multithread-copy-true-16777217-2: Done sending chunk 1 2026/09/25 01:37:56 DEBUG : test-multithread-copy-true-16777217-2: multi-thread copy: chunk 2/3 (8388608-16777216) size 8Mi finished 2026/09/25 01:37:56 DEBUG : test-multithread-copy-true-16777217-2: Finishing large file upload with 3 parts 2026/09/25 01:37:57 DEBUG : test-multithread-copy-true-16777217-2: Finished multi-thread copy with 3 parts of size 8Mi fstest.go:299: Sleeping for 1s for list eventual consistency: 1/5 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/5 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/5 fstest.go:299: Sleeping for 8s for list eventual consistency: 4/5 fstest.go:299: Sleeping for 16s for list eventual consistency: 5/5 fstest.go:306: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:306 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:339 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:233 Error: Should be true Test: TestMultithreadCopy/upload=true,size=16777217,streams=2 Messages: listing wrong, want test-multithread-copy-true-16777217-2 (16777217) got test-multithread-copy-true-16777215-2 (16777215), test-multithread-copy-true-16777217-2 (16777217) fstest.go:192: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:192 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:309 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:339 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:233 Error: Should be true Test: TestMultithreadCopy/upload=true,size=16777217,streams=2 Messages: Unexpected file "test-multithread-copy-true-16777215-2" --- FAIL: TestMultithreadCopy (245.36s) --- PASS: TestMultithreadCopy/upload=false,size=16777215,streams=2 (8.57s) --- PASS: TestMultithreadCopy/upload=false,size=16777216,streams=2 (9.79s) --- PASS: TestMultithreadCopy/upload=false,size=16777217,streams=2 (5.99s) --- FAIL: TestMultithreadCopy/upload=true,size=16777215,streams=2 (80.95s) --- FAIL: TestMultithreadCopy/upload=true,size=16777216,streams=2 (69.37s) --- FAIL: TestMultithreadCopy/upload=true,size=16777217,streams=2 (69.05s) === RUN TestMultithreadCopyAbort run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" 2026/09/25 01:38:30 DEBUG : chunksize-probe: Cancelling large file upload 2026/09/25 01:38:32 DEBUG : test-multithread-abort: multi-thread copy: disabling buffering because source is local disk 2026/09/25 01:38:32 DEBUG : test-multithread-abort: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 1 2026/09/25 01:38:32 DEBUG : test-multithread-abort: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2026/09/25 01:38:32 DEBUG : test-multithread-abort: Starting multi-thread copy with 3 chunks of size 8Mi with 3 parallel streams 2026/09/25 01:38:32 DEBUG : test-multithread-abort: multi-thread copy: chunk 3/3 (16777216-16777217) size 1 starting 2026/09/25 01:38:32 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2026/09/25 01:38:32 DEBUG : test-multithread-abort: multi-thread copy: chunk 1/3 (0-8388608) size 8Mi starting 2026/09/25 01:38:32 DEBUG : test-multithread-abort: multi-thread copy: chunk 2/3 (8388608-16777216) size 8Mi starting 2026/09/25 01:38:32 DEBUG : Open with options = [RangeOption(0,8388607)] 2026/09/25 01:38:32 DEBUG : Open with options = [RangeOption(8388608,16777215)] 2026/09/25 01:38:32 DEBUG : test-multithread-abort: Sending chunk 0 length 8388608 2026/09/25 01:38:32 DEBUG : test-multithread-abort: Sending chunk 1 length 8388608 2026/09/25 01:38:35 DEBUG : test-multithread-abort: Done sending chunk 0 2026/09/25 01:38:35 DEBUG : test-multithread-abort: multi-thread copy: chunk 1/3 (0-8388608) size 8Mi finished 2026/09/25 01:38:35 DEBUG : test-multithread-abort: Done sending chunk 1 2026/09/25 01:38:35 DEBUG : test-multithread-abort: multi-thread copy: chunk 2/3 (8388608-16777216) size 8Mi finished 2026/09/25 01:38:35 DEBUG : Returning error reader 2026/09/25 01:38:35 DEBUG : test-multithread-abort: Sending chunk 2 length 1 2026/09/25 01:38:35 DEBUG : BOOM: simulated read failure 2026/09/25 01:38:35 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 0/10: BOOM: simulated read failure 2026/09/25 01:38:35 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2026/09/25 01:38:36 DEBUG : Returning error reader 2026/09/25 01:38:36 DEBUG : BOOM: simulated read failure 2026/09/25 01:38:36 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 1/10: BOOM: simulated read failure 2026/09/25 01:38:36 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2026/09/25 01:38:37 DEBUG : Returning error reader 2026/09/25 01:38:37 DEBUG : BOOM: simulated read failure 2026/09/25 01:38:37 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 2/10: BOOM: simulated read failure 2026/09/25 01:38:37 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2026/09/25 01:38:38 DEBUG : Returning error reader 2026/09/25 01:38:38 DEBUG : BOOM: simulated read failure 2026/09/25 01:38:38 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 3/10: BOOM: simulated read failure 2026/09/25 01:38:38 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2026/09/25 01:38:39 DEBUG : Returning error reader 2026/09/25 01:38:39 DEBUG : BOOM: simulated read failure 2026/09/25 01:38:39 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 4/10: BOOM: simulated read failure 2026/09/25 01:38:39 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2026/09/25 01:38:40 DEBUG : Returning error reader 2026/09/25 01:38:40 DEBUG : BOOM: simulated read failure 2026/09/25 01:38:40 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 5/10: BOOM: simulated read failure 2026/09/25 01:38:40 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2026/09/25 01:38:41 DEBUG : Returning error reader 2026/09/25 01:38:41 DEBUG : BOOM: simulated read failure 2026/09/25 01:38:41 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 6/10: BOOM: simulated read failure 2026/09/25 01:38:41 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2026/09/25 01:38:42 DEBUG : Returning error reader 2026/09/25 01:38:42 DEBUG : BOOM: simulated read failure 2026/09/25 01:38:42 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 7/10: BOOM: simulated read failure 2026/09/25 01:38:42 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2026/09/25 01:38:43 DEBUG : Returning error reader 2026/09/25 01:38:43 DEBUG : BOOM: simulated read failure 2026/09/25 01:38:43 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 8/10: BOOM: simulated read failure 2026/09/25 01:38:43 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2026/09/25 01:38:44 DEBUG : Returning error reader 2026/09/25 01:38:44 DEBUG : BOOM: simulated read failure 2026/09/25 01:38:44 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 9/10: BOOM: simulated read failure 2026/09/25 01:38:44 DEBUG : Open with options = [RangeOption(16777216,16777216)] 2026/09/25 01:38:45 DEBUG : Returning error reader 2026/09/25 01:38:45 DEBUG : BOOM: simulated read failure 2026/09/25 01:38:45 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 10/10: BOOM: simulated read failure 2026/09/25 01:38:45 DEBUG : test-multithread-abort: Reopen failed after offset 0 bytes read: failed to reopen: too many retries 2026/09/25 01:38:45 DEBUG : test-multithread-abort: Error sending chunk 2 (retry=false): Post "https://pod-000-1182-09.backblaze.com/b2api/v1/b2_upload_part/4_z0fd20de3303ddb6baf080e1b_f204b312f795408ba_d20260925_m013832_c001_v0001182_t0046_u01790300312867/0047": BOOM: simulated read failure: &url.Error{Op:"Post", URL:"https://pod-000-1182-09.backblaze.com/b2api/v1/b2_upload_part/4_z0fd20de3303ddb6baf080e1b_f204b312f795408ba_d20260925_m013832_c001_v0001182_t0046_u01790300312867/0047", Err:(*errors.errorString)(0x39baac02e2d0)} 2026/09/25 01:38:45 DEBUG : test-multithread-abort: Error sending chunk 2: Post "https://pod-000-1182-09.backblaze.com/b2api/v1/b2_upload_part/4_z0fd20de3303ddb6baf080e1b_f204b312f795408ba_d20260925_m013832_c001_v0001182_t0046_u01790300312867/0047": BOOM: simulated read failure 2026/09/25 01:38:45 DEBUG : test-multithread-abort: multi-thread copy: chunk 3/3 failed: multi-thread copy: failed to write chunk: Post "https://pod-000-1182-09.backblaze.com/b2api/v1/b2_upload_part/4_z0fd20de3303ddb6baf080e1b_f204b312f795408ba_d20260925_m013832_c001_v0001182_t0046_u01790300312867/0047": BOOM: simulated read failure 2026/09/25 01:38:45 DEBUG : test-multithread-abort: multi-thread copy: cancelling transfer on exit 2026/09/25 01:38:45 DEBUG : test-multithread-abort: Cancelling large file upload --- PASS: TestMultithreadCopyAbort (17.17s) === RUN TestMultithreadCopyWriterAtErrors === RUN TestMultithreadCopyWriterAtErrors/open/fatal=false 2026/09/25 01:38:47 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt === RUN TestMultithreadCopyWriterAtErrors/open/fatal=true 2026/09/25 01:38:47 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt === RUN TestMultithreadCopyWriterAtErrors/write/fatal=false 2026/09/25 01:38:47 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2026/09/25 01:38:47 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams 2026/09/25 01:38:47 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting 2026/09/25 01:38:47 DEBUG : file.txt: writing chunk 1 2026/09/25 01:38:47 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting 2026/09/25 01:38:47 DEBUG : file.txt: writing chunk 0 2026/09/25 01:38:47 DEBUG : file.txt: multi-thread copy: chunk 2/4 failed: multi-thread copy: failed to write chunk: disk full 2026/09/25 01:38:47 DEBUG : file.txt: multi-thread copy: chunk 1/4 failed: multi-thread copy: failed to write chunk: disk full 2026/09/25 01:38:47 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting 2026/09/25 01:38:47 DEBUG : file.txt: writing chunk 2 2026/09/25 01:38:47 DEBUG : file.txt: multi-thread copy: chunk 3/4 failed: multi-thread copy: failed to write chunk: disk full 2026/09/25 01:38:47 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit 2026/09/25 01:38:47 DEBUG : file.txt: multi-thread copy: abort failed: multi-thread copy: failed to find temp file when aborting chunk writer: object not found === RUN TestMultithreadCopyWriterAtErrors/write/fatal=true 2026/09/25 01:38:47 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2026/09/25 01:38:47 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams 2026/09/25 01:38:47 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting 2026/09/25 01:38:47 DEBUG : file.txt: writing chunk 1 2026/09/25 01:38:47 DEBUG : file.txt: multi-thread copy: chunk 2/4 failed: multi-thread copy: failed to write chunk: disk full 2026/09/25 01:38:47 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting 2026/09/25 01:38:47 DEBUG : file.txt: writing chunk 2 2026/09/25 01:38:47 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting 2026/09/25 01:38:47 DEBUG : file.txt: multi-thread copy: chunk 3/4 failed: multi-thread copy: failed to write chunk: disk full 2026/09/25 01:38:47 DEBUG : file.txt: writing chunk 0 2026/09/25 01:38:47 DEBUG : file.txt: multi-thread copy: chunk 1/4 failed: multi-thread copy: failed to write chunk: disk full 2026/09/25 01:38:47 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit 2026/09/25 01:38:47 DEBUG : file.txt: multi-thread copy: abort failed: multi-thread copy: failed to find temp file when aborting chunk writer: object not found === RUN TestMultithreadCopyWriterAtErrors/close/fatal=false 2026/09/25 01:38:47 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2026/09/25 01:38:47 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams 2026/09/25 01:38:47 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting 2026/09/25 01:38:47 DEBUG : file.txt: writing chunk 1 2026/09/25 01:38:47 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 finished 2026/09/25 01:38:47 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting 2026/09/25 01:38:47 DEBUG : file.txt: writing chunk 2 2026/09/25 01:38:47 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting 2026/09/25 01:38:47 DEBUG : file.txt: writing chunk 0 2026/09/25 01:38:47 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 finished 2026/09/25 01:38:47 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 finished 2026/09/25 01:38:47 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 starting 2026/09/25 01:38:47 DEBUG : file.txt: writing chunk 3 2026/09/25 01:38:47 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 finished 2026/09/25 01:38:47 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit 2026/09/25 01:38:47 DEBUG : file.txt: multi-thread copy: abort failed: multi-thread copy: failed to find temp file when aborting chunk writer: object not found === RUN TestMultithreadCopyWriterAtErrors/close/fatal=true 2026/09/25 01:38:47 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2026/09/25 01:38:47 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams 2026/09/25 01:38:47 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting 2026/09/25 01:38:47 DEBUG : file.txt: writing chunk 1 2026/09/25 01:38:47 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting 2026/09/25 01:38:47 DEBUG : file.txt: writing chunk 0 2026/09/25 01:38:47 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 finished 2026/09/25 01:38:47 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 finished 2026/09/25 01:38:47 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting 2026/09/25 01:38:47 DEBUG : file.txt: writing chunk 2 2026/09/25 01:38:47 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 finished 2026/09/25 01:38:47 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 starting 2026/09/25 01:38:47 DEBUG : file.txt: writing chunk 3 2026/09/25 01:38:47 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 finished 2026/09/25 01:38:47 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit 2026/09/25 01:38:47 DEBUG : file.txt: multi-thread copy: abort failed: multi-thread copy: failed to find temp file when aborting chunk writer: object not found --- PASS: TestMultithreadCopyWriterAtErrors (0.00s) --- PASS: TestMultithreadCopyWriterAtErrors/open/fatal=false (0.00s) --- PASS: TestMultithreadCopyWriterAtErrors/open/fatal=true (0.00s) --- PASS: TestMultithreadCopyWriterAtErrors/write/fatal=false (0.00s) --- PASS: TestMultithreadCopyWriterAtErrors/write/fatal=true (0.00s) --- PASS: TestMultithreadCopyWriterAtErrors/close/fatal=false (0.00s) --- PASS: TestMultithreadCopyWriterAtErrors/close/fatal=true (0.00s) === RUN TestSizeDiffers 2026/09/25 01:38:47 DEBUG : a: size = 0 OK 2026/09/25 01:38:47 DEBUG : a: size = 1 (memory) 2026/09/25 01:38:47 DEBUG : a: size = 2 (memory) --- PASS: TestSizeDiffers (0.00s) === RUN TestDirTransferEntry --- PASS: TestDirTransferEntry (0.00s) === RUN TestReOpen === RUN TestReOpen/Normal === RUN TestReOpen/Normal/Basics 2026/09/25 01:38:47 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/ErrorAtStart === RUN TestReOpen/Normal/WithErrors 2026/09/25 01:38:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/09/25 01:38:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/09/25 01:38:47 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/Normal/TooManyErrors 2026/09/25 01:38:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/09/25 01:38:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/09/25 01:38:47 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/09/25 01:38:47 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/Normal/ReadAt 2026/09/25 01:38:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/09/25 01:38:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/09/25 01:38:47 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/Normal/Seek 2026/09/25 01:38:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/09/25 01:38:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/09/25 01:38:47 DEBUG : potato: Seek from 5 to 2 === RUN TestReOpen/Normal/AccountRead === RUN TestReOpen/Normal/AccountReadDelay 2026/09/25 01:38:47 DEBUG : potato: Seek from 10 to 0 2026/09/25 01:38:47 DEBUG : potato: Seek from 10 to 0 2026/09/25 01:38:47 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/AccountReadError === RUN TestReOpen/WithRangeOption === RUN TestReOpen/WithRangeOption/Basics 2026/09/25 01:38:47 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/ErrorAtStart === RUN TestReOpen/WithRangeOption/WithErrors 2026/09/25 01:38:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/09/25 01:38:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/09/25 01:38:47 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithRangeOption/TooManyErrors 2026/09/25 01:38:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/09/25 01:38:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/09/25 01:38:47 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/09/25 01:38:47 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithRangeOption/ReadAt 2026/09/25 01:38:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/09/25 01:38:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/09/25 01:38:47 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/WithRangeOption/Seek 2026/09/25 01:38:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/09/25 01:38:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/09/25 01:38:47 DEBUG : potato: Seek from 5 to 2 2026/09/25 01:38:47 DEBUG : potato: Seek from 7 to 4 === RUN TestReOpen/WithRangeOption/AccountRead === RUN TestReOpen/WithRangeOption/AccountReadDelay 2026/09/25 01:38:47 DEBUG : potato: Seek from 7 to 0 2026/09/25 01:38:47 DEBUG : potato: Seek from 7 to 0 2026/09/25 01:38:47 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/AccountReadError === RUN TestReOpen/WithSeekOption === RUN TestReOpen/WithSeekOption/Basics 2026/09/25 01:38:47 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/ErrorAtStart === RUN TestReOpen/WithSeekOption/WithErrors 2026/09/25 01:38:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/09/25 01:38:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/09/25 01:38:47 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithSeekOption/TooManyErrors 2026/09/25 01:38:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/09/25 01:38:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/09/25 01:38:47 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/09/25 01:38:47 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithSeekOption/ReadAt 2026/09/25 01:38:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/09/25 01:38:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/09/25 01:38:47 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/WithSeekOption/Seek 2026/09/25 01:38:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/09/25 01:38:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/09/25 01:38:47 DEBUG : potato: Seek from 5 to 2 2026/09/25 01:38:47 DEBUG : potato: Seek from 7 to 5 === RUN TestReOpen/WithSeekOption/AccountRead === RUN TestReOpen/WithSeekOption/AccountReadDelay 2026/09/25 01:38:47 DEBUG : potato: Seek from 8 to 0 2026/09/25 01:38:47 DEBUG : potato: Seek from 8 to 0 2026/09/25 01:38:47 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/AccountReadError === RUN TestReOpen/UnknownSize === RUN TestReOpen/UnknownSize/Basics 2026/09/25 01:38:47 DEBUG : potato: Seek from 9 to 0 === RUN TestReOpen/UnknownSize/ErrorAtStart === RUN TestReOpen/UnknownSize/WithErrors 2026/09/25 01:38:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/09/25 01:38:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/09/25 01:38:47 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/UnknownSize/TooManyErrors 2026/09/25 01:38:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/09/25 01:38:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/09/25 01:38:47 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/09/25 01:38:47 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/UnknownSize/ReadAt 2026/09/25 01:38:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/09/25 01:38:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/09/25 01:38:47 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/UnknownSize/Seek 2026/09/25 01:38:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/09/25 01:38:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/09/25 01:38:47 DEBUG : potato: Seek from 5 to 2 2026/09/25 01:38:47 DEBUG : potato: Seek from 7 to 6 === RUN TestReOpen/UnknownSize/AccountRead === RUN TestReOpen/UnknownSize/AccountReadDelay 2026/09/25 01:38:47 DEBUG : potato: Seek from 9 to 0 2026/09/25 01:38:47 DEBUG : potato: Seek from 9 to 0 2026/09/25 01:38:47 DEBUG : potato: Seek from 9 to 0 === RUN TestReOpen/UnknownSize/AccountReadError --- PASS: TestReOpen (0.00s) --- PASS: TestReOpen/Normal (0.00s) --- PASS: TestReOpen/Normal/Basics (0.00s) --- PASS: TestReOpen/Normal/ErrorAtStart (0.00s) --- PASS: TestReOpen/Normal/WithErrors (0.00s) --- PASS: TestReOpen/Normal/TooManyErrors (0.00s) --- PASS: TestReOpen/Normal/ReadAt (0.00s) --- PASS: TestReOpen/Normal/Seek (0.00s) --- PASS: TestReOpen/Normal/AccountRead (0.00s) --- PASS: TestReOpen/Normal/AccountReadDelay (0.00s) --- PASS: TestReOpen/Normal/AccountReadError (0.00s) --- PASS: TestReOpen/WithRangeOption (0.00s) --- PASS: TestReOpen/WithRangeOption/Basics (0.00s) --- PASS: TestReOpen/WithRangeOption/ErrorAtStart (0.00s) --- PASS: TestReOpen/WithRangeOption/WithErrors (0.00s) --- PASS: TestReOpen/WithRangeOption/TooManyErrors (0.00s) --- PASS: TestReOpen/WithRangeOption/ReadAt (0.00s) --- PASS: TestReOpen/WithRangeOption/Seek (0.00s) --- PASS: TestReOpen/WithRangeOption/AccountRead (0.00s) --- PASS: TestReOpen/WithRangeOption/AccountReadDelay (0.00s) --- PASS: TestReOpen/WithRangeOption/AccountReadError (0.00s) --- PASS: TestReOpen/WithSeekOption (0.00s) --- PASS: TestReOpen/WithSeekOption/Basics (0.00s) --- PASS: TestReOpen/WithSeekOption/ErrorAtStart (0.00s) --- PASS: TestReOpen/WithSeekOption/WithErrors (0.00s) --- PASS: TestReOpen/WithSeekOption/TooManyErrors (0.00s) --- PASS: TestReOpen/WithSeekOption/ReadAt (0.00s) --- PASS: TestReOpen/WithSeekOption/Seek (0.00s) --- PASS: TestReOpen/WithSeekOption/AccountRead (0.00s) --- PASS: TestReOpen/WithSeekOption/AccountReadDelay (0.00s) --- PASS: TestReOpen/WithSeekOption/AccountReadError (0.00s) --- PASS: TestReOpen/UnknownSize (0.00s) --- PASS: TestReOpen/UnknownSize/Basics (0.00s) --- PASS: TestReOpen/UnknownSize/ErrorAtStart (0.00s) --- PASS: TestReOpen/UnknownSize/WithErrors (0.00s) --- PASS: TestReOpen/UnknownSize/TooManyErrors (0.00s) --- PASS: TestReOpen/UnknownSize/ReadAt (0.00s) --- PASS: TestReOpen/UnknownSize/Seek (0.00s) --- PASS: TestReOpen/UnknownSize/AccountRead (0.00s) --- PASS: TestReOpen/UnknownSize/AccountReadDelay (0.00s) --- PASS: TestReOpen/UnknownSize/AccountReadError (0.00s) === RUN TestCheck run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", 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.40s) --- PASS: TestCheck/1 (0.18s) --- PASS: TestCheck/2 (0.18s) --- PASS: TestCheck/3 (0.18s) --- PASS: TestCheck/4 (0.18s) --- PASS: TestCheck/5 (0.18s) --- PASS: TestCheck/6 (0.19s) --- PASS: TestCheck/7 (0.18s) === RUN TestCheckFsError 2026/09/25 01:38:51 DEBUG : Creating backend with remote "nonexistent" 2026/09/25 01:38:51 DEBUG : Config file has changed externally - reloading 2026/09/25 01:38:51 DEBUG : Creating backend with remote "nonexistent" 2026/09/25 01:38:51 DEBUG : Local file system at nonexistent: Waiting for checks to finish 2026/09/25 01:38:51 ERROR : Local file system at nonexistent: error reading source root directory: directory not found 2026/09/25 01:38:51 NOTICE: Local file system at nonexistent: 0 differences found 2026/09/25 01:38:51 NOTICE: Local file system at nonexistent: 2 errors while checking --- PASS: TestCheckFsError (0.00s) === RUN TestCheckDownload run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", 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 (5.74s) --- PASS: TestCheckDownload/1 (0.37s) --- PASS: TestCheckDownload/2 (0.36s) --- PASS: TestCheckDownload/3 (0.36s) --- PASS: TestCheckDownload/4 (0.55s) --- PASS: TestCheckDownload/5 (0.50s) --- PASS: TestCheckDownload/6 (0.52s) --- PASS: TestCheckDownload/7 (0.38s) === RUN TestCheckSizeOnly run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", 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 (3.97s) --- PASS: TestCheckSizeOnly/1 (0.18s) --- PASS: TestCheckSizeOnly/2 (0.18s) --- PASS: TestCheckSizeOnly/3 (0.18s) --- PASS: TestCheckSizeOnly/4 (0.18s) --- PASS: TestCheckSizeOnly/5 (0.18s) --- PASS: TestCheckSizeOnly/6 (0.18s) --- PASS: TestCheckSizeOnly/7 (0.18s) === RUN TestCheckEqualReaders --- PASS: TestCheckEqualReaders (0.00s) === RUN TestParseSumFile run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" 2026/09/25 01:39:02 NOTICE: test.sum: improperly formatted checksum line 4 2026/09/25 01:39:02 NOTICE: test.sum: improperly formatted checksum line 5 2026/09/25 01:39:02 NOTICE: test.sum: improperly formatted checksum line 6 2026/09/25 01:39:02 NOTICE: test.sum: 2 warning(s) suppressed... 2026/09/25 01:39:03 NOTICE: test.sum: improperly formatted checksum line 4 2026/09/25 01:39:03 NOTICE: test.sum: improperly formatted checksum line 5 2026/09/25 01:39:03 NOTICE: test.sum: improperly formatted checksum line 6 2026/09/25 01:39:03 NOTICE: test.sum: 2 warning(s) suppressed... --- PASS: TestParseSumFile (2.23s) === RUN TestCheckSum run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" 2026/09/25 01:39:03 DEBUG : Creating backend with remote "TestB2:rclone-test-hedekel9dubi/data" check_test.go:354: B2 bucket rclone-test-hedekel9dubi path data lacks md5, skipping --- SKIP: TestCheckSum (1.48s) === RUN TestCheckSumDownload run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" 2026/09/25 01:39:05 DEBUG : Creating backend with remote "TestB2:rclone-test-hedekel9dubi/data" === RUN TestCheckSumDownload/subtest1 === RUN TestCheckSumDownload/subtest2 === RUN TestCheckSumDownload/subtest3 === RUN TestCheckSumDownload/subtest4 === RUN TestCheckSumDownload/subtest5 === RUN TestCheckSumDownload/subtest6 === RUN TestCheckSumDownload/subtest7 --- PASS: TestCheckSumDownload (11.62s) --- PASS: TestCheckSumDownload/subtest1 (1.32s) --- PASS: TestCheckSumDownload/subtest2 (0.72s) --- PASS: TestCheckSumDownload/subtest3 (0.92s) --- PASS: TestCheckSumDownload/subtest4 (0.73s) --- PASS: TestCheckSumDownload/subtest5 (0.75s) --- PASS: TestCheckSumDownload/subtest6 (0.74s) --- PASS: TestCheckSumDownload/subtest7 (0.74s) === RUN TestCheckSumConcurrency 2026/09/25 01:39:16 DEBUG : file0: md5 = 022eb0c0085c731f335df970fafa7446 OK 2026/09/25 01:39:16 DEBUG : file2: md5 = a58f09ae3db8ae4b943fdbaace096072 OK 2026/09/25 01:39:16 DEBUG : file1: md5 = 17fbabbccbdea0e6be9eb64c8c6fea41 OK 2026/09/25 01:39:16 DEBUG : file3: md5 = e0639b17e0744aca9a3ba29c049e59ee OK 2026/09/25 01:39:16 NOTICE: Mock file system at checkSum: 0 differences found 2026/09/25 01:39:16 NOTICE: Mock file system at checkSum: 4 matching files --- PASS: TestCheckSumConcurrency (0.00s) === RUN TestCheckSumDownloadConcurrency 2026/09/25 01:39:16 DEBUG : file0: md5 = 022eb0c0085c731f335df970fafa7446 OK 2026/09/25 01:39:16 DEBUG : file2: md5 = a58f09ae3db8ae4b943fdbaace096072 OK 2026/09/25 01:39:16 DEBUG : file1: md5 = 17fbabbccbdea0e6be9eb64c8c6fea41 OK 2026/09/25 01:39:16 DEBUG : file3: md5 = e0639b17e0744aca9a3ba29c049e59ee OK 2026/09/25 01:39:16 NOTICE: Mock file system at checkSum: 0 differences found 2026/09/25 01:39:16 NOTICE: Mock file system at checkSum: 4 matching files --- PASS: TestCheckSumDownloadConcurrency (0.00s) === RUN TestApplyTransforms 2026/09/25 01:39:16 DEBUG : Creating backend with remote "TestB2:rclone-test-pugodub3wego" 2026/09/25 01:39:16 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/25 01:39:17 DEBUG : Creating backend with remote "/tmp/rclone2938291250" run.go:198: Remote "B2 bucket rclone-test-pugodub3wego", Local "Local file system at /tmp/rclone2938291250", Modify Window "1ms" upper checkfile vs. lower remote (without normalization) 2026/09/25 01:39:19 ERROR : hello, world!: sum not found 2026/09/25 01:39:19 ERROR : HELLO, WORLD!: file not in B2 bucket rclone-test-pugodub3wego 2026/09/25 01:39:19 NOTICE: B2 bucket rclone-test-pugodub3wego: 1 files missing 2026/09/25 01:39:19 NOTICE: 1 hashes missing 2026/09/25 01:39:19 NOTICE: B2 bucket rclone-test-pugodub3wego: 1 differences found 2026/09/25 01:39:19 NOTICE: B2 bucket rclone-test-pugodub3wego: 2 errors while checking upper checkfile vs. lower remote (with normalization) 2026/09/25 01:39:19 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/25 01:39:19 NOTICE: B2 bucket rclone-test-pugodub3wego: 0 differences found 2026/09/25 01:39:19 NOTICE: B2 bucket rclone-test-pugodub3wego: 1 matching files 2026/09/25 01:39:19 DEBUG : Creating backend with remote "TestB2:rclone-test-dojiqin6nure" 2026/09/25 01:39:19 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/25 01:39:20 DEBUG : Creating backend with remote "/tmp/rclone86753796" run.go:198: Remote "B2 bucket rclone-test-dojiqin6nure", Local "Local file system at /tmp/rclone86753796", Modify Window "1ms" lower checkfile vs. upper remote (without normalization) 2026/09/25 01:39:22 ERROR : HELLO, WORLD!: sum not found 2026/09/25 01:39:22 ERROR : hello, world!: file not in B2 bucket rclone-test-dojiqin6nure 2026/09/25 01:39:22 NOTICE: B2 bucket rclone-test-dojiqin6nure: 1 files missing 2026/09/25 01:39:22 NOTICE: 1 hashes missing 2026/09/25 01:39:22 NOTICE: B2 bucket rclone-test-dojiqin6nure: 1 differences found 2026/09/25 01:39:22 NOTICE: B2 bucket rclone-test-dojiqin6nure: 2 errors while checking lower checkfile vs. upper remote (with normalization) 2026/09/25 01:39:23 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/25 01:39:23 NOTICE: B2 bucket rclone-test-dojiqin6nure: 0 differences found 2026/09/25 01:39:23 NOTICE: B2 bucket rclone-test-dojiqin6nure: 1 matching files 2026/09/25 01:39:23 DEBUG : Creating backend with remote "TestB2:rclone-test-nazidof2ziti" 2026/09/25 01:39:23 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/25 01:39:23 DEBUG : Creating backend with remote "/tmp/rclone2995484480" run.go:198: Remote "B2 bucket rclone-test-nazidof2ziti", Local "Local file system at /tmp/rclone2995484480", Modify Window "1ms" lower checkfile vs. upperlowermixed remote (without normalization) 2026/09/25 01:39:25 ERROR : HeLlO, wOrLd!: sum not found 2026/09/25 01:39:25 ERROR : hello, world!: file not in B2 bucket rclone-test-nazidof2ziti 2026/09/25 01:39:25 NOTICE: B2 bucket rclone-test-nazidof2ziti: 1 files missing 2026/09/25 01:39:25 NOTICE: 1 hashes missing 2026/09/25 01:39:25 NOTICE: B2 bucket rclone-test-nazidof2ziti: 1 differences found 2026/09/25 01:39:25 NOTICE: B2 bucket rclone-test-nazidof2ziti: 2 errors while checking lower checkfile vs. upperlowermixed remote (with normalization) 2026/09/25 01:39:26 DEBUG : HeLlO, wOrLd!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/25 01:39:26 NOTICE: B2 bucket rclone-test-nazidof2ziti: 0 differences found 2026/09/25 01:39:26 NOTICE: B2 bucket rclone-test-nazidof2ziti: 1 matching files 2026/09/25 01:39:26 DEBUG : Creating backend with remote "TestB2:rclone-test-yitojix6sevi" 2026/09/25 01:39:26 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/25 01:39:26 DEBUG : Creating backend with remote "/tmp/rclone557865150" run.go:198: Remote "B2 bucket rclone-test-yitojix6sevi", Local "Local file system at /tmp/rclone557865150", Modify Window "1ms" upperlowermixed checkfile vs. upper remote (without normalization) 2026/09/25 01:39:28 ERROR : HELLO, WORLD!: sum not found 2026/09/25 01:39:28 ERROR : HeLlO, wOrLd!: file not in B2 bucket rclone-test-yitojix6sevi 2026/09/25 01:39:28 NOTICE: B2 bucket rclone-test-yitojix6sevi: 1 files missing 2026/09/25 01:39:28 NOTICE: 1 hashes missing 2026/09/25 01:39:28 NOTICE: B2 bucket rclone-test-yitojix6sevi: 1 differences found 2026/09/25 01:39:28 NOTICE: B2 bucket rclone-test-yitojix6sevi: 2 errors while checking upperlowermixed checkfile vs. upper remote (with normalization) 2026/09/25 01:39:29 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/25 01:39:29 NOTICE: B2 bucket rclone-test-yitojix6sevi: 0 differences found 2026/09/25 01:39:29 NOTICE: B2 bucket rclone-test-yitojix6sevi: 1 matching files 2026/09/25 01:39:29 DEBUG : Creating backend with remote "TestB2:rclone-test-gucakes0ruci" 2026/09/25 01:39:29 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/25 01:39:29 DEBUG : Creating backend with remote "/tmp/rclone3315818694" run.go:198: Remote "B2 bucket rclone-test-gucakes0ruci", Local "Local file system at /tmp/rclone3315818694", Modify Window "1ms" NFD checkfile vs. NFC remote (without normalization) 2026/09/25 01:39:31 ERROR : 測試_Русский___ě_áñ: sum not found 2026/09/25 01:39:31 ERROR : 測試_Русский___ě_áñ: file not in B2 bucket rclone-test-gucakes0ruci 2026/09/25 01:39:31 NOTICE: B2 bucket rclone-test-gucakes0ruci: 1 files missing 2026/09/25 01:39:31 NOTICE: 1 hashes missing 2026/09/25 01:39:31 NOTICE: B2 bucket rclone-test-gucakes0ruci: 1 differences found 2026/09/25 01:39:31 NOTICE: B2 bucket rclone-test-gucakes0ruci: 2 errors while checking NFD checkfile vs. NFC remote (with normalization) 2026/09/25 01:39:32 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/25 01:39:32 NOTICE: B2 bucket rclone-test-gucakes0ruci: 0 differences found 2026/09/25 01:39:32 NOTICE: B2 bucket rclone-test-gucakes0ruci: 1 matching files 2026/09/25 01:39:32 DEBUG : Creating backend with remote "TestB2:rclone-test-nuxisov2seji" 2026/09/25 01:39:32 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/25 01:39:33 DEBUG : Creating backend with remote "/tmp/rclone3821916568" run.go:198: Remote "B2 bucket rclone-test-nuxisov2seji", Local "Local file system at /tmp/rclone3821916568", Modify Window "1ms" NFC checkfile vs. NFD remote (without normalization) 2026/09/25 01:39:35 ERROR : 測試_Русский___ě_áñ: sum not found 2026/09/25 01:39:35 ERROR : 測試_Русский___ě_áñ: file not in B2 bucket rclone-test-nuxisov2seji 2026/09/25 01:39:35 NOTICE: B2 bucket rclone-test-nuxisov2seji: 1 files missing 2026/09/25 01:39:35 NOTICE: 1 hashes missing 2026/09/25 01:39:35 NOTICE: B2 bucket rclone-test-nuxisov2seji: 1 differences found 2026/09/25 01:39:35 NOTICE: B2 bucket rclone-test-nuxisov2seji: 2 errors while checking NFC checkfile vs. NFD remote (with normalization) 2026/09/25 01:39:36 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/25 01:39:36 NOTICE: B2 bucket rclone-test-nuxisov2seji: 0 differences found 2026/09/25 01:39:36 NOTICE: B2 bucket rclone-test-nuxisov2seji: 1 matching files 2026/09/25 01:39:36 DEBUG : Creating backend with remote "TestB2:rclone-test-hakoyak2fona" 2026/09/25 01:39:36 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/25 01:39:36 DEBUG : Creating backend with remote "/tmp/rclone3019170096" run.go:198: Remote "B2 bucket rclone-test-hakoyak2fona", Local "Local file system at /tmp/rclone3019170096", Modify Window "1ms" NFDx2 checkfile vs. both remote (without normalization) 2026/09/25 01:39:38 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/09/25 01:39:38 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-hakoyak2fona 2026/09/25 01:39:38 NOTICE: B2 bucket rclone-test-hakoyak2fona: 1 files missing 2026/09/25 01:39:38 NOTICE: 1 hashes missing 2026/09/25 01:39:38 NOTICE: B2 bucket rclone-test-hakoyak2fona: 1 differences found 2026/09/25 01:39:38 NOTICE: B2 bucket rclone-test-hakoyak2fona: 2 errors while checking NFDx2 checkfile vs. both remote (with normalization) 2026/09/25 01:39:39 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/25 01:39:39 NOTICE: B2 bucket rclone-test-hakoyak2fona: 0 differences found 2026/09/25 01:39:39 NOTICE: B2 bucket rclone-test-hakoyak2fona: 1 matching files 2026/09/25 01:39:39 DEBUG : Creating backend with remote "TestB2:rclone-test-tedeley6biro" 2026/09/25 01:39:39 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/25 01:39:40 DEBUG : Creating backend with remote "/tmp/rclone1031080747" run.go:198: Remote "B2 bucket rclone-test-tedeley6biro", Local "Local file system at /tmp/rclone1031080747", Modify Window "1ms" NFCx2 checkfile vs. both remote (without normalization) 2026/09/25 01:39:42 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/09/25 01:39:42 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-tedeley6biro 2026/09/25 01:39:42 NOTICE: B2 bucket rclone-test-tedeley6biro: 1 files missing 2026/09/25 01:39:42 NOTICE: 1 hashes missing 2026/09/25 01:39:42 NOTICE: B2 bucket rclone-test-tedeley6biro: 1 differences found 2026/09/25 01:39:42 NOTICE: B2 bucket rclone-test-tedeley6biro: 2 errors while checking NFCx2 checkfile vs. both remote (with normalization) 2026/09/25 01:39:42 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/25 01:39:42 NOTICE: B2 bucket rclone-test-tedeley6biro: 0 differences found 2026/09/25 01:39:42 NOTICE: B2 bucket rclone-test-tedeley6biro: 1 matching files 2026/09/25 01:39:42 DEBUG : Creating backend with remote "TestB2:rclone-test-ludenik2mepo" 2026/09/25 01:39:42 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/25 01:39:43 DEBUG : Creating backend with remote "/tmp/rclone2608871408" run.go:198: Remote "B2 bucket rclone-test-ludenik2mepo", Local "Local file system at /tmp/rclone2608871408", Modify Window "1ms" both checkfile vs. NFDx2 remote (without normalization) 2026/09/25 01:39:45 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/09/25 01:39:45 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-ludenik2mepo 2026/09/25 01:39:45 NOTICE: B2 bucket rclone-test-ludenik2mepo: 1 files missing 2026/09/25 01:39:45 NOTICE: 1 hashes missing 2026/09/25 01:39:45 NOTICE: B2 bucket rclone-test-ludenik2mepo: 1 differences found 2026/09/25 01:39:45 NOTICE: B2 bucket rclone-test-ludenik2mepo: 2 errors while checking both checkfile vs. NFDx2 remote (with normalization) 2026/09/25 01:39:46 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/25 01:39:46 NOTICE: B2 bucket rclone-test-ludenik2mepo: 0 differences found 2026/09/25 01:39:46 NOTICE: B2 bucket rclone-test-ludenik2mepo: 1 matching files 2026/09/25 01:39:46 DEBUG : Creating backend with remote "TestB2:rclone-test-qiresuf3suqu" 2026/09/25 01:39:46 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/25 01:39:47 DEBUG : Creating backend with remote "/tmp/rclone2343575037" run.go:198: Remote "B2 bucket rclone-test-qiresuf3suqu", Local "Local file system at /tmp/rclone2343575037", Modify Window "1ms" both checkfile vs. NFCx2 remote (without normalization) 2026/09/25 01:39:49 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/09/25 01:39:49 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-qiresuf3suqu 2026/09/25 01:39:49 NOTICE: B2 bucket rclone-test-qiresuf3suqu: 1 files missing 2026/09/25 01:39:49 NOTICE: 1 hashes missing 2026/09/25 01:39:49 NOTICE: B2 bucket rclone-test-qiresuf3suqu: 1 differences found 2026/09/25 01:39:49 NOTICE: B2 bucket rclone-test-qiresuf3suqu: 2 errors while checking both checkfile vs. NFCx2 remote (with normalization) 2026/09/25 01:39:50 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/25 01:39:50 NOTICE: B2 bucket rclone-test-qiresuf3suqu: 0 differences found 2026/09/25 01:39:50 NOTICE: B2 bucket rclone-test-qiresuf3suqu: 1 matching files 2026/09/25 01:39:50 DEBUG : B2 bucket rclone-test-qiresuf3suqu: Purge remote 2026/09/25 01:39:50 INFO : B2 bucket rclone-test-qiresuf3suqu: cleaning bucket "rclone-test-qiresuf3suqu" of all files 2026/09/25 01:39:50 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_z9f42fd93307ddb6baf080e1b_f100e0200edaa33b5_d20260925_m013948_c001_v0001103_t0054_u01790300388636") 2026/09/25 01:39:50 DEBUG : B2 bucket rclone-test-ludenik2mepo: Purge remote 2026/09/25 01:39:50 INFO : B2 bucket rclone-test-ludenik2mepo: cleaning bucket "rclone-test-ludenik2mepo" of all files 2026/09/25 01:39:50 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_z0fc2edb3307ddb6baf080e1b_f111baa2bcb8adb98_d20260925_m013944_c001_v0001116_t0044_u01790300384917") 2026/09/25 01:39:51 DEBUG : B2 bucket rclone-test-tedeley6biro: Purge remote 2026/09/25 01:39:51 INFO : B2 bucket rclone-test-tedeley6biro: cleaning bucket "rclone-test-tedeley6biro" of all files 2026/09/25 01:39:52 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_zbfe2ddd3307ddb6baf080e1b_f100b60e246fb9514_d20260925_m013941_c001_v0001181_t0053_u01790300381524") 2026/09/25 01:39:52 DEBUG : B2 bucket rclone-test-hakoyak2fona: Purge remote 2026/09/25 01:39:52 INFO : B2 bucket rclone-test-hakoyak2fona: cleaning bucket "rclone-test-hakoyak2fona" of all files 2026/09/25 01:39:53 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_z6f82dd13307ddb6baf080e1b_f1175ef22471dc7b0_d20260925_m013938_c001_v0001183_t0059_u01790300378314") 2026/09/25 01:39:53 DEBUG : B2 bucket rclone-test-nuxisov2seji: Purge remote 2026/09/25 01:39:53 INFO : B2 bucket rclone-test-nuxisov2seji: cleaning bucket "rclone-test-nuxisov2seji" of all files 2026/09/25 01:39:54 DEBUG : 測試_Русский___ě_áñ: Deleting (id "4_z7fe2cd23307ddb6baf080e1b_f114b843f4df52a52_d20260925_m013934_c001_v0001102_t0031_u01790300374685") 2026/09/25 01:39:54 DEBUG : B2 bucket rclone-test-gucakes0ruci: Purge remote 2026/09/25 01:39:54 INFO : B2 bucket rclone-test-gucakes0ruci: cleaning bucket "rclone-test-gucakes0ruci" of all files 2026/09/25 01:39:55 DEBUG : 測試_Русский___ě_áñ: Deleting (id "4_zff52bd53307ddb6baf080e1b_f4145db2e64093c6c_d20260925_m013931_c001_v0001180_t0053_u01790300371353") 2026/09/25 01:39:55 DEBUG : B2 bucket rclone-test-yitojix6sevi: Purge remote 2026/09/25 01:39:55 INFO : B2 bucket rclone-test-yitojix6sevi: cleaning bucket "rclone-test-yitojix6sevi" of all files 2026/09/25 01:39:56 DEBUG : HELLO, WORLD!: Deleting (id "4_zcf72ad93307ddb6baf080e1b_f4108c497393193cd_d20260925_m013928_c001_v0001041_t0025_u01790300368210") 2026/09/25 01:39:57 DEBUG : B2 bucket rclone-test-nazidof2ziti: Purge remote 2026/09/25 01:39:57 INFO : B2 bucket rclone-test-nazidof2ziti: cleaning bucket "rclone-test-nazidof2ziti" of all files 2026/09/25 01:39:57 DEBUG : HeLlO, wOrLd!: Deleting (id "4_z8f129dd3307ddb6baf080e1b_f411625decb78aa84_d20260925_m013925_c001_v0001113_t0026_u01790300365090") 2026/09/25 01:39:58 DEBUG : B2 bucket rclone-test-dojiqin6nure: Purge remote 2026/09/25 01:39:58 INFO : B2 bucket rclone-test-dojiqin6nure: cleaning bucket "rclone-test-dojiqin6nure" of all files 2026/09/25 01:39:58 DEBUG : HELLO, WORLD!: Deleting (id "4_z9f129d03307ddb6baf080e1b_f419d25db894fba59_d20260925_m013921_c001_v0001145_t0057_u01790300361877") 2026/09/25 01:39:59 DEBUG : B2 bucket rclone-test-pugodub3wego: Purge remote 2026/09/25 01:39:59 INFO : B2 bucket rclone-test-pugodub3wego: cleaning bucket "rclone-test-pugodub3wego" of all files 2026/09/25 01:39:59 DEBUG : hello, world!: Deleting (id "4_z7fb28d43307ddb6baf080e1b_f4142cf3cb177b104_d20260925_m013918_c001_v0001165_t0057_u01790300358642") --- PASS: TestApplyTransforms (43.56s) === RUN TestTruncateString --- PASS: TestTruncateString (0.00s) === RUN TestCopyFile run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" 2026/09/25 01:40:00 DEBUG : file1: Need to transfer - File not found at Destination 2026/09/25 01:40:01 DEBUG : sub/file2: size = 14 OK 2026/09/25 01:40:01 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2026/09/25 01:40:01 INFO : file1: Copied (new) to: sub/file2 2026/09/25 01:40:02 DEBUG : sub/file2: size = 14 OK 2026/09/25 01:40:02 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/25 01:40:02 DEBUG : file1: Unchanged skipping 2026/09/25 01:40:02 DEBUG : B2 bucket rclone-test-hedekel9dubi: don't need to copy/move sub/file2, it is already at target location --- PASS: TestCopyFile (2.71s) === RUN TestCopyFileImmutable run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" 2026/09/25 01:40:03 DEBUG : existing: Need to transfer - File not found at Destination 2026/09/25 01:40:03 DEBUG : existing: size = 6 OK 2026/09/25 01:40:03 DEBUG : existing: sha1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2026/09/25 01:40:03 INFO : existing: Copied (new) 2026/09/25 01:40:03 DEBUG : existing: size = 6 OK 2026/09/25 01:40:03 DEBUG : existing: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/25 01:40:03 DEBUG : existing: Unchanged skipping 2026/09/25 01:40:04 DEBUG : existing: size = 8 (Local file system at /tmp/rclone2832011420) 2026/09/25 01:40:04 DEBUG : existing: size = 6 (B2 bucket rclone-test-hedekel9dubi) 2026/09/25 01:40:04 DEBUG : existing: Sizes differ 2026/09/25 01:40:04 ERROR : existing: Source and destination exist but do not match: immutable file modified --- PASS: TestCopyFileImmutable (1.97s) === RUN TestCopyLongFile run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" copy_test.go:188: Test only runs on local --- SKIP: TestCopyLongFile (0.35s) === RUN TestCopyFileBackupDir run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" 2026/09/25 01:40:05 DEBUG : Creating backend with remote "TestB2:rclone-test-hedekel9dubi/backup" 2026/09/25 01:40:06 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone2832011420) 2026/09/25 01:40:06 DEBUG : dst/file1: size = 18 (B2 bucket rclone-test-hedekel9dubi) 2026/09/25 01:40:06 DEBUG : dst/file1: Sizes differ 2026/09/25 01:40:08 DEBUG : dst/file1: size = 18 OK 2026/09/25 01:40:08 DEBUG : dst/file1: sha1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK 2026/09/25 01:40:08 INFO : dst/file1: Copied (server-side copy) 2026/09/25 01:40:08 INFO : dst/file1: Deleted 2026/09/25 01:40:08 DEBUG : dst/file1: size = 14 OK 2026/09/25 01:40:08 DEBUG : dst/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2026/09/25 01:40:08 INFO : dst/file1: Copied (new) --- PASS: TestCopyFileBackupDir (4.48s) === RUN TestCopyFileCompareDest run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" 2026/09/25 01:40:09 DEBUG : Creating backend with remote "TestB2:rclone-test-hedekel9dubi/dst" 2026/09/25 01:40:11 DEBUG : Creating backend with remote "TestB2:rclone-test-hedekel9dubi/CompareDest" 2026/09/25 01:40:12 DEBUG : one: Need to transfer - File not found at Destination 2026/09/25 01:40:14 DEBUG : one: size = 3 OK 2026/09/25 01:40:14 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2026/09/25 01:40:14 INFO : one: Copied (new) 2026/09/25 01:40:14 DEBUG : one: size = 5 (Local file system at /tmp/rclone2832011420) 2026/09/25 01:40:14 DEBUG : one: size = 3 (B2 bucket rclone-test-hedekel9dubi path dst) 2026/09/25 01:40:14 DEBUG : one: Sizes differ 2026/09/25 01:40:15 DEBUG : one: size = 5 OK 2026/09/25 01:40:15 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2026/09/25 01:40:15 INFO : one: Copied (replaced existing) 2026/09/25 01:40:16 DEBUG : one: size = 5 (Local file system at /tmp/rclone2832011420) 2026/09/25 01:40:16 DEBUG : one: size = 3 (B2 bucket rclone-test-hedekel9dubi path dst) 2026/09/25 01:40:16 DEBUG : one: Sizes differ 2026/09/25 01:40:16 DEBUG : one: size = 5 OK 2026/09/25 01:40:16 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/09/25 01:40:16 DEBUG : one: Destination found in --compare-dest, skipping 2026/09/25 01:40:17 DEBUG : two: Need to transfer - File not found at Destination 2026/09/25 01:40:17 DEBUG : two: size = 3 OK 2026/09/25 01:40:17 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/09/25 01:40:17 DEBUG : two: Destination found in --compare-dest, skipping 2026/09/25 01:40:17 DEBUG : two: Need to transfer - File not found at Destination 2026/09/25 01:40:18 DEBUG : two: size = 3 OK 2026/09/25 01:40:18 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/09/25 01:40:18 DEBUG : two: Destination found in --compare-dest, skipping 2026/09/25 01:40:18 DEBUG : two: Need to transfer - File not found at Destination 2026/09/25 01:40:18 DEBUG : two: size = 5 (Local file system at /tmp/rclone2832011420) 2026/09/25 01:40:18 DEBUG : two: size = 3 (B2 bucket rclone-test-hedekel9dubi path CompareDest) 2026/09/25 01:40:18 DEBUG : two: Sizes differ 2026/09/25 01:40:19 DEBUG : two: size = 5 OK 2026/09/25 01:40:19 DEBUG : two: sha1 = a4be8877ad88f3109a6411985a1f359e10e2d930 OK 2026/09/25 01:40:19 INFO : two: Copied (new) --- PASS: TestCopyFileCompareDest (10.48s) === RUN TestCopyFileCopyDestImmutable run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" 2026/09/25 01:40:20 DEBUG : Creating backend with remote "TestB2:rclone-test-hedekel9dubi/dst" 2026/09/25 01:40:22 DEBUG : Creating backend with remote "TestB2:rclone-test-hedekel9dubi/CopyDest" 2026/09/25 01:40:23 DEBUG : one: size = 5 (Local file system at /tmp/rclone2832011420) 2026/09/25 01:40:23 DEBUG : one: size = 3 (B2 bucket rclone-test-hedekel9dubi path dst) 2026/09/25 01:40:23 DEBUG : one: Sizes differ 2026/09/25 01:40:23 DEBUG : one: size = 5 OK 2026/09/25 01:40:23 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/09/25 01:40:23 DEBUG : one: size = 5 (Local file system at /tmp/rclone2832011420) 2026/09/25 01:40:23 DEBUG : one: size = 3 (B2 bucket rclone-test-hedekel9dubi path dst) 2026/09/25 01:40:23 DEBUG : one: Sizes differ 2026/09/25 01:40:23 ERROR : one: Source and destination exist but do not match: immutable file modified --- PASS: TestCopyFileCopyDestImmutable (4.11s) === RUN TestCopyFileCopyDest run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" 2026/09/25 01:40:24 DEBUG : Creating backend with remote "TestB2:rclone-test-hedekel9dubi/dst" 2026/09/25 01:40:45 DEBUG : pacer: low level retry 1/10 (error Get "https://api.backblazeb2.com/b2api/v4/b2_authorize_account": EOF) 2026/09/25 01:40:45 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/09/25 01:40:46 DEBUG : pacer: Reducing sleep to 10ms 2026/09/25 01:40:46 DEBUG : Creating backend with remote "TestB2:rclone-test-hedekel9dubi/CopyDest" 2026/09/25 01:41:08 DEBUG : pacer: low level retry 1/10 (error Head "https://f001.backblazeb2.com/file/rclone-test-hedekel9dubi/CopyDest": EOF) 2026/09/25 01:41:08 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/09/25 01:41:09 DEBUG : pacer: Reducing sleep to 10ms 2026/09/25 01:41:09 DEBUG : one: Need to transfer - File not found at Destination 2026/09/25 01:41:11 DEBUG : one: size = 3 OK 2026/09/25 01:41:11 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2026/09/25 01:41:11 INFO : one: Copied (new) 2026/09/25 01:41:12 DEBUG : one: size = 5 (Local file system at /tmp/rclone2832011420) 2026/09/25 01:41:12 DEBUG : one: size = 3 (B2 bucket rclone-test-hedekel9dubi path dst) 2026/09/25 01:41:12 DEBUG : one: Sizes differ 2026/09/25 01:41:13 DEBUG : one: size = 5 OK 2026/09/25 01:41:13 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2026/09/25 01:41:13 INFO : one: Copied (replaced existing) 2026/09/25 01:41:14 DEBUG : Creating backend with remote "TestB2:rclone-test-hedekel9dubi/BackupDir" 2026/09/25 01:41:15 DEBUG : one: size = 5 (Local file system at /tmp/rclone2832011420) 2026/09/25 01:41:15 DEBUG : one: size = 3 (B2 bucket rclone-test-hedekel9dubi path dst) 2026/09/25 01:41:15 DEBUG : one: Sizes differ 2026/09/25 01:41:15 DEBUG : one: size = 5 OK 2026/09/25 01:41:15 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/09/25 01:41:15 DEBUG : one: size = 5 (Local file system at /tmp/rclone2832011420) 2026/09/25 01:41:15 DEBUG : one: size = 3 (B2 bucket rclone-test-hedekel9dubi path dst) 2026/09/25 01:41:15 DEBUG : one: Sizes differ 2026/09/25 01:41:17 DEBUG : one: size = 3 OK 2026/09/25 01:41:17 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2026/09/25 01:41:17 INFO : one: Copied (server-side copy) 2026/09/25 01:41:17 INFO : one: Deleted 2026/09/25 01:41:18 DEBUG : one: size = 5 OK 2026/09/25 01:41:18 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2026/09/25 01:41:18 INFO : one: Copied (server-side copy) 2026/09/25 01:41:18 DEBUG : one: Destination found in --copy-dest, using server-side copy 2026/09/25 01:41:18 DEBUG : two: Need to transfer - File not found at Destination 2026/09/25 01:41:19 DEBUG : two: size = 3 OK 2026/09/25 01:41:19 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/09/25 01:41:19 DEBUG : two: size = 3 OK 2026/09/25 01:41:19 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2026/09/25 01:41:19 INFO : two: Copied (server-side copy) 2026/09/25 01:41:19 DEBUG : two: Destination found in --copy-dest, using server-side copy 2026/09/25 01:41:19 DEBUG : two: size = 3 OK 2026/09/25 01:41:19 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/09/25 01:41:19 DEBUG : two: Unchanged skipping 2026/09/25 01:41:20 DEBUG : three: Need to transfer - File not found at Destination 2026/09/25 01:41:20 DEBUG : three: size = 7 (Local file system at /tmp/rclone2832011420) 2026/09/25 01:41:20 DEBUG : three: size = 5 (B2 bucket rclone-test-hedekel9dubi path CopyDest) 2026/09/25 01:41:20 DEBUG : three: Sizes differ 2026/09/25 01:41:20 DEBUG : three: Destination not found in --copy-dest 2026/09/25 01:41:21 DEBUG : three: size = 7 OK 2026/09/25 01:41:21 DEBUG : three: sha1 = 370e613335f3cf2f19b2a2d35a86402f55c659f8 OK 2026/09/25 01:41:21 INFO : three: Copied (new) --- PASS: TestCopyFileCopyDest (58.70s) === RUN TestCopyInplace run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" copy_test.go:434: Partial uploads not supported --- SKIP: TestCopyInplace (0.36s) === RUN TestCopyLongFileName run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" copy_test.go:467: Partial uploads not supported --- SKIP: TestCopyLongFileName (0.36s) === RUN TestCopyLongFileNameCollision run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" copy_test.go:500: Partial uploads not supported --- SKIP: TestCopyLongFileNameCollision (0.36s) === RUN TestCopyFileMaxTransfer run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" 2026/09/25 01:41:24 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination 2026/09/25 01:41:24 DEBUG : TestCopyFileMaxTransfer/file1: size = 14 OK 2026/09/25 01:41:24 DEBUG : TestCopyFileMaxTransfer/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2026/09/25 01:41:24 INFO : TestCopyFileMaxTransfer/file1: Copied (new) 2026/09/25 01:41:25 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination 2026/09/25 01:41:25 ERROR : TestCopyFileMaxTransfer/file2: Failed to copy: Post "https://pod-000-1180-09.backblaze.com/b2api/v1/b2_upload_file/0fd20de3303ddb6baf080e1b/c001_v0001180_t0050": max transfer limit reached as set by --max-transfer 2026/09/25 01:41:25 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination 2026/09/25 01:41:26 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination 2026/09/25 01:41:26 DEBUG : TestCopyFileMaxTransfer/file4: size = 2062 OK 2026/09/25 01:41:26 DEBUG : TestCopyFileMaxTransfer/file4: sha1 = beaf617616821665315557596ccc878c63ff5b7b OK 2026/09/25 01:41:26 INFO : TestCopyFileMaxTransfer/file4: Copied (new) --- PASS: TestCopyFileMaxTransfer (3.42s) === RUN TestDeduplicateInteractive run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" dedupe_test.go:25: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateInteractive (0.44s) === RUN TestDeduplicateSkip run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" dedupe_test.go:25: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSkip (0.36s) === RUN TestDeduplicateSizeOnly run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" dedupe_test.go:25: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSizeOnly (0.36s) === RUN TestDeduplicateFirst run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" dedupe_test.go:25: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateFirst (0.36s) === RUN TestDeduplicateNewest run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" dedupe_test.go:25: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateNewest (0.36s) === RUN TestDeduplicateNewestByHash run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" 2026/09/25 01:41:30 INFO : B2 bucket rclone-test-hedekel9dubi: Looking for duplicate sha1 hashes using newest mode. 2026/09/25 01:41:30 NOTICE: 362d17b84246d10e217c5a356416db86a726d111: Found 3 files with duplicate sha1 hashes 2026/09/25 01:41:30 INFO : one: Deleted 2026/09/25 01:41:31 INFO : also/one: Deleted 2026/09/25 01:41:31 NOTICE: 362d17b84246d10e217c5a356416db86a726d111: Deleted 2 extra copies --- PASS: TestDeduplicateNewestByHash (2.62s) === RUN TestDeduplicateOldest run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" dedupe_test.go:25: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateOldest (0.36s) === RUN TestDeduplicateLargest run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" dedupe_test.go:25: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateLargest (0.36s) === RUN TestDeduplicateSmallest run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" dedupe_test.go:25: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSmallest (0.43s) === RUN TestDeduplicateRename run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" dedupe_test.go:25: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateRename (0.36s) === RUN TestDeduplicateRenameManyExisting run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" dedupe_test.go:25: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateRenameManyExisting (0.36s) === RUN TestMergeDirs run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" dedupe_test.go:294: Can't merge directories --- SKIP: TestMergeDirs (0.36s) === RUN TestListDirSorted run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" 2026/09/25 01:41:36 DEBUG : a.txt: Excluded (Size Filter) 2026/09/25 01:41:36 DEBUG : a.txt: Excluded 2026/09/25 01:41:36 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/09/25 01:41:36 DEBUG : sub dir/hello world: Excluded 2026/09/25 01:41:36 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/09/25 01:41:36 DEBUG : sub dir/hello world2: Excluded 2026/09/25 01:41:36 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/09/25 01:41:36 DEBUG : sub dir/hello world: Excluded 2026/09/25 01:41:36 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/09/25 01:41:36 DEBUG : sub dir/hello world2: Excluded 2026/09/25 01:41:37 DEBUG : sub dir/ignore dir: Excluded 2026/09/25 01:41:37 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSorted (5.47s) === RUN TestListDirSortedFn run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" 2026/09/25 01:41:41 DEBUG : a.txt: Excluded (Size Filter) 2026/09/25 01:41:41 DEBUG : a.txt: Excluded 2026/09/25 01:41:42 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/09/25 01:41:42 DEBUG : sub dir/hello world: Excluded 2026/09/25 01:41:42 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/09/25 01:41:42 DEBUG : sub dir/hello world2: Excluded 2026/09/25 01:41:42 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/09/25 01:41:42 DEBUG : sub dir/hello world: Excluded 2026/09/25 01:41:42 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/09/25 01:41:42 DEBUG : sub dir/hello world2: Excluded 2026/09/25 01:41:42 DEBUG : sub dir/ignore dir: Excluded 2026/09/25 01:41:42 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSortedFn (5.20s) === RUN TestListJSON run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", 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 2026/09/25 01:41:47 ERROR : file1: Failed to read hash: hash type not supported === RUN TestListJSON/Metadata --- PASS: TestListJSON (3.44s) --- PASS: TestListJSON/Default (0.18s) --- PASS: TestListJSON/FilesOnly (0.18s) --- PASS: TestListJSON/DirsOnly (0.18s) --- PASS: TestListJSON/Recurse (0.18s) --- PASS: TestListJSON/SubDir (0.18s) --- PASS: TestListJSON/NoModTime (0.18s) --- PASS: TestListJSON/NoMimeType (0.18s) --- PASS: TestListJSON/ShowHash (0.18s) --- PASS: TestListJSON/HashTypes (0.18s) --- PASS: TestListJSON/Metadata (0.18s) === RUN TestStatJSON run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", 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 2026/09/25 01:41:52 DEBUG : Creating backend with remote "TestB2:rclone-test-hedekel9dubi/notfound" --- PASS: TestStatJSON (6.58s) --- PASS: TestStatJSON/Root (0.18s) --- PASS: TestStatJSON/RootFilesOnly (0.00s) --- PASS: TestStatJSON/RootDirsOnly (0.18s) --- PASS: TestStatJSON/Dir (0.81s) --- PASS: TestStatJSON/DirWithTrailingSlash (0.18s) --- PASS: TestStatJSON/File (0.18s) --- PASS: TestStatJSON/NotFound (0.54s) --- PASS: TestStatJSON/DirFilesOnly (0.18s) --- PASS: TestStatJSON/FileFilesOnly (0.18s) --- PASS: TestStatJSON/NotFoundFilesOnly (0.18s) --- PASS: TestStatJSON/DirDirsOnly (0.18s) --- PASS: TestStatJSON/FileDirsOnly (0.36s) --- PASS: TestStatJSON/NotFoundDirsOnly (0.36s) --- PASS: TestStatJSON/RootNotFound (1.81s) === RUN TestStatJSONMemory 2026/09/25 01:41:55 DEBUG : Creating backend with remote ":memory:" 2026/09/25 01:41:55 DEBUG : Memory root '': File to upload is small (5 bytes), uploading instead of streaming 2026/09/25 01:41:55 DEBUG : sub/file1: size = 5 OK 2026/09/25 01:41:55 DEBUG : sub/file1: md5 = 5d41402abc4b2a76b9719d911017c592 OK 2026/09/25 01:41:55 DEBUG : sub/file1: Size and md5 of src and dst objects identical === RUN TestStatJSONMemory/Dir === RUN TestStatJSONMemory/DirWithTrailingSlash === RUN TestStatJSONMemory/File === RUN TestStatJSONMemory/NotFound --- PASS: TestStatJSONMemory (0.00s) --- PASS: TestStatJSONMemory/Dir (0.00s) --- PASS: TestStatJSONMemory/DirWithTrailingSlash (0.00s) --- PASS: TestStatJSONMemory/File (0.00s) --- PASS: TestStatJSONMemory/NotFound (0.00s) === RUN TestStatJSONConfinement --- PASS: TestStatJSONConfinement (0.00s) === RUN TestMkdir run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" 2026/09/25 01:41:55 INFO : B2 bucket rclone-test-hedekel9dubi: Making directory 2026/09/25 01:41:55 INFO : B2 bucket rclone-test-hedekel9dubi: Making directory --- PASS: TestMkdir (0.54s) === RUN TestLsd run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" --- PASS: TestLsd (1.52s) === RUN TestLs run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" --- PASS: TestLs (1.50s) === RUN TestLsWithFilesFrom run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" 2026/09/25 01:41:59 DEBUG : empty space: Excluded (FilesFrom Filter) 2026/09/25 01:41:59 DEBUG : empty space: Excluded --- PASS: TestLsWithFilesFrom (2.04s) === RUN TestLsLong run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" --- PASS: TestLsLong (1.65s) === RUN TestHashSums run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" === RUN TestHashSums/Sha1 === RUN TestHashSums/Sha1Download --- PASS: TestHashSums (1.89s) --- PASS: TestHashSums/Sha1 (0.18s) --- PASS: TestHashSums/Sha1Download (0.37s) === RUN TestHashSumsWithErrors 2026/09/25 01:42:04 DEBUG : Creating backend with remote ":memory:" 2026/09/25 01:42:04 ERROR : file1: hash unsupported: hash type not supported 2026/09/25 01:42:04 ERROR : sub/file1: hash unsupported: hash type not supported 2026/09/25 01:42:04 ERROR : sub/file1: hash unsupported: hash type not supported --- PASS: TestHashSumsWithErrors (0.01s) === RUN TestHashStream 2026/09/25 01:42:04 DEBUG : Creating md5 hash of 0 bytes read from input stream 2026/09/25 01:42:04 DEBUG : Creating md5 hash of 0 bytes read from input stream 2026/09/25 01:42:04 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2026/09/25 01:42:04 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2026/09/25 01:42:04 DEBUG : Creating md5 hash of 12 bytes read from input stream 2026/09/25 01:42:04 DEBUG : Creating md5 hash of 12 bytes read from input stream 2026/09/25 01:42:04 DEBUG : Creating sha1 hash of 12 bytes read from input stream 2026/09/25 01:42:04 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:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" --- PASS: TestCount (1.88s) === RUN TestDelete run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" 2026/09/25 01:42:06 DEBUG : Waiting for deletions to finish 2026/09/25 01:42:07 DEBUG : large: Excluded (Size Filter) 2026/09/25 01:42:07 INFO : medium: Deleted 2026/09/25 01:42:07 INFO : small: Deleted --- PASS: TestDelete (2.15s) === RUN TestDeleteFatalError run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" 2026/09/25 01:42:12 DEBUG : Waiting for deletions to finish 2026/09/25 01:42:12 ERROR : file1: Got fatal error on delete: --max-delete threshold reached 2026/09/25 01:42:12 ERROR : file10: Got fatal error on delete: --max-delete threshold reached 2026/09/25 01:42:12 ERROR : file11: Got fatal error on delete: --max-delete threshold reached 2026/09/25 01:42:12 ERROR : file12: Got fatal error on delete: --max-delete threshold reached 2026/09/25 01:42:12 ERROR : file13: Got fatal error on delete: --max-delete threshold reached 2026/09/25 01:42:12 ERROR : file14: Got fatal error on delete: --max-delete threshold reached 2026/09/25 01:42:12 ERROR : file15: Got fatal error on delete: --max-delete threshold reached 2026/09/25 01:42:12 ERROR : file16: Got fatal error on delete: --max-delete threshold reached 2026/09/25 01:42:12 ERROR : file17: Got fatal error on delete: --max-delete threshold reached 2026/09/25 01:42:12 ERROR : file18: Got fatal error on delete: --max-delete threshold reached 2026/09/25 01:42:12 ERROR : file19: Got fatal error on delete: --max-delete threshold reached 2026/09/25 01:42:12 ERROR : file2: Got fatal error on delete: --max-delete threshold reached 2026/09/25 01:42:12 ERROR : file3: Got fatal error on delete: --max-delete threshold reached 2026/09/25 01:42:12 ERROR : file4: Got fatal error on delete: --max-delete threshold reached 2026/09/25 01:42:12 ERROR : file5: Got fatal error on delete: --max-delete threshold reached 2026/09/25 01:42:12 ERROR : file6: Got fatal error on delete: --max-delete threshold reached 2026/09/25 01:42:12 ERROR : file7: Got fatal error on delete: --max-delete threshold reached 2026/09/25 01:42:12 ERROR : file8: Got fatal error on delete: --max-delete threshold reached 2026/09/25 01:42:12 ERROR : file9: Got fatal error on delete: --max-delete threshold reached 2026/09/25 01:42:12 ERROR : file0: Got fatal error on delete: --max-delete threshold reached --- PASS: TestDeleteFatalError (8.29s) === RUN TestMaxDelete run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" 2026/09/25 01:42:17 DEBUG : Waiting for deletions to finish 2026/09/25 01:42:17 ERROR : large: Got fatal error on delete: --max-delete threshold reached 2026/09/25 01:42:17 INFO : small: Deleted 2026/09/25 01:42:17 INFO : medium: Deleted --- PASS: TestMaxDelete (2.45s) === RUN TestMaxDeleteSizeLargeFile run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" 2026/09/25 01:42:20 DEBUG : Waiting for deletions to finish 2026/09/25 01:42:20 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached 2026/09/25 01:42:21 INFO : medium: Deleted 2026/09/25 01:42:21 INFO : small: Deleted --- PASS: TestMaxDeleteSizeLargeFile (3.25s) === RUN TestMaxDeleteSize run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" 2026/09/25 01:42:23 DEBUG : Waiting for deletions to finish 2026/09/25 01:42:23 ERROR : small: Got fatal error on delete: --max-delete-size threshold reached 2026/09/25 01:42:23 INFO : large: Deleted 2026/09/25 01:42:23 INFO : medium: Deleted --- PASS: TestMaxDeleteSize (2.30s) === RUN TestReadFile run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" --- PASS: TestReadFile (2.01s) === RUN TestRetry 2026/09/25 01:42:26 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 1/5 2026/09/25 01:42:26 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 2/5 2026/09/25 01:42:26 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2026/09/25 01:42:26 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2026/09/25 01:42:26 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2026/09/25 01:42:26 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms --- PASS: TestRetry (0.04s) === RUN TestRetryAfterContextCancel 2026/09/25 01:42:26 DEBUG : Sleeping for 1h0m0s (as indicated by the server) to obey Retry-After error: BANG: trying again in 1h0m0s --- PASS: TestRetryAfterContextCancel (0.00s) === RUN TestRetryAfterLastTry --- PASS: TestRetryAfterLastTry (0.00s) === RUN TestCat run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" --- PASS: TestCat (4.03s) === RUN TestPurge 2026/09/25 01:42:30 DEBUG : Creating backend with remote "TestB2:rclone-test-qecimul3folu" 2026/09/25 01:42:30 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/25 01:42:31 DEBUG : Creating backend with remote "/tmp/rclone3422726426" run.go:198: Remote "B2 bucket rclone-test-qecimul3folu", Local "Local file system at /tmp/rclone3422726426", Modify Window "1ms" 2026/09/25 01:42:33 INFO : A2: Making directory 2026/09/25 01:42:33 INFO : A1/B2: Making directory 2026/09/25 01:42:33 INFO : A1/B2/C2: Making directory 2026/09/25 01:42:33 INFO : A1/B1/C3: Making directory 2026/09/25 01:42:33 INFO : A3: Making directory 2026/09/25 01:42:33 INFO : A3/B3: Making directory 2026/09/25 01:42:33 INFO : A3/B3/C4: Making directory fstest.go:251: Filtering empty directory "A2" fstest.go:251: Filtering empty directory "A1/B2" fstest.go:251: Filtering empty directory "A1/B2/C2" fstest.go:251: Filtering empty directory "A1/B1/C3" fstest.go:251: Filtering empty directory "A3" fstest.go:251: Filtering empty directory "A3/B3" fstest.go:251: Filtering empty directory "A3/B3/C4" 2026/09/25 01:42:33 INFO : B2 bucket rclone-test-qecimul3folu: cleaning bucket "rclone-test-qecimul3folu" of all files 2026/09/25 01:42:33 DEBUG : A1/B1/C1/one: Deleting (id "4_z4f827da330addb6baf080e1b_f41242b850a7227d5_d20260925_m014232_c001_v0001097_t0043_u01790300552916") fstest.go:251: Filtering empty directory "A2" fstest.go:251: Filtering empty directory "A1/B2" fstest.go:251: Filtering empty directory "A1/B2/C2" fstest.go:251: Filtering empty directory "A3" fstest.go:251: Filtering empty directory "A3/B3" fstest.go:251: Filtering empty directory "A3/B3/C4" 2026/09/25 01:42:33 INFO : B2 bucket rclone-test-qecimul3folu: cleaning bucket "rclone-test-qecimul3folu" of all files 2026/09/25 01:42:34 DEBUG : A1/two: Deleting (id "4_z4f827da330addb6baf080e1b_f41242b850a7227d7_d20260925_m014233_c001_v0001097_t0043_u01790300553103") 2026/09/25 01:42:34 DEBUG : B2 bucket rclone-test-qecimul3folu: Purge remote 2026/09/25 01:42:34 INFO : B2 bucket rclone-test-qecimul3folu: cleaning bucket "rclone-test-qecimul3folu" of all files 2026/09/25 01:42:35 NOTICE: purge failed: directory not found --- PASS: TestPurge (4.45s) === RUN TestRmdirsNoLeaveRoot run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" 2026/09/25 01:42:35 INFO : A2: Making directory 2026/09/25 01:42:35 INFO : A1/B2: Making directory 2026/09/25 01:42:35 INFO : A1/B2/C2: Making directory 2026/09/25 01:42:35 INFO : A1/B1/C3: Making directory 2026/09/25 01:42:35 INFO : A3: Making directory 2026/09/25 01:42:35 INFO : A3/B3: Making directory 2026/09/25 01:42:35 INFO : A3/B3/C4: Making directory fstest.go:251: Filtering empty directory "A2" fstest.go:251: Filtering empty directory "A1/B2" fstest.go:251: Filtering empty directory "A1/B2/C2" fstest.go:251: Filtering empty directory "A1/B1/C3" fstest.go:251: Filtering empty directory "A3" fstest.go:251: Filtering empty directory "A3/B3" fstest.go:251: Filtering empty directory "A3/B3/C4" 2026/09/25 01:42:36 DEBUG : removing 1 level 3 directories 2026/09/25 01:42:36 INFO : A3/B3/C4: Removing directory fstest.go:251: Filtering empty directory "A2" fstest.go:251: Filtering empty directory "A1/B2" fstest.go:251: Filtering empty directory "A1/B2/C2" fstest.go:251: Filtering empty directory "A1/B1/C3" fstest.go:251: Filtering empty directory "A3" fstest.go:251: Filtering empty directory "A3/B3" 2026/09/25 01:42:38 DEBUG : removing 1 level 0 directories 2026/09/25 01:42:38 INFO : B2 bucket rclone-test-hedekel9dubi: Removing directory 2026/09/25 01:42:38 ERROR : Failed to rmdir: failed to delete bucket: Cannot delete non-empty bucket (400 cannot_delete_non_empty_bucket) operations_test.go:807: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:807 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.42s) === RUN TestRmdirsLeaveRoot run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" 2026/09/25 01:42:39 INFO : A1: Making directory 2026/09/25 01:42:39 INFO : A1/B1: Making directory 2026/09/25 01:42:39 INFO : A1/B1/C1: Making directory fstest.go:251: Filtering empty directory "A1" fstest.go:251: Filtering empty directory "A1/B1" fstest.go:251: Filtering empty directory "A1/B1/C1" fstest.go:251: Filtering empty directory "A1" --- PASS: TestRmdirsLeaveRoot (0.91s) === RUN TestRmdirsWithFilter run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" 2026/09/25 01:42:40 INFO : A1: Making directory 2026/09/25 01:42:40 INFO : A1/B1: Making directory 2026/09/25 01:42:40 INFO : A1/B1/C1: Making directory fstest.go:251: Filtering empty directory "A1" fstest.go:251: Filtering empty directory "A1/B1" fstest.go:251: Filtering empty directory "A1/B1/C1" fstest.go:251: Filtering empty directory "A1" --- PASS: TestRmdirsWithFilter (1.02s) === RUN TestCopyURL run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" 2026/09/25 01:42:42 DEBUG : file1: size = 14 OK 2026/09/25 01:42:42 DEBUG : file1: sha1 = 3c3771c1f3304bdb052b101cc97a23df1ab9531f OK 2026/09/25 01:42:42 DEBUG : filename.txt: File name found in url 2026/09/25 01:42:42 DEBUG : filename.txt: size = 14 OK 2026/09/25 01:42:42 DEBUG : filename.txt: sha1 = 3c3771c1f3304bdb052b101cc97a23df1ab9531f OK 2026/09/25 01:42:42 DEBUG : headerfilename.txt: filename found in Content-Disposition header. 2026/09/25 01:42:43 DEBUG : headerfilename.txt: size = 14 OK 2026/09/25 01:42:43 DEBUG : headerfilename.txt: sha1 = 3c3771c1f3304bdb052b101cc97a23df1ab9531f OK 2026/09/25 01:42:43 DEBUG : file2: size = 14 OK 2026/09/25 01:42:43 DEBUG : file2: sha1 = 3c3771c1f3304bdb052b101cc97a23df1ab9531f OK --- PASS: TestCopyURL (3.18s) === RUN TestCopyURLDownloadHeaders run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" 2026/09/25 01:42:44 DEBUG : file1: size = 14 OK 2026/09/25 01:42:44 DEBUG : file1: sha1 = 3c3771c1f3304bdb052b101cc97a23df1ab9531f OK --- PASS: TestCopyURLDownloadHeaders (0.73s) === RUN TestCopyURLToWriter --- PASS: TestCopyURLToWriter (0.00s) === RUN TestMoveFile run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" 2026/09/25 01:42:45 DEBUG : file1: Need to transfer - File not found at Destination 2026/09/25 01:42:45 DEBUG : sub/file2: size = 14 OK 2026/09/25 01:42:45 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2026/09/25 01:42:45 INFO : file1: Copied (new) to: sub/file2 2026/09/25 01:42:45 INFO : file1: Deleted 2026/09/25 01:42:46 DEBUG : sub/file2: size = 14 OK 2026/09/25 01:42:46 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/25 01:42:46 DEBUG : file1: Unchanged skipping 2026/09/25 01:42:46 INFO : file1: Deleted 2026/09/25 01:42:46 DEBUG : B2 bucket rclone-test-hedekel9dubi: don't need to copy/move sub/file2, it is already at target location --- PASS: TestMoveFile (1.64s) === RUN TestMoveFileWithIgnoreExisting run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" 2026/09/25 01:42:47 DEBUG : file1: Need to transfer - File not found at Destination 2026/09/25 01:42:47 DEBUG : file1: size = 14 OK 2026/09/25 01:42:47 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2026/09/25 01:42:47 INFO : file1: Copied (new) 2026/09/25 01:42:47 INFO : file1: Deleted 2026/09/25 01:42:47 DEBUG : file1: Destination exists, skipping 2026/09/25 01:42:47 DEBUG : file1: Not removing source file as destination file exists and --ignore-existing is set --- PASS: TestMoveFileWithIgnoreExisting (1.46s) === RUN TestMoveFileImmutable run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" 2026/09/25 01:42:48 DEBUG : existing: size = 8 (Local file system at /tmp/rclone2832011420) 2026/09/25 01:42:48 DEBUG : existing: size = 6 (B2 bucket rclone-test-hedekel9dubi) 2026/09/25 01:42:48 DEBUG : existing: Sizes differ 2026/09/25 01:42:48 ERROR : existing: Source and destination exist but do not match: immutable file modified --- PASS: TestMoveFileImmutable (1.31s) === RUN TestCaseInsensitiveMoveFile run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFile (0.37s) === RUN TestCaseInsensitiveMoveFileDryRun run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFileDryRun (0.37s) === RUN TestMoveFileBackupDir run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" 2026/09/25 01:42:51 DEBUG : Creating backend with remote "TestB2:rclone-test-hedekel9dubi/backup" 2026/09/25 01:42:52 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone2832011420) 2026/09/25 01:42:52 DEBUG : dst/file1: size = 18 (B2 bucket rclone-test-hedekel9dubi) 2026/09/25 01:42:52 DEBUG : dst/file1: Sizes differ 2026/09/25 01:42:58 DEBUG : dst/file1: size = 18 OK 2026/09/25 01:42:58 DEBUG : dst/file1: sha1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK 2026/09/25 01:42:58 INFO : dst/file1: Copied (server-side copy) 2026/09/25 01:42:59 INFO : dst/file1: Deleted 2026/09/25 01:42:59 DEBUG : dst/file1: size = 14 OK 2026/09/25 01:42:59 DEBUG : dst/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2026/09/25 01:42:59 INFO : dst/file1: Copied (new) 2026/09/25 01:42:59 INFO : dst/file1: Deleted --- PASS: TestMoveFileBackupDir (10.43s) === 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 TestDirMoveMoveError run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" operations_test.go:1490: Skipping test on non local remote --- SKIP: TestDirMoveMoveError (7.72s) === RUN TestDirMoveContext run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" operations_test.go:1490: Skipping test on non local remote --- SKIP: TestDirMoveContext (1.10s) === RUN TestDirMove run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" 2026/09/25 01:43:10 INFO : A1/B2: Making directory 2026/09/25 01:43:10 INFO : A1/B1/C3: Making directory fstest.go:251: Filtering empty directory "A1/B2" fstest.go:251: Filtering empty directory "A1/B1/C3" 2026/09/25 01:43:11 DEBUG : A2/B1/C1/four: size = 4 OK 2026/09/25 01:43:11 DEBUG : A1/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2026/09/25 01:43:11 INFO : A1/B1/C1/four: Copied (server-side copy) to: A2/B1/C1/four 2026/09/25 01:43:12 DEBUG : A2/one: size = 3 OK 2026/09/25 01:43:12 DEBUG : A1/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2026/09/25 01:43:12 INFO : A1/one: Copied (server-side copy) to: A2/one 2026/09/25 01:43:12 DEBUG : A2/B1/C2/five: size = 4 OK 2026/09/25 01:43:12 DEBUG : A1/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2026/09/25 01:43:12 INFO : A1/B1/C2/five: Copied (server-side copy) to: A2/B1/C2/five 2026/09/25 01:43:12 DEBUG : A2/two: size = 3 OK 2026/09/25 01:43:12 DEBUG : A1/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2026/09/25 01:43:12 INFO : A1/two: Copied (server-side copy) to: A2/two 2026/09/25 01:43:12 INFO : A1/B1/C1/four: Deleted 2026/09/25 01:43:12 INFO : A1/one: Deleted 2026/09/25 01:43:12 INFO : A1/B1/C2/five: Deleted 2026/09/25 01:43:12 INFO : A1/two: Deleted 2026/09/25 01:43:16 DEBUG : A2/B1/three: size = 5 OK 2026/09/25 01:43:16 DEBUG : A1/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2026/09/25 01:43:16 INFO : A1/B1/three: Copied (server-side copy) to: A2/B1/three 2026/09/25 01:43:17 INFO : A1/B1/three: Deleted fstest.go:251: Filtering empty directory "A2/B2" fstest.go:251: Filtering empty directory "A2/B1/C3" 2026/09/25 01:43:18 DEBUG : pacer: low level retry 1/10 (error Post "https://api001.backblazeb2.com/b2api/v1/b2_copy_file": EOF) 2026/09/25 01:43:18 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/09/25 01:43:18 DEBUG : pacer: low level retry 1/10 (error Post "https://api001.backblazeb2.com/b2api/v1/b2_copy_file": EOF) 2026/09/25 01:43:18 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/09/25 01:43:18 DEBUG : pacer: Reducing sleep to 20ms 2026/09/25 01:43:18 DEBUG : pacer: Reducing sleep to 10ms 2026/09/25 01:43:18 DEBUG : A3/B1/C2/five: size = 4 OK 2026/09/25 01:43:18 DEBUG : A2/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2026/09/25 01:43:18 INFO : A2/B1/C2/five: Copied (server-side copy) to: A3/B1/C2/five 2026/09/25 01:43:18 DEBUG : A3/B1/C1/four: size = 4 OK 2026/09/25 01:43:18 DEBUG : A2/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2026/09/25 01:43:18 INFO : A2/B1/C1/four: Copied (server-side copy) to: A3/B1/C1/four 2026/09/25 01:43:18 DEBUG : A3/B1/three: size = 5 OK 2026/09/25 01:43:18 DEBUG : A2/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2026/09/25 01:43:18 INFO : A2/B1/three: Copied (server-side copy) to: A3/B1/three 2026/09/25 01:43:18 DEBUG : A3/one: size = 3 OK 2026/09/25 01:43:18 DEBUG : A2/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2026/09/25 01:43:18 INFO : A2/one: Copied (server-side copy) to: A3/one 2026/09/25 01:43:18 DEBUG : A3/two: size = 3 OK 2026/09/25 01:43:18 DEBUG : A2/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2026/09/25 01:43:18 INFO : A2/two: Copied (server-side copy) to: A3/two 2026/09/25 01:43:18 INFO : A2/B1/C2/five: Deleted 2026/09/25 01:43:18 INFO : A2/B1/C1/four: Deleted 2026/09/25 01:43:18 INFO : A2/B1/three: Deleted 2026/09/25 01:43:18 INFO : A2/one: Deleted 2026/09/25 01:43:18 INFO : A2/two: Deleted fstest.go:251: Filtering empty directory "A3/B2" fstest.go:251: Filtering empty directory "A3/B1/C3" 2026/09/25 01:43:19 INFO : B2 bucket rclone-test-hedekel9dubi: Can't DirMove - falling back to file moves: can't move directory - incompatible remotes 2026/09/25 01:43:19 DEBUG : A4/B1/C1/four: size = 4 OK 2026/09/25 01:43:19 DEBUG : A3/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2026/09/25 01:43:19 INFO : A3/B1/C1/four: Copied (server-side copy) to: A4/B1/C1/four 2026/09/25 01:43:19 DEBUG : A4/B1/three: size = 5 OK 2026/09/25 01:43:19 DEBUG : A3/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2026/09/25 01:43:19 INFO : A3/B1/three: Copied (server-side copy) to: A4/B1/three 2026/09/25 01:43:19 DEBUG : A4/B1/C2/five: size = 4 OK 2026/09/25 01:43:19 DEBUG : A3/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2026/09/25 01:43:19 INFO : A3/B1/C2/five: Copied (server-side copy) to: A4/B1/C2/five 2026/09/25 01:43:19 DEBUG : A4/one: size = 3 OK 2026/09/25 01:43:19 DEBUG : A3/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2026/09/25 01:43:19 INFO : A3/one: Copied (server-side copy) to: A4/one 2026/09/25 01:43:19 DEBUG : A4/two: size = 3 OK 2026/09/25 01:43:19 DEBUG : A3/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2026/09/25 01:43:19 INFO : A3/two: Copied (server-side copy) to: A4/two 2026/09/25 01:43:19 INFO : A3/B1/C1/four: Deleted 2026/09/25 01:43:19 INFO : A3/B1/three: Deleted 2026/09/25 01:43:19 INFO : A3/B1/C2/five: Deleted 2026/09/25 01:43:19 INFO : A3/one: Deleted 2026/09/25 01:43:19 INFO : A3/two: Deleted fstest.go:251: Filtering empty directory "A4/B2" fstest.go:251: Filtering empty directory "A4/B1/C3" --- PASS: TestDirMove (11.64s) === RUN TestGetFsInfo run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" 2026/09/25 01:43:21 DEBUG : Config file has changed externally - reloading --- PASS: TestGetFsInfo (0.38s) === RUN TestRcat === RUN TestRcat/withChecksum=false,ignoreChecksum=false run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" 2026/09/25 01:43:21 DEBUG : B2 bucket rclone-test-hedekel9dubi: File to upload is small (34 bytes), uploading instead of streaming 2026/09/25 01:43:22 DEBUG : no_checksum_small_file_from_pipe: size = 34 OK 2026/09/25 01:43:22 DEBUG : no_checksum_small_file_from_pipe: sha1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK 2026/09/25 01:43:22 DEBUG : no_checksum_small_file_from_pipe: Size and sha1 of src and dst objects identical 2026/09/25 01:43:22 DEBUG : no_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2026/09/25 01:43:23 DEBUG : no_checksum_big_file_from_pipe: size = 102401 OK 2026/09/25 01:43:23 DEBUG : no_checksum_big_file_from_pipe: sha1 = d0080f73aa8b494570ad63df1beb25c01ea9eea2 OK 2026/09/25 01:43:23 DEBUG : no_checksum_big_file_from_pipe: Size and sha1 of src and dst objects identical === RUN TestRcat/withChecksum=true,ignoreChecksum=false run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" 2026/09/25 01:43:24 DEBUG : B2 bucket rclone-test-hedekel9dubi: File to upload is small (34 bytes), uploading instead of streaming 2026/09/25 01:43:24 DEBUG : with_checksum_small_file_from_pipe: size = 34 OK 2026/09/25 01:43:24 DEBUG : with_checksum_small_file_from_pipe: sha1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK 2026/09/25 01:43:24 DEBUG : with_checksum_small_file_from_pipe: Size and sha1 of src and dst objects identical 2026/09/25 01:43:24 DEBUG : with_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2026/09/25 01:43:25 DEBUG : with_checksum_big_file_from_pipe: size = 102401 OK 2026/09/25 01:43:25 DEBUG : with_checksum_big_file_from_pipe: sha1 = d0080f73aa8b494570ad63df1beb25c01ea9eea2 OK 2026/09/25 01:43:25 DEBUG : with_checksum_big_file_from_pipe: Size and sha1 of src and dst objects identical === RUN TestRcat/withChecksum=false,ignoreChecksum=true run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" 2026/09/25 01:43:26 DEBUG : B2 bucket rclone-test-hedekel9dubi: File to upload is small (34 bytes), uploading instead of streaming 2026/09/25 01:43:26 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK 2026/09/25 01:43:26 DEBUG : ignore_checksum_small_file_from_pipe: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/25 01:43:26 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2026/09/25 01:43:27 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK 2026/09/25 01:43:27 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:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" 2026/09/25 01:43:28 DEBUG : B2 bucket rclone-test-hedekel9dubi: File to upload is small (34 bytes), uploading instead of streaming 2026/09/25 01:43:28 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK 2026/09/25 01:43:28 DEBUG : ignore_checksum_small_file_from_pipe: Src hash empty - aborting Dst hash check 2026/09/25 01:43:28 DEBUG : ignore_checksum_small_file_from_pipe: Size of src and dst objects identical 2026/09/25 01:43:28 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2026/09/25 01:43:29 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK 2026/09/25 01:43:29 DEBUG : ignore_checksum_big_file_from_pipe: Src hash empty - aborting Dst hash check 2026/09/25 01:43:29 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical --- PASS: TestRcat (8.89s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (2.47s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (2.11s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (2.18s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (2.13s) === RUN TestRcatMetadata run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" operations_test.go:1745: Skipping as destination doesn't support user metadata --- SKIP: TestRcatMetadata (0.37s) === RUN TestRcatSize run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" 2026/09/25 01:43:31 DEBUG : potato1: size = 60 OK 2026/09/25 01:43:31 DEBUG : potato1: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2026/09/25 01:43:31 DEBUG : B2 bucket rclone-test-hedekel9dubi: File to upload is small (60 bytes), uploading instead of streaming 2026/09/25 01:43:31 DEBUG : potato2: size = 60 OK 2026/09/25 01:43:31 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2026/09/25 01:43:31 DEBUG : potato2: Size and sha1 of src and dst objects identical --- PASS: TestRcatSize (1.76s) === RUN TestRcatSizeShortEOF run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" 2026/09/25 01:43:32 DEBUG : potato1: Clearing upload URL because of error: Post "https://pod-000-1180-09.backblaze.com/b2api/v1/b2_upload_file/0fd20de3303ddb6baf080e1b/c001_v0001180_t0050": net/http: HTTP/1.x transport connection broken: http: ContentLength=160 with Body length 100 2026/09/25 01:43:32 DEBUG : pacer: low level retry 1/1 (error Post "https://pod-000-1180-09.backblaze.com/b2api/v1/b2_upload_file/0fd20de3303ddb6baf080e1b/c001_v0001180_t0050": net/http: HTTP/1.x transport connection broken: http: ContentLength=160 with Body length 100) 2026/09/25 01:43:32 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/09/25 01:43:32 ERROR : potato1: Post request put error: Post "https://pod-000-1180-09.backblaze.com/b2api/v1/b2_upload_file/0fd20de3303ddb6baf080e1b/c001_v0001180_t0050": net/http: HTTP/1.x transport connection broken: http: ContentLength=160 with Body length 100 2026/09/25 01:43:32 DEBUG : pacer: Reducing sleep to 10ms --- PASS: TestRcatSizeShortEOF (0.39s) === RUN TestRcatSizeMetadata run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" operations_test.go:1838: Skipping as destination doesn't support user metadata --- SKIP: TestRcatSizeMetadata (0.37s) === RUN TestRcatSizeUploadHeaders run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" 2026/09/25 01:43:34 DEBUG : potato1: size = 60 OK 2026/09/25 01:43:34 DEBUG : potato1: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK --- PASS: TestRcatSizeUploadHeaders (1.36s) === RUN TestRcatSizeChecksum === RUN TestRcatSizeChecksum/Corrupted run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" 2026/09/25 01:43:35 DEBUG : potato1: size = 60 OK 2026/09/25 01:43:35 DEBUG : potato1: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d (B2 bucket rclone-test-hedekel9dubi) 2026/09/25 01:43:35 DEBUG : potato1: sha1 = 3639923d60907789b4155daea94152f89e4fb69c (B2 bucket rclone-test-hedekel9dubi) 2026/09/25 01:43:35 ERROR : potato1: corrupted on transfer: sha1 hashes differ src "9dc7f7d3279715991a22853f5981df582b7f9f6d" vs dst(B2 bucket rclone-test-hedekel9dubi) "3639923d60907789b4155daea94152f89e4fb69c" 2026/09/25 01:43:35 INFO : potato1: Removing failed copy === RUN TestRcatSizeChecksum/SizeDiffers run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" 2026/09/25 01:43:35 DEBUG : potato4: size = 60 (B2 bucket rclone-test-hedekel9dubi) 2026/09/25 01:43:35 DEBUG : potato4: size = 59 (B2 bucket rclone-test-hedekel9dubi) 2026/09/25 01:43:35 ERROR : potato4: corrupted on transfer: sizes differ src 60 vs dst(B2 bucket rclone-test-hedekel9dubi) 59 2026/09/25 01:43:35 INFO : potato4: Removing failed copy === RUN TestRcatSizeChecksum/IgnoreChecksum run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" 2026/09/25 01:43:36 DEBUG : potato2: size = 60 OK === RUN TestRcatSizeChecksum/NoHashes run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" 2026/09/25 01:43:37 DEBUG : potato3: size = 60 OK --- PASS: TestRcatSizeChecksum (3.33s) --- PASS: TestRcatSizeChecksum/Corrupted (0.92s) --- PASS: TestRcatSizeChecksum/SizeDiffers (0.93s) --- PASS: TestRcatSizeChecksum/IgnoreChecksum (0.74s) --- PASS: TestRcatSizeChecksum/NoHashes (0.74s) === RUN TestTouchDir run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" 2026/09/25 01:43:39 DEBUG : B2 bucket rclone-test-hedekel9dubi: Touching "sub dir/potato3" 2026/09/25 01:43:39 DEBUG : B2 bucket rclone-test-hedekel9dubi: Touching "empty space" 2026/09/25 01:43:39 DEBUG : B2 bucket rclone-test-hedekel9dubi: Touching "potato2" --- PASS: TestTouchDir (3.81s) === RUN TestMkdirMetadata run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" operations_test.go:2058: Skipping test as remote does not support MkdirMetadata --- SKIP: TestMkdirMetadata (0.37s) === RUN TestMkdirModTime run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" operations_test.go:2079: Skipping test as remote does not support DirSetModTime or MkdirMetadata --- SKIP: TestMkdirModTime (0.37s) === RUN TestCopyDirMetadata run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" operations_test.go:2123: Skipping test as remote does not support WriteDirMetadata or MkdirMetadata --- SKIP: TestCopyDirMetadata (0.37s) === RUN TestSetDirModTime run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" operations_test.go:2161: Skipping test as remote does not support DirSetModTime or WriteDirSetModTime --- SKIP: TestSetDirModTime (0.37s) === RUN TestDirsEqual run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" operations_test.go:2212: Skipping test as remote does not support WriteDirMetadata or MkdirMetadata --- SKIP: TestDirsEqual (0.37s) === RUN TestRemoveExisting run.go:198: Remote "B2 bucket rclone-test-hedekel9dubi", Local "Local file system at /tmp/rclone2832011420", Modify Window "1ms" operations_test.go:2281: Skipping as remote can't Move --- SKIP: TestRemoveExisting (0.37s) === RUN TestRcatInputFailurePreservesDestination 2026/09/25 01:43:44 DEBUG : Creating backend with remote "/tmp/TestRcatInputFailurePreservesDestination712414484/001" --- PASS: TestRcatInputFailurePreservesDestination (0.00s) === RUN TestRcAbout rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcAbout (0.00s) === RUN TestRcCleanup rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcCleanup (0.00s) === RUN TestRcCopyfile rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcCopyfile (0.00s) === RUN TestRcCopyurl rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcCopyurl (0.00s) === RUN TestRcDelete rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcDelete (0.00s) === RUN TestRcDeletefile rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcDeletefile (0.00s) === RUN TestRcList rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcList (0.00s) === RUN TestRcStat rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcStat (0.00s) === RUN TestRcSetTier rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcSetTier (0.00s) === RUN TestRcSetTierFile rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcSetTierFile (0.00s) === RUN TestRcMkdir rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcMkdir (0.00s) === RUN TestRcMovefile rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcMovefile (0.00s) === RUN TestRcPurge rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcPurge (0.00s) === RUN TestRcRmdir rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcRmdir (0.00s) === RUN TestRcRmdirs rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcRmdirs (0.00s) === RUN TestRcSize rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcSize (0.00s) === RUN TestRcPublicLink rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcPublicLink (0.00s) === RUN TestRcFsInfo rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcFsInfo (0.00s) === RUN TestUploadFile rc_test.go:31: Skipping test on non local remote --- SKIP: TestUploadFile (0.00s) === RUN TestRcCommand rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcCommand (0.00s) === RUN TestRcDu rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcDu (0.00s) === RUN TestRcCheck rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcCheck (0.00s) === RUN TestRcHashsum rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcHashsum (0.00s) === RUN TestRcHashsumSingleFile rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcHashsumSingleFile (0.00s) === RUN TestRcHashsumFile rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcHashsumFile (0.00s) === RUN TestRcGetFile rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcGetFile (0.00s) FAIL 2026/09/25 01:43:44 DEBUG : B2 bucket rclone-test-hedekel9dubi: Purge remote 2026/09/25 01:43:44 INFO : B2 bucket rclone-test-hedekel9dubi: cleaning bucket "rclone-test-hedekel9dubi" of all files 2026/09/25 01:43:44 DEBUG : A/file0: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f42399e9efabcd49d_d20260925_m014304_c001_v7007000_t0000_u01790300584866") 2026/09/25 01:43:44 DEBUG : A/file0: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29824d0_d20260925_m014300_c001_v0001180_t0050_u01790300580971") 2026/09/25 01:43:44 DEBUG : A/file1: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f42399e9efabcd49e_d20260925_m014305_c001_v7007000_t0000_u01790300585047") 2026/09/25 01:43:44 DEBUG : A/file1: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29824d2_d20260925_m014301_c001_v0001180_t0050_u01790300581154") 2026/09/25 01:43:44 DEBUG : A/file10: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f42399e9efabcd49f_d20260925_m014305_c001_v7007000_t0000_u01790300585228") 2026/09/25 01:43:44 DEBUG : A/file10: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29824e8_d20260925_m014302_c001_v0001180_t0050_u01790300582807") 2026/09/25 01:43:44 DEBUG : A/file11: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f42399e9efabcd4a0_d20260925_m014305_c001_v7007000_t0000_u01790300585409") 2026/09/25 01:43:44 DEBUG : A/file11: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29824ea_d20260925_m014303_c001_v0001180_t0050_u01790300583006") 2026/09/25 01:43:44 DEBUG : A/file12: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f42399e9efabcd4a1_d20260925_m014305_c001_v7007000_t0000_u01790300585590") 2026/09/25 01:43:45 DEBUG : A/file12: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29824ec_d20260925_m014303_c001_v0001180_t0050_u01790300583189") 2026/09/25 01:43:45 DEBUG : A/file13: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f42399e9efabcd4a3_d20260925_m014305_c001_v7007000_t0000_u01790300585771") 2026/09/25 01:43:45 DEBUG : A/file13: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29824ee_d20260925_m014303_c001_v0001180_t0050_u01790300583372") 2026/09/25 01:43:45 DEBUG : A/file14: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f42399e9efabcd4a4_d20260925_m014305_c001_v7007000_t0000_u01790300585952") 2026/09/25 01:43:45 DEBUG : A/file14: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29824f0_d20260925_m014303_c001_v0001180_t0050_u01790300583553") 2026/09/25 01:43:45 DEBUG : A/file15: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f42399e9efabcd4a5_d20260925_m014306_c001_v7007000_t0000_u01790300586133") 2026/09/25 01:43:45 DEBUG : A/file15: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29824f2_d20260925_m014303_c001_v0001180_t0050_u01790300583737") 2026/09/25 01:43:45 DEBUG : A/file16: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f42399e9efabcd4a6_d20260925_m014306_c001_v7007000_t0000_u01790300586314") 2026/09/25 01:43:45 DEBUG : A/file16: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29824f4_d20260925_m014303_c001_v0001180_t0050_u01790300583920") 2026/09/25 01:43:45 DEBUG : A/file17: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f42399e9efabcd4a7_d20260925_m014306_c001_v7007000_t0000_u01790300586495") 2026/09/25 01:43:45 DEBUG : A/file17: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29824f8_d20260925_m014304_c001_v0001180_t0050_u01790300584103") 2026/09/25 01:43:45 DEBUG : A/file18: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f42399e9efabcd4a8_d20260925_m014306_c001_v7007000_t0000_u01790300586675") 2026/09/25 01:43:45 DEBUG : A/file18: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29824fc_d20260925_m014304_c001_v0001180_t0050_u01790300584286") 2026/09/25 01:43:45 DEBUG : A/file19: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f42399e9efabcd4a9_d20260925_m014306_c001_v7007000_t0000_u01790300586856") 2026/09/25 01:43:45 DEBUG : A/file19: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29824fe_d20260925_m014304_c001_v0001180_t0050_u01790300584468") 2026/09/25 01:43:45 DEBUG : A/file2: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f42399e9efabcd4ab_d20260925_m014307_c001_v7007000_t0000_u01790300587037") 2026/09/25 01:43:45 DEBUG : A/file2: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29824d4_d20260925_m014301_c001_v0001180_t0050_u01790300581339") 2026/09/25 01:43:45 DEBUG : A/file3: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f42399e9efabcd4ac_d20260925_m014307_c001_v7007000_t0000_u01790300587217") 2026/09/25 01:43:46 DEBUG : A/file3: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29824d6_d20260925_m014301_c001_v0001180_t0050_u01790300581522") 2026/09/25 01:43:46 DEBUG : A/file4: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f42399e9efabcd4ad_d20260925_m014307_c001_v7007000_t0000_u01790300587398") 2026/09/25 01:43:46 DEBUG : A/file4: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29824d8_d20260925_m014301_c001_v0001180_t0050_u01790300581709") 2026/09/25 01:43:46 DEBUG : A/file5: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f42399e9efabcd4ae_d20260925_m014307_c001_v7007000_t0000_u01790300587579") 2026/09/25 01:43:46 DEBUG : A/file5: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29824da_d20260925_m014301_c001_v0001180_t0050_u01790300581892") 2026/09/25 01:43:46 DEBUG : A/file6: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f42399e9efabcd4af_d20260925_m014307_c001_v7007000_t0000_u01790300587761") 2026/09/25 01:43:46 DEBUG : A/file6: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29824de_d20260925_m014302_c001_v0001180_t0050_u01790300582075") 2026/09/25 01:43:46 DEBUG : A/file7: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f42399e9efabcd4b0_d20260925_m014307_c001_v7007000_t0000_u01790300587942") 2026/09/25 01:43:46 DEBUG : A/file7: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29824e0_d20260925_m014302_c001_v0001180_t0050_u01790300582258") 2026/09/25 01:43:46 DEBUG : A/file8: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f42399e9efabcd4b1_d20260925_m014308_c001_v7007000_t0000_u01790300588143") 2026/09/25 01:43:46 DEBUG : A/file8: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29824e2_d20260925_m014302_c001_v0001180_t0050_u01790300582441") 2026/09/25 01:43:46 DEBUG : A/file9: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f42399e9efabcd4b2_d20260925_m014308_c001_v7007000_t0000_u01790300588323") 2026/09/25 01:43:46 DEBUG : A/file9: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29824e6_d20260925_m014302_c001_v0001180_t0050_u01790300582624") 2026/09/25 01:43:46 DEBUG : A/one: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f42399e9efabcd4b4_d20260925_m014309_c001_v7007000_t0000_u01790300589238") 2026/09/25 01:43:46 DEBUG : A/one: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e2982512_d20260925_m014308_c001_v0001180_t0050_u01790300588691") 2026/09/25 01:43:46 DEBUG : A/two: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f42399e9efabcd4b5_d20260925_m014309_c001_v7007000_t0000_u01790300589419") 2026/09/25 01:43:46 DEBUG : A/two: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e2982516_d20260925_m014308_c001_v0001180_t0050_u01790300588873") 2026/09/25 01:43:46 DEBUG : A1/B1/C1/four: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4014a7c970bf67f1_d20260925_m014312_c001_v7007000_t0000_u01790300592194") 2026/09/25 01:43:46 DEBUG : A1/B1/C1/four: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e2982526_d20260925_m014310_c001_v0001180_t0050_u01790300590336") 2026/09/25 01:43:46 DEBUG : A1/B1/C1/one: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4209e18e512fe4e3_d20260925_m014237_c001_v7007000_t0000_u01790300557876") 2026/09/25 01:43:46 DEBUG : A1/B1/C1/one: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e298245e_d20260925_m014235_c001_v0001180_t0050_u01790300555491") 2026/09/25 01:43:47 DEBUG : A1/B1/C2/five: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4090658e34e45261_d20260925_m014312_c001_v7007000_t0000_u01790300592222") 2026/09/25 01:43:47 DEBUG : A1/B1/C2/five: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e298252a_d20260925_m014310_c001_v0001180_t0050_u01790300590520") 2026/09/25 01:43:47 DEBUG : A1/B1/three: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f42399e9efabcd4bc_d20260925_m014317_c001_v7007000_t0000_u01790300597036") 2026/09/25 01:43:47 DEBUG : A1/B1/three: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e2982524_d20260925_m014310_c001_v0001180_t0050_u01790300590153") 2026/09/25 01:43:47 DEBUG : A1/one: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4209e18e512fe4f9_d20260925_m014312_c001_v7007000_t0000_u01790300592211") 2026/09/25 01:43:47 DEBUG : A1/one: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e2982520_d20260925_m014309_c001_v0001180_t0050_u01790300589788") 2026/09/25 01:43:47 DEBUG : A1/two: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4014a7c970bf67f2_d20260925_m014312_c001_v7007000_t0000_u01790300592246") 2026/09/25 01:43:47 DEBUG : A1/two: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e2982522_d20260925_m014309_c001_v0001180_t0050_u01790300589970") 2026/09/25 01:43:47 DEBUG : A1/two: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4209e18e512fe4e4_d20260925_m014238_c001_v7007000_t0000_u01790300558254") 2026/09/25 01:43:47 DEBUG : A1/two: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e2982460_d20260925_m014235_c001_v0001180_t0050_u01790300555674") 2026/09/25 01:43:47 DEBUG : A2/B1/C1/four: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4160b8fd5dfebab9_d20260925_m014318_c001_v7007000_t0000_u01790300598625") 2026/09/25 01:43:47 DEBUG : A2/B1/C1/four: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4120879cbfe610f1_d20260925_m014311_c001_v0001177_t0021_u01790300591645") 2026/09/25 01:43:47 DEBUG : A2/B1/C2/five: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f415a7f71ed219a57_d20260925_m014318_c001_v7007000_t0000_u01790300598616") 2026/09/25 01:43:47 DEBUG : A2/B1/C2/five: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4150280fc33c4f78_d20260925_m014312_c001_v0001181_t0037_u01790300592033") 2026/09/25 01:43:47 DEBUG : A2/B1/three: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4014a7c970bf67f4_d20260925_m014318_c001_v7007000_t0000_u01790300598656") 2026/09/25 01:43:47 DEBUG : A2/B1/three: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4076ec6c9500803b_d20260925_m014316_c001_v0001144_t0053_u01790300596848") 2026/09/25 01:43:47 DEBUG : A2/one: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f424aee5d7f0148f2_d20260925_m014318_c001_v7007000_t0000_u01790300598678") 2026/09/25 01:43:47 DEBUG : A2/one: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f418a81623fb2ea37_d20260925_m014312_c001_v0001185_t0039_u01790300592023") 2026/09/25 01:43:47 DEBUG : A2/two: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f42399e9efabcd4be_d20260925_m014318_c001_v7007000_t0000_u01790300598750") 2026/09/25 01:43:47 DEBUG : A2/two: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4177b77345d0542f_d20260925_m014312_c001_v0001165_t0036_u01790300592059") 2026/09/25 01:43:47 DEBUG : A3/B1/C1/four: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f42399e9efabcd4bf_d20260925_m014319_c001_v7007000_t0000_u01790300599698") 2026/09/25 01:43:47 DEBUG : A3/B1/C1/four: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f403502a3b3163567_d20260925_m014318_c001_v0001177_t0038_u01790300598238") 2026/09/25 01:43:47 DEBUG : A3/B1/C2/five: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4014a7c970bf67f6_d20260925_m014319_c001_v7007000_t0000_u01790300599735") 2026/09/25 01:43:47 DEBUG : A3/B1/C2/five: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4021628f5d50b80e_d20260925_m014318_c001_v0001092_t0054_u01790300598160") 2026/09/25 01:43:48 DEBUG : A3/B1/three: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f424aee5d7f0148f3_d20260925_m014319_c001_v7007000_t0000_u01790300599723") 2026/09/25 01:43:48 DEBUG : A3/B1/three: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4101c5e08b4319d7_d20260925_m014318_c001_v0001171_t0022_u01790300598367") 2026/09/25 01:43:48 DEBUG : A3/one: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4160b8fd5dfebaba_d20260925_m014319_c001_v7007000_t0000_u01790300599746") 2026/09/25 01:43:48 DEBUG : A3/one: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f412f138c1effd8e1_d20260925_m014318_c001_v0001173_t0026_u01790300598438") 2026/09/25 01:43:48 DEBUG : A3/two: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4005c7cd230e1879_d20260925_m014319_c001_v7007000_t0000_u01790300599758") 2026/09/25 01:43:48 DEBUG : A3/two: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f402f5bda634d7331_d20260925_m014318_c001_v0001178_t0044_u01790300598564") 2026/09/25 01:43:48 DEBUG : A4/B1/C1/four: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4005c7cd230e187c_d20260925_m014320_c001_v7007000_t0000_u01790300600327") 2026/09/25 01:43:48 DEBUG : A4/B1/C1/four: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4133e9fb7781b5d4_d20260925_m014319_c001_v0001102_t0016_u01790300599515") 2026/09/25 01:43:48 DEBUG : A4/B1/C2/five: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4005c7cd230e187e_d20260925_m014320_c001_v7007000_t0000_u01790300600509") 2026/09/25 01:43:48 DEBUG : A4/B1/C2/five: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4037d078753da9e5_d20260925_m014319_c001_v0001037_t0029_u01790300599541") 2026/09/25 01:43:48 DEBUG : A4/B1/three: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4005c7cd230e187f_d20260925_m014320_c001_v7007000_t0000_u01790300600691") 2026/09/25 01:43:48 DEBUG : A4/B1/three: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4008c1b00b13f3eb_d20260925_m014319_c001_v0001179_t0033_u01790300599538") 2026/09/25 01:43:48 DEBUG : A4/one: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4005c7cd230e1881_d20260925_m014320_c001_v7007000_t0000_u01790300600873") 2026/09/25 01:43:48 DEBUG : A4/one: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f416dd73a5ad6b418_d20260925_m014319_c001_v0001037_t0055_u01790300599551") 2026/09/25 01:43:48 DEBUG : A4/two: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4005c7cd230e1882_d20260925_m014321_c001_v7007000_t0000_u01790300601055") 2026/09/25 01:43:48 DEBUG : A4/two: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f41669662c8cdfaca_d20260925_m014319_c001_v0001098_t0042_u01790300599557") 2026/09/25 01:43:48 DEBUG : BackupDir/one: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40613a6532539554_d20260925_m014121_c001_v7007000_t0000_u01790300481723") 2026/09/25 01:43:48 DEBUG : BackupDir/one: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4160cdcf34edf518_d20260925_m014117_c001_v0001044_t0009_u01790300477158") 2026/09/25 01:43:48 DEBUG : CompareDest/one: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f422bda88a26886f9_d20260925_m014019_c001_v7007000_t0000_u01790300419471") 2026/09/25 01:43:48 DEBUG : CompareDest/one: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29821ce_d20260925_m014016_c001_v0001180_t0050_u01790300416038") 2026/09/25 01:43:49 DEBUG : CompareDest/two: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f422bda88a26886fa_d20260925_m014019_c001_v7007000_t0000_u01790300419651") 2026/09/25 01:43:49 DEBUG : CompareDest/two: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29821d0_d20260925_m014016_c001_v0001180_t0050_u01790300416934") 2026/09/25 01:43:49 DEBUG : CopyDest/one: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40613a6532539555_d20260925_m014121_c001_v7007000_t0000_u01790300481908") 2026/09/25 01:43:49 DEBUG : CopyDest/one: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29822a4_d20260925_m014113_c001_v0001180_t0050_u01790300473967") 2026/09/25 01:43:49 DEBUG : CopyDest/one: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f422bda88a2688709_d20260925_m014023_c001_v7007000_t0000_u01790300423937") 2026/09/25 01:43:49 DEBUG : CopyDest/one: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29821e8_d20260925_m014021_c001_v0001180_t0050_u01790300421646") 2026/09/25 01:43:49 DEBUG : CopyDest/three: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40613a6532539556_d20260925_m014122_c001_v7007000_t0000_u01790300482090") 2026/09/25 01:43:49 DEBUG : CopyDest/three: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29822c2_d20260925_m014119_c001_v0001180_t0050_u01790300479890") 2026/09/25 01:43:49 DEBUG : CopyDest/two: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40613a6532539557_d20260925_m014122_c001_v7007000_t0000_u01790300482272") 2026/09/25 01:43:49 DEBUG : CopyDest/two: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29822b4_d20260925_m014118_c001_v0001180_t0050_u01790300478416") 2026/09/25 01:43:49 DEBUG : ReadFile: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4209e18e512fe4d6_d20260925_m014225_c001_v7007000_t0000_u01790300545879") 2026/09/25 01:43:49 DEBUG : ReadFile: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e2982434_d20260925_m014224_c001_v0001180_t0050_u01790300544603") 2026/09/25 01:43:49 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40613a653253955d_d20260925_m014127_c001_v7007000_t0000_u01790300487137") 2026/09/25 01:43:49 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f10940491e29822d0_d20260925_m014124_c001_v0001180_t0029_u01790300484810") 2026/09/25 01:43:49 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40613a653253955e_d20260925_m014127_c001_v7007000_t0000_u01790300487318") 2026/09/25 01:43:49 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f10940491e29822e4_d20260925_m014126_c001_v0001180_t0049_u01790300486539") 2026/09/25 01:43:49 DEBUG : a.txt: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40613a653253956f_d20260925_m014143_c001_v7007000_t0000_u01790300503507") 2026/09/25 01:43:49 DEBUG : a.txt: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e2982330_d20260925_m014139_c001_v0001180_t0050_u01790300499896") 2026/09/25 01:43:49 DEBUG : a.txt: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40613a6532539567_d20260925_m014138_c001_v7007000_t0000_u01790300498436") 2026/09/25 01:43:49 DEBUG : a.txt: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e2982316_d20260925_m014134_c001_v0001180_t0050_u01790300494429") 2026/09/25 01:43:49 DEBUG : also/one: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40613a6532539562_d20260925_m014131_c001_v7007000_t0000_u01790300491016") 2026/09/25 01:43:49 DEBUG : also/one: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f10940491e29822fa_d20260925_m014129_c001_v0001180_t0054_u01790300489823") 2026/09/25 01:43:49 DEBUG : another: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40613a6532539563_d20260925_m014131_c001_v7007000_t0000_u01790300491564") 2026/09/25 01:43:49 DEBUG : another: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f10940491e2982300_d20260925_m014130_c001_v0001180_t0026_u01790300490052") 2026/09/25 01:43:50 DEBUG : backup/dst/file1: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f42399e9efabcd499_d20260925_m014300_c001_v7007000_t0000_u01790300580333") 2026/09/25 01:43:50 DEBUG : backup/dst/file1: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4005369381b63e4f_d20260925_m014258_c001_v0001145_t0030_u01790300578572") 2026/09/25 01:43:50 DEBUG : backup/dst/file1: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f422bda88a26886f0_d20260925_m014009_c001_v7007000_t0000_u01790300409349") 2026/09/25 01:43:50 DEBUG : backup/dst/file1: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f404f6c2ba3601f45_d20260925_m014008_c001_v0001172_t0058_u01790300408442") 2026/09/25 01:43:50 DEBUG : data/banana: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4124e2d955658a7c_d20260925_m013916_c001_v7007000_t0000_u01790300356097") 2026/09/25 01:43:50 DEBUG : data/banana: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e2982118_d20260925_m013914_c001_v0001180_t0050_u01790300354329") 2026/09/25 01:43:50 DEBUG : data/banana: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29820f4_d20260925_m013906_c001_v0001180_t0050_u01790300346289") 2026/09/25 01:43:50 DEBUG : data/potato: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4124e2d955658a7d_d20260925_m013916_c001_v7007000_t0000_u01790300356278") 2026/09/25 01:43:50 DEBUG : data/potato: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e298211a_d20260925_m013914_c001_v0001180_t0050_u01790300354512") 2026/09/25 01:43:50 DEBUG : data/potato: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29820fa_d20260925_m013908_c001_v0001180_t0050_u01790300348157") 2026/09/25 01:43:50 DEBUG : dst/file1: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f42399e9efabcd49a_d20260925_m014300_c001_v7007000_t0000_u01790300580603") 2026/09/25 01:43:50 DEBUG : dst/file1: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29824c6_d20260925_m014259_c001_v0001180_t0050_u01790300579660") 2026/09/25 01:43:50 DEBUG : dst/file1: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f42399e9efabcd497_d20260925_m014259_c001_v7007000_t0000_u01790300579117") 2026/09/25 01:43:50 DEBUG : dst/file1: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29824a4_d20260925_m014250_c001_v0001180_t0050_u01790300570545") 2026/09/25 01:43:50 DEBUG : dst/file1: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f422bda88a26886f1_d20260925_m014009_c001_v7007000_t0000_u01790300409528") 2026/09/25 01:43:50 DEBUG : dst/file1: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29821b6_d20260925_m014008_c001_v0001180_t0050_u01790300408812") 2026/09/25 01:43:50 DEBUG : dst/file1: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f422bda88a26886ee_d20260925_m014008_c001_v7007000_t0000_u01790300408630") 2026/09/25 01:43:50 DEBUG : dst/file1: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29821aa_d20260925_m014005_c001_v0001180_t0050_u01790300405412") 2026/09/25 01:43:50 DEBUG : dst/one: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40613a6532539558_d20260925_m014122_c001_v7007000_t0000_u01790300482454") 2026/09/25 01:43:50 DEBUG : dst/one: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f41299d2bf3374ec6_d20260925_m014118_c001_v0001036_t0033_u01790300478051") 2026/09/25 01:43:50 DEBUG : dst/one: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40707b4664cb56e0_d20260925_m014117_c001_v7007000_t0000_u01790300477709") 2026/09/25 01:43:50 DEBUG : dst/one: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29822a2_d20260925_m014113_c001_v0001180_t0050_u01790300473783") 2026/09/25 01:43:51 DEBUG : dst/one: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4033666c31b3a06f_d20260925_m014113_c001_v0001144_t0025_u01790300473057") 2026/09/25 01:43:51 DEBUG : dst/one: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4033666c31b3a063_d20260925_m014111_c001_v0001144_t0025_u01790300471417") 2026/09/25 01:43:51 DEBUG : dst/one: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f422bda88a268870b_d20260925_m014024_c001_v7007000_t0000_u01790300424117") 2026/09/25 01:43:51 DEBUG : dst/one: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29821e6_d20260925_m014021_c001_v0001180_t0050_u01790300421463") 2026/09/25 01:43:51 DEBUG : dst/one: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f422bda88a26886fd_d20260925_m014019_c001_v7007000_t0000_u01790300419830") 2026/09/25 01:43:51 DEBUG : dst/one: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29821cc_d20260925_m014015_c001_v0001180_t0050_u01790300415854") 2026/09/25 01:43:51 DEBUG : dst/one: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f408263a5bccf3aaf_d20260925_m014015_c001_v0001098_t0055_u01790300415124") 2026/09/25 01:43:51 DEBUG : dst/one: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f408263a5bccf3aad_d20260925_m014014_c001_v0001098_t0055_u01790300414226") 2026/09/25 01:43:51 DEBUG : dst/three: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40613a6532539559_d20260925_m014122_c001_v7007000_t0000_u01790300482635") 2026/09/25 01:43:51 DEBUG : dst/three: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4033666c31b3a07b_d20260925_m014121_c001_v0001144_t0025_u01790300481096") 2026/09/25 01:43:51 DEBUG : dst/two: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40613a653253955a_d20260925_m014122_c001_v7007000_t0000_u01790300482817") 2026/09/25 01:43:51 DEBUG : dst/two: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f410e5a077e17d12b_d20260925_m014119_c001_v0001184_t0034_u01790300479155") 2026/09/25 01:43:51 DEBUG : dst/two: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f422bda88a26886fe_d20260925_m014020_c001_v7007000_t0000_u01790300420010") 2026/09/25 01:43:51 DEBUG : dst/two: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f408263a5bccf3abf_d20260925_m014018_c001_v0001098_t0055_u01790300418931") 2026/09/25 01:43:51 DEBUG : empty space: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4124e2d955658b54_d20260925_m014341_c001_v7007000_t0000_u01790300621270") 2026/09/25 01:43:51 DEBUG : empty space: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4002d1a7627a90d5_d20260925_m014340_c001_v0001177_t0035_u01790300620351") 2026/09/25 01:43:51 DEBUG : empty space: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f405b75da4c4a79a4_d20260925_m014338_c001_v0001036_t0001_u01790300618848") 2026/09/25 01:43:51 DEBUG : empty space: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4198356c06fe8992_d20260925_m014205_c001_v7007000_t0000_u01790300525414") 2026/09/25 01:43:51 DEBUG : empty space: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29823aa_d20260925_m014204_c001_v0001180_t0050_u01790300524505") 2026/09/25 01:43:51 DEBUG : empty space: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4198356c06fe898f_d20260925_m014203_c001_v7007000_t0000_u01790300523727") 2026/09/25 01:43:51 DEBUG : empty space: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29823a2_d20260925_m014202_c001_v0001180_t0050_u01790300522631") 2026/09/25 01:43:51 DEBUG : empty space: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4198356c06fe898b_d20260925_m014201_c001_v7007000_t0000_u01790300521797") 2026/09/25 01:43:52 DEBUG : empty space: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e2982396_d20260925_m014201_c001_v0001180_t0050_u01790300521063") 2026/09/25 01:43:52 DEBUG : empty space: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4198356c06fe8989_d20260925_m014200_c001_v7007000_t0000_u01790300520176") 2026/09/25 01:43:52 DEBUG : empty space: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e2982388_d20260925_m014158_c001_v0001180_t0050_u01790300518906") 2026/09/25 01:43:52 DEBUG : empty space: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4198356c06fe8986_d20260925_m014158_c001_v7007000_t0000_u01790300518139") 2026/09/25 01:43:52 DEBUG : empty space: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e298237e_d20260925_m014157_c001_v0001180_t0050_u01790300517419") 2026/09/25 01:43:52 DEBUG : empty space: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4124e2d955658677_d20260925_m013900_c001_v7007000_t0000_u01790300340581") 2026/09/25 01:43:52 DEBUG : empty space: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29820d6_d20260925_m013858_c001_v0001180_t0050_u01790300338297") 2026/09/25 01:43:52 DEBUG : empty space: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4124e2d95565866d_d20260925_m013856_c001_v7007000_t0000_u01790300336569") 2026/09/25 01:43:52 DEBUG : empty space: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29820b4_d20260925_m013852_c001_v0001180_t0050_u01790300332869") 2026/09/25 01:43:52 DEBUG : empty space: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4124e2d955658666_d20260925_m013850_c001_v7007000_t0000_u01790300330866") 2026/09/25 01:43:52 DEBUG : empty space: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e298209d_d20260925_m013848_c001_v0001180_t0050_u01790300328559") 2026/09/25 01:43:52 DEBUG : existing: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4131dcfe617225bd_d20260925_m014249_c001_v7007000_t0000_u01790300569411") 2026/09/25 01:43:52 DEBUG : existing: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e2982498_d20260925_m014248_c001_v0001180_t0050_u01790300568496") 2026/09/25 01:43:52 DEBUG : existing: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f422bda88a26886ec_d20260925_m014004_c001_v7007000_t0000_u01790300404697") 2026/09/25 01:43:52 DEBUG : existing: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29821a4_d20260925_m014003_c001_v0001180_t0050_u01790300403265") 2026/09/25 01:43:52 DEBUG : file0: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4198356c06fe899a_d20260925_m014212_c001_v7007000_t0000_u01790300532819") 2026/09/25 01:43:52 DEBUG : file0: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29823d4_d20260925_m014208_c001_v0001180_t0050_u01790300528320") 2026/09/25 01:43:52 DEBUG : file1: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4131dcfe617221d4_d20260925_m014248_c001_v7007000_t0000_u01790300568130") 2026/09/25 01:43:52 DEBUG : file1: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e298248e_d20260925_m014247_c001_v0001180_t0050_u01790300567217") 2026/09/25 01:43:52 DEBUG : file1: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4131dcfe617221d0_d20260925_m014245_c001_v7007000_t0000_u01790300565028") 2026/09/25 01:43:52 DEBUG : file1: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e2982488_d20260925_m014244_c001_v0001180_t0050_u01790300564662") 2026/09/25 01:43:53 DEBUG : file1: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4131dcfe617221c9_d20260925_m014243_c001_v7007000_t0000_u01790300563748") 2026/09/25 01:43:53 DEBUG : file1: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e2982476_d20260925_m014242_c001_v0001180_t0050_u01790300562112") 2026/09/25 01:43:53 DEBUG : file1: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4209e18e512fe4dc_d20260925_m014230_c001_v7007000_t0000_u01790300550130") 2026/09/25 01:43:53 DEBUG : file1: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e298243a_d20260925_m014226_c001_v0001180_t0050_u01790300546654") 2026/09/25 01:43:53 DEBUG : file1: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4198356c06fe899c_d20260925_m014212_c001_v7007000_t0000_u01790300532999") 2026/09/25 01:43:53 DEBUG : file1: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29823d6_d20260925_m014208_c001_v0001180_t0050_u01790300528530") 2026/09/25 01:43:53 DEBUG : file1: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4198356c06fe8982_d20260925_m014154_c001_v7007000_t0000_u01790300514578") 2026/09/25 01:43:53 DEBUG : file1: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e2982366_d20260925_m014148_c001_v0001180_t0050_u01790300508539") 2026/09/25 01:43:53 DEBUG : file1: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4198356c06fe897b_d20260925_m014147_c001_v7007000_t0000_u01790300507997") 2026/09/25 01:43:53 DEBUG : file1: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e298234e_d20260925_m014145_c001_v0001180_t0050_u01790300505101") 2026/09/25 01:43:53 DEBUG : file10: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4198356c06fe899d_d20260925_m014213_c001_v7007000_t0000_u01790300533179") 2026/09/25 01:43:53 DEBUG : file10: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29823ea_d20260925_m014210_c001_v0001180_t0050_u01790300530267") 2026/09/25 01:43:53 DEBUG : file11: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4198356c06fe899e_d20260925_m014213_c001_v7007000_t0000_u01790300533358") 2026/09/25 01:43:53 DEBUG : file11: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29823ee_d20260925_m014210_c001_v0001180_t0050_u01790300530529") 2026/09/25 01:43:53 DEBUG : file12: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4198356c06fe89a0_d20260925_m014213_c001_v7007000_t0000_u01790300533538") 2026/09/25 01:43:53 DEBUG : file12: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29823f2_d20260925_m014210_c001_v0001180_t0050_u01790300530747") 2026/09/25 01:43:53 DEBUG : file13: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4198356c06fe89a1_d20260925_m014213_c001_v7007000_t0000_u01790300533717") 2026/09/25 01:43:53 DEBUG : file13: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29823f6_d20260925_m014210_c001_v0001180_t0050_u01790300530957") 2026/09/25 01:43:53 DEBUG : file14: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4198356c06fe89a2_d20260925_m014213_c001_v7007000_t0000_u01790300533897") 2026/09/25 01:43:53 DEBUG : file14: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29823f8_d20260925_m014211_c001_v0001180_t0050_u01790300531183") 2026/09/25 01:43:53 DEBUG : file15: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4198356c06fe89a3_d20260925_m014214_c001_v7007000_t0000_u01790300534076") 2026/09/25 01:43:53 DEBUG : file15: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29823fa_d20260925_m014211_c001_v0001180_t0050_u01790300531380") 2026/09/25 01:43:53 DEBUG : file16: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4198356c06fe89a4_d20260925_m014214_c001_v7007000_t0000_u01790300534256") 2026/09/25 01:43:54 DEBUG : file16: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29823fc_d20260925_m014211_c001_v0001180_t0050_u01790300531565") 2026/09/25 01:43:54 DEBUG : file17: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4198356c06fe89a5_d20260925_m014214_c001_v7007000_t0000_u01790300534436") 2026/09/25 01:43:54 DEBUG : file17: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29823fe_d20260925_m014211_c001_v0001180_t0050_u01790300531749") 2026/09/25 01:43:54 DEBUG : file18: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4198356c06fe89a6_d20260925_m014214_c001_v7007000_t0000_u01790300534616") 2026/09/25 01:43:54 DEBUG : file18: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e2982400_d20260925_m014211_c001_v0001180_t0050_u01790300531933") 2026/09/25 01:43:54 DEBUG : file19: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4198356c06fe89a7_d20260925_m014214_c001_v7007000_t0000_u01790300534796") 2026/09/25 01:43:54 DEBUG : file19: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e2982404_d20260925_m014212_c001_v0001180_t0050_u01790300532116") 2026/09/25 01:43:54 DEBUG : file2: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4131dcfe617221cc_d20260925_m014243_c001_v7007000_t0000_u01790300563933") 2026/09/25 01:43:54 DEBUG : file2: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e2982480_d20260925_m014243_c001_v0001180_t0050_u01790300563115") 2026/09/25 01:43:54 DEBUG : file2: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4209e18e512fe4dd_d20260925_m014230_c001_v7007000_t0000_u01790300550310") 2026/09/25 01:43:54 DEBUG : file2: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e298243e_d20260925_m014226_c001_v0001180_t0050_u01790300546837") 2026/09/25 01:43:54 DEBUG : file2: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4198356c06fe89a8_d20260925_m014214_c001_v7007000_t0000_u01790300534976") 2026/09/25 01:43:54 DEBUG : file2: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29823d8_d20260925_m014208_c001_v0001180_t0050_u01790300528713") 2026/09/25 01:43:54 DEBUG : file3: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4198356c06fe89a9_d20260925_m014215_c001_v7007000_t0000_u01790300535156") 2026/09/25 01:43:54 DEBUG : file3: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29823da_d20260925_m014208_c001_v0001180_t0050_u01790300528897") 2026/09/25 01:43:54 DEBUG : file4: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4198356c06fe89aa_d20260925_m014215_c001_v7007000_t0000_u01790300535335") 2026/09/25 01:43:54 DEBUG : file4: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29823dc_d20260925_m014209_c001_v0001180_t0050_u01790300529081") 2026/09/25 01:43:54 DEBUG : file5: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4198356c06fe89ab_d20260925_m014215_c001_v7007000_t0000_u01790300535515") 2026/09/25 01:43:54 DEBUG : file5: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29823e0_d20260925_m014209_c001_v0001180_t0050_u01790300529264") 2026/09/25 01:43:54 DEBUG : file6: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4198356c06fe89ac_d20260925_m014215_c001_v7007000_t0000_u01790300535694") 2026/09/25 01:43:54 DEBUG : file6: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29823e2_d20260925_m014209_c001_v0001180_t0050_u01790300529513") 2026/09/25 01:43:55 DEBUG : file7: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4198356c06fe89ad_d20260925_m014215_c001_v7007000_t0000_u01790300535874") 2026/09/25 01:43:55 DEBUG : file7: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29823e4_d20260925_m014209_c001_v0001180_t0050_u01790300529696") 2026/09/25 01:43:55 DEBUG : file8: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4198356c06fe89ae_d20260925_m014216_c001_v7007000_t0000_u01790300536054") 2026/09/25 01:43:55 DEBUG : file8: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29823e6_d20260925_m014209_c001_v0001180_t0050_u01790300529879") 2026/09/25 01:43:55 DEBUG : file9: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4209e18e512fe4c9_d20260925_m014216_c001_v7007000_t0000_u01790300536235") 2026/09/25 01:43:55 DEBUG : file9: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29823e8_d20260925_m014210_c001_v0001180_t0050_u01790300530064") 2026/09/25 01:43:55 DEBUG : filename.txt: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4131dcfe617221ce_d20260925_m014244_c001_v7007000_t0000_u01790300564113") 2026/09/25 01:43:55 DEBUG : filename.txt: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e298247a_d20260925_m014242_c001_v0001180_t0050_u01790300562738") 2026/09/25 01:43:55 DEBUG : headerfilename.txt: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4131dcfe617221cf_d20260925_m014244_c001_v7007000_t0000_u01790300564295") 2026/09/25 01:43:55 DEBUG : headerfilename.txt: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e298247e_d20260925_m014242_c001_v0001180_t0050_u01790300562925") 2026/09/25 01:43:55 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4005c7cd230e18a5_d20260925_m014330_c001_v7007000_t0000_u01790300610141") 2026/09/25 01:43:55 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f10940491e2982574_d20260925_m014328_c001_v0001180_t0014_u01790300608992") 2026/09/25 01:43:55 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4005c7cd230e189b_d20260925_m014328_c001_v7007000_t0000_u01790300608017") 2026/09/25 01:43:55 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f10940491e2982566_d20260925_m014326_c001_v0001180_t0058_u01790300606779") 2026/09/25 01:43:55 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4005c7cd230e18a6_d20260925_m014330_c001_v7007000_t0000_u01790300610324") 2026/09/25 01:43:55 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f10940491e2982572_d20260925_m014328_c001_v0001180_t0026_u01790300608755") 2026/09/25 01:43:55 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4005c7cd230e189c_d20260925_m014328_c001_v7007000_t0000_u01790300608199") 2026/09/25 01:43:55 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f10940491e2982564_d20260925_m014326_c001_v0001180_t0023_u01790300606567") 2026/09/25 01:43:55 DEBUG : large: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f417f47192d61e10b_d20260925_m014223_c001_v7007000_t0000_u01790300543309") 2026/09/25 01:43:55 DEBUG : large: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f10940491e298242e_d20260925_m014222_c001_v0001180_t0036_u01790300542711") 2026/09/25 01:43:55 DEBUG : large: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f417f47192d61e109_d20260925_m014221_c001_v7007000_t0000_u01790300541562") 2026/09/25 01:43:55 DEBUG : large: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f10940491e2982424_d20260925_m014220_c001_v0001180_t0039_u01790300540312") 2026/09/25 01:43:56 DEBUG : large: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4209e18e512fe4cd_d20260925_m014218_c001_v7007000_t0000_u01790300538315") 2026/09/25 01:43:56 DEBUG : large: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f10940491e2982418_d20260925_m014217_c001_v0001180_t0030_u01790300537011") 2026/09/25 01:43:56 DEBUG : large: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4198356c06fe8998_d20260925_m014207_c001_v7007000_t0000_u01790300527944") 2026/09/25 01:43:56 DEBUG : large: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f10940491e29823c6_d20260925_m014206_c001_v0001180_t0025_u01790300526584") 2026/09/25 01:43:56 DEBUG : medium: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4209e18e512fe4d3_d20260925_m014223_c001_v7007000_t0000_u01790300543326") 2026/09/25 01:43:56 DEBUG : medium: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f10940491e298242c_d20260925_m014222_c001_v0001180_t0054_u01790300542483") 2026/09/25 01:43:56 DEBUG : medium: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4209e18e512fe4d0_d20260925_m014220_c001_v7007000_t0000_u01790300540996") 2026/09/25 01:43:56 DEBUG : medium: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f10940491e2982420_d20260925_m014219_c001_v0001180_t0006_u01790300539230") 2026/09/25 01:43:56 DEBUG : medium: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4209e18e512fe4cb_d20260925_m014217_c001_v7007000_t0000_u01790300537767") 2026/09/25 01:43:56 DEBUG : medium: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f10940491e2982416_d20260925_m014216_c001_v0001180_t0056_u01790300536786") 2026/09/25 01:43:56 DEBUG : medium: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4198356c06fe8996_d20260925_m014207_c001_v7007000_t0000_u01790300527203") 2026/09/25 01:43:56 DEBUG : medium: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f10940491e29823c4_d20260925_m014206_c001_v0001180_t0012_u01790300526355") 2026/09/25 01:43:56 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4005c7cd230e188a_d20260925_m014323_c001_v7007000_t0000_u01790300603716") 2026/09/25 01:43:56 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f10940491e298254a_d20260925_m014322_c001_v0001180_t0026_u01790300602598") 2026/09/25 01:43:56 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4005c7cd230e188b_d20260925_m014323_c001_v7007000_t0000_u01790300603898") 2026/09/25 01:43:56 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f10940491e2982548_d20260925_m014322_c001_v0001180_t0025_u01790300602350") 2026/09/25 01:43:56 DEBUG : not-one: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40613a6532539564_d20260925_m014131_c001_v7007000_t0000_u01790300491745") 2026/09/25 01:43:56 DEBUG : not-one: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e2982306_d20260925_m014130_c001_v0001180_t0050_u01790300490290") 2026/09/25 01:43:56 DEBUG : one: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40613a6532539561_d20260925_m014130_c001_v7007000_t0000_u01790300490833") 2026/09/25 01:43:56 DEBUG : one: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f10940491e29822f4_d20260925_m014129_c001_v0001180_t0022_u01790300489591") 2026/09/25 01:43:56 DEBUG : potato1: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4005c7cd230e18b4_d20260925_m014335_c001_v7007000_t0000_u01790300615131") 2026/09/25 01:43:56 DEBUG : potato1: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f405b75da4c4a7992_d20260925_m014334_c001_v0001036_t0001_u01790300614949") 2026/09/25 01:43:57 DEBUG : potato1: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4005c7cd230e18b3_d20260925_m014334_c001_v7007000_t0000_u01790300614577") 2026/09/25 01:43:57 DEBUG : potato1: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f405b75da4c4a798a_d20260925_m014334_c001_v0001036_t0001_u01790300614132") 2026/09/25 01:43:57 DEBUG : potato1: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4005c7cd230e18ad_d20260925_m014332_c001_v7007000_t0000_u01790300612276") 2026/09/25 01:43:57 DEBUG : potato1: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e298257e_d20260925_m014331_c001_v0001180_t0050_u01790300611065") 2026/09/25 01:43:57 DEBUG : potato2: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4124e2d955658b55_d20260925_m014341_c001_v7007000_t0000_u01790300621450") 2026/09/25 01:43:57 DEBUG : potato2: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f101432a188728d7d_d20260925_m014340_c001_v0001174_t0046_u01790300620524") 2026/09/25 01:43:57 DEBUG : potato2: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f105b75da4c4a79a2_d20260925_m014338_c001_v0001036_t0055_u01790300618280") 2026/09/25 01:43:57 DEBUG : potato2: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4005c7cd230e18b7_d20260925_m014337_c001_v7007000_t0000_u01790300617167") 2026/09/25 01:43:57 DEBUG : potato2: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f405b75da4c4a799c_d20260925_m014336_c001_v0001036_t0001_u01790300616799") 2026/09/25 01:43:57 DEBUG : potato2: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4005c7cd230e18af_d20260925_m014332_c001_v7007000_t0000_u01790300612458") 2026/09/25 01:43:57 DEBUG : potato2: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f10940491e2982580_d20260925_m014331_c001_v0001180_t0011_u01790300611248") 2026/09/25 01:43:57 DEBUG : potato2: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4198356c06fe8993_d20260925_m014205_c001_v7007000_t0000_u01790300525594") 2026/09/25 01:43:57 DEBUG : potato2: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f10940491e29823a8_d20260925_m014204_c001_v0001180_t0020_u01790300524273") 2026/09/25 01:43:57 DEBUG : potato2: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4198356c06fe8990_d20260925_m014203_c001_v7007000_t0000_u01790300523907") 2026/09/25 01:43:57 DEBUG : potato2: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f10940491e29823a0_d20260925_m014202_c001_v0001180_t0032_u01790300522400") 2026/09/25 01:43:57 DEBUG : potato2: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4198356c06fe898c_d20260925_m014202_c001_v7007000_t0000_u01790300522007") 2026/09/25 01:43:57 DEBUG : potato2: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f10940491e2982393_d20260925_m014200_c001_v0001180_t0041_u01790300520719") 2026/09/25 01:43:57 DEBUG : potato2: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4198356c06fe898a_d20260925_m014200_c001_v7007000_t0000_u01790300520357") 2026/09/25 01:43:57 DEBUG : potato2: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f10940491e2982386_d20260925_m014158_c001_v0001180_t0023_u01790300518684") 2026/09/25 01:43:57 DEBUG : potato2: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4198356c06fe8987_d20260925_m014158_c001_v7007000_t0000_u01790300518318") 2026/09/25 01:43:57 DEBUG : potato2: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f10940491e298237c_d20260925_m014157_c001_v0001180_t0040_u01790300517181") 2026/09/25 01:43:57 DEBUG : potato2: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4124e2d955658678_d20260925_m013900_c001_v7007000_t0000_u01790300340762") 2026/09/25 01:43:57 DEBUG : potato2: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f10940491e29820de_d20260925_m013858_c001_v0001180_t0008_u01790300338839") 2026/09/25 01:43:57 DEBUG : potato2: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4124e2d95565866e_d20260925_m013856_c001_v7007000_t0000_u01790300336750") 2026/09/25 01:43:58 DEBUG : potato2: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f10940491e29820ba_d20260925_m013853_c001_v0001180_t0041_u01790300333593") 2026/09/25 01:43:58 DEBUG : potato2: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4124e2d955658667_d20260925_m013851_c001_v7007000_t0000_u01790300331047") 2026/09/25 01:43:58 DEBUG : potato2: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f10940491e29820a1_d20260925_m013849_c001_v0001180_t0048_u01790300329101") 2026/09/25 01:43:58 DEBUG : potato3: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4005c7cd230e18b9_d20260925_m014337_c001_v7007000_t0000_u01790300617911") 2026/09/25 01:43:58 DEBUG : potato3: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f405b75da4c4a799e_d20260925_m014337_c001_v0001036_t0001_u01790300617537") 2026/09/25 01:43:58 DEBUG : potato4: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4005c7cd230e18b6_d20260925_m014336_c001_v7007000_t0000_u01790300616056") 2026/09/25 01:43:58 DEBUG : potato4: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f405b75da4c4a7998_d20260925_m014335_c001_v0001036_t0001_u01790300615873") 2026/09/25 01:43:58 DEBUG : remotepotato: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4124e2d955658679_d20260925_m013900_c001_v7007000_t0000_u01790300340942") 2026/09/25 01:43:58 DEBUG : remotepotato: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f10940491e29820e0_d20260925_m013859_c001_v0001180_t0027_u01790300339649") 2026/09/25 01:43:58 DEBUG : remotepotato: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4124e2d95565866f_d20260925_m013856_c001_v7007000_t0000_u01790300336975") 2026/09/25 01:43:58 DEBUG : remotepotato: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f10940491e29820c4_d20260925_m013855_c001_v0001180_t0009_u01790300335034") 2026/09/25 01:43:58 DEBUG : remotepotato: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4124e2d955658668_d20260925_m013851_c001_v7007000_t0000_u01790300331228") 2026/09/25 01:43:58 DEBUG : remotepotato: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f10940491e29820a3_d20260925_m013849_c001_v0001180_t0052_u01790300329923") 2026/09/25 01:43:58 DEBUG : rutabaga: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4124e2d95565867a_d20260925_m013901_c001_v7007000_t0000_u01790300341122") 2026/09/25 01:43:58 DEBUG : rutabaga: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29820d0_d20260925_m013857_c001_v0001180_t0050_u01790300337515") 2026/09/25 01:43:58 DEBUG : rutabaga: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4124e2d955658670_d20260925_m013857_c001_v7007000_t0000_u01790300337156") 2026/09/25 01:43:58 DEBUG : rutabaga: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29820a9_d20260925_m013851_c001_v0001180_t0050_u01790300331772") 2026/09/25 01:43:58 DEBUG : rutabaga: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4124e2d955658669_d20260925_m013851_c001_v7007000_t0000_u01790300331408") 2026/09/25 01:43:58 DEBUG : rutabaga: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e2982099_d20260925_m013847_c001_v0001180_t0050_u01790300327737") 2026/09/25 01:43:58 DEBUG : small: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4209e18e512fe4d4_d20260925_m014223_c001_v7007000_t0000_u01790300543873") 2026/09/25 01:43:59 DEBUG : small: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e298242a_d20260925_m014222_c001_v0001180_t0050_u01790300542300") 2026/09/25 01:43:59 DEBUG : small: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f417f47192d61e107_d20260925_m014221_c001_v7007000_t0000_u01790300541012") 2026/09/25 01:43:59 DEBUG : small: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e298241e_d20260925_m014219_c001_v0001180_t0050_u01790300539046") 2026/09/25 01:43:59 DEBUG : small: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4209e18e512fe4ca_d20260925_m014217_c001_v7007000_t0000_u01790300537587") 2026/09/25 01:43:59 DEBUG : small: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e2982412_d20260925_m014216_c001_v0001180_t0050_u01790300536600") 2026/09/25 01:43:59 DEBUG : small: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4198356c06fe8997_d20260925_m014207_c001_v7007000_t0000_u01790300527388") 2026/09/25 01:43:59 DEBUG : small: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29823c0_d20260925_m014206_c001_v0001180_t0050_u01790300526153") 2026/09/25 01:43:59 DEBUG : sub dir/hello world: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4198356c06fe8984_d20260925_m014156_c001_v7007000_t0000_u01790300516749") 2026/09/25 01:43:59 DEBUG : sub dir/hello world: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e2982374_d20260925_m014156_c001_v0001180_t0050_u01790300516026") 2026/09/25 01:43:59 DEBUG : sub dir/hello world: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40613a6532539570_d20260925_m014143_c001_v7007000_t0000_u01790300503689") 2026/09/25 01:43:59 DEBUG : sub dir/hello world: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e2982334_d20260925_m014140_c001_v0001180_t0050_u01790300500266") 2026/09/25 01:43:59 DEBUG : sub dir/hello world: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40613a6532539569_d20260925_m014138_c001_v7007000_t0000_u01790300498618") 2026/09/25 01:43:59 DEBUG : sub dir/hello world: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e298231c_d20260925_m014134_c001_v0001180_t0050_u01790300494799") 2026/09/25 01:43:59 DEBUG : sub dir/hello world2: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40613a6532539571_d20260925_m014143_c001_v7007000_t0000_u01790300503871") 2026/09/25 01:43:59 DEBUG : sub dir/hello world2: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e2982338_d20260925_m014140_c001_v0001180_t0050_u01790300500450") 2026/09/25 01:43:59 DEBUG : sub dir/hello world2: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40613a653253956a_d20260925_m014138_c001_v7007000_t0000_u01790300498804") 2026/09/25 01:43:59 DEBUG : sub dir/hello world2: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e298231e_d20260925_m014134_c001_v0001180_t0050_u01790300494985") 2026/09/25 01:43:59 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40613a6532539572_d20260925_m014144_c001_v7007000_t0000_u01790300504053") 2026/09/25 01:43:59 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e298233a_d20260925_m014140_c001_v0001180_t0050_u01790300500634") 2026/09/25 01:43:59 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40613a653253956b_d20260925_m014138_c001_v7007000_t0000_u01790300498987") 2026/09/25 01:43:59 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e2982320_d20260925_m014135_c001_v0001180_t0050_u01790300495169") 2026/09/25 01:43:59 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40613a6532539573_d20260925_m014144_c001_v7007000_t0000_u01790300504281") 2026/09/25 01:43:59 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f10940491e298233c_d20260925_m014140_c001_v0001180_t0043_u01790300500819") 2026/09/25 01:43:59 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40613a653253956c_d20260925_m014139_c001_v7007000_t0000_u01790300499168") 2026/09/25 01:44:00 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f10940491e2982322_d20260925_m014135_c001_v0001180_t0038_u01790300495353") 2026/09/25 01:44:00 DEBUG : sub dir/potato3: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4124e2d955658b56_d20260925_m014341_c001_v7007000_t0000_u01790300621630") 2026/09/25 01:44:00 DEBUG : sub dir/potato3: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f409f449e4bf73f9b_d20260925_m014340_c001_v0001105_t0052_u01790300620147") 2026/09/25 01:44:00 DEBUG : sub dir/potato3: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f405b75da4c4a79a6_d20260925_m014339_c001_v0001036_t0001_u01790300619031") 2026/09/25 01:44:00 DEBUG : sub dir/potato3: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4198356c06fe8994_d20260925_m014205_c001_v7007000_t0000_u01790300525773") 2026/09/25 01:44:00 DEBUG : sub dir/potato3: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29823ae_d20260925_m014204_c001_v0001180_t0050_u01790300524688") 2026/09/25 01:44:00 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40613a6532539574_d20260925_m014144_c001_v7007000_t0000_u01790300504462") 2026/09/25 01:44:00 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f10940491e298233e_d20260925_m014141_c001_v0001180_t0047_u01790300501073") 2026/09/25 01:44:00 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40613a653253956d_d20260925_m014139_c001_v7007000_t0000_u01790300499349") 2026/09/25 01:44:00 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f10940491e2982324_d20260925_m014135_c001_v0001180_t0008_u01790300495576") 2026/09/25 01:44:00 DEBUG : sub/file2: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4131dcfe617221d3_d20260925_m014246_c001_v7007000_t0000_u01790300566672") 2026/09/25 01:44:00 DEBUG : sub/file2: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e298248a_d20260925_m014245_c001_v0001180_t0050_u01790300565578") 2026/09/25 01:44:00 DEBUG : sub/file2: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4198356c06fe8983_d20260925_m014154_c001_v7007000_t0000_u01790300514757") 2026/09/25 01:44:00 DEBUG : sub/file2: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e2982368_d20260925_m014148_c001_v0001180_t0050_u01790300508722") 2026/09/25 01:44:00 DEBUG : sub/file2: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4198356c06fe897c_d20260925_m014148_c001_v7007000_t0000_u01790300508176") 2026/09/25 01:44:00 DEBUG : sub/file2: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e2982354_d20260925_m014145_c001_v0001180_t0050_u01790300505285") 2026/09/25 01:44:00 DEBUG : sub/file2: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f422bda88a26886eb_d20260925_m014002_c001_v7007000_t0000_u01790300402720") 2026/09/25 01:44:00 DEBUG : sub/file2: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e298219e_d20260925_m014001_c001_v0001180_t0050_u01790300401275") 2026/09/25 01:44:00 DEBUG : test-multithread-abort: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4124e2d955658663_d20260925_m013846_c001_v7007000_t0000_u01790300326815") 2026/09/25 01:44:00 DEBUG : test-multithread-abort: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f10940491e298206d_d20260925_m013830_c001_v0001180_t0000_u01790300310932") 2026/09/25 01:44:01 DEBUG : test-multithread-copy-false-16777215-2: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f415a7f71ed2154aa_d20260925_m013434_c001_v7007000_t0000_u01790300074498") 2026/09/25 01:44:01 DEBUG : test-multithread-copy-false-16777215-2: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f117466d379baffda_d20260925_m013426_c001_v0001101_t0054_u01790300066858") 2026/09/25 01:44:01 DEBUG : test-multithread-copy-false-16777216-2: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f415a7f71ed2154ab_d20260925_m013444_c001_v7007000_t0000_u01790300084289") 2026/09/25 01:44:01 DEBUG : test-multithread-copy-false-16777216-2: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f10940491e2981df7_d20260925_m013437_c001_v0001180_t0025_u01790300077491") 2026/09/25 01:44:01 DEBUG : test-multithread-copy-false-16777217-2: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f415a7f71ed2154b0_d20260925_m013450_c001_v7007000_t0000_u01790300090274") 2026/09/25 01:44:01 DEBUG : test-multithread-copy-false-16777217-2: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f10940491e2981e0b_d20260925_m013444_c001_v0001180_t0006_u01790300084717") 2026/09/25 01:44:01 DEBUG : test-multithread-copy-true-16777216-2: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4119e53ac010165c_d20260925_m013720_c001_v7007000_t0000_u01790300240583") 2026/09/25 01:44:01 DEBUG : test-multithread-copy-true-16777216-2: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f20253dc3db346a1c_d20260925_m013644_c001_v0001153_t0024_u01790300204463") 2026/09/25 01:44:01 DEBUG : test-multithread-copy-true-16777217-2: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f404b312f795408b7_d20260925_m013829_c001_v7007000_t0000_u01790300309637") 2026/09/25 01:44:01 DEBUG : test-multithread-copy-true-16777217-2: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f222bda88a26886bd_d20260925_m013753_c001_v0001120_t0057_u01790300273762") 2026/09/25 01:44:01 DEBUG : test.sum: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4124e2d955658a7e_d20260925_m013916_c001_v7007000_t0000_u01790300356458") 2026/09/25 01:44:01 DEBUG : test.sum: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f10940491e298211c_d20260925_m013914_c001_v0001180_t0059_u01790300354696") 2026/09/25 01:44:01 DEBUG : test.sum: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f10940491e2982116_d20260925_m013913_c001_v0001180_t0058_u01790300353193") 2026/09/25 01:44:01 DEBUG : test.sum: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f10940491e2982110_d20260925_m013911_c001_v0001180_t0008_u01790300351971") 2026/09/25 01:44:01 DEBUG : test.sum: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f10940491e298210a_d20260925_m013910_c001_v0001180_t0026_u01790300350797") 2026/09/25 01:44:01 DEBUG : test.sum: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f10940491e2982100_d20260925_m013909_c001_v0001180_t0059_u01790300349422") 2026/09/25 01:44:01 DEBUG : test.sum: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29820fc_d20260925_m013908_c001_v0001180_t0050_u01790300348342") 2026/09/25 01:44:01 DEBUG : test.sum: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e29820f6_d20260925_m013906_c001_v0001180_t0050_u01790300346472") 2026/09/25 01:44:01 DEBUG : test.sum: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4124e2d95565867d_d20260925_m013903_c001_v7007000_t0000_u01790300343354") 2026/09/25 01:44:01 DEBUG : test.sum: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f10940491e29820ee_d20260925_m013902_c001_v0001180_t0029_u01790300342584") 2026/09/25 01:44:01 DEBUG : test.sum: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f10940491e29820ec_d20260925_m013901_c001_v0001180_t0000_u01790300341483") 2026/09/25 01:44:01 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4005c7cd230e1891_d20260925_m014325_c001_v7007000_t0000_u01790300605831") 2026/09/25 01:44:01 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f10940491e2982554_d20260925_m014324_c001_v0001180_t0032_u01790300604674") 2026/09/25 01:44:02 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f4005c7cd230e1893_d20260925_m014326_c001_v7007000_t0000_u01790300606013") 2026/09/25 01:44:02 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f10940491e2982552_d20260925_m014324_c001_v0001180_t0056_u01790300604453") 2026/09/25 01:44:02 DEBUG : zend.txt: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40613a6532539575_d20260925_m014144_c001_v7007000_t0000_u01790300504644") 2026/09/25 01:44:02 DEBUG : zend.txt: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e2982332_d20260925_m014140_c001_v0001180_t0050_u01790300500082") 2026/09/25 01:44:02 DEBUG : zend.txt: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40613a653253956e_d20260925_m014139_c001_v7007000_t0000_u01790300499531") 2026/09/25 01:44:02 DEBUG : zend.txt: Deleting (id "4_z0fd20de3303ddb6baf080e1b_f40940491e2982318_d20260925_m014134_c001_v0001180_t0050_u01790300494614") "./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose" - Finished ERROR in 9m39.071007605s (try 1/5): exit status 1: Failed [TestMultithreadCopy/upload=true,size=16777215,streams=2 TestMultithreadCopy/upload=true,size=16777216,streams=2 TestMultithreadCopy/upload=true,size=16777217,streams=2]