"./operations.test -test.v -test.timeout 1h0m0s -remote TestCryptDrive: -verbose" - Starting (try 1/5)
2026/10/12 04:22:53 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-soyebah2yafe"
2026/10/12 04:22:53 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/12 04:22:53 DEBUG : Creating backend with remote "TestDrive:crypt/3lr4390jip2q9g8ao5ds7vf9as0a7itvio95vb7qd4i7vahepmpg"
2026/10/12 04:22:54 DEBUG : Creating backend with remote "/tmp/rclone3458124860"
=== 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-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
    multithread_test.go:120: multithread writing not supported
--- SKIP: TestMultithreadCopy (0.24s)
=== RUN   TestMultithreadCopyAbort
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
    multithread_test.go:120: multithread writing not supported
--- SKIP: TestMultithreadCopyAbort (0.34s)
=== RUN   TestMultithreadCopyWriterAtErrors
=== RUN   TestMultithreadCopyWriterAtErrors/open/fatal=false
2026/10/12 04:22:55 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt
=== RUN   TestMultithreadCopyWriterAtErrors/open/fatal=true
2026/10/12 04:22:55 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt
=== RUN   TestMultithreadCopyWriterAtErrors/write/fatal=false
2026/10/12 04:22:55 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt
2026/10/12 04:22:55 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams
2026/10/12 04:22:55 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting
2026/10/12 04:22:55 DEBUG : file.txt: writing chunk 1
2026/10/12 04:22:55 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting
2026/10/12 04:22:55 DEBUG : file.txt: writing chunk 0
2026/10/12 04:22:55 DEBUG : file.txt: multi-thread copy: chunk 2/4 failed: multi-thread copy: failed to write chunk: disk full
2026/10/12 04:22:55 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting
2026/10/12 04:22:55 DEBUG : file.txt: multi-thread copy: chunk 1/4 failed: multi-thread copy: failed to write chunk: disk full
2026/10/12 04:22:55 DEBUG : file.txt: writing chunk 2
2026/10/12 04:22:55 DEBUG : file.txt: multi-thread copy: chunk 3/4 failed: multi-thread copy: failed to write chunk: disk full
2026/10/12 04:22:55 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit
2026/10/12 04:22:55 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/12 04:22:55 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt
2026/10/12 04:22:55 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams
2026/10/12 04:22:55 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting
2026/10/12 04:22:55 DEBUG : file.txt: writing chunk 1
2026/10/12 04:22:55 DEBUG : file.txt: multi-thread copy: chunk 2/4 failed: multi-thread copy: failed to write chunk: disk full
2026/10/12 04:22:55 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting
2026/10/12 04:22:55 DEBUG : file.txt: writing chunk 2
2026/10/12 04:22:55 DEBUG : file.txt: multi-thread copy: chunk 3/4 failed: multi-thread copy: failed to write chunk: disk full
2026/10/12 04:22:55 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting
2026/10/12 04:22:55 DEBUG : file.txt: writing chunk 0
2026/10/12 04:22:55 DEBUG : file.txt: multi-thread copy: chunk 1/4 failed: multi-thread copy: failed to write chunk: disk full
2026/10/12 04:22:55 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit
2026/10/12 04:22:55 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/12 04:22:55 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt
2026/10/12 04:22:55 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams
2026/10/12 04:22:55 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting
2026/10/12 04:22:55 DEBUG : file.txt: writing chunk 1
2026/10/12 04:22:55 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 finished
2026/10/12 04:22:55 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting
2026/10/12 04:22:55 DEBUG : file.txt: writing chunk 2
2026/10/12 04:22:55 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 finished
2026/10/12 04:22:55 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 starting
2026/10/12 04:22:55 DEBUG : file.txt: writing chunk 3
2026/10/12 04:22:55 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 finished
2026/10/12 04:22:55 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting
2026/10/12 04:22:55 DEBUG : file.txt: writing chunk 0
2026/10/12 04:22:55 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 finished
2026/10/12 04:22:55 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit
2026/10/12 04:22:55 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/12 04:22:55 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt
2026/10/12 04:22:55 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams
2026/10/12 04:22:55 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting
2026/10/12 04:22:55 DEBUG : file.txt: writing chunk 1
2026/10/12 04:22:55 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 finished
2026/10/12 04:22:55 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting
2026/10/12 04:22:55 DEBUG : file.txt: writing chunk 2
2026/10/12 04:22:55 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 finished
2026/10/12 04:22:55 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 starting
2026/10/12 04:22:55 DEBUG : file.txt: writing chunk 3
2026/10/12 04:22:55 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 finished
2026/10/12 04:22:55 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting
2026/10/12 04:22:55 DEBUG : file.txt: writing chunk 0
2026/10/12 04:22:55 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 finished
2026/10/12 04:22:55 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit
2026/10/12 04:22:55 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/12 04:22:55 DEBUG : a: size = 0 OK
2026/10/12 04:22:55 DEBUG : a: size = 1 (memory)
2026/10/12 04:22:55 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/12 04:22:55 DEBUG : potato: Seek from 10 to 0
=== RUN   TestReOpen/Normal/ErrorAtStart
=== RUN   TestReOpen/Normal/WithErrors
2026/10/12 04:22:55 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2026/10/12 04:22:55 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2026/10/12 04:22:55 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN   TestReOpen/Normal/TooManyErrors
2026/10/12 04:22:55 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2026/10/12 04:22:55 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2026/10/12 04:22:55 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2026/10/12 04:22:55 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN   TestReOpen/Normal/ReadAt
2026/10/12 04:22:55 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/10/12 04:22:55 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/10/12 04:22:55 DEBUG : potato: Seek from 5 to 1
=== RUN   TestReOpen/Normal/Seek
2026/10/12 04:22:55 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/10/12 04:22:55 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/10/12 04:22:55 DEBUG : potato: Seek from 5 to 2
=== RUN   TestReOpen/Normal/AccountRead
=== RUN   TestReOpen/Normal/AccountReadDelay
2026/10/12 04:22:55 DEBUG : potato: Seek from 10 to 0
2026/10/12 04:22:55 DEBUG : potato: Seek from 10 to 0
2026/10/12 04:22:55 DEBUG : potato: Seek from 10 to 0
=== RUN   TestReOpen/Normal/AccountReadError
=== RUN   TestReOpen/WithRangeOption
=== RUN   TestReOpen/WithRangeOption/Basics
2026/10/12 04:22:55 DEBUG : potato: Seek from 7 to 0
=== RUN   TestReOpen/WithRangeOption/ErrorAtStart
=== RUN   TestReOpen/WithRangeOption/WithErrors
2026/10/12 04:22:55 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2026/10/12 04:22:55 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2026/10/12 04:22:55 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN   TestReOpen/WithRangeOption/TooManyErrors
2026/10/12 04:22:55 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2026/10/12 04:22:55 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2026/10/12 04:22:55 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2026/10/12 04:22:55 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN   TestReOpen/WithRangeOption/ReadAt
2026/10/12 04:22:55 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/10/12 04:22:55 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/10/12 04:22:55 DEBUG : potato: Seek from 5 to 1
=== RUN   TestReOpen/WithRangeOption/Seek
2026/10/12 04:22:55 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/10/12 04:22:55 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/10/12 04:22:55 DEBUG : potato: Seek from 5 to 2
2026/10/12 04:22:55 DEBUG : potato: Seek from 7 to 4
=== RUN   TestReOpen/WithRangeOption/AccountRead
=== RUN   TestReOpen/WithRangeOption/AccountReadDelay
2026/10/12 04:22:55 DEBUG : potato: Seek from 7 to 0
2026/10/12 04:22:55 DEBUG : potato: Seek from 7 to 0
2026/10/12 04:22:55 DEBUG : potato: Seek from 7 to 0
=== RUN   TestReOpen/WithRangeOption/AccountReadError
=== RUN   TestReOpen/WithSeekOption
=== RUN   TestReOpen/WithSeekOption/Basics
2026/10/12 04:22:55 DEBUG : potato: Seek from 8 to 0
=== RUN   TestReOpen/WithSeekOption/ErrorAtStart
=== RUN   TestReOpen/WithSeekOption/WithErrors
2026/10/12 04:22:55 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2026/10/12 04:22:55 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2026/10/12 04:22:55 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN   TestReOpen/WithSeekOption/TooManyErrors
2026/10/12 04:22:55 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2026/10/12 04:22:55 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2026/10/12 04:22:55 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2026/10/12 04:22:55 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN   TestReOpen/WithSeekOption/ReadAt
2026/10/12 04:22:55 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/10/12 04:22:55 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/10/12 04:22:55 DEBUG : potato: Seek from 5 to 1
=== RUN   TestReOpen/WithSeekOption/Seek
2026/10/12 04:22:55 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/10/12 04:22:55 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/10/12 04:22:55 DEBUG : potato: Seek from 5 to 2
2026/10/12 04:22:55 DEBUG : potato: Seek from 7 to 5
=== RUN   TestReOpen/WithSeekOption/AccountRead
=== RUN   TestReOpen/WithSeekOption/AccountReadDelay
2026/10/12 04:22:55 DEBUG : potato: Seek from 8 to 0
2026/10/12 04:22:55 DEBUG : potato: Seek from 8 to 0
2026/10/12 04:22:55 DEBUG : potato: Seek from 8 to 0
=== RUN   TestReOpen/WithSeekOption/AccountReadError
=== RUN   TestReOpen/UnknownSize
=== RUN   TestReOpen/UnknownSize/Basics
2026/10/12 04:22:55 DEBUG : potato: Seek from 9 to 0
=== RUN   TestReOpen/UnknownSize/ErrorAtStart
=== RUN   TestReOpen/UnknownSize/WithErrors
2026/10/12 04:22:55 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2026/10/12 04:22:55 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2026/10/12 04:22:55 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN   TestReOpen/UnknownSize/TooManyErrors
2026/10/12 04:22:55 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2026/10/12 04:22:55 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2026/10/12 04:22:55 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2026/10/12 04:22:55 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN   TestReOpen/UnknownSize/ReadAt
2026/10/12 04:22:55 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/10/12 04:22:55 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/10/12 04:22:55 DEBUG : potato: Seek from 5 to 1
=== RUN   TestReOpen/UnknownSize/Seek
2026/10/12 04:22:55 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/10/12 04:22:55 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/10/12 04:22:55 DEBUG : potato: Seek from 5 to 2
2026/10/12 04:22:55 DEBUG : potato: Seek from 7 to 6
=== RUN   TestReOpen/UnknownSize/AccountRead
=== RUN   TestReOpen/UnknownSize/AccountReadDelay
2026/10/12 04:22:55 DEBUG : potato: Seek from 9 to 0
2026/10/12 04:22:55 DEBUG : potato: Seek from 9 to 0
2026/10/12 04:22:55 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-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:22:58 DEBUG : rutabaga: md5 = c5929468474a27aff2b34b1558eeca6a OK
=== RUN   TestCheck/1
=== RUN   TestCheck/2
2026/10/12 04:23:01 DEBUG : empty space: md5 = 9cdecee4dac570c6f259eba93595084b OK
=== RUN   TestCheck/3
2026/10/12 04:23:03 DEBUG : potato2: md5 = 0e44dc1dcc4f74d66d2e7357f8d40108 OK
=== RUN   TestCheck/4
=== RUN   TestCheck/5
2026/10/12 04:23:06 DEBUG : remotepotato: md5 = fdaff080aa2e6796c37f785c53ef293a OK
=== RUN   TestCheck/6
=== RUN   TestCheck/7
--- PASS: TestCheck (16.04s)
    --- PASS: TestCheck/1 (0.36s)
    --- PASS: TestCheck/2 (0.34s)
    --- PASS: TestCheck/3 (0.36s)
    --- PASS: TestCheck/4 (0.35s)
    --- PASS: TestCheck/5 (0.34s)
    --- PASS: TestCheck/6 (0.39s)
    --- PASS: TestCheck/7 (0.35s)
=== RUN   TestCheckFsError
2026/10/12 04:23:11 DEBUG : Creating backend with remote "nonexistent"
2026/10/12 04:23:11 DEBUG : Creating backend with remote "nonexistent"
2026/10/12 04:23:11 DEBUG : Local file system at nonexistent: Waiting for checks to finish
2026/10/12 04:23:11 ERROR : Local file system at nonexistent: error reading source root directory: directory not found
2026/10/12 04:23:11 NOTICE: Local file system at nonexistent: 0 differences found
2026/10/12 04:23:11 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-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:23:13 DEBUG : rutabaga: md5 = edce77dd09d722191de343c4ec1f4b49 OK
=== RUN   TestCheckDownload/1
=== RUN   TestCheckDownload/2
2026/10/12 04:23:17 DEBUG : empty space: md5 = 2ac34a9018ac83fab189a1b6df672c16 OK
=== RUN   TestCheckDownload/3
2026/10/12 04:23:20 DEBUG : potato2: md5 = fd686cede92873cad73685783fe6c852 OK
=== RUN   TestCheckDownload/4
=== RUN   TestCheckDownload/5
2026/10/12 04:23:25 DEBUG : remotepotato: md5 = 5cf65182490588f966c2168d2aa32d9e OK
=== RUN   TestCheckDownload/6
=== RUN   TestCheckDownload/7
--- PASS: TestCheckDownload (19.55s)
    --- PASS: TestCheckDownload/1 (1.07s)
    --- PASS: TestCheckDownload/2 (1.07s)
    --- PASS: TestCheckDownload/3 (0.88s)
    --- PASS: TestCheckDownload/4 (1.10s)
    --- PASS: TestCheckDownload/5 (1.07s)
    --- PASS: TestCheckDownload/6 (0.90s)
    --- PASS: TestCheckDownload/7 (0.93s)
=== RUN   TestCheckSizeOnly
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:23:32 DEBUG : rutabaga: md5 = 5673deeca54b5efdd5a74a4de88b25bc OK
=== RUN   TestCheckSizeOnly/1
=== RUN   TestCheckSizeOnly/2
2026/10/12 04:23:35 DEBUG : empty space: md5 = dd1298163a2ba97da0a7b146bf02a37c OK
=== RUN   TestCheckSizeOnly/3
2026/10/12 04:23:38 DEBUG : potato2: md5 = 2f57e75f31e6189db36f29d075338a2e OK
=== RUN   TestCheckSizeOnly/4
=== RUN   TestCheckSizeOnly/5
2026/10/12 04:23:41 DEBUG : remotepotato: md5 = 217c6f340f4341a102d1b1d35bd22f90 OK
=== RUN   TestCheckSizeOnly/6
=== RUN   TestCheckSizeOnly/7
--- PASS: TestCheckSizeOnly (15.15s)
    --- PASS: TestCheckSizeOnly/1 (0.33s)
    --- PASS: TestCheckSizeOnly/2 (0.40s)
    --- PASS: TestCheckSizeOnly/3 (0.36s)
    --- PASS: TestCheckSizeOnly/4 (0.24s)
    --- PASS: TestCheckSizeOnly/5 (0.36s)
    --- PASS: TestCheckSizeOnly/6 (0.32s)
    --- PASS: TestCheckSizeOnly/7 (0.32s)
=== RUN   TestCheckEqualReaders
--- PASS: TestCheckEqualReaders (0.00s)
=== RUN   TestParseSumFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:23:48 DEBUG : test.sum: md5 = a1287b66d9a67f2fb06aafc131548516 OK
2026/10/12 04:23:49 NOTICE: test.sum: improperly formatted checksum line 4
2026/10/12 04:23:49 NOTICE: test.sum: improperly formatted checksum line 5
2026/10/12 04:23:49 NOTICE: test.sum: improperly formatted checksum line 6
2026/10/12 04:23:49 NOTICE: test.sum: 2 warning(s) suppressed...
2026/10/12 04:23:51 DEBUG : test.sum: md5 = 3246a3a0f7496e359c2779d2819eae3d OK
2026/10/12 04:23:52 NOTICE: test.sum: improperly formatted checksum line 4
2026/10/12 04:23:52 NOTICE: test.sum: improperly formatted checksum line 5
2026/10/12 04:23:52 NOTICE: test.sum: improperly formatted checksum line 6
2026/10/12 04:23:52 NOTICE: test.sum: 2 warning(s) suppressed...
--- PASS: TestParseSumFile (7.47s)
=== RUN   TestCheckSum
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:23:53 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-soyebah2yafe/data"
2026/10/12 04:23:53 DEBUG : Config file has changed externally - reloading
2026/10/12 04:23:53 DEBUG : Creating backend with remote "TestDrive:crypt/3lr4390jip2q9g8ao5ds7vf9as0a7itvio95vb7qd4i7vahepmpg/vjrnln8ratgmqakfosrqe8espk"
    check_test.go:354: Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe/data' lacks md5, skipping
--- SKIP: TestCheckSum (2.58s)
=== RUN   TestCheckSumDownload
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:23:56 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-soyebah2yafe/data"
2026/10/12 04:23:56 DEBUG : Creating backend with remote "TestDrive:crypt/3lr4390jip2q9g8ao5ds7vf9as0a7itvio95vb7qd4i7vahepmpg/vjrnln8ratgmqakfosrqe8espk"
2026/10/12 04:24:01 DEBUG : data/banana: md5 = 76452cf1027ab76981d4fd5b3cf57df0 OK
2026/10/12 04:24:03 DEBUG : test.sum: md5 = 8b7b759a52a6c5022244ccdd59ff5e43 OK
=== RUN   TestCheckSumDownload/subtest1
2026/10/12 04:24:08 DEBUG : data/potato: md5 = 8e5cfa27580cf7fa66c456edfa50879d OK
2026/10/12 04:24:10 DEBUG : test.sum: md5 = 7a2322b3b16e87eac923fa1d2a2e7e8c OK
=== RUN   TestCheckSumDownload/subtest2
2026/10/12 04:24:15 DEBUG : test.sum: md5 = 2a4348701794a2aacebf1ac745e4e9b0 OK
=== RUN   TestCheckSumDownload/subtest3
2026/10/12 04:24:19 DEBUG : test.sum: md5 = 5bc2e1b2988332d27285b4d4582d0d0a OK
=== RUN   TestCheckSumDownload/subtest4
2026/10/12 04:24:24 DEBUG : test.sum: md5 = bf67612ecd1b912dfdee7a12c1e375fa OK
=== RUN   TestCheckSumDownload/subtest5
2026/10/12 04:24:29 DEBUG : test.sum: md5 = 1880997b3f869d324168bf455d52cd53 OK
=== RUN   TestCheckSumDownload/subtest6
2026/10/12 04:24:34 DEBUG : data/banana: md5 = dc4cef8d2f2a01662c05daba04c94c9d OK
2026/10/12 04:24:35 DEBUG : data/potato: md5 = 0310184046e41ac6bd5d791f618d2ffc OK
2026/10/12 04:24:38 DEBUG : test.sum: md5 = 93603949b248b0173ec944eacd128b85 OK
=== RUN   TestCheckSumDownload/subtest7
--- PASS: TestCheckSumDownload (48.64s)
    --- PASS: TestCheckSumDownload/subtest1 (2.43s)
    --- PASS: TestCheckSumDownload/subtest2 (2.11s)
    --- PASS: TestCheckSumDownload/subtest3 (2.14s)
    --- PASS: TestCheckSumDownload/subtest4 (2.05s)
    --- PASS: TestCheckSumDownload/subtest5 (1.97s)
    --- PASS: TestCheckSumDownload/subtest6 (2.18s)
    --- PASS: TestCheckSumDownload/subtest7 (2.14s)
=== RUN   TestCheckSumConcurrency
2026/10/12 04:24:44 DEBUG : file0: md5 = 022eb0c0085c731f335df970fafa7446 OK
2026/10/12 04:24:44 DEBUG : file2: md5 = a58f09ae3db8ae4b943fdbaace096072 OK
2026/10/12 04:24:44 DEBUG : file1: md5 = 17fbabbccbdea0e6be9eb64c8c6fea41 OK
2026/10/12 04:24:44 DEBUG : file3: md5 = e0639b17e0744aca9a3ba29c049e59ee OK
2026/10/12 04:24:44 NOTICE: Mock file system at checkSum: 0 differences found
2026/10/12 04:24:44 NOTICE: Mock file system at checkSum: 4 matching files
--- PASS: TestCheckSumConcurrency (0.00s)
=== RUN   TestCheckSumDownloadConcurrency
2026/10/12 04:24:44 DEBUG : file0: md5 = 022eb0c0085c731f335df970fafa7446 OK
2026/10/12 04:24:44 DEBUG : file1: md5 = 17fbabbccbdea0e6be9eb64c8c6fea41 OK
2026/10/12 04:24:44 DEBUG : file2: md5 = a58f09ae3db8ae4b943fdbaace096072 OK
2026/10/12 04:24:44 DEBUG : file3: md5 = e0639b17e0744aca9a3ba29c049e59ee OK
2026/10/12 04:24:44 NOTICE: Mock file system at checkSum: 0 differences found
2026/10/12 04:24:44 NOTICE: Mock file system at checkSum: 4 matching files
--- PASS: TestCheckSumDownloadConcurrency (0.00s)
=== RUN   TestApplyTransforms
2026/10/12 04:24:44 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-sabupab5yexu"
2026/10/12 04:24:44 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/12 04:24:44 DEBUG : Creating backend with remote "TestDrive:crypt/puercbidqtvv0calfn8kgr8n7gesddbmbn2o3h0vji92mls6nacg"
2026/10/12 04:24:46 DEBUG : Creating backend with remote "/tmp/rclone3054713173"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-sabupab5yexu'", Local "Local file system at /tmp/rclone3054713173", Modify Window "1ms"
2026/10/12 04:24:49 DEBUG : hello, world!: md5 = 6625678cc9f8de2249f1f91292607e06 OK
upper checkfile vs. lower remote (without normalization)
2026/10/12 04:24:49 ERROR : hello, world!: sum not found
2026/10/12 04:24:49 ERROR : HELLO, WORLD!: file not in Encrypted drive 'TestCryptDrive:rclone-test-sabupab5yexu'
2026/10/12 04:24:49 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-sabupab5yexu': 1 files missing
2026/10/12 04:24:49 NOTICE: 1 hashes missing
2026/10/12 04:24:49 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-sabupab5yexu': 1 differences found
2026/10/12 04:24:49 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-sabupab5yexu': 2 errors while checking
upper checkfile vs. lower remote (with normalization)
2026/10/12 04:24:50 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/12 04:24:50 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-sabupab5yexu': 0 differences found
2026/10/12 04:24:50 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-sabupab5yexu': 1 matching files
2026/10/12 04:24:50 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-quconow6heve"
2026/10/12 04:24:50 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/12 04:24:51 DEBUG : Creating backend with remote "TestDrive:crypt/agi06j2tu57drkg9e7gjlmiql28u41raor8fhe8fk5jusmtbg610"
2026/10/12 04:24:52 DEBUG : Creating backend with remote "/tmp/rclone2101468675"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-quconow6heve'", Local "Local file system at /tmp/rclone2101468675", Modify Window "1ms"
2026/10/12 04:24:55 DEBUG : HELLO, WORLD!: md5 = a4f0e027143e1d982a04df458eb54370 OK
lower checkfile vs. upper remote (without normalization)
2026/10/12 04:24:55 ERROR : HELLO, WORLD!: sum not found
2026/10/12 04:24:55 ERROR : hello, world!: file not in Encrypted drive 'TestCryptDrive:rclone-test-quconow6heve'
2026/10/12 04:24:55 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-quconow6heve': 1 files missing
2026/10/12 04:24:55 NOTICE: 1 hashes missing
2026/10/12 04:24:55 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-quconow6heve': 1 differences found
2026/10/12 04:24:55 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-quconow6heve': 2 errors while checking
lower checkfile vs. upper remote (with normalization)
2026/10/12 04:24:56 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/12 04:24:56 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-quconow6heve': 0 differences found
2026/10/12 04:24:56 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-quconow6heve': 1 matching files
2026/10/12 04:24:56 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-kegirin7dika"
2026/10/12 04:24:56 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/12 04:24:56 DEBUG : Creating backend with remote "TestDrive:crypt/9g0ks2hhfo4a1ukp5odmom4bhdhomo8tati07bhkpn3rp9m1qphg"
2026/10/12 04:24:58 DEBUG : Creating backend with remote "/tmp/rclone2139449850"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kegirin7dika'", Local "Local file system at /tmp/rclone2139449850", Modify Window "1ms"
2026/10/12 04:25:01 DEBUG : HeLlO, wOrLd!: md5 = e5c4eb42415ae5da9c78cda9035536d0 OK
lower checkfile vs. upperlowermixed remote (without normalization)
2026/10/12 04:25:01 ERROR : HeLlO, wOrLd!: sum not found
2026/10/12 04:25:01 ERROR : hello, world!: file not in Encrypted drive 'TestCryptDrive:rclone-test-kegirin7dika'
2026/10/12 04:25:01 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kegirin7dika': 1 files missing
2026/10/12 04:25:01 NOTICE: 1 hashes missing
2026/10/12 04:25:01 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kegirin7dika': 1 differences found
2026/10/12 04:25:01 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kegirin7dika': 2 errors while checking
lower checkfile vs. upperlowermixed remote (with normalization)
2026/10/12 04:25:02 DEBUG : HeLlO, wOrLd!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/12 04:25:02 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kegirin7dika': 0 differences found
2026/10/12 04:25:02 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kegirin7dika': 1 matching files
2026/10/12 04:25:02 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-mefitac8suzu"
2026/10/12 04:25:02 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/12 04:25:02 DEBUG : Creating backend with remote "TestDrive:crypt/4mmcemud7rr7mgdl6jrhs1srsdapkisea6lti2aag1c8872hvobg"
2026/10/12 04:25:04 DEBUG : Creating backend with remote "/tmp/rclone1462279682"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mefitac8suzu'", Local "Local file system at /tmp/rclone1462279682", Modify Window "1ms"
2026/10/12 04:25:08 DEBUG : HELLO, WORLD!: md5 = ff43b8e8ed6e10c86256097c40aa0d87 OK
upperlowermixed checkfile vs. upper remote (without normalization)
2026/10/12 04:25:09 ERROR : HELLO, WORLD!: sum not found
2026/10/12 04:25:09 ERROR : HeLlO, wOrLd!: file not in Encrypted drive 'TestCryptDrive:rclone-test-mefitac8suzu'
2026/10/12 04:25:09 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-mefitac8suzu': 1 files missing
2026/10/12 04:25:09 NOTICE: 1 hashes missing
2026/10/12 04:25:09 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-mefitac8suzu': 1 differences found
2026/10/12 04:25:09 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-mefitac8suzu': 2 errors while checking
upperlowermixed checkfile vs. upper remote (with normalization)
2026/10/12 04:25:11 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/12 04:25:11 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-mefitac8suzu': 0 differences found
2026/10/12 04:25:11 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-mefitac8suzu': 1 matching files
2026/10/12 04:25:11 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-qapokew6qusa"
2026/10/12 04:25:11 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/12 04:25:11 DEBUG : Creating backend with remote "TestDrive:crypt/e7rqif35ebeh0oiu994uc3o9etg6v3umqdi1pkp8keo8bqj3qcig"
2026/10/12 04:25:12 DEBUG : Creating backend with remote "/tmp/rclone4007316708"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-qapokew6qusa'", Local "Local file system at /tmp/rclone4007316708", Modify Window "1ms"
2026/10/12 04:25:15 DEBUG : 測試_Русский___ě_áñ: md5 = 232bbe20bf5aa24904bfaeaa7a1a3baa OK
NFD checkfile vs. NFC remote (without normalization)
2026/10/12 04:25:16 ERROR : 測試_Русский___ě_áñ: sum not found
2026/10/12 04:25:16 ERROR : 測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-qapokew6qusa'
2026/10/12 04:25:16 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-qapokew6qusa': 1 files missing
2026/10/12 04:25:16 NOTICE: 1 hashes missing
2026/10/12 04:25:16 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-qapokew6qusa': 1 differences found
2026/10/12 04:25:16 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-qapokew6qusa': 2 errors while checking
NFD checkfile vs. NFC remote (with normalization)
2026/10/12 04:25:16 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/12 04:25:16 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-qapokew6qusa': 0 differences found
2026/10/12 04:25:16 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-qapokew6qusa': 1 matching files
2026/10/12 04:25:16 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-dipokis8celi"
2026/10/12 04:25:16 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/12 04:25:17 DEBUG : Creating backend with remote "TestDrive:crypt/t5ekf7afs8220795e4m4s3v6p9aif9e9f2s2hqq6rvf2ct67214g"
2026/10/12 04:25:18 DEBUG : Creating backend with remote "/tmp/rclone2740167871"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-dipokis8celi'", Local "Local file system at /tmp/rclone2740167871", Modify Window "1ms"
2026/10/12 04:25:21 DEBUG : 測試_Русский___ě_áñ: md5 = a14f6262586ecffbf9633b092a0abd4b OK
NFC checkfile vs. NFD remote (without normalization)
2026/10/12 04:25:22 ERROR : 測試_Русский___ě_áñ: sum not found
2026/10/12 04:25:22 ERROR : 測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-dipokis8celi'
2026/10/12 04:25:22 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-dipokis8celi': 1 files missing
2026/10/12 04:25:22 NOTICE: 1 hashes missing
2026/10/12 04:25:22 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-dipokis8celi': 1 differences found
2026/10/12 04:25:22 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-dipokis8celi': 2 errors while checking
NFC checkfile vs. NFD remote (with normalization)
2026/10/12 04:25:23 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/12 04:25:23 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-dipokis8celi': 0 differences found
2026/10/12 04:25:23 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-dipokis8celi': 1 matching files
2026/10/12 04:25:23 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-cuzenel1ziyo"
2026/10/12 04:25:23 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/12 04:25:23 DEBUG : Creating backend with remote "TestDrive:crypt/lefclm377373dhu99kjotpj8gjsntuc20mb0solblrdie95iqreg"
2026/10/12 04:25:24 DEBUG : Creating backend with remote "/tmp/rclone4240330387"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cuzenel1ziyo'", Local "Local file system at /tmp/rclone4240330387", Modify Window "1ms"
2026/10/12 04:25:27 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 987a52df6bb3a673cf67bed5ed9da6f2 OK
NFDx2 checkfile vs. both remote (without normalization)
2026/10/12 04:25:28 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found
2026/10/12 04:25:28 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-cuzenel1ziyo'
2026/10/12 04:25:28 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-cuzenel1ziyo': 1 files missing
2026/10/12 04:25:28 NOTICE: 1 hashes missing
2026/10/12 04:25:28 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-cuzenel1ziyo': 1 differences found
2026/10/12 04:25:28 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-cuzenel1ziyo': 2 errors while checking
NFDx2 checkfile vs. both remote (with normalization)
2026/10/12 04:25:29 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/12 04:25:29 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-cuzenel1ziyo': 0 differences found
2026/10/12 04:25:29 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-cuzenel1ziyo': 1 matching files
2026/10/12 04:25:29 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-relibot6babo"
2026/10/12 04:25:29 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/12 04:25:29 DEBUG : Creating backend with remote "TestDrive:crypt/bm6137vjkev8bbvusvput404na5aa57k9omsmdt6grtk7ptlmqd0"
2026/10/12 04:25:31 DEBUG : Creating backend with remote "/tmp/rclone3357678811"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-relibot6babo'", Local "Local file system at /tmp/rclone3357678811", Modify Window "1ms"
2026/10/12 04:25:34 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = af7ca0681c1d787ece7f058962498745 OK
NFCx2 checkfile vs. both remote (without normalization)
2026/10/12 04:25:34 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found
2026/10/12 04:25:34 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-relibot6babo'
2026/10/12 04:25:34 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-relibot6babo': 1 files missing
2026/10/12 04:25:34 NOTICE: 1 hashes missing
2026/10/12 04:25:34 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-relibot6babo': 1 differences found
2026/10/12 04:25:34 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-relibot6babo': 2 errors while checking
NFCx2 checkfile vs. both remote (with normalization)
2026/10/12 04:25:35 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/12 04:25:35 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-relibot6babo': 0 differences found
2026/10/12 04:25:35 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-relibot6babo': 1 matching files
2026/10/12 04:25:35 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-vacaxas3jaca"
2026/10/12 04:25:35 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/12 04:25:35 DEBUG : Creating backend with remote "TestDrive:crypt/kge168rqsvogfuirrg0bal80p91nuhosson3en5pbaeji01shtkg"
2026/10/12 04:25:37 DEBUG : Creating backend with remote "/tmp/rclone3297192625"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-vacaxas3jaca'", Local "Local file system at /tmp/rclone3297192625", Modify Window "1ms"
2026/10/12 04:25:40 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 3822e6f1c60cc0f505df8b98fe9b98f8 OK
both checkfile vs. NFDx2 remote (without normalization)
2026/10/12 04:25:41 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found
2026/10/12 04:25:41 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-vacaxas3jaca'
2026/10/12 04:25:41 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-vacaxas3jaca': 1 files missing
2026/10/12 04:25:41 NOTICE: 1 hashes missing
2026/10/12 04:25:41 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-vacaxas3jaca': 1 differences found
2026/10/12 04:25:41 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-vacaxas3jaca': 2 errors while checking
both checkfile vs. NFDx2 remote (with normalization)
2026/10/12 04:25:42 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/12 04:25:42 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-vacaxas3jaca': 0 differences found
2026/10/12 04:25:42 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-vacaxas3jaca': 1 matching files
2026/10/12 04:25:42 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-noyuquy2goqe"
2026/10/12 04:25:42 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/12 04:25:42 DEBUG : Creating backend with remote "TestDrive:crypt/2mgnom50o6s1umifk0amm88mlb0i0krti56v9m07c9k0c8ihcg7g"
2026/10/12 04:25:43 DEBUG : Creating backend with remote "/tmp/rclone2493763741"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-noyuquy2goqe'", Local "Local file system at /tmp/rclone2493763741", Modify Window "1ms"
2026/10/12 04:25:46 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = c505bdd128f184313d664c781666af31 OK
both checkfile vs. NFCx2 remote (without normalization)
2026/10/12 04:25:47 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found
2026/10/12 04:25:47 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-noyuquy2goqe'
2026/10/12 04:25:47 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-noyuquy2goqe': 1 files missing
2026/10/12 04:25:47 NOTICE: 1 hashes missing
2026/10/12 04:25:47 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-noyuquy2goqe': 1 differences found
2026/10/12 04:25:47 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-noyuquy2goqe': 2 errors while checking
both checkfile vs. NFCx2 remote (with normalization)
2026/10/12 04:25:48 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/12 04:25:48 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-noyuquy2goqe': 0 differences found
2026/10/12 04:25:48 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-noyuquy2goqe': 1 matching files
2026/10/12 04:25:48 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-noyuquy2goqe': Purge remote
2026/10/12 04:25:49 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-vacaxas3jaca': Purge remote
2026/10/12 04:25:50 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-relibot6babo': Purge remote
2026/10/12 04:25:50 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cuzenel1ziyo': Purge remote
2026/10/12 04:25:51 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-dipokis8celi': Purge remote
2026/10/12 04:25:52 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-qapokew6qusa': Purge remote
2026/10/12 04:25:52 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-mefitac8suzu': Purge remote
2026/10/12 04:25:53 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-kegirin7dika': Purge remote
2026/10/12 04:25:54 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-quconow6heve': Purge remote
2026/10/12 04:25:55 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-sabupab5yexu': Purge remote
--- PASS: TestApplyTransforms (70.99s)
=== RUN   TestTruncateString
--- PASS: TestTruncateString (0.00s)
=== RUN   TestCopyFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:25:56 DEBUG : file1: Need to transfer - File not found at Destination
2026/10/12 04:25:59 DEBUG : sub/file2: md5 = e43d9f46685b2c3a2802dcb0c021d2ed OK
2026/10/12 04:25:59 DEBUG : sub/file2: size = 14 OK
2026/10/12 04:25:59 INFO  : file1: Copied (new) to: sub/file2
2026/10/12 04:26:00 DEBUG : sub/file2: size = 14 OK
2026/10/12 04:26:00 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2026/10/12 04:26:00 DEBUG : file1: Unchanged skipping
2026/10/12 04:26:01 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe': don't need to copy/move sub/file2, it is already at target location
--- PASS: TestCopyFile (8.84s)
=== RUN   TestCopyFileImmutable
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:26:05 DEBUG : existing: Need to transfer - File not found at Destination
2026/10/12 04:26:06 DEBUG : existing: md5 = dca22efabae9d365b960355d4bda7797 OK
2026/10/12 04:26:06 DEBUG : existing: size = 6 OK
2026/10/12 04:26:06 INFO  : existing: Copied (new)
2026/10/12 04:26:07 DEBUG : existing: size = 6 OK
2026/10/12 04:26:07 DEBUG : existing: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2026/10/12 04:26:07 DEBUG : existing: Unchanged skipping
2026/10/12 04:26:08 DEBUG : existing: size = 8 (Local file system at /tmp/rclone3458124860)
2026/10/12 04:26:08 DEBUG : existing: size = 6 (Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe')
2026/10/12 04:26:08 DEBUG : existing: Sizes differ
2026/10/12 04:26:08 ERROR : existing: Source and destination exist but do not match: immutable file modified
--- PASS: TestCopyFileImmutable (5.92s)
=== RUN   TestCopyLongFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
    copy_test.go:188: Test only runs on local
--- SKIP: TestCopyLongFile (0.62s)
=== RUN   TestCopyFileBackupDir
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:26:14 DEBUG : dst/file1: md5 = 364c98d585bcdca28c3fff1e34cf2b6d OK
2026/10/12 04:26:15 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-soyebah2yafe/backup"
2026/10/12 04:26:15 DEBUG : Creating backend with remote "TestDrive:crypt/3lr4390jip2q9g8ao5ds7vf9as0a7itvio95vb7qd4i7vahepmpg/1nrff024r7pq65ecp72fc28jb0"
2026/10/12 04:26:18 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone3458124860)
2026/10/12 04:26:18 DEBUG : dst/file1: size = 18 (Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe')
2026/10/12 04:26:18 DEBUG : dst/file1: Sizes differ
2026/10/12 04:26:21 INFO  : dst/file1: Moved (server-side)
2026/10/12 04:26:23 DEBUG : dst/file1: md5 = 90c3c10b474bafc261bde97b70545dd5 OK
2026/10/12 04:26:23 DEBUG : dst/file1: size = 14 OK
2026/10/12 04:26:23 INFO  : dst/file1: Copied (new)
--- PASS: TestCopyFileBackupDir (18.28s)
=== RUN   TestCopyFileCompareDest
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:26:29 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-soyebah2yafe/dst"
2026/10/12 04:26:29 DEBUG : Creating backend with remote "TestDrive:crypt/3lr4390jip2q9g8ao5ds7vf9as0a7itvio95vb7qd4i7vahepmpg/31u3jie661vd5p8j7rtc3hgbh0"
2026/10/12 04:26:31 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-soyebah2yafe/CompareDest"
2026/10/12 04:26:32 DEBUG : Creating backend with remote "TestDrive:crypt/3lr4390jip2q9g8ao5ds7vf9as0a7itvio95vb7qd4i7vahepmpg/gveqi14airsml4bgu7krj116o8"
2026/10/12 04:26:34 DEBUG : one: Need to transfer - File not found at Destination
2026/10/12 04:26:37 DEBUG : one: md5 = fb758f894e3876ed82b4d229acbe87f5 OK
2026/10/12 04:26:37 DEBUG : one: size = 3 OK
2026/10/12 04:26:37 INFO  : one: Copied (new)
2026/10/12 04:26:39 DEBUG : one: size = 5 (Local file system at /tmp/rclone3458124860)
2026/10/12 04:26:39 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe/dst')
2026/10/12 04:26:39 DEBUG : one: Sizes differ
2026/10/12 04:26:41 DEBUG : one: md5 = f1bbb6313e288ea2b0177f315fea5480 OK
2026/10/12 04:26:41 DEBUG : one: size = 5 OK
2026/10/12 04:26:41 INFO  : one: Copied (replaced existing)
2026/10/12 04:26:43 DEBUG : dst/one: md5 = 3e0b89754f2d00d02fb5ca38961df685 OK
2026/10/12 04:26:47 DEBUG : CompareDest/one: md5 = 9b14e54bebee34951c7b9cb0bd44dda8 OK
2026/10/12 04:26:48 DEBUG : one: size = 5 (Local file system at /tmp/rclone3458124860)
2026/10/12 04:26:48 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe/dst')
2026/10/12 04:26:48 DEBUG : one: Sizes differ
2026/10/12 04:26:49 DEBUG : one: size = 5 OK
2026/10/12 04:26:49 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/10/12 04:26:49 DEBUG : one: Destination found in --compare-dest, skipping
2026/10/12 04:26:51 DEBUG : CompareDest/two: md5 = 007c955a38d3fed0f285e07b25bc3580 OK
2026/10/12 04:26:52 DEBUG : two: Need to transfer - File not found at Destination
2026/10/12 04:26:53 DEBUG : two: size = 3 OK
2026/10/12 04:26:53 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/10/12 04:26:53 DEBUG : two: Destination found in --compare-dest, skipping
2026/10/12 04:26:54 DEBUG : two: Need to transfer - File not found at Destination
2026/10/12 04:26:54 DEBUG : two: size = 3 OK
2026/10/12 04:26:54 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/10/12 04:26:54 DEBUG : two: Destination found in --compare-dest, skipping
2026/10/12 04:26:56 DEBUG : two: Need to transfer - File not found at Destination
2026/10/12 04:26:56 DEBUG : two: size = 5 (Local file system at /tmp/rclone3458124860)
2026/10/12 04:26:56 DEBUG : two: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe/CompareDest')
2026/10/12 04:26:56 DEBUG : two: Sizes differ
2026/10/12 04:26:58 DEBUG : two: md5 = 7d3e9971a556517e165c127f11500592 OK
2026/10/12 04:26:58 DEBUG : two: size = 5 OK
2026/10/12 04:26:58 INFO  : two: Copied (new)
--- PASS: TestCopyFileCompareDest (35.15s)
=== RUN   TestCopyFileCopyDestImmutable
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:27:04 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-soyebah2yafe/dst"
2026/10/12 04:27:04 DEBUG : Creating backend with remote "TestDrive:crypt/3lr4390jip2q9g8ao5ds7vf9as0a7itvio95vb7qd4i7vahepmpg/31u3jie661vd5p8j7rtc3hgbh0"
2026/10/12 04:27:09 DEBUG : dst/one: md5 = 77506e24738c643c6b928c9101d125a7 OK
2026/10/12 04:27:12 DEBUG : CopyDest/one: md5 = a9236fc4dd293ced72e41c907a275ee1 OK
2026/10/12 04:27:14 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-soyebah2yafe/CopyDest"
2026/10/12 04:27:14 DEBUG : Creating backend with remote "TestDrive:crypt/3lr4390jip2q9g8ao5ds7vf9as0a7itvio95vb7qd4i7vahepmpg/d09o6po3f7bm6ce32vdgs8h9ls"
2026/10/12 04:27:15 DEBUG : one: size = 5 (Local file system at /tmp/rclone3458124860)
2026/10/12 04:27:15 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe/dst')
2026/10/12 04:27:15 DEBUG : one: Sizes differ
2026/10/12 04:27:15 DEBUG : one: size = 5 OK
2026/10/12 04:27:15 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/10/12 04:27:15 DEBUG : one: size = 5 (Local file system at /tmp/rclone3458124860)
2026/10/12 04:27:15 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe/dst')
2026/10/12 04:27:15 DEBUG : one: Sizes differ
2026/10/12 04:27:15 ERROR : one: Source and destination exist but do not match: immutable file modified
--- PASS: TestCopyFileCopyDestImmutable (16.61s)
=== RUN   TestCopyFileCopyDest
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:27:21 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-soyebah2yafe/dst"
2026/10/12 04:27:21 DEBUG : Creating backend with remote "TestDrive:crypt/3lr4390jip2q9g8ao5ds7vf9as0a7itvio95vb7qd4i7vahepmpg/31u3jie661vd5p8j7rtc3hgbh0"
2026/10/12 04:27:23 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-soyebah2yafe/CopyDest"
2026/10/12 04:27:23 DEBUG : Creating backend with remote "TestDrive:crypt/3lr4390jip2q9g8ao5ds7vf9as0a7itvio95vb7qd4i7vahepmpg/d09o6po3f7bm6ce32vdgs8h9ls"
2026/10/12 04:27:25 DEBUG : one: Need to transfer - File not found at Destination
2026/10/12 04:27:29 DEBUG : one: md5 = 91291a8d6a9b7ab97868d137d5e71826 OK
2026/10/12 04:27:29 DEBUG : one: size = 3 OK
2026/10/12 04:27:29 INFO  : one: Copied (new)
2026/10/12 04:27:31 DEBUG : one: size = 5 (Local file system at /tmp/rclone3458124860)
2026/10/12 04:27:31 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe/dst')
2026/10/12 04:27:31 DEBUG : one: Sizes differ
2026/10/12 04:27:33 DEBUG : one: md5 = cbf41a3d554e1801387b2a8ad58e3e52 OK
2026/10/12 04:27:33 DEBUG : one: size = 5 OK
2026/10/12 04:27:33 INFO  : one: Copied (replaced existing)
2026/10/12 04:27:35 DEBUG : dst/one: md5 = 9982fcf2871c4b42bd214a61e4a8f12a OK
2026/10/12 04:27:39 DEBUG : CopyDest/one: md5 = 46c77ff458b05f85cd45978bd77066de OK
2026/10/12 04:27:40 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-soyebah2yafe/BackupDir"
2026/10/12 04:27:40 DEBUG : Creating backend with remote "TestDrive:crypt/3lr4390jip2q9g8ao5ds7vf9as0a7itvio95vb7qd4i7vahepmpg/s6dbk3lfi7c9kfvo6j7bla9m0g"
2026/10/12 04:27:42 DEBUG : one: size = 5 (Local file system at /tmp/rclone3458124860)
2026/10/12 04:27:42 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe/dst')
2026/10/12 04:27:42 DEBUG : one: Sizes differ
2026/10/12 04:27:42 DEBUG : one: size = 5 OK
2026/10/12 04:27:42 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/10/12 04:27:42 DEBUG : one: size = 5 (Local file system at /tmp/rclone3458124860)
2026/10/12 04:27:42 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe/dst')
2026/10/12 04:27:42 DEBUG : one: Sizes differ
2026/10/12 04:27:45 INFO  : one: Moved (server-side)
2026/10/12 04:27:46 DEBUG : one: size = 5 OK
2026/10/12 04:27:46 INFO  : one: Copied (server-side copy)
2026/10/12 04:27:46 DEBUG : one: Destination found in --copy-dest, using server-side copy
2026/10/12 04:27:49 DEBUG : CopyDest/two: md5 = adcb9254b348e2eca48f214c00dac58d OK
2026/10/12 04:27:50 DEBUG : two: Need to transfer - File not found at Destination
2026/10/12 04:27:51 DEBUG : two: size = 3 OK
2026/10/12 04:27:51 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/10/12 04:27:52 DEBUG : two: size = 3 OK
2026/10/12 04:27:52 INFO  : two: Copied (server-side copy)
2026/10/12 04:27:52 DEBUG : two: Destination found in --copy-dest, using server-side copy
2026/10/12 04:27:53 DEBUG : two: size = 3 OK
2026/10/12 04:27:53 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/10/12 04:27:53 DEBUG : two: Unchanged skipping
2026/10/12 04:27:56 DEBUG : CopyDest/three: md5 = 463be310684a6eda67872af99ab0b2b9 OK
2026/10/12 04:27:57 DEBUG : three: Need to transfer - File not found at Destination
2026/10/12 04:27:57 DEBUG : three: size = 7 (Local file system at /tmp/rclone3458124860)
2026/10/12 04:27:57 DEBUG : three: size = 5 (Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe/CopyDest')
2026/10/12 04:27:57 DEBUG : three: Sizes differ
2026/10/12 04:27:57 DEBUG : three: Destination not found in --copy-dest
2026/10/12 04:27:59 DEBUG : three: md5 = 4ef0cd7d2c4ddfb128b71d2c384b97c0 OK
2026/10/12 04:27:59 DEBUG : three: size = 7 OK
2026/10/12 04:27:59 INFO  : three: Copied (new)
--- PASS: TestCopyFileCopyDest (48.48s)
=== RUN   TestCopyInplace
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
    copy_test.go:434: Partial uploads not supported
--- SKIP: TestCopyInplace (0.66s)
=== RUN   TestCopyLongFileName
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
    copy_test.go:467: Partial uploads not supported
--- SKIP: TestCopyLongFileName (0.66s)
=== RUN   TestCopyLongFileNameCollision
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
    copy_test.go:500: Partial uploads not supported
--- SKIP: TestCopyLongFileNameCollision (0.59s)
=== RUN   TestCopyFileMaxTransfer
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:28:11 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination
2026/10/12 04:28:15 DEBUG : TestCopyFileMaxTransfer/file1: md5 = 43dd35ca51ef98173f36c01c35ef1d6b OK
2026/10/12 04:28:15 DEBUG : TestCopyFileMaxTransfer/file1: size = 14 OK
2026/10/12 04:28:15 INFO  : TestCopyFileMaxTransfer/file1: Copied (new)
2026/10/12 04:28:16 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination
2026/10/12 04:28:16 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/12 04:28:17 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination
2026/10/12 04:28:18 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination
2026/10/12 04:28:20 DEBUG : TestCopyFileMaxTransfer/file4: md5 = 81d73c4118ccd2b42e03bacc02729a4a OK
2026/10/12 04:28:20 DEBUG : TestCopyFileMaxTransfer/file4: size = 2062 OK
2026/10/12 04:28:20 INFO  : TestCopyFileMaxTransfer/file4: Copied (new)
--- PASS: TestCopyFileMaxTransfer (12.74s)
=== RUN   TestDeduplicateInteractive
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
    dedupe_test.go:37: Can't run this test without a hash
--- SKIP: TestDeduplicateInteractive (0.57s)
=== RUN   TestDeduplicateSkip
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:28:29 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe': Looking for duplicate names using skip mode.
2026/10/12 04:28:29 NOTICE: one: Found 2 files with duplicate names
2026/10/12 04:28:29 NOTICE: one: Skipping 2 files with duplicate names
--- PASS: TestDeduplicateSkip (6.92s)
=== RUN   TestDeduplicateSizeOnly
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:28:37 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe': Looking for duplicate names using skip mode.
2026/10/12 04:28:38 NOTICE: one: Found 3 files with duplicate names
2026/10/12 04:28:38 NOTICE: one: Deleting 1/2 identical duplicates (size 11)
2026/10/12 04:28:38 INFO  : one: Deleted
2026/10/12 04:28:38 NOTICE: one: Skipping 2 files with duplicate names
--- PASS: TestDeduplicateSizeOnly (9.28s)
=== RUN   TestDeduplicateFirst
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:28:46 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe': Looking for duplicate names using first mode.
2026/10/12 04:28:46 NOTICE: one: Found 3 files with duplicate names
2026/10/12 04:28:47 INFO  : one: Deleted
2026/10/12 04:28:48 INFO  : one: Deleted
2026/10/12 04:28:48 NOTICE: one: Deleted 2 extra copies
--- PASS: TestDeduplicateFirst (8.72s)
=== RUN   TestDeduplicateNewest
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:28:54 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe': Looking for duplicate names using newest mode.
2026/10/12 04:28:55 NOTICE: one: Found 3 files with duplicate names
2026/10/12 04:28:55 INFO  : one: Deleted
2026/10/12 04:28:56 INFO  : one: Deleted
2026/10/12 04:28:56 NOTICE: one: Deleted 2 extra copies
--- PASS: TestDeduplicateNewest (8.51s)
=== RUN   TestDeduplicateNewestByHash
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
    dedupe_test.go:37: Can't run this test without a hash
--- SKIP: TestDeduplicateNewestByHash (0.55s)
=== RUN   TestDeduplicateOldest
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:29:04 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe': Looking for duplicate names using oldest mode.
2026/10/12 04:29:04 NOTICE: one: Found 3 files with duplicate names
2026/10/12 04:29:05 INFO  : one: Deleted
2026/10/12 04:29:05 INFO  : one: Deleted
2026/10/12 04:29:05 NOTICE: one: Deleted 2 extra copies
--- PASS: TestDeduplicateOldest (8.61s)
=== RUN   TestDeduplicateLargest
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:29:12 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe': Looking for duplicate names using largest mode.
2026/10/12 04:29:13 NOTICE: one: Found 3 files with duplicate names
2026/10/12 04:29:13 INFO  : one: Deleted
2026/10/12 04:29:14 INFO  : one: Deleted
2026/10/12 04:29:14 NOTICE: one: Deleted 2 extra copies
--- PASS: TestDeduplicateLargest (8.65s)
=== RUN   TestDeduplicateSmallest
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:29:21 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe': Looking for duplicate names using smallest mode.
2026/10/12 04:29:22 NOTICE: one: Found 3 files with duplicate names
2026/10/12 04:29:22 INFO  : one: Deleted
2026/10/12 04:29:23 INFO  : one: Deleted
2026/10/12 04:29:23 NOTICE: one: Deleted 2 extra copies
--- PASS: TestDeduplicateSmallest (8.82s)
=== RUN   TestDeduplicateRename
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:29:31 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe': Looking for duplicate names using rename mode.
2026/10/12 04:29:32 NOTICE: one.txt: Found 3 files with duplicate names
2026/10/12 04:29:33 INFO  : one-2.txt: renamed from: one.txt
2026/10/12 04:29:34 INFO  : one-3.txt: renamed from: one.txt
2026/10/12 04:29:35 INFO  : one-4.txt: renamed from: one.txt
--- PASS: TestDeduplicateRename (13.47s)
=== RUN   TestDeduplicateRenameManyExisting
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:29:40 DEBUG : one-1.txt: md5 = 87121d30bfa086d776dce744cf59720c OK
2026/10/12 04:29:43 DEBUG : one-2.txt: md5 = 8a0a1360e1feaefb1f85a8b55138ecab OK
2026/10/12 04:29:45 DEBUG : one-3.txt: md5 = a41b36454ac8c1c0fd4035bede911b5e OK
2026/10/12 04:29:46 DEBUG : one-4.txt: md5 = 0673f9acaae9b130ee50fe562d795b4d OK
2026/10/12 04:29:48 DEBUG : one-5.txt: md5 = da770e9f59b849efbbd528c4e08042e6 OK
2026/10/12 04:29:50 DEBUG : one-6.txt: md5 = 131ddf2a25d7efce2fbbd79e5ab35ea0 OK
2026/10/12 04:29:52 DEBUG : one-7.txt: md5 = 51354d954b999232d2f69c59e490e131 OK
2026/10/12 04:29:54 DEBUG : one-8.txt: md5 = 3ddc6955388147ee156380755cc7f182 OK
2026/10/12 04:29:55 DEBUG : one-9.txt: md5 = c27711e3f83386ecd4576d1ded620d4f OK
2026/10/12 04:29:57 DEBUG : one-10.txt: md5 = 922b27799bc2a4a1647b925c31b62ad0 OK
2026/10/12 04:29:59 DEBUG : one-11.txt: md5 = c44384c155d2a46284d518fc801929d8 OK
2026/10/12 04:30:01 DEBUG : one-12.txt: md5 = 15d0845596994140f39ce37043eec1a2 OK
2026/10/12 04:30:03 DEBUG : one-13.txt: md5 = b07e10d1dad4b520e34144f0b215a481 OK
2026/10/12 04:30:05 DEBUG : one-14.txt: md5 = a7755d5e95f14fb230af260277f264b0 OK
2026/10/12 04:30:07 DEBUG : one-15.txt: md5 = 8cd7fb751a141dca043775dd49a4d51d OK
2026/10/12 04:30:08 DEBUG : one-16.txt: md5 = 0180d9ff2689a74c6576924eebbd6ad6 OK
2026/10/12 04:30:10 DEBUG : one-17.txt: md5 = 2acf2cef2762bd0a450d98df221ebaa6 OK
2026/10/12 04:30:12 DEBUG : one-18.txt: md5 = b609c7e3a3e5f27fa655badeaae8c92c OK
2026/10/12 04:30:14 DEBUG : one-19.txt: md5 = a15dd8a6c106ff0000ec9426e49e61d2 OK
2026/10/12 04:30:16 DEBUG : one-20.txt: md5 = be904358a7fbc12b696684adb045cffe OK
2026/10/12 04:30:18 DEBUG : one-21.txt: md5 = 1c0d425156c97083ea1ba5a9903aba2f OK
2026/10/12 04:30:20 DEBUG : one-22.txt: md5 = 405027924aaa2c3d25d5d458e3636c8d OK
2026/10/12 04:30:22 DEBUG : one-23.txt: md5 = 9900c67c3b183382916953a96c85a1a1 OK
2026/10/12 04:30:24 DEBUG : one-24.txt: md5 = 90724f64c4b079c9b09a92539ca87df2 OK
2026/10/12 04:30:25 DEBUG : one-25.txt: md5 = 6a251a36f7fe304731e3461a17b5b171 OK
2026/10/12 04:30:27 DEBUG : one-26.txt: md5 = 96fafeb03c1a37a9870786e9e46cee8e OK
2026/10/12 04:30:29 DEBUG : one-27.txt: md5 = 038f9e9dd56dad1be768a9d0da128dff OK
2026/10/12 04:30:31 DEBUG : one-28.txt: md5 = f110c7044146f3736d37644bef24b5a9 OK
2026/10/12 04:30:33 DEBUG : one-29.txt: md5 = 529bcc4d9ff0ee638dbbfec6343d8870 OK
2026/10/12 04:30:35 DEBUG : one-30.txt: md5 = 34e6e75cc763367b58c6c37b1acd650e OK
2026/10/12 04:30:36 DEBUG : one-31.txt: md5 = e5cfd17ca587b6e9afbccdf4556da977 OK
2026/10/12 04:30:38 DEBUG : one-32.txt: md5 = 238692a837f82fadc26d481f105e4d63 OK
2026/10/12 04:30:40 DEBUG : one-33.txt: md5 = 784d9a67b990b74ba551165b0d90e3bf OK
2026/10/12 04:30:42 DEBUG : one-34.txt: md5 = 8cf818ca0dcd1ad4b9ca40fcbfc1b5fe OK
2026/10/12 04:30:44 DEBUG : one-35.txt: md5 = 0906bca6c8a2796c0a66746542743da7 OK
2026/10/12 04:30:46 DEBUG : one-36.txt: md5 = 352e4c7b38e2b3a54301015f73cb6803 OK
2026/10/12 04:30:48 DEBUG : one-37.txt: md5 = b07d0be9ebca18f4e906389569252e36 OK
2026/10/12 04:30:50 DEBUG : one-38.txt: md5 = 30148067a62d9312b73c73c5e8919b6b OK
2026/10/12 04:30:52 DEBUG : one-39.txt: md5 = 94eccf9429285c0c438cfc75a49ce2dc OK
2026/10/12 04:30:54 DEBUG : one-40.txt: md5 = d976aa20f4b903161502ecc07e1657f8 OK
2026/10/12 04:30:56 DEBUG : one-41.txt: md5 = b9927cc40604f5e7f7be634d8a864513 OK
2026/10/12 04:30:58 DEBUG : one-42.txt: md5 = c17b169c9a5843fd39547078d5568ead OK
2026/10/12 04:31:00 DEBUG : one-43.txt: md5 = ebb70ee0533c10310b327b2c6882d2f7 OK
2026/10/12 04:31:02 DEBUG : one-44.txt: md5 = 9d5ad889ec3b69c414fd837a03b2460c OK
2026/10/12 04:31:04 DEBUG : one-45.txt: md5 = db5a9d1f9711dee1a4847f9a066f7c36 OK
2026/10/12 04:31:05 DEBUG : one-46.txt: md5 = 62899be5545633e32ca9cb32e8d4c7fe OK
2026/10/12 04:31:07 DEBUG : one-47.txt: md5 = 6b677817164ad75ba8642b1f44ffb012 OK
2026/10/12 04:31:09 DEBUG : one-48.txt: md5 = 2c17d1a65ac4aeca03911381dcb0dc38 OK
2026/10/12 04:31:11 DEBUG : one-49.txt: md5 = 99e38ee912744f7acd2738dd19a116b4 OK
2026/10/12 04:31:12 DEBUG : one-50.txt: md5 = 8858e9cc184d4cc55698c07a8615d118 OK
2026/10/12 04:31:14 DEBUG : one-51.txt: md5 = 1d31b2cba2f550600ac35863b0a751cd OK
2026/10/12 04:31:16 DEBUG : one-52.txt: md5 = 55dc79916fb13d5dd5f085e162f8967d OK
2026/10/12 04:31:18 DEBUG : one-53.txt: md5 = 900273604faadb966b2b5a597a2f3c7d OK
2026/10/12 04:31:20 DEBUG : one-54.txt: md5 = 0383b20fea89830ddb4fb88016025f76 OK
2026/10/12 04:31:22 DEBUG : one-55.txt: md5 = 846079977f90e1e290c1fb3ebd5be141 OK
2026/10/12 04:31:24 DEBUG : one-56.txt: md5 = c3ee3997c640809688f0901072b1987d OK
2026/10/12 04:31:26 DEBUG : one-57.txt: md5 = 6ece8a666b5a6da26f72731730684938 OK
2026/10/12 04:31:28 DEBUG : one-58.txt: md5 = 73595004180a4c71a6d6f396ed8722a5 OK
2026/10/12 04:31:29 DEBUG : one-59.txt: md5 = fac0d5442b0309f1e192ecab675c2985 OK
2026/10/12 04:31:31 DEBUG : one-60.txt: md5 = df4b9548c57d8f72aa54bbf8f0cfd7c5 OK
2026/10/12 04:31:33 DEBUG : one-61.txt: md5 = 4a0a27101d3c3a23cb218c7ca6f30ba9 OK
2026/10/12 04:31:35 DEBUG : one-62.txt: md5 = dfb859eda192ae0be45bfc69fdd479b9 OK
2026/10/12 04:31:36 DEBUG : one-63.txt: md5 = c1d9333cad83ed0dda842b903e2dd65c OK
2026/10/12 04:31:39 DEBUG : one-64.txt: md5 = 116e09e75b589e8e32491d308ab079c1 OK
2026/10/12 04:31:41 DEBUG : one-65.txt: md5 = ec722184765821cc34fbe3b22817e135 OK
2026/10/12 04:31:43 DEBUG : one-66.txt: md5 = 6f971b07069ce28887832d1fd0eb2fe8 OK
2026/10/12 04:31:45 DEBUG : one-67.txt: md5 = 8d83bae8f46fa3f6dd5f9e73833986b2 OK
2026/10/12 04:31:47 DEBUG : one-68.txt: md5 = cc1406f7f894b5348b3d92431ded83ec OK
2026/10/12 04:31:49 DEBUG : one-69.txt: md5 = d5e58ff681e5481fcc873ecd675b974e OK
2026/10/12 04:31:51 DEBUG : one-70.txt: md5 = 4811b9171c5cfb45519bda5168c2b84b OK
2026/10/12 04:31:53 DEBUG : one-71.txt: md5 = cd51e191e1da67879e9fcba32550664d OK
2026/10/12 04:31:55 DEBUG : one-72.txt: md5 = 4a6b8dff2f57c525ef27cdb29f59ae52 OK
2026/10/12 04:31:56 DEBUG : one-73.txt: md5 = 876988f10a298884cb42be4905116df9 OK
2026/10/12 04:31:58 DEBUG : one-74.txt: md5 = eb8de95345a5d54e0769866d236e8a39 OK
2026/10/12 04:32:00 DEBUG : one-75.txt: md5 = 6e9df3649c1ca255436dae795bb65691 OK
2026/10/12 04:32:02 DEBUG : one-76.txt: md5 = 5d193bee7bc7dfba2e80dae8fada7a1e OK
2026/10/12 04:32:04 DEBUG : one-77.txt: md5 = 2faeda0e0b44aa7b4ec7012474f70a2b OK
2026/10/12 04:32:06 DEBUG : one-78.txt: md5 = 5f032591b875f2213967cddd89a70adf OK
2026/10/12 04:32:07 DEBUG : one-79.txt: md5 = ef1780d248cabd9927cc18c5ce78256d OK
2026/10/12 04:32:09 DEBUG : one-80.txt: md5 = 1628eb0311ea6b4779179fe40ba20144 OK
2026/10/12 04:32:12 DEBUG : one-81.txt: md5 = 6612c71ab9519adb62c0388d68a7fefb OK
2026/10/12 04:32:15 DEBUG : one-82.txt: md5 = 8b640c9626aaaae3812e169df33764ea OK
2026/10/12 04:32:17 DEBUG : one-83.txt: md5 = 353a7064f5b722b7c620adbef6795f4b OK
2026/10/12 04:32:19 DEBUG : one-84.txt: md5 = 7040cd30161bda2b130c1856472c7214 OK
2026/10/12 04:32:21 DEBUG : one-85.txt: md5 = 9b73daec43a9c3550779eb5e45ddda28 OK
2026/10/12 04:32:22 DEBUG : one-86.txt: md5 = 68cc3ce45655c1762085e22e878af4f4 OK
2026/10/12 04:32:24 DEBUG : one-87.txt: md5 = fa99926839c2d6733864e7f46615b946 OK
2026/10/12 04:32:26 DEBUG : one-88.txt: md5 = e504aa5c7864c042693e035f0c1b6c27 OK
2026/10/12 04:32:28 DEBUG : one-89.txt: md5 = d8e9ea887e8854fdfee05527151fffa8 OK
2026/10/12 04:32:30 DEBUG : one-90.txt: md5 = 95f73de86c55554136b33ac127685fc1 OK
2026/10/12 04:32:32 DEBUG : one-91.txt: md5 = b7808bbc0360935c4063ad0be909c59c OK
2026/10/12 04:32:34 DEBUG : one-92.txt: md5 = 402c5dec73cbb3c2908ffa21f5d18586 OK
2026/10/12 04:32:36 DEBUG : one-93.txt: md5 = a8fd8c39824aa3c59befe8ac93738546 OK
2026/10/12 04:32:37 DEBUG : one-94.txt: md5 = 167238ae1261fca56a393dc5cead41e1 OK
2026/10/12 04:32:39 DEBUG : one-95.txt: md5 = fb2bd029a74be43ae4d6f16d3c2ff88e OK
2026/10/12 04:32:41 DEBUG : one-96.txt: md5 = 9f6ef5703803150bfc69b158db9e83e6 OK
2026/10/12 04:32:43 DEBUG : one-97.txt: md5 = 9f15e45515b22a1d9b6895601989935e OK
2026/10/12 04:32:45 DEBUG : one-98.txt: md5 = 77965d5f22b5e46d90af03995789fe69 OK
2026/10/12 04:32:47 DEBUG : one-99.txt: md5 = 2583a4c44a97a6803364b5c099e428d0 OK
2026/10/12 04:32:49 DEBUG : one-100.txt: md5 = 1480229546ab1284f508997abf1c6cb3 OK
2026/10/12 04:32:51 DEBUG : one-101.txt: md5 = c152cc85ff9911c993238420d9331c01 OK
2026/10/12 04:32:53 DEBUG : one-102.txt: md5 = 2f1d0480f5cdd1e5d65613b98e501d67 OK
2026/10/12 04:32:55 DEBUG : one-103.txt: md5 = 6e45802b8c6507c09f1b15595275c2a3 OK
2026/10/12 04:32:57 DEBUG : one-104.txt: md5 = 4d6dabe505ddcd1a72b1ce2d2beec721 OK
2026/10/12 04:32:59 DEBUG : one-105.txt: md5 = 9e36358f87364698d059952840e72c81 OK
2026/10/12 04:33:02 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe': Looking for duplicate names using rename mode.
2026/10/12 04:33:03 NOTICE: one.txt: Found 2 files with duplicate names
2026/10/12 04:33:04 INFO  : one-106.txt: renamed from: one.txt
2026/10/12 04:33:05 INFO  : one-107.txt: renamed from: one.txt
--- PASS: TestDeduplicateRenameManyExisting (280.99s)
=== RUN   TestMergeDirs
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:34:22 DEBUG : dupe1/one.txt: md5 = a1d7cb2dd44221b32bf5426172bbdda6 OK
2026/10/12 04:34:25 DEBUG : dupe2/two.txt: md5 = c13e3a2158671ef94d57bd33383de995 OK
2026/10/12 04:34:28 DEBUG : dupe3/three.txt: md5 = 8abce9fe91586707c7ffbf9211776ba3 OK
2026/10/12 04:34:29 INFO  : urj8gsducqbtvekeq181ntd2u8: merging "sfrom47189m9qr3mt4qonu281c"
2026/10/12 04:34:30 INFO  : urj8gsducqbtvekeq181ntd2u8: removing empty directory
2026/10/12 04:34:31 INFO  : 1u1r4ei7c628fnjg9blqt3j60o: merging "tr2hj63d80ftlmvm6a952snjcc"
2026/10/12 04:34:32 INFO  : 1u1r4ei7c628fnjg9blqt3j60o: removing empty directory
--- PASS: TestMergeDirs (19.84s)
=== RUN   TestIndex
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:34:41 DEBUG : file1.txt: md5 = e4f96cefa20eb836db245d6191b730a7 OK
2026/10/12 04:34:43 DEBUG : sub/file2.txt: md5 = 068456a16dc4e4c21d4329696614572f OK
2026/10/12 04:34:46 DEBUG : sub/deep/file3.txt: md5 = 46fda7052a33e03b98f89884162c5f0c OK
2026/10/12 04:34:49 DEBUG : sub/deep/index.html: md5 = f28b5c3c8c49147b6f0e7f70cfe9c584 OK
2026/10/12 04:34:49 DEBUG : sub/deep/index.html: size = 12087 OK
2026/10/12 04:34:49 INFO  : sub/deep/index.html: Copied (new)
2026/10/12 04:34:49 DEBUG : caddy.json: md5 = 7e616399dac5e2a6df8fc8dc5bf9ddda OK
2026/10/12 04:34:49 DEBUG : caddy.json: size = 259 OK
2026/10/12 04:34:49 INFO  : caddy.json: Copied (new)
2026/10/12 04:34:49 DEBUG : index.json: md5 = 4d7c7f0f9a967bcda4ef0ccdf6259239 OK
2026/10/12 04:34:49 DEBUG : index.json: size = 213 OK
2026/10/12 04:34:49 INFO  : index.json: Copied (new)
2026/10/12 04:34:49 DEBUG : index.html: md5 = 90bc966e53a8681690f86754ced22394 OK
2026/10/12 04:34:49 DEBUG : index.html: size = 12147 OK
2026/10/12 04:34:49 INFO  : index.html: Copied (new)
2026/10/12 04:34:51 DEBUG : sub/deep/index.json: md5 = 4bc8b20bb20bf6b41383fec7d1501f63 OK
2026/10/12 04:34:51 DEBUG : sub/deep/index.json: size = 119 OK
2026/10/12 04:34:51 INFO  : sub/deep/index.json: Copied (new)
2026/10/12 04:34:51 DEBUG : sub/index.json: md5 = 088c60295879a2042ba2e006fe5c74a2 OK
2026/10/12 04:34:51 DEBUG : sub/index.json: size = 215 OK
2026/10/12 04:34:51 INFO  : sub/index.json: Copied (new)
2026/10/12 04:34:51 DEBUG : sub/deep/caddy.json: md5 = d1593ec6178af696066c241ece6b4769 OK
2026/10/12 04:34:51 DEBUG : sub/deep/caddy.json: size = 131 OK
2026/10/12 04:34:51 INFO  : sub/deep/caddy.json: Copied (new)
2026/10/12 04:34:51 DEBUG : sub/index.html: md5 = 2230f9b4cef94c5cce56ba486286e5ae OK
2026/10/12 04:34:51 DEBUG : sub/index.html: size = 12306 OK
2026/10/12 04:34:51 INFO  : sub/index.html: Copied (new)
2026/10/12 04:34:53 DEBUG : sub/caddy.json: md5 = 3fafaa82639850270969dbfff72fcdf2 OK
2026/10/12 04:34:53 DEBUG : sub/caddy.json: size = 261 OK
2026/10/12 04:34:53 INFO  : sub/caddy.json: Copied (new)
2026/10/12 04:35:00 DEBUG : index.html: Unchanged skipping
2026/10/12 04:35:00 DEBUG : sub/index.html: Unchanged skipping
2026/10/12 04:35:00 DEBUG : sub/deep/index.html: Unchanged skipping
2026/10/12 04:35:00 DEBUG : index.json: Unchanged skipping
2026/10/12 04:35:01 DEBUG : sub/index.json: Unchanged skipping
2026/10/12 04:35:01 DEBUG : sub/deep/index.json: Unchanged skipping
2026/10/12 04:35:01 DEBUG : caddy.json: Unchanged skipping
2026/10/12 04:35:01 DEBUG : sub/caddy.json: Unchanged skipping
2026/10/12 04:35:01 DEBUG : sub/deep/caddy.json: Unchanged skipping
2026/10/12 04:35:04 DEBUG : index.html: md5 = 7c3e33ee7717e74192edfe74cfeb2b08 OK
2026/10/12 04:35:04 DEBUG : index.html: size = 12147 OK
2026/10/12 04:35:04 INFO  : index.html: Copied (replaced existing)
2026/10/12 04:35:04 DEBUG : sub/deep/index.html: md5 = 5b310a6f62f3fbdd9173e937b3d7803a OK
2026/10/12 04:35:04 DEBUG : sub/deep/index.html: size = 12087 OK
2026/10/12 04:35:04 INFO  : sub/deep/index.html: Copied (replaced existing)
2026/10/12 04:35:04 DEBUG : index.json: md5 = fed2658dad2415e4843a62175b7f0490 OK
2026/10/12 04:35:04 DEBUG : index.json: size = 213 OK
2026/10/12 04:35:04 INFO  : index.json: Copied (replaced existing)
2026/10/12 04:35:04 DEBUG : caddy.json: md5 = fbe6db771b147c10a153263f89e0cb5c OK
2026/10/12 04:35:04 DEBUG : caddy.json: size = 259 OK
2026/10/12 04:35:04 INFO  : caddy.json: Copied (replaced existing)
2026/10/12 04:35:05 DEBUG : sub/deep/index.json: md5 = 92e53244f67bf0b7f14c2b896bacd52a OK
2026/10/12 04:35:05 DEBUG : sub/deep/index.json: size = 119 OK
2026/10/12 04:35:05 INFO  : sub/deep/index.json: Copied (replaced existing)
2026/10/12 04:35:06 DEBUG : sub/index.html: md5 = 16b80ed739a771f22017cfa34b693236 OK
2026/10/12 04:35:06 DEBUG : sub/index.html: size = 12306 OK
2026/10/12 04:35:06 INFO  : sub/index.html: Copied (replaced existing)
2026/10/12 04:35:06 DEBUG : sub/deep/caddy.json: md5 = 493b615d25c60c088797cec77aaad17a OK
2026/10/12 04:35:06 DEBUG : sub/deep/caddy.json: size = 131 OK
2026/10/12 04:35:06 INFO  : sub/deep/caddy.json: Copied (replaced existing)
2026/10/12 04:35:06 DEBUG : sub/index.json: md5 = bd15e5f31b5bb06fbf5498f084e45d4b OK
2026/10/12 04:35:06 DEBUG : sub/index.json: size = 215 OK
2026/10/12 04:35:06 INFO  : sub/index.json: Copied (replaced existing)
2026/10/12 04:35:07 DEBUG : sub/caddy.json: md5 = 337b220c11f2a7b5a48266d74f206b3d OK
2026/10/12 04:35:07 DEBUG : sub/caddy.json: size = 261 OK
2026/10/12 04:35:07 INFO  : sub/caddy.json: Copied (replaced existing)
2026/10/12 04:35:09 DEBUG : sub/deep/file4.txt: md5 = 24e80459919f1c074920dce301aeb207 OK
2026/10/12 04:35:10 DEBUG : sub/index.html: Unchanged skipping
2026/10/12 04:35:10 DEBUG : index.html: Unchanged skipping
2026/10/12 04:35:11 DEBUG : sub/deep/index.json: md5 = 1684abc72110ae6338310251c227d4a6 OK
2026/10/12 04:35:11 DEBUG : sub/deep/index.json: size = 235 OK
2026/10/12 04:35:11 INFO  : sub/deep/index.json: Copied (replaced existing)
2026/10/12 04:35:11 DEBUG : sub/index.json: Unchanged skipping
2026/10/12 04:35:11 DEBUG : index.json: Unchanged skipping
2026/10/12 04:35:11 DEBUG : sub/deep/index.html: md5 = 40253c81a28850e09209a27b8fdb5ce0 OK
2026/10/12 04:35:11 DEBUG : sub/deep/index.html: size = 12346 OK
2026/10/12 04:35:11 INFO  : sub/deep/index.html: Copied (replaced existing)
2026/10/12 04:35:11 DEBUG : sub/deep/caddy.json: md5 = 9ed46c3c5ffeeb8d312713968caa0544 OK
2026/10/12 04:35:11 DEBUG : sub/deep/caddy.json: size = 260 OK
2026/10/12 04:35:11 INFO  : sub/deep/caddy.json: Copied (replaced existing)
2026/10/12 04:35:12 DEBUG : caddy.json: Unchanged skipping
2026/10/12 04:35:12 DEBUG : sub/caddy.json: Unchanged skipping
2026/10/12 04:35:15 DEBUG : sub/deep/file5.txt: md5 = 917f4668b57c220a6244239d0c0e22f3 OK
2026/10/12 04:35:16 NOTICE: sub/deep/index.html: Skipped copy as --dry-run is set (size 12.309Ki)
2026/10/12 04:35:16 NOTICE: sub/deep/index.json: Skipped copy as --dry-run is set (size 351)
2026/10/12 04:35:16 NOTICE: sub/deep/caddy.json: Skipped copy as --dry-run is set (size 389)
2026/10/12 04:35:16 DEBUG : sub/index.html: Unchanged skipping
2026/10/12 04:35:16 DEBUG : index.html: Unchanged skipping
2026/10/12 04:35:17 DEBUG : sub/index.json: Unchanged skipping
2026/10/12 04:35:17 DEBUG : index.json: Unchanged skipping
2026/10/12 04:35:17 DEBUG : sub/caddy.json: Unchanged skipping
2026/10/12 04:35:17 DEBUG : caddy.json: Unchanged skipping
--- PASS: TestIndex (52.18s)
=== RUN   TestIndexDelete
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:35:33 DEBUG : file1.txt: md5 = 04c38385ee23933c147607cad049421a OK
2026/10/12 04:35:36 DEBUG : sub/file2.txt: md5 = 1f63d3fc95e20339c580d2fdb84153b5 OK
2026/10/12 04:35:39 DEBUG : sub/deep/file3.txt: md5 = f1e6f516811f21916801654040724eca OK
2026/10/12 04:35:42 DEBUG : sub/deep/index.html: md5 = 37830f6fa90daf5d123346611c5fe876 OK
2026/10/12 04:35:42 DEBUG : sub/deep/index.html: size = 12087 OK
2026/10/12 04:35:42 INFO  : sub/deep/index.html: Copied (new)
2026/10/12 04:35:42 DEBUG : index.html: md5 = 3688a389d1dcde9ae960797df607e422 OK
2026/10/12 04:35:42 DEBUG : index.html: size = 12147 OK
2026/10/12 04:35:42 INFO  : index.html: Copied (new)
2026/10/12 04:35:42 DEBUG : sub/index.html: md5 = 4f78c1fca4ce9a2aa60e318dfabe6311 OK
2026/10/12 04:35:42 DEBUG : sub/index.html: size = 12306 OK
2026/10/12 04:35:42 INFO  : sub/index.html: Copied (new)
2026/10/12 04:35:44 DEBUG : index.html: Unchanged skipping
2026/10/12 04:35:44 DEBUG : sub/index.html: Unchanged skipping
2026/10/12 04:35:45 DEBUG : sub/deep/index.html: md5 = aaa35cfe84ee4220733cdcea031931df OK
2026/10/12 04:35:45 DEBUG : sub/deep/index.html: size = 11829 OK
2026/10/12 04:35:45 INFO  : sub/deep/index.html: Copied (replaced existing)
--- PASS: TestIndexDelete (23.50s)
=== RUN   TestIndexFilter
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:35:56 DEBUG : file1.txt: md5 = 6aa8c3a99a8dcd3c834b1737361f6012 OK
2026/10/12 04:36:00 DEBUG : sub/file2.txt: md5 = cbcc51dec78e6d225c5d490aa406b18a OK
2026/10/12 04:36:02 DEBUG : sub/deep/file3.txt: md5 = da453fa7ca4bf5aba82a1a5641320ed1 OK
2026/10/12 04:36:04 DEBUG : sub/deep/index.html: md5 = 6fa769cc07ad7864b3faaa60f70b0424 OK
2026/10/12 04:36:07 DEBUG : other/notes.md: md5 = 8bb5e26dfc564ddfa8e0c55d2d3a92df OK
2026/10/12 04:36:08 DEBUG : other/notes.md: Excluded (Path Filter)
2026/10/12 04:36:10 DEBUG : other/index.html: md5 = 73a62e90bfe8b356b660d2b6fc5af922 OK
2026/10/12 04:36:10 DEBUG : other/index.html: size = 11802 OK
2026/10/12 04:36:10 INFO  : other/index.html: Copied (new)
2026/10/12 04:36:10 DEBUG : sub/index.html: md5 = a4b18b44c70ef6e027b55e451878bdca OK
2026/10/12 04:36:10 DEBUG : sub/index.html: size = 12056 OK
2026/10/12 04:36:10 INFO  : sub/index.html: Copied (new)
2026/10/12 04:36:10 DEBUG : index.html: md5 = 2535255d5938562d3bd1a29c71156144 OK
2026/10/12 04:36:10 DEBUG : index.html: size = 12399 OK
2026/10/12 04:36:10 INFO  : index.html: Copied (new)
2026/10/12 04:36:16 DEBUG : other/notes.md: Excluded (Path Filter)
2026/10/12 04:36:17 DEBUG : index.html: Unchanged skipping
2026/10/12 04:36:17 DEBUG : sub/index.html: Unchanged skipping
2026/10/12 04:36:17 DEBUG : other/index.html: Unchanged skipping
--- PASS: TestIndexFilter (32.26s)
=== RUN   TestIndexRules
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:36:29 DEBUG : file1.txt: md5 = 047bf99d1b8a5d29cd5e614b4a10fac5 OK
2026/10/12 04:36:32 DEBUG : sub/file2.txt: md5 = d7ea5f7c146beae053e0b2a6405e761d OK
2026/10/12 04:36:35 DEBUG : sub/deep/file3.txt: md5 = dddb98d4dc8a239425e8464bdd625a6e OK
2026/10/12 04:36:36 DEBUG : sub/deep/index.html: md5 = 3531643e1832c590e3800286cf85d0db OK
2026/10/12 04:36:39 DEBUG : index.html: md5 = ff93d39e30c415657770e126acfcd69d OK
2026/10/12 04:36:39 DEBUG : index.html: size = 12147 OK
2026/10/12 04:36:39 INFO  : index.html: Copied (new)
2026/10/12 04:36:39 DEBUG : sub/index.html: md5 = 065d1671bdc99ad8a4503d73948f14ca OK
2026/10/12 04:36:39 DEBUG : sub/index.html: size = 12306 OK
2026/10/12 04:36:39 INFO  : sub/index.html: Copied (new)
2026/10/12 04:36:45 DEBUG : sub/index.html: Unchanged skipping
2026/10/12 04:36:46 DEBUG : sub/deep/index.html: md5 = 9d0378f293b3c9e0dd0ea6ce8fad2985 OK
2026/10/12 04:36:46 DEBUG : sub/deep/index.html: size = 12087 OK
2026/10/12 04:36:46 INFO  : sub/deep/index.html: Copied (replaced existing)
--- PASS: TestIndexRules (29.23s)
=== RUN   TestIndexMaxDepth
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:36:58 DEBUG : file1.txt: md5 = cd5c555efd9064ff2de9e158b6c891fa OK
2026/10/12 04:37:01 DEBUG : sub/file2.txt: md5 = 043b2765895b775a42f17997b6a6fe43 OK
2026/10/12 04:37:04 DEBUG : sub/deep/file3.txt: md5 = e16e1001cd0706cf54533de38bc238a4 OK
2026/10/12 04:37:07 DEBUG : index.html: md5 = ad497ec21a8afc3b1dbe7b31974e224f OK
2026/10/12 04:37:07 DEBUG : index.html: size = 12147 OK
2026/10/12 04:37:07 INFO  : index.html: Copied (new)
--- PASS: TestIndexMaxDepth (18.86s)
=== RUN   TestIndexLinkIndex
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:37:17 DEBUG : file1.txt: md5 = 741f80ce8508d48abc9c81232c7ee8a9 OK
2026/10/12 04:37:20 DEBUG : sub/file2.txt: md5 = 00c4a10e2a1643e00b35ff2f3d59e139 OK
2026/10/12 04:37:22 DEBUG : sub/deep/file3.txt: md5 = 7e53c8636858da168132c6ed0a411881 OK
2026/10/12 04:37:25 DEBUG : sub/deep/index.html: md5 = a999d3a4eb26fb1f3cd9a0f791c48686 OK
2026/10/12 04:37:25 DEBUG : sub/deep/index.html: size = 12128 OK
2026/10/12 04:37:25 INFO  : sub/deep/index.html: Copied (new)
2026/10/12 04:37:25 DEBUG : sub/index.html: md5 = 6cd3b0b55180be8c64cba2614ea462ab OK
2026/10/12 04:37:25 DEBUG : sub/index.html: size = 12347 OK
2026/10/12 04:37:25 INFO  : sub/index.html: Copied (new)
2026/10/12 04:37:25 DEBUG : index.html: md5 = 7e84715a692cf77566e3efb3ef014c20 OK
2026/10/12 04:37:25 DEBUG : index.html: size = 12167 OK
2026/10/12 04:37:25 INFO  : index.html: Copied (new)
--- PASS: TestIndexLinkIndex (19.82s)
=== RUN   TestIndexDirTime
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:37:36 DEBUG : file1.txt: md5 = 82da246723b5fd5fa938f93c11d1cef4 OK
2026/10/12 04:37:40 DEBUG : sub/file2.txt: md5 = aad2452e0a508384aefb992a48a600d6 OK
2026/10/12 04:37:42 DEBUG : sub/deep/file3.txt: md5 = 023621e2ea993b6b0ce8c20bc390e4e8 OK
2026/10/12 04:37:45 DEBUG : sub/index.json: md5 = 76f638e92149becafd1de212d51223e1 OK
2026/10/12 04:37:45 DEBUG : sub/index.json: size = 259 OK
2026/10/12 04:37:45 INFO  : sub/index.json: Copied (new)
2026/10/12 04:37:46 DEBUG : sub/deep/index.json: md5 = 0da64fb31b1a501de82c41cb1be8fa92 OK
2026/10/12 04:37:46 DEBUG : sub/deep/index.json: size = 139 OK
2026/10/12 04:37:46 INFO  : sub/deep/index.json: Copied (new)
2026/10/12 04:37:46 DEBUG : index.json: md5 = 4b775591e921b2ab2f387c0648608ba5 OK
2026/10/12 04:37:46 DEBUG : index.json: size = 257 OK
2026/10/12 04:37:46 INFO  : index.json: Copied (new)
2026/10/12 04:37:49 DEBUG : sub/deep/index.json: Unchanged skipping
2026/10/12 04:37:50 DEBUG : index.json: md5 = 743f32226c064b5a6c8dddf29d042b4f OK
2026/10/12 04:37:50 DEBUG : index.json: size = 237 OK
2026/10/12 04:37:50 INFO  : index.json: Copied (replaced existing)
2026/10/12 04:37:51 DEBUG : sub/index.json: md5 = cd8d530859258e6da8ff7206b1ae57b9 OK
2026/10/12 04:37:51 DEBUG : sub/index.json: size = 239 OK
2026/10/12 04:37:51 INFO  : sub/index.json: Copied (replaced existing)
2026/10/12 04:37:56 DEBUG : index.json: md5 = 32e865da181e7e471d18c3e0b3a22343 OK
2026/10/12 04:37:56 DEBUG : index.json: size = 213 OK
2026/10/12 04:37:56 INFO  : index.json: Copied (replaced existing)
2026/10/12 04:37:57 DEBUG : sub/deep/index.json: md5 = 3e3a0db18847435b697c98a138c8a7ee OK
2026/10/12 04:37:57 DEBUG : sub/deep/index.json: size = 119 OK
2026/10/12 04:37:57 INFO  : sub/deep/index.json: Copied (replaced existing)
2026/10/12 04:37:57 DEBUG : sub/index.json: md5 = 78482eb1cbe0819136819feeebf7ec4b OK
2026/10/12 04:37:57 DEBUG : sub/index.json: size = 215 OK
2026/10/12 04:37:57 INFO  : sub/index.json: Copied (replaced existing)
--- PASS: TestIndexDirTime (30.84s)
=== RUN   TestIndexTemplate
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:38:07 DEBUG : file1.txt: md5 = 89cc8b820d8aa315c16ffd05953423b8 OK
2026/10/12 04:38:11 DEBUG : sub/file2.txt: md5 = 5a6bc9f942d4e07d348e605110f638bb OK
2026/10/12 04:38:14 DEBUG : sub/deep/file3.txt: md5 = 0bd8a6e2b5733dc5db8d441ceeb4a0b7 OK
2026/10/12 04:38:17 DEBUG : sub/deep/list.txt: md5 = cd09a6207b1ad42faa989c3f25fe1bb5 OK
2026/10/12 04:38:17 DEBUG : sub/deep/list.txt: size = 47 OK
2026/10/12 04:38:17 INFO  : sub/deep/list.txt: Copied (new)
2026/10/12 04:38:17 DEBUG : sub/list.txt: md5 = b1f88f068f4fd4f704efa1f513d85b3a OK
2026/10/12 04:38:17 DEBUG : sub/list.txt: size = 64 OK
2026/10/12 04:38:17 INFO  : sub/list.txt: Copied (new)
2026/10/12 04:38:17 DEBUG : list.txt: md5 = 76cd4c2a1e80c1e757b1f717df847c3e OK
2026/10/12 04:38:17 DEBUG : list.txt: size = 59 OK
2026/10/12 04:38:17 INFO  : list.txt: Copied (new)
--- PASS: TestIndexTemplate (20.19s)
=== 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-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:38:27 DEBUG : file1.txt: md5 = 47a8b024b5a7300fd80124b1b48201e1 OK
2026/10/12 04:38:30 DEBUG : sub/file2.txt: md5 = 167797615170e3e9a8b72bf280835756 OK
2026/10/12 04:38:34 DEBUG : sub/deep/file3.txt: md5 = 12c8a712f40b5e70b352b9b2c94d4d80 OK
2026/10/12 04:38:36 DEBUG : other/notes.md: md5 = 28d19064ba8715b142b3e454d146a97e OK
2026/10/12 04:38:39 DEBUG : sub/deep/index.html: md5 = 3bd1924f6b9ab5afa2db127b4b76e6b4 OK
2026/10/12 04:38:39 DEBUG : sub/deep/index.html: size = 12087 OK
2026/10/12 04:38:39 INFO  : sub/deep/index.html: Copied (new)
2026/10/12 04:38:39 DEBUG : sub/index.html: md5 = 2416f7c90f316208fb115f8273171a52 OK
2026/10/12 04:38:39 DEBUG : sub/index.html: size = 12306 OK
2026/10/12 04:38:39 INFO  : sub/index.html: Copied (new)
2026/10/12 04:38:39 DEBUG : index.html: md5 = 200fcb779747dfc2ccf25da0cefab4ec OK
2026/10/12 04:38:39 DEBUG : index.html: size = 12399 OK
2026/10/12 04:38:39 INFO  : index.html: Copied (new)
2026/10/12 04:38:39 DEBUG : other/index.html: md5 = 8002ee8e095389c588dbb86195fb2987 OK
2026/10/12 04:38:39 DEBUG : other/index.html: size = 12058 OK
2026/10/12 04:38:39 INFO  : other/index.html: Copied (new)
2026/10/12 04:38:41 DEBUG : sub/deep/file4.txt: md5 = fca8c07e8bf99e4ec8d12834d801e6d4 OK
2026/10/12 04:38:43 DEBUG : other/undeclared.txt: md5 = e86a62bac9f35bad22487f4c29ebf672 OK
2026/10/12 04:38:43 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe': Partial index: listing 3 directories and walking 0 changed directories
2026/10/12 04:38:45 DEBUG : sub/index.html: Unchanged skipping
2026/10/12 04:38:45 DEBUG : index.html: Unchanged skipping
2026/10/12 04:38:46 DEBUG : sub/deep/index.html: md5 = 0bd362f9be4bae17013bcac502f2c75f OK
2026/10/12 04:38:46 DEBUG : sub/deep/index.html: size = 12346 OK
2026/10/12 04:38:46 INFO  : sub/deep/index.html: Copied (replaced existing)
2026/10/12 04:38:50 DEBUG : sub/new/file5.txt: md5 = aceaf41078faf0daaa28ecf8807096bf OK
2026/10/12 04:38:50 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe': Partial index: listing 3 directories and walking 0 changed directories
2026/10/12 04:38:52 DEBUG : index.html: Unchanged skipping
2026/10/12 04:38:53 DEBUG : sub/index.html: md5 = 6e0724ba7d613280516995e9c1e8dee1 OK
2026/10/12 04:38:53 DEBUG : sub/index.html: size = 12554 OK
2026/10/12 04:38:53 INFO  : sub/index.html: Copied (replaced existing)
2026/10/12 04:38:53 DEBUG : sub/new/index.html: md5 = aed7be8b788ab31c301621e410763b51 OK
2026/10/12 04:38:53 DEBUG : sub/new/index.html: size = 12085 OK
2026/10/12 04:38:53 INFO  : sub/new/index.html: Copied (new)
2026/10/12 04:38:57 DEBUG : other/undeclared0.txt: md5 = 729ecbf718556109a4f17d2d121ce6b8 OK
2026/10/12 04:38:57 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe': Partial index: listing 1 directories and walking 1 changed directories
2026/10/12 04:38:59 DEBUG : index.html: Unchanged skipping
2026/10/12 04:39:00 DEBUG : other/index.html: md5 = e3090fc9b18124fd35b157baa5473386 OK
2026/10/12 04:39:00 DEBUG : other/index.html: size = 12597 OK
2026/10/12 04:39:00 INFO  : other/index.html: Copied (replaced existing)
2026/10/12 04:39:03 DEBUG : other/undeclared1.txt: md5 = a2454097e27dba7c411d29e5affa06c3 OK
2026/10/12 04:39:03 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe': Partial index: listing 1 directories and walking 0 changed directories
2026/10/12 04:39:04 DEBUG : index.html: Unchanged skipping
2026/10/12 04:39:05 DEBUG : other/index.html: md5 = d0d9d6809c388ac2a4880ec0229737ae OK
2026/10/12 04:39:05 DEBUG : other/index.html: size = 12867 OK
2026/10/12 04:39:05 INFO  : other/index.html: Copied (replaced existing)
2026/10/12 04:39:08 DEBUG : sub/file6.txt: md5 = 321edf1ade7dc3daf363420349ba7a44 OK
2026/10/12 04:39:08 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe': Nothing changed so nothing to index
2026/10/12 04:39:09 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe': Root changed so doing a full index
2026/10/12 04:39:11 DEBUG : index.html: Unchanged skipping
2026/10/12 04:39:11 DEBUG : sub/new/index.html: Unchanged skipping
2026/10/12 04:39:11 DEBUG : sub/deep/index.html: Unchanged skipping
2026/10/12 04:39:11 DEBUG : other/index.html: Unchanged skipping
2026/10/12 04:39:12 DEBUG : sub/index.html: md5 = 1bd9f1c8b212d754fdc2eb2d7ed018fb OK
2026/10/12 04:39:12 DEBUG : sub/index.html: size = 12813 OK
2026/10/12 04:39:12 INFO  : sub/index.html: Copied (replaced existing)
2026/10/12 04:39:15 DEBUG : sub/file7.txt: md5 = fdd6a97b6e18678504f6dc72fd03dcfe OK
2026/10/12 04:39:15 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe': Partial index would list 2 directories, more than 1, so doing a full index
2026/10/12 04:39:16 DEBUG : index.html: Unchanged skipping
2026/10/12 04:39:16 DEBUG : other/index.html: Unchanged skipping
2026/10/12 04:39:16 DEBUG : sub/deep/index.html: Unchanged skipping
2026/10/12 04:39:16 DEBUG : sub/new/index.html: Unchanged skipping
2026/10/12 04:39:17 DEBUG : sub/index.html: md5 = 5021652f54a385a791012f9778ce3ea3 OK
2026/10/12 04:39:17 DEBUG : sub/index.html: size = 13071 OK
2026/10/12 04:39:17 INFO  : sub/index.html: Copied (replaced existing)
2026/10/12 04:39:20 DEBUG : sub/deep/file8.txt: md5 = 91abfb658dc50c1dff59377d59a4f571 OK
2026/10/12 04:39:20 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe': Partial index: listing 1 directories and walking 1 changed directories
2026/10/12 04:39:22 DEBUG : sub/index.html: Unchanged skipping
2026/10/12 04:39:22 DEBUG : index.html: Unchanged skipping
2026/10/12 04:39:22 DEBUG : sub/new/index.html: Unchanged skipping
2026/10/12 04:39:23 DEBUG : sub/deep/index.html: md5 = 2d057ab3d7d773ac5f903e3238677801 OK
2026/10/12 04:39:23 DEBUG : sub/deep/index.html: size = 12604 OK
2026/10/12 04:39:23 INFO  : sub/deep/index.html: Copied (replaced existing)
2026/10/12 04:39:26 DEBUG : sub/deep/file9.txt: md5 = b3cc94253e2d653cae03a3c616da0b9a OK
2026/10/12 04:39:28 DEBUG : sub/file10.txt: md5 = fa77c81a0d9ae06bfcf458d246f2dd78 OK
2026/10/12 04:39:28 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe': Partial index: listing 2 directories and walking 0 changed directories
2026/10/12 04:39:29 DEBUG : index.html: Unchanged skipping
2026/10/12 04:39:31 DEBUG : sub/index.html: md5 = 01ee89f3cdb37b2a2427dff277dbb527 OK
2026/10/12 04:39:31 DEBUG : sub/index.html: size = 13331 OK
2026/10/12 04:39:31 INFO  : sub/index.html: Copied (replaced existing)
--- PASS: TestIndexChanged (85.15s)
=== RUN   TestIndexChangedDelete
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:39:53 DEBUG : file1.txt: md5 = 0bc36aecf9b1c4c727be9cb199c6377a OK
2026/10/12 04:39:56 DEBUG : sub/file2.txt: md5 = 427155cae421dc42c4dad135fff1a21b OK
2026/10/12 04:39:59 DEBUG : sub/deep/file3.txt: md5 = acf31ffa7700993b8566b7cb64a5bd06 OK
2026/10/12 04:40:02 DEBUG : index.html: md5 = 455a2e9243e3f46479559ded6daaa0f1 OK
2026/10/12 04:40:02 DEBUG : index.html: size = 12147 OK
2026/10/12 04:40:02 INFO  : index.html: Copied (new)
2026/10/12 04:40:02 DEBUG : sub/index.html: md5 = 82b58fd55e188769a12fcfd0b4e96915 OK
2026/10/12 04:40:02 DEBUG : sub/index.html: size = 12306 OK
2026/10/12 04:40:02 INFO  : sub/index.html: Copied (new)
2026/10/12 04:40:02 DEBUG : sub/deep/index.html: md5 = ca8af4bf4d0aa1305bb40d11034d87da OK
2026/10/12 04:40:02 DEBUG : sub/deep/index.html: size = 12087 OK
2026/10/12 04:40:02 INFO  : sub/deep/index.html: Copied (new)
2026/10/12 04:40:03 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe': Partial index: listing 3 directories and walking 0 changed directories
2026/10/12 04:40:05 DEBUG : index.html: Unchanged skipping
2026/10/12 04:40:05 DEBUG : sub/index.html: Unchanged skipping
2026/10/12 04:40:06 DEBUG : sub/deep/index.html: md5 = 062c9b1516cc7e53f76102d5dad3e802 OK
2026/10/12 04:40:06 DEBUG : sub/deep/index.html: size = 11829 OK
2026/10/12 04:40:06 INFO  : sub/deep/index.html: Copied (replaced existing)
--- PASS: TestIndexChangedDelete (23.60s)
=== RUN   TestIndexChangedFiles
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:40:16 DEBUG : file1.txt: md5 = 792d5c7196d89ca693604d5af54a554f OK
2026/10/12 04:40:19 DEBUG : sub/file2.txt: md5 = 3cbe590d278073a8d9401df5f8921ceb OK
2026/10/12 04:40:22 DEBUG : sub/deep/file3.txt: md5 = 6b49cd61b110ebc750d58f0ad5f559ae OK
2026/10/12 04:40:25 DEBUG : - odd/file.txt: md5 = 94b78b0a8faa5401c196ff9444f2fd6e OK
2026/10/12 04:40:28 DEBUG : sub/index.html: md5 = 47e41ed4aaba376d9e2e7673249bc290 OK
2026/10/12 04:40:28 DEBUG : sub/index.html: size = 12306 OK
2026/10/12 04:40:28 INFO  : sub/index.html: Copied (new)
2026/10/12 04:40:28 DEBUG : sub/deep/index.html: md5 = bc0610d8d851b732ea2fbf9017fe0290 OK
2026/10/12 04:40:28 DEBUG : sub/deep/index.html: size = 12087 OK
2026/10/12 04:40:28 INFO  : sub/deep/index.html: Copied (new)
2026/10/12 04:40:28 DEBUG : index.html: md5 = 3f231be000bdfa99ccf9d06b3189f593 OK
2026/10/12 04:40:28 DEBUG : index.html: size = 12401 OK
2026/10/12 04:40:28 INFO  : index.html: Copied (new)
2026/10/12 04:40:28 DEBUG : - odd/index.html: md5 = 1407e0e6e3b35a4db3283a9da418381a OK
2026/10/12 04:40:28 DEBUG : - odd/index.html: size = 12058 OK
2026/10/12 04:40:28 INFO  : - odd/index.html: Copied (new)
2026/10/12 04:40:30 DEBUG : - odd/file8.txt: md5 = 59cc05efbdf24c3a8b6cd8a45bf6583e OK
2026/10/12 04:40:32 DEBUG : sub/file9.txt: md5 = a9eefe31f84fec537ec5f771f8ed5620 OK
2026/10/12 04:40:32 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe': Partial index: listing 2 directories and walking 0 changed directories
2026/10/12 04:40:33 DEBUG : index.html: Unchanged skipping
2026/10/12 04:40:34 DEBUG : - odd/index.html: md5 = 07c0ffe597a8e6fde06ca5bce357452c OK
2026/10/12 04:40:34 DEBUG : - odd/index.html: size = 12316 OK
2026/10/12 04:40:34 INFO  : - odd/index.html: Copied (replaced existing)
2026/10/12 04:40:36 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe': Partial index: listing 4 directories and walking 0 changed directories
2026/10/12 04:40:38 DEBUG : index.html: Unchanged skipping
2026/10/12 04:40:38 DEBUG : sub/deep/index.html: Unchanged skipping
2026/10/12 04:40:38 DEBUG : - odd/index.html: Unchanged skipping
2026/10/12 04:40:39 DEBUG : sub/index.html: md5 = aac133bbe2aac69454a4ec5cd9540100 OK
2026/10/12 04:40:39 DEBUG : sub/index.html: size = 12565 OK
2026/10/12 04:40:39 INFO  : sub/index.html: Copied (replaced existing)
--- PASS: TestIndexChangedFiles (37.37s)
=== RUN   TestIndexChangedDirTime
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:40:54 DEBUG : file1.txt: md5 = 5191db4692070ea6833d4c567e4bec8f OK
2026/10/12 04:40:57 DEBUG : sub/file2.txt: md5 = 3d9907756985bbdf54f948e5b7ac2f5a OK
2026/10/12 04:41:00 DEBUG : sub/deep/file3.txt: md5 = a10b3b0e61d0ab9867d44fd62fb190af OK
2026/10/12 04:41:03 DEBUG : other/notes.md: md5 = d30951c88988572ffd831dcf24b63ce4 OK
2026/10/12 04:41:06 DEBUG : index.json: md5 = 8c7a27e1fee2b9c9d9fed53947501d4a OK
2026/10/12 04:41:06 DEBUG : index.json: size = 379 OK
2026/10/12 04:41:06 INFO  : index.json: Copied (new)
2026/10/12 04:41:06 DEBUG : sub/deep/index.json: md5 = d7d65c89acda560be7710ed8597c533f OK
2026/10/12 04:41:06 DEBUG : sub/deep/index.json: size = 139 OK
2026/10/12 04:41:06 INFO  : sub/deep/index.json: Copied (new)
2026/10/12 04:41:06 DEBUG : other/index.json: md5 = b762ebc5ba6b165dac2f961a0603a22b OK
2026/10/12 04:41:06 DEBUG : other/index.json: size = 144 OK
2026/10/12 04:41:06 INFO  : other/index.json: Copied (new)
2026/10/12 04:41:06 DEBUG : sub/index.json: md5 = c3c7142684568f210ac1902963974932 OK
2026/10/12 04:41:06 DEBUG : sub/index.json: size = 259 OK
2026/10/12 04:41:06 INFO  : sub/index.json: Copied (new)
2026/10/12 04:41:08 DEBUG : sub/deep/file4.txt: md5 = 62f54c22672cdbb5b83287c68ee20fb0 OK
2026/10/12 04:41:08 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe': Partial index: listing 3 directories and walking 0 changed directories
2026/10/12 04:41:11 DEBUG : sub/deep/index.json: md5 = 36285ded362538d3f1769c35f45b76e3 OK
2026/10/12 04:41:11 DEBUG : sub/deep/index.json: size = 275 OK
2026/10/12 04:41:11 INFO  : sub/deep/index.json: Copied (replaced existing)
2026/10/12 04:41:12 DEBUG : sub/index.json: md5 = e4c7e49874a98c6d1feb3d0871f36ce9 OK
2026/10/12 04:41:12 DEBUG : sub/index.json: size = 259 OK
2026/10/12 04:41:12 INFO  : sub/index.json: Copied (replaced existing)
2026/10/12 04:41:12 DEBUG : index.json: md5 = b1ec7dac764b59c83fd0e13cc9a606b2 OK
2026/10/12 04:41:12 DEBUG : index.json: size = 379 OK
2026/10/12 04:41:12 INFO  : index.json: Copied (replaced existing)
--- PASS: TestIndexChangedDirTime (31.29s)
=== RUN   TestListDirSorted
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:41:25 DEBUG : a.txt: md5 = e0ba7a34c5d786a3d048e9cb1dd8d942 OK
2026/10/12 04:41:27 DEBUG : zend.txt: md5 = e2f4c50594b6474d428a45bb0c7d16cb OK
2026/10/12 04:41:30 DEBUG : sub dir/hello world: md5 = fe75559dc6b60f7b6f025217f7d1e4b6 OK
2026/10/12 04:41:32 DEBUG : sub dir/hello world2: md5 = 45210e1f389fa84e75d8a06890e6347d OK
2026/10/12 04:41:35 DEBUG : sub dir/ignore dir/.ignore: md5 = 519c593e2d3a871c0572c5c671fe0157 OK
2026/10/12 04:41:36 DEBUG : sub dir/ignore dir/should be ignored: md5 = 60814e928ae1e44249d406c195f20df4 OK
2026/10/12 04:41:39 DEBUG : sub dir/sub sub dir/hello world3: md5 = f7fd3383468a6576f40cf559c45a0ba2 OK
2026/10/12 04:41:41 DEBUG : a.txt: Excluded (Size Filter)
2026/10/12 04:41:41 DEBUG : a.txt: Excluded
2026/10/12 04:41:42 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2026/10/12 04:41:42 DEBUG : sub dir/hello world2: Excluded
2026/10/12 04:41:42 DEBUG : sub dir/hello world: Excluded (Size Filter)
2026/10/12 04:41:42 DEBUG : sub dir/hello world: Excluded
2026/10/12 04:41:43 DEBUG : sub dir/ignore dir: Excluded
2026/10/12 04:41:43 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2026/10/12 04:41:43 DEBUG : sub dir/hello world2: Excluded
2026/10/12 04:41:43 DEBUG : sub dir/hello world: Excluded (Size Filter)
2026/10/12 04:41:43 DEBUG : sub dir/hello world: Excluded
2026/10/12 04:41:43 DEBUG : sub dir/ignore dir: Excluded
--- PASS: TestListDirSorted (29.19s)
=== RUN   TestListDirSortedFn
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:41:55 DEBUG : a.txt: md5 = d93dd33482a9ec0cf613daa90916d7fb OK
2026/10/12 04:41:57 DEBUG : zend.txt: md5 = d80f86a05ea0e40df3e6e4335e8d436f OK
2026/10/12 04:42:00 DEBUG : sub dir/hello world: md5 = b118cdd724cc9244936b1e2046c2bb4b OK
2026/10/12 04:42:03 DEBUG : sub dir/hello world2: md5 = 2f566e686dbfba121c251dbb694532ae OK
2026/10/12 04:42:06 DEBUG : sub dir/ignore dir/.ignore: md5 = 0a032b457699eb87a8c0ca8ec0c120cb OK
2026/10/12 04:42:08 DEBUG : sub dir/ignore dir/should be ignored: md5 = 468a145992519e946f837d8970b98dcb OK
2026/10/12 04:42:11 DEBUG : sub dir/sub sub dir/hello world3: md5 = d6afa8658f55867ab0040ad27bc342f6 OK
2026/10/12 04:42:14 DEBUG : a.txt: Excluded (Size Filter)
2026/10/12 04:42:14 DEBUG : a.txt: Excluded
2026/10/12 04:42:14 DEBUG : sub dir/hello world: Excluded (Size Filter)
2026/10/12 04:42:14 DEBUG : sub dir/hello world: Excluded
2026/10/12 04:42:14 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2026/10/12 04:42:14 DEBUG : sub dir/hello world2: Excluded
2026/10/12 04:42:15 DEBUG : sub dir/ignore dir: Excluded
2026/10/12 04:42:15 DEBUG : sub dir/hello world: Excluded (Size Filter)
2026/10/12 04:42:15 DEBUG : sub dir/hello world: Excluded
2026/10/12 04:42:15 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2026/10/12 04:42:15 DEBUG : sub dir/hello world2: Excluded
2026/10/12 04:42:16 DEBUG : sub dir/ignore dir: Excluded
--- PASS: TestListDirSortedFn (32.85s)
=== RUN   TestListJSON
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:42:27 DEBUG : file1: md5 = 0891a953819868717cfd23147881fbf0 OK
2026/10/12 04:42:30 DEBUG : sub/file2: md5 = 97dc7bbf3fe0fd3e773dbd13049dad49 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/12 04:42:34 ERROR : file1: Failed to read hash: hash type not supported
=== RUN   TestListJSON/Metadata
2026/10/12 04:42:35 DEBUG : eer8kka55qnghc34cq76ca668g: Fetching metadata
2026/10/12 04:42:35 DEBUG : 150fuo3cn4j1uenq6r4g4qk6t4: Fetching metadata
--- PASS: TestListJSON (13.46s)
    --- PASS: TestListJSON/Default (0.35s)
    --- PASS: TestListJSON/FilesOnly (0.34s)
    --- PASS: TestListJSON/DirsOnly (0.33s)
    --- PASS: TestListJSON/Recurse (0.73s)
    --- PASS: TestListJSON/SubDir (0.32s)
    --- PASS: TestListJSON/NoModTime (0.41s)
    --- PASS: TestListJSON/NoMimeType (0.37s)
    --- PASS: TestListJSON/ShowHash (0.39s)
    --- PASS: TestListJSON/HashTypes (0.38s)
    --- PASS: TestListJSON/Metadata (0.94s)
=== RUN   TestStatJSON
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:42:40 DEBUG : file1: md5 = ca663acf6164b1cef3d4a7a6a37b25e8 OK
2026/10/12 04:42:44 DEBUG : sub/file2: md5 = a4da366489754958453923f09c1e3b54 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/12 04:42:49 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-soyebah2yafe/notfound"
2026/10/12 04:42:49 DEBUG : Config file has changed externally - reloading
2026/10/12 04:42:49 DEBUG : Creating backend with remote "TestDrive:crypt/3lr4390jip2q9g8ao5ds7vf9as0a7itvio95vb7qd4i7vahepmpg/qe0i31qdkoejk60elps0ni5sqk"
--- PASS: TestStatJSON (16.34s)
    --- PASS: TestStatJSON/Root (0.36s)
    --- PASS: TestStatJSON/RootFilesOnly (0.00s)
    --- PASS: TestStatJSON/RootDirsOnly (0.36s)
    --- PASS: TestStatJSON/Dir (0.67s)
    --- PASS: TestStatJSON/DirWithTrailingSlash (0.47s)
    --- PASS: TestStatJSON/File (0.33s)
    --- PASS: TestStatJSON/NotFound (0.67s)
    --- PASS: TestStatJSON/DirFilesOnly (0.38s)
    --- PASS: TestStatJSON/FileFilesOnly (0.31s)
    --- PASS: TestStatJSON/NotFoundFilesOnly (0.30s)
    --- PASS: TestStatJSON/DirDirsOnly (0.40s)
    --- PASS: TestStatJSON/FileDirsOnly (0.38s)
    --- PASS: TestStatJSON/NotFoundDirsOnly (0.33s)
    --- PASS: TestStatJSON/RootNotFound (2.28s)
=== RUN   TestStatJSONMemory
2026/10/12 04:42:55 DEBUG : Creating backend with remote ":memory:"
2026/10/12 04:42:55 DEBUG : Memory root '': File to upload is small (5 bytes), uploading instead of streaming
2026/10/12 04:42:55 DEBUG : sub/file1: size = 5 OK
2026/10/12 04:42:55 DEBUG : sub/file1: md5 = 5d41402abc4b2a76b9719d911017c592 OK
2026/10/12 04:42:55 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-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:42:55 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe': Making directory
2026/10/12 04:42:55 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe': Making directory
--- PASS: TestMkdir (0.90s)
=== RUN   TestLsd
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:42:59 DEBUG : sub dir/hello world: md5 = 851d79a6bdc5472970b9aa5c1902df9a OK
--- PASS: TestLsd (6.35s)
=== RUN   TestLs
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:43:04 DEBUG : potato2: md5 = 425068a6caf3670a6d4358131e32b45f OK
2026/10/12 04:43:06 DEBUG : empty space: md5 = 5bb4edc4a12c1b563053a27460d1827e OK
--- PASS: TestLs (6.62s)
=== RUN   TestLsWithFilesFrom
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:43:10 DEBUG : potato2: md5 = 41bc431e0cffa38009b8e9f6a3134683 OK
2026/10/12 04:43:12 DEBUG : empty space: md5 = 62f743589db939307fce1ad092dacc99 OK
2026/10/12 04:43:13 DEBUG : empty space: Excluded (FilesFrom Filter)
2026/10/12 04:43:13 DEBUG : empty space: Excluded
--- PASS: TestLsWithFilesFrom (6.35s)
=== RUN   TestLsLong
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:43:17 DEBUG : potato2: md5 = 59534339fef7b9b1ed453d094ea40e52 OK
2026/10/12 04:43:19 DEBUG : empty space: md5 = 585c8ac575e61ced23d513833eabb586 OK
--- PASS: TestLsLong (6.40s)
=== RUN   TestHashSums
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:43:23 DEBUG : potato2: md5 = b6d3e27081a05ac116f378ac446a9227 OK
2026/10/12 04:43:25 DEBUG : empty space: md5 = 18996d34078155c2de6c2d59238becba OK
--- PASS: TestHashSums (5.82s)
=== RUN   TestHashSumsWithErrors
2026/10/12 04:43:27 DEBUG : Creating backend with remote ":memory:"
2026/10/12 04:43:27 ERROR : sub/file1: hash unsupported: hash type not supported
2026/10/12 04:43:27 ERROR : file1: hash unsupported: hash type not supported
2026/10/12 04:43:27 ERROR : file1: hash unsupported: hash type not supported
--- PASS: TestHashSumsWithErrors (0.00s)
=== RUN   TestHashStream
2026/10/12 04:43:27 DEBUG : Creating md5 hash of 0 bytes read from input stream
2026/10/12 04:43:27 DEBUG : Creating md5 hash of 0 bytes read from input stream
2026/10/12 04:43:27 DEBUG : Creating sha1 hash of 0 bytes read from input stream
2026/10/12 04:43:27 DEBUG : Creating sha1 hash of 0 bytes read from input stream
2026/10/12 04:43:27 DEBUG : Creating md5 hash of 12 bytes read from input stream
2026/10/12 04:43:27 DEBUG : Creating md5 hash of 12 bytes read from input stream
2026/10/12 04:43:27 DEBUG : Creating sha1 hash of 12 bytes read from input stream
2026/10/12 04:43:27 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-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:43:29 DEBUG : potato2: md5 = 0d5c130277b2a29eea6e27f52ed93a93 OK
2026/10/12 04:43:31 DEBUG : empty space: md5 = e88321ddb6da8336320ac3ccd320be28 OK
2026/10/12 04:43:34 DEBUG : sub dir/potato3: md5 = 33a94203dfd727fedc4403cca0eaadb1 OK
--- PASS: TestCount (11.27s)
=== RUN   TestDelete
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:43:40 DEBUG : small: md5 = 3230fb51b969cb117e78fd053d0667f2 OK
2026/10/12 04:43:42 DEBUG : medium: md5 = 0d0e4229ae747f729464f98ca5ed1bf3 OK
2026/10/12 04:43:44 DEBUG : large: md5 = e7ba39b6ef71eaea0103356f91302490 OK
2026/10/12 04:43:44 DEBUG : Waiting for deletions to finish
2026/10/12 04:43:45 DEBUG : large: Excluded (Size Filter)
2026/10/12 04:43:45 INFO  : small: Deleted
2026/10/12 04:43:45 INFO  : medium: Deleted
--- PASS: TestDelete (8.37s)
=== RUN   TestDeleteFatalError
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:43:49 DEBUG : file0: md5 = 10c09b2a1e14a98871459f722a4a98f4 OK
2026/10/12 04:43:51 DEBUG : file1: md5 = 2c2225fde0a64ecd2fb0d8a285b16bcc OK
2026/10/12 04:43:53 DEBUG : file2: md5 = 92415aadb71f72313d238d9f8b23ddc6 OK
2026/10/12 04:43:55 DEBUG : file3: md5 = 8cabae55875310d3acc530dd9f25e5c0 OK
2026/10/12 04:43:57 DEBUG : file4: md5 = 70733925f4ace8ab01eac8ae07b34b4f OK
2026/10/12 04:43:59 DEBUG : file5: md5 = 79f443eb022a2dd9f737ee1c41ec920f OK
2026/10/12 04:44:01 DEBUG : file6: md5 = f5fd8eebb123914a0bcf714e42c36ece OK
2026/10/12 04:44:03 DEBUG : file7: md5 = 3fa4ca40493a75cf070a7298d569f18b OK
2026/10/12 04:44:05 DEBUG : file8: md5 = f685833455d70eec46b62fa3135cfa68 OK
2026/10/12 04:44:07 DEBUG : file9: md5 = 8a2c19a2a45da4164c126c0d94949e97 OK
2026/10/12 04:44:09 DEBUG : file10: md5 = b9d65e31d0e2cfc0ad8c7c0be2a6a294 OK
2026/10/12 04:44:11 DEBUG : file11: md5 = fa81c39548ee43f9a2dd65bb7d923729 OK
2026/10/12 04:44:13 DEBUG : file12: md5 = d269d7df6b749ccb060418d4b051c64b OK
2026/10/12 04:44:15 DEBUG : file13: md5 = 8ffbb1c64a34af98b3a8180ec610588b OK
2026/10/12 04:44:17 DEBUG : file14: md5 = 65c1205fcfd72a2b49cafdf88720e625 OK
2026/10/12 04:44:18 DEBUG : file15: md5 = 3b3ac01941c03fdee4e797fea534087b OK
2026/10/12 04:44:20 DEBUG : file16: md5 = 4b67b68b4e76e732e2a92a91b3519a44 OK
2026/10/12 04:44:22 DEBUG : file17: md5 = 5d3e1c1b6e9e8e15667b8ea637899255 OK
2026/10/12 04:44:24 DEBUG : file18: md5 = 598c1193ff08ae14a34e16118df6b01f OK
2026/10/12 04:44:26 DEBUG : file19: md5 = 17330e60cd1579c56143f7b313a79457 OK
2026/10/12 04:44:26 DEBUG : Waiting for deletions to finish
2026/10/12 04:44:26 ERROR : file2: Got fatal error on delete: --max-delete threshold reached
2026/10/12 04:44:26 ERROR : file0: Got fatal error on delete: --max-delete threshold reached
2026/10/12 04:44:26 ERROR : file13: Got fatal error on delete: --max-delete threshold reached
2026/10/12 04:44:26 ERROR : file18: Got fatal error on delete: --max-delete threshold reached
2026/10/12 04:44:26 ERROR : file7: Got fatal error on delete: --max-delete threshold reached
2026/10/12 04:44:26 ERROR : file12: Got fatal error on delete: --max-delete threshold reached
2026/10/12 04:44:26 ERROR : file11: Got fatal error on delete: --max-delete threshold reached
2026/10/12 04:44:26 ERROR : file3: Got fatal error on delete: --max-delete threshold reached
2026/10/12 04:44:26 ERROR : file14: Got fatal error on delete: --max-delete threshold reached
2026/10/12 04:44:26 ERROR : file17: Got fatal error on delete: --max-delete threshold reached
2026/10/12 04:44:26 ERROR : file19: Got fatal error on delete: --max-delete threshold reached
2026/10/12 04:44:26 ERROR : file4: Got fatal error on delete: --max-delete threshold reached
2026/10/12 04:44:26 ERROR : file9: Got fatal error on delete: --max-delete threshold reached
2026/10/12 04:44:26 ERROR : file16: Got fatal error on delete: --max-delete threshold reached
2026/10/12 04:44:26 ERROR : file1: Got fatal error on delete: --max-delete threshold reached
2026/10/12 04:44:26 ERROR : file6: Got fatal error on delete: --max-delete threshold reached
2026/10/12 04:44:26 ERROR : file15: Got fatal error on delete: --max-delete threshold reached
2026/10/12 04:44:26 ERROR : file8: Got fatal error on delete: --max-delete threshold reached
2026/10/12 04:44:26 ERROR : file10: Got fatal error on delete: --max-delete threshold reached
2026/10/12 04:44:26 ERROR : file5: Got fatal error on delete: --max-delete threshold reached
--- PASS: TestDeleteFatalError (54.40s)
=== RUN   TestMaxDelete
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:44:43 DEBUG : small: md5 = 882453b336f6ed380e7f36842896713a OK
2026/10/12 04:44:45 DEBUG : medium: md5 = c0a80f182b82d1d83b5dcb950a6b184d OK
2026/10/12 04:44:47 DEBUG : large: md5 = 2af604b15782b8f04bfaa9eb58c9ffae OK
2026/10/12 04:44:48 DEBUG : Waiting for deletions to finish
2026/10/12 04:44:48 ERROR : medium: Got fatal error on delete: --max-delete threshold reached
2026/10/12 04:44:49 INFO  : large: Deleted
2026/10/12 04:44:49 INFO  : small: Deleted
--- PASS: TestMaxDelete (9.63s)
=== RUN   TestMaxDeleteSizeLargeFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:44:53 DEBUG : small: md5 = cc1b59018424a9a7d52d9d3d42bb510d OK
2026/10/12 04:44:55 DEBUG : medium: md5 = 07d85712418d3c43947e71bcdbbde46d OK
2026/10/12 04:44:57 DEBUG : large: md5 = e36da626eb4b19f4908bd36111a33b37 OK
2026/10/12 04:44:57 DEBUG : Waiting for deletions to finish
2026/10/12 04:44:57 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached
2026/10/12 04:44:58 INFO  : small: Deleted
2026/10/12 04:44:58 INFO  : medium: Deleted
--- PASS: TestMaxDeleteSizeLargeFile (9.13s)
=== RUN   TestMaxDeleteSize
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:45:02 DEBUG : small: md5 = f92dcc6facfbf5148114fd73b47c9737 OK
2026/10/12 04:45:04 DEBUG : medium: md5 = 0866de18f7b5564758d0094497a374c8 OK
2026/10/12 04:45:06 DEBUG : large: md5 = d25a574e6786505005510e1d97af3374 OK
2026/10/12 04:45:07 DEBUG : Waiting for deletions to finish
2026/10/12 04:45:07 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached
2026/10/12 04:45:08 INFO  : medium: Deleted
2026/10/12 04:45:08 INFO  : small: Deleted
--- PASS: TestMaxDeleteSize (9.85s)
=== RUN   TestReadFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:45:12 DEBUG : ReadFile: md5 = 28cb4131bb9a49e4045bf8f5fe6b37fc OK
--- PASS: TestReadFile (5.21s)
=== RUN   TestRetry
2026/10/12 04:45:15 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 1/5
2026/10/12 04:45:15 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 2/5
2026/10/12 04:45:15 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms
2026/10/12 04:45:15 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms
2026/10/12 04:45:15 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms
2026/10/12 04:45:15 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/12 04:45:15 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-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:45:17 DEBUG : file1: md5 = 5dab135f4dc579a8c45d2fdc6f7d904a OK
2026/10/12 04:45:19 DEBUG : file2: md5 = 9d4d4f40a8b36b7a83d80e55fe016f86 OK
--- PASS: TestCat (16.35s)
=== RUN   TestPurge
2026/10/12 04:45:32 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-xitayoz2vebi"
2026/10/12 04:45:32 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/12 04:45:32 DEBUG : Creating backend with remote "TestDrive:crypt/lj2p43036b2q7jprc6sabhsuhmse3gcfmnbdab608931b19i1dt0"
2026/10/12 04:45:33 DEBUG : Creating backend with remote "/tmp/rclone1399703183"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-xitayoz2vebi'", Local "Local file system at /tmp/rclone1399703183", Modify Window "1ms"
2026/10/12 04:45:39 DEBUG : A1/B1/C1/one: md5 = 4602c3b4ffeb9c2090b8cc7ec26d0095 OK
2026/10/12 04:45:39 INFO  : A2: Making directory
2026/10/12 04:45:40 INFO  : A1/B2: Making directory
2026/10/12 04:45:42 INFO  : A1/B2/C2: Making directory
2026/10/12 04:45:43 INFO  : A1/B1/C3: Making directory
2026/10/12 04:45:44 INFO  : A3: Making directory
2026/10/12 04:45:45 INFO  : A3/B3: Making directory
2026/10/12 04:45:46 INFO  : A3/B3/C4: Making directory
2026/10/12 04:45:49 DEBUG : A1/two: md5 = 6653ed3e7298612a8bf1d952f81631cf OK
2026/10/12 04:45:54 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-xitayoz2vebi': Purge remote
2026/10/12 04:45:55 NOTICE: purge failed: directory not found
--- PASS: TestPurge (22.99s)
=== RUN   TestRmdirsNoLeaveRoot
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:46:00 DEBUG : A1/B1/C1/one: md5 = 70886dc750e9c12ed4819a1bd2eee7b2 OK
2026/10/12 04:46:00 INFO  : A2: Making directory
2026/10/12 04:46:01 INFO  : A1/B2: Making directory
2026/10/12 04:46:02 INFO  : A1/B2/C2: Making directory
2026/10/12 04:46:03 INFO  : A1/B1/C3: Making directory
2026/10/12 04:46:04 INFO  : A3: Making directory
2026/10/12 04:46:05 INFO  : A3/B3: Making directory
2026/10/12 04:46:06 INFO  : A3/B3/C4: Making directory
2026/10/12 04:46:09 DEBUG : A1/two: md5 = 59ff9037706a4afc2c9ef9868fd1625a OK
2026/10/12 04:46:10 DEBUG : removing 1 level 3 directories
2026/10/12 04:46:10 INFO  : A3/B3/C4: Removing directory
2026/10/12 04:46:14 DEBUG : removing 2 level 3 directories
2026/10/12 04:46:14 INFO  : A1/B2/C2: Removing directory
2026/10/12 04:46:14 INFO  : A1/B1/C3: Removing directory
2026/10/12 04:46:15 DEBUG : removing 2 level 2 directories
2026/10/12 04:46:15 INFO  : A3/B3: Removing directory
2026/10/12 04:46:15 INFO  : A1/B2: Removing directory
2026/10/12 04:46:16 DEBUG : removing 2 level 1 directories
2026/10/12 04:46:16 INFO  : A3: Removing directory
2026/10/12 04:46:16 INFO  : A2: Removing directory
2026/10/12 04:46:22 DEBUG : removing 1 level 3 directories
2026/10/12 04:46:22 INFO  : A1/B1/C1: Removing directory
2026/10/12 04:46:23 DEBUG : removing 1 level 2 directories
2026/10/12 04:46:23 INFO  : A1/B1: Removing directory
2026/10/12 04:46:24 DEBUG : removing 1 level 1 directories
2026/10/12 04:46:24 INFO  : A1: Removing directory
2026/10/12 04:46:25 DEBUG : removing 1 level 0 directories
2026/10/12 04:46:25 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe': Removing directory
--- PASS: TestRmdirsNoLeaveRoot (31.83s)
=== RUN   TestRmdirsLeaveRoot
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:46:28 INFO  : A1: Making directory
2026/10/12 04:46:29 INFO  : A1/B1: Making directory
2026/10/12 04:46:30 INFO  : A1/B1/C1: Making directory
2026/10/12 04:46:33 DEBUG : removing 1 level 3 directories
2026/10/12 04:46:33 INFO  : A1/B1/C1: Removing directory
2026/10/12 04:46:35 DEBUG : removing 1 level 2 directories
2026/10/12 04:46:35 INFO  : A1/B1: Removing directory
--- PASS: TestRmdirsLeaveRoot (11.80s)
=== RUN   TestRmdirsWithFilter
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:46:38 INFO  : A1: Making directory
2026/10/12 04:46:39 INFO  : A1/B1: Making directory
2026/10/12 04:46:41 INFO  : A1/B1/C1: Making directory
2026/10/12 04:46:44 DEBUG : removing 1 level 3 directories
2026/10/12 04:46:44 INFO  : A1/B1/C1: Removing directory
2026/10/12 04:46:45 DEBUG : removing 1 level 2 directories
2026/10/12 04:46:45 INFO  : A1/B1: Removing directory
--- PASS: TestRmdirsWithFilter (10.39s)
=== RUN   TestCopyURL
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:46:51 DEBUG : file1: md5 = 5b5a7a28c4b2eb5ebf8084f9f1ba7221 OK
2026/10/12 04:46:51 DEBUG : file1: size = 14 OK
2026/10/12 04:46:51 DEBUG : filename.txt: File name found in url
2026/10/12 04:46:53 DEBUG : filename.txt: md5 = 6b8aa8836c2097fdd6196ca8b64ee872 OK
2026/10/12 04:46:53 DEBUG : filename.txt: size = 14 OK
2026/10/12 04:46:53 DEBUG : headerfilename.txt: filename found in Content-Disposition header.
2026/10/12 04:46:55 DEBUG : headerfilename.txt: md5 = ed5fe3735d3e0185ed7f9a0d82d5eb2c OK
2026/10/12 04:46:55 DEBUG : headerfilename.txt: size = 14 OK
2026/10/12 04:46:57 DEBUG : file2: md5 = 1d12d7ff18ffa99ddd128969370164f2 OK
2026/10/12 04:46:57 DEBUG : file2: size = 14 OK
--- PASS: TestCopyURL (11.99s)
=== RUN   TestCopyURLDownloadHeaders
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:47:03 DEBUG : file1: md5 = 01401c2ee8460e6e68346aeaf61251e4 OK
2026/10/12 04:47:03 DEBUG : file1: size = 14 OK
--- PASS: TestCopyURLDownloadHeaders (4.01s)
=== RUN   TestCopyURLToWriter
--- PASS: TestCopyURLToWriter (0.00s)
=== RUN   TestMoveFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:47:05 DEBUG : file1: Need to transfer - File not found at Destination
2026/10/12 04:47:08 DEBUG : sub/file2: md5 = 055f3346f23169029bc20de10e7b674e OK
2026/10/12 04:47:08 DEBUG : sub/file2: size = 14 OK
2026/10/12 04:47:08 INFO  : file1: Copied (new) to: sub/file2
2026/10/12 04:47:08 INFO  : file1: Deleted
2026/10/12 04:47:09 DEBUG : sub/file2: size = 14 OK
2026/10/12 04:47:09 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2026/10/12 04:47:09 DEBUG : file1: Unchanged skipping
2026/10/12 04:47:09 INFO  : file1: Deleted
2026/10/12 04:47:09 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe': don't need to copy/move sub/file2, it is already at target location
--- PASS: TestMoveFile (8.14s)
=== RUN   TestMoveFileWithIgnoreExisting
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:47:13 DEBUG : file1: Need to transfer - File not found at Destination
2026/10/12 04:47:15 DEBUG : file1: md5 = 65a5492049aa1397cb8cf635fd7662b5 OK
2026/10/12 04:47:15 DEBUG : file1: size = 14 OK
2026/10/12 04:47:15 INFO  : file1: Copied (new)
2026/10/12 04:47:15 INFO  : file1: Deleted
2026/10/12 04:47:16 DEBUG : file1: Destination exists, skipping
2026/10/12 04:47:16 DEBUG : file1: Not removing source file as destination file exists and --ignore-existing is set
--- PASS: TestMoveFileWithIgnoreExisting (5.43s)
=== RUN   TestMoveFileImmutable
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:47:20 DEBUG : existing: md5 = 10789c7f63a20ed25f1d6d383b0d2f3d OK
2026/10/12 04:47:21 DEBUG : existing: size = 8 (Local file system at /tmp/rclone3458124860)
2026/10/12 04:47:21 DEBUG : existing: size = 6 (Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe')
2026/10/12 04:47:21 DEBUG : existing: Sizes differ
2026/10/12 04:47:21 ERROR : existing: Source and destination exist but do not match: immutable file modified
--- PASS: TestMoveFileImmutable (4.60s)
=== RUN   TestCaseInsensitiveMoveFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
--- PASS: TestCaseInsensitiveMoveFile (0.56s)
=== RUN   TestCaseInsensitiveMoveFileDryRun
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
--- PASS: TestCaseInsensitiveMoveFileDryRun (0.61s)
=== RUN   TestMoveFileBackupDir
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:47:27 DEBUG : dst/file1: md5 = a9e5619ec76a1fabed732346f7f2f082 OK
2026/10/12 04:47:28 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-soyebah2yafe/backup"
2026/10/12 04:47:28 DEBUG : Creating backend with remote "TestDrive:crypt/3lr4390jip2q9g8ao5ds7vf9as0a7itvio95vb7qd4i7vahepmpg/1nrff024r7pq65ecp72fc28jb0"
2026/10/12 04:47:30 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone3458124860)
2026/10/12 04:47:30 DEBUG : dst/file1: size = 18 (Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe')
2026/10/12 04:47:30 DEBUG : dst/file1: Sizes differ
2026/10/12 04:47:34 INFO  : dst/file1: Moved (server-side)
2026/10/12 04:47:36 DEBUG : dst/file1: md5 = f53e9fb0416de6dc66f5c4c71aeb7e60 OK
2026/10/12 04:47:36 DEBUG : dst/file1: size = 14 OK
2026/10/12 04:47:36 INFO  : dst/file1: Copied (new)
2026/10/12 04:47:36 INFO  : dst/file1: Deleted
--- PASS: TestMoveFileBackupDir (18.55s)
=== 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-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:47:45 DEBUG : A/file0: md5 = 7d4091040ae2ed093543aeb020f5d564 OK
2026/10/12 04:47:47 DEBUG : A/file1: md5 = 0732d9bcbe47db52f6b47945f390668f OK
2026/10/12 04:47:49 DEBUG : A/file2: md5 = e647d74c2236247017f65550a8bdbc49 OK
2026/10/12 04:47:51 DEBUG : A/file3: md5 = fafe5f652ef9c282789e9ed4aef3d1e6 OK
2026/10/12 04:47:53 DEBUG : A/file4: md5 = fa97768867e74a7c3b92c74c465b966e OK
2026/10/12 04:47:55 DEBUG : A/file5: md5 = 5347c8712366034a0594d28761d93985 OK
2026/10/12 04:47:57 DEBUG : A/file6: md5 = ba6ad1b8ad5e111828d4ceb219071476 OK
2026/10/12 04:47:59 DEBUG : A/file7: md5 = d336200628c1d58022d8192e90e9d7ac OK
2026/10/12 04:48:01 DEBUG : A/file8: md5 = a684e620b0e3da573632b8ae4ab14d8d OK
2026/10/12 04:48:03 DEBUG : A/file9: md5 = 11b57be2b1a6f42d10916064a1345aec OK
2026/10/12 04:48:05 DEBUG : A/file10: md5 = 8ba85891f7912e4cf21a46713500a52a OK
2026/10/12 04:48:07 DEBUG : A/file11: md5 = 613088be9e91a426872dfdb3f8d223b0 OK
2026/10/12 04:48:09 DEBUG : A/file12: md5 = 01057ce295787025a6267a189e1fb44f OK
2026/10/12 04:48:11 DEBUG : A/file13: md5 = 95872b8ddbac0daab0914aa978bd6cec OK
2026/10/12 04:48:12 DEBUG : A/file14: md5 = ad57e41f5800a211dc4f945ac4988c3c OK
2026/10/12 04:48:14 DEBUG : A/file15: md5 = 4a07ce21899bb03a099b14bcf74d55bb OK
2026/10/12 04:48:16 DEBUG : A/file16: md5 = b3b76dc7e1a0ffd5a2040a6622e47488 OK
2026/10/12 04:48:18 DEBUG : A/file17: md5 = c627f3613c03d573c3d8b37f0b723113 OK
2026/10/12 04:48:19 DEBUG : A/file18: md5 = 7beabc9bb5d955acb18be500bb1c24fe OK
2026/10/12 04:48:21 DEBUG : A/file19: md5 = 5260f39c4dee7a0af8a4dbae78e6769b OK
    operations_test.go:1490: Skipping test on non local remote
--- SKIP: TestDirMoveMoveError (54.61s)
=== RUN   TestDirMoveContext
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:48:41 DEBUG : A/one: md5 = 837ab8be60522332d338e457ddcf9732 OK
2026/10/12 04:48:43 DEBUG : A/two: md5 = 31fbbcbac674c3c2b59d8b5237d85660 OK
    operations_test.go:1490: Skipping test on non local remote
--- SKIP: TestDirMoveContext (9.00s)
=== RUN   TestDirMove
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:48:49 DEBUG : A1/one: md5 = 4a22174f6902098b735fbcbaea0b49c4 OK
2026/10/12 04:48:51 DEBUG : A1/two: md5 = 623415cb6719e50f5efafd6864e3771d OK
2026/10/12 04:48:54 DEBUG : A1/B1/three: md5 = 644f0fed8fe33100ee973f7e212972bd OK
2026/10/12 04:48:57 DEBUG : A1/B1/C1/four: md5 = d9a5298cf93766cb46d306dc535efe76 OK
2026/10/12 04:48:59 DEBUG : A1/B1/C2/five: md5 = ae0d428e23023ec7cde10caf46e8c324 OK
2026/10/12 04:48:59 INFO  : A1/B2: Making directory
2026/10/12 04:49:00 INFO  : A1/B1/C3: Making directory
2026/10/12 04:49:15 INFO  : A2/B1/three: Moved (server-side) to: A3/B1/three
2026/10/12 04:49:15 INFO  : A2/one: Moved (server-side) to: A3/one
2026/10/12 04:49:15 INFO  : A2/B1/C2/five: Moved (server-side) to: A3/B1/C2/five
2026/10/12 04:49:15 INFO  : A2/two: Moved (server-side) to: A3/two
2026/10/12 04:49:15 INFO  : A2/B1/C1/four: Moved (server-side) to: A3/B1/C1/four
2026/10/12 04:49:23 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe': Can't DirMove - falling back to file moves: can't move directory - incompatible remotes
2026/10/12 04:49:33 INFO  : A3/B1/three: Moved (server-side) to: A4/B1/three
2026/10/12 04:49:33 INFO  : A3/two: Moved (server-side) to: A4/two
2026/10/12 04:49:34 INFO  : A3/B1/C1/four: Moved (server-side) to: A4/B1/C1/four
2026/10/12 04:49:34 INFO  : A3/B1/C2/five: Moved (server-side) to: A4/B1/C2/five
2026/10/12 04:49:34 INFO  : A3/one: Moved (server-side) to: A4/one
--- PASS: TestDirMove (68.68s)
=== RUN   TestGetFsInfo
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
--- PASS: TestGetFsInfo (0.68s)
=== RUN   TestRcat
=== RUN   TestRcat/withChecksum=false,ignoreChecksum=false
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:49:56 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe': File to upload is small (34 bytes), uploading instead of streaming
2026/10/12 04:49:58 DEBUG : no_checksum_small_file_from_pipe: md5 = 169f280af3b844f5639ab0194645951c OK
2026/10/12 04:49:58 DEBUG : no_checksum_small_file_from_pipe: size = 34 OK
2026/10/12 04:49:58 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only
2026/10/12 04:49:58 DEBUG : no_checksum_small_file_from_pipe: Size of src and dst objects identical
2026/10/12 04:49:59 DEBUG : qc9k4uauqa9ls6a2r6lo8jf4b1m7r0510pkurois1cc70egbs4h0: Sending chunk 0 length 102465
2026/10/12 04:50:00 DEBUG : no_checksum_big_file_from_pipe: md5 = e0aaa01a856a53ee1539cbf28bb3cf82 OK
2026/10/12 04:50:00 DEBUG : no_checksum_big_file_from_pipe: size = 102401 OK
2026/10/12 04:50:00 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-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:50:03 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe': File to upload is small (34 bytes), uploading instead of streaming
2026/10/12 04:50:04 DEBUG : with_checksum_small_file_from_pipe: md5 = 1f3b133aa6d02913010a1931c5be9bd7 OK
2026/10/12 04:50:04 DEBUG : with_checksum_small_file_from_pipe: size = 34 OK
2026/10/12 04:50:04 DEBUG : with_checksum_small_file_from_pipe: Size of src and dst objects identical
2026/10/12 04:50:05 DEBUG : e74jo8a7lr1aimg2u78fct67gnuetc1qujiofaci0c8fnq7rjt1d4i296pcklqhgdihs03etvgdi4: Sending chunk 0 length 102465
2026/10/12 04:50:07 DEBUG : with_checksum_big_file_from_pipe: md5 = 11b021d03e6f71bbc3823d8acdd60474 OK
2026/10/12 04:50:07 DEBUG : with_checksum_big_file_from_pipe: size = 102401 OK
2026/10/12 04:50:07 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-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:50:09 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe': File to upload is small (34 bytes), uploading instead of streaming
2026/10/12 04:50:11 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK
2026/10/12 04:50:11 DEBUG : ignore_checksum_small_file_from_pipe: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2026/10/12 04:50:12 DEBUG : 1963rn6olajfbjn4nbqi9nacu7bk879vpf0d2bg8r0ndcqqsfntob0urt056p0qujf574rqnb5f62: Sending chunk 0 length 102465
2026/10/12 04:50:13 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK
2026/10/12 04:50:13 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-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:50:16 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe': File to upload is small (34 bytes), uploading instead of streaming
2026/10/12 04:50:18 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK
2026/10/12 04:50:18 DEBUG : ignore_checksum_small_file_from_pipe: Size of src and dst objects identical
2026/10/12 04:50:19 DEBUG : 1963rn6olajfbjn4nbqi9nacu7bk879vpf0d2bg8r0ndcqqsfntob0urt056p0qujf574rqnb5f62: Sending chunk 0 length 102465
2026/10/12 04:50:20 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK
2026/10/12 04:50:20 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical
--- PASS: TestRcat (27.32s)
    --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (6.96s)
    --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (6.32s)
    --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (6.91s)
    --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (7.15s)
=== RUN   TestRcatMetadata
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
=== RUN   TestRcatMetadata/Normal
2026/10/12 04:50:23 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe': File to upload is small (48 bytes), uploading instead of streaming
2026/10/12 04:50:25 DEBUG : rcat_metadata: md5 = 8918c38961e58042122ee1c4b55faef4 OK
2026/10/12 04:50:25 DEBUG : rcat_metadata: size = 48 OK
2026/10/12 04:50:25 DEBUG : rcat_metadata: Size of src and dst objects identical
=== RUN   TestRcatMetadata/ViaDisk
2026/10/12 04:50:27 DEBUG : rijssoobhbn85c5p1imhg55m6frank1nlplla6hkdjlqmqb2471g: Sending chunk 0 length 111
2026/10/12 04:50:29 DEBUG : rcat_metadata_uploadcutoff0: md5 = 1501818970dc47210ea34458faf0c238 OK
2026/10/12 04:50:29 DEBUG : rcat_metadata_uploadcutoff0: size = 63 OK
2026/10/12 04:50:29 DEBUG : rcat_metadata_uploadcutoff0: Size of src and dst objects identical
--- PASS: TestRcatMetadata (8.69s)
    --- PASS: TestRcatMetadata/Normal (3.43s)
    --- PASS: TestRcatMetadata/ViaDisk (3.72s)
=== RUN   TestRcatSize
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:50:33 DEBUG : potato1: md5 = be0f01885ee56b7551eba96c95276b25 OK
2026/10/12 04:50:33 DEBUG : potato1: size = 60 OK
2026/10/12 04:50:33 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe': File to upload is small (60 bytes), uploading instead of streaming
2026/10/12 04:50:35 DEBUG : potato2: md5 = a597fcf75c349925cc78797ba47f57e4 OK
2026/10/12 04:50:35 DEBUG : potato2: size = 60 OK
2026/10/12 04:50:35 DEBUG : potato2: Size of src and dst objects identical
--- PASS: TestRcatSize (6.34s)
=== RUN   TestRcatSizeShortEOF
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:50:40 DEBUG : potato1: md5 = c9da9dcf8fd429aebd9941aa64d3596a OK
2026/10/12 04:50:40 DEBUG : potato1: size = 120 (Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe')
2026/10/12 04:50:40 DEBUG : potato1: size = 60 (Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe')
2026/10/12 04:50:40 ERROR : potato1: corrupted on transfer: sizes differ src 120 vs dst(Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe') 60
2026/10/12 04:50:40 INFO  : potato1: Removing failed copy
--- PASS: TestRcatSizeShortEOF (2.97s)
=== RUN   TestRcatSizeMetadata
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:50:43 DEBUG : potato1: md5 = ab985f36a0562c127dda8af2a60ac2b0 OK
2026/10/12 04:50:43 DEBUG : potato1: size = 60 OK
2026/10/12 04:50:43 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe': File to upload is small (60 bytes), uploading instead of streaming
2026/10/12 04:50:45 DEBUG : potato2: md5 = 3bf2ed1da1f4203deffcebdccf9b2d1f OK
2026/10/12 04:50:45 DEBUG : potato2: size = 60 OK
2026/10/12 04:50:45 DEBUG : potato2: Size of src and dst objects identical
--- PASS: TestRcatSizeMetadata (6.98s)
=== RUN   TestRcatSizeUploadHeaders
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:50:50 DEBUG : potato1: md5 = 0b853ac4df283ab9b56161626b94b037 OK
2026/10/12 04:50:50 DEBUG : potato1: size = 60 OK
--- PASS: TestRcatSizeUploadHeaders (3.28s)
=== RUN   TestRcatSizeChecksum
=== RUN   TestRcatSizeChecksum/Corrupted
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", 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-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:50:54 DEBUG : potato4: md5 = 1a3397d0ef0072fe601b65d3946f0f23 OK
2026/10/12 04:50:54 DEBUG : potato4: size = 60 (Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe')
2026/10/12 04:50:54 DEBUG : potato4: size = 59 (Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe')
2026/10/12 04:50:54 ERROR : potato4: corrupted on transfer: sizes differ src 60 vs dst(Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe') 59
2026/10/12 04:50:54 INFO  : potato4: Removing failed copy
=== RUN   TestRcatSizeChecksum/IgnoreChecksum
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:50:57 DEBUG : potato2: size = 60 OK
=== RUN   TestRcatSizeChecksum/NoHashes
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:51:01 DEBUG : potato3: md5 = 3452c5350f016ba9de2347f552493655 OK
2026/10/12 04:51:01 DEBUG : potato3: size = 60 OK
--- PASS: TestRcatSizeChecksum (11.19s)
    --- SKIP: TestRcatSizeChecksum/Corrupted (0.59s)
    --- PASS: TestRcatSizeChecksum/SizeDiffers (3.46s)
    --- PASS: TestRcatSizeChecksum/IgnoreChecksum (3.14s)
    --- PASS: TestRcatSizeChecksum/NoHashes (4.00s)
=== RUN   TestTouchDir
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:51:04 DEBUG : potato2: md5 = ce0b693cdb9965acdb1d091595e88e0c OK
2026/10/12 04:51:06 DEBUG : empty space: md5 = efc4935fb71515818bf27bbe568f2a86 OK
2026/10/12 04:51:09 DEBUG : sub dir/potato3: md5 = 755692036d03f93fa764edeb27681229 OK
2026/10/12 04:51:10 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe': Touching "sub dir/potato3"
2026/10/12 04:51:10 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe': Touching "potato2"
2026/10/12 04:51:10 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe': Touching "empty space"
--- PASS: TestTouchDir (13.59s)
=== RUN   TestMkdirMetadata
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:51:16 DEBUG : dir with metadata: Making directory with metadata
2026/10/12 04:51:17 INFO  : dir with metadata: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z)
--- PASS: TestMkdirMetadata (3.28s)
=== RUN   TestMkdirModTime
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:51:19 DEBUG : directory with modtime: Making directory with metadata
2026/10/12 04:51:20 INFO  : directory with modtime: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2026/10/12 04:51:21 NOTICE: dry run directory with modtime: Skipped make directory as --dry-run is set
--- PASS: TestMkdirModTime (3.40s)
=== RUN   TestCopyDirMetadata
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:51:23 DEBUG : dir with metadata to be copied: Making directory with metadata
2026/10/12 04:51:23 INFO  : dir with metadata to be copied: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z)
2026/10/12 04:51:23 DEBUG : Google drive root 'crypt/3lr4390jip2q9g8ao5ds7vf9as0a7itvio95vb7qd4i7vahepmpg': Skipping btime metadata as can't update it on an existing file: 2026-10-12T04:51:23.076616182Z
2026/10/12 04:51:24 INFO  : non existent directory: Updated directory metadata
2026/10/12 04:51:27 DEBUG : Google drive root 'crypt/3lr4390jip2q9g8ao5ds7vf9as0a7itvio95vb7qd4i7vahepmpg': Skipping btime metadata as can't update it on an existing file: 2026-10-12T04:51:23.076616182Z
2026/10/12 04:51:27 INFO  : existing directory: Updated directory metadata
--- PASS: TestCopyDirMetadata (7.16s)
=== RUN   TestSetDirModTime
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:51:30 DEBUG : set modtime on non existent directory: Skipping set directory modification time as --no-update-dir-modtime is set
2026/10/12 04:51:32 INFO  : set modtime on existing directory: Set directory modification time (using SetModTime)
2026/10/12 04:51:33 INFO  : set modtime on existing directory: Set directory modification time (using DirSetModTime)
--- PASS: TestSetDirModTime (5.83s)
=== RUN   TestDirsEqual
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:51:36 DEBUG : dir with metadata to be copied: Making directory with metadata
2026/10/12 04:51:36 INFO  : dir with metadata to be copied: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z)
2026/10/12 04:51:36 DEBUG : dst: Making directory with metadata
2026/10/12 04:51:37 INFO  : dst: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z)
2026/10/12 04:51:37 DEBUG : dst: Directory modification time the same (differ by -999.999µs, within tolerance 1ms)
2026/10/12 04:51:38 INFO  : dst: Set directory modification time (using SetModTime)
2026/10/12 04:51:38 INFO  : dst: Set directory modification time (using SetModTime)
2026/10/12 04:51:38 DEBUG : dst: Directory modification time the same (differ by 1ns, within tolerance 1ms)
2026/10/12 04:51:39 INFO  : dst: Set directory modification time (using SetModTime)
2026/10/12 04:51:39 DEBUG : dst: Destination directory is newer than source, skipping
--- PASS: TestDirsEqual (5.45s)
=== RUN   TestRemoveExisting
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe'", Local "Local file system at /tmp/rclone3458124860", Modify Window "1ms"
2026/10/12 04:51:44 DEBUG : sub dir/test remove existing: md5 = 8335360a9a21b0c9550a7ba2b068d760 OK
2026/10/12 04:51:46 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: md5 = cfaafcb7ec4b1e8361aa00c6a82b85d4 OK
2026/10/12 04:51:49 DEBUG : sub dir/test remove existing: TEST: renaming existing object to "sub dir/test remove existing.gicebex1" before starting
2026/10/12 04:51:50 DEBUG : sub dir/test remove existing.gicebex1: TEST: removing renamed existing file after operation
2026/10/12 04:51:52 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: TEST: renaming existing object to "sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.bamunit7" before starting
2026/10/12 04:51:53 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.bamunit7: TEST: renaming existing back after failed operation
2026/10/12 04:51:55 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: TEST: renaming existing object to "sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.jaxosuy0" before starting
2026/10/12 04:51:56 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.jaxosuy0: TEST: removing renamed existing file after operation
--- PASS: TestRemoveExisting (18.52s)
=== RUN   TestRcatInputFailurePreservesDestination
2026/10/12 04:52:00 DEBUG : Creating backend with remote "/tmp/TestRcatInputFailurePreservesDestination3887219321/001"
2026/10/12 04:52:00 DEBUG : Config file has changed externally - reloading
--- PASS: TestRcatInputFailurePreservesDestination (0.01s)
=== 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/12 04:52:00 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-soyebah2yafe': Purge remote
"./operations.test -test.v -test.timeout 1h0m0s -remote TestCryptDrive: -verbose" - Finished OK in 29m7.519913976s (try 1/5)