"./operations.test -test.v -test.timeout 1h0m0s -remote TestCryptDrive: -verbose" - Starting (try 1/5)
2026/10/24 01:20:26 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-kinejoz2jilo"
2026/10/24 01:20:26 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/24 01:20:26 DEBUG : Creating backend with remote "TestDrive:crypt/vt4em8t9kto0583k70d1lau4i0it8fsqmo0drh6gl1jsnqt46rl0"
2026/10/24 01:20:27 DEBUG : Creating backend with remote "/tmp/rclone2244109848"
=== 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-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
    multithread_test.go:120: multithread writing not supported
--- SKIP: TestMultithreadCopy (0.23s)
=== RUN   TestMultithreadCopyAbort
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
    multithread_test.go:120: multithread writing not supported
--- SKIP: TestMultithreadCopyAbort (0.28s)
=== RUN   TestMultithreadCopyWriterAtErrors
=== RUN   TestMultithreadCopyWriterAtErrors/open/fatal=false
2026/10/24 01:20:28 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt
=== RUN   TestMultithreadCopyWriterAtErrors/open/fatal=true
2026/10/24 01:20:28 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt
=== RUN   TestMultithreadCopyWriterAtErrors/write/fatal=false
2026/10/24 01:20:28 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt
2026/10/24 01:20:28 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams
2026/10/24 01:20:28 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting
2026/10/24 01:20:28 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting
2026/10/24 01:20:28 DEBUG : file.txt: writing chunk 0
2026/10/24 01:20:28 DEBUG : file.txt: multi-thread copy: chunk 1/4 failed: multi-thread copy: failed to write chunk: disk full
2026/10/24 01:20:28 DEBUG : file.txt: writing chunk 1
2026/10/24 01:20:28 DEBUG : file.txt: multi-thread copy: chunk 2/4 failed: multi-thread copy: failed to write chunk: disk full
2026/10/24 01:20:28 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting
2026/10/24 01:20:28 DEBUG : file.txt: writing chunk 2
2026/10/24 01:20:28 DEBUG : file.txt: multi-thread copy: chunk 3/4 failed: multi-thread copy: failed to write chunk: disk full
2026/10/24 01:20:28 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit
2026/10/24 01:20:28 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/24 01:20:28 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt
2026/10/24 01:20:28 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams
2026/10/24 01:20:28 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting
2026/10/24 01:20:28 DEBUG : file.txt: writing chunk 1
2026/10/24 01:20:28 DEBUG : file.txt: multi-thread copy: chunk 2/4 failed: multi-thread copy: failed to write chunk: disk full
2026/10/24 01:20:28 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting
2026/10/24 01:20:28 DEBUG : file.txt: writing chunk 2
2026/10/24 01:20:28 DEBUG : file.txt: multi-thread copy: chunk 3/4 failed: multi-thread copy: failed to write chunk: disk full
2026/10/24 01:20:28 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting
2026/10/24 01:20:28 DEBUG : file.txt: writing chunk 0
2026/10/24 01:20:28 DEBUG : file.txt: multi-thread copy: chunk 1/4 failed: multi-thread copy: failed to write chunk: disk full
2026/10/24 01:20:28 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit
2026/10/24 01:20:28 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/24 01:20:28 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt
2026/10/24 01:20:28 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams
2026/10/24 01:20:28 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting
2026/10/24 01:20:28 DEBUG : file.txt: writing chunk 1
2026/10/24 01:20:28 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 finished
2026/10/24 01:20:28 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting
2026/10/24 01:20:28 DEBUG : file.txt: writing chunk 2
2026/10/24 01:20:28 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 finished
2026/10/24 01:20:28 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 starting
2026/10/24 01:20:28 DEBUG : file.txt: writing chunk 3
2026/10/24 01:20:28 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 finished
2026/10/24 01:20:28 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting
2026/10/24 01:20:28 DEBUG : file.txt: writing chunk 0
2026/10/24 01:20:28 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 finished
2026/10/24 01:20:28 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit
2026/10/24 01:20:28 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/24 01:20:28 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt
2026/10/24 01:20:28 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams
2026/10/24 01:20:28 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting
2026/10/24 01:20:28 DEBUG : file.txt: writing chunk 1
2026/10/24 01:20:28 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 finished
2026/10/24 01:20:28 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting
2026/10/24 01:20:28 DEBUG : file.txt: writing chunk 2
2026/10/24 01:20:28 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 finished
2026/10/24 01:20:28 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 starting
2026/10/24 01:20:28 DEBUG : file.txt: writing chunk 3
2026/10/24 01:20:28 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 finished
2026/10/24 01:20:28 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting
2026/10/24 01:20:28 DEBUG : file.txt: writing chunk 0
2026/10/24 01:20:28 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 finished
2026/10/24 01:20:28 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit
2026/10/24 01:20:28 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/24 01:20:28 DEBUG : a: size = 0 OK
2026/10/24 01:20:28 DEBUG : a: size = 1 (memory)
2026/10/24 01:20:28 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/24 01:20:28 DEBUG : potato: Seek from 10 to 0
=== RUN   TestReOpen/Normal/ErrorAtStart
=== RUN   TestReOpen/Normal/WithErrors
2026/10/24 01:20:28 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2026/10/24 01:20:28 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2026/10/24 01:20:28 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN   TestReOpen/Normal/TooManyErrors
2026/10/24 01:20:28 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2026/10/24 01:20:28 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2026/10/24 01:20:28 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2026/10/24 01:20:28 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN   TestReOpen/Normal/ReadAt
2026/10/24 01:20:28 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/10/24 01:20:28 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/10/24 01:20:28 DEBUG : potato: Seek from 5 to 1
=== RUN   TestReOpen/Normal/Seek
2026/10/24 01:20:28 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/10/24 01:20:28 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/10/24 01:20:28 DEBUG : potato: Seek from 5 to 2
=== RUN   TestReOpen/Normal/AccountRead
=== RUN   TestReOpen/Normal/AccountReadDelay
2026/10/24 01:20:28 DEBUG : potato: Seek from 10 to 0
2026/10/24 01:20:28 DEBUG : potato: Seek from 10 to 0
2026/10/24 01:20:28 DEBUG : potato: Seek from 10 to 0
=== RUN   TestReOpen/Normal/AccountReadError
=== RUN   TestReOpen/WithRangeOption
=== RUN   TestReOpen/WithRangeOption/Basics
2026/10/24 01:20:28 DEBUG : potato: Seek from 7 to 0
=== RUN   TestReOpen/WithRangeOption/ErrorAtStart
=== RUN   TestReOpen/WithRangeOption/WithErrors
2026/10/24 01:20:28 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2026/10/24 01:20:28 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2026/10/24 01:20:28 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN   TestReOpen/WithRangeOption/TooManyErrors
2026/10/24 01:20:28 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2026/10/24 01:20:28 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2026/10/24 01:20:28 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2026/10/24 01:20:28 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN   TestReOpen/WithRangeOption/ReadAt
2026/10/24 01:20:28 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/10/24 01:20:28 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/10/24 01:20:28 DEBUG : potato: Seek from 5 to 1
=== RUN   TestReOpen/WithRangeOption/Seek
2026/10/24 01:20:28 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/10/24 01:20:28 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/10/24 01:20:28 DEBUG : potato: Seek from 5 to 2
2026/10/24 01:20:28 DEBUG : potato: Seek from 7 to 4
=== RUN   TestReOpen/WithRangeOption/AccountRead
=== RUN   TestReOpen/WithRangeOption/AccountReadDelay
2026/10/24 01:20:28 DEBUG : potato: Seek from 7 to 0
2026/10/24 01:20:28 DEBUG : potato: Seek from 7 to 0
2026/10/24 01:20:28 DEBUG : potato: Seek from 7 to 0
=== RUN   TestReOpen/WithRangeOption/AccountReadError
=== RUN   TestReOpen/WithSeekOption
=== RUN   TestReOpen/WithSeekOption/Basics
2026/10/24 01:20:28 DEBUG : potato: Seek from 8 to 0
=== RUN   TestReOpen/WithSeekOption/ErrorAtStart
=== RUN   TestReOpen/WithSeekOption/WithErrors
2026/10/24 01:20:28 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2026/10/24 01:20:28 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2026/10/24 01:20:28 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN   TestReOpen/WithSeekOption/TooManyErrors
2026/10/24 01:20:28 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2026/10/24 01:20:28 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2026/10/24 01:20:28 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2026/10/24 01:20:28 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN   TestReOpen/WithSeekOption/ReadAt
2026/10/24 01:20:28 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/10/24 01:20:28 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/10/24 01:20:28 DEBUG : potato: Seek from 5 to 1
=== RUN   TestReOpen/WithSeekOption/Seek
2026/10/24 01:20:28 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/10/24 01:20:28 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/10/24 01:20:28 DEBUG : potato: Seek from 5 to 2
2026/10/24 01:20:28 DEBUG : potato: Seek from 7 to 5
=== RUN   TestReOpen/WithSeekOption/AccountRead
=== RUN   TestReOpen/WithSeekOption/AccountReadDelay
2026/10/24 01:20:28 DEBUG : potato: Seek from 8 to 0
2026/10/24 01:20:28 DEBUG : potato: Seek from 8 to 0
2026/10/24 01:20:28 DEBUG : potato: Seek from 8 to 0
=== RUN   TestReOpen/WithSeekOption/AccountReadError
=== RUN   TestReOpen/UnknownSize
=== RUN   TestReOpen/UnknownSize/Basics
2026/10/24 01:20:28 DEBUG : potato: Seek from 9 to 0
=== RUN   TestReOpen/UnknownSize/ErrorAtStart
=== RUN   TestReOpen/UnknownSize/WithErrors
2026/10/24 01:20:28 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2026/10/24 01:20:28 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2026/10/24 01:20:28 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN   TestReOpen/UnknownSize/TooManyErrors
2026/10/24 01:20:28 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2026/10/24 01:20:28 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2026/10/24 01:20:28 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2026/10/24 01:20:28 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN   TestReOpen/UnknownSize/ReadAt
2026/10/24 01:20:28 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/10/24 01:20:28 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/10/24 01:20:28 DEBUG : potato: Seek from 5 to 1
=== RUN   TestReOpen/UnknownSize/Seek
2026/10/24 01:20:28 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/10/24 01:20:28 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/10/24 01:20:28 DEBUG : potato: Seek from 5 to 2
2026/10/24 01:20:28 DEBUG : potato: Seek from 7 to 6
=== RUN   TestReOpen/UnknownSize/AccountRead
=== RUN   TestReOpen/UnknownSize/AccountReadDelay
2026/10/24 01:20:28 DEBUG : potato: Seek from 9 to 0
2026/10/24 01:20:28 DEBUG : potato: Seek from 9 to 0
2026/10/24 01:20:28 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-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:20:30 DEBUG : rutabaga: md5 = 120212000ccf95ea386a78e41e6d8344 OK
=== RUN   TestCheck/1
=== RUN   TestCheck/2
2026/10/24 01:20:32 DEBUG : empty space: md5 = b27e560f38afff09cb5535ce5142ef79 OK
=== RUN   TestCheck/3
2026/10/24 01:20:34 DEBUG : potato2: md5 = 55f8577dd1aec64d104734988f261df8 OK
=== RUN   TestCheck/4
=== RUN   TestCheck/5
2026/10/24 01:20:37 DEBUG : remotepotato: md5 = 22b8d08e738bb8ca78b4ff62807f605f OK
=== RUN   TestCheck/6
=== RUN   TestCheck/7
--- PASS: TestCheck (12.20s)
    --- PASS: TestCheck/1 (0.26s)
    --- PASS: TestCheck/2 (0.26s)
    --- PASS: TestCheck/3 (0.23s)
    --- PASS: TestCheck/4 (0.29s)
    --- PASS: TestCheck/5 (0.26s)
    --- PASS: TestCheck/6 (0.38s)
    --- PASS: TestCheck/7 (0.29s)
=== RUN   TestCheckFsError
2026/10/24 01:20:40 DEBUG : Creating backend with remote "nonexistent"
2026/10/24 01:20:40 DEBUG : Config file has changed externally - reloading
2026/10/24 01:20:40 DEBUG : Creating backend with remote "nonexistent"
2026/10/24 01:20:40 DEBUG : Local file system at nonexistent: Waiting for checks to finish
2026/10/24 01:20:40 ERROR : Local file system at nonexistent: error reading source root directory: directory not found
2026/10/24 01:20:40 NOTICE: Local file system at nonexistent: 0 differences found
2026/10/24 01:20:40 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-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:20:41 DEBUG : rutabaga: md5 = 390a0965c928c62df27d0630e15bc781 OK
=== RUN   TestCheckDownload/1
=== RUN   TestCheckDownload/2
2026/10/24 01:20:45 DEBUG : empty space: md5 = b382c5416f533184b64519248384f7fe OK
=== RUN   TestCheckDownload/3
2026/10/24 01:20:48 DEBUG : potato2: md5 = 867823b4edbc03e5cc0c6c83573483ee OK
=== RUN   TestCheckDownload/4
=== RUN   TestCheckDownload/5
2026/10/24 01:20:51 DEBUG : remotepotato: md5 = 6e5facd120f9f946d1c74ba712631dfd OK
=== RUN   TestCheckDownload/6
=== RUN   TestCheckDownload/7
--- PASS: TestCheckDownload (15.74s)
    --- PASS: TestCheckDownload/1 (1.18s)
    --- PASS: TestCheckDownload/2 (0.80s)
    --- PASS: TestCheckDownload/3 (0.99s)
    --- PASS: TestCheckDownload/4 (0.96s)
    --- PASS: TestCheckDownload/5 (1.11s)
    --- PASS: TestCheckDownload/6 (0.77s)
    --- PASS: TestCheckDownload/7 (0.80s)
=== RUN   TestCheckSizeOnly
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:20:57 DEBUG : rutabaga: md5 = be0c18c9373721f784ed07538966c57a OK
=== RUN   TestCheckSizeOnly/1
=== RUN   TestCheckSizeOnly/2
2026/10/24 01:20:59 DEBUG : empty space: md5 = 8912a911ebab32293ec992ed1e492b4e OK
=== RUN   TestCheckSizeOnly/3
2026/10/24 01:21:01 DEBUG : potato2: md5 = 40da27e1c6f1cc114a8e54a36af38611 OK
=== RUN   TestCheckSizeOnly/4
=== RUN   TestCheckSizeOnly/5
2026/10/24 01:21:04 DEBUG : remotepotato: md5 = e42bb0fc1407936056acaebac712d10b OK
=== RUN   TestCheckSizeOnly/6
=== RUN   TestCheckSizeOnly/7
--- PASS: TestCheckSizeOnly (11.30s)
    --- PASS: TestCheckSizeOnly/1 (0.26s)
    --- PASS: TestCheckSizeOnly/2 (0.28s)
    --- PASS: TestCheckSizeOnly/3 (0.24s)
    --- PASS: TestCheckSizeOnly/4 (0.25s)
    --- PASS: TestCheckSizeOnly/5 (0.26s)
    --- PASS: TestCheckSizeOnly/6 (0.25s)
    --- PASS: TestCheckSizeOnly/7 (0.27s)
=== RUN   TestCheckEqualReaders
--- PASS: TestCheckEqualReaders (0.00s)
=== RUN   TestParseSumFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:21:08 DEBUG : test.sum: md5 = 9a0145cfcda96f8f0ea1dce83997917e OK
2026/10/24 01:21:09 NOTICE: test.sum: improperly formatted checksum line 4
2026/10/24 01:21:09 NOTICE: test.sum: improperly formatted checksum line 5
2026/10/24 01:21:09 NOTICE: test.sum: improperly formatted checksum line 6
2026/10/24 01:21:09 NOTICE: test.sum: 2 warning(s) suppressed...
2026/10/24 01:21:11 DEBUG : test.sum: md5 = b873c12710bae0cc9f7403420ce50f7c OK
2026/10/24 01:21:12 NOTICE: test.sum: improperly formatted checksum line 4
2026/10/24 01:21:12 NOTICE: test.sum: improperly formatted checksum line 5
2026/10/24 01:21:12 NOTICE: test.sum: improperly formatted checksum line 6
2026/10/24 01:21:12 NOTICE: test.sum: 2 warning(s) suppressed...
--- PASS: TestParseSumFile (5.80s)
=== RUN   TestCheckSum
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:21:13 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-kinejoz2jilo/data"
2026/10/24 01:21:13 DEBUG : Config file has changed externally - reloading
2026/10/24 01:21:13 DEBUG : Creating backend with remote "TestDrive:crypt/vt4em8t9kto0583k70d1lau4i0it8fsqmo0drh6gl1jsnqt46rl0/vjrnln8ratgmqakfosrqe8espk"
    check_test.go:354: Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo/data' lacks md5, skipping
--- SKIP: TestCheckSum (2.11s)
=== RUN   TestCheckSumDownload
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:21:15 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-kinejoz2jilo/data"
2026/10/24 01:21:15 DEBUG : Creating backend with remote "TestDrive:crypt/vt4em8t9kto0583k70d1lau4i0it8fsqmo0drh6gl1jsnqt46rl0/vjrnln8ratgmqakfosrqe8espk"
2026/10/24 01:21:19 DEBUG : data/banana: md5 = fed080fb957a93dd8e542370ff727aa3 OK
2026/10/24 01:21:20 DEBUG : test.sum: md5 = 699ee6acad4ed774f51d6b740a5cb194 OK
=== RUN   TestCheckSumDownload/subtest1
2026/10/24 01:21:25 DEBUG : data/potato: md5 = 25afa6bb228e7f5a6d9166b61332d481 OK
2026/10/24 01:21:26 DEBUG : test.sum: md5 = a934c3326517c0542f554c32fe730a77 OK
=== RUN   TestCheckSumDownload/subtest2
2026/10/24 01:21:30 DEBUG : test.sum: md5 = d4dd9a073da05a78176a6de9401066a1 OK
=== RUN   TestCheckSumDownload/subtest3
2026/10/24 01:21:34 DEBUG : test.sum: md5 = 875bef60749e8f9c6be3ab54f18c546b OK
=== RUN   TestCheckSumDownload/subtest4
2026/10/24 01:21:38 DEBUG : test.sum: md5 = 40bcdcf637aaba1ef70bf261f508ff09 OK
=== RUN   TestCheckSumDownload/subtest5
2026/10/24 01:21:42 DEBUG : test.sum: md5 = 4f4084cff26c1575d71b3dfa8eeb5f61 OK
=== RUN   TestCheckSumDownload/subtest6
2026/10/24 01:21:46 DEBUG : data/banana: md5 = a2bdc8d0a1b8e0fd9ddc15447c02d798 OK
2026/10/24 01:21:48 DEBUG : data/potato: md5 = 8858a2912f716f3b528e0b49288a27cf OK
2026/10/24 01:21:49 DEBUG : test.sum: md5 = c5a7eab14189cf52341f8c10f64bf136 OK
=== RUN   TestCheckSumDownload/subtest7
2026/10/24 01:21:52 DEBUG : TestDrive: Token expired
2026/10/24 01:21:52 DEBUG : Config file has changed externally - reloading
2026/10/24 01:21:52 DEBUG : TestDrive: Loaded fresh token from config file
2026/10/24 01:21:52 DEBUG : TestDrive: Token refresh successful
--- PASS: TestCheckSumDownload (39.69s)
    --- PASS: TestCheckSumDownload/subtest1 (2.28s)
    --- PASS: TestCheckSumDownload/subtest2 (1.92s)
    --- PASS: TestCheckSumDownload/subtest3 (2.09s)
    --- PASS: TestCheckSumDownload/subtest4 (2.09s)
    --- PASS: TestCheckSumDownload/subtest5 (1.74s)
    --- PASS: TestCheckSumDownload/subtest6 (1.84s)
    --- PASS: TestCheckSumDownload/subtest7 (1.92s)
=== RUN   TestCheckSumConcurrency
2026/10/24 01:21:55 DEBUG : file0: md5 = 022eb0c0085c731f335df970fafa7446 OK
2026/10/24 01:21:55 DEBUG : file2: md5 = a58f09ae3db8ae4b943fdbaace096072 OK
2026/10/24 01:21:55 DEBUG : file1: md5 = 17fbabbccbdea0e6be9eb64c8c6fea41 OK
2026/10/24 01:21:55 DEBUG : file3: md5 = e0639b17e0744aca9a3ba29c049e59ee OK
2026/10/24 01:21:55 NOTICE: Mock file system at checkSum: 0 differences found
2026/10/24 01:21:55 NOTICE: Mock file system at checkSum: 4 matching files
--- PASS: TestCheckSumConcurrency (0.00s)
=== RUN   TestCheckSumDownloadConcurrency
2026/10/24 01:21:55 DEBUG : file0: md5 = 022eb0c0085c731f335df970fafa7446 OK
2026/10/24 01:21:55 DEBUG : file2: md5 = a58f09ae3db8ae4b943fdbaace096072 OK
2026/10/24 01:21:55 DEBUG : file1: md5 = 17fbabbccbdea0e6be9eb64c8c6fea41 OK
2026/10/24 01:21:55 DEBUG : file3: md5 = e0639b17e0744aca9a3ba29c049e59ee OK
2026/10/24 01:21:55 NOTICE: Mock file system at checkSum: 0 differences found
2026/10/24 01:21:55 NOTICE: Mock file system at checkSum: 4 matching files
--- PASS: TestCheckSumDownloadConcurrency (0.00s)
=== RUN   TestApplyTransforms
2026/10/24 01:21:55 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-zuvuyoz9goma"
2026/10/24 01:21:55 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/24 01:21:55 DEBUG : Creating backend with remote "TestDrive:crypt/68e1jsdumrcjhe8qvg5qhvmqqodcsj6ivt2fg4m2quceqdvakmn0"
2026/10/24 01:21:56 DEBUG : Creating backend with remote "/tmp/rclone693488656"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zuvuyoz9goma'", Local "Local file system at /tmp/rclone693488656", Modify Window "1ms"
2026/10/24 01:21:58 DEBUG : hello, world!: md5 = 739b4f4e5e3d4855fb962cf6665cea26 OK
upper checkfile vs. lower remote (without normalization)
2026/10/24 01:21:58 ERROR : hello, world!: sum not found
2026/10/24 01:21:58 ERROR : HELLO, WORLD!: file not in Encrypted drive 'TestCryptDrive:rclone-test-zuvuyoz9goma'
2026/10/24 01:21:58 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-zuvuyoz9goma': 1 files missing
2026/10/24 01:21:58 NOTICE: 1 hashes missing
2026/10/24 01:21:58 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-zuvuyoz9goma': 1 differences found
2026/10/24 01:21:58 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-zuvuyoz9goma': 2 errors while checking
upper checkfile vs. lower remote (with normalization)
2026/10/24 01:21:59 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/24 01:21:59 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-zuvuyoz9goma': 0 differences found
2026/10/24 01:21:59 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-zuvuyoz9goma': 1 matching files
2026/10/24 01:21:59 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-fifayun6hane"
2026/10/24 01:21:59 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/24 01:21:59 DEBUG : Creating backend with remote "TestDrive:crypt/db3809hja590u4s5govhe43glrms1qsif1htutmrjspqc7m6fchg"
2026/10/24 01:22:01 DEBUG : Creating backend with remote "/tmp/rclone863619901"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fifayun6hane'", Local "Local file system at /tmp/rclone863619901", Modify Window "1ms"
2026/10/24 01:22:03 DEBUG : HELLO, WORLD!: md5 = 66c22f8bd04c915892c459e8842bbce0 OK
lower checkfile vs. upper remote (without normalization)
2026/10/24 01:22:03 ERROR : HELLO, WORLD!: sum not found
2026/10/24 01:22:03 ERROR : hello, world!: file not in Encrypted drive 'TestCryptDrive:rclone-test-fifayun6hane'
2026/10/24 01:22:03 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fifayun6hane': 1 files missing
2026/10/24 01:22:03 NOTICE: 1 hashes missing
2026/10/24 01:22:03 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fifayun6hane': 1 differences found
2026/10/24 01:22:03 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fifayun6hane': 2 errors while checking
lower checkfile vs. upper remote (with normalization)
2026/10/24 01:22:04 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/24 01:22:04 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fifayun6hane': 0 differences found
2026/10/24 01:22:04 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fifayun6hane': 1 matching files
2026/10/24 01:22:04 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-dawuyum8rudo"
2026/10/24 01:22:04 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/24 01:22:04 DEBUG : Creating backend with remote "TestDrive:crypt/ha7cv9qn1j2hr1mrnqejsjm7lf4di2n7t59kq92asl9ro8jg54h0"
2026/10/24 01:22:05 DEBUG : Creating backend with remote "/tmp/rclone2633941726"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-dawuyum8rudo'", Local "Local file system at /tmp/rclone2633941726", Modify Window "1ms"
2026/10/24 01:22:08 DEBUG : HeLlO, wOrLd!: md5 = 3291b931077e229bb3d8b61f2367e07a OK
lower checkfile vs. upperlowermixed remote (without normalization)
2026/10/24 01:22:08 ERROR : HeLlO, wOrLd!: sum not found
2026/10/24 01:22:08 ERROR : hello, world!: file not in Encrypted drive 'TestCryptDrive:rclone-test-dawuyum8rudo'
2026/10/24 01:22:08 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-dawuyum8rudo': 1 files missing
2026/10/24 01:22:08 NOTICE: 1 hashes missing
2026/10/24 01:22:08 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-dawuyum8rudo': 1 differences found
2026/10/24 01:22:08 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-dawuyum8rudo': 2 errors while checking
lower checkfile vs. upperlowermixed remote (with normalization)
2026/10/24 01:22:09 DEBUG : HeLlO, wOrLd!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/24 01:22:09 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-dawuyum8rudo': 0 differences found
2026/10/24 01:22:09 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-dawuyum8rudo': 1 matching files
2026/10/24 01:22:09 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-rihuzaf4cowo"
2026/10/24 01:22:09 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/24 01:22:09 DEBUG : Creating backend with remote "TestDrive:crypt/rjkpt55312mb9keur7v4hntkajbku5fkaovaktfpi08drrf79mog"
2026/10/24 01:22:10 DEBUG : Creating backend with remote "/tmp/rclone137913381"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-rihuzaf4cowo'", Local "Local file system at /tmp/rclone137913381", Modify Window "1ms"
2026/10/24 01:22:12 DEBUG : HELLO, WORLD!: md5 = d6f862520a0abc1aa47ecd4a655113a0 OK
upperlowermixed checkfile vs. upper remote (without normalization)
2026/10/24 01:22:13 ERROR : HELLO, WORLD!: sum not found
2026/10/24 01:22:13 ERROR : HeLlO, wOrLd!: file not in Encrypted drive 'TestCryptDrive:rclone-test-rihuzaf4cowo'
2026/10/24 01:22:13 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-rihuzaf4cowo': 1 files missing
2026/10/24 01:22:13 NOTICE: 1 hashes missing
2026/10/24 01:22:13 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-rihuzaf4cowo': 1 differences found
2026/10/24 01:22:13 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-rihuzaf4cowo': 2 errors while checking
upperlowermixed checkfile vs. upper remote (with normalization)
2026/10/24 01:22:14 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/24 01:22:14 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-rihuzaf4cowo': 0 differences found
2026/10/24 01:22:14 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-rihuzaf4cowo': 1 matching files
2026/10/24 01:22:14 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-vadujiv4zido"
2026/10/24 01:22:14 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/24 01:22:14 DEBUG : Creating backend with remote "TestDrive:crypt/274dtp6c7aj61jjdar68jqnl3mkbb2pnjvsfhsd8j5b0s75il55g"
2026/10/24 01:22:15 DEBUG : Creating backend with remote "/tmp/rclone1275772492"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-vadujiv4zido'", Local "Local file system at /tmp/rclone1275772492", Modify Window "1ms"
2026/10/24 01:22:17 DEBUG : 測試_Русский___ě_áñ: md5 = 8030be2613c8bf5cdd43e98cbc473135 OK
NFD checkfile vs. NFC remote (without normalization)
2026/10/24 01:22:18 ERROR : 測試_Русский___ě_áñ: sum not found
2026/10/24 01:22:18 ERROR : 測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-vadujiv4zido'
2026/10/24 01:22:18 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-vadujiv4zido': 1 files missing
2026/10/24 01:22:18 NOTICE: 1 hashes missing
2026/10/24 01:22:18 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-vadujiv4zido': 1 differences found
2026/10/24 01:22:18 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-vadujiv4zido': 2 errors while checking
NFD checkfile vs. NFC remote (with normalization)
2026/10/24 01:22:19 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/24 01:22:19 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-vadujiv4zido': 0 differences found
2026/10/24 01:22:19 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-vadujiv4zido': 1 matching files
2026/10/24 01:22:19 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-hapehuh5masa"
2026/10/24 01:22:19 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/24 01:22:19 DEBUG : Creating backend with remote "TestDrive:crypt/mb4p263rsn24j15spfrut4019c27s54ceqrh043iljo9qvfmm6dg"
2026/10/24 01:22:20 DEBUG : Creating backend with remote "/tmp/rclone135820001"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hapehuh5masa'", Local "Local file system at /tmp/rclone135820001", Modify Window "1ms"
2026/10/24 01:22:22 DEBUG : 測試_Русский___ě_áñ: md5 = db7fb95c1c6dccd1a3fd448b9e0b3afa OK
NFC checkfile vs. NFD remote (without normalization)
2026/10/24 01:22:23 ERROR : 測試_Русский___ě_áñ: sum not found
2026/10/24 01:22:23 ERROR : 測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-hapehuh5masa'
2026/10/24 01:22:23 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-hapehuh5masa': 1 files missing
2026/10/24 01:22:23 NOTICE: 1 hashes missing
2026/10/24 01:22:23 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-hapehuh5masa': 1 differences found
2026/10/24 01:22:23 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-hapehuh5masa': 2 errors while checking
NFC checkfile vs. NFD remote (with normalization)
2026/10/24 01:22:24 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/24 01:22:24 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-hapehuh5masa': 0 differences found
2026/10/24 01:22:24 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-hapehuh5masa': 1 matching files
2026/10/24 01:22:24 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-gelofux4mijo"
2026/10/24 01:22:24 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/24 01:22:24 DEBUG : Creating backend with remote "TestDrive:crypt/nl43gualuha55u8vdouok27iik55bkmdvvus9ardtnsesmjeeosg"
2026/10/24 01:22:25 DEBUG : Creating backend with remote "/tmp/rclone5874660"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gelofux4mijo'", Local "Local file system at /tmp/rclone5874660", Modify Window "1ms"
2026/10/24 01:22:27 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = be375e92726413c723358d46ba07fc82 OK
NFDx2 checkfile vs. both remote (without normalization)
2026/10/24 01:22:28 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found
2026/10/24 01:22:28 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-gelofux4mijo'
2026/10/24 01:22:28 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-gelofux4mijo': 1 files missing
2026/10/24 01:22:28 NOTICE: 1 hashes missing
2026/10/24 01:22:28 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-gelofux4mijo': 1 differences found
2026/10/24 01:22:28 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-gelofux4mijo': 2 errors while checking
NFDx2 checkfile vs. both remote (with normalization)
2026/10/24 01:22:29 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/24 01:22:29 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-gelofux4mijo': 0 differences found
2026/10/24 01:22:29 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-gelofux4mijo': 1 matching files
2026/10/24 01:22:29 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-koyagup0lutu"
2026/10/24 01:22:29 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/24 01:22:29 DEBUG : Creating backend with remote "TestDrive:crypt/lm78fr6hu18hduh4qol06f6g43obnaooasfoh82g1lqco0f113e0"
2026/10/24 01:22:30 DEBUG : Creating backend with remote "/tmp/rclone3277486992"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koyagup0lutu'", Local "Local file system at /tmp/rclone3277486992", Modify Window "1ms"
2026/10/24 01:22:32 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = f22664f1a4550fff5975ed08d76808e6 OK
NFCx2 checkfile vs. both remote (without normalization)
2026/10/24 01:22:32 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found
2026/10/24 01:22:32 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-koyagup0lutu'
2026/10/24 01:22:32 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-koyagup0lutu': 1 files missing
2026/10/24 01:22:32 NOTICE: 1 hashes missing
2026/10/24 01:22:32 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-koyagup0lutu': 1 differences found
2026/10/24 01:22:32 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-koyagup0lutu': 2 errors while checking
NFCx2 checkfile vs. both remote (with normalization)
2026/10/24 01:22:33 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/24 01:22:33 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-koyagup0lutu': 0 differences found
2026/10/24 01:22:33 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-koyagup0lutu': 1 matching files
2026/10/24 01:22:33 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-cagokav7rise"
2026/10/24 01:22:33 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/24 01:22:34 DEBUG : Creating backend with remote "TestDrive:crypt/5u2kurfsie5hkcnnbqk3d5dp7tm968c78s984v60inus7h9m8egg"
2026/10/24 01:22:35 DEBUG : Creating backend with remote "/tmp/rclone1296072401"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cagokav7rise'", Local "Local file system at /tmp/rclone1296072401", Modify Window "1ms"
2026/10/24 01:22:37 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = e6d9571b714ffe20fac58d81c65744e9 OK
both checkfile vs. NFDx2 remote (without normalization)
2026/10/24 01:22:37 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found
2026/10/24 01:22:37 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-cagokav7rise'
2026/10/24 01:22:37 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-cagokav7rise': 1 files missing
2026/10/24 01:22:37 NOTICE: 1 hashes missing
2026/10/24 01:22:37 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-cagokav7rise': 1 differences found
2026/10/24 01:22:37 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-cagokav7rise': 2 errors while checking
both checkfile vs. NFDx2 remote (with normalization)
2026/10/24 01:22:38 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/24 01:22:38 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-cagokav7rise': 0 differences found
2026/10/24 01:22:38 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-cagokav7rise': 1 matching files
2026/10/24 01:22:38 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-wucitup7mive"
2026/10/24 01:22:38 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/24 01:22:38 DEBUG : Creating backend with remote "TestDrive:crypt/cqp4e9441ii2s7qclmin2aj8tl5l7mr02944chi87snekv9oc950"
2026/10/24 01:22:39 DEBUG : Creating backend with remote "/tmp/rclone385227481"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wucitup7mive'", Local "Local file system at /tmp/rclone385227481", Modify Window "1ms"
2026/10/24 01:22:42 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = eb40b3e0bb04cab71a63346120e993d7 OK
both checkfile vs. NFCx2 remote (without normalization)
2026/10/24 01:22:42 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found
2026/10/24 01:22:42 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-wucitup7mive'
2026/10/24 01:22:42 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-wucitup7mive': 1 files missing
2026/10/24 01:22:42 NOTICE: 1 hashes missing
2026/10/24 01:22:42 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-wucitup7mive': 1 differences found
2026/10/24 01:22:42 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-wucitup7mive': 2 errors while checking
both checkfile vs. NFCx2 remote (with normalization)
2026/10/24 01:22:43 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/24 01:22:43 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-wucitup7mive': 0 differences found
2026/10/24 01:22:43 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-wucitup7mive': 1 matching files
2026/10/24 01:22:43 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-wucitup7mive': Purge remote
2026/10/24 01:22:44 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cagokav7rise': Purge remote
2026/10/24 01:22:44 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-koyagup0lutu': Purge remote
2026/10/24 01:22:45 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-gelofux4mijo': Purge remote
2026/10/24 01:22:45 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-hapehuh5masa': Purge remote
2026/10/24 01:22:46 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-vadujiv4zido': Purge remote
2026/10/24 01:22:46 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-rihuzaf4cowo': Purge remote
2026/10/24 01:22:47 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-dawuyum8rudo': Purge remote
2026/10/24 01:22:47 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-fifayun6hane': Purge remote
2026/10/24 01:22:48 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zuvuyoz9goma': Purge remote
--- PASS: TestApplyTransforms (53.57s)
=== RUN   TestTruncateString
--- PASS: TestTruncateString (0.00s)
=== RUN   TestCopyFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:22:48 DEBUG : file1: Need to transfer - File not found at Destination
2026/10/24 01:22:51 DEBUG : sub/file2: md5 = 5352a3cb58c4dfdb86953f47090fb5d1 OK
2026/10/24 01:22:51 DEBUG : sub/file2: size = 14 OK
2026/10/24 01:22:51 INFO  : file1: Copied (new) to: sub/file2
2026/10/24 01:22:51 DEBUG : sub/file2: size = 14 OK
2026/10/24 01:22:51 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2026/10/24 01:22:51 DEBUG : file1: Unchanged skipping
2026/10/24 01:22:52 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo': don't need to copy/move sub/file2, it is already at target location
--- PASS: TestCopyFile (6.15s)
=== RUN   TestCopyFileImmutable
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:22:55 DEBUG : existing: Need to transfer - File not found at Destination
2026/10/24 01:22:56 DEBUG : existing: md5 = 6de09e87f56adb7d22931270bb8f7b66 OK
2026/10/24 01:22:56 DEBUG : existing: size = 6 OK
2026/10/24 01:22:56 INFO  : existing: Copied (new)
2026/10/24 01:22:57 DEBUG : existing: size = 6 OK
2026/10/24 01:22:57 DEBUG : existing: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2026/10/24 01:22:57 DEBUG : existing: Unchanged skipping
2026/10/24 01:22:57 DEBUG : existing: size = 8 (Local file system at /tmp/rclone2244109848)
2026/10/24 01:22:57 DEBUG : existing: size = 6 (Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo')
2026/10/24 01:22:57 DEBUG : existing: Sizes differ
2026/10/24 01:22:57 ERROR : existing: Source and destination exist but do not match: immutable file modified
--- PASS: TestCopyFileImmutable (4.25s)
=== RUN   TestCopyLongFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
    copy_test.go:188: Test only runs on local
--- SKIP: TestCopyLongFile (0.47s)
=== RUN   TestCopyFileBackupDir
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:23:01 DEBUG : dst/file1: md5 = f0f32a97f12db9ac07043e985c1d44ac OK
2026/10/24 01:23:02 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-kinejoz2jilo/backup"
2026/10/24 01:23:02 DEBUG : Config file has changed externally - reloading
2026/10/24 01:23:02 DEBUG : Creating backend with remote "TestDrive:crypt/vt4em8t9kto0583k70d1lau4i0it8fsqmo0drh6gl1jsnqt46rl0/1nrff024r7pq65ecp72fc28jb0"
2026/10/24 01:23:04 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone2244109848)
2026/10/24 01:23:04 DEBUG : dst/file1: size = 18 (Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo')
2026/10/24 01:23:04 DEBUG : dst/file1: Sizes differ
2026/10/24 01:23:06 INFO  : dst/file1: Moved (server-side)
2026/10/24 01:23:07 DEBUG : dst/file1: md5 = 5c9e94842be2087f20ccdf6b94f50a26 OK
2026/10/24 01:23:07 DEBUG : dst/file1: size = 14 OK
2026/10/24 01:23:07 INFO  : dst/file1: Copied (new)
--- PASS: TestCopyFileBackupDir (13.69s)
=== RUN   TestCopyFileCompareDest
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:23:13 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-kinejoz2jilo/dst"
2026/10/24 01:23:13 DEBUG : Config file has changed externally - reloading
2026/10/24 01:23:13 DEBUG : Creating backend with remote "TestDrive:crypt/vt4em8t9kto0583k70d1lau4i0it8fsqmo0drh6gl1jsnqt46rl0/31u3jie661vd5p8j7rtc3hgbh0"
2026/10/24 01:23:15 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-kinejoz2jilo/CompareDest"
2026/10/24 01:23:15 DEBUG : Creating backend with remote "TestDrive:crypt/vt4em8t9kto0583k70d1lau4i0it8fsqmo0drh6gl1jsnqt46rl0/gveqi14airsml4bgu7krj116o8"
2026/10/24 01:23:16 DEBUG : one: Need to transfer - File not found at Destination
2026/10/24 01:23:19 DEBUG : one: md5 = 54c232944fbfc0d91582d6a96d86243e OK
2026/10/24 01:23:19 DEBUG : one: size = 3 OK
2026/10/24 01:23:19 INFO  : one: Copied (new)
2026/10/24 01:23:20 DEBUG : one: size = 5 (Local file system at /tmp/rclone2244109848)
2026/10/24 01:23:20 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo/dst')
2026/10/24 01:23:20 DEBUG : one: Sizes differ
2026/10/24 01:23:21 DEBUG : one: md5 = 21518a7425dcd0b4c3bb5b632249d655 OK
2026/10/24 01:23:21 DEBUG : one: size = 5 OK
2026/10/24 01:23:21 INFO  : one: Copied (replaced existing)
2026/10/24 01:23:24 DEBUG : dst/one: md5 = de7a68f84d5720ad8d14f88a3d3c6a5f OK
2026/10/24 01:23:26 DEBUG : CompareDest/one: md5 = f078f1a585bd4a60662568777313911d OK
2026/10/24 01:23:27 DEBUG : one: size = 5 (Local file system at /tmp/rclone2244109848)
2026/10/24 01:23:27 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo/dst')
2026/10/24 01:23:27 DEBUG : one: Sizes differ
2026/10/24 01:23:27 DEBUG : one: size = 5 OK
2026/10/24 01:23:27 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/10/24 01:23:27 DEBUG : one: Destination found in --compare-dest, skipping
2026/10/24 01:23:29 DEBUG : CompareDest/two: md5 = 2d58179d9b30b2288076c7f7aa00d4ce OK
2026/10/24 01:23:30 DEBUG : two: Need to transfer - File not found at Destination
2026/10/24 01:23:31 DEBUG : two: size = 3 OK
2026/10/24 01:23:31 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/10/24 01:23:31 DEBUG : two: Destination found in --compare-dest, skipping
2026/10/24 01:23:32 DEBUG : two: Need to transfer - File not found at Destination
2026/10/24 01:23:32 DEBUG : two: size = 3 OK
2026/10/24 01:23:32 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/10/24 01:23:32 DEBUG : two: Destination found in --compare-dest, skipping
2026/10/24 01:23:33 DEBUG : two: Need to transfer - File not found at Destination
2026/10/24 01:23:33 DEBUG : two: size = 5 (Local file system at /tmp/rclone2244109848)
2026/10/24 01:23:33 DEBUG : two: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo/CompareDest')
2026/10/24 01:23:33 DEBUG : two: Sizes differ
2026/10/24 01:23:35 DEBUG : two: md5 = 9d7645a46794e6cbbbbd73abd3f18650 OK
2026/10/24 01:23:35 DEBUG : two: size = 5 OK
2026/10/24 01:23:35 INFO  : two: Copied (new)
--- PASS: TestCopyFileCompareDest (26.57s)
=== RUN   TestCopyFileCopyDestImmutable
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:23:39 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-kinejoz2jilo/dst"
2026/10/24 01:23:39 DEBUG : Config file has changed externally - reloading
2026/10/24 01:23:39 DEBUG : Creating backend with remote "TestDrive:crypt/vt4em8t9kto0583k70d1lau4i0it8fsqmo0drh6gl1jsnqt46rl0/31u3jie661vd5p8j7rtc3hgbh0"
2026/10/24 01:23:43 DEBUG : dst/one: md5 = 1209a04005bb5696d1f56f9e2ecf2b2d OK
2026/10/24 01:23:45 DEBUG : CopyDest/one: md5 = 03af6627b48d3010c1e46a9b95a96a26 OK
2026/10/24 01:23:46 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-kinejoz2jilo/CopyDest"
2026/10/24 01:23:46 DEBUG : Config file has changed externally - reloading
2026/10/24 01:23:47 DEBUG : Creating backend with remote "TestDrive:crypt/vt4em8t9kto0583k70d1lau4i0it8fsqmo0drh6gl1jsnqt46rl0/d09o6po3f7bm6ce32vdgs8h9ls"
2026/10/24 01:23:47 DEBUG : one: size = 5 (Local file system at /tmp/rclone2244109848)
2026/10/24 01:23:47 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo/dst')
2026/10/24 01:23:47 DEBUG : one: Sizes differ
2026/10/24 01:23:48 DEBUG : one: size = 5 OK
2026/10/24 01:23:48 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/10/24 01:23:48 DEBUG : one: size = 5 (Local file system at /tmp/rclone2244109848)
2026/10/24 01:23:48 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo/dst')
2026/10/24 01:23:48 DEBUG : one: Sizes differ
2026/10/24 01:23:48 ERROR : one: Source and destination exist but do not match: immutable file modified
--- PASS: TestCopyFileCopyDestImmutable (11.87s)
=== RUN   TestCopyFileCopyDest
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:23:51 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-kinejoz2jilo/dst"
2026/10/24 01:23:51 DEBUG : Config file has changed externally - reloading
2026/10/24 01:23:51 DEBUG : Creating backend with remote "TestDrive:crypt/vt4em8t9kto0583k70d1lau4i0it8fsqmo0drh6gl1jsnqt46rl0/31u3jie661vd5p8j7rtc3hgbh0"
2026/10/24 01:23:53 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-kinejoz2jilo/CopyDest"
2026/10/24 01:23:53 DEBUG : Creating backend with remote "TestDrive:crypt/vt4em8t9kto0583k70d1lau4i0it8fsqmo0drh6gl1jsnqt46rl0/d09o6po3f7bm6ce32vdgs8h9ls"
2026/10/24 01:23:55 DEBUG : one: Need to transfer - File not found at Destination
2026/10/24 01:23:57 DEBUG : one: md5 = 24267f58c47ef1ff56534a2864e7eef6 OK
2026/10/24 01:23:57 DEBUG : one: size = 3 OK
2026/10/24 01:23:57 INFO  : one: Copied (new)
2026/10/24 01:23:59 DEBUG : one: size = 5 (Local file system at /tmp/rclone2244109848)
2026/10/24 01:23:59 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo/dst')
2026/10/24 01:23:59 DEBUG : one: Sizes differ
2026/10/24 01:24:00 DEBUG : one: md5 = 9f569eb56f9bb84a690d36fc45e317c0 OK
2026/10/24 01:24:00 DEBUG : one: size = 5 OK
2026/10/24 01:24:00 INFO  : one: Copied (replaced existing)
2026/10/24 01:24:03 DEBUG : dst/one: md5 = 87bff3ac8139dd627fef3041d81c44a6 OK
2026/10/24 01:24:05 DEBUG : CopyDest/one: md5 = d349fa315bedb0bcee98758c84fdc040 OK
2026/10/24 01:24:06 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-kinejoz2jilo/BackupDir"
2026/10/24 01:24:06 DEBUG : Config file has changed externally - reloading
2026/10/24 01:24:06 DEBUG : Creating backend with remote "TestDrive:crypt/vt4em8t9kto0583k70d1lau4i0it8fsqmo0drh6gl1jsnqt46rl0/s6dbk3lfi7c9kfvo6j7bla9m0g"
2026/10/24 01:24:08 DEBUG : one: size = 5 (Local file system at /tmp/rclone2244109848)
2026/10/24 01:24:08 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo/dst')
2026/10/24 01:24:08 DEBUG : one: Sizes differ
2026/10/24 01:24:08 DEBUG : one: size = 5 OK
2026/10/24 01:24:08 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/10/24 01:24:08 DEBUG : one: size = 5 (Local file system at /tmp/rclone2244109848)
2026/10/24 01:24:08 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo/dst')
2026/10/24 01:24:08 DEBUG : one: Sizes differ
2026/10/24 01:24:10 INFO  : one: Moved (server-side)
2026/10/24 01:24:11 DEBUG : one: size = 5 OK
2026/10/24 01:24:11 INFO  : one: Copied (server-side copy)
2026/10/24 01:24:11 DEBUG : one: Destination found in --copy-dest, using server-side copy
2026/10/24 01:24:13 DEBUG : CopyDest/two: md5 = 118acb205d4c4884b066f779bac16b14 OK
2026/10/24 01:24:14 DEBUG : two: Need to transfer - File not found at Destination
2026/10/24 01:24:14 DEBUG : two: size = 3 OK
2026/10/24 01:24:14 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/10/24 01:24:15 DEBUG : two: size = 3 OK
2026/10/24 01:24:15 INFO  : two: Copied (server-side copy)
2026/10/24 01:24:15 DEBUG : two: Destination found in --copy-dest, using server-side copy
2026/10/24 01:24:16 DEBUG : two: size = 3 OK
2026/10/24 01:24:16 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/10/24 01:24:16 DEBUG : two: Unchanged skipping
2026/10/24 01:24:18 DEBUG : CopyDest/three: md5 = 1aaf899a1c3aaf765a3e793f2c5674d9 OK
2026/10/24 01:24:19 DEBUG : three: Need to transfer - File not found at Destination
2026/10/24 01:24:19 DEBUG : three: size = 7 (Local file system at /tmp/rclone2244109848)
2026/10/24 01:24:19 DEBUG : three: size = 5 (Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo/CopyDest')
2026/10/24 01:24:19 DEBUG : three: Sizes differ
2026/10/24 01:24:19 DEBUG : three: Destination not found in --copy-dest
2026/10/24 01:24:21 DEBUG : three: md5 = b450b2b7433650fedab57f36e75509b1 OK
2026/10/24 01:24:21 DEBUG : three: size = 7 OK
2026/10/24 01:24:21 INFO  : three: Copied (new)
--- PASS: TestCopyFileCopyDest (36.23s)
=== RUN   TestCopyInplace
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
    copy_test.go:434: Partial uploads not supported
--- SKIP: TestCopyInplace (0.45s)
=== RUN   TestCopyLongFileName
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
    copy_test.go:467: Partial uploads not supported
--- SKIP: TestCopyLongFileName (0.48s)
=== RUN   TestCopyLongFileNameCollision
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
    copy_test.go:500: Partial uploads not supported
--- SKIP: TestCopyLongFileNameCollision (0.62s)
=== RUN   TestCopyFileMaxTransfer
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:24:29 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination
2026/10/24 01:24:32 DEBUG : TestCopyFileMaxTransfer/file1: md5 = a99b416a75c46c67f3c93a44bc3d5986 OK
2026/10/24 01:24:32 DEBUG : TestCopyFileMaxTransfer/file1: size = 14 OK
2026/10/24 01:24:32 INFO  : TestCopyFileMaxTransfer/file1: Copied (new)
2026/10/24 01:24:33 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination
2026/10/24 01:24:33 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/24 01:24:34 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination
2026/10/24 01:24:34 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination
2026/10/24 01:24:36 DEBUG : TestCopyFileMaxTransfer/file4: md5 = 6d450fb8d78cd830b52480a98c4f7d76 OK
2026/10/24 01:24:36 DEBUG : TestCopyFileMaxTransfer/file4: size = 2062 OK
2026/10/24 01:24:36 INFO  : TestCopyFileMaxTransfer/file4: Copied (new)
--- PASS: TestCopyFileMaxTransfer (9.82s)
=== RUN   TestDeduplicateInteractive
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
    dedupe_test.go:37: Can't run this test without a hash
--- SKIP: TestDeduplicateInteractive (0.49s)
=== RUN   TestDeduplicateSkip
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:24:42 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo': Looking for duplicate names using skip mode.
2026/10/24 01:24:42 NOTICE: one: Found 2 files with duplicate names
2026/10/24 01:24:42 NOTICE: one: Skipping 2 files with duplicate names
--- PASS: TestDeduplicateSkip (4.88s)
=== RUN   TestDeduplicateSizeOnly
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:24:48 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo': Looking for duplicate names using skip mode.
2026/10/24 01:24:49 NOTICE: one: Found 3 files with duplicate names
2026/10/24 01:24:49 NOTICE: one: Deleting 1/2 identical duplicates (size 11)
2026/10/24 01:24:49 INFO  : one: Deleted
2026/10/24 01:24:49 NOTICE: one: Skipping 2 files with duplicate names
--- PASS: TestDeduplicateSizeOnly (6.95s)
=== RUN   TestDeduplicateFirst
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:24:55 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo': Looking for duplicate names using first mode.
2026/10/24 01:24:56 NOTICE: one: Found 3 files with duplicate names
2026/10/24 01:24:56 INFO  : one: Deleted
2026/10/24 01:24:57 INFO  : one: Deleted
2026/10/24 01:24:57 NOTICE: one: Deleted 2 extra copies
--- PASS: TestDeduplicateFirst (7.26s)
=== RUN   TestDeduplicateNewest
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:25:02 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo': Looking for duplicate names using newest mode.
2026/10/24 01:25:03 NOTICE: one: Found 3 files with duplicate names
2026/10/24 01:25:03 INFO  : one: Deleted
2026/10/24 01:25:04 INFO  : one: Deleted
2026/10/24 01:25:04 NOTICE: one: Deleted 2 extra copies
--- PASS: TestDeduplicateNewest (7.17s)
=== RUN   TestDeduplicateNewestByHash
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
    dedupe_test.go:37: Can't run this test without a hash
--- SKIP: TestDeduplicateNewestByHash (0.48s)
=== RUN   TestDeduplicateOldest
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:25:10 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo': Looking for duplicate names using oldest mode.
2026/10/24 01:25:10 NOTICE: one: Found 3 files with duplicate names
2026/10/24 01:25:11 INFO  : one: Deleted
2026/10/24 01:25:11 INFO  : one: Deleted
2026/10/24 01:25:11 NOTICE: one: Deleted 2 extra copies
--- PASS: TestDeduplicateOldest (6.57s)
=== RUN   TestDeduplicateLargest
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:25:17 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo': Looking for duplicate names using largest mode.
2026/10/24 01:25:17 NOTICE: one: Found 3 files with duplicate names
2026/10/24 01:25:18 INFO  : one: Deleted
2026/10/24 01:25:18 INFO  : one: Deleted
2026/10/24 01:25:18 NOTICE: one: Deleted 2 extra copies
--- PASS: TestDeduplicateLargest (6.83s)
=== RUN   TestDeduplicateSmallest
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:25:23 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo': Looking for duplicate names using smallest mode.
2026/10/24 01:25:24 NOTICE: one: Found 3 files with duplicate names
2026/10/24 01:25:24 INFO  : one: Deleted
2026/10/24 01:25:25 INFO  : one: Deleted
2026/10/24 01:25:25 NOTICE: one: Deleted 2 extra copies
--- PASS: TestDeduplicateSmallest (6.70s)
=== RUN   TestDeduplicateRename
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:25:31 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo': Looking for duplicate names using rename mode.
2026/10/24 01:25:32 NOTICE: one.txt: Found 3 files with duplicate names
2026/10/24 01:25:32 INFO  : one-2.txt: renamed from: one.txt
2026/10/24 01:25:33 INFO  : one-3.txt: renamed from: one.txt
2026/10/24 01:25:34 INFO  : one-4.txt: renamed from: one.txt
--- PASS: TestDeduplicateRename (10.53s)
=== RUN   TestDeduplicateRenameManyExisting
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:25:38 DEBUG : one-1.txt: md5 = 21d35531f365b1f654139db663e20a3d OK
2026/10/24 01:25:40 DEBUG : one-2.txt: md5 = c9f1505115f9d210b21965137da24c79 OK
2026/10/24 01:25:41 DEBUG : one-3.txt: md5 = 13e3ffe9087bd07c6fde8e544cbec543 OK
2026/10/24 01:25:43 DEBUG : one-4.txt: md5 = 75eb2c3c794f78f16e23dbd66d3fb459 OK
2026/10/24 01:25:44 DEBUG : one-5.txt: md5 = 567f701bf3df6d3fc7fcf4795abff2de OK
2026/10/24 01:25:46 DEBUG : one-6.txt: md5 = 2586dbeb7e2ee5b6a59fb4a8fb033451 OK
2026/10/24 01:25:47 DEBUG : one-7.txt: md5 = c1d02b78a03a410b9a4e76ce3f968d05 OK
2026/10/24 01:25:48 DEBUG : one-8.txt: md5 = 8eb6f747b37187bfe6ee3610f3340d13 OK
2026/10/24 01:25:50 DEBUG : one-9.txt: md5 = d88c22a04b6e0bbc67917f574cd5d2e6 OK
2026/10/24 01:25:52 DEBUG : one-10.txt: md5 = 701f7d1e827e671277842ac7ad75a4bc OK
2026/10/24 01:25:53 DEBUG : one-11.txt: md5 = 2ff26ce501294fc8ee25c4c0ad67b2a6 OK
2026/10/24 01:25:55 DEBUG : one-12.txt: md5 = b0a764cd63a1d2d3d53cd12fc58552d6 OK
2026/10/24 01:25:56 DEBUG : one-13.txt: md5 = d0a7da89e951b1ceeedfee359dd9fe1f OK
2026/10/24 01:25:58 DEBUG : one-14.txt: md5 = 6c4d0cd4241316dc1f713afb9bbd2434 OK
2026/10/24 01:25:59 DEBUG : one-15.txt: md5 = cbb6433f92586eaf682c5c2bb121d1d3 OK
2026/10/24 01:26:01 DEBUG : one-16.txt: md5 = 1281d2e440e8992d2fe1622941de0fd3 OK
2026/10/24 01:26:02 DEBUG : one-17.txt: md5 = 6f7362665069f4a6543215c0c043adbd OK
2026/10/24 01:26:04 DEBUG : one-18.txt: md5 = 6c90d067a0ddcb097785d9b311cb2b59 OK
2026/10/24 01:26:06 DEBUG : one-19.txt: md5 = 673c2d2acffc85024e689e6c3707e336 OK
2026/10/24 01:26:07 DEBUG : one-20.txt: md5 = fd94aca44113be82e5c4e1ed68924860 OK
2026/10/24 01:26:09 DEBUG : one-21.txt: md5 = 33116007b2c6878e03977e34183de1ad OK
2026/10/24 01:26:10 DEBUG : one-22.txt: md5 = bff47a110f81c056bc37e78dc36a9237 OK
2026/10/24 01:26:11 DEBUG : one-23.txt: md5 = e5caecaa8eee1b4656a791bdd0545fcc OK
2026/10/24 01:26:13 DEBUG : one-24.txt: md5 = eade7800f2b11bafadee73f8280ebb28 OK
2026/10/24 01:26:14 DEBUG : one-25.txt: md5 = 6ed9e8ac2c173153fc55c9cee67b516f OK
2026/10/24 01:26:16 DEBUG : one-26.txt: md5 = e66c3724ffa840e750e2ba5f34390153 OK
2026/10/24 01:26:17 DEBUG : one-27.txt: md5 = d2f61eafc81b07ccd4da06a3fb9da8bf OK
2026/10/24 01:26:19 DEBUG : one-28.txt: md5 = e72050e4af0db2388d394a8f0218432a OK
2026/10/24 01:26:20 DEBUG : one-29.txt: md5 = c455ecfc52754e2552c7da3acc28f9d5 OK
2026/10/24 01:26:22 DEBUG : one-30.txt: md5 = 0a1226b5f152599ffeada6fffce545a1 OK
2026/10/24 01:26:23 DEBUG : one-31.txt: md5 = 336d362e17c006715d5c8940af9518bc OK
2026/10/24 01:26:25 DEBUG : one-32.txt: md5 = 97c206436be4db03264f7fabe75ee180 OK
2026/10/24 01:26:26 DEBUG : one-33.txt: md5 = 376e46b09fa2d4ac3c17a4b7a1d74f49 OK
2026/10/24 01:26:28 DEBUG : one-34.txt: md5 = 8c1a380d4dd3c4710cb1287c73f1ea62 OK
2026/10/24 01:26:29 DEBUG : one-35.txt: md5 = 171bf690664e27477eef9dbb18100394 OK
2026/10/24 01:26:31 DEBUG : one-36.txt: md5 = 867e89dcbf5476a98404d07728031449 OK
2026/10/24 01:26:32 DEBUG : one-37.txt: md5 = 67383dae35fefae35ec111fc3b98d9d8 OK
2026/10/24 01:26:34 DEBUG : one-38.txt: md5 = 158ee2bde43a52d62821a2e3efdf1c17 OK
2026/10/24 01:26:35 DEBUG : one-39.txt: md5 = 996daafa44669842aef7d7b426faf049 OK
2026/10/24 01:26:37 DEBUG : one-40.txt: md5 = 549f96fdefe48116863113c7e6a1e686 OK
2026/10/24 01:26:38 DEBUG : one-41.txt: md5 = d3d55af7a5fd7ac9c69c0c81c7cf2677 OK
2026/10/24 01:26:40 DEBUG : one-42.txt: md5 = 2deee4e8a78b18f8b4e2b4434e4a2a6a OK
2026/10/24 01:26:42 DEBUG : one-43.txt: md5 = 1864a0588f07bdabeb82e689f066ac70 OK
2026/10/24 01:26:43 DEBUG : one-44.txt: md5 = bed79ea4d521e05710aa95dec56ce93c OK
2026/10/24 01:26:44 DEBUG : one-45.txt: md5 = acedcbc77f82c3cae34c6bdc736c4836 OK
2026/10/24 01:26:46 DEBUG : one-46.txt: md5 = c400652b53ff1d56d90097474f433eb4 OK
2026/10/24 01:26:47 DEBUG : one-47.txt: md5 = 1d7bac5a7c844fddf51970ae363446c6 OK
2026/10/24 01:26:49 DEBUG : one-48.txt: md5 = 32654f6e79fd97c8587669ef8c3f649a OK
2026/10/24 01:26:50 DEBUG : one-49.txt: md5 = 31f3d09d17c58e954fbe3b0c3bea9d06 OK
2026/10/24 01:26:52 DEBUG : one-50.txt: md5 = 97e5574f433fcee227710acffbc8e8cc OK
2026/10/24 01:26:53 DEBUG : one-51.txt: md5 = e1c9ee41672e48d3d13f9f8962a30e3a OK
2026/10/24 01:26:55 DEBUG : one-52.txt: md5 = e7fd0133f5f22cfa8977d203b46dfa84 OK
2026/10/24 01:26:56 DEBUG : one-53.txt: md5 = f54fd511febefdf9de84e5e54090c5d7 OK
2026/10/24 01:26:58 DEBUG : one-54.txt: md5 = 4a30359f00417e9034d61ea5bac3ba12 OK
2026/10/24 01:26:59 DEBUG : one-55.txt: md5 = ed2ae509ebfbd911ce95e2872398863f OK
2026/10/24 01:27:01 DEBUG : one-56.txt: md5 = 94892f0a6c52748847af2b8457d7bedd OK
2026/10/24 01:27:02 DEBUG : one-57.txt: md5 = 7f24fa346d4bad3ce6eb2d0be106ce1f OK
2026/10/24 01:27:03 DEBUG : one-58.txt: md5 = 30d0bd81c2bf0dc25cc9594131879ba6 OK
2026/10/24 01:27:05 DEBUG : one-59.txt: md5 = 859b2b9cdded493acde496c744e5dac8 OK
2026/10/24 01:27:06 DEBUG : one-60.txt: md5 = 5c5bb0e1e9f85789a6c5ac5e5b9a9462 OK
2026/10/24 01:27:08 DEBUG : one-61.txt: md5 = 2e38a5bd627f74f7bd080495d9ff36c1 OK
2026/10/24 01:27:09 DEBUG : one-62.txt: md5 = 204a2cd8372fa62972dd76eb465f0181 OK
2026/10/24 01:27:11 DEBUG : one-63.txt: md5 = 96548ebabfed3391b3f06c29425dc4fd OK
2026/10/24 01:27:12 DEBUG : one-64.txt: md5 = cab50b1d6020f9f67cea2a18b3ceb6a5 OK
2026/10/24 01:27:14 DEBUG : one-65.txt: md5 = 2bfb325d096ddc9b4c4a901d9837306d OK
2026/10/24 01:27:15 DEBUG : one-66.txt: md5 = 0f2d7c020723a4d612d243e80221e685 OK
2026/10/24 01:27:17 DEBUG : one-67.txt: md5 = 73f10fc53ac882d9829d3cbd689910d0 OK
2026/10/24 01:27:18 DEBUG : one-68.txt: md5 = d1f0681c09def3730208a551259ba299 OK
2026/10/24 01:27:20 DEBUG : one-69.txt: md5 = e1d9eef0a6acd759002bd5a13df0bb53 OK
2026/10/24 01:27:21 DEBUG : one-70.txt: md5 = 9c26f1ac63d3889fb5ae39c778a06953 OK
2026/10/24 01:27:23 DEBUG : one-71.txt: md5 = 0d5c1ce3a34d0ede4427526fb455aa79 OK
2026/10/24 01:27:24 DEBUG : one-72.txt: md5 = 3df7d0d57cec5f85194bc3bd425589ba OK
2026/10/24 01:27:26 DEBUG : one-73.txt: md5 = b927058bd61ad598f4a2d8ae2ce85e7c OK
2026/10/24 01:27:27 DEBUG : one-74.txt: md5 = ad36cadd3f165ea41907bbd7babe2a03 OK
2026/10/24 01:27:29 DEBUG : one-75.txt: md5 = 4d0c8e13da8229675e93741817c65415 OK
2026/10/24 01:27:30 DEBUG : one-76.txt: md5 = 6d15e3d82c0fa89f98e0c3b16a53386a OK
2026/10/24 01:27:32 DEBUG : one-77.txt: md5 = ec6c0ebea725e4e03223cddd40e08062 OK
2026/10/24 01:27:33 DEBUG : one-78.txt: md5 = a2517d246282fe5cd3ff456f5f6d1f55 OK
2026/10/24 01:27:35 DEBUG : one-79.txt: md5 = c5b065ea3e8b632633e023ed94da812b OK
2026/10/24 01:27:36 DEBUG : one-80.txt: md5 = 2c927e3bbdc8eead86ac6ece8c4af659 OK
2026/10/24 01:27:38 DEBUG : one-81.txt: md5 = cebd33136f1355dd65d454c1574bdb86 OK
2026/10/24 01:27:39 DEBUG : one-82.txt: md5 = 0951bcb972f7361338a3d365c7c769d4 OK
2026/10/24 01:27:40 DEBUG : one-83.txt: md5 = e058ad65e2a519556db42a0d04b9a3ab OK
2026/10/24 01:27:42 DEBUG : one-84.txt: md5 = 34540a4582cbb7462b8cc694e8cdcbf4 OK
2026/10/24 01:27:44 DEBUG : one-85.txt: md5 = 8e13a0e39ce4019aaa3677b4e7bc2179 OK
2026/10/24 01:27:45 DEBUG : one-86.txt: md5 = f0e3dddfaa6073b107e3d355fb65e215 OK
2026/10/24 01:27:47 DEBUG : one-87.txt: md5 = b019c8b745f7b1e94483c93a4e8e0928 OK
2026/10/24 01:27:48 DEBUG : one-88.txt: md5 = f47f64dba57db923ff54d56e7ce134d9 OK
2026/10/24 01:27:50 DEBUG : one-89.txt: md5 = e93165579d7a02e51e86f6ae55b09a8e OK
2026/10/24 01:27:51 DEBUG : one-90.txt: md5 = 902401b6c2a4f4f68e444baaf18816f0 OK
2026/10/24 01:27:53 DEBUG : one-91.txt: md5 = f5f4bbb469d784b7e78998bcd47e9799 OK
2026/10/24 01:27:54 DEBUG : one-92.txt: md5 = e5c0cf889a8f1a9658f9decce32a2dd2 OK
2026/10/24 01:27:56 DEBUG : one-93.txt: md5 = 94d9a5ca4ba84bcbb5b50ba1a0b2c2e7 OK
2026/10/24 01:27:57 DEBUG : one-94.txt: md5 = b2a78f522130a8cabd848e4cd1d04be8 OK
2026/10/24 01:27:59 DEBUG : one-95.txt: md5 = f069be584ed6f92dec9890ff9f9f3723 OK
2026/10/24 01:28:00 DEBUG : one-96.txt: md5 = c5711804deaf1e0a2c18d963842184ab OK
2026/10/24 01:28:02 DEBUG : one-97.txt: md5 = 963cb887fc8b80fd862fd39160952888 OK
2026/10/24 01:28:03 DEBUG : one-98.txt: md5 = 73846cbb3ea1b4ebe614b261ab742aaa OK
2026/10/24 01:28:05 DEBUG : one-99.txt: md5 = 31e062a824ef901b9a4a54d275884584 OK
2026/10/24 01:28:06 DEBUG : one-100.txt: md5 = 792bcb9f604249854404126e4b001d67 OK
2026/10/24 01:28:08 DEBUG : one-101.txt: md5 = e47ceadf2eb1361d7667fdb775e76c87 OK
2026/10/24 01:28:10 DEBUG : one-102.txt: md5 = 49b5fc670bcd4c028272f19fd801d5bc OK
2026/10/24 01:28:11 DEBUG : one-103.txt: md5 = bba626bfc546f19b3fc5563e6c517723 OK
2026/10/24 01:28:12 DEBUG : one-104.txt: md5 = fc396f6af92a557b3cce8c4ca1af0b70 OK
2026/10/24 01:28:14 DEBUG : one-105.txt: md5 = 0663e4733d210552a7fe3cf22262ee03 OK
2026/10/24 01:28:17 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo': Looking for duplicate names using rename mode.
2026/10/24 01:28:18 NOTICE: one.txt: Found 2 files with duplicate names
2026/10/24 01:28:18 INFO  : one-106.txt: renamed from: one.txt
2026/10/24 01:28:19 INFO  : one-107.txt: renamed from: one.txt
--- PASS: TestDeduplicateRenameManyExisting (212.84s)
=== RUN   TestMergeDirs
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:29:12 DEBUG : dupe1/one.txt: md5 = 44cd76473dd706d27465fb25541fd3c3 OK
2026/10/24 01:29:14 DEBUG : dupe2/two.txt: md5 = e527a49f50cab86d5dd667caf2f02978 OK
2026/10/24 01:29:16 DEBUG : dupe3/three.txt: md5 = e1b0ba864b9672aa7e136704e3ed4e17 OK
2026/10/24 01:29:16 INFO  : urj8gsducqbtvekeq181ntd2u8: merging "sfrom47189m9qr3mt4qonu281c"
2026/10/24 01:29:17 INFO  : urj8gsducqbtvekeq181ntd2u8: removing empty directory
2026/10/24 01:29:18 INFO  : 1u1r4ei7c628fnjg9blqt3j60o: merging "tr2hj63d80ftlmvm6a952snjcc"
2026/10/24 01:29:18 INFO  : 1u1r4ei7c628fnjg9blqt3j60o: removing empty directory
--- PASS: TestMergeDirs (12.77s)
=== RUN   TestIndex
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:29:24 DEBUG : file1.txt: md5 = e2f77b4398ccea943136f23295eb561a OK
2026/10/24 01:29:26 DEBUG : sub/file2.txt: md5 = d8522d431ddf5ec3c23ae64ea1545d2a OK
2026/10/24 01:29:28 DEBUG : sub/deep/file3.txt: md5 = 5dd0233b606d32160537b0cfc308494f OK
2026/10/24 01:29:30 DEBUG : sub/deep/index.json: md5 = 10497e3e484a42b85ff920b2b099dcca OK
2026/10/24 01:29:30 DEBUG : sub/deep/index.json: size = 119 OK
2026/10/24 01:29:30 INFO  : sub/deep/index.json: Copied (new)
2026/10/24 01:29:30 DEBUG : index.html: md5 = e07ef3506b5afc47a8d91a6980e4afe8 OK
2026/10/24 01:29:30 DEBUG : index.html: size = 12147 OK
2026/10/24 01:29:30 INFO  : index.html: Copied (new)
2026/10/24 01:29:30 DEBUG : sub/deep/caddy.json: md5 = becbd797ccaee64a4d9258aa7572a9fa OK
2026/10/24 01:29:30 DEBUG : sub/deep/caddy.json: size = 131 OK
2026/10/24 01:29:30 INFO  : sub/deep/caddy.json: Copied (new)
2026/10/24 01:29:30 DEBUG : sub/deep/index.html: md5 = 946e9e78707005a559495dcc935bda66 OK
2026/10/24 01:29:30 DEBUG : sub/deep/index.html: size = 12087 OK
2026/10/24 01:29:30 INFO  : sub/deep/index.html: Copied (new)
2026/10/24 01:29:32 DEBUG : caddy.json: md5 = ca9cfc76f9fd3e2bcfa5217ab3d771a1 OK
2026/10/24 01:29:32 DEBUG : caddy.json: size = 259 OK
2026/10/24 01:29:32 INFO  : caddy.json: Copied (new)
2026/10/24 01:29:32 DEBUG : index.json: md5 = af468fd2e1ae86c7a62e92ad69fc6b59 OK
2026/10/24 01:29:32 DEBUG : index.json: size = 213 OK
2026/10/24 01:29:32 INFO  : index.json: Copied (new)
2026/10/24 01:29:32 DEBUG : sub/index.html: md5 = 6b1dd4c212d0add56bc72443b9875866 OK
2026/10/24 01:29:32 DEBUG : sub/index.html: size = 12306 OK
2026/10/24 01:29:32 INFO  : sub/index.html: Copied (new)
2026/10/24 01:29:32 DEBUG : sub/index.json: md5 = 99e51acb12d1e660aeea7069a7df2a5d OK
2026/10/24 01:29:32 DEBUG : sub/index.json: size = 215 OK
2026/10/24 01:29:32 INFO  : sub/index.json: Copied (new)
2026/10/24 01:29:33 DEBUG : sub/caddy.json: md5 = 58cecbc916e02e667ccaf17deb5424e5 OK
2026/10/24 01:29:33 DEBUG : sub/caddy.json: size = 261 OK
2026/10/24 01:29:33 INFO  : sub/caddy.json: Copied (new)
2026/10/24 01:29:39 DEBUG : index.html: Unchanged skipping
2026/10/24 01:29:39 DEBUG : sub/index.html: Unchanged skipping
2026/10/24 01:29:39 DEBUG : sub/deep/index.html: Unchanged skipping
2026/10/24 01:29:40 DEBUG : index.json: Unchanged skipping
2026/10/24 01:29:40 DEBUG : sub/index.json: Unchanged skipping
2026/10/24 01:29:40 DEBUG : sub/deep/index.json: Unchanged skipping
2026/10/24 01:29:40 DEBUG : caddy.json: Unchanged skipping
2026/10/24 01:29:41 DEBUG : sub/deep/caddy.json: Unchanged skipping
2026/10/24 01:29:41 DEBUG : sub/caddy.json: Unchanged skipping
2026/10/24 01:29:43 DEBUG : index.json: md5 = bd1b54b15fc35e548cef9ca4d7ead36a OK
2026/10/24 01:29:43 DEBUG : index.json: size = 213 OK
2026/10/24 01:29:43 INFO  : index.json: Copied (replaced existing)
2026/10/24 01:29:43 DEBUG : caddy.json: md5 = 123fec3c276e673257c10fcab4b13e46 OK
2026/10/24 01:29:43 DEBUG : caddy.json: size = 259 OK
2026/10/24 01:29:43 INFO  : caddy.json: Copied (replaced existing)
2026/10/24 01:29:43 DEBUG : sub/deep/index.html: md5 = cf9c893f98c43000083c35f94e6f1dc2 OK
2026/10/24 01:29:43 DEBUG : sub/deep/index.html: size = 12087 OK
2026/10/24 01:29:43 INFO  : sub/deep/index.html: Copied (replaced existing)
2026/10/24 01:29:43 DEBUG : index.html: md5 = ecb53a5650799e0bbddca8c6fe80e7e0 OK
2026/10/24 01:29:43 DEBUG : index.html: size = 12147 OK
2026/10/24 01:29:43 INFO  : index.html: Copied (replaced existing)
2026/10/24 01:29:44 DEBUG : sub/deep/index.json: md5 = 45d00906a1cffe78f8c968798b445267 OK
2026/10/24 01:29:44 DEBUG : sub/deep/index.json: size = 119 OK
2026/10/24 01:29:44 INFO  : sub/deep/index.json: Copied (replaced existing)
2026/10/24 01:29:44 DEBUG : sub/index.html: md5 = 07e54251ad71ad9789f10f9f94ba58ba OK
2026/10/24 01:29:44 DEBUG : sub/index.html: size = 12306 OK
2026/10/24 01:29:44 INFO  : sub/index.html: Copied (replaced existing)
2026/10/24 01:29:44 DEBUG : sub/index.json: md5 = 5fa9c8304afeb522148979bea5c1e2f7 OK
2026/10/24 01:29:44 DEBUG : sub/index.json: size = 215 OK
2026/10/24 01:29:44 INFO  : sub/index.json: Copied (replaced existing)
2026/10/24 01:29:44 DEBUG : sub/deep/caddy.json: md5 = 7c9e7c93791ade89c9720c822b9e691f OK
2026/10/24 01:29:44 DEBUG : sub/deep/caddy.json: size = 131 OK
2026/10/24 01:29:44 INFO  : sub/deep/caddy.json: Copied (replaced existing)
2026/10/24 01:29:45 DEBUG : sub/caddy.json: md5 = 5eb0e35e70d35029b2efcacb08f80490 OK
2026/10/24 01:29:45 DEBUG : sub/caddy.json: size = 261 OK
2026/10/24 01:29:45 INFO  : sub/caddy.json: Copied (replaced existing)
2026/10/24 01:29:47 DEBUG : sub/deep/file4.txt: md5 = a573d34bef061f2498181625eec33fb7 OK
2026/10/24 01:29:48 DEBUG : index.html: Unchanged skipping
2026/10/24 01:29:48 DEBUG : sub/index.html: Unchanged skipping
2026/10/24 01:29:49 DEBUG : sub/deep/index.json: md5 = 98f619c57072b6afec6c6ccd30251ac4 OK
2026/10/24 01:29:49 DEBUG : sub/deep/index.json: size = 235 OK
2026/10/24 01:29:49 INFO  : sub/deep/index.json: Copied (replaced existing)
2026/10/24 01:29:49 DEBUG : sub/deep/caddy.json: md5 = 24e006cf152cbbebf25861719aaf145c OK
2026/10/24 01:29:49 DEBUG : sub/deep/caddy.json: size = 260 OK
2026/10/24 01:29:49 INFO  : sub/deep/caddy.json: Copied (replaced existing)
2026/10/24 01:29:49 DEBUG : sub/deep/index.html: md5 = d592f538c900ae0a1c58644544ad771b OK
2026/10/24 01:29:49 DEBUG : sub/deep/index.html: size = 12346 OK
2026/10/24 01:29:49 INFO  : sub/deep/index.html: Copied (replaced existing)
2026/10/24 01:29:49 DEBUG : index.json: Unchanged skipping
2026/10/24 01:29:49 DEBUG : sub/index.json: Unchanged skipping
2026/10/24 01:29:50 DEBUG : caddy.json: Unchanged skipping
2026/10/24 01:29:50 DEBUG : sub/caddy.json: Unchanged skipping
2026/10/24 01:29:52 DEBUG : sub/deep/file5.txt: md5 = cfc717c93e35fd59e28af0cc47d77c8d OK
2026/10/24 01:29:53 NOTICE: sub/deep/index.html: Skipped copy as --dry-run is set (size 12.309Ki)
2026/10/24 01:29:53 NOTICE: sub/deep/index.json: Skipped copy as --dry-run is set (size 351)
2026/10/24 01:29:53 NOTICE: sub/deep/caddy.json: Skipped copy as --dry-run is set (size 389)
2026/10/24 01:29:54 DEBUG : sub/index.html: Unchanged skipping
2026/10/24 01:29:54 DEBUG : index.html: Unchanged skipping
2026/10/24 01:29:54 DEBUG : index.json: Unchanged skipping
2026/10/24 01:29:54 DEBUG : sub/index.json: Unchanged skipping
2026/10/24 01:29:55 DEBUG : caddy.json: Unchanged skipping
2026/10/24 01:29:55 DEBUG : sub/caddy.json: Unchanged skipping
--- PASS: TestIndex (42.63s)
=== RUN   TestIndexDelete
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:30:06 DEBUG : file1.txt: md5 = 428ba98f6177de1f085ede4672985b28 OK
2026/10/24 01:30:09 DEBUG : sub/file2.txt: md5 = 83c1ce85b52d75a7fdd9238ba2bdc322 OK
2026/10/24 01:30:11 DEBUG : sub/deep/file3.txt: md5 = f1912eb66934ad4f8aaec336b0c69435 OK
2026/10/24 01:30:13 DEBUG : index.html: md5 = adb81d5a96a228de7b220a2e950c296e OK
2026/10/24 01:30:13 DEBUG : index.html: size = 12147 OK
2026/10/24 01:30:13 INFO  : index.html: Copied (new)
2026/10/24 01:30:13 DEBUG : sub/deep/index.html: md5 = e75ad42c751675c4c49b06023b950c4f OK
2026/10/24 01:30:13 DEBUG : sub/deep/index.html: size = 12087 OK
2026/10/24 01:30:13 INFO  : sub/deep/index.html: Copied (new)
2026/10/24 01:30:13 DEBUG : sub/index.html: md5 = 9ab4d4c1d4b91aff004aee2e678a39e0 OK
2026/10/24 01:30:13 DEBUG : sub/index.html: size = 12306 OK
2026/10/24 01:30:13 INFO  : sub/index.html: Copied (new)
2026/10/24 01:30:15 DEBUG : sub/index.html: Unchanged skipping
2026/10/24 01:30:15 DEBUG : index.html: Unchanged skipping
2026/10/24 01:30:16 DEBUG : sub/deep/index.html: md5 = d0ca06437cf972fa7c5d0d2de9dfe3d5 OK
2026/10/24 01:30:16 DEBUG : sub/deep/index.html: size = 11829 OK
2026/10/24 01:30:16 INFO  : sub/deep/index.html: Copied (replaced existing)
--- PASS: TestIndexDelete (18.23s)
=== RUN   TestIndexFilter
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:30:24 DEBUG : file1.txt: md5 = 9f2453f02abedf2d98d868c9f6e7bfc5 OK
2026/10/24 01:30:27 DEBUG : sub/file2.txt: md5 = abe95be9b56d86bd34996d0d25f3c222 OK
2026/10/24 01:30:29 DEBUG : sub/deep/file3.txt: md5 = 9c57dde218802c844adcc60a71c43a71 OK
2026/10/24 01:30:31 DEBUG : sub/deep/index.html: md5 = a0a03eeb727ee517b58c7dfae765fd53 OK
2026/10/24 01:30:33 DEBUG : other/notes.md: md5 = 9e5f7571b915607a816e28d07ce2b5e5 OK
2026/10/24 01:30:34 DEBUG : other/notes.md: Excluded (Path Filter)
2026/10/24 01:30:35 DEBUG : index.html: md5 = 7c246747b98a1747bb3c3f3cf5c33098 OK
2026/10/24 01:30:35 DEBUG : index.html: size = 12399 OK
2026/10/24 01:30:35 INFO  : index.html: Copied (new)
2026/10/24 01:30:35 DEBUG : other/index.html: md5 = 919470ffef087acb0c411f4aa3ad0bce OK
2026/10/24 01:30:35 DEBUG : other/index.html: size = 11802 OK
2026/10/24 01:30:35 INFO  : other/index.html: Copied (new)
2026/10/24 01:30:35 DEBUG : sub/index.html: md5 = bc053b4529284ea203173c0da879bd4a OK
2026/10/24 01:30:35 DEBUG : sub/index.html: size = 12056 OK
2026/10/24 01:30:35 INFO  : sub/index.html: Copied (new)
2026/10/24 01:30:41 DEBUG : other/notes.md: Excluded (Path Filter)
2026/10/24 01:30:42 DEBUG : index.html: Unchanged skipping
2026/10/24 01:30:42 DEBUG : sub/index.html: Unchanged skipping
2026/10/24 01:30:42 DEBUG : other/index.html: Unchanged skipping
--- PASS: TestIndexFilter (25.82s)
=== RUN   TestIndexRules
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:30:51 DEBUG : file1.txt: md5 = 4454a400c08db34f1411e7960c8cdf2e OK
2026/10/24 01:30:53 DEBUG : sub/file2.txt: md5 = e793205863871a8516de04e81461a03b OK
2026/10/24 01:30:55 DEBUG : sub/deep/file3.txt: md5 = 0d979c441c2ae0d1c34fbdc35fb56f73 OK
2026/10/24 01:30:57 DEBUG : sub/deep/index.html: md5 = 646530e107ef55889d221382949783fd OK
2026/10/24 01:30:59 DEBUG : index.html: md5 = 29089896350cde69225d8578a5204fe3 OK
2026/10/24 01:30:59 DEBUG : index.html: size = 12147 OK
2026/10/24 01:30:59 INFO  : index.html: Copied (new)
2026/10/24 01:30:59 DEBUG : sub/index.html: md5 = aeff0150d05c7d3a5f70212ab67d163b OK
2026/10/24 01:30:59 DEBUG : sub/index.html: size = 12306 OK
2026/10/24 01:30:59 INFO  : sub/index.html: Copied (new)
2026/10/24 01:31:04 DEBUG : sub/index.html: Unchanged skipping
2026/10/24 01:31:04 DEBUG : sub/deep/index.html: md5 = cff53b33b53a79768a8127a36bdbb1cc OK
2026/10/24 01:31:04 DEBUG : sub/deep/index.html: size = 12087 OK
2026/10/24 01:31:04 INFO  : sub/deep/index.html: Copied (replaced existing)
--- PASS: TestIndexRules (22.68s)
=== RUN   TestIndexMaxDepth
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:31:13 DEBUG : file1.txt: md5 = 9e0881812fc23e4bfa7813ab368e8233 OK
2026/10/24 01:31:15 DEBUG : sub/file2.txt: md5 = ab26e8b27585167a0dd6e162df74ea7b OK
2026/10/24 01:31:18 DEBUG : sub/deep/file3.txt: md5 = 5c365a5c55ef09def72f08e7dba72547 OK
2026/10/24 01:31:20 DEBUG : index.html: md5 = 3d0e160ea7ab564c6d1c538bc042fa65 OK
2026/10/24 01:31:20 DEBUG : index.html: size = 12147 OK
2026/10/24 01:31:20 INFO  : index.html: Copied (new)
--- PASS: TestIndexMaxDepth (15.01s)
=== RUN   TestIndexLinkIndex
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:31:28 DEBUG : file1.txt: md5 = 194f8a7df07dd1a3b379daea706dd01c OK
2026/10/24 01:31:30 DEBUG : sub/file2.txt: md5 = b6a50952da3cb2e4812d813891313d56 OK
2026/10/24 01:31:33 DEBUG : sub/deep/file3.txt: md5 = b83d2af82cf8f9acf3664787c503c6a7 OK
2026/10/24 01:31:35 DEBUG : sub/index.html: md5 = 53542aea2a33057e4e87ec1c44942531 OK
2026/10/24 01:31:35 DEBUG : sub/index.html: size = 12347 OK
2026/10/24 01:31:35 INFO  : sub/index.html: Copied (new)
2026/10/24 01:31:35 DEBUG : index.html: md5 = 00873186964c8bb16f4c0e61cd8ef561 OK
2026/10/24 01:31:35 DEBUG : index.html: size = 12167 OK
2026/10/24 01:31:35 INFO  : index.html: Copied (new)
2026/10/24 01:31:35 DEBUG : sub/deep/index.html: md5 = 2cf23cb4cbac799934a0e3ddae293a80 OK
2026/10/24 01:31:35 DEBUG : sub/deep/index.html: size = 12128 OK
2026/10/24 01:31:35 INFO  : sub/deep/index.html: Copied (new)
--- PASS: TestIndexLinkIndex (15.96s)
=== RUN   TestIndexDirTime
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:31:44 DEBUG : file1.txt: md5 = 5cf30db33f8736314a9cdd0bbf56aed1 OK
2026/10/24 01:31:46 DEBUG : sub/file2.txt: md5 = 27832e5744aa2637bada36bbe8096630 OK
2026/10/24 01:31:49 DEBUG : sub/deep/file3.txt: md5 = 1c4d437c0dcb2059e1833f813cdd43e6 OK
2026/10/24 01:31:51 DEBUG : index.json: md5 = c548096f150106091b1d1374bbcc4893 OK
2026/10/24 01:31:51 DEBUG : index.json: size = 257 OK
2026/10/24 01:31:51 INFO  : index.json: Copied (new)
2026/10/24 01:31:51 DEBUG : sub/deep/index.json: md5 = 3e38bba4af551c58e15e8483f9941896 OK
2026/10/24 01:31:51 DEBUG : sub/deep/index.json: size = 139 OK
2026/10/24 01:31:51 INFO  : sub/deep/index.json: Copied (new)
2026/10/24 01:31:51 DEBUG : sub/index.json: md5 = 05141c25a1c71e3fd7be985dc4d8fbd2 OK
2026/10/24 01:31:51 DEBUG : sub/index.json: size = 259 OK
2026/10/24 01:31:51 INFO  : sub/index.json: Copied (new)
2026/10/24 01:31:54 DEBUG : sub/deep/index.json: Unchanged skipping
2026/10/24 01:31:55 DEBUG : index.json: md5 = 09fc9d89ec5cbf0aedffa9ac68a886be OK
2026/10/24 01:31:55 DEBUG : index.json: size = 237 OK
2026/10/24 01:31:55 INFO  : index.json: Copied (replaced existing)
2026/10/24 01:31:55 DEBUG : sub/index.json: md5 = 4c807bb03737805773e2c78164ac5a47 OK
2026/10/24 01:31:55 DEBUG : sub/index.json: size = 239 OK
2026/10/24 01:31:55 INFO  : sub/index.json: Copied (replaced existing)
2026/10/24 01:31:58 DEBUG : sub/deep/index.json: md5 = e5ca58bbd2075008aac5d4dda8c4ac4c OK
2026/10/24 01:31:58 DEBUG : sub/deep/index.json: size = 119 OK
2026/10/24 01:31:58 INFO  : sub/deep/index.json: Copied (replaced existing)
2026/10/24 01:31:58 DEBUG : index.json: md5 = 603a344ef383202ab2e2b6a0452c7f81 OK
2026/10/24 01:31:58 DEBUG : index.json: size = 213 OK
2026/10/24 01:31:58 INFO  : index.json: Copied (replaced existing)
2026/10/24 01:31:58 DEBUG : sub/index.json: md5 = 2cc6850454706bdfbc299ae00f1aed35 OK
2026/10/24 01:31:58 DEBUG : sub/index.json: size = 215 OK
2026/10/24 01:31:58 INFO  : sub/index.json: Copied (replaced existing)
--- PASS: TestIndexDirTime (21.60s)
=== RUN   TestIndexTemplate
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:32:06 DEBUG : file1.txt: md5 = 925387839addc96171b4d409b881ad5e OK
2026/10/24 01:32:08 DEBUG : sub/file2.txt: md5 = 2ff6eb2d3dc6494a165ab84ceb5ddd7f OK
2026/10/24 01:32:10 DEBUG : sub/deep/file3.txt: md5 = 96c80c8187d663eefa38a24d32abc077 OK
2026/10/24 01:32:12 DEBUG : sub/deep/list.txt: md5 = 6d7549ea47a32e59766890a3f980f3e1 OK
2026/10/24 01:32:12 DEBUG : sub/deep/list.txt: size = 47 OK
2026/10/24 01:32:12 INFO  : sub/deep/list.txt: Copied (new)
2026/10/24 01:32:12 DEBUG : list.txt: md5 = efa672b20251ec63f77601f5a758d8f7 OK
2026/10/24 01:32:12 DEBUG : list.txt: size = 59 OK
2026/10/24 01:32:12 INFO  : list.txt: Copied (new)
2026/10/24 01:32:12 DEBUG : sub/list.txt: md5 = ecd19589c2b2e1a136f8d9b04f602224 OK
2026/10/24 01:32:12 DEBUG : sub/list.txt: size = 64 OK
2026/10/24 01:32:12 INFO  : sub/list.txt: Copied (new)
--- PASS: TestIndexTemplate (14.36s)
=== 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-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:32:20 DEBUG : file1.txt: md5 = 1ee5f57cc3067a47e45edf869767f37d OK
2026/10/24 01:32:22 DEBUG : sub/file2.txt: md5 = a1fe4af366e408cbcbd04874733da5b3 OK
2026/10/24 01:32:24 DEBUG : sub/deep/file3.txt: md5 = a0eaa4e083c1d0e063de3f19606ab930 OK
2026/10/24 01:32:27 DEBUG : other/notes.md: md5 = 6d458fc6130730235167e89932249935 OK
2026/10/24 01:32:29 DEBUG : sub/index.html: md5 = a99cabf1ad919bcded3799d20d1051e3 OK
2026/10/24 01:32:29 DEBUG : sub/index.html: size = 12306 OK
2026/10/24 01:32:29 INFO  : sub/index.html: Copied (new)
2026/10/24 01:32:29 DEBUG : index.html: md5 = a27262cf4c7b156538b7b1ae8948a2f3 OK
2026/10/24 01:32:29 DEBUG : index.html: size = 12399 OK
2026/10/24 01:32:29 INFO  : index.html: Copied (new)
2026/10/24 01:32:29 DEBUG : other/index.html: md5 = 154b0fe0d8a1c20e47993541822556e0 OK
2026/10/24 01:32:29 DEBUG : other/index.html: size = 12058 OK
2026/10/24 01:32:29 INFO  : other/index.html: Copied (new)
2026/10/24 01:32:29 DEBUG : sub/deep/index.html: md5 = 4921b4088faddbfdb527823bd06eaa50 OK
2026/10/24 01:32:29 DEBUG : sub/deep/index.html: size = 12087 OK
2026/10/24 01:32:29 INFO  : sub/deep/index.html: Copied (new)
2026/10/24 01:32:30 DEBUG : sub/deep/file4.txt: md5 = c61583a3a850b6c270e27cf2b98afb90 OK
2026/10/24 01:32:32 DEBUG : other/undeclared.txt: md5 = 97207e880153da6e69d30a44ac32e94a OK
2026/10/24 01:32:32 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo': Partial index: listing 3 directories and walking 0 changed directories
2026/10/24 01:32:33 DEBUG : sub/index.html: Unchanged skipping
2026/10/24 01:32:33 DEBUG : index.html: Unchanged skipping
2026/10/24 01:32:34 DEBUG : sub/deep/index.html: md5 = 185d585f1a9269e90660e95bbf184d35 OK
2026/10/24 01:32:34 DEBUG : sub/deep/index.html: size = 12346 OK
2026/10/24 01:32:34 INFO  : sub/deep/index.html: Copied (replaced existing)
2026/10/24 01:32:38 DEBUG : sub/new/file5.txt: md5 = 3483ca83dfaf17fc6973ab298ef7369f OK
2026/10/24 01:32:38 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo': Partial index: listing 3 directories and walking 0 changed directories
2026/10/24 01:32:39 DEBUG : index.html: Unchanged skipping
2026/10/24 01:32:40 DEBUG : sub/index.html: md5 = b06b7b7c7599979fa9622478d70184c1 OK
2026/10/24 01:32:40 DEBUG : sub/index.html: size = 12554 OK
2026/10/24 01:32:40 INFO  : sub/index.html: Copied (replaced existing)
2026/10/24 01:32:40 DEBUG : sub/new/index.html: md5 = 4ba313b9c8a7936f965ebdf467bf1773 OK
2026/10/24 01:32:40 DEBUG : sub/new/index.html: size = 12085 OK
2026/10/24 01:32:40 INFO  : sub/new/index.html: Copied (new)
2026/10/24 01:32:44 DEBUG : other/undeclared0.txt: md5 = 1bfe26a506aa06e1282ef3ab5106952f OK
2026/10/24 01:32:44 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo': Partial index: listing 1 directories and walking 1 changed directories
2026/10/24 01:32:45 DEBUG : index.html: Unchanged skipping
2026/10/24 01:32:46 DEBUG : other/index.html: md5 = 10fad12ef195df281e05ff7f5705050c OK
2026/10/24 01:32:46 DEBUG : other/index.html: size = 12597 OK
2026/10/24 01:32:46 INFO  : other/index.html: Copied (replaced existing)
2026/10/24 01:32:48 DEBUG : other/undeclared1.txt: md5 = d4d30eedcfc030c90266064351c17abf OK
2026/10/24 01:32:48 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo': Partial index: listing 1 directories and walking 0 changed directories
2026/10/24 01:32:49 DEBUG : index.html: Unchanged skipping
2026/10/24 01:32:50 DEBUG : other/index.html: md5 = b23d45742952814953f71128f43d68a5 OK
2026/10/24 01:32:50 DEBUG : other/index.html: size = 12867 OK
2026/10/24 01:32:50 INFO  : other/index.html: Copied (replaced existing)
2026/10/24 01:32:52 DEBUG : sub/file6.txt: md5 = 26a8c80b1f52a3de47be8542a7a58e15 OK
2026/10/24 01:32:52 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo': Nothing changed so nothing to index
2026/10/24 01:32:53 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo': Root changed so doing a full index
2026/10/24 01:32:54 DEBUG : sub/deep/index.html: Unchanged skipping
2026/10/24 01:32:54 DEBUG : index.html: Unchanged skipping
2026/10/24 01:32:54 DEBUG : sub/new/index.html: Unchanged skipping
2026/10/24 01:32:55 DEBUG : other/index.html: Unchanged skipping
2026/10/24 01:32:55 DEBUG : sub/index.html: md5 = 0c2a7b816a74647a03a86cf423457c6b OK
2026/10/24 01:32:55 DEBUG : sub/index.html: size = 12813 OK
2026/10/24 01:32:55 INFO  : sub/index.html: Copied (replaced existing)
2026/10/24 01:32:58 DEBUG : sub/file7.txt: md5 = ec6988f85a562f223224bfd3f4343b61 OK
2026/10/24 01:32:58 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo': Partial index would list 2 directories, more than 1, so doing a full index
2026/10/24 01:32:59 DEBUG : sub/new/index.html: Unchanged skipping
2026/10/24 01:32:59 DEBUG : sub/deep/index.html: Unchanged skipping
2026/10/24 01:32:59 DEBUG : index.html: Unchanged skipping
2026/10/24 01:32:59 DEBUG : other/index.html: Unchanged skipping
2026/10/24 01:33:00 DEBUG : sub/index.html: md5 = 3992cd0c5ed52c28154b5dcf924f9320 OK
2026/10/24 01:33:00 DEBUG : sub/index.html: size = 13071 OK
2026/10/24 01:33:00 INFO  : sub/index.html: Copied (replaced existing)
2026/10/24 01:33:02 DEBUG : sub/deep/file8.txt: md5 = 2c4890b0de9e5f572ad36ce24e78762b OK
2026/10/24 01:33:02 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo': Partial index: listing 1 directories and walking 1 changed directories
2026/10/24 01:33:03 DEBUG : index.html: Unchanged skipping
2026/10/24 01:33:03 DEBUG : sub/new/index.html: Unchanged skipping
2026/10/24 01:33:03 DEBUG : sub/index.html: Unchanged skipping
2026/10/24 01:33:04 DEBUG : sub/deep/index.html: md5 = b40861482276f6fcd473320f622376fb OK
2026/10/24 01:33:04 DEBUG : sub/deep/index.html: size = 12604 OK
2026/10/24 01:33:04 INFO  : sub/deep/index.html: Copied (replaced existing)
2026/10/24 01:33:06 DEBUG : sub/deep/file9.txt: md5 = a6bc433e5987a046f9b3eca4ff999c12 OK
2026/10/24 01:33:08 DEBUG : sub/file10.txt: md5 = 72417ca64e6b05471243c52fe8133878 OK
2026/10/24 01:33:08 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo': Partial index: listing 2 directories and walking 0 changed directories
2026/10/24 01:33:09 DEBUG : index.html: Unchanged skipping
2026/10/24 01:33:10 DEBUG : sub/index.html: md5 = 499b1cf5f5aa1417defcc1a779e34067 OK
2026/10/24 01:33:10 DEBUG : sub/index.html: size = 13331 OK
2026/10/24 01:33:10 INFO  : sub/index.html: Copied (replaced existing)
--- PASS: TestIndexChanged (65.46s)
=== RUN   TestIndexChangedDelete
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:33:25 DEBUG : file1.txt: md5 = 07bbf27bcf8869441b720030d54e471b OK
2026/10/24 01:33:28 DEBUG : sub/file2.txt: md5 = 7b880c7996fd58da7c87178ab61a4553 OK
2026/10/24 01:33:30 DEBUG : sub/deep/file3.txt: md5 = eef586365aaf91d706dee7bce7511fe3 OK
2026/10/24 01:33:32 DEBUG : sub/index.html: md5 = c8681adb1f09c3aff9464f701fceb27f OK
2026/10/24 01:33:32 DEBUG : sub/index.html: size = 12306 OK
2026/10/24 01:33:32 INFO  : sub/index.html: Copied (new)
2026/10/24 01:33:32 DEBUG : index.html: md5 = aee488da38359f35865ddf96d1ac024c OK
2026/10/24 01:33:32 DEBUG : index.html: size = 12147 OK
2026/10/24 01:33:32 INFO  : index.html: Copied (new)
2026/10/24 01:33:32 DEBUG : sub/deep/index.html: md5 = 87bed74056ee0d4d1f96176a0c382b1b OK
2026/10/24 01:33:32 DEBUG : sub/deep/index.html: size = 12087 OK
2026/10/24 01:33:32 INFO  : sub/deep/index.html: Copied (new)
2026/10/24 01:33:33 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo': Partial index: listing 3 directories and walking 0 changed directories
2026/10/24 01:33:34 DEBUG : sub/index.html: Unchanged skipping
2026/10/24 01:33:34 DEBUG : index.html: Unchanged skipping
2026/10/24 01:33:35 DEBUG : sub/deep/index.html: md5 = 3d3721a5278cd74d35a0dee016338cd8 OK
2026/10/24 01:33:35 DEBUG : sub/deep/index.html: size = 11829 OK
2026/10/24 01:33:35 INFO  : sub/deep/index.html: Copied (replaced existing)
--- PASS: TestIndexChangedDelete (17.38s)
=== RUN   TestIndexChangedFiles
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:33:43 DEBUG : file1.txt: md5 = 856ccc494ca4a21f88105294b48e74bd OK
2026/10/24 01:33:45 DEBUG : sub/file2.txt: md5 = c53e9d5578b47dcdbce11a874af1a0cf OK
2026/10/24 01:33:48 DEBUG : sub/deep/file3.txt: md5 = 64dfcd1cd530bbc368ed16531f135887 OK
2026/10/24 01:33:50 DEBUG : - odd/file.txt: md5 = 96992ee263de024a847ea030ee9e1074 OK
2026/10/24 01:33:52 DEBUG : index.html: md5 = 7fbb39a4155de2395abe8a479ea5d13b OK
2026/10/24 01:33:52 DEBUG : index.html: size = 12401 OK
2026/10/24 01:33:52 INFO  : index.html: Copied (new)
2026/10/24 01:33:52 DEBUG : sub/deep/index.html: md5 = 1d9368eb0937ad2f3de8bfdb7dbba685 OK
2026/10/24 01:33:52 DEBUG : sub/deep/index.html: size = 12087 OK
2026/10/24 01:33:52 INFO  : sub/deep/index.html: Copied (new)
2026/10/24 01:33:52 DEBUG : - odd/index.html: md5 = 7aa6e770c1ffd402b65a95ac3ad7dafb OK
2026/10/24 01:33:52 DEBUG : - odd/index.html: size = 12058 OK
2026/10/24 01:33:52 INFO  : - odd/index.html: Copied (new)
2026/10/24 01:33:52 DEBUG : sub/index.html: md5 = 53beec00d22402a813c8819a278796be OK
2026/10/24 01:33:52 DEBUG : sub/index.html: size = 12306 OK
2026/10/24 01:33:52 INFO  : sub/index.html: Copied (new)
2026/10/24 01:33:54 DEBUG : - odd/file8.txt: md5 = 14cd5054c6bc58f2c997c5660f6fb0a2 OK
2026/10/24 01:33:55 DEBUG : sub/file9.txt: md5 = 7b3241afe47d62ee124ea9e93d21a99a OK
2026/10/24 01:33:55 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo': Partial index: listing 2 directories and walking 0 changed directories
2026/10/24 01:33:56 DEBUG : index.html: Unchanged skipping
2026/10/24 01:33:57 DEBUG : - odd/index.html: md5 = dad1277f7b69eda63d0877124dfe6640 OK
2026/10/24 01:33:57 DEBUG : - odd/index.html: size = 12316 OK
2026/10/24 01:33:57 INFO  : - odd/index.html: Copied (replaced existing)
2026/10/24 01:33:59 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo': Partial index: listing 4 directories and walking 0 changed directories
2026/10/24 01:34:00 DEBUG : - odd/index.html: Unchanged skipping
2026/10/24 01:34:00 DEBUG : index.html: Unchanged skipping
2026/10/24 01:34:00 DEBUG : sub/deep/index.html: Unchanged skipping
2026/10/24 01:34:01 DEBUG : sub/index.html: md5 = 6f0080909ce21e5e212aab39b070a92d OK
2026/10/24 01:34:01 DEBUG : sub/index.html: size = 12565 OK
2026/10/24 01:34:01 INFO  : sub/index.html: Copied (replaced existing)
--- PASS: TestIndexChangedFiles (28.49s)
=== RUN   TestIndexChangedDirTime
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:34:11 DEBUG : file1.txt: md5 = 38d3977c9f4abaeee535461b6511336b OK
2026/10/24 01:34:14 DEBUG : sub/file2.txt: md5 = 44078fdf0805efceaa12231e7744d8d8 OK
2026/10/24 01:34:16 DEBUG : sub/deep/file3.txt: md5 = 316549a2d6eae348b349d7c7e43b44ea OK
2026/10/24 01:34:18 DEBUG : other/notes.md: md5 = bffacb114ef9b6246407c66badafe13a OK
2026/10/24 01:34:20 DEBUG : index.json: md5 = 1c46aa9db8b5765f87c2d4716f06bbf7 OK
2026/10/24 01:34:20 DEBUG : index.json: size = 379 OK
2026/10/24 01:34:20 INFO  : index.json: Copied (new)
2026/10/24 01:34:20 DEBUG : other/index.json: md5 = 5446470d9af7ce565b146a39a41421ce OK
2026/10/24 01:34:20 DEBUG : other/index.json: size = 144 OK
2026/10/24 01:34:20 INFO  : other/index.json: Copied (new)
2026/10/24 01:34:20 DEBUG : sub/deep/index.json: md5 = f5f88564c23559b3d7cd4c5ae5aa7f08 OK
2026/10/24 01:34:20 DEBUG : sub/deep/index.json: size = 139 OK
2026/10/24 01:34:20 INFO  : sub/deep/index.json: Copied (new)
2026/10/24 01:34:21 DEBUG : sub/index.json: md5 = 998e7c42efb179a034278891778de6ac OK
2026/10/24 01:34:21 DEBUG : sub/index.json: size = 259 OK
2026/10/24 01:34:21 INFO  : sub/index.json: Copied (new)
2026/10/24 01:34:22 DEBUG : sub/deep/file4.txt: md5 = 5583cf208c76b1b8594e0eab852a1f3f OK
2026/10/24 01:34:22 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo': Partial index: listing 3 directories and walking 0 changed directories
2026/10/24 01:34:24 DEBUG : sub/deep/index.json: md5 = 7d052443e1b598c60ad8147a4ee505c4 OK
2026/10/24 01:34:24 DEBUG : sub/deep/index.json: size = 275 OK
2026/10/24 01:34:24 INFO  : sub/deep/index.json: Copied (replaced existing)
2026/10/24 01:34:25 DEBUG : sub/index.json: md5 = 7f6ba2fa854e8040208e744973b01c2d OK
2026/10/24 01:34:25 DEBUG : sub/index.json: size = 259 OK
2026/10/24 01:34:25 INFO  : sub/index.json: Copied (replaced existing)
2026/10/24 01:34:25 DEBUG : index.json: md5 = 8187b9f34854076191fbfe938bccd9e7 OK
2026/10/24 01:34:25 DEBUG : index.json: size = 379 OK
2026/10/24 01:34:25 INFO  : index.json: Copied (replaced existing)
--- PASS: TestIndexChangedDirTime (23.72s)
=== RUN   TestListDirSorted
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:34:35 DEBUG : a.txt: md5 = e89dc56f53b097419bc8aef0e514b55e OK
2026/10/24 01:34:37 DEBUG : zend.txt: md5 = b8830478e03eab3205f8e3d63d84cd07 OK
2026/10/24 01:34:39 DEBUG : sub dir/hello world: md5 = f504aabee9993531dbf2ea8d8db38eaf OK
2026/10/24 01:34:40 DEBUG : sub dir/hello world2: md5 = c364dd0f67fbd26c754a3180e46bdb23 OK
2026/10/24 01:34:43 DEBUG : sub dir/ignore dir/.ignore: md5 = 7086913737cc3df42ca16a4ae87d49b4 OK
2026/10/24 01:34:44 DEBUG : sub dir/ignore dir/should be ignored: md5 = d019325b3b837a798b4917d894adc50e OK
2026/10/24 01:34:46 DEBUG : sub dir/sub sub dir/hello world3: md5 = 458e8ff072e96852473c29e8d57724ff OK
2026/10/24 01:34:48 DEBUG : a.txt: Excluded (Size Filter)
2026/10/24 01:34:48 DEBUG : a.txt: Excluded
2026/10/24 01:34:48 DEBUG : sub dir/hello world: Excluded (Size Filter)
2026/10/24 01:34:48 DEBUG : sub dir/hello world: Excluded
2026/10/24 01:34:48 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2026/10/24 01:34:48 DEBUG : sub dir/hello world2: Excluded
2026/10/24 01:34:49 DEBUG : sub dir/ignore dir: Excluded
2026/10/24 01:34:49 DEBUG : sub dir/hello world: Excluded (Size Filter)
2026/10/24 01:34:49 DEBUG : sub dir/hello world: Excluded
2026/10/24 01:34:49 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2026/10/24 01:34:49 DEBUG : sub dir/hello world2: Excluded
2026/10/24 01:34:49 DEBUG : sub dir/ignore dir: Excluded
--- PASS: TestListDirSorted (23.20s)
=== RUN   TestListDirSortedFn
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:34:58 DEBUG : a.txt: md5 = 2a613fc34acc100e3e25b4618cc13889 OK
2026/10/24 01:35:00 DEBUG : zend.txt: md5 = 49c44ae62638a2a598894d53db4a202b OK
2026/10/24 01:35:02 DEBUG : sub dir/hello world: md5 = efcb3ca8b576034411f86f7886d728b2 OK
2026/10/24 01:35:04 DEBUG : sub dir/hello world2: md5 = 51ea76be65a5a97b14eb3b36023cd812 OK
2026/10/24 01:35:06 DEBUG : sub dir/ignore dir/.ignore: md5 = 484a369f991c568768f19e4a46336cb2 OK
2026/10/24 01:35:07 DEBUG : sub dir/ignore dir/should be ignored: md5 = 2e7a9654093747801826b40b18343e47 OK
2026/10/24 01:35:10 DEBUG : sub dir/sub sub dir/hello world3: md5 = 7533f8f1ccdcbca9d64e1884b96f4d56 OK
2026/10/24 01:35:11 DEBUG : a.txt: Excluded (Size Filter)
2026/10/24 01:35:11 DEBUG : a.txt: Excluded
2026/10/24 01:35:12 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2026/10/24 01:35:12 DEBUG : sub dir/hello world2: Excluded
2026/10/24 01:35:12 DEBUG : sub dir/hello world: Excluded (Size Filter)
2026/10/24 01:35:12 DEBUG : sub dir/hello world: Excluded
2026/10/24 01:35:13 DEBUG : sub dir/ignore dir: Excluded
2026/10/24 01:35:13 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2026/10/24 01:35:13 DEBUG : sub dir/hello world2: Excluded
2026/10/24 01:35:13 DEBUG : sub dir/hello world: Excluded (Size Filter)
2026/10/24 01:35:13 DEBUG : sub dir/hello world: Excluded
2026/10/24 01:35:13 DEBUG : sub dir/ignore dir: Excluded
--- PASS: TestListDirSortedFn (23.22s)
=== RUN   TestListJSON
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:35:21 DEBUG : file1: md5 = adc38385816b83d727868b928fda7a85 OK
2026/10/24 01:35:24 DEBUG : sub/file2: md5 = 3527642f20c1fc85efd230491d791444 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/24 01:35:27 ERROR : file1: Failed to read hash: hash type not supported
=== RUN   TestListJSON/Metadata
2026/10/24 01:35:27 DEBUG : eer8kka55qnghc34cq76ca668g: Fetching metadata
2026/10/24 01:35:27 DEBUG : 150fuo3cn4j1uenq6r4g4qk6t4: Fetching metadata
--- PASS: TestListJSON (9.97s)
    --- PASS: TestListJSON/Default (0.29s)
    --- PASS: TestListJSON/FilesOnly (0.29s)
    --- PASS: TestListJSON/DirsOnly (0.28s)
    --- PASS: TestListJSON/Recurse (0.53s)
    --- PASS: TestListJSON/SubDir (0.28s)
    --- PASS: TestListJSON/NoModTime (0.23s)
    --- PASS: TestListJSON/NoMimeType (0.25s)
    --- PASS: TestListJSON/ShowHash (0.27s)
    --- PASS: TestListJSON/HashTypes (0.29s)
    --- PASS: TestListJSON/Metadata (0.67s)
=== RUN   TestStatJSON
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:35:31 DEBUG : file1: md5 = aa729c9f10bf780ea37a68de06e0ad3d OK
2026/10/24 01:35:34 DEBUG : sub/file2: md5 = b0be69e703c577a19c736b4e22fe78a2 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/24 01:35:38 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-kinejoz2jilo/notfound"
2026/10/24 01:35:38 DEBUG : Config file has changed externally - reloading
2026/10/24 01:35:38 DEBUG : Creating backend with remote "TestDrive:crypt/vt4em8t9kto0583k70d1lau4i0it8fsqmo0drh6gl1jsnqt46rl0/qe0i31qdkoejk60elps0ni5sqk"
--- PASS: TestStatJSON (12.60s)
    --- PASS: TestStatJSON/Root (0.27s)
    --- PASS: TestStatJSON/RootFilesOnly (0.00s)
    --- PASS: TestStatJSON/RootDirsOnly (0.27s)
    --- PASS: TestStatJSON/Dir (0.59s)
    --- PASS: TestStatJSON/DirWithTrailingSlash (0.30s)
    --- PASS: TestStatJSON/File (0.26s)
    --- PASS: TestStatJSON/NotFound (0.52s)
    --- PASS: TestStatJSON/DirFilesOnly (0.27s)
    --- PASS: TestStatJSON/FileFilesOnly (0.27s)
    --- PASS: TestStatJSON/NotFoundFilesOnly (0.24s)
    --- PASS: TestStatJSON/DirDirsOnly (0.31s)
    --- PASS: TestStatJSON/FileDirsOnly (0.28s)
    --- PASS: TestStatJSON/NotFoundDirsOnly (0.25s)
    --- PASS: TestStatJSON/RootNotFound (1.88s)
=== RUN   TestStatJSONMemory
2026/10/24 01:35:42 DEBUG : Creating backend with remote ":memory:"
2026/10/24 01:35:42 DEBUG : Config file has changed externally - reloading
2026/10/24 01:35:42 DEBUG : Memory root '': File to upload is small (5 bytes), uploading instead of streaming
2026/10/24 01:35:42 DEBUG : sub/file1: size = 5 OK
2026/10/24 01:35:42 DEBUG : sub/file1: md5 = 5d41402abc4b2a76b9719d911017c592 OK
2026/10/24 01:35:42 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-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:35:42 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo': Making directory
2026/10/24 01:35:43 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo': Making directory
--- PASS: TestMkdir (0.85s)
=== RUN   TestLsd
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:35:46 DEBUG : sub dir/hello world: md5 = bb9c266fe78346f7bb97127eb2f7d9ad OK
--- PASS: TestLsd (5.03s)
=== RUN   TestLs
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:35:50 DEBUG : potato2: md5 = 0439261337d97bed2cb2b27b2ef765c6 OK
2026/10/24 01:35:52 DEBUG : empty space: md5 = ec3047f6576e6614462767437bccdbf8 OK
--- PASS: TestLs (5.23s)
=== RUN   TestLsWithFilesFrom
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:35:55 DEBUG : potato2: md5 = c6dd3095bec60ab9b9e7a3c446157fe9 OK
2026/10/24 01:35:56 DEBUG : empty space: md5 = 0dcf4c13dc8126ed586584e3ff9582e5 OK
2026/10/24 01:35:57 DEBUG : empty space: Excluded (FilesFrom Filter)
2026/10/24 01:35:57 DEBUG : empty space: Excluded
--- PASS: TestLsWithFilesFrom (5.48s)
=== RUN   TestLsLong
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:36:01 DEBUG : potato2: md5 = ab2fd5f0f657a41c8927327394ff6b34 OK
2026/10/24 01:36:02 DEBUG : empty space: md5 = 55ca64d55f759c1d8ddb21ca80c97b6b OK
--- PASS: TestLsLong (5.13s)
=== RUN   TestHashSums
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:36:06 DEBUG : potato2: md5 = 2d09f79c8848156e30755d510883aa04 OK
2026/10/24 01:36:07 DEBUG : empty space: md5 = 07e0795bebb3f2a2c47750a34f6a6b8f OK
--- PASS: TestHashSums (4.66s)
=== RUN   TestHashSumsWithErrors
2026/10/24 01:36:09 DEBUG : Creating backend with remote ":memory:"
2026/10/24 01:36:09 DEBUG : Config file has changed externally - reloading
2026/10/24 01:36:09 ERROR : file1: hash unsupported: hash type not supported
2026/10/24 01:36:09 ERROR : sub/file1: hash unsupported: hash type not supported
2026/10/24 01:36:09 ERROR : sub/file1: hash unsupported: hash type not supported
--- PASS: TestHashSumsWithErrors (0.00s)
=== RUN   TestHashStream
2026/10/24 01:36:09 DEBUG : Creating md5 hash of 0 bytes read from input stream
2026/10/24 01:36:09 DEBUG : Creating md5 hash of 0 bytes read from input stream
2026/10/24 01:36:09 DEBUG : Creating sha1 hash of 0 bytes read from input stream
2026/10/24 01:36:09 DEBUG : Creating sha1 hash of 0 bytes read from input stream
2026/10/24 01:36:09 DEBUG : Creating md5 hash of 12 bytes read from input stream
2026/10/24 01:36:09 DEBUG : Creating md5 hash of 12 bytes read from input stream
2026/10/24 01:36:09 DEBUG : Creating sha1 hash of 12 bytes read from input stream
2026/10/24 01:36:09 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-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:36:10 DEBUG : potato2: md5 = eaaf2261fc378453215b96012e49336a OK
2026/10/24 01:36:12 DEBUG : empty space: md5 = 1cec99e98522a96fe6c986310369f2f7 OK
2026/10/24 01:36:14 DEBUG : sub dir/potato3: md5 = 82e30599a83d782b88d1709e6363ea91 OK
--- PASS: TestCount (9.20s)
=== RUN   TestDelete
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:36:20 DEBUG : small: md5 = adb1c5798d512e3b8206d428df348891 OK
2026/10/24 01:36:21 DEBUG : medium: md5 = bbafc1ce944a35a572d872191a769d35 OK
2026/10/24 01:36:23 DEBUG : large: md5 = 434785f410608717af43a396be01610b OK
2026/10/24 01:36:23 DEBUG : Waiting for deletions to finish
2026/10/24 01:36:23 DEBUG : large: Excluded (Size Filter)
2026/10/24 01:36:24 INFO  : medium: Deleted
2026/10/24 01:36:24 INFO  : small: Deleted
--- PASS: TestDelete (6.86s)
=== RUN   TestDeleteFatalError
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:36:26 DEBUG : file0: md5 = 50b08543211dc4ec52a5358bb2c489b8 OK
2026/10/24 01:36:28 DEBUG : file1: md5 = 19253a9ab0a0de25553f21087e37e378 OK
2026/10/24 01:36:30 DEBUG : file2: md5 = 3a9e3b45b44c86d9722f481357820326 OK
2026/10/24 01:36:31 DEBUG : file3: md5 = ed291b4fe947692eeeceb3ed5bd36d7e OK
2026/10/24 01:36:33 DEBUG : file4: md5 = 5a2bf4bee76633685926cdc666e8e5ad OK
2026/10/24 01:36:34 DEBUG : file5: md5 = f898eca58f4cac1f17fce2f99f349336 OK
2026/10/24 01:36:36 DEBUG : file6: md5 = 9d9aff36e751a29c778603821d12397c OK
2026/10/24 01:36:37 DEBUG : file7: md5 = bd59d857467a1c43d66331c947d9676a OK
2026/10/24 01:36:39 DEBUG : file8: md5 = 9d6826e81e7ee837a1d489c7632c9efc OK
2026/10/24 01:36:40 DEBUG : file9: md5 = 0e2530fa0bcc32cc94b19171b3e658b3 OK
2026/10/24 01:36:42 DEBUG : file10: md5 = 613d2bf697a8c893414d56d31bcd1b2a OK
2026/10/24 01:36:43 DEBUG : file11: md5 = 0ad94ed7dbc3eaa80d4e75525fcbabb5 OK
2026/10/24 01:36:45 DEBUG : file12: md5 = 1fe39ec51893cd1e20be164a8b568a86 OK
2026/10/24 01:36:46 DEBUG : file13: md5 = dcf68b3fd8c83d0852d5e42f37d95905 OK
2026/10/24 01:36:48 DEBUG : file14: md5 = 46206f9294bf06eec9c0f573a9dd1543 OK
2026/10/24 01:36:49 DEBUG : file15: md5 = f1e3f85b43864177ea707482d2d78ccd OK
2026/10/24 01:36:51 DEBUG : file16: md5 = 2011975aeb449fc8b6d6d5a4037a8727 OK
2026/10/24 01:36:52 DEBUG : file17: md5 = a33de3edfb2a6c4b07e32c37beadc12d OK
2026/10/24 01:36:54 DEBUG : file18: md5 = aab61591b3c98a04536b9b31f93e886f OK
2026/10/24 01:36:55 DEBUG : file19: md5 = 1f21f237ec9b52378b85e7193d5b5df8 OK
2026/10/24 01:36:55 DEBUG : Waiting for deletions to finish
2026/10/24 01:36:56 ERROR : file16: Got fatal error on delete: --max-delete threshold reached
2026/10/24 01:36:56 ERROR : file8: Got fatal error on delete: --max-delete threshold reached
2026/10/24 01:36:56 ERROR : file9: Got fatal error on delete: --max-delete threshold reached
2026/10/24 01:36:56 ERROR : file15: Got fatal error on delete: --max-delete threshold reached
2026/10/24 01:36:56 ERROR : file5: Got fatal error on delete: --max-delete threshold reached
2026/10/24 01:36:56 ERROR : file4: Got fatal error on delete: --max-delete threshold reached
2026/10/24 01:36:56 ERROR : file13: Got fatal error on delete: --max-delete threshold reached
2026/10/24 01:36:56 ERROR : file14: Got fatal error on delete: --max-delete threshold reached
2026/10/24 01:36:56 ERROR : file17: Got fatal error on delete: --max-delete threshold reached
2026/10/24 01:36:56 ERROR : file0: Got fatal error on delete: --max-delete threshold reached
2026/10/24 01:36:56 ERROR : file6: Got fatal error on delete: --max-delete threshold reached
2026/10/24 01:36:56 ERROR : file11: Got fatal error on delete: --max-delete threshold reached
2026/10/24 01:36:56 ERROR : file19: Got fatal error on delete: --max-delete threshold reached
2026/10/24 01:36:56 ERROR : file3: Got fatal error on delete: --max-delete threshold reached
2026/10/24 01:36:56 ERROR : file1: Got fatal error on delete: --max-delete threshold reached
2026/10/24 01:36:56 ERROR : file10: Got fatal error on delete: --max-delete threshold reached
2026/10/24 01:36:56 ERROR : file18: Got fatal error on delete: --max-delete threshold reached
2026/10/24 01:36:56 ERROR : file2: Got fatal error on delete: --max-delete threshold reached
2026/10/24 01:36:56 ERROR : file12: Got fatal error on delete: --max-delete threshold reached
2026/10/24 01:36:56 ERROR : file7: Got fatal error on delete: --max-delete threshold reached
--- PASS: TestDeleteFatalError (40.70s)
=== RUN   TestMaxDelete
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:37:07 DEBUG : small: md5 = b6a9b832a86f521d3203aa888032fab5 OK
2026/10/24 01:37:09 DEBUG : medium: md5 = 4878835f4c11dc091cf711f45de0cd38 OK
2026/10/24 01:37:10 DEBUG : large: md5 = 12bc3ee8f948577f51d38a9e372cfd4b OK
2026/10/24 01:37:10 DEBUG : Waiting for deletions to finish
2026/10/24 01:37:11 ERROR : large: Got fatal error on delete: --max-delete threshold reached
2026/10/24 01:37:11 INFO  : small: Deleted
2026/10/24 01:37:11 INFO  : medium: Deleted
--- PASS: TestMaxDelete (7.23s)
=== RUN   TestMaxDeleteSizeLargeFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:37:14 DEBUG : small: md5 = e1daf8a7654bf881bbe0cf63e069e2f2 OK
2026/10/24 01:37:16 DEBUG : medium: md5 = e30e1bbcb0eae1a006c73f6f48b24784 OK
2026/10/24 01:37:18 DEBUG : large: md5 = e4f434517f1036c29cae19a71300fbdd OK
2026/10/24 01:37:18 DEBUG : Waiting for deletions to finish
2026/10/24 01:37:18 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached
2026/10/24 01:37:19 INFO  : small: Deleted
2026/10/24 01:37:19 INFO  : medium: Deleted
--- PASS: TestMaxDeleteSizeLargeFile (7.47s)
=== RUN   TestMaxDeleteSize
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:37:22 DEBUG : small: md5 = ed852cf36482c24f3caa8e7e8ccd5699 OK
2026/10/24 01:37:23 DEBUG : medium: md5 = c1832fa44f1275663f1119ceae59aed7 OK
2026/10/24 01:37:25 DEBUG : large: md5 = d032e760c837a09a5330f183c9bae0d4 OK
2026/10/24 01:37:25 DEBUG : Waiting for deletions to finish
2026/10/24 01:37:26 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached
2026/10/24 01:37:26 INFO  : medium: Deleted
2026/10/24 01:37:26 INFO  : small: Deleted
--- PASS: TestMaxDeleteSize (7.40s)
=== RUN   TestReadFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:37:29 DEBUG : ReadFile: md5 = e704f1fa0847ba790801a46eb5707348 OK
--- PASS: TestReadFile (4.34s)
=== RUN   TestRetry
2026/10/24 01:37:32 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 1/5
2026/10/24 01:37:32 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 2/5
2026/10/24 01:37:32 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms
2026/10/24 01:37:32 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms
2026/10/24 01:37:32 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms
2026/10/24 01:37:32 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/24 01:37:32 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-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:37:34 DEBUG : file1: md5 = 1c3cf75516b7c04e54e031ecef8a0ad5 OK
2026/10/24 01:37:35 DEBUG : file2: md5 = f0b80f10480a7f8a4daa68baa34d9215 OK
--- PASS: TestCat (15.00s)
=== RUN   TestPurge
2026/10/24 01:37:47 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jukekan4hufa"
2026/10/24 01:37:47 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/24 01:37:47 DEBUG : Creating backend with remote "TestDrive:crypt/6mojvu1t7r2u2vfi3iunbc5fufm160ik591fek5plrn1t92l7ka0"
2026/10/24 01:37:48 DEBUG : Creating backend with remote "/tmp/rclone3560761289"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jukekan4hufa'", Local "Local file system at /tmp/rclone3560761289", Modify Window "1ms"
2026/10/24 01:37:53 DEBUG : A1/B1/C1/one: md5 = 56764a613fc050ae1b8c05e251b247e7 OK
2026/10/24 01:37:53 INFO  : A2: Making directory
2026/10/24 01:37:54 INFO  : A1/B2: Making directory
2026/10/24 01:37:55 INFO  : A1/B2/C2: Making directory
2026/10/24 01:37:55 INFO  : A1/B1/C3: Making directory
2026/10/24 01:37:56 INFO  : A3: Making directory
2026/10/24 01:37:57 INFO  : A3/B3: Making directory
2026/10/24 01:37:58 INFO  : A3/B3/C4: Making directory
2026/10/24 01:38:00 DEBUG : A1/two: md5 = bcc60fc83506dbe4099c5c03682ec629 OK
2026/10/24 01:38:04 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jukekan4hufa': Purge remote
2026/10/24 01:38:04 NOTICE: purge failed: directory not found
--- PASS: TestPurge (16.90s)
=== RUN   TestRmdirsNoLeaveRoot
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:38:09 DEBUG : A1/B1/C1/one: md5 = 68055f9d0255466fc3d270b49188e163 OK
2026/10/24 01:38:09 INFO  : A2: Making directory
2026/10/24 01:38:09 INFO  : A1/B2: Making directory
2026/10/24 01:38:10 INFO  : A1/B2/C2: Making directory
2026/10/24 01:38:11 INFO  : A1/B1/C3: Making directory
2026/10/24 01:38:12 INFO  : A3: Making directory
2026/10/24 01:38:13 INFO  : A3/B3: Making directory
2026/10/24 01:38:13 INFO  : A3/B3/C4: Making directory
2026/10/24 01:38:15 DEBUG : A1/two: md5 = 9af2b9041063b339bcefda54d10b4306 OK
2026/10/24 01:38:17 DEBUG : removing 1 level 3 directories
2026/10/24 01:38:17 INFO  : A3/B3/C4: Removing directory
2026/10/24 01:38:19 DEBUG : removing 2 level 3 directories
2026/10/24 01:38:19 INFO  : A1/B2/C2: Removing directory
2026/10/24 01:38:19 INFO  : A1/B1/C3: Removing directory
2026/10/24 01:38:20 DEBUG : removing 2 level 2 directories
2026/10/24 01:38:20 INFO  : A3/B3: Removing directory
2026/10/24 01:38:20 INFO  : A1/B2: Removing directory
2026/10/24 01:38:21 DEBUG : removing 2 level 1 directories
2026/10/24 01:38:21 INFO  : A3: Removing directory
2026/10/24 01:38:21 INFO  : A2: Removing directory
2026/10/24 01:38:25 DEBUG : removing 1 level 3 directories
2026/10/24 01:38:25 INFO  : A1/B1/C1: Removing directory
2026/10/24 01:38:26 DEBUG : removing 1 level 2 directories
2026/10/24 01:38:26 INFO  : A1/B1: Removing directory
2026/10/24 01:38:27 DEBUG : removing 1 level 1 directories
2026/10/24 01:38:27 INFO  : A1: Removing directory
2026/10/24 01:38:27 DEBUG : removing 1 level 0 directories
2026/10/24 01:38:27 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo': Removing directory
--- PASS: TestRmdirsNoLeaveRoot (24.80s)
=== RUN   TestRmdirsLeaveRoot
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:38:30 INFO  : A1: Making directory
2026/10/24 01:38:30 INFO  : A1/B1: Making directory
2026/10/24 01:38:31 INFO  : A1/B1/C1: Making directory
2026/10/24 01:38:34 DEBUG : removing 1 level 3 directories
2026/10/24 01:38:34 INFO  : A1/B1/C1: Removing directory
2026/10/24 01:38:34 DEBUG : removing 1 level 2 directories
2026/10/24 01:38:34 INFO  : A1/B1: Removing directory
--- PASS: TestRmdirsLeaveRoot (8.29s)
=== RUN   TestRmdirsWithFilter
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:38:37 INFO  : A1: Making directory
2026/10/24 01:38:38 INFO  : A1/B1: Making directory
2026/10/24 01:38:39 INFO  : A1/B1/C1: Making directory
2026/10/24 01:38:41 DEBUG : removing 1 level 3 directories
2026/10/24 01:38:41 INFO  : A1/B1/C1: Removing directory
2026/10/24 01:38:42 DEBUG : removing 1 level 2 directories
2026/10/24 01:38:42 INFO  : A1/B1: Removing directory
--- PASS: TestRmdirsWithFilter (7.72s)
=== RUN   TestCopyURL
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:38:47 DEBUG : file1: md5 = 272d8d2d94f23dd666aff515b533dab5 OK
2026/10/24 01:38:47 DEBUG : file1: size = 14 OK
2026/10/24 01:38:47 DEBUG : filename.txt: File name found in url
2026/10/24 01:38:49 DEBUG : filename.txt: md5 = 38e85b735c89374b268a883c776bf2e7 OK
2026/10/24 01:38:49 DEBUG : filename.txt: size = 14 OK
2026/10/24 01:38:49 DEBUG : headerfilename.txt: filename found in Content-Disposition header.
2026/10/24 01:38:50 DEBUG : headerfilename.txt: md5 = bf534f023534942551c85cdf0dcd470e OK
2026/10/24 01:38:50 DEBUG : headerfilename.txt: size = 14 OK
2026/10/24 01:38:52 DEBUG : file2: md5 = b347e033df8ddc34c4454f06b827114d OK
2026/10/24 01:38:52 DEBUG : file2: size = 14 OK
--- PASS: TestCopyURL (9.74s)
=== RUN   TestCopyURLDownloadHeaders
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:38:56 DEBUG : file1: md5 = a44419158d1ec34d251e935633e04ef2 OK
2026/10/24 01:38:56 DEBUG : file1: size = 14 OK
--- PASS: TestCopyURLDownloadHeaders (2.55s)
=== RUN   TestCopyURLToWriter
--- PASS: TestCopyURLToWriter (0.00s)
=== RUN   TestMoveFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:38:57 DEBUG : file1: Need to transfer - File not found at Destination
2026/10/24 01:38:59 DEBUG : sub/file2: md5 = 5cc60474b4f94d10de915d47add56251 OK
2026/10/24 01:38:59 DEBUG : sub/file2: size = 14 OK
2026/10/24 01:38:59 INFO  : file1: Copied (new) to: sub/file2
2026/10/24 01:38:59 INFO  : file1: Deleted
2026/10/24 01:39:00 DEBUG : sub/file2: size = 14 OK
2026/10/24 01:39:00 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2026/10/24 01:39:00 DEBUG : file1: Unchanged skipping
2026/10/24 01:39:00 INFO  : file1: Deleted
2026/10/24 01:39:01 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo': don't need to copy/move sub/file2, it is already at target location
--- PASS: TestMoveFile (6.08s)
=== RUN   TestMoveFileWithIgnoreExisting
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:39:03 DEBUG : file1: Need to transfer - File not found at Destination
2026/10/24 01:39:05 DEBUG : file1: md5 = 3ff75ccb85e6d15720ebcdaa063ba466 OK
2026/10/24 01:39:05 DEBUG : file1: size = 14 OK
2026/10/24 01:39:05 INFO  : file1: Copied (new)
2026/10/24 01:39:05 INFO  : file1: Deleted
2026/10/24 01:39:05 DEBUG : file1: Destination exists, skipping
2026/10/24 01:39:05 DEBUG : file1: Not removing source file as destination file exists and --ignore-existing is set
--- PASS: TestMoveFileWithIgnoreExisting (3.42s)
=== RUN   TestMoveFileImmutable
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:39:08 DEBUG : existing: md5 = ca66988b959cdd88a6cf5c74a1f1165a OK
2026/10/24 01:39:09 DEBUG : existing: size = 8 (Local file system at /tmp/rclone2244109848)
2026/10/24 01:39:09 DEBUG : existing: size = 6 (Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo')
2026/10/24 01:39:09 DEBUG : existing: Sizes differ
2026/10/24 01:39:09 ERROR : existing: Source and destination exist but do not match: immutable file modified
--- PASS: TestMoveFileImmutable (3.38s)
=== RUN   TestCaseInsensitiveMoveFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
--- PASS: TestCaseInsensitiveMoveFile (0.49s)
=== RUN   TestMoveUnicodeEquivalentWithoutServerSideMove
2026/10/24 01:39:10 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-ceduxek6mafa"
2026/10/24 01:39:10 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/24 01:39:11 DEBUG : Creating backend with remote "TestDrive:crypt/q8bog5kcee6qq2mq18708j59s9rsghhvr1sfiv0ti4cf223rv3qg"
2026/10/24 01:39:12 DEBUG : Creating backend with remote "/tmp/rclone3625848502"
2026/10/24 01:39:12 DEBUG : Config file has changed externally - reloading
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-ceduxek6mafa'", Local "Local file system at /tmp/rclone3625848502", Modify Window "1ms"
2026/10/24 01:39:12 DEBUG : Reset feature "Move"
2026/10/24 01:39:15 DEBUG : sub/équivalent.txt: md5 = 6b70e4dc123375214b31f87f23be4dfd OK
2026/10/24 01:39:15 DEBUG : sub/équivalent.txt: moving to sub/équivalent.txt-rclone-move-kegolov4
2026/10/24 01:39:16 DEBUG : sub/équivalent.txt-rclone-move-kegolov4: size = 14 OK
2026/10/24 01:39:16 INFO  : sub/équivalent.txt: Copied (server-side copy) to: sub/équivalent.txt-rclone-move-kegolov4
2026/10/24 01:39:17 INFO  : sub/équivalent.txt: Deleted
2026/10/24 01:39:17 DEBUG : sub/équivalent.txt: moving to sub/équivalent.txt
2026/10/24 01:39:18 DEBUG : sub/équivalent.txt: size = 14 OK
2026/10/24 01:39:18 INFO  : sub/équivalent.txt-rclone-move-kegolov4: Copied (server-side copy) to: sub/équivalent.txt
2026/10/24 01:39:18 INFO  : sub/équivalent.txt-rclone-move-kegolov4: Deleted
2026/10/24 01:39:19 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-ceduxek6mafa': Purge remote
--- PASS: TestMoveUnicodeEquivalentWithoutServerSideMove (8.84s)
=== RUN   TestCaseInsensitiveMoveWithoutServerSideMove
2026/10/24 01:39:19 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-poxoxeh2zeci"
2026/10/24 01:39:19 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/24 01:39:19 DEBUG : Creating backend with remote "TestDrive:crypt/752voeftmn145cnj1il8jonodtoqdghj2vq1urcop9r249lo32j0"
2026/10/24 01:39:20 DEBUG : Creating backend with remote "/tmp/rclone3934558891"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-poxoxeh2zeci'", Local "Local file system at /tmp/rclone3934558891", Modify Window "1ms"
    operations_test.go:1161: remote is case sensitive
2026/10/24 01:39:20 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-poxoxeh2zeci': Purge remote
2026/10/24 01:39:21 NOTICE: purge failed: directory not found
--- SKIP: TestCaseInsensitiveMoveWithoutServerSideMove (1.25s)
=== RUN   TestCaseInsensitiveMoveFileDryRun
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
--- PASS: TestCaseInsensitiveMoveFileDryRun (0.50s)
=== RUN   TestMoveFileBackupDir
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:39:23 DEBUG : dst/file1: md5 = b648760cb19d0fb5ec45097bdb0cb6ed OK
2026/10/24 01:39:24 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-kinejoz2jilo/backup"
2026/10/24 01:39:24 DEBUG : Creating backend with remote "TestDrive:crypt/vt4em8t9kto0583k70d1lau4i0it8fsqmo0drh6gl1jsnqt46rl0/1nrff024r7pq65ecp72fc28jb0"
2026/10/24 01:39:26 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone2244109848)
2026/10/24 01:39:26 DEBUG : dst/file1: size = 18 (Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo')
2026/10/24 01:39:26 DEBUG : dst/file1: Sizes differ
2026/10/24 01:39:28 INFO  : dst/file1: Moved (server-side)
2026/10/24 01:39:29 DEBUG : dst/file1: md5 = 9d4919bfc38d2601c737c1bfa4bc85a4 OK
2026/10/24 01:39:29 DEBUG : dst/file1: size = 14 OK
2026/10/24 01:39:29 INFO  : dst/file1: Copied (new)
2026/10/24 01:39:29 INFO  : dst/file1: Deleted
--- PASS: TestMoveFileBackupDir (13.22s)
=== 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-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:39:37 DEBUG : A/file0: md5 = 46f3819a1927154e12c507dd719fb892 OK
2026/10/24 01:39:38 DEBUG : A/file1: md5 = ea29f7e38ed3a71b026ee288bfe4c84f OK
2026/10/24 01:39:40 DEBUG : A/file2: md5 = 5bbf10c7581a617f0ffa9648c57d4023 OK
2026/10/24 01:39:41 DEBUG : A/file3: md5 = f122f03258c0d898b89abf0b54881a56 OK
2026/10/24 01:39:43 DEBUG : A/file4: md5 = d110719fb6387f369a00d1f68121c080 OK
2026/10/24 01:39:44 DEBUG : A/file5: md5 = f65dd504846d4d2962c3f0bede8a639b OK
2026/10/24 01:39:46 DEBUG : A/file6: md5 = d5c16ed11251387bee163cf7b6433c01 OK
2026/10/24 01:39:47 DEBUG : A/file7: md5 = 222eeabc624db3f419d137ca77e67708 OK
2026/10/24 01:39:49 DEBUG : A/file8: md5 = 07891935e90b252ce7499f49f772dc4f OK
2026/10/24 01:39:50 DEBUG : A/file9: md5 = f95ca45f6be29168b576532592517449 OK
2026/10/24 01:39:51 DEBUG : A/file10: md5 = f79fd24c0717f1e07060df6d53c2f018 OK
2026/10/24 01:39:53 DEBUG : A/file11: md5 = 1e66101a704069f2fed10f9f457db45a OK
2026/10/24 01:39:54 DEBUG : A/file12: md5 = c05b9b4c2e71c78cc867640b2a1f7b68 OK
2026/10/24 01:39:56 DEBUG : A/file13: md5 = 55af3c65e15ef1412205fb20dbec8a7c OK
2026/10/24 01:39:57 DEBUG : A/file14: md5 = 6fd768e2f3f2ff9268d9fed0a36985d2 OK
2026/10/24 01:39:59 DEBUG : A/file15: md5 = 8ddefcd1a45f7f72ff4041af47b96dab OK
2026/10/24 01:40:00 DEBUG : A/file16: md5 = 5d1ab360a97d6e76327e199652445262 OK
2026/10/24 01:40:02 DEBUG : A/file17: md5 = d2d36836f826c32933341027198b3ab5 OK
2026/10/24 01:40:03 DEBUG : A/file18: md5 = 4b1a38b305a492534e98aedd0ee7c365 OK
2026/10/24 01:40:05 DEBUG : A/file19: md5 = 9f3d2ac8248f8dacddee21ce1c267f12 OK
    operations_test.go:1526: Skipping test on non local remote
--- SKIP: TestDirMoveMoveError (41.12s)
=== RUN   TestDirMoveContext
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:40:18 DEBUG : A/one: md5 = f3150a278fab11dc69be3aaa068a872e OK
2026/10/24 01:40:19 DEBUG : A/two: md5 = c4ec2b55138d4e6df5ff3c2d044a2ac2 OK
    operations_test.go:1526: Skipping test on non local remote
--- SKIP: TestDirMoveContext (6.15s)
=== RUN   TestDirMove
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:40:24 DEBUG : A1/one: md5 = dea66b285c0b4e09a4b6ea3061dd4268 OK
2026/10/24 01:40:25 DEBUG : A1/two: md5 = 060d2fc58f355e0e4aa66dfcd64f2099 OK
2026/10/24 01:40:28 DEBUG : A1/B1/three: md5 = befd25f0c4d52dee67278c353f541e61 OK
2026/10/24 01:40:30 DEBUG : A1/B1/C1/four: md5 = b61b4ff254c5dbbce058907d31cbdaf6 OK
2026/10/24 01:40:32 DEBUG : A1/B1/C2/five: md5 = 1a42d31ebf1cf091fd8ffc0c117c5797 OK
2026/10/24 01:40:32 INFO  : A1/B2: Making directory
2026/10/24 01:40:33 INFO  : A1/B1/C3: Making directory
2026/10/24 01:40:43 INFO  : A2/B1/C1/four: Moved (server-side) to: A3/B1/C1/four
2026/10/24 01:40:43 INFO  : A2/two: Moved (server-side) to: A3/two
2026/10/24 01:40:44 INFO  : A2/B1/C2/five: Moved (server-side) to: A3/B1/C2/five
2026/10/24 01:40:44 INFO  : A2/one: Moved (server-side) to: A3/one
2026/10/24 01:40:44 INFO  : A2/B1/three: Moved (server-side) to: A3/B1/three
2026/10/24 01:40:49 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo': Can't DirMove - falling back to file moves: can't move directory - incompatible remotes
2026/10/24 01:40:56 INFO  : A3/B1/C1/four: Moved (server-side) to: A4/B1/C1/four
2026/10/24 01:40:56 INFO  : A3/two: Moved (server-side) to: A4/two
2026/10/24 01:40:56 INFO  : A3/B1/C2/five: Moved (server-side) to: A4/B1/C2/five
2026/10/24 01:40:56 INFO  : A3/one: Moved (server-side) to: A4/one
2026/10/24 01:40:56 INFO  : A3/B1/three: Moved (server-side) to: A4/B1/three
--- PASS: TestDirMove (47.59s)
=== RUN   TestGetFsInfo
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:41:09 DEBUG : Config file has changed externally - reloading
--- PASS: TestGetFsInfo (0.46s)
=== RUN   TestRcat
=== RUN   TestRcat/withChecksum=false,ignoreChecksum=false
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:41:10 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo': File to upload is small (34 bytes), uploading instead of streaming
2026/10/24 01:41:11 DEBUG : no_checksum_small_file_from_pipe: md5 = cfdf155a9076ffcf664e5904f767cd8a OK
2026/10/24 01:41:11 DEBUG : no_checksum_small_file_from_pipe: size = 34 OK
2026/10/24 01:41:11 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only
2026/10/24 01:41:11 DEBUG : no_checksum_small_file_from_pipe: Size of src and dst objects identical
2026/10/24 01:41:12 DEBUG : qc9k4uauqa9ls6a2r6lo8jf4b1m7r0510pkurois1cc70egbs4h0: Sending chunk 0 length 102465
2026/10/24 01:41:13 DEBUG : no_checksum_big_file_from_pipe: md5 = 10dbfd41db7b70c0037d64838c818916 OK
2026/10/24 01:41:13 DEBUG : no_checksum_big_file_from_pipe: size = 102401 OK
2026/10/24 01:41:13 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-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:41:15 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo': File to upload is small (34 bytes), uploading instead of streaming
2026/10/24 01:41:17 DEBUG : with_checksum_small_file_from_pipe: md5 = fefe482a63017cc6dd818fdb5209a542 OK
2026/10/24 01:41:17 DEBUG : with_checksum_small_file_from_pipe: size = 34 OK
2026/10/24 01:41:17 DEBUG : with_checksum_small_file_from_pipe: Size of src and dst objects identical
2026/10/24 01:41:18 DEBUG : e74jo8a7lr1aimg2u78fct67gnuetc1qujiofaci0c8fnq7rjt1d4i296pcklqhgdihs03etvgdi4: Sending chunk 0 length 102465
2026/10/24 01:41:19 DEBUG : with_checksum_big_file_from_pipe: md5 = 3a13236242181334b72ab0e85286df84 OK
2026/10/24 01:41:19 DEBUG : with_checksum_big_file_from_pipe: size = 102401 OK
2026/10/24 01:41:19 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-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:41:21 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo': File to upload is small (34 bytes), uploading instead of streaming
2026/10/24 01:41:23 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK
2026/10/24 01:41:23 DEBUG : ignore_checksum_small_file_from_pipe: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2026/10/24 01:41:23 DEBUG : 1963rn6olajfbjn4nbqi9nacu7bk879vpf0d2bg8r0ndcqqsfntob0urt056p0qujf574rqnb5f62: Sending chunk 0 length 102465
2026/10/24 01:41:24 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK
2026/10/24 01:41:24 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-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:41:26 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo': File to upload is small (34 bytes), uploading instead of streaming
2026/10/24 01:41:28 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK
2026/10/24 01:41:28 DEBUG : ignore_checksum_small_file_from_pipe: Size of src and dst objects identical
2026/10/24 01:41:29 DEBUG : 1963rn6olajfbjn4nbqi9nacu7bk879vpf0d2bg8r0ndcqqsfntob0urt056p0qujf574rqnb5f62: Sending chunk 0 length 102465
2026/10/24 01:41:30 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK
2026/10/24 01:41:30 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical
--- PASS: TestRcat (22.03s)
    --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (5.31s)
    --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (5.79s)
    --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (5.41s)
    --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (5.51s)
=== RUN   TestRcatMetadata
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
=== RUN   TestRcatMetadata/Normal
2026/10/24 01:41:32 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo': File to upload is small (48 bytes), uploading instead of streaming
2026/10/24 01:41:33 DEBUG : rcat_metadata: md5 = 92553e90d4fce54163403b4ae8a0ccb8 OK
2026/10/24 01:41:33 DEBUG : rcat_metadata: size = 48 OK
2026/10/24 01:41:33 DEBUG : rcat_metadata: Size of src and dst objects identical
=== RUN   TestRcatMetadata/ViaDisk
2026/10/24 01:41:36 DEBUG : rijssoobhbn85c5p1imhg55m6frank1nlplla6hkdjlqmqb2471g: Sending chunk 0 length 111
2026/10/24 01:41:37 DEBUG : rcat_metadata_uploadcutoff0: md5 = 67538ea47eb32200daab302651e2e0d0 OK
2026/10/24 01:41:37 DEBUG : rcat_metadata_uploadcutoff0: size = 63 OK
2026/10/24 01:41:37 DEBUG : rcat_metadata_uploadcutoff0: Size of src and dst objects identical
--- PASS: TestRcatMetadata (6.61s)
    --- PASS: TestRcatMetadata/Normal (2.82s)
    --- PASS: TestRcatMetadata/ViaDisk (3.34s)
=== RUN   TestRcatSize
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:41:40 DEBUG : potato1: md5 = 54d4ef75830be4b5a864a49950c2f392 OK
2026/10/24 01:41:40 DEBUG : potato1: size = 60 OK
2026/10/24 01:41:40 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo': File to upload is small (60 bytes), uploading instead of streaming
2026/10/24 01:41:41 DEBUG : potato2: md5 = 7f7936ce3b7562417f3fc6872ca412e8 OK
2026/10/24 01:41:41 DEBUG : potato2: size = 60 OK
2026/10/24 01:41:41 DEBUG : potato2: Size of src and dst objects identical
--- PASS: TestRcatSize (4.73s)
=== RUN   TestRcatSizeShortEOF
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:41:44 DEBUG : potato1: md5 = 940aef183c31ca7764fc91fce2f2c6e8 OK
2026/10/24 01:41:44 DEBUG : potato1: size = 120 (Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo')
2026/10/24 01:41:44 DEBUG : potato1: size = 60 (Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo')
2026/10/24 01:41:44 ERROR : potato1: corrupted on transfer: sizes differ src 120 vs dst(Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo') 60
2026/10/24 01:41:44 INFO  : potato1: Removing failed copy
--- PASS: TestRcatSizeShortEOF (2.54s)
=== RUN   TestRcatSizeMetadata
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:41:47 DEBUG : potato1: md5 = e87f57b0683b45382f53e4071268fe2a OK
2026/10/24 01:41:47 DEBUG : potato1: size = 60 OK
2026/10/24 01:41:47 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo': File to upload is small (60 bytes), uploading instead of streaming
2026/10/24 01:41:48 DEBUG : potato2: md5 = afebe8cdc4247754e0ffde58ed66cd55 OK
2026/10/24 01:41:48 DEBUG : potato2: size = 60 OK
2026/10/24 01:41:48 DEBUG : potato2: Size of src and dst objects identical
--- PASS: TestRcatSizeMetadata (5.20s)
=== RUN   TestRcatSizeUploadHeaders
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:41:52 DEBUG : potato1: md5 = b43c8e98174086fcb0793d2654b8640e OK
2026/10/24 01:41:52 DEBUG : potato1: size = 60 OK
--- PASS: TestRcatSizeUploadHeaders (2.55s)
=== RUN   TestRcatSizeChecksum
=== RUN   TestRcatSizeChecksum/Corrupted
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
    operations_test.go:2011: Skipping as destination doesn't support hashes
=== RUN   TestRcatSizeChecksum/SizeDiffers
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:41:55 DEBUG : potato4: md5 = a06d33a625b4e8004caac7a5471ebe89 OK
2026/10/24 01:41:55 DEBUG : potato4: size = 60 (Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo')
2026/10/24 01:41:55 DEBUG : potato4: size = 59 (Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo')
2026/10/24 01:41:55 ERROR : potato4: corrupted on transfer: sizes differ src 60 vs dst(Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo') 59
2026/10/24 01:41:55 INFO  : potato4: Removing failed copy
=== RUN   TestRcatSizeChecksum/IgnoreChecksum
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:41:58 DEBUG : potato2: size = 60 OK
=== RUN   TestRcatSizeChecksum/NoHashes
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:42:01 DEBUG : potato3: md5 = bfa73045045135fb1349d4e5a3e65bf1 OK
2026/10/24 01:42:01 DEBUG : potato3: size = 60 OK
--- PASS: TestRcatSizeChecksum (8.41s)
    --- SKIP: TestRcatSizeChecksum/Corrupted (0.49s)
    --- PASS: TestRcatSizeChecksum/SizeDiffers (2.94s)
    --- PASS: TestRcatSizeChecksum/IgnoreChecksum (2.41s)
    --- PASS: TestRcatSizeChecksum/NoHashes (2.57s)
=== RUN   TestTouchDir
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:42:03 DEBUG : potato2: md5 = 54ccd63b95c2846796d3b28d4035197c OK
2026/10/24 01:42:05 DEBUG : empty space: md5 = 9eb4c276b34d7e9d370425394f96df14 OK
2026/10/24 01:42:07 DEBUG : sub dir/potato3: md5 = 1445b7616a90163d1ad7735b1fcc7b5b OK
2026/10/24 01:42:08 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo': Touching "sub dir/potato3"
2026/10/24 01:42:08 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo': Touching "empty space"
2026/10/24 01:42:08 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo': Touching "potato2"
--- PASS: TestTouchDir (10.34s)
=== RUN   TestMkdirMetadata
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:42:12 DEBUG : dir with metadata: Making directory with metadata
2026/10/24 01:42:13 INFO  : dir with metadata: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z)
--- PASS: TestMkdirMetadata (2.60s)
=== RUN   TestMkdirModTime
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:42:15 DEBUG : directory with modtime: Making directory with metadata
2026/10/24 01:42:15 INFO  : directory with modtime: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2026/10/24 01:42:16 NOTICE: dry run directory with modtime: Skipped make directory as --dry-run is set
--- PASS: TestMkdirModTime (2.58s)
=== RUN   TestCopyDirMetadata
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:42:17 DEBUG : dir with metadata to be copied: Making directory with metadata
2026/10/24 01:42:17 INFO  : dir with metadata to be copied: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z)
2026/10/24 01:42:17 DEBUG : Google drive root 'crypt/vt4em8t9kto0583k70d1lau4i0it8fsqmo0drh6gl1jsnqt46rl0': Skipping btime metadata as can't update it on an existing file: 2026-10-24T01:42:17.715055396Z
2026/10/24 01:42:18 INFO  : non existent directory: Updated directory metadata
2026/10/24 01:42:20 DEBUG : Google drive root 'crypt/vt4em8t9kto0583k70d1lau4i0it8fsqmo0drh6gl1jsnqt46rl0': Skipping btime metadata as can't update it on an existing file: 2026-10-24T01:42:17.715055396Z
2026/10/24 01:42:21 INFO  : existing directory: Updated directory metadata
--- PASS: TestCopyDirMetadata (5.13s)
=== RUN   TestSetDirModTime
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:42:22 DEBUG : set modtime on non existent directory: Skipping set directory modification time as --no-update-dir-modtime is set
2026/10/24 01:42:24 INFO  : set modtime on existing directory: Set directory modification time (using SetModTime)
2026/10/24 01:42:25 INFO  : set modtime on existing directory: Set directory modification time (using DirSetModTime)
--- PASS: TestSetDirModTime (4.35s)
=== RUN   TestDirsEqual
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:42:27 DEBUG : dir with metadata to be copied: Making directory with metadata
2026/10/24 01:42:27 INFO  : dir with metadata to be copied: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z)
2026/10/24 01:42:27 DEBUG : dst: Making directory with metadata
2026/10/24 01:42:28 INFO  : dst: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z)
2026/10/24 01:42:28 DEBUG : dst: Directory modification time the same (differ by -999.999µs, within tolerance 1ms)
2026/10/24 01:42:28 INFO  : dst: Set directory modification time (using SetModTime)
2026/10/24 01:42:28 INFO  : dst: Set directory modification time (using SetModTime)
2026/10/24 01:42:28 DEBUG : dst: Directory modification time the same (differ by 1ns, within tolerance 1ms)
2026/10/24 01:42:29 INFO  : dst: Set directory modification time (using SetModTime)
2026/10/24 01:42:29 DEBUG : dst: Destination directory is newer than source, skipping
--- PASS: TestDirsEqual (3.75s)
=== RUN   TestRemoveExisting
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo'", Local "Local file system at /tmp/rclone2244109848", Modify Window "1ms"
2026/10/24 01:42:33 DEBUG : sub dir/test remove existing: md5 = ef6f42f154577001e5b2c66becc1acbf OK
2026/10/24 01:42:34 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: md5 = 30b0139c15321a6f98f2d65b4075327c OK
2026/10/24 01:42:36 DEBUG : sub dir/test remove existing: TEST: renaming existing object to "sub dir/test remove existing.tehigup4" before starting
2026/10/24 01:42:37 DEBUG : sub dir/test remove existing.tehigup4: TEST: removing renamed existing file after operation
2026/10/24 01:42:38 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: TEST: renaming existing object to "sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.giqizin0" before starting
2026/10/24 01:42:39 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.giqizin0: TEST: renaming existing back after failed operation
2026/10/24 01:42:41 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: TEST: renaming existing object to "sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.gelepoy6" before starting
2026/10/24 01:42:42 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.gelepoy6: TEST: removing renamed existing file after operation
--- PASS: TestRemoveExisting (13.55s)
=== RUN   TestRcatInputFailurePreservesDestination
2026/10/24 01:42:44 DEBUG : Creating backend with remote "/tmp/TestRcatInputFailurePreservesDestination4271916401/001"
2026/10/24 01:42:44 DEBUG : Config file has changed externally - reloading
--- PASS: TestRcatInputFailurePreservesDestination (0.00s)
=== RUN   TestRcAbout
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcAbout (0.00s)
=== RUN   TestRcCleanup
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcCleanup (0.00s)
=== RUN   TestRcCopyfile
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcCopyfile (0.00s)
=== RUN   TestRcCopyurl
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcCopyurl (0.00s)
=== RUN   TestRcDelete
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcDelete (0.00s)
=== RUN   TestRcDeletefile
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcDeletefile (0.00s)
=== RUN   TestRcDeletefileBackupDir
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcDeletefileBackupDir (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/24 01:42:44 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-kinejoz2jilo': Purge remote
"./operations.test -test.v -test.timeout 1h0m0s -remote TestCryptDrive: -verbose" - Finished OK in 22m18.406125796s (try 1/5)