"./operations.test -test.v -test.timeout 1h0m0s -remote TestCryptDrive: -verbose" - Starting (try 1/5)
2026/10/29 03:14:30 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-hijuxav3rija"
2026/10/29 03:14:30 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/29 03:14:30 DEBUG : Creating backend with remote "TestDrive:crypt/ve8oicq2msl4onulkqnmhaivejssop9vu384tt4t4ggjqf4tf1og"
2026/10/29 03:14:31 DEBUG : Creating backend with remote "/tmp/rclone2749422086"
=== 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-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
    multithread_test.go:120: multithread writing not supported
--- SKIP: TestMultithreadCopy (0.21s)
=== RUN   TestMultithreadCopyAbort
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
    multithread_test.go:120: multithread writing not supported
--- SKIP: TestMultithreadCopyAbort (0.22s)
=== RUN   TestMultithreadCopyWriterAtErrors
=== RUN   TestMultithreadCopyWriterAtErrors/open/fatal=false
2026/10/29 03:14:31 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt
=== RUN   TestMultithreadCopyWriterAtErrors/open/fatal=true
2026/10/29 03:14:31 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt
=== RUN   TestMultithreadCopyWriterAtErrors/write/fatal=false
2026/10/29 03:14:31 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt
2026/10/29 03:14:31 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams
2026/10/29 03:14:31 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting
2026/10/29 03:14:31 DEBUG : file.txt: writing chunk 1
2026/10/29 03:14:31 DEBUG : file.txt: multi-thread copy: chunk 2/4 failed: multi-thread copy: failed to write chunk: disk full
2026/10/29 03:14:31 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting
2026/10/29 03:14:31 DEBUG : file.txt: writing chunk 2
2026/10/29 03:14:31 DEBUG : file.txt: multi-thread copy: chunk 3/4 failed: multi-thread copy: failed to write chunk: disk full
2026/10/29 03:14:31 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting
2026/10/29 03:14:31 DEBUG : file.txt: writing chunk 0
2026/10/29 03:14:31 DEBUG : file.txt: multi-thread copy: chunk 1/4 failed: multi-thread copy: failed to write chunk: disk full
2026/10/29 03:14:31 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit
2026/10/29 03:14:31 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/29 03:14:31 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt
2026/10/29 03:14:31 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams
2026/10/29 03:14:31 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting
2026/10/29 03:14:31 DEBUG : file.txt: writing chunk 1
2026/10/29 03:14:31 DEBUG : file.txt: multi-thread copy: chunk 2/4 failed: multi-thread copy: failed to write chunk: disk full
2026/10/29 03:14:31 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting
2026/10/29 03:14:31 DEBUG : file.txt: writing chunk 0
2026/10/29 03:14:31 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting
2026/10/29 03:14:31 DEBUG : file.txt: writing chunk 2
2026/10/29 03:14:31 DEBUG : file.txt: multi-thread copy: chunk 1/4 failed: multi-thread copy: failed to write chunk: disk full
2026/10/29 03:14:31 DEBUG : file.txt: multi-thread copy: chunk 3/4 failed: multi-thread copy: failed to write chunk: disk full
2026/10/29 03:14:31 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit
2026/10/29 03:14:31 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/29 03:14:31 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt
2026/10/29 03:14:31 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams
2026/10/29 03:14:31 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting
2026/10/29 03:14:31 DEBUG : file.txt: writing chunk 1
2026/10/29 03:14:31 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 finished
2026/10/29 03:14:31 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting
2026/10/29 03:14:31 DEBUG : file.txt: writing chunk 2
2026/10/29 03:14:31 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 finished
2026/10/29 03:14:31 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 starting
2026/10/29 03:14:31 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting
2026/10/29 03:14:31 DEBUG : file.txt: writing chunk 0
2026/10/29 03:14:31 DEBUG : file.txt: writing chunk 3
2026/10/29 03:14:31 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 finished
2026/10/29 03:14:31 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 finished
2026/10/29 03:14:31 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit
2026/10/29 03:14:31 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/29 03:14:31 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt
2026/10/29 03:14:31 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams
2026/10/29 03:14:31 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting
2026/10/29 03:14:31 DEBUG : file.txt: writing chunk 1
2026/10/29 03:14:31 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 finished
2026/10/29 03:14:31 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting
2026/10/29 03:14:31 DEBUG : file.txt: writing chunk 2
2026/10/29 03:14:31 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 finished
2026/10/29 03:14:31 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 starting
2026/10/29 03:14:31 DEBUG : file.txt: writing chunk 3
2026/10/29 03:14:31 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 finished
2026/10/29 03:14:31 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting
2026/10/29 03:14:31 DEBUG : file.txt: writing chunk 0
2026/10/29 03:14:31 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 finished
2026/10/29 03:14:31 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit
2026/10/29 03:14:31 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/29 03:14:31 DEBUG : a: size = 0 OK
2026/10/29 03:14:31 DEBUG : a: size = 1 (memory)
2026/10/29 03:14:31 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/29 03:14:31 DEBUG : potato: Seek from 10 to 0
=== RUN   TestReOpen/Normal/ErrorAtStart
=== RUN   TestReOpen/Normal/WithErrors
2026/10/29 03:14:31 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2026/10/29 03:14:31 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2026/10/29 03:14:31 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN   TestReOpen/Normal/TooManyErrors
2026/10/29 03:14:31 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2026/10/29 03:14:31 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2026/10/29 03:14:31 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2026/10/29 03:14:31 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN   TestReOpen/Normal/ReadAt
2026/10/29 03:14:31 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/10/29 03:14:31 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/10/29 03:14:31 DEBUG : potato: Seek from 5 to 1
=== RUN   TestReOpen/Normal/Seek
2026/10/29 03:14:31 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/10/29 03:14:31 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/10/29 03:14:31 DEBUG : potato: Seek from 5 to 2
=== RUN   TestReOpen/Normal/AccountRead
=== RUN   TestReOpen/Normal/AccountReadDelay
2026/10/29 03:14:31 DEBUG : potato: Seek from 10 to 0
2026/10/29 03:14:31 DEBUG : potato: Seek from 10 to 0
2026/10/29 03:14:31 DEBUG : potato: Seek from 10 to 0
=== RUN   TestReOpen/Normal/AccountReadError
=== RUN   TestReOpen/WithRangeOption
=== RUN   TestReOpen/WithRangeOption/Basics
2026/10/29 03:14:31 DEBUG : potato: Seek from 7 to 0
=== RUN   TestReOpen/WithRangeOption/ErrorAtStart
=== RUN   TestReOpen/WithRangeOption/WithErrors
2026/10/29 03:14:31 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2026/10/29 03:14:31 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2026/10/29 03:14:31 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN   TestReOpen/WithRangeOption/TooManyErrors
2026/10/29 03:14:31 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2026/10/29 03:14:31 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2026/10/29 03:14:31 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2026/10/29 03:14:31 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN   TestReOpen/WithRangeOption/ReadAt
2026/10/29 03:14:31 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/10/29 03:14:31 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/10/29 03:14:31 DEBUG : potato: Seek from 5 to 1
=== RUN   TestReOpen/WithRangeOption/Seek
2026/10/29 03:14:31 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/10/29 03:14:31 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/10/29 03:14:31 DEBUG : potato: Seek from 5 to 2
2026/10/29 03:14:31 DEBUG : potato: Seek from 7 to 4
=== RUN   TestReOpen/WithRangeOption/AccountRead
=== RUN   TestReOpen/WithRangeOption/AccountReadDelay
2026/10/29 03:14:31 DEBUG : potato: Seek from 7 to 0
2026/10/29 03:14:31 DEBUG : potato: Seek from 7 to 0
2026/10/29 03:14:31 DEBUG : potato: Seek from 7 to 0
=== RUN   TestReOpen/WithRangeOption/AccountReadError
=== RUN   TestReOpen/WithSeekOption
=== RUN   TestReOpen/WithSeekOption/Basics
2026/10/29 03:14:31 DEBUG : potato: Seek from 8 to 0
=== RUN   TestReOpen/WithSeekOption/ErrorAtStart
=== RUN   TestReOpen/WithSeekOption/WithErrors
2026/10/29 03:14:31 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2026/10/29 03:14:31 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2026/10/29 03:14:31 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN   TestReOpen/WithSeekOption/TooManyErrors
2026/10/29 03:14:31 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2026/10/29 03:14:31 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2026/10/29 03:14:31 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2026/10/29 03:14:31 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN   TestReOpen/WithSeekOption/ReadAt
2026/10/29 03:14:31 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/10/29 03:14:31 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/10/29 03:14:31 DEBUG : potato: Seek from 5 to 1
=== RUN   TestReOpen/WithSeekOption/Seek
2026/10/29 03:14:31 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/10/29 03:14:31 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/10/29 03:14:31 DEBUG : potato: Seek from 5 to 2
2026/10/29 03:14:31 DEBUG : potato: Seek from 7 to 5
=== RUN   TestReOpen/WithSeekOption/AccountRead
=== RUN   TestReOpen/WithSeekOption/AccountReadDelay
2026/10/29 03:14:31 DEBUG : potato: Seek from 8 to 0
2026/10/29 03:14:31 DEBUG : potato: Seek from 8 to 0
2026/10/29 03:14:31 DEBUG : potato: Seek from 8 to 0
=== RUN   TestReOpen/WithSeekOption/AccountReadError
=== RUN   TestReOpen/UnknownSize
=== RUN   TestReOpen/UnknownSize/Basics
2026/10/29 03:14:31 DEBUG : potato: Seek from 9 to 0
=== RUN   TestReOpen/UnknownSize/ErrorAtStart
=== RUN   TestReOpen/UnknownSize/WithErrors
2026/10/29 03:14:31 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2026/10/29 03:14:31 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2026/10/29 03:14:31 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN   TestReOpen/UnknownSize/TooManyErrors
2026/10/29 03:14:31 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2026/10/29 03:14:31 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2026/10/29 03:14:31 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2026/10/29 03:14:31 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN   TestReOpen/UnknownSize/ReadAt
2026/10/29 03:14:31 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/10/29 03:14:31 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/10/29 03:14:31 DEBUG : potato: Seek from 5 to 1
=== RUN   TestReOpen/UnknownSize/Seek
2026/10/29 03:14:31 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/10/29 03:14:31 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/10/29 03:14:31 DEBUG : potato: Seek from 5 to 2
2026/10/29 03:14:31 DEBUG : potato: Seek from 7 to 6
=== RUN   TestReOpen/UnknownSize/AccountRead
=== RUN   TestReOpen/UnknownSize/AccountReadDelay
2026/10/29 03:14:31 DEBUG : potato: Seek from 9 to 0
2026/10/29 03:14:31 DEBUG : potato: Seek from 9 to 0
2026/10/29 03:14:31 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-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:14:34 DEBUG : rutabaga: md5 = 9e9c8b5817f632e0bd5be61bf65c6340 OK
=== RUN   TestCheck/1
=== RUN   TestCheck/2
2026/10/29 03:14:36 DEBUG : empty space: md5 = ee3082739718ef1a0fe34f92e5cec10d OK
=== RUN   TestCheck/3
2026/10/29 03:14:38 DEBUG : potato2: md5 = d5f2b8e8c96e8735e7ff16043b4fa619 OK
=== RUN   TestCheck/4
=== RUN   TestCheck/5
2026/10/29 03:14:41 DEBUG : remotepotato: md5 = 8faf18112b036deb06bf75e5f61adca0 OK
=== RUN   TestCheck/6
=== RUN   TestCheck/7
--- PASS: TestCheck (13.26s)
    --- PASS: TestCheck/1 (0.27s)
    --- PASS: TestCheck/2 (0.25s)
    --- PASS: TestCheck/3 (0.26s)
    --- PASS: TestCheck/4 (0.26s)
    --- PASS: TestCheck/5 (0.28s)
    --- PASS: TestCheck/6 (0.27s)
    --- PASS: TestCheck/7 (0.29s)
=== RUN   TestCheckFsError
2026/10/29 03:14:45 DEBUG : Creating backend with remote "nonexistent"
2026/10/29 03:14:45 DEBUG : Creating backend with remote "nonexistent"
2026/10/29 03:14:45 DEBUG : Local file system at nonexistent: Waiting for checks to finish
2026/10/29 03:14:45 ERROR : Local file system at nonexistent: error reading source root directory: directory not found
2026/10/29 03:14:45 NOTICE: Local file system at nonexistent: 0 differences found
2026/10/29 03:14:45 NOTICE: Local file system at nonexistent: 2 errors while checking
--- PASS: TestCheckFsError (0.00s)
=== RUN   TestCheckDownload
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:14:46 DEBUG : rutabaga: md5 = 1a5f5d2d27988d5973418f2089b4435a OK
=== RUN   TestCheckDownload/1
=== RUN   TestCheckDownload/2
2026/10/29 03:14:50 DEBUG : empty space: md5 = 9cbcbcae0879383798162feac63402a8 OK
=== RUN   TestCheckDownload/3
2026/10/29 03:14:52 DEBUG : potato2: md5 = 913e565e8131ae1b592fcfa9a70c36cc OK
=== RUN   TestCheckDownload/4
=== RUN   TestCheckDownload/5
2026/10/29 03:14:56 DEBUG : remotepotato: md5 = 85ba8ab9e560e94262a590e1ee5addb4 OK
=== RUN   TestCheckDownload/6
=== RUN   TestCheckDownload/7
--- PASS: TestCheckDownload (15.44s)
    --- PASS: TestCheckDownload/1 (0.92s)
    --- PASS: TestCheckDownload/2 (0.88s)
    --- PASS: TestCheckDownload/3 (0.74s)
    --- PASS: TestCheckDownload/4 (0.92s)
    --- PASS: TestCheckDownload/5 (0.86s)
    --- PASS: TestCheckDownload/6 (0.82s)
    --- PASS: TestCheckDownload/7 (0.74s)
=== RUN   TestCheckSizeOnly
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:15:01 DEBUG : rutabaga: md5 = f08b6b1530e43c3fbad1e5892bd7d6fb OK
=== RUN   TestCheckSizeOnly/1
=== RUN   TestCheckSizeOnly/2
2026/10/29 03:15:04 DEBUG : empty space: md5 = 73838be596c16eaee5fe931adcf8fe21 OK
=== RUN   TestCheckSizeOnly/3
2026/10/29 03:15:06 DEBUG : potato2: md5 = d7ecca8572ce255242ceb23324e9b97a OK
=== RUN   TestCheckSizeOnly/4
=== RUN   TestCheckSizeOnly/5
2026/10/29 03:15:08 DEBUG : remotepotato: md5 = 5fd87fe9f83ac88e32097e6598292efa OK
=== RUN   TestCheckSizeOnly/6
=== RUN   TestCheckSizeOnly/7
--- PASS: TestCheckSizeOnly (11.81s)
    --- PASS: TestCheckSizeOnly/1 (0.35s)
    --- PASS: TestCheckSizeOnly/2 (0.25s)
    --- PASS: TestCheckSizeOnly/3 (0.27s)
    --- PASS: TestCheckSizeOnly/4 (0.25s)
    --- PASS: TestCheckSizeOnly/5 (0.26s)
    --- PASS: TestCheckSizeOnly/6 (0.26s)
    --- PASS: TestCheckSizeOnly/7 (0.26s)
=== RUN   TestCheckEqualReaders
--- PASS: TestCheckEqualReaders (0.00s)
=== RUN   TestParseSumFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:15:13 DEBUG : test.sum: md5 = 5dcded745d411934825188559c9f426a OK
2026/10/29 03:15:14 NOTICE: test.sum: improperly formatted checksum line 4
2026/10/29 03:15:14 NOTICE: test.sum: improperly formatted checksum line 5
2026/10/29 03:15:14 NOTICE: test.sum: improperly formatted checksum line 6
2026/10/29 03:15:14 NOTICE: test.sum: 2 warning(s) suppressed...
2026/10/29 03:15:16 DEBUG : test.sum: md5 = 6534e8525795f25e43feb4b17106ed69 OK
2026/10/29 03:15:17 NOTICE: test.sum: improperly formatted checksum line 4
2026/10/29 03:15:17 NOTICE: test.sum: improperly formatted checksum line 5
2026/10/29 03:15:17 NOTICE: test.sum: improperly formatted checksum line 6
2026/10/29 03:15:17 NOTICE: test.sum: 2 warning(s) suppressed...
--- PASS: TestParseSumFile (5.83s)
=== RUN   TestCheckSum
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:15:18 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-hijuxav3rija/data"
2026/10/29 03:15:18 DEBUG : Config file has changed externally - reloading
2026/10/29 03:15:18 DEBUG : Creating backend with remote "TestDrive:crypt/ve8oicq2msl4onulkqnmhaivejssop9vu384tt4t4ggjqf4tf1og/vjrnln8ratgmqakfosrqe8espk"
    check_test.go:354: Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija/data' lacks md5, skipping
--- SKIP: TestCheckSum (1.98s)
=== RUN   TestCheckSumDownload
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:15:20 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-hijuxav3rija/data"
2026/10/29 03:15:20 DEBUG : Creating backend with remote "TestDrive:crypt/ve8oicq2msl4onulkqnmhaivejssop9vu384tt4t4ggjqf4tf1og/vjrnln8ratgmqakfosrqe8espk"
2026/10/29 03:15:24 DEBUG : data/banana: md5 = 8e93ebc7c6926a4406ccff6067426f85 OK
2026/10/29 03:15:25 DEBUG : test.sum: md5 = fe6a60fb1d99f5b0d28568efd87842f1 OK
=== RUN   TestCheckSumDownload/subtest1
2026/10/29 03:15:29 DEBUG : data/potato: md5 = 89f5bc1ec7277f21a3c7520a43b367a6 OK
2026/10/29 03:15:31 DEBUG : test.sum: md5 = ca5616a0c4bf059420521c8d66237c51 OK
=== RUN   TestCheckSumDownload/subtest2
2026/10/29 03:15:35 DEBUG : test.sum: md5 = d0498d71f1edf5fc3647d3597345f30b OK
=== RUN   TestCheckSumDownload/subtest3
2026/10/29 03:15:38 DEBUG : test.sum: md5 = ad0911030a6e263024d0f1839bc61c58 OK
=== RUN   TestCheckSumDownload/subtest4
2026/10/29 03:15:42 DEBUG : test.sum: md5 = e8ba8e91e5e689ad7bb7fcf4313c3eac OK
=== RUN   TestCheckSumDownload/subtest5
2026/10/29 03:15:46 DEBUG : test.sum: md5 = 73e1c8e54a1de05c12fd9cf84600f973 OK
=== RUN   TestCheckSumDownload/subtest6
2026/10/29 03:15:50 DEBUG : data/banana: md5 = 66394e4002d355bb6ab847dcedb18c0e OK
2026/10/29 03:15:51 DEBUG : data/potato: md5 = bbfcef099ed76697522a76b536d01175 OK
2026/10/29 03:15:52 DEBUG : test.sum: md5 = 29bd3325692a9129a1262d2671f701f6 OK
=== RUN   TestCheckSumDownload/subtest7
--- PASS: TestCheckSumDownload (38.42s)
    --- PASS: TestCheckSumDownload/subtest1 (2.14s)
    --- PASS: TestCheckSumDownload/subtest2 (1.81s)
    --- PASS: TestCheckSumDownload/subtest3 (1.89s)
    --- PASS: TestCheckSumDownload/subtest4 (1.67s)
    --- PASS: TestCheckSumDownload/subtest5 (1.85s)
    --- PASS: TestCheckSumDownload/subtest6 (1.67s)
    --- PASS: TestCheckSumDownload/subtest7 (1.93s)
=== RUN   TestCheckSumConcurrency
2026/10/29 03:15:58 DEBUG : file0: md5 = 022eb0c0085c731f335df970fafa7446 OK
2026/10/29 03:15:58 DEBUG : file2: md5 = a58f09ae3db8ae4b943fdbaace096072 OK
2026/10/29 03:15:58 DEBUG : file1: md5 = 17fbabbccbdea0e6be9eb64c8c6fea41 OK
2026/10/29 03:15:58 DEBUG : file3: md5 = e0639b17e0744aca9a3ba29c049e59ee OK
2026/10/29 03:15:58 NOTICE: Mock file system at checkSum: 0 differences found
2026/10/29 03:15:58 NOTICE: Mock file system at checkSum: 4 matching files
--- PASS: TestCheckSumConcurrency (0.00s)
=== RUN   TestCheckSumDownloadConcurrency
2026/10/29 03:15:58 DEBUG : file0: md5 = 022eb0c0085c731f335df970fafa7446 OK
2026/10/29 03:15:58 DEBUG : file2: md5 = a58f09ae3db8ae4b943fdbaace096072 OK
2026/10/29 03:15:58 DEBUG : file1: md5 = 17fbabbccbdea0e6be9eb64c8c6fea41 OK
2026/10/29 03:15:58 DEBUG : file3: md5 = e0639b17e0744aca9a3ba29c049e59ee OK
2026/10/29 03:15:58 NOTICE: Mock file system at checkSum: 0 differences found
2026/10/29 03:15:58 NOTICE: Mock file system at checkSum: 4 matching files
--- PASS: TestCheckSumDownloadConcurrency (0.00s)
=== RUN   TestApplyTransforms
2026/10/29 03:15:58 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-mahuvoh7doli"
2026/10/29 03:15:58 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/29 03:15:58 DEBUG : Creating backend with remote "TestDrive:crypt/f0den0hsgqir5e01soconk6v8tfflb9j66n5q1d95oppv29ckn50"
2026/10/29 03:15:59 DEBUG : Creating backend with remote "/tmp/rclone2199233021"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mahuvoh7doli'", Local "Local file system at /tmp/rclone2199233021", Modify Window "1ms"
2026/10/29 03:16:01 DEBUG : hello, world!: md5 = bae7bbcf5d24c99070fdfaa7a7cecee1 OK
upper checkfile vs. lower remote (without normalization)
2026/10/29 03:16:02 ERROR : hello, world!: sum not found
2026/10/29 03:16:02 ERROR : HELLO, WORLD!: file not in Encrypted drive 'TestCryptDrive:rclone-test-mahuvoh7doli'
2026/10/29 03:16:02 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-mahuvoh7doli': 1 files missing
2026/10/29 03:16:02 NOTICE: 1 hashes missing
2026/10/29 03:16:02 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-mahuvoh7doli': 1 differences found
2026/10/29 03:16:02 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-mahuvoh7doli': 2 errors while checking
upper checkfile vs. lower remote (with normalization)
2026/10/29 03:16:03 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/29 03:16:03 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-mahuvoh7doli': 0 differences found
2026/10/29 03:16:03 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-mahuvoh7doli': 1 matching files
2026/10/29 03:16:03 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-tiyijag0cuve"
2026/10/29 03:16:03 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/29 03:16:03 DEBUG : Creating backend with remote "TestDrive:crypt/mkjsn8svesgrnd1gfqeh1bt6kun2jin77q7mocj2js0ok0kub6cg"
2026/10/29 03:16:04 DEBUG : Creating backend with remote "/tmp/rclone3009937692"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tiyijag0cuve'", Local "Local file system at /tmp/rclone3009937692", Modify Window "1ms"
2026/10/29 03:16:06 DEBUG : HELLO, WORLD!: md5 = 858d25dc23443b76df7c93f3680f1be7 OK
lower checkfile vs. upper remote (without normalization)
2026/10/29 03:16:07 ERROR : HELLO, WORLD!: sum not found
2026/10/29 03:16:07 ERROR : hello, world!: file not in Encrypted drive 'TestCryptDrive:rclone-test-tiyijag0cuve'
2026/10/29 03:16:07 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-tiyijag0cuve': 1 files missing
2026/10/29 03:16:07 NOTICE: 1 hashes missing
2026/10/29 03:16:07 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-tiyijag0cuve': 1 differences found
2026/10/29 03:16:07 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-tiyijag0cuve': 2 errors while checking
lower checkfile vs. upper remote (with normalization)
2026/10/29 03:16:08 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/29 03:16:08 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-tiyijag0cuve': 0 differences found
2026/10/29 03:16:08 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-tiyijag0cuve': 1 matching files
2026/10/29 03:16:08 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-xepoxos9ruzu"
2026/10/29 03:16:08 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/29 03:16:08 DEBUG : Creating backend with remote "TestDrive:crypt/u2e50bq4selt4mjrltkj52152t7c638870ldgstpi20m5sci8h6g"
2026/10/29 03:16:09 DEBUG : Creating backend with remote "/tmp/rclone2359218756"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-xepoxos9ruzu'", Local "Local file system at /tmp/rclone2359218756", Modify Window "1ms"
2026/10/29 03:16:11 DEBUG : HeLlO, wOrLd!: md5 = a22b607be328d119726dbc1702b9824f OK
lower checkfile vs. upperlowermixed remote (without normalization)
2026/10/29 03:16:12 ERROR : HeLlO, wOrLd!: sum not found
2026/10/29 03:16:12 ERROR : hello, world!: file not in Encrypted drive 'TestCryptDrive:rclone-test-xepoxos9ruzu'
2026/10/29 03:16:12 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-xepoxos9ruzu': 1 files missing
2026/10/29 03:16:12 NOTICE: 1 hashes missing
2026/10/29 03:16:12 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-xepoxos9ruzu': 1 differences found
2026/10/29 03:16:12 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-xepoxos9ruzu': 2 errors while checking
lower checkfile vs. upperlowermixed remote (with normalization)
2026/10/29 03:16:13 DEBUG : HeLlO, wOrLd!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/29 03:16:13 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-xepoxos9ruzu': 0 differences found
2026/10/29 03:16:13 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-xepoxos9ruzu': 1 matching files
2026/10/29 03:16:13 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-yaqagaw6mevo"
2026/10/29 03:16:13 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/29 03:16:13 DEBUG : Creating backend with remote "TestDrive:crypt/6pgcp0nfq2bvhib2u6rjc0q0ajn9aa45kve9ooeg3gsi9naaefl0"
2026/10/29 03:16:14 DEBUG : Creating backend with remote "/tmp/rclone3629788914"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-yaqagaw6mevo'", Local "Local file system at /tmp/rclone3629788914", Modify Window "1ms"
2026/10/29 03:16:16 DEBUG : HELLO, WORLD!: md5 = dcba10098c62394a6ed49f8b331641e5 OK
upperlowermixed checkfile vs. upper remote (without normalization)
2026/10/29 03:16:16 ERROR : HELLO, WORLD!: sum not found
2026/10/29 03:16:16 ERROR : HeLlO, wOrLd!: file not in Encrypted drive 'TestCryptDrive:rclone-test-yaqagaw6mevo'
2026/10/29 03:16:16 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-yaqagaw6mevo': 1 files missing
2026/10/29 03:16:16 NOTICE: 1 hashes missing
2026/10/29 03:16:16 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-yaqagaw6mevo': 1 differences found
2026/10/29 03:16:16 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-yaqagaw6mevo': 2 errors while checking
upperlowermixed checkfile vs. upper remote (with normalization)
2026/10/29 03:16:17 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/29 03:16:17 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-yaqagaw6mevo': 0 differences found
2026/10/29 03:16:17 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-yaqagaw6mevo': 1 matching files
2026/10/29 03:16:17 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-sogubux0keci"
2026/10/29 03:16:17 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/29 03:16:17 DEBUG : Creating backend with remote "TestDrive:crypt/vhbkdq8tirpm7rit6rfij9k359g0d2a2gha51ol4fm7r7oa3f4r0"
2026/10/29 03:16:18 DEBUG : Creating backend with remote "/tmp/rclone1394998408"
2026/10/29 03:16:18 DEBUG : Config file has changed externally - reloading
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-sogubux0keci'", Local "Local file system at /tmp/rclone1394998408", Modify Window "1ms"
2026/10/29 03:16:21 DEBUG : 測試_Русский___ě_áñ: md5 = 92923c90339a6143781b4f5ee1894d1a OK
NFD checkfile vs. NFC remote (without normalization)
2026/10/29 03:16:21 ERROR : 測試_Русский___ě_áñ: sum not found
2026/10/29 03:16:21 ERROR : 測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-sogubux0keci'
2026/10/29 03:16:21 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-sogubux0keci': 1 files missing
2026/10/29 03:16:21 NOTICE: 1 hashes missing
2026/10/29 03:16:21 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-sogubux0keci': 1 differences found
2026/10/29 03:16:21 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-sogubux0keci': 2 errors while checking
NFD checkfile vs. NFC remote (with normalization)
2026/10/29 03:16:22 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/29 03:16:22 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-sogubux0keci': 0 differences found
2026/10/29 03:16:22 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-sogubux0keci': 1 matching files
2026/10/29 03:16:22 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-niyiwud4vaqo"
2026/10/29 03:16:22 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/29 03:16:22 DEBUG : Creating backend with remote "TestDrive:crypt/lkk41mu0360ke38camnrdrrnkidbrrslc2bj5nujqen2j5r8uhh0"
2026/10/29 03:16:23 DEBUG : Creating backend with remote "/tmp/rclone990949779"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-niyiwud4vaqo'", Local "Local file system at /tmp/rclone990949779", Modify Window "1ms"
2026/10/29 03:16:26 DEBUG : 測試_Русский___ě_áñ: md5 = ff19ada1a5f290848679ef1b337e9924 OK
NFC checkfile vs. NFD remote (without normalization)
2026/10/29 03:16:26 ERROR : 測試_Русский___ě_áñ: sum not found
2026/10/29 03:16:26 ERROR : 測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-niyiwud4vaqo'
2026/10/29 03:16:26 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-niyiwud4vaqo': 1 files missing
2026/10/29 03:16:26 NOTICE: 1 hashes missing
2026/10/29 03:16:26 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-niyiwud4vaqo': 1 differences found
2026/10/29 03:16:26 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-niyiwud4vaqo': 2 errors while checking
NFC checkfile vs. NFD remote (with normalization)
2026/10/29 03:16:27 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/29 03:16:27 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-niyiwud4vaqo': 0 differences found
2026/10/29 03:16:27 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-niyiwud4vaqo': 1 matching files
2026/10/29 03:16:27 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-duxucat0xuna"
2026/10/29 03:16:27 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/29 03:16:27 DEBUG : Creating backend with remote "TestDrive:crypt/rr08lumgk4l5s1fu7rcfqhqsmgedkb24pa8o6b0sesspjkqm4bi0"
2026/10/29 03:16:28 DEBUG : Creating backend with remote "/tmp/rclone635500555"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-duxucat0xuna'", Local "Local file system at /tmp/rclone635500555", Modify Window "1ms"
2026/10/29 03:16:30 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 0bc90944ecd4dad5fb02a918423750d8 OK
NFDx2 checkfile vs. both remote (without normalization)
2026/10/29 03:16:31 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found
2026/10/29 03:16:31 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-duxucat0xuna'
2026/10/29 03:16:31 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-duxucat0xuna': 1 files missing
2026/10/29 03:16:31 NOTICE: 1 hashes missing
2026/10/29 03:16:31 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-duxucat0xuna': 1 differences found
2026/10/29 03:16:31 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-duxucat0xuna': 2 errors while checking
NFDx2 checkfile vs. both remote (with normalization)
2026/10/29 03:16:32 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/29 03:16:32 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-duxucat0xuna': 0 differences found
2026/10/29 03:16:32 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-duxucat0xuna': 1 matching files
2026/10/29 03:16:32 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-kayehij1zodu"
2026/10/29 03:16:32 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/29 03:16:32 DEBUG : Creating backend with remote "TestDrive:crypt/96329qm39oe0tpnhliggq1gr5jgou0keuks6upiavtde8rsiicug"
2026/10/29 03:16:33 DEBUG : Creating backend with remote "/tmp/rclone952088906"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kayehij1zodu'", Local "Local file system at /tmp/rclone952088906", Modify Window "1ms"
2026/10/29 03:16:35 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = cca46dcad5a92b4a6de289335865f44c OK
NFCx2 checkfile vs. both remote (without normalization)
2026/10/29 03:16:36 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found
2026/10/29 03:16:36 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-kayehij1zodu'
2026/10/29 03:16:36 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kayehij1zodu': 1 files missing
2026/10/29 03:16:36 NOTICE: 1 hashes missing
2026/10/29 03:16:36 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kayehij1zodu': 1 differences found
2026/10/29 03:16:36 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kayehij1zodu': 2 errors while checking
NFCx2 checkfile vs. both remote (with normalization)
2026/10/29 03:16:37 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/29 03:16:37 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kayehij1zodu': 0 differences found
2026/10/29 03:16:37 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kayehij1zodu': 1 matching files
2026/10/29 03:16:37 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-nuxuvuk8nusu"
2026/10/29 03:16:37 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/29 03:16:37 DEBUG : Creating backend with remote "TestDrive:crypt/5aml93lc83kd63pkfns7f91kic9jenntdn7u01f87nc9ums77t20"
2026/10/29 03:16:38 DEBUG : Creating backend with remote "/tmp/rclone870184843"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-nuxuvuk8nusu'", Local "Local file system at /tmp/rclone870184843", Modify Window "1ms"
2026/10/29 03:16:40 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = bc6c520945801c065a4d8849d997ee9f OK
both checkfile vs. NFDx2 remote (without normalization)
2026/10/29 03:16:41 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found
2026/10/29 03:16:41 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-nuxuvuk8nusu'
2026/10/29 03:16:41 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-nuxuvuk8nusu': 1 files missing
2026/10/29 03:16:41 NOTICE: 1 hashes missing
2026/10/29 03:16:41 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-nuxuvuk8nusu': 1 differences found
2026/10/29 03:16:41 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-nuxuvuk8nusu': 2 errors while checking
both checkfile vs. NFDx2 remote (with normalization)
2026/10/29 03:16:41 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/29 03:16:41 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-nuxuvuk8nusu': 0 differences found
2026/10/29 03:16:41 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-nuxuvuk8nusu': 1 matching files
2026/10/29 03:16:41 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jinoqad6deli"
2026/10/29 03:16:41 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/29 03:16:41 DEBUG : Creating backend with remote "TestDrive:crypt/btkbeg3u8pov35u32jqmumbfk09ouim8bobct8h23arrfdgknhr0"
2026/10/29 03:16:43 DEBUG : Creating backend with remote "/tmp/rclone4208738325"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jinoqad6deli'", Local "Local file system at /tmp/rclone4208738325", Modify Window "1ms"
2026/10/29 03:16:45 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = ba077db5401f0cf2f781308e4086fcf2 OK
both checkfile vs. NFCx2 remote (without normalization)
2026/10/29 03:16:45 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found
2026/10/29 03:16:45 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-jinoqad6deli'
2026/10/29 03:16:45 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-jinoqad6deli': 1 files missing
2026/10/29 03:16:45 NOTICE: 1 hashes missing
2026/10/29 03:16:45 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-jinoqad6deli': 1 differences found
2026/10/29 03:16:45 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-jinoqad6deli': 2 errors while checking
both checkfile vs. NFCx2 remote (with normalization)
2026/10/29 03:16:46 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/29 03:16:46 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-jinoqad6deli': 0 differences found
2026/10/29 03:16:46 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-jinoqad6deli': 1 matching files
2026/10/29 03:16:46 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jinoqad6deli': Purge remote
2026/10/29 03:16:47 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-nuxuvuk8nusu': Purge remote
2026/10/29 03:16:47 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-kayehij1zodu': Purge remote
2026/10/29 03:16:48 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-duxucat0xuna': Purge remote
2026/10/29 03:16:48 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-niyiwud4vaqo': Purge remote
2026/10/29 03:16:49 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-sogubux0keci': Purge remote
2026/10/29 03:16:49 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-yaqagaw6mevo': Purge remote
2026/10/29 03:16:50 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-xepoxos9ruzu': Purge remote
2026/10/29 03:16:51 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-tiyijag0cuve': Purge remote
2026/10/29 03:16:51 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-mahuvoh7doli': Purge remote
--- PASS: TestApplyTransforms (53.67s)
=== RUN   TestTruncateString
--- PASS: TestTruncateString (0.00s)
=== RUN   TestCopyFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:16:52 DEBUG : file1: Need to transfer - File not found at Destination
2026/10/29 03:16:54 DEBUG : sub/file2: md5 = 3a5619eca3ff6ee2b82370d77c166be2 OK
2026/10/29 03:16:54 DEBUG : sub/file2: size = 14 OK
2026/10/29 03:16:54 INFO  : file1: Copied (new) to: sub/file2
2026/10/29 03:16:55 DEBUG : sub/file2: size = 14 OK
2026/10/29 03:16:55 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2026/10/29 03:16:55 DEBUG : file1: Unchanged skipping
2026/10/29 03:16:55 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija': don't need to copy/move sub/file2, it is already at target location
--- PASS: TestCopyFile (6.40s)
=== RUN   TestCopyFileImmutable
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:16:58 DEBUG : existing: Need to transfer - File not found at Destination
2026/10/29 03:17:00 DEBUG : existing: md5 = fd8e27b8f372601af7e1b15ba369da7b OK
2026/10/29 03:17:00 DEBUG : existing: size = 6 OK
2026/10/29 03:17:00 INFO  : existing: Copied (new)
2026/10/29 03:17:00 DEBUG : existing: size = 6 OK
2026/10/29 03:17:00 DEBUG : existing: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2026/10/29 03:17:00 DEBUG : existing: Unchanged skipping
2026/10/29 03:17:01 DEBUG : existing: size = 8 (Local file system at /tmp/rclone2749422086)
2026/10/29 03:17:01 DEBUG : existing: size = 6 (Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija')
2026/10/29 03:17:01 DEBUG : existing: Sizes differ
2026/10/29 03:17:01 ERROR : existing: Source and destination exist but do not match: immutable file modified
--- PASS: TestCopyFileImmutable (4.43s)
=== RUN   TestCopyLongFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
    copy_test.go:188: Test only runs on local
--- SKIP: TestCopyLongFile (0.47s)
=== RUN   TestCopyFileBackupDir
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:17:05 DEBUG : dst/file1: md5 = 581fcb7897af3afe4fb0c57c197eb391 OK
2026/10/29 03:17:06 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-hijuxav3rija/backup"
2026/10/29 03:17:06 DEBUG : Config file has changed externally - reloading
2026/10/29 03:17:06 DEBUG : Creating backend with remote "TestDrive:crypt/ve8oicq2msl4onulkqnmhaivejssop9vu384tt4t4ggjqf4tf1og/1nrff024r7pq65ecp72fc28jb0"
2026/10/29 03:17:08 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone2749422086)
2026/10/29 03:17:08 DEBUG : dst/file1: size = 18 (Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija')
2026/10/29 03:17:08 DEBUG : dst/file1: Sizes differ
2026/10/29 03:17:10 INFO  : dst/file1: Moved (server-side)
2026/10/29 03:17:12 DEBUG : dst/file1: md5 = 5c9020d3817c3973245de301f20bdce4 OK
2026/10/29 03:17:12 DEBUG : dst/file1: size = 14 OK
2026/10/29 03:17:12 INFO  : dst/file1: Copied (new)
--- PASS: TestCopyFileBackupDir (13.97s)
=== RUN   TestCopyFileCompareDest
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:17:17 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-hijuxav3rija/dst"
2026/10/29 03:17:17 DEBUG : Creating backend with remote "TestDrive:crypt/ve8oicq2msl4onulkqnmhaivejssop9vu384tt4t4ggjqf4tf1og/31u3jie661vd5p8j7rtc3hgbh0"
2026/10/29 03:17:19 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-hijuxav3rija/CompareDest"
2026/10/29 03:17:19 DEBUG : Config file has changed externally - reloading
2026/10/29 03:17:19 DEBUG : Creating backend with remote "TestDrive:crypt/ve8oicq2msl4onulkqnmhaivejssop9vu384tt4t4ggjqf4tf1og/gveqi14airsml4bgu7krj116o8"
2026/10/29 03:17:20 DEBUG : one: Need to transfer - File not found at Destination
2026/10/29 03:17:23 DEBUG : one: md5 = 0225a222ce0b61a49abab8fc19a29558 OK
2026/10/29 03:17:23 DEBUG : one: size = 3 OK
2026/10/29 03:17:23 INFO  : one: Copied (new)
2026/10/29 03:17:24 DEBUG : one: size = 5 (Local file system at /tmp/rclone2749422086)
2026/10/29 03:17:24 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija/dst')
2026/10/29 03:17:24 DEBUG : one: Sizes differ
2026/10/29 03:17:26 DEBUG : one: md5 = 0f4ed456fdfa4fccfd2494fc399c3945 OK
2026/10/29 03:17:26 DEBUG : one: size = 5 OK
2026/10/29 03:17:26 INFO  : one: Copied (replaced existing)
2026/10/29 03:17:28 DEBUG : dst/one: md5 = 727057114f79cbe314584d96fb047032 OK
2026/10/29 03:17:30 DEBUG : CompareDest/one: md5 = 8e682f24354ebbcd97f39d18080920a8 OK
2026/10/29 03:17:31 DEBUG : one: size = 5 (Local file system at /tmp/rclone2749422086)
2026/10/29 03:17:31 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija/dst')
2026/10/29 03:17:31 DEBUG : one: Sizes differ
2026/10/29 03:17:32 DEBUG : one: size = 5 OK
2026/10/29 03:17:32 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/10/29 03:17:32 DEBUG : one: Destination found in --compare-dest, skipping
2026/10/29 03:17:34 DEBUG : CompareDest/two: md5 = 8e41f7ad20716ba95a4af9d34f2e5997 OK
2026/10/29 03:17:34 DEBUG : two: Need to transfer - File not found at Destination
2026/10/29 03:17:35 DEBUG : two: size = 3 OK
2026/10/29 03:17:35 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/10/29 03:17:35 DEBUG : two: Destination found in --compare-dest, skipping
2026/10/29 03:17:35 DEBUG : two: Need to transfer - File not found at Destination
2026/10/29 03:17:36 DEBUG : two: size = 3 OK
2026/10/29 03:17:36 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/10/29 03:17:36 DEBUG : two: Destination found in --compare-dest, skipping
2026/10/29 03:17:37 DEBUG : two: Need to transfer - File not found at Destination
2026/10/29 03:17:37 DEBUG : two: size = 5 (Local file system at /tmp/rclone2749422086)
2026/10/29 03:17:37 DEBUG : two: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija/CompareDest')
2026/10/29 03:17:37 DEBUG : two: Sizes differ
2026/10/29 03:17:39 DEBUG : two: md5 = d48f8b6bbee59b62d08819a1f838c85b OK
2026/10/29 03:17:39 DEBUG : two: size = 5 OK
2026/10/29 03:17:39 INFO  : two: Copied (new)
--- PASS: TestCopyFileCompareDest (26.66s)
=== RUN   TestCopyFileCopyDestImmutable
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:17:44 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-hijuxav3rija/dst"
2026/10/29 03:17:44 DEBUG : Creating backend with remote "TestDrive:crypt/ve8oicq2msl4onulkqnmhaivejssop9vu384tt4t4ggjqf4tf1og/31u3jie661vd5p8j7rtc3hgbh0"
2026/10/29 03:17:47 DEBUG : dst/one: md5 = e9104644f50b082985f98625cd610bbe OK
2026/10/29 03:17:50 DEBUG : CopyDest/one: md5 = aea14b9ed07612a8bc18d96431f50906 OK
2026/10/29 03:17:51 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-hijuxav3rija/CopyDest"
2026/10/29 03:17:51 DEBUG : Config file has changed externally - reloading
2026/10/29 03:17:51 DEBUG : Creating backend with remote "TestDrive:crypt/ve8oicq2msl4onulkqnmhaivejssop9vu384tt4t4ggjqf4tf1og/d09o6po3f7bm6ce32vdgs8h9ls"
2026/10/29 03:17:52 DEBUG : one: size = 5 (Local file system at /tmp/rclone2749422086)
2026/10/29 03:17:52 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija/dst')
2026/10/29 03:17:52 DEBUG : one: Sizes differ
2026/10/29 03:17:52 DEBUG : one: size = 5 OK
2026/10/29 03:17:52 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/10/29 03:17:52 DEBUG : one: size = 5 (Local file system at /tmp/rclone2749422086)
2026/10/29 03:17:52 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija/dst')
2026/10/29 03:17:52 DEBUG : one: Sizes differ
2026/10/29 03:17:52 ERROR : one: Source and destination exist but do not match: immutable file modified
--- PASS: TestCopyFileCopyDestImmutable (12.45s)
=== RUN   TestCopyFileCopyDest
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:17:56 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-hijuxav3rija/dst"
2026/10/29 03:17:56 DEBUG : Creating backend with remote "TestDrive:crypt/ve8oicq2msl4onulkqnmhaivejssop9vu384tt4t4ggjqf4tf1og/31u3jie661vd5p8j7rtc3hgbh0"
2026/10/29 03:17:58 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-hijuxav3rija/CopyDest"
2026/10/29 03:17:58 DEBUG : Creating backend with remote "TestDrive:crypt/ve8oicq2msl4onulkqnmhaivejssop9vu384tt4t4ggjqf4tf1og/d09o6po3f7bm6ce32vdgs8h9ls"
2026/10/29 03:17:59 DEBUG : one: Need to transfer - File not found at Destination
2026/10/29 03:18:02 DEBUG : one: md5 = cbee2a02eae5e56e11bbd0b50fa29f3e OK
2026/10/29 03:18:02 DEBUG : one: size = 3 OK
2026/10/29 03:18:02 INFO  : one: Copied (new)
2026/10/29 03:18:03 DEBUG : one: size = 5 (Local file system at /tmp/rclone2749422086)
2026/10/29 03:18:03 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija/dst')
2026/10/29 03:18:03 DEBUG : one: Sizes differ
2026/10/29 03:18:05 DEBUG : one: md5 = 987464f4debbe85dbf4ff00fdbe3af86 OK
2026/10/29 03:18:05 DEBUG : one: size = 5 OK
2026/10/29 03:18:05 INFO  : one: Copied (replaced existing)
2026/10/29 03:18:07 DEBUG : dst/one: md5 = d5b77c52cde067b41d7446b4708a736b OK
2026/10/29 03:18:09 DEBUG : CopyDest/one: md5 = ec5cb0fbb51a9a205bbccbeddeebf38b OK
2026/10/29 03:18:10 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-hijuxav3rija/BackupDir"
2026/10/29 03:18:10 DEBUG : Creating backend with remote "TestDrive:crypt/ve8oicq2msl4onulkqnmhaivejssop9vu384tt4t4ggjqf4tf1og/s6dbk3lfi7c9kfvo6j7bla9m0g"
2026/10/29 03:18:12 DEBUG : one: size = 5 (Local file system at /tmp/rclone2749422086)
2026/10/29 03:18:12 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija/dst')
2026/10/29 03:18:12 DEBUG : one: Sizes differ
2026/10/29 03:18:12 DEBUG : one: size = 5 OK
2026/10/29 03:18:12 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/10/29 03:18:12 DEBUG : one: size = 5 (Local file system at /tmp/rclone2749422086)
2026/10/29 03:18:12 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija/dst')
2026/10/29 03:18:12 DEBUG : one: Sizes differ
2026/10/29 03:18:14 INFO  : one: Moved (server-side)
2026/10/29 03:18:15 DEBUG : one: size = 5 OK
2026/10/29 03:18:15 INFO  : one: Copied (server-side copy)
2026/10/29 03:18:15 DEBUG : one: Destination found in --copy-dest, using server-side copy
2026/10/29 03:18:17 DEBUG : CopyDest/two: md5 = 016979ed33d96fc59164d6da29375d16 OK
2026/10/29 03:18:18 DEBUG : two: Need to transfer - File not found at Destination
2026/10/29 03:18:18 DEBUG : two: size = 3 OK
2026/10/29 03:18:18 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/10/29 03:18:19 DEBUG : two: size = 3 OK
2026/10/29 03:18:19 INFO  : two: Copied (server-side copy)
2026/10/29 03:18:19 DEBUG : two: Destination found in --copy-dest, using server-side copy
2026/10/29 03:18:20 DEBUG : two: size = 3 OK
2026/10/29 03:18:20 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/10/29 03:18:20 DEBUG : two: Unchanged skipping
2026/10/29 03:18:22 DEBUG : CopyDest/three: md5 = 692befe7578855a4fd5bdba89ed986ec OK
2026/10/29 03:18:23 DEBUG : three: Need to transfer - File not found at Destination
2026/10/29 03:18:23 DEBUG : three: size = 7 (Local file system at /tmp/rclone2749422086)
2026/10/29 03:18:23 DEBUG : three: size = 5 (Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija/CopyDest')
2026/10/29 03:18:23 DEBUG : three: Sizes differ
2026/10/29 03:18:23 DEBUG : three: Destination not found in --copy-dest
2026/10/29 03:18:25 DEBUG : three: md5 = c2784de246eb20b3b887eb6668e1873b OK
2026/10/29 03:18:25 DEBUG : three: size = 7 OK
2026/10/29 03:18:25 INFO  : three: Copied (new)
--- PASS: TestCopyFileCopyDest (35.77s)
=== RUN   TestCopyInplace
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
    copy_test.go:434: Partial uploads not supported
--- SKIP: TestCopyInplace (0.45s)
=== RUN   TestCopyLongFileName
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
    copy_test.go:467: Partial uploads not supported
--- SKIP: TestCopyLongFileName (0.46s)
=== RUN   TestCopyLongFileNameCollision
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
    copy_test.go:500: Partial uploads not supported
--- SKIP: TestCopyLongFileNameCollision (0.41s)
=== RUN   TestCopyFileMaxTransfer
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:18:33 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination
2026/10/29 03:18:36 DEBUG : TestCopyFileMaxTransfer/file1: md5 = 954837914944dde149c2bf28e6c4db73 OK
2026/10/29 03:18:36 DEBUG : TestCopyFileMaxTransfer/file1: size = 14 OK
2026/10/29 03:18:36 INFO  : TestCopyFileMaxTransfer/file1: Copied (new)
2026/10/29 03:18:37 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination
2026/10/29 03:18:37 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/29 03:18:38 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination
2026/10/29 03:18:39 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination
2026/10/29 03:18:40 DEBUG : TestCopyFileMaxTransfer/file4: md5 = 8db8b4b68b96dca2ada7c6777c4a7e9e OK
2026/10/29 03:18:40 DEBUG : TestCopyFileMaxTransfer/file4: size = 2062 OK
2026/10/29 03:18:40 INFO  : TestCopyFileMaxTransfer/file4: Copied (new)
--- PASS: TestCopyFileMaxTransfer (10.19s)
=== RUN   TestDeduplicateInteractive
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
    dedupe_test.go:37: Can't run this test without a hash
--- SKIP: TestDeduplicateInteractive (0.47s)
=== RUN   TestDeduplicateSkip
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:18:47 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija': Looking for duplicate names using skip mode.
2026/10/29 03:18:47 NOTICE: one: Found 2 files with duplicate names
2026/10/29 03:18:47 NOTICE: one: Skipping 2 files with duplicate names
--- PASS: TestDeduplicateSkip (5.03s)
=== RUN   TestDeduplicateSizeOnly
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:18:53 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija': Looking for duplicate names using skip mode.
2026/10/29 03:18:54 NOTICE: one: Found 3 files with duplicate names
2026/10/29 03:18:54 NOTICE: one: Deleting 1/2 identical duplicates (size 11)
2026/10/29 03:18:54 INFO  : one: Deleted
2026/10/29 03:18:54 NOTICE: one: Skipping 2 files with duplicate names
--- PASS: TestDeduplicateSizeOnly (7.13s)
=== RUN   TestDeduplicateFirst
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:19:00 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija': Looking for duplicate names using first mode.
2026/10/29 03:19:01 NOTICE: one: Found 3 files with duplicate names
2026/10/29 03:19:01 INFO  : one: Deleted
2026/10/29 03:19:02 INFO  : one: Deleted
2026/10/29 03:19:02 NOTICE: one: Deleted 2 extra copies
--- PASS: TestDeduplicateFirst (7.16s)
=== RUN   TestDeduplicateNewest
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:19:07 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija': Looking for duplicate names using newest mode.
2026/10/29 03:19:08 NOTICE: one: Found 3 files with duplicate names
2026/10/29 03:19:08 INFO  : one: Deleted
2026/10/29 03:19:09 INFO  : one: Deleted
2026/10/29 03:19:09 NOTICE: one: Deleted 2 extra copies
--- PASS: TestDeduplicateNewest (7.50s)
=== RUN   TestDeduplicateNewestByHash
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
    dedupe_test.go:37: Can't run this test without a hash
--- SKIP: TestDeduplicateNewestByHash (0.48s)
=== RUN   TestDeduplicateOldest
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:19:15 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija': Looking for duplicate names using oldest mode.
2026/10/29 03:19:16 NOTICE: one: Found 3 files with duplicate names
2026/10/29 03:19:16 INFO  : one: Deleted
2026/10/29 03:19:17 INFO  : one: Deleted
2026/10/29 03:19:17 NOTICE: one: Deleted 2 extra copies
--- PASS: TestDeduplicateOldest (7.33s)
=== RUN   TestDeduplicateLargest
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:19:23 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija': Looking for duplicate names using largest mode.
2026/10/29 03:19:23 NOTICE: one: Found 3 files with duplicate names
2026/10/29 03:19:24 INFO  : one: Deleted
2026/10/29 03:19:24 INFO  : one: Deleted
2026/10/29 03:19:24 NOTICE: one: Deleted 2 extra copies
--- PASS: TestDeduplicateLargest (7.11s)
=== RUN   TestDeduplicateSmallest
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:19:30 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija': Looking for duplicate names using smallest mode.
2026/10/29 03:19:30 NOTICE: one: Found 3 files with duplicate names
2026/10/29 03:19:31 INFO  : one: Deleted
2026/10/29 03:19:31 INFO  : one: Deleted
2026/10/29 03:19:31 NOTICE: one: Deleted 2 extra copies
--- PASS: TestDeduplicateSmallest (7.13s)
=== RUN   TestDeduplicateRename
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:19:38 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija': Looking for duplicate names using rename mode.
2026/10/29 03:19:39 NOTICE: one.txt: Found 3 files with duplicate names
2026/10/29 03:19:39 INFO  : one-2.txt: renamed from: one.txt
2026/10/29 03:19:40 INFO  : one-3.txt: renamed from: one.txt
2026/10/29 03:19:41 INFO  : one-4.txt: renamed from: one.txt
--- PASS: TestDeduplicateRename (10.72s)
=== RUN   TestDeduplicateRenameManyExisting
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:19:45 DEBUG : one-1.txt: md5 = f61466a3cc82346e5741536d9e0dbb29 OK
2026/10/29 03:19:47 DEBUG : one-2.txt: md5 = ebbf220d3d20a13571f4f8c675073c7c OK
2026/10/29 03:19:48 DEBUG : one-3.txt: md5 = 5cbe1171876a95b81a1b3d193f60d35e OK
2026/10/29 03:19:50 DEBUG : one-4.txt: md5 = 0993419503fe63635ab2d6ef52559ab3 OK
2026/10/29 03:19:52 DEBUG : one-5.txt: md5 = 8655aae4b225e671cda5dc5d39ac76e8 OK
2026/10/29 03:19:53 DEBUG : one-6.txt: md5 = 25c4910b2c91cfd79ae2a2fba446a3b1 OK
2026/10/29 03:19:55 DEBUG : one-7.txt: md5 = 4464f2a564a4794bf3490ac0e61d59ff OK
2026/10/29 03:19:56 DEBUG : one-8.txt: md5 = 061455b52a4d8881d4d436796705d678 OK
2026/10/29 03:19:58 DEBUG : one-9.txt: md5 = a07fbbe586af9087382148f3f522063e OK
2026/10/29 03:19:59 DEBUG : one-10.txt: md5 = 3afd239c5273442cdec694a2ad72dba6 OK
2026/10/29 03:20:01 DEBUG : one-11.txt: md5 = a017dd75f92878b181f356dad8438cba OK
2026/10/29 03:20:02 DEBUG : one-12.txt: md5 = f60c9ff11b8412856c9ed13172c82341 OK
2026/10/29 03:20:04 DEBUG : one-13.txt: md5 = d8543b06e618ef210c83cfc0f260d25b OK
2026/10/29 03:20:05 DEBUG : one-14.txt: md5 = fc96f07cc06b34d7fe60a0c8b0514f1e OK
2026/10/29 03:20:07 DEBUG : one-15.txt: md5 = bb71aaae1dc25f69cacc725438f9b132 OK
2026/10/29 03:20:09 DEBUG : one-16.txt: md5 = 2c445eef18ec3276295f7dd5662fb6f9 OK
2026/10/29 03:20:10 DEBUG : one-17.txt: md5 = a6336edcfcc0884b5d3592884aa157f3 OK
2026/10/29 03:20:12 DEBUG : one-18.txt: md5 = 8274c630e89d8e28e62a3a25f07c4c2d OK
2026/10/29 03:20:13 DEBUG : one-19.txt: md5 = bd76eced86b8357509adcca1374b3e74 OK
2026/10/29 03:20:15 DEBUG : one-20.txt: md5 = 1416e60b02583c082300a54bad1e7b3f OK
2026/10/29 03:20:16 DEBUG : one-21.txt: md5 = a3393e9589d7beac6741d15d7be5cf48 OK
2026/10/29 03:20:18 DEBUG : one-22.txt: md5 = ccbe73a7d2d742123d11aa1714721a98 OK
2026/10/29 03:20:19 DEBUG : one-23.txt: md5 = 96983790f4f5c9e1e923bfd0069c2a3f OK
2026/10/29 03:20:21 DEBUG : one-24.txt: md5 = acc5e62704b776022b8186d17161f674 OK
2026/10/29 03:20:22 DEBUG : one-25.txt: md5 = 07ab5d67a35b52e6fbd2ef603c060715 OK
2026/10/29 03:20:24 DEBUG : one-26.txt: md5 = 3397baef15cc33345c520588bb66676d OK
2026/10/29 03:20:26 DEBUG : one-27.txt: md5 = c7aceaec87a9ba6ec8c26b9b608a2e52 OK
2026/10/29 03:20:27 DEBUG : one-28.txt: md5 = 9d4ede057c044c65f33e38fb8aa1f246 OK
2026/10/29 03:20:29 DEBUG : one-29.txt: md5 = 2171a10ceb2168017fb1949eed6dc8bb OK
2026/10/29 03:20:31 DEBUG : one-30.txt: md5 = 45969187e78bf6d3c87c72c765cba372 OK
2026/10/29 03:20:32 DEBUG : one-31.txt: md5 = a451090c50c8797734982b82d64279e5 OK
2026/10/29 03:20:34 DEBUG : one-32.txt: md5 = 6c7bb403f086526d9572d7f844e5e232 OK
2026/10/29 03:20:35 DEBUG : one-33.txt: md5 = f7f77cc67a3899573297e390e387dec3 OK
2026/10/29 03:20:37 DEBUG : one-34.txt: md5 = 3d9d9c6b58e19f0bc5a05042092ae66b OK
2026/10/29 03:20:38 DEBUG : one-35.txt: md5 = 5c0894d5cd8156db1d7dbc6bf17503e1 OK
2026/10/29 03:20:40 DEBUG : one-36.txt: md5 = 1be37fe6fbd53894e92f86fef844da2b OK
2026/10/29 03:20:41 DEBUG : one-37.txt: md5 = 2327f50225b5460cbcf8c4592ea8e601 OK
2026/10/29 03:20:42 DEBUG : one-38.txt: md5 = 7ebafecb15d91f4b78d055cb3edd3b1e OK
2026/10/29 03:20:44 DEBUG : one-39.txt: md5 = 9b5e032aa0d99447f0efbc7dbdceac38 OK
2026/10/29 03:20:45 DEBUG : one-40.txt: md5 = dba3e69c2e4a82a7a5f0ada9974771f5 OK
2026/10/29 03:20:47 DEBUG : one-41.txt: md5 = d7837a592a46e1f54d0aefad92224ad4 OK
2026/10/29 03:20:48 DEBUG : one-42.txt: md5 = 55cac01ccc5a314b072dfb6aae363d97 OK
2026/10/29 03:20:50 DEBUG : one-43.txt: md5 = a8d767e038ca8f63b22231a596f91c82 OK
2026/10/29 03:20:51 DEBUG : one-44.txt: md5 = b336227a578e740377d51a813a836b2a OK
2026/10/29 03:20:52 DEBUG : one-45.txt: md5 = fe52149fefe7a6a9951cce00206d514a OK
2026/10/29 03:20:54 DEBUG : one-46.txt: md5 = 767fa27e745a1fd5b48fea86e12d803b OK
2026/10/29 03:20:55 DEBUG : one-47.txt: md5 = 8418f30aba3ed3fbbae24672fce93b61 OK
2026/10/29 03:20:57 DEBUG : one-48.txt: md5 = 5c46673fc06fb2205bced2948a8d3bfb OK
2026/10/29 03:20:59 DEBUG : one-49.txt: md5 = 68132da2725b0835c3a1a49bc7ecb82b OK
2026/10/29 03:21:00 DEBUG : one-50.txt: md5 = 0accd0976f87b61882d9bf6f1cb56222 OK
2026/10/29 03:21:02 DEBUG : one-51.txt: md5 = b24e8e12a9ce4868b1f0e9cd4bb388fb OK
2026/10/29 03:21:03 DEBUG : one-52.txt: md5 = 554e6c7f574a80b9b28558c57520d68c OK
2026/10/29 03:21:05 DEBUG : one-53.txt: md5 = 120605530137c38520624bda4c501553 OK
2026/10/29 03:21:06 DEBUG : one-54.txt: md5 = 28151f595b3eb77ec74ac7d8c2ecdb0c OK
2026/10/29 03:21:08 DEBUG : one-55.txt: md5 = 48b79dd3803cf0f0ebcc713617d86fc8 OK
2026/10/29 03:21:09 DEBUG : one-56.txt: md5 = d4a9bbdc3e5793f97b6d9a0407e0de90 OK
2026/10/29 03:21:11 DEBUG : one-57.txt: md5 = ff0d1da2138023bc5e1a8d1c199a053d OK
2026/10/29 03:21:12 DEBUG : one-58.txt: md5 = 4ccbf5fea18020fb2516e4fec024f2dd OK
2026/10/29 03:21:14 DEBUG : one-59.txt: md5 = 95f6fc0e3560c400e4255d8f34959454 OK
2026/10/29 03:21:15 DEBUG : one-60.txt: md5 = 1d88857980dcd72081be2b5f1cf9000f OK
2026/10/29 03:21:17 DEBUG : one-61.txt: md5 = 0f590279758fb253c22f11dc51f68898 OK
2026/10/29 03:21:18 DEBUG : one-62.txt: md5 = b8fed414b126b51d2d11a061e8d1d2d2 OK
2026/10/29 03:21:20 DEBUG : one-63.txt: md5 = 213dd4b0e41e150ee236094a2922fb98 OK
2026/10/29 03:21:21 DEBUG : one-64.txt: md5 = fe9ada8c47c2fe842eb18b305dcde7b3 OK
2026/10/29 03:21:23 DEBUG : one-65.txt: md5 = 952196bf258bfa57d33b87d5737d92c8 OK
2026/10/29 03:21:24 DEBUG : one-66.txt: md5 = dcab344254ec081edbd276c412e85bde OK
2026/10/29 03:21:26 DEBUG : one-67.txt: md5 = 458436f4de5fdabf205dca0684a6e715 OK
2026/10/29 03:21:27 DEBUG : one-68.txt: md5 = 0216077fad696f8934fea83f197940d7 OK
2026/10/29 03:21:29 DEBUG : one-69.txt: md5 = 91589d6677c15634b8be0643cc1be864 OK
2026/10/29 03:21:30 DEBUG : one-70.txt: md5 = ed817d3e548143d275aef561dae9adda OK
2026/10/29 03:21:30 DEBUG : TestDrive: Token expired
2026/10/29 03:21:30 DEBUG : Config file has changed externally - reloading
2026/10/29 03:21:30 DEBUG : TestDrive: Loaded fresh token from config file
2026/10/29 03:21:30 DEBUG : TestDrive: Token refresh successful
2026/10/29 03:21:32 DEBUG : one-71.txt: md5 = be02417b32c2189c3461bde15ed94bb8 OK
2026/10/29 03:21:34 DEBUG : one-72.txt: md5 = 1d7c74d6798dc2a200b85de306c50c59 OK
2026/10/29 03:21:35 DEBUG : one-73.txt: md5 = 0d29e083dcd0f62f9e4bc1b689c97ad6 OK
2026/10/29 03:21:37 DEBUG : one-74.txt: md5 = 8d1f3053755890015b2ca31c21731559 OK
2026/10/29 03:21:38 DEBUG : one-75.txt: md5 = 4559eebf54d15b091c8616773e25b23f OK
2026/10/29 03:21:40 DEBUG : one-76.txt: md5 = ab9e9b7c3ed0c099cbd0cb51179f1c81 OK
2026/10/29 03:21:42 DEBUG : one-77.txt: md5 = 8ba8b131156087b34acb6b3f765fcbec OK
2026/10/29 03:21:43 DEBUG : one-78.txt: md5 = 9f7aca35e6913ffb3ef90f8d5fc23797 OK
2026/10/29 03:21:45 DEBUG : one-79.txt: md5 = 477744a985d89ede5ff014787d085314 OK
2026/10/29 03:21:46 DEBUG : one-80.txt: md5 = 61fa922cef6ccf8acb86cff721b04d30 OK
2026/10/29 03:21:48 DEBUG : one-81.txt: md5 = a4459f8a9c90377bd8cc087ca94cf310 OK
2026/10/29 03:21:49 DEBUG : one-82.txt: md5 = 0ab073c356568ad5ed3214be8cb0dc3c OK
2026/10/29 03:21:51 DEBUG : one-83.txt: md5 = a6087c6e09accb65681d13683a21cc89 OK
2026/10/29 03:21:52 DEBUG : one-84.txt: md5 = b218f20dd2866ad8df046a5ccaaf0b80 OK
2026/10/29 03:21:54 DEBUG : one-85.txt: md5 = 1383fcbbdbd1fddaf8b531307b80189d OK
2026/10/29 03:21:55 DEBUG : one-86.txt: md5 = 0466042d11825cca70797f12d3051699 OK
2026/10/29 03:21:57 DEBUG : one-87.txt: md5 = 240379f2009d0378e060ba12e8256196 OK
2026/10/29 03:21:58 DEBUG : one-88.txt: md5 = f3d735e480e4c07024bafdfec88b58f8 OK
2026/10/29 03:22:00 DEBUG : one-89.txt: md5 = aa2eb859bd2bcec0dd46ac2772d88276 OK
2026/10/29 03:22:01 DEBUG : one-90.txt: md5 = c78257b90355be62055145b3e6a3ca1e OK
2026/10/29 03:22:03 DEBUG : one-91.txt: md5 = 36f81998d6391d7c0cc2dfbcee44d03b OK
2026/10/29 03:22:04 DEBUG : one-92.txt: md5 = b86d3d6bcd8af7f16d96e29f2436a67e OK
2026/10/29 03:22:06 DEBUG : one-93.txt: md5 = 946d8fa4e267bc9eb31ea714ad84ad92 OK
2026/10/29 03:22:07 DEBUG : one-94.txt: md5 = 61f76cc430c2d044374f0e482f259c9e OK
2026/10/29 03:22:09 DEBUG : one-95.txt: md5 = f4fd731e69d9e90d2c1d5559e82c9278 OK
2026/10/29 03:22:10 DEBUG : one-96.txt: md5 = 655d1e048d8a1571dfc205d6ab961d5e OK
2026/10/29 03:22:12 DEBUG : one-97.txt: md5 = 4e4955814b9eda01e92fcebda7ad4e99 OK
2026/10/29 03:22:14 DEBUG : one-98.txt: md5 = a80ed8ce7a21d922820f99c3ff362b65 OK
2026/10/29 03:22:16 DEBUG : one-99.txt: md5 = 2965c0f9f177faee45b134139a835ec3 OK
2026/10/29 03:22:18 DEBUG : one-100.txt: md5 = 83b02803bd24c5c5c528a4d2f5d0259c OK
2026/10/29 03:22:19 DEBUG : one-101.txt: md5 = 4a0724f32191d2797aa92a62a1dbdb13 OK
2026/10/29 03:22:21 DEBUG : one-102.txt: md5 = 37580a4b3cdd65748cbc38e5aafa9680 OK
2026/10/29 03:22:22 DEBUG : one-103.txt: md5 = e007700defab93a8a9b63d99200feee0 OK
2026/10/29 03:22:24 DEBUG : one-104.txt: md5 = e9240e537a5e8e1b4bb4db60f2ed1326 OK
2026/10/29 03:22:25 DEBUG : one-105.txt: md5 = 2ce3f90d0826c5fd4c2d0f50a2498f01 OK
2026/10/29 03:22:29 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija': Looking for duplicate names using rename mode.
2026/10/29 03:22:29 NOTICE: one.txt: Found 2 files with duplicate names
2026/10/29 03:22:30 INFO  : one-106.txt: renamed from: one.txt
2026/10/29 03:22:31 INFO  : one-107.txt: renamed from: one.txt
--- PASS: TestDeduplicateRenameManyExisting (225.34s)
=== RUN   TestMergeDirs
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:23:31 DEBUG : dupe1/one.txt: md5 = 67455b052499962a02207ea627aa9e01 OK
2026/10/29 03:23:33 DEBUG : dupe2/two.txt: md5 = e9f960c18870d44564ff4d7bf9b74476 OK
2026/10/29 03:23:36 DEBUG : dupe3/three.txt: md5 = 7ea3d85cd983bb7dcece5bc7af91d574 OK
2026/10/29 03:23:36 INFO  : urj8gsducqbtvekeq181ntd2u8: merging "sfrom47189m9qr3mt4qonu281c"
2026/10/29 03:23:37 INFO  : urj8gsducqbtvekeq181ntd2u8: removing empty directory
2026/10/29 03:23:38 INFO  : 1u1r4ei7c628fnjg9blqt3j60o: merging "tr2hj63d80ftlmvm6a952snjcc"
2026/10/29 03:23:39 INFO  : 1u1r4ei7c628fnjg9blqt3j60o: removing empty directory
--- PASS: TestMergeDirs (14.69s)
=== RUN   TestIndex
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:23:45 DEBUG : file1.txt: md5 = 2adeece27df47d3690736aba357a52e2 OK
2026/10/29 03:23:47 DEBUG : sub/file2.txt: md5 = c0519be26f2fbf93824d0e9c5798854c OK
2026/10/29 03:23:50 DEBUG : sub/deep/file3.txt: md5 = 8096d30fa897f958e170f852605add7b OK
2026/10/29 03:23:52 DEBUG : sub/deep/index.html: md5 = 3013c9b7d32c68d90d9ae0d248bc4928 OK
2026/10/29 03:23:52 DEBUG : sub/deep/index.html: size = 12087 OK
2026/10/29 03:23:52 INFO  : sub/deep/index.html: Copied (new)
2026/10/29 03:23:52 DEBUG : caddy.json: md5 = fc4d278be6a661be10e4c3f5c43ed2e0 OK
2026/10/29 03:23:52 DEBUG : caddy.json: size = 259 OK
2026/10/29 03:23:52 INFO  : caddy.json: Copied (new)
2026/10/29 03:23:52 DEBUG : index.json: md5 = 3f85e203cf6a166fc1cfddc680b4d840 OK
2026/10/29 03:23:52 DEBUG : index.json: size = 213 OK
2026/10/29 03:23:52 INFO  : index.json: Copied (new)
2026/10/29 03:23:52 DEBUG : index.html: md5 = 5d34d4fb039ce8df6272fca5a3ecea19 OK
2026/10/29 03:23:52 DEBUG : index.html: size = 12147 OK
2026/10/29 03:23:52 INFO  : index.html: Copied (new)
2026/10/29 03:23:53 DEBUG : sub/deep/index.json: md5 = 077c6b4569907482a72ea9f40455d69b OK
2026/10/29 03:23:53 DEBUG : sub/deep/index.json: size = 119 OK
2026/10/29 03:23:53 INFO  : sub/deep/index.json: Copied (new)
2026/10/29 03:23:53 DEBUG : sub/index.html: md5 = 8284a8699b225cc4574504bfb182e05c OK
2026/10/29 03:23:53 DEBUG : sub/index.html: size = 12306 OK
2026/10/29 03:23:53 INFO  : sub/index.html: Copied (new)
2026/10/29 03:23:54 DEBUG : sub/deep/caddy.json: md5 = 05b556507d572319b51746a039fd348b OK
2026/10/29 03:23:54 DEBUG : sub/deep/caddy.json: size = 131 OK
2026/10/29 03:23:54 INFO  : sub/deep/caddy.json: Copied (new)
2026/10/29 03:23:54 DEBUG : sub/index.json: md5 = 4bcfd3dc7e8c8eeb258216fa9da0727b OK
2026/10/29 03:23:54 DEBUG : sub/index.json: size = 215 OK
2026/10/29 03:23:54 INFO  : sub/index.json: Copied (new)
2026/10/29 03:23:55 DEBUG : sub/caddy.json: md5 = f79f3d7b00e94d2f7f770fe6d3a84fbd OK
2026/10/29 03:23:55 DEBUG : sub/caddy.json: size = 261 OK
2026/10/29 03:23:55 INFO  : sub/caddy.json: Copied (new)
2026/10/29 03:24:01 DEBUG : index.html: Unchanged skipping
2026/10/29 03:24:01 DEBUG : sub/deep/index.html: Unchanged skipping
2026/10/29 03:24:01 DEBUG : sub/index.html: Unchanged skipping
2026/10/29 03:24:01 DEBUG : index.json: Unchanged skipping
2026/10/29 03:24:01 DEBUG : sub/deep/index.json: Unchanged skipping
2026/10/29 03:24:01 DEBUG : sub/index.json: Unchanged skipping
2026/10/29 03:24:02 DEBUG : caddy.json: Unchanged skipping
2026/10/29 03:24:02 DEBUG : sub/deep/caddy.json: Unchanged skipping
2026/10/29 03:24:02 DEBUG : sub/caddy.json: Unchanged skipping
2026/10/29 03:24:04 DEBUG : caddy.json: md5 = 452e55b3e4683f7517dbe47af46a7ac5 OK
2026/10/29 03:24:04 DEBUG : caddy.json: size = 259 OK
2026/10/29 03:24:04 INFO  : caddy.json: Copied (replaced existing)
2026/10/29 03:24:04 DEBUG : index.html: md5 = 44785ecd45eae94c37445747db3b9d07 OK
2026/10/29 03:24:04 DEBUG : index.html: size = 12147 OK
2026/10/29 03:24:04 INFO  : index.html: Copied (replaced existing)
2026/10/29 03:24:04 DEBUG : sub/deep/index.html: md5 = b9050c169ae8b2cf0b3136eba5f0726b OK
2026/10/29 03:24:04 DEBUG : sub/deep/index.html: size = 12087 OK
2026/10/29 03:24:04 INFO  : sub/deep/index.html: Copied (replaced existing)
2026/10/29 03:24:04 DEBUG : index.json: md5 = 88a510ffdc5b4cf8ffd7e1d18e2fd9c9 OK
2026/10/29 03:24:04 DEBUG : index.json: size = 213 OK
2026/10/29 03:24:04 INFO  : index.json: Copied (replaced existing)
2026/10/29 03:24:05 DEBUG : sub/index.html: md5 = da1492bd928683d24a9d617fde93d039 OK
2026/10/29 03:24:05 DEBUG : sub/index.html: size = 12306 OK
2026/10/29 03:24:05 INFO  : sub/index.html: Copied (replaced existing)
2026/10/29 03:24:05 DEBUG : sub/deep/index.json: md5 = a8db3d78393d75ed680c5216d0f89662 OK
2026/10/29 03:24:05 DEBUG : sub/deep/index.json: size = 119 OK
2026/10/29 03:24:05 INFO  : sub/deep/index.json: Copied (replaced existing)
2026/10/29 03:24:05 DEBUG : sub/deep/caddy.json: md5 = 885c84913d33b0d7c0a1706493ad3354 OK
2026/10/29 03:24:05 DEBUG : sub/deep/caddy.json: size = 131 OK
2026/10/29 03:24:05 INFO  : sub/deep/caddy.json: Copied (replaced existing)
2026/10/29 03:24:06 DEBUG : sub/index.json: md5 = f69de6545d75199664820b43cb0d39e0 OK
2026/10/29 03:24:06 DEBUG : sub/index.json: size = 215 OK
2026/10/29 03:24:06 INFO  : sub/index.json: Copied (replaced existing)
2026/10/29 03:24:07 DEBUG : sub/caddy.json: md5 = 7bc43343e16d9d86b4b09b9f09344136 OK
2026/10/29 03:24:07 DEBUG : sub/caddy.json: size = 261 OK
2026/10/29 03:24:07 INFO  : sub/caddy.json: Copied (replaced existing)
2026/10/29 03:24:08 DEBUG : sub/deep/file4.txt: md5 = fb1c14d8d14cf9a5e36dc6b42a7bdec8 OK
2026/10/29 03:24:10 DEBUG : index.html: Unchanged skipping
2026/10/29 03:24:10 DEBUG : sub/index.html: Unchanged skipping
2026/10/29 03:24:10 DEBUG : sub/deep/caddy.json: md5 = 4d6aea04e1d1ce85bc10a21a7a596a7a OK
2026/10/29 03:24:10 DEBUG : sub/deep/caddy.json: size = 260 OK
2026/10/29 03:24:10 INFO  : sub/deep/caddy.json: Copied (replaced existing)
2026/10/29 03:24:10 DEBUG : sub/deep/index.html: md5 = 7e86f216bc2fd957f32229165c85b417 OK
2026/10/29 03:24:10 DEBUG : sub/deep/index.html: size = 12346 OK
2026/10/29 03:24:10 INFO  : sub/deep/index.html: Copied (replaced existing)
2026/10/29 03:24:10 DEBUG : index.json: Unchanged skipping
2026/10/29 03:24:10 DEBUG : sub/deep/index.json: md5 = 5b5dfe0ff3f053c0b562a3b6813e5f74 OK
2026/10/29 03:24:10 DEBUG : sub/deep/index.json: size = 235 OK
2026/10/29 03:24:10 INFO  : sub/deep/index.json: Copied (replaced existing)
2026/10/29 03:24:10 DEBUG : sub/index.json: Unchanged skipping
2026/10/29 03:24:11 DEBUG : caddy.json: Unchanged skipping
2026/10/29 03:24:11 DEBUG : sub/caddy.json: Unchanged skipping
2026/10/29 03:24:14 DEBUG : sub/deep/file5.txt: md5 = bf2f9ee9c546d5a54d8d782b8b45549e OK
2026/10/29 03:24:14 NOTICE: sub/deep/caddy.json: Skipped copy as --dry-run is set (size 389)
2026/10/29 03:24:14 NOTICE: sub/deep/index.html: Skipped copy as --dry-run is set (size 12.309Ki)
2026/10/29 03:24:14 NOTICE: sub/deep/index.json: Skipped copy as --dry-run is set (size 351)
2026/10/29 03:24:15 DEBUG : index.html: Unchanged skipping
2026/10/29 03:24:15 DEBUG : sub/index.html: Unchanged skipping
2026/10/29 03:24:16 DEBUG : index.json: Unchanged skipping
2026/10/29 03:24:16 DEBUG : sub/index.json: Unchanged skipping
2026/10/29 03:24:16 DEBUG : caddy.json: Unchanged skipping
2026/10/29 03:24:16 DEBUG : sub/caddy.json: Unchanged skipping
--- PASS: TestIndex (43.59s)
=== RUN   TestIndexDelete
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:24:29 DEBUG : file1.txt: md5 = f2fc129ec2826d372ae7db7d9c07ffa5 OK
2026/10/29 03:24:31 DEBUG : sub/file2.txt: md5 = b9bf72a96992c0b43a0167305e5569ab OK
2026/10/29 03:24:33 DEBUG : sub/deep/file3.txt: md5 = f2ad30e37271ed3a5e16b23614261257 OK
2026/10/29 03:24:35 DEBUG : index.html: md5 = cde8c0e3ac031342ad867aebe912006f OK
2026/10/29 03:24:35 DEBUG : index.html: size = 12147 OK
2026/10/29 03:24:35 INFO  : index.html: Copied (new)
2026/10/29 03:24:35 DEBUG : sub/deep/index.html: md5 = 1fe18cbbc86c15174be900e1c033a07d OK
2026/10/29 03:24:35 DEBUG : sub/deep/index.html: size = 12087 OK
2026/10/29 03:24:35 INFO  : sub/deep/index.html: Copied (new)
2026/10/29 03:24:35 DEBUG : sub/index.html: md5 = 82a2ecaf587059923ea1cbe3d27931bd OK
2026/10/29 03:24:35 DEBUG : sub/index.html: size = 12306 OK
2026/10/29 03:24:35 INFO  : sub/index.html: Copied (new)
2026/10/29 03:24:38 DEBUG : sub/index.html: Unchanged skipping
2026/10/29 03:24:38 DEBUG : index.html: Unchanged skipping
2026/10/29 03:24:39 DEBUG : sub/deep/index.html: md5 = 2cd2221314efbbcbfaa4e4212199433e OK
2026/10/29 03:24:39 DEBUG : sub/deep/index.html: size = 11829 OK
2026/10/29 03:24:39 INFO  : sub/deep/index.html: Copied (replaced existing)
--- PASS: TestIndexDelete (19.39s)
=== RUN   TestIndexFilter
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:24:48 DEBUG : file1.txt: md5 = 875c3f6582f30074374a1d4ea26ea02e OK
2026/10/29 03:24:50 DEBUG : sub/file2.txt: md5 = e4474d4a7e9c61c73f3359b84068921d OK
2026/10/29 03:24:53 DEBUG : sub/deep/file3.txt: md5 = 6c7d2291123d44f9a0d05ac09277af94 OK
2026/10/29 03:24:54 DEBUG : sub/deep/index.html: md5 = a6e5bebfc96d81cb19cf2ca6256ee9af OK
2026/10/29 03:24:57 DEBUG : other/notes.md: md5 = d7be06892ec37858eaf5aad6c67f37ca OK
2026/10/29 03:24:57 DEBUG : other/notes.md: Excluded (Path Filter)
2026/10/29 03:24:59 DEBUG : other/index.html: md5 = eca0d855ef3ddd3d30f1369a0e260912 OK
2026/10/29 03:24:59 DEBUG : other/index.html: size = 11802 OK
2026/10/29 03:24:59 INFO  : other/index.html: Copied (new)
2026/10/29 03:24:59 DEBUG : sub/index.html: md5 = 67f61b84377297f7534c6f755834db22 OK
2026/10/29 03:24:59 DEBUG : sub/index.html: size = 12056 OK
2026/10/29 03:24:59 INFO  : sub/index.html: Copied (new)
2026/10/29 03:24:59 DEBUG : index.html: md5 = 27026b0c85cd2e4f9d2fc23371c67303 OK
2026/10/29 03:24:59 DEBUG : index.html: size = 12399 OK
2026/10/29 03:24:59 INFO  : index.html: Copied (new)
2026/10/29 03:25:04 DEBUG : other/notes.md: Excluded (Path Filter)
2026/10/29 03:25:05 DEBUG : index.html: Unchanged skipping
2026/10/29 03:25:05 DEBUG : sub/index.html: Unchanged skipping
2026/10/29 03:25:05 DEBUG : other/index.html: Unchanged skipping
--- PASS: TestIndexFilter (25.87s)
=== RUN   TestIndexRules
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:25:14 DEBUG : file1.txt: md5 = 2500f76e3929985c3df472aedb6d4c53 OK
2026/10/29 03:25:16 DEBUG : sub/file2.txt: md5 = 1f360577d3796382998884612d6dc0a7 OK
2026/10/29 03:25:18 DEBUG : sub/deep/file3.txt: md5 = e34e8f72e51b1dd373590be1b928ab96 OK
2026/10/29 03:25:20 DEBUG : sub/deep/index.html: md5 = 016c58d737d3511db526ba6f48542e25 OK
2026/10/29 03:25:22 DEBUG : sub/index.html: md5 = b365335f2d3dea48e8daf7502c1e74c1 OK
2026/10/29 03:25:22 DEBUG : sub/index.html: size = 12306 OK
2026/10/29 03:25:22 INFO  : sub/index.html: Copied (new)
2026/10/29 03:25:22 DEBUG : index.html: md5 = 660da0977d0a05e2bda8f2f5e21bac05 OK
2026/10/29 03:25:22 DEBUG : index.html: size = 12147 OK
2026/10/29 03:25:22 INFO  : index.html: Copied (new)
2026/10/29 03:25:27 DEBUG : sub/index.html: Unchanged skipping
2026/10/29 03:25:28 DEBUG : sub/deep/index.html: md5 = 3394e6356a90fddbafc6b4e23bdf19a1 OK
2026/10/29 03:25:28 DEBUG : sub/deep/index.html: size = 12087 OK
2026/10/29 03:25:28 INFO  : sub/deep/index.html: Copied (replaced existing)
--- PASS: TestIndexRules (22.29s)
=== RUN   TestIndexMaxDepth
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:25:36 DEBUG : file1.txt: md5 = fa07dc16551da23430c5e4a4b4ff9094 OK
2026/10/29 03:25:39 DEBUG : sub/file2.txt: md5 = 52b91a1c1342445d56d4a735b371e90b OK
2026/10/29 03:25:41 DEBUG : sub/deep/file3.txt: md5 = 1233d63b8cfd35c2b293cb7df5453e30 OK
2026/10/29 03:25:43 DEBUG : index.html: md5 = 7927cb8b8be22d4a868d4f22f0f1eadb OK
2026/10/29 03:25:43 DEBUG : index.html: size = 12147 OK
2026/10/29 03:25:43 INFO  : index.html: Copied (new)
--- PASS: TestIndexMaxDepth (15.31s)
=== RUN   TestIndexLinkIndex
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:25:51 DEBUG : file1.txt: md5 = d774b31894ae1479c535ccfb2fe31bae OK
2026/10/29 03:25:54 DEBUG : sub/file2.txt: md5 = 9089b92c883f43225bd892d6b5b3a05b OK
2026/10/29 03:25:56 DEBUG : sub/deep/file3.txt: md5 = 071d07738866bafe1f7cb0d9bacfd291 OK
2026/10/29 03:25:58 DEBUG : index.html: md5 = 5e0b8706efc3aa8ef84baf524ab48229 OK
2026/10/29 03:25:58 DEBUG : index.html: size = 12167 OK
2026/10/29 03:25:58 INFO  : index.html: Copied (new)
2026/10/29 03:25:58 DEBUG : sub/index.html: md5 = 24dcaf771cff40d64d25832f71dd4e39 OK
2026/10/29 03:25:58 DEBUG : sub/index.html: size = 12347 OK
2026/10/29 03:25:58 INFO  : sub/index.html: Copied (new)
2026/10/29 03:25:58 DEBUG : sub/deep/index.html: md5 = d40e7b4b9f00992210b5a0c934873d22 OK
2026/10/29 03:25:58 DEBUG : sub/deep/index.html: size = 12128 OK
2026/10/29 03:25:58 INFO  : sub/deep/index.html: Copied (new)
--- PASS: TestIndexLinkIndex (16.58s)
=== RUN   TestIndexDirTime
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:26:08 DEBUG : file1.txt: md5 = b20656f8c9105521f05a77451a4d3ca9 OK
2026/10/29 03:26:10 DEBUG : sub/file2.txt: md5 = 493d39c4a5940786a56a8f11e6693d27 OK
2026/10/29 03:26:13 DEBUG : sub/deep/file3.txt: md5 = e006006cb0167606ae234d87d3284057 OK
2026/10/29 03:26:15 DEBUG : sub/index.json: md5 = 1827c1c2525a69952e26af94684faf72 OK
2026/10/29 03:26:15 DEBUG : sub/index.json: size = 259 OK
2026/10/29 03:26:15 INFO  : sub/index.json: Copied (new)
2026/10/29 03:26:15 DEBUG : index.json: md5 = 071fd38fd23fdf8a7801bd000dc307dc OK
2026/10/29 03:26:15 DEBUG : index.json: size = 257 OK
2026/10/29 03:26:15 INFO  : index.json: Copied (new)
2026/10/29 03:26:15 DEBUG : sub/deep/index.json: md5 = e31589ae4af0e400538fbb0964867e6f OK
2026/10/29 03:26:15 DEBUG : sub/deep/index.json: size = 139 OK
2026/10/29 03:26:15 INFO  : sub/deep/index.json: Copied (new)
2026/10/29 03:26:18 DEBUG : sub/deep/index.json: Unchanged skipping
2026/10/29 03:26:18 DEBUG : index.json: md5 = bf49c867e951256109a8f001bfe05c18 OK
2026/10/29 03:26:18 DEBUG : index.json: size = 237 OK
2026/10/29 03:26:18 INFO  : index.json: Copied (replaced existing)
2026/10/29 03:26:19 DEBUG : sub/index.json: md5 = ab4000ca6af17127f2e062564bcd4dda OK
2026/10/29 03:26:19 DEBUG : sub/index.json: size = 239 OK
2026/10/29 03:26:19 INFO  : sub/index.json: Copied (replaced existing)
2026/10/29 03:26:22 DEBUG : index.json: md5 = 47162ff3922c48a3425a917843b9f644 OK
2026/10/29 03:26:22 DEBUG : index.json: size = 213 OK
2026/10/29 03:26:22 INFO  : index.json: Copied (replaced existing)
2026/10/29 03:26:22 DEBUG : sub/deep/index.json: md5 = 416b8bc0985a4fa7189d7b621cb45cfe OK
2026/10/29 03:26:22 DEBUG : sub/deep/index.json: size = 119 OK
2026/10/29 03:26:22 INFO  : sub/deep/index.json: Copied (replaced existing)
2026/10/29 03:26:22 DEBUG : sub/index.json: md5 = ab95ae6ad17ff67ac91be813df35c236 OK
2026/10/29 03:26:22 DEBUG : sub/index.json: size = 215 OK
2026/10/29 03:26:22 INFO  : sub/index.json: Copied (replaced existing)
--- PASS: TestIndexDirTime (21.45s)
=== RUN   TestIndexTemplate
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:26:29 DEBUG : file1.txt: md5 = 0077af0cd184725a8e182077a42845b3 OK
2026/10/29 03:26:32 DEBUG : sub/file2.txt: md5 = fd281887ed04b3d739ce7f8adf5dfff5 OK
2026/10/29 03:26:34 DEBUG : sub/deep/file3.txt: md5 = 882713e9f28045320b09889d96e4618d OK
2026/10/29 03:26:36 DEBUG : list.txt: md5 = b40648f580015327a6af0652c00d10e7 OK
2026/10/29 03:26:36 DEBUG : list.txt: size = 59 OK
2026/10/29 03:26:36 INFO  : list.txt: Copied (new)
2026/10/29 03:26:36 DEBUG : sub/deep/list.txt: md5 = c9c858b3f75a7127af89ddde0d907318 OK
2026/10/29 03:26:36 DEBUG : sub/deep/list.txt: size = 47 OK
2026/10/29 03:26:36 INFO  : sub/deep/list.txt: Copied (new)
2026/10/29 03:26:36 DEBUG : sub/list.txt: md5 = 912d0672fa1da5f8ddbfc10469b63f21 OK
2026/10/29 03:26:36 DEBUG : sub/list.txt: size = 64 OK
2026/10/29 03:26:36 INFO  : sub/list.txt: Copied (new)
--- PASS: TestIndexTemplate (16.76s)
=== 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-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:26:46 DEBUG : file1.txt: md5 = 419d5e4eee74fd327364ec816637b990 OK
2026/10/29 03:26:49 DEBUG : sub/file2.txt: md5 = ad9f2422ba407c9de714c3c27cb16f66 OK
2026/10/29 03:26:51 DEBUG : sub/deep/file3.txt: md5 = c5ba99b1635bab8373e61b3d0418a30b OK
2026/10/29 03:26:53 DEBUG : other/notes.md: md5 = 3b7b7b24c7c6d5d34c5c75a3f22af4f1 OK
2026/10/29 03:26:56 DEBUG : sub/index.html: md5 = 1f81474c841c06e20dd7f5c076283a75 OK
2026/10/29 03:26:56 DEBUG : sub/index.html: size = 12306 OK
2026/10/29 03:26:56 INFO  : sub/index.html: Copied (new)
2026/10/29 03:26:56 DEBUG : sub/deep/index.html: md5 = fba432c720e835b7e456d1e459947f2f OK
2026/10/29 03:26:56 DEBUG : sub/deep/index.html: size = 12087 OK
2026/10/29 03:26:56 INFO  : sub/deep/index.html: Copied (new)
2026/10/29 03:26:56 DEBUG : other/index.html: md5 = 3c7f136f8f9c8112f6ecfc72ed1473c3 OK
2026/10/29 03:26:56 DEBUG : other/index.html: size = 12058 OK
2026/10/29 03:26:56 INFO  : other/index.html: Copied (new)
2026/10/29 03:26:56 DEBUG : index.html: md5 = cf529274b0445f40bcc3f9d9c6b64c52 OK
2026/10/29 03:26:56 DEBUG : index.html: size = 12399 OK
2026/10/29 03:26:56 INFO  : index.html: Copied (new)
2026/10/29 03:26:57 DEBUG : sub/deep/file4.txt: md5 = ba4a605b755d1e496416fa63e0411ac8 OK
2026/10/29 03:26:59 DEBUG : other/undeclared.txt: md5 = e578af221863a81a6e794e49d5013625 OK
2026/10/29 03:26:59 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija': Partial index: listing 3 directories and walking 0 changed directories
2026/10/29 03:27:00 DEBUG : sub/index.html: Unchanged skipping
2026/10/29 03:27:00 DEBUG : index.html: Unchanged skipping
2026/10/29 03:27:01 DEBUG : sub/deep/index.html: md5 = 0b5639c2e99f2c283a6af2e523f2954d OK
2026/10/29 03:27:01 DEBUG : sub/deep/index.html: size = 12346 OK
2026/10/29 03:27:01 INFO  : sub/deep/index.html: Copied (replaced existing)
2026/10/29 03:27:05 DEBUG : sub/new/file5.txt: md5 = 634b8a62a1fa2dc2a1f03ad9afb81164 OK
2026/10/29 03:27:05 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija': Partial index: listing 3 directories and walking 0 changed directories
2026/10/29 03:27:06 DEBUG : index.html: Unchanged skipping
2026/10/29 03:27:07 DEBUG : sub/index.html: md5 = 59e7524843ebe037edfd46e8b9250482 OK
2026/10/29 03:27:07 DEBUG : sub/index.html: size = 12554 OK
2026/10/29 03:27:07 INFO  : sub/index.html: Copied (replaced existing)
2026/10/29 03:27:07 DEBUG : sub/new/index.html: md5 = d033da3abd68d98b7b2eb6918ef8d345 OK
2026/10/29 03:27:07 DEBUG : sub/new/index.html: size = 12085 OK
2026/10/29 03:27:07 INFO  : sub/new/index.html: Copied (new)
2026/10/29 03:27:11 DEBUG : other/undeclared0.txt: md5 = 9d9fce0318eb9b9231c47b349f4cbaf2 OK
2026/10/29 03:27:11 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija': Partial index: listing 1 directories and walking 1 changed directories
2026/10/29 03:27:12 DEBUG : index.html: Unchanged skipping
2026/10/29 03:27:13 DEBUG : other/index.html: md5 = d0d800f047c781e0f431c7e18eb1c65c OK
2026/10/29 03:27:13 DEBUG : other/index.html: size = 12597 OK
2026/10/29 03:27:13 INFO  : other/index.html: Copied (replaced existing)
2026/10/29 03:27:15 DEBUG : other/undeclared1.txt: md5 = 2089a3154cc8f69eb33b0a1bf8625839 OK
2026/10/29 03:27:15 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija': Partial index: listing 1 directories and walking 0 changed directories
2026/10/29 03:27:16 DEBUG : index.html: Unchanged skipping
2026/10/29 03:27:17 DEBUG : other/index.html: md5 = b3b3352574d0b788a867a60c2a845e4b OK
2026/10/29 03:27:17 DEBUG : other/index.html: size = 12867 OK
2026/10/29 03:27:17 INFO  : other/index.html: Copied (replaced existing)
2026/10/29 03:27:19 DEBUG : sub/file6.txt: md5 = 6c742f78dbf1331e994710f76f13c885 OK
2026/10/29 03:27:19 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija': Nothing changed so nothing to index
2026/10/29 03:27:20 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija': Root changed so doing a full index
2026/10/29 03:27:22 DEBUG : other/index.html: Unchanged skipping
2026/10/29 03:27:22 DEBUG : sub/new/index.html: Unchanged skipping
2026/10/29 03:27:22 DEBUG : index.html: Unchanged skipping
2026/10/29 03:27:22 DEBUG : sub/deep/index.html: Unchanged skipping
2026/10/29 03:27:23 DEBUG : sub/index.html: md5 = 6ed46b73bcb2e3ffd4f12831c0044744 OK
2026/10/29 03:27:23 DEBUG : sub/index.html: size = 12813 OK
2026/10/29 03:27:23 INFO  : sub/index.html: Copied (replaced existing)
2026/10/29 03:27:25 DEBUG : sub/file7.txt: md5 = dc2c22d258f9108bcefbc7bb339a8ad0 OK
2026/10/29 03:27:25 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija': Partial index would list 2 directories, more than 1, so doing a full index
2026/10/29 03:27:26 DEBUG : index.html: Unchanged skipping
2026/10/29 03:27:26 DEBUG : other/index.html: Unchanged skipping
2026/10/29 03:27:26 DEBUG : sub/deep/index.html: Unchanged skipping
2026/10/29 03:27:26 DEBUG : sub/new/index.html: Unchanged skipping
2026/10/29 03:27:27 DEBUG : sub/index.html: md5 = 4e711d2cc752ade9b87998e9d482d2d7 OK
2026/10/29 03:27:27 DEBUG : sub/index.html: size = 13071 OK
2026/10/29 03:27:27 INFO  : sub/index.html: Copied (replaced existing)
2026/10/29 03:27:30 DEBUG : sub/deep/file8.txt: md5 = 44a9a9197a59631c7ce945d71997e5a3 OK
2026/10/29 03:27:30 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija': Partial index: listing 1 directories and walking 1 changed directories
2026/10/29 03:27:31 DEBUG : index.html: Unchanged skipping
2026/10/29 03:27:31 DEBUG : sub/new/index.html: Unchanged skipping
2026/10/29 03:27:31 DEBUG : sub/index.html: Unchanged skipping
2026/10/29 03:27:32 DEBUG : sub/deep/index.html: md5 = b85c66b0d1c0dcb778adf5a71cefd779 OK
2026/10/29 03:27:32 DEBUG : sub/deep/index.html: size = 12604 OK
2026/10/29 03:27:32 INFO  : sub/deep/index.html: Copied (replaced existing)
2026/10/29 03:27:34 DEBUG : sub/deep/file9.txt: md5 = 7d9a49dc48626a2a738a7948c81c60a7 OK
2026/10/29 03:27:36 DEBUG : sub/file10.txt: md5 = f6bf4879c2d7aabc7760ca23b121ad44 OK
2026/10/29 03:27:36 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija': Partial index: listing 2 directories and walking 0 changed directories
2026/10/29 03:27:37 DEBUG : index.html: Unchanged skipping
2026/10/29 03:27:38 DEBUG : sub/index.html: md5 = 889fee2c77dbec8732ac5c267756011a OK
2026/10/29 03:27:38 DEBUG : sub/index.html: size = 13331 OK
2026/10/29 03:27:38 INFO  : sub/index.html: Copied (replaced existing)
--- PASS: TestIndexChanged (70.61s)
=== RUN   TestIndexChangedDelete
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:27:57 DEBUG : file1.txt: md5 = f0c9ebaa19068c94f7e7dd0291903b68 OK
2026/10/29 03:27:59 DEBUG : sub/file2.txt: md5 = 5766423ecea0d9a610a55b7c97ec28aa OK
2026/10/29 03:28:02 DEBUG : sub/deep/file3.txt: md5 = d3b9928d6d3c8725e48fdad0674dc814 OK
2026/10/29 03:28:04 DEBUG : sub/deep/index.html: md5 = c8a84ddfffcb0f8158d3065c6a690117 OK
2026/10/29 03:28:04 DEBUG : sub/deep/index.html: size = 12087 OK
2026/10/29 03:28:04 INFO  : sub/deep/index.html: Copied (new)
2026/10/29 03:28:04 DEBUG : index.html: md5 = 766d62b8476dc8b624a965f35f5707aa OK
2026/10/29 03:28:04 DEBUG : index.html: size = 12147 OK
2026/10/29 03:28:04 INFO  : index.html: Copied (new)
2026/10/29 03:28:04 DEBUG : sub/index.html: md5 = a264d69253cb764752c331dae30670e2 OK
2026/10/29 03:28:04 DEBUG : sub/index.html: size = 12306 OK
2026/10/29 03:28:04 INFO  : sub/index.html: Copied (new)
2026/10/29 03:28:05 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija': Partial index: listing 3 directories and walking 0 changed directories
2026/10/29 03:28:06 DEBUG : index.html: Unchanged skipping
2026/10/29 03:28:06 DEBUG : sub/index.html: Unchanged skipping
2026/10/29 03:28:07 DEBUG : sub/deep/index.html: md5 = af6e1c701f80584f1015e584df54b098 OK
2026/10/29 03:28:07 DEBUG : sub/deep/index.html: size = 11829 OK
2026/10/29 03:28:07 INFO  : sub/deep/index.html: Copied (replaced existing)
--- PASS: TestIndexChangedDelete (17.54s)
=== RUN   TestIndexChangedFiles
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:28:14 DEBUG : file1.txt: md5 = dbfa010c8b24562299af2ddc2e734edf OK
2026/10/29 03:28:17 DEBUG : sub/file2.txt: md5 = fc87bd8cd37e0f6870560b4c6a2c4cd0 OK
2026/10/29 03:28:19 DEBUG : sub/deep/file3.txt: md5 = c400bb210bf1204036e8721556a4e3be OK
2026/10/29 03:28:21 DEBUG : - odd/file.txt: md5 = eac1c2a07213c954ecbb2324047b0f82 OK
2026/10/29 03:28:24 DEBUG : index.html: md5 = 0fb5c23f72673b3bbc8d0a6720cf2968 OK
2026/10/29 03:28:24 DEBUG : index.html: size = 12401 OK
2026/10/29 03:28:24 INFO  : index.html: Copied (new)
2026/10/29 03:28:24 DEBUG : - odd/index.html: md5 = 66d9d37c0a35d5ca29310af2bd56ff91 OK
2026/10/29 03:28:24 DEBUG : - odd/index.html: size = 12058 OK
2026/10/29 03:28:24 INFO  : - odd/index.html: Copied (new)
2026/10/29 03:28:24 DEBUG : sub/index.html: md5 = c6915d3fad2bdb3f5767377ef1a471a5 OK
2026/10/29 03:28:24 DEBUG : sub/index.html: size = 12306 OK
2026/10/29 03:28:24 INFO  : sub/index.html: Copied (new)
2026/10/29 03:28:24 DEBUG : sub/deep/index.html: md5 = aad1e261f9e2564a53a42877d94509dc OK
2026/10/29 03:28:24 DEBUG : sub/deep/index.html: size = 12087 OK
2026/10/29 03:28:24 INFO  : sub/deep/index.html: Copied (new)
2026/10/29 03:28:25 DEBUG : - odd/file8.txt: md5 = ceda03b0b1fdf52ea2afc2133a747daf OK
2026/10/29 03:28:27 DEBUG : sub/file9.txt: md5 = e19e0c0815026d6720fe6f914b57a7dd OK
2026/10/29 03:28:27 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija': Partial index: listing 2 directories and walking 0 changed directories
2026/10/29 03:28:28 DEBUG : index.html: Unchanged skipping
2026/10/29 03:28:29 DEBUG : - odd/index.html: md5 = 99d05658e047fad02ddd243acd0f4a76 OK
2026/10/29 03:28:29 DEBUG : - odd/index.html: size = 12316 OK
2026/10/29 03:28:29 INFO  : - odd/index.html: Copied (replaced existing)
2026/10/29 03:28:31 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija': Partial index: listing 4 directories and walking 0 changed directories
2026/10/29 03:28:32 DEBUG : - odd/index.html: Unchanged skipping
2026/10/29 03:28:32 DEBUG : index.html: Unchanged skipping
2026/10/29 03:28:32 DEBUG : sub/deep/index.html: Unchanged skipping
2026/10/29 03:28:33 DEBUG : sub/index.html: md5 = 0f7e4a31b9bcc082c63fc515f6c4712d OK
2026/10/29 03:28:33 DEBUG : sub/index.html: size = 12565 OK
2026/10/29 03:28:33 INFO  : sub/index.html: Copied (replaced existing)
--- PASS: TestIndexChangedFiles (29.78s)
=== RUN   TestIndexChangedDirTime
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:28:44 DEBUG : file1.txt: md5 = 45d6d7b6c218587a334e3912591454e4 OK
2026/10/29 03:28:46 DEBUG : sub/file2.txt: md5 = ad866a6daf3b3b1901fff4d6f9b57600 OK
2026/10/29 03:28:49 DEBUG : sub/deep/file3.txt: md5 = adca800411abe7b3e0122ccbb734d109 OK
2026/10/29 03:28:52 DEBUG : other/notes.md: md5 = c990fe24c29a70c5904a919c5b34e648 OK
2026/10/29 03:28:54 DEBUG : sub/index.json: md5 = 69fdf2c99c6c31172d19ccc963af255f OK
2026/10/29 03:28:54 DEBUG : sub/index.json: size = 259 OK
2026/10/29 03:28:54 INFO  : sub/index.json: Copied (new)
2026/10/29 03:28:54 DEBUG : sub/deep/index.json: md5 = 3244b3df1981ab9d86e98fdd621c2357 OK
2026/10/29 03:28:54 DEBUG : sub/deep/index.json: size = 139 OK
2026/10/29 03:28:54 INFO  : sub/deep/index.json: Copied (new)
2026/10/29 03:28:54 DEBUG : index.json: md5 = 16c7ec1c38f9e54963027a4d97e74848 OK
2026/10/29 03:28:54 DEBUG : index.json: size = 379 OK
2026/10/29 03:28:54 INFO  : index.json: Copied (new)
2026/10/29 03:28:54 DEBUG : other/index.json: md5 = a70cf96e03dd5fd7d72ed4983cd2f706 OK
2026/10/29 03:28:54 DEBUG : other/index.json: size = 144 OK
2026/10/29 03:28:54 INFO  : other/index.json: Copied (new)
2026/10/29 03:28:56 DEBUG : sub/deep/file4.txt: md5 = ef944490b12a378e666520e43aec99d8 OK
2026/10/29 03:28:56 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija': Partial index: listing 3 directories and walking 0 changed directories
2026/10/29 03:28:58 DEBUG : sub/deep/index.json: md5 = d67c6a784c87bdf1952860152e226643 OK
2026/10/29 03:28:58 DEBUG : sub/deep/index.json: size = 275 OK
2026/10/29 03:28:58 INFO  : sub/deep/index.json: Copied (replaced existing)
2026/10/29 03:28:59 DEBUG : sub/index.json: md5 = be8fb12a4b874abe5a89235372d8c3f1 OK
2026/10/29 03:28:59 DEBUG : sub/index.json: size = 259 OK
2026/10/29 03:28:59 INFO  : sub/index.json: Copied (replaced existing)
2026/10/29 03:28:59 DEBUG : index.json: md5 = e11b61af479e39ef604bd756c78142c9 OK
2026/10/29 03:28:59 DEBUG : index.json: size = 379 OK
2026/10/29 03:28:59 INFO  : index.json: Copied (replaced existing)
--- PASS: TestIndexChangedDirTime (24.90s)
=== RUN   TestListDirSorted
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:29:09 DEBUG : a.txt: md5 = cc43915bead17b76698f9ab6c20eb138 OK
2026/10/29 03:29:10 DEBUG : zend.txt: md5 = 3fdacfeabbf98e6334317d5c30476caf OK
2026/10/29 03:29:13 DEBUG : sub dir/hello world: md5 = d6c54c40c4cbb5544240bc77c4e33861 OK
2026/10/29 03:29:14 DEBUG : sub dir/hello world2: md5 = d3cb95316c12842bbb7f01b10437a62b OK
2026/10/29 03:29:17 DEBUG : sub dir/ignore dir/.ignore: md5 = 98ff0acdfc583ee5485e50f87bec2811 OK
2026/10/29 03:29:18 DEBUG : sub dir/ignore dir/should be ignored: md5 = d9230aa890b5eb4f2539498f65762a52 OK
2026/10/29 03:29:21 DEBUG : sub dir/sub sub dir/hello world3: md5 = 37ac88157c2604329e35ccf805029fc9 OK
2026/10/29 03:29:22 DEBUG : a.txt: Excluded (Size Filter)
2026/10/29 03:29:22 DEBUG : a.txt: Excluded
2026/10/29 03:29:23 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2026/10/29 03:29:23 DEBUG : sub dir/hello world2: Excluded
2026/10/29 03:29:23 DEBUG : sub dir/hello world: Excluded (Size Filter)
2026/10/29 03:29:23 DEBUG : sub dir/hello world: Excluded
2026/10/29 03:29:24 DEBUG : sub dir/ignore dir: Excluded
2026/10/29 03:29:24 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2026/10/29 03:29:24 DEBUG : sub dir/hello world2: Excluded
2026/10/29 03:29:24 DEBUG : sub dir/hello world: Excluded (Size Filter)
2026/10/29 03:29:24 DEBUG : sub dir/hello world: Excluded
2026/10/29 03:29:24 DEBUG : sub dir/ignore dir: Excluded
--- PASS: TestListDirSorted (23.78s)
=== RUN   TestListDirSortedFn
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:29:33 DEBUG : a.txt: md5 = d7ffc7c5af3de474e90b12867f1fdbcf OK
2026/10/29 03:29:34 DEBUG : zend.txt: md5 = ab27db601437eff99f1375ec40beb706 OK
2026/10/29 03:29:37 DEBUG : sub dir/hello world: md5 = b35194f8fd129471ed8de8abcaa66e75 OK
2026/10/29 03:29:38 DEBUG : sub dir/hello world2: md5 = d168fba5196ac59a34b746fb92a39f3e OK
2026/10/29 03:29:41 DEBUG : sub dir/ignore dir/.ignore: md5 = c86467f05a36d0cccec82b83a7731809 OK
2026/10/29 03:29:42 DEBUG : sub dir/ignore dir/should be ignored: md5 = eedcd2b2f70b2c63a0007819f6e60487 OK
2026/10/29 03:29:45 DEBUG : sub dir/sub sub dir/hello world3: md5 = a5cc954f03ad23a87889eb3c5619c936 OK
2026/10/29 03:29:46 DEBUG : a.txt: Excluded (Size Filter)
2026/10/29 03:29:46 DEBUG : a.txt: Excluded
2026/10/29 03:29:47 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2026/10/29 03:29:47 DEBUG : sub dir/hello world2: Excluded
2026/10/29 03:29:47 DEBUG : sub dir/hello world: Excluded (Size Filter)
2026/10/29 03:29:47 DEBUG : sub dir/hello world: Excluded
2026/10/29 03:29:47 DEBUG : sub dir/ignore dir: Excluded
2026/10/29 03:29:47 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2026/10/29 03:29:47 DEBUG : sub dir/hello world2: Excluded
2026/10/29 03:29:47 DEBUG : sub dir/hello world: Excluded (Size Filter)
2026/10/29 03:29:47 DEBUG : sub dir/hello world: Excluded
2026/10/29 03:29:48 DEBUG : sub dir/ignore dir: Excluded
--- PASS: TestListDirSortedFn (24.58s)
=== RUN   TestListJSON
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:29:57 DEBUG : file1: md5 = 43a40489b60018211960eb9d3904146e OK
2026/10/29 03:30:00 DEBUG : sub/file2: md5 = 7a7b00c728cdead4c34261255002a0bf 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/29 03:30:03 ERROR : file1: Failed to read hash: hash type not supported
=== RUN   TestListJSON/Metadata
2026/10/29 03:30:03 DEBUG : eer8kka55qnghc34cq76ca668g: Fetching metadata
2026/10/29 03:30:03 DEBUG : 150fuo3cn4j1uenq6r4g4qk6t4: Fetching metadata
--- PASS: TestListJSON (10.69s)
    --- PASS: TestListJSON/Default (0.25s)
    --- PASS: TestListJSON/FilesOnly (0.24s)
    --- PASS: TestListJSON/DirsOnly (0.29s)
    --- PASS: TestListJSON/Recurse (0.50s)
    --- PASS: TestListJSON/SubDir (0.24s)
    --- PASS: TestListJSON/NoModTime (0.22s)
    --- PASS: TestListJSON/NoMimeType (0.23s)
    --- PASS: TestListJSON/ShowHash (0.25s)
    --- PASS: TestListJSON/HashTypes (0.25s)
    --- PASS: TestListJSON/Metadata (0.70s)
=== RUN   TestStatJSON
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:30:08 DEBUG : file1: md5 = 1b03ea77df9e908efc48a316fcbeb66c OK
2026/10/29 03:30:10 DEBUG : sub/file2: md5 = dbecbfc1c32ac48e96cdabebedb31f9d 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/29 03:30:14 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-hijuxav3rija/notfound"
2026/10/29 03:30:14 DEBUG : Config file has changed externally - reloading
2026/10/29 03:30:14 DEBUG : Creating backend with remote "TestDrive:crypt/ve8oicq2msl4onulkqnmhaivejssop9vu384tt4t4ggjqf4tf1og/qe0i31qdkoejk60elps0ni5sqk"
--- PASS: TestStatJSON (12.07s)
    --- PASS: TestStatJSON/Root (0.27s)
    --- PASS: TestStatJSON/RootFilesOnly (0.00s)
    --- PASS: TestStatJSON/RootDirsOnly (0.27s)
    --- PASS: TestStatJSON/Dir (0.50s)
    --- PASS: TestStatJSON/DirWithTrailingSlash (0.24s)
    --- PASS: TestStatJSON/File (0.22s)
    --- PASS: TestStatJSON/NotFound (0.47s)
    --- PASS: TestStatJSON/DirFilesOnly (0.25s)
    --- PASS: TestStatJSON/FileFilesOnly (0.26s)
    --- PASS: TestStatJSON/NotFoundFilesOnly (0.21s)
    --- PASS: TestStatJSON/DirDirsOnly (0.27s)
    --- PASS: TestStatJSON/FileDirsOnly (0.23s)
    --- PASS: TestStatJSON/NotFoundDirsOnly (0.26s)
    --- PASS: TestStatJSON/RootNotFound (1.72s)
=== RUN   TestStatJSONMemory
2026/10/29 03:30:19 DEBUG : Creating backend with remote ":memory:"
2026/10/29 03:30:19 DEBUG : Memory root '': File to upload is small (5 bytes), uploading instead of streaming
2026/10/29 03:30:19 DEBUG : sub/file1: size = 5 OK
2026/10/29 03:30:19 DEBUG : sub/file1: md5 = 5d41402abc4b2a76b9719d911017c592 OK
2026/10/29 03:30:19 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-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:30:19 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija': Making directory
2026/10/29 03:30:19 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija': Making directory
--- PASS: TestMkdir (0.70s)
=== RUN   TestLsd
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:30:22 DEBUG : sub dir/hello world: md5 = 03d5f85c74990e0861aa7e58081f8831 OK
--- PASS: TestLsd (5.41s)
=== RUN   TestLs
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:30:27 DEBUG : potato2: md5 = d5c4a582d1a427ff85dddac645f7cdc7 OK
2026/10/29 03:30:28 DEBUG : empty space: md5 = 20fe2aac0f1901ee740349fb3e340abd OK
--- PASS: TestLs (5.58s)
=== RUN   TestLsWithFilesFrom
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:30:32 DEBUG : potato2: md5 = 184673ccc2876fd74456d7babee44f1e OK
2026/10/29 03:30:33 DEBUG : empty space: md5 = af5fab3496deeafbdcfc5d5b94668189 OK
2026/10/29 03:30:34 DEBUG : empty space: Excluded (FilesFrom Filter)
2026/10/29 03:30:34 DEBUG : empty space: Excluded
--- PASS: TestLsWithFilesFrom (5.33s)
=== RUN   TestLsLong
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:30:37 DEBUG : potato2: md5 = 79bc86ee189c638b8730c0beea709b87 OK
2026/10/29 03:30:39 DEBUG : empty space: md5 = dc6057fccf91f7febae1d024742a2b8d OK
--- PASS: TestLsLong (5.08s)
=== RUN   TestHashSums
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:30:42 DEBUG : potato2: md5 = 97e812cde51ffff8fb6482e6739a29f8 OK
2026/10/29 03:30:44 DEBUG : empty space: md5 = b6422a664b0e4dea38a7cc551a7ac86e OK
--- PASS: TestHashSums (5.65s)
=== RUN   TestHashSumsWithErrors
2026/10/29 03:30:46 DEBUG : Creating backend with remote ":memory:"
2026/10/29 03:30:46 ERROR : file1: hash unsupported: hash type not supported
2026/10/29 03:30:46 ERROR : sub/file1: hash unsupported: hash type not supported
2026/10/29 03:30:46 ERROR : sub/file1: hash unsupported: hash type not supported
--- PASS: TestHashSumsWithErrors (0.00s)
=== RUN   TestHashStream
2026/10/29 03:30:46 DEBUG : Creating md5 hash of 0 bytes read from input stream
2026/10/29 03:30:46 DEBUG : Creating md5 hash of 0 bytes read from input stream
2026/10/29 03:30:46 DEBUG : Creating sha1 hash of 0 bytes read from input stream
2026/10/29 03:30:46 DEBUG : Creating sha1 hash of 0 bytes read from input stream
2026/10/29 03:30:46 DEBUG : Creating md5 hash of 12 bytes read from input stream
2026/10/29 03:30:46 DEBUG : Creating md5 hash of 12 bytes read from input stream
2026/10/29 03:30:46 DEBUG : Creating sha1 hash of 12 bytes read from input stream
2026/10/29 03:30:46 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-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:30:48 DEBUG : potato2: md5 = cd55c8a8f3112020b6699c3d65c1ab1d OK
2026/10/29 03:30:50 DEBUG : empty space: md5 = a1eb7ee3135895d27397238f36ba65b9 OK
2026/10/29 03:30:52 DEBUG : sub dir/potato3: md5 = a1a1e05affddb61a49d16ccc859ce121 OK
--- PASS: TestCount (9.55s)
=== RUN   TestDelete
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:30:57 DEBUG : small: md5 = b536a3d44707256b1edad4cb5ca36408 OK
2026/10/29 03:30:59 DEBUG : medium: md5 = 11fd22b1af0f5588ce4f0fa561c3bcdc OK
2026/10/29 03:31:00 DEBUG : large: md5 = 2b681f187d9a86f1a762a4ef6f5733ef OK
2026/10/29 03:31:01 DEBUG : Waiting for deletions to finish
2026/10/29 03:31:01 DEBUG : large: Excluded (Size Filter)
2026/10/29 03:31:02 INFO  : medium: Deleted
2026/10/29 03:31:02 INFO  : small: Deleted
--- PASS: TestDelete (6.90s)
=== RUN   TestDeleteFatalError
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:31:05 DEBUG : file0: md5 = c491db5d020667f7b31c1933bc2072a6 OK
2026/10/29 03:31:06 DEBUG : file1: md5 = 8e98835118edd515192b6e5527c465e0 OK
2026/10/29 03:31:08 DEBUG : file2: md5 = ddd918d9f8bde7738942bdec8eb23d0c OK
2026/10/29 03:31:09 DEBUG : file3: md5 = f8ddb23f46b4436092055798de010e92 OK
2026/10/29 03:31:11 DEBUG : file4: md5 = 9a3f315fc76e2f6eb0c664c90c558a71 OK
2026/10/29 03:31:12 DEBUG : file5: md5 = 6d68a85b7962e782c2162324476ee2bc OK
2026/10/29 03:31:14 DEBUG : file6: md5 = a48a95dd6ea3a0661b5093c9fbe90dbf OK
2026/10/29 03:31:15 DEBUG : file7: md5 = 130e93e2b60878cf3a799c266c2fb18f OK
2026/10/29 03:31:17 DEBUG : file8: md5 = 6c9add75635f166bef762901515535eb OK
2026/10/29 03:31:18 DEBUG : file9: md5 = 86fcebb450d92decc55000cc2b4fb50f OK
2026/10/29 03:31:19 DEBUG : file10: md5 = cc308b26eecd5090492017c25ceebfce OK
2026/10/29 03:31:21 DEBUG : file11: md5 = ce0398f7ea53e2c8c25690428b410fea OK
2026/10/29 03:31:23 DEBUG : file12: md5 = 9b67e1b351660fdd1582da096d64a5f3 OK
2026/10/29 03:31:24 DEBUG : file13: md5 = acf416db57b0851e1b26316eea369b73 OK
2026/10/29 03:31:26 DEBUG : file14: md5 = 775be369b08f92e09827a9e67d8bfea8 OK
2026/10/29 03:31:27 DEBUG : file15: md5 = 988f631400280581aa5c6de366fedf44 OK
2026/10/29 03:31:29 DEBUG : file16: md5 = 63020c8722596f71b7404fc20c8cca82 OK
2026/10/29 03:31:31 DEBUG : file17: md5 = 24e434277a75612792112c957cce571f OK
2026/10/29 03:31:32 DEBUG : file18: md5 = 2b9197c9e32423f0635d1b5e73deb89f OK
2026/10/29 03:31:34 DEBUG : file19: md5 = f57092d0425fb74047c66c758b1b3e94 OK
2026/10/29 03:31:34 DEBUG : Waiting for deletions to finish
2026/10/29 03:31:34 ERROR : file10: Got fatal error on delete: --max-delete threshold reached
2026/10/29 03:31:34 ERROR : file5: Got fatal error on delete: --max-delete threshold reached
2026/10/29 03:31:34 ERROR : file4: Got fatal error on delete: --max-delete threshold reached
2026/10/29 03:31:34 ERROR : file3: Got fatal error on delete: --max-delete threshold reached
2026/10/29 03:31:34 ERROR : file7: Got fatal error on delete: --max-delete threshold reached
2026/10/29 03:31:34 ERROR : file19: Got fatal error on delete: --max-delete threshold reached
2026/10/29 03:31:34 ERROR : file12: Got fatal error on delete: --max-delete threshold reached
2026/10/29 03:31:34 ERROR : file9: Got fatal error on delete: --max-delete threshold reached
2026/10/29 03:31:34 ERROR : file11: Got fatal error on delete: --max-delete threshold reached
2026/10/29 03:31:34 ERROR : file15: Got fatal error on delete: --max-delete threshold reached
2026/10/29 03:31:34 ERROR : file8: Got fatal error on delete: --max-delete threshold reached
2026/10/29 03:31:34 ERROR : file17: Got fatal error on delete: --max-delete threshold reached
2026/10/29 03:31:34 ERROR : file6: Got fatal error on delete: --max-delete threshold reached
2026/10/29 03:31:34 ERROR : file13: Got fatal error on delete: --max-delete threshold reached
2026/10/29 03:31:34 ERROR : file16: Got fatal error on delete: --max-delete threshold reached
2026/10/29 03:31:34 ERROR : file1: Got fatal error on delete: --max-delete threshold reached
2026/10/29 03:31:34 ERROR : file14: Got fatal error on delete: --max-delete threshold reached
2026/10/29 03:31:34 ERROR : file0: Got fatal error on delete: --max-delete threshold reached
2026/10/29 03:31:34 ERROR : file18: Got fatal error on delete: --max-delete threshold reached
2026/10/29 03:31:34 ERROR : file2: Got fatal error on delete: --max-delete threshold reached
--- PASS: TestDeleteFatalError (42.43s)
=== RUN   TestMaxDelete
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:31:47 DEBUG : small: md5 = 4c85ea4dc6dad776fdb5d97c51b7f39d OK
2026/10/29 03:31:48 DEBUG : medium: md5 = 2b6596553f12483952049b5b9df405d6 OK
2026/10/29 03:31:50 DEBUG : large: md5 = 11dc8e2ad0092ac7c944761dbb0eeaa6 OK
2026/10/29 03:31:50 DEBUG : Waiting for deletions to finish
2026/10/29 03:31:51 ERROR : medium: Got fatal error on delete: --max-delete threshold reached
2026/10/29 03:31:51 INFO  : large: Deleted
2026/10/29 03:31:51 INFO  : small: Deleted
--- PASS: TestMaxDelete (8.27s)
=== RUN   TestMaxDeleteSizeLargeFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:31:55 DEBUG : small: md5 = 05b4ab62eb4b9d51ef5afe774f372303 OK
2026/10/29 03:31:57 DEBUG : medium: md5 = 7a3966972834a763c449733bea9553ef OK
2026/10/29 03:31:58 DEBUG : large: md5 = 6d97d170067c46dee3c5bd9813373dec OK
2026/10/29 03:31:59 DEBUG : Waiting for deletions to finish
2026/10/29 03:31:59 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached
2026/10/29 03:32:00 INFO  : medium: Deleted
2026/10/29 03:32:00 INFO  : small: Deleted
--- PASS: TestMaxDeleteSizeLargeFile (7.91s)
=== RUN   TestMaxDeleteSize
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:32:03 DEBUG : small: md5 = 701d50543f2430a666995fc1f9e3358c OK
2026/10/29 03:32:05 DEBUG : medium: md5 = 902d9060a68f79bb3893fc5c30960068 OK
2026/10/29 03:32:06 DEBUG : large: md5 = 14f14e80877ba9526bafd2f890e20d43 OK
2026/10/29 03:32:06 DEBUG : Waiting for deletions to finish
2026/10/29 03:32:07 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached
2026/10/29 03:32:07 INFO  : medium: Deleted
2026/10/29 03:32:07 INFO  : small: Deleted
--- PASS: TestMaxDeleteSize (7.76s)
=== RUN   TestReadFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:32:11 DEBUG : ReadFile: md5 = 717a15fb6e0ecd3d88136ec8827df6ab OK
--- PASS: TestReadFile (4.21s)
=== RUN   TestRetry
2026/10/29 03:32:13 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 1/5
2026/10/29 03:32:13 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 2/5
2026/10/29 03:32:13 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms
2026/10/29 03:32:13 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms
2026/10/29 03:32:13 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms
2026/10/29 03:32:13 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/29 03:32:13 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-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:32:15 DEBUG : file1: md5 = 72195ffc5dce4d74c5499d05a952e773 OK
2026/10/29 03:32:17 DEBUG : file2: md5 = b84db8d5a51837973eb502ff716037bf OK
--- PASS: TestCat (13.95s)
=== RUN   TestPurge
2026/10/29 03:32:27 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-fodexor9tota"
2026/10/29 03:32:27 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/29 03:32:27 DEBUG : Creating backend with remote "TestDrive:crypt/a4q5upf4abs5cavt6n0rltek56iio4st616f5up5lni2eq8jsr60"
2026/10/29 03:32:28 DEBUG : Creating backend with remote "/tmp/rclone1591915594"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fodexor9tota'", Local "Local file system at /tmp/rclone1591915594", Modify Window "1ms"
2026/10/29 03:32:34 DEBUG : A1/B1/C1/one: md5 = e9812d9c904fbc63fef92c1a780f3067 OK
2026/10/29 03:32:34 INFO  : A2: Making directory
2026/10/29 03:32:34 INFO  : A1/B2: Making directory
2026/10/29 03:32:35 INFO  : A1/B2/C2: Making directory
2026/10/29 03:32:36 INFO  : A1/B1/C3: Making directory
2026/10/29 03:32:37 INFO  : A3: Making directory
2026/10/29 03:32:38 INFO  : A3/B3: Making directory
2026/10/29 03:32:39 INFO  : A3/B3/C4: Making directory
2026/10/29 03:32:41 DEBUG : A1/two: md5 = e88e14ae23d657c1a3b67b1232eca1d8 OK
2026/10/29 03:32:45 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-fodexor9tota': Purge remote
2026/10/29 03:32:45 NOTICE: purge failed: directory not found
--- PASS: TestPurge (17.69s)
=== RUN   TestRmdirsNoLeaveRoot
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:32:49 DEBUG : A1/B1/C1/one: md5 = 51ccc1a37b3739c2e02c4800d7a81e73 OK
2026/10/29 03:32:49 INFO  : A2: Making directory
2026/10/29 03:32:50 INFO  : A1/B2: Making directory
2026/10/29 03:32:51 INFO  : A1/B2/C2: Making directory
2026/10/29 03:32:52 INFO  : A1/B1/C3: Making directory
2026/10/29 03:32:53 INFO  : A3: Making directory
2026/10/29 03:32:53 INFO  : A3/B3: Making directory
2026/10/29 03:32:54 INFO  : A3/B3/C4: Making directory
2026/10/29 03:32:57 DEBUG : A1/two: md5 = 524b59525c43ef3f11b3e9e2b54b28fe OK
2026/10/29 03:32:58 DEBUG : removing 1 level 3 directories
2026/10/29 03:32:58 INFO  : A3/B3/C4: Removing directory
2026/10/29 03:33:01 DEBUG : removing 2 level 3 directories
2026/10/29 03:33:01 INFO  : A1/B2/C2: Removing directory
2026/10/29 03:33:01 INFO  : A1/B1/C3: Removing directory
2026/10/29 03:33:02 DEBUG : removing 2 level 2 directories
2026/10/29 03:33:02 INFO  : A3/B3: Removing directory
2026/10/29 03:33:02 INFO  : A1/B2: Removing directory
2026/10/29 03:33:03 DEBUG : removing 2 level 1 directories
2026/10/29 03:33:03 INFO  : A3: Removing directory
2026/10/29 03:33:03 INFO  : A2: Removing directory
2026/10/29 03:33:07 DEBUG : removing 1 level 3 directories
2026/10/29 03:33:07 INFO  : A1/B1/C1: Removing directory
2026/10/29 03:33:08 DEBUG : removing 1 level 2 directories
2026/10/29 03:33:08 INFO  : A1/B1: Removing directory
2026/10/29 03:33:09 DEBUG : removing 1 level 1 directories
2026/10/29 03:33:09 INFO  : A1: Removing directory
2026/10/29 03:33:10 DEBUG : removing 1 level 0 directories
2026/10/29 03:33:10 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija': Removing directory
--- PASS: TestRmdirsNoLeaveRoot (25.97s)
=== RUN   TestRmdirsLeaveRoot
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:33:12 INFO  : A1: Making directory
2026/10/29 03:33:13 INFO  : A1/B1: Making directory
2026/10/29 03:33:14 INFO  : A1/B1/C1: Making directory
2026/10/29 03:33:16 DEBUG : removing 1 level 3 directories
2026/10/29 03:33:16 INFO  : A1/B1/C1: Removing directory
2026/10/29 03:33:17 DEBUG : removing 1 level 2 directories
2026/10/29 03:33:17 INFO  : A1/B1: Removing directory
--- PASS: TestRmdirsLeaveRoot (8.67s)
=== RUN   TestRmdirsWithFilter
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:33:20 INFO  : A1: Making directory
2026/10/29 03:33:21 INFO  : A1/B1: Making directory
2026/10/29 03:33:21 INFO  : A1/B1/C1: Making directory
2026/10/29 03:33:24 DEBUG : removing 1 level 3 directories
2026/10/29 03:33:24 INFO  : A1/B1/C1: Removing directory
2026/10/29 03:33:25 DEBUG : removing 1 level 2 directories
2026/10/29 03:33:25 INFO  : A1/B1: Removing directory
--- PASS: TestRmdirsWithFilter (8.09s)
=== RUN   TestCopyURL
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:33:30 DEBUG : file1: md5 = 95b25f528dab04487fa7fd88ae255150 OK
2026/10/29 03:33:30 DEBUG : file1: size = 14 OK
2026/10/29 03:33:30 DEBUG : filename.txt: File name found in url
2026/10/29 03:33:32 DEBUG : filename.txt: md5 = 47ec6aabaf5a8b562372c94ff3c5196e OK
2026/10/29 03:33:32 DEBUG : filename.txt: size = 14 OK
2026/10/29 03:33:32 DEBUG : headerfilename.txt: filename found in Content-Disposition header.
2026/10/29 03:33:33 DEBUG : headerfilename.txt: md5 = 4d21002d5bc83b54c5c70fecae529b70 OK
2026/10/29 03:33:33 DEBUG : headerfilename.txt: size = 14 OK
2026/10/29 03:33:35 DEBUG : file2: md5 = d94fef13cbf000c1123132da7a2f2373 OK
2026/10/29 03:33:35 DEBUG : file2: size = 14 OK
--- PASS: TestCopyURL (10.16s)
=== RUN   TestCopyURLDownloadHeaders
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:33:40 DEBUG : file1: md5 = 3c63bad1c35979b6b9d245b4211e6fce OK
2026/10/29 03:33:40 DEBUG : file1: size = 14 OK
--- PASS: TestCopyURLDownloadHeaders (2.77s)
=== RUN   TestCopyURLToWriter
--- PASS: TestCopyURLToWriter (0.00s)
=== RUN   TestMoveFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:33:41 DEBUG : file1: Need to transfer - File not found at Destination
2026/10/29 03:33:44 DEBUG : sub/file2: md5 = 03c9d78beef9b5f574f2f9eb251aafa4 OK
2026/10/29 03:33:44 DEBUG : sub/file2: size = 14 OK
2026/10/29 03:33:44 INFO  : file1: Copied (new) to: sub/file2
2026/10/29 03:33:44 INFO  : file1: Deleted
2026/10/29 03:33:44 DEBUG : sub/file2: size = 14 OK
2026/10/29 03:33:44 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2026/10/29 03:33:44 DEBUG : file1: Unchanged skipping
2026/10/29 03:33:44 INFO  : file1: Deleted
2026/10/29 03:33:45 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija': don't need to copy/move sub/file2, it is already at target location
--- PASS: TestMoveFile (6.68s)
=== RUN   TestMoveFileWithIgnoreExisting
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:33:48 DEBUG : file1: Need to transfer - File not found at Destination
2026/10/29 03:33:49 DEBUG : file1: md5 = fd5d1097f1e706b4b35e25e415b57a9b OK
2026/10/29 03:33:49 DEBUG : file1: size = 14 OK
2026/10/29 03:33:49 INFO  : file1: Copied (new)
2026/10/29 03:33:49 INFO  : file1: Deleted
2026/10/29 03:33:50 DEBUG : file1: Destination exists, skipping
2026/10/29 03:33:50 DEBUG : file1: Not removing source file as destination file exists and --ignore-existing is set
--- PASS: TestMoveFileWithIgnoreExisting (3.60s)
=== RUN   TestMoveFileImmutable
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:33:53 DEBUG : existing: md5 = 6e802db6f49f3c455dd32e9dcf18a68a OK
2026/10/29 03:33:53 DEBUG : existing: size = 8 (Local file system at /tmp/rclone2749422086)
2026/10/29 03:33:53 DEBUG : existing: size = 6 (Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija')
2026/10/29 03:33:53 DEBUG : existing: Sizes differ
2026/10/29 03:33:53 ERROR : existing: Source and destination exist but do not match: immutable file modified
--- PASS: TestMoveFileImmutable (3.84s)
=== RUN   TestCaseInsensitiveMoveFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
--- PASS: TestCaseInsensitiveMoveFile (0.86s)
=== RUN   TestMoveOntoItself
=== RUN   TestMoveOntoItself/viaSubdir=false,disableMove=false
2026/10/29 03:33:56 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-sizefaq3ceha"
2026/10/29 03:33:56 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/29 03:33:56 DEBUG : Creating backend with remote "TestDrive:crypt/vbughv76f0g9gduolv6spntta932m1vamj10rne0gkoaqv1h4mq0"
2026/10/29 03:33:57 DEBUG : Creating backend with remote "/tmp/rclone3944724187"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-sizefaq3ceha'", Local "Local file system at /tmp/rclone3944724187", Modify Window "1ms"
2026/10/29 03:34:00 DEBUG : sub/file1: md5 = 1ff3009f6b327310cde15a251683ea60 OK
2026/10/29 03:34:01 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-sizefaq3ceha': Purge remote
=== RUN   TestMoveOntoItself/viaSubdir=false,disableMove=true
2026/10/29 03:34:02 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-vucuguv1nore"
2026/10/29 03:34:02 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/29 03:34:02 DEBUG : Creating backend with remote "TestDrive:crypt/ne9ku9vmsu3tia7751n8m99brojgud80suk6jjap26n0u2o1kprg"
2026/10/29 03:34:03 DEBUG : Creating backend with remote "/tmp/rclone3888570702"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-vucuguv1nore'", Local "Local file system at /tmp/rclone3888570702", Modify Window "1ms"
2026/10/29 03:34:06 DEBUG : sub/file1: md5 = cb325e5211f154866d69348d51ce93c8 OK
2026/10/29 03:34:06 DEBUG : Reset feature "Move"
2026/10/29 03:34:06 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-vucuguv1nore': Purge remote
=== RUN   TestMoveOntoItself/viaSubdir=true,disableMove=false
2026/10/29 03:34:07 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-mijuhes2lowe"
2026/10/29 03:34:07 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/29 03:34:07 DEBUG : Creating backend with remote "TestDrive:crypt/15i3s90j5ccpai4ildg5ta8ufdifm1ovf9173dti9gburv3p20r0"
2026/10/29 03:34:08 DEBUG : Creating backend with remote "/tmp/rclone1034689052"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mijuhes2lowe'", Local "Local file system at /tmp/rclone1034689052", Modify Window "1ms"
2026/10/29 03:34:11 DEBUG : sub/file1: md5 = 60187d346fe77747974501edb9d452af OK
2026/10/29 03:34:12 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-mijuhes2lowe/sub"
2026/10/29 03:34:12 DEBUG : Creating backend with remote "TestDrive:crypt/15i3s90j5ccpai4ildg5ta8ufdifm1ovf9173dti9gburv3p20r0/150fuo3cn4j1uenq6r4g4qk6t4"
2026/10/29 03:34:13 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-mijuhes2lowe': Purge remote
=== RUN   TestMoveOntoItself/viaSubdir=true,disableMove=true
2026/10/29 03:34:13 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-puxosom0boda"
2026/10/29 03:34:13 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/29 03:34:13 DEBUG : Creating backend with remote "TestDrive:crypt/ti0gide82c36b32ec5h9oshi6fjpe278ks3jfquuuonkmsb3aaag"
2026/10/29 03:34:14 DEBUG : Creating backend with remote "/tmp/rclone3089365137"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-puxosom0boda'", Local "Local file system at /tmp/rclone3089365137", Modify Window "1ms"
2026/10/29 03:34:17 DEBUG : sub/file1: md5 = 57d3c85e24ddb9e0f69b5976fd2d24da OK
2026/10/29 03:34:18 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-puxosom0boda/sub"
2026/10/29 03:34:18 DEBUG : Creating backend with remote "TestDrive:crypt/ti0gide82c36b32ec5h9oshi6fjpe278ks3jfquuuonkmsb3aaag/150fuo3cn4j1uenq6r4g4qk6t4"
2026/10/29 03:34:19 DEBUG : Reset feature "Move"
2026/10/29 03:34:19 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-puxosom0boda': Purge remote
--- PASS: TestMoveOntoItself (24.19s)
    --- PASS: TestMoveOntoItself/viaSubdir=false,disableMove=false (5.80s)
    --- PASS: TestMoveOntoItself/viaSubdir=false,disableMove=true (5.46s)
    --- PASS: TestMoveOntoItself/viaSubdir=true,disableMove=false (6.34s)
    --- PASS: TestMoveOntoItself/viaSubdir=true,disableMove=true (6.58s)
=== RUN   TestMoveUnicodeEquivalentWithoutServerSideMove
2026/10/29 03:34:20 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-hejiheb7lika"
2026/10/29 03:34:20 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/29 03:34:20 DEBUG : Creating backend with remote "TestDrive:crypt/tdsupleaopa4rv8f2l8vjsqmhqlt9on7de9gsvci3dvgc848f57g"
2026/10/29 03:34:21 DEBUG : Creating backend with remote "/tmp/rclone1392715264"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hejiheb7lika'", Local "Local file system at /tmp/rclone1392715264", Modify Window "1ms"
2026/10/29 03:34:21 DEBUG : Reset feature "Move"
2026/10/29 03:34:24 DEBUG : sub/équivalent.txt: md5 = ad193cf505e17740e7a29da8b01d3abf OK
2026/10/29 03:34:25 DEBUG : sub/équivalent.txt: moving to sub/équivalent.txt-rclone-move-diqiwid6
2026/10/29 03:34:26 DEBUG : sub/équivalent.txt-rclone-move-diqiwid6: size = 14 OK
2026/10/29 03:34:26 INFO  : sub/équivalent.txt: Copied (server-side copy) to: sub/équivalent.txt-rclone-move-diqiwid6
2026/10/29 03:34:27 INFO  : sub/équivalent.txt: Deleted
2026/10/29 03:34:27 DEBUG : sub/équivalent.txt: moving to sub/équivalent.txt
2026/10/29 03:34:28 DEBUG : sub/équivalent.txt: size = 14 OK
2026/10/29 03:34:28 INFO  : sub/équivalent.txt-rclone-move-diqiwid6: Copied (server-side copy) to: sub/équivalent.txt
2026/10/29 03:34:28 INFO  : sub/équivalent.txt-rclone-move-diqiwid6: Deleted
2026/10/29 03:34:29 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-hejiheb7lika': Purge remote
--- PASS: TestMoveUnicodeEquivalentWithoutServerSideMove (9.47s)
=== RUN   TestCaseInsensitiveMoveWithoutServerSideMove
2026/10/29 03:34:29 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-wacecob2viqe"
2026/10/29 03:34:29 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/29 03:34:29 DEBUG : Creating backend with remote "TestDrive:crypt/tqrg3k11ucbo1a0li0jp2u8jt9fuvmlgu9pcvjtejs0enol8aang"
2026/10/29 03:34:30 DEBUG : Creating backend with remote "/tmp/rclone1989569122"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wacecob2viqe'", Local "Local file system at /tmp/rclone1989569122", Modify Window "1ms"
    operations_test.go:1196: remote is case sensitive
2026/10/29 03:34:30 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-wacecob2viqe': Purge remote
2026/10/29 03:34:31 NOTICE: purge failed: directory not found
--- SKIP: TestCaseInsensitiveMoveWithoutServerSideMove (1.23s)
=== RUN   TestCaseInsensitiveMoveFileDryRun
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
--- PASS: TestCaseInsensitiveMoveFileDryRun (0.46s)
=== RUN   TestMoveFileBackupDir
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:34:33 DEBUG : dst/file1: md5 = 03da224653acb7b9096c5a9d4f0a7d0c OK
2026/10/29 03:34:34 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-hijuxav3rija/backup"
2026/10/29 03:34:34 DEBUG : Creating backend with remote "TestDrive:crypt/ve8oicq2msl4onulkqnmhaivejssop9vu384tt4t4ggjqf4tf1og/1nrff024r7pq65ecp72fc28jb0"
2026/10/29 03:34:36 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone2749422086)
2026/10/29 03:34:36 DEBUG : dst/file1: size = 18 (Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija')
2026/10/29 03:34:36 DEBUG : dst/file1: Sizes differ
2026/10/29 03:34:38 INFO  : dst/file1: Moved (server-side)
2026/10/29 03:34:40 DEBUG : dst/file1: md5 = 16d04e0771dfd92105c106af8139ef4b OK
2026/10/29 03:34:40 DEBUG : dst/file1: size = 14 OK
2026/10/29 03:34:40 INFO  : dst/file1: Copied (new)
2026/10/29 03:34:40 INFO  : dst/file1: Deleted
--- PASS: TestMoveFileBackupDir (13.81s)
=== 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-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:34:47 DEBUG : A/file0: md5 = 136c85a683332c8c26e80398ea7c960e OK
2026/10/29 03:34:49 DEBUG : A/file1: md5 = 8073a3cf257c7e2fc7c4c144ce26a5c2 OK
2026/10/29 03:34:50 DEBUG : A/file2: md5 = 4b681dcb3136176e30458524be5afc01 OK
2026/10/29 03:34:52 DEBUG : A/file3: md5 = 9964798d43b14779f9efe5a6d40460de OK
2026/10/29 03:34:53 DEBUG : A/file4: md5 = 080b377439431b16dac3d519d5e454ee OK
2026/10/29 03:34:55 DEBUG : A/file5: md5 = ae02a187c9a53d7dacfec6e706d50335 OK
2026/10/29 03:34:56 DEBUG : A/file6: md5 = 023bcdb8b736b0ab6d8121c0cfe1afac OK
2026/10/29 03:34:58 DEBUG : A/file7: md5 = 5346e8f69657a52340f2b869d70de30c OK
2026/10/29 03:34:59 DEBUG : A/file8: md5 = cfda5766e8706dba601ed55836791d2c OK
2026/10/29 03:35:01 DEBUG : A/file9: md5 = 50eb56666bc81515b037c3a8d0cf4bec OK
2026/10/29 03:35:02 DEBUG : A/file10: md5 = dcb99b29eb93888f9cb6d1d85b26c36a OK
2026/10/29 03:35:04 DEBUG : A/file11: md5 = 4898be20769a22370fbe58ebdad05e6f OK
2026/10/29 03:35:05 DEBUG : A/file12: md5 = d82098704e753b3f27489c4c8ca74a69 OK
2026/10/29 03:35:07 DEBUG : A/file13: md5 = 23404192e11c56d2aa22d13979b81e70 OK
2026/10/29 03:35:08 DEBUG : A/file14: md5 = e330cf2ace8a8c564a506409500ed1bb OK
2026/10/29 03:35:10 DEBUG : A/file15: md5 = 28c9a5144c62d3ae77594d2d38c9673b OK
2026/10/29 03:35:11 DEBUG : A/file16: md5 = b0794f90092d269d9e35d71ef1f70731 OK
2026/10/29 03:35:13 DEBUG : A/file17: md5 = fb3d5d5be0a055625c86fc062540b380 OK
2026/10/29 03:35:14 DEBUG : A/file18: md5 = 13be0561053a031963b7d5d102607c6a OK
2026/10/29 03:35:16 DEBUG : A/file19: md5 = 3746162d7ce67b45cb3be34b89048de1 OK
    operations_test.go:1561: Skipping test on non local remote
--- SKIP: TestDirMoveMoveError (43.18s)
=== RUN   TestDirMoveContext
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:35:30 DEBUG : A/one: md5 = 7d68931ad239ca43ee36b99bb6cf1c08 OK
2026/10/29 03:35:32 DEBUG : A/two: md5 = ba33b6d6c3c1a56d44693639813765f7 OK
    operations_test.go:1561: Skipping test on non local remote
--- SKIP: TestDirMoveContext (6.74s)
=== RUN   TestDirMove
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:35:37 DEBUG : A1/one: md5 = 2095e1a1a5d3e3c1d1a85d9bd954156b OK
2026/10/29 03:35:39 DEBUG : A1/two: md5 = d7b777f7843c48f1aecc66e75a110f56 OK
2026/10/29 03:35:41 DEBUG : A1/B1/three: md5 = e001eae317b2b7f9f41d4f50f519cd4b OK
2026/10/29 03:35:43 DEBUG : A1/B1/C1/four: md5 = b67bc3d26c482c1bfbcfaa2cd5049189 OK
2026/10/29 03:35:46 DEBUG : A1/B1/C2/five: md5 = 773768b7b763a059954bdd7162393354 OK
2026/10/29 03:35:46 INFO  : A1/B2: Making directory
2026/10/29 03:35:47 INFO  : A1/B1/C3: Making directory
2026/10/29 03:35:57 INFO  : A2/B1/three: Moved (server-side) to: A3/B1/three
2026/10/29 03:35:58 INFO  : A2/B1/C2/five: Moved (server-side) to: A3/B1/C2/five
2026/10/29 03:35:58 INFO  : A2/two: Moved (server-side) to: A3/two
2026/10/29 03:35:58 INFO  : A2/B1/C1/four: Moved (server-side) to: A3/B1/C1/four
2026/10/29 03:35:58 INFO  : A2/one: Moved (server-side) to: A3/one
2026/10/29 03:36:04 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija': Can't DirMove - falling back to file moves: can't move directory - incompatible remotes
2026/10/29 03:36:11 INFO  : A3/one: Moved (server-side) to: A4/one
2026/10/29 03:36:11 INFO  : A3/B1/C2/five: Moved (server-side) to: A4/B1/C2/five
2026/10/29 03:36:11 INFO  : A3/B1/C1/four: Moved (server-side) to: A4/B1/C1/four
2026/10/29 03:36:11 INFO  : A3/two: Moved (server-side) to: A4/two
2026/10/29 03:36:12 INFO  : A3/B1/three: Moved (server-side) to: A4/B1/three
--- PASS: TestDirMove (52.89s)
=== RUN   TestGetFsInfo
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
--- PASS: TestGetFsInfo (0.48s)
=== RUN   TestRcat
=== RUN   TestRcat/withChecksum=false,ignoreChecksum=false
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:36:28 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija': File to upload is small (34 bytes), uploading instead of streaming
2026/10/29 03:36:30 DEBUG : no_checksum_small_file_from_pipe: md5 = c337719c64dccaf55476b552318d0d3b OK
2026/10/29 03:36:30 DEBUG : no_checksum_small_file_from_pipe: size = 34 OK
2026/10/29 03:36:30 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only
2026/10/29 03:36:30 DEBUG : no_checksum_small_file_from_pipe: Size of src and dst objects identical
2026/10/29 03:36:31 DEBUG : qc9k4uauqa9ls6a2r6lo8jf4b1m7r0510pkurois1cc70egbs4h0: Sending chunk 0 length 102465
2026/10/29 03:36:32 DEBUG : no_checksum_big_file_from_pipe: md5 = 934ccc06cfb0dc41c5f923b7560bd3db OK
2026/10/29 03:36:32 DEBUG : no_checksum_big_file_from_pipe: size = 102401 OK
2026/10/29 03:36:32 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-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:36:34 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija': File to upload is small (34 bytes), uploading instead of streaming
2026/10/29 03:36:35 DEBUG : with_checksum_small_file_from_pipe: md5 = dbf0df214b9f04e518e8d6e2fb30f24e OK
2026/10/29 03:36:35 DEBUG : with_checksum_small_file_from_pipe: size = 34 OK
2026/10/29 03:36:35 DEBUG : with_checksum_small_file_from_pipe: Size of src and dst objects identical
2026/10/29 03:36:36 DEBUG : e74jo8a7lr1aimg2u78fct67gnuetc1qujiofaci0c8fnq7rjt1d4i296pcklqhgdihs03etvgdi4: Sending chunk 0 length 102465
2026/10/29 03:36:37 DEBUG : with_checksum_big_file_from_pipe: md5 = 52af9e0ca3d07c047e8b1c04b25c3ccb OK
2026/10/29 03:36:37 DEBUG : with_checksum_big_file_from_pipe: size = 102401 OK
2026/10/29 03:36:37 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-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:36:40 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija': File to upload is small (34 bytes), uploading instead of streaming
2026/10/29 03:36:41 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK
2026/10/29 03:36:41 DEBUG : ignore_checksum_small_file_from_pipe: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2026/10/29 03:36:42 DEBUG : 1963rn6olajfbjn4nbqi9nacu7bk879vpf0d2bg8r0ndcqqsfntob0urt056p0qujf574rqnb5f62: Sending chunk 0 length 102465
2026/10/29 03:36:43 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK
2026/10/29 03:36:43 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-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:36:45 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija': File to upload is small (34 bytes), uploading instead of streaming
2026/10/29 03:36:47 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK
2026/10/29 03:36:47 DEBUG : ignore_checksum_small_file_from_pipe: Size of src and dst objects identical
2026/10/29 03:36:47 DEBUG : 1963rn6olajfbjn4nbqi9nacu7bk879vpf0d2bg8r0ndcqqsfntob0urt056p0qujf574rqnb5f62: Sending chunk 0 length 102465
2026/10/29 03:36:49 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK
2026/10/29 03:36:49 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical
--- PASS: TestRcat (22.27s)
    --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (5.32s)
    --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (5.78s)
    --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (5.54s)
    --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (5.63s)
=== RUN   TestRcatMetadata
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
=== RUN   TestRcatMetadata/Normal
2026/10/29 03:36:51 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija': File to upload is small (48 bytes), uploading instead of streaming
2026/10/29 03:36:52 DEBUG : rcat_metadata: md5 = ab01ae3141d202070f4e29444bfd1d67 OK
2026/10/29 03:36:52 DEBUG : rcat_metadata: size = 48 OK
2026/10/29 03:36:52 DEBUG : rcat_metadata: Size of src and dst objects identical
=== RUN   TestRcatMetadata/ViaDisk
2026/10/29 03:36:54 DEBUG : rijssoobhbn85c5p1imhg55m6frank1nlplla6hkdjlqmqb2471g: Sending chunk 0 length 111
2026/10/29 03:36:56 DEBUG : rcat_metadata_uploadcutoff0: md5 = 933c11e1efe861595864601ebd5268bd OK
2026/10/29 03:36:56 DEBUG : rcat_metadata_uploadcutoff0: size = 63 OK
2026/10/29 03:36:56 DEBUG : rcat_metadata_uploadcutoff0: Size of src and dst objects identical
--- PASS: TestRcatMetadata (6.60s)
    --- PASS: TestRcatMetadata/Normal (2.90s)
    --- PASS: TestRcatMetadata/ViaDisk (3.24s)
=== RUN   TestRcatSize
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:36:59 DEBUG : potato1: md5 = 59817c6619bc24c4e34c1a1a61055fa3 OK
2026/10/29 03:36:59 DEBUG : potato1: size = 60 OK
2026/10/29 03:36:59 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija': File to upload is small (60 bytes), uploading instead of streaming
2026/10/29 03:37:00 DEBUG : potato2: md5 = 731427c27230f0946497a248c9caba34 OK
2026/10/29 03:37:00 DEBUG : potato2: size = 60 OK
2026/10/29 03:37:00 DEBUG : potato2: Size of src and dst objects identical
--- PASS: TestRcatSize (4.89s)
=== RUN   TestRcatSizeShortEOF
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:37:03 DEBUG : potato1: md5 = 7752b55b3d5093533a97831030c1f407 OK
2026/10/29 03:37:03 DEBUG : potato1: size = 120 (Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija')
2026/10/29 03:37:03 DEBUG : potato1: size = 60 (Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija')
2026/10/29 03:37:03 ERROR : potato1: corrupted on transfer: sizes differ src 120 vs dst(Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija') 60
2026/10/29 03:37:03 INFO  : potato1: Removing failed copy
--- PASS: TestRcatSizeShortEOF (2.41s)
=== RUN   TestRcatSizeMetadata
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:37:06 DEBUG : potato1: md5 = 36c24e5c2caaa080fd1f1591933d90b7 OK
2026/10/29 03:37:06 DEBUG : potato1: size = 60 OK
2026/10/29 03:37:06 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija': File to upload is small (60 bytes), uploading instead of streaming
2026/10/29 03:37:08 DEBUG : potato2: md5 = c0203b8d5dc3f99e6470608b2b4308cc OK
2026/10/29 03:37:08 DEBUG : potato2: size = 60 OK
2026/10/29 03:37:08 DEBUG : potato2: Size of src and dst objects identical
--- PASS: TestRcatSizeMetadata (5.63s)
=== RUN   TestRcatSizeUploadHeaders
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:37:12 DEBUG : potato1: md5 = 3add21be150c2a4bdb9149534f0d5a31 OK
2026/10/29 03:37:12 DEBUG : potato1: size = 60 OK
--- PASS: TestRcatSizeUploadHeaders (2.50s)
=== RUN   TestRcatSizeChecksum
=== RUN   TestRcatSizeChecksum/Corrupted
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
    operations_test.go:2046: Skipping as destination doesn't support hashes
=== RUN   TestRcatSizeChecksum/SizeDiffers
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:37:15 DEBUG : potato4: md5 = 42dd507493eb604b56a957eef84a6045 OK
2026/10/29 03:37:15 DEBUG : potato4: size = 60 (Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija')
2026/10/29 03:37:15 DEBUG : potato4: size = 59 (Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija')
2026/10/29 03:37:15 ERROR : potato4: corrupted on transfer: sizes differ src 60 vs dst(Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija') 59
2026/10/29 03:37:15 INFO  : potato4: Removing failed copy
=== RUN   TestRcatSizeChecksum/IgnoreChecksum
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:37:17 DEBUG : potato2: size = 60 OK
=== RUN   TestRcatSizeChecksum/NoHashes
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:37:20 DEBUG : potato3: md5 = 6d8ff62934668238826eb69daf328974 OK
2026/10/29 03:37:20 DEBUG : potato3: size = 60 OK
--- PASS: TestRcatSizeChecksum (8.51s)
    --- SKIP: TestRcatSizeChecksum/Corrupted (0.48s)
    --- PASS: TestRcatSizeChecksum/SizeDiffers (2.90s)
    --- PASS: TestRcatSizeChecksum/IgnoreChecksum (2.53s)
    --- PASS: TestRcatSizeChecksum/NoHashes (2.60s)
=== RUN   TestTouchDir
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:37:23 DEBUG : potato2: md5 = 3ff72e3004b8df1bb4e54b95c2ad8387 OK
2026/10/29 03:37:24 DEBUG : empty space: md5 = 995aa335bbae72ae040d1d812a3d5935 OK
2026/10/29 03:37:27 DEBUG : sub dir/potato3: md5 = b6b2ec50bdeffa28d9c7258a874b828e OK
2026/10/29 03:37:28 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija': Touching "sub dir/potato3"
2026/10/29 03:37:28 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija': Touching "empty space"
2026/10/29 03:37:28 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija': Touching "potato2"
--- PASS: TestTouchDir (11.20s)
=== RUN   TestMkdirMetadata
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:37:32 DEBUG : dir with metadata: Making directory with metadata
2026/10/29 03:37:33 INFO  : dir with metadata: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z)
--- PASS: TestMkdirMetadata (2.66s)
=== RUN   TestMkdirModTime
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:37:35 DEBUG : directory with modtime: Making directory with metadata
2026/10/29 03:37:36 INFO  : directory with modtime: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2026/10/29 03:37:36 NOTICE: dry run directory with modtime: Skipped make directory as --dry-run is set
--- PASS: TestMkdirModTime (2.65s)
=== RUN   TestCopyDirMetadata
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:37:38 DEBUG : dir with metadata to be copied: Making directory with metadata
2026/10/29 03:37:38 INFO  : dir with metadata to be copied: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z)
2026/10/29 03:37:38 DEBUG : Google drive root 'crypt/ve8oicq2msl4onulkqnmhaivejssop9vu384tt4t4ggjqf4tf1og': Skipping btime metadata as can't update it on an existing file: 2026-10-29T03:37:38.031110361Z
2026/10/29 03:37:38 INFO  : non existent directory: Updated directory metadata
2026/10/29 03:37:41 DEBUG : Google drive root 'crypt/ve8oicq2msl4onulkqnmhaivejssop9vu384tt4t4ggjqf4tf1og': Skipping btime metadata as can't update it on an existing file: 2026-10-29T03:37:38.031110361Z
2026/10/29 03:37:41 INFO  : existing directory: Updated directory metadata
--- PASS: TestCopyDirMetadata (5.39s)
=== RUN   TestSetDirModTime
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:37:43 DEBUG : set modtime on non existent directory: Skipping set directory modification time as --no-update-dir-modtime is set
2026/10/29 03:37:45 INFO  : set modtime on existing directory: Set directory modification time (using SetModTime)
2026/10/29 03:37:46 INFO  : set modtime on existing directory: Set directory modification time (using DirSetModTime)
--- PASS: TestSetDirModTime (4.51s)
=== RUN   TestDirsEqual
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:37:47 DEBUG : dir with metadata to be copied: Making directory with metadata
2026/10/29 03:37:47 INFO  : dir with metadata to be copied: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z)
2026/10/29 03:37:47 DEBUG : dst: Making directory with metadata
2026/10/29 03:37:48 INFO  : dst: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z)
2026/10/29 03:37:48 DEBUG : dst: Directory modification time the same (differ by -999.999µs, within tolerance 1ms)
2026/10/29 03:37:49 INFO  : dst: Set directory modification time (using SetModTime)
2026/10/29 03:37:49 INFO  : dst: Set directory modification time (using SetModTime)
2026/10/29 03:37:49 DEBUG : dst: Directory modification time the same (differ by 1ns, within tolerance 1ms)
2026/10/29 03:37:50 INFO  : dst: Set directory modification time (using SetModTime)
2026/10/29 03:37:50 DEBUG : dst: Destination directory is newer than source, skipping
--- PASS: TestDirsEqual (4.05s)
=== RUN   TestRemoveExisting
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija'", Local "Local file system at /tmp/rclone2749422086", Modify Window "1ms"
2026/10/29 03:37:54 DEBUG : sub dir/test remove existing: md5 = 8fac4ed17f76a2bdfe8d38da6d57e7c2 OK
2026/10/29 03:37:56 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: md5 = d6319c94d36d3b63ea265649306b0dc1 OK
2026/10/29 03:37:58 DEBUG : sub dir/test remove existing: TEST: renaming existing object to "sub dir/test remove existing.yaziziy3" before starting
2026/10/29 03:37:59 DEBUG : sub dir/test remove existing.yaziziy3: TEST: removing renamed existing file after operation
2026/10/29 03:38:00 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: TEST: renaming existing object to "sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.dehulav5" before starting
2026/10/29 03:38:01 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.dehulav5: TEST: renaming existing back after failed operation
2026/10/29 03:38:03 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: TEST: renaming existing object to "sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.juhinoj2" before starting
2026/10/29 03:38:04 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.juhinoj2: TEST: removing renamed existing file after operation
--- PASS: TestRemoveExisting (14.99s)
=== RUN   TestRcatInputFailurePreservesDestination
2026/10/29 03:38:06 DEBUG : Creating backend with remote "/tmp/TestRcatInputFailurePreservesDestination1804261154/001"
--- PASS: TestRcatInputFailurePreservesDestination (0.01s)
=== RUN   TestRcAbout
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcAbout (0.00s)
=== RUN   TestRcCleanup
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcCleanup (0.00s)
=== RUN   TestRcCopyfile
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcCopyfile (0.00s)
=== RUN   TestRcCopyurl
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcCopyurl (0.00s)
=== RUN   TestRcDelete
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcDelete (0.00s)
=== RUN   TestRcDeletefile
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcDeletefile (0.00s)
=== RUN   TestRcDeletefileBackupDir
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcDeletefileBackupDir (0.00s)
=== RUN   TestRcList
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcList (0.00s)
=== RUN   TestRcStat
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcStat (0.00s)
=== RUN   TestRcSetTier
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcSetTier (0.00s)
=== RUN   TestRcSetTierFile
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcSetTierFile (0.00s)
=== RUN   TestRcMkdir
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcMkdir (0.00s)
=== RUN   TestRcMovefile
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcMovefile (0.00s)
=== RUN   TestRcPurge
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcPurge (0.00s)
=== RUN   TestRcRmdir
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcRmdir (0.00s)
=== RUN   TestRcRmdirs
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcRmdirs (0.00s)
=== RUN   TestRcSize
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcSize (0.00s)
=== RUN   TestRcPublicLink
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcPublicLink (0.00s)
=== RUN   TestRcFsInfo
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcFsInfo (0.00s)
=== RUN   TestUploadFile
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestUploadFile (0.00s)
=== RUN   TestRcCommand
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcCommand (0.00s)
=== RUN   TestRcDu
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcDu (0.00s)
=== RUN   TestRcCheck
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcCheck (0.00s)
=== RUN   TestRcHashsum
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcHashsum (0.00s)
=== RUN   TestRcHashsumSingleFile
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcHashsumSingleFile (0.00s)
=== RUN   TestRcHashsumFile
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcHashsumFile (0.00s)
=== RUN   TestRcGetFile
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcGetFile (0.00s)
PASS
2026/10/29 03:38:06 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-hijuxav3rija': Purge remote
"./operations.test -test.v -test.timeout 1h0m0s -remote TestCryptDrive: -verbose" - Finished OK in 23m37.255850457s (try 1/5)