"./operations.test -test.v -test.timeout 1h0m0s -remote TestCryptDrive: -verbose -fast-list" - Starting (try 1/5)
2026/09/09 04:31:53 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jowotal3yepa"
2026/09/09 04:31:53 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/09/09 04:31:53 DEBUG : Creating backend with remote "TestDrive:crypt/7j5po6ap6o56utp59ssrp5mv5n67cnn3t2p2ou9rupj0tnhfqp0g"
2026/09/09 04:31:54 DEBUG : Creating backend with remote "/tmp/rclone3461562559"
=== 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-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
    multithread_test.go:120: multithread writing not supported
--- SKIP: TestMultithreadCopy (0.24s)
=== RUN   TestMultithreadCopyAbort
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
    multithread_test.go:120: multithread writing not supported
--- SKIP: TestMultithreadCopyAbort (0.25s)
=== RUN   TestMultithreadCopyWriterAtErrors
=== RUN   TestMultithreadCopyWriterAtErrors/open/fatal=false
2026/09/09 04:31:55 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt
=== RUN   TestMultithreadCopyWriterAtErrors/open/fatal=true
2026/09/09 04:31:55 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt
=== RUN   TestMultithreadCopyWriterAtErrors/write/fatal=false
2026/09/09 04:31:55 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt
2026/09/09 04:31:55 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams
2026/09/09 04:31:55 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting
2026/09/09 04:31:55 DEBUG : file.txt: writing chunk 1
2026/09/09 04:31:55 DEBUG : file.txt: multi-thread copy: chunk 2/4 failed: multi-thread copy: failed to write chunk: disk full
2026/09/09 04:31:55 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting
2026/09/09 04:31:55 DEBUG : file.txt: writing chunk 0
2026/09/09 04:31:55 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting
2026/09/09 04:31:55 DEBUG : file.txt: writing chunk 2
2026/09/09 04:31:55 DEBUG : file.txt: multi-thread copy: chunk 1/4 failed: multi-thread copy: failed to write chunk: disk full
2026/09/09 04:31:55 DEBUG : file.txt: multi-thread copy: chunk 3/4 failed: multi-thread copy: failed to write chunk: disk full
2026/09/09 04:31:55 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit
2026/09/09 04:31:55 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/09 04:31:55 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt
2026/09/09 04:31:55 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams
2026/09/09 04:31:55 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting
2026/09/09 04:31:55 DEBUG : file.txt: writing chunk 1
2026/09/09 04:31:55 DEBUG : file.txt: multi-thread copy: chunk 2/4 failed: multi-thread copy: failed to write chunk: disk full
2026/09/09 04:31:55 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting
2026/09/09 04:31:55 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting
2026/09/09 04:31:55 DEBUG : file.txt: writing chunk 2
2026/09/09 04:31:55 DEBUG : file.txt: multi-thread copy: chunk 3/4 failed: multi-thread copy: failed to write chunk: disk full
2026/09/09 04:31:55 DEBUG : file.txt: writing chunk 0
2026/09/09 04:31:55 DEBUG : file.txt: multi-thread copy: chunk 1/4 failed: multi-thread copy: failed to write chunk: disk full
2026/09/09 04:31:55 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit
2026/09/09 04:31:55 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/09 04:31:55 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt
2026/09/09 04:31:55 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams
2026/09/09 04:31:55 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting
2026/09/09 04:31:55 DEBUG : file.txt: writing chunk 1
2026/09/09 04:31:55 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting
2026/09/09 04:31:55 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 finished
2026/09/09 04:31:55 DEBUG : file.txt: writing chunk 0
2026/09/09 04:31:55 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 finished
2026/09/09 04:31:55 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 starting
2026/09/09 04:31:55 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting
2026/09/09 04:31:55 DEBUG : file.txt: writing chunk 3
2026/09/09 04:31:55 DEBUG : file.txt: writing chunk 2
2026/09/09 04:31:55 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 finished
2026/09/09 04:31:55 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 finished
2026/09/09 04:31:55 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit
2026/09/09 04:31:55 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/09 04:31:55 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt
2026/09/09 04:31:55 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams
2026/09/09 04:31:55 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting
2026/09/09 04:31:55 DEBUG : file.txt: writing chunk 1
2026/09/09 04:31:55 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting
2026/09/09 04:31:55 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 finished
2026/09/09 04:31:55 DEBUG : file.txt: writing chunk 0
2026/09/09 04:31:55 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 finished
2026/09/09 04:31:55 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting
2026/09/09 04:31:55 DEBUG : file.txt: writing chunk 2
2026/09/09 04:31:55 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 starting
2026/09/09 04:31:55 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 finished
2026/09/09 04:31:55 DEBUG : file.txt: writing chunk 3
2026/09/09 04:31:55 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 finished
2026/09/09 04:31:55 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit
2026/09/09 04:31:55 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/09 04:31:55 DEBUG : a: size = 0 OK
2026/09/09 04:31:55 DEBUG : a: size = 1 (memory)
2026/09/09 04:31:55 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/09 04:31:55 DEBUG : potato: Seek from 10 to 0
=== RUN   TestReOpen/Normal/ErrorAtStart
=== RUN   TestReOpen/Normal/WithErrors
2026/09/09 04:31:55 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2026/09/09 04:31:55 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2026/09/09 04:31:55 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN   TestReOpen/Normal/TooManyErrors
2026/09/09 04:31:55 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2026/09/09 04:31:55 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2026/09/09 04:31:55 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2026/09/09 04:31:55 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN   TestReOpen/Normal/ReadAt
2026/09/09 04:31:55 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/09/09 04:31:55 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/09/09 04:31:55 DEBUG : potato: Seek from 5 to 1
=== RUN   TestReOpen/Normal/Seek
2026/09/09 04:31:55 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/09/09 04:31:55 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/09/09 04:31:55 DEBUG : potato: Seek from 5 to 2
=== RUN   TestReOpen/Normal/AccountRead
=== RUN   TestReOpen/Normal/AccountReadDelay
2026/09/09 04:31:55 DEBUG : potato: Seek from 10 to 0
2026/09/09 04:31:55 DEBUG : potato: Seek from 10 to 0
2026/09/09 04:31:55 DEBUG : potato: Seek from 10 to 0
=== RUN   TestReOpen/Normal/AccountReadError
=== RUN   TestReOpen/WithRangeOption
=== RUN   TestReOpen/WithRangeOption/Basics
2026/09/09 04:31:55 DEBUG : potato: Seek from 7 to 0
=== RUN   TestReOpen/WithRangeOption/ErrorAtStart
=== RUN   TestReOpen/WithRangeOption/WithErrors
2026/09/09 04:31:55 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2026/09/09 04:31:55 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2026/09/09 04:31:55 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN   TestReOpen/WithRangeOption/TooManyErrors
2026/09/09 04:31:55 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2026/09/09 04:31:55 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2026/09/09 04:31:55 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2026/09/09 04:31:55 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN   TestReOpen/WithRangeOption/ReadAt
2026/09/09 04:31:55 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/09/09 04:31:55 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/09/09 04:31:55 DEBUG : potato: Seek from 5 to 1
=== RUN   TestReOpen/WithRangeOption/Seek
2026/09/09 04:31:55 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/09/09 04:31:55 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/09/09 04:31:55 DEBUG : potato: Seek from 5 to 2
2026/09/09 04:31:55 DEBUG : potato: Seek from 7 to 4
=== RUN   TestReOpen/WithRangeOption/AccountRead
=== RUN   TestReOpen/WithRangeOption/AccountReadDelay
2026/09/09 04:31:55 DEBUG : potato: Seek from 7 to 0
2026/09/09 04:31:55 DEBUG : potato: Seek from 7 to 0
2026/09/09 04:31:55 DEBUG : potato: Seek from 7 to 0
=== RUN   TestReOpen/WithRangeOption/AccountReadError
=== RUN   TestReOpen/WithSeekOption
=== RUN   TestReOpen/WithSeekOption/Basics
2026/09/09 04:31:55 DEBUG : potato: Seek from 8 to 0
=== RUN   TestReOpen/WithSeekOption/ErrorAtStart
=== RUN   TestReOpen/WithSeekOption/WithErrors
2026/09/09 04:31:55 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2026/09/09 04:31:55 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2026/09/09 04:31:55 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN   TestReOpen/WithSeekOption/TooManyErrors
2026/09/09 04:31:55 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2026/09/09 04:31:55 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2026/09/09 04:31:55 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2026/09/09 04:31:55 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN   TestReOpen/WithSeekOption/ReadAt
2026/09/09 04:31:55 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/09/09 04:31:55 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/09/09 04:31:55 DEBUG : potato: Seek from 5 to 1
=== RUN   TestReOpen/WithSeekOption/Seek
2026/09/09 04:31:55 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/09/09 04:31:55 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/09/09 04:31:55 DEBUG : potato: Seek from 5 to 2
2026/09/09 04:31:55 DEBUG : potato: Seek from 7 to 5
=== RUN   TestReOpen/WithSeekOption/AccountRead
=== RUN   TestReOpen/WithSeekOption/AccountReadDelay
2026/09/09 04:31:55 DEBUG : potato: Seek from 8 to 0
2026/09/09 04:31:55 DEBUG : potato: Seek from 8 to 0
2026/09/09 04:31:55 DEBUG : potato: Seek from 8 to 0
=== RUN   TestReOpen/WithSeekOption/AccountReadError
=== RUN   TestReOpen/UnknownSize
=== RUN   TestReOpen/UnknownSize/Basics
2026/09/09 04:31:55 DEBUG : potato: Seek from 9 to 0
=== RUN   TestReOpen/UnknownSize/ErrorAtStart
=== RUN   TestReOpen/UnknownSize/WithErrors
2026/09/09 04:31:55 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2026/09/09 04:31:55 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2026/09/09 04:31:55 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN   TestReOpen/UnknownSize/TooManyErrors
2026/09/09 04:31:55 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2026/09/09 04:31:55 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2026/09/09 04:31:55 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2026/09/09 04:31:55 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN   TestReOpen/UnknownSize/ReadAt
2026/09/09 04:31:55 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/09/09 04:31:55 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/09/09 04:31:55 DEBUG : potato: Seek from 5 to 1
=== RUN   TestReOpen/UnknownSize/Seek
2026/09/09 04:31:55 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/09/09 04:31:55 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/09/09 04:31:55 DEBUG : potato: Seek from 5 to 2
2026/09/09 04:31:55 DEBUG : potato: Seek from 7 to 6
=== RUN   TestReOpen/UnknownSize/AccountRead
=== RUN   TestReOpen/UnknownSize/AccountReadDelay
2026/09/09 04:31:55 DEBUG : potato: Seek from 9 to 0
2026/09/09 04:31:55 DEBUG : potato: Seek from 9 to 0
2026/09/09 04:31:55 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-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:31:57 DEBUG : rutabaga: md5 = 4bc9af13d634ce32bf9b47aaf6037e33 OK
=== RUN   TestCheck/1
=== RUN   TestCheck/2
2026/09/09 04:32:00 DEBUG : empty space: md5 = 0972a024bfc7d96b68d0ac4935f89227 OK
=== RUN   TestCheck/3
2026/09/09 04:32:02 DEBUG : potato2: md5 = 5d3bee81f693133c11555502d1a36f14 OK
=== RUN   TestCheck/4
=== RUN   TestCheck/5
2026/09/09 04:32:04 DEBUG : remotepotato: md5 = 6278c5a7804b43510010fab3abd0fc35 OK
=== RUN   TestCheck/6
=== RUN   TestCheck/7
--- PASS: TestCheck (13.19s)
    --- PASS: TestCheck/1 (0.31s)
    --- PASS: TestCheck/2 (0.30s)
    --- PASS: TestCheck/3 (0.28s)
    --- PASS: TestCheck/4 (0.30s)
    --- PASS: TestCheck/5 (0.30s)
    --- PASS: TestCheck/6 (0.28s)
    --- PASS: TestCheck/7 (0.30s)
=== RUN   TestCheckFsError
2026/09/09 04:32:08 DEBUG : Creating backend with remote "nonexistent"
2026/09/09 04:32:08 DEBUG : Creating backend with remote "nonexistent"
2026/09/09 04:32:08 DEBUG : Local file system at nonexistent: Waiting for checks to finish
2026/09/09 04:32:08 ERROR : Local file system at nonexistent: error reading source root directory: directory not found
2026/09/09 04:32:08 NOTICE: Local file system at nonexistent: 0 differences found
2026/09/09 04:32:08 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-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:32:10 DEBUG : rutabaga: md5 = 2bc8ac093344f940b2dca53b255e2be9 OK
=== RUN   TestCheckDownload/1
=== RUN   TestCheckDownload/2
2026/09/09 04:32:13 DEBUG : empty space: md5 = c2c5a790378b3e3b96aa0a6ddcf0df6f OK
=== RUN   TestCheckDownload/3
2026/09/09 04:32:16 DEBUG : potato2: md5 = 3badde186ef04d5b8c52515e01159020 OK
=== RUN   TestCheckDownload/4
=== RUN   TestCheckDownload/5
2026/09/09 04:32:20 DEBUG : remotepotato: md5 = 3f3b7c3c3e982ca4f70fcc62a730c48b OK
=== RUN   TestCheckDownload/6
=== RUN   TestCheckDownload/7
--- PASS: TestCheckDownload (16.48s)
    --- PASS: TestCheckDownload/1 (1.05s)
    --- PASS: TestCheckDownload/2 (0.86s)
    --- PASS: TestCheckDownload/3 (0.97s)
    --- PASS: TestCheckDownload/4 (1.05s)
    --- PASS: TestCheckDownload/5 (0.86s)
    --- PASS: TestCheckDownload/6 (0.85s)
    --- PASS: TestCheckDownload/7 (0.86s)
=== RUN   TestCheckSizeOnly
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:32:26 DEBUG : rutabaga: md5 = ad01313d3aacfc0d6e27bd6a5595d288 OK
=== RUN   TestCheckSizeOnly/1
=== RUN   TestCheckSizeOnly/2
2026/09/09 04:32:28 DEBUG : empty space: md5 = a6b7bab155a8bef29f0c27d41d4fa0f5 OK
=== RUN   TestCheckSizeOnly/3
2026/09/09 04:32:30 DEBUG : potato2: md5 = cd8df7f5502e765a925771a0f7039281 OK
=== RUN   TestCheckSizeOnly/4
=== RUN   TestCheckSizeOnly/5
2026/09/09 04:32:33 DEBUG : remotepotato: md5 = 15dcb72304adf1a5c3c7ce6224f6f203 OK
=== RUN   TestCheckSizeOnly/6
=== RUN   TestCheckSizeOnly/7
--- PASS: TestCheckSizeOnly (12.08s)
    --- PASS: TestCheckSizeOnly/1 (0.29s)
    --- PASS: TestCheckSizeOnly/2 (0.29s)
    --- PASS: TestCheckSizeOnly/3 (0.26s)
    --- PASS: TestCheckSizeOnly/4 (0.29s)
    --- PASS: TestCheckSizeOnly/5 (0.31s)
    --- PASS: TestCheckSizeOnly/6 (0.30s)
    --- PASS: TestCheckSizeOnly/7 (0.29s)
=== RUN   TestCheckEqualReaders
--- PASS: TestCheckEqualReaders (0.00s)
=== RUN   TestParseSumFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:32:38 DEBUG : test.sum: md5 = 8da74a636477edd274d8e49cead90849 OK
2026/09/09 04:32:39 NOTICE: test.sum: improperly formatted checksum line 4
2026/09/09 04:32:39 NOTICE: test.sum: improperly formatted checksum line 5
2026/09/09 04:32:39 NOTICE: test.sum: improperly formatted checksum line 6
2026/09/09 04:32:39 NOTICE: test.sum: 2 warning(s) suppressed...
2026/09/09 04:32:41 DEBUG : test.sum: md5 = 12835a3e626010d0cc9599914070225b OK
2026/09/09 04:32:42 NOTICE: test.sum: improperly formatted checksum line 4
2026/09/09 04:32:42 NOTICE: test.sum: improperly formatted checksum line 5
2026/09/09 04:32:42 NOTICE: test.sum: improperly formatted checksum line 6
2026/09/09 04:32:42 NOTICE: test.sum: 2 warning(s) suppressed...
--- PASS: TestParseSumFile (6.57s)
=== RUN   TestCheckSum
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:32:43 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jowotal3yepa/data"
2026/09/09 04:32:43 DEBUG : Creating backend with remote "TestDrive:crypt/7j5po6ap6o56utp59ssrp5mv5n67cnn3t2p2ou9rupj0tnhfqp0g/vjrnln8ratgmqakfosrqe8espk"
    check_test.go:354: Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa/data' lacks md5, skipping
--- SKIP: TestCheckSum (2.17s)
=== RUN   TestCheckSumDownload
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:32:45 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jowotal3yepa/data"
2026/09/09 04:32:45 DEBUG : Creating backend with remote "TestDrive:crypt/7j5po6ap6o56utp59ssrp5mv5n67cnn3t2p2ou9rupj0tnhfqp0g/vjrnln8ratgmqakfosrqe8espk"
2026/09/09 04:32:49 DEBUG : data/banana: md5 = 57c9aec5d37aabf5787645402bd11171 OK
2026/09/09 04:32:51 DEBUG : test.sum: md5 = 9c89a68fe8f58d52061cbdbb39eeaae7 OK
=== RUN   TestCheckSumDownload/subtest1
2026/09/09 04:32:55 DEBUG : data/potato: md5 = d79d844a64026791d11d30a380b9dda7 OK
2026/09/09 04:32:57 DEBUG : test.sum: md5 = a4e9edfd956f102fdcd6c4fddc81e1f2 OK
=== RUN   TestCheckSumDownload/subtest2
2026/09/09 04:33:01 DEBUG : test.sum: md5 = 20d2d6892f5f9239f5503f13c89db4ec OK
=== RUN   TestCheckSumDownload/subtest3
2026/09/09 04:33:06 DEBUG : test.sum: md5 = 79151ffefa955bffff0aba3e6861cc1b OK
=== RUN   TestCheckSumDownload/subtest4
2026/09/09 04:33:10 DEBUG : test.sum: md5 = a61dd88e660619a288292d76221a1a4a OK
=== RUN   TestCheckSumDownload/subtest5
2026/09/09 04:33:14 DEBUG : test.sum: md5 = aa1af27bd916cf1b98955b7e0feff2f7 OK
=== RUN   TestCheckSumDownload/subtest6
2026/09/09 04:33:21 DEBUG : data/banana: md5 = 53aaa4c52cdc0d92dfb71466925c46ec OK
2026/09/09 04:33:22 DEBUG : data/potato: md5 = 4c74e7d831cfe6cb70c98b4357c12136 OK
2026/09/09 04:33:24 DEBUG : test.sum: md5 = bc8432706501b93eaa4d6b5314044211 OK
=== RUN   TestCheckSumDownload/subtest7
--- PASS: TestCheckSumDownload (44.93s)
    --- PASS: TestCheckSumDownload/subtest1 (2.28s)
    --- PASS: TestCheckSumDownload/subtest2 (1.81s)
    --- PASS: TestCheckSumDownload/subtest3 (1.98s)
    --- PASS: TestCheckSumDownload/subtest4 (1.76s)
    --- PASS: TestCheckSumDownload/subtest5 (1.78s)
    --- PASS: TestCheckSumDownload/subtest6 (1.77s)
    --- PASS: TestCheckSumDownload/subtest7 (2.05s)
=== RUN   TestCheckSumConcurrency
2026/09/09 04:33:30 DEBUG : file0: md5 = 022eb0c0085c731f335df970fafa7446 OK
2026/09/09 04:33:30 DEBUG : file2: md5 = a58f09ae3db8ae4b943fdbaace096072 OK
2026/09/09 04:33:30 DEBUG : file1: md5 = 17fbabbccbdea0e6be9eb64c8c6fea41 OK
2026/09/09 04:33:30 DEBUG : file3: md5 = e0639b17e0744aca9a3ba29c049e59ee OK
2026/09/09 04:33:30 NOTICE: Mock file system at checkSum: 0 differences found
2026/09/09 04:33:30 NOTICE: Mock file system at checkSum: 4 matching files
--- PASS: TestCheckSumConcurrency (0.00s)
=== RUN   TestCheckSumDownloadConcurrency
2026/09/09 04:33:30 DEBUG : file0: md5 = 022eb0c0085c731f335df970fafa7446 OK
2026/09/09 04:33:30 DEBUG : file2: md5 = a58f09ae3db8ae4b943fdbaace096072 OK
2026/09/09 04:33:30 DEBUG : file1: md5 = 17fbabbccbdea0e6be9eb64c8c6fea41 OK
2026/09/09 04:33:30 DEBUG : file3: md5 = e0639b17e0744aca9a3ba29c049e59ee OK
2026/09/09 04:33:30 NOTICE: Mock file system at checkSum: 0 differences found
2026/09/09 04:33:30 NOTICE: Mock file system at checkSum: 4 matching files
--- PASS: TestCheckSumDownloadConcurrency (0.00s)
=== RUN   TestApplyTransforms
2026/09/09 04:33:30 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-qizokom0tofa"
2026/09/09 04:33:30 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/09/09 04:33:30 DEBUG : Creating backend with remote "TestDrive:crypt/nasjdg79ov3vruib3m1nvho1ps5dtqg6a8a45cheejmbtusksvgg"
2026/09/09 04:33:31 DEBUG : Creating backend with remote "/tmp/rclone3212964668"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-qizokom0tofa'", Local "Local file system at /tmp/rclone3212964668", Modify Window "1ms"
2026/09/09 04:33:34 DEBUG : hello, world!: md5 = 28f265d103ece299e17fe7dd880e2895 OK
upper checkfile vs. lower remote (without normalization)
2026/09/09 04:33:35 ERROR : hello, world!: sum not found
2026/09/09 04:33:35 ERROR : HELLO, WORLD!: file not in Encrypted drive 'TestCryptDrive:rclone-test-qizokom0tofa'
2026/09/09 04:33:35 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-qizokom0tofa': 1 files missing
2026/09/09 04:33:35 NOTICE: 1 hashes missing
2026/09/09 04:33:35 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-qizokom0tofa': 1 differences found
2026/09/09 04:33:35 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-qizokom0tofa': 2 errors while checking
upper checkfile vs. lower remote (with normalization)
2026/09/09 04:33:36 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/09/09 04:33:36 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-qizokom0tofa': 0 differences found
2026/09/09 04:33:36 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-qizokom0tofa': 1 matching files
2026/09/09 04:33:36 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-kecidog5qogo"
2026/09/09 04:33:36 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/09/09 04:33:36 DEBUG : Creating backend with remote "TestDrive:crypt/qo27oe90lr5dd4pboc34sm0od7igi2v6rs4onlc0pncg71f4ks1g"
2026/09/09 04:33:37 DEBUG : Creating backend with remote "/tmp/rclone226077372"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kecidog5qogo'", Local "Local file system at /tmp/rclone226077372", Modify Window "1ms"
2026/09/09 04:33:40 DEBUG : HELLO, WORLD!: md5 = 08db5e05948b2bb5d0dce5064a710272 OK
lower checkfile vs. upper remote (without normalization)
2026/09/09 04:33:41 ERROR : HELLO, WORLD!: sum not found
2026/09/09 04:33:41 ERROR : hello, world!: file not in Encrypted drive 'TestCryptDrive:rclone-test-kecidog5qogo'
2026/09/09 04:33:41 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kecidog5qogo': 1 files missing
2026/09/09 04:33:41 NOTICE: 1 hashes missing
2026/09/09 04:33:41 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kecidog5qogo': 1 differences found
2026/09/09 04:33:41 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kecidog5qogo': 2 errors while checking
lower checkfile vs. upper remote (with normalization)
2026/09/09 04:33:42 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/09/09 04:33:42 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kecidog5qogo': 0 differences found
2026/09/09 04:33:42 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kecidog5qogo': 1 matching files
2026/09/09 04:33:42 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-fukevib4cile"
2026/09/09 04:33:42 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/09/09 04:33:42 DEBUG : Creating backend with remote "TestDrive:crypt/kd6rt6ko41us6qh3dkm25514cl9rbth8a6jns154cvs6ek2qlp1g"
2026/09/09 04:33:43 DEBUG : Creating backend with remote "/tmp/rclone1256260116"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fukevib4cile'", Local "Local file system at /tmp/rclone1256260116", Modify Window "1ms"
2026/09/09 04:33:46 DEBUG : HeLlO, wOrLd!: md5 = 565adbed2c19815a7c77291c8e5fcbb7 OK
lower checkfile vs. upperlowermixed remote (without normalization)
2026/09/09 04:33:46 ERROR : HeLlO, wOrLd!: sum not found
2026/09/09 04:33:46 ERROR : hello, world!: file not in Encrypted drive 'TestCryptDrive:rclone-test-fukevib4cile'
2026/09/09 04:33:46 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fukevib4cile': 1 files missing
2026/09/09 04:33:46 NOTICE: 1 hashes missing
2026/09/09 04:33:46 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fukevib4cile': 1 differences found
2026/09/09 04:33:46 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fukevib4cile': 2 errors while checking
lower checkfile vs. upperlowermixed remote (with normalization)
2026/09/09 04:33:47 DEBUG : HeLlO, wOrLd!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/09/09 04:33:47 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fukevib4cile': 0 differences found
2026/09/09 04:33:47 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fukevib4cile': 1 matching files
2026/09/09 04:33:47 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-zopoqoc4fuwo"
2026/09/09 04:33:47 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/09/09 04:33:47 DEBUG : Creating backend with remote "TestDrive:crypt/bjq5g86ruuhh191naitrdmch5s1teemhbrogmd1dp1r4vit3ngeg"
2026/09/09 04:33:48 DEBUG : Creating backend with remote "/tmp/rclone73257765"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zopoqoc4fuwo'", Local "Local file system at /tmp/rclone73257765", Modify Window "1ms"
2026/09/09 04:33:51 DEBUG : HELLO, WORLD!: md5 = 8dadb01ac6c76a7233db3ec3090497bb OK
upperlowermixed checkfile vs. upper remote (without normalization)
2026/09/09 04:33:52 ERROR : HELLO, WORLD!: sum not found
2026/09/09 04:33:52 ERROR : HeLlO, wOrLd!: file not in Encrypted drive 'TestCryptDrive:rclone-test-zopoqoc4fuwo'
2026/09/09 04:33:52 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-zopoqoc4fuwo': 1 files missing
2026/09/09 04:33:52 NOTICE: 1 hashes missing
2026/09/09 04:33:52 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-zopoqoc4fuwo': 1 differences found
2026/09/09 04:33:52 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-zopoqoc4fuwo': 2 errors while checking
upperlowermixed checkfile vs. upper remote (with normalization)
2026/09/09 04:33:53 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/09/09 04:33:53 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-zopoqoc4fuwo': 0 differences found
2026/09/09 04:33:53 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-zopoqoc4fuwo': 1 matching files
2026/09/09 04:33:53 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-vutuceb9tawu"
2026/09/09 04:33:53 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/09/09 04:33:53 DEBUG : Creating backend with remote "TestDrive:crypt/n9gj16u454ivj9emgb2i74flllrq6gs16ucqo4v86f26oe8v23h0"
2026/09/09 04:33:54 DEBUG : Creating backend with remote "/tmp/rclone534858293"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-vutuceb9tawu'", Local "Local file system at /tmp/rclone534858293", Modify Window "1ms"
2026/09/09 04:33:57 DEBUG : 測試_Русский___ě_áñ: md5 = 17c0f2d9aef11ddd743df8249b428dad OK
NFD checkfile vs. NFC remote (without normalization)
2026/09/09 04:33:57 ERROR : 測試_Русский___ě_áñ: sum not found
2026/09/09 04:33:57 ERROR : 測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-vutuceb9tawu'
2026/09/09 04:33:57 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-vutuceb9tawu': 1 files missing
2026/09/09 04:33:57 NOTICE: 1 hashes missing
2026/09/09 04:33:57 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-vutuceb9tawu': 1 differences found
2026/09/09 04:33:57 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-vutuceb9tawu': 2 errors while checking
NFD checkfile vs. NFC remote (with normalization)
2026/09/09 04:33:58 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/09/09 04:33:58 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-vutuceb9tawu': 0 differences found
2026/09/09 04:33:58 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-vutuceb9tawu': 1 matching files
2026/09/09 04:33:58 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-voyeqez1zumo"
2026/09/09 04:33:58 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/09/09 04:33:58 DEBUG : Creating backend with remote "TestDrive:crypt/t37ujlhjjlrr8trs3utb2tbg884cvekn98liiovtfjf8s6hv6e20"
2026/09/09 04:33:59 DEBUG : Creating backend with remote "/tmp/rclone1902238564"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-voyeqez1zumo'", Local "Local file system at /tmp/rclone1902238564", Modify Window "1ms"
2026/09/09 04:34:02 DEBUG : 測試_Русский___ě_áñ: md5 = 2cd8ffaebd35ba29c8f7f9f492e9af92 OK
NFC checkfile vs. NFD remote (without normalization)
2026/09/09 04:34:02 ERROR : 測試_Русский___ě_áñ: sum not found
2026/09/09 04:34:02 ERROR : 測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-voyeqez1zumo'
2026/09/09 04:34:02 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-voyeqez1zumo': 1 files missing
2026/09/09 04:34:02 NOTICE: 1 hashes missing
2026/09/09 04:34:02 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-voyeqez1zumo': 1 differences found
2026/09/09 04:34:02 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-voyeqez1zumo': 2 errors while checking
NFC checkfile vs. NFD remote (with normalization)
2026/09/09 04:34:03 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/09/09 04:34:03 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-voyeqez1zumo': 0 differences found
2026/09/09 04:34:03 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-voyeqez1zumo': 1 matching files
2026/09/09 04:34:03 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-tiyopeq7kixo"
2026/09/09 04:34:03 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/09/09 04:34:03 DEBUG : Creating backend with remote "TestDrive:crypt/jpqba36elipc0usilpu46musnmemjtb5oiggs7gko64mnvotapt0"
2026/09/09 04:34:04 DEBUG : Creating backend with remote "/tmp/rclone3485807930"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tiyopeq7kixo'", Local "Local file system at /tmp/rclone3485807930", Modify Window "1ms"
2026/09/09 04:34:07 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = a452f6435c9d55ae618822fa93018d5e OK
NFDx2 checkfile vs. both remote (without normalization)
2026/09/09 04:34:08 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found
2026/09/09 04:34:08 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-tiyopeq7kixo'
2026/09/09 04:34:08 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-tiyopeq7kixo': 1 files missing
2026/09/09 04:34:08 NOTICE: 1 hashes missing
2026/09/09 04:34:08 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-tiyopeq7kixo': 1 differences found
2026/09/09 04:34:08 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-tiyopeq7kixo': 2 errors while checking
NFDx2 checkfile vs. both remote (with normalization)
2026/09/09 04:34:08 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/09/09 04:34:08 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-tiyopeq7kixo': 0 differences found
2026/09/09 04:34:08 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-tiyopeq7kixo': 1 matching files
2026/09/09 04:34:08 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-terilaj6zahe"
2026/09/09 04:34:08 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/09/09 04:34:09 DEBUG : Creating backend with remote "TestDrive:crypt/voqqadafvlq7pa4ag8bpfv8i0446e1sev70e3jt5v6n532gits10"
2026/09/09 04:34:10 DEBUG : Creating backend with remote "/tmp/rclone3726148895"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-terilaj6zahe'", Local "Local file system at /tmp/rclone3726148895", Modify Window "1ms"
2026/09/09 04:34:12 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = b6954797463a31d9f86dd91daf800003 OK
NFCx2 checkfile vs. both remote (without normalization)
2026/09/09 04:34:13 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found
2026/09/09 04:34:13 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-terilaj6zahe'
2026/09/09 04:34:13 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-terilaj6zahe': 1 files missing
2026/09/09 04:34:13 NOTICE: 1 hashes missing
2026/09/09 04:34:13 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-terilaj6zahe': 1 differences found
2026/09/09 04:34:13 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-terilaj6zahe': 2 errors while checking
NFCx2 checkfile vs. both remote (with normalization)
2026/09/09 04:34:14 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/09/09 04:34:14 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-terilaj6zahe': 0 differences found
2026/09/09 04:34:14 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-terilaj6zahe': 1 matching files
2026/09/09 04:34:14 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-bahezab4jofe"
2026/09/09 04:34:14 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/09/09 04:34:14 DEBUG : Creating backend with remote "TestDrive:crypt/te3vfo1khvb7onk56n27kevfaut9b0hgs2ia687dctp5s5h12b10"
2026/09/09 04:34:15 DEBUG : Creating backend with remote "/tmp/rclone277497491"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-bahezab4jofe'", Local "Local file system at /tmp/rclone277497491", Modify Window "1ms"
2026/09/09 04:34:18 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = c04c694819352f036a31c4c6816a9221 OK
both checkfile vs. NFDx2 remote (without normalization)
2026/09/09 04:34:18 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found
2026/09/09 04:34:18 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-bahezab4jofe'
2026/09/09 04:34:18 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-bahezab4jofe': 1 files missing
2026/09/09 04:34:18 NOTICE: 1 hashes missing
2026/09/09 04:34:18 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-bahezab4jofe': 1 differences found
2026/09/09 04:34:18 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-bahezab4jofe': 2 errors while checking
both checkfile vs. NFDx2 remote (with normalization)
2026/09/09 04:34:19 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/09/09 04:34:19 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-bahezab4jofe': 0 differences found
2026/09/09 04:34:19 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-bahezab4jofe': 1 matching files
2026/09/09 04:34:19 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-pasejip7jezo"
2026/09/09 04:34:19 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/09/09 04:34:19 DEBUG : Creating backend with remote "TestDrive:crypt/5sotdjhac18f8rt0ks9fiqa5t5oln1erqna7sp5chdfs6a9hhklg"
2026/09/09 04:34:21 DEBUG : Creating backend with remote "/tmp/rclone2339770035"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-pasejip7jezo'", Local "Local file system at /tmp/rclone2339770035", Modify Window "1ms"
2026/09/09 04:34:23 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 618da96ec70e729cfe1cd4823fda6f5e OK
both checkfile vs. NFCx2 remote (without normalization)
2026/09/09 04:34:24 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found
2026/09/09 04:34:24 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-pasejip7jezo'
2026/09/09 04:34:24 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-pasejip7jezo': 1 files missing
2026/09/09 04:34:24 NOTICE: 1 hashes missing
2026/09/09 04:34:24 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-pasejip7jezo': 1 differences found
2026/09/09 04:34:24 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-pasejip7jezo': 2 errors while checking
both checkfile vs. NFCx2 remote (with normalization)
2026/09/09 04:34:25 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/09/09 04:34:25 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-pasejip7jezo': 0 differences found
2026/09/09 04:34:25 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-pasejip7jezo': 1 matching files
2026/09/09 04:34:25 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-pasejip7jezo': Purge remote
2026/09/09 04:34:25 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-bahezab4jofe': Purge remote
2026/09/09 04:34:26 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-terilaj6zahe': Purge remote
2026/09/09 04:34:28 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-tiyopeq7kixo': Purge remote
2026/09/09 04:34:29 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-voyeqez1zumo': Purge remote
2026/09/09 04:34:29 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-vutuceb9tawu': Purge remote
2026/09/09 04:34:30 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zopoqoc4fuwo': Purge remote
2026/09/09 04:34:30 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-fukevib4cile': Purge remote
2026/09/09 04:34:31 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-kecidog5qogo': Purge remote
2026/09/09 04:34:31 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-qizokom0tofa': Purge remote
--- PASS: TestApplyTransforms (61.75s)
=== RUN   TestTruncateString
--- PASS: TestTruncateString (0.00s)
=== RUN   TestCopyFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:34:32 DEBUG : file1: Need to transfer - File not found at Destination
2026/09/09 04:34:35 DEBUG : sub/file2: md5 = 7c56821668af8dc2bc6a0e9cd9ab975d OK
2026/09/09 04:34:35 DEBUG : sub/file2: size = 14 OK
2026/09/09 04:34:35 INFO  : file1: Copied (new) to: sub/file2
2026/09/09 04:34:36 DEBUG : sub/file2: size = 14 OK
2026/09/09 04:34:36 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2026/09/09 04:34:36 DEBUG : file1: Unchanged skipping
2026/09/09 04:34:36 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa': don't need to copy/move sub/file2, it is already at target location
--- PASS: TestCopyFile (7.34s)
=== RUN   TestCopyLongFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", 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-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:34:42 DEBUG : dst/file1: md5 = 1b091cf4968612db1edd3b31a7b7a59f OK
2026/09/09 04:34:43 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jowotal3yepa/backup"
2026/09/09 04:34:43 DEBUG : Creating backend with remote "TestDrive:crypt/7j5po6ap6o56utp59ssrp5mv5n67cnn3t2p2ou9rupj0tnhfqp0g/1nrff024r7pq65ecp72fc28jb0"
2026/09/09 04:34:45 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone3461562559)
2026/09/09 04:34:45 DEBUG : dst/file1: size = 18 (Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa')
2026/09/09 04:34:45 DEBUG : dst/file1: Sizes differ
2026/09/09 04:34:49 INFO  : dst/file1: Moved (server-side)
2026/09/09 04:34:50 DEBUG : dst/file1: md5 = 8c0e0e3349236d55b98a3a09cfede308 OK
2026/09/09 04:34:50 DEBUG : dst/file1: size = 14 OK
2026/09/09 04:34:50 INFO  : dst/file1: Copied (new)
--- PASS: TestCopyFileBackupDir (16.08s)
=== RUN   TestCopyFileCompareDest
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:34:56 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jowotal3yepa/dst"
2026/09/09 04:34:56 DEBUG : Creating backend with remote "TestDrive:crypt/7j5po6ap6o56utp59ssrp5mv5n67cnn3t2p2ou9rupj0tnhfqp0g/31u3jie661vd5p8j7rtc3hgbh0"
2026/09/09 04:34:58 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jowotal3yepa/CompareDest"
2026/09/09 04:34:58 DEBUG : Creating backend with remote "TestDrive:crypt/7j5po6ap6o56utp59ssrp5mv5n67cnn3t2p2ou9rupj0tnhfqp0g/gveqi14airsml4bgu7krj116o8"
2026/09/09 04:34:59 DEBUG : one: Need to transfer - File not found at Destination
2026/09/09 04:35:02 DEBUG : one: md5 = b3b29c09066eada365eb357747991537 OK
2026/09/09 04:35:02 DEBUG : one: size = 3 OK
2026/09/09 04:35:02 INFO  : one: Copied (new)
2026/09/09 04:35:03 DEBUG : one: size = 5 (Local file system at /tmp/rclone3461562559)
2026/09/09 04:35:03 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa/dst')
2026/09/09 04:35:03 DEBUG : one: Sizes differ
2026/09/09 04:35:05 DEBUG : one: md5 = c28abdeedbec09c73b4cb503e9f33334 OK
2026/09/09 04:35:05 DEBUG : one: size = 5 OK
2026/09/09 04:35:05 INFO  : one: Copied (replaced existing)
2026/09/09 04:35:07 DEBUG : dst/one: md5 = 751e7eb4627c919d25dbcbfc6903b959 OK
2026/09/09 04:35:10 DEBUG : CompareDest/one: md5 = 6c6e4b701f3831bd4eadee119f131a34 OK
2026/09/09 04:35:11 DEBUG : one: size = 5 (Local file system at /tmp/rclone3461562559)
2026/09/09 04:35:11 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa/dst')
2026/09/09 04:35:11 DEBUG : one: Sizes differ
2026/09/09 04:35:11 DEBUG : one: size = 5 OK
2026/09/09 04:35:11 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/09/09 04:35:11 DEBUG : one: Destination found in --compare-dest, skipping
2026/09/09 04:35:13 DEBUG : CompareDest/two: md5 = 542d10ca466aa304ef934d24476912a8 OK
2026/09/09 04:35:14 DEBUG : two: Need to transfer - File not found at Destination
2026/09/09 04:35:15 DEBUG : two: size = 3 OK
2026/09/09 04:35:15 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/09/09 04:35:15 DEBUG : two: Destination found in --compare-dest, skipping
2026/09/09 04:35:15 DEBUG : two: Need to transfer - File not found at Destination
2026/09/09 04:35:16 DEBUG : two: size = 3 OK
2026/09/09 04:35:16 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/09/09 04:35:16 DEBUG : two: Destination found in --compare-dest, skipping
2026/09/09 04:35:17 DEBUG : two: Need to transfer - File not found at Destination
2026/09/09 04:35:17 DEBUG : two: size = 5 (Local file system at /tmp/rclone3461562559)
2026/09/09 04:35:17 DEBUG : two: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa/CompareDest')
2026/09/09 04:35:17 DEBUG : two: Sizes differ
2026/09/09 04:35:19 DEBUG : two: md5 = aba6a50a893424d250674c5e6a8fa1aa OK
2026/09/09 04:35:19 DEBUG : two: size = 5 OK
2026/09/09 04:35:19 INFO  : two: Copied (new)
--- PASS: TestCopyFileCompareDest (28.53s)
=== RUN   TestCopyFileCopyDest
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:35:24 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jowotal3yepa/dst"
2026/09/09 04:35:24 DEBUG : Creating backend with remote "TestDrive:crypt/7j5po6ap6o56utp59ssrp5mv5n67cnn3t2p2ou9rupj0tnhfqp0g/31u3jie661vd5p8j7rtc3hgbh0"
2026/09/09 04:35:26 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jowotal3yepa/CopyDest"
2026/09/09 04:35:26 DEBUG : Creating backend with remote "TestDrive:crypt/7j5po6ap6o56utp59ssrp5mv5n67cnn3t2p2ou9rupj0tnhfqp0g/d09o6po3f7bm6ce32vdgs8h9ls"
2026/09/09 04:35:28 DEBUG : one: Need to transfer - File not found at Destination
2026/09/09 04:35:31 DEBUG : one: md5 = edba7826ffdcb4936ca163ad7d25c8b0 OK
2026/09/09 04:35:31 DEBUG : one: size = 3 OK
2026/09/09 04:35:31 INFO  : one: Copied (new)
2026/09/09 04:35:32 DEBUG : one: size = 5 (Local file system at /tmp/rclone3461562559)
2026/09/09 04:35:32 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa/dst')
2026/09/09 04:35:32 DEBUG : one: Sizes differ
2026/09/09 04:35:34 DEBUG : one: md5 = 65ab338b932edeced807ca996624bbea OK
2026/09/09 04:35:34 DEBUG : one: size = 5 OK
2026/09/09 04:35:34 INFO  : one: Copied (replaced existing)
2026/09/09 04:35:36 DEBUG : dst/one: md5 = 323ef9cf788cd473b0c064c18166db8e OK
2026/09/09 04:35:39 DEBUG : CopyDest/one: md5 = e581acfb590fd5e6ff1ea5575cd4907c OK
2026/09/09 04:35:40 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jowotal3yepa/BackupDir"
2026/09/09 04:35:40 DEBUG : Creating backend with remote "TestDrive:crypt/7j5po6ap6o56utp59ssrp5mv5n67cnn3t2p2ou9rupj0tnhfqp0g/s6dbk3lfi7c9kfvo6j7bla9m0g"
2026/09/09 04:35:41 DEBUG : one: size = 5 (Local file system at /tmp/rclone3461562559)
2026/09/09 04:35:41 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa/dst')
2026/09/09 04:35:41 DEBUG : one: Sizes differ
2026/09/09 04:35:42 DEBUG : one: size = 5 OK
2026/09/09 04:35:42 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/09/09 04:35:42 DEBUG : one: size = 5 (Local file system at /tmp/rclone3461562559)
2026/09/09 04:35:42 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa/dst')
2026/09/09 04:35:42 DEBUG : one: Sizes differ
2026/09/09 04:35:44 INFO  : one: Moved (server-side)
2026/09/09 04:35:46 DEBUG : one: size = 5 OK
2026/09/09 04:35:46 INFO  : one: Copied (server-side copy)
2026/09/09 04:35:46 DEBUG : one: Destination found in --copy-dest, using server-side copy
2026/09/09 04:35:48 DEBUG : CopyDest/two: md5 = ef4d51bc5d64677839bc98c203d14217 OK
2026/09/09 04:35:53 DEBUG : two: Need to transfer - File not found at Destination
2026/09/09 04:35:53 DEBUG : two: size = 3 OK
2026/09/09 04:35:53 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/09/09 04:35:54 DEBUG : two: size = 3 OK
2026/09/09 04:35:54 INFO  : two: Copied (server-side copy)
2026/09/09 04:35:54 DEBUG : two: Destination found in --copy-dest, using server-side copy
2026/09/09 04:35:55 DEBUG : two: size = 3 OK
2026/09/09 04:35:55 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/09/09 04:35:55 DEBUG : two: Unchanged skipping
2026/09/09 04:35:57 DEBUG : CopyDest/three: md5 = fd042feb402148b3120b528457e9941b OK
2026/09/09 04:35:58 DEBUG : three: Need to transfer - File not found at Destination
2026/09/09 04:35:59 DEBUG : three: size = 7 (Local file system at /tmp/rclone3461562559)
2026/09/09 04:35:59 DEBUG : three: size = 5 (Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa/CopyDest')
2026/09/09 04:35:59 DEBUG : three: Sizes differ
2026/09/09 04:35:59 DEBUG : three: Destination not found in --copy-dest
2026/09/09 04:36:00 DEBUG : three: md5 = 2568bc5e5e1571370ecdae4928e75ff7 OK
2026/09/09 04:36:00 DEBUG : three: size = 7 OK
2026/09/09 04:36:00 INFO  : three: Copied (new)
--- PASS: TestCopyFileCopyDest (43.54s)
=== RUN   TestCopyInplace
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
    copy_test.go:371: Partial uploads not supported
--- SKIP: TestCopyInplace (0.47s)
=== RUN   TestCopyLongFileName
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", 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-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
    copy_test.go:437: Partial uploads not supported
--- SKIP: TestCopyLongFileNameCollision (1.87s)
=== RUN   TestCopyFileMaxTransfer
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:36:11 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination
2026/09/09 04:36:13 DEBUG : TestCopyFileMaxTransfer/file1: md5 = 40da46a41af03fb16f2b4b359a747726 OK
2026/09/09 04:36:13 DEBUG : TestCopyFileMaxTransfer/file1: size = 14 OK
2026/09/09 04:36:13 INFO  : TestCopyFileMaxTransfer/file1: Copied (new)
2026/09/09 04:36:14 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination
2026/09/09 04:36:14 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/09 04:36:15 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination
2026/09/09 04:36:16 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination
2026/09/09 04:36:18 DEBUG : TestCopyFileMaxTransfer/file4: md5 = de0e50782b990fefd7fc8e4d0ea6bcfc OK
2026/09/09 04:36:18 DEBUG : TestCopyFileMaxTransfer/file4: size = 2062 OK
2026/09/09 04:36:18 INFO  : TestCopyFileMaxTransfer/file4: Copied (new)
--- PASS: TestCopyFileMaxTransfer (10.32s)
=== RUN   TestDeduplicateInteractive
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
    dedupe_test.go:37: Can't run this test without a hash
--- SKIP: TestDeduplicateInteractive (0.46s)
=== RUN   TestDeduplicateSkip
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:36:25 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa': Looking for duplicate names using skip mode.
2026/09/09 04:36:25 NOTICE: one: Found 2 files with duplicate names
2026/09/09 04:36:25 NOTICE: one: Skipping 2 files with duplicate names
--- PASS: TestDeduplicateSkip (5.61s)
=== RUN   TestDeduplicateSizeOnly
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:36:32 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa': Looking for duplicate names using skip mode.
2026/09/09 04:36:33 NOTICE: one: Found 3 files with duplicate names
2026/09/09 04:36:33 NOTICE: one: Deleting 1/2 identical duplicates (size 11)
2026/09/09 04:36:33 INFO  : one: Deleted
2026/09/09 04:36:33 NOTICE: one: Skipping 2 files with duplicate names
--- PASS: TestDeduplicateSizeOnly (8.19s)
=== RUN   TestDeduplicateFirst
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:36:40 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa': Looking for duplicate names using first mode.
2026/09/09 04:36:40 NOTICE: one: Found 3 files with duplicate names
2026/09/09 04:36:41 INFO  : one: Deleted
2026/09/09 04:36:41 INFO  : one: Deleted
2026/09/09 04:36:41 NOTICE: one: Deleted 2 extra copies
--- PASS: TestDeduplicateFirst (7.50s)
=== RUN   TestDeduplicateNewest
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:36:47 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa': Looking for duplicate names using newest mode.
2026/09/09 04:36:48 NOTICE: one: Found 3 files with duplicate names
2026/09/09 04:36:48 INFO  : one: Deleted
2026/09/09 04:36:49 INFO  : one: Deleted
2026/09/09 04:36:49 NOTICE: one: Deleted 2 extra copies
--- PASS: TestDeduplicateNewest (7.69s)
=== RUN   TestDeduplicateNewestByHash
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
    dedupe_test.go:37: Can't run this test without a hash
--- SKIP: TestDeduplicateNewestByHash (0.49s)
=== RUN   TestDeduplicateOldest
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:36:56 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa': Looking for duplicate names using oldest mode.
2026/09/09 04:36:56 NOTICE: one: Found 3 files with duplicate names
2026/09/09 04:36:57 INFO  : one: Deleted
2026/09/09 04:36:57 INFO  : one: Deleted
2026/09/09 04:36:57 NOTICE: one: Deleted 2 extra copies
--- PASS: TestDeduplicateOldest (7.90s)
=== RUN   TestDeduplicateLargest
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:37:03 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa': Looking for duplicate names using largest mode.
2026/09/09 04:37:04 NOTICE: one: Found 3 files with duplicate names
2026/09/09 04:37:04 INFO  : one: Deleted
2026/09/09 04:37:05 INFO  : one: Deleted
2026/09/09 04:37:05 NOTICE: one: Deleted 2 extra copies
--- PASS: TestDeduplicateLargest (7.41s)
=== RUN   TestDeduplicateSmallest
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:37:11 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa': Looking for duplicate names using smallest mode.
2026/09/09 04:37:11 NOTICE: one: Found 3 files with duplicate names
2026/09/09 04:37:12 INFO  : one: Deleted
2026/09/09 04:37:12 INFO  : one: Deleted
2026/09/09 04:37:12 NOTICE: one: Deleted 2 extra copies
--- PASS: TestDeduplicateSmallest (7.46s)
=== RUN   TestDeduplicateRename
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:37:20 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa': Looking for duplicate names using rename mode.
2026/09/09 04:37:21 NOTICE: one.txt: Found 3 files with duplicate names
2026/09/09 04:37:22 INFO  : one-2.txt: renamed from: one.txt
2026/09/09 04:37:23 INFO  : one-3.txt: renamed from: one.txt
2026/09/09 04:37:24 INFO  : one-4.txt: renamed from: one.txt
--- PASS: TestDeduplicateRename (13.06s)
=== RUN   TestDeduplicateRenameManyExisting
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:37:28 DEBUG : one-1.txt: md5 = af0bfa09071e2d5e4646111dc12d05ad OK
2026/09/09 04:37:30 DEBUG : one-2.txt: md5 = e3eb295dbe5a1635aa354efa9213a4c3 OK
2026/09/09 04:37:31 DEBUG : one-3.txt: md5 = 73fd9bf72dbf960a05a7e31828747b9b OK
2026/09/09 04:37:33 DEBUG : one-4.txt: md5 = 95639abb5e7fe544367d2da1908045ff OK
2026/09/09 04:37:35 DEBUG : one-5.txt: md5 = 26b1336aa8a68530dfae9df06f0862a4 OK
2026/09/09 04:37:36 DEBUG : one-6.txt: md5 = dfef87e0e998aaa9cad8d31d4a5af2ce OK
2026/09/09 04:37:38 DEBUG : one-7.txt: md5 = b024d58d528e3874ebe369be592feab6 OK
2026/09/09 04:37:40 DEBUG : one-8.txt: md5 = 3e5e16c7f9a6f3038e53c32e332c409c OK
2026/09/09 04:37:41 DEBUG : one-9.txt: md5 = aaddfc74f03a459d4221e1a00709dfc9 OK
2026/09/09 04:37:43 DEBUG : one-10.txt: md5 = b7e4b0bb7327a796791598fc0d55a967 OK
2026/09/09 04:37:45 DEBUG : one-11.txt: md5 = 2a928e318b516ab25dce4e8085a74ede OK
2026/09/09 04:37:46 DEBUG : one-12.txt: md5 = c0db45c8a0fbfb1305a6a334b94b07c3 OK
2026/09/09 04:37:48 DEBUG : one-13.txt: md5 = 8f2dd5504146891037d3968e649deb0a OK
2026/09/09 04:37:50 DEBUG : one-14.txt: md5 = 22066b95f83e31528e96d463e15539a9 OK
2026/09/09 04:37:51 DEBUG : one-15.txt: md5 = 0862d0e2d2f6fca8c38f05dbe9d1ad0f OK
2026/09/09 04:37:53 DEBUG : one-16.txt: md5 = 3ccb04eb39a1b334f0e677fc9764d42b OK
2026/09/09 04:37:55 DEBUG : one-17.txt: md5 = ac4c6788351abbb668cb795aaf3e8204 OK
2026/09/09 04:37:56 DEBUG : one-18.txt: md5 = 90a828b93804fce9728e578415344382 OK
2026/09/09 04:37:58 DEBUG : one-19.txt: md5 = 2c5852c60a689ac291ce2c749ee60fd0 OK
2026/09/09 04:38:00 DEBUG : one-20.txt: md5 = 8794a4da3f9b9cfad7d44418dfc52dfb OK
2026/09/09 04:38:01 DEBUG : one-21.txt: md5 = ca6d27a51634d0e0d780ab19ce6085cf OK
2026/09/09 04:38:03 DEBUG : one-22.txt: md5 = d4aa46cc188f12c87a419488581b177d OK
2026/09/09 04:38:04 DEBUG : one-23.txt: md5 = 1c4ab091a3a8e2cef015fe48979e71de OK
2026/09/09 04:38:06 DEBUG : one-24.txt: md5 = 8c9ae627b8f3e43a5a3a75f5148c783b OK
2026/09/09 04:38:08 DEBUG : one-25.txt: md5 = 5e039f8b070120ffeca8d1c28f386f91 OK
2026/09/09 04:38:09 DEBUG : one-26.txt: md5 = 444463146f3949b5b4cf452daf0c2df5 OK
2026/09/09 04:38:11 DEBUG : one-27.txt: md5 = 74cfd1dcb8fb0847124f65b125009c76 OK
2026/09/09 04:38:12 DEBUG : one-28.txt: md5 = b508b65d8111cee6a0a9cdf8e595b1f1 OK
2026/09/09 04:38:14 DEBUG : one-29.txt: md5 = b7be056d4e65eb9a10e579db22e9d4ff OK
2026/09/09 04:38:16 DEBUG : one-30.txt: md5 = adfcaf3878301c5d22f58c0978316238 OK
2026/09/09 04:38:17 DEBUG : one-31.txt: md5 = dddc7a9164256dfacc852f31ad78379a OK
2026/09/09 04:38:19 DEBUG : one-32.txt: md5 = 5e845709def768b33da78f685d422d7a OK
2026/09/09 04:38:20 DEBUG : one-33.txt: md5 = 30c7bad2441da6c31a2e535c69fdd9ea OK
2026/09/09 04:38:22 DEBUG : one-34.txt: md5 = 34c98c66059f677eb2d70af7460eb752 OK
2026/09/09 04:38:23 DEBUG : one-35.txt: md5 = a808ee992d241fb4101efc4963380bc1 OK
2026/09/09 04:38:25 DEBUG : one-36.txt: md5 = 4401f3699af948e5355bea8829376ade OK
2026/09/09 04:38:27 DEBUG : one-37.txt: md5 = 6f8238376f22371d8c29fc26fe87a481 OK
2026/09/09 04:38:28 DEBUG : one-38.txt: md5 = e804412df5a16a20c2f55fb5089c9d4c OK
2026/09/09 04:38:30 DEBUG : one-39.txt: md5 = 9071c9da05fc77cbf0a3339f830867ca OK
2026/09/09 04:38:31 DEBUG : one-40.txt: md5 = e8ae127b56888ee08588bc190f0fa1fd OK
2026/09/09 04:38:33 DEBUG : one-41.txt: md5 = a865edc209e53c10e137f5e07d00f7fb OK
2026/09/09 04:38:35 DEBUG : one-42.txt: md5 = 703015945ceb43829f9bde6dc01c2975 OK
2026/09/09 04:38:36 DEBUG : one-43.txt: md5 = da60f673af3680f42d7559d298ff005f OK
2026/09/09 04:38:38 DEBUG : one-44.txt: md5 = d512f35d63ee58d16d7dc91671e3f8ab OK
2026/09/09 04:38:39 DEBUG : one-45.txt: md5 = ea34e37fa94097efe45b194044d0fdd9 OK
2026/09/09 04:38:41 DEBUG : one-46.txt: md5 = 9cbe26a985ae2b8a5644b1bd5c474a11 OK
2026/09/09 04:38:43 DEBUG : one-47.txt: md5 = 3e9055cb4fb85a9567ab1606b43be9d7 OK
2026/09/09 04:38:44 DEBUG : one-48.txt: md5 = bbed54cf1c0f0247b72f12aec34a06d6 OK
2026/09/09 04:38:46 DEBUG : one-49.txt: md5 = 166fac9f719209e2c8bba2567c754b23 OK
2026/09/09 04:38:47 DEBUG : one-50.txt: md5 = 98548ec73d2fd5403e688608b536671f OK
2026/09/09 04:38:49 DEBUG : one-51.txt: md5 = db43391b44d6f6fc2cb1830b52360106 OK
2026/09/09 04:38:50 DEBUG : one-52.txt: md5 = 02e8d8b1dfa2a4d751aa3d208b325c91 OK
2026/09/09 04:38:52 DEBUG : one-53.txt: md5 = 553c2547c5fc7e07d4472233c1326dfd OK
2026/09/09 04:38:53 DEBUG : one-54.txt: md5 = 15a68ca7d47295a03320d1bbf76b0092 OK
2026/09/09 04:38:55 DEBUG : one-55.txt: md5 = 9551e953218eaca746460fa28d01c48b OK
2026/09/09 04:38:57 DEBUG : one-56.txt: md5 = bf677e3d6585fe3bc923a5a2f2143d93 OK
2026/09/09 04:38:58 DEBUG : one-57.txt: md5 = 381357fb5e136a3f216873780826dcd3 OK
2026/09/09 04:39:00 DEBUG : one-58.txt: md5 = 3878db78ef6704326e91d2bcbabcb868 OK
2026/09/09 04:39:01 DEBUG : one-59.txt: md5 = 9c96111aab81221801b73d6b4c2571f6 OK
2026/09/09 04:39:03 DEBUG : one-60.txt: md5 = 725339362e6c46b3397982ac7228ac95 OK
2026/09/09 04:39:05 DEBUG : one-61.txt: md5 = 59a34e1f897c742941284deb470ec5b4 OK
2026/09/09 04:39:07 DEBUG : one-62.txt: md5 = be1faf059e6351bc76094ae320744276 OK
2026/09/09 04:39:08 DEBUG : one-63.txt: md5 = ea349a0e50f71f69541e6b0da9f0f75b OK
2026/09/09 04:39:10 DEBUG : one-64.txt: md5 = f98be930566a2079b0a8fa7e36fe5862 OK
2026/09/09 04:39:11 DEBUG : one-65.txt: md5 = 8ceb5013550d064e58aca003f20db298 OK
2026/09/09 04:39:13 DEBUG : one-66.txt: md5 = 5a09bf094794e050b53292dc88a60e66 OK
2026/09/09 04:39:14 DEBUG : one-67.txt: md5 = 63a0562f453eac00e9fad826ddd0e5ef OK
2026/09/09 04:39:16 DEBUG : one-68.txt: md5 = 23ac4b56d720f4417f1b50fef8b78bfa OK
2026/09/09 04:39:18 DEBUG : one-69.txt: md5 = 30f85d7631bcb861eb878b95fdadfe20 OK
2026/09/09 04:39:19 DEBUG : one-70.txt: md5 = 251eba0ddc36c7c0402852c6e08ef619 OK
2026/09/09 04:39:21 DEBUG : one-71.txt: md5 = c3c356cce571a5251e5688bdb588f519 OK
2026/09/09 04:39:22 DEBUG : one-72.txt: md5 = ced1a124434817488b673b5f803caba1 OK
2026/09/09 04:39:24 DEBUG : one-73.txt: md5 = d2fbcbe52ccc48cde6de12a296feb2a5 OK
2026/09/09 04:39:25 DEBUG : one-74.txt: md5 = 1fe5091ed772692ec6b88bf5b8c39f42 OK
2026/09/09 04:39:27 DEBUG : one-75.txt: md5 = bd6d73a54faa511c61820baf829ff3ad OK
2026/09/09 04:39:29 DEBUG : one-76.txt: md5 = fc7aee2e07fd3da3c6a9609312a24581 OK
2026/09/09 04:39:30 DEBUG : one-77.txt: md5 = 267e1430f3f95654c91f6eb34d8443bc OK
2026/09/09 04:39:32 DEBUG : one-78.txt: md5 = 3fc6710ec25cda834bf37753013a9634 OK
2026/09/09 04:39:33 DEBUG : one-79.txt: md5 = 73802256113da6061db33b58cc757eda OK
2026/09/09 04:39:35 DEBUG : one-80.txt: md5 = 5053491f03ee1bf2e588c7093d526e46 OK
2026/09/09 04:39:36 DEBUG : one-81.txt: md5 = 2f6fb021900a5b22ebcc3cf8bb7ec1a8 OK
2026/09/09 04:39:38 DEBUG : one-82.txt: md5 = 9ad04a95761d10040dfad251587e71da OK
2026/09/09 04:39:39 DEBUG : one-83.txt: md5 = 26193faf2cd89a44973e83243ed8f8e9 OK
2026/09/09 04:39:41 DEBUG : one-84.txt: md5 = fb3d1084bda0adafdbfba4f7a3b25230 OK
2026/09/09 04:39:43 DEBUG : one-85.txt: md5 = 862431c609642890b775e410afa4abc1 OK
2026/09/09 04:39:44 DEBUG : one-86.txt: md5 = 98474ea78daf77ea2778820c004395d0 OK
2026/09/09 04:39:46 DEBUG : one-87.txt: md5 = 058395483ab0f93375fd464bb07a6907 OK
2026/09/09 04:39:47 DEBUG : one-88.txt: md5 = 45515328efc94572f4b4e3d5e03a3f48 OK
2026/09/09 04:39:49 DEBUG : one-89.txt: md5 = 05f769f76c9372e624d9b762a0090131 OK
2026/09/09 04:39:51 DEBUG : one-90.txt: md5 = 4f62cba9dcbd6f322ee185d67183a1f4 OK
2026/09/09 04:39:52 DEBUG : one-91.txt: md5 = e1171222c048a833f154a63ace051f6c OK
2026/09/09 04:39:54 DEBUG : one-92.txt: md5 = aabfa7774cba524e2c6106fa9b0e47bc OK
2026/09/09 04:39:55 DEBUG : one-93.txt: md5 = 3a1f799fc1d81e61ffe53c3f1d170505 OK
2026/09/09 04:39:57 DEBUG : one-94.txt: md5 = 664e8e29775ce4c0f69e0542cde93826 OK
2026/09/09 04:39:58 DEBUG : one-95.txt: md5 = 20106c554ef9432972c6c483f928458c OK
2026/09/09 04:40:00 DEBUG : one-96.txt: md5 = 7473a6d80b06c7c3137ebb55562e5c08 OK
2026/09/09 04:40:02 DEBUG : one-97.txt: md5 = feeef1b1d0d6b92d0990c15e7b19b2d9 OK
2026/09/09 04:40:03 DEBUG : one-98.txt: md5 = 9559d15b6d19af9ce4ee976010b58eb6 OK
2026/09/09 04:40:05 DEBUG : one-99.txt: md5 = 63da6925121ed648127ed22f3816c024 OK
2026/09/09 04:40:06 DEBUG : one-100.txt: md5 = 9e4ccc4c94af8cd296792881fde1795f OK
2026/09/09 04:40:08 DEBUG : one-101.txt: md5 = 1a7349fad117446275db3a3dd41fa73b OK
2026/09/09 04:40:10 DEBUG : one-102.txt: md5 = 005388770b514d962a914ce197cfbd41 OK
2026/09/09 04:40:11 DEBUG : one-103.txt: md5 = e3555d2bf67e0634b6dfb64bc816bb67 OK
2026/09/09 04:40:13 DEBUG : one-104.txt: md5 = 6b485a28dc27920b3698ad8b670f58b4 OK
2026/09/09 04:40:15 DEBUG : one-105.txt: md5 = 81bade98a84cdbfa1d434c0f09392e3a OK
2026/09/09 04:40:18 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa': Looking for duplicate names using rename mode.
2026/09/09 04:40:19 NOTICE: one.txt: Found 2 files with duplicate names
2026/09/09 04:40:19 INFO  : one-106.txt: renamed from: one.txt
2026/09/09 04:40:20 INFO  : one-107.txt: renamed from: one.txt
--- PASS: TestDeduplicateRenameManyExisting (232.70s)
=== RUN   TestMergeDirs
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:41:22 DEBUG : dupe1/one.txt: md5 = 48f4e8e2826d5d426490617de7df6e20 OK
2026/09/09 04:41:24 DEBUG : dupe2/two.txt: md5 = 6683aebc6ee33aa7f4d3871eafae0089 OK
2026/09/09 04:41:27 DEBUG : dupe3/three.txt: md5 = 5cb99b7306f5cb7dd36934113013afdc OK
2026/09/09 04:41:27 INFO  : urj8gsducqbtvekeq181ntd2u8: merging "sfrom47189m9qr3mt4qonu281c"
2026/09/09 04:41:28 INFO  : urj8gsducqbtvekeq181ntd2u8: removing empty directory
2026/09/09 04:41:29 INFO  : 1u1r4ei7c628fnjg9blqt3j60o: merging "tr2hj63d80ftlmvm6a952snjcc"
2026/09/09 04:41:30 INFO  : 1u1r4ei7c628fnjg9blqt3j60o: removing empty directory
--- PASS: TestMergeDirs (15.47s)
=== RUN   TestListDirSorted
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:41:36 DEBUG : a.txt: md5 = 9607774b05ce909d91c46503490f1af8 OK
2026/09/09 04:41:38 DEBUG : zend.txt: md5 = db04274e978e4b43ed2d324d83a96029 OK
2026/09/09 04:41:40 DEBUG : sub dir/hello world: md5 = c5338f419e1d77498475a34c48aedab6 OK
2026/09/09 04:41:42 DEBUG : sub dir/hello world2: md5 = b86fcdf39a914575d39b60a19feaa473 OK
2026/09/09 04:41:45 DEBUG : sub dir/ignore dir/.ignore: md5 = 9adffeadf3d23d153a79ffe7133c0b4c OK
2026/09/09 04:41:46 DEBUG : sub dir/ignore dir/should be ignored: md5 = a65beef20a81b977b18c843977523065 OK
2026/09/09 04:41:49 DEBUG : sub dir/sub sub dir/hello world3: md5 = 4aa0daa1be2fc1d37bd27c3645243d3f OK
2026/09/09 04:41:50 DEBUG : a.txt: Excluded (Size Filter)
2026/09/09 04:41:50 DEBUG : a.txt: Excluded
2026/09/09 04:41:51 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2026/09/09 04:41:51 DEBUG : sub dir/hello world2: Excluded
2026/09/09 04:41:51 DEBUG : sub dir/hello world: Excluded (Size Filter)
2026/09/09 04:41:51 DEBUG : sub dir/hello world: Excluded
2026/09/09 04:41:51 DEBUG : sub dir/ignore dir: Excluded
2026/09/09 04:41:51 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2026/09/09 04:41:51 DEBUG : sub dir/hello world2: Excluded
2026/09/09 04:41:51 DEBUG : sub dir/hello world: Excluded (Size Filter)
2026/09/09 04:41:51 DEBUG : sub dir/hello world: Excluded
2026/09/09 04:41:52 DEBUG : sub dir/ignore dir: Excluded
--- PASS: TestListDirSorted (24.36s)
=== RUN   TestListDirSortedFn
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:42:01 DEBUG : a.txt: md5 = 3c7f903a1e692e2d5bcca05abb503c5d OK
2026/09/09 04:42:02 DEBUG : zend.txt: md5 = 6e2e4f347ff247f3cc77571261049221 OK
2026/09/09 04:42:05 DEBUG : sub dir/hello world: md5 = 1546ee90991620eb040146393164ea4b OK
2026/09/09 04:42:06 DEBUG : sub dir/hello world2: md5 = 3a81c2e676bcdd48dc99799eacc47cb8 OK
2026/09/09 04:42:09 DEBUG : sub dir/ignore dir/.ignore: md5 = b52117b4fcb097b0bdf7a9f46e34be83 OK
2026/09/09 04:42:11 DEBUG : sub dir/ignore dir/should be ignored: md5 = 99e1b95c846adb325a01f71983b3fa91 OK
2026/09/09 04:42:13 DEBUG : sub dir/sub sub dir/hello world3: md5 = 8b56afa865b6aea7f8af16552541f482 OK
2026/09/09 04:42:14 DEBUG : a.txt: Excluded (Size Filter)
2026/09/09 04:42:14 DEBUG : a.txt: Excluded
2026/09/09 04:42:15 DEBUG : sub dir/hello world: Excluded (Size Filter)
2026/09/09 04:42:15 DEBUG : sub dir/hello world: Excluded
2026/09/09 04:42:15 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2026/09/09 04:42:15 DEBUG : sub dir/hello world2: Excluded
2026/09/09 04:42:16 DEBUG : sub dir/ignore dir: Excluded
2026/09/09 04:42:16 DEBUG : sub dir/hello world: Excluded (Size Filter)
2026/09/09 04:42:16 DEBUG : sub dir/hello world: Excluded
2026/09/09 04:42:16 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2026/09/09 04:42:16 DEBUG : sub dir/hello world2: Excluded
2026/09/09 04:42:16 DEBUG : sub dir/ignore dir: Excluded
--- PASS: TestListDirSortedFn (24.84s)
=== RUN   TestListJSON
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:42:26 DEBUG : file1: md5 = 81f3fc5a308cfc8a3f8c2b6f0403db1a OK
2026/09/09 04:42:28 DEBUG : sub/file2: md5 = e6d749212fa66a496aca8ceb96d70755 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/09 04:42:32 ERROR : file1: Failed to read hash: hash type not supported
=== RUN   TestListJSON/Metadata
2026/09/09 04:42:32 DEBUG : eer8kka55qnghc34cq76ca668g: Fetching metadata
2026/09/09 04:42:32 DEBUG : 150fuo3cn4j1uenq6r4g4qk6t4: Fetching metadata
--- PASS: TestListJSON (11.01s)
    --- PASS: TestListJSON/Default (0.28s)
    --- PASS: TestListJSON/FilesOnly (0.28s)
    --- PASS: TestListJSON/DirsOnly (0.29s)
    --- PASS: TestListJSON/Recurse (0.60s)
    --- PASS: TestListJSON/SubDir (0.31s)
    --- PASS: TestListJSON/NoModTime (0.28s)
    --- PASS: TestListJSON/NoMimeType (0.27s)
    --- PASS: TestListJSON/ShowHash (0.29s)
    --- PASS: TestListJSON/HashTypes (0.29s)
    --- PASS: TestListJSON/Metadata (0.74s)
=== RUN   TestStatJSON
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:42:37 DEBUG : file1: md5 = 588c9f64fa1bdf2cd52e48da8cdc7b68 OK
2026/09/09 04:42:39 DEBUG : sub/file2: md5 = 92d1099a2950a9180af171fa174543ba 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/09 04:42:44 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jowotal3yepa/notfound"
2026/09/09 04:42:44 DEBUG : Creating backend with remote "TestDrive:crypt/7j5po6ap6o56utp59ssrp5mv5n67cnn3t2p2ou9rupj0tnhfqp0g/qe0i31qdkoejk60elps0ni5sqk"
--- PASS: TestStatJSON (13.58s)
    --- PASS: TestStatJSON/Root (0.29s)
    --- PASS: TestStatJSON/RootFilesOnly (0.00s)
    --- PASS: TestStatJSON/RootDirsOnly (0.29s)
    --- PASS: TestStatJSON/Dir (0.55s)
    --- PASS: TestStatJSON/DirWithTrailingSlash (0.30s)
    --- PASS: TestStatJSON/File (0.28s)
    --- PASS: TestStatJSON/NotFound (0.56s)
    --- PASS: TestStatJSON/DirFilesOnly (0.28s)
    --- PASS: TestStatJSON/FileFilesOnly (0.32s)
    --- PASS: TestStatJSON/NotFoundFilesOnly (0.27s)
    --- PASS: TestStatJSON/DirDirsOnly (0.30s)
    --- PASS: TestStatJSON/FileDirsOnly (0.28s)
    --- PASS: TestStatJSON/NotFoundDirsOnly (0.31s)
    --- PASS: TestStatJSON/RootNotFound (1.93s)
=== RUN   TestStatJSONMemory
2026/09/09 04:42:49 DEBUG : Creating backend with remote ":memory:"
2026/09/09 04:42:49 DEBUG : Memory root '': File to upload is small (5 bytes), uploading instead of streaming
2026/09/09 04:42:49 DEBUG : sub/file1: size = 5 OK
2026/09/09 04:42:49 DEBUG : sub/file1: md5 = 5d41402abc4b2a76b9719d911017c592 OK
2026/09/09 04:42:49 DEBUG : sub/file1: Size and md5 of src and dst objects identical
=== RUN   TestStatJSONMemory/Dir
=== RUN   TestStatJSONMemory/DirWithTrailingSlash
=== RUN   TestStatJSONMemory/File
=== RUN   TestStatJSONMemory/NotFound
--- PASS: TestStatJSONMemory (0.00s)
    --- PASS: TestStatJSONMemory/Dir (0.00s)
    --- PASS: TestStatJSONMemory/DirWithTrailingSlash (0.00s)
    --- PASS: TestStatJSONMemory/File (0.00s)
    --- PASS: TestStatJSONMemory/NotFound (0.00s)
=== RUN   TestStatJSONConfinement
--- PASS: TestStatJSONConfinement (0.00s)
=== RUN   TestMkdir
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:42:49 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa': Making directory
2026/09/09 04:42:49 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa': Making directory
--- PASS: TestMkdir (0.73s)
=== RUN   TestLsd
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:42:52 DEBUG : sub dir/hello world: md5 = 0a29cec9440b8fefc50a30175b759e96 OK
--- PASS: TestLsd (5.55s)
=== RUN   TestLs
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:42:57 DEBUG : potato2: md5 = 086d9747fd97c217c0c461107d0c7c6c OK
2026/09/09 04:42:58 DEBUG : empty space: md5 = 2b743ceccda5e8929b8f6d0923a33a6b OK
--- PASS: TestLs (5.24s)
=== RUN   TestLsWithFilesFrom
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:43:02 DEBUG : potato2: md5 = 81508b398f617185d498b3b39b29354f OK
2026/09/09 04:43:04 DEBUG : empty space: md5 = 6d9b75292ed08b620498a6bc58f50313 OK
2026/09/09 04:43:04 DEBUG : empty space: Excluded (FilesFrom Filter)
--- PASS: TestLsWithFilesFrom (5.67s)
=== RUN   TestLsLong
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:43:08 DEBUG : potato2: md5 = cbb3c775f864400aa427f7a0e489d098 OK
2026/09/09 04:43:10 DEBUG : empty space: md5 = fc00d7079eb881a1c48f3bc4a2450ff5 OK
--- PASS: TestLsLong (5.78s)
=== RUN   TestHashSums
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:43:13 DEBUG : potato2: md5 = a06d2d4c91467261d27167a6a9b89e2d OK
2026/09/09 04:43:15 DEBUG : empty space: md5 = 732d90fcc7f08f70392788b331931378 OK
--- PASS: TestHashSums (5.01s)
=== RUN   TestHashSumsWithErrors
2026/09/09 04:43:17 DEBUG : Creating backend with remote ":memory:"
2026/09/09 04:43:17 ERROR : sub/file1: hash unsupported: hash type not supported
2026/09/09 04:43:17 ERROR : file1: hash unsupported: hash type not supported
2026/09/09 04:43:17 ERROR : file1: hash unsupported: hash type not supported
--- PASS: TestHashSumsWithErrors (0.00s)
=== RUN   TestHashStream
2026/09/09 04:43:17 DEBUG : Creating md5 hash of 0 bytes read from input stream
2026/09/09 04:43:17 DEBUG : Creating md5 hash of 0 bytes read from input stream
2026/09/09 04:43:17 DEBUG : Creating sha1 hash of 0 bytes read from input stream
2026/09/09 04:43:17 DEBUG : Creating sha1 hash of 0 bytes read from input stream
2026/09/09 04:43:17 DEBUG : Creating md5 hash of 12 bytes read from input stream
2026/09/09 04:43:17 DEBUG : Creating md5 hash of 12 bytes read from input stream
2026/09/09 04:43:17 DEBUG : Creating sha1 hash of 12 bytes read from input stream
2026/09/09 04:43:17 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-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:43:18 DEBUG : potato2: md5 = d9e6860e5f7324a7a04d733bd6c85cff OK
2026/09/09 04:43:20 DEBUG : empty space: md5 = 5e226c152d69c474f5d20b7ea5f0966d OK
2026/09/09 04:43:22 DEBUG : sub dir/potato3: md5 = a2075a76c9535b63264600070d3d55e6 OK
--- PASS: TestCount (9.88s)
=== RUN   TestDelete
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:43:28 DEBUG : small: md5 = 502d0bd5998b56dea1f8f4109a3ee122 OK
2026/09/09 04:43:30 DEBUG : medium: md5 = bc7f31e196ad6d1acab536ca1156d232 OK
2026/09/09 04:43:31 DEBUG : large: md5 = bf346943e5f022d9163ff70fc61f04fc OK
2026/09/09 04:43:32 DEBUG : Waiting for deletions to finish
2026/09/09 04:43:32 DEBUG : large: Excluded (Size Filter)
2026/09/09 04:43:33 INFO  : small: Deleted
2026/09/09 04:43:33 INFO  : medium: Deleted
--- PASS: TestDelete (7.37s)
=== RUN   TestMaxDelete
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:43:36 DEBUG : small: md5 = b5ca4738272ac9f72d881186025b8633 OK
2026/09/09 04:43:37 DEBUG : medium: md5 = a59fb176b576eeff1f19f783df136189 OK
2026/09/09 04:43:39 DEBUG : large: md5 = df9089c99bcead175f953d386edf5427 OK
2026/09/09 04:43:39 DEBUG : Waiting for deletions to finish
2026/09/09 04:43:40 ERROR : large: Got fatal error on delete: --max-delete threshold reached
2026/09/09 04:43:40 INFO  : medium: Deleted
2026/09/09 04:43:40 INFO  : small: Deleted
--- PASS: TestMaxDelete (8.39s)
=== RUN   TestMaxDeleteSizeLargeFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:43:44 DEBUG : small: md5 = 12475932a8ed7b5a91530cd283f925a3 OK
2026/09/09 04:43:46 DEBUG : medium: md5 = 5750626f57b0313c910a42ceb5fc99d8 OK
2026/09/09 04:43:47 DEBUG : large: md5 = 630376f3a6ebcfd502158d192d8335ba OK
2026/09/09 04:43:47 DEBUG : Waiting for deletions to finish
2026/09/09 04:43:48 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached
2026/09/09 04:43:48 INFO  : medium: Deleted
2026/09/09 04:43:48 INFO  : small: Deleted
--- PASS: TestMaxDeleteSizeLargeFile (7.87s)
=== RUN   TestMaxDeleteSize
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:43:52 DEBUG : small: md5 = 4e174d2ea4b2449a7440d7d0b25b1238 OK
2026/09/09 04:43:53 DEBUG : medium: md5 = 4b151527405acf8857118d01a7890ea6 OK
2026/09/09 04:43:55 DEBUG : large: md5 = 031b314ddd79a9809bc9f56dd26efe1f OK
2026/09/09 04:43:55 DEBUG : Waiting for deletions to finish
2026/09/09 04:43:56 ERROR : medium: Got fatal error on delete: --max-delete-size threshold reached
2026/09/09 04:43:56 INFO  : small: Deleted
2026/09/09 04:43:56 INFO  : large: Deleted
--- PASS: TestMaxDeleteSize (7.61s)
=== RUN   TestReadFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:43:59 DEBUG : ReadFile: md5 = b3f521bef060c0cc72b9585c5b227b3c OK
--- PASS: TestReadFile (4.41s)
=== RUN   TestRetry
2026/09/09 04:44:02 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 1/5
2026/09/09 04:44:02 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 2/5
2026/09/09 04:44:02 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms
2026/09/09 04:44:02 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms
2026/09/09 04:44:02 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms
2026/09/09 04:44:02 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms
2026/09/09 04:44:02 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-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:44:04 DEBUG : file1: md5 = a34931dc43344900486abae1b3501402 OK
2026/09/09 04:44:05 DEBUG : file2: md5 = ab417414afb7590fbee1e2e9789b9727 OK
--- PASS: TestCat (14.28s)
=== RUN   TestPurge
2026/09/09 04:44:17 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-vujeger3keva"
2026/09/09 04:44:17 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/09/09 04:44:17 DEBUG : Creating backend with remote "TestDrive:crypt/otrjqoas93kvbn1la5oejiio4aoa6pjbbid57tcu0jsgbjpjr4d0"
2026/09/09 04:44:18 DEBUG : Creating backend with remote "/tmp/rclone148457301"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-vujeger3keva'", Local "Local file system at /tmp/rclone148457301", Modify Window "1ms"
2026/09/09 04:44:23 DEBUG : A1/B1/C1/one: md5 = abc979956c15b885f604902150645d58 OK
2026/09/09 04:44:23 INFO  : A2: Making directory
2026/09/09 04:44:24 INFO  : A1/B2: Making directory
2026/09/09 04:44:25 INFO  : A1/B2/C2: Making directory
2026/09/09 04:44:26 INFO  : A1/B1/C3: Making directory
2026/09/09 04:44:27 INFO  : A3: Making directory
2026/09/09 04:44:28 INFO  : A3/B3: Making directory
2026/09/09 04:44:29 INFO  : A3/B3/C4: Making directory
2026/09/09 04:44:32 DEBUG : A1/two: md5 = 5d3373acaaa3d31aca230dfddb9113b9 OK
2026/09/09 04:44:36 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-vujeger3keva': Purge remote
2026/09/09 04:44:36 NOTICE: purge failed: directory not found
--- PASS: TestPurge (19.67s)
=== RUN   TestRmdirsNoLeaveRoot
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:44:41 DEBUG : A1/B1/C1/one: md5 = f7772fd4456f2d638350b394cf62d1f0 OK
2026/09/09 04:44:41 INFO  : A2: Making directory
2026/09/09 04:44:42 INFO  : A1/B2: Making directory
2026/09/09 04:44:42 INFO  : A1/B2/C2: Making directory
2026/09/09 04:44:44 INFO  : A1/B1/C3: Making directory
2026/09/09 04:44:44 INFO  : A3: Making directory
2026/09/09 04:44:45 INFO  : A3/B3: Making directory
2026/09/09 04:44:47 INFO  : A3/B3/C4: Making directory
2026/09/09 04:44:49 DEBUG : A1/two: md5 = 8a7b6dab1ff98364ff05dd3987de393e OK
2026/09/09 04:44:51 DEBUG : removing 1 level 3 directories
2026/09/09 04:44:51 INFO  : A3/B3/C4: Removing directory
2026/09/09 04:44:54 DEBUG : removing 2 level 3 directories
2026/09/09 04:44:54 INFO  : A1/B2/C2: Removing directory
2026/09/09 04:44:54 INFO  : A1/B1/C3: Removing directory
2026/09/09 04:44:55 DEBUG : removing 2 level 2 directories
2026/09/09 04:44:55 INFO  : A3/B3: Removing directory
2026/09/09 04:44:55 INFO  : A1/B2: Removing directory
2026/09/09 04:44:55 DEBUG : removing 2 level 1 directories
2026/09/09 04:44:55 INFO  : A3: Removing directory
2026/09/09 04:44:55 INFO  : A2: Removing directory
2026/09/09 04:45:00 DEBUG : removing 1 level 3 directories
2026/09/09 04:45:00 INFO  : A1/B1/C1: Removing directory
2026/09/09 04:45:01 DEBUG : removing 1 level 2 directories
2026/09/09 04:45:01 INFO  : A1/B1: Removing directory
2026/09/09 04:45:02 DEBUG : removing 1 level 1 directories
2026/09/09 04:45:02 INFO  : A1: Removing directory
2026/09/09 04:45:02 DEBUG : removing 1 level 0 directories
2026/09/09 04:45:02 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa': Removing directory
--- PASS: TestRmdirsNoLeaveRoot (27.80s)
=== RUN   TestRmdirsLeaveRoot
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:45:05 INFO  : A1: Making directory
2026/09/09 04:45:06 INFO  : A1/B1: Making directory
2026/09/09 04:45:07 INFO  : A1/B1/C1: Making directory
2026/09/09 04:45:10 DEBUG : removing 1 level 3 directories
2026/09/09 04:45:10 INFO  : A1/B1/C1: Removing directory
2026/09/09 04:45:11 DEBUG : removing 1 level 2 directories
2026/09/09 04:45:11 INFO  : A1/B1: Removing directory
--- PASS: TestRmdirsLeaveRoot (9.99s)
=== RUN   TestRmdirsWithFilter
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:45:14 INFO  : A1: Making directory
2026/09/09 04:45:15 INFO  : A1/B1: Making directory
2026/09/09 04:45:16 INFO  : A1/B1/C1: Making directory
2026/09/09 04:45:19 DEBUG : removing 1 level 3 directories
2026/09/09 04:45:19 INFO  : A1/B1/C1: Removing directory
2026/09/09 04:45:20 DEBUG : removing 1 level 2 directories
2026/09/09 04:45:20 INFO  : A1/B1: Removing directory
--- PASS: TestRmdirsWithFilter (8.59s)
=== RUN   TestCopyURL
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:45:25 DEBUG : file1: md5 = cc59c92afc1c0585a32653b5aa322879 OK
2026/09/09 04:45:25 DEBUG : file1: size = 14 OK
2026/09/09 04:45:25 DEBUG : filename.txt: File name found in url
2026/09/09 04:45:27 DEBUG : filename.txt: md5 = 7f4d6717b28920a3a2f4b1c3fd4c6add OK
2026/09/09 04:45:27 DEBUG : filename.txt: size = 14 OK
2026/09/09 04:45:27 DEBUG : headerfilename.txt: filename found in Content-Disposition header.
2026/09/09 04:45:28 DEBUG : headerfilename.txt: md5 = ae7e863db1e962a0c4dc089bf0f1ede5 OK
2026/09/09 04:45:28 DEBUG : headerfilename.txt: size = 14 OK
2026/09/09 04:45:30 DEBUG : file2: md5 = 16d6a71984f891b7ee0e89d5619ddd48 OK
2026/09/09 04:45:30 DEBUG : file2: size = 14 OK
--- PASS: TestCopyURL (10.03s)
=== RUN   TestCopyURLDownloadHeaders
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:45:34 DEBUG : file1: md5 = ae0fc45c396dcd88d7ec493c2599eb77 OK
2026/09/09 04:45:34 DEBUG : file1: size = 14 OK
--- PASS: TestCopyURLDownloadHeaders (2.66s)
=== RUN   TestCopyURLToWriter
--- PASS: TestCopyURLToWriter (0.00s)
=== RUN   TestMoveFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:45:36 DEBUG : file1: Need to transfer - File not found at Destination
2026/09/09 04:45:38 DEBUG : sub/file2: md5 = fbdb0a1f4777bebd53f60858743c30e7 OK
2026/09/09 04:45:38 DEBUG : sub/file2: size = 14 OK
2026/09/09 04:45:38 INFO  : file1: Copied (new) to: sub/file2
2026/09/09 04:45:38 INFO  : file1: Deleted
2026/09/09 04:45:39 DEBUG : sub/file2: size = 14 OK
2026/09/09 04:45:39 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2026/09/09 04:45:39 DEBUG : file1: Unchanged skipping
2026/09/09 04:45:39 INFO  : file1: Deleted
2026/09/09 04:45:40 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa': don't need to copy/move sub/file2, it is already at target location
--- PASS: TestMoveFile (6.89s)
=== RUN   TestMoveFileWithIgnoreExisting
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:45:42 DEBUG : file1: Need to transfer - File not found at Destination
2026/09/09 04:45:44 DEBUG : file1: md5 = 03d1bc60203411826aa5f0545835b242 OK
2026/09/09 04:45:44 DEBUG : file1: size = 14 OK
2026/09/09 04:45:44 INFO  : file1: Copied (new)
2026/09/09 04:45:44 INFO  : file1: Deleted
2026/09/09 04:45:45 DEBUG : file1: Destination exists, skipping
2026/09/09 04:45:45 DEBUG : file1: Not removing source file as destination file exists and --ignore-existing is set
--- PASS: TestMoveFileWithIgnoreExisting (3.76s)
=== RUN   TestCaseInsensitiveMoveFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
--- PASS: TestCaseInsensitiveMoveFile (0.49s)
=== RUN   TestCaseInsensitiveMoveFileDryRun
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
--- PASS: TestCaseInsensitiveMoveFileDryRun (0.50s)
=== RUN   TestMoveFileBackupDir
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:45:49 DEBUG : dst/file1: md5 = be03c9486c3af41b173eae6aadc75df7 OK
2026/09/09 04:45:50 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jowotal3yepa/backup"
2026/09/09 04:45:50 DEBUG : Creating backend with remote "TestDrive:crypt/7j5po6ap6o56utp59ssrp5mv5n67cnn3t2p2ou9rupj0tnhfqp0g/1nrff024r7pq65ecp72fc28jb0"
2026/09/09 04:45:52 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone3461562559)
2026/09/09 04:45:52 DEBUG : dst/file1: size = 18 (Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa')
2026/09/09 04:45:52 DEBUG : dst/file1: Sizes differ
2026/09/09 04:45:55 INFO  : dst/file1: Moved (server-side)
2026/09/09 04:45:57 DEBUG : dst/file1: md5 = d0e43b2fa398b6bbbe444c88915bf841 OK
2026/09/09 04:45:57 DEBUG : dst/file1: size = 14 OK
2026/09/09 04:45:57 INFO  : dst/file1: Copied (new)
2026/09/09 04:45:57 INFO  : dst/file1: Deleted
--- PASS: TestMoveFileBackupDir (15.35s)
=== 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-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:46:05 DEBUG : A1/one: md5 = 7f8475b592cfe567e8082b6a3db78983 OK
2026/09/09 04:46:06 DEBUG : A1/two: md5 = e4225896bf959a1050610c54d3e4d4d4 OK
2026/09/09 04:46:09 DEBUG : A1/B1/three: md5 = 85f001952a40a81248d255babb0bcfe8 OK
2026/09/09 04:46:11 DEBUG : A1/B1/C1/four: md5 = 7bb56db1ddc2a6fe5597ef67a19068be OK
2026/09/09 04:46:14 DEBUG : A1/B1/C2/five: md5 = c31d5ccc32e7cc69e9d2662de36ae657 OK
2026/09/09 04:46:14 INFO  : A1/B2: Making directory
2026/09/09 04:46:15 INFO  : A1/B1/C3: Making directory
2026/09/09 04:46:26 INFO  : A2/B1/three: Moved (server-side) to: A3/B1/three
2026/09/09 04:46:26 INFO  : A2/one: Moved (server-side) to: A3/one
2026/09/09 04:46:26 INFO  : A2/B1/C1/four: Moved (server-side) to: A3/B1/C1/four
2026/09/09 04:46:26 INFO  : A2/B1/C2/five: Moved (server-side) to: A3/B1/C2/five
2026/09/09 04:46:26 INFO  : A2/two: Moved (server-side) to: A3/two
2026/09/09 04:46:32 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa': Can't DirMove - falling back to file moves: can't move directory - incompatible remotes
2026/09/09 04:46:40 INFO  : A3/two: Moved (server-side) to: A4/two
2026/09/09 04:46:40 INFO  : A3/B1/C1/four: Moved (server-side) to: A4/B1/C1/four
2026/09/09 04:46:40 INFO  : A3/B1/three: Moved (server-side) to: A4/B1/three
2026/09/09 04:46:40 INFO  : A3/B1/C2/five: Moved (server-side) to: A4/B1/C2/five
2026/09/09 04:46:40 INFO  : A3/one: Moved (server-side) to: A4/one
--- PASS: TestDirMove (52.21s)
=== RUN   TestGetFsInfo
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
--- PASS: TestGetFsInfo (0.44s)
=== RUN   TestRcat
=== RUN   TestRcat/withChecksum=false,ignoreChecksum=false
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:46:55 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa': File to upload is small (34 bytes), uploading instead of streaming
2026/09/09 04:46:57 DEBUG : no_checksum_small_file_from_pipe: md5 = ed969742480df71c87496e5d0ff47371 OK
2026/09/09 04:46:57 DEBUG : no_checksum_small_file_from_pipe: size = 34 OK
2026/09/09 04:46:57 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only
2026/09/09 04:46:57 DEBUG : no_checksum_small_file_from_pipe: Size of src and dst objects identical
2026/09/09 04:46:58 DEBUG : qc9k4uauqa9ls6a2r6lo8jf4b1m7r0510pkurois1cc70egbs4h0: Sending chunk 0 length 102465
2026/09/09 04:46:59 DEBUG : no_checksum_big_file_from_pipe: md5 = f60a279bc917d79ec495bda89167ca21 OK
2026/09/09 04:46:59 DEBUG : no_checksum_big_file_from_pipe: size = 102401 OK
2026/09/09 04:46:59 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-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:47:01 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa': File to upload is small (34 bytes), uploading instead of streaming
2026/09/09 04:47:02 DEBUG : with_checksum_small_file_from_pipe: md5 = 5d6086c3c12d66058febf2dbf807a1fa OK
2026/09/09 04:47:02 DEBUG : with_checksum_small_file_from_pipe: size = 34 OK
2026/09/09 04:47:02 DEBUG : with_checksum_small_file_from_pipe: Size of src and dst objects identical
2026/09/09 04:47:03 DEBUG : e74jo8a7lr1aimg2u78fct67gnuetc1qujiofaci0c8fnq7rjt1d4i296pcklqhgdihs03etvgdi4: Sending chunk 0 length 102465
2026/09/09 04:47:04 DEBUG : with_checksum_big_file_from_pipe: md5 = 0825582941def0c2afdac54ad0a1ea9e OK
2026/09/09 04:47:04 DEBUG : with_checksum_big_file_from_pipe: size = 102401 OK
2026/09/09 04:47:04 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-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:47:06 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa': File to upload is small (34 bytes), uploading instead of streaming
2026/09/09 04:47:08 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK
2026/09/09 04:47:08 DEBUG : ignore_checksum_small_file_from_pipe: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2026/09/09 04:47:08 DEBUG : 1963rn6olajfbjn4nbqi9nacu7bk879vpf0d2bg8r0ndcqqsfntob0urt056p0qujf574rqnb5f62: Sending chunk 0 length 102465
2026/09/09 04:47:10 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK
2026/09/09 04:47:10 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-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:47:12 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa': File to upload is small (34 bytes), uploading instead of streaming
2026/09/09 04:47:13 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK
2026/09/09 04:47:13 DEBUG : ignore_checksum_small_file_from_pipe: Size of src and dst objects identical
2026/09/09 04:47:14 DEBUG : 1963rn6olajfbjn4nbqi9nacu7bk879vpf0d2bg8r0ndcqqsfntob0urt056p0qujf574rqnb5f62: Sending chunk 0 length 102465
2026/09/09 04:47:15 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK
2026/09/09 04:47:15 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical
--- PASS: TestRcat (21.65s)
    --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (5.37s)
    --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (5.46s)
    --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (5.57s)
    --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (5.25s)
=== RUN   TestRcatMetadata
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
=== RUN   TestRcatMetadata/Normal
2026/09/09 04:47:17 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa': File to upload is small (48 bytes), uploading instead of streaming
2026/09/09 04:47:18 DEBUG : rcat_metadata: md5 = f31dbf36c59f139b58cabf3e633b481d OK
2026/09/09 04:47:18 DEBUG : rcat_metadata: size = 48 OK
2026/09/09 04:47:18 DEBUG : rcat_metadata: Size of src and dst objects identical
=== RUN   TestRcatMetadata/ViaDisk
2026/09/09 04:47:20 DEBUG : rijssoobhbn85c5p1imhg55m6frank1nlplla6hkdjlqmqb2471g: Sending chunk 0 length 111
2026/09/09 04:47:21 DEBUG : rcat_metadata_uploadcutoff0: md5 = 11bbe16b0075246879b20fd17026184d OK
2026/09/09 04:47:21 DEBUG : rcat_metadata_uploadcutoff0: size = 63 OK
2026/09/09 04:47:21 DEBUG : rcat_metadata_uploadcutoff0: Size of src and dst objects identical
--- PASS: TestRcatMetadata (6.19s)
    --- PASS: TestRcatMetadata/Normal (2.75s)
    --- PASS: TestRcatMetadata/ViaDisk (2.98s)
=== RUN   TestRcatSize
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:47:24 DEBUG : potato1: md5 = 3b5399854f169769307ff1940b199b85 OK
2026/09/09 04:47:24 DEBUG : potato1: size = 60 OK
2026/09/09 04:47:24 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa': File to upload is small (60 bytes), uploading instead of streaming
2026/09/09 04:47:26 DEBUG : potato2: md5 = bd1cdb8c112de48a2af4da94a4e9915d OK
2026/09/09 04:47:26 DEBUG : potato2: size = 60 OK
2026/09/09 04:47:26 DEBUG : potato2: Size of src and dst objects identical
--- PASS: TestRcatSize (5.10s)
=== RUN   TestRcatSizeShortEOF
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:47:29 DEBUG : potato1: md5 = 857292847dfaef924a7b689306781961 OK
2026/09/09 04:47:29 DEBUG : potato1: size = 120 (Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa')
2026/09/09 04:47:29 DEBUG : potato1: size = 60 (Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa')
2026/09/09 04:47:29 ERROR : potato1: corrupted on transfer: sizes differ src 120 vs dst(Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa') 60
2026/09/09 04:47:29 INFO  : potato1: Removing failed copy
--- PASS: TestRcatSizeShortEOF (2.64s)
=== RUN   TestRcatSizeMetadata
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:47:32 DEBUG : potato1: md5 = a30c438ace6f991f7cdd9fd6731f47e3 OK
2026/09/09 04:47:32 DEBUG : potato1: size = 60 OK
2026/09/09 04:47:32 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa': File to upload is small (60 bytes), uploading instead of streaming
2026/09/09 04:47:34 DEBUG : potato2: md5 = ad906dbc7c31a75be537da15d8037d8d OK
2026/09/09 04:47:34 DEBUG : potato2: size = 60 OK
2026/09/09 04:47:34 DEBUG : potato2: Size of src and dst objects identical
--- PASS: TestRcatSizeMetadata (5.54s)
=== RUN   TestRcatSizeUploadHeaders
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:47:38 DEBUG : potato1: md5 = 17b59d82df4a5aecbb05510375e96e5f OK
2026/09/09 04:47:38 DEBUG : potato1: size = 60 OK
--- PASS: TestRcatSizeUploadHeaders (2.49s)
=== RUN   TestRcatSizeChecksum
=== RUN   TestRcatSizeChecksum/Corrupted
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", 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-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:47:40 DEBUG : potato4: md5 = 5aa6e6894fb04cc64f9443ed9af597c7 OK
2026/09/09 04:47:40 DEBUG : potato4: size = 60 (Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa')
2026/09/09 04:47:40 DEBUG : potato4: size = 59 (Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa')
2026/09/09 04:47:40 ERROR : potato4: corrupted on transfer: sizes differ src 60 vs dst(Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa') 59
2026/09/09 04:47:40 INFO  : potato4: Removing failed copy
=== RUN   TestRcatSizeChecksum/IgnoreChecksum
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:47:43 DEBUG : potato2: size = 60 OK
=== RUN   TestRcatSizeChecksum/NoHashes
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:47:46 DEBUG : potato3: md5 = 056497c4649a7ea74546b01693e01873 OK
2026/09/09 04:47:46 DEBUG : potato3: size = 60 OK
--- PASS: TestRcatSizeChecksum (8.63s)
    --- SKIP: TestRcatSizeChecksum/Corrupted (0.48s)
    --- PASS: TestRcatSizeChecksum/SizeDiffers (2.59s)
    --- PASS: TestRcatSizeChecksum/IgnoreChecksum (2.79s)
    --- PASS: TestRcatSizeChecksum/NoHashes (2.76s)
=== RUN   TestTouchDir
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:47:49 DEBUG : potato2: md5 = aaa98f181fb482ada85b28cfa8cd00c7 OK
2026/09/09 04:47:50 DEBUG : empty space: md5 = c93ad5152ed61863f00e0583f6cd5af0 OK
2026/09/09 04:47:53 DEBUG : sub dir/potato3: md5 = 89e5fecc355f7834bc8b251a3eaa946b OK
2026/09/09 04:47:54 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa': Touching "sub dir/potato3"
2026/09/09 04:47:54 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa': Touching "potato2"
2026/09/09 04:47:54 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa': Touching "empty space"
--- PASS: TestTouchDir (11.10s)
=== RUN   TestMkdirMetadata
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:47:58 DEBUG : dir with metadata: Making directory with metadata
2026/09/09 04:47:59 INFO  : dir with metadata: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z)
--- PASS: TestMkdirMetadata (2.65s)
=== RUN   TestMkdirModTime
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:48:01 DEBUG : directory with modtime: Making directory with metadata
2026/09/09 04:48:02 INFO  : directory with modtime: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2026/09/09 04:48:02 NOTICE: dry run directory with modtime: Skipped make directory as --dry-run is set
--- PASS: TestMkdirModTime (2.75s)
=== RUN   TestCopyDirMetadata
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:48:04 DEBUG : dir with metadata to be copied: Making directory with metadata
2026/09/09 04:48:04 INFO  : dir with metadata to be copied: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z)
2026/09/09 04:48:04 DEBUG : Google drive root 'crypt/7j5po6ap6o56utp59ssrp5mv5n67cnn3t2p2ou9rupj0tnhfqp0g': Skipping btime metadata as can't update it on an existing file: 2026-09-09T04:48:04.208030903Z
2026/09/09 04:48:05 INFO  : non existent directory: Updated directory metadata
2026/09/09 04:48:07 DEBUG : Google drive root 'crypt/7j5po6ap6o56utp59ssrp5mv5n67cnn3t2p2ou9rupj0tnhfqp0g': Skipping btime metadata as can't update it on an existing file: 2026-09-09T04:48:04.208030903Z
2026/09/09 04:48:08 INFO  : existing directory: Updated directory metadata
--- PASS: TestCopyDirMetadata (5.57s)
=== RUN   TestSetDirModTime
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:48:09 DEBUG : set modtime on non existent directory: Skipping set directory modification time as --no-update-dir-modtime is set
2026/09/09 04:48:12 INFO  : set modtime on existing directory: Set directory modification time (using SetModTime)
2026/09/09 04:48:12 INFO  : set modtime on existing directory: Set directory modification time (using DirSetModTime)
--- PASS: TestSetDirModTime (4.82s)
=== RUN   TestDirsEqual
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:48:14 DEBUG : dir with metadata to be copied: Making directory with metadata
2026/09/09 04:48:14 INFO  : dir with metadata to be copied: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z)
2026/09/09 04:48:14 DEBUG : dst: Making directory with metadata
2026/09/09 04:48:15 INFO  : dst: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z)
2026/09/09 04:48:15 DEBUG : dst: Directory modification time the same (differ by -999.999µs, within tolerance 1ms)
2026/09/09 04:48:16 INFO  : dst: Set directory modification time (using SetModTime)
2026/09/09 04:48:17 INFO  : dst: Set directory modification time (using SetModTime)
2026/09/09 04:48:17 DEBUG : dst: Directory modification time the same (differ by 1ns, within tolerance 1ms)
2026/09/09 04:48:17 INFO  : dst: Set directory modification time (using SetModTime)
2026/09/09 04:48:17 DEBUG : dst: Destination directory is newer than source, skipping
--- PASS: TestDirsEqual (4.51s)
=== RUN   TestRemoveExisting
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa'", Local "Local file system at /tmp/rclone3461562559", Modify Window "1ms"
2026/09/09 04:48:21 DEBUG : sub dir/test remove existing: md5 = 6ba6a943870a0540067444babddaf8d2 OK
2026/09/09 04:48:23 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: md5 = ca3bc39dfcdd2a078786283042e95577 OK
2026/09/09 04:48:25 DEBUG : sub dir/test remove existing: TEST: renaming existing object to "sub dir/test remove existing.ruyuxax0" before starting
2026/09/09 04:48:26 DEBUG : sub dir/test remove existing.ruyuxax0: TEST: removing renamed existing file after operation
2026/09/09 04:48:27 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: TEST: renaming existing object to "sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.nitatis8" before starting
2026/09/09 04:48:28 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.nitatis8: TEST: renaming existing back after failed operation
2026/09/09 04:48:30 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: TEST: renaming existing object to "sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.duxeciw4" before starting
2026/09/09 04:48:31 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.duxeciw4: TEST: removing renamed existing file after operation
--- PASS: TestRemoveExisting (14.95s)
=== 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/09 04:48:34 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jowotal3yepa': Purge remote
"./operations.test -test.v -test.timeout 1h0m0s -remote TestCryptDrive: -verbose -fast-list" - Finished OK in 16m41.126490213s (try 1/5)