"./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -fast-list" - Starting (try 1/5) 2024/04/11 03:49:48 DEBUG : Creating backend with remote "TestB2:rclone-test-fukadiq7wasiwer1puwumal5" 2024/04/11 03:49:48 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/04/11 03:49:48 DEBUG : Creating backend with remote "/tmp/rclone4258348187" === 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-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", Modify Window "1ms" 2024/04/11 03:49:49 DEBUG : chunksize-probe: Cancelling large file upload === RUN TestMultithreadCopy/upload=false,size=201326591,streams=2 2024/04/11 03:50:08 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2024/04/11 03:50:08 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: write buffer set to 131072 2024/04/11 03:50:08 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2024/04/11 03:50:08 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2024/04/11 03:50:08 DEBUG : test-multithread-copy-false-201326591-2: Starting multi-thread copy with 3 chunks of size 64Mi with 3 parallel streams 2024/04/11 03:50:08 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 3/3 (134217728-201326591) size 64.000Mi starting 2024/04/11 03:50:08 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 1/3 (0-67108864) size 64Mi starting 2024/04/11 03:50:08 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 2/3 (67108864-134217728) size 64Mi starting 2024/04/11 03:50:09 DEBUG : test-multithread-copy-false-201326591-2: writing chunk 2 2024/04/11 03:50:09 DEBUG : test-multithread-copy-false-201326591-2: writing chunk 0 2024/04/11 03:50:09 DEBUG : test-multithread-copy-false-201326591-2: writing chunk 1 2024/04/11 03:50:14 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 3/3 (134217728-201326591) size 64.000Mi finished 2024/04/11 03:50:14 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 2/3 (67108864-134217728) size 64Mi finished 2024/04/11 03:50:14 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 1/3 (0-67108864) size 64Mi finished 2024/04/11 03:50:14 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 03:50:33 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2024/04/11 03:50:33 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: write buffer set to 131072 2024/04/11 03:50:33 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2024/04/11 03:50:33 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2024/04/11 03:50:33 DEBUG : test-multithread-copy-false-201326592-2: Starting multi-thread copy with 3 chunks of size 64Mi with 3 parallel streams 2024/04/11 03:50:33 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 3/3 (134217728-201326592) size 64Mi starting 2024/04/11 03:50:33 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 1/3 (0-67108864) size 64Mi starting 2024/04/11 03:50:33 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 2/3 (67108864-134217728) size 64Mi starting 2024/04/11 03:50:33 DEBUG : test-multithread-copy-false-201326592-2: writing chunk 2 2024/04/11 03:50:34 DEBUG : test-multithread-copy-false-201326592-2: writing chunk 0 2024/04/11 03:50:34 DEBUG : test-multithread-copy-false-201326592-2: writing chunk 1 2024/04/11 03:50:38 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 3/3 (134217728-201326592) size 64Mi finished 2024/04/11 03:50:39 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 1/3 (0-67108864) size 64Mi finished 2024/04/11 03:50:39 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 2/3 (67108864-134217728) size 64Mi finished 2024/04/11 03:50:39 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 03:50:57 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2024/04/11 03:50:57 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: write buffer set to 131072 2024/04/11 03:50:57 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2024/04/11 03:50:57 DEBUG : test-multithread-copy-false-201326593-2: Starting multi-thread copy with 4 chunks of size 64Mi with 4 parallel streams 2024/04/11 03:50:57 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 4/4 (201326592-201326593) size 1 starting 2024/04/11 03:50:57 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 2/4 (67108864-134217728) size 64Mi starting 2024/04/11 03:50:57 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 1/4 (0-67108864) size 64Mi starting 2024/04/11 03:50:57 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 3/4 (134217728-201326592) size 64Mi starting 2024/04/11 03:50:58 DEBUG : test-multithread-copy-false-201326593-2: writing chunk 3 2024/04/11 03:50:58 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 4/4 (201326592-201326593) size 1 finished 2024/04/11 03:50:58 DEBUG : test-multithread-copy-false-201326593-2: writing chunk 1 2024/04/11 03:50:58 DEBUG : test-multithread-copy-false-201326593-2: writing chunk 0 2024/04/11 03:50:58 DEBUG : test-multithread-copy-false-201326593-2: writing chunk 2 2024/04/11 03:51:03 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 2/4 (67108864-134217728) size 64Mi finished 2024/04/11 03:51:03 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 1/4 (0-67108864) size 64Mi finished 2024/04/11 03:51:03 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 3/4 (134217728-201326592) size 64Mi finished 2024/04/11 03:51:03 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 03:51:07 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: disabling buffering because source is local disk 2024/04/11 03:51:07 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2024/04/11 03:51:07 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: number of streams 4 was bigger than number of chunks 2 2024/04/11 03:51:07 DEBUG : test-multithread-copy-true-201326591-2: Starting multi-thread copy with 2 chunks of size 96Mi with 2 parallel streams 2024/04/11 03:51:07 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: chunk 2/2 (100663296-201326591) size 96.000Mi starting 2024/04/11 03:51:07 DEBUG : test-multithread-copy-true-201326591-2: Sending chunk 1 length 100663295 2024/04/11 03:51:07 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: chunk 1/2 (0-100663296) size 96Mi starting 2024/04/11 03:51:07 DEBUG : test-multithread-copy-true-201326591-2: Sending chunk 0 length 100663296 2024/04/11 03:51:15 DEBUG : test-multithread-copy-true-201326591-2: Done sending chunk 1 2024/04/11 03:51:15 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: chunk 2/2 (100663296-201326591) size 96.000Mi finished 2024/04/11 03:51:15 DEBUG : test-multithread-copy-true-201326591-2: Done sending chunk 0 2024/04/11 03:51:15 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: chunk 1/2 (0-100663296) size 96Mi finished 2024/04/11 03:51:15 DEBUG : test-multithread-copy-true-201326591-2: Finishing large file upload with 2 parts 2024/04/11 03:51:16 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 03:51:19 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: disabling buffering because source is local disk 2024/04/11 03:51:20 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2024/04/11 03:51:20 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: number of streams 4 was bigger than number of chunks 2 2024/04/11 03:51:20 DEBUG : test-multithread-copy-true-201326592-2: Starting multi-thread copy with 2 chunks of size 96Mi with 2 parallel streams 2024/04/11 03:51:20 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: chunk 2/2 (100663296-201326592) size 96Mi starting 2024/04/11 03:51:20 DEBUG : test-multithread-copy-true-201326592-2: Sending chunk 1 length 100663296 2024/04/11 03:51:20 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: chunk 1/2 (0-100663296) size 96Mi starting 2024/04/11 03:51:20 DEBUG : test-multithread-copy-true-201326592-2: Sending chunk 0 length 100663296 2024/04/11 03:51:28 DEBUG : test-multithread-copy-true-201326592-2: Done sending chunk 1 2024/04/11 03:51:28 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: chunk 2/2 (100663296-201326592) size 96Mi finished 2024/04/11 03:51:28 DEBUG : test-multithread-copy-true-201326592-2: Done sending chunk 0 2024/04/11 03:51:28 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: chunk 1/2 (0-100663296) size 96Mi finished 2024/04/11 03:51:28 DEBUG : test-multithread-copy-true-201326592-2: Finishing large file upload with 2 parts 2024/04/11 03:51:29 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 03:51:32 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: disabling buffering because source is local disk 2024/04/11 03:51:33 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2024/04/11 03:51:33 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2024/04/11 03:51:33 DEBUG : test-multithread-copy-true-201326593-2: Starting multi-thread copy with 3 chunks of size 96Mi with 3 parallel streams 2024/04/11 03:51:33 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 3/3 (201326592-201326593) size 1 starting 2024/04/11 03:51:33 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 2/3 (100663296-201326592) size 96Mi starting 2024/04/11 03:51:33 DEBUG : test-multithread-copy-true-201326593-2: Sending chunk 2 length 1 2024/04/11 03:51:33 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 1/3 (0-100663296) size 96Mi starting 2024/04/11 03:51:33 DEBUG : test-multithread-copy-true-201326593-2: Sending chunk 1 length 100663296 2024/04/11 03:51:33 DEBUG : test-multithread-copy-true-201326593-2: Sending chunk 0 length 100663296 2024/04/11 03:51:34 DEBUG : test-multithread-copy-true-201326593-2: Done sending chunk 2 2024/04/11 03:51:34 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 3/3 (201326592-201326593) size 1 finished 2024/04/11 03:51:41 DEBUG : test-multithread-copy-true-201326593-2: Done sending chunk 1 2024/04/11 03:51:41 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 2/3 (100663296-201326592) size 96Mi finished 2024/04/11 03:51:41 DEBUG : test-multithread-copy-true-201326593-2: Done sending chunk 0 2024/04/11 03:51:41 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 1/3 (0-100663296) size 96Mi finished 2024/04/11 03:51:41 DEBUG : test-multithread-copy-true-201326593-2: Finishing large file upload with 3 parts 2024/04/11 03:51:42 DEBUG : test-multithread-copy-true-201326593-2: Finished multi-thread copy with 3 parts of size 96Mi --- PASS: TestMultithreadCopy (114.73s) --- PASS: TestMultithreadCopy/upload=false,size=201326591,streams=2 (25.63s) --- PASS: TestMultithreadCopy/upload=false,size=201326592,streams=2 (24.71s) --- PASS: TestMultithreadCopy/upload=false,size=201326593,streams=2 (24.28s) --- PASS: TestMultithreadCopy/upload=true,size=201326591,streams=2 (12.49s) --- PASS: TestMultithreadCopy/upload=true,size=201326592,streams=2 (13.06s) --- PASS: TestMultithreadCopy/upload=true,size=201326593,streams=2 (12.87s) === RUN TestMultithreadCopyAbort run.go:180: Remote "B2 bucket rclone-test-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", Modify Window "1ms" 2024/04/11 03:51:43 DEBUG : chunksize-probe: Cancelling large file upload 2024/04/11 03:51:47 DEBUG : test-multithread-abort: multi-thread copy: disabling buffering because source is local disk 2024/04/11 03:51:47 DEBUG : test-multithread-abort: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 1 2024/04/11 03:51:47 DEBUG : test-multithread-abort: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2024/04/11 03:51:47 DEBUG : test-multithread-abort: Starting multi-thread copy with 3 chunks of size 96Mi with 3 parallel streams 2024/04/11 03:51:47 DEBUG : test-multithread-abort: multi-thread copy: chunk 3/3 (201326592-201326593) size 1 starting 2024/04/11 03:51:47 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2024/04/11 03:51:47 DEBUG : test-multithread-abort: multi-thread copy: chunk 1/3 (0-100663296) size 96Mi starting 2024/04/11 03:51:47 DEBUG : Open with options = [RangeOption(0,100663295)] 2024/04/11 03:51:47 DEBUG : test-multithread-abort: Sending chunk 0 length 100663296 2024/04/11 03:51:47 DEBUG : test-multithread-abort: multi-thread copy: chunk 2/3 (100663296-201326592) size 96Mi starting 2024/04/11 03:51:47 DEBUG : Open with options = [RangeOption(100663296,201326591)] 2024/04/11 03:51:47 DEBUG : test-multithread-abort: Sending chunk 1 length 100663296 2024/04/11 03:51:55 DEBUG : test-multithread-abort: Done sending chunk 1 2024/04/11 03:51:55 DEBUG : test-multithread-abort: multi-thread copy: chunk 2/3 (100663296-201326592) size 96Mi finished 2024/04/11 03:51:55 DEBUG : test-multithread-abort: Done sending chunk 0 2024/04/11 03:51:55 DEBUG : test-multithread-abort: multi-thread copy: chunk 1/3 (0-100663296) size 96Mi finished 2024/04/11 03:51:55 DEBUG : Returning error reader 2024/04/11 03:51:55 DEBUG : test-multithread-abort: Sending chunk 2 length 1 2024/04/11 03:51:55 DEBUG : BOOM: simulated read failure 2024/04/11 03:51:55 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 0/10: BOOM: simulated read failure 2024/04/11 03:51:55 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2024/04/11 03:51:56 DEBUG : Returning error reader 2024/04/11 03:51:56 DEBUG : BOOM: simulated read failure 2024/04/11 03:51:56 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 1/10: BOOM: simulated read failure 2024/04/11 03:51:56 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2024/04/11 03:51:57 DEBUG : Returning error reader 2024/04/11 03:51:57 DEBUG : BOOM: simulated read failure 2024/04/11 03:51:57 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 2/10: BOOM: simulated read failure 2024/04/11 03:51:57 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2024/04/11 03:51:58 DEBUG : Returning error reader 2024/04/11 03:51:58 DEBUG : BOOM: simulated read failure 2024/04/11 03:51:58 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 3/10: BOOM: simulated read failure 2024/04/11 03:51:58 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2024/04/11 03:51:59 DEBUG : Returning error reader 2024/04/11 03:51:59 DEBUG : BOOM: simulated read failure 2024/04/11 03:51:59 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 4/10: BOOM: simulated read failure 2024/04/11 03:51:59 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2024/04/11 03:52:00 DEBUG : Returning error reader 2024/04/11 03:52:00 DEBUG : BOOM: simulated read failure 2024/04/11 03:52:00 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 5/10: BOOM: simulated read failure 2024/04/11 03:52:00 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2024/04/11 03:52:01 DEBUG : Returning error reader 2024/04/11 03:52:01 DEBUG : BOOM: simulated read failure 2024/04/11 03:52:01 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 6/10: BOOM: simulated read failure 2024/04/11 03:52:01 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2024/04/11 03:52:02 DEBUG : Returning error reader 2024/04/11 03:52:02 DEBUG : BOOM: simulated read failure 2024/04/11 03:52:02 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 7/10: BOOM: simulated read failure 2024/04/11 03:52:02 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2024/04/11 03:52:03 DEBUG : Returning error reader 2024/04/11 03:52:03 DEBUG : BOOM: simulated read failure 2024/04/11 03:52:03 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 8/10: BOOM: simulated read failure 2024/04/11 03:52:03 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2024/04/11 03:52:04 DEBUG : Returning error reader 2024/04/11 03:52:04 DEBUG : BOOM: simulated read failure 2024/04/11 03:52:04 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 9/10: BOOM: simulated read failure 2024/04/11 03:52:04 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2024/04/11 03:52:05 DEBUG : Returning error reader 2024/04/11 03:52:05 DEBUG : BOOM: simulated read failure 2024/04/11 03:52:05 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 10/10: BOOM: simulated read failure 2024/04/11 03:52:05 DEBUG : test-multithread-abort: Reopen failed after offset 0 bytes read: failed to reopen: too many retries 2024/04/11 03:52:05 DEBUG : test-multithread-abort: Error sending chunk 2 (retry=false): Post "https://pod-000-1180-19.backblaze.com/b2api/v1/b2_upload_part/4_zff52ad73406dcbbb8fe80e1b_f204178af593e6c93_d20240411_m035147_c001_v0001180_t0033_u01712807507556/0034": BOOM: simulated read failure: &url.Error{Op:"Post", URL:"https://pod-000-1180-19.backblaze.com/b2api/v1/b2_upload_part/4_zff52ad73406dcbbb8fe80e1b_f204178af593e6c93_d20240411_m035147_c001_v0001180_t0033_u01712807507556/0034", Err:(*errors.errorString)(0xc0007a2fe0)} 2024/04/11 03:52:05 DEBUG : test-multithread-abort: Error sending chunk 2: Post "https://pod-000-1180-19.backblaze.com/b2api/v1/b2_upload_part/4_zff52ad73406dcbbb8fe80e1b_f204178af593e6c93_d20240411_m035147_c001_v0001180_t0033_u01712807507556/0034": BOOM: simulated read failure 2024/04/11 03:52:05 DEBUG : test-multithread-abort: multi-thread copy: chunk 3/3 failed: multi-thread copy: failed to write chunk: Post "https://pod-000-1180-19.backblaze.com/b2api/v1/b2_upload_part/4_zff52ad73406dcbbb8fe80e1b_f204178af593e6c93_d20240411_m035147_c001_v0001180_t0033_u01712807507556/0034": BOOM: simulated read failure 2024/04/11 03:52:05 DEBUG : test-multithread-abort: multi-thread copy: cancelling transfer on exit 2024/04/11 03:52:05 DEBUG : test-multithread-abort: Cancelling large file upload --- PASS: TestMultithreadCopyAbort (23.99s) === RUN TestSizeDiffers --- PASS: TestSizeDiffers (0.00s) === RUN TestReOpen === RUN TestReOpen/Normal === RUN TestReOpen/Normal/Basics 2024/04/11 03:52:07 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/ErrorAtStart === RUN TestReOpen/Normal/WithErrors 2024/04/11 03:52:07 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2024/04/11 03:52:07 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2024/04/11 03:52:07 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/Normal/TooManyErrors 2024/04/11 03:52:07 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2024/04/11 03:52:07 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2024/04/11 03:52:07 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2024/04/11 03:52:07 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/Normal/Seek 2024/04/11 03:52:07 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2024/04/11 03:52:07 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2024/04/11 03:52:07 DEBUG : potato: Seek from 5 to 2 === RUN TestReOpen/Normal/AccountRead === RUN TestReOpen/Normal/AccountReadDelay 2024/04/11 03:52:07 DEBUG : potato: Seek from 10 to 0 2024/04/11 03:52:07 DEBUG : potato: Seek from 10 to 0 2024/04/11 03:52:07 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/AccountReadError === RUN TestReOpen/WithRangeOption === RUN TestReOpen/WithRangeOption/Basics 2024/04/11 03:52:07 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/ErrorAtStart === RUN TestReOpen/WithRangeOption/WithErrors 2024/04/11 03:52:07 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2024/04/11 03:52:07 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2024/04/11 03:52:07 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithRangeOption/TooManyErrors 2024/04/11 03:52:07 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2024/04/11 03:52:07 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2024/04/11 03:52:07 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2024/04/11 03:52:07 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithRangeOption/Seek 2024/04/11 03:52:07 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2024/04/11 03:52:07 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2024/04/11 03:52:07 DEBUG : potato: Seek from 5 to 2 2024/04/11 03:52:07 DEBUG : potato: Seek from 7 to 4 === RUN TestReOpen/WithRangeOption/AccountRead === RUN TestReOpen/WithRangeOption/AccountReadDelay 2024/04/11 03:52:07 DEBUG : potato: Seek from 7 to 0 2024/04/11 03:52:07 DEBUG : potato: Seek from 7 to 0 2024/04/11 03:52:07 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/AccountReadError === RUN TestReOpen/WithSeekOption === RUN TestReOpen/WithSeekOption/Basics 2024/04/11 03:52:07 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/ErrorAtStart === RUN TestReOpen/WithSeekOption/WithErrors 2024/04/11 03:52:07 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2024/04/11 03:52:07 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2024/04/11 03:52:07 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithSeekOption/TooManyErrors 2024/04/11 03:52:07 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2024/04/11 03:52:07 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2024/04/11 03:52:07 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2024/04/11 03:52:07 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithSeekOption/Seek 2024/04/11 03:52:07 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2024/04/11 03:52:07 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2024/04/11 03:52:07 DEBUG : potato: Seek from 5 to 2 2024/04/11 03:52:07 DEBUG : potato: Seek from 7 to 5 === RUN TestReOpen/WithSeekOption/AccountRead === RUN TestReOpen/WithSeekOption/AccountReadDelay 2024/04/11 03:52:07 DEBUG : potato: Seek from 8 to 0 2024/04/11 03:52:07 DEBUG : potato: Seek from 8 to 0 2024/04/11 03:52:07 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/AccountReadError === RUN TestReOpen/UnknownSize === RUN TestReOpen/UnknownSize/Basics 2024/04/11 03:52:07 DEBUG : potato: Seek from 9 to 0 === RUN TestReOpen/UnknownSize/ErrorAtStart === RUN TestReOpen/UnknownSize/WithErrors 2024/04/11 03:52:07 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2024/04/11 03:52:07 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2024/04/11 03:52:07 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/UnknownSize/TooManyErrors 2024/04/11 03:52:07 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2024/04/11 03:52:07 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2024/04/11 03:52:07 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2024/04/11 03:52:07 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/UnknownSize/Seek 2024/04/11 03:52:07 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2024/04/11 03:52:07 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2024/04/11 03:52:07 DEBUG : potato: Seek from 5 to 2 2024/04/11 03:52:07 DEBUG : potato: Seek from 7 to 6 === RUN TestReOpen/UnknownSize/AccountRead === RUN TestReOpen/UnknownSize/AccountReadDelay 2024/04/11 03:52:07 DEBUG : potato: Seek from 9 to 0 2024/04/11 03:52:07 DEBUG : potato: Seek from 9 to 0 2024/04/11 03:52:07 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-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", Modify Window "1ms" === RUN TestCheck/1 === RUN TestCheck/2 === RUN TestCheck/3 === RUN TestCheck/4 === RUN TestCheck/5 === RUN TestCheck/6 === RUN TestCheck/7 --- PASS: TestCheck (4.95s) --- PASS: TestCheck/1 (0.21s) --- PASS: TestCheck/2 (0.18s) --- PASS: TestCheck/3 (0.18s) --- PASS: TestCheck/4 (0.18s) --- PASS: TestCheck/5 (0.22s) --- PASS: TestCheck/6 (0.24s) --- PASS: TestCheck/7 (0.19s) === RUN TestCheckFsError 2024/04/11 03:52:12 DEBUG : Creating backend with remote "nonexistent" 2024/04/11 03:52:12 DEBUG : Config file has changed externally - reloading 2024/04/11 03:52:12 DEBUG : Creating backend with remote "nonexistent" 2024/04/11 03:52:12 DEBUG : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: Waiting for checks to finish 2024/04/11 03:52:12 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 03:52:12 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: 2 differences found 2024/04/11 03:52:12 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-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", Modify Window "1ms" === RUN TestCheckDownload/1 === RUN TestCheckDownload/2 === RUN TestCheckDownload/3 === RUN TestCheckDownload/4 === RUN TestCheckDownload/5 === RUN TestCheckDownload/6 === RUN TestCheckDownload/7 --- PASS: TestCheckDownload (6.12s) --- PASS: TestCheckDownload/1 (0.73s) --- PASS: TestCheckDownload/2 (0.41s) --- PASS: TestCheckDownload/3 (0.39s) --- PASS: TestCheckDownload/4 (0.56s) --- PASS: TestCheckDownload/5 (0.38s) --- PASS: TestCheckDownload/6 (0.38s) --- PASS: TestCheckDownload/7 (0.38s) === RUN TestCheckSizeOnly run.go:180: Remote "B2 bucket rclone-test-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", 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.23s) --- PASS: TestCheckSizeOnly/1 (0.18s) --- PASS: TestCheckSizeOnly/2 (0.18s) --- PASS: TestCheckSizeOnly/3 (0.18s) --- PASS: TestCheckSizeOnly/4 (0.18s) --- PASS: TestCheckSizeOnly/5 (0.19s) --- PASS: TestCheckSizeOnly/6 (0.19s) --- PASS: TestCheckSizeOnly/7 (0.18s) === RUN TestCheckEqualReaders --- PASS: TestCheckEqualReaders (0.00s) === RUN TestParseSumFile run.go:180: Remote "B2 bucket rclone-test-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", Modify Window "1ms" 2024/04/11 03:52:24 NOTICE: test.sum: improperly formatted checksum line 4 2024/04/11 03:52:24 NOTICE: test.sum: improperly formatted checksum line 5 2024/04/11 03:52:24 NOTICE: test.sum: improperly formatted checksum line 6 2024/04/11 03:52:24 NOTICE: test.sum: 2 warning(s) suppressed... 2024/04/11 03:52:24 NOTICE: test.sum: improperly formatted checksum line 4 2024/04/11 03:52:24 NOTICE: test.sum: improperly formatted checksum line 5 2024/04/11 03:52:24 NOTICE: test.sum: improperly formatted checksum line 6 2024/04/11 03:52:24 NOTICE: test.sum: 2 warning(s) suppressed... --- PASS: TestParseSumFile (2.57s) === RUN TestCheckSum run.go:180: Remote "B2 bucket rclone-test-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", Modify Window "1ms" 2024/04/11 03:52:25 DEBUG : Creating backend with remote "TestB2:rclone-test-fukadiq7wasiwer1puwumal5/data" check_test.go:354: B2 bucket rclone-test-fukadiq7wasiwer1puwumal5 path data lacks md5, skipping --- SKIP: TestCheckSum (1.19s) === RUN TestCheckSumDownload run.go:180: Remote "B2 bucket rclone-test-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", Modify Window "1ms" 2024/04/11 03:52:26 DEBUG : Creating backend with remote "TestB2:rclone-test-fukadiq7wasiwer1puwumal5/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.75s) --- PASS: TestCheckSumDownload/subtest1 (0.98s) --- PASS: TestCheckSumDownload/subtest2 (0.73s) --- PASS: TestCheckSumDownload/subtest3 (0.97s) --- PASS: TestCheckSumDownload/subtest4 (0.75s) --- PASS: TestCheckSumDownload/subtest5 (0.75s) --- PASS: TestCheckSumDownload/subtest6 (0.78s) --- PASS: TestCheckSumDownload/subtest7 (0.79s) === RUN TestApplyTransforms 2024/04/11 03:52:38 DEBUG : Creating backend with remote "TestB2:rclone-test-valibol4sunapel5dodutih3" 2024/04/11 03:52:38 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/04/11 03:52:38 DEBUG : Creating backend with remote "/tmp/rclone1340090994" run.go:180: Remote "B2 bucket rclone-test-valibol4sunapel5dodutih3", Local "Local file system at /tmp/rclone1340090994", Modify Window "1ms" upper checkfile vs. lower remote (without normalization) 2024/04/11 03:52:40 ERROR : hello, world!: sum not found 2024/04/11 03:52:40 ERROR : HELLO, WORLD!: file not in B2 bucket rclone-test-valibol4sunapel5dodutih3 2024/04/11 03:52:40 NOTICE: B2 bucket rclone-test-valibol4sunapel5dodutih3: 1 files missing 2024/04/11 03:52:40 NOTICE: 1 hashes missing 2024/04/11 03:52:40 NOTICE: B2 bucket rclone-test-valibol4sunapel5dodutih3: 2 differences found 2024/04/11 03:52:40 NOTICE: B2 bucket rclone-test-valibol4sunapel5dodutih3: 2 errors while checking upper checkfile vs. lower remote (with normalization) 2024/04/11 03:52:41 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2024/04/11 03:52:41 NOTICE: B2 bucket rclone-test-valibol4sunapel5dodutih3: 0 differences found 2024/04/11 03:52:41 NOTICE: B2 bucket rclone-test-valibol4sunapel5dodutih3: 1 matching files 2024/04/11 03:52:41 DEBUG : Creating backend with remote "TestB2:rclone-test-xewozop0hojixoc1kizigen6" 2024/04/11 03:52:41 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/04/11 03:52:41 DEBUG : Creating backend with remote "/tmp/rclone3722369646" run.go:180: Remote "B2 bucket rclone-test-xewozop0hojixoc1kizigen6", Local "Local file system at /tmp/rclone3722369646", Modify Window "1ms" lower checkfile vs. upper remote (without normalization) 2024/04/11 03:52:43 ERROR : HELLO, WORLD!: sum not found 2024/04/11 03:52:43 ERROR : hello, world!: file not in B2 bucket rclone-test-xewozop0hojixoc1kizigen6 2024/04/11 03:52:43 NOTICE: B2 bucket rclone-test-xewozop0hojixoc1kizigen6: 1 files missing 2024/04/11 03:52:43 NOTICE: 1 hashes missing 2024/04/11 03:52:43 NOTICE: B2 bucket rclone-test-xewozop0hojixoc1kizigen6: 2 differences found 2024/04/11 03:52:43 NOTICE: B2 bucket rclone-test-xewozop0hojixoc1kizigen6: 2 errors while checking lower checkfile vs. upper remote (with normalization) 2024/04/11 03:52:43 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2024/04/11 03:52:43 NOTICE: B2 bucket rclone-test-xewozop0hojixoc1kizigen6: 0 differences found 2024/04/11 03:52:43 NOTICE: B2 bucket rclone-test-xewozop0hojixoc1kizigen6: 1 matching files 2024/04/11 03:52:43 DEBUG : Creating backend with remote "TestB2:rclone-test-ruhemay1wisimod7tudovev3" 2024/04/11 03:52:43 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/04/11 03:52:43 DEBUG : Creating backend with remote "/tmp/rclone3279998" run.go:180: Remote "B2 bucket rclone-test-ruhemay1wisimod7tudovev3", Local "Local file system at /tmp/rclone3279998", Modify Window "1ms" lower checkfile vs. upperlowermixed remote (without normalization) 2024/04/11 03:52:45 ERROR : HeLlO, wOrLd!: sum not found 2024/04/11 03:52:45 ERROR : hello, world!: file not in B2 bucket rclone-test-ruhemay1wisimod7tudovev3 2024/04/11 03:52:45 NOTICE: B2 bucket rclone-test-ruhemay1wisimod7tudovev3: 1 files missing 2024/04/11 03:52:45 NOTICE: 1 hashes missing 2024/04/11 03:52:45 NOTICE: B2 bucket rclone-test-ruhemay1wisimod7tudovev3: 2 differences found 2024/04/11 03:52:45 NOTICE: B2 bucket rclone-test-ruhemay1wisimod7tudovev3: 2 errors while checking lower checkfile vs. upperlowermixed remote (with normalization) 2024/04/11 03:52:45 DEBUG : HeLlO, wOrLd!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2024/04/11 03:52:45 NOTICE: B2 bucket rclone-test-ruhemay1wisimod7tudovev3: 0 differences found 2024/04/11 03:52:45 NOTICE: B2 bucket rclone-test-ruhemay1wisimod7tudovev3: 1 matching files 2024/04/11 03:52:45 DEBUG : Creating backend with remote "TestB2:rclone-test-nirocik9yigaleb4pihimod3" 2024/04/11 03:52:45 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/04/11 03:52:46 DEBUG : Creating backend with remote "/tmp/rclone3945022458" run.go:180: Remote "B2 bucket rclone-test-nirocik9yigaleb4pihimod3", Local "Local file system at /tmp/rclone3945022458", Modify Window "1ms" upperlowermixed checkfile vs. upper remote (without normalization) 2024/04/11 03:52:47 ERROR : HELLO, WORLD!: sum not found 2024/04/11 03:52:47 ERROR : HeLlO, wOrLd!: file not in B2 bucket rclone-test-nirocik9yigaleb4pihimod3 2024/04/11 03:52:47 NOTICE: B2 bucket rclone-test-nirocik9yigaleb4pihimod3: 1 files missing 2024/04/11 03:52:47 NOTICE: 1 hashes missing 2024/04/11 03:52:47 NOTICE: B2 bucket rclone-test-nirocik9yigaleb4pihimod3: 2 differences found 2024/04/11 03:52:47 NOTICE: B2 bucket rclone-test-nirocik9yigaleb4pihimod3: 2 errors while checking upperlowermixed checkfile vs. upper remote (with normalization) 2024/04/11 03:52:48 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2024/04/11 03:52:48 NOTICE: B2 bucket rclone-test-nirocik9yigaleb4pihimod3: 0 differences found 2024/04/11 03:52:48 NOTICE: B2 bucket rclone-test-nirocik9yigaleb4pihimod3: 1 matching files 2024/04/11 03:52:48 DEBUG : Creating backend with remote "TestB2:rclone-test-fecevoh2ruhedin7dovexad2" 2024/04/11 03:52:48 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/04/11 03:52:48 DEBUG : Creating backend with remote "/tmp/rclone3840788843" run.go:180: Remote "B2 bucket rclone-test-fecevoh2ruhedin7dovexad2", Local "Local file system at /tmp/rclone3840788843", Modify Window "1ms" NFD checkfile vs. NFC remote (without normalization) 2024/04/11 03:52:50 ERROR : 測試_Русский___ě_áñ: sum not found 2024/04/11 03:52:50 ERROR : 測試_Русский___ě_áñ: file not in B2 bucket rclone-test-fecevoh2ruhedin7dovexad2 2024/04/11 03:52:50 NOTICE: B2 bucket rclone-test-fecevoh2ruhedin7dovexad2: 1 files missing 2024/04/11 03:52:50 NOTICE: 1 hashes missing 2024/04/11 03:52:50 NOTICE: B2 bucket rclone-test-fecevoh2ruhedin7dovexad2: 2 differences found 2024/04/11 03:52:50 NOTICE: B2 bucket rclone-test-fecevoh2ruhedin7dovexad2: 2 errors while checking NFD checkfile vs. NFC remote (with normalization) 2024/04/11 03:52:50 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2024/04/11 03:52:50 NOTICE: B2 bucket rclone-test-fecevoh2ruhedin7dovexad2: 0 differences found 2024/04/11 03:52:50 NOTICE: B2 bucket rclone-test-fecevoh2ruhedin7dovexad2: 1 matching files 2024/04/11 03:52:50 DEBUG : Creating backend with remote "TestB2:rclone-test-lacikon8sakuceb5fawajid3" 2024/04/11 03:52:50 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/04/11 03:52:51 DEBUG : Creating backend with remote "/tmp/rclone1008439023" run.go:180: Remote "B2 bucket rclone-test-lacikon8sakuceb5fawajid3", Local "Local file system at /tmp/rclone1008439023", Modify Window "1ms" NFC checkfile vs. NFD remote (without normalization) 2024/04/11 03:52:52 ERROR : 測試_Русский___ě_áñ: sum not found 2024/04/11 03:52:52 ERROR : 測試_Русский___ě_áñ: file not in B2 bucket rclone-test-lacikon8sakuceb5fawajid3 2024/04/11 03:52:52 NOTICE: B2 bucket rclone-test-lacikon8sakuceb5fawajid3: 1 files missing 2024/04/11 03:52:52 NOTICE: 1 hashes missing 2024/04/11 03:52:52 NOTICE: B2 bucket rclone-test-lacikon8sakuceb5fawajid3: 2 differences found 2024/04/11 03:52:52 NOTICE: B2 bucket rclone-test-lacikon8sakuceb5fawajid3: 2 errors while checking NFC checkfile vs. NFD remote (with normalization) 2024/04/11 03:52:53 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2024/04/11 03:52:53 NOTICE: B2 bucket rclone-test-lacikon8sakuceb5fawajid3: 0 differences found 2024/04/11 03:52:53 NOTICE: B2 bucket rclone-test-lacikon8sakuceb5fawajid3: 1 matching files 2024/04/11 03:52:53 DEBUG : Creating backend with remote "TestB2:rclone-test-jidexud5kunutog7xeporiz4" 2024/04/11 03:52:53 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/04/11 03:52:53 DEBUG : Creating backend with remote "/tmp/rclone543851274" run.go:180: Remote "B2 bucket rclone-test-jidexud5kunutog7xeporiz4", Local "Local file system at /tmp/rclone543851274", Modify Window "1ms" NFDx2 checkfile vs. both remote (without normalization) 2024/04/11 03:52:55 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2024/04/11 03:52:55 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-jidexud5kunutog7xeporiz4 2024/04/11 03:52:55 NOTICE: B2 bucket rclone-test-jidexud5kunutog7xeporiz4: 1 files missing 2024/04/11 03:52:55 NOTICE: 1 hashes missing 2024/04/11 03:52:55 NOTICE: B2 bucket rclone-test-jidexud5kunutog7xeporiz4: 2 differences found 2024/04/11 03:52:55 NOTICE: B2 bucket rclone-test-jidexud5kunutog7xeporiz4: 2 errors while checking NFDx2 checkfile vs. both remote (with normalization) 2024/04/11 03:52:55 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2024/04/11 03:52:55 NOTICE: B2 bucket rclone-test-jidexud5kunutog7xeporiz4: 0 differences found 2024/04/11 03:52:55 NOTICE: B2 bucket rclone-test-jidexud5kunutog7xeporiz4: 1 matching files 2024/04/11 03:52:55 DEBUG : Creating backend with remote "TestB2:rclone-test-gudomec5xohocoz8fexuwok7" 2024/04/11 03:52:55 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/04/11 03:52:55 DEBUG : Creating backend with remote "/tmp/rclone2433608473" run.go:180: Remote "B2 bucket rclone-test-gudomec5xohocoz8fexuwok7", Local "Local file system at /tmp/rclone2433608473", Modify Window "1ms" NFCx2 checkfile vs. both remote (without normalization) 2024/04/11 03:52:57 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2024/04/11 03:52:57 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-gudomec5xohocoz8fexuwok7 2024/04/11 03:52:57 NOTICE: B2 bucket rclone-test-gudomec5xohocoz8fexuwok7: 1 files missing 2024/04/11 03:52:57 NOTICE: 1 hashes missing 2024/04/11 03:52:57 NOTICE: B2 bucket rclone-test-gudomec5xohocoz8fexuwok7: 2 differences found 2024/04/11 03:52:57 NOTICE: B2 bucket rclone-test-gudomec5xohocoz8fexuwok7: 2 errors while checking NFCx2 checkfile vs. both remote (with normalization) 2024/04/11 03:52:57 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2024/04/11 03:52:57 NOTICE: B2 bucket rclone-test-gudomec5xohocoz8fexuwok7: 0 differences found 2024/04/11 03:52:57 NOTICE: B2 bucket rclone-test-gudomec5xohocoz8fexuwok7: 1 matching files 2024/04/11 03:52:57 DEBUG : Creating backend with remote "TestB2:rclone-test-yeyicop5romonek1megiyot2" 2024/04/11 03:52:57 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/04/11 03:52:58 DEBUG : Creating backend with remote "/tmp/rclone3139419260" run.go:180: Remote "B2 bucket rclone-test-yeyicop5romonek1megiyot2", Local "Local file system at /tmp/rclone3139419260", Modify Window "1ms" both checkfile vs. NFDx2 remote (without normalization) 2024/04/11 03:52:59 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2024/04/11 03:52:59 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-yeyicop5romonek1megiyot2 2024/04/11 03:52:59 NOTICE: B2 bucket rclone-test-yeyicop5romonek1megiyot2: 1 files missing 2024/04/11 03:52:59 NOTICE: 1 hashes missing 2024/04/11 03:52:59 NOTICE: B2 bucket rclone-test-yeyicop5romonek1megiyot2: 2 differences found 2024/04/11 03:52:59 NOTICE: B2 bucket rclone-test-yeyicop5romonek1megiyot2: 2 errors while checking both checkfile vs. NFDx2 remote (with normalization) 2024/04/11 03:53:00 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2024/04/11 03:53:00 NOTICE: B2 bucket rclone-test-yeyicop5romonek1megiyot2: 0 differences found 2024/04/11 03:53:00 NOTICE: B2 bucket rclone-test-yeyicop5romonek1megiyot2: 1 matching files 2024/04/11 03:53:00 DEBUG : Creating backend with remote "TestB2:rclone-test-nihucuy8cocuqex5huyijow6" 2024/04/11 03:53:00 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/04/11 03:53:00 DEBUG : Creating backend with remote "/tmp/rclone3241931987" run.go:180: Remote "B2 bucket rclone-test-nihucuy8cocuqex5huyijow6", Local "Local file system at /tmp/rclone3241931987", Modify Window "1ms" both checkfile vs. NFCx2 remote (without normalization) 2024/04/11 03:53:02 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2024/04/11 03:53:02 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-nihucuy8cocuqex5huyijow6 2024/04/11 03:53:02 NOTICE: B2 bucket rclone-test-nihucuy8cocuqex5huyijow6: 1 files missing 2024/04/11 03:53:02 NOTICE: 1 hashes missing 2024/04/11 03:53:02 NOTICE: B2 bucket rclone-test-nihucuy8cocuqex5huyijow6: 2 differences found 2024/04/11 03:53:02 NOTICE: B2 bucket rclone-test-nihucuy8cocuqex5huyijow6: 2 errors while checking both checkfile vs. NFCx2 remote (with normalization) 2024/04/11 03:53:02 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2024/04/11 03:53:02 NOTICE: B2 bucket rclone-test-nihucuy8cocuqex5huyijow6: 0 differences found 2024/04/11 03:53:02 NOTICE: B2 bucket rclone-test-nihucuy8cocuqex5huyijow6: 1 matching files 2024/04/11 03:53:02 DEBUG : B2 bucket rclone-test-nihucuy8cocuqex5huyijow6: Purge remote 2024/04/11 03:53:02 INFO : B2 bucket rclone-test-nihucuy8cocuqex5huyijow6: cleaning bucket "rclone-test-nihucuy8cocuqex5huyijow6" of all files 2024/04/11 03:53:02 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_z9f529d33409dcbbb8fe80e1b_f116138a57bb2cdf4_d20240411_m035301_c001_v0001161_t0055_u01712807581644") 2024/04/11 03:53:03 DEBUG : B2 bucket rclone-test-yeyicop5romonek1megiyot2: Purge remote 2024/04/11 03:53:03 INFO : B2 bucket rclone-test-yeyicop5romonek1megiyot2: cleaning bucket "rclone-test-yeyicop5romonek1megiyot2" of all files 2024/04/11 03:53:03 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_z5f428da3409dcbbb8fe80e1b_f118d8cdad829c38b_d20240411_m035259_c001_v0001161_t0002_u01712807579229") 2024/04/11 03:53:04 DEBUG : B2 bucket rclone-test-gudomec5xohocoz8fexuwok7: Purge remote 2024/04/11 03:53:04 INFO : B2 bucket rclone-test-gudomec5xohocoz8fexuwok7: cleaning bucket "rclone-test-gudomec5xohocoz8fexuwok7" of all files 2024/04/11 03:53:04 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_zaf628d13409dcbbb8fe80e1b_f1158f61d45e6356c_d20240411_m035256_c001_v0001181_t0018_u01712807576997") 2024/04/11 03:53:05 DEBUG : B2 bucket rclone-test-jidexud5kunutog7xeporiz4: Purge remote 2024/04/11 03:53:05 INFO : B2 bucket rclone-test-jidexud5kunutog7xeporiz4: cleaning bucket "rclone-test-jidexud5kunutog7xeporiz4" of all files 2024/04/11 03:53:05 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_z4f027d83409dcbbb8fe80e1b_f114c97d2a782e726_d20240411_m035254_c001_v0001157_t0009_u01712807574609") 2024/04/11 03:53:06 DEBUG : B2 bucket rclone-test-lacikon8sakuceb5fawajid3: Purge remote 2024/04/11 03:53:06 INFO : B2 bucket rclone-test-lacikon8sakuceb5fawajid3: cleaning bucket "rclone-test-lacikon8sakuceb5fawajid3" of all files 2024/04/11 03:53:06 DEBUG : 測試_Русский___ě_áñ: Deleting (id "4_zaf426df3409dcbbb8fe80e1b_f113fb0836d872af9_d20240411_m035252_c001_v0001179_t0043_u01712807572340") 2024/04/11 03:53:07 DEBUG : B2 bucket rclone-test-fecevoh2ruhedin7dovexad2: Purge remote 2024/04/11 03:53:07 INFO : B2 bucket rclone-test-fecevoh2ruhedin7dovexad2: cleaning bucket "rclone-test-fecevoh2ruhedin7dovexad2" of all files 2024/04/11 03:53:07 DEBUG : 測試_Русский___ě_áñ: Deleting (id "4_z8f526d63409dcbbb8fe80e1b_f115fff04eee5b1bb_d20240411_m035250_c001_v0001131_t0019_u01712807570024") 2024/04/11 03:53:08 DEBUG : B2 bucket rclone-test-nirocik9yigaleb4pihimod3: Purge remote 2024/04/11 03:53:08 INFO : B2 bucket rclone-test-nirocik9yigaleb4pihimod3: cleaning bucket "rclone-test-nirocik9yigaleb4pihimod3" of all files 2024/04/11 03:53:08 DEBUG : HELLO, WORLD!: Deleting (id "4_z5f525db3409dcbbb8fe80e1b_f104feb130893a74a_d20240411_m035247_c001_v0001033_t0053_u01712807567282") 2024/04/11 03:53:09 DEBUG : B2 bucket rclone-test-ruhemay1wisimod7tudovev3: Purge remote 2024/04/11 03:53:09 INFO : B2 bucket rclone-test-ruhemay1wisimod7tudovev3: cleaning bucket "rclone-test-ruhemay1wisimod7tudovev3" of all files 2024/04/11 03:53:09 DEBUG : HeLlO, wOrLd!: Deleting (id "4_z4fb25d23409dcbbb8fe80e1b_f11549522fbad1bf5_d20240411_m035244_c001_v0001165_t0051_u01712807564924") 2024/04/11 03:53:10 DEBUG : B2 bucket rclone-test-xewozop0hojixoc1kizigen6: Purge remote 2024/04/11 03:53:10 INFO : B2 bucket rclone-test-xewozop0hojixoc1kizigen6: cleaning bucket "rclone-test-xewozop0hojixoc1kizigen6" of all files 2024/04/11 03:53:10 DEBUG : HELLO, WORLD!: Deleting (id "4_z9f024d83409dcbbb8fe80e1b_f101404c53540c2e4_d20240411_m035242_c001_v0001179_t0018_u01712807562364") 2024/04/11 03:53:11 DEBUG : B2 bucket rclone-test-valibol4sunapel5dodutih3: Purge remote 2024/04/11 03:53:11 INFO : B2 bucket rclone-test-valibol4sunapel5dodutih3: cleaning bucket "rclone-test-valibol4sunapel5dodutih3" of all files 2024/04/11 03:53:11 DEBUG : hello, world!: Deleting (id "4_z2f023df3409dcbbb8fe80e1b_f113fc3350f8b9e1b_d20240411_m035240_c001_v0001172_t0038_u01712807560096") --- PASS: TestApplyTransforms (33.87s) === RUN TestTruncateString --- PASS: TestTruncateString (0.00s) === RUN TestCopyFile run.go:180: Remote "B2 bucket rclone-test-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", Modify Window "1ms" 2024/04/11 03:53:12 DEBUG : file1: Need to transfer - File not found at Destination 2024/04/11 03:53:13 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2024/04/11 03:53:13 INFO : file1: Copied (new) to: sub/file2 2024/04/11 03:53:13 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/04/11 03:53:13 DEBUG : file1: Unchanged skipping 2024/04/11 03:53:13 DEBUG : B2 bucket rclone-test-fukadiq7wasiwer1puwumal5: don't need to copy/move sub/file2, it is already at target location --- PASS: TestCopyFile (2.50s) === RUN TestCopyLongFile run.go:180: Remote "B2 bucket rclone-test-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", 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-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", Modify Window "1ms" 2024/04/11 03:53:15 DEBUG : Creating backend with remote "TestB2:rclone-test-fukadiq7wasiwer1puwumal5/backup" 2024/04/11 03:53:16 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18) 2024/04/11 03:53:17 DEBUG : dst/file1: sha1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK 2024/04/11 03:53:17 INFO : dst/file1: Copied (server-side copy) 2024/04/11 03:53:17 INFO : dst/file1: Deleted 2024/04/11 03:53:17 DEBUG : dst/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2024/04/11 03:53:17 INFO : dst/file1: Copied (new) --- PASS: TestCopyFileBackupDir (3.78s) === RUN TestCopyFileCompareDest run.go:180: Remote "B2 bucket rclone-test-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", Modify Window "1ms" 2024/04/11 03:53:18 DEBUG : Creating backend with remote "TestB2:rclone-test-fukadiq7wasiwer1puwumal5/dst" 2024/04/11 03:53:19 DEBUG : Creating backend with remote "TestB2:rclone-test-fukadiq7wasiwer1puwumal5/CompareDest" 2024/04/11 03:53:19 DEBUG : one: Need to transfer - File not found at Destination 2024/04/11 03:53:21 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2024/04/11 03:53:21 INFO : one: Copied (new) 2024/04/11 03:53:21 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/04/11 03:53:22 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2024/04/11 03:53:22 INFO : one: Copied (replaced existing) 2024/04/11 03:53:23 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/04/11 03:53:23 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2024/04/11 03:53:23 DEBUG : one: Destination found in --compare-dest, skipping 2024/04/11 03:53:24 DEBUG : two: Need to transfer - File not found at Destination 2024/04/11 03:53:24 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2024/04/11 03:53:24 DEBUG : two: Destination found in --compare-dest, skipping 2024/04/11 03:53:24 DEBUG : two: Need to transfer - File not found at Destination 2024/04/11 03:53:24 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2024/04/11 03:53:24 DEBUG : two: Destination found in --compare-dest, skipping 2024/04/11 03:53:25 DEBUG : two: Need to transfer - File not found at Destination 2024/04/11 03:53:25 DEBUG : two: Sizes differ (src 5 vs dst 3) 2024/04/11 03:53:25 DEBUG : two: sha1 = a4be8877ad88f3109a6411985a1f359e10e2d930 OK 2024/04/11 03:53:25 INFO : two: Copied (new) --- PASS: TestCopyFileCompareDest (8.37s) === RUN TestCopyFileCopyDest run.go:180: Remote "B2 bucket rclone-test-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", Modify Window "1ms" 2024/04/11 03:53:27 DEBUG : Creating backend with remote "TestB2:rclone-test-fukadiq7wasiwer1puwumal5/dst" 2024/04/11 03:53:28 DEBUG : Creating backend with remote "TestB2:rclone-test-fukadiq7wasiwer1puwumal5/CopyDest" 2024/04/11 03:53:28 DEBUG : one: Need to transfer - File not found at Destination 2024/04/11 03:53:30 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2024/04/11 03:53:30 INFO : one: Copied (new) 2024/04/11 03:53:30 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/04/11 03:53:31 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2024/04/11 03:53:31 INFO : one: Copied (replaced existing) 2024/04/11 03:53:32 DEBUG : Creating backend with remote "TestB2:rclone-test-fukadiq7wasiwer1puwumal5/BackupDir" 2024/04/11 03:53:33 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/04/11 03:53:33 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2024/04/11 03:53:33 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/04/11 03:53:34 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2024/04/11 03:53:34 INFO : one: Copied (server-side copy) 2024/04/11 03:53:34 INFO : one: Deleted 2024/04/11 03:53:35 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2024/04/11 03:53:35 INFO : one: Copied (server-side copy) 2024/04/11 03:53:35 DEBUG : one: Destination found in --copy-dest, using server-side copy 2024/04/11 03:53:35 DEBUG : two: Need to transfer - File not found at Destination 2024/04/11 03:53:36 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2024/04/11 03:53:36 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2024/04/11 03:53:36 INFO : two: Copied (server-side copy) 2024/04/11 03:53:36 DEBUG : two: Destination found in --copy-dest, using server-side copy 2024/04/11 03:53:36 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2024/04/11 03:53:36 DEBUG : two: Unchanged skipping 2024/04/11 03:53:37 DEBUG : three: Need to transfer - File not found at Destination 2024/04/11 03:53:37 DEBUG : three: Sizes differ (src 7 vs dst 5) 2024/04/11 03:53:37 DEBUG : three: Destination not found in --copy-dest 2024/04/11 03:53:38 DEBUG : three: sha1 = 370e613335f3cf2f19b2a2d35a86402f55c659f8 OK 2024/04/11 03:53:38 INFO : three: Copied (new) --- PASS: TestCopyFileCopyDest (13.19s) === RUN TestCopyInplace run.go:180: Remote "B2 bucket rclone-test-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", Modify Window "1ms" copy_test.go:369: Partial uploads not supported --- SKIP: TestCopyInplace (0.38s) === RUN TestCopyLongFileName run.go:180: Remote "B2 bucket rclone-test-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", Modify Window "1ms" copy_test.go:402: Partial uploads not supported --- SKIP: TestCopyLongFileName (0.36s) === RUN TestCopyFileMaxTransfer run.go:180: Remote "B2 bucket rclone-test-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", Modify Window "1ms" 2024/04/11 03:53:41 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination 2024/04/11 03:53:41 DEBUG : TestCopyFileMaxTransfer/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2024/04/11 03:53:41 INFO : TestCopyFileMaxTransfer/file1: Copied (new) 2024/04/11 03:53:41 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination 2024/04/11 03:53:41 ERROR : TestCopyFileMaxTransfer/file2: Failed to copy: Post "https://pod-000-1179-19.backblaze.com/b2api/v1/b2_upload_file/ff52ad73406dcbbb8fe80e1b/c001_v0001179_t0026": max transfer limit reached as set by --max-transfer 2024/04/11 03:53:42 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination 2024/04/11 03:53:42 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination 2024/04/11 03:53:43 DEBUG : TestCopyFileMaxTransfer/file4: sha1 = 631704722e7e159d6a9256641dd288ffed98d3bb OK 2024/04/11 03:53:43 INFO : TestCopyFileMaxTransfer/file4: Copied (new) --- PASS: TestCopyFileMaxTransfer (3.11s) === RUN TestDeduplicateInteractive run.go:180: Remote "B2 bucket rclone-test-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", 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-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSkip (0.37s) === RUN TestDeduplicateSizeOnly run.go:180: Remote "B2 bucket rclone-test-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", 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-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateFirst (0.40s) === RUN TestDeduplicateNewest run.go:180: Remote "B2 bucket rclone-test-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateNewest (0.36s) === RUN TestDeduplicateNewestByHash run.go:180: Remote "B2 bucket rclone-test-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", Modify Window "1ms" 2024/04/11 03:53:47 INFO : B2 bucket rclone-test-fukadiq7wasiwer1puwumal5: Looking for duplicate sha1 hashes using newest mode. 2024/04/11 03:53:47 NOTICE: b981beaa953545164b4b9379f2bd2136f51ffc2c: Found 3 files with duplicate sha1 hashes 2024/04/11 03:53:48 INFO : one: Deleted 2024/04/11 03:53:48 INFO : also/one: Deleted 2024/04/11 03:53:48 NOTICE: b981beaa953545164b4b9379f2bd2136f51ffc2c: Deleted 2 extra copies --- PASS: TestDeduplicateNewestByHash (3.22s) === RUN TestDeduplicateOldest run.go:180: Remote "B2 bucket rclone-test-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", 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-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateLargest (0.37s) === RUN TestDeduplicateSmallest run.go:180: Remote "B2 bucket rclone-test-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSmallest (0.36s) === RUN TestDeduplicateRename run.go:180: Remote "B2 bucket rclone-test-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateRename (0.36s) === RUN TestMergeDirs run.go:180: Remote "B2 bucket rclone-test-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", Modify Window "1ms" dedupe_test.go:256: Can't merge directories --- SKIP: TestMergeDirs (0.36s) === RUN TestListDirSorted run.go:180: Remote "B2 bucket rclone-test-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", Modify Window "1ms" 2024/04/11 03:53:53 DEBUG : a.txt: Excluded (Size Filter) 2024/04/11 03:53:53 DEBUG : a.txt: Excluded 2024/04/11 03:53:54 DEBUG : sub dir/hello world: Excluded (Size Filter) 2024/04/11 03:53:54 DEBUG : sub dir/hello world: Excluded 2024/04/11 03:53:54 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2024/04/11 03:53:54 DEBUG : sub dir/hello world2: Excluded 2024/04/11 03:53:54 DEBUG : sub dir/hello world: Excluded (Size Filter) 2024/04/11 03:53:54 DEBUG : sub dir/hello world: Excluded 2024/04/11 03:53:54 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2024/04/11 03:53:54 DEBUG : sub dir/hello world2: Excluded 2024/04/11 03:53:55 DEBUG : sub dir/ignore dir: Excluded 2024/04/11 03:53:55 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSorted (6.46s) === RUN TestListJSON run.go:180: Remote "B2 bucket rclone-test-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", 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 03:54:00 ERROR : file1: Failed to read hash: hash type not supported === RUN TestListJSON/Metadata --- PASS: TestListJSON (3.60s) --- PASS: TestListJSON/Default (0.20s) --- PASS: TestListJSON/FilesOnly (0.19s) --- PASS: TestListJSON/DirsOnly (0.19s) --- PASS: TestListJSON/Recurse (0.18s) --- PASS: TestListJSON/SubDir (0.18s) --- PASS: TestListJSON/NoModTime (0.19s) --- PASS: TestListJSON/NoMimeType (0.21s) --- PASS: TestListJSON/ShowHash (0.19s) --- PASS: TestListJSON/HashTypes (0.18s) --- PASS: TestListJSON/Metadata (0.18s) === RUN TestStatJSON run.go:180: Remote "B2 bucket rclone-test-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", 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 03:54:05 DEBUG : Creating backend with remote "TestB2:rclone-test-fukadiq7wasiwer1puwumal5/notfound" --- PASS: TestStatJSON (5.91s) --- PASS: TestStatJSON/Root (0.19s) --- PASS: TestStatJSON/RootFilesOnly (0.00s) --- PASS: TestStatJSON/RootDirsOnly (0.18s) --- PASS: TestStatJSON/Dir (0.77s) --- PASS: TestStatJSON/DirWithTrailingSlash (0.19s) --- PASS: TestStatJSON/File (0.18s) --- PASS: TestStatJSON/NotFound (0.37s) --- PASS: TestStatJSON/DirFilesOnly (0.18s) --- PASS: TestStatJSON/FileFilesOnly (0.18s) --- PASS: TestStatJSON/NotFoundFilesOnly (0.18s) --- PASS: TestStatJSON/DirDirsOnly (0.20s) --- PASS: TestStatJSON/FileDirsOnly (0.19s) --- PASS: TestStatJSON/NotFoundDirsOnly (0.18s) --- PASS: TestStatJSON/RootNotFound (1.17s) === RUN TestMkdir run.go:180: Remote "B2 bucket rclone-test-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", Modify Window "1ms" 2024/04/11 03:54:07 DEBUG : B2 bucket rclone-test-fukadiq7wasiwer1puwumal5: Making directory 2024/04/11 03:54:07 DEBUG : B2 bucket rclone-test-fukadiq7wasiwer1puwumal5: Making directory --- PASS: TestMkdir (0.55s) === RUN TestLsd run.go:180: Remote "B2 bucket rclone-test-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", Modify Window "1ms" --- PASS: TestLsd (1.54s) === RUN TestLs run.go:180: Remote "B2 bucket rclone-test-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", Modify Window "1ms" --- PASS: TestLs (1.53s) === RUN TestLsWithFilesFrom run.go:180: Remote "B2 bucket rclone-test-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", Modify Window "1ms" 2024/04/11 03:54:11 DEBUG : empty space: Excluded (FilesFrom Filter) --- PASS: TestLsWithFilesFrom (2.47s) === RUN TestLsLong run.go:180: Remote "B2 bucket rclone-test-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", Modify Window "1ms" --- PASS: TestLsLong (1.66s) === RUN TestHashSums run.go:180: Remote "B2 bucket rclone-test-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", Modify Window "1ms" === RUN TestHashSums/Sha1 === RUN TestHashSums/Sha1Download --- PASS: TestHashSums (2.51s) --- PASS: TestHashSums/Sha1 (0.19s) --- PASS: TestHashSums/Sha1Download (0.39s) === RUN TestHashSumsWithErrors 2024/04/11 03:54:17 DEBUG : Creating backend with remote ":memory:" 2024/04/11 03:54:17 ERROR : file1: hash unsupported: hash type not supported --- PASS: TestHashSumsWithErrors (0.00s) === RUN TestHashStream 2024/04/11 03:54:17 DEBUG : Creating md5 hash of 0 bytes read from input stream 2024/04/11 03:54:17 DEBUG : Creating md5 hash of 0 bytes read from input stream 2024/04/11 03:54:17 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2024/04/11 03:54:17 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2024/04/11 03:54:17 DEBUG : Creating md5 hash of 12 bytes read from input stream 2024/04/11 03:54:17 DEBUG : Creating md5 hash of 12 bytes read from input stream 2024/04/11 03:54:17 DEBUG : Creating sha1 hash of 12 bytes read from input stream 2024/04/11 03:54:17 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-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", Modify Window "1ms" --- PASS: TestCount (2.18s) === RUN TestDelete run.go:180: Remote "B2 bucket rclone-test-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", Modify Window "1ms" 2024/04/11 03:54:20 DEBUG : Waiting for deletions to finish 2024/04/11 03:54:20 DEBUG : large: Excluded (Size Filter) 2024/04/11 03:54:21 INFO : medium: Deleted 2024/04/11 03:54:21 INFO : small: Deleted --- PASS: TestDelete (2.53s) === RUN TestMaxDelete run.go:180: Remote "B2 bucket rclone-test-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", Modify Window "1ms" 2024/04/11 03:54:24 DEBUG : Waiting for deletions to finish 2024/04/11 03:54:24 ERROR : small: Got fatal error on delete: --max-delete threshold reached 2024/04/11 03:54:25 INFO : large: Deleted 2024/04/11 03:54:25 INFO : medium: Deleted --- PASS: TestMaxDelete (4.28s) === RUN TestMaxDeleteSizeLargeFile run.go:180: Remote "B2 bucket rclone-test-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", Modify Window "1ms" 2024/04/11 03:54:27 DEBUG : Waiting for deletions to finish 2024/04/11 03:54:27 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached 2024/04/11 03:54:27 INFO : medium: Deleted 2024/04/11 03:54:27 INFO : small: Deleted --- PASS: TestMaxDeleteSizeLargeFile (2.49s) === RUN TestMaxDeleteSize run.go:180: Remote "B2 bucket rclone-test-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", Modify Window "1ms" 2024/04/11 03:54:30 DEBUG : Waiting for deletions to finish 2024/04/11 03:54:30 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached 2024/04/11 03:54:30 INFO : small: Deleted 2024/04/11 03:54:31 INFO : medium: Deleted --- PASS: TestMaxDeleteSize (3.42s) === RUN TestRetry 2024/04/11 03:54:32 DEBUG : Received error: EOF - low level retry 1/5 2024/04/11 03:54:32 DEBUG : Received error: EOF - low level retry 2/5 2024/04/11 03:54:32 DEBUG : Received error: EOF - low level retry 1/5 2024/04/11 03:54:32 DEBUG : Received error: EOF - low level retry 2/5 2024/04/11 03:54:32 DEBUG : Received error: EOF - low level retry 3/5 2024/04/11 03:54:32 DEBUG : Received error: EOF - low level retry 4/5 2024/04/11 03:54:32 DEBUG : Received error: EOF - low level retry 5/5 --- PASS: TestRetry (0.00s) === RUN TestCat run.go:180: Remote "B2 bucket rclone-test-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", Modify Window "1ms" --- PASS: TestCat (4.50s) === RUN TestPurge 2024/04/11 03:54:36 DEBUG : Creating backend with remote "TestB2:rclone-test-fobuxeg7corifej3citariz4" 2024/04/11 03:54:36 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/04/11 03:54:37 DEBUG : Creating backend with remote "/tmp/rclone1523870093" run.go:180: Remote "B2 bucket rclone-test-fobuxeg7corifej3citariz4", Local "Local file system at /tmp/rclone1523870093", Modify Window "1ms" 2024/04/11 03:54:38 DEBUG : A2: Making directory 2024/04/11 03:54:38 DEBUG : A1/B2: Making directory 2024/04/11 03:54:38 DEBUG : A1/B2/C2: Making directory 2024/04/11 03:54:38 DEBUG : A1/B1/C3: Making directory 2024/04/11 03:54:38 DEBUG : A3: Making directory 2024/04/11 03:54:38 DEBUG : A3/B3: Making directory 2024/04/11 03:54:38 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 03:54:39 INFO : B2 bucket rclone-test-fobuxeg7corifej3citariz4: cleaning bucket "rclone-test-fobuxeg7corifej3citariz4" of all files 2024/04/11 03:54:39 DEBUG : A1/B1/C1/one: Deleting (id "4_zbff20dd340bdcbbb8fe80e1b_f101c514892b82932_d20240411_m035438_c001_v0001099_t0005_u01712807678541") 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 03:54:39 INFO : B2 bucket rclone-test-fobuxeg7corifej3citariz4: cleaning bucket "rclone-test-fobuxeg7corifej3citariz4" of all files 2024/04/11 03:54:40 DEBUG : A1/two: Deleting (id "4_zbff20dd340bdcbbb8fe80e1b_f101c514892b82938_d20240411_m035438_c001_v0001099_t0024_u01712807678895") 2024/04/11 03:54:41 DEBUG : B2 bucket rclone-test-fobuxeg7corifej3citariz4: Purge remote 2024/04/11 03:54:41 INFO : B2 bucket rclone-test-fobuxeg7corifej3citariz4: cleaning bucket "rclone-test-fobuxeg7corifej3citariz4" of all files 2024/04/11 03:54:41 purge failed: directory not found --- PASS: TestPurge (4.53s) === RUN TestRmdirsNoLeaveRoot run.go:180: Remote "B2 bucket rclone-test-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", Modify Window "1ms" 2024/04/11 03:54:42 DEBUG : A2: Making directory 2024/04/11 03:54:42 DEBUG : A1/B2: Making directory 2024/04/11 03:54:42 DEBUG : A1/B2/C2: Making directory 2024/04/11 03:54:42 DEBUG : A1/B1/C3: Making directory 2024/04/11 03:54:42 DEBUG : A3: Making directory 2024/04/11 03:54:42 DEBUG : A3/B3: Making directory 2024/04/11 03:54:42 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 03:54:42 DEBUG : removing 1 level 3 directories 2024/04/11 03:54:42 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 03:54:44 DEBUG : removing 1 level 0 directories 2024/04/11 03:54:44 INFO : B2 bucket rclone-test-fukadiq7wasiwer1puwumal5: Removing directory 2024/04/11 03:54: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 (3.93s) === RUN TestRmdirsLeaveRoot run.go:180: Remote "B2 bucket rclone-test-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", Modify Window "1ms" 2024/04/11 03:54:45 DEBUG : A1: Making directory 2024/04/11 03:54:45 DEBUG : A1/B1: Making directory 2024/04/11 03:54: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.93s) === RUN TestRmdirsWithFilter run.go:180: Remote "B2 bucket rclone-test-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", Modify Window "1ms" 2024/04/11 03:54:46 DEBUG : A1: Making directory 2024/04/11 03:54:46 DEBUG : A1/B1: Making directory 2024/04/11 03:54: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.96s) === RUN TestCopyURL run.go:180: Remote "B2 bucket rclone-test-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", Modify Window "1ms" 2024/04/11 03:54:48 DEBUG : filename.txt: File name found in url 2024/04/11 03:54:48 DEBUG : headerfilename.txt: filename found in Content-Disposition header. --- PASS: TestCopyURL (3.09s) === RUN TestCopyURLToWriter --- PASS: TestCopyURLToWriter (0.00s) === RUN TestMoveFile run.go:180: Remote "B2 bucket rclone-test-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", Modify Window "1ms" 2024/04/11 03:54:50 DEBUG : file1: Need to transfer - File not found at Destination 2024/04/11 03:54:51 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2024/04/11 03:54:51 INFO : file1: Copied (new) to: sub/file2 2024/04/11 03:54:51 INFO : file1: Deleted 2024/04/11 03:54:51 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/04/11 03:54:51 DEBUG : file1: Unchanged skipping 2024/04/11 03:54:51 INFO : file1: Deleted 2024/04/11 03:54:51 DEBUG : B2 bucket rclone-test-fukadiq7wasiwer1puwumal5: don't need to copy/move sub/file2, it is already at target location --- PASS: TestMoveFile (2.31s) === RUN TestMoveFileWithIgnoreExisting run.go:180: Remote "B2 bucket rclone-test-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", Modify Window "1ms" 2024/04/11 03:54:52 DEBUG : file1: Need to transfer - File not found at Destination 2024/04/11 03:54:52 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2024/04/11 03:54:52 INFO : file1: Copied (new) 2024/04/11 03:54:52 INFO : file1: Deleted 2024/04/11 03:54:53 DEBUG : file1: Destination exists, skipping 2024/04/11 03:54:53 DEBUG : file1: Not removing source file as destination file exists and --ignore-existing is set --- PASS: TestMoveFileWithIgnoreExisting (1.54s) === RUN TestCaseInsensitiveMoveFile run.go:180: Remote "B2 bucket rclone-test-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFile (0.37s) === RUN TestCaseInsensitiveMoveFileDryRun run.go:180: Remote "B2 bucket rclone-test-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFileDryRun (0.38s) === RUN TestMoveFileBackupDir run.go:180: Remote "B2 bucket rclone-test-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", Modify Window "1ms" 2024/04/11 03:54:55 DEBUG : Creating backend with remote "TestB2:rclone-test-fukadiq7wasiwer1puwumal5/backup" 2024/04/11 03:54:56 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18) 2024/04/11 03:54:58 DEBUG : dst/file1: sha1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK 2024/04/11 03:54:58 INFO : dst/file1: Copied (server-side copy) 2024/04/11 03:54:58 INFO : dst/file1: Deleted 2024/04/11 03:54:58 DEBUG : dst/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2024/04/11 03:54:58 INFO : dst/file1: Copied (new) 2024/04/11 03:54:58 INFO : dst/file1: Deleted --- PASS: TestMoveFileBackupDir (4.75s) === 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-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", Modify Window "1ms" 2024/04/11 03:55:01 DEBUG : A1/B2: Making directory 2024/04/11 03:55:01 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 03:55:02 DEBUG : A1/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2024/04/11 03:55:02 INFO : A1/B1/C1/four: Copied (server-side copy) to: A2/B1/C1/four 2024/04/11 03:55:02 DEBUG : A1/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2024/04/11 03:55:02 INFO : A1/B1/three: Copied (server-side copy) to: A2/B1/three 2024/04/11 03:55:02 INFO : A1/B1/C1/four: Deleted 2024/04/11 03:55:02 DEBUG : A1/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2024/04/11 03:55:02 INFO : A1/B1/C2/five: Copied (server-side copy) to: A2/B1/C2/five 2024/04/11 03:55:02 INFO : A1/B1/three: Deleted 2024/04/11 03:55:02 DEBUG : A1/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2024/04/11 03:55:02 INFO : A1/one: Copied (server-side copy) to: A2/one 2024/04/11 03:55:03 INFO : A1/B1/C2/five: Deleted 2024/04/11 03:55:03 DEBUG : A1/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2024/04/11 03:55:03 INFO : A1/two: Copied (server-side copy) to: A2/two 2024/04/11 03:55:03 INFO : A1/one: Deleted 2024/04/11 03:55: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 03:55:04 DEBUG : A2/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2024/04/11 03:55:04 INFO : A2/two: Copied (server-side copy) to: A3/two 2024/04/11 03:55:04 DEBUG : A2/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2024/04/11 03:55:04 INFO : A2/one: Copied (server-side copy) to: A3/one 2024/04/11 03:55:04 DEBUG : A2/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2024/04/11 03:55:04 INFO : A2/B1/three: Copied (server-side copy) to: A3/B1/three 2024/04/11 03:55:04 DEBUG : A2/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2024/04/11 03:55:04 INFO : A2/B1/C2/five: Copied (server-side copy) to: A3/B1/C2/five 2024/04/11 03:55:04 INFO : A2/two: Deleted 2024/04/11 03:55:04 INFO : A2/one: Deleted 2024/04/11 03:55:04 INFO : A2/B1/three: Deleted 2024/04/11 03:55:04 INFO : A2/B1/C2/five: Deleted 2024/04/11 03:55:04 DEBUG : A2/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2024/04/11 03:55:04 INFO : A2/B1/C1/four: Copied (server-side copy) to: A3/B1/C1/four 2024/04/11 03:55:04 INFO : A2/B1/C1/four: Deleted fstest.go:244: Filtering empty directory "A3/B2" fstest.go:244: Filtering empty directory "A3/B1/C3" 2024/04/11 03:55:05 INFO : B2 bucket rclone-test-fukadiq7wasiwer1puwumal5: Can't DirMove - falling back to file moves: can't move directory - incompatible remotes 2024/04/11 03:55:05 DEBUG : A3/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2024/04/11 03:55:05 INFO : A3/B1/C2/five: Copied (server-side copy) to: A4/B1/C2/five 2024/04/11 03:55:05 DEBUG : A3/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2024/04/11 03:55:05 INFO : A3/one: Copied (server-side copy) to: A4/one 2024/04/11 03:55:05 INFO : A3/B1/C2/five: Deleted 2024/04/11 03:55:05 INFO : A3/one: Deleted 2024/04/11 03:55:06 DEBUG : A3/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2024/04/11 03:55:06 INFO : A3/B1/three: Copied (server-side copy) to: A4/B1/three 2024/04/11 03:55:06 INFO : A3/B1/three: Deleted 2024/04/11 03:55:06 DEBUG : A3/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2024/04/11 03:55:06 INFO : A3/B1/C1/four: Copied (server-side copy) to: A4/B1/C1/four 2024/04/11 03:55:06 DEBUG : A3/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2024/04/11 03:55:06 INFO : A3/two: Copied (server-side copy) to: A4/two 2024/04/11 03:55:06 INFO : A3/B1/C1/four: Deleted 2024/04/11 03:55:06 INFO : A3/two: Deleted fstest.go:244: Filtering empty directory "A4/B2" fstest.go:244: Filtering empty directory "A4/B1/C3" --- PASS: TestDirMove (8.65s) === RUN TestGetFsInfo run.go:180: Remote "B2 bucket rclone-test-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", Modify Window "1ms" --- PASS: TestGetFsInfo (0.37s) === RUN TestRcat === RUN TestRcat/withChecksum=false,ignoreChecksum=false run.go:180: Remote "B2 bucket rclone-test-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", Modify Window "1ms" 2024/04/11 03:55:08 DEBUG : B2 bucket rclone-test-fukadiq7wasiwer1puwumal5: File to upload is small (34 bytes), uploading instead of streaming 2024/04/11 03:55:09 DEBUG : no_checksum_small_file_from_pipe: sha1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK 2024/04/11 03:55:09 INFO : no_checksum_small_file_from_pipe: Copied (new) 2024/04/11 03:55:09 DEBUG : no_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2024/04/11 03:55:10 DEBUG : no_checksum_big_file_from_pipe: sha1 = d0080f73aa8b494570ad63df1beb25c01ea9eea2 OK 2024/04/11 03:55: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-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", Modify Window "1ms" 2024/04/11 03:55:11 DEBUG : B2 bucket rclone-test-fukadiq7wasiwer1puwumal5: File to upload is small (34 bytes), uploading instead of streaming 2024/04/11 03:55:11 DEBUG : with_checksum_small_file_from_pipe: sha1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK 2024/04/11 03:55:11 INFO : with_checksum_small_file_from_pipe: Copied (new) 2024/04/11 03:55:11 DEBUG : with_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2024/04/11 03:55:12 DEBUG : with_checksum_big_file_from_pipe: sha1 = d0080f73aa8b494570ad63df1beb25c01ea9eea2 OK 2024/04/11 03:55: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-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", Modify Window "1ms" 2024/04/11 03:55:13 DEBUG : B2 bucket rclone-test-fukadiq7wasiwer1puwumal5: File to upload is small (34 bytes), uploading instead of streaming 2024/04/11 03:55:14 INFO : ignore_checksum_small_file_from_pipe: Copied (new) 2024/04/11 03:55:14 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2024/04/11 03:55:14 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-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", Modify Window "1ms" 2024/04/11 03:55:15 DEBUG : B2 bucket rclone-test-fukadiq7wasiwer1puwumal5: File to upload is small (34 bytes), uploading instead of streaming 2024/04/11 03:55:16 INFO : ignore_checksum_small_file_from_pipe: Copied (new) 2024/04/11 03:55:16 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2024/04/11 03:55:17 DEBUG : ignore_checksum_big_file_from_pipe: Src hash empty - aborting Dst hash check 2024/04/11 03:55:17 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical --- PASS: TestRcat (9.84s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (2.53s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (2.66s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (2.05s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (2.60s) === RUN TestRcatMetadata run.go:180: Remote "B2 bucket rclone-test-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", Modify Window "1ms" operations_test.go:1514: Skipping as destination doesn't support user metadata --- SKIP: TestRcatMetadata (0.42s) === RUN TestRcatSize run.go:180: Remote "B2 bucket rclone-test-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", Modify Window "1ms" 2024/04/11 03:55:19 DEBUG : B2 bucket rclone-test-fukadiq7wasiwer1puwumal5: File to upload is small (60 bytes), uploading instead of streaming 2024/04/11 03:55:19 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2024/04/11 03:55:19 INFO : potato2: Copied (new) --- PASS: TestRcatSize (1.46s) === RUN TestRcatSizeMetadata run.go:180: Remote "B2 bucket rclone-test-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", 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-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", Modify Window "1ms" 2024/04/11 03:55:21 DEBUG : B2 bucket rclone-test-fukadiq7wasiwer1puwumal5: Touching "empty space" 2024/04/11 03:55:22 DEBUG : B2 bucket rclone-test-fukadiq7wasiwer1puwumal5: Touching "potato2" 2024/04/11 03:55:23 DEBUG : B2 bucket rclone-test-fukadiq7wasiwer1puwumal5: Touching "sub dir/potato3" --- PASS: TestTouchDir (4.32s) === RUN TestMkdirMetadata run.go:180: Remote "B2 bucket rclone-test-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", Modify Window "1ms" operations_test.go:1685: Skipping test as remote does not support MkdirMetadata --- SKIP: TestMkdirMetadata (0.38s) === RUN TestMkdirModTime run.go:180: Remote "B2 bucket rclone-test-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", 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-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", Modify Window "1ms" operations_test.go:1725: Skipping test as remote does not support WriteDirMetadata or MkdirMetadata --- SKIP: TestCopyDirMetadata (0.38s) === RUN TestSetDirModTime run.go:180: Remote "B2 bucket rclone-test-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", 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-fukadiq7wasiwer1puwumal5", Local "Local file system at /tmp/rclone4258348187", 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 03:55:26 DEBUG : B2 bucket rclone-test-fukadiq7wasiwer1puwumal5: Purge remote 2024/04/11 03:55:26 INFO : B2 bucket rclone-test-fukadiq7wasiwer1puwumal5: cleaning bucket "rclone-test-fukadiq7wasiwer1puwumal5" of all files 2024/04/11 03:55:27 DEBUG : A1/B1/C1/four: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f403151b2446743bf_d20240411_m035502_c001_v7007000_t0000_u01712807702659") 2024/04/11 03:55:27 DEBUG : A1/B1/C1/four: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b17494_d20240411_m035500_c001_v0001179_t0020_u01712807700661") 2024/04/11 03:55:27 DEBUG : A1/B1/C1/one: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f403151b2446743b0_d20240411_m035443_c001_v7007000_t0000_u01712807683896") 2024/04/11 03:55:27 DEBUG : A1/B1/C1/one: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b17145_d20240411_m035441_c001_v0001179_t0024_u01712807681685") 2024/04/11 03:55:27 DEBUG : A1/B1/C2/five: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f4007893bcb4b3057_d20240411_m035502_c001_v7007000_t0000_u01712807702917") 2024/04/11 03:55:27 DEBUG : A1/B1/C2/five: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b1749e_d20240411_m035500_c001_v0001179_t0020_u01712807700972") 2024/04/11 03:55:27 DEBUG : A1/B1/three: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f403b73892aafc876_d20240411_m035502_c001_v7007000_t0000_u01712807702810") 2024/04/11 03:55:27 DEBUG : A1/B1/three: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b1748c_d20240411_m035500_c001_v0001179_t0023_u01712807700394") 2024/04/11 03:55:27 DEBUG : A1/one: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f40566323c6993995_d20240411_m035503_c001_v7007000_t0000_u01712807703002") 2024/04/11 03:55:27 DEBUG : A1/one: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b17464_d20240411_m035459_c001_v0001179_t0022_u01712807699592") 2024/04/11 03:55:27 DEBUG : A1/two: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f403b73892aafc877_d20240411_m035503_c001_v7007000_t0000_u01712807703106") 2024/04/11 03:55:28 DEBUG : A1/two: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b17476_d20240411_m035459_c001_v0001179_t0021_u01712807699856") 2024/04/11 03:55:28 DEBUG : A1/two: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f403151b2446743b1_d20240411_m035444_c001_v7007000_t0000_u01712807684284") 2024/04/11 03:55:28 DEBUG : A1/two: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b17153_d20240411_m035442_c001_v0001179_t0038_u01712807682109") 2024/04/11 03:55:28 DEBUG : A2/B1/C1/four: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f403151b2446743c0_d20240411_m035504_c001_v7007000_t0000_u01712807704717") 2024/04/11 03:55:28 DEBUG : A2/B1/C1/four: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f109fbb67d403c7a1_d20240411_m035502_c001_v0001136_t0013_u01712807702253") 2024/04/11 03:55:28 DEBUG : A2/B1/C2/five: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f403b73892aafc87b_d20240411_m035504_c001_v7007000_t0000_u01712807704325") 2024/04/11 03:55:28 DEBUG : A2/B1/C2/five: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f115d1960bb984d50_d20240411_m035502_c001_v0001091_t0009_u01712807702575") 2024/04/11 03:55:28 DEBUG : A2/B1/three: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f40566323c6993996_d20240411_m035504_c001_v7007000_t0000_u01712807704262") 2024/04/11 03:55:28 DEBUG : A2/B1/three: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f107653d6bac4f2cb_d20240411_m035502_c001_v0001180_t0059_u01712807702592") 2024/04/11 03:55:28 DEBUG : A2/one: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f4007893bcb4b305a_d20240411_m035504_c001_v7007000_t0000_u01712807704177") 2024/04/11 03:55:28 DEBUG : A2/one: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1153979a9a8645b0_d20240411_m035502_c001_v0001137_t0052_u01712807702625") 2024/04/11 03:55:28 DEBUG : A2/two: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f403b73892aafc87a_d20240411_m035504_c001_v7007000_t0000_u01712807704152") 2024/04/11 03:55:28 DEBUG : A2/two: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b174ea_d20240411_m035502_c001_v0001179_t0049_u01712807702633") 2024/04/11 03:55:28 DEBUG : A3/B1/C1/four: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f403151b2446743c1_d20240411_m035506_c001_v7007000_t0000_u01712807706421") 2024/04/11 03:55:28 DEBUG : A3/B1/C1/four: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f110a7cc1c720f8e4_d20240411_m035503_c001_v0001145_t0050_u01712807703951") 2024/04/11 03:55:28 DEBUG : A3/B1/C2/five: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f403b73892aafc87e_d20240411_m035505_c001_v7007000_t0000_u01712807705831") 2024/04/11 03:55:28 DEBUG : A3/B1/C2/five: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f11657f63c8b406aa_d20240411_m035503_c001_v0001165_t0057_u01712807703890") 2024/04/11 03:55:28 DEBUG : A3/B1/three: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f40566323c699399e_d20240411_m035506_c001_v7007000_t0000_u01712807706091") 2024/04/11 03:55:28 DEBUG : A3/B1/three: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f107fd84c9e79f6fd_d20240411_m035503_c001_v0001090_t0038_u01712807703905") 2024/04/11 03:55:29 DEBUG : A3/one: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f4007893bcb4b305e_d20240411_m035505_c001_v7007000_t0000_u01712807705892") 2024/04/11 03:55:29 DEBUG : A3/one: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1158f61d45e649a9_d20240411_m035503_c001_v0001181_t0003_u01712807703926") 2024/04/11 03:55:29 DEBUG : A3/two: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f403b73892aafcc68_d20240411_m035506_c001_v7007000_t0000_u01712807706547") 2024/04/11 03:55:29 DEBUG : A3/two: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1137327b7568ade7_d20240411_m035503_c001_v0001181_t0030_u01712807703926") 2024/04/11 03:55:29 DEBUG : A4/B1/C1/four: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f403b73892aafcc69_d20240411_m035507_c001_v7007000_t0000_u01712807707123") 2024/04/11 03:55:29 DEBUG : A4/B1/C1/four: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f111b7f2fab11f1db_d20240411_m035505_c001_v0001023_t0006_u01712807705540") 2024/04/11 03:55:29 DEBUG : A4/B1/C2/five: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f403b73892aafcc6a_d20240411_m035507_c001_v7007000_t0000_u01712807707305") 2024/04/11 03:55:29 DEBUG : A4/B1/C2/five: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f11173de2d9f36de6_d20240411_m035505_c001_v0001179_t0053_u01712807705530") 2024/04/11 03:55:29 DEBUG : A4/B1/three: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f403b73892aafcc6b_d20240411_m035507_c001_v7007000_t0000_u01712807707490") 2024/04/11 03:55:29 DEBUG : A4/B1/three: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f119a65649dab65ca_d20240411_m035505_c001_v0001031_t0014_u01712807705563") 2024/04/11 03:55:29 DEBUG : A4/one: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f403b73892aafcc6c_d20240411_m035507_c001_v7007000_t0000_u01712807707678") 2024/04/11 03:55:29 DEBUG : A4/one: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f101f3d8cac6a8026_d20240411_m035505_c001_v0001181_t0048_u01712807705568") 2024/04/11 03:55:29 DEBUG : A4/two: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f403b73892aafcc6d_d20240411_m035507_c001_v7007000_t0000_u01712807707860") 2024/04/11 03:55:29 DEBUG : A4/two: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f103c453ad3dcdd13_d20240411_m035505_c001_v0001120_t0038_u01712807705582") 2024/04/11 03:55:29 DEBUG : BackupDir/one: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f417075306c2cdfd8_d20240411_m035338_c001_v7007000_t0000_u01712807618831") 2024/04/11 03:55:29 DEBUG : BackupDir/one: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1140ffc850712833_d20240411_m035333_c001_v0001031_t0029_u01712807613900") 2024/04/11 03:55:29 DEBUG : CompareDest/one: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f40561a87487509ea_d20240411_m035326_c001_v7007000_t0000_u01712807606245") 2024/04/11 03:55:29 DEBUG : CompareDest/one: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b165e9_d20240411_m035322_c001_v0001179_t0033_u01712807602677") 2024/04/11 03:55:29 DEBUG : CompareDest/two: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f40561a87487509eb_d20240411_m035326_c001_v7007000_t0000_u01712807606428") 2024/04/11 03:55:30 DEBUG : CompareDest/two: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b16615_d20240411_m035323_c001_v0001179_t0007_u01712807603611") 2024/04/11 03:55:30 DEBUG : CopyDest/one: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f417075306c2cdfd9_d20240411_m035339_c001_v7007000_t0000_u01712807619072") 2024/04/11 03:55:30 DEBUG : CopyDest/one: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b16753_d20240411_m035332_c001_v0001179_t0050_u01712807612045") 2024/04/11 03:55:30 DEBUG : CopyDest/three: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f417075306c2cdfda_d20240411_m035339_c001_v7007000_t0000_u01712807619255") 2024/04/11 03:55:30 DEBUG : CopyDest/three: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b1680a_d20240411_m035337_c001_v0001179_t0055_u01712807617108") 2024/04/11 03:55:30 DEBUG : CopyDest/two: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f417075306c2cdfdb_d20240411_m035339_c001_v7007000_t0000_u01712807619441") 2024/04/11 03:55:30 DEBUG : CopyDest/two: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b167c8_d20240411_m035335_c001_v0001179_t0047_u01712807615468") 2024/04/11 03:55:30 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f417075306c2cdfe5_d20240411_m035343_c001_v7007000_t0000_u01712807623699") 2024/04/11 03:55:30 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b168c9_d20240411_m035341_c001_v0001179_t0036_u01712807621324") 2024/04/11 03:55:30 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f417075306c2cdfe6_d20240411_m035343_c001_v7007000_t0000_u01712807623882") 2024/04/11 03:55:30 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b168f7_d20240411_m035343_c001_v0001179_t0010_u01712807623091") 2024/04/11 03:55:30 DEBUG : a.txt: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f417075306c2ce002_d20240411_m035356_c001_v7007000_t0000_u01712807636060") 2024/04/11 03:55:30 DEBUG : a.txt: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b16a3f_d20240411_m035351_c001_v0001179_t0007_u01712807631139") 2024/04/11 03:55:30 DEBUG : also/one: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f417075306c2cdfed_d20240411_m035348_c001_v7007000_t0000_u01712807628225") 2024/04/11 03:55:30 DEBUG : also/one: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b16987_d20240411_m035346_c001_v0001179_t0056_u01712807626325") 2024/04/11 03:55:30 DEBUG : another: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f417075306c2cdfee_d20240411_m035348_c001_v7007000_t0000_u01712807628769") 2024/04/11 03:55:30 DEBUG : another: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b1699b_d20240411_m035346_c001_v0001179_t0049_u01712807626573") 2024/04/11 03:55:30 DEBUG : backup/dst/file1: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f403151b2446743bd_d20240411_m035459_c001_v7007000_t0000_u01712807699012") 2024/04/11 03:55:30 DEBUG : backup/dst/file1: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1038d69132d771c5_d20240411_m035457_c001_v0001037_t0032_u01712807697646") 2024/04/11 03:55:30 DEBUG : backup/dst/file1: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f40561a87487509e6_d20240411_m035318_c001_v7007000_t0000_u01712807598294") 2024/04/11 03:55:31 DEBUG : backup/dst/file1: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f110e9bb049177ad6_d20240411_m035317_c001_v0001172_t0049_u01712807597098") 2024/04/11 03:55:31 DEBUG : data/banana: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f40878ed6710f9830_d20240411_m035237_c001_v7007000_t0000_u01712807557587") 2024/04/11 03:55:31 DEBUG : data/banana: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b15e79_d20240411_m035235_c001_v0001179_t0046_u01712807555333") 2024/04/11 03:55:31 DEBUG : data/banana: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b15d05_d20240411_m035227_c001_v0001179_t0017_u01712807547041") 2024/04/11 03:55:31 DEBUG : data/potato: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f40878ed6710f9831_d20240411_m035237_c001_v7007000_t0000_u01712807557769") 2024/04/11 03:55:31 DEBUG : data/potato: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b15e87_d20240411_m035235_c001_v0001179_t0006_u01712807555551") 2024/04/11 03:55:31 DEBUG : data/potato: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b15d51_d20240411_m035228_c001_v0001179_t0038_u01712807548709") 2024/04/11 03:55:31 DEBUG : dst/file1: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f403151b2446743be_d20240411_m035459_c001_v7007000_t0000_u01712807699201") 2024/04/11 03:55:31 DEBUG : dst/file1: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b17442_d20240411_m035458_c001_v0001179_t0056_u01712807698403") 2024/04/11 03:55:31 DEBUG : dst/file1: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f403151b2446743bb_d20240411_m035458_c001_v7007000_t0000_u01712807698218") 2024/04/11 03:55:31 DEBUG : dst/file1: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b1738c_d20240411_m035454_c001_v0001179_t0009_u01712807694826") 2024/04/11 03:55:31 DEBUG : dst/file1: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f40561a87487509e7_d20240411_m035318_c001_v7007000_t0000_u01712807598475") 2024/04/11 03:55:31 DEBUG : dst/file1: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b16512_d20240411_m035317_c001_v0001179_t0018_u01712807597726") 2024/04/11 03:55:31 DEBUG : dst/file1: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f40561a87487509e5_d20240411_m035317_c001_v7007000_t0000_u01712807597543") 2024/04/11 03:55:31 DEBUG : dst/file1: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b16498_d20240411_m035315_c001_v0001179_t0045_u01712807595063") 2024/04/11 03:55:31 DEBUG : dst/one: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f417075306c2cdfdc_d20240411_m035339_c001_v7007000_t0000_u01712807619659") 2024/04/11 03:55:31 DEBUG : dst/one: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f11585103b16389a4_d20240411_m035334_c001_v0001154_t0023_u01712807614935") 2024/04/11 03:55:31 DEBUG : dst/one: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f417075306c2cdfd2_d20240411_m035334_c001_v7007000_t0000_u01712807614717") 2024/04/11 03:55:31 DEBUG : dst/one: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b16747_d20240411_m035331_c001_v0001179_t0010_u01712807611830") 2024/04/11 03:55:31 DEBUG : dst/one: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f118909a039d80ae1_d20240411_m035331_c001_v0001179_t0008_u01712807611031") 2024/04/11 03:55:32 DEBUG : dst/one: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f118909a039d80abd_d20240411_m035329_c001_v0001179_t0021_u01712807609962") 2024/04/11 03:55:32 DEBUG : dst/one: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f40561a87487509ec_d20240411_m035326_c001_v7007000_t0000_u01712807606617") 2024/04/11 03:55:32 DEBUG : dst/one: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b165dd_d20240411_m035322_c001_v0001179_t0057_u01712807602475") 2024/04/11 03:55:32 DEBUG : dst/one: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1162c57eee564c87_d20240411_m035321_c001_v0001179_t0025_u01712807601992") 2024/04/11 03:55:32 DEBUG : dst/one: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1162c57eee564c40_d20240411_m035320_c001_v0001179_t0052_u01712807600974") 2024/04/11 03:55:32 DEBUG : dst/three: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f417075306c2cdfde_d20240411_m035339_c001_v7007000_t0000_u01712807619842") 2024/04/11 03:55:32 DEBUG : dst/three: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f118909a039d80be8_d20240411_m035338_c001_v0001179_t0018_u01712807618246") 2024/04/11 03:55:32 DEBUG : dst/two: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f417075306c2cdfdf_d20240411_m035340_c001_v7007000_t0000_u01712807620033") 2024/04/11 03:55:32 DEBUG : dst/two: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1188fc36e074e03e_d20240411_m035336_c001_v0001181_t0049_u01712807616275") 2024/04/11 03:55:32 DEBUG : dst/two: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f40561a87487509ed_d20240411_m035326_c001_v7007000_t0000_u01712807606842") 2024/04/11 03:55:32 DEBUG : dst/two: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1162c57eee564d5a_d20240411_m035325_c001_v0001179_t0057_u01712807605665") 2024/04/11 03:55:32 DEBUG : empty space: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f403b73892aafd873_d20240411_m035524_c001_v7007000_t0000_u01712807724274") 2024/04/11 03:55:32 DEBUG : empty space: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f11615e59476c796e_d20240411_m035522_c001_v0001131_t0045_u01712807722472") 2024/04/11 03:55:32 DEBUG : empty space: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b1780e_d20240411_m035520_c001_v0001179_t0045_u01712807720886") 2024/04/11 03:55:32 DEBUG : empty space: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f419eb674a84fee9f_d20240411_m035418_c001_v7007000_t0000_u01712807658818") 2024/04/11 03:55:32 DEBUG : empty space: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b16ddb_d20240411_m035417_c001_v0001179_t0039_u01712807657686") 2024/04/11 03:55:32 DEBUG : empty space: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f419eb674a84fee81_d20240411_m035416_c001_v7007000_t0000_u01712807656813") 2024/04/11 03:55:32 DEBUG : empty space: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b16d5d_d20240411_m035415_c001_v0001179_t0015_u01712807655080") 2024/04/11 03:55:33 DEBUG : empty space: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f419eb674a84fee7a_d20240411_m035414_c001_v7007000_t0000_u01712807654306") 2024/04/11 03:55:33 DEBUG : empty space: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b16d2d_d20240411_m035413_c001_v0001179_t0031_u01712807653481") 2024/04/11 03:55:33 DEBUG : empty space: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f419eb674a84fee75_d20240411_m035412_c001_v7007000_t0000_u01712807652633") 2024/04/11 03:55:33 DEBUG : empty space: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b16ce3_d20240411_m035410_c001_v0001179_t0008_u01712807650937") 2024/04/11 03:55:33 DEBUG : empty space: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f419eb674a84fee70_d20240411_m035410_c001_v7007000_t0000_u01712807650163") 2024/04/11 03:55:33 DEBUG : empty space: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b16cb3_d20240411_m035409_c001_v0001179_t0025_u01712807649402") 2024/04/11 03:55:33 DEBUG : empty space: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f40878ed6710f9828_d20240411_m035221_c001_v7007000_t0000_u01712807541888") 2024/04/11 03:55:33 DEBUG : empty space: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b15bc5_d20240411_m035219_c001_v0001179_t0046_u01712807539353") 2024/04/11 03:55:33 DEBUG : empty space: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f40878ed6710f9822_d20240411_m035217_c001_v7007000_t0000_u01712807537656") 2024/04/11 03:55:33 DEBUG : empty space: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b15ad0_d20240411_m035213_c001_v0001179_t0022_u01712807533988") 2024/04/11 03:55:33 DEBUG : empty space: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f40878ed6710f981e_d20240411_m035211_c001_v7007000_t0000_u01712807531487") 2024/04/11 03:55:33 DEBUG : empty space: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b15a1a_d20240411_m035208_c001_v0001179_t0038_u01712807528773") 2024/04/11 03:55:33 DEBUG : file1: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f403151b2446743b9_d20240411_m035453_c001_v7007000_t0000_u01712807693686") 2024/04/11 03:55:33 DEBUG : file1: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b17346_d20240411_m035452_c001_v0001179_t0022_u01712807692721") 2024/04/11 03:55:33 DEBUG : file1: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f403151b2446743b3_d20240411_m035449_c001_v7007000_t0000_u01712807689249") 2024/04/11 03:55:33 DEBUG : file1: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f4194c02398b1723e_d20240411_m035447_c001_v0001179_t0026_u01712807687696") 2024/04/11 03:55:33 DEBUG : file1: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f403151b2446743aa_d20240411_m035436_c001_v7007000_t0000_u01712807676217") 2024/04/11 03:55:33 DEBUG : file1: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b16fb5_d20240411_m035432_c001_v0001179_t0049_u01712807672280") 2024/04/11 03:55:33 DEBUG : file1: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f419eb674a84fee6a_d20240411_m035406_c001_v7007000_t0000_u01712807646545") 2024/04/11 03:55:33 DEBUG : file1: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b16ba9_d20240411_m035401_c001_v0001179_t0031_u01712807641198") 2024/04/11 03:55:34 DEBUG : file1: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f417075306c2ce00c_d20240411_m035400_c001_v7007000_t0000_u01712807640628") 2024/04/11 03:55:34 DEBUG : file1: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b16b23_d20240411_m035357_c001_v0001179_t0000_u01712807637596") 2024/04/11 03:55:34 DEBUG : file2: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f403151b2446743b4_d20240411_m035449_c001_v7007000_t0000_u01712807689436") 2024/04/11 03:55:34 DEBUG : file2: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f4194c02398b1726a_d20240411_m035448_c001_v0001179_t0026_u01712807688666") 2024/04/11 03:55:34 DEBUG : file2: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f403151b2446743ac_d20240411_m035436_c001_v7007000_t0000_u01712807676400") 2024/04/11 03:55:34 DEBUG : file2: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b16fbf_d20240411_m035432_c001_v0001179_t0025_u01712807672498") 2024/04/11 03:55:34 DEBUG : filename.txt: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f403151b2446743b5_d20240411_m035449_c001_v7007000_t0000_u01712807689619") 2024/04/11 03:55:34 DEBUG : filename.txt: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b17258_d20240411_m035448_c001_v0001179_t0034_u01712807688253") 2024/04/11 03:55:34 DEBUG : headerfilename.txt: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f403151b2446743b6_d20240411_m035449_c001_v7007000_t0000_u01712807689802") 2024/04/11 03:55:34 DEBUG : headerfilename.txt: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b17262_d20240411_m035448_c001_v0001179_t0059_u01712807688458") 2024/04/11 03:55:34 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f403b73892aafd848_d20240411_m035517_c001_v7007000_t0000_u01712807717867") 2024/04/11 03:55:34 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b17762_d20240411_m035516_c001_v0001179_t0040_u01712807716746") 2024/04/11 03:55:34 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f403b73892aafd837_d20240411_m035515_c001_v7007000_t0000_u01712807715275") 2024/04/11 03:55:34 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b176f0_d20240411_m035514_c001_v0001179_t0047_u01712807714165") 2024/04/11 03:55:34 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f403b73892aafd84b_d20240411_m035518_c001_v7007000_t0000_u01712807718054") 2024/04/11 03:55:34 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b17738_d20240411_m035516_c001_v0001179_t0026_u01712807716024") 2024/04/11 03:55:34 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f403b73892aafd838_d20240411_m035515_c001_v7007000_t0000_u01712807715458") 2024/04/11 03:55:34 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b176e4_d20240411_m035513_c001_v0001179_t0017_u01712807713974") 2024/04/11 03:55:34 DEBUG : large: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f403151b2446743a7_d20240411_m035431_c001_v7007000_t0000_u01712807671484") 2024/04/11 03:55:35 DEBUG : large: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b16f5d_d20240411_m035429_c001_v0001179_t0001_u01712807669282") 2024/04/11 03:55:35 DEBUG : large: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f419eb674a84feeaf_d20240411_m035428_c001_v7007000_t0000_u01712807668118") 2024/04/11 03:55:35 DEBUG : large: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b16f13_d20240411_m035426_c001_v0001179_t0011_u01712807666934") 2024/04/11 03:55:35 DEBUG : large: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f419eb674a84feead_d20240411_m035424_c001_v7007000_t0000_u01712807664992") 2024/04/11 03:55:35 DEBUG : large: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b16e91_d20240411_m035422_c001_v0001179_t0001_u01712807662537") 2024/04/11 03:55:35 DEBUG : large: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f419eb674a84feea8_d20240411_m035421_c001_v7007000_t0000_u01712807661711") 2024/04/11 03:55:35 DEBUG : large: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b16e47_d20240411_m035420_c001_v0001179_t0007_u01712807660129") 2024/04/11 03:55:35 DEBUG : medium: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f403151b2446743a5_d20240411_m035430_c001_v7007000_t0000_u01712807670914") 2024/04/11 03:55:35 DEBUG : medium: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b16f4f_d20240411_m035429_c001_v0001179_t0027_u01712807669059") 2024/04/11 03:55:35 DEBUG : medium: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f403151b2446743a4_d20240411_m035427_c001_v7007000_t0000_u01712807667558") 2024/04/11 03:55:35 DEBUG : medium: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b16f03_d20240411_m035426_c001_v0001179_t0031_u01712807666558") 2024/04/11 03:55:35 DEBUG : medium: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f403151b2446743a0_d20240411_m035425_c001_v7007000_t0000_u01712807665011") 2024/04/11 03:55:35 DEBUG : medium: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b16e87_d20240411_m035422_c001_v0001179_t0011_u01712807662311") 2024/04/11 03:55:35 DEBUG : medium: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f419eb674a84feea5_d20240411_m035420_c001_v7007000_t0000_u01712807660936") 2024/04/11 03:55:35 DEBUG : medium: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b16e37_d20240411_m035419_c001_v0001179_t0055_u01712807659845") 2024/04/11 03:55:35 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f403b73892aafcc72_d20240411_m035510_c001_v7007000_t0000_u01712807710569") 2024/04/11 03:55:35 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b1761e_d20240411_m035509_c001_v0001179_t0017_u01712807709412") 2024/04/11 03:55:35 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f403b73892aafcc73_d20240411_m035510_c001_v7007000_t0000_u01712807710751") 2024/04/11 03:55:36 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b17612_d20240411_m035509_c001_v0001179_t0026_u01712807709159") 2024/04/11 03:55:36 DEBUG : not-one: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f417075306c2cdfef_d20240411_m035348_c001_v7007000_t0000_u01712807628950") 2024/04/11 03:55:36 DEBUG : not-one: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b169a9_d20240411_m035346_c001_v0001179_t0005_u01712807626855") 2024/04/11 03:55:36 DEBUG : one: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f417075306c2cdfec_d20240411_m035348_c001_v7007000_t0000_u01712807628030") 2024/04/11 03:55:36 DEBUG : one: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b16981_d20240411_m035346_c001_v0001179_t0057_u01712807626103") 2024/04/11 03:55:36 DEBUG : potato1: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f403b73892aafd861_d20240411_m035519_c001_v7007000_t0000_u01712807719753") 2024/04/11 03:55:36 DEBUG : potato1: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b177b6_d20240411_m035518_c001_v0001179_t0024_u01712807718869") 2024/04/11 03:55:36 DEBUG : potato2: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f403b73892aafd874_d20240411_m035524_c001_v7007000_t0000_u01712807724457") 2024/04/11 03:55:36 DEBUG : potato2: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f11039af5c59a808b_d20240411_m035522_c001_v0001181_t0008_u01712807722967") 2024/04/11 03:55:36 DEBUG : potato2: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b17806_d20240411_m035520_c001_v0001179_t0010_u01712807720689") 2024/04/11 03:55:36 DEBUG : potato2: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f403b73892aafd864_d20240411_m035519_c001_v7007000_t0000_u01712807719946") 2024/04/11 03:55:36 DEBUG : potato2: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b177c6_d20240411_m035519_c001_v0001179_t0024_u01712807719177") 2024/04/11 03:55:36 DEBUG : potato2: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f419eb674a84feea0_d20240411_m035419_c001_v7007000_t0000_u01712807659002") 2024/04/11 03:55:36 DEBUG : potato2: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b16dc5_d20240411_m035417_c001_v0001179_t0055_u01712807657372") 2024/04/11 03:55:36 DEBUG : potato2: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f419eb674a84fee82_d20240411_m035416_c001_v7007000_t0000_u01712807656995") 2024/04/11 03:55:36 DEBUG : potato2: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b16d55_d20240411_m035414_c001_v0001179_t0047_u01712807654877") 2024/04/11 03:55:36 DEBUG : potato2: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f419eb674a84fee7c_d20240411_m035414_c001_v7007000_t0000_u01712807654495") 2024/04/11 03:55:36 DEBUG : potato2: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b16d23_d20240411_m035413_c001_v0001179_t0008_u01712807653201") 2024/04/11 03:55:36 DEBUG : potato2: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f419eb674a84fee76_d20240411_m035412_c001_v7007000_t0000_u01712807652835") 2024/04/11 03:55:36 DEBUG : potato2: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b16cdf_d20240411_m035410_c001_v0001179_t0020_u01712807650731") 2024/04/11 03:55:36 DEBUG : potato2: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f419eb674a84fee72_d20240411_m035410_c001_v7007000_t0000_u01712807650366") 2024/04/11 03:55:37 DEBUG : potato2: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b16cad_d20240411_m035409_c001_v0001179_t0037_u01712807649200") 2024/04/11 03:55:37 DEBUG : potato2: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f40878ed6710f9829_d20240411_m035222_c001_v7007000_t0000_u01712807542080") 2024/04/11 03:55:37 DEBUG : potato2: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b15bdb_d20240411_m035219_c001_v0001179_t0044_u01712807539927") 2024/04/11 03:55:37 DEBUG : potato2: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f40878ed6710f9823_d20240411_m035217_c001_v7007000_t0000_u01712807537847") 2024/04/11 03:55:37 DEBUG : potato2: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b15af8_d20240411_m035214_c001_v0001179_t0014_u01712807534919") 2024/04/11 03:55:37 DEBUG : potato2: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f40878ed6710f981f_d20240411_m035211_c001_v7007000_t0000_u01712807531699") 2024/04/11 03:55:37 DEBUG : potato2: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b15a24_d20240411_m035209_c001_v0001179_t0016_u01712807529393") 2024/04/11 03:55:37 DEBUG : remotepotato: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f40878ed6710f982a_d20240411_m035222_c001_v7007000_t0000_u01712807542271") 2024/04/11 03:55:37 DEBUG : remotepotato: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b15c00_d20240411_m035220_c001_v0001179_t0035_u01712807540872") 2024/04/11 03:55:37 DEBUG : remotepotato: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f40878ed6710f9824_d20240411_m035218_c001_v7007000_t0000_u01712807538038") 2024/04/11 03:55:37 DEBUG : remotepotato: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b15b3c_d20240411_m035216_c001_v0001179_t0052_u01712807536321") 2024/04/11 03:55:37 DEBUG : remotepotato: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f40878ed6710f9820_d20240411_m035211_c001_v7007000_t0000_u01712807531886") 2024/04/11 03:55:37 DEBUG : remotepotato: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b15a46_d20240411_m035210_c001_v0001179_t0035_u01712807530251") 2024/04/11 03:55:37 DEBUG : rutabaga: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f40878ed6710f982b_d20240411_m035222_c001_v7007000_t0000_u01712807542452") 2024/04/11 03:55:37 DEBUG : rutabaga: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b15b9a_d20240411_m035218_c001_v0001179_t0016_u01712807538585") 2024/04/11 03:55:37 DEBUG : rutabaga: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f40878ed6710f9825_d20240411_m035218_c001_v7007000_t0000_u01712807538220") 2024/04/11 03:55:37 DEBUG : rutabaga: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b15a8e_d20240411_m035212_c001_v0001179_t0052_u01712807532465") 2024/04/11 03:55:37 DEBUG : rutabaga: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f40878ed6710f9821_d20240411_m035212_c001_v7007000_t0000_u01712807532070") 2024/04/11 03:55:37 DEBUG : rutabaga: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b159fc_d20240411_m035207_c001_v0001179_t0026_u01712807527891") 2024/04/11 03:55:38 DEBUG : small: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f419eb674a84feeb2_d20240411_m035430_c001_v7007000_t0000_u01712807670874") 2024/04/11 03:55:38 DEBUG : small: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b16f47_d20240411_m035428_c001_v0001179_t0004_u01712807668856") 2024/04/11 03:55:38 DEBUG : small: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f419eb674a84feeae_d20240411_m035427_c001_v7007000_t0000_u01712807667566") 2024/04/11 03:55:38 DEBUG : small: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b16f01_d20240411_m035426_c001_v0001179_t0016_u01712807666361") 2024/04/11 03:55:38 DEBUG : small: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f403151b2446743a2_d20240411_m035425_c001_v7007000_t0000_u01712807665613") 2024/04/11 03:55:38 DEBUG : small: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b16e7b_d20240411_m035422_c001_v0001179_t0050_u01712807662078") 2024/04/11 03:55:38 DEBUG : small: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f419eb674a84feea7_d20240411_m035421_c001_v7007000_t0000_u01712807661122") 2024/04/11 03:55:38 DEBUG : small: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b16e2e_d20240411_m035419_c001_v0001179_t0029_u01712807659552") 2024/04/11 03:55:38 DEBUG : sub dir/hello world: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f419eb674a84fee6d_d20240411_m035408_c001_v7007000_t0000_u01712807648820") 2024/04/11 03:55:38 DEBUG : sub dir/hello world: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b16c8b_d20240411_m035408_c001_v0001179_t0007_u01712807648037") 2024/04/11 03:55:38 DEBUG : sub dir/hello world: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f417075306c2ce003_d20240411_m035356_c001_v7007000_t0000_u01712807636269") 2024/04/11 03:55:38 DEBUG : sub dir/hello world: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b16a55_d20240411_m035351_c001_v0001179_t0012_u01712807631538") 2024/04/11 03:55:38 DEBUG : sub dir/hello world2: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f417075306c2ce004_d20240411_m035356_c001_v7007000_t0000_u01712807636477") 2024/04/11 03:55:38 DEBUG : sub dir/hello world2: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b16a61_d20240411_m035351_c001_v0001179_t0026_u01712807631734") 2024/04/11 03:55:38 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f417075306c2ce005_d20240411_m035356_c001_v7007000_t0000_u01712807636665") 2024/04/11 03:55:38 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b16a73_d20240411_m035352_c001_v0001179_t0020_u01712807632536") 2024/04/11 03:55:38 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f417075306c2ce006_d20240411_m035356_c001_v7007000_t0000_u01712807636854") 2024/04/11 03:55:38 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b16a81_d20240411_m035352_c001_v0001179_t0051_u01712807632830") 2024/04/11 03:55:38 DEBUG : sub dir/potato3: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f403b73892aafd875_d20240411_m035524_c001_v7007000_t0000_u01712807724639") 2024/04/11 03:55:38 DEBUG : sub dir/potato3: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1038d69132d771e3_d20240411_m035523_c001_v0001037_t0011_u01712807723399") 2024/04/11 03:55:39 DEBUG : sub dir/potato3: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b1781c_d20240411_m035521_c001_v0001179_t0007_u01712807721285") 2024/04/11 03:55:39 DEBUG : sub dir/potato3: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f419eb674a84feea2_d20240411_m035419_c001_v7007000_t0000_u01712807659182") 2024/04/11 03:55:39 DEBUG : sub dir/potato3: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b16de3_d20240411_m035417_c001_v0001179_t0014_u01712807657967") 2024/04/11 03:55:39 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f417075306c2ce007_d20240411_m035357_c001_v7007000_t0000_u01712807637034") 2024/04/11 03:55:39 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b16a8b_d20240411_m035353_c001_v0001179_t0016_u01712807633095") 2024/04/11 03:55:39 DEBUG : sub/file2: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f403151b2446743b8_d20240411_m035452_c001_v7007000_t0000_u01712807692148") 2024/04/11 03:55:39 DEBUG : sub/file2: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b172bc_d20240411_m035450_c001_v0001179_t0040_u01712807690413") 2024/04/11 03:55:39 DEBUG : sub/file2: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f419eb674a84fee6b_d20240411_m035406_c001_v7007000_t0000_u01712807646737") 2024/04/11 03:55:39 DEBUG : sub/file2: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b16bb3_d20240411_m035401_c001_v0001179_t0024_u01712807641439") 2024/04/11 03:55:39 DEBUG : sub/file2: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f417075306c2ce00d_d20240411_m035400_c001_v7007000_t0000_u01712807640812") 2024/04/11 03:55:39 DEBUG : sub/file2: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b16b32_d20240411_m035357_c001_v0001179_t0044_u01712807637921") 2024/04/11 03:55:39 DEBUG : sub/file2: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f40561a87487509e4_d20240411_m035314_c001_v7007000_t0000_u01712807594303") 2024/04/11 03:55:39 DEBUG : sub/file2: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b16434_d20240411_m035313_c001_v0001179_t0017_u01712807593120") 2024/04/11 03:55:39 DEBUG : test-multithread-abort: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f40878ed6710f981d_d20240411_m035206_c001_v7007000_t0000_u01712807526962") 2024/04/11 03:55:39 DEBUG : test-multithread-abort: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b1563c_d20240411_m035144_c001_v0001179_t0058_u01712807504310") 2024/04/11 03:55:39 DEBUG : test-multithread-copy-false-201326591-2: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f40878ed6710f9801_d20240411_m035015_c001_v7007000_t0000_u01712807415563") 2024/04/11 03:55:39 DEBUG : test-multithread-copy-false-201326591-2: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b145e1_d20240411_m034953_c001_v0001179_t0059_u01712807393600") 2024/04/11 03:55:39 DEBUG : test-multithread-copy-false-201326592-2: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f41355d70c99ab4af_d20240411_m035040_c001_v7007000_t0000_u01712807440265") 2024/04/11 03:55:39 DEBUG : test-multithread-copy-false-201326592-2: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b1498b_d20240411_m035018_c001_v0001179_t0000_u01712807418157") 2024/04/11 03:55:40 DEBUG : test-multithread-copy-false-201326593-2: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f417075306c2cdec3_d20240411_m035104_c001_v7007000_t0000_u01712807464546") 2024/04/11 03:55:40 DEBUG : test-multithread-copy-false-201326593-2: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b14dc4_d20240411_m035042_c001_v0001179_t0020_u01712807442827") 2024/04/11 03:55:40 DEBUG : test-multithread-copy-true-201326591-2: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f403b73892aafb746_d20240411_m035117_c001_v7007000_t0000_u01712807477031") 2024/04/11 03:55:40 DEBUG : test-multithread-copy-true-201326591-2: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f217075306c2cdecd_d20240411_m035107_c001_v0001179_t0018_u01712807467751") 2024/04/11 03:55:40 DEBUG : test-multithread-copy-true-201326592-2: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f403b73892aafb758_d20240411_m035130_c001_v7007000_t0000_u01712807490106") 2024/04/11 03:55:40 DEBUG : test-multithread-copy-true-201326592-2: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f203b73892aafb74c_d20240411_m035120_c001_v0001172_t0018_u01712807480320") 2024/04/11 03:55:40 DEBUG : test-multithread-copy-true-201326593-2: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f404178af593e6c8c_d20240411_m035142_c001_v7007000_t0000_u01712807502973") 2024/04/11 03:55:40 DEBUG : test-multithread-copy-true-201326593-2: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f203b73892aafb75c_d20240411_m035133_c001_v0001180_t0037_u01712807493209") 2024/04/11 03:55:40 DEBUG : test.sum: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f40878ed6710f9833_d20240411_m035237_c001_v7007000_t0000_u01712807557953") 2024/04/11 03:55:40 DEBUG : test.sum: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b15e9d_d20240411_m035235_c001_v0001179_t0010_u01712807555918") 2024/04/11 03:55:40 DEBUG : test.sum: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b15e39_d20240411_m035233_c001_v0001179_t0017_u01712807553960") 2024/04/11 03:55:40 DEBUG : test.sum: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b15dff_d20240411_m035232_c001_v0001179_t0050_u01712807552824") 2024/04/11 03:55:40 DEBUG : test.sum: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b15dd4_d20240411_m035231_c001_v0001179_t0023_u01712807551604") 2024/04/11 03:55:40 DEBUG : test.sum: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b15d90_d20240411_m035230_c001_v0001179_t0020_u01712807550178") 2024/04/11 03:55:40 DEBUG : test.sum: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b15d5d_d20240411_m035228_c001_v0001179_t0032_u01712807548947") 2024/04/11 03:55:40 DEBUG : test.sum: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b15d15_d20240411_m035227_c001_v0001179_t0048_u01712807547343") 2024/04/11 03:55:40 DEBUG : test.sum: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f40878ed6710f982d_d20240411_m035224_c001_v7007000_t0000_u01712807544919") 2024/04/11 03:55:40 DEBUG : test.sum: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b15c97_d20240411_m035224_c001_v0001179_t0039_u01712807544131") 2024/04/11 03:55:40 DEBUG : test.sum: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b15c5a_d20240411_m035222_c001_v0001179_t0019_u01712807542816") 2024/04/11 03:55:40 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f403b73892aafd830_d20240411_m035513_c001_v7007000_t0000_u01712807713240") 2024/04/11 03:55:41 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b1767a_d20240411_m035511_c001_v0001179_t0050_u01712807711543") 2024/04/11 03:55:41 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f403b73892aafd831_d20240411_m035513_c001_v7007000_t0000_u01712807713422") 2024/04/11 03:55:41 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b1766a_d20240411_m035511_c001_v0001179_t0002_u01712807711326") 2024/04/11 03:55:41 DEBUG : zend.txt: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f417075306c2ce008_d20240411_m035357_c001_v7007000_t0000_u01712807637219") 2024/04/11 03:55:41 DEBUG : zend.txt: Deleting (id "4_zff52ad73406dcbbb8fe80e1b_f1194c02398b16a4d_d20240411_m035351_c001_v0001179_t0025_u01712807631333") "./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -fast-list" - Finished OK in 5m54.250835203s (try 1/5)