"./operations.test -test.v -test.timeout 1h0m0s -remote TestCryptDrive: -verbose" - Starting (try 1/5)
2026/10/10 03:13:45 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jidazuy5pate"
2026/10/10 03:13:45 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/10 03:13:45 DEBUG : Creating backend with remote "TestDrive:crypt/dk13k2is7heuf9fqbv1qce710vcbjsoohqk6qu3gd00cahoe8f9g"
2026/10/10 03:13:47 DEBUG : Creating backend with remote "/tmp/rclone2406082256"
=== 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-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
    multithread_test.go:120: multithread writing not supported
--- SKIP: TestMultithreadCopy (0.29s)
=== RUN   TestMultithreadCopyAbort
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
    multithread_test.go:120: multithread writing not supported
--- SKIP: TestMultithreadCopyAbort (0.29s)
=== RUN   TestMultithreadCopyWriterAtErrors
=== RUN   TestMultithreadCopyWriterAtErrors/open/fatal=false
2026/10/10 03:13:47 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt
=== RUN   TestMultithreadCopyWriterAtErrors/open/fatal=true
2026/10/10 03:13:47 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt
=== RUN   TestMultithreadCopyWriterAtErrors/write/fatal=false
2026/10/10 03:13:47 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt
2026/10/10 03:13:47 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams
2026/10/10 03:13:47 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting
2026/10/10 03:13:47 DEBUG : file.txt: writing chunk 1
2026/10/10 03:13:47 DEBUG : file.txt: multi-thread copy: chunk 2/4 failed: multi-thread copy: failed to write chunk: disk full
2026/10/10 03:13:47 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting
2026/10/10 03:13:47 DEBUG : file.txt: writing chunk 2
2026/10/10 03:13:47 DEBUG : file.txt: multi-thread copy: chunk 3/4 failed: multi-thread copy: failed to write chunk: disk full
2026/10/10 03:13:47 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting
2026/10/10 03:13:47 DEBUG : file.txt: writing chunk 0
2026/10/10 03:13:47 DEBUG : file.txt: multi-thread copy: chunk 1/4 failed: multi-thread copy: failed to write chunk: disk full
2026/10/10 03:13:47 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit
2026/10/10 03:13:47 DEBUG : file.txt: multi-thread copy: abort failed: multi-thread copy: failed to find temp file when aborting chunk writer: object not found
=== RUN   TestMultithreadCopyWriterAtErrors/write/fatal=true
2026/10/10 03:13:47 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt
2026/10/10 03:13:47 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams
2026/10/10 03:13:47 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting
2026/10/10 03:13:47 DEBUG : file.txt: writing chunk 1
2026/10/10 03:13:47 DEBUG : file.txt: multi-thread copy: chunk 2/4 failed: multi-thread copy: failed to write chunk: disk full
2026/10/10 03:13:47 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting
2026/10/10 03:13:47 DEBUG : file.txt: writing chunk 2
2026/10/10 03:13:47 DEBUG : file.txt: multi-thread copy: chunk 3/4 failed: multi-thread copy: failed to write chunk: disk full
2026/10/10 03:13:47 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting
2026/10/10 03:13:47 DEBUG : file.txt: writing chunk 0
2026/10/10 03:13:47 DEBUG : file.txt: multi-thread copy: chunk 1/4 failed: multi-thread copy: failed to write chunk: disk full
2026/10/10 03:13:47 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit
2026/10/10 03:13:47 DEBUG : file.txt: multi-thread copy: abort failed: multi-thread copy: failed to find temp file when aborting chunk writer: object not found
=== RUN   TestMultithreadCopyWriterAtErrors/close/fatal=false
2026/10/10 03:13:47 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt
2026/10/10 03:13:47 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams
2026/10/10 03:13:47 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting
2026/10/10 03:13:47 DEBUG : file.txt: writing chunk 1
2026/10/10 03:13:47 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 finished
2026/10/10 03:13:47 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting
2026/10/10 03:13:47 DEBUG : file.txt: writing chunk 2
2026/10/10 03:13:47 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 finished
2026/10/10 03:13:47 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 starting
2026/10/10 03:13:47 DEBUG : file.txt: writing chunk 3
2026/10/10 03:13:47 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 finished
2026/10/10 03:13:47 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting
2026/10/10 03:13:47 DEBUG : file.txt: writing chunk 0
2026/10/10 03:13:47 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 finished
2026/10/10 03:13:47 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit
2026/10/10 03:13:47 DEBUG : file.txt: multi-thread copy: abort failed: multi-thread copy: failed to find temp file when aborting chunk writer: object not found
=== RUN   TestMultithreadCopyWriterAtErrors/close/fatal=true
2026/10/10 03:13:47 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt
2026/10/10 03:13:47 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams
2026/10/10 03:13:47 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting
2026/10/10 03:13:47 DEBUG : file.txt: writing chunk 1
2026/10/10 03:13:47 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 finished
2026/10/10 03:13:47 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting
2026/10/10 03:13:47 DEBUG : file.txt: writing chunk 2
2026/10/10 03:13:47 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 finished
2026/10/10 03:13:47 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 starting
2026/10/10 03:13:47 DEBUG : file.txt: writing chunk 3
2026/10/10 03:13:47 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 finished
2026/10/10 03:13:47 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting
2026/10/10 03:13:47 DEBUG : file.txt: writing chunk 0
2026/10/10 03:13:47 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 finished
2026/10/10 03:13:47 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit
2026/10/10 03:13:47 DEBUG : file.txt: multi-thread copy: abort failed: multi-thread copy: failed to find temp file when aborting chunk writer: object not found
--- PASS: TestMultithreadCopyWriterAtErrors (0.00s)
    --- PASS: TestMultithreadCopyWriterAtErrors/open/fatal=false (0.00s)
    --- PASS: TestMultithreadCopyWriterAtErrors/open/fatal=true (0.00s)
    --- PASS: TestMultithreadCopyWriterAtErrors/write/fatal=false (0.00s)
    --- PASS: TestMultithreadCopyWriterAtErrors/write/fatal=true (0.00s)
    --- PASS: TestMultithreadCopyWriterAtErrors/close/fatal=false (0.00s)
    --- PASS: TestMultithreadCopyWriterAtErrors/close/fatal=true (0.00s)
=== RUN   TestSizeDiffers
2026/10/10 03:13:47 DEBUG : a: size = 0 OK
2026/10/10 03:13:47 DEBUG : a: size = 1 (memory)
2026/10/10 03:13:47 DEBUG : a: size = 2 (memory)
--- PASS: TestSizeDiffers (0.00s)
=== RUN   TestDirTransferEntry
--- PASS: TestDirTransferEntry (0.00s)
=== RUN   TestReOpen
=== RUN   TestReOpen/Normal
=== RUN   TestReOpen/Normal/Basics
2026/10/10 03:13:47 DEBUG : potato: Seek from 10 to 0
=== RUN   TestReOpen/Normal/ErrorAtStart
=== RUN   TestReOpen/Normal/WithErrors
2026/10/10 03:13:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2026/10/10 03:13:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2026/10/10 03:13:47 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN   TestReOpen/Normal/TooManyErrors
2026/10/10 03:13:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2026/10/10 03:13:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2026/10/10 03:13:47 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2026/10/10 03:13:47 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN   TestReOpen/Normal/ReadAt
2026/10/10 03:13:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/10/10 03:13:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/10/10 03:13:47 DEBUG : potato: Seek from 5 to 1
=== RUN   TestReOpen/Normal/Seek
2026/10/10 03:13:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/10/10 03:13:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/10/10 03:13:47 DEBUG : potato: Seek from 5 to 2
=== RUN   TestReOpen/Normal/AccountRead
=== RUN   TestReOpen/Normal/AccountReadDelay
2026/10/10 03:13:47 DEBUG : potato: Seek from 10 to 0
2026/10/10 03:13:47 DEBUG : potato: Seek from 10 to 0
2026/10/10 03:13:47 DEBUG : potato: Seek from 10 to 0
=== RUN   TestReOpen/Normal/AccountReadError
=== RUN   TestReOpen/WithRangeOption
=== RUN   TestReOpen/WithRangeOption/Basics
2026/10/10 03:13:47 DEBUG : potato: Seek from 7 to 0
=== RUN   TestReOpen/WithRangeOption/ErrorAtStart
=== RUN   TestReOpen/WithRangeOption/WithErrors
2026/10/10 03:13:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2026/10/10 03:13:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2026/10/10 03:13:47 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN   TestReOpen/WithRangeOption/TooManyErrors
2026/10/10 03:13:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2026/10/10 03:13:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2026/10/10 03:13:47 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2026/10/10 03:13:47 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN   TestReOpen/WithRangeOption/ReadAt
2026/10/10 03:13:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/10/10 03:13:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/10/10 03:13:47 DEBUG : potato: Seek from 5 to 1
=== RUN   TestReOpen/WithRangeOption/Seek
2026/10/10 03:13:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/10/10 03:13:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/10/10 03:13:47 DEBUG : potato: Seek from 5 to 2
2026/10/10 03:13:47 DEBUG : potato: Seek from 7 to 4
=== RUN   TestReOpen/WithRangeOption/AccountRead
=== RUN   TestReOpen/WithRangeOption/AccountReadDelay
2026/10/10 03:13:47 DEBUG : potato: Seek from 7 to 0
2026/10/10 03:13:47 DEBUG : potato: Seek from 7 to 0
2026/10/10 03:13:47 DEBUG : potato: Seek from 7 to 0
=== RUN   TestReOpen/WithRangeOption/AccountReadError
=== RUN   TestReOpen/WithSeekOption
=== RUN   TestReOpen/WithSeekOption/Basics
2026/10/10 03:13:47 DEBUG : potato: Seek from 8 to 0
=== RUN   TestReOpen/WithSeekOption/ErrorAtStart
=== RUN   TestReOpen/WithSeekOption/WithErrors
2026/10/10 03:13:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2026/10/10 03:13:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2026/10/10 03:13:47 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN   TestReOpen/WithSeekOption/TooManyErrors
2026/10/10 03:13:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2026/10/10 03:13:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2026/10/10 03:13:47 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2026/10/10 03:13:47 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN   TestReOpen/WithSeekOption/ReadAt
2026/10/10 03:13:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/10/10 03:13:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/10/10 03:13:47 DEBUG : potato: Seek from 5 to 1
=== RUN   TestReOpen/WithSeekOption/Seek
2026/10/10 03:13:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/10/10 03:13:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/10/10 03:13:47 DEBUG : potato: Seek from 5 to 2
2026/10/10 03:13:47 DEBUG : potato: Seek from 7 to 5
=== RUN   TestReOpen/WithSeekOption/AccountRead
=== RUN   TestReOpen/WithSeekOption/AccountReadDelay
2026/10/10 03:13:47 DEBUG : potato: Seek from 8 to 0
2026/10/10 03:13:47 DEBUG : potato: Seek from 8 to 0
2026/10/10 03:13:47 DEBUG : potato: Seek from 8 to 0
=== RUN   TestReOpen/WithSeekOption/AccountReadError
=== RUN   TestReOpen/UnknownSize
=== RUN   TestReOpen/UnknownSize/Basics
2026/10/10 03:13:47 DEBUG : potato: Seek from 9 to 0
=== RUN   TestReOpen/UnknownSize/ErrorAtStart
=== RUN   TestReOpen/UnknownSize/WithErrors
2026/10/10 03:13:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2026/10/10 03:13:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2026/10/10 03:13:47 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN   TestReOpen/UnknownSize/TooManyErrors
2026/10/10 03:13:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2026/10/10 03:13:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2026/10/10 03:13:47 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2026/10/10 03:13:47 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN   TestReOpen/UnknownSize/ReadAt
2026/10/10 03:13:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/10/10 03:13:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/10/10 03:13:47 DEBUG : potato: Seek from 5 to 1
=== RUN   TestReOpen/UnknownSize/Seek
2026/10/10 03:13:47 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/10/10 03:13:47 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/10/10 03:13:47 DEBUG : potato: Seek from 5 to 2
2026/10/10 03:13:47 DEBUG : potato: Seek from 7 to 6
=== RUN   TestReOpen/UnknownSize/AccountRead
=== RUN   TestReOpen/UnknownSize/AccountReadDelay
2026/10/10 03:13:47 DEBUG : potato: Seek from 9 to 0
2026/10/10 03:13:47 DEBUG : potato: Seek from 9 to 0
2026/10/10 03:13:47 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-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:13:50 DEBUG : rutabaga: md5 = ae8883df758957ce1b7f3a34e0d9f733 OK
=== RUN   TestCheck/1
=== RUN   TestCheck/2
2026/10/10 03:13:52 DEBUG : empty space: md5 = 1bb3752d3a29ca41b77164128ce52dfe OK
=== RUN   TestCheck/3
2026/10/10 03:13:55 DEBUG : potato2: md5 = 034f205bca68ff779727c8dec5b91a0e OK
=== RUN   TestCheck/4
=== RUN   TestCheck/5
2026/10/10 03:13:57 DEBUG : remotepotato: md5 = 2869c31e0e712b1355f33185334db983 OK
=== RUN   TestCheck/6
=== RUN   TestCheck/7
--- PASS: TestCheck (13.38s)
    --- PASS: TestCheck/1 (0.33s)
    --- PASS: TestCheck/2 (0.31s)
    --- PASS: TestCheck/3 (0.31s)
    --- PASS: TestCheck/4 (0.28s)
    --- PASS: TestCheck/5 (0.33s)
    --- PASS: TestCheck/6 (0.34s)
    --- PASS: TestCheck/7 (0.34s)
=== RUN   TestCheckFsError
2026/10/10 03:14:01 DEBUG : Creating backend with remote "nonexistent"
2026/10/10 03:14:01 DEBUG : Creating backend with remote "nonexistent"
2026/10/10 03:14:01 DEBUG : Local file system at nonexistent: Waiting for checks to finish
2026/10/10 03:14:01 ERROR : Local file system at nonexistent: error reading source root directory: directory not found
2026/10/10 03:14:01 NOTICE: Local file system at nonexistent: 0 differences found
2026/10/10 03:14:01 NOTICE: Local file system at nonexistent: 2 errors while checking
--- PASS: TestCheckFsError (0.01s)
=== RUN   TestCheckDownload
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:14:02 DEBUG : rutabaga: md5 = bee9d722c74b4f1c6fdd178b58b2047e OK
=== RUN   TestCheckDownload/1
=== RUN   TestCheckDownload/2
2026/10/10 03:14:06 DEBUG : empty space: md5 = f594d69f44fd25bbe1b1559f182a648a OK
=== RUN   TestCheckDownload/3
2026/10/10 03:14:09 DEBUG : potato2: md5 = 7a05b9722e2a33d85e4f6c06f3132461 OK
=== RUN   TestCheckDownload/4
=== RUN   TestCheckDownload/5
2026/10/10 03:14:13 DEBUG : remotepotato: md5 = e4a0853edcefd1b1e94f9b3c29943926 OK
=== RUN   TestCheckDownload/6
=== RUN   TestCheckDownload/7
--- PASS: TestCheckDownload (16.58s)
    --- PASS: TestCheckDownload/1 (1.02s)
    --- PASS: TestCheckDownload/2 (0.99s)
    --- PASS: TestCheckDownload/3 (0.89s)
    --- PASS: TestCheckDownload/4 (0.98s)
    --- PASS: TestCheckDownload/5 (0.90s)
    --- PASS: TestCheckDownload/6 (0.87s)
    --- PASS: TestCheckDownload/7 (0.77s)
=== RUN   TestCheckSizeOnly
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:14:19 DEBUG : rutabaga: md5 = 202f9b561826926facd6097c4b8cfcbe OK
=== RUN   TestCheckSizeOnly/1
=== RUN   TestCheckSizeOnly/2
2026/10/10 03:14:21 DEBUG : empty space: md5 = 2cb7eb199302af14a5e2ee2f332db007 OK
=== RUN   TestCheckSizeOnly/3
2026/10/10 03:14:23 DEBUG : potato2: md5 = 01f4189a16414fba28780760abe9bcf0 OK
=== RUN   TestCheckSizeOnly/4
=== RUN   TestCheckSizeOnly/5
2026/10/10 03:14:26 DEBUG : remotepotato: md5 = b5e8071cea8643eea1b49818302f4c40 OK
=== RUN   TestCheckSizeOnly/6
=== RUN   TestCheckSizeOnly/7
--- PASS: TestCheckSizeOnly (11.98s)
    --- PASS: TestCheckSizeOnly/1 (0.29s)
    --- PASS: TestCheckSizeOnly/2 (0.29s)
    --- PASS: TestCheckSizeOnly/3 (0.27s)
    --- PASS: TestCheckSizeOnly/4 (0.30s)
    --- PASS: TestCheckSizeOnly/5 (0.31s)
    --- PASS: TestCheckSizeOnly/6 (0.31s)
    --- PASS: TestCheckSizeOnly/7 (0.32s)
=== RUN   TestCheckEqualReaders
--- PASS: TestCheckEqualReaders (0.00s)
=== RUN   TestParseSumFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:14:31 DEBUG : test.sum: md5 = a5c8a429076f9192984fa50982f69e57 OK
2026/10/10 03:14:32 NOTICE: test.sum: improperly formatted checksum line 4
2026/10/10 03:14:32 NOTICE: test.sum: improperly formatted checksum line 5
2026/10/10 03:14:32 NOTICE: test.sum: improperly formatted checksum line 6
2026/10/10 03:14:32 NOTICE: test.sum: 2 warning(s) suppressed...
2026/10/10 03:14:33 DEBUG : test.sum: md5 = b42af89f27d2b7ada357b0b4ddb39a0d OK
2026/10/10 03:14:34 NOTICE: test.sum: improperly formatted checksum line 4
2026/10/10 03:14:34 NOTICE: test.sum: improperly formatted checksum line 5
2026/10/10 03:14:34 NOTICE: test.sum: improperly formatted checksum line 6
2026/10/10 03:14:34 NOTICE: test.sum: 2 warning(s) suppressed...
--- PASS: TestParseSumFile (6.20s)
=== RUN   TestCheckSum
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:14:36 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jidazuy5pate/data"
2026/10/10 03:14:36 DEBUG : Creating backend with remote "TestDrive:crypt/dk13k2is7heuf9fqbv1qce710vcbjsoohqk6qu3gd00cahoe8f9g/vjrnln8ratgmqakfosrqe8espk"
    check_test.go:354: Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate/data' lacks md5, skipping
--- SKIP: TestCheckSum (2.44s)
=== RUN   TestCheckSumDownload
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:14:38 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jidazuy5pate/data"
2026/10/10 03:14:38 DEBUG : Creating backend with remote "TestDrive:crypt/dk13k2is7heuf9fqbv1qce710vcbjsoohqk6qu3gd00cahoe8f9g/vjrnln8ratgmqakfosrqe8espk"
2026/10/10 03:14:42 DEBUG : data/banana: md5 = a04bbca96dda499c61c50bf67886b8a2 OK
2026/10/10 03:14:44 DEBUG : test.sum: md5 = 6d0ab7ab86a28d986361e85791f1812d OK
=== RUN   TestCheckSumDownload/subtest1
2026/10/10 03:14:48 DEBUG : data/potato: md5 = 0435b648f264c2e8bcbef9811fbf7a1c OK
2026/10/10 03:14:50 DEBUG : test.sum: md5 = ac7d19a2821f2a31f1ed36206fdbd105 OK
=== RUN   TestCheckSumDownload/subtest2
2026/10/10 03:14:54 DEBUG : test.sum: md5 = a7575d38b69099cc541f76072415e0dc OK
=== RUN   TestCheckSumDownload/subtest3
2026/10/10 03:14:58 DEBUG : test.sum: md5 = e04855f272ff2eaf81bfffcc79e6ac31 OK
=== RUN   TestCheckSumDownload/subtest4
2026/10/10 03:15:02 DEBUG : test.sum: md5 = 6368be3b097ed8b997f92da3a2aeefef OK
=== RUN   TestCheckSumDownload/subtest5
2026/10/10 03:15:07 DEBUG : test.sum: md5 = 78bbbbea3bdcefb5d85e860e3daa5719 OK
=== RUN   TestCheckSumDownload/subtest6
2026/10/10 03:15:11 DEBUG : data/banana: md5 = 0c462722f48b49a31eb4ebd8dc898fd2 OK
2026/10/10 03:15:13 DEBUG : data/potato: md5 = b07f88008154ec0e639ecb1f749bdb4d OK
2026/10/10 03:15:14 DEBUG : test.sum: md5 = 74266139a807ae8f88a00c38a1386605 OK
=== RUN   TestCheckSumDownload/subtest7
--- PASS: TestCheckSumDownload (41.75s)
    --- PASS: TestCheckSumDownload/subtest1 (2.17s)
    --- PASS: TestCheckSumDownload/subtest2 (1.83s)
    --- PASS: TestCheckSumDownload/subtest3 (2.09s)
    --- PASS: TestCheckSumDownload/subtest4 (1.86s)
    --- PASS: TestCheckSumDownload/subtest5 (2.05s)
    --- PASS: TestCheckSumDownload/subtest6 (1.96s)
    --- PASS: TestCheckSumDownload/subtest7 (1.84s)
=== RUN   TestCheckSumConcurrency
2026/10/10 03:15:20 DEBUG : file0: md5 = 022eb0c0085c731f335df970fafa7446 OK
2026/10/10 03:15:20 DEBUG : file2: md5 = a58f09ae3db8ae4b943fdbaace096072 OK
2026/10/10 03:15:20 DEBUG : file1: md5 = 17fbabbccbdea0e6be9eb64c8c6fea41 OK
2026/10/10 03:15:20 DEBUG : file3: md5 = e0639b17e0744aca9a3ba29c049e59ee OK
2026/10/10 03:15:20 NOTICE: Mock file system at checkSum: 0 differences found
2026/10/10 03:15:20 NOTICE: Mock file system at checkSum: 4 matching files
--- PASS: TestCheckSumConcurrency (0.00s)
=== RUN   TestCheckSumDownloadConcurrency
2026/10/10 03:15:20 DEBUG : file0: md5 = 022eb0c0085c731f335df970fafa7446 OK
2026/10/10 03:15:20 DEBUG : file2: md5 = a58f09ae3db8ae4b943fdbaace096072 OK
2026/10/10 03:15:20 DEBUG : file1: md5 = 17fbabbccbdea0e6be9eb64c8c6fea41 OK
2026/10/10 03:15:20 DEBUG : file3: md5 = e0639b17e0744aca9a3ba29c049e59ee OK
2026/10/10 03:15:20 NOTICE: Mock file system at checkSum: 0 differences found
2026/10/10 03:15:20 NOTICE: Mock file system at checkSum: 4 matching files
--- PASS: TestCheckSumDownloadConcurrency (0.00s)
=== RUN   TestApplyTransforms
2026/10/10 03:15:20 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-sugezit8bama"
2026/10/10 03:15:20 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/10 03:15:20 DEBUG : Creating backend with remote "TestDrive:crypt/790blsde6oudg8147ohet83asigambt0a6hf6anirl1t04ltquvg"
2026/10/10 03:15:21 DEBUG : Creating backend with remote "/tmp/rclone3372685189"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-sugezit8bama'", Local "Local file system at /tmp/rclone3372685189", Modify Window "1ms"
2026/10/10 03:15:23 DEBUG : hello, world!: md5 = c97744066825715575bf39f166de40b5 OK
upper checkfile vs. lower remote (without normalization)
2026/10/10 03:15:24 ERROR : hello, world!: sum not found
2026/10/10 03:15:24 ERROR : HELLO, WORLD!: file not in Encrypted drive 'TestCryptDrive:rclone-test-sugezit8bama'
2026/10/10 03:15:24 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-sugezit8bama': 1 files missing
2026/10/10 03:15:24 NOTICE: 1 hashes missing
2026/10/10 03:15:24 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-sugezit8bama': 1 differences found
2026/10/10 03:15:24 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-sugezit8bama': 2 errors while checking
upper checkfile vs. lower remote (with normalization)
2026/10/10 03:15:25 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/10 03:15:25 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-sugezit8bama': 0 differences found
2026/10/10 03:15:25 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-sugezit8bama': 1 matching files
2026/10/10 03:15:25 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-fukosov6roga"
2026/10/10 03:15:25 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/10 03:15:25 DEBUG : Creating backend with remote "TestDrive:crypt/prhonidv00hoipnrnu9kq7v17c5evulnhg6fijsk8jgvh4br0svg"
2026/10/10 03:15:26 DEBUG : Creating backend with remote "/tmp/rclone2935542023"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fukosov6roga'", Local "Local file system at /tmp/rclone2935542023", Modify Window "1ms"
2026/10/10 03:15:29 DEBUG : HELLO, WORLD!: md5 = 721e4e75911be6202847d6ce949d0675 OK
lower checkfile vs. upper remote (without normalization)
2026/10/10 03:15:29 ERROR : HELLO, WORLD!: sum not found
2026/10/10 03:15:29 ERROR : hello, world!: file not in Encrypted drive 'TestCryptDrive:rclone-test-fukosov6roga'
2026/10/10 03:15:29 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fukosov6roga': 1 files missing
2026/10/10 03:15:29 NOTICE: 1 hashes missing
2026/10/10 03:15:29 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fukosov6roga': 1 differences found
2026/10/10 03:15:29 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fukosov6roga': 2 errors while checking
lower checkfile vs. upper remote (with normalization)
2026/10/10 03:15:30 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/10 03:15:30 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fukosov6roga': 0 differences found
2026/10/10 03:15:30 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fukosov6roga': 1 matching files
2026/10/10 03:15:30 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-tehiqaz9fohe"
2026/10/10 03:15:30 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/10 03:15:31 DEBUG : Creating backend with remote "TestDrive:crypt/mnquug4lrtvchiva04m7cu8jpluo3c9sq42qcm7q22j4s0nd6co0"
2026/10/10 03:15:32 DEBUG : Creating backend with remote "/tmp/rclone1180370152"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tehiqaz9fohe'", Local "Local file system at /tmp/rclone1180370152", Modify Window "1ms"
2026/10/10 03:15:34 DEBUG : HeLlO, wOrLd!: md5 = f63d403fbab677c753575d6535a43230 OK
lower checkfile vs. upperlowermixed remote (without normalization)
2026/10/10 03:15:35 ERROR : HeLlO, wOrLd!: sum not found
2026/10/10 03:15:35 ERROR : hello, world!: file not in Encrypted drive 'TestCryptDrive:rclone-test-tehiqaz9fohe'
2026/10/10 03:15:35 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-tehiqaz9fohe': 1 files missing
2026/10/10 03:15:35 NOTICE: 1 hashes missing
2026/10/10 03:15:35 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-tehiqaz9fohe': 1 differences found
2026/10/10 03:15:35 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-tehiqaz9fohe': 2 errors while checking
lower checkfile vs. upperlowermixed remote (with normalization)
2026/10/10 03:15:36 DEBUG : HeLlO, wOrLd!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/10 03:15:36 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-tehiqaz9fohe': 0 differences found
2026/10/10 03:15:36 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-tehiqaz9fohe': 1 matching files
2026/10/10 03:15:36 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-litusas9mupo"
2026/10/10 03:15:36 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/10 03:15:36 DEBUG : Creating backend with remote "TestDrive:crypt/vrqrc37m659hqk83kgu39d9oupm5lr288q98tnoebbf0t3u9h1ng"
2026/10/10 03:15:37 DEBUG : Creating backend with remote "/tmp/rclone336984515"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-litusas9mupo'", Local "Local file system at /tmp/rclone336984515", Modify Window "1ms"
2026/10/10 03:15:40 DEBUG : HELLO, WORLD!: md5 = 1c27bd735a2622142317276506550f0d OK
upperlowermixed checkfile vs. upper remote (without normalization)
2026/10/10 03:15:41 ERROR : HELLO, WORLD!: sum not found
2026/10/10 03:15:41 ERROR : HeLlO, wOrLd!: file not in Encrypted drive 'TestCryptDrive:rclone-test-litusas9mupo'
2026/10/10 03:15:41 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-litusas9mupo': 1 files missing
2026/10/10 03:15:41 NOTICE: 1 hashes missing
2026/10/10 03:15:41 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-litusas9mupo': 1 differences found
2026/10/10 03:15:41 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-litusas9mupo': 2 errors while checking
upperlowermixed checkfile vs. upper remote (with normalization)
2026/10/10 03:15:42 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/10 03:15:42 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-litusas9mupo': 0 differences found
2026/10/10 03:15:42 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-litusas9mupo': 1 matching files
2026/10/10 03:15:42 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-ticuqij7zohi"
2026/10/10 03:15:42 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/10 03:15:42 DEBUG : Creating backend with remote "TestDrive:crypt/opv36tuboccnvlmn0t2h41268ii0gmjpedd8eagi5olho9uthfng"
2026/10/10 03:15:43 DEBUG : Creating backend with remote "/tmp/rclone2422056333"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-ticuqij7zohi'", Local "Local file system at /tmp/rclone2422056333", Modify Window "1ms"
2026/10/10 03:15:46 DEBUG : 測試_Русский___ě_áñ: md5 = 37f6d0042b647fd83002d984c8d4d603 OK
NFD checkfile vs. NFC remote (without normalization)
2026/10/10 03:15:46 ERROR : 測試_Русский___ě_áñ: sum not found
2026/10/10 03:15:46 ERROR : 測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-ticuqij7zohi'
2026/10/10 03:15:46 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-ticuqij7zohi': 1 files missing
2026/10/10 03:15:46 NOTICE: 1 hashes missing
2026/10/10 03:15:46 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-ticuqij7zohi': 1 differences found
2026/10/10 03:15:46 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-ticuqij7zohi': 2 errors while checking
NFD checkfile vs. NFC remote (with normalization)
2026/10/10 03:15:47 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/10 03:15:47 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-ticuqij7zohi': 0 differences found
2026/10/10 03:15:47 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-ticuqij7zohi': 1 matching files
2026/10/10 03:15:47 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-zuwopaf1wezo"
2026/10/10 03:15:47 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/10 03:15:47 DEBUG : Creating backend with remote "TestDrive:crypt/3qdu29ijho9lke3dvbkm8s5acfe8h774ncbk2cqlsp1sf3s486fg"
2026/10/10 03:15:48 DEBUG : Creating backend with remote "/tmp/rclone2358435202"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zuwopaf1wezo'", Local "Local file system at /tmp/rclone2358435202", Modify Window "1ms"
2026/10/10 03:15:51 DEBUG : 測試_Русский___ě_áñ: md5 = 4b0b226d40d0f698f7df7fee202253ea OK
NFC checkfile vs. NFD remote (without normalization)
2026/10/10 03:15:51 ERROR : 測試_Русский___ě_áñ: sum not found
2026/10/10 03:15:51 ERROR : 測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-zuwopaf1wezo'
2026/10/10 03:15:51 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-zuwopaf1wezo': 1 files missing
2026/10/10 03:15:51 NOTICE: 1 hashes missing
2026/10/10 03:15:51 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-zuwopaf1wezo': 1 differences found
2026/10/10 03:15:51 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-zuwopaf1wezo': 2 errors while checking
NFC checkfile vs. NFD remote (with normalization)
2026/10/10 03:15:52 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/10 03:15:52 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-zuwopaf1wezo': 0 differences found
2026/10/10 03:15:52 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-zuwopaf1wezo': 1 matching files
2026/10/10 03:15:52 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-witogob1soye"
2026/10/10 03:15:52 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/10 03:15:53 DEBUG : Creating backend with remote "TestDrive:crypt/2egm3j4hbvjgjru77bjjqfgp87ja1lmaopa38jtmse9l5c3taqig"
2026/10/10 03:15:54 DEBUG : Creating backend with remote "/tmp/rclone3867695878"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-witogob1soye'", Local "Local file system at /tmp/rclone3867695878", Modify Window "1ms"
2026/10/10 03:15:56 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = ed3d61ee7ecc99e6d77794098e281f42 OK
NFDx2 checkfile vs. both remote (without normalization)
2026/10/10 03:15:57 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found
2026/10/10 03:15:57 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-witogob1soye'
2026/10/10 03:15:57 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-witogob1soye': 1 files missing
2026/10/10 03:15:57 NOTICE: 1 hashes missing
2026/10/10 03:15:57 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-witogob1soye': 1 differences found
2026/10/10 03:15:57 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-witogob1soye': 2 errors while checking
NFDx2 checkfile vs. both remote (with normalization)
2026/10/10 03:15:58 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/10 03:15:58 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-witogob1soye': 0 differences found
2026/10/10 03:15:58 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-witogob1soye': 1 matching files
2026/10/10 03:15:58 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jaqoyiq1nupo"
2026/10/10 03:15:58 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/10 03:15:58 DEBUG : Creating backend with remote "TestDrive:crypt/ivrmmb9hilu3dljrkal3cvo9vhr6klghan6acrdt4ugepst7ie4g"
2026/10/10 03:15:59 DEBUG : Creating backend with remote "/tmp/rclone3208703574"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqoyiq1nupo'", Local "Local file system at /tmp/rclone3208703574", Modify Window "1ms"
2026/10/10 03:16:01 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 849a52b18aac63355e292c47247ecf50 OK
NFCx2 checkfile vs. both remote (without normalization)
2026/10/10 03:16:02 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found
2026/10/10 03:16:02 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-jaqoyiq1nupo'
2026/10/10 03:16:02 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-jaqoyiq1nupo': 1 files missing
2026/10/10 03:16:02 NOTICE: 1 hashes missing
2026/10/10 03:16:02 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-jaqoyiq1nupo': 1 differences found
2026/10/10 03:16:02 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-jaqoyiq1nupo': 2 errors while checking
NFCx2 checkfile vs. both remote (with normalization)
2026/10/10 03:16:03 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/10 03:16:03 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-jaqoyiq1nupo': 0 differences found
2026/10/10 03:16:03 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-jaqoyiq1nupo': 1 matching files
2026/10/10 03:16:03 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-daradeh9nafe"
2026/10/10 03:16:03 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/10 03:16:03 DEBUG : Creating backend with remote "TestDrive:crypt/m46oj8lalpjcpon53fi5p1550stur5km7s1836n5vhofhl35c7n0"
2026/10/10 03:16:04 DEBUG : Creating backend with remote "/tmp/rclone2032838730"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-daradeh9nafe'", Local "Local file system at /tmp/rclone2032838730", Modify Window "1ms"
2026/10/10 03:16:06 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 1ceb8c86c8aca8ebefb07fa3eae832ab OK
both checkfile vs. NFDx2 remote (without normalization)
2026/10/10 03:16:07 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found
2026/10/10 03:16:07 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-daradeh9nafe'
2026/10/10 03:16:07 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-daradeh9nafe': 1 files missing
2026/10/10 03:16:07 NOTICE: 1 hashes missing
2026/10/10 03:16:07 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-daradeh9nafe': 1 differences found
2026/10/10 03:16:07 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-daradeh9nafe': 2 errors while checking
both checkfile vs. NFDx2 remote (with normalization)
2026/10/10 03:16:08 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/10 03:16:08 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-daradeh9nafe': 0 differences found
2026/10/10 03:16:08 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-daradeh9nafe': 1 matching files
2026/10/10 03:16:08 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-qabavoq3naci"
2026/10/10 03:16:08 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/10 03:16:08 DEBUG : Creating backend with remote "TestDrive:crypt/t2sk8ogd0r26cg5mcl84r606ncch9v3gbkgb6tqa4bcdm5738edg"
2026/10/10 03:16:09 DEBUG : Creating backend with remote "/tmp/rclone1806137991"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-qabavoq3naci'", Local "Local file system at /tmp/rclone1806137991", Modify Window "1ms"
2026/10/10 03:16:11 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 6bc75646c656e670e13ac6046f113517 OK
both checkfile vs. NFCx2 remote (without normalization)
2026/10/10 03:16:12 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found
2026/10/10 03:16:12 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-qabavoq3naci'
2026/10/10 03:16:12 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-qabavoq3naci': 1 files missing
2026/10/10 03:16:12 NOTICE: 1 hashes missing
2026/10/10 03:16:12 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-qabavoq3naci': 1 differences found
2026/10/10 03:16:12 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-qabavoq3naci': 2 errors while checking
both checkfile vs. NFCx2 remote (with normalization)
2026/10/10 03:16:13 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/10 03:16:13 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-qabavoq3naci': 0 differences found
2026/10/10 03:16:13 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-qabavoq3naci': 1 matching files
2026/10/10 03:16:13 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-qabavoq3naci': Purge remote
2026/10/10 03:16:13 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-daradeh9nafe': Purge remote
2026/10/10 03:16:14 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jaqoyiq1nupo': Purge remote
2026/10/10 03:16:14 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-witogob1soye': Purge remote
2026/10/10 03:16:15 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zuwopaf1wezo': Purge remote
2026/10/10 03:16:15 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-ticuqij7zohi': Purge remote
2026/10/10 03:16:16 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-litusas9mupo': Purge remote
2026/10/10 03:16:16 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-tehiqaz9fohe': Purge remote
2026/10/10 03:16:17 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-fukosov6roga': Purge remote
2026/10/10 03:16:17 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-sugezit8bama': Purge remote
--- PASS: TestApplyTransforms (58.44s)
=== RUN   TestTruncateString
--- PASS: TestTruncateString (0.00s)
=== RUN   TestCopyFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:16:18 DEBUG : file1: Need to transfer - File not found at Destination
2026/10/10 03:16:21 DEBUG : sub/file2: md5 = 159679e361dac23971fb0998609b66aa OK
2026/10/10 03:16:21 DEBUG : sub/file2: size = 14 OK
2026/10/10 03:16:21 INFO  : file1: Copied (new) to: sub/file2
2026/10/10 03:16:22 DEBUG : sub/file2: size = 14 OK
2026/10/10 03:16:22 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2026/10/10 03:16:22 DEBUG : file1: Unchanged skipping
2026/10/10 03:16:22 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate': don't need to copy/move sub/file2, it is already at target location
--- PASS: TestCopyFile (6.74s)
=== RUN   TestCopyFileImmutable
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:16:25 DEBUG : existing: Need to transfer - File not found at Destination
2026/10/10 03:16:27 DEBUG : existing: md5 = 50673ac8dadc4d0e198fdeea9c29c8e4 OK
2026/10/10 03:16:27 DEBUG : existing: size = 6 OK
2026/10/10 03:16:27 INFO  : existing: Copied (new)
2026/10/10 03:16:28 DEBUG : existing: size = 6 OK
2026/10/10 03:16:28 DEBUG : existing: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2026/10/10 03:16:28 DEBUG : existing: Unchanged skipping
2026/10/10 03:16:28 DEBUG : existing: size = 8 (Local file system at /tmp/rclone2406082256)
2026/10/10 03:16:28 DEBUG : existing: size = 6 (Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate')
2026/10/10 03:16:28 DEBUG : existing: Sizes differ
2026/10/10 03:16:28 ERROR : existing: Source and destination exist but do not match: immutable file modified
--- PASS: TestCopyFileImmutable (4.91s)
=== RUN   TestCopyLongFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
    copy_test.go:188: Test only runs on local
--- SKIP: TestCopyLongFile (0.52s)
=== RUN   TestCopyFileBackupDir
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:16:33 DEBUG : dst/file1: md5 = 4d6eb4de4d435a7b17f1b937b24f419d OK
2026/10/10 03:16:33 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jidazuy5pate/backup"
2026/10/10 03:16:33 DEBUG : Creating backend with remote "TestDrive:crypt/dk13k2is7heuf9fqbv1qce710vcbjsoohqk6qu3gd00cahoe8f9g/1nrff024r7pq65ecp72fc28jb0"
2026/10/10 03:16:35 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone2406082256)
2026/10/10 03:16:35 DEBUG : dst/file1: size = 18 (Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate')
2026/10/10 03:16:35 DEBUG : dst/file1: Sizes differ
2026/10/10 03:16:38 INFO  : dst/file1: Moved (server-side)
2026/10/10 03:16:39 DEBUG : dst/file1: md5 = bef2ad56066d86320bf8d7c2415f088b OK
2026/10/10 03:16:39 DEBUG : dst/file1: size = 14 OK
2026/10/10 03:16:39 INFO  : dst/file1: Copied (new)
--- PASS: TestCopyFileBackupDir (14.28s)
=== RUN   TestCopyFileCompareDest
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:16:45 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jidazuy5pate/dst"
2026/10/10 03:16:45 DEBUG : Creating backend with remote "TestDrive:crypt/dk13k2is7heuf9fqbv1qce710vcbjsoohqk6qu3gd00cahoe8f9g/31u3jie661vd5p8j7rtc3hgbh0"
2026/10/10 03:16:47 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jidazuy5pate/CompareDest"
2026/10/10 03:16:47 DEBUG : Creating backend with remote "TestDrive:crypt/dk13k2is7heuf9fqbv1qce710vcbjsoohqk6qu3gd00cahoe8f9g/gveqi14airsml4bgu7krj116o8"
2026/10/10 03:16:48 DEBUG : one: Need to transfer - File not found at Destination
2026/10/10 03:16:51 DEBUG : one: md5 = 53e1f10f62f163d7dc0d8d8cf5d8c871 OK
2026/10/10 03:16:51 DEBUG : one: size = 3 OK
2026/10/10 03:16:51 INFO  : one: Copied (new)
2026/10/10 03:16:52 DEBUG : one: size = 5 (Local file system at /tmp/rclone2406082256)
2026/10/10 03:16:52 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate/dst')
2026/10/10 03:16:52 DEBUG : one: Sizes differ
2026/10/10 03:16:54 DEBUG : one: md5 = b6102b4fd15ee8618421f3ed3b704be2 OK
2026/10/10 03:16:54 DEBUG : one: size = 5 OK
2026/10/10 03:16:54 INFO  : one: Copied (replaced existing)
2026/10/10 03:16:56 DEBUG : dst/one: md5 = defcffec04fa3e9a08681be9a4e97f3f OK
2026/10/10 03:16:58 DEBUG : CompareDest/one: md5 = 919a61ed8b0e4e37730e66d4b6f7ab01 OK
2026/10/10 03:16:59 DEBUG : one: size = 5 (Local file system at /tmp/rclone2406082256)
2026/10/10 03:16:59 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate/dst')
2026/10/10 03:16:59 DEBUG : one: Sizes differ
2026/10/10 03:17:00 DEBUG : one: size = 5 OK
2026/10/10 03:17:00 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/10/10 03:17:00 DEBUG : one: Destination found in --compare-dest, skipping
2026/10/10 03:17:02 DEBUG : CompareDest/two: md5 = 4a59df8e7dd3d9ec1a10b6716a34cc34 OK
2026/10/10 03:17:02 DEBUG : two: Need to transfer - File not found at Destination
2026/10/10 03:17:03 DEBUG : two: size = 3 OK
2026/10/10 03:17:03 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/10/10 03:17:03 DEBUG : two: Destination found in --compare-dest, skipping
2026/10/10 03:17:03 DEBUG : two: Need to transfer - File not found at Destination
2026/10/10 03:17:04 DEBUG : two: size = 3 OK
2026/10/10 03:17:04 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/10/10 03:17:04 DEBUG : two: Destination found in --compare-dest, skipping
2026/10/10 03:17:05 DEBUG : two: Need to transfer - File not found at Destination
2026/10/10 03:17:05 DEBUG : two: size = 5 (Local file system at /tmp/rclone2406082256)
2026/10/10 03:17:05 DEBUG : two: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate/CompareDest')
2026/10/10 03:17:05 DEBUG : two: Sizes differ
2026/10/10 03:17:07 DEBUG : two: md5 = 317f80b1084fec5d5be5b1257f1f1698 OK
2026/10/10 03:17:07 DEBUG : two: size = 5 OK
2026/10/10 03:17:07 INFO  : two: Copied (new)
--- PASS: TestCopyFileCompareDest (27.08s)
=== RUN   TestCopyFileCopyDestImmutable
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:17:12 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jidazuy5pate/dst"
2026/10/10 03:17:12 DEBUG : Config file has changed externally - reloading
2026/10/10 03:17:12 DEBUG : Creating backend with remote "TestDrive:crypt/dk13k2is7heuf9fqbv1qce710vcbjsoohqk6qu3gd00cahoe8f9g/31u3jie661vd5p8j7rtc3hgbh0"
2026/10/10 03:17:16 DEBUG : dst/one: md5 = 9b2ae408dae55006da85cf2c926904c3 OK
2026/10/10 03:17:19 DEBUG : CopyDest/one: md5 = 69d4906ecd8d0e81c81e725e3316b8d7 OK
2026/10/10 03:17:20 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jidazuy5pate/CopyDest"
2026/10/10 03:17:20 DEBUG : Creating backend with remote "TestDrive:crypt/dk13k2is7heuf9fqbv1qce710vcbjsoohqk6qu3gd00cahoe8f9g/d09o6po3f7bm6ce32vdgs8h9ls"
2026/10/10 03:17:21 DEBUG : one: size = 5 (Local file system at /tmp/rclone2406082256)
2026/10/10 03:17:21 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate/dst')
2026/10/10 03:17:21 DEBUG : one: Sizes differ
2026/10/10 03:17:21 DEBUG : one: size = 5 OK
2026/10/10 03:17:21 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/10/10 03:17:21 DEBUG : one: size = 5 (Local file system at /tmp/rclone2406082256)
2026/10/10 03:17:21 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate/dst')
2026/10/10 03:17:21 DEBUG : one: Sizes differ
2026/10/10 03:17:21 ERROR : one: Source and destination exist but do not match: immutable file modified
--- PASS: TestCopyFileCopyDestImmutable (13.15s)
=== RUN   TestCopyFileCopyDest
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:17:25 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jidazuy5pate/dst"
2026/10/10 03:17:25 DEBUG : Creating backend with remote "TestDrive:crypt/dk13k2is7heuf9fqbv1qce710vcbjsoohqk6qu3gd00cahoe8f9g/31u3jie661vd5p8j7rtc3hgbh0"
2026/10/10 03:17:27 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jidazuy5pate/CopyDest"
2026/10/10 03:17:27 DEBUG : Creating backend with remote "TestDrive:crypt/dk13k2is7heuf9fqbv1qce710vcbjsoohqk6qu3gd00cahoe8f9g/d09o6po3f7bm6ce32vdgs8h9ls"
2026/10/10 03:17:28 DEBUG : one: Need to transfer - File not found at Destination
2026/10/10 03:17:31 DEBUG : one: md5 = 0a04292661d3be05cf35e59b98f85893 OK
2026/10/10 03:17:31 DEBUG : one: size = 3 OK
2026/10/10 03:17:31 INFO  : one: Copied (new)
2026/10/10 03:17:32 DEBUG : one: size = 5 (Local file system at /tmp/rclone2406082256)
2026/10/10 03:17:32 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate/dst')
2026/10/10 03:17:32 DEBUG : one: Sizes differ
2026/10/10 03:17:35 DEBUG : one: md5 = 9b07753a6e1dd83a5e63081e521e2b2b OK
2026/10/10 03:17:35 DEBUG : one: size = 5 OK
2026/10/10 03:17:35 INFO  : one: Copied (replaced existing)
2026/10/10 03:17:37 DEBUG : dst/one: md5 = 52eafc64c93d3100a8856111889958c8 OK
2026/10/10 03:17:39 DEBUG : CopyDest/one: md5 = c7003113e297d07d98fe5f2c131a98c1 OK
2026/10/10 03:17:40 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jidazuy5pate/BackupDir"
2026/10/10 03:17:40 DEBUG : Creating backend with remote "TestDrive:crypt/dk13k2is7heuf9fqbv1qce710vcbjsoohqk6qu3gd00cahoe8f9g/s6dbk3lfi7c9kfvo6j7bla9m0g"
2026/10/10 03:17:42 DEBUG : one: size = 5 (Local file system at /tmp/rclone2406082256)
2026/10/10 03:17:42 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate/dst')
2026/10/10 03:17:42 DEBUG : one: Sizes differ
2026/10/10 03:17:43 DEBUG : one: size = 5 OK
2026/10/10 03:17:43 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/10/10 03:17:43 DEBUG : one: size = 5 (Local file system at /tmp/rclone2406082256)
2026/10/10 03:17:43 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate/dst')
2026/10/10 03:17:43 DEBUG : one: Sizes differ
2026/10/10 03:17:44 INFO  : one: Moved (server-side)
2026/10/10 03:17:45 DEBUG : one: size = 5 OK
2026/10/10 03:17:45 INFO  : one: Copied (server-side copy)
2026/10/10 03:17:45 DEBUG : one: Destination found in --copy-dest, using server-side copy
2026/10/10 03:17:48 DEBUG : CopyDest/two: md5 = 5cb01e3cf357f6580778c43f1de18229 OK
2026/10/10 03:17:48 DEBUG : two: Need to transfer - File not found at Destination
2026/10/10 03:17:49 DEBUG : two: size = 3 OK
2026/10/10 03:17:49 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/10/10 03:17:50 DEBUG : two: size = 3 OK
2026/10/10 03:17:50 INFO  : two: Copied (server-side copy)
2026/10/10 03:17:50 DEBUG : two: Destination found in --copy-dest, using server-side copy
2026/10/10 03:17:50 DEBUG : two: size = 3 OK
2026/10/10 03:17:50 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/10/10 03:17:50 DEBUG : two: Unchanged skipping
2026/10/10 03:17:52 DEBUG : CopyDest/three: md5 = 8cd6095e1b810f54a0ac132ae6c43bd6 OK
2026/10/10 03:17:54 DEBUG : three: Need to transfer - File not found at Destination
2026/10/10 03:17:54 DEBUG : three: size = 7 (Local file system at /tmp/rclone2406082256)
2026/10/10 03:17:54 DEBUG : three: size = 5 (Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate/CopyDest')
2026/10/10 03:17:54 DEBUG : three: Sizes differ
2026/10/10 03:17:54 DEBUG : three: Destination not found in --copy-dest
2026/10/10 03:17:55 DEBUG : three: md5 = 844429b99390e6b69bd197ebbfa9f491 OK
2026/10/10 03:17:55 DEBUG : three: size = 7 OK
2026/10/10 03:17:55 INFO  : three: Copied (new)
--- PASS: TestCopyFileCopyDest (38.38s)
=== RUN   TestCopyInplace
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
    copy_test.go:434: Partial uploads not supported
--- SKIP: TestCopyInplace (0.56s)
=== RUN   TestCopyLongFileName
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", 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-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
    copy_test.go:500: Partial uploads not supported
--- SKIP: TestCopyLongFileNameCollision (0.53s)
=== RUN   TestCopyFileMaxTransfer
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:18:05 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination
2026/10/10 03:18:07 DEBUG : TestCopyFileMaxTransfer/file1: md5 = 233fbf18b3ad0de2ee5e15b0b21330c8 OK
2026/10/10 03:18:07 DEBUG : TestCopyFileMaxTransfer/file1: size = 14 OK
2026/10/10 03:18:07 INFO  : TestCopyFileMaxTransfer/file1: Copied (new)
2026/10/10 03:18:08 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination
2026/10/10 03:18:08 ERROR : TestCopyFileMaxTransfer/file2: Failed to copy: Post "https://www.googleapis.com/upload/drive/v3/files?alt=json&fields=id%2Cname%2Csize%2Cmd5Checksum%2Csha1Checksum%2Csha256Checksum%2Ctrashed%2CexplicitlyTrashed%2CmodifiedTime%2CcreatedTime%2CmimeType%2Cparents%2CwebViewLink%2CshortcutDetails%2CexportLinks%2CresourceKey&keepRevisionForever=false&prettyPrint=false&supportsAllDrives=true&uploadType=multipart": googleapi: Copy failed: max transfer limit reached as set by --max-transfer
    copy_test.go:563: Expecting error to contain accounting.ErrorMaxTransferLimitReachedFatal
2026/10/10 03:18:09 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination
2026/10/10 03:18:10 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination
2026/10/10 03:18:11 DEBUG : TestCopyFileMaxTransfer/file4: md5 = a6054c6c2138a8e95d619f1e0a5b4893 OK
2026/10/10 03:18:11 DEBUG : TestCopyFileMaxTransfer/file4: size = 2062 OK
2026/10/10 03:18:11 INFO  : TestCopyFileMaxTransfer/file4: Copied (new)
--- PASS: TestCopyFileMaxTransfer (9.39s)
=== RUN   TestDeduplicateInteractive
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
    dedupe_test.go:37: Can't run this test without a hash
--- SKIP: TestDeduplicateInteractive (0.56s)
=== RUN   TestDeduplicateSkip
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:18:17 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate': Looking for duplicate names using skip mode.
2026/10/10 03:18:18 NOTICE: one: Found 2 files with duplicate names
2026/10/10 03:18:18 NOTICE: one: Skipping 2 files with duplicate names
--- PASS: TestDeduplicateSkip (4.69s)
=== RUN   TestDeduplicateSizeOnly
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:18:23 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate': Looking for duplicate names using skip mode.
2026/10/10 03:18:26 NOTICE: one: Found 3 files with duplicate names
2026/10/10 03:18:26 NOTICE: one: Deleting 1/2 identical duplicates (size 11)
2026/10/10 03:18:26 INFO  : one: Deleted
2026/10/10 03:18:26 NOTICE: one: Skipping 2 files with duplicate names
--- PASS: TestDeduplicateSizeOnly (8.84s)
=== RUN   TestDeduplicateFirst
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:18:32 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate': Looking for duplicate names using first mode.
2026/10/10 03:18:33 NOTICE: one: Found 3 files with duplicate names
2026/10/10 03:18:33 INFO  : one: Deleted
2026/10/10 03:18:34 INFO  : one: Deleted
2026/10/10 03:18:34 NOTICE: one: Deleted 2 extra copies
--- PASS: TestDeduplicateFirst (6.75s)
=== RUN   TestDeduplicateNewest
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:18:39 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate': Looking for duplicate names using newest mode.
2026/10/10 03:18:40 NOTICE: one: Found 3 files with duplicate names
2026/10/10 03:18:40 INFO  : one: Deleted
2026/10/10 03:18:41 INFO  : one: Deleted
2026/10/10 03:18:41 NOTICE: one: Deleted 2 extra copies
--- PASS: TestDeduplicateNewest (7.08s)
=== RUN   TestDeduplicateNewestByHash
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
    dedupe_test.go:37: Can't run this test without a hash
--- SKIP: TestDeduplicateNewestByHash (0.50s)
=== RUN   TestDeduplicateOldest
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:18:46 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate': Looking for duplicate names using oldest mode.
2026/10/10 03:18:47 NOTICE: one: Found 3 files with duplicate names
2026/10/10 03:18:47 INFO  : one: Deleted
2026/10/10 03:18:48 INFO  : one: Deleted
2026/10/10 03:18:48 NOTICE: one: Deleted 2 extra copies
--- PASS: TestDeduplicateOldest (6.47s)
=== RUN   TestDeduplicateLargest
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:18:53 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate': Looking for duplicate names using largest mode.
2026/10/10 03:18:54 NOTICE: one: Found 3 files with duplicate names
2026/10/10 03:18:54 INFO  : one: Deleted
2026/10/10 03:18:55 INFO  : one: Deleted
2026/10/10 03:18:55 NOTICE: one: Deleted 2 extra copies
--- PASS: TestDeduplicateLargest (6.90s)
=== RUN   TestDeduplicateSmallest
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:19:00 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate': Looking for duplicate names using smallest mode.
2026/10/10 03:19:00 NOTICE: one: Found 3 files with duplicate names
2026/10/10 03:19:01 INFO  : one: Deleted
2026/10/10 03:19:01 INFO  : one: Deleted
2026/10/10 03:19:01 NOTICE: one: Deleted 2 extra copies
--- PASS: TestDeduplicateSmallest (6.80s)
=== RUN   TestDeduplicateRename
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:19:08 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate': Looking for duplicate names using rename mode.
2026/10/10 03:19:09 NOTICE: one.txt: Found 3 files with duplicate names
2026/10/10 03:19:09 INFO  : one-2.txt: renamed from: one.txt
2026/10/10 03:19:10 INFO  : one-3.txt: renamed from: one.txt
2026/10/10 03:19:11 INFO  : one-4.txt: renamed from: one.txt
--- PASS: TestDeduplicateRename (10.45s)
=== RUN   TestDeduplicateRenameManyExisting
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:19:15 DEBUG : one-1.txt: md5 = 83cfb781a2f6be2ae38ba0b97b759fec OK
2026/10/10 03:19:16 DEBUG : one-2.txt: md5 = 35f9fb517e58ceb498855b9f6a401368 OK
2026/10/10 03:19:17 DEBUG : one-3.txt: md5 = 2537f72e24e7179fb045e709dd79023b OK
2026/10/10 03:19:19 DEBUG : one-4.txt: md5 = ebae23ac44e127bbf66167414c863408 OK
2026/10/10 03:19:20 DEBUG : one-5.txt: md5 = 90b930697c3f66e07a5cff9bd331615f OK
2026/10/10 03:19:22 DEBUG : one-6.txt: md5 = 8a53510263735900b6b004ed9d9ec429 OK
2026/10/10 03:19:24 DEBUG : one-7.txt: md5 = 690fd5da81c4599c659f91641b675145 OK
2026/10/10 03:19:25 DEBUG : one-8.txt: md5 = b9252a25eace2c1eee0e8607fd8cec9b OK
2026/10/10 03:19:26 DEBUG : one-9.txt: md5 = 4cb4e11982436604d80e7e1aa8fbe8c9 OK
2026/10/10 03:19:28 DEBUG : one-10.txt: md5 = fbdce46c86bd3c183e7ad1971818377c OK
2026/10/10 03:19:29 DEBUG : one-11.txt: md5 = 31e6fe201092f2e7d83a774fd725ab7e OK
2026/10/10 03:19:31 DEBUG : one-12.txt: md5 = 8b23854cec1770f879b205940b275fbf OK
2026/10/10 03:19:32 DEBUG : one-13.txt: md5 = a3e4e41299972771b2a1effe27598b2e OK
2026/10/10 03:19:34 DEBUG : one-14.txt: md5 = b8607319db13a015daf20fb1dc45970f OK
2026/10/10 03:19:35 DEBUG : one-15.txt: md5 = 4f9860bb13d27b4a9d549e877f56ff4a OK
2026/10/10 03:19:37 DEBUG : one-16.txt: md5 = e7c44aab437b2080b404cdf56725d740 OK
2026/10/10 03:19:38 DEBUG : one-17.txt: md5 = 37acdcee4d5bb6987d53bc05a1c11930 OK
2026/10/10 03:19:40 DEBUG : one-18.txt: md5 = edccd70463e48ae145bc4994c6aeb75a OK
2026/10/10 03:19:41 DEBUG : one-19.txt: md5 = 28d7ea86bd03027ed4ab5121e40965da OK
2026/10/10 03:19:43 DEBUG : one-20.txt: md5 = 404dc779197e0e60f68789d3357649a5 OK
2026/10/10 03:19:44 DEBUG : one-21.txt: md5 = 694ee56adba87094059b35db73da109d OK
2026/10/10 03:19:46 DEBUG : one-22.txt: md5 = c0b8f6737395895eda4114b34c490bdb OK
2026/10/10 03:19:48 DEBUG : one-23.txt: md5 = 98c63aace497299fddc2fd74615f9a85 OK
2026/10/10 03:19:49 DEBUG : one-24.txt: md5 = d775acae1517e79195ede065667830d1 OK
2026/10/10 03:19:51 DEBUG : one-25.txt: md5 = ec7762540bf6eda2175910e76acfc126 OK
2026/10/10 03:19:52 DEBUG : one-26.txt: md5 = 96cd574157b75ce0e10a02bbe98ef624 OK
2026/10/10 03:19:54 DEBUG : one-27.txt: md5 = e001dd510a0dd8c28f3ea747e6d8bcae OK
2026/10/10 03:19:55 DEBUG : one-28.txt: md5 = 184035a76b492aafa57ecc1792a27d29 OK
2026/10/10 03:19:57 DEBUG : one-29.txt: md5 = c63c2e0e0f7e6bbc579b913dd1530858 OK
2026/10/10 03:19:58 DEBUG : one-30.txt: md5 = fffa882929886d3e7b2a9a6e57a84535 OK
2026/10/10 03:20:00 DEBUG : one-31.txt: md5 = ecb0917f4f6d58d4ac468f1f63a707c4 OK
2026/10/10 03:20:01 DEBUG : one-32.txt: md5 = 8795374444e11348db28fe2a89a2b2d0 OK
2026/10/10 03:20:03 DEBUG : one-33.txt: md5 = 92d58dea4592c8b21a21fcd6c3792564 OK
2026/10/10 03:20:04 DEBUG : one-34.txt: md5 = 81eab44008757104150367dc26cfd754 OK
2026/10/10 03:20:06 DEBUG : one-35.txt: md5 = e84ee2f4fd9fe6e26ec8d69b76bd7bf7 OK
2026/10/10 03:20:07 DEBUG : one-36.txt: md5 = a859031d61f8eddf02795b1471c96a97 OK
2026/10/10 03:20:09 DEBUG : one-37.txt: md5 = 9bc36b3a59d4b726d9d73cf09d0faaea OK
2026/10/10 03:20:10 DEBUG : one-38.txt: md5 = 4cc3059ebd3d3fc05d07cdb4d82734fa OK
2026/10/10 03:20:12 DEBUG : one-39.txt: md5 = 1c2307a114edb460dddafe4d8a1f5557 OK
2026/10/10 03:20:13 DEBUG : one-40.txt: md5 = 6da1b7e17989b4610f9312a725f089d8 OK
2026/10/10 03:20:15 DEBUG : one-41.txt: md5 = d47249ebf66e9e8032f4cc3df774f9b6 OK
2026/10/10 03:20:17 DEBUG : one-42.txt: md5 = d3cd09caee4656eb2ec79a8692a4a0fe OK
2026/10/10 03:20:18 DEBUG : one-43.txt: md5 = 3fee5e056fc8847dcdbe3799eba14842 OK
2026/10/10 03:20:20 DEBUG : one-44.txt: md5 = ced5f61d4f2905964d3a099e0b59d7bc OK
2026/10/10 03:20:21 DEBUG : one-45.txt: md5 = 1f1e1a60e252312fa6a34effea115651 OK
2026/10/10 03:20:23 DEBUG : one-46.txt: md5 = 558183a419834ace0f9a5cae7e7b46f2 OK
2026/10/10 03:20:24 DEBUG : one-47.txt: md5 = c3d3cd62c27b1a9cc655444dad28d5c5 OK
2026/10/10 03:20:26 DEBUG : one-48.txt: md5 = 603cf489db7fb465b8cb5c9ce6e5d913 OK
2026/10/10 03:20:27 DEBUG : one-49.txt: md5 = ad92c01e000c91fcd44d9ac221fd59be OK
2026/10/10 03:20:29 DEBUG : one-50.txt: md5 = 4b264e53c57ff5917f5f3d4c8b6043f9 OK
2026/10/10 03:20:30 DEBUG : one-51.txt: md5 = 57f5c49dd8ac4c1714b19c788567dcfe OK
2026/10/10 03:20:32 DEBUG : one-52.txt: md5 = 7cc76d697aac1be0fc865f3b45acc0ec OK
2026/10/10 03:20:33 DEBUG : one-53.txt: md5 = 618a4f5ab84b32781b43ba3559ed5595 OK
2026/10/10 03:20:35 DEBUG : one-54.txt: md5 = 3fe1f31d644cb1b01373c42a31719ed9 OK
2026/10/10 03:20:36 DEBUG : one-55.txt: md5 = d7c84dedf438dd04b0908f9a769d4249 OK
2026/10/10 03:20:38 DEBUG : one-56.txt: md5 = 2a6203f6d7d087b02ecf5132afb4bdff OK
2026/10/10 03:20:39 DEBUG : one-57.txt: md5 = 32c335f5725a8d1227d090c29c63abfa OK
2026/10/10 03:20:41 DEBUG : one-58.txt: md5 = 1fc75b9a6a6f19c9f26afe89ea5fc213 OK
2026/10/10 03:20:42 DEBUG : one-59.txt: md5 = 08d22ef87bfbcbb63d3f092976cef458 OK
2026/10/10 03:20:44 DEBUG : one-60.txt: md5 = a2b94d52f791e733f744ff9c7fd8626d OK
2026/10/10 03:20:45 DEBUG : one-61.txt: md5 = 11180422e43a510b9a5d80b14275fd5a OK
2026/10/10 03:20:47 DEBUG : one-62.txt: md5 = 3904779f7934e5e3baec6b2e6512e415 OK
2026/10/10 03:20:48 DEBUG : one-63.txt: md5 = 55f8edd9e1d35e16cc13cb3e42cc91ea OK
2026/10/10 03:20:50 DEBUG : one-64.txt: md5 = 33cfe70c4fafcfe8b38319e5592ef097 OK
2026/10/10 03:20:52 DEBUG : one-65.txt: md5 = 554a29a37d34479390cf981b5318248b OK
2026/10/10 03:20:53 DEBUG : one-66.txt: md5 = 777f3af7c30fda9f8eb392a8af23b041 OK
2026/10/10 03:20:54 DEBUG : one-67.txt: md5 = fa489bc5d4442561a4893b7100ad0053 OK
2026/10/10 03:20:56 DEBUG : one-68.txt: md5 = 4cabd760a8d650bf45d90250a9fd348a OK
2026/10/10 03:20:58 DEBUG : one-69.txt: md5 = 16a370192c78216aa0cb6c240572f3cf OK
2026/10/10 03:20:59 DEBUG : one-70.txt: md5 = c81e483565d6e098c9c5a9938b8205c0 OK
2026/10/10 03:21:01 DEBUG : one-71.txt: md5 = e44ced12cb12da2bba2518591fc34034 OK
2026/10/10 03:21:02 DEBUG : one-72.txt: md5 = 794ebc8576bc9cd35012c14bec9ba080 OK
2026/10/10 03:21:04 DEBUG : one-73.txt: md5 = 229dafd0113d72e57ce7f54ddadcf134 OK
2026/10/10 03:21:05 DEBUG : one-74.txt: md5 = cd5c47e208493c1f1ace2170c028e06f OK
2026/10/10 03:21:07 DEBUG : one-75.txt: md5 = 4e4f253c1fe0a9a99dad0ff9310a2b80 OK
2026/10/10 03:21:08 DEBUG : one-76.txt: md5 = 1f8c8f9c4b1eb910a50cdecab7be73ab OK
2026/10/10 03:21:10 DEBUG : one-77.txt: md5 = 8f9a39cef39beee079a562c7ee827515 OK
2026/10/10 03:21:11 DEBUG : one-78.txt: md5 = 4b35d7bd06911ff50d4ff4de864bdcd1 OK
2026/10/10 03:21:13 DEBUG : one-79.txt: md5 = 2c49c7e141d18a522494f81bb8e0e273 OK
2026/10/10 03:21:14 DEBUG : one-80.txt: md5 = 7a01b2d3a0bfe0d3f1bd5636913f956e OK
2026/10/10 03:21:16 DEBUG : one-81.txt: md5 = 0cf49a7cd31fc264c83b8036dd142b87 OK
2026/10/10 03:21:17 DEBUG : one-82.txt: md5 = 1f624e467c7c7b0fc03648035bffa923 OK
2026/10/10 03:21:19 DEBUG : one-83.txt: md5 = 05f25dfaf122430a443c7036e486daa1 OK
2026/10/10 03:21:20 DEBUG : one-84.txt: md5 = 4a1d41b8cf5f2d8636965f55a7d8c6fa OK
2026/10/10 03:21:21 DEBUG : one-85.txt: md5 = 32c288a2276fa99b6ede0874bc43de3e OK
2026/10/10 03:21:23 DEBUG : one-86.txt: md5 = 43c30d9a306452611d41f57182ea7b04 OK
2026/10/10 03:21:24 DEBUG : one-87.txt: md5 = 35b171a9081b13895088351a50883bee OK
2026/10/10 03:21:26 DEBUG : one-88.txt: md5 = bf70fc8a55c2c3b5378c5ae3b853f1cd OK
2026/10/10 03:21:27 DEBUG : one-89.txt: md5 = a4ff9c89e4c8afb2fdff838f425af8a2 OK
2026/10/10 03:21:29 DEBUG : one-90.txt: md5 = e37bda0a47efbba0643194e075735ba8 OK
2026/10/10 03:21:30 DEBUG : one-91.txt: md5 = cf290bd3bb95c22e437feb51a40aff0b OK
2026/10/10 03:21:30 DEBUG : TestDrive: Token expired
2026/10/10 03:21:30 DEBUG : Config file has changed externally - reloading
2026/10/10 03:21:30 DEBUG : TestDrive: Loaded fresh token from config file
2026/10/10 03:21:30 DEBUG : TestDrive: Token refresh successful
2026/10/10 03:21:32 DEBUG : one-92.txt: md5 = 120b3274b4e7759de74b57f2e195ed23 OK
2026/10/10 03:21:33 DEBUG : one-93.txt: md5 = 9fdc0548f4ee89f9ad936f03c436208b OK
2026/10/10 03:21:35 DEBUG : one-94.txt: md5 = 5f1741b19fadfa509ccfaccbe0b5ed13 OK
2026/10/10 03:21:36 DEBUG : one-95.txt: md5 = e0f37a9eefc418c878ceec826b24f558 OK
2026/10/10 03:21:38 DEBUG : one-96.txt: md5 = a7db800b5fe604c7c8d72cc63d8bd66b OK
2026/10/10 03:21:39 DEBUG : one-97.txt: md5 = aab9b203fee9d609e6c2cc9a602dcde4 OK
2026/10/10 03:21:40 DEBUG : one-98.txt: md5 = efe9312878e9e32a3e3ec3567f35fdb1 OK
2026/10/10 03:21:42 DEBUG : one-99.txt: md5 = c26a207342b3ed18186897711c2b97eb OK
2026/10/10 03:21:43 DEBUG : one-100.txt: md5 = a965914eedc8e15fa71ade981917551f OK
2026/10/10 03:21:45 DEBUG : one-101.txt: md5 = a6de442b3e216a6f929fe887e21c4e66 OK
2026/10/10 03:21:46 DEBUG : one-102.txt: md5 = 3380d3a779f18aa353e8e035b60be3de OK
2026/10/10 03:21:48 DEBUG : one-103.txt: md5 = 3ac3838cd256073a98d569d5c3dc4ceb OK
2026/10/10 03:21:49 DEBUG : one-104.txt: md5 = 5ec70c095774b787c3d59383aaeed042 OK
2026/10/10 03:21:50 DEBUG : one-105.txt: md5 = f1f51b12621f8560411be014ea6fa1ee OK
2026/10/10 03:21:53 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate': Looking for duplicate names using rename mode.
2026/10/10 03:21:54 NOTICE: one.txt: Found 2 files with duplicate names
2026/10/10 03:21:55 INFO  : one-106.txt: renamed from: one.txt
2026/10/10 03:21:55 INFO  : one-107.txt: renamed from: one.txt
--- PASS: TestDeduplicateRenameManyExisting (210.78s)
=== RUN   TestMergeDirs
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:22:46 DEBUG : dupe1/one.txt: md5 = 4026d6a85c1b1c43c0766759d6482fd9 OK
2026/10/10 03:22:48 DEBUG : dupe2/two.txt: md5 = f5cef2f368b93d0ad542b8622ab88696 OK
2026/10/10 03:22:51 DEBUG : dupe3/three.txt: md5 = 016819fd46b6372558264ce15aed04db OK
2026/10/10 03:22:51 INFO  : urj8gsducqbtvekeq181ntd2u8: merging "sfrom47189m9qr3mt4qonu281c"
2026/10/10 03:22:52 INFO  : urj8gsducqbtvekeq181ntd2u8: removing empty directory
2026/10/10 03:22:52 INFO  : 1u1r4ei7c628fnjg9blqt3j60o: merging "tr2hj63d80ftlmvm6a952snjcc"
2026/10/10 03:22:53 INFO  : 1u1r4ei7c628fnjg9blqt3j60o: removing empty directory
--- PASS: TestMergeDirs (13.06s)
=== RUN   TestIndex
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:22:59 DEBUG : file1.txt: md5 = c72083f71bddf709722a58c6931781fe OK
2026/10/10 03:23:01 DEBUG : sub/file2.txt: md5 = 0ffb00e29db0e0715109222becb9db5e OK
2026/10/10 03:23:03 DEBUG : sub/deep/file3.txt: md5 = 2aeb34ba17f85ceaf2a201b2e2788bff OK
2026/10/10 03:23:05 DEBUG : index.html: md5 = 58619c6e64c6d071e9b2ceb1b9ad30ce OK
2026/10/10 03:23:05 DEBUG : index.html: size = 12147 OK
2026/10/10 03:23:05 INFO  : index.html: Copied (new)
2026/10/10 03:23:05 DEBUG : sub/deep/index.html: md5 = bb2aea941cc8c0164c459176de26f027 OK
2026/10/10 03:23:05 DEBUG : sub/deep/index.html: size = 12087 OK
2026/10/10 03:23:05 INFO  : sub/deep/index.html: Copied (new)
2026/10/10 03:23:05 DEBUG : sub/index.html: md5 = 582221ada8cd925a85418489292ce75e OK
2026/10/10 03:23:05 DEBUG : sub/index.html: size = 12306 OK
2026/10/10 03:23:05 INFO  : sub/index.html: Copied (new)
2026/10/10 03:23:05 DEBUG : sub/index.json: md5 = cecd9433825a71559bd661579b09d614 OK
2026/10/10 03:23:05 DEBUG : sub/index.json: size = 215 OK
2026/10/10 03:23:05 INFO  : sub/index.json: Copied (new)
2026/10/10 03:23:06 DEBUG : index.json: md5 = 002b1408ea890d7e4a21b29ce8c42b87 OK
2026/10/10 03:23:06 DEBUG : index.json: size = 213 OK
2026/10/10 03:23:06 INFO  : index.json: Copied (new)
2026/10/10 03:23:06 DEBUG : caddy.json: md5 = 1a472d2bb7c059d5e1db4b4f53bc6110 OK
2026/10/10 03:23:06 DEBUG : caddy.json: size = 259 OK
2026/10/10 03:23:06 INFO  : caddy.json: Copied (new)
2026/10/10 03:23:06 DEBUG : sub/caddy.json: md5 = 5c0f748141bb5d59dced545cd501d1cd OK
2026/10/10 03:23:06 DEBUG : sub/caddy.json: size = 261 OK
2026/10/10 03:23:06 INFO  : sub/caddy.json: Copied (new)
2026/10/10 03:23:06 DEBUG : sub/deep/index.json: md5 = c9b16f561fa649a3a5116533d6a3073f OK
2026/10/10 03:23:06 DEBUG : sub/deep/index.json: size = 119 OK
2026/10/10 03:23:06 INFO  : sub/deep/index.json: Copied (new)
2026/10/10 03:23:08 DEBUG : sub/deep/caddy.json: md5 = a72e1f068b190ef7a0b3cd7b489a3526 OK
2026/10/10 03:23:08 DEBUG : sub/deep/caddy.json: size = 131 OK
2026/10/10 03:23:08 INFO  : sub/deep/caddy.json: Copied (new)
2026/10/10 03:23:13 DEBUG : index.html: Unchanged skipping
2026/10/10 03:23:13 DEBUG : sub/index.html: Unchanged skipping
2026/10/10 03:23:13 DEBUG : sub/deep/index.html: Unchanged skipping
2026/10/10 03:23:14 DEBUG : index.json: Unchanged skipping
2026/10/10 03:23:14 DEBUG : sub/deep/index.json: Unchanged skipping
2026/10/10 03:23:14 DEBUG : sub/index.json: Unchanged skipping
2026/10/10 03:23:14 DEBUG : caddy.json: Unchanged skipping
2026/10/10 03:23:15 DEBUG : sub/deep/caddy.json: Unchanged skipping
2026/10/10 03:23:15 DEBUG : sub/caddy.json: Unchanged skipping
2026/10/10 03:23:17 DEBUG : caddy.json: md5 = f0c39193cd4ccd8242eeed2471e423c9 OK
2026/10/10 03:23:17 DEBUG : caddy.json: size = 259 OK
2026/10/10 03:23:17 INFO  : caddy.json: Copied (replaced existing)
2026/10/10 03:23:17 DEBUG : index.html: md5 = b6f31c543c251c6033b1ce555b00f99c OK
2026/10/10 03:23:17 DEBUG : index.html: size = 12147 OK
2026/10/10 03:23:17 INFO  : index.html: Copied (replaced existing)
2026/10/10 03:23:17 DEBUG : index.json: md5 = aa46981628f6e1b2d86b378ea462d588 OK
2026/10/10 03:23:17 DEBUG : index.json: size = 213 OK
2026/10/10 03:23:17 INFO  : index.json: Copied (replaced existing)
2026/10/10 03:23:17 DEBUG : sub/deep/index.html: md5 = 897232fd39a684c2d2afac1a1e89c05b OK
2026/10/10 03:23:17 DEBUG : sub/deep/index.html: size = 12087 OK
2026/10/10 03:23:17 INFO  : sub/deep/index.html: Copied (replaced existing)
2026/10/10 03:23:18 DEBUG : sub/index.html: md5 = 9fae555a68847c02b1e0b39c896bba21 OK
2026/10/10 03:23:18 DEBUG : sub/index.html: size = 12306 OK
2026/10/10 03:23:18 INFO  : sub/index.html: Copied (replaced existing)
2026/10/10 03:23:18 DEBUG : sub/deep/index.json: md5 = 27c6b13c0e650beeb2774ddff7f66ee7 OK
2026/10/10 03:23:18 DEBUG : sub/deep/index.json: size = 119 OK
2026/10/10 03:23:18 INFO  : sub/deep/index.json: Copied (replaced existing)
2026/10/10 03:23:18 DEBUG : sub/deep/caddy.json: md5 = 2ef09fbf771c33decfab84e3b00627b5 OK
2026/10/10 03:23:18 DEBUG : sub/deep/caddy.json: size = 131 OK
2026/10/10 03:23:18 INFO  : sub/deep/caddy.json: Copied (replaced existing)
2026/10/10 03:23:19 DEBUG : sub/index.json: md5 = a831bd23924a982c02ea1d7f15e851fb OK
2026/10/10 03:23:19 DEBUG : sub/index.json: size = 215 OK
2026/10/10 03:23:19 INFO  : sub/index.json: Copied (replaced existing)
2026/10/10 03:23:19 DEBUG : sub/caddy.json: md5 = ba273164748e88abe3ea3ca8f1c31f11 OK
2026/10/10 03:23:19 DEBUG : sub/caddy.json: size = 261 OK
2026/10/10 03:23:19 INFO  : sub/caddy.json: Copied (replaced existing)
2026/10/10 03:23:20 DEBUG : sub/deep/file4.txt: md5 = a72b34c4157eb3e447d11fad4c72ee34 OK
2026/10/10 03:23:22 DEBUG : index.html: Unchanged skipping
2026/10/10 03:23:22 DEBUG : sub/index.html: Unchanged skipping
2026/10/10 03:23:22 DEBUG : sub/deep/caddy.json: md5 = 4367a0059482a71cc58797f1a6f4fada OK
2026/10/10 03:23:22 DEBUG : sub/deep/caddy.json: size = 260 OK
2026/10/10 03:23:22 INFO  : sub/deep/caddy.json: Copied (replaced existing)
2026/10/10 03:23:22 DEBUG : sub/deep/index.json: md5 = 6b453a378478dca54583ea9620885cd6 OK
2026/10/10 03:23:22 DEBUG : sub/deep/index.json: size = 235 OK
2026/10/10 03:23:22 INFO  : sub/deep/index.json: Copied (replaced existing)
2026/10/10 03:23:22 DEBUG : index.json: Unchanged skipping
2026/10/10 03:23:22 DEBUG : sub/deep/index.html: md5 = bb175abde6cde6db04bf971c634dfafa OK
2026/10/10 03:23:22 DEBUG : sub/deep/index.html: size = 12346 OK
2026/10/10 03:23:22 INFO  : sub/deep/index.html: Copied (replaced existing)
2026/10/10 03:23:22 DEBUG : sub/index.json: Unchanged skipping
2026/10/10 03:23:23 DEBUG : caddy.json: Unchanged skipping
2026/10/10 03:23:23 DEBUG : sub/caddy.json: Unchanged skipping
2026/10/10 03:23:26 DEBUG : sub/deep/file5.txt: md5 = 09a2dec66d59844ea2c8ae92620a05b1 OK
2026/10/10 03:23:26 NOTICE: sub/deep/index.html: Skipped copy as --dry-run is set (size 12.309Ki)
2026/10/10 03:23:26 NOTICE: sub/deep/caddy.json: Skipped copy as --dry-run is set (size 389)
2026/10/10 03:23:26 NOTICE: sub/deep/index.json: Skipped copy as --dry-run is set (size 351)
2026/10/10 03:23:27 DEBUG : sub/index.html: Unchanged skipping
2026/10/10 03:23:27 DEBUG : index.html: Unchanged skipping
2026/10/10 03:23:27 DEBUG : sub/index.json: Unchanged skipping
2026/10/10 03:23:27 DEBUG : index.json: Unchanged skipping
2026/10/10 03:23:28 DEBUG : sub/caddy.json: Unchanged skipping
2026/10/10 03:23:28 DEBUG : caddy.json: Unchanged skipping
--- PASS: TestIndex (40.56s)
=== RUN   TestIndexDelete
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:23:39 DEBUG : file1.txt: md5 = 55016f53a10b2749097edbe4be94b650 OK
2026/10/10 03:23:41 DEBUG : sub/file2.txt: md5 = e2a5f415c6938310f4e0e87e898b45f6 OK
2026/10/10 03:23:43 DEBUG : sub/deep/file3.txt: md5 = a437d7dfc2b7558b0bf7a1b6d6bb3d9e OK
2026/10/10 03:23:46 DEBUG : index.html: md5 = d8bd98fe23181ed87186ce27d1600f46 OK
2026/10/10 03:23:46 DEBUG : index.html: size = 12147 OK
2026/10/10 03:23:46 INFO  : index.html: Copied (new)
2026/10/10 03:23:46 DEBUG : sub/deep/index.html: md5 = b95f98647b39e4067b1e868ca2df53e3 OK
2026/10/10 03:23:46 DEBUG : sub/deep/index.html: size = 12087 OK
2026/10/10 03:23:46 INFO  : sub/deep/index.html: Copied (new)
2026/10/10 03:23:46 DEBUG : sub/index.html: md5 = 3bb04edd376377ae628dfcf85192575b OK
2026/10/10 03:23:46 DEBUG : sub/index.html: size = 12306 OK
2026/10/10 03:23:46 INFO  : sub/index.html: Copied (new)
2026/10/10 03:23:48 DEBUG : sub/index.html: Unchanged skipping
2026/10/10 03:23:48 DEBUG : index.html: Unchanged skipping
2026/10/10 03:23:49 DEBUG : sub/deep/index.html: md5 = 157183468f259c9b9012c09ccb316f10 OK
2026/10/10 03:23:49 DEBUG : sub/deep/index.html: size = 11829 OK
2026/10/10 03:23:49 INFO  : sub/deep/index.html: Copied (replaced existing)
--- PASS: TestIndexDelete (18.17s)
=== RUN   TestIndexFilter
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:23:57 DEBUG : file1.txt: md5 = d4fcc906156f014249e635214bdb2320 OK
2026/10/10 03:23:59 DEBUG : sub/file2.txt: md5 = 362ba182eb53156eb8fd7d1a5d6da6c8 OK
2026/10/10 03:24:01 DEBUG : sub/deep/file3.txt: md5 = ed3717687e3baca1183fe537a944a3b2 OK
2026/10/10 03:24:03 DEBUG : sub/deep/index.html: md5 = 6856f754c8da5bb6b66b433b2d63b7e9 OK
2026/10/10 03:24:05 DEBUG : other/notes.md: md5 = 9206a24d7b3199ba579d9347b4acb36a OK
2026/10/10 03:24:05 DEBUG : other/notes.md: Excluded (Path Filter)
2026/10/10 03:24:07 DEBUG : sub/index.html: md5 = 156602122c056bf7fe4698e4e6278cc2 OK
2026/10/10 03:24:07 DEBUG : sub/index.html: size = 12056 OK
2026/10/10 03:24:07 INFO  : sub/index.html: Copied (new)
2026/10/10 03:24:07 DEBUG : other/index.html: md5 = 88f59f561287b4731833525b9b79fd28 OK
2026/10/10 03:24:07 DEBUG : other/index.html: size = 11802 OK
2026/10/10 03:24:07 INFO  : other/index.html: Copied (new)
2026/10/10 03:24:08 DEBUG : index.html: md5 = df3ebd313c39632d5dedf8c385eeb12c OK
2026/10/10 03:24:08 DEBUG : index.html: size = 12399 OK
2026/10/10 03:24:08 INFO  : index.html: Copied (new)
2026/10/10 03:24:12 DEBUG : other/notes.md: Excluded (Path Filter)
2026/10/10 03:24:13 DEBUG : sub/index.html: Unchanged skipping
2026/10/10 03:24:13 DEBUG : index.html: Unchanged skipping
2026/10/10 03:24:13 DEBUG : other/index.html: Unchanged skipping
--- PASS: TestIndexFilter (23.96s)
=== RUN   TestIndexRules
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:24:21 DEBUG : file1.txt: md5 = 0c253f7068e9ce8fcbb288e1ca4a7dcd OK
2026/10/10 03:24:23 DEBUG : sub/file2.txt: md5 = 7f9c4ccee469e28a83ff8b72223b0a7e OK
2026/10/10 03:24:27 DEBUG : sub/deep/file3.txt: md5 = cd0c58a3793dad981b5482810ac60673 OK
2026/10/10 03:24:28 DEBUG : sub/deep/index.html: md5 = 48ca8bc2ae3d16938b025114f033a222 OK
2026/10/10 03:24:31 DEBUG : index.html: md5 = cbaeecbd4237db3ad488a476d76b4aec OK
2026/10/10 03:24:31 DEBUG : index.html: size = 12147 OK
2026/10/10 03:24:31 INFO  : index.html: Copied (new)
2026/10/10 03:24:31 DEBUG : sub/index.html: md5 = 0f4cb740dbb3f4411405acb3495330a6 OK
2026/10/10 03:24:31 DEBUG : sub/index.html: size = 12306 OK
2026/10/10 03:24:31 INFO  : sub/index.html: Copied (new)
2026/10/10 03:24:35 DEBUG : sub/index.html: Unchanged skipping
2026/10/10 03:24:36 DEBUG : sub/deep/index.html: md5 = 00bb4dfb65e8eaff4952aec396c66876 OK
2026/10/10 03:24:36 DEBUG : sub/deep/index.html: size = 12087 OK
2026/10/10 03:24:36 INFO  : sub/deep/index.html: Copied (replaced existing)
--- PASS: TestIndexRules (22.76s)
=== RUN   TestIndexMaxDepth
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:24:44 DEBUG : file1.txt: md5 = ba13450ff0c3f95c0a9207b927574511 OK
2026/10/10 03:24:46 DEBUG : sub/file2.txt: md5 = 7215411f91f985d7e43097136e521c09 OK
2026/10/10 03:24:50 DEBUG : sub/deep/file3.txt: md5 = 1a9496c4ed99bcb2ece13d7186ce82af OK
2026/10/10 03:24:52 DEBUG : index.html: md5 = 9d646e2286eac1949788e3076eff6f3a OK
2026/10/10 03:24:52 DEBUG : index.html: size = 12147 OK
2026/10/10 03:24:52 INFO  : index.html: Copied (new)
--- PASS: TestIndexMaxDepth (16.57s)
=== RUN   TestIndexLinkIndex
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:25:01 DEBUG : file1.txt: md5 = 0e4f2923098b83391ef6f0c337a91aa0 OK
2026/10/10 03:25:03 DEBUG : sub/file2.txt: md5 = 5a12cbfd9ff999b211d61fddd58aebe3 OK
2026/10/10 03:25:05 DEBUG : sub/deep/file3.txt: md5 = 5e876bd60f033ffb08f5003600ac27bd OK
2026/10/10 03:25:07 DEBUG : sub/deep/index.html: md5 = 9381ec4d0604ea5504b738d7efe0e5ce OK
2026/10/10 03:25:07 DEBUG : sub/deep/index.html: size = 12128 OK
2026/10/10 03:25:07 INFO  : sub/deep/index.html: Copied (new)
2026/10/10 03:25:07 DEBUG : sub/index.html: md5 = 624dec8a237c88ed14c18bf34a1f712f OK
2026/10/10 03:25:07 DEBUG : sub/index.html: size = 12347 OK
2026/10/10 03:25:07 INFO  : sub/index.html: Copied (new)
2026/10/10 03:25:08 DEBUG : index.html: md5 = 0c928f5c7d93d773ecb43483049ddff9 OK
2026/10/10 03:25:08 DEBUG : index.html: size = 12167 OK
2026/10/10 03:25:08 INFO  : index.html: Copied (new)
--- PASS: TestIndexLinkIndex (15.25s)
=== RUN   TestIndexDirTime
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:25:16 DEBUG : file1.txt: md5 = f9d2e6af6cd13306daaa6e54ffcafb9b OK
2026/10/10 03:25:18 DEBUG : sub/file2.txt: md5 = ca1bee89ff5aa3c0ee18b35bd93d36c6 OK
2026/10/10 03:25:20 DEBUG : sub/deep/file3.txt: md5 = 9679cc8da5475b3572618221e2435303 OK
2026/10/10 03:25:22 DEBUG : index.json: md5 = 312e2dd4ac8c275c1fd6f507f4479344 OK
2026/10/10 03:25:22 DEBUG : index.json: size = 257 OK
2026/10/10 03:25:22 INFO  : index.json: Copied (new)
2026/10/10 03:25:22 DEBUG : sub/deep/index.json: md5 = 6317f42144d2ebd8510951ec0565ac3b OK
2026/10/10 03:25:22 DEBUG : sub/deep/index.json: size = 139 OK
2026/10/10 03:25:22 INFO  : sub/deep/index.json: Copied (new)
2026/10/10 03:25:22 DEBUG : sub/index.json: md5 = de2c730633c78a1924871bab37e44f4f OK
2026/10/10 03:25:22 DEBUG : sub/index.json: size = 259 OK
2026/10/10 03:25:22 INFO  : sub/index.json: Copied (new)
2026/10/10 03:25:25 DEBUG : sub/deep/index.json: Unchanged skipping
2026/10/10 03:25:26 DEBUG : sub/index.json: md5 = f14562121f0d542c640d199cc8843696 OK
2026/10/10 03:25:26 DEBUG : sub/index.json: size = 239 OK
2026/10/10 03:25:26 INFO  : sub/index.json: Copied (replaced existing)
2026/10/10 03:25:26 DEBUG : index.json: md5 = 84c7e5ba926d4c1199649d2326f247b7 OK
2026/10/10 03:25:26 DEBUG : index.json: size = 237 OK
2026/10/10 03:25:26 INFO  : index.json: Copied (replaced existing)
2026/10/10 03:25:31 DEBUG : sub/index.json: md5 = 201a3772be182f0daa952f10b857d722 OK
2026/10/10 03:25:31 DEBUG : sub/index.json: size = 215 OK
2026/10/10 03:25:31 INFO  : sub/index.json: Copied (replaced existing)
2026/10/10 03:25:31 DEBUG : sub/deep/index.json: md5 = 674a9735a8fa228300dd087053e3f9a4 OK
2026/10/10 03:25:31 DEBUG : sub/deep/index.json: size = 119 OK
2026/10/10 03:25:31 INFO  : sub/deep/index.json: Copied (replaced existing)
2026/10/10 03:25:31 DEBUG : index.json: md5 = eac8be0f24aa6aa30d80e1266de6d71f OK
2026/10/10 03:25:31 DEBUG : index.json: size = 213 OK
2026/10/10 03:25:31 INFO  : index.json: Copied (replaced existing)
--- PASS: TestIndexDirTime (22.57s)
=== RUN   TestIndexTemplate
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:25:38 DEBUG : file1.txt: md5 = b9c05d258ff52449f84de26fe354333d OK
2026/10/10 03:25:41 DEBUG : sub/file2.txt: md5 = 8665acf914b5b42e1127643e406ff048 OK
2026/10/10 03:25:43 DEBUG : sub/deep/file3.txt: md5 = c77412dee06d714ea4911a458a0bdd66 OK
2026/10/10 03:25:45 DEBUG : sub/list.txt: md5 = f34335d041fcf9cd1ffa577589c5fd68 OK
2026/10/10 03:25:45 DEBUG : sub/list.txt: size = 64 OK
2026/10/10 03:25:45 INFO  : sub/list.txt: Copied (new)
2026/10/10 03:25:45 DEBUG : list.txt: md5 = 45647f1f39dcf6b457a80a7ca5410a36 OK
2026/10/10 03:25:45 DEBUG : list.txt: size = 59 OK
2026/10/10 03:25:45 INFO  : list.txt: Copied (new)
2026/10/10 03:25:45 DEBUG : sub/deep/list.txt: md5 = d5c981832ae92695e939e9dc984915d1 OK
2026/10/10 03:25:45 DEBUG : sub/deep/list.txt: size = 47 OK
2026/10/10 03:25:45 INFO  : sub/deep/list.txt: Copied (new)
--- PASS: TestIndexTemplate (14.60s)
=== 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-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:25:53 DEBUG : file1.txt: md5 = 40eb4c688a988a36568209d44a74fa07 OK
2026/10/10 03:25:55 DEBUG : sub/file2.txt: md5 = 1b68a9371ba8330ff306241febe3976b OK
2026/10/10 03:25:58 DEBUG : sub/deep/file3.txt: md5 = ae9697d74223296912015e8e5d68b1f8 OK
2026/10/10 03:26:00 DEBUG : other/notes.md: md5 = 135026e31c191d85f5917440c24b1751 OK
2026/10/10 03:26:02 DEBUG : sub/deep/index.html: md5 = d2f503a245d87a5ca97c0be858eae790 OK
2026/10/10 03:26:02 DEBUG : sub/deep/index.html: size = 12087 OK
2026/10/10 03:26:02 INFO  : sub/deep/index.html: Copied (new)
2026/10/10 03:26:02 DEBUG : sub/index.html: md5 = 67afb78d2d077d14e296f64ecf0a42af OK
2026/10/10 03:26:02 DEBUG : sub/index.html: size = 12306 OK
2026/10/10 03:26:02 INFO  : sub/index.html: Copied (new)
2026/10/10 03:26:02 DEBUG : index.html: md5 = f9fd7c51ff9fde76ec08dbbeb317aee4 OK
2026/10/10 03:26:02 DEBUG : index.html: size = 12399 OK
2026/10/10 03:26:02 INFO  : index.html: Copied (new)
2026/10/10 03:26:03 DEBUG : other/index.html: md5 = 675164c8d40b6631b672538278d63815 OK
2026/10/10 03:26:03 DEBUG : other/index.html: size = 12058 OK
2026/10/10 03:26:03 INFO  : other/index.html: Copied (new)
2026/10/10 03:26:04 DEBUG : sub/deep/file4.txt: md5 = e0c170af28cb365dde48a30b54ba1c74 OK
2026/10/10 03:26:05 DEBUG : other/undeclared.txt: md5 = 29078750eaa076b5aa5301db26ef3c28 OK
2026/10/10 03:26:05 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate': Partial index: listing 3 directories and walking 0 changed directories
2026/10/10 03:26:07 DEBUG : index.html: Unchanged skipping
2026/10/10 03:26:07 DEBUG : sub/index.html: Unchanged skipping
2026/10/10 03:26:07 DEBUG : sub/deep/index.html: md5 = dccfa341b38aa9ba7a2b0b7b48dea204 OK
2026/10/10 03:26:07 DEBUG : sub/deep/index.html: size = 12346 OK
2026/10/10 03:26:07 INFO  : sub/deep/index.html: Copied (replaced existing)
2026/10/10 03:26:11 DEBUG : sub/new/file5.txt: md5 = 4e9ae13c0bde1f9695b06a89d2ef66df OK
2026/10/10 03:26:11 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate': Partial index: listing 3 directories and walking 0 changed directories
2026/10/10 03:26:13 DEBUG : index.html: Unchanged skipping
2026/10/10 03:26:13 DEBUG : sub/index.html: md5 = a52499e5e6514dbe784c7ea7c07188d6 OK
2026/10/10 03:26:13 DEBUG : sub/index.html: size = 12554 OK
2026/10/10 03:26:13 INFO  : sub/index.html: Copied (replaced existing)
2026/10/10 03:26:13 DEBUG : sub/new/index.html: md5 = 379789f721d62d135acd4e6f72dbb366 OK
2026/10/10 03:26:13 DEBUG : sub/new/index.html: size = 12085 OK
2026/10/10 03:26:13 INFO  : sub/new/index.html: Copied (new)
2026/10/10 03:26:16 DEBUG : other/undeclared0.txt: md5 = b6ff5c1cefae403625acf6978697a04d OK
2026/10/10 03:26:16 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate': Partial index: listing 1 directories and walking 1 changed directories
2026/10/10 03:26:17 DEBUG : index.html: Unchanged skipping
2026/10/10 03:26:18 DEBUG : other/index.html: md5 = 9468010208ae5745c6673be816a943e0 OK
2026/10/10 03:26:18 DEBUG : other/index.html: size = 12597 OK
2026/10/10 03:26:18 INFO  : other/index.html: Copied (replaced existing)
2026/10/10 03:26:21 DEBUG : other/undeclared1.txt: md5 = b727be350b6bfcb0ec89db156b979901 OK
2026/10/10 03:26:21 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate': Partial index: listing 1 directories and walking 0 changed directories
2026/10/10 03:26:22 DEBUG : index.html: Unchanged skipping
2026/10/10 03:26:22 DEBUG : other/index.html: md5 = da2ebbdb372546e2fee62d0276aae721 OK
2026/10/10 03:26:22 DEBUG : other/index.html: size = 12867 OK
2026/10/10 03:26:22 INFO  : other/index.html: Copied (replaced existing)
2026/10/10 03:26:25 DEBUG : sub/file6.txt: md5 = ab204c0fe2beca861c4a98f8c8c656f2 OK
2026/10/10 03:26:25 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate': Nothing changed so nothing to index
2026/10/10 03:26:25 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate': Root changed so doing a full index
2026/10/10 03:26:27 DEBUG : index.html: Unchanged skipping
2026/10/10 03:26:27 DEBUG : other/index.html: Unchanged skipping
2026/10/10 03:26:27 DEBUG : sub/deep/index.html: Unchanged skipping
2026/10/10 03:26:27 DEBUG : sub/new/index.html: Unchanged skipping
2026/10/10 03:26:28 DEBUG : sub/index.html: md5 = fb9e20b0cab161af207048bee0dd1ebe OK
2026/10/10 03:26:28 DEBUG : sub/index.html: size = 12813 OK
2026/10/10 03:26:28 INFO  : sub/index.html: Copied (replaced existing)
2026/10/10 03:26:31 DEBUG : sub/file7.txt: md5 = e46b683a3998a1ed9c0e981a59b10b36 OK
2026/10/10 03:26:31 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate': Partial index would list 2 directories, more than 1, so doing a full index
2026/10/10 03:26:32 DEBUG : sub/new/index.html: Unchanged skipping
2026/10/10 03:26:32 DEBUG : sub/deep/index.html: Unchanged skipping
2026/10/10 03:26:32 DEBUG : index.html: Unchanged skipping
2026/10/10 03:26:32 DEBUG : other/index.html: Unchanged skipping
2026/10/10 03:26:33 DEBUG : sub/index.html: md5 = be7d2e8e21182dbc547b7607f6b9ad40 OK
2026/10/10 03:26:33 DEBUG : sub/index.html: size = 13071 OK
2026/10/10 03:26:33 INFO  : sub/index.html: Copied (replaced existing)
2026/10/10 03:26:36 DEBUG : sub/deep/file8.txt: md5 = 9a3da9118139e51c1c473dedcb6b200f OK
2026/10/10 03:26:36 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate': Partial index: listing 1 directories and walking 1 changed directories
2026/10/10 03:26:37 DEBUG : sub/new/index.html: Unchanged skipping
2026/10/10 03:26:37 DEBUG : index.html: Unchanged skipping
2026/10/10 03:26:37 DEBUG : sub/index.html: Unchanged skipping
2026/10/10 03:26:38 DEBUG : sub/deep/index.html: md5 = e78505b3960a92b896a993882481bf1e OK
2026/10/10 03:26:38 DEBUG : sub/deep/index.html: size = 12604 OK
2026/10/10 03:26:38 INFO  : sub/deep/index.html: Copied (replaced existing)
2026/10/10 03:26:40 DEBUG : sub/deep/file9.txt: md5 = c709bd04d5dd60c2f577a4ae08e67a5c OK
2026/10/10 03:26:41 DEBUG : sub/file10.txt: md5 = dd44dc01ed26f9f11325e6ebd45fad8a OK
2026/10/10 03:26:41 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate': Partial index: listing 2 directories and walking 0 changed directories
2026/10/10 03:26:42 DEBUG : index.html: Unchanged skipping
2026/10/10 03:26:43 DEBUG : sub/index.html: md5 = 263fc566f92a15d5b65ee835679f9297 OK
2026/10/10 03:26:43 DEBUG : sub/index.html: size = 13331 OK
2026/10/10 03:26:43 INFO  : sub/index.html: Copied (replaced existing)
--- PASS: TestIndexChanged (65.53s)
=== RUN   TestIndexChangedDelete
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:26:58 DEBUG : file1.txt: md5 = 06b3a01c6e38c2d7553c6058662530b3 OK
2026/10/10 03:27:00 DEBUG : sub/file2.txt: md5 = ad9d79481d94871343103e900413d210 OK
2026/10/10 03:27:03 DEBUG : sub/deep/file3.txt: md5 = 84d0894b81259bbc232d57856fb51e0c OK
2026/10/10 03:27:05 DEBUG : sub/deep/index.html: md5 = a449ab7106596c87ee91f02f45d5cd63 OK
2026/10/10 03:27:05 DEBUG : sub/deep/index.html: size = 12087 OK
2026/10/10 03:27:05 INFO  : sub/deep/index.html: Copied (new)
2026/10/10 03:27:05 DEBUG : index.html: md5 = 847bed7072a50b46f05f312138c8bf9e OK
2026/10/10 03:27:05 DEBUG : index.html: size = 12147 OK
2026/10/10 03:27:05 INFO  : index.html: Copied (new)
2026/10/10 03:27:05 DEBUG : sub/index.html: md5 = 17afb5790e623c8b087305513de29790 OK
2026/10/10 03:27:05 DEBUG : sub/index.html: size = 12306 OK
2026/10/10 03:27:05 INFO  : sub/index.html: Copied (new)
2026/10/10 03:27:06 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate': Partial index: listing 3 directories and walking 0 changed directories
2026/10/10 03:27:07 DEBUG : sub/index.html: Unchanged skipping
2026/10/10 03:27:07 DEBUG : index.html: Unchanged skipping
2026/10/10 03:27:09 DEBUG : sub/deep/index.html: md5 = 9cdff78a4cc67d5b9182192633c17660 OK
2026/10/10 03:27:09 DEBUG : sub/deep/index.html: size = 11829 OK
2026/10/10 03:27:09 INFO  : sub/deep/index.html: Copied (replaced existing)
--- PASS: TestIndexChangedDelete (17.03s)
=== RUN   TestIndexChangedFiles
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:27:15 DEBUG : file1.txt: md5 = 42716a486cb5a3a05f844b584e5ba40d OK
2026/10/10 03:27:18 DEBUG : sub/file2.txt: md5 = 602aceab0313c56e5e268329a5421a4b OK
2026/10/10 03:27:20 DEBUG : sub/deep/file3.txt: md5 = 2809ccd8a2fa7ed89e376d32f5ff28e6 OK
2026/10/10 03:27:22 DEBUG : - odd/file.txt: md5 = 6fff0886b498220ab23b5729f8abe719 OK
2026/10/10 03:27:24 DEBUG : index.html: md5 = bf9f5b4a6709a20d17074a71978dff6a OK
2026/10/10 03:27:24 DEBUG : index.html: size = 12401 OK
2026/10/10 03:27:24 INFO  : index.html: Copied (new)
2026/10/10 03:27:24 DEBUG : - odd/index.html: md5 = 54211266e0f45737b15833b7a9c8e626 OK
2026/10/10 03:27:24 DEBUG : - odd/index.html: size = 12058 OK
2026/10/10 03:27:24 INFO  : - odd/index.html: Copied (new)
2026/10/10 03:27:24 DEBUG : sub/index.html: md5 = 581f83553cec5386b7dff92e14231fcf OK
2026/10/10 03:27:24 DEBUG : sub/index.html: size = 12306 OK
2026/10/10 03:27:24 INFO  : sub/index.html: Copied (new)
2026/10/10 03:27:24 DEBUG : sub/deep/index.html: md5 = 58ea1f53e3f212eb474e1dbda121186b OK
2026/10/10 03:27:24 DEBUG : sub/deep/index.html: size = 12087 OK
2026/10/10 03:27:24 INFO  : sub/deep/index.html: Copied (new)
2026/10/10 03:27:26 DEBUG : - odd/file8.txt: md5 = edd9a9953088a721733201828105b55c OK
2026/10/10 03:27:27 DEBUG : sub/file9.txt: md5 = e81abcbc047409998c258039f94e1d68 OK
2026/10/10 03:27:27 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate': Partial index: listing 2 directories and walking 0 changed directories
2026/10/10 03:27:29 DEBUG : index.html: Unchanged skipping
2026/10/10 03:27:30 DEBUG : - odd/index.html: md5 = b2ecd4b9aefee4bfc0d29dddc91aeeab OK
2026/10/10 03:27:30 DEBUG : - odd/index.html: size = 12316 OK
2026/10/10 03:27:30 INFO  : - odd/index.html: Copied (replaced existing)
2026/10/10 03:27:31 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate': Partial index: listing 4 directories and walking 0 changed directories
2026/10/10 03:27:33 DEBUG : - odd/index.html: Unchanged skipping
2026/10/10 03:27:33 DEBUG : index.html: Unchanged skipping
2026/10/10 03:27:33 DEBUG : sub/deep/index.html: Unchanged skipping
2026/10/10 03:27:34 DEBUG : sub/index.html: md5 = 82df3936229be53b8637616cc6f04111 OK
2026/10/10 03:27:34 DEBUG : sub/index.html: size = 12565 OK
2026/10/10 03:27:34 INFO  : sub/index.html: Copied (replaced existing)
--- PASS: TestIndexChangedFiles (28.39s)
=== RUN   TestIndexChangedDirTime
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:27:44 DEBUG : file1.txt: md5 = 84c15a48f9ce3a5b519d81b1ee420ec6 OK
2026/10/10 03:27:46 DEBUG : sub/file2.txt: md5 = 529ecbc03e11ac680cb608847ed8b10c OK
2026/10/10 03:27:48 DEBUG : sub/deep/file3.txt: md5 = 6fd77e65590d63e1904e98d435552ff7 OK
2026/10/10 03:27:50 DEBUG : other/notes.md: md5 = 8143f9379f5b2faf3d9e9f6f68c1da41 OK
2026/10/10 03:27:52 DEBUG : index.json: md5 = 5181bfd8af786f64ef02d6b20bfbfda4 OK
2026/10/10 03:27:52 DEBUG : index.json: size = 379 OK
2026/10/10 03:27:52 INFO  : index.json: Copied (new)
2026/10/10 03:27:52 DEBUG : sub/index.json: md5 = b8f344e5119029e60d5f37581a2c0529 OK
2026/10/10 03:27:52 DEBUG : sub/index.json: size = 259 OK
2026/10/10 03:27:52 INFO  : sub/index.json: Copied (new)
2026/10/10 03:27:52 DEBUG : other/index.json: md5 = c87b0cf24e9b8117d3d08757aaae4272 OK
2026/10/10 03:27:52 DEBUG : other/index.json: size = 144 OK
2026/10/10 03:27:52 INFO  : other/index.json: Copied (new)
2026/10/10 03:27:52 DEBUG : sub/deep/index.json: md5 = 056041f02dc7c6cead51aefe61d78588 OK
2026/10/10 03:27:52 DEBUG : sub/deep/index.json: size = 139 OK
2026/10/10 03:27:52 INFO  : sub/deep/index.json: Copied (new)
2026/10/10 03:27:54 DEBUG : sub/deep/file4.txt: md5 = a33fae329f2ea1024492c9d931e16307 OK
2026/10/10 03:27:54 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate': Partial index: listing 3 directories and walking 0 changed directories
2026/10/10 03:27:56 DEBUG : sub/deep/index.json: md5 = fcfd37a3b414e72fc11897db38aee405 OK
2026/10/10 03:27:56 DEBUG : sub/deep/index.json: size = 275 OK
2026/10/10 03:27:56 INFO  : sub/deep/index.json: Copied (replaced existing)
2026/10/10 03:27:57 DEBUG : sub/index.json: md5 = bcc0a442461e25df432cf4f2d55b49b2 OK
2026/10/10 03:27:57 DEBUG : sub/index.json: size = 259 OK
2026/10/10 03:27:57 INFO  : sub/index.json: Copied (replaced existing)
2026/10/10 03:27:57 DEBUG : index.json: md5 = b0edf1d00d99958054a965815b1ca485 OK
2026/10/10 03:27:57 DEBUG : index.json: size = 379 OK
2026/10/10 03:27:57 INFO  : index.json: Copied (replaced existing)
--- PASS: TestIndexChangedDirTime (22.19s)
=== RUN   TestListDirSorted
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:28:06 DEBUG : a.txt: md5 = 686b6b19aa1ef41482cd687a1d38758d OK
2026/10/10 03:28:08 DEBUG : zend.txt: md5 = 3cec541a8e334beea886212dfab3a868 OK
2026/10/10 03:28:10 DEBUG : sub dir/hello world: md5 = 02aa4ad33a3974465986ce8da911c7d0 OK
2026/10/10 03:28:11 DEBUG : sub dir/hello world2: md5 = febc7d8111766f32851f3c379d58c1ec OK
2026/10/10 03:28:13 DEBUG : sub dir/ignore dir/.ignore: md5 = b02cd1bc3ade09f587453281ed161103 OK
2026/10/10 03:28:15 DEBUG : sub dir/ignore dir/should be ignored: md5 = f9bdb9c0fc7f976a54581164694a3cc5 OK
2026/10/10 03:28:18 DEBUG : sub dir/sub sub dir/hello world3: md5 = 4f967d2374f7a19ea8f297e736daa4df OK
2026/10/10 03:28:19 DEBUG : a.txt: Excluded (Size Filter)
2026/10/10 03:28:19 DEBUG : a.txt: Excluded
2026/10/10 03:28:19 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2026/10/10 03:28:19 DEBUG : sub dir/hello world2: Excluded
2026/10/10 03:28:19 DEBUG : sub dir/hello world: Excluded (Size Filter)
2026/10/10 03:28:19 DEBUG : sub dir/hello world: Excluded
2026/10/10 03:28:20 DEBUG : sub dir/ignore dir: Excluded
2026/10/10 03:28:20 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2026/10/10 03:28:20 DEBUG : sub dir/hello world2: Excluded
2026/10/10 03:28:20 DEBUG : sub dir/hello world: Excluded (Size Filter)
2026/10/10 03:28:20 DEBUG : sub dir/hello world: Excluded
2026/10/10 03:28:20 DEBUG : sub dir/ignore dir: Excluded
--- PASS: TestListDirSorted (22.45s)
=== RUN   TestListDirSortedFn
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:28:29 DEBUG : a.txt: md5 = a1c9a82975b378ed343d90dcc3d16b0d OK
2026/10/10 03:28:30 DEBUG : zend.txt: md5 = 59f7e0f953bdfa2d04108843f2de5800 OK
2026/10/10 03:28:32 DEBUG : sub dir/hello world: md5 = 59b0d17c63cb9c595066eda01f9c999f OK
2026/10/10 03:28:33 DEBUG : sub dir/hello world2: md5 = 01b41c572bb7b7f520b247b360a2ef65 OK
2026/10/10 03:28:36 DEBUG : sub dir/ignore dir/.ignore: md5 = ce036bc9c63edd2125996df40f0650f4 OK
2026/10/10 03:28:38 DEBUG : sub dir/ignore dir/should be ignored: md5 = 5749ac389088107b82b42a231b6f6407 OK
2026/10/10 03:28:41 DEBUG : sub dir/sub sub dir/hello world3: md5 = 63007170f1c2c2a0dbdd08ed0361e4c6 OK
2026/10/10 03:28:42 DEBUG : a.txt: Excluded (Size Filter)
2026/10/10 03:28:42 DEBUG : a.txt: Excluded
2026/10/10 03:28:43 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2026/10/10 03:28:43 DEBUG : sub dir/hello world2: Excluded
2026/10/10 03:28:43 DEBUG : sub dir/hello world: Excluded (Size Filter)
2026/10/10 03:28:43 DEBUG : sub dir/hello world: Excluded
2026/10/10 03:28:44 DEBUG : sub dir/ignore dir: Excluded
2026/10/10 03:28:44 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2026/10/10 03:28:44 DEBUG : sub dir/hello world2: Excluded
2026/10/10 03:28:44 DEBUG : sub dir/hello world: Excluded (Size Filter)
2026/10/10 03:28:44 DEBUG : sub dir/hello world: Excluded
2026/10/10 03:28:44 DEBUG : sub dir/ignore dir: Excluded
--- PASS: TestListDirSortedFn (25.13s)
=== RUN   TestListJSON
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:28:55 DEBUG : file1: md5 = 99da1c2b236a7afc8f2de8e4d112f7df OK
2026/10/10 03:28:57 DEBUG : sub/file2: md5 = eabfcb9fda78509b4a0c401cdcb8cbb4 OK
=== RUN   TestListJSON/Default
=== RUN   TestListJSON/FilesOnly
=== RUN   TestListJSON/DirsOnly
=== RUN   TestListJSON/Recurse
=== RUN   TestListJSON/SubDir
=== RUN   TestListJSON/NoModTime
=== RUN   TestListJSON/NoMimeType
=== RUN   TestListJSON/ShowHash
=== RUN   TestListJSON/HashTypes
2026/10/10 03:29:00 ERROR : file1: Failed to read hash: hash type not supported
=== RUN   TestListJSON/Metadata
2026/10/10 03:29:00 DEBUG : eer8kka55qnghc34cq76ca668g: Fetching metadata
2026/10/10 03:29:00 DEBUG : 150fuo3cn4j1uenq6r4g4qk6t4: Fetching metadata
--- PASS: TestListJSON (10.46s)
    --- PASS: TestListJSON/Default (0.23s)
    --- PASS: TestListJSON/FilesOnly (0.27s)
    --- PASS: TestListJSON/DirsOnly (0.25s)
    --- PASS: TestListJSON/Recurse (0.48s)
    --- PASS: TestListJSON/SubDir (0.23s)
    --- PASS: TestListJSON/NoModTime (0.28s)
    --- PASS: TestListJSON/NoMimeType (0.24s)
    --- PASS: TestListJSON/ShowHash (0.28s)
    --- PASS: TestListJSON/HashTypes (0.27s)
    --- PASS: TestListJSON/Metadata (0.70s)
=== RUN   TestStatJSON
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:29:04 DEBUG : file1: md5 = 3768a784c9bcfc4273599470ea5a2d17 OK
2026/10/10 03:29:06 DEBUG : sub/file2: md5 = 06a2a99a55ddc91cf4faa9304e0fdcd9 OK
=== RUN   TestStatJSON/Root
=== RUN   TestStatJSON/RootFilesOnly
=== RUN   TestStatJSON/RootDirsOnly
=== RUN   TestStatJSON/Dir
=== RUN   TestStatJSON/DirWithTrailingSlash
=== RUN   TestStatJSON/File
=== RUN   TestStatJSON/NotFound
=== RUN   TestStatJSON/DirFilesOnly
=== RUN   TestStatJSON/FileFilesOnly
=== RUN   TestStatJSON/NotFoundFilesOnly
=== RUN   TestStatJSON/DirDirsOnly
=== RUN   TestStatJSON/FileDirsOnly
=== RUN   TestStatJSON/NotFoundDirsOnly
=== RUN   TestStatJSON/RootNotFound
2026/10/10 03:29:11 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jidazuy5pate/notfound"
2026/10/10 03:29:11 DEBUG : Config file has changed externally - reloading
2026/10/10 03:29:11 DEBUG : Creating backend with remote "TestDrive:crypt/dk13k2is7heuf9fqbv1qce710vcbjsoohqk6qu3gd00cahoe8f9g/qe0i31qdkoejk60elps0ni5sqk"
--- PASS: TestStatJSON (12.31s)
    --- PASS: TestStatJSON/Root (0.26s)
    --- PASS: TestStatJSON/RootFilesOnly (0.00s)
    --- PASS: TestStatJSON/RootDirsOnly (0.31s)
    --- PASS: TestStatJSON/Dir (0.53s)
    --- PASS: TestStatJSON/DirWithTrailingSlash (0.25s)
    --- PASS: TestStatJSON/File (0.29s)
    --- PASS: TestStatJSON/NotFound (0.52s)
    --- PASS: TestStatJSON/DirFilesOnly (0.29s)
    --- PASS: TestStatJSON/FileFilesOnly (0.24s)
    --- PASS: TestStatJSON/NotFoundFilesOnly (0.24s)
    --- PASS: TestStatJSON/DirDirsOnly (0.25s)
    --- PASS: TestStatJSON/FileDirsOnly (0.29s)
    --- PASS: TestStatJSON/NotFoundDirsOnly (0.27s)
    --- PASS: TestStatJSON/RootNotFound (1.88s)
=== RUN   TestStatJSONMemory
2026/10/10 03:29:15 DEBUG : Creating backend with remote ":memory:"
2026/10/10 03:29:15 DEBUG : Memory root '': File to upload is small (5 bytes), uploading instead of streaming
2026/10/10 03:29:15 DEBUG : sub/file1: size = 5 OK
2026/10/10 03:29:15 DEBUG : sub/file1: md5 = 5d41402abc4b2a76b9719d911017c592 OK
2026/10/10 03:29:15 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-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:29:15 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate': Making directory
2026/10/10 03:29:15 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate': Making directory
--- PASS: TestMkdir (1.26s)
=== RUN   TestLsd
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:29:18 DEBUG : sub dir/hello world: md5 = 12ca9e8ba1ddabfacf81d3333115bc70 OK
--- PASS: TestLsd (4.71s)
=== RUN   TestLs
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:29:22 DEBUG : potato2: md5 = 6b154c94ac009727b557303c93e90a80 OK
2026/10/10 03:29:24 DEBUG : empty space: md5 = 6c69c9575ddf527a8d8b6691b63418a1 OK
--- PASS: TestLs (4.80s)
=== RUN   TestLsWithFilesFrom
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:29:27 DEBUG : potato2: md5 = 960d03bae7e6c06a983f2d98c6548446 OK
2026/10/10 03:29:29 DEBUG : empty space: md5 = 09339177eadf246b113b8c15c227f123 OK
2026/10/10 03:29:30 DEBUG : empty space: Excluded (FilesFrom Filter)
2026/10/10 03:29:30 DEBUG : empty space: Excluded
--- PASS: TestLsWithFilesFrom (5.58s)
=== RUN   TestLsLong
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:29:33 DEBUG : potato2: md5 = b709c7d7f5c3bcb91ab383740a6a75de OK
2026/10/10 03:29:34 DEBUG : empty space: md5 = d0e13075a1330986026c57f23b038ecd OK
--- PASS: TestLsLong (4.69s)
=== RUN   TestHashSums
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:29:37 DEBUG : potato2: md5 = dec8acd89bcf4fec82c9449507476128 OK
2026/10/10 03:29:39 DEBUG : empty space: md5 = 4d932380f68615981c84a3ae41940b8c OK
--- PASS: TestHashSums (4.59s)
=== RUN   TestHashSumsWithErrors
2026/10/10 03:29:41 DEBUG : Creating backend with remote ":memory:"
2026/10/10 03:29:41 ERROR : file1: hash unsupported: hash type not supported
2026/10/10 03:29:41 ERROR : sub/file1: hash unsupported: hash type not supported
2026/10/10 03:29:41 ERROR : sub/file1: hash unsupported: hash type not supported
--- PASS: TestHashSumsWithErrors (0.00s)
=== RUN   TestHashStream
2026/10/10 03:29:41 DEBUG : Creating md5 hash of 0 bytes read from input stream
2026/10/10 03:29:41 DEBUG : Creating md5 hash of 0 bytes read from input stream
2026/10/10 03:29:41 DEBUG : Creating sha1 hash of 0 bytes read from input stream
2026/10/10 03:29:41 DEBUG : Creating sha1 hash of 0 bytes read from input stream
2026/10/10 03:29:41 DEBUG : Creating md5 hash of 12 bytes read from input stream
2026/10/10 03:29:41 DEBUG : Creating md5 hash of 12 bytes read from input stream
2026/10/10 03:29:41 DEBUG : Creating sha1 hash of 12 bytes read from input stream
2026/10/10 03:29:41 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-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:29:42 DEBUG : potato2: md5 = f8916bffb24a8f15dff93b6286c718df OK
2026/10/10 03:29:44 DEBUG : empty space: md5 = 76ede3e383ec42cde0c5b44cd57d34eb OK
2026/10/10 03:29:46 DEBUG : sub dir/potato3: md5 = 0a74996d45dddabeea48c87015cd3500 OK
--- PASS: TestCount (8.55s)
=== RUN   TestDelete
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:29:51 DEBUG : small: md5 = b55271f5e4b26fb31ce6ae281f69d8e2 OK
2026/10/10 03:29:52 DEBUG : medium: md5 = 0bb85acdee78eab2318a2f90997ed0f3 OK
2026/10/10 03:29:53 DEBUG : large: md5 = 2fbca298a7a2466f11faf195280713da OK
2026/10/10 03:29:54 DEBUG : Waiting for deletions to finish
2026/10/10 03:29:54 DEBUG : large: Excluded (Size Filter)
2026/10/10 03:29:54 INFO  : medium: Deleted
2026/10/10 03:29:54 INFO  : small: Deleted
--- PASS: TestDelete (6.42s)
=== RUN   TestDeleteFatalError
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:29:57 DEBUG : file0: md5 = 26fb105d54349e0bb2b5cccae74d8e03 OK
2026/10/10 03:29:58 DEBUG : file1: md5 = 5ce63bf8e8802ea6fd1080d42fed1d48 OK
2026/10/10 03:30:00 DEBUG : file2: md5 = 00948842562fc56c955bf84a825fff57 OK
2026/10/10 03:30:02 DEBUG : file3: md5 = ac2cb9a38ab96bb96cc907789f84ea8f OK
2026/10/10 03:30:04 DEBUG : file4: md5 = cd9be4d8fa920a13c7849b3d04968799 OK
2026/10/10 03:30:05 DEBUG : file5: md5 = 96bb129109b9cfcc66152978b2daf1f1 OK
2026/10/10 03:30:07 DEBUG : file6: md5 = 04e59eb29dfd0df0c5ad3447a797360b OK
2026/10/10 03:30:08 DEBUG : file7: md5 = 0be13795eb82a75795931c9904893223 OK
2026/10/10 03:30:10 DEBUG : file8: md5 = 6312ff07fb05e51aca304731f6a27842 OK
2026/10/10 03:30:11 DEBUG : file9: md5 = abcc17c49b14f2f9926a6c21c76b1917 OK
2026/10/10 03:30:13 DEBUG : file10: md5 = 14dd83f8fa72a24c6a4ff5ec20a29539 OK
2026/10/10 03:30:14 DEBUG : file11: md5 = c90bd517ded7bfefe8d69f004333fc85 OK
2026/10/10 03:30:16 DEBUG : file12: md5 = 70bde173fbc880062b7d80632e54648e OK
2026/10/10 03:30:17 DEBUG : file13: md5 = 830e025a2d00e24aa47e59feb95ba853 OK
2026/10/10 03:30:18 DEBUG : file14: md5 = 79082067b6ef9f6fa1cb1f8470642cf2 OK
2026/10/10 03:30:20 DEBUG : file15: md5 = 6ed00b75a95ebd2bbb7126b622b0bb61 OK
2026/10/10 03:30:21 DEBUG : file16: md5 = 0619697eeef49b561a2a68c07b6caf3c OK
2026/10/10 03:30:23 DEBUG : file17: md5 = 4751035824e959e4cd1bb4255c7a4f34 OK
2026/10/10 03:30:24 DEBUG : file18: md5 = 0fc911eff74582b3916896b507b1a2af OK
2026/10/10 03:30:26 DEBUG : file19: md5 = 67768410daa602e1512ad6b3a7364b43 OK
2026/10/10 03:30:26 DEBUG : Waiting for deletions to finish
2026/10/10 03:30:26 ERROR : file17: Got fatal error on delete: --max-delete threshold reached
2026/10/10 03:30:26 ERROR : file10: Got fatal error on delete: --max-delete threshold reached
2026/10/10 03:30:26 ERROR : file7: Got fatal error on delete: --max-delete threshold reached
2026/10/10 03:30:26 ERROR : file0: Got fatal error on delete: --max-delete threshold reached
2026/10/10 03:30:26 ERROR : file9: Got fatal error on delete: --max-delete threshold reached
2026/10/10 03:30:26 ERROR : file4: Got fatal error on delete: --max-delete threshold reached
2026/10/10 03:30:26 ERROR : file2: Got fatal error on delete: --max-delete threshold reached
2026/10/10 03:30:26 ERROR : file15: Got fatal error on delete: --max-delete threshold reached
2026/10/10 03:30:26 ERROR : file6: Got fatal error on delete: --max-delete threshold reached
2026/10/10 03:30:26 ERROR : file1: Got fatal error on delete: --max-delete threshold reached
2026/10/10 03:30:26 ERROR : file8: Got fatal error on delete: --max-delete threshold reached
2026/10/10 03:30:26 ERROR : file5: Got fatal error on delete: --max-delete threshold reached
2026/10/10 03:30:26 ERROR : file19: Got fatal error on delete: --max-delete threshold reached
2026/10/10 03:30:26 ERROR : file14: Got fatal error on delete: --max-delete threshold reached
2026/10/10 03:30:26 ERROR : file3: Got fatal error on delete: --max-delete threshold reached
2026/10/10 03:30:26 ERROR : file16: Got fatal error on delete: --max-delete threshold reached
2026/10/10 03:30:26 ERROR : file18: Got fatal error on delete: --max-delete threshold reached
2026/10/10 03:30:26 ERROR : file12: Got fatal error on delete: --max-delete threshold reached
2026/10/10 03:30:26 ERROR : file11: Got fatal error on delete: --max-delete threshold reached
2026/10/10 03:30:26 ERROR : file13: Got fatal error on delete: --max-delete threshold reached
--- PASS: TestDeleteFatalError (40.52s)
=== RUN   TestMaxDelete
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:30:38 DEBUG : small: md5 = 0762690b722ae1b9c3f2df7a0215d66c OK
2026/10/10 03:30:39 DEBUG : medium: md5 = ade15e3984d9f2c31eca22e68bcbbfa0 OK
2026/10/10 03:30:40 DEBUG : large: md5 = 3efccdf29f4cf504e6155edb31843e26 OK
2026/10/10 03:30:41 DEBUG : Waiting for deletions to finish
2026/10/10 03:30:41 ERROR : medium: Got fatal error on delete: --max-delete threshold reached
2026/10/10 03:30:41 INFO  : small: Deleted
2026/10/10 03:30:41 INFO  : large: Deleted
--- PASS: TestMaxDelete (7.04s)
=== RUN   TestMaxDeleteSizeLargeFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:30:45 DEBUG : small: md5 = 8bd7089aff265eba3da7f3f7e5e2408b OK
2026/10/10 03:30:46 DEBUG : medium: md5 = 766392235cfb3ec4b36efdf4b49b942b OK
2026/10/10 03:30:48 DEBUG : large: md5 = 760f7efaea1d2f1551b755bbca92e7a2 OK
2026/10/10 03:30:48 DEBUG : Waiting for deletions to finish
2026/10/10 03:30:48 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached
2026/10/10 03:30:49 INFO  : medium: Deleted
2026/10/10 03:30:49 INFO  : small: Deleted
--- PASS: TestMaxDeleteSizeLargeFile (6.97s)
=== RUN   TestMaxDeleteSize
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:30:52 DEBUG : small: md5 = bdc23c2da66acbbebb8cc2d91ba5c091 OK
2026/10/10 03:30:53 DEBUG : medium: md5 = ba2d4aab0f7df7b84b8c8d43806597ba OK
2026/10/10 03:30:55 DEBUG : large: md5 = 739ded3350ea3f321def0ffd72503b8d OK
2026/10/10 03:30:55 DEBUG : Waiting for deletions to finish
2026/10/10 03:30:55 ERROR : medium: Got fatal error on delete: --max-delete-size threshold reached
2026/10/10 03:30:56 INFO  : small: Deleted
2026/10/10 03:30:56 INFO  : large: Deleted
--- PASS: TestMaxDeleteSize (7.88s)
=== RUN   TestReadFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:30:59 DEBUG : ReadFile: md5 = 683c00dbceed339b18b833d997a2d2e6 OK
--- PASS: TestReadFile (3.81s)
=== RUN   TestRetry
2026/10/10 03:31:02 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 1/5
2026/10/10 03:31:02 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 2/5
2026/10/10 03:31:02 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms
2026/10/10 03:31:02 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms
2026/10/10 03:31:02 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms
2026/10/10 03:31:02 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms
--- PASS: TestRetry (0.04s)
=== RUN   TestRetryAfterContextCancel
2026/10/10 03:31:02 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-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:31:03 DEBUG : file1: md5 = 7a3f4db4ac665a03b6b1130d63c7e65a OK
2026/10/10 03:31:05 DEBUG : file2: md5 = 2470ef4d0f5771d0bd17a5b8ca980ad1 OK
--- PASS: TestCat (13.47s)
=== RUN   TestPurge
2026/10/10 03:31:15 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-varonoq7kahe"
2026/10/10 03:31:15 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/10 03:31:15 DEBUG : Creating backend with remote "TestDrive:crypt/ibsgb7g7qk36svgh8luv50j3q540c65tmu4pp1957l536quil6h0"
2026/10/10 03:31:16 DEBUG : Creating backend with remote "/tmp/rclone2514647260"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varonoq7kahe'", Local "Local file system at /tmp/rclone2514647260", Modify Window "1ms"
2026/10/10 03:31:21 DEBUG : A1/B1/C1/one: md5 = 11d2e99bd8f0793dd4dd289edb3d27ca OK
2026/10/10 03:31:21 INFO  : A2: Making directory
2026/10/10 03:31:22 INFO  : A1/B2: Making directory
2026/10/10 03:31:22 INFO  : A1/B2/C2: Making directory
2026/10/10 03:31:23 INFO  : A1/B1/C3: Making directory
2026/10/10 03:31:24 INFO  : A3: Making directory
2026/10/10 03:31:25 INFO  : A3/B3: Making directory
2026/10/10 03:31:26 INFO  : A3/B3/C4: Making directory
2026/10/10 03:31:28 DEBUG : A1/two: md5 = 6cfb1c4efba723a631eafc0c7af5551e OK
2026/10/10 03:31:32 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-varonoq7kahe': Purge remote
2026/10/10 03:31:32 NOTICE: purge failed: directory not found
--- PASS: TestPurge (17.09s)
=== RUN   TestRmdirsNoLeaveRoot
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:31:36 DEBUG : A1/B1/C1/one: md5 = 270402c6891046b01edb327b1924242f OK
2026/10/10 03:31:36 INFO  : A2: Making directory
2026/10/10 03:31:37 INFO  : A1/B2: Making directory
2026/10/10 03:31:37 INFO  : A1/B2/C2: Making directory
2026/10/10 03:31:38 INFO  : A1/B1/C3: Making directory
2026/10/10 03:31:39 INFO  : A3: Making directory
2026/10/10 03:31:40 INFO  : A3/B3: Making directory
2026/10/10 03:31:40 INFO  : A3/B3/C4: Making directory
2026/10/10 03:31:42 DEBUG : A1/two: md5 = 95e4a7b60f16cc2a69fc8bc0304d0726 OK
2026/10/10 03:31:44 DEBUG : removing 1 level 3 directories
2026/10/10 03:31:44 INFO  : A3/B3/C4: Removing directory
2026/10/10 03:31:46 DEBUG : removing 2 level 3 directories
2026/10/10 03:31:46 INFO  : A1/B2/C2: Removing directory
2026/10/10 03:31:46 INFO  : A1/B1/C3: Removing directory
2026/10/10 03:31:47 DEBUG : removing 2 level 2 directories
2026/10/10 03:31:47 INFO  : A3/B3: Removing directory
2026/10/10 03:31:47 INFO  : A1/B2: Removing directory
2026/10/10 03:31:48 DEBUG : removing 2 level 1 directories
2026/10/10 03:31:48 INFO  : A3: Removing directory
2026/10/10 03:31:48 INFO  : A2: Removing directory
2026/10/10 03:31:52 DEBUG : removing 1 level 3 directories
2026/10/10 03:31:52 INFO  : A1/B1/C1: Removing directory
2026/10/10 03:31:52 DEBUG : removing 1 level 2 directories
2026/10/10 03:31:52 INFO  : A1/B1: Removing directory
2026/10/10 03:31:53 DEBUG : removing 1 level 1 directories
2026/10/10 03:31:53 INFO  : A1: Removing directory
2026/10/10 03:31:54 DEBUG : removing 1 level 0 directories
2026/10/10 03:31:54 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate': Removing directory
--- PASS: TestRmdirsNoLeaveRoot (22.95s)
=== RUN   TestRmdirsLeaveRoot
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:31:56 INFO  : A1: Making directory
2026/10/10 03:31:57 INFO  : A1/B1: Making directory
2026/10/10 03:31:58 INFO  : A1/B1/C1: Making directory
2026/10/10 03:32:00 DEBUG : removing 1 level 3 directories
2026/10/10 03:32:00 INFO  : A1/B1/C1: Removing directory
2026/10/10 03:32:01 DEBUG : removing 1 level 2 directories
2026/10/10 03:32:01 INFO  : A1/B1: Removing directory
--- PASS: TestRmdirsLeaveRoot (8.31s)
=== RUN   TestRmdirsWithFilter
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:32:04 INFO  : A1: Making directory
2026/10/10 03:32:04 INFO  : A1/B1: Making directory
2026/10/10 03:32:05 INFO  : A1/B1/C1: Making directory
2026/10/10 03:32:08 DEBUG : removing 1 level 3 directories
2026/10/10 03:32:08 INFO  : A1/B1/C1: Removing directory
2026/10/10 03:32:09 DEBUG : removing 1 level 2 directories
2026/10/10 03:32:09 INFO  : A1/B1: Removing directory
--- PASS: TestRmdirsWithFilter (7.98s)
=== RUN   TestCopyURL
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:32:14 DEBUG : file1: md5 = 0d9beb8d02bf375c6d6d73baa6b5a3d0 OK
2026/10/10 03:32:14 DEBUG : file1: size = 14 OK
2026/10/10 03:32:14 DEBUG : filename.txt: File name found in url
2026/10/10 03:32:15 DEBUG : filename.txt: md5 = 0cfbc1afc7b934f78b632e39d0a15cda OK
2026/10/10 03:32:15 DEBUG : filename.txt: size = 14 OK
2026/10/10 03:32:15 DEBUG : headerfilename.txt: filename found in Content-Disposition header.
2026/10/10 03:32:17 DEBUG : headerfilename.txt: md5 = 402b05668c772cc02eaaebab0b434669 OK
2026/10/10 03:32:17 DEBUG : headerfilename.txt: size = 14 OK
2026/10/10 03:32:18 DEBUG : file2: md5 = 4c3f1ddc91d1cba6e90930dc60ee6ac2 OK
2026/10/10 03:32:18 DEBUG : file2: size = 14 OK
--- PASS: TestCopyURL (10.69s)
=== RUN   TestCopyURLDownloadHeaders
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:32:24 DEBUG : file1: md5 = 795ee9edaf64b8a1ab920722ab21e754 OK
2026/10/10 03:32:24 DEBUG : file1: size = 14 OK
--- PASS: TestCopyURLDownloadHeaders (2.35s)
=== RUN   TestCopyURLToWriter
--- PASS: TestCopyURLToWriter (0.00s)
=== RUN   TestMoveFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:32:25 DEBUG : file1: Need to transfer - File not found at Destination
2026/10/10 03:32:27 DEBUG : sub/file2: md5 = 1149af74b44fbab2e6fe00bfff245ea0 OK
2026/10/10 03:32:27 DEBUG : sub/file2: size = 14 OK
2026/10/10 03:32:27 INFO  : file1: Copied (new) to: sub/file2
2026/10/10 03:32:27 INFO  : file1: Deleted
2026/10/10 03:32:28 DEBUG : sub/file2: size = 14 OK
2026/10/10 03:32:28 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2026/10/10 03:32:28 DEBUG : file1: Unchanged skipping
2026/10/10 03:32:28 INFO  : file1: Deleted
2026/10/10 03:32:28 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate': don't need to copy/move sub/file2, it is already at target location
--- PASS: TestMoveFile (6.38s)
=== RUN   TestMoveFileWithIgnoreExisting
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:32:31 DEBUG : file1: Need to transfer - File not found at Destination
2026/10/10 03:32:33 DEBUG : file1: md5 = c78876eac3e30f12a8673eb3bb1c26c8 OK
2026/10/10 03:32:33 DEBUG : file1: size = 14 OK
2026/10/10 03:32:33 INFO  : file1: Copied (new)
2026/10/10 03:32:33 INFO  : file1: Deleted
2026/10/10 03:32:33 DEBUG : file1: Destination exists, skipping
2026/10/10 03:32:33 DEBUG : file1: Not removing source file as destination file exists and --ignore-existing is set
--- PASS: TestMoveFileWithIgnoreExisting (3.46s)
=== RUN   TestMoveFileImmutable
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:32:36 DEBUG : existing: md5 = d4f0c3640da22a848d6b7f92fd83acf1 OK
2026/10/10 03:32:37 DEBUG : existing: size = 8 (Local file system at /tmp/rclone2406082256)
2026/10/10 03:32:37 DEBUG : existing: size = 6 (Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate')
2026/10/10 03:32:37 DEBUG : existing: Sizes differ
2026/10/10 03:32:37 ERROR : existing: Source and destination exist but do not match: immutable file modified
--- PASS: TestMoveFileImmutable (3.18s)
=== RUN   TestCaseInsensitiveMoveFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
--- PASS: TestCaseInsensitiveMoveFile (0.50s)
=== RUN   TestCaseInsensitiveMoveFileDryRun
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
--- PASS: TestCaseInsensitiveMoveFileDryRun (0.45s)
=== RUN   TestMoveFileBackupDir
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:32:41 DEBUG : dst/file1: md5 = 104d7bea1b511b918b978e0c616aa837 OK
2026/10/10 03:32:42 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jidazuy5pate/backup"
2026/10/10 03:32:42 DEBUG : Creating backend with remote "TestDrive:crypt/dk13k2is7heuf9fqbv1qce710vcbjsoohqk6qu3gd00cahoe8f9g/1nrff024r7pq65ecp72fc28jb0"
2026/10/10 03:32:44 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone2406082256)
2026/10/10 03:32:44 DEBUG : dst/file1: size = 18 (Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate')
2026/10/10 03:32:44 DEBUG : dst/file1: Sizes differ
2026/10/10 03:32:46 INFO  : dst/file1: Moved (server-side)
2026/10/10 03:32:48 DEBUG : dst/file1: md5 = 265a377cf1567158ead5f15e55d4a355 OK
2026/10/10 03:32:48 DEBUG : dst/file1: size = 14 OK
2026/10/10 03:32:48 INFO  : dst/file1: Copied (new)
2026/10/10 03:32:48 INFO  : dst/file1: Deleted
--- PASS: TestMoveFileBackupDir (13.88s)
=== 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-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:32:55 DEBUG : A/file0: md5 = b028d70567f3d48f2c5a73a0c214d2e3 OK
2026/10/10 03:32:56 DEBUG : A/file1: md5 = ce6fb4e457de23a5fb6a86272f10d48c OK
2026/10/10 03:32:58 DEBUG : A/file2: md5 = 0e28983046c79eb053686f1323b00888 OK
2026/10/10 03:33:00 DEBUG : A/file3: md5 = 9dac79f287d05ff9095332c9a0fbb5bb OK
2026/10/10 03:33:01 DEBUG : A/file4: md5 = 363ea21334563be30dc90b0f9d46dcb8 OK
2026/10/10 03:33:03 DEBUG : A/file5: md5 = 7a5d0591210c7984fba602f8d31554c5 OK
2026/10/10 03:33:04 DEBUG : A/file6: md5 = 75505ef1d12a5c7b0b0afb8b33308468 OK
2026/10/10 03:33:05 DEBUG : A/file7: md5 = ed7e0f1e331c1b03b10eaa06fd26377b OK
2026/10/10 03:33:07 DEBUG : A/file8: md5 = 9bee4f476a7577af99063eb3faf5727d OK
2026/10/10 03:33:08 DEBUG : A/file9: md5 = 0b19228babdc2043de7726d80581d6f1 OK
2026/10/10 03:33:10 DEBUG : A/file10: md5 = d71d2fdc74d618de4bd91becc7697041 OK
2026/10/10 03:33:11 DEBUG : A/file11: md5 = 4feb656242df6bacd4c35afea9bd519a OK
2026/10/10 03:33:12 DEBUG : A/file12: md5 = 88e9bdb0e8741700982f50fca4300ca9 OK
2026/10/10 03:33:14 DEBUG : A/file13: md5 = 62ed9bee7db5a5fa348d61747f14a797 OK
2026/10/10 03:33:15 DEBUG : A/file14: md5 = 0b5e14423411958da1d7f7b5a1f55c84 OK
2026/10/10 03:33:17 DEBUG : A/file15: md5 = 82af6477174fca952eb399f51594bb1b OK
2026/10/10 03:33:18 DEBUG : A/file16: md5 = d5ad82bececf4d9f47d54b0c29588b00 OK
2026/10/10 03:33:19 DEBUG : A/file17: md5 = dce6d6db174056c0e024acefe499e0b9 OK
2026/10/10 03:33:21 DEBUG : A/file18: md5 = e348f4c5f2667d26a42c71fc62c076ca OK
2026/10/10 03:33:22 DEBUG : A/file19: md5 = f0a2dfcb0e4f0f6b490b370173e08516 OK
    operations_test.go:1490: Skipping test on non local remote
--- SKIP: TestDirMoveMoveError (39.77s)
=== RUN   TestDirMoveContext
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:33:35 DEBUG : A/one: md5 = ef1aab77c9d784c65fcd23a9e94c18c8 OK
2026/10/10 03:33:37 DEBUG : A/two: md5 = b51135b5271c18296c59433c040eab9d OK
    operations_test.go:1490: Skipping test on non local remote
--- SKIP: TestDirMoveContext (7.43s)
=== RUN   TestDirMove
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:33:42 DEBUG : A1/one: md5 = 8c59dd1889fea7f279e1a87f16f250ac OK
2026/10/10 03:33:43 DEBUG : A1/two: md5 = a59f9ac84c2c1796fdeb27e8ddf1a108 OK
2026/10/10 03:33:45 DEBUG : A1/B1/three: md5 = 8b307d03d52901799a513c1556239dbf OK
2026/10/10 03:33:48 DEBUG : A1/B1/C1/four: md5 = 7f5b152d93b38d9af86a1c711d254c90 OK
2026/10/10 03:33:50 DEBUG : A1/B1/C2/five: md5 = ec3a9fb8aaa4719dc6fe51ff2b242b45 OK
2026/10/10 03:33:50 INFO  : A1/B2: Making directory
2026/10/10 03:33:51 INFO  : A1/B1/C3: Making directory
2026/10/10 03:34:01 INFO  : A2/two: Moved (server-side) to: A3/two
2026/10/10 03:34:01 INFO  : A2/B1/C2/five: Moved (server-side) to: A3/B1/C2/five
2026/10/10 03:34:01 INFO  : A2/B1/C1/four: Moved (server-side) to: A3/B1/C1/four
2026/10/10 03:34:01 INFO  : A2/B1/three: Moved (server-side) to: A3/B1/three
2026/10/10 03:34:01 INFO  : A2/one: Moved (server-side) to: A3/one
2026/10/10 03:34:06 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate': Can't DirMove - falling back to file moves: can't move directory - incompatible remotes
2026/10/10 03:34:12 INFO  : A3/B1/C1/four: Moved (server-side) to: A4/B1/C1/four
2026/10/10 03:34:12 INFO  : A3/two: Moved (server-side) to: A4/two
2026/10/10 03:34:12 INFO  : A3/B1/C2/five: Moved (server-side) to: A4/B1/C2/five
2026/10/10 03:34:12 INFO  : A3/one: Moved (server-side) to: A4/one
2026/10/10 03:34:13 INFO  : A3/B1/three: Moved (server-side) to: A4/B1/three
--- PASS: TestDirMove (45.82s)
=== RUN   TestGetFsInfo
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
--- PASS: TestGetFsInfo (0.46s)
=== RUN   TestRcat
=== RUN   TestRcat/withChecksum=false,ignoreChecksum=false
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:34:26 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate': File to upload is small (34 bytes), uploading instead of streaming
2026/10/10 03:34:28 DEBUG : no_checksum_small_file_from_pipe: md5 = 3c7c31948b6cab1ed104f9e3c1c6ec1c OK
2026/10/10 03:34:28 DEBUG : no_checksum_small_file_from_pipe: size = 34 OK
2026/10/10 03:34:28 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only
2026/10/10 03:34:28 DEBUG : no_checksum_small_file_from_pipe: Size of src and dst objects identical
2026/10/10 03:34:28 DEBUG : qc9k4uauqa9ls6a2r6lo8jf4b1m7r0510pkurois1cc70egbs4h0: Sending chunk 0 length 102465
2026/10/10 03:34:29 DEBUG : no_checksum_big_file_from_pipe: md5 = 63f8c10d415cff5b12f31277cdd42f61 OK
2026/10/10 03:34:29 DEBUG : no_checksum_big_file_from_pipe: size = 102401 OK
2026/10/10 03:34:29 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-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:34:31 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate': File to upload is small (34 bytes), uploading instead of streaming
2026/10/10 03:34:33 DEBUG : with_checksum_small_file_from_pipe: md5 = 33c70c7799de00656a345e54df163418 OK
2026/10/10 03:34:33 DEBUG : with_checksum_small_file_from_pipe: size = 34 OK
2026/10/10 03:34:33 DEBUG : with_checksum_small_file_from_pipe: Size of src and dst objects identical
2026/10/10 03:34:34 DEBUG : e74jo8a7lr1aimg2u78fct67gnuetc1qujiofaci0c8fnq7rjt1d4i296pcklqhgdihs03etvgdi4: Sending chunk 0 length 102465
2026/10/10 03:34:35 DEBUG : with_checksum_big_file_from_pipe: md5 = 61ce91fd4b24ac1a583acb63235c02a7 OK
2026/10/10 03:34:35 DEBUG : with_checksum_big_file_from_pipe: size = 102401 OK
2026/10/10 03:34:35 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-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:34:37 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate': File to upload is small (34 bytes), uploading instead of streaming
2026/10/10 03:34:38 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK
2026/10/10 03:34:38 DEBUG : ignore_checksum_small_file_from_pipe: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2026/10/10 03:34:39 DEBUG : 1963rn6olajfbjn4nbqi9nacu7bk879vpf0d2bg8r0ndcqqsfntob0urt056p0qujf574rqnb5f62: Sending chunk 0 length 102465
2026/10/10 03:34:40 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK
2026/10/10 03:34:40 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-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:34:42 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate': File to upload is small (34 bytes), uploading instead of streaming
2026/10/10 03:34:43 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK
2026/10/10 03:34:43 DEBUG : ignore_checksum_small_file_from_pipe: Size of src and dst objects identical
2026/10/10 03:34:44 DEBUG : 1963rn6olajfbjn4nbqi9nacu7bk879vpf0d2bg8r0ndcqqsfntob0urt056p0qujf574rqnb5f62: Sending chunk 0 length 102465
2026/10/10 03:34:45 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK
2026/10/10 03:34:45 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical
--- PASS: TestRcat (20.50s)
    --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (5.02s)
    --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (5.38s)
    --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (4.83s)
    --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (5.28s)
=== RUN   TestRcatMetadata
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
=== RUN   TestRcatMetadata/Normal
2026/10/10 03:34:47 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate': File to upload is small (48 bytes), uploading instead of streaming
2026/10/10 03:34:48 DEBUG : rcat_metadata: md5 = 2b64fbc60b9f52cde82fb4d29c6f06b6 OK
2026/10/10 03:34:48 DEBUG : rcat_metadata: size = 48 OK
2026/10/10 03:34:48 DEBUG : rcat_metadata: Size of src and dst objects identical
=== RUN   TestRcatMetadata/ViaDisk
2026/10/10 03:34:50 DEBUG : rijssoobhbn85c5p1imhg55m6frank1nlplla6hkdjlqmqb2471g: Sending chunk 0 length 111
2026/10/10 03:34:51 DEBUG : rcat_metadata_uploadcutoff0: md5 = d2b74a31a1ae469d4d16833d16e2a386 OK
2026/10/10 03:34:51 DEBUG : rcat_metadata_uploadcutoff0: size = 63 OK
2026/10/10 03:34:51 DEBUG : rcat_metadata_uploadcutoff0: Size of src and dst objects identical
--- PASS: TestRcatMetadata (5.73s)
    --- PASS: TestRcatMetadata/Normal (2.60s)
    --- PASS: TestRcatMetadata/ViaDisk (2.70s)
=== RUN   TestRcatSize
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:34:54 DEBUG : potato1: md5 = 8d32824aad6ceed177b5a125e78dd19e OK
2026/10/10 03:34:54 DEBUG : potato1: size = 60 OK
2026/10/10 03:34:54 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate': File to upload is small (60 bytes), uploading instead of streaming
2026/10/10 03:34:55 DEBUG : potato2: md5 = bbd2e0c4cb1bfe3ee8287a9e8aad0248 OK
2026/10/10 03:34:55 DEBUG : potato2: size = 60 OK
2026/10/10 03:34:55 DEBUG : potato2: Size of src and dst objects identical
--- PASS: TestRcatSize (4.47s)
=== RUN   TestRcatSizeShortEOF
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:34:58 DEBUG : potato1: md5 = 2ea5ee2982494794a1b8fdd45be0ecc1 OK
2026/10/10 03:34:58 DEBUG : potato1: size = 120 (Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate')
2026/10/10 03:34:58 DEBUG : potato1: size = 60 (Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate')
2026/10/10 03:34:58 ERROR : potato1: corrupted on transfer: sizes differ src 120 vs dst(Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate') 60
2026/10/10 03:34:58 INFO  : potato1: Removing failed copy
--- PASS: TestRcatSizeShortEOF (2.16s)
=== RUN   TestRcatSizeMetadata
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:35:00 DEBUG : potato1: md5 = 187da85470b6fbb3e612659017103f63 OK
2026/10/10 03:35:00 DEBUG : potato1: size = 60 OK
2026/10/10 03:35:00 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate': File to upload is small (60 bytes), uploading instead of streaming
2026/10/10 03:35:02 DEBUG : potato2: md5 = 55f3c3bb01ba2c82bf000f6f9bdc42d9 OK
2026/10/10 03:35:02 DEBUG : potato2: size = 60 OK
2026/10/10 03:35:02 DEBUG : potato2: Size of src and dst objects identical
--- PASS: TestRcatSizeMetadata (4.78s)
=== RUN   TestRcatSizeUploadHeaders
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:35:05 DEBUG : potato1: md5 = c334698d6b8b0e0385972908a9556d6a OK
2026/10/10 03:35:05 DEBUG : potato1: size = 60 OK
--- PASS: TestRcatSizeUploadHeaders (2.28s)
=== RUN   TestRcatSizeChecksum
=== RUN   TestRcatSizeChecksum/Corrupted
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
    operations_test.go:1975: Skipping as destination doesn't support hashes
=== RUN   TestRcatSizeChecksum/SizeDiffers
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:35:08 DEBUG : potato4: md5 = 6bcb6b612f156cdd169a72e92887732b OK
2026/10/10 03:35:08 DEBUG : potato4: size = 60 (Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate')
2026/10/10 03:35:08 DEBUG : potato4: size = 59 (Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate')
2026/10/10 03:35:08 ERROR : potato4: corrupted on transfer: sizes differ src 60 vs dst(Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate') 59
2026/10/10 03:35:08 INFO  : potato4: Removing failed copy
=== RUN   TestRcatSizeChecksum/IgnoreChecksum
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:35:10 DEBUG : potato2: size = 60 OK
=== RUN   TestRcatSizeChecksum/NoHashes
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:35:13 DEBUG : potato3: md5 = ea229acd4474aa1de32c37fec3bd75b8 OK
2026/10/10 03:35:13 DEBUG : potato3: size = 60 OK
--- PASS: TestRcatSizeChecksum (7.71s)
    --- SKIP: TestRcatSizeChecksum/Corrupted (0.44s)
    --- PASS: TestRcatSizeChecksum/SizeDiffers (2.64s)
    --- PASS: TestRcatSizeChecksum/IgnoreChecksum (2.29s)
    --- PASS: TestRcatSizeChecksum/NoHashes (2.34s)
=== RUN   TestTouchDir
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:35:15 DEBUG : potato2: md5 = 23c65d87291b13064db7502ac5c1a842 OK
2026/10/10 03:35:16 DEBUG : empty space: md5 = 892e635c17540e698c9044bd17a3ac53 OK
2026/10/10 03:35:19 DEBUG : sub dir/potato3: md5 = 135db6d1aeb04ba839615c0cd6bea3d8 OK
2026/10/10 03:35:20 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate': Touching "sub dir/potato3"
2026/10/10 03:35:20 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate': Touching "potato2"
2026/10/10 03:35:20 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate': Touching "empty space"
--- PASS: TestTouchDir (10.04s)
=== RUN   TestMkdirMetadata
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:35:24 DEBUG : dir with metadata: Making directory with metadata
2026/10/10 03:35:24 INFO  : dir with metadata: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z)
--- PASS: TestMkdirMetadata (2.62s)
=== RUN   TestMkdirModTime
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:35:26 DEBUG : directory with modtime: Making directory with metadata
2026/10/10 03:35:27 INFO  : directory with modtime: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2026/10/10 03:35:27 NOTICE: dry run directory with modtime: Skipped make directory as --dry-run is set
--- PASS: TestMkdirModTime (2.53s)
=== RUN   TestCopyDirMetadata
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:35:29 DEBUG : dir with metadata to be copied: Making directory with metadata
2026/10/10 03:35:29 INFO  : dir with metadata to be copied: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z)
2026/10/10 03:35:29 DEBUG : Google drive root 'crypt/dk13k2is7heuf9fqbv1qce710vcbjsoohqk6qu3gd00cahoe8f9g': Skipping btime metadata as can't update it on an existing file: 2026-10-10T03:35:29.33463197Z
2026/10/10 03:35:30 INFO  : non existent directory: Updated directory metadata
2026/10/10 03:35:32 DEBUG : Google drive root 'crypt/dk13k2is7heuf9fqbv1qce710vcbjsoohqk6qu3gd00cahoe8f9g': Skipping btime metadata as can't update it on an existing file: 2026-10-10T03:35:29.33463197Z
2026/10/10 03:35:32 INFO  : existing directory: Updated directory metadata
--- PASS: TestCopyDirMetadata (5.32s)
=== RUN   TestSetDirModTime
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:35:34 DEBUG : set modtime on non existent directory: Skipping set directory modification time as --no-update-dir-modtime is set
2026/10/10 03:35:36 INFO  : set modtime on existing directory: Set directory modification time (using SetModTime)
2026/10/10 03:35:37 INFO  : set modtime on existing directory: Set directory modification time (using DirSetModTime)
--- PASS: TestSetDirModTime (4.26s)
=== RUN   TestDirsEqual
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:35:38 DEBUG : dir with metadata to be copied: Making directory with metadata
2026/10/10 03:35:38 INFO  : dir with metadata to be copied: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z)
2026/10/10 03:35:38 DEBUG : dst: Making directory with metadata
2026/10/10 03:35:39 INFO  : dst: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z)
2026/10/10 03:35:39 DEBUG : dst: Directory modification time the same (differ by -999.999µs, within tolerance 1ms)
2026/10/10 03:35:40 INFO  : dst: Set directory modification time (using SetModTime)
2026/10/10 03:35:40 INFO  : dst: Set directory modification time (using SetModTime)
2026/10/10 03:35:40 DEBUG : dst: Directory modification time the same (differ by 1ns, within tolerance 1ms)
2026/10/10 03:35:41 INFO  : dst: Set directory modification time (using SetModTime)
2026/10/10 03:35:41 DEBUG : dst: Destination directory is newer than source, skipping
--- PASS: TestDirsEqual (3.93s)
=== RUN   TestRemoveExisting
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate'", Local "Local file system at /tmp/rclone2406082256", Modify Window "1ms"
2026/10/10 03:35:44 DEBUG : sub dir/test remove existing: md5 = 216d2e35fc89096b3c246118ce7c47ba OK
2026/10/10 03:35:46 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: md5 = a4c0f3dbe46142f33673b3c4fd45dd80 OK
2026/10/10 03:35:48 DEBUG : sub dir/test remove existing: TEST: renaming existing object to "sub dir/test remove existing.makiyog7" before starting
2026/10/10 03:35:49 DEBUG : sub dir/test remove existing.makiyog7: TEST: removing renamed existing file after operation
2026/10/10 03:35:50 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: TEST: renaming existing object to "sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.boqesef2" before starting
2026/10/10 03:35:51 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.boqesef2: TEST: renaming existing back after failed operation
2026/10/10 03:35:52 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: TEST: renaming existing object to "sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.hixuyem4" before starting
2026/10/10 03:35:53 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.hixuyem4: TEST: removing renamed existing file after operation
--- PASS: TestRemoveExisting (13.17s)
=== RUN   TestRcatInputFailurePreservesDestination
2026/10/10 03:35:56 DEBUG : Creating backend with remote "/tmp/TestRcatInputFailurePreservesDestination307021031/001"
--- PASS: TestRcatInputFailurePreservesDestination (0.00s)
=== RUN   TestRcAbout
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcAbout (0.00s)
=== RUN   TestRcCleanup
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcCleanup (0.00s)
=== RUN   TestRcCopyfile
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcCopyfile (0.00s)
=== RUN   TestRcCopyurl
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcCopyurl (0.00s)
=== RUN   TestRcDelete
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcDelete (0.00s)
=== RUN   TestRcDeletefile
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcDeletefile (0.00s)
=== RUN   TestRcList
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcList (0.00s)
=== RUN   TestRcStat
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcStat (0.00s)
=== RUN   TestRcSetTier
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcSetTier (0.00s)
=== RUN   TestRcSetTierFile
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcSetTierFile (0.00s)
=== RUN   TestRcMkdir
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcMkdir (0.00s)
=== RUN   TestRcMovefile
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcMovefile (0.00s)
=== RUN   TestRcPurge
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcPurge (0.00s)
=== RUN   TestRcRmdir
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcRmdir (0.00s)
=== RUN   TestRcRmdirs
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcRmdirs (0.00s)
=== RUN   TestRcSize
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcSize (0.00s)
=== RUN   TestRcPublicLink
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcPublicLink (0.00s)
=== RUN   TestRcFsInfo
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcFsInfo (0.00s)
=== RUN   TestUploadFile
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestUploadFile (0.00s)
=== RUN   TestRcCommand
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcCommand (0.00s)
=== RUN   TestRcDu
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcDu (0.00s)
=== RUN   TestRcCheck
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcCheck (0.00s)
=== RUN   TestRcHashsum
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcHashsum (0.00s)
=== RUN   TestRcHashsumSingleFile
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcHashsumSingleFile (0.00s)
=== RUN   TestRcHashsumFile
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcHashsumFile (0.00s)
=== RUN   TestRcGetFile
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcGetFile (0.00s)
PASS
2026/10/10 03:35:56 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jidazuy5pate': Purge remote
"./operations.test -test.v -test.timeout 1h0m0s -remote TestCryptDrive: -verbose" - Finished OK in 22m10.728373155s (try 1/5)