"./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose" - Starting (try 1/5) 2024/12/15 04:01:08 DEBUG : Creating backend with remote "TestB2:rclone-test-bemegub0gama" 2024/12/15 04:01:08 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/12/15 04:01:08 DEBUG : Creating backend with remote "/tmp/rclone2684689852" === 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-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", Modify Window "1ms" 2024/12/15 04:01:09 DEBUG : chunksize-probe: Cancelling large file upload === RUN TestMultithreadCopy/upload=false,size=201326591,streams=2 2024/12/15 04:01:34 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2024/12/15 04:01:34 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: write buffer set to 131072 2024/12/15 04:01:34 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2024/12/15 04:01:34 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2024/12/15 04:01:34 DEBUG : test-multithread-copy-false-201326591-2: Starting multi-thread copy with 3 chunks of size 64Mi with 3 parallel streams 2024/12/15 04:01:34 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 3/3 (134217728-201326591) size 64.000Mi starting 2024/12/15 04:01:34 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 1/3 (0-67108864) size 64Mi starting 2024/12/15 04:01:34 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 2/3 (67108864-134217728) size 64Mi starting 2024/12/15 04:01:34 DEBUG : test-multithread-copy-false-201326591-2: writing chunk 2 2024/12/15 04:01:34 DEBUG : test-multithread-copy-false-201326591-2: writing chunk 0 2024/12/15 04:01:35 DEBUG : test-multithread-copy-false-201326591-2: writing chunk 1 2024/12/15 04:01:39 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 3/3 (134217728-201326591) size 64.000Mi finished 2024/12/15 04:01:40 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 1/3 (0-67108864) size 64Mi finished 2024/12/15 04:01:40 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 2/3 (67108864-134217728) size 64Mi finished 2024/12/15 04:01:40 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 2024/12/15 04:02:10 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2024/12/15 04:02:10 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: write buffer set to 131072 2024/12/15 04:02:10 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2024/12/15 04:02:10 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2024/12/15 04:02:10 DEBUG : test-multithread-copy-false-201326592-2: Starting multi-thread copy with 3 chunks of size 64Mi with 3 parallel streams 2024/12/15 04:02:10 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 3/3 (134217728-201326592) size 64Mi starting 2024/12/15 04:02:10 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 1/3 (0-67108864) size 64Mi starting 2024/12/15 04:02:10 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 2/3 (67108864-134217728) size 64Mi starting 2024/12/15 04:02:10 DEBUG : test-multithread-copy-false-201326592-2: writing chunk 2 2024/12/15 04:02:11 DEBUG : test-multithread-copy-false-201326592-2: writing chunk 0 2024/12/15 04:02:11 DEBUG : test-multithread-copy-false-201326592-2: writing chunk 1 2024/12/15 04:02:15 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 3/3 (134217728-201326592) size 64Mi finished 2024/12/15 04:02:16 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 1/3 (0-67108864) size 64Mi finished 2024/12/15 04:02:16 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 2/3 (67108864-134217728) size 64Mi finished 2024/12/15 04:02:16 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 2024/12/15 04:02:48 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2024/12/15 04:02:48 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: write buffer set to 131072 2024/12/15 04:02:48 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2024/12/15 04:02:48 DEBUG : test-multithread-copy-false-201326593-2: Starting multi-thread copy with 4 chunks of size 64Mi with 4 parallel streams 2024/12/15 04:02:48 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 1/4 (0-67108864) size 64Mi starting 2024/12/15 04:02:48 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 4/4 (201326592-201326593) size 1 starting 2024/12/15 04:02:48 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 3/4 (134217728-201326592) size 64Mi starting 2024/12/15 04:02:48 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 2/4 (67108864-134217728) size 64Mi starting 2024/12/15 04:02:48 DEBUG : test-multithread-copy-false-201326593-2: writing chunk 0 2024/12/15 04:02:49 DEBUG : test-multithread-copy-false-201326593-2: writing chunk 3 2024/12/15 04:02:49 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 4/4 (201326592-201326593) size 1 finished 2024/12/15 04:02:49 DEBUG : test-multithread-copy-false-201326593-2: writing chunk 2 2024/12/15 04:02:49 DEBUG : test-multithread-copy-false-201326593-2: writing chunk 1 2024/12/15 04:02:53 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 1/4 (0-67108864) size 64Mi finished 2024/12/15 04:02:54 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 3/4 (134217728-201326592) size 64Mi finished 2024/12/15 04:02:54 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 2/4 (67108864-134217728) size 64Mi finished 2024/12/15 04:02:54 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 2024/12/15 04:03:16 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: disabling buffering because source is local disk 2024/12/15 04:03:17 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2024/12/15 04:03:17 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: number of streams 4 was bigger than number of chunks 2 2024/12/15 04:03:17 DEBUG : test-multithread-copy-true-201326591-2: Starting multi-thread copy with 2 chunks of size 96Mi with 2 parallel streams 2024/12/15 04:03:17 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: chunk 2/2 (100663296-201326591) size 96.000Mi starting 2024/12/15 04:03:17 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: chunk 1/2 (0-100663296) size 96Mi starting 2024/12/15 04:03:17 DEBUG : test-multithread-copy-true-201326591-2: Sending chunk 0 length 100663296 2024/12/15 04:03:17 DEBUG : test-multithread-copy-true-201326591-2: Sending chunk 1 length 100663295 2024/12/15 04:03:25 DEBUG : test-multithread-copy-true-201326591-2: Done sending chunk 0 2024/12/15 04:03:25 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: chunk 1/2 (0-100663296) size 96Mi finished 2024/12/15 04:03:25 DEBUG : test-multithread-copy-true-201326591-2: Done sending chunk 1 2024/12/15 04:03:25 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: chunk 2/2 (100663296-201326591) size 96.000Mi finished 2024/12/15 04:03:25 DEBUG : test-multithread-copy-true-201326591-2: Finishing large file upload with 2 parts 2024/12/15 04:03:26 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 2024/12/15 04:03:47 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: disabling buffering because source is local disk 2024/12/15 04:03:48 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2024/12/15 04:03:48 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: number of streams 4 was bigger than number of chunks 2 2024/12/15 04:03:48 DEBUG : test-multithread-copy-true-201326592-2: Starting multi-thread copy with 2 chunks of size 96Mi with 2 parallel streams 2024/12/15 04:03:48 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: chunk 2/2 (100663296-201326592) size 96Mi starting 2024/12/15 04:03:48 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: chunk 1/2 (0-100663296) size 96Mi starting 2024/12/15 04:03:48 DEBUG : test-multithread-copy-true-201326592-2: Sending chunk 1 length 100663296 2024/12/15 04:03:48 DEBUG : test-multithread-copy-true-201326592-2: Sending chunk 0 length 100663296 2024/12/15 04:03:56 DEBUG : test-multithread-copy-true-201326592-2: Done sending chunk 0 2024/12/15 04:03:56 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: chunk 1/2 (0-100663296) size 96Mi finished 2024/12/15 04:03:57 DEBUG : test-multithread-copy-true-201326592-2: Done sending chunk 1 2024/12/15 04:03:57 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: chunk 2/2 (100663296-201326592) size 96Mi finished 2024/12/15 04:03:57 DEBUG : test-multithread-copy-true-201326592-2: Finishing large file upload with 2 parts 2024/12/15 04:03:57 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 2024/12/15 04:04:19 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: disabling buffering because source is local disk 2024/12/15 04:04:20 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2024/12/15 04:04:20 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2024/12/15 04:04:20 DEBUG : test-multithread-copy-true-201326593-2: Starting multi-thread copy with 3 chunks of size 96Mi with 3 parallel streams 2024/12/15 04:04:20 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 3/3 (201326592-201326593) size 1 starting 2024/12/15 04:04:20 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 1/3 (0-100663296) size 96Mi starting 2024/12/15 04:04:20 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 2/3 (100663296-201326592) size 96Mi starting 2024/12/15 04:04:20 DEBUG : test-multithread-copy-true-201326593-2: Sending chunk 2 length 1 2024/12/15 04:04:20 DEBUG : test-multithread-copy-true-201326593-2: Sending chunk 0 length 100663296 2024/12/15 04:04:20 DEBUG : test-multithread-copy-true-201326593-2: Sending chunk 1 length 100663296 2024/12/15 04:04:21 DEBUG : test-multithread-copy-true-201326593-2: Done sending chunk 2 2024/12/15 04:04:21 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 3/3 (201326592-201326593) size 1 finished 2024/12/15 04:04:28 DEBUG : test-multithread-copy-true-201326593-2: Done sending chunk 0 2024/12/15 04:04:28 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 1/3 (0-100663296) size 96Mi finished 2024/12/15 04:04:28 DEBUG : test-multithread-copy-true-201326593-2: Done sending chunk 1 2024/12/15 04:04:28 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 2/3 (100663296-201326592) size 96Mi finished 2024/12/15 04:04:28 DEBUG : test-multithread-copy-true-201326593-2: Finishing large file upload with 3 parts 2024/12/15 04:04:29 DEBUG : test-multithread-copy-true-201326593-2: Finished multi-thread copy with 3 parts of size 96Mi --- PASS: TestMultithreadCopy (208.18s) --- PASS: TestMultithreadCopy/upload=false,size=201326591,streams=2 (37.91s) --- PASS: TestMultithreadCopy/upload=false,size=201326592,streams=2 (36.11s) --- PASS: TestMultithreadCopy/upload=false,size=201326593,streams=2 (38.20s) --- PASS: TestMultithreadCopy/upload=true,size=201326591,streams=2 (31.71s) --- PASS: TestMultithreadCopy/upload=true,size=201326592,streams=2 (31.65s) --- PASS: TestMultithreadCopy/upload=true,size=201326593,streams=2 (30.87s) === RUN TestMultithreadCopyAbort run.go:180: Remote "B2 bucket rclone-test-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", Modify Window "1ms" 2024/12/15 04:04:37 DEBUG : chunksize-probe: Cancelling large file upload 2024/12/15 04:04:51 DEBUG : test-multithread-abort: multi-thread copy: disabling buffering because source is local disk 2024/12/15 04:04:52 DEBUG : test-multithread-abort: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 1 2024/12/15 04:04:52 DEBUG : test-multithread-abort: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2024/12/15 04:04:52 DEBUG : test-multithread-abort: Starting multi-thread copy with 3 chunks of size 96Mi with 3 parallel streams 2024/12/15 04:04:52 DEBUG : test-multithread-abort: multi-thread copy: chunk 3/3 (201326592-201326593) size 1 starting 2024/12/15 04:04:52 DEBUG : test-multithread-abort: multi-thread copy: chunk 1/3 (0-100663296) size 96Mi starting 2024/12/15 04:04:52 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2024/12/15 04:04:52 DEBUG : Open with options = [RangeOption(0,100663295)] 2024/12/15 04:04:52 DEBUG : test-multithread-abort: multi-thread copy: chunk 2/3 (100663296-201326592) size 96Mi starting 2024/12/15 04:04:52 DEBUG : Open with options = [RangeOption(100663296,201326591)] 2024/12/15 04:04:52 DEBUG : test-multithread-abort: Sending chunk 0 length 100663296 2024/12/15 04:04:52 DEBUG : test-multithread-abort: Sending chunk 1 length 100663296 2024/12/15 04:05:00 DEBUG : test-multithread-abort: Done sending chunk 0 2024/12/15 04:05:00 DEBUG : test-multithread-abort: multi-thread copy: chunk 1/3 (0-100663296) size 96Mi finished 2024/12/15 04:05:00 DEBUG : test-multithread-abort: Done sending chunk 1 2024/12/15 04:05:00 DEBUG : test-multithread-abort: multi-thread copy: chunk 2/3 (100663296-201326592) size 96Mi finished 2024/12/15 04:05:00 DEBUG : Returning error reader 2024/12/15 04:05:00 DEBUG : test-multithread-abort: Sending chunk 2 length 1 2024/12/15 04:05:00 DEBUG : BOOM: simulated read failure 2024/12/15 04:05:00 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 0/10: BOOM: simulated read failure 2024/12/15 04:05:00 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2024/12/15 04:05:01 DEBUG : Returning error reader 2024/12/15 04:05:01 DEBUG : BOOM: simulated read failure 2024/12/15 04:05:01 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 1/10: BOOM: simulated read failure 2024/12/15 04:05:01 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2024/12/15 04:05:02 DEBUG : Returning error reader 2024/12/15 04:05:02 DEBUG : BOOM: simulated read failure 2024/12/15 04:05:02 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 2/10: BOOM: simulated read failure 2024/12/15 04:05:02 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2024/12/15 04:05:03 DEBUG : Returning error reader 2024/12/15 04:05:03 DEBUG : BOOM: simulated read failure 2024/12/15 04:05:03 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 3/10: BOOM: simulated read failure 2024/12/15 04:05:03 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2024/12/15 04:05:04 DEBUG : Returning error reader 2024/12/15 04:05:04 DEBUG : BOOM: simulated read failure 2024/12/15 04:05:04 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 4/10: BOOM: simulated read failure 2024/12/15 04:05:04 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2024/12/15 04:05:05 DEBUG : Returning error reader 2024/12/15 04:05:05 DEBUG : BOOM: simulated read failure 2024/12/15 04:05:05 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 5/10: BOOM: simulated read failure 2024/12/15 04:05:05 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2024/12/15 04:05:06 DEBUG : Returning error reader 2024/12/15 04:05:06 DEBUG : BOOM: simulated read failure 2024/12/15 04:05:06 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 6/10: BOOM: simulated read failure 2024/12/15 04:05:06 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2024/12/15 04:05:07 DEBUG : Returning error reader 2024/12/15 04:05:07 DEBUG : BOOM: simulated read failure 2024/12/15 04:05:07 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 7/10: BOOM: simulated read failure 2024/12/15 04:05:07 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2024/12/15 04:05:08 DEBUG : Returning error reader 2024/12/15 04:05:08 DEBUG : BOOM: simulated read failure 2024/12/15 04:05:08 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 8/10: BOOM: simulated read failure 2024/12/15 04:05:08 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2024/12/15 04:05:09 DEBUG : Returning error reader 2024/12/15 04:05:09 DEBUG : BOOM: simulated read failure 2024/12/15 04:05:09 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 9/10: BOOM: simulated read failure 2024/12/15 04:05:09 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2024/12/15 04:05:10 DEBUG : Returning error reader 2024/12/15 04:05:10 DEBUG : BOOM: simulated read failure 2024/12/15 04:05:10 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 10/10: BOOM: simulated read failure 2024/12/15 04:05:10 DEBUG : test-multithread-abort: Reopen failed after offset 0 bytes read: failed to reopen: too many retries 2024/12/15 04:05:10 DEBUG : test-multithread-abort: Error sending chunk 2 (retry=false): Post "https://pod-000-1177-19.backblaze.com/b2api/v1/b2_upload_part/4_zbf822d8370adcb8b9f380e1b_f2184e9ccd320d40d_d20241215_m040452_c001_v0001177_t0038_u01734235492619/0027": BOOM: simulated read failure: &url.Error{Op:"Post", URL:"https://pod-000-1177-19.backblaze.com/b2api/v1/b2_upload_part/4_zbf822d8370adcb8b9f380e1b_f2184e9ccd320d40d_d20241215_m040452_c001_v0001177_t0038_u01734235492619/0027", Err:(*errors.errorString)(0xc0003f0780)} 2024/12/15 04:05:10 DEBUG : test-multithread-abort: Error sending chunk 2: Post "https://pod-000-1177-19.backblaze.com/b2api/v1/b2_upload_part/4_zbf822d8370adcb8b9f380e1b_f2184e9ccd320d40d_d20241215_m040452_c001_v0001177_t0038_u01734235492619/0027": BOOM: simulated read failure 2024/12/15 04:05:10 DEBUG : test-multithread-abort: multi-thread copy: chunk 3/3 failed: multi-thread copy: failed to write chunk: Post "https://pod-000-1177-19.backblaze.com/b2api/v1/b2_upload_part/4_zbf822d8370adcb8b9f380e1b_f2184e9ccd320d40d_d20241215_m040452_c001_v0001177_t0038_u01734235492619/0027": BOOM: simulated read failure 2024/12/15 04:05:10 DEBUG : test-multithread-abort: multi-thread copy: cancelling transfer on exit 2024/12/15 04:05:10 DEBUG : test-multithread-abort: Cancelling large file upload --- PASS: TestMultithreadCopyAbort (35.46s) === RUN TestSizeDiffers --- PASS: TestSizeDiffers (0.00s) === RUN TestReOpen === RUN TestReOpen/Normal === RUN TestReOpen/Normal/Basics 2024/12/15 04:05:12 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/ErrorAtStart === RUN TestReOpen/Normal/WithErrors 2024/12/15 04:05:12 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2024/12/15 04:05:12 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2024/12/15 04:05:12 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/Normal/TooManyErrors 2024/12/15 04:05:12 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2024/12/15 04:05:12 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2024/12/15 04:05:12 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2024/12/15 04:05:12 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/Normal/Seek 2024/12/15 04:05:12 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2024/12/15 04:05:12 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2024/12/15 04:05:12 DEBUG : potato: Seek from 5 to 2 === RUN TestReOpen/Normal/AccountRead === RUN TestReOpen/Normal/AccountReadDelay 2024/12/15 04:05:12 DEBUG : potato: Seek from 10 to 0 2024/12/15 04:05:12 DEBUG : potato: Seek from 10 to 0 2024/12/15 04:05:12 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/AccountReadError === RUN TestReOpen/WithRangeOption === RUN TestReOpen/WithRangeOption/Basics 2024/12/15 04:05:12 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/ErrorAtStart === RUN TestReOpen/WithRangeOption/WithErrors 2024/12/15 04:05:12 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2024/12/15 04:05:12 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2024/12/15 04:05:12 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithRangeOption/TooManyErrors 2024/12/15 04:05:12 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2024/12/15 04:05:12 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2024/12/15 04:05:12 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2024/12/15 04:05:12 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithRangeOption/Seek 2024/12/15 04:05:12 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2024/12/15 04:05:12 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2024/12/15 04:05:12 DEBUG : potato: Seek from 5 to 2 2024/12/15 04:05:12 DEBUG : potato: Seek from 7 to 4 === RUN TestReOpen/WithRangeOption/AccountRead === RUN TestReOpen/WithRangeOption/AccountReadDelay 2024/12/15 04:05:12 DEBUG : potato: Seek from 7 to 0 2024/12/15 04:05:12 DEBUG : potato: Seek from 7 to 0 2024/12/15 04:05:12 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/AccountReadError === RUN TestReOpen/WithSeekOption === RUN TestReOpen/WithSeekOption/Basics 2024/12/15 04:05:12 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/ErrorAtStart === RUN TestReOpen/WithSeekOption/WithErrors 2024/12/15 04:05:12 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2024/12/15 04:05:12 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2024/12/15 04:05:12 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithSeekOption/TooManyErrors 2024/12/15 04:05:12 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2024/12/15 04:05:12 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2024/12/15 04:05:12 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2024/12/15 04:05:12 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithSeekOption/Seek 2024/12/15 04:05:12 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2024/12/15 04:05:12 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2024/12/15 04:05:12 DEBUG : potato: Seek from 5 to 2 2024/12/15 04:05:12 DEBUG : potato: Seek from 7 to 5 === RUN TestReOpen/WithSeekOption/AccountRead === RUN TestReOpen/WithSeekOption/AccountReadDelay 2024/12/15 04:05:12 DEBUG : potato: Seek from 8 to 0 2024/12/15 04:05:12 DEBUG : potato: Seek from 8 to 0 2024/12/15 04:05:12 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/AccountReadError === RUN TestReOpen/UnknownSize === RUN TestReOpen/UnknownSize/Basics 2024/12/15 04:05:12 DEBUG : potato: Seek from 9 to 0 === RUN TestReOpen/UnknownSize/ErrorAtStart === RUN TestReOpen/UnknownSize/WithErrors 2024/12/15 04:05:12 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2024/12/15 04:05:12 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2024/12/15 04:05:12 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/UnknownSize/TooManyErrors 2024/12/15 04:05:12 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2024/12/15 04:05:12 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2024/12/15 04:05:12 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2024/12/15 04:05:12 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/UnknownSize/Seek 2024/12/15 04:05:12 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2024/12/15 04:05:12 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2024/12/15 04:05:12 DEBUG : potato: Seek from 5 to 2 2024/12/15 04:05:12 DEBUG : potato: Seek from 7 to 6 === RUN TestReOpen/UnknownSize/AccountRead === RUN TestReOpen/UnknownSize/AccountReadDelay 2024/12/15 04:05:12 DEBUG : potato: Seek from 9 to 0 2024/12/15 04:05:12 DEBUG : potato: Seek from 9 to 0 2024/12/15 04:05:12 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-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", Modify Window "1ms" === RUN TestCheck/1 === RUN TestCheck/2 === RUN TestCheck/3 === RUN TestCheck/4 === RUN TestCheck/5 === RUN TestCheck/6 === RUN TestCheck/7 --- PASS: TestCheck (5.65s) --- PASS: TestCheck/1 (0.18s) --- PASS: TestCheck/2 (0.19s) --- PASS: TestCheck/3 (0.18s) --- PASS: TestCheck/4 (0.19s) --- PASS: TestCheck/5 (0.18s) --- PASS: TestCheck/6 (0.19s) --- PASS: TestCheck/7 (0.18s) === RUN TestCheckFsError 2024/12/15 04:05:18 DEBUG : Creating backend with remote "nonexistent" 2024/12/15 04:05:18 DEBUG : Creating backend with remote "nonexistent" 2024/12/15 04:05:18 DEBUG : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: Waiting for checks to finish 2024/12/15 04:05:18 ERROR : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: error reading source root directory: directory not found 2024/12/15 04:05:18 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: 2 differences found 2024/12/15 04:05:18 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-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", Modify Window "1ms" === RUN TestCheckDownload/1 === RUN TestCheckDownload/2 === RUN TestCheckDownload/3 === RUN TestCheckDownload/4 === RUN TestCheckDownload/5 === RUN TestCheckDownload/6 === RUN TestCheckDownload/7 --- PASS: TestCheckDownload (7.32s) --- PASS: TestCheckDownload/1 (0.73s) --- PASS: TestCheckDownload/2 (0.37s) --- PASS: TestCheckDownload/3 (0.36s) --- PASS: TestCheckDownload/4 (0.57s) --- PASS: TestCheckDownload/5 (0.38s) --- PASS: TestCheckDownload/6 (0.38s) --- PASS: TestCheckDownload/7 (0.38s) === RUN TestCheckSizeOnly run.go:180: Remote "B2 bucket rclone-test-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", Modify Window "1ms" === RUN TestCheckSizeOnly/1 === RUN TestCheckSizeOnly/2 === RUN TestCheckSizeOnly/3 === RUN TestCheckSizeOnly/4 === RUN TestCheckSizeOnly/5 === RUN TestCheckSizeOnly/6 === RUN TestCheckSizeOnly/7 --- PASS: TestCheckSizeOnly (5.18s) --- PASS: TestCheckSizeOnly/1 (0.18s) --- PASS: TestCheckSizeOnly/2 (0.18s) --- PASS: TestCheckSizeOnly/3 (0.18s) --- PASS: TestCheckSizeOnly/4 (0.18s) --- PASS: TestCheckSizeOnly/5 (0.18s) --- PASS: TestCheckSizeOnly/6 (0.18s) --- PASS: TestCheckSizeOnly/7 (0.18s) === RUN TestCheckEqualReaders --- PASS: TestCheckEqualReaders (0.00s) === RUN TestParseSumFile run.go:180: Remote "B2 bucket rclone-test-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", Modify Window "1ms" 2024/12/15 04:05:32 NOTICE: test.sum: improperly formatted checksum line 4 2024/12/15 04:05:32 NOTICE: test.sum: improperly formatted checksum line 5 2024/12/15 04:05:32 NOTICE: test.sum: improperly formatted checksum line 6 2024/12/15 04:05:32 NOTICE: test.sum: 2 warning(s) suppressed... 2024/12/15 04:05:33 NOTICE: test.sum: improperly formatted checksum line 4 2024/12/15 04:05:33 NOTICE: test.sum: improperly formatted checksum line 5 2024/12/15 04:05:33 NOTICE: test.sum: improperly formatted checksum line 6 2024/12/15 04:05:33 NOTICE: test.sum: 2 warning(s) suppressed... --- PASS: TestParseSumFile (3.35s) === RUN TestCheckSum run.go:180: Remote "B2 bucket rclone-test-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", Modify Window "1ms" 2024/12/15 04:05:33 DEBUG : Creating backend with remote "TestB2:rclone-test-bemegub0gama/data" check_test.go:354: B2 bucket rclone-test-bemegub0gama path data lacks md5, skipping --- SKIP: TestCheckSum (1.49s) === RUN TestCheckSumDownload run.go:180: Remote "B2 bucket rclone-test-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", Modify Window "1ms" 2024/12/15 04:05:35 DEBUG : Creating backend with remote "TestB2:rclone-test-bemegub0gama/data" === RUN TestCheckSumDownload/subtest1 === RUN TestCheckSumDownload/subtest2 === RUN TestCheckSumDownload/subtest3 === RUN TestCheckSumDownload/subtest4 === RUN TestCheckSumDownload/subtest5 === RUN TestCheckSumDownload/subtest6 === RUN TestCheckSumDownload/subtest7 --- PASS: TestCheckSumDownload (17.86s) --- PASS: TestCheckSumDownload/subtest1 (1.68s) --- PASS: TestCheckSumDownload/subtest2 (0.73s) --- PASS: TestCheckSumDownload/subtest3 (0.92s) --- PASS: TestCheckSumDownload/subtest4 (0.76s) --- PASS: TestCheckSumDownload/subtest5 (0.74s) --- PASS: TestCheckSumDownload/subtest6 (0.78s) --- PASS: TestCheckSumDownload/subtest7 (0.75s) === RUN TestApplyTransforms 2024/12/15 04:05:53 DEBUG : Creating backend with remote "TestB2:rclone-test-boquxuy0xufu" 2024/12/15 04:05:53 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/12/15 04:05:53 DEBUG : Creating backend with remote "/tmp/rclone664765152" run.go:180: Remote "B2 bucket rclone-test-boquxuy0xufu", Local "Local file system at /tmp/rclone664765152", Modify Window "1ms" upper checkfile vs. lower remote (without normalization) 2024/12/15 04:05:56 ERROR : hello, world!: sum not found 2024/12/15 04:05:56 ERROR : HELLO, WORLD!: file not in B2 bucket rclone-test-boquxuy0xufu 2024/12/15 04:05:56 NOTICE: B2 bucket rclone-test-boquxuy0xufu: 1 files missing 2024/12/15 04:05:56 NOTICE: 1 hashes missing 2024/12/15 04:05:56 NOTICE: B2 bucket rclone-test-boquxuy0xufu: 2 differences found 2024/12/15 04:05:56 NOTICE: B2 bucket rclone-test-boquxuy0xufu: 2 errors while checking upper checkfile vs. lower remote (with normalization) 2024/12/15 04:05:57 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2024/12/15 04:05:57 NOTICE: B2 bucket rclone-test-boquxuy0xufu: 0 differences found 2024/12/15 04:05:57 NOTICE: B2 bucket rclone-test-boquxuy0xufu: 1 matching files 2024/12/15 04:05:57 DEBUG : Creating backend with remote "TestB2:rclone-test-suhofix2liqo" 2024/12/15 04:05:57 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/12/15 04:05:57 DEBUG : Creating backend with remote "/tmp/rclone1568609155" run.go:180: Remote "B2 bucket rclone-test-suhofix2liqo", Local "Local file system at /tmp/rclone1568609155", Modify Window "1ms" lower checkfile vs. upper remote (without normalization) 2024/12/15 04:05:59 ERROR : HELLO, WORLD!: sum not found 2024/12/15 04:05:59 ERROR : hello, world!: file not in B2 bucket rclone-test-suhofix2liqo 2024/12/15 04:05:59 NOTICE: B2 bucket rclone-test-suhofix2liqo: 1 files missing 2024/12/15 04:05:59 NOTICE: 1 hashes missing 2024/12/15 04:05:59 NOTICE: B2 bucket rclone-test-suhofix2liqo: 2 differences found 2024/12/15 04:05:59 NOTICE: B2 bucket rclone-test-suhofix2liqo: 2 errors while checking lower checkfile vs. upper remote (with normalization) 2024/12/15 04:06:00 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2024/12/15 04:06:00 NOTICE: B2 bucket rclone-test-suhofix2liqo: 0 differences found 2024/12/15 04:06:00 NOTICE: B2 bucket rclone-test-suhofix2liqo: 1 matching files 2024/12/15 04:06:00 DEBUG : Creating backend with remote "TestB2:rclone-test-luloked2yalo" 2024/12/15 04:06:00 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/12/15 04:06:01 DEBUG : Creating backend with remote "/tmp/rclone666897831" run.go:180: Remote "B2 bucket rclone-test-luloked2yalo", Local "Local file system at /tmp/rclone666897831", Modify Window "1ms" lower checkfile vs. upperlowermixed remote (without normalization) 2024/12/15 04:06:03 ERROR : HeLlO, wOrLd!: sum not found 2024/12/15 04:06:03 ERROR : hello, world!: file not in B2 bucket rclone-test-luloked2yalo 2024/12/15 04:06:03 NOTICE: B2 bucket rclone-test-luloked2yalo: 1 files missing 2024/12/15 04:06:03 NOTICE: 1 hashes missing 2024/12/15 04:06:03 NOTICE: B2 bucket rclone-test-luloked2yalo: 2 differences found 2024/12/15 04:06:03 NOTICE: B2 bucket rclone-test-luloked2yalo: 2 errors while checking lower checkfile vs. upperlowermixed remote (with normalization) 2024/12/15 04:06:03 DEBUG : HeLlO, wOrLd!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2024/12/15 04:06:03 NOTICE: B2 bucket rclone-test-luloked2yalo: 0 differences found 2024/12/15 04:06:03 NOTICE: B2 bucket rclone-test-luloked2yalo: 1 matching files 2024/12/15 04:06:03 DEBUG : Creating backend with remote "TestB2:rclone-test-higecuc8goki" 2024/12/15 04:06:03 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/12/15 04:06:04 DEBUG : Creating backend with remote "/tmp/rclone2604312273" run.go:180: Remote "B2 bucket rclone-test-higecuc8goki", Local "Local file system at /tmp/rclone2604312273", Modify Window "1ms" upperlowermixed checkfile vs. upper remote (without normalization) 2024/12/15 04:06:06 ERROR : HELLO, WORLD!: sum not found 2024/12/15 04:06:06 ERROR : HeLlO, wOrLd!: file not in B2 bucket rclone-test-higecuc8goki 2024/12/15 04:06:06 NOTICE: B2 bucket rclone-test-higecuc8goki: 1 files missing 2024/12/15 04:06:06 NOTICE: 1 hashes missing 2024/12/15 04:06:06 NOTICE: B2 bucket rclone-test-higecuc8goki: 2 differences found 2024/12/15 04:06:06 NOTICE: B2 bucket rclone-test-higecuc8goki: 2 errors while checking upperlowermixed checkfile vs. upper remote (with normalization) 2024/12/15 04:06:07 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2024/12/15 04:06:07 NOTICE: B2 bucket rclone-test-higecuc8goki: 0 differences found 2024/12/15 04:06:07 NOTICE: B2 bucket rclone-test-higecuc8goki: 1 matching files 2024/12/15 04:06:07 DEBUG : Creating backend with remote "TestB2:rclone-test-bujuguv2niwu" 2024/12/15 04:06:07 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/12/15 04:06:08 DEBUG : Creating backend with remote "/tmp/rclone2024404917" run.go:180: Remote "B2 bucket rclone-test-bujuguv2niwu", Local "Local file system at /tmp/rclone2024404917", Modify Window "1ms" NFD checkfile vs. NFC remote (without normalization) 2024/12/15 04:06:10 ERROR : 測試_Русский___ě_áñ: sum not found 2024/12/15 04:06:10 ERROR : 測試_Русский___ě_áñ: file not in B2 bucket rclone-test-bujuguv2niwu 2024/12/15 04:06:10 NOTICE: B2 bucket rclone-test-bujuguv2niwu: 1 files missing 2024/12/15 04:06:10 NOTICE: 1 hashes missing 2024/12/15 04:06:10 NOTICE: B2 bucket rclone-test-bujuguv2niwu: 2 differences found 2024/12/15 04:06:10 NOTICE: B2 bucket rclone-test-bujuguv2niwu: 2 errors while checking NFD checkfile vs. NFC remote (with normalization) 2024/12/15 04:06:10 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2024/12/15 04:06:10 NOTICE: B2 bucket rclone-test-bujuguv2niwu: 0 differences found 2024/12/15 04:06:10 NOTICE: B2 bucket rclone-test-bujuguv2niwu: 1 matching files 2024/12/15 04:06:10 DEBUG : Creating backend with remote "TestB2:rclone-test-xazefen9fije" 2024/12/15 04:06:10 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/12/15 04:06:11 DEBUG : Creating backend with remote "/tmp/rclone2070958271" run.go:180: Remote "B2 bucket rclone-test-xazefen9fije", Local "Local file system at /tmp/rclone2070958271", Modify Window "1ms" NFC checkfile vs. NFD remote (without normalization) 2024/12/15 04:06:13 ERROR : 測試_Русский___ě_áñ: sum not found 2024/12/15 04:06:13 ERROR : 測試_Русский___ě_áñ: file not in B2 bucket rclone-test-xazefen9fije 2024/12/15 04:06:13 NOTICE: B2 bucket rclone-test-xazefen9fije: 1 files missing 2024/12/15 04:06:13 NOTICE: 1 hashes missing 2024/12/15 04:06:13 NOTICE: B2 bucket rclone-test-xazefen9fije: 2 differences found 2024/12/15 04:06:13 NOTICE: B2 bucket rclone-test-xazefen9fije: 2 errors while checking NFC checkfile vs. NFD remote (with normalization) 2024/12/15 04:06:14 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2024/12/15 04:06:14 NOTICE: B2 bucket rclone-test-xazefen9fije: 0 differences found 2024/12/15 04:06:14 NOTICE: B2 bucket rclone-test-xazefen9fije: 1 matching files 2024/12/15 04:06:14 DEBUG : Creating backend with remote "TestB2:rclone-test-qicagar4xamo" 2024/12/15 04:06:14 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/12/15 04:06:15 DEBUG : Creating backend with remote "/tmp/rclone2966358302" run.go:180: Remote "B2 bucket rclone-test-qicagar4xamo", Local "Local file system at /tmp/rclone2966358302", Modify Window "1ms" NFDx2 checkfile vs. both remote (without normalization) 2024/12/15 04:06:17 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2024/12/15 04:06:17 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-qicagar4xamo 2024/12/15 04:06:17 NOTICE: B2 bucket rclone-test-qicagar4xamo: 1 files missing 2024/12/15 04:06:17 NOTICE: 1 hashes missing 2024/12/15 04:06:17 NOTICE: B2 bucket rclone-test-qicagar4xamo: 2 differences found 2024/12/15 04:06:17 NOTICE: B2 bucket rclone-test-qicagar4xamo: 2 errors while checking NFDx2 checkfile vs. both remote (with normalization) 2024/12/15 04:06:18 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2024/12/15 04:06:18 NOTICE: B2 bucket rclone-test-qicagar4xamo: 0 differences found 2024/12/15 04:06:18 NOTICE: B2 bucket rclone-test-qicagar4xamo: 1 matching files 2024/12/15 04:06:18 DEBUG : Creating backend with remote "TestB2:rclone-test-vawamax8hawu" 2024/12/15 04:06:18 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/12/15 04:06:18 DEBUG : Creating backend with remote "/tmp/rclone719730209" run.go:180: Remote "B2 bucket rclone-test-vawamax8hawu", Local "Local file system at /tmp/rclone719730209", Modify Window "1ms" NFCx2 checkfile vs. both remote (without normalization) 2024/12/15 04:06:20 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2024/12/15 04:06:20 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-vawamax8hawu 2024/12/15 04:06:20 NOTICE: B2 bucket rclone-test-vawamax8hawu: 1 files missing 2024/12/15 04:06:20 NOTICE: 1 hashes missing 2024/12/15 04:06:20 NOTICE: B2 bucket rclone-test-vawamax8hawu: 2 differences found 2024/12/15 04:06:20 NOTICE: B2 bucket rclone-test-vawamax8hawu: 2 errors while checking NFCx2 checkfile vs. both remote (with normalization) 2024/12/15 04:06:21 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2024/12/15 04:06:21 NOTICE: B2 bucket rclone-test-vawamax8hawu: 0 differences found 2024/12/15 04:06:21 NOTICE: B2 bucket rclone-test-vawamax8hawu: 1 matching files 2024/12/15 04:06:21 DEBUG : Creating backend with remote "TestB2:rclone-test-negetec9noso" 2024/12/15 04:06:21 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/12/15 04:06:22 DEBUG : Creating backend with remote "/tmp/rclone515679399" run.go:180: Remote "B2 bucket rclone-test-negetec9noso", Local "Local file system at /tmp/rclone515679399", Modify Window "1ms" both checkfile vs. NFDx2 remote (without normalization) 2024/12/15 04:06:24 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2024/12/15 04:06:24 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-negetec9noso 2024/12/15 04:06:24 NOTICE: B2 bucket rclone-test-negetec9noso: 1 files missing 2024/12/15 04:06:24 NOTICE: 1 hashes missing 2024/12/15 04:06:24 NOTICE: B2 bucket rclone-test-negetec9noso: 2 differences found 2024/12/15 04:06:24 NOTICE: B2 bucket rclone-test-negetec9noso: 2 errors while checking both checkfile vs. NFDx2 remote (with normalization) 2024/12/15 04:06:24 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2024/12/15 04:06:24 NOTICE: B2 bucket rclone-test-negetec9noso: 0 differences found 2024/12/15 04:06:24 NOTICE: B2 bucket rclone-test-negetec9noso: 1 matching files 2024/12/15 04:06:24 DEBUG : Creating backend with remote "TestB2:rclone-test-vamisom0zaxo" 2024/12/15 04:06:24 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/12/15 04:06:25 DEBUG : Creating backend with remote "/tmp/rclone283837311" run.go:180: Remote "B2 bucket rclone-test-vamisom0zaxo", Local "Local file system at /tmp/rclone283837311", Modify Window "1ms" both checkfile vs. NFCx2 remote (without normalization) 2024/12/15 04:06:28 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2024/12/15 04:06:28 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-vamisom0zaxo 2024/12/15 04:06:28 NOTICE: B2 bucket rclone-test-vamisom0zaxo: 1 files missing 2024/12/15 04:06:28 NOTICE: 1 hashes missing 2024/12/15 04:06:28 NOTICE: B2 bucket rclone-test-vamisom0zaxo: 2 differences found 2024/12/15 04:06:28 NOTICE: B2 bucket rclone-test-vamisom0zaxo: 2 errors while checking both checkfile vs. NFCx2 remote (with normalization) 2024/12/15 04:06:28 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2024/12/15 04:06:28 NOTICE: B2 bucket rclone-test-vamisom0zaxo: 0 differences found 2024/12/15 04:06:28 NOTICE: B2 bucket rclone-test-vamisom0zaxo: 1 matching files 2024/12/15 04:06:28 DEBUG : B2 bucket rclone-test-vamisom0zaxo: Purge remote 2024/12/15 04:06:28 INFO : B2 bucket rclone-test-vamisom0zaxo: cleaning bucket "rclone-test-vamisom0zaxo" of all files 2024/12/15 04:06:29 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_zef72fdd370edcb8b9f380e1b_f111506c600da42d3_d20241215_m040627_c001_v0001109_t0059_u01734235587333") 2024/12/15 04:06:29 DEBUG : B2 bucket rclone-test-negetec9noso: Purge remote 2024/12/15 04:06:29 INFO : B2 bucket rclone-test-negetec9noso: cleaning bucket "rclone-test-negetec9noso" of all files 2024/12/15 04:06:30 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_zdf12fd0370edcb8b9f380e1b_f107850b1f6f2408f_d20241215_m040623_c001_v0001183_t0014_u01734235583743") 2024/12/15 04:06:30 DEBUG : B2 bucket rclone-test-vawamax8hawu: Purge remote 2024/12/15 04:06:30 INFO : B2 bucket rclone-test-vawamax8hawu: cleaning bucket "rclone-test-vawamax8hawu" of all files 2024/12/15 04:06:31 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_z0f62ed4370edcb8b9f380e1b_f1193b1b32b9cd09a_d20241215_m040620_c001_v0001182_t0046_u01734235580394") 2024/12/15 04:06:32 DEBUG : B2 bucket rclone-test-qicagar4xamo: Purge remote 2024/12/15 04:06:32 INFO : B2 bucket rclone-test-qicagar4xamo: cleaning bucket "rclone-test-qicagar4xamo" of all files 2024/12/15 04:06:32 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_z2f32dd5370edcb8b9f380e1b_f11754fea1bedefeb_d20241215_m040616_c001_v0001115_t0002_u01734235576847") 2024/12/15 04:06:33 DEBUG : B2 bucket rclone-test-xazefen9fije: Purge remote 2024/12/15 04:06:33 INFO : B2 bucket rclone-test-xazefen9fije: cleaning bucket "rclone-test-xazefen9fije" of all files 2024/12/15 04:06:33 DEBUG : 測試_Русский___ě_áñ: Deleting (id "4_z8f42cd6370edcb8b9f380e1b_f115acf5d1a8ea439_d20241215_m040612_c001_v0001106_t0038_u01734235572938") 2024/12/15 04:06:34 DEBUG : B2 bucket rclone-test-bujuguv2niwu: Purge remote 2024/12/15 04:06:34 INFO : B2 bucket rclone-test-bujuguv2niwu: cleaning bucket "rclone-test-bujuguv2niwu" of all files 2024/12/15 04:06:35 DEBUG : 測試_Русский___ě_áñ: Deleting (id "4_z7f62bd9370edcb8b9f380e1b_f115423aa0f0ef606_d20241215_m040609_c001_v0001183_t0054_u01734235569488") 2024/12/15 04:06:35 DEBUG : B2 bucket rclone-test-higecuc8goki: Purge remote 2024/12/15 04:06:35 INFO : B2 bucket rclone-test-higecuc8goki: cleaning bucket "rclone-test-higecuc8goki" of all files 2024/12/15 04:06:36 DEBUG : HELLO, WORLD!: Deleting (id "4_zdfe2adb370edcb8b9f380e1b_f1102192c6170875f_d20241215_m040606_c001_v0001181_t0028_u01734235566142") 2024/12/15 04:06:37 DEBUG : B2 bucket rclone-test-luloked2yalo: Purge remote 2024/12/15 04:06:37 INFO : B2 bucket rclone-test-luloked2yalo: cleaning bucket "rclone-test-luloked2yalo" of all files 2024/12/15 04:06:37 DEBUG : HeLlO, wOrLd!: Deleting (id "4_zaf329de370edcb8b9f380e1b_f108459a0daa9e6e7_d20241215_m040602_c001_v0001183_t0049_u01734235562757") 2024/12/15 04:06:38 DEBUG : B2 bucket rclone-test-suhofix2liqo: Purge remote 2024/12/15 04:06:38 INFO : B2 bucket rclone-test-suhofix2liqo: cleaning bucket "rclone-test-suhofix2liqo" of all files 2024/12/15 04:06:38 DEBUG : HELLO, WORLD!: Deleting (id "4_zff229d0370edcb8b9f380e1b_f1151ee59046bfc4c_d20241215_m040559_c001_v0001176_t0056_u01734235559278") 2024/12/15 04:06:39 DEBUG : B2 bucket rclone-test-boquxuy0xufu: Purge remote 2024/12/15 04:06:39 INFO : B2 bucket rclone-test-boquxuy0xufu: cleaning bucket "rclone-test-boquxuy0xufu" of all files 2024/12/15 04:06:40 DEBUG : hello, world!: Deleting (id "4_z1ff28d2370edcb8b9f380e1b_f11463fcba59f5fb5_d20241215_m040555_c001_v0001116_t0052_u01734235555568") --- PASS: TestApplyTransforms (47.73s) === RUN TestTruncateString --- PASS: TestTruncateString (0.00s) === RUN TestCopyFile run.go:180: Remote "B2 bucket rclone-test-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", Modify Window "1ms" 2024/12/15 04:06:41 DEBUG : file1: Need to transfer - File not found at Destination 2024/12/15 04:06:42 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2024/12/15 04:06:42 INFO : file1: Copied (new) to: sub/file2 2024/12/15 04:06:43 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/12/15 04:06:43 DEBUG : file1: Unchanged skipping 2024/12/15 04:06:43 DEBUG : B2 bucket rclone-test-bemegub0gama: don't need to copy/move sub/file2, it is already at target location --- PASS: TestCopyFile (3.40s) === RUN TestCopyLongFile run.go:180: Remote "B2 bucket rclone-test-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", 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-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", Modify Window "1ms" 2024/12/15 04:06:45 DEBUG : Creating backend with remote "TestB2:rclone-test-bemegub0gama/backup" 2024/12/15 04:06:46 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18) 2024/12/15 04:06:48 DEBUG : dst/file1: sha1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK 2024/12/15 04:06:48 INFO : dst/file1: Copied (server-side copy) 2024/12/15 04:06:48 INFO : dst/file1: Deleted 2024/12/15 04:06:48 DEBUG : dst/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2024/12/15 04:06:48 INFO : dst/file1: Copied (new) --- PASS: TestCopyFileBackupDir (4.92s) === RUN TestCopyFileCompareDest run.go:180: Remote "B2 bucket rclone-test-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", Modify Window "1ms" 2024/12/15 04:06:49 DEBUG : Creating backend with remote "TestB2:rclone-test-bemegub0gama/dst" 2024/12/15 04:06:50 DEBUG : Creating backend with remote "TestB2:rclone-test-bemegub0gama/CompareDest" 2024/12/15 04:06:52 DEBUG : one: Need to transfer - File not found at Destination 2024/12/15 04:06:54 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2024/12/15 04:06:54 INFO : one: Copied (new) 2024/12/15 04:06:54 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/12/15 04:06:55 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2024/12/15 04:06:55 INFO : one: Copied (replaced existing) 2024/12/15 04:06:57 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/12/15 04:06:57 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2024/12/15 04:06:57 DEBUG : one: Destination found in --compare-dest, skipping 2024/12/15 04:06:59 DEBUG : two: Need to transfer - File not found at Destination 2024/12/15 04:06:59 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2024/12/15 04:06:59 DEBUG : two: Destination found in --compare-dest, skipping 2024/12/15 04:06:59 DEBUG : two: Need to transfer - File not found at Destination 2024/12/15 04:06:59 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2024/12/15 04:06:59 DEBUG : two: Destination found in --compare-dest, skipping 2024/12/15 04:07:00 DEBUG : two: Need to transfer - File not found at Destination 2024/12/15 04:07:00 DEBUG : two: Sizes differ (src 5 vs dst 3) 2024/12/15 04:07:01 DEBUG : two: sha1 = a4be8877ad88f3109a6411985a1f359e10e2d930 OK 2024/12/15 04:07:01 INFO : two: Copied (new) --- PASS: TestCopyFileCompareDest (13.02s) === RUN TestCopyFileCopyDest run.go:180: Remote "B2 bucket rclone-test-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", Modify Window "1ms" 2024/12/15 04:07:02 DEBUG : Creating backend with remote "TestB2:rclone-test-bemegub0gama/dst" 2024/12/15 04:07:04 DEBUG : Creating backend with remote "TestB2:rclone-test-bemegub0gama/CopyDest" 2024/12/15 04:07:05 DEBUG : one: Need to transfer - File not found at Destination 2024/12/15 04:07:06 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2024/12/15 04:07:06 INFO : one: Copied (new) 2024/12/15 04:07:07 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/12/15 04:07:07 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2024/12/15 04:07:07 INFO : one: Copied (replaced existing) 2024/12/15 04:07:09 DEBUG : Creating backend with remote "TestB2:rclone-test-bemegub0gama/BackupDir" 2024/12/15 04:07:10 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/12/15 04:07:11 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2024/12/15 04:07:11 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/12/15 04:07:12 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2024/12/15 04:07:12 INFO : one: Copied (server-side copy) 2024/12/15 04:07:12 INFO : one: Deleted 2024/12/15 04:07:13 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2024/12/15 04:07:13 INFO : one: Copied (server-side copy) 2024/12/15 04:07:13 DEBUG : one: Destination found in --copy-dest, using server-side copy 2024/12/15 04:07:14 DEBUG : two: Need to transfer - File not found at Destination 2024/12/15 04:07:14 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2024/12/15 04:07:15 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2024/12/15 04:07:15 INFO : two: Copied (server-side copy) 2024/12/15 04:07:15 DEBUG : two: Destination found in --copy-dest, using server-side copy 2024/12/15 04:07:16 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2024/12/15 04:07:16 DEBUG : two: Unchanged skipping 2024/12/15 04:07:17 DEBUG : three: Need to transfer - File not found at Destination 2024/12/15 04:07:17 DEBUG : three: Sizes differ (src 7 vs dst 5) 2024/12/15 04:07:17 DEBUG : three: Destination not found in --copy-dest 2024/12/15 04:07:18 DEBUG : three: sha1 = 370e613335f3cf2f19b2a2d35a86402f55c659f8 OK 2024/12/15 04:07:18 INFO : three: Copied (new) --- PASS: TestCopyFileCopyDest (17.22s) === RUN TestCopyInplace run.go:180: Remote "B2 bucket rclone-test-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", 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-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", 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-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", Modify Window "1ms" 2024/12/15 04:07:21 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination 2024/12/15 04:07:21 DEBUG : TestCopyFileMaxTransfer/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2024/12/15 04:07:21 INFO : TestCopyFileMaxTransfer/file1: Copied (new) 2024/12/15 04:07:22 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination 2024/12/15 04:07:22 ERROR : TestCopyFileMaxTransfer/file2: Failed to copy: Post "https://pod-000-1117-12.backblaze.com/b2api/v1/b2_upload_file/bf822d8370adcb8b9f380e1b/c001_v0001117_t0010": max transfer limit reached as set by --max-transfer 2024/12/15 04:07:22 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination 2024/12/15 04:07:22 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination 2024/12/15 04:07:23 DEBUG : TestCopyFileMaxTransfer/file4: sha1 = 22605aeb91a69d8db4811f6a2694c8040f3aad7d OK 2024/12/15 04:07:23 INFO : TestCopyFileMaxTransfer/file4: Copied (new) --- PASS: TestCopyFileMaxTransfer (4.10s) === RUN TestDeduplicateInteractive run.go:180: Remote "B2 bucket rclone-test-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateInteractive (0.37s) === RUN TestDeduplicateSkip run.go:180: Remote "B2 bucket rclone-test-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSkip (0.37s) === RUN TestDeduplicateSizeOnly run.go:180: Remote "B2 bucket rclone-test-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", 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-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateFirst (0.37s) === RUN TestDeduplicateNewest run.go:180: Remote "B2 bucket rclone-test-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateNewest (0.37s) === RUN TestDeduplicateNewestByHash run.go:180: Remote "B2 bucket rclone-test-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", Modify Window "1ms" 2024/12/15 04:07:28 INFO : B2 bucket rclone-test-bemegub0gama: Looking for duplicate sha1 hashes using newest mode. 2024/12/15 04:07:29 NOTICE: 064c7d0f1c88c49fb1a56749e056f2698f941b59: Found 3 files with duplicate sha1 hashes 2024/12/15 04:07:29 INFO : one: Deleted 2024/12/15 04:07:29 INFO : also/one: Deleted 2024/12/15 04:07:29 NOTICE: 064c7d0f1c88c49fb1a56749e056f2698f941b59: Deleted 2 extra copies --- PASS: TestDeduplicateNewestByHash (3.81s) === RUN TestDeduplicateOldest run.go:180: Remote "B2 bucket rclone-test-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", 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-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", 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-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSmallest (0.37s) === RUN TestDeduplicateRename run.go:180: Remote "B2 bucket rclone-test-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateRename (0.36s) === RUN TestMergeDirs run.go:180: Remote "B2 bucket rclone-test-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", Modify Window "1ms" dedupe_test.go:256: Can't merge directories --- SKIP: TestMergeDirs (0.37s) === RUN TestListDirSorted run.go:180: Remote "B2 bucket rclone-test-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", Modify Window "1ms" 2024/12/15 04:07:37 DEBUG : a.txt: Excluded (Size Filter) 2024/12/15 04:07:37 DEBUG : a.txt: Excluded 2024/12/15 04:07:37 DEBUG : sub dir/hello world: Excluded (Size Filter) 2024/12/15 04:07:37 DEBUG : sub dir/hello world: Excluded 2024/12/15 04:07:37 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2024/12/15 04:07:37 DEBUG : sub dir/hello world2: Excluded 2024/12/15 04:07:37 DEBUG : sub dir/hello world: Excluded (Size Filter) 2024/12/15 04:07:37 DEBUG : sub dir/hello world: Excluded 2024/12/15 04:07:37 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2024/12/15 04:07:37 DEBUG : sub dir/hello world2: Excluded 2024/12/15 04:07:38 DEBUG : sub dir/ignore dir: Excluded 2024/12/15 04:07:38 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSorted (8.94s) === RUN TestListJSON run.go:180: Remote "B2 bucket rclone-test-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", 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 2024/12/15 04:07:44 ERROR : file1: Failed to read hash: hash type not supported === RUN TestListJSON/Metadata --- PASS: TestListJSON (3.88s) --- PASS: TestListJSON/Default (0.18s) --- PASS: TestListJSON/FilesOnly (0.18s) --- PASS: TestListJSON/DirsOnly (0.22s) --- PASS: TestListJSON/Recurse (0.18s) --- PASS: TestListJSON/SubDir (0.18s) --- PASS: TestListJSON/NoModTime (0.18s) --- PASS: TestListJSON/NoMimeType (0.20s) --- 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-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", 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 2024/12/15 04:07:49 DEBUG : Creating backend with remote "TestB2:rclone-test-bemegub0gama/notfound" --- PASS: TestStatJSON (7.01s) --- PASS: TestStatJSON/Root (0.18s) --- PASS: TestStatJSON/RootFilesOnly (0.00s) --- PASS: TestStatJSON/RootDirsOnly (0.18s) --- PASS: TestStatJSON/Dir (0.72s) --- PASS: TestStatJSON/DirWithTrailingSlash (0.18s) --- PASS: TestStatJSON/File (0.18s) --- PASS: TestStatJSON/NotFound (0.37s) --- PASS: TestStatJSON/DirFilesOnly (0.18s) --- PASS: TestStatJSON/FileFilesOnly (0.18s) --- PASS: TestStatJSON/NotFoundFilesOnly (0.18s) --- PASS: TestStatJSON/DirDirsOnly (0.18s) --- PASS: TestStatJSON/FileDirsOnly (0.18s) --- PASS: TestStatJSON/NotFoundDirsOnly (0.18s) --- PASS: TestStatJSON/RootNotFound (1.87s) === RUN TestMkdir run.go:180: Remote "B2 bucket rclone-test-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", Modify Window "1ms" 2024/12/15 04:07:52 DEBUG : B2 bucket rclone-test-bemegub0gama: Making directory 2024/12/15 04:07:52 DEBUG : B2 bucket rclone-test-bemegub0gama: Making directory --- PASS: TestMkdir (0.54s) === RUN TestLsd run.go:180: Remote "B2 bucket rclone-test-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", Modify Window "1ms" --- PASS: TestLsd (1.96s) === RUN TestLs run.go:180: Remote "B2 bucket rclone-test-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", Modify Window "1ms" --- PASS: TestLs (2.23s) === RUN TestLsWithFilesFrom run.go:180: Remote "B2 bucket rclone-test-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", Modify Window "1ms" 2024/12/15 04:07:58 DEBUG : empty space: Excluded (FilesFrom Filter) 2024/12/15 04:07:58 DEBUG : empty space: Excluded --- PASS: TestLsWithFilesFrom (2.66s) === RUN TestLsLong run.go:180: Remote "B2 bucket rclone-test-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", Modify Window "1ms" --- PASS: TestLsLong (2.28s) === RUN TestHashSums run.go:180: Remote "B2 bucket rclone-test-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", Modify Window "1ms" === RUN TestHashSums/Sha1 === RUN TestHashSums/Sha1Download --- PASS: TestHashSums (2.91s) --- PASS: TestHashSums/Sha1 (0.18s) --- PASS: TestHashSums/Sha1Download (0.55s) === RUN TestHashSumsWithErrors 2024/12/15 04:08:04 DEBUG : Creating backend with remote ":memory:" 2024/12/15 04:08:04 ERROR : file1: hash unsupported: hash type not supported --- PASS: TestHashSumsWithErrors (0.00s) === RUN TestHashStream 2024/12/15 04:08:04 DEBUG : Creating md5 hash of 0 bytes read from input stream 2024/12/15 04:08:04 DEBUG : Creating md5 hash of 0 bytes read from input stream 2024/12/15 04:08:04 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2024/12/15 04:08:04 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2024/12/15 04:08:04 DEBUG : Creating md5 hash of 12 bytes read from input stream 2024/12/15 04:08:04 DEBUG : Creating md5 hash of 12 bytes read from input stream 2024/12/15 04:08:04 DEBUG : Creating sha1 hash of 12 bytes read from input stream 2024/12/15 04:08:04 DEBUG : Creating sha1 hash of 12 bytes read from input stream --- PASS: TestHashStream (0.00s) === RUN TestSuffixName --- PASS: TestSuffixName (0.00s) === RUN TestCount run.go:180: Remote "B2 bucket rclone-test-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", Modify Window "1ms" --- PASS: TestCount (3.36s) === RUN TestDelete run.go:180: Remote "B2 bucket rclone-test-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", Modify Window "1ms" 2024/12/15 04:08:10 DEBUG : Waiting for deletions to finish 2024/12/15 04:08:10 DEBUG : large: Excluded (Size Filter) 2024/12/15 04:08:10 INFO : medium: Deleted 2024/12/15 04:08:10 INFO : small: Deleted --- PASS: TestDelete (3.53s) === RUN TestMaxDelete run.go:180: Remote "B2 bucket rclone-test-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", Modify Window "1ms" 2024/12/15 04:08:13 DEBUG : Waiting for deletions to finish 2024/12/15 04:08:13 ERROR : small: Got fatal error on delete: --max-delete threshold reached 2024/12/15 04:08:14 INFO : large: Deleted 2024/12/15 04:08:14 INFO : medium: Deleted --- PASS: TestMaxDelete (3.79s) === RUN TestMaxDeleteSizeLargeFile run.go:180: Remote "B2 bucket rclone-test-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", Modify Window "1ms" 2024/12/15 04:08:17 DEBUG : Waiting for deletions to finish 2024/12/15 04:08:17 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached 2024/12/15 04:08:17 INFO : medium: Deleted 2024/12/15 04:08:17 INFO : small: Deleted --- PASS: TestMaxDeleteSizeLargeFile (3.68s) === RUN TestMaxDeleteSize run.go:180: Remote "B2 bucket rclone-test-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", Modify Window "1ms" 2024/12/15 04:08:21 DEBUG : Waiting for deletions to finish 2024/12/15 04:08:21 ERROR : small: Got fatal error on delete: --max-delete-size threshold reached 2024/12/15 04:08:21 INFO : large: Deleted 2024/12/15 04:08:21 INFO : medium: Deleted --- PASS: TestMaxDeleteSize (3.52s) === RUN TestReadFile run.go:180: Remote "B2 bucket rclone-test-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", Modify Window "1ms" --- PASS: TestReadFile (2.02s) === RUN TestRetry 2024/12/15 04:08:24 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 1/5 2024/12/15 04:08:24 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 2/5 2024/12/15 04:08:24 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG 2024/12/15 04:08:24 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG 2024/12/15 04:08:24 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG 2024/12/15 04:08:24 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG 2024/12/15 04:08:24 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-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", Modify Window "1ms" --- PASS: TestCat (4.71s) === RUN TestPurge 2024/12/15 04:08:29 DEBUG : Creating backend with remote "TestB2:rclone-test-xewubig8xupu" 2024/12/15 04:08:29 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/12/15 04:08:29 DEBUG : Creating backend with remote "/tmp/rclone3392604745" run.go:180: Remote "B2 bucket rclone-test-xewubig8xupu", Local "Local file system at /tmp/rclone3392604745", Modify Window "1ms" 2024/12/15 04:08:31 DEBUG : A2: Making directory 2024/12/15 04:08:31 DEBUG : A1/B2: Making directory 2024/12/15 04:08:31 DEBUG : A1/B2/C2: Making directory 2024/12/15 04:08:31 DEBUG : A1/B1/C3: Making directory 2024/12/15 04:08:31 DEBUG : A3: Making directory 2024/12/15 04:08:31 DEBUG : A3/B3: Making directory 2024/12/15 04:08:31 DEBUG : 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" 2024/12/15 04:08:32 INFO : B2 bucket rclone-test-xewubig8xupu: cleaning bucket "rclone-test-xewubig8xupu" of all files 2024/12/15 04:08:32 DEBUG : A1/B1/C1/one: Deleting (id "4_z9fb2ed33800dcb8b9f380e1b_f1002a636049602b8_d20241215_m040831_c001_v0001182_t0059_u01734235711543") 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" 2024/12/15 04:08:32 INFO : B2 bucket rclone-test-xewubig8xupu: cleaning bucket "rclone-test-xewubig8xupu" of all files 2024/12/15 04:08:32 DEBUG : A1/two: Deleting (id "4_z9fb2ed33800dcb8b9f380e1b_f1002a636049602be_d20241215_m040831_c001_v0001182_t0058_u01734235711794") 2024/12/15 04:08:33 DEBUG : B2 bucket rclone-test-xewubig8xupu: Purge remote 2024/12/15 04:08:33 INFO : B2 bucket rclone-test-xewubig8xupu: cleaning bucket "rclone-test-xewubig8xupu" of all files 2024/12/15 04:08:34 NOTICE: purge failed: directory not found --- PASS: TestPurge (4.72s) === RUN TestRmdirsNoLeaveRoot run.go:180: Remote "B2 bucket rclone-test-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", Modify Window "1ms" 2024/12/15 04:08:35 DEBUG : A2: Making directory 2024/12/15 04:08:35 DEBUG : A1/B2: Making directory 2024/12/15 04:08:35 DEBUG : A1/B2/C2: Making directory 2024/12/15 04:08:35 DEBUG : A1/B1/C3: Making directory 2024/12/15 04:08:35 DEBUG : A3: Making directory 2024/12/15 04:08:35 DEBUG : A3/B3: Making directory 2024/12/15 04:08:35 DEBUG : 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" 2024/12/15 04:08:36 DEBUG : removing 1 level 3 directories 2024/12/15 04:08:36 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" 2024/12/15 04:08:39 DEBUG : removing 1 level 0 directories 2024/12/15 04:08:39 INFO : B2 bucket rclone-test-bemegub0gama: Removing directory 2024/12/15 04:08:39 ERROR : : Failed to rmdir: failed to delete bucket: Cannot delete non-empty bucket (400 cannot_delete_non_empty_bucket) operations_test.go:728: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:728 Error: Received unexpected error: failed to remove directories: failed to delete bucket: Cannot delete non-empty bucket (400 cannot_delete_non_empty_bucket) Test: TestRmdirsNoLeaveRoot --- FAIL: TestRmdirsNoLeaveRoot (5.68s) === RUN TestRmdirsLeaveRoot run.go:180: Remote "B2 bucket rclone-test-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", Modify Window "1ms" 2024/12/15 04:08:39 DEBUG : A1: Making directory 2024/12/15 04:08:39 DEBUG : A1/B1: Making directory 2024/12/15 04:08:39 DEBUG : 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.94s) === RUN TestRmdirsWithFilter run.go:180: Remote "B2 bucket rclone-test-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", Modify Window "1ms" 2024/12/15 04:08:40 DEBUG : A1: Making directory 2024/12/15 04:08:40 DEBUG : A1/B1: Making directory 2024/12/15 04:08:40 DEBUG : 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.95s) === RUN TestCopyURL run.go:180: Remote "B2 bucket rclone-test-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", Modify Window "1ms" 2024/12/15 04:08:42 DEBUG : filename.txt: File name found in url 2024/12/15 04:08:43 DEBUG : headerfilename.txt: filename found in Content-Disposition header. --- PASS: TestCopyURL (4.25s) === RUN TestCopyURLToWriter --- PASS: TestCopyURLToWriter (0.00s) === RUN TestMoveFile run.go:180: Remote "B2 bucket rclone-test-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", Modify Window "1ms" 2024/12/15 04:08:46 DEBUG : file1: Need to transfer - File not found at Destination 2024/12/15 04:08:46 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2024/12/15 04:08:46 INFO : file1: Copied (new) to: sub/file2 2024/12/15 04:08:46 INFO : file1: Deleted 2024/12/15 04:08:47 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/12/15 04:08:47 DEBUG : file1: Unchanged skipping 2024/12/15 04:08:47 INFO : file1: Deleted 2024/12/15 04:08:47 DEBUG : B2 bucket rclone-test-bemegub0gama: don't need to copy/move sub/file2, it is already at target location --- PASS: TestMoveFile (2.16s) === RUN TestMoveFileWithIgnoreExisting run.go:180: Remote "B2 bucket rclone-test-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", Modify Window "1ms" 2024/12/15 04:08:48 DEBUG : file1: Need to transfer - File not found at Destination 2024/12/15 04:08:49 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2024/12/15 04:08:49 INFO : file1: Copied (new) 2024/12/15 04:08:49 INFO : file1: Deleted 2024/12/15 04:08:49 DEBUG : file1: Destination exists, skipping 2024/12/15 04:08:49 DEBUG : file1: Not removing source file as destination file exists and --ignore-existing is set --- PASS: TestMoveFileWithIgnoreExisting (2.39s) === RUN TestCaseInsensitiveMoveFile run.go:180: Remote "B2 bucket rclone-test-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFile (0.37s) === RUN TestCaseInsensitiveMoveFileDryRun run.go:180: Remote "B2 bucket rclone-test-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFileDryRun (0.38s) === RUN TestMoveFileBackupDir run.go:180: Remote "B2 bucket rclone-test-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", Modify Window "1ms" 2024/12/15 04:08:52 DEBUG : Creating backend with remote "TestB2:rclone-test-bemegub0gama/backup" 2024/12/15 04:08:52 DEBUG : Config file has changed externally - reloading 2024/12/15 04:08:53 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18) 2024/12/15 04:08:54 DEBUG : dst/file1: sha1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK 2024/12/15 04:08:54 INFO : dst/file1: Copied (server-side copy) 2024/12/15 04:08:54 INFO : dst/file1: Deleted 2024/12/15 04:08:55 DEBUG : dst/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2024/12/15 04:08:55 INFO : dst/file1: Copied (new) 2024/12/15 04:08:55 INFO : dst/file1: Deleted --- PASS: TestMoveFileBackupDir (5.74s) === 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-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", Modify Window "1ms" 2024/12/15 04:09:07 DEBUG : A1/B2: Making directory 2024/12/15 04:09:07 DEBUG : A1/B1/C3: Making directory fstest.go:244: Filtering empty directory "A1/B2" fstest.go:244: Filtering empty directory "A1/B1/C3" 2024/12/15 04:09:09 DEBUG : A1/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2024/12/15 04:09:09 INFO : A1/B1/C1/four: Copied (server-side copy) to: A2/B1/C1/four 2024/12/15 04:09:09 INFO : A1/B1/C1/four: Deleted 2024/12/15 04:09:09 DEBUG : A1/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2024/12/15 04:09:09 INFO : A1/two: Copied (server-side copy) to: A2/two 2024/12/15 04:09:09 DEBUG : A1/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2024/12/15 04:09:09 INFO : A1/B1/three: Copied (server-side copy) to: A2/B1/three 2024/12/15 04:09:09 DEBUG : A1/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2024/12/15 04:09:09 INFO : A1/one: Copied (server-side copy) to: A2/one 2024/12/15 04:09:09 INFO : A1/two: Deleted 2024/12/15 04:09:09 INFO : A1/B1/three: Deleted 2024/12/15 04:09:09 DEBUG : A1/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2024/12/15 04:09:09 INFO : A1/B1/C2/five: Copied (server-side copy) to: A2/B1/C2/five 2024/12/15 04:09:09 INFO : A1/one: Deleted 2024/12/15 04:09:10 INFO : A1/B1/C2/five: Deleted fstest.go:244: Filtering empty directory "A2/B2" fstest.go:244: Filtering empty directory "A2/B1/C3" 2024/12/15 04:09:10 DEBUG : A2/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2024/12/15 04:09:10 INFO : A2/B1/C1/four: Copied (server-side copy) to: A3/B1/C1/four 2024/12/15 04:09:10 DEBUG : A2/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2024/12/15 04:09:10 INFO : A2/B1/three: Copied (server-side copy) to: A3/B1/three 2024/12/15 04:09:10 DEBUG : A2/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2024/12/15 04:09:10 INFO : A2/B1/C2/five: Copied (server-side copy) to: A3/B1/C2/five 2024/12/15 04:09:10 DEBUG : A2/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2024/12/15 04:09:10 INFO : A2/two: Copied (server-side copy) to: A3/two 2024/12/15 04:09:10 DEBUG : A2/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2024/12/15 04:09:10 INFO : A2/one: Copied (server-side copy) to: A3/one 2024/12/15 04:09:11 INFO : A2/B1/C1/four: Deleted 2024/12/15 04:09:11 INFO : A2/B1/three: Deleted 2024/12/15 04:09:11 INFO : A2/B1/C2/five: Deleted 2024/12/15 04:09:11 INFO : A2/two: Deleted 2024/12/15 04:09:11 INFO : A2/one: Deleted fstest.go:244: Filtering empty directory "A3/B2" fstest.go:244: Filtering empty directory "A3/B1/C3" 2024/12/15 04:09:11 INFO : B2 bucket rclone-test-bemegub0gama: Can't DirMove - falling back to file moves: can't move directory - incompatible remotes 2024/12/15 04:09:11 DEBUG : A3/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2024/12/15 04:09:11 INFO : A3/B1/three: Copied (server-side copy) to: A4/B1/three 2024/12/15 04:09:12 DEBUG : A3/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2024/12/15 04:09:12 INFO : A3/two: Copied (server-side copy) to: A4/two 2024/12/15 04:09:12 DEBUG : A3/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2024/12/15 04:09:12 INFO : A3/B1/C2/five: Copied (server-side copy) to: A4/B1/C2/five 2024/12/15 04:09:12 DEBUG : A3/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2024/12/15 04:09:12 INFO : A3/B1/C1/four: Copied (server-side copy) to: A4/B1/C1/four 2024/12/15 04:09:12 INFO : A3/B1/three: Deleted 2024/12/15 04:09:12 INFO : A3/two: Deleted 2024/12/15 04:09:12 INFO : A3/B1/C2/five: Deleted 2024/12/15 04:09:12 DEBUG : A3/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2024/12/15 04:09:12 INFO : A3/one: Copied (server-side copy) to: A4/one 2024/12/15 04:09:12 INFO : A3/B1/C1/four: Deleted 2024/12/15 04:09:12 INFO : A3/one: Deleted fstest.go:244: Filtering empty directory "A4/B2" fstest.go:244: Filtering empty directory "A4/B1/C3" --- PASS: TestDirMove (17.10s) === RUN TestGetFsInfo run.go:180: Remote "B2 bucket rclone-test-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", Modify Window "1ms" 2024/12/15 04:09:13 DEBUG : Config file has changed externally - reloading --- PASS: TestGetFsInfo (0.38s) === RUN TestRcat === RUN TestRcat/withChecksum=false,ignoreChecksum=false run.go:180: Remote "B2 bucket rclone-test-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", Modify Window "1ms" 2024/12/15 04:09:14 DEBUG : B2 bucket rclone-test-bemegub0gama: File to upload is small (34 bytes), uploading instead of streaming 2024/12/15 04:09:16 DEBUG : no_checksum_small_file_from_pipe: sha1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK 2024/12/15 04:09:16 DEBUG : no_checksum_small_file_from_pipe: Size and sha1 of src and dst objects identical 2024/12/15 04:09:16 DEBUG : no_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2024/12/15 04:09:17 DEBUG : no_checksum_big_file_from_pipe: sha1 = d0080f73aa8b494570ad63df1beb25c01ea9eea2 OK 2024/12/15 04:09:17 DEBUG : no_checksum_big_file_from_pipe: Size and sha1 of src and dst objects identical === RUN TestRcat/withChecksum=true,ignoreChecksum=false run.go:180: Remote "B2 bucket rclone-test-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", Modify Window "1ms" 2024/12/15 04:09:18 DEBUG : B2 bucket rclone-test-bemegub0gama: File to upload is small (34 bytes), uploading instead of streaming 2024/12/15 04:09:19 DEBUG : with_checksum_small_file_from_pipe: sha1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK 2024/12/15 04:09:19 DEBUG : with_checksum_small_file_from_pipe: Size and sha1 of src and dst objects identical 2024/12/15 04:09:19 DEBUG : with_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2024/12/15 04:09:19 DEBUG : with_checksum_big_file_from_pipe: Clearing upload URL because of error: CPU too busy (503 service_unavailable) 2024/12/15 04:09:19 DEBUG : pacer: low level retry 1/1 (error CPU too busy (503 service_unavailable)) 2024/12/15 04:09:19 DEBUG : pacer: Rate limited, increasing sleep to 1s operations_test.go:1512: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1512 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1523 Error: Received unexpected error: CPU too busy (503 service_unavailable) Test: TestRcat/withChecksum=true,ignoreChecksum=false 2024/12/15 04:09:19 DEBUG : pacer: Reducing sleep to 500ms 2024/12/15 04:09:20 DEBUG : pacer: Reducing sleep to 250ms 2024/12/15 04:09:21 DEBUG : pacer: Reducing sleep to 125ms === RUN TestRcat/withChecksum=false,ignoreChecksum=true run.go:180: Remote "B2 bucket rclone-test-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", Modify Window "1ms" 2024/12/15 04:09:21 DEBUG : pacer: Reducing sleep to 62.5ms 2024/12/15 04:09:21 DEBUG : B2 bucket rclone-test-bemegub0gama: File to upload is small (34 bytes), uploading instead of streaming 2024/12/15 04:09:21 DEBUG : pacer: Reducing sleep to 31.25ms 2024/12/15 04:09:22 DEBUG : pacer: Reducing sleep to 15.625ms 2024/12/15 04:09:22 DEBUG : ignore_checksum_small_file_from_pipe: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/12/15 04:09:22 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2024/12/15 04:09:22 DEBUG : pacer: Reducing sleep to 10ms 2024/12/15 04:09:22 DEBUG : ignore_checksum_big_file_from_pipe: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) === RUN TestRcat/withChecksum=true,ignoreChecksum=true run.go:180: Remote "B2 bucket rclone-test-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", Modify Window "1ms" 2024/12/15 04:09:24 DEBUG : B2 bucket rclone-test-bemegub0gama: File to upload is small (34 bytes), uploading instead of streaming 2024/12/15 04:09:24 DEBUG : ignore_checksum_small_file_from_pipe: Src hash empty - aborting Dst hash check 2024/12/15 04:09:24 DEBUG : ignore_checksum_small_file_from_pipe: Size of src and dst objects identical 2024/12/15 04:09:24 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2024/12/15 04:09:24 DEBUG : ignore_checksum_big_file_from_pipe: Src hash empty - aborting Dst hash check 2024/12/15 04:09:24 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical --- FAIL: TestRcat (11.55s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (4.07s) --- FAIL: TestRcat/withChecksum=true,ignoreChecksum=false (2.68s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (2.69s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (2.11s) === RUN TestRcatMetadata run.go:180: Remote "B2 bucket rclone-test-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", 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-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", Modify Window "1ms" 2024/12/15 04:09:26 DEBUG : B2 bucket rclone-test-bemegub0gama: File to upload is small (60 bytes), uploading instead of streaming 2024/12/15 04:09:26 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2024/12/15 04:09:26 DEBUG : potato2: Size and sha1 of src and dst objects identical --- PASS: TestRcatSize (1.37s) === RUN TestRcatSizeMetadata run.go:180: Remote "B2 bucket rclone-test-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", Modify Window "1ms" operations_test.go:1613: Skipping as destination doesn't support user metadata --- SKIP: TestRcatSizeMetadata (0.40s) === RUN TestTouchDir run.go:180: Remote "B2 bucket rclone-test-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", Modify Window "1ms" 2024/12/15 04:09:29 DEBUG : B2 bucket rclone-test-bemegub0gama: Touching "empty space" 2024/12/15 04:09:30 DEBUG : B2 bucket rclone-test-bemegub0gama: Touching "potato2" 2024/12/15 04:09:30 DEBUG : B2 bucket rclone-test-bemegub0gama: Touching "sub dir/potato3" --- PASS: TestTouchDir (4.26s) === RUN TestMkdirMetadata run.go:180: Remote "B2 bucket rclone-test-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", Modify Window "1ms" operations_test.go:1703: Skipping test as remote does not support MkdirMetadata --- SKIP: TestMkdirMetadata (0.40s) === RUN TestMkdirModTime run.go:180: Remote "B2 bucket rclone-test-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", Modify Window "1ms" operations_test.go:1722: Skipping test as remote does not support DirSetModTime or MkdirMetadata --- SKIP: TestMkdirModTime (0.41s) === RUN TestCopyDirMetadata run.go:180: Remote "B2 bucket rclone-test-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", Modify Window "1ms" operations_test.go:1743: Skipping test as remote does not support WriteDirMetadata or MkdirMetadata --- SKIP: TestCopyDirMetadata (0.39s) === RUN TestSetDirModTime run.go:180: Remote "B2 bucket rclone-test-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", 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-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", Modify Window "1ms" operations_test.go:1823: Skipping test as remote does not support WriteDirMetadata or MkdirMetadata --- SKIP: TestDirsEqual (0.38s) === RUN TestRemoveExisting run.go:180: Remote "B2 bucket rclone-test-bemegub0gama", Local "Local file system at /tmp/rclone2684689852", Modify Window "1ms" operations_test.go:1892: Skipping as remote can't Move --- SKIP: TestRemoveExisting (0.39s) === 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 2024/12/15 04:09:34 DEBUG : B2 bucket rclone-test-bemegub0gama: Purge remote 2024/12/15 04:09:34 INFO : B2 bucket rclone-test-bemegub0gama: cleaning bucket "rclone-test-bemegub0gama" of all files 2024/12/15 04:09:35 DEBUG : A1/B1/C1/four: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f4191a778bf23a344_d20241215_m040909_c001_v7007000_t0000_u01734235749466") 2024/12/15 04:09:35 DEBUG : A1/B1/C1/four: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30fa4_d20241215_m040905_c001_v0001117_t0041_u01734235745469") 2024/12/15 04:09:35 DEBUG : A1/B1/C1/one: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f4191a778bf23a2d0_d20241215_m040838_c001_v7007000_t0000_u01734235718477") 2024/12/15 04:09:35 DEBUG : A1/B1/C1/one: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30ef8_d20241215_m040834_c001_v0001117_t0029_u01734235714470") 2024/12/15 04:09:35 DEBUG : A1/B1/C2/five: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f407af66b0183778e_d20241215_m040909_c001_v7007000_t0000_u01734235749918") 2024/12/15 04:09:35 DEBUG : A1/B1/C2/five: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30fb0_d20241215_m040906_c001_v0001117_t0023_u01734235746663") 2024/12/15 04:09:35 DEBUG : A1/B1/three: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f408340799480ec0e_d20241215_m040909_c001_v7007000_t0000_u01734235749692") 2024/12/15 04:09:35 DEBUG : A1/B1/three: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30fa2_d20241215_m040904_c001_v0001117_t0055_u01734235744183") 2024/12/15 04:09:35 DEBUG : A1/one: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f4184e9ccd320ddb1_d20241215_m040909_c001_v7007000_t0000_u01734235749744") 2024/12/15 04:09:35 DEBUG : A1/one: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30f88_d20241215_m040856_c001_v0001117_t0058_u01734235736979") 2024/12/15 04:09:35 DEBUG : A1/two: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f400b9e616b2fa646_d20241215_m040909_c001_v7007000_t0000_u01734235749676") 2024/12/15 04:09:35 DEBUG : A1/two: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30fa0_d20241215_m040903_c001_v0001117_t0005_u01734235743387") 2024/12/15 04:09:35 DEBUG : A1/two: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f4191a778bf23a2d4_d20241215_m040838_c001_v7007000_t0000_u01734235718843") 2024/12/15 04:09:35 DEBUG : A1/two: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30efc_d20241215_m040835_c001_v0001117_t0000_u01734235715295") 2024/12/15 04:09:35 DEBUG : A2/B1/C1/four: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f407af66b01837792_d20241215_m040910_c001_v7007000_t0000_u01734235750932") 2024/12/15 04:09:36 DEBUG : A2/B1/C1/four: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f102440a5e3377141_d20241215_m040909_c001_v0001033_t0026_u01734235749042") 2024/12/15 04:09:36 DEBUG : A2/B1/C2/five: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f4184e9ccd320ddb4_d20241215_m040911_c001_v7007000_t0000_u01734235751025") 2024/12/15 04:09:36 DEBUG : A2/B1/C2/five: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f11134273da5299d2_d20241215_m040909_c001_v0001137_t0057_u01734235749435") 2024/12/15 04:09:36 DEBUG : A2/B1/three: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f408340799480ec12_d20241215_m040910_c001_v7007000_t0000_u01734235750994") 2024/12/15 04:09:36 DEBUG : A2/B1/three: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f118e6ead9b09bd16_d20241215_m040909_c001_v0001182_t0022_u01734235749483") 2024/12/15 04:09:36 DEBUG : A2/one: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f400b9e616b2fa648_d20241215_m040911_c001_v7007000_t0000_u01734235751081") 2024/12/15 04:09:36 DEBUG : A2/one: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f11385d1e5528d3b2_d20241215_m040909_c001_v0001182_t0003_u01734235749502") 2024/12/15 04:09:36 DEBUG : A2/two: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f4191a778bf23a346_d20241215_m040911_c001_v7007000_t0000_u01734235751059") 2024/12/15 04:09:36 DEBUG : A2/two: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f1017cf900de7c8f0_d20241215_m040909_c001_v0001179_t0044_u01734235749459") 2024/12/15 04:09:36 DEBUG : A3/B1/C1/four: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f4184e9ccd320ddb5_d20241215_m040912_c001_v7007000_t0000_u01734235752251") 2024/12/15 04:09:36 DEBUG : A3/B1/C1/four: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f118e6ead9b09bd4a_d20241215_m040910_c001_v0001182_t0049_u01734235750710") 2024/12/15 04:09:36 DEBUG : A3/B1/C2/five: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f408340799480ec13_d20241215_m040912_c001_v7007000_t0000_u01734235752185") 2024/12/15 04:09:36 DEBUG : A3/B1/C2/five: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f109089eb63af6355_d20241215_m040910_c001_v0001183_t0028_u01734235750808") 2024/12/15 04:09:36 DEBUG : A3/B1/three: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f400b9e616b2fa64c_d20241215_m040912_c001_v7007000_t0000_u01734235752067") 2024/12/15 04:09:36 DEBUG : A3/B1/three: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f115423aa0f0f2194_d20241215_m040910_c001_v0001183_t0025_u01734235750782") 2024/12/15 04:09:36 DEBUG : A3/one: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f4191a778bf23a348_d20241215_m040912_c001_v7007000_t0000_u01734235752376") 2024/12/15 04:09:36 DEBUG : A3/one: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f108459a0daaa1d5b_d20241215_m040910_c001_v0001183_t0025_u01734235750852") 2024/12/15 04:09:36 DEBUG : A3/two: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f407af66b01837796_d20241215_m040912_c001_v7007000_t0000_u01734235752095") 2024/12/15 04:09:36 DEBUG : A3/two: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f10333e892542020a_d20241215_m040910_c001_v0001183_t0038_u01734235750839") 2024/12/15 04:09:37 DEBUG : A4/B1/C1/four: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f4191a778bf23a34a_d20241215_m040912_c001_v7007000_t0000_u01734235752951") 2024/12/15 04:09:37 DEBUG : A4/B1/C1/four: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f115cbe75985fb96e_d20241215_m040911_c001_v0001182_t0015_u01734235751947") 2024/12/15 04:09:37 DEBUG : A4/B1/C2/five: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f4191a778bf23a34c_d20241215_m040913_c001_v7007000_t0000_u01734235753161") 2024/12/15 04:09:37 DEBUG : A4/B1/C2/five: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f1114d83728a2f642_d20241215_m040911_c001_v0001131_t0001_u01734235751906") 2024/12/15 04:09:37 DEBUG : A4/B1/three: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f4191a778bf23a34d_d20241215_m040913_c001_v7007000_t0000_u01734235753343") 2024/12/15 04:09:37 DEBUG : A4/B1/three: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f104fcd0225c2f086_d20241215_m040911_c001_v0001183_t0019_u01734235751856") 2024/12/15 04:09:37 DEBUG : A4/one: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f4191a778bf23a34f_d20241215_m040913_c001_v7007000_t0000_u01734235753525") 2024/12/15 04:09:37 DEBUG : A4/one: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f1137c577fb56eb68_d20241215_m040911_c001_v0001102_t0011_u01734235751870") 2024/12/15 04:09:37 DEBUG : A4/two: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f4191a778bf23a350_d20241215_m040913_c001_v7007000_t0000_u01734235753708") 2024/12/15 04:09:37 DEBUG : A4/two: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f10992d990dfb301a_d20241215_m040911_c001_v0001181_t0041_u01734235751895") 2024/12/15 04:09:37 DEBUG : BackupDir/one: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f403661e5c9c5ef1e_d20241215_m040718_c001_v7007000_t0000_u01734235638550") 2024/12/15 04:09:37 DEBUG : BackupDir/one: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f119b0f3022f29132_d20241215_m040712_c001_v0001183_t0050_u01734235632240") 2024/12/15 04:09:37 DEBUG : CompareDest/one: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f403661e5c9c5eede_d20241215_m040701_c001_v7007000_t0000_u01734235621907") 2024/12/15 04:09:37 DEBUG : CompareDest/one: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30cca_d20241215_m040656_c001_v0001117_t0001_u01734235616406") 2024/12/15 04:09:37 DEBUG : CompareDest/two: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f403661e5c9c5eee0_d20241215_m040702_c001_v7007000_t0000_u01734235622095") 2024/12/15 04:09:37 DEBUG : CompareDest/two: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30cd2_d20241215_m040657_c001_v0001117_t0030_u01734235617759") 2024/12/15 04:09:37 DEBUG : CopyDest/one: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f403661e5c9c5ef1f_d20241215_m040718_c001_v7007000_t0000_u01734235638745") 2024/12/15 04:09:37 DEBUG : CopyDest/one: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30d0e_d20241215_m040709_c001_v0001117_t0056_u01734235629017") 2024/12/15 04:09:38 DEBUG : CopyDest/three: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f403661e5c9c5ef21_d20241215_m040718_c001_v7007000_t0000_u01734235638927") 2024/12/15 04:09:38 DEBUG : CopyDest/three: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30d2e_d20241215_m040716_c001_v0001117_t0005_u01734235636371") 2024/12/15 04:09:38 DEBUG : CopyDest/two: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f403661e5c9c5ef22_d20241215_m040719_c001_v7007000_t0000_u01734235639107") 2024/12/15 04:09:38 DEBUG : CopyDest/two: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30d1e_d20241215_m040713_c001_v0001117_t0001_u01734235633829") 2024/12/15 04:09:38 DEBUG : ReadFile: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f4191a778bf23a2b4_d20241215_m040823_c001_v7007000_t0000_u01734235703884") 2024/12/15 04:09:38 DEBUG : ReadFile: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30ec6_d20241215_m040822_c001_v0001117_t0038_u01734235702610") 2024/12/15 04:09:38 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f403661e5c9c5ef2b_d20241215_m040724_c001_v7007000_t0000_u01734235644304") 2024/12/15 04:09:38 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30d4e_d20241215_m040721_c001_v0001117_t0023_u01734235641299") 2024/12/15 04:09:38 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f403661e5c9c5ef2d_d20241215_m040724_c001_v7007000_t0000_u01734235644485") 2024/12/15 04:09:38 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30d56_d20241215_m040723_c001_v0001117_t0030_u01734235643342") 2024/12/15 04:09:38 DEBUG : a.txt: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f400b9e616b2fa529_d20241215_m040739_c001_v7007000_t0000_u01734235659813") 2024/12/15 04:09:38 DEBUG : a.txt: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30d9e_d20241215_m040732_c001_v0001117_t0033_u01734235652313") 2024/12/15 04:09:38 DEBUG : also/one: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f403661e5c9c5ef32_d20241215_m040729_c001_v7007000_t0000_u01734235649383") 2024/12/15 04:09:38 DEBUG : also/one: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30d7a_d20241215_m040727_c001_v0001117_t0015_u01734235647097") 2024/12/15 04:09:38 DEBUG : another: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f403661e5c9c5ef33_d20241215_m040729_c001_v7007000_t0000_u01734235649931") 2024/12/15 04:09:38 DEBUG : another: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30d82_d20241215_m040727_c001_v0001117_t0045_u01734235647687") 2024/12/15 04:09:38 DEBUG : backup/dst/file1: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f4191a778bf23a2fd_d20241215_m040856_c001_v7007000_t0000_u01734235736423") 2024/12/15 04:09:38 DEBUG : backup/dst/file1: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f107b1fda47c34b2f_d20241215_m040854_c001_v0001136_t0047_u01734235734480") 2024/12/15 04:09:38 DEBUG : backup/dst/file1: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f403661e5c9c5eebd_d20241215_m040649_c001_v7007000_t0000_u01734235609231") 2024/12/15 04:09:38 DEBUG : backup/dst/file1: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112b283fd8bd8466_d20241215_m040647_c001_v0001157_t0025_u01734235607783") 2024/12/15 04:09:38 DEBUG : data/banana: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f417797dfc935939b_d20241215_m040552_c001_v7007000_t0000_u01734235552640") 2024/12/15 04:09:39 DEBUG : data/banana: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30ba9_d20241215_m040549_c001_v0001117_t0042_u01734235549467") 2024/12/15 04:09:39 DEBUG : data/banana: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30b71_d20241215_m040536_c001_v0001117_t0023_u01734235536651") 2024/12/15 04:09:39 DEBUG : data/potato: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f417797dfc93593a2_d20241215_m040552_c001_v7007000_t0000_u01734235552824") 2024/12/15 04:09:39 DEBUG : data/potato: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30bb3_d20241215_m040550_c001_v0001117_t0016_u01734235550396") 2024/12/15 04:09:39 DEBUG : data/potato: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30b7f_d20241215_m040540_c001_v0001117_t0051_u01734235540356") 2024/12/15 04:09:39 DEBUG : dst/file1: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f4191a778bf23a2fe_d20241215_m040856_c001_v7007000_t0000_u01734235736610") 2024/12/15 04:09:39 DEBUG : dst/file1: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30f78_d20241215_m040854_c001_v0001117_t0027_u01734235734983") 2024/12/15 04:09:39 DEBUG : dst/file1: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f4191a778bf23a2f8_d20241215_m040854_c001_v7007000_t0000_u01734235734774") 2024/12/15 04:09:39 DEBUG : dst/file1: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30f5a_d20241215_m040851_c001_v0001117_t0050_u01734235731242") 2024/12/15 04:09:39 DEBUG : dst/file1: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f403661e5c9c5eebe_d20241215_m040649_c001_v7007000_t0000_u01734235609412") 2024/12/15 04:09:39 DEBUG : dst/file1: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30ca4_d20241215_m040648_c001_v0001117_t0007_u01734235608335") 2024/12/15 04:09:39 DEBUG : dst/file1: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f403661e5c9c5eeba_d20241215_m040648_c001_v7007000_t0000_u01734235608120") 2024/12/15 04:09:39 DEBUG : dst/file1: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30c94_d20241215_m040644_c001_v0001117_t0026_u01734235604875") 2024/12/15 04:09:39 DEBUG : dst/one: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f403661e5c9c5ef23_d20241215_m040719_c001_v7007000_t0000_u01734235639308") 2024/12/15 04:09:39 DEBUG : dst/one: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f1008d7c53b795a3e_d20241215_m040713_c001_v0001106_t0014_u01734235633035") 2024/12/15 04:09:39 DEBUG : dst/one: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f408340799480eb07_d20241215_m040712_c001_v7007000_t0000_u01734235632836") 2024/12/15 04:09:39 DEBUG : dst/one: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30d08_d20241215_m040708_c001_v0001117_t0017_u01734235628509") 2024/12/15 04:09:39 DEBUG : dst/one: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f1111e696ec392433_d20241215_m040707_c001_v0001182_t0023_u01734235627724") 2024/12/15 04:09:39 DEBUG : dst/one: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f1111e696ec39240b_d20241215_m040706_c001_v0001182_t0012_u01734235626706") 2024/12/15 04:09:40 DEBUG : dst/one: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f403661e5c9c5eee2_d20241215_m040702_c001_v7007000_t0000_u01734235622276") 2024/12/15 04:09:40 DEBUG : dst/one: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30cc4_d20241215_m040655_c001_v0001117_t0040_u01734235615998") 2024/12/15 04:09:40 DEBUG : dst/one: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30cbc_d20241215_m040654_c001_v0001117_t0049_u01734235614994") 2024/12/15 04:09:40 DEBUG : dst/one: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30cb6_d20241215_m040653_c001_v0001117_t0007_u01734235613687") 2024/12/15 04:09:40 DEBUG : dst/three: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f403661e5c9c5ef24_d20241215_m040719_c001_v7007000_t0000_u01734235639488") 2024/12/15 04:09:40 DEBUG : dst/three: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f1111e696ec392688_d20241215_m040717_c001_v0001182_t0029_u01734235637924") 2024/12/15 04:09:40 DEBUG : dst/two: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f403661e5c9c5ef25_d20241215_m040719_c001_v7007000_t0000_u01734235639674") 2024/12/15 04:09:40 DEBUG : dst/two: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f1105c07dee7f9ae6_d20241215_m040715_c001_v0001109_t0001_u01734235635019") 2024/12/15 04:09:40 DEBUG : dst/two: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f403661e5c9c5eee4_d20241215_m040702_c001_v7007000_t0000_u01734235622457") 2024/12/15 04:09:40 DEBUG : dst/two: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30cea_d20241215_m040700_c001_v0001117_t0059_u01734235620981") 2024/12/15 04:09:40 DEBUG : empty space: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f4191a778bf23a370_d20241215_m040931_c001_v7007000_t0000_u01734235771680") 2024/12/15 04:09:40 DEBUG : empty space: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f109cfc1d2cb64902_d20241215_m040929_c001_v0001146_t0016_u01734235769782") 2024/12/15 04:09:40 DEBUG : empty space: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f100c6e246d3317f6_d20241215_m040928_c001_v0001183_t0047_u01734235768385") 2024/12/15 04:09:40 DEBUG : empty space: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f400b9e616b2fa5aa_d20241215_m040807_c001_v7007000_t0000_u01734235687370") 2024/12/15 04:09:40 DEBUG : empty space: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30e5e_d20241215_m040805_c001_v0001117_t0023_u01734235685721") 2024/12/15 04:09:40 DEBUG : empty space: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f400b9e616b2fa5a4_d20241215_m040804_c001_v7007000_t0000_u01734235684192") 2024/12/15 04:09:40 DEBUG : empty space: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30e4c_d20241215_m040802_c001_v0001117_t0039_u01734235682450") 2024/12/15 04:09:40 DEBUG : empty space: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f400b9e616b2fa5a0_d20241215_m040801_c001_v7007000_t0000_u01734235681283") 2024/12/15 04:09:41 DEBUG : empty space: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30e36_d20241215_m040800_c001_v0001117_t0033_u01734235680196") 2024/12/15 04:09:41 DEBUG : empty space: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f400b9e616b2fa59a_d20241215_m040759_c001_v7007000_t0000_u01734235679002") 2024/12/15 04:09:41 DEBUG : empty space: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30e30_d20241215_m040757_c001_v0001117_t0040_u01734235677369") 2024/12/15 04:09:41 DEBUG : empty space: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f400b9e616b2fa592_d20241215_m040756_c001_v7007000_t0000_u01734235676334") 2024/12/15 04:09:41 DEBUG : empty space: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30e20_d20241215_m040755_c001_v0001117_t0050_u01734235675265") 2024/12/15 04:09:41 DEBUG : empty space: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f417797dfc9359352_d20241215_m040529_c001_v7007000_t0000_u01734235529753") 2024/12/15 04:09:41 DEBUG : empty space: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30b49_d20241215_m040526_c001_v0001117_t0034_u01734235526575") 2024/12/15 04:09:41 DEBUG : empty space: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f417797dfc935934a_d20241215_m040524_c001_v7007000_t0000_u01734235524569") 2024/12/15 04:09:41 DEBUG : empty space: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30b25_d20241215_m040520_c001_v0001117_t0049_u01734235520160") 2024/12/15 04:09:41 DEBUG : empty space: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f417797dfc935933c_d20241215_m040517_c001_v7007000_t0000_u01734235517248") 2024/12/15 04:09:41 DEBUG : empty space: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30afb_d20241215_m040514_c001_v0001117_t0000_u01734235514030") 2024/12/15 04:09:41 DEBUG : file1: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f4191a778bf23a2f1_d20241215_m040850_c001_v7007000_t0000_u01734235730062") 2024/12/15 04:09:41 DEBUG : file1: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30f4e_d20241215_m040848_c001_v0001117_t0058_u01734235728276") 2024/12/15 04:09:41 DEBUG : file1: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f4191a778bf23a2e8_d20241215_m040844_c001_v7007000_t0000_u01734235724998") 2024/12/15 04:09:41 DEBUG : file1: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f412032f88ab30f26_d20241215_m040842_c001_v0001117_t0010_u01734235722235") 2024/12/15 04:09:41 DEBUG : file1: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f4191a778bf23a2c0_d20241215_m040828_c001_v7007000_t0000_u01734235708838") 2024/12/15 04:09:41 DEBUG : file1: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30eca_d20241215_m040824_c001_v0001117_t0019_u01734235704684") 2024/12/15 04:09:41 DEBUG : file1: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f400b9e616b2fa586_d20241215_m040751_c001_v7007000_t0000_u01734235671606") 2024/12/15 04:09:41 DEBUG : file1: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30df2_d20241215_m040745_c001_v0001117_t0015_u01734235665138") 2024/12/15 04:09:41 DEBUG : file1: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f400b9e616b2fa547_d20241215_m040744_c001_v7007000_t0000_u01734235664596") 2024/12/15 04:09:42 DEBUG : file1: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30dde_d20241215_m040741_c001_v0001117_t0055_u01734235661258") 2024/12/15 04:09:42 DEBUG : file2: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f4191a778bf23a2e9_d20241215_m040845_c001_v7007000_t0000_u01734235725180") 2024/12/15 04:09:42 DEBUG : file2: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f412032f88ab30f3c_d20241215_m040844_c001_v0001117_t0010_u01734235724443") 2024/12/15 04:09:42 DEBUG : file2: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f4191a778bf23a2c1_d20241215_m040829_c001_v7007000_t0000_u01734235709021") 2024/12/15 04:09:42 DEBUG : file2: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30ece_d20241215_m040825_c001_v0001117_t0047_u01734235705160") 2024/12/15 04:09:42 DEBUG : filename.txt: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f4191a778bf23a2ea_d20241215_m040845_c001_v7007000_t0000_u01734235725376") 2024/12/15 04:09:42 DEBUG : filename.txt: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30f28_d20241215_m040842_c001_v0001117_t0004_u01734235722784") 2024/12/15 04:09:42 DEBUG : headerfilename.txt: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f4191a778bf23a2eb_d20241215_m040845_c001_v7007000_t0000_u01734235725558") 2024/12/15 04:09:42 DEBUG : headerfilename.txt: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30f30_d20241215_m040843_c001_v0001117_t0040_u01734235723324") 2024/12/15 04:09:42 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f4191a778bf23a365_d20241215_m040925_c001_v7007000_t0000_u01734235765452") 2024/12/15 04:09:42 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f100c6e246d33169f_d20241215_m040924_c001_v0001183_t0022_u01734235764292") 2024/12/15 04:09:42 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f4191a778bf23a361_d20241215_m040923_c001_v7007000_t0000_u01734235763334") 2024/12/15 04:09:42 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f100c6e246d3315f5_d20241215_m040922_c001_v0001183_t0048_u01734235762205") 2024/12/15 04:09:42 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f4191a778bf23a366_d20241215_m040925_c001_v7007000_t0000_u01734235765641") 2024/12/15 04:09:42 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f100c6e246d331693_d20241215_m040924_c001_v0001183_t0004_u01734235764092") 2024/12/15 04:09:42 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f4191a778bf23a363_d20241215_m040923_c001_v7007000_t0000_u01734235763526") 2024/12/15 04:09:42 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f100c6e246d3315e3_d20241215_m040922_c001_v0001183_t0012_u01734235762000") 2024/12/15 04:09:42 DEBUG : large: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f400b9e616b2fa5d3_d20241215_m040821_c001_v7007000_t0000_u01734235701282") 2024/12/15 04:09:42 DEBUG : large: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30ebc_d20241215_m040820_c001_v0001117_t0052_u01734235700331") 2024/12/15 04:09:42 DEBUG : large: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f400b9e616b2fa5cb_d20241215_m040818_c001_v7007000_t0000_u01734235698359") 2024/12/15 04:09:42 DEBUG : large: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30ea8_d20241215_m040816_c001_v0001117_t0015_u01734235696533") 2024/12/15 04:09:43 DEBUG : large: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f400b9e616b2fa5c2_d20241215_m040814_c001_v7007000_t0000_u01734235694041") 2024/12/15 04:09:43 DEBUG : large: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30e96_d20241215_m040813_c001_v0001117_t0046_u01734235693017") 2024/12/15 04:09:43 DEBUG : large: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f400b9e616b2fa5bb_d20241215_m040811_c001_v7007000_t0000_u01734235691259") 2024/12/15 04:09:43 DEBUG : large: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30e78_d20241215_m040809_c001_v0001117_t0030_u01734235689652") 2024/12/15 04:09:43 DEBUG : medium: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f4191a778bf23a2b1_d20241215_m040821_c001_v7007000_t0000_u01734235701290") 2024/12/15 04:09:43 DEBUG : medium: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30eba_d20241215_m040819_c001_v0001117_t0019_u01734235699738") 2024/12/15 04:09:43 DEBUG : medium: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f4191a778bf23a2ae_d20241215_m040817_c001_v7007000_t0000_u01734235697800") 2024/12/15 04:09:43 DEBUG : medium: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30ea4_d20241215_m040815_c001_v0001117_t0056_u01734235695874") 2024/12/15 04:09:43 DEBUG : medium: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f4191a778bf23a2a8_d20241215_m040814_c001_v7007000_t0000_u01734235694053") 2024/12/15 04:09:43 DEBUG : medium: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30e8e_d20241215_m040812_c001_v0001117_t0018_u01734235692181") 2024/12/15 04:09:43 DEBUG : medium: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f400b9e616b2fa5b3_d20241215_m040810_c001_v7007000_t0000_u01734235690521") 2024/12/15 04:09:43 DEBUG : medium: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30e72_d20241215_m040808_c001_v0001117_t0043_u01734235688638") 2024/12/15 04:09:43 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f4191a778bf23a358_d20241215_m040917_c001_v7007000_t0000_u01734235757951") 2024/12/15 04:09:43 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30fe5_d20241215_m040916_c001_v0001117_t0047_u01734235756494") 2024/12/15 04:09:43 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f4191a778bf23a359_d20241215_m040918_c001_v7007000_t0000_u01734235758159") 2024/12/15 04:09:43 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30fe3_d20241215_m040915_c001_v0001117_t0025_u01734235755006") 2024/12/15 04:09:43 DEBUG : not-one: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f403661e5c9c5ef34_d20241215_m040730_c001_v7007000_t0000_u01734235650112") 2024/12/15 04:09:43 DEBUG : not-one: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30d86_d20241215_m040728_c001_v0001117_t0004_u01734235648204") 2024/12/15 04:09:43 DEBUG : one: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f403661e5c9c5ef31_d20241215_m040729_c001_v7007000_t0000_u01734235649201") 2024/12/15 04:09:44 DEBUG : one: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30d76_d20241215_m040726_c001_v0001117_t0040_u01734235646683") 2024/12/15 04:09:44 DEBUG : potato1: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f4191a778bf23a36a_d20241215_m040927_c001_v7007000_t0000_u01734235767209") 2024/12/15 04:09:44 DEBUG : potato1: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f100c6e246d33174f_d20241215_m040926_c001_v0001183_t0043_u01734235766388") 2024/12/15 04:09:44 DEBUG : potato2: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f4191a778bf23a371_d20241215_m040931_c001_v7007000_t0000_u01734235771863") 2024/12/15 04:09:44 DEBUG : potato2: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f10532e3a957bb022_d20241215_m040930_c001_v0001157_t0002_u01734235770509") 2024/12/15 04:09:44 DEBUG : potato2: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f100c6e246d3317e0_d20241215_m040928_c001_v0001183_t0006_u01734235768157") 2024/12/15 04:09:44 DEBUG : potato2: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f4191a778bf23a36c_d20241215_m040927_c001_v7007000_t0000_u01734235767391") 2024/12/15 04:09:44 DEBUG : potato2: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f100c6e246d33176d_d20241215_m040926_c001_v0001183_t0041_u01734235766612") 2024/12/15 04:09:44 DEBUG : potato2: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f400b9e616b2fa5ac_d20241215_m040807_c001_v7007000_t0000_u01734235687552") 2024/12/15 04:09:44 DEBUG : potato2: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30e58_d20241215_m040804_c001_v0001117_t0056_u01734235684737") 2024/12/15 04:09:44 DEBUG : potato2: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f400b9e616b2fa5a5_d20241215_m040804_c001_v7007000_t0000_u01734235684372") 2024/12/15 04:09:44 DEBUG : potato2: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30e42_d20241215_m040801_c001_v0001117_t0011_u01734235681822") 2024/12/15 04:09:44 DEBUG : potato2: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f400b9e616b2fa5a1_d20241215_m040801_c001_v7007000_t0000_u01734235681463") 2024/12/15 04:09:44 DEBUG : potato2: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30e32_d20241215_m040759_c001_v0001117_t0007_u01734235679544") 2024/12/15 04:09:44 DEBUG : potato2: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f400b9e616b2fa59c_d20241215_m040759_c001_v7007000_t0000_u01734235679183") 2024/12/15 04:09:44 DEBUG : potato2: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30e2e_d20241215_m040756_c001_v0001117_t0034_u01734235676882") 2024/12/15 04:09:44 DEBUG : potato2: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f400b9e616b2fa594_d20241215_m040756_c001_v7007000_t0000_u01734235676521") 2024/12/15 04:09:44 DEBUG : potato2: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30e1e_d20241215_m040754_c001_v0001117_t0034_u01734235674648") 2024/12/15 04:09:44 DEBUG : potato2: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f417797dfc9359353_d20241215_m040529_c001_v7007000_t0000_u01734235529937") 2024/12/15 04:09:44 DEBUG : potato2: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30b4b_d20241215_m040527_c001_v0001117_t0056_u01734235527411") 2024/12/15 04:09:45 DEBUG : potato2: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f417797dfc935934b_d20241215_m040524_c001_v7007000_t0000_u01734235524758") 2024/12/15 04:09:45 DEBUG : potato2: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30b29_d20241215_m040521_c001_v0001117_t0033_u01734235521249") 2024/12/15 04:09:45 DEBUG : potato2: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f417797dfc935933d_d20241215_m040517_c001_v7007000_t0000_u01734235517431") 2024/12/15 04:09:45 DEBUG : potato2: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30b03_d20241215_m040514_c001_v0001117_t0054_u01734235514873") 2024/12/15 04:09:45 DEBUG : remotepotato: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f417797dfc9359354_d20241215_m040530_c001_v7007000_t0000_u01734235530121") 2024/12/15 04:09:45 DEBUG : remotepotato: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30b51_d20241215_m040528_c001_v0001117_t0045_u01734235528487") 2024/12/15 04:09:45 DEBUG : remotepotato: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f417797dfc935934c_d20241215_m040524_c001_v7007000_t0000_u01734235524941") 2024/12/15 04:09:45 DEBUG : remotepotato: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30b31_d20241215_m040522_c001_v0001117_t0008_u01734235522913") 2024/12/15 04:09:45 DEBUG : remotepotato: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f417797dfc935933e_d20241215_m040517_c001_v7007000_t0000_u01734235517614") 2024/12/15 04:09:45 DEBUG : remotepotato: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30b0d_d20241215_m040516_c001_v0001117_t0059_u01734235516022") 2024/12/15 04:09:45 DEBUG : rutabaga: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f417797dfc9359356_d20241215_m040530_c001_v7007000_t0000_u01734235530305") 2024/12/15 04:09:45 DEBUG : rutabaga: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30b41_d20241215_m040525_c001_v0001117_t0016_u01734235525489") 2024/12/15 04:09:45 DEBUG : rutabaga: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f417797dfc935934d_d20241215_m040525_c001_v7007000_t0000_u01734235525125") 2024/12/15 04:09:45 DEBUG : rutabaga: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30b1d_d20241215_m040518_c001_v0001117_t0053_u01734235518165") 2024/12/15 04:09:45 DEBUG : rutabaga: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f417797dfc935933f_d20241215_m040517_c001_v7007000_t0000_u01734235517798") 2024/12/15 04:09:45 DEBUG : rutabaga: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30af7_d20241215_m040512_c001_v0001117_t0006_u01734235512875") 2024/12/15 04:09:45 DEBUG : small: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f4191a778bf23a2b2_d20241215_m040821_c001_v7007000_t0000_u01734235701845") 2024/12/15 04:09:45 DEBUG : small: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30eb0_d20241215_m040819_c001_v0001117_t0005_u01734235699086") 2024/12/15 04:09:45 DEBUG : small: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f400b9e616b2fa5ca_d20241215_m040817_c001_v7007000_t0000_u01734235697812") 2024/12/15 04:09:45 DEBUG : small: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30ea2_d20241215_m040815_c001_v0001117_t0035_u01734235695410") 2024/12/15 04:09:46 DEBUG : small: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f4191a778bf23a2ab_d20241215_m040814_c001_v7007000_t0000_u01734235694609") 2024/12/15 04:09:46 DEBUG : small: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30e88_d20241215_m040811_c001_v0001117_t0002_u01734235691624") 2024/12/15 04:09:46 DEBUG : small: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f400b9e616b2fa5b5_d20241215_m040810_c001_v7007000_t0000_u01734235690715") 2024/12/15 04:09:46 DEBUG : small: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30e70_d20241215_m040808_c001_v0001117_t0044_u01734235688095") 2024/12/15 04:09:46 DEBUG : sub dir/hello world: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f400b9e616b2fa58d_d20241215_m040754_c001_v7007000_t0000_u01734235674286") 2024/12/15 04:09:46 DEBUG : sub dir/hello world: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30e1a_d20241215_m040753_c001_v0001117_t0031_u01734235673053") 2024/12/15 04:09:46 DEBUG : sub dir/hello world: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f400b9e616b2fa52a_d20241215_m040739_c001_v7007000_t0000_u01734235659994") 2024/12/15 04:09:46 DEBUG : sub dir/hello world: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30daa_d20241215_m040733_c001_v0001117_t0039_u01734235653661") 2024/12/15 04:09:46 DEBUG : sub dir/hello world2: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f400b9e616b2fa52b_d20241215_m040740_c001_v7007000_t0000_u01734235660174") 2024/12/15 04:09:46 DEBUG : sub dir/hello world2: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30db0_d20241215_m040734_c001_v0001117_t0024_u01734235654451") 2024/12/15 04:09:46 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f400b9e616b2fa52c_d20241215_m040740_c001_v7007000_t0000_u01734235660355") 2024/12/15 04:09:46 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30db2_d20241215_m040735_c001_v0001117_t0024_u01734235655082") 2024/12/15 04:09:46 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f400b9e616b2fa52e_d20241215_m040740_c001_v7007000_t0000_u01734235660535") 2024/12/15 04:09:46 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30dba_d20241215_m040735_c001_v0001117_t0028_u01734235655812") 2024/12/15 04:09:46 DEBUG : sub dir/potato3: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f4191a778bf23a372_d20241215_m040932_c001_v7007000_t0000_u01734235772046") 2024/12/15 04:09:46 DEBUG : sub dir/potato3: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f110cbdae247068b1_d20241215_m040931_c001_v0001179_t0031_u01734235771051") 2024/12/15 04:09:46 DEBUG : sub dir/potato3: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f100c6e246d33180e_d20241215_m040928_c001_v0001183_t0040_u01734235768583") 2024/12/15 04:09:46 DEBUG : sub dir/potato3: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f400b9e616b2fa5ae_d20241215_m040807_c001_v7007000_t0000_u01734235687733") 2024/12/15 04:09:46 DEBUG : sub dir/potato3: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30e60_d20241215_m040806_c001_v0001117_t0045_u01734235686308") 2024/12/15 04:09:46 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f400b9e616b2fa52f_d20241215_m040740_c001_v7007000_t0000_u01734235660716") 2024/12/15 04:09:47 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30dc0_d20241215_m040736_c001_v0001117_t0043_u01734235656485") 2024/12/15 04:09:47 DEBUG : sub/file2: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f4191a778bf23a2ee_d20241215_m040847_c001_v7007000_t0000_u01734235727724") 2024/12/15 04:09:47 DEBUG : sub/file2: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30f46_d20241215_m040846_c001_v0001117_t0003_u01734235726170") 2024/12/15 04:09:47 DEBUG : sub/file2: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f400b9e616b2fa587_d20241215_m040751_c001_v7007000_t0000_u01734235671788") 2024/12/15 04:09:47 DEBUG : sub/file2: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30df8_d20241215_m040745_c001_v0001117_t0015_u01734235665906") 2024/12/15 04:09:47 DEBUG : sub/file2: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f400b9e616b2fa54a_d20241215_m040744_c001_v7007000_t0000_u01734235664777") 2024/12/15 04:09:47 DEBUG : sub/file2: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30de4_d20241215_m040741_c001_v0001117_t0045_u01734235661813") 2024/12/15 04:09:47 DEBUG : sub/file2: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f403661e5c9c5eeb2_d20241215_m040644_c001_v7007000_t0000_u01734235604147") 2024/12/15 04:09:47 DEBUG : sub/file2: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30c7a_d20241215_m040642_c001_v0001117_t0019_u01734235602053") 2024/12/15 04:09:47 DEBUG : test-multithread-abort: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f417797dfc9359334_d20241215_m040511_c001_v7007000_t0000_u01734235511965") 2024/12/15 04:09:47 DEBUG : test-multithread-abort: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30a75_d20241215_m040437_c001_v0001117_t0051_u01734235477815") 2024/12/15 04:09:47 DEBUG : test-multithread-copy-false-201326591-2: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f40462cc00e5133cf_d20241215_m040147_c001_v7007000_t0000_u01734235307949") 2024/12/15 04:09:47 DEBUG : test-multithread-copy-false-201326591-2: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab307c5_d20241215_m040112_c001_v0001117_t0045_u01734235272219") 2024/12/15 04:09:47 DEBUG : test-multithread-copy-false-201326592-2: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f407af66b01836844_d20241215_m040224_c001_v7007000_t0000_u01734235344060") 2024/12/15 04:09:47 DEBUG : test-multithread-copy-false-201326592-2: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30850_d20241215_m040149_c001_v0001117_t0000_u01734235309791") 2024/12/15 04:09:47 DEBUG : test-multithread-copy-false-201326593-2: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f400b9e616b2f9a8d_d20241215_m040302_c001_v7007000_t0000_u01734235382263") 2024/12/15 04:09:47 DEBUG : test-multithread-copy-false-201326593-2: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab308dc_d20241215_m040226_c001_v0001117_t0045_u01734235346208") 2024/12/15 04:09:47 DEBUG : test-multithread-copy-true-201326591-2: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f4184e9ccd320d2c0_d20241215_m040333_c001_v7007000_t0000_u01734235413972") 2024/12/15 04:09:47 DEBUG : test-multithread-copy-true-201326591-2: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f2101957d2eb6f63f_d20241215_m040317_c001_v0001183_t0051_u01734235397327") 2024/12/15 04:09:48 DEBUG : test-multithread-copy-true-201326592-2: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f403661e5c9c5eb71_d20241215_m040405_c001_v7007000_t0000_u01734235445623") 2024/12/15 04:09:48 DEBUG : test-multithread-copy-true-201326592-2: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f2101957d2eb6f750_d20241215_m040348_c001_v0001092_t0044_u01734235428708") 2024/12/15 04:09:48 DEBUG : test-multithread-copy-true-201326593-2: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f403661e5c9c5ebd8_d20241215_m040436_c001_v7007000_t0000_u01734235476485") 2024/12/15 04:09:48 DEBUG : test-multithread-copy-true-201326593-2: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f2021fd09eaee1417_d20241215_m040420_c001_v0001183_t0018_u01734235460289") 2024/12/15 04:09:48 DEBUG : test.sum: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f417797dfc93593a9_d20241215_m040553_c001_v7007000_t0000_u01734235553009") 2024/12/15 04:09:48 DEBUG : test.sum: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30bb5_d20241215_m040551_c001_v0001117_t0028_u01734235551023") 2024/12/15 04:09:48 DEBUG : test.sum: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30ba1_d20241215_m040547_c001_v0001117_t0030_u01734235547523") 2024/12/15 04:09:48 DEBUG : test.sum: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30b99_d20241215_m040546_c001_v0001117_t0047_u01734235546049") 2024/12/15 04:09:48 DEBUG : test.sum: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30b91_d20241215_m040544_c001_v0001117_t0014_u01734235544379") 2024/12/15 04:09:48 DEBUG : test.sum: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30b8b_d20241215_m040542_c001_v0001117_t0052_u01734235542858") 2024/12/15 04:09:48 DEBUG : test.sum: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30b83_d20241215_m040540_c001_v0001117_t0026_u01734235540816") 2024/12/15 04:09:48 DEBUG : test.sum: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30b77_d20241215_m040537_c001_v0001117_t0029_u01734235537651") 2024/12/15 04:09:48 DEBUG : test.sum: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f417797dfc9359357_d20241215_m040533_c001_v7007000_t0000_u01734235533657") 2024/12/15 04:09:48 DEBUG : test.sum: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30b61_d20241215_m040532_c001_v0001117_t0008_u01734235532434") 2024/12/15 04:09:48 DEBUG : test.sum: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30b59_d20241215_m040530_c001_v0001117_t0006_u01734235530674") 2024/12/15 04:09:48 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f4191a778bf23a35e_d20241215_m040920_c001_v7007000_t0000_u01734235760521") 2024/12/15 04:09:48 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30ff3_d20241215_m040918_c001_v0001117_t0022_u01734235758719") 2024/12/15 04:09:48 DEBUG : zend.txt: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f400b9e616b2fa531_d20241215_m040740_c001_v7007000_t0000_u01734235660896") 2024/12/15 04:09:48 DEBUG : zend.txt: Deleting (id "4_zbf822d8370adcb8b9f380e1b_f112032f88ab30da4_d20241215_m040732_c001_v0001117_t0007_u01734235652789") "./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose" - Finished ERROR in 8m41.898147268s (try 1/5): exit status 1: Failed [TestRcat/withChecksum=true,ignoreChecksum=false]