"./operations.test -test.v -test.timeout 1h0m0s -remote TestCryptDrive: -verbose" - Starting (try 1/5)
2026/09/18 02:27:01 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-konudap8joto"
2026/09/18 02:27:01 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/09/18 02:27:01 DEBUG : Creating backend with remote "TestDrive:crypt/famka70mk01l5in31e13afoej657rn7hsj0fgfdirbsbfjrliqk0"
2026/09/18 02:27:02 DEBUG : Creating backend with remote "/tmp/rclone257193870"
=== 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-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
    multithread_test.go:120: multithread writing not supported
--- SKIP: TestMultithreadCopy (0.23s)
=== RUN   TestMultithreadCopyAbort
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
    multithread_test.go:120: multithread writing not supported
--- SKIP: TestMultithreadCopyAbort (0.24s)
=== RUN   TestMultithreadCopyWriterAtErrors
=== RUN   TestMultithreadCopyWriterAtErrors/open/fatal=false
2026/09/18 02:27:03 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt
=== RUN   TestMultithreadCopyWriterAtErrors/open/fatal=true
2026/09/18 02:27:03 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt
=== RUN   TestMultithreadCopyWriterAtErrors/write/fatal=false
2026/09/18 02:27:03 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt
2026/09/18 02:27:03 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams
2026/09/18 02:27:03 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting
2026/09/18 02:27:03 DEBUG : file.txt: writing chunk 1
2026/09/18 02:27:03 DEBUG : file.txt: multi-thread copy: chunk 2/4 failed: multi-thread copy: failed to write chunk: disk full
2026/09/18 02:27:03 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting
2026/09/18 02:27:03 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting
2026/09/18 02:27:03 DEBUG : file.txt: writing chunk 2
2026/09/18 02:27:03 DEBUG : file.txt: writing chunk 0
2026/09/18 02:27:03 DEBUG : file.txt: multi-thread copy: chunk 3/4 failed: multi-thread copy: failed to write chunk: disk full
2026/09/18 02:27:03 DEBUG : file.txt: multi-thread copy: chunk 1/4 failed: multi-thread copy: failed to write chunk: disk full
2026/09/18 02:27:03 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit
2026/09/18 02:27:03 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/09/18 02:27:03 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt
2026/09/18 02:27:03 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams
2026/09/18 02:27:03 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting
2026/09/18 02:27:03 DEBUG : file.txt: writing chunk 1
2026/09/18 02:27:03 DEBUG : file.txt: multi-thread copy: chunk 2/4 failed: multi-thread copy: failed to write chunk: disk full
2026/09/18 02:27:03 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting
2026/09/18 02:27:03 DEBUG : file.txt: writing chunk 2
2026/09/18 02:27:03 DEBUG : file.txt: multi-thread copy: chunk 3/4 failed: multi-thread copy: failed to write chunk: disk full
2026/09/18 02:27:03 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting
2026/09/18 02:27:03 DEBUG : file.txt: writing chunk 0
2026/09/18 02:27:03 DEBUG : file.txt: multi-thread copy: chunk 1/4 failed: multi-thread copy: failed to write chunk: disk full
2026/09/18 02:27:03 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit
2026/09/18 02:27:03 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/09/18 02:27:03 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt
2026/09/18 02:27:03 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams
2026/09/18 02:27:03 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting
2026/09/18 02:27:03 DEBUG : file.txt: writing chunk 1
2026/09/18 02:27:03 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 finished
2026/09/18 02:27:03 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting
2026/09/18 02:27:03 DEBUG : file.txt: writing chunk 2
2026/09/18 02:27:03 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 finished
2026/09/18 02:27:03 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 starting
2026/09/18 02:27:03 DEBUG : file.txt: writing chunk 3
2026/09/18 02:27:03 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 finished
2026/09/18 02:27:03 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting
2026/09/18 02:27:03 DEBUG : file.txt: writing chunk 0
2026/09/18 02:27:03 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 finished
2026/09/18 02:27:03 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit
2026/09/18 02:27:03 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/09/18 02:27:03 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt
2026/09/18 02:27:03 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams
2026/09/18 02:27:03 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting
2026/09/18 02:27:03 DEBUG : file.txt: writing chunk 1
2026/09/18 02:27:03 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 finished
2026/09/18 02:27:03 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting
2026/09/18 02:27:03 DEBUG : file.txt: writing chunk 0
2026/09/18 02:27:03 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 finished
2026/09/18 02:27:03 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting
2026/09/18 02:27:03 DEBUG : file.txt: writing chunk 2
2026/09/18 02:27:03 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 starting
2026/09/18 02:27:03 DEBUG : file.txt: writing chunk 3
2026/09/18 02:27:03 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 finished
2026/09/18 02:27:03 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 finished
2026/09/18 02:27:03 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit
2026/09/18 02:27:03 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/09/18 02:27:03 DEBUG : a: size = 0 OK
2026/09/18 02:27:03 DEBUG : a: size = 1 (memory)
2026/09/18 02:27:03 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/09/18 02:27:03 DEBUG : potato: Seek from 10 to 0
=== RUN   TestReOpen/Normal/ErrorAtStart
=== RUN   TestReOpen/Normal/WithErrors
2026/09/18 02:27:03 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2026/09/18 02:27:03 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2026/09/18 02:27:03 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN   TestReOpen/Normal/TooManyErrors
2026/09/18 02:27:03 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2026/09/18 02:27:03 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2026/09/18 02:27:03 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2026/09/18 02:27:03 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN   TestReOpen/Normal/ReadAt
2026/09/18 02:27:03 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/09/18 02:27:03 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/09/18 02:27:03 DEBUG : potato: Seek from 5 to 1
=== RUN   TestReOpen/Normal/Seek
2026/09/18 02:27:03 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/09/18 02:27:03 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/09/18 02:27:03 DEBUG : potato: Seek from 5 to 2
=== RUN   TestReOpen/Normal/AccountRead
=== RUN   TestReOpen/Normal/AccountReadDelay
2026/09/18 02:27:03 DEBUG : potato: Seek from 10 to 0
2026/09/18 02:27:03 DEBUG : potato: Seek from 10 to 0
2026/09/18 02:27:03 DEBUG : potato: Seek from 10 to 0
=== RUN   TestReOpen/Normal/AccountReadError
=== RUN   TestReOpen/WithRangeOption
=== RUN   TestReOpen/WithRangeOption/Basics
2026/09/18 02:27:03 DEBUG : potato: Seek from 7 to 0
=== RUN   TestReOpen/WithRangeOption/ErrorAtStart
=== RUN   TestReOpen/WithRangeOption/WithErrors
2026/09/18 02:27:03 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2026/09/18 02:27:03 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2026/09/18 02:27:03 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN   TestReOpen/WithRangeOption/TooManyErrors
2026/09/18 02:27:03 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2026/09/18 02:27:03 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2026/09/18 02:27:03 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2026/09/18 02:27:03 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN   TestReOpen/WithRangeOption/ReadAt
2026/09/18 02:27:03 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/09/18 02:27:03 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/09/18 02:27:03 DEBUG : potato: Seek from 5 to 1
=== RUN   TestReOpen/WithRangeOption/Seek
2026/09/18 02:27:03 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/09/18 02:27:03 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/09/18 02:27:03 DEBUG : potato: Seek from 5 to 2
2026/09/18 02:27:03 DEBUG : potato: Seek from 7 to 4
=== RUN   TestReOpen/WithRangeOption/AccountRead
=== RUN   TestReOpen/WithRangeOption/AccountReadDelay
2026/09/18 02:27:03 DEBUG : potato: Seek from 7 to 0
2026/09/18 02:27:03 DEBUG : potato: Seek from 7 to 0
2026/09/18 02:27:03 DEBUG : potato: Seek from 7 to 0
=== RUN   TestReOpen/WithRangeOption/AccountReadError
=== RUN   TestReOpen/WithSeekOption
=== RUN   TestReOpen/WithSeekOption/Basics
2026/09/18 02:27:03 DEBUG : potato: Seek from 8 to 0
=== RUN   TestReOpen/WithSeekOption/ErrorAtStart
=== RUN   TestReOpen/WithSeekOption/WithErrors
2026/09/18 02:27:03 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2026/09/18 02:27:03 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2026/09/18 02:27:03 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN   TestReOpen/WithSeekOption/TooManyErrors
2026/09/18 02:27:03 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2026/09/18 02:27:03 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2026/09/18 02:27:03 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2026/09/18 02:27:03 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN   TestReOpen/WithSeekOption/ReadAt
2026/09/18 02:27:03 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/09/18 02:27:03 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/09/18 02:27:03 DEBUG : potato: Seek from 5 to 1
=== RUN   TestReOpen/WithSeekOption/Seek
2026/09/18 02:27:03 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/09/18 02:27:03 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/09/18 02:27:03 DEBUG : potato: Seek from 5 to 2
2026/09/18 02:27:03 DEBUG : potato: Seek from 7 to 5
=== RUN   TestReOpen/WithSeekOption/AccountRead
=== RUN   TestReOpen/WithSeekOption/AccountReadDelay
2026/09/18 02:27:03 DEBUG : potato: Seek from 8 to 0
2026/09/18 02:27:03 DEBUG : potato: Seek from 8 to 0
2026/09/18 02:27:03 DEBUG : potato: Seek from 8 to 0
=== RUN   TestReOpen/WithSeekOption/AccountReadError
=== RUN   TestReOpen/UnknownSize
=== RUN   TestReOpen/UnknownSize/Basics
2026/09/18 02:27:03 DEBUG : potato: Seek from 9 to 0
=== RUN   TestReOpen/UnknownSize/ErrorAtStart
=== RUN   TestReOpen/UnknownSize/WithErrors
2026/09/18 02:27:03 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2026/09/18 02:27:03 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2026/09/18 02:27:03 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN   TestReOpen/UnknownSize/TooManyErrors
2026/09/18 02:27:03 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2026/09/18 02:27:03 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2026/09/18 02:27:03 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2026/09/18 02:27:03 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN   TestReOpen/UnknownSize/ReadAt
2026/09/18 02:27:03 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/09/18 02:27:03 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/09/18 02:27:03 DEBUG : potato: Seek from 5 to 1
=== RUN   TestReOpen/UnknownSize/Seek
2026/09/18 02:27:03 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/09/18 02:27:03 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/09/18 02:27:03 DEBUG : potato: Seek from 5 to 2
2026/09/18 02:27:03 DEBUG : potato: Seek from 7 to 6
=== RUN   TestReOpen/UnknownSize/AccountRead
=== RUN   TestReOpen/UnknownSize/AccountReadDelay
2026/09/18 02:27:03 DEBUG : potato: Seek from 9 to 0
2026/09/18 02:27:03 DEBUG : potato: Seek from 9 to 0
2026/09/18 02:27:03 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-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:27:05 DEBUG : rutabaga: md5 = 1a7f0e113dc47ad251c01090cd3a61cf OK
=== RUN   TestCheck/1
=== RUN   TestCheck/2
2026/09/18 02:27:07 DEBUG : empty space: md5 = 0c495e10fac2bb09be3a8003079b2ee5 OK
=== RUN   TestCheck/3
2026/09/18 02:27:09 DEBUG : potato2: md5 = e505a0d965abea70df4c243691e219d9 OK
=== RUN   TestCheck/4
=== RUN   TestCheck/5
2026/09/18 02:27:11 DEBUG : remotepotato: md5 = 532dd747264c1f85ef4025150718cc2d OK
=== RUN   TestCheck/6
=== RUN   TestCheck/7
--- PASS: TestCheck (11.53s)
    --- PASS: TestCheck/1 (0.23s)
    --- PASS: TestCheck/2 (0.27s)
    --- PASS: TestCheck/3 (0.25s)
    --- PASS: TestCheck/4 (0.27s)
    --- PASS: TestCheck/5 (0.24s)
    --- PASS: TestCheck/6 (0.25s)
    --- PASS: TestCheck/7 (0.26s)
=== RUN   TestCheckFsError
2026/09/18 02:27:14 DEBUG : Creating backend with remote "nonexistent"
2026/09/18 02:27:14 DEBUG : Creating backend with remote "nonexistent"
2026/09/18 02:27:14 DEBUG : Local file system at nonexistent: Waiting for checks to finish
2026/09/18 02:27:14 ERROR : Local file system at nonexistent: error reading source root directory: directory not found
2026/09/18 02:27:14 NOTICE: Local file system at nonexistent: 0 differences found
2026/09/18 02:27:14 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-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:27:16 DEBUG : rutabaga: md5 = 8d11b604e935a30cd30b978ac5876f88 OK
=== RUN   TestCheckDownload/1
=== RUN   TestCheckDownload/2
2026/09/18 02:27:19 DEBUG : empty space: md5 = cf9531a75fe95d9de63816b5496c143f OK
=== RUN   TestCheckDownload/3
2026/09/18 02:27:22 DEBUG : potato2: md5 = eea87769605801053bfded566941b042 OK
=== RUN   TestCheckDownload/4
=== RUN   TestCheckDownload/5
2026/09/18 02:27:26 DEBUG : remotepotato: md5 = 5001dd958dfbddf87297d8355bbe0f49 OK
=== RUN   TestCheckDownload/6
=== RUN   TestCheckDownload/7
--- PASS: TestCheckDownload (16.85s)
    --- PASS: TestCheckDownload/1 (0.95s)
    --- PASS: TestCheckDownload/2 (0.79s)
    --- PASS: TestCheckDownload/3 (0.83s)
    --- PASS: TestCheckDownload/4 (0.91s)
    --- PASS: TestCheckDownload/5 (0.96s)
    --- PASS: TestCheckDownload/6 (0.90s)
    --- PASS: TestCheckDownload/7 (0.79s)
=== RUN   TestCheckSizeOnly
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:27:32 DEBUG : rutabaga: md5 = d0050c5ddf85c2e66ea6ec4347fe2b77 OK
=== RUN   TestCheckSizeOnly/1
=== RUN   TestCheckSizeOnly/2
2026/09/18 02:27:35 DEBUG : empty space: md5 = f98d70b7fd8277394c6ec7073c747860 OK
=== RUN   TestCheckSizeOnly/3
2026/09/18 02:27:37 DEBUG : potato2: md5 = 82dbfc374817b03ae5d297492ba01728 OK
=== RUN   TestCheckSizeOnly/4
=== RUN   TestCheckSizeOnly/5
2026/09/18 02:27:39 DEBUG : remotepotato: md5 = 893e51d400303ddf270f139321432919 OK
=== RUN   TestCheckSizeOnly/6
=== RUN   TestCheckSizeOnly/7
--- PASS: TestCheckSizeOnly (11.67s)
    --- PASS: TestCheckSizeOnly/1 (0.26s)
    --- PASS: TestCheckSizeOnly/2 (0.27s)
    --- PASS: TestCheckSizeOnly/3 (0.27s)
    --- PASS: TestCheckSizeOnly/4 (0.28s)
    --- PASS: TestCheckSizeOnly/5 (0.27s)
    --- PASS: TestCheckSizeOnly/6 (0.26s)
    --- PASS: TestCheckSizeOnly/7 (0.29s)
=== RUN   TestCheckEqualReaders
--- PASS: TestCheckEqualReaders (0.00s)
=== RUN   TestParseSumFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:27:44 DEBUG : test.sum: md5 = 78527f943b0848890f3e314cf1dd1dba OK
2026/09/18 02:27:45 NOTICE: test.sum: improperly formatted checksum line 4
2026/09/18 02:27:45 NOTICE: test.sum: improperly formatted checksum line 5
2026/09/18 02:27:45 NOTICE: test.sum: improperly formatted checksum line 6
2026/09/18 02:27:45 NOTICE: test.sum: 2 warning(s) suppressed...
2026/09/18 02:27:47 DEBUG : test.sum: md5 = ff8d3fc3c333de61c3b6ff41d4f184d9 OK
2026/09/18 02:27:47 NOTICE: test.sum: improperly formatted checksum line 4
2026/09/18 02:27:47 NOTICE: test.sum: improperly formatted checksum line 5
2026/09/18 02:27:47 NOTICE: test.sum: improperly formatted checksum line 6
2026/09/18 02:27:47 NOTICE: test.sum: 2 warning(s) suppressed...
--- PASS: TestParseSumFile (5.69s)
=== RUN   TestCheckSum
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:27:48 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-konudap8joto/data"
2026/09/18 02:27:48 DEBUG : Creating backend with remote "TestDrive:crypt/famka70mk01l5in31e13afoej657rn7hsj0fgfdirbsbfjrliqk0/vjrnln8ratgmqakfosrqe8espk"
    check_test.go:354: Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto/data' lacks md5, skipping
--- SKIP: TestCheckSum (2.07s)
=== RUN   TestCheckSumDownload
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:27:50 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-konudap8joto/data"
2026/09/18 02:27:51 DEBUG : Creating backend with remote "TestDrive:crypt/famka70mk01l5in31e13afoej657rn7hsj0fgfdirbsbfjrliqk0/vjrnln8ratgmqakfosrqe8espk"
2026/09/18 02:27:55 DEBUG : data/banana: md5 = b6ad0a61b380352b5369238169922d15 OK
2026/09/18 02:27:56 DEBUG : test.sum: md5 = dfd70ecf37f81463e0a16231e518a2c3 OK
=== RUN   TestCheckSumDownload/subtest1
2026/09/18 02:28:00 DEBUG : data/potato: md5 = 0062a6c561fa53214efa13844c8c5364 OK
2026/09/18 02:28:02 DEBUG : test.sum: md5 = d43aee83ee4c2582887215d742ff1ba3 OK
=== RUN   TestCheckSumDownload/subtest2
2026/09/18 02:28:05 DEBUG : test.sum: md5 = e15cb13fc52150f621a908f98c0def5d OK
=== RUN   TestCheckSumDownload/subtest3
2026/09/18 02:28:09 DEBUG : test.sum: md5 = fccb8db04d74b71691344e33c1afdd2d OK
=== RUN   TestCheckSumDownload/subtest4
2026/09/18 02:28:13 DEBUG : test.sum: md5 = af54b76f4f13085819f974fc3b0ee863 OK
=== RUN   TestCheckSumDownload/subtest5
2026/09/18 02:28:17 DEBUG : test.sum: md5 = 822c99456001bf303334d62d278e7e1d OK
=== RUN   TestCheckSumDownload/subtest6
2026/09/18 02:28:21 DEBUG : data/banana: md5 = 1d4c8a5290ca77b3e45e7ec16733bb39 OK
2026/09/18 02:28:22 DEBUG : data/potato: md5 = 7f30f462d2279cd8a9e8fc112ba3d5de OK
2026/09/18 02:28:24 DEBUG : test.sum: md5 = f0897fd8e45c482a3a25c65dfa374cc8 OK
=== RUN   TestCheckSumDownload/subtest7
--- PASS: TestCheckSumDownload (37.96s)
    --- PASS: TestCheckSumDownload/subtest1 (2.15s)
    --- PASS: TestCheckSumDownload/subtest2 (1.88s)
    --- PASS: TestCheckSumDownload/subtest3 (1.94s)
    --- PASS: TestCheckSumDownload/subtest4 (1.89s)
    --- PASS: TestCheckSumDownload/subtest5 (1.82s)
    --- PASS: TestCheckSumDownload/subtest6 (1.86s)
    --- PASS: TestCheckSumDownload/subtest7 (1.81s)
=== RUN   TestCheckSumConcurrency
2026/09/18 02:28:28 DEBUG : file0: md5 = 022eb0c0085c731f335df970fafa7446 OK
2026/09/18 02:28:28 DEBUG : file1: md5 = 17fbabbccbdea0e6be9eb64c8c6fea41 OK
2026/09/18 02:28:28 DEBUG : file2: md5 = a58f09ae3db8ae4b943fdbaace096072 OK
2026/09/18 02:28:28 DEBUG : file3: md5 = e0639b17e0744aca9a3ba29c049e59ee OK
2026/09/18 02:28:28 NOTICE: Mock file system at checkSum: 0 differences found
2026/09/18 02:28:28 NOTICE: Mock file system at checkSum: 4 matching files
--- PASS: TestCheckSumConcurrency (0.00s)
=== RUN   TestCheckSumDownloadConcurrency
2026/09/18 02:28:28 DEBUG : file0: md5 = 022eb0c0085c731f335df970fafa7446 OK
2026/09/18 02:28:28 DEBUG : file2: md5 = a58f09ae3db8ae4b943fdbaace096072 OK
2026/09/18 02:28:28 DEBUG : file3: md5 = e0639b17e0744aca9a3ba29c049e59ee OK
2026/09/18 02:28:28 DEBUG : file1: md5 = 17fbabbccbdea0e6be9eb64c8c6fea41 OK
2026/09/18 02:28:28 NOTICE: Mock file system at checkSum: 0 differences found
2026/09/18 02:28:28 NOTICE: Mock file system at checkSum: 4 matching files
--- PASS: TestCheckSumDownloadConcurrency (0.00s)
=== RUN   TestApplyTransforms
2026/09/18 02:28:28 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-rikecaz1qahu"
2026/09/18 02:28:28 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/09/18 02:28:28 DEBUG : Creating backend with remote "TestDrive:crypt/tctjco95vlp2en6be0aun2iffmmordta6ffl56itumgtqc9iie9g"
2026/09/18 02:28:29 DEBUG : Creating backend with remote "/tmp/rclone1467941897"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-rikecaz1qahu'", Local "Local file system at /tmp/rclone1467941897", Modify Window "1ms"
2026/09/18 02:28:32 DEBUG : hello, world!: md5 = b8b55829e0fe49c7a165b0a908b81250 OK
upper checkfile vs. lower remote (without normalization)
2026/09/18 02:28:32 ERROR : hello, world!: sum not found
2026/09/18 02:28:32 ERROR : HELLO, WORLD!: file not in Encrypted drive 'TestCryptDrive:rclone-test-rikecaz1qahu'
2026/09/18 02:28:32 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-rikecaz1qahu': 1 files missing
2026/09/18 02:28:32 NOTICE: 1 hashes missing
2026/09/18 02:28:32 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-rikecaz1qahu': 1 differences found
2026/09/18 02:28:32 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-rikecaz1qahu': 2 errors while checking
upper checkfile vs. lower remote (with normalization)
2026/09/18 02:28:33 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/09/18 02:28:33 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-rikecaz1qahu': 0 differences found
2026/09/18 02:28:33 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-rikecaz1qahu': 1 matching files
2026/09/18 02:28:33 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-digayuv6ruta"
2026/09/18 02:28:33 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/09/18 02:28:33 DEBUG : Creating backend with remote "TestDrive:crypt/n4aas8d4tqp6aefiv5n2j8l9jct3hpnhpr2l13pbcg5s9us6c070"
2026/09/18 02:28:34 DEBUG : Creating backend with remote "/tmp/rclone2505210264"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-digayuv6ruta'", Local "Local file system at /tmp/rclone2505210264", Modify Window "1ms"
2026/09/18 02:28:36 DEBUG : HELLO, WORLD!: md5 = bb664a1a1beababd17f453e299f09865 OK
lower checkfile vs. upper remote (without normalization)
2026/09/18 02:28:37 ERROR : HELLO, WORLD!: sum not found
2026/09/18 02:28:37 ERROR : hello, world!: file not in Encrypted drive 'TestCryptDrive:rclone-test-digayuv6ruta'
2026/09/18 02:28:37 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-digayuv6ruta': 1 files missing
2026/09/18 02:28:37 NOTICE: 1 hashes missing
2026/09/18 02:28:37 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-digayuv6ruta': 1 differences found
2026/09/18 02:28:37 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-digayuv6ruta': 2 errors while checking
lower checkfile vs. upper remote (with normalization)
2026/09/18 02:28:38 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/09/18 02:28:38 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-digayuv6ruta': 0 differences found
2026/09/18 02:28:38 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-digayuv6ruta': 1 matching files
2026/09/18 02:28:38 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-kaqirem3nemi"
2026/09/18 02:28:38 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/09/18 02:28:38 DEBUG : Creating backend with remote "TestDrive:crypt/edvs2uersgq06ik3hbuul9948o3404vqavjj344kq3v13nlbvh90"
2026/09/18 02:28:39 DEBUG : Creating backend with remote "/tmp/rclone407879485"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kaqirem3nemi'", Local "Local file system at /tmp/rclone407879485", Modify Window "1ms"
2026/09/18 02:28:41 DEBUG : HeLlO, wOrLd!: md5 = 3df5bfd6e0fb49299c4bc21d64c960ec OK
lower checkfile vs. upperlowermixed remote (without normalization)
2026/09/18 02:28:42 ERROR : HeLlO, wOrLd!: sum not found
2026/09/18 02:28:42 ERROR : hello, world!: file not in Encrypted drive 'TestCryptDrive:rclone-test-kaqirem3nemi'
2026/09/18 02:28:42 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kaqirem3nemi': 1 files missing
2026/09/18 02:28:42 NOTICE: 1 hashes missing
2026/09/18 02:28:42 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kaqirem3nemi': 1 differences found
2026/09/18 02:28:42 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kaqirem3nemi': 2 errors while checking
lower checkfile vs. upperlowermixed remote (with normalization)
2026/09/18 02:28:43 DEBUG : HeLlO, wOrLd!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/09/18 02:28:43 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kaqirem3nemi': 0 differences found
2026/09/18 02:28:43 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kaqirem3nemi': 1 matching files
2026/09/18 02:28:43 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-nuhucar2juqa"
2026/09/18 02:28:43 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/09/18 02:28:43 DEBUG : Creating backend with remote "TestDrive:crypt/fdhglcl988m1qaki7ubahu3ss3jqmjmuiiml5vqae0p3g907jaeg"
2026/09/18 02:28:44 DEBUG : Creating backend with remote "/tmp/rclone796384858"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-nuhucar2juqa'", Local "Local file system at /tmp/rclone796384858", Modify Window "1ms"
2026/09/18 02:28:46 DEBUG : HELLO, WORLD!: md5 = 67595641846f828e25dd939644c4248b OK
upperlowermixed checkfile vs. upper remote (without normalization)
2026/09/18 02:28:47 ERROR : HELLO, WORLD!: sum not found
2026/09/18 02:28:47 ERROR : HeLlO, wOrLd!: file not in Encrypted drive 'TestCryptDrive:rclone-test-nuhucar2juqa'
2026/09/18 02:28:47 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-nuhucar2juqa': 1 files missing
2026/09/18 02:28:47 NOTICE: 1 hashes missing
2026/09/18 02:28:47 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-nuhucar2juqa': 1 differences found
2026/09/18 02:28:47 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-nuhucar2juqa': 2 errors while checking
upperlowermixed checkfile vs. upper remote (with normalization)
2026/09/18 02:28:48 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/09/18 02:28:48 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-nuhucar2juqa': 0 differences found
2026/09/18 02:28:48 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-nuhucar2juqa': 1 matching files
2026/09/18 02:28:48 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-xilubam4pimu"
2026/09/18 02:28:48 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/09/18 02:28:48 DEBUG : Creating backend with remote "TestDrive:crypt/6kuo6vkl72fefs9n1anvcmj6uud627pl9h4gluvbvtcntrtpab10"
2026/09/18 02:28:49 DEBUG : Creating backend with remote "/tmp/rclone246391953"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-xilubam4pimu'", Local "Local file system at /tmp/rclone246391953", Modify Window "1ms"
2026/09/18 02:28:51 DEBUG : 測試_Русский___ě_áñ: md5 = 3dc6a3a7a2e4ee40a5c98dff0fc61c7e OK
NFD checkfile vs. NFC remote (without normalization)
2026/09/18 02:28:51 ERROR : 測試_Русский___ě_áñ: sum not found
2026/09/18 02:28:51 ERROR : 測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-xilubam4pimu'
2026/09/18 02:28:51 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-xilubam4pimu': 1 files missing
2026/09/18 02:28:51 NOTICE: 1 hashes missing
2026/09/18 02:28:51 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-xilubam4pimu': 1 differences found
2026/09/18 02:28:51 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-xilubam4pimu': 2 errors while checking
NFD checkfile vs. NFC remote (with normalization)
2026/09/18 02:28:52 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/09/18 02:28:52 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-xilubam4pimu': 0 differences found
2026/09/18 02:28:52 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-xilubam4pimu': 1 matching files
2026/09/18 02:28:52 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jaqoqiq6yemi"
2026/09/18 02:28:52 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/09/18 02:28:52 DEBUG : Creating backend with remote "TestDrive:crypt/2tnu5gsojtthqj6nahba689rkn84lsev7um7olo1m4d8m3amhcog"
2026/09/18 02:28:53 DEBUG : Creating backend with remote "/tmp/rclone3125275686"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jaqoqiq6yemi'", Local "Local file system at /tmp/rclone3125275686", Modify Window "1ms"
2026/09/18 02:28:56 DEBUG : 測試_Русский___ě_áñ: md5 = 283de1bd960fe4b09e730b4a469fe527 OK
NFC checkfile vs. NFD remote (without normalization)
2026/09/18 02:28:56 ERROR : 測試_Русский___ě_áñ: sum not found
2026/09/18 02:28:56 ERROR : 測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-jaqoqiq6yemi'
2026/09/18 02:28:56 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-jaqoqiq6yemi': 1 files missing
2026/09/18 02:28:56 NOTICE: 1 hashes missing
2026/09/18 02:28:56 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-jaqoqiq6yemi': 1 differences found
2026/09/18 02:28:56 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-jaqoqiq6yemi': 2 errors while checking
NFC checkfile vs. NFD remote (with normalization)
2026/09/18 02:28:57 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/09/18 02:28:57 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-jaqoqiq6yemi': 0 differences found
2026/09/18 02:28:57 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-jaqoqiq6yemi': 1 matching files
2026/09/18 02:28:57 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-behiyos3tepe"
2026/09/18 02:28:57 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/09/18 02:28:57 DEBUG : Creating backend with remote "TestDrive:crypt/c4uvg6dnlk0s76jhgu17q1l68377o52pv4tq624frmhvdtcib9v0"
2026/09/18 02:28:58 DEBUG : Creating backend with remote "/tmp/rclone4090971610"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-behiyos3tepe'", Local "Local file system at /tmp/rclone4090971610", Modify Window "1ms"
2026/09/18 02:29:00 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 38d5509791664f8a7c8104611febc109 OK
NFDx2 checkfile vs. both remote (without normalization)
2026/09/18 02:29:01 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found
2026/09/18 02:29:01 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-behiyos3tepe'
2026/09/18 02:29:01 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-behiyos3tepe': 1 files missing
2026/09/18 02:29:01 NOTICE: 1 hashes missing
2026/09/18 02:29:01 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-behiyos3tepe': 1 differences found
2026/09/18 02:29:01 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-behiyos3tepe': 2 errors while checking
NFDx2 checkfile vs. both remote (with normalization)
2026/09/18 02:29:02 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/09/18 02:29:02 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-behiyos3tepe': 0 differences found
2026/09/18 02:29:02 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-behiyos3tepe': 1 matching files
2026/09/18 02:29:02 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-zutovun2risa"
2026/09/18 02:29:02 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/09/18 02:29:02 DEBUG : Creating backend with remote "TestDrive:crypt/1s1mfgh168cau0kcs13iiake6r0er7snn6dpi1g9ulttl1dmg9e0"
2026/09/18 02:29:03 DEBUG : Creating backend with remote "/tmp/rclone4271125042"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zutovun2risa'", Local "Local file system at /tmp/rclone4271125042", Modify Window "1ms"
2026/09/18 02:29:05 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 1a0aacc26afa10fc184aad4ac6589f61 OK
NFCx2 checkfile vs. both remote (without normalization)
2026/09/18 02:29:06 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found
2026/09/18 02:29:06 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-zutovun2risa'
2026/09/18 02:29:06 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-zutovun2risa': 1 files missing
2026/09/18 02:29:06 NOTICE: 1 hashes missing
2026/09/18 02:29:06 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-zutovun2risa': 1 differences found
2026/09/18 02:29:06 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-zutovun2risa': 2 errors while checking
NFCx2 checkfile vs. both remote (with normalization)
2026/09/18 02:29:07 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/09/18 02:29:07 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-zutovun2risa': 0 differences found
2026/09/18 02:29:07 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-zutovun2risa': 1 matching files
2026/09/18 02:29:07 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-hakakul8gowu"
2026/09/18 02:29:07 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/09/18 02:29:07 DEBUG : Creating backend with remote "TestDrive:crypt/ud6v5o4etqeueahjgegome6vhtr581a8vm7cetuo50jdl7qv8mn0"
2026/09/18 02:29:08 DEBUG : Creating backend with remote "/tmp/rclone4015769325"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hakakul8gowu'", Local "Local file system at /tmp/rclone4015769325", Modify Window "1ms"
2026/09/18 02:29:10 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = f2f6ece0037151d357006223d71787f8 OK
both checkfile vs. NFDx2 remote (without normalization)
2026/09/18 02:29:11 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found
2026/09/18 02:29:11 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-hakakul8gowu'
2026/09/18 02:29:11 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-hakakul8gowu': 1 files missing
2026/09/18 02:29:11 NOTICE: 1 hashes missing
2026/09/18 02:29:11 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-hakakul8gowu': 1 differences found
2026/09/18 02:29:11 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-hakakul8gowu': 2 errors while checking
both checkfile vs. NFDx2 remote (with normalization)
2026/09/18 02:29:11 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/09/18 02:29:11 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-hakakul8gowu': 0 differences found
2026/09/18 02:29:11 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-hakakul8gowu': 1 matching files
2026/09/18 02:29:11 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-hifisax0napi"
2026/09/18 02:29:12 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/09/18 02:29:12 DEBUG : Creating backend with remote "TestDrive:crypt/kk516d8990lp0vc4e42ifbkiqpctssuo0icl9bddhrlvik11g9h0"
2026/09/18 02:29:13 DEBUG : Creating backend with remote "/tmp/rclone2765278444"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hifisax0napi'", Local "Local file system at /tmp/rclone2765278444", Modify Window "1ms"
2026/09/18 02:29:15 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 0c35d2baf07c48ab5f05efdd0a0a5a69 OK
both checkfile vs. NFCx2 remote (without normalization)
2026/09/18 02:29:15 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found
2026/09/18 02:29:15 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-hifisax0napi'
2026/09/18 02:29:15 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-hifisax0napi': 1 files missing
2026/09/18 02:29:15 NOTICE: 1 hashes missing
2026/09/18 02:29:15 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-hifisax0napi': 1 differences found
2026/09/18 02:29:15 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-hifisax0napi': 2 errors while checking
both checkfile vs. NFCx2 remote (with normalization)
2026/09/18 02:29:16 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/09/18 02:29:16 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-hifisax0napi': 0 differences found
2026/09/18 02:29:16 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-hifisax0napi': 1 matching files
2026/09/18 02:29:16 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-hifisax0napi': Purge remote
2026/09/18 02:29:17 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-hakakul8gowu': Purge remote
2026/09/18 02:29:17 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zutovun2risa': Purge remote
2026/09/18 02:29:17 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-behiyos3tepe': Purge remote
2026/09/18 02:29:18 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jaqoqiq6yemi': Purge remote
2026/09/18 02:29:18 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-xilubam4pimu': Purge remote
2026/09/18 02:29:19 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-nuhucar2juqa': Purge remote
2026/09/18 02:29:19 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-kaqirem3nemi': Purge remote
2026/09/18 02:29:20 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-digayuv6ruta': Purge remote
2026/09/18 02:29:20 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-rikecaz1qahu': Purge remote
--- PASS: TestApplyTransforms (52.20s)
=== RUN   TestTruncateString
--- PASS: TestTruncateString (0.00s)
=== RUN   TestCopyFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:29:21 DEBUG : file1: Need to transfer - File not found at Destination
2026/09/18 02:29:23 DEBUG : sub/file2: md5 = 21ce12b99522a3c3347bcd231016f45f OK
2026/09/18 02:29:23 DEBUG : sub/file2: size = 14 OK
2026/09/18 02:29:23 INFO  : file1: Copied (new) to: sub/file2
2026/09/18 02:29:24 DEBUG : sub/file2: size = 14 OK
2026/09/18 02:29:24 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2026/09/18 02:29:24 DEBUG : file1: Unchanged skipping
2026/09/18 02:29:24 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto': don't need to copy/move sub/file2, it is already at target location
--- PASS: TestCopyFile (6.14s)
=== RUN   TestCopyLongFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
    copy_test.go:154: Test only runs on local
--- SKIP: TestCopyLongFile (0.48s)
=== RUN   TestCopyFileBackupDir
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:29:29 DEBUG : dst/file1: md5 = 82a70ddb555fe5bff921fa095c92a0bc OK
2026/09/18 02:29:30 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-konudap8joto/backup"
2026/09/18 02:29:30 DEBUG : Creating backend with remote "TestDrive:crypt/famka70mk01l5in31e13afoej657rn7hsj0fgfdirbsbfjrliqk0/1nrff024r7pq65ecp72fc28jb0"
2026/09/18 02:29:32 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone257193870)
2026/09/18 02:29:32 DEBUG : dst/file1: size = 18 (Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto')
2026/09/18 02:29:32 DEBUG : dst/file1: Sizes differ
2026/09/18 02:29:34 INFO  : dst/file1: Moved (server-side)
2026/09/18 02:29:35 DEBUG : dst/file1: md5 = d3dcf91c70b9c67c5830842e3441a218 OK
2026/09/18 02:29:35 DEBUG : dst/file1: size = 14 OK
2026/09/18 02:29:35 INFO  : dst/file1: Copied (new)
--- PASS: TestCopyFileBackupDir (12.80s)
=== RUN   TestCopyFileCompareDest
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:29:40 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-konudap8joto/dst"
2026/09/18 02:29:40 DEBUG : Creating backend with remote "TestDrive:crypt/famka70mk01l5in31e13afoej657rn7hsj0fgfdirbsbfjrliqk0/31u3jie661vd5p8j7rtc3hgbh0"
2026/09/18 02:29:42 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-konudap8joto/CompareDest"
2026/09/18 02:29:42 DEBUG : Creating backend with remote "TestDrive:crypt/famka70mk01l5in31e13afoej657rn7hsj0fgfdirbsbfjrliqk0/gveqi14airsml4bgu7krj116o8"
2026/09/18 02:29:44 DEBUG : one: Need to transfer - File not found at Destination
2026/09/18 02:29:46 DEBUG : one: md5 = e6e679c28696ab39a9611b2f20c0b009 OK
2026/09/18 02:29:46 DEBUG : one: size = 3 OK
2026/09/18 02:29:46 INFO  : one: Copied (new)
2026/09/18 02:29:47 DEBUG : one: size = 5 (Local file system at /tmp/rclone257193870)
2026/09/18 02:29:47 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto/dst')
2026/09/18 02:29:47 DEBUG : one: Sizes differ
2026/09/18 02:29:49 DEBUG : one: md5 = 01e8bdd535d728216a53b510008d4653 OK
2026/09/18 02:29:49 DEBUG : one: size = 5 OK
2026/09/18 02:29:49 INFO  : one: Copied (replaced existing)
2026/09/18 02:29:51 DEBUG : dst/one: md5 = b1337db35e4b4a782b453bd69b0e156f OK
2026/09/18 02:29:53 DEBUG : CompareDest/one: md5 = bfdcbd480b1a3bb6578cdf227a2834ec OK
2026/09/18 02:29:54 DEBUG : one: size = 5 (Local file system at /tmp/rclone257193870)
2026/09/18 02:29:54 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto/dst')
2026/09/18 02:29:54 DEBUG : one: Sizes differ
2026/09/18 02:29:54 DEBUG : one: size = 5 OK
2026/09/18 02:29:54 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/09/18 02:29:54 DEBUG : one: Destination found in --compare-dest, skipping
2026/09/18 02:29:57 DEBUG : CompareDest/two: md5 = cb6d95eda4de171767b1f0101c9eb0db OK
2026/09/18 02:29:57 DEBUG : two: Need to transfer - File not found at Destination
2026/09/18 02:29:58 DEBUG : two: size = 3 OK
2026/09/18 02:29:58 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/09/18 02:29:58 DEBUG : two: Destination found in --compare-dest, skipping
2026/09/18 02:29:58 DEBUG : two: Need to transfer - File not found at Destination
2026/09/18 02:29:59 DEBUG : two: size = 3 OK
2026/09/18 02:29:59 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/09/18 02:29:59 DEBUG : two: Destination found in --compare-dest, skipping
2026/09/18 02:30:00 DEBUG : two: Need to transfer - File not found at Destination
2026/09/18 02:30:00 DEBUG : two: size = 5 (Local file system at /tmp/rclone257193870)
2026/09/18 02:30:00 DEBUG : two: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto/CompareDest')
2026/09/18 02:30:00 DEBUG : two: Sizes differ
2026/09/18 02:30:02 DEBUG : two: md5 = a2e026b55fcb872042fb01e05de96c2c OK
2026/09/18 02:30:02 DEBUG : two: size = 5 OK
2026/09/18 02:30:02 INFO  : two: Copied (new)
--- PASS: TestCopyFileCompareDest (26.29s)
=== RUN   TestCopyFileCopyDest
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:30:06 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-konudap8joto/dst"
2026/09/18 02:30:06 DEBUG : Creating backend with remote "TestDrive:crypt/famka70mk01l5in31e13afoej657rn7hsj0fgfdirbsbfjrliqk0/31u3jie661vd5p8j7rtc3hgbh0"
2026/09/18 02:30:08 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-konudap8joto/CopyDest"
2026/09/18 02:30:08 DEBUG : Creating backend with remote "TestDrive:crypt/famka70mk01l5in31e13afoej657rn7hsj0fgfdirbsbfjrliqk0/d09o6po3f7bm6ce32vdgs8h9ls"
2026/09/18 02:30:10 DEBUG : one: Need to transfer - File not found at Destination
2026/09/18 02:30:12 DEBUG : one: md5 = 037e0ff64853add8e39b7f5a2472dd95 OK
2026/09/18 02:30:12 DEBUG : one: size = 3 OK
2026/09/18 02:30:12 INFO  : one: Copied (new)
2026/09/18 02:30:14 DEBUG : one: size = 5 (Local file system at /tmp/rclone257193870)
2026/09/18 02:30:14 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto/dst')
2026/09/18 02:30:14 DEBUG : one: Sizes differ
2026/09/18 02:30:15 DEBUG : one: md5 = cf78accded411d69295a752cb7888734 OK
2026/09/18 02:30:15 DEBUG : one: size = 5 OK
2026/09/18 02:30:15 INFO  : one: Copied (replaced existing)
2026/09/18 02:30:17 DEBUG : dst/one: md5 = d30388e511907550c638e8de02a61130 OK
2026/09/18 02:30:20 DEBUG : CopyDest/one: md5 = bc4afea89b563974d5c10eb454383def OK
2026/09/18 02:30:20 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-konudap8joto/BackupDir"
2026/09/18 02:30:20 DEBUG : Creating backend with remote "TestDrive:crypt/famka70mk01l5in31e13afoej657rn7hsj0fgfdirbsbfjrliqk0/s6dbk3lfi7c9kfvo6j7bla9m0g"
2026/09/18 02:30:22 DEBUG : one: size = 5 (Local file system at /tmp/rclone257193870)
2026/09/18 02:30:22 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto/dst')
2026/09/18 02:30:22 DEBUG : one: Sizes differ
2026/09/18 02:30:22 DEBUG : one: size = 5 OK
2026/09/18 02:30:22 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/09/18 02:30:22 DEBUG : one: size = 5 (Local file system at /tmp/rclone257193870)
2026/09/18 02:30:22 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto/dst')
2026/09/18 02:30:22 DEBUG : one: Sizes differ
2026/09/18 02:30:24 INFO  : one: Moved (server-side)
2026/09/18 02:30:25 DEBUG : one: size = 5 OK
2026/09/18 02:30:25 INFO  : one: Copied (server-side copy)
2026/09/18 02:30:25 DEBUG : one: Destination found in --copy-dest, using server-side copy
2026/09/18 02:30:28 DEBUG : CopyDest/two: md5 = f2842ca6d51e2cacdf1cbdd5449c1fd9 OK
2026/09/18 02:30:28 DEBUG : two: Need to transfer - File not found at Destination
2026/09/18 02:30:29 DEBUG : two: size = 3 OK
2026/09/18 02:30:29 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/09/18 02:30:30 DEBUG : two: size = 3 OK
2026/09/18 02:30:30 INFO  : two: Copied (server-side copy)
2026/09/18 02:30:30 DEBUG : two: Destination found in --copy-dest, using server-side copy
2026/09/18 02:30:30 DEBUG : two: size = 3 OK
2026/09/18 02:30:30 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/09/18 02:30:30 DEBUG : two: Unchanged skipping
2026/09/18 02:30:33 DEBUG : CopyDest/three: md5 = cc1b280c2bb21624377a1e064050916b OK
2026/09/18 02:30:33 DEBUG : three: Need to transfer - File not found at Destination
2026/09/18 02:30:34 DEBUG : three: size = 7 (Local file system at /tmp/rclone257193870)
2026/09/18 02:30:34 DEBUG : three: size = 5 (Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto/CopyDest')
2026/09/18 02:30:34 DEBUG : three: Sizes differ
2026/09/18 02:30:34 DEBUG : three: Destination not found in --copy-dest
2026/09/18 02:30:35 DEBUG : three: md5 = 5d8dcf756a4862f8fbf4cb71e3fecfc5 OK
2026/09/18 02:30:35 DEBUG : three: size = 7 OK
2026/09/18 02:30:35 INFO  : three: Copied (new)
--- PASS: TestCopyFileCopyDest (35.40s)
=== RUN   TestCopyInplace
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
    copy_test.go:371: Partial uploads not supported
--- SKIP: TestCopyInplace (0.54s)
=== RUN   TestCopyLongFileName
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
    copy_test.go:404: Partial uploads not supported
--- SKIP: TestCopyLongFileName (0.48s)
=== RUN   TestCopyLongFileNameCollision
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
    copy_test.go:437: Partial uploads not supported
--- SKIP: TestCopyLongFileNameCollision (0.50s)
=== RUN   TestCopyFileMaxTransfer
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:30:44 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination
2026/09/18 02:30:46 DEBUG : TestCopyFileMaxTransfer/file1: md5 = e9a0022af8bcdf240d016fee5cbf8131 OK
2026/09/18 02:30:46 DEBUG : TestCopyFileMaxTransfer/file1: size = 14 OK
2026/09/18 02:30:46 INFO  : TestCopyFileMaxTransfer/file1: Copied (new)
2026/09/18 02:30:47 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination
2026/09/18 02:30:47 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:500: Expecting error to contain accounting.ErrorMaxTransferLimitReachedFatal
2026/09/18 02:30:48 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination
2026/09/18 02:30:48 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination
2026/09/18 02:30:50 DEBUG : TestCopyFileMaxTransfer/file4: md5 = 6f4ee394def925fd1a61a3909cee2b34 OK
2026/09/18 02:30:50 DEBUG : TestCopyFileMaxTransfer/file4: size = 2062 OK
2026/09/18 02:30:50 INFO  : TestCopyFileMaxTransfer/file4: Copied (new)
--- PASS: TestCopyFileMaxTransfer (9.74s)
=== RUN   TestDeduplicateInteractive
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
    dedupe_test.go:37: Can't run this test without a hash
--- SKIP: TestDeduplicateInteractive (0.49s)
=== RUN   TestDeduplicateSkip
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:30:56 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto': Looking for duplicate names using skip mode.
2026/09/18 02:30:57 NOTICE: one: Found 2 files with duplicate names
2026/09/18 02:30:57 NOTICE: one: Skipping 2 files with duplicate names
--- PASS: TestDeduplicateSkip (4.81s)
=== RUN   TestDeduplicateSizeOnly
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:31:02 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto': Looking for duplicate names using skip mode.
2026/09/18 02:31:03 NOTICE: one: Found 3 files with duplicate names
2026/09/18 02:31:03 NOTICE: one: Deleting 1/2 identical duplicates (size 11)
2026/09/18 02:31:03 INFO  : one: Deleted
2026/09/18 02:31:03 NOTICE: one: Skipping 2 files with duplicate names
--- PASS: TestDeduplicateSizeOnly (6.65s)
=== RUN   TestDeduplicateFirst
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:31:09 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto': Looking for duplicate names using first mode.
2026/09/18 02:31:09 NOTICE: one: Found 3 files with duplicate names
2026/09/18 02:31:10 INFO  : one: Deleted
2026/09/18 02:31:10 INFO  : one: Deleted
2026/09/18 02:31:10 NOTICE: one: Deleted 2 extra copies
--- PASS: TestDeduplicateFirst (6.75s)
=== RUN   TestDeduplicateNewest
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:31:16 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto': Looking for duplicate names using newest mode.
2026/09/18 02:31:16 NOTICE: one: Found 3 files with duplicate names
2026/09/18 02:31:17 INFO  : one: Deleted
2026/09/18 02:31:17 INFO  : one: Deleted
2026/09/18 02:31:17 NOTICE: one: Deleted 2 extra copies
--- PASS: TestDeduplicateNewest (6.77s)
=== RUN   TestDeduplicateNewestByHash
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
    dedupe_test.go:37: Can't run this test without a hash
--- SKIP: TestDeduplicateNewestByHash (0.52s)
=== RUN   TestDeduplicateOldest
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:31:23 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto': Looking for duplicate names using oldest mode.
2026/09/18 02:31:24 NOTICE: one: Found 3 files with duplicate names
2026/09/18 02:31:24 INFO  : one: Deleted
2026/09/18 02:31:25 INFO  : one: Deleted
2026/09/18 02:31:25 NOTICE: one: Deleted 2 extra copies
--- PASS: TestDeduplicateOldest (6.95s)
=== RUN   TestDeduplicateLargest
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:31:30 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto': Looking for duplicate names using largest mode.
2026/09/18 02:31:31 NOTICE: one: Found 3 files with duplicate names
2026/09/18 02:31:31 INFO  : one: Deleted
2026/09/18 02:31:32 INFO  : one: Deleted
2026/09/18 02:31:32 NOTICE: one: Deleted 2 extra copies
--- PASS: TestDeduplicateLargest (7.30s)
=== RUN   TestDeduplicateSmallest
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:31:37 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto': Looking for duplicate names using smallest mode.
2026/09/18 02:31:38 NOTICE: one: Found 3 files with duplicate names
2026/09/18 02:31:38 INFO  : one: Deleted
2026/09/18 02:31:39 INFO  : one: Deleted
2026/09/18 02:31:39 NOTICE: one: Deleted 2 extra copies
--- PASS: TestDeduplicateSmallest (6.73s)
=== RUN   TestDeduplicateRename
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:31:45 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto': Looking for duplicate names using rename mode.
2026/09/18 02:31:46 NOTICE: one.txt: Found 3 files with duplicate names
2026/09/18 02:31:46 INFO  : one-2.txt: renamed from: one.txt
2026/09/18 02:31:47 INFO  : one-3.txt: renamed from: one.txt
2026/09/18 02:31:48 INFO  : one-4.txt: renamed from: one.txt
--- PASS: TestDeduplicateRename (11.02s)
=== RUN   TestDeduplicateRenameManyExisting
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:31:53 DEBUG : one-1.txt: md5 = 595de5be7c87344126f7e04f337dbcbb OK
2026/09/18 02:31:54 DEBUG : one-2.txt: md5 = bf12f46ab40da19cae367a3e3623ae5e OK
2026/09/18 02:31:56 DEBUG : one-3.txt: md5 = 840e7b23c838802d3940d46870a02044 OK
2026/09/18 02:31:57 DEBUG : one-4.txt: md5 = c6ae5a658a3818d58b10832819862ee1 OK
2026/09/18 02:31:59 DEBUG : one-5.txt: md5 = 8877b84f072fe9066c7e886bdb28a8b9 OK
2026/09/18 02:32:00 DEBUG : one-6.txt: md5 = 021ac82070f027e60314587168367f43 OK
2026/09/18 02:32:02 DEBUG : one-7.txt: md5 = e40918574aed9766025e9814a010f70e OK
2026/09/18 02:32:03 DEBUG : one-8.txt: md5 = 6d6bf7313acda49119c4a7b85b3b09ff OK
2026/09/18 02:32:05 DEBUG : one-9.txt: md5 = 4cc454c8143444f741aea64aadc02e26 OK
2026/09/18 02:32:06 DEBUG : one-10.txt: md5 = 7f6f184c400c0f9f1350c3eb0b50ca73 OK
2026/09/18 02:32:08 DEBUG : one-11.txt: md5 = b7cffcad561f35e49d0531fec6fb059e OK
2026/09/18 02:32:09 DEBUG : one-12.txt: md5 = b0cc04c102057c7f88e9b6e416ec2f42 OK
2026/09/18 02:32:11 DEBUG : one-13.txt: md5 = a23c8c4b331f2eadb80713eebd4b518b OK
2026/09/18 02:32:12 DEBUG : one-14.txt: md5 = eee6870d00b14fe607b969e62681fffc OK
2026/09/18 02:32:14 DEBUG : one-15.txt: md5 = dafa657b0c69a849b2d64adc1240c490 OK
2026/09/18 02:32:15 DEBUG : one-16.txt: md5 = 71ec9fe69754fcdc380398da545b578d OK
2026/09/18 02:32:17 DEBUG : one-17.txt: md5 = dc5b341aecc2cd6f0e1d8efef87ca665 OK
2026/09/18 02:32:18 DEBUG : one-18.txt: md5 = a9975ac0dd4c9ad2e09b14f866f855a9 OK
2026/09/18 02:32:20 DEBUG : one-19.txt: md5 = fb0aa6c088f23b886a8e661f1d1a00bf OK
2026/09/18 02:32:21 DEBUG : one-20.txt: md5 = c4d21b8a143808b918701ccb7724b6d6 OK
2026/09/18 02:32:23 DEBUG : one-21.txt: md5 = 4c5c6c46cd4813ec7193d45b0064a5a3 OK
2026/09/18 02:32:25 DEBUG : one-22.txt: md5 = 3541e9d2e80d138f7cae518c138d4776 OK
2026/09/18 02:32:26 DEBUG : one-23.txt: md5 = 16d2cbc9c9e250361588b71a06d980fe OK
2026/09/18 02:32:28 DEBUG : one-24.txt: md5 = 6a9f395107500d2410527a3fe2a723c9 OK
2026/09/18 02:32:29 DEBUG : one-25.txt: md5 = cbf6cb667e4111bb3ff3b2e0983d7f7b OK
2026/09/18 02:32:31 DEBUG : one-26.txt: md5 = 3f2a918614efe7845ed09f9a106e6243 OK
2026/09/18 02:32:32 DEBUG : one-27.txt: md5 = c80fe6e35a0997518617334298645875 OK
2026/09/18 02:32:34 DEBUG : one-28.txt: md5 = c3964b342f68df2624d94ccc4f9f0a89 OK
2026/09/18 02:32:35 DEBUG : one-29.txt: md5 = c2e19166c9352b1bc83c146980376837 OK
2026/09/18 02:32:37 DEBUG : one-30.txt: md5 = 09d65fde35f6a8927fcb0fc99289b7a7 OK
2026/09/18 02:32:38 DEBUG : one-31.txt: md5 = b0768a6823f43347e4205dd5173a66b7 OK
2026/09/18 02:32:40 DEBUG : one-32.txt: md5 = 184857ff61c03f0891a16a171e883a54 OK
2026/09/18 02:32:41 DEBUG : one-33.txt: md5 = 9f60735f391a4b1c72bf37635da53836 OK
2026/09/18 02:32:43 DEBUG : one-34.txt: md5 = 744eb8598fa66445f690bf4dd8ec8fff OK
2026/09/18 02:32:44 DEBUG : one-35.txt: md5 = 082108f0acbf08b081fc754189bc29a2 OK
2026/09/18 02:32:46 DEBUG : one-36.txt: md5 = 52ff5b5cd4c4037954126150a71d7fa2 OK
2026/09/18 02:32:47 DEBUG : one-37.txt: md5 = 30c2185edbf555c7ac72ca645627af15 OK
2026/09/18 02:32:49 DEBUG : one-38.txt: md5 = 26bb2f353b789e3215f042a31b290531 OK
2026/09/18 02:32:51 DEBUG : one-39.txt: md5 = 7111ef4f23ee611e52b8789b1cb0f7f6 OK
2026/09/18 02:32:52 DEBUG : one-40.txt: md5 = c986cec4d3473b5850d4f4c110683601 OK
2026/09/18 02:32:53 DEBUG : one-41.txt: md5 = 2bf36648075762075470973202c5c049 OK
2026/09/18 02:32:55 DEBUG : one-42.txt: md5 = 2c3851317738c4c8e90436943d518a25 OK
2026/09/18 02:32:56 DEBUG : one-43.txt: md5 = 9abad853fc459b5e8094d6ee0c53f4f4 OK
2026/09/18 02:32:58 DEBUG : one-44.txt: md5 = 0dcbbe11ef102d33f0243062a183de89 OK
2026/09/18 02:32:59 DEBUG : one-45.txt: md5 = 27e2eb463b979df2243651ef40808bc7 OK
2026/09/18 02:33:01 DEBUG : one-46.txt: md5 = 86097d80500588ec366eb25f22c328c4 OK
2026/09/18 02:33:02 DEBUG : one-47.txt: md5 = 38d02e29bc185131b6fdd07d39a6b701 OK
2026/09/18 02:33:04 DEBUG : one-48.txt: md5 = 9e575d27cf9ab9b9ddaa59e57d212f18 OK
2026/09/18 02:33:05 DEBUG : one-49.txt: md5 = a3df160f8220e130fd78eb078de63503 OK
2026/09/18 02:33:07 DEBUG : one-50.txt: md5 = 9b5d9ddd4b3a8322877a3137d3f8d24b OK
2026/09/18 02:33:08 DEBUG : one-51.txt: md5 = 6f33b71312fd912358402926bc28bada OK
2026/09/18 02:33:10 DEBUG : one-52.txt: md5 = a97314fb6f7cda9b1cec70cc2b77276b OK
2026/09/18 02:33:11 DEBUG : one-53.txt: md5 = eaa49e743ee1eae95acb7566dc53d3fb OK
2026/09/18 02:33:13 DEBUG : one-54.txt: md5 = f2bf5e60d1db23e4b50f518bfe72dcb5 OK
2026/09/18 02:33:14 DEBUG : one-55.txt: md5 = 8a391fc7befb7d95d171cbdfef93071d OK
2026/09/18 02:33:16 DEBUG : one-56.txt: md5 = becae1642365bea414d3111b52069871 OK
2026/09/18 02:33:17 DEBUG : one-57.txt: md5 = ea07210b188b7acf1b4784d8a4f22136 OK
2026/09/18 02:33:19 DEBUG : one-58.txt: md5 = 04a74947de4ceea66a70bfbc182da8bb OK
2026/09/18 02:33:21 DEBUG : one-59.txt: md5 = 172ce9ae87cbb318c374ab1b54c59c1f OK
2026/09/18 02:33:22 DEBUG : one-60.txt: md5 = 1837b965ea5eb4fbd54c72a8470575fc OK
2026/09/18 02:33:23 DEBUG : one-61.txt: md5 = e76e4237210b2f0c031c1266b3c00ed8 OK
2026/09/18 02:33:25 DEBUG : one-62.txt: md5 = 084e2b90b6017d6aa07ce5a0ca6c2917 OK
2026/09/18 02:33:26 DEBUG : one-63.txt: md5 = a97f1bd8c7f0532b9e9f2b6f4469e705 OK
2026/09/18 02:33:28 DEBUG : one-64.txt: md5 = fa730769deb6f0250853b46c7512560a OK
2026/09/18 02:33:29 DEBUG : one-65.txt: md5 = fca816b08bdc2665885dfee856490014 OK
2026/09/18 02:33:30 DEBUG : one-66.txt: md5 = e642ee9cf18268184ff5d930dc3d00b8 OK
2026/09/18 02:33:32 DEBUG : one-67.txt: md5 = 4a7c40c25502ecd2a948ab6321e524c2 OK
2026/09/18 02:33:33 DEBUG : one-68.txt: md5 = 7bc4827a1e596229adb795cdf1740142 OK
2026/09/18 02:33:35 DEBUG : one-69.txt: md5 = e09dd8f0056c21ad4dd99fef44bf9bd4 OK
2026/09/18 02:33:36 DEBUG : one-70.txt: md5 = 36d6abda982d3c6535aa9fa048f724bb OK
2026/09/18 02:33:38 DEBUG : one-71.txt: md5 = ae924bd234f878b4a27f7e04a6110400 OK
2026/09/18 02:33:39 DEBUG : one-72.txt: md5 = b53d009d36f995bb6dded18977f4cc0b OK
2026/09/18 02:33:41 DEBUG : one-73.txt: md5 = 2a3d38540d9afeea7b267ffcbb100acd OK
2026/09/18 02:33:42 DEBUG : one-74.txt: md5 = b84935cf4e309fd435edf9192af8c9be OK
2026/09/18 02:33:44 DEBUG : one-75.txt: md5 = cc0f3a9c1e320c0bc258175ea7eb3b66 OK
2026/09/18 02:33:45 DEBUG : one-76.txt: md5 = 68935c8e0a7db894240dfe9b00acb71e OK
2026/09/18 02:33:47 DEBUG : one-77.txt: md5 = 235d3e96c9be8334bad23222f1bbf8eb OK
2026/09/18 02:33:48 DEBUG : one-78.txt: md5 = c98b77aad6545e099e8293e5a0083022 OK
2026/09/18 02:33:50 DEBUG : one-79.txt: md5 = 7900b32d2227acb6fb44f874ba426f16 OK
2026/09/18 02:33:51 DEBUG : one-80.txt: md5 = 4a5080827fc61d18d736d3474ca51c49 OK
2026/09/18 02:33:52 DEBUG : one-81.txt: md5 = 79f69c0168e305a9b4e92f1651b58cc8 OK
2026/09/18 02:33:54 DEBUG : one-82.txt: md5 = f0d96cad6745d900841138447f981879 OK
2026/09/18 02:33:55 DEBUG : one-83.txt: md5 = 308af62144ab330e692cbb8f2f043eab OK
2026/09/18 02:33:57 DEBUG : one-84.txt: md5 = ac031d13991d3aa9395fb28636413b5a OK
2026/09/18 02:33:58 DEBUG : one-85.txt: md5 = abd0256594e0ecc4ea524239d1124721 OK
2026/09/18 02:34:00 DEBUG : one-86.txt: md5 = bf334997a35508b509998d51e7b66061 OK
2026/09/18 02:34:01 DEBUG : one-87.txt: md5 = 6bce3b3d45fa8466ac1b09b37cd1b631 OK
2026/09/18 02:34:03 DEBUG : one-88.txt: md5 = b7a299dfcb11a18b9f032ed77d21b02f OK
2026/09/18 02:34:04 DEBUG : one-89.txt: md5 = 013964344d82cae73045856bb92a0ac4 OK
2026/09/18 02:34:06 DEBUG : one-90.txt: md5 = a4fd54bbaecc28bce4c06a67806a206a OK
2026/09/18 02:34:07 DEBUG : one-91.txt: md5 = 5d883d365e68fd18ab99a48d6573113a OK
2026/09/18 02:34:09 DEBUG : one-92.txt: md5 = c7a718965aebe03e3f620345f499ca04 OK
2026/09/18 02:34:10 DEBUG : one-93.txt: md5 = 90cc5567cc87916eb1d2182a09357eae OK
2026/09/18 02:34:12 DEBUG : one-94.txt: md5 = 7bc545320a9e46ff2d21de9606ffd2da OK
2026/09/18 02:34:13 DEBUG : one-95.txt: md5 = 6929b549c16c5570ea38d7240a0df633 OK
2026/09/18 02:34:15 DEBUG : one-96.txt: md5 = 2052fc473eea77ae048754b0b72bce37 OK
2026/09/18 02:34:16 DEBUG : one-97.txt: md5 = 37911c578f15d667e71419aba34383ed OK
2026/09/18 02:34:18 DEBUG : one-98.txt: md5 = bd09a305e80ced3011d042166eb68e3a OK
2026/09/18 02:34:19 DEBUG : one-99.txt: md5 = 9b66b821e37bc046ae48d7c4d51cb5fb OK
2026/09/18 02:34:21 DEBUG : one-100.txt: md5 = 41d723e50effd3b7335b0025d58d9e3e OK
2026/09/18 02:34:22 DEBUG : one-101.txt: md5 = 996377ed8a2db1f781e95441a6672b9c OK
2026/09/18 02:34:24 DEBUG : one-102.txt: md5 = bfd3a71a46b80fa9ab72676124e39dd3 OK
2026/09/18 02:34:25 DEBUG : one-103.txt: md5 = 1a4754297448897829a8316ba0dc874d OK
2026/09/18 02:34:27 DEBUG : one-104.txt: md5 = 6d626e46289a01abd8f27eee3a50d26b OK
2026/09/18 02:34:28 DEBUG : one-105.txt: md5 = 714d1c26ac8004dc3634cc4d1a0abffb OK
2026/09/18 02:34:31 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto': Looking for duplicate names using rename mode.
2026/09/18 02:34:32 NOTICE: one.txt: Found 2 files with duplicate names
2026/09/18 02:34:33 INFO  : one-106.txt: renamed from: one.txt
2026/09/18 02:34:33 INFO  : one-107.txt: renamed from: one.txt
--- PASS: TestDeduplicateRenameManyExisting (210.93s)
=== RUN   TestMergeDirs
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:35:24 DEBUG : dupe1/one.txt: md5 = 0ff31fbfd9668ea1353967b07b5e8278 OK
2026/09/18 02:35:26 DEBUG : dupe2/two.txt: md5 = 4bda05dce8ed1bcf156644520529b684 OK
2026/09/18 02:35:28 DEBUG : dupe3/three.txt: md5 = ec01254bae09d3ead42a54b3570c0681 OK
2026/09/18 02:35:29 INFO  : urj8gsducqbtvekeq181ntd2u8: merging "sfrom47189m9qr3mt4qonu281c"
2026/09/18 02:35:29 INFO  : urj8gsducqbtvekeq181ntd2u8: removing empty directory
2026/09/18 02:35:30 INFO  : 1u1r4ei7c628fnjg9blqt3j60o: merging "tr2hj63d80ftlmvm6a952snjcc"
2026/09/18 02:35:31 INFO  : 1u1r4ei7c628fnjg9blqt3j60o: removing empty directory
--- PASS: TestMergeDirs (12.61s)
=== RUN   TestListDirSorted
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:35:36 DEBUG : a.txt: md5 = 66fad517f8d6ca1576f567d0a8d27f29 OK
2026/09/18 02:35:38 DEBUG : zend.txt: md5 = d18321d38adc7b9bea7895b6d6fb6f42 OK
2026/09/18 02:35:40 DEBUG : sub dir/hello world: md5 = 2f5ffe8c3a6b0018f9631852ea35f7b5 OK
2026/09/18 02:35:42 DEBUG : sub dir/hello world2: md5 = b72a10fd043dab9dfdf2d3be17ffb6cd OK
2026/09/18 02:35:44 DEBUG : sub dir/ignore dir/.ignore: md5 = 8925c46d212c642fe3917a77224525b6 OK
2026/09/18 02:35:45 DEBUG : sub dir/ignore dir/should be ignored: md5 = 1f12694de2e6f196da63a06cc0c4985a OK
2026/09/18 02:35:47 DEBUG : sub dir/sub sub dir/hello world3: md5 = 3a398140ec72da721272cb7f4c9cc616 OK
2026/09/18 02:35:48 DEBUG : a.txt: Excluded (Size Filter)
2026/09/18 02:35:48 DEBUG : a.txt: Excluded
2026/09/18 02:35:49 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2026/09/18 02:35:49 DEBUG : sub dir/hello world2: Excluded
2026/09/18 02:35:49 DEBUG : sub dir/hello world: Excluded (Size Filter)
2026/09/18 02:35:49 DEBUG : sub dir/hello world: Excluded
2026/09/18 02:35:50 DEBUG : sub dir/ignore dir: Excluded
2026/09/18 02:35:50 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2026/09/18 02:35:50 DEBUG : sub dir/hello world2: Excluded
2026/09/18 02:35:50 DEBUG : sub dir/hello world: Excluded (Size Filter)
2026/09/18 02:35:50 DEBUG : sub dir/hello world: Excluded
2026/09/18 02:35:50 DEBUG : sub dir/ignore dir: Excluded
--- PASS: TestListDirSorted (22.47s)
=== RUN   TestListDirSortedFn
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:35:58 DEBUG : a.txt: md5 = e7804680fb1e14c1f83c1b32b70da188 OK
2026/09/18 02:36:00 DEBUG : zend.txt: md5 = ce9a154a7e23761748143a6a775c0004 OK
2026/09/18 02:36:02 DEBUG : sub dir/hello world: md5 = 2be7278cd9f79fd18b126c371e3e1a26 OK
2026/09/18 02:36:03 DEBUG : sub dir/hello world2: md5 = 1298cfa05dfae7115e404ec8ae14d11c OK
2026/09/18 02:36:06 DEBUG : sub dir/ignore dir/.ignore: md5 = d5debc35b871f75c89f8f5a7c986bf71 OK
2026/09/18 02:36:07 DEBUG : sub dir/ignore dir/should be ignored: md5 = b47616efa8c2aa85e5ade7805074901e OK
2026/09/18 02:36:09 DEBUG : sub dir/sub sub dir/hello world3: md5 = 271e70e7908ed5f18537c18d42a58b5b OK
2026/09/18 02:36:11 DEBUG : a.txt: Excluded (Size Filter)
2026/09/18 02:36:11 DEBUG : a.txt: Excluded
2026/09/18 02:36:11 DEBUG : sub dir/hello world: Excluded (Size Filter)
2026/09/18 02:36:11 DEBUG : sub dir/hello world: Excluded
2026/09/18 02:36:11 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2026/09/18 02:36:11 DEBUG : sub dir/hello world2: Excluded
2026/09/18 02:36:12 DEBUG : sub dir/ignore dir: Excluded
2026/09/18 02:36:12 DEBUG : sub dir/hello world: Excluded (Size Filter)
2026/09/18 02:36:12 DEBUG : sub dir/hello world: Excluded
2026/09/18 02:36:12 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2026/09/18 02:36:12 DEBUG : sub dir/hello world2: Excluded
2026/09/18 02:36:12 DEBUG : sub dir/ignore dir: Excluded
--- PASS: TestListDirSortedFn (21.82s)
=== RUN   TestListJSON
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:36:20 DEBUG : file1: md5 = 9f86ad3c4683edba5700db36091a18bb OK
2026/09/18 02:36:22 DEBUG : sub/file2: md5 = 630c012547a06aea0acb208ce14269bf 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/09/18 02:36:25 ERROR : file1: Failed to read hash: hash type not supported
=== RUN   TestListJSON/Metadata
2026/09/18 02:36:26 DEBUG : eer8kka55qnghc34cq76ca668g: Fetching metadata
2026/09/18 02:36:26 DEBUG : 150fuo3cn4j1uenq6r4g4qk6t4: Fetching metadata
--- PASS: TestListJSON (9.82s)
    --- PASS: TestListJSON/Default (0.25s)
    --- PASS: TestListJSON/FilesOnly (0.27s)
    --- PASS: TestListJSON/DirsOnly (0.26s)
    --- PASS: TestListJSON/Recurse (0.50s)
    --- PASS: TestListJSON/SubDir (0.24s)
    --- PASS: TestListJSON/NoModTime (0.24s)
    --- PASS: TestListJSON/NoMimeType (0.23s)
    --- PASS: TestListJSON/ShowHash (0.26s)
    --- PASS: TestListJSON/HashTypes (0.27s)
    --- PASS: TestListJSON/Metadata (0.74s)
=== RUN   TestStatJSON
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:36:30 DEBUG : file1: md5 = 8473a2d847a9bdacd4bb3156877f3519 OK
2026/09/18 02:36:32 DEBUG : sub/file2: md5 = a01203e5086a657dfbba65e7b64c61b8 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/09/18 02:36:36 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-konudap8joto/notfound"
2026/09/18 02:36:36 DEBUG : Config file has changed externally - reloading
2026/09/18 02:36:36 DEBUG : Creating backend with remote "TestDrive:crypt/famka70mk01l5in31e13afoej657rn7hsj0fgfdirbsbfjrliqk0/qe0i31qdkoejk60elps0ni5sqk"
--- PASS: TestStatJSON (11.80s)
    --- PASS: TestStatJSON/Root (0.24s)
    --- PASS: TestStatJSON/RootFilesOnly (0.00s)
    --- PASS: TestStatJSON/RootDirsOnly (0.23s)
    --- PASS: TestStatJSON/Dir (0.50s)
    --- PASS: TestStatJSON/DirWithTrailingSlash (0.24s)
    --- PASS: TestStatJSON/File (0.25s)
    --- PASS: TestStatJSON/NotFound (0.48s)
    --- PASS: TestStatJSON/DirFilesOnly (0.24s)
    --- PASS: TestStatJSON/FileFilesOnly (0.27s)
    --- PASS: TestStatJSON/NotFoundFilesOnly (0.24s)
    --- PASS: TestStatJSON/DirDirsOnly (0.25s)
    --- PASS: TestStatJSON/FileDirsOnly (0.26s)
    --- PASS: TestStatJSON/NotFoundDirsOnly (0.25s)
    --- PASS: TestStatJSON/RootNotFound (1.88s)
=== RUN   TestStatJSONMemory
2026/09/18 02:36:40 DEBUG : Creating backend with remote ":memory:"
2026/09/18 02:36:40 DEBUG : Memory root '': File to upload is small (5 bytes), uploading instead of streaming
2026/09/18 02:36:40 DEBUG : sub/file1: size = 5 OK
2026/09/18 02:36:40 DEBUG : sub/file1: md5 = 5d41402abc4b2a76b9719d911017c592 OK
2026/09/18 02:36:40 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.01s)
    --- 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-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:36:40 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto': Making directory
2026/09/18 02:36:41 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto': Making directory
--- PASS: TestMkdir (0.68s)
=== RUN   TestLsd
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:36:43 DEBUG : sub dir/hello world: md5 = 1376651a07b8ed3ed685d6cbc37b6f68 OK
--- PASS: TestLsd (5.05s)
=== RUN   TestLs
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:36:48 DEBUG : potato2: md5 = 8c075d19e4c62c701abaa6a5569fb428 OK
2026/09/18 02:36:49 DEBUG : empty space: md5 = c98109d36cf6259cf025639c3721c456 OK
--- PASS: TestLs (5.13s)
=== RUN   TestLsWithFilesFrom
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:36:53 DEBUG : potato2: md5 = dfae755820dd65cd8837eea7d1f69e4b OK
2026/09/18 02:36:54 DEBUG : empty space: md5 = bdebf30e68871e803cd7c11e917abe1b OK
2026/09/18 02:36:55 DEBUG : empty space: Excluded (FilesFrom Filter)
2026/09/18 02:36:55 DEBUG : empty space: Excluded
--- PASS: TestLsWithFilesFrom (5.29s)
=== RUN   TestLsLong
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:36:58 DEBUG : potato2: md5 = d54d8392a0d4c940d076995d8bba8389 OK
2026/09/18 02:36:59 DEBUG : empty space: md5 = 73c77269e189d6914e7e20ee89ce8314 OK
--- PASS: TestLsLong (4.79s)
=== RUN   TestHashSums
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:37:03 DEBUG : potato2: md5 = eddeb9fbea36dbbb606eea56d0873a93 OK
2026/09/18 02:37:04 DEBUG : empty space: md5 = 9fd0c5685ed12959fdbf9df5763a2935 OK
--- PASS: TestHashSums (4.54s)
=== RUN   TestHashSumsWithErrors
2026/09/18 02:37:06 DEBUG : Creating backend with remote ":memory:"
2026/09/18 02:37:06 ERROR : file1: hash unsupported: hash type not supported
2026/09/18 02:37:06 ERROR : sub/file1: hash unsupported: hash type not supported
2026/09/18 02:37:06 ERROR : sub/file1: hash unsupported: hash type not supported
--- PASS: TestHashSumsWithErrors (0.00s)
=== RUN   TestHashStream
2026/09/18 02:37:06 DEBUG : Creating md5 hash of 0 bytes read from input stream
2026/09/18 02:37:06 DEBUG : Creating md5 hash of 0 bytes read from input stream
2026/09/18 02:37:06 DEBUG : Creating sha1 hash of 0 bytes read from input stream
2026/09/18 02:37:06 DEBUG : Creating sha1 hash of 0 bytes read from input stream
2026/09/18 02:37:06 DEBUG : Creating md5 hash of 12 bytes read from input stream
2026/09/18 02:37:06 DEBUG : Creating md5 hash of 12 bytes read from input stream
2026/09/18 02:37:06 DEBUG : Creating sha1 hash of 12 bytes read from input stream
2026/09/18 02:37:06 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-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:37:07 DEBUG : potato2: md5 = 74a2f1432c71b54578e76fe5d9b41a88 OK
2026/09/18 02:37:09 DEBUG : empty space: md5 = 9de998defdc724af357329726dce6d4d OK
2026/09/18 02:37:11 DEBUG : sub dir/potato3: md5 = 1baf3602bab0428b87fa07618b1111b5 OK
--- PASS: TestCount (8.44s)
=== RUN   TestDelete
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:37:16 DEBUG : small: md5 = 40f7068f6d3e832ba8975a34c63d4d1a OK
2026/09/18 02:37:17 DEBUG : medium: md5 = 9a1c6e65671fa9416883ebd884c9b7cb OK
2026/09/18 02:37:19 DEBUG : large: md5 = 45dcef7e8af153d8ce3020400a253dd1 OK
2026/09/18 02:37:19 DEBUG : Waiting for deletions to finish
2026/09/18 02:37:20 DEBUG : large: Excluded (Size Filter)
2026/09/18 02:37:20 INFO  : small: Deleted
2026/09/18 02:37:20 INFO  : medium: Deleted
--- PASS: TestDelete (6.94s)
=== RUN   TestMaxDelete
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:37:23 DEBUG : small: md5 = b1f07fdd0dd64450b059aed1c5479500 OK
2026/09/18 02:37:24 DEBUG : medium: md5 = 0af182e0ed40b3108208af543f414ac7 OK
2026/09/18 02:37:26 DEBUG : large: md5 = 8cee65c1e30a61861ce42e67a403b6bd OK
2026/09/18 02:37:26 DEBUG : Waiting for deletions to finish
2026/09/18 02:37:26 ERROR : large: Got fatal error on delete: --max-delete threshold reached
2026/09/18 02:37:27 INFO  : medium: Deleted
2026/09/18 02:37:27 INFO  : small: Deleted
--- PASS: TestMaxDelete (7.07s)
=== RUN   TestMaxDeleteSizeLargeFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:37:30 DEBUG : small: md5 = c84327e55b6e1361eafcce31d49c2ed1 OK
2026/09/18 02:37:31 DEBUG : medium: md5 = 5ba0a8fa95893bcc1f14ed2fb4050577 OK
2026/09/18 02:37:33 DEBUG : large: md5 = d900e41e8c3b7e63631a4be1f97fa5a3 OK
2026/09/18 02:37:33 DEBUG : Waiting for deletions to finish
2026/09/18 02:37:33 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached
2026/09/18 02:37:34 INFO  : medium: Deleted
2026/09/18 02:37:34 INFO  : small: Deleted
--- PASS: TestMaxDeleteSizeLargeFile (7.05s)
=== RUN   TestMaxDeleteSize
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:37:37 DEBUG : small: md5 = 06bb4f401734ac7d7e6bfc4c7f8ac658 OK
2026/09/18 02:37:38 DEBUG : medium: md5 = f2b0af8ea87fbc26bf0a0271e557033c OK
2026/09/18 02:37:40 DEBUG : large: md5 = f54fde598659ced6d5877ab48c49aa0b OK
2026/09/18 02:37:40 DEBUG : Waiting for deletions to finish
2026/09/18 02:37:40 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached
2026/09/18 02:37:41 INFO  : medium: Deleted
2026/09/18 02:37:41 INFO  : small: Deleted
--- PASS: TestMaxDeleteSize (6.88s)
=== RUN   TestReadFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:37:44 DEBUG : ReadFile: md5 = f38a66f26a1dcb769fed9e156fa83efe OK
--- PASS: TestReadFile (4.12s)
=== RUN   TestRetry
2026/09/18 02:37:46 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 1/5
2026/09/18 02:37:46 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 2/5
2026/09/18 02:37:46 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms
2026/09/18 02:37:46 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms
2026/09/18 02:37:46 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms
2026/09/18 02:37:46 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms
2026/09/18 02:37:46 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms
--- PASS: TestRetry (0.05s)
=== RUN   TestCat
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:37:48 DEBUG : file1: md5 = 7d4fa98fe17b806e70e60b54bb56447a OK
2026/09/18 02:37:49 DEBUG : file2: md5 = 6804c09fa75b35e840d0719c54d79db3 OK
--- PASS: TestCat (13.95s)
=== RUN   TestPurge
2026/09/18 02:38:00 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-nuhusoc6dedo"
2026/09/18 02:38:00 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/09/18 02:38:01 DEBUG : Creating backend with remote "TestDrive:crypt/goso32if8f8etnau3igcrp1p7va2icmmottta0p91afqfacqo6g0"
2026/09/18 02:38:01 DEBUG : Creating backend with remote "/tmp/rclone2223582240"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-nuhusoc6dedo'", Local "Local file system at /tmp/rclone2223582240", Modify Window "1ms"
2026/09/18 02:38:07 DEBUG : A1/B1/C1/one: md5 = 92f131d0d4270e68f0ca4d88df40417b OK
2026/09/18 02:38:07 INFO  : A2: Making directory
2026/09/18 02:38:07 INFO  : A1/B2: Making directory
2026/09/18 02:38:08 INFO  : A1/B2/C2: Making directory
2026/09/18 02:38:09 INFO  : A1/B1/C3: Making directory
2026/09/18 02:38:10 INFO  : A3: Making directory
2026/09/18 02:38:10 INFO  : A3/B3: Making directory
2026/09/18 02:38:11 INFO  : A3/B3/C4: Making directory
2026/09/18 02:38:13 DEBUG : A1/two: md5 = 5cd8e7f9d01dcbb84c9ea11c48cdb8aa OK
2026/09/18 02:38:17 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-nuhusoc6dedo': Purge remote
2026/09/18 02:38:17 NOTICE: purge failed: directory not found
--- PASS: TestPurge (16.82s)
=== RUN   TestRmdirsNoLeaveRoot
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:38:21 DEBUG : A1/B1/C1/one: md5 = 0809e2d1c39ef70659d7b512fdb27698 OK
2026/09/18 02:38:21 INFO  : A2: Making directory
2026/09/18 02:38:22 INFO  : A1/B2: Making directory
2026/09/18 02:38:22 INFO  : A1/B2/C2: Making directory
2026/09/18 02:38:23 INFO  : A1/B1/C3: Making directory
2026/09/18 02:38:24 INFO  : A3: Making directory
2026/09/18 02:38:25 INFO  : A3/B3: Making directory
2026/09/18 02:38:25 INFO  : A3/B3/C4: Making directory
2026/09/18 02:38:28 DEBUG : A1/two: md5 = f30294208818eb6004a874e8b43c66b9 OK
2026/09/18 02:38:29 DEBUG : removing 1 level 3 directories
2026/09/18 02:38:29 INFO  : A3/B3/C4: Removing directory
2026/09/18 02:38:32 DEBUG : removing 2 level 3 directories
2026/09/18 02:38:32 INFO  : A1/B2/C2: Removing directory
2026/09/18 02:38:32 INFO  : A1/B1/C3: Removing directory
2026/09/18 02:38:32 DEBUG : removing 2 level 2 directories
2026/09/18 02:38:32 INFO  : A3/B3: Removing directory
2026/09/18 02:38:32 INFO  : A1/B2: Removing directory
2026/09/18 02:38:33 DEBUG : removing 2 level 1 directories
2026/09/18 02:38:33 INFO  : A3: Removing directory
2026/09/18 02:38:33 INFO  : A2: Removing directory
2026/09/18 02:38:37 DEBUG : removing 1 level 3 directories
2026/09/18 02:38:37 INFO  : A1/B1/C1: Removing directory
2026/09/18 02:38:38 DEBUG : removing 1 level 2 directories
2026/09/18 02:38:38 INFO  : A1/B1: Removing directory
2026/09/18 02:38:39 DEBUG : removing 1 level 1 directories
2026/09/18 02:38:39 INFO  : A1: Removing directory
2026/09/18 02:38:40 DEBUG : removing 1 level 0 directories
2026/09/18 02:38:40 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto': Removing directory
--- PASS: TestRmdirsNoLeaveRoot (24.07s)
=== RUN   TestRmdirsLeaveRoot
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:38:42 INFO  : A1: Making directory
2026/09/18 02:38:43 INFO  : A1/B1: Making directory
2026/09/18 02:38:44 INFO  : A1/B1/C1: Making directory
2026/09/18 02:38:46 DEBUG : removing 1 level 3 directories
2026/09/18 02:38:46 INFO  : A1/B1/C1: Removing directory
2026/09/18 02:38:47 DEBUG : removing 1 level 2 directories
2026/09/18 02:38:47 INFO  : A1/B1: Removing directory
--- PASS: TestRmdirsLeaveRoot (8.00s)
=== RUN   TestRmdirsWithFilter
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:38:49 INFO  : A1: Making directory
2026/09/18 02:38:50 INFO  : A1/B1: Making directory
2026/09/18 02:38:51 INFO  : A1/B1/C1: Making directory
2026/09/18 02:38:53 DEBUG : removing 1 level 3 directories
2026/09/18 02:38:53 INFO  : A1/B1/C1: Removing directory
2026/09/18 02:38:54 DEBUG : removing 1 level 2 directories
2026/09/18 02:38:54 INFO  : A1/B1: Removing directory
--- PASS: TestRmdirsWithFilter (7.29s)
=== RUN   TestCopyURL
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:38:58 DEBUG : file1: md5 = 1ac1a9fc8a402566edfc8202e7de76dd OK
2026/09/18 02:38:58 DEBUG : file1: size = 14 OK
2026/09/18 02:38:59 DEBUG : filename.txt: File name found in url
2026/09/18 02:39:00 DEBUG : filename.txt: md5 = 79dd83f97e4b6773d89b366654f00430 OK
2026/09/18 02:39:00 DEBUG : filename.txt: size = 14 OK
2026/09/18 02:39:00 DEBUG : headerfilename.txt: filename found in Content-Disposition header.
2026/09/18 02:39:02 DEBUG : headerfilename.txt: md5 = c6dd2943a5c820d36a2ba53e73315be4 OK
2026/09/18 02:39:02 DEBUG : headerfilename.txt: size = 14 OK
2026/09/18 02:39:03 DEBUG : file2: md5 = ecf63942633d58b4e9dfc9fc8ad22f7d OK
2026/09/18 02:39:03 DEBUG : file2: size = 14 OK
--- PASS: TestCopyURL (9.06s)
=== RUN   TestCopyURLDownloadHeaders
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:39:07 DEBUG : file1: md5 = 93010eb6af928e7cb39f50f5ed4fe898 OK
2026/09/18 02:39:07 DEBUG : file1: size = 14 OK
--- PASS: TestCopyURLDownloadHeaders (2.52s)
=== RUN   TestCopyURLToWriter
--- PASS: TestCopyURLToWriter (0.00s)
=== RUN   TestMoveFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:39:08 DEBUG : file1: Need to transfer - File not found at Destination
2026/09/18 02:39:11 DEBUG : sub/file2: md5 = 67ee40068264039b692ca28a68260cef OK
2026/09/18 02:39:11 DEBUG : sub/file2: size = 14 OK
2026/09/18 02:39:11 INFO  : file1: Copied (new) to: sub/file2
2026/09/18 02:39:11 INFO  : file1: Deleted
2026/09/18 02:39:11 DEBUG : sub/file2: size = 14 OK
2026/09/18 02:39:11 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2026/09/18 02:39:11 DEBUG : file1: Unchanged skipping
2026/09/18 02:39:11 INFO  : file1: Deleted
2026/09/18 02:39:12 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto': don't need to copy/move sub/file2, it is already at target location
--- PASS: TestMoveFile (6.00s)
=== RUN   TestMoveFileWithIgnoreExisting
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:39:14 DEBUG : file1: Need to transfer - File not found at Destination
2026/09/18 02:39:16 DEBUG : file1: md5 = a7436ff4ccd03838e91ebf127eed0d2d OK
2026/09/18 02:39:16 DEBUG : file1: size = 14 OK
2026/09/18 02:39:16 INFO  : file1: Copied (new)
2026/09/18 02:39:16 INFO  : file1: Deleted
2026/09/18 02:39:16 DEBUG : file1: Destination exists, skipping
2026/09/18 02:39:16 DEBUG : file1: Not removing source file as destination file exists and --ignore-existing is set
--- PASS: TestMoveFileWithIgnoreExisting (3.47s)
=== RUN   TestCaseInsensitiveMoveFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
--- PASS: TestCaseInsensitiveMoveFile (0.49s)
=== RUN   TestCaseInsensitiveMoveFileDryRun
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
--- PASS: TestCaseInsensitiveMoveFileDryRun (0.61s)
=== RUN   TestMoveFileBackupDir
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:39:21 DEBUG : dst/file1: md5 = 81acfec15369c62aaa9ea9eae70d1a34 OK
2026/09/18 02:39:22 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-konudap8joto/backup"
2026/09/18 02:39:22 DEBUG : Creating backend with remote "TestDrive:crypt/famka70mk01l5in31e13afoej657rn7hsj0fgfdirbsbfjrliqk0/1nrff024r7pq65ecp72fc28jb0"
2026/09/18 02:39:23 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone257193870)
2026/09/18 02:39:23 DEBUG : dst/file1: size = 18 (Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto')
2026/09/18 02:39:23 DEBUG : dst/file1: Sizes differ
2026/09/18 02:39:26 INFO  : dst/file1: Moved (server-side)
2026/09/18 02:39:27 DEBUG : dst/file1: md5 = aeef6c03e79722f8c79af7c5b1a89fd6 OK
2026/09/18 02:39:27 DEBUG : dst/file1: size = 14 OK
2026/09/18 02:39:27 INFO  : dst/file1: Copied (new)
2026/09/18 02:39:27 INFO  : dst/file1: Deleted
--- PASS: TestMoveFileBackupDir (13.21s)
=== 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   TestDirMove
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:39:34 DEBUG : A1/one: md5 = 85f3c2cded5f8a96ec7d0fe1f17fa403 OK
2026/09/18 02:39:36 DEBUG : A1/two: md5 = d4460aced825292dfc4435447a6075d7 OK
2026/09/18 02:39:38 DEBUG : A1/B1/three: md5 = c804b5b59aea4660a9fce1bc6ba9f087 OK
2026/09/18 02:39:40 DEBUG : A1/B1/C1/four: md5 = e865280ddb98574f17db7f691ca3469e OK
2026/09/18 02:39:42 DEBUG : A1/B1/C2/five: md5 = f3c011258e04e1be3068234828df522d OK
2026/09/18 02:39:42 INFO  : A1/B2: Making directory
2026/09/18 02:39:43 INFO  : A1/B1/C3: Making directory
2026/09/18 02:39:53 INFO  : A2/one: Moved (server-side) to: A3/one
2026/09/18 02:39:53 INFO  : A2/B1/C1/four: Moved (server-side) to: A3/B1/C1/four
2026/09/18 02:39:53 INFO  : A2/B1/C2/five: Moved (server-side) to: A3/B1/C2/five
2026/09/18 02:39:53 INFO  : A2/two: Moved (server-side) to: A3/two
2026/09/18 02:39:53 INFO  : A2/B1/three: Moved (server-side) to: A3/B1/three
2026/09/18 02:39:58 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto': Can't DirMove - falling back to file moves: can't move directory - incompatible remotes
2026/09/18 02:40:05 INFO  : A3/two: Moved (server-side) to: A4/two
2026/09/18 02:40:05 INFO  : A3/B1/C1/four: Moved (server-side) to: A4/B1/C1/four
2026/09/18 02:40:05 INFO  : A3/B1/C2/five: Moved (server-side) to: A4/B1/C2/five
2026/09/18 02:40:05 INFO  : A3/B1/three: Moved (server-side) to: A4/B1/three
2026/09/18 02:40:05 INFO  : A3/one: Moved (server-side) to: A4/one
--- PASS: TestDirMove (46.12s)
=== RUN   TestGetFsInfo
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
--- PASS: TestGetFsInfo (0.62s)
=== RUN   TestRcat
=== RUN   TestRcat/withChecksum=false,ignoreChecksum=false
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:40:19 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto': File to upload is small (34 bytes), uploading instead of streaming
2026/09/18 02:40:21 DEBUG : no_checksum_small_file_from_pipe: md5 = c97bfe7284f7c851eec2ad91f6f5e7bf OK
2026/09/18 02:40:21 DEBUG : no_checksum_small_file_from_pipe: size = 34 OK
2026/09/18 02:40:21 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only
2026/09/18 02:40:21 DEBUG : no_checksum_small_file_from_pipe: Size of src and dst objects identical
2026/09/18 02:40:21 DEBUG : qc9k4uauqa9ls6a2r6lo8jf4b1m7r0510pkurois1cc70egbs4h0: Sending chunk 0 length 102465
2026/09/18 02:40:22 DEBUG : no_checksum_big_file_from_pipe: md5 = c61b1c4412298284bcbe59e72cc2a2d5 OK
2026/09/18 02:40:22 DEBUG : no_checksum_big_file_from_pipe: size = 102401 OK
2026/09/18 02:40:22 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-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:40:24 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto': File to upload is small (34 bytes), uploading instead of streaming
2026/09/18 02:40:26 DEBUG : with_checksum_small_file_from_pipe: md5 = 47e7a45f05a91dea2ed47e8df696fce4 OK
2026/09/18 02:40:26 DEBUG : with_checksum_small_file_from_pipe: size = 34 OK
2026/09/18 02:40:26 DEBUG : with_checksum_small_file_from_pipe: Size of src and dst objects identical
2026/09/18 02:40:27 DEBUG : e74jo8a7lr1aimg2u78fct67gnuetc1qujiofaci0c8fnq7rjt1d4i296pcklqhgdihs03etvgdi4: Sending chunk 0 length 102465
2026/09/18 02:40:28 DEBUG : with_checksum_big_file_from_pipe: md5 = c500c2da4687ba8af1c7eb3f89e529a6 OK
2026/09/18 02:40:28 DEBUG : with_checksum_big_file_from_pipe: size = 102401 OK
2026/09/18 02:40:28 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-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:40:29 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto': File to upload is small (34 bytes), uploading instead of streaming
2026/09/18 02:40:31 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK
2026/09/18 02:40:31 DEBUG : ignore_checksum_small_file_from_pipe: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2026/09/18 02:40:32 DEBUG : 1963rn6olajfbjn4nbqi9nacu7bk879vpf0d2bg8r0ndcqqsfntob0urt056p0qujf574rqnb5f62: Sending chunk 0 length 102465
2026/09/18 02:40:33 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK
2026/09/18 02:40:33 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-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:40:35 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto': File to upload is small (34 bytes), uploading instead of streaming
2026/09/18 02:40:36 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK
2026/09/18 02:40:36 DEBUG : ignore_checksum_small_file_from_pipe: Size of src and dst objects identical
2026/09/18 02:40:37 DEBUG : 1963rn6olajfbjn4nbqi9nacu7bk879vpf0d2bg8r0ndcqqsfntob0urt056p0qujf574rqnb5f62: Sending chunk 0 length 102465
2026/09/18 02:40:38 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK
2026/09/18 02:40:38 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical
--- PASS: TestRcat (20.66s)
    --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (5.45s)
    --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (5.00s)
    --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (5.14s)
    --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (5.07s)
=== RUN   TestRcatMetadata
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
=== RUN   TestRcatMetadata/Normal
2026/09/18 02:40:40 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto': File to upload is small (48 bytes), uploading instead of streaming
2026/09/18 02:40:41 DEBUG : rcat_metadata: md5 = 8be8d5daf83e0b9b0980ac8763d44b52 OK
2026/09/18 02:40:41 DEBUG : rcat_metadata: size = 48 OK
2026/09/18 02:40:41 DEBUG : rcat_metadata: Size of src and dst objects identical
=== RUN   TestRcatMetadata/ViaDisk
2026/09/18 02:40:43 DEBUG : rijssoobhbn85c5p1imhg55m6frank1nlplla6hkdjlqmqb2471g: Sending chunk 0 length 111
2026/09/18 02:40:44 DEBUG : rcat_metadata_uploadcutoff0: md5 = 1a5c6cec1c988358e74ddcbde11ae70f OK
2026/09/18 02:40:44 DEBUG : rcat_metadata_uploadcutoff0: size = 63 OK
2026/09/18 02:40:44 DEBUG : rcat_metadata_uploadcutoff0: Size of src and dst objects identical
--- PASS: TestRcatMetadata (6.28s)
    --- PASS: TestRcatMetadata/Normal (2.85s)
    --- PASS: TestRcatMetadata/ViaDisk (2.91s)
=== RUN   TestRcatSize
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:40:47 DEBUG : potato1: md5 = 3a1e5092969e78bee62bfb917ae9fa81 OK
2026/09/18 02:40:47 DEBUG : potato1: size = 60 OK
2026/09/18 02:40:47 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto': File to upload is small (60 bytes), uploading instead of streaming
2026/09/18 02:40:49 DEBUG : potato2: md5 = 80a5312d47280ca6b8b57df205927721 OK
2026/09/18 02:40:49 DEBUG : potato2: size = 60 OK
2026/09/18 02:40:49 DEBUG : potato2: Size of src and dst objects identical
--- PASS: TestRcatSize (4.73s)
=== RUN   TestRcatSizeShortEOF
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:40:52 DEBUG : potato1: md5 = 3bc74380ef4413f9e001339f09534cc7 OK
2026/09/18 02:40:52 DEBUG : potato1: size = 120 (Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto')
2026/09/18 02:40:52 DEBUG : potato1: size = 60 (Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto')
2026/09/18 02:40:52 ERROR : potato1: corrupted on transfer: sizes differ src 120 vs dst(Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto') 60
2026/09/18 02:40:52 INFO  : potato1: Removing failed copy
--- PASS: TestRcatSizeShortEOF (2.54s)
=== RUN   TestRcatSizeMetadata
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:40:54 DEBUG : potato1: md5 = 855ba7d838c91bd293bd54f4472ec3f4 OK
2026/09/18 02:40:54 DEBUG : potato1: size = 60 OK
2026/09/18 02:40:54 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto': File to upload is small (60 bytes), uploading instead of streaming
2026/09/18 02:40:56 DEBUG : potato2: md5 = 0b19cf3f5a27c01a04a0845ace397f11 OK
2026/09/18 02:40:56 DEBUG : potato2: size = 60 OK
2026/09/18 02:40:56 DEBUG : potato2: Size of src and dst objects identical
--- PASS: TestRcatSizeMetadata (5.40s)
=== RUN   TestRcatSizeUploadHeaders
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:41:00 DEBUG : potato1: md5 = e3c08b4c8f367eb270eafc642045ed5a OK
2026/09/18 02:41:00 DEBUG : potato1: size = 60 OK
--- PASS: TestRcatSizeUploadHeaders (2.46s)
=== RUN   TestRcatSizeChecksum
=== RUN   TestRcatSizeChecksum/Corrupted
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
    operations_test.go:1797: Skipping as destination doesn't support hashes
=== RUN   TestRcatSizeChecksum/SizeDiffers
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:41:03 DEBUG : potato4: md5 = 26e38b479e4b062038bd8a3adb9a2818 OK
2026/09/18 02:41:03 DEBUG : potato4: size = 60 (Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto')
2026/09/18 02:41:03 DEBUG : potato4: size = 59 (Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto')
2026/09/18 02:41:03 ERROR : potato4: corrupted on transfer: sizes differ src 60 vs dst(Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto') 59
2026/09/18 02:41:03 INFO  : potato4: Removing failed copy
=== RUN   TestRcatSizeChecksum/IgnoreChecksum
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:41:06 DEBUG : potato2: size = 60 OK
=== RUN   TestRcatSizeChecksum/NoHashes
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:41:08 DEBUG : potato3: md5 = 0a9c1a61487d37c71ed6430d0b7558dd OK
2026/09/18 02:41:08 DEBUG : potato3: size = 60 OK
--- PASS: TestRcatSizeChecksum (8.21s)
    --- SKIP: TestRcatSizeChecksum/Corrupted (0.48s)
    --- PASS: TestRcatSizeChecksum/SizeDiffers (2.64s)
    --- PASS: TestRcatSizeChecksum/IgnoreChecksum (2.55s)
    --- PASS: TestRcatSizeChecksum/NoHashes (2.54s)
=== RUN   TestTouchDir
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:41:11 DEBUG : potato2: md5 = 2bd1e3446d20fc18b1f50d8ab860211f OK
2026/09/18 02:41:12 DEBUG : empty space: md5 = c27901811c7848797e0d929a937d7b57 OK
2026/09/18 02:41:14 DEBUG : sub dir/potato3: md5 = 4fddaf30cf89ceb637c7891489e042b6 OK
2026/09/18 02:41:15 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto': Touching "sub dir/potato3"
2026/09/18 02:41:15 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto': Touching "empty space"
2026/09/18 02:41:15 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto': Touching "potato2"
--- PASS: TestTouchDir (9.80s)
=== RUN   TestMkdirMetadata
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:41:19 DEBUG : dir with metadata: Making directory with metadata
2026/09/18 02:41:20 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-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:41:21 DEBUG : directory with modtime: Making directory with metadata
2026/09/18 02:41:22 INFO  : directory with modtime: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2026/09/18 02:41:22 NOTICE: dry run directory with modtime: Skipped make directory as --dry-run is set
--- PASS: TestMkdirModTime (2.41s)
=== RUN   TestCopyDirMetadata
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:41:24 DEBUG : dir with metadata to be copied: Making directory with metadata
2026/09/18 02:41:24 INFO  : dir with metadata to be copied: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z)
2026/09/18 02:41:24 DEBUG : Google drive root 'crypt/famka70mk01l5in31e13afoej657rn7hsj0fgfdirbsbfjrliqk0': Skipping btime metadata as can't update it on an existing file: 2026-09-18T02:41:24.142944073Z
2026/09/18 02:41:24 INFO  : non existent directory: Updated directory metadata
2026/09/18 02:41:26 DEBUG : Google drive root 'crypt/famka70mk01l5in31e13afoej657rn7hsj0fgfdirbsbfjrliqk0': Skipping btime metadata as can't update it on an existing file: 2026-09-18T02:41:24.142944073Z
2026/09/18 02:41:27 INFO  : existing directory: Updated directory metadata
--- PASS: TestCopyDirMetadata (4.71s)
=== RUN   TestSetDirModTime
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:41:28 DEBUG : set modtime on non existent directory: Skipping set directory modification time as --no-update-dir-modtime is set
2026/09/18 02:41:30 INFO  : set modtime on existing directory: Set directory modification time (using SetModTime)
2026/09/18 02:41:31 INFO  : set modtime on existing directory: Set directory modification time (using DirSetModTime)
--- PASS: TestSetDirModTime (4.16s)
=== RUN   TestDirsEqual
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:41:33 DEBUG : dir with metadata to be copied: Making directory with metadata
2026/09/18 02:41:33 INFO  : dir with metadata to be copied: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z)
2026/09/18 02:41:33 DEBUG : dst: Making directory with metadata
2026/09/18 02:41:33 INFO  : dst: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z)
2026/09/18 02:41:33 DEBUG : dst: Directory modification time the same (differ by -999.999µs, within tolerance 1ms)
2026/09/18 02:41:34 INFO  : dst: Set directory modification time (using SetModTime)
2026/09/18 02:41:34 INFO  : dst: Set directory modification time (using SetModTime)
2026/09/18 02:41:34 DEBUG : dst: Directory modification time the same (differ by 1ns, within tolerance 1ms)
2026/09/18 02:41:35 INFO  : dst: Set directory modification time (using SetModTime)
2026/09/18 02:41:35 DEBUG : dst: Destination directory is newer than source, skipping
--- PASS: TestDirsEqual (3.60s)
=== RUN   TestRemoveExisting
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto'", Local "Local file system at /tmp/rclone257193870", Modify Window "1ms"
2026/09/18 02:41:38 DEBUG : sub dir/test remove existing: md5 = a63624549c700f4ff0968dd9194d200c OK
2026/09/18 02:41:40 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: md5 = 57db8b75f4f447614977e5dbbbc455e8 OK
2026/09/18 02:41:42 DEBUG : sub dir/test remove existing: TEST: renaming existing object to "sub dir/test remove existing.barezos2" before starting
2026/09/18 02:41:43 DEBUG : sub dir/test remove existing.barezos2: TEST: removing renamed existing file after operation
2026/09/18 02:41:44 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: TEST: renaming existing object to "sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.majozof9" before starting
2026/09/18 02:41:45 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.majozof9: TEST: renaming existing back after failed operation
2026/09/18 02:41:46 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: TEST: renaming existing object to "sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.daroqug8" before starting
2026/09/18 02:41:47 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.daroqug8: TEST: removing renamed existing file after operation
--- PASS: TestRemoveExisting (13.70s)
=== 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/09/18 02:41:50 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-konudap8joto': Purge remote
"./operations.test -test.v -test.timeout 1h0m0s -remote TestCryptDrive: -verbose" - Finished OK in 14m49.352269579s (try 1/5)