"./operations.test -test.v -test.timeout 1h0m0s -remote TestCryptDrive: -verbose -fast-list" - Starting (try 1/5) 2026/09/24 04:34:20 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-mibuwin0qufo" 2026/09/24 04:34:20 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/24 04:34:20 DEBUG : Creating backend with remote "TestDrive:crypt/dv3ch684ovo78si0l2skmn1iaecq98ci9h5f9b1qj60jlsi53ulg" 2026/09/24 04:34:21 DEBUG : Creating backend with remote "/tmp/rclone2912110156" === 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:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" multithread_test.go:120: multithread writing not supported --- SKIP: TestMultithreadCopy (0.24s) === RUN TestMultithreadCopyAbort run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" multithread_test.go:120: multithread writing not supported --- SKIP: TestMultithreadCopyAbort (0.25s) === RUN TestMultithreadCopyWriterAtErrors === RUN TestMultithreadCopyWriterAtErrors/open/fatal=false 2026/09/24 04:34:21 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt === RUN TestMultithreadCopyWriterAtErrors/open/fatal=true 2026/09/24 04:34:21 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt === RUN TestMultithreadCopyWriterAtErrors/write/fatal=false 2026/09/24 04:34:21 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2026/09/24 04:34:21 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams 2026/09/24 04:34:21 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting 2026/09/24 04:34:21 DEBUG : file.txt: writing chunk 1 2026/09/24 04:34:21 DEBUG : file.txt: multi-thread copy: chunk 2/4 failed: multi-thread copy: failed to write chunk: disk full 2026/09/24 04:34:21 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting 2026/09/24 04:34:21 DEBUG : file.txt: writing chunk 2 2026/09/24 04:34:21 DEBUG : file.txt: multi-thread copy: chunk 3/4 failed: multi-thread copy: failed to write chunk: disk full 2026/09/24 04:34:21 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting 2026/09/24 04:34:21 DEBUG : file.txt: writing chunk 0 2026/09/24 04:34:21 DEBUG : file.txt: multi-thread copy: chunk 1/4 failed: multi-thread copy: failed to write chunk: disk full 2026/09/24 04:34:21 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit 2026/09/24 04:34:21 DEBUG : file.txt: multi-thread copy: abort failed: multi-thread copy: failed to find temp file when aborting chunk writer: object not found === RUN TestMultithreadCopyWriterAtErrors/write/fatal=true 2026/09/24 04:34:21 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2026/09/24 04:34:21 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams 2026/09/24 04:34:21 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting 2026/09/24 04:34:21 DEBUG : file.txt: writing chunk 1 2026/09/24 04:34:21 DEBUG : file.txt: multi-thread copy: chunk 2/4 failed: multi-thread copy: failed to write chunk: disk full 2026/09/24 04:34:21 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting 2026/09/24 04:34:21 DEBUG : file.txt: writing chunk 2 2026/09/24 04:34:21 DEBUG : file.txt: multi-thread copy: chunk 3/4 failed: multi-thread copy: failed to write chunk: disk full 2026/09/24 04:34:21 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting 2026/09/24 04:34:21 DEBUG : file.txt: writing chunk 0 2026/09/24 04:34:21 DEBUG : file.txt: multi-thread copy: chunk 1/4 failed: multi-thread copy: failed to write chunk: disk full 2026/09/24 04:34:21 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit 2026/09/24 04:34:21 DEBUG : file.txt: multi-thread copy: abort failed: multi-thread copy: failed to find temp file when aborting chunk writer: object not found === RUN TestMultithreadCopyWriterAtErrors/close/fatal=false 2026/09/24 04:34:21 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2026/09/24 04:34:21 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams 2026/09/24 04:34:21 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting 2026/09/24 04:34:21 DEBUG : file.txt: writing chunk 1 2026/09/24 04:34:21 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting 2026/09/24 04:34:21 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 finished 2026/09/24 04:34:21 DEBUG : file.txt: writing chunk 0 2026/09/24 04:34:21 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 finished 2026/09/24 04:34:21 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting 2026/09/24 04:34:21 DEBUG : file.txt: writing chunk 2 2026/09/24 04:34:21 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 starting 2026/09/24 04:34:21 DEBUG : file.txt: writing chunk 3 2026/09/24 04:34:21 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 finished 2026/09/24 04:34:21 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 finished 2026/09/24 04:34:21 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit 2026/09/24 04:34:21 DEBUG : file.txt: multi-thread copy: abort failed: multi-thread copy: failed to find temp file when aborting chunk writer: object not found === RUN TestMultithreadCopyWriterAtErrors/close/fatal=true 2026/09/24 04:34:21 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2026/09/24 04:34:21 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams 2026/09/24 04:34:21 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting 2026/09/24 04:34:21 DEBUG : file.txt: writing chunk 1 2026/09/24 04:34:21 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 finished 2026/09/24 04:34:21 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting 2026/09/24 04:34:21 DEBUG : file.txt: writing chunk 2 2026/09/24 04:34:21 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 finished 2026/09/24 04:34:21 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 starting 2026/09/24 04:34:21 DEBUG : file.txt: writing chunk 3 2026/09/24 04:34:21 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 finished 2026/09/24 04:34:21 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting 2026/09/24 04:34:21 DEBUG : file.txt: writing chunk 0 2026/09/24 04:34:21 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 finished 2026/09/24 04:34:21 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit 2026/09/24 04:34:21 DEBUG : file.txt: multi-thread copy: abort failed: multi-thread copy: failed to find temp file when aborting chunk writer: object not found --- PASS: TestMultithreadCopyWriterAtErrors (0.00s) --- PASS: TestMultithreadCopyWriterAtErrors/open/fatal=false (0.00s) --- PASS: TestMultithreadCopyWriterAtErrors/open/fatal=true (0.00s) --- PASS: TestMultithreadCopyWriterAtErrors/write/fatal=false (0.00s) --- PASS: TestMultithreadCopyWriterAtErrors/write/fatal=true (0.00s) --- PASS: TestMultithreadCopyWriterAtErrors/close/fatal=false (0.00s) --- PASS: TestMultithreadCopyWriterAtErrors/close/fatal=true (0.00s) === RUN TestSizeDiffers 2026/09/24 04:34:21 DEBUG : a: size = 0 OK 2026/09/24 04:34:21 DEBUG : a: size = 1 (memory) 2026/09/24 04:34:21 DEBUG : a: size = 2 (memory) --- PASS: TestSizeDiffers (0.00s) === RUN TestDirTransferEntry --- PASS: TestDirTransferEntry (0.00s) === RUN TestReOpen === RUN TestReOpen/Normal === RUN TestReOpen/Normal/Basics 2026/09/24 04:34:21 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/ErrorAtStart === RUN TestReOpen/Normal/WithErrors 2026/09/24 04:34:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/09/24 04:34:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/09/24 04:34:21 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/Normal/TooManyErrors 2026/09/24 04:34:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/09/24 04:34:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/09/24 04:34:21 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/09/24 04:34:21 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/Normal/ReadAt 2026/09/24 04:34:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/09/24 04:34:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/09/24 04:34:21 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/Normal/Seek 2026/09/24 04:34:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/09/24 04:34:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/09/24 04:34:21 DEBUG : potato: Seek from 5 to 2 === RUN TestReOpen/Normal/AccountRead === RUN TestReOpen/Normal/AccountReadDelay 2026/09/24 04:34:21 DEBUG : potato: Seek from 10 to 0 2026/09/24 04:34:21 DEBUG : potato: Seek from 10 to 0 2026/09/24 04:34:21 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/AccountReadError === RUN TestReOpen/WithRangeOption === RUN TestReOpen/WithRangeOption/Basics 2026/09/24 04:34:21 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/ErrorAtStart === RUN TestReOpen/WithRangeOption/WithErrors 2026/09/24 04:34:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/09/24 04:34:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/09/24 04:34:21 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithRangeOption/TooManyErrors 2026/09/24 04:34:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/09/24 04:34:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/09/24 04:34:21 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/09/24 04:34:21 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithRangeOption/ReadAt 2026/09/24 04:34:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/09/24 04:34:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/09/24 04:34:21 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/WithRangeOption/Seek 2026/09/24 04:34:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/09/24 04:34:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/09/24 04:34:21 DEBUG : potato: Seek from 5 to 2 2026/09/24 04:34:21 DEBUG : potato: Seek from 7 to 4 === RUN TestReOpen/WithRangeOption/AccountRead === RUN TestReOpen/WithRangeOption/AccountReadDelay 2026/09/24 04:34:21 DEBUG : potato: Seek from 7 to 0 2026/09/24 04:34:21 DEBUG : potato: Seek from 7 to 0 2026/09/24 04:34:21 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/AccountReadError === RUN TestReOpen/WithSeekOption === RUN TestReOpen/WithSeekOption/Basics 2026/09/24 04:34:21 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/ErrorAtStart === RUN TestReOpen/WithSeekOption/WithErrors 2026/09/24 04:34:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/09/24 04:34:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/09/24 04:34:21 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithSeekOption/TooManyErrors 2026/09/24 04:34:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/09/24 04:34:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/09/24 04:34:21 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/09/24 04:34:21 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithSeekOption/ReadAt 2026/09/24 04:34:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/09/24 04:34:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/09/24 04:34:21 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/WithSeekOption/Seek 2026/09/24 04:34:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/09/24 04:34:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/09/24 04:34:21 DEBUG : potato: Seek from 5 to 2 2026/09/24 04:34:21 DEBUG : potato: Seek from 7 to 5 === RUN TestReOpen/WithSeekOption/AccountRead === RUN TestReOpen/WithSeekOption/AccountReadDelay 2026/09/24 04:34:21 DEBUG : potato: Seek from 8 to 0 2026/09/24 04:34:21 DEBUG : potato: Seek from 8 to 0 2026/09/24 04:34:21 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/AccountReadError === RUN TestReOpen/UnknownSize === RUN TestReOpen/UnknownSize/Basics 2026/09/24 04:34:21 DEBUG : potato: Seek from 9 to 0 === RUN TestReOpen/UnknownSize/ErrorAtStart === RUN TestReOpen/UnknownSize/WithErrors 2026/09/24 04:34:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/09/24 04:34:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/09/24 04:34:21 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/UnknownSize/TooManyErrors 2026/09/24 04:34:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/09/24 04:34:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/09/24 04:34:21 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/09/24 04:34:21 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/UnknownSize/ReadAt 2026/09/24 04:34:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/09/24 04:34:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/09/24 04:34:21 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/UnknownSize/Seek 2026/09/24 04:34:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/09/24 04:34:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/09/24 04:34:21 DEBUG : potato: Seek from 5 to 2 2026/09/24 04:34:21 DEBUG : potato: Seek from 7 to 6 === RUN TestReOpen/UnknownSize/AccountRead === RUN TestReOpen/UnknownSize/AccountReadDelay 2026/09/24 04:34:21 DEBUG : potato: Seek from 9 to 0 2026/09/24 04:34:21 DEBUG : potato: Seek from 9 to 0 2026/09/24 04:34:21 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:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:34:23 DEBUG : rutabaga: md5 = 43cb41fb51f35587cddd715b0958846c OK === RUN TestCheck/1 === RUN TestCheck/2 2026/09/24 04:34:25 DEBUG : empty space: md5 = ce2dadc2842adaca3b003c594b7b9294 OK === RUN TestCheck/3 2026/09/24 04:34:27 DEBUG : potato2: md5 = 18a4be84f3a2356c2e07a2461297b07b OK === RUN TestCheck/4 === RUN TestCheck/5 2026/09/24 04:34:30 DEBUG : remotepotato: md5 = c82b5abe9e110bbaed5441c98f1a5274 OK === RUN TestCheck/6 === RUN TestCheck/7 --- PASS: TestCheck (11.46s) --- PASS: TestCheck/1 (0.23s) --- PASS: TestCheck/2 (0.23s) --- PASS: TestCheck/3 (0.24s) --- PASS: TestCheck/4 (0.45s) --- PASS: TestCheck/5 (0.25s) --- PASS: TestCheck/6 (0.25s) --- PASS: TestCheck/7 (0.27s) === RUN TestCheckFsError 2026/09/24 04:34:33 DEBUG : Creating backend with remote "nonexistent" 2026/09/24 04:34:33 DEBUG : Creating backend with remote "nonexistent" 2026/09/24 04:34:33 DEBUG : Local file system at nonexistent: Waiting for checks to finish 2026/09/24 04:34:33 ERROR : Local file system at nonexistent: error reading source root directory: directory not found 2026/09/24 04:34:33 NOTICE: Local file system at nonexistent: 0 differences found 2026/09/24 04:34:33 NOTICE: Local file system at nonexistent: 2 errors while checking --- PASS: TestCheckFsError (0.00s) === RUN TestCheckDownload run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:34:34 DEBUG : rutabaga: md5 = a7b9e17e8b37261e6292d8c824562d2e OK === RUN TestCheckDownload/1 === RUN TestCheckDownload/2 2026/09/24 04:34:37 DEBUG : empty space: md5 = d033e7e9a8744e123cd94bdf2b556904 OK === RUN TestCheckDownload/3 2026/09/24 04:34:40 DEBUG : potato2: md5 = d6cf6d150bb72939b68f8d6028c22893 OK === RUN TestCheckDownload/4 === RUN TestCheckDownload/5 2026/09/24 04:34:43 DEBUG : remotepotato: md5 = f0f1c0df9aa1c16f046ac9215c5de959 OK === RUN TestCheckDownload/6 === RUN TestCheckDownload/7 --- PASS: TestCheckDownload (14.88s) --- PASS: TestCheckDownload/1 (0.87s) --- PASS: TestCheckDownload/2 (0.81s) --- PASS: TestCheckDownload/3 (0.75s) --- PASS: TestCheckDownload/4 (0.89s) --- PASS: TestCheckDownload/5 (0.87s) --- PASS: TestCheckDownload/6 (0.79s) --- PASS: TestCheckDownload/7 (0.73s) === RUN TestCheckSizeOnly run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:34:49 DEBUG : rutabaga: md5 = d735285eef7c3da53f13705fc9c178f7 OK === RUN TestCheckSizeOnly/1 === RUN TestCheckSizeOnly/2 2026/09/24 04:34:51 DEBUG : empty space: md5 = cfaf3e4ee2fc01796e4bd793aa2499ef OK === RUN TestCheckSizeOnly/3 2026/09/24 04:34:54 DEBUG : potato2: md5 = 824331a0ddfc6091cc3dc96da8485aef OK === RUN TestCheckSizeOnly/4 === RUN TestCheckSizeOnly/5 2026/09/24 04:34:56 DEBUG : remotepotato: md5 = 6cb9e5b671e71761907c160cb05f87b6 OK === RUN TestCheckSizeOnly/6 === RUN TestCheckSizeOnly/7 --- PASS: TestCheckSizeOnly (11.27s) --- PASS: TestCheckSizeOnly/1 (0.25s) --- PASS: TestCheckSizeOnly/2 (0.25s) --- PASS: TestCheckSizeOnly/3 (0.40s) --- PASS: TestCheckSizeOnly/4 (0.26s) --- PASS: TestCheckSizeOnly/5 (0.25s) --- PASS: TestCheckSizeOnly/6 (0.26s) --- PASS: TestCheckSizeOnly/7 (0.27s) === RUN TestCheckEqualReaders --- PASS: TestCheckEqualReaders (0.00s) === RUN TestParseSumFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:35:00 DEBUG : test.sum: md5 = 3862e4a7ab0994607f16fbec948e6ad7 OK 2026/09/24 04:35:01 NOTICE: test.sum: improperly formatted checksum line 4 2026/09/24 04:35:01 NOTICE: test.sum: improperly formatted checksum line 5 2026/09/24 04:35:01 NOTICE: test.sum: improperly formatted checksum line 6 2026/09/24 04:35:01 NOTICE: test.sum: 2 warning(s) suppressed... 2026/09/24 04:35:03 DEBUG : test.sum: md5 = c6def94ecf8ad5d7ced3803de1adf57d OK 2026/09/24 04:35:03 NOTICE: test.sum: improperly formatted checksum line 4 2026/09/24 04:35:03 NOTICE: test.sum: improperly formatted checksum line 5 2026/09/24 04:35:03 NOTICE: test.sum: improperly formatted checksum line 6 2026/09/24 04:35:03 NOTICE: test.sum: 2 warning(s) suppressed... --- PASS: TestParseSumFile (5.60s) === RUN TestCheckSum run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:35:04 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-mibuwin0qufo/data" 2026/09/24 04:35:04 DEBUG : Creating backend with remote "TestDrive:crypt/dv3ch684ovo78si0l2skmn1iaecq98ci9h5f9b1qj60jlsi53ulg/vjrnln8ratgmqakfosrqe8espk" check_test.go:354: Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo/data' lacks md5, skipping --- SKIP: TestCheckSum (2.15s) === RUN TestCheckSumDownload run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:35:07 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-mibuwin0qufo/data" 2026/09/24 04:35:07 DEBUG : Creating backend with remote "TestDrive:crypt/dv3ch684ovo78si0l2skmn1iaecq98ci9h5f9b1qj60jlsi53ulg/vjrnln8ratgmqakfosrqe8espk" 2026/09/24 04:35:10 DEBUG : data/banana: md5 = 21632366519bed7ba81061acc031837d OK 2026/09/24 04:35:12 DEBUG : test.sum: md5 = df6de8fac5d3bed4bef2f8a22f75a22d OK === RUN TestCheckSumDownload/subtest1 2026/09/24 04:35:16 DEBUG : data/potato: md5 = 8020b6f4a4949eb61e1b48ef461d5be2 OK 2026/09/24 04:35:17 DEBUG : test.sum: md5 = 8716c81ef323132b182bdaf7a7485551 OK === RUN TestCheckSumDownload/subtest2 2026/09/24 04:35:21 DEBUG : test.sum: md5 = ef20c2ec989c1a968e0ae981ab9d8471 OK === RUN TestCheckSumDownload/subtest3 2026/09/24 04:35:25 DEBUG : test.sum: md5 = e01beed7738862d8b33b90be9b59acc1 OK === RUN TestCheckSumDownload/subtest4 2026/09/24 04:35:29 DEBUG : test.sum: md5 = 7248b56736e8df089f04ff0f9279782a OK === RUN TestCheckSumDownload/subtest5 2026/09/24 04:35:33 DEBUG : test.sum: md5 = ef75f574c6b47e381304f4773ca6b249 OK === RUN TestCheckSumDownload/subtest6 2026/09/24 04:35:36 DEBUG : data/banana: md5 = c52b251123bcd236dbefcbbc4dcd1d88 OK 2026/09/24 04:35:38 DEBUG : data/potato: md5 = 73cfd6b2454cb90b6579a7873ebbf79d OK 2026/09/24 04:35:39 DEBUG : test.sum: md5 = 9e5fa48c56e69036ba987b0784b2dfcf OK === RUN TestCheckSumDownload/subtest7 --- PASS: TestCheckSumDownload (37.89s) --- PASS: TestCheckSumDownload/subtest1 (2.09s) --- PASS: TestCheckSumDownload/subtest2 (1.76s) --- PASS: TestCheckSumDownload/subtest3 (1.77s) --- PASS: TestCheckSumDownload/subtest4 (1.69s) --- PASS: TestCheckSumDownload/subtest5 (1.72s) --- PASS: TestCheckSumDownload/subtest6 (1.71s) --- PASS: TestCheckSumDownload/subtest7 (2.01s) === RUN TestCheckSumConcurrency 2026/09/24 04:35:44 DEBUG : file0: md5 = 022eb0c0085c731f335df970fafa7446 OK 2026/09/24 04:35:44 DEBUG : file2: md5 = a58f09ae3db8ae4b943fdbaace096072 OK 2026/09/24 04:35:44 DEBUG : file1: md5 = 17fbabbccbdea0e6be9eb64c8c6fea41 OK 2026/09/24 04:35:44 DEBUG : file3: md5 = e0639b17e0744aca9a3ba29c049e59ee OK 2026/09/24 04:35:44 NOTICE: Mock file system at checkSum: 0 differences found 2026/09/24 04:35:44 NOTICE: Mock file system at checkSum: 4 matching files --- PASS: TestCheckSumConcurrency (0.00s) === RUN TestCheckSumDownloadConcurrency 2026/09/24 04:35:44 DEBUG : file0: md5 = 022eb0c0085c731f335df970fafa7446 OK 2026/09/24 04:35:44 DEBUG : file1: md5 = 17fbabbccbdea0e6be9eb64c8c6fea41 OK 2026/09/24 04:35:44 DEBUG : file2: md5 = a58f09ae3db8ae4b943fdbaace096072 OK 2026/09/24 04:35:44 DEBUG : file3: md5 = e0639b17e0744aca9a3ba29c049e59ee OK 2026/09/24 04:35:44 NOTICE: Mock file system at checkSum: 0 differences found 2026/09/24 04:35:44 NOTICE: Mock file system at checkSum: 4 matching files --- PASS: TestCheckSumDownloadConcurrency (0.00s) === RUN TestApplyTransforms 2026/09/24 04:35:44 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-bujazir5sugo" 2026/09/24 04:35:44 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/24 04:35:45 DEBUG : Creating backend with remote "TestDrive:crypt/lf1pj12gc77acj51fnqvp1q3fte9uck984l2r2skafml6f90s6t0" 2026/09/24 04:35:45 DEBUG : Creating backend with remote "/tmp/rclone1701505293" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-bujazir5sugo'", Local "Local file system at /tmp/rclone1701505293", Modify Window "1ms" 2026/09/24 04:35:48 DEBUG : hello, world!: md5 = fe7b3aba51cd994c9aac1b2787e859e1 OK upper checkfile vs. lower remote (without normalization) 2026/09/24 04:35:48 ERROR : hello, world!: sum not found 2026/09/24 04:35:48 ERROR : HELLO, WORLD!: file not in Encrypted drive 'TestCryptDrive:rclone-test-bujazir5sugo' 2026/09/24 04:35:48 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-bujazir5sugo': 1 files missing 2026/09/24 04:35:48 NOTICE: 1 hashes missing 2026/09/24 04:35:48 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-bujazir5sugo': 1 differences found 2026/09/24 04:35:48 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-bujazir5sugo': 2 errors while checking upper checkfile vs. lower remote (with normalization) 2026/09/24 04:35:49 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/24 04:35:49 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-bujazir5sugo': 0 differences found 2026/09/24 04:35:49 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-bujazir5sugo': 1 matching files 2026/09/24 04:35:49 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-kumosev5deju" 2026/09/24 04:35:49 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/24 04:35:49 DEBUG : Creating backend with remote "TestDrive:crypt/jcbfa5v8upvpb5i07ap6ngb3klej1mkr1qchbu239otn7a30u3pg" 2026/09/24 04:35:50 DEBUG : Creating backend with remote "/tmp/rclone818421174" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kumosev5deju'", Local "Local file system at /tmp/rclone818421174", Modify Window "1ms" 2026/09/24 04:35:53 DEBUG : HELLO, WORLD!: md5 = 4d1129e21384670e42b0b2748c305491 OK lower checkfile vs. upper remote (without normalization) 2026/09/24 04:35:53 ERROR : HELLO, WORLD!: sum not found 2026/09/24 04:35:53 ERROR : hello, world!: file not in Encrypted drive 'TestCryptDrive:rclone-test-kumosev5deju' 2026/09/24 04:35:53 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kumosev5deju': 1 files missing 2026/09/24 04:35:53 NOTICE: 1 hashes missing 2026/09/24 04:35:53 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kumosev5deju': 1 differences found 2026/09/24 04:35:53 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kumosev5deju': 2 errors while checking lower checkfile vs. upper remote (with normalization) 2026/09/24 04:35:54 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/24 04:35:54 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kumosev5deju': 0 differences found 2026/09/24 04:35:54 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kumosev5deju': 1 matching files 2026/09/24 04:35:54 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-quzewex2guco" 2026/09/24 04:35:54 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/24 04:35:54 DEBUG : Creating backend with remote "TestDrive:crypt/2e29bk8osvp33jcfg53dvnasil9cnt8gs6u3n1ngfsq7fqvbt8f0" 2026/09/24 04:35:55 DEBUG : Creating backend with remote "/tmp/rclone4118911995" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-quzewex2guco'", Local "Local file system at /tmp/rclone4118911995", Modify Window "1ms" 2026/09/24 04:35:57 DEBUG : HeLlO, wOrLd!: md5 = d257a9a34eba8edd98adee004d28293e OK lower checkfile vs. upperlowermixed remote (without normalization) 2026/09/24 04:35:58 ERROR : HeLlO, wOrLd!: sum not found 2026/09/24 04:35:58 ERROR : hello, world!: file not in Encrypted drive 'TestCryptDrive:rclone-test-quzewex2guco' 2026/09/24 04:35:58 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-quzewex2guco': 1 files missing 2026/09/24 04:35:58 NOTICE: 1 hashes missing 2026/09/24 04:35:58 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-quzewex2guco': 1 differences found 2026/09/24 04:35:58 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-quzewex2guco': 2 errors while checking lower checkfile vs. upperlowermixed remote (with normalization) 2026/09/24 04:35:59 DEBUG : HeLlO, wOrLd!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/24 04:35:59 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-quzewex2guco': 0 differences found 2026/09/24 04:35:59 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-quzewex2guco': 1 matching files 2026/09/24 04:35:59 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-sapomir7pugi" 2026/09/24 04:35:59 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/24 04:35:59 DEBUG : Creating backend with remote "TestDrive:crypt/t4kinaqsnagolm264q5s95lp7etiaqhi7385ksgnrcu4njcmumh0" 2026/09/24 04:36:00 DEBUG : Creating backend with remote "/tmp/rclone2621759993" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-sapomir7pugi'", Local "Local file system at /tmp/rclone2621759993", Modify Window "1ms" 2026/09/24 04:36:02 DEBUG : HELLO, WORLD!: md5 = 6c646935c207ae40b4ca507c02438811 OK upperlowermixed checkfile vs. upper remote (without normalization) 2026/09/24 04:36:03 ERROR : HELLO, WORLD!: sum not found 2026/09/24 04:36:03 ERROR : HeLlO, wOrLd!: file not in Encrypted drive 'TestCryptDrive:rclone-test-sapomir7pugi' 2026/09/24 04:36:03 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-sapomir7pugi': 1 files missing 2026/09/24 04:36:03 NOTICE: 1 hashes missing 2026/09/24 04:36:03 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-sapomir7pugi': 1 differences found 2026/09/24 04:36:03 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-sapomir7pugi': 2 errors while checking upperlowermixed checkfile vs. upper remote (with normalization) 2026/09/24 04:36:04 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/24 04:36:04 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-sapomir7pugi': 0 differences found 2026/09/24 04:36:04 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-sapomir7pugi': 1 matching files 2026/09/24 04:36:04 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-fakiyok3reni" 2026/09/24 04:36:04 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/24 04:36:04 DEBUG : Creating backend with remote "TestDrive:crypt/r7bjp2qjcefn6sdusfsods19e6sjo60j02o123smpqr18p67s19g" 2026/09/24 04:36:05 DEBUG : Creating backend with remote "/tmp/rclone4159608426" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fakiyok3reni'", Local "Local file system at /tmp/rclone4159608426", Modify Window "1ms" 2026/09/24 04:36:07 DEBUG : 測試_Русский___ě_áñ: md5 = 1f8df76315b230231ab43f2552f550ae OK NFD checkfile vs. NFC remote (without normalization) 2026/09/24 04:36:07 ERROR : 測試_Русский___ě_áñ: sum not found 2026/09/24 04:36:07 ERROR : 測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-fakiyok3reni' 2026/09/24 04:36:07 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fakiyok3reni': 1 files missing 2026/09/24 04:36:07 NOTICE: 1 hashes missing 2026/09/24 04:36:07 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fakiyok3reni': 1 differences found 2026/09/24 04:36:07 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fakiyok3reni': 2 errors while checking NFD checkfile vs. NFC remote (with normalization) 2026/09/24 04:36:08 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/24 04:36:08 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fakiyok3reni': 0 differences found 2026/09/24 04:36:08 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fakiyok3reni': 1 matching files 2026/09/24 04:36:08 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-xinoqel8fewu" 2026/09/24 04:36:08 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/24 04:36:08 DEBUG : Creating backend with remote "TestDrive:crypt/8ec3882go6ihtj4je442ksa2rksfcb3pcudtmk77enrps3p7ck90" 2026/09/24 04:36:09 DEBUG : Creating backend with remote "/tmp/rclone3181144537" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-xinoqel8fewu'", Local "Local file system at /tmp/rclone3181144537", Modify Window "1ms" 2026/09/24 04:36:13 DEBUG : 測試_Русский___ě_áñ: md5 = 6189d6294d1a1c323d212a883e167bd8 OK NFC checkfile vs. NFD remote (without normalization) 2026/09/24 04:36:13 ERROR : 測試_Русский___ě_áñ: sum not found 2026/09/24 04:36:13 ERROR : 測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-xinoqel8fewu' 2026/09/24 04:36:13 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-xinoqel8fewu': 1 files missing 2026/09/24 04:36:13 NOTICE: 1 hashes missing 2026/09/24 04:36:13 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-xinoqel8fewu': 1 differences found 2026/09/24 04:36:13 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-xinoqel8fewu': 2 errors while checking NFC checkfile vs. NFD remote (with normalization) 2026/09/24 04:36:14 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/24 04:36:14 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-xinoqel8fewu': 0 differences found 2026/09/24 04:36:14 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-xinoqel8fewu': 1 matching files 2026/09/24 04:36:14 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-vuhotaf3maxu" 2026/09/24 04:36:14 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/24 04:36:14 DEBUG : Creating backend with remote "TestDrive:crypt/mjpg9tk3pj3367qqgpnmvt2n23bqf7l5mhuj7hje6g1v1b4o2c7g" 2026/09/24 04:36:15 DEBUG : Creating backend with remote "/tmp/rclone713236158" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-vuhotaf3maxu'", Local "Local file system at /tmp/rclone713236158", Modify Window "1ms" 2026/09/24 04:36:17 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 97957356bada1a2418ef45a014beb0bc OK NFDx2 checkfile vs. both remote (without normalization) 2026/09/24 04:36:18 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/09/24 04:36:18 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-vuhotaf3maxu' 2026/09/24 04:36:18 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-vuhotaf3maxu': 1 files missing 2026/09/24 04:36:18 NOTICE: 1 hashes missing 2026/09/24 04:36:18 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-vuhotaf3maxu': 1 differences found 2026/09/24 04:36:18 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-vuhotaf3maxu': 2 errors while checking NFDx2 checkfile vs. both remote (with normalization) 2026/09/24 04:36:19 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/24 04:36:19 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-vuhotaf3maxu': 0 differences found 2026/09/24 04:36:19 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-vuhotaf3maxu': 1 matching files 2026/09/24 04:36:19 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-gosayab1dezo" 2026/09/24 04:36:19 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/24 04:36:19 DEBUG : Creating backend with remote "TestDrive:crypt/pmaoptdtljk7hbjktiqn9ofjbgoi47vo2gcetol235n126amgobg" 2026/09/24 04:36:20 DEBUG : Creating backend with remote "/tmp/rclone2890441407" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gosayab1dezo'", Local "Local file system at /tmp/rclone2890441407", Modify Window "1ms" 2026/09/24 04:36:22 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = d9628fcdf1865a0232d876ab7af13e53 OK NFCx2 checkfile vs. both remote (without normalization) 2026/09/24 04:36:23 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/09/24 04:36:23 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-gosayab1dezo' 2026/09/24 04:36:23 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-gosayab1dezo': 1 files missing 2026/09/24 04:36:23 NOTICE: 1 hashes missing 2026/09/24 04:36:23 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-gosayab1dezo': 1 differences found 2026/09/24 04:36:23 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-gosayab1dezo': 2 errors while checking NFCx2 checkfile vs. both remote (with normalization) 2026/09/24 04:36:24 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/24 04:36:24 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-gosayab1dezo': 0 differences found 2026/09/24 04:36:24 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-gosayab1dezo': 1 matching files 2026/09/24 04:36:24 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-zizukix5tate" 2026/09/24 04:36:24 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/24 04:36:24 DEBUG : Creating backend with remote "TestDrive:crypt/917n2dtulreh5l7849iu82ldejkfb30o53luifb2cs0s4iaag3m0" 2026/09/24 04:36:25 DEBUG : Creating backend with remote "/tmp/rclone2364839316" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zizukix5tate'", Local "Local file system at /tmp/rclone2364839316", Modify Window "1ms" 2026/09/24 04:36:27 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = b5a0e034d85454f15ba7e534e6a43033 OK both checkfile vs. NFDx2 remote (without normalization) 2026/09/24 04:36:27 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/09/24 04:36:27 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-zizukix5tate' 2026/09/24 04:36:27 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-zizukix5tate': 1 files missing 2026/09/24 04:36:27 NOTICE: 1 hashes missing 2026/09/24 04:36:27 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-zizukix5tate': 1 differences found 2026/09/24 04:36:27 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-zizukix5tate': 2 errors while checking both checkfile vs. NFDx2 remote (with normalization) 2026/09/24 04:36:28 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/24 04:36:28 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-zizukix5tate': 0 differences found 2026/09/24 04:36:28 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-zizukix5tate': 1 matching files 2026/09/24 04:36:28 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-wawijuk5boda" 2026/09/24 04:36:28 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/24 04:36:28 DEBUG : Creating backend with remote "TestDrive:crypt/2gqq8tj49df7t90osvgpsaff8bmgfjq2aughf16p698ea7lpvvlg" 2026/09/24 04:36:29 DEBUG : Creating backend with remote "/tmp/rclone1558673139" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wawijuk5boda'", Local "Local file system at /tmp/rclone1558673139", Modify Window "1ms" 2026/09/24 04:36:32 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = b803a30b641da4b41e5686c5410f2ca0 OK both checkfile vs. NFCx2 remote (without normalization) 2026/09/24 04:36:32 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/09/24 04:36:32 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-wawijuk5boda' 2026/09/24 04:36:32 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-wawijuk5boda': 1 files missing 2026/09/24 04:36:32 NOTICE: 1 hashes missing 2026/09/24 04:36:32 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-wawijuk5boda': 1 differences found 2026/09/24 04:36:32 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-wawijuk5boda': 2 errors while checking both checkfile vs. NFCx2 remote (with normalization) 2026/09/24 04:36:33 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/24 04:36:33 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-wawijuk5boda': 0 differences found 2026/09/24 04:36:33 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-wawijuk5boda': 1 matching files 2026/09/24 04:36:33 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-wawijuk5boda': Purge remote 2026/09/24 04:36:33 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zizukix5tate': Purge remote 2026/09/24 04:36:34 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-gosayab1dezo': Purge remote 2026/09/24 04:36:34 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-vuhotaf3maxu': Purge remote 2026/09/24 04:36:35 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-xinoqel8fewu': Purge remote 2026/09/24 04:36:35 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-fakiyok3reni': Purge remote 2026/09/24 04:36:36 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-sapomir7pugi': Purge remote 2026/09/24 04:36:36 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-quzewex2guco': Purge remote 2026/09/24 04:36:36 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-kumosev5deju': Purge remote 2026/09/24 04:36:37 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-bujazir5sugo': Purge remote --- PASS: TestApplyTransforms (52.82s) === RUN TestTruncateString --- PASS: TestTruncateString (0.00s) === RUN TestCopyFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:36:38 DEBUG : file1: Need to transfer - File not found at Destination 2026/09/24 04:36:40 DEBUG : sub/file2: md5 = 85c08d11ea666993da4483c37397439a OK 2026/09/24 04:36:40 DEBUG : sub/file2: size = 14 OK 2026/09/24 04:36:40 INFO : file1: Copied (new) to: sub/file2 2026/09/24 04:36:41 DEBUG : sub/file2: size = 14 OK 2026/09/24 04:36:41 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/24 04:36:41 DEBUG : file1: Unchanged skipping 2026/09/24 04:36:41 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo': don't need to copy/move sub/file2, it is already at target location --- PASS: TestCopyFile (6.12s) === RUN TestCopyFileImmutable run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:36:44 DEBUG : existing: Need to transfer - File not found at Destination 2026/09/24 04:36:45 DEBUG : existing: md5 = de3d26c2513fdbf029daa64659b05aae OK 2026/09/24 04:36:45 DEBUG : existing: size = 6 OK 2026/09/24 04:36:45 INFO : existing: Copied (new) 2026/09/24 04:36:45 DEBUG : existing: size = 6 OK 2026/09/24 04:36:45 DEBUG : existing: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/24 04:36:45 DEBUG : existing: Unchanged skipping 2026/09/24 04:36:46 DEBUG : existing: size = 8 (Local file system at /tmp/rclone2912110156) 2026/09/24 04:36:46 DEBUG : existing: size = 6 (Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo') 2026/09/24 04:36:46 DEBUG : existing: Sizes differ 2026/09/24 04:36:46 ERROR : existing: Source and destination exist but do not match: immutable file modified --- PASS: TestCopyFileImmutable (3.99s) === RUN TestCopyLongFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" copy_test.go:188: Test only runs on local --- SKIP: TestCopyLongFile (0.57s) === RUN TestCopyFileBackupDir run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:36:50 DEBUG : dst/file1: md5 = 1b109de1cdb1d90f10f76c86a5757e38 OK 2026/09/24 04:36:51 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-mibuwin0qufo/backup" 2026/09/24 04:36:51 DEBUG : Creating backend with remote "TestDrive:crypt/dv3ch684ovo78si0l2skmn1iaecq98ci9h5f9b1qj60jlsi53ulg/1nrff024r7pq65ecp72fc28jb0" 2026/09/24 04:36:53 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone2912110156) 2026/09/24 04:36:53 DEBUG : dst/file1: size = 18 (Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo') 2026/09/24 04:36:53 DEBUG : dst/file1: Sizes differ 2026/09/24 04:36:55 INFO : dst/file1: Moved (server-side) 2026/09/24 04:36:56 DEBUG : dst/file1: md5 = de56a780ca6fb2d00fe3ed3abbd42c95 OK 2026/09/24 04:36:56 DEBUG : dst/file1: size = 14 OK 2026/09/24 04:36:56 INFO : dst/file1: Copied (new) --- PASS: TestCopyFileBackupDir (13.08s) === RUN TestCopyFileCompareDest run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:37:01 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-mibuwin0qufo/dst" 2026/09/24 04:37:01 DEBUG : Creating backend with remote "TestDrive:crypt/dv3ch684ovo78si0l2skmn1iaecq98ci9h5f9b1qj60jlsi53ulg/31u3jie661vd5p8j7rtc3hgbh0" 2026/09/24 04:37:03 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-mibuwin0qufo/CompareDest" 2026/09/24 04:37:03 DEBUG : Creating backend with remote "TestDrive:crypt/dv3ch684ovo78si0l2skmn1iaecq98ci9h5f9b1qj60jlsi53ulg/gveqi14airsml4bgu7krj116o8" 2026/09/24 04:37:04 DEBUG : one: Need to transfer - File not found at Destination 2026/09/24 04:37:07 DEBUG : one: md5 = ed3108e37af85e8956175b4061b759de OK 2026/09/24 04:37:07 DEBUG : one: size = 3 OK 2026/09/24 04:37:07 INFO : one: Copied (new) 2026/09/24 04:37:08 DEBUG : one: size = 5 (Local file system at /tmp/rclone2912110156) 2026/09/24 04:37:08 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo/dst') 2026/09/24 04:37:08 DEBUG : one: Sizes differ 2026/09/24 04:37:10 DEBUG : one: md5 = 0585ef8910bb4f64bf455b7e34caf960 OK 2026/09/24 04:37:10 DEBUG : one: size = 5 OK 2026/09/24 04:37:10 INFO : one: Copied (replaced existing) 2026/09/24 04:37:12 DEBUG : dst/one: md5 = 94e0d1c35bb2afc4b1e992056a97b575 OK 2026/09/24 04:37:14 DEBUG : CompareDest/one: md5 = c69b75c244e97d7b30566069edfe1235 OK 2026/09/24 04:37:15 DEBUG : one: size = 5 (Local file system at /tmp/rclone2912110156) 2026/09/24 04:37:15 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo/dst') 2026/09/24 04:37:15 DEBUG : one: Sizes differ 2026/09/24 04:37:15 DEBUG : one: size = 5 OK 2026/09/24 04:37:15 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/09/24 04:37:15 DEBUG : one: Destination found in --compare-dest, skipping 2026/09/24 04:37:17 DEBUG : CompareDest/two: md5 = d754ae629335d6523ec42e57550ee019 OK 2026/09/24 04:37:18 DEBUG : two: Need to transfer - File not found at Destination 2026/09/24 04:37:18 DEBUG : two: size = 3 OK 2026/09/24 04:37:18 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/09/24 04:37:18 DEBUG : two: Destination found in --compare-dest, skipping 2026/09/24 04:37:19 DEBUG : two: Need to transfer - File not found at Destination 2026/09/24 04:37:19 DEBUG : two: size = 3 OK 2026/09/24 04:37:19 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/09/24 04:37:19 DEBUG : two: Destination found in --compare-dest, skipping 2026/09/24 04:37:20 DEBUG : two: Need to transfer - File not found at Destination 2026/09/24 04:37:21 DEBUG : two: size = 5 (Local file system at /tmp/rclone2912110156) 2026/09/24 04:37:21 DEBUG : two: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo/CompareDest') 2026/09/24 04:37:21 DEBUG : two: Sizes differ 2026/09/24 04:37:22 DEBUG : two: md5 = 7862f89e4eb2a832ee140a2a57ae833c OK 2026/09/24 04:37:22 DEBUG : two: size = 5 OK 2026/09/24 04:37:22 INFO : two: Copied (new) --- PASS: TestCopyFileCompareDest (25.41s) === RUN TestCopyFileCopyDestImmutable run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:37:26 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-mibuwin0qufo/dst" 2026/09/24 04:37:26 DEBUG : Creating backend with remote "TestDrive:crypt/dv3ch684ovo78si0l2skmn1iaecq98ci9h5f9b1qj60jlsi53ulg/31u3jie661vd5p8j7rtc3hgbh0" 2026/09/24 04:37:30 DEBUG : dst/one: md5 = 0a33de9aed5532877b6eec97dc94a861 OK 2026/09/24 04:37:32 DEBUG : CopyDest/one: md5 = 09686b934472917eca7c6080491cda76 OK 2026/09/24 04:37:33 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-mibuwin0qufo/CopyDest" 2026/09/24 04:37:33 DEBUG : Creating backend with remote "TestDrive:crypt/dv3ch684ovo78si0l2skmn1iaecq98ci9h5f9b1qj60jlsi53ulg/d09o6po3f7bm6ce32vdgs8h9ls" 2026/09/24 04:37:34 DEBUG : one: size = 5 (Local file system at /tmp/rclone2912110156) 2026/09/24 04:37:34 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo/dst') 2026/09/24 04:37:34 DEBUG : one: Sizes differ 2026/09/24 04:37:34 DEBUG : one: size = 5 OK 2026/09/24 04:37:34 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/09/24 04:37:34 DEBUG : one: size = 5 (Local file system at /tmp/rclone2912110156) 2026/09/24 04:37:34 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo/dst') 2026/09/24 04:37:34 DEBUG : one: Sizes differ 2026/09/24 04:37:34 ERROR : one: Source and destination exist but do not match: immutable file modified --- PASS: TestCopyFileCopyDestImmutable (11.61s) === RUN TestCopyFileCopyDest run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:37:38 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-mibuwin0qufo/dst" 2026/09/24 04:37:38 DEBUG : Creating backend with remote "TestDrive:crypt/dv3ch684ovo78si0l2skmn1iaecq98ci9h5f9b1qj60jlsi53ulg/31u3jie661vd5p8j7rtc3hgbh0" 2026/09/24 04:37:40 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-mibuwin0qufo/CopyDest" 2026/09/24 04:37:40 DEBUG : Creating backend with remote "TestDrive:crypt/dv3ch684ovo78si0l2skmn1iaecq98ci9h5f9b1qj60jlsi53ulg/d09o6po3f7bm6ce32vdgs8h9ls" 2026/09/24 04:37:41 DEBUG : one: Need to transfer - File not found at Destination 2026/09/24 04:37:44 DEBUG : one: md5 = 9e5bfc8f73c98ebe9317e7b0a11b0a48 OK 2026/09/24 04:37:44 DEBUG : one: size = 3 OK 2026/09/24 04:37:44 INFO : one: Copied (new) 2026/09/24 04:37:45 DEBUG : one: size = 5 (Local file system at /tmp/rclone2912110156) 2026/09/24 04:37:45 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo/dst') 2026/09/24 04:37:45 DEBUG : one: Sizes differ 2026/09/24 04:37:47 DEBUG : one: md5 = 61c1f979fb272cc0edf4f79578fd773e OK 2026/09/24 04:37:47 DEBUG : one: size = 5 OK 2026/09/24 04:37:47 INFO : one: Copied (replaced existing) 2026/09/24 04:37:49 DEBUG : dst/one: md5 = 04aab6c9ffdd5aeb393abe754e108f98 OK 2026/09/24 04:37:51 DEBUG : CopyDest/one: md5 = 0f315626e045fdff253dba2d6b6732af OK 2026/09/24 04:37:52 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-mibuwin0qufo/BackupDir" 2026/09/24 04:37:52 DEBUG : Creating backend with remote "TestDrive:crypt/dv3ch684ovo78si0l2skmn1iaecq98ci9h5f9b1qj60jlsi53ulg/s6dbk3lfi7c9kfvo6j7bla9m0g" 2026/09/24 04:37:53 DEBUG : one: size = 5 (Local file system at /tmp/rclone2912110156) 2026/09/24 04:37:53 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo/dst') 2026/09/24 04:37:53 DEBUG : one: Sizes differ 2026/09/24 04:37:54 DEBUG : one: size = 5 OK 2026/09/24 04:37:54 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/09/24 04:37:54 DEBUG : one: size = 5 (Local file system at /tmp/rclone2912110156) 2026/09/24 04:37:54 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo/dst') 2026/09/24 04:37:54 DEBUG : one: Sizes differ 2026/09/24 04:37:55 INFO : one: Moved (server-side) 2026/09/24 04:37:56 DEBUG : one: size = 5 OK 2026/09/24 04:37:56 INFO : one: Copied (server-side copy) 2026/09/24 04:37:56 DEBUG : one: Destination found in --copy-dest, using server-side copy 2026/09/24 04:37:59 DEBUG : CopyDest/two: md5 = daa635dbadcd28980e96972003d96459 OK 2026/09/24 04:37:59 DEBUG : two: Need to transfer - File not found at Destination 2026/09/24 04:38:00 DEBUG : two: size = 3 OK 2026/09/24 04:38:00 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/09/24 04:38:01 DEBUG : two: size = 3 OK 2026/09/24 04:38:01 INFO : two: Copied (server-side copy) 2026/09/24 04:38:01 DEBUG : two: Destination found in --copy-dest, using server-side copy 2026/09/24 04:38:01 DEBUG : two: size = 3 OK 2026/09/24 04:38:01 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/09/24 04:38:01 DEBUG : two: Unchanged skipping 2026/09/24 04:38:03 DEBUG : CopyDest/three: md5 = 841a76c44666f8967cb11f1fc950a3c9 OK 2026/09/24 04:38:04 DEBUG : three: Need to transfer - File not found at Destination 2026/09/24 04:38:04 DEBUG : three: size = 7 (Local file system at /tmp/rclone2912110156) 2026/09/24 04:38:04 DEBUG : three: size = 5 (Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo/CopyDest') 2026/09/24 04:38:04 DEBUG : three: Sizes differ 2026/09/24 04:38:04 DEBUG : three: Destination not found in --copy-dest 2026/09/24 04:38:06 DEBUG : three: md5 = ddf8b80e5014396db7e03547d40a812a OK 2026/09/24 04:38:06 DEBUG : three: size = 7 OK 2026/09/24 04:38:06 INFO : three: Copied (new) --- PASS: TestCopyFileCopyDest (33.89s) === RUN TestCopyInplace run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" copy_test.go:434: Partial uploads not supported --- SKIP: TestCopyInplace (0.40s) === RUN TestCopyLongFileName run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" copy_test.go:467: Partial uploads not supported --- SKIP: TestCopyLongFileName (0.52s) === RUN TestCopyLongFileNameCollision run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" copy_test.go:500: Partial uploads not supported --- SKIP: TestCopyLongFileNameCollision (0.48s) === RUN TestCopyFileMaxTransfer run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:38:14 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination 2026/09/24 04:38:16 DEBUG : TestCopyFileMaxTransfer/file1: md5 = 6941597a3f727724150a161be85dd854 OK 2026/09/24 04:38:16 DEBUG : TestCopyFileMaxTransfer/file1: size = 14 OK 2026/09/24 04:38:16 INFO : TestCopyFileMaxTransfer/file1: Copied (new) 2026/09/24 04:38:16 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination 2026/09/24 04:38:17 ERROR : TestCopyFileMaxTransfer/file2: Failed to copy: Post "https://www.googleapis.com/upload/drive/v3/files?alt=json&fields=id%2Cname%2Csize%2Cmd5Checksum%2Csha1Checksum%2Csha256Checksum%2Ctrashed%2CexplicitlyTrashed%2CmodifiedTime%2CcreatedTime%2CmimeType%2Cparents%2CwebViewLink%2CshortcutDetails%2CexportLinks%2CresourceKey&keepRevisionForever=false&prettyPrint=false&supportsAllDrives=true&uploadType=multipart": googleapi: Copy failed: max transfer limit reached as set by --max-transfer copy_test.go:563: Expecting error to contain accounting.ErrorMaxTransferLimitReachedFatal 2026/09/24 04:38:17 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination 2026/09/24 04:38:18 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination 2026/09/24 04:38:20 DEBUG : TestCopyFileMaxTransfer/file4: md5 = 3c75dbe203984ab4771da502b6dbe1c4 OK 2026/09/24 04:38:20 DEBUG : TestCopyFileMaxTransfer/file4: size = 2062 OK 2026/09/24 04:38:20 INFO : TestCopyFileMaxTransfer/file4: Copied (new) --- PASS: TestCopyFileMaxTransfer (10.16s) === RUN TestDeduplicateInteractive run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" dedupe_test.go:37: Can't run this test without a hash --- SKIP: TestDeduplicateInteractive (0.43s) === RUN TestDeduplicateSkip run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:38:27 INFO : Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo': Looking for duplicate names using skip mode. 2026/09/24 04:38:27 NOTICE: one: Found 2 files with duplicate names 2026/09/24 04:38:27 NOTICE: one: Skipping 2 files with duplicate names --- PASS: TestDeduplicateSkip (4.94s) === RUN TestDeduplicateSizeOnly run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:38:33 INFO : Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo': Looking for duplicate names using skip mode. 2026/09/24 04:38:33 NOTICE: one: Found 3 files with duplicate names 2026/09/24 04:38:33 NOTICE: one: Deleting 1/2 identical duplicates (size 11) 2026/09/24 04:38:34 INFO : one: Deleted 2026/09/24 04:38:34 NOTICE: one: Skipping 2 files with duplicate names --- PASS: TestDeduplicateSizeOnly (6.67s) === RUN TestDeduplicateFirst run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:38:39 INFO : Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo': Looking for duplicate names using first mode. 2026/09/24 04:38:40 NOTICE: one: Found 3 files with duplicate names 2026/09/24 04:38:40 INFO : one: Deleted 2026/09/24 04:38:41 INFO : one: Deleted 2026/09/24 04:38:41 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateFirst (6.80s) === RUN TestDeduplicateNewest run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:38:47 INFO : Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo': Looking for duplicate names using newest mode. 2026/09/24 04:38:47 NOTICE: one: Found 3 files with duplicate names 2026/09/24 04:38:47 INFO : one: Deleted 2026/09/24 04:38:48 INFO : one: Deleted 2026/09/24 04:38:48 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateNewest (6.91s) === RUN TestDeduplicateNewestByHash run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" dedupe_test.go:37: Can't run this test without a hash --- SKIP: TestDeduplicateNewestByHash (0.44s) === RUN TestDeduplicateOldest run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:38:54 INFO : Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo': Looking for duplicate names using oldest mode. 2026/09/24 04:38:54 NOTICE: one: Found 3 files with duplicate names 2026/09/24 04:38:55 INFO : one: Deleted 2026/09/24 04:38:55 INFO : one: Deleted 2026/09/24 04:38:55 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateOldest (6.44s) === RUN TestDeduplicateLargest run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:39:00 INFO : Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo': Looking for duplicate names using largest mode. 2026/09/24 04:39:00 NOTICE: one: Found 3 files with duplicate names 2026/09/24 04:39:01 INFO : one: Deleted 2026/09/24 04:39:01 INFO : one: Deleted 2026/09/24 04:39:01 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateLargest (6.20s) === RUN TestDeduplicateSmallest run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:39:06 INFO : Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo': Looking for duplicate names using smallest mode. 2026/09/24 04:39:07 NOTICE: one: Found 3 files with duplicate names 2026/09/24 04:39:07 INFO : one: Deleted 2026/09/24 04:39:07 INFO : one: Deleted 2026/09/24 04:39:07 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateSmallest (6.43s) === RUN TestDeduplicateRename run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:39:14 INFO : Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo': Looking for duplicate names using rename mode. 2026/09/24 04:39:15 NOTICE: one.txt: Found 3 files with duplicate names 2026/09/24 04:39:15 INFO : one-2.txt: renamed from: one.txt 2026/09/24 04:39:16 INFO : one-3.txt: renamed from: one.txt 2026/09/24 04:39:17 INFO : one-4.txt: renamed from: one.txt --- PASS: TestDeduplicateRename (10.12s) === RUN TestDeduplicateRenameManyExisting run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:39:20 DEBUG : one-1.txt: md5 = c3d962b3477e49d1fef1463e17fc371f OK 2026/09/24 04:39:22 DEBUG : one-2.txt: md5 = 0deb4f371f84e374548dfea0ef786e2e OK 2026/09/24 04:39:23 DEBUG : one-3.txt: md5 = 46932fe01a5ad3bcda5491cceebfc0d9 OK 2026/09/24 04:39:25 DEBUG : one-4.txt: md5 = b1a742a4dde4a5d4ca974d618ef7817e OK 2026/09/24 04:39:26 DEBUG : one-5.txt: md5 = 61868ec20f327c0d55896d424c6dd007 OK 2026/09/24 04:39:27 DEBUG : one-6.txt: md5 = 1ff8e91371c1b7100a5f70836a7dc406 OK 2026/09/24 04:39:29 DEBUG : one-7.txt: md5 = 3e6c176c6e97bdf4bd2e5be0c87a1b3d OK 2026/09/24 04:39:30 DEBUG : one-8.txt: md5 = d8e4f51909f9c75efad67de875c3553c OK 2026/09/24 04:39:32 DEBUG : one-9.txt: md5 = 4cc140262c5bd3f8b5be74a9c0bbb003 OK 2026/09/24 04:39:33 DEBUG : one-10.txt: md5 = d57e2037d319d50fe7155be806066530 OK 2026/09/24 04:39:35 DEBUG : one-11.txt: md5 = efb218207d5b8b005db744478b8ccb58 OK 2026/09/24 04:39:36 DEBUG : one-12.txt: md5 = aa89a8da04eafb1e2a796f1db8e993d3 OK 2026/09/24 04:39:38 DEBUG : one-13.txt: md5 = 5410a37a387c67f9aa9727bb48444897 OK 2026/09/24 04:39:39 DEBUG : one-14.txt: md5 = 4ef9450a68b7b5debc8c340f99abb687 OK 2026/09/24 04:39:40 DEBUG : one-15.txt: md5 = 05f6c9a3532b750b4102e6439a7d6171 OK 2026/09/24 04:39:42 DEBUG : one-16.txt: md5 = ce0e5b31e7b50cb30459d2d802deea1b OK 2026/09/24 04:39:43 DEBUG : one-17.txt: md5 = 8c3a3dbe850034281cbcf458dc6193d3 OK 2026/09/24 04:39:45 DEBUG : one-18.txt: md5 = 79a146b0beff1a6051717ce99303f853 OK 2026/09/24 04:39:46 DEBUG : one-19.txt: md5 = 4a0c282b45bde00d354ea26f3df92fe3 OK 2026/09/24 04:39:47 DEBUG : one-20.txt: md5 = e239116d5e6ef81cc64c638924a673c7 OK 2026/09/24 04:39:49 DEBUG : one-21.txt: md5 = 720a041b263b656a6e5f624b6e180643 OK 2026/09/24 04:39:50 DEBUG : one-22.txt: md5 = 037d582a61f5759ce5c11b6d9e8ed069 OK 2026/09/24 04:39:52 DEBUG : one-23.txt: md5 = 3c7fe0f6f1a005c7a708a9bb073627a1 OK 2026/09/24 04:39:54 DEBUG : one-24.txt: md5 = 4fddf0c2a3473a65bb0b54f811bb0a2c OK 2026/09/24 04:39:55 DEBUG : one-25.txt: md5 = 1dfd488e6ff2e442084252ec5e4fbc97 OK 2026/09/24 04:39:56 DEBUG : one-26.txt: md5 = 17c7b708b26f2bc17499a4f5551f4c7f OK 2026/09/24 04:39:58 DEBUG : one-27.txt: md5 = 66c3f793198d666c18ac1e134b5270ba OK 2026/09/24 04:39:59 DEBUG : one-28.txt: md5 = 2108dad41066e1f93776a3343e7a687b OK 2026/09/24 04:40:01 DEBUG : one-29.txt: md5 = 15e9b805191ca1b4e0a13fd1df4dd3c6 OK 2026/09/24 04:40:02 DEBUG : one-30.txt: md5 = 2c8c5d79e8d78cc9b06293e36c4f1bbb OK 2026/09/24 04:40:04 DEBUG : one-31.txt: md5 = 44a4e2e857788920dd46571f36df01ac OK 2026/09/24 04:40:05 DEBUG : one-32.txt: md5 = 57a1d30e429e3161d5625acb6c57c1d8 OK 2026/09/24 04:40:07 DEBUG : one-33.txt: md5 = e7cf9a9bb14976a0cb747fcf3b6469c6 OK 2026/09/24 04:40:08 DEBUG : one-34.txt: md5 = 0fa19083520c1158bb92a6fc73b8b43b OK 2026/09/24 04:40:10 DEBUG : one-35.txt: md5 = fa4afcb0f5f5d6f85d6cccbe918d8e0c OK 2026/09/24 04:40:12 DEBUG : one-36.txt: md5 = b6d41153c6743abbb7182e2e79b97fe5 OK 2026/09/24 04:40:13 DEBUG : one-37.txt: md5 = 87c078d4c2f47ad077d1978545a2351e OK 2026/09/24 04:40:15 DEBUG : one-38.txt: md5 = 69f85311745359842a191bc82c0e7115 OK 2026/09/24 04:40:16 DEBUG : one-39.txt: md5 = 9cc29a786c528648d6afd98d246fb412 OK 2026/09/24 04:40:18 DEBUG : one-40.txt: md5 = 7c4994753fd46055d7db8f73be9bce66 OK 2026/09/24 04:40:19 DEBUG : one-41.txt: md5 = 043ec93465c9bcc1acc1f5926e37a913 OK 2026/09/24 04:40:20 DEBUG : one-42.txt: md5 = 6c61e3dfc6724037b90412a0fc962046 OK 2026/09/24 04:40:22 DEBUG : one-43.txt: md5 = d29f96366723960cb5a636bae79b3faa OK 2026/09/24 04:40:23 DEBUG : one-44.txt: md5 = aa9fda8b8eaae8faef3739e32f880883 OK 2026/09/24 04:40:25 DEBUG : one-45.txt: md5 = 6967a20246fa23ef8f422a265b879cc1 OK 2026/09/24 04:40:26 DEBUG : one-46.txt: md5 = 47422bb50397a40357a457b6716beac5 OK 2026/09/24 04:40:28 DEBUG : one-47.txt: md5 = 476e9316cd9aff737fe802c0a6d039d5 OK 2026/09/24 04:40:29 DEBUG : one-48.txt: md5 = da1f4a597aa55b2217df0a6dbb392475 OK 2026/09/24 04:40:31 DEBUG : one-49.txt: md5 = 7e8ef7728b5343fabb5294be28119b29 OK 2026/09/24 04:40:32 DEBUG : one-50.txt: md5 = 74c7abe5c006a15a248aea8af72f959e OK 2026/09/24 04:40:33 DEBUG : one-51.txt: md5 = 8857137b26ada2182d7978138fbfe557 OK 2026/09/24 04:40:35 DEBUG : one-52.txt: md5 = 814db1b22a2fbb2f435e3d3bae40ddce OK 2026/09/24 04:40:36 DEBUG : one-53.txt: md5 = 9a88f9c272604615b8af182783473b5a OK 2026/09/24 04:40:38 DEBUG : one-54.txt: md5 = 06541872bb501b7e2866aa4b9170fbd2 OK 2026/09/24 04:40:39 DEBUG : one-55.txt: md5 = 1198273347fd4732c2ee1945a2876c26 OK 2026/09/24 04:40:41 DEBUG : one-56.txt: md5 = 85b4b20571acb809df2f62d1935cd7ff OK 2026/09/24 04:40:42 DEBUG : one-57.txt: md5 = 8dde16e0a62f0769a69f4f2f4467f288 OK 2026/09/24 04:40:44 DEBUG : one-58.txt: md5 = a335fce6d40c12eb58f1e495bce75399 OK 2026/09/24 04:40:46 DEBUG : one-59.txt: md5 = d5fdb7a8abc413011323b3783ec1fbcb OK 2026/09/24 04:40:47 DEBUG : one-60.txt: md5 = 54208b8e4b60a90267d8133f2662625b OK 2026/09/24 04:40:49 DEBUG : one-61.txt: md5 = 7239d7954c09aaf300520ee9272626a0 OK 2026/09/24 04:40:50 DEBUG : one-62.txt: md5 = b7e8bc8be21c79f94569402eaab2dd4d OK 2026/09/24 04:40:52 DEBUG : one-63.txt: md5 = 501612e9421b2c6e636a303bd8cca78d OK 2026/09/24 04:40:53 DEBUG : one-64.txt: md5 = b224dc29f57f9471ea4653715cb8aa18 OK 2026/09/24 04:40:55 DEBUG : one-65.txt: md5 = 688e71ce4d5659a7c93df06217f2258a OK 2026/09/24 04:40:56 DEBUG : one-66.txt: md5 = c131291b992bb686ed5db6d2f7cc9f88 OK 2026/09/24 04:40:57 DEBUG : one-67.txt: md5 = 03ec3257bda9805688cabe59a0b6f0b7 OK 2026/09/24 04:40:59 DEBUG : one-68.txt: md5 = 9f05c166d4af2addb4df7329ca4e7fea OK 2026/09/24 04:41:00 DEBUG : one-69.txt: md5 = 1bb6df827dcd991cdcb4cedabf106f44 OK 2026/09/24 04:41:02 DEBUG : one-70.txt: md5 = 301e9f5444d64d790396366990a815c6 OK 2026/09/24 04:41:03 DEBUG : one-71.txt: md5 = 23fa27253a900deee6810d20a5f0c98f OK 2026/09/24 04:41:04 DEBUG : one-72.txt: md5 = f07bba39b25733e32fe6292e5e335ed6 OK 2026/09/24 04:41:06 DEBUG : one-73.txt: md5 = 1dfe89ebdc7eb19ed1e34512945be2f4 OK 2026/09/24 04:41:07 DEBUG : one-74.txt: md5 = e4fd77d166394a588da396fcf1a162b0 OK 2026/09/24 04:41:09 DEBUG : one-75.txt: md5 = 3d225b8dd84cf84421f37078e57ed5cd OK 2026/09/24 04:41:10 DEBUG : one-76.txt: md5 = 8be66df520e1bba0010e348ef30c382a OK 2026/09/24 04:41:12 DEBUG : one-77.txt: md5 = acff412bcb3f4337798610f35ced4574 OK 2026/09/24 04:41:13 DEBUG : one-78.txt: md5 = fbf330ce0828198a20e335e3964d17e7 OK 2026/09/24 04:41:15 DEBUG : one-79.txt: md5 = cc04296514744bd96c26725a1c64d903 OK 2026/09/24 04:41:16 DEBUG : one-80.txt: md5 = 154833266128c330adeab10391aeb025 OK 2026/09/24 04:41:17 DEBUG : one-81.txt: md5 = 86159862db838761da6241b5bc620e4a OK 2026/09/24 04:41:19 DEBUG : one-82.txt: md5 = 8f94f3d474f7147064bcf36316d331d4 OK 2026/09/24 04:41:20 DEBUG : one-83.txt: md5 = 42388ab25dd90e8f2aa1912a4faa676d OK 2026/09/24 04:41:21 DEBUG : one-84.txt: md5 = c77c866daf45acbc83145cb03b7f17c6 OK 2026/09/24 04:41:23 DEBUG : one-85.txt: md5 = 6552add13d17f159a815f12e97a6948a OK 2026/09/24 04:41:24 DEBUG : one-86.txt: md5 = 4cdcd0306e380d19a70055a36b44c4df OK 2026/09/24 04:41:26 DEBUG : one-87.txt: md5 = 58a8adfeb741036e3264f1eb81e66771 OK 2026/09/24 04:41:28 DEBUG : one-88.txt: md5 = 14da2294570297293a5d18b17210e334 OK 2026/09/24 04:41:29 DEBUG : one-89.txt: md5 = ec6265d28ad066f168290bcc35d77d04 OK 2026/09/24 04:41:30 DEBUG : one-90.txt: md5 = 3e8892ede3b14767bd216f5caa43e40e OK 2026/09/24 04:41:32 DEBUG : one-91.txt: md5 = 1e574a4140efc1e2b25a930b9a5ffdff OK 2026/09/24 04:41:33 DEBUG : one-92.txt: md5 = ab6d537d7498db3c55ff43980e56ec80 OK 2026/09/24 04:41:36 DEBUG : one-93.txt: md5 = 982097b73bf7d7935a3d72bd234bc1f1 OK 2026/09/24 04:41:38 DEBUG : one-94.txt: md5 = 6a0357bd1ed809899892d7b69ab9c345 OK 2026/09/24 04:41:40 DEBUG : one-95.txt: md5 = 4c17090954517ae2b95ddbe1a223b253 OK 2026/09/24 04:41:41 DEBUG : one-96.txt: md5 = fb539cf38ac62475fb869ba49d9545f4 OK 2026/09/24 04:41:43 DEBUG : one-97.txt: md5 = c87a4a7cb4978b3df35bdcdf9ebee1d6 OK 2026/09/24 04:41:44 DEBUG : one-98.txt: md5 = d6eb6da1b86b164336f815f74dbd8458 OK 2026/09/24 04:41:45 DEBUG : one-99.txt: md5 = ca13bbdd62b73cc885e03fe54110ba7c OK 2026/09/24 04:41:47 DEBUG : one-100.txt: md5 = 404186375aaf5ae39ee706ee33297281 OK 2026/09/24 04:41:48 DEBUG : one-101.txt: md5 = 56642213a28b7774392c298121772b46 OK 2026/09/24 04:41:50 DEBUG : one-102.txt: md5 = f94ee04825681a05729d6370933bd091 OK 2026/09/24 04:41:52 DEBUG : one-103.txt: md5 = e2d3900ef6fd62d84afe11927d1f7ebb OK 2026/09/24 04:41:53 DEBUG : one-104.txt: md5 = bb6453e0a9995c00a799d7d8778af341 OK 2026/09/24 04:41:54 DEBUG : one-105.txt: md5 = 42aa168616f61442768f0a3e3658e4b4 OK 2026/09/24 04:41:57 INFO : Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo': Looking for duplicate names using rename mode. 2026/09/24 04:41:58 NOTICE: one.txt: Found 2 files with duplicate names 2026/09/24 04:41:59 INFO : one-106.txt: renamed from: one.txt 2026/09/24 04:42:00 INFO : one-107.txt: renamed from: one.txt --- PASS: TestDeduplicateRenameManyExisting (208.39s) === RUN TestMergeDirs run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:42:50 DEBUG : dupe1/one.txt: md5 = 6769b2f02065f395ad282e00a94f3368 OK 2026/09/24 04:42:52 DEBUG : dupe2/two.txt: md5 = 7cd27e1e78bbc2f13bf11357e50fcb6f OK 2026/09/24 04:42:54 DEBUG : dupe3/three.txt: md5 = 15ba24eae099a9035a4689a8ca0d1c10 OK 2026/09/24 04:42:55 INFO : urj8gsducqbtvekeq181ntd2u8: merging "sfrom47189m9qr3mt4qonu281c" 2026/09/24 04:42:55 INFO : urj8gsducqbtvekeq181ntd2u8: removing empty directory 2026/09/24 04:42:56 INFO : 1u1r4ei7c628fnjg9blqt3j60o: merging "tr2hj63d80ftlmvm6a952snjcc" 2026/09/24 04:42:57 INFO : 1u1r4ei7c628fnjg9blqt3j60o: removing empty directory --- PASS: TestMergeDirs (13.59s) === RUN TestListDirSorted run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:43:02 DEBUG : a.txt: md5 = 7191aa3ac95badd2264d39ac1bdc4ddf OK 2026/09/24 04:43:04 DEBUG : zend.txt: md5 = 0350ee8d9f99254706793d012821dde5 OK 2026/09/24 04:43:07 DEBUG : sub dir/hello world: md5 = 709fbcbb87c5a0673f91ea48ff28ca32 OK 2026/09/24 04:43:08 DEBUG : sub dir/hello world2: md5 = 592b23e4afd0981d61320c312d86b025 OK 2026/09/24 04:43:10 DEBUG : sub dir/ignore dir/.ignore: md5 = 1e9dc8feca3fd25b1bf557d1b582b45d OK 2026/09/24 04:43:12 DEBUG : sub dir/ignore dir/should be ignored: md5 = c6c9926fd3a0d5b803a7c9dcc81afb02 OK 2026/09/24 04:43:14 DEBUG : sub dir/sub sub dir/hello world3: md5 = 8561d8061ac5b52e549877a80d427b36 OK 2026/09/24 04:43:15 DEBUG : a.txt: Excluded (Size Filter) 2026/09/24 04:43:15 DEBUG : a.txt: Excluded 2026/09/24 04:43:16 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/09/24 04:43:16 DEBUG : sub dir/hello world: Excluded 2026/09/24 04:43:16 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/09/24 04:43:16 DEBUG : sub dir/hello world2: Excluded 2026/09/24 04:43:16 DEBUG : sub dir/ignore dir: Excluded 2026/09/24 04:43:16 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/09/24 04:43:16 DEBUG : sub dir/hello world: Excluded 2026/09/24 04:43:16 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/09/24 04:43:16 DEBUG : sub dir/hello world2: Excluded 2026/09/24 04:43:17 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSorted (22.24s) === RUN TestListDirSortedFn run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:43:24 DEBUG : a.txt: md5 = d5cbf91dbfe703f5faf136115129c764 OK 2026/09/24 04:43:26 DEBUG : zend.txt: md5 = 255ef421870c6e32a5dd0e9f9b36abc1 OK 2026/09/24 04:43:28 DEBUG : sub dir/hello world: md5 = 1230bbb1fcef71adac1767ed5f57328d OK 2026/09/24 04:43:30 DEBUG : sub dir/hello world2: md5 = db4c34b01e18b1cadce0201b98bc3869 OK 2026/09/24 04:43:32 DEBUG : sub dir/ignore dir/.ignore: md5 = 0d2bf43f3adc60bda5eb990f115e255a OK 2026/09/24 04:43:33 DEBUG : sub dir/ignore dir/should be ignored: md5 = f763628737a8309eb6de1797870ee3bd OK 2026/09/24 04:43:35 DEBUG : sub dir/sub sub dir/hello world3: md5 = 3455306aa6092448a27629d479770b48 OK 2026/09/24 04:43:37 DEBUG : a.txt: Excluded (Size Filter) 2026/09/24 04:43:37 DEBUG : a.txt: Excluded 2026/09/24 04:43:38 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/09/24 04:43:38 DEBUG : sub dir/hello world: Excluded 2026/09/24 04:43:38 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/09/24 04:43:38 DEBUG : sub dir/hello world2: Excluded 2026/09/24 04:43:38 DEBUG : sub dir/ignore dir: Excluded 2026/09/24 04:43:38 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/09/24 04:43:38 DEBUG : sub dir/hello world: Excluded 2026/09/24 04:43:38 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/09/24 04:43:38 DEBUG : sub dir/hello world2: Excluded 2026/09/24 04:43:39 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSortedFn (21.97s) === RUN TestListJSON run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:43:46 DEBUG : file1: md5 = b659bcf1b38cedc74fbb65e64d24f734 OK 2026/09/24 04:43:49 DEBUG : sub/file2: md5 = 46eec9e7503f2834e470a6314b76af07 OK === 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 2026/09/24 04:43:52 ERROR : file1: Failed to read hash: hash type not supported === RUN TestListJSON/Metadata 2026/09/24 04:43:52 DEBUG : eer8kka55qnghc34cq76ca668g: Fetching metadata 2026/09/24 04:43:52 DEBUG : 150fuo3cn4j1uenq6r4g4qk6t4: Fetching metadata --- PASS: TestListJSON (9.57s) --- PASS: TestListJSON/Default (0.27s) --- PASS: TestListJSON/FilesOnly (0.26s) --- PASS: TestListJSON/DirsOnly (0.23s) --- PASS: TestListJSON/Recurse (0.46s) --- PASS: TestListJSON/SubDir (0.23s) --- PASS: TestListJSON/NoModTime (0.24s) --- PASS: TestListJSON/NoMimeType (0.24s) --- PASS: TestListJSON/ShowHash (0.28s) --- PASS: TestListJSON/HashTypes (0.26s) --- PASS: TestListJSON/Metadata (0.68s) === RUN TestStatJSON run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:43:56 DEBUG : file1: md5 = 21e333708226c916f04d41725cdf77b3 OK 2026/09/24 04:43:58 DEBUG : sub/file2: md5 = 4fb29f599aa74c7e3cc5d59717bffe26 OK === 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 2026/09/24 04:44:02 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-mibuwin0qufo/notfound" 2026/09/24 04:44:02 DEBUG : Config file has changed externally - reloading 2026/09/24 04:44:02 DEBUG : Creating backend with remote "TestDrive:crypt/dv3ch684ovo78si0l2skmn1iaecq98ci9h5f9b1qj60jlsi53ulg/qe0i31qdkoejk60elps0ni5sqk" --- PASS: TestStatJSON (11.84s) --- PASS: TestStatJSON/Root (0.31s) --- PASS: TestStatJSON/RootFilesOnly (0.00s) --- PASS: TestStatJSON/RootDirsOnly (0.24s) --- PASS: TestStatJSON/Dir (0.53s) --- PASS: TestStatJSON/DirWithTrailingSlash (0.23s) --- PASS: TestStatJSON/File (0.25s) --- PASS: TestStatJSON/NotFound (0.48s) --- PASS: TestStatJSON/DirFilesOnly (0.29s) --- PASS: TestStatJSON/FileFilesOnly (0.22s) --- PASS: TestStatJSON/NotFoundFilesOnly (0.24s) --- PASS: TestStatJSON/DirDirsOnly (0.30s) --- PASS: TestStatJSON/FileDirsOnly (0.28s) --- PASS: TestStatJSON/NotFoundDirsOnly (0.25s) --- PASS: TestStatJSON/RootNotFound (1.90s) === RUN TestStatJSONMemory 2026/09/24 04:44:06 DEBUG : Creating backend with remote ":memory:" 2026/09/24 04:44:06 DEBUG : Memory root '': File to upload is small (5 bytes), uploading instead of streaming 2026/09/24 04:44:06 DEBUG : sub/file1: size = 5 OK 2026/09/24 04:44:06 DEBUG : sub/file1: md5 = 5d41402abc4b2a76b9719d911017c592 OK 2026/09/24 04:44:06 DEBUG : sub/file1: Size and md5 of src and dst objects identical === RUN TestStatJSONMemory/Dir === RUN TestStatJSONMemory/DirWithTrailingSlash === RUN TestStatJSONMemory/File === RUN TestStatJSONMemory/NotFound --- PASS: TestStatJSONMemory (0.00s) --- PASS: TestStatJSONMemory/Dir (0.00s) --- PASS: TestStatJSONMemory/DirWithTrailingSlash (0.00s) --- PASS: TestStatJSONMemory/File (0.00s) --- PASS: TestStatJSONMemory/NotFound (0.00s) === RUN TestStatJSONConfinement --- PASS: TestStatJSONConfinement (0.00s) === RUN TestMkdir run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:44:06 INFO : Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo': Making directory 2026/09/24 04:44:07 INFO : Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo': Making directory --- PASS: TestMkdir (0.76s) === RUN TestLsd run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:44:09 DEBUG : sub dir/hello world: md5 = bd3265c43d424b1cc9af9137174aafe5 OK --- PASS: TestLsd (4.67s) === RUN TestLs run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:44:13 DEBUG : potato2: md5 = 19b65f6173c53a0175c4b5212660d1bc OK 2026/09/24 04:44:15 DEBUG : empty space: md5 = 6a7a7b1034f28f6d1679b09525e761ba OK --- PASS: TestLs (5.66s) === RUN TestLsWithFilesFrom run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:44:19 DEBUG : potato2: md5 = 1bf01fbe1cb5ec576e1f14e6eb0420dc OK 2026/09/24 04:44:20 DEBUG : empty space: md5 = 094887425d01c9dbb2ffdaa5d2ed72ae OK 2026/09/24 04:44:21 DEBUG : empty space: Excluded (FilesFrom Filter) --- PASS: TestLsWithFilesFrom (4.95s) === RUN TestLsLong run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:44:24 DEBUG : potato2: md5 = 31ccc9aed012142c40f292bf2d9a5eb0 OK 2026/09/24 04:44:25 DEBUG : empty space: md5 = 314b4064dae8d0c0091ca4de30d908f0 OK --- PASS: TestLsLong (4.74s) === RUN TestHashSums run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:44:29 DEBUG : potato2: md5 = b7861515f50ae292b99f2b9742d7e37a OK 2026/09/24 04:44:30 DEBUG : empty space: md5 = 1c459b1f3c76d916937375872f74508b OK --- PASS: TestHashSums (4.53s) === RUN TestHashSumsWithErrors 2026/09/24 04:44:32 DEBUG : Creating backend with remote ":memory:" 2026/09/24 04:44:32 ERROR : file1: hash unsupported: hash type not supported 2026/09/24 04:44:32 ERROR : sub/file1: hash unsupported: hash type not supported 2026/09/24 04:44:32 ERROR : sub/file1: hash unsupported: hash type not supported --- PASS: TestHashSumsWithErrors (0.00s) === RUN TestHashStream 2026/09/24 04:44:32 DEBUG : Creating md5 hash of 0 bytes read from input stream 2026/09/24 04:44:32 DEBUG : Creating md5 hash of 0 bytes read from input stream 2026/09/24 04:44:32 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2026/09/24 04:44:32 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2026/09/24 04:44:32 DEBUG : Creating md5 hash of 12 bytes read from input stream 2026/09/24 04:44:32 DEBUG : Creating md5 hash of 12 bytes read from input stream 2026/09/24 04:44:32 DEBUG : Creating sha1 hash of 12 bytes read from input stream 2026/09/24 04:44:32 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:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:44:33 DEBUG : potato2: md5 = 0da9161c5b40f4f7de75f97632d1c4be OK 2026/09/24 04:44:35 DEBUG : empty space: md5 = 4cb7965dfe91a0e8f96b338dd62eba29 OK 2026/09/24 04:44:37 DEBUG : sub dir/potato3: md5 = 4d93587a47bd91e9b9e7a5855dd6e90b OK --- PASS: TestCount (8.65s) === RUN TestDelete run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:44:42 DEBUG : small: md5 = dc03ad808fcc25c4470bec1a23bac6f2 OK 2026/09/24 04:44:43 DEBUG : medium: md5 = abdc21b16d8e07399c91a0c14846532b OK 2026/09/24 04:44:45 DEBUG : large: md5 = f7dd5011e357deb18446585643b12c05 OK 2026/09/24 04:44:45 DEBUG : Waiting for deletions to finish 2026/09/24 04:44:45 DEBUG : large: Excluded (Size Filter) 2026/09/24 04:44:46 INFO : medium: Deleted 2026/09/24 04:44:46 INFO : small: Deleted --- PASS: TestDelete (6.53s) === RUN TestDeleteFatalError run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:44:48 DEBUG : file0: md5 = da5e7f8b9b53340f480614e25fe13243 OK 2026/09/24 04:44:50 DEBUG : file1: md5 = 9c4a4b64227453277d9fe040a632adc8 OK 2026/09/24 04:44:51 DEBUG : file2: md5 = 756902d250c6be2b82c779aa6fb50811 OK 2026/09/24 04:44:53 DEBUG : file3: md5 = a9c329488f2c63bd9c071674434925cb OK 2026/09/24 04:44:55 DEBUG : file4: md5 = 0ee33613ddf85ed0a74c831360e64d37 OK 2026/09/24 04:44:56 DEBUG : file5: md5 = 70d7acf25ce49389e19b57e8d5655b48 OK 2026/09/24 04:44:57 DEBUG : file6: md5 = 9bb597f1a28b8a82398cf2e28dae75ed OK 2026/09/24 04:44:59 DEBUG : file7: md5 = 23c2a80de36019003571aa3b0f1a8480 OK 2026/09/24 04:45:00 DEBUG : file8: md5 = a9d6e40be6c7adadcba05d4669927077 OK 2026/09/24 04:45:02 DEBUG : file9: md5 = 46f4693c204734c3b61590030a1a2ef1 OK 2026/09/24 04:45:04 DEBUG : file10: md5 = 395c06a079c0543d0f5bf8e92010e283 OK 2026/09/24 04:45:05 DEBUG : file11: md5 = 68e7b312cee353b533ccc499f58f5f83 OK 2026/09/24 04:45:06 DEBUG : file12: md5 = aee7b188465146e160e1ae90db2983b3 OK 2026/09/24 04:45:08 DEBUG : file13: md5 = a51053950faaa811fcab8ea3922fb419 OK 2026/09/24 04:45:09 DEBUG : file14: md5 = b24afc7bc7dd76124db8f5d52261c98e OK 2026/09/24 04:45:11 DEBUG : file15: md5 = 12bb1519d9a3b3c39ed98883ed8bb6c5 OK 2026/09/24 04:45:12 DEBUG : file16: md5 = 60e572c17185b81d6a33f8703f9168f5 OK 2026/09/24 04:45:13 DEBUG : file17: md5 = 51a08c9245e74dd9ea700cde379d7787 OK 2026/09/24 04:45:15 DEBUG : file18: md5 = dc6159ebc7aa32dde920fee902fd8e13 OK 2026/09/24 04:45:16 DEBUG : file19: md5 = 86898162b90d0bbdadfac0cb13af97fe OK 2026/09/24 04:45:16 DEBUG : Waiting for deletions to finish 2026/09/24 04:45:16 ERROR : file9: Got fatal error on delete: --max-delete threshold reached 2026/09/24 04:45:16 ERROR : file14: Got fatal error on delete: --max-delete threshold reached 2026/09/24 04:45:16 ERROR : file4: Got fatal error on delete: --max-delete threshold reached 2026/09/24 04:45:16 ERROR : file8: Got fatal error on delete: --max-delete threshold reached 2026/09/24 04:45:16 ERROR : file2: Got fatal error on delete: --max-delete threshold reached 2026/09/24 04:45:16 ERROR : file12: Got fatal error on delete: --max-delete threshold reached 2026/09/24 04:45:16 ERROR : file18: Got fatal error on delete: --max-delete threshold reached 2026/09/24 04:45:16 ERROR : file15: Got fatal error on delete: --max-delete threshold reached 2026/09/24 04:45:16 ERROR : file10: Got fatal error on delete: --max-delete threshold reached 2026/09/24 04:45:16 ERROR : file16: Got fatal error on delete: --max-delete threshold reached 2026/09/24 04:45:16 ERROR : file13: Got fatal error on delete: --max-delete threshold reached 2026/09/24 04:45:16 ERROR : file0: Got fatal error on delete: --max-delete threshold reached 2026/09/24 04:45:16 ERROR : file6: Got fatal error on delete: --max-delete threshold reached 2026/09/24 04:45:16 ERROR : file7: Got fatal error on delete: --max-delete threshold reached 2026/09/24 04:45:16 ERROR : file5: Got fatal error on delete: --max-delete threshold reached 2026/09/24 04:45:16 ERROR : file1: Got fatal error on delete: --max-delete threshold reached 2026/09/24 04:45:16 ERROR : file19: Got fatal error on delete: --max-delete threshold reached 2026/09/24 04:45:16 ERROR : file3: Got fatal error on delete: --max-delete threshold reached 2026/09/24 04:45:16 ERROR : file11: Got fatal error on delete: --max-delete threshold reached 2026/09/24 04:45:16 ERROR : file17: Got fatal error on delete: --max-delete threshold reached --- PASS: TestDeleteFatalError (38.35s) === RUN TestMaxDelete run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:45:27 DEBUG : small: md5 = 35067aae2e63a86583f501a598691dcb OK 2026/09/24 04:45:28 DEBUG : medium: md5 = 423fc576285c6ffcbc6d762e9a6ff23d OK 2026/09/24 04:45:30 DEBUG : large: md5 = f3e9646ec96cd96b1970b837207528f3 OK 2026/09/24 04:45:30 DEBUG : Waiting for deletions to finish 2026/09/24 04:45:30 ERROR : medium: Got fatal error on delete: --max-delete threshold reached 2026/09/24 04:45:30 INFO : large: Deleted 2026/09/24 04:45:30 INFO : small: Deleted --- PASS: TestMaxDelete (6.75s) === RUN TestMaxDeleteSizeLargeFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:45:33 DEBUG : small: md5 = 82b8db29dba4889071810576b63cdcc3 OK 2026/09/24 04:45:35 DEBUG : medium: md5 = 33c009ecde3371ca170f3021ab667bdc OK 2026/09/24 04:45:36 DEBUG : large: md5 = bf026adeee813e93b52c82d05295e2e4 OK 2026/09/24 04:45:37 DEBUG : Waiting for deletions to finish 2026/09/24 04:45:37 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached 2026/09/24 04:45:37 INFO : small: Deleted 2026/09/24 04:45:37 INFO : medium: Deleted --- PASS: TestMaxDeleteSizeLargeFile (6.98s) === RUN TestMaxDeleteSize run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:45:40 DEBUG : small: md5 = 0b32bf9cd76c9a6795e8f5270d4f96ac OK 2026/09/24 04:45:42 DEBUG : medium: md5 = d51a77911e48270212dcc26c41ee4200 OK 2026/09/24 04:45:43 DEBUG : large: md5 = c88b24c6d80a407aff0468b0a003419c OK 2026/09/24 04:45:43 DEBUG : Waiting for deletions to finish 2026/09/24 04:45:44 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached 2026/09/24 04:45:44 INFO : medium: Deleted 2026/09/24 04:45:44 INFO : small: Deleted --- PASS: TestMaxDeleteSize (6.68s) === RUN TestReadFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:45:47 DEBUG : ReadFile: md5 = b43751e97ad013cf45da5cb5c021ea31 OK --- PASS: TestReadFile (4.08s) === RUN TestRetry 2026/09/24 04:45:50 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 1/5 2026/09/24 04:45:50 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 2/5 2026/09/24 04:45:50 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2026/09/24 04:45:50 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2026/09/24 04:45:50 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2026/09/24 04:45:50 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms --- PASS: TestRetry (0.04s) === RUN TestRetryAfterContextCancel 2026/09/24 04:45:50 DEBUG : Sleeping for 1h0m0s (as indicated by the server) to obey Retry-After error: BANG: trying again in 1h0m0s --- PASS: TestRetryAfterContextCancel (0.00s) === RUN TestRetryAfterLastTry --- PASS: TestRetryAfterLastTry (0.00s) === RUN TestCat run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:45:51 DEBUG : file1: md5 = 6e77d757dd4dc61442a8165cc6c7044d OK 2026/09/24 04:45:53 DEBUG : file2: md5 = a827f01feed7afe43c98d7724624cfb3 OK --- PASS: TestCat (14.02s) === RUN TestPurge 2026/09/24 04:46:04 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-muciqeq2nozu" 2026/09/24 04:46:04 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/24 04:46:04 DEBUG : Creating backend with remote "TestDrive:crypt/8rdkqe90b2rl9klhmbrqt5kaiv1qiiig8s4277cuas2qb6o24bmg" 2026/09/24 04:46:05 DEBUG : Creating backend with remote "/tmp/rclone193065348" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-muciqeq2nozu'", Local "Local file system at /tmp/rclone193065348", Modify Window "1ms" 2026/09/24 04:46:09 DEBUG : A1/B1/C1/one: md5 = d7d3abcc3b022cc31c63dc458dd394b7 OK 2026/09/24 04:46:09 INFO : A2: Making directory 2026/09/24 04:46:10 INFO : A1/B2: Making directory 2026/09/24 04:46:11 INFO : A1/B2/C2: Making directory 2026/09/24 04:46:11 INFO : A1/B1/C3: Making directory 2026/09/24 04:46:12 INFO : A3: Making directory 2026/09/24 04:46:13 INFO : A3/B3: Making directory 2026/09/24 04:46:13 INFO : A3/B3/C4: Making directory 2026/09/24 04:46:16 DEBUG : A1/two: md5 = 736ba3e24e46639b6e670a9ff09a14b2 OK 2026/09/24 04:46:19 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-muciqeq2nozu': Purge remote 2026/09/24 04:46:19 NOTICE: purge failed: directory not found --- PASS: TestPurge (15.60s) === RUN TestRmdirsNoLeaveRoot run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:46:23 DEBUG : A1/B1/C1/one: md5 = 446f5e04a2fb149b7ac0975e477e23ce OK 2026/09/24 04:46:23 INFO : A2: Making directory 2026/09/24 04:46:24 INFO : A1/B2: Making directory 2026/09/24 04:46:25 INFO : A1/B2/C2: Making directory 2026/09/24 04:46:25 INFO : A1/B1/C3: Making directory 2026/09/24 04:46:26 INFO : A3: Making directory 2026/09/24 04:46:27 INFO : A3/B3: Making directory 2026/09/24 04:46:27 INFO : A3/B3/C4: Making directory 2026/09/24 04:46:29 DEBUG : A1/two: md5 = b65ef5ab62a59064409728bd82a730e7 OK 2026/09/24 04:46:31 DEBUG : removing 1 level 3 directories 2026/09/24 04:46:31 INFO : A3/B3/C4: Removing directory 2026/09/24 04:46:33 DEBUG : removing 2 level 3 directories 2026/09/24 04:46:33 INFO : A1/B2/C2: Removing directory 2026/09/24 04:46:33 INFO : A1/B1/C3: Removing directory 2026/09/24 04:46:34 DEBUG : removing 2 level 2 directories 2026/09/24 04:46:34 INFO : A3/B3: Removing directory 2026/09/24 04:46:34 INFO : A1/B2: Removing directory 2026/09/24 04:46:35 DEBUG : removing 2 level 1 directories 2026/09/24 04:46:35 INFO : A3: Removing directory 2026/09/24 04:46:35 INFO : A2: Removing directory 2026/09/24 04:46:38 DEBUG : removing 1 level 3 directories 2026/09/24 04:46:38 INFO : A1/B1/C1: Removing directory 2026/09/24 04:46:39 DEBUG : removing 1 level 2 directories 2026/09/24 04:46:39 INFO : A1/B1: Removing directory 2026/09/24 04:46:40 DEBUG : removing 1 level 1 directories 2026/09/24 04:46:40 INFO : A1: Removing directory 2026/09/24 04:46:40 DEBUG : removing 1 level 0 directories 2026/09/24 04:46:40 INFO : Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo': Removing directory --- PASS: TestRmdirsNoLeaveRoot (22.42s) === RUN TestRmdirsLeaveRoot run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:46:43 INFO : A1: Making directory 2026/09/24 04:46:43 INFO : A1/B1: Making directory 2026/09/24 04:46:44 INFO : A1/B1/C1: Making directory 2026/09/24 04:46:46 DEBUG : removing 1 level 3 directories 2026/09/24 04:46:46 INFO : A1/B1/C1: Removing directory 2026/09/24 04:46:47 DEBUG : removing 1 level 2 directories 2026/09/24 04:46:47 INFO : A1/B1: Removing directory --- PASS: TestRmdirsLeaveRoot (8.09s) === RUN TestRmdirsWithFilter run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:46:50 INFO : A1: Making directory 2026/09/24 04:46:51 INFO : A1/B1: Making directory 2026/09/24 04:46:51 INFO : A1/B1/C1: Making directory 2026/09/24 04:46:55 DEBUG : removing 1 level 3 directories 2026/09/24 04:46:55 INFO : A1/B1/C1: Removing directory 2026/09/24 04:46:55 DEBUG : removing 1 level 2 directories 2026/09/24 04:46:55 INFO : A1/B1: Removing directory --- PASS: TestRmdirsWithFilter (8.12s) === RUN TestCopyURL run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:47:00 DEBUG : file1: md5 = 98ada806dfa1452907b497e1ef40de41 OK 2026/09/24 04:47:00 DEBUG : file1: size = 14 OK 2026/09/24 04:47:00 DEBUG : filename.txt: File name found in url 2026/09/24 04:47:02 DEBUG : filename.txt: md5 = 93977f23d537ee5b147be759e0341255 OK 2026/09/24 04:47:02 DEBUG : filename.txt: size = 14 OK 2026/09/24 04:47:02 DEBUG : headerfilename.txt: filename found in Content-Disposition header. 2026/09/24 04:47:03 DEBUG : headerfilename.txt: md5 = 647b967819b481942ca27be7023d8b62 OK 2026/09/24 04:47:03 DEBUG : headerfilename.txt: size = 14 OK 2026/09/24 04:47:05 DEBUG : file2: md5 = a21fbc6a6b2d41628e411f85003bc355 OK 2026/09/24 04:47:05 DEBUG : file2: size = 14 OK --- PASS: TestCopyURL (8.95s) === RUN TestCopyURLDownloadHeaders run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:47:09 DEBUG : file1: md5 = bd0b8eef781046b6d332fc2f25a0be65 OK 2026/09/24 04:47:09 DEBUG : file1: size = 14 OK --- PASS: TestCopyURLDownloadHeaders (2.63s) === RUN TestCopyURLToWriter --- PASS: TestCopyURLToWriter (0.00s) === RUN TestMoveFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:47:10 DEBUG : file1: Need to transfer - File not found at Destination 2026/09/24 04:47:12 DEBUG : sub/file2: md5 = c30b0a13f638d5a95f741c7e2c9c8c06 OK 2026/09/24 04:47:12 DEBUG : sub/file2: size = 14 OK 2026/09/24 04:47:12 INFO : file1: Copied (new) to: sub/file2 2026/09/24 04:47:12 INFO : file1: Deleted 2026/09/24 04:47:13 DEBUG : sub/file2: size = 14 OK 2026/09/24 04:47:13 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/24 04:47:13 DEBUG : file1: Unchanged skipping 2026/09/24 04:47:13 INFO : file1: Deleted 2026/09/24 04:47:13 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo': don't need to copy/move sub/file2, it is already at target location --- PASS: TestMoveFile (5.90s) === RUN TestMoveFileWithIgnoreExisting run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:47:16 DEBUG : file1: Need to transfer - File not found at Destination 2026/09/24 04:47:17 DEBUG : file1: md5 = 93c3a3c105e7038ee8d372d165c6b6d4 OK 2026/09/24 04:47:17 DEBUG : file1: size = 14 OK 2026/09/24 04:47:17 INFO : file1: Copied (new) 2026/09/24 04:47:17 INFO : file1: Deleted 2026/09/24 04:47:18 DEBUG : file1: Destination exists, skipping 2026/09/24 04:47:18 DEBUG : file1: Not removing source file as destination file exists and --ignore-existing is set --- PASS: TestMoveFileWithIgnoreExisting (3.22s) === RUN TestMoveFileImmutable run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:47:20 DEBUG : existing: md5 = ff0f5ae7856b35978b2df616f92f23ab OK 2026/09/24 04:47:21 DEBUG : existing: size = 8 (Local file system at /tmp/rclone2912110156) 2026/09/24 04:47:21 DEBUG : existing: size = 6 (Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo') 2026/09/24 04:47:21 DEBUG : existing: Sizes differ 2026/09/24 04:47:21 ERROR : existing: Source and destination exist but do not match: immutable file modified --- PASS: TestMoveFileImmutable (3.08s) === RUN TestCaseInsensitiveMoveFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFile (0.47s) === RUN TestCaseInsensitiveMoveFileDryRun run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFileDryRun (0.45s) === RUN TestMoveFileBackupDir run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:47:25 DEBUG : dst/file1: md5 = dda39233f8434570107808bf2b8431ee OK 2026/09/24 04:47:26 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-mibuwin0qufo/backup" 2026/09/24 04:47:26 DEBUG : Config file has changed externally - reloading 2026/09/24 04:47:26 DEBUG : Creating backend with remote "TestDrive:crypt/dv3ch684ovo78si0l2skmn1iaecq98ci9h5f9b1qj60jlsi53ulg/1nrff024r7pq65ecp72fc28jb0" 2026/09/24 04:47:27 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone2912110156) 2026/09/24 04:47:27 DEBUG : dst/file1: size = 18 (Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo') 2026/09/24 04:47:27 DEBUG : dst/file1: Sizes differ 2026/09/24 04:47:29 INFO : dst/file1: Moved (server-side) 2026/09/24 04:47:31 DEBUG : dst/file1: md5 = db28d753cfcb89e8e5e8bb6075b1a543 OK 2026/09/24 04:47:31 DEBUG : dst/file1: size = 14 OK 2026/09/24 04:47:31 INFO : dst/file1: Copied (new) 2026/09/24 04:47:31 INFO : dst/file1: Deleted --- PASS: TestMoveFileBackupDir (12.75s) === RUN TestSameConfig --- PASS: TestSameConfig (0.00s) === RUN TestSame --- PASS: TestSame (0.00s) === RUN TestOverlappingFilterCheckWithoutFilter --- PASS: TestOverlappingFilterCheckWithoutFilter (0.00s) === RUN TestOverlappingFilterCheckWithFilter --- PASS: TestOverlappingFilterCheckWithFilter (0.00s) === RUN TestListFormat --- PASS: TestListFormat (0.00s) === RUN TestDirMoveMoveError run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:47:38 DEBUG : A/file0: md5 = 17e27c81b481e05f8298b4acf20eb3a3 OK 2026/09/24 04:47:39 DEBUG : A/file1: md5 = 3e09609fdd548839d9af9454c705cfe5 OK 2026/09/24 04:47:41 DEBUG : A/file2: md5 = a0244454f894f6be8f894482d415379f OK 2026/09/24 04:47:42 DEBUG : A/file3: md5 = b386396be90e9d064a757ff9886c5427 OK 2026/09/24 04:47:44 DEBUG : A/file4: md5 = 7076b931e16058a5720f19eb098d90bc OK 2026/09/24 04:47:45 DEBUG : A/file5: md5 = a33aa46bd0f0171e28e2fa0ac06b4e77 OK 2026/09/24 04:47:46 DEBUG : A/file6: md5 = a3315ffefa01a0a3a6fe72b4ed6d5759 OK 2026/09/24 04:47:48 DEBUG : A/file7: md5 = ea8d54705fec21c901ac46e0dba2d2d7 OK 2026/09/24 04:47:50 DEBUG : A/file8: md5 = 8eacbd1316dd8b3842f281fdbaee1f4f OK 2026/09/24 04:47:51 DEBUG : A/file9: md5 = c21e592c564c0190ea4bccf4f331973e OK 2026/09/24 04:47:53 DEBUG : A/file10: md5 = 1cd8fb6311ccb7ee5ebc887b04e48ae2 OK 2026/09/24 04:47:54 DEBUG : A/file11: md5 = 60b015e1b05c827fca479e8841cddeb7 OK 2026/09/24 04:47:55 DEBUG : A/file12: md5 = c08fd1935e9c45b252f8d354a17e5294 OK 2026/09/24 04:47:57 DEBUG : A/file13: md5 = f26bb00282892c93e676ffed6c58e7f7 OK 2026/09/24 04:47:58 DEBUG : A/file14: md5 = 643ec56699ce2958ef1d6c27fcf29417 OK 2026/09/24 04:48:00 DEBUG : A/file15: md5 = 170d723efc75cefd683e01be006b0d9a OK 2026/09/24 04:48:01 DEBUG : A/file16: md5 = 2fb5dd2125133debc03ba80f9d514fbc OK 2026/09/24 04:48:03 DEBUG : A/file17: md5 = 0191f14a81c5ecc67c6acd064c7846da OK 2026/09/24 04:48:04 DEBUG : A/file18: md5 = d88009aae69b284d3513b9f1a30f0764 OK 2026/09/24 04:48:06 DEBUG : A/file19: md5 = 6c3da2fddb5366f66b6cddc0d287965f OK operations_test.go:1490: Skipping test on non local remote --- SKIP: TestDirMoveMoveError (40.10s) === RUN TestDirMoveContext run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:48:18 DEBUG : A/one: md5 = 25b9e849a28f87b168b195afc05f6486 OK 2026/09/24 04:48:19 DEBUG : A/two: md5 = 2d8a964c77a8198ae83718d3239eb260 OK operations_test.go:1490: Skipping test on non local remote --- SKIP: TestDirMoveContext (5.86s) === RUN TestDirMove run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:48:24 DEBUG : A1/one: md5 = e1f54ffc18e270f4dae955d9c0693745 OK 2026/09/24 04:48:25 DEBUG : A1/two: md5 = 3fcc04b04befe2dd5c3d0bd159c94136 OK 2026/09/24 04:48:27 DEBUG : A1/B1/three: md5 = 79d728833c5f328faba5e1d8fab3677f OK 2026/09/24 04:48:29 DEBUG : A1/B1/C1/four: md5 = 5812a3bd5d4662f6361dd1dc8d404094 OK 2026/09/24 04:48:31 DEBUG : A1/B1/C2/five: md5 = 8141bfeea6cc953b3a6698871da9a90d OK 2026/09/24 04:48:31 INFO : A1/B2: Making directory 2026/09/24 04:48:32 INFO : A1/B1/C3: Making directory 2026/09/24 04:48:41 INFO : A2/two: Moved (server-side) to: A3/two 2026/09/24 04:48:42 INFO : A2/B1/C1/four: Moved (server-side) to: A3/B1/C1/four 2026/09/24 04:48:42 INFO : A2/B1/C2/five: Moved (server-side) to: A3/B1/C2/five 2026/09/24 04:48:42 INFO : A2/one: Moved (server-side) to: A3/one 2026/09/24 04:48:42 INFO : A2/B1/three: Moved (server-side) to: A3/B1/three 2026/09/24 04:48:47 INFO : Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo': Can't DirMove - falling back to file moves: can't move directory - incompatible remotes 2026/09/24 04:48:53 INFO : A3/B1/three: Moved (server-side) to: A4/B1/three 2026/09/24 04:48:53 INFO : A3/one: Moved (server-side) to: A4/one 2026/09/24 04:48:53 INFO : A3/B1/C2/five: Moved (server-side) to: A4/B1/C2/five 2026/09/24 04:48:53 INFO : A3/B1/C1/four: Moved (server-side) to: A4/B1/C1/four 2026/09/24 04:48:53 INFO : A3/two: Moved (server-side) to: A4/two --- PASS: TestDirMove (44.40s) === RUN TestGetFsInfo run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" --- PASS: TestGetFsInfo (0.42s) === RUN TestRcat === RUN TestRcat/withChecksum=false,ignoreChecksum=false run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:49:07 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo': File to upload is small (34 bytes), uploading instead of streaming 2026/09/24 04:49:08 DEBUG : no_checksum_small_file_from_pipe: md5 = ef2c50bb22c9396d0e238fbe47993062 OK 2026/09/24 04:49:08 DEBUG : no_checksum_small_file_from_pipe: size = 34 OK 2026/09/24 04:49:08 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2026/09/24 04:49:08 DEBUG : no_checksum_small_file_from_pipe: Size of src and dst objects identical 2026/09/24 04:49:09 DEBUG : qc9k4uauqa9ls6a2r6lo8jf4b1m7r0510pkurois1cc70egbs4h0: Sending chunk 0 length 102465 2026/09/24 04:49:10 DEBUG : no_checksum_big_file_from_pipe: md5 = 7f4351f59c59515979f8e1e5f18efac0 OK 2026/09/24 04:49:10 DEBUG : no_checksum_big_file_from_pipe: size = 102401 OK 2026/09/24 04:49:10 DEBUG : no_checksum_big_file_from_pipe: Size of src and dst objects identical === RUN TestRcat/withChecksum=true,ignoreChecksum=false run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:49:11 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo': File to upload is small (34 bytes), uploading instead of streaming 2026/09/24 04:49:13 DEBUG : with_checksum_small_file_from_pipe: md5 = 657df8bc4222fc8ef55547c7b163e489 OK 2026/09/24 04:49:13 DEBUG : with_checksum_small_file_from_pipe: size = 34 OK 2026/09/24 04:49:13 DEBUG : with_checksum_small_file_from_pipe: Size of src and dst objects identical 2026/09/24 04:49:13 DEBUG : e74jo8a7lr1aimg2u78fct67gnuetc1qujiofaci0c8fnq7rjt1d4i296pcklqhgdihs03etvgdi4: Sending chunk 0 length 102465 2026/09/24 04:49:15 DEBUG : with_checksum_big_file_from_pipe: md5 = 51848fb445a1c578398abf7fc6eb57f8 OK 2026/09/24 04:49:15 DEBUG : with_checksum_big_file_from_pipe: size = 102401 OK 2026/09/24 04:49:15 DEBUG : with_checksum_big_file_from_pipe: Size of src and dst objects identical === RUN TestRcat/withChecksum=false,ignoreChecksum=true run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:49:17 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo': File to upload is small (34 bytes), uploading instead of streaming 2026/09/24 04:49:18 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK 2026/09/24 04:49:18 DEBUG : ignore_checksum_small_file_from_pipe: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/24 04:49:19 DEBUG : 1963rn6olajfbjn4nbqi9nacu7bk879vpf0d2bg8r0ndcqqsfntob0urt056p0qujf574rqnb5f62: Sending chunk 0 length 102465 2026/09/24 04:49:20 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK 2026/09/24 04:49:20 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:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:49:22 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo': File to upload is small (34 bytes), uploading instead of streaming 2026/09/24 04:49:24 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK 2026/09/24 04:49:24 DEBUG : ignore_checksum_small_file_from_pipe: Size of src and dst objects identical 2026/09/24 04:49:24 DEBUG : 1963rn6olajfbjn4nbqi9nacu7bk879vpf0d2bg8r0ndcqqsfntob0urt056p0qujf574rqnb5f62: Sending chunk 0 length 102465 2026/09/24 04:49:26 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK 2026/09/24 04:49:26 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical --- PASS: TestRcat (20.85s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (4.81s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (5.76s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (5.10s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (5.18s) === RUN TestRcatMetadata run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" === RUN TestRcatMetadata/Normal 2026/09/24 04:49:27 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo': File to upload is small (48 bytes), uploading instead of streaming 2026/09/24 04:49:29 DEBUG : rcat_metadata: md5 = 05b1f79310dedb3e56545622dfcf9f3d OK 2026/09/24 04:49:29 DEBUG : rcat_metadata: size = 48 OK 2026/09/24 04:49:29 DEBUG : rcat_metadata: Size of src and dst objects identical === RUN TestRcatMetadata/ViaDisk 2026/09/24 04:49:31 DEBUG : rijssoobhbn85c5p1imhg55m6frank1nlplla6hkdjlqmqb2471g: Sending chunk 0 length 111 2026/09/24 04:49:32 DEBUG : rcat_metadata_uploadcutoff0: md5 = 31e72bcd997882ea00e9bbe47b7a2b42 OK 2026/09/24 04:49:32 DEBUG : rcat_metadata_uploadcutoff0: size = 63 OK 2026/09/24 04:49:32 DEBUG : rcat_metadata_uploadcutoff0: Size of src and dst objects identical --- PASS: TestRcatMetadata (6.13s) --- PASS: TestRcatMetadata/Normal (2.75s) --- PASS: TestRcatMetadata/ViaDisk (2.91s) === RUN TestRcatSize run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:49:35 DEBUG : potato1: md5 = c92e69c4d6a5cef6de59be4f0c42b046 OK 2026/09/24 04:49:35 DEBUG : potato1: size = 60 OK 2026/09/24 04:49:35 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo': File to upload is small (60 bytes), uploading instead of streaming 2026/09/24 04:49:37 DEBUG : potato2: md5 = 6804d619a0abd03f120b08c5464c4fdb OK 2026/09/24 04:49:37 DEBUG : potato2: size = 60 OK 2026/09/24 04:49:37 DEBUG : potato2: Size of src and dst objects identical --- PASS: TestRcatSize (4.89s) === RUN TestRcatSizeShortEOF run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:49:40 DEBUG : potato1: md5 = 904d1676621085d6c494019a84d76819 OK 2026/09/24 04:49:40 DEBUG : potato1: size = 120 (Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo') 2026/09/24 04:49:40 DEBUG : potato1: size = 60 (Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo') 2026/09/24 04:49:40 ERROR : potato1: corrupted on transfer: sizes differ src 120 vs dst(Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo') 60 2026/09/24 04:49:40 INFO : potato1: Removing failed copy --- PASS: TestRcatSizeShortEOF (2.28s) === RUN TestRcatSizeMetadata run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:49:42 DEBUG : potato1: md5 = 830296095bdd744e59321279c4e20884 OK 2026/09/24 04:49:42 DEBUG : potato1: size = 60 OK 2026/09/24 04:49:42 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo': File to upload is small (60 bytes), uploading instead of streaming 2026/09/24 04:49:43 DEBUG : potato2: md5 = 77b72fafb1bcfc945eb79a3c2413d5ce OK 2026/09/24 04:49:43 DEBUG : potato2: size = 60 OK 2026/09/24 04:49:43 DEBUG : potato2: Size of src and dst objects identical --- PASS: TestRcatSizeMetadata (5.29s) === RUN TestRcatSizeUploadHeaders run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:49:47 DEBUG : potato1: md5 = 2d4c22813490522f95ad6e7b12ebb53d OK 2026/09/24 04:49:47 DEBUG : potato1: size = 60 OK --- PASS: TestRcatSizeUploadHeaders (2.35s) === RUN TestRcatSizeChecksum === RUN TestRcatSizeChecksum/Corrupted run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" operations_test.go:1975: Skipping as destination doesn't support hashes === RUN TestRcatSizeChecksum/SizeDiffers run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:49:50 DEBUG : potato4: md5 = 7b163a07c421873d8bdf7aa01e216c98 OK 2026/09/24 04:49:50 DEBUG : potato4: size = 60 (Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo') 2026/09/24 04:49:50 DEBUG : potato4: size = 59 (Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo') 2026/09/24 04:49:50 ERROR : potato4: corrupted on transfer: sizes differ src 60 vs dst(Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo') 59 2026/09/24 04:49:50 INFO : potato4: Removing failed copy === RUN TestRcatSizeChecksum/IgnoreChecksum run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:49:53 DEBUG : potato2: size = 60 OK === RUN TestRcatSizeChecksum/NoHashes run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:51:50 DEBUG : potato3: md5 = 58d168f93a7df6d7cb277164ebce8743 OK 2026/09/24 04:51:50 DEBUG : potato3: size = 60 OK --- PASS: TestRcatSizeChecksum (123.28s) --- SKIP: TestRcatSizeChecksum/Corrupted (0.51s) --- PASS: TestRcatSizeChecksum/SizeDiffers (2.99s) --- PASS: TestRcatSizeChecksum/IgnoreChecksum (2.42s) --- PASS: TestRcatSizeChecksum/NoHashes (117.35s) === RUN TestTouchDir run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:51:53 DEBUG : potato2: md5 = d07802e5d5e981f2a7d84b9e940acc7f OK 2026/09/24 04:51:55 DEBUG : empty space: md5 = 90823a1eb33641abbc8a57cc81b1c6c5 OK 2026/09/24 04:51:57 DEBUG : sub dir/potato3: md5 = 0a5adfb5dbfd5fdb25756d449bbb2f18 OK 2026/09/24 04:51:58 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo': Touching "sub dir/potato3" 2026/09/24 04:51:58 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo': Touching "potato2" 2026/09/24 04:51:58 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo': Touching "empty space" --- PASS: TestTouchDir (10.34s) === RUN TestMkdirMetadata run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:52:02 DEBUG : dir with metadata: Making directory with metadata 2026/09/24 04:52:02 INFO : dir with metadata: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) --- PASS: TestMkdirMetadata (2.73s) === RUN TestMkdirModTime run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:52:04 DEBUG : directory with modtime: Making directory with metadata 2026/09/24 04:52:05 INFO : directory with modtime: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/09/24 04:52:05 NOTICE: dry run directory with modtime: Skipped make directory as --dry-run is set --- PASS: TestMkdirModTime (2.48s) === RUN TestCopyDirMetadata run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:52:07 DEBUG : dir with metadata to be copied: Making directory with metadata 2026/09/24 04:52:07 INFO : dir with metadata to be copied: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2026/09/24 04:52:07 DEBUG : Google drive root 'crypt/dv3ch684ovo78si0l2skmn1iaecq98ci9h5f9b1qj60jlsi53ulg': Skipping btime metadata as can't update it on an existing file: 2026-09-24T04:52:07.4436404Z 2026/09/24 04:52:08 INFO : non existent directory: Updated directory metadata 2026/09/24 04:54:02 DEBUG : Google drive root 'crypt/dv3ch684ovo78si0l2skmn1iaecq98ci9h5f9b1qj60jlsi53ulg': Skipping btime metadata as can't update it on an existing file: 2026-09-24T04:52:07.4436404Z 2026/09/24 04:54:02 INFO : existing directory: Updated directory metadata --- PASS: TestCopyDirMetadata (117.09s) === RUN TestSetDirModTime run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:54:04 DEBUG : set modtime on non existent directory: Skipping set directory modification time as --no-update-dir-modtime is set 2026/09/24 04:54:06 INFO : set modtime on existing directory: Set directory modification time (using SetModTime) 2026/09/24 04:54:06 INFO : set modtime on existing directory: Set directory modification time (using DirSetModTime) --- PASS: TestSetDirModTime (4.22s) === RUN TestDirsEqual run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:54:08 DEBUG : dir with metadata to be copied: Making directory with metadata 2026/09/24 04:54:08 INFO : dir with metadata to be copied: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2026/09/24 04:54:08 DEBUG : dst: Making directory with metadata 2026/09/24 04:54:09 INFO : dst: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2026/09/24 04:54:09 DEBUG : dst: Directory modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/24 04:54:09 INFO : dst: Set directory modification time (using SetModTime) 2026/09/24 04:54:10 INFO : dst: Set directory modification time (using SetModTime) 2026/09/24 04:54:10 DEBUG : dst: Directory modification time the same (differ by 1ns, within tolerance 1ms) 2026/09/24 04:54:10 INFO : dst: Set directory modification time (using SetModTime) 2026/09/24 04:54:10 DEBUG : dst: Destination directory is newer than source, skipping --- PASS: TestDirsEqual (3.62s) === RUN TestRemoveExisting run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo'", Local "Local file system at /tmp/rclone2912110156", Modify Window "1ms" 2026/09/24 04:54:14 DEBUG : sub dir/test remove existing: md5 = 43c731d70db51cf3edc44e6fbd10bc19 OK 2026/09/24 04:54:15 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: md5 = b9e56b3a41626fb4d48c6cd7cdc1e49e OK 2026/09/24 04:54:18 DEBUG : sub dir/test remove existing: TEST: renaming existing object to "sub dir/test remove existing.vudabal8" before starting 2026/09/24 04:54:19 DEBUG : sub dir/test remove existing.vudabal8: TEST: removing renamed existing file after operation 2026/09/24 04:54:20 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: TEST: renaming existing object to "sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.jusikef6" before starting 2026/09/24 04:54:21 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.jusikef6: TEST: renaming existing back after failed operation 2026/09/24 04:54:22 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: TEST: renaming existing object to "sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.fubikig8" before starting 2026/09/24 04:55:07 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.fubikig8: TEST: removing renamed existing file after operation --- PASS: TestRemoveExisting (57.10s) === RUN TestRcatInputFailurePreservesDestination 2026/09/24 04:55:09 DEBUG : Creating backend with remote "/tmp/TestRcatInputFailurePreservesDestination2951179527/001" 2026/09/24 04:55:09 DEBUG : Config file has changed externally - reloading --- PASS: TestRcatInputFailurePreservesDestination (0.00s) === RUN TestRcAbout rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcAbout (0.00s) === RUN TestRcCleanup rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcCleanup (0.00s) === RUN TestRcCopyfile rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcCopyfile (0.00s) === RUN TestRcCopyurl rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcCopyurl (0.00s) === RUN TestRcDelete rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcDelete (0.00s) === RUN TestRcDeletefile rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcDeletefile (0.00s) === RUN TestRcList rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcList (0.00s) === RUN TestRcStat rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcStat (0.00s) === RUN TestRcSetTier rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcSetTier (0.00s) === RUN TestRcSetTierFile rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcSetTierFile (0.00s) === RUN TestRcMkdir rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcMkdir (0.00s) === RUN TestRcMovefile rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcMovefile (0.00s) === RUN TestRcPurge rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcPurge (0.00s) === RUN TestRcRmdir rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcRmdir (0.00s) === RUN TestRcRmdirs rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcRmdirs (0.00s) === RUN TestRcSize rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcSize (0.00s) === RUN TestRcPublicLink rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcPublicLink (0.00s) === RUN TestRcFsInfo rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcFsInfo (0.00s) === RUN TestUploadFile rc_test.go:31: Skipping test on non local remote --- SKIP: TestUploadFile (0.00s) === RUN TestRcCommand rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcCommand (0.00s) === RUN TestRcDu rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcDu (0.00s) === RUN TestRcCheck rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcCheck (0.00s) === RUN TestRcHashsum rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcHashsum (0.00s) === RUN TestRcHashsumSingleFile rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcHashsumSingleFile (0.00s) === RUN TestRcHashsumFile rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcHashsumFile (0.00s) === RUN TestRcGetFile rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcGetFile (0.00s) PASS 2026/09/24 04:55:09 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-mibuwin0qufo': Purge remote "./operations.test -test.v -test.timeout 1h0m0s -remote TestCryptDrive: -verbose -fast-list" - Finished OK in 20m49.937938474s (try 1/5)