"./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -fast-list" - Starting (try 1/5) 2025/01/22 01:20:31 DEBUG : Creating backend with remote "TestB2:rclone-test-fogiqof1viri" 2025/01/22 01:20:31 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/01/22 01:20:32 DEBUG : Creating backend with remote "/tmp/rclone3970289793" === 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-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", Modify Window "1ms" 2025/01/22 01:20:36 DEBUG : chunksize-probe: Cancelling large file upload === RUN TestMultithreadCopy/upload=false,size=201326591,streams=2 2025/01/22 01:20:58 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2025/01/22 01:20:58 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: write buffer set to 131072 2025/01/22 01:20:58 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2025/01/22 01:20:58 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2025/01/22 01:20:58 DEBUG : test-multithread-copy-false-201326591-2: Starting multi-thread copy with 3 chunks of size 64Mi with 3 parallel streams 2025/01/22 01:20:58 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 1/3 (0-67108864) size 64Mi starting 2025/01/22 01:20:58 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 3/3 (134217728-201326591) size 64.000Mi starting 2025/01/22 01:20:58 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 2/3 (67108864-134217728) size 64Mi starting 2025/01/22 01:20:58 DEBUG : test-multithread-copy-false-201326591-2: writing chunk 0 2025/01/22 01:20:59 DEBUG : test-multithread-copy-false-201326591-2: writing chunk 1 2025/01/22 01:20:59 DEBUG : test-multithread-copy-false-201326591-2: writing chunk 2 2025/01/22 01:21:03 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 1/3 (0-67108864) size 64Mi finished 2025/01/22 01:21:04 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 2/3 (67108864-134217728) size 64Mi finished 2025/01/22 01:21:04 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 3/3 (134217728-201326591) size 64.000Mi finished 2025/01/22 01:21:04 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/01/22 01:21:34 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2025/01/22 01:21:34 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: write buffer set to 131072 2025/01/22 01:21:34 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2025/01/22 01:21:34 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2025/01/22 01:21:34 DEBUG : test-multithread-copy-false-201326592-2: Starting multi-thread copy with 3 chunks of size 64Mi with 3 parallel streams 2025/01/22 01:21:34 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 3/3 (134217728-201326592) size 64Mi starting 2025/01/22 01:21:34 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 1/3 (0-67108864) size 64Mi starting 2025/01/22 01:21:34 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 2/3 (67108864-134217728) size 64Mi starting 2025/01/22 01:21:34 DEBUG : test-multithread-copy-false-201326592-2: writing chunk 2 2025/01/22 01:21:35 DEBUG : test-multithread-copy-false-201326592-2: writing chunk 0 2025/01/22 01:21:35 DEBUG : test-multithread-copy-false-201326592-2: writing chunk 1 2025/01/22 01:21:40 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 3/3 (134217728-201326592) size 64Mi finished 2025/01/22 01:21:40 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 1/3 (0-67108864) size 64Mi finished 2025/01/22 01:21:40 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 2/3 (67108864-134217728) size 64Mi finished 2025/01/22 01:21:40 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/01/22 01:22:10 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2025/01/22 01:22:10 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: write buffer set to 131072 2025/01/22 01:22:10 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2025/01/22 01:22:10 DEBUG : test-multithread-copy-false-201326593-2: Starting multi-thread copy with 4 chunks of size 64Mi with 4 parallel streams 2025/01/22 01:22:10 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 4/4 (201326592-201326593) size 1 starting 2025/01/22 01:22:10 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 1/4 (0-67108864) size 64Mi starting 2025/01/22 01:22:10 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 3/4 (134217728-201326592) size 64Mi starting 2025/01/22 01:22:10 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 2/4 (67108864-134217728) size 64Mi starting 2025/01/22 01:22:10 DEBUG : test-multithread-copy-false-201326593-2: writing chunk 3 2025/01/22 01:22:10 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 4/4 (201326592-201326593) size 1 finished 2025/01/22 01:22:10 DEBUG : test-multithread-copy-false-201326593-2: writing chunk 0 2025/01/22 01:22:10 DEBUG : test-multithread-copy-false-201326593-2: writing chunk 1 2025/01/22 01:22:10 DEBUG : test-multithread-copy-false-201326593-2: writing chunk 2 2025/01/22 01:22:15 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 1/4 (0-67108864) size 64Mi finished 2025/01/22 01:22:15 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 2/4 (67108864-134217728) size 64Mi finished 2025/01/22 01:22:15 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 3/4 (134217728-201326592) size 64Mi finished 2025/01/22 01:22:15 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/01/22 01:22:37 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: disabling buffering because source is local disk 2025/01/22 01:22:38 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2025/01/22 01:22:38 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: number of streams 4 was bigger than number of chunks 2 2025/01/22 01:22:38 DEBUG : test-multithread-copy-true-201326591-2: Starting multi-thread copy with 2 chunks of size 96Mi with 2 parallel streams 2025/01/22 01:22:38 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: chunk 2/2 (100663296-201326591) size 96.000Mi starting 2025/01/22 01:22:38 DEBUG : test-multithread-copy-true-201326591-2: Sending chunk 1 length 100663295 2025/01/22 01:22:38 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: chunk 1/2 (0-100663296) size 96Mi starting 2025/01/22 01:22:38 DEBUG : test-multithread-copy-true-201326591-2: Sending chunk 0 length 100663296 2025/01/22 01:22:46 DEBUG : test-multithread-copy-true-201326591-2: Done sending chunk 1 2025/01/22 01:22:46 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: chunk 2/2 (100663296-201326591) size 96.000Mi finished 2025/01/22 01:22:46 DEBUG : test-multithread-copy-true-201326591-2: Done sending chunk 0 2025/01/22 01:22:46 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: chunk 1/2 (0-100663296) size 96Mi finished 2025/01/22 01:22:46 DEBUG : test-multithread-copy-true-201326591-2: Finishing large file upload with 2 parts 2025/01/22 01:22:47 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/01/22 01:23:08 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: disabling buffering because source is local disk 2025/01/22 01:23:09 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2025/01/22 01:23:09 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: number of streams 4 was bigger than number of chunks 2 2025/01/22 01:23:09 DEBUG : test-multithread-copy-true-201326592-2: Starting multi-thread copy with 2 chunks of size 96Mi with 2 parallel streams 2025/01/22 01:23:09 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: chunk 2/2 (100663296-201326592) size 96Mi starting 2025/01/22 01:23:09 DEBUG : test-multithread-copy-true-201326592-2: Sending chunk 1 length 100663296 2025/01/22 01:23:09 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: chunk 1/2 (0-100663296) size 96Mi starting 2025/01/22 01:23:09 DEBUG : test-multithread-copy-true-201326592-2: Sending chunk 0 length 100663296 2025/01/22 01:23:17 DEBUG : test-multithread-copy-true-201326592-2: Done sending chunk 1 2025/01/22 01:23:17 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: chunk 2/2 (100663296-201326592) size 96Mi finished 2025/01/22 01:23:18 DEBUG : test-multithread-copy-true-201326592-2: Done sending chunk 0 2025/01/22 01:23:18 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: chunk 1/2 (0-100663296) size 96Mi finished 2025/01/22 01:23:18 DEBUG : test-multithread-copy-true-201326592-2: Finishing large file upload with 2 parts 2025/01/22 01:23:18 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/01/22 01:23:41 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: disabling buffering because source is local disk 2025/01/22 01:23:42 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2025/01/22 01:23:42 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2025/01/22 01:23:42 DEBUG : test-multithread-copy-true-201326593-2: Starting multi-thread copy with 3 chunks of size 96Mi with 3 parallel streams 2025/01/22 01:23:42 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 3/3 (201326592-201326593) size 1 starting 2025/01/22 01:23:42 DEBUG : test-multithread-copy-true-201326593-2: Sending chunk 2 length 1 2025/01/22 01:23:42 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 2/3 (100663296-201326592) size 96Mi starting 2025/01/22 01:23:42 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 1/3 (0-100663296) size 96Mi starting 2025/01/22 01:23:42 DEBUG : test-multithread-copy-true-201326593-2: Sending chunk 1 length 100663296 2025/01/22 01:23:42 DEBUG : test-multithread-copy-true-201326593-2: Sending chunk 0 length 100663296 2025/01/22 01:23:42 DEBUG : test-multithread-copy-true-201326593-2: Done sending chunk 2 2025/01/22 01:23:42 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 3/3 (201326592-201326593) size 1 finished 2025/01/22 01:23:50 DEBUG : test-multithread-copy-true-201326593-2: Done sending chunk 1 2025/01/22 01:23:50 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 2/3 (100663296-201326592) size 96Mi finished 2025/01/22 01:23:50 DEBUG : test-multithread-copy-true-201326593-2: Done sending chunk 0 2025/01/22 01:23:50 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 1/3 (0-100663296) size 96Mi finished 2025/01/22 01:23:50 DEBUG : test-multithread-copy-true-201326593-2: Finishing large file upload with 3 parts 2025/01/22 01:23:51 DEBUG : test-multithread-copy-true-201326593-2: Finished multi-thread copy with 3 parts of size 96Mi --- PASS: TestMultithreadCopy (206.95s) --- PASS: TestMultithreadCopy/upload=false,size=201326591,streams=2 (35.47s) --- PASS: TestMultithreadCopy/upload=false,size=201326592,streams=2 (36.02s) --- PASS: TestMultithreadCopy/upload=false,size=201326593,streams=2 (35.33s) --- PASS: TestMultithreadCopy/upload=true,size=201326591,streams=2 (31.15s) --- PASS: TestMultithreadCopy/upload=true,size=201326592,streams=2 (32.67s) --- PASS: TestMultithreadCopy/upload=true,size=201326593,streams=2 (31.53s) === RUN TestMultithreadCopyAbort run.go:180: Remote "B2 bucket rclone-test-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", Modify Window "1ms" 2025/01/22 01:23:59 DEBUG : chunksize-probe: Cancelling large file upload 2025/01/22 01:24:13 DEBUG : test-multithread-abort: multi-thread copy: disabling buffering because source is local disk 2025/01/22 01:24:14 DEBUG : test-multithread-abort: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 1 2025/01/22 01:24:14 DEBUG : test-multithread-abort: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2025/01/22 01:24:14 DEBUG : test-multithread-abort: Starting multi-thread copy with 3 chunks of size 96Mi with 3 parallel streams 2025/01/22 01:24:14 DEBUG : test-multithread-abort: multi-thread copy: chunk 3/3 (201326592-201326593) size 1 starting 2025/01/22 01:24:14 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2025/01/22 01:24:14 DEBUG : test-multithread-abort: multi-thread copy: chunk 2/3 (100663296-201326592) size 96Mi starting 2025/01/22 01:24:14 DEBUG : test-multithread-abort: multi-thread copy: chunk 1/3 (0-100663296) size 96Mi starting 2025/01/22 01:24:14 DEBUG : Open with options = [RangeOption(100663296,201326591)] 2025/01/22 01:24:14 DEBUG : Open with options = [RangeOption(0,100663295)] 2025/01/22 01:24:14 DEBUG : test-multithread-abort: Sending chunk 1 length 100663296 2025/01/22 01:24:14 DEBUG : test-multithread-abort: Sending chunk 0 length 100663296 2025/01/22 01:24:22 DEBUG : test-multithread-abort: Done sending chunk 1 2025/01/22 01:24:22 DEBUG : test-multithread-abort: multi-thread copy: chunk 2/3 (100663296-201326592) size 96Mi finished 2025/01/22 01:24:23 DEBUG : test-multithread-abort: Done sending chunk 0 2025/01/22 01:24:23 DEBUG : test-multithread-abort: multi-thread copy: chunk 1/3 (0-100663296) size 96Mi finished 2025/01/22 01:24:23 DEBUG : Returning error reader 2025/01/22 01:24:23 DEBUG : test-multithread-abort: Sending chunk 2 length 1 2025/01/22 01:24:23 DEBUG : BOOM: simulated read failure 2025/01/22 01:24:23 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 0/10: BOOM: simulated read failure 2025/01/22 01:24:23 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2025/01/22 01:24:24 DEBUG : Returning error reader 2025/01/22 01:24:24 DEBUG : BOOM: simulated read failure 2025/01/22 01:24:24 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 1/10: BOOM: simulated read failure 2025/01/22 01:24:24 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2025/01/22 01:24:25 DEBUG : Returning error reader 2025/01/22 01:24:25 DEBUG : BOOM: simulated read failure 2025/01/22 01:24:25 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 2/10: BOOM: simulated read failure 2025/01/22 01:24:25 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2025/01/22 01:24:26 DEBUG : Returning error reader 2025/01/22 01:24:26 DEBUG : BOOM: simulated read failure 2025/01/22 01:24:26 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 3/10: BOOM: simulated read failure 2025/01/22 01:24:26 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2025/01/22 01:24:27 DEBUG : Returning error reader 2025/01/22 01:24:27 DEBUG : BOOM: simulated read failure 2025/01/22 01:24:27 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 4/10: BOOM: simulated read failure 2025/01/22 01:24:27 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2025/01/22 01:24:28 DEBUG : Returning error reader 2025/01/22 01:24:28 DEBUG : BOOM: simulated read failure 2025/01/22 01:24:28 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 5/10: BOOM: simulated read failure 2025/01/22 01:24:28 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2025/01/22 01:24:29 DEBUG : Returning error reader 2025/01/22 01:24:29 DEBUG : BOOM: simulated read failure 2025/01/22 01:24:29 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 6/10: BOOM: simulated read failure 2025/01/22 01:24:29 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2025/01/22 01:24:30 DEBUG : Returning error reader 2025/01/22 01:24:30 DEBUG : BOOM: simulated read failure 2025/01/22 01:24:30 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 7/10: BOOM: simulated read failure 2025/01/22 01:24:30 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2025/01/22 01:24:31 DEBUG : Returning error reader 2025/01/22 01:24:31 DEBUG : BOOM: simulated read failure 2025/01/22 01:24:31 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 8/10: BOOM: simulated read failure 2025/01/22 01:24:31 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2025/01/22 01:24:32 DEBUG : Returning error reader 2025/01/22 01:24:32 DEBUG : BOOM: simulated read failure 2025/01/22 01:24:32 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 9/10: BOOM: simulated read failure 2025/01/22 01:24:32 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2025/01/22 01:24:33 DEBUG : Returning error reader 2025/01/22 01:24:33 DEBUG : BOOM: simulated read failure 2025/01/22 01:24:33 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 10/10: BOOM: simulated read failure 2025/01/22 01:24:33 DEBUG : test-multithread-abort: Reopen failed after offset 0 bytes read: failed to reopen: too many retries 2025/01/22 01:24:33 DEBUG : test-multithread-abort: Error sending chunk 2 (retry=false): Post "https://pod-000-1183-01.backblaze.com/b2api/v1/b2_upload_part/4_z0f72cd73908d8bbb9f480e1b_f2057744a63590ec0_d20250122_m012414_c001_v0001183_t0030_u01737509054789/0037": BOOM: simulated read failure: &url.Error{Op:"Post", URL:"https://pod-000-1183-01.backblaze.com/b2api/v1/b2_upload_part/4_z0f72cd73908d8bbb9f480e1b_f2057744a63590ec0_d20250122_m012414_c001_v0001183_t0030_u01737509054789/0037", Err:(*errors.errorString)(0xc000892ce0)} 2025/01/22 01:24:33 DEBUG : test-multithread-abort: Error sending chunk 2: Post "https://pod-000-1183-01.backblaze.com/b2api/v1/b2_upload_part/4_z0f72cd73908d8bbb9f480e1b_f2057744a63590ec0_d20250122_m012414_c001_v0001183_t0030_u01737509054789/0037": BOOM: simulated read failure 2025/01/22 01:24:33 DEBUG : test-multithread-abort: multi-thread copy: chunk 3/3 failed: multi-thread copy: failed to write chunk: Post "https://pod-000-1183-01.backblaze.com/b2api/v1/b2_upload_part/4_z0f72cd73908d8bbb9f480e1b_f2057744a63590ec0_d20250122_m012414_c001_v0001183_t0030_u01737509054789/0037": BOOM: simulated read failure 2025/01/22 01:24:33 DEBUG : test-multithread-abort: multi-thread copy: cancelling transfer on exit 2025/01/22 01:24:33 DEBUG : test-multithread-abort: Cancelling large file upload --- PASS: TestMultithreadCopyAbort (35.15s) === RUN TestSizeDiffers --- PASS: TestSizeDiffers (0.00s) === RUN TestReOpen === RUN TestReOpen/Normal === RUN TestReOpen/Normal/Basics 2025/01/22 01:24:34 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/ErrorAtStart === RUN TestReOpen/Normal/WithErrors 2025/01/22 01:24:34 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2025/01/22 01:24:34 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2025/01/22 01:24:34 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/Normal/TooManyErrors 2025/01/22 01:24:34 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2025/01/22 01:24:34 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2025/01/22 01:24:34 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2025/01/22 01:24:34 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/Normal/Seek 2025/01/22 01:24:34 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/01/22 01:24:34 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/01/22 01:24:34 DEBUG : potato: Seek from 5 to 2 === RUN TestReOpen/Normal/AccountRead === RUN TestReOpen/Normal/AccountReadDelay 2025/01/22 01:24:34 DEBUG : potato: Seek from 10 to 0 2025/01/22 01:24:34 DEBUG : potato: Seek from 10 to 0 2025/01/22 01:24:34 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/AccountReadError === RUN TestReOpen/WithRangeOption === RUN TestReOpen/WithRangeOption/Basics 2025/01/22 01:24:34 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/ErrorAtStart === RUN TestReOpen/WithRangeOption/WithErrors 2025/01/22 01:24:34 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2025/01/22 01:24:34 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2025/01/22 01:24:34 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithRangeOption/TooManyErrors 2025/01/22 01:24:34 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2025/01/22 01:24:34 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2025/01/22 01:24:34 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2025/01/22 01:24:34 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithRangeOption/Seek 2025/01/22 01:24:34 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/01/22 01:24:34 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/01/22 01:24:34 DEBUG : potato: Seek from 5 to 2 2025/01/22 01:24:34 DEBUG : potato: Seek from 7 to 4 === RUN TestReOpen/WithRangeOption/AccountRead === RUN TestReOpen/WithRangeOption/AccountReadDelay 2025/01/22 01:24:34 DEBUG : potato: Seek from 7 to 0 2025/01/22 01:24:34 DEBUG : potato: Seek from 7 to 0 2025/01/22 01:24:34 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/AccountReadError === RUN TestReOpen/WithSeekOption === RUN TestReOpen/WithSeekOption/Basics 2025/01/22 01:24:34 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/ErrorAtStart === RUN TestReOpen/WithSeekOption/WithErrors 2025/01/22 01:24:34 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2025/01/22 01:24:34 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2025/01/22 01:24:34 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithSeekOption/TooManyErrors 2025/01/22 01:24:34 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2025/01/22 01:24:34 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2025/01/22 01:24:34 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2025/01/22 01:24:34 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithSeekOption/Seek 2025/01/22 01:24:34 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/01/22 01:24:34 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/01/22 01:24:34 DEBUG : potato: Seek from 5 to 2 2025/01/22 01:24:34 DEBUG : potato: Seek from 7 to 5 === RUN TestReOpen/WithSeekOption/AccountRead === RUN TestReOpen/WithSeekOption/AccountReadDelay 2025/01/22 01:24:34 DEBUG : potato: Seek from 8 to 0 2025/01/22 01:24:34 DEBUG : potato: Seek from 8 to 0 2025/01/22 01:24:34 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/AccountReadError === RUN TestReOpen/UnknownSize === RUN TestReOpen/UnknownSize/Basics 2025/01/22 01:24:34 DEBUG : potato: Seek from 9 to 0 === RUN TestReOpen/UnknownSize/ErrorAtStart === RUN TestReOpen/UnknownSize/WithErrors 2025/01/22 01:24:34 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2025/01/22 01:24:34 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2025/01/22 01:24:34 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/UnknownSize/TooManyErrors 2025/01/22 01:24:34 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2025/01/22 01:24:34 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2025/01/22 01:24:34 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2025/01/22 01:24:34 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/UnknownSize/Seek 2025/01/22 01:24:34 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/01/22 01:24:34 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/01/22 01:24:34 DEBUG : potato: Seek from 5 to 2 2025/01/22 01:24:34 DEBUG : potato: Seek from 7 to 6 === RUN TestReOpen/UnknownSize/AccountRead === RUN TestReOpen/UnknownSize/AccountReadDelay 2025/01/22 01:24:34 DEBUG : potato: Seek from 9 to 0 2025/01/22 01:24:34 DEBUG : potato: Seek from 9 to 0 2025/01/22 01:24:34 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-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", Modify Window "1ms" === RUN TestCheck/1 === RUN TestCheck/2 === RUN TestCheck/3 === RUN TestCheck/4 === RUN TestCheck/5 === RUN TestCheck/6 === RUN TestCheck/7 --- PASS: TestCheck (4.31s) --- PASS: TestCheck/1 (0.18s) --- PASS: TestCheck/2 (0.18s) --- PASS: TestCheck/3 (0.18s) --- PASS: TestCheck/4 (0.18s) --- PASS: TestCheck/5 (0.18s) --- PASS: TestCheck/6 (0.19s) --- PASS: TestCheck/7 (0.18s) === RUN TestCheckFsError 2025/01/22 01:24:38 DEBUG : Creating backend with remote "nonexistent" 2025/01/22 01:24:38 DEBUG : Config file has changed externally - reloading 2025/01/22 01:24:38 DEBUG : Creating backend with remote "nonexistent" 2025/01/22 01:24:38 DEBUG : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: Waiting for checks to finish 2025/01/22 01:24:38 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/01/22 01:24:38 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: 2 differences found 2025/01/22 01:24:38 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-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", Modify Window "1ms" === RUN TestCheckDownload/1 === RUN TestCheckDownload/2 === RUN TestCheckDownload/3 === RUN TestCheckDownload/4 === RUN TestCheckDownload/5 === RUN TestCheckDownload/6 === RUN TestCheckDownload/7 --- PASS: TestCheckDownload (5.85s) --- PASS: TestCheckDownload/1 (0.72s) --- PASS: TestCheckDownload/2 (0.37s) --- PASS: TestCheckDownload/3 (0.36s) --- PASS: TestCheckDownload/4 (0.55s) --- PASS: TestCheckDownload/5 (0.38s) --- PASS: TestCheckDownload/6 (0.37s) --- PASS: TestCheckDownload/7 (0.43s) === RUN TestCheckSizeOnly run.go:180: Remote "B2 bucket rclone-test-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", 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.06s) --- PASS: TestCheckSizeOnly/1 (0.18s) --- PASS: TestCheckSizeOnly/2 (0.18s) --- PASS: TestCheckSizeOnly/3 (0.18s) --- PASS: TestCheckSizeOnly/4 (0.18s) --- PASS: TestCheckSizeOnly/5 (0.18s) --- PASS: TestCheckSizeOnly/6 (0.18s) --- PASS: TestCheckSizeOnly/7 (0.19s) === RUN TestCheckEqualReaders --- PASS: TestCheckEqualReaders (0.00s) === RUN TestParseSumFile run.go:180: Remote "B2 bucket rclone-test-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", Modify Window "1ms" 2025/01/22 01:24:49 NOTICE: test.sum: improperly formatted checksum line 4 2025/01/22 01:24:49 NOTICE: test.sum: improperly formatted checksum line 5 2025/01/22 01:24:49 NOTICE: test.sum: improperly formatted checksum line 6 2025/01/22 01:24:49 NOTICE: test.sum: 2 warning(s) suppressed... 2025/01/22 01:24:50 NOTICE: test.sum: improperly formatted checksum line 4 2025/01/22 01:24:50 NOTICE: test.sum: improperly formatted checksum line 5 2025/01/22 01:24:50 NOTICE: test.sum: improperly formatted checksum line 6 2025/01/22 01:24:50 NOTICE: test.sum: 2 warning(s) suppressed... --- PASS: TestParseSumFile (2.04s) === RUN TestCheckSum run.go:180: Remote "B2 bucket rclone-test-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", Modify Window "1ms" 2025/01/22 01:24:50 DEBUG : Creating backend with remote "TestB2:rclone-test-fogiqof1viri/data" check_test.go:353: B2 bucket rclone-test-fogiqof1viri path data lacks md5, skipping --- SKIP: TestCheckSum (1.46s) === RUN TestCheckSumDownload run.go:180: Remote "B2 bucket rclone-test-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", Modify Window "1ms" 2025/01/22 01:24:52 DEBUG : Creating backend with remote "TestB2:rclone-test-fogiqof1viri/data" === RUN TestCheckSumDownload/subtest1 === RUN TestCheckSumDownload/subtest2 === RUN TestCheckSumDownload/subtest3 === RUN TestCheckSumDownload/subtest4 === RUN TestCheckSumDownload/subtest5 === RUN TestCheckSumDownload/subtest6 === RUN TestCheckSumDownload/subtest7 --- PASS: TestCheckSumDownload (11.57s) --- PASS: TestCheckSumDownload/subtest1 (1.35s) --- PASS: TestCheckSumDownload/subtest2 (0.73s) --- PASS: TestCheckSumDownload/subtest3 (0.92s) --- PASS: TestCheckSumDownload/subtest4 (0.74s) --- PASS: TestCheckSumDownload/subtest5 (0.75s) --- PASS: TestCheckSumDownload/subtest6 (0.74s) --- PASS: TestCheckSumDownload/subtest7 (0.75s) === RUN TestApplyTransforms 2025/01/22 01:25:03 DEBUG : Creating backend with remote "TestB2:rclone-test-sigunoz4joni" 2025/01/22 01:25:03 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/01/22 01:25:04 DEBUG : Creating backend with remote "/tmp/rclone3390289242" run.go:180: Remote "B2 bucket rclone-test-sigunoz4joni", Local "Local file system at /tmp/rclone3390289242", Modify Window "1ms" upper checkfile vs. lower remote (without normalization) 2025/01/22 01:25:09 ERROR : hello, world!: sum not found 2025/01/22 01:25:09 ERROR : HELLO, WORLD!: file not in B2 bucket rclone-test-sigunoz4joni 2025/01/22 01:25:09 NOTICE: B2 bucket rclone-test-sigunoz4joni: 1 files missing 2025/01/22 01:25:09 NOTICE: 1 hashes missing 2025/01/22 01:25:09 NOTICE: B2 bucket rclone-test-sigunoz4joni: 2 differences found 2025/01/22 01:25:09 NOTICE: B2 bucket rclone-test-sigunoz4joni: 2 errors while checking upper checkfile vs. lower remote (with normalization) 2025/01/22 01:25:10 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/01/22 01:25:10 NOTICE: B2 bucket rclone-test-sigunoz4joni: 0 differences found 2025/01/22 01:25:10 NOTICE: B2 bucket rclone-test-sigunoz4joni: 1 matching files 2025/01/22 01:25:10 DEBUG : Creating backend with remote "TestB2:rclone-test-sulejem1veci" 2025/01/22 01:25:10 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/01/22 01:25:10 DEBUG : Creating backend with remote "/tmp/rclone2430434441" run.go:180: Remote "B2 bucket rclone-test-sulejem1veci", Local "Local file system at /tmp/rclone2430434441", Modify Window "1ms" lower checkfile vs. upper remote (without normalization) 2025/01/22 01:25:16 ERROR : HELLO, WORLD!: sum not found 2025/01/22 01:25:16 ERROR : hello, world!: file not in B2 bucket rclone-test-sulejem1veci 2025/01/22 01:25:16 NOTICE: B2 bucket rclone-test-sulejem1veci: 1 files missing 2025/01/22 01:25:16 NOTICE: 1 hashes missing 2025/01/22 01:25:16 NOTICE: B2 bucket rclone-test-sulejem1veci: 2 differences found 2025/01/22 01:25:16 NOTICE: B2 bucket rclone-test-sulejem1veci: 2 errors while checking lower checkfile vs. upper remote (with normalization) 2025/01/22 01:25:16 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/01/22 01:25:16 NOTICE: B2 bucket rclone-test-sulejem1veci: 0 differences found 2025/01/22 01:25:16 NOTICE: B2 bucket rclone-test-sulejem1veci: 1 matching files 2025/01/22 01:25:16 DEBUG : Creating backend with remote "TestB2:rclone-test-yajefas9bala" 2025/01/22 01:25:16 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/01/22 01:25:17 DEBUG : Creating backend with remote "/tmp/rclone1680253583" run.go:180: Remote "B2 bucket rclone-test-yajefas9bala", Local "Local file system at /tmp/rclone1680253583", Modify Window "1ms" lower checkfile vs. upperlowermixed remote (without normalization) 2025/01/22 01:25:22 ERROR : HeLlO, wOrLd!: sum not found 2025/01/22 01:25:22 ERROR : hello, world!: file not in B2 bucket rclone-test-yajefas9bala 2025/01/22 01:25:22 NOTICE: B2 bucket rclone-test-yajefas9bala: 1 files missing 2025/01/22 01:25:22 NOTICE: 1 hashes missing 2025/01/22 01:25:22 NOTICE: B2 bucket rclone-test-yajefas9bala: 2 differences found 2025/01/22 01:25:22 NOTICE: B2 bucket rclone-test-yajefas9bala: 2 errors while checking lower checkfile vs. upperlowermixed remote (with normalization) 2025/01/22 01:25:23 DEBUG : HeLlO, wOrLd!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/01/22 01:25:23 NOTICE: B2 bucket rclone-test-yajefas9bala: 0 differences found 2025/01/22 01:25:23 NOTICE: B2 bucket rclone-test-yajefas9bala: 1 matching files 2025/01/22 01:25:23 DEBUG : Creating backend with remote "TestB2:rclone-test-moxigov1sasa" 2025/01/22 01:25:23 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/01/22 01:25:23 DEBUG : Creating backend with remote "/tmp/rclone1878893485" run.go:180: Remote "B2 bucket rclone-test-moxigov1sasa", Local "Local file system at /tmp/rclone1878893485", Modify Window "1ms" upperlowermixed checkfile vs. upper remote (without normalization) 2025/01/22 01:25:28 ERROR : HELLO, WORLD!: sum not found 2025/01/22 01:25:28 ERROR : HeLlO, wOrLd!: file not in B2 bucket rclone-test-moxigov1sasa 2025/01/22 01:25:28 NOTICE: B2 bucket rclone-test-moxigov1sasa: 1 files missing 2025/01/22 01:25:28 NOTICE: 1 hashes missing 2025/01/22 01:25:28 NOTICE: B2 bucket rclone-test-moxigov1sasa: 2 differences found 2025/01/22 01:25:28 NOTICE: B2 bucket rclone-test-moxigov1sasa: 2 errors while checking upperlowermixed checkfile vs. upper remote (with normalization) 2025/01/22 01:25:29 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/01/22 01:25:29 NOTICE: B2 bucket rclone-test-moxigov1sasa: 0 differences found 2025/01/22 01:25:29 NOTICE: B2 bucket rclone-test-moxigov1sasa: 1 matching files 2025/01/22 01:25:29 DEBUG : Creating backend with remote "TestB2:rclone-test-hupuroj4pedi" 2025/01/22 01:25:29 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/01/22 01:25:29 DEBUG : Creating backend with remote "/tmp/rclone2279268933" run.go:180: Remote "B2 bucket rclone-test-hupuroj4pedi", Local "Local file system at /tmp/rclone2279268933", Modify Window "1ms" NFD checkfile vs. NFC remote (without normalization) 2025/01/22 01:25:35 ERROR : 測試_Русский___ě_áñ: sum not found 2025/01/22 01:25:35 ERROR : 測試_Русский___ě_áñ: file not in B2 bucket rclone-test-hupuroj4pedi 2025/01/22 01:25:35 NOTICE: B2 bucket rclone-test-hupuroj4pedi: 1 files missing 2025/01/22 01:25:35 NOTICE: 1 hashes missing 2025/01/22 01:25:35 NOTICE: B2 bucket rclone-test-hupuroj4pedi: 2 differences found 2025/01/22 01:25:35 NOTICE: B2 bucket rclone-test-hupuroj4pedi: 2 errors while checking NFD checkfile vs. NFC remote (with normalization) 2025/01/22 01:25:36 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/01/22 01:25:36 NOTICE: B2 bucket rclone-test-hupuroj4pedi: 0 differences found 2025/01/22 01:25:36 NOTICE: B2 bucket rclone-test-hupuroj4pedi: 1 matching files 2025/01/22 01:25:36 DEBUG : Creating backend with remote "TestB2:rclone-test-tigobam4haki" 2025/01/22 01:25:36 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/01/22 01:25:36 DEBUG : Creating backend with remote "/tmp/rclone190369174" run.go:180: Remote "B2 bucket rclone-test-tigobam4haki", Local "Local file system at /tmp/rclone190369174", Modify Window "1ms" NFC checkfile vs. NFD remote (without normalization) 2025/01/22 01:25:42 ERROR : 測試_Русский___ě_áñ: sum not found 2025/01/22 01:25:42 ERROR : 測試_Русский___ě_áñ: file not in B2 bucket rclone-test-tigobam4haki 2025/01/22 01:25:42 NOTICE: B2 bucket rclone-test-tigobam4haki: 1 files missing 2025/01/22 01:25:42 NOTICE: 1 hashes missing 2025/01/22 01:25:42 NOTICE: B2 bucket rclone-test-tigobam4haki: 2 differences found 2025/01/22 01:25:42 NOTICE: B2 bucket rclone-test-tigobam4haki: 2 errors while checking NFC checkfile vs. NFD remote (with normalization) 2025/01/22 01:25:42 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/01/22 01:25:42 NOTICE: B2 bucket rclone-test-tigobam4haki: 0 differences found 2025/01/22 01:25:42 NOTICE: B2 bucket rclone-test-tigobam4haki: 1 matching files 2025/01/22 01:25:42 DEBUG : Creating backend with remote "TestB2:rclone-test-xazekat1vopo" 2025/01/22 01:25:42 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/01/22 01:25:43 DEBUG : Creating backend with remote "/tmp/rclone2354455793" run.go:180: Remote "B2 bucket rclone-test-xazekat1vopo", Local "Local file system at /tmp/rclone2354455793", Modify Window "1ms" NFDx2 checkfile vs. both remote (without normalization) 2025/01/22 01:25:48 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2025/01/22 01:25:48 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-xazekat1vopo 2025/01/22 01:25:48 NOTICE: B2 bucket rclone-test-xazekat1vopo: 1 files missing 2025/01/22 01:25:48 NOTICE: 1 hashes missing 2025/01/22 01:25:48 NOTICE: B2 bucket rclone-test-xazekat1vopo: 2 differences found 2025/01/22 01:25:48 NOTICE: B2 bucket rclone-test-xazekat1vopo: 2 errors while checking NFDx2 checkfile vs. both remote (with normalization) 2025/01/22 01:25:49 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/01/22 01:25:49 NOTICE: B2 bucket rclone-test-xazekat1vopo: 0 differences found 2025/01/22 01:25:49 NOTICE: B2 bucket rclone-test-xazekat1vopo: 1 matching files 2025/01/22 01:25:49 DEBUG : Creating backend with remote "TestB2:rclone-test-wicoram8ceza" 2025/01/22 01:25:49 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/01/22 01:25:49 DEBUG : Creating backend with remote "/tmp/rclone1645823592" run.go:180: Remote "B2 bucket rclone-test-wicoram8ceza", Local "Local file system at /tmp/rclone1645823592", Modify Window "1ms" NFCx2 checkfile vs. both remote (without normalization) 2025/01/22 01:25:54 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2025/01/22 01:25:54 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-wicoram8ceza 2025/01/22 01:25:54 NOTICE: B2 bucket rclone-test-wicoram8ceza: 1 files missing 2025/01/22 01:25:54 NOTICE: 1 hashes missing 2025/01/22 01:25:54 NOTICE: B2 bucket rclone-test-wicoram8ceza: 2 differences found 2025/01/22 01:25:54 NOTICE: B2 bucket rclone-test-wicoram8ceza: 2 errors while checking NFCx2 checkfile vs. both remote (with normalization) 2025/01/22 01:25:55 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/01/22 01:25:55 NOTICE: B2 bucket rclone-test-wicoram8ceza: 0 differences found 2025/01/22 01:25:55 NOTICE: B2 bucket rclone-test-wicoram8ceza: 1 matching files 2025/01/22 01:25:55 DEBUG : Creating backend with remote "TestB2:rclone-test-qozuhun1momo" 2025/01/22 01:25:55 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/01/22 01:25:56 DEBUG : Creating backend with remote "/tmp/rclone2990979321" run.go:180: Remote "B2 bucket rclone-test-qozuhun1momo", Local "Local file system at /tmp/rclone2990979321", Modify Window "1ms" both checkfile vs. NFDx2 remote (without normalization) 2025/01/22 01:26:01 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2025/01/22 01:26:01 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-qozuhun1momo 2025/01/22 01:26:01 NOTICE: B2 bucket rclone-test-qozuhun1momo: 1 files missing 2025/01/22 01:26:01 NOTICE: 1 hashes missing 2025/01/22 01:26:01 NOTICE: B2 bucket rclone-test-qozuhun1momo: 2 differences found 2025/01/22 01:26:01 NOTICE: B2 bucket rclone-test-qozuhun1momo: 2 errors while checking both checkfile vs. NFDx2 remote (with normalization) 2025/01/22 01:26:02 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/01/22 01:26:02 NOTICE: B2 bucket rclone-test-qozuhun1momo: 0 differences found 2025/01/22 01:26:02 NOTICE: B2 bucket rclone-test-qozuhun1momo: 1 matching files 2025/01/22 01:26:02 DEBUG : Creating backend with remote "TestB2:rclone-test-xaluwuv6vixi" 2025/01/22 01:26:02 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/01/22 01:26:02 DEBUG : Creating backend with remote "/tmp/rclone3288753135" run.go:180: Remote "B2 bucket rclone-test-xaluwuv6vixi", Local "Local file system at /tmp/rclone3288753135", Modify Window "1ms" both checkfile vs. NFCx2 remote (without normalization) 2025/01/22 01:26:08 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2025/01/22 01:26:08 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-xaluwuv6vixi 2025/01/22 01:26:08 NOTICE: B2 bucket rclone-test-xaluwuv6vixi: 1 files missing 2025/01/22 01:26:08 NOTICE: 1 hashes missing 2025/01/22 01:26:08 NOTICE: B2 bucket rclone-test-xaluwuv6vixi: 2 differences found 2025/01/22 01:26:08 NOTICE: B2 bucket rclone-test-xaluwuv6vixi: 2 errors while checking both checkfile vs. NFCx2 remote (with normalization) 2025/01/22 01:26:09 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/01/22 01:26:09 NOTICE: B2 bucket rclone-test-xaluwuv6vixi: 0 differences found 2025/01/22 01:26:09 NOTICE: B2 bucket rclone-test-xaluwuv6vixi: 1 matching files 2025/01/22 01:26:09 DEBUG : B2 bucket rclone-test-xaluwuv6vixi: Purge remote 2025/01/22 01:26:09 INFO : B2 bucket rclone-test-xaluwuv6vixi: cleaning bucket "rclone-test-xaluwuv6vixi" of all files 2025/01/22 01:26:09 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_z8fe2dd1390dd8bbb9f480e1b_f100f7087a2a23336_d20250122_m012607_c001_v0001106_t0034_u01737509167624") 2025/01/22 01:26:13 DEBUG : B2 bucket rclone-test-qozuhun1momo: Purge remote 2025/01/22 01:26:13 INFO : B2 bucket rclone-test-qozuhun1momo: cleaning bucket "rclone-test-qozuhun1momo" of all files 2025/01/22 01:26:14 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_z1fe2bd7390dd8bbb9f480e1b_f1149a3051571cdc6_d20250122_m012600_c001_v0001120_t0026_u01737509160780") 2025/01/22 01:26:16 DEBUG : B2 bucket rclone-test-wicoram8ceza: Purge remote 2025/01/22 01:26:16 INFO : B2 bucket rclone-test-wicoram8ceza: cleaning bucket "rclone-test-wicoram8ceza" of all files 2025/01/22 01:26:17 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_z6f529de390dd8bbb9f480e1b_f113103fb1cb19795_d20250122_m012554_c001_v0001184_t0040_u01737509154342") 2025/01/22 01:26:19 DEBUG : B2 bucket rclone-test-xazekat1vopo: Purge remote 2025/01/22 01:26:19 INFO : B2 bucket rclone-test-xazekat1vopo: cleaning bucket "rclone-test-xazekat1vopo" of all files 2025/01/22 01:26:20 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_z0f228d5390dd8bbb9f480e1b_f11369340816fb1bb_d20250122_m012547_c001_v0001181_t0051_u01737509147857") 2025/01/22 01:26:22 DEBUG : B2 bucket rclone-test-tigobam4haki: Purge remote 2025/01/22 01:26:22 INFO : B2 bucket rclone-test-tigobam4haki: cleaning bucket "rclone-test-tigobam4haki" of all files 2025/01/22 01:26:23 DEBUG : 測試_Русский___ě_áñ: Deleting (id "4_z6f326db390dd8bbb9f480e1b_f101eabf3bf30e661_d20250122_m012541_c001_v0001038_t0010_u01737509141271") 2025/01/22 01:26:25 DEBUG : B2 bucket rclone-test-hupuroj4pedi: Purge remote 2025/01/22 01:26:25 INFO : B2 bucket rclone-test-hupuroj4pedi: cleaning bucket "rclone-test-hupuroj4pedi" of all files 2025/01/22 01:26:26 DEBUG : 測試_Русский___ě_áñ: Deleting (id "4_z5fe25d0390dd8bbb9f480e1b_f1063a3e159e0f564_d20250122_m012534_c001_v0001106_t0022_u01737509134470") 2025/01/22 01:26:28 DEBUG : B2 bucket rclone-test-moxigov1sasa: Purge remote 2025/01/22 01:26:28 INFO : B2 bucket rclone-test-moxigov1sasa: cleaning bucket "rclone-test-moxigov1sasa" of all files 2025/01/22 01:26:29 DEBUG : HELLO, WORLD!: Deleting (id "4_zdf223d8390dd8bbb9f480e1b_f1138e894bb0f9a85_d20250122_m012527_c001_v0001102_t0024_u01737509127853") 2025/01/22 01:26:31 DEBUG : B2 bucket rclone-test-yajefas9bala: Purge remote 2025/01/22 01:26:31 INFO : B2 bucket rclone-test-yajefas9bala: cleaning bucket "rclone-test-yajefas9bala" of all files 2025/01/22 01:26:32 DEBUG : HeLlO, wOrLd!: Deleting (id "4_z7f421df390dd8bbb9f480e1b_f107475f7c2ab8169_d20250122_m012521_c001_v0001105_t0033_u01737509121701") 2025/01/22 01:26:35 DEBUG : B2 bucket rclone-test-sulejem1veci: Purge remote 2025/01/22 01:26:35 INFO : B2 bucket rclone-test-sulejem1veci: cleaning bucket "rclone-test-sulejem1veci" of all files 2025/01/22 01:26:35 DEBUG : HELLO, WORLD!: Deleting (id "4_zaf520d6390dd8bbb9f480e1b_f1116e75824d1a628_d20250122_m012515_c001_v0001178_t0035_u01737509115565") 2025/01/22 01:26:38 DEBUG : B2 bucket rclone-test-sigunoz4joni: Purge remote 2025/01/22 01:26:38 INFO : B2 bucket rclone-test-sigunoz4joni: cleaning bucket "rclone-test-sigunoz4joni" of all files 2025/01/22 01:26:38 DEBUG : hello, world!: Deleting (id "4_z5f62edd390cd8bbb9f480e1b_f109f7f2764fb60f8_d20250122_m012509_c001_v0001099_t0014_u01737509109003") --- PASS: TestApplyTransforms (97.14s) === RUN TestTruncateString --- PASS: TestTruncateString (0.00s) === RUN TestCopyFile run.go:180: Remote "B2 bucket rclone-test-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", Modify Window "1ms" 2025/01/22 01:26:41 DEBUG : file1: Need to transfer - File not found at Destination 2025/01/22 01:26:42 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2025/01/22 01:26:42 INFO : file1: Copied (new) to: sub/file2 2025/01/22 01:26:42 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/01/22 01:26:42 DEBUG : file1: Unchanged skipping 2025/01/22 01:26:43 DEBUG : B2 bucket rclone-test-fogiqof1viri: don't need to copy/move sub/file2, it is already at target location --- PASS: TestCopyFile (2.75s) === RUN TestCopyLongFile run.go:180: Remote "B2 bucket rclone-test-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", Modify Window "1ms" copy_test.go:153: Test only runs on local --- SKIP: TestCopyLongFile (0.36s) === RUN TestCopyFileBackupDir run.go:180: Remote "B2 bucket rclone-test-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", Modify Window "1ms" 2025/01/22 01:26:44 DEBUG : Creating backend with remote "TestB2:rclone-test-fogiqof1viri/backup" 2025/01/22 01:26:45 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18) 2025/01/22 01:26:47 DEBUG : dst/file1: sha1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK 2025/01/22 01:26:47 INFO : dst/file1: Copied (server-side copy) 2025/01/22 01:26:47 INFO : dst/file1: Deleted 2025/01/22 01:26:47 DEBUG : dst/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2025/01/22 01:26:47 INFO : dst/file1: Copied (new) --- PASS: TestCopyFileBackupDir (4.20s) === RUN TestCopyFileCompareDest run.go:180: Remote "B2 bucket rclone-test-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", Modify Window "1ms" 2025/01/22 01:26:48 DEBUG : Creating backend with remote "TestB2:rclone-test-fogiqof1viri/dst" 2025/01/22 01:26:49 DEBUG : Creating backend with remote "TestB2:rclone-test-fogiqof1viri/CompareDest" 2025/01/22 01:26:50 DEBUG : one: Need to transfer - File not found at Destination 2025/01/22 01:26:52 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2025/01/22 01:26:52 INFO : one: Copied (new) 2025/01/22 01:26:53 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/01/22 01:26:53 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2025/01/22 01:26:53 INFO : one: Copied (replaced existing) 2025/01/22 01:26:54 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/01/22 01:26:54 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/01/22 01:26:54 DEBUG : one: Destination found in --compare-dest, skipping 2025/01/22 01:26:55 DEBUG : two: Need to transfer - File not found at Destination 2025/01/22 01:26:55 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/01/22 01:26:55 DEBUG : two: Destination found in --compare-dest, skipping 2025/01/22 01:26:56 DEBUG : two: Need to transfer - File not found at Destination 2025/01/22 01:26:56 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/01/22 01:26:56 DEBUG : two: Destination found in --compare-dest, skipping 2025/01/22 01:26:56 DEBUG : two: Need to transfer - File not found at Destination 2025/01/22 01:26:57 DEBUG : two: Sizes differ (src 5 vs dst 3) 2025/01/22 01:26:57 DEBUG : two: sha1 = a4be8877ad88f3109a6411985a1f359e10e2d930 OK 2025/01/22 01:26:57 INFO : two: Copied (new) --- PASS: TestCopyFileCompareDest (10.32s) === RUN TestCopyFileCopyDest run.go:180: Remote "B2 bucket rclone-test-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", Modify Window "1ms" 2025/01/22 01:26:58 DEBUG : Creating backend with remote "TestB2:rclone-test-fogiqof1viri/dst" 2025/01/22 01:26:59 DEBUG : Creating backend with remote "TestB2:rclone-test-fogiqof1viri/CopyDest" 2025/01/22 01:27:01 DEBUG : one: Need to transfer - File not found at Destination 2025/01/22 01:27:03 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2025/01/22 01:27:03 INFO : one: Copied (new) 2025/01/22 01:27:03 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/01/22 01:27:04 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2025/01/22 01:27:04 INFO : one: Copied (replaced existing) 2025/01/22 01:27:05 DEBUG : Creating backend with remote "TestB2:rclone-test-fogiqof1viri/BackupDir" 2025/01/22 01:27:06 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/01/22 01:27:06 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/01/22 01:27:06 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/01/22 01:27:08 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2025/01/22 01:27:08 INFO : one: Copied (server-side copy) 2025/01/22 01:27:08 INFO : one: Deleted 2025/01/22 01:27:09 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2025/01/22 01:27:09 INFO : one: Copied (server-side copy) 2025/01/22 01:27:09 DEBUG : one: Destination found in --copy-dest, using server-side copy 2025/01/22 01:27:09 DEBUG : two: Need to transfer - File not found at Destination 2025/01/22 01:27:10 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/01/22 01:27:10 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2025/01/22 01:27:10 INFO : two: Copied (server-side copy) 2025/01/22 01:27:10 DEBUG : two: Destination found in --copy-dest, using server-side copy 2025/01/22 01:27:10 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/01/22 01:27:10 DEBUG : two: Unchanged skipping 2025/01/22 01:27:11 DEBUG : three: Need to transfer - File not found at Destination 2025/01/22 01:27:11 DEBUG : three: Sizes differ (src 7 vs dst 5) 2025/01/22 01:27:11 DEBUG : three: Destination not found in --copy-dest 2025/01/22 01:27:12 DEBUG : three: sha1 = 370e613335f3cf2f19b2a2d35a86402f55c659f8 OK 2025/01/22 01:27:12 INFO : three: Copied (new) --- PASS: TestCopyFileCopyDest (15.89s) === RUN TestCopyInplace run.go:180: Remote "B2 bucket rclone-test-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", Modify Window "1ms" copy_test.go:370: Partial uploads not supported --- SKIP: TestCopyInplace (0.36s) === RUN TestCopyLongFileName run.go:180: Remote "B2 bucket rclone-test-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", 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-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", Modify Window "1ms" 2025/01/22 01:27:15 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination 2025/01/22 01:27:16 DEBUG : TestCopyFileMaxTransfer/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2025/01/22 01:27:16 INFO : TestCopyFileMaxTransfer/file1: Copied (new) 2025/01/22 01:27:16 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination 2025/01/22 01:27:16 ERROR : TestCopyFileMaxTransfer/file2: Failed to copy: Post "https://pod-000-1184-15.backblaze.com/b2api/v1/b2_upload_file/0f72cd73908d8bbb9f480e1b/c001_v0001184_t0033": max transfer limit reached as set by --max-transfer 2025/01/22 01:27:16 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination 2025/01/22 01:27:17 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination 2025/01/22 01:27:17 DEBUG : TestCopyFileMaxTransfer/file4: sha1 = 982004c2ce5452dc52bcce765e7f299fb3f60bae OK 2025/01/22 01:27:17 INFO : TestCopyFileMaxTransfer/file4: Copied (new) --- PASS: TestCopyFileMaxTransfer (3.38s) === RUN TestDeduplicateInteractive run.go:180: Remote "B2 bucket rclone-test-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateInteractive (0.36s) === RUN TestDeduplicateSkip run.go:180: Remote "B2 bucket rclone-test-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSkip (0.36s) === RUN TestDeduplicateSizeOnly run.go:180: Remote "B2 bucket rclone-test-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSizeOnly (0.36s) === RUN TestDeduplicateFirst run.go:180: Remote "B2 bucket rclone-test-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", 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-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateNewest (0.36s) === RUN TestDeduplicateNewestByHash run.go:180: Remote "B2 bucket rclone-test-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", Modify Window "1ms" 2025/01/22 01:27:21 INFO : B2 bucket rclone-test-fogiqof1viri: Looking for duplicate sha1 hashes using newest mode. 2025/01/22 01:27:21 NOTICE: b3cd7d8ac9fb0af44d14eeb2568db87e002beea2: Found 3 files with duplicate sha1 hashes 2025/01/22 01:27:21 INFO : one: Deleted 2025/01/22 01:27:22 INFO : also/one: Deleted 2025/01/22 01:27:22 NOTICE: b3cd7d8ac9fb0af44d14eeb2568db87e002beea2: Deleted 2 extra copies --- PASS: TestDeduplicateNewestByHash (2.46s) === RUN TestDeduplicateOldest run.go:180: Remote "B2 bucket rclone-test-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", 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-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", 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-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSmallest (0.36s) === RUN TestDeduplicateRename run.go:180: Remote "B2 bucket rclone-test-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateRename (0.42s) === RUN TestMergeDirs run.go:180: Remote "B2 bucket rclone-test-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", Modify Window "1ms" dedupe_test.go:256: Can't merge directories --- SKIP: TestMergeDirs (0.36s) === RUN TestListDirSorted run.go:180: Remote "B2 bucket rclone-test-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", Modify Window "1ms" 2025/01/22 01:27:26 DEBUG : a.txt: Excluded (Size Filter) 2025/01/22 01:27:26 DEBUG : a.txt: Excluded 2025/01/22 01:27:27 DEBUG : sub dir/hello world: Excluded (Size Filter) 2025/01/22 01:27:27 DEBUG : sub dir/hello world: Excluded 2025/01/22 01:27:27 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2025/01/22 01:27:27 DEBUG : sub dir/hello world2: Excluded 2025/01/22 01:27:27 DEBUG : sub dir/hello world: Excluded (Size Filter) 2025/01/22 01:27:27 DEBUG : sub dir/hello world: Excluded 2025/01/22 01:27:27 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2025/01/22 01:27:27 DEBUG : sub dir/hello world2: Excluded 2025/01/22 01:27:27 DEBUG : sub dir/ignore dir: Excluded 2025/01/22 01:27:28 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSorted (5.79s) === RUN TestListJSON run.go:180: Remote "B2 bucket rclone-test-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", 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/01/22 01:27:32 ERROR : file1: Failed to read hash: hash type not supported === RUN TestListJSON/Metadata --- PASS: TestListJSON (3.15s) --- PASS: TestListJSON/Default (0.18s) --- PASS: TestListJSON/FilesOnly (0.18s) --- PASS: TestListJSON/DirsOnly (0.18s) --- PASS: TestListJSON/Recurse (0.18s) --- PASS: TestListJSON/SubDir (0.18s) --- PASS: TestListJSON/NoModTime (0.18s) --- PASS: TestListJSON/NoMimeType (0.18s) --- PASS: TestListJSON/ShowHash (0.18s) --- PASS: TestListJSON/HashTypes (0.18s) --- PASS: TestListJSON/Metadata (0.18s) === RUN TestStatJSON run.go:180: Remote "B2 bucket rclone-test-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", 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/01/22 01:27:37 DEBUG : Creating backend with remote "TestB2:rclone-test-fogiqof1viri/notfound" --- PASS: TestStatJSON (6.09s) --- PASS: TestStatJSON/Root (0.18s) --- PASS: TestStatJSON/RootFilesOnly (0.00s) --- PASS: TestStatJSON/RootDirsOnly (0.19s) --- PASS: TestStatJSON/Dir (0.74s) --- PASS: TestStatJSON/DirWithTrailingSlash (0.18s) --- PASS: TestStatJSON/File (0.18s) --- PASS: TestStatJSON/NotFound (0.36s) --- PASS: TestStatJSON/DirFilesOnly (0.18s) --- PASS: TestStatJSON/FileFilesOnly (0.18s) --- PASS: TestStatJSON/NotFoundFilesOnly (0.18s) --- PASS: TestStatJSON/DirDirsOnly (0.18s) --- PASS: TestStatJSON/FileDirsOnly (0.18s) --- PASS: TestStatJSON/NotFoundDirsOnly (0.18s) --- PASS: TestStatJSON/RootNotFound (1.85s) === RUN TestMkdir run.go:180: Remote "B2 bucket rclone-test-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", Modify Window "1ms" 2025/01/22 01:27:39 INFO : B2 bucket rclone-test-fogiqof1viri: Making directory 2025/01/22 01:27:40 INFO : B2 bucket rclone-test-fogiqof1viri: Making directory --- PASS: TestMkdir (0.55s) === RUN TestLsd run.go:180: Remote "B2 bucket rclone-test-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", Modify Window "1ms" --- PASS: TestLsd (1.47s) === RUN TestLs run.go:180: Remote "B2 bucket rclone-test-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", Modify Window "1ms" --- PASS: TestLs (1.48s) === RUN TestLsWithFilesFrom run.go:180: Remote "B2 bucket rclone-test-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", Modify Window "1ms" 2025/01/22 01:27:44 DEBUG : empty space: Excluded (FilesFrom Filter) --- PASS: TestLsWithFilesFrom (2.07s) === RUN TestLsLong run.go:180: Remote "B2 bucket rclone-test-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", Modify Window "1ms" --- PASS: TestLsLong (1.53s) === RUN TestHashSums run.go:180: Remote "B2 bucket rclone-test-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", Modify Window "1ms" === RUN TestHashSums/Sha1 === RUN TestHashSums/Sha1Download --- PASS: TestHashSums (1.90s) --- PASS: TestHashSums/Sha1 (0.18s) --- PASS: TestHashSums/Sha1Download (0.38s) === RUN TestHashSumsWithErrors 2025/01/22 01:27:48 DEBUG : Creating backend with remote ":memory:" 2025/01/22 01:27:48 ERROR : file1: hash unsupported: hash type not supported --- PASS: TestHashSumsWithErrors (0.00s) === RUN TestHashStream 2025/01/22 01:27:48 DEBUG : Creating md5 hash of 0 bytes read from input stream 2025/01/22 01:27:48 DEBUG : Creating md5 hash of 0 bytes read from input stream 2025/01/22 01:27:48 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2025/01/22 01:27:48 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2025/01/22 01:27:48 DEBUG : Creating md5 hash of 12 bytes read from input stream 2025/01/22 01:27:48 DEBUG : Creating md5 hash of 12 bytes read from input stream 2025/01/22 01:27:48 DEBUG : Creating sha1 hash of 12 bytes read from input stream 2025/01/22 01:27:48 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-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", Modify Window "1ms" --- PASS: TestCount (2.37s) === RUN TestDelete run.go:180: Remote "B2 bucket rclone-test-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", Modify Window "1ms" 2025/01/22 01:27:52 DEBUG : Waiting for deletions to finish 2025/01/22 01:27:52 DEBUG : large: Excluded (Size Filter) 2025/01/22 01:27:52 INFO : medium: Deleted 2025/01/22 01:27:52 INFO : small: Deleted --- PASS: TestDelete (2.22s) === RUN TestMaxDelete run.go:180: Remote "B2 bucket rclone-test-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", Modify Window "1ms" 2025/01/22 01:27:54 DEBUG : Waiting for deletions to finish 2025/01/22 01:27:54 ERROR : small: Got fatal error on delete: --max-delete threshold reached 2025/01/22 01:27:54 INFO : large: Deleted 2025/01/22 01:27:54 INFO : medium: Deleted --- PASS: TestMaxDelete (2.53s) === RUN TestMaxDeleteSizeLargeFile run.go:180: Remote "B2 bucket rclone-test-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", Modify Window "1ms" 2025/01/22 01:27:57 DEBUG : Waiting for deletions to finish 2025/01/22 01:27:57 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached 2025/01/22 01:27:57 INFO : medium: Deleted 2025/01/22 01:27:57 INFO : small: Deleted --- PASS: TestMaxDeleteSizeLargeFile (2.84s) === RUN TestMaxDeleteSize run.go:180: Remote "B2 bucket rclone-test-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", Modify Window "1ms" 2025/01/22 01:27:59 DEBUG : Waiting for deletions to finish 2025/01/22 01:27:59 ERROR : small: Got fatal error on delete: --max-delete-size threshold reached 2025/01/22 01:27:59 INFO : large: Deleted 2025/01/22 01:27:59 INFO : medium: Deleted --- PASS: TestMaxDeleteSize (2.30s) === RUN TestReadFile run.go:180: Remote "B2 bucket rclone-test-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", Modify Window "1ms" --- PASS: TestReadFile (2.10s) === RUN TestRetry 2025/01/22 01:28:03 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 1/5 2025/01/22 01:28:03 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 2/5 2025/01/22 01:28:03 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG 2025/01/22 01:28:03 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG 2025/01/22 01:28:03 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG 2025/01/22 01:28:03 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG 2025/01/22 01:28:03 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-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", Modify Window "1ms" --- PASS: TestCat (4.13s) === RUN TestPurge 2025/01/22 01:28:07 DEBUG : Creating backend with remote "TestB2:rclone-test-taqefuk2luho" 2025/01/22 01:28:07 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/01/22 01:28:07 DEBUG : Creating backend with remote "/tmp/rclone2593165140" run.go:180: Remote "B2 bucket rclone-test-taqefuk2luho", Local "Local file system at /tmp/rclone2593165140", Modify Window "1ms" 2025/01/22 01:28:12 INFO : A2: Making directory 2025/01/22 01:28:12 INFO : A1/B2: Making directory 2025/01/22 01:28:12 INFO : A1/B2/C2: Making directory 2025/01/22 01:28:12 INFO : A1/B1/C3: Making directory 2025/01/22 01:28:12 INFO : A3: Making directory 2025/01/22 01:28:12 INFO : A3/B3: Making directory 2025/01/22 01:28:12 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/01/22 01:28:13 INFO : B2 bucket rclone-test-taqefuk2luho: cleaning bucket "rclone-test-taqefuk2luho" of all files 2025/01/22 01:28:13 DEBUG : A1/B1/C1/one: Deleting (id "4_zffd2bd9390fd8bbb9f480e1b_f1152b7d6d18d38c9_d20250122_m012812_c001_v0001178_t0036_u01737509292824") 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/01/22 01:28:13 INFO : B2 bucket rclone-test-taqefuk2luho: cleaning bucket "rclone-test-taqefuk2luho" of all files 2025/01/22 01:28:14 DEBUG : A1/two: Deleting (id "4_zffd2bd9390fd8bbb9f480e1b_f1152b7d6d18d38cd_d20250122_m012813_c001_v0001178_t0012_u01737509293040") 2025/01/22 01:28:15 DEBUG : B2 bucket rclone-test-taqefuk2luho: Purge remote 2025/01/22 01:28:15 INFO : B2 bucket rclone-test-taqefuk2luho: cleaning bucket "rclone-test-taqefuk2luho" of all files 2025/01/22 01:28:15 NOTICE: purge failed: directory not found --- PASS: TestPurge (8.51s) === RUN TestRmdirsNoLeaveRoot run.go:180: Remote "B2 bucket rclone-test-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", Modify Window "1ms" 2025/01/22 01:28:16 INFO : A2: Making directory 2025/01/22 01:28:16 INFO : A1/B2: Making directory 2025/01/22 01:28:16 INFO : A1/B2/C2: Making directory 2025/01/22 01:28:16 INFO : A1/B1/C3: Making directory 2025/01/22 01:28:16 INFO : A3: Making directory 2025/01/22 01:28:16 INFO : A3/B3: Making directory 2025/01/22 01:28:16 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/01/22 01:28:17 DEBUG : removing 1 level 3 directories 2025/01/22 01:28:17 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/01/22 01:28:19 DEBUG : removing 1 level 0 directories 2025/01/22 01:28:19 INFO : B2 bucket rclone-test-fogiqof1viri: Removing directory 2025/01/22 01:28:19 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 (3.93s) === RUN TestRmdirsLeaveRoot run.go:180: Remote "B2 bucket rclone-test-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", Modify Window "1ms" 2025/01/22 01:28:19 INFO : A1: Making directory 2025/01/22 01:28:19 INFO : A1/B1: Making directory 2025/01/22 01:28:19 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.92s) === RUN TestRmdirsWithFilter run.go:180: Remote "B2 bucket rclone-test-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", Modify Window "1ms" 2025/01/22 01:28:20 INFO : A1: Making directory 2025/01/22 01:28:20 INFO : A1/B1: Making directory 2025/01/22 01:28:20 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-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", Modify Window "1ms" 2025/01/22 01:28:22 DEBUG : filename.txt: File name found in url 2025/01/22 01:28:22 DEBUG : headerfilename.txt: filename found in Content-Disposition header. --- PASS: TestCopyURL (3.01s) === RUN TestCopyURLToWriter --- PASS: TestCopyURLToWriter (0.00s) === RUN TestMoveFile run.go:180: Remote "B2 bucket rclone-test-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", Modify Window "1ms" 2025/01/22 01:28:24 DEBUG : file1: Need to transfer - File not found at Destination 2025/01/22 01:28:25 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2025/01/22 01:28:25 INFO : file1: Copied (new) to: sub/file2 2025/01/22 01:28:25 INFO : file1: Deleted 2025/01/22 01:28:25 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/01/22 01:28:25 DEBUG : file1: Unchanged skipping 2025/01/22 01:28:25 INFO : file1: Deleted 2025/01/22 01:28:25 DEBUG : B2 bucket rclone-test-fogiqof1viri: don't need to copy/move sub/file2, it is already at target location --- PASS: TestMoveFile (1.68s) === RUN TestMoveFileWithIgnoreExisting run.go:180: Remote "B2 bucket rclone-test-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", Modify Window "1ms" 2025/01/22 01:28:26 DEBUG : file1: Need to transfer - File not found at Destination 2025/01/22 01:28:26 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2025/01/22 01:28:26 INFO : file1: Copied (new) 2025/01/22 01:28:26 INFO : file1: Deleted 2025/01/22 01:28:27 DEBUG : file1: Destination exists, skipping 2025/01/22 01:28:27 DEBUG : file1: Not removing source file as destination file exists and --ignore-existing is set --- PASS: TestMoveFileWithIgnoreExisting (1.51s) === RUN TestCaseInsensitiveMoveFile run.go:180: Remote "B2 bucket rclone-test-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFile (0.37s) === RUN TestCaseInsensitiveMoveFileDryRun run.go:180: Remote "B2 bucket rclone-test-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFileDryRun (0.37s) === RUN TestMoveFileBackupDir run.go:180: Remote "B2 bucket rclone-test-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", Modify Window "1ms" 2025/01/22 01:28:29 DEBUG : Creating backend with remote "TestB2:rclone-test-fogiqof1viri/backup" 2025/01/22 01:28:29 DEBUG : Config file has changed externally - reloading 2025/01/22 01:28:30 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18) 2025/01/22 01:28:31 DEBUG : dst/file1: sha1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK 2025/01/22 01:28:31 INFO : dst/file1: Copied (server-side copy) 2025/01/22 01:28:31 INFO : dst/file1: Deleted 2025/01/22 01:28:32 DEBUG : dst/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2025/01/22 01:28:32 INFO : dst/file1: Copied (new) 2025/01/22 01:28:32 INFO : dst/file1: Deleted --- PASS: TestMoveFileBackupDir (4.55s) === 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-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", Modify Window "1ms" 2025/01/22 01:28:34 INFO : A1/B2: Making directory 2025/01/22 01:28:34 INFO : A1/B1/C3: Making directory fstest.go:244: Filtering empty directory "A1/B2" fstest.go:244: Filtering empty directory "A1/B1/C3" 2025/01/22 01:28:35 DEBUG : A1/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2025/01/22 01:28:35 INFO : A1/one: Copied (server-side copy) to: A2/one 2025/01/22 01:28:35 DEBUG : A1/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2025/01/22 01:28:35 INFO : A1/B1/C2/five: Copied (server-side copy) to: A2/B1/C2/five 2025/01/22 01:28:35 INFO : A1/one: Deleted 2025/01/22 01:28:35 DEBUG : A1/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2025/01/22 01:28:35 INFO : A1/two: Copied (server-side copy) to: A2/two 2025/01/22 01:28:36 INFO : A1/B1/C2/five: Deleted 2025/01/22 01:28:36 DEBUG : A1/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2025/01/22 01:28:36 INFO : A1/B1/C1/four: Copied (server-side copy) to: A2/B1/C1/four 2025/01/22 01:28:36 INFO : A1/two: Deleted 2025/01/22 01:28:36 INFO : A1/B1/C1/four: Deleted 2025/01/22 01:28:36 DEBUG : A1/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2025/01/22 01:28:36 INFO : A1/B1/three: Copied (server-side copy) to: A2/B1/three 2025/01/22 01:28:36 INFO : A1/B1/three: Deleted fstest.go:244: Filtering empty directory "A2/B2" fstest.go:244: Filtering empty directory "A2/B1/C3" 2025/01/22 01:28:37 DEBUG : A2/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2025/01/22 01:28:37 INFO : A2/B1/C1/four: Copied (server-side copy) to: A3/B1/C1/four 2025/01/22 01:28:37 DEBUG : A2/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2025/01/22 01:28:37 INFO : A2/B1/three: Copied (server-side copy) to: A3/B1/three 2025/01/22 01:28:37 DEBUG : A2/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2025/01/22 01:28:37 INFO : A2/B1/C2/five: Copied (server-side copy) to: A3/B1/C2/five 2025/01/22 01:28:37 INFO : A2/B1/C1/four: Deleted 2025/01/22 01:28:37 INFO : A2/B1/three: Deleted 2025/01/22 01:28:37 DEBUG : A2/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2025/01/22 01:28:37 INFO : A2/one: Copied (server-side copy) to: A3/one 2025/01/22 01:28:37 INFO : A2/B1/C2/five: Deleted 2025/01/22 01:28:37 INFO : A2/one: Deleted 2025/01/22 01:28:38 DEBUG : A2/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2025/01/22 01:28:38 INFO : A2/two: Copied (server-side copy) to: A3/two 2025/01/22 01:28:38 INFO : A2/two: Deleted fstest.go:244: Filtering empty directory "A3/B2" fstest.go:244: Filtering empty directory "A3/B1/C3" 2025/01/22 01:28:38 INFO : B2 bucket rclone-test-fogiqof1viri: Can't DirMove - falling back to file moves: can't move directory - incompatible remotes 2025/01/22 01:28:39 DEBUG : A3/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2025/01/22 01:28:39 INFO : A3/two: Copied (server-side copy) to: A4/two 2025/01/22 01:28:39 DEBUG : A3/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2025/01/22 01:28:39 INFO : A3/one: Copied (server-side copy) to: A4/one 2025/01/22 01:28:39 INFO : A3/two: Deleted 2025/01/22 01:28:39 DEBUG : A3/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2025/01/22 01:28:39 INFO : A3/B1/three: Copied (server-side copy) to: A4/B1/three 2025/01/22 01:28:39 DEBUG : A3/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2025/01/22 01:28:39 INFO : A3/B1/C2/five: Copied (server-side copy) to: A4/B1/C2/five 2025/01/22 01:28:39 INFO : A3/one: Deleted 2025/01/22 01:28:39 DEBUG : A3/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2025/01/22 01:28:39 INFO : A3/B1/C1/four: Copied (server-side copy) to: A4/B1/C1/four 2025/01/22 01:28:39 INFO : A3/B1/three: Deleted 2025/01/22 01:28:39 INFO : A3/B1/C2/five: Deleted 2025/01/22 01:28:39 INFO : A3/B1/C1/four: Deleted fstest.go:244: Filtering empty directory "A4/B2" fstest.go:244: Filtering empty directory "A4/B1/C3" --- PASS: TestDirMove (8.23s) === RUN TestGetFsInfo run.go:180: Remote "B2 bucket rclone-test-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", Modify Window "1ms" --- PASS: TestGetFsInfo (0.38s) === RUN TestRcat === RUN TestRcat/withChecksum=false,ignoreChecksum=false run.go:180: Remote "B2 bucket rclone-test-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", Modify Window "1ms" 2025/01/22 01:28:41 DEBUG : B2 bucket rclone-test-fogiqof1viri: File to upload is small (34 bytes), uploading instead of streaming 2025/01/22 01:28:42 DEBUG : no_checksum_small_file_from_pipe: sha1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK 2025/01/22 01:28:42 DEBUG : no_checksum_small_file_from_pipe: Size and sha1 of src and dst objects identical 2025/01/22 01:28:42 DEBUG : no_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2025/01/22 01:28:43 DEBUG : no_checksum_big_file_from_pipe: sha1 = d0080f73aa8b494570ad63df1beb25c01ea9eea2 OK 2025/01/22 01:28:43 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-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", Modify Window "1ms" 2025/01/22 01:28:44 DEBUG : B2 bucket rclone-test-fogiqof1viri: File to upload is small (34 bytes), uploading instead of streaming 2025/01/22 01:28:44 DEBUG : with_checksum_small_file_from_pipe: sha1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK 2025/01/22 01:28:44 DEBUG : with_checksum_small_file_from_pipe: Size and sha1 of src and dst objects identical 2025/01/22 01:28:44 DEBUG : with_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2025/01/22 01:28:45 DEBUG : with_checksum_big_file_from_pipe: sha1 = d0080f73aa8b494570ad63df1beb25c01ea9eea2 OK 2025/01/22 01:28:45 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-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", Modify Window "1ms" 2025/01/22 01:28:46 DEBUG : B2 bucket rclone-test-fogiqof1viri: File to upload is small (34 bytes), uploading instead of streaming 2025/01/22 01:28:46 DEBUG : ignore_checksum_small_file_from_pipe: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/01/22 01:28:46 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2025/01/22 01:28:47 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-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", Modify Window "1ms" 2025/01/22 01:28:48 DEBUG : B2 bucket rclone-test-fogiqof1viri: File to upload is small (34 bytes), uploading instead of streaming 2025/01/22 01:28:48 DEBUG : ignore_checksum_small_file_from_pipe: Src hash empty - aborting Dst hash check 2025/01/22 01:28:48 DEBUG : ignore_checksum_small_file_from_pipe: Size of src and dst objects identical 2025/01/22 01:28:48 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2025/01/22 01:28:49 DEBUG : ignore_checksum_big_file_from_pipe: Src hash empty - aborting Dst hash check 2025/01/22 01:28:49 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical --- PASS: TestRcat (8.60s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (2.39s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (2.10s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (2.07s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (2.05s) === RUN TestRcatMetadata run.go:180: Remote "B2 bucket rclone-test-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", Modify Window "1ms" operations_test.go:1532: Skipping as destination doesn't support user metadata --- SKIP: TestRcatMetadata (0.38s) === RUN TestRcatSize run.go:180: Remote "B2 bucket rclone-test-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", Modify Window "1ms" 2025/01/22 01:28:50 DEBUG : B2 bucket rclone-test-fogiqof1viri: File to upload is small (60 bytes), uploading instead of streaming 2025/01/22 01:28:51 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2025/01/22 01:28:51 DEBUG : potato2: Size and sha1 of src and dst objects identical --- PASS: TestRcatSize (1.69s) === RUN TestRcatSizeMetadata run.go:180: Remote "B2 bucket rclone-test-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", Modify Window "1ms" operations_test.go:1613: Skipping as destination doesn't support user metadata --- SKIP: TestRcatSizeMetadata (0.37s) === RUN TestTouchDir run.go:180: Remote "B2 bucket rclone-test-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", Modify Window "1ms" 2025/01/22 01:28:53 DEBUG : B2 bucket rclone-test-fogiqof1viri: Touching "empty space" 2025/01/22 01:28:54 DEBUG : B2 bucket rclone-test-fogiqof1viri: Touching "potato2" 2025/01/22 01:28:55 DEBUG : B2 bucket rclone-test-fogiqof1viri: Touching "sub dir/potato3" --- PASS: TestTouchDir (4.22s) === RUN TestMkdirMetadata run.go:180: Remote "B2 bucket rclone-test-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", Modify Window "1ms" operations_test.go:1703: Skipping test as remote does not support MkdirMetadata --- SKIP: TestMkdirMetadata (0.38s) === RUN TestMkdirModTime run.go:180: Remote "B2 bucket rclone-test-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", 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-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", 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-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", 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-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", Modify Window "1ms" operations_test.go:1823: Skipping test as remote does not support WriteDirMetadata or MkdirMetadata --- SKIP: TestDirsEqual (0.41s) === RUN TestRemoveExisting run.go:180: Remote "B2 bucket rclone-test-fogiqof1viri", Local "Local file system at /tmp/rclone3970289793", Modify Window "1ms" operations_test.go:1892: Skipping as remote can't Move --- SKIP: TestRemoveExisting (0.38s) === 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/01/22 01:28:59 DEBUG : B2 bucket rclone-test-fogiqof1viri: Purge remote 2025/01/22 01:28:59 INFO : B2 bucket rclone-test-fogiqof1viri: cleaning bucket "rclone-test-fogiqof1viri" of all files 2025/01/22 01:28:59 DEBUG : A1/B1/C1/four: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f41564a3b900daed1_d20250122_m012836_c001_v7007000_t0000_u01737509316209") 2025/01/22 01:28:59 DEBUG : A1/B1/C1/four: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368abfe_d20250122_m012833_c001_v0001184_t0054_u01737509313900") 2025/01/22 01:28:59 DEBUG : A1/B1/C1/one: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f41564a3b900dae68_d20250122_m012818_c001_v7007000_t0000_u01737509298589") 2025/01/22 01:28:59 DEBUG : A1/B1/C1/one: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368a90a_d20250122_m012816_c001_v0001184_t0025_u01737509296342") 2025/01/22 01:28:59 DEBUG : A1/B1/C2/five: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f404d75fb9429a062_d20250122_m012835_c001_v7007000_t0000_u01737509315954") 2025/01/22 01:28:59 DEBUG : A1/B1/C2/five: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368ac07_d20250122_m012834_c001_v0001184_t0008_u01737509314101") 2025/01/22 01:28:59 DEBUG : A1/B1/three: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f401cd3ce9308ea77_d20250122_m012836_c001_v7007000_t0000_u01737509316585") 2025/01/22 01:28:59 DEBUG : A1/B1/three: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368abef_d20250122_m012833_c001_v0001184_t0004_u01737509313657") 2025/01/22 01:28:59 DEBUG : A1/one: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f409d499a74c8b887_d20250122_m012835_c001_v7007000_t0000_u01737509315890") 2025/01/22 01:29:00 DEBUG : A1/one: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368abdf_d20250122_m012833_c001_v0001184_t0000_u01737509313242") 2025/01/22 01:29:00 DEBUG : A1/two: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f418a837a896e7cd3_d20250122_m012836_c001_v7007000_t0000_u01737509316078") 2025/01/22 01:29:00 DEBUG : A1/two: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368abe5_d20250122_m012833_c001_v0001184_t0042_u01737509313441") 2025/01/22 01:29:00 DEBUG : A1/two: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f41564a3b900dae69_d20250122_m012818_c001_v7007000_t0000_u01737509298991") 2025/01/22 01:29:00 DEBUG : A1/two: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368a910_d20250122_m012816_c001_v0001184_t0046_u01737509296548") 2025/01/22 01:29:00 DEBUG : A2/B1/C1/four: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f401cd3ce9308ea7b_d20250122_m012837_c001_v7007000_t0000_u01737509317609") 2025/01/22 01:29:00 DEBUG : A2/B1/C1/four: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f110480938d53ba64_d20250122_m012835_c001_v0001128_t0028_u01737509315285") 2025/01/22 01:29:00 DEBUG : A2/B1/C2/five: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f41564a3b900daed4_d20250122_m012837_c001_v7007000_t0000_u01737509317667") 2025/01/22 01:29:00 DEBUG : A2/B1/C2/five: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f10715a6ac1cfe12a_d20250122_m012835_c001_v0001161_t0019_u01737509315638") 2025/01/22 01:29:00 DEBUG : A2/B1/three: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f418a837a896e7cd4_d20250122_m012837_c001_v7007000_t0000_u01737509317622") 2025/01/22 01:29:00 DEBUG : A2/B1/three: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f101e4eefa5edfc30_d20250122_m012835_c001_v0001116_t0007_u01737509315660") 2025/01/22 01:29:00 DEBUG : A2/one: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f404d75fb9429a067_d20250122_m012837_c001_v7007000_t0000_u01737509317869") 2025/01/22 01:29:00 DEBUG : A2/one: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f10697322d4b9048b_d20250122_m012835_c001_v0001182_t0017_u01737509315640") 2025/01/22 01:29:00 DEBUG : A2/two: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f409d499a74c8b8b2_d20250122_m012838_c001_v7007000_t0000_u01737509318449") 2025/01/22 01:29:01 DEBUG : A2/two: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f115b340772b51159_d20250122_m012835_c001_v0001100_t0054_u01737509315667") 2025/01/22 01:29:01 DEBUG : A3/B1/C1/four: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f41564a3b900daed6_d20250122_m012839_c001_v7007000_t0000_u01737509319789") 2025/01/22 01:29:01 DEBUG : A3/B1/C1/four: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11003079e010f688_d20250122_m012837_c001_v0001182_t0044_u01737509317410") 2025/01/22 01:29:01 DEBUG : A3/B1/C2/five: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f401cd3ce9308ea89_d20250122_m012839_c001_v7007000_t0000_u01737509319731") 2025/01/22 01:29:01 DEBUG : A3/B1/C2/five: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f107bf4211d0352e0_d20250122_m012837_c001_v0001182_t0010_u01737509317448") 2025/01/22 01:29:01 DEBUG : A3/B1/three: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f409d499a74c8b8be_d20250122_m012839_c001_v7007000_t0000_u01737509319692") 2025/01/22 01:29:01 DEBUG : A3/B1/three: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f1178f6ca7fa5bc8d_d20250122_m012837_c001_v0001183_t0040_u01737509317424") 2025/01/22 01:29:01 DEBUG : A3/one: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f404d75fb9429a069_d20250122_m012839_c001_v7007000_t0000_u01737509319606") 2025/01/22 01:29:01 DEBUG : A3/one: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f1137dde741917ea5_d20250122_m012837_c001_v0001029_t0036_u01737509317439") 2025/01/22 01:29:01 DEBUG : A3/two: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f418a837a896e7cd5_d20250122_m012839_c001_v7007000_t0000_u01737509319487") 2025/01/22 01:29:01 DEBUG : A3/two: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f1083f0636ce03faf_d20250122_m012837_c001_v0001106_t0027_u01737509317449") 2025/01/22 01:29:01 DEBUG : A4/B1/C1/four: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f41564a3b900daed7_d20250122_m012840_c001_v7007000_t0000_u01737509320362") 2025/01/22 01:29:01 DEBUG : A4/B1/C1/four: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11892a9913dca42d_d20250122_m012839_c001_v0001103_t0009_u01737509319238") 2025/01/22 01:29:01 DEBUG : A4/B1/C2/five: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f41564a3b900daed8_d20250122_m012840_c001_v7007000_t0000_u01737509320545") 2025/01/22 01:29:01 DEBUG : A4/B1/C2/five: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f1006819c56c073fc_d20250122_m012839_c001_v0001039_t0005_u01737509319255") 2025/01/22 01:29:01 DEBUG : A4/B1/three: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f41564a3b900daed9_d20250122_m012840_c001_v7007000_t0000_u01737509320728") 2025/01/22 01:29:01 DEBUG : A4/B1/three: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f114fea31da8ad09f_d20250122_m012839_c001_v0001116_t0041_u01737509319219") 2025/01/22 01:29:01 DEBUG : A4/one: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f41564a3b900daeda_d20250122_m012840_c001_v7007000_t0000_u01737509320918") 2025/01/22 01:29:01 DEBUG : A4/one: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f114a9328b0447c4d_d20250122_m012839_c001_v0001032_t0015_u01737509319226") 2025/01/22 01:29:01 DEBUG : A4/two: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f41564a3b900daedb_d20250122_m012841_c001_v7007000_t0000_u01737509321101") 2025/01/22 01:29:02 DEBUG : A4/two: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f1012dbdd3991b428_d20250122_m012839_c001_v0001182_t0052_u01737509319250") 2025/01/22 01:29:02 DEBUG : BackupDir/one: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f405043bc8f243ce4_d20250122_m012713_c001_v7007000_t0000_u01737509233207") 2025/01/22 01:29:02 DEBUG : BackupDir/one: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f115e145c425dfce5_d20250122_m012707_c001_v0001144_t0051_u01737509227861") 2025/01/22 01:29:02 DEBUG : CompareDest/one: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f405043bc8f243cda_d20250122_m012657_c001_v7007000_t0000_u01737509217837") 2025/01/22 01:29:02 DEBUG : CompareDest/one: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf3689b6f_d20250122_m012654_c001_v0001184_t0025_u01737509214300") 2025/01/22 01:29:02 DEBUG : CompareDest/two: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f405043bc8f243cdb_d20250122_m012658_c001_v7007000_t0000_u01737509218023") 2025/01/22 01:29:02 DEBUG : CompareDest/two: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf3689b9b_d20250122_m012655_c001_v0001184_t0024_u01737509215233") 2025/01/22 01:29:02 DEBUG : CopyDest/one: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f405043bc8f243ce5_d20250122_m012713_c001_v7007000_t0000_u01737509233389") 2025/01/22 01:29:02 DEBUG : CopyDest/one: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf3689d4d_d20250122_m012705_c001_v0001184_t0029_u01737509225015") 2025/01/22 01:29:02 DEBUG : CopyDest/three: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f405043bc8f243ce6_d20250122_m012713_c001_v7007000_t0000_u01737509233571") 2025/01/22 01:29:02 DEBUG : CopyDest/three: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf3689e7a_d20250122_m012711_c001_v0001184_t0025_u01737509231208") 2025/01/22 01:29:02 DEBUG : CopyDest/two: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f405043bc8f243ce7_d20250122_m012713_c001_v7007000_t0000_u01737509233753") 2025/01/22 01:29:02 DEBUG : CopyDest/two: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf3689e18_d20250122_m012709_c001_v0001184_t0032_u01737509229434") 2025/01/22 01:29:02 DEBUG : ReadFile: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f409d499a74c8b7b5_d20250122_m012802_c001_v7007000_t0000_u01737509282509") 2025/01/22 01:29:02 DEBUG : ReadFile: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368a6d8_d20250122_m012801_c001_v0001184_t0055_u01737509281195") 2025/01/22 01:29:02 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f405043bc8f243cf0_d20250122_m012718_c001_v7007000_t0000_u01737509238233") 2025/01/22 01:29:02 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf3689f65_d20250122_m012715_c001_v0001184_t0029_u01737509235936") 2025/01/22 01:29:02 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f405043bc8f243cf1_d20250122_m012718_c001_v7007000_t0000_u01737509238414") 2025/01/22 01:29:02 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf3689fc1_d20250122_m012717_c001_v0001184_t0006_u01737509237659") 2025/01/22 01:29:02 DEBUG : a.txt: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f409d499a74c8b703_d20250122_m012729_c001_v7007000_t0000_u01737509249254") 2025/01/22 01:29:02 DEBUG : a.txt: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368a0d9_d20250122_m012724_c001_v0001184_t0043_u01737509244916") 2025/01/22 01:29:02 DEBUG : also/one: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f405043bc8f243cf3_d20250122_m012721_c001_v7007000_t0000_u01737509241957") 2025/01/22 01:29:03 DEBUG : also/one: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368a02d_d20250122_m012720_c001_v0001184_t0009_u01737509240795") 2025/01/22 01:29:03 DEBUG : another: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f405043bc8f243cf4_d20250122_m012722_c001_v7007000_t0000_u01737509242505") 2025/01/22 01:29:03 DEBUG : another: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368a043_d20250122_m012720_c001_v0001184_t0059_u01737509240996") 2025/01/22 01:29:03 DEBUG : backup/dst/file1: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f41564a3b900daece_d20250122_m012832_c001_v7007000_t0000_u01737509312637") 2025/01/22 01:29:03 DEBUG : backup/dst/file1: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f112958cc32bf8032_d20250122_m012831_c001_v0001093_t0024_u01737509311403") 2025/01/22 01:29:03 DEBUG : backup/dst/file1: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f405043bc8f243cc9_d20250122_m012647_c001_v7007000_t0000_u01737509207917") 2025/01/22 01:29:03 DEBUG : backup/dst/file1: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f107280e7ec1e4e2b_d20250122_m012646_c001_v0001184_t0026_u01737509206977") 2025/01/22 01:29:03 DEBUG : data/banana: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f4120d195cf0980fe_d20250122_m012503_c001_v7007000_t0000_u01737509103270") 2025/01/22 01:29:03 DEBUG : data/banana: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf36889fa_d20250122_m012501_c001_v0001184_t0010_u01737509101521") 2025/01/22 01:29:03 DEBUG : data/banana: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf36888c7_d20250122_m012453_c001_v0001184_t0022_u01737509093534") 2025/01/22 01:29:03 DEBUG : data/potato: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f4120d195cf0980ff_d20250122_m012503_c001_v7007000_t0000_u01737509103454") 2025/01/22 01:29:03 DEBUG : data/potato: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf36889fc_d20250122_m012501_c001_v0001184_t0058_u01737509101726") 2025/01/22 01:29:03 DEBUG : data/potato: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368891d_d20250122_m012455_c001_v0001184_t0044_u01737509095513") 2025/01/22 01:29:03 DEBUG : dst/file1: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f41564a3b900daecf_d20250122_m012832_c001_v7007000_t0000_u01737509312838") 2025/01/22 01:29:03 DEBUG : dst/file1: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368abb3_d20250122_m012832_c001_v0001184_t0031_u01737509312074") 2025/01/22 01:29:03 DEBUG : dst/file1: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f41564a3b900daecd_d20250122_m012831_c001_v7007000_t0000_u01737509311892") 2025/01/22 01:29:03 DEBUG : dst/file1: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368ab34_d20250122_m012828_c001_v0001184_t0048_u01737509308696") 2025/01/22 01:29:03 DEBUG : dst/file1: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f405043bc8f243ccc_d20250122_m012648_c001_v7007000_t0000_u01737509208098") 2025/01/22 01:29:03 DEBUG : dst/file1: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf3689a54_d20250122_m012647_c001_v0001184_t0007_u01737509207356") 2025/01/22 01:29:03 DEBUG : dst/file1: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f405043bc8f243cc7_d20250122_m012647_c001_v7007000_t0000_u01737509207173") 2025/01/22 01:29:03 DEBUG : dst/file1: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf36899b7_d20250122_m012644_c001_v0001184_t0006_u01737509204258") 2025/01/22 01:29:03 DEBUG : dst/one: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f405043bc8f243ce8_d20250122_m012713_c001_v7007000_t0000_u01737509233935") 2025/01/22 01:29:04 DEBUG : dst/one: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f113bbebd341140ab_d20250122_m012709_c001_v0001182_t0016_u01737509229034") 2025/01/22 01:29:04 DEBUG : dst/one: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f418a837a896e79d8_d20250122_m012708_c001_v7007000_t0000_u01737509228819") 2025/01/22 01:29:04 DEBUG : dst/one: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf3689d41_d20250122_m012704_c001_v0001184_t0058_u01737509224798") 2025/01/22 01:29:04 DEBUG : dst/one: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f106866026b3dfdc6_d20250122_m012703_c001_v0001098_t0052_u01737509223906") 2025/01/22 01:29:04 DEBUG : dst/one: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f106866026b3dfdc2_d20250122_m012702_c001_v0001098_t0017_u01737509222787") 2025/01/22 01:29:04 DEBUG : dst/one: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f405043bc8f243cdc_d20250122_m012658_c001_v7007000_t0000_u01737509218226") 2025/01/22 01:29:04 DEBUG : dst/one: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf3689b64_d20250122_m012654_c001_v0001184_t0054_u01737509214074") 2025/01/22 01:29:04 DEBUG : dst/one: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f119bca63941bb6a2_d20250122_m012653_c001_v0001183_t0014_u01737509213325") 2025/01/22 01:29:04 DEBUG : dst/one: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f119bca63941bb686_d20250122_m012652_c001_v0001183_t0008_u01737509212402") 2025/01/22 01:29:04 DEBUG : dst/three: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f405043bc8f243ce9_d20250122_m012714_c001_v7007000_t0000_u01737509234118") 2025/01/22 01:29:04 DEBUG : dst/three: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f106866026b3dfdd6_d20250122_m012712_c001_v0001098_t0036_u01737509232329") 2025/01/22 01:29:04 DEBUG : dst/two: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f405043bc8f243cea_d20250122_m012714_c001_v7007000_t0000_u01737509234314") 2025/01/22 01:29:04 DEBUG : dst/two: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11017402f9407878_d20250122_m012710_c001_v0001099_t0056_u01737509230278") 2025/01/22 01:29:04 DEBUG : dst/two: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f405043bc8f243cdd_d20250122_m012658_c001_v7007000_t0000_u01737509218416") 2025/01/22 01:29:04 DEBUG : dst/two: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f119bca63941bb709_d20250122_m012657_c001_v0001183_t0057_u01737509217270") 2025/01/22 01:29:04 DEBUG : empty space: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f406e9b3bb21440a8_d20250122_m012856_c001_v7007000_t0000_u01737509336351") 2025/01/22 01:29:04 DEBUG : empty space: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f10334adc93baac88_d20250122_m012854_c001_v0001181_t0019_u01737509334511") 2025/01/22 01:29:04 DEBUG : empty space: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368af10_d20250122_m012853_c001_v0001184_t0011_u01737509333132") 2025/01/22 01:29:04 DEBUG : empty space: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f409d499a74c8b730_d20250122_m012750_c001_v7007000_t0000_u01737509270502") 2025/01/22 01:29:04 DEBUG : empty space: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368a4f2_d20250122_m012749_c001_v0001184_t0030_u01737509269132") 2025/01/22 01:29:05 DEBUG : empty space: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f409d499a74c8b72b_d20250122_m012748_c001_v7007000_t0000_u01737509268400") 2025/01/22 01:29:05 DEBUG : empty space: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368a4a0_d20250122_m012747_c001_v0001184_t0026_u01737509267237") 2025/01/22 01:29:05 DEBUG : empty space: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f409d499a74c8b729_d20250122_m012746_c001_v7007000_t0000_u01737509266484") 2025/01/22 01:29:05 DEBUG : empty space: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368a43e_d20250122_m012745_c001_v0001184_t0051_u01737509265709") 2025/01/22 01:29:05 DEBUG : empty space: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f409d499a74c8b726_d20250122_m012744_c001_v7007000_t0000_u01737509264959") 2025/01/22 01:29:05 DEBUG : empty space: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368a3d1_d20250122_m012743_c001_v0001184_t0006_u01737509263635") 2025/01/22 01:29:05 DEBUG : empty space: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f409d499a74c8b71f_d20250122_m012742_c001_v7007000_t0000_u01737509262903") 2025/01/22 01:29:05 DEBUG : empty space: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368a38f_d20250122_m012742_c001_v0001184_t0033_u01737509262154") 2025/01/22 01:29:05 DEBUG : empty space: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f4120d195cf098082_d20250122_m012448_c001_v7007000_t0000_u01737509088003") 2025/01/22 01:29:05 DEBUG : empty space: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368879c_d20250122_m012445_c001_v0001184_t0012_u01737509085639") 2025/01/22 01:29:05 DEBUG : empty space: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f4120d195cf098076_d20250122_m012443_c001_v7007000_t0000_u01737509083943") 2025/01/22 01:29:05 DEBUG : empty space: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf36886cc_d20250122_m012440_c001_v0001184_t0021_u01737509080498") 2025/01/22 01:29:05 DEBUG : empty space: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f4120d195cf098040_d20250122_m012438_c001_v7007000_t0000_u01737509078102") 2025/01/22 01:29:05 DEBUG : empty space: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf3688610_d20250122_m012435_c001_v0001184_t0019_u01737509075833") 2025/01/22 01:29:05 DEBUG : file1: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f41564a3b900daec9_d20250122_m012827_c001_v7007000_t0000_u01737509307586") 2025/01/22 01:29:05 DEBUG : file1: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368aae2_d20250122_m012826_c001_v0001184_t0047_u01737509306628") 2025/01/22 01:29:05 DEBUG : file1: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f41564a3b900dae95_d20250122_m012823_c001_v7007000_t0000_u01737509303841") 2025/01/22 01:29:05 DEBUG : file1: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f41532f6bf368aa12_d20250122_m012822_c001_v0001184_t0033_u01737509302297") 2025/01/22 01:29:05 DEBUG : file1: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f409d499a74c8b7be_d20250122_m012806_c001_v7007000_t0000_u01737509286929") 2025/01/22 01:29:05 DEBUG : file1: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368a71a_d20250122_m012803_c001_v0001184_t0012_u01737509283345") 2025/01/22 01:29:06 DEBUG : file1: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f409d499a74c8b717_d20250122_m012739_c001_v7007000_t0000_u01737509259407") 2025/01/22 01:29:06 DEBUG : file1: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368a255_d20250122_m012733_c001_v0001184_t0030_u01737509253852") 2025/01/22 01:29:06 DEBUG : file1: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f409d499a74c8b70f_d20250122_m012733_c001_v7007000_t0000_u01737509253312") 2025/01/22 01:29:06 DEBUG : file1: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368a1d7_d20250122_m012730_c001_v0001184_t0010_u01737509250706") 2025/01/22 01:29:06 DEBUG : file2: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f41564a3b900dae96_d20250122_m012824_c001_v7007000_t0000_u01737509304024") 2025/01/22 01:29:06 DEBUG : file2: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f41532f6bf368aa40_d20250122_m012823_c001_v0001184_t0033_u01737509303287") 2025/01/22 01:29:06 DEBUG : file2: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f409d499a74c8b7c0_d20250122_m012807_c001_v7007000_t0000_u01737509287113") 2025/01/22 01:29:06 DEBUG : file2: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368a72c_d20250122_m012803_c001_v0001184_t0038_u01737509283561") 2025/01/22 01:29:06 DEBUG : filename.txt: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f41564a3b900dae9a_d20250122_m012824_c001_v7007000_t0000_u01737509304217") 2025/01/22 01:29:06 DEBUG : filename.txt: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368aa2c_d20250122_m012822_c001_v0001184_t0010_u01737509302846") 2025/01/22 01:29:06 DEBUG : headerfilename.txt: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f41564a3b900dae9f_d20250122_m012824_c001_v7007000_t0000_u01737509304399") 2025/01/22 01:29:06 DEBUG : headerfilename.txt: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368aa34_d20250122_m012823_c001_v0001184_t0046_u01737509303046") 2025/01/22 01:29:06 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f41564a3b900daee6_d20250122_m012849_c001_v7007000_t0000_u01737509329887") 2025/01/22 01:29:06 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368ae49_d20250122_m012848_c001_v0001184_t0021_u01737509328791") 2025/01/22 01:29:06 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f41564a3b900daee3_d20250122_m012847_c001_v7007000_t0000_u01737509327832") 2025/01/22 01:29:06 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368adf3_d20250122_m012846_c001_v0001184_t0038_u01737509326710") 2025/01/22 01:29:06 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f41564a3b900daee7_d20250122_m012850_c001_v7007000_t0000_u01737509330080") 2025/01/22 01:29:06 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368ae43_d20250122_m012848_c001_v0001184_t0013_u01737509328581") 2025/01/22 01:29:06 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f41564a3b900daee4_d20250122_m012848_c001_v7007000_t0000_u01737509328014") 2025/01/22 01:29:06 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368adeb_d20250122_m012846_c001_v0001184_t0001_u01737509326512") 2025/01/22 01:29:06 DEBUG : large: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f409d499a74c8b7af_d20250122_m012759_c001_v7007000_t0000_u01737509279883") 2025/01/22 01:29:07 DEBUG : large: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368a692_d20250122_m012759_c001_v0001184_t0023_u01737509279286") 2025/01/22 01:29:07 DEBUG : large: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f409d499a74c8b7ab_d20250122_m012758_c001_v7007000_t0000_u01737509278166") 2025/01/22 01:29:07 DEBUG : large: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368a61a_d20250122_m012756_c001_v0001184_t0023_u01737509276462") 2025/01/22 01:29:07 DEBUG : large: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f409d499a74c8b759_d20250122_m012754_c001_v7007000_t0000_u01737509274600") 2025/01/22 01:29:07 DEBUG : large: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368a5b6_d20250122_m012753_c001_v0001184_t0028_u01737509273938") 2025/01/22 01:29:07 DEBUG : large: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f409d499a74c8b735_d20250122_m012753_c001_v7007000_t0000_u01737509273023") 2025/01/22 01:29:07 DEBUG : large: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368a56a_d20250122_m012751_c001_v0001184_t0029_u01737509271698") 2025/01/22 01:29:07 DEBUG : medium: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f409d499a74c8b7b0_d20250122_m012759_c001_v7007000_t0000_u01737509279896") 2025/01/22 01:29:07 DEBUG : medium: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368a68a_d20250122_m012759_c001_v0001184_t0031_u01737509279092") 2025/01/22 01:29:07 DEBUG : medium: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f409d499a74c8b7a8_d20250122_m012757_c001_v7007000_t0000_u01737509277524") 2025/01/22 01:29:07 DEBUG : medium: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368a60a_d20250122_m012756_c001_v0001184_t0052_u01737509276256") 2025/01/22 01:29:07 DEBUG : medium: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f409d499a74c8b75b_d20250122_m012754_c001_v7007000_t0000_u01737509274610") 2025/01/22 01:29:07 DEBUG : medium: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368a5a6_d20250122_m012753_c001_v0001184_t0010_u01737509273732") 2025/01/22 01:29:07 DEBUG : medium: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f409d499a74c8b733_d20250122_m012752_c001_v7007000_t0000_u01737509272290") 2025/01/22 01:29:07 DEBUG : medium: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368a558_d20250122_m012751_c001_v0001184_t0028_u01737509271505") 2025/01/22 01:29:07 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f41564a3b900daedd_d20250122_m012843_c001_v7007000_t0000_u01737509323685") 2025/01/22 01:29:07 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368ad4d_d20250122_m012842_c001_v0001184_t0024_u01737509322579") 2025/01/22 01:29:07 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f41564a3b900daede_d20250122_m012843_c001_v7007000_t0000_u01737509323867") 2025/01/22 01:29:07 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368ad47_d20250122_m012842_c001_v0001184_t0016_u01737509322384") 2025/01/22 01:29:07 DEBUG : not-one: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f405043bc8f243cf5_d20250122_m012722_c001_v7007000_t0000_u01737509242689") 2025/01/22 01:29:07 DEBUG : not-one: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368a04f_d20250122_m012721_c001_v0001184_t0012_u01737509241202") 2025/01/22 01:29:07 DEBUG : one: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f405043bc8f243cf2_d20250122_m012721_c001_v7007000_t0000_u01737509241776") 2025/01/22 01:29:08 DEBUG : one: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368a027_d20250122_m012720_c001_v0001184_t0008_u01737509240587") 2025/01/22 01:29:08 DEBUG : potato1: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f41564a3b900daee9_d20250122_m012851_c001_v7007000_t0000_u01737509331608") 2025/01/22 01:29:08 DEBUG : potato1: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368aea8_d20250122_m012850_c001_v0001184_t0027_u01737509330829") 2025/01/22 01:29:08 DEBUG : potato2: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f406e9b3bb21440a9_d20250122_m012856_c001_v7007000_t0000_u01737509336553") 2025/01/22 01:29:08 DEBUG : potato2: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11853f50c2d5ba25_d20250122_m012854_c001_v0001184_t0002_u01737509334978") 2025/01/22 01:29:08 DEBUG : potato2: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368af04_d20250122_m012852_c001_v0001184_t0002_u01737509332889") 2025/01/22 01:29:08 DEBUG : potato2: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f41564a3b900daeea_d20250122_m012851_c001_v7007000_t0000_u01737509331791") 2025/01/22 01:29:08 DEBUG : potato2: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368aeb4_d20250122_m012851_c001_v0001184_t0014_u01737509331036") 2025/01/22 01:29:08 DEBUG : potato2: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f409d499a74c8b731_d20250122_m012750_c001_v7007000_t0000_u01737509270684") 2025/01/22 01:29:08 DEBUG : potato2: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368a4e4_d20250122_m012748_c001_v0001184_t0042_u01737509268935") 2025/01/22 01:29:08 DEBUG : potato2: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f409d499a74c8b72e_d20250122_m012748_c001_v7007000_t0000_u01737509268581") 2025/01/22 01:29:08 DEBUG : potato2: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368a48e_d20250122_m012747_c001_v0001184_t0035_u01737509267033") 2025/01/22 01:29:08 DEBUG : potato2: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f409d499a74c8b72a_d20250122_m012746_c001_v7007000_t0000_u01737509266665") 2025/01/22 01:29:08 DEBUG : potato2: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368a432_d20250122_m012745_c001_v0001184_t0055_u01737509265505") 2025/01/22 01:29:08 DEBUG : potato2: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f409d499a74c8b727_d20250122_m012745_c001_v7007000_t0000_u01737509265151") 2025/01/22 01:29:08 DEBUG : potato2: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368a3c7_d20250122_m012743_c001_v0001184_t0014_u01737509263439") 2025/01/22 01:29:08 DEBUG : potato2: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f409d499a74c8b720_d20250122_m012743_c001_v7007000_t0000_u01737509263083") 2025/01/22 01:29:08 DEBUG : potato2: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368a38b_d20250122_m012741_c001_v0001184_t0048_u01737509261957") 2025/01/22 01:29:08 DEBUG : potato2: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f4120d195cf098083_d20250122_m012448_c001_v7007000_t0000_u01737509088191") 2025/01/22 01:29:08 DEBUG : potato2: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf36887ba_d20250122_m012446_c001_v0001184_t0006_u01737509086228") 2025/01/22 01:29:08 DEBUG : potato2: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f4120d195cf098078_d20250122_m012444_c001_v7007000_t0000_u01737509084127") 2025/01/22 01:29:09 DEBUG : potato2: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf36886e6_d20250122_m012441_c001_v0001184_t0028_u01737509081237") 2025/01/22 01:29:09 DEBUG : potato2: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f4120d195cf098044_d20250122_m012438_c001_v7007000_t0000_u01737509078287") 2025/01/22 01:29:09 DEBUG : potato2: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf3688620_d20250122_m012436_c001_v0001184_t0007_u01737509076394") 2025/01/22 01:29:09 DEBUG : remotepotato: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f4120d195cf098085_d20250122_m012448_c001_v7007000_t0000_u01737509088387") 2025/01/22 01:29:09 DEBUG : remotepotato: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf36887d4_d20250122_m012446_c001_v0001184_t0059_u01737509086997") 2025/01/22 01:29:09 DEBUG : remotepotato: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f4120d195cf098079_d20250122_m012444_c001_v7007000_t0000_u01737509084311") 2025/01/22 01:29:09 DEBUG : remotepotato: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf3688716_d20250122_m012442_c001_v0001184_t0016_u01737509082568") 2025/01/22 01:29:09 DEBUG : remotepotato: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f4120d195cf098049_d20250122_m012438_c001_v7007000_t0000_u01737509078477") 2025/01/22 01:29:09 DEBUG : remotepotato: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf3688644_d20250122_m012437_c001_v0001184_t0019_u01737509077153") 2025/01/22 01:29:09 DEBUG : rutabaga: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f4120d195cf098086_d20250122_m012448_c001_v7007000_t0000_u01737509088571") 2025/01/22 01:29:09 DEBUG : rutabaga: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf3688772_d20250122_m012444_c001_v0001184_t0042_u01737509084878") 2025/01/22 01:29:09 DEBUG : rutabaga: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f4120d195cf09807a_d20250122_m012444_c001_v7007000_t0000_u01737509084499") 2025/01/22 01:29:09 DEBUG : rutabaga: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf3688696_d20250122_m012439_c001_v0001184_t0028_u01737509079024") 2025/01/22 01:29:09 DEBUG : rutabaga: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f4120d195cf09804f_d20250122_m012438_c001_v7007000_t0000_u01737509078661") 2025/01/22 01:29:09 DEBUG : rutabaga: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf36885f9_d20250122_m012435_c001_v0001184_t0042_u01737509075080") 2025/01/22 01:29:09 DEBUG : small: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f409d499a74c8b7b2_d20250122_m012800_c001_v7007000_t0000_u01737509280462") 2025/01/22 01:29:09 DEBUG : small: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368a67e_d20250122_m012758_c001_v0001184_t0023_u01737509278892") 2025/01/22 01:29:09 DEBUG : small: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f409d499a74c8b7a9_d20250122_m012757_c001_v7007000_t0000_u01737509277533") 2025/01/22 01:29:09 DEBUG : small: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368a604_d20250122_m012756_c001_v0001184_t0032_u01737509276050") 2025/01/22 01:29:09 DEBUG : small: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f409d499a74c8b771_d20250122_m012755_c001_v7007000_t0000_u01737509275311") 2025/01/22 01:29:09 DEBUG : small: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368a5a4_d20250122_m012753_c001_v0001184_t0000_u01737509273523") 2025/01/22 01:29:09 DEBUG : small: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f409d499a74c8b734_d20250122_m012752_c001_v7007000_t0000_u01737509272479") 2025/01/22 01:29:10 DEBUG : small: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368a550_d20250122_m012751_c001_v0001184_t0002_u01737509271306") 2025/01/22 01:29:10 DEBUG : sub dir/hello world: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f409d499a74c8b71b_d20250122_m012741_c001_v7007000_t0000_u01737509261605") 2025/01/22 01:29:10 DEBUG : sub dir/hello world: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368a361_d20250122_m012740_c001_v0001184_t0014_u01737509260849") 2025/01/22 01:29:10 DEBUG : sub dir/hello world: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f409d499a74c8b705_d20250122_m012729_c001_v7007000_t0000_u01737509249435") 2025/01/22 01:29:10 DEBUG : sub dir/hello world: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368a0e9_d20250122_m012725_c001_v0001184_t0043_u01737509245323") 2025/01/22 01:29:10 DEBUG : sub dir/hello world2: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f409d499a74c8b706_d20250122_m012729_c001_v7007000_t0000_u01737509249621") 2025/01/22 01:29:10 DEBUG : sub dir/hello world2: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368a0f3_d20250122_m012725_c001_v0001184_t0027_u01737509245530") 2025/01/22 01:29:10 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f409d499a74c8b708_d20250122_m012729_c001_v7007000_t0000_u01737509249803") 2025/01/22 01:29:10 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368a0fd_d20250122_m012725_c001_v0001184_t0033_u01737509245737") 2025/01/22 01:29:10 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f409d499a74c8b709_d20250122_m012729_c001_v7007000_t0000_u01737509249984") 2025/01/22 01:29:10 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368a105_d20250122_m012725_c001_v0001184_t0050_u01737509245949") 2025/01/22 01:29:10 DEBUG : sub dir/potato3: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f406e9b3bb21440aa_d20250122_m012856_c001_v7007000_t0000_u01737509336743") 2025/01/22 01:29:10 DEBUG : sub dir/potato3: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f107fa41f1835792f_d20250122_m012855_c001_v0001172_t0019_u01737509335391") 2025/01/22 01:29:10 DEBUG : sub dir/potato3: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368af18_d20250122_m012853_c001_v0001184_t0011_u01737509333325") 2025/01/22 01:29:10 DEBUG : sub dir/potato3: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f409d499a74c8b732_d20250122_m012750_c001_v7007000_t0000_u01737509270936") 2025/01/22 01:29:10 DEBUG : sub dir/potato3: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368a506_d20250122_m012749_c001_v0001184_t0046_u01737509269335") 2025/01/22 01:29:10 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f409d499a74c8b70a_d20250122_m012730_c001_v7007000_t0000_u01737509250166") 2025/01/22 01:29:10 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368a10d_d20250122_m012726_c001_v0001184_t0017_u01737509246146") 2025/01/22 01:29:10 DEBUG : sub/file2: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f41564a3b900daebb_d20250122_m012826_c001_v7007000_t0000_u01737509306079") 2025/01/22 01:29:10 DEBUG : sub/file2: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368aa92_d20250122_m012824_c001_v0001184_t0010_u01737509304948") 2025/01/22 01:29:11 DEBUG : sub/file2: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f409d499a74c8b719_d20250122_m012739_c001_v7007000_t0000_u01737509259589") 2025/01/22 01:29:11 DEBUG : sub/file2: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368a25d_d20250122_m012734_c001_v0001184_t0050_u01737509254053") 2025/01/22 01:29:11 DEBUG : sub/file2: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f409d499a74c8b710_d20250122_m012733_c001_v7007000_t0000_u01737509253495") 2025/01/22 01:29:11 DEBUG : sub/file2: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368a1dd_d20250122_m012730_c001_v0001184_t0037_u01737509250910") 2025/01/22 01:29:11 DEBUG : sub/file2: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f405043bc8f243cc2_d20250122_m012643_c001_v7007000_t0000_u01737509203536") 2025/01/22 01:29:11 DEBUG : sub/file2: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368995f_d20250122_m012642_c001_v0001184_t0023_u01737509202056") 2025/01/22 01:29:11 DEBUG : test-multithread-abort: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f4120d195cf098002_d20250122_m012434_c001_v7007000_t0000_u01737509074173") 2025/01/22 01:29:11 DEBUG : test-multithread-abort: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf3688094_d20250122_m012400_c001_v0001184_t0058_u01737509040330") 2025/01/22 01:29:11 DEBUG : test-multithread-copy-false-201326591-2: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f409d499a74c89dd7_d20250122_m012112_c001_v7007000_t0000_u01737508872314") 2025/01/22 01:29:11 DEBUG : test-multithread-copy-false-201326591-2: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf3686153_d20250122_m012039_c001_v0001184_t0004_u01737508839106") 2025/01/22 01:29:11 DEBUG : test-multithread-copy-false-201326592-2: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f4057744a635905f8_d20250122_m012148_c001_v7007000_t0000_u01737508908348") 2025/01/22 01:29:11 DEBUG : test-multithread-copy-false-201326592-2: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf3686691_d20250122_m012114_c001_v0001184_t0007_u01737508874309") 2025/01/22 01:29:11 DEBUG : test-multithread-copy-false-201326593-2: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f410c5327f39c1f03_d20250122_m012223_c001_v7007000_t0000_u01737508943664") 2025/01/22 01:29:11 DEBUG : test-multithread-copy-false-201326593-2: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf3686bfb_d20250122_m012150_c001_v0001184_t0016_u01737508910292") 2025/01/22 01:29:11 DEBUG : test-multithread-copy-true-201326591-2: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f4120d195cf097c74_d20250122_m012254_c001_v7007000_t0000_u01737508974796") 2025/01/22 01:29:11 DEBUG : test-multithread-copy-true-201326591-2: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f2120d195cf097b5f_d20250122_m012238_c001_v0001182_t0015_u01737508958300") 2025/01/22 01:29:11 DEBUG : test-multithread-copy-true-201326592-2: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f4009652886c578a1_d20250122_m012327_c001_v7007000_t0000_u01737509007486") 2025/01/22 01:29:11 DEBUG : test-multithread-copy-true-201326592-2: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f204d75fb94299883_d20250122_m012309_c001_v0001177_t0013_u01737508989545") 2025/01/22 01:29:11 DEBUG : test-multithread-copy-true-201326593-2: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f41338e763cff4ef7_d20250122_m012359_c001_v7007000_t0000_u01737509039026") 2025/01/22 01:29:11 DEBUG : test-multithread-copy-true-201326593-2: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f2169dc1abe5d7504_d20250122_m012342_c001_v0001183_t0045_u01737509022128") 2025/01/22 01:29:11 DEBUG : test.sum: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f4120d195cf098100_d20250122_m012503_c001_v7007000_t0000_u01737509103640") 2025/01/22 01:29:11 DEBUG : test.sum: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf3688a0b_d20250122_m012501_c001_v0001184_t0036_u01737509101942") 2025/01/22 01:29:11 DEBUG : test.sum: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf36889c6_d20250122_m012500_c001_v0001184_t0000_u01737509100392") 2025/01/22 01:29:12 DEBUG : test.sum: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf36889a2_d20250122_m012459_c001_v0001184_t0026_u01737509099265") 2025/01/22 01:29:12 DEBUG : test.sum: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368897e_d20250122_m012458_c001_v0001184_t0039_u01737509098135") 2025/01/22 01:29:12 DEBUG : test.sum: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf3688946_d20250122_m012456_c001_v0001184_t0038_u01737509096841") 2025/01/22 01:29:12 DEBUG : test.sum: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf3688925_d20250122_m012455_c001_v0001184_t0021_u01737509095718") 2025/01/22 01:29:12 DEBUG : test.sum: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf36888d3_d20250122_m012453_c001_v0001184_t0058_u01737509093773") 2025/01/22 01:29:12 DEBUG : test.sum: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f4120d195cf098089_d20250122_m012450_c001_v7007000_t0000_u01737509090614") 2025/01/22 01:29:12 DEBUG : test.sum: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf3688832_d20250122_m012449_c001_v0001184_t0019_u01737509089857") 2025/01/22 01:29:12 DEBUG : test.sum: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf3688814_d20250122_m012448_c001_v0001184_t0004_u01737509088935") 2025/01/22 01:29:12 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f41564a3b900daedf_d20250122_m012845_c001_v7007000_t0000_u01737509325727") 2025/01/22 01:29:12 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368ada7_d20250122_m012844_c001_v0001184_t0013_u01737509324615") 2025/01/22 01:29:12 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f41564a3b900daee1_d20250122_m012845_c001_v7007000_t0000_u01737509325909") 2025/01/22 01:29:12 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368ad8f_d20250122_m012844_c001_v0001184_t0013_u01737509324418") 2025/01/22 01:29:12 DEBUG : zend.txt: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f409d499a74c8b70b_d20250122_m012730_c001_v7007000_t0000_u01737509250348") 2025/01/22 01:29:12 DEBUG : zend.txt: Deleting (id "4_z0f72cd73908d8bbb9f480e1b_f11532f6bf368a0e1_d20250122_m012725_c001_v0001184_t0045_u01737509245126") "./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -fast-list" - Finished OK in 8m42.330431164s (try 1/5)