"./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -fast-list" - Starting (try 1/5) 2025/11/02 04:21:36 DEBUG : Creating backend with remote "TestB2:rclone-test-vaqifif7doke" 2025/11/02 04:21:36 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/02 04:21:37 DEBUG : Creating backend with remote "/tmp/rclone1974340866" 2025/11/02 04:21:37 DEBUG : Config file has changed externally - reloading === 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:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" multithread_test.go:137: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:137 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:148 Error: Received unexpected error: expected a FileSseMode but found: '' (400 bad_request) Test: TestMultithreadCopy --- FAIL: TestMultithreadCopy (1.37s) === RUN TestMultithreadCopyAbort run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" multithread_test.go:137: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:137 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:293 Error: Received unexpected error: expected a FileSseMode but found: '' (400 bad_request) Test: TestMultithreadCopyAbort --- FAIL: TestMultithreadCopyAbort (0.54s) === RUN TestSizeDiffers 2025/11/02 04:21:39 DEBUG : a: size = 0 OK 2025/11/02 04:21:39 DEBUG : a: size = 1 (memory) 2025/11/02 04:21:39 DEBUG : a: size = 2 (memory) --- PASS: TestSizeDiffers (0.00s) === RUN TestReOpen === RUN TestReOpen/Normal === RUN TestReOpen/Normal/Basics 2025/11/02 04:21:39 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/ErrorAtStart === RUN TestReOpen/Normal/WithErrors 2025/11/02 04:21:39 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2025/11/02 04:21:39 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2025/11/02 04:21:39 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/Normal/TooManyErrors 2025/11/02 04:21:39 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2025/11/02 04:21:39 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2025/11/02 04:21:39 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2025/11/02 04:21:39 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/Normal/ReadAt 2025/11/02 04:21:39 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/11/02 04:21:39 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/11/02 04:21:39 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/Normal/Seek 2025/11/02 04:21:39 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/11/02 04:21:39 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/11/02 04:21:39 DEBUG : potato: Seek from 5 to 2 === RUN TestReOpen/Normal/AccountRead === RUN TestReOpen/Normal/AccountReadDelay 2025/11/02 04:21:39 DEBUG : potato: Seek from 10 to 0 2025/11/02 04:21:39 DEBUG : potato: Seek from 10 to 0 2025/11/02 04:21:39 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/AccountReadError === RUN TestReOpen/WithRangeOption === RUN TestReOpen/WithRangeOption/Basics 2025/11/02 04:21:39 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/ErrorAtStart === RUN TestReOpen/WithRangeOption/WithErrors 2025/11/02 04:21:39 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2025/11/02 04:21:39 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2025/11/02 04:21:39 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithRangeOption/TooManyErrors 2025/11/02 04:21:39 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2025/11/02 04:21:39 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2025/11/02 04:21:39 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2025/11/02 04:21:39 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithRangeOption/ReadAt 2025/11/02 04:21:39 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/11/02 04:21:39 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/11/02 04:21:39 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/WithRangeOption/Seek 2025/11/02 04:21:39 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/11/02 04:21:39 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/11/02 04:21:39 DEBUG : potato: Seek from 5 to 2 2025/11/02 04:21:39 DEBUG : potato: Seek from 7 to 4 === RUN TestReOpen/WithRangeOption/AccountRead === RUN TestReOpen/WithRangeOption/AccountReadDelay 2025/11/02 04:21:39 DEBUG : potato: Seek from 7 to 0 2025/11/02 04:21:39 DEBUG : potato: Seek from 7 to 0 2025/11/02 04:21:39 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/AccountReadError === RUN TestReOpen/WithSeekOption === RUN TestReOpen/WithSeekOption/Basics 2025/11/02 04:21:39 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/ErrorAtStart === RUN TestReOpen/WithSeekOption/WithErrors 2025/11/02 04:21:39 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2025/11/02 04:21:39 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2025/11/02 04:21:39 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithSeekOption/TooManyErrors 2025/11/02 04:21:39 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2025/11/02 04:21:39 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2025/11/02 04:21:39 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2025/11/02 04:21:39 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithSeekOption/ReadAt 2025/11/02 04:21:39 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/11/02 04:21:39 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/11/02 04:21:39 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/WithSeekOption/Seek 2025/11/02 04:21:39 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/11/02 04:21:39 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/11/02 04:21:39 DEBUG : potato: Seek from 5 to 2 2025/11/02 04:21:39 DEBUG : potato: Seek from 7 to 5 === RUN TestReOpen/WithSeekOption/AccountRead === RUN TestReOpen/WithSeekOption/AccountReadDelay 2025/11/02 04:21:39 DEBUG : potato: Seek from 8 to 0 2025/11/02 04:21:39 DEBUG : potato: Seek from 8 to 0 2025/11/02 04:21:39 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/AccountReadError === RUN TestReOpen/UnknownSize === RUN TestReOpen/UnknownSize/Basics 2025/11/02 04:21:39 DEBUG : potato: Seek from 9 to 0 === RUN TestReOpen/UnknownSize/ErrorAtStart === RUN TestReOpen/UnknownSize/WithErrors 2025/11/02 04:21:39 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2025/11/02 04:21:39 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2025/11/02 04:21:39 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/UnknownSize/TooManyErrors 2025/11/02 04:21:39 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2025/11/02 04:21:39 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2025/11/02 04:21:39 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2025/11/02 04:21:39 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/UnknownSize/ReadAt 2025/11/02 04:21:39 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/11/02 04:21:39 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/11/02 04:21:39 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/UnknownSize/Seek 2025/11/02 04:21:39 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/11/02 04:21:39 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/11/02 04:21:39 DEBUG : potato: Seek from 5 to 2 2025/11/02 04:21:39 DEBUG : potato: Seek from 7 to 6 === RUN TestReOpen/UnknownSize/AccountRead === RUN TestReOpen/UnknownSize/AccountReadDelay 2025/11/02 04:21:39 DEBUG : potato: Seek from 9 to 0 2025/11/02 04:21:39 DEBUG : potato: Seek from 9 to 0 2025/11/02 04:21:39 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/ReadAt (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/ReadAt (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/ReadAt (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/ReadAt (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:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" 2025/11/02 04:21:40 DEBUG : rutabaga: Clearing upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/02 04:21:40 DEBUG : pacer: low level retry 1/1 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/02 04:21:40 DEBUG : pacer: Rate limited, increasing sleep to 1s run.go:286: Retry Put of "rutabaga" to B2 bucket rclone-test-vaqifif7doke: 1/10 (no tomes available (503 service_unavailable): trying again in 1s) 2025/11/02 04:21:42 DEBUG : pacer: Reducing sleep to 500ms 2025/11/02 04:21:43 DEBUG : pacer: Reducing sleep to 250ms 2025/11/02 04:21:44 DEBUG : pacer: Reducing sleep to 125ms === RUN TestCheck/1 === RUN TestCheck/2 2025/11/02 04:21:45 DEBUG : pacer: Reducing sleep to 15.625ms 2025/11/02 04:21:45 DEBUG : pacer: Reducing sleep to 10ms === RUN TestCheck/3 2025/11/02 04:21:45 DEBUG : potato2: Clearing upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/02 04:21:45 DEBUG : pacer: low level retry 1/1 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/02 04:21:45 DEBUG : pacer: Rate limited, increasing sleep to 1s === NAME TestCheck run.go:286: Retry Put of "potato2" to B2 bucket rclone-test-vaqifif7doke: 1/10 (no tomes available (503 service_unavailable): trying again in 1s) 2025/11/02 04:21:47 DEBUG : pacer: Reducing sleep to 500ms 2025/11/02 04:21:49 DEBUG : pacer: Reducing sleep to 250ms 2025/11/02 04:21:49 DEBUG : pacer: Reducing sleep to 125ms === RUN TestCheck/4 === RUN TestCheck/5 2025/11/02 04:21:50 DEBUG : pacer: Reducing sleep to 15.625ms 2025/11/02 04:21:50 DEBUG : pacer: Reducing sleep to 10ms === RUN TestCheck/6 === RUN TestCheck/7 --- PASS: TestCheck (12.83s) --- PASS: TestCheck/1 (0.25s) --- PASS: TestCheck/2 (0.18s) --- PASS: TestCheck/3 (0.18s) --- PASS: TestCheck/4 (0.25s) --- PASS: TestCheck/5 (0.18s) --- PASS: TestCheck/6 (0.18s) --- PASS: TestCheck/7 (0.18s) === RUN TestCheckFsError 2025/11/02 04:21:52 DEBUG : Creating backend with remote "nonexistent" 2025/11/02 04:21:52 DEBUG : Creating backend with remote "nonexistent" 2025/11/02 04:21:52 DEBUG : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: Waiting for checks to finish 2025/11/02 04:21:52 ERROR : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: error reading source root directory: directory not found 2025/11/02 04:21:52 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: 0 differences found 2025/11/02 04:21:52 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: 2 errors while checking --- PASS: TestCheckFsError (0.00s) === RUN TestCheckDownload run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" === RUN TestCheckDownload/1 === RUN TestCheckDownload/2 === RUN TestCheckDownload/3 === RUN TestCheckDownload/4 === RUN TestCheckDownload/5 === RUN TestCheckDownload/6 === RUN TestCheckDownload/7 --- PASS: TestCheckDownload (7.04s) --- PASS: TestCheckDownload/1 (0.73s) --- PASS: TestCheckDownload/2 (0.37s) --- PASS: TestCheckDownload/3 (0.36s) --- PASS: TestCheckDownload/4 (0.55s) --- PASS: TestCheckDownload/5 (0.38s) --- PASS: TestCheckDownload/6 (0.38s) --- PASS: TestCheckDownload/7 (0.38s) === RUN TestCheckSizeOnly run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", 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.70s) --- PASS: TestCheckSizeOnly/1 (0.18s) --- PASS: TestCheckSizeOnly/2 (0.18s) --- PASS: TestCheckSizeOnly/3 (0.18s) --- PASS: TestCheckSizeOnly/4 (0.18s) --- PASS: TestCheckSizeOnly/5 (0.18s) --- PASS: TestCheckSizeOnly/6 (0.18s) --- PASS: TestCheckSizeOnly/7 (0.18s) === RUN TestCheckEqualReaders --- PASS: TestCheckEqualReaders (0.00s) === RUN TestParseSumFile run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" 2025/11/02 04:22:05 NOTICE: test.sum: improperly formatted checksum line 4 2025/11/02 04:22:05 NOTICE: test.sum: improperly formatted checksum line 5 2025/11/02 04:22:05 NOTICE: test.sum: improperly formatted checksum line 6 2025/11/02 04:22:05 NOTICE: test.sum: 2 warning(s) suppressed... 2025/11/02 04:22:05 NOTICE: test.sum: improperly formatted checksum line 4 2025/11/02 04:22:05 NOTICE: test.sum: improperly formatted checksum line 5 2025/11/02 04:22:05 NOTICE: test.sum: improperly formatted checksum line 6 2025/11/02 04:22:05 NOTICE: test.sum: 2 warning(s) suppressed... --- PASS: TestParseSumFile (2.60s) === RUN TestCheckSum run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" 2025/11/02 04:22:06 DEBUG : Creating backend with remote "TestB2:rclone-test-vaqifif7doke/data" check_test.go:350: B2 bucket rclone-test-vaqifif7doke path data lacks md5, skipping --- SKIP: TestCheckSum (1.52s) === RUN TestCheckSumDownload run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" 2025/11/02 04:22:08 DEBUG : Creating backend with remote "TestB2:rclone-test-vaqifif7doke/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.84s) --- PASS: TestCheckSumDownload/subtest1 (1.29s) --- PASS: TestCheckSumDownload/subtest2 (0.73s) --- PASS: TestCheckSumDownload/subtest3 (0.92s) --- PASS: TestCheckSumDownload/subtest4 (0.74s) --- PASS: TestCheckSumDownload/subtest5 (0.75s) --- PASS: TestCheckSumDownload/subtest6 (0.77s) --- PASS: TestCheckSumDownload/subtest7 (0.78s) === RUN TestApplyTransforms 2025/11/02 04:22:21 DEBUG : Creating backend with remote "TestB2:rclone-test-mijejur8niru" 2025/11/02 04:22:21 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/02 04:22:22 DEBUG : Creating backend with remote "/tmp/rclone3763531985" run.go:185: Remote "B2 bucket rclone-test-mijejur8niru", Local "Local file system at /tmp/rclone3763531985", Modify Window "1ms" upper checkfile vs. lower remote (without normalization) 2025/11/02 04:22:24 ERROR : hello, world!: sum not found 2025/11/02 04:22:24 ERROR : HELLO, WORLD!: file not in B2 bucket rclone-test-mijejur8niru 2025/11/02 04:22:24 NOTICE: B2 bucket rclone-test-mijejur8niru: 1 files missing 2025/11/02 04:22:24 NOTICE: 1 hashes missing 2025/11/02 04:22:24 NOTICE: B2 bucket rclone-test-mijejur8niru: 1 differences found 2025/11/02 04:22:24 NOTICE: B2 bucket rclone-test-mijejur8niru: 2 errors while checking upper checkfile vs. lower remote (with normalization) 2025/11/02 04:22:25 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/11/02 04:22:25 NOTICE: B2 bucket rclone-test-mijejur8niru: 0 differences found 2025/11/02 04:22:25 NOTICE: B2 bucket rclone-test-mijejur8niru: 1 matching files 2025/11/02 04:22:25 DEBUG : Creating backend with remote "TestB2:rclone-test-leyinej3rida" 2025/11/02 04:22:25 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/02 04:22:25 DEBUG : Creating backend with remote "/tmp/rclone680527539" run.go:185: Remote "B2 bucket rclone-test-leyinej3rida", Local "Local file system at /tmp/rclone680527539", Modify Window "1ms" lower checkfile vs. upper remote (without normalization) 2025/11/02 04:22:27 ERROR : HELLO, WORLD!: sum not found 2025/11/02 04:22:27 ERROR : hello, world!: file not in B2 bucket rclone-test-leyinej3rida 2025/11/02 04:22:27 NOTICE: B2 bucket rclone-test-leyinej3rida: 1 files missing 2025/11/02 04:22:27 NOTICE: 1 hashes missing 2025/11/02 04:22:27 NOTICE: B2 bucket rclone-test-leyinej3rida: 1 differences found 2025/11/02 04:22:27 NOTICE: B2 bucket rclone-test-leyinej3rida: 2 errors while checking lower checkfile vs. upper remote (with normalization) 2025/11/02 04:22:28 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/11/02 04:22:28 NOTICE: B2 bucket rclone-test-leyinej3rida: 0 differences found 2025/11/02 04:22:28 NOTICE: B2 bucket rclone-test-leyinej3rida: 1 matching files 2025/11/02 04:22:28 DEBUG : Creating backend with remote "TestB2:rclone-test-pevixaz9wote" 2025/11/02 04:22:28 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/02 04:22:29 DEBUG : Creating backend with remote "/tmp/rclone2429088825" run.go:185: Remote "B2 bucket rclone-test-pevixaz9wote", Local "Local file system at /tmp/rclone2429088825", Modify Window "1ms" lower checkfile vs. upperlowermixed remote (without normalization) 2025/11/02 04:22:31 ERROR : HeLlO, wOrLd!: sum not found 2025/11/02 04:22:31 ERROR : hello, world!: file not in B2 bucket rclone-test-pevixaz9wote 2025/11/02 04:22:31 NOTICE: B2 bucket rclone-test-pevixaz9wote: 1 files missing 2025/11/02 04:22:31 NOTICE: 1 hashes missing 2025/11/02 04:22:31 NOTICE: B2 bucket rclone-test-pevixaz9wote: 1 differences found 2025/11/02 04:22:31 NOTICE: B2 bucket rclone-test-pevixaz9wote: 2 errors while checking lower checkfile vs. upperlowermixed remote (with normalization) 2025/11/02 04:22:31 DEBUG : HeLlO, wOrLd!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/11/02 04:22:31 NOTICE: B2 bucket rclone-test-pevixaz9wote: 0 differences found 2025/11/02 04:22:31 NOTICE: B2 bucket rclone-test-pevixaz9wote: 1 matching files 2025/11/02 04:22:31 DEBUG : Creating backend with remote "TestB2:rclone-test-rudoluf7cofo" 2025/11/02 04:22:31 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/02 04:22:32 DEBUG : Creating backend with remote "/tmp/rclone2955549700" run.go:185: Remote "B2 bucket rclone-test-rudoluf7cofo", Local "Local file system at /tmp/rclone2955549700", Modify Window "1ms" upperlowermixed checkfile vs. upper remote (without normalization) 2025/11/02 04:22:34 ERROR : HELLO, WORLD!: sum not found 2025/11/02 04:22:34 ERROR : HeLlO, wOrLd!: file not in B2 bucket rclone-test-rudoluf7cofo 2025/11/02 04:22:34 NOTICE: B2 bucket rclone-test-rudoluf7cofo: 1 files missing 2025/11/02 04:22:34 NOTICE: 1 hashes missing 2025/11/02 04:22:34 NOTICE: B2 bucket rclone-test-rudoluf7cofo: 1 differences found 2025/11/02 04:22:34 NOTICE: B2 bucket rclone-test-rudoluf7cofo: 2 errors while checking upperlowermixed checkfile vs. upper remote (with normalization) 2025/11/02 04:22:35 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/11/02 04:22:35 NOTICE: B2 bucket rclone-test-rudoluf7cofo: 0 differences found 2025/11/02 04:22:35 NOTICE: B2 bucket rclone-test-rudoluf7cofo: 1 matching files 2025/11/02 04:22:35 DEBUG : Creating backend with remote "TestB2:rclone-test-rofotoq3yale" 2025/11/02 04:22:35 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/02 04:22:35 DEBUG : Creating backend with remote "/tmp/rclone2661944803" run.go:185: Remote "B2 bucket rclone-test-rofotoq3yale", Local "Local file system at /tmp/rclone2661944803", Modify Window "1ms" NFD checkfile vs. NFC remote (without normalization) 2025/11/02 04:22:37 ERROR : 測試_Русский___ě_áñ: sum not found 2025/11/02 04:22:37 ERROR : 測試_Русский___ě_áñ: file not in B2 bucket rclone-test-rofotoq3yale 2025/11/02 04:22:37 NOTICE: B2 bucket rclone-test-rofotoq3yale: 1 files missing 2025/11/02 04:22:37 NOTICE: 1 hashes missing 2025/11/02 04:22:37 NOTICE: B2 bucket rclone-test-rofotoq3yale: 1 differences found 2025/11/02 04:22:37 NOTICE: B2 bucket rclone-test-rofotoq3yale: 2 errors while checking NFD checkfile vs. NFC remote (with normalization) 2025/11/02 04:22:38 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/11/02 04:22:38 NOTICE: B2 bucket rclone-test-rofotoq3yale: 0 differences found 2025/11/02 04:22:38 NOTICE: B2 bucket rclone-test-rofotoq3yale: 1 matching files 2025/11/02 04:22:38 DEBUG : Creating backend with remote "TestB2:rclone-test-xuholew4bawi" 2025/11/02 04:22:38 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/02 04:22:39 DEBUG : Creating backend with remote "/tmp/rclone2204013289" run.go:185: Remote "B2 bucket rclone-test-xuholew4bawi", Local "Local file system at /tmp/rclone2204013289", Modify Window "1ms" NFC checkfile vs. NFD remote (without normalization) 2025/11/02 04:22:41 ERROR : 測試_Русский___ě_áñ: sum not found 2025/11/02 04:22:41 ERROR : 測試_Русский___ě_áñ: file not in B2 bucket rclone-test-xuholew4bawi 2025/11/02 04:22:41 NOTICE: B2 bucket rclone-test-xuholew4bawi: 1 files missing 2025/11/02 04:22:41 NOTICE: 1 hashes missing 2025/11/02 04:22:41 NOTICE: B2 bucket rclone-test-xuholew4bawi: 1 differences found 2025/11/02 04:22:41 NOTICE: B2 bucket rclone-test-xuholew4bawi: 2 errors while checking NFC checkfile vs. NFD remote (with normalization) 2025/11/02 04:22:41 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/11/02 04:22:41 NOTICE: B2 bucket rclone-test-xuholew4bawi: 0 differences found 2025/11/02 04:22:41 NOTICE: B2 bucket rclone-test-xuholew4bawi: 1 matching files 2025/11/02 04:22:41 DEBUG : Creating backend with remote "TestB2:rclone-test-pilunuz5jeqo" 2025/11/02 04:22:41 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/02 04:22:42 DEBUG : Creating backend with remote "/tmp/rclone976436362" run.go:185: Remote "B2 bucket rclone-test-pilunuz5jeqo", Local "Local file system at /tmp/rclone976436362", Modify Window "1ms" NFDx2 checkfile vs. both remote (without normalization) 2025/11/02 04:22:44 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2025/11/02 04:22:44 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-pilunuz5jeqo 2025/11/02 04:22:44 NOTICE: B2 bucket rclone-test-pilunuz5jeqo: 1 files missing 2025/11/02 04:22:44 NOTICE: 1 hashes missing 2025/11/02 04:22:44 NOTICE: B2 bucket rclone-test-pilunuz5jeqo: 1 differences found 2025/11/02 04:22:44 NOTICE: B2 bucket rclone-test-pilunuz5jeqo: 2 errors while checking NFDx2 checkfile vs. both remote (with normalization) 2025/11/02 04:22:45 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/11/02 04:22:45 NOTICE: B2 bucket rclone-test-pilunuz5jeqo: 0 differences found 2025/11/02 04:22:45 NOTICE: B2 bucket rclone-test-pilunuz5jeqo: 1 matching files 2025/11/02 04:22:45 DEBUG : Creating backend with remote "TestB2:rclone-test-bebugay4fabu" 2025/11/02 04:22:45 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/02 04:22:46 DEBUG : Creating backend with remote "/tmp/rclone1692905004" 2025/11/02 04:22:46 DEBUG : Config file has changed externally - reloading run.go:185: Remote "B2 bucket rclone-test-bebugay4fabu", Local "Local file system at /tmp/rclone1692905004", Modify Window "1ms" NFCx2 checkfile vs. both remote (without normalization) 2025/11/02 04:22:48 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2025/11/02 04:22:48 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-bebugay4fabu 2025/11/02 04:22:48 NOTICE: B2 bucket rclone-test-bebugay4fabu: 1 files missing 2025/11/02 04:22:48 NOTICE: 1 hashes missing 2025/11/02 04:22:48 NOTICE: B2 bucket rclone-test-bebugay4fabu: 1 differences found 2025/11/02 04:22:48 NOTICE: B2 bucket rclone-test-bebugay4fabu: 2 errors while checking NFCx2 checkfile vs. both remote (with normalization) 2025/11/02 04:22:48 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/11/02 04:22:48 NOTICE: B2 bucket rclone-test-bebugay4fabu: 0 differences found 2025/11/02 04:22:48 NOTICE: B2 bucket rclone-test-bebugay4fabu: 1 matching files 2025/11/02 04:22:48 DEBUG : Creating backend with remote "TestB2:rclone-test-ledimag9zuri" 2025/11/02 04:22:48 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/02 04:22:49 DEBUG : Creating backend with remote "/tmp/rclone2950475336" run.go:185: Remote "B2 bucket rclone-test-ledimag9zuri", Local "Local file system at /tmp/rclone2950475336", Modify Window "1ms" both checkfile vs. NFDx2 remote (without normalization) 2025/11/02 04:22:51 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2025/11/02 04:22:51 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-ledimag9zuri 2025/11/02 04:22:51 NOTICE: B2 bucket rclone-test-ledimag9zuri: 1 files missing 2025/11/02 04:22:51 NOTICE: 1 hashes missing 2025/11/02 04:22:51 NOTICE: B2 bucket rclone-test-ledimag9zuri: 1 differences found 2025/11/02 04:22:51 NOTICE: B2 bucket rclone-test-ledimag9zuri: 2 errors while checking both checkfile vs. NFDx2 remote (with normalization) 2025/11/02 04:22:52 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/11/02 04:22:52 NOTICE: B2 bucket rclone-test-ledimag9zuri: 0 differences found 2025/11/02 04:22:52 NOTICE: B2 bucket rclone-test-ledimag9zuri: 1 matching files 2025/11/02 04:22:52 DEBUG : Creating backend with remote "TestB2:rclone-test-qilaxew3niho" 2025/11/02 04:22:52 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/02 04:22:52 DEBUG : Creating backend with remote "/tmp/rclone3876761266" run.go:185: Remote "B2 bucket rclone-test-qilaxew3niho", Local "Local file system at /tmp/rclone3876761266", Modify Window "1ms" both checkfile vs. NFCx2 remote (without normalization) 2025/11/02 04:22:54 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2025/11/02 04:22:54 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in B2 bucket rclone-test-qilaxew3niho 2025/11/02 04:22:54 NOTICE: B2 bucket rclone-test-qilaxew3niho: 1 files missing 2025/11/02 04:22:54 NOTICE: 1 hashes missing 2025/11/02 04:22:54 NOTICE: B2 bucket rclone-test-qilaxew3niho: 1 differences found 2025/11/02 04:22:54 NOTICE: B2 bucket rclone-test-qilaxew3niho: 2 errors while checking both checkfile vs. NFCx2 remote (with normalization) 2025/11/02 04:22:55 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/11/02 04:22:55 NOTICE: B2 bucket rclone-test-qilaxew3niho: 0 differences found 2025/11/02 04:22:55 NOTICE: B2 bucket rclone-test-qilaxew3niho: 1 matching files 2025/11/02 04:22:55 DEBUG : B2 bucket rclone-test-qilaxew3niho: Purge remote 2025/11/02 04:22:55 INFO : B2 bucket rclone-test-qilaxew3niho: cleaning bucket "rclone-test-qilaxew3niho" of all files 2025/11/02 04:22:55 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_zdf22cd53c0dd4b2b9fa80e1b_f101d0826704d5c23_d20251102_m042254_c001_v0001172_t0029_u01762057374161") 2025/11/02 04:22:56 DEBUG : B2 bucket rclone-test-ledimag9zuri: Purge remote 2025/11/02 04:22:56 INFO : B2 bucket rclone-test-ledimag9zuri: cleaning bucket "rclone-test-ledimag9zuri" of all files 2025/11/02 04:22:56 DEBUG : pacer: low level retry 1/10 (error Post "https://api001.backblazeb2.com/b2api/v1/b2_list_file_versions": EOF) 2025/11/02 04:22:56 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/11/02 04:22:57 DEBUG : pacer: Reducing sleep to 10ms 2025/11/02 04:22:57 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_zdfb2bd83c0dd4b2b9fa80e1b_f1090619337583c49_d20251102_m042250_c001_v0001113_t0051_u01762057370726") 2025/11/02 04:22:57 DEBUG : B2 bucket rclone-test-bebugay4fabu: Purge remote 2025/11/02 04:22:57 INFO : B2 bucket rclone-test-bebugay4fabu: cleaning bucket "rclone-test-bebugay4fabu" of all files 2025/11/02 04:22:58 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_z0f22adc3c0dd4b2b9fa80e1b_f101dc984632c34ed_d20251102_m042247_c001_v0001145_t0031_u01762057367479") 2025/11/02 04:22:58 DEBUG : B2 bucket rclone-test-pilunuz5jeqo: Purge remote 2025/11/02 04:22:58 INFO : B2 bucket rclone-test-pilunuz5jeqo: cleaning bucket "rclone-test-pilunuz5jeqo" of all files 2025/11/02 04:22:59 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Deleting (id "4_zafa29de3c0dd4b2b9fa80e1b_f111ab6d993c4a999_d20251102_m042244_c001_v0001130_t0055_u01762057364125") 2025/11/02 04:22:59 DEBUG : B2 bucket rclone-test-xuholew4bawi: Purge remote 2025/11/02 04:22:59 INFO : B2 bucket rclone-test-xuholew4bawi: cleaning bucket "rclone-test-xuholew4bawi" of all files 2025/11/02 04:23:00 DEBUG : 測試_Русский___ě_áñ: Deleting (id "4_z3f329d13c0dd4b2b9fa80e1b_f111913cf52cf69d2_d20251102_m042240_c001_v0001150_t0056_u01762057360601") 2025/11/02 04:23:01 DEBUG : B2 bucket rclone-test-rofotoq3yale: Purge remote 2025/11/02 04:23:01 INFO : B2 bucket rclone-test-rofotoq3yale: cleaning bucket "rclone-test-rofotoq3yale" of all files 2025/11/02 04:23:01 DEBUG : 測試_Русский___ě_áñ: Deleting (id "4_z6fe28d33c0dd4b2b9fa80e1b_f108390703afae390_d20251102_m042237_c001_v0001157_t0012_u01762057357100") 2025/11/02 04:23:02 DEBUG : B2 bucket rclone-test-rudoluf7cofo: Purge remote 2025/11/02 04:23:02 INFO : B2 bucket rclone-test-rudoluf7cofo: cleaning bucket "rclone-test-rudoluf7cofo" of all files 2025/11/02 04:23:02 DEBUG : HELLO, WORLD!: Deleting (id "4_zaf227d63c0dd4b2b9fa80e1b_f1052d7027a47666a_d20251102_m042233_c001_v0001102_t0001_u01762057353821") 2025/11/02 04:23:03 DEBUG : B2 bucket rclone-test-pevixaz9wote: Purge remote 2025/11/02 04:23:03 INFO : B2 bucket rclone-test-pevixaz9wote: cleaning bucket "rclone-test-pevixaz9wote" of all files 2025/11/02 04:23:03 DEBUG : HeLlO, wOrLd!: Deleting (id "4_z0f426da3c0dd4b2b9fa80e1b_f11318f524d58e0a2_d20251102_m042230_c001_v0001179_t0006_u01762057350601") 2025/11/02 04:23:04 DEBUG : B2 bucket rclone-test-leyinej3rida: Purge remote 2025/11/02 04:23:04 INFO : B2 bucket rclone-test-leyinej3rida: cleaning bucket "rclone-test-leyinej3rida" of all files 2025/11/02 04:23:05 DEBUG : HELLO, WORLD!: Deleting (id "4_zef125dc3c0dd4b2b9fa80e1b_f118a3ec28bb66109_d20251102_m042227_c001_v0001180_t0032_u01762057347243") 2025/11/02 04:23:05 DEBUG : B2 bucket rclone-test-mijejur8niru: Purge remote 2025/11/02 04:23:05 INFO : B2 bucket rclone-test-mijejur8niru: cleaning bucket "rclone-test-mijejur8niru" of all files 2025/11/02 04:23:06 DEBUG : hello, world!: Deleting (id "4_zef024df3c0dd4b2b9fa80e1b_f114e9f1b573fe144_d20251102_m042223_c001_v0001100_t0055_u01762057343875") --- PASS: TestApplyTransforms (45.11s) === RUN TestTruncateString --- PASS: TestTruncateString (0.00s) === RUN TestCopyFile run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" 2025/11/02 04:23:07 DEBUG : file1: Need to transfer - File not found at Destination 2025/11/02 04:23:08 DEBUG : sub/file2: size = 14 OK 2025/11/02 04:23:08 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2025/11/02 04:23:08 INFO : file1: Copied (new) to: sub/file2 2025/11/02 04:23:08 DEBUG : sub/file2: size = 14 OK 2025/11/02 04:23:08 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/02 04:23:08 DEBUG : file1: Unchanged skipping 2025/11/02 04:23:09 DEBUG : B2 bucket rclone-test-vaqifif7doke: don't need to copy/move sub/file2, it is already at target location --- PASS: TestCopyFile (2.85s) === RUN TestCopyLongFile run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" copy_test.go:154: Test only runs on local --- SKIP: TestCopyLongFile (0.36s) === RUN TestCopyFileBackupDir run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" 2025/11/02 04:23:11 DEBUG : Creating backend with remote "TestB2:rclone-test-vaqifif7doke/backup" 2025/11/02 04:23:12 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone1974340866) 2025/11/02 04:23:12 DEBUG : dst/file1: size = 18 (B2 bucket rclone-test-vaqifif7doke) 2025/11/02 04:23:12 DEBUG : dst/file1: Sizes differ 2025/11/02 04:23:13 ERROR : dst/file1: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:23:13 ERROR : dst/file1: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) copy_test.go:186: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy_test.go:186 Error: Received unexpected error: moving to --backup-dir failed: expected a FileSseMode but found: '' (400 bad_request) Test: TestCopyFileBackupDir --- FAIL: TestCopyFileBackupDir (3.68s) === RUN TestCopyFileCompareDest run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" 2025/11/02 04:23:13 DEBUG : Creating backend with remote "TestB2:rclone-test-vaqifif7doke/dst" 2025/11/02 04:23:15 DEBUG : Creating backend with remote "TestB2:rclone-test-vaqifif7doke/CompareDest" 2025/11/02 04:23:16 DEBUG : one: Need to transfer - File not found at Destination 2025/11/02 04:23:18 DEBUG : one: size = 3 OK 2025/11/02 04:23:18 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2025/11/02 04:23:18 INFO : one: Copied (new) 2025/11/02 04:23:18 DEBUG : one: size = 5 (Local file system at /tmp/rclone1974340866) 2025/11/02 04:23:18 DEBUG : one: size = 3 (B2 bucket rclone-test-vaqifif7doke path dst) 2025/11/02 04:23:18 DEBUG : one: Sizes differ 2025/11/02 04:23:19 DEBUG : one: size = 5 OK 2025/11/02 04:23:19 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2025/11/02 04:23:19 INFO : one: Copied (replaced existing) 2025/11/02 04:23:21 DEBUG : one: size = 5 (Local file system at /tmp/rclone1974340866) 2025/11/02 04:23:21 DEBUG : one: size = 3 (B2 bucket rclone-test-vaqifif7doke path dst) 2025/11/02 04:23:21 DEBUG : one: Sizes differ 2025/11/02 04:23:21 DEBUG : one: size = 5 OK 2025/11/02 04:23:21 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/11/02 04:23:21 DEBUG : one: Destination found in --compare-dest, skipping 2025/11/02 04:23:22 DEBUG : two: Need to transfer - File not found at Destination 2025/11/02 04:23:22 DEBUG : two: size = 3 OK 2025/11/02 04:23:22 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/11/02 04:23:22 DEBUG : two: Destination found in --compare-dest, skipping 2025/11/02 04:23:23 DEBUG : two: Need to transfer - File not found at Destination 2025/11/02 04:23:23 DEBUG : two: size = 3 OK 2025/11/02 04:23:23 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/11/02 04:23:23 DEBUG : two: Destination found in --compare-dest, skipping 2025/11/02 04:23:23 DEBUG : two: Need to transfer - File not found at Destination 2025/11/02 04:23:24 DEBUG : two: size = 5 (Local file system at /tmp/rclone1974340866) 2025/11/02 04:23:24 DEBUG : two: size = 3 (B2 bucket rclone-test-vaqifif7doke path CompareDest) 2025/11/02 04:23:24 DEBUG : two: Sizes differ 2025/11/02 04:23:24 DEBUG : two: size = 5 OK 2025/11/02 04:23:24 DEBUG : two: sha1 = a4be8877ad88f3109a6411985a1f359e10e2d930 OK 2025/11/02 04:23:24 INFO : two: Copied (new) --- PASS: TestCopyFileCompareDest (11.74s) === RUN TestCopyFileCopyDest run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" 2025/11/02 04:23:25 DEBUG : Creating backend with remote "TestB2:rclone-test-vaqifif7doke/dst" 2025/11/02 04:23:26 DEBUG : Creating backend with remote "TestB2:rclone-test-vaqifif7doke/CopyDest" 2025/11/02 04:23:28 DEBUG : one: Need to transfer - File not found at Destination 2025/11/02 04:23:29 DEBUG : one: size = 3 OK 2025/11/02 04:23:29 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2025/11/02 04:23:29 INFO : one: Copied (new) 2025/11/02 04:23:30 DEBUG : one: size = 5 (Local file system at /tmp/rclone1974340866) 2025/11/02 04:23:30 DEBUG : one: size = 3 (B2 bucket rclone-test-vaqifif7doke path dst) 2025/11/02 04:23:30 DEBUG : one: Sizes differ 2025/11/02 04:23:31 DEBUG : one: size = 5 OK 2025/11/02 04:23:31 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2025/11/02 04:23:31 INFO : one: Copied (replaced existing) 2025/11/02 04:23:32 DEBUG : Creating backend with remote "TestB2:rclone-test-vaqifif7doke/BackupDir" 2025/11/02 04:23:33 DEBUG : pacer: low level retry 1/10 (error incident id 3894aee67876-395383d4141c6c9d (500 internal_error)) 2025/11/02 04:23:33 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/11/02 04:23:33 DEBUG : pacer: low level retry 2/10 (error incident id 3894aee67876-20c5cbe1c3367f08 (500 internal_error)) 2025/11/02 04:23:33 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2025/11/02 04:23:33 DEBUG : pacer: low level retry 3/10 (error incident id 3894aee67876-b37f8c19a8a32d43 (500 internal_error)) 2025/11/02 04:23:33 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2025/11/02 04:23:33 DEBUG : pacer: low level retry 4/10 (error incident id 3894aee67876-1463ced86a5ec2e4 (500 internal_error)) 2025/11/02 04:23:33 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2025/11/02 04:23:34 DEBUG : pacer: low level retry 5/10 (error incident id 3894aee67876-b4a4fb9c8e449d37 (500 internal_error)) 2025/11/02 04:23:34 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2025/11/02 04:23:34 DEBUG : pacer: low level retry 6/10 (error incident id 3894aee67876-d04e26611aa0c110 (500 internal_error)) 2025/11/02 04:23:34 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2025/11/02 04:23:34 DEBUG : pacer: low level retry 7/10 (error incident id 3894aee67876-2370e07984d4d505 (500 internal_error)) 2025/11/02 04:23:34 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2025/11/02 04:23:35 DEBUG : pacer: low level retry 8/10 (error incident id 3894aee67876-1d0e11c00d51c175 (500 internal_error)) 2025/11/02 04:23:35 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2025/11/02 04:23:36 DEBUG : pacer: low level retry 9/10 (error incident id 3894aee67876-825f5dee8a66f340 (500 internal_error)) 2025/11/02 04:23:36 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2025/11/02 04:23:39 DEBUG : pacer: low level retry 10/10 (error incident id 3894aee67876-4bc6bc712f9f4daa (500 internal_error)) 2025/11/02 04:23:39 DEBUG : pacer: Rate limited, increasing sleep to 10.24s copy_test.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy_test.go:321 Error: Received unexpected error: creating Fs for --backup-dir failed: failed to make fs for --backup-dir "TestB2:rclone-test-vaqifif7doke/BackupDir": failed to authorize account: failed to authenticate: incident id 3894aee67876-4bc6bc712f9f4daa (500 internal_error) Test: TestCopyFileCopyDest --- FAIL: TestCopyFileCopyDest (14.47s) === RUN TestCopyInplace run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" copy_test.go:371: Partial uploads not supported --- SKIP: TestCopyInplace (0.36s) === RUN TestCopyLongFileName run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" copy_test.go:404: Partial uploads not supported --- SKIP: TestCopyLongFileName (0.36s) === RUN TestCopyLongFileNameCollision run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" copy_test.go:437: Partial uploads not supported --- SKIP: TestCopyLongFileNameCollision (0.36s) === RUN TestCopyFileMaxTransfer run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" 2025/11/02 04:23:41 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination 2025/11/02 04:23:42 DEBUG : TestCopyFileMaxTransfer/file1: size = 14 OK 2025/11/02 04:23:42 DEBUG : TestCopyFileMaxTransfer/file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2025/11/02 04:23:42 INFO : TestCopyFileMaxTransfer/file1: Copied (new) 2025/11/02 04:23:42 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination 2025/11/02 04:23:42 ERROR : TestCopyFileMaxTransfer/file2: Failed to copy: Post "https://pod-000-1092-19.backblaze.com/b2api/v1/b2_upload_file/1f42ad03c0cd4b2b9fa80e1b/c001_v0001092_t0041": max transfer limit reached as set by --max-transfer 2025/11/02 04:23:43 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination 2025/11/02 04:23:43 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination 2025/11/02 04:23:44 DEBUG : TestCopyFileMaxTransfer/file4: size = 2062 OK 2025/11/02 04:23:44 DEBUG : TestCopyFileMaxTransfer/file4: sha1 = a11e5ee0c188a05944bf05a0a4feb6e6129adf68 OK 2025/11/02 04:23:44 INFO : TestCopyFileMaxTransfer/file4: Copied (new) --- PASS: TestCopyFileMaxTransfer (4.08s) === RUN TestDeduplicateInteractive run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateInteractive (0.36s) === RUN TestDeduplicateSkip run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSkip (0.36s) === RUN TestDeduplicateSizeOnly run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSizeOnly (0.36s) === RUN TestDeduplicateFirst run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateFirst (0.36s) === RUN TestDeduplicateNewest run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateNewest (0.36s) === RUN TestDeduplicateNewestByHash run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" 2025/11/02 04:23:49 INFO : B2 bucket rclone-test-vaqifif7doke: Looking for duplicate sha1 hashes using newest mode. 2025/11/02 04:23:49 NOTICE: 2120f7a86a735a1b5c5090b5625251f36c303654: Found 3 files with duplicate sha1 hashes 2025/11/02 04:23:50 INFO : one: Deleted 2025/11/02 04:23:50 INFO : also/one: Deleted 2025/11/02 04:23:50 NOTICE: 2120f7a86a735a1b5c5090b5625251f36c303654: Deleted 2 extra copies --- PASS: TestDeduplicateNewestByHash (4.22s) === RUN TestDeduplicateOldest run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateOldest (0.36s) === RUN TestDeduplicateLargest run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateLargest (0.36s) === RUN TestDeduplicateSmallest run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSmallest (0.36s) === RUN TestDeduplicateRename run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateRename (0.36s) === RUN TestMergeDirs run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" dedupe_test.go:256: Can't merge directories --- SKIP: TestMergeDirs (0.36s) === RUN TestListDirSorted run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" 2025/11/02 04:23:56 DEBUG : a.txt: Excluded (Size Filter) 2025/11/02 04:23:56 DEBUG : a.txt: Excluded 2025/11/02 04:23:56 DEBUG : sub dir/hello world: Excluded (Size Filter) 2025/11/02 04:23:56 DEBUG : sub dir/hello world: Excluded 2025/11/02 04:23:56 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2025/11/02 04:23:56 DEBUG : sub dir/hello world2: Excluded 2025/11/02 04:23:57 DEBUG : sub dir/hello world: Excluded (Size Filter) 2025/11/02 04:23:57 DEBUG : sub dir/hello world: Excluded 2025/11/02 04:23:57 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2025/11/02 04:23:57 DEBUG : sub dir/hello world2: Excluded 2025/11/02 04:23:57 DEBUG : sub dir/ignore dir: Excluded 2025/11/02 04:23:58 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSorted (6.96s) === RUN TestListDirSortedFn run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" 2025/11/02 04:24:03 DEBUG : a.txt: Excluded (Size Filter) 2025/11/02 04:24:03 DEBUG : a.txt: Excluded 2025/11/02 04:24:04 DEBUG : sub dir/hello world: Excluded (Size Filter) 2025/11/02 04:24:04 DEBUG : sub dir/hello world: Excluded 2025/11/02 04:24:04 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2025/11/02 04:24:04 DEBUG : sub dir/hello world2: Excluded 2025/11/02 04:24:04 DEBUG : sub dir/hello world: Excluded (Size Filter) 2025/11/02 04:24:04 DEBUG : sub dir/hello world: Excluded 2025/11/02 04:24:04 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2025/11/02 04:24:04 DEBUG : sub dir/hello world2: Excluded 2025/11/02 04:24:04 DEBUG : sub dir/ignore dir: Excluded 2025/11/02 04:24:05 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSortedFn (7.05s) === RUN TestListJSON run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", 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 2025/11/02 04:24:10 ERROR : file1: Failed to read hash: hash type not supported === RUN TestListJSON/Metadata --- PASS: TestListJSON (3.84s) --- PASS: TestListJSON/Default (0.18s) --- PASS: TestListJSON/FilesOnly (0.18s) --- PASS: TestListJSON/DirsOnly (0.18s) --- PASS: TestListJSON/Recurse (0.18s) --- PASS: TestListJSON/SubDir (0.18s) --- PASS: TestListJSON/NoModTime (0.18s) --- PASS: TestListJSON/NoMimeType (0.18s) --- PASS: TestListJSON/ShowHash (0.18s) --- PASS: TestListJSON/HashTypes (0.18s) --- PASS: TestListJSON/Metadata (0.18s) === RUN TestStatJSON run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", 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 2025/11/02 04:24:15 DEBUG : Creating backend with remote "TestB2:rclone-test-vaqifif7doke/notfound" --- PASS: TestStatJSON (6.94s) --- PASS: TestStatJSON/Root (0.18s) --- PASS: TestStatJSON/RootFilesOnly (0.00s) --- PASS: TestStatJSON/RootDirsOnly (0.54s) --- PASS: TestStatJSON/Dir (0.74s) --- PASS: TestStatJSON/DirWithTrailingSlash (0.18s) --- PASS: TestStatJSON/File (0.18s) --- PASS: TestStatJSON/NotFound (0.36s) --- PASS: TestStatJSON/DirFilesOnly (0.18s) --- PASS: TestStatJSON/FileFilesOnly (0.18s) --- PASS: TestStatJSON/NotFoundFilesOnly (0.18s) --- PASS: TestStatJSON/DirDirsOnly (0.18s) --- PASS: TestStatJSON/FileDirsOnly (0.18s) --- PASS: TestStatJSON/NotFoundDirsOnly (0.18s) --- PASS: TestStatJSON/RootNotFound (1.89s) === RUN TestMkdir run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" 2025/11/02 04:24:17 INFO : B2 bucket rclone-test-vaqifif7doke: Making directory 2025/11/02 04:24:18 INFO : B2 bucket rclone-test-vaqifif7doke: Making directory --- PASS: TestMkdir (0.54s) === RUN TestLsd run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" --- PASS: TestLsd (1.87s) === RUN TestLs run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" --- PASS: TestLs (2.02s) === RUN TestLsWithFilesFrom run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" 2025/11/02 04:24:23 DEBUG : empty space: Excluded (FilesFrom Filter) --- PASS: TestLsWithFilesFrom (2.48s) === RUN TestLsLong run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" --- PASS: TestLsLong (1.81s) === RUN TestHashSums run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" === RUN TestHashSums/Sha1 === RUN TestHashSums/Sha1Download --- PASS: TestHashSums (2.31s) --- PASS: TestHashSums/Sha1 (0.18s) --- PASS: TestHashSums/Sha1Download (0.42s) === RUN TestHashSumsWithErrors 2025/11/02 04:24:28 DEBUG : Creating backend with remote ":memory:" 2025/11/02 04:24:28 ERROR : file1: hash unsupported: hash type not supported --- PASS: TestHashSumsWithErrors (0.00s) === RUN TestHashStream 2025/11/02 04:24:28 DEBUG : Creating md5 hash of 0 bytes read from input stream 2025/11/02 04:24:28 DEBUG : Creating md5 hash of 0 bytes read from input stream 2025/11/02 04:24:28 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2025/11/02 04:24:28 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2025/11/02 04:24:28 DEBUG : Creating md5 hash of 12 bytes read from input stream 2025/11/02 04:24:28 DEBUG : Creating md5 hash of 12 bytes read from input stream 2025/11/02 04:24:28 DEBUG : Creating sha1 hash of 12 bytes read from input stream 2025/11/02 04:24:28 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:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" --- PASS: TestCount (2.61s) === RUN TestDelete run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" 2025/11/02 04:24:32 DEBUG : Waiting for deletions to finish 2025/11/02 04:24:33 DEBUG : large: Excluded (Size Filter) 2025/11/02 04:24:33 INFO : medium: Deleted 2025/11/02 04:24:33 INFO : small: Deleted --- PASS: TestDelete (2.68s) === RUN TestMaxDelete run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" 2025/11/02 04:24:35 DEBUG : Waiting for deletions to finish 2025/11/02 04:24:35 ERROR : large: Got fatal error on delete: --max-delete threshold reached 2025/11/02 04:24:36 INFO : small: Deleted 2025/11/02 04:24:36 INFO : medium: Deleted --- PASS: TestMaxDelete (3.06s) === RUN TestMaxDeleteSizeLargeFile run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" 2025/11/02 04:24:38 DEBUG : Waiting for deletions to finish 2025/11/02 04:24:38 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached 2025/11/02 04:24:39 INFO : medium: Deleted 2025/11/02 04:24:39 INFO : small: Deleted --- PASS: TestMaxDeleteSizeLargeFile (2.97s) === RUN TestMaxDeleteSize run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" 2025/11/02 04:24:42 DEBUG : Waiting for deletions to finish 2025/11/02 04:24:42 ERROR : medium: Got fatal error on delete: --max-delete-size threshold reached 2025/11/02 04:24:42 INFO : large: Deleted 2025/11/02 04:24:42 INFO : small: Deleted --- PASS: TestMaxDeleteSize (3.32s) === RUN TestReadFile run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" --- PASS: TestReadFile (2.36s) === RUN TestRetry 2025/11/02 04:24:45 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 1/5 2025/11/02 04:24:45 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 2/5 2025/11/02 04:24:45 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2025/11/02 04:24:45 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2025/11/02 04:24:45 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2025/11/02 04:24:45 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2025/11/02 04:24:45 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms --- PASS: TestRetry (0.05s) === RUN TestCat run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" --- PASS: TestCat (4.46s) === RUN TestPurge 2025/11/02 04:24:50 DEBUG : Creating backend with remote "TestB2:rclone-test-rebuvex4kela" 2025/11/02 04:24:50 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/02 04:24:51 DEBUG : Creating backend with remote "/tmp/rclone115689507" run.go:185: Remote "B2 bucket rclone-test-rebuvex4kela", Local "Local file system at /tmp/rclone115689507", Modify Window "1ms" 2025/11/02 04:24:52 INFO : A2: Making directory 2025/11/02 04:24:52 INFO : A1/B2: Making directory 2025/11/02 04:24:52 INFO : A1/B2/C2: Making directory 2025/11/02 04:24:52 INFO : A1/B1/C3: Making directory 2025/11/02 04:24:52 INFO : A3: Making directory 2025/11/02 04:24:52 INFO : A3/B3: Making directory 2025/11/02 04:24:52 INFO : A3/B3/C4: Making directory fstest.go:250: Filtering empty directory "A2" fstest.go:250: Filtering empty directory "A1/B2" fstest.go:250: Filtering empty directory "A1/B2/C2" fstest.go:250: Filtering empty directory "A1/B1/C3" fstest.go:250: Filtering empty directory "A3" fstest.go:250: Filtering empty directory "A3/B3" fstest.go:250: Filtering empty directory "A3/B3/C4" 2025/11/02 04:24:53 INFO : B2 bucket rclone-test-rebuvex4kela: cleaning bucket "rclone-test-rebuvex4kela" of all files 2025/11/02 04:24:53 DEBUG : A1/B1/C1/one: Deleting (id "4_z1fd29d43c0fd4b2b9fa80e1b_f1021b6e8ebb769f6_d20251102_m042452_c001_v0001157_t0035_u01762057492419") fstest.go:250: Filtering empty directory "A2" fstest.go:250: Filtering empty directory "A1/B2" fstest.go:250: Filtering empty directory "A1/B2/C2" fstest.go:250: Filtering empty directory "A3" fstest.go:250: Filtering empty directory "A3/B3" fstest.go:250: Filtering empty directory "A3/B3/C4" 2025/11/02 04:24:53 INFO : B2 bucket rclone-test-rebuvex4kela: cleaning bucket "rclone-test-rebuvex4kela" of all files 2025/11/02 04:24:54 DEBUG : A1/two: Deleting (id "4_z1fd29d43c0fd4b2b9fa80e1b_f1021b6e8ebb769f8_d20251102_m042452_c001_v0001157_t0041_u01762057492890") 2025/11/02 04:24:54 DEBUG : B2 bucket rclone-test-rebuvex4kela: Purge remote 2025/11/02 04:24:54 INFO : B2 bucket rclone-test-rebuvex4kela: cleaning bucket "rclone-test-rebuvex4kela" of all files 2025/11/02 04:24:55 NOTICE: purge failed: directory not found --- PASS: TestPurge (4.55s) === RUN TestRmdirsNoLeaveRoot run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" 2025/11/02 04:24:55 INFO : A2: Making directory 2025/11/02 04:24:55 INFO : A1/B2: Making directory 2025/11/02 04:24:55 INFO : A1/B2/C2: Making directory 2025/11/02 04:24:55 INFO : A1/B1/C3: Making directory 2025/11/02 04:24:55 INFO : A3: Making directory 2025/11/02 04:24:55 INFO : A3/B3: Making directory 2025/11/02 04:24:55 INFO : A3/B3/C4: Making directory fstest.go:250: Filtering empty directory "A2" fstest.go:250: Filtering empty directory "A1/B2" fstest.go:250: Filtering empty directory "A1/B2/C2" fstest.go:250: Filtering empty directory "A1/B1/C3" fstest.go:250: Filtering empty directory "A3" fstest.go:250: Filtering empty directory "A3/B3" fstest.go:250: Filtering empty directory "A3/B3/C4" 2025/11/02 04:24:56 DEBUG : removing 1 level 3 directories 2025/11/02 04:24:56 INFO : A3/B3/C4: Removing directory fstest.go:250: Filtering empty directory "A2" fstest.go:250: Filtering empty directory "A1/B2" fstest.go:250: Filtering empty directory "A1/B2/C2" fstest.go:250: Filtering empty directory "A1/B1/C3" fstest.go:250: Filtering empty directory "A3" fstest.go:250: Filtering empty directory "A3/B3" 2025/11/02 04:24:58 DEBUG : removing 1 level 0 directories 2025/11/02 04:24:58 INFO : B2 bucket rclone-test-vaqifif7doke: Removing directory 2025/11/02 04:24:59 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.35s) === RUN TestRmdirsLeaveRoot run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" 2025/11/02 04:24:59 INFO : A1: Making directory 2025/11/02 04:24:59 INFO : A1/B1: Making directory 2025/11/02 04:24:59 INFO : A1/B1/C1: Making directory fstest.go:250: Filtering empty directory "A1" fstest.go:250: Filtering empty directory "A1/B1" fstest.go:250: Filtering empty directory "A1/B1/C1" fstest.go:250: Filtering empty directory "A1" --- PASS: TestRmdirsLeaveRoot (0.94s) === RUN TestRmdirsWithFilter run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" 2025/11/02 04:25:00 INFO : A1: Making directory 2025/11/02 04:25:00 INFO : A1/B1: Making directory 2025/11/02 04:25:00 INFO : A1/B1/C1: Making directory fstest.go:250: Filtering empty directory "A1" fstest.go:250: Filtering empty directory "A1/B1" fstest.go:250: Filtering empty directory "A1/B1/C1" fstest.go:250: Filtering empty directory "A1" --- PASS: TestRmdirsWithFilter (0.91s) === RUN TestCopyURL run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" 2025/11/02 04:25:02 DEBUG : filename.txt: File name found in url 2025/11/02 04:25:02 DEBUG : headerfilename.txt: filename found in Content-Disposition header. --- PASS: TestCopyURL (3.30s) === RUN TestCopyURLToWriter --- PASS: TestCopyURLToWriter (0.00s) === RUN TestMoveFile run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" 2025/11/02 04:25:04 DEBUG : file1: Need to transfer - File not found at Destination 2025/11/02 04:25:05 DEBUG : sub/file2: size = 14 OK 2025/11/02 04:25:05 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2025/11/02 04:25:05 INFO : file1: Copied (new) to: sub/file2 2025/11/02 04:25:05 INFO : file1: Deleted 2025/11/02 04:25:05 DEBUG : sub/file2: size = 14 OK 2025/11/02 04:25:05 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/02 04:25:05 DEBUG : file1: Unchanged skipping 2025/11/02 04:25:05 INFO : file1: Deleted 2025/11/02 04:25:05 DEBUG : B2 bucket rclone-test-vaqifif7doke: don't need to copy/move sub/file2, it is already at target location --- PASS: TestMoveFile (2.11s) === RUN TestMoveFileWithIgnoreExisting run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" 2025/11/02 04:25:06 DEBUG : file1: Need to transfer - File not found at Destination 2025/11/02 04:25:07 DEBUG : file1: size = 14 OK 2025/11/02 04:25:07 DEBUG : file1: sha1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2025/11/02 04:25:07 INFO : file1: Copied (new) 2025/11/02 04:25:07 INFO : file1: Deleted 2025/11/02 04:25:07 DEBUG : file1: Destination exists, skipping 2025/11/02 04:25:07 DEBUG : file1: Not removing source file as destination file exists and --ignore-existing is set --- PASS: TestMoveFileWithIgnoreExisting (1.70s) === RUN TestCaseInsensitiveMoveFile run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFile (0.37s) === RUN TestCaseInsensitiveMoveFileDryRun run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFileDryRun (0.37s) === RUN TestMoveFileBackupDir run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" 2025/11/02 04:25:09 DEBUG : Creating backend with remote "TestB2:rclone-test-vaqifif7doke/backup" 2025/11/02 04:25:10 DEBUG : pacer: low level retry 1/10 (error incident id 3894aee67876-88722dad8cf31d97 (500 internal_error)) 2025/11/02 04:25:10 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/11/02 04:25:10 DEBUG : pacer: low level retry 2/10 (error incident id 3894aee67876-d622724e50b4ce72 (500 internal_error)) 2025/11/02 04:25:10 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2025/11/02 04:25:10 DEBUG : pacer: low level retry 3/10 (error incident id 3894aee67876-9669f6439215f0fe (500 internal_error)) 2025/11/02 04:25:10 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2025/11/02 04:25:11 DEBUG : pacer: low level retry 4/10 (error incident id 3894aee67876-778a83bbb3812cb4 (500 internal_error)) 2025/11/02 04:25:11 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2025/11/02 04:25:11 DEBUG : pacer: low level retry 5/10 (error incident id 3894aee67876-2e1567938d7369fa (500 internal_error)) 2025/11/02 04:25:11 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2025/11/02 04:25:11 DEBUG : pacer: low level retry 6/10 (error incident id 3894aee67876-be1a02d5ab81f30e (500 internal_error)) 2025/11/02 04:25:11 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2025/11/02 04:25:11 DEBUG : pacer: low level retry 7/10 (error incident id 3894aee67876-ecbcc92575ce4724 (500 internal_error)) 2025/11/02 04:25:11 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2025/11/02 04:25:12 DEBUG : pacer: low level retry 8/10 (error incident id 3894aee67876-dd79f60b14268247 (500 internal_error)) 2025/11/02 04:25:12 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2025/11/02 04:25:13 DEBUG : pacer: low level retry 9/10 (error incident id 3894aee67876-74f92d816efc957b (500 internal_error)) 2025/11/02 04:25:13 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2025/11/02 04:25:16 DEBUG : pacer: low level retry 10/10 (error incident id 3894aee67876-8ecd1540a5611c47 (500 internal_error)) 2025/11/02 04:25:16 DEBUG : pacer: Rate limited, increasing sleep to 10.24s operations_test.go:1052: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1052 Error: Received unexpected error: creating Fs for --backup-dir failed: failed to make fs for --backup-dir "TestB2:rclone-test-vaqifif7doke/backup": failed to authorize account: failed to authenticate: incident id 3894aee67876-8ecd1540a5611c47 (500 internal_error) Test: TestMoveFileBackupDir --- FAIL: TestMoveFileBackupDir (8.16s) === 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:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" 2025/11/02 04:25:19 INFO : A1/B2: Making directory 2025/11/02 04:25:19 INFO : A1/B1/C3: Making directory fstest.go:250: Filtering empty directory "A1/B2" fstest.go:250: Filtering empty directory "A1/B1/C3" 2025/11/02 04:25:20 ERROR : A1/B1/C1/four: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:25:20 ERROR : A1/B1/C1/four: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:25:20 ERROR : A1/two: Failed to copy: Post "https://api001.backblazeb2.com/b2api/v1/b2_copy_file": expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:25:20 ERROR : A1/two: Not deleting source as copy failed: Post "https://api001.backblazeb2.com/b2api/v1/b2_copy_file": expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:25:20 ERROR : A1/one: Failed to copy: Post "https://api001.backblazeb2.com/b2api/v1/b2_copy_file": expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:25:20 ERROR : A1/B1/C2/five: Failed to copy: Post "https://api001.backblazeb2.com/b2api/v1/b2_copy_file": expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:25:20 ERROR : A1/one: Not deleting source as copy failed: Post "https://api001.backblazeb2.com/b2api/v1/b2_copy_file": expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:25:20 ERROR : A1/B1/C2/five: Not deleting source as copy failed: Post "https://api001.backblazeb2.com/b2api/v1/b2_copy_file": expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:25:20 ERROR : A1/B1/three: Failed to copy: Post "https://api001.backblazeb2.com/b2api/v1/b2_copy_file": expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:25:20 ERROR : A1/B1/three: Not deleting source as copy failed: Post "https://api001.backblazeb2.com/b2api/v1/b2_copy_file": expected a FileSseMode but found: '' (400 bad_request) operations_test.go:1388: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1388 Error: Received unexpected error: RenameDir renames: expected a FileSseMode but found: '' (400 bad_request) Test: TestDirMove --- FAIL: TestDirMove (4.94s) === RUN TestGetFsInfo run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" --- PASS: TestGetFsInfo (0.37s) === RUN TestRcat === RUN TestRcat/withChecksum=false,ignoreChecksum=false run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" 2025/11/02 04:25:22 DEBUG : B2 bucket rclone-test-vaqifif7doke: File to upload is small (34 bytes), uploading instead of streaming 2025/11/02 04:25:23 DEBUG : no_checksum_small_file_from_pipe: size = 34 OK 2025/11/02 04:25:23 DEBUG : no_checksum_small_file_from_pipe: sha1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK 2025/11/02 04:25:23 DEBUG : no_checksum_small_file_from_pipe: Size and sha1 of src and dst objects identical 2025/11/02 04:25:23 DEBUG : no_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2025/11/02 04:25:24 DEBUG : no_checksum_big_file_from_pipe: size = 102401 OK 2025/11/02 04:25:24 DEBUG : no_checksum_big_file_from_pipe: sha1 = d0080f73aa8b494570ad63df1beb25c01ea9eea2 OK 2025/11/02 04:25:24 DEBUG : no_checksum_big_file_from_pipe: Size and sha1 of src and dst objects identical === RUN TestRcat/withChecksum=true,ignoreChecksum=false run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" 2025/11/02 04:25:25 DEBUG : B2 bucket rclone-test-vaqifif7doke: File to upload is small (34 bytes), uploading instead of streaming 2025/11/02 04:25:25 DEBUG : with_checksum_small_file_from_pipe: size = 34 OK 2025/11/02 04:25:25 DEBUG : with_checksum_small_file_from_pipe: sha1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK 2025/11/02 04:25:25 DEBUG : with_checksum_small_file_from_pipe: Size and sha1 of src and dst objects identical 2025/11/02 04:25:25 DEBUG : with_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2025/11/02 04:25:26 DEBUG : with_checksum_big_file_from_pipe: size = 102401 OK 2025/11/02 04:25:26 DEBUG : with_checksum_big_file_from_pipe: sha1 = d0080f73aa8b494570ad63df1beb25c01ea9eea2 OK 2025/11/02 04:25:26 DEBUG : with_checksum_big_file_from_pipe: Size and sha1 of src and dst objects identical === RUN TestRcat/withChecksum=false,ignoreChecksum=true run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" 2025/11/02 04:25:27 DEBUG : B2 bucket rclone-test-vaqifif7doke: File to upload is small (34 bytes), uploading instead of streaming 2025/11/02 04:25:28 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK 2025/11/02 04:25:28 DEBUG : ignore_checksum_small_file_from_pipe: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/02 04:25:28 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2025/11/02 04:25:29 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK 2025/11/02 04:25:29 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:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" 2025/11/02 04:25:30 DEBUG : B2 bucket rclone-test-vaqifif7doke: File to upload is small (34 bytes), uploading instead of streaming 2025/11/02 04:25:30 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK 2025/11/02 04:25:30 DEBUG : ignore_checksum_small_file_from_pipe: Src hash empty - aborting Dst hash check 2025/11/02 04:25:30 DEBUG : ignore_checksum_small_file_from_pipe: Size of src and dst objects identical 2025/11/02 04:25:30 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2025/11/02 04:25:31 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK 2025/11/02 04:25:31 DEBUG : ignore_checksum_big_file_from_pipe: Src hash empty - aborting Dst hash check 2025/11/02 04:25:31 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical --- PASS: TestRcat (10.03s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (2.48s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (2.58s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (2.37s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (2.60s) === RUN TestRcatMetadata run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" operations_test.go:1540: Skipping as destination doesn't support user metadata --- SKIP: TestRcatMetadata (0.37s) === RUN TestRcatSize run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" 2025/11/02 04:25:33 DEBUG : B2 bucket rclone-test-vaqifif7doke: File to upload is small (60 bytes), uploading instead of streaming 2025/11/02 04:25:33 DEBUG : potato2: size = 60 OK 2025/11/02 04:25:33 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2025/11/02 04:25:33 DEBUG : potato2: Size and sha1 of src and dst objects identical --- PASS: TestRcatSize (1.80s) === RUN TestRcatSizeMetadata run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" operations_test.go:1621: Skipping as destination doesn't support user metadata --- SKIP: TestRcatSizeMetadata (0.37s) === RUN TestTouchDir run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" 2025/11/02 04:25:36 DEBUG : B2 bucket rclone-test-vaqifif7doke: Touching "sub dir/potato3" 2025/11/02 04:25:36 DEBUG : B2 bucket rclone-test-vaqifif7doke: Touching "empty space" 2025/11/02 04:25:36 DEBUG : B2 bucket rclone-test-vaqifif7doke: Touching "potato2" 2025/11/02 04:25:37 ERROR : sub dir/potato3: failed to touch: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:25:37 ERROR : empty space: failed to touch: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:25:37 ERROR : potato2: failed to touch: expected a FileSseMode but found: '' (400 bad_request) operations_test.go:1687: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1687 Error: Should be true Test: TestTouchDir --- FAIL: TestTouchDir (3.38s) === RUN TestMkdirMetadata run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" operations_test.go:1711: Skipping test as remote does not support MkdirMetadata --- SKIP: TestMkdirMetadata (0.37s) === RUN TestMkdirModTime run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" operations_test.go:1730: Skipping test as remote does not support DirSetModTime or MkdirMetadata --- SKIP: TestMkdirModTime (0.37s) === RUN TestCopyDirMetadata run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" operations_test.go:1751: Skipping test as remote does not support WriteDirMetadata or MkdirMetadata --- SKIP: TestCopyDirMetadata (0.37s) === RUN TestSetDirModTime run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" operations_test.go:1787: Skipping test as remote does not support DirSetModTime or WriteDirSetModTime --- SKIP: TestSetDirModTime (0.37s) === RUN TestDirsEqual run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" operations_test.go:1831: Skipping test as remote does not support WriteDirMetadata or MkdirMetadata --- SKIP: TestDirsEqual (0.37s) === RUN TestRemoveExisting run.go:185: Remote "B2 bucket rclone-test-vaqifif7doke", Local "Local file system at /tmp/rclone1974340866", Modify Window "1ms" operations_test.go:1900: 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 2025/11/02 04:25:40 DEBUG : B2 bucket rclone-test-vaqifif7doke: Purge remote 2025/11/02 04:25:40 INFO : B2 bucket rclone-test-vaqifif7doke: cleaning bucket "rclone-test-vaqifif7doke" of all files 2025/11/02 04:25:41 DEBUG : A1/B1/C1/four: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f4064ce6046d9759a_d20251102_m042521_c001_v7007000_t0000_u01762057521128") 2025/11/02 04:25:41 DEBUG : A1/B1/C1/four: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207ec2d_d20251102_m042518_c001_v0001092_t0048_u01762057518853") 2025/11/02 04:25:41 DEBUG : A1/B1/C1/one: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f419b66f47b93de9f_d20251102_m042458_c001_v7007000_t0000_u01762057498161") 2025/11/02 04:25:41 DEBUG : A1/B1/C1/one: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207ebec_d20251102_m042455_c001_v0001092_t0030_u01762057495469") 2025/11/02 04:25:41 DEBUG : A1/B1/C2/five: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f4064ce6046d9759b_d20251102_m042521_c001_v7007000_t0000_u01762057521315") 2025/11/02 04:25:41 DEBUG : A1/B1/C2/five: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207ec31_d20251102_m042519_c001_v0001092_t0037_u01762057519323") 2025/11/02 04:25:41 DEBUG : A1/B1/three: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f4064ce6046d9759c_d20251102_m042521_c001_v7007000_t0000_u01762057521501") 2025/11/02 04:25:41 DEBUG : A1/B1/three: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207ec2b_d20251102_m042518_c001_v0001092_t0030_u01762057518475") 2025/11/02 04:25:41 DEBUG : A1/one: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f4064ce6046d9759e_d20251102_m042521_c001_v7007000_t0000_u01762057521687") 2025/11/02 04:25:41 DEBUG : A1/one: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207ec27_d20251102_m042517_c001_v0001092_t0055_u01762057517665") 2025/11/02 04:25:41 DEBUG : A1/two: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f4064ce6046d9759f_d20251102_m042521_c001_v7007000_t0000_u01762057521871") 2025/11/02 04:25:41 DEBUG : A1/two: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207ec29_d20251102_m042518_c001_v0001092_t0017_u01762057518065") 2025/11/02 04:25:41 DEBUG : A1/two: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f419b66f47b93dea1_d20251102_m042458_c001_v7007000_t0000_u01762057498525") 2025/11/02 04:25:41 DEBUG : A1/two: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207ebf0_d20251102_m042456_c001_v0001092_t0059_u01762057496000") 2025/11/02 04:25:41 DEBUG : CompareDest/one: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f4179f6057dfa3970_d20251102_m042324_c001_v7007000_t0000_u01762057404810") 2025/11/02 04:25:41 DEBUG : CompareDest/one: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207eb0e_d20251102_m042320_c001_v0001092_t0001_u01762057400544") 2025/11/02 04:25:41 DEBUG : CompareDest/two: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f4179f6057dfa3971_d20251102_m042324_c001_v7007000_t0000_u01762057404994") 2025/11/02 04:25:41 DEBUG : CompareDest/two: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207eb10_d20251102_m042321_c001_v0001092_t0016_u01762057401709") 2025/11/02 04:25:41 DEBUG : CopyDest/one: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f4064ce6046d973bd_d20251102_m042339_c001_v7007000_t0000_u01762057419649") 2025/11/02 04:25:41 DEBUG : CopyDest/one: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207eb1e_d20251102_m042332_c001_v0001092_t0056_u01762057412001") 2025/11/02 04:25:41 DEBUG : ReadFile: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f4096eeeee1c1a8e0_d20251102_m042445_c001_v7007000_t0000_u01762057485303") 2025/11/02 04:25:42 DEBUG : ReadFile: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207ebd8_d20251102_m042443_c001_v0001092_t0047_u01762057483685") 2025/11/02 04:25:42 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f4064ce6046d973f8_d20251102_m042344_c001_v7007000_t0000_u01762057424819") 2025/11/02 04:25:42 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207eb2a_d20251102_m042342_c001_v0001092_t0049_u01762057422187") 2025/11/02 04:25:42 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f4064ce6046d973fc_d20251102_m042345_c001_v7007000_t0000_u01762057425004") 2025/11/02 04:25:42 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207eb2e_d20251102_m042344_c001_v0001092_t0000_u01762057424136") 2025/11/02 04:25:42 DEBUG : a.txt: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f4064ce6046d97435_d20251102_m042405_c001_v7007000_t0000_u01762057445725") 2025/11/02 04:25:42 DEBUG : a.txt: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207eb54_d20251102_m042400_c001_v0001092_t0019_u01762057440157") 2025/11/02 04:25:42 DEBUG : a.txt: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f4064ce6046d97424_d20251102_m042358_c001_v7007000_t0000_u01762057438682") 2025/11/02 04:25:42 DEBUG : a.txt: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207eb44_d20251102_m042353_c001_v0001092_t0029_u01762057433199") 2025/11/02 04:25:42 DEBUG : also/one: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f4064ce6046d97417_d20251102_m042350_c001_v7007000_t0000_u01762057430237") 2025/11/02 04:25:42 DEBUG : also/one: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207eb36_d20251102_m042347_c001_v0001092_t0032_u01762057427557") 2025/11/02 04:25:42 DEBUG : another: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f4064ce6046d97419_d20251102_m042350_c001_v7007000_t0000_u01762057430833") 2025/11/02 04:25:42 DEBUG : another: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207eb38_d20251102_m042348_c001_v0001092_t0039_u01762057428334") 2025/11/02 04:25:42 DEBUG : data/banana: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f413849d483df3255_d20251102_m042221_c001_v7007000_t0000_u01762057341266") 2025/11/02 04:25:42 DEBUG : data/banana: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207eab5_d20251102_m042218_c001_v0001092_t0041_u01762057338579") 2025/11/02 04:25:42 DEBUG : data/banana: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207ea94_d20251102_m042209_c001_v0001092_t0021_u01762057329255") 2025/11/02 04:25:42 DEBUG : data/potato: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f413849d483df3256_d20251102_m042221_c001_v7007000_t0000_u01762057341450") 2025/11/02 04:25:42 DEBUG : data/potato: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207eabb_d20251102_m042219_c001_v0001092_t0032_u01762057339213") 2025/11/02 04:25:42 DEBUG : data/potato: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207ea9d_d20251102_m042211_c001_v0001092_t0039_u01762057331657") 2025/11/02 04:25:42 DEBUG : dst/file1: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f419682f80416c302_d20251102_m042516_c001_v7007000_t0000_u01762057516915") 2025/11/02 04:25:43 DEBUG : dst/file1: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207ec12_d20251102_m042509_c001_v0001092_t0017_u01762057509154") 2025/11/02 04:25:43 DEBUG : dst/file1: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f4179f6057dfa3960_d20251102_m042313_c001_v7007000_t0000_u01762057393626") 2025/11/02 04:25:43 DEBUG : dst/file1: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207eaf9_d20251102_m042310_c001_v0001092_t0001_u01762057390329") 2025/11/02 04:25:43 DEBUG : dst/one: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f4064ce6046d973be_d20251102_m042339_c001_v7007000_t0000_u01762057419834") 2025/11/02 04:25:43 DEBUG : dst/one: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207eb1c_d20251102_m042331_c001_v0001092_t0001_u01762057411712") 2025/11/02 04:25:43 DEBUG : dst/one: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f10068b0783cc2ede_d20251102_m042330_c001_v0001099_t0034_u01762057410775") 2025/11/02 04:25:43 DEBUG : dst/one: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f10068b0783cc2ed8_d20251102_m042329_c001_v0001099_t0018_u01762057409650") 2025/11/02 04:25:43 DEBUG : dst/one: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f4179f6057dfa3972_d20251102_m042325_c001_v7007000_t0000_u01762057405179") 2025/11/02 04:25:43 DEBUG : dst/one: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207eb0c_d20251102_m042320_c001_v0001092_t0013_u01762057400165") 2025/11/02 04:25:43 DEBUG : dst/one: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1049a158d6b85cd2_d20251102_m042319_c001_v0001145_t0017_u01762057399039") 2025/11/02 04:25:43 DEBUG : dst/one: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1049a158d6b85cce_d20251102_m042317_c001_v0001145_t0025_u01762057397921") 2025/11/02 04:25:43 DEBUG : dst/two: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f4179f6057dfa3973_d20251102_m042325_c001_v7007000_t0000_u01762057405364") 2025/11/02 04:25:43 DEBUG : dst/two: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1049a158d6b85cdb_d20251102_m042324_c001_v0001145_t0052_u01762057404175") 2025/11/02 04:25:43 DEBUG : empty space: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f413849d483df7662_d20251102_m042537_c001_v7007000_t0000_u01762057537795") 2025/11/02 04:25:43 DEBUG : empty space: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207ec5e_d20251102_m042535_c001_v0001092_t0017_u01762057535510") 2025/11/02 04:25:43 DEBUG : empty space: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f41823ea56c32df51_d20251102_m042430_c001_v7007000_t0000_u01762057470905") 2025/11/02 04:25:43 DEBUG : empty space: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207ebac_d20251102_m042429_c001_v0001092_t0043_u01762057469434") 2025/11/02 04:25:43 DEBUG : empty space: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f41823ea56c32df49_d20251102_m042428_c001_v7007000_t0000_u01762057468476") 2025/11/02 04:25:43 DEBUG : empty space: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207eba2_d20251102_m042427_c001_v0001092_t0037_u01762057467144") 2025/11/02 04:25:43 DEBUG : empty space: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f41823ea56c32df40_d20251102_m042426_c001_v7007000_t0000_u01762057466157") 2025/11/02 04:25:44 DEBUG : empty space: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207eb9c_d20251102_m042425_c001_v0001092_t0023_u01762057465211") 2025/11/02 04:25:44 DEBUG : empty space: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f41823ea56c32df34_d20251102_m042424_c001_v7007000_t0000_u01762057464350") 2025/11/02 04:25:44 DEBUG : empty space: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207eb90_d20251102_m042422_c001_v0001092_t0022_u01762057462814") 2025/11/02 04:25:44 DEBUG : empty space: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f41823ea56c32df20_d20251102_m042421_c001_v7007000_t0000_u01762057461859") 2025/11/02 04:25:44 DEBUG : empty space: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207eb88_d20251102_m042420_c001_v0001092_t0032_u01762057460880") 2025/11/02 04:25:44 DEBUG : empty space: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f413849d483df322e_d20251102_m042203_c001_v7007000_t0000_u01762057323109") 2025/11/02 04:25:44 DEBUG : empty space: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207ea7c_d20251102_m042200_c001_v0001092_t0019_u01762057320229") 2025/11/02 04:25:44 DEBUG : empty space: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f413849d483df3227_d20251102_m042158_c001_v7007000_t0000_u01762057318400") 2025/11/02 04:25:44 DEBUG : empty space: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207ea70_d20251102_m042154_c001_v0001092_t0030_u01762057314085") 2025/11/02 04:25:44 DEBUG : empty space: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f413849d483df3217_d20251102_m042151_c001_v7007000_t0000_u01762057311367") 2025/11/02 04:25:44 DEBUG : empty space: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f11522481d3c11cec_d20251102_m042144_c001_v0001105_t0050_u01762057304686") 2025/11/02 04:25:44 DEBUG : file1: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f419b66f47b93deb5_d20251102_m042508_c001_v7007000_t0000_u01762057508038") 2025/11/02 04:25:44 DEBUG : file1: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207ec0e_d20251102_m042506_c001_v0001092_t0046_u01762057506913") 2025/11/02 04:25:44 DEBUG : file1: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f419b66f47b93dea7_d20251102_m042503_c001_v7007000_t0000_u01762057503685") 2025/11/02 04:25:44 DEBUG : file1: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f4194db6ec207ebfa_d20251102_m042501_c001_v0001092_t0041_u01762057501863") 2025/11/02 04:25:44 DEBUG : file1: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f4096eeeee1c1a8e8_d20251102_m042450_c001_v7007000_t0000_u01762057490000") 2025/11/02 04:25:44 DEBUG : file1: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207ebda_d20251102_m042446_c001_v0001092_t0029_u01762057486097") 2025/11/02 04:25:44 DEBUG : file1: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f41823ea56c32df08_d20251102_m042417_c001_v7007000_t0000_u01762057457433") 2025/11/02 04:25:44 DEBUG : file1: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207eb74_d20251102_m042411_c001_v0001092_t0019_u01762057451043") 2025/11/02 04:25:44 DEBUG : file1: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f4064ce6046d97446_d20251102_m042410_c001_v7007000_t0000_u01762057450487") 2025/11/02 04:25:44 DEBUG : file1: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207eb6e_d20251102_m042407_c001_v0001092_t0029_u01762057447205") 2025/11/02 04:25:45 DEBUG : file2: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f419b66f47b93dea9_d20251102_m042503_c001_v7007000_t0000_u01762057503871") 2025/11/02 04:25:45 DEBUG : file2: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f4194db6ec207ec04_d20251102_m042503_c001_v0001092_t0041_u01762057503132") 2025/11/02 04:25:45 DEBUG : file2: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f4096eeeee1c1a8e9_d20251102_m042450_c001_v7007000_t0000_u01762057490187") 2025/11/02 04:25:45 DEBUG : file2: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207ebdc_d20251102_m042446_c001_v0001092_t0049_u01762057486447") 2025/11/02 04:25:45 DEBUG : filename.txt: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f419b66f47b93deaa_d20251102_m042504_c001_v7007000_t0000_u01762057504056") 2025/11/02 04:25:45 DEBUG : filename.txt: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207ebfe_d20251102_m042502_c001_v0001092_t0048_u01762057502410") 2025/11/02 04:25:45 DEBUG : headerfilename.txt: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f419b66f47b93deac_d20251102_m042504_c001_v7007000_t0000_u01762057504239") 2025/11/02 04:25:45 DEBUG : headerfilename.txt: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207ec00_d20251102_m042502_c001_v0001092_t0009_u01762057502720") 2025/11/02 04:25:45 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f4064ce6046d975be_d20251102_m042532_c001_v7007000_t0000_u01762057532082") 2025/11/02 04:25:45 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207ec4e_d20251102_m042530_c001_v0001092_t0010_u01762057530658") 2025/11/02 04:25:45 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f4064ce6046d975ad_d20251102_m042529_c001_v7007000_t0000_u01762057529488") 2025/11/02 04:25:45 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207ec48_d20251102_m042528_c001_v0001092_t0023_u01762057528195") 2025/11/02 04:25:45 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f4064ce6046d975c2_d20251102_m042532_c001_v7007000_t0000_u01762057532266") 2025/11/02 04:25:45 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207ec4c_d20251102_m042530_c001_v0001092_t0017_u01762057530225") 2025/11/02 04:25:45 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f4064ce6046d975ae_d20251102_m042529_c001_v7007000_t0000_u01762057529672") 2025/11/02 04:25:45 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207ec46_d20251102_m042527_c001_v0001092_t0059_u01762057527855") 2025/11/02 04:25:45 DEBUG : large: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f41823ea56c32df62_d20251102_m042442_c001_v7007000_t0000_u01762057482370") 2025/11/02 04:25:45 DEBUG : large: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207ebd2_d20251102_m042441_c001_v0001092_t0022_u01762057481387") 2025/11/02 04:25:45 DEBUG : large: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f41823ea56c32df60_d20251102_m042439_c001_v7007000_t0000_u01762057479632") 2025/11/02 04:25:45 DEBUG : large: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207ebcc_d20251102_m042438_c001_v0001092_t0000_u01762057478236") 2025/11/02 04:25:45 DEBUG : large: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f4096eeeee1c1a8c4_d20251102_m042436_c001_v7007000_t0000_u01762057476655") 2025/11/02 04:25:45 DEBUG : large: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207ebc2_d20251102_m042435_c001_v0001092_t0018_u01762057475247") 2025/11/02 04:25:46 DEBUG : large: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f41823ea56c32df57_d20251102_m042433_c001_v7007000_t0000_u01762057473960") 2025/11/02 04:25:46 DEBUG : large: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207ebb8_d20251102_m042432_c001_v0001092_t0022_u01762057472419") 2025/11/02 04:25:46 DEBUG : medium: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f4096eeeee1c1a8dc_d20251102_m042442_c001_v7007000_t0000_u01762057482947") 2025/11/02 04:25:46 DEBUG : medium: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207ebd0_d20251102_m042440_c001_v0001092_t0043_u01762057480927") 2025/11/02 04:25:46 DEBUG : medium: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f4096eeeee1c1a8ce_d20251102_m042439_c001_v7007000_t0000_u01762057479067") 2025/11/02 04:25:46 DEBUG : medium: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207ebca_d20251102_m042437_c001_v0001092_t0057_u01762057477875") 2025/11/02 04:25:46 DEBUG : medium: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f4096eeeee1c1a8c2_d20251102_m042436_c001_v7007000_t0000_u01762057476099") 2025/11/02 04:25:46 DEBUG : medium: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207ebc0_d20251102_m042434_c001_v0001092_t0057_u01762057474808") 2025/11/02 04:25:46 DEBUG : medium: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f41823ea56c32df55_d20251102_m042433_c001_v7007000_t0000_u01762057473202") 2025/11/02 04:25:46 DEBUG : medium: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207ebb6_d20251102_m042432_c001_v0001092_t0001_u01762057472040") 2025/11/02 04:25:46 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f4064ce6046d975a5_d20251102_m042524_c001_v7007000_t0000_u01762057524529") 2025/11/02 04:25:46 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207ec3c_d20251102_m042523_c001_v0001092_t0047_u01762057523140") 2025/11/02 04:25:46 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f4064ce6046d975a6_d20251102_m042524_c001_v7007000_t0000_u01762057524713") 2025/11/02 04:25:46 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207ec3a_d20251102_m042522_c001_v0001092_t0044_u01762057522791") 2025/11/02 04:25:46 DEBUG : not-one: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f4064ce6046d9741a_d20251102_m042351_c001_v7007000_t0000_u01762057431017") 2025/11/02 04:25:46 DEBUG : not-one: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207eb3c_d20251102_m042349_c001_v0001092_t0000_u01762057429346") 2025/11/02 04:25:46 DEBUG : one: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f4064ce6046d97416_d20251102_m042350_c001_v7007000_t0000_u01762057430052") 2025/11/02 04:25:46 DEBUG : one: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207eb32_d20251102_m042347_c001_v0001092_t0044_u01762057427174") 2025/11/02 04:25:46 DEBUG : potato1: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f4064ce6046d975d0_d20251102_m042534_c001_v7007000_t0000_u01762057534243") 2025/11/02 04:25:46 DEBUG : potato1: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207ec54_d20251102_m042533_c001_v0001092_t0054_u01762057533006") 2025/11/02 04:25:46 DEBUG : potato2: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f413849d483df7663_d20251102_m042537_c001_v7007000_t0000_u01762057537982") 2025/11/02 04:25:47 DEBUG : potato2: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207ec5c_d20251102_m042535_c001_v0001092_t0042_u01762057535171") 2025/11/02 04:25:47 DEBUG : potato2: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f4064ce6046d975d4_d20251102_m042534_c001_v7007000_t0000_u01762057534431") 2025/11/02 04:25:47 DEBUG : potato2: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207ec56_d20251102_m042533_c001_v0001092_t0003_u01762057533520") 2025/11/02 04:25:47 DEBUG : potato2: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f41823ea56c32df52_d20251102_m042431_c001_v7007000_t0000_u01762057471090") 2025/11/02 04:25:47 DEBUG : potato2: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207eba6_d20251102_m042429_c001_v0001092_t0052_u01762057469031") 2025/11/02 04:25:47 DEBUG : potato2: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f41823ea56c32df4b_d20251102_m042428_c001_v7007000_t0000_u01762057468661") 2025/11/02 04:25:47 DEBUG : potato2: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207eba0_d20251102_m042426_c001_v0001092_t0025_u01762057466714") 2025/11/02 04:25:47 DEBUG : potato2: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f41823ea56c32df44_d20251102_m042426_c001_v7007000_t0000_u01762057466341") 2025/11/02 04:25:47 DEBUG : potato2: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207eb94_d20251102_m042424_c001_v0001092_t0037_u01762057464903") 2025/11/02 04:25:47 DEBUG : potato2: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f41823ea56c32df36_d20251102_m042424_c001_v7007000_t0000_u01762057464534") 2025/11/02 04:25:47 DEBUG : potato2: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207eb8e_d20251102_m042422_c001_v0001092_t0007_u01762057462428") 2025/11/02 04:25:47 DEBUG : potato2: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f41823ea56c32df22_d20251102_m042422_c001_v7007000_t0000_u01762057462049") 2025/11/02 04:25:47 DEBUG : potato2: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207eb84_d20251102_m042420_c001_v0001092_t0029_u01762057460403") 2025/11/02 04:25:47 DEBUG : potato2: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f413849d483df322f_d20251102_m042203_c001_v7007000_t0000_u01762057323294") 2025/11/02 04:25:47 DEBUG : potato2: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207ea80_d20251102_m042201_c001_v0001092_t0051_u01762057321083") 2025/11/02 04:25:47 DEBUG : potato2: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f413849d483df3228_d20251102_m042158_c001_v7007000_t0000_u01762057318591") 2025/11/02 04:25:47 DEBUG : potato2: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207ea72_d20251102_m042155_c001_v0001092_t0057_u01762057315366") 2025/11/02 04:25:47 DEBUG : potato2: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f413849d483df3219_d20251102_m042151_c001_v7007000_t0000_u01762057311552") 2025/11/02 04:25:47 DEBUG : potato2: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207ea64_d20251102_m042149_c001_v0001092_t0012_u01762057309235") 2025/11/02 04:25:48 DEBUG : remotepotato: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f413849d483df3230_d20251102_m042203_c001_v7007000_t0000_u01762057323481") 2025/11/02 04:25:48 DEBUG : remotepotato: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207ea82_d20251102_m042201_c001_v0001092_t0009_u01762057321997") 2025/11/02 04:25:48 DEBUG : remotepotato: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f413849d483df322a_d20251102_m042158_c001_v7007000_t0000_u01762057318778") 2025/11/02 04:25:48 DEBUG : remotepotato: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207ea76_d20251102_m042156_c001_v0001092_t0050_u01762057316881") 2025/11/02 04:25:48 DEBUG : remotepotato: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f413849d483df321a_d20251102_m042151_c001_v7007000_t0000_u01762057311737") 2025/11/02 04:25:48 DEBUG : remotepotato: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207ea68_d20251102_m042150_c001_v0001092_t0035_u01762057310240") 2025/11/02 04:25:48 DEBUG : rutabaga: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f413849d483df3231_d20251102_m042203_c001_v7007000_t0000_u01762057323667") 2025/11/02 04:25:48 DEBUG : rutabaga: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207ea7a_d20251102_m042159_c001_v0001092_t0008_u01762057319334") 2025/11/02 04:25:48 DEBUG : rutabaga: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f413849d483df322b_d20251102_m042158_c001_v7007000_t0000_u01762057318965") 2025/11/02 04:25:48 DEBUG : rutabaga: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207ea6a_d20251102_m042152_c001_v0001092_t0058_u01762057312299") 2025/11/02 04:25:48 DEBUG : rutabaga: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f413849d483df321b_d20251102_m042151_c001_v7007000_t0000_u01762057311925") 2025/11/02 04:25:48 DEBUG : rutabaga: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f11522481d3c11ce9_d20251102_m042143_c001_v0001105_t0004_u01762057303562") 2025/11/02 04:25:48 DEBUG : small: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f4096eeeee1c1a8da_d20251102_m042442_c001_v7007000_t0000_u01762057482382") 2025/11/02 04:25:48 DEBUG : small: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207ebce_d20251102_m042440_c001_v0001092_t0000_u01762057480367") 2025/11/02 04:25:48 DEBUG : small: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f41823ea56c32df5f_d20251102_m042439_c001_v7007000_t0000_u01762057479076") 2025/11/02 04:25:48 DEBUG : small: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207ebc8_d20251102_m042437_c001_v0001092_t0019_u01762057477392") 2025/11/02 04:25:48 DEBUG : small: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f41823ea56c32df5d_d20251102_m042436_c001_v7007000_t0000_u01762057476083") 2025/11/02 04:25:48 DEBUG : small: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207ebbe_d20251102_m042434_c001_v0001092_t0025_u01762057474329") 2025/11/02 04:25:48 DEBUG : small: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f41823ea56c32df56_d20251102_m042433_c001_v7007000_t0000_u01762057473388") 2025/11/02 04:25:48 DEBUG : small: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207ebb4_d20251102_m042431_c001_v0001092_t0028_u01762057471644") 2025/11/02 04:25:49 DEBUG : sub dir/hello world: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f41823ea56c32df17_d20251102_m042420_c001_v7007000_t0000_u01762057460033") 2025/11/02 04:25:49 DEBUG : sub dir/hello world: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207eb80_d20251102_m042418_c001_v0001092_t0043_u01762057458893") 2025/11/02 04:25:49 DEBUG : sub dir/hello world: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f4064ce6046d97436_d20251102_m042405_c001_v7007000_t0000_u01762057445909") 2025/11/02 04:25:49 DEBUG : sub dir/hello world: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207eb58_d20251102_m042401_c001_v0001092_t0026_u01762057441091") 2025/11/02 04:25:49 DEBUG : sub dir/hello world: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f4064ce6046d97425_d20251102_m042358_c001_v7007000_t0000_u01762057438867") 2025/11/02 04:25:49 DEBUG : sub dir/hello world: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207eb48_d20251102_m042354_c001_v0001092_t0038_u01762057434299") 2025/11/02 04:25:49 DEBUG : sub dir/hello world2: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f4064ce6046d97438_d20251102_m042406_c001_v7007000_t0000_u01762057446097") 2025/11/02 04:25:49 DEBUG : sub dir/hello world2: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207eb5a_d20251102_m042401_c001_v0001092_t0014_u01762057441509") 2025/11/02 04:25:49 DEBUG : sub dir/hello world2: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f4064ce6046d97426_d20251102_m042359_c001_v7007000_t0000_u01762057439051") 2025/11/02 04:25:49 DEBUG : sub dir/hello world2: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207eb4c_d20251102_m042354_c001_v0001092_t0045_u01762057434591") 2025/11/02 04:25:49 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f4064ce6046d97439_d20251102_m042406_c001_v7007000_t0000_u01762057446281") 2025/11/02 04:25:49 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207eb5e_d20251102_m042401_c001_v0001092_t0018_u01762057441886") 2025/11/02 04:25:49 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f4064ce6046d97427_d20251102_m042359_c001_v7007000_t0000_u01762057439236") 2025/11/02 04:25:49 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207eb4e_d20251102_m042354_c001_v0001092_t0038_u01762057434966") 2025/11/02 04:25:49 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f4064ce6046d9743a_d20251102_m042406_c001_v7007000_t0000_u01762057446467") 2025/11/02 04:25:49 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207eb62_d20251102_m042402_c001_v0001092_t0010_u01762057442374") 2025/11/02 04:25:49 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f4064ce6046d97429_d20251102_m042359_c001_v7007000_t0000_u01762057439420") 2025/11/02 04:25:49 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207eb50_d20251102_m042355_c001_v0001092_t0019_u01762057435359") 2025/11/02 04:25:49 DEBUG : sub dir/potato3: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f413849d483df7664_d20251102_m042538_c001_v7007000_t0000_u01762057538173") 2025/11/02 04:25:49 DEBUG : sub dir/potato3: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207ec60_d20251102_m042536_c001_v0001092_t0056_u01762057536160") 2025/11/02 04:25:50 DEBUG : sub dir/potato3: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f41823ea56c32df53_d20251102_m042431_c001_v7007000_t0000_u01762057471274") 2025/11/02 04:25:50 DEBUG : sub dir/potato3: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207ebb0_d20251102_m042430_c001_v0001092_t0045_u01762057470015") 2025/11/02 04:25:50 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f4064ce6046d9743b_d20251102_m042406_c001_v7007000_t0000_u01762057446652") 2025/11/02 04:25:50 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207eb66_d20251102_m042402_c001_v0001092_t0042_u01762057442851") 2025/11/02 04:25:50 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f4064ce6046d9742c_d20251102_m042359_c001_v7007000_t0000_u01762057439606") 2025/11/02 04:25:50 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207eb52_d20251102_m042355_c001_v0001092_t0048_u01762057435816") 2025/11/02 04:25:50 DEBUG : sub/file2: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f419b66f47b93deb0_d20251102_m042506_c001_v7007000_t0000_u01762057506350") 2025/11/02 04:25:50 DEBUG : sub/file2: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207ec08_d20251102_m042504_c001_v0001092_t0031_u01762057504801") 2025/11/02 04:25:50 DEBUG : sub/file2: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f41823ea56c32df0a_d20251102_m042417_c001_v7007000_t0000_u01762057457618") 2025/11/02 04:25:50 DEBUG : sub/file2: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207eb76_d20251102_m042411_c001_v0001092_t0037_u01762057451591") 2025/11/02 04:25:50 DEBUG : sub/file2: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f4064ce6046d97447_d20251102_m042410_c001_v7007000_t0000_u01762057450674") 2025/11/02 04:25:50 DEBUG : sub/file2: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207eb72_d20251102_m042407_c001_v0001092_t0032_u01762057447763") 2025/11/02 04:25:50 DEBUG : sub/file2: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f4179f6057dfa3954_d20251102_m042309_c001_v7007000_t0000_u01762057389592") 2025/11/02 04:25:50 DEBUG : sub/file2: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207eaf5_d20251102_m042308_c001_v0001092_t0044_u01762057388025") 2025/11/02 04:25:50 DEBUG : test.sum: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f413849d483df3257_d20251102_m042221_c001_v7007000_t0000_u01762057341635") 2025/11/02 04:25:50 DEBUG : test.sum: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207eabd_d20251102_m042219_c001_v0001092_t0052_u01762057339764") 2025/11/02 04:25:50 DEBUG : test.sum: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207eab1_d20251102_m042217_c001_v0001092_t0004_u01762057337301") 2025/11/02 04:25:50 DEBUG : test.sum: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207eaaf_d20251102_m042216_c001_v0001092_t0051_u01762057336042") 2025/11/02 04:25:50 DEBUG : test.sum: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207eaa7_d20251102_m042214_c001_v0001092_t0000_u01762057334763") 2025/11/02 04:25:50 DEBUG : test.sum: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207eaa3_d20251102_m042213_c001_v0001092_t0053_u01762057333332") 2025/11/02 04:25:51 DEBUG : test.sum: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207ea9f_d20251102_m042212_c001_v0001092_t0002_u01762057332030") 2025/11/02 04:25:51 DEBUG : test.sum: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207ea96_d20251102_m042209_c001_v0001092_t0001_u01762057329768") 2025/11/02 04:25:51 DEBUG : test.sum: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f413849d483df3232_d20251102_m042206_c001_v7007000_t0000_u01762057326238") 2025/11/02 04:25:51 DEBUG : test.sum: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207ea8c_d20251102_m042205_c001_v0001092_t0027_u01762057325215") 2025/11/02 04:25:51 DEBUG : test.sum: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207ea88_d20251102_m042204_c001_v0001092_t0038_u01762057324038") 2025/11/02 04:25:51 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f4064ce6046d975a9_d20251102_m042527_c001_v7007000_t0000_u01762057527111") 2025/11/02 04:25:51 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207ec42_d20251102_m042525_c001_v0001092_t0022_u01762057525746") 2025/11/02 04:25:51 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f4064ce6046d975aa_d20251102_m042527_c001_v7007000_t0000_u01762057527296") 2025/11/02 04:25:51 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207ec40_d20251102_m042525_c001_v0001092_t0015_u01762057525268") 2025/11/02 04:25:51 DEBUG : zend.txt: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f4064ce6046d9743c_d20251102_m042406_c001_v7007000_t0000_u01762057446837") 2025/11/02 04:25:51 DEBUG : zend.txt: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207eb56_d20251102_m042400_c001_v0001092_t0001_u01762057440719") 2025/11/02 04:25:51 DEBUG : zend.txt: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f4064ce6046d9742d_d20251102_m042359_c001_v7007000_t0000_u01762057439790") 2025/11/02 04:25:51 DEBUG : zend.txt: Deleting (id "4_z1f42ad03c0cd4b2b9fa80e1b_f1194db6ec207eb46_d20251102_m042353_c001_v0001092_t0023_u01762057433684") "./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -fast-list" - Finished ERROR in 4m15.664092974s (try 1/5): exit status 1: Failed [TestMultithreadCopy TestMultithreadCopyAbort TestCopyFileBackupDir TestCopyFileCopyDest TestMoveFileBackupDir TestDirMove TestTouchDir]