"./operations.test -test.v -test.timeout 1h0m0s -remote TestCryptDrive: -verbose" - Starting (try 1/5)
2026/10/01 02:50:13 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-fuporus9dele"
2026/10/01 02:50:13 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/01 02:50:13 DEBUG : Creating backend with remote "TestDrive:crypt/pp62fi2pb9cpsk867ktg44cc4ecbae2lkllsqt2ojpuaom1e01q0"
2026/10/01 02:50:14 DEBUG : Creating backend with remote "/tmp/rclone2683199630"
=== 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-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
    multithread_test.go:120: multithread writing not supported
--- SKIP: TestMultithreadCopy (0.27s)
=== RUN   TestMultithreadCopyAbort
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
    multithread_test.go:120: multithread writing not supported
--- SKIP: TestMultithreadCopyAbort (0.21s)
=== RUN   TestMultithreadCopyWriterAtErrors
=== RUN   TestMultithreadCopyWriterAtErrors/open/fatal=false
2026/10/01 02:50:15 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt
=== RUN   TestMultithreadCopyWriterAtErrors/open/fatal=true
2026/10/01 02:50:15 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt
=== RUN   TestMultithreadCopyWriterAtErrors/write/fatal=false
2026/10/01 02:50:15 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt
2026/10/01 02:50:15 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams
2026/10/01 02:50:15 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting
2026/10/01 02:50:15 DEBUG : file.txt: writing chunk 1
2026/10/01 02:50:15 DEBUG : file.txt: multi-thread copy: chunk 2/4 failed: multi-thread copy: failed to write chunk: disk full
2026/10/01 02:50:15 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting
2026/10/01 02:50:15 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting
2026/10/01 02:50:15 DEBUG : file.txt: writing chunk 2
2026/10/01 02:50:15 DEBUG : file.txt: writing chunk 0
2026/10/01 02:50:15 DEBUG : file.txt: multi-thread copy: chunk 3/4 failed: multi-thread copy: failed to write chunk: disk full
2026/10/01 02:50:15 DEBUG : file.txt: multi-thread copy: chunk 1/4 failed: multi-thread copy: failed to write chunk: disk full
2026/10/01 02:50:15 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit
2026/10/01 02:50:15 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/01 02:50:15 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt
2026/10/01 02:50:15 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams
2026/10/01 02:50:15 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting
2026/10/01 02:50:15 DEBUG : file.txt: writing chunk 1
2026/10/01 02:50:15 DEBUG : file.txt: multi-thread copy: chunk 2/4 failed: multi-thread copy: failed to write chunk: disk full
2026/10/01 02:50:15 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting
2026/10/01 02:50:15 DEBUG : file.txt: writing chunk 2
2026/10/01 02:50:15 DEBUG : file.txt: multi-thread copy: chunk 3/4 failed: multi-thread copy: failed to write chunk: disk full
2026/10/01 02:50:15 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting
2026/10/01 02:50:15 DEBUG : file.txt: writing chunk 0
2026/10/01 02:50:15 DEBUG : file.txt: multi-thread copy: chunk 1/4 failed: multi-thread copy: failed to write chunk: disk full
2026/10/01 02:50:15 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit
2026/10/01 02:50:15 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/01 02:50:15 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt
2026/10/01 02:50:15 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams
2026/10/01 02:50:15 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting
2026/10/01 02:50:15 DEBUG : file.txt: writing chunk 1
2026/10/01 02:50:15 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 finished
2026/10/01 02:50:15 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting
2026/10/01 02:50:15 DEBUG : file.txt: writing chunk 2
2026/10/01 02:50:15 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting
2026/10/01 02:50:15 DEBUG : file.txt: writing chunk 0
2026/10/01 02:50:15 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 finished
2026/10/01 02:50:15 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 finished
2026/10/01 02:50:15 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 starting
2026/10/01 02:50:15 DEBUG : file.txt: writing chunk 3
2026/10/01 02:50:15 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 finished
2026/10/01 02:50:15 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit
2026/10/01 02:50:15 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/01 02:50:15 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt
2026/10/01 02:50:15 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams
2026/10/01 02:50:15 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting
2026/10/01 02:50:15 DEBUG : file.txt: writing chunk 1
2026/10/01 02:50:15 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 finished
2026/10/01 02:50:15 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting
2026/10/01 02:50:15 DEBUG : file.txt: writing chunk 2
2026/10/01 02:50:15 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 finished
2026/10/01 02:50:15 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting
2026/10/01 02:50:15 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 starting
2026/10/01 02:50:15 DEBUG : file.txt: writing chunk 3
2026/10/01 02:50:15 DEBUG : file.txt: writing chunk 0
2026/10/01 02:50:15 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 finished
2026/10/01 02:50:15 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 finished
2026/10/01 02:50:15 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit
2026/10/01 02:50:15 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/01 02:50:15 DEBUG : a: size = 0 OK
2026/10/01 02:50:15 DEBUG : a: size = 1 (memory)
2026/10/01 02:50:15 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/01 02:50:15 DEBUG : potato: Seek from 10 to 0
=== RUN   TestReOpen/Normal/ErrorAtStart
=== RUN   TestReOpen/Normal/WithErrors
2026/10/01 02:50:15 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2026/10/01 02:50:15 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2026/10/01 02:50:15 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN   TestReOpen/Normal/TooManyErrors
2026/10/01 02:50:15 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2026/10/01 02:50:15 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2026/10/01 02:50:15 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2026/10/01 02:50:15 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN   TestReOpen/Normal/ReadAt
2026/10/01 02:50:15 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/10/01 02:50:15 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/10/01 02:50:15 DEBUG : potato: Seek from 5 to 1
=== RUN   TestReOpen/Normal/Seek
2026/10/01 02:50:15 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/10/01 02:50:15 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/10/01 02:50:15 DEBUG : potato: Seek from 5 to 2
=== RUN   TestReOpen/Normal/AccountRead
=== RUN   TestReOpen/Normal/AccountReadDelay
2026/10/01 02:50:15 DEBUG : potato: Seek from 10 to 0
2026/10/01 02:50:15 DEBUG : potato: Seek from 10 to 0
2026/10/01 02:50:15 DEBUG : potato: Seek from 10 to 0
=== RUN   TestReOpen/Normal/AccountReadError
=== RUN   TestReOpen/WithRangeOption
=== RUN   TestReOpen/WithRangeOption/Basics
2026/10/01 02:50:15 DEBUG : potato: Seek from 7 to 0
=== RUN   TestReOpen/WithRangeOption/ErrorAtStart
=== RUN   TestReOpen/WithRangeOption/WithErrors
2026/10/01 02:50:15 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2026/10/01 02:50:15 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2026/10/01 02:50:15 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN   TestReOpen/WithRangeOption/TooManyErrors
2026/10/01 02:50:15 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2026/10/01 02:50:15 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2026/10/01 02:50:15 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2026/10/01 02:50:15 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN   TestReOpen/WithRangeOption/ReadAt
2026/10/01 02:50:15 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/10/01 02:50:15 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/10/01 02:50:15 DEBUG : potato: Seek from 5 to 1
=== RUN   TestReOpen/WithRangeOption/Seek
2026/10/01 02:50:15 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/10/01 02:50:15 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/10/01 02:50:15 DEBUG : potato: Seek from 5 to 2
2026/10/01 02:50:15 DEBUG : potato: Seek from 7 to 4
=== RUN   TestReOpen/WithRangeOption/AccountRead
=== RUN   TestReOpen/WithRangeOption/AccountReadDelay
2026/10/01 02:50:15 DEBUG : potato: Seek from 7 to 0
2026/10/01 02:50:15 DEBUG : potato: Seek from 7 to 0
2026/10/01 02:50:15 DEBUG : potato: Seek from 7 to 0
=== RUN   TestReOpen/WithRangeOption/AccountReadError
=== RUN   TestReOpen/WithSeekOption
=== RUN   TestReOpen/WithSeekOption/Basics
2026/10/01 02:50:15 DEBUG : potato: Seek from 8 to 0
=== RUN   TestReOpen/WithSeekOption/ErrorAtStart
=== RUN   TestReOpen/WithSeekOption/WithErrors
2026/10/01 02:50:15 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2026/10/01 02:50:15 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2026/10/01 02:50:15 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN   TestReOpen/WithSeekOption/TooManyErrors
2026/10/01 02:50:15 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2026/10/01 02:50:15 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2026/10/01 02:50:15 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2026/10/01 02:50:15 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN   TestReOpen/WithSeekOption/ReadAt
2026/10/01 02:50:15 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/10/01 02:50:15 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/10/01 02:50:15 DEBUG : potato: Seek from 5 to 1
=== RUN   TestReOpen/WithSeekOption/Seek
2026/10/01 02:50:15 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/10/01 02:50:15 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/10/01 02:50:15 DEBUG : potato: Seek from 5 to 2
2026/10/01 02:50:15 DEBUG : potato: Seek from 7 to 5
=== RUN   TestReOpen/WithSeekOption/AccountRead
=== RUN   TestReOpen/WithSeekOption/AccountReadDelay
2026/10/01 02:50:15 DEBUG : potato: Seek from 8 to 0
2026/10/01 02:50:15 DEBUG : potato: Seek from 8 to 0
2026/10/01 02:50:15 DEBUG : potato: Seek from 8 to 0
=== RUN   TestReOpen/WithSeekOption/AccountReadError
=== RUN   TestReOpen/UnknownSize
=== RUN   TestReOpen/UnknownSize/Basics
2026/10/01 02:50:15 DEBUG : potato: Seek from 9 to 0
=== RUN   TestReOpen/UnknownSize/ErrorAtStart
=== RUN   TestReOpen/UnknownSize/WithErrors
2026/10/01 02:50:15 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2026/10/01 02:50:15 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2026/10/01 02:50:15 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN   TestReOpen/UnknownSize/TooManyErrors
2026/10/01 02:50:15 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2026/10/01 02:50:15 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2026/10/01 02:50:15 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2026/10/01 02:50:15 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN   TestReOpen/UnknownSize/ReadAt
2026/10/01 02:50:15 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/10/01 02:50:15 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/10/01 02:50:15 DEBUG : potato: Seek from 5 to 1
=== RUN   TestReOpen/UnknownSize/Seek
2026/10/01 02:50:15 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/10/01 02:50:15 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/10/01 02:50:15 DEBUG : potato: Seek from 5 to 2
2026/10/01 02:50:15 DEBUG : potato: Seek from 7 to 6
=== RUN   TestReOpen/UnknownSize/AccountRead
=== RUN   TestReOpen/UnknownSize/AccountReadDelay
2026/10/01 02:50:15 DEBUG : potato: Seek from 9 to 0
2026/10/01 02:50:15 DEBUG : potato: Seek from 9 to 0
2026/10/01 02:50:15 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-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 02:50:17 DEBUG : rutabaga: md5 = 895105eccf8b29524c3201fb41a4ad0c OK
=== RUN   TestCheck/1
=== RUN   TestCheck/2
2026/10/01 02:50:20 DEBUG : empty space: md5 = 9182fc3c1cd0617a3612413e56d23ad2 OK
=== RUN   TestCheck/3
2026/10/01 02:50:22 DEBUG : potato2: md5 = ef6cb783c752f6179d9230c24a0c81b1 OK
=== RUN   TestCheck/4
=== RUN   TestCheck/5
2026/10/01 02:50:25 DEBUG : remotepotato: md5 = ab1bb9f4fd1fdfec10d6aec69639dfd8 OK
=== RUN   TestCheck/6
=== RUN   TestCheck/7
--- PASS: TestCheck (12.85s)
    --- PASS: TestCheck/1 (0.23s)
    --- PASS: TestCheck/2 (0.23s)
    --- PASS: TestCheck/3 (0.25s)
    --- PASS: TestCheck/4 (0.23s)
    --- PASS: TestCheck/5 (0.27s)
    --- PASS: TestCheck/6 (0.38s)
    --- PASS: TestCheck/7 (0.27s)
=== RUN   TestCheckFsError
2026/10/01 02:50:28 DEBUG : Creating backend with remote "nonexistent"
2026/10/01 02:50:28 DEBUG : Creating backend with remote "nonexistent"
2026/10/01 02:50:28 DEBUG : Local file system at nonexistent: Waiting for checks to finish
2026/10/01 02:50:28 ERROR : Local file system at nonexistent: error reading source root directory: directory not found
2026/10/01 02:50:28 NOTICE: Local file system at nonexistent: 0 differences found
2026/10/01 02:50:28 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-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 02:50:29 DEBUG : rutabaga: md5 = e70dcdacd91333ff247cce55025c5b88 OK
=== RUN   TestCheckDownload/1
=== RUN   TestCheckDownload/2
2026/10/01 02:50:33 DEBUG : empty space: md5 = 87621f915b22e0a06ee5486bf1e2daff OK
=== RUN   TestCheckDownload/3
2026/10/01 02:50:35 DEBUG : potato2: md5 = 13b217fe4edbb52dff8d7a859e1825d1 OK
=== RUN   TestCheckDownload/4
=== RUN   TestCheckDownload/5
2026/10/01 02:50:39 DEBUG : remotepotato: md5 = fe1cb7ee5a76348428168a164cda68b5 OK
=== RUN   TestCheckDownload/6
=== RUN   TestCheckDownload/7
--- PASS: TestCheckDownload (15.27s)
    --- PASS: TestCheckDownload/1 (0.93s)
    --- PASS: TestCheckDownload/2 (0.76s)
    --- PASS: TestCheckDownload/3 (0.75s)
    --- PASS: TestCheckDownload/4 (0.90s)
    --- PASS: TestCheckDownload/5 (0.90s)
    --- PASS: TestCheckDownload/6 (0.73s)
    --- PASS: TestCheckDownload/7 (0.77s)
=== RUN   TestCheckSizeOnly
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 02:50:44 DEBUG : rutabaga: md5 = f10fa77d5180fa3b1abd910ff02cc0f3 OK
=== RUN   TestCheckSizeOnly/1
=== RUN   TestCheckSizeOnly/2
2026/10/01 02:50:46 DEBUG : empty space: md5 = 175d61e680f0db7f3f805443377c5e87 OK
=== RUN   TestCheckSizeOnly/3
2026/10/01 02:50:48 DEBUG : potato2: md5 = a8811b026ad4e959a39552dec486ef60 OK
=== RUN   TestCheckSizeOnly/4
=== RUN   TestCheckSizeOnly/5
2026/10/01 02:50:51 DEBUG : remotepotato: md5 = 78b1d6b0db99060c397b2feceadc10e3 OK
=== RUN   TestCheckSizeOnly/6
=== RUN   TestCheckSizeOnly/7
--- PASS: TestCheckSizeOnly (10.95s)
    --- PASS: TestCheckSizeOnly/1 (0.25s)
    --- PASS: TestCheckSizeOnly/2 (0.21s)
    --- PASS: TestCheckSizeOnly/3 (0.21s)
    --- PASS: TestCheckSizeOnly/4 (0.25s)
    --- PASS: TestCheckSizeOnly/5 (0.29s)
    --- PASS: TestCheckSizeOnly/6 (0.27s)
    --- PASS: TestCheckSizeOnly/7 (0.25s)
=== RUN   TestCheckEqualReaders
--- PASS: TestCheckEqualReaders (0.00s)
=== RUN   TestParseSumFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 02:50:57 DEBUG : test.sum: md5 = f0b01810b7657999a42b181150be536e OK
2026/10/01 02:50:58 NOTICE: test.sum: improperly formatted checksum line 4
2026/10/01 02:50:58 NOTICE: test.sum: improperly formatted checksum line 5
2026/10/01 02:50:58 NOTICE: test.sum: improperly formatted checksum line 6
2026/10/01 02:50:58 NOTICE: test.sum: 2 warning(s) suppressed...
2026/10/01 02:50:59 DEBUG : test.sum: md5 = 4d76639e695feec737e1ec9ba9d6319d OK
2026/10/01 02:51:00 NOTICE: test.sum: improperly formatted checksum line 4
2026/10/01 02:51:00 NOTICE: test.sum: improperly formatted checksum line 5
2026/10/01 02:51:00 NOTICE: test.sum: improperly formatted checksum line 6
2026/10/01 02:51:00 NOTICE: test.sum: 2 warning(s) suppressed...
--- PASS: TestParseSumFile (7.38s)
=== RUN   TestCheckSum
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 02:51:01 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-fuporus9dele/data"
2026/10/01 02:51:01 DEBUG : Creating backend with remote "TestDrive:crypt/pp62fi2pb9cpsk867ktg44cc4ecbae2lkllsqt2ojpuaom1e01q0/vjrnln8ratgmqakfosrqe8espk"
    check_test.go:354: Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele/data' lacks md5, skipping
--- SKIP: TestCheckSum (1.87s)
=== RUN   TestCheckSumDownload
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 02:51:03 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-fuporus9dele/data"
2026/10/01 02:51:03 DEBUG : Creating backend with remote "TestDrive:crypt/pp62fi2pb9cpsk867ktg44cc4ecbae2lkllsqt2ojpuaom1e01q0/vjrnln8ratgmqakfosrqe8espk"
2026/10/01 02:51:07 DEBUG : data/banana: md5 = 96349e724bc852469d30a44021156e9b OK
2026/10/01 02:51:08 DEBUG : test.sum: md5 = 48c861f5f36d4cfd2f4743b2376e1e0f OK
=== RUN   TestCheckSumDownload/subtest1
2026/10/01 02:51:12 DEBUG : data/potato: md5 = 8e5f440a19f326905769a72c5ad6ed56 OK
2026/10/01 02:51:14 DEBUG : test.sum: md5 = 445a3bbbcb611dbde891ae14f945e0fa OK
=== RUN   TestCheckSumDownload/subtest2
2026/10/01 02:51:17 DEBUG : test.sum: md5 = 98cc738dd5afa46090df2d3525b21957 OK
=== RUN   TestCheckSumDownload/subtest3
2026/10/01 02:51:21 DEBUG : test.sum: md5 = 2245b1e5256cb8ef8b03dce20948806d OK
=== RUN   TestCheckSumDownload/subtest4
2026/10/01 02:51:25 DEBUG : test.sum: md5 = 81035f24ea8caff60baf7235378d4f6b OK
=== RUN   TestCheckSumDownload/subtest5
2026/10/01 02:51:28 DEBUG : test.sum: md5 = 1e93f538288fc9348c4427a3705b2ab0 OK
=== RUN   TestCheckSumDownload/subtest6
2026/10/01 02:51:32 DEBUG : data/banana: md5 = 2c3dedc0b9f2e2f94bb36c4bee6e5e64 OK
2026/10/01 02:51:33 DEBUG : data/potato: md5 = c81005e1ea4c61a91d34f29d4fb07fd6 OK
2026/10/01 02:51:35 DEBUG : test.sum: md5 = e05eee56cd59d9fbb2365e7596face44 OK
=== RUN   TestCheckSumDownload/subtest7
--- PASS: TestCheckSumDownload (36.40s)
    --- PASS: TestCheckSumDownload/subtest1 (1.88s)
    --- PASS: TestCheckSumDownload/subtest2 (1.71s)
    --- PASS: TestCheckSumDownload/subtest3 (1.87s)
    --- PASS: TestCheckSumDownload/subtest4 (1.79s)
    --- PASS: TestCheckSumDownload/subtest5 (1.82s)
    --- PASS: TestCheckSumDownload/subtest6 (1.65s)
    --- PASS: TestCheckSumDownload/subtest7 (1.74s)
=== RUN   TestCheckSumConcurrency
2026/10/01 02:51:40 DEBUG : file0: md5 = 022eb0c0085c731f335df970fafa7446 OK
2026/10/01 02:51:40 DEBUG : file2: md5 = a58f09ae3db8ae4b943fdbaace096072 OK
2026/10/01 02:51:40 DEBUG : file3: md5 = e0639b17e0744aca9a3ba29c049e59ee OK
2026/10/01 02:51:40 DEBUG : file1: md5 = 17fbabbccbdea0e6be9eb64c8c6fea41 OK
2026/10/01 02:51:40 NOTICE: Mock file system at checkSum: 0 differences found
2026/10/01 02:51:40 NOTICE: Mock file system at checkSum: 4 matching files
--- PASS: TestCheckSumConcurrency (0.00s)
=== RUN   TestCheckSumDownloadConcurrency
2026/10/01 02:51:40 DEBUG : file0: md5 = 022eb0c0085c731f335df970fafa7446 OK
2026/10/01 02:51:40 DEBUG : file1: md5 = 17fbabbccbdea0e6be9eb64c8c6fea41 OK
2026/10/01 02:51:40 DEBUG : file2: md5 = a58f09ae3db8ae4b943fdbaace096072 OK
2026/10/01 02:51:40 DEBUG : file3: md5 = e0639b17e0744aca9a3ba29c049e59ee OK
2026/10/01 02:51:40 NOTICE: Mock file system at checkSum: 0 differences found
2026/10/01 02:51:40 NOTICE: Mock file system at checkSum: 4 matching files
--- PASS: TestCheckSumDownloadConcurrency (0.00s)
=== RUN   TestApplyTransforms
2026/10/01 02:51:40 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-pogawot5caho"
2026/10/01 02:51:40 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/01 02:51:40 DEBUG : Creating backend with remote "TestDrive:crypt/n5rjpsf1kel4biu28lcepscdqeqceb5eclhd120n1iutniq2fefg"
2026/10/01 02:51:41 DEBUG : Creating backend with remote "/tmp/rclone852310363"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-pogawot5caho'", Local "Local file system at /tmp/rclone852310363", Modify Window "1ms"
2026/10/01 02:51:43 DEBUG : hello, world!: md5 = abe9d831a9d018a8cc24163a2f18fe64 OK
upper checkfile vs. lower remote (without normalization)
2026/10/01 02:51:43 ERROR : hello, world!: sum not found
2026/10/01 02:51:43 ERROR : HELLO, WORLD!: file not in Encrypted drive 'TestCryptDrive:rclone-test-pogawot5caho'
2026/10/01 02:51:43 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-pogawot5caho': 1 files missing
2026/10/01 02:51:43 NOTICE: 1 hashes missing
2026/10/01 02:51:43 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-pogawot5caho': 1 differences found
2026/10/01 02:51:43 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-pogawot5caho': 2 errors while checking
upper checkfile vs. lower remote (with normalization)
2026/10/01 02:51:44 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/01 02:51:44 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-pogawot5caho': 0 differences found
2026/10/01 02:51:44 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-pogawot5caho': 1 matching files
2026/10/01 02:51:44 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-fugorex6kibe"
2026/10/01 02:51:44 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/01 02:51:44 DEBUG : Creating backend with remote "TestDrive:crypt/knae6nvefv55tu267gks1vj889hj05d7mepjsetl0kmi19d4b95g"
2026/10/01 02:51:45 DEBUG : Creating backend with remote "/tmp/rclone725263956"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fugorex6kibe'", Local "Local file system at /tmp/rclone725263956", Modify Window "1ms"
2026/10/01 02:51:47 DEBUG : HELLO, WORLD!: md5 = 3d01fad101945ee9bf177c1386f9a75d OK
lower checkfile vs. upper remote (without normalization)
2026/10/01 02:51:48 ERROR : HELLO, WORLD!: sum not found
2026/10/01 02:51:48 ERROR : hello, world!: file not in Encrypted drive 'TestCryptDrive:rclone-test-fugorex6kibe'
2026/10/01 02:51:48 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fugorex6kibe': 1 files missing
2026/10/01 02:51:48 NOTICE: 1 hashes missing
2026/10/01 02:51:48 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fugorex6kibe': 1 differences found
2026/10/01 02:51:48 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fugorex6kibe': 2 errors while checking
lower checkfile vs. upper remote (with normalization)
2026/10/01 02:51:48 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/01 02:51:48 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fugorex6kibe': 0 differences found
2026/10/01 02:51:48 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fugorex6kibe': 1 matching files
2026/10/01 02:51:48 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-qidezaw1pina"
2026/10/01 02:51:48 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/01 02:51:49 DEBUG : Creating backend with remote "TestDrive:crypt/grrbk5h9eqj27fei4livhudts0q54hvorclugk3je50jodsi0sh0"
2026/10/01 02:51:50 DEBUG : Creating backend with remote "/tmp/rclone4043196389"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-qidezaw1pina'", Local "Local file system at /tmp/rclone4043196389", Modify Window "1ms"
2026/10/01 02:51:53 DEBUG : HeLlO, wOrLd!: md5 = 33d05f90f295a054e64dad46e93a702d OK
lower checkfile vs. upperlowermixed remote (without normalization)
2026/10/01 02:51:53 ERROR : HeLlO, wOrLd!: sum not found
2026/10/01 02:51:53 ERROR : hello, world!: file not in Encrypted drive 'TestCryptDrive:rclone-test-qidezaw1pina'
2026/10/01 02:51:53 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-qidezaw1pina': 1 files missing
2026/10/01 02:51:53 NOTICE: 1 hashes missing
2026/10/01 02:51:53 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-qidezaw1pina': 1 differences found
2026/10/01 02:51:53 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-qidezaw1pina': 2 errors while checking
lower checkfile vs. upperlowermixed remote (with normalization)
2026/10/01 02:51:54 DEBUG : HeLlO, wOrLd!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/01 02:51:54 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-qidezaw1pina': 0 differences found
2026/10/01 02:51:54 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-qidezaw1pina': 1 matching files
2026/10/01 02:51:54 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-pazigit0ceka"
2026/10/01 02:51:54 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/01 02:51:54 DEBUG : Creating backend with remote "TestDrive:crypt/qbeqv2d73ohsdqm59n2vne3hridhs6k6u43af98nkg6r2tgjj2pg"
2026/10/01 02:51:55 DEBUG : Creating backend with remote "/tmp/rclone292209737"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-pazigit0ceka'", Local "Local file system at /tmp/rclone292209737", Modify Window "1ms"
2026/10/01 02:51:58 DEBUG : HELLO, WORLD!: md5 = 206a9398882221866bfee2273825ecdf OK
upperlowermixed checkfile vs. upper remote (without normalization)
2026/10/01 02:51:58 ERROR : HELLO, WORLD!: sum not found
2026/10/01 02:51:58 ERROR : HeLlO, wOrLd!: file not in Encrypted drive 'TestCryptDrive:rclone-test-pazigit0ceka'
2026/10/01 02:51:58 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-pazigit0ceka': 1 files missing
2026/10/01 02:51:58 NOTICE: 1 hashes missing
2026/10/01 02:51:58 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-pazigit0ceka': 1 differences found
2026/10/01 02:51:58 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-pazigit0ceka': 2 errors while checking
upperlowermixed checkfile vs. upper remote (with normalization)
2026/10/01 02:51:59 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/01 02:51:59 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-pazigit0ceka': 0 differences found
2026/10/01 02:51:59 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-pazigit0ceka': 1 matching files
2026/10/01 02:51:59 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-qeyemeb0jaka"
2026/10/01 02:51:59 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/01 02:51:59 DEBUG : Creating backend with remote "TestDrive:crypt/54iodlbse5nivs4m9bs35oic1gjnvttjq4hq36atej146ga53mp0"
2026/10/01 02:52:00 DEBUG : Creating backend with remote "/tmp/rclone954732643"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-qeyemeb0jaka'", Local "Local file system at /tmp/rclone954732643", Modify Window "1ms"
2026/10/01 02:52:02 DEBUG : 測試_Русский___ě_áñ: md5 = 88a4f3d0bbe3dabdfbc39197d6bd8e29 OK
NFD checkfile vs. NFC remote (without normalization)
2026/10/01 02:52:03 ERROR : 測試_Русский___ě_áñ: sum not found
2026/10/01 02:52:03 ERROR : 測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-qeyemeb0jaka'
2026/10/01 02:52:03 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-qeyemeb0jaka': 1 files missing
2026/10/01 02:52:03 NOTICE: 1 hashes missing
2026/10/01 02:52:03 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-qeyemeb0jaka': 1 differences found
2026/10/01 02:52:03 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-qeyemeb0jaka': 2 errors while checking
NFD checkfile vs. NFC remote (with normalization)
2026/10/01 02:52:04 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/01 02:52:04 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-qeyemeb0jaka': 0 differences found
2026/10/01 02:52:04 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-qeyemeb0jaka': 1 matching files
2026/10/01 02:52:04 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-lawisil7pihe"
2026/10/01 02:52:04 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/01 02:52:04 DEBUG : Creating backend with remote "TestDrive:crypt/lhrvaspfhh4v6k43nf7ojv2f1mcn38crg7oer7pllbd7f6g4ka70"
2026/10/01 02:52:05 DEBUG : Creating backend with remote "/tmp/rclone3588063115"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-lawisil7pihe'", Local "Local file system at /tmp/rclone3588063115", Modify Window "1ms"
2026/10/01 02:52:07 DEBUG : 測試_Русский___ě_áñ: md5 = d2480ea213fe27ddbb57e98a6a984c7a OK
NFC checkfile vs. NFD remote (without normalization)
2026/10/01 02:52:07 ERROR : 測試_Русский___ě_áñ: sum not found
2026/10/01 02:52:07 ERROR : 測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-lawisil7pihe'
2026/10/01 02:52:07 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-lawisil7pihe': 1 files missing
2026/10/01 02:52:07 NOTICE: 1 hashes missing
2026/10/01 02:52:07 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-lawisil7pihe': 1 differences found
2026/10/01 02:52:07 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-lawisil7pihe': 2 errors while checking
NFC checkfile vs. NFD remote (with normalization)
2026/10/01 02:52:08 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/01 02:52:08 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-lawisil7pihe': 0 differences found
2026/10/01 02:52:08 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-lawisil7pihe': 1 matching files
2026/10/01 02:52:08 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-sozafij1gucu"
2026/10/01 02:52:08 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/01 02:52:08 DEBUG : Creating backend with remote "TestDrive:crypt/bkambcr8sc9q1rah7dsk7u5vl859scsi020c2e1pkm4drsl2e740"
2026/10/01 02:52:09 DEBUG : Creating backend with remote "/tmp/rclone879822151"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-sozafij1gucu'", Local "Local file system at /tmp/rclone879822151", Modify Window "1ms"
2026/10/01 02:52:11 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 8d271aafe38a8ca580b8a944bd3eaf49 OK
NFDx2 checkfile vs. both remote (without normalization)
2026/10/01 02:52:12 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found
2026/10/01 02:52:12 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-sozafij1gucu'
2026/10/01 02:52:12 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-sozafij1gucu': 1 files missing
2026/10/01 02:52:12 NOTICE: 1 hashes missing
2026/10/01 02:52:12 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-sozafij1gucu': 1 differences found
2026/10/01 02:52:12 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-sozafij1gucu': 2 errors while checking
NFDx2 checkfile vs. both remote (with normalization)
2026/10/01 02:52:13 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/01 02:52:13 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-sozafij1gucu': 0 differences found
2026/10/01 02:52:13 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-sozafij1gucu': 1 matching files
2026/10/01 02:52:13 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-hujobej6kolu"
2026/10/01 02:52:13 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/01 02:52:13 DEBUG : Creating backend with remote "TestDrive:crypt/7ee2bbkcmbmk88kfteu4i4eirjv1dffg1rsb0q0q5ht5ku5oh340"
2026/10/01 02:52:14 DEBUG : Creating backend with remote "/tmp/rclone1670846948"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hujobej6kolu'", Local "Local file system at /tmp/rclone1670846948", Modify Window "1ms"
2026/10/01 02:52:16 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 44c5699ffdeeed4e83883b5c2f9287ec OK
NFCx2 checkfile vs. both remote (without normalization)
2026/10/01 02:52:16 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found
2026/10/01 02:52:16 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-hujobej6kolu'
2026/10/01 02:52:16 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-hujobej6kolu': 1 files missing
2026/10/01 02:52:16 NOTICE: 1 hashes missing
2026/10/01 02:52:16 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-hujobej6kolu': 1 differences found
2026/10/01 02:52:16 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-hujobej6kolu': 2 errors while checking
NFCx2 checkfile vs. both remote (with normalization)
2026/10/01 02:52:17 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/01 02:52:17 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-hujobej6kolu': 0 differences found
2026/10/01 02:52:17 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-hujobej6kolu': 1 matching files
2026/10/01 02:52:17 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-wimiqoj5suhu"
2026/10/01 02:52:17 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/01 02:52:17 DEBUG : Creating backend with remote "TestDrive:crypt/90kaobebc2g0t94i1g4cg9cljpkleogg9fu65sv85jpdabpgqlhg"
2026/10/01 02:52:18 DEBUG : Creating backend with remote "/tmp/rclone4001362366"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wimiqoj5suhu'", Local "Local file system at /tmp/rclone4001362366", Modify Window "1ms"
2026/10/01 02:52:20 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 955264e88fa382af71094aa9700581e8 OK
both checkfile vs. NFDx2 remote (without normalization)
2026/10/01 02:52:21 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found
2026/10/01 02:52:21 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-wimiqoj5suhu'
2026/10/01 02:52:21 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-wimiqoj5suhu': 1 files missing
2026/10/01 02:52:21 NOTICE: 1 hashes missing
2026/10/01 02:52:21 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-wimiqoj5suhu': 1 differences found
2026/10/01 02:52:21 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-wimiqoj5suhu': 2 errors while checking
both checkfile vs. NFDx2 remote (with normalization)
2026/10/01 02:52:22 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/01 02:52:22 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-wimiqoj5suhu': 0 differences found
2026/10/01 02:52:22 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-wimiqoj5suhu': 1 matching files
2026/10/01 02:52:22 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-nunewaz5wuse"
2026/10/01 02:52:22 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/01 02:52:22 DEBUG : Creating backend with remote "TestDrive:crypt/1jebsqv8ce1lhsb48jc3en2vu1nvfvc49va41at31ibf2h4okm60"
2026/10/01 02:52:23 DEBUG : Creating backend with remote "/tmp/rclone3966690187"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-nunewaz5wuse'", Local "Local file system at /tmp/rclone3966690187", Modify Window "1ms"
2026/10/01 02:52:25 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = b082ba956bb4ee9855dde69e134cfe75 OK
both checkfile vs. NFCx2 remote (without normalization)
2026/10/01 02:52:26 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found
2026/10/01 02:52:26 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-nunewaz5wuse'
2026/10/01 02:52:26 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-nunewaz5wuse': 1 files missing
2026/10/01 02:52:26 NOTICE: 1 hashes missing
2026/10/01 02:52:26 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-nunewaz5wuse': 1 differences found
2026/10/01 02:52:26 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-nunewaz5wuse': 2 errors while checking
both checkfile vs. NFCx2 remote (with normalization)
2026/10/01 02:52:27 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/01 02:52:27 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-nunewaz5wuse': 0 differences found
2026/10/01 02:52:27 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-nunewaz5wuse': 1 matching files
2026/10/01 02:52:27 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-nunewaz5wuse': Purge remote
2026/10/01 02:52:27 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-wimiqoj5suhu': Purge remote
2026/10/01 02:52:28 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-hujobej6kolu': Purge remote
2026/10/01 02:52:28 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-sozafij1gucu': Purge remote
2026/10/01 02:52:28 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-lawisil7pihe': Purge remote
2026/10/01 02:52:29 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-qeyemeb0jaka': Purge remote
2026/10/01 02:52:29 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-pazigit0ceka': Purge remote
2026/10/01 02:52:30 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-qidezaw1pina': Purge remote
2026/10/01 02:52:31 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-fugorex6kibe': Purge remote
2026/10/01 02:52:31 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-pogawot5caho': Purge remote
--- PASS: TestApplyTransforms (52.08s)
=== RUN   TestTruncateString
--- PASS: TestTruncateString (0.00s)
=== RUN   TestCopyFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 02:52:32 DEBUG : file1: Need to transfer - File not found at Destination
2026/10/01 02:52:34 DEBUG : sub/file2: md5 = 86f166fae302f6de55eb463854320eb8 OK
2026/10/01 02:52:34 DEBUG : sub/file2: size = 14 OK
2026/10/01 02:52:34 INFO  : file1: Copied (new) to: sub/file2
2026/10/01 02:52:35 DEBUG : sub/file2: size = 14 OK
2026/10/01 02:52:35 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2026/10/01 02:52:35 DEBUG : file1: Unchanged skipping
2026/10/01 02:52:35 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele': don't need to copy/move sub/file2, it is already at target location
--- PASS: TestCopyFile (5.89s)
=== RUN   TestCopyFileImmutable
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 02:52:38 DEBUG : existing: Need to transfer - File not found at Destination
2026/10/01 02:52:39 DEBUG : existing: md5 = 24b8873f57b093f54414e2c07d29ba59 OK
2026/10/01 02:52:39 DEBUG : existing: size = 6 OK
2026/10/01 02:52:39 INFO  : existing: Copied (new)
2026/10/01 02:52:40 DEBUG : existing: size = 6 OK
2026/10/01 02:52:40 DEBUG : existing: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2026/10/01 02:52:40 DEBUG : existing: Unchanged skipping
2026/10/01 02:52:41 DEBUG : existing: size = 8 (Local file system at /tmp/rclone2683199630)
2026/10/01 02:52:41 DEBUG : existing: size = 6 (Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele')
2026/10/01 02:52:41 DEBUG : existing: Sizes differ
2026/10/01 02:52:41 ERROR : existing: Source and destination exist but do not match: immutable file modified
--- PASS: TestCopyFileImmutable (4.60s)
=== RUN   TestCopyLongFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
    copy_test.go:188: Test only runs on local
--- SKIP: TestCopyLongFile (0.45s)
=== RUN   TestCopyFileBackupDir
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 02:52:45 DEBUG : dst/file1: md5 = 9804896e785bd1fa7c099b0c18dd8cb5 OK
2026/10/01 02:52:45 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-fuporus9dele/backup"
2026/10/01 02:52:45 DEBUG : Creating backend with remote "TestDrive:crypt/pp62fi2pb9cpsk867ktg44cc4ecbae2lkllsqt2ojpuaom1e01q0/1nrff024r7pq65ecp72fc28jb0"
2026/10/01 02:52:47 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone2683199630)
2026/10/01 02:52:47 DEBUG : dst/file1: size = 18 (Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele')
2026/10/01 02:52:47 DEBUG : dst/file1: Sizes differ
2026/10/01 02:52:49 INFO  : dst/file1: Moved (server-side)
2026/10/01 02:52:51 DEBUG : dst/file1: md5 = b3bbac323b825ebc188d129fd556a56a OK
2026/10/01 02:52:51 DEBUG : dst/file1: size = 14 OK
2026/10/01 02:52:51 INFO  : dst/file1: Copied (new)
--- PASS: TestCopyFileBackupDir (12.69s)
=== RUN   TestCopyFileCompareDest
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 02:52:55 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-fuporus9dele/dst"
2026/10/01 02:52:55 DEBUG : Creating backend with remote "TestDrive:crypt/pp62fi2pb9cpsk867ktg44cc4ecbae2lkllsqt2ojpuaom1e01q0/31u3jie661vd5p8j7rtc3hgbh0"
2026/10/01 02:52:57 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-fuporus9dele/CompareDest"
2026/10/01 02:52:57 DEBUG : Creating backend with remote "TestDrive:crypt/pp62fi2pb9cpsk867ktg44cc4ecbae2lkllsqt2ojpuaom1e01q0/gveqi14airsml4bgu7krj116o8"
2026/10/01 02:52:58 DEBUG : one: Need to transfer - File not found at Destination
2026/10/01 02:53:01 DEBUG : one: md5 = b94f5ef80929071cb98cb4d02a0629c7 OK
2026/10/01 02:53:01 DEBUG : one: size = 3 OK
2026/10/01 02:53:01 INFO  : one: Copied (new)
2026/10/01 02:53:02 DEBUG : one: size = 5 (Local file system at /tmp/rclone2683199630)
2026/10/01 02:53:02 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele/dst')
2026/10/01 02:53:02 DEBUG : one: Sizes differ
2026/10/01 02:53:03 DEBUG : one: md5 = 20060522d76c6e3bb14b130631fb2df1 OK
2026/10/01 02:53:03 DEBUG : one: size = 5 OK
2026/10/01 02:53:03 INFO  : one: Copied (replaced existing)
2026/10/01 02:53:05 DEBUG : dst/one: md5 = 8cea0fc20b2aa53c8eb305d442751013 OK
2026/10/01 02:53:07 DEBUG : CompareDest/one: md5 = 255f984c9a9c074b19a546419815a94a OK
2026/10/01 02:53:08 DEBUG : one: size = 5 (Local file system at /tmp/rclone2683199630)
2026/10/01 02:53:08 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele/dst')
2026/10/01 02:53:08 DEBUG : one: Sizes differ
2026/10/01 02:53:08 DEBUG : one: size = 5 OK
2026/10/01 02:53:08 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/10/01 02:53:08 DEBUG : one: Destination found in --compare-dest, skipping
2026/10/01 02:53:10 DEBUG : CompareDest/two: md5 = 1ace7dc419541041f7b01ff753f05bb5 OK
2026/10/01 02:53:11 DEBUG : two: Need to transfer - File not found at Destination
2026/10/01 02:53:11 DEBUG : two: size = 3 OK
2026/10/01 02:53:11 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/10/01 02:53:11 DEBUG : two: Destination found in --compare-dest, skipping
2026/10/01 02:53:12 DEBUG : two: Need to transfer - File not found at Destination
2026/10/01 02:53:12 DEBUG : two: size = 3 OK
2026/10/01 02:53:12 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/10/01 02:53:12 DEBUG : two: Destination found in --compare-dest, skipping
2026/10/01 02:53:14 DEBUG : two: Need to transfer - File not found at Destination
2026/10/01 02:53:14 DEBUG : two: size = 5 (Local file system at /tmp/rclone2683199630)
2026/10/01 02:53:14 DEBUG : two: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele/CompareDest')
2026/10/01 02:53:14 DEBUG : two: Sizes differ
2026/10/01 02:53:15 DEBUG : two: md5 = 6a421ef85f6129c788c832460765b298 OK
2026/10/01 02:53:15 DEBUG : two: size = 5 OK
2026/10/01 02:53:15 INFO  : two: Copied (new)
--- PASS: TestCopyFileCompareDest (24.36s)
=== RUN   TestCopyFileCopyDestImmutable
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 02:53:20 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-fuporus9dele/dst"
2026/10/01 02:53:20 DEBUG : Creating backend with remote "TestDrive:crypt/pp62fi2pb9cpsk867ktg44cc4ecbae2lkllsqt2ojpuaom1e01q0/31u3jie661vd5p8j7rtc3hgbh0"
2026/10/01 02:53:23 DEBUG : dst/one: md5 = 96e54aaafb5bcb8285b5992c5af74ab2 OK
2026/10/01 02:53:25 DEBUG : CopyDest/one: md5 = 6a13baee0c4a0c75ad1bd33f6436b75e OK
2026/10/01 02:53:26 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-fuporus9dele/CopyDest"
2026/10/01 02:53:26 DEBUG : Creating backend with remote "TestDrive:crypt/pp62fi2pb9cpsk867ktg44cc4ecbae2lkllsqt2ojpuaom1e01q0/d09o6po3f7bm6ce32vdgs8h9ls"
2026/10/01 02:53:27 DEBUG : one: size = 5 (Local file system at /tmp/rclone2683199630)
2026/10/01 02:53:27 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele/dst')
2026/10/01 02:53:27 DEBUG : one: Sizes differ
2026/10/01 02:53:27 DEBUG : one: size = 5 OK
2026/10/01 02:53:27 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/10/01 02:53:27 DEBUG : one: size = 5 (Local file system at /tmp/rclone2683199630)
2026/10/01 02:53:27 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele/dst')
2026/10/01 02:53:27 DEBUG : one: Sizes differ
2026/10/01 02:53:27 ERROR : one: Source and destination exist but do not match: immutable file modified
--- PASS: TestCopyFileCopyDestImmutable (11.11s)
=== RUN   TestCopyFileCopyDest
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 02:53:31 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-fuporus9dele/dst"
2026/10/01 02:53:31 DEBUG : Creating backend with remote "TestDrive:crypt/pp62fi2pb9cpsk867ktg44cc4ecbae2lkllsqt2ojpuaom1e01q0/31u3jie661vd5p8j7rtc3hgbh0"
2026/10/01 02:53:32 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-fuporus9dele/CopyDest"
2026/10/01 02:53:32 DEBUG : Creating backend with remote "TestDrive:crypt/pp62fi2pb9cpsk867ktg44cc4ecbae2lkllsqt2ojpuaom1e01q0/d09o6po3f7bm6ce32vdgs8h9ls"
2026/10/01 02:53:34 DEBUG : one: Need to transfer - File not found at Destination
2026/10/01 02:53:36 DEBUG : one: md5 = fd8fc4f1fba675bd9152207616697212 OK
2026/10/01 02:53:36 DEBUG : one: size = 3 OK
2026/10/01 02:53:36 INFO  : one: Copied (new)
2026/10/01 02:53:37 DEBUG : one: size = 5 (Local file system at /tmp/rclone2683199630)
2026/10/01 02:53:37 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele/dst')
2026/10/01 02:53:37 DEBUG : one: Sizes differ
2026/10/01 02:53:39 DEBUG : one: md5 = e594297bc2d4e9aaf18f2a9f45ca1371 OK
2026/10/01 02:53:39 DEBUG : one: size = 5 OK
2026/10/01 02:53:39 INFO  : one: Copied (replaced existing)
2026/10/01 02:53:41 DEBUG : dst/one: md5 = 05bb470a4ee39260292bd2cbb9da38d1 OK
2026/10/01 02:53:43 DEBUG : CopyDest/one: md5 = abf1194b26c0ebba714283709dbfb8fb OK
2026/10/01 02:53:44 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-fuporus9dele/BackupDir"
2026/10/01 02:53:44 DEBUG : Creating backend with remote "TestDrive:crypt/pp62fi2pb9cpsk867ktg44cc4ecbae2lkllsqt2ojpuaom1e01q0/s6dbk3lfi7c9kfvo6j7bla9m0g"
2026/10/01 02:53:45 DEBUG : one: size = 5 (Local file system at /tmp/rclone2683199630)
2026/10/01 02:53:45 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele/dst')
2026/10/01 02:53:45 DEBUG : one: Sizes differ
2026/10/01 02:53:46 DEBUG : one: size = 5 OK
2026/10/01 02:53:46 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/10/01 02:53:46 DEBUG : one: size = 5 (Local file system at /tmp/rclone2683199630)
2026/10/01 02:53:46 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele/dst')
2026/10/01 02:53:46 DEBUG : one: Sizes differ
2026/10/01 02:53:48 INFO  : one: Moved (server-side)
2026/10/01 02:53:49 DEBUG : one: size = 5 OK
2026/10/01 02:53:49 INFO  : one: Copied (server-side copy)
2026/10/01 02:53:49 DEBUG : one: Destination found in --copy-dest, using server-side copy
2026/10/01 02:53:51 DEBUG : CopyDest/two: md5 = f6fcacfd44822f7aab4003c6616a10f7 OK
2026/10/01 02:53:52 DEBUG : two: Need to transfer - File not found at Destination
2026/10/01 02:53:52 DEBUG : two: size = 3 OK
2026/10/01 02:53:52 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/10/01 02:53:53 DEBUG : two: size = 3 OK
2026/10/01 02:53:53 INFO  : two: Copied (server-side copy)
2026/10/01 02:53:53 DEBUG : two: Destination found in --copy-dest, using server-side copy
2026/10/01 02:53:54 DEBUG : two: size = 3 OK
2026/10/01 02:53:54 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/10/01 02:53:54 DEBUG : two: Unchanged skipping
2026/10/01 02:53:56 DEBUG : CopyDest/three: md5 = 030ecb2ce3730aa145e5ae20d52b880f OK
2026/10/01 02:53:57 DEBUG : three: Need to transfer - File not found at Destination
2026/10/01 02:53:58 DEBUG : three: size = 7 (Local file system at /tmp/rclone2683199630)
2026/10/01 02:53:58 DEBUG : three: size = 5 (Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele/CopyDest')
2026/10/01 02:53:58 DEBUG : three: Sizes differ
2026/10/01 02:53:58 DEBUG : three: Destination not found in --copy-dest
2026/10/01 02:53:59 DEBUG : three: md5 = 1162dd5c3f44c73273bf482c8f954f9f OK
2026/10/01 02:53:59 DEBUG : three: size = 7 OK
2026/10/01 02:53:59 INFO  : three: Copied (new)
--- PASS: TestCopyFileCopyDest (35.20s)
=== RUN   TestCopyInplace
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
    copy_test.go:434: Partial uploads not supported
--- SKIP: TestCopyInplace (0.43s)
=== RUN   TestCopyLongFileName
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
    copy_test.go:467: Partial uploads not supported
--- SKIP: TestCopyLongFileName (0.48s)
=== RUN   TestCopyLongFileNameCollision
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
    copy_test.go:500: Partial uploads not supported
--- SKIP: TestCopyLongFileNameCollision (0.39s)
=== RUN   TestCopyFileMaxTransfer
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 02:54:07 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination
2026/10/01 02:54:10 DEBUG : TestCopyFileMaxTransfer/file1: md5 = 8eac3e360ddec56dada32229d840caae OK
2026/10/01 02:54:10 DEBUG : TestCopyFileMaxTransfer/file1: size = 14 OK
2026/10/01 02:54:10 INFO  : TestCopyFileMaxTransfer/file1: Copied (new)
2026/10/01 02:54:10 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination
2026/10/01 02:54:10 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/01 02:54:11 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination
2026/10/01 02:54:12 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination
2026/10/01 02:54:13 DEBUG : TestCopyFileMaxTransfer/file4: md5 = 356923259f905778581412218f6c80a4 OK
2026/10/01 02:54:13 DEBUG : TestCopyFileMaxTransfer/file4: size = 2062 OK
2026/10/01 02:54:13 INFO  : TestCopyFileMaxTransfer/file4: Copied (new)
--- PASS: TestCopyFileMaxTransfer (8.99s)
=== RUN   TestDeduplicateInteractive
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
    dedupe_test.go:37: Can't run this test without a hash
--- SKIP: TestDeduplicateInteractive (0.44s)
=== RUN   TestDeduplicateSkip
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 02:54:19 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele': Looking for duplicate names using skip mode.
2026/10/01 02:54:21 NOTICE: one: Found 2 files with duplicate names
2026/10/01 02:54:21 NOTICE: one: Skipping 2 files with duplicate names
--- PASS: TestDeduplicateSkip (5.55s)
=== RUN   TestDeduplicateSizeOnly
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 02:54:27 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele': Looking for duplicate names using skip mode.
2026/10/01 02:54:27 NOTICE: one: Found 3 files with duplicate names
2026/10/01 02:54:27 NOTICE: one: Deleting 1/2 identical duplicates (size 11)
2026/10/01 02:54:27 INFO  : one: Deleted
2026/10/01 02:54:27 NOTICE: one: Skipping 2 files with duplicate names
--- PASS: TestDeduplicateSizeOnly (6.79s)
=== RUN   TestDeduplicateFirst
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 02:54:33 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele': Looking for duplicate names using first mode.
2026/10/01 02:54:33 NOTICE: one: Found 3 files with duplicate names
2026/10/01 02:54:34 INFO  : one: Deleted
2026/10/01 02:54:34 INFO  : one: Deleted
2026/10/01 02:54:34 NOTICE: one: Deleted 2 extra copies
--- PASS: TestDeduplicateFirst (6.28s)
=== RUN   TestDeduplicateNewest
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 02:54:39 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele': Looking for duplicate names using newest mode.
2026/10/01 02:54:40 NOTICE: one: Found 3 files with duplicate names
2026/10/01 02:54:40 INFO  : one: Deleted
2026/10/01 02:54:40 INFO  : one: Deleted
2026/10/01 02:54:40 NOTICE: one: Deleted 2 extra copies
--- PASS: TestDeduplicateNewest (6.22s)
=== RUN   TestDeduplicateNewestByHash
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
    dedupe_test.go:37: Can't run this test without a hash
--- SKIP: TestDeduplicateNewestByHash (0.44s)
=== RUN   TestDeduplicateOldest
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 02:54:46 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele': Looking for duplicate names using oldest mode.
2026/10/01 02:54:46 NOTICE: one: Found 3 files with duplicate names
2026/10/01 02:54:47 INFO  : one: Deleted
2026/10/01 02:54:47 INFO  : one: Deleted
2026/10/01 02:54:47 NOTICE: one: Deleted 2 extra copies
--- PASS: TestDeduplicateOldest (6.19s)
=== RUN   TestDeduplicateLargest
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 02:54:52 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele': Looking for duplicate names using largest mode.
2026/10/01 02:54:52 NOTICE: one: Found 3 files with duplicate names
2026/10/01 02:54:53 INFO  : one: Deleted
2026/10/01 02:54:53 INFO  : one: Deleted
2026/10/01 02:54:53 NOTICE: one: Deleted 2 extra copies
--- PASS: TestDeduplicateLargest (6.13s)
=== RUN   TestDeduplicateSmallest
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 02:54:58 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele': Looking for duplicate names using smallest mode.
2026/10/01 02:54:59 NOTICE: one: Found 3 files with duplicate names
2026/10/01 02:54:59 INFO  : one: Deleted
2026/10/01 02:55:00 INFO  : one: Deleted
2026/10/01 02:55:00 NOTICE: one: Deleted 2 extra copies
--- PASS: TestDeduplicateSmallest (6.48s)
=== RUN   TestDeduplicateRename
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 02:55:05 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele': Looking for duplicate names using rename mode.
2026/10/01 02:55:06 NOTICE: one.txt: Found 3 files with duplicate names
2026/10/01 02:55:07 INFO  : one-2.txt: renamed from: one.txt
2026/10/01 02:55:07 INFO  : one-3.txt: renamed from: one.txt
2026/10/01 02:55:08 INFO  : one-4.txt: renamed from: one.txt
--- PASS: TestDeduplicateRename (9.77s)
=== RUN   TestDeduplicateRenameManyExisting
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 02:55:12 DEBUG : one-1.txt: md5 = bb5d881bf562c58ca6a5cf21e898c379 OK
2026/10/01 02:55:14 DEBUG : one-2.txt: md5 = fd0ac712d9730cb7da71c96e3babc586 OK
2026/10/01 02:55:15 DEBUG : one-3.txt: md5 = 52b3629a90478afde612361ff04ca4d3 OK
2026/10/01 02:55:17 DEBUG : one-4.txt: md5 = bb8d26ec179c1334a83eef05952e2c4b OK
2026/10/01 02:55:18 DEBUG : one-5.txt: md5 = 6eb17389a584c00a83f9d41ae5eb200b OK
2026/10/01 02:55:20 DEBUG : one-6.txt: md5 = 95c74d6c7705aee6ebf34d6b172eaf31 OK
2026/10/01 02:55:21 DEBUG : one-7.txt: md5 = 5424523cd57631f7779402f366f5b9e6 OK
2026/10/01 02:55:23 DEBUG : one-8.txt: md5 = c0af6710f33afec92ee7ce51c228955a OK
2026/10/01 02:55:24 DEBUG : one-9.txt: md5 = 772850b2df3446aa491022e840e41950 OK
2026/10/01 02:55:25 DEBUG : one-10.txt: md5 = eccbd286b9343f93a7af95728134ab14 OK
2026/10/01 02:55:27 DEBUG : one-11.txt: md5 = 6138b5dc8e3aa79630d9692f46147a7b OK
2026/10/01 02:55:28 DEBUG : one-12.txt: md5 = 58eba31e376fe3aa1b9c47029ffc5df2 OK
2026/10/01 02:55:29 DEBUG : one-13.txt: md5 = 0081e3f80436d7a4c12da87fa71f04e8 OK
2026/10/01 02:55:31 DEBUG : one-14.txt: md5 = 610bbfeb1a02e0d9ecead9eb95aa1eb9 OK
2026/10/01 02:55:32 DEBUG : one-15.txt: md5 = 9add6209aa4c75f9734969524f17c612 OK
2026/10/01 02:55:34 DEBUG : one-16.txt: md5 = d2ac27528e77ebb408c119a6758974f9 OK
2026/10/01 02:55:35 DEBUG : one-17.txt: md5 = a9c88f74d73867db0206cd3f1295873a OK
2026/10/01 02:55:36 DEBUG : one-18.txt: md5 = d67da1b476098a0dbc2fef9d3d96e67b OK
2026/10/01 02:55:38 DEBUG : one-19.txt: md5 = e3adbbcd07f195d1c101177e3af4502b OK
2026/10/01 02:55:39 DEBUG : one-20.txt: md5 = 85a4d5c62e4371f17e8451c6ae78048e OK
2026/10/01 02:55:41 DEBUG : one-21.txt: md5 = 3534ec2b8db312412828106a39dd5eb3 OK
2026/10/01 02:55:42 DEBUG : one-22.txt: md5 = f97372fd97f6f46fd43b0061e70efb08 OK
2026/10/01 02:55:44 DEBUG : one-23.txt: md5 = c2b076a427333e48df72f38127090bfa OK
2026/10/01 02:55:45 DEBUG : one-24.txt: md5 = 40b331b36f56e067a8c62ce9ea08532e OK
2026/10/01 02:55:47 DEBUG : one-25.txt: md5 = dde055a1099fe848410786bd47bbdd7f OK
2026/10/01 02:55:48 DEBUG : one-26.txt: md5 = 8bea12a3c7c61418c59817c498f0d1d4 OK
2026/10/01 02:55:49 DEBUG : one-27.txt: md5 = 3c8c2c255bd1e10bdd6c9e2d85effa40 OK
2026/10/01 02:55:51 DEBUG : one-28.txt: md5 = 5fb8f7d6af844d0c4a498a08ab0fd986 OK
2026/10/01 02:55:52 DEBUG : one-29.txt: md5 = a4024484c2237eb77b8f40701ed13811 OK
2026/10/01 02:55:54 DEBUG : one-30.txt: md5 = 8352eb3aa18b5a4295ebfcf369159a6c OK
2026/10/01 02:55:55 DEBUG : one-31.txt: md5 = 8724f9601a83dc5853a0ef1e557d2fc0 OK
2026/10/01 02:55:57 DEBUG : one-32.txt: md5 = 237984e793d4416cff95f83064b9ec4e OK
2026/10/01 02:55:59 DEBUG : one-33.txt: md5 = 9a9f53e9509905ab178d4040ee32896c OK
2026/10/01 02:56:00 DEBUG : one-34.txt: md5 = 204b0516b97cb8b43765752259c84cac OK
2026/10/01 02:56:02 DEBUG : one-35.txt: md5 = 0dfdc37c99a0a09f45a2116337a3e522 OK
2026/10/01 02:56:03 DEBUG : one-36.txt: md5 = e91a568c441b7fb52f3d5aecd62c15db OK
2026/10/01 02:56:05 DEBUG : one-37.txt: md5 = 0d5651dee860843c8c4dd1f36d668e70 OK
2026/10/01 02:56:06 DEBUG : one-38.txt: md5 = 8c392cdb7d3b76e3d551bd9d63fd3132 OK
2026/10/01 02:56:08 DEBUG : one-39.txt: md5 = e8f18b70d28fe7a90c8e05403c6a6480 OK
2026/10/01 02:56:09 DEBUG : one-40.txt: md5 = 5c82538f8c02a89f20ce3b471e01ad91 OK
2026/10/01 02:56:11 DEBUG : one-41.txt: md5 = 00b4ba74c78ce1943ff0017f9c4bd9f9 OK
2026/10/01 02:56:12 DEBUG : one-42.txt: md5 = 08621ec65bdcf85cd74d517f0c0634f9 OK
2026/10/01 02:56:13 DEBUG : one-43.txt: md5 = c40ef8991d7de0882eb5a3d5cf688d66 OK
2026/10/01 02:56:15 DEBUG : one-44.txt: md5 = 9a10d4e54186e299ce0dc4f83e3ef119 OK
2026/10/01 02:56:16 DEBUG : one-45.txt: md5 = 361d63afef31adb4bfe9f6407d1e40d3 OK
2026/10/01 02:56:18 DEBUG : one-46.txt: md5 = 8975a4c44f6c1426ac2bac526415ae59 OK
2026/10/01 02:56:19 DEBUG : one-47.txt: md5 = fd0de41bb5467da16d20317d3734c4d0 OK
2026/10/01 02:56:20 DEBUG : one-48.txt: md5 = 2f5f6905affee0a962a9ce2202595ca7 OK
2026/10/01 02:56:22 DEBUG : one-49.txt: md5 = ef032dd9bec455a94b034d0da9a56961 OK
2026/10/01 02:56:23 DEBUG : one-50.txt: md5 = 3746df2ee17964239fffe47259df691a OK
2026/10/01 02:56:25 DEBUG : one-51.txt: md5 = bd69ce100a5fe1e7d8b14ff9936b65f9 OK
2026/10/01 02:56:27 DEBUG : one-52.txt: md5 = 851096c280e4bee0c5ccf83a5c198dff OK
2026/10/01 02:56:28 DEBUG : one-53.txt: md5 = 797155a4cc74e6e74a8d5df46f9e474f OK
2026/10/01 02:56:30 DEBUG : one-54.txt: md5 = 42e17d5403db68c5c2ec9081a63b6019 OK
2026/10/01 02:56:31 DEBUG : one-55.txt: md5 = d5691b420ab13852dac5f719d358530c OK
2026/10/01 02:56:32 DEBUG : one-56.txt: md5 = 9a41b5a29c948382829de4aa0613719c OK
2026/10/01 02:56:34 DEBUG : one-57.txt: md5 = b3db56fec2ffe0c72c0e921736f9cbbb OK
2026/10/01 02:56:35 DEBUG : one-58.txt: md5 = f1977777fa10fd676371c69ff15f5fec OK
2026/10/01 02:56:37 DEBUG : one-59.txt: md5 = f31a3896646f8caa4eb0ab31ede57067 OK
2026/10/01 02:56:38 DEBUG : one-60.txt: md5 = 5e1718ea1bcb7dab18360addc9ab0375 OK
2026/10/01 02:56:40 DEBUG : one-61.txt: md5 = 573402918f96d4ab754f1f924e27268b OK
2026/10/01 02:56:41 DEBUG : one-62.txt: md5 = 798f327c2b4eb551d9821c7964dbafbd OK
2026/10/01 02:56:42 DEBUG : one-63.txt: md5 = d0d35802c393539e2d42380d41bca66b OK
2026/10/01 02:56:44 DEBUG : one-64.txt: md5 = c8f0bef1c9e4a7a2c8a093b7d5eb0ada OK
2026/10/01 02:56:45 DEBUG : one-65.txt: md5 = afbff2ebe3e5c9e6c437e2dcfa61f8e7 OK
2026/10/01 02:56:47 DEBUG : one-66.txt: md5 = 689be78330bdd1b2591713ae324daf3b OK
2026/10/01 02:56:48 DEBUG : one-67.txt: md5 = 1879069f760da6cac81cedbfbef54006 OK
2026/10/01 02:56:50 DEBUG : one-68.txt: md5 = 7a2efea71f8cb2548d710682c3400df3 OK
2026/10/01 02:56:51 DEBUG : one-69.txt: md5 = 3cd739bf85f56b1c0ad790e25fc5a780 OK
2026/10/01 02:56:53 DEBUG : one-70.txt: md5 = 331c04128273905872a798995e12768b OK
2026/10/01 02:56:54 DEBUG : one-71.txt: md5 = 936d8c49bc8e3451ce9cd8b1660851ce OK
2026/10/01 02:56:55 DEBUG : one-72.txt: md5 = 48678fa68dc77718d46618dbf536f595 OK
2026/10/01 02:56:57 DEBUG : one-73.txt: md5 = 1a0de0a1162dc79c01cbb55d4a68b788 OK
2026/10/01 02:56:58 DEBUG : one-74.txt: md5 = 1d1b26572fbcd3e2279d5bdecf1fe0b7 OK
2026/10/01 02:56:59 DEBUG : one-75.txt: md5 = fe50d619a61fc55c6d34642f4de7a02d OK
2026/10/01 02:57:01 DEBUG : one-76.txt: md5 = 3493e662c6777363ef56a525bdca4d39 OK
2026/10/01 02:57:02 DEBUG : one-77.txt: md5 = d4f7d4ae621fd5ce26ceef271d5c2203 OK
2026/10/01 02:57:04 DEBUG : one-78.txt: md5 = cb36195afb60fa1bbbe2fd0bfeb8e1ae OK
2026/10/01 02:57:05 DEBUG : one-79.txt: md5 = 42cf666a2d35d5a9da15606037d7f58c OK
2026/10/01 02:57:06 DEBUG : one-80.txt: md5 = 8630f2f8269d465d857cdba7660a07ce OK
2026/10/01 02:57:08 DEBUG : one-81.txt: md5 = cfa00e76049a83763c0a81eb4a21d5d9 OK
2026/10/01 02:57:09 DEBUG : one-82.txt: md5 = f65964a9b817fd85afb64756126b7402 OK
2026/10/01 02:57:11 DEBUG : one-83.txt: md5 = 814ab4d96bd904ec9fae82a9b815bb8d OK
2026/10/01 02:57:12 DEBUG : one-84.txt: md5 = 06faa60ea988d10b6c12e82ee69b587a OK
2026/10/01 02:57:14 DEBUG : one-85.txt: md5 = c3b7fcccd0d6cd20347988ff621228d4 OK
2026/10/01 02:57:15 DEBUG : one-86.txt: md5 = 0d248a349a54e0d74714770c1e8000ed OK
2026/10/01 02:57:17 DEBUG : one-87.txt: md5 = ebed2cdacd94bc785300f83e2cbd56e0 OK
2026/10/01 02:57:18 DEBUG : one-88.txt: md5 = 59d738ac939cbfd6429f202fe2c7bd8c OK
2026/10/01 02:57:20 DEBUG : one-89.txt: md5 = 4c5620185de6896192ab11997073c7dd OK
2026/10/01 02:57:21 DEBUG : one-90.txt: md5 = 024d5454ce057145d6e0d97231529f61 OK
2026/10/01 02:57:22 DEBUG : one-91.txt: md5 = 55006bbeb93d53347b179b30c730bf7d OK
2026/10/01 02:57:24 DEBUG : one-92.txt: md5 = c7dce69e114a8695eb62785517e61beb OK
2026/10/01 02:57:25 DEBUG : one-93.txt: md5 = fd5e12e19eaa4fb8fe74de746af9009c OK
2026/10/01 02:57:27 DEBUG : one-94.txt: md5 = 0d323e6716e5cde3937b392dfb76af23 OK
2026/10/01 02:57:28 DEBUG : one-95.txt: md5 = ffc68d8058dc13b3d3354e250c8801ac OK
2026/10/01 02:57:29 DEBUG : one-96.txt: md5 = 1ebb34e93bce53b12f45ae49fa7d138f OK
2026/10/01 02:57:31 DEBUG : one-97.txt: md5 = 7a0f6fba041c2e72050679cef520e814 OK
2026/10/01 02:57:32 DEBUG : one-98.txt: md5 = 32c3f8bd9cbe80a0625761ca9dec9af5 OK
2026/10/01 02:57:34 DEBUG : one-99.txt: md5 = 6bd1e384c66c9ae346210cae50935285 OK
2026/10/01 02:57:35 DEBUG : one-100.txt: md5 = 74ab718d755c4221ec382c1eae06bb47 OK
2026/10/01 02:57:37 DEBUG : one-101.txt: md5 = a0b33c29e62cac4214a1ee41beeab0e9 OK
2026/10/01 02:57:38 DEBUG : one-102.txt: md5 = 60def79016cd2584c6efa2976a238a17 OK
2026/10/01 02:57:40 DEBUG : one-103.txt: md5 = bc2ead96094d41182d236b9210fc07e1 OK
2026/10/01 02:57:41 DEBUG : one-104.txt: md5 = 17aa92570d25a78ea60502f5ded606d6 OK
2026/10/01 02:57:42 DEBUG : one-105.txt: md5 = dd01e578762be3a4282afa8c851093cb OK
2026/10/01 02:57:45 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele': Looking for duplicate names using rename mode.
2026/10/01 02:57:46 NOTICE: one.txt: Found 2 files with duplicate names
2026/10/01 02:57:46 INFO  : one-106.txt: renamed from: one.txt
2026/10/01 02:57:47 INFO  : one-107.txt: renamed from: one.txt
--- PASS: TestDeduplicateRenameManyExisting (207.45s)
=== RUN   TestMergeDirs
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 02:58:40 DEBUG : dupe1/one.txt: md5 = d368194ca2aad0ee0c0b5cf80afc2526 OK
2026/10/01 02:58:43 DEBUG : dupe2/two.txt: md5 = adefdb4c201a56d51fae5f1f503c9af6 OK
2026/10/01 02:58:45 DEBUG : dupe3/three.txt: md5 = db24ea23e3e7dc37625add84b5250457 OK
2026/10/01 02:58:45 INFO  : urj8gsducqbtvekeq181ntd2u8: merging "sfrom47189m9qr3mt4qonu281c"
2026/10/01 02:58:46 INFO  : urj8gsducqbtvekeq181ntd2u8: removing empty directory
2026/10/01 02:58:47 INFO  : 1u1r4ei7c628fnjg9blqt3j60o: merging "tr2hj63d80ftlmvm6a952snjcc"
2026/10/01 02:58:47 INFO  : 1u1r4ei7c628fnjg9blqt3j60o: removing empty directory
--- PASS: TestMergeDirs (12.81s)
=== RUN   TestIndex
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 02:58:52 DEBUG : file1.txt: md5 = 87251a61302d6b66b25ed2eeb8ef0e92 OK
2026/10/01 02:58:54 DEBUG : sub/file2.txt: md5 = 14a0bd17a1edf79eca99e70cdc42a41d OK
2026/10/01 02:58:58 DEBUG : sub/deep/file3.txt: md5 = 7fe7e871088c8bac22ca15285726fd8d OK
2026/10/01 02:59:00 DEBUG : index.html: md5 = 25af84721188080be7113e44f8b6d4c8 OK
2026/10/01 02:59:00 DEBUG : index.html: size = 12147 OK
2026/10/01 02:59:00 INFO  : index.html: Copied (new)
2026/10/01 02:59:00 DEBUG : sub/deep/index.html: md5 = f8392f8057b0b23fcb28c980adfac218 OK
2026/10/01 02:59:00 DEBUG : sub/deep/index.html: size = 12087 OK
2026/10/01 02:59:00 INFO  : sub/deep/index.html: Copied (new)
2026/10/01 02:59:00 DEBUG : sub/index.html: md5 = bbba949d21ba525efba06d81ea1ae838 OK
2026/10/01 02:59:00 DEBUG : sub/index.html: size = 12306 OK
2026/10/01 02:59:00 INFO  : sub/index.html: Copied (new)
2026/10/01 02:59:00 DEBUG : index.json: md5 = b88dcbfd0b17f6232ad1c383bff16f08 OK
2026/10/01 02:59:00 DEBUG : index.json: size = 213 OK
2026/10/01 02:59:00 INFO  : index.json: Copied (new)
2026/10/01 02:59:02 DEBUG : caddy.json: md5 = b921ddaa8acd68ea7f012648674c7112 OK
2026/10/01 02:59:02 DEBUG : caddy.json: size = 259 OK
2026/10/01 02:59:02 INFO  : caddy.json: Copied (new)
2026/10/01 02:59:02 DEBUG : sub/deep/index.json: md5 = 7bef2e02b05481355ee1125aec9c2a3d OK
2026/10/01 02:59:02 DEBUG : sub/deep/index.json: size = 119 OK
2026/10/01 02:59:02 INFO  : sub/deep/index.json: Copied (new)
2026/10/01 02:59:02 DEBUG : sub/caddy.json: md5 = 579c19461dcacc6c3f86adafd20bb2b7 OK
2026/10/01 02:59:02 DEBUG : sub/caddy.json: size = 261 OK
2026/10/01 02:59:02 INFO  : sub/caddy.json: Copied (new)
2026/10/01 02:59:02 DEBUG : sub/index.json: md5 = 9319a977b6907b96965654e533fad4e9 OK
2026/10/01 02:59:02 DEBUG : sub/index.json: size = 215 OK
2026/10/01 02:59:02 INFO  : sub/index.json: Copied (new)
2026/10/01 02:59:03 DEBUG : sub/deep/caddy.json: md5 = eefd0f7298c739e729d04f594f5c6bb1 OK
2026/10/01 02:59:03 DEBUG : sub/deep/caddy.json: size = 131 OK
2026/10/01 02:59:03 INFO  : sub/deep/caddy.json: Copied (new)
2026/10/01 02:59:09 DEBUG : sub/index.html: Unchanged skipping
2026/10/01 02:59:09 DEBUG : sub/deep/index.html: Unchanged skipping
2026/10/01 02:59:09 DEBUG : index.html: Unchanged skipping
2026/10/01 02:59:09 DEBUG : index.json: Unchanged skipping
2026/10/01 02:59:10 DEBUG : sub/index.json: Unchanged skipping
2026/10/01 02:59:10 DEBUG : sub/deep/index.json: Unchanged skipping
2026/10/01 02:59:10 DEBUG : caddy.json: Unchanged skipping
2026/10/01 02:59:10 DEBUG : sub/deep/caddy.json: Unchanged skipping
2026/10/01 02:59:10 DEBUG : sub/caddy.json: Unchanged skipping
2026/10/01 02:59:12 DEBUG : sub/deep/index.html: md5 = f5bd39b34d5c10b1e43aff6200d4603d OK
2026/10/01 02:59:12 DEBUG : sub/deep/index.html: size = 12087 OK
2026/10/01 02:59:12 INFO  : sub/deep/index.html: Copied (replaced existing)
2026/10/01 02:59:12 DEBUG : sub/index.html: md5 = 7f519e72781a639617b72177053edac5 OK
2026/10/01 02:59:12 DEBUG : sub/index.html: size = 12306 OK
2026/10/01 02:59:12 INFO  : sub/index.html: Copied (replaced existing)
2026/10/01 02:59:12 DEBUG : sub/deep/caddy.json: md5 = b63755f5b3c3b2a1a238702987c93ad3 OK
2026/10/01 02:59:12 DEBUG : sub/deep/caddy.json: size = 131 OK
2026/10/01 02:59:12 INFO  : sub/deep/caddy.json: Copied (replaced existing)
2026/10/01 02:59:13 DEBUG : sub/deep/index.json: md5 = c71a8b1be0f127ce422bea7711560923 OK
2026/10/01 02:59:13 DEBUG : sub/deep/index.json: size = 119 OK
2026/10/01 02:59:13 INFO  : sub/deep/index.json: Copied (replaced existing)
2026/10/01 02:59:13 DEBUG : sub/index.json: md5 = f4f22b790f600e3618bedb8bb24e2cc2 OK
2026/10/01 02:59:13 DEBUG : sub/index.json: size = 215 OK
2026/10/01 02:59:13 INFO  : sub/index.json: Copied (replaced existing)
2026/10/01 02:59:13 DEBUG : sub/caddy.json: md5 = 04372b2a851b5dd3d543bbe7d1f2cd31 OK
2026/10/01 02:59:13 DEBUG : sub/caddy.json: size = 261 OK
2026/10/01 02:59:13 INFO  : sub/caddy.json: Copied (replaced existing)
2026/10/01 02:59:13 DEBUG : index.html: md5 = 72bedfc32aca3a61a1f40a9be8539d87 OK
2026/10/01 02:59:13 DEBUG : index.html: size = 12147 OK
2026/10/01 02:59:13 INFO  : index.html: Copied (replaced existing)
2026/10/01 02:59:14 DEBUG : index.json: md5 = 4cc6c4e7318cec7690d0a3aabb67b372 OK
2026/10/01 02:59:14 DEBUG : index.json: size = 213 OK
2026/10/01 02:59:14 INFO  : index.json: Copied (replaced existing)
2026/10/01 02:59:14 DEBUG : caddy.json: md5 = 4cbecf8ec08f717a6073d361eb2c0c21 OK
2026/10/01 02:59:14 DEBUG : caddy.json: size = 259 OK
2026/10/01 02:59:14 INFO  : caddy.json: Copied (replaced existing)
2026/10/01 02:59:16 DEBUG : sub/deep/file4.txt: md5 = a21ddd69448230558e3313f0fb21ded7 OK
2026/10/01 02:59:17 DEBUG : sub/index.html: Unchanged skipping
2026/10/01 02:59:17 DEBUG : index.html: Unchanged skipping
2026/10/01 02:59:18 DEBUG : sub/deep/index.html: md5 = 3e8687021a50dce735c2a032e6a48901 OK
2026/10/01 02:59:18 DEBUG : sub/deep/index.html: size = 12346 OK
2026/10/01 02:59:18 INFO  : sub/deep/index.html: Copied (replaced existing)
2026/10/01 02:59:18 DEBUG : sub/deep/index.json: md5 = 35846d4f8f9c96943d0306cc30669bbb OK
2026/10/01 02:59:18 DEBUG : sub/deep/index.json: size = 235 OK
2026/10/01 02:59:18 INFO  : sub/deep/index.json: Copied (replaced existing)
2026/10/01 02:59:18 DEBUG : sub/deep/caddy.json: md5 = adbe5c6b160db5d0585c63539d4bf17a OK
2026/10/01 02:59:18 DEBUG : sub/deep/caddy.json: size = 260 OK
2026/10/01 02:59:18 INFO  : sub/deep/caddy.json: Copied (replaced existing)
2026/10/01 02:59:18 DEBUG : sub/index.json: Unchanged skipping
2026/10/01 02:59:18 DEBUG : index.json: Unchanged skipping
2026/10/01 02:59:18 DEBUG : caddy.json: Unchanged skipping
2026/10/01 02:59:19 DEBUG : sub/caddy.json: Unchanged skipping
2026/10/01 02:59:21 DEBUG : sub/deep/file5.txt: md5 = 9a9634cb899963e335ec0b0b0fed4d16 OK
2026/10/01 02:59:22 NOTICE: sub/deep/index.html: Skipped copy as --dry-run is set (size 12.309Ki)
2026/10/01 02:59:22 NOTICE: sub/deep/caddy.json: Skipped copy as --dry-run is set (size 389)
2026/10/01 02:59:22 NOTICE: sub/deep/index.json: Skipped copy as --dry-run is set (size 351)
2026/10/01 02:59:22 DEBUG : sub/index.html: Unchanged skipping
2026/10/01 02:59:22 DEBUG : index.html: Unchanged skipping
2026/10/01 02:59:23 DEBUG : index.json: Unchanged skipping
2026/10/01 02:59:23 DEBUG : sub/index.json: Unchanged skipping
2026/10/01 02:59:23 DEBUG : caddy.json: Unchanged skipping
2026/10/01 02:59:23 DEBUG : sub/caddy.json: Unchanged skipping
--- PASS: TestIndex (41.83s)
=== RUN   TestIndexDelete
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 02:59:34 DEBUG : file1.txt: md5 = 12306b71d1deaf8483a31b6d46364563 OK
2026/10/01 02:59:37 DEBUG : sub/file2.txt: md5 = 02fe0f58c84413024f71f06a6148104d OK
2026/10/01 02:59:39 DEBUG : sub/deep/file3.txt: md5 = f0377671843b8d11ac4505b3db3353ca OK
2026/10/01 02:59:41 DEBUG : sub/index.html: md5 = f39d39318910b00327e6ec08489a3635 OK
2026/10/01 02:59:41 DEBUG : sub/index.html: size = 12306 OK
2026/10/01 02:59:41 INFO  : sub/index.html: Copied (new)
2026/10/01 02:59:41 DEBUG : index.html: md5 = 873fb2480ddc7cdc8ed709dbef3ab0e0 OK
2026/10/01 02:59:41 DEBUG : index.html: size = 12147 OK
2026/10/01 02:59:41 INFO  : index.html: Copied (new)
2026/10/01 02:59:41 DEBUG : sub/deep/index.html: md5 = 64bf6998cfab4eadfa5ee4eda8206539 OK
2026/10/01 02:59:41 DEBUG : sub/deep/index.html: size = 12087 OK
2026/10/01 02:59:41 INFO  : sub/deep/index.html: Copied (new)
2026/10/01 02:59:43 DEBUG : sub/index.html: Unchanged skipping
2026/10/01 02:59:43 DEBUG : index.html: Unchanged skipping
2026/10/01 02:59:44 DEBUG : sub/deep/index.html: md5 = 1e9e9bca3ff4e6614bc4b39e43cbdc03 OK
2026/10/01 02:59:44 DEBUG : sub/deep/index.html: size = 11829 OK
2026/10/01 02:59:44 INFO  : sub/deep/index.html: Copied (replaced existing)
--- PASS: TestIndexDelete (18.49s)
=== RUN   TestIndexFilter
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 02:59:53 DEBUG : file1.txt: md5 = 5340930e8e16df7a36fdabda85029a1f OK
2026/10/01 02:59:55 DEBUG : sub/file2.txt: md5 = e8c2b5270af7905a89388625412a6a48 OK
2026/10/01 02:59:57 DEBUG : sub/deep/file3.txt: md5 = 98ff9ad7ca6e6f13b7f472a0aaf0e763 OK
2026/10/01 02:59:58 DEBUG : sub/deep/index.html: md5 = a56fd210f5db9c2d9ba9f7f26382f7a6 OK
2026/10/01 03:00:01 DEBUG : other/notes.md: md5 = e2dd1cf9fb61c9498f36605c54be2680 OK
2026/10/01 03:00:01 DEBUG : other/notes.md: Excluded (Path Filter)
2026/10/01 03:00:03 DEBUG : other/index.html: md5 = c77fdd36d6897be796650960664602aa OK
2026/10/01 03:00:03 DEBUG : other/index.html: size = 11802 OK
2026/10/01 03:00:03 INFO  : other/index.html: Copied (new)
2026/10/01 03:00:03 DEBUG : index.html: md5 = 035bcb8e62cc2df3df698dbb18a3d544 OK
2026/10/01 03:00:03 DEBUG : index.html: size = 12399 OK
2026/10/01 03:00:03 INFO  : index.html: Copied (new)
2026/10/01 03:00:03 DEBUG : sub/index.html: md5 = 392e96a9ccf2ce6d0f2ad1c1a7c413d1 OK
2026/10/01 03:00:03 DEBUG : sub/index.html: size = 12056 OK
2026/10/01 03:00:03 INFO  : sub/index.html: Copied (new)
2026/10/01 03:00:08 DEBUG : other/notes.md: Excluded (Path Filter)
2026/10/01 03:00:09 DEBUG : other/index.html: Unchanged skipping
2026/10/01 03:00:09 DEBUG : index.html: Unchanged skipping
2026/10/01 03:00:09 DEBUG : sub/index.html: Unchanged skipping
--- PASS: TestIndexFilter (24.47s)
=== RUN   TestIndexRules
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 03:00:17 DEBUG : file1.txt: md5 = ae3acb93f20a11916eaed3fefaef3dfd OK
2026/10/01 03:00:19 DEBUG : sub/file2.txt: md5 = e80ba444f39e575758c96ae27acb53e6 OK
2026/10/01 03:00:22 DEBUG : sub/deep/file3.txt: md5 = d9fdf7ba280ca993d8e50441962652f3 OK
2026/10/01 03:00:23 DEBUG : sub/deep/index.html: md5 = becda643379b800110129b7901517103 OK
2026/10/01 03:00:25 DEBUG : sub/index.html: md5 = c6cef95a3a2cf8c3d84e5f6e46c5dd0b OK
2026/10/01 03:00:25 DEBUG : sub/index.html: size = 12306 OK
2026/10/01 03:00:25 INFO  : sub/index.html: Copied (new)
2026/10/01 03:00:25 DEBUG : index.html: md5 = 55771a8f0d67163f032a6d9c7861b738 OK
2026/10/01 03:00:25 DEBUG : index.html: size = 12147 OK
2026/10/01 03:00:25 INFO  : index.html: Copied (new)
2026/10/01 03:00:30 DEBUG : sub/index.html: Unchanged skipping
2026/10/01 03:00:31 DEBUG : sub/deep/index.html: md5 = 6cfcab7ae34128750e0847c2673c9fc7 OK
2026/10/01 03:00:31 DEBUG : sub/deep/index.html: size = 12087 OK
2026/10/01 03:00:31 INFO  : sub/deep/index.html: Copied (replaced existing)
--- PASS: TestIndexRules (22.02s)
=== RUN   TestIndexMaxDepth
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 03:00:39 DEBUG : file1.txt: md5 = 1cd38660661600b231affebefb6676ef OK
2026/10/01 03:00:41 DEBUG : sub/file2.txt: md5 = 61752a99166942aec41f2b30db1d7886 OK
2026/10/01 03:00:44 DEBUG : sub/deep/file3.txt: md5 = 028c5fe0faaa9797138d00bcca7ed612 OK
2026/10/01 03:00:46 DEBUG : index.html: md5 = 726f2ce90d6d6a7663c0cfe449503322 OK
2026/10/01 03:00:46 DEBUG : index.html: size = 12147 OK
2026/10/01 03:00:46 INFO  : index.html: Copied (new)
--- PASS: TestIndexMaxDepth (14.44s)
=== RUN   TestIndexLinkIndex
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 03:00:54 DEBUG : file1.txt: md5 = 88f51ffb23c34ce55c418ad6b7d121ed OK
2026/10/01 03:00:56 DEBUG : sub/file2.txt: md5 = f595c41fc394789a2b924e250d16105f OK
2026/10/01 03:00:58 DEBUG : sub/deep/file3.txt: md5 = f786066b85e68f360433f733aa3a0142 OK
2026/10/01 03:01:01 DEBUG : sub/index.html: md5 = 20d5a808f91c811f8ef047563272815b OK
2026/10/01 03:01:01 DEBUG : sub/index.html: size = 12347 OK
2026/10/01 03:01:01 INFO  : sub/index.html: Copied (new)
2026/10/01 03:01:01 DEBUG : index.html: md5 = 47c2bdf8e440f97fc9646a461a9b726f OK
2026/10/01 03:01:01 DEBUG : index.html: size = 12167 OK
2026/10/01 03:01:01 INFO  : index.html: Copied (new)
2026/10/01 03:01:01 DEBUG : sub/deep/index.html: md5 = 7bce5a1de2be8e373da747d045b72e31 OK
2026/10/01 03:01:01 DEBUG : sub/deep/index.html: size = 12128 OK
2026/10/01 03:01:01 INFO  : sub/deep/index.html: Copied (new)
--- PASS: TestIndexLinkIndex (16.07s)
=== RUN   TestIndexDirTime
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 03:01:10 DEBUG : file1.txt: md5 = ff9201dff55dcd80344dc10b3ffe06c0 OK
2026/10/01 03:01:12 DEBUG : sub/file2.txt: md5 = 95760b9a4750a674cbbf502ac9ecfe38 OK
2026/10/01 03:01:14 DEBUG : sub/deep/file3.txt: md5 = fb847c38236cf5a75326fec5b62f5e16 OK
2026/10/01 03:01:16 DEBUG : sub/deep/index.json: md5 = ddb84d4b5e7bcd29ba30b0d632ebe727 OK
2026/10/01 03:01:16 DEBUG : sub/deep/index.json: size = 139 OK
2026/10/01 03:01:16 INFO  : sub/deep/index.json: Copied (new)
2026/10/01 03:01:16 DEBUG : sub/index.json: md5 = 9c01d92c5d6f19d02a5089510e9ae885 OK
2026/10/01 03:01:16 DEBUG : sub/index.json: size = 259 OK
2026/10/01 03:01:16 INFO  : sub/index.json: Copied (new)
2026/10/01 03:01:16 DEBUG : index.json: md5 = fcfc31fc680931252fee414d38e3f340 OK
2026/10/01 03:01:16 DEBUG : index.json: size = 257 OK
2026/10/01 03:01:16 INFO  : index.json: Copied (new)
2026/10/01 03:01:19 DEBUG : sub/deep/index.json: Unchanged skipping
2026/10/01 03:01:19 DEBUG : index.json: md5 = 84125d1dd1b35968fc5708cffdf43050 OK
2026/10/01 03:01:19 DEBUG : index.json: size = 237 OK
2026/10/01 03:01:19 INFO  : index.json: Copied (replaced existing)
2026/10/01 03:01:19 DEBUG : sub/index.json: md5 = 864b8115aa289020735a3da9c438cb81 OK
2026/10/01 03:01:19 DEBUG : sub/index.json: size = 239 OK
2026/10/01 03:01:19 INFO  : sub/index.json: Copied (replaced existing)
2026/10/01 03:01:22 DEBUG : sub/deep/index.json: md5 = d549eb82b82f9cf87a915769ece200b7 OK
2026/10/01 03:01:22 DEBUG : sub/deep/index.json: size = 119 OK
2026/10/01 03:01:22 INFO  : sub/deep/index.json: Copied (replaced existing)
2026/10/01 03:01:22 DEBUG : index.json: md5 = cdb36a81c51f326c546d8f5d398c244a OK
2026/10/01 03:01:22 DEBUG : index.json: size = 213 OK
2026/10/01 03:01:22 INFO  : index.json: Copied (replaced existing)
2026/10/01 03:01:23 DEBUG : sub/index.json: md5 = ed3173c535cd18807721881e77216de7 OK
2026/10/01 03:01:23 DEBUG : sub/index.json: size = 215 OK
2026/10/01 03:01:23 INFO  : sub/index.json: Copied (replaced existing)
--- PASS: TestIndexDirTime (20.77s)
=== RUN   TestIndexTemplate
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 03:01:30 DEBUG : file1.txt: md5 = 7392bdd3ce09568547deb238acde5d30 OK
2026/10/01 03:01:32 DEBUG : sub/file2.txt: md5 = dd41e3c296bebdf818bb931070e00273 OK
2026/10/01 03:01:34 DEBUG : sub/deep/file3.txt: md5 = df32a588326a00b2e407bf548e0809e3 OK
2026/10/01 03:01:37 DEBUG : sub/deep/list.txt: md5 = 71c2ac371c453d220fbc89634e5e06c2 OK
2026/10/01 03:01:37 DEBUG : sub/deep/list.txt: size = 47 OK
2026/10/01 03:01:37 INFO  : sub/deep/list.txt: Copied (new)
2026/10/01 03:01:37 DEBUG : list.txt: md5 = 1e7dc9909f2d46100c4503431684c00a OK
2026/10/01 03:01:37 DEBUG : list.txt: size = 59 OK
2026/10/01 03:01:37 INFO  : list.txt: Copied (new)
2026/10/01 03:01:37 DEBUG : sub/list.txt: md5 = d35b2c3f7a23a50b95c180a12dc0dcb8 OK
2026/10/01 03:01:37 DEBUG : sub/list.txt: size = 64 OK
2026/10/01 03:01:37 INFO  : sub/list.txt: Copied (new)
--- PASS: TestIndexTemplate (14.44s)
=== 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:371: Skipping test on non local remote
--- SKIP: TestIndexNoHash (0.00s)
=== RUN   TestIndexChanged
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 03:01:45 DEBUG : file1.txt: md5 = 2fd87f7871b0ec15bc0dccf9c0768b38 OK
2026/10/01 03:01:47 DEBUG : sub/file2.txt: md5 = 6eb457999af413cfe3849970e1a2465a OK
2026/10/01 03:01:49 DEBUG : sub/deep/file3.txt: md5 = ecce0e2cc478edf26512ab6b9da2bb40 OK
2026/10/01 03:01:51 DEBUG : other/notes.md: md5 = 9ab984612e4e249a77c05a55e626bc5c OK
2026/10/01 03:01:53 DEBUG : other/index.html: md5 = 6dbdb34b10c74b7fcbae23b4b4facd25 OK
2026/10/01 03:01:53 DEBUG : other/index.html: size = 12058 OK
2026/10/01 03:01:53 INFO  : other/index.html: Copied (new)
2026/10/01 03:01:53 DEBUG : sub/index.html: md5 = f040741a8f60bcb3ef718c38aff3d631 OK
2026/10/01 03:01:53 DEBUG : sub/index.html: size = 12306 OK
2026/10/01 03:01:53 INFO  : sub/index.html: Copied (new)
2026/10/01 03:01:53 DEBUG : index.html: md5 = 2b8961c99c213edfa2312d9e3ac7cbf7 OK
2026/10/01 03:01:53 DEBUG : index.html: size = 12399 OK
2026/10/01 03:01:53 INFO  : index.html: Copied (new)
2026/10/01 03:01:54 DEBUG : sub/deep/index.html: md5 = 5788856c7e438b651f9e82bfc0fdd0ca OK
2026/10/01 03:01:54 DEBUG : sub/deep/index.html: size = 12087 OK
2026/10/01 03:01:54 INFO  : sub/deep/index.html: Copied (new)
2026/10/01 03:01:55 DEBUG : sub/deep/file4.txt: md5 = 6fbe26cfe36ebcb48f2ae85f5192d8f2 OK
2026/10/01 03:01:56 DEBUG : other/undeclared.txt: md5 = 8d710467abc6c30dda8d5d3060104656 OK
2026/10/01 03:01:56 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele': Partial index: listing 3 directories and walking 0 changed directories
2026/10/01 03:01:58 DEBUG : index.html: Unchanged skipping
2026/10/01 03:01:58 DEBUG : sub/index.html: Unchanged skipping
2026/10/01 03:01:58 DEBUG : sub/deep/index.html: md5 = 12a09abdf760238c1209ab6a78bfa1ae OK
2026/10/01 03:01:58 DEBUG : sub/deep/index.html: size = 12346 OK
2026/10/01 03:01:58 INFO  : sub/deep/index.html: Copied (replaced existing)
2026/10/01 03:02:02 DEBUG : sub/new/file5.txt: md5 = 6055ad4238c3053092bec12ca0db72ea OK
2026/10/01 03:02:02 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele': Partial index: listing 3 directories and walking 0 changed directories
2026/10/01 03:02:04 DEBUG : index.html: Unchanged skipping
2026/10/01 03:02:04 DEBUG : sub/index.html: md5 = c8e67e9a3f57a979baf727d7c22b1500 OK
2026/10/01 03:02:04 DEBUG : sub/index.html: size = 12554 OK
2026/10/01 03:02:04 INFO  : sub/index.html: Copied (replaced existing)
2026/10/01 03:02:05 DEBUG : sub/new/index.html: md5 = cd447817767116e66dcd19a2babac9a0 OK
2026/10/01 03:02:05 DEBUG : sub/new/index.html: size = 12085 OK
2026/10/01 03:02:05 INFO  : sub/new/index.html: Copied (new)
2026/10/01 03:02:08 DEBUG : other/undeclared0.txt: md5 = b39e20970fce58b95c45febb28891447 OK
2026/10/01 03:02:08 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele': Partial index: listing 1 directories and walking 1 changed directories
2026/10/01 03:02:09 DEBUG : index.html: Unchanged skipping
2026/10/01 03:02:10 DEBUG : other/index.html: md5 = 4cded8d3a7095570ce0450edec026ba3 OK
2026/10/01 03:02:10 DEBUG : other/index.html: size = 12597 OK
2026/10/01 03:02:10 INFO  : other/index.html: Copied (replaced existing)
2026/10/01 03:02:12 DEBUG : other/undeclared1.txt: md5 = 65240f0f64ad71e578ad261cb3f105aa OK
2026/10/01 03:02:12 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele': Partial index: listing 1 directories and walking 0 changed directories
2026/10/01 03:02:13 DEBUG : index.html: Unchanged skipping
2026/10/01 03:02:14 DEBUG : other/index.html: md5 = d17157f3008f281e04f4b603e0dea37f OK
2026/10/01 03:02:14 DEBUG : other/index.html: size = 12867 OK
2026/10/01 03:02:14 INFO  : other/index.html: Copied (replaced existing)
2026/10/01 03:02:16 DEBUG : sub/file6.txt: md5 = e7698b6e8cf8a832eeb53e3a97b4eadf OK
2026/10/01 03:02:16 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele': Nothing changed so nothing to index
2026/10/01 03:02:17 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele': Root changed so doing a full index
2026/10/01 03:02:18 DEBUG : sub/new/index.html: Unchanged skipping
2026/10/01 03:02:18 DEBUG : other/index.html: Unchanged skipping
2026/10/01 03:02:18 DEBUG : sub/deep/index.html: Unchanged skipping
2026/10/01 03:02:18 DEBUG : index.html: Unchanged skipping
2026/10/01 03:02:19 DEBUG : sub/index.html: md5 = dce2dc7ab4e3ae424f28c47537923050 OK
2026/10/01 03:02:19 DEBUG : sub/index.html: size = 12813 OK
2026/10/01 03:02:19 INFO  : sub/index.html: Copied (replaced existing)
2026/10/01 03:02:21 DEBUG : sub/file7.txt: md5 = 55df9d6c80206a7f4183a98f24f1d603 OK
2026/10/01 03:02:21 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele': Partial index would list 2 directories, more than 1, so doing a full index
2026/10/01 03:02:23 DEBUG : sub/new/index.html: Unchanged skipping
2026/10/01 03:02:23 DEBUG : other/index.html: Unchanged skipping
2026/10/01 03:02:23 DEBUG : sub/deep/index.html: Unchanged skipping
2026/10/01 03:02:23 DEBUG : index.html: Unchanged skipping
2026/10/01 03:02:23 DEBUG : sub/index.html: md5 = bfed917fc36ec832176048800d67193d OK
2026/10/01 03:02:23 DEBUG : sub/index.html: size = 13071 OK
2026/10/01 03:02:23 INFO  : sub/index.html: Copied (replaced existing)
2026/10/01 03:02:25 DEBUG : sub/deep/file8.txt: md5 = c74e0f717830c1606a325ad284b21ab9 OK
2026/10/01 03:02:25 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele': Partial index: listing 1 directories and walking 1 changed directories
2026/10/01 03:02:27 DEBUG : index.html: Unchanged skipping
2026/10/01 03:02:27 DEBUG : sub/new/index.html: Unchanged skipping
2026/10/01 03:02:27 DEBUG : sub/index.html: Unchanged skipping
2026/10/01 03:02:28 DEBUG : sub/deep/index.html: md5 = 77bcde3b541366d62cd453011ef7f72e OK
2026/10/01 03:02:28 DEBUG : sub/deep/index.html: size = 12604 OK
2026/10/01 03:02:28 INFO  : sub/deep/index.html: Copied (replaced existing)
2026/10/01 03:02:30 DEBUG : sub/deep/file9.txt: md5 = 0dced51786c27afec41ad906c1d39bad OK
2026/10/01 03:02:31 DEBUG : sub/file10.txt: md5 = e3512d5f1eb39879d20e79d69637688c OK
2026/10/01 03:02:31 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele': Partial index: listing 2 directories and walking 0 changed directories
2026/10/01 03:02:33 DEBUG : index.html: Unchanged skipping
2026/10/01 03:02:34 DEBUG : sub/index.html: md5 = d5d5fcf75156fe1a484575a8e69874b5 OK
2026/10/01 03:02:34 DEBUG : sub/index.html: size = 13331 OK
2026/10/01 03:02:34 INFO  : sub/index.html: Copied (replaced existing)
--- PASS: TestIndexChanged (65.89s)
=== RUN   TestIndexChangedDelete
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 03:02:51 DEBUG : file1.txt: md5 = 3e6d7b2a1e051af8cdb2051e66fc494d OK
2026/10/01 03:02:53 DEBUG : sub/file2.txt: md5 = 08fd7ce6ab32ed95765e3852a8c75223 OK
2026/10/01 03:02:55 DEBUG : sub/deep/file3.txt: md5 = 2efff3e7ea557747a5d51010509e17c4 OK
2026/10/01 03:02:57 DEBUG : index.html: md5 = a827b22c429abbf3e0a45a801de4b948 OK
2026/10/01 03:02:57 DEBUG : index.html: size = 12147 OK
2026/10/01 03:02:57 INFO  : index.html: Copied (new)
2026/10/01 03:02:58 DEBUG : sub/index.html: md5 = 4ce8cbbb4d99316c0fe61471db24fb96 OK
2026/10/01 03:02:58 DEBUG : sub/index.html: size = 12306 OK
2026/10/01 03:02:58 INFO  : sub/index.html: Copied (new)
2026/10/01 03:02:58 DEBUG : sub/deep/index.html: md5 = 0822cf088e3d3c96c2cbf64b9c684444 OK
2026/10/01 03:02:58 DEBUG : sub/deep/index.html: size = 12087 OK
2026/10/01 03:02:58 INFO  : sub/deep/index.html: Copied (new)
2026/10/01 03:02:58 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele': Partial index: listing 3 directories and walking 0 changed directories
2026/10/01 03:03:00 DEBUG : sub/index.html: Unchanged skipping
2026/10/01 03:03:00 DEBUG : index.html: Unchanged skipping
2026/10/01 03:03:00 DEBUG : sub/deep/index.html: md5 = b9513c1e6a522d6684a9e08405a9f73b OK
2026/10/01 03:03:00 DEBUG : sub/deep/index.html: size = 11829 OK
2026/10/01 03:03:00 INFO  : sub/deep/index.html: Copied (replaced existing)
--- PASS: TestIndexChangedDelete (16.59s)
=== RUN   TestIndexChangedFiles
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 03:03:07 DEBUG : file1.txt: md5 = b1c864996ae521e3f6fb7766775fbb61 OK
2026/10/01 03:03:09 DEBUG : sub/file2.txt: md5 = d954f0e8394c61cdfd38315b4385b14a OK
2026/10/01 03:03:12 DEBUG : sub/deep/file3.txt: md5 = 94b267cda9060f1f6e1834e55c58b714 OK
2026/10/01 03:03:14 DEBUG : - odd/file.txt: md5 = 6a64be933183b57d20122c01d95d0447 OK
2026/10/01 03:03:16 DEBUG : - odd/index.html: md5 = 08db2e0327654bcfccaa5a226a974426 OK
2026/10/01 03:03:16 DEBUG : - odd/index.html: size = 12058 OK
2026/10/01 03:03:16 INFO  : - odd/index.html: Copied (new)
2026/10/01 03:03:16 DEBUG : sub/deep/index.html: md5 = 8af27fa65609db8e5c25598aacef804e OK
2026/10/01 03:03:16 DEBUG : sub/deep/index.html: size = 12087 OK
2026/10/01 03:03:16 INFO  : sub/deep/index.html: Copied (new)
2026/10/01 03:03:16 DEBUG : index.html: md5 = 98e57edd77d72430b24d74a307dd1966 OK
2026/10/01 03:03:16 DEBUG : index.html: size = 12401 OK
2026/10/01 03:03:16 INFO  : index.html: Copied (new)
2026/10/01 03:03:16 DEBUG : sub/index.html: md5 = 39a071b511f199d5e36a360bfe0f6aa4 OK
2026/10/01 03:03:16 DEBUG : sub/index.html: size = 12306 OK
2026/10/01 03:03:16 INFO  : sub/index.html: Copied (new)
2026/10/01 03:03:17 DEBUG : - odd/file8.txt: md5 = 4c5367afc4f41a958762cc6d8825a095 OK
2026/10/01 03:03:19 DEBUG : sub/file9.txt: md5 = d0ca91f7cb50c25ac668cf9b0dc1733a OK
2026/10/01 03:03:19 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele': Partial index: listing 2 directories and walking 0 changed directories
2026/10/01 03:03:20 DEBUG : index.html: Unchanged skipping
2026/10/01 03:03:22 DEBUG : - odd/index.html: md5 = eb83173d46cee6788fb5c20548aef429 OK
2026/10/01 03:03:22 DEBUG : - odd/index.html: size = 12316 OK
2026/10/01 03:03:22 INFO  : - odd/index.html: Copied (replaced existing)
2026/10/01 03:03:23 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele': Partial index: listing 4 directories and walking 0 changed directories
2026/10/01 03:03:25 DEBUG : index.html: Unchanged skipping
2026/10/01 03:03:25 DEBUG : - odd/index.html: Unchanged skipping
2026/10/01 03:03:25 DEBUG : sub/deep/index.html: Unchanged skipping
2026/10/01 03:03:26 DEBUG : sub/index.html: md5 = 5944dc3113949acdb19223bd560a2d03 OK
2026/10/01 03:03:26 DEBUG : sub/index.html: size = 12565 OK
2026/10/01 03:03:26 INFO  : sub/index.html: Copied (replaced existing)
--- PASS: TestIndexChangedFiles (28.70s)
=== RUN   TestIndexChangedDirTime
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 03:03:36 DEBUG : file1.txt: md5 = d3885183bc3b0fe3a7b2cf8973ec1f00 OK
2026/10/01 03:03:38 DEBUG : sub/file2.txt: md5 = aed957a7a902936a36672ed2cf570806 OK
2026/10/01 03:03:40 DEBUG : sub/deep/file3.txt: md5 = 68f79c4158314ba802dc937570b54a5d OK
2026/10/01 03:03:42 DEBUG : other/notes.md: md5 = bab7f2777ee6c6febdba2b3a35da5624 OK
2026/10/01 03:03:45 DEBUG : sub/index.json: md5 = 687bb1aaed9d233ac0d05f51e5ad39ec OK
2026/10/01 03:03:45 DEBUG : sub/index.json: size = 259 OK
2026/10/01 03:03:45 INFO  : sub/index.json: Copied (new)
2026/10/01 03:03:45 DEBUG : index.json: md5 = cbb19d744f510a9918cc90f295788582 OK
2026/10/01 03:03:45 DEBUG : index.json: size = 379 OK
2026/10/01 03:03:45 INFO  : index.json: Copied (new)
2026/10/01 03:03:45 DEBUG : other/index.json: md5 = 325b079ad4c22fdcc0084fd4983695bd OK
2026/10/01 03:03:45 DEBUG : other/index.json: size = 144 OK
2026/10/01 03:03:45 INFO  : other/index.json: Copied (new)
2026/10/01 03:03:45 DEBUG : sub/deep/index.json: md5 = 9eb22bda8c782ea9637c9a00afc8f073 OK
2026/10/01 03:03:45 DEBUG : sub/deep/index.json: size = 139 OK
2026/10/01 03:03:45 INFO  : sub/deep/index.json: Copied (new)
2026/10/01 03:03:47 DEBUG : sub/deep/file4.txt: md5 = d51aace6039d0d73948616488781cf04 OK
2026/10/01 03:03:47 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele': Partial index: listing 3 directories and walking 0 changed directories
2026/10/01 03:03:49 DEBUG : sub/deep/index.json: md5 = 3d0d427c4d41e8b77fe3a8fba30149da OK
2026/10/01 03:03:49 DEBUG : sub/deep/index.json: size = 275 OK
2026/10/01 03:03:49 INFO  : sub/deep/index.json: Copied (replaced existing)
2026/10/01 03:03:49 DEBUG : sub/index.json: md5 = 98666dfafd694d3554b447b71a7be31b OK
2026/10/01 03:03:49 DEBUG : sub/index.json: size = 259 OK
2026/10/01 03:03:49 INFO  : sub/index.json: Copied (replaced existing)
2026/10/01 03:03:50 DEBUG : index.json: md5 = 37c3cfa87b32a286ea8da58c50678815 OK
2026/10/01 03:03:50 DEBUG : index.json: size = 379 OK
2026/10/01 03:03:50 INFO  : index.json: Copied (replaced existing)
--- PASS: TestIndexChangedDirTime (23.04s)
=== RUN   TestListDirSorted
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 03:03:59 DEBUG : a.txt: md5 = 991d17925bf92c24b9f6d83160fcc305 OK
2026/10/01 03:04:00 DEBUG : zend.txt: md5 = 6ab116f19828dd45bc81e54e0531c6f1 OK
2026/10/01 03:04:03 DEBUG : sub dir/hello world: md5 = c5063b8b186f9b6aadc5726590b95b43 OK
2026/10/01 03:04:04 DEBUG : sub dir/hello world2: md5 = c0388086f00cc54c03dddd547d0aa71f OK
2026/10/01 03:04:06 DEBUG : sub dir/ignore dir/.ignore: md5 = dc6939f8db58c687b95123fa3d6de3d9 OK
2026/10/01 03:04:08 DEBUG : sub dir/ignore dir/should be ignored: md5 = b3b7934645589f337fc2f15c165708a2 OK
2026/10/01 03:04:10 DEBUG : sub dir/sub sub dir/hello world3: md5 = 42c446d428d25410a417717d88bc94a6 OK
2026/10/01 03:04:11 DEBUG : a.txt: Excluded (Size Filter)
2026/10/01 03:04:11 DEBUG : a.txt: Excluded
2026/10/01 03:04:11 DEBUG : sub dir/hello world: Excluded (Size Filter)
2026/10/01 03:04:11 DEBUG : sub dir/hello world: Excluded
2026/10/01 03:04:11 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2026/10/01 03:04:11 DEBUG : sub dir/hello world2: Excluded
2026/10/01 03:04:12 DEBUG : sub dir/ignore dir: Excluded
2026/10/01 03:04:12 DEBUG : sub dir/hello world: Excluded (Size Filter)
2026/10/01 03:04:12 DEBUG : sub dir/hello world: Excluded
2026/10/01 03:04:12 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2026/10/01 03:04:12 DEBUG : sub dir/hello world2: Excluded
2026/10/01 03:04:12 DEBUG : sub dir/ignore dir: Excluded
--- PASS: TestListDirSorted (21.70s)
=== RUN   TestListDirSortedFn
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 03:04:21 DEBUG : a.txt: md5 = 66b972428c21c003ea91b0876c321bff OK
2026/10/01 03:04:22 DEBUG : zend.txt: md5 = 96e9ab057dcbbca4a7e3cddbc925fd06 OK
2026/10/01 03:04:24 DEBUG : sub dir/hello world: md5 = af024e2e4849e1caba2b16b84cbaaa14 OK
2026/10/01 03:04:26 DEBUG : sub dir/hello world2: md5 = 6af5e0ea98eba988d76e9d43cb5907e7 OK
2026/10/01 03:04:28 DEBUG : sub dir/ignore dir/.ignore: md5 = d842c411c357cceb37375497f680f6d8 OK
2026/10/01 03:04:30 DEBUG : sub dir/ignore dir/should be ignored: md5 = 58d3e8d8bcef85730556c8cabd96c2e9 OK
2026/10/01 03:04:32 DEBUG : sub dir/sub sub dir/hello world3: md5 = 8fa51f6c11e7f91a4d06326fda70d605 OK
2026/10/01 03:04:33 DEBUG : a.txt: Excluded (Size Filter)
2026/10/01 03:04:33 DEBUG : a.txt: Excluded
2026/10/01 03:04:34 DEBUG : sub dir/hello world: Excluded (Size Filter)
2026/10/01 03:04:34 DEBUG : sub dir/hello world: Excluded
2026/10/01 03:04:34 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2026/10/01 03:04:34 DEBUG : sub dir/hello world2: Excluded
2026/10/01 03:04:35 DEBUG : sub dir/ignore dir: Excluded
2026/10/01 03:04:35 DEBUG : sub dir/hello world: Excluded (Size Filter)
2026/10/01 03:04:35 DEBUG : sub dir/hello world: Excluded
2026/10/01 03:04:35 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2026/10/01 03:04:35 DEBUG : sub dir/hello world2: Excluded
2026/10/01 03:04:35 DEBUG : sub dir/ignore dir: Excluded
--- PASS: TestListDirSortedFn (22.28s)
=== RUN   TestListJSON
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 03:04:43 DEBUG : file1: md5 = 9f39a040a27ddd23bcd989448803e2f9 OK
2026/10/01 03:04:45 DEBUG : sub/file2: md5 = 45ea8d8d5b77058fba0376cef1084f80 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/01 03:04:48 ERROR : file1: Failed to read hash: hash type not supported
=== RUN   TestListJSON/Metadata
2026/10/01 03:04:48 DEBUG : eer8kka55qnghc34cq76ca668g: Fetching metadata
2026/10/01 03:04:48 DEBUG : 150fuo3cn4j1uenq6r4g4qk6t4: Fetching metadata
--- PASS: TestListJSON (9.22s)
    --- PASS: TestListJSON/Default (0.23s)
    --- PASS: TestListJSON/FilesOnly (0.23s)
    --- PASS: TestListJSON/DirsOnly (0.21s)
    --- PASS: TestListJSON/Recurse (0.45s)
    --- PASS: TestListJSON/SubDir (0.24s)
    --- PASS: TestListJSON/NoModTime (0.22s)
    --- PASS: TestListJSON/NoMimeType (0.23s)
    --- PASS: TestListJSON/ShowHash (0.23s)
    --- PASS: TestListJSON/HashTypes (0.22s)
    --- PASS: TestListJSON/Metadata (0.62s)
=== RUN   TestStatJSON
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 03:04:52 DEBUG : file1: md5 = 7272a53b9535e5cc434e02160d0bfd53 OK
2026/10/01 03:04:54 DEBUG : sub/file2: md5 = 07aaf51d199005b8033626fa86ce14d5 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/01 03:04:58 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-fuporus9dele/notfound"
2026/10/01 03:04:58 DEBUG : Config file has changed externally - reloading
2026/10/01 03:04:58 DEBUG : Creating backend with remote "TestDrive:crypt/pp62fi2pb9cpsk867ktg44cc4ecbae2lkllsqt2ojpuaom1e01q0/qe0i31qdkoejk60elps0ni5sqk"
--- PASS: TestStatJSON (11.59s)
    --- PASS: TestStatJSON/Root (0.23s)
    --- PASS: TestStatJSON/RootFilesOnly (0.00s)
    --- PASS: TestStatJSON/RootDirsOnly (0.24s)
    --- PASS: TestStatJSON/Dir (0.46s)
    --- PASS: TestStatJSON/DirWithTrailingSlash (0.24s)
    --- PASS: TestStatJSON/File (0.23s)
    --- PASS: TestStatJSON/NotFound (0.49s)
    --- PASS: TestStatJSON/DirFilesOnly (0.22s)
    --- PASS: TestStatJSON/FileFilesOnly (0.23s)
    --- PASS: TestStatJSON/NotFoundFilesOnly (0.27s)
    --- PASS: TestStatJSON/DirDirsOnly (0.27s)
    --- PASS: TestStatJSON/FileDirsOnly (0.28s)
    --- PASS: TestStatJSON/NotFoundDirsOnly (0.24s)
    --- PASS: TestStatJSON/RootNotFound (1.68s)
=== RUN   TestStatJSONMemory
2026/10/01 03:05:02 DEBUG : Creating backend with remote ":memory:"
2026/10/01 03:05:02 DEBUG : Config file has changed externally - reloading
2026/10/01 03:05:02 DEBUG : Memory root '': File to upload is small (5 bytes), uploading instead of streaming
2026/10/01 03:05:02 DEBUG : sub/file1: size = 5 OK
2026/10/01 03:05:02 DEBUG : sub/file1: md5 = 5d41402abc4b2a76b9719d911017c592 OK
2026/10/01 03:05:02 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-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 03:05:02 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele': Making directory
2026/10/01 03:05:03 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele': Making directory
--- PASS: TestMkdir (0.78s)
=== RUN   TestLsd
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 03:05:05 DEBUG : sub dir/hello world: md5 = f418b4abe288674b7d7e5437116c8617 OK
--- PASS: TestLsd (4.68s)
=== RUN   TestLs
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 03:05:09 DEBUG : potato2: md5 = 15755431adfe98829aa155ae003b39a5 OK
2026/10/01 03:05:11 DEBUG : empty space: md5 = 7f5cd149ec7c174d883787fd08ba9f0b OK
--- PASS: TestLs (5.00s)
=== RUN   TestLsWithFilesFrom
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 03:05:14 DEBUG : potato2: md5 = 6f221a3d0d5105e14c1236a93dedc544 OK
2026/10/01 03:05:16 DEBUG : empty space: md5 = e83732f653ad0956e63d7e02fe4b28ed OK
2026/10/01 03:05:16 DEBUG : empty space: Excluded (FilesFrom Filter)
2026/10/01 03:05:16 DEBUG : empty space: Excluded
--- PASS: TestLsWithFilesFrom (5.32s)
=== RUN   TestLsLong
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 03:05:20 DEBUG : potato2: md5 = 6d641c8cac02575a2fa5dddf4f465855 OK
2026/10/01 03:05:21 DEBUG : empty space: md5 = 3a14ee5f4baef7b60f5b1b17f41cb231 OK
--- PASS: TestLsLong (4.84s)
=== RUN   TestHashSums
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 03:05:24 DEBUG : potato2: md5 = be4a845cc8f260b0d25cb13b55c90bd0 OK
2026/10/01 03:05:26 DEBUG : empty space: md5 = ed4d7c4ad7596fd9c5822619c34f848b OK
--- PASS: TestHashSums (4.32s)
=== RUN   TestHashSumsWithErrors
2026/10/01 03:05:27 DEBUG : Creating backend with remote ":memory:"
2026/10/01 03:05:27 ERROR : file1: hash unsupported: hash type not supported
2026/10/01 03:05:27 ERROR : sub/file1: hash unsupported: hash type not supported
2026/10/01 03:05:27 ERROR : sub/file1: hash unsupported: hash type not supported
--- PASS: TestHashSumsWithErrors (0.00s)
=== RUN   TestHashStream
2026/10/01 03:05:27 DEBUG : Creating md5 hash of 0 bytes read from input stream
2026/10/01 03:05:27 DEBUG : Creating md5 hash of 0 bytes read from input stream
2026/10/01 03:05:27 DEBUG : Creating sha1 hash of 0 bytes read from input stream
2026/10/01 03:05:27 DEBUG : Creating sha1 hash of 0 bytes read from input stream
2026/10/01 03:05:27 DEBUG : Creating md5 hash of 12 bytes read from input stream
2026/10/01 03:05:27 DEBUG : Creating md5 hash of 12 bytes read from input stream
2026/10/01 03:05:27 DEBUG : Creating sha1 hash of 12 bytes read from input stream
2026/10/01 03:05:27 DEBUG : Creating sha1 hash of 12 bytes read from input stream
--- PASS: TestHashStream (0.00s)
=== RUN   TestSuffixName
--- PASS: TestSuffixName (0.00s)
=== RUN   TestCount
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 03:05:29 DEBUG : potato2: md5 = d93dc4c19956620bef82abe3d41f861a OK
2026/10/01 03:05:30 DEBUG : empty space: md5 = 269842c09fbee0a41998c2ea935b5a2b OK
2026/10/01 03:05:33 DEBUG : sub dir/potato3: md5 = 4c6f57e388bfdc74447a8646030998f4 OK
--- PASS: TestCount (9.25s)
=== RUN   TestDelete
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 03:05:38 DEBUG : small: md5 = e37b394e81ca75cfa38316ad31711f8b OK
2026/10/01 03:05:40 DEBUG : medium: md5 = 9837446654bba41be7a200a11811a059 OK
2026/10/01 03:05:41 DEBUG : large: md5 = 702ff8ca6cf523b642e15cc223b87a1f OK
2026/10/01 03:05:41 DEBUG : Waiting for deletions to finish
2026/10/01 03:05:42 DEBUG : large: Excluded (Size Filter)
2026/10/01 03:05:42 INFO  : small: Deleted
2026/10/01 03:05:42 INFO  : medium: Deleted
--- PASS: TestDelete (6.81s)
=== RUN   TestDeleteFatalError
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 03:05:45 DEBUG : file0: md5 = 1945dfe16aca6573d3dee6dccfc8a60a OK
2026/10/01 03:05:46 DEBUG : file1: md5 = 9f0a3102d4ac212467a58e112c222426 OK
2026/10/01 03:05:48 DEBUG : file2: md5 = a7542d35de2d97c742a654ebca6fa9a8 OK
2026/10/01 03:05:49 DEBUG : file3: md5 = 01b8eb6e02e0d66883e6dd90408fdf56 OK
2026/10/01 03:05:51 DEBUG : file4: md5 = 4878db96d7da3146b60b6d53e797b7f1 OK
2026/10/01 03:05:52 DEBUG : file5: md5 = 79db92eca78bcb4e90ddedd0aad9379f OK
2026/10/01 03:05:54 DEBUG : file6: md5 = cc05d37cfa117c823de78e112c8dd794 OK
2026/10/01 03:05:55 DEBUG : file7: md5 = 0337bb9b121f0c051320611729539162 OK
2026/10/01 03:05:56 DEBUG : file8: md5 = e90d881f9912fd10ac11b10e3cbc1018 OK
2026/10/01 03:05:58 DEBUG : file9: md5 = 1c40765fb0c3e382ecbb447ccb5c1578 OK
2026/10/01 03:05:59 DEBUG : file10: md5 = 7a8292bcd7ece719e00ba5709078f562 OK
2026/10/01 03:06:01 DEBUG : file11: md5 = bbf9a19cbe8302db0575ef9b88475f2e OK
2026/10/01 03:06:02 DEBUG : file12: md5 = 82f78b6843e621486d772dc2460b0019 OK
2026/10/01 03:06:04 DEBUG : file13: md5 = 464faac3d0f044ee34745f1009ea451f OK
2026/10/01 03:06:05 DEBUG : file14: md5 = 7011490ae960d150b6322d2f19ae2fd4 OK
2026/10/01 03:06:07 DEBUG : file15: md5 = b0a87892b90dc154901de432e4fb1114 OK
2026/10/01 03:06:08 DEBUG : file16: md5 = 556f142169011af1db24c4335fa8d284 OK
2026/10/01 03:06:10 DEBUG : file17: md5 = ff07467c3b030bc4f9b457d5ee201dab OK
2026/10/01 03:06:11 DEBUG : file18: md5 = 3681e4e7556535dc319ad94ae1723ca5 OK
2026/10/01 03:06:13 DEBUG : file19: md5 = e2fe6ec2c2180fb3fa9cacc90d80cd4f OK
2026/10/01 03:06:13 DEBUG : Waiting for deletions to finish
2026/10/01 03:06:13 ERROR : file6: Got fatal error on delete: --max-delete threshold reached
2026/10/01 03:06:13 ERROR : file1: Got fatal error on delete: --max-delete threshold reached
2026/10/01 03:06:13 ERROR : file4: Got fatal error on delete: --max-delete threshold reached
2026/10/01 03:06:13 ERROR : file14: Got fatal error on delete: --max-delete threshold reached
2026/10/01 03:06:13 ERROR : file8: Got fatal error on delete: --max-delete threshold reached
2026/10/01 03:06:13 ERROR : file19: Got fatal error on delete: --max-delete threshold reached
2026/10/01 03:06:13 ERROR : file10: Got fatal error on delete: --max-delete threshold reached
2026/10/01 03:06:13 ERROR : file7: Got fatal error on delete: --max-delete threshold reached
2026/10/01 03:06:13 ERROR : file5: Got fatal error on delete: --max-delete threshold reached
2026/10/01 03:06:13 ERROR : file17: Got fatal error on delete: --max-delete threshold reached
2026/10/01 03:06:13 ERROR : file11: Got fatal error on delete: --max-delete threshold reached
2026/10/01 03:06:13 ERROR : file12: Got fatal error on delete: --max-delete threshold reached
2026/10/01 03:06:13 ERROR : file13: Got fatal error on delete: --max-delete threshold reached
2026/10/01 03:06:13 ERROR : file9: Got fatal error on delete: --max-delete threshold reached
2026/10/01 03:06:13 ERROR : file16: Got fatal error on delete: --max-delete threshold reached
2026/10/01 03:06:13 ERROR : file0: Got fatal error on delete: --max-delete threshold reached
2026/10/01 03:06:13 ERROR : file2: Got fatal error on delete: --max-delete threshold reached
2026/10/01 03:06:13 ERROR : file15: Got fatal error on delete: --max-delete threshold reached
2026/10/01 03:06:13 ERROR : file3: Got fatal error on delete: --max-delete threshold reached
2026/10/01 03:06:13 ERROR : file18: Got fatal error on delete: --max-delete threshold reached
--- PASS: TestDeleteFatalError (39.08s)
=== RUN   TestMaxDelete
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 03:06:24 DEBUG : small: md5 = ee2c1ff7e68212bf31c7b9770f8d9ef0 OK
2026/10/01 03:06:25 DEBUG : medium: md5 = 02c8713fd1dd9eaf0576493ab2bbe5b6 OK
2026/10/01 03:06:27 DEBUG : large: md5 = a841851c2e2312c3b65024b892ab71c5 OK
2026/10/01 03:06:27 DEBUG : Waiting for deletions to finish
2026/10/01 03:06:28 ERROR : medium: Got fatal error on delete: --max-delete threshold reached
2026/10/01 03:06:28 INFO  : large: Deleted
2026/10/01 03:06:28 INFO  : small: Deleted
--- PASS: TestMaxDelete (7.25s)
=== RUN   TestMaxDeleteSizeLargeFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 03:06:31 DEBUG : small: md5 = f926b7c06d50c3311997dcca6a1db952 OK
2026/10/01 03:06:32 DEBUG : medium: md5 = 2d3feab8e2679a3c89a018ff7273f642 OK
2026/10/01 03:06:34 DEBUG : large: md5 = 8e6b6259b5c36a64543d3f9aba551709 OK
2026/10/01 03:06:34 DEBUG : Waiting for deletions to finish
2026/10/01 03:06:34 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached
2026/10/01 03:06:35 INFO  : small: Deleted
2026/10/01 03:06:35 INFO  : medium: Deleted
--- PASS: TestMaxDeleteSizeLargeFile (6.74s)
=== RUN   TestMaxDeleteSize
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 03:06:38 DEBUG : small: md5 = c58d38398fd110f33b96dab6018d55f8 OK
2026/10/01 03:06:39 DEBUG : medium: md5 = bf53bf80da3eaa1199cfd18c4b9a3d2f OK
2026/10/01 03:06:41 DEBUG : large: md5 = 63aad65d55eebc8115a08c07ef254aa3 OK
2026/10/01 03:06:41 DEBUG : Waiting for deletions to finish
2026/10/01 03:06:41 ERROR : medium: Got fatal error on delete: --max-delete-size threshold reached
2026/10/01 03:06:42 INFO  : large: Deleted
2026/10/01 03:06:42 INFO  : small: Deleted
--- PASS: TestMaxDeleteSize (6.86s)
=== RUN   TestReadFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 03:06:45 DEBUG : ReadFile: md5 = 447c8767d75bec5bfde780ec8cd6f0ef OK
--- PASS: TestReadFile (4.02s)
=== RUN   TestRetry
2026/10/01 03:06:47 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 1/5
2026/10/01 03:06:47 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 2/5
2026/10/01 03:06:47 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms
2026/10/01 03:06:47 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms
2026/10/01 03:06:47 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms
2026/10/01 03:06:47 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/01 03:06:47 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-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 03:06:49 DEBUG : file1: md5 = 0c16b1208670ce2f71a61ab364d558b1 OK
2026/10/01 03:06:50 DEBUG : file2: md5 = b765bb367399347421549752fe3621fb OK
--- PASS: TestCat (13.54s)
=== RUN   TestPurge
2026/10/01 03:07:01 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-cafopev4kebu"
2026/10/01 03:07:01 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/01 03:07:01 DEBUG : Creating backend with remote "TestDrive:crypt/eot5c550gsjvuejgvqaq6vh283ofi1d6ec3aqe6rh5c98044gb40"
2026/10/01 03:07:02 DEBUG : Creating backend with remote "/tmp/rclone4002687333"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cafopev4kebu'", Local "Local file system at /tmp/rclone4002687333", Modify Window "1ms"
2026/10/01 03:07:06 DEBUG : A1/B1/C1/one: md5 = 6f5df7ec09a66c87edab925ca6a9b49e OK
2026/10/01 03:07:06 INFO  : A2: Making directory
2026/10/01 03:07:07 INFO  : A1/B2: Making directory
2026/10/01 03:07:08 INFO  : A1/B2/C2: Making directory
2026/10/01 03:07:08 INFO  : A1/B1/C3: Making directory
2026/10/01 03:07:09 INFO  : A3: Making directory
2026/10/01 03:07:10 INFO  : A3/B3: Making directory
2026/10/01 03:07:10 INFO  : A3/B3/C4: Making directory
2026/10/01 03:07:13 DEBUG : A1/two: md5 = d2437734979dda3fceedc2d246d8f024 OK
2026/10/01 03:07:16 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cafopev4kebu': Purge remote
2026/10/01 03:07:17 NOTICE: purge failed: directory not found
--- PASS: TestPurge (15.69s)
=== RUN   TestRmdirsNoLeaveRoot
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 03:07:20 DEBUG : A1/B1/C1/one: md5 = d922088ee4bfa7f5b3f869235fc31e4f OK
2026/10/01 03:07:20 INFO  : A2: Making directory
2026/10/01 03:07:21 INFO  : A1/B2: Making directory
2026/10/01 03:07:22 INFO  : A1/B2/C2: Making directory
2026/10/01 03:07:22 INFO  : A1/B1/C3: Making directory
2026/10/01 03:07:23 INFO  : A3: Making directory
2026/10/01 03:07:24 INFO  : A3/B3: Making directory
2026/10/01 03:07:24 INFO  : A3/B3/C4: Making directory
2026/10/01 03:07:27 DEBUG : A1/two: md5 = 81071d729d5f2a934d4f7540ecf4fdb6 OK
2026/10/01 03:07:28 DEBUG : removing 1 level 3 directories
2026/10/01 03:07:28 INFO  : A3/B3/C4: Removing directory
2026/10/01 03:07:32 DEBUG : removing 2 level 3 directories
2026/10/01 03:07:32 INFO  : A1/B2/C2: Removing directory
2026/10/01 03:07:32 INFO  : A1/B1/C3: Removing directory
2026/10/01 03:07:32 DEBUG : removing 2 level 2 directories
2026/10/01 03:07:32 INFO  : A3/B3: Removing directory
2026/10/01 03:07:32 INFO  : A1/B2: Removing directory
2026/10/01 03:07:33 DEBUG : removing 2 level 1 directories
2026/10/01 03:07:33 INFO  : A3: Removing directory
2026/10/01 03:07:33 INFO  : A2: Removing directory
2026/10/01 03:07:38 DEBUG : removing 1 level 3 directories
2026/10/01 03:07:38 INFO  : A1/B1/C1: Removing directory
2026/10/01 03:07:38 DEBUG : removing 1 level 2 directories
2026/10/01 03:07:38 INFO  : A1/B1: Removing directory
2026/10/01 03:07:39 DEBUG : removing 1 level 1 directories
2026/10/01 03:07:39 INFO  : A1: Removing directory
2026/10/01 03:07:41 DEBUG : removing 1 level 0 directories
2026/10/01 03:07:41 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele': Removing directory
--- PASS: TestRmdirsNoLeaveRoot (26.90s)
=== RUN   TestRmdirsLeaveRoot
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 03:07:44 INFO  : A1: Making directory
2026/10/01 03:07:45 INFO  : A1/B1: Making directory
2026/10/01 03:07:46 INFO  : A1/B1/C1: Making directory
2026/10/01 03:07:48 DEBUG : removing 1 level 3 directories
2026/10/01 03:07:48 INFO  : A1/B1/C1: Removing directory
2026/10/01 03:07:49 DEBUG : removing 1 level 2 directories
2026/10/01 03:07:49 INFO  : A1/B1: Removing directory
--- PASS: TestRmdirsLeaveRoot (7.97s)
=== RUN   TestRmdirsWithFilter
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 03:07:51 INFO  : A1: Making directory
2026/10/01 03:07:52 INFO  : A1/B1: Making directory
2026/10/01 03:07:53 INFO  : A1/B1/C1: Making directory
2026/10/01 03:07:55 DEBUG : removing 1 level 3 directories
2026/10/01 03:07:55 INFO  : A1/B1/C1: Removing directory
2026/10/01 03:07:56 DEBUG : removing 1 level 2 directories
2026/10/01 03:07:56 INFO  : A1/B1: Removing directory
--- PASS: TestRmdirsWithFilter (7.09s)
=== RUN   TestCopyURL
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 03:08:00 DEBUG : file1: md5 = 82401271b07d88dbbe0551b3dad6a74c OK
2026/10/01 03:08:00 DEBUG : file1: size = 14 OK
2026/10/01 03:08:01 DEBUG : filename.txt: File name found in url
2026/10/01 03:08:02 DEBUG : filename.txt: md5 = 00bb8adc74c3e3d3e441d7fea6348b55 OK
2026/10/01 03:08:02 DEBUG : filename.txt: size = 14 OK
2026/10/01 03:08:02 DEBUG : headerfilename.txt: filename found in Content-Disposition header.
2026/10/01 03:08:04 DEBUG : headerfilename.txt: md5 = 332e1a2daa50f9dbd47efc57ec4a1e3a OK
2026/10/01 03:08:04 DEBUG : headerfilename.txt: size = 14 OK
2026/10/01 03:08:05 DEBUG : file2: md5 = 72d23e3a07eabdf9b8fd4031ca1d9124 OK
2026/10/01 03:08:05 DEBUG : file2: size = 14 OK
--- PASS: TestCopyURL (9.09s)
=== RUN   TestCopyURLDownloadHeaders
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 03:08:09 DEBUG : file1: md5 = 60d185debafc2e761fa0cb30ffaf41a4 OK
2026/10/01 03:08:09 DEBUG : file1: size = 14 OK
--- PASS: TestCopyURLDownloadHeaders (2.34s)
=== RUN   TestCopyURLToWriter
--- PASS: TestCopyURLToWriter (0.00s)
=== RUN   TestMoveFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 03:08:10 DEBUG : file1: Need to transfer - File not found at Destination
2026/10/01 03:08:12 DEBUG : sub/file2: md5 = bb1b2d255091d2372d1f7f0d0acac7f3 OK
2026/10/01 03:08:12 DEBUG : sub/file2: size = 14 OK
2026/10/01 03:08:12 INFO  : file1: Copied (new) to: sub/file2
2026/10/01 03:08:12 INFO  : file1: Deleted
2026/10/01 03:08:13 DEBUG : sub/file2: size = 14 OK
2026/10/01 03:08:13 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2026/10/01 03:08:13 DEBUG : file1: Unchanged skipping
2026/10/01 03:08:13 INFO  : file1: Deleted
2026/10/01 03:08:13 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele': don't need to copy/move sub/file2, it is already at target location
--- PASS: TestMoveFile (5.92s)
=== RUN   TestMoveFileWithIgnoreExisting
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 03:08:16 DEBUG : file1: Need to transfer - File not found at Destination
2026/10/01 03:08:18 DEBUG : file1: md5 = 7ca0dfcbbee68e3d1cee248c96feb1af OK
2026/10/01 03:08:18 DEBUG : file1: size = 14 OK
2026/10/01 03:08:18 INFO  : file1: Copied (new)
2026/10/01 03:08:18 INFO  : file1: Deleted
2026/10/01 03:08:18 DEBUG : file1: Destination exists, skipping
2026/10/01 03:08:18 DEBUG : file1: Not removing source file as destination file exists and --ignore-existing is set
--- PASS: TestMoveFileWithIgnoreExisting (4.41s)
=== RUN   TestMoveFileImmutable
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 03:08:22 DEBUG : existing: md5 = 2dd304a613d02c1e79128af90689685f OK
2026/10/01 03:08:22 DEBUG : existing: size = 8 (Local file system at /tmp/rclone2683199630)
2026/10/01 03:08:22 DEBUG : existing: size = 6 (Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele')
2026/10/01 03:08:22 DEBUG : existing: Sizes differ
2026/10/01 03:08:22 ERROR : existing: Source and destination exist but do not match: immutable file modified
--- PASS: TestMoveFileImmutable (2.97s)
=== RUN   TestCaseInsensitiveMoveFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
--- PASS: TestCaseInsensitiveMoveFile (0.44s)
=== RUN   TestCaseInsensitiveMoveFileDryRun
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
--- PASS: TestCaseInsensitiveMoveFileDryRun (0.45s)
=== RUN   TestMoveFileBackupDir
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 03:08:27 DEBUG : dst/file1: md5 = 53af5f7c81c172abe7766e103e1d3b1b OK
2026/10/01 03:08:27 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-fuporus9dele/backup"
2026/10/01 03:08:27 DEBUG : Creating backend with remote "TestDrive:crypt/pp62fi2pb9cpsk867ktg44cc4ecbae2lkllsqt2ojpuaom1e01q0/1nrff024r7pq65ecp72fc28jb0"
2026/10/01 03:08:29 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone2683199630)
2026/10/01 03:08:29 DEBUG : dst/file1: size = 18 (Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele')
2026/10/01 03:08:29 DEBUG : dst/file1: Sizes differ
2026/10/01 03:08:31 INFO  : dst/file1: Moved (server-side)
2026/10/01 03:08:32 DEBUG : dst/file1: md5 = b1d2f0ef76a1f43f8e93f428a1817d46 OK
2026/10/01 03:08:32 DEBUG : dst/file1: size = 14 OK
2026/10/01 03:08:32 INFO  : dst/file1: Copied (new)
2026/10/01 03:08:32 INFO  : dst/file1: Deleted
--- PASS: TestMoveFileBackupDir (12.98s)
=== 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-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 03:08:39 DEBUG : A/file0: md5 = 3c8cf2e0c719b631ab3d0bd3e944feff OK
2026/10/01 03:08:41 DEBUG : A/file1: md5 = ea8b376d06081c99f53fd847131ff3e8 OK
2026/10/01 03:08:42 DEBUG : A/file2: md5 = 6125a66ed260f1f24c4c4cae46bfac8a OK
2026/10/01 03:08:44 DEBUG : A/file3: md5 = ecbe86ac47b1ba281e15324eca25109e OK
2026/10/01 03:08:45 DEBUG : A/file4: md5 = c413f64fa716baf43f7ddb6ac86d6f2f OK
2026/10/01 03:08:46 DEBUG : A/file5: md5 = 733b0c52bfc9922cd71b077cee2bf092 OK
2026/10/01 03:08:48 DEBUG : A/file6: md5 = b5513ea8e5a1f5211616453f8b0f828d OK
2026/10/01 03:08:50 DEBUG : A/file7: md5 = caebbcc5939e3829556dd3ad2e87357c OK
2026/10/01 03:08:51 DEBUG : A/file8: md5 = b0917ea274ca94a1b33056cb36acc5f4 OK
2026/10/01 03:08:53 DEBUG : A/file9: md5 = 116ac8cc4bae17e93132bf1537ddf238 OK
2026/10/01 03:08:55 DEBUG : A/file10: md5 = 41c94927b72f8fec9b21d5d8252164ce OK
2026/10/01 03:08:56 DEBUG : A/file11: md5 = 474321b5755bf8d34fb23ded63c8be44 OK
2026/10/01 03:08:58 DEBUG : A/file12: md5 = 2da2dc2d5bfd4b48ad21c2dff0a7a698 OK
2026/10/01 03:08:59 DEBUG : A/file13: md5 = d425537694f8082d0eaca9fe1f9cdc51 OK
2026/10/01 03:09:00 DEBUG : A/file14: md5 = c634f2d61c2cf882bc9c747693d9c7e8 OK
2026/10/01 03:09:02 DEBUG : A/file15: md5 = 6113a39a7f1c641453f0d607d59341de OK
2026/10/01 03:09:03 DEBUG : A/file16: md5 = 4b5d8efdd664e21c4c22db0e26260cb9 OK
2026/10/01 03:09:05 DEBUG : A/file17: md5 = cbac96ce74dd7d2f8a6f65a334d70b06 OK
2026/10/01 03:09:06 DEBUG : A/file18: md5 = 7f901365cb5a235c760933bb59281fa2 OK
2026/10/01 03:09:08 DEBUG : A/file19: md5 = 7c4c796122763cec9b3d169016e52d97 OK
    operations_test.go:1490: Skipping test on non local remote
--- SKIP: TestDirMoveMoveError (41.07s)
=== RUN   TestDirMoveContext
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 03:09:20 DEBUG : A/one: md5 = 7667fc68c6fec6573a9f12ae05574720 OK
2026/10/01 03:09:22 DEBUG : A/two: md5 = 9ae2ce9ec2fbc4f162727ea65f1e5de4 OK
    operations_test.go:1490: Skipping test on non local remote
--- SKIP: TestDirMoveContext (5.95s)
=== RUN   TestDirMove
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 03:09:26 DEBUG : A1/one: md5 = 962b30e4e385459f776f31f1c1f9020d OK
2026/10/01 03:09:28 DEBUG : A1/two: md5 = b9ce2d49b8b92099aded30422cff1680 OK
2026/10/01 03:09:30 DEBUG : A1/B1/three: md5 = e204f610617a7188ca3262c27cace0f7 OK
2026/10/01 03:09:32 DEBUG : A1/B1/C1/four: md5 = 0212fb6cea37d7948c1eddef6d494e42 OK
2026/10/01 03:09:34 DEBUG : A1/B1/C2/five: md5 = fce4e27efe44737d013e9c1479457a1c OK
2026/10/01 03:09:34 INFO  : A1/B2: Making directory
2026/10/01 03:09:35 INFO  : A1/B1/C3: Making directory
2026/10/01 03:09:44 INFO  : A2/two: Moved (server-side) to: A3/two
2026/10/01 03:09:44 INFO  : A2/B1/C2/five: Moved (server-side) to: A3/B1/C2/five
2026/10/01 03:09:44 INFO  : A2/B1/C1/four: Moved (server-side) to: A3/B1/C1/four
2026/10/01 03:09:44 INFO  : A2/one: Moved (server-side) to: A3/one
2026/10/01 03:09:44 INFO  : A2/B1/three: Moved (server-side) to: A3/B1/three
2026/10/01 03:09:50 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele': Can't DirMove - falling back to file moves: can't move directory - incompatible remotes
2026/10/01 03:09:55 INFO  : A3/two: Moved (server-side) to: A4/two
2026/10/01 03:09:55 INFO  : A3/B1/C2/five: Moved (server-side) to: A4/B1/C2/five
2026/10/01 03:09:56 INFO  : A3/B1/C1/four: Moved (server-side) to: A4/B1/C1/four
2026/10/01 03:09:56 INFO  : A3/one: Moved (server-side) to: A4/one
2026/10/01 03:09:56 INFO  : A3/B1/three: Moved (server-side) to: A4/B1/three
--- PASS: TestDirMove (44.29s)
=== RUN   TestGetFsInfo
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
--- PASS: TestGetFsInfo (0.44s)
=== RUN   TestRcat
=== RUN   TestRcat/withChecksum=false,ignoreChecksum=false
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 03:10:09 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele': File to upload is small (34 bytes), uploading instead of streaming
2026/10/01 03:10:11 DEBUG : no_checksum_small_file_from_pipe: md5 = b2000fe50607d9fa2543be9094492431 OK
2026/10/01 03:10:11 DEBUG : no_checksum_small_file_from_pipe: size = 34 OK
2026/10/01 03:10:11 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only
2026/10/01 03:10:11 DEBUG : no_checksum_small_file_from_pipe: Size of src and dst objects identical
2026/10/01 03:10:12 DEBUG : qc9k4uauqa9ls6a2r6lo8jf4b1m7r0510pkurois1cc70egbs4h0: Sending chunk 0 length 102465
2026/10/01 03:10:13 DEBUG : no_checksum_big_file_from_pipe: md5 = 12ba4a24f135e96e2113c5814b928674 OK
2026/10/01 03:10:13 DEBUG : no_checksum_big_file_from_pipe: size = 102401 OK
2026/10/01 03:10:13 DEBUG : no_checksum_big_file_from_pipe: Size of src and dst objects identical
=== RUN   TestRcat/withChecksum=true,ignoreChecksum=false
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 03:10:14 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele': File to upload is small (34 bytes), uploading instead of streaming
2026/10/01 03:10:16 DEBUG : with_checksum_small_file_from_pipe: md5 = a30fcf406e5523c6bd85a1294c372def OK
2026/10/01 03:10:16 DEBUG : with_checksum_small_file_from_pipe: size = 34 OK
2026/10/01 03:10:16 DEBUG : with_checksum_small_file_from_pipe: Size of src and dst objects identical
2026/10/01 03:10:17 DEBUG : e74jo8a7lr1aimg2u78fct67gnuetc1qujiofaci0c8fnq7rjt1d4i296pcklqhgdihs03etvgdi4: Sending chunk 0 length 102465
2026/10/01 03:10:18 DEBUG : with_checksum_big_file_from_pipe: md5 = b5cf401bb5dbff785a23d96b6c6e6c1e OK
2026/10/01 03:10:18 DEBUG : with_checksum_big_file_from_pipe: size = 102401 OK
2026/10/01 03:10:18 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-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 03:10:20 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele': File to upload is small (34 bytes), uploading instead of streaming
2026/10/01 03:10:21 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK
2026/10/01 03:10:21 DEBUG : ignore_checksum_small_file_from_pipe: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2026/10/01 03:10:22 DEBUG : 1963rn6olajfbjn4nbqi9nacu7bk879vpf0d2bg8r0ndcqqsfntob0urt056p0qujf574rqnb5f62: Sending chunk 0 length 102465
2026/10/01 03:10:23 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK
2026/10/01 03:10:23 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-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 03:10:25 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele': File to upload is small (34 bytes), uploading instead of streaming
2026/10/01 03:10:26 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK
2026/10/01 03:10:26 DEBUG : ignore_checksum_small_file_from_pipe: Size of src and dst objects identical
2026/10/01 03:10:27 DEBUG : 1963rn6olajfbjn4nbqi9nacu7bk879vpf0d2bg8r0ndcqqsfntob0urt056p0qujf574rqnb5f62: Sending chunk 0 length 102465
2026/10/01 03:10:28 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK
2026/10/01 03:10:28 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical
--- PASS: TestRcat (20.96s)
    --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (5.39s)
    --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (5.45s)
    --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (4.81s)
    --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (5.32s)
=== RUN   TestRcatMetadata
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
=== RUN   TestRcatMetadata/Normal
2026/10/01 03:10:30 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele': File to upload is small (48 bytes), uploading instead of streaming
2026/10/01 03:10:31 DEBUG : rcat_metadata: md5 = f51134d6ce998f053f3f7a14503766c5 OK
2026/10/01 03:10:31 DEBUG : rcat_metadata: size = 48 OK
2026/10/01 03:10:31 DEBUG : rcat_metadata: Size of src and dst objects identical
=== RUN   TestRcatMetadata/ViaDisk
2026/10/01 03:10:33 DEBUG : rijssoobhbn85c5p1imhg55m6frank1nlplla6hkdjlqmqb2471g: Sending chunk 0 length 111
2026/10/01 03:10:34 DEBUG : rcat_metadata_uploadcutoff0: md5 = 6420ea74934a2e011c2e54bcb8aba0e3 OK
2026/10/01 03:10:34 DEBUG : rcat_metadata_uploadcutoff0: size = 63 OK
2026/10/01 03:10:34 DEBUG : rcat_metadata_uploadcutoff0: Size of src and dst objects identical
--- PASS: TestRcatMetadata (5.92s)
    --- PASS: TestRcatMetadata/Normal (2.61s)
    --- PASS: TestRcatMetadata/ViaDisk (2.87s)
=== RUN   TestRcatSize
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 03:10:38 DEBUG : potato1: md5 = 4718ab16f6836bb5a4bcd3bd95a6f0e6 OK
2026/10/01 03:10:38 DEBUG : potato1: size = 60 OK
2026/10/01 03:10:38 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele': File to upload is small (60 bytes), uploading instead of streaming
2026/10/01 03:10:39 DEBUG : potato2: md5 = 8f00fb74dee7627a1a5f3d6ddbb6ce72 OK
2026/10/01 03:10:39 DEBUG : potato2: size = 60 OK
2026/10/01 03:10:39 DEBUG : potato2: Size of src and dst objects identical
--- PASS: TestRcatSize (4.95s)
=== RUN   TestRcatSizeShortEOF
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 03:10:42 DEBUG : potato1: md5 = 67588fc95a3be4ae3dc10a010226bf64 OK
2026/10/01 03:10:42 DEBUG : potato1: size = 120 (Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele')
2026/10/01 03:10:42 DEBUG : potato1: size = 60 (Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele')
2026/10/01 03:10:42 ERROR : potato1: corrupted on transfer: sizes differ src 120 vs dst(Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele') 60
2026/10/01 03:10:42 INFO  : potato1: Removing failed copy
--- PASS: TestRcatSizeShortEOF (2.32s)
=== RUN   TestRcatSizeMetadata
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 03:10:44 DEBUG : potato1: md5 = 9cee9359603fed117056e56edc73dce6 OK
2026/10/01 03:10:44 DEBUG : potato1: size = 60 OK
2026/10/01 03:10:44 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele': File to upload is small (60 bytes), uploading instead of streaming
2026/10/01 03:10:46 DEBUG : potato2: md5 = 9e656293e0c15f5f0797d18ce941db79 OK
2026/10/01 03:10:46 DEBUG : potato2: size = 60 OK
2026/10/01 03:10:46 DEBUG : potato2: Size of src and dst objects identical
--- PASS: TestRcatSizeMetadata (4.86s)
=== RUN   TestRcatSizeUploadHeaders
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 03:10:49 DEBUG : potato1: md5 = 4e68a4a4de2663562f2653a46f5ea5fb OK
2026/10/01 03:10:49 DEBUG : potato1: size = 60 OK
--- PASS: TestRcatSizeUploadHeaders (2.43s)
=== RUN   TestRcatSizeChecksum
=== RUN   TestRcatSizeChecksum/Corrupted
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
    operations_test.go:1975: Skipping as destination doesn't support hashes
=== RUN   TestRcatSizeChecksum/SizeDiffers
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 03:10:52 DEBUG : potato4: md5 = e4d56ee6ae12f09da60fe8e6294c7b5c OK
2026/10/01 03:10:52 DEBUG : potato4: size = 60 (Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele')
2026/10/01 03:10:52 DEBUG : potato4: size = 59 (Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele')
2026/10/01 03:10:52 ERROR : potato4: corrupted on transfer: sizes differ src 60 vs dst(Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele') 59
2026/10/01 03:10:52 INFO  : potato4: Removing failed copy
=== RUN   TestRcatSizeChecksum/IgnoreChecksum
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 03:10:55 DEBUG : potato2: size = 60 OK
=== RUN   TestRcatSizeChecksum/NoHashes
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 03:10:57 DEBUG : potato3: md5 = b1314c79af20aa7ff771b39a57370a5f OK
2026/10/01 03:10:57 DEBUG : potato3: size = 60 OK
--- PASS: TestRcatSizeChecksum (7.44s)
    --- SKIP: TestRcatSizeChecksum/Corrupted (0.45s)
    --- PASS: TestRcatSizeChecksum/SizeDiffers (2.53s)
    --- PASS: TestRcatSizeChecksum/IgnoreChecksum (2.16s)
    --- PASS: TestRcatSizeChecksum/NoHashes (2.29s)
=== RUN   TestTouchDir
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 03:10:59 DEBUG : potato2: md5 = 104bf711c46539147d8061e26bc1cfe4 OK
2026/10/01 03:11:01 DEBUG : empty space: md5 = 6af21b8f890da83cfdf8f70597700ad9 OK
2026/10/01 03:11:03 DEBUG : sub dir/potato3: md5 = 5ee98977a6a302746e3c5072bbcfe3f2 OK
2026/10/01 03:11:04 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele': Touching "sub dir/potato3"
2026/10/01 03:11:04 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele': Touching "empty space"
2026/10/01 03:11:04 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele': Touching "potato2"
--- PASS: TestTouchDir (9.83s)
=== RUN   TestMkdirMetadata
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 03:11:08 DEBUG : dir with metadata: Making directory with metadata
2026/10/01 03:11:08 INFO  : dir with metadata: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z)
--- PASS: TestMkdirMetadata (2.40s)
=== RUN   TestMkdirModTime
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 03:11:10 DEBUG : directory with modtime: Making directory with metadata
2026/10/01 03:11:11 INFO  : directory with modtime: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2026/10/01 03:11:11 NOTICE: dry run directory with modtime: Skipped make directory as --dry-run is set
--- PASS: TestMkdirModTime (2.36s)
=== RUN   TestCopyDirMetadata
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 03:11:12 DEBUG : dir with metadata to be copied: Making directory with metadata
2026/10/01 03:11:12 INFO  : dir with metadata to be copied: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z)
2026/10/01 03:11:13 DEBUG : Google drive root 'crypt/pp62fi2pb9cpsk867ktg44cc4ecbae2lkllsqt2ojpuaom1e01q0': Skipping btime metadata as can't update it on an existing file: 2026-10-01T03:11:12.813792078Z
2026/10/01 03:11:13 INFO  : non existent directory: Updated directory metadata
2026/10/01 03:11:15 DEBUG : Google drive root 'crypt/pp62fi2pb9cpsk867ktg44cc4ecbae2lkllsqt2ojpuaom1e01q0': Skipping btime metadata as can't update it on an existing file: 2026-10-01T03:11:12.813792078Z
2026/10/01 03:11:15 INFO  : existing directory: Updated directory metadata
--- PASS: TestCopyDirMetadata (4.63s)
=== RUN   TestSetDirModTime
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 03:11:17 DEBUG : set modtime on non existent directory: Skipping set directory modification time as --no-update-dir-modtime is set
2026/10/01 03:11:19 INFO  : set modtime on existing directory: Set directory modification time (using SetModTime)
2026/10/01 03:11:19 INFO  : set modtime on existing directory: Set directory modification time (using DirSetModTime)
--- PASS: TestSetDirModTime (3.85s)
=== RUN   TestDirsEqual
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 03:11:21 DEBUG : dir with metadata to be copied: Making directory with metadata
2026/10/01 03:11:21 INFO  : dir with metadata to be copied: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z)
2026/10/01 03:11:21 DEBUG : dst: Making directory with metadata
2026/10/01 03:11:21 INFO  : dst: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z)
2026/10/01 03:11:21 DEBUG : dst: Directory modification time the same (differ by -999.999µs, within tolerance 1ms)
2026/10/01 03:11:22 INFO  : dst: Set directory modification time (using SetModTime)
2026/10/01 03:11:22 INFO  : dst: Set directory modification time (using SetModTime)
2026/10/01 03:11:22 DEBUG : dst: Directory modification time the same (differ by 1ns, within tolerance 1ms)
2026/10/01 03:11:23 INFO  : dst: Set directory modification time (using SetModTime)
2026/10/01 03:11:23 DEBUG : dst: Destination directory is newer than source, skipping
--- PASS: TestDirsEqual (3.68s)
=== RUN   TestRemoveExisting
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele'", Local "Local file system at /tmp/rclone2683199630", Modify Window "1ms"
2026/10/01 03:11:27 DEBUG : sub dir/test remove existing: md5 = 522caa705d29c4a707e8d53725ce487a OK
2026/10/01 03:11:28 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: md5 = 29041a7d86c2b9adec4ef12cde260208 OK
2026/10/01 03:11:30 DEBUG : sub dir/test remove existing: TEST: renaming existing object to "sub dir/test remove existing.timaqib7" before starting
2026/10/01 03:11:31 DEBUG : sub dir/test remove existing.timaqib7: TEST: removing renamed existing file after operation
2026/10/01 03:11:32 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: TEST: renaming existing object to "sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.mapoluz3" before starting
2026/10/01 03:11:33 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.mapoluz3: TEST: renaming existing back after failed operation
2026/10/01 03:11:34 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: TEST: renaming existing object to "sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.juzamel2" before starting
2026/10/01 03:11:36 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.juzamel2: TEST: removing renamed existing file after operation
--- PASS: TestRemoveExisting (13.42s)
=== RUN   TestRcatInputFailurePreservesDestination
2026/10/01 03:11:38 DEBUG : Creating backend with remote "/tmp/TestRcatInputFailurePreservesDestination2335427195/001"
--- PASS: TestRcatInputFailurePreservesDestination (0.00s)
=== RUN   TestRcAbout
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcAbout (0.00s)
=== RUN   TestRcCleanup
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcCleanup (0.00s)
=== RUN   TestRcCopyfile
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcCopyfile (0.00s)
=== RUN   TestRcCopyurl
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcCopyurl (0.00s)
=== RUN   TestRcDelete
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcDelete (0.00s)
=== RUN   TestRcDeletefile
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcDeletefile (0.00s)
=== RUN   TestRcList
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcList (0.00s)
=== RUN   TestRcStat
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcStat (0.00s)
=== RUN   TestRcSetTier
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcSetTier (0.00s)
=== RUN   TestRcSetTierFile
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcSetTierFile (0.00s)
=== RUN   TestRcMkdir
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcMkdir (0.00s)
=== RUN   TestRcMovefile
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcMovefile (0.00s)
=== RUN   TestRcPurge
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcPurge (0.00s)
=== RUN   TestRcRmdir
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcRmdir (0.00s)
=== RUN   TestRcRmdirs
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcRmdirs (0.00s)
=== RUN   TestRcSize
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcSize (0.00s)
=== RUN   TestRcPublicLink
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcPublicLink (0.00s)
=== RUN   TestRcFsInfo
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcFsInfo (0.00s)
=== RUN   TestUploadFile
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestUploadFile (0.00s)
=== RUN   TestRcCommand
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcCommand (0.00s)
=== RUN   TestRcDu
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcDu (0.00s)
=== RUN   TestRcCheck
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcCheck (0.00s)
=== RUN   TestRcHashsum
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcHashsum (0.00s)
=== RUN   TestRcHashsumSingleFile
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcHashsumSingleFile (0.00s)
=== RUN   TestRcHashsumFile
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcHashsumFile (0.00s)
=== RUN   TestRcGetFile
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcGetFile (0.00s)
PASS
2026/10/01 03:11:38 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-fuporus9dele': Purge remote
"./operations.test -test.v -test.timeout 1h0m0s -remote TestCryptDrive: -verbose" - Finished OK in 21m25.096843873s (try 1/5)