"./operations.test -test.v -test.timeout 1h0m0s -remote TestCryptDrive: -verbose -fast-list" - Starting (try 1/5)
2026/10/10 02:35:19 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jejanub7data"
2026/10/10 02:35:19 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/10 02:35:19 DEBUG : Creating backend with remote "TestDrive:crypt/c9vi13fe91ns30ne5lotrp9f2vro5okml54qeii135v23234bhq0"
2026/10/10 02:35:20 DEBUG : Creating backend with remote "/tmp/rclone2319411210"
=== 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-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
    multithread_test.go:120: multithread writing not supported
--- SKIP: TestMultithreadCopy (0.25s)
=== RUN   TestMultithreadCopyAbort
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
    multithread_test.go:120: multithread writing not supported
--- SKIP: TestMultithreadCopyAbort (0.24s)
=== RUN   TestMultithreadCopyWriterAtErrors
=== RUN   TestMultithreadCopyWriterAtErrors/open/fatal=false
2026/10/10 02:35:21 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt
=== RUN   TestMultithreadCopyWriterAtErrors/open/fatal=true
2026/10/10 02:35:21 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt
=== RUN   TestMultithreadCopyWriterAtErrors/write/fatal=false
2026/10/10 02:35:21 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt
2026/10/10 02:35:21 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams
2026/10/10 02:35:21 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting
2026/10/10 02:35:21 DEBUG : file.txt: writing chunk 1
2026/10/10 02:35:21 DEBUG : file.txt: multi-thread copy: chunk 2/4 failed: multi-thread copy: failed to write chunk: disk full
2026/10/10 02:35:21 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting
2026/10/10 02:35:21 DEBUG : file.txt: writing chunk 2
2026/10/10 02:35:21 DEBUG : file.txt: multi-thread copy: chunk 3/4 failed: multi-thread copy: failed to write chunk: disk full
2026/10/10 02:35:21 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting
2026/10/10 02:35:21 DEBUG : file.txt: writing chunk 0
2026/10/10 02:35:21 DEBUG : file.txt: multi-thread copy: chunk 1/4 failed: multi-thread copy: failed to write chunk: disk full
2026/10/10 02:35:21 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit
2026/10/10 02:35:21 DEBUG : file.txt: multi-thread copy: abort failed: multi-thread copy: failed to find temp file when aborting chunk writer: object not found
=== RUN   TestMultithreadCopyWriterAtErrors/write/fatal=true
2026/10/10 02:35:21 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt
2026/10/10 02:35:21 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams
2026/10/10 02:35:21 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting
2026/10/10 02:35:21 DEBUG : file.txt: writing chunk 1
2026/10/10 02:35:21 DEBUG : file.txt: multi-thread copy: chunk 2/4 failed: multi-thread copy: failed to write chunk: disk full
2026/10/10 02:35:21 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting
2026/10/10 02:35:21 DEBUG : file.txt: writing chunk 2
2026/10/10 02:35:21 DEBUG : file.txt: multi-thread copy: chunk 3/4 failed: multi-thread copy: failed to write chunk: disk full
2026/10/10 02:35:21 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting
2026/10/10 02:35:21 DEBUG : file.txt: writing chunk 0
2026/10/10 02:35:21 DEBUG : file.txt: multi-thread copy: chunk 1/4 failed: multi-thread copy: failed to write chunk: disk full
2026/10/10 02:35:21 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit
2026/10/10 02:35:21 DEBUG : file.txt: multi-thread copy: abort failed: multi-thread copy: failed to find temp file when aborting chunk writer: object not found
=== RUN   TestMultithreadCopyWriterAtErrors/close/fatal=false
2026/10/10 02:35:21 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt
2026/10/10 02:35:21 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams
2026/10/10 02:35:21 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting
2026/10/10 02:35:21 DEBUG : file.txt: writing chunk 1
2026/10/10 02:35:21 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 finished
2026/10/10 02:35:21 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting
2026/10/10 02:35:21 DEBUG : file.txt: writing chunk 2
2026/10/10 02:35:21 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 finished
2026/10/10 02:35:21 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 starting
2026/10/10 02:35:21 DEBUG : file.txt: writing chunk 3
2026/10/10 02:35:21 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 finished
2026/10/10 02:35:21 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting
2026/10/10 02:35:21 DEBUG : file.txt: writing chunk 0
2026/10/10 02:35:21 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 finished
2026/10/10 02:35:21 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit
2026/10/10 02:35:21 DEBUG : file.txt: multi-thread copy: abort failed: multi-thread copy: failed to find temp file when aborting chunk writer: object not found
=== RUN   TestMultithreadCopyWriterAtErrors/close/fatal=true
2026/10/10 02:35:21 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt
2026/10/10 02:35:21 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams
2026/10/10 02:35:21 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting
2026/10/10 02:35:21 DEBUG : file.txt: writing chunk 1
2026/10/10 02:35:21 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 finished
2026/10/10 02:35:21 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting
2026/10/10 02:35:21 DEBUG : file.txt: writing chunk 2
2026/10/10 02:35:21 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 finished
2026/10/10 02:35:21 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 starting
2026/10/10 02:35:21 DEBUG : file.txt: writing chunk 3
2026/10/10 02:35:21 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 finished
2026/10/10 02:35:21 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting
2026/10/10 02:35:21 DEBUG : file.txt: writing chunk 0
2026/10/10 02:35:21 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 finished
2026/10/10 02:35:21 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit
2026/10/10 02:35:21 DEBUG : file.txt: multi-thread copy: abort failed: multi-thread copy: failed to find temp file when aborting chunk writer: object not found
--- PASS: TestMultithreadCopyWriterAtErrors (0.00s)
    --- PASS: TestMultithreadCopyWriterAtErrors/open/fatal=false (0.00s)
    --- PASS: TestMultithreadCopyWriterAtErrors/open/fatal=true (0.00s)
    --- PASS: TestMultithreadCopyWriterAtErrors/write/fatal=false (0.00s)
    --- PASS: TestMultithreadCopyWriterAtErrors/write/fatal=true (0.00s)
    --- PASS: TestMultithreadCopyWriterAtErrors/close/fatal=false (0.00s)
    --- PASS: TestMultithreadCopyWriterAtErrors/close/fatal=true (0.00s)
=== RUN   TestSizeDiffers
2026/10/10 02:35:21 DEBUG : a: size = 0 OK
2026/10/10 02:35:21 DEBUG : a: size = 1 (memory)
2026/10/10 02:35:21 DEBUG : a: size = 2 (memory)
--- PASS: TestSizeDiffers (0.00s)
=== RUN   TestDirTransferEntry
--- PASS: TestDirTransferEntry (0.00s)
=== RUN   TestReOpen
=== RUN   TestReOpen/Normal
=== RUN   TestReOpen/Normal/Basics
2026/10/10 02:35:21 DEBUG : potato: Seek from 10 to 0
=== RUN   TestReOpen/Normal/ErrorAtStart
=== RUN   TestReOpen/Normal/WithErrors
2026/10/10 02:35:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2026/10/10 02:35:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2026/10/10 02:35:21 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN   TestReOpen/Normal/TooManyErrors
2026/10/10 02:35:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2026/10/10 02:35:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2026/10/10 02:35:21 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2026/10/10 02:35:21 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN   TestReOpen/Normal/ReadAt
2026/10/10 02:35:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/10/10 02:35:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/10/10 02:35:21 DEBUG : potato: Seek from 5 to 1
=== RUN   TestReOpen/Normal/Seek
2026/10/10 02:35:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/10/10 02:35:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/10/10 02:35:21 DEBUG : potato: Seek from 5 to 2
=== RUN   TestReOpen/Normal/AccountRead
=== RUN   TestReOpen/Normal/AccountReadDelay
2026/10/10 02:35:21 DEBUG : potato: Seek from 10 to 0
2026/10/10 02:35:21 DEBUG : potato: Seek from 10 to 0
2026/10/10 02:35:21 DEBUG : potato: Seek from 10 to 0
=== RUN   TestReOpen/Normal/AccountReadError
=== RUN   TestReOpen/WithRangeOption
=== RUN   TestReOpen/WithRangeOption/Basics
2026/10/10 02:35:21 DEBUG : potato: Seek from 7 to 0
=== RUN   TestReOpen/WithRangeOption/ErrorAtStart
=== RUN   TestReOpen/WithRangeOption/WithErrors
2026/10/10 02:35:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2026/10/10 02:35:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2026/10/10 02:35:21 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN   TestReOpen/WithRangeOption/TooManyErrors
2026/10/10 02:35:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2026/10/10 02:35:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2026/10/10 02:35:21 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2026/10/10 02:35:21 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN   TestReOpen/WithRangeOption/ReadAt
2026/10/10 02:35:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/10/10 02:35:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/10/10 02:35:21 DEBUG : potato: Seek from 5 to 1
=== RUN   TestReOpen/WithRangeOption/Seek
2026/10/10 02:35:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/10/10 02:35:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/10/10 02:35:21 DEBUG : potato: Seek from 5 to 2
2026/10/10 02:35:21 DEBUG : potato: Seek from 7 to 4
=== RUN   TestReOpen/WithRangeOption/AccountRead
=== RUN   TestReOpen/WithRangeOption/AccountReadDelay
2026/10/10 02:35:21 DEBUG : potato: Seek from 7 to 0
2026/10/10 02:35:21 DEBUG : potato: Seek from 7 to 0
2026/10/10 02:35:21 DEBUG : potato: Seek from 7 to 0
=== RUN   TestReOpen/WithRangeOption/AccountReadError
=== RUN   TestReOpen/WithSeekOption
=== RUN   TestReOpen/WithSeekOption/Basics
2026/10/10 02:35:21 DEBUG : potato: Seek from 8 to 0
=== RUN   TestReOpen/WithSeekOption/ErrorAtStart
=== RUN   TestReOpen/WithSeekOption/WithErrors
2026/10/10 02:35:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2026/10/10 02:35:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2026/10/10 02:35:21 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN   TestReOpen/WithSeekOption/TooManyErrors
2026/10/10 02:35:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2026/10/10 02:35:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2026/10/10 02:35:21 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2026/10/10 02:35:21 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN   TestReOpen/WithSeekOption/ReadAt
2026/10/10 02:35:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/10/10 02:35:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/10/10 02:35:21 DEBUG : potato: Seek from 5 to 1
=== RUN   TestReOpen/WithSeekOption/Seek
2026/10/10 02:35:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/10/10 02:35:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/10/10 02:35:21 DEBUG : potato: Seek from 5 to 2
2026/10/10 02:35:21 DEBUG : potato: Seek from 7 to 5
=== RUN   TestReOpen/WithSeekOption/AccountRead
=== RUN   TestReOpen/WithSeekOption/AccountReadDelay
2026/10/10 02:35:21 DEBUG : potato: Seek from 8 to 0
2026/10/10 02:35:21 DEBUG : potato: Seek from 8 to 0
2026/10/10 02:35:21 DEBUG : potato: Seek from 8 to 0
=== RUN   TestReOpen/WithSeekOption/AccountReadError
=== RUN   TestReOpen/UnknownSize
=== RUN   TestReOpen/UnknownSize/Basics
2026/10/10 02:35:21 DEBUG : potato: Seek from 9 to 0
=== RUN   TestReOpen/UnknownSize/ErrorAtStart
=== RUN   TestReOpen/UnknownSize/WithErrors
2026/10/10 02:35:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2026/10/10 02:35:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2026/10/10 02:35:21 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN   TestReOpen/UnknownSize/TooManyErrors
2026/10/10 02:35:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2026/10/10 02:35:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2026/10/10 02:35:21 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2026/10/10 02:35:21 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN   TestReOpen/UnknownSize/ReadAt
2026/10/10 02:35:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/10/10 02:35:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/10/10 02:35:21 DEBUG : potato: Seek from 5 to 1
=== RUN   TestReOpen/UnknownSize/Seek
2026/10/10 02:35:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/10/10 02:35:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/10/10 02:35:21 DEBUG : potato: Seek from 5 to 2
2026/10/10 02:35:21 DEBUG : potato: Seek from 7 to 6
=== RUN   TestReOpen/UnknownSize/AccountRead
=== RUN   TestReOpen/UnknownSize/AccountReadDelay
2026/10/10 02:35:21 DEBUG : potato: Seek from 9 to 0
2026/10/10 02:35:21 DEBUG : potato: Seek from 9 to 0
2026/10/10 02:35:21 DEBUG : potato: Seek from 9 to 0
=== RUN   TestReOpen/UnknownSize/AccountReadError
--- PASS: TestReOpen (0.00s)
    --- PASS: TestReOpen/Normal (0.00s)
        --- PASS: TestReOpen/Normal/Basics (0.00s)
        --- PASS: TestReOpen/Normal/ErrorAtStart (0.00s)
        --- PASS: TestReOpen/Normal/WithErrors (0.00s)
        --- PASS: TestReOpen/Normal/TooManyErrors (0.00s)
        --- PASS: TestReOpen/Normal/ReadAt (0.00s)
        --- PASS: TestReOpen/Normal/Seek (0.00s)
        --- PASS: TestReOpen/Normal/AccountRead (0.00s)
        --- PASS: TestReOpen/Normal/AccountReadDelay (0.00s)
        --- PASS: TestReOpen/Normal/AccountReadError (0.00s)
    --- PASS: TestReOpen/WithRangeOption (0.00s)
        --- PASS: TestReOpen/WithRangeOption/Basics (0.00s)
        --- PASS: TestReOpen/WithRangeOption/ErrorAtStart (0.00s)
        --- PASS: TestReOpen/WithRangeOption/WithErrors (0.00s)
        --- PASS: TestReOpen/WithRangeOption/TooManyErrors (0.00s)
        --- PASS: TestReOpen/WithRangeOption/ReadAt (0.00s)
        --- PASS: TestReOpen/WithRangeOption/Seek (0.00s)
        --- PASS: TestReOpen/WithRangeOption/AccountRead (0.00s)
        --- PASS: TestReOpen/WithRangeOption/AccountReadDelay (0.00s)
        --- PASS: TestReOpen/WithRangeOption/AccountReadError (0.00s)
    --- PASS: TestReOpen/WithSeekOption (0.00s)
        --- PASS: TestReOpen/WithSeekOption/Basics (0.00s)
        --- PASS: TestReOpen/WithSeekOption/ErrorAtStart (0.00s)
        --- PASS: TestReOpen/WithSeekOption/WithErrors (0.00s)
        --- PASS: TestReOpen/WithSeekOption/TooManyErrors (0.00s)
        --- PASS: TestReOpen/WithSeekOption/ReadAt (0.00s)
        --- PASS: TestReOpen/WithSeekOption/Seek (0.00s)
        --- PASS: TestReOpen/WithSeekOption/AccountRead (0.00s)
        --- PASS: TestReOpen/WithSeekOption/AccountReadDelay (0.00s)
        --- PASS: TestReOpen/WithSeekOption/AccountReadError (0.00s)
    --- PASS: TestReOpen/UnknownSize (0.00s)
        --- PASS: TestReOpen/UnknownSize/Basics (0.00s)
        --- PASS: TestReOpen/UnknownSize/ErrorAtStart (0.00s)
        --- PASS: TestReOpen/UnknownSize/WithErrors (0.00s)
        --- PASS: TestReOpen/UnknownSize/TooManyErrors (0.00s)
        --- PASS: TestReOpen/UnknownSize/ReadAt (0.00s)
        --- PASS: TestReOpen/UnknownSize/Seek (0.00s)
        --- PASS: TestReOpen/UnknownSize/AccountRead (0.00s)
        --- PASS: TestReOpen/UnknownSize/AccountReadDelay (0.00s)
        --- PASS: TestReOpen/UnknownSize/AccountReadError (0.00s)
=== RUN   TestCheck
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:35:23 DEBUG : rutabaga: md5 = 12fbdbed07d959b5c1218e2a5b6f995e OK
=== RUN   TestCheck/1
=== RUN   TestCheck/2
2026/10/10 02:35:25 DEBUG : empty space: md5 = bf41fa27bf6f64b1695b2c0cd6dc2118 OK
=== RUN   TestCheck/3
2026/10/10 02:35:27 DEBUG : potato2: md5 = e38d31818aff4c60f4ec87aaea35c6d9 OK
=== RUN   TestCheck/4
=== RUN   TestCheck/5
2026/10/10 02:35:30 DEBUG : remotepotato: md5 = 74c2f5b9e211cedd36ad96270bd881be OK
=== RUN   TestCheck/6
=== RUN   TestCheck/7
--- PASS: TestCheck (13.44s)
    --- PASS: TestCheck/1 (0.28s)
    --- PASS: TestCheck/2 (0.26s)
    --- PASS: TestCheck/3 (0.27s)
    --- PASS: TestCheck/4 (0.30s)
    --- PASS: TestCheck/5 (0.23s)
    --- PASS: TestCheck/6 (0.40s)
    --- PASS: TestCheck/7 (0.32s)
=== RUN   TestCheckFsError
2026/10/10 02:35:34 DEBUG : Creating backend with remote "nonexistent"
2026/10/10 02:35:34 DEBUG : Creating backend with remote "nonexistent"
2026/10/10 02:35:34 DEBUG : Local file system at nonexistent: Waiting for checks to finish
2026/10/10 02:35:34 ERROR : Local file system at nonexistent: error reading source root directory: directory not found
2026/10/10 02:35:34 NOTICE: Local file system at nonexistent: 0 differences found
2026/10/10 02:35:34 NOTICE: Local file system at nonexistent: 2 errors while checking
--- PASS: TestCheckFsError (0.00s)
=== RUN   TestCheckDownload
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:35:36 DEBUG : rutabaga: md5 = f255d7eeb20ee41d5aa41d72b3743ee7 OK
=== RUN   TestCheckDownload/1
=== RUN   TestCheckDownload/2
2026/10/10 02:35:40 DEBUG : empty space: md5 = f0b47d0045f7b7894b7dc0b3fec88cbe OK
=== RUN   TestCheckDownload/3
2026/10/10 02:35:43 DEBUG : potato2: md5 = 511ccb6d48a72de0c36f8f14f582a027 OK
=== RUN   TestCheckDownload/4
=== RUN   TestCheckDownload/5
2026/10/10 02:35:46 DEBUG : remotepotato: md5 = baa824b9cfee08e5e4f3ca2f60ff011c OK
=== RUN   TestCheckDownload/6
=== RUN   TestCheckDownload/7
--- PASS: TestCheckDownload (17.34s)
    --- PASS: TestCheckDownload/1 (1.02s)
    --- PASS: TestCheckDownload/2 (0.90s)
    --- PASS: TestCheckDownload/3 (0.91s)
    --- PASS: TestCheckDownload/4 (0.97s)
    --- PASS: TestCheckDownload/5 (0.78s)
    --- PASS: TestCheckDownload/6 (0.91s)
    --- PASS: TestCheckDownload/7 (0.84s)
=== RUN   TestCheckSizeOnly
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:35:53 DEBUG : rutabaga: md5 = 02f0908c0b15db97c2f3daf540c74751 OK
=== RUN   TestCheckSizeOnly/1
=== RUN   TestCheckSizeOnly/2
2026/10/10 02:35:55 DEBUG : empty space: md5 = bdf00f8c6be1de458f08525e45d77af1 OK
=== RUN   TestCheckSizeOnly/3
2026/10/10 02:35:57 DEBUG : potato2: md5 = f65800c9715d706d139bf9f76288dabf OK
=== RUN   TestCheckSizeOnly/4
=== RUN   TestCheckSizeOnly/5
2026/10/10 02:35:59 DEBUG : remotepotato: md5 = d9b41a18508dd46fe2fe3b68fb35c4f8 OK
=== RUN   TestCheckSizeOnly/6
=== RUN   TestCheckSizeOnly/7
--- PASS: TestCheckSizeOnly (11.29s)
    --- PASS: TestCheckSizeOnly/1 (0.22s)
    --- PASS: TestCheckSizeOnly/2 (0.28s)
    --- PASS: TestCheckSizeOnly/3 (0.22s)
    --- PASS: TestCheckSizeOnly/4 (0.28s)
    --- PASS: TestCheckSizeOnly/5 (0.26s)
    --- PASS: TestCheckSizeOnly/6 (0.25s)
    --- PASS: TestCheckSizeOnly/7 (0.24s)
=== RUN   TestCheckEqualReaders
--- PASS: TestCheckEqualReaders (0.00s)
=== RUN   TestParseSumFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:36:04 DEBUG : test.sum: md5 = 11371bf471ad413f81394173bbc40733 OK
2026/10/10 02:36:05 NOTICE: test.sum: improperly formatted checksum line 4
2026/10/10 02:36:05 NOTICE: test.sum: improperly formatted checksum line 5
2026/10/10 02:36:05 NOTICE: test.sum: improperly formatted checksum line 6
2026/10/10 02:36:05 NOTICE: test.sum: 2 warning(s) suppressed...
2026/10/10 02:36:07 DEBUG : test.sum: md5 = 633144bd8eccb0bfe6830f7d15d1e6c7 OK
2026/10/10 02:36:08 NOTICE: test.sum: improperly formatted checksum line 4
2026/10/10 02:36:08 NOTICE: test.sum: improperly formatted checksum line 5
2026/10/10 02:36:08 NOTICE: test.sum: improperly formatted checksum line 6
2026/10/10 02:36:08 NOTICE: test.sum: 2 warning(s) suppressed...
--- PASS: TestParseSumFile (6.22s)
=== RUN   TestCheckSum
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:36:09 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jejanub7data/data"
2026/10/10 02:36:09 DEBUG : Creating backend with remote "TestDrive:crypt/c9vi13fe91ns30ne5lotrp9f2vro5okml54qeii135v23234bhq0/vjrnln8ratgmqakfosrqe8espk"
    check_test.go:354: Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data/data' lacks md5, skipping
--- SKIP: TestCheckSum (2.28s)
=== RUN   TestCheckSumDownload
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:36:11 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jejanub7data/data"
2026/10/10 02:36:11 DEBUG : Creating backend with remote "TestDrive:crypt/c9vi13fe91ns30ne5lotrp9f2vro5okml54qeii135v23234bhq0/vjrnln8ratgmqakfosrqe8espk"
2026/10/10 02:36:15 DEBUG : data/banana: md5 = 2d7a8bb31bb420e35ebe0b56aace62c4 OK
2026/10/10 02:36:17 DEBUG : test.sum: md5 = 4d68fd07fa0425be15b13c60cef34145 OK
=== RUN   TestCheckSumDownload/subtest1
2026/10/10 02:36:21 DEBUG : data/potato: md5 = 062fb0b657e452414f1ad4e90cc9a620 OK
2026/10/10 02:36:23 DEBUG : test.sum: md5 = a6ed49ee48032f663071276dca4a05ee OK
=== RUN   TestCheckSumDownload/subtest2
2026/10/10 02:36:27 DEBUG : test.sum: md5 = 8fb14b2d9ee6b7d89aab8094cd0744a1 OK
=== RUN   TestCheckSumDownload/subtest3
2026/10/10 02:36:31 DEBUG : test.sum: md5 = 20e856e9fc2f0952d30e9310176da31f OK
=== RUN   TestCheckSumDownload/subtest4
2026/10/10 02:36:35 DEBUG : test.sum: md5 = 3f4fa3fbcd0ed095b48ae4782dc10673 OK
=== RUN   TestCheckSumDownload/subtest5
2026/10/10 02:36:39 DEBUG : test.sum: md5 = 65c5de71b14415c5f43815fe11496a84 OK
=== RUN   TestCheckSumDownload/subtest6
2026/10/10 02:36:43 DEBUG : data/banana: md5 = 945dbb7edece75919d604eb9a6e2fa13 OK
2026/10/10 02:36:45 DEBUG : data/potato: md5 = 495a885dcf0c407939812d2e0ae4bc6c OK
2026/10/10 02:36:46 DEBUG : test.sum: md5 = 65af927635d9b7ce028919cf5668ff5c OK
=== RUN   TestCheckSumDownload/subtest7
--- PASS: TestCheckSumDownload (40.91s)
    --- PASS: TestCheckSumDownload/subtest1 (2.29s)
    --- PASS: TestCheckSumDownload/subtest2 (1.76s)
    --- PASS: TestCheckSumDownload/subtest3 (1.93s)
    --- PASS: TestCheckSumDownload/subtest4 (1.96s)
    --- PASS: TestCheckSumDownload/subtest5 (1.87s)
    --- PASS: TestCheckSumDownload/subtest6 (1.84s)
    --- PASS: TestCheckSumDownload/subtest7 (1.96s)
=== RUN   TestCheckSumConcurrency
2026/10/10 02:36:52 DEBUG : file0: md5 = 022eb0c0085c731f335df970fafa7446 OK
2026/10/10 02:36:52 DEBUG : file2: md5 = a58f09ae3db8ae4b943fdbaace096072 OK
2026/10/10 02:36:52 DEBUG : file1: md5 = 17fbabbccbdea0e6be9eb64c8c6fea41 OK
2026/10/10 02:36:52 DEBUG : file3: md5 = e0639b17e0744aca9a3ba29c049e59ee OK
2026/10/10 02:36:52 NOTICE: Mock file system at checkSum: 0 differences found
2026/10/10 02:36:52 NOTICE: Mock file system at checkSum: 4 matching files
--- PASS: TestCheckSumConcurrency (0.00s)
=== RUN   TestCheckSumDownloadConcurrency
2026/10/10 02:36:52 DEBUG : file0: md5 = 022eb0c0085c731f335df970fafa7446 OK
2026/10/10 02:36:52 DEBUG : file2: md5 = a58f09ae3db8ae4b943fdbaace096072 OK
2026/10/10 02:36:52 DEBUG : file1: md5 = 17fbabbccbdea0e6be9eb64c8c6fea41 OK
2026/10/10 02:36:52 DEBUG : file3: md5 = e0639b17e0744aca9a3ba29c049e59ee OK
2026/10/10 02:36:52 NOTICE: Mock file system at checkSum: 0 differences found
2026/10/10 02:36:52 NOTICE: Mock file system at checkSum: 4 matching files
--- PASS: TestCheckSumDownloadConcurrency (0.00s)
=== RUN   TestApplyTransforms
2026/10/10 02:36:52 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-fuhepod8sosu"
2026/10/10 02:36:52 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/10 02:36:52 DEBUG : Creating backend with remote "TestDrive:crypt/41cg7j2s0h73adqr22g2j9cpied9rvvvcctiu6dca8m3mj4cis8g"
2026/10/10 02:36:53 DEBUG : Creating backend with remote "/tmp/rclone1059448296"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuhepod8sosu'", Local "Local file system at /tmp/rclone1059448296", Modify Window "1ms"
2026/10/10 02:36:56 DEBUG : hello, world!: md5 = 63e94b412f8be28fe941bb47b9631837 OK
upper checkfile vs. lower remote (without normalization)
2026/10/10 02:36:56 ERROR : hello, world!: sum not found
2026/10/10 02:36:56 ERROR : HELLO, WORLD!: file not in Encrypted drive 'TestCryptDrive:rclone-test-fuhepod8sosu'
2026/10/10 02:36:56 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fuhepod8sosu': 1 files missing
2026/10/10 02:36:56 NOTICE: 1 hashes missing
2026/10/10 02:36:56 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fuhepod8sosu': 1 differences found
2026/10/10 02:36:56 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fuhepod8sosu': 2 errors while checking
upper checkfile vs. lower remote (with normalization)
2026/10/10 02:36:57 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/10 02:36:57 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fuhepod8sosu': 0 differences found
2026/10/10 02:36:57 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fuhepod8sosu': 1 matching files
2026/10/10 02:36:57 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-safosix5ruyu"
2026/10/10 02:36:57 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/10 02:36:57 DEBUG : Creating backend with remote "TestDrive:crypt/aoe27mp19gdqs0tvj8j18k7gcbve00q58g1l1q0vffboo8brem70"
2026/10/10 02:36:58 DEBUG : Creating backend with remote "/tmp/rclone3886821458"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-safosix5ruyu'", Local "Local file system at /tmp/rclone3886821458", Modify Window "1ms"
2026/10/10 02:37:01 DEBUG : HELLO, WORLD!: md5 = 1084472df6b07188192e5f95bb3f4644 OK
lower checkfile vs. upper remote (without normalization)
2026/10/10 02:37:01 ERROR : HELLO, WORLD!: sum not found
2026/10/10 02:37:01 ERROR : hello, world!: file not in Encrypted drive 'TestCryptDrive:rclone-test-safosix5ruyu'
2026/10/10 02:37:01 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-safosix5ruyu': 1 files missing
2026/10/10 02:37:01 NOTICE: 1 hashes missing
2026/10/10 02:37:01 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-safosix5ruyu': 1 differences found
2026/10/10 02:37:01 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-safosix5ruyu': 2 errors while checking
lower checkfile vs. upper remote (with normalization)
2026/10/10 02:37:02 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/10 02:37:02 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-safosix5ruyu': 0 differences found
2026/10/10 02:37:02 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-safosix5ruyu': 1 matching files
2026/10/10 02:37:02 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-muwacew3woge"
2026/10/10 02:37:02 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/10 02:37:03 DEBUG : Creating backend with remote "TestDrive:crypt/btuehmu0bc5b63dcdfml9e2rvohuoqpeo51oqtd56dcdc9qnfoh0"
2026/10/10 02:37:04 DEBUG : Creating backend with remote "/tmp/rclone2180175147"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-muwacew3woge'", Local "Local file system at /tmp/rclone2180175147", Modify Window "1ms"
2026/10/10 02:37:06 DEBUG : HeLlO, wOrLd!: md5 = 0a69499bcfb201f11f7cecf29ad6f156 OK
lower checkfile vs. upperlowermixed remote (without normalization)
2026/10/10 02:37:07 ERROR : HeLlO, wOrLd!: sum not found
2026/10/10 02:37:07 ERROR : hello, world!: file not in Encrypted drive 'TestCryptDrive:rclone-test-muwacew3woge'
2026/10/10 02:37:07 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-muwacew3woge': 1 files missing
2026/10/10 02:37:07 NOTICE: 1 hashes missing
2026/10/10 02:37:07 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-muwacew3woge': 1 differences found
2026/10/10 02:37:07 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-muwacew3woge': 2 errors while checking
lower checkfile vs. upperlowermixed remote (with normalization)
2026/10/10 02:37:08 DEBUG : HeLlO, wOrLd!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/10 02:37:08 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-muwacew3woge': 0 differences found
2026/10/10 02:37:08 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-muwacew3woge': 1 matching files
2026/10/10 02:37:08 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-fijovap2dopi"
2026/10/10 02:37:08 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/10 02:37:08 DEBUG : Creating backend with remote "TestDrive:crypt/am332jevmjvt20nn7bg98jfa738qndom8m0fublbkpvqak8vjd10"
2026/10/10 02:37:09 DEBUG : Creating backend with remote "/tmp/rclone716603468"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fijovap2dopi'", Local "Local file system at /tmp/rclone716603468", Modify Window "1ms"
2026/10/10 02:37:11 DEBUG : HELLO, WORLD!: md5 = 7267fc001a87d3a6a7b55de375d882be OK
upperlowermixed checkfile vs. upper remote (without normalization)
2026/10/10 02:37:12 ERROR : HELLO, WORLD!: sum not found
2026/10/10 02:37:12 ERROR : HeLlO, wOrLd!: file not in Encrypted drive 'TestCryptDrive:rclone-test-fijovap2dopi'
2026/10/10 02:37:12 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fijovap2dopi': 1 files missing
2026/10/10 02:37:12 NOTICE: 1 hashes missing
2026/10/10 02:37:12 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fijovap2dopi': 1 differences found
2026/10/10 02:37:12 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fijovap2dopi': 2 errors while checking
upperlowermixed checkfile vs. upper remote (with normalization)
2026/10/10 02:37:13 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/10 02:37:13 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fijovap2dopi': 0 differences found
2026/10/10 02:37:13 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fijovap2dopi': 1 matching files
2026/10/10 02:37:13 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-sesejuf2soka"
2026/10/10 02:37:13 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/10 02:37:13 DEBUG : Creating backend with remote "TestDrive:crypt/hb184g2sn1sj14ve3tnuhj2s5cn21ds20o800i72i7r67703jv7g"
2026/10/10 02:37:14 DEBUG : Creating backend with remote "/tmp/rclone3520241010"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-sesejuf2soka'", Local "Local file system at /tmp/rclone3520241010", Modify Window "1ms"
2026/10/10 02:37:16 DEBUG : 測試_Русский___ě_áñ: md5 = 34b5fbbe2bb90c6674a78353d46a6aa3 OK
NFD checkfile vs. NFC remote (without normalization)
2026/10/10 02:37:17 ERROR : 測試_Русский___ě_áñ: sum not found
2026/10/10 02:37:17 ERROR : 測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-sesejuf2soka'
2026/10/10 02:37:17 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-sesejuf2soka': 1 files missing
2026/10/10 02:37:17 NOTICE: 1 hashes missing
2026/10/10 02:37:17 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-sesejuf2soka': 1 differences found
2026/10/10 02:37:17 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-sesejuf2soka': 2 errors while checking
NFD checkfile vs. NFC remote (with normalization)
2026/10/10 02:37:18 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/10 02:37:18 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-sesejuf2soka': 0 differences found
2026/10/10 02:37:18 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-sesejuf2soka': 1 matching files
2026/10/10 02:37:18 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-pubobit0mudo"
2026/10/10 02:37:18 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/10 02:37:18 DEBUG : Creating backend with remote "TestDrive:crypt/pqsd8sgipio676c9ri5kullv1j6rrb71fb31a252ip2j0b4v5v9g"
2026/10/10 02:37:19 DEBUG : Creating backend with remote "/tmp/rclone1217084788"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-pubobit0mudo'", Local "Local file system at /tmp/rclone1217084788", Modify Window "1ms"
2026/10/10 02:37:22 DEBUG : 測試_Русский___ě_áñ: md5 = 2f4ab2cd2354def07c569e2c37a6749b OK
NFC checkfile vs. NFD remote (without normalization)
2026/10/10 02:37:23 ERROR : 測試_Русский___ě_áñ: sum not found
2026/10/10 02:37:23 ERROR : 測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-pubobit0mudo'
2026/10/10 02:37:23 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-pubobit0mudo': 1 files missing
2026/10/10 02:37:23 NOTICE: 1 hashes missing
2026/10/10 02:37:23 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-pubobit0mudo': 1 differences found
2026/10/10 02:37:23 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-pubobit0mudo': 2 errors while checking
NFC checkfile vs. NFD remote (with normalization)
2026/10/10 02:37:24 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/10 02:37:24 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-pubobit0mudo': 0 differences found
2026/10/10 02:37:24 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-pubobit0mudo': 1 matching files
2026/10/10 02:37:24 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-gixuyeh1vuti"
2026/10/10 02:37:24 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/10 02:37:24 DEBUG : Creating backend with remote "TestDrive:crypt/8375rsp23tn7a5j1i12i6f2rvrbborfh4m53o5k0e046vvb7og10"
2026/10/10 02:37:25 DEBUG : Creating backend with remote "/tmp/rclone3848293428"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gixuyeh1vuti'", Local "Local file system at /tmp/rclone3848293428", Modify Window "1ms"
2026/10/10 02:37:27 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = b6bb991424574f87b6340fac0d0af467 OK
NFDx2 checkfile vs. both remote (without normalization)
2026/10/10 02:37:28 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found
2026/10/10 02:37:28 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-gixuyeh1vuti'
2026/10/10 02:37:28 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-gixuyeh1vuti': 1 files missing
2026/10/10 02:37:28 NOTICE: 1 hashes missing
2026/10/10 02:37:28 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-gixuyeh1vuti': 1 differences found
2026/10/10 02:37:28 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-gixuyeh1vuti': 2 errors while checking
NFDx2 checkfile vs. both remote (with normalization)
2026/10/10 02:37:29 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/10 02:37:29 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-gixuyeh1vuti': 0 differences found
2026/10/10 02:37:29 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-gixuyeh1vuti': 1 matching files
2026/10/10 02:37:29 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-hatewis9yucu"
2026/10/10 02:37:29 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/10 02:37:29 DEBUG : Creating backend with remote "TestDrive:crypt/3r44rf1lgrrb6kltn06j6fac9f08na5rgftbpvdvuouab2nbs7a0"
2026/10/10 02:37:30 DEBUG : Creating backend with remote "/tmp/rclone275804640"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hatewis9yucu'", Local "Local file system at /tmp/rclone275804640", Modify Window "1ms"
2026/10/10 02:37:33 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 76d9d7c73045a100b6290041de00cd0d OK
NFCx2 checkfile vs. both remote (without normalization)
2026/10/10 02:37:34 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found
2026/10/10 02:37:34 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-hatewis9yucu'
2026/10/10 02:37:34 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-hatewis9yucu': 1 files missing
2026/10/10 02:37:34 NOTICE: 1 hashes missing
2026/10/10 02:37:34 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-hatewis9yucu': 1 differences found
2026/10/10 02:37:34 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-hatewis9yucu': 2 errors while checking
NFCx2 checkfile vs. both remote (with normalization)
2026/10/10 02:37:35 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/10 02:37:35 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-hatewis9yucu': 0 differences found
2026/10/10 02:37:35 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-hatewis9yucu': 1 matching files
2026/10/10 02:37:35 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-hesiqem3jejo"
2026/10/10 02:37:35 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/10 02:37:35 DEBUG : Creating backend with remote "TestDrive:crypt/shm31er3vbpr5vsp5ve1cj2utro5qtp2p3s9nbd9niq3pe3mpr7g"
2026/10/10 02:37:36 DEBUG : Creating backend with remote "/tmp/rclone342149748"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hesiqem3jejo'", Local "Local file system at /tmp/rclone342149748", Modify Window "1ms"
2026/10/10 02:37:38 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = b7453f798a82f7b15ea988895647c89b OK
both checkfile vs. NFDx2 remote (without normalization)
2026/10/10 02:37:39 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found
2026/10/10 02:37:39 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-hesiqem3jejo'
2026/10/10 02:37:39 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-hesiqem3jejo': 1 files missing
2026/10/10 02:37:39 NOTICE: 1 hashes missing
2026/10/10 02:37:39 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-hesiqem3jejo': 1 differences found
2026/10/10 02:37:39 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-hesiqem3jejo': 2 errors while checking
both checkfile vs. NFDx2 remote (with normalization)
2026/10/10 02:37:40 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/10 02:37:40 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-hesiqem3jejo': 0 differences found
2026/10/10 02:37:40 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-hesiqem3jejo': 1 matching files
2026/10/10 02:37:40 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-maneliy7qoju"
2026/10/10 02:37:40 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/10 02:37:40 DEBUG : Creating backend with remote "TestDrive:crypt/mkau024da0amqrvgcg34hqpq5c7spemsdg14qncl252l4spj2f4g"
2026/10/10 02:37:41 DEBUG : Creating backend with remote "/tmp/rclone1877603369"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-maneliy7qoju'", Local "Local file system at /tmp/rclone1877603369", Modify Window "1ms"
2026/10/10 02:37:43 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 03937e29456ef86fc40e3d14b686babb OK
both checkfile vs. NFCx2 remote (without normalization)
2026/10/10 02:37:44 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found
2026/10/10 02:37:44 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-maneliy7qoju'
2026/10/10 02:37:44 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-maneliy7qoju': 1 files missing
2026/10/10 02:37:44 NOTICE: 1 hashes missing
2026/10/10 02:37:44 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-maneliy7qoju': 1 differences found
2026/10/10 02:37:44 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-maneliy7qoju': 2 errors while checking
both checkfile vs. NFCx2 remote (with normalization)
2026/10/10 02:37:45 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/10 02:37:45 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-maneliy7qoju': 0 differences found
2026/10/10 02:37:45 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-maneliy7qoju': 1 matching files
2026/10/10 02:37:45 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-maneliy7qoju': Purge remote
2026/10/10 02:37:45 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-hesiqem3jejo': Purge remote
2026/10/10 02:37:46 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-hatewis9yucu': Purge remote
2026/10/10 02:37:46 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-gixuyeh1vuti': Purge remote
2026/10/10 02:37:47 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-pubobit0mudo': Purge remote
2026/10/10 02:37:47 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-sesejuf2soka': Purge remote
2026/10/10 02:37:48 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-fijovap2dopi': Purge remote
2026/10/10 02:37:48 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-muwacew3woge': Purge remote
2026/10/10 02:37:49 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-safosix5ruyu': Purge remote
2026/10/10 02:37:49 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-fuhepod8sosu': Purge remote
--- PASS: TestApplyTransforms (57.62s)
=== RUN   TestTruncateString
--- PASS: TestTruncateString (0.00s)
=== RUN   TestCopyFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:37:50 DEBUG : file1: Need to transfer - File not found at Destination
2026/10/10 02:37:52 DEBUG : sub/file2: md5 = 55d9894ca579542c7f899be7c89a91de OK
2026/10/10 02:37:52 DEBUG : sub/file2: size = 14 OK
2026/10/10 02:37:52 INFO  : file1: Copied (new) to: sub/file2
2026/10/10 02:37:53 DEBUG : sub/file2: size = 14 OK
2026/10/10 02:37:53 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2026/10/10 02:37:53 DEBUG : file1: Unchanged skipping
2026/10/10 02:37:54 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data': don't need to copy/move sub/file2, it is already at target location
--- PASS: TestCopyFile (6.95s)
=== RUN   TestCopyFileImmutable
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:37:57 DEBUG : existing: Need to transfer - File not found at Destination
2026/10/10 02:37:59 DEBUG : existing: md5 = 1e75ddc84881d67dfd91d1f5c8256db9 OK
2026/10/10 02:37:59 DEBUG : existing: size = 6 OK
2026/10/10 02:37:59 INFO  : existing: Copied (new)
2026/10/10 02:37:59 DEBUG : existing: size = 6 OK
2026/10/10 02:37:59 DEBUG : existing: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2026/10/10 02:37:59 DEBUG : existing: Unchanged skipping
2026/10/10 02:38:00 DEBUG : existing: size = 8 (Local file system at /tmp/rclone2319411210)
2026/10/10 02:38:00 DEBUG : existing: size = 6 (Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data')
2026/10/10 02:38:00 DEBUG : existing: Sizes differ
2026/10/10 02:38:00 ERROR : existing: Source and destination exist but do not match: immutable file modified
--- PASS: TestCopyFileImmutable (4.71s)
=== RUN   TestCopyLongFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
    copy_test.go:188: Test only runs on local
--- SKIP: TestCopyLongFile (0.51s)
=== RUN   TestCopyFileBackupDir
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:38:04 DEBUG : dst/file1: md5 = 4c46b81ed14d7ebbd7721e38c2c5730b OK
2026/10/10 02:38:05 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jejanub7data/backup"
2026/10/10 02:38:05 DEBUG : Creating backend with remote "TestDrive:crypt/c9vi13fe91ns30ne5lotrp9f2vro5okml54qeii135v23234bhq0/1nrff024r7pq65ecp72fc28jb0"
2026/10/10 02:38:07 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone2319411210)
2026/10/10 02:38:07 DEBUG : dst/file1: size = 18 (Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data')
2026/10/10 02:38:07 DEBUG : dst/file1: Sizes differ
2026/10/10 02:38:09 INFO  : dst/file1: Moved (server-side)
2026/10/10 02:38:11 DEBUG : dst/file1: md5 = f82374b791588e692ed117a115da09ea OK
2026/10/10 02:38:11 DEBUG : dst/file1: size = 14 OK
2026/10/10 02:38:11 INFO  : dst/file1: Copied (new)
--- PASS: TestCopyFileBackupDir (14.01s)
=== RUN   TestCopyFileCompareDest
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:38:16 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jejanub7data/dst"
2026/10/10 02:38:16 DEBUG : Creating backend with remote "TestDrive:crypt/c9vi13fe91ns30ne5lotrp9f2vro5okml54qeii135v23234bhq0/31u3jie661vd5p8j7rtc3hgbh0"
2026/10/10 02:38:18 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jejanub7data/CompareDest"
2026/10/10 02:38:18 DEBUG : Creating backend with remote "TestDrive:crypt/c9vi13fe91ns30ne5lotrp9f2vro5okml54qeii135v23234bhq0/gveqi14airsml4bgu7krj116o8"
2026/10/10 02:38:20 DEBUG : one: Need to transfer - File not found at Destination
2026/10/10 02:38:22 DEBUG : one: md5 = f49243af29c17bca13282abb917f5196 OK
2026/10/10 02:38:22 DEBUG : one: size = 3 OK
2026/10/10 02:38:22 INFO  : one: Copied (new)
2026/10/10 02:38:23 DEBUG : one: size = 5 (Local file system at /tmp/rclone2319411210)
2026/10/10 02:38:23 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data/dst')
2026/10/10 02:38:23 DEBUG : one: Sizes differ
2026/10/10 02:38:25 DEBUG : one: md5 = 5521aa0ef77007ad23b90c77a8a57e59 OK
2026/10/10 02:38:25 DEBUG : one: size = 5 OK
2026/10/10 02:38:25 INFO  : one: Copied (replaced existing)
2026/10/10 02:38:27 DEBUG : dst/one: md5 = 6357034f16b467f2f48edaafb7f9db01 OK
2026/10/10 02:38:29 DEBUG : CompareDest/one: md5 = d62ba778d478f480c4586aaed852d157 OK
2026/10/10 02:38:30 DEBUG : one: size = 5 (Local file system at /tmp/rclone2319411210)
2026/10/10 02:38:30 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data/dst')
2026/10/10 02:38:30 DEBUG : one: Sizes differ
2026/10/10 02:38:31 DEBUG : one: size = 5 OK
2026/10/10 02:38:31 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/10/10 02:38:31 DEBUG : one: Destination found in --compare-dest, skipping
2026/10/10 02:38:33 DEBUG : CompareDest/two: md5 = 5068834b415155bd46daa92c00360a67 OK
2026/10/10 02:38:34 DEBUG : two: Need to transfer - File not found at Destination
2026/10/10 02:38:34 DEBUG : two: size = 3 OK
2026/10/10 02:38:34 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/10/10 02:38:34 DEBUG : two: Destination found in --compare-dest, skipping
2026/10/10 02:38:35 DEBUG : two: Need to transfer - File not found at Destination
2026/10/10 02:38:35 DEBUG : two: size = 3 OK
2026/10/10 02:38:35 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/10/10 02:38:35 DEBUG : two: Destination found in --compare-dest, skipping
2026/10/10 02:38:37 DEBUG : two: Need to transfer - File not found at Destination
2026/10/10 02:38:37 DEBUG : two: size = 5 (Local file system at /tmp/rclone2319411210)
2026/10/10 02:38:37 DEBUG : two: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data/CompareDest')
2026/10/10 02:38:37 DEBUG : two: Sizes differ
2026/10/10 02:38:39 DEBUG : two: md5 = 581de57f99fef26696950a20dc83e6a8 OK
2026/10/10 02:38:39 DEBUG : two: size = 5 OK
2026/10/10 02:38:39 INFO  : two: Copied (new)
--- PASS: TestCopyFileCompareDest (27.94s)
=== RUN   TestCopyFileCopyDestImmutable
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:38:44 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jejanub7data/dst"
2026/10/10 02:38:44 DEBUG : Creating backend with remote "TestDrive:crypt/c9vi13fe91ns30ne5lotrp9f2vro5okml54qeii135v23234bhq0/31u3jie661vd5p8j7rtc3hgbh0"
2026/10/10 02:38:48 DEBUG : dst/one: md5 = f09cb07e8153c8791d10eca1118be3f6 OK
2026/10/10 02:38:51 DEBUG : CopyDest/one: md5 = 4b690858468a9730d4ceb3e16d1cb978 OK
2026/10/10 02:38:52 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jejanub7data/CopyDest"
2026/10/10 02:38:52 DEBUG : Creating backend with remote "TestDrive:crypt/c9vi13fe91ns30ne5lotrp9f2vro5okml54qeii135v23234bhq0/d09o6po3f7bm6ce32vdgs8h9ls"
2026/10/10 02:38:53 DEBUG : one: size = 5 (Local file system at /tmp/rclone2319411210)
2026/10/10 02:38:53 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data/dst')
2026/10/10 02:38:53 DEBUG : one: Sizes differ
2026/10/10 02:38:53 DEBUG : one: size = 5 OK
2026/10/10 02:38:53 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/10/10 02:38:53 DEBUG : one: size = 5 (Local file system at /tmp/rclone2319411210)
2026/10/10 02:38:53 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data/dst')
2026/10/10 02:38:53 DEBUG : one: Sizes differ
2026/10/10 02:38:53 ERROR : one: Source and destination exist but do not match: immutable file modified
--- PASS: TestCopyFileCopyDestImmutable (13.38s)
=== RUN   TestCopyFileCopyDest
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:38:57 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jejanub7data/dst"
2026/10/10 02:38:57 DEBUG : Creating backend with remote "TestDrive:crypt/c9vi13fe91ns30ne5lotrp9f2vro5okml54qeii135v23234bhq0/31u3jie661vd5p8j7rtc3hgbh0"
2026/10/10 02:38:59 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jejanub7data/CopyDest"
2026/10/10 02:38:59 DEBUG : Creating backend with remote "TestDrive:crypt/c9vi13fe91ns30ne5lotrp9f2vro5okml54qeii135v23234bhq0/d09o6po3f7bm6ce32vdgs8h9ls"
2026/10/10 02:39:01 DEBUG : one: Need to transfer - File not found at Destination
2026/10/10 02:39:03 DEBUG : one: md5 = 12718c6df2cb25e1eda3846fb9a39077 OK
2026/10/10 02:39:03 DEBUG : one: size = 3 OK
2026/10/10 02:39:03 INFO  : one: Copied (new)
2026/10/10 02:39:05 DEBUG : one: size = 5 (Local file system at /tmp/rclone2319411210)
2026/10/10 02:39:05 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data/dst')
2026/10/10 02:39:05 DEBUG : one: Sizes differ
2026/10/10 02:39:06 DEBUG : one: md5 = 9852696703befb755319b1044da0bba0 OK
2026/10/10 02:39:06 DEBUG : one: size = 5 OK
2026/10/10 02:39:06 INFO  : one: Copied (replaced existing)
2026/10/10 02:39:08 DEBUG : dst/one: md5 = 2fe0c3fd9af10ad3765facb1176760f4 OK
2026/10/10 02:39:11 DEBUG : CopyDest/one: md5 = 7c5b744acee29f0d4f943d1e34dab26d OK
2026/10/10 02:39:12 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jejanub7data/BackupDir"
2026/10/10 02:39:12 DEBUG : Creating backend with remote "TestDrive:crypt/c9vi13fe91ns30ne5lotrp9f2vro5okml54qeii135v23234bhq0/s6dbk3lfi7c9kfvo6j7bla9m0g"
2026/10/10 02:39:13 DEBUG : one: size = 5 (Local file system at /tmp/rclone2319411210)
2026/10/10 02:39:13 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data/dst')
2026/10/10 02:39:13 DEBUG : one: Sizes differ
2026/10/10 02:39:14 DEBUG : one: size = 5 OK
2026/10/10 02:39:14 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/10/10 02:39:14 DEBUG : one: size = 5 (Local file system at /tmp/rclone2319411210)
2026/10/10 02:39:14 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data/dst')
2026/10/10 02:39:14 DEBUG : one: Sizes differ
2026/10/10 02:39:15 INFO  : one: Moved (server-side)
2026/10/10 02:39:16 DEBUG : one: size = 5 OK
2026/10/10 02:39:16 INFO  : one: Copied (server-side copy)
2026/10/10 02:39:16 DEBUG : one: Destination found in --copy-dest, using server-side copy
2026/10/10 02:39:18 DEBUG : CopyDest/two: md5 = 5016e95b9cb25eb9285f8b0f5ce9ba05 OK
2026/10/10 02:39:19 DEBUG : two: Need to transfer - File not found at Destination
2026/10/10 02:39:20 DEBUG : two: size = 3 OK
2026/10/10 02:39:20 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/10/10 02:39:21 DEBUG : two: size = 3 OK
2026/10/10 02:39:21 INFO  : two: Copied (server-side copy)
2026/10/10 02:39:21 DEBUG : two: Destination found in --copy-dest, using server-side copy
2026/10/10 02:39:22 DEBUG : two: size = 3 OK
2026/10/10 02:39:22 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/10/10 02:39:22 DEBUG : two: Unchanged skipping
2026/10/10 02:39:24 DEBUG : CopyDest/three: md5 = d7898b9f2651eab5031b84530eaa3d0b OK
2026/10/10 02:39:25 DEBUG : three: Need to transfer - File not found at Destination
2026/10/10 02:39:25 DEBUG : three: size = 7 (Local file system at /tmp/rclone2319411210)
2026/10/10 02:39:25 DEBUG : three: size = 5 (Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data/CopyDest')
2026/10/10 02:39:25 DEBUG : three: Sizes differ
2026/10/10 02:39:25 DEBUG : three: Destination not found in --copy-dest
2026/10/10 02:39:26 DEBUG : three: md5 = f06ca96074c8cbc1d5db4238bb9d2e8b OK
2026/10/10 02:39:26 DEBUG : three: size = 7 OK
2026/10/10 02:39:26 INFO  : three: Copied (new)
--- PASS: TestCopyFileCopyDest (36.49s)
=== RUN   TestCopyInplace
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
    copy_test.go:434: Partial uploads not supported
--- SKIP: TestCopyInplace (0.50s)
=== RUN   TestCopyLongFileName
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
    copy_test.go:467: Partial uploads not supported
--- SKIP: TestCopyLongFileName (0.49s)
=== RUN   TestCopyLongFileNameCollision
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
    copy_test.go:500: Partial uploads not supported
--- SKIP: TestCopyLongFileNameCollision (0.51s)
=== RUN   TestCopyFileMaxTransfer
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:39:35 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination
2026/10/10 02:39:38 DEBUG : TestCopyFileMaxTransfer/file1: md5 = 01fb6227895784e36c2b7dd46315db77 OK
2026/10/10 02:39:38 DEBUG : TestCopyFileMaxTransfer/file1: size = 14 OK
2026/10/10 02:39:38 INFO  : TestCopyFileMaxTransfer/file1: Copied (new)
2026/10/10 02:39:39 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination
2026/10/10 02:39:39 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/10/10 02:39:40 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination
2026/10/10 02:39:41 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination
2026/10/10 02:39:42 DEBUG : TestCopyFileMaxTransfer/file4: md5 = 4f03a0a425353686b7c7a6ea31cf18d0 OK
2026/10/10 02:39:42 DEBUG : TestCopyFileMaxTransfer/file4: size = 2062 OK
2026/10/10 02:39:42 INFO  : TestCopyFileMaxTransfer/file4: Copied (new)
--- PASS: TestCopyFileMaxTransfer (10.12s)
=== RUN   TestDeduplicateInteractive
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
    dedupe_test.go:37: Can't run this test without a hash
--- SKIP: TestDeduplicateInteractive (0.48s)
=== RUN   TestDeduplicateSkip
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:39:48 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data': Looking for duplicate names using skip mode.
2026/10/10 02:39:49 NOTICE: one: Found 2 files with duplicate names
2026/10/10 02:39:49 NOTICE: one: Skipping 2 files with duplicate names
--- PASS: TestDeduplicateSkip (4.95s)
=== RUN   TestDeduplicateSizeOnly
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:39:55 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data': Looking for duplicate names using skip mode.
2026/10/10 02:39:55 NOTICE: one: Found 3 files with duplicate names
2026/10/10 02:39:55 NOTICE: one: Deleting 1/2 identical duplicates (size 11)
2026/10/10 02:39:56 INFO  : one: Deleted
2026/10/10 02:39:56 NOTICE: one: Skipping 2 files with duplicate names
--- PASS: TestDeduplicateSizeOnly (6.72s)
=== RUN   TestDeduplicateFirst
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:40:02 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data': Looking for duplicate names using first mode.
2026/10/10 02:40:02 NOTICE: one: Found 3 files with duplicate names
2026/10/10 02:40:03 INFO  : one: Deleted
2026/10/10 02:40:03 INFO  : one: Deleted
2026/10/10 02:40:03 NOTICE: one: Deleted 2 extra copies
--- PASS: TestDeduplicateFirst (7.09s)
=== RUN   TestDeduplicateNewest
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:40:09 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data': Looking for duplicate names using newest mode.
2026/10/10 02:40:09 NOTICE: one: Found 3 files with duplicate names
2026/10/10 02:40:10 INFO  : one: Deleted
2026/10/10 02:40:10 INFO  : one: Deleted
2026/10/10 02:40:10 NOTICE: one: Deleted 2 extra copies
--- PASS: TestDeduplicateNewest (6.77s)
=== RUN   TestDeduplicateNewestByHash
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
    dedupe_test.go:37: Can't run this test without a hash
--- SKIP: TestDeduplicateNewestByHash (0.49s)
=== RUN   TestDeduplicateOldest
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:40:16 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data': Looking for duplicate names using oldest mode.
2026/10/10 02:40:16 NOTICE: one: Found 3 files with duplicate names
2026/10/10 02:40:17 INFO  : one: Deleted
2026/10/10 02:40:17 INFO  : one: Deleted
2026/10/10 02:40:17 NOTICE: one: Deleted 2 extra copies
--- PASS: TestDeduplicateOldest (6.70s)
=== RUN   TestDeduplicateLargest
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:40:23 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data': Looking for duplicate names using largest mode.
2026/10/10 02:40:23 NOTICE: one: Found 3 files with duplicate names
2026/10/10 02:40:24 INFO  : one: Deleted
2026/10/10 02:40:24 INFO  : one: Deleted
2026/10/10 02:40:24 NOTICE: one: Deleted 2 extra copies
--- PASS: TestDeduplicateLargest (6.82s)
=== RUN   TestDeduplicateSmallest
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:40:30 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data': Looking for duplicate names using smallest mode.
2026/10/10 02:40:30 NOTICE: one: Found 3 files with duplicate names
2026/10/10 02:40:31 INFO  : one: Deleted
2026/10/10 02:40:31 INFO  : one: Deleted
2026/10/10 02:40:31 NOTICE: one: Deleted 2 extra copies
--- PASS: TestDeduplicateSmallest (7.18s)
=== RUN   TestDeduplicateRename
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:40:38 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data': Looking for duplicate names using rename mode.
2026/10/10 02:40:39 NOTICE: one.txt: Found 3 files with duplicate names
2026/10/10 02:40:39 INFO  : one-2.txt: renamed from: one.txt
2026/10/10 02:40:40 INFO  : one-3.txt: renamed from: one.txt
2026/10/10 02:40:41 INFO  : one-4.txt: renamed from: one.txt
--- PASS: TestDeduplicateRename (11.22s)
=== RUN   TestDeduplicateRenameManyExisting
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:40:45 DEBUG : one-1.txt: md5 = d8c33de1d105f28aed1c49c7a6934af7 OK
2026/10/10 02:40:47 DEBUG : one-2.txt: md5 = 662c9422da886357d2f3cffb8eb4aacb OK
2026/10/10 02:40:49 DEBUG : one-3.txt: md5 = f9a9054f4f96d766f4b1a4ee72768bb2 OK
2026/10/10 02:40:50 DEBUG : one-4.txt: md5 = fc442d3c8801480e321c36a2f49025fe OK
2026/10/10 02:40:52 DEBUG : one-5.txt: md5 = b8d333dc531207762f58eb20db7dc100 OK
2026/10/10 02:40:53 DEBUG : one-6.txt: md5 = 9c96367337521c2eac9fb75cbebf5273 OK
2026/10/10 02:40:55 DEBUG : one-7.txt: md5 = 14098b76a1adf3f0313d75daf621471b OK
2026/10/10 02:40:56 DEBUG : one-8.txt: md5 = 92d269257e9071d7fc5f2ecb1993d23e OK
2026/10/10 02:40:58 DEBUG : one-9.txt: md5 = f324f4804af89faf3879e09675e880a4 OK
2026/10/10 02:40:59 DEBUG : one-10.txt: md5 = 41ff2c1091dc8117f5d7e0222fb933c5 OK
2026/10/10 02:41:01 DEBUG : one-11.txt: md5 = fc70609531a749573e2b1f886d096ab7 OK
2026/10/10 02:41:02 DEBUG : one-12.txt: md5 = 04cc8e8d5c9740bba89a9f188914f377 OK
2026/10/10 02:41:04 DEBUG : one-13.txt: md5 = 7fa12a7cc23cece657b1111caba11c3f OK
2026/10/10 02:41:05 DEBUG : one-14.txt: md5 = 7ab02501e76ffcde76c92ef02e8c5301 OK
2026/10/10 02:41:07 DEBUG : one-15.txt: md5 = 8e99112702d299ee7290aeab309a4214 OK
2026/10/10 02:41:08 DEBUG : one-16.txt: md5 = d4a575afdbb23e7f5975d013423af9a5 OK
2026/10/10 02:41:10 DEBUG : one-17.txt: md5 = 63d117edadfde2eeb2c1cd3d6f2637ac OK
2026/10/10 02:41:11 DEBUG : one-18.txt: md5 = 6cf2d1797741112ee491947f3d5928f9 OK
2026/10/10 02:41:13 DEBUG : one-19.txt: md5 = 8ddf6f884826b0fabf755933071440c1 OK
2026/10/10 02:41:14 DEBUG : one-20.txt: md5 = 60fc45cb20a47500e41343585b9a12bd OK
2026/10/10 02:41:15 DEBUG : one-21.txt: md5 = a9d0a819eddcb55292b2e4624ccc5ab0 OK
2026/10/10 02:41:17 DEBUG : one-22.txt: md5 = 7c592b468631a905d438832e15a8de3d OK
2026/10/10 02:41:19 DEBUG : one-23.txt: md5 = 3a62a15e0706cb21e67559a13e0391fc OK
2026/10/10 02:41:20 DEBUG : one-24.txt: md5 = b8fdc364b3004ee639c46c287028cb3e OK
2026/10/10 02:41:22 DEBUG : one-25.txt: md5 = e33659113b5b4276a1fd358a77d8061a OK
2026/10/10 02:41:23 DEBUG : one-26.txt: md5 = 1dcaa5603de57941c1ec728e8ac0feb3 OK
2026/10/10 02:41:25 DEBUG : one-27.txt: md5 = 698624f68513b5a367f97bd0fe880efe OK
2026/10/10 02:41:26 DEBUG : one-28.txt: md5 = 9e73c8f6259a372693cde8149402e81a OK
2026/10/10 02:41:28 DEBUG : one-29.txt: md5 = d4807af6bacbeb7bb0ff65362b68d11d OK
2026/10/10 02:41:29 DEBUG : one-30.txt: md5 = 15f2519bd86cd2b15330cec6762ad9e5 OK
2026/10/10 02:41:31 DEBUG : one-31.txt: md5 = e6b6970d5f0c21ef3730a4d8bd0e3f02 OK
2026/10/10 02:41:32 DEBUG : one-32.txt: md5 = 627b74478d7c7e40a47200c6bb2b3d50 OK
2026/10/10 02:41:34 DEBUG : one-33.txt: md5 = 7facd931685d43113b6699130e6d3580 OK
2026/10/10 02:41:35 DEBUG : one-34.txt: md5 = 5793d4df2ea99e7ca868d7e5f2a96c1d OK
2026/10/10 02:41:37 DEBUG : one-35.txt: md5 = 3c4fcc80bdf502046ddcde6e9f31437c OK
2026/10/10 02:41:39 DEBUG : one-36.txt: md5 = 6526c45b2782012dbcd93417e1d7c042 OK
2026/10/10 02:41:40 DEBUG : one-37.txt: md5 = d2ed2ac6d8b19843f3fec45bed9ac88e OK
2026/10/10 02:41:42 DEBUG : one-38.txt: md5 = 87e87a0f5cc115a528a693ea90c5e87f OK
2026/10/10 02:41:43 DEBUG : one-39.txt: md5 = 970f924f6eb7f6790dedf42ba59e917d OK
2026/10/10 02:41:45 DEBUG : one-40.txt: md5 = afe9da8e01677af442d7d10f8b458189 OK
2026/10/10 02:41:47 DEBUG : one-41.txt: md5 = 6519678debbd4467ee0ee846dd74b144 OK
2026/10/10 02:41:48 DEBUG : one-42.txt: md5 = 1a6934f4eeb2452f86bf813eb8099c00 OK
2026/10/10 02:41:50 DEBUG : one-43.txt: md5 = 4277b0253a2f7dd65c85fb4af714aac6 OK
2026/10/10 02:41:51 DEBUG : one-44.txt: md5 = 42f59f94374ef4062f1def63bf21eff3 OK
2026/10/10 02:41:53 DEBUG : one-45.txt: md5 = 9e668c012a5a57586dc5d6f0016ce268 OK
2026/10/10 02:41:54 DEBUG : one-46.txt: md5 = 2a9d3cf92eb547018ffff7d34832b191 OK
2026/10/10 02:41:56 DEBUG : one-47.txt: md5 = 51c7a48ecf47af77ada1ec18dd9005c8 OK
2026/10/10 02:41:57 DEBUG : one-48.txt: md5 = b4c9656cb06869775f66267e0f07cb3e OK
2026/10/10 02:41:59 DEBUG : one-49.txt: md5 = 5b85d020a5cea71bd60879eb543d941a OK
2026/10/10 02:42:00 DEBUG : one-50.txt: md5 = 19f29974b65549a3150e9ddeb8b359f1 OK
2026/10/10 02:42:02 DEBUG : one-51.txt: md5 = f2f9307fbaa74eb6cb1e2c4dcb14d17b OK
2026/10/10 02:42:04 DEBUG : one-52.txt: md5 = fe53f5c647ca9aad95ca7bc311d2a539 OK
2026/10/10 02:42:05 DEBUG : one-53.txt: md5 = 1ae5ff37c1c98bd77be209ef74979965 OK
2026/10/10 02:42:06 DEBUG : one-54.txt: md5 = 62dca4bf1bba1203cb6d92942dd2ec76 OK
2026/10/10 02:42:08 DEBUG : one-55.txt: md5 = da2c31fe8d6ccc9d9b9f89069f1fc235 OK
2026/10/10 02:42:09 DEBUG : one-56.txt: md5 = e892259dadc4f234d77b71ca174092db OK
2026/10/10 02:42:11 DEBUG : one-57.txt: md5 = 759e4d68276fe83463d9e8290888b336 OK
2026/10/10 02:42:13 DEBUG : one-58.txt: md5 = f4a03fedeb31fdca8f410fb39e17e8e8 OK
2026/10/10 02:42:14 DEBUG : one-59.txt: md5 = df8b362d9c025b36754e00d90814ef46 OK
2026/10/10 02:42:15 DEBUG : one-60.txt: md5 = 034b06f8faa6bd332191bc54acc17c48 OK
2026/10/10 02:42:17 DEBUG : one-61.txt: md5 = 2d07cf5538d63d03ed66629708648f31 OK
2026/10/10 02:42:19 DEBUG : one-62.txt: md5 = 57ca321fb8fd9933b3bad876b85c7f9b OK
2026/10/10 02:42:20 DEBUG : one-63.txt: md5 = 6a5e441c4922150c79265d6cec0a9329 OK
2026/10/10 02:42:21 DEBUG : one-64.txt: md5 = b17379edae6bd33246d08bb39fbc8b22 OK
2026/10/10 02:42:23 DEBUG : one-65.txt: md5 = f9921bc54fa94437c5c34b2cb0d7c3ed OK
2026/10/10 02:42:24 DEBUG : one-66.txt: md5 = 8ad2310bf0db2c13110d58fa7901d562 OK
2026/10/10 02:42:26 DEBUG : one-67.txt: md5 = 92795d33f1a14b11fc077efb5247398c OK
2026/10/10 02:42:27 DEBUG : one-68.txt: md5 = 8bddcb0d5d39e6c6fe746239a893f439 OK
2026/10/10 02:42:29 DEBUG : one-69.txt: md5 = 8f53d80ac2ec4b5a7ef9993705d78e5f OK
2026/10/10 02:42:30 DEBUG : one-70.txt: md5 = d9d6018cc4a7818b9d69d26cd72fc3bb OK
2026/10/10 02:42:32 DEBUG : one-71.txt: md5 = 52b4fd551effbdcf4edf348094608a0b OK
2026/10/10 02:42:34 DEBUG : one-72.txt: md5 = 4fac40621d1b8606aacbe4aeb6a8124e OK
2026/10/10 02:42:35 DEBUG : one-73.txt: md5 = c5041fa6534e271ddcd0eb261b0e569d OK
2026/10/10 02:42:37 DEBUG : one-74.txt: md5 = 6742771ba520679e1deb19ff17ffd265 OK
2026/10/10 02:42:38 DEBUG : one-75.txt: md5 = 69140c042b1ba5dabc6ea526157b44af OK
2026/10/10 02:42:40 DEBUG : one-76.txt: md5 = 9299d3d363efd5ae1cf6d0cbf9dc453d OK
2026/10/10 02:42:41 DEBUG : one-77.txt: md5 = 8a8f948408201cca6c7244551346f029 OK
2026/10/10 02:42:43 DEBUG : one-78.txt: md5 = 9045feaaeb7390a65cd02017409257c2 OK
2026/10/10 02:42:44 DEBUG : one-79.txt: md5 = 5ef0c586934f8b7b2d4acd4c83b0a449 OK
2026/10/10 02:42:46 DEBUG : one-80.txt: md5 = e3205f4b7021b062b3ce5ada2fcec65c OK
2026/10/10 02:42:47 DEBUG : one-81.txt: md5 = 15ef62e4a7b6886bbf983d7ce5e8f766 OK
2026/10/10 02:42:49 DEBUG : one-82.txt: md5 = 70d23bc196c50632bb2ed3d8517a3284 OK
2026/10/10 02:42:51 DEBUG : one-83.txt: md5 = 37092642e3f968312f4bfd511a5a5373 OK
2026/10/10 02:42:52 DEBUG : one-84.txt: md5 = dde5d627011494d8a69cd9f1c2ee950d OK
2026/10/10 02:42:54 DEBUG : one-85.txt: md5 = 2fe66206cf13b913d680e1e2a8151ec8 OK
2026/10/10 02:42:55 DEBUG : one-86.txt: md5 = 9df94b1854b194f076aad907636b1422 OK
2026/10/10 02:42:57 DEBUG : one-87.txt: md5 = 6d695e219f4d209b624fcf089cc20d5b OK
2026/10/10 02:42:58 DEBUG : one-88.txt: md5 = 94bc4b4b9095e47fc664d370fc200068 OK
2026/10/10 02:43:00 DEBUG : one-89.txt: md5 = a69eed8e1f76de196f56a071530d3137 OK
2026/10/10 02:43:01 DEBUG : one-90.txt: md5 = 6820007a724a1fc4a9da540000749a20 OK
2026/10/10 02:43:03 DEBUG : one-91.txt: md5 = 953e59f94060c8eb635862312a00dd99 OK
2026/10/10 02:43:04 DEBUG : one-92.txt: md5 = 036ebf9df66699257c2497eed53022df OK
2026/10/10 02:43:06 DEBUG : one-93.txt: md5 = ae90149fd9c48423381e95198c35ba52 OK
2026/10/10 02:43:07 DEBUG : one-94.txt: md5 = 04ed1d18074d5a01f8d522b39c0c24ec OK
2026/10/10 02:43:08 DEBUG : one-95.txt: md5 = 414642a042a351ad57b181d0f0024fac OK
2026/10/10 02:43:10 DEBUG : one-96.txt: md5 = 5098e35457966ca9f5fa8f668a941599 OK
2026/10/10 02:43:12 DEBUG : one-97.txt: md5 = 0528b331798f2d7749be1f061eada7d1 OK
2026/10/10 02:43:13 DEBUG : one-98.txt: md5 = 2498ce90dfb70759b88d3e8c1dbfba05 OK
2026/10/10 02:43:15 DEBUG : one-99.txt: md5 = ad94ccb4ebd09c64f3363492d682dc98 OK
2026/10/10 02:43:16 DEBUG : one-100.txt: md5 = 1bd0db02771c85cef065261d75eb2e4f OK
2026/10/10 02:43:18 DEBUG : one-101.txt: md5 = c345aa0c8fdb3eb3e88d8a32afbafc5d OK
2026/10/10 02:43:19 DEBUG : one-102.txt: md5 = 6f852f8e2c1bc8778a87f82c9fb4f977 OK
2026/10/10 02:43:20 DEBUG : one-103.txt: md5 = 5618611ee386c8f7fc29c946505a6d06 OK
2026/10/10 02:43:22 DEBUG : one-104.txt: md5 = 57b8f75a3bb56617ae45582193d397a9 OK
2026/10/10 02:43:23 DEBUG : one-105.txt: md5 = 208b4435359a31d93a6dc6117cefa340 OK
2026/10/10 02:43:26 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data': Looking for duplicate names using rename mode.
2026/10/10 02:43:27 NOTICE: one.txt: Found 2 files with duplicate names
2026/10/10 02:43:28 INFO  : one-106.txt: renamed from: one.txt
2026/10/10 02:43:28 INFO  : one-107.txt: renamed from: one.txt
--- PASS: TestDeduplicateRenameManyExisting (217.50s)
=== RUN   TestMergeDirs
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:44:23 DEBUG : dupe1/one.txt: md5 = 5eaff58c020f554c823d7ee3e3bae54c OK
2026/10/10 02:44:26 DEBUG : dupe2/two.txt: md5 = 25d95746e0a4bcaf32b18d215660f2da OK
2026/10/10 02:44:28 DEBUG : dupe3/three.txt: md5 = e27ab1adafc77abbe3b45bbf7ca71866 OK
2026/10/10 02:44:29 INFO  : urj8gsducqbtvekeq181ntd2u8: merging "sfrom47189m9qr3mt4qonu281c"
2026/10/10 02:44:29 INFO  : urj8gsducqbtvekeq181ntd2u8: removing empty directory
2026/10/10 02:44:30 INFO  : 1u1r4ei7c628fnjg9blqt3j60o: merging "tr2hj63d80ftlmvm6a952snjcc"
2026/10/10 02:44:30 INFO  : 1u1r4ei7c628fnjg9blqt3j60o: removing empty directory
--- PASS: TestMergeDirs (13.13s)
=== RUN   TestIndex
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:44:36 DEBUG : file1.txt: md5 = 9ca3944a6a698d81c9295b1ccc27820d OK
2026/10/10 02:44:38 DEBUG : sub/file2.txt: md5 = 0588676ff2f25a5757c1d8c2fcab090d OK
2026/10/10 02:44:40 DEBUG : sub/deep/file3.txt: md5 = 6a60738761fd40707ca2073944e11a2a OK
2026/10/10 02:44:43 DEBUG : index.html: md5 = a4f207b3217b14a3217f2bb26758b107 OK
2026/10/10 02:44:43 DEBUG : index.html: size = 12147 OK
2026/10/10 02:44:43 INFO  : index.html: Copied (new)
2026/10/10 02:44:43 DEBUG : caddy.json: md5 = 670f898f160014fc3e45cd29eb5cff93 OK
2026/10/10 02:44:43 DEBUG : caddy.json: size = 259 OK
2026/10/10 02:44:43 INFO  : caddy.json: Copied (new)
2026/10/10 02:44:43 DEBUG : sub/index.html: md5 = 7630da4a65d5c4028b06eeb326684aae OK
2026/10/10 02:44:43 DEBUG : sub/index.html: size = 12306 OK
2026/10/10 02:44:43 INFO  : sub/index.html: Copied (new)
2026/10/10 02:44:43 DEBUG : index.json: md5 = e359a76ad700b57332836da1b10a4a65 OK
2026/10/10 02:44:43 DEBUG : index.json: size = 213 OK
2026/10/10 02:44:43 INFO  : index.json: Copied (new)
2026/10/10 02:44:44 DEBUG : sub/index.json: md5 = 84a9c0104608a1a7a3953ab27b0541a9 OK
2026/10/10 02:44:44 DEBUG : sub/index.json: size = 215 OK
2026/10/10 02:44:44 INFO  : sub/index.json: Copied (new)
2026/10/10 02:44:44 DEBUG : sub/caddy.json: md5 = 09297ee7fa061d060ead5341872b7f33 OK
2026/10/10 02:44:44 DEBUG : sub/caddy.json: size = 261 OK
2026/10/10 02:44:44 INFO  : sub/caddy.json: Copied (new)
2026/10/10 02:44:44 DEBUG : sub/deep/index.html: md5 = a1a00ff8fa43754dc04d093f2219eb7f OK
2026/10/10 02:44:44 DEBUG : sub/deep/index.html: size = 12087 OK
2026/10/10 02:44:44 INFO  : sub/deep/index.html: Copied (new)
2026/10/10 02:44:45 DEBUG : sub/deep/index.json: md5 = 5ee1499af11be442342be74337ec40c4 OK
2026/10/10 02:44:45 DEBUG : sub/deep/index.json: size = 119 OK
2026/10/10 02:44:45 INFO  : sub/deep/index.json: Copied (new)
2026/10/10 02:44:46 DEBUG : sub/deep/caddy.json: md5 = cf828660ad6492b0c94bb55b0670d649 OK
2026/10/10 02:44:46 DEBUG : sub/deep/caddy.json: size = 131 OK
2026/10/10 02:44:46 INFO  : sub/deep/caddy.json: Copied (new)
2026/10/10 02:44:51 DEBUG : sub/index.html: Unchanged skipping
2026/10/10 02:44:51 DEBUG : index.html: Unchanged skipping
2026/10/10 02:44:51 DEBUG : sub/deep/index.html: Unchanged skipping
2026/10/10 02:44:52 DEBUG : index.json: Unchanged skipping
2026/10/10 02:44:52 DEBUG : sub/index.json: Unchanged skipping
2026/10/10 02:44:52 DEBUG : sub/deep/index.json: Unchanged skipping
2026/10/10 02:44:53 DEBUG : caddy.json: Unchanged skipping
2026/10/10 02:44:53 DEBUG : sub/caddy.json: Unchanged skipping
2026/10/10 02:44:53 DEBUG : sub/deep/caddy.json: Unchanged skipping
2026/10/10 02:44:55 DEBUG : index.json: md5 = 24f3fe869017053b23ecae231e434700 OK
2026/10/10 02:44:55 DEBUG : index.json: size = 213 OK
2026/10/10 02:44:55 INFO  : index.json: Copied (replaced existing)
2026/10/10 02:44:55 DEBUG : sub/deep/index.html: md5 = 1800e4d2e5c02a09409152adfe702f34 OK
2026/10/10 02:44:55 DEBUG : sub/deep/index.html: size = 12087 OK
2026/10/10 02:44:55 INFO  : sub/deep/index.html: Copied (replaced existing)
2026/10/10 02:44:55 DEBUG : caddy.json: md5 = 164dbf69e08832798038195add884ea7 OK
2026/10/10 02:44:55 DEBUG : caddy.json: size = 259 OK
2026/10/10 02:44:55 INFO  : caddy.json: Copied (replaced existing)
2026/10/10 02:44:55 DEBUG : index.html: md5 = 210d1e83344e21ebac1a398cb73587dd OK
2026/10/10 02:44:55 DEBUG : index.html: size = 12147 OK
2026/10/10 02:44:55 INFO  : index.html: Copied (replaced existing)
2026/10/10 02:44:56 DEBUG : sub/deep/index.json: md5 = f9be2d5f29d9ba23b186df01907df5f5 OK
2026/10/10 02:44:56 DEBUG : sub/deep/index.json: size = 119 OK
2026/10/10 02:44:56 INFO  : sub/deep/index.json: Copied (replaced existing)
2026/10/10 02:44:56 DEBUG : sub/index.html: md5 = 8694c52e6beef8f96fbe1375a0747fbe OK
2026/10/10 02:44:56 DEBUG : sub/index.html: size = 12306 OK
2026/10/10 02:44:56 INFO  : sub/index.html: Copied (replaced existing)
2026/10/10 02:44:57 DEBUG : sub/deep/caddy.json: md5 = 8d7545ee5f4568a89605b55228d9776c OK
2026/10/10 02:44:57 DEBUG : sub/deep/caddy.json: size = 131 OK
2026/10/10 02:44:57 INFO  : sub/deep/caddy.json: Copied (replaced existing)
2026/10/10 02:44:57 DEBUG : sub/index.json: md5 = 935c0a0822cbeeeb53321552baba6326 OK
2026/10/10 02:44:57 DEBUG : sub/index.json: size = 215 OK
2026/10/10 02:44:57 INFO  : sub/index.json: Copied (replaced existing)
2026/10/10 02:44:58 DEBUG : sub/caddy.json: md5 = 1326de7885a559ab0e35cf0d4c91a1b7 OK
2026/10/10 02:44:58 DEBUG : sub/caddy.json: size = 261 OK
2026/10/10 02:44:58 INFO  : sub/caddy.json: Copied (replaced existing)
2026/10/10 02:44:59 DEBUG : sub/deep/file4.txt: md5 = d42ece032462229e62748e1c8affea37 OK
2026/10/10 02:45:00 DEBUG : sub/index.html: Unchanged skipping
2026/10/10 02:45:00 DEBUG : index.html: Unchanged skipping
2026/10/10 02:45:01 DEBUG : sub/deep/index.json: md5 = 7583fdad524bb874f95d61f81ef8a7f0 OK
2026/10/10 02:45:01 DEBUG : sub/deep/index.json: size = 235 OK
2026/10/10 02:45:01 INFO  : sub/deep/index.json: Copied (replaced existing)
2026/10/10 02:45:01 DEBUG : sub/deep/caddy.json: md5 = 2d4cb20e48646f6fe054e8e47944be38 OK
2026/10/10 02:45:01 DEBUG : sub/deep/caddy.json: size = 260 OK
2026/10/10 02:45:01 INFO  : sub/deep/caddy.json: Copied (replaced existing)
2026/10/10 02:45:01 DEBUG : index.json: Unchanged skipping
2026/10/10 02:45:01 DEBUG : sub/index.json: Unchanged skipping
2026/10/10 02:45:01 DEBUG : sub/deep/index.html: md5 = 51712251369bf37b0da3c6e24a7c1120 OK
2026/10/10 02:45:01 DEBUG : sub/deep/index.html: size = 12346 OK
2026/10/10 02:45:01 INFO  : sub/deep/index.html: Copied (replaced existing)
2026/10/10 02:45:02 DEBUG : caddy.json: Unchanged skipping
2026/10/10 02:45:02 DEBUG : sub/caddy.json: Unchanged skipping
2026/10/10 02:45:05 DEBUG : sub/deep/file5.txt: md5 = bef9f3d591174ec6b9d43369ca39f5d0 OK
2026/10/10 02:45:05 NOTICE: sub/deep/index.html: Skipped copy as --dry-run is set (size 12.309Ki)
2026/10/10 02:45:05 NOTICE: sub/deep/caddy.json: Skipped copy as --dry-run is set (size 389)
2026/10/10 02:45:05 NOTICE: sub/deep/index.json: Skipped copy as --dry-run is set (size 351)
2026/10/10 02:45:06 DEBUG : sub/index.html: Unchanged skipping
2026/10/10 02:45:06 DEBUG : index.html: Unchanged skipping
2026/10/10 02:45:07 DEBUG : index.json: Unchanged skipping
2026/10/10 02:45:07 DEBUG : sub/index.json: Unchanged skipping
2026/10/10 02:45:07 DEBUG : caddy.json: Unchanged skipping
2026/10/10 02:45:07 DEBUG : sub/caddy.json: Unchanged skipping
--- PASS: TestIndex (43.05s)
=== RUN   TestIndexDelete
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:45:19 DEBUG : file1.txt: md5 = 1d4b0e211d8c7a7ce3af6fd46c7e4ac9 OK
2026/10/10 02:45:21 DEBUG : sub/file2.txt: md5 = 11408ea4eb0353d444f5c934c9213fad OK
2026/10/10 02:45:23 DEBUG : sub/deep/file3.txt: md5 = 401b9402381c28297c98b03e4222f8fd OK
2026/10/10 02:45:25 DEBUG : sub/deep/index.html: md5 = 8ba2025a0fff75773a1e9f371c620ec0 OK
2026/10/10 02:45:25 DEBUG : sub/deep/index.html: size = 12087 OK
2026/10/10 02:45:25 INFO  : sub/deep/index.html: Copied (new)
2026/10/10 02:45:26 DEBUG : index.html: md5 = b7d49ec4d5e94d68aa858c196c2b1018 OK
2026/10/10 02:45:26 DEBUG : index.html: size = 12147 OK
2026/10/10 02:45:26 INFO  : index.html: Copied (new)
2026/10/10 02:45:26 DEBUG : sub/index.html: md5 = 18620b6118145d204673e2ea6d1c241c OK
2026/10/10 02:45:26 DEBUG : sub/index.html: size = 12306 OK
2026/10/10 02:45:26 INFO  : sub/index.html: Copied (new)
2026/10/10 02:45:28 DEBUG : index.html: Unchanged skipping
2026/10/10 02:45:28 DEBUG : sub/index.html: Unchanged skipping
2026/10/10 02:45:28 DEBUG : sub/deep/index.html: md5 = 3e9598a103398c3effd4ab56a78a2f13 OK
2026/10/10 02:45:28 DEBUG : sub/deep/index.html: size = 11829 OK
2026/10/10 02:45:28 INFO  : sub/deep/index.html: Copied (replaced existing)
--- PASS: TestIndexDelete (18.64s)
=== RUN   TestIndexFilter
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:45:38 DEBUG : file1.txt: md5 = 82266f79a090d62ba0d9c7fc9fb79a3f OK
2026/10/10 02:45:40 DEBUG : sub/file2.txt: md5 = 805f30a14faae30ece970cd10ba0a3a5 OK
2026/10/10 02:45:42 DEBUG : sub/deep/file3.txt: md5 = 10f7e283aea066263eea87cb778abeac OK
2026/10/10 02:45:44 DEBUG : sub/deep/index.html: md5 = 392fa77c6f34bd8a87cba02e18589ea5 OK
2026/10/10 02:45:46 DEBUG : other/notes.md: md5 = bd5306154dee1674384dd3bb6b9ad466 OK
2026/10/10 02:45:47 DEBUG : other/notes.md: Excluded (Path Filter)
2026/10/10 02:45:48 DEBUG : other/index.html: md5 = 18067178d8358b8e98f49c2319c0875a OK
2026/10/10 02:45:48 DEBUG : other/index.html: size = 11802 OK
2026/10/10 02:45:48 INFO  : other/index.html: Copied (new)
2026/10/10 02:45:49 DEBUG : sub/index.html: md5 = a714632375f174ec812f983556f11e84 OK
2026/10/10 02:45:49 DEBUG : sub/index.html: size = 12056 OK
2026/10/10 02:45:49 INFO  : sub/index.html: Copied (new)
2026/10/10 02:45:49 DEBUG : index.html: md5 = f63af957169707b474bf3614499b4cbe OK
2026/10/10 02:45:49 DEBUG : index.html: size = 12399 OK
2026/10/10 02:45:49 INFO  : index.html: Copied (new)
2026/10/10 02:45:54 DEBUG : other/notes.md: Excluded (Path Filter)
2026/10/10 02:45:54 DEBUG : sub/index.html: Unchanged skipping
2026/10/10 02:45:55 DEBUG : index.html: Unchanged skipping
2026/10/10 02:45:55 DEBUG : other/index.html: Unchanged skipping
--- PASS: TestIndexFilter (25.41s)
=== RUN   TestIndexRules
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:46:03 DEBUG : file1.txt: md5 = a7495dd60e7e9cce0788d9b60e6f6ae8 OK
2026/10/10 02:46:05 DEBUG : sub/file2.txt: md5 = 4725b173863110758de586e27255aca6 OK
2026/10/10 02:46:08 DEBUG : sub/deep/file3.txt: md5 = d5e369ca3b7a60af45346297557d9ed7 OK
2026/10/10 02:46:09 DEBUG : sub/deep/index.html: md5 = a8d606ef48d40713f525e41c9fb369fe OK
2026/10/10 02:46:12 DEBUG : sub/index.html: md5 = 6da79086181bb082a1fa06db94ad804c OK
2026/10/10 02:46:12 DEBUG : sub/index.html: size = 12306 OK
2026/10/10 02:46:12 INFO  : sub/index.html: Copied (new)
2026/10/10 02:46:12 DEBUG : index.html: md5 = 8ca90e478a788b3b8fc52dc9d9a0e66c OK
2026/10/10 02:46:12 DEBUG : index.html: size = 12147 OK
2026/10/10 02:46:12 INFO  : index.html: Copied (new)
2026/10/10 02:46:16 DEBUG : sub/index.html: Unchanged skipping
2026/10/10 02:46:17 DEBUG : sub/deep/index.html: md5 = d5234769f150408b27f90d913cf95182 OK
2026/10/10 02:46:17 DEBUG : sub/deep/index.html: size = 12087 OK
2026/10/10 02:46:17 INFO  : sub/deep/index.html: Copied (replaced existing)
--- PASS: TestIndexRules (23.47s)
=== RUN   TestIndexMaxDepth
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:46:27 DEBUG : file1.txt: md5 = 57f11f96aec19b89d30dbf4057bd4f3b OK
2026/10/10 02:46:29 DEBUG : sub/file2.txt: md5 = bbb07ea4e346fbc3b9a2654a687240d0 OK
2026/10/10 02:46:31 DEBUG : sub/deep/file3.txt: md5 = df69538aa7a1d6906fab936929cd650c OK
2026/10/10 02:46:34 DEBUG : index.html: md5 = c468b7ae8e03aaf7a9b4b80231964c17 OK
2026/10/10 02:46:34 DEBUG : index.html: size = 12147 OK
2026/10/10 02:46:34 INFO  : index.html: Copied (new)
--- PASS: TestIndexMaxDepth (14.89s)
=== RUN   TestIndexLinkIndex
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:46:42 DEBUG : file1.txt: md5 = baa6ff883fdbdd21622dafc33f66949b OK
2026/10/10 02:46:45 DEBUG : sub/file2.txt: md5 = da004c61e84d56afac9b5648e1a718b9 OK
2026/10/10 02:46:47 DEBUG : sub/deep/file3.txt: md5 = c0e6b682bdc84c9894b57f1ed1f52616 OK
2026/10/10 02:46:49 DEBUG : sub/deep/index.html: md5 = 84244bb2554b7f8947ad7166e5dd936d OK
2026/10/10 02:46:49 DEBUG : sub/deep/index.html: size = 12128 OK
2026/10/10 02:46:49 INFO  : sub/deep/index.html: Copied (new)
2026/10/10 02:46:49 DEBUG : index.html: md5 = 5498bdcf309e50ac1631eee352e6f9f8 OK
2026/10/10 02:46:49 DEBUG : index.html: size = 12167 OK
2026/10/10 02:46:49 INFO  : index.html: Copied (new)
2026/10/10 02:46:50 DEBUG : sub/index.html: md5 = 03371df9c68d01cb9b21238284e78679 OK
2026/10/10 02:46:50 DEBUG : sub/index.html: size = 12347 OK
2026/10/10 02:46:50 INFO  : sub/index.html: Copied (new)
--- PASS: TestIndexLinkIndex (16.78s)
=== RUN   TestIndexDirTime
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:46:58 DEBUG : file1.txt: md5 = 4bbfba363a78a42c91e53406e83bc5d6 OK
2026/10/10 02:47:00 DEBUG : sub/file2.txt: md5 = ef5ecb1a0dcc739535e62403dbe89ba0 OK
2026/10/10 02:47:03 DEBUG : sub/deep/file3.txt: md5 = 1d0d5e69b5f96a78958aa72e0e31ce16 OK
2026/10/10 02:47:05 DEBUG : index.json: md5 = 54d158ad703c659d7c3ba7bedb337f6f OK
2026/10/10 02:47:05 DEBUG : index.json: size = 257 OK
2026/10/10 02:47:05 INFO  : index.json: Copied (new)
2026/10/10 02:47:05 DEBUG : sub/index.json: md5 = 46bec8db6a396e8aee8afa807bebfbda OK
2026/10/10 02:47:05 DEBUG : sub/index.json: size = 259 OK
2026/10/10 02:47:05 INFO  : sub/index.json: Copied (new)
2026/10/10 02:47:05 DEBUG : sub/deep/index.json: md5 = 80b67c46ef61c56e12a77de750ef75ce OK
2026/10/10 02:47:05 DEBUG : sub/deep/index.json: size = 139 OK
2026/10/10 02:47:05 INFO  : sub/deep/index.json: Copied (new)
2026/10/10 02:47:08 DEBUG : sub/deep/index.json: Unchanged skipping
2026/10/10 02:47:09 DEBUG : sub/index.json: md5 = 2c367d161058ba8b6f6dbdcaa1fe1777 OK
2026/10/10 02:47:09 DEBUG : sub/index.json: size = 239 OK
2026/10/10 02:47:09 INFO  : sub/index.json: Copied (replaced existing)
2026/10/10 02:47:09 DEBUG : index.json: md5 = efcb0739d4e9760a74a005832fe2587c OK
2026/10/10 02:47:09 DEBUG : index.json: size = 237 OK
2026/10/10 02:47:09 INFO  : index.json: Copied (replaced existing)
2026/10/10 02:47:12 DEBUG : index.json: md5 = f64ead325a0914d4bb01f35c8f66e35a OK
2026/10/10 02:47:12 DEBUG : index.json: size = 213 OK
2026/10/10 02:47:12 INFO  : index.json: Copied (replaced existing)
2026/10/10 02:47:12 DEBUG : sub/index.json: md5 = 0254ed0aac857e6b06b0511c94f4075a OK
2026/10/10 02:47:12 DEBUG : sub/index.json: size = 215 OK
2026/10/10 02:47:12 INFO  : sub/index.json: Copied (replaced existing)
2026/10/10 02:47:12 DEBUG : sub/deep/index.json: md5 = 9bd386f823f21bd5d2d90677ce90d6e8 OK
2026/10/10 02:47:12 DEBUG : sub/deep/index.json: size = 119 OK
2026/10/10 02:47:12 INFO  : sub/deep/index.json: Copied (replaced existing)
--- PASS: TestIndexDirTime (21.67s)
=== RUN   TestIndexTemplate
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:47:20 DEBUG : file1.txt: md5 = 5619084e95423931e6ae5e27492648c0 OK
2026/10/10 02:47:22 DEBUG : sub/file2.txt: md5 = ab6d39909378049fa31e446e88ec8909 OK
2026/10/10 02:47:25 DEBUG : sub/deep/file3.txt: md5 = ae4820caf527ad1229d42f5a301a97c2 OK
2026/10/10 02:47:27 DEBUG : sub/deep/list.txt: md5 = accb600af7c11a019d788f9a4557f0f3 OK
2026/10/10 02:47:27 DEBUG : sub/deep/list.txt: size = 47 OK
2026/10/10 02:47:27 INFO  : sub/deep/list.txt: Copied (new)
2026/10/10 02:47:27 DEBUG : list.txt: md5 = ca6438697ca16f0d148b062d061d6a7b OK
2026/10/10 02:47:27 DEBUG : list.txt: size = 59 OK
2026/10/10 02:47:27 INFO  : list.txt: Copied (new)
2026/10/10 02:47:27 DEBUG : sub/list.txt: md5 = a8c236a6c6296ef1965a78404d4f66ea OK
2026/10/10 02:47:27 DEBUG : sub/list.txt: size = 64 OK
2026/10/10 02:47:27 INFO  : sub/list.txt: Copied (new)
--- PASS: TestIndexTemplate (14.96s)
=== RUN   TestIndexTemplateBuiltin
--- PASS: TestIndexTemplateBuiltin (0.00s)
=== RUN   TestRcIndex
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcIndex (0.00s)
=== RUN   TestIndexNoHash
    index_test.go:385: Skipping test on non local remote
--- SKIP: TestIndexNoHash (0.00s)
=== RUN   TestIndexChanged
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:47:35 DEBUG : file1.txt: md5 = 825f162c4c0b8ce6d19a597526a5f97b OK
2026/10/10 02:47:37 DEBUG : sub/file2.txt: md5 = b1f1b5003bc8d7ace9d648f44ef54ec9 OK
2026/10/10 02:47:39 DEBUG : sub/deep/file3.txt: md5 = 7997f6de46b59a26039fa4935cf1b23f OK
2026/10/10 02:47:41 DEBUG : other/notes.md: md5 = 9f4162e88597b0db2039d335d5202b11 OK
2026/10/10 02:47:44 DEBUG : other/index.html: md5 = cbc8a1a01f003cbf683fdc62966281a5 OK
2026/10/10 02:47:44 DEBUG : other/index.html: size = 12058 OK
2026/10/10 02:47:44 INFO  : other/index.html: Copied (new)
2026/10/10 02:47:44 DEBUG : sub/deep/index.html: md5 = bc90855af7586bc64dc28185dd683d65 OK
2026/10/10 02:47:44 DEBUG : sub/deep/index.html: size = 12087 OK
2026/10/10 02:47:44 INFO  : sub/deep/index.html: Copied (new)
2026/10/10 02:47:44 DEBUG : sub/index.html: md5 = d431f46e36eb1b6887e3e942e5b8287d OK
2026/10/10 02:47:44 DEBUG : sub/index.html: size = 12306 OK
2026/10/10 02:47:44 INFO  : sub/index.html: Copied (new)
2026/10/10 02:47:44 DEBUG : index.html: md5 = d855b3fa42fdb131fd3ad3c4a0380bdb OK
2026/10/10 02:47:44 DEBUG : index.html: size = 12399 OK
2026/10/10 02:47:44 INFO  : index.html: Copied (new)
2026/10/10 02:47:46 DEBUG : sub/deep/file4.txt: md5 = e97c3cd12c2e1f7f1cdca8487172f366 OK
2026/10/10 02:47:47 DEBUG : other/undeclared.txt: md5 = aa7d9842259d044da557064fab4de6c1 OK
2026/10/10 02:47:47 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data': Partial index: listing 3 directories and walking 0 changed directories
2026/10/10 02:47:49 DEBUG : index.html: Unchanged skipping
2026/10/10 02:47:49 DEBUG : sub/index.html: Unchanged skipping
2026/10/10 02:47:49 DEBUG : sub/deep/index.html: md5 = 55c185aadfa56fdbfec3862a77873905 OK
2026/10/10 02:47:49 DEBUG : sub/deep/index.html: size = 12346 OK
2026/10/10 02:47:49 INFO  : sub/deep/index.html: Copied (replaced existing)
2026/10/10 02:47:53 DEBUG : sub/new/file5.txt: md5 = 2b97e323ab32f30b12c2a669a747fbd5 OK
2026/10/10 02:47:53 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data': Partial index: listing 3 directories and walking 0 changed directories
2026/10/10 02:47:55 DEBUG : index.html: Unchanged skipping
2026/10/10 02:47:56 DEBUG : sub/index.html: md5 = 46089962621405b52ff49b3ad68a60b2 OK
2026/10/10 02:47:56 DEBUG : sub/index.html: size = 12554 OK
2026/10/10 02:47:56 INFO  : sub/index.html: Copied (replaced existing)
2026/10/10 02:47:56 DEBUG : sub/new/index.html: md5 = 5821eca448246644f753590d71784164 OK
2026/10/10 02:47:56 DEBUG : sub/new/index.html: size = 12085 OK
2026/10/10 02:47:56 INFO  : sub/new/index.html: Copied (new)
2026/10/10 02:47:59 DEBUG : other/undeclared0.txt: md5 = 71e94cc26cceafad11d0ae074918502c OK
2026/10/10 02:47:59 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data': Partial index: listing 1 directories and walking 1 changed directories
2026/10/10 02:48:00 DEBUG : index.html: Unchanged skipping
2026/10/10 02:48:01 DEBUG : other/index.html: md5 = 494052005dbf786d004d5f4ce53ccf5d OK
2026/10/10 02:48:01 DEBUG : other/index.html: size = 12597 OK
2026/10/10 02:48:01 INFO  : other/index.html: Copied (replaced existing)
2026/10/10 02:48:03 DEBUG : other/undeclared1.txt: md5 = 56441dceda3820c68bf984f8241db640 OK
2026/10/10 02:48:03 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data': Partial index: listing 1 directories and walking 0 changed directories
2026/10/10 02:48:04 DEBUG : index.html: Unchanged skipping
2026/10/10 02:48:05 DEBUG : other/index.html: md5 = 31a0659bdc9bdcb226778ebf6b77a0aa OK
2026/10/10 02:48:05 DEBUG : other/index.html: size = 12867 OK
2026/10/10 02:48:05 INFO  : other/index.html: Copied (replaced existing)
2026/10/10 02:48:08 DEBUG : sub/file6.txt: md5 = ab6d93dc2f0681f729459571b58d035e OK
2026/10/10 02:48:08 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data': Nothing changed so nothing to index
2026/10/10 02:48:09 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data': Root changed so doing a full index
2026/10/10 02:48:10 DEBUG : other/index.html: Unchanged skipping
2026/10/10 02:48:10 DEBUG : index.html: Unchanged skipping
2026/10/10 02:48:10 DEBUG : sub/deep/index.html: Unchanged skipping
2026/10/10 02:48:10 DEBUG : sub/new/index.html: Unchanged skipping
2026/10/10 02:48:11 DEBUG : sub/index.html: md5 = 71c4ba10ff86d16758bca55d0d82deaf OK
2026/10/10 02:48:11 DEBUG : sub/index.html: size = 12813 OK
2026/10/10 02:48:11 INFO  : sub/index.html: Copied (replaced existing)
2026/10/10 02:48:13 DEBUG : sub/file7.txt: md5 = 14a226fccde4ea44c69a95c45e81a49f OK
2026/10/10 02:48:13 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data': Partial index would list 2 directories, more than 1, so doing a full index
2026/10/10 02:48:15 DEBUG : sub/deep/index.html: Unchanged skipping
2026/10/10 02:48:15 DEBUG : index.html: Unchanged skipping
2026/10/10 02:48:15 DEBUG : other/index.html: Unchanged skipping
2026/10/10 02:48:15 DEBUG : sub/new/index.html: Unchanged skipping
2026/10/10 02:48:16 DEBUG : sub/index.html: md5 = 81e6b7e19d464988bb206f2d5f25f969 OK
2026/10/10 02:48:16 DEBUG : sub/index.html: size = 13071 OK
2026/10/10 02:48:16 INFO  : sub/index.html: Copied (replaced existing)
2026/10/10 02:48:18 DEBUG : sub/deep/file8.txt: md5 = 43ce95d536e877b782f302aea97175d9 OK
2026/10/10 02:48:18 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data': Partial index: listing 1 directories and walking 1 changed directories
2026/10/10 02:48:19 DEBUG : sub/new/index.html: Unchanged skipping
2026/10/10 02:48:19 DEBUG : sub/index.html: Unchanged skipping
2026/10/10 02:48:19 DEBUG : index.html: Unchanged skipping
2026/10/10 02:48:20 DEBUG : sub/deep/index.html: md5 = 92463d04f6bc17ecf950e931c19f7879 OK
2026/10/10 02:48:20 DEBUG : sub/deep/index.html: size = 12604 OK
2026/10/10 02:48:20 INFO  : sub/deep/index.html: Copied (replaced existing)
2026/10/10 02:48:23 DEBUG : sub/deep/file9.txt: md5 = 2ef801423010459346f8ba5e20300833 OK
2026/10/10 02:48:24 DEBUG : sub/file10.txt: md5 = d815227f0206b4ce15c322e78b6263e9 OK
2026/10/10 02:48:24 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data': Partial index: listing 2 directories and walking 0 changed directories
2026/10/10 02:48:25 DEBUG : index.html: Unchanged skipping
2026/10/10 02:48:26 DEBUG : sub/index.html: md5 = 996045b865553794bcee9d7a4187b055 OK
2026/10/10 02:48:26 DEBUG : sub/index.html: size = 13331 OK
2026/10/10 02:48:26 INFO  : sub/index.html: Copied (replaced existing)
--- PASS: TestIndexChanged (68.98s)
=== RUN   TestIndexChangedDelete
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:48:44 DEBUG : file1.txt: md5 = 53a211acb172ddff3f0bf8d0d6b92c9c OK
2026/10/10 02:48:46 DEBUG : sub/file2.txt: md5 = 0e76fc053ac9c84400b3215079bbd386 OK
2026/10/10 02:48:49 DEBUG : sub/deep/file3.txt: md5 = d03fe9e747418383b97cc46a78c2d102 OK
2026/10/10 02:48:51 DEBUG : sub/deep/index.html: md5 = 4cde9ea35d57bdc86f0f1a667f97560d OK
2026/10/10 02:48:51 DEBUG : sub/deep/index.html: size = 12087 OK
2026/10/10 02:48:51 INFO  : sub/deep/index.html: Copied (new)
2026/10/10 02:48:51 DEBUG : sub/index.html: md5 = e96e9ed6f2ca4d98815f7d94008fe549 OK
2026/10/10 02:48:51 DEBUG : sub/index.html: size = 12306 OK
2026/10/10 02:48:51 INFO  : sub/index.html: Copied (new)
2026/10/10 02:48:51 DEBUG : index.html: md5 = a44f92c18e3989237c0aa4ef26db2adf OK
2026/10/10 02:48:51 DEBUG : index.html: size = 12147 OK
2026/10/10 02:48:51 INFO  : index.html: Copied (new)
2026/10/10 02:48:52 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data': Partial index: listing 3 directories and walking 0 changed directories
2026/10/10 02:48:53 DEBUG : sub/index.html: Unchanged skipping
2026/10/10 02:48:53 DEBUG : index.html: Unchanged skipping
2026/10/10 02:48:54 DEBUG : sub/deep/index.html: md5 = a84f287432ef5aaa2de1c3c10f2f8fe8 OK
2026/10/10 02:48:54 DEBUG : sub/deep/index.html: size = 11829 OK
2026/10/10 02:48:54 INFO  : sub/deep/index.html: Copied (replaced existing)
--- PASS: TestIndexChangedDelete (17.18s)
=== RUN   TestIndexChangedFiles
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:49:01 DEBUG : file1.txt: md5 = 2c4029df1135311dd35d8f1b3af0b623 OK
2026/10/10 02:49:03 DEBUG : sub/file2.txt: md5 = 01c9cb4923c8b58f9987bf62e5f8290e OK
2026/10/10 02:49:05 DEBUG : sub/deep/file3.txt: md5 = c83e7ae107b07281226008c04ab3c2eb OK
2026/10/10 02:49:07 DEBUG : - odd/file.txt: md5 = d1e2430633891910f96672b674f2ebe3 OK
2026/10/10 02:49:09 DEBUG : sub/deep/index.html: md5 = 9ae45bef979cad2388ec2e68a9b359de OK
2026/10/10 02:49:09 DEBUG : sub/deep/index.html: size = 12087 OK
2026/10/10 02:49:09 INFO  : sub/deep/index.html: Copied (new)
2026/10/10 02:49:09 DEBUG : - odd/index.html: md5 = c68eea1293e90c593b613c2ed8d2229b OK
2026/10/10 02:49:09 DEBUG : - odd/index.html: size = 12058 OK
2026/10/10 02:49:09 INFO  : - odd/index.html: Copied (new)
2026/10/10 02:49:09 DEBUG : index.html: md5 = 961625b72c1cdf842731139c179e54d9 OK
2026/10/10 02:49:09 DEBUG : index.html: size = 12401 OK
2026/10/10 02:49:09 INFO  : index.html: Copied (new)
2026/10/10 02:49:10 DEBUG : sub/index.html: md5 = 8aa9460d298ac32662a96b174adfffc2 OK
2026/10/10 02:49:10 DEBUG : sub/index.html: size = 12306 OK
2026/10/10 02:49:10 INFO  : sub/index.html: Copied (new)
2026/10/10 02:49:11 DEBUG : - odd/file8.txt: md5 = 777942e42bbafdbe2a97dcf62f189103 OK
2026/10/10 02:49:12 DEBUG : sub/file9.txt: md5 = a35b84a62f88a56ed62c5e84f8e0f981 OK
2026/10/10 02:49:12 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data': Partial index: listing 2 directories and walking 0 changed directories
2026/10/10 02:49:14 DEBUG : index.html: Unchanged skipping
2026/10/10 02:49:14 DEBUG : - odd/index.html: md5 = a8d7d28edecbcbdccf1e41fc644ecc27 OK
2026/10/10 02:49:14 DEBUG : - odd/index.html: size = 12316 OK
2026/10/10 02:49:14 INFO  : - odd/index.html: Copied (replaced existing)
2026/10/10 02:49:16 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data': Partial index: listing 4 directories and walking 0 changed directories
2026/10/10 02:49:17 DEBUG : index.html: Unchanged skipping
2026/10/10 02:49:17 DEBUG : sub/deep/index.html: Unchanged skipping
2026/10/10 02:49:17 DEBUG : - odd/index.html: Unchanged skipping
2026/10/10 02:49:18 DEBUG : sub/index.html: md5 = 506815a88e44982dbe6f66f3fdd95dbd OK
2026/10/10 02:49:18 DEBUG : sub/index.html: size = 12565 OK
2026/10/10 02:49:18 INFO  : sub/index.html: Copied (replaced existing)
--- PASS: TestIndexChangedFiles (27.71s)
=== RUN   TestIndexChangedDirTime
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:49:28 DEBUG : file1.txt: md5 = 05f0de16520675b959d4dc2ad87a05fd OK
2026/10/10 02:49:31 DEBUG : sub/file2.txt: md5 = 68cb7ec5def5fc58f1dc39454096be1e OK
2026/10/10 02:49:33 DEBUG : sub/deep/file3.txt: md5 = 6ebd151b96c787be49abc92e05570b6d OK
2026/10/10 02:49:35 DEBUG : other/notes.md: md5 = f024b8a41a483ba200aca9680eaa684f OK
2026/10/10 02:49:37 DEBUG : sub/index.json: md5 = dbce1490073cb7b0c44fbaa6c46ca50f OK
2026/10/10 02:49:37 DEBUG : sub/index.json: size = 259 OK
2026/10/10 02:49:37 INFO  : sub/index.json: Copied (new)
2026/10/10 02:49:37 DEBUG : index.json: md5 = 87974da54d62126418371b2e11f3ec63 OK
2026/10/10 02:49:37 DEBUG : index.json: size = 379 OK
2026/10/10 02:49:37 INFO  : index.json: Copied (new)
2026/10/10 02:49:37 DEBUG : sub/deep/index.json: md5 = 6dbe32fc80ed6a0456593e9737ff36f6 OK
2026/10/10 02:49:37 DEBUG : sub/deep/index.json: size = 139 OK
2026/10/10 02:49:37 INFO  : sub/deep/index.json: Copied (new)
2026/10/10 02:49:37 DEBUG : other/index.json: md5 = 78813e60ee054514311d0d2aebd1e8b5 OK
2026/10/10 02:49:37 DEBUG : other/index.json: size = 144 OK
2026/10/10 02:49:37 INFO  : other/index.json: Copied (new)
2026/10/10 02:49:39 DEBUG : sub/deep/file4.txt: md5 = 063e0c2869e3081cd0be441a71ebfc9a OK
2026/10/10 02:49:39 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data': Partial index: listing 3 directories and walking 0 changed directories
2026/10/10 02:49:42 DEBUG : sub/index.json: md5 = a59c6e4df0d21e3cddf2a7fac5a27552 OK
2026/10/10 02:49:42 DEBUG : sub/index.json: size = 259 OK
2026/10/10 02:49:42 INFO  : sub/index.json: Copied (replaced existing)
2026/10/10 02:49:42 DEBUG : sub/deep/index.json: md5 = 5f3621358a55328e42aca1a5ce01e8e5 OK
2026/10/10 02:49:42 DEBUG : sub/deep/index.json: size = 275 OK
2026/10/10 02:49:42 INFO  : sub/deep/index.json: Copied (replaced existing)
2026/10/10 02:49:42 DEBUG : index.json: md5 = 3c2cbcaa1f2d1c38a8c22e9675b913bc OK
2026/10/10 02:49:42 DEBUG : index.json: size = 379 OK
2026/10/10 02:49:42 INFO  : index.json: Copied (replaced existing)
--- PASS: TestIndexChangedDirTime (22.79s)
=== RUN   TestListDirSorted
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:49:51 DEBUG : a.txt: md5 = 816694ab24d58af367fa21ea2aff0624 OK
2026/10/10 02:49:53 DEBUG : zend.txt: md5 = c26373c72bbf3366da0da6d1f42d59c9 OK
2026/10/10 02:49:55 DEBUG : sub dir/hello world: md5 = 7a0756fc429edbd75b1132d5090815de OK
2026/10/10 02:49:57 DEBUG : sub dir/hello world2: md5 = 2a7d1efda2e0371c5481841a8b1d33b5 OK
2026/10/10 02:49:59 DEBUG : sub dir/ignore dir/.ignore: md5 = f671021a90f3d3c7c573c07c1e40c12f OK
2026/10/10 02:50:01 DEBUG : sub dir/ignore dir/should be ignored: md5 = cbe513d21ac2897cabcf069d8bf844b0 OK
2026/10/10 02:50:03 DEBUG : sub dir/sub sub dir/hello world3: md5 = 0a4f02fb259a0bafa14ad36490afde69 OK
2026/10/10 02:50:04 DEBUG : a.txt: Excluded (Size Filter)
2026/10/10 02:50:04 DEBUG : a.txt: Excluded
2026/10/10 02:50:05 DEBUG : sub dir/hello world: Excluded (Size Filter)
2026/10/10 02:50:05 DEBUG : sub dir/hello world: Excluded
2026/10/10 02:50:05 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2026/10/10 02:50:05 DEBUG : sub dir/hello world2: Excluded
2026/10/10 02:50:06 DEBUG : sub dir/ignore dir: Excluded
2026/10/10 02:50:06 DEBUG : sub dir/hello world: Excluded (Size Filter)
2026/10/10 02:50:06 DEBUG : sub dir/hello world: Excluded
2026/10/10 02:50:06 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2026/10/10 02:50:06 DEBUG : sub dir/hello world2: Excluded
2026/10/10 02:50:06 DEBUG : sub dir/ignore dir: Excluded
--- PASS: TestListDirSorted (22.95s)
=== RUN   TestListDirSortedFn
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:50:14 DEBUG : a.txt: md5 = 1636f4373accc1a1cb236facc623fff9 OK
2026/10/10 02:50:16 DEBUG : zend.txt: md5 = 305c19a99bbf0da57f0c980861ed11bf OK
2026/10/10 02:50:18 DEBUG : sub dir/hello world: md5 = b225046d1455e7bb0a2a26fe3e8568dc OK
2026/10/10 02:50:20 DEBUG : sub dir/hello world2: md5 = c8e0e2446124546f400eb928416bc5c1 OK
2026/10/10 02:50:22 DEBUG : sub dir/ignore dir/.ignore: md5 = 4d8a593048c364971d680819c02be185 OK
2026/10/10 02:50:24 DEBUG : sub dir/ignore dir/should be ignored: md5 = 8882713ada76ae3253dc304526c24fe3 OK
2026/10/10 02:50:26 DEBUG : sub dir/sub sub dir/hello world3: md5 = 3da44d5d29aa17b902292389d3e15067 OK
2026/10/10 02:50:28 DEBUG : a.txt: Excluded (Size Filter)
2026/10/10 02:50:28 DEBUG : a.txt: Excluded
2026/10/10 02:50:28 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2026/10/10 02:50:28 DEBUG : sub dir/hello world2: Excluded
2026/10/10 02:50:28 DEBUG : sub dir/hello world: Excluded (Size Filter)
2026/10/10 02:50:28 DEBUG : sub dir/hello world: Excluded
2026/10/10 02:50:29 DEBUG : sub dir/ignore dir: Excluded
2026/10/10 02:50:29 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2026/10/10 02:50:29 DEBUG : sub dir/hello world2: Excluded
2026/10/10 02:50:29 DEBUG : sub dir/hello world: Excluded (Size Filter)
2026/10/10 02:50:29 DEBUG : sub dir/hello world: Excluded
2026/10/10 02:50:29 DEBUG : sub dir/ignore dir: Excluded
--- PASS: TestListDirSortedFn (23.61s)
=== RUN   TestListJSON
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:50:38 DEBUG : file1: md5 = 7a852aa383e72a506166df1b4694f78e OK
2026/10/10 02:50:40 DEBUG : sub/file2: md5 = 69242dc959c8a218ab93b4f9b3507c4c 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/10/10 02:50:43 ERROR : file1: Failed to read hash: hash type not supported
=== RUN   TestListJSON/Metadata
2026/10/10 02:50:44 DEBUG : eer8kka55qnghc34cq76ca668g: Fetching metadata
2026/10/10 02:50:44 DEBUG : 150fuo3cn4j1uenq6r4g4qk6t4: Fetching metadata
--- PASS: TestListJSON (10.01s)
    --- PASS: TestListJSON/Default (0.29s)
    --- PASS: TestListJSON/FilesOnly (0.24s)
    --- PASS: TestListJSON/DirsOnly (0.27s)
    --- PASS: TestListJSON/Recurse (0.49s)
    --- PASS: TestListJSON/SubDir (0.24s)
    --- PASS: TestListJSON/NoModTime (0.27s)
    --- PASS: TestListJSON/NoMimeType (0.29s)
    --- PASS: TestListJSON/ShowHash (0.24s)
    --- PASS: TestListJSON/HashTypes (0.22s)
    --- PASS: TestListJSON/Metadata (0.69s)
=== RUN   TestStatJSON
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:50:48 DEBUG : file1: md5 = 1d3e7afec1e28455dec68d2b8caac84b OK
2026/10/10 02:50:50 DEBUG : sub/file2: md5 = afbc6a3a182426e7bf2ca733dd171653 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/10/10 02:50:54 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jejanub7data/notfound"
2026/10/10 02:50:54 DEBUG : Creating backend with remote "TestDrive:crypt/c9vi13fe91ns30ne5lotrp9f2vro5okml54qeii135v23234bhq0/qe0i31qdkoejk60elps0ni5sqk"
--- PASS: TestStatJSON (11.94s)
    --- PASS: TestStatJSON/Root (0.27s)
    --- PASS: TestStatJSON/RootFilesOnly (0.00s)
    --- PASS: TestStatJSON/RootDirsOnly (0.28s)
    --- PASS: TestStatJSON/Dir (0.53s)
    --- PASS: TestStatJSON/DirWithTrailingSlash (0.25s)
    --- PASS: TestStatJSON/File (0.26s)
    --- PASS: TestStatJSON/NotFound (0.48s)
    --- PASS: TestStatJSON/DirFilesOnly (0.27s)
    --- PASS: TestStatJSON/FileFilesOnly (0.26s)
    --- PASS: TestStatJSON/NotFoundFilesOnly (0.28s)
    --- PASS: TestStatJSON/DirDirsOnly (0.26s)
    --- PASS: TestStatJSON/FileDirsOnly (0.24s)
    --- PASS: TestStatJSON/NotFoundDirsOnly (0.26s)
    --- PASS: TestStatJSON/RootNotFound (1.83s)
=== RUN   TestStatJSONMemory
2026/10/10 02:50:58 DEBUG : Creating backend with remote ":memory:"
2026/10/10 02:50:58 DEBUG : Memory root '': File to upload is small (5 bytes), uploading instead of streaming
2026/10/10 02:50:58 DEBUG : sub/file1: size = 5 OK
2026/10/10 02:50:58 DEBUG : sub/file1: md5 = 5d41402abc4b2a76b9719d911017c592 OK
2026/10/10 02:50:58 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-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:50:58 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data': Making directory
2026/10/10 02:50:59 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data': Making directory
--- PASS: TestMkdir (0.70s)
=== RUN   TestLsd
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:51:01 DEBUG : sub dir/hello world: md5 = 2199cfaaf63a6d39daac21b584fb1de1 OK
--- PASS: TestLsd (5.25s)
=== RUN   TestLs
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:51:06 DEBUG : potato2: md5 = 24001ea982edfef352bee0f6e79f7acb OK
2026/10/10 02:51:07 DEBUG : empty space: md5 = 6ca14a1317fbcb90b48cbec178f2dad0 OK
--- PASS: TestLs (4.74s)
=== RUN   TestLsWithFilesFrom
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:51:11 DEBUG : potato2: md5 = d5c3a1bb72c5cfcbb62810c2534242b8 OK
2026/10/10 02:51:12 DEBUG : empty space: md5 = 640f50c5f383ba0d871e617c5a747706 OK
2026/10/10 02:51:13 DEBUG : empty space: Excluded (FilesFrom Filter)
--- PASS: TestLsWithFilesFrom (5.34s)
=== RUN   TestLsLong
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:51:16 DEBUG : potato2: md5 = 5c58e46c81fe0ec964975a1e1c78da81 OK
2026/10/10 02:51:18 DEBUG : empty space: md5 = db84dd433692565178a609c766654bbe OK
--- PASS: TestLsLong (5.00s)
=== RUN   TestHashSums
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:51:21 DEBUG : potato2: md5 = 7db179b359857776d5cde8af9d523c0f OK
2026/10/10 02:51:22 DEBUG : empty space: md5 = 77373ae3f77d892ace5dcfbb7c1492c1 OK
--- PASS: TestHashSums (4.62s)
=== RUN   TestHashSumsWithErrors
2026/10/10 02:51:24 DEBUG : Creating backend with remote ":memory:"
2026/10/10 02:51:24 ERROR : file1: hash unsupported: hash type not supported
2026/10/10 02:51:24 ERROR : sub/file1: hash unsupported: hash type not supported
2026/10/10 02:51:24 ERROR : sub/file1: hash unsupported: hash type not supported
--- PASS: TestHashSumsWithErrors (0.00s)
=== RUN   TestHashStream
2026/10/10 02:51:24 DEBUG : Creating md5 hash of 0 bytes read from input stream
2026/10/10 02:51:24 DEBUG : Creating md5 hash of 0 bytes read from input stream
2026/10/10 02:51:24 DEBUG : Creating sha1 hash of 0 bytes read from input stream
2026/10/10 02:51:24 DEBUG : Creating sha1 hash of 0 bytes read from input stream
2026/10/10 02:51:24 DEBUG : Creating md5 hash of 12 bytes read from input stream
2026/10/10 02:51:24 DEBUG : Creating md5 hash of 12 bytes read from input stream
2026/10/10 02:51:24 DEBUG : Creating sha1 hash of 12 bytes read from input stream
2026/10/10 02:51:24 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-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:51:25 DEBUG : potato2: md5 = 54192347f43115f37f5a73e9b11ebb32 OK
2026/10/10 02:51:27 DEBUG : empty space: md5 = b8478a313b3d3d58b4cd0e131105135b OK
2026/10/10 02:51:29 DEBUG : sub dir/potato3: md5 = 8f93f0f178480e678d68a39df78d508e OK
--- PASS: TestCount (8.89s)
=== RUN   TestDelete
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:51:34 DEBUG : small: md5 = 15185a2c618f67fdcb282dc31baf9d69 OK
2026/10/10 02:51:36 DEBUG : medium: md5 = 5c1933690a1fc3b485ffd02dc1eb7dca OK
2026/10/10 02:51:37 DEBUG : large: md5 = 9a47c44bb9f2ba381a9dba01cc7825e8 OK
2026/10/10 02:51:37 DEBUG : Waiting for deletions to finish
2026/10/10 02:51:38 DEBUG : large: Excluded (Size Filter)
2026/10/10 02:51:38 INFO  : medium: Deleted
2026/10/10 02:51:38 INFO  : small: Deleted
--- PASS: TestDelete (6.53s)
=== RUN   TestDeleteFatalError
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:51:41 DEBUG : file0: md5 = f0ef9aaffd87ffda0db56230dd69909f OK
2026/10/10 02:51:42 DEBUG : file1: md5 = da0d65b16dbcc1b58e42862297f72321 OK
2026/10/10 02:51:44 DEBUG : file2: md5 = 221eac8cc4d6df8e3d4a7c6c0c1df8ee OK
2026/10/10 02:51:45 DEBUG : file3: md5 = 9b47247724de4bc63c8cdea9872607f0 OK
2026/10/10 02:51:46 DEBUG : file4: md5 = fb0f988b0d5ad9c841da1041ba13fe2e OK
2026/10/10 02:51:48 DEBUG : file5: md5 = feef8ba71688d98b9689546aa506030f OK
2026/10/10 02:51:49 DEBUG : file6: md5 = 3015a7b30ddd0151b8a0c9531bf8f657 OK
2026/10/10 02:51:51 DEBUG : file7: md5 = 78edcac2e0e9f8db7bcba8400b6b4dda OK
2026/10/10 02:51:52 DEBUG : file8: md5 = b60fc939d8e9284ff7df07ab0773dd36 OK
2026/10/10 02:51:54 DEBUG : file9: md5 = 4e08ff991cee170ccab6d495fff003cd OK
2026/10/10 02:51:55 DEBUG : file10: md5 = 23441b7c07f0102cd594e4a2f4014763 OK
2026/10/10 02:51:57 DEBUG : file11: md5 = f8f09680a8a80d6f7737178a315bc0c8 OK
2026/10/10 02:51:58 DEBUG : file12: md5 = 3cd57483461a843258884018f7b7b3f1 OK
2026/10/10 02:52:01 DEBUG : file13: md5 = dd3b458af718bbd14df662b0af2ebc65 OK
2026/10/10 02:52:03 DEBUG : file14: md5 = 4e0c47c0aa34c178bc42fdb638d42101 OK
2026/10/10 02:52:04 DEBUG : file15: md5 = 82dee9a56baa2735cc9830f7700b4267 OK
2026/10/10 02:52:06 DEBUG : file16: md5 = 55b7b77c7f9f21b93bbff24348aa0856 OK
2026/10/10 02:52:07 DEBUG : file17: md5 = 4fa78ac7a770f8c40ef7e983b5d6c1f8 OK
2026/10/10 02:52:09 DEBUG : file18: md5 = bb2fe2faaf8f859aec1c16719f6f489c OK
2026/10/10 02:52:10 DEBUG : file19: md5 = e2b7fb2cee0578d5ed644f44015e0338 OK
2026/10/10 02:52:10 DEBUG : Waiting for deletions to finish
2026/10/10 02:52:10 ERROR : file2: Got fatal error on delete: --max-delete threshold reached
2026/10/10 02:52:10 ERROR : file18: Got fatal error on delete: --max-delete threshold reached
2026/10/10 02:52:10 ERROR : file19: Got fatal error on delete: --max-delete threshold reached
2026/10/10 02:52:10 ERROR : file9: Got fatal error on delete: --max-delete threshold reached
2026/10/10 02:52:10 ERROR : file11: Got fatal error on delete: --max-delete threshold reached
2026/10/10 02:52:10 ERROR : file1: Got fatal error on delete: --max-delete threshold reached
2026/10/10 02:52:10 ERROR : file5: Got fatal error on delete: --max-delete threshold reached
2026/10/10 02:52:10 ERROR : file13: Got fatal error on delete: --max-delete threshold reached
2026/10/10 02:52:10 ERROR : file10: Got fatal error on delete: --max-delete threshold reached
2026/10/10 02:52:10 ERROR : file12: Got fatal error on delete: --max-delete threshold reached
2026/10/10 02:52:10 ERROR : file0: Got fatal error on delete: --max-delete threshold reached
2026/10/10 02:52:10 ERROR : file17: Got fatal error on delete: --max-delete threshold reached
2026/10/10 02:52:10 ERROR : file16: Got fatal error on delete: --max-delete threshold reached
2026/10/10 02:52:10 ERROR : file8: Got fatal error on delete: --max-delete threshold reached
2026/10/10 02:52:10 ERROR : file15: Got fatal error on delete: --max-delete threshold reached
2026/10/10 02:52:10 ERROR : file14: Got fatal error on delete: --max-delete threshold reached
2026/10/10 02:52:10 ERROR : file4: Got fatal error on delete: --max-delete threshold reached
2026/10/10 02:52:10 ERROR : file3: Got fatal error on delete: --max-delete threshold reached
2026/10/10 02:52:10 ERROR : file7: Got fatal error on delete: --max-delete threshold reached
2026/10/10 02:52:10 ERROR : file6: Got fatal error on delete: --max-delete threshold reached
--- PASS: TestDeleteFatalError (40.11s)
=== RUN   TestMaxDelete
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:52:21 DEBUG : small: md5 = 19ff804959fdebee9273b405ff49d788 OK
2026/10/10 02:52:23 DEBUG : medium: md5 = 27636f8a33de08d4113ce70137605356 OK
2026/10/10 02:52:24 DEBUG : large: md5 = 701f7d8ac4929de976dccece309c12b1 OK
2026/10/10 02:52:25 DEBUG : Waiting for deletions to finish
2026/10/10 02:52:25 ERROR : small: Got fatal error on delete: --max-delete threshold reached
2026/10/10 02:52:25 INFO  : large: Deleted
2026/10/10 02:52:25 INFO  : medium: Deleted
--- PASS: TestMaxDelete (7.49s)
=== RUN   TestMaxDeleteSizeLargeFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:52:28 DEBUG : small: md5 = 0402e9394f216fde22a3887a684abd34 OK
2026/10/10 02:52:30 DEBUG : medium: md5 = b360b5ecab3ab517dd80be4aeaa5d114 OK
2026/10/10 02:52:31 DEBUG : large: md5 = e3f3a51278b68ba4cfd05ccb115bf183 OK
2026/10/10 02:52:32 DEBUG : Waiting for deletions to finish
2026/10/10 02:52:32 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached
2026/10/10 02:52:32 INFO  : small: Deleted
2026/10/10 02:52:32 INFO  : medium: Deleted
--- PASS: TestMaxDeleteSizeLargeFile (6.80s)
=== RUN   TestMaxDeleteSize
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:52:35 DEBUG : small: md5 = 1250445a5c3a88f3300ec984499ff98d OK
2026/10/10 02:52:37 DEBUG : medium: md5 = 135b353cdd707ba07d564500d2727fef OK
2026/10/10 02:52:38 DEBUG : large: md5 = e3b8e6d4ae337556bcc690b1e440a1c7 OK
2026/10/10 02:52:39 DEBUG : Waiting for deletions to finish
2026/10/10 02:52:39 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached
2026/10/10 02:52:39 INFO  : medium: Deleted
2026/10/10 02:52:39 INFO  : small: Deleted
--- PASS: TestMaxDeleteSize (7.33s)
=== RUN   TestReadFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:52:43 DEBUG : ReadFile: md5 = 21ac63ede3415f73b967c20dd9fd6a2f OK
--- PASS: TestReadFile (4.64s)
=== RUN   TestRetry
2026/10/10 02:52:46 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 1/5
2026/10/10 02:52:46 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 2/5
2026/10/10 02:52:46 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms
2026/10/10 02:52:46 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms
2026/10/10 02:52:46 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms
2026/10/10 02:52:46 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/10/10 02:52:46 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-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:52:47 DEBUG : file1: md5 = 37170cbd20a7785375b7abd199d67e8f OK
2026/10/10 02:52:49 DEBUG : file2: md5 = 71277182abd565246e752d05ad25589f OK
--- PASS: TestCat (13.68s)
=== RUN   TestPurge
2026/10/10 02:53:00 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-vagolix5hani"
2026/10/10 02:53:00 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/10 02:53:00 DEBUG : Creating backend with remote "TestDrive:crypt/4vn4cnbofoq6kjn2q4l2f6k3b8qgv9s1qt3s489kb1a7n6epeo5g"
2026/10/10 02:53:01 DEBUG : Creating backend with remote "/tmp/rclone3730220664"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-vagolix5hani'", Local "Local file system at /tmp/rclone3730220664", Modify Window "1ms"
2026/10/10 02:53:05 DEBUG : A1/B1/C1/one: md5 = 9559d75538fe75541b17840b9035c337 OK
2026/10/10 02:53:05 INFO  : A2: Making directory
2026/10/10 02:53:06 INFO  : A1/B2: Making directory
2026/10/10 02:53:07 INFO  : A1/B2/C2: Making directory
2026/10/10 02:53:08 INFO  : A1/B1/C3: Making directory
2026/10/10 02:53:08 INFO  : A3: Making directory
2026/10/10 02:53:09 INFO  : A3/B3: Making directory
2026/10/10 02:53:10 INFO  : A3/B3/C4: Making directory
2026/10/10 02:53:12 DEBUG : A1/two: md5 = 4d33a05fed4af34ba85160204d504041 OK
2026/10/10 02:53:16 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-vagolix5hani': Purge remote
2026/10/10 02:53:16 NOTICE: purge failed: directory not found
--- PASS: TestPurge (16.39s)
=== RUN   TestRmdirsNoLeaveRoot
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:53:20 DEBUG : A1/B1/C1/one: md5 = 6612aedecf6c046154b70f306898c593 OK
2026/10/10 02:53:20 INFO  : A2: Making directory
2026/10/10 02:53:20 INFO  : A1/B2: Making directory
2026/10/10 02:53:21 INFO  : A1/B2/C2: Making directory
2026/10/10 02:53:22 INFO  : A1/B1/C3: Making directory
2026/10/10 02:53:23 INFO  : A3: Making directory
2026/10/10 02:53:23 INFO  : A3/B3: Making directory
2026/10/10 02:53:24 INFO  : A3/B3/C4: Making directory
2026/10/10 02:53:26 DEBUG : A1/two: md5 = 59ea65c8937f9ef06c6adff02cd1c259 OK
2026/10/10 02:53:27 DEBUG : removing 1 level 3 directories
2026/10/10 02:53:27 INFO  : A3/B3/C4: Removing directory
2026/10/10 02:53:30 DEBUG : removing 2 level 3 directories
2026/10/10 02:53:30 INFO  : A1/B2/C2: Removing directory
2026/10/10 02:53:30 INFO  : A1/B1/C3: Removing directory
2026/10/10 02:53:31 DEBUG : removing 2 level 2 directories
2026/10/10 02:53:31 INFO  : A3/B3: Removing directory
2026/10/10 02:53:31 INFO  : A1/B2: Removing directory
2026/10/10 02:53:32 DEBUG : removing 2 level 1 directories
2026/10/10 02:53:32 INFO  : A2: Removing directory
2026/10/10 02:53:32 INFO  : A3: Removing directory
2026/10/10 02:53:36 DEBUG : removing 1 level 3 directories
2026/10/10 02:53:36 INFO  : A1/B1/C1: Removing directory
2026/10/10 02:53:37 DEBUG : removing 1 level 2 directories
2026/10/10 02:53:37 INFO  : A1/B1: Removing directory
2026/10/10 02:53:38 DEBUG : removing 1 level 1 directories
2026/10/10 02:53:38 INFO  : A1: Removing directory
2026/10/10 02:53:38 DEBUG : removing 1 level 0 directories
2026/10/10 02:53:38 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data': Removing directory
--- PASS: TestRmdirsNoLeaveRoot (23.71s)
=== RUN   TestRmdirsLeaveRoot
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:53:40 INFO  : A1: Making directory
2026/10/10 02:53:41 INFO  : A1/B1: Making directory
2026/10/10 02:53:42 INFO  : A1/B1/C1: Making directory
2026/10/10 02:53:44 DEBUG : removing 1 level 3 directories
2026/10/10 02:53:44 INFO  : A1/B1/C1: Removing directory
2026/10/10 02:53:45 DEBUG : removing 1 level 2 directories
2026/10/10 02:53:45 INFO  : A1/B1: Removing directory
--- PASS: TestRmdirsLeaveRoot (7.91s)
=== RUN   TestRmdirsWithFilter
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:53:48 INFO  : A1: Making directory
2026/10/10 02:53:48 INFO  : A1/B1: Making directory
2026/10/10 02:53:49 INFO  : A1/B1/C1: Making directory
2026/10/10 02:53:52 DEBUG : removing 1 level 3 directories
2026/10/10 02:53:52 INFO  : A1/B1/C1: Removing directory
2026/10/10 02:53:53 DEBUG : removing 1 level 2 directories
2026/10/10 02:53:53 INFO  : A1/B1: Removing directory
--- PASS: TestRmdirsWithFilter (7.69s)
=== RUN   TestCopyURL
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:53:57 DEBUG : file1: md5 = 649a187a04b661f444926070c354e511 OK
2026/10/10 02:53:57 DEBUG : file1: size = 14 OK
2026/10/10 02:53:57 DEBUG : filename.txt: File name found in url
2026/10/10 02:53:59 DEBUG : filename.txt: md5 = 0da1f26209f1888b14d033ef8629928c OK
2026/10/10 02:53:59 DEBUG : filename.txt: size = 14 OK
2026/10/10 02:53:59 DEBUG : headerfilename.txt: filename found in Content-Disposition header.
2026/10/10 02:54:01 DEBUG : headerfilename.txt: md5 = 8f46c004b41f6f7a0b461d4d99781b0a OK
2026/10/10 02:54:01 DEBUG : headerfilename.txt: size = 14 OK
2026/10/10 02:54:02 DEBUG : file2: md5 = 1ab875f3791918b4c1763c6fb70daf9d OK
2026/10/10 02:54:02 DEBUG : file2: size = 14 OK
--- PASS: TestCopyURL (9.38s)
=== RUN   TestCopyURLDownloadHeaders
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:54:06 DEBUG : file1: md5 = 9fb9b7f8f826a525ddd7b0e15d0ed507 OK
2026/10/10 02:54:06 DEBUG : file1: size = 14 OK
--- PASS: TestCopyURLDownloadHeaders (2.48s)
=== RUN   TestCopyURLToWriter
--- PASS: TestCopyURLToWriter (0.00s)
=== RUN   TestMoveFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:54:07 DEBUG : file1: Need to transfer - File not found at Destination
2026/10/10 02:54:10 DEBUG : sub/file2: md5 = 69b6e1326bcca9f25055c590c06a3adf OK
2026/10/10 02:54:10 DEBUG : sub/file2: size = 14 OK
2026/10/10 02:54:10 INFO  : file1: Copied (new) to: sub/file2
2026/10/10 02:54:10 INFO  : file1: Deleted
2026/10/10 02:54:10 DEBUG : sub/file2: size = 14 OK
2026/10/10 02:54:10 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2026/10/10 02:54:10 DEBUG : file1: Unchanged skipping
2026/10/10 02:54:10 INFO  : file1: Deleted
2026/10/10 02:54:11 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data': don't need to copy/move sub/file2, it is already at target location
--- PASS: TestMoveFile (6.20s)
=== RUN   TestMoveFileWithIgnoreExisting
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:54:14 DEBUG : file1: Need to transfer - File not found at Destination
2026/10/10 02:54:15 DEBUG : file1: md5 = 1bfa02f139176166aeb9d74cbe3deed6 OK
2026/10/10 02:54:15 DEBUG : file1: size = 14 OK
2026/10/10 02:54:15 INFO  : file1: Copied (new)
2026/10/10 02:54:15 INFO  : file1: Deleted
2026/10/10 02:54:16 DEBUG : file1: Destination exists, skipping
2026/10/10 02:54:16 DEBUG : file1: Not removing source file as destination file exists and --ignore-existing is set
--- PASS: TestMoveFileWithIgnoreExisting (3.60s)
=== RUN   TestMoveFileImmutable
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:54:18 DEBUG : existing: md5 = 3c4a76326eea813eb8a04173f7aa03f5 OK
2026/10/10 02:54:19 DEBUG : existing: size = 8 (Local file system at /tmp/rclone2319411210)
2026/10/10 02:54:19 DEBUG : existing: size = 6 (Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data')
2026/10/10 02:54:19 DEBUG : existing: Sizes differ
2026/10/10 02:54:19 ERROR : existing: Source and destination exist but do not match: immutable file modified
--- PASS: TestMoveFileImmutable (2.99s)
=== RUN   TestCaseInsensitiveMoveFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
--- PASS: TestCaseInsensitiveMoveFile (0.51s)
=== RUN   TestCaseInsensitiveMoveFileDryRun
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
--- PASS: TestCaseInsensitiveMoveFileDryRun (0.46s)
=== RUN   TestMoveFileBackupDir
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:54:23 DEBUG : dst/file1: md5 = d33f376dcdac8cb38d9d8be69c8f5182 OK
2026/10/10 02:54:24 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jejanub7data/backup"
2026/10/10 02:54:24 DEBUG : Config file has changed externally - reloading
2026/10/10 02:54:24 DEBUG : Creating backend with remote "TestDrive:crypt/c9vi13fe91ns30ne5lotrp9f2vro5okml54qeii135v23234bhq0/1nrff024r7pq65ecp72fc28jb0"
2026/10/10 02:54:25 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone2319411210)
2026/10/10 02:54:25 DEBUG : dst/file1: size = 18 (Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data')
2026/10/10 02:54:25 DEBUG : dst/file1: Sizes differ
2026/10/10 02:54:27 INFO  : dst/file1: Moved (server-side)
2026/10/10 02:54:29 DEBUG : dst/file1: md5 = e893665f0ed329414f7e2ae6b9466578 OK
2026/10/10 02:54:29 DEBUG : dst/file1: size = 14 OK
2026/10/10 02:54:29 INFO  : dst/file1: Copied (new)
2026/10/10 02:54:29 INFO  : dst/file1: Deleted
--- PASS: TestMoveFileBackupDir (12.76s)
=== 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-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:54:36 DEBUG : A/file0: md5 = 46c523e586a519faeece45f89e11cc9c OK
2026/10/10 02:54:37 DEBUG : A/file1: md5 = e4a1c1e6aadc50447c97f56b38592889 OK
2026/10/10 02:54:39 DEBUG : A/file2: md5 = 42bb8d35b8e1230797ea1c8de7d0802c OK
2026/10/10 02:54:40 DEBUG : A/file3: md5 = 96e2963b9a5c3e840ca3d81e0f53d3dd OK
2026/10/10 02:54:42 DEBUG : A/file4: md5 = 075e241e02169458223f35a64ea07989 OK
2026/10/10 02:54:43 DEBUG : A/file5: md5 = 3f3766c2cbcbb40355d2db28a6bccf7d OK
2026/10/10 02:54:45 DEBUG : A/file6: md5 = 4338bd7348074fa4c3cbbf42250f4c41 OK
2026/10/10 02:54:46 DEBUG : A/file7: md5 = 7d3976dc89b565b27cf40ea587f2f8d7 OK
2026/10/10 02:54:48 DEBUG : A/file8: md5 = e04c5ab331c6ebb273e60f07fa976af9 OK
2026/10/10 02:54:49 DEBUG : A/file9: md5 = 830acc28e74b576e1faa1b906f9434ca OK
2026/10/10 02:54:50 DEBUG : A/file10: md5 = d61727c8f334bc56a599c06a7054c3e8 OK
2026/10/10 02:54:52 DEBUG : A/file11: md5 = 55d703acf934b6183f0ce479024ace7a OK
2026/10/10 02:54:53 DEBUG : A/file12: md5 = fcf2fae054c7bbe6e4034ebcc76dc64d OK
2026/10/10 02:54:55 DEBUG : A/file13: md5 = 46fdd8c6d3d1c0c3b1b58dc7b991f47d OK
2026/10/10 02:54:56 DEBUG : A/file14: md5 = 6783f1d16968bc3ee50c3745f8cb67da OK
2026/10/10 02:54:58 DEBUG : A/file15: md5 = 0d2957fb40680a4f92447cc7d205dd79 OK
2026/10/10 02:55:00 DEBUG : A/file16: md5 = 053790894bc29977e2f7b06d61d31f44 OK
2026/10/10 02:55:01 DEBUG : A/file17: md5 = fba5999b5289e194431f375e6293a0cf OK
2026/10/10 02:55:02 DEBUG : A/file18: md5 = 7058d14a8fb975150cc0c7bf3e5871ca OK
2026/10/10 02:55:05 DEBUG : A/file19: md5 = 1237ce95a0054af56ad8cac933dfa8e3 OK
    operations_test.go:1490: Skipping test on non local remote
--- SKIP: TestDirMoveMoveError (41.75s)
=== RUN   TestDirMoveContext
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:55:18 DEBUG : A/one: md5 = dcb6a05c744ae898482e6f3515e27116 OK
2026/10/10 02:55:19 DEBUG : A/two: md5 = 2e6ccfe1a148f5c2769e2d5456aff885 OK
    operations_test.go:1490: Skipping test on non local remote
--- SKIP: TestDirMoveContext (6.25s)
=== RUN   TestDirMove
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:55:24 DEBUG : A1/one: md5 = 6a8eed746f99eeba335bb8e113a2751e OK
2026/10/10 02:55:26 DEBUG : A1/two: md5 = 273b96c1d8016192e9e84d12597ce9a4 OK
2026/10/10 02:55:29 DEBUG : A1/B1/three: md5 = 5af9c32672edab4d49115391c47885c0 OK
2026/10/10 02:55:31 DEBUG : A1/B1/C1/four: md5 = 9477d4cf16d695565caf9c0d42a48629 OK
2026/10/10 02:55:33 DEBUG : A1/B1/C2/five: md5 = 1ce1a0656ef89b5b43dc60f1a0d8b090 OK
2026/10/10 02:55:33 INFO  : A1/B2: Making directory
2026/10/10 02:55:34 INFO  : A1/B1/C3: Making directory
2026/10/10 02:55:44 INFO  : A2/B1/three: Moved (server-side) to: A3/B1/three
2026/10/10 02:55:45 INFO  : A2/B1/C1/four: Moved (server-side) to: A3/B1/C1/four
2026/10/10 02:55:45 INFO  : A2/B1/C2/five: Moved (server-side) to: A3/B1/C2/five
2026/10/10 02:55:45 INFO  : A2/two: Moved (server-side) to: A3/two
2026/10/10 02:55:46 INFO  : A2/one: Moved (server-side) to: A3/one
2026/10/10 02:55:51 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data': Can't DirMove - falling back to file moves: can't move directory - incompatible remotes
2026/10/10 02:55:57 INFO  : A3/two: Moved (server-side) to: A4/two
2026/10/10 02:55:57 INFO  : A3/B1/C2/five: Moved (server-side) to: A4/B1/C2/five
2026/10/10 02:55:57 INFO  : A3/B1/C1/four: Moved (server-side) to: A4/B1/C1/four
2026/10/10 02:55:58 INFO  : A3/one: Moved (server-side) to: A4/one
2026/10/10 02:55:58 INFO  : A3/B1/three: Moved (server-side) to: A4/B1/three
--- PASS: TestDirMove (49.07s)
=== RUN   TestGetFsInfo
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
--- PASS: TestGetFsInfo (0.53s)
=== RUN   TestRcat
=== RUN   TestRcat/withChecksum=false,ignoreChecksum=false
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:56:11 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data': File to upload is small (34 bytes), uploading instead of streaming
2026/10/10 02:56:13 DEBUG : no_checksum_small_file_from_pipe: md5 = 1eed52fdd089f37edf9520de1efeb68a OK
2026/10/10 02:56:13 DEBUG : no_checksum_small_file_from_pipe: size = 34 OK
2026/10/10 02:56:13 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only
2026/10/10 02:56:13 DEBUG : no_checksum_small_file_from_pipe: Size of src and dst objects identical
2026/10/10 02:56:14 DEBUG : qc9k4uauqa9ls6a2r6lo8jf4b1m7r0510pkurois1cc70egbs4h0: Sending chunk 0 length 102465
2026/10/10 02:56:15 DEBUG : no_checksum_big_file_from_pipe: md5 = 95c2c3fe1a56f63351a5601ffb32aedc OK
2026/10/10 02:56:15 DEBUG : no_checksum_big_file_from_pipe: size = 102401 OK
2026/10/10 02:56:15 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-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:56:17 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data': File to upload is small (34 bytes), uploading instead of streaming
2026/10/10 02:56:18 DEBUG : with_checksum_small_file_from_pipe: md5 = 93dc4bb4fb4a2ca1a071b9828fd0e99d OK
2026/10/10 02:56:18 DEBUG : with_checksum_small_file_from_pipe: size = 34 OK
2026/10/10 02:56:18 DEBUG : with_checksum_small_file_from_pipe: Size of src and dst objects identical
2026/10/10 02:56:19 DEBUG : e74jo8a7lr1aimg2u78fct67gnuetc1qujiofaci0c8fnq7rjt1d4i296pcklqhgdihs03etvgdi4: Sending chunk 0 length 102465
2026/10/10 02:56:20 DEBUG : with_checksum_big_file_from_pipe: md5 = df0ac76ce31f2e12346ecd5297c9350e OK
2026/10/10 02:56:20 DEBUG : with_checksum_big_file_from_pipe: size = 102401 OK
2026/10/10 02:56:20 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-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:56:22 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data': File to upload is small (34 bytes), uploading instead of streaming
2026/10/10 02:56:24 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK
2026/10/10 02:56:24 DEBUG : ignore_checksum_small_file_from_pipe: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2026/10/10 02:56:24 DEBUG : 1963rn6olajfbjn4nbqi9nacu7bk879vpf0d2bg8r0ndcqqsfntob0urt056p0qujf574rqnb5f62: Sending chunk 0 length 102465
2026/10/10 02:56:26 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK
2026/10/10 02:56:26 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-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:56:28 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data': File to upload is small (34 bytes), uploading instead of streaming
2026/10/10 02:56:29 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK
2026/10/10 02:56:29 DEBUG : ignore_checksum_small_file_from_pipe: Size of src and dst objects identical
2026/10/10 02:56:30 DEBUG : 1963rn6olajfbjn4nbqi9nacu7bk879vpf0d2bg8r0ndcqqsfntob0urt056p0qujf574rqnb5f62: Sending chunk 0 length 102465
2026/10/10 02:56:31 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK
2026/10/10 02:56:31 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical
--- PASS: TestRcat (21.68s)
    --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (5.59s)
    --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (5.11s)
    --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (5.56s)
    --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (5.42s)
=== RUN   TestRcatMetadata
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
=== RUN   TestRcatMetadata/Normal
2026/10/10 02:56:33 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data': File to upload is small (48 bytes), uploading instead of streaming
2026/10/10 02:56:35 DEBUG : rcat_metadata: md5 = 4c5d1f39a1ffbe0a849aa72d3cdd85e4 OK
2026/10/10 02:56:35 DEBUG : rcat_metadata: size = 48 OK
2026/10/10 02:56:35 DEBUG : rcat_metadata: Size of src and dst objects identical
=== RUN   TestRcatMetadata/ViaDisk
2026/10/10 02:56:37 DEBUG : rijssoobhbn85c5p1imhg55m6frank1nlplla6hkdjlqmqb2471g: Sending chunk 0 length 111
2026/10/10 02:56:38 DEBUG : rcat_metadata_uploadcutoff0: md5 = 6c40a0c219baeeb61528d798c34cef74 OK
2026/10/10 02:56:38 DEBUG : rcat_metadata_uploadcutoff0: size = 63 OK
2026/10/10 02:56:38 DEBUG : rcat_metadata_uploadcutoff0: Size of src and dst objects identical
--- PASS: TestRcatMetadata (6.58s)
    --- PASS: TestRcatMetadata/Normal (2.87s)
    --- PASS: TestRcatMetadata/ViaDisk (3.00s)
=== RUN   TestRcatSize
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:56:41 DEBUG : potato1: md5 = cc15c1f9e9f1f983877e216ee2ea26b8 OK
2026/10/10 02:56:41 DEBUG : potato1: size = 60 OK
2026/10/10 02:56:41 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data': File to upload is small (60 bytes), uploading instead of streaming
2026/10/10 02:56:42 DEBUG : potato2: md5 = 46cc3cf79414d6fd5de3a511c21949c3 OK
2026/10/10 02:56:42 DEBUG : potato2: size = 60 OK
2026/10/10 02:56:42 DEBUG : potato2: Size of src and dst objects identical
--- PASS: TestRcatSize (4.69s)
=== RUN   TestRcatSizeShortEOF
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:56:46 DEBUG : potato1: md5 = 000326eae18c7af8989aec9e844c5f3a OK
2026/10/10 02:56:46 DEBUG : potato1: size = 120 (Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data')
2026/10/10 02:56:46 DEBUG : potato1: size = 60 (Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data')
2026/10/10 02:56:46 ERROR : potato1: corrupted on transfer: sizes differ src 120 vs dst(Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data') 60
2026/10/10 02:56:46 INFO  : potato1: Removing failed copy
--- PASS: TestRcatSizeShortEOF (2.31s)
=== RUN   TestRcatSizeMetadata
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:56:48 DEBUG : potato1: md5 = 665c62d59a15335a541acebe410315a5 OK
2026/10/10 02:56:48 DEBUG : potato1: size = 60 OK
2026/10/10 02:56:48 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data': File to upload is small (60 bytes), uploading instead of streaming
2026/10/10 02:56:50 DEBUG : potato2: md5 = c8a34917879dc0639eb9767e2e05acfd OK
2026/10/10 02:56:50 DEBUG : potato2: size = 60 OK
2026/10/10 02:56:50 DEBUG : potato2: Size of src and dst objects identical
--- PASS: TestRcatSizeMetadata (5.10s)
=== RUN   TestRcatSizeUploadHeaders
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:56:53 DEBUG : potato1: md5 = f5de3a00513d8620a8ecbf760f694ffe OK
2026/10/10 02:56:53 DEBUG : potato1: size = 60 OK
--- PASS: TestRcatSizeUploadHeaders (2.53s)
=== RUN   TestRcatSizeChecksum
=== RUN   TestRcatSizeChecksum/Corrupted
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", 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-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:56:56 DEBUG : potato4: md5 = 2fc07196b4058cb682545edb6c8a9895 OK
2026/10/10 02:56:56 DEBUG : potato4: size = 60 (Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data')
2026/10/10 02:56:56 DEBUG : potato4: size = 59 (Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data')
2026/10/10 02:56:56 ERROR : potato4: corrupted on transfer: sizes differ src 60 vs dst(Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data') 59
2026/10/10 02:56:56 INFO  : potato4: Removing failed copy
=== RUN   TestRcatSizeChecksum/IgnoreChecksum
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:56:59 DEBUG : potato2: size = 60 OK
=== RUN   TestRcatSizeChecksum/NoHashes
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:57:01 DEBUG : potato3: md5 = 7ee812963b7bc9fbd57c3febff98fa54 OK
2026/10/10 02:57:01 DEBUG : potato3: size = 60 OK
--- PASS: TestRcatSizeChecksum (8.38s)
    --- SKIP: TestRcatSizeChecksum/Corrupted (0.45s)
    --- PASS: TestRcatSizeChecksum/SizeDiffers (2.81s)
    --- PASS: TestRcatSizeChecksum/IgnoreChecksum (2.73s)
    --- PASS: TestRcatSizeChecksum/NoHashes (2.38s)
=== RUN   TestTouchDir
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:57:04 DEBUG : potato2: md5 = 3fcda0e326582f80cde53308e740e0c7 OK
2026/10/10 02:57:05 DEBUG : empty space: md5 = 733f168d287a0918d485db730471757f OK
2026/10/10 02:57:07 DEBUG : sub dir/potato3: md5 = e2c1482545d9ff5a0c56cae30e217364 OK
2026/10/10 02:57:08 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data': Touching "sub dir/potato3"
2026/10/10 02:57:08 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data': Touching "potato2"
2026/10/10 02:57:08 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data': Touching "empty space"
--- PASS: TestTouchDir (11.07s)
=== RUN   TestMkdirMetadata
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:57:14 DEBUG : dir with metadata: Making directory with metadata
2026/10/10 02:57:14 INFO  : dir with metadata: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z)
--- PASS: TestMkdirMetadata (2.44s)
=== RUN   TestMkdirModTime
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:57:16 DEBUG : directory with modtime: Making directory with metadata
2026/10/10 02:57:17 INFO  : directory with modtime: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2026/10/10 02:57:17 NOTICE: dry run directory with modtime: Skipped make directory as --dry-run is set
--- PASS: TestMkdirModTime (2.36s)
=== RUN   TestCopyDirMetadata
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:57:18 DEBUG : dir with metadata to be copied: Making directory with metadata
2026/10/10 02:57:18 INFO  : dir with metadata to be copied: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z)
2026/10/10 02:57:19 DEBUG : Google drive root 'crypt/c9vi13fe91ns30ne5lotrp9f2vro5okml54qeii135v23234bhq0': Skipping btime metadata as can't update it on an existing file: 2026-10-10T02:57:18.884683923Z
2026/10/10 02:57:19 INFO  : non existent directory: Updated directory metadata
2026/10/10 02:57:21 DEBUG : Google drive root 'crypt/c9vi13fe91ns30ne5lotrp9f2vro5okml54qeii135v23234bhq0': Skipping btime metadata as can't update it on an existing file: 2026-10-10T02:57:18.884683923Z
2026/10/10 02:57:22 INFO  : existing directory: Updated directory metadata
--- PASS: TestCopyDirMetadata (5.23s)
=== RUN   TestSetDirModTime
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:57:24 DEBUG : set modtime on non existent directory: Skipping set directory modification time as --no-update-dir-modtime is set
2026/10/10 02:57:25 INFO  : set modtime on existing directory: Set directory modification time (using SetModTime)
2026/10/10 02:57:26 INFO  : set modtime on existing directory: Set directory modification time (using DirSetModTime)
--- PASS: TestSetDirModTime (4.34s)
=== RUN   TestDirsEqual
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:57:28 DEBUG : dir with metadata to be copied: Making directory with metadata
2026/10/10 02:57:28 INFO  : dir with metadata to be copied: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z)
2026/10/10 02:57:28 DEBUG : dst: Making directory with metadata
2026/10/10 02:57:29 INFO  : dst: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z)
2026/10/10 02:57:29 DEBUG : dst: Directory modification time the same (differ by -999.999µs, within tolerance 1ms)
2026/10/10 02:57:29 INFO  : dst: Set directory modification time (using SetModTime)
2026/10/10 02:57:30 INFO  : dst: Set directory modification time (using SetModTime)
2026/10/10 02:57:30 DEBUG : dst: Directory modification time the same (differ by 1ns, within tolerance 1ms)
2026/10/10 02:57:30 INFO  : dst: Set directory modification time (using SetModTime)
2026/10/10 02:57:30 DEBUG : dst: Destination directory is newer than source, skipping
--- PASS: TestDirsEqual (3.74s)
=== RUN   TestRemoveExisting
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data'", Local "Local file system at /tmp/rclone2319411210", Modify Window "1ms"
2026/10/10 02:57:34 DEBUG : sub dir/test remove existing: md5 = 5265b49fb05981ee473ec8a46f32ac73 OK
2026/10/10 02:57:35 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: md5 = 561961e2d6d22f2cfb01dd3378db884a OK
2026/10/10 02:57:37 DEBUG : sub dir/test remove existing: TEST: renaming existing object to "sub dir/test remove existing.pobigig0" before starting
2026/10/10 02:57:39 DEBUG : sub dir/test remove existing.pobigig0: TEST: removing renamed existing file after operation
2026/10/10 02:57:40 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: TEST: renaming existing object to "sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.ripuqiv2" before starting
2026/10/10 02:57:41 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.ripuqiv2: TEST: renaming existing back after failed operation
2026/10/10 02:57:43 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: TEST: renaming existing object to "sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.xitehin9" before starting
2026/10/10 02:57:44 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.xitehin9: TEST: removing renamed existing file after operation
--- PASS: TestRemoveExisting (14.27s)
=== RUN   TestRcatInputFailurePreservesDestination
2026/10/10 02:57:46 DEBUG : Creating backend with remote "/tmp/TestRcatInputFailurePreservesDestination1501410401/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/10/10 02:57:46 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jejanub7data': Purge remote
"./operations.test -test.v -test.timeout 1h0m0s -remote TestCryptDrive: -verbose -fast-list" - Finished OK in 22m27.688713537s (try 1/5)