"./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose" - Starting (try 1/5) 2024/04/11 04:08:16 DEBUG : Creating backend with remote "TestB2:rclone-test-yexoyif0zozuyuj9merocub2" 2024/04/11 04:08:16 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/04/11 04:08:17 DEBUG : Creating backend with remote "/tmp/rclone1530930504" === 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-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" 2024/04/11 04:08:18 DEBUG : chunksize-probe: Cancelling large file upload === RUN TestMultithreadCopy/upload=false,size=201326591,streams=2 2024/04/11 04:08:37 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2024/04/11 04:08:37 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: write buffer set to 131072 2024/04/11 04:08:37 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2024/04/11 04:08:37 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2024/04/11 04:08:37 DEBUG : test-multithread-copy-false-201326591-2: Starting multi-thread copy with 3 chunks of size 64Mi with 3 parallel streams 2024/04/11 04:08:37 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 3/3 (134217728-201326591) size 64.000Mi starting 2024/04/11 04:08:37 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 1/3 (0-67108864) size 64Mi starting 2024/04/11 04:08:37 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 2/3 (67108864-134217728) size 64Mi starting 2024/04/11 04:08:37 DEBUG : test-multithread-copy-false-201326591-2: writing chunk 2 2024/04/11 04:08:37 DEBUG : test-multithread-copy-false-201326591-2: writing chunk 0 2024/04/11 04:08:38 DEBUG : test-multithread-copy-false-201326591-2: writing chunk 1 2024/04/11 04:08:42 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 3/3 (134217728-201326591) size 64.000Mi finished 2024/04/11 04:08:42 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 1/3 (0-67108864) size 64Mi finished 2024/04/11 04:08:43 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 2/3 (67108864-134217728) size 64Mi finished 2024/04/11 04:08:43 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/04/11 04:09:01 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2024/04/11 04:09:01 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: write buffer set to 131072 2024/04/11 04:09:01 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2024/04/11 04:09:01 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2024/04/11 04:09:01 DEBUG : test-multithread-copy-false-201326592-2: Starting multi-thread copy with 3 chunks of size 64Mi with 3 parallel streams 2024/04/11 04:09:01 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 3/3 (134217728-201326592) size 64Mi starting 2024/04/11 04:09:01 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 1/3 (0-67108864) size 64Mi starting 2024/04/11 04:09:01 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 2/3 (67108864-134217728) size 64Mi starting 2024/04/11 04:09:01 DEBUG : test-multithread-copy-false-201326592-2: writing chunk 2 2024/04/11 04:09:01 DEBUG : test-multithread-copy-false-201326592-2: writing chunk 1 2024/04/11 04:09:02 DEBUG : test-multithread-copy-false-201326592-2: writing chunk 0 2024/04/11 04:09:06 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 3/3 (134217728-201326592) size 64Mi finished 2024/04/11 04:09:06 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 2/3 (67108864-134217728) size 64Mi finished 2024/04/11 04:09:07 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 1/3 (0-67108864) size 64Mi finished 2024/04/11 04:09:07 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/04/11 04:09:25 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2024/04/11 04:09:25 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: write buffer set to 131072 2024/04/11 04:09:25 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2024/04/11 04:09:25 DEBUG : test-multithread-copy-false-201326593-2: Starting multi-thread copy with 4 chunks of size 64Mi with 4 parallel streams 2024/04/11 04:09:25 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 4/4 (201326592-201326593) size 1 starting 2024/04/11 04:09:25 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 1/4 (0-67108864) size 64Mi starting 2024/04/11 04:09:25 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 2/4 (67108864-134217728) size 64Mi starting 2024/04/11 04:09:25 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 3/4 (134217728-201326592) size 64Mi starting 2024/04/11 04:09:25 DEBUG : test-multithread-copy-false-201326593-2: writing chunk 3 2024/04/11 04:09:25 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 4/4 (201326592-201326593) size 1 finished 2024/04/11 04:09:25 DEBUG : test-multithread-copy-false-201326593-2: writing chunk 0 2024/04/11 04:09:25 DEBUG : test-multithread-copy-false-201326593-2: writing chunk 2 2024/04/11 04:09:25 DEBUG : test-multithread-copy-false-201326593-2: writing chunk 1 2024/04/11 04:09:30 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 1/4 (0-67108864) size 64Mi finished 2024/04/11 04:09:31 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 2/4 (67108864-134217728) size 64Mi finished 2024/04/11 04:09:31 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 3/4 (134217728-201326592) size 64Mi finished 2024/04/11 04:09:31 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/04/11 04:09:34 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: disabling buffering because source is local disk 2024/04/11 04:09:35 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2024/04/11 04:09:35 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: number of streams 4 was bigger than number of chunks 2 2024/04/11 04:09:35 DEBUG : test-multithread-copy-true-201326591-2: Starting multi-thread copy with 2 chunks of size 96Mi with 2 parallel streams 2024/04/11 04:09:35 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: chunk 2/2 (100663296-201326591) size 96.000Mi starting 2024/04/11 04:09:35 DEBUG : test-multithread-copy-true-201326591-2: Sending chunk 1 length 100663295 2024/04/11 04:09:35 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: chunk 1/2 (0-100663296) size 96Mi starting 2024/04/11 04:09:35 DEBUG : test-multithread-copy-true-201326591-2: Sending chunk 0 length 100663296 2024/04/11 04:09:43 DEBUG : test-multithread-copy-true-201326591-2: Done sending chunk 1 2024/04/11 04:09:43 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: chunk 2/2 (100663296-201326591) size 96.000Mi finished 2024/04/11 04:09:44 DEBUG : test-multithread-copy-true-201326591-2: Done sending chunk 0 2024/04/11 04:09:44 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: chunk 1/2 (0-100663296) size 96Mi finished 2024/04/11 04:09:44 DEBUG : test-multithread-copy-true-201326591-2: Finishing large file upload with 2 parts 2024/04/11 04:09:44 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/04/11 04:09:48 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: disabling buffering because source is local disk 2024/04/11 04:09:48 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2024/04/11 04:09:48 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: number of streams 4 was bigger than number of chunks 2 2024/04/11 04:09:48 DEBUG : test-multithread-copy-true-201326592-2: Starting multi-thread copy with 2 chunks of size 96Mi with 2 parallel streams 2024/04/11 04:09:48 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: chunk 2/2 (100663296-201326592) size 96Mi starting 2024/04/11 04:09:48 DEBUG : test-multithread-copy-true-201326592-2: Sending chunk 1 length 100663296 2024/04/11 04:09:48 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: chunk 1/2 (0-100663296) size 96Mi starting 2024/04/11 04:09:48 DEBUG : test-multithread-copy-true-201326592-2: Sending chunk 0 length 100663296 2024/04/11 04:09:57 DEBUG : test-multithread-copy-true-201326592-2: Done sending chunk 1 2024/04/11 04:09:57 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: chunk 2/2 (100663296-201326592) size 96Mi finished 2024/04/11 04:09:58 DEBUG : test-multithread-copy-true-201326592-2: Done sending chunk 0 2024/04/11 04:09:58 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: chunk 1/2 (0-100663296) size 96Mi finished 2024/04/11 04:09:58 DEBUG : test-multithread-copy-true-201326592-2: Finishing large file upload with 2 parts 2024/04/11 04:09:59 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/04/11 04:10:02 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: disabling buffering because source is local disk 2024/04/11 04:10:03 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2024/04/11 04:10:03 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2024/04/11 04:10:03 DEBUG : test-multithread-copy-true-201326593-2: Starting multi-thread copy with 3 chunks of size 96Mi with 3 parallel streams 2024/04/11 04:10:03 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 3/3 (201326592-201326593) size 1 starting 2024/04/11 04:10:03 DEBUG : test-multithread-copy-true-201326593-2: Sending chunk 2 length 1 2024/04/11 04:10:03 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 2/3 (100663296-201326592) size 96Mi starting 2024/04/11 04:10:03 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 1/3 (0-100663296) size 96Mi starting 2024/04/11 04:10:03 DEBUG : test-multithread-copy-true-201326593-2: Sending chunk 1 length 100663296 2024/04/11 04:10:03 DEBUG : test-multithread-copy-true-201326593-2: Sending chunk 0 length 100663296 2024/04/11 04:10:04 DEBUG : test-multithread-copy-true-201326593-2: Done sending chunk 2 2024/04/11 04:10:04 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 3/3 (201326592-201326593) size 1 finished 2024/04/11 04:10:12 DEBUG : test-multithread-copy-true-201326593-2: Done sending chunk 0 2024/04/11 04:10:12 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 1/3 (0-100663296) size 96Mi finished 2024/04/11 04:10:18 DEBUG : test-multithread-copy-true-201326593-2: Done sending chunk 1 2024/04/11 04:10:18 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 2/3 (100663296-201326592) size 96Mi finished 2024/04/11 04:10:18 DEBUG : test-multithread-copy-true-201326593-2: Finishing large file upload with 3 parts 2024/04/11 04:10:19 DEBUG : test-multithread-copy-true-201326593-2: Finished multi-thread copy with 3 parts of size 96Mi --- PASS: TestMultithreadCopy (122.57s) --- PASS: TestMultithreadCopy/upload=false,size=201326591,streams=2 (25.18s) --- PASS: TestMultithreadCopy/upload=false,size=201326592,streams=2 (24.05s) --- PASS: TestMultithreadCopy/upload=false,size=201326593,streams=2 (23.97s) --- PASS: TestMultithreadCopy/upload=true,size=201326591,streams=2 (13.16s) --- PASS: TestMultithreadCopy/upload=true,size=201326592,streams=2 (14.55s) --- PASS: TestMultithreadCopy/upload=true,size=201326593,streams=2 (19.89s) === RUN TestMultithreadCopyAbort run.go:180: Remote "B2 bucket rclone-test-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" 2024/04/11 04:10:20 DEBUG : chunksize-probe: Cancelling large file upload 2024/04/11 04:10:23 DEBUG : test-multithread-abort: multi-thread copy: disabling buffering because source is local disk 2024/04/11 04:10:24 DEBUG : test-multithread-abort: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 1 2024/04/11 04:10:24 DEBUG : test-multithread-abort: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2024/04/11 04:10:24 DEBUG : test-multithread-abort: Starting multi-thread copy with 3 chunks of size 96Mi with 3 parallel streams 2024/04/11 04:10:24 DEBUG : test-multithread-abort: multi-thread copy: chunk 1/3 (0-100663296) size 96Mi starting 2024/04/11 04:10:24 DEBUG : test-multithread-abort: multi-thread copy: chunk 2/3 (100663296-201326592) size 96Mi starting 2024/04/11 04:10:24 DEBUG : Open with options = [RangeOption(0,100663295)] 2024/04/11 04:10:24 DEBUG : Open with options = [RangeOption(100663296,201326591)] 2024/04/11 04:10:24 DEBUG : test-multithread-abort: Sending chunk 1 length 100663296 2024/04/11 04:10:24 DEBUG : test-multithread-abort: multi-thread copy: chunk 3/3 (201326592-201326593) size 1 starting 2024/04/11 04:10:24 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2024/04/11 04:10:24 DEBUG : test-multithread-abort: Sending chunk 0 length 100663296 2024/04/11 04:10:32 DEBUG : test-multithread-abort: Done sending chunk 0 2024/04/11 04:10:32 DEBUG : test-multithread-abort: multi-thread copy: chunk 1/3 (0-100663296) size 96Mi finished 2024/04/11 04:10:32 DEBUG : test-multithread-abort: Done sending chunk 1 2024/04/11 04:10:32 DEBUG : test-multithread-abort: multi-thread copy: chunk 2/3 (100663296-201326592) size 96Mi finished 2024/04/11 04:10:32 DEBUG : Returning error reader 2024/04/11 04:10:32 DEBUG : test-multithread-abort: Sending chunk 2 length 1 2024/04/11 04:10:32 DEBUG : BOOM: simulated read failure 2024/04/11 04:10:32 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 0/10: BOOM: simulated read failure 2024/04/11 04:10:32 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2024/04/11 04:10:33 DEBUG : Returning error reader 2024/04/11 04:10:33 DEBUG : BOOM: simulated read failure 2024/04/11 04:10:33 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 1/10: BOOM: simulated read failure 2024/04/11 04:10:33 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2024/04/11 04:10:34 DEBUG : Returning error reader 2024/04/11 04:10:34 DEBUG : BOOM: simulated read failure 2024/04/11 04:10:34 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 2/10: BOOM: simulated read failure 2024/04/11 04:10:34 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2024/04/11 04:10:35 DEBUG : Returning error reader 2024/04/11 04:10:35 DEBUG : BOOM: simulated read failure 2024/04/11 04:10:35 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 3/10: BOOM: simulated read failure 2024/04/11 04:10:35 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2024/04/11 04:10:36 DEBUG : Returning error reader 2024/04/11 04:10:36 DEBUG : BOOM: simulated read failure 2024/04/11 04:10:36 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 4/10: BOOM: simulated read failure 2024/04/11 04:10:36 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2024/04/11 04:10:37 DEBUG : Returning error reader 2024/04/11 04:10:37 DEBUG : BOOM: simulated read failure 2024/04/11 04:10:37 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 5/10: BOOM: simulated read failure 2024/04/11 04:10:37 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2024/04/11 04:10:38 DEBUG : Returning error reader 2024/04/11 04:10:38 DEBUG : BOOM: simulated read failure 2024/04/11 04:10:38 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 6/10: BOOM: simulated read failure 2024/04/11 04:10:38 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2024/04/11 04:10:39 DEBUG : Returning error reader 2024/04/11 04:10:39 DEBUG : BOOM: simulated read failure 2024/04/11 04:10:39 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 7/10: BOOM: simulated read failure 2024/04/11 04:10:39 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2024/04/11 04:10:40 DEBUG : Returning error reader 2024/04/11 04:10:40 DEBUG : BOOM: simulated read failure 2024/04/11 04:10:40 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 8/10: BOOM: simulated read failure 2024/04/11 04:10:40 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2024/04/11 04:10:41 DEBUG : Returning error reader 2024/04/11 04:10:41 DEBUG : BOOM: simulated read failure 2024/04/11 04:10:41 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 9/10: BOOM: simulated read failure 2024/04/11 04:10:41 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2024/04/11 04:10:42 DEBUG : Returning error reader 2024/04/11 04:10:42 DEBUG : BOOM: simulated read failure 2024/04/11 04:10:42 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 10/10: BOOM: simulated read failure 2024/04/11 04:10:42 DEBUG : test-multithread-abort: Reopen failed after offset 0 bytes read: failed to reopen: too many retries 2024/04/11 04:10:42 DEBUG : test-multithread-abort: Error sending chunk 2 (retry=false): Post "https://pod-000-1181-09.backblaze.com/b2api/v1/b2_upload_part/4_zdf529d33507dcbbb8fe80e1b_f203b73892ab01393_d20240411_m041024_c001_v0001181_t0036_u01712808624208/0047": BOOM: simulated read failure: &url.Error{Op:"Post", URL:"https://pod-000-1181-09.backblaze.com/b2api/v1/b2_upload_part/4_zdf529d33507dcbbb8fe80e1b_f203b73892ab01393_d20240411_m041024_c001_v0001181_t0036_u01712808624208/0047", Err:(*errors.errorString)(0xc000069240)} 2024/04/11 04:10:42 DEBUG : test-multithread-abort: Error sending chunk 2: Post "https://pod-000-1181-09.backblaze.com/b2api/v1/b2_upload_part/4_zdf529d33507dcbbb8fe80e1b_f203b73892ab01393_d20240411_m041024_c001_v0001181_t0036_u01712808624208/0047": BOOM: simulated read failure 2024/04/11 04:10:42 DEBUG : test-multithread-abort: multi-thread copy: chunk 3/3 failed: multi-thread copy: failed to write chunk: Post "https://pod-000-1181-09.backblaze.com/b2api/v1/b2_upload_part/4_zdf529d33507dcbbb8fe80e1b_f203b73892ab01393_d20240411_m041024_c001_v0001181_t0036_u01712808624208/0047": BOOM: simulated read failure 2024/04/11 04:10:42 DEBUG : test-multithread-abort: multi-thread copy: cancelling transfer on exit 2024/04/11 04:10:42 DEBUG : test-multithread-abort: Cancelling large file upload --- PASS: TestMultithreadCopyAbort (23.97s) === RUN TestSizeDiffers --- PASS: TestSizeDiffers (0.00s) === RUN TestReOpen === RUN TestReOpen/Normal === RUN TestReOpen/Normal/Basics 2024/04/11 04:10:44 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/ErrorAtStart === RUN TestReOpen/Normal/WithErrors 2024/04/11 04:10:44 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2024/04/11 04:10:44 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2024/04/11 04:10:44 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/Normal/TooManyErrors 2024/04/11 04:10:44 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2024/04/11 04:10:44 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2024/04/11 04:10:44 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2024/04/11 04:10:44 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/Normal/Seek 2024/04/11 04:10:44 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2024/04/11 04:10:44 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2024/04/11 04:10:44 DEBUG : potato: Seek from 5 to 2 === RUN TestReOpen/Normal/AccountRead === RUN TestReOpen/Normal/AccountReadDelay 2024/04/11 04:10:44 DEBUG : potato: Seek from 10 to 0 2024/04/11 04:10:44 DEBUG : potato: Seek from 10 to 0 2024/04/11 04:10:44 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/AccountReadError === RUN TestReOpen/WithRangeOption === RUN TestReOpen/WithRangeOption/Basics 2024/04/11 04:10:44 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/ErrorAtStart === RUN TestReOpen/WithRangeOption/WithErrors 2024/04/11 04:10:44 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2024/04/11 04:10:44 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2024/04/11 04:10:44 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithRangeOption/TooManyErrors 2024/04/11 04:10:44 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2024/04/11 04:10:44 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2024/04/11 04:10:44 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2024/04/11 04:10:44 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithRangeOption/Seek 2024/04/11 04:10:44 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2024/04/11 04:10:44 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2024/04/11 04:10:44 DEBUG : potato: Seek from 5 to 2 2024/04/11 04:10:44 DEBUG : potato: Seek from 7 to 4 === RUN TestReOpen/WithRangeOption/AccountRead === RUN TestReOpen/WithRangeOption/AccountReadDelay 2024/04/11 04:10:44 DEBUG : potato: Seek from 7 to 0 2024/04/11 04:10:44 DEBUG : potato: Seek from 7 to 0 2024/04/11 04:10:44 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/AccountReadError === RUN TestReOpen/WithSeekOption === RUN TestReOpen/WithSeekOption/Basics 2024/04/11 04:10:44 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/ErrorAtStart === RUN TestReOpen/WithSeekOption/WithErrors 2024/04/11 04:10:44 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2024/04/11 04:10:44 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2024/04/11 04:10:44 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithSeekOption/TooManyErrors 2024/04/11 04:10:44 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2024/04/11 04:10:44 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2024/04/11 04:10:44 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2024/04/11 04:10:44 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithSeekOption/Seek 2024/04/11 04:10:44 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2024/04/11 04:10:44 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2024/04/11 04:10:44 DEBUG : potato: Seek from 5 to 2 2024/04/11 04:10:44 DEBUG : potato: Seek from 7 to 5 === RUN TestReOpen/WithSeekOption/AccountRead === RUN TestReOpen/WithSeekOption/AccountReadDelay 2024/04/11 04:10:44 DEBUG : potato: Seek from 8 to 0 2024/04/11 04:10:44 DEBUG : potato: Seek from 8 to 0 2024/04/11 04:10:44 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/AccountReadError === RUN TestReOpen/UnknownSize === RUN TestReOpen/UnknownSize/Basics 2024/04/11 04:10:44 DEBUG : potato: Seek from 9 to 0 === RUN TestReOpen/UnknownSize/ErrorAtStart === RUN TestReOpen/UnknownSize/WithErrors 2024/04/11 04:10:44 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2024/04/11 04:10:44 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2024/04/11 04:10:44 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/UnknownSize/TooManyErrors 2024/04/11 04:10:44 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2024/04/11 04:10:44 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2024/04/11 04:10:44 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2024/04/11 04:10:44 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/UnknownSize/Seek 2024/04/11 04:10:44 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2024/04/11 04:10:44 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2024/04/11 04:10:44 DEBUG : potato: Seek from 5 to 2 2024/04/11 04:10:44 DEBUG : potato: Seek from 7 to 6 === RUN TestReOpen/UnknownSize/AccountRead === RUN TestReOpen/UnknownSize/AccountReadDelay 2024/04/11 04:10:44 DEBUG : potato: Seek from 9 to 0 2024/04/11 04:10:44 DEBUG : potato: Seek from 9 to 0 2024/04/11 04:10:44 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-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", 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.01s) --- PASS: TestCheck/1 (0.19s) --- PASS: TestCheck/2 (0.18s) --- PASS: TestCheck/3 (0.18s) --- PASS: TestCheck/4 (0.19s) --- PASS: TestCheck/5 (0.18s) --- PASS: TestCheck/6 (0.18s) --- PASS: TestCheck/7 (0.18s) === RUN TestCheckFsError 2024/04/11 04:10:49 DEBUG : Creating backend with remote "nonexistent" 2024/04/11 04:10:49 DEBUG : Creating backend with remote "nonexistent" 2024/04/11 04:10:49 DEBUG : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: Waiting for checks to finish 2024/04/11 04:10:49 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/04/11 04:10:49 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: 2 differences found 2024/04/11 04:10:49 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-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" === RUN TestCheckDownload/1 === RUN TestCheckDownload/2 === RUN TestCheckDownload/3 === RUN TestCheckDownload/4 === RUN TestCheckDownload/5 === RUN TestCheckDownload/6 === RUN TestCheckDownload/7 --- PASS: TestCheckDownload (5.99s) --- PASS: TestCheckDownload/1 (0.78s) --- PASS: TestCheckDownload/2 (0.38s) --- PASS: TestCheckDownload/3 (0.37s) --- PASS: TestCheckDownload/4 (0.57s) --- PASS: TestCheckDownload/5 (0.39s) --- PASS: TestCheckDownload/6 (0.42s) --- PASS: TestCheckDownload/7 (0.39s) === RUN TestCheckSizeOnly run.go:180: Remote "B2 bucket rclone-test-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" === RUN TestCheckSizeOnly/1 === RUN TestCheckSizeOnly/2 === RUN TestCheckSizeOnly/3 === RUN TestCheckSizeOnly/4 === RUN TestCheckSizeOnly/5 === RUN TestCheckSizeOnly/6 === RUN TestCheckSizeOnly/7 --- PASS: TestCheckSizeOnly (4.08s) --- PASS: TestCheckSizeOnly/1 (0.18s) --- PASS: TestCheckSizeOnly/2 (0.20s) --- PASS: TestCheckSizeOnly/3 (0.19s) --- PASS: TestCheckSizeOnly/4 (0.19s) --- PASS: TestCheckSizeOnly/5 (0.19s) --- 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-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" 2024/04/11 04:11:00 NOTICE: test.sum: improperly formatted checksum line 4 2024/04/11 04:11:00 NOTICE: test.sum: improperly formatted checksum line 5 2024/04/11 04:11:00 NOTICE: test.sum: improperly formatted checksum line 6 2024/04/11 04:11:00 NOTICE: test.sum: 2 warning(s) suppressed... 2024/04/11 04:11:00 NOTICE: test.sum: improperly formatted checksum line 4 2024/04/11 04:11:00 NOTICE: test.sum: improperly formatted checksum line 5 2024/04/11 04:11:00 NOTICE: test.sum: improperly formatted checksum line 6 2024/04/11 04:11:00 NOTICE: test.sum: 2 warning(s) suppressed... --- PASS: TestParseSumFile (2.22s) === RUN TestCheckSum run.go:180: Remote "B2 bucket rclone-test-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" 2024/04/11 04:11:01 DEBUG : Creating backend with remote "TestB2:rclone-test-yexoyif0zozuyuj9merocub2/data" check_test.go:354: B2 bucket rclone-test-yexoyif0zozuyuj9merocub2 path data lacks md5, skipping --- SKIP: TestCheckSum (1.20s) === RUN TestCheckSumDownload run.go:180: Remote "B2 bucket rclone-test-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" 2024/04/11 04:11:02 DEBUG : Creating backend with remote "TestB2:rclone-test-yexoyif0zozuyuj9merocub2/data" === RUN TestCheckSumDownload/subtest1 === RUN TestCheckSumDownload/subtest2 === RUN TestCheckSumDownload/subtest3 === RUN TestCheckSumDownload/subtest4 === RUN TestCheckSumDownload/subtest5 === RUN TestCheckSumDownload/subtest6 === RUN TestCheckSumDownload/subtest7 --- PASS: TestCheckSumDownload (11.72s) --- PASS: TestCheckSumDownload/subtest1 (0.93s) --- PASS: TestCheckSumDownload/subtest2 (0.73s) --- PASS: TestCheckSumDownload/subtest3 (0.92s) --- PASS: TestCheckSumDownload/subtest4 (0.78s) --- PASS: TestCheckSumDownload/subtest5 (0.76s) --- PASS: TestCheckSumDownload/subtest6 (0.75s) --- PASS: TestCheckSumDownload/subtest7 (0.75s) === RUN TestApplyTransforms 2024/04/11 04:11:14 DEBUG : Creating backend with remote "TestB2:rclone-test-zuruvez2cacaxar5keruwiz3" 2024/04/11 04:11:14 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/04/11 04:11:14 DEBUG : Creating backend with remote "/tmp/rclone1823309564" run.go:180: Remote "B2 bucket rclone-test-zuruvez2cacaxar5keruwiz3", Local "Local file system at /tmp/rclone1823309564", Modify Window "1ms" upper checkfile vs. lower remote (without normalization) 2024/04/11 04:11:17 ERROR : hello, world!: sum not found 2024/04/11 04:11:17 ERROR : HELLO, WORLD!: file not in B2 bucket rclone-test-zuruvez2cacaxar5keruwiz3 2024/04/11 04:11:17 NOTICE: B2 bucket rclone-test-zuruvez2cacaxar5keruwiz3: 1 files missing 2024/04/11 04:11:17 NOTICE: 1 hashes missing 2024/04/11 04:11:17 NOTICE: B2 bucket rclone-test-zuruvez2cacaxar5keruwiz3: 2 differences found 2024/04/11 04:11:17 NOTICE: B2 bucket rclone-test-zuruvez2cacaxar5keruwiz3: 2 errors while checking upper checkfile vs. lower remote (with normalization) 2024/04/11 04:11:17 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2024/04/11 04:11:17 NOTICE: B2 bucket rclone-test-zuruvez2cacaxar5keruwiz3: 0 differences found 2024/04/11 04:11:17 NOTICE: B2 bucket rclone-test-zuruvez2cacaxar5keruwiz3: 1 matching files 2024/04/11 04:11:17 DEBUG : Creating backend with remote "TestB2:rclone-test-diyokit4kosifof0yiloyag4" 2024/04/11 04:11:17 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/04/11 04:11:17 DEBUG : Creating backend with remote "/tmp/rclone2345343115" run.go:180: Remote "B2 bucket rclone-test-diyokit4kosifof0yiloyag4", Local "Local file system at /tmp/rclone2345343115", Modify Window "1ms" lower checkfile vs. upper remote (without normalization) 2024/04/11 04:11:20 ERROR : HELLO, WORLD!: sum not found 2024/04/11 04:11:20 ERROR : hello, world!: file not in B2 bucket rclone-test-diyokit4kosifof0yiloyag4 2024/04/11 04:11:20 NOTICE: B2 bucket rclone-test-diyokit4kosifof0yiloyag4: 1 files missing 2024/04/11 04:11:20 NOTICE: 1 hashes missing 2024/04/11 04:11:20 NOTICE: B2 bucket rclone-test-diyokit4kosifof0yiloyag4: 2 differences found 2024/04/11 04:11:20 NOTICE: B2 bucket rclone-test-diyokit4kosifof0yiloyag4: 2 errors while checking lower checkfile vs. upper remote (with normalization) 2024/04/11 04:11:20 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2024/04/11 04:11:20 NOTICE: B2 bucket rclone-test-diyokit4kosifof0yiloyag4: 0 differences found 2024/04/11 04:11:20 NOTICE: B2 bucket rclone-test-diyokit4kosifof0yiloyag4: 1 matching files 2024/04/11 04:11:20 DEBUG : Creating backend with remote "TestB2:rclone-test-sedawed8qeyuweb9getebik7" 2024/04/11 04:11:20 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/04/11 04:11:20 DEBUG : Creating backend with remote "/tmp/rclone2316777764" run.go:180: Remote "B2 bucket rclone-test-sedawed8qeyuweb9getebik7", Local "Local file system at /tmp/rclone2316777764", Modify Window "1ms" lower checkfile vs. upperlowermixed remote (without normalization) 2024/04/11 04:11:22 ERROR : HeLlO, wOrLd!: sum not found 2024/04/11 04:11:22 ERROR : hello, world!: file not in B2 bucket rclone-test-sedawed8qeyuweb9getebik7 2024/04/11 04:11:22 NOTICE: B2 bucket rclone-test-sedawed8qeyuweb9getebik7: 1 files missing 2024/04/11 04:11:22 NOTICE: 1 hashes missing 2024/04/11 04:11:22 NOTICE: B2 bucket rclone-test-sedawed8qeyuweb9getebik7: 2 differences found 2024/04/11 04:11:22 NOTICE: B2 bucket rclone-test-sedawed8qeyuweb9getebik7: 2 errors while checking lower checkfile vs. upperlowermixed remote (with normalization) 2024/04/11 04:11:22 DEBUG : HeLlO, wOrLd!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2024/04/11 04:11:22 NOTICE: B2 bucket rclone-test-sedawed8qeyuweb9getebik7: 0 differences found 2024/04/11 04:11:22 NOTICE: B2 bucket rclone-test-sedawed8qeyuweb9getebik7: 1 matching files 2024/04/11 04:11:22 DEBUG : Creating backend with remote "TestB2:rclone-test-yayecet1panixem1vetetoz6" 2024/04/11 04:11:22 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/04/11 04:11:22 DEBUG : Creating backend with remote "/tmp/rclone598768532" run.go:180: Remote "B2 bucket rclone-test-yayecet1panixem1vetetoz6", Local "Local file system at /tmp/rclone598768532", Modify Window "1ms" upperlowermixed checkfile vs. upper remote (without normalization) 2024/04/11 04:11:24 ERROR : HELLO, WORLD!: sum not found 2024/04/11 04:11:24 ERROR : HeLlO, wOrLd!: file not in B2 bucket rclone-test-yayecet1panixem1vetetoz6 2024/04/11 04:11:24 NOTICE: B2 bucket rclone-test-yayecet1panixem1vetetoz6: 1 files missing 2024/04/11 04:11:24 NOTICE: 1 hashes missing 2024/04/11 04:11:24 NOTICE: B2 bucket rclone-test-yayecet1panixem1vetetoz6: 2 differences found 2024/04/11 04:11:24 NOTICE: B2 bucket rclone-test-yayecet1panixem1vetetoz6: 2 errors while checking upperlowermixed checkfile vs. upper remote (with normalization) 2024/04/11 04:11:25 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2024/04/11 04:11:25 NOTICE: B2 bucket rclone-test-yayecet1panixem1vetetoz6: 0 differences found 2024/04/11 04:11:25 NOTICE: B2 bucket rclone-test-yayecet1panixem1vetetoz6: 1 matching files 2024/04/11 04:11:25 DEBUG : Creating backend with remote "TestB2:rclone-test-mefesaq0wunuyir2reluyip3" 2024/04/11 04:11:25 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/04/11 04:11:25 DEBUG : Creating backend with remote "/tmp/rclone3127600722" run.go:180: Remote "B2 bucket rclone-test-mefesaq0wunuyir2reluyip3", Local "Local file system at /tmp/rclone3127600722", Modify Window "1ms" NFD checkfile vs. NFC remote (without normalization) 2024/04/11 04:11:27 ERROR : 測試_Русский___ě_áñ: sum not found 2024/04/11 04:11:27 ERROR : 測試_Русский___ě_áñ: file not in B2 bucket rclone-test-mefesaq0wunuyir2reluyip3 2024/04/11 04:11:27 NOTICE: B2 bucket rclone-test-mefesaq0wunuyir2reluyip3: 1 files missing 2024/04/11 04:11:27 NOTICE: 1 hashes missing 2024/04/11 04:11:27 NOTICE: B2 bucket rclone-test-mefesaq0wunuyir2reluyip3: 2 differences found 2024/04/11 04:11:27 NOTICE: B2 bucket rclone-test-mefesaq0wunuyir2reluyip3: 2 errors while checking NFD checkfile vs. NFC remote (with normalization) 2024/04/11 04:11:28 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2024/04/11 04:11:28 NOTICE: B2 bucket rclone-test-mefesaq0wunuyir2reluyip3: 0 differences found 2024/04/11 04:11:28 NOTICE: B2 bucket rclone-test-mefesaq0wunuyir2reluyip3: 1 matching files 2024/04/11 04:11:28 DEBUG : Creating backend with remote "TestB2:rclone-test-welavem5matekoc9zusewej0" 2024/04/11 04:11:28 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/04/11 04:11:28 DEBUG : Creating backend with remote "/tmp/rclone3229193459" run.go:180: Remote "B2 bucket rclone-test-welavem5matekoc9zusewej0", Local "Local file system at /tmp/rclone3229193459", Modify Window "1ms" NFC checkfile vs. NFD remote (without normalization) 2024/04/11 04:11:30 ERROR : 測試_Русский___ě_áñ: sum not found 2024/04/11 04:11:30 ERROR : 測試_Русский___ě_áñ: file not in B2 bucket rclone-test-welavem5matekoc9zusewej0 2024/04/11 04:11:30 NOTICE: B2 bucket rclone-test-welavem5matekoc9zusewej0: 1 files missing 2024/04/11 04:11:30 NOTICE: 1 hashes missing 2024/04/11 04:11:30 NOTICE: B2 bucket rclone-test-welavem5matekoc9zusewej0: 2 differences found 2024/04/11 04:11:30 NOTICE: B2 bucket rclone-test-welavem5matekoc9zusewej0: 2 errors while checking NFC checkfile vs. NFD remote (with normalization) 2024/04/11 04:11:30 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2024/04/11 04:11:30 NOTICE: B2 bucket rclone-test-welavem5matekoc9zusewej0: 0 differences found 2024/04/11 04:11:30 NOTICE: B2 bucket rclone-test-welavem5matekoc9zusewej0: 1 matching files 2024/04/11 04:11:30 DEBUG : Creating backend with remote "TestB2:rclone-test-cuvebav7nipicuh1bagipip4" 2024/04/11 04:11:30 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/04/11 04:11:30 DEBUG : Creating backend with remote "/tmp/rclone791331452" run.go:180: Remote "B2 bucket rclone-test-cuvebav7nipicuh1bagipip4", Local "Local file system at /tmp/rclone791331452", Modify Window "1ms" NFDx2 checkfile vs. both remote (without normalization) 2024/04/11 04:11:33 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2024/04/11 04:11:33 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-cuvebav7nipicuh1bagipip4 2024/04/11 04:11:33 NOTICE: B2 bucket rclone-test-cuvebav7nipicuh1bagipip4: 1 files missing 2024/04/11 04:11:33 NOTICE: 1 hashes missing 2024/04/11 04:11:33 NOTICE: B2 bucket rclone-test-cuvebav7nipicuh1bagipip4: 2 differences found 2024/04/11 04:11:33 NOTICE: B2 bucket rclone-test-cuvebav7nipicuh1bagipip4: 2 errors while checking NFDx2 checkfile vs. both remote (with normalization) 2024/04/11 04:11:33 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2024/04/11 04:11:33 NOTICE: B2 bucket rclone-test-cuvebav7nipicuh1bagipip4: 0 differences found 2024/04/11 04:11:33 NOTICE: B2 bucket rclone-test-cuvebav7nipicuh1bagipip4: 1 matching files 2024/04/11 04:11:33 DEBUG : Creating backend with remote "TestB2:rclone-test-fejetiv1zukapok8bohexer2" 2024/04/11 04:11:33 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/04/11 04:11:33 DEBUG : Creating backend with remote "/tmp/rclone1171688021" run.go:180: Remote "B2 bucket rclone-test-fejetiv1zukapok8bohexer2", Local "Local file system at /tmp/rclone1171688021", Modify Window "1ms" NFCx2 checkfile vs. both remote (without normalization) 2024/04/11 04:11:35 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2024/04/11 04:11:35 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-fejetiv1zukapok8bohexer2 2024/04/11 04:11:35 NOTICE: B2 bucket rclone-test-fejetiv1zukapok8bohexer2: 1 files missing 2024/04/11 04:11:35 NOTICE: 1 hashes missing 2024/04/11 04:11:35 NOTICE: B2 bucket rclone-test-fejetiv1zukapok8bohexer2: 2 differences found 2024/04/11 04:11:35 NOTICE: B2 bucket rclone-test-fejetiv1zukapok8bohexer2: 2 errors while checking NFCx2 checkfile vs. both remote (with normalization) 2024/04/11 04:11:35 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2024/04/11 04:11:35 NOTICE: B2 bucket rclone-test-fejetiv1zukapok8bohexer2: 0 differences found 2024/04/11 04:11:35 NOTICE: B2 bucket rclone-test-fejetiv1zukapok8bohexer2: 1 matching files 2024/04/11 04:11:35 DEBUG : Creating backend with remote "TestB2:rclone-test-zasalaq0kijabiw3kovoval3" 2024/04/11 04:11:35 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/04/11 04:11:36 DEBUG : Creating backend with remote "/tmp/rclone1966275682" run.go:180: Remote "B2 bucket rclone-test-zasalaq0kijabiw3kovoval3", Local "Local file system at /tmp/rclone1966275682", Modify Window "1ms" both checkfile vs. NFDx2 remote (without normalization) 2024/04/11 04:11:37 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2024/04/11 04:11:37 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-zasalaq0kijabiw3kovoval3 2024/04/11 04:11:37 NOTICE: B2 bucket rclone-test-zasalaq0kijabiw3kovoval3: 1 files missing 2024/04/11 04:11:37 NOTICE: 1 hashes missing 2024/04/11 04:11:37 NOTICE: B2 bucket rclone-test-zasalaq0kijabiw3kovoval3: 2 differences found 2024/04/11 04:11:37 NOTICE: B2 bucket rclone-test-zasalaq0kijabiw3kovoval3: 2 errors while checking both checkfile vs. NFDx2 remote (with normalization) 2024/04/11 04:11:38 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2024/04/11 04:11:38 NOTICE: B2 bucket rclone-test-zasalaq0kijabiw3kovoval3: 0 differences found 2024/04/11 04:11:38 NOTICE: B2 bucket rclone-test-zasalaq0kijabiw3kovoval3: 1 matching files 2024/04/11 04:11:38 DEBUG : Creating backend with remote "TestB2:rclone-test-ficurig7gezokux8cifufew6" 2024/04/11 04:11:38 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/04/11 04:11:38 DEBUG : Creating backend with remote "/tmp/rclone1718756897" run.go:180: Remote "B2 bucket rclone-test-ficurig7gezokux8cifufew6", Local "Local file system at /tmp/rclone1718756897", Modify Window "1ms" both checkfile vs. NFCx2 remote (without normalization) 2024/04/11 04:11:40 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2024/04/11 04:11:40 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-ficurig7gezokux8cifufew6 2024/04/11 04:11:40 NOTICE: B2 bucket rclone-test-ficurig7gezokux8cifufew6: 1 files missing 2024/04/11 04:11:40 NOTICE: 1 hashes missing 2024/04/11 04:11:40 NOTICE: B2 bucket rclone-test-ficurig7gezokux8cifufew6: 2 differences found 2024/04/11 04:11:40 NOTICE: B2 bucket rclone-test-ficurig7gezokux8cifufew6: 2 errors while checking both checkfile vs. NFCx2 remote (with normalization) 2024/04/11 04:11:40 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2024/04/11 04:11:40 NOTICE: B2 bucket rclone-test-ficurig7gezokux8cifufew6: 0 differences found 2024/04/11 04:11:40 NOTICE: B2 bucket rclone-test-ficurig7gezokux8cifufew6: 1 matching files 2024/04/11 04:11:40 DEBUG : B2 bucket rclone-test-ficurig7gezokux8cifufew6: Purge remote 2024/04/11 04:11:40 INFO : B2 bucket rclone-test-ficurig7gezokux8cifufew6: cleaning bucket "rclone-test-ficurig7gezokux8cifufew6" of all files 2024/04/11 04:11:41 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_z0f72ad3350adcbbb8fe80e1b_f110ebd09bc407bec_d20240411_m041139_c001_v0001041_t0039_u01712808699941") 2024/04/11 04:11:42 DEBUG : B2 bucket rclone-test-zasalaq0kijabiw3kovoval3: Purge remote 2024/04/11 04:11:42 INFO : B2 bucket rclone-test-zasalaq0kijabiw3kovoval3: cleaning bucket "rclone-test-zasalaq0kijabiw3kovoval3" of all files 2024/04/11 04:11:43 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_zbfa29d9350adcbbb8fe80e1b_f1143b27da0500251_d20240411_m041137_c001_v0001181_t0010_u01712808697322") 2024/04/11 04:11:44 DEBUG : B2 bucket rclone-test-fejetiv1zukapok8bohexer2: Purge remote 2024/04/11 04:11:44 INFO : B2 bucket rclone-test-fejetiv1zukapok8bohexer2: cleaning bucket "rclone-test-fejetiv1zukapok8bohexer2" of all files 2024/04/11 04:11:44 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_zdfd28df350adcbbb8fe80e1b_f1080a96ca2756e02_d20240411_m041134_c001_v0001145_t0036_u01712808694754") 2024/04/11 04:11:47 DEBUG : B2 bucket rclone-test-cuvebav7nipicuh1bagipip4: Purge remote 2024/04/11 04:11:47 INFO : B2 bucket rclone-test-cuvebav7nipicuh1bagipip4: cleaning bucket "rclone-test-cuvebav7nipicuh1bagipip4" of all files 2024/04/11 04:11:48 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_z0f228d5350adcbbb8fe80e1b_f1107e67363b5d573_d20240411_m041132_c001_v0001179_t0042_u01712808692397") 2024/04/11 04:11:52 DEBUG : B2 bucket rclone-test-welavem5matekoc9zusewej0: Purge remote 2024/04/11 04:11:52 INFO : B2 bucket rclone-test-welavem5matekoc9zusewej0: cleaning bucket "rclone-test-welavem5matekoc9zusewej0" of all files 2024/04/11 04:11:53 DEBUG : 測試_Русский___ě_áñ: Deleting (id "4_zff027da350adcbbb8fe80e1b_f105fc7d6f60a0756_d20240411_m041129_c001_v0001172_t0052_u01712808689460") 2024/04/11 04:11:54 DEBUG : B2 bucket rclone-test-mefesaq0wunuyir2reluyip3: Purge remote 2024/04/11 04:11:54 INFO : B2 bucket rclone-test-mefesaq0wunuyir2reluyip3: cleaning bucket "rclone-test-mefesaq0wunuyir2reluyip3" of all files 2024/04/11 04:11:54 DEBUG : 測試_Русский___ě_áñ: Deleting (id "4_z7f727d1350adcbbb8fe80e1b_f116e28bbc1f73368_d20240411_m041126_c001_v0001100_t0043_u01712808686963") 2024/04/11 04:11:55 DEBUG : B2 bucket rclone-test-yayecet1panixem1vetetoz6: Purge remote 2024/04/11 04:11:55 INFO : B2 bucket rclone-test-yayecet1panixem1vetetoz6: cleaning bucket "rclone-test-yayecet1panixem1vetetoz6" of all files 2024/04/11 04:11:55 DEBUG : HELLO, WORLD!: Deleting (id "4_z4f626d6350adcbbb8fe80e1b_f110d915b4d2106e7_d20240411_m041124_c001_v0001150_t0012_u01712808684278") 2024/04/11 04:11:59 DEBUG : B2 bucket rclone-test-sedawed8qeyuweb9getebik7: Purge remote 2024/04/11 04:11:59 INFO : B2 bucket rclone-test-sedawed8qeyuweb9getebik7: cleaning bucket "rclone-test-sedawed8qeyuweb9getebik7" of all files 2024/04/11 04:11:59 DEBUG : HeLlO, wOrLd!: Deleting (id "4_z0f325dd350adcbbb8fe80e1b_f1095d89c05c09d84_d20240411_m041121_c001_v0001179_t0023_u01712808681912") 2024/04/11 04:12:00 DEBUG : B2 bucket rclone-test-diyokit4kosifof0yiloyag4: Purge remote 2024/04/11 04:12:00 INFO : B2 bucket rclone-test-diyokit4kosifof0yiloyag4: cleaning bucket "rclone-test-diyokit4kosifof0yiloyag4" of all files 2024/04/11 04:12:00 DEBUG : HELLO, WORLD!: Deleting (id "4_zbff25d2350adcbbb8fe80e1b_f105d97954ece9af7_d20240411_m041119_c001_v0001179_t0031_u01712808679264") 2024/04/11 04:12:03 DEBUG : B2 bucket rclone-test-zuruvez2cacaxar5keruwiz3: Purge remote 2024/04/11 04:12:03 INFO : B2 bucket rclone-test-zuruvez2cacaxar5keruwiz3: cleaning bucket "rclone-test-zuruvez2cacaxar5keruwiz3" of all files 2024/04/11 04:12:03 DEBUG : hello, world!: Deleting (id "4_zffd24d6350adcbbb8fe80e1b_f103e60963c337509_d20240411_m041116_c001_v0001030_t0005_u01712808676344") --- PASS: TestApplyTransforms (53.18s) === RUN TestTruncateString --- PASS: TestTruncateString (0.00s) === RUN TestCopyFile run.go:180: Remote "B2 bucket rclone-test-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" 2024/04/11 04:12:08 DEBUG : file1: Need to transfer - File not found at Destination 2024/04/11 04:12:08 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2024/04/11 04:12:08 INFO : file1: Copied (new) to: sub/file2 2024/04/11 04:12:09 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/04/11 04:12:09 DEBUG : file1: Unchanged skipping 2024/04/11 04:12:09 DEBUG : B2 bucket rclone-test-yexoyif0zozuyuj9merocub2: don't need to copy/move sub/file2, it is already at target location --- PASS: TestCopyFile (2.60s) === RUN TestCopyLongFile run.go:180: Remote "B2 bucket rclone-test-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" copy_test.go:152: Test only runs on local --- SKIP: TestCopyLongFile (0.36s) === RUN TestCopyFileBackupDir run.go:180: Remote "B2 bucket rclone-test-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" 2024/04/11 04:12:11 DEBUG : Creating backend with remote "TestB2:rclone-test-yexoyif0zozuyuj9merocub2/backup" 2024/04/11 04:12:12 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18) 2024/04/11 04:12:14 DEBUG : dst/file1: sha1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK 2024/04/11 04:12:14 INFO : dst/file1: Copied (server-side copy) 2024/04/11 04:12:14 INFO : dst/file1: Deleted 2024/04/11 04:12:15 DEBUG : dst/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2024/04/11 04:12:15 INFO : dst/file1: Copied (new) --- PASS: TestCopyFileBackupDir (5.52s) === RUN TestCopyFileCompareDest run.go:180: Remote "B2 bucket rclone-test-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" 2024/04/11 04:12:16 DEBUG : Creating backend with remote "TestB2:rclone-test-yexoyif0zozuyuj9merocub2/dst" 2024/04/11 04:12:16 DEBUG : Creating backend with remote "TestB2:rclone-test-yexoyif0zozuyuj9merocub2/CompareDest" 2024/04/11 04:12:17 DEBUG : one: Need to transfer - File not found at Destination 2024/04/11 04:12:18 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2024/04/11 04:12:18 INFO : one: Copied (new) 2024/04/11 04:12:19 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/04/11 04:12:19 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2024/04/11 04:12:19 INFO : one: Copied (replaced existing) 2024/04/11 04:12:20 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/04/11 04:12:21 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2024/04/11 04:12:21 DEBUG : one: Destination found in --compare-dest, skipping 2024/04/11 04:12:21 DEBUG : two: Need to transfer - File not found at Destination 2024/04/11 04:12:21 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2024/04/11 04:12:21 DEBUG : two: Destination found in --compare-dest, skipping 2024/04/11 04:12:22 DEBUG : two: Need to transfer - File not found at Destination 2024/04/11 04:12:22 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2024/04/11 04:12:22 DEBUG : two: Destination found in --compare-dest, skipping 2024/04/11 04:12:23 DEBUG : two: Need to transfer - File not found at Destination 2024/04/11 04:12:23 DEBUG : two: Sizes differ (src 5 vs dst 3) 2024/04/11 04:12:23 DEBUG : two: sha1 = a4be8877ad88f3109a6411985a1f359e10e2d930 OK 2024/04/11 04:12:23 INFO : two: Copied (new) --- PASS: TestCopyFileCompareDest (8.86s) === RUN TestCopyFileCopyDest run.go:180: Remote "B2 bucket rclone-test-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" 2024/04/11 04:12:24 DEBUG : Creating backend with remote "TestB2:rclone-test-yexoyif0zozuyuj9merocub2/dst" 2024/04/11 04:12:25 DEBUG : Creating backend with remote "TestB2:rclone-test-yexoyif0zozuyuj9merocub2/CopyDest" 2024/04/11 04:12:26 DEBUG : one: Need to transfer - File not found at Destination 2024/04/11 04:12:28 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2024/04/11 04:12:28 INFO : one: Copied (new) 2024/04/11 04:12:28 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/04/11 04:12:29 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2024/04/11 04:12:29 INFO : one: Copied (replaced existing) 2024/04/11 04:12:30 DEBUG : Creating backend with remote "TestB2:rclone-test-yexoyif0zozuyuj9merocub2/BackupDir" 2024/04/11 04:12:31 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/04/11 04:12:31 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2024/04/11 04:12:31 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/04/11 04:12:32 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2024/04/11 04:12:32 INFO : one: Copied (server-side copy) 2024/04/11 04:12:32 INFO : one: Deleted 2024/04/11 04:12:33 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2024/04/11 04:12:33 INFO : one: Copied (server-side copy) 2024/04/11 04:12:33 DEBUG : one: Destination found in --copy-dest, using server-side copy 2024/04/11 04:12:34 DEBUG : two: Need to transfer - File not found at Destination 2024/04/11 04:12:34 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2024/04/11 04:12:34 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2024/04/11 04:12:34 INFO : two: Copied (server-side copy) 2024/04/11 04:12:34 DEBUG : two: Destination found in --copy-dest, using server-side copy 2024/04/11 04:12:34 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2024/04/11 04:12:34 DEBUG : two: Unchanged skipping 2024/04/11 04:12:35 DEBUG : three: Need to transfer - File not found at Destination 2024/04/11 04:12:35 DEBUG : three: Sizes differ (src 7 vs dst 5) 2024/04/11 04:12:35 DEBUG : three: Destination not found in --copy-dest 2024/04/11 04:12:36 DEBUG : three: sha1 = 370e613335f3cf2f19b2a2d35a86402f55c659f8 OK 2024/04/11 04:12:36 INFO : three: Copied (new) --- PASS: TestCopyFileCopyDest (13.61s) === RUN TestCopyInplace run.go:180: Remote "B2 bucket rclone-test-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" copy_test.go:369: Partial uploads not supported --- SKIP: TestCopyInplace (0.37s) === RUN TestCopyLongFileName run.go:180: Remote "B2 bucket rclone-test-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" copy_test.go:402: Partial uploads not supported --- SKIP: TestCopyLongFileName (0.37s) === RUN TestCopyFileMaxTransfer run.go:180: Remote "B2 bucket rclone-test-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" 2024/04/11 04:12:39 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination 2024/04/11 04:12:39 DEBUG : TestCopyFileMaxTransfer/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2024/04/11 04:12:39 INFO : TestCopyFileMaxTransfer/file1: Copied (new) 2024/04/11 04:12:40 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination 2024/04/11 04:12:40 ERROR : TestCopyFileMaxTransfer/file2: Failed to copy: Post "https://pod-000-1179-18.backblaze.com/b2api/v1/b2_upload_file/df529d33507dcbbb8fe80e1b/c001_v0001179_t0057": max transfer limit reached as set by --max-transfer 2024/04/11 04:12:40 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination 2024/04/11 04:12:40 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination 2024/04/11 04:12:41 DEBUG : TestCopyFileMaxTransfer/file4: sha1 = 6111b7efa8cdf365eba3a1118cf64c4fee4f5f5e OK 2024/04/11 04:12:41 INFO : TestCopyFileMaxTransfer/file4: Copied (new) --- PASS: TestCopyFileMaxTransfer (3.58s) === RUN TestDeduplicateInteractive run.go:180: Remote "B2 bucket rclone-test-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateInteractive (0.36s) === RUN TestDeduplicateSkip run.go:180: Remote "B2 bucket rclone-test-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSkip (0.36s) === RUN TestDeduplicateSizeOnly run.go:180: Remote "B2 bucket rclone-test-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSizeOnly (0.37s) === RUN TestDeduplicateFirst run.go:180: Remote "B2 bucket rclone-test-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateFirst (0.36s) === RUN TestDeduplicateNewest run.go:180: Remote "B2 bucket rclone-test-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", 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-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" 2024/04/11 04:12:46 INFO : B2 bucket rclone-test-yexoyif0zozuyuj9merocub2: Looking for duplicate sha1 hashes using newest mode. 2024/04/11 04:12:46 NOTICE: fc1d2120ecf1a62214356c031b8013b0aa1039bc: Found 3 files with duplicate sha1 hashes 2024/04/11 04:12:46 INFO : one: Deleted 2024/04/11 04:12:47 INFO : also/one: Deleted 2024/04/11 04:12:47 NOTICE: fc1d2120ecf1a62214356c031b8013b0aa1039bc: Deleted 2 extra copies --- PASS: TestDeduplicateNewestByHash (3.39s) === RUN TestDeduplicateOldest run.go:180: Remote "B2 bucket rclone-test-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateOldest (0.37s) === RUN TestDeduplicateLargest run.go:180: Remote "B2 bucket rclone-test-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateLargest (0.42s) === RUN TestDeduplicateSmallest run.go:180: Remote "B2 bucket rclone-test-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", 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-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateRename (0.37s) === RUN TestMergeDirs run.go:180: Remote "B2 bucket rclone-test-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" dedupe_test.go:256: Can't merge directories --- SKIP: TestMergeDirs (0.38s) === RUN TestListDirSorted run.go:180: Remote "B2 bucket rclone-test-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" 2024/04/11 04:12:52 DEBUG : a.txt: Excluded (Size Filter) 2024/04/11 04:12:52 DEBUG : a.txt: Excluded 2024/04/11 04:12:53 DEBUG : sub dir/hello world: Excluded (Size Filter) 2024/04/11 04:12:53 DEBUG : sub dir/hello world: Excluded 2024/04/11 04:12:53 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2024/04/11 04:12:53 DEBUG : sub dir/hello world2: Excluded 2024/04/11 04:12:53 DEBUG : sub dir/hello world: Excluded (Size Filter) 2024/04/11 04:12:53 DEBUG : sub dir/hello world: Excluded 2024/04/11 04:12:53 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2024/04/11 04:12:53 DEBUG : sub dir/hello world2: Excluded 2024/04/11 04:12:53 DEBUG : sub dir/ignore dir: Excluded 2024/04/11 04:12:54 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSorted (6.37s) === RUN TestListJSON run.go:180: Remote "B2 bucket rclone-test-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", 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/04/11 04:12:58 ERROR : file1: Failed to read hash: hash type not supported === RUN TestListJSON/Metadata --- PASS: TestListJSON (3.57s) --- PASS: TestListJSON/Default (0.18s) --- PASS: TestListJSON/FilesOnly (0.19s) --- PASS: TestListJSON/DirsOnly (0.19s) --- PASS: TestListJSON/Recurse (0.18s) --- PASS: TestListJSON/SubDir (0.18s) --- PASS: TestListJSON/NoModTime (0.18s) --- PASS: TestListJSON/NoMimeType (0.19s) --- PASS: TestListJSON/ShowHash (0.18s) --- PASS: TestListJSON/HashTypes (0.19s) --- PASS: TestListJSON/Metadata (0.19s) === RUN TestStatJSON run.go:180: Remote "B2 bucket rclone-test-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", 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/04/11 04:13:03 DEBUG : Creating backend with remote "TestB2:rclone-test-yexoyif0zozuyuj9merocub2/notfound" --- PASS: TestStatJSON (5.83s) --- PASS: TestStatJSON/Root (0.19s) --- PASS: TestStatJSON/RootFilesOnly (0.00s) --- PASS: TestStatJSON/RootDirsOnly (0.19s) --- PASS: TestStatJSON/Dir (0.74s) --- PASS: TestStatJSON/DirWithTrailingSlash (0.19s) --- PASS: TestStatJSON/File (0.19s) --- PASS: TestStatJSON/NotFound (0.39s) --- PASS: TestStatJSON/DirFilesOnly (0.18s) --- PASS: TestStatJSON/FileFilesOnly (0.18s) --- PASS: TestStatJSON/NotFoundFilesOnly (0.18s) --- PASS: TestStatJSON/DirDirsOnly (0.18s) --- PASS: TestStatJSON/FileDirsOnly (0.19s) --- PASS: TestStatJSON/NotFoundDirsOnly (0.19s) --- PASS: TestStatJSON/RootNotFound (1.13s) === RUN TestMkdir run.go:180: Remote "B2 bucket rclone-test-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" 2024/04/11 04:13:05 DEBUG : B2 bucket rclone-test-yexoyif0zozuyuj9merocub2: Making directory 2024/04/11 04:13:05 DEBUG : B2 bucket rclone-test-yexoyif0zozuyuj9merocub2: Making directory --- PASS: TestMkdir (0.55s) === RUN TestLsd run.go:180: Remote "B2 bucket rclone-test-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" --- PASS: TestLsd (1.59s) === RUN TestLs run.go:180: Remote "B2 bucket rclone-test-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" --- PASS: TestLs (1.64s) === RUN TestLsWithFilesFrom run.go:180: Remote "B2 bucket rclone-test-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" 2024/04/11 04:13:10 DEBUG : empty space: Excluded (FilesFrom Filter) 2024/04/11 04:13:10 DEBUG : empty space: Excluded --- PASS: TestLsWithFilesFrom (2.09s) === RUN TestLsLong run.go:180: Remote "B2 bucket rclone-test-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" --- PASS: TestLsLong (1.90s) === RUN TestHashSums run.go:180: Remote "B2 bucket rclone-test-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" === RUN TestHashSums/Sha1 === RUN TestHashSums/Sha1Download --- PASS: TestHashSums (2.28s) --- PASS: TestHashSums/Sha1 (0.18s) --- PASS: TestHashSums/Sha1Download (0.40s) === RUN TestHashSumsWithErrors 2024/04/11 04:13:15 DEBUG : Creating backend with remote ":memory:" 2024/04/11 04:13:15 ERROR : file1: hash unsupported: hash type not supported --- PASS: TestHashSumsWithErrors (0.00s) === RUN TestHashStream 2024/04/11 04:13:15 DEBUG : Creating md5 hash of 0 bytes read from input stream 2024/04/11 04:13:15 DEBUG : Creating md5 hash of 0 bytes read from input stream 2024/04/11 04:13:15 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2024/04/11 04:13:15 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2024/04/11 04:13:15 DEBUG : Creating md5 hash of 12 bytes read from input stream 2024/04/11 04:13:15 DEBUG : Creating md5 hash of 12 bytes read from input stream 2024/04/11 04:13:15 DEBUG : Creating sha1 hash of 12 bytes read from input stream 2024/04/11 04:13:15 DEBUG : Creating sha1 hash of 12 bytes read from input stream --- PASS: TestHashStream (0.00s) === RUN TestSuffixName --- PASS: TestSuffixName (0.00s) === RUN TestCount run.go:180: Remote "B2 bucket rclone-test-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" --- PASS: TestCount (3.11s) === RUN TestDelete run.go:180: Remote "B2 bucket rclone-test-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" 2024/04/11 04:13:19 DEBUG : Waiting for deletions to finish 2024/04/11 04:13:20 DEBUG : large: Excluded (Size Filter) 2024/04/11 04:13:20 INFO : medium: Deleted 2024/04/11 04:13:20 INFO : small: Deleted --- PASS: TestDelete (2.32s) === RUN TestMaxDelete run.go:180: Remote "B2 bucket rclone-test-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" 2024/04/11 04:13:22 DEBUG : Waiting for deletions to finish 2024/04/11 04:13:22 ERROR : small: Got fatal error on delete: --max-delete threshold reached 2024/04/11 04:13:22 INFO : large: Deleted 2024/04/11 04:13:22 INFO : medium: Deleted --- PASS: TestMaxDelete (2.63s) === RUN TestMaxDeleteSizeLargeFile run.go:180: Remote "B2 bucket rclone-test-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" 2024/04/11 04:13:24 DEBUG : Waiting for deletions to finish 2024/04/11 04:13:25 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached 2024/04/11 04:13:25 INFO : small: Deleted 2024/04/11 04:13:25 INFO : medium: Deleted --- PASS: TestMaxDeleteSizeLargeFile (2.62s) === RUN TestMaxDeleteSize run.go:180: Remote "B2 bucket rclone-test-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" 2024/04/11 04:13:27 DEBUG : Waiting for deletions to finish 2024/04/11 04:13:27 ERROR : small: Got fatal error on delete: --max-delete-size threshold reached 2024/04/11 04:13:27 INFO : large: Deleted 2024/04/11 04:13:27 INFO : medium: Deleted --- PASS: TestMaxDeleteSize (2.44s) === RUN TestRetry 2024/04/11 04:13:28 DEBUG : Received error: EOF - low level retry 1/5 2024/04/11 04:13:28 DEBUG : Received error: EOF - low level retry 2/5 2024/04/11 04:13:28 DEBUG : Received error: EOF - low level retry 1/5 2024/04/11 04:13:28 DEBUG : Received error: EOF - low level retry 2/5 2024/04/11 04:13:28 DEBUG : Received error: EOF - low level retry 3/5 2024/04/11 04:13:28 DEBUG : Received error: EOF - low level retry 4/5 2024/04/11 04:13:28 DEBUG : Received error: EOF - low level retry 5/5 --- PASS: TestRetry (0.00s) === RUN TestCat run.go:180: Remote "B2 bucket rclone-test-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" --- PASS: TestCat (5.02s) === RUN TestPurge 2024/04/11 04:13:33 DEBUG : Creating backend with remote "TestB2:rclone-test-zegesic6ruletem3mihowaq7" 2024/04/11 04:13:33 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/04/11 04:13:34 DEBUG : Creating backend with remote "/tmp/rclone122867559" run.go:180: Remote "B2 bucket rclone-test-zegesic6ruletem3mihowaq7", Local "Local file system at /tmp/rclone122867559", Modify Window "1ms" 2024/04/11 04:13:35 DEBUG : A2: Making directory 2024/04/11 04:13:35 DEBUG : A1/B2: Making directory 2024/04/11 04:13:35 DEBUG : A1/B2/C2: Making directory 2024/04/11 04:13:35 DEBUG : A1/B1/C3: Making directory 2024/04/11 04:13:35 DEBUG : A3: Making directory 2024/04/11 04:13:35 DEBUG : A3/B3: Making directory 2024/04/11 04:13: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/04/11 04:13:36 INFO : B2 bucket rclone-test-zegesic6ruletem3mihowaq7: cleaning bucket "rclone-test-zegesic6ruletem3mihowaq7" of all files 2024/04/11 04:13:36 DEBUG : A1/B1/C1/one: Deleting (id "4_z0f526d8350cdcbbb8fe80e1b_f106547342e0b82f1_d20240411_m041335_c001_v0001153_t0019_u01712808815651") 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/04/11 04:13:37 INFO : B2 bucket rclone-test-zegesic6ruletem3mihowaq7: cleaning bucket "rclone-test-zegesic6ruletem3mihowaq7" of all files 2024/04/11 04:13:37 DEBUG : A1/two: Deleting (id "4_z0f526d8350cdcbbb8fe80e1b_f106547342e0b82f5_d20240411_m041335_c001_v0001153_t0009_u01712808815986") 2024/04/11 04:13:40 DEBUG : B2 bucket rclone-test-zegesic6ruletem3mihowaq7: Purge remote 2024/04/11 04:13:40 INFO : B2 bucket rclone-test-zegesic6ruletem3mihowaq7: cleaning bucket "rclone-test-zegesic6ruletem3mihowaq7" of all files 2024/04/11 04:13:40 purge failed: directory not found --- PASS: TestPurge (6.85s) === RUN TestRmdirsNoLeaveRoot run.go:180: Remote "B2 bucket rclone-test-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" 2024/04/11 04:13:41 DEBUG : A2: Making directory 2024/04/11 04:13:41 DEBUG : A1/B2: Making directory 2024/04/11 04:13:41 DEBUG : A1/B2/C2: Making directory 2024/04/11 04:13:41 DEBUG : A1/B1/C3: Making directory 2024/04/11 04:13:41 DEBUG : A3: Making directory 2024/04/11 04:13:41 DEBUG : A3/B3: Making directory 2024/04/11 04:13:41 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/04/11 04:13:41 DEBUG : removing 1 level 3 directories 2024/04/11 04:13:41 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/04/11 04:13:44 DEBUG : removing 1 level 0 directories 2024/04/11 04:13:44 INFO : B2 bucket rclone-test-yexoyif0zozuyuj9merocub2: Removing directory 2024/04/11 04:13:44 ERROR : : Failed to rmdir: failed to delete bucket: Cannot delete non-empty bucket (400 cannot_delete_non_empty_bucket) operations_test.go:710: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:710 Error: Received unexpected error: failed to remove directories: failed to delete bucket: Cannot delete non-empty bucket (400 cannot_delete_non_empty_bucket) Test: TestRmdirsNoLeaveRoot --- FAIL: TestRmdirsNoLeaveRoot (4.26s) === RUN TestRmdirsLeaveRoot run.go:180: Remote "B2 bucket rclone-test-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" 2024/04/11 04:13:45 DEBUG : A1: Making directory 2024/04/11 04:13:45 DEBUG : A1/B1: Making directory 2024/04/11 04:13:45 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.98s) === RUN TestRmdirsWithFilter run.go:180: Remote "B2 bucket rclone-test-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" 2024/04/11 04:13:46 DEBUG : A1: Making directory 2024/04/11 04:13:46 DEBUG : A1/B1: Making directory 2024/04/11 04:13:46 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.99s) === RUN TestCopyURL run.go:180: Remote "B2 bucket rclone-test-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" 2024/04/11 04:13:48 DEBUG : filename.txt: File name found in url 2024/04/11 04:13:48 DEBUG : headerfilename.txt: filename found in Content-Disposition header. --- PASS: TestCopyURL (3.39s) === RUN TestCopyURLToWriter --- PASS: TestCopyURLToWriter (0.00s) === RUN TestMoveFile run.go:180: Remote "B2 bucket rclone-test-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" 2024/04/11 04:13:50 DEBUG : file1: Need to transfer - File not found at Destination 2024/04/11 04:13:50 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2024/04/11 04:13:50 INFO : file1: Copied (new) to: sub/file2 2024/04/11 04:13:50 INFO : file1: Deleted 2024/04/11 04:13:51 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/04/11 04:13:51 DEBUG : file1: Unchanged skipping 2024/04/11 04:13:51 INFO : file1: Deleted 2024/04/11 04:13:51 DEBUG : B2 bucket rclone-test-yexoyif0zozuyuj9merocub2: don't need to copy/move sub/file2, it is already at target location --- PASS: TestMoveFile (1.87s) === RUN TestMoveFileWithIgnoreExisting run.go:180: Remote "B2 bucket rclone-test-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" 2024/04/11 04:13:52 DEBUG : file1: Need to transfer - File not found at Destination 2024/04/11 04:13:52 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2024/04/11 04:13:52 INFO : file1: Copied (new) 2024/04/11 04:13:52 INFO : file1: Deleted 2024/04/11 04:13:53 DEBUG : file1: Destination exists, skipping 2024/04/11 04:13:53 DEBUG : file1: Not removing source file as destination file exists and --ignore-existing is set --- PASS: TestMoveFileWithIgnoreExisting (1.58s) === RUN TestCaseInsensitiveMoveFile run.go:180: Remote "B2 bucket rclone-test-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFile (0.39s) === RUN TestCaseInsensitiveMoveFileDryRun run.go:180: Remote "B2 bucket rclone-test-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFileDryRun (0.38s) === RUN TestMoveFileBackupDir run.go:180: Remote "B2 bucket rclone-test-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" 2024/04/11 04:13:55 DEBUG : Creating backend with remote "TestB2:rclone-test-yexoyif0zozuyuj9merocub2/backup" 2024/04/11 04:13:56 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18) 2024/04/11 04:13:57 DEBUG : dst/file1: sha1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK 2024/04/11 04:13:57 INFO : dst/file1: Copied (server-side copy) 2024/04/11 04:13:58 INFO : dst/file1: Deleted 2024/04/11 04:13:58 DEBUG : dst/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2024/04/11 04:13:58 INFO : dst/file1: Copied (new) 2024/04/11 04:13:58 INFO : dst/file1: Deleted --- PASS: TestMoveFileBackupDir (4.69s) === 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-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" 2024/04/11 04:14:00 DEBUG : A1/B2: Making directory 2024/04/11 04:14:00 DEBUG : A1/B1/C3: Making directory fstest.go:244: Filtering empty directory "A1/B2" fstest.go:244: Filtering empty directory "A1/B1/C3" 2024/04/11 04:14:01 DEBUG : A1/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2024/04/11 04:14:01 INFO : A1/B1/C2/five: Copied (server-side copy) to: A2/B1/C2/five 2024/04/11 04:14:02 INFO : A1/B1/C2/five: Deleted 2024/04/11 04:14:02 DEBUG : A1/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2024/04/11 04:14:02 INFO : A1/B1/three: Copied (server-side copy) to: A2/B1/three 2024/04/11 04:14:02 INFO : A1/B1/three: Deleted 2024/04/11 04:14:02 DEBUG : A1/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2024/04/11 04:14:02 INFO : A1/B1/C1/four: Copied (server-side copy) to: A2/B1/C1/four 2024/04/11 04:14:02 DEBUG : A1/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2024/04/11 04:14:02 INFO : A1/one: Copied (server-side copy) to: A2/one 2024/04/11 04:14:02 INFO : A1/B1/C1/four: Deleted 2024/04/11 04:14:02 INFO : A1/one: Deleted 2024/04/11 04:14:03 DEBUG : A1/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2024/04/11 04:14:03 INFO : A1/two: Copied (server-side copy) to: A2/two 2024/04/11 04:14:03 INFO : A1/two: Deleted fstest.go:244: Filtering empty directory "A2/B2" fstest.go:244: Filtering empty directory "A2/B1/C3" 2024/04/11 04:14:04 DEBUG : A2/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2024/04/11 04:14:04 INFO : A2/one: Copied (server-side copy) to: A3/one 2024/04/11 04:14:04 DEBUG : A2/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2024/04/11 04:14:04 INFO : A2/B1/C1/four: Copied (server-side copy) to: A3/B1/C1/four 2024/04/11 04:14:04 DEBUG : A2/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2024/04/11 04:14:04 INFO : A2/B1/three: Copied (server-side copy) to: A3/B1/three 2024/04/11 04:14:04 INFO : A2/one: Deleted 2024/04/11 04:14:04 INFO : A2/B1/C1/four: Deleted 2024/04/11 04:14:04 INFO : A2/B1/three: Deleted 2024/04/11 04:14:04 DEBUG : A2/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2024/04/11 04:14:04 INFO : A2/two: Copied (server-side copy) to: A3/two 2024/04/11 04:14:05 INFO : A2/two: Deleted 2024/04/11 04:14:05 DEBUG : A2/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2024/04/11 04:14:05 INFO : A2/B1/C2/five: Copied (server-side copy) to: A3/B1/C2/five 2024/04/11 04:14:05 INFO : A2/B1/C2/five: Deleted fstest.go:244: Filtering empty directory "A3/B2" fstest.go:244: Filtering empty directory "A3/B1/C3" 2024/04/11 04:14:05 INFO : B2 bucket rclone-test-yexoyif0zozuyuj9merocub2: Can't DirMove - falling back to file moves: can't move directory - incompatible remotes 2024/04/11 04:14:06 DEBUG : A3/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2024/04/11 04:14:06 INFO : A3/B1/three: Copied (server-side copy) to: A4/B1/three 2024/04/11 04:14:06 DEBUG : A3/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2024/04/11 04:14:06 INFO : A3/B1/C2/five: Copied (server-side copy) to: A4/B1/C2/five 2024/04/11 04:14:06 DEBUG : A3/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2024/04/11 04:14:06 INFO : A3/one: Copied (server-side copy) to: A4/one 2024/04/11 04:14:06 INFO : A3/B1/C2/five: Deleted 2024/04/11 04:14:06 INFO : A3/one: Deleted 2024/04/11 04:14:06 DEBUG : A3/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2024/04/11 04:14:06 INFO : A3/B1/C1/four: Copied (server-side copy) to: A4/B1/C1/four 2024/04/11 04:14:06 DEBUG : A3/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2024/04/11 04:14:06 INFO : A3/two: Copied (server-side copy) to: A4/two 2024/04/11 04:14:06 INFO : A3/B1/three: Deleted 2024/04/11 04:14:06 INFO : A3/B1/C1/four: Deleted 2024/04/11 04:14:06 INFO : A3/two: Deleted fstest.go:244: Filtering empty directory "A4/B2" fstest.go:244: Filtering empty directory "A4/B1/C3" --- PASS: TestDirMove (9.20s) === RUN TestGetFsInfo run.go:180: Remote "B2 bucket rclone-test-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" --- PASS: TestGetFsInfo (0.37s) === RUN TestRcat === RUN TestRcat/withChecksum=false,ignoreChecksum=false run.go:180: Remote "B2 bucket rclone-test-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" 2024/04/11 04:14:09 DEBUG : B2 bucket rclone-test-yexoyif0zozuyuj9merocub2: File to upload is small (34 bytes), uploading instead of streaming 2024/04/11 04:14:09 DEBUG : no_checksum_small_file_from_pipe: sha1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK 2024/04/11 04:14:09 INFO : no_checksum_small_file_from_pipe: Copied (new) 2024/04/11 04:14:09 DEBUG : no_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2024/04/11 04:14:10 DEBUG : no_checksum_big_file_from_pipe: sha1 = d0080f73aa8b494570ad63df1beb25c01ea9eea2 OK 2024/04/11 04:14:10 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-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" 2024/04/11 04:14:11 DEBUG : B2 bucket rclone-test-yexoyif0zozuyuj9merocub2: File to upload is small (34 bytes), uploading instead of streaming 2024/04/11 04:14:12 DEBUG : with_checksum_small_file_from_pipe: sha1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK 2024/04/11 04:14:12 INFO : with_checksum_small_file_from_pipe: Copied (new) 2024/04/11 04:14:12 DEBUG : with_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2024/04/11 04:14:12 DEBUG : with_checksum_big_file_from_pipe: sha1 = d0080f73aa8b494570ad63df1beb25c01ea9eea2 OK 2024/04/11 04:14:12 DEBUG : with_checksum_big_file_from_pipe: Size and sha1 of src and dst objects identical === RUN TestRcat/withChecksum=false,ignoreChecksum=true run.go:180: Remote "B2 bucket rclone-test-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" 2024/04/11 04:14:14 DEBUG : B2 bucket rclone-test-yexoyif0zozuyuj9merocub2: File to upload is small (34 bytes), uploading instead of streaming 2024/04/11 04:14:14 INFO : ignore_checksum_small_file_from_pipe: Copied (new) 2024/04/11 04:14:14 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2024/04/11 04:14:15 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-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" 2024/04/11 04:14:16 DEBUG : B2 bucket rclone-test-yexoyif0zozuyuj9merocub2: File to upload is small (34 bytes), uploading instead of streaming 2024/04/11 04:14:16 INFO : ignore_checksum_small_file_from_pipe: Copied (new) 2024/04/11 04:14:16 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2024/04/11 04:14:17 DEBUG : ignore_checksum_big_file_from_pipe: Src hash empty - aborting Dst hash check 2024/04/11 04:14:17 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical --- PASS: TestRcat (9.42s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (2.90s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (2.07s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (2.39s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (2.06s) === RUN TestRcatMetadata run.go:180: Remote "B2 bucket rclone-test-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" operations_test.go:1514: Skipping as destination doesn't support user metadata --- SKIP: TestRcatMetadata (0.37s) === RUN TestRcatSize run.go:180: Remote "B2 bucket rclone-test-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" 2024/04/11 04:14:18 DEBUG : B2 bucket rclone-test-yexoyif0zozuyuj9merocub2: File to upload is small (60 bytes), uploading instead of streaming 2024/04/11 04:14:19 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2024/04/11 04:14:19 INFO : potato2: Copied (new) --- PASS: TestRcatSize (1.46s) === RUN TestRcatSizeMetadata run.go:180: Remote "B2 bucket rclone-test-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" operations_test.go:1595: Skipping as destination doesn't support user metadata --- SKIP: TestRcatSizeMetadata (0.37s) === RUN TestTouchDir run.go:180: Remote "B2 bucket rclone-test-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" 2024/04/11 04:14:21 DEBUG : B2 bucket rclone-test-yexoyif0zozuyuj9merocub2: Touching "empty space" 2024/04/11 04:14:22 DEBUG : B2 bucket rclone-test-yexoyif0zozuyuj9merocub2: Touching "potato2" 2024/04/11 04:14:23 DEBUG : B2 bucket rclone-test-yexoyif0zozuyuj9merocub2: Touching "sub dir/potato3" --- PASS: TestTouchDir (4.56s) === RUN TestMkdirMetadata run.go:180: Remote "B2 bucket rclone-test-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" operations_test.go:1685: Skipping test as remote does not support MkdirMetadata --- SKIP: TestMkdirMetadata (0.37s) === RUN TestMkdirModTime run.go:180: Remote "B2 bucket rclone-test-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" operations_test.go:1704: Skipping test as remote does not support DirSetModTime or MkdirMetadata --- SKIP: TestMkdirModTime (0.37s) === RUN TestCopyDirMetadata run.go:180: Remote "B2 bucket rclone-test-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" operations_test.go:1725: Skipping test as remote does not support WriteDirMetadata or MkdirMetadata --- SKIP: TestCopyDirMetadata (0.40s) === RUN TestSetDirModTime run.go:180: Remote "B2 bucket rclone-test-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" operations_test.go:1761: Skipping test as remote does not support DirSetModTime or WriteDirSetModTime --- SKIP: TestSetDirModTime (0.37s) === RUN TestDirsEqual run.go:180: Remote "B2 bucket rclone-test-yexoyif0zozuyuj9merocub2", Local "Local file system at /tmp/rclone1530930504", Modify Window "1ms" operations_test.go:1805: Skipping test as remote does not support WriteDirMetadata or MkdirMetadata --- SKIP: TestDirsEqual (0.37s) === RUN TestRcAbout rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcAbout (0.00s) === RUN TestRcCleanup rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcCleanup (0.00s) === RUN TestRcCopyfile rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcCopyfile (0.00s) === RUN TestRcCopyurl rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcCopyurl (0.00s) === RUN TestRcDelete rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcDelete (0.00s) === RUN TestRcDeletefile rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcDeletefile (0.00s) === RUN TestRcList rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcList (0.00s) === RUN TestRcStat rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcStat (0.00s) === RUN TestRcSetTier rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcSetTier (0.00s) === RUN TestRcSetTierFile rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcSetTierFile (0.00s) === RUN TestRcMkdir rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcMkdir (0.00s) === RUN TestRcMovefile rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcMovefile (0.00s) === RUN TestRcPurge rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcPurge (0.00s) === RUN TestRcRmdir rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcRmdir (0.00s) === RUN TestRcRmdirs rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcRmdirs (0.00s) === RUN TestRcSize rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcSize (0.00s) === RUN TestRcPublicLink rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcPublicLink (0.00s) === RUN TestRcFsInfo rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcFsInfo (0.00s) === RUN TestUploadFile rc_test.go:30: Skipping test on non local remote --- SKIP: TestUploadFile (0.00s) === RUN TestRcCommand rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcCommand (0.00s) === RUN TestRcDu rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcDu (0.00s) === RUN TestRcCheck rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcCheck (0.00s) === RUN TestRcHashsum rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcHashsum (0.00s) === RUN TestRcHashsumFile rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcHashsumFile (0.00s) FAIL 2024/04/11 04:14:26 DEBUG : B2 bucket rclone-test-yexoyif0zozuyuj9merocub2: Purge remote 2024/04/11 04:14:26 INFO : B2 bucket rclone-test-yexoyif0zozuyuj9merocub2: cleaning bucket "rclone-test-yexoyif0zozuyuj9merocub2" of all files 2024/04/11 04:14:27 DEBUG : A1/B1/C1/four: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f417075306c2d34aa_d20240411_m041402_c001_v7007000_t0000_u01712808842753") 2024/04/11 04:14:27 DEBUG : A1/B1/C1/four: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8fed5_d20240411_m041400_c001_v0001179_t0000_u01712808840155") 2024/04/11 04:14:27 DEBUG : A1/B1/C1/one: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f417075306c2d33c2_d20240411_m041343_c001_v7007000_t0000_u01712808823724") 2024/04/11 04:14:27 DEBUG : A1/B1/C1/one: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8fb1d_d20240411_m041341_c001_v0001179_t0031_u01712808821232") 2024/04/11 04:14:27 DEBUG : A1/B1/C2/five: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f4169b787928f3fc2_d20240411_m041402_c001_v7007000_t0000_u01712808842079") 2024/04/11 04:14:27 DEBUG : A1/B1/C2/five: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8fee2_d20240411_m041400_c001_v0001179_t0004_u01712808840377") 2024/04/11 04:14:27 DEBUG : A1/B1/three: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f4075e69e2954db3c_d20240411_m041402_c001_v7007000_t0000_u01712808842429") 2024/04/11 04:14:27 DEBUG : A1/B1/three: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8fecd_d20240411_m041359_c001_v0001179_t0023_u01712808839948") 2024/04/11 04:14:27 DEBUG : A1/one: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f417075306c2d34ad_d20240411_m041402_c001_v7007000_t0000_u01712808842842") 2024/04/11 04:14:27 DEBUG : A1/one: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8fea7_d20240411_m041359_c001_v0001179_t0009_u01712808839390") 2024/04/11 04:14:28 DEBUG : A1/two: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f4169b787928f3fce_d20240411_m041403_c001_v7007000_t0000_u01712808843434") 2024/04/11 04:14:28 DEBUG : A1/two: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8febb_d20240411_m041359_c001_v0001179_t0038_u01712808839744") 2024/04/11 04:14:28 DEBUG : A1/two: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f417075306c2d33c4_d20240411_m041344_c001_v7007000_t0000_u01712808824142") 2024/04/11 04:14:28 DEBUG : A1/two: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8fb34_d20240411_m041341_c001_v0001179_t0022_u01712808821437") 2024/04/11 04:14:28 DEBUG : A2/B1/C1/four: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f4169b787928f3ff1_d20240411_m041404_c001_v7007000_t0000_u01712808844661") 2024/04/11 04:14:28 DEBUG : A2/B1/C1/four: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f116c19adf5d98c37_d20240411_m041402_c001_v0001023_t0015_u01712808842035") 2024/04/11 04:14:28 DEBUG : A2/B1/C2/five: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f41538d6c2a877750_d20240411_m041405_c001_v7007000_t0000_u01712808845287") 2024/04/11 04:14:28 DEBUG : A2/B1/C2/five: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f1113d17e48bd4414_d20240411_m041401_c001_v0001181_t0003_u01712808841669") 2024/04/11 04:14:28 DEBUG : A2/B1/three: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f4075e69e2954db3e_d20240411_m041404_c001_v7007000_t0000_u01712808844723") 2024/04/11 04:14:28 DEBUG : A2/B1/three: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f1182a86a00bb5324_d20240411_m041402_c001_v0001153_t0017_u01712808842094") 2024/04/11 04:14:28 DEBUG : A2/one: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f417075306c2d34c1_d20240411_m041404_c001_v7007000_t0000_u01712808844625") 2024/04/11 04:14:28 DEBUG : A2/one: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f108e48ede4c0b52f_d20240411_m041402_c001_v0001026_t0004_u01712808842133") 2024/04/11 04:14:28 DEBUG : A2/two: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f417075306c2d34c2_d20240411_m041404_c001_v7007000_t0000_u01712808844979") 2024/04/11 04:14:28 DEBUG : A2/two: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f10205f83ee5dabef_d20240411_m041402_c001_v0001146_t0044_u01712808842025") 2024/04/11 04:14:28 DEBUG : A3/B1/C1/four: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f4169b787928f4010_d20240411_m041406_c001_v7007000_t0000_u01712808846872") 2024/04/11 04:14:28 DEBUG : A3/B1/C1/four: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f105d97954eceb80a_d20240411_m041404_c001_v0001179_t0032_u01712808844438") 2024/04/11 04:14:28 DEBUG : A3/B1/C2/five: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f41538d6c2a87775d_d20240411_m041406_c001_v7007000_t0000_u01712808846423") 2024/04/11 04:14:29 DEBUG : A3/B1/C2/five: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f113fb0836d87f8c9_d20240411_m041404_c001_v0001179_t0042_u01712808844646") 2024/04/11 04:14:29 DEBUG : A3/B1/three: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f417075306c2d34cd_d20240411_m041406_c001_v7007000_t0000_u01712808846747") 2024/04/11 04:14:29 DEBUG : A3/B1/three: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f115d1590b6ee0a76_d20240411_m041404_c001_v0001036_t0023_u01712808844335") 2024/04/11 04:14:29 DEBUG : A3/one: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f4075e69e2954db40_d20240411_m041406_c001_v7007000_t0000_u01712808846517") 2024/04/11 04:14:29 DEBUG : A3/one: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f1084761735d9adf7_d20240411_m041404_c001_v0001172_t0009_u01712808844332") 2024/04/11 04:14:29 DEBUG : A3/two: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f4169b787928f4011_d20240411_m041406_c001_v7007000_t0000_u01712808846882") 2024/04/11 04:14:29 DEBUG : A3/two: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f1110f1639082c7a1_d20240411_m041404_c001_v0001120_t0058_u01712808844325") 2024/04/11 04:14:29 DEBUG : A4/B1/C1/four: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f4169b787928f4016_d20240411_m041407_c001_v7007000_t0000_u01712808847482") 2024/04/11 04:14:29 DEBUG : A4/B1/C1/four: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f1197bfaeacfcd5b8_d20240411_m041406_c001_v0001120_t0016_u01712808846113") 2024/04/11 04:14:29 DEBUG : A4/B1/C2/five: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f4169b787928f4019_d20240411_m041407_c001_v7007000_t0000_u01712808847672") 2024/04/11 04:14:29 DEBUG : A4/B1/C2/five: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f10310f5982607883_d20240411_m041406_c001_v0001181_t0030_u01712808846163") 2024/04/11 04:14:29 DEBUG : A4/B1/three: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f4169b787928f401f_d20240411_m041407_c001_v7007000_t0000_u01712808847854") 2024/04/11 04:14:29 DEBUG : A4/B1/three: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8fff8_d20240411_m041406_c001_v0001179_t0030_u01712808846085") 2024/04/11 04:14:29 DEBUG : A4/one: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f4169b787928f4023_d20240411_m041408_c001_v7007000_t0000_u01712808848035") 2024/04/11 04:14:29 DEBUG : A4/one: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f11396653cd2486db_d20240411_m041406_c001_v0001157_t0000_u01712808846100") 2024/04/11 04:14:29 DEBUG : A4/two: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f4169b787928f4026_d20240411_m041408_c001_v7007000_t0000_u01712808848221") 2024/04/11 04:14:29 DEBUG : A4/two: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f107f7154767bf611_d20240411_m041406_c001_v0001120_t0016_u01712808846100") 2024/04/11 04:14:29 DEBUG : BackupDir/one: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f4007893bcb4b8b19_d20240411_m041237_c001_v7007000_t0000_u01712808757074") 2024/04/11 04:14:29 DEBUG : BackupDir/one: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f108091ecac0ac026_d20240411_m041232_c001_v0001038_t0047_u01712808752131") 2024/04/11 04:14:29 DEBUG : CompareDest/one: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f41355d70c99ac6c3_d20240411_m041224_c001_v7007000_t0000_u01712808744074") 2024/04/11 04:14:30 DEBUG : CompareDest/one: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8e940_d20240411_m041220_c001_v0001179_t0005_u01712808740217") 2024/04/11 04:14:30 DEBUG : CompareDest/two: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f41355d70c99ac6c4_d20240411_m041224_c001_v7007000_t0000_u01712808744255") 2024/04/11 04:14:30 DEBUG : CompareDest/two: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8e974_d20240411_m041221_c001_v0001179_t0018_u01712808741304") 2024/04/11 04:14:30 DEBUG : CopyDest/one: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f4007893bcb4b8b1c_d20240411_m041237_c001_v7007000_t0000_u01712808757256") 2024/04/11 04:14:30 DEBUG : CopyDest/one: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8eb69_d20240411_m041230_c001_v0001179_t0003_u01712808750271") 2024/04/11 04:14:30 DEBUG : CopyDest/three: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f4007893bcb4b8b1d_d20240411_m041237_c001_v7007000_t0000_u01712808757462") 2024/04/11 04:14:30 DEBUG : CopyDest/three: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8ec82_d20240411_m041235_c001_v0001179_t0011_u01712808755163") 2024/04/11 04:14:30 DEBUG : CopyDest/two: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f4007893bcb4b8b20_d20240411_m041237_c001_v7007000_t0000_u01712808757651") 2024/04/11 04:14:30 DEBUG : CopyDest/two: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8ec2e_d20240411_m041233_c001_v0001179_t0024_u01712808753505") 2024/04/11 04:14:30 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f4007893bcb4b8b64_d20240411_m041242_c001_v7007000_t0000_u01712808762373") 2024/04/11 04:14:30 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8eda1_d20240411_m041239_c001_v0001179_t0035_u01712808759549") 2024/04/11 04:14:30 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f4007893bcb4b8b67_d20240411_m041242_c001_v7007000_t0000_u01712808762554") 2024/04/11 04:14:30 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8ee09_d20240411_m041241_c001_v0001179_t0055_u01712808761298") 2024/04/11 04:14:30 DEBUG : a.txt: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f4007893bcb4b8b8e_d20240411_m041254_c001_v7007000_t0000_u01712808774943") 2024/04/11 04:14:30 DEBUG : a.txt: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8efc4_d20240411_m041250_c001_v0001179_t0013_u01712808770052") 2024/04/11 04:14:30 DEBUG : also/one: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f4007893bcb4b8b7a_d20240411_m041247_c001_v7007000_t0000_u01712808767029") 2024/04/11 04:14:30 DEBUG : also/one: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8eebe_d20240411_m041244_c001_v0001179_t0003_u01712808764965") 2024/04/11 04:14:30 DEBUG : another: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f4007893bcb4b8b7b_d20240411_m041247_c001_v7007000_t0000_u01712808767582") 2024/04/11 04:14:30 DEBUG : another: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8eefa_d20240411_m041246_c001_v0001179_t0056_u01712808766053") 2024/04/11 04:14:31 DEBUG : backup/dst/file1: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f417075306c2d347e_d20240411_m041358_c001_v7007000_t0000_u01712808838775") 2024/04/11 04:14:31 DEBUG : backup/dst/file1: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f116ec12fc7c19dcb_d20240411_m041357_c001_v0001030_t0009_u01712808837519") 2024/04/11 04:14:31 DEBUG : backup/dst/file1: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f41355d70c99ac6b4_d20240411_m041215_c001_v7007000_t0000_u01712808735591") 2024/04/11 04:14:31 DEBUG : backup/dst/file1: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f1143c3d2ba27d041_d20240411_m041213_c001_v0001042_t0016_u01712808733656") 2024/04/11 04:14:31 DEBUG : data/banana: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f401b3bfec9dde302_d20240411_m041113_c001_v7007000_t0000_u01712808673740") 2024/04/11 04:14:31 DEBUG : data/banana: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8d978_d20240411_m041111_c001_v0001179_t0013_u01712808671678") 2024/04/11 04:14:31 DEBUG : data/banana: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8d77e_d20240411_m041103_c001_v0001179_t0018_u01712808663250") 2024/04/11 04:14:31 DEBUG : data/potato: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f401b3bfec9dde304_d20240411_m041113_c001_v7007000_t0000_u01712808673931") 2024/04/11 04:14:31 DEBUG : data/potato: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8d980_d20240411_m041111_c001_v0001179_t0056_u01712808671896") 2024/04/11 04:14:31 DEBUG : data/potato: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8d7f3_d20240411_m041104_c001_v0001179_t0039_u01712808664986") 2024/04/11 04:14:31 DEBUG : dst/file1: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f417075306c2d3481_d20240411_m041358_c001_v7007000_t0000_u01712808838958") 2024/04/11 04:14:31 DEBUG : dst/file1: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8fe6b_d20240411_m041358_c001_v0001179_t0052_u01712808838127") 2024/04/11 04:14:31 DEBUG : dst/file1: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f417075306c2d3470_d20240411_m041357_c001_v7007000_t0000_u01712808837943") 2024/04/11 04:14:31 DEBUG : dst/file1: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8fdaa_d20240411_m041354_c001_v0001179_t0008_u01712808834695") 2024/04/11 04:14:31 DEBUG : dst/file1: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f41355d70c99ac6b6_d20240411_m041215_c001_v7007000_t0000_u01712808735776") 2024/04/11 04:14:31 DEBUG : dst/file1: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8e801_d20240411_m041214_c001_v0001179_t0050_u01712808734357") 2024/04/11 04:14:31 DEBUG : dst/file1: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f41355d70c99ac6b0_d20240411_m041214_c001_v7007000_t0000_u01712808734175") 2024/04/11 04:14:31 DEBUG : dst/file1: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8e71b_d20240411_m041210_c001_v0001179_t0041_u01712808730627") 2024/04/11 04:14:31 DEBUG : dst/one: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f4007893bcb4b8b23_d20240411_m041237_c001_v7007000_t0000_u01712808757845") 2024/04/11 04:14:31 DEBUG : dst/one: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f109c528f1768319a_d20240411_m041232_c001_v0001039_t0057_u01712808752866") 2024/04/11 04:14:32 DEBUG : dst/one: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f4007893bcb4b8ae7_d20240411_m041232_c001_v7007000_t0000_u01712808752649") 2024/04/11 04:14:32 DEBUG : dst/one: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8eb55_d20240411_m041229_c001_v0001179_t0000_u01712808749833") 2024/04/11 04:14:32 DEBUG : dst/one: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f109c121ce2faa024_d20240411_m041228_c001_v0001153_t0006_u01712808748913") 2024/04/11 04:14:32 DEBUG : dst/one: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f109c121ce2faa020_d20240411_m041227_c001_v0001153_t0016_u01712808747819") 2024/04/11 04:14:32 DEBUG : dst/one: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f41355d70c99ac6c5_d20240411_m041224_c001_v7007000_t0000_u01712808744446") 2024/04/11 04:14:32 DEBUG : dst/one: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8e934_d20240411_m041219_c001_v0001179_t0039_u01712808739946") 2024/04/11 04:14:32 DEBUG : dst/one: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f104feb130893afc2_d20240411_m041219_c001_v0001033_t0026_u01712808739401") 2024/04/11 04:14:32 DEBUG : dst/one: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f104feb130893afb5_d20240411_m041218_c001_v0001033_t0026_u01712808738213") 2024/04/11 04:14:32 DEBUG : dst/three: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f4007893bcb4b8b28_d20240411_m041238_c001_v7007000_t0000_u01712808758059") 2024/04/11 04:14:32 DEBUG : dst/three: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f109c121ce2faa02a_d20240411_m041236_c001_v0001153_t0008_u01712808756357") 2024/04/11 04:14:32 DEBUG : dst/two: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f4007893bcb4b8b2c_d20240411_m041238_c001_v7007000_t0000_u01712808758240") 2024/04/11 04:14:32 DEBUG : dst/two: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f10310f59826067ed_d20240411_m041234_c001_v0001181_t0004_u01712808754355") 2024/04/11 04:14:32 DEBUG : dst/two: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f41355d70c99ac6c6_d20240411_m041224_c001_v7007000_t0000_u01712808744629") 2024/04/11 04:14:32 DEBUG : dst/two: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f104feb130893afda_d20240411_m041223_c001_v0001033_t0011_u01712808743367") 2024/04/11 04:14:32 DEBUG : empty space: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f4169b787928f40c8_d20240411_m041424_c001_v7007000_t0000_u01712808864401") 2024/04/11 04:14:32 DEBUG : empty space: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f1110f1639082c7d0_d20240411_m041422_c001_v0001120_t0031_u01712808862472") 2024/04/11 04:14:32 DEBUG : empty space: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d902c8_d20240411_m041420_c001_v0001179_t0039_u01712808860793") 2024/04/11 04:14:32 DEBUG : empty space: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f40680e37164a0c8d_d20240411_m041318_c001_v7007000_t0000_u01712808798196") 2024/04/11 04:14:32 DEBUG : empty space: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8f572_d20240411_m041316_c001_v0001179_t0048_u01712808796340") 2024/04/11 04:14:33 DEBUG : empty space: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f40680e37164a0c5f_d20240411_m041315_c001_v7007000_t0000_u01712808795315") 2024/04/11 04:14:33 DEBUG : empty space: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8f4f7_d20240411_m041313_c001_v0001179_t0023_u01712808793788") 2024/04/11 04:14:33 DEBUG : empty space: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f40680e37164a0c47_d20240411_m041313_c001_v7007000_t0000_u01712808793037") 2024/04/11 04:14:33 DEBUG : empty space: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8f46a_d20240411_m041311_c001_v0001179_t0035_u01712808791968") 2024/04/11 04:14:33 DEBUG : empty space: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f40680e37164a0c3c_d20240411_m041311_c001_v7007000_t0000_u01712808791121") 2024/04/11 04:14:33 DEBUG : empty space: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8f3ec_d20240411_m041309_c001_v0001179_t0058_u01712808789781") 2024/04/11 04:14:33 DEBUG : empty space: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f40680e37164a0c37_d20240411_m041309_c001_v7007000_t0000_u01712808789043") 2024/04/11 04:14:33 DEBUG : empty space: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8f398_d20240411_m041308_c001_v0001179_t0035_u01712808788217") 2024/04/11 04:14:33 DEBUG : empty space: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f401b3bfec9dde231_d20240411_m041058_c001_v7007000_t0000_u01712808658402") 2024/04/11 04:14:33 DEBUG : empty space: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8d5ec_d20240411_m041056_c001_v0001179_t0017_u01712808656049") 2024/04/11 04:14:33 DEBUG : empty space: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f401b3bfec9dde1d9_d20240411_m041054_c001_v7007000_t0000_u01712808654353") 2024/04/11 04:14:33 DEBUG : empty space: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8d4ea_d20240411_m041050_c001_v0001179_t0013_u01712808650849") 2024/04/11 04:14:33 DEBUG : empty space: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f401b3bfec9dde182_d20240411_m041048_c001_v7007000_t0000_u01712808648352") 2024/04/11 04:14:33 DEBUG : empty space: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8d3de_d20240411_m041045_c001_v0001179_t0033_u01712808645403") 2024/04/11 04:14:33 DEBUG : file1: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f417075306c2d3441_d20240411_m041353_c001_v7007000_t0000_u01712808833512") 2024/04/11 04:14:33 DEBUG : file1: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8fd44_d20240411_m041352_c001_v0001179_t0007_u01712808832528") 2024/04/11 04:14:33 DEBUG : file1: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f417075306c2d3400_d20240411_m041349_c001_v7007000_t0000_u01712808829533") 2024/04/11 04:14:33 DEBUG : file1: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f418909a039d8fc68_d20240411_m041347_c001_v0001179_t0057_u01712808827651") 2024/04/11 04:14:33 DEBUG : file1: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f417075306c2d3338_d20240411_m041333_c001_v7007000_t0000_u01712808813008") 2024/04/11 04:14:33 DEBUG : file1: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8f880_d20240411_m041328_c001_v0001179_t0051_u01712808808989") 2024/04/11 04:14:34 DEBUG : file1: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f40680e37164a0c24_d20240411_m041305_c001_v7007000_t0000_u01712808785266") 2024/04/11 04:14:34 DEBUG : file1: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8f1bc_d20240411_m041259_c001_v0001179_t0033_u01712808779990") 2024/04/11 04:14:34 DEBUG : file1: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f4007893bcb4b8b9f_d20240411_m041259_c001_v7007000_t0000_u01712808779268") 2024/04/11 04:14:34 DEBUG : file1: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8f0ec_d20240411_m041256_c001_v0001179_t0056_u01712808776423") 2024/04/11 04:14:34 DEBUG : file2: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f417075306c2d3404_d20240411_m041349_c001_v7007000_t0000_u01712808829717") 2024/04/11 04:14:34 DEBUG : file2: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f418909a039d8fca2_d20240411_m041348_c001_v0001179_t0057_u01712808828941") 2024/04/11 04:14:34 DEBUG : file2: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f417075306c2d3344_d20240411_m041333_c001_v7007000_t0000_u01712808813624") 2024/04/11 04:14:34 DEBUG : file2: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8f892_d20240411_m041329_c001_v0001179_t0018_u01712808809245") 2024/04/11 04:14:34 DEBUG : filename.txt: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f417075306c2d3407_d20240411_m041349_c001_v7007000_t0000_u01712808829900") 2024/04/11 04:14:34 DEBUG : filename.txt: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8fc85_d20240411_m041348_c001_v0001179_t0024_u01712808828207") 2024/04/11 04:14:34 DEBUG : headerfilename.txt: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f417075306c2d3408_d20240411_m041350_c001_v7007000_t0000_u01712808830093") 2024/04/11 04:14:34 DEBUG : headerfilename.txt: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8fc9c_d20240411_m041348_c001_v0001179_t0023_u01712808828719") 2024/04/11 04:14:34 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f4169b787928f40a6_d20240411_m041417_c001_v7007000_t0000_u01712808857828") 2024/04/11 04:14:34 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d90204_d20240411_m041416_c001_v0001179_t0035_u01712808856713") 2024/04/11 04:14:34 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f4169b787928f4092_d20240411_m041415_c001_v7007000_t0000_u01712808855752") 2024/04/11 04:14:34 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d90189_d20240411_m041414_c001_v0001179_t0033_u01712808854609") 2024/04/11 04:14:34 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f4169b787928f40a9_d20240411_m041418_c001_v7007000_t0000_u01712808858011") 2024/04/11 04:14:34 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d901fa_d20240411_m041416_c001_v0001179_t0008_u01712808856510") 2024/04/11 04:14:34 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f4169b787928f4095_d20240411_m041415_c001_v7007000_t0000_u01712808855943") 2024/04/11 04:14:35 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d90169_d20240411_m041414_c001_v0001179_t0025_u01712808854123") 2024/04/11 04:14:35 DEBUG : large: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f40680e37164a0cdb_d20240411_m041327_c001_v7007000_t0000_u01712808807669") 2024/04/11 04:14:35 DEBUG : large: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8f80e_d20240411_m041327_c001_v0001179_t0005_u01712808807079") 2024/04/11 04:14:35 DEBUG : large: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f40680e37164a0cd7_d20240411_m041325_c001_v7007000_t0000_u01712808805810") 2024/04/11 04:14:35 DEBUG : large: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8f748_d20240411_m041324_c001_v0001179_t0045_u01712808804358") 2024/04/11 04:14:35 DEBUG : large: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f40680e37164a0ccc_d20240411_m041322_c001_v7007000_t0000_u01712808802579") 2024/04/11 04:14:35 DEBUG : large: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8f6a8_d20240411_m041321_c001_v0001179_t0017_u01712808801860") 2024/04/11 04:14:35 DEBUG : large: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f40680e37164a0cbe_d20240411_m041320_c001_v7007000_t0000_u01712808800931") 2024/04/11 04:14:35 DEBUG : large: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8f60c_d20240411_m041319_c001_v0001179_t0002_u01712808799469") 2024/04/11 04:14:35 DEBUG : medium: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f417075306c2d32f3_d20240411_m041327_c001_v7007000_t0000_u01712808807688") 2024/04/11 04:14:35 DEBUG : medium: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8f7f0_d20240411_m041326_c001_v0001179_t0017_u01712808806745") 2024/04/11 04:14:35 DEBUG : medium: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f40680e37164a0cd6_d20240411_m041325_c001_v7007000_t0000_u01712808805243") 2024/04/11 04:14:35 DEBUG : medium: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8f73a_d20240411_m041324_c001_v0001179_t0040_u01712808804132") 2024/04/11 04:14:35 DEBUG : medium: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f417075306c2d2ec2_d20240411_m041322_c001_v7007000_t0000_u01712808802633") 2024/04/11 04:14:35 DEBUG : medium: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8f68a_d20240411_m041321_c001_v0001179_t0055_u01712808801537") 2024/04/11 04:14:35 DEBUG : medium: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f40680e37164a0cb2_d20240411_m041320_c001_v7007000_t0000_u01712808800173") 2024/04/11 04:14:35 DEBUG : medium: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8f602_d20240411_m041319_c001_v0001179_t0033_u01712808799239") 2024/04/11 04:14:36 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f4169b787928f405e_d20240411_m041411_c001_v7007000_t0000_u01712808851288") 2024/04/11 04:14:36 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d900a0_d20240411_m041409_c001_v0001179_t0030_u01712808849750") 2024/04/11 04:14:36 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f4169b787928f4063_d20240411_m041411_c001_v7007000_t0000_u01712808851482") 2024/04/11 04:14:36 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d90096_d20240411_m041409_c001_v0001179_t0030_u01712808849528") 2024/04/11 04:14:36 DEBUG : not-one: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f4007893bcb4b8b7d_d20240411_m041247_c001_v7007000_t0000_u01712808767763") 2024/04/11 04:14:36 DEBUG : not-one: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8ef02_d20240411_m041246_c001_v0001179_t0035_u01712808766242") 2024/04/11 04:14:36 DEBUG : one: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f4007893bcb4b8b78_d20240411_m041246_c001_v7007000_t0000_u01712808766840") 2024/04/11 04:14:36 DEBUG : one: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8eeb1_d20240411_m041244_c001_v0001179_t0042_u01712808764743") 2024/04/11 04:14:36 DEBUG : potato1: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f4169b787928f40ba_d20240411_m041419_c001_v7007000_t0000_u01712808859657") 2024/04/11 04:14:36 DEBUG : potato1: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d90254_d20240411_m041418_c001_v0001179_t0018_u01712808858749") 2024/04/11 04:14:36 DEBUG : potato2: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f4169b787928f40c9_d20240411_m041424_c001_v7007000_t0000_u01712808864585") 2024/04/11 04:14:36 DEBUG : potato2: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f110ebd09bc407ca7_d20240411_m041423_c001_v0001041_t0026_u01712808863236") 2024/04/11 04:14:36 DEBUG : potato2: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d902b8_d20240411_m041420_c001_v0001179_t0027_u01712808860577") 2024/04/11 04:14:36 DEBUG : potato2: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f4169b787928f40bb_d20240411_m041419_c001_v7007000_t0000_u01712808859837") 2024/04/11 04:14:36 DEBUG : potato2: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d90266_d20240411_m041419_c001_v0001179_t0037_u01712808859052") 2024/04/11 04:14:36 DEBUG : potato2: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f40680e37164a0c90_d20240411_m041318_c001_v7007000_t0000_u01712808798380") 2024/04/11 04:14:36 DEBUG : potato2: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8f558_d20240411_m041315_c001_v0001179_t0045_u01712808795867") 2024/04/11 04:14:36 DEBUG : potato2: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f40680e37164a0c62_d20240411_m041315_c001_v7007000_t0000_u01712808795497") 2024/04/11 04:14:37 DEBUG : potato2: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8f4e6_d20240411_m041313_c001_v0001179_t0046_u01712808793589") 2024/04/11 04:14:37 DEBUG : potato2: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f40680e37164a0c4b_d20240411_m041313_c001_v7007000_t0000_u01712808793219") 2024/04/11 04:14:37 DEBUG : potato2: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8f45a_d20240411_m041311_c001_v0001179_t0011_u01712808791685") 2024/04/11 04:14:37 DEBUG : potato2: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f40680e37164a0c3f_d20240411_m041311_c001_v7007000_t0000_u01712808791313") 2024/04/11 04:14:37 DEBUG : potato2: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8f3e6_d20240411_m041309_c001_v0001179_t0055_u01712808789594") 2024/04/11 04:14:37 DEBUG : potato2: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f40680e37164a0c38_d20240411_m041309_c001_v7007000_t0000_u01712808789228") 2024/04/11 04:14:37 DEBUG : potato2: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8f388_d20240411_m041307_c001_v0001179_t0055_u01712808787959") 2024/04/11 04:14:37 DEBUG : potato2: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f401b3bfec9dde233_d20240411_m041058_c001_v7007000_t0000_u01712808658608") 2024/04/11 04:14:37 DEBUG : potato2: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8d614_d20240411_m041056_c001_v0001179_t0042_u01712808656649") 2024/04/11 04:14:37 DEBUG : potato2: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f401b3bfec9dde1db_d20240411_m041054_c001_v7007000_t0000_u01712808654536") 2024/04/11 04:14:37 DEBUG : potato2: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8d50e_d20240411_m041051_c001_v0001179_t0036_u01712808651611") 2024/04/11 04:14:37 DEBUG : potato2: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f401b3bfec9dde183_d20240411_m041048_c001_v7007000_t0000_u01712808648536") 2024/04/11 04:14:37 DEBUG : potato2: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8d3fe_d20240411_m041046_c001_v0001179_t0023_u01712808646146") 2024/04/11 04:14:37 DEBUG : remotepotato: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f401b3bfec9dde234_d20240411_m041058_c001_v7007000_t0000_u01712808658793") 2024/04/11 04:14:37 DEBUG : remotepotato: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8d652_d20240411_m041057_c001_v0001179_t0041_u01712808657466") 2024/04/11 04:14:37 DEBUG : remotepotato: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f401b3bfec9dde1dd_d20240411_m041054_c001_v7007000_t0000_u01712808654719") 2024/04/11 04:14:37 DEBUG : remotepotato: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8d546_d20240411_m041052_c001_v0001179_t0000_u01712808652977") 2024/04/11 04:14:37 DEBUG : remotepotato: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f401b3bfec9dde187_d20240411_m041048_c001_v7007000_t0000_u01712808648720") 2024/04/11 04:14:37 DEBUG : remotepotato: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8d434_d20240411_m041047_c001_v0001179_t0026_u01712808647395") 2024/04/11 04:14:38 DEBUG : rutabaga: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f401b3bfec9dde238_d20240411_m041058_c001_v7007000_t0000_u01712808658983") 2024/04/11 04:14:38 DEBUG : rutabaga: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8d5bc_d20240411_m041055_c001_v0001179_t0046_u01712808655265") 2024/04/11 04:14:38 DEBUG : rutabaga: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f401b3bfec9dde1e4_d20240411_m041054_c001_v7007000_t0000_u01712808654900") 2024/04/11 04:14:38 DEBUG : rutabaga: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8d48e_d20240411_m041049_c001_v0001179_t0004_u01712808649280") 2024/04/11 04:14:38 DEBUG : rutabaga: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f401b3bfec9dde188_d20240411_m041048_c001_v7007000_t0000_u01712808648914") 2024/04/11 04:14:38 DEBUG : rutabaga: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8d3b8_d20240411_m041044_c001_v0001179_t0018_u01712808644642") 2024/04/11 04:14:38 DEBUG : small: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f417075306c2d32fb_d20240411_m041328_c001_v7007000_t0000_u01712808808243") 2024/04/11 04:14:38 DEBUG : small: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8f7e6_d20240411_m041326_c001_v0001179_t0029_u01712808806546") 2024/04/11 04:14:38 DEBUG : small: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f417075306c2d2ee6_d20240411_m041325_c001_v7007000_t0000_u01712808805239") 2024/04/11 04:14:38 DEBUG : small: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8f726_d20240411_m041323_c001_v0001179_t0024_u01712808803929") 2024/04/11 04:14:38 DEBUG : small: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f417075306c2d2ec8_d20240411_m041323_c001_v7007000_t0000_u01712808803189") 2024/04/11 04:14:38 DEBUG : small: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8f674_d20240411_m041321_c001_v0001179_t0050_u01712808801300") 2024/04/11 04:14:38 DEBUG : small: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f40680e37164a0cb5_d20240411_m041320_c001_v7007000_t0000_u01712808800357") 2024/04/11 04:14:38 DEBUG : small: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8f5f4_d20240411_m041318_c001_v0001179_t0055_u01712808798979") 2024/04/11 04:14:38 DEBUG : sub dir/hello world: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f40680e37164a0c2c_d20240411_m041307_c001_v7007000_t0000_u01712808787583") 2024/04/11 04:14:38 DEBUG : sub dir/hello world: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8f327_d20240411_m041306_c001_v0001179_t0046_u01712808786747") 2024/04/11 04:14:38 DEBUG : sub dir/hello world: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f4007893bcb4b8b8f_d20240411_m041255_c001_v7007000_t0000_u01712808775148") 2024/04/11 04:14:38 DEBUG : sub dir/hello world: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8eff2_d20240411_m041250_c001_v0001179_t0038_u01712808770807") 2024/04/11 04:14:39 DEBUG : sub dir/hello world2: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f4007893bcb4b8b90_d20240411_m041255_c001_v7007000_t0000_u01712808775332") 2024/04/11 04:14:39 DEBUG : sub dir/hello world2: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8f000_d20240411_m041251_c001_v0001179_t0059_u01712808771014") 2024/04/11 04:14:39 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f4007893bcb4b8b92_d20240411_m041255_c001_v7007000_t0000_u01712808775512") 2024/04/11 04:14:39 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8f00a_d20240411_m041251_c001_v0001179_t0032_u01712808771214") 2024/04/11 04:14:39 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f4007893bcb4b8b93_d20240411_m041255_c001_v7007000_t0000_u01712808775693") 2024/04/11 04:14:39 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8f022_d20240411_m041251_c001_v0001179_t0052_u01712808771806") 2024/04/11 04:14:39 DEBUG : sub dir/potato3: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f4169b787928f40ca_d20240411_m041424_c001_v7007000_t0000_u01712808864767") 2024/04/11 04:14:39 DEBUG : sub dir/potato3: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f1158f61d45e70994_d20240411_m041423_c001_v0001181_t0007_u01712808863776") 2024/04/11 04:14:39 DEBUG : sub dir/potato3: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d902e0_d20240411_m041421_c001_v0001179_t0039_u01712808861197") 2024/04/11 04:14:39 DEBUG : sub dir/potato3: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f40680e37164a0c95_d20240411_m041318_c001_v7007000_t0000_u01712808798563") 2024/04/11 04:14:39 DEBUG : sub dir/potato3: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8f596_d20240411_m041316_c001_v0001179_t0025_u01712808796873") 2024/04/11 04:14:39 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f4007893bcb4b8b94_d20240411_m041255_c001_v7007000_t0000_u01712808775874") 2024/04/11 04:14:39 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8f02c_d20240411_m041252_c001_v0001179_t0006_u01712808772130") 2024/04/11 04:14:39 DEBUG : sub/file2: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f417075306c2d3430_d20240411_m041351_c001_v7007000_t0000_u01712808831929") 2024/04/11 04:14:39 DEBUG : sub/file2: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8fcfc_d20240411_m041350_c001_v0001179_t0030_u01712808830659") 2024/04/11 04:14:39 DEBUG : sub/file2: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f40680e37164a0c25_d20240411_m041305_c001_v7007000_t0000_u01712808785453") 2024/04/11 04:14:39 DEBUG : sub/file2: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8f1ca_d20240411_m041300_c001_v0001179_t0056_u01712808780196") 2024/04/11 04:14:39 DEBUG : sub/file2: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f4007893bcb4b8ba1_d20240411_m041259_c001_v7007000_t0000_u01712808779500") 2024/04/11 04:14:39 DEBUG : sub/file2: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8f100_d20240411_m041256_c001_v0001179_t0003_u01712808776632") 2024/04/11 04:14:39 DEBUG : sub/file2: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f41355d70c99ac6aa_d20240411_m041209_c001_v7007000_t0000_u01712808729884") 2024/04/11 04:14:39 DEBUG : sub/file2: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8e693_d20240411_m041208_c001_v0001179_t0039_u01712808728605") 2024/04/11 04:14:40 DEBUG : test-multithread-abort: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f401b3bfec9dde15f_d20240411_m041043_c001_v7007000_t0000_u01712808643719") 2024/04/11 04:14:40 DEBUG : test-multithread-abort: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8ce41_d20240411_m041021_c001_v0001179_t0038_u01712808621129") 2024/04/11 04:14:40 DEBUG : test-multithread-copy-false-201326591-2: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f419eb674a85025cc_d20240411_m040844_c001_v7007000_t0000_u01712808524131") 2024/04/11 04:14:40 DEBUG : test-multithread-copy-false-201326591-2: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8b4ff_d20240411_m040821_c001_v0001179_t0045_u01712808501757") 2024/04/11 04:14:40 DEBUG : test-multithread-copy-false-201326592-2: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f417075306c2d197c_d20240411_m040908_c001_v7007000_t0000_u01712808548179") 2024/04/11 04:14:40 DEBUG : test-multithread-copy-false-201326592-2: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8ba59_d20240411_m040846_c001_v0001179_t0026_u01712808526766") 2024/04/11 04:14:40 DEBUG : test-multithread-copy-false-201326593-2: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f40566323c69997d1_d20240411_m040932_c001_v7007000_t0000_u01712808572107") 2024/04/11 04:14:40 DEBUG : test-multithread-copy-false-201326593-2: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8bf02_d20240411_m040910_c001_v0001179_t0040_u01712808550735") 2024/04/11 04:14:40 DEBUG : test-multithread-copy-true-201326591-2: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f4045cbce00b5a300_d20240411_m040945_c001_v7007000_t0000_u01712808585304") 2024/04/11 04:14:40 DEBUG : test-multithread-copy-true-201326591-2: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f20566323c6999812_d20240411_m040935_c001_v0001179_t0008_u01712808575309") 2024/04/11 04:14:40 DEBUG : test-multithread-copy-true-201326592-2: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f40561a8748751705_d20240411_m040959_c001_v7007000_t0000_u01712808599860") 2024/04/11 04:14:40 DEBUG : test-multithread-copy-true-201326592-2: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f2045cbce00b5a31e_d20240411_m040948_c001_v0001145_t0020_u01712808588509") 2024/04/11 04:14:40 DEBUG : test-multithread-copy-true-201326593-2: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f403b73892ab01384_d20240411_m041019_c001_v7007000_t0000_u01712808619751") 2024/04/11 04:14:40 DEBUG : test-multithread-copy-true-201326593-2: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f20561a874875170b_d20240411_m041003_c001_v0001031_t0031_u01712808603244") 2024/04/11 04:14:40 DEBUG : test.sum: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f401b3bfec9dde305_d20240411_m041114_c001_v7007000_t0000_u01712808674116") 2024/04/11 04:14:40 DEBUG : test.sum: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8d996_d20240411_m041112_c001_v0001179_t0055_u01712808672186") 2024/04/11 04:14:40 DEBUG : test.sum: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8d940_d20240411_m041110_c001_v0001179_t0046_u01712808670543") 2024/04/11 04:14:40 DEBUG : test.sum: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8d8f0_d20240411_m041109_c001_v0001179_t0023_u01712808669222") 2024/04/11 04:14:40 DEBUG : test.sum: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8d8b2_d20240411_m041107_c001_v0001179_t0039_u01712808667918") 2024/04/11 04:14:41 DEBUG : test.sum: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8d84c_d20240411_m041106_c001_v0001179_t0031_u01712808666358") 2024/04/11 04:14:41 DEBUG : test.sum: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8d808_d20240411_m041105_c001_v0001179_t0012_u01712808665246") 2024/04/11 04:14:41 DEBUG : test.sum: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8d78e_d20240411_m041103_c001_v0001179_t0016_u01712808663462") 2024/04/11 04:14:41 DEBUG : test.sum: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f401b3bfec9dde26f_d20240411_m041101_c001_v7007000_t0000_u01712808661191") 2024/04/11 04:14:41 DEBUG : test.sum: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8d6f8_d20240411_m041100_c001_v0001179_t0059_u01712808660324") 2024/04/11 04:14:41 DEBUG : test.sum: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8d6c0_d20240411_m041059_c001_v0001179_t0026_u01712808659347") 2024/04/11 04:14:41 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f4169b787928f4076_d20240411_m041413_c001_v7007000_t0000_u01712808853374") 2024/04/11 04:14:41 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d90113_d20240411_m041412_c001_v0001179_t0023_u01712808852253") 2024/04/11 04:14:41 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f4169b787928f4078_d20240411_m041413_c001_v7007000_t0000_u01712808853558") 2024/04/11 04:14:41 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d90109_d20240411_m041412_c001_v0001179_t0006_u01712808852051") 2024/04/11 04:14:41 DEBUG : zend.txt: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f4007893bcb4b8b96_d20240411_m041256_c001_v7007000_t0000_u01712808776056") 2024/04/11 04:14:41 DEBUG : zend.txt: Deleting (id "4_zdf529d33507dcbbb8fe80e1b_f118909a039d8efd2_d20240411_m041250_c001_v0001179_t0040_u01712808770257") "./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose" - Finished OK in 6m26.925681122s (try 1/5)