"./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose" - Starting (try 1/5) 2024/09/30 01:43:59 DEBUG : Creating backend with remote "TestB2:rclone-test-weyaboq3gage" 2024/09/30 01:43:59 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/09/30 01:44:00 DEBUG : Creating backend with remote "/tmp/rclone1930589672" === 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-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" 2024/09/30 01:44:01 DEBUG : chunksize-probe: Cancelling large file upload === RUN TestMultithreadCopy/upload=false,size=201326591,streams=2 2024/09/30 01:44:37 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2024/09/30 01:44:37 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: write buffer set to 131072 2024/09/30 01:44:37 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2024/09/30 01:44:37 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2024/09/30 01:44:37 DEBUG : test-multithread-copy-false-201326591-2: Starting multi-thread copy with 3 chunks of size 64Mi with 3 parallel streams 2024/09/30 01:44:37 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 3/3 (134217728-201326591) size 64.000Mi starting 2024/09/30 01:44:37 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 1/3 (0-67108864) size 64Mi starting 2024/09/30 01:44:37 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 2/3 (67108864-134217728) size 64Mi starting 2024/09/30 01:44:38 DEBUG : test-multithread-copy-false-201326591-2: writing chunk 2 2024/09/30 01:44:38 DEBUG : test-multithread-copy-false-201326591-2: writing chunk 0 2024/09/30 01:44:38 DEBUG : test-multithread-copy-false-201326591-2: writing chunk 1 2024/09/30 01:44:43 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 3/3 (134217728-201326591) size 64.000Mi finished 2024/09/30 01:44:43 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 2/3 (67108864-134217728) size 64Mi finished 2024/09/30 01:44:43 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 1/3 (0-67108864) size 64Mi finished 2024/09/30 01:44:43 DEBUG : test-multithread-copy-false-201326591-2: Finished multi-thread copy with 3 parts of size 64Mi === RUN TestMultithreadCopy/upload=false,size=201326592,streams=2 2024/09/30 01:45:19 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2024/09/30 01:45:19 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: write buffer set to 131072 2024/09/30 01:45:19 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2024/09/30 01:45:19 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2024/09/30 01:45:19 DEBUG : test-multithread-copy-false-201326592-2: Starting multi-thread copy with 3 chunks of size 64Mi with 3 parallel streams 2024/09/30 01:45:19 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 3/3 (134217728-201326592) size 64Mi starting 2024/09/30 01:45:19 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 1/3 (0-67108864) size 64Mi starting 2024/09/30 01:45:19 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 2/3 (67108864-134217728) size 64Mi starting 2024/09/30 01:45:19 DEBUG : test-multithread-copy-false-201326592-2: writing chunk 2 2024/09/30 01:45:20 DEBUG : test-multithread-copy-false-201326592-2: writing chunk 0 2024/09/30 01:45:20 DEBUG : test-multithread-copy-false-201326592-2: writing chunk 1 2024/09/30 01:45:24 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 3/3 (134217728-201326592) size 64Mi finished 2024/09/30 01:45:25 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 1/3 (0-67108864) size 64Mi finished 2024/09/30 01:45:25 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 2/3 (67108864-134217728) size 64Mi finished 2024/09/30 01:45:25 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/09/30 01:46:09 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2024/09/30 01:46:09 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: write buffer set to 131072 2024/09/30 01:46:09 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2024/09/30 01:46:09 DEBUG : test-multithread-copy-false-201326593-2: Starting multi-thread copy with 4 chunks of size 64Mi with 4 parallel streams 2024/09/30 01:46:09 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 4/4 (201326592-201326593) size 1 starting 2024/09/30 01:46:09 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 2/4 (67108864-134217728) size 64Mi starting 2024/09/30 01:46:09 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 1/4 (0-67108864) size 64Mi starting 2024/09/30 01:46:09 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 3/4 (134217728-201326592) size 64Mi starting 2024/09/30 01:46:09 DEBUG : test-multithread-copy-false-201326593-2: writing chunk 3 2024/09/30 01:46:09 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 4/4 (201326592-201326593) size 1 finished 2024/09/30 01:46:09 DEBUG : test-multithread-copy-false-201326593-2: writing chunk 1 2024/09/30 01:46:10 DEBUG : test-multithread-copy-false-201326593-2: writing chunk 0 2024/09/30 01:46:10 DEBUG : test-multithread-copy-false-201326593-2: writing chunk 2 2024/09/30 01:46:15 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 2/4 (67108864-134217728) size 64Mi finished 2024/09/30 01:46:15 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 1/4 (0-67108864) size 64Mi finished 2024/09/30 01:46:15 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 3/4 (134217728-201326592) size 64Mi finished 2024/09/30 01:46:15 DEBUG : test-multithread-copy-false-201326593-2: Finished multi-thread copy with 4 parts of size 64Mi === RUN TestMultithreadCopy/upload=true,size=201326591,streams=2 2024/09/30 01:46:41 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: disabling buffering because source is local disk 2024/09/30 01:46:42 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2024/09/30 01:46:42 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: number of streams 4 was bigger than number of chunks 2 2024/09/30 01:46:42 DEBUG : test-multithread-copy-true-201326591-2: Starting multi-thread copy with 2 chunks of size 96Mi with 2 parallel streams 2024/09/30 01:46:42 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: chunk 2/2 (100663296-201326591) size 96.000Mi starting 2024/09/30 01:46:42 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: chunk 1/2 (0-100663296) size 96Mi starting 2024/09/30 01:46:42 DEBUG : test-multithread-copy-true-201326591-2: Sending chunk 1 length 100663295 2024/09/30 01:46:42 DEBUG : test-multithread-copy-true-201326591-2: Sending chunk 0 length 100663296 2024/09/30 01:46:51 DEBUG : test-multithread-copy-true-201326591-2: Done sending chunk 1 2024/09/30 01:46:51 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: chunk 2/2 (100663296-201326591) size 96.000Mi finished 2024/09/30 01:47:09 DEBUG : test-multithread-copy-true-201326591-2: Done sending chunk 0 2024/09/30 01:47:09 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: chunk 1/2 (0-100663296) size 96Mi finished 2024/09/30 01:47:09 DEBUG : test-multithread-copy-true-201326591-2: Finishing large file upload with 2 parts 2024/09/30 01:47:09 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/09/30 01:47:35 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: disabling buffering because source is local disk 2024/09/30 01:47:36 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2024/09/30 01:47:36 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: number of streams 4 was bigger than number of chunks 2 2024/09/30 01:47:36 DEBUG : test-multithread-copy-true-201326592-2: Starting multi-thread copy with 2 chunks of size 96Mi with 2 parallel streams 2024/09/30 01:47:36 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: chunk 2/2 (100663296-201326592) size 96Mi starting 2024/09/30 01:47:36 DEBUG : test-multithread-copy-true-201326592-2: Sending chunk 1 length 100663296 2024/09/30 01:47:36 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: chunk 1/2 (0-100663296) size 96Mi starting 2024/09/30 01:47:36 DEBUG : test-multithread-copy-true-201326592-2: Sending chunk 0 length 100663296 2024/09/30 01:47:44 DEBUG : test-multithread-copy-true-201326592-2: Done sending chunk 0 2024/09/30 01:47:44 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: chunk 1/2 (0-100663296) size 96Mi finished 2024/09/30 01:47:53 DEBUG : test-multithread-copy-true-201326592-2: Done sending chunk 1 2024/09/30 01:47:53 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: chunk 2/2 (100663296-201326592) size 96Mi finished 2024/09/30 01:47:53 DEBUG : test-multithread-copy-true-201326592-2: Finishing large file upload with 2 parts 2024/09/30 01:47:54 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/09/30 01:48:20 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: disabling buffering because source is local disk 2024/09/30 01:48:21 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2024/09/30 01:48:21 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2024/09/30 01:48:21 DEBUG : test-multithread-copy-true-201326593-2: Starting multi-thread copy with 3 chunks of size 96Mi with 3 parallel streams 2024/09/30 01:48:21 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 3/3 (201326592-201326593) size 1 starting 2024/09/30 01:48:21 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 1/3 (0-100663296) size 96Mi starting 2024/09/30 01:48:21 DEBUG : test-multithread-copy-true-201326593-2: Sending chunk 2 length 1 2024/09/30 01:48:21 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 2/3 (100663296-201326592) size 96Mi starting 2024/09/30 01:48:21 DEBUG : test-multithread-copy-true-201326593-2: Sending chunk 1 length 100663296 2024/09/30 01:48:21 DEBUG : test-multithread-copy-true-201326593-2: Sending chunk 0 length 100663296 2024/09/30 01:48:22 DEBUG : test-multithread-copy-true-201326593-2: Done sending chunk 2 2024/09/30 01:48:22 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 3/3 (201326592-201326593) size 1 finished 2024/09/30 01:48:30 DEBUG : test-multithread-copy-true-201326593-2: Done sending chunk 0 2024/09/30 01:48:30 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 1/3 (0-100663296) size 96Mi finished 2024/09/30 01:48:30 DEBUG : test-multithread-copy-true-201326593-2: Done sending chunk 1 2024/09/30 01:48:30 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 2/3 (100663296-201326592) size 96Mi finished 2024/09/30 01:48:30 DEBUG : test-multithread-copy-true-201326593-2: Finishing large file upload with 3 parts 2024/09/30 01:48:31 DEBUG : test-multithread-copy-true-201326593-2: Finished multi-thread copy with 3 parts of size 96Mi --- PASS: TestMultithreadCopy (280.71s) --- PASS: TestMultithreadCopy/upload=false,size=201326591,streams=2 (52.35s) --- PASS: TestMultithreadCopy/upload=false,size=201326592,streams=2 (40.17s) --- PASS: TestMultithreadCopy/upload=false,size=201326593,streams=2 (49.95s) --- PASS: TestMultithreadCopy/upload=true,size=201326591,streams=2 (54.39s) --- PASS: TestMultithreadCopy/upload=true,size=201326592,streams=2 (45.01s) --- PASS: TestMultithreadCopy/upload=true,size=201326593,streams=2 (37.12s) === RUN TestMultithreadCopyAbort run.go:180: Remote "B2 bucket rclone-test-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" 2024/09/30 01:48:41 DEBUG : chunksize-probe: Cancelling large file upload 2024/09/30 01:48:59 DEBUG : test-multithread-abort: multi-thread copy: disabling buffering because source is local disk 2024/09/30 01:49:00 DEBUG : test-multithread-abort: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 1 2024/09/30 01:49:00 DEBUG : test-multithread-abort: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2024/09/30 01:49:00 DEBUG : test-multithread-abort: Starting multi-thread copy with 3 chunks of size 96Mi with 3 parallel streams 2024/09/30 01:49:00 DEBUG : test-multithread-abort: multi-thread copy: chunk 3/3 (201326592-201326593) size 1 starting 2024/09/30 01:49:00 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2024/09/30 01:49:00 DEBUG : test-multithread-abort: multi-thread copy: chunk 1/3 (0-100663296) size 96Mi starting 2024/09/30 01:49:00 DEBUG : Open with options = [RangeOption(0,100663295)] 2024/09/30 01:49:00 DEBUG : test-multithread-abort: Sending chunk 0 length 100663296 2024/09/30 01:49:00 DEBUG : test-multithread-abort: multi-thread copy: chunk 2/3 (100663296-201326592) size 96Mi starting 2024/09/30 01:49:00 DEBUG : Open with options = [RangeOption(100663296,201326591)] 2024/09/30 01:49:00 DEBUG : test-multithread-abort: Sending chunk 1 length 100663296 2024/09/30 01:49:09 DEBUG : test-multithread-abort: Done sending chunk 1 2024/09/30 01:49:09 DEBUG : test-multithread-abort: multi-thread copy: chunk 2/3 (100663296-201326592) size 96Mi finished 2024/09/30 01:49:35 DEBUG : test-multithread-abort: Done sending chunk 0 2024/09/30 01:49:35 DEBUG : test-multithread-abort: multi-thread copy: chunk 1/3 (0-100663296) size 96Mi finished 2024/09/30 01:49:35 DEBUG : Returning error reader 2024/09/30 01:49:35 DEBUG : test-multithread-abort: Sending chunk 2 length 1 2024/09/30 01:49:36 DEBUG : BOOM: simulated read failure 2024/09/30 01:49:36 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 0/10: BOOM: simulated read failure 2024/09/30 01:49:36 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2024/09/30 01:49:37 DEBUG : Returning error reader 2024/09/30 01:49:37 DEBUG : BOOM: simulated read failure 2024/09/30 01:49:37 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 1/10: BOOM: simulated read failure 2024/09/30 01:49:37 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2024/09/30 01:49:38 DEBUG : Returning error reader 2024/09/30 01:49:38 DEBUG : BOOM: simulated read failure 2024/09/30 01:49:38 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 2/10: BOOM: simulated read failure 2024/09/30 01:49:38 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2024/09/30 01:49:39 DEBUG : Returning error reader 2024/09/30 01:49:39 DEBUG : BOOM: simulated read failure 2024/09/30 01:49:39 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 3/10: BOOM: simulated read failure 2024/09/30 01:49:39 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2024/09/30 01:49:40 DEBUG : Returning error reader 2024/09/30 01:49:40 DEBUG : BOOM: simulated read failure 2024/09/30 01:49:40 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 4/10: BOOM: simulated read failure 2024/09/30 01:49:40 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2024/09/30 01:49:41 DEBUG : Returning error reader 2024/09/30 01:49:41 DEBUG : BOOM: simulated read failure 2024/09/30 01:49:41 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 5/10: BOOM: simulated read failure 2024/09/30 01:49:41 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2024/09/30 01:49:42 DEBUG : Returning error reader 2024/09/30 01:49:42 DEBUG : BOOM: simulated read failure 2024/09/30 01:49:42 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 6/10: BOOM: simulated read failure 2024/09/30 01:49:42 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2024/09/30 01:49:43 DEBUG : Returning error reader 2024/09/30 01:49:43 DEBUG : BOOM: simulated read failure 2024/09/30 01:49:43 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 7/10: BOOM: simulated read failure 2024/09/30 01:49:43 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2024/09/30 01:49:44 DEBUG : Returning error reader 2024/09/30 01:49:44 DEBUG : BOOM: simulated read failure 2024/09/30 01:49:44 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 8/10: BOOM: simulated read failure 2024/09/30 01:49:44 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2024/09/30 01:49:45 DEBUG : Returning error reader 2024/09/30 01:49:45 DEBUG : BOOM: simulated read failure 2024/09/30 01:49:45 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 9/10: BOOM: simulated read failure 2024/09/30 01:49:45 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2024/09/30 01:49:46 DEBUG : Returning error reader 2024/09/30 01:49:46 DEBUG : BOOM: simulated read failure 2024/09/30 01:49:46 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 10/10: BOOM: simulated read failure 2024/09/30 01:49:46 DEBUG : test-multithread-abort: Reopen failed after offset 0 bytes read: failed to reopen: too many retries 2024/09/30 01:49:46 DEBUG : test-multithread-abort: Error sending chunk 2 (retry=false): Post "https://pod-000-1130-02.backblaze.com/b2api/v1/b2_upload_part/4_zaf524da3909d4b0b9f280e1b_f216861bea1fe9fd0_d20240930_m014900_c001_v0001130_t0033_u01727660940565/0013": BOOM: simulated read failure: &url.Error{Op:"Post", URL:"https://pod-000-1130-02.backblaze.com/b2api/v1/b2_upload_part/4_zaf524da3909d4b0b9f280e1b_f216861bea1fe9fd0_d20240930_m014900_c001_v0001130_t0033_u01727660940565/0013", Err:(*errors.errorString)(0xc000771050)} 2024/09/30 01:49:46 DEBUG : test-multithread-abort: Error sending chunk 2: Post "https://pod-000-1130-02.backblaze.com/b2api/v1/b2_upload_part/4_zaf524da3909d4b0b9f280e1b_f216861bea1fe9fd0_d20240930_m014900_c001_v0001130_t0033_u01727660940565/0013": BOOM: simulated read failure 2024/09/30 01:49:46 DEBUG : test-multithread-abort: multi-thread copy: chunk 3/3 failed: multi-thread copy: failed to write chunk: Post "https://pod-000-1130-02.backblaze.com/b2api/v1/b2_upload_part/4_zaf524da3909d4b0b9f280e1b_f216861bea1fe9fd0_d20240930_m014900_c001_v0001130_t0033_u01727660940565/0013": BOOM: simulated read failure 2024/09/30 01:49:46 DEBUG : test-multithread-abort: multi-thread copy: cancelling transfer on exit 2024/09/30 01:49:46 DEBUG : test-multithread-abort: Cancelling large file upload --- PASS: TestMultithreadCopyAbort (66.47s) === RUN TestSizeDiffers --- PASS: TestSizeDiffers (0.00s) === RUN TestReOpen === RUN TestReOpen/Normal === RUN TestReOpen/Normal/Basics 2024/09/30 01:49:47 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/ErrorAtStart === RUN TestReOpen/Normal/WithErrors 2024/09/30 01:49:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2024/09/30 01:49:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2024/09/30 01:49:47 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/Normal/TooManyErrors 2024/09/30 01:49:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2024/09/30 01:49:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2024/09/30 01:49:47 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2024/09/30 01:49:47 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/Normal/Seek 2024/09/30 01:49:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2024/09/30 01:49:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2024/09/30 01:49:47 DEBUG : potato: Seek from 5 to 2 === RUN TestReOpen/Normal/AccountRead === RUN TestReOpen/Normal/AccountReadDelay 2024/09/30 01:49:47 DEBUG : potato: Seek from 10 to 0 2024/09/30 01:49:47 DEBUG : potato: Seek from 10 to 0 2024/09/30 01:49:47 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/AccountReadError === RUN TestReOpen/WithRangeOption === RUN TestReOpen/WithRangeOption/Basics 2024/09/30 01:49:47 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/ErrorAtStart === RUN TestReOpen/WithRangeOption/WithErrors 2024/09/30 01:49:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2024/09/30 01:49:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2024/09/30 01:49:47 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithRangeOption/TooManyErrors 2024/09/30 01:49:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2024/09/30 01:49:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2024/09/30 01:49:47 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2024/09/30 01:49:47 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithRangeOption/Seek 2024/09/30 01:49:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2024/09/30 01:49:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2024/09/30 01:49:47 DEBUG : potato: Seek from 5 to 2 2024/09/30 01:49:47 DEBUG : potato: Seek from 7 to 4 === RUN TestReOpen/WithRangeOption/AccountRead === RUN TestReOpen/WithRangeOption/AccountReadDelay 2024/09/30 01:49:47 DEBUG : potato: Seek from 7 to 0 2024/09/30 01:49:47 DEBUG : potato: Seek from 7 to 0 2024/09/30 01:49:47 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/AccountReadError === RUN TestReOpen/WithSeekOption === RUN TestReOpen/WithSeekOption/Basics 2024/09/30 01:49:47 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/ErrorAtStart === RUN TestReOpen/WithSeekOption/WithErrors 2024/09/30 01:49:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2024/09/30 01:49:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2024/09/30 01:49:47 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithSeekOption/TooManyErrors 2024/09/30 01:49:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2024/09/30 01:49:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2024/09/30 01:49:47 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2024/09/30 01:49:47 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithSeekOption/Seek 2024/09/30 01:49:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2024/09/30 01:49:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2024/09/30 01:49:47 DEBUG : potato: Seek from 5 to 2 2024/09/30 01:49:47 DEBUG : potato: Seek from 7 to 5 === RUN TestReOpen/WithSeekOption/AccountRead === RUN TestReOpen/WithSeekOption/AccountReadDelay 2024/09/30 01:49:47 DEBUG : potato: Seek from 8 to 0 2024/09/30 01:49:47 DEBUG : potato: Seek from 8 to 0 2024/09/30 01:49:47 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/AccountReadError === RUN TestReOpen/UnknownSize === RUN TestReOpen/UnknownSize/Basics 2024/09/30 01:49:47 DEBUG : potato: Seek from 9 to 0 === RUN TestReOpen/UnknownSize/ErrorAtStart === RUN TestReOpen/UnknownSize/WithErrors 2024/09/30 01:49:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2024/09/30 01:49:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2024/09/30 01:49:47 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/UnknownSize/TooManyErrors 2024/09/30 01:49:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2024/09/30 01:49:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2024/09/30 01:49:47 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2024/09/30 01:49:47 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/UnknownSize/Seek 2024/09/30 01:49:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2024/09/30 01:49:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2024/09/30 01:49:47 DEBUG : potato: Seek from 5 to 2 2024/09/30 01:49:47 DEBUG : potato: Seek from 7 to 6 === RUN TestReOpen/UnknownSize/AccountRead === RUN TestReOpen/UnknownSize/AccountReadDelay 2024/09/30 01:49:47 DEBUG : potato: Seek from 9 to 0 2024/09/30 01:49:47 DEBUG : potato: Seek from 9 to 0 2024/09/30 01:49:47 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-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", 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 (6.44s) --- PASS: TestCheck/1 (0.18s) --- PASS: TestCheck/2 (0.18s) --- PASS: TestCheck/3 (0.18s) --- PASS: TestCheck/4 (0.18s) --- PASS: TestCheck/5 (0.18s) --- PASS: TestCheck/6 (0.18s) --- PASS: TestCheck/7 (0.18s) === RUN TestCheckFsError 2024/09/30 01:49:54 DEBUG : Creating backend with remote "nonexistent" 2024/09/30 01:49:54 DEBUG : Config file has changed externally - reloading 2024/09/30 01:49:54 DEBUG : Creating backend with remote "nonexistent" 2024/09/30 01:49:54 DEBUG : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: Waiting for checks to finish 2024/09/30 01:49:54 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/09/30 01:49:54 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: 2 differences found 2024/09/30 01:49:54 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-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", 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 (8.19s) --- PASS: TestCheckDownload/1 (0.74s) --- PASS: TestCheckDownload/2 (0.36s) --- PASS: TestCheckDownload/3 (0.37s) --- PASS: TestCheckDownload/4 (0.56s) --- PASS: TestCheckDownload/5 (0.40s) --- PASS: TestCheckDownload/6 (0.38s) --- PASS: TestCheckDownload/7 (0.44s) === RUN TestCheckSizeOnly run.go:180: Remote "B2 bucket rclone-test-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" === RUN TestCheckSizeOnly/1 === RUN TestCheckSizeOnly/2 === RUN TestCheckSizeOnly/3 === RUN TestCheckSizeOnly/4 === RUN TestCheckSizeOnly/5 === RUN TestCheckSizeOnly/6 === RUN TestCheckSizeOnly/7 --- PASS: TestCheckSizeOnly (5.92s) --- PASS: TestCheckSizeOnly/1 (0.18s) --- PASS: TestCheckSizeOnly/2 (0.18s) --- PASS: TestCheckSizeOnly/3 (0.18s) --- PASS: TestCheckSizeOnly/4 (0.19s) --- PASS: TestCheckSizeOnly/5 (0.18s) --- PASS: TestCheckSizeOnly/6 (0.18s) --- PASS: TestCheckSizeOnly/7 (0.18s) === RUN TestCheckEqualReaders --- PASS: TestCheckEqualReaders (0.00s) === RUN TestParseSumFile run.go:180: Remote "B2 bucket rclone-test-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" 2024/09/30 01:50:10 NOTICE: test.sum: improperly formatted checksum line 4 2024/09/30 01:50:10 NOTICE: test.sum: improperly formatted checksum line 5 2024/09/30 01:50:10 NOTICE: test.sum: improperly formatted checksum line 6 2024/09/30 01:50:10 NOTICE: test.sum: 2 warning(s) suppressed... 2024/09/30 01:50:11 NOTICE: test.sum: improperly formatted checksum line 4 2024/09/30 01:50:11 NOTICE: test.sum: improperly formatted checksum line 5 2024/09/30 01:50:11 NOTICE: test.sum: improperly formatted checksum line 6 2024/09/30 01:50:11 NOTICE: test.sum: 2 warning(s) suppressed... --- PASS: TestParseSumFile (3.50s) === RUN TestCheckSum run.go:180: Remote "B2 bucket rclone-test-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" 2024/09/30 01:50:11 DEBUG : Creating backend with remote "TestB2:rclone-test-weyaboq3gage/data" check_test.go:354: B2 bucket rclone-test-weyaboq3gage path data lacks md5, skipping --- SKIP: TestCheckSum (1.48s) === RUN TestCheckSumDownload run.go:180: Remote "B2 bucket rclone-test-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" 2024/09/30 01:50:13 DEBUG : Creating backend with remote "TestB2:rclone-test-weyaboq3gage/data" === RUN TestCheckSumDownload/subtest1 === RUN TestCheckSumDownload/subtest2 === RUN TestCheckSumDownload/subtest3 === RUN TestCheckSumDownload/subtest4 === RUN TestCheckSumDownload/subtest5 === RUN TestCheckSumDownload/subtest6 === RUN TestCheckSumDownload/subtest7 --- PASS: TestCheckSumDownload (17.62s) --- PASS: TestCheckSumDownload/subtest1 (1.34s) --- PASS: TestCheckSumDownload/subtest2 (0.82s) --- PASS: TestCheckSumDownload/subtest3 (0.92s) --- PASS: TestCheckSumDownload/subtest4 (0.77s) --- PASS: TestCheckSumDownload/subtest5 (0.75s) --- PASS: TestCheckSumDownload/subtest6 (0.75s) --- PASS: TestCheckSumDownload/subtest7 (0.76s) === RUN TestApplyTransforms 2024/09/30 01:50:30 DEBUG : Creating backend with remote "TestB2:rclone-test-gegekix6nujo" 2024/09/30 01:50:30 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/09/30 01:50:31 DEBUG : Creating backend with remote "/tmp/rclone2511272710" run.go:180: Remote "B2 bucket rclone-test-gegekix6nujo", Local "Local file system at /tmp/rclone2511272710", Modify Window "1ms" upper checkfile vs. lower remote (without normalization) 2024/09/30 01:50:33 ERROR : hello, world!: sum not found 2024/09/30 01:50:33 ERROR : HELLO, WORLD!: file not in B2 bucket rclone-test-gegekix6nujo 2024/09/30 01:50:33 NOTICE: B2 bucket rclone-test-gegekix6nujo: 1 files missing 2024/09/30 01:50:33 NOTICE: 1 hashes missing 2024/09/30 01:50:33 NOTICE: B2 bucket rclone-test-gegekix6nujo: 2 differences found 2024/09/30 01:50:33 NOTICE: B2 bucket rclone-test-gegekix6nujo: 2 errors while checking upper checkfile vs. lower remote (with normalization) 2024/09/30 01:50:34 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2024/09/30 01:50:34 NOTICE: B2 bucket rclone-test-gegekix6nujo: 0 differences found 2024/09/30 01:50:34 NOTICE: B2 bucket rclone-test-gegekix6nujo: 1 matching files 2024/09/30 01:50:34 DEBUG : Creating backend with remote "TestB2:rclone-test-zatedar0jibu" 2024/09/30 01:50:34 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/09/30 01:50:34 DEBUG : Creating backend with remote "/tmp/rclone1977607055" run.go:180: Remote "B2 bucket rclone-test-zatedar0jibu", Local "Local file system at /tmp/rclone1977607055", Modify Window "1ms" lower checkfile vs. upper remote (without normalization) 2024/09/30 01:50:36 ERROR : HELLO, WORLD!: sum not found 2024/09/30 01:50:36 ERROR : hello, world!: file not in B2 bucket rclone-test-zatedar0jibu 2024/09/30 01:50:36 NOTICE: B2 bucket rclone-test-zatedar0jibu: 1 files missing 2024/09/30 01:50:36 NOTICE: 1 hashes missing 2024/09/30 01:50:36 NOTICE: B2 bucket rclone-test-zatedar0jibu: 2 differences found 2024/09/30 01:50:36 NOTICE: B2 bucket rclone-test-zatedar0jibu: 2 errors while checking lower checkfile vs. upper remote (with normalization) 2024/09/30 01:50:37 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2024/09/30 01:50:37 NOTICE: B2 bucket rclone-test-zatedar0jibu: 0 differences found 2024/09/30 01:50:37 NOTICE: B2 bucket rclone-test-zatedar0jibu: 1 matching files 2024/09/30 01:50:37 DEBUG : Creating backend with remote "TestB2:rclone-test-vacecuk7xuzo" 2024/09/30 01:50:37 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/09/30 01:50:38 DEBUG : Creating backend with remote "/tmp/rclone1984473830" run.go:180: Remote "B2 bucket rclone-test-vacecuk7xuzo", Local "Local file system at /tmp/rclone1984473830", Modify Window "1ms" lower checkfile vs. upperlowermixed remote (without normalization) 2024/09/30 01:50:40 ERROR : HeLlO, wOrLd!: sum not found 2024/09/30 01:50:40 ERROR : hello, world!: file not in B2 bucket rclone-test-vacecuk7xuzo 2024/09/30 01:50:40 NOTICE: B2 bucket rclone-test-vacecuk7xuzo: 1 files missing 2024/09/30 01:50:40 NOTICE: 1 hashes missing 2024/09/30 01:50:40 NOTICE: B2 bucket rclone-test-vacecuk7xuzo: 2 differences found 2024/09/30 01:50:40 NOTICE: B2 bucket rclone-test-vacecuk7xuzo: 2 errors while checking lower checkfile vs. upperlowermixed remote (with normalization) 2024/09/30 01:50:41 DEBUG : HeLlO, wOrLd!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2024/09/30 01:50:41 NOTICE: B2 bucket rclone-test-vacecuk7xuzo: 0 differences found 2024/09/30 01:50:41 NOTICE: B2 bucket rclone-test-vacecuk7xuzo: 1 matching files 2024/09/30 01:50:41 DEBUG : Creating backend with remote "TestB2:rclone-test-mexogub1vome" 2024/09/30 01:50:41 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/09/30 01:50:42 DEBUG : Creating backend with remote "/tmp/rclone2951024242" run.go:180: Remote "B2 bucket rclone-test-mexogub1vome", Local "Local file system at /tmp/rclone2951024242", Modify Window "1ms" upperlowermixed checkfile vs. upper remote (without normalization) 2024/09/30 01:50:44 ERROR : HELLO, WORLD!: sum not found 2024/09/30 01:50:44 ERROR : HeLlO, wOrLd!: file not in B2 bucket rclone-test-mexogub1vome 2024/09/30 01:50:44 NOTICE: B2 bucket rclone-test-mexogub1vome: 1 files missing 2024/09/30 01:50:44 NOTICE: 1 hashes missing 2024/09/30 01:50:44 NOTICE: B2 bucket rclone-test-mexogub1vome: 2 differences found 2024/09/30 01:50:44 NOTICE: B2 bucket rclone-test-mexogub1vome: 2 errors while checking upperlowermixed checkfile vs. upper remote (with normalization) 2024/09/30 01:50:45 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2024/09/30 01:50:45 NOTICE: B2 bucket rclone-test-mexogub1vome: 0 differences found 2024/09/30 01:50:45 NOTICE: B2 bucket rclone-test-mexogub1vome: 1 matching files 2024/09/30 01:50:45 DEBUG : Creating backend with remote "TestB2:rclone-test-xoxitax4ceda" 2024/09/30 01:50:45 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/09/30 01:50:45 DEBUG : Creating backend with remote "/tmp/rclone1417469338" run.go:180: Remote "B2 bucket rclone-test-xoxitax4ceda", Local "Local file system at /tmp/rclone1417469338", Modify Window "1ms" NFD checkfile vs. NFC remote (without normalization) 2024/09/30 01:50:47 ERROR : 測試_Русский___ě_áñ: sum not found 2024/09/30 01:50:47 ERROR : 測試_Русский___ě_áñ: file not in B2 bucket rclone-test-xoxitax4ceda 2024/09/30 01:50:47 NOTICE: B2 bucket rclone-test-xoxitax4ceda: 1 files missing 2024/09/30 01:50:47 NOTICE: 1 hashes missing 2024/09/30 01:50:47 NOTICE: B2 bucket rclone-test-xoxitax4ceda: 2 differences found 2024/09/30 01:50:47 NOTICE: B2 bucket rclone-test-xoxitax4ceda: 2 errors while checking NFD checkfile vs. NFC remote (with normalization) 2024/09/30 01:50:48 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2024/09/30 01:50:48 NOTICE: B2 bucket rclone-test-xoxitax4ceda: 0 differences found 2024/09/30 01:50:48 NOTICE: B2 bucket rclone-test-xoxitax4ceda: 1 matching files 2024/09/30 01:50:48 DEBUG : Creating backend with remote "TestB2:rclone-test-nibijes9tisu" 2024/09/30 01:50:48 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/09/30 01:50:49 DEBUG : Creating backend with remote "/tmp/rclone556565536" run.go:180: Remote "B2 bucket rclone-test-nibijes9tisu", Local "Local file system at /tmp/rclone556565536", Modify Window "1ms" NFC checkfile vs. NFD remote (without normalization) 2024/09/30 01:50:51 ERROR : 測試_Русский___ě_áñ: sum not found 2024/09/30 01:50:51 ERROR : 測試_Русский___ě_áñ: file not in B2 bucket rclone-test-nibijes9tisu 2024/09/30 01:50:51 NOTICE: B2 bucket rclone-test-nibijes9tisu: 1 files missing 2024/09/30 01:50:51 NOTICE: 1 hashes missing 2024/09/30 01:50:51 NOTICE: B2 bucket rclone-test-nibijes9tisu: 2 differences found 2024/09/30 01:50:51 NOTICE: B2 bucket rclone-test-nibijes9tisu: 2 errors while checking NFC checkfile vs. NFD remote (with normalization) 2024/09/30 01:50:52 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2024/09/30 01:50:52 NOTICE: B2 bucket rclone-test-nibijes9tisu: 0 differences found 2024/09/30 01:50:52 NOTICE: B2 bucket rclone-test-nibijes9tisu: 1 matching files 2024/09/30 01:50:52 DEBUG : Creating backend with remote "TestB2:rclone-test-retocuc4sutu" 2024/09/30 01:50:52 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/09/30 01:50:53 DEBUG : Creating backend with remote "/tmp/rclone311276813" run.go:180: Remote "B2 bucket rclone-test-retocuc4sutu", Local "Local file system at /tmp/rclone311276813", Modify Window "1ms" NFDx2 checkfile vs. both remote (without normalization) 2024/09/30 01:50:56 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2024/09/30 01:50:56 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-retocuc4sutu 2024/09/30 01:50:56 NOTICE: B2 bucket rclone-test-retocuc4sutu: 1 files missing 2024/09/30 01:50:56 NOTICE: 1 hashes missing 2024/09/30 01:50:56 NOTICE: B2 bucket rclone-test-retocuc4sutu: 2 differences found 2024/09/30 01:50:56 NOTICE: B2 bucket rclone-test-retocuc4sutu: 2 errors while checking NFDx2 checkfile vs. both remote (with normalization) 2024/09/30 01:50:56 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2024/09/30 01:50:56 NOTICE: B2 bucket rclone-test-retocuc4sutu: 0 differences found 2024/09/30 01:50:56 NOTICE: B2 bucket rclone-test-retocuc4sutu: 1 matching files 2024/09/30 01:50:56 DEBUG : Creating backend with remote "TestB2:rclone-test-sehuquk2tulu" 2024/09/30 01:50:56 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/09/30 01:50:57 DEBUG : Creating backend with remote "/tmp/rclone3901831056" run.go:180: Remote "B2 bucket rclone-test-sehuquk2tulu", Local "Local file system at /tmp/rclone3901831056", Modify Window "1ms" NFCx2 checkfile vs. both remote (without normalization) 2024/09/30 01:50:59 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2024/09/30 01:50:59 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-sehuquk2tulu 2024/09/30 01:50:59 NOTICE: B2 bucket rclone-test-sehuquk2tulu: 1 files missing 2024/09/30 01:50:59 NOTICE: 1 hashes missing 2024/09/30 01:50:59 NOTICE: B2 bucket rclone-test-sehuquk2tulu: 2 differences found 2024/09/30 01:50:59 NOTICE: B2 bucket rclone-test-sehuquk2tulu: 2 errors while checking NFCx2 checkfile vs. both remote (with normalization) 2024/09/30 01:51:00 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2024/09/30 01:51:00 NOTICE: B2 bucket rclone-test-sehuquk2tulu: 0 differences found 2024/09/30 01:51:00 NOTICE: B2 bucket rclone-test-sehuquk2tulu: 1 matching files 2024/09/30 01:51:00 DEBUG : Creating backend with remote "TestB2:rclone-test-kazunev5wuke" 2024/09/30 01:51:00 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/09/30 01:51:01 DEBUG : Creating backend with remote "/tmp/rclone1287989659" run.go:180: Remote "B2 bucket rclone-test-kazunev5wuke", Local "Local file system at /tmp/rclone1287989659", Modify Window "1ms" both checkfile vs. NFDx2 remote (without normalization) 2024/09/30 01:51:03 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2024/09/30 01:51:03 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-kazunev5wuke 2024/09/30 01:51:03 NOTICE: B2 bucket rclone-test-kazunev5wuke: 1 files missing 2024/09/30 01:51:03 NOTICE: 1 hashes missing 2024/09/30 01:51:03 NOTICE: B2 bucket rclone-test-kazunev5wuke: 2 differences found 2024/09/30 01:51:03 NOTICE: B2 bucket rclone-test-kazunev5wuke: 2 errors while checking both checkfile vs. NFDx2 remote (with normalization) 2024/09/30 01:51:04 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2024/09/30 01:51:04 NOTICE: B2 bucket rclone-test-kazunev5wuke: 0 differences found 2024/09/30 01:51:04 NOTICE: B2 bucket rclone-test-kazunev5wuke: 1 matching files 2024/09/30 01:51:04 DEBUG : Creating backend with remote "TestB2:rclone-test-kuyisix6cici" 2024/09/30 01:51:04 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/09/30 01:51:04 DEBUG : Creating backend with remote "/tmp/rclone3365464956" run.go:180: Remote "B2 bucket rclone-test-kuyisix6cici", Local "Local file system at /tmp/rclone3365464956", Modify Window "1ms" both checkfile vs. NFCx2 remote (without normalization) 2024/09/30 01:51:07 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2024/09/30 01:51:07 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-kuyisix6cici 2024/09/30 01:51:07 NOTICE: B2 bucket rclone-test-kuyisix6cici: 1 files missing 2024/09/30 01:51:07 NOTICE: 1 hashes missing 2024/09/30 01:51:07 NOTICE: B2 bucket rclone-test-kuyisix6cici: 2 differences found 2024/09/30 01:51:07 NOTICE: B2 bucket rclone-test-kuyisix6cici: 2 errors while checking both checkfile vs. NFCx2 remote (with normalization) 2024/09/30 01:51:07 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2024/09/30 01:51:07 NOTICE: B2 bucket rclone-test-kuyisix6cici: 0 differences found 2024/09/30 01:51:07 NOTICE: B2 bucket rclone-test-kuyisix6cici: 1 matching files 2024/09/30 01:51:07 DEBUG : B2 bucket rclone-test-kuyisix6cici: Purge remote 2024/09/30 01:51:07 INFO : B2 bucket rclone-test-kuyisix6cici: cleaning bucket "rclone-test-kuyisix6cici" of all files 2024/09/30 01:51:07 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_zff52cd3390fd4b0b9f280e1b_f1028cfb5f46c8738_d20240930_m015106_c001_v0001146_t0009_u01727661066205") 2024/09/30 01:51:08 DEBUG : B2 bucket rclone-test-kazunev5wuke: Purge remote 2024/09/30 01:51:08 INFO : B2 bucket rclone-test-kazunev5wuke: cleaning bucket "rclone-test-kazunev5wuke" of all files 2024/09/30 01:51:09 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_z4f12bd6390fd4b0b9f280e1b_f119968107262143f_d20240930_m015102_c001_v0001176_t0004_u01727661062921") 2024/09/30 01:51:09 DEBUG : B2 bucket rclone-test-sehuquk2tulu: Purge remote 2024/09/30 01:51:09 INFO : B2 bucket rclone-test-sehuquk2tulu: cleaning bucket "rclone-test-sehuquk2tulu" of all files 2024/09/30 01:51:10 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_z6fe2ad7390fd4b0b9f280e1b_f1123a4f35b0b1651_d20240930_m015059_c001_v0001029_t0030_u01727661059015") 2024/09/30 01:51:11 DEBUG : B2 bucket rclone-test-retocuc4sutu: Purge remote 2024/09/30 01:51:11 INFO : B2 bucket rclone-test-retocuc4sutu: cleaning bucket "rclone-test-retocuc4sutu" of all files 2024/09/30 01:51:11 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_zaf329d6390fd4b0b9f280e1b_f10123180884b394c_d20240930_m015054_c001_v0001116_t0041_u01727661054626") 2024/09/30 01:51:12 DEBUG : B2 bucket rclone-test-nibijes9tisu: Purge remote 2024/09/30 01:51:12 INFO : B2 bucket rclone-test-nibijes9tisu: cleaning bucket "rclone-test-nibijes9tisu" of all files 2024/09/30 01:51:12 DEBUG : 測試_Русский___ě_áñ: Deleting (id "4_z7fa28d7390fd4b0b9f280e1b_f1127adb3780289d5_d20240930_m015050_c001_v0001181_t0019_u01727661050909") 2024/09/30 01:51:13 DEBUG : B2 bucket rclone-test-xoxitax4ceda: Purge remote 2024/09/30 01:51:13 INFO : B2 bucket rclone-test-xoxitax4ceda: cleaning bucket "rclone-test-xoxitax4ceda" of all files 2024/09/30 01:51:14 DEBUG : 測試_Русский___ě_áñ: Deleting (id "4_z0fa27da390fd4b0b9f280e1b_f1145699b605bcbea_d20240930_m015047_c001_v0001157_t0052_u01727661047344") 2024/09/30 01:51:14 DEBUG : B2 bucket rclone-test-mexogub1vome: Purge remote 2024/09/30 01:51:14 INFO : B2 bucket rclone-test-mexogub1vome: cleaning bucket "rclone-test-mexogub1vome" of all files 2024/09/30 01:51:15 DEBUG : HELLO, WORLD!: Deleting (id "4_z3fc26dc390fd4b0b9f280e1b_f116a946f84007f4e_d20240930_m015043_c001_v0001097_t0006_u01727661043906") 2024/09/30 01:51:15 DEBUG : B2 bucket rclone-test-vacecuk7xuzo: Purge remote 2024/09/30 01:51:15 INFO : B2 bucket rclone-test-vacecuk7xuzo: cleaning bucket "rclone-test-vacecuk7xuzo" of all files 2024/09/30 01:51:16 DEBUG : HeLlO, wOrLd!: Deleting (id "4_z3fe25dc390fd4b0b9f280e1b_f101556e60c5f1ec9_d20240930_m015039_c001_v0001115_t0053_u01727661039634") 2024/09/30 01:51:17 DEBUG : B2 bucket rclone-test-zatedar0jibu: Purge remote 2024/09/30 01:51:17 INFO : B2 bucket rclone-test-zatedar0jibu: cleaning bucket "rclone-test-zatedar0jibu" of all files 2024/09/30 01:51:17 DEBUG : HELLO, WORLD!: Deleting (id "4_z1f824df390fd4b0b9f280e1b_f110cdaa0e056e9bc_d20240930_m015036_c001_v0001176_t0059_u01727661036340") 2024/09/30 01:51:18 DEBUG : B2 bucket rclone-test-gegekix6nujo: Purge remote 2024/09/30 01:51:18 INFO : B2 bucket rclone-test-gegekix6nujo: cleaning bucket "rclone-test-gegekix6nujo" of all files 2024/09/30 01:51:19 DEBUG : hello, world!: Deleting (id "4_zdf024d1390fd4b0b9f280e1b_f106814beaa58551b_d20240930_m015032_c001_v0001181_t0047_u01727661032859") --- PASS: TestApplyTransforms (48.96s) === RUN TestTruncateString --- PASS: TestTruncateString (0.00s) === RUN TestCopyFile run.go:180: Remote "B2 bucket rclone-test-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" 2024/09/30 01:51:20 DEBUG : file1: Need to transfer - File not found at Destination 2024/09/30 01:51:21 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2024/09/30 01:51:21 INFO : file1: Copied (new) to: sub/file2 2024/09/30 01:51:22 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/30 01:51:22 DEBUG : file1: Unchanged skipping 2024/09/30 01:51:22 DEBUG : B2 bucket rclone-test-weyaboq3gage: don't need to copy/move sub/file2, it is already at target location --- PASS: TestCopyFile (3.61s) === RUN TestCopyLongFile run.go:180: Remote "B2 bucket rclone-test-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" copy_test.go:153: Test only runs on local --- SKIP: TestCopyLongFile (0.41s) === RUN TestCopyFileBackupDir run.go:180: Remote "B2 bucket rclone-test-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" 2024/09/30 01:51:24 DEBUG : Creating backend with remote "TestB2:rclone-test-weyaboq3gage/backup" 2024/09/30 01:51:25 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18) 2024/09/30 01:51:27 DEBUG : dst/file1: sha1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK 2024/09/30 01:51:27 INFO : dst/file1: Copied (server-side copy) 2024/09/30 01:51:27 INFO : dst/file1: Deleted 2024/09/30 01:51:28 DEBUG : dst/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2024/09/30 01:51:28 INFO : dst/file1: Copied (new) --- PASS: TestCopyFileBackupDir (5.33s) === RUN TestCopyFileCompareDest run.go:180: Remote "B2 bucket rclone-test-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" 2024/09/30 01:51:29 DEBUG : Creating backend with remote "TestB2:rclone-test-weyaboq3gage/dst" 2024/09/30 01:51:30 DEBUG : Creating backend with remote "TestB2:rclone-test-weyaboq3gage/CompareDest" 2024/09/30 01:51:31 DEBUG : one: Need to transfer - File not found at Destination 2024/09/30 01:51:33 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2024/09/30 01:51:33 INFO : one: Copied (new) 2024/09/30 01:51:34 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/09/30 01:51:35 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2024/09/30 01:51:35 INFO : one: Copied (replaced existing) 2024/09/30 01:51:36 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/09/30 01:51:37 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2024/09/30 01:51:37 DEBUG : one: Destination found in --compare-dest, skipping 2024/09/30 01:51:38 DEBUG : two: Need to transfer - File not found at Destination 2024/09/30 01:51:38 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2024/09/30 01:51:38 DEBUG : two: Destination found in --compare-dest, skipping 2024/09/30 01:51:38 DEBUG : two: Need to transfer - File not found at Destination 2024/09/30 01:51:39 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2024/09/30 01:51:39 DEBUG : two: Destination found in --compare-dest, skipping 2024/09/30 01:51:39 DEBUG : two: Need to transfer - File not found at Destination 2024/09/30 01:51:40 DEBUG : two: Sizes differ (src 5 vs dst 3) 2024/09/30 01:51:40 DEBUG : two: Clearing upload URL because of error: Post "https://pod-000-1103-09.backblaze.com/b2api/v1/b2_upload_file/af524da3909d4b0b9f280e1b/c001_v0001103_t0046": EOF 2024/09/30 01:51:40 DEBUG : pacer: low level retry 1/1 (error Post "https://pod-000-1103-09.backblaze.com/b2api/v1/b2_upload_file/af524da3909d4b0b9f280e1b/c001_v0001103_t0046": EOF) 2024/09/30 01:51:40 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2024/09/30 01:51:40 DEBUG : two: Received error: Post "https://pod-000-1103-09.backblaze.com/b2api/v1/b2_upload_file/af524da3909d4b0b9f280e1b/c001_v0001103_t0046": EOF - low level retry 0/10 2024/09/30 01:51:40 DEBUG : pacer: Reducing sleep to 10ms 2024/09/30 01:51:41 DEBUG : two: sha1 = a4be8877ad88f3109a6411985a1f359e10e2d930 OK 2024/09/30 01:51:41 INFO : two: Copied (new) --- PASS: TestCopyFileCompareDest (13.87s) === RUN TestCopyFileCopyDest run.go:180: Remote "B2 bucket rclone-test-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" 2024/09/30 01:51:42 DEBUG : Creating backend with remote "TestB2:rclone-test-weyaboq3gage/dst" 2024/09/30 01:51:44 DEBUG : Creating backend with remote "TestB2:rclone-test-weyaboq3gage/CopyDest" 2024/09/30 01:51:45 DEBUG : one: Need to transfer - File not found at Destination 2024/09/30 01:51:47 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2024/09/30 01:51:47 INFO : one: Copied (new) 2024/09/30 01:51:47 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/09/30 01:51:48 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2024/09/30 01:51:48 INFO : one: Copied (replaced existing) 2024/09/30 01:51:51 DEBUG : Creating backend with remote "TestB2:rclone-test-weyaboq3gage/BackupDir" 2024/09/30 01:51:52 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/09/30 01:51:52 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2024/09/30 01:51:52 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/09/30 01:51:54 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2024/09/30 01:51:54 INFO : one: Copied (server-side copy) 2024/09/30 01:51:55 INFO : one: Deleted 2024/09/30 01:51:55 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2024/09/30 01:51:55 INFO : one: Copied (server-side copy) 2024/09/30 01:51:55 DEBUG : one: Destination found in --copy-dest, using server-side copy 2024/09/30 01:51:55 DEBUG : CopyDest/two: Clearing upload URL because of error: Post "https://pod-000-1115-07.backblaze.com/b2api/v1/b2_upload_file/af524da3909d4b0b9f280e1b/c001_v0001115_t0059": EOF 2024/09/30 01:51:55 DEBUG : pacer: low level retry 1/1 (error Post "https://pod-000-1115-07.backblaze.com/b2api/v1/b2_upload_file/af524da3909d4b0b9f280e1b/c001_v0001115_t0059": EOF) 2024/09/30 01:51:55 DEBUG : pacer: Rate limited, increasing sleep to 20ms run.go:281: Retry Put of "CopyDest/two" to B2 bucket rclone-test-weyaboq3gage: 1/10 (Post "https://pod-000-1115-07.backblaze.com/b2api/v1/b2_upload_file/af524da3909d4b0b9f280e1b/c001_v0001115_t0059": EOF) 2024/09/30 01:51:57 DEBUG : pacer: Reducing sleep to 10ms 2024/09/30 01:52:01 DEBUG : two: Need to transfer - File not found at Destination 2024/09/30 01:52:02 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2024/09/30 01:52:03 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2024/09/30 01:52:03 INFO : two: Copied (server-side copy) 2024/09/30 01:52:03 DEBUG : two: Destination found in --copy-dest, using server-side copy 2024/09/30 01:52:03 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2024/09/30 01:52:03 DEBUG : two: Unchanged skipping 2024/09/30 01:52:05 DEBUG : three: Need to transfer - File not found at Destination 2024/09/30 01:52:05 DEBUG : three: Sizes differ (src 7 vs dst 5) 2024/09/30 01:52:05 DEBUG : three: Destination not found in --copy-dest 2024/09/30 01:52:06 DEBUG : three: sha1 = 370e613335f3cf2f19b2a2d35a86402f55c659f8 OK 2024/09/30 01:52:06 INFO : three: Copied (new) --- PASS: TestCopyFileCopyDest (25.04s) === RUN TestCopyInplace run.go:180: Remote "B2 bucket rclone-test-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" copy_test.go:370: Partial uploads not supported --- SKIP: TestCopyInplace (0.37s) === RUN TestCopyLongFileName run.go:180: Remote "B2 bucket rclone-test-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" copy_test.go:403: Partial uploads not supported --- SKIP: TestCopyLongFileName (0.37s) === RUN TestCopyFileMaxTransfer run.go:180: Remote "B2 bucket rclone-test-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" 2024/09/30 01:52:09 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination 2024/09/30 01:52:13 DEBUG : TestCopyFileMaxTransfer/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2024/09/30 01:52:13 INFO : TestCopyFileMaxTransfer/file1: Copied (new) 2024/09/30 01:52:13 DEBUG : pacer: low level retry 1/10 (error Post "https://api001.backblazeb2.com/b2api/v1/b2_list_file_names": EOF) 2024/09/30 01:52:13 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2024/09/30 01:52:14 DEBUG : pacer: Reducing sleep to 10ms 2024/09/30 01:52:14 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination 2024/09/30 01:52:14 ERROR : TestCopyFileMaxTransfer/file2: Failed to copy: Post "https://pod-000-1106-16.backblaze.com/b2api/v1/b2_upload_file/af524da3909d4b0b9f280e1b/c001_v0001106_t0013": max transfer limit reached as set by --max-transfer 2024/09/30 01:52:15 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination 2024/09/30 01:52:15 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination 2024/09/30 01:52:16 DEBUG : TestCopyFileMaxTransfer/file4: sha1 = 5871b1b68709b003bcd195cf8f9d688c6ca93418 OK 2024/09/30 01:52:16 INFO : TestCopyFileMaxTransfer/file4: Copied (new) --- PASS: TestCopyFileMaxTransfer (8.88s) === RUN TestDeduplicateInteractive run.go:180: Remote "B2 bucket rclone-test-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateInteractive (0.37s) === RUN TestDeduplicateSkip run.go:180: Remote "B2 bucket rclone-test-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", 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-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", 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-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateFirst (0.37s) === RUN TestDeduplicateNewest run.go:180: Remote "B2 bucket rclone-test-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateNewest (0.37s) === RUN TestDeduplicateNewestByHash run.go:180: Remote "B2 bucket rclone-test-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" 2024/09/30 01:52:27 INFO : B2 bucket rclone-test-weyaboq3gage: Looking for duplicate sha1 hashes using newest mode. 2024/09/30 01:52:27 NOTICE: 9594413fee1fd8f8f7f4dac151718b4e7dad9e0e: Found 3 files with duplicate sha1 hashes 2024/09/30 01:52:27 INFO : one: Deleted 2024/09/30 01:52:28 INFO : also/one: Deleted 2024/09/30 01:52:28 NOTICE: 9594413fee1fd8f8f7f4dac151718b4e7dad9e0e: Deleted 2 extra copies --- PASS: TestDeduplicateNewestByHash (9.51s) === RUN TestDeduplicateOldest run.go:180: Remote "B2 bucket rclone-test-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateOldest (0.39s) === RUN TestDeduplicateLargest run.go:180: Remote "B2 bucket rclone-test-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateLargest (0.36s) === RUN TestDeduplicateSmallest run.go:180: Remote "B2 bucket rclone-test-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSmallest (0.37s) === RUN TestDeduplicateRename run.go:180: Remote "B2 bucket rclone-test-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateRename (0.38s) === RUN TestMergeDirs run.go:180: Remote "B2 bucket rclone-test-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" dedupe_test.go:256: Can't merge directories --- SKIP: TestMergeDirs (0.37s) === RUN TestListDirSorted run.go:180: Remote "B2 bucket rclone-test-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" 2024/09/30 01:52:44 DEBUG : a.txt: Excluded (Size Filter) 2024/09/30 01:52:44 DEBUG : a.txt: Excluded 2024/09/30 01:52:44 DEBUG : sub dir/hello world: Excluded (Size Filter) 2024/09/30 01:52:44 DEBUG : sub dir/hello world: Excluded 2024/09/30 01:52:44 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2024/09/30 01:52:44 DEBUG : sub dir/hello world2: Excluded 2024/09/30 01:52:44 DEBUG : sub dir/hello world: Excluded (Size Filter) 2024/09/30 01:52:44 DEBUG : sub dir/hello world: Excluded 2024/09/30 01:52:44 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2024/09/30 01:52:44 DEBUG : sub dir/hello world2: Excluded 2024/09/30 01:52:45 DEBUG : sub dir/ignore dir: Excluded 2024/09/30 01:52:45 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSorted (16.97s) === RUN TestListJSON run.go:180: Remote "B2 bucket rclone-test-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", 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/09/30 01:52:51 ERROR : file1: Failed to read hash: hash type not supported === RUN TestListJSON/Metadata --- PASS: TestListJSON (4.25s) --- PASS: TestListJSON/Default (0.18s) --- PASS: TestListJSON/FilesOnly (0.19s) --- PASS: TestListJSON/DirsOnly (0.18s) --- PASS: TestListJSON/Recurse (0.18s) --- PASS: TestListJSON/SubDir (0.18s) --- PASS: TestListJSON/NoModTime (0.19s) --- PASS: TestListJSON/NoMimeType (0.18s) --- PASS: TestListJSON/ShowHash (0.19s) --- PASS: TestListJSON/HashTypes (0.18s) --- PASS: TestListJSON/Metadata (0.19s) === RUN TestStatJSON run.go:180: Remote "B2 bucket rclone-test-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", 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/09/30 01:52:57 DEBUG : Creating backend with remote "TestB2:rclone-test-weyaboq3gage/notfound" --- PASS: TestStatJSON (8.04s) --- PASS: TestStatJSON/Root (0.18s) --- PASS: TestStatJSON/RootFilesOnly (0.00s) --- PASS: TestStatJSON/RootDirsOnly (0.19s) --- PASS: TestStatJSON/Dir (0.73s) --- PASS: TestStatJSON/DirWithTrailingSlash (0.19s) --- PASS: TestStatJSON/File (0.21s) --- PASS: TestStatJSON/NotFound (0.37s) --- PASS: TestStatJSON/DirFilesOnly (0.18s) --- PASS: TestStatJSON/FileFilesOnly (0.18s) --- PASS: TestStatJSON/NotFoundFilesOnly (0.18s) --- PASS: TestStatJSON/DirDirsOnly (0.19s) --- PASS: TestStatJSON/FileDirsOnly (0.19s) --- PASS: TestStatJSON/NotFoundDirsOnly (0.18s) --- PASS: TestStatJSON/RootNotFound (1.95s) === RUN TestMkdir run.go:180: Remote "B2 bucket rclone-test-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" 2024/09/30 01:53:00 DEBUG : B2 bucket rclone-test-weyaboq3gage: Making directory 2024/09/30 01:53:00 DEBUG : B2 bucket rclone-test-weyaboq3gage: Making directory --- PASS: TestMkdir (0.56s) === RUN TestLsd run.go:180: Remote "B2 bucket rclone-test-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" --- PASS: TestLsd (1.94s) === RUN TestLs run.go:180: Remote "B2 bucket rclone-test-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" --- PASS: TestLs (2.63s) === RUN TestLsWithFilesFrom run.go:180: Remote "B2 bucket rclone-test-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" 2024/09/30 01:53:11 DEBUG : empty space: Excluded (FilesFrom Filter) 2024/09/30 01:53:11 DEBUG : empty space: Excluded --- PASS: TestLsWithFilesFrom (7.69s) === RUN TestLsLong run.go:180: Remote "B2 bucket rclone-test-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" --- PASS: TestLsLong (4.48s) === RUN TestHashSums run.go:180: Remote "B2 bucket rclone-test-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" === RUN TestHashSums/Sha1 === RUN TestHashSums/Sha1Download --- PASS: TestHashSums (9.73s) --- PASS: TestHashSums/Sha1 (0.18s) --- PASS: TestHashSums/Sha1Download (0.75s) === RUN TestHashSumsWithErrors 2024/09/30 01:53:27 DEBUG : Creating backend with remote ":memory:" 2024/09/30 01:53:27 ERROR : file1: hash unsupported: hash type not supported --- PASS: TestHashSumsWithErrors (0.00s) === RUN TestHashStream 2024/09/30 01:53:27 DEBUG : Creating md5 hash of 0 bytes read from input stream 2024/09/30 01:53:27 DEBUG : Creating md5 hash of 0 bytes read from input stream 2024/09/30 01:53:27 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2024/09/30 01:53:27 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2024/09/30 01:53:27 DEBUG : Creating md5 hash of 12 bytes read from input stream 2024/09/30 01:53:27 DEBUG : Creating md5 hash of 12 bytes read from input stream 2024/09/30 01:53:27 DEBUG : Creating sha1 hash of 12 bytes read from input stream 2024/09/30 01:53:27 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-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" --- PASS: TestCount (3.55s) === RUN TestDelete run.go:180: Remote "B2 bucket rclone-test-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" 2024/09/30 01:53:35 DEBUG : Waiting for deletions to finish 2024/09/30 01:53:35 DEBUG : large: Excluded (Size Filter) 2024/09/30 01:53:35 INFO : medium: Deleted 2024/09/30 01:53:35 INFO : small: Deleted --- PASS: TestDelete (5.84s) === RUN TestMaxDelete run.go:180: Remote "B2 bucket rclone-test-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" 2024/09/30 01:53:40 DEBUG : Waiting for deletions to finish 2024/09/30 01:53:40 ERROR : small: Got fatal error on delete: --max-delete threshold reached 2024/09/30 01:53:40 INFO : large: Deleted 2024/09/30 01:53:40 INFO : medium: Deleted --- PASS: TestMaxDelete (5.64s) === RUN TestMaxDeleteSizeLargeFile run.go:180: Remote "B2 bucket rclone-test-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" 2024/09/30 01:53:46 DEBUG : Waiting for deletions to finish 2024/09/30 01:53:46 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached 2024/09/30 01:53:46 INFO : medium: Deleted 2024/09/30 01:53:46 INFO : small: Deleted --- PASS: TestMaxDeleteSizeLargeFile (5.82s) === RUN TestMaxDeleteSize run.go:180: Remote "B2 bucket rclone-test-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" 2024/09/30 01:53:53 DEBUG : Waiting for deletions to finish 2024/09/30 01:53:53 ERROR : small: Got fatal error on delete: --max-delete-size threshold reached 2024/09/30 01:53:53 INFO : large: Deleted 2024/09/30 01:53:53 INFO : medium: Deleted --- PASS: TestMaxDeleteSize (7.06s) === RUN TestReadFile run.go:180: Remote "B2 bucket rclone-test-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" --- PASS: TestReadFile (2.91s) === RUN TestRetry 2024/09/30 01:53:57 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 1/5 2024/09/30 01:53:57 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 2/5 2024/09/30 01:53:57 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG 2024/09/30 01:53:57 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG 2024/09/30 01:53:57 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG 2024/09/30 01:53:57 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG 2024/09/30 01:53:57 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG --- PASS: TestRetry (0.05s) === RUN TestCat run.go:180: Remote "B2 bucket rclone-test-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" --- PASS: TestCat (5.33s) === RUN TestPurge 2024/09/30 01:54:03 DEBUG : Creating backend with remote "TestB2:rclone-test-xekacaf9xiqo" 2024/09/30 01:54:03 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/09/30 01:54:03 DEBUG : Creating backend with remote "/tmp/rclone1679599844" run.go:180: Remote "B2 bucket rclone-test-xekacaf9xiqo", Local "Local file system at /tmp/rclone1679599844", Modify Window "1ms" 2024/09/30 01:54:05 DEBUG : A2: Making directory 2024/09/30 01:54:05 DEBUG : A1/B2: Making directory 2024/09/30 01:54:05 DEBUG : A1/B2/C2: Making directory 2024/09/30 01:54:05 DEBUG : A1/B1/C3: Making directory 2024/09/30 01:54:05 DEBUG : A3: Making directory 2024/09/30 01:54:05 DEBUG : A3/B3: Making directory 2024/09/30 01:54:05 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/09/30 01:54:06 INFO : B2 bucket rclone-test-xekacaf9xiqo: cleaning bucket "rclone-test-xekacaf9xiqo" of all files 2024/09/30 01:54:06 DEBUG : A1/B1/C1/one: Deleting (id "4_zdf727df3a02d4b0b9f280e1b_f10936fcb1f32304d_d20240930_m015405_c001_v0001038_t0018_u01727661245378") 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/09/30 01:54:07 INFO : B2 bucket rclone-test-xekacaf9xiqo: cleaning bucket "rclone-test-xekacaf9xiqo" of all files 2024/09/30 01:54:07 DEBUG : A1/two: Deleting (id "4_zdf727df3a02d4b0b9f280e1b_f10936fcb1f32304f_d20240930_m015405_c001_v0001038_t0027_u01727661245847") 2024/09/30 01:54:08 DEBUG : B2 bucket rclone-test-xekacaf9xiqo: Purge remote 2024/09/30 01:54:08 INFO : B2 bucket rclone-test-xekacaf9xiqo: cleaning bucket "rclone-test-xekacaf9xiqo" of all files 2024/09/30 01:54:08 NOTICE: purge failed: directory not found --- PASS: TestPurge (5.04s) === RUN TestRmdirsNoLeaveRoot run.go:180: Remote "B2 bucket rclone-test-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" 2024/09/30 01:54:09 DEBUG : A2: Making directory 2024/09/30 01:54:09 DEBUG : A1/B2: Making directory 2024/09/30 01:54:09 DEBUG : A1/B2/C2: Making directory 2024/09/30 01:54:09 DEBUG : A1/B1/C3: Making directory 2024/09/30 01:54:09 DEBUG : A3: Making directory 2024/09/30 01:54:09 DEBUG : A3/B3: Making directory 2024/09/30 01:54:09 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/09/30 01:54:14 DEBUG : removing 1 level 3 directories 2024/09/30 01:54:14 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/09/30 01:54:16 DEBUG : removing 1 level 0 directories 2024/09/30 01:54:16 INFO : B2 bucket rclone-test-weyaboq3gage: Removing directory 2024/09/30 01:54:16 ERROR : : Failed to rmdir: failed to delete bucket: Cannot delete non-empty bucket (400 cannot_delete_non_empty_bucket) operations_test.go:728: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:728 Error: Received unexpected error: failed to remove directories: failed to delete bucket: Cannot delete non-empty bucket (400 cannot_delete_non_empty_bucket) Test: TestRmdirsNoLeaveRoot --- FAIL: TestRmdirsNoLeaveRoot (9.03s) === RUN TestRmdirsLeaveRoot run.go:180: Remote "B2 bucket rclone-test-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" 2024/09/30 01:54:17 DEBUG : A1: Making directory 2024/09/30 01:54:17 DEBUG : A1/B1: Making directory 2024/09/30 01:54:17 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-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" 2024/09/30 01:54:18 DEBUG : A1: Making directory 2024/09/30 01:54:18 DEBUG : A1/B1: Making directory 2024/09/30 01:54:18 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.93s) === RUN TestCopyURL run.go:180: Remote "B2 bucket rclone-test-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" 2024/09/30 01:54:20 DEBUG : filename.txt: File name found in url 2024/09/30 01:54:20 DEBUG : headerfilename.txt: filename found in Content-Disposition header. --- PASS: TestCopyURL (3.71s) === RUN TestCopyURLToWriter --- PASS: TestCopyURLToWriter (0.00s) === RUN TestMoveFile run.go:180: Remote "B2 bucket rclone-test-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" 2024/09/30 01:54:23 DEBUG : file1: Need to transfer - File not found at Destination 2024/09/30 01:54:23 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2024/09/30 01:54:23 INFO : file1: Copied (new) to: sub/file2 2024/09/30 01:54:23 INFO : file1: Deleted 2024/09/30 01:54:24 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/30 01:54:24 DEBUG : file1: Unchanged skipping 2024/09/30 01:54:24 INFO : file1: Deleted 2024/09/30 01:54:24 DEBUG : B2 bucket rclone-test-weyaboq3gage: don't need to copy/move sub/file2, it is already at target location --- PASS: TestMoveFile (2.06s) === RUN TestMoveFileWithIgnoreExisting run.go:180: Remote "B2 bucket rclone-test-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" 2024/09/30 01:54:25 DEBUG : file1: Need to transfer - File not found at Destination 2024/09/30 01:54:26 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2024/09/30 01:54:26 INFO : file1: Copied (new) 2024/09/30 01:54:26 INFO : file1: Deleted 2024/09/30 01:54:26 DEBUG : file1: Destination exists, skipping 2024/09/30 01:54:26 DEBUG : file1: Not removing source file as destination file exists and --ignore-existing is set --- PASS: TestMoveFileWithIgnoreExisting (2.32s) === RUN TestCaseInsensitiveMoveFile run.go:180: Remote "B2 bucket rclone-test-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFile (0.37s) === RUN TestCaseInsensitiveMoveFileDryRun run.go:180: Remote "B2 bucket rclone-test-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFileDryRun (0.37s) === RUN TestMoveFileBackupDir run.go:180: Remote "B2 bucket rclone-test-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" 2024/09/30 01:54:29 DEBUG : Creating backend with remote "TestB2:rclone-test-weyaboq3gage/backup" 2024/09/30 01:54:30 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18) 2024/09/30 01:54:32 DEBUG : dst/file1: sha1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK 2024/09/30 01:54:32 INFO : dst/file1: Copied (server-side copy) 2024/09/30 01:54:32 INFO : dst/file1: Deleted 2024/09/30 01:54:35 DEBUG : dst/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2024/09/30 01:54:35 INFO : dst/file1: Copied (new) 2024/09/30 01:54:35 INFO : dst/file1: Deleted --- PASS: TestMoveFileBackupDir (8.00s) === 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-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" 2024/09/30 01:54:42 DEBUG : A1/B2: Making directory 2024/09/30 01:54:42 DEBUG : A1/B1/C3: Making directory fstest.go:244: Filtering empty directory "A1/B2" fstest.go:244: Filtering empty directory "A1/B1/C3" 2024/09/30 01:54:44 DEBUG : A1/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2024/09/30 01:54:44 INFO : A1/B1/C1/four: Copied (server-side copy) to: A2/B1/C1/four 2024/09/30 01:54:44 INFO : A1/B1/C1/four: Deleted 2024/09/30 01:54:45 DEBUG : A1/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2024/09/30 01:54:45 INFO : A1/B1/C2/five: Copied (server-side copy) to: A2/B1/C2/five 2024/09/30 01:54:45 INFO : A1/B1/C2/five: Deleted 2024/09/30 01:54:45 DEBUG : A1/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2024/09/30 01:54:45 INFO : A1/two: Copied (server-side copy) to: A2/two 2024/09/30 01:54:45 DEBUG : A1/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2024/09/30 01:54:45 INFO : A1/one: Copied (server-side copy) to: A2/one 2024/09/30 01:54:45 INFO : A1/two: Deleted 2024/09/30 01:54:45 INFO : A1/one: Deleted 2024/09/30 01:54:45 DEBUG : A1/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2024/09/30 01:54:45 INFO : A1/B1/three: Copied (server-side copy) to: A2/B1/three 2024/09/30 01:54:45 INFO : A1/B1/three: Deleted fstest.go:244: Filtering empty directory "A2/B2" fstest.go:244: Filtering empty directory "A2/B1/C3" 2024/09/30 01:54:46 DEBUG : A2/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2024/09/30 01:54:46 INFO : A2/B1/C2/five: Copied (server-side copy) to: A3/B1/C2/five 2024/09/30 01:54:46 DEBUG : A2/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2024/09/30 01:54:46 INFO : A2/B1/three: Copied (server-side copy) to: A3/B1/three 2024/09/30 01:54:46 DEBUG : A2/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2024/09/30 01:54:46 INFO : A2/one: Copied (server-side copy) to: A3/one 2024/09/30 01:54:46 INFO : A2/B1/three: Deleted 2024/09/30 01:54:46 INFO : A2/B1/C2/five: Deleted 2024/09/30 01:54:47 INFO : A2/one: Deleted 2024/09/30 01:54:47 DEBUG : A2/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2024/09/30 01:54:47 INFO : A2/two: Copied (server-side copy) to: A3/two 2024/09/30 01:54:47 DEBUG : A2/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2024/09/30 01:54:47 INFO : A2/B1/C1/four: Copied (server-side copy) to: A3/B1/C1/four 2024/09/30 01:54:47 INFO : A2/two: Deleted 2024/09/30 01:54:47 INFO : A2/B1/C1/four: Deleted fstest.go:244: Filtering empty directory "A3/B2" fstest.go:244: Filtering empty directory "A3/B1/C3" 2024/09/30 01:54:47 INFO : B2 bucket rclone-test-weyaboq3gage: Can't DirMove - falling back to file moves: can't move directory - incompatible remotes 2024/09/30 01:54:48 DEBUG : A3/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2024/09/30 01:54:48 INFO : A3/B1/three: Copied (server-side copy) to: A4/B1/three 2024/09/30 01:54:48 DEBUG : A3/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2024/09/30 01:54:48 INFO : A3/one: Copied (server-side copy) to: A4/one 2024/09/30 01:54:48 INFO : A3/B1/three: Deleted 2024/09/30 01:54:48 DEBUG : A3/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2024/09/30 01:54:48 INFO : A3/B1/C2/five: Copied (server-side copy) to: A4/B1/C2/five 2024/09/30 01:54:48 DEBUG : A3/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2024/09/30 01:54:48 INFO : A3/two: Copied (server-side copy) to: A4/two 2024/09/30 01:54:48 INFO : A3/one: Deleted 2024/09/30 01:54:48 INFO : A3/B1/C2/five: Deleted 2024/09/30 01:54:48 INFO : A3/two: Deleted 2024/09/30 01:54:48 DEBUG : A3/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2024/09/30 01:54:48 INFO : A3/B1/C1/four: Copied (server-side copy) to: A4/B1/C1/four 2024/09/30 01:54:48 INFO : A3/B1/C1/four: Deleted fstest.go:244: Filtering empty directory "A4/B2" fstest.go:244: Filtering empty directory "A4/B1/C3" --- PASS: TestDirMove (14.24s) === RUN TestGetFsInfo run.go:180: Remote "B2 bucket rclone-test-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" --- PASS: TestGetFsInfo (0.37s) === RUN TestRcat === RUN TestRcat/withChecksum=false,ignoreChecksum=false run.go:180: Remote "B2 bucket rclone-test-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" 2024/09/30 01:54:50 DEBUG : B2 bucket rclone-test-weyaboq3gage: File to upload is small (34 bytes), uploading instead of streaming 2024/09/30 01:54:51 DEBUG : no_checksum_small_file_from_pipe: sha1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK 2024/09/30 01:54:51 DEBUG : no_checksum_small_file_from_pipe: Size and sha1 of src and dst objects identical 2024/09/30 01:54:51 DEBUG : no_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2024/09/30 01:54:54 DEBUG : no_checksum_big_file_from_pipe: sha1 = d0080f73aa8b494570ad63df1beb25c01ea9eea2 OK 2024/09/30 01:54:54 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-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" 2024/09/30 01:54:55 DEBUG : B2 bucket rclone-test-weyaboq3gage: File to upload is small (34 bytes), uploading instead of streaming 2024/09/30 01:54:55 DEBUG : with_checksum_small_file_from_pipe: sha1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK 2024/09/30 01:54:55 DEBUG : with_checksum_small_file_from_pipe: Size and sha1 of src and dst objects identical 2024/09/30 01:54:55 DEBUG : with_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2024/09/30 01:54:57 DEBUG : with_checksum_big_file_from_pipe: sha1 = d0080f73aa8b494570ad63df1beb25c01ea9eea2 OK 2024/09/30 01:54:57 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-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" 2024/09/30 01:54:58 DEBUG : B2 bucket rclone-test-weyaboq3gage: File to upload is small (34 bytes), uploading instead of streaming 2024/09/30 01:54:58 DEBUG : ignore_checksum_small_file_from_pipe: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/30 01:54:58 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2024/09/30 01:55:00 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-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" 2024/09/30 01:55:01 DEBUG : B2 bucket rclone-test-weyaboq3gage: File to upload is small (34 bytes), uploading instead of streaming 2024/09/30 01:55:01 DEBUG : ignore_checksum_small_file_from_pipe: Src hash empty - aborting Dst hash check 2024/09/30 01:55:01 DEBUG : ignore_checksum_small_file_from_pipe: Size of src and dst objects identical 2024/09/30 01:55:01 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2024/09/30 01:55:03 DEBUG : ignore_checksum_big_file_from_pipe: Src hash empty - aborting Dst hash check 2024/09/30 01:55:03 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical --- PASS: TestRcat (14.10s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (4.41s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (2.91s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (3.11s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (3.67s) === RUN TestRcatMetadata run.go:180: Remote "B2 bucket rclone-test-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" operations_test.go:1532: Skipping as destination doesn't support user metadata --- SKIP: TestRcatMetadata (0.38s) === RUN TestRcatSize run.go:180: Remote "B2 bucket rclone-test-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" 2024/09/30 01:55:05 DEBUG : B2 bucket rclone-test-weyaboq3gage: File to upload is small (60 bytes), uploading instead of streaming 2024/09/30 01:55:06 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2024/09/30 01:55:06 DEBUG : potato2: Size and sha1 of src and dst objects identical --- PASS: TestRcatSize (2.15s) === RUN TestRcatSizeMetadata run.go:180: Remote "B2 bucket rclone-test-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" operations_test.go:1613: Skipping as destination doesn't support user metadata --- SKIP: TestRcatSizeMetadata (0.37s) === RUN TestTouchDir run.go:180: Remote "B2 bucket rclone-test-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" 2024/09/30 01:55:10 DEBUG : B2 bucket rclone-test-weyaboq3gage: Touching "empty space" 2024/09/30 01:55:11 DEBUG : B2 bucket rclone-test-weyaboq3gage: Touching "potato2" 2024/09/30 01:55:12 DEBUG : B2 bucket rclone-test-weyaboq3gage: Touching "sub dir/potato3" --- PASS: TestTouchDir (6.27s) === RUN TestMkdirMetadata run.go:180: Remote "B2 bucket rclone-test-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" operations_test.go:1703: Skipping test as remote does not support MkdirMetadata --- SKIP: TestMkdirMetadata (0.45s) === RUN TestMkdirModTime run.go:180: Remote "B2 bucket rclone-test-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" operations_test.go:1722: Skipping test as remote does not support DirSetModTime or MkdirMetadata --- SKIP: TestMkdirModTime (0.38s) === RUN TestCopyDirMetadata run.go:180: Remote "B2 bucket rclone-test-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" operations_test.go:1743: Skipping test as remote does not support WriteDirMetadata or MkdirMetadata --- SKIP: TestCopyDirMetadata (0.37s) === RUN TestSetDirModTime run.go:180: Remote "B2 bucket rclone-test-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" operations_test.go:1779: Skipping test as remote does not support DirSetModTime or WriteDirSetModTime --- SKIP: TestSetDirModTime (0.37s) === RUN TestDirsEqual run.go:180: Remote "B2 bucket rclone-test-weyaboq3gage", Local "Local file system at /tmp/rclone1930589672", Modify Window "1ms" operations_test.go:1823: Skipping test as remote does not support WriteDirMetadata or MkdirMetadata --- SKIP: TestDirsEqual (0.38s) === RUN TestRcAbout rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcAbout (0.00s) === RUN TestRcCleanup rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcCleanup (0.00s) === RUN TestRcCopyfile rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcCopyfile (0.00s) === RUN TestRcCopyurl rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcCopyurl (0.00s) === RUN TestRcDelete rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcDelete (0.00s) === RUN TestRcDeletefile rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcDeletefile (0.00s) === RUN TestRcList rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcList (0.00s) === RUN TestRcStat rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcStat (0.00s) === RUN TestRcSetTier rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcSetTier (0.00s) === RUN TestRcSetTierFile rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcSetTierFile (0.00s) === RUN TestRcMkdir rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcMkdir (0.00s) === RUN TestRcMovefile rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcMovefile (0.00s) === RUN TestRcPurge rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcPurge (0.00s) === RUN TestRcRmdir rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcRmdir (0.00s) === RUN TestRcRmdirs rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcRmdirs (0.00s) === RUN TestRcSize rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcSize (0.00s) === RUN TestRcPublicLink rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcPublicLink (0.00s) === RUN TestRcFsInfo rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcFsInfo (0.00s) === RUN TestUploadFile rc_test.go:30: Skipping test on non local remote --- SKIP: TestUploadFile (0.00s) === RUN TestRcCommand rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcCommand (0.00s) === RUN TestRcDu rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcDu (0.00s) === RUN TestRcCheck rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcCheck (0.00s) === RUN TestRcHashsum rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcHashsum (0.00s) === RUN TestRcHashsumFile rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcHashsumFile (0.00s) FAIL 2024/09/30 01:55:15 DEBUG : B2 bucket rclone-test-weyaboq3gage: Purge remote 2024/09/30 01:55:15 INFO : B2 bucket rclone-test-weyaboq3gage: cleaning bucket "rclone-test-weyaboq3gage" of all files 2024/09/30 01:55:16 DEBUG : A1/B1/C1/four: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f4072ef152d39701e_d20240930_m015444_c001_v7007000_t0000_u01727661284715") 2024/09/30 01:55:16 DEBUG : A1/B1/C1/four: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd1a8_d20240930_m015439_c001_v0001106_t0043_u01727661279071") 2024/09/30 01:55:16 DEBUG : A1/B1/C1/one: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f400c5d35501e57f0_d20240930_m015416_c001_v7007000_t0000_u01727661256156") 2024/09/30 01:55:16 DEBUG : A1/B1/C1/one: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd136_d20240930_m015408_c001_v0001106_t0033_u01727661248786") 2024/09/30 01:55:16 DEBUG : A1/B1/C2/five: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f417daf1a6c1b6f20_d20240930_m015445_c001_v7007000_t0000_u01727661285136") 2024/09/30 01:55:16 DEBUG : A1/B1/C2/five: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd1b6_d20240930_m015441_c001_v0001106_t0018_u01727661281363") 2024/09/30 01:55:16 DEBUG : A1/B1/three: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f406522c03684dc5f_d20240930_m015445_c001_v7007000_t0000_u01727661285608") 2024/09/30 01:55:16 DEBUG : A1/B1/three: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd1a4_d20240930_m015438_c001_v0001106_t0055_u01727661278315") 2024/09/30 01:55:16 DEBUG : A1/one: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f41934e594ac6eabd_d20240930_m015445_c001_v7007000_t0000_u01727661285425") 2024/09/30 01:55:16 DEBUG : A1/one: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd19a_d20240930_m015436_c001_v0001106_t0037_u01727661276149") 2024/09/30 01:55:17 DEBUG : A1/two: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f403ceacdee68c189_d20240930_m015445_c001_v7007000_t0000_u01727661285425") 2024/09/30 01:55:17 DEBUG : A1/two: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd1a0_d20240930_m015437_c001_v0001106_t0013_u01727661277048") 2024/09/30 01:55:17 DEBUG : A1/two: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f400c5d35501e57f1_d20240930_m015416_c001_v7007000_t0000_u01727661256518") 2024/09/30 01:55:17 DEBUG : A1/two: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd138_d20240930_m015409_c001_v0001106_t0032_u01727661249858") 2024/09/30 01:55:17 DEBUG : A2/B1/C1/four: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f406522c03684dc61_d20240930_m015447_c001_v7007000_t0000_u01727661287162") 2024/09/30 01:55:17 DEBUG : A2/B1/C1/four: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f119067f8c8d22f7c_d20240930_m015444_c001_v0001090_t0017_u01727661284315") 2024/09/30 01:55:17 DEBUG : A2/B1/C2/five: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f41934e594ac6ead8_d20240930_m015446_c001_v7007000_t0000_u01727661286849") 2024/09/30 01:55:17 DEBUG : A2/B1/C2/five: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f108a61a92c570004_d20240930_m015444_c001_v0001103_t0024_u01727661284650") 2024/09/30 01:55:17 DEBUG : A2/B1/three: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f403ceacdee68c18b_d20240930_m015446_c001_v7007000_t0000_u01727661286849") 2024/09/30 01:55:17 DEBUG : A2/B1/three: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f118f5d9afdad7e78_d20240930_m015444_c001_v0001144_t0045_u01727661284733") 2024/09/30 01:55:17 DEBUG : A2/one: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f417daf1a6c1b730a_d20240930_m015446_c001_v7007000_t0000_u01727661286944") 2024/09/30 01:55:17 DEBUG : A2/one: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f116d56f890388ff5_d20240930_m015444_c001_v0001101_t0056_u01727661284707") 2024/09/30 01:55:17 DEBUG : A2/two: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f400c5d35501e6f99_d20240930_m015447_c001_v7007000_t0000_u01727661287125") 2024/09/30 01:55:17 DEBUG : A2/two: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f106ac1d984251802_d20240930_m015444_c001_v0001144_t0011_u01727661284763") 2024/09/30 01:55:17 DEBUG : A3/B1/C1/four: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f417daf1a6c1b730c_d20240930_m015448_c001_v7007000_t0000_u01727661288726") 2024/09/30 01:55:17 DEBUG : A3/B1/C1/four: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f105aed051dcdb3f4_d20240930_m015446_c001_v0001117_t0042_u01727661286432") 2024/09/30 01:55:17 DEBUG : A3/B1/C2/five: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f406522c03684dc62_d20240930_m015448_c001_v7007000_t0000_u01727661288445") 2024/09/30 01:55:18 DEBUG : A3/B1/C2/five: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11741d85971c28db_d20240930_m015446_c001_v0001029_t0037_u01727661286431") 2024/09/30 01:55:18 DEBUG : A3/B1/three: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f400c5d35501e6f9a_d20240930_m015448_c001_v7007000_t0000_u01727661288222") 2024/09/30 01:55:18 DEBUG : A3/B1/three: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11014c26e8bce1d2_d20240930_m015446_c001_v0001146_t0031_u01727661286458") 2024/09/30 01:55:18 DEBUG : A3/one: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f41934e594ac6eadb_d20240930_m015448_c001_v7007000_t0000_u01727661288261") 2024/09/30 01:55:18 DEBUG : A3/one: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f113baec7f34a84ef_d20240930_m015446_c001_v0001105_t0034_u01727661286439") 2024/09/30 01:55:18 DEBUG : A3/two: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f403ceacdee68c18c_d20240930_m015448_c001_v7007000_t0000_u01727661288455") 2024/09/30 01:55:18 DEBUG : A3/two: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11269a92dca9dab4_d20240930_m015446_c001_v0001144_t0059_u01727661286459") 2024/09/30 01:55:18 DEBUG : A4/B1/C1/four: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f417daf1a6c1b730d_d20240930_m015449_c001_v7007000_t0000_u01727661289304") 2024/09/30 01:55:18 DEBUG : A4/B1/C1/four: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f111c3ea3259a034a_d20240930_m015448_c001_v0001115_t0037_u01727661288012") 2024/09/30 01:55:18 DEBUG : A4/B1/C2/five: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f417daf1a6c1b730e_d20240930_m015449_c001_v7007000_t0000_u01727661289485") 2024/09/30 01:55:18 DEBUG : A4/B1/C2/five: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f1042867feed39929_d20240930_m015447_c001_v0001172_t0033_u01727661287990") 2024/09/30 01:55:18 DEBUG : A4/B1/three: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f417daf1a6c1b730f_d20240930_m015449_c001_v7007000_t0000_u01727661289673") 2024/09/30 01:55:18 DEBUG : A4/B1/three: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f111b072db305d328_d20240930_m015448_c001_v0001181_t0026_u01727661288000") 2024/09/30 01:55:18 DEBUG : A4/one: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f417daf1a6c1b7310_d20240930_m015449_c001_v7007000_t0000_u01727661289854") 2024/09/30 01:55:18 DEBUG : A4/one: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f118385f16b07e48e_d20240930_m015448_c001_v0001181_t0015_u01727661288031") 2024/09/30 01:55:18 DEBUG : A4/two: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f417daf1a6c1b7311_d20240930_m015450_c001_v7007000_t0000_u01727661290035") 2024/09/30 01:55:18 DEBUG : A4/two: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f1142ed6ce4cdeaa2_d20240930_m015448_c001_v0001029_t0003_u01727661288093") 2024/09/30 01:55:19 DEBUG : BackupDir/one: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f4087cf48f22ccabd_d20240930_m015206_c001_v7007000_t0000_u01727661126516") 2024/09/30 01:55:19 DEBUG : BackupDir/one: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f1122903b94976d6d_d20240930_m015153_c001_v0001116_t0041_u01727661113681") 2024/09/30 01:55:19 DEBUG : CompareDest/one: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f4087cf48f22ccaad_d20240930_m015142_c001_v7007000_t0000_u01727661102051") 2024/09/30 01:55:19 DEBUG : CompareDest/one: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f107aed79f39afe8c_d20240930_m015136_c001_v0001115_t0040_u01727661096076") 2024/09/30 01:55:19 DEBUG : CompareDest/two: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f4087cf48f22ccaaf_d20240930_m015142_c001_v7007000_t0000_u01727661102239") 2024/09/30 01:55:19 DEBUG : CompareDest/two: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f107aed79f39afe91_d20240930_m015137_c001_v0001115_t0003_u01727661097356") 2024/09/30 01:55:19 DEBUG : CopyDest/one: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f4087cf48f22ccabf_d20240930_m015206_c001_v7007000_t0000_u01727661126726") 2024/09/30 01:55:19 DEBUG : CopyDest/one: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f107aed79f39afed1_d20240930_m015150_c001_v0001115_t0038_u01727661110036") 2024/09/30 01:55:19 DEBUG : CopyDest/three: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f4087cf48f22ccac1_d20240930_m015206_c001_v7007000_t0000_u01727661126912") 2024/09/30 01:55:19 DEBUG : CopyDest/three: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fcf84_d20240930_m015204_c001_v0001106_t0008_u01727661124005") 2024/09/30 01:55:19 DEBUG : CopyDest/two: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f4087cf48f22ccac4_d20240930_m015207_c001_v7007000_t0000_u01727661127102") 2024/09/30 01:55:19 DEBUG : CopyDest/two: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fcf70_d20240930_m015158_c001_v0001106_t0042_u01727661118387") 2024/09/30 01:55:19 DEBUG : ReadFile: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f401bec702e32309e_d20240930_m015357_c001_v7007000_t0000_u01727661237263") 2024/09/30 01:55:19 DEBUG : ReadFile: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd114_d20240930_m015355_c001_v0001106_t0058_u01727661235100") 2024/09/30 01:55:19 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f41934e594ac6d173_d20240930_m015217_c001_v7007000_t0000_u01727661137125") 2024/09/30 01:55:19 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fcfa2_d20240930_m015209_c001_v0001106_t0031_u01727661129336") 2024/09/30 01:55:19 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f41934e594ac6d174_d20240930_m015217_c001_v7007000_t0000_u01727661137311") 2024/09/30 01:55:19 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fcfb0_d20240930_m015216_c001_v0001106_t0031_u01727661136021") 2024/09/30 01:55:20 DEBUG : a.txt: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f409226856fe37d65_d20240930_m015246_c001_v7007000_t0000_u01727661166374") 2024/09/30 01:55:20 DEBUG : a.txt: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fcfde_d20240930_m015230_c001_v0001106_t0027_u01727661150885") 2024/09/30 01:55:20 DEBUG : also/one: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f406940be6e007f1c_d20240930_m015227_c001_v7007000_t0000_u01727661147941") 2024/09/30 01:55:20 DEBUG : also/one: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fcfc2_d20240930_m015222_c001_v0001106_t0059_u01727661142479") 2024/09/30 01:55:20 DEBUG : another: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f406940be6e007f1f_d20240930_m015228_c001_v7007000_t0000_u01727661148486") 2024/09/30 01:55:20 DEBUG : another: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fcfc4_d20240930_m015222_c001_v0001106_t0046_u01727661142970") 2024/09/30 01:55:20 DEBUG : backup/dst/file1: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f400c5d35501e6f87_d20240930_m015435_c001_v7007000_t0000_u01727661275595") 2024/09/30 01:55:20 DEBUG : backup/dst/file1: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f1069df546898e6eb_d20240930_m015431_c001_v0001117_t0047_u01727661271968") 2024/09/30 01:55:20 DEBUG : backup/dst/file1: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f4087cf48f22ccaa3_d20240930_m015128_c001_v7007000_t0000_u01727661088529") 2024/09/30 01:55:20 DEBUG : backup/dst/file1: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f1137ff0c46d56c49_d20240930_m015127_c001_v0001150_t0050_u01727661087097") 2024/09/30 01:55:20 DEBUG : data/banana: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f404c1d82b6fe30a8_d20240930_m015030_c001_v7007000_t0000_u01727661030065") 2024/09/30 01:55:20 DEBUG : data/banana: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f107aed79f39afd14_d20240930_m015026_c001_v0001115_t0006_u01727661026649") 2024/09/30 01:55:20 DEBUG : data/banana: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f107aed79f39afcca_d20240930_m015014_c001_v0001115_t0024_u01727661014402") 2024/09/30 01:55:20 DEBUG : data/potato: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f404c1d82b6fe30a9_d20240930_m015030_c001_v7007000_t0000_u01727661030296") 2024/09/30 01:55:20 DEBUG : data/potato: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f107aed79f39afd16_d20240930_m015027_c001_v0001115_t0026_u01727661027490") 2024/09/30 01:55:20 DEBUG : data/potato: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f107aed79f39afcd6_d20240930_m015017_c001_v0001115_t0025_u01727661017159") 2024/09/30 01:55:20 DEBUG : dst/file1: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f400c5d35501e6f88_d20240930_m015435_c001_v7007000_t0000_u01727661275784") 2024/09/30 01:55:20 DEBUG : dst/file1: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd18c_d20240930_m015432_c001_v0001106_t0030_u01727661272987") 2024/09/30 01:55:20 DEBUG : dst/file1: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f400c5d35501e6f84_d20240930_m015432_c001_v7007000_t0000_u01727661272807") 2024/09/30 01:55:20 DEBUG : dst/file1: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd172_d20240930_m015428_c001_v0001106_t0040_u01727661268156") 2024/09/30 01:55:21 DEBUG : dst/file1: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f4087cf48f22ccaa4_d20240930_m015128_c001_v7007000_t0000_u01727661088751") 2024/09/30 01:55:21 DEBUG : dst/file1: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f107aed79f39afe62_d20240930_m015127_c001_v0001115_t0042_u01727661087577") 2024/09/30 01:55:21 DEBUG : dst/file1: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f4087cf48f22ccaa2_d20240930_m015127_c001_v7007000_t0000_u01727661087393") 2024/09/30 01:55:21 DEBUG : dst/file1: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f107aed79f39afe54_d20240930_m015123_c001_v0001115_t0020_u01727661083834") 2024/09/30 01:55:21 DEBUG : dst/one: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f4087cf48f22ccac6_d20240930_m015207_c001_v7007000_t0000_u01727661127289") 2024/09/30 01:55:21 DEBUG : dst/one: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f115ebb1dce922257_d20240930_m015155_c001_v0001091_t0038_u01727661115189") 2024/09/30 01:55:21 DEBUG : dst/one: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f402d7dd1c01ef4b2_d20240930_m015154_c001_v7007000_t0000_u01727661114979") 2024/09/30 01:55:21 DEBUG : dst/one: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f107aed79f39afecf_d20240930_m015149_c001_v0001115_t0043_u01727661109025") 2024/09/30 01:55:21 DEBUG : dst/one: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f109c29b6447ebef9_d20240930_m015148_c001_v0001130_t0002_u01727661108169") 2024/09/30 01:55:21 DEBUG : dst/one: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f109c29b6447ebef1_d20240930_m015146_c001_v0001130_t0034_u01727661106926") 2024/09/30 01:55:21 DEBUG : dst/one: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f4087cf48f22ccab0_d20240930_m015142_c001_v7007000_t0000_u01727661102452") 2024/09/30 01:55:21 DEBUG : dst/one: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f107aed79f39afe88_d20240930_m015135_c001_v0001115_t0037_u01727661095678") 2024/09/30 01:55:21 DEBUG : dst/one: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f109af5712df04d05_d20240930_m015134_c001_v0001103_t0027_u01727661094602") 2024/09/30 01:55:21 DEBUG : dst/one: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f109af5712df04d01_d20240930_m015133_c001_v0001103_t0027_u01727661093279") 2024/09/30 01:55:21 DEBUG : dst/three: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f4087cf48f22ccac7_d20240930_m015207_c001_v7007000_t0000_u01727661127487") 2024/09/30 01:55:21 DEBUG : dst/three: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f109c29b6447ebf27_d20240930_m015205_c001_v0001130_t0034_u01727661125705") 2024/09/30 01:55:22 DEBUG : dst/two: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f4087cf48f22ccac9_d20240930_m015207_c001_v7007000_t0000_u01727661127672") 2024/09/30 01:55:22 DEBUG : dst/two: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f1108f5a8d0f9893f_d20240930_m015202_c001_v0001106_t0058_u01727661122675") 2024/09/30 01:55:22 DEBUG : dst/two: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f4087cf48f22ccab1_d20240930_m015142_c001_v7007000_t0000_u01727661102646") 2024/09/30 01:55:22 DEBUG : dst/two: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f102fb49d299f0de2_d20240930_m015141_c001_v0001128_t0047_u01727661101018") 2024/09/30 01:55:22 DEBUG : empty space: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f417daf1a6c1b776d_d20240930_m015513_c001_v7007000_t0000_u01727661313299") 2024/09/30 01:55:22 DEBUG : empty space: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f100f6e4bd8c3b52c_d20240930_m015510_c001_v0001150_t0020_u01727661310733") 2024/09/30 01:55:22 DEBUG : empty space: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd232_d20240930_m015508_c001_v0001106_t0056_u01727661308510") 2024/09/30 01:55:22 DEBUG : empty space: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f402d7dd1c01f06a4_d20240930_m015329_c001_v7007000_t0000_u01727661209973") 2024/09/30 01:55:22 DEBUG : empty space: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd0b6_d20240930_m015327_c001_v0001106_t0037_u01727661207623") 2024/09/30 01:55:22 DEBUG : empty space: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f402d7dd1c01f0684_d20240930_m015326_c001_v7007000_t0000_u01727661206620") 2024/09/30 01:55:22 DEBUG : empty space: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd09c_d20240930_m015321_c001_v0001106_t0020_u01727661201746") 2024/09/30 01:55:22 DEBUG : empty space: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f405345e0f3b67194_d20240930_m015316_c001_v7007000_t0000_u01727661196836") 2024/09/30 01:55:22 DEBUG : empty space: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd084_d20240930_m015314_c001_v0001106_t0019_u01727661194724") 2024/09/30 01:55:22 DEBUG : empty space: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f405345e0f3b67174_d20240930_m015312_c001_v7007000_t0000_u01727661192411") 2024/09/30 01:55:22 DEBUG : empty space: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd06b_d20240930_m015308_c001_v0001106_t0045_u01727661188028") 2024/09/30 01:55:22 DEBUG : empty space: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f409226856fe37d88_d20240930_m015304_c001_v7007000_t0000_u01727661184722") 2024/09/30 01:55:22 DEBUG : empty space: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd057_d20240930_m015303_c001_v0001106_t0003_u01727661183443") 2024/09/30 01:55:22 DEBUG : empty space: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f404c1d82b6fe308d_d20240930_m015007_c001_v7007000_t0000_u01727661007329") 2024/09/30 01:55:22 DEBUG : empty space: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f107aed79f39afc8d_d20240930_m015003_c001_v0001115_t0017_u01727661003543") 2024/09/30 01:55:23 DEBUG : empty space: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f404c1d82b6fe307f_d20240930_m015001_c001_v7007000_t0000_u01727661001394") 2024/09/30 01:55:23 DEBUG : empty space: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f107aed79f39afc61_d20240930_m014956_c001_v0001115_t0050_u01727660996385") 2024/09/30 01:55:23 DEBUG : empty space: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f404c1d82b6fe306b_d20240930_m014953_c001_v7007000_t0000_u01727660993193") 2024/09/30 01:55:23 DEBUG : empty space: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f107aed79f39afc2d_d20240930_m014949_c001_v0001115_t0011_u01727660989483") 2024/09/30 01:55:23 DEBUG : file1: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f400c5d35501e6b99_d20240930_m015427_c001_v7007000_t0000_u01727661267046") 2024/09/30 01:55:23 DEBUG : file1: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd168_d20240930_m015425_c001_v0001106_t0059_u01727661265282") 2024/09/30 01:55:23 DEBUG : file1: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f400c5d35501e6b89_d20240930_m015422_c001_v7007000_t0000_u01727661262121") 2024/09/30 01:55:23 DEBUG : file1: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f41690d1dba1fd14e_d20240930_m015419_c001_v0001106_t0013_u01727661259890") 2024/09/30 01:55:23 DEBUG : file1: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f401bec702e3230a5_d20240930_m015402_c001_v7007000_t0000_u01727661242843") 2024/09/30 01:55:23 DEBUG : file1: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd11e_d20240930_m015358_c001_v0001106_t0001_u01727661238065") 2024/09/30 01:55:23 DEBUG : file1: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f409226856fe37d78_d20240930_m015259_c001_v7007000_t0000_u01727661179580") 2024/09/30 01:55:23 DEBUG : file1: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd027_d20240930_m015252_c001_v0001106_t0027_u01727661172098") 2024/09/30 01:55:23 DEBUG : file1: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f409226856fe37d6c_d20240930_m015251_c001_v7007000_t0000_u01727661171548") 2024/09/30 01:55:23 DEBUG : file1: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd01a_d20240930_m015247_c001_v0001106_t0039_u01727661167850") 2024/09/30 01:55:23 DEBUG : file2: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f400c5d35501e6b8b_d20240930_m015422_c001_v7007000_t0000_u01727661262303") 2024/09/30 01:55:23 DEBUG : file2: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f41690d1dba1fd15a_d20240930_m015421_c001_v0001106_t0013_u01727661261565") 2024/09/30 01:55:23 DEBUG : file2: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f401bec702e3230a7_d20240930_m015403_c001_v7007000_t0000_u01727661243025") 2024/09/30 01:55:24 DEBUG : file2: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd122_d20240930_m015359_c001_v0001106_t0036_u01727661239098") 2024/09/30 01:55:24 DEBUG : filename.txt: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f400c5d35501e6b8c_d20240930_m015422_c001_v7007000_t0000_u01727661262485") 2024/09/30 01:55:24 DEBUG : filename.txt: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd152_d20240930_m015420_c001_v0001106_t0046_u01727661260466") 2024/09/30 01:55:24 DEBUG : headerfilename.txt: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f400c5d35501e6b8e_d20240930_m015422_c001_v7007000_t0000_u01727661262666") 2024/09/30 01:55:24 DEBUG : headerfilename.txt: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd158_d20240930_m015420_c001_v0001106_t0039_u01727661260948") 2024/09/30 01:55:24 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f417daf1a6c1b7741_d20240930_m015504_c001_v7007000_t0000_u01727661304301") 2024/09/30 01:55:24 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd206_d20240930_m015501_c001_v0001106_t0026_u01727661301990") 2024/09/30 01:55:24 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f417daf1a6c1b7721_d20240930_m015500_c001_v7007000_t0000_u01727661300644") 2024/09/30 01:55:24 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd1fc_d20240930_m015458_c001_v0001106_t0014_u01727661298886") 2024/09/30 01:55:24 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f417daf1a6c1b7742_d20240930_m015504_c001_v7007000_t0000_u01727661304495") 2024/09/30 01:55:24 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd204_d20240930_m015501_c001_v0001106_t0035_u01727661301426") 2024/09/30 01:55:24 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f417daf1a6c1b7722_d20240930_m015500_c001_v7007000_t0000_u01727661300838") 2024/09/30 01:55:24 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd1f6_d20240930_m015458_c001_v0001106_t0048_u01727661298272") 2024/09/30 01:55:24 DEBUG : large: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f401bec702e323096_d20240930_m015353_c001_v7007000_t0000_u01727661233546") 2024/09/30 01:55:24 DEBUG : large: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd104_d20240930_m015350_c001_v0001106_t0003_u01727661230122") 2024/09/30 01:55:24 DEBUG : large: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f413341b1a1a82a74_d20240930_m015347_c001_v7007000_t0000_u01727661227287") 2024/09/30 01:55:24 DEBUG : large: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd0f2_d20240930_m015344_c001_v0001106_t0006_u01727661224269") 2024/09/30 01:55:24 DEBUG : large: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f402d7dd1c01f06b9_d20240930_m015340_c001_v7007000_t0000_u01727661220890") 2024/09/30 01:55:25 DEBUG : large: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd0dc_d20240930_m015338_c001_v0001106_t0040_u01727661218724") 2024/09/30 01:55:25 DEBUG : large: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f402d7dd1c01f06b2_d20240930_m015336_c001_v7007000_t0000_u01727661216204") 2024/09/30 01:55:25 DEBUG : large: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd0ce_d20240930_m015334_c001_v0001106_t0017_u01727661214494") 2024/09/30 01:55:25 DEBUG : medium: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f401bec702e323097_d20240930_m015353_c001_v7007000_t0000_u01727661233757") 2024/09/30 01:55:25 DEBUG : medium: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd100_d20240930_m015349_c001_v0001106_t0010_u01727661229566") 2024/09/30 01:55:25 DEBUG : medium: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f413341b1a1a82a72_d20240930_m015346_c001_v7007000_t0000_u01727661226534") 2024/09/30 01:55:25 DEBUG : medium: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd0ee_d20240930_m015343_c001_v0001106_t0057_u01727661223767") 2024/09/30 01:55:25 DEBUG : medium: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f413341b1a1a82a6f_d20240930_m015340_c001_v7007000_t0000_u01727661220905") 2024/09/30 01:55:25 DEBUG : medium: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd0da_d20240930_m015337_c001_v0001106_t0017_u01727661217700") 2024/09/30 01:55:25 DEBUG : medium: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f402d7dd1c01f06af_d20240930_m015335_c001_v7007000_t0000_u01727661215455") 2024/09/30 01:55:25 DEBUG : medium: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd0c8_d20240930_m015332_c001_v0001106_t0037_u01727661212955") 2024/09/30 01:55:25 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f417daf1a6c1b732d_d20240930_m015454_c001_v7007000_t0000_u01727661294635") 2024/09/30 01:55:25 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd1dc_d20240930_m015452_c001_v0001106_t0023_u01727661292003") 2024/09/30 01:55:25 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f417daf1a6c1b732e_d20240930_m015454_c001_v7007000_t0000_u01727661294817") 2024/09/30 01:55:25 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd1da_d20240930_m015451_c001_v0001106_t0057_u01727661291360") 2024/09/30 01:55:25 DEBUG : not-one: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f406940be6e007f21_d20240930_m015228_c001_v7007000_t0000_u01727661148665") 2024/09/30 01:55:25 DEBUG : not-one: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fcfce_d20240930_m015225_c001_v0001106_t0056_u01727661145331") 2024/09/30 01:55:25 DEBUG : one: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f406940be6e007f1b_d20240930_m015227_c001_v7007000_t0000_u01727661147761") 2024/09/30 01:55:25 DEBUG : one: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fcfba_d20240930_m015219_c001_v0001106_t0022_u01727661139518") 2024/09/30 01:55:26 DEBUG : potato1: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f417daf1a6c1b7746_d20240930_m015506_c001_v7007000_t0000_u01727661306823") 2024/09/30 01:55:26 DEBUG : potato1: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd20e_d20240930_m015505_c001_v0001106_t0054_u01727661305241") 2024/09/30 01:55:26 DEBUG : potato2: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f417daf1a6c1b776e_d20240930_m015513_c001_v7007000_t0000_u01727661313486") 2024/09/30 01:55:26 DEBUG : potato2: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f1049d1ee5108738b_d20240930_m015511_c001_v0001115_t0051_u01727661311460") 2024/09/30 01:55:26 DEBUG : potato2: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd224_d20240930_m015507_c001_v0001106_t0001_u01727661307762") 2024/09/30 01:55:26 DEBUG : potato2: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f417daf1a6c1b7747_d20240930_m015507_c001_v7007000_t0000_u01727661307029") 2024/09/30 01:55:26 DEBUG : potato2: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd218_d20240930_m015505_c001_v0001106_t0048_u01727661305982") 2024/09/30 01:55:26 DEBUG : potato2: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f402d7dd1c01f06a5_d20240930_m015330_c001_v7007000_t0000_u01727661210155") 2024/09/30 01:55:26 DEBUG : potato2: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd0b0_d20240930_m015327_c001_v0001106_t0040_u01727661207174") 2024/09/30 01:55:26 DEBUG : potato2: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f402d7dd1c01f0686_d20240930_m015326_c001_v7007000_t0000_u01727661206804") 2024/09/30 01:55:26 DEBUG : potato2: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd08a_d20240930_m015317_c001_v0001106_t0020_u01727661197438") 2024/09/30 01:55:26 DEBUG : potato2: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f405345e0f3b67197_d20240930_m015317_c001_v7007000_t0000_u01727661197053") 2024/09/30 01:55:26 DEBUG : potato2: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd07e_d20240930_m015312_c001_v0001106_t0009_u01727661192956") 2024/09/30 01:55:26 DEBUG : potato2: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f405345e0f3b67175_d20240930_m015312_c001_v7007000_t0000_u01727661192593") 2024/09/30 01:55:26 DEBUG : potato2: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd061_d20240930_m015305_c001_v0001106_t0036_u01727661185270") 2024/09/30 01:55:26 DEBUG : potato2: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f409226856fe37d89_d20240930_m015304_c001_v7007000_t0000_u01727661184903") 2024/09/30 01:55:26 DEBUG : potato2: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd055_d20240930_m015302_c001_v0001106_t0018_u01727661182642") 2024/09/30 01:55:27 DEBUG : potato2: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f404c1d82b6fe308e_d20240930_m015007_c001_v7007000_t0000_u01727661007510") 2024/09/30 01:55:27 DEBUG : potato2: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f107aed79f39afc93_d20240930_m015004_c001_v0001115_t0030_u01727661004530") 2024/09/30 01:55:27 DEBUG : potato2: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f404c1d82b6fe3081_d20240930_m015001_c001_v7007000_t0000_u01727661001585") 2024/09/30 01:55:27 DEBUG : potato2: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f107aed79f39afc6b_d20240930_m014957_c001_v0001115_t0044_u01727660997659") 2024/09/30 01:55:27 DEBUG : potato2: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f404c1d82b6fe306d_d20240930_m014953_c001_v7007000_t0000_u01727660993374") 2024/09/30 01:55:27 DEBUG : potato2: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f107aed79f39afc33_d20240930_m014950_c001_v0001115_t0038_u01727660990352") 2024/09/30 01:55:27 DEBUG : remotepotato: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f404c1d82b6fe308f_d20240930_m015007_c001_v7007000_t0000_u01727661007691") 2024/09/30 01:55:27 DEBUG : remotepotato: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f107aed79f39afc99_d20240930_m015005_c001_v0001115_t0022_u01727661005781") 2024/09/30 01:55:27 DEBUG : remotepotato: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f404c1d82b6fe3082_d20240930_m015001_c001_v7007000_t0000_u01727661001766") 2024/09/30 01:55:27 DEBUG : remotepotato: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f107aed79f39afc75_d20240930_m014959_c001_v0001115_t0001_u01727660999467") 2024/09/30 01:55:27 DEBUG : remotepotato: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f404c1d82b6fe306e_d20240930_m014953_c001_v7007000_t0000_u01727660993558") 2024/09/30 01:55:27 DEBUG : remotepotato: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f107aed79f39afc3b_d20240930_m014951_c001_v0001115_t0020_u01727660991624") 2024/09/30 01:55:27 DEBUG : rutabaga: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f404c1d82b6fe3090_d20240930_m015007_c001_v7007000_t0000_u01727661007873") 2024/09/30 01:55:27 DEBUG : rutabaga: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f107aed79f39afc83_d20240930_m015002_c001_v0001115_t0030_u01727661002315") 2024/09/30 01:55:27 DEBUG : rutabaga: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f404c1d82b6fe3083_d20240930_m015001_c001_v7007000_t0000_u01727661001948") 2024/09/30 01:55:27 DEBUG : rutabaga: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f107aed79f39afc4f_d20240930_m014954_c001_v0001115_t0016_u01727660994231") 2024/09/30 01:55:27 DEBUG : rutabaga: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f404c1d82b6fe306f_d20240930_m014953_c001_v7007000_t0000_u01727660993743") 2024/09/30 01:55:27 DEBUG : rutabaga: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f107aed79f39afc25_d20240930_m014948_c001_v0001115_t0039_u01727660988064") 2024/09/30 01:55:27 DEBUG : small: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f401bec702e32309a_d20240930_m015354_c001_v7007000_t0000_u01727661234358") 2024/09/30 01:55:28 DEBUG : small: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd0fa_d20240930_m015348_c001_v0001106_t0039_u01727661228036") 2024/09/30 01:55:28 DEBUG : small: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f413341b1a1a82a73_d20240930_m015346_c001_v7007000_t0000_u01727661226721") 2024/09/30 01:55:28 DEBUG : small: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd0ea_d20240930_m015342_c001_v0001106_t0056_u01727661222212") 2024/09/30 01:55:28 DEBUG : small: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f413341b1a1a82a70_d20240930_m015341_c001_v7007000_t0000_u01727661221463") 2024/09/30 01:55:28 DEBUG : small: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd0d4_d20240930_m015336_c001_v0001106_t0056_u01727661216571") 2024/09/30 01:55:28 DEBUG : small: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f402d7dd1c01f06b1_d20240930_m015335_c001_v7007000_t0000_u01727661215637") 2024/09/30 01:55:28 DEBUG : small: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd0c0_d20240930_m015330_c001_v0001106_t0018_u01727661210728") 2024/09/30 01:55:28 DEBUG : sub dir/hello world: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f409226856fe37d85_d20240930_m015302_c001_v7007000_t0000_u01727661182279") 2024/09/30 01:55:28 DEBUG : sub dir/hello world: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd04f_d20240930_m015301_c001_v0001106_t0057_u01727661181070") 2024/09/30 01:55:28 DEBUG : sub dir/hello world: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f409226856fe37d66_d20240930_m015246_c001_v7007000_t0000_u01727661166555") 2024/09/30 01:55:28 DEBUG : sub dir/hello world: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fcff4_d20240930_m015236_c001_v0001106_t0016_u01727661156018") 2024/09/30 01:55:28 DEBUG : sub dir/hello world2: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f409226856fe37d67_d20240930_m015246_c001_v7007000_t0000_u01727661166736") 2024/09/30 01:55:28 DEBUG : sub dir/hello world2: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fcff6_d20240930_m015237_c001_v0001106_t0044_u01727661157174") 2024/09/30 01:55:28 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f409226856fe37d68_d20240930_m015246_c001_v7007000_t0000_u01727661166928") 2024/09/30 01:55:28 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fcffe_d20240930_m015240_c001_v0001106_t0051_u01727661160546") 2024/09/30 01:55:28 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f409226856fe37d69_d20240930_m015247_c001_v7007000_t0000_u01727661167114") 2024/09/30 01:55:28 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd002_d20240930_m015241_c001_v0001106_t0052_u01727661161320") 2024/09/30 01:55:28 DEBUG : sub dir/potato3: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f417daf1a6c1b7770_d20240930_m015513_c001_v7007000_t0000_u01727661313673") 2024/09/30 01:55:29 DEBUG : sub dir/potato3: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f116812e51b283d64_d20240930_m015512_c001_v0001181_t0022_u01727661312669") 2024/09/30 01:55:29 DEBUG : sub dir/potato3: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd23a_d20240930_m015509_c001_v0001106_t0020_u01727661309213") 2024/09/30 01:55:29 DEBUG : sub dir/potato3: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f402d7dd1c01f06a6_d20240930_m015330_c001_v7007000_t0000_u01727661210351") 2024/09/30 01:55:29 DEBUG : sub dir/potato3: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd0b8_d20240930_m015328_c001_v0001106_t0053_u01727661208166") 2024/09/30 01:55:29 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f409226856fe37d6a_d20240930_m015247_c001_v7007000_t0000_u01727661167295") 2024/09/30 01:55:29 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd00a_d20240930_m015242_c001_v0001106_t0015_u01727661162600") 2024/09/30 01:55:29 DEBUG : sub/file2: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f400c5d35501e6b92_d20240930_m015424_c001_v7007000_t0000_u01727661264731") 2024/09/30 01:55:29 DEBUG : sub/file2: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd160_d20240930_m015423_c001_v0001106_t0028_u01727661263213") 2024/09/30 01:55:29 DEBUG : sub/file2: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f409226856fe37d79_d20240930_m015259_c001_v7007000_t0000_u01727661179771") 2024/09/30 01:55:29 DEBUG : sub/file2: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd02d_d20240930_m015253_c001_v0001106_t0057_u01727661173642") 2024/09/30 01:55:29 DEBUG : sub/file2: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f409226856fe37d6d_d20240930_m015251_c001_v7007000_t0000_u01727661171734") 2024/09/30 01:55:29 DEBUG : sub/file2: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd01e_d20240930_m015248_c001_v0001106_t0059_u01727661168809") 2024/09/30 01:55:29 DEBUG : sub/file2: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f4087cf48f22ccaa1_d20240930_m015123_c001_v7007000_t0000_u01727661083031") 2024/09/30 01:55:29 DEBUG : sub/file2: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f107aed79f39afe48_d20240930_m015120_c001_v0001115_t0005_u01727661080745") 2024/09/30 01:55:29 DEBUG : test-multithread-abort: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f404c1d82b6fe3060_d20240930_m014947_c001_v7007000_t0000_u01727660987138") 2024/09/30 01:55:29 DEBUG : test-multithread-abort: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f107aed79f39afaa9_d20240930_m014842_c001_v0001115_t0050_u01727660922019") 2024/09/30 01:55:29 DEBUG : test-multithread-copy-false-201326591-2: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f404c1d82b6fe25b1_d20240930_m014454_c001_v7007000_t0000_u01727660694020") 2024/09/30 01:55:29 DEBUG : test-multithread-copy-false-201326591-2: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f107aed79f39af4d5_d20240930_m014404_c001_v0001115_t0018_u01727660644728") 2024/09/30 01:55:30 DEBUG : test-multithread-copy-false-201326592-2: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f400c5d35501e022a_d20240930_m014534_c001_v7007000_t0000_u01727660734186") 2024/09/30 01:55:30 DEBUG : test-multithread-copy-false-201326592-2: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f107aed79f39af634_d20240930_m014457_c001_v0001115_t0025_u01727660697151") 2024/09/30 01:55:30 DEBUG : test-multithread-copy-false-201326593-2: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f411bfd38f7492139_d20240930_m014624_c001_v7007000_t0000_u01727660784142") 2024/09/30 01:55:30 DEBUG : test-multithread-copy-false-201326593-2: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f107aed79f39af719_d20240930_m014537_c001_v0001115_t0022_u01727660737080") 2024/09/30 01:55:30 DEBUG : test-multithread-copy-true-201326591-2: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f406522c03684cbaa_d20240930_m014718_c001_v7007000_t0000_u01727660838524") 2024/09/30 01:55:30 DEBUG : test-multithread-copy-true-201326591-2: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f2072ef152d394dc8_d20240930_m014642_c001_v0001036_t0034_u01727660802478") 2024/09/30 01:55:30 DEBUG : test-multithread-copy-true-201326592-2: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f416861bea1fe9f7c_d20240930_m014803_c001_v7007000_t0000_u01727660883536") 2024/09/30 01:55:30 DEBUG : test-multithread-copy-true-201326592-2: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f206940be6e007801_d20240930_m014736_c001_v0001097_t0052_u01727660856330") 2024/09/30 01:55:30 DEBUG : test-multithread-copy-true-201326593-2: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f4057abf64a683192_d20240930_m014840_c001_v7007000_t0000_u01727660920650") 2024/09/30 01:55:30 DEBUG : test-multithread-copy-true-201326593-2: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f203af202347b138e_d20240930_m014821_c001_v0001144_t0035_u01727660901498") 2024/09/30 01:55:30 DEBUG : test.sum: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f404c1d82b6fe30aa_d20240930_m015030_c001_v7007000_t0000_u01727661030479") 2024/09/30 01:55:30 DEBUG : test.sum: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f107aed79f39afd1c_d20240930_m015028_c001_v0001115_t0054_u01727661028370") 2024/09/30 01:55:30 DEBUG : test.sum: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f107aed79f39afd0c_d20240930_m015025_c001_v0001115_t0038_u01727661025137") 2024/09/30 01:55:30 DEBUG : test.sum: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f107aed79f39afcfc_d20240930_m015023_c001_v0001115_t0047_u01727661023578") 2024/09/30 01:55:30 DEBUG : test.sum: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f107aed79f39afcf6_d20240930_m015022_c001_v0001115_t0036_u01727661022000") 2024/09/30 01:55:30 DEBUG : test.sum: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f107aed79f39afcf0_d20240930_m015020_c001_v0001115_t0036_u01727661020157") 2024/09/30 01:55:31 DEBUG : test.sum: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f107aed79f39afcde_d20240930_m015018_c001_v0001115_t0032_u01727661018055") 2024/09/30 01:55:31 DEBUG : test.sum: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f107aed79f39afcce_d20240930_m015015_c001_v0001115_t0033_u01727661015055") 2024/09/30 01:55:31 DEBUG : test.sum: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f404c1d82b6fe3096_d20240930_m015011_c001_v7007000_t0000_u01727661011374") 2024/09/30 01:55:31 DEBUG : test.sum: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f107aed79f39afcb4_d20240930_m015010_c001_v0001115_t0009_u01727661010237") 2024/09/30 01:55:31 DEBUG : test.sum: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f107aed79f39afca9_d20240930_m015008_c001_v0001115_t0055_u01727661008237") 2024/09/30 01:55:31 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f417daf1a6c1b7331_d20240930_m015457_c001_v7007000_t0000_u01727661297545") 2024/09/30 01:55:31 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd1ec_d20240930_m015455_c001_v0001106_t0025_u01727661295836") 2024/09/30 01:55:31 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f417daf1a6c1b7332_d20240930_m015457_c001_v7007000_t0000_u01727661297728") 2024/09/30 01:55:31 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fd1ea_d20240930_m015455_c001_v0001106_t0040_u01727661295357") 2024/09/30 01:55:31 DEBUG : zend.txt: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f409226856fe37d6b_d20240930_m015247_c001_v7007000_t0000_u01727661167488") 2024/09/30 01:55:31 DEBUG : zend.txt: Deleting (id "4_zaf524da3909d4b0b9f280e1b_f11690d1dba1fcff2_d20240930_m015235_c001_v0001106_t0032_u01727661155221") "./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose" - Finished OK in 11m32.671889802s (try 1/5)