"./operations.test -test.v -test.timeout 1h0m0s -remote TestCryptDrive: -verbose" - Starting (try 1/5) 2026/09/29 01:01:16 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jaqutih0kife" 2026/09/29 01:01:16 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/29 01:01:16 DEBUG : Creating backend with remote "TestDrive:crypt/ag9v8arsn74v53ofqqbcmf8p23a3qi5hjh7fsbe1q81i4g6up45g" 2026/09/29 01:01:17 DEBUG : Creating backend with remote "/tmp/rclone839572334" === 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-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" multithread_test.go:120: multithread writing not supported --- SKIP: TestMultithreadCopy (0.28s) === RUN TestMultithreadCopyAbort run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" multithread_test.go:120: multithread writing not supported --- SKIP: TestMultithreadCopyAbort (0.26s) === RUN TestMultithreadCopyWriterAtErrors === RUN TestMultithreadCopyWriterAtErrors/open/fatal=false 2026/09/29 01:01:18 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt === RUN TestMultithreadCopyWriterAtErrors/open/fatal=true 2026/09/29 01:01:18 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt === RUN TestMultithreadCopyWriterAtErrors/write/fatal=false 2026/09/29 01:01:18 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2026/09/29 01:01:18 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams 2026/09/29 01:01:18 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting 2026/09/29 01:01:18 DEBUG : file.txt: writing chunk 1 2026/09/29 01:01:18 DEBUG : file.txt: multi-thread copy: chunk 2/4 failed: multi-thread copy: failed to write chunk: disk full 2026/09/29 01:01:18 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting 2026/09/29 01:01:18 DEBUG : file.txt: writing chunk 2 2026/09/29 01:01:18 DEBUG : file.txt: multi-thread copy: chunk 3/4 failed: multi-thread copy: failed to write chunk: disk full 2026/09/29 01:01:18 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting 2026/09/29 01:01:18 DEBUG : file.txt: writing chunk 0 2026/09/29 01:01:18 DEBUG : file.txt: multi-thread copy: chunk 1/4 failed: multi-thread copy: failed to write chunk: disk full 2026/09/29 01:01:18 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit 2026/09/29 01:01:18 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/29 01:01:18 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2026/09/29 01:01:18 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams 2026/09/29 01:01:18 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting 2026/09/29 01:01:18 DEBUG : file.txt: writing chunk 1 2026/09/29 01:01:18 DEBUG : file.txt: multi-thread copy: chunk 2/4 failed: multi-thread copy: failed to write chunk: disk full 2026/09/29 01:01:18 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting 2026/09/29 01:01:18 DEBUG : file.txt: writing chunk 2 2026/09/29 01:01:18 DEBUG : file.txt: multi-thread copy: chunk 3/4 failed: multi-thread copy: failed to write chunk: disk full 2026/09/29 01:01:18 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting 2026/09/29 01:01:18 DEBUG : file.txt: writing chunk 0 2026/09/29 01:01:18 DEBUG : file.txt: multi-thread copy: chunk 1/4 failed: multi-thread copy: failed to write chunk: disk full 2026/09/29 01:01:18 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit 2026/09/29 01:01:18 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/29 01:01:18 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2026/09/29 01:01:18 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams 2026/09/29 01:01:18 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting 2026/09/29 01:01:18 DEBUG : file.txt: writing chunk 1 2026/09/29 01:01:18 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 finished 2026/09/29 01:01:18 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting 2026/09/29 01:01:18 DEBUG : file.txt: writing chunk 2 2026/09/29 01:01:18 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 finished 2026/09/29 01:01:18 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 starting 2026/09/29 01:01:18 DEBUG : file.txt: writing chunk 3 2026/09/29 01:01:18 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 finished 2026/09/29 01:01:18 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting 2026/09/29 01:01:18 DEBUG : file.txt: writing chunk 0 2026/09/29 01:01:18 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 finished 2026/09/29 01:01:18 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit 2026/09/29 01:01:18 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/29 01:01:18 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2026/09/29 01:01:18 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams 2026/09/29 01:01:18 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting 2026/09/29 01:01:18 DEBUG : file.txt: writing chunk 1 2026/09/29 01:01:18 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 finished 2026/09/29 01:01:18 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting 2026/09/29 01:01:18 DEBUG : file.txt: writing chunk 2 2026/09/29 01:01:18 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 finished 2026/09/29 01:01:18 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 starting 2026/09/29 01:01:18 DEBUG : file.txt: writing chunk 3 2026/09/29 01:01:18 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 finished 2026/09/29 01:01:18 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting 2026/09/29 01:01:18 DEBUG : file.txt: writing chunk 0 2026/09/29 01:01:18 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 finished 2026/09/29 01:01:18 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit 2026/09/29 01:01:18 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/29 01:01:18 DEBUG : a: size = 0 OK 2026/09/29 01:01:18 DEBUG : a: size = 1 (memory) 2026/09/29 01:01:18 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/29 01:01:18 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/ErrorAtStart === RUN TestReOpen/Normal/WithErrors 2026/09/29 01:01:18 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/09/29 01:01:18 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/09/29 01:01:18 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/Normal/TooManyErrors 2026/09/29 01:01:18 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/09/29 01:01:18 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/09/29 01:01:18 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/09/29 01:01:18 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/Normal/ReadAt 2026/09/29 01:01:18 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/09/29 01:01:18 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/09/29 01:01:18 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/Normal/Seek 2026/09/29 01:01:18 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/09/29 01:01:18 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/09/29 01:01:18 DEBUG : potato: Seek from 5 to 2 === RUN TestReOpen/Normal/AccountRead === RUN TestReOpen/Normal/AccountReadDelay 2026/09/29 01:01:18 DEBUG : potato: Seek from 10 to 0 2026/09/29 01:01:18 DEBUG : potato: Seek from 10 to 0 2026/09/29 01:01:18 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/AccountReadError === RUN TestReOpen/WithRangeOption === RUN TestReOpen/WithRangeOption/Basics 2026/09/29 01:01:18 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/ErrorAtStart === RUN TestReOpen/WithRangeOption/WithErrors 2026/09/29 01:01:18 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/09/29 01:01:18 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/09/29 01:01:18 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithRangeOption/TooManyErrors 2026/09/29 01:01:18 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/09/29 01:01:18 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/09/29 01:01:18 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/09/29 01:01:18 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithRangeOption/ReadAt 2026/09/29 01:01:18 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/09/29 01:01:18 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/09/29 01:01:18 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/WithRangeOption/Seek 2026/09/29 01:01:18 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/09/29 01:01:18 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/09/29 01:01:18 DEBUG : potato: Seek from 5 to 2 2026/09/29 01:01:18 DEBUG : potato: Seek from 7 to 4 === RUN TestReOpen/WithRangeOption/AccountRead === RUN TestReOpen/WithRangeOption/AccountReadDelay 2026/09/29 01:01:18 DEBUG : potato: Seek from 7 to 0 2026/09/29 01:01:18 DEBUG : potato: Seek from 7 to 0 2026/09/29 01:01:18 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/AccountReadError === RUN TestReOpen/WithSeekOption === RUN TestReOpen/WithSeekOption/Basics 2026/09/29 01:01:18 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/ErrorAtStart === RUN TestReOpen/WithSeekOption/WithErrors 2026/09/29 01:01:18 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/09/29 01:01:18 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/09/29 01:01:18 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithSeekOption/TooManyErrors 2026/09/29 01:01:18 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/09/29 01:01:18 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/09/29 01:01:18 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/09/29 01:01:18 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithSeekOption/ReadAt 2026/09/29 01:01:18 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/09/29 01:01:18 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/09/29 01:01:18 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/WithSeekOption/Seek 2026/09/29 01:01:18 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/09/29 01:01:18 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/09/29 01:01:18 DEBUG : potato: Seek from 5 to 2 2026/09/29 01:01:18 DEBUG : potato: Seek from 7 to 5 === RUN TestReOpen/WithSeekOption/AccountRead === RUN TestReOpen/WithSeekOption/AccountReadDelay 2026/09/29 01:01:18 DEBUG : potato: Seek from 8 to 0 2026/09/29 01:01:18 DEBUG : potato: Seek from 8 to 0 2026/09/29 01:01:18 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/AccountReadError === RUN TestReOpen/UnknownSize === RUN TestReOpen/UnknownSize/Basics 2026/09/29 01:01:18 DEBUG : potato: Seek from 9 to 0 === RUN TestReOpen/UnknownSize/ErrorAtStart === RUN TestReOpen/UnknownSize/WithErrors 2026/09/29 01:01:18 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/09/29 01:01:18 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/09/29 01:01:18 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/UnknownSize/TooManyErrors 2026/09/29 01:01:18 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/09/29 01:01:18 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/09/29 01:01:18 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/09/29 01:01:18 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/UnknownSize/ReadAt 2026/09/29 01:01:18 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/09/29 01:01:18 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/09/29 01:01:18 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/UnknownSize/Seek 2026/09/29 01:01:18 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/09/29 01:01:18 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/09/29 01:01:18 DEBUG : potato: Seek from 5 to 2 2026/09/29 01:01:18 DEBUG : potato: Seek from 7 to 6 === RUN TestReOpen/UnknownSize/AccountRead === RUN TestReOpen/UnknownSize/AccountReadDelay 2026/09/29 01:01:18 DEBUG : potato: Seek from 9 to 0 2026/09/29 01:01:18 DEBUG : potato: Seek from 9 to 0 2026/09/29 01:01:18 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-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:01:20 DEBUG : rutabaga: md5 = 669431f2db17fbad639a99b4cc31b811 OK === RUN TestCheck/1 === RUN TestCheck/2 2026/09/29 01:01:23 DEBUG : empty space: md5 = c3bfc855594db926187d8a77a0adfca1 OK === RUN TestCheck/3 2026/09/29 01:01:25 DEBUG : potato2: md5 = 9c74072307c21708d4792748fe5f1df5 OK === RUN TestCheck/4 === RUN TestCheck/5 2026/09/29 01:01:28 DEBUG : remotepotato: md5 = 61f830703df87aba3ce5a6872f25397f OK === RUN TestCheck/6 === RUN TestCheck/7 --- PASS: TestCheck (13.46s) --- PASS: TestCheck/1 (0.31s) --- PASS: TestCheck/2 (0.33s) --- PASS: TestCheck/3 (0.26s) --- PASS: TestCheck/4 (0.27s) --- PASS: TestCheck/5 (0.28s) --- PASS: TestCheck/6 (0.27s) --- PASS: TestCheck/7 (0.27s) === RUN TestCheckFsError 2026/09/29 01:01:31 DEBUG : Creating backend with remote "nonexistent" 2026/09/29 01:01:31 DEBUG : Creating backend with remote "nonexistent" 2026/09/29 01:01:31 DEBUG : Local file system at nonexistent: Waiting for checks to finish 2026/09/29 01:01:31 ERROR : Local file system at nonexistent: error reading source root directory: directory not found 2026/09/29 01:01:31 NOTICE: Local file system at nonexistent: 0 differences found 2026/09/29 01:01:31 NOTICE: Local file system at nonexistent: 2 errors while checking --- PASS: TestCheckFsError (0.01s) === RUN TestCheckDownload run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:01:33 DEBUG : rutabaga: md5 = 32a6163d87987a66db3b89fdbd200c4c OK === RUN TestCheckDownload/1 === RUN TestCheckDownload/2 2026/09/29 01:01:37 DEBUG : empty space: md5 = d24d489b5f93f416378cd36c510f8f31 OK === RUN TestCheckDownload/3 2026/09/29 01:01:40 DEBUG : potato2: md5 = 9105d85bca5d917043d52cabfeee8bb2 OK === RUN TestCheckDownload/4 === RUN TestCheckDownload/5 2026/09/29 01:01:44 DEBUG : remotepotato: md5 = 39bd7a82b08dd16ac61cda0ad0d07a0b OK === RUN TestCheckDownload/6 === RUN TestCheckDownload/7 --- PASS: TestCheckDownload (17.00s) --- PASS: TestCheckDownload/1 (1.32s) --- PASS: TestCheckDownload/2 (0.80s) --- PASS: TestCheckDownload/3 (0.83s) --- PASS: TestCheckDownload/4 (0.94s) --- PASS: TestCheckDownload/5 (0.83s) --- PASS: TestCheckDownload/6 (0.79s) --- PASS: TestCheckDownload/7 (0.79s) === RUN TestCheckSizeOnly run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:01:50 DEBUG : rutabaga: md5 = 477b3ab86e1593a41d47b4c0ca338fb0 OK === RUN TestCheckSizeOnly/1 === RUN TestCheckSizeOnly/2 2026/09/29 01:01:52 DEBUG : empty space: md5 = 39d57d63b9f1f89cd587395ab6b5b26b OK === RUN TestCheckSizeOnly/3 2026/09/29 01:01:55 DEBUG : potato2: md5 = c5f995ce23de0db02b36bbde4bce97fc OK === RUN TestCheckSizeOnly/4 === RUN TestCheckSizeOnly/5 2026/09/29 01:01:57 DEBUG : remotepotato: md5 = 68a7698883e4232ce7b8fa038f6284c6 OK === RUN TestCheckSizeOnly/6 === RUN TestCheckSizeOnly/7 --- PASS: TestCheckSizeOnly (12.69s) --- PASS: TestCheckSizeOnly/1 (0.26s) --- PASS: TestCheckSizeOnly/2 (0.26s) --- PASS: TestCheckSizeOnly/3 (0.29s) --- PASS: TestCheckSizeOnly/4 (0.25s) --- PASS: TestCheckSizeOnly/5 (0.31s) --- PASS: TestCheckSizeOnly/6 (0.33s) --- PASS: TestCheckSizeOnly/7 (0.27s) === RUN TestCheckEqualReaders --- PASS: TestCheckEqualReaders (0.00s) === RUN TestParseSumFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:02:03 DEBUG : test.sum: md5 = bab87fe5b11eb10a385459b73b11de24 OK 2026/09/29 01:02:04 NOTICE: test.sum: improperly formatted checksum line 4 2026/09/29 01:02:04 NOTICE: test.sum: improperly formatted checksum line 5 2026/09/29 01:02:04 NOTICE: test.sum: improperly formatted checksum line 6 2026/09/29 01:02:04 NOTICE: test.sum: 2 warning(s) suppressed... 2026/09/29 01:02:06 DEBUG : test.sum: md5 = d97eed19b896065721d990cf15b6d0b9 OK 2026/09/29 01:02:07 NOTICE: test.sum: improperly formatted checksum line 4 2026/09/29 01:02:07 NOTICE: test.sum: improperly formatted checksum line 5 2026/09/29 01:02:07 NOTICE: test.sum: improperly formatted checksum line 6 2026/09/29 01:02:07 NOTICE: test.sum: 2 warning(s) suppressed... --- PASS: TestParseSumFile (6.59s) === RUN TestCheckSum run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:02:08 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jaqutih0kife/data" 2026/09/29 01:02:08 DEBUG : Creating backend with remote "TestDrive:crypt/ag9v8arsn74v53ofqqbcmf8p23a3qi5hjh7fsbe1q81i4g6up45g/vjrnln8ratgmqakfosrqe8espk" check_test.go:354: Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife/data' lacks md5, skipping --- SKIP: TestCheckSum (2.14s) === RUN TestCheckSumDownload run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:02:10 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jaqutih0kife/data" 2026/09/29 01:02:10 DEBUG : Creating backend with remote "TestDrive:crypt/ag9v8arsn74v53ofqqbcmf8p23a3qi5hjh7fsbe1q81i4g6up45g/vjrnln8ratgmqakfosrqe8espk" 2026/09/29 01:02:15 DEBUG : data/banana: md5 = 8a6470f654195ebe713bcc1e74acb2ab OK 2026/09/29 01:02:17 DEBUG : test.sum: md5 = 6f044bef168409754e300fb451f94abc OK === RUN TestCheckSumDownload/subtest1 2026/09/29 01:02:23 DEBUG : data/potato: md5 = 0384986389ca4d13be7e4ed1a035bbb7 OK 2026/09/29 01:02:24 DEBUG : test.sum: md5 = a3e9bf77e3abea615f8a16755c5a0a7f OK === RUN TestCheckSumDownload/subtest2 2026/09/29 01:02:28 DEBUG : test.sum: md5 = 9b1b3d393ce292766bffb891312c7ad2 OK === RUN TestCheckSumDownload/subtest3 2026/09/29 01:02:34 DEBUG : test.sum: md5 = d7c69c0fadb8f671b6f04e4ea9e3d0b9 OK === RUN TestCheckSumDownload/subtest4 2026/09/29 01:02:38 DEBUG : test.sum: md5 = d1b07bf646cc9ba0acbc3da1eb090c56 OK === RUN TestCheckSumDownload/subtest5 2026/09/29 01:02:42 DEBUG : test.sum: md5 = 002a46018bd2a85f53489e6e9fd15840 OK === RUN TestCheckSumDownload/subtest6 2026/09/29 01:02:46 DEBUG : data/banana: md5 = c74df5b50b2bc44ddda748c570d2993a OK 2026/09/29 01:02:47 DEBUG : data/potato: md5 = 2d93a8983afea01426d9b65d0b5f14c6 OK 2026/09/29 01:02:49 DEBUG : test.sum: md5 = 6318173b07e6db5f1eed2bb057da5467 OK === RUN TestCheckSumDownload/subtest7 --- PASS: TestCheckSumDownload (52.21s) --- PASS: TestCheckSumDownload/subtest1 (2.61s) --- PASS: TestCheckSumDownload/subtest2 (1.85s) --- PASS: TestCheckSumDownload/subtest3 (2.25s) --- PASS: TestCheckSumDownload/subtest4 (1.85s) --- PASS: TestCheckSumDownload/subtest5 (1.84s) --- PASS: TestCheckSumDownload/subtest6 (1.95s) --- PASS: TestCheckSumDownload/subtest7 (9.46s) === RUN TestCheckSumConcurrency 2026/09/29 01:03:02 DEBUG : file0: md5 = 022eb0c0085c731f335df970fafa7446 OK 2026/09/29 01:03:02 DEBUG : file2: md5 = a58f09ae3db8ae4b943fdbaace096072 OK 2026/09/29 01:03:02 DEBUG : file1: md5 = 17fbabbccbdea0e6be9eb64c8c6fea41 OK 2026/09/29 01:03:02 DEBUG : file3: md5 = e0639b17e0744aca9a3ba29c049e59ee OK 2026/09/29 01:03:02 NOTICE: Mock file system at checkSum: 0 differences found 2026/09/29 01:03:02 NOTICE: Mock file system at checkSum: 4 matching files --- PASS: TestCheckSumConcurrency (0.00s) === RUN TestCheckSumDownloadConcurrency 2026/09/29 01:03:02 DEBUG : file0: md5 = 022eb0c0085c731f335df970fafa7446 OK 2026/09/29 01:03:02 DEBUG : file2: md5 = a58f09ae3db8ae4b943fdbaace096072 OK 2026/09/29 01:03:02 DEBUG : file1: md5 = 17fbabbccbdea0e6be9eb64c8c6fea41 OK 2026/09/29 01:03:02 DEBUG : file3: md5 = e0639b17e0744aca9a3ba29c049e59ee OK 2026/09/29 01:03:02 NOTICE: Mock file system at checkSum: 0 differences found 2026/09/29 01:03:02 NOTICE: Mock file system at checkSum: 4 matching files --- PASS: TestCheckSumDownloadConcurrency (0.00s) === RUN TestApplyTransforms 2026/09/29 01:03:02 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-tuzikeh2qire" 2026/09/29 01:03:02 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/29 01:03:02 DEBUG : Creating backend with remote "TestDrive:crypt/p35rttnmtj716cr472rc9o3pjv6ug5pc338astvf2o5bdecr17og" 2026/09/29 01:03:03 DEBUG : Creating backend with remote "/tmp/rclone2858037810" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tuzikeh2qire'", Local "Local file system at /tmp/rclone2858037810", Modify Window "1ms" 2026/09/29 01:03:06 DEBUG : hello, world!: md5 = 60f63166b5ccdf036187a62ad93a5f96 OK upper checkfile vs. lower remote (without normalization) 2026/09/29 01:03:07 ERROR : hello, world!: sum not found 2026/09/29 01:03:07 ERROR : HELLO, WORLD!: file not in Encrypted drive 'TestCryptDrive:rclone-test-tuzikeh2qire' 2026/09/29 01:03:07 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-tuzikeh2qire': 1 files missing 2026/09/29 01:03:07 NOTICE: 1 hashes missing 2026/09/29 01:03:07 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-tuzikeh2qire': 1 differences found 2026/09/29 01:03:07 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-tuzikeh2qire': 2 errors while checking upper checkfile vs. lower remote (with normalization) 2026/09/29 01:03:08 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/29 01:03:08 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-tuzikeh2qire': 0 differences found 2026/09/29 01:03:08 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-tuzikeh2qire': 1 matching files 2026/09/29 01:03:08 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-conazap6juju" 2026/09/29 01:03:08 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/29 01:03:08 DEBUG : Creating backend with remote "TestDrive:crypt/1pmdhlbfb43damadp3ouc056brm9maqu2ms08rhrcfl7m3rn9rt0" 2026/09/29 01:03:09 DEBUG : Creating backend with remote "/tmp/rclone1776317988" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-conazap6juju'", Local "Local file system at /tmp/rclone1776317988", Modify Window "1ms" 2026/09/29 01:03:11 DEBUG : HELLO, WORLD!: md5 = b105546bfc2a9442a4afcaee1c704bd4 OK lower checkfile vs. upper remote (without normalization) 2026/09/29 01:03:12 ERROR : HELLO, WORLD!: sum not found 2026/09/29 01:03:12 ERROR : hello, world!: file not in Encrypted drive 'TestCryptDrive:rclone-test-conazap6juju' 2026/09/29 01:03:12 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-conazap6juju': 1 files missing 2026/09/29 01:03:12 NOTICE: 1 hashes missing 2026/09/29 01:03:12 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-conazap6juju': 1 differences found 2026/09/29 01:03:12 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-conazap6juju': 2 errors while checking lower checkfile vs. upper remote (with normalization) 2026/09/29 01:03:27 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/29 01:03:27 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-conazap6juju': 0 differences found 2026/09/29 01:03:27 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-conazap6juju': 1 matching files 2026/09/29 01:03:27 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-daladiw5leqe" 2026/09/29 01:03:27 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/29 01:03:27 DEBUG : Creating backend with remote "TestDrive:crypt/dnspa6rbt0mf7bsvhlp39ee0htgdk6d1eo4e3it3hkldo03s9cs0" 2026/09/29 01:03:28 DEBUG : Creating backend with remote "/tmp/rclone2384727046" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-daladiw5leqe'", Local "Local file system at /tmp/rclone2384727046", Modify Window "1ms" 2026/09/29 01:03:31 DEBUG : HeLlO, wOrLd!: md5 = 4d45e0a9b7a055abaceb2ac761357c60 OK lower checkfile vs. upperlowermixed remote (without normalization) 2026/09/29 01:03:32 ERROR : HeLlO, wOrLd!: sum not found 2026/09/29 01:03:32 ERROR : hello, world!: file not in Encrypted drive 'TestCryptDrive:rclone-test-daladiw5leqe' 2026/09/29 01:03:32 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-daladiw5leqe': 1 files missing 2026/09/29 01:03:32 NOTICE: 1 hashes missing 2026/09/29 01:03:32 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-daladiw5leqe': 1 differences found 2026/09/29 01:03:32 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-daladiw5leqe': 2 errors while checking lower checkfile vs. upperlowermixed remote (with normalization) 2026/09/29 01:03:40 DEBUG : HeLlO, wOrLd!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/29 01:03:40 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-daladiw5leqe': 0 differences found 2026/09/29 01:03:40 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-daladiw5leqe': 1 matching files 2026/09/29 01:03:40 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-ruqepon5jeza" 2026/09/29 01:03:40 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/29 01:03:41 DEBUG : Creating backend with remote "TestDrive:crypt/5mn6tr9r57ke0anmih3qmrir3p9gkej5i3kn409efpa4685fsol0" 2026/09/29 01:03:42 DEBUG : Creating backend with remote "/tmp/rclone3215714877" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-ruqepon5jeza'", Local "Local file system at /tmp/rclone3215714877", Modify Window "1ms" 2026/09/29 01:03:44 DEBUG : HELLO, WORLD!: md5 = baa7e3a63c2810fb85a88f9ba26c7582 OK upperlowermixed checkfile vs. upper remote (without normalization) 2026/09/29 01:03:45 ERROR : HELLO, WORLD!: sum not found 2026/09/29 01:03:45 ERROR : HeLlO, wOrLd!: file not in Encrypted drive 'TestCryptDrive:rclone-test-ruqepon5jeza' 2026/09/29 01:03:45 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-ruqepon5jeza': 1 files missing 2026/09/29 01:03:45 NOTICE: 1 hashes missing 2026/09/29 01:03:45 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-ruqepon5jeza': 1 differences found 2026/09/29 01:03:45 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-ruqepon5jeza': 2 errors while checking upperlowermixed checkfile vs. upper remote (with normalization) 2026/09/29 01:03:46 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/29 01:03:46 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-ruqepon5jeza': 0 differences found 2026/09/29 01:03:46 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-ruqepon5jeza': 1 matching files 2026/09/29 01:03:46 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-vigugak2capu" 2026/09/29 01:03:46 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/29 01:03:46 DEBUG : Creating backend with remote "TestDrive:crypt/ad2fmsm1gjb42j348bmm9qeff96nl0c56bjki068vi54btmbnl20" 2026/09/29 01:03:47 DEBUG : Creating backend with remote "/tmp/rclone4255274206" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-vigugak2capu'", Local "Local file system at /tmp/rclone4255274206", Modify Window "1ms" 2026/09/29 01:03:49 DEBUG : 測試_Русский___ě_áñ: md5 = 9ad72ddafea799ae5959c39a52aa1905 OK NFD checkfile vs. NFC remote (without normalization) 2026/09/29 01:03:50 ERROR : 測試_Русский___ě_áñ: sum not found 2026/09/29 01:03:50 ERROR : 測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-vigugak2capu' 2026/09/29 01:03:50 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-vigugak2capu': 1 files missing 2026/09/29 01:03:50 NOTICE: 1 hashes missing 2026/09/29 01:03:50 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-vigugak2capu': 1 differences found 2026/09/29 01:03:50 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-vigugak2capu': 2 errors while checking NFD checkfile vs. NFC remote (with normalization) 2026/09/29 01:03:58 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/29 01:03:58 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-vigugak2capu': 0 differences found 2026/09/29 01:03:58 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-vigugak2capu': 1 matching files 2026/09/29 01:03:58 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-naqaxur1gepa" 2026/09/29 01:03:58 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/29 01:03:58 DEBUG : Creating backend with remote "TestDrive:crypt/m3099u1js2pr39ke2c734gf3gcm1v98ejdojrfv3eh6u526jj8e0" 2026/09/29 01:03:59 DEBUG : Creating backend with remote "/tmp/rclone1209442577" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-naqaxur1gepa'", Local "Local file system at /tmp/rclone1209442577", Modify Window "1ms" 2026/09/29 01:04:02 DEBUG : 測試_Русский___ě_áñ: md5 = d7c204a97f8d29ddbd559831d514f3bb OK NFC checkfile vs. NFD remote (without normalization) 2026/09/29 01:04:02 ERROR : 測試_Русский___ě_áñ: sum not found 2026/09/29 01:04:02 ERROR : 測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-naqaxur1gepa' 2026/09/29 01:04:02 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-naqaxur1gepa': 1 files missing 2026/09/29 01:04:02 NOTICE: 1 hashes missing 2026/09/29 01:04:02 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-naqaxur1gepa': 1 differences found 2026/09/29 01:04:02 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-naqaxur1gepa': 2 errors while checking NFC checkfile vs. NFD remote (with normalization) 2026/09/29 01:04:03 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/29 01:04:03 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-naqaxur1gepa': 0 differences found 2026/09/29 01:04:03 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-naqaxur1gepa': 1 matching files 2026/09/29 01:04:03 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-cavabij0kewa" 2026/09/29 01:04:03 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/29 01:04:03 DEBUG : Creating backend with remote "TestDrive:crypt/jnc4gfrghsnqgs13fi5p1mr05telq3ca8odg2845ddd4sjl13ebg" 2026/09/29 01:04:04 DEBUG : Creating backend with remote "/tmp/rclone3045162618" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cavabij0kewa'", Local "Local file system at /tmp/rclone3045162618", Modify Window "1ms" 2026/09/29 01:04:07 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = ea163ef96be36d076ace7d335bd1d2a0 OK NFDx2 checkfile vs. both remote (without normalization) 2026/09/29 01:04:08 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/09/29 01:04:08 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-cavabij0kewa' 2026/09/29 01:04:08 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-cavabij0kewa': 1 files missing 2026/09/29 01:04:08 NOTICE: 1 hashes missing 2026/09/29 01:04:08 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-cavabij0kewa': 1 differences found 2026/09/29 01:04:08 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-cavabij0kewa': 2 errors while checking NFDx2 checkfile vs. both remote (with normalization) 2026/09/29 01:04:09 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/29 01:04:09 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-cavabij0kewa': 0 differences found 2026/09/29 01:04:09 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-cavabij0kewa': 1 matching files 2026/09/29 01:04:09 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-pugevuv8giba" 2026/09/29 01:04:09 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/29 01:04:09 DEBUG : Creating backend with remote "TestDrive:crypt/gfv6ovnp626qr7oit01mv996dsb09amgejt3ugsdo40760m9v140" 2026/09/29 01:04:10 DEBUG : Creating backend with remote "/tmp/rclone1085738677" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-pugevuv8giba'", Local "Local file system at /tmp/rclone1085738677", Modify Window "1ms" 2026/09/29 01:04:12 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = a92caaabdd56e39f7e7cc2beabef8fef OK NFCx2 checkfile vs. both remote (without normalization) 2026/09/29 01:04:13 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/09/29 01:04:13 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-pugevuv8giba' 2026/09/29 01:04:13 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-pugevuv8giba': 1 files missing 2026/09/29 01:04:13 NOTICE: 1 hashes missing 2026/09/29 01:04:13 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-pugevuv8giba': 1 differences found 2026/09/29 01:04:13 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-pugevuv8giba': 2 errors while checking NFCx2 checkfile vs. both remote (with normalization) 2026/09/29 01:04:14 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/29 01:04:14 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-pugevuv8giba': 0 differences found 2026/09/29 01:04:14 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-pugevuv8giba': 1 matching files 2026/09/29 01:04:14 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-bazizat9gahi" 2026/09/29 01:04:14 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/29 01:04:14 DEBUG : Creating backend with remote "TestDrive:crypt/fco4at88as2lsanun686122ecv0tteja61otkm6pe9nhsq429vn0" 2026/09/29 01:04:15 DEBUG : Creating backend with remote "/tmp/rclone2689452802" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-bazizat9gahi'", Local "Local file system at /tmp/rclone2689452802", Modify Window "1ms" 2026/09/29 01:04:18 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = c7b9bcac4a00587780dc71b89dc42a2b OK both checkfile vs. NFDx2 remote (without normalization) 2026/09/29 01:04:18 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/09/29 01:04:18 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-bazizat9gahi' 2026/09/29 01:04:18 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-bazizat9gahi': 1 files missing 2026/09/29 01:04:18 NOTICE: 1 hashes missing 2026/09/29 01:04:18 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-bazizat9gahi': 1 differences found 2026/09/29 01:04:18 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-bazizat9gahi': 2 errors while checking both checkfile vs. NFDx2 remote (with normalization) 2026/09/29 01:04:19 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/29 01:04:19 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-bazizat9gahi': 0 differences found 2026/09/29 01:04:19 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-bazizat9gahi': 1 matching files 2026/09/29 01:04:19 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-radagoj3muva" 2026/09/29 01:04:19 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/29 01:04:19 DEBUG : Creating backend with remote "TestDrive:crypt/j1u12vjdr8bldkabcba7m4kg9qi2p8rthk9gomjfpsmnmkbokprg" 2026/09/29 01:04:20 DEBUG : Creating backend with remote "/tmp/rclone2988256208" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-radagoj3muva'", Local "Local file system at /tmp/rclone2988256208", Modify Window "1ms" 2026/09/29 01:04:23 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = e497c6069d5a0d0fda2b4a09053830cf OK both checkfile vs. NFCx2 remote (without normalization) 2026/09/29 01:04:24 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/09/29 01:04:24 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-radagoj3muva' 2026/09/29 01:04:24 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-radagoj3muva': 1 files missing 2026/09/29 01:04:24 NOTICE: 1 hashes missing 2026/09/29 01:04:24 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-radagoj3muva': 1 differences found 2026/09/29 01:04:24 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-radagoj3muva': 2 errors while checking both checkfile vs. NFCx2 remote (with normalization) 2026/09/29 01:04:25 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/29 01:04:25 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-radagoj3muva': 0 differences found 2026/09/29 01:04:25 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-radagoj3muva': 1 matching files 2026/09/29 01:04:25 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-radagoj3muva': Purge remote 2026/09/29 01:04:26 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-bazizat9gahi': Purge remote 2026/09/29 01:04:26 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-pugevuv8giba': Purge remote 2026/09/29 01:04:27 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cavabij0kewa': Purge remote 2026/09/29 01:04:27 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-naqaxur1gepa': Purge remote 2026/09/29 01:04:28 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-vigugak2capu': Purge remote 2026/09/29 01:04:28 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-ruqepon5jeza': Purge remote 2026/09/29 01:04:29 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-daladiw5leqe': Purge remote 2026/09/29 01:04:29 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-conazap6juju': Purge remote 2026/09/29 01:04:30 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-tuzikeh2qire': Purge remote --- PASS: TestApplyTransforms (88.23s) === RUN TestTruncateString --- PASS: TestTruncateString (0.00s) === RUN TestCopyFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:04:30 DEBUG : file1: Need to transfer - File not found at Destination 2026/09/29 01:04:33 DEBUG : sub/file2: md5 = 8a19525129d37b589b0a44a8c89f5edd OK 2026/09/29 01:04:33 DEBUG : sub/file2: size = 14 OK 2026/09/29 01:04:33 INFO : file1: Copied (new) to: sub/file2 2026/09/29 01:04:34 DEBUG : sub/file2: size = 14 OK 2026/09/29 01:04:34 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/29 01:04:34 DEBUG : file1: Unchanged skipping 2026/09/29 01:04:34 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife': don't need to copy/move sub/file2, it is already at target location --- PASS: TestCopyFile (6.41s) === RUN TestCopyFileImmutable run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:04:37 DEBUG : existing: Need to transfer - File not found at Destination 2026/09/29 01:04:38 DEBUG : existing: md5 = 68967a89e2e0afc1f6628431c0c2903c OK 2026/09/29 01:04:38 DEBUG : existing: size = 6 OK 2026/09/29 01:04:38 INFO : existing: Copied (new) 2026/09/29 01:04:39 DEBUG : existing: size = 6 OK 2026/09/29 01:04:39 DEBUG : existing: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/29 01:04:39 DEBUG : existing: Unchanged skipping 2026/09/29 01:04:39 DEBUG : existing: size = 8 (Local file system at /tmp/rclone839572334) 2026/09/29 01:04:39 DEBUG : existing: size = 6 (Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife') 2026/09/29 01:04:39 DEBUG : existing: Sizes differ 2026/09/29 01:04:39 ERROR : existing: Source and destination exist but do not match: immutable file modified --- PASS: TestCopyFileImmutable (4.39s) === RUN TestCopyLongFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" copy_test.go:188: Test only runs on local --- SKIP: TestCopyLongFile (0.46s) === RUN TestCopyFileBackupDir run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:04:44 DEBUG : dst/file1: md5 = d6d346cc1dc3962f414320ba0cf85edc OK 2026/09/29 01:04:45 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jaqutih0kife/backup" 2026/09/29 01:04:45 DEBUG : Creating backend with remote "TestDrive:crypt/ag9v8arsn74v53ofqqbcmf8p23a3qi5hjh7fsbe1q81i4g6up45g/1nrff024r7pq65ecp72fc28jb0" 2026/09/29 01:04:47 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone839572334) 2026/09/29 01:04:47 DEBUG : dst/file1: size = 18 (Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife') 2026/09/29 01:04:47 DEBUG : dst/file1: Sizes differ 2026/09/29 01:04:49 INFO : dst/file1: Moved (server-side) 2026/09/29 01:04:51 DEBUG : dst/file1: md5 = 0973eb8ca9b0afc1ecaf8a8e00ccfd66 OK 2026/09/29 01:04:51 DEBUG : dst/file1: size = 14 OK 2026/09/29 01:04:51 INFO : dst/file1: Copied (new) --- PASS: TestCopyFileBackupDir (14.29s) === RUN TestCopyFileCompareDest run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:04:56 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jaqutih0kife/dst" 2026/09/29 01:04:56 DEBUG : Creating backend with remote "TestDrive:crypt/ag9v8arsn74v53ofqqbcmf8p23a3qi5hjh7fsbe1q81i4g6up45g/31u3jie661vd5p8j7rtc3hgbh0" 2026/09/29 01:04:58 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jaqutih0kife/CompareDest" 2026/09/29 01:04:58 DEBUG : Creating backend with remote "TestDrive:crypt/ag9v8arsn74v53ofqqbcmf8p23a3qi5hjh7fsbe1q81i4g6up45g/gveqi14airsml4bgu7krj116o8" 2026/09/29 01:04:59 DEBUG : one: Need to transfer - File not found at Destination 2026/09/29 01:05:02 DEBUG : one: md5 = 28eb3c37ee9d3c53998408c38a376380 OK 2026/09/29 01:05:02 DEBUG : one: size = 3 OK 2026/09/29 01:05:02 INFO : one: Copied (new) 2026/09/29 01:05:03 DEBUG : one: size = 5 (Local file system at /tmp/rclone839572334) 2026/09/29 01:05:03 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife/dst') 2026/09/29 01:05:03 DEBUG : one: Sizes differ 2026/09/29 01:05:05 DEBUG : one: md5 = 133dba5926169abe4d5516319012f6d0 OK 2026/09/29 01:05:05 DEBUG : one: size = 5 OK 2026/09/29 01:05:05 INFO : one: Copied (replaced existing) 2026/09/29 01:05:08 DEBUG : dst/one: md5 = 20ee922fdcfc76c85a51293ced27cf71 OK 2026/09/29 01:05:10 DEBUG : CompareDest/one: md5 = 718e11fd6cbcdb439f89b01fa4e45c7e OK 2026/09/29 01:05:11 DEBUG : one: size = 5 (Local file system at /tmp/rclone839572334) 2026/09/29 01:05:11 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife/dst') 2026/09/29 01:05:11 DEBUG : one: Sizes differ 2026/09/29 01:05:12 DEBUG : one: size = 5 OK 2026/09/29 01:05:12 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/09/29 01:05:12 DEBUG : one: Destination found in --compare-dest, skipping 2026/09/29 01:05:14 DEBUG : CompareDest/two: md5 = 4839a6aa07c2cb83429c09a1fbbd8aa8 OK 2026/09/29 01:05:15 DEBUG : two: Need to transfer - File not found at Destination 2026/09/29 01:05:15 DEBUG : two: size = 3 OK 2026/09/29 01:05:15 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/09/29 01:05:15 DEBUG : two: Destination found in --compare-dest, skipping 2026/09/29 01:05:16 DEBUG : two: Need to transfer - File not found at Destination 2026/09/29 01:05:16 DEBUG : two: size = 3 OK 2026/09/29 01:05:16 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/09/29 01:05:16 DEBUG : two: Destination found in --compare-dest, skipping 2026/09/29 01:05:17 DEBUG : two: Need to transfer - File not found at Destination 2026/09/29 01:05:18 DEBUG : two: size = 5 (Local file system at /tmp/rclone839572334) 2026/09/29 01:05:18 DEBUG : two: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife/CompareDest') 2026/09/29 01:05:18 DEBUG : two: Sizes differ 2026/09/29 01:05:19 DEBUG : two: md5 = 71d6936f3c21e3bf926508ba35f26c92 OK 2026/09/29 01:05:19 DEBUG : two: size = 5 OK 2026/09/29 01:05:19 INFO : two: Copied (new) --- PASS: TestCopyFileCompareDest (28.02s) === RUN TestCopyFileCopyDestImmutable run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:05:24 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jaqutih0kife/dst" 2026/09/29 01:05:24 DEBUG : Creating backend with remote "TestDrive:crypt/ag9v8arsn74v53ofqqbcmf8p23a3qi5hjh7fsbe1q81i4g6up45g/31u3jie661vd5p8j7rtc3hgbh0" 2026/09/29 01:05:28 DEBUG : dst/one: md5 = f53de261f512952c06337116e39d872c OK 2026/09/29 01:05:30 DEBUG : CopyDest/one: md5 = 4b2a749f241d61cbc94d7b4d340332c5 OK 2026/09/29 01:05:31 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jaqutih0kife/CopyDest" 2026/09/29 01:05:31 DEBUG : Creating backend with remote "TestDrive:crypt/ag9v8arsn74v53ofqqbcmf8p23a3qi5hjh7fsbe1q81i4g6up45g/d09o6po3f7bm6ce32vdgs8h9ls" 2026/09/29 01:05:32 DEBUG : one: size = 5 (Local file system at /tmp/rclone839572334) 2026/09/29 01:05:32 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife/dst') 2026/09/29 01:05:32 DEBUG : one: Sizes differ 2026/09/29 01:05:32 DEBUG : one: size = 5 OK 2026/09/29 01:05:32 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/09/29 01:05:32 DEBUG : one: size = 5 (Local file system at /tmp/rclone839572334) 2026/09/29 01:05:32 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife/dst') 2026/09/29 01:05:32 DEBUG : one: Sizes differ 2026/09/29 01:05:32 ERROR : one: Source and destination exist but do not match: immutable file modified --- PASS: TestCopyFileCopyDestImmutable (12.48s) === RUN TestCopyFileCopyDest run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:05:36 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jaqutih0kife/dst" 2026/09/29 01:05:36 DEBUG : Creating backend with remote "TestDrive:crypt/ag9v8arsn74v53ofqqbcmf8p23a3qi5hjh7fsbe1q81i4g6up45g/31u3jie661vd5p8j7rtc3hgbh0" 2026/09/29 01:05:38 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jaqutih0kife/CopyDest" 2026/09/29 01:05:38 DEBUG : Creating backend with remote "TestDrive:crypt/ag9v8arsn74v53ofqqbcmf8p23a3qi5hjh7fsbe1q81i4g6up45g/d09o6po3f7bm6ce32vdgs8h9ls" 2026/09/29 01:05:41 DEBUG : one: Need to transfer - File not found at Destination 2026/09/29 01:05:44 DEBUG : one: md5 = 5195188d29d7789811ed9288dcc5d5b6 OK 2026/09/29 01:05:44 DEBUG : one: size = 3 OK 2026/09/29 01:05:44 INFO : one: Copied (new) 2026/09/29 01:05:45 DEBUG : one: size = 5 (Local file system at /tmp/rclone839572334) 2026/09/29 01:05:45 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife/dst') 2026/09/29 01:05:45 DEBUG : one: Sizes differ 2026/09/29 01:05:47 DEBUG : one: md5 = 22e63e6a83c5576f3bbcb99f64910110 OK 2026/09/29 01:05:47 DEBUG : one: size = 5 OK 2026/09/29 01:05:47 INFO : one: Copied (replaced existing) 2026/09/29 01:05:49 DEBUG : dst/one: md5 = 131eadec6d23e8d73f2e126d3980396d OK 2026/09/29 01:05:52 DEBUG : CopyDest/one: md5 = 762d0a8bed00af95ca6b69167dddb989 OK 2026/09/29 01:05:52 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jaqutih0kife/BackupDir" 2026/09/29 01:05:52 DEBUG : Creating backend with remote "TestDrive:crypt/ag9v8arsn74v53ofqqbcmf8p23a3qi5hjh7fsbe1q81i4g6up45g/s6dbk3lfi7c9kfvo6j7bla9m0g" 2026/09/29 01:05:54 DEBUG : one: size = 5 (Local file system at /tmp/rclone839572334) 2026/09/29 01:05:54 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife/dst') 2026/09/29 01:05:54 DEBUG : one: Sizes differ 2026/09/29 01:05:55 DEBUG : one: size = 5 OK 2026/09/29 01:05:55 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/09/29 01:05:55 DEBUG : one: size = 5 (Local file system at /tmp/rclone839572334) 2026/09/29 01:05:55 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife/dst') 2026/09/29 01:05:55 DEBUG : one: Sizes differ 2026/09/29 01:05:56 INFO : one: Moved (server-side) 2026/09/29 01:05:57 DEBUG : one: size = 5 OK 2026/09/29 01:05:57 INFO : one: Copied (server-side copy) 2026/09/29 01:05:57 DEBUG : one: Destination found in --copy-dest, using server-side copy 2026/09/29 01:05:59 DEBUG : CopyDest/two: md5 = cda667b8cf25d7ea3e891d6c17afaa3e OK 2026/09/29 01:06:00 DEBUG : two: Need to transfer - File not found at Destination 2026/09/29 01:06:00 DEBUG : two: size = 3 OK 2026/09/29 01:06:00 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/09/29 01:06:02 DEBUG : two: size = 3 OK 2026/09/29 01:06:02 INFO : two: Copied (server-side copy) 2026/09/29 01:06:02 DEBUG : two: Destination found in --copy-dest, using server-side copy 2026/09/29 01:06:02 DEBUG : two: size = 3 OK 2026/09/29 01:06:02 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/09/29 01:06:02 DEBUG : two: Unchanged skipping 2026/09/29 01:06:05 DEBUG : CopyDest/three: md5 = b7b0da93340b6b1ff22577ecea3457b2 OK 2026/09/29 01:06:06 DEBUG : three: Need to transfer - File not found at Destination 2026/09/29 01:06:06 DEBUG : three: size = 7 (Local file system at /tmp/rclone839572334) 2026/09/29 01:06:06 DEBUG : three: size = 5 (Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife/CopyDest') 2026/09/29 01:06:06 DEBUG : three: Sizes differ 2026/09/29 01:06:06 DEBUG : three: Destination not found in --copy-dest 2026/09/29 01:06:07 DEBUG : three: md5 = 55b7dbcc4081db5d9ba6a8df863043f2 OK 2026/09/29 01:06:07 DEBUG : three: size = 7 OK 2026/09/29 01:06:07 INFO : three: Copied (new) --- PASS: TestCopyFileCopyDest (37.97s) === RUN TestCopyInplace run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" copy_test.go:434: Partial uploads not supported --- SKIP: TestCopyInplace (0.49s) === RUN TestCopyLongFileName run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" copy_test.go:467: Partial uploads not supported --- SKIP: TestCopyLongFileName (0.50s) === RUN TestCopyLongFileNameCollision run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" copy_test.go:500: Partial uploads not supported --- SKIP: TestCopyLongFileNameCollision (0.47s) === RUN TestCopyFileMaxTransfer run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:06:16 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination 2026/09/29 01:06:18 DEBUG : TestCopyFileMaxTransfer/file1: md5 = 528d4b0316e3ce53a681a67b282db187 OK 2026/09/29 01:06:18 DEBUG : TestCopyFileMaxTransfer/file1: size = 14 OK 2026/09/29 01:06:18 INFO : TestCopyFileMaxTransfer/file1: Copied (new) 2026/09/29 01:06:19 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination 2026/09/29 01:06:19 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/29 01:06:21 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination 2026/09/29 01:06:22 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination 2026/09/29 01:06:24 DEBUG : TestCopyFileMaxTransfer/file4: md5 = fe7b0088f6e5b2c67b1ab27af15cac03 OK 2026/09/29 01:06:24 DEBUG : TestCopyFileMaxTransfer/file4: size = 2062 OK 2026/09/29 01:06:24 INFO : TestCopyFileMaxTransfer/file4: Copied (new) --- PASS: TestCopyFileMaxTransfer (11.14s) === RUN TestDeduplicateInteractive run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" dedupe_test.go:37: Can't run this test without a hash --- SKIP: TestDeduplicateInteractive (0.51s) === RUN TestDeduplicateSkip run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:06:30 INFO : Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife': Looking for duplicate names using skip mode. 2026/09/29 01:06:31 NOTICE: one: Found 2 files with duplicate names 2026/09/29 01:06:31 NOTICE: one: Skipping 2 files with duplicate names --- PASS: TestDeduplicateSkip (5.53s) === RUN TestDeduplicateSizeOnly run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:06:37 INFO : Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife': Looking for duplicate names using skip mode. 2026/09/29 01:06:37 NOTICE: one: Found 3 files with duplicate names 2026/09/29 01:06:37 NOTICE: one: Deleting 1/2 identical duplicates (size 11) 2026/09/29 01:06:38 INFO : one: Deleted 2026/09/29 01:06:38 NOTICE: one: Skipping 2 files with duplicate names --- PASS: TestDeduplicateSizeOnly (6.77s) === RUN TestDeduplicateFirst run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:06:44 INFO : Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife': Looking for duplicate names using first mode. 2026/09/29 01:06:44 NOTICE: one: Found 3 files with duplicate names 2026/09/29 01:06:45 INFO : one: Deleted 2026/09/29 01:06:45 INFO : one: Deleted 2026/09/29 01:06:45 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateFirst (7.25s) === RUN TestDeduplicateNewest run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:06:51 INFO : Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife': Looking for duplicate names using newest mode. 2026/09/29 01:06:51 NOTICE: one: Found 3 files with duplicate names 2026/09/29 01:06:52 INFO : one: Deleted 2026/09/29 01:06:53 INFO : one: Deleted 2026/09/29 01:06:53 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateNewest (7.01s) === RUN TestDeduplicateNewestByHash run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" dedupe_test.go:37: Can't run this test without a hash --- SKIP: TestDeduplicateNewestByHash (0.41s) === RUN TestDeduplicateOldest run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:06:59 INFO : Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife': Looking for duplicate names using oldest mode. 2026/09/29 01:06:59 NOTICE: one: Found 3 files with duplicate names 2026/09/29 01:07:00 INFO : one: Deleted 2026/09/29 01:07:00 INFO : one: Deleted 2026/09/29 01:07:00 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateOldest (7.20s) === RUN TestDeduplicateLargest run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:07:06 INFO : Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife': Looking for duplicate names using largest mode. 2026/09/29 01:07:06 NOTICE: one: Found 3 files with duplicate names 2026/09/29 01:07:07 INFO : one: Deleted 2026/09/29 01:07:07 INFO : one: Deleted 2026/09/29 01:07:07 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateLargest (7.02s) === RUN TestDeduplicateSmallest run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:07:13 INFO : Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife': Looking for duplicate names using smallest mode. 2026/09/29 01:07:13 NOTICE: one: Found 3 files with duplicate names 2026/09/29 01:07:14 INFO : one: Deleted 2026/09/29 01:07:14 INFO : one: Deleted 2026/09/29 01:07:14 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateSmallest (6.82s) === RUN TestDeduplicateRename run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:07:21 INFO : Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife': Looking for duplicate names using rename mode. 2026/09/29 01:07:21 NOTICE: one.txt: Found 3 files with duplicate names 2026/09/29 01:07:22 INFO : one-2.txt: renamed from: one.txt 2026/09/29 01:07:23 INFO : one-3.txt: renamed from: one.txt 2026/09/29 01:07:24 INFO : one-4.txt: renamed from: one.txt --- PASS: TestDeduplicateRename (11.15s) === RUN TestDeduplicateRenameManyExisting run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:07:28 DEBUG : one-1.txt: md5 = b631dde5cd7834c811c3d6c89f3f088b OK 2026/09/29 01:07:30 DEBUG : one-2.txt: md5 = 698c7d6057acb601e83eecb67f3eee33 OK 2026/09/29 01:07:32 DEBUG : one-3.txt: md5 = dc8d04e33c6a138f9738edf9db9728dc OK 2026/09/29 01:07:34 DEBUG : one-4.txt: md5 = c9201195f30d49c0ee5d615144584b2a OK 2026/09/29 01:07:35 DEBUG : one-5.txt: md5 = 9597b45ca38ae4e886e2193b6d78adfd OK 2026/09/29 01:07:37 DEBUG : one-6.txt: md5 = 90aac6901ed8b97190a84844bbbe913e OK 2026/09/29 01:07:38 DEBUG : one-7.txt: md5 = 9aa9e83acdd935a0fa820374bcb4a27b OK 2026/09/29 01:07:40 DEBUG : one-8.txt: md5 = e1a804659eb70d36562fb80d5c6d549c OK 2026/09/29 01:07:41 DEBUG : one-9.txt: md5 = 7c6c8980469de9d404f3df64ec998177 OK 2026/09/29 01:07:43 DEBUG : one-10.txt: md5 = 6f1b14d958980a4254eedca8e9857dc4 OK 2026/09/29 01:07:44 DEBUG : one-11.txt: md5 = d7ae7df86101a35a95ed6a85ffb3f347 OK 2026/09/29 01:07:46 DEBUG : one-12.txt: md5 = 6c596a881c1018afed2ec0cfb4d119fb OK 2026/09/29 01:07:47 DEBUG : one-13.txt: md5 = d50ab785fc3e0448a3023d5eaf99d478 OK 2026/09/29 01:07:49 DEBUG : one-14.txt: md5 = 8adea4083b665346abd90c35d329eba6 OK 2026/09/29 01:07:51 DEBUG : one-15.txt: md5 = 88871e9178c43c74ac79f2c64b8e29b3 OK 2026/09/29 01:07:52 DEBUG : one-16.txt: md5 = 5cc807ce117a1f216c3846a61e2e72ce OK 2026/09/29 01:07:54 DEBUG : one-17.txt: md5 = 840d9848225d59bcc52e3fb9ccfdfec3 OK 2026/09/29 01:07:56 DEBUG : one-18.txt: md5 = 562a51e6c93553106e5642e1fbf9c88e OK 2026/09/29 01:07:57 DEBUG : one-19.txt: md5 = 153c1d593a2449b0809b7e29eb7bdaf7 OK 2026/09/29 01:07:59 DEBUG : one-20.txt: md5 = 5e59879712650f8768f042cfdbd7a7d0 OK 2026/09/29 01:08:01 DEBUG : one-21.txt: md5 = dd967f7acfb6b4a43f6905487f350048 OK 2026/09/29 01:08:02 DEBUG : one-22.txt: md5 = ed93fd61ca83eab6dbb755749b6a9ea9 OK 2026/09/29 01:08:04 DEBUG : one-23.txt: md5 = 7208b9486d6b2bd32b358af4fd834b32 OK 2026/09/29 01:08:05 DEBUG : one-24.txt: md5 = cf6773655c1b2584a767c28bda6a13ca OK 2026/09/29 01:08:08 DEBUG : one-25.txt: md5 = 6bd977155fa870d61becb77c6f3699a2 OK 2026/09/29 01:08:09 DEBUG : one-26.txt: md5 = 972d29f37edc2c8dd3640252c1b3e287 OK 2026/09/29 01:08:11 DEBUG : one-27.txt: md5 = ae0a29f6dd83d8a439664f3134a3480c OK 2026/09/29 01:08:12 DEBUG : one-28.txt: md5 = db472b37e90df86f413e802c5f457d3d OK 2026/09/29 01:08:14 DEBUG : one-29.txt: md5 = 854061914e2ab75b59ed8288eb10014e OK 2026/09/29 01:08:16 DEBUG : one-30.txt: md5 = 6b3d96286c5cd393428e7c869afcf0ca OK 2026/09/29 01:08:17 DEBUG : one-31.txt: md5 = 784bced1d48ec2c194408f85502eeede OK 2026/09/29 01:08:19 DEBUG : one-32.txt: md5 = f5e1cbd6c98fde18118331e23f4153a4 OK 2026/09/29 01:08:20 DEBUG : one-33.txt: md5 = bb7c28594ce672afb72d561c4a9ae3e5 OK 2026/09/29 01:08:22 DEBUG : one-34.txt: md5 = 09928a89406262b84d9030a4d207d79b OK 2026/09/29 01:08:23 DEBUG : one-35.txt: md5 = c7715e0d90a0cd71f131d8517f9a29bf OK 2026/09/29 01:08:25 DEBUG : one-36.txt: md5 = 38338c8ce1a88aa742c068f102025762 OK 2026/09/29 01:08:26 DEBUG : one-37.txt: md5 = 441d0711c434d0838a0ccf512faaf6fa OK 2026/09/29 01:08:28 DEBUG : one-38.txt: md5 = 11e2a5b889203ebbebe21d8e1c17b129 OK 2026/09/29 01:08:29 DEBUG : one-39.txt: md5 = 920ea7897203aba47732065cecee906e OK 2026/09/29 01:08:31 DEBUG : one-40.txt: md5 = d787afecea4fac67c8f49a44b5b50b74 OK 2026/09/29 01:08:33 DEBUG : one-41.txt: md5 = c1a6f53753f8552d6faed7d476c027ad OK 2026/09/29 01:08:35 DEBUG : one-42.txt: md5 = 4d078446a6007c78ea9f0d767b99cde1 OK 2026/09/29 01:08:36 DEBUG : one-43.txt: md5 = 694daa9b92110464dde7c8572caf942c OK 2026/09/29 01:08:38 DEBUG : one-44.txt: md5 = b9b49365fe17de379758faad8e600282 OK 2026/09/29 01:08:40 DEBUG : one-45.txt: md5 = 2db9927ae77831fc64b6a986350f26a1 OK 2026/09/29 01:08:41 DEBUG : one-46.txt: md5 = 9218070388ea1f06a7524b858a31d9f3 OK 2026/09/29 01:08:43 DEBUG : one-47.txt: md5 = 95b04eb0b9938a9c27d9dde4060f17a3 OK 2026/09/29 01:08:45 DEBUG : one-48.txt: md5 = 3fd5efbe7eb2935344ebececcb95a264 OK 2026/09/29 01:08:46 DEBUG : one-49.txt: md5 = 851eb47fd0c721d320d307035b1767bd OK 2026/09/29 01:08:48 DEBUG : one-50.txt: md5 = a6400c586fc859fe711f9397a1083c93 OK 2026/09/29 01:08:50 DEBUG : one-51.txt: md5 = d1cbd1f884a245e7a6856617918d29a9 OK 2026/09/29 01:08:51 DEBUG : one-52.txt: md5 = 1b875f010fa12a278417706d4e46819d OK 2026/09/29 01:08:53 DEBUG : one-53.txt: md5 = 4ce1fcf5e1239be2b5e05627436383c7 OK 2026/09/29 01:08:55 DEBUG : one-54.txt: md5 = 310e8e9333cfda87b29ad68026ee1ab5 OK 2026/09/29 01:08:56 DEBUG : one-55.txt: md5 = b715b58be9837849b27f09d07d87359a OK 2026/09/29 01:08:59 DEBUG : one-56.txt: md5 = fb2914fa321d01531e07cdd137684968 OK 2026/09/29 01:09:00 DEBUG : one-57.txt: md5 = a7f142603338f768f6452c46ba8da8b0 OK 2026/09/29 01:09:02 DEBUG : one-58.txt: md5 = 7e1c40c31ce21edaea25cdf0124c80ca OK 2026/09/29 01:09:03 DEBUG : one-59.txt: md5 = 8e74421d59c365d11931a7e242d3f124 OK 2026/09/29 01:09:05 DEBUG : one-60.txt: md5 = a4816e46807a3c9e26fef8eedf06a116 OK 2026/09/29 01:09:06 DEBUG : one-61.txt: md5 = b208f0494c1c5b0ec62f94a6b2ae9358 OK 2026/09/29 01:09:08 DEBUG : one-62.txt: md5 = fa4df55ca8f4b0d704fceb578cf51d25 OK 2026/09/29 01:09:10 DEBUG : one-63.txt: md5 = f3edfcb9bbf72626fafd8b5ade7d1c07 OK 2026/09/29 01:09:11 DEBUG : one-64.txt: md5 = 7d4f1b7d17692237f731ecf116479c9f OK 2026/09/29 01:09:13 DEBUG : one-65.txt: md5 = a249bf12761453cd400a42360346e74b OK 2026/09/29 01:09:15 DEBUG : one-66.txt: md5 = 314e97a03bff26ab99642b287b5d6e02 OK 2026/09/29 01:09:17 DEBUG : one-67.txt: md5 = ab1f3bf28b94084df6f33c817f188107 OK 2026/09/29 01:09:18 DEBUG : one-68.txt: md5 = bfacb56dc8dc23e86d9f3e906eb93291 OK 2026/09/29 01:09:20 DEBUG : one-69.txt: md5 = 7dafb441e54ea0de2da6e054278683cf OK 2026/09/29 01:09:21 DEBUG : one-70.txt: md5 = 450fccadfadd8c369b19579d2042e4c8 OK 2026/09/29 01:09:23 DEBUG : one-71.txt: md5 = e45853e7811aa1cfd88f9badaafe9020 OK 2026/09/29 01:09:24 DEBUG : one-72.txt: md5 = f6a71b3a3c269fda5fb6341515714bee OK 2026/09/29 01:09:26 DEBUG : one-73.txt: md5 = d5c5f403ab0d04679d9fe17b14c56c09 OK 2026/09/29 01:09:28 DEBUG : one-74.txt: md5 = 5e1c3870927f50804f6bfd6f232f36d4 OK 2026/09/29 01:09:29 DEBUG : one-75.txt: md5 = 626dbb1c342393e1743ba10c8f3f5ee9 OK 2026/09/29 01:09:31 DEBUG : one-76.txt: md5 = 7aa7fb7112ad362541b34190002bb050 OK 2026/09/29 01:09:32 DEBUG : one-77.txt: md5 = bccfaa04411ffb7008fc1fbba033d2f0 OK 2026/09/29 01:09:34 DEBUG : one-78.txt: md5 = aa2ebf1df709b58dbf94e83a2b21f38d OK 2026/09/29 01:09:35 DEBUG : one-79.txt: md5 = 5d4fd3fae54a16bef3e90f9216145941 OK 2026/09/29 01:09:37 DEBUG : one-80.txt: md5 = 2cd6d2f61b8647f879e8b903963bff4d OK 2026/09/29 01:09:38 DEBUG : one-81.txt: md5 = fe9730568ffa2c5409dbdeef865ff94c OK 2026/09/29 01:09:40 DEBUG : one-82.txt: md5 = fa0c06b95754ebe5cfc6ac67cb697e01 OK 2026/09/29 01:09:41 DEBUG : one-83.txt: md5 = c14e4dcc4ea49390345c19f4882a2f3f OK 2026/09/29 01:09:43 DEBUG : one-84.txt: md5 = 36adc5688807a03ca7240f9f3f103cc2 OK 2026/09/29 01:09:45 DEBUG : one-85.txt: md5 = 28c9e57036edc5813990e48a37f530cb OK 2026/09/29 01:09:46 DEBUG : one-86.txt: md5 = e47ae381a8ba35f33be0e4ffa878d7a2 OK 2026/09/29 01:09:48 DEBUG : one-87.txt: md5 = 7ff42f006b469471c21731a570e8e7f4 OK 2026/09/29 01:09:49 DEBUG : one-88.txt: md5 = c0759c0025efe08c35170d3af8333f43 OK 2026/09/29 01:09:51 DEBUG : one-89.txt: md5 = 83a3c17c37f6d3d9b120beb1ba61e0bb OK 2026/09/29 01:09:52 DEBUG : one-90.txt: md5 = d48afca1c9ab78b709507af895db395f OK 2026/09/29 01:09:54 DEBUG : one-91.txt: md5 = c73856afd7d2667cd1ff8a8849229b57 OK 2026/09/29 01:09:56 DEBUG : one-92.txt: md5 = 12865aab668eaeda8841a42a9de2a5a3 OK 2026/09/29 01:09:57 DEBUG : one-93.txt: md5 = 91e84b5d3bfbd752a4d79c5764fb9655 OK 2026/09/29 01:09:59 DEBUG : one-94.txt: md5 = 397e64b258c3ff2823a4098afe32c9fe OK 2026/09/29 01:10:00 DEBUG : one-95.txt: md5 = cc488cbef62672d75678aa89992975bf OK 2026/09/29 01:10:02 DEBUG : one-96.txt: md5 = 8b4d81896e4bbcb83bd1b876c91e93ab OK 2026/09/29 01:10:03 DEBUG : one-97.txt: md5 = 2d279fb9dd3a782d939c271fd1e8ce50 OK 2026/09/29 01:10:05 DEBUG : one-98.txt: md5 = 97b06d2bb6ee90d3fbdb233bd0602315 OK 2026/09/29 01:10:07 DEBUG : one-99.txt: md5 = 68ad6ab924b1ef7462d6fe04f61aca4e OK 2026/09/29 01:10:09 DEBUG : one-100.txt: md5 = 4b63090826b0a648cba710e4f9904a14 OK 2026/09/29 01:10:10 DEBUG : one-101.txt: md5 = 2b94c50d7f694e95ca3ddca9ab98cf45 OK 2026/09/29 01:10:12 DEBUG : one-102.txt: md5 = c61aae9a55d662cb0a4147dce373e538 OK 2026/09/29 01:10:13 DEBUG : one-103.txt: md5 = 5afab525786ac7e240011840bc9b37ed OK 2026/09/29 01:10:15 DEBUG : one-104.txt: md5 = 67ea7f18c4af175b640b68550c38275e OK 2026/09/29 01:10:17 DEBUG : one-105.txt: md5 = 0fc6393174b908dcd8f9613bf9adce1a OK 2026/09/29 01:10:20 INFO : Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife': Looking for duplicate names using rename mode. 2026/09/29 01:10:20 NOTICE: one.txt: Found 2 files with duplicate names 2026/09/29 01:10:21 INFO : one-106.txt: renamed from: one.txt 2026/09/29 01:10:22 INFO : one-107.txt: renamed from: one.txt --- PASS: TestDeduplicateRenameManyExisting (228.83s) === RUN TestMergeDirs run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:11:18 DEBUG : dupe1/one.txt: md5 = f4334db87119bfabaf7cdc8b9a6373de OK 2026/09/29 01:11:20 DEBUG : dupe2/two.txt: md5 = d1434f0d1040b2bb54f250a10926ff36 OK 2026/09/29 01:11:23 DEBUG : dupe3/three.txt: md5 = c104ae83b239185b751014468294813c OK 2026/09/29 01:11:23 INFO : urj8gsducqbtvekeq181ntd2u8: merging "sfrom47189m9qr3mt4qonu281c" 2026/09/29 01:11:24 INFO : urj8gsducqbtvekeq181ntd2u8: removing empty directory 2026/09/29 01:11:24 INFO : 1u1r4ei7c628fnjg9blqt3j60o: merging "tr2hj63d80ftlmvm6a952snjcc" 2026/09/29 01:11:25 INFO : 1u1r4ei7c628fnjg9blqt3j60o: removing empty directory --- PASS: TestMergeDirs (13.81s) === RUN TestListDirSorted run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:11:31 DEBUG : a.txt: md5 = 8b5d5eebf25778fc274d3cff9f373fc0 OK 2026/09/29 01:11:32 DEBUG : zend.txt: md5 = 547e75944cc7cabfe0ec7fddba260e86 OK 2026/09/29 01:11:34 DEBUG : sub dir/hello world: md5 = 773b877cc5a55c527e8b6a2f25291435 OK 2026/09/29 01:11:36 DEBUG : sub dir/hello world2: md5 = 5e2c8f0b7d61fd497c113b8fe211027e OK 2026/09/29 01:11:38 DEBUG : sub dir/ignore dir/.ignore: md5 = cde68328b53122ef3460a8e347e79461 OK 2026/09/29 01:11:40 DEBUG : sub dir/ignore dir/should be ignored: md5 = 4006ec2ef7faa2f8f385832f87178525 OK 2026/09/29 01:11:42 DEBUG : sub dir/sub sub dir/hello world3: md5 = a70b3d3bb61a67ad3498e6968cbb8a70 OK 2026/09/29 01:11:44 DEBUG : a.txt: Excluded (Size Filter) 2026/09/29 01:11:44 DEBUG : a.txt: Excluded 2026/09/29 01:11:44 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/09/29 01:11:44 DEBUG : sub dir/hello world2: Excluded 2026/09/29 01:11:44 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/09/29 01:11:44 DEBUG : sub dir/hello world: Excluded 2026/09/29 01:11:45 DEBUG : sub dir/ignore dir: Excluded 2026/09/29 01:11:45 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/09/29 01:11:45 DEBUG : sub dir/hello world2: Excluded 2026/09/29 01:11:45 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/09/29 01:11:45 DEBUG : sub dir/hello world: Excluded 2026/09/29 01:11:45 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSorted (22.88s) === RUN TestListDirSortedFn run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:11:54 DEBUG : a.txt: md5 = 52b94823acd895e701129c3e3e77d1d1 OK 2026/09/29 01:11:55 DEBUG : zend.txt: md5 = e53f3508796a25265f288a7be55baf6e OK 2026/09/29 01:11:57 DEBUG : sub dir/hello world: md5 = 5c863680e76d5b93c6a9fdf986177f40 OK 2026/09/29 01:11:59 DEBUG : sub dir/hello world2: md5 = 079c5fcf300c70cbe4fe0a8ec8fdb9e1 OK 2026/09/29 01:12:01 DEBUG : sub dir/ignore dir/.ignore: md5 = ab05651bdf0bb1487dc9196f956d0635 OK 2026/09/29 01:12:03 DEBUG : sub dir/ignore dir/should be ignored: md5 = 9229de768d04eb8803c1df6e18d19b83 OK 2026/09/29 01:12:05 DEBUG : sub dir/sub sub dir/hello world3: md5 = e400bd272456318010b4e92c33f60e12 OK 2026/09/29 01:12:06 DEBUG : a.txt: Excluded (Size Filter) 2026/09/29 01:12:06 DEBUG : a.txt: Excluded 2026/09/29 01:12:07 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/09/29 01:12:07 DEBUG : sub dir/hello world2: Excluded 2026/09/29 01:12:07 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/09/29 01:12:07 DEBUG : sub dir/hello world: Excluded 2026/09/29 01:12:07 DEBUG : sub dir/ignore dir: Excluded 2026/09/29 01:12:07 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/09/29 01:12:07 DEBUG : sub dir/hello world2: Excluded 2026/09/29 01:12:07 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/09/29 01:12:07 DEBUG : sub dir/hello world: Excluded 2026/09/29 01:12:08 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSortedFn (22.71s) === RUN TestListJSON run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:12:16 DEBUG : file1: md5 = 5b9a4d233dbded4a4dc76f511fc18414 OK 2026/09/29 01:12:19 DEBUG : sub/file2: md5 = a7d029d7d50cce586ed43f8c54e3bcfd 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/29 01:12:22 ERROR : file1: Failed to read hash: hash type not supported === RUN TestListJSON/Metadata 2026/09/29 01:12:22 DEBUG : eer8kka55qnghc34cq76ca668g: Fetching metadata 2026/09/29 01:12:22 DEBUG : 150fuo3cn4j1uenq6r4g4qk6t4: Fetching metadata --- PASS: TestListJSON (10.48s) --- PASS: TestListJSON/Default (0.21s) --- PASS: TestListJSON/FilesOnly (0.26s) --- PASS: TestListJSON/DirsOnly (0.29s) --- PASS: TestListJSON/Recurse (0.51s) --- PASS: TestListJSON/SubDir (0.27s) --- PASS: TestListJSON/NoModTime (0.23s) --- PASS: TestListJSON/NoMimeType (0.28s) --- PASS: TestListJSON/ShowHash (0.33s) --- PASS: TestListJSON/HashTypes (0.30s) --- PASS: TestListJSON/Metadata (0.69s) === RUN TestStatJSON run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:12:27 DEBUG : file1: md5 = abce206b9bc351248a7d5781edde5135 OK 2026/09/29 01:12:30 DEBUG : sub/file2: md5 = 6a63306f02258d03d506bd554bc3d959 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/29 01:12:34 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jaqutih0kife/notfound" 2026/09/29 01:12:34 DEBUG : Config file has changed externally - reloading 2026/09/29 01:12:34 DEBUG : Creating backend with remote "TestDrive:crypt/ag9v8arsn74v53ofqqbcmf8p23a3qi5hjh7fsbe1q81i4g6up45g/qe0i31qdkoejk60elps0ni5sqk" --- PASS: TestStatJSON (12.79s) --- PASS: TestStatJSON/Root (0.26s) --- PASS: TestStatJSON/RootFilesOnly (0.00s) --- PASS: TestStatJSON/RootDirsOnly (0.28s) --- PASS: TestStatJSON/Dir (0.53s) --- PASS: TestStatJSON/DirWithTrailingSlash (0.30s) --- PASS: TestStatJSON/File (0.23s) --- PASS: TestStatJSON/NotFound (0.54s) --- PASS: TestStatJSON/DirFilesOnly (0.24s) --- PASS: TestStatJSON/FileFilesOnly (0.24s) --- PASS: TestStatJSON/NotFoundFilesOnly (0.22s) --- PASS: TestStatJSON/DirDirsOnly (0.24s) --- PASS: TestStatJSON/FileDirsOnly (0.23s) --- PASS: TestStatJSON/NotFoundDirsOnly (0.28s) --- PASS: TestStatJSON/RootNotFound (1.81s) === RUN TestStatJSONMemory 2026/09/29 01:12:38 DEBUG : Creating backend with remote ":memory:" 2026/09/29 01:12:38 DEBUG : Memory root '': File to upload is small (5 bytes), uploading instead of streaming 2026/09/29 01:12:38 DEBUG : sub/file1: size = 5 OK 2026/09/29 01:12:38 DEBUG : sub/file1: md5 = 5d41402abc4b2a76b9719d911017c592 OK 2026/09/29 01:12:38 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-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:12:38 INFO : Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife': Making directory 2026/09/29 01:12:38 INFO : Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife': Making directory --- PASS: TestMkdir (0.69s) === RUN TestLsd run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:12:41 DEBUG : sub dir/hello world: md5 = 0b455de4cf3db231d3a6ffdd8de9d149 OK --- PASS: TestLsd (4.91s) === RUN TestLs run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:12:45 DEBUG : potato2: md5 = 61b6441053eb987dc4d417ffdde43477 OK 2026/09/29 01:12:46 DEBUG : empty space: md5 = 0311f47565ff2c976afc5b488ef5f88e OK --- PASS: TestLs (4.82s) === RUN TestLsWithFilesFrom run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:12:50 DEBUG : potato2: md5 = c1ab0da9dd015eb2edff1d71429ff9a6 OK 2026/09/29 01:12:52 DEBUG : empty space: md5 = 87e00a94261d9f18ed9121a5d12eb854 OK 2026/09/29 01:12:52 DEBUG : empty space: Excluded (FilesFrom Filter) 2026/09/29 01:12:52 DEBUG : empty space: Excluded --- PASS: TestLsWithFilesFrom (5.84s) === RUN TestLsLong run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:12:56 DEBUG : potato2: md5 = c378f92cf44f9ec7eb2f6e6a0aef58f4 OK 2026/09/29 01:12:57 DEBUG : empty space: md5 = ac6c9852de297d1e1d7be25f3a434719 OK --- PASS: TestLsLong (5.08s) === RUN TestHashSums run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:13:01 DEBUG : potato2: md5 = b9a858f05f25f87b1f3c7ac0e4c88536 OK 2026/09/29 01:13:02 DEBUG : empty space: md5 = 4480c33828c69cba69b0b76d4cace8d1 OK --- PASS: TestHashSums (4.61s) === RUN TestHashSumsWithErrors 2026/09/29 01:13:04 DEBUG : Creating backend with remote ":memory:" 2026/09/29 01:13:04 DEBUG : Config file has changed externally - reloading 2026/09/29 01:13:04 ERROR : sub/file1: hash unsupported: hash type not supported 2026/09/29 01:13:04 ERROR : sub/file1: hash unsupported: hash type not supported 2026/09/29 01:13:04 ERROR : file1: hash unsupported: hash type not supported --- PASS: TestHashSumsWithErrors (0.00s) === RUN TestHashStream 2026/09/29 01:13:04 DEBUG : Creating md5 hash of 0 bytes read from input stream 2026/09/29 01:13:04 DEBUG : Creating md5 hash of 0 bytes read from input stream 2026/09/29 01:13:04 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2026/09/29 01:13:04 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2026/09/29 01:13:04 DEBUG : Creating md5 hash of 12 bytes read from input stream 2026/09/29 01:13:04 DEBUG : Creating md5 hash of 12 bytes read from input stream 2026/09/29 01:13:04 DEBUG : Creating sha1 hash of 12 bytes read from input stream 2026/09/29 01:13:04 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-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:13:05 DEBUG : potato2: md5 = b34a6c465acc3d845e66d18beb9b84c8 OK 2026/09/29 01:13:07 DEBUG : empty space: md5 = 07b172e41ffaf90abeb002227e3cd89f OK 2026/09/29 01:13:09 DEBUG : sub dir/potato3: md5 = 669497594f4534ec8d44289550cdaa5d OK --- PASS: TestCount (9.05s) === RUN TestDelete run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:13:14 DEBUG : small: md5 = 1737cb51b8d86c0a1b170de9c90e67a8 OK 2026/09/29 01:13:16 DEBUG : medium: md5 = 693a3a4126b2e93fb14b7d3d2014f7df OK 2026/09/29 01:13:17 DEBUG : large: md5 = 000e206e12fa6159aa5beece5564e93e OK 2026/09/29 01:13:18 DEBUG : Waiting for deletions to finish 2026/09/29 01:13:18 DEBUG : large: Excluded (Size Filter) 2026/09/29 01:13:18 INFO : medium: Deleted 2026/09/29 01:13:18 INFO : small: Deleted --- PASS: TestDelete (6.65s) === RUN TestDeleteFatalError run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:13:21 DEBUG : file0: md5 = 1ad86cf9fde62526a42792744a854c14 OK 2026/09/29 01:13:22 DEBUG : file1: md5 = f44ceb1414eaf91bcfa22051c2ae3d6a OK 2026/09/29 01:13:24 DEBUG : file2: md5 = f901546a54bb9673ee2893c16f8bde1e OK 2026/09/29 01:13:25 DEBUG : file3: md5 = 8c73b6192abeeb442823e463ae73d95a OK 2026/09/29 01:13:27 DEBUG : file4: md5 = d88cc6b820290c4d74c5315ba0871192 OK 2026/09/29 01:13:29 DEBUG : file5: md5 = c58fbfd08131a8123c1afe7fa6843b9d OK 2026/09/29 01:13:30 DEBUG : file6: md5 = 07bc9603e69be74394956dbc8191f66d OK 2026/09/29 01:13:32 DEBUG : file7: md5 = 80f628ddf30c854e19029d6c9f167c32 OK 2026/09/29 01:13:33 DEBUG : file8: md5 = 6a0a1d7bf4fe01dd248d73d7ea883877 OK 2026/09/29 01:13:35 DEBUG : file9: md5 = 76ac563ab7c454da107657f04574274a OK 2026/09/29 01:13:36 DEBUG : file10: md5 = a1988daa08b8480ebd9abbb93989fb4c OK 2026/09/29 01:13:38 DEBUG : file11: md5 = f23e5b0e1f7943cbb7e530ab4d29518b OK 2026/09/29 01:13:39 DEBUG : file12: md5 = de5514f7f5269add3a2dbfc184d260d9 OK 2026/09/29 01:13:41 DEBUG : file13: md5 = 7577384d6ce1549f3717d1898ee08195 OK 2026/09/29 01:13:42 DEBUG : file14: md5 = f4e1bc594d54754d468f389232b6bbfc OK 2026/09/29 01:13:44 DEBUG : file15: md5 = faea869e997457c7cb8f1405e90c3356 OK 2026/09/29 01:13:46 DEBUG : file16: md5 = 16c7d34cd87abe0e44419abc1e966006 OK 2026/09/29 01:13:47 DEBUG : file17: md5 = 5bfaab6db9d4c297a18711cb90445fc0 OK 2026/09/29 01:13:49 DEBUG : file18: md5 = ba7a574bf9bd9a5eef9a0963c19980f4 OK 2026/09/29 01:13:50 DEBUG : file19: md5 = 231d4ee3ff1515ddb30a4481e50d5097 OK 2026/09/29 01:13:50 DEBUG : Waiting for deletions to finish 2026/09/29 01:13:51 ERROR : file1: Got fatal error on delete: --max-delete threshold reached 2026/09/29 01:13:51 ERROR : file16: Got fatal error on delete: --max-delete threshold reached 2026/09/29 01:13:51 ERROR : file18: Got fatal error on delete: --max-delete threshold reached 2026/09/29 01:13:51 ERROR : file8: Got fatal error on delete: --max-delete threshold reached 2026/09/29 01:13:51 ERROR : file14: Got fatal error on delete: --max-delete threshold reached 2026/09/29 01:13:51 ERROR : file17: Got fatal error on delete: --max-delete threshold reached 2026/09/29 01:13:51 ERROR : file0: Got fatal error on delete: --max-delete threshold reached 2026/09/29 01:13:51 ERROR : file15: Got fatal error on delete: --max-delete threshold reached 2026/09/29 01:13:51 ERROR : file3: Got fatal error on delete: --max-delete threshold reached 2026/09/29 01:13:51 ERROR : file9: Got fatal error on delete: --max-delete threshold reached 2026/09/29 01:13:51 ERROR : file11: Got fatal error on delete: --max-delete threshold reached 2026/09/29 01:13:51 ERROR : file13: Got fatal error on delete: --max-delete threshold reached 2026/09/29 01:13:51 ERROR : file19: Got fatal error on delete: --max-delete threshold reached 2026/09/29 01:13:51 ERROR : file12: Got fatal error on delete: --max-delete threshold reached 2026/09/29 01:13:51 ERROR : file5: Got fatal error on delete: --max-delete threshold reached 2026/09/29 01:13:51 ERROR : file6: Got fatal error on delete: --max-delete threshold reached 2026/09/29 01:13:51 ERROR : file2: Got fatal error on delete: --max-delete threshold reached 2026/09/29 01:13:51 ERROR : file10: Got fatal error on delete: --max-delete threshold reached 2026/09/29 01:13:51 ERROR : file4: Got fatal error on delete: --max-delete threshold reached 2026/09/29 01:13:51 ERROR : file7: Got fatal error on delete: --max-delete threshold reached --- PASS: TestDeleteFatalError (40.55s) === RUN TestMaxDelete run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:14:02 DEBUG : small: md5 = b8303dbf71fae464ebb4310c110e1338 OK 2026/09/29 01:14:03 DEBUG : medium: md5 = c0142134733f6ebc3fbab0c926e35860 OK 2026/09/29 01:14:05 DEBUG : large: md5 = 15a687e2137ff1547ec8acb6e005ea58 OK 2026/09/29 01:14:05 DEBUG : Waiting for deletions to finish 2026/09/29 01:14:05 ERROR : large: Got fatal error on delete: --max-delete threshold reached 2026/09/29 01:14:05 INFO : small: Deleted 2026/09/29 01:14:05 INFO : medium: Deleted --- PASS: TestMaxDelete (6.94s) === RUN TestMaxDeleteSizeLargeFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:14:08 DEBUG : small: md5 = 94c45df6dfd6aaf84f5ce3964a302300 OK 2026/09/29 01:14:10 DEBUG : medium: md5 = bd4c2cd71a21d58509ba2671e2245a10 OK 2026/09/29 01:14:11 DEBUG : large: md5 = dc2fbb10baa8c91887143d30e9226a00 OK 2026/09/29 01:14:12 DEBUG : Waiting for deletions to finish 2026/09/29 01:14:12 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached 2026/09/29 01:14:12 INFO : small: Deleted 2026/09/29 01:14:12 INFO : medium: Deleted --- PASS: TestMaxDeleteSizeLargeFile (7.15s) === RUN TestMaxDeleteSize run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:14:16 DEBUG : small: md5 = e67e62b3004daeb0b37c5b9e2e33fe1b OK 2026/09/29 01:14:17 DEBUG : medium: md5 = 070c771622a8325352aa5880fb7f00b3 OK 2026/09/29 01:14:19 DEBUG : large: md5 = 6243fa8d223f02a280e9ab34a5bbcdac OK 2026/09/29 01:14:19 DEBUG : Waiting for deletions to finish 2026/09/29 01:14:19 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached 2026/09/29 01:14:19 INFO : small: Deleted 2026/09/29 01:14:19 INFO : medium: Deleted --- PASS: TestMaxDeleteSize (6.85s) === RUN TestReadFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:14:23 DEBUG : ReadFile: md5 = 09eddf4e1332f631b6a0bcf24a9ae940 OK --- PASS: TestReadFile (4.11s) === RUN TestRetry 2026/09/29 01:14:25 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 1/5 2026/09/29 01:14:25 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 2/5 2026/09/29 01:14:25 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2026/09/29 01:14:25 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2026/09/29 01:14:25 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2026/09/29 01:14:25 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/29 01:14:25 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-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:14:27 DEBUG : file1: md5 = fa2646b0eaf325794c8cb705c6cd58d6 OK 2026/09/29 01:14:28 DEBUG : file2: md5 = 447ec9c1b0727dd37a5b592346e06ebb OK --- PASS: TestCat (13.43s) === RUN TestPurge 2026/09/29 01:14:39 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-hukibas8voye" 2026/09/29 01:14:39 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/29 01:14:39 DEBUG : Creating backend with remote "TestDrive:crypt/sgla10v5ptbmrbnbd6l07s7qn2c32ln7elttojm8vmcj3jd3geog" 2026/09/29 01:14:40 DEBUG : Creating backend with remote "/tmp/rclone750580478" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hukibas8voye'", Local "Local file system at /tmp/rclone750580478", Modify Window "1ms" 2026/09/29 01:14:44 DEBUG : A1/B1/C1/one: md5 = 729d33af7d51673418a0d79520217735 OK 2026/09/29 01:14:44 INFO : A2: Making directory 2026/09/29 01:14:45 INFO : A1/B2: Making directory 2026/09/29 01:14:46 INFO : A1/B2/C2: Making directory 2026/09/29 01:14:47 INFO : A1/B1/C3: Making directory 2026/09/29 01:14:47 INFO : A3: Making directory 2026/09/29 01:14:48 INFO : A3/B3: Making directory 2026/09/29 01:14:49 INFO : A3/B3/C4: Making directory 2026/09/29 01:14:51 DEBUG : A1/two: md5 = 9c7f620f3c28325a996faf4a90edb435 OK 2026/09/29 01:14:54 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-hukibas8voye': Purge remote 2026/09/29 01:14:55 NOTICE: purge failed: directory not found --- PASS: TestPurge (16.02s) === RUN TestRmdirsNoLeaveRoot run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:14:59 DEBUG : A1/B1/C1/one: md5 = c622249461accc0ac6734f4403c2fb12 OK 2026/09/29 01:14:59 INFO : A2: Making directory 2026/09/29 01:15:00 INFO : A1/B2: Making directory 2026/09/29 01:15:00 INFO : A1/B2/C2: Making directory 2026/09/29 01:15:01 INFO : A1/B1/C3: Making directory 2026/09/29 01:15:02 INFO : A3: Making directory 2026/09/29 01:15:03 INFO : A3/B3: Making directory 2026/09/29 01:15:05 INFO : A3/B3/C4: Making directory 2026/09/29 01:15:08 DEBUG : A1/two: md5 = b0ac8d946dfc2002209d4c96984896e3 OK 2026/09/29 01:15:09 DEBUG : removing 1 level 3 directories 2026/09/29 01:15:09 INFO : A3/B3/C4: Removing directory 2026/09/29 01:15:11 DEBUG : removing 2 level 3 directories 2026/09/29 01:15:11 INFO : A1/B2/C2: Removing directory 2026/09/29 01:15:11 INFO : A1/B1/C3: Removing directory 2026/09/29 01:15:12 DEBUG : removing 2 level 2 directories 2026/09/29 01:15:12 INFO : A3/B3: Removing directory 2026/09/29 01:15:12 INFO : A1/B2: Removing directory 2026/09/29 01:15:13 DEBUG : removing 2 level 1 directories 2026/09/29 01:15:13 INFO : A3: Removing directory 2026/09/29 01:15:13 INFO : A2: Removing directory 2026/09/29 01:15:17 DEBUG : removing 1 level 3 directories 2026/09/29 01:15:17 INFO : A1/B1/C1: Removing directory 2026/09/29 01:15:18 DEBUG : removing 1 level 2 directories 2026/09/29 01:15:18 INFO : A1/B1: Removing directory 2026/09/29 01:15:18 DEBUG : removing 1 level 1 directories 2026/09/29 01:15:18 INFO : A1: Removing directory 2026/09/29 01:15:19 DEBUG : removing 1 level 0 directories 2026/09/29 01:15:19 INFO : Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife': Removing directory --- PASS: TestRmdirsNoLeaveRoot (25.93s) === RUN TestRmdirsLeaveRoot run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:15:21 INFO : A1: Making directory 2026/09/29 01:15:22 INFO : A1/B1: Making directory 2026/09/29 01:15:23 INFO : A1/B1/C1: Making directory 2026/09/29 01:15:26 DEBUG : removing 1 level 3 directories 2026/09/29 01:15:26 INFO : A1/B1/C1: Removing directory 2026/09/29 01:15:26 DEBUG : removing 1 level 2 directories 2026/09/29 01:15:26 INFO : A1/B1: Removing directory --- PASS: TestRmdirsLeaveRoot (8.14s) === RUN TestRmdirsWithFilter run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:15:29 INFO : A1: Making directory 2026/09/29 01:15:29 INFO : A1/B1: Making directory 2026/09/29 01:15:30 INFO : A1/B1/C1: Making directory 2026/09/29 01:15:33 DEBUG : removing 1 level 3 directories 2026/09/29 01:15:33 INFO : A1/B1/C1: Removing directory 2026/09/29 01:15:34 DEBUG : removing 1 level 2 directories 2026/09/29 01:15:34 INFO : A1/B1: Removing directory --- PASS: TestRmdirsWithFilter (7.49s) === RUN TestCopyURL run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:15:38 DEBUG : file1: md5 = 6c0f22b992fca1826b85bec902ac1b16 OK 2026/09/29 01:15:38 DEBUG : file1: size = 14 OK 2026/09/29 01:15:39 DEBUG : filename.txt: File name found in url 2026/09/29 01:15:40 DEBUG : filename.txt: md5 = e15adf7996bc2183703bd68f63166e61 OK 2026/09/29 01:15:40 DEBUG : filename.txt: size = 14 OK 2026/09/29 01:15:40 DEBUG : headerfilename.txt: filename found in Content-Disposition header. 2026/09/29 01:15:42 DEBUG : headerfilename.txt: md5 = f5ec29b2f000275b681056da35d7f9fa OK 2026/09/29 01:15:42 DEBUG : headerfilename.txt: size = 14 OK 2026/09/29 01:15:43 DEBUG : file2: md5 = 28dc7c35a65c8332fcf8e7eb43da0e98 OK 2026/09/29 01:15:43 DEBUG : file2: size = 14 OK --- PASS: TestCopyURL (9.50s) === RUN TestCopyURLDownloadHeaders run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:15:47 DEBUG : file1: md5 = b31c39c9d413b17c14dd3a37d718269e OK 2026/09/29 01:15:47 DEBUG : file1: size = 14 OK --- PASS: TestCopyURLDownloadHeaders (2.31s) === RUN TestCopyURLToWriter --- PASS: TestCopyURLToWriter (0.00s) === RUN TestMoveFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:15:48 DEBUG : file1: Need to transfer - File not found at Destination 2026/09/29 01:15:51 DEBUG : sub/file2: md5 = b5a8aaa12903ae7cdedb5f63c8003152 OK 2026/09/29 01:15:51 DEBUG : sub/file2: size = 14 OK 2026/09/29 01:15:51 INFO : file1: Copied (new) to: sub/file2 2026/09/29 01:15:51 INFO : file1: Deleted 2026/09/29 01:15:52 DEBUG : sub/file2: size = 14 OK 2026/09/29 01:15:52 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/29 01:15:52 DEBUG : file1: Unchanged skipping 2026/09/29 01:15:52 INFO : file1: Deleted 2026/09/29 01:15:52 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife': don't need to copy/move sub/file2, it is already at target location --- PASS: TestMoveFile (6.35s) === RUN TestMoveFileWithIgnoreExisting run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:15:55 DEBUG : file1: Need to transfer - File not found at Destination 2026/09/29 01:15:56 DEBUG : file1: md5 = 5b353499f6728158f61e4a37985214db OK 2026/09/29 01:15:56 DEBUG : file1: size = 14 OK 2026/09/29 01:15:56 INFO : file1: Copied (new) 2026/09/29 01:15:56 INFO : file1: Deleted 2026/09/29 01:15:57 DEBUG : file1: Destination exists, skipping 2026/09/29 01:15:57 DEBUG : file1: Not removing source file as destination file exists and --ignore-existing is set --- PASS: TestMoveFileWithIgnoreExisting (3.33s) === RUN TestMoveFileImmutable run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:15:59 DEBUG : existing: md5 = 734f0a63fdc375838128ce7996b38e47 OK 2026/09/29 01:16:00 DEBUG : existing: size = 8 (Local file system at /tmp/rclone839572334) 2026/09/29 01:16:00 DEBUG : existing: size = 6 (Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife') 2026/09/29 01:16:00 DEBUG : existing: Sizes differ 2026/09/29 01:16:00 ERROR : existing: Source and destination exist but do not match: immutable file modified --- PASS: TestMoveFileImmutable (3.33s) === RUN TestCaseInsensitiveMoveFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFile (0.53s) === RUN TestCaseInsensitiveMoveFileDryRun run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFileDryRun (0.47s) === RUN TestMoveFileBackupDir run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:16:04 DEBUG : dst/file1: md5 = 74c3b4999bd839ea29927d411c9599ae OK 2026/09/29 01:16:05 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jaqutih0kife/backup" 2026/09/29 01:16:05 DEBUG : Config file has changed externally - reloading 2026/09/29 01:16:05 DEBUG : Creating backend with remote "TestDrive:crypt/ag9v8arsn74v53ofqqbcmf8p23a3qi5hjh7fsbe1q81i4g6up45g/1nrff024r7pq65ecp72fc28jb0" 2026/09/29 01:16:07 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone839572334) 2026/09/29 01:16:07 DEBUG : dst/file1: size = 18 (Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife') 2026/09/29 01:16:07 DEBUG : dst/file1: Sizes differ 2026/09/29 01:16:09 INFO : dst/file1: Moved (server-side) 2026/09/29 01:16:10 DEBUG : dst/file1: md5 = 6f2873124e9773f41e5c3e7ab2a849bf OK 2026/09/29 01:16:10 DEBUG : dst/file1: size = 14 OK 2026/09/29 01:16:10 INFO : dst/file1: Copied (new) 2026/09/29 01:16:10 INFO : dst/file1: Deleted --- PASS: TestMoveFileBackupDir (13.27s) === 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-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:16:17 DEBUG : A/file0: md5 = bddf1ddbb34b26fd70f4c327029714cf OK 2026/09/29 01:16:19 DEBUG : A/file1: md5 = 66e8847cd3094f32a915cf331d4b73d7 OK 2026/09/29 01:16:21 DEBUG : A/file2: md5 = 9706849f1b1617740a3e32e9a9e22d44 OK 2026/09/29 01:16:22 DEBUG : A/file3: md5 = 14c0b4b29a68ce9bc39478bdbd574446 OK 2026/09/29 01:16:24 DEBUG : A/file4: md5 = 615bd70d1b65fcf55a7b79e09a97201a OK 2026/09/29 01:16:26 DEBUG : A/file5: md5 = 7448ffea468a30c12aa221a637c5b215 OK 2026/09/29 01:16:27 DEBUG : A/file6: md5 = 174e7e2456ebcba9e4dc1aaf7ed39b27 OK 2026/09/29 01:16:29 DEBUG : A/file7: md5 = d3958ef6e6686d99c92c039aedf77d9d OK 2026/09/29 01:16:30 DEBUG : A/file8: md5 = 4f471e4c3cd1a83213688a2222249a64 OK 2026/09/29 01:16:32 DEBUG : A/file9: md5 = fd17d7cfa937ed39ccac29141aa0cbf2 OK 2026/09/29 01:16:34 DEBUG : A/file10: md5 = 3f0588e0bb3d622c6994de71c064a549 OK 2026/09/29 01:16:35 DEBUG : A/file11: md5 = 4ebdcff1d5ac84e39274ac7f07974321 OK 2026/09/29 01:16:37 DEBUG : A/file12: md5 = dd88b357a727794c96830fc65c99ba0c OK 2026/09/29 01:16:38 DEBUG : A/file13: md5 = 97e95e1624d46007b1722c694a449875 OK 2026/09/29 01:16:40 DEBUG : A/file14: md5 = d5620a8a66b8a7a1bedb8b7a292e9940 OK 2026/09/29 01:16:41 DEBUG : A/file15: md5 = 30156010714aaf752967f3d0e7f25a43 OK 2026/09/29 01:16:43 DEBUG : A/file16: md5 = 83d6de65579e1cc429b0b249f301fe37 OK 2026/09/29 01:16:44 DEBUG : A/file17: md5 = 0051c4d28e99a017cb4cae99b763ac5e OK 2026/09/29 01:16:45 DEBUG : A/file18: md5 = c6251c84b925c51b519af7f4d1aa5c97 OK 2026/09/29 01:16:47 DEBUG : A/file19: md5 = d9c995d2b709233d42304863b4c1c308 OK operations_test.go:1490: Skipping test on non local remote --- SKIP: TestDirMoveMoveError (42.14s) === RUN TestDirMoveContext run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:17:00 DEBUG : A/one: md5 = c4e1395e2b457f8a15bb7c133f11ee87 OK 2026/09/29 01:17:01 DEBUG : A/two: md5 = 0d35754d6a967b42fdd5f460a06bf7c9 OK operations_test.go:1490: Skipping test on non local remote --- SKIP: TestDirMoveContext (6.27s) === RUN TestDirMove run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:17:06 DEBUG : A1/one: md5 = 4fd4fdd044f6006b16c0bd0118bf331d OK 2026/09/29 01:17:08 DEBUG : A1/two: md5 = 9db632cedaf2134c75e3d7b856525685 OK 2026/09/29 01:17:10 DEBUG : A1/B1/three: md5 = d6f7cd383bba9fd921d8f11e266d74d6 OK 2026/09/29 01:17:13 DEBUG : A1/B1/C1/four: md5 = 49c7b54597e618e76078ca90884d6b32 OK 2026/09/29 01:17:15 DEBUG : A1/B1/C2/five: md5 = 06b356b3bcac8992da5dc30b9e5831b2 OK 2026/09/29 01:17:15 INFO : A1/B2: Making directory 2026/09/29 01:17:15 INFO : A1/B1/C3: Making directory 2026/09/29 01:17:25 INFO : A2/two: Moved (server-side) to: A3/two 2026/09/29 01:17:25 INFO : A2/B1/C1/four: Moved (server-side) to: A3/B1/C1/four 2026/09/29 01:17:25 INFO : A2/B1/C2/five: Moved (server-side) to: A3/B1/C2/five 2026/09/29 01:17:25 INFO : A2/one: Moved (server-side) to: A3/one 2026/09/29 01:17:25 INFO : A2/B1/three: Moved (server-side) to: A3/B1/three 2026/09/29 01:17:30 INFO : Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife': Can't DirMove - falling back to file moves: can't move directory - incompatible remotes 2026/09/29 01:17:36 INFO : A3/two: Moved (server-side) to: A4/two 2026/09/29 01:17:36 INFO : A3/B1/C2/five: Moved (server-side) to: A4/B1/C2/five 2026/09/29 01:17:36 INFO : A3/B1/C1/four: Moved (server-side) to: A4/B1/C1/four 2026/09/29 01:17:37 INFO : A3/B1/three: Moved (server-side) to: A4/B1/three 2026/09/29 01:17:37 INFO : A3/one: Moved (server-side) to: A4/one --- PASS: TestDirMove (45.63s) === RUN TestGetFsInfo run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" --- PASS: TestGetFsInfo (0.49s) === RUN TestRcat === RUN TestRcat/withChecksum=false,ignoreChecksum=false run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:17:50 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife': File to upload is small (34 bytes), uploading instead of streaming 2026/09/29 01:17:52 DEBUG : no_checksum_small_file_from_pipe: md5 = d6313326f5663a96ea1928911924ee01 OK 2026/09/29 01:17:52 DEBUG : no_checksum_small_file_from_pipe: size = 34 OK 2026/09/29 01:17:52 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2026/09/29 01:17:52 DEBUG : no_checksum_small_file_from_pipe: Size of src and dst objects identical 2026/09/29 01:17:52 DEBUG : qc9k4uauqa9ls6a2r6lo8jf4b1m7r0510pkurois1cc70egbs4h0: Sending chunk 0 length 102465 2026/09/29 01:17:53 DEBUG : no_checksum_big_file_from_pipe: md5 = fa657fac3fea3ded34decfeba08c6117 OK 2026/09/29 01:17:53 DEBUG : no_checksum_big_file_from_pipe: size = 102401 OK 2026/09/29 01:17:53 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-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:17:55 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife': File to upload is small (34 bytes), uploading instead of streaming 2026/09/29 01:17:57 DEBUG : with_checksum_small_file_from_pipe: md5 = 6237059b50b72a35b2a2be4c0648e7f0 OK 2026/09/29 01:17:57 DEBUG : with_checksum_small_file_from_pipe: size = 34 OK 2026/09/29 01:17:57 DEBUG : with_checksum_small_file_from_pipe: Size of src and dst objects identical 2026/09/29 01:17:57 DEBUG : e74jo8a7lr1aimg2u78fct67gnuetc1qujiofaci0c8fnq7rjt1d4i296pcklqhgdihs03etvgdi4: Sending chunk 0 length 102465 2026/09/29 01:17:58 DEBUG : with_checksum_big_file_from_pipe: md5 = 9d2db3bfc6f7a97344fc520606216445 OK 2026/09/29 01:17:58 DEBUG : with_checksum_big_file_from_pipe: size = 102401 OK 2026/09/29 01:17:58 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-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:18:00 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife': File to upload is small (34 bytes), uploading instead of streaming 2026/09/29 01:18:02 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK 2026/09/29 01:18:02 DEBUG : ignore_checksum_small_file_from_pipe: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/29 01:18:02 DEBUG : 1963rn6olajfbjn4nbqi9nacu7bk879vpf0d2bg8r0ndcqqsfntob0urt056p0qujf574rqnb5f62: Sending chunk 0 length 102465 2026/09/29 01:18:03 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK 2026/09/29 01:18:03 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-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:18:05 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife': File to upload is small (34 bytes), uploading instead of streaming 2026/09/29 01:18:07 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK 2026/09/29 01:18:07 DEBUG : ignore_checksum_small_file_from_pipe: Size of src and dst objects identical 2026/09/29 01:18:07 DEBUG : 1963rn6olajfbjn4nbqi9nacu7bk879vpf0d2bg8r0ndcqqsfntob0urt056p0qujf574rqnb5f62: Sending chunk 0 length 102465 2026/09/29 01:18:08 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK 2026/09/29 01:18:08 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical --- PASS: TestRcat (20.13s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (5.20s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (4.96s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (5.04s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (4.94s) === RUN TestRcatMetadata run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" === RUN TestRcatMetadata/Normal 2026/09/29 01:18:10 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife': File to upload is small (48 bytes), uploading instead of streaming 2026/09/29 01:18:12 DEBUG : rcat_metadata: md5 = 3f6265cfcc818b2af095c2480cebdc34 OK 2026/09/29 01:18:12 DEBUG : rcat_metadata: size = 48 OK 2026/09/29 01:18:12 DEBUG : rcat_metadata: Size of src and dst objects identical === RUN TestRcatMetadata/ViaDisk 2026/09/29 01:18:13 DEBUG : rijssoobhbn85c5p1imhg55m6frank1nlplla6hkdjlqmqb2471g: Sending chunk 0 length 111 2026/09/29 01:18:14 DEBUG : rcat_metadata_uploadcutoff0: md5 = b9291a1371719f02fcb5392d7cbdc4ad OK 2026/09/29 01:18:14 DEBUG : rcat_metadata_uploadcutoff0: size = 63 OK 2026/09/29 01:18:14 DEBUG : rcat_metadata_uploadcutoff0: Size of src and dst objects identical --- PASS: TestRcatMetadata (5.79s) --- PASS: TestRcatMetadata/Normal (2.53s) --- PASS: TestRcatMetadata/ViaDisk (2.82s) === RUN TestRcatSize run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:18:17 DEBUG : potato1: md5 = 080b81f4fd2e2e6ee9bf500302893caa OK 2026/09/29 01:18:17 DEBUG : potato1: size = 60 OK 2026/09/29 01:18:17 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife': File to upload is small (60 bytes), uploading instead of streaming 2026/09/29 01:18:19 DEBUG : potato2: md5 = 7b69ae5b40d40d9804bc533b23fb622b OK 2026/09/29 01:18:19 DEBUG : potato2: size = 60 OK 2026/09/29 01:18:19 DEBUG : potato2: Size of src and dst objects identical --- PASS: TestRcatSize (4.50s) === RUN TestRcatSizeShortEOF run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:18:22 DEBUG : potato1: md5 = 1b37b7297a0a70abf584af924065b077 OK 2026/09/29 01:18:22 DEBUG : potato1: size = 120 (Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife') 2026/09/29 01:18:22 DEBUG : potato1: size = 60 (Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife') 2026/09/29 01:18:22 ERROR : potato1: corrupted on transfer: sizes differ src 120 vs dst(Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife') 60 2026/09/29 01:18:22 INFO : potato1: Removing failed copy --- PASS: TestRcatSizeShortEOF (2.29s) === RUN TestRcatSizeMetadata run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:18:24 DEBUG : potato1: md5 = 13eba32a5c9cae6f20d431103b945b0c OK 2026/09/29 01:18:24 DEBUG : potato1: size = 60 OK 2026/09/29 01:18:24 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife': File to upload is small (60 bytes), uploading instead of streaming 2026/09/29 01:18:25 DEBUG : potato2: md5 = 613284187e7c44657f98c99ce9a63f55 OK 2026/09/29 01:18:25 DEBUG : potato2: size = 60 OK 2026/09/29 01:18:25 DEBUG : potato2: Size of src and dst objects identical --- PASS: TestRcatSizeMetadata (5.04s) === RUN TestRcatSizeUploadHeaders run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:18:29 DEBUG : potato1: md5 = b125fa7ed80ed419fece8eef595b464a OK 2026/09/29 01:18:29 DEBUG : potato1: size = 60 OK --- PASS: TestRcatSizeUploadHeaders (2.42s) === RUN TestRcatSizeChecksum === RUN TestRcatSizeChecksum/Corrupted run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", 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-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:18:32 DEBUG : potato4: md5 = b8ad7663f179b723533efba794d509dd OK 2026/09/29 01:18:32 DEBUG : potato4: size = 60 (Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife') 2026/09/29 01:18:32 DEBUG : potato4: size = 59 (Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife') 2026/09/29 01:18:32 ERROR : potato4: corrupted on transfer: sizes differ src 60 vs dst(Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife') 59 2026/09/29 01:18:32 INFO : potato4: Removing failed copy === RUN TestRcatSizeChecksum/IgnoreChecksum run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:18:34 DEBUG : potato2: size = 60 OK === RUN TestRcatSizeChecksum/NoHashes run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:18:37 DEBUG : potato3: md5 = 2a56a4f4f533d594880a98911da86425 OK 2026/09/29 01:18:37 DEBUG : potato3: size = 60 OK --- PASS: TestRcatSizeChecksum (7.70s) --- SKIP: TestRcatSizeChecksum/Corrupted (0.48s) --- PASS: TestRcatSizeChecksum/SizeDiffers (2.55s) --- PASS: TestRcatSizeChecksum/IgnoreChecksum (2.29s) --- PASS: TestRcatSizeChecksum/NoHashes (2.37s) === RUN TestTouchDir run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:18:39 DEBUG : potato2: md5 = e39cd58bab3a631a525ec8809fc11bd6 OK 2026/09/29 01:18:40 DEBUG : empty space: md5 = 5cb65f529214c1edc384fe439d53eb96 OK 2026/09/29 01:18:43 DEBUG : sub dir/potato3: md5 = 673191ea00c13d7eeffb7e987dbccd81 OK 2026/09/29 01:18:44 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife': Touching "sub dir/potato3" 2026/09/29 01:18:44 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife': Touching "potato2" 2026/09/29 01:18:44 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife': Touching "empty space" --- PASS: TestTouchDir (9.66s) === RUN TestMkdirMetadata run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:18:47 DEBUG : dir with metadata: Making directory with metadata 2026/09/29 01:18:48 INFO : dir with metadata: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) --- PASS: TestMkdirMetadata (2.48s) === RUN TestMkdirModTime run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:18:50 DEBUG : directory with modtime: Making directory with metadata 2026/09/29 01:18:51 INFO : directory with modtime: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/09/29 01:18:51 NOTICE: dry run directory with modtime: Skipped make directory as --dry-run is set --- PASS: TestMkdirModTime (2.30s) === RUN TestCopyDirMetadata run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:18:52 DEBUG : dir with metadata to be copied: Making directory with metadata 2026/09/29 01:18:52 INFO : dir with metadata to be copied: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2026/09/29 01:18:52 DEBUG : Google drive root 'crypt/ag9v8arsn74v53ofqqbcmf8p23a3qi5hjh7fsbe1q81i4g6up45g': Skipping btime metadata as can't update it on an existing file: 2026-09-29T01:18:52.663320868Z 2026/09/29 01:18:53 INFO : non existent directory: Updated directory metadata 2026/09/29 01:18:55 DEBUG : Google drive root 'crypt/ag9v8arsn74v53ofqqbcmf8p23a3qi5hjh7fsbe1q81i4g6up45g': Skipping btime metadata as can't update it on an existing file: 2026-09-29T01:18:52.663320868Z 2026/09/29 01:18:55 INFO : existing directory: Updated directory metadata --- PASS: TestCopyDirMetadata (4.75s) === RUN TestSetDirModTime run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:18:57 DEBUG : set modtime on non existent directory: Skipping set directory modification time as --no-update-dir-modtime is set 2026/09/29 01:18:59 INFO : set modtime on existing directory: Set directory modification time (using SetModTime) 2026/09/29 01:18:59 INFO : set modtime on existing directory: Set directory modification time (using DirSetModTime) --- PASS: TestSetDirModTime (3.90s) === RUN TestDirsEqual run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:19:01 DEBUG : dir with metadata to be copied: Making directory with metadata 2026/09/29 01:19:01 INFO : dir with metadata to be copied: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2026/09/29 01:19:01 DEBUG : dst: Making directory with metadata 2026/09/29 01:19:02 INFO : dst: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2026/09/29 01:19:02 DEBUG : dst: Directory modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/29 01:19:02 INFO : dst: Set directory modification time (using SetModTime) 2026/09/29 01:19:02 INFO : dst: Set directory modification time (using SetModTime) 2026/09/29 01:19:02 DEBUG : dst: Directory modification time the same (differ by 1ns, within tolerance 1ms) 2026/09/29 01:19:03 INFO : dst: Set directory modification time (using SetModTime) 2026/09/29 01:19:03 DEBUG : dst: Destination directory is newer than source, skipping --- PASS: TestDirsEqual (3.49s) === RUN TestRemoveExisting run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife'", Local "Local file system at /tmp/rclone839572334", Modify Window "1ms" 2026/09/29 01:19:07 DEBUG : sub dir/test remove existing: md5 = 91f12310a86ccd3968e368997e099076 OK 2026/09/29 01:19:08 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: md5 = b9d7b87a4bbd16fa72b0e9ef20e0f31b OK 2026/09/29 01:19:10 DEBUG : sub dir/test remove existing: TEST: renaming existing object to "sub dir/test remove existing.nafudit5" before starting 2026/09/29 01:19:11 DEBUG : sub dir/test remove existing.nafudit5: TEST: removing renamed existing file after operation 2026/09/29 01:19:13 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: TEST: renaming existing object to "sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.voquwid2" before starting 2026/09/29 01:19:14 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.voquwid2: TEST: renaming existing back after failed operation 2026/09/29 01:19:15 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: TEST: renaming existing object to "sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.xazedah2" before starting 2026/09/29 01:19:16 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.xazedah2: TEST: removing renamed existing file after operation --- PASS: TestRemoveExisting (13.72s) === RUN TestRcatInputFailurePreservesDestination 2026/09/29 01:19:18 DEBUG : Creating backend with remote "/tmp/TestRcatInputFailurePreservesDestination88352749/001" --- 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/29 01:19:18 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jaqutih0kife': Purge remote "./operations.test -test.v -test.timeout 1h0m0s -remote TestCryptDrive: -verbose" - Finished OK in 18m2.434584982s (try 1/5)