"./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose" - Starting (try 1/5) 2024/12/22 01:56:44 DEBUG : Creating backend with remote "TestB2:rclone-test-yehimix1yaja" 2024/12/22 01:56:44 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/12/22 01:56:45 DEBUG : Creating backend with remote "/tmp/rclone3233824478" === 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-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", Modify Window "1ms" 2024/12/22 01:56:46 DEBUG : chunksize-probe: Cancelling large file upload === RUN TestMultithreadCopy/upload=false,size=201326591,streams=2 2024/12/22 01:57:14 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2024/12/22 01:57:14 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: write buffer set to 131072 2024/12/22 01:57:14 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2024/12/22 01:57:14 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2024/12/22 01:57:14 DEBUG : test-multithread-copy-false-201326591-2: Starting multi-thread copy with 3 chunks of size 64Mi with 3 parallel streams 2024/12/22 01:57:14 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 3/3 (134217728-201326591) size 64.000Mi starting 2024/12/22 01:57:14 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 1/3 (0-67108864) size 64Mi starting 2024/12/22 01:57:14 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 2/3 (67108864-134217728) size 64Mi starting 2024/12/22 01:57:15 DEBUG : test-multithread-copy-false-201326591-2: writing chunk 2 2024/12/22 01:57:15 DEBUG : test-multithread-copy-false-201326591-2: writing chunk 1 2024/12/22 01:57:15 DEBUG : test-multithread-copy-false-201326591-2: writing chunk 0 2024/12/22 01:57:20 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 3/3 (134217728-201326591) size 64.000Mi finished 2024/12/22 01:57:20 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 2/3 (67108864-134217728) size 64Mi finished 2024/12/22 01:57:20 DEBUG : test-multithread-copy-false-201326591-2: multi-thread copy: chunk 1/3 (0-67108864) size 64Mi finished 2024/12/22 01:57:20 DEBUG : test-multithread-copy-false-201326591-2: Finished multi-thread copy with 3 parts of size 64Mi === RUN TestMultithreadCopy/upload=false,size=201326592,streams=2 2024/12/22 01:57:58 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2024/12/22 01:57:58 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: write buffer set to 131072 2024/12/22 01:57:58 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2024/12/22 01:57:58 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2024/12/22 01:57:58 DEBUG : test-multithread-copy-false-201326592-2: Starting multi-thread copy with 3 chunks of size 64Mi with 3 parallel streams 2024/12/22 01:57:58 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 3/3 (134217728-201326592) size 64Mi starting 2024/12/22 01:57:58 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 1/3 (0-67108864) size 64Mi starting 2024/12/22 01:57:58 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 2/3 (67108864-134217728) size 64Mi starting 2024/12/22 01:57:59 DEBUG : test-multithread-copy-false-201326592-2: writing chunk 2 2024/12/22 01:57:59 DEBUG : test-multithread-copy-false-201326592-2: writing chunk 0 2024/12/22 01:57:59 DEBUG : test-multithread-copy-false-201326592-2: writing chunk 1 2024/12/22 01:58:04 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 3/3 (134217728-201326592) size 64Mi finished 2024/12/22 01:58:04 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 1/3 (0-67108864) size 64Mi finished 2024/12/22 01:58:04 DEBUG : test-multithread-copy-false-201326592-2: multi-thread copy: chunk 2/3 (67108864-134217728) size 64Mi finished 2024/12/22 01:58:04 DEBUG : test-multithread-copy-false-201326592-2: Finished multi-thread copy with 3 parts of size 64Mi === RUN TestMultithreadCopy/upload=false,size=201326593,streams=2 2024/12/22 01:58:39 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2024/12/22 01:58:39 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: write buffer set to 131072 2024/12/22 01:58:39 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2024/12/22 01:58:39 DEBUG : test-multithread-copy-false-201326593-2: Starting multi-thread copy with 4 chunks of size 64Mi with 4 parallel streams 2024/12/22 01:58:39 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 4/4 (201326592-201326593) size 1 starting 2024/12/22 01:58:39 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 2/4 (67108864-134217728) size 64Mi starting 2024/12/22 01:58:39 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 3/4 (134217728-201326592) size 64Mi starting 2024/12/22 01:58:39 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 1/4 (0-67108864) size 64Mi starting 2024/12/22 01:58:40 DEBUG : test-multithread-copy-false-201326593-2: writing chunk 3 2024/12/22 01:58:40 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 4/4 (201326592-201326593) size 1 finished 2024/12/22 01:58:40 DEBUG : test-multithread-copy-false-201326593-2: writing chunk 1 2024/12/22 01:58:40 DEBUG : test-multithread-copy-false-201326593-2: writing chunk 0 2024/12/22 01:58:40 DEBUG : test-multithread-copy-false-201326593-2: writing chunk 2 2024/12/22 01:58:45 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 2/4 (67108864-134217728) size 64Mi finished 2024/12/22 01:58:45 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 1/4 (0-67108864) size 64Mi finished 2024/12/22 01:58:45 DEBUG : test-multithread-copy-false-201326593-2: multi-thread copy: chunk 3/4 (134217728-201326592) size 64Mi finished 2024/12/22 01:58:45 DEBUG : test-multithread-copy-false-201326593-2: Finished multi-thread copy with 4 parts of size 64Mi === RUN TestMultithreadCopy/upload=true,size=201326591,streams=2 2024/12/22 01:59:07 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: disabling buffering because source is local disk 2024/12/22 01:59:08 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2024/12/22 01:59:08 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: number of streams 4 was bigger than number of chunks 2 2024/12/22 01:59:08 DEBUG : test-multithread-copy-true-201326591-2: Starting multi-thread copy with 2 chunks of size 96Mi with 2 parallel streams 2024/12/22 01:59:08 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: chunk 2/2 (100663296-201326591) size 96.000Mi starting 2024/12/22 01:59:08 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: chunk 1/2 (0-100663296) size 96Mi starting 2024/12/22 01:59:08 DEBUG : test-multithread-copy-true-201326591-2: Sending chunk 1 length 100663295 2024/12/22 01:59:08 DEBUG : test-multithread-copy-true-201326591-2: Sending chunk 0 length 100663296 2024/12/22 01:59:16 DEBUG : test-multithread-copy-true-201326591-2: Done sending chunk 1 2024/12/22 01:59:16 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: chunk 2/2 (100663296-201326591) size 96.000Mi finished 2024/12/22 01:59:16 DEBUG : test-multithread-copy-true-201326591-2: Done sending chunk 0 2024/12/22 01:59:16 DEBUG : test-multithread-copy-true-201326591-2: multi-thread copy: chunk 1/2 (0-100663296) size 96Mi finished 2024/12/22 01:59:16 DEBUG : test-multithread-copy-true-201326591-2: Finishing large file upload with 2 parts 2024/12/22 01:59:17 DEBUG : test-multithread-copy-true-201326591-2: Finished multi-thread copy with 2 parts of size 96Mi === RUN TestMultithreadCopy/upload=true,size=201326592,streams=2 2024/12/22 01:59:39 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: disabling buffering because source is local disk 2024/12/22 01:59:40 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2024/12/22 01:59:40 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: number of streams 4 was bigger than number of chunks 2 2024/12/22 01:59:40 DEBUG : test-multithread-copy-true-201326592-2: Starting multi-thread copy with 2 chunks of size 96Mi with 2 parallel streams 2024/12/22 01:59:40 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: chunk 1/2 (0-100663296) size 96Mi starting 2024/12/22 01:59:40 DEBUG : test-multithread-copy-true-201326592-2: Sending chunk 0 length 100663296 2024/12/22 01:59:40 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: chunk 2/2 (100663296-201326592) size 96Mi starting 2024/12/22 01:59:40 DEBUG : test-multithread-copy-true-201326592-2: Sending chunk 1 length 100663296 2024/12/22 01:59:48 DEBUG : test-multithread-copy-true-201326592-2: Done sending chunk 1 2024/12/22 01:59:48 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: chunk 2/2 (100663296-201326592) size 96Mi finished 2024/12/22 01:59:48 DEBUG : test-multithread-copy-true-201326592-2: Done sending chunk 0 2024/12/22 01:59:48 DEBUG : test-multithread-copy-true-201326592-2: multi-thread copy: chunk 1/2 (0-100663296) size 96Mi finished 2024/12/22 01:59:48 DEBUG : test-multithread-copy-true-201326592-2: Finishing large file upload with 2 parts 2024/12/22 01:59:49 DEBUG : test-multithread-copy-true-201326592-2: Finished multi-thread copy with 2 parts of size 96Mi === RUN TestMultithreadCopy/upload=true,size=201326593,streams=2 2024/12/22 02:01:59 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: disabling buffering because source is local disk 2024/12/22 02:02:01 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2024/12/22 02:02:01 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2024/12/22 02:02:01 DEBUG : test-multithread-copy-true-201326593-2: Starting multi-thread copy with 3 chunks of size 96Mi with 3 parallel streams 2024/12/22 02:02:01 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 2/3 (100663296-201326592) size 96Mi starting 2024/12/22 02:02:01 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 1/3 (0-100663296) size 96Mi starting 2024/12/22 02:02:01 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 3/3 (201326592-201326593) size 1 starting 2024/12/22 02:02:02 DEBUG : test-multithread-copy-true-201326593-2: Sending chunk 2 length 1 2024/12/22 02:02:02 DEBUG : test-multithread-copy-true-201326593-2: Sending chunk 1 length 100663296 2024/12/22 02:02:02 DEBUG : test-multithread-copy-true-201326593-2: Sending chunk 0 length 100663296 2024/12/22 02:02:04 DEBUG : test-multithread-copy-true-201326593-2: Done sending chunk 2 2024/12/22 02:02:04 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 3/3 (201326592-201326593) size 1 finished 2024/12/22 02:03:54 DEBUG : test-multithread-copy-true-201326593-2: Done sending chunk 0 2024/12/22 02:03:54 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 1/3 (0-100663296) size 96Mi finished 2024/12/22 02:03:56 DEBUG : test-multithread-copy-true-201326593-2: Done sending chunk 1 2024/12/22 02:03:56 DEBUG : test-multithread-copy-true-201326593-2: multi-thread copy: chunk 2/3 (100663296-201326592) size 96Mi finished 2024/12/22 02:03:56 DEBUG : test-multithread-copy-true-201326593-2: Finishing large file upload with 3 parts 2024/12/22 02:03:57 DEBUG : test-multithread-copy-true-201326593-2: Finished multi-thread copy with 3 parts of size 96Mi --- PASS: TestMultithreadCopy (440.24s) --- PASS: TestMultithreadCopy/upload=false,size=201326591,streams=2 (41.94s) --- PASS: TestMultithreadCopy/upload=false,size=201326592,streams=2 (43.80s) --- PASS: TestMultithreadCopy/upload=false,size=201326593,streams=2 (40.96s) --- PASS: TestMultithreadCopy/upload=true,size=201326591,streams=2 (31.38s) --- PASS: TestMultithreadCopy/upload=true,size=201326592,streams=2 (109.16s) --- PASS: TestMultithreadCopy/upload=true,size=201326593,streams=2 (171.13s) === RUN TestMultithreadCopyAbort run.go:180: Remote "B2 bucket rclone-test-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", Modify Window "1ms" 2024/12/22 02:04:05 DEBUG : chunksize-probe: Cancelling large file upload 2024/12/22 02:04:20 DEBUG : test-multithread-abort: multi-thread copy: disabling buffering because source is local disk 2024/12/22 02:04:21 DEBUG : test-multithread-abort: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 1 2024/12/22 02:04:21 DEBUG : test-multithread-abort: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2024/12/22 02:04:21 DEBUG : test-multithread-abort: Starting multi-thread copy with 3 chunks of size 96Mi with 3 parallel streams 2024/12/22 02:04:21 DEBUG : test-multithread-abort: multi-thread copy: chunk 1/3 (0-100663296) size 96Mi starting 2024/12/22 02:04:21 DEBUG : test-multithread-abort: multi-thread copy: chunk 3/3 (201326592-201326593) size 1 starting 2024/12/22 02:04:21 DEBUG : test-multithread-abort: multi-thread copy: chunk 2/3 (100663296-201326592) size 96Mi starting 2024/12/22 02:04:21 DEBUG : Open with options = [RangeOption(100663296,201326591)] 2024/12/22 02:04:21 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2024/12/22 02:04:21 DEBUG : Open with options = [RangeOption(0,100663295)] 2024/12/22 02:04:21 DEBUG : test-multithread-abort: Sending chunk 1 length 100663296 2024/12/22 02:04:21 DEBUG : test-multithread-abort: Sending chunk 0 length 100663296 2024/12/22 02:04:30 DEBUG : test-multithread-abort: Done sending chunk 1 2024/12/22 02:04:30 DEBUG : test-multithread-abort: multi-thread copy: chunk 2/3 (100663296-201326592) size 96Mi finished 2024/12/22 02:04:32 DEBUG : test-multithread-abort: Done sending chunk 0 2024/12/22 02:04:32 DEBUG : test-multithread-abort: multi-thread copy: chunk 1/3 (0-100663296) size 96Mi finished 2024/12/22 02:04:32 DEBUG : Returning error reader 2024/12/22 02:04:32 DEBUG : test-multithread-abort: Sending chunk 2 length 1 2024/12/22 02:04:32 DEBUG : BOOM: simulated read failure 2024/12/22 02:04:32 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 0/10: BOOM: simulated read failure 2024/12/22 02:04:32 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2024/12/22 02:04:33 DEBUG : Returning error reader 2024/12/22 02:04:33 DEBUG : BOOM: simulated read failure 2024/12/22 02:04:33 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 1/10: BOOM: simulated read failure 2024/12/22 02:04:33 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2024/12/22 02:04:34 DEBUG : Returning error reader 2024/12/22 02:04:34 DEBUG : BOOM: simulated read failure 2024/12/22 02:04:34 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 2/10: BOOM: simulated read failure 2024/12/22 02:04:34 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2024/12/22 02:04:35 DEBUG : Returning error reader 2024/12/22 02:04:35 DEBUG : BOOM: simulated read failure 2024/12/22 02:04:35 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 3/10: BOOM: simulated read failure 2024/12/22 02:04:35 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2024/12/22 02:04:36 DEBUG : Returning error reader 2024/12/22 02:04:36 DEBUG : BOOM: simulated read failure 2024/12/22 02:04:36 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 4/10: BOOM: simulated read failure 2024/12/22 02:04:36 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2024/12/22 02:04:37 DEBUG : Returning error reader 2024/12/22 02:04:37 DEBUG : BOOM: simulated read failure 2024/12/22 02:04:37 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 5/10: BOOM: simulated read failure 2024/12/22 02:04:37 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2024/12/22 02:04:38 DEBUG : Returning error reader 2024/12/22 02:04:38 DEBUG : BOOM: simulated read failure 2024/12/22 02:04:38 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 6/10: BOOM: simulated read failure 2024/12/22 02:04:38 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2024/12/22 02:04:39 DEBUG : Returning error reader 2024/12/22 02:04:39 DEBUG : BOOM: simulated read failure 2024/12/22 02:04:39 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 7/10: BOOM: simulated read failure 2024/12/22 02:04:39 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2024/12/22 02:04:40 DEBUG : Returning error reader 2024/12/22 02:04:40 DEBUG : BOOM: simulated read failure 2024/12/22 02:04:40 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 8/10: BOOM: simulated read failure 2024/12/22 02:04:40 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2024/12/22 02:04:41 DEBUG : Returning error reader 2024/12/22 02:04:41 DEBUG : BOOM: simulated read failure 2024/12/22 02:04:41 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 9/10: BOOM: simulated read failure 2024/12/22 02:04:41 DEBUG : Open with options = [RangeOption(201326592,201326592)] 2024/12/22 02:04:42 DEBUG : Returning error reader 2024/12/22 02:04:42 DEBUG : BOOM: simulated read failure 2024/12/22 02:04:42 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 10/10: BOOM: simulated read failure 2024/12/22 02:04:42 DEBUG : test-multithread-abort: Reopen failed after offset 0 bytes read: failed to reopen: too many retries 2024/12/22 02:04:42 DEBUG : test-multithread-abort: Error sending chunk 2 (retry=false): Post "https://pod-000-1102-14.backblaze.com/b2api/v1/b2_upload_part/4_zefd2cda3104debcb9f380e1b_f2136256a137fe354_d20241222_m020421_c001_v0001102_t0047_u01734833061571/0056": BOOM: simulated read failure: &url.Error{Op:"Post", URL:"https://pod-000-1102-14.backblaze.com/b2api/v1/b2_upload_part/4_zefd2cda3104debcb9f380e1b_f2136256a137fe354_d20241222_m020421_c001_v0001102_t0047_u01734833061571/0056", Err:(*errors.errorString)(0xc00047b550)} 2024/12/22 02:04:42 DEBUG : test-multithread-abort: Error sending chunk 2: Post "https://pod-000-1102-14.backblaze.com/b2api/v1/b2_upload_part/4_zefd2cda3104debcb9f380e1b_f2136256a137fe354_d20241222_m020421_c001_v0001102_t0047_u01734833061571/0056": BOOM: simulated read failure 2024/12/22 02:04:42 DEBUG : test-multithread-abort: multi-thread copy: chunk 3/3 failed: multi-thread copy: failed to write chunk: Post "https://pod-000-1102-14.backblaze.com/b2api/v1/b2_upload_part/4_zefd2cda3104debcb9f380e1b_f2136256a137fe354_d20241222_m020421_c001_v0001102_t0047_u01734833061571/0056": BOOM: simulated read failure 2024/12/22 02:04:42 DEBUG : test-multithread-abort: multi-thread copy: cancelling transfer on exit 2024/12/22 02:04:42 DEBUG : test-multithread-abort: Cancelling large file upload --- PASS: TestMultithreadCopyAbort (37.98s) === RUN TestSizeDiffers --- PASS: TestSizeDiffers (0.00s) === RUN TestReOpen === RUN TestReOpen/Normal === RUN TestReOpen/Normal/Basics 2024/12/22 02:04:43 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/ErrorAtStart === RUN TestReOpen/Normal/WithErrors 2024/12/22 02:04:43 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2024/12/22 02:04:43 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2024/12/22 02:04:43 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/Normal/TooManyErrors 2024/12/22 02:04:43 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2024/12/22 02:04:43 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2024/12/22 02:04:43 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2024/12/22 02:04:43 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/Normal/Seek 2024/12/22 02:04:43 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2024/12/22 02:04:43 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2024/12/22 02:04:43 DEBUG : potato: Seek from 5 to 2 === RUN TestReOpen/Normal/AccountRead === RUN TestReOpen/Normal/AccountReadDelay 2024/12/22 02:04:43 DEBUG : potato: Seek from 10 to 0 2024/12/22 02:04:43 DEBUG : potato: Seek from 10 to 0 2024/12/22 02:04:43 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/AccountReadError === RUN TestReOpen/WithRangeOption === RUN TestReOpen/WithRangeOption/Basics 2024/12/22 02:04:43 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/ErrorAtStart === RUN TestReOpen/WithRangeOption/WithErrors 2024/12/22 02:04:43 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2024/12/22 02:04:43 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2024/12/22 02:04:43 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithRangeOption/TooManyErrors 2024/12/22 02:04:43 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2024/12/22 02:04:43 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2024/12/22 02:04:43 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2024/12/22 02:04:43 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithRangeOption/Seek 2024/12/22 02:04:43 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2024/12/22 02:04:43 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2024/12/22 02:04:43 DEBUG : potato: Seek from 5 to 2 2024/12/22 02:04:43 DEBUG : potato: Seek from 7 to 4 === RUN TestReOpen/WithRangeOption/AccountRead === RUN TestReOpen/WithRangeOption/AccountReadDelay 2024/12/22 02:04:43 DEBUG : potato: Seek from 7 to 0 2024/12/22 02:04:43 DEBUG : potato: Seek from 7 to 0 2024/12/22 02:04:43 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/AccountReadError === RUN TestReOpen/WithSeekOption === RUN TestReOpen/WithSeekOption/Basics 2024/12/22 02:04:43 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/ErrorAtStart === RUN TestReOpen/WithSeekOption/WithErrors 2024/12/22 02:04:43 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2024/12/22 02:04:43 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2024/12/22 02:04:43 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithSeekOption/TooManyErrors 2024/12/22 02:04:43 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2024/12/22 02:04:43 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2024/12/22 02:04:43 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2024/12/22 02:04:43 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithSeekOption/Seek 2024/12/22 02:04:43 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2024/12/22 02:04:43 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2024/12/22 02:04:43 DEBUG : potato: Seek from 5 to 2 2024/12/22 02:04:43 DEBUG : potato: Seek from 7 to 5 === RUN TestReOpen/WithSeekOption/AccountRead === RUN TestReOpen/WithSeekOption/AccountReadDelay 2024/12/22 02:04:43 DEBUG : potato: Seek from 8 to 0 2024/12/22 02:04:43 DEBUG : potato: Seek from 8 to 0 2024/12/22 02:04:43 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/AccountReadError === RUN TestReOpen/UnknownSize === RUN TestReOpen/UnknownSize/Basics 2024/12/22 02:04:43 DEBUG : potato: Seek from 9 to 0 === RUN TestReOpen/UnknownSize/ErrorAtStart === RUN TestReOpen/UnknownSize/WithErrors 2024/12/22 02:04:43 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2024/12/22 02:04:43 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2024/12/22 02:04:43 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/UnknownSize/TooManyErrors 2024/12/22 02:04:43 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2024/12/22 02:04:43 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2024/12/22 02:04:43 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2024/12/22 02:04:43 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/UnknownSize/Seek 2024/12/22 02:04:43 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2024/12/22 02:04:43 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2024/12/22 02:04:43 DEBUG : potato: Seek from 5 to 2 2024/12/22 02:04:43 DEBUG : potato: Seek from 7 to 6 === RUN TestReOpen/UnknownSize/AccountRead === RUN TestReOpen/UnknownSize/AccountReadDelay 2024/12/22 02:04:43 DEBUG : potato: Seek from 9 to 0 2024/12/22 02:04:43 DEBUG : potato: Seek from 9 to 0 2024/12/22 02:04:43 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-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", Modify Window "1ms" === RUN TestCheck/1 === RUN TestCheck/2 === RUN TestCheck/3 === RUN TestCheck/4 === RUN TestCheck/5 === RUN TestCheck/6 === RUN TestCheck/7 --- PASS: TestCheck (5.14s) --- PASS: TestCheck/1 (0.18s) --- PASS: TestCheck/2 (0.21s) --- PASS: TestCheck/3 (0.19s) --- PASS: TestCheck/4 (0.18s) --- PASS: TestCheck/5 (0.24s) --- PASS: TestCheck/6 (0.19s) --- PASS: TestCheck/7 (0.18s) === RUN TestCheckFsError 2024/12/22 02:04:48 DEBUG : Creating backend with remote "nonexistent" 2024/12/22 02:04:48 DEBUG : Creating backend with remote "nonexistent" 2024/12/22 02:04:48 DEBUG : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: Waiting for checks to finish 2024/12/22 02:04:48 ERROR : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: error reading source root directory: directory not found 2024/12/22 02:04:48 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: 2 differences found 2024/12/22 02:04:48 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: 2 errors while checking --- PASS: TestCheckFsError (0.01s) === RUN TestCheckDownload run.go:180: Remote "B2 bucket rclone-test-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", Modify Window "1ms" === RUN TestCheckDownload/1 === RUN TestCheckDownload/2 === RUN TestCheckDownload/3 === RUN TestCheckDownload/4 === RUN TestCheckDownload/5 === RUN TestCheckDownload/6 === RUN TestCheckDownload/7 --- PASS: TestCheckDownload (6.45s) --- PASS: TestCheckDownload/1 (0.77s) --- PASS: TestCheckDownload/2 (0.37s) --- PASS: TestCheckDownload/3 (0.36s) --- PASS: TestCheckDownload/4 (0.56s) --- PASS: TestCheckDownload/5 (0.40s) --- PASS: TestCheckDownload/6 (0.38s) --- PASS: TestCheckDownload/7 (0.37s) === RUN TestCheckSizeOnly run.go:180: Remote "B2 bucket rclone-test-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", Modify Window "1ms" === RUN TestCheckSizeOnly/1 === RUN TestCheckSizeOnly/2 === RUN TestCheckSizeOnly/3 === RUN TestCheckSizeOnly/4 === RUN TestCheckSizeOnly/5 === RUN TestCheckSizeOnly/6 === RUN TestCheckSizeOnly/7 --- PASS: TestCheckSizeOnly (4.57s) --- 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-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", Modify Window "1ms" 2024/12/22 02:05:00 NOTICE: test.sum: improperly formatted checksum line 4 2024/12/22 02:05:00 NOTICE: test.sum: improperly formatted checksum line 5 2024/12/22 02:05:00 NOTICE: test.sum: improperly formatted checksum line 6 2024/12/22 02:05:00 NOTICE: test.sum: 2 warning(s) suppressed... 2024/12/22 02:05:01 NOTICE: test.sum: improperly formatted checksum line 4 2024/12/22 02:05:01 NOTICE: test.sum: improperly formatted checksum line 5 2024/12/22 02:05:01 NOTICE: test.sum: improperly formatted checksum line 6 2024/12/22 02:05:01 NOTICE: test.sum: 2 warning(s) suppressed... --- PASS: TestParseSumFile (2.49s) === RUN TestCheckSum run.go:180: Remote "B2 bucket rclone-test-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", Modify Window "1ms" 2024/12/22 02:05:02 DEBUG : Creating backend with remote "TestB2:rclone-test-yehimix1yaja/data" check_test.go:354: B2 bucket rclone-test-yehimix1yaja path data lacks md5, skipping --- SKIP: TestCheckSum (1.51s) === RUN TestCheckSumDownload run.go:180: Remote "B2 bucket rclone-test-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", Modify Window "1ms" 2024/12/22 02:05:03 DEBUG : Creating backend with remote "TestB2:rclone-test-yehimix1yaja/data" === RUN TestCheckSumDownload/subtest1 === RUN TestCheckSumDownload/subtest2 === RUN TestCheckSumDownload/subtest3 === RUN TestCheckSumDownload/subtest4 === RUN TestCheckSumDownload/subtest5 === RUN TestCheckSumDownload/subtest6 === RUN TestCheckSumDownload/subtest7 --- PASS: TestCheckSumDownload (13.99s) --- PASS: TestCheckSumDownload/subtest1 (1.32s) --- PASS: TestCheckSumDownload/subtest2 (0.74s) --- PASS: TestCheckSumDownload/subtest3 (0.95s) --- PASS: TestCheckSumDownload/subtest4 (0.76s) --- PASS: TestCheckSumDownload/subtest5 (0.75s) --- PASS: TestCheckSumDownload/subtest6 (0.75s) --- PASS: TestCheckSumDownload/subtest7 (0.76s) === RUN TestApplyTransforms 2024/12/22 02:05:17 DEBUG : Creating backend with remote "TestB2:rclone-test-habibeb2revu" 2024/12/22 02:05:17 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/12/22 02:05:18 DEBUG : Creating backend with remote "/tmp/rclone2868134493" run.go:180: Remote "B2 bucket rclone-test-habibeb2revu", Local "Local file system at /tmp/rclone2868134493", Modify Window "1ms" upper checkfile vs. lower remote (without normalization) 2024/12/22 02:05:20 ERROR : hello, world!: sum not found 2024/12/22 02:05:20 ERROR : HELLO, WORLD!: file not in B2 bucket rclone-test-habibeb2revu 2024/12/22 02:05:20 NOTICE: B2 bucket rclone-test-habibeb2revu: 1 files missing 2024/12/22 02:05:20 NOTICE: 1 hashes missing 2024/12/22 02:05:20 NOTICE: B2 bucket rclone-test-habibeb2revu: 2 differences found 2024/12/22 02:05:20 NOTICE: B2 bucket rclone-test-habibeb2revu: 2 errors while checking upper checkfile vs. lower remote (with normalization) 2024/12/22 02:05:21 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2024/12/22 02:05:21 NOTICE: B2 bucket rclone-test-habibeb2revu: 0 differences found 2024/12/22 02:05:21 NOTICE: B2 bucket rclone-test-habibeb2revu: 1 matching files 2024/12/22 02:05:21 DEBUG : Creating backend with remote "TestB2:rclone-test-galokij1yehu" 2024/12/22 02:05:21 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/12/22 02:05:21 DEBUG : Creating backend with remote "/tmp/rclone567358894" run.go:180: Remote "B2 bucket rclone-test-galokij1yehu", Local "Local file system at /tmp/rclone567358894", Modify Window "1ms" lower checkfile vs. upper remote (without normalization) 2024/12/22 02:05:23 ERROR : HELLO, WORLD!: sum not found 2024/12/22 02:05:23 ERROR : hello, world!: file not in B2 bucket rclone-test-galokij1yehu 2024/12/22 02:05:23 NOTICE: B2 bucket rclone-test-galokij1yehu: 1 files missing 2024/12/22 02:05:23 NOTICE: 1 hashes missing 2024/12/22 02:05:23 NOTICE: B2 bucket rclone-test-galokij1yehu: 2 differences found 2024/12/22 02:05:23 NOTICE: B2 bucket rclone-test-galokij1yehu: 2 errors while checking lower checkfile vs. upper remote (with normalization) 2024/12/22 02:05:24 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2024/12/22 02:05:24 NOTICE: B2 bucket rclone-test-galokij1yehu: 0 differences found 2024/12/22 02:05:24 NOTICE: B2 bucket rclone-test-galokij1yehu: 1 matching files 2024/12/22 02:05:24 DEBUG : Creating backend with remote "TestB2:rclone-test-cacadex0guvi" 2024/12/22 02:05:24 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/12/22 02:05:25 DEBUG : Creating backend with remote "/tmp/rclone446279177" run.go:180: Remote "B2 bucket rclone-test-cacadex0guvi", Local "Local file system at /tmp/rclone446279177", Modify Window "1ms" lower checkfile vs. upperlowermixed remote (without normalization) 2024/12/22 02:05:27 ERROR : HeLlO, wOrLd!: sum not found 2024/12/22 02:05:27 ERROR : hello, world!: file not in B2 bucket rclone-test-cacadex0guvi 2024/12/22 02:05:27 NOTICE: B2 bucket rclone-test-cacadex0guvi: 1 files missing 2024/12/22 02:05:27 NOTICE: 1 hashes missing 2024/12/22 02:05:27 NOTICE: B2 bucket rclone-test-cacadex0guvi: 2 differences found 2024/12/22 02:05:27 NOTICE: B2 bucket rclone-test-cacadex0guvi: 2 errors while checking lower checkfile vs. upperlowermixed remote (with normalization) 2024/12/22 02:05:27 DEBUG : HeLlO, wOrLd!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2024/12/22 02:05:27 NOTICE: B2 bucket rclone-test-cacadex0guvi: 0 differences found 2024/12/22 02:05:27 NOTICE: B2 bucket rclone-test-cacadex0guvi: 1 matching files 2024/12/22 02:05:27 DEBUG : Creating backend with remote "TestB2:rclone-test-xuzegob0fuwe" 2024/12/22 02:05:27 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/12/22 02:05:28 DEBUG : Creating backend with remote "/tmp/rclone1074267248" run.go:180: Remote "B2 bucket rclone-test-xuzegob0fuwe", Local "Local file system at /tmp/rclone1074267248", Modify Window "1ms" upperlowermixed checkfile vs. upper remote (without normalization) 2024/12/22 02:05:30 ERROR : HELLO, WORLD!: sum not found 2024/12/22 02:05:30 ERROR : HeLlO, wOrLd!: file not in B2 bucket rclone-test-xuzegob0fuwe 2024/12/22 02:05:30 NOTICE: B2 bucket rclone-test-xuzegob0fuwe: 1 files missing 2024/12/22 02:05:30 NOTICE: 1 hashes missing 2024/12/22 02:05:30 NOTICE: B2 bucket rclone-test-xuzegob0fuwe: 2 differences found 2024/12/22 02:05:30 NOTICE: B2 bucket rclone-test-xuzegob0fuwe: 2 errors while checking upperlowermixed checkfile vs. upper remote (with normalization) 2024/12/22 02:05:31 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2024/12/22 02:05:31 NOTICE: B2 bucket rclone-test-xuzegob0fuwe: 0 differences found 2024/12/22 02:05:31 NOTICE: B2 bucket rclone-test-xuzegob0fuwe: 1 matching files 2024/12/22 02:05:31 DEBUG : Creating backend with remote "TestB2:rclone-test-felenus3voki" 2024/12/22 02:05:31 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/12/22 02:05:31 DEBUG : Creating backend with remote "/tmp/rclone2330574354" run.go:180: Remote "B2 bucket rclone-test-felenus3voki", Local "Local file system at /tmp/rclone2330574354", Modify Window "1ms" NFD checkfile vs. NFC remote (without normalization) 2024/12/22 02:05:33 ERROR : 測試_Русский___ě_áñ: sum not found 2024/12/22 02:05:33 ERROR : 測試_Русский___ě_áñ: file not in B2 bucket rclone-test-felenus3voki 2024/12/22 02:05:33 NOTICE: B2 bucket rclone-test-felenus3voki: 1 files missing 2024/12/22 02:05:33 NOTICE: 1 hashes missing 2024/12/22 02:05:33 NOTICE: B2 bucket rclone-test-felenus3voki: 2 differences found 2024/12/22 02:05:33 NOTICE: B2 bucket rclone-test-felenus3voki: 2 errors while checking NFD checkfile vs. NFC remote (with normalization) 2024/12/22 02:05:34 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2024/12/22 02:05:34 NOTICE: B2 bucket rclone-test-felenus3voki: 0 differences found 2024/12/22 02:05:34 NOTICE: B2 bucket rclone-test-felenus3voki: 1 matching files 2024/12/22 02:05:34 DEBUG : Creating backend with remote "TestB2:rclone-test-jetilaz0pugi" 2024/12/22 02:05:34 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/12/22 02:05:35 DEBUG : Creating backend with remote "/tmp/rclone738428004" run.go:180: Remote "B2 bucket rclone-test-jetilaz0pugi", Local "Local file system at /tmp/rclone738428004", Modify Window "1ms" NFC checkfile vs. NFD remote (without normalization) 2024/12/22 02:05:37 ERROR : 測試_Русский___ě_áñ: sum not found 2024/12/22 02:05:37 ERROR : 測試_Русский___ě_áñ: file not in B2 bucket rclone-test-jetilaz0pugi 2024/12/22 02:05:37 NOTICE: B2 bucket rclone-test-jetilaz0pugi: 1 files missing 2024/12/22 02:05:37 NOTICE: 1 hashes missing 2024/12/22 02:05:37 NOTICE: B2 bucket rclone-test-jetilaz0pugi: 2 differences found 2024/12/22 02:05:37 NOTICE: B2 bucket rclone-test-jetilaz0pugi: 2 errors while checking NFC checkfile vs. NFD remote (with normalization) 2024/12/22 02:05:38 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2024/12/22 02:05:38 NOTICE: B2 bucket rclone-test-jetilaz0pugi: 0 differences found 2024/12/22 02:05:38 NOTICE: B2 bucket rclone-test-jetilaz0pugi: 1 matching files 2024/12/22 02:05:38 DEBUG : Creating backend with remote "TestB2:rclone-test-qujarus4waku" 2024/12/22 02:05:38 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/12/22 02:05:38 DEBUG : Creating backend with remote "/tmp/rclone3936300398" run.go:180: Remote "B2 bucket rclone-test-qujarus4waku", Local "Local file system at /tmp/rclone3936300398", Modify Window "1ms" NFDx2 checkfile vs. both remote (without normalization) 2024/12/22 02:05:40 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2024/12/22 02:05:40 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-qujarus4waku 2024/12/22 02:05:40 NOTICE: B2 bucket rclone-test-qujarus4waku: 1 files missing 2024/12/22 02:05:40 NOTICE: 1 hashes missing 2024/12/22 02:05:40 NOTICE: B2 bucket rclone-test-qujarus4waku: 2 differences found 2024/12/22 02:05:40 NOTICE: B2 bucket rclone-test-qujarus4waku: 2 errors while checking NFDx2 checkfile vs. both remote (with normalization) 2024/12/22 02:05:41 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2024/12/22 02:05:41 NOTICE: B2 bucket rclone-test-qujarus4waku: 0 differences found 2024/12/22 02:05:41 NOTICE: B2 bucket rclone-test-qujarus4waku: 1 matching files 2024/12/22 02:05:41 DEBUG : Creating backend with remote "TestB2:rclone-test-zuxupuq1wozo" 2024/12/22 02:05:41 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/12/22 02:05:42 DEBUG : Creating backend with remote "/tmp/rclone1862566293" run.go:180: Remote "B2 bucket rclone-test-zuxupuq1wozo", Local "Local file system at /tmp/rclone1862566293", Modify Window "1ms" NFCx2 checkfile vs. both remote (without normalization) 2024/12/22 02:05:44 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2024/12/22 02:05:44 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-zuxupuq1wozo 2024/12/22 02:05:44 NOTICE: B2 bucket rclone-test-zuxupuq1wozo: 1 files missing 2024/12/22 02:05:44 NOTICE: 1 hashes missing 2024/12/22 02:05:44 NOTICE: B2 bucket rclone-test-zuxupuq1wozo: 2 differences found 2024/12/22 02:05:44 NOTICE: B2 bucket rclone-test-zuxupuq1wozo: 2 errors while checking NFCx2 checkfile vs. both remote (with normalization) 2024/12/22 02:05:44 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2024/12/22 02:05:44 NOTICE: B2 bucket rclone-test-zuxupuq1wozo: 0 differences found 2024/12/22 02:05:44 NOTICE: B2 bucket rclone-test-zuxupuq1wozo: 1 matching files 2024/12/22 02:05:44 DEBUG : Creating backend with remote "TestB2:rclone-test-neleyew2nusu" 2024/12/22 02:05:44 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/12/22 02:05:45 DEBUG : Creating backend with remote "/tmp/rclone1659319511" run.go:180: Remote "B2 bucket rclone-test-neleyew2nusu", Local "Local file system at /tmp/rclone1659319511", Modify Window "1ms" both checkfile vs. NFDx2 remote (without normalization) 2024/12/22 02:05:47 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2024/12/22 02:05:47 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-neleyew2nusu 2024/12/22 02:05:47 NOTICE: B2 bucket rclone-test-neleyew2nusu: 1 files missing 2024/12/22 02:05:47 NOTICE: 1 hashes missing 2024/12/22 02:05:47 NOTICE: B2 bucket rclone-test-neleyew2nusu: 2 differences found 2024/12/22 02:05:47 NOTICE: B2 bucket rclone-test-neleyew2nusu: 2 errors while checking both checkfile vs. NFDx2 remote (with normalization) 2024/12/22 02:05:48 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2024/12/22 02:05:48 NOTICE: B2 bucket rclone-test-neleyew2nusu: 0 differences found 2024/12/22 02:05:48 NOTICE: B2 bucket rclone-test-neleyew2nusu: 1 matching files 2024/12/22 02:05:48 DEBUG : Creating backend with remote "TestB2:rclone-test-cetekej7gagi" 2024/12/22 02:05:48 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/12/22 02:05:49 DEBUG : Creating backend with remote "/tmp/rclone763090075" run.go:180: Remote "B2 bucket rclone-test-cetekej7gagi", Local "Local file system at /tmp/rclone763090075", Modify Window "1ms" both checkfile vs. NFCx2 remote (without normalization) 2024/12/22 02:05:51 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2024/12/22 02:05:51 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-cetekej7gagi 2024/12/22 02:05:51 NOTICE: B2 bucket rclone-test-cetekej7gagi: 1 files missing 2024/12/22 02:05:51 NOTICE: 1 hashes missing 2024/12/22 02:05:51 NOTICE: B2 bucket rclone-test-cetekej7gagi: 2 differences found 2024/12/22 02:05:51 NOTICE: B2 bucket rclone-test-cetekej7gagi: 2 errors while checking both checkfile vs. NFCx2 remote (with normalization) 2024/12/22 02:05:51 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2024/12/22 02:05:51 NOTICE: B2 bucket rclone-test-cetekej7gagi: 0 differences found 2024/12/22 02:05:51 NOTICE: B2 bucket rclone-test-cetekej7gagi: 1 matching files 2024/12/22 02:05:51 DEBUG : B2 bucket rclone-test-cetekej7gagi: Purge remote 2024/12/22 02:05:51 INFO : B2 bucket rclone-test-cetekej7gagi: cleaning bucket "rclone-test-cetekej7gagi" of all files 2024/12/22 02:05:52 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_zbf221d6310ddebcb9f380e1b_f119913e8c5a84af7_d20241222_m020550_c001_v0001182_t0000_u01734833150676") 2024/12/22 02:05:52 DEBUG : B2 bucket rclone-test-neleyew2nusu: Purge remote 2024/12/22 02:05:52 INFO : B2 bucket rclone-test-neleyew2nusu: cleaning bucket "rclone-test-neleyew2nusu" of all files 2024/12/22 02:05:53 DEBUG : pacer: low level retry 1/10 (error Post "https://api001.backblazeb2.com/b2api/v1/b2_list_file_versions": EOF) 2024/12/22 02:05:53 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2024/12/22 02:05:53 DEBUG : pacer: Reducing sleep to 10ms 2024/12/22 02:05:53 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_z8fe20d8310ddebcb9f380e1b_f100e04fbd8f5bc06_d20241222_m020547_c001_v0001120_t0008_u01734833147078") 2024/12/22 02:05:54 DEBUG : B2 bucket rclone-test-zuxupuq1wozo: Purge remote 2024/12/22 02:05:54 INFO : B2 bucket rclone-test-zuxupuq1wozo: cleaning bucket "rclone-test-zuxupuq1wozo" of all files 2024/12/22 02:05:54 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_z9f42fdb310cdebcb9f380e1b_f10721cac69933da0_d20241222_m020543_c001_v0001183_t0033_u01734833143695") 2024/12/22 02:05:55 DEBUG : B2 bucket rclone-test-qujarus4waku: Purge remote 2024/12/22 02:05:55 INFO : B2 bucket rclone-test-qujarus4waku: cleaning bucket "rclone-test-qujarus4waku" of all files 2024/12/22 02:05:56 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_z5f32ede310cdebcb9f380e1b_f109cccd0f0c31ca4_d20241222_m020540_c001_v0001176_t0014_u01734833140373") 2024/12/22 02:05:56 DEBUG : B2 bucket rclone-test-jetilaz0pugi: Purge remote 2024/12/22 02:05:56 INFO : B2 bucket rclone-test-jetilaz0pugi: cleaning bucket "rclone-test-jetilaz0pugi" of all files 2024/12/22 02:05:57 DEBUG : 測試_Русский___ě_áñ: Deleting (id "4_z0fb2ed0310cdebcb9f380e1b_f119c719f23528ad7_d20241222_m020536_c001_v0001136_t0010_u01734833136761") 2024/12/22 02:05:58 DEBUG : B2 bucket rclone-test-felenus3voki: Purge remote 2024/12/22 02:05:58 INFO : B2 bucket rclone-test-felenus3voki: cleaning bucket "rclone-test-felenus3voki" of all files 2024/12/22 02:05:58 DEBUG : 測試_Русский___ě_áñ: Deleting (id "4_z0fe2dd3310cdebcb9f380e1b_f10221fa3e8eae73c_d20241222_m020533_c001_v0001183_t0021_u01734833133348") 2024/12/22 02:05:59 DEBUG : B2 bucket rclone-test-xuzegob0fuwe: Purge remote 2024/12/22 02:05:59 INFO : B2 bucket rclone-test-xuzegob0fuwe: cleaning bucket "rclone-test-xuzegob0fuwe" of all files 2024/12/22 02:05:59 DEBUG : HELLO, WORLD!: Deleting (id "4_zef72cd5310cdebcb9f380e1b_f111a30d6e680930a_d20241222_m020529_c001_v0001182_t0030_u01734833129951") 2024/12/22 02:06:00 DEBUG : B2 bucket rclone-test-cacadex0guvi: Purge remote 2024/12/22 02:06:00 INFO : B2 bucket rclone-test-cacadex0guvi: cleaning bucket "rclone-test-cacadex0guvi" of all files 2024/12/22 02:06:01 DEBUG : HeLlO, wOrLd!: Deleting (id "4_z2f82bd9310cdebcb9f380e1b_f114cb64f45b381cc_d20241222_m020526_c001_v0001182_t0057_u01734833126613") 2024/12/22 02:06:01 DEBUG : B2 bucket rclone-test-galokij1yehu: Purge remote 2024/12/22 02:06:01 INFO : B2 bucket rclone-test-galokij1yehu: cleaning bucket "rclone-test-galokij1yehu" of all files 2024/12/22 02:06:02 DEBUG : HELLO, WORLD!: Deleting (id "4_z6f22adb310cdebcb9f380e1b_f1172b27c42fc4e0c_d20241222_m020523_c001_v0001182_t0053_u01734833123398") 2024/12/22 02:06:03 DEBUG : B2 bucket rclone-test-habibeb2revu: Purge remote 2024/12/22 02:06:03 INFO : B2 bucket rclone-test-habibeb2revu: cleaning bucket "rclone-test-habibeb2revu" of all files 2024/12/22 02:06:03 DEBUG : hello, world!: Deleting (id "4_z7f629de310cdebcb9f380e1b_f10721cac6993383d_d20241222_m020519_c001_v0001183_t0011_u01734833119834") --- PASS: TestApplyTransforms (46.67s) === RUN TestTruncateString --- PASS: TestTruncateString (0.00s) === RUN TestCopyFile run.go:180: Remote "B2 bucket rclone-test-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", Modify Window "1ms" 2024/12/22 02:06:04 DEBUG : file1: Need to transfer - File not found at Destination 2024/12/22 02:06:05 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2024/12/22 02:06:05 INFO : file1: Copied (new) to: sub/file2 2024/12/22 02:06:06 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/12/22 02:06:06 DEBUG : file1: Unchanged skipping 2024/12/22 02:06:06 DEBUG : B2 bucket rclone-test-yehimix1yaja: don't need to copy/move sub/file2, it is already at target location --- PASS: TestCopyFile (2.90s) === RUN TestCopyLongFile run.go:180: Remote "B2 bucket rclone-test-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", Modify Window "1ms" copy_test.go:153: Test only runs on local --- SKIP: TestCopyLongFile (0.36s) === RUN TestCopyFileBackupDir run.go:180: Remote "B2 bucket rclone-test-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", Modify Window "1ms" 2024/12/22 02:06:08 DEBUG : Creating backend with remote "TestB2:rclone-test-yehimix1yaja/backup" 2024/12/22 02:06:09 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18) 2024/12/22 02:06:10 DEBUG : dst/file1: sha1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK 2024/12/22 02:06:10 INFO : dst/file1: Copied (server-side copy) 2024/12/22 02:06:11 INFO : dst/file1: Deleted 2024/12/22 02:06:11 DEBUG : dst/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2024/12/22 02:06:11 INFO : dst/file1: Copied (new) --- PASS: TestCopyFileBackupDir (4.62s) === RUN TestCopyFileCompareDest run.go:180: Remote "B2 bucket rclone-test-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", Modify Window "1ms" 2024/12/22 02:06:12 DEBUG : Creating backend with remote "TestB2:rclone-test-yehimix1yaja/dst" 2024/12/22 02:06:13 DEBUG : Creating backend with remote "TestB2:rclone-test-yehimix1yaja/CompareDest" 2024/12/22 02:06:14 DEBUG : one: Need to transfer - File not found at Destination 2024/12/22 02:06:16 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2024/12/22 02:06:16 INFO : one: Copied (new) 2024/12/22 02:06:16 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/12/22 02:06:17 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2024/12/22 02:06:17 INFO : one: Copied (replaced existing) 2024/12/22 02:06:19 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/12/22 02:06:19 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2024/12/22 02:06:19 DEBUG : one: Destination found in --compare-dest, skipping 2024/12/22 02:06:20 DEBUG : two: Need to transfer - File not found at Destination 2024/12/22 02:06:21 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2024/12/22 02:06:21 DEBUG : two: Destination found in --compare-dest, skipping 2024/12/22 02:06:21 DEBUG : two: Need to transfer - File not found at Destination 2024/12/22 02:06:21 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2024/12/22 02:06:21 DEBUG : two: Destination found in --compare-dest, skipping 2024/12/22 02:06:22 DEBUG : two: Need to transfer - File not found at Destination 2024/12/22 02:06:22 DEBUG : two: Sizes differ (src 5 vs dst 3) 2024/12/22 02:06:22 DEBUG : two: Clearing upload URL because of error: Post "https://pod-000-1177-02.backblaze.com/b2api/v1/b2_upload_file/efd2cda3104debcb9f380e1b/c001_v0001177_t0023": EOF 2024/12/22 02:06:22 DEBUG : pacer: low level retry 1/1 (error Post "https://pod-000-1177-02.backblaze.com/b2api/v1/b2_upload_file/efd2cda3104debcb9f380e1b/c001_v0001177_t0023": EOF) 2024/12/22 02:06:22 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2024/12/22 02:06:22 DEBUG : two: Received error: Post "https://pod-000-1177-02.backblaze.com/b2api/v1/b2_upload_file/efd2cda3104debcb9f380e1b/c001_v0001177_t0023": EOF - low level retry 0/10 2024/12/22 02:06:22 DEBUG : pacer: Reducing sleep to 10ms 2024/12/22 02:06:23 DEBUG : two: sha1 = a4be8877ad88f3109a6411985a1f359e10e2d930 OK 2024/12/22 02:06:23 INFO : two: Copied (new) --- PASS: TestCopyFileCompareDest (12.48s) === RUN TestCopyFileCopyDest run.go:180: Remote "B2 bucket rclone-test-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", Modify Window "1ms" 2024/12/22 02:06:24 DEBUG : Creating backend with remote "TestB2:rclone-test-yehimix1yaja/dst" 2024/12/22 02:06:26 DEBUG : Creating backend with remote "TestB2:rclone-test-yehimix1yaja/CopyDest" 2024/12/22 02:06:27 DEBUG : one: Need to transfer - File not found at Destination 2024/12/22 02:06:29 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2024/12/22 02:06:29 INFO : one: Copied (new) 2024/12/22 02:06:29 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/12/22 02:06:30 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2024/12/22 02:06:30 INFO : one: Copied (replaced existing) 2024/12/22 02:06:31 DEBUG : Creating backend with remote "TestB2:rclone-test-yehimix1yaja/BackupDir" 2024/12/22 02:06:32 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/12/22 02:06:33 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2024/12/22 02:06:33 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/12/22 02:06:34 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2024/12/22 02:06:34 INFO : one: Copied (server-side copy) 2024/12/22 02:06:34 INFO : one: Deleted 2024/12/22 02:06:35 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2024/12/22 02:06:35 INFO : one: Copied (server-side copy) 2024/12/22 02:06:35 DEBUG : one: Destination found in --copy-dest, using server-side copy 2024/12/22 02:06:36 DEBUG : two: Need to transfer - File not found at Destination 2024/12/22 02:06:36 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2024/12/22 02:06:36 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2024/12/22 02:06:36 INFO : two: Copied (server-side copy) 2024/12/22 02:06:36 DEBUG : two: Destination found in --copy-dest, using server-side copy 2024/12/22 02:06:37 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2024/12/22 02:06:37 DEBUG : two: Unchanged skipping 2024/12/22 02:06:38 DEBUG : three: Need to transfer - File not found at Destination 2024/12/22 02:06:38 DEBUG : three: Sizes differ (src 7 vs dst 5) 2024/12/22 02:06:38 DEBUG : three: Destination not found in --copy-dest 2024/12/22 02:06:39 DEBUG : three: sha1 = 370e613335f3cf2f19b2a2d35a86402f55c659f8 OK 2024/12/22 02:06:39 INFO : three: Copied (new) --- PASS: TestCopyFileCopyDest (16.24s) === RUN TestCopyInplace run.go:180: Remote "B2 bucket rclone-test-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", 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-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", 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-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", Modify Window "1ms" 2024/12/22 02:06:42 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination 2024/12/22 02:06:42 DEBUG : TestCopyFileMaxTransfer/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2024/12/22 02:06:42 INFO : TestCopyFileMaxTransfer/file1: Copied (new) 2024/12/22 02:06:43 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination 2024/12/22 02:06:43 ERROR : TestCopyFileMaxTransfer/file2: Failed to copy: Post "https://pod-000-1136-13.backblaze.com/b2api/v1/b2_upload_file/efd2cda3104debcb9f380e1b/c001_v0001136_t0055": max transfer limit reached as set by --max-transfer 2024/12/22 02:06:43 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination 2024/12/22 02:06:43 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination 2024/12/22 02:06:44 DEBUG : TestCopyFileMaxTransfer/file4: sha1 = efe75257a6819b5c084129269dc8684cb965fde7 OK 2024/12/22 02:06:44 INFO : TestCopyFileMaxTransfer/file4: Copied (new) --- PASS: TestCopyFileMaxTransfer (3.67s) === RUN TestDeduplicateInteractive run.go:180: Remote "B2 bucket rclone-test-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", 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-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", 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-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSizeOnly (0.36s) === RUN TestDeduplicateFirst run.go:180: Remote "B2 bucket rclone-test-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateFirst (0.36s) === RUN TestDeduplicateNewest run.go:180: Remote "B2 bucket rclone-test-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", 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-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", Modify Window "1ms" 2024/12/22 02:06:48 INFO : B2 bucket rclone-test-yehimix1yaja: Looking for duplicate sha1 hashes using newest mode. 2024/12/22 02:06:49 NOTICE: f9dde60d2bbddf824f87082968dce311e31d985f: Found 3 files with duplicate sha1 hashes 2024/12/22 02:06:49 INFO : one: Deleted 2024/12/22 02:06:49 INFO : also/one: Deleted 2024/12/22 02:06:49 NOTICE: f9dde60d2bbddf824f87082968dce311e31d985f: Deleted 2 extra copies --- PASS: TestDeduplicateNewestByHash (3.25s) === RUN TestDeduplicateOldest run.go:180: Remote "B2 bucket rclone-test-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateOldest (0.37s) === RUN TestDeduplicateLargest run.go:180: Remote "B2 bucket rclone-test-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", 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-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", 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-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateRename (0.39s) === RUN TestMergeDirs run.go:180: Remote "B2 bucket rclone-test-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", 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-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", Modify Window "1ms" 2024/12/22 02:06:55 DEBUG : a.txt: Excluded (Size Filter) 2024/12/22 02:06:55 DEBUG : a.txt: Excluded 2024/12/22 02:06:55 DEBUG : sub dir/hello world: Excluded (Size Filter) 2024/12/22 02:06:55 DEBUG : sub dir/hello world: Excluded 2024/12/22 02:06:55 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2024/12/22 02:06:55 DEBUG : sub dir/hello world2: Excluded 2024/12/22 02:06:56 DEBUG : sub dir/hello world: Excluded (Size Filter) 2024/12/22 02:06:56 DEBUG : sub dir/hello world: Excluded 2024/12/22 02:06:56 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2024/12/22 02:06:56 DEBUG : sub dir/hello world2: Excluded 2024/12/22 02:06:56 DEBUG : sub dir/ignore dir: Excluded 2024/12/22 02:06:56 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSorted (6.99s) === RUN TestListJSON run.go:180: Remote "B2 bucket rclone-test-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", Modify Window "1ms" === RUN TestListJSON/Default === RUN TestListJSON/FilesOnly === RUN TestListJSON/DirsOnly === RUN TestListJSON/Recurse === RUN TestListJSON/SubDir === RUN TestListJSON/NoModTime === RUN TestListJSON/NoMimeType === RUN TestListJSON/ShowHash === RUN TestListJSON/HashTypes 2024/12/22 02:07:02 ERROR : file1: Failed to read hash: hash type not supported === RUN TestListJSON/Metadata --- PASS: TestListJSON (3.59s) --- PASS: TestListJSON/Default (0.19s) --- 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.19s) --- PASS: TestListJSON/ShowHash (0.19s) --- PASS: TestListJSON/HashTypes (0.18s) --- PASS: TestListJSON/Metadata (0.18s) === RUN TestStatJSON run.go:180: Remote "B2 bucket rclone-test-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", Modify Window "1ms" === RUN TestStatJSON/Root === RUN TestStatJSON/RootFilesOnly === RUN TestStatJSON/RootDirsOnly === RUN TestStatJSON/Dir === RUN TestStatJSON/DirWithTrailingSlash === RUN TestStatJSON/File === RUN TestStatJSON/NotFound === RUN TestStatJSON/DirFilesOnly === RUN TestStatJSON/FileFilesOnly === RUN TestStatJSON/NotFoundFilesOnly === RUN TestStatJSON/DirDirsOnly === RUN TestStatJSON/FileDirsOnly === RUN TestStatJSON/NotFoundDirsOnly === RUN TestStatJSON/RootNotFound 2024/12/22 02:07:07 DEBUG : Creating backend with remote "TestB2:rclone-test-yehimix1yaja/notfound" --- PASS: TestStatJSON (6.82s) --- PASS: TestStatJSON/Root (0.18s) --- PASS: TestStatJSON/RootFilesOnly (0.00s) --- PASS: TestStatJSON/RootDirsOnly (0.18s) --- PASS: TestStatJSON/Dir (0.75s) --- PASS: TestStatJSON/DirWithTrailingSlash (0.18s) --- PASS: TestStatJSON/File (0.18s) --- PASS: TestStatJSON/NotFound (0.37s) --- PASS: TestStatJSON/DirFilesOnly (0.18s) --- PASS: TestStatJSON/FileFilesOnly (0.18s) --- PASS: TestStatJSON/NotFoundFilesOnly (0.18s) --- PASS: TestStatJSON/DirDirsOnly (0.19s) --- PASS: TestStatJSON/FileDirsOnly (0.18s) --- PASS: TestStatJSON/NotFoundDirsOnly (0.18s) --- PASS: TestStatJSON/RootNotFound (1.85s) === RUN TestMkdir run.go:180: Remote "B2 bucket rclone-test-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", Modify Window "1ms" 2024/12/22 02:07:09 DEBUG : B2 bucket rclone-test-yehimix1yaja: Making directory 2024/12/22 02:07:09 DEBUG : B2 bucket rclone-test-yehimix1yaja: Making directory --- PASS: TestMkdir (0.55s) === RUN TestLsd run.go:180: Remote "B2 bucket rclone-test-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", Modify Window "1ms" --- PASS: TestLsd (1.63s) === RUN TestLs run.go:180: Remote "B2 bucket rclone-test-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", Modify Window "1ms" --- PASS: TestLs (1.99s) === RUN TestLsWithFilesFrom run.go:180: Remote "B2 bucket rclone-test-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", Modify Window "1ms" 2024/12/22 02:07:15 DEBUG : empty space: Excluded (FilesFrom Filter) 2024/12/22 02:07:15 DEBUG : empty space: Excluded --- PASS: TestLsWithFilesFrom (2.58s) === RUN TestLsLong run.go:180: Remote "B2 bucket rclone-test-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", Modify Window "1ms" --- PASS: TestLsLong (1.68s) === RUN TestHashSums run.go:180: Remote "B2 bucket rclone-test-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", Modify Window "1ms" === RUN TestHashSums/Sha1 === RUN TestHashSums/Sha1Download --- PASS: TestHashSums (2.45s) --- PASS: TestHashSums/Sha1 (0.27s) --- PASS: TestHashSums/Sha1Download (0.39s) === RUN TestHashSumsWithErrors 2024/12/22 02:07:20 DEBUG : Creating backend with remote ":memory:" 2024/12/22 02:07:20 ERROR : file1: hash unsupported: hash type not supported --- PASS: TestHashSumsWithErrors (0.00s) === RUN TestHashStream 2024/12/22 02:07:20 DEBUG : Creating md5 hash of 0 bytes read from input stream 2024/12/22 02:07:20 DEBUG : Creating md5 hash of 0 bytes read from input stream 2024/12/22 02:07:20 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2024/12/22 02:07:20 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2024/12/22 02:07:20 DEBUG : Creating md5 hash of 12 bytes read from input stream 2024/12/22 02:07:20 DEBUG : Creating md5 hash of 12 bytes read from input stream 2024/12/22 02:07:20 DEBUG : Creating sha1 hash of 12 bytes read from input stream 2024/12/22 02:07:20 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-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", Modify Window "1ms" --- PASS: TestCount (2.33s) === RUN TestDelete run.go:180: Remote "B2 bucket rclone-test-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", Modify Window "1ms" 2024/12/22 02:07:24 DEBUG : Waiting for deletions to finish 2024/12/22 02:07:24 DEBUG : large: Excluded (Size Filter) 2024/12/22 02:07:25 INFO : medium: Deleted 2024/12/22 02:07:25 INFO : small: Deleted --- PASS: TestDelete (3.33s) === RUN TestMaxDelete run.go:180: Remote "B2 bucket rclone-test-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", Modify Window "1ms" 2024/12/22 02:07:27 DEBUG : Waiting for deletions to finish 2024/12/22 02:07:27 ERROR : small: Got fatal error on delete: --max-delete threshold reached 2024/12/22 02:07:28 INFO : large: Deleted 2024/12/22 02:07:28 INFO : medium: Deleted --- PASS: TestMaxDelete (2.95s) === RUN TestMaxDeleteSizeLargeFile run.go:180: Remote "B2 bucket rclone-test-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", Modify Window "1ms" 2024/12/22 02:07:30 DEBUG : Waiting for deletions to finish 2024/12/22 02:07:30 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached 2024/12/22 02:07:31 INFO : small: Deleted 2024/12/22 02:07:31 INFO : medium: Deleted --- PASS: TestMaxDeleteSizeLargeFile (3.01s) === RUN TestMaxDeleteSize run.go:180: Remote "B2 bucket rclone-test-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", Modify Window "1ms" 2024/12/22 02:07:33 DEBUG : Waiting for deletions to finish 2024/12/22 02:07:33 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached 2024/12/22 02:07:34 INFO : medium: Deleted 2024/12/22 02:07:34 INFO : small: Deleted --- PASS: TestMaxDeleteSize (2.88s) === RUN TestReadFile run.go:180: Remote "B2 bucket rclone-test-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", Modify Window "1ms" --- PASS: TestReadFile (2.21s) === RUN TestRetry 2024/12/22 02:07:37 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 1/5 2024/12/22 02:07:37 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 2/5 2024/12/22 02:07:37 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG 2024/12/22 02:07:37 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG 2024/12/22 02:07:37 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG 2024/12/22 02:07:37 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG 2024/12/22 02:07:37 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-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", Modify Window "1ms" --- PASS: TestCat (4.46s) === RUN TestPurge 2024/12/22 02:07:41 DEBUG : Creating backend with remote "TestB2:rclone-test-juqivuh1siwe" 2024/12/22 02:07:41 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/12/22 02:07:42 DEBUG : Creating backend with remote "/tmp/rclone2521781897" run.go:180: Remote "B2 bucket rclone-test-juqivuh1siwe", Local "Local file system at /tmp/rclone2521781897", Modify Window "1ms" 2024/12/22 02:07:44 DEBUG : A2: Making directory 2024/12/22 02:07:44 DEBUG : A1/B2: Making directory 2024/12/22 02:07:44 DEBUG : A1/B2/C2: Making directory 2024/12/22 02:07:44 DEBUG : A1/B1/C3: Making directory 2024/12/22 02:07:44 DEBUG : A3: Making directory 2024/12/22 02:07:44 DEBUG : A3/B3: Making directory 2024/12/22 02:07:44 DEBUG : A3/B3/C4: Making directory fstest.go:244: Filtering empty directory "A2" fstest.go:244: Filtering empty directory "A1/B2" fstest.go:244: Filtering empty directory "A1/B2/C2" fstest.go:244: Filtering empty directory "A1/B1/C3" fstest.go:244: Filtering empty directory "A3" fstest.go:244: Filtering empty directory "A3/B3" fstest.go:244: Filtering empty directory "A3/B3/C4" 2024/12/22 02:07:44 INFO : B2 bucket rclone-test-juqivuh1siwe: cleaning bucket "rclone-test-juqivuh1siwe" of all files 2024/12/22 02:07:44 DEBUG : A1/B1/C1/one: Deleting (id "4_z7f32dd1310edebcb9f380e1b_f102216a0ecd290c1_d20241222_m020743_c001_v0001182_t0049_u01734833263971") fstest.go:244: Filtering empty directory "A2" fstest.go:244: Filtering empty directory "A1/B2" fstest.go:244: Filtering empty directory "A1/B2/C2" fstest.go:244: Filtering empty directory "A3" fstest.go:244: Filtering empty directory "A3/B3" fstest.go:244: Filtering empty directory "A3/B3/C4" 2024/12/22 02:07:45 INFO : B2 bucket rclone-test-juqivuh1siwe: cleaning bucket "rclone-test-juqivuh1siwe" of all files 2024/12/22 02:07:45 DEBUG : A1/two: Deleting (id "4_z7f32dd1310edebcb9f380e1b_f102216a0ecd290c9_d20241222_m020744_c001_v0001182_t0035_u01734833264184") 2024/12/22 02:07:46 DEBUG : B2 bucket rclone-test-juqivuh1siwe: Purge remote 2024/12/22 02:07:46 INFO : B2 bucket rclone-test-juqivuh1siwe: cleaning bucket "rclone-test-juqivuh1siwe" of all files 2024/12/22 02:07:46 NOTICE: purge failed: directory not found --- PASS: TestPurge (4.53s) === RUN TestRmdirsNoLeaveRoot run.go:180: Remote "B2 bucket rclone-test-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", Modify Window "1ms" 2024/12/22 02:07:47 DEBUG : A2: Making directory 2024/12/22 02:07:47 DEBUG : A1/B2: Making directory 2024/12/22 02:07:47 DEBUG : A1/B2/C2: Making directory 2024/12/22 02:07:47 DEBUG : A1/B1/C3: Making directory 2024/12/22 02:07:47 DEBUG : A3: Making directory 2024/12/22 02:07:47 DEBUG : A3/B3: Making directory 2024/12/22 02:07:47 DEBUG : A3/B3/C4: Making directory fstest.go:244: Filtering empty directory "A2" fstest.go:244: Filtering empty directory "A1/B2" fstest.go:244: Filtering empty directory "A1/B2/C2" fstest.go:244: Filtering empty directory "A1/B1/C3" fstest.go:244: Filtering empty directory "A3" fstest.go:244: Filtering empty directory "A3/B3" fstest.go:244: Filtering empty directory "A3/B3/C4" 2024/12/22 02:07:48 DEBUG : removing 1 level 3 directories 2024/12/22 02:07:48 INFO : A3/B3/C4: Removing directory fstest.go:244: Filtering empty directory "A2" fstest.go:244: Filtering empty directory "A1/B2" fstest.go:244: Filtering empty directory "A1/B2/C2" fstest.go:244: Filtering empty directory "A1/B1/C3" fstest.go:244: Filtering empty directory "A3" fstest.go:244: Filtering empty directory "A3/B3" 2024/12/22 02:07:50 DEBUG : removing 1 level 0 directories 2024/12/22 02:07:50 INFO : B2 bucket rclone-test-yehimix1yaja: Removing directory 2024/12/22 02:07:50 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 (4.74s) === RUN TestRmdirsLeaveRoot run.go:180: Remote "B2 bucket rclone-test-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", Modify Window "1ms" 2024/12/22 02:07:51 DEBUG : A1: Making directory 2024/12/22 02:07:51 DEBUG : A1/B1: Making directory 2024/12/22 02:07:51 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.92s) === RUN TestRmdirsWithFilter run.go:180: Remote "B2 bucket rclone-test-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", Modify Window "1ms" 2024/12/22 02:07:52 DEBUG : A1: Making directory 2024/12/22 02:07:52 DEBUG : A1/B1: Making directory 2024/12/22 02:07:52 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.92s) === RUN TestCopyURL run.go:180: Remote "B2 bucket rclone-test-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", Modify Window "1ms" 2024/12/22 02:07:54 DEBUG : filename.txt: File name found in url 2024/12/22 02:07:54 DEBUG : headerfilename.txt: filename found in Content-Disposition header. --- PASS: TestCopyURL (3.31s) === RUN TestCopyURLToWriter --- PASS: TestCopyURLToWriter (0.00s) === RUN TestMoveFile run.go:180: Remote "B2 bucket rclone-test-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", Modify Window "1ms" 2024/12/22 02:07:56 DEBUG : file1: Need to transfer - File not found at Destination 2024/12/22 02:07:57 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2024/12/22 02:07:57 INFO : file1: Copied (new) to: sub/file2 2024/12/22 02:07:57 INFO : file1: Deleted 2024/12/22 02:07:57 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/12/22 02:07:57 DEBUG : file1: Unchanged skipping 2024/12/22 02:07:57 INFO : file1: Deleted 2024/12/22 02:07:57 DEBUG : B2 bucket rclone-test-yehimix1yaja: don't need to copy/move sub/file2, it is already at target location --- PASS: TestMoveFile (2.09s) === RUN TestMoveFileWithIgnoreExisting run.go:180: Remote "B2 bucket rclone-test-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", Modify Window "1ms" 2024/12/22 02:07:58 DEBUG : file1: Need to transfer - File not found at Destination 2024/12/22 02:07:58 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2024/12/22 02:07:58 INFO : file1: Copied (new) 2024/12/22 02:07:58 INFO : file1: Deleted 2024/12/22 02:07:59 DEBUG : file1: Destination exists, skipping 2024/12/22 02:07:59 DEBUG : file1: Not removing source file as destination file exists and --ignore-existing is set --- PASS: TestMoveFileWithIgnoreExisting (1.58s) === RUN TestCaseInsensitiveMoveFile run.go:180: Remote "B2 bucket rclone-test-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFile (0.37s) === RUN TestCaseInsensitiveMoveFileDryRun run.go:180: Remote "B2 bucket rclone-test-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFileDryRun (0.37s) === RUN TestMoveFileBackupDir run.go:180: Remote "B2 bucket rclone-test-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", Modify Window "1ms" 2024/12/22 02:08:01 DEBUG : Creating backend with remote "TestB2:rclone-test-yehimix1yaja/backup" 2024/12/22 02:08:02 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18) 2024/12/22 02:08:03 DEBUG : dst/file1: sha1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK 2024/12/22 02:08:03 INFO : dst/file1: Copied (server-side copy) 2024/12/22 02:08:03 INFO : dst/file1: Deleted 2024/12/22 02:08:04 DEBUG : dst/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2024/12/22 02:08:04 INFO : dst/file1: Copied (new) 2024/12/22 02:08:04 INFO : dst/file1: Deleted --- PASS: TestMoveFileBackupDir (4.67s) === 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-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", Modify Window "1ms" 2024/12/22 02:08:07 DEBUG : A1/B2: Making directory 2024/12/22 02:08:07 DEBUG : A1/B1/C3: Making directory fstest.go:244: Filtering empty directory "A1/B2" fstest.go:244: Filtering empty directory "A1/B1/C3" 2024/12/22 02:08:09 DEBUG : A1/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2024/12/22 02:08:09 INFO : A1/one: Copied (server-side copy) to: A2/one 2024/12/22 02:08:09 INFO : A1/one: Deleted 2024/12/22 02:08:09 DEBUG : A1/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2024/12/22 02:08:09 INFO : A1/B1/C2/five: Copied (server-side copy) to: A2/B1/C2/five 2024/12/22 02:08:09 DEBUG : A1/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2024/12/22 02:08:09 INFO : A1/B1/three: Copied (server-side copy) to: A2/B1/three 2024/12/22 02:08:09 DEBUG : A1/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2024/12/22 02:08:09 INFO : A1/two: Copied (server-side copy) to: A2/two 2024/12/22 02:08:09 INFO : A1/B1/C2/five: Deleted 2024/12/22 02:08:09 INFO : A1/B1/three: Deleted 2024/12/22 02:08:09 INFO : A1/two: Deleted 2024/12/22 02:08:10 DEBUG : A1/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2024/12/22 02:08:10 INFO : A1/B1/C1/four: Copied (server-side copy) to: A2/B1/C1/four 2024/12/22 02:08:10 INFO : A1/B1/C1/four: Deleted fstest.go:244: Filtering empty directory "A2/B2" fstest.go:244: Filtering empty directory "A2/B1/C3" 2024/12/22 02:08:11 DEBUG : A2/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2024/12/22 02:08:11 INFO : A2/B1/three: Copied (server-side copy) to: A3/B1/three 2024/12/22 02:08:11 DEBUG : A2/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2024/12/22 02:08:11 INFO : A2/B1/C1/four: Copied (server-side copy) to: A3/B1/C1/four 2024/12/22 02:08:11 DEBUG : A2/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2024/12/22 02:08:11 INFO : A2/two: Copied (server-side copy) to: A3/two 2024/12/22 02:08:11 INFO : A2/B1/three: Deleted 2024/12/22 02:08:11 INFO : A2/B1/C1/four: Deleted 2024/12/22 02:08:11 DEBUG : A2/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2024/12/22 02:08:11 INFO : A2/one: Copied (server-side copy) to: A3/one 2024/12/22 02:08:11 INFO : A2/two: Deleted 2024/12/22 02:08:11 INFO : A2/one: Deleted 2024/12/22 02:08:11 DEBUG : A2/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2024/12/22 02:08:11 INFO : A2/B1/C2/five: Copied (server-side copy) to: A3/B1/C2/five 2024/12/22 02:08:11 INFO : A2/B1/C2/five: Deleted fstest.go:244: Filtering empty directory "A3/B2" fstest.go:244: Filtering empty directory "A3/B1/C3" 2024/12/22 02:08:11 INFO : B2 bucket rclone-test-yehimix1yaja: Can't DirMove - falling back to file moves: can't move directory - incompatible remotes 2024/12/22 02:08:12 DEBUG : A3/B1/C1/four: sha1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2024/12/22 02:08:12 INFO : A3/B1/C1/four: Copied (server-side copy) to: A4/B1/C1/four 2024/12/22 02:08:12 DEBUG : A3/one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2024/12/22 02:08:12 INFO : A3/one: Copied (server-side copy) to: A4/one 2024/12/22 02:08:12 DEBUG : A3/B1/three: sha1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2024/12/22 02:08:12 INFO : A3/B1/three: Copied (server-side copy) to: A4/B1/three 2024/12/22 02:08:12 DEBUG : A3/B1/C2/five: sha1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2024/12/22 02:08:12 INFO : A3/B1/C2/five: Copied (server-side copy) to: A4/B1/C2/five 2024/12/22 02:08:12 INFO : A3/B1/C1/four: Deleted 2024/12/22 02:08:12 INFO : A3/one: Deleted 2024/12/22 02:08:12 INFO : A3/B1/three: Deleted 2024/12/22 02:08:12 INFO : A3/B1/C2/five: Deleted 2024/12/22 02:08:12 DEBUG : A3/two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2024/12/22 02:08:12 INFO : A3/two: Copied (server-side copy) to: A4/two 2024/12/22 02:08:13 INFO : A3/two: Deleted fstest.go:244: Filtering empty directory "A4/B2" fstest.go:244: Filtering empty directory "A4/B1/C3" --- PASS: TestDirMove (9.38s) === RUN TestGetFsInfo run.go:180: Remote "B2 bucket rclone-test-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", Modify Window "1ms" --- PASS: TestGetFsInfo (0.38s) === RUN TestRcat === RUN TestRcat/withChecksum=false,ignoreChecksum=false run.go:180: Remote "B2 bucket rclone-test-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", Modify Window "1ms" 2024/12/22 02:08:15 DEBUG : B2 bucket rclone-test-yehimix1yaja: File to upload is small (34 bytes), uploading instead of streaming 2024/12/22 02:08:16 DEBUG : no_checksum_small_file_from_pipe: sha1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK 2024/12/22 02:08:16 DEBUG : no_checksum_small_file_from_pipe: Size and sha1 of src and dst objects identical 2024/12/22 02:08:16 DEBUG : no_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2024/12/22 02:08:16 DEBUG : no_checksum_big_file_from_pipe: sha1 = d0080f73aa8b494570ad63df1beb25c01ea9eea2 OK 2024/12/22 02:08:16 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-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", Modify Window "1ms" 2024/12/22 02:08:18 DEBUG : B2 bucket rclone-test-yehimix1yaja: File to upload is small (34 bytes), uploading instead of streaming 2024/12/22 02:08:18 DEBUG : with_checksum_small_file_from_pipe: sha1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK 2024/12/22 02:08:18 DEBUG : with_checksum_small_file_from_pipe: Size and sha1 of src and dst objects identical 2024/12/22 02:08:18 DEBUG : with_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2024/12/22 02:08:19 DEBUG : with_checksum_big_file_from_pipe: sha1 = d0080f73aa8b494570ad63df1beb25c01ea9eea2 OK 2024/12/22 02:08:19 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-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", Modify Window "1ms" 2024/12/22 02:08:20 DEBUG : B2 bucket rclone-test-yehimix1yaja: File to upload is small (34 bytes), uploading instead of streaming 2024/12/22 02:08:21 DEBUG : ignore_checksum_small_file_from_pipe: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/12/22 02:08:21 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2024/12/22 02:08:22 DEBUG : ignore_checksum_big_file_from_pipe: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) === RUN TestRcat/withChecksum=true,ignoreChecksum=true run.go:180: Remote "B2 bucket rclone-test-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", Modify Window "1ms" 2024/12/22 02:08:23 DEBUG : B2 bucket rclone-test-yehimix1yaja: File to upload is small (34 bytes), uploading instead of streaming 2024/12/22 02:08:23 DEBUG : ignore_checksum_small_file_from_pipe: Src hash empty - aborting Dst hash check 2024/12/22 02:08:23 DEBUG : ignore_checksum_small_file_from_pipe: Size of src and dst objects identical 2024/12/22 02:08:23 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2024/12/22 02:08:24 DEBUG : ignore_checksum_big_file_from_pipe: Src hash empty - aborting Dst hash check 2024/12/22 02:08:24 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical --- PASS: TestRcat (10.35s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (2.72s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (2.58s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (2.72s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (2.33s) === RUN TestRcatMetadata run.go:180: Remote "B2 bucket rclone-test-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", Modify Window "1ms" operations_test.go:1532: Skipping as destination doesn't support user metadata --- SKIP: TestRcatMetadata (0.37s) === RUN TestRcatSize run.go:180: Remote "B2 bucket rclone-test-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", Modify Window "1ms" 2024/12/22 02:08:26 DEBUG : B2 bucket rclone-test-yehimix1yaja: File to upload is small (60 bytes), uploading instead of streaming 2024/12/22 02:08:26 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2024/12/22 02:08:26 DEBUG : potato2: Size and sha1 of src and dst objects identical --- PASS: TestRcatSize (1.89s) === RUN TestRcatSizeMetadata run.go:180: Remote "B2 bucket rclone-test-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", 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-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", Modify Window "1ms" 2024/12/22 02:08:29 DEBUG : B2 bucket rclone-test-yehimix1yaja: Touching "empty space" 2024/12/22 02:08:30 DEBUG : B2 bucket rclone-test-yehimix1yaja: Touching "potato2" 2024/12/22 02:08:31 DEBUG : B2 bucket rclone-test-yehimix1yaja: Touching "sub dir/potato3" --- PASS: TestTouchDir (4.63s) === RUN TestMkdirMetadata run.go:180: Remote "B2 bucket rclone-test-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", Modify Window "1ms" operations_test.go:1703: Skipping test as remote does not support MkdirMetadata --- SKIP: TestMkdirMetadata (0.37s) === RUN TestMkdirModTime run.go:180: Remote "B2 bucket rclone-test-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", Modify Window "1ms" operations_test.go:1722: Skipping test as remote does not support DirSetModTime or MkdirMetadata --- SKIP: TestMkdirModTime (0.37s) === RUN TestCopyDirMetadata run.go:180: Remote "B2 bucket rclone-test-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", 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-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", 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-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", Modify Window "1ms" operations_test.go:1823: Skipping test as remote does not support WriteDirMetadata or MkdirMetadata --- SKIP: TestDirsEqual (0.37s) === RUN TestRemoveExisting run.go:180: Remote "B2 bucket rclone-test-yehimix1yaja", Local "Local file system at /tmp/rclone3233824478", Modify Window "1ms" operations_test.go:1892: Skipping as remote can't Move --- SKIP: TestRemoveExisting (0.37s) === RUN TestRcAbout rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcAbout (0.00s) === RUN TestRcCleanup rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcCleanup (0.00s) === RUN TestRcCopyfile rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcCopyfile (0.00s) === RUN TestRcCopyurl rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcCopyurl (0.00s) === RUN TestRcDelete rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcDelete (0.00s) === RUN TestRcDeletefile rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcDeletefile (0.00s) === RUN TestRcList rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcList (0.00s) === RUN TestRcStat rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcStat (0.00s) === RUN TestRcSetTier rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcSetTier (0.00s) === RUN TestRcSetTierFile rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcSetTierFile (0.00s) === RUN TestRcMkdir rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcMkdir (0.00s) === RUN TestRcMovefile rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcMovefile (0.00s) === RUN TestRcPurge rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcPurge (0.00s) === RUN TestRcRmdir rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcRmdir (0.00s) === RUN TestRcRmdirs rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcRmdirs (0.00s) === RUN TestRcSize rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcSize (0.00s) === RUN TestRcPublicLink rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcPublicLink (0.00s) === RUN TestRcFsInfo rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcFsInfo (0.00s) === RUN TestUploadFile rc_test.go:30: Skipping test on non local remote --- SKIP: TestUploadFile (0.00s) === RUN TestRcCommand rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcCommand (0.00s) === RUN TestRcDu rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcDu (0.00s) === RUN TestRcCheck rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcCheck (0.00s) === RUN TestRcHashsum rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcHashsum (0.00s) === RUN TestRcHashsumFile rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcHashsumFile (0.00s) FAIL 2024/12/22 02:08:34 DEBUG : B2 bucket rclone-test-yehimix1yaja: Purge remote 2024/12/22 02:08:34 INFO : B2 bucket rclone-test-yehimix1yaja: cleaning bucket "rclone-test-yehimix1yaja" of all files 2024/12/22 02:08:35 DEBUG : A1/B1/C1/four: Deleting (id "4_zefd2cda3104debcb9f380e1b_f4053fe083c563434_d20241222_m020810_c001_v7007000_t0000_u01734833290202") 2024/12/22 02:08:35 DEBUG : A1/B1/C1/four: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f3d6_d20241222_m020807_c001_v0001136_t0055_u01734833287199") 2024/12/22 02:08:35 DEBUG : A1/B1/C1/one: Deleting (id "4_zefd2cda3104debcb9f380e1b_f408fb784060ab1c5_d20241222_m020749_c001_v7007000_t0000_u01734833269928") 2024/12/22 02:08:35 DEBUG : A1/B1/C1/one: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f396_d20241222_m020746_c001_v0001136_t0056_u01734833266848") 2024/12/22 02:08:35 DEBUG : A1/B1/C2/five: Deleting (id "4_zefd2cda3104debcb9f380e1b_f4053fe083c563433_d20241222_m020809_c001_v7007000_t0000_u01734833289500") 2024/12/22 02:08:35 DEBUG : A1/B1/C2/five: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f3da_d20241222_m020807_c001_v0001136_t0008_u01734833287501") 2024/12/22 02:08:35 DEBUG : A1/B1/three: Deleting (id "4_zefd2cda3104debcb9f380e1b_f410cb332e5ae02ba_d20241222_m020809_c001_v7007000_t0000_u01734833289525") 2024/12/22 02:08:35 DEBUG : A1/B1/three: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f3d0_d20241222_m020806_c001_v0001136_t0007_u01734833286375") 2024/12/22 02:08:35 DEBUG : A1/one: Deleting (id "4_zefd2cda3104debcb9f380e1b_f408de58c679a58f0_d20241222_m020809_c001_v7007000_t0000_u01734833289263") 2024/12/22 02:08:35 DEBUG : A1/one: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f3c8_d20241222_m020805_c001_v0001136_t0038_u01734833285453") 2024/12/22 02:08:35 DEBUG : A1/two: Deleting (id "4_zefd2cda3104debcb9f380e1b_f408fb784060ab1d3_d20241222_m020809_c001_v7007000_t0000_u01734833289599") 2024/12/22 02:08:36 DEBUG : A1/two: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f3cc_d20241222_m020806_c001_v0001136_t0019_u01734833286013") 2024/12/22 02:08:36 DEBUG : A1/two: Deleting (id "4_zefd2cda3104debcb9f380e1b_f408fb784060ab1c6_d20241222_m020750_c001_v7007000_t0000_u01734833270291") 2024/12/22 02:08:36 DEBUG : A1/two: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f398_d20241222_m020747_c001_v0001136_t0001_u01734833267153") 2024/12/22 02:08:36 DEBUG : A2/B1/C1/four: Deleting (id "4_zefd2cda3104debcb9f380e1b_f4053fe083c563435_d20241222_m020811_c001_v7007000_t0000_u01734833291218") 2024/12/22 02:08:36 DEBUG : A2/B1/C1/four: Deleting (id "4_zefd2cda3104debcb9f380e1b_f1118396c87b7e556_d20241222_m020809_c001_v0001115_t0053_u01734833289262") 2024/12/22 02:08:36 DEBUG : A2/B1/C2/five: Deleting (id "4_zefd2cda3104debcb9f380e1b_f408fb784060ab1d4_d20241222_m020811_c001_v7007000_t0000_u01734833291662") 2024/12/22 02:08:36 DEBUG : A2/B1/C2/five: Deleting (id "4_zefd2cda3104debcb9f380e1b_f10166afc1e6b13c0_d20241222_m020809_c001_v0001183_t0038_u01734833289264") 2024/12/22 02:08:36 DEBUG : A2/B1/three: Deleting (id "4_zefd2cda3104debcb9f380e1b_f410cb332e5ae02bd_d20241222_m020811_c001_v7007000_t0000_u01734833291203") 2024/12/22 02:08:36 DEBUG : A2/B1/three: Deleting (id "4_zefd2cda3104debcb9f380e1b_f119babad96fa40d8_d20241222_m020809_c001_v0001183_t0053_u01734833289296") 2024/12/22 02:08:36 DEBUG : A2/one: Deleting (id "4_zefd2cda3104debcb9f380e1b_f4053fe083c563436_d20241222_m020811_c001_v7007000_t0000_u01734833291411") 2024/12/22 02:08:36 DEBUG : A2/one: Deleting (id "4_zefd2cda3104debcb9f380e1b_f113bd64f996ee6b6_d20241222_m020808_c001_v0001181_t0059_u01734833288872") 2024/12/22 02:08:36 DEBUG : A2/two: Deleting (id "4_zefd2cda3104debcb9f380e1b_f408de58c679a58f6_d20241222_m020811_c001_v7007000_t0000_u01734833291231") 2024/12/22 02:08:36 DEBUG : A2/two: Deleting (id "4_zefd2cda3104debcb9f380e1b_f112af5a6ca62a586_d20241222_m020809_c001_v0001098_t0050_u01734833289144") 2024/12/22 02:08:36 DEBUG : A3/B1/C1/four: Deleting (id "4_zefd2cda3104debcb9f380e1b_f408fb784060ab1d7_d20241222_m020812_c001_v7007000_t0000_u01734833292747") 2024/12/22 02:08:36 DEBUG : A3/B1/C1/four: Deleting (id "4_zefd2cda3104debcb9f380e1b_f1128e50f4f0843f5_d20241222_m020810_c001_v0001183_t0041_u01734833290969") 2024/12/22 02:08:36 DEBUG : A3/B1/C2/five: Deleting (id "4_zefd2cda3104debcb9f380e1b_f4053fe083c563439_d20241222_m020812_c001_v7007000_t0000_u01734833292849") 2024/12/22 02:08:36 DEBUG : A3/B1/C2/five: Deleting (id "4_zefd2cda3104debcb9f380e1b_f104055590db1c468_d20241222_m020810_c001_v0001117_t0018_u01734833290998") 2024/12/22 02:08:36 DEBUG : A3/B1/three: Deleting (id "4_zefd2cda3104debcb9f380e1b_f408de58c679a58f9_d20241222_m020812_c001_v7007000_t0000_u01734833292835") 2024/12/22 02:08:37 DEBUG : A3/B1/three: Deleting (id "4_zefd2cda3104debcb9f380e1b_f105e7ec756136a73_d20241222_m020811_c001_v0001183_t0049_u01734833291000") 2024/12/22 02:08:37 DEBUG : A3/one: Deleting (id "4_zefd2cda3104debcb9f380e1b_f4053fe083c563438_d20241222_m020812_c001_v7007000_t0000_u01734833292819") 2024/12/22 02:08:37 DEBUG : A3/one: Deleting (id "4_zefd2cda3104debcb9f380e1b_f1101c847cae08725_d20241222_m020811_c001_v0001105_t0007_u01734833291003") 2024/12/22 02:08:37 DEBUG : A3/two: Deleting (id "4_zefd2cda3104debcb9f380e1b_f410cb332e5ae02bf_d20241222_m020813_c001_v7007000_t0000_u01734833293085") 2024/12/22 02:08:37 DEBUG : A3/two: Deleting (id "4_zefd2cda3104debcb9f380e1b_f100fd4e695b588e8_d20241222_m020811_c001_v0001182_t0028_u01734833291005") 2024/12/22 02:08:37 DEBUG : A4/B1/C1/four: Deleting (id "4_zefd2cda3104debcb9f380e1b_f410cb332e5ae02c0_d20241222_m020813_c001_v7007000_t0000_u01734833293649") 2024/12/22 02:08:37 DEBUG : A4/B1/C1/four: Deleting (id "4_zefd2cda3104debcb9f380e1b_f115eb0e66d009ee1_d20241222_m020812_c001_v0001150_t0046_u01734833292452") 2024/12/22 02:08:37 DEBUG : A4/B1/C2/five: Deleting (id "4_zefd2cda3104debcb9f380e1b_f410cb332e5ae02c1_d20241222_m020813_c001_v7007000_t0000_u01734833293835") 2024/12/22 02:08:37 DEBUG : A4/B1/C2/five: Deleting (id "4_zefd2cda3104debcb9f380e1b_f111b1bdc5c50478f_d20241222_m020812_c001_v0001037_t0047_u01734833292465") 2024/12/22 02:08:37 DEBUG : A4/B1/three: Deleting (id "4_zefd2cda3104debcb9f380e1b_f410cb332e5ae02c2_d20241222_m020814_c001_v7007000_t0000_u01734833294018") 2024/12/22 02:08:37 DEBUG : A4/B1/three: Deleting (id "4_zefd2cda3104debcb9f380e1b_f103e85dd5f15f1c4_d20241222_m020812_c001_v0001146_t0013_u01734833292466") 2024/12/22 02:08:37 DEBUG : A4/one: Deleting (id "4_zefd2cda3104debcb9f380e1b_f410cb332e5ae02c3_d20241222_m020814_c001_v7007000_t0000_u01734833294201") 2024/12/22 02:08:37 DEBUG : A4/one: Deleting (id "4_zefd2cda3104debcb9f380e1b_f1050ca4f7dffdfb8_d20241222_m020812_c001_v0001130_t0010_u01734833292472") 2024/12/22 02:08:37 DEBUG : A4/two: Deleting (id "4_zefd2cda3104debcb9f380e1b_f410cb332e5ae02c4_d20241222_m020814_c001_v7007000_t0000_u01734833294394") 2024/12/22 02:08:37 DEBUG : A4/two: Deleting (id "4_zefd2cda3104debcb9f380e1b_f10570c92cca1a8cc_d20241222_m020812_c001_v0001036_t0016_u01734833292508") 2024/12/22 02:08:37 DEBUG : BackupDir/one: Deleting (id "4_zefd2cda3104debcb9f380e1b_f41963de4df36b62e_d20241222_m020639_c001_v7007000_t0000_u01734833199590") 2024/12/22 02:08:37 DEBUG : BackupDir/one: Deleting (id "4_zefd2cda3104debcb9f380e1b_f1022c3143f99577e_d20241222_m020634_c001_v0001136_t0029_u01734833194130") 2024/12/22 02:08:37 DEBUG : CompareDest/one: Deleting (id "4_zefd2cda3104debcb9f380e1b_f41963de4df36b614_d20241222_m020623_c001_v7007000_t0000_u01734833183952") 2024/12/22 02:08:37 DEBUG : CompareDest/one: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f29a_d20241222_m020618_c001_v0001136_t0021_u01734833178874") 2024/12/22 02:08:37 DEBUG : CompareDest/two: Deleting (id "4_zefd2cda3104debcb9f380e1b_f41963de4df36b615_d20241222_m020624_c001_v7007000_t0000_u01734833184138") 2024/12/22 02:08:38 DEBUG : CompareDest/two: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f29c_d20241222_m020620_c001_v0001136_t0011_u01734833180276") 2024/12/22 02:08:38 DEBUG : CopyDest/one: Deleting (id "4_zefd2cda3104debcb9f380e1b_f41963de4df36b630_d20241222_m020639_c001_v7007000_t0000_u01734833199772") 2024/12/22 02:08:38 DEBUG : CopyDest/one: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f2b2_d20241222_m020631_c001_v0001136_t0033_u01734833191158") 2024/12/22 02:08:38 DEBUG : CopyDest/three: Deleting (id "4_zefd2cda3104debcb9f380e1b_f41963de4df36b631_d20241222_m020639_c001_v7007000_t0000_u01734833199961") 2024/12/22 02:08:38 DEBUG : CopyDest/three: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f2be_d20241222_m020637_c001_v0001136_t0007_u01734833197378") 2024/12/22 02:08:38 DEBUG : CopyDest/two: Deleting (id "4_zefd2cda3104debcb9f380e1b_f41963de4df36b632_d20241222_m020640_c001_v7007000_t0000_u01734833200163") 2024/12/22 02:08:38 DEBUG : CopyDest/two: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f2ba_d20241222_m020635_c001_v0001136_t0008_u01734833195468") 2024/12/22 02:08:38 DEBUG : ReadFile: Deleting (id "4_zefd2cda3104debcb9f380e1b_f402f2ad1fbf97d5b_d20241222_m020736_c001_v7007000_t0000_u01734833256663") 2024/12/22 02:08:38 DEBUG : ReadFile: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f382_d20241222_m020735_c001_v0001136_t0043_u01734833255234") 2024/12/22 02:08:38 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_zefd2cda3104debcb9f380e1b_f41963de4df36b63b_d20241222_m020644_c001_v7007000_t0000_u01734833204972") 2024/12/22 02:08:38 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f2c8_d20241222_m020642_c001_v0001136_t0027_u01734833202402") 2024/12/22 02:08:38 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_zefd2cda3104debcb9f380e1b_f41963de4df36b63c_d20241222_m020645_c001_v7007000_t0000_u01734833205168") 2024/12/22 02:08:38 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f2ce_d20241222_m020644_c001_v0001136_t0043_u01734833204262") 2024/12/22 02:08:38 DEBUG : a.txt: Deleting (id "4_zefd2cda3104debcb9f380e1b_f402f2ad1fbf97d36_d20241222_m020657_c001_v7007000_t0000_u01734833217982") 2024/12/22 02:08:38 DEBUG : a.txt: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f2dc_d20241222_m020652_c001_v0001136_t0053_u01734833212482") 2024/12/22 02:08:38 DEBUG : also/one: Deleting (id "4_zefd2cda3104debcb9f380e1b_f41963de4df36b642_d20241222_m020649_c001_v7007000_t0000_u01734833209454") 2024/12/22 02:08:38 DEBUG : also/one: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f2d2_d20241222_m020647_c001_v0001136_t0038_u01734833207731") 2024/12/22 02:08:38 DEBUG : another: Deleting (id "4_zefd2cda3104debcb9f380e1b_f41963de4df36b644_d20241222_m020650_c001_v7007000_t0000_u01734833210022") 2024/12/22 02:08:38 DEBUG : another: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f2d4_d20241222_m020648_c001_v0001136_t0049_u01734833208153") 2024/12/22 02:08:38 DEBUG : backup/dst/file1: Deleting (id "4_zefd2cda3104debcb9f380e1b_f408fb784060ab1d0_d20241222_m020804_c001_v7007000_t0000_u01734833284878") 2024/12/22 02:08:39 DEBUG : backup/dst/file1: Deleting (id "4_zefd2cda3104debcb9f380e1b_f112089b91f7ef831_d20241222_m020803_c001_v0001157_t0058_u01734833283598") 2024/12/22 02:08:39 DEBUG : backup/dst/file1: Deleting (id "4_zefd2cda3104debcb9f380e1b_f41963de4df36b607_d20241222_m020611_c001_v7007000_t0000_u01734833171849") 2024/12/22 02:08:39 DEBUG : backup/dst/file1: Deleting (id "4_zefd2cda3104debcb9f380e1b_f119913e8c5a84d7d_d20241222_m020610_c001_v0001182_t0034_u01734833170774") 2024/12/22 02:08:39 DEBUG : data/banana: Deleting (id "4_zefd2cda3104debcb9f380e1b_f4104050ce277d710_d20241222_m020517_c001_v7007000_t0000_u01734833117123") 2024/12/22 02:08:39 DEBUG : data/banana: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f1fa_d20241222_m020514_c001_v0001136_t0050_u01734833114747") 2024/12/22 02:08:39 DEBUG : data/banana: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f1d0_d20241222_m020504_c001_v0001136_t0029_u01734833104981") 2024/12/22 02:08:39 DEBUG : data/potato: Deleting (id "4_zefd2cda3104debcb9f380e1b_f4104050ce277d712_d20241222_m020517_c001_v7007000_t0000_u01734833117306") 2024/12/22 02:08:39 DEBUG : data/potato: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f200_d20241222_m020515_c001_v0001136_t0018_u01734833115065") 2024/12/22 02:08:39 DEBUG : data/potato: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f1de_d20241222_m020507_c001_v0001136_t0031_u01734833107349") 2024/12/22 02:08:39 DEBUG : dst/file1: Deleting (id "4_zefd2cda3104debcb9f380e1b_f408fb784060ab1d1_d20241222_m020805_c001_v7007000_t0000_u01734833285061") 2024/12/22 02:08:39 DEBUG : dst/file1: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f3c6_d20241222_m020804_c001_v0001136_t0050_u01734833284087") 2024/12/22 02:08:39 DEBUG : dst/file1: Deleting (id "4_zefd2cda3104debcb9f380e1b_f408fb784060ab1ce_d20241222_m020803_c001_v7007000_t0000_u01734833283903") 2024/12/22 02:08:39 DEBUG : dst/file1: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f3b8_d20241222_m020800_c001_v0001136_t0000_u01734833280788") 2024/12/22 02:08:39 DEBUG : dst/file1: Deleting (id "4_zefd2cda3104debcb9f380e1b_f41963de4df36b608_d20241222_m020612_c001_v7007000_t0000_u01734833172042") 2024/12/22 02:08:39 DEBUG : dst/file1: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f280_d20241222_m020611_c001_v0001136_t0002_u01734833171174") 2024/12/22 02:08:39 DEBUG : dst/file1: Deleting (id "4_zefd2cda3104debcb9f380e1b_f41963de4df36b605_d20241222_m020610_c001_v7007000_t0000_u01734833170977") 2024/12/22 02:08:39 DEBUG : dst/file1: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f27a_d20241222_m020607_c001_v0001136_t0027_u01734833167792") 2024/12/22 02:08:39 DEBUG : dst/one: Deleting (id "4_zefd2cda3104debcb9f380e1b_f41963de4df36b633_d20241222_m020640_c001_v7007000_t0000_u01734833200349") 2024/12/22 02:08:39 DEBUG : dst/one: Deleting (id "4_zefd2cda3104debcb9f380e1b_f10729d5310de815e_d20241222_m020635_c001_v0001182_t0009_u01734833195053") 2024/12/22 02:08:39 DEBUG : dst/one: Deleting (id "4_zefd2cda3104debcb9f380e1b_f41568191abc222cb_d20241222_m020634_c001_v7007000_t0000_u01734833194833") 2024/12/22 02:08:40 DEBUG : dst/one: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f2b0_d20241222_m020630_c001_v0001136_t0053_u01734833190798") 2024/12/22 02:08:40 DEBUG : dst/one: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11539eb924c8099b_d20241222_m020629_c001_v0001041_t0035_u01734833189850") 2024/12/22 02:08:40 DEBUG : dst/one: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11539eb924c80999_d20241222_m020628_c001_v0001041_t0006_u01734833188787") 2024/12/22 02:08:40 DEBUG : dst/one: Deleting (id "4_zefd2cda3104debcb9f380e1b_f41963de4df36b616_d20241222_m020624_c001_v7007000_t0000_u01734833184321") 2024/12/22 02:08:40 DEBUG : dst/one: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f298_d20241222_m020618_c001_v0001136_t0020_u01734833178029") 2024/12/22 02:08:40 DEBUG : dst/one: Deleting (id "4_zefd2cda3104debcb9f380e1b_f102a1c42aa9c8d01_d20241222_m020617_c001_v0001177_t0044_u01734833177251") 2024/12/22 02:08:40 DEBUG : dst/one: Deleting (id "4_zefd2cda3104debcb9f380e1b_f102a1c42aa9c8cf7_d20241222_m020616_c001_v0001177_t0002_u01734833176311") 2024/12/22 02:08:40 DEBUG : dst/three: Deleting (id "4_zefd2cda3104debcb9f380e1b_f41963de4df36b634_d20241222_m020640_c001_v7007000_t0000_u01734833200535") 2024/12/22 02:08:40 DEBUG : dst/three: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11539eb924c809a5_d20241222_m020638_c001_v0001041_t0016_u01734833198784") 2024/12/22 02:08:40 DEBUG : dst/two: Deleting (id "4_zefd2cda3104debcb9f380e1b_f41963de4df36b635_d20241222_m020640_c001_v7007000_t0000_u01734833200726") 2024/12/22 02:08:40 DEBUG : dst/two: Deleting (id "4_zefd2cda3104debcb9f380e1b_f105e7ec756135921_d20241222_m020636_c001_v0001183_t0056_u01734833196586") 2024/12/22 02:08:40 DEBUG : dst/two: Deleting (id "4_zefd2cda3104debcb9f380e1b_f41963de4df36b617_d20241222_m020624_c001_v7007000_t0000_u01734833184504") 2024/12/22 02:08:40 DEBUG : dst/two: Deleting (id "4_zefd2cda3104debcb9f380e1b_f100cc0c2adafac57_d20241222_m020623_c001_v0001179_t0013_u01734833183395") 2024/12/22 02:08:40 DEBUG : empty space: Deleting (id "4_zefd2cda3104debcb9f380e1b_f410cb332e5ae02f2_d20241222_m020832_c001_v7007000_t0000_u01734833312075") 2024/12/22 02:08:40 DEBUG : empty space: Deleting (id "4_zefd2cda3104debcb9f380e1b_f110e0ae2f8dd80d8_d20241222_m020830_c001_v0001183_t0014_u01734833310445") 2024/12/22 02:08:40 DEBUG : empty space: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f423_d20241222_m020828_c001_v0001136_t0030_u01734833308577") 2024/12/22 02:08:40 DEBUG : empty space: Deleting (id "4_zefd2cda3104debcb9f380e1b_f402f2ad1fbf97d4e_d20241222_m020722_c001_v7007000_t0000_u01734833242322") 2024/12/22 02:08:40 DEBUG : empty space: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f342_d20241222_m020721_c001_v0001136_t0018_u01734833241043") 2024/12/22 02:08:41 DEBUG : empty space: Deleting (id "4_zefd2cda3104debcb9f380e1b_f402f2ad1fbf97d4c_d20241222_m020720_c001_v7007000_t0000_u01734833240166") 2024/12/22 02:08:41 DEBUG : empty space: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f338_d20241222_m020718_c001_v0001136_t0050_u01734833238830") 2024/12/22 02:08:41 DEBUG : empty space: Deleting (id "4_zefd2cda3104debcb9f380e1b_f402f2ad1fbf97d4a_d20241222_m020717_c001_v7007000_t0000_u01734833237745") 2024/12/22 02:08:41 DEBUG : empty space: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f32c_d20241222_m020716_c001_v0001136_t0058_u01734833236911") 2024/12/22 02:08:41 DEBUG : empty space: Deleting (id "4_zefd2cda3104debcb9f380e1b_f402f2ad1fbf97d47_d20241222_m020716_c001_v7007000_t0000_u01734833236055") 2024/12/22 02:08:41 DEBUG : empty space: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f320_d20241222_m020714_c001_v0001136_t0036_u01734833234385") 2024/12/22 02:08:41 DEBUG : empty space: Deleting (id "4_zefd2cda3104debcb9f380e1b_f402f2ad1fbf97d45_d20241222_m020713_c001_v7007000_t0000_u01734833233477") 2024/12/22 02:08:41 DEBUG : empty space: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f316_d20241222_m020712_c001_v0001136_t0033_u01734833232457") 2024/12/22 02:08:41 DEBUG : empty space: Deleting (id "4_zefd2cda3104debcb9f380e1b_f4104050ce277d6fa_d20241222_m020458_c001_v7007000_t0000_u01734833098952") 2024/12/22 02:08:41 DEBUG : empty space: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f1b4_d20241222_m020456_c001_v0001136_t0001_u01734833096147") 2024/12/22 02:08:41 DEBUG : empty space: Deleting (id "4_zefd2cda3104debcb9f380e1b_f4104050ce277d6e8_d20241222_m020454_c001_v7007000_t0000_u01734833094387") 2024/12/22 02:08:41 DEBUG : empty space: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f1a0_d20241222_m020450_c001_v0001136_t0043_u01734833090472") 2024/12/22 02:08:41 DEBUG : empty space: Deleting (id "4_zefd2cda3104debcb9f380e1b_f4104050ce277d6d9_d20241222_m020447_c001_v7007000_t0000_u01734833087904") 2024/12/22 02:08:41 DEBUG : empty space: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f18c_d20241222_m020444_c001_v0001136_t0035_u01734833084947") 2024/12/22 02:08:41 DEBUG : file1: Deleting (id "4_zefd2cda3104debcb9f380e1b_f408fb784060ab1cd_d20241222_m020759_c001_v7007000_t0000_u01734833279680") 2024/12/22 02:08:41 DEBUG : file1: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f3b4_d20241222_m020758_c001_v0001136_t0028_u01734833278648") 2024/12/22 02:08:41 DEBUG : file1: Deleting (id "4_zefd2cda3104debcb9f380e1b_f408fb784060ab1c7_d20241222_m020755_c001_v7007000_t0000_u01734833275441") 2024/12/22 02:08:41 DEBUG : file1: Deleting (id "4_zefd2cda3104debcb9f380e1b_f41385756d617f3a4_d20241222_m020753_c001_v0001136_t0055_u01734833273613") 2024/12/22 02:08:41 DEBUG : file1: Deleting (id "4_zefd2cda3104debcb9f380e1b_f4053fe083c563409_d20241222_m020741_c001_v7007000_t0000_u01734833261406") 2024/12/22 02:08:41 DEBUG : file1: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f386_d20241222_m020737_c001_v0001136_t0040_u01734833257495") 2024/12/22 02:08:42 DEBUG : file1: Deleting (id "4_zefd2cda3104debcb9f380e1b_f402f2ad1fbf97d41_d20241222_m020709_c001_v7007000_t0000_u01734833229310") 2024/12/22 02:08:42 DEBUG : file1: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f302_d20241222_m020703_c001_v0001136_t0015_u01734833223050") 2024/12/22 02:08:42 DEBUG : file1: Deleting (id "4_zefd2cda3104debcb9f380e1b_f402f2ad1fbf97d3e_d20241222_m020702_c001_v7007000_t0000_u01734833222498") 2024/12/22 02:08:42 DEBUG : file1: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f2ee_d20241222_m020659_c001_v0001136_t0004_u01734833219462") 2024/12/22 02:08:42 DEBUG : file2: Deleting (id "4_zefd2cda3104debcb9f380e1b_f408fb784060ab1c8_d20241222_m020755_c001_v7007000_t0000_u01734833275643") 2024/12/22 02:08:42 DEBUG : file2: Deleting (id "4_zefd2cda3104debcb9f380e1b_f41385756d617f3aa_d20241222_m020754_c001_v0001136_t0055_u01734833274890") 2024/12/22 02:08:42 DEBUG : file2: Deleting (id "4_zefd2cda3104debcb9f380e1b_f4053fe083c56340a_d20241222_m020741_c001_v7007000_t0000_u01734833261591") 2024/12/22 02:08:42 DEBUG : file2: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f388_d20241222_m020737_c001_v0001136_t0039_u01734833257827") 2024/12/22 02:08:42 DEBUG : filename.txt: Deleting (id "4_zefd2cda3104debcb9f380e1b_f408fb784060ab1c9_d20241222_m020755_c001_v7007000_t0000_u01734833275825") 2024/12/22 02:08:42 DEBUG : filename.txt: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f3a6_d20241222_m020754_c001_v0001136_t0001_u01734833274165") 2024/12/22 02:08:42 DEBUG : headerfilename.txt: Deleting (id "4_zefd2cda3104debcb9f380e1b_f408fb784060ab1ca_d20241222_m020756_c001_v7007000_t0000_u01734833276008") 2024/12/22 02:08:42 DEBUG : headerfilename.txt: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f3a8_d20241222_m020754_c001_v0001136_t0045_u01734833274516") 2024/12/22 02:08:42 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_zefd2cda3104debcb9f380e1b_f410cb332e5ae02d6_d20241222_m020825_c001_v7007000_t0000_u01734833305001") 2024/12/22 02:08:42 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f40f_d20241222_m020823_c001_v0001136_t0056_u01734833303818") 2024/12/22 02:08:42 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_zefd2cda3104debcb9f380e1b_f410cb332e5ae02d1_d20241222_m020822_c001_v7007000_t0000_u01734833302628") 2024/12/22 02:08:42 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f409_d20241222_m020821_c001_v0001136_t0030_u01734833301246") 2024/12/22 02:08:42 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_zefd2cda3104debcb9f380e1b_f410cb332e5ae02d9_d20241222_m020825_c001_v7007000_t0000_u01734833305184") 2024/12/22 02:08:42 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f40d_d20241222_m020823_c001_v0001136_t0010_u01734833303413") 2024/12/22 02:08:42 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_zefd2cda3104debcb9f380e1b_f410cb332e5ae02d3_d20241222_m020822_c001_v7007000_t0000_u01734833302856") 2024/12/22 02:08:42 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f405_d20241222_m020820_c001_v0001136_t0021_u01734833300694") 2024/12/22 02:08:43 DEBUG : large: Deleting (id "4_zefd2cda3104debcb9f380e1b_f402f2ad1fbf97d5a_d20241222_m020734_c001_v7007000_t0000_u01734833254499") 2024/12/22 02:08:43 DEBUG : large: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f37e_d20241222_m020733_c001_v0001136_t0020_u01734833253014") 2024/12/22 02:08:43 DEBUG : large: Deleting (id "4_zefd2cda3104debcb9f380e1b_f4053fe083c562c13_d20241222_m020731_c001_v7007000_t0000_u01734833251618") 2024/12/22 02:08:43 DEBUG : large: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f374_d20241222_m020730_c001_v0001136_t0024_u01734833250117") 2024/12/22 02:08:43 DEBUG : large: Deleting (id "4_zefd2cda3104debcb9f380e1b_f4053fe083c562053_d20241222_m020728_c001_v7007000_t0000_u01734833248054") 2024/12/22 02:08:43 DEBUG : large: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f35c_d20241222_m020727_c001_v0001136_t0038_u01734833247214") 2024/12/22 02:08:43 DEBUG : large: Deleting (id "4_zefd2cda3104debcb9f380e1b_f4053fe083c562051_d20241222_m020725_c001_v7007000_t0000_u01734833245994") 2024/12/22 02:08:43 DEBUG : large: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f34e_d20241222_m020723_c001_v0001136_t0019_u01734833243878") 2024/12/22 02:08:43 DEBUG : medium: Deleting (id "4_zefd2cda3104debcb9f380e1b_f4053fe083c5633f7_d20241222_m020733_c001_v7007000_t0000_u01734833253929") 2024/12/22 02:08:43 DEBUG : medium: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f37c_d20241222_m020732_c001_v0001136_t0000_u01734833252646") 2024/12/22 02:08:43 DEBUG : medium: Deleting (id "4_zefd2cda3104debcb9f380e1b_f4053fe083c562826_d20241222_m020731_c001_v7007000_t0000_u01734833251069") 2024/12/22 02:08:43 DEBUG : medium: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f36e_d20241222_m020729_c001_v0001136_t0016_u01734833249666") 2024/12/22 02:08:43 DEBUG : medium: Deleting (id "4_zefd2cda3104debcb9f380e1b_f402f2ad1fbf97d55_d20241222_m020728_c001_v7007000_t0000_u01734833248065") 2024/12/22 02:08:43 DEBUG : medium: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f35a_d20241222_m020726_c001_v0001136_t0030_u01734833246723") 2024/12/22 02:08:43 DEBUG : medium: Deleting (id "4_zefd2cda3104debcb9f380e1b_f402f2ad1fbf97d53_d20241222_m020724_c001_v7007000_t0000_u01734833244919") 2024/12/22 02:08:43 DEBUG : medium: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f34a_d20241222_m020723_c001_v0001136_t0043_u01734833243365") 2024/12/22 02:08:43 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_zefd2cda3104debcb9f380e1b_f410cb332e5ae02c9_d20241222_m020817_c001_v7007000_t0000_u01734833297374") 2024/12/22 02:08:43 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f3f7_d20241222_m020816_c001_v0001136_t0042_u01734833296146") 2024/12/22 02:08:43 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_zefd2cda3104debcb9f380e1b_f410cb332e5ae02cb_d20241222_m020817_c001_v7007000_t0000_u01734833297561") 2024/12/22 02:08:44 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f3f5_d20241222_m020815_c001_v0001136_t0027_u01734833295798") 2024/12/22 02:08:44 DEBUG : not-one: Deleting (id "4_zefd2cda3104debcb9f380e1b_f41963de4df36b645_d20241222_m020650_c001_v7007000_t0000_u01734833210215") 2024/12/22 02:08:44 DEBUG : not-one: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f2d6_d20241222_m020648_c001_v0001136_t0040_u01734833208471") 2024/12/22 02:08:44 DEBUG : one: Deleting (id "4_zefd2cda3104debcb9f380e1b_f41963de4df36b641_d20241222_m020649_c001_v7007000_t0000_u01734833209269") 2024/12/22 02:08:44 DEBUG : one: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f2d0_d20241222_m020647_c001_v0001136_t0034_u01734833207364") 2024/12/22 02:08:44 DEBUG : potato1: Deleting (id "4_zefd2cda3104debcb9f380e1b_f410cb332e5ae02dd_d20241222_m020827_c001_v7007000_t0000_u01734833307239") 2024/12/22 02:08:44 DEBUG : potato1: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f41b_d20241222_m020825_c001_v0001136_t0021_u01734833305923") 2024/12/22 02:08:44 DEBUG : potato2: Deleting (id "4_zefd2cda3104debcb9f380e1b_f410cb332e5ae02f4_d20241222_m020832_c001_v7007000_t0000_u01734833312261") 2024/12/22 02:08:44 DEBUG : potato2: Deleting (id "4_zefd2cda3104debcb9f380e1b_f107951806cc37a56_d20241222_m020830_c001_v0001181_t0001_u01734833310965") 2024/12/22 02:08:44 DEBUG : potato2: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f421_d20241222_m020828_c001_v0001136_t0013_u01734833308185") 2024/12/22 02:08:44 DEBUG : potato2: Deleting (id "4_zefd2cda3104debcb9f380e1b_f410cb332e5ae02de_d20241222_m020827_c001_v7007000_t0000_u01734833307444") 2024/12/22 02:08:44 DEBUG : potato2: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f41d_d20241222_m020826_c001_v0001136_t0034_u01734833306513") 2024/12/22 02:08:44 DEBUG : potato2: Deleting (id "4_zefd2cda3104debcb9f380e1b_f402f2ad1fbf97d4f_d20241222_m020722_c001_v7007000_t0000_u01734833242515") 2024/12/22 02:08:44 DEBUG : potato2: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f340_d20241222_m020720_c001_v0001136_t0051_u01734833240743") 2024/12/22 02:08:44 DEBUG : potato2: Deleting (id "4_zefd2cda3104debcb9f380e1b_f402f2ad1fbf97d4d_d20241222_m020720_c001_v7007000_t0000_u01734833240361") 2024/12/22 02:08:44 DEBUG : potato2: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f334_d20241222_m020718_c001_v0001136_t0038_u01734833238295") 2024/12/22 02:08:44 DEBUG : potato2: Deleting (id "4_zefd2cda3104debcb9f380e1b_f402f2ad1fbf97d4b_d20241222_m020717_c001_v7007000_t0000_u01734833237929") 2024/12/22 02:08:44 DEBUG : potato2: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f328_d20241222_m020716_c001_v0001136_t0058_u01734833236612") 2024/12/22 02:08:44 DEBUG : potato2: Deleting (id "4_zefd2cda3104debcb9f380e1b_f402f2ad1fbf97d48_d20241222_m020716_c001_v7007000_t0000_u01734833236244") 2024/12/22 02:08:44 DEBUG : potato2: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f31a_d20241222_m020714_c001_v0001136_t0034_u01734833234030") 2024/12/22 02:08:45 DEBUG : potato2: Deleting (id "4_zefd2cda3104debcb9f380e1b_f402f2ad1fbf97d46_d20241222_m020713_c001_v7007000_t0000_u01734833233662") 2024/12/22 02:08:45 DEBUG : potato2: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f312_d20241222_m020712_c001_v0001136_t0034_u01734833232038") 2024/12/22 02:08:45 DEBUG : potato2: Deleting (id "4_zefd2cda3104debcb9f380e1b_f4104050ce277d6fb_d20241222_m020459_c001_v7007000_t0000_u01734833099135") 2024/12/22 02:08:45 DEBUG : potato2: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f1b6_d20241222_m020456_c001_v0001136_t0029_u01734833096823") 2024/12/22 02:08:45 DEBUG : potato2: Deleting (id "4_zefd2cda3104debcb9f380e1b_f4104050ce277d6e9_d20241222_m020454_c001_v7007000_t0000_u01734833094569") 2024/12/22 02:08:45 DEBUG : potato2: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f1a2_d20241222_m020451_c001_v0001136_t0032_u01734833091331") 2024/12/22 02:08:45 DEBUG : potato2: Deleting (id "4_zefd2cda3104debcb9f380e1b_f4104050ce277d6da_d20241222_m020448_c001_v7007000_t0000_u01734833088097") 2024/12/22 02:08:45 DEBUG : potato2: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f191_d20241222_m020445_c001_v0001136_t0043_u01734833085950") 2024/12/22 02:08:45 DEBUG : remotepotato: Deleting (id "4_zefd2cda3104debcb9f380e1b_f4104050ce277d6fc_d20241222_m020459_c001_v7007000_t0000_u01734833099316") 2024/12/22 02:08:45 DEBUG : remotepotato: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f1b8_d20241222_m020457_c001_v0001136_t0025_u01734833097759") 2024/12/22 02:08:45 DEBUG : remotepotato: Deleting (id "4_zefd2cda3104debcb9f380e1b_f4104050ce277d6ea_d20241222_m020454_c001_v7007000_t0000_u01734833094752") 2024/12/22 02:08:45 DEBUG : remotepotato: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f1a6_d20241222_m020452_c001_v0001136_t0002_u01734833092856") 2024/12/22 02:08:45 DEBUG : remotepotato: Deleting (id "4_zefd2cda3104debcb9f380e1b_f4104050ce277d6db_d20241222_m020448_c001_v7007000_t0000_u01734833088279") 2024/12/22 02:08:45 DEBUG : remotepotato: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f194_d20241222_m020446_c001_v0001136_t0047_u01734833086860") 2024/12/22 02:08:45 DEBUG : rutabaga: Deleting (id "4_zefd2cda3104debcb9f380e1b_f4104050ce277d6fd_d20241222_m020459_c001_v7007000_t0000_u01734833099500") 2024/12/22 02:08:45 DEBUG : rutabaga: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f1b0_d20241222_m020455_c001_v0001136_t0054_u01734833095302") 2024/12/22 02:08:45 DEBUG : rutabaga: Deleting (id "4_zefd2cda3104debcb9f380e1b_f4104050ce277d6eb_d20241222_m020454_c001_v7007000_t0000_u01734833094934") 2024/12/22 02:08:45 DEBUG : rutabaga: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f19c_d20241222_m020448_c001_v0001136_t0059_u01734833088853") 2024/12/22 02:08:46 DEBUG : rutabaga: Deleting (id "4_zefd2cda3104debcb9f380e1b_f4104050ce277d6dc_d20241222_m020448_c001_v7007000_t0000_u01734833088462") 2024/12/22 02:08:46 DEBUG : rutabaga: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f18a_d20241222_m020444_c001_v0001136_t0044_u01734833084059") 2024/12/22 02:08:46 DEBUG : small: Deleting (id "4_zefd2cda3104debcb9f380e1b_f402f2ad1fbf97d59_d20241222_m020733_c001_v7007000_t0000_u01734833253937") 2024/12/22 02:08:46 DEBUG : small: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f37a_d20241222_m020732_c001_v0001136_t0002_u01734833252359") 2024/12/22 02:08:46 DEBUG : small: Deleting (id "4_zefd2cda3104debcb9f380e1b_f402f2ad1fbf97d57_d20241222_m020731_c001_v7007000_t0000_u01734833251060") 2024/12/22 02:08:46 DEBUG : small: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f36c_d20241222_m020729_c001_v0001136_t0045_u01734833249349") 2024/12/22 02:08:46 DEBUG : small: Deleting (id "4_zefd2cda3104debcb9f380e1b_f402f2ad1fbf97d56_d20241222_m020728_c001_v7007000_t0000_u01734833248615") 2024/12/22 02:08:46 DEBUG : small: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f356_d20241222_m020726_c001_v0001136_t0000_u01734833246400") 2024/12/22 02:08:46 DEBUG : small: Deleting (id "4_zefd2cda3104debcb9f380e1b_f4053fe083c56204f_d20241222_m020725_c001_v7007000_t0000_u01734833245307") 2024/12/22 02:08:46 DEBUG : small: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f348_d20241222_m020723_c001_v0001136_t0058_u01734833243072") 2024/12/22 02:08:46 DEBUG : sub dir/hello world: Deleting (id "4_zefd2cda3104debcb9f380e1b_f402f2ad1fbf97d44_d20241222_m020711_c001_v7007000_t0000_u01734833231651") 2024/12/22 02:08:46 DEBUG : sub dir/hello world: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f310_d20241222_m020710_c001_v0001136_t0057_u01734833230770") 2024/12/22 02:08:46 DEBUG : sub dir/hello world: Deleting (id "4_zefd2cda3104debcb9f380e1b_f402f2ad1fbf97d37_d20241222_m020658_c001_v7007000_t0000_u01734833218166") 2024/12/22 02:08:46 DEBUG : sub dir/hello world: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f2e0_d20241222_m020653_c001_v0001136_t0040_u01734833213239") 2024/12/22 02:08:46 DEBUG : sub dir/hello world2: Deleting (id "4_zefd2cda3104debcb9f380e1b_f402f2ad1fbf97d38_d20241222_m020658_c001_v7007000_t0000_u01734833218351") 2024/12/22 02:08:46 DEBUG : sub dir/hello world2: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f2e2_d20241222_m020653_c001_v0001136_t0003_u01734833213586") 2024/12/22 02:08:46 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_zefd2cda3104debcb9f380e1b_f402f2ad1fbf97d39_d20241222_m020658_c001_v7007000_t0000_u01734833218536") 2024/12/22 02:08:46 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f2e4_d20241222_m020653_c001_v0001136_t0015_u01734833213893") 2024/12/22 02:08:46 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_zefd2cda3104debcb9f380e1b_f402f2ad1fbf97d3b_d20241222_m020658_c001_v7007000_t0000_u01734833218720") 2024/12/22 02:08:46 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f2e6_d20241222_m020654_c001_v0001136_t0013_u01734833214250") 2024/12/22 02:08:47 DEBUG : sub dir/potato3: Deleting (id "4_zefd2cda3104debcb9f380e1b_f410cb332e5ae02f6_d20241222_m020832_c001_v7007000_t0000_u01734833312443") 2024/12/22 02:08:47 DEBUG : sub dir/potato3: Deleting (id "4_zefd2cda3104debcb9f380e1b_f102016a445b8bce0_d20241222_m020831_c001_v0001101_t0043_u01734833311396") 2024/12/22 02:08:47 DEBUG : sub dir/potato3: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f427_d20241222_m020829_c001_v0001136_t0056_u01734833309141") 2024/12/22 02:08:47 DEBUG : sub dir/potato3: Deleting (id "4_zefd2cda3104debcb9f380e1b_f402f2ad1fbf97d50_d20241222_m020722_c001_v7007000_t0000_u01734833242702") 2024/12/22 02:08:47 DEBUG : sub dir/potato3: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f344_d20241222_m020721_c001_v0001136_t0055_u01734833241427") 2024/12/22 02:08:47 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_zefd2cda3104debcb9f380e1b_f402f2ad1fbf97d3c_d20241222_m020658_c001_v7007000_t0000_u01734833218903") 2024/12/22 02:08:47 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f2e8_d20241222_m020654_c001_v0001136_t0018_u01734833214592") 2024/12/22 02:08:47 DEBUG : sub/file2: Deleting (id "4_zefd2cda3104debcb9f380e1b_f408fb784060ab1cb_d20241222_m020758_c001_v7007000_t0000_u01734833278081") 2024/12/22 02:08:47 DEBUG : sub/file2: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f3ac_d20241222_m020756_c001_v0001136_t0035_u01734833276561") 2024/12/22 02:08:47 DEBUG : sub/file2: Deleting (id "4_zefd2cda3104debcb9f380e1b_f402f2ad1fbf97d42_d20241222_m020709_c001_v7007000_t0000_u01734833229495") 2024/12/22 02:08:47 DEBUG : sub/file2: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f304_d20241222_m020703_c001_v0001136_t0027_u01734833223581") 2024/12/22 02:08:47 DEBUG : sub/file2: Deleting (id "4_zefd2cda3104debcb9f380e1b_f402f2ad1fbf97d3f_d20241222_m020702_c001_v7007000_t0000_u01734833222681") 2024/12/22 02:08:47 DEBUG : sub/file2: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f2f2_d20241222_m020659_c001_v0001136_t0056_u01734833219789") 2024/12/22 02:08:47 DEBUG : sub/file2: Deleting (id "4_zefd2cda3104debcb9f380e1b_f41963de4df36b601_d20241222_m020607_c001_v7007000_t0000_u01734833167061") 2024/12/22 02:08:47 DEBUG : sub/file2: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f274_d20241222_m020605_c001_v0001136_t0011_u01734833165435") 2024/12/22 02:08:47 DEBUG : test-multithread-abort: Deleting (id "4_zefd2cda3104debcb9f380e1b_f4104050ce277d6d3_d20241222_m020443_c001_v7007000_t0000_u01734833083127") 2024/12/22 02:08:47 DEBUG : test-multithread-abort: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f13c_d20241222_m020406_c001_v0001136_t0007_u01734833046491") 2024/12/22 02:08:47 DEBUG : test-multithread-copy-false-201326591-2: Deleting (id "4_zefd2cda3104debcb9f380e1b_f4053fe083c56063b_d20241222_m015728_c001_v7007000_t0000_u01734832648708") 2024/12/22 02:08:47 DEBUG : test-multithread-copy-false-201326591-2: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617ee87_d20241222_m015649_c001_v0001136_t0019_u01734832609045") 2024/12/22 02:08:48 DEBUG : test-multithread-copy-false-201326592-2: Deleting (id "4_zefd2cda3104debcb9f380e1b_f4009b2585edcf3bc_d20241222_m015812_c001_v7007000_t0000_u01734832692518") 2024/12/22 02:08:48 DEBUG : test-multithread-copy-false-201326592-2: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617eec3_d20241222_m015730_c001_v0001136_t0035_u01734832650698") 2024/12/22 02:08:48 DEBUG : test-multithread-copy-false-201326593-2: Deleting (id "4_zefd2cda3104debcb9f380e1b_f40991aae4add0d6d_d20241222_m015853_c001_v7007000_t0000_u01734832733482") 2024/12/22 02:08:48 DEBUG : test-multithread-copy-false-201326593-2: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617eef9_d20241222_m015814_c001_v0001136_t0014_u01734832694458") 2024/12/22 02:08:48 DEBUG : test-multithread-copy-true-201326591-2: Deleting (id "4_zefd2cda3104debcb9f380e1b_f401e59d573d7e9cb_d20241222_m015924_c001_v7007000_t0000_u01734832764854") 2024/12/22 02:08:48 DEBUG : test-multithread-copy-true-201326591-2: Deleting (id "4_zefd2cda3104debcb9f380e1b_f2053fe083c560829_d20241222_m015908_c001_v0001183_t0038_u01734832748682") 2024/12/22 02:08:48 DEBUG : test-multithread-copy-true-201326592-2: Deleting (id "4_zefd2cda3104debcb9f380e1b_f40991aae4add0db3_d20241222_m020113_c001_v7007000_t0000_u01734832873730") 2024/12/22 02:08:48 DEBUG : test-multithread-copy-true-201326592-2: Deleting (id "4_zefd2cda3104debcb9f380e1b_f201c032894776419_d20241222_m015940_c001_v0001109_t0016_u01734832780023") 2024/12/22 02:08:48 DEBUG : test-multithread-copy-true-201326593-2: Deleting (id "4_zefd2cda3104debcb9f380e1b_f4040db686b91fe4d_d20241222_m020405_c001_v7007000_t0000_u01734833045162") 2024/12/22 02:08:48 DEBUG : test-multithread-copy-true-201326593-2: Deleting (id "4_zefd2cda3104debcb9f380e1b_f207f961c8277a4a8_d20241222_m020201_c001_v0001044_t0002_u01734832921607") 2024/12/22 02:08:48 DEBUG : test.sum: Deleting (id "4_zefd2cda3104debcb9f380e1b_f4104050ce277d713_d20241222_m020517_c001_v7007000_t0000_u01734833117488") 2024/12/22 02:08:48 DEBUG : test.sum: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f202_d20241222_m020515_c001_v0001136_t0024_u01734833115429") 2024/12/22 02:08:48 DEBUG : test.sum: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f1f4_d20241222_m020513_c001_v0001136_t0012_u01734833113447") 2024/12/22 02:08:48 DEBUG : test.sum: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f1f0_d20241222_m020512_c001_v0001136_t0018_u01734833112138") 2024/12/22 02:08:48 DEBUG : test.sum: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f1ec_d20241222_m020510_c001_v0001136_t0006_u01734833110630") 2024/12/22 02:08:48 DEBUG : test.sum: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f1e8_d20241222_m020509_c001_v0001136_t0043_u01734833109189") 2024/12/22 02:08:48 DEBUG : test.sum: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f1e4_d20241222_m020507_c001_v0001136_t0025_u01734833107890") 2024/12/22 02:08:48 DEBUG : test.sum: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f1d6_d20241222_m020505_c001_v0001136_t0004_u01734833105393") 2024/12/22 02:08:48 DEBUG : test.sum: Deleting (id "4_zefd2cda3104debcb9f380e1b_f4104050ce277d701_d20241222_m020501_c001_v7007000_t0000_u01734833101983") 2024/12/22 02:08:49 DEBUG : test.sum: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f1c0_d20241222_m020501_c001_v0001136_t0053_u01734833101059") 2024/12/22 02:08:49 DEBUG : test.sum: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f1be_d20241222_m020459_c001_v0001136_t0036_u01734833099870") 2024/12/22 02:08:49 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_zefd2cda3104debcb9f380e1b_f410cb332e5ae02cd_d20241222_m020819_c001_v7007000_t0000_u01734833299951") 2024/12/22 02:08:49 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f401_d20241222_m020818_c001_v0001136_t0051_u01734833298524") 2024/12/22 02:08:49 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_zefd2cda3104debcb9f380e1b_f410cb332e5ae02ce_d20241222_m020820_c001_v7007000_t0000_u01734833300134") 2024/12/22 02:08:49 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f3ff_d20241222_m020818_c001_v0001136_t0013_u01734833298115") 2024/12/22 02:08:49 DEBUG : zend.txt: Deleting (id "4_zefd2cda3104debcb9f380e1b_f402f2ad1fbf97d3d_d20241222_m020659_c001_v7007000_t0000_u01734833219087") 2024/12/22 02:08:49 DEBUG : zend.txt: Deleting (id "4_zefd2cda3104debcb9f380e1b_f11385756d617f2de_d20241222_m020652_c001_v0001136_t0009_u01734833212822") "./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose" - Finished OK in 12m5.620891962s (try 1/5)