"./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose" - Starting (try 1/5) 2025/02/07 02:07:34 DEBUG : Creating backend with remote "TestB2:rclone-test-jotegax4kaqu" 2025/02/07 02:07:34 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/02/07 02:07:35 DEBUG : Creating backend with remote "/tmp/rclone1391224125" === RUN TestDoMultiThreadCopy --- PASS: TestDoMultiThreadCopy (0.00s) === RUN TestMultithreadCalculateNumChunks === RUN TestMultithreadCalculateNumChunks/{size:1_chunkSize:65536_wantNumChunks:1} === RUN TestMultithreadCalculateNumChunks/{size:1048576_chunkSize:1_wantNumChunks:1048576} === RUN TestMultithreadCalculateNumChunks/{size:1048576_chunkSize:2_wantNumChunks:524288} === RUN TestMultithreadCalculateNumChunks/{size:1048577_chunkSize:2_wantNumChunks:524289} === RUN TestMultithreadCalculateNumChunks/{size:1048575_chunkSize:2_wantNumChunks:524288} --- PASS: TestMultithreadCalculateNumChunks (0.00s) --- PASS: TestMultithreadCalculateNumChunks/{size:1_chunkSize:65536_wantNumChunks:1} (0.00s) --- PASS: TestMultithreadCalculateNumChunks/{size:1048576_chunkSize:1_wantNumChunks:1048576} (0.00s) --- PASS: TestMultithreadCalculateNumChunks/{size:1048576_chunkSize:2_wantNumChunks:524288} (0.00s) --- PASS: TestMultithreadCalculateNumChunks/{size:1048577_chunkSize:2_wantNumChunks:524289} (0.00s) --- PASS: TestMultithreadCalculateNumChunks/{size:1048575_chunkSize:2_wantNumChunks:524288} (0.00s) === RUN TestMultithreadCopy run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" 2025/02/07 02:07:38 DEBUG : chunksize-probe: Cancelling large file upload === RUN TestMultithreadCopy/upload=false,size=201326591,streams=2 2025/02/07 02:08:01 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2025/02/07 02:08:01 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: write buffer set to 131072 2025/02/07 02:08:01 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2025/02/07 02:08:01 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2025/02/07 02:08:01 DEBUG : test-multithread-copy-false-201326591-2: Starting multi-thread copy with 3 chunks of size 64Mi with 3 parallel streams 2025/02/07 02:08:01 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 3/3 (134217728-201326591) size 64.000Mi starting 2025/02/07 02:08:01 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 1/3 (0-67108864) size 64Mi starting 2025/02/07 02:08:01 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 2/3 (67108864-134217728) size 64Mi starting 2025/02/07 02:08:01 DEBUG : test-multithread-copy-false-201326591-2: writing chunk 2 2025/02/07 02:08:02 DEBUG : test-multithread-copy-false-201326591-2: writing chunk 0 2025/02/07 02:08:02 DEBUG : test-multithread-copy-false-201326591-2: writing chunk 1 2025/02/07 02:08:06 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 3/3 (134217728-201326591) size 64.000Mi finished 2025/02/07 02:08:07 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 1/3 (0-67108864) size 64Mi finished 2025/02/07 02:08:07 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 2/3 (67108864-134217728) size 64Mi finished 2025/02/07 02:08:07 DEBUG : test-multithread-copy-false-201326591-2: Finished multi-thread copy with 3 parts of size 64Mi === RUN TestMultithreadCopy/upload=false,size=201326592,streams=2 2025/02/07 02:08:39 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2025/02/07 02:08:39 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: write buffer set to 131072 2025/02/07 02:08:39 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2025/02/07 02:08:39 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2025/02/07 02:08:39 DEBUG : test-multithread-copy-false-201326592-2: Starting multi-thread copy with 3 chunks of size 64Mi with 3 parallel streams 2025/02/07 02:08:39 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 3/3 (134217728-201326592) size 64Mi starting 2025/02/07 02:08:39 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 1/3 (0-67108864) size 64Mi starting 2025/02/07 02:08:39 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 2/3 (67108864-134217728) size 64Mi starting 2025/02/07 02:08:40 DEBUG : test-multithread-copy-false-201326592-2: writing chunk 2 2025/02/07 02:08:40 DEBUG : test-multithread-copy-false-201326592-2: writing chunk 0 2025/02/07 02:08:40 DEBUG : test-multithread-copy-false-201326592-2: writing chunk 1 2025/02/07 02:08:45 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 3/3 (134217728-201326592) size 64Mi finished 2025/02/07 02:08:45 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 2/3 (67108864-134217728) size 64Mi finished 2025/02/07 02:08:45 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 1/3 (0-67108864) size 64Mi finished 2025/02/07 02:08:45 DEBUG : test-multithread-copy-false-201326592-2: Finished multi-thread copy with 3 parts of size 64Mi === RUN TestMultithreadCopy/upload=false,size=201326593,streams=2 2025/02/07 02:09:19 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2025/02/07 02:09:19 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: write buffer set to 131072 2025/02/07 02:09:19 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2025/02/07 02:09:19 DEBUG : test-multithread-copy-false-201326593-2: Starting multi-thread copy with 4 chunks of size 64Mi with 4 parallel streams 2025/02/07 02:09:19 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 4/4 (201326592-201326593) size 1 starting 2025/02/07 02:09:19 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 1/4 (0-67108864) size 64Mi starting 2025/02/07 02:09:19 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 3/4 (134217728-201326592) size 64Mi starting 2025/02/07 02:09:19 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 2/4 (67108864-134217728) size 64Mi starting 2025/02/07 02:09:20 DEBUG : test-multithread-copy-false-201326593-2: writing chunk 3 2025/02/07 02:09:20 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 4/4 (201326592-201326593) size 1 finished 2025/02/07 02:09:20 DEBUG : test-multithread-copy-false-201326593-2: writing chunk 0 2025/02/07 02:09:20 DEBUG : test-multithread-copy-false-201326593-2: writing chunk 1 2025/02/07 02:09:21 DEBUG : test-multithread-copy-false-201326593-2: writing chunk 2 2025/02/07 02:09:25 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 1/4 (0-67108864) size 64Mi finished 2025/02/07 02:09:25 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 2/4 (67108864-134217728) size 64Mi finished 2025/02/07 02:09:26 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 3/4 (134217728-201326592) size 64Mi finished 2025/02/07 02:09:26 DEBUG : test-multithread-copy-false-201326593-2: Finished multi-thread copy with 4 parts of size 64Mi === RUN TestMultithreadCopy/upload=true,size=201326591,streams=2 2025/02/07 02:09:48 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: disabling buffering because source is local disk 2025/02/07 02:09:49 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2025/02/07 02:09:49 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: number of streams 4 was bigger than number of chunks 2 2025/02/07 02:09:49 DEBUG : test-multithread-copy-true-201326591-2: Starting multi-thread copy with 2 chunks of size 96Mi with 2 parallel streams 2025/02/07 02:09:49 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: chunk 2/2 (100663296-201326591) size 96.000Mi starting 2025/02/07 02:09:49 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: chunk 1/2 (0-100663296) size 96Mi starting 2025/02/07 02:09:49 DEBUG : test-multithread-copy-true-201326591-2: Sending chunk 1 length 100663295 2025/02/07 02:09:49 DEBUG : test-multithread-copy-true-201326591-2: Sending chunk 0 length 100663296 2025/02/07 02:09:57 DEBUG : test-multithread-copy-true-201326591-2: Done sending chunk 1 2025/02/07 02:09:57 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: chunk 2/2 (100663296-201326591) size 96.000Mi finished 2025/02/07 02:09:57 DEBUG : test-multithread-copy-true-201326591-2: Done sending chunk 0 2025/02/07 02:09:57 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: chunk 1/2 (0-100663296) size 96Mi finished 2025/02/07 02:09:57 DEBUG : test-multithread-copy-true-201326591-2: Finishing large file upload with 2 parts 2025/02/07 02:09:58 DEBUG : test-multithread-copy-true-201326591-2: Finished multi-thread copy with 2 parts of size 96Mi === RUN TestMultithreadCopy/upload=true,size=201326592,streams=2 2025/02/07 02:10:20 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: disabling buffering because source is local disk 2025/02/07 02:10:21 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2025/02/07 02:10:21 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: number of streams 4 was bigger than number of chunks 2 2025/02/07 02:10:21 DEBUG : test-multithread-copy-true-201326592-2: Starting multi-thread copy with 2 chunks of size 96Mi with 2 parallel streams 2025/02/07 02:10:21 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: chunk 2/2 (100663296-201326592) size 96Mi starting 2025/02/07 02:10:21 DEBUG : test-multithread-copy-true-201326592-2: Sending chunk 1 length 100663296 2025/02/07 02:10:21 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: chunk 1/2 (0-100663296) size 96Mi starting 2025/02/07 02:10:21 DEBUG : test-multithread-copy-true-201326592-2: Sending chunk 0 length 100663296 2025/02/07 02:10:30 DEBUG : test-multithread-copy-true-201326592-2: Done sending chunk 0 2025/02/07 02:10:30 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: chunk 1/2 (0-100663296) size 96Mi finished 2025/02/07 02:10:30 DEBUG : test-multithread-copy-true-201326592-2: Done sending chunk 1 2025/02/07 02:10:30 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: chunk 2/2 (100663296-201326592) size 96Mi finished 2025/02/07 02:10:30 DEBUG : test-multithread-copy-true-201326592-2: Finishing large file upload with 2 parts 2025/02/07 02:10:31 DEBUG : test-multithread-copy-true-201326592-2: Finished multi-thread copy with 2 parts of size 96Mi === RUN TestMultithreadCopy/upload=true,size=201326593,streams=2 2025/02/07 02:10:53 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: disabling buffering because source is local disk 2025/02/07 02:10:54 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2025/02/07 02:10:54 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2025/02/07 02:10:54 DEBUG : test-multithread-copy-true-201326593-2: Starting multi-thread copy with 3 chunks of size 96Mi with 3 parallel streams 2025/02/07 02:10:54 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 3/3 (201326592-201326593) size 1 starting 2025/02/07 02:10:54 DEBUG : test-multithread-copy-true-201326593-2: Sending chunk 2 length 1 2025/02/07 02:10:54 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 1/3 (0-100663296) size 96Mi starting 2025/02/07 02:10:54 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 2/3 (100663296-201326592) size 96Mi starting 2025/02/07 02:10:54 DEBUG : test-multithread-copy-true-201326593-2: Sending chunk 0 length 100663296 2025/02/07 02:10:54 DEBUG : test-multithread-copy-true-201326593-2: Sending chunk 1 length 100663296 2025/02/07 02:10:55 DEBUG : test-multithread-copy-true-201326593-2: Done sending chunk 2 2025/02/07 02:10:55 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 3/3 (201326592-201326593) size 1 finished 2025/02/07 02:11:03 DEBUG : test-multithread-copy-true-201326593-2: Done sending chunk 0 2025/02/07 02:11:03 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 1/3 (0-100663296) size 96Mi finished 2025/02/07 02:11:05 DEBUG : test-multithread-copy-true-201326593-2: Done sending chunk 1 2025/02/07 02:11:05 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 2/3 (100663296-201326592) size 96Mi finished 2025/02/07 02:11:05 DEBUG : test-multithread-copy-true-201326593-2: Finishing large file upload with 3 parts 2025/02/07 02:11:06 DEBUG : test-multithread-copy-true-201326593-2: Finished multi-thread copy with 3 parts of size 96Mi --- PASS: TestMultithreadCopy (219.76s) --- PASS: TestMultithreadCopy/upload=false,size=201326591,streams=2 (37.20s) --- PASS: TestMultithreadCopy/upload=false,size=201326592,streams=2 (38.20s) --- PASS: TestMultithreadCopy/upload=false,size=201326593,streams=2 (40.59s) --- PASS: TestMultithreadCopy/upload=true,size=201326591,streams=2 (31.93s) --- PASS: TestMultithreadCopy/upload=true,size=201326592,streams=2 (33.24s) --- PASS: TestMultithreadCopy/upload=true,size=201326593,streams=2 (35.08s) === RUN TestMultithreadCopyAbort run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" 2025/02/07 02:11:15 DEBUG : chunksize-probe: Cancelling large file upload 2025/02/07 02:11:29 DEBUG : test-multithread-abort: multi-thread copy: disabling buffering because source is local disk 2025/02/07 02:11:30 DEBUG : test-multithread-abort: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 1 2025/02/07 02:11:30 DEBUG : test-multithread-abort: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2025/02/07 02:11:30 DEBUG : test-multithread-abort: Starting multi-thread copy with 3 chunks of size 96Mi with 3 parallel streams 2025/02/07 02:11:30 DEBUG : test-multithread-abort: multi-thread copy: chunk 3/3 (201326592-201326593) size 1 starting 2025/02/07 02:11:30 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2025/02/07 02:11:30 DEBUG : test-multithread-abort: multi-thread copy: chunk 2/3 (100663296-201326592) size 96Mi starting 2025/02/07 02:11:30 DEBUG : Open with options = [RangeOption(100663296,201326591)] 2025/02/07 02:11:30 DEBUG : test-multithread-abort: multi-thread copy: chunk 1/3 (0-100663296) size 96Mi starting 2025/02/07 02:11:30 DEBUG : test-multithread-abort: Sending chunk 1 length 100663296 2025/02/07 02:11:30 DEBUG : Open with options = [RangeOption(0,100663295)] 2025/02/07 02:11:30 DEBUG : test-multithread-abort: Sending chunk 0 length 100663296 2025/02/07 02:11:38 DEBUG : test-multithread-abort: Done sending chunk 1 2025/02/07 02:11:38 DEBUG : test-multithread-abort: multi-thread copy: chunk 2/3 (100663296-201326592) size 96Mi finished 2025/02/07 02:11:40 DEBUG : test-multithread-abort: Done sending chunk 0 2025/02/07 02:11:40 DEBUG : test-multithread-abort: multi-thread copy: chunk 1/3 (0-100663296) size 96Mi finished 2025/02/07 02:11:40 DEBUG : Returning error reader 2025/02/07 02:11:40 DEBUG : test-multithread-abort: Sending chunk 2 length 1 2025/02/07 02:11:40 DEBUG : BOOM: simulated read failure 2025/02/07 02:11:40 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 0/10: BOOM: simulated read failure 2025/02/07 02:11:40 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2025/02/07 02:11:41 DEBUG : Returning error reader 2025/02/07 02:11:41 DEBUG : BOOM: simulated read failure 2025/02/07 02:11:41 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 1/10: BOOM: simulated read failure 2025/02/07 02:11:41 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2025/02/07 02:11:42 DEBUG : Returning error reader 2025/02/07 02:11:42 DEBUG : BOOM: simulated read failure 2025/02/07 02:11:42 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 2/10: BOOM: simulated read failure 2025/02/07 02:11:42 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2025/02/07 02:11:43 DEBUG : Returning error reader 2025/02/07 02:11:43 DEBUG : BOOM: simulated read failure 2025/02/07 02:11:43 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 3/10: BOOM: simulated read failure 2025/02/07 02:11:43 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2025/02/07 02:11:44 DEBUG : Returning error reader 2025/02/07 02:11:44 DEBUG : BOOM: simulated read failure 2025/02/07 02:11:44 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 4/10: BOOM: simulated read failure 2025/02/07 02:11:44 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2025/02/07 02:11:45 DEBUG : Returning error reader 2025/02/07 02:11:45 DEBUG : BOOM: simulated read failure 2025/02/07 02:11:45 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 5/10: BOOM: simulated read failure 2025/02/07 02:11:45 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2025/02/07 02:11:46 DEBUG : Returning error reader 2025/02/07 02:11:46 DEBUG : BOOM: simulated read failure 2025/02/07 02:11:46 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 6/10: BOOM: simulated read failure 2025/02/07 02:11:46 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2025/02/07 02:11:47 DEBUG : Returning error reader 2025/02/07 02:11:47 DEBUG : BOOM: simulated read failure 2025/02/07 02:11:47 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 7/10: BOOM: simulated read failure 2025/02/07 02:11:47 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2025/02/07 02:11:48 DEBUG : Returning error reader 2025/02/07 02:11:48 DEBUG : BOOM: simulated read failure 2025/02/07 02:11:48 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 8/10: BOOM: simulated read failure 2025/02/07 02:11:48 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2025/02/07 02:11:49 DEBUG : Returning error reader 2025/02/07 02:11:49 DEBUG : BOOM: simulated read failure 2025/02/07 02:11:49 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 9/10: BOOM: simulated read failure 2025/02/07 02:11:49 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2025/02/07 02:11:50 DEBUG : Returning error reader 2025/02/07 02:11:50 DEBUG : BOOM: simulated read failure 2025/02/07 02:11:50 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 10/10: BOOM: simulated read failure 2025/02/07 02:11:50 DEBUG : test-multithread-abort: Reopen failed after offset 0 bytes read: failed to reopen: too many retries 2025/02/07 02:11:50 DEBUG : test-multithread-abort: Error sending chunk 2 (retry=false): Post "https://pod-000-1106-10.backblaze.com/b2api/v1/b2_upload_part/4_z4f229d93209ddbeb9f480e1b_f218589218909bbe5_d20250207_m021130_c001_v0001106_t0018_u01738894290817/0011": BOOM: simulated read failure: &url.Error{Op:"Post", URL:"https://pod-000-1106-10.backblaze.com/b2api/v1/b2_upload_part/4_z4f229d93209ddbeb9f480e1b_f218589218909bbe5_d20250207_m021130_c001_v0001106_t0018_u01738894290817/0011", Err:(*errors.errorString)(0xc0005a1c80)} 2025/02/07 02:11:50 DEBUG : test-multithread-abort: Error sending chunk 2: Post "https://pod-000-1106-10.backblaze.com/b2api/v1/b2_upload_part/4_z4f229d93209ddbeb9f480e1b_f218589218909bbe5_d20250207_m021130_c001_v0001106_t0018_u01738894290817/0011": BOOM: simulated read failure 2025/02/07 02:11:50 DEBUG : test-multithread-abort: multi-thread copy: chunk 3/3 failed: multi-thread copy: failed to write chunk: Post "https://pod-000-1106-10.backblaze.com/b2api/v1/b2_upload_part/4_z4f229d93209ddbeb9f480e1b_f218589218909bbe5_d20250207_m021130_c001_v0001106_t0018_u01738894290817/0011": BOOM: simulated read failure 2025/02/07 02:11:50 DEBUG : test-multithread-abort: multi-thread copy: cancelling transfer on exit 2025/02/07 02:11:50 DEBUG : test-multithread-abort: Cancelling large file upload --- PASS: TestMultithreadCopyAbort (36.72s) === RUN TestSizeDiffers --- PASS: TestSizeDiffers (0.00s) === RUN TestReOpen === RUN TestReOpen/Normal === RUN TestReOpen/Normal/Basics 2025/02/07 02:11:51 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/ErrorAtStart === RUN TestReOpen/Normal/WithErrors 2025/02/07 02:11:51 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2025/02/07 02:11:51 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2025/02/07 02:11:51 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/Normal/TooManyErrors 2025/02/07 02:11:51 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2025/02/07 02:11:51 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2025/02/07 02:11:51 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2025/02/07 02:11:51 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/Normal/Seek 2025/02/07 02:11:51 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/02/07 02:11:51 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/02/07 02:11:51 DEBUG : potato: Seek from 5 to 2 === RUN TestReOpen/Normal/AccountRead === RUN TestReOpen/Normal/AccountReadDelay 2025/02/07 02:11:51 DEBUG : potato: Seek from 10 to 0 2025/02/07 02:11:51 DEBUG : potato: Seek from 10 to 0 2025/02/07 02:11:51 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/AccountReadError === RUN TestReOpen/WithRangeOption === RUN TestReOpen/WithRangeOption/Basics 2025/02/07 02:11:51 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/ErrorAtStart === RUN TestReOpen/WithRangeOption/WithErrors 2025/02/07 02:11:51 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2025/02/07 02:11:51 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2025/02/07 02:11:51 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithRangeOption/TooManyErrors 2025/02/07 02:11:51 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2025/02/07 02:11:51 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2025/02/07 02:11:51 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2025/02/07 02:11:51 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithRangeOption/Seek 2025/02/07 02:11:51 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/02/07 02:11:51 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/02/07 02:11:51 DEBUG : potato: Seek from 5 to 2 2025/02/07 02:11:51 DEBUG : potato: Seek from 7 to 4 === RUN TestReOpen/WithRangeOption/AccountRead === RUN TestReOpen/WithRangeOption/AccountReadDelay 2025/02/07 02:11:51 DEBUG : potato: Seek from 7 to 0 2025/02/07 02:11:51 DEBUG : potato: Seek from 7 to 0 2025/02/07 02:11:51 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/AccountReadError === RUN TestReOpen/WithSeekOption === RUN TestReOpen/WithSeekOption/Basics 2025/02/07 02:11:51 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/ErrorAtStart === RUN TestReOpen/WithSeekOption/WithErrors 2025/02/07 02:11:51 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2025/02/07 02:11:51 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2025/02/07 02:11:51 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithSeekOption/TooManyErrors 2025/02/07 02:11:51 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2025/02/07 02:11:51 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2025/02/07 02:11:51 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2025/02/07 02:11:51 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithSeekOption/Seek 2025/02/07 02:11:51 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/02/07 02:11:51 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/02/07 02:11:51 DEBUG : potato: Seek from 5 to 2 2025/02/07 02:11:51 DEBUG : potato: Seek from 7 to 5 === RUN TestReOpen/WithSeekOption/AccountRead === RUN TestReOpen/WithSeekOption/AccountReadDelay 2025/02/07 02:11:51 DEBUG : potato: Seek from 8 to 0 2025/02/07 02:11:51 DEBUG : potato: Seek from 8 to 0 2025/02/07 02:11:51 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/AccountReadError === RUN TestReOpen/UnknownSize === RUN TestReOpen/UnknownSize/Basics 2025/02/07 02:11:51 DEBUG : potato: Seek from 9 to 0 === RUN TestReOpen/UnknownSize/ErrorAtStart === RUN TestReOpen/UnknownSize/WithErrors 2025/02/07 02:11:51 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2025/02/07 02:11:51 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2025/02/07 02:11:51 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/UnknownSize/TooManyErrors 2025/02/07 02:11:51 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2025/02/07 02:11:51 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2025/02/07 02:11:51 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2025/02/07 02:11:51 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/UnknownSize/Seek 2025/02/07 02:11:51 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/02/07 02:11:51 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/02/07 02:11:51 DEBUG : potato: Seek from 5 to 2 2025/02/07 02:11:51 DEBUG : potato: Seek from 7 to 6 === RUN TestReOpen/UnknownSize/AccountRead === RUN TestReOpen/UnknownSize/AccountReadDelay 2025/02/07 02:11:51 DEBUG : potato: Seek from 9 to 0 2025/02/07 02:11:51 DEBUG : potato: Seek from 9 to 0 2025/02/07 02:11:51 DEBUG : potato: Seek from 9 to 0 === RUN TestReOpen/UnknownSize/AccountReadError --- PASS: TestReOpen (0.00s) --- PASS: TestReOpen/Normal (0.00s) --- PASS: TestReOpen/Normal/Basics (0.00s) --- PASS: TestReOpen/Normal/ErrorAtStart (0.00s) --- PASS: TestReOpen/Normal/WithErrors (0.00s) --- PASS: TestReOpen/Normal/TooManyErrors (0.00s) --- PASS: TestReOpen/Normal/Seek (0.00s) --- PASS: TestReOpen/Normal/AccountRead (0.00s) --- PASS: TestReOpen/Normal/AccountReadDelay (0.00s) --- PASS: TestReOpen/Normal/AccountReadError (0.00s) --- PASS: TestReOpen/WithRangeOption (0.00s) --- PASS: TestReOpen/WithRangeOption/Basics (0.00s) --- PASS: TestReOpen/WithRangeOption/ErrorAtStart (0.00s) --- PASS: TestReOpen/WithRangeOption/WithErrors (0.00s) --- PASS: TestReOpen/WithRangeOption/TooManyErrors (0.00s) --- PASS: TestReOpen/WithRangeOption/Seek (0.00s) --- PASS: TestReOpen/WithRangeOption/AccountRead (0.00s) --- PASS: TestReOpen/WithRangeOption/AccountReadDelay (0.00s) --- PASS: TestReOpen/WithRangeOption/AccountReadError (0.00s) --- PASS: TestReOpen/WithSeekOption (0.00s) --- PASS: TestReOpen/WithSeekOption/Basics (0.00s) --- PASS: TestReOpen/WithSeekOption/ErrorAtStart (0.00s) --- PASS: TestReOpen/WithSeekOption/WithErrors (0.00s) --- PASS: TestReOpen/WithSeekOption/TooManyErrors (0.00s) --- PASS: TestReOpen/WithSeekOption/Seek (0.00s) --- PASS: TestReOpen/WithSeekOption/AccountRead (0.00s) --- PASS: TestReOpen/WithSeekOption/AccountReadDelay (0.00s) --- PASS: TestReOpen/WithSeekOption/AccountReadError (0.00s) --- PASS: TestReOpen/UnknownSize (0.00s) --- PASS: TestReOpen/UnknownSize/Basics (0.00s) --- PASS: TestReOpen/UnknownSize/ErrorAtStart (0.00s) --- PASS: TestReOpen/UnknownSize/WithErrors (0.00s) --- PASS: TestReOpen/UnknownSize/TooManyErrors (0.00s) --- PASS: TestReOpen/UnknownSize/Seek (0.00s) --- PASS: TestReOpen/UnknownSize/AccountRead (0.00s) --- PASS: TestReOpen/UnknownSize/AccountReadDelay (0.00s) --- PASS: TestReOpen/UnknownSize/AccountReadError (0.00s) === RUN TestCheck run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" === RUN TestCheck/1 === RUN TestCheck/2 === RUN TestCheck/3 === RUN TestCheck/4 === RUN TestCheck/5 === RUN TestCheck/6 === RUN TestCheck/7 --- PASS: TestCheck (5.23s) --- PASS: TestCheck/1 (0.18s) --- PASS: TestCheck/2 (0.18s) --- PASS: TestCheck/3 (0.18s) --- PASS: TestCheck/4 (0.18s) --- PASS: TestCheck/5 (0.18s) --- PASS: TestCheck/6 (0.18s) --- PASS: TestCheck/7 (0.18s) === RUN TestCheckFsError 2025/02/07 02:11:56 DEBUG : Creating backend with remote "nonexistent" 2025/02/07 02:11:56 DEBUG : Creating backend with remote "nonexistent" 2025/02/07 02:11:56 DEBUG : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: Waiting for checks to finish 2025/02/07 02:11:56 ERROR : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: error reading source root directory: directory not found 2025/02/07 02:11:56 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: 2 differences found 2025/02/07 02:11:56 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: 2 errors while checking --- PASS: TestCheckFsError (0.00s) === RUN TestCheckDownload run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" === RUN TestCheckDownload/1 === RUN TestCheckDownload/2 === RUN TestCheckDownload/3 === RUN TestCheckDownload/4 === RUN TestCheckDownload/5 === RUN TestCheckDownload/6 === RUN TestCheckDownload/7 --- PASS: TestCheckDownload (6.72s) --- PASS: TestCheckDownload/1 (0.73s) --- PASS: TestCheckDownload/2 (0.37s) --- PASS: TestCheckDownload/3 (0.38s) --- PASS: TestCheckDownload/4 (0.56s) --- PASS: TestCheckDownload/5 (0.38s) --- PASS: TestCheckDownload/6 (0.38s) --- PASS: TestCheckDownload/7 (0.37s) === RUN TestCheckSizeOnly run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" === RUN TestCheckSizeOnly/1 === RUN TestCheckSizeOnly/2 === RUN TestCheckSizeOnly/3 === RUN TestCheckSizeOnly/4 === RUN TestCheckSizeOnly/5 === RUN TestCheckSizeOnly/6 === RUN TestCheckSizeOnly/7 --- PASS: TestCheckSizeOnly (4.76s) --- PASS: TestCheckSizeOnly/1 (0.18s) --- PASS: TestCheckSizeOnly/2 (0.18s) --- PASS: TestCheckSizeOnly/3 (0.19s) --- PASS: TestCheckSizeOnly/4 (0.18s) --- PASS: TestCheckSizeOnly/5 (0.18s) --- PASS: TestCheckSizeOnly/6 (0.18s) --- PASS: TestCheckSizeOnly/7 (0.18s) === RUN TestCheckEqualReaders --- PASS: TestCheckEqualReaders (0.00s) === RUN TestParseSumFile run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" 2025/02/07 02:12:09 NOTICE: test.sum: improperly formatted checksum line 4 2025/02/07 02:12:09 NOTICE: test.sum: improperly formatted checksum line 5 2025/02/07 02:12:09 NOTICE: test.sum: improperly formatted checksum line 6 2025/02/07 02:12:09 NOTICE: test.sum: 2 warning(s) suppressed... 2025/02/07 02:12:10 NOTICE: test.sum: improperly formatted checksum line 4 2025/02/07 02:12:10 NOTICE: test.sum: improperly formatted checksum line 5 2025/02/07 02:12:10 NOTICE: test.sum: improperly formatted checksum line 6 2025/02/07 02:12:10 NOTICE: test.sum: 2 warning(s) suppressed... --- PASS: TestParseSumFile (2.51s) === RUN TestCheckSum run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" 2025/02/07 02:12:10 DEBUG : Creating backend with remote "TestB2:rclone-test-jotegax4kaqu/data" check_test.go:353: B2 bucket rclone-test-jotegax4kaqu path data lacks md5, skipping --- SKIP: TestCheckSum (1.49s) === RUN TestCheckSumDownload run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" 2025/02/07 02:12:12 DEBUG : Creating backend with remote "TestB2:rclone-test-jotegax4kaqu/data" === RUN TestCheckSumDownload/subtest1 === RUN TestCheckSumDownload/subtest2 === RUN TestCheckSumDownload/subtest3 === RUN TestCheckSumDownload/subtest4 === RUN TestCheckSumDownload/subtest5 === RUN TestCheckSumDownload/subtest6 === RUN TestCheckSumDownload/subtest7 --- PASS: TestCheckSumDownload (13.76s) --- PASS: TestCheckSumDownload/subtest1 (1.31s) --- PASS: TestCheckSumDownload/subtest2 (0.75s) --- PASS: TestCheckSumDownload/subtest3 (0.92s) --- PASS: TestCheckSumDownload/subtest4 (0.75s) --- PASS: TestCheckSumDownload/subtest5 (0.74s) --- PASS: TestCheckSumDownload/subtest6 (0.74s) --- PASS: TestCheckSumDownload/subtest7 (0.77s) === RUN TestApplyTransforms 2025/02/07 02:12:26 DEBUG : Creating backend with remote "TestB2:rclone-test-xadekih4wubo" 2025/02/07 02:12:26 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/02/07 02:12:26 DEBUG : Creating backend with remote "/tmp/rclone1025549090" run.go:180: Remote "B2 bucket rclone-test-xadekih4wubo", Local "Local file system at /tmp/rclone1025549090", Modify Window "1ms" upper checkfile vs. lower remote (without normalization) 2025/02/07 02:12:31 ERROR : hello, world!: sum not found 2025/02/07 02:12:31 ERROR : HELLO, WORLD!: file not in B2 bucket rclone-test-xadekih4wubo 2025/02/07 02:12:31 NOTICE: B2 bucket rclone-test-xadekih4wubo: 1 files missing 2025/02/07 02:12:31 NOTICE: 1 hashes missing 2025/02/07 02:12:31 NOTICE: B2 bucket rclone-test-xadekih4wubo: 2 differences found 2025/02/07 02:12:31 NOTICE: B2 bucket rclone-test-xadekih4wubo: 2 errors while checking upper checkfile vs. lower remote (with normalization) 2025/02/07 02:12:31 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/02/07 02:12:31 NOTICE: B2 bucket rclone-test-xadekih4wubo: 0 differences found 2025/02/07 02:12:31 NOTICE: B2 bucket rclone-test-xadekih4wubo: 1 matching files 2025/02/07 02:12:31 DEBUG : Creating backend with remote "TestB2:rclone-test-puricag2masa" 2025/02/07 02:12:31 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/02/07 02:12:32 DEBUG : Creating backend with remote "/tmp/rclone4098377806" run.go:180: Remote "B2 bucket rclone-test-puricag2masa", Local "Local file system at /tmp/rclone4098377806", Modify Window "1ms" lower checkfile vs. upper remote (without normalization) 2025/02/07 02:12:38 ERROR : HELLO, WORLD!: sum not found 2025/02/07 02:12:38 ERROR : hello, world!: file not in B2 bucket rclone-test-puricag2masa 2025/02/07 02:12:38 NOTICE: B2 bucket rclone-test-puricag2masa: 1 files missing 2025/02/07 02:12:38 NOTICE: 1 hashes missing 2025/02/07 02:12:38 NOTICE: B2 bucket rclone-test-puricag2masa: 2 differences found 2025/02/07 02:12:38 NOTICE: B2 bucket rclone-test-puricag2masa: 2 errors while checking lower checkfile vs. upper remote (with normalization) 2025/02/07 02:12:38 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/02/07 02:12:38 NOTICE: B2 bucket rclone-test-puricag2masa: 0 differences found 2025/02/07 02:12:38 NOTICE: B2 bucket rclone-test-puricag2masa: 1 matching files 2025/02/07 02:12:38 DEBUG : Creating backend with remote "TestB2:rclone-test-gayabec7duxo" 2025/02/07 02:12:38 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/02/07 02:12:39 DEBUG : Creating backend with remote "/tmp/rclone2433564079" run.go:180: Remote "B2 bucket rclone-test-gayabec7duxo", Local "Local file system at /tmp/rclone2433564079", Modify Window "1ms" lower checkfile vs. upperlowermixed remote (without normalization) 2025/02/07 02:12:44 ERROR : HeLlO, wOrLd!: sum not found 2025/02/07 02:12:44 ERROR : hello, world!: file not in B2 bucket rclone-test-gayabec7duxo 2025/02/07 02:12:44 NOTICE: B2 bucket rclone-test-gayabec7duxo: 1 files missing 2025/02/07 02:12:44 NOTICE: 1 hashes missing 2025/02/07 02:12:44 NOTICE: B2 bucket rclone-test-gayabec7duxo: 2 differences found 2025/02/07 02:12:44 NOTICE: B2 bucket rclone-test-gayabec7duxo: 2 errors while checking lower checkfile vs. upperlowermixed remote (with normalization) 2025/02/07 02:12:45 DEBUG : HeLlO, wOrLd!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/02/07 02:12:45 NOTICE: B2 bucket rclone-test-gayabec7duxo: 0 differences found 2025/02/07 02:12:45 NOTICE: B2 bucket rclone-test-gayabec7duxo: 1 matching files 2025/02/07 02:12:45 DEBUG : Creating backend with remote "TestB2:rclone-test-nizaxac7yeno" 2025/02/07 02:12:45 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/02/07 02:12:45 DEBUG : Creating backend with remote "/tmp/rclone3285098202" run.go:180: Remote "B2 bucket rclone-test-nizaxac7yeno", Local "Local file system at /tmp/rclone3285098202", Modify Window "1ms" upperlowermixed checkfile vs. upper remote (without normalization) 2025/02/07 02:12:50 ERROR : HELLO, WORLD!: sum not found 2025/02/07 02:12:50 ERROR : HeLlO, wOrLd!: file not in B2 bucket rclone-test-nizaxac7yeno 2025/02/07 02:12:50 NOTICE: B2 bucket rclone-test-nizaxac7yeno: 1 files missing 2025/02/07 02:12:50 NOTICE: 1 hashes missing 2025/02/07 02:12:50 NOTICE: B2 bucket rclone-test-nizaxac7yeno: 2 differences found 2025/02/07 02:12:50 NOTICE: B2 bucket rclone-test-nizaxac7yeno: 2 errors while checking upperlowermixed checkfile vs. upper remote (with normalization) 2025/02/07 02:12:51 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/02/07 02:12:51 NOTICE: B2 bucket rclone-test-nizaxac7yeno: 0 differences found 2025/02/07 02:12:51 NOTICE: B2 bucket rclone-test-nizaxac7yeno: 1 matching files 2025/02/07 02:12:51 DEBUG : Creating backend with remote "TestB2:rclone-test-cumexam3muha" 2025/02/07 02:12:51 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/02/07 02:12:52 DEBUG : Creating backend with remote "/tmp/rclone3904989001" run.go:180: Remote "B2 bucket rclone-test-cumexam3muha", Local "Local file system at /tmp/rclone3904989001", Modify Window "1ms" NFD checkfile vs. NFC remote (without normalization) 2025/02/07 02:12:57 ERROR : 測試_Русский___ě_áñ: sum not found 2025/02/07 02:12:57 ERROR : 測試_Русский___ě_áñ: file not in B2 bucket rclone-test-cumexam3muha 2025/02/07 02:12:57 NOTICE: B2 bucket rclone-test-cumexam3muha: 1 files missing 2025/02/07 02:12:57 NOTICE: 1 hashes missing 2025/02/07 02:12:57 NOTICE: B2 bucket rclone-test-cumexam3muha: 2 differences found 2025/02/07 02:12:57 NOTICE: B2 bucket rclone-test-cumexam3muha: 2 errors while checking NFD checkfile vs. NFC remote (with normalization) 2025/02/07 02:12:58 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/02/07 02:12:58 NOTICE: B2 bucket rclone-test-cumexam3muha: 0 differences found 2025/02/07 02:12:58 NOTICE: B2 bucket rclone-test-cumexam3muha: 1 matching files 2025/02/07 02:12:58 DEBUG : Creating backend with remote "TestB2:rclone-test-bapayub0hiro" 2025/02/07 02:12:58 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/02/07 02:12:59 DEBUG : Creating backend with remote "/tmp/rclone2339922104" run.go:180: Remote "B2 bucket rclone-test-bapayub0hiro", Local "Local file system at /tmp/rclone2339922104", Modify Window "1ms" NFC checkfile vs. NFD remote (without normalization) 2025/02/07 02:13:04 ERROR : 測試_Русский___ě_áñ: sum not found 2025/02/07 02:13:04 ERROR : 測試_Русский___ě_áñ: file not in B2 bucket rclone-test-bapayub0hiro 2025/02/07 02:13:04 NOTICE: B2 bucket rclone-test-bapayub0hiro: 1 files missing 2025/02/07 02:13:04 NOTICE: 1 hashes missing 2025/02/07 02:13:04 NOTICE: B2 bucket rclone-test-bapayub0hiro: 2 differences found 2025/02/07 02:13:04 NOTICE: B2 bucket rclone-test-bapayub0hiro: 2 errors while checking NFC checkfile vs. NFD remote (with normalization) 2025/02/07 02:13:05 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/02/07 02:13:05 NOTICE: B2 bucket rclone-test-bapayub0hiro: 0 differences found 2025/02/07 02:13:05 NOTICE: B2 bucket rclone-test-bapayub0hiro: 1 matching files 2025/02/07 02:13:05 DEBUG : Creating backend with remote "TestB2:rclone-test-wazibif0nibi" 2025/02/07 02:13:05 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/02/07 02:13:05 DEBUG : Creating backend with remote "/tmp/rclone68155682" run.go:180: Remote "B2 bucket rclone-test-wazibif0nibi", Local "Local file system at /tmp/rclone68155682", Modify Window "1ms" NFDx2 checkfile vs. both remote (without normalization) 2025/02/07 02:13:11 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2025/02/07 02:13:11 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-wazibif0nibi 2025/02/07 02:13:11 NOTICE: B2 bucket rclone-test-wazibif0nibi: 1 files missing 2025/02/07 02:13:11 NOTICE: 1 hashes missing 2025/02/07 02:13:11 NOTICE: B2 bucket rclone-test-wazibif0nibi: 2 differences found 2025/02/07 02:13:11 NOTICE: B2 bucket rclone-test-wazibif0nibi: 2 errors while checking NFDx2 checkfile vs. both remote (with normalization) 2025/02/07 02:13:11 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/02/07 02:13:11 NOTICE: B2 bucket rclone-test-wazibif0nibi: 0 differences found 2025/02/07 02:13:11 NOTICE: B2 bucket rclone-test-wazibif0nibi: 1 matching files 2025/02/07 02:13:11 DEBUG : Creating backend with remote "TestB2:rclone-test-senuwop6gaxi" 2025/02/07 02:13:11 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/02/07 02:13:12 DEBUG : Creating backend with remote "/tmp/rclone123890558" run.go:180: Remote "B2 bucket rclone-test-senuwop6gaxi", Local "Local file system at /tmp/rclone123890558", Modify Window "1ms" NFCx2 checkfile vs. both remote (without normalization) 2025/02/07 02:13:17 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2025/02/07 02:13:17 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-senuwop6gaxi 2025/02/07 02:13:17 NOTICE: B2 bucket rclone-test-senuwop6gaxi: 1 files missing 2025/02/07 02:13:17 NOTICE: 1 hashes missing 2025/02/07 02:13:17 NOTICE: B2 bucket rclone-test-senuwop6gaxi: 2 differences found 2025/02/07 02:13:17 NOTICE: B2 bucket rclone-test-senuwop6gaxi: 2 errors while checking NFCx2 checkfile vs. both remote (with normalization) 2025/02/07 02:13:18 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/02/07 02:13:18 NOTICE: B2 bucket rclone-test-senuwop6gaxi: 0 differences found 2025/02/07 02:13:18 NOTICE: B2 bucket rclone-test-senuwop6gaxi: 1 matching files 2025/02/07 02:13:18 DEBUG : Creating backend with remote "TestB2:rclone-test-dixitus2xofo" 2025/02/07 02:13:18 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/02/07 02:13:18 DEBUG : Creating backend with remote "/tmp/rclone3908551965" run.go:180: Remote "B2 bucket rclone-test-dixitus2xofo", Local "Local file system at /tmp/rclone3908551965", Modify Window "1ms" both checkfile vs. NFDx2 remote (without normalization) 2025/02/07 02:13:23 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2025/02/07 02:13:23 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-dixitus2xofo 2025/02/07 02:13:23 NOTICE: B2 bucket rclone-test-dixitus2xofo: 1 files missing 2025/02/07 02:13:23 NOTICE: 1 hashes missing 2025/02/07 02:13:23 NOTICE: B2 bucket rclone-test-dixitus2xofo: 2 differences found 2025/02/07 02:13:23 NOTICE: B2 bucket rclone-test-dixitus2xofo: 2 errors while checking both checkfile vs. NFDx2 remote (with normalization) 2025/02/07 02:13:24 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/02/07 02:13:24 NOTICE: B2 bucket rclone-test-dixitus2xofo: 0 differences found 2025/02/07 02:13:24 NOTICE: B2 bucket rclone-test-dixitus2xofo: 1 matching files 2025/02/07 02:13:24 DEBUG : Creating backend with remote "TestB2:rclone-test-damuheg3rene" 2025/02/07 02:13:24 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/02/07 02:13:25 DEBUG : Creating backend with remote "/tmp/rclone3482801611" run.go:180: Remote "B2 bucket rclone-test-damuheg3rene", Local "Local file system at /tmp/rclone3482801611", Modify Window "1ms" both checkfile vs. NFCx2 remote (without normalization) 2025/02/07 02:13:30 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2025/02/07 02:13:30 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-damuheg3rene 2025/02/07 02:13:30 NOTICE: B2 bucket rclone-test-damuheg3rene: 1 files missing 2025/02/07 02:13:30 NOTICE: 1 hashes missing 2025/02/07 02:13:30 NOTICE: B2 bucket rclone-test-damuheg3rene: 2 differences found 2025/02/07 02:13:30 NOTICE: B2 bucket rclone-test-damuheg3rene: 2 errors while checking both checkfile vs. NFCx2 remote (with normalization) 2025/02/07 02:13:31 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/02/07 02:13:31 NOTICE: B2 bucket rclone-test-damuheg3rene: 0 differences found 2025/02/07 02:13:31 NOTICE: B2 bucket rclone-test-damuheg3rene: 1 matching files 2025/02/07 02:13:31 DEBUG : B2 bucket rclone-test-damuheg3rene: Purge remote 2025/02/07 02:13:31 INFO : B2 bucket rclone-test-damuheg3rene: cleaning bucket "rclone-test-damuheg3rene" of all files 2025/02/07 02:13:31 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_z3f82fd0320eddbeb9f480e1b_f1033a9721ea932ba_d20250207_m021329_c001_v0001184_t0006_u01738894409912") 2025/02/07 02:13:32 DEBUG : B2 bucket rclone-test-dixitus2xofo: Purge remote 2025/02/07 02:13:32 INFO : B2 bucket rclone-test-dixitus2xofo: cleaning bucket "rclone-test-dixitus2xofo" of all files 2025/02/07 02:13:33 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_z2f62dd7320eddbeb9f480e1b_f106c708d656d5ec6_d20250207_m021323_c001_v0001172_t0030_u01738894403311") 2025/02/07 02:13:36 DEBUG : B2 bucket rclone-test-senuwop6gaxi: Purge remote 2025/02/07 02:13:36 INFO : B2 bucket rclone-test-senuwop6gaxi: cleaning bucket "rclone-test-senuwop6gaxi" of all files 2025/02/07 02:13:36 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_z4f52bde320eddbeb9f480e1b_f100fcf18b5a358b2_d20250207_m021316_c001_v0001184_t0056_u01738894396980") 2025/02/07 02:13:39 DEBUG : B2 bucket rclone-test-wazibif0nibi: Purge remote 2025/02/07 02:13:39 INFO : B2 bucket rclone-test-wazibif0nibi: cleaning bucket "rclone-test-wazibif0nibi" of all files 2025/02/07 02:13:39 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_z1f52ad4320eddbeb9f480e1b_f10481b5c970f750c_d20250207_m021310_c001_v0001106_t0029_u01738894390316") 2025/02/07 02:13:42 DEBUG : B2 bucket rclone-test-bapayub0hiro: Purge remote 2025/02/07 02:13:42 INFO : B2 bucket rclone-test-bapayub0hiro: cleaning bucket "rclone-test-bapayub0hiro" of all files 2025/02/07 02:13:42 DEBUG : 測試_Русский___ě_áñ: Deleting (id "4_zafc28da320eddbeb9f480e1b_f1051cb62e42760e0_d20250207_m021303_c001_v0001157_t0017_u01738894383726") 2025/02/07 02:13:45 DEBUG : B2 bucket rclone-test-cumexam3muha: Purge remote 2025/02/07 02:13:45 INFO : B2 bucket rclone-test-cumexam3muha: cleaning bucket "rclone-test-cumexam3muha" of all files 2025/02/07 02:13:45 DEBUG : 測試_Русский___ě_áñ: Deleting (id "4_z9f026df320eddbeb9f480e1b_f115f799b9663e36f_d20250207_m021257_c001_v0001128_t0047_u01738894377231") 2025/02/07 02:13:48 DEBUG : B2 bucket rclone-test-nizaxac7yeno: Purge remote 2025/02/07 02:13:48 INFO : B2 bucket rclone-test-nizaxac7yeno: cleaning bucket "rclone-test-nizaxac7yeno" of all files 2025/02/07 02:13:48 DEBUG : HELLO, WORLD!: Deleting (id "4_zaf125d6320eddbeb9f480e1b_f1169c9cba22dc298_d20250207_m021250_c001_v0001182_t0009_u01738894370383") 2025/02/07 02:13:51 DEBUG : B2 bucket rclone-test-gayabec7duxo: Purge remote 2025/02/07 02:13:51 INFO : B2 bucket rclone-test-gayabec7duxo: cleaning bucket "rclone-test-gayabec7duxo" of all files 2025/02/07 02:13:52 DEBUG : HeLlO, wOrLd!: Deleting (id "4_z0f823dd320eddbeb9f480e1b_f10372e3e3be37fc4_d20250207_m021243_c001_v0001130_t0053_u01738894363962") 2025/02/07 02:13:54 DEBUG : B2 bucket rclone-test-puricag2masa: Purge remote 2025/02/07 02:13:54 INFO : B2 bucket rclone-test-puricag2masa: cleaning bucket "rclone-test-puricag2masa" of all files 2025/02/07 02:13:55 DEBUG : HELLO, WORLD!: Deleting (id "4_z3fa22d3320eddbeb9f480e1b_f10507038c857335c_d20250207_m021237_c001_v0001184_t0034_u01738894357548") 2025/02/07 02:13:57 DEBUG : B2 bucket rclone-test-xadekih4wubo: Purge remote 2025/02/07 02:13:57 INFO : B2 bucket rclone-test-xadekih4wubo: cleaning bucket "rclone-test-xadekih4wubo" of all files 2025/02/07 02:13:58 DEBUG : hello, world!: Deleting (id "4_z4f120dc320eddbeb9f480e1b_f116500f03be29535_d20250207_m021230_c001_v0001100_t0045_u01738894350447") --- PASS: TestApplyTransforms (94.71s) === RUN TestTruncateString --- PASS: TestTruncateString (0.00s) === RUN TestCopyFile run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" 2025/02/07 02:14:01 DEBUG : file1: Need to transfer - File not found at Destination 2025/02/07 02:14:02 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2025/02/07 02:14:02 INFO : file1: Copied (new) to: sub/file2 2025/02/07 02:14:02 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/07 02:14:02 DEBUG : file1: Unchanged skipping 2025/02/07 02:14:03 DEBUG : B2 bucket rclone-test-jotegax4kaqu: don't need to copy/move sub/file2, it is already at target location --- PASS: TestCopyFile (2.95s) === RUN TestCopyLongFile run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" copy_test.go:153: Test only runs on local --- SKIP: TestCopyLongFile (0.37s) === RUN TestCopyFileBackupDir run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" 2025/02/07 02:14:04 DEBUG : Creating backend with remote "TestB2:rclone-test-jotegax4kaqu/backup" 2025/02/07 02:14:05 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18) 2025/02/07 02:14:07 DEBUG : dst/file1: sha1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK 2025/02/07 02:14:07 INFO : dst/file1: Copied (server-side copy) 2025/02/07 02:14:07 INFO : dst/file1: Deleted 2025/02/07 02:14:07 DEBUG : dst/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2025/02/07 02:14:07 INFO : dst/file1: Copied (new) --- PASS: TestCopyFileBackupDir (4.42s) === RUN TestCopyFileCompareDest run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" 2025/02/07 02:14:08 DEBUG : Creating backend with remote "TestB2:rclone-test-jotegax4kaqu/dst" 2025/02/07 02:14:09 DEBUG : Creating backend with remote "TestB2:rclone-test-jotegax4kaqu/CompareDest" 2025/02/07 02:14:11 DEBUG : one: Need to transfer - File not found at Destination 2025/02/07 02:14:13 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2025/02/07 02:14:13 INFO : one: Copied (new) 2025/02/07 02:14:13 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/02/07 02:14:14 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2025/02/07 02:14:14 INFO : one: Copied (replaced existing) 2025/02/07 02:14:16 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/02/07 02:14:16 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/02/07 02:14:16 DEBUG : one: Destination found in --compare-dest, skipping 2025/02/07 02:14:17 DEBUG : two: Need to transfer - File not found at Destination 2025/02/07 02:14:17 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/02/07 02:14:17 DEBUG : two: Destination found in --compare-dest, skipping 2025/02/07 02:14:17 DEBUG : two: Need to transfer - File not found at Destination 2025/02/07 02:14:17 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/02/07 02:14:17 DEBUG : two: Destination found in --compare-dest, skipping 2025/02/07 02:14:18 DEBUG : two: Need to transfer - File not found at Destination 2025/02/07 02:14:18 DEBUG : two: Sizes differ (src 5 vs dst 3) 2025/02/07 02:14:19 DEBUG : two: sha1 = a4be8877ad88f3109a6411985a1f359e10e2d930 OK 2025/02/07 02:14:19 INFO : two: Copied (new) --- PASS: TestCopyFileCompareDest (11.88s) === RUN TestCopyFileCopyDest run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" 2025/02/07 02:14:20 DEBUG : Creating backend with remote "TestB2:rclone-test-jotegax4kaqu/dst" 2025/02/07 02:14:21 DEBUG : Creating backend with remote "TestB2:rclone-test-jotegax4kaqu/CopyDest" 2025/02/07 02:14:23 DEBUG : one: Need to transfer - File not found at Destination 2025/02/07 02:14:25 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2025/02/07 02:14:25 INFO : one: Copied (new) 2025/02/07 02:14:25 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/02/07 02:14:26 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2025/02/07 02:14:26 INFO : one: Copied (replaced existing) 2025/02/07 02:14:27 DEBUG : Creating backend with remote "TestB2:rclone-test-jotegax4kaqu/BackupDir" 2025/02/07 02:14:28 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/02/07 02:14:29 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/02/07 02:14:29 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/02/07 02:14:30 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2025/02/07 02:14:30 INFO : one: Copied (server-side copy) 2025/02/07 02:14:30 INFO : one: Deleted 2025/02/07 02:14:31 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2025/02/07 02:14:31 INFO : one: Copied (server-side copy) 2025/02/07 02:14:31 DEBUG : one: Destination found in --copy-dest, using server-side copy 2025/02/07 02:14:32 DEBUG : two: Need to transfer - File not found at Destination 2025/02/07 02:14:32 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/02/07 02:14:32 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2025/02/07 02:14:32 INFO : two: Copied (server-side copy) 2025/02/07 02:14:32 DEBUG : two: Destination found in --copy-dest, using server-side copy 2025/02/07 02:14:32 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/02/07 02:14:32 DEBUG : two: Unchanged skipping 2025/02/07 02:14:33 DEBUG : three: Need to transfer - File not found at Destination 2025/02/07 02:14:34 DEBUG : three: Sizes differ (src 7 vs dst 5) 2025/02/07 02:14:34 DEBUG : three: Destination not found in --copy-dest 2025/02/07 02:14:34 DEBUG : three: sha1 = 370e613335f3cf2f19b2a2d35a86402f55c659f8 OK 2025/02/07 02:14:34 INFO : three: Copied (new) --- PASS: TestCopyFileCopyDest (16.10s) === RUN TestCopyInplace run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" copy_test.go:370: Partial uploads not supported --- SKIP: TestCopyInplace (0.37s) === RUN TestCopyLongFileName run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" copy_test.go:403: Partial uploads not supported --- SKIP: TestCopyLongFileName (0.36s) === RUN TestCopyFileMaxTransfer run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" 2025/02/07 02:14:37 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination 2025/02/07 02:14:38 DEBUG : TestCopyFileMaxTransfer/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2025/02/07 02:14:38 INFO : TestCopyFileMaxTransfer/file1: Copied (new) 2025/02/07 02:14:38 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination 2025/02/07 02:14:38 ERROR : TestCopyFileMaxTransfer/file2: Failed to copy: Post "https://pod-000-1146-03.backblaze.com/b2api/v1/b2_upload_file/4f229d93209ddbeb9f480e1b/c001_v0001146_t0042": max transfer limit reached as set by --max-transfer 2025/02/07 02:14:39 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination 2025/02/07 02:14:39 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination 2025/02/07 02:14:40 DEBUG : TestCopyFileMaxTransfer/file4: sha1 = 0470185e2e4d3be729d7be02471765be61345d71 OK 2025/02/07 02:14:40 INFO : TestCopyFileMaxTransfer/file4: Copied (new) --- PASS: TestCopyFileMaxTransfer (4.07s) === RUN TestDeduplicateInteractive run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateInteractive (0.37s) === RUN TestDeduplicateSkip run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSkip (0.37s) === RUN TestDeduplicateSizeOnly run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSizeOnly (0.37s) === RUN TestDeduplicateFirst run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateFirst (0.36s) === RUN TestDeduplicateNewest run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateNewest (0.37s) === RUN TestDeduplicateNewestByHash run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" 2025/02/07 02:14:44 INFO : B2 bucket rclone-test-jotegax4kaqu: Looking for duplicate sha1 hashes using newest mode. 2025/02/07 02:14:45 NOTICE: bba488a2a3065de627add812f276af64fbe0b318: Found 3 files with duplicate sha1 hashes 2025/02/07 02:14:45 INFO : one: Deleted 2025/02/07 02:14:45 INFO : also/one: Deleted 2025/02/07 02:14:45 NOTICE: bba488a2a3065de627add812f276af64fbe0b318: Deleted 2 extra copies --- PASS: TestDeduplicateNewestByHash (3.05s) === RUN TestDeduplicateOldest run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateOldest (0.36s) === RUN TestDeduplicateLargest run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateLargest (0.37s) === RUN TestDeduplicateSmallest run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSmallest (0.37s) === RUN TestDeduplicateRename run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateRename (0.37s) === RUN TestMergeDirs run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" dedupe_test.go:256: Can't merge directories --- SKIP: TestMergeDirs (0.37s) === RUN TestListDirSorted run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" 2025/02/07 02:14:51 DEBUG : a.txt: Excluded (Size Filter) 2025/02/07 02:14:51 DEBUG : a.txt: Excluded 2025/02/07 02:14:52 DEBUG : sub dir/hello world: Excluded (Size Filter) 2025/02/07 02:14:52 DEBUG : sub dir/hello world: Excluded 2025/02/07 02:14:52 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2025/02/07 02:14:52 DEBUG : sub dir/hello world2: Excluded 2025/02/07 02:14:52 DEBUG : sub dir/hello world: Excluded (Size Filter) 2025/02/07 02:14:52 DEBUG : sub dir/hello world: Excluded 2025/02/07 02:14:52 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2025/02/07 02:14:52 DEBUG : sub dir/hello world2: Excluded 2025/02/07 02:14:53 DEBUG : sub dir/ignore dir: Excluded 2025/02/07 02:14:53 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSorted (7.68s) === RUN TestListJSON run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" === RUN TestListJSON/Default === RUN TestListJSON/FilesOnly === RUN TestListJSON/DirsOnly === RUN TestListJSON/Recurse === RUN TestListJSON/SubDir === RUN TestListJSON/NoModTime === RUN TestListJSON/NoMimeType === RUN TestListJSON/ShowHash === RUN TestListJSON/HashTypes 2025/02/07 02:14:58 ERROR : file1: Failed to read hash: hash type not supported === RUN TestListJSON/Metadata --- PASS: TestListJSON (3.59s) --- PASS: TestListJSON/Default (0.18s) --- PASS: TestListJSON/FilesOnly (0.18s) --- PASS: TestListJSON/DirsOnly (0.19s) --- PASS: TestListJSON/Recurse (0.18s) --- PASS: TestListJSON/SubDir (0.19s) --- PASS: TestListJSON/NoModTime (0.19s) --- PASS: TestListJSON/NoMimeType (0.18s) --- PASS: TestListJSON/ShowHash (0.18s) --- PASS: TestListJSON/HashTypes (0.18s) --- PASS: TestListJSON/Metadata (0.18s) === RUN TestStatJSON run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" === RUN TestStatJSON/Root === RUN TestStatJSON/RootFilesOnly === RUN TestStatJSON/RootDirsOnly === RUN TestStatJSON/Dir === RUN TestStatJSON/DirWithTrailingSlash === RUN TestStatJSON/File === RUN TestStatJSON/NotFound === RUN TestStatJSON/DirFilesOnly === RUN TestStatJSON/FileFilesOnly === RUN TestStatJSON/NotFoundFilesOnly === RUN TestStatJSON/DirDirsOnly === RUN TestStatJSON/FileDirsOnly === RUN TestStatJSON/NotFoundDirsOnly === RUN TestStatJSON/RootNotFound 2025/02/07 02:15:03 DEBUG : Creating backend with remote "TestB2:rclone-test-jotegax4kaqu/notfound" 2025/02/07 02:15:03 DEBUG : Config file has changed externally - reloading --- PASS: TestStatJSON (6.29s) --- PASS: TestStatJSON/Root (0.18s) --- PASS: TestStatJSON/RootFilesOnly (0.00s) --- PASS: TestStatJSON/RootDirsOnly (0.18s) --- PASS: TestStatJSON/Dir (0.72s) --- PASS: TestStatJSON/DirWithTrailingSlash (0.18s) --- PASS: TestStatJSON/File (0.18s) --- PASS: TestStatJSON/NotFound (0.36s) --- PASS: TestStatJSON/DirFilesOnly (0.18s) --- PASS: TestStatJSON/FileFilesOnly (0.19s) --- PASS: TestStatJSON/NotFoundFilesOnly (0.18s) --- PASS: TestStatJSON/DirDirsOnly (0.18s) --- PASS: TestStatJSON/FileDirsOnly (0.18s) --- PASS: TestStatJSON/NotFoundDirsOnly (0.18s) --- PASS: TestStatJSON/RootNotFound (1.87s) === RUN TestMkdir run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" 2025/02/07 02:15:05 INFO : B2 bucket rclone-test-jotegax4kaqu: Making directory 2025/02/07 02:15:05 INFO : B2 bucket rclone-test-jotegax4kaqu: Making directory --- PASS: TestMkdir (0.56s) === RUN TestLsd run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" --- PASS: TestLsd (1.70s) === RUN TestLs run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" --- PASS: TestLs (1.66s) === RUN TestLsWithFilesFrom run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" 2025/02/07 02:15:10 DEBUG : empty space: Excluded (FilesFrom Filter) 2025/02/07 02:15:10 DEBUG : empty space: Excluded --- PASS: TestLsWithFilesFrom (2.31s) === RUN TestLsLong run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" --- PASS: TestLsLong (1.81s) === RUN TestHashSums run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" === RUN TestHashSums/Sha1 === RUN TestHashSums/Sha1Download --- PASS: TestHashSums (2.20s) --- PASS: TestHashSums/Sha1 (0.19s) --- PASS: TestHashSums/Sha1Download (0.38s) === RUN TestHashSumsWithErrors 2025/02/07 02:15:15 DEBUG : Creating backend with remote ":memory:" 2025/02/07 02:15:15 DEBUG : Config file has changed externally - reloading 2025/02/07 02:15:15 ERROR : file1: hash unsupported: hash type not supported --- PASS: TestHashSumsWithErrors (0.00s) === RUN TestHashStream 2025/02/07 02:15:15 DEBUG : Creating md5 hash of 0 bytes read from input stream 2025/02/07 02:15:15 DEBUG : Creating md5 hash of 0 bytes read from input stream 2025/02/07 02:15:15 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2025/02/07 02:15:15 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2025/02/07 02:15:15 DEBUG : Creating md5 hash of 12 bytes read from input stream 2025/02/07 02:15:15 DEBUG : Creating md5 hash of 12 bytes read from input stream 2025/02/07 02:15:15 DEBUG : Creating sha1 hash of 12 bytes read from input stream 2025/02/07 02:15:15 DEBUG : Creating sha1 hash of 12 bytes read from input stream --- PASS: TestHashStream (0.00s) === RUN TestSuffixName --- PASS: TestSuffixName (0.00s) === RUN TestCount run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" --- PASS: TestCount (2.49s) === RUN TestDelete run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" 2025/02/07 02:15:19 DEBUG : Waiting for deletions to finish 2025/02/07 02:15:19 DEBUG : large: Excluded (Size Filter) 2025/02/07 02:15:20 INFO : medium: Deleted 2025/02/07 02:15:20 INFO : small: Deleted --- PASS: TestDelete (2.60s) === RUN TestMaxDelete run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" 2025/02/07 02:15:22 DEBUG : Waiting for deletions to finish 2025/02/07 02:15:22 ERROR : small: Got fatal error on delete: --max-delete threshold reached 2025/02/07 02:15:22 INFO : large: Deleted 2025/02/07 02:15:22 INFO : medium: Deleted --- PASS: TestMaxDelete (2.83s) === RUN TestMaxDeleteSizeLargeFile run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" 2025/02/07 02:15:25 DEBUG : Waiting for deletions to finish 2025/02/07 02:15:25 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached 2025/02/07 02:15:25 INFO : medium: Deleted 2025/02/07 02:15:25 INFO : small: Deleted --- PASS: TestMaxDeleteSizeLargeFile (2.89s) === RUN TestMaxDeleteSize run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" 2025/02/07 02:15:27 DEBUG : Waiting for deletions to finish 2025/02/07 02:15:28 ERROR : small: Got fatal error on delete: --max-delete-size threshold reached 2025/02/07 02:15:28 INFO : large: Deleted 2025/02/07 02:15:28 INFO : medium: Deleted --- PASS: TestMaxDeleteSize (2.68s) === RUN TestReadFile run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" --- PASS: TestReadFile (2.44s) === RUN TestRetry 2025/02/07 02:15:31 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 1/5 2025/02/07 02:15:31 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 2/5 2025/02/07 02:15:31 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG 2025/02/07 02:15:31 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG 2025/02/07 02:15:31 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG 2025/02/07 02:15:31 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG 2025/02/07 02:15:31 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG --- PASS: TestRetry (0.05s) === RUN TestCat run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" --- PASS: TestCat (4.24s) === RUN TestPurge 2025/02/07 02:15:36 DEBUG : Creating backend with remote "TestB2:rclone-test-logalek5kiba" 2025/02/07 02:15:36 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/02/07 02:15:36 DEBUG : Creating backend with remote "/tmp/rclone3228998311" run.go:180: Remote "B2 bucket rclone-test-logalek5kiba", Local "Local file system at /tmp/rclone3228998311", Modify Window "1ms" 2025/02/07 02:15:41 INFO : A2: Making directory 2025/02/07 02:15:41 INFO : A1/B2: Making directory 2025/02/07 02:15:41 INFO : A1/B2/C2: Making directory 2025/02/07 02:15:41 INFO : A1/B1/C3: Making directory 2025/02/07 02:15:41 INFO : A3: Making directory 2025/02/07 02:15:41 INFO : A3/B3: Making directory 2025/02/07 02:15:41 INFO : A3/B3/C4: Making directory fstest.go:244: Filtering empty directory "A2" fstest.go:244: Filtering empty directory "A1/B2" fstest.go:244: Filtering empty directory "A1/B2/C2" fstest.go:244: Filtering empty directory "A1/B1/C3" fstest.go:244: Filtering empty directory "A3" fstest.go:244: Filtering empty directory "A3/B3" fstest.go:244: Filtering empty directory "A3/B3/C4" 2025/02/07 02:15:41 INFO : B2 bucket rclone-test-logalek5kiba: cleaning bucket "rclone-test-logalek5kiba" of all files 2025/02/07 02:15:42 DEBUG : A1/B1/C1/one: Deleting (id "4_zefa2fd13300ddbeb9f480e1b_f112f9ffe4a25dff0_d20250207_m021541_c001_v0001184_t0054_u01738894541325") fstest.go:244: Filtering empty directory "A2" fstest.go:244: Filtering empty directory "A1/B2" fstest.go:244: Filtering empty directory "A1/B2/C2" fstest.go:244: Filtering empty directory "A3" fstest.go:244: Filtering empty directory "A3/B3" fstest.go:244: Filtering empty directory "A3/B3/C4" 2025/02/07 02:15:43 INFO : B2 bucket rclone-test-logalek5kiba: cleaning bucket "rclone-test-logalek5kiba" of all files 2025/02/07 02:15:43 DEBUG : A1/two: Deleting (id "4_zefa2fd13300ddbeb9f480e1b_f112f9ffe4a25dffb_d20250207_m021541_c001_v0001184_t0054_u01738894541529") 2025/02/07 02:15:47 DEBUG : B2 bucket rclone-test-logalek5kiba: Purge remote 2025/02/07 02:15:47 INFO : B2 bucket rclone-test-logalek5kiba: cleaning bucket "rclone-test-logalek5kiba" of all files 2025/02/07 02:15:47 NOTICE: purge failed: directory not found --- PASS: TestPurge (11.74s) === RUN TestRmdirsNoLeaveRoot run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" 2025/02/07 02:15:48 INFO : A2: Making directory 2025/02/07 02:15:48 INFO : A1/B2: Making directory 2025/02/07 02:15:48 INFO : A1/B2/C2: Making directory 2025/02/07 02:15:48 INFO : A1/B1/C3: Making directory 2025/02/07 02:15:48 INFO : A3: Making directory 2025/02/07 02:15:48 INFO : A3/B3: Making directory 2025/02/07 02:15:48 INFO : A3/B3/C4: Making directory fstest.go:244: Filtering empty directory "A2" fstest.go:244: Filtering empty directory "A1/B2" fstest.go:244: Filtering empty directory "A1/B2/C2" fstest.go:244: Filtering empty directory "A1/B1/C3" fstest.go:244: Filtering empty directory "A3" fstest.go:244: Filtering empty directory "A3/B3" fstest.go:244: Filtering empty directory "A3/B3/C4" 2025/02/07 02:15:49 DEBUG : removing 1 level 3 directories 2025/02/07 02:15:49 INFO : A3/B3/C4: Removing directory fstest.go:244: Filtering empty directory "A2" fstest.go:244: Filtering empty directory "A1/B2" fstest.go:244: Filtering empty directory "A1/B2/C2" fstest.go:244: Filtering empty directory "A1/B1/C3" fstest.go:244: Filtering empty directory "A3" fstest.go:244: Filtering empty directory "A3/B3" 2025/02/07 02:15:52 DEBUG : removing 1 level 0 directories 2025/02/07 02:15:52 INFO : B2 bucket rclone-test-jotegax4kaqu: Removing directory 2025/02/07 02:15:52 ERROR : : Failed to rmdir: failed to delete bucket: Cannot delete non-empty bucket (400 cannot_delete_non_empty_bucket) operations_test.go:728: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:728 Error: Received unexpected error: failed to remove directories: failed to delete bucket: Cannot delete non-empty bucket (400 cannot_delete_non_empty_bucket) Test: TestRmdirsNoLeaveRoot --- FAIL: TestRmdirsNoLeaveRoot (4.86s) === RUN TestRmdirsLeaveRoot run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" 2025/02/07 02:15:52 INFO : A1: Making directory 2025/02/07 02:15:52 INFO : A1/B1: Making directory 2025/02/07 02:15:52 INFO : A1/B1/C1: Making directory fstest.go:244: Filtering empty directory "A1" fstest.go:244: Filtering empty directory "A1/B1" fstest.go:244: Filtering empty directory "A1/B1/C1" fstest.go:244: Filtering empty directory "A1" --- PASS: TestRmdirsLeaveRoot (0.91s) === RUN TestRmdirsWithFilter run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" 2025/02/07 02:15:53 INFO : A1: Making directory 2025/02/07 02:15:53 INFO : A1/B1: Making directory 2025/02/07 02:15:53 INFO : A1/B1/C1: Making directory fstest.go:244: Filtering empty directory "A1" fstest.go:244: Filtering empty directory "A1/B1" fstest.go:244: Filtering empty directory "A1/B1/C1" fstest.go:244: Filtering empty directory "A1" --- PASS: TestRmdirsWithFilter (0.92s) === RUN TestCopyURL run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" 2025/02/07 02:15:55 DEBUG : filename.txt: File name found in url 2025/02/07 02:15:56 DEBUG : headerfilename.txt: filename found in Content-Disposition header. --- PASS: TestCopyURL (3.25s) === RUN TestCopyURLToWriter --- PASS: TestCopyURLToWriter (0.00s) === RUN TestMoveFile run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" 2025/02/07 02:15:58 DEBUG : file1: Need to transfer - File not found at Destination 2025/02/07 02:15:58 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2025/02/07 02:15:58 INFO : file1: Copied (new) to: sub/file2 2025/02/07 02:15:58 INFO : file1: Deleted 2025/02/07 02:15:58 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/07 02:15:58 DEBUG : file1: Unchanged skipping 2025/02/07 02:15:58 INFO : file1: Deleted 2025/02/07 02:15:59 DEBUG : B2 bucket rclone-test-jotegax4kaqu: don't need to copy/move sub/file2, it is already at target location --- PASS: TestMoveFile (1.96s) === RUN TestMoveFileWithIgnoreExisting run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" 2025/02/07 02:15:59 DEBUG : file1: Need to transfer - File not found at Destination 2025/02/07 02:16:00 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2025/02/07 02:16:00 INFO : file1: Copied (new) 2025/02/07 02:16:00 INFO : file1: Deleted 2025/02/07 02:16:00 DEBUG : file1: Destination exists, skipping 2025/02/07 02:16:00 DEBUG : file1: Not removing source file as destination file exists and --ignore-existing is set --- PASS: TestMoveFileWithIgnoreExisting (1.77s) === RUN TestCaseInsensitiveMoveFile run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFile (0.36s) === RUN TestCaseInsensitiveMoveFileDryRun run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFileDryRun (0.36s) === RUN TestMoveFileBackupDir run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" 2025/02/07 02:16:02 DEBUG : Creating backend with remote "TestB2:rclone-test-jotegax4kaqu/backup" 2025/02/07 02:16:02 DEBUG : Config file has changed externally - reloading 2025/02/07 02:16:04 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18) 2025/02/07 02:16:05 DEBUG : dst/file1: sha1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK 2025/02/07 02:16:05 INFO : dst/file1: Copied (server-side copy) 2025/02/07 02:16:05 INFO : dst/file1: Deleted 2025/02/07 02:16:05 DEBUG : dst/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2025/02/07 02:16:05 INFO : dst/file1: Copied (new) 2025/02/07 02:16:05 INFO : dst/file1: Deleted --- PASS: TestMoveFileBackupDir (4.60s) === RUN TestSameConfig --- PASS: TestSameConfig (0.00s) === RUN TestSame --- PASS: TestSame (0.00s) === RUN TestOverlappingFilterCheckWithoutFilter --- PASS: TestOverlappingFilterCheckWithoutFilter (0.00s) === RUN TestOverlappingFilterCheckWithFilter --- PASS: TestOverlappingFilterCheckWithFilter (0.00s) === RUN TestListFormat --- PASS: TestListFormat (0.00s) === RUN TestDirMove run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" 2025/02/07 02:16:08 INFO : A1/B2: Making directory 2025/02/07 02:16:08 INFO : A1/B1/C3: Making directory fstest.go:244: Filtering empty directory "A1/B2" fstest.go:244: Filtering empty directory "A1/B1/C3" 2025/02/07 02:16:10 DEBUG : A1/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2025/02/07 02:16:10 INFO : A1/B1/C1/four: Copied (server-side copy) to: A2/B1/C1/four 2025/02/07 02:16:10 DEBUG : A1/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2025/02/07 02:16:10 INFO : A1/one: Copied (server-side copy) to: A2/one 2025/02/07 02:16:10 DEBUG : A1/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2025/02/07 02:16:10 INFO : A1/B1/three: Copied (server-side copy) to: A2/B1/three 2025/02/07 02:16:10 INFO : A1/B1/C1/four: Deleted 2025/02/07 02:16:10 DEBUG : A1/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2025/02/07 02:16:10 INFO : A1/two: Copied (server-side copy) to: A2/two 2025/02/07 02:16:10 INFO : A1/one: Deleted 2025/02/07 02:16:10 INFO : A1/B1/three: Deleted 2025/02/07 02:16:10 DEBUG : A1/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2025/02/07 02:16:10 INFO : A1/B1/C2/five: Copied (server-side copy) to: A2/B1/C2/five 2025/02/07 02:16:10 INFO : A1/two: Deleted 2025/02/07 02:16:10 INFO : A1/B1/C2/five: Deleted fstest.go:244: Filtering empty directory "A2/B2" fstest.go:244: Filtering empty directory "A2/B1/C3" 2025/02/07 02:16:11 DEBUG : A2/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2025/02/07 02:16:11 INFO : A2/two: Copied (server-side copy) to: A3/two 2025/02/07 02:16:11 DEBUG : A2/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2025/02/07 02:16:11 INFO : A2/B1/C2/five: Copied (server-side copy) to: A3/B1/C2/five 2025/02/07 02:16:11 DEBUG : A2/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2025/02/07 02:16:11 INFO : A2/B1/C1/four: Copied (server-side copy) to: A3/B1/C1/four 2025/02/07 02:16:11 INFO : A2/two: Deleted 2025/02/07 02:16:11 DEBUG : A2/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2025/02/07 02:16:11 INFO : A2/B1/three: Copied (server-side copy) to: A3/B1/three 2025/02/07 02:16:11 INFO : A2/B1/C2/five: Deleted 2025/02/07 02:16:11 INFO : A2/B1/C1/four: Deleted 2025/02/07 02:16:11 INFO : A2/B1/three: Deleted 2025/02/07 02:16:12 DEBUG : A2/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2025/02/07 02:16:12 INFO : A2/one: Copied (server-side copy) to: A3/one 2025/02/07 02:16:12 INFO : A2/one: Deleted fstest.go:244: Filtering empty directory "A3/B2" fstest.go:244: Filtering empty directory "A3/B1/C3" 2025/02/07 02:16:12 INFO : B2 bucket rclone-test-jotegax4kaqu: Can't DirMove - falling back to file moves: can't move directory - incompatible remotes 2025/02/07 02:16:13 DEBUG : A3/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2025/02/07 02:16:13 INFO : A3/two: Copied (server-side copy) to: A4/two 2025/02/07 02:16:13 DEBUG : A3/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2025/02/07 02:16:13 INFO : A3/B1/C2/five: Copied (server-side copy) to: A4/B1/C2/five 2025/02/07 02:16:13 DEBUG : A3/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2025/02/07 02:16:13 INFO : A3/B1/three: Copied (server-side copy) to: A4/B1/three 2025/02/07 02:16:13 DEBUG : A3/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2025/02/07 02:16:13 INFO : A3/B1/C1/four: Copied (server-side copy) to: A4/B1/C1/four 2025/02/07 02:16:13 INFO : A3/two: Deleted 2025/02/07 02:16:13 INFO : A3/B1/C2/five: Deleted 2025/02/07 02:16:13 DEBUG : A3/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2025/02/07 02:16:13 INFO : A3/one: Copied (server-side copy) to: A4/one 2025/02/07 02:16:13 INFO : A3/B1/three: Deleted 2025/02/07 02:16:13 INFO : A3/B1/C1/four: Deleted 2025/02/07 02:16:13 INFO : A3/one: Deleted fstest.go:244: Filtering empty directory "A4/B2" fstest.go:244: Filtering empty directory "A4/B1/C3" --- PASS: TestDirMove (8.51s) === RUN TestGetFsInfo run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" --- PASS: TestGetFsInfo (0.37s) === RUN TestRcat === RUN TestRcat/withChecksum=false,ignoreChecksum=false run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" 2025/02/07 02:16:15 DEBUG : B2 bucket rclone-test-jotegax4kaqu: File to upload is small (34 bytes), uploading instead of streaming 2025/02/07 02:16:16 DEBUG : no_checksum_small_file_from_pipe: sha1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK 2025/02/07 02:16:16 DEBUG : no_checksum_small_file_from_pipe: Size and sha1 of src and dst objects identical 2025/02/07 02:16:16 DEBUG : no_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2025/02/07 02:16:17 DEBUG : no_checksum_big_file_from_pipe: sha1 = d0080f73aa8b494570ad63df1beb25c01ea9eea2 OK 2025/02/07 02:16:17 DEBUG : no_checksum_big_file_from_pipe: Size and sha1 of src and dst objects identical === RUN TestRcat/withChecksum=true,ignoreChecksum=false run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" 2025/02/07 02:16:18 DEBUG : B2 bucket rclone-test-jotegax4kaqu: File to upload is small (34 bytes), uploading instead of streaming 2025/02/07 02:16:19 DEBUG : with_checksum_small_file_from_pipe: sha1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK 2025/02/07 02:16:19 DEBUG : with_checksum_small_file_from_pipe: Size and sha1 of src and dst objects identical 2025/02/07 02:16:19 DEBUG : with_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2025/02/07 02:16:19 DEBUG : with_checksum_big_file_from_pipe: sha1 = d0080f73aa8b494570ad63df1beb25c01ea9eea2 OK 2025/02/07 02:16:19 DEBUG : with_checksum_big_file_from_pipe: Size and sha1 of src and dst objects identical === RUN TestRcat/withChecksum=false,ignoreChecksum=true run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" 2025/02/07 02:16:21 DEBUG : B2 bucket rclone-test-jotegax4kaqu: File to upload is small (34 bytes), uploading instead of streaming 2025/02/07 02:16:21 DEBUG : ignore_checksum_small_file_from_pipe: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/02/07 02:16:21 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2025/02/07 02:16:22 DEBUG : ignore_checksum_big_file_from_pipe: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) === RUN TestRcat/withChecksum=true,ignoreChecksum=true run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" 2025/02/07 02:16:23 DEBUG : B2 bucket rclone-test-jotegax4kaqu: File to upload is small (34 bytes), uploading instead of streaming 2025/02/07 02:16:23 DEBUG : ignore_checksum_small_file_from_pipe: Src hash empty - aborting Dst hash check 2025/02/07 02:16:23 DEBUG : ignore_checksum_small_file_from_pipe: Size of src and dst objects identical 2025/02/07 02:16:23 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2025/02/07 02:16:25 DEBUG : ignore_checksum_big_file_from_pipe: Src hash empty - aborting Dst hash check 2025/02/07 02:16:25 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical --- PASS: TestRcat (10.20s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (2.67s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (2.45s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (2.32s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (2.76s) === RUN TestRcatMetadata run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" operations_test.go:1532: Skipping as destination doesn't support user metadata --- SKIP: TestRcatMetadata (0.37s) === RUN TestRcatSize run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" 2025/02/07 02:16:27 DEBUG : B2 bucket rclone-test-jotegax4kaqu: File to upload is small (60 bytes), uploading instead of streaming 2025/02/07 02:16:27 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2025/02/07 02:16:27 DEBUG : potato2: Size and sha1 of src and dst objects identical --- PASS: TestRcatSize (2.07s) === RUN TestRcatSizeMetadata run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" operations_test.go:1613: Skipping as destination doesn't support user metadata --- SKIP: TestRcatSizeMetadata (0.42s) === RUN TestTouchDir run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" 2025/02/07 02:16:30 DEBUG : B2 bucket rclone-test-jotegax4kaqu: Touching "empty space" 2025/02/07 02:16:31 DEBUG : B2 bucket rclone-test-jotegax4kaqu: Touching "potato2" 2025/02/07 02:16:31 DEBUG : B2 bucket rclone-test-jotegax4kaqu: Touching "sub dir/potato3" --- PASS: TestTouchDir (4.45s) === RUN TestMkdirMetadata run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" operations_test.go:1703: Skipping test as remote does not support MkdirMetadata --- SKIP: TestMkdirMetadata (0.37s) === RUN TestMkdirModTime run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" operations_test.go:1722: Skipping test as remote does not support DirSetModTime or MkdirMetadata --- SKIP: TestMkdirModTime (0.37s) === RUN TestCopyDirMetadata run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" operations_test.go:1743: Skipping test as remote does not support WriteDirMetadata or MkdirMetadata --- SKIP: TestCopyDirMetadata (0.37s) === RUN TestSetDirModTime run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" operations_test.go:1779: Skipping test as remote does not support DirSetModTime or WriteDirSetModTime --- SKIP: TestSetDirModTime (0.37s) === RUN TestDirsEqual run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" operations_test.go:1823: Skipping test as remote does not support WriteDirMetadata or MkdirMetadata --- SKIP: TestDirsEqual (0.37s) === RUN TestRemoveExisting run.go:180: Remote "B2 bucket rclone-test-jotegax4kaqu", Local "Local file system at /tmp/rclone1391224125", Modify Window "1ms" operations_test.go:1892: Skipping as remote can't Move --- SKIP: TestRemoveExisting (0.37s) === RUN TestRcAbout rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcAbout (0.00s) === RUN TestRcCleanup rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcCleanup (0.00s) === RUN TestRcCopyfile rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcCopyfile (0.00s) === RUN TestRcCopyurl rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcCopyurl (0.00s) === RUN TestRcDelete rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcDelete (0.00s) === RUN TestRcDeletefile rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcDeletefile (0.00s) === RUN TestRcList rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcList (0.00s) === RUN TestRcStat rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcStat (0.00s) === RUN TestRcSetTier rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcSetTier (0.00s) === RUN TestRcSetTierFile rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcSetTierFile (0.00s) === RUN TestRcMkdir rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcMkdir (0.00s) === RUN TestRcMovefile rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcMovefile (0.00s) === RUN TestRcPurge rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcPurge (0.00s) === RUN TestRcRmdir rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcRmdir (0.00s) === RUN TestRcRmdirs rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcRmdirs (0.00s) === RUN TestRcSize rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcSize (0.00s) === RUN TestRcPublicLink rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcPublicLink (0.00s) === RUN TestRcFsInfo rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcFsInfo (0.00s) === RUN TestUploadFile rc_test.go:30: Skipping test on non local remote --- SKIP: TestUploadFile (0.00s) === RUN TestRcCommand rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcCommand (0.00s) === RUN TestRcDu rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcDu (0.00s) === RUN TestRcCheck rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcCheck (0.00s) === RUN TestRcHashsum rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcHashsum (0.00s) === RUN TestRcHashsumFile rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcHashsumFile (0.00s) FAIL 2025/02/07 02:16:35 DEBUG : B2 bucket rclone-test-jotegax4kaqu: Purge remote 2025/02/07 02:16:35 INFO : B2 bucket rclone-test-jotegax4kaqu: cleaning bucket "rclone-test-jotegax4kaqu" of all files 2025/02/07 02:16:36 DEBUG : A1/B1/C1/four: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f402dc14a0833aa2a_d20250207_m021610_c001_v7007000_t0000_u01738894570428") 2025/02/07 02:16:36 DEBUG : A1/B1/C1/four: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808de43_d20250207_m021608_c001_v0001146_t0018_u01738894568266") 2025/02/07 02:16:36 DEBUG : A1/B1/C1/one: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f402dc14a0833aa07_d20250207_m021551_c001_v7007000_t0000_u01738894551495") 2025/02/07 02:16:36 DEBUG : A1/B1/C1/one: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808de05_d20250207_m021548_c001_v0001146_t0022_u01738894548333") 2025/02/07 02:16:36 DEBUG : A1/B1/C2/five: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f400835ac9f90f431_d20250207_m021610_c001_v7007000_t0000_u01738894570696") 2025/02/07 02:16:36 DEBUG : A1/B1/C2/five: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808de45_d20250207_m021608_c001_v0001146_t0027_u01738894568620") 2025/02/07 02:16:36 DEBUG : A1/B1/three: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f402dc14a0833aa2d_d20250207_m021610_c001_v7007000_t0000_u01738894570493") 2025/02/07 02:16:36 DEBUG : A1/B1/three: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808de3f_d20250207_m021607_c001_v0001146_t0036_u01738894567825") 2025/02/07 02:16:36 DEBUG : A1/one: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f402dc14a0833aa2c_d20250207_m021610_c001_v7007000_t0000_u01738894570473") 2025/02/07 02:16:36 DEBUG : A1/one: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808de39_d20250207_m021606_c001_v0001146_t0012_u01738894566971") 2025/02/07 02:16:36 DEBUG : A1/two: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f4102e424675271c4_d20250207_m021610_c001_v7007000_t0000_u01738894570618") 2025/02/07 02:16:36 DEBUG : A1/two: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808de3b_d20250207_m021607_c001_v0001146_t0028_u01738894567365") 2025/02/07 02:16:36 DEBUG : A1/two: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f402dc14a0833aa0a_d20250207_m021551_c001_v7007000_t0000_u01738894551859") 2025/02/07 02:16:36 DEBUG : A1/two: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808de09_d20250207_m021548_c001_v0001146_t0018_u01738894548714") 2025/02/07 02:16:36 DEBUG : A2/B1/C1/four: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f400835ac9f90f435_d20250207_m021611_c001_v7007000_t0000_u01738894571824") 2025/02/07 02:16:36 DEBUG : A2/B1/C1/four: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f1143ec9328404b57_d20250207_m021609_c001_v0001037_t0036_u01738894569895") 2025/02/07 02:16:37 DEBUG : A2/B1/C2/five: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f4102e424675271c5_d20250207_m021611_c001_v7007000_t0000_u01738894571781") 2025/02/07 02:16:37 DEBUG : A2/B1/C2/five: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f10069f92f65edc88_d20250207_m021610_c001_v0001039_t0048_u01738894570263") 2025/02/07 02:16:37 DEBUG : A2/B1/three: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f402dc14a0833aa30_d20250207_m021611_c001_v7007000_t0000_u01738894571907") 2025/02/07 02:16:37 DEBUG : A2/B1/three: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f111f84170bbe37bb_d20250207_m021610_c001_v0001184_t0020_u01738894570293") 2025/02/07 02:16:37 DEBUG : A2/one: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f402dc14a0833aa32_d20250207_m021612_c001_v7007000_t0000_u01738894572573") 2025/02/07 02:16:37 DEBUG : A2/one: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f113339191fdf66b8_d20250207_m021610_c001_v0001182_t0002_u01738894570269") 2025/02/07 02:16:37 DEBUG : A2/two: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f402dc14a0833aa2f_d20250207_m021611_c001_v7007000_t0000_u01738894571712") 2025/02/07 02:16:37 DEBUG : A2/two: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103be7024cfb7f1b_d20250207_m021610_c001_v0001093_t0042_u01738894570306") 2025/02/07 02:16:37 DEBUG : A3/B1/C1/four: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f402dc14a0833aa38_d20250207_m021613_c001_v7007000_t0000_u01738894573709") 2025/02/07 02:16:37 DEBUG : A3/B1/C1/four: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f112887fd20d70c0c_d20250207_m021611_c001_v0001093_t0038_u01738894571452") 2025/02/07 02:16:37 DEBUG : A3/B1/C2/five: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f4102e424675271c8_d20250207_m021613_c001_v7007000_t0000_u01738894573596") 2025/02/07 02:16:37 DEBUG : A3/B1/C2/five: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f1184f784e0d7c27d_d20250207_m021611_c001_v0001146_t0020_u01738894571469") 2025/02/07 02:16:37 DEBUG : A3/B1/three: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f402dc14a0833aa37_d20250207_m021613_c001_v7007000_t0000_u01738894573627") 2025/02/07 02:16:37 DEBUG : A3/B1/three: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f106b8b1a1a437af6_d20250207_m021611_c001_v0001161_t0008_u01738894571468") 2025/02/07 02:16:37 DEBUG : A3/one: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f400835ac9f90f437_d20250207_m021613_c001_v7007000_t0000_u01738894573806") 2025/02/07 02:16:37 DEBUG : A3/one: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f10594e16d1ee73d7_d20250207_m021611_c001_v0001109_t0018_u01738894571561") 2025/02/07 02:16:37 DEBUG : A3/two: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f402dc14a0833aa36_d20250207_m021613_c001_v7007000_t0000_u01738894573572") 2025/02/07 02:16:37 DEBUG : A3/two: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f1161346906641629_d20250207_m021611_c001_v0001183_t0042_u01738894571513") 2025/02/07 02:16:37 DEBUG : A4/B1/C1/four: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f400835ac9f90f439_d20250207_m021614_c001_v7007000_t0000_u01738894574378") 2025/02/07 02:16:37 DEBUG : A4/B1/C1/four: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f11468659b06d6c6a_d20250207_m021613_c001_v0001145_t0024_u01738894573411") 2025/02/07 02:16:38 DEBUG : A4/B1/C2/five: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f400835ac9f90f43a_d20250207_m021614_c001_v7007000_t0000_u01738894574560") 2025/02/07 02:16:38 DEBUG : A4/B1/C2/five: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f105069272ef6c8b8_d20250207_m021613_c001_v0001183_t0040_u01738894573395") 2025/02/07 02:16:38 DEBUG : A4/B1/three: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f400835ac9f90f43b_d20250207_m021614_c001_v7007000_t0000_u01738894574743") 2025/02/07 02:16:38 DEBUG : A4/B1/three: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f107f4278538582c4_d20250207_m021613_c001_v0001180_t0048_u01738894573368") 2025/02/07 02:16:38 DEBUG : A4/one: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f400835ac9f90f43c_d20250207_m021614_c001_v7007000_t0000_u01738894574924") 2025/02/07 02:16:38 DEBUG : A4/one: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808de4b_d20250207_m021613_c001_v0001146_t0049_u01738894573385") 2025/02/07 02:16:38 DEBUG : A4/two: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f400835ac9f90f43e_d20250207_m021615_c001_v7007000_t0000_u01738894575106") 2025/02/07 02:16:38 DEBUG : A4/two: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f102f0a978be6e7f2_d20250207_m021613_c001_v0001182_t0020_u01738894573370") 2025/02/07 02:16:38 DEBUG : BackupDir/one: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f4090711e5498a7f9_d20250207_m021435_c001_v7007000_t0000_u01738894475212") 2025/02/07 02:16:38 DEBUG : BackupDir/one: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f10230ff21474a084_d20250207_m021430_c001_v0001183_t0036_u01738894470224") 2025/02/07 02:16:38 DEBUG : CompareDest/one: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f4090711e5498a7e7_d20250207_m021419_c001_v7007000_t0000_u01738894459612") 2025/02/07 02:16:38 DEBUG : CompareDest/one: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808dc71_d20250207_m021415_c001_v0001146_t0037_u01738894455434") 2025/02/07 02:16:38 DEBUG : CompareDest/two: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f4090711e5498a7e8_d20250207_m021419_c001_v7007000_t0000_u01738894459808") 2025/02/07 02:16:38 DEBUG : CompareDest/two: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808dc77_d20250207_m021416_c001_v0001146_t0041_u01738894456547") 2025/02/07 02:16:38 DEBUG : CopyDest/one: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f4090711e5498a7fa_d20250207_m021435_c001_v7007000_t0000_u01738894475396") 2025/02/07 02:16:38 DEBUG : CopyDest/one: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808dcab_d20250207_m021427_c001_v0001146_t0050_u01738894467201") 2025/02/07 02:16:38 DEBUG : CopyDest/three: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f4090711e5498a7fc_d20250207_m021435_c001_v7007000_t0000_u01738894475579") 2025/02/07 02:16:38 DEBUG : CopyDest/three: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808dcbf_d20250207_m021433_c001_v0001146_t0047_u01738894473260") 2025/02/07 02:16:38 DEBUG : CopyDest/two: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f4090711e5498a7fd_d20250207_m021435_c001_v7007000_t0000_u01738894475763") 2025/02/07 02:16:38 DEBUG : CopyDest/two: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808dcbd_d20250207_m021431_c001_v0001146_t0042_u01738894471550") 2025/02/07 02:16:38 DEBUG : ReadFile: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f4147ebc10155ad66_d20250207_m021531_c001_v7007000_t0000_u01738894531200") 2025/02/07 02:16:39 DEBUG : ReadFile: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808ddbf_d20250207_m021529_c001_v0001146_t0006_u01738894529490") 2025/02/07 02:16:39 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f4090711e5498a806_d20250207_m021440_c001_v7007000_t0000_u01738894480893") 2025/02/07 02:16:39 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808dccd_d20250207_m021437_c001_v0001146_t0035_u01738894477958") 2025/02/07 02:16:39 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f4090711e5498a807_d20250207_m021441_c001_v7007000_t0000_u01738894481081") 2025/02/07 02:16:39 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808dcdb_d20250207_m021440_c001_v0001146_t0057_u01738894480022") 2025/02/07 02:16:39 DEBUG : a.txt: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f406e682d4e4c90b6_d20250207_m021454_c001_v7007000_t0000_u01738894494431") 2025/02/07 02:16:39 DEBUG : a.txt: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808dcfe_d20250207_m021448_c001_v0001146_t0046_u01738894488210") 2025/02/07 02:16:39 DEBUG : also/one: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f4090711e5498a80d_d20250207_m021445_c001_v7007000_t0000_u01738894485277") 2025/02/07 02:16:39 DEBUG : also/one: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808dce5_d20250207_m021443_c001_v0001146_t0052_u01738894483618") 2025/02/07 02:16:39 DEBUG : another: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f4090711e5498a80e_d20250207_m021445_c001_v7007000_t0000_u01738894485824") 2025/02/07 02:16:39 DEBUG : another: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808dce9_d20250207_m021443_c001_v0001146_t0003_u01738894483958") 2025/02/07 02:16:39 DEBUG : backup/dst/file1: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f402dc14a0833aa20_d20250207_m021606_c001_v7007000_t0000_u01738894566423") 2025/02/07 02:16:39 DEBUG : backup/dst/file1: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f115daf9d2768b8fd_d20250207_m021605_c001_v0001105_t0039_u01738894565159") 2025/02/07 02:16:39 DEBUG : backup/dst/file1: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f4090711e5498a7da_d20250207_m021408_c001_v7007000_t0000_u01738894448154") 2025/02/07 02:16:39 DEBUG : backup/dst/file1: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f1169c9cba22dc9f5_d20250207_m021407_c001_v0001182_t0049_u01738894447104") 2025/02/07 02:16:39 DEBUG : data/banana: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f40569ccd2f532ede_d20250207_m021225_c001_v7007000_t0000_u01738894345535") 2025/02/07 02:16:39 DEBUG : data/banana: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808db48_d20250207_m021223_c001_v0001146_t0044_u01738894343111") 2025/02/07 02:16:39 DEBUG : data/banana: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808db20_d20250207_m021213_c001_v0001146_t0020_u01738894333597") 2025/02/07 02:16:39 DEBUG : data/potato: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f40569ccd2f532edf_d20250207_m021225_c001_v7007000_t0000_u01738894345716") 2025/02/07 02:16:39 DEBUG : data/potato: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808db4a_d20250207_m021223_c001_v0001146_t0041_u01738894343424") 2025/02/07 02:16:40 DEBUG : data/potato: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808db26_d20250207_m021215_c001_v0001146_t0020_u01738894335806") 2025/02/07 02:16:40 DEBUG : dst/file1: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f402dc14a0833aa21_d20250207_m021606_c001_v7007000_t0000_u01738894566605") 2025/02/07 02:16:40 DEBUG : dst/file1: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808de37_d20250207_m021605_c001_v0001146_t0035_u01738894565756") 2025/02/07 02:16:40 DEBUG : dst/file1: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f402dc14a0833aa1f_d20250207_m021605_c001_v7007000_t0000_u01738894565574") 2025/02/07 02:16:40 DEBUG : dst/file1: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808de31_d20250207_m021602_c001_v0001146_t0032_u01738894562377") 2025/02/07 02:16:40 DEBUG : dst/file1: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f4090711e5498a7dc_d20250207_m021408_c001_v7007000_t0000_u01738894448340") 2025/02/07 02:16:40 DEBUG : dst/file1: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808dc5d_d20250207_m021407_c001_v0001146_t0045_u01738894447488") 2025/02/07 02:16:40 DEBUG : dst/file1: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f4090711e5498a7d8_d20250207_m021407_c001_v7007000_t0000_u01738894447303") 2025/02/07 02:16:40 DEBUG : dst/file1: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808dc57_d20250207_m021404_c001_v0001146_t0040_u01738894444290") 2025/02/07 02:16:40 DEBUG : dst/one: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f4090711e5498a7fe_d20250207_m021435_c001_v7007000_t0000_u01738894475948") 2025/02/07 02:16:40 DEBUG : dst/one: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f11760cb9db8970ab_d20250207_m021431_c001_v0001103_t0057_u01738894471006") 2025/02/07 02:16:40 DEBUG : dst/one: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f406e682d4e4c9086_d20250207_m021430_c001_v7007000_t0000_u01738894470802") 2025/02/07 02:16:40 DEBUG : dst/one: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808dca9_d20250207_m021426_c001_v0001146_t0015_u01738894466886") 2025/02/07 02:16:40 DEBUG : dst/one: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f111a43abec0d14af_d20250207_m021426_c001_v0001103_t0052_u01738894466017") 2025/02/07 02:16:40 DEBUG : dst/one: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f111a43abec0d14ad_d20250207_m021424_c001_v0001103_t0040_u01738894464972") 2025/02/07 02:16:40 DEBUG : dst/one: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f4090711e5498a7e9_d20250207_m021419_c001_v7007000_t0000_u01738894459992") 2025/02/07 02:16:40 DEBUG : dst/one: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808dc6f_d20250207_m021415_c001_v0001146_t0028_u01738894455114") 2025/02/07 02:16:40 DEBUG : dst/one: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f11874fa13f34e7e1_d20250207_m021413_c001_v0001115_t0008_u01738894453926") 2025/02/07 02:16:40 DEBUG : dst/one: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f11874fa13f34e7db_d20250207_m021412_c001_v0001115_t0001_u01738894452663") 2025/02/07 02:16:40 DEBUG : dst/three: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f4090711e5498a7ff_d20250207_m021436_c001_v7007000_t0000_u01738894476131") 2025/02/07 02:16:41 DEBUG : dst/three: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f111a43abec0d14cb_d20250207_m021434_c001_v0001103_t0038_u01738894474503") 2025/02/07 02:16:41 DEBUG : dst/two: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f4090711e5498a801_d20250207_m021436_c001_v7007000_t0000_u01738894476315") 2025/02/07 02:16:41 DEBUG : dst/two: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f113af8a9124dba61_d20250207_m021432_c001_v0001145_t0014_u01738894472445") 2025/02/07 02:16:41 DEBUG : dst/two: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f4090711e5498a7ea_d20250207_m021420_c001_v7007000_t0000_u01738894460179") 2025/02/07 02:16:41 DEBUG : dst/two: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f11874fa13f34e7f5_d20250207_m021418_c001_v0001115_t0031_u01738894458710") 2025/02/07 02:16:41 DEBUG : empty space: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f400835ac9f90f457_d20250207_m021632_c001_v7007000_t0000_u01738894592601") 2025/02/07 02:16:41 DEBUG : empty space: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f11419fc590e30604_d20250207_m021630_c001_v0001137_t0012_u01738894590839") 2025/02/07 02:16:41 DEBUG : empty space: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808de7d_d20250207_m021629_c001_v0001146_t0012_u01738894589260") 2025/02/07 02:16:41 DEBUG : empty space: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f406e682d4e4c90ea_d20250207_m021517_c001_v7007000_t0000_u01738894517730") 2025/02/07 02:16:41 DEBUG : empty space: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808dd7e_d20250207_m021516_c001_v0001146_t0003_u01738894516398") 2025/02/07 02:16:41 DEBUG : empty space: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f406e682d4e4c90e3_d20250207_m021515_c001_v7007000_t0000_u01738894515447") 2025/02/07 02:16:41 DEBUG : empty space: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808dd7a_d20250207_m021514_c001_v0001146_t0011_u01738894514195") 2025/02/07 02:16:41 DEBUG : empty space: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f406e682d4e4c90dd_d20250207_m021513_c001_v7007000_t0000_u01738894513252") 2025/02/07 02:16:41 DEBUG : empty space: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808dd72_d20250207_m021512_c001_v0001146_t0038_u01738894512322") 2025/02/07 02:16:41 DEBUG : empty space: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f406e682d4e4c90db_d20250207_m021511_c001_v7007000_t0000_u01738894511436") 2025/02/07 02:16:41 DEBUG : empty space: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808dd68_d20250207_m021510_c001_v0001146_t0037_u01738894510036") 2025/02/07 02:16:41 DEBUG : empty space: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f406e682d4e4c90d5_d20250207_m021509_c001_v7007000_t0000_u01738894509130") 2025/02/07 02:16:41 DEBUG : empty space: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808dd60_d20250207_m021508_c001_v0001146_t0028_u01738894508302") 2025/02/07 02:16:41 DEBUG : empty space: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f40569ccd2f532ec5_d20250207_m021207_c001_v7007000_t0000_u01738894327575") 2025/02/07 02:16:41 DEBUG : empty space: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808daf6_d20250207_m021204_c001_v0001146_t0052_u01738894324672") 2025/02/07 02:16:42 DEBUG : empty space: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f40569ccd2f532ebd_d20250207_m021202_c001_v7007000_t0000_u01738894322818") 2025/02/07 02:16:42 DEBUG : empty space: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808dae4_d20250207_m021158_c001_v0001146_t0056_u01738894318732") 2025/02/07 02:16:42 DEBUG : empty space: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f40569ccd2f532ea3_d20250207_m021156_c001_v7007000_t0000_u01738894316095") 2025/02/07 02:16:42 DEBUG : empty space: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808dac8_d20250207_m021153_c001_v0001146_t0057_u01738894313011") 2025/02/07 02:16:42 DEBUG : file1: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f402dc14a0833aa1b_d20250207_m021601_c001_v7007000_t0000_u01738894561277") 2025/02/07 02:16:42 DEBUG : file1: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808de2b_d20250207_m021600_c001_v0001146_t0048_u01738894560061") 2025/02/07 02:16:42 DEBUG : file1: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f402dc14a0833aa12_d20250207_m021556_c001_v7007000_t0000_u01738894556992") 2025/02/07 02:16:42 DEBUG : file1: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f403d62ff3808de13_d20250207_m021555_c001_v0001146_t0042_u01738894555216") 2025/02/07 02:16:42 DEBUG : file1: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f4147ebc10155ad68_d20250207_m021535_c001_v7007000_t0000_u01738894535679") 2025/02/07 02:16:42 DEBUG : file1: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808ddc7_d20250207_m021531_c001_v0001146_t0039_u01738894531981") 2025/02/07 02:16:42 DEBUG : file1: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f406e682d4e4c90ce_d20250207_m021505_c001_v7007000_t0000_u01738894505216") 2025/02/07 02:16:42 DEBUG : file1: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808dd38_d20250207_m021459_c001_v0001146_t0037_u01738894499477") 2025/02/07 02:16:42 DEBUG : file1: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f406e682d4e4c90c6_d20250207_m021458_c001_v7007000_t0000_u01738894498924") 2025/02/07 02:16:42 DEBUG : file1: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808dd2a_d20250207_m021455_c001_v0001146_t0030_u01738894495888") 2025/02/07 02:16:42 DEBUG : file2: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f402dc14a0833aa13_d20250207_m021557_c001_v7007000_t0000_u01738894557176") 2025/02/07 02:16:42 DEBUG : file2: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f403d62ff3808de1f_d20250207_m021556_c001_v0001146_t0042_u01738894556439") 2025/02/07 02:16:42 DEBUG : file2: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f4147ebc10155ad69_d20250207_m021535_c001_v7007000_t0000_u01738894535859") 2025/02/07 02:16:42 DEBUG : file2: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808ddcb_d20250207_m021532_c001_v0001146_t0040_u01738894532254") 2025/02/07 02:16:42 DEBUG : filename.txt: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f402dc14a0833aa15_d20250207_m021557_c001_v7007000_t0000_u01738894557358") 2025/02/07 02:16:42 DEBUG : filename.txt: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808de15_d20250207_m021555_c001_v0001146_t0026_u01738894555766") 2025/02/07 02:16:43 DEBUG : headerfilename.txt: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f402dc14a0833aa16_d20250207_m021557_c001_v7007000_t0000_u01738894557539") 2025/02/07 02:16:43 DEBUG : headerfilename.txt: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808de19_d20250207_m021556_c001_v0001146_t0044_u01738894556128") 2025/02/07 02:16:43 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f400835ac9f90f44d_d20250207_m021625_c001_v7007000_t0000_u01738894585491") 2025/02/07 02:16:43 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808de6f_d20250207_m021623_c001_v0001146_t0009_u01738894583982") 2025/02/07 02:16:43 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f400835ac9f90f44b_d20250207_m021622_c001_v7007000_t0000_u01738894582728") 2025/02/07 02:16:43 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808de67_d20250207_m021621_c001_v0001146_t0033_u01738894581465") 2025/02/07 02:16:43 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f400835ac9f90f44e_d20250207_m021625_c001_v7007000_t0000_u01738894585674") 2025/02/07 02:16:43 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808de6b_d20250207_m021623_c001_v0001146_t0016_u01738894583466") 2025/02/07 02:16:43 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f400835ac9f90f44c_d20250207_m021622_c001_v7007000_t0000_u01738894582910") 2025/02/07 02:16:43 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808de65_d20250207_m021621_c001_v0001146_t0037_u01738894581148") 2025/02/07 02:16:43 DEBUG : large: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f406e682d4e4c9102_d20250207_m021528_c001_v7007000_t0000_u01738894528190") 2025/02/07 02:16:43 DEBUG : large: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808ddb9_d20250207_m021527_c001_v0001146_t0012_u01738894527507") 2025/02/07 02:16:43 DEBUG : large: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f406e682d4e4c90ff_d20250207_m021526_c001_v7007000_t0000_u01738894526078") 2025/02/07 02:16:43 DEBUG : large: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808ddab_d20250207_m021524_c001_v0001146_t0053_u01738894524791") 2025/02/07 02:16:43 DEBUG : large: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f406e682d4e4c90f7_d20250207_m021522_c001_v7007000_t0000_u01738894522604") 2025/02/07 02:16:43 DEBUG : large: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808dd9b_d20250207_m021521_c001_v0001146_t0013_u01738894521848") 2025/02/07 02:16:43 DEBUG : large: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f406e682d4e4c90f4_d20250207_m021520_c001_v7007000_t0000_u01738894520730") 2025/02/07 02:16:43 DEBUG : large: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808dd8e_d20250207_m021519_c001_v0001146_t0030_u01738894519244") 2025/02/07 02:16:43 DEBUG : medium: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f4147ebc10155ad5f_d20250207_m021528_c001_v7007000_t0000_u01738894528199") 2025/02/07 02:16:44 DEBUG : medium: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808ddb5_d20250207_m021527_c001_v0001146_t0017_u01738894527188") 2025/02/07 02:16:44 DEBUG : medium: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f4147ebc10155ad5b_d20250207_m021525_c001_v7007000_t0000_u01738894525517") 2025/02/07 02:16:44 DEBUG : medium: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808dda9_d20250207_m021524_c001_v0001146_t0032_u01738894524417") 2025/02/07 02:16:44 DEBUG : medium: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f4147ebc10155ad58_d20250207_m021522_c001_v7007000_t0000_u01738894522615") 2025/02/07 02:16:44 DEBUG : medium: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808dd98_d20250207_m021521_c001_v0001146_t0042_u01738894521508") 2025/02/07 02:16:44 DEBUG : medium: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f406e682d4e4c90f1_d20250207_m021520_c001_v7007000_t0000_u01738894520002") 2025/02/07 02:16:44 DEBUG : medium: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808dd8c_d20250207_m021518_c001_v0001146_t0030_u01738894518808") 2025/02/07 02:16:44 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f400835ac9f90f442_d20250207_m021617_c001_v7007000_t0000_u01738894577956") 2025/02/07 02:16:44 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808de53_d20250207_m021616_c001_v0001146_t0037_u01738894576761") 2025/02/07 02:16:44 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f400835ac9f90f443_d20250207_m021618_c001_v7007000_t0000_u01738894578138") 2025/02/07 02:16:44 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808de51_d20250207_m021616_c001_v0001146_t0044_u01738894576389") 2025/02/07 02:16:44 DEBUG : not-one: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f4090711e5498a810_d20250207_m021446_c001_v7007000_t0000_u01738894486006") 2025/02/07 02:16:44 DEBUG : not-one: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808dceb_d20250207_m021444_c001_v0001146_t0028_u01738894484418") 2025/02/07 02:16:44 DEBUG : one: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f4090711e5498a80b_d20250207_m021445_c001_v7007000_t0000_u01738894485093") 2025/02/07 02:16:44 DEBUG : one: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808dce3_d20250207_m021443_c001_v0001146_t0018_u01738894483318") 2025/02/07 02:16:44 DEBUG : potato1: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f400835ac9f90f451_d20250207_m021627_c001_v7007000_t0000_u01738894587937") 2025/02/07 02:16:44 DEBUG : potato1: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808de71_d20250207_m021626_c001_v0001146_t0001_u01738894586418") 2025/02/07 02:16:44 DEBUG : potato2: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f400835ac9f90f458_d20250207_m021632_c001_v7007000_t0000_u01738894592784") 2025/02/07 02:16:44 DEBUG : potato2: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f1167051c08ee9da3_d20250207_m021631_c001_v0001161_t0014_u01738894591492") 2025/02/07 02:16:44 DEBUG : potato2: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808de79_d20250207_m021628_c001_v0001146_t0011_u01738894588914") 2025/02/07 02:16:45 DEBUG : potato2: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f400835ac9f90f452_d20250207_m021628_c001_v7007000_t0000_u01738894588119") 2025/02/07 02:16:45 DEBUG : potato2: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808de73_d20250207_m021627_c001_v0001146_t0032_u01738894587175") 2025/02/07 02:16:45 DEBUG : potato2: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f406e682d4e4c90ec_d20250207_m021517_c001_v7007000_t0000_u01738894517928") 2025/02/07 02:16:45 DEBUG : potato2: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808dd7c_d20250207_m021515_c001_v0001146_t0030_u01738894515998") 2025/02/07 02:16:45 DEBUG : potato2: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f406e682d4e4c90e4_d20250207_m021515_c001_v7007000_t0000_u01738894515633") 2025/02/07 02:16:45 DEBUG : potato2: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808dd78_d20250207_m021513_c001_v0001146_t0042_u01738894513797") 2025/02/07 02:16:45 DEBUG : potato2: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f406e682d4e4c90df_d20250207_m021513_c001_v7007000_t0000_u01738894513434") 2025/02/07 02:16:45 DEBUG : potato2: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808dd6e_d20250207_m021511_c001_v0001146_t0043_u01738894511984") 2025/02/07 02:16:45 DEBUG : potato2: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f406e682d4e4c90dc_d20250207_m021511_c001_v7007000_t0000_u01738894511619") 2025/02/07 02:16:45 DEBUG : potato2: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808dd64_d20250207_m021509_c001_v0001146_t0014_u01738894509676") 2025/02/07 02:16:45 DEBUG : potato2: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f406e682d4e4c90d8_d20250207_m021509_c001_v7007000_t0000_u01738894509311") 2025/02/07 02:16:45 DEBUG : potato2: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808dd5e_d20250207_m021508_c001_v0001146_t0010_u01738894508021") 2025/02/07 02:16:45 DEBUG : potato2: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f40569ccd2f532ec8_d20250207_m021207_c001_v7007000_t0000_u01738894327764") 2025/02/07 02:16:45 DEBUG : potato2: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808dafe_d20250207_m021205_c001_v0001146_t0008_u01738894325502") 2025/02/07 02:16:45 DEBUG : potato2: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f40569ccd2f532ebe_d20250207_m021202_c001_v7007000_t0000_u01738894322999") 2025/02/07 02:16:45 DEBUG : potato2: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808dae6_d20250207_m021159_c001_v0001146_t0011_u01738894319832") 2025/02/07 02:16:45 DEBUG : potato2: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f40569ccd2f532ea4_d20250207_m021156_c001_v7007000_t0000_u01738894316279") 2025/02/07 02:16:45 DEBUG : potato2: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808dace_d20250207_m021153_c001_v0001146_t0048_u01738894313867") 2025/02/07 02:16:45 DEBUG : remotepotato: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f40569ccd2f532eca_d20250207_m021207_c001_v7007000_t0000_u01738894327958") 2025/02/07 02:16:45 DEBUG : remotepotato: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808db08_d20250207_m021206_c001_v0001146_t0040_u01738894326522") 2025/02/07 02:16:46 DEBUG : remotepotato: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f40569ccd2f532ebf_d20250207_m021203_c001_v7007000_t0000_u01738894323195") 2025/02/07 02:16:46 DEBUG : remotepotato: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808daea_d20250207_m021201_c001_v0001146_t0046_u01738894321288") 2025/02/07 02:16:46 DEBUG : remotepotato: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f40569ccd2f532ea5_d20250207_m021156_c001_v7007000_t0000_u01738894316460") 2025/02/07 02:16:46 DEBUG : remotepotato: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808dad2_d20250207_m021155_c001_v0001146_t0031_u01738894315066") 2025/02/07 02:16:46 DEBUG : rutabaga: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f40569ccd2f532ecd_d20250207_m021208_c001_v7007000_t0000_u01738894328138") 2025/02/07 02:16:46 DEBUG : rutabaga: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808daee_d20250207_m021203_c001_v0001146_t0015_u01738894323740") 2025/02/07 02:16:46 DEBUG : rutabaga: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f40569ccd2f532ec0_d20250207_m021203_c001_v7007000_t0000_u01738894323376") 2025/02/07 02:16:46 DEBUG : rutabaga: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808dae0_d20250207_m021157_c001_v0001146_t0025_u01738894317017") 2025/02/07 02:16:46 DEBUG : rutabaga: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f40569ccd2f532ea7_d20250207_m021156_c001_v7007000_t0000_u01738894316655") 2025/02/07 02:16:46 DEBUG : rutabaga: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808dac4_d20250207_m021152_c001_v0001146_t0011_u01738894312150") 2025/02/07 02:16:46 DEBUG : small: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f4147ebc10155ad62_d20250207_m021528_c001_v7007000_t0000_u01738894528761") 2025/02/07 02:16:46 DEBUG : small: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808ddb3_d20250207_m021526_c001_v0001146_t0041_u01738894526812") 2025/02/07 02:16:46 DEBUG : small: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f406e682d4e4c90fe_d20250207_m021525_c001_v7007000_t0000_u01738894525528") 2025/02/07 02:16:46 DEBUG : small: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808dda7_d20250207_m021523_c001_v0001146_t0003_u01738894523924") 2025/02/07 02:16:46 DEBUG : small: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f4147ebc10155ad5a_d20250207_m021523_c001_v7007000_t0000_u01738894523185") 2025/02/07 02:16:46 DEBUG : small: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808dd96_d20250207_m021521_c001_v0001146_t0000_u01738894521095") 2025/02/07 02:16:46 DEBUG : small: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f406e682d4e4c90f3_d20250207_m021520_c001_v7007000_t0000_u01738894520183") 2025/02/07 02:16:46 DEBUG : small: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808dd8a_d20250207_m021518_c001_v0001146_t0048_u01738894518490") 2025/02/07 02:16:46 DEBUG : sub dir/hello world: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f406e682d4e4c90d2_d20250207_m021507_c001_v7007000_t0000_u01738894507657") 2025/02/07 02:16:46 DEBUG : sub dir/hello world: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808dd54_d20250207_m021506_c001_v0001146_t0026_u01738894506676") 2025/02/07 02:16:46 DEBUG : sub dir/hello world: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f406e682d4e4c90b7_d20250207_m021454_c001_v7007000_t0000_u01738894494613") 2025/02/07 02:16:47 DEBUG : sub dir/hello world: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808dd08_d20250207_m021449_c001_v0001146_t0046_u01738894489403") 2025/02/07 02:16:47 DEBUG : sub dir/hello world2: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f406e682d4e4c90b9_d20250207_m021454_c001_v7007000_t0000_u01738894494796") 2025/02/07 02:16:47 DEBUG : sub dir/hello world2: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808dd0a_d20250207_m021450_c001_v0001146_t0053_u01738894490029") 2025/02/07 02:16:47 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f406e682d4e4c90ba_d20250207_m021454_c001_v7007000_t0000_u01738894494977") 2025/02/07 02:16:47 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808dd10_d20250207_m021450_c001_v0001146_t0039_u01738894490403") 2025/02/07 02:16:47 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f406e682d4e4c90bb_d20250207_m021455_c001_v7007000_t0000_u01738894495158") 2025/02/07 02:16:47 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808dd14_d20250207_m021450_c001_v0001146_t0009_u01738894490683") 2025/02/07 02:16:47 DEBUG : sub dir/potato3: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f400835ac9f90f459_d20250207_m021632_c001_v7007000_t0000_u01738894592987") 2025/02/07 02:16:47 DEBUG : sub dir/potato3: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f1093e3dcd33403da_d20250207_m021631_c001_v0001184_t0059_u01738894591982") 2025/02/07 02:16:47 DEBUG : sub dir/potato3: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808de7f_d20250207_m021629_c001_v0001146_t0050_u01738894589538") 2025/02/07 02:16:47 DEBUG : sub dir/potato3: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f406e682d4e4c90ed_d20250207_m021518_c001_v7007000_t0000_u01738894518109") 2025/02/07 02:16:47 DEBUG : sub dir/potato3: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808dd80_d20250207_m021516_c001_v0001146_t0037_u01738894516867") 2025/02/07 02:16:47 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f406e682d4e4c90bc_d20250207_m021455_c001_v7007000_t0000_u01738894495341") 2025/02/07 02:16:47 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808dd18_d20250207_m021451_c001_v0001146_t0043_u01738894491230") 2025/02/07 02:16:47 DEBUG : sub/file2: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f402dc14a0833aa1a_d20250207_m021559_c001_v7007000_t0000_u01738894559508") 2025/02/07 02:16:47 DEBUG : sub/file2: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808de25_d20250207_m021558_c001_v0001146_t0001_u01738894558095") 2025/02/07 02:16:47 DEBUG : sub/file2: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f406e682d4e4c90cf_d20250207_m021505_c001_v7007000_t0000_u01738894505398") 2025/02/07 02:16:47 DEBUG : sub/file2: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808dd3a_d20250207_m021459_c001_v0001146_t0013_u01738894499776") 2025/02/07 02:16:47 DEBUG : sub/file2: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f406e682d4e4c90c7_d20250207_m021459_c001_v7007000_t0000_u01738894499107") 2025/02/07 02:16:48 DEBUG : sub/file2: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808dd2c_d20250207_m021456_c001_v0001146_t0001_u01738894496258") 2025/02/07 02:16:48 DEBUG : sub/file2: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f4090711e5498a7d2_d20250207_m021403_c001_v7007000_t0000_u01738894443558") 2025/02/07 02:16:48 DEBUG : sub/file2: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808dc4f_d20250207_m021401_c001_v0001146_t0050_u01738894441881") 2025/02/07 02:16:48 DEBUG : test-multithread-abort: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f40569ccd2f532e9d_d20250207_m021151_c001_v7007000_t0000_u01738894311248") 2025/02/07 02:16:48 DEBUG : test-multithread-abort: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808da6b_d20250207_m021115_c001_v0001146_t0002_u01738894275833") 2025/02/07 02:16:48 DEBUG : test-multithread-copy-false-201326591-2: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f406e682d4e4c5703_d20250207_m020815_c001_v7007000_t0000_u01738894095478") 2025/02/07 02:16:48 DEBUG : test-multithread-copy-false-201326591-2: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808d7ff_d20250207_m020740_c001_v0001146_t0003_u01738894060319") 2025/02/07 02:16:48 DEBUG : test-multithread-copy-false-201326592-2: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f415bbdf2d6640c83_d20250207_m020853_c001_v7007000_t0000_u01738894133690") 2025/02/07 02:16:48 DEBUG : test-multithread-copy-false-201326592-2: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808d87f_d20250207_m020817_c001_v0001146_t0024_u01738894097434") 2025/02/07 02:16:48 DEBUG : test-multithread-copy-false-201326593-2: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f406e682d4e4c5f7a_d20250207_m020934_c001_v7007000_t0000_u01738894174274") 2025/02/07 02:16:48 DEBUG : test-multithread-copy-false-201326593-2: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808d8da_d20250207_m020855_c001_v0001146_t0031_u01738894135635") 2025/02/07 02:16:48 DEBUG : test-multithread-copy-true-201326591-2: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f412ff869d0a722e5_d20250207_m021006_c001_v7007000_t0000_u01738894206206") 2025/02/07 02:16:48 DEBUG : test-multithread-copy-true-201326591-2: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f2083145de41fa670_d20250207_m020949_c001_v0001146_t0016_u01738894189580") 2025/02/07 02:16:48 DEBUG : test-multithread-copy-true-201326592-2: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f4090711e54988007_d20250207_m021039_c001_v7007000_t0000_u01738894239434") 2025/02/07 02:16:48 DEBUG : test-multithread-copy-true-201326592-2: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f2147ebc101556512_d20250207_m021021_c001_v0001134_t0059_u01738894221679") 2025/02/07 02:16:48 DEBUG : test-multithread-copy-true-201326593-2: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f408be985ee55f51f_d20250207_m021114_c001_v7007000_t0000_u01738894274514") 2025/02/07 02:16:48 DEBUG : test-multithread-copy-true-201326593-2: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f218589218909a3fb_d20250207_m021054_c001_v0001181_t0029_u01738894254397") 2025/02/07 02:16:48 DEBUG : test.sum: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f40569ccd2f532ee0_d20250207_m021225_c001_v7007000_t0000_u01738894345897") 2025/02/07 02:16:48 DEBUG : test.sum: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808db4e_d20250207_m021224_c001_v0001146_t0056_u01738894344012") 2025/02/07 02:16:48 DEBUG : test.sum: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808db42_d20250207_m021221_c001_v0001146_t0009_u01738894341630") 2025/02/07 02:16:48 DEBUG : test.sum: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808db3a_d20250207_m021220_c001_v0001146_t0003_u01738894340156") 2025/02/07 02:16:49 DEBUG : test.sum: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808db36_d20250207_m021218_c001_v0001146_t0022_u01738894338896") 2025/02/07 02:16:49 DEBUG : test.sum: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808db30_d20250207_m021217_c001_v0001146_t0053_u01738894337352") 2025/02/07 02:16:49 DEBUG : test.sum: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808db28_d20250207_m021216_c001_v0001146_t0046_u01738894336104") 2025/02/07 02:16:49 DEBUG : test.sum: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808db22_d20250207_m021213_c001_v0001146_t0012_u01738894333895") 2025/02/07 02:16:49 DEBUG : test.sum: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f40569ccd2f532ed4_d20250207_m021210_c001_v7007000_t0000_u01738894330649") 2025/02/07 02:16:49 DEBUG : test.sum: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808db14_d20250207_m021209_c001_v0001146_t0001_u01738894329590") 2025/02/07 02:16:49 DEBUG : test.sum: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808db12_d20250207_m021208_c001_v0001146_t0029_u01738894328498") 2025/02/07 02:16:49 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f400835ac9f90f445_d20250207_m021620_c001_v7007000_t0000_u01738894580406") 2025/02/07 02:16:49 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808de5d_d20250207_m021619_c001_v0001146_t0047_u01738894579138") 2025/02/07 02:16:49 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f400835ac9f90f446_d20250207_m021620_c001_v7007000_t0000_u01738894580588") 2025/02/07 02:16:49 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808de59_d20250207_m021618_c001_v0001146_t0009_u01738894578699") 2025/02/07 02:16:49 DEBUG : zend.txt: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f406e682d4e4c90bd_d20250207_m021455_c001_v7007000_t0000_u01738894495522") 2025/02/07 02:16:49 DEBUG : zend.txt: Deleting (id "4_z4f229d93209ddbeb9f480e1b_f103d62ff3808dd04_d20250207_m021448_c001_v0001146_t0059_u01738894488844") "./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose" - Finished OK in 9m18.963733646s (try 1/5)