"./operations.test -test.v -test.timeout 1h0m0s -remote TestCryptDrive: -verbose" - Starting (try 1/5)
2026/10/19 03:46:57 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-weyapag1ziqu"
2026/10/19 03:46:57 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/19 03:46:57 DEBUG : Creating backend with remote "TestDrive:crypt/mgqrlgabkh4qu4jmvhvvahqod9cv2gjeqsmi6sk74c30p3v3f7jg"
2026/10/19 03:46:59 DEBUG : Creating backend with remote "/tmp/rclone144652753"
=== 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-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
    multithread_test.go:120: multithread writing not supported
--- SKIP: TestMultithreadCopy (0.30s)
=== RUN   TestMultithreadCopyAbort
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
    multithread_test.go:120: multithread writing not supported
--- SKIP: TestMultithreadCopyAbort (0.32s)
=== RUN   TestMultithreadCopyWriterAtErrors
=== RUN   TestMultithreadCopyWriterAtErrors/open/fatal=false
2026/10/19 03:46:59 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt
=== RUN   TestMultithreadCopyWriterAtErrors/open/fatal=true
2026/10/19 03:46:59 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt
=== RUN   TestMultithreadCopyWriterAtErrors/write/fatal=false
2026/10/19 03:46:59 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt
2026/10/19 03:46:59 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams
2026/10/19 03:46:59 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting
2026/10/19 03:46:59 DEBUG : file.txt: writing chunk 1
2026/10/19 03:46:59 DEBUG : file.txt: multi-thread copy: chunk 2/4 failed: multi-thread copy: failed to write chunk: disk full
2026/10/19 03:46:59 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting
2026/10/19 03:46:59 DEBUG : file.txt: writing chunk 2
2026/10/19 03:46:59 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting
2026/10/19 03:46:59 DEBUG : file.txt: multi-thread copy: chunk 3/4 failed: multi-thread copy: failed to write chunk: disk full
2026/10/19 03:46:59 DEBUG : file.txt: writing chunk 0
2026/10/19 03:46:59 DEBUG : file.txt: multi-thread copy: chunk 1/4 failed: multi-thread copy: failed to write chunk: disk full
2026/10/19 03:46:59 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit
2026/10/19 03:46:59 DEBUG : file.txt: multi-thread copy: abort failed: multi-thread copy: failed to find temp file when aborting chunk writer: object not found
=== RUN   TestMultithreadCopyWriterAtErrors/write/fatal=true
2026/10/19 03:46:59 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt
2026/10/19 03:46:59 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams
2026/10/19 03:46:59 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting
2026/10/19 03:46:59 DEBUG : file.txt: writing chunk 1
2026/10/19 03:46:59 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting
2026/10/19 03:46:59 DEBUG : file.txt: writing chunk 0
2026/10/19 03:46:59 DEBUG : file.txt: multi-thread copy: chunk 2/4 failed: multi-thread copy: failed to write chunk: disk full
2026/10/19 03:46:59 DEBUG : file.txt: multi-thread copy: chunk 1/4 failed: multi-thread copy: failed to write chunk: disk full
2026/10/19 03:46:59 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting
2026/10/19 03:46:59 DEBUG : file.txt: writing chunk 2
2026/10/19 03:46:59 DEBUG : file.txt: multi-thread copy: chunk 3/4 failed: multi-thread copy: failed to write chunk: disk full
2026/10/19 03:46:59 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit
2026/10/19 03:46:59 DEBUG : file.txt: multi-thread copy: abort failed: multi-thread copy: failed to find temp file when aborting chunk writer: object not found
=== RUN   TestMultithreadCopyWriterAtErrors/close/fatal=false
2026/10/19 03:46:59 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt
2026/10/19 03:46:59 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams
2026/10/19 03:46:59 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting
2026/10/19 03:46:59 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting
2026/10/19 03:46:59 DEBUG : file.txt: writing chunk 1
2026/10/19 03:46:59 DEBUG : file.txt: writing chunk 0
2026/10/19 03:46:59 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 finished
2026/10/19 03:46:59 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 finished
2026/10/19 03:46:59 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting
2026/10/19 03:46:59 DEBUG : file.txt: writing chunk 2
2026/10/19 03:46:59 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 starting
2026/10/19 03:46:59 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 finished
2026/10/19 03:46:59 DEBUG : file.txt: writing chunk 3
2026/10/19 03:46:59 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 finished
2026/10/19 03:46:59 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit
2026/10/19 03:46:59 DEBUG : file.txt: multi-thread copy: abort failed: multi-thread copy: failed to find temp file when aborting chunk writer: object not found
=== RUN   TestMultithreadCopyWriterAtErrors/close/fatal=true
2026/10/19 03:46:59 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt
2026/10/19 03:46:59 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams
2026/10/19 03:46:59 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting
2026/10/19 03:46:59 DEBUG : file.txt: writing chunk 1
2026/10/19 03:46:59 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 finished
2026/10/19 03:46:59 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting
2026/10/19 03:46:59 DEBUG : file.txt: writing chunk 2
2026/10/19 03:46:59 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 finished
2026/10/19 03:46:59 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 starting
2026/10/19 03:46:59 DEBUG : file.txt: writing chunk 3
2026/10/19 03:46:59 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 finished
2026/10/19 03:46:59 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting
2026/10/19 03:46:59 DEBUG : file.txt: writing chunk 0
2026/10/19 03:46:59 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 finished
2026/10/19 03:46:59 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit
2026/10/19 03:46:59 DEBUG : file.txt: multi-thread copy: abort failed: multi-thread copy: failed to find temp file when aborting chunk writer: object not found
--- PASS: TestMultithreadCopyWriterAtErrors (0.00s)
    --- PASS: TestMultithreadCopyWriterAtErrors/open/fatal=false (0.00s)
    --- PASS: TestMultithreadCopyWriterAtErrors/open/fatal=true (0.00s)
    --- PASS: TestMultithreadCopyWriterAtErrors/write/fatal=false (0.00s)
    --- PASS: TestMultithreadCopyWriterAtErrors/write/fatal=true (0.00s)
    --- PASS: TestMultithreadCopyWriterAtErrors/close/fatal=false (0.00s)
    --- PASS: TestMultithreadCopyWriterAtErrors/close/fatal=true (0.00s)
=== RUN   TestSizeDiffers
2026/10/19 03:46:59 DEBUG : a: size = 0 OK
2026/10/19 03:46:59 DEBUG : a: size = 1 (memory)
2026/10/19 03:46:59 DEBUG : a: size = 2 (memory)
--- PASS: TestSizeDiffers (0.00s)
=== RUN   TestDirTransferEntry
--- PASS: TestDirTransferEntry (0.00s)
=== RUN   TestReOpen
=== RUN   TestReOpen/Normal
=== RUN   TestReOpen/Normal/Basics
2026/10/19 03:46:59 DEBUG : potato: Seek from 10 to 0
=== RUN   TestReOpen/Normal/ErrorAtStart
=== RUN   TestReOpen/Normal/WithErrors
2026/10/19 03:46:59 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2026/10/19 03:46:59 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2026/10/19 03:46:59 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN   TestReOpen/Normal/TooManyErrors
2026/10/19 03:46:59 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2026/10/19 03:46:59 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2026/10/19 03:46:59 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2026/10/19 03:46:59 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN   TestReOpen/Normal/ReadAt
2026/10/19 03:46:59 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/10/19 03:46:59 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/10/19 03:46:59 DEBUG : potato: Seek from 5 to 1
=== RUN   TestReOpen/Normal/Seek
2026/10/19 03:46:59 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/10/19 03:46:59 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/10/19 03:46:59 DEBUG : potato: Seek from 5 to 2
=== RUN   TestReOpen/Normal/AccountRead
=== RUN   TestReOpen/Normal/AccountReadDelay
2026/10/19 03:46:59 DEBUG : potato: Seek from 10 to 0
2026/10/19 03:46:59 DEBUG : potato: Seek from 10 to 0
2026/10/19 03:46:59 DEBUG : potato: Seek from 10 to 0
=== RUN   TestReOpen/Normal/AccountReadError
=== RUN   TestReOpen/WithRangeOption
=== RUN   TestReOpen/WithRangeOption/Basics
2026/10/19 03:46:59 DEBUG : potato: Seek from 7 to 0
=== RUN   TestReOpen/WithRangeOption/ErrorAtStart
=== RUN   TestReOpen/WithRangeOption/WithErrors
2026/10/19 03:46:59 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2026/10/19 03:46:59 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2026/10/19 03:46:59 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN   TestReOpen/WithRangeOption/TooManyErrors
2026/10/19 03:46:59 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2026/10/19 03:46:59 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2026/10/19 03:46:59 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2026/10/19 03:46:59 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN   TestReOpen/WithRangeOption/ReadAt
2026/10/19 03:46:59 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/10/19 03:46:59 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/10/19 03:46:59 DEBUG : potato: Seek from 5 to 1
=== RUN   TestReOpen/WithRangeOption/Seek
2026/10/19 03:46:59 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/10/19 03:46:59 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/10/19 03:46:59 DEBUG : potato: Seek from 5 to 2
2026/10/19 03:46:59 DEBUG : potato: Seek from 7 to 4
=== RUN   TestReOpen/WithRangeOption/AccountRead
=== RUN   TestReOpen/WithRangeOption/AccountReadDelay
2026/10/19 03:46:59 DEBUG : potato: Seek from 7 to 0
2026/10/19 03:46:59 DEBUG : potato: Seek from 7 to 0
2026/10/19 03:46:59 DEBUG : potato: Seek from 7 to 0
=== RUN   TestReOpen/WithRangeOption/AccountReadError
=== RUN   TestReOpen/WithSeekOption
=== RUN   TestReOpen/WithSeekOption/Basics
2026/10/19 03:46:59 DEBUG : potato: Seek from 8 to 0
=== RUN   TestReOpen/WithSeekOption/ErrorAtStart
=== RUN   TestReOpen/WithSeekOption/WithErrors
2026/10/19 03:46:59 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2026/10/19 03:46:59 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2026/10/19 03:46:59 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN   TestReOpen/WithSeekOption/TooManyErrors
2026/10/19 03:46:59 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2026/10/19 03:46:59 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2026/10/19 03:46:59 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2026/10/19 03:46:59 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN   TestReOpen/WithSeekOption/ReadAt
2026/10/19 03:46:59 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/10/19 03:46:59 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/10/19 03:46:59 DEBUG : potato: Seek from 5 to 1
=== RUN   TestReOpen/WithSeekOption/Seek
2026/10/19 03:46:59 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/10/19 03:46:59 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/10/19 03:46:59 DEBUG : potato: Seek from 5 to 2
2026/10/19 03:46:59 DEBUG : potato: Seek from 7 to 5
=== RUN   TestReOpen/WithSeekOption/AccountRead
=== RUN   TestReOpen/WithSeekOption/AccountReadDelay
2026/10/19 03:46:59 DEBUG : potato: Seek from 8 to 0
2026/10/19 03:46:59 DEBUG : potato: Seek from 8 to 0
2026/10/19 03:46:59 DEBUG : potato: Seek from 8 to 0
=== RUN   TestReOpen/WithSeekOption/AccountReadError
=== RUN   TestReOpen/UnknownSize
=== RUN   TestReOpen/UnknownSize/Basics
2026/10/19 03:46:59 DEBUG : potato: Seek from 9 to 0
=== RUN   TestReOpen/UnknownSize/ErrorAtStart
=== RUN   TestReOpen/UnknownSize/WithErrors
2026/10/19 03:46:59 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2026/10/19 03:46:59 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2026/10/19 03:46:59 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN   TestReOpen/UnknownSize/TooManyErrors
2026/10/19 03:46:59 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2026/10/19 03:46:59 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2026/10/19 03:46:59 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2026/10/19 03:46:59 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN   TestReOpen/UnknownSize/ReadAt
2026/10/19 03:46:59 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/10/19 03:46:59 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/10/19 03:46:59 DEBUG : potato: Seek from 5 to 1
=== RUN   TestReOpen/UnknownSize/Seek
2026/10/19 03:46:59 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/10/19 03:46:59 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/10/19 03:46:59 DEBUG : potato: Seek from 5 to 2
2026/10/19 03:46:59 DEBUG : potato: Seek from 7 to 6
=== RUN   TestReOpen/UnknownSize/AccountRead
=== RUN   TestReOpen/UnknownSize/AccountReadDelay
2026/10/19 03:46:59 DEBUG : potato: Seek from 9 to 0
2026/10/19 03:46:59 DEBUG : potato: Seek from 9 to 0
2026/10/19 03:46:59 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-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 03:47:02 DEBUG : rutabaga: md5 = 269a87bafe5b6866ac16621a5c2df300 OK
=== RUN   TestCheck/1
=== RUN   TestCheck/2
2026/10/19 03:47:05 DEBUG : empty space: md5 = 2a41906f426efbd1d0baf6d0b62384d3 OK
=== RUN   TestCheck/3
2026/10/19 03:47:08 DEBUG : potato2: md5 = 88ebc9c2ec438d16cab09f9c79e67cad OK
=== RUN   TestCheck/4
=== RUN   TestCheck/5
2026/10/19 03:47:11 DEBUG : remotepotato: md5 = 09055fba2c62c18c97f267380eaf8ec1 OK
=== RUN   TestCheck/6
=== RUN   TestCheck/7
--- PASS: TestCheck (15.78s)
    --- PASS: TestCheck/1 (0.32s)
    --- PASS: TestCheck/2 (0.33s)
    --- PASS: TestCheck/3 (0.34s)
    --- PASS: TestCheck/4 (0.32s)
    --- PASS: TestCheck/5 (0.30s)
    --- PASS: TestCheck/6 (0.35s)
    --- PASS: TestCheck/7 (0.35s)
=== RUN   TestCheckFsError
2026/10/19 03:47:15 DEBUG : Creating backend with remote "nonexistent"
2026/10/19 03:47:15 DEBUG : Creating backend with remote "nonexistent"
2026/10/19 03:47:15 DEBUG : Local file system at nonexistent: Waiting for checks to finish
2026/10/19 03:47:15 ERROR : Local file system at nonexistent: error reading source root directory: directory not found
2026/10/19 03:47:15 NOTICE: Local file system at nonexistent: 0 differences found
2026/10/19 03:47:15 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-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 03:47:17 DEBUG : rutabaga: md5 = c1b263a99e8eb9fa0d0598b6f6b24608 OK
=== RUN   TestCheckDownload/1
=== RUN   TestCheckDownload/2
2026/10/19 03:47:21 DEBUG : empty space: md5 = b287685326298473aa0fefffd9e79947 OK
=== RUN   TestCheckDownload/3
2026/10/19 03:47:24 DEBUG : potato2: md5 = cf88a9a77b6f53145d821315df1b684d OK
=== RUN   TestCheckDownload/4
=== RUN   TestCheckDownload/5
2026/10/19 03:47:29 DEBUG : remotepotato: md5 = 99971e6fbefd110469ee3d29f44fc721 OK
=== RUN   TestCheckDownload/6
=== RUN   TestCheckDownload/7
--- PASS: TestCheckDownload (18.93s)
    --- PASS: TestCheckDownload/1 (1.04s)
    --- PASS: TestCheckDownload/2 (1.00s)
    --- PASS: TestCheckDownload/3 (0.81s)
    --- PASS: TestCheckDownload/4 (1.09s)
    --- PASS: TestCheckDownload/5 (1.04s)
    --- PASS: TestCheckDownload/6 (0.87s)
    --- PASS: TestCheckDownload/7 (0.88s)
=== RUN   TestCheckSizeOnly
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 03:47:36 DEBUG : rutabaga: md5 = a422c57e3c642c87cfdac7f0d44942d3 OK
=== RUN   TestCheckSizeOnly/1
=== RUN   TestCheckSizeOnly/2
2026/10/19 03:47:39 DEBUG : empty space: md5 = e4b98af3cfb47398849e153b4e9a4b01 OK
=== RUN   TestCheckSizeOnly/3
2026/10/19 03:47:42 DEBUG : potato2: md5 = 6d1dba6518745e007e991c1bf862baf7 OK
=== RUN   TestCheckSizeOnly/4
=== RUN   TestCheckSizeOnly/5
2026/10/19 03:47:45 DEBUG : remotepotato: md5 = 81a1221599696f5868d569bcf0a07724 OK
=== RUN   TestCheckSizeOnly/6
=== RUN   TestCheckSizeOnly/7
--- PASS: TestCheckSizeOnly (15.10s)
    --- PASS: TestCheckSizeOnly/1 (0.34s)
    --- PASS: TestCheckSizeOnly/2 (0.31s)
    --- PASS: TestCheckSizeOnly/3 (0.33s)
    --- PASS: TestCheckSizeOnly/4 (0.38s)
    --- PASS: TestCheckSizeOnly/5 (0.35s)
    --- PASS: TestCheckSizeOnly/6 (0.30s)
    --- PASS: TestCheckSizeOnly/7 (0.37s)
=== RUN   TestCheckEqualReaders
--- PASS: TestCheckEqualReaders (0.00s)
=== RUN   TestParseSumFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 03:47:51 DEBUG : test.sum: md5 = 773f9cb3bbb9fc5c79b39f4e629e1a68 OK
2026/10/19 03:47:52 NOTICE: test.sum: improperly formatted checksum line 4
2026/10/19 03:47:52 NOTICE: test.sum: improperly formatted checksum line 5
2026/10/19 03:47:52 NOTICE: test.sum: improperly formatted checksum line 6
2026/10/19 03:47:52 NOTICE: test.sum: 2 warning(s) suppressed...
2026/10/19 03:47:54 DEBUG : test.sum: md5 = 7f831a44529b82647927e86eec2f869f OK
2026/10/19 03:47:55 NOTICE: test.sum: improperly formatted checksum line 4
2026/10/19 03:47:55 NOTICE: test.sum: improperly formatted checksum line 5
2026/10/19 03:47:55 NOTICE: test.sum: improperly formatted checksum line 6
2026/10/19 03:47:55 NOTICE: test.sum: 2 warning(s) suppressed...
--- PASS: TestParseSumFile (6.94s)
=== RUN   TestCheckSum
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 03:47:56 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-weyapag1ziqu/data"
2026/10/19 03:47:56 DEBUG : Creating backend with remote "TestDrive:crypt/mgqrlgabkh4qu4jmvhvvahqod9cv2gjeqsmi6sk74c30p3v3f7jg/vjrnln8ratgmqakfosrqe8espk"
    check_test.go:354: Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu/data' lacks md5, skipping
--- SKIP: TestCheckSum (2.45s)
=== RUN   TestCheckSumDownload
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 03:47:59 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-weyapag1ziqu/data"
2026/10/19 03:47:59 DEBUG : Creating backend with remote "TestDrive:crypt/mgqrlgabkh4qu4jmvhvvahqod9cv2gjeqsmi6sk74c30p3v3f7jg/vjrnln8ratgmqakfosrqe8espk"
2026/10/19 03:48:04 DEBUG : data/banana: md5 = 7a209c77a5a56171e60f069b05aad484 OK
2026/10/19 03:48:06 DEBUG : test.sum: md5 = 7b81aa86c26b1f2fc020c577d214c969 OK
=== RUN   TestCheckSumDownload/subtest1
2026/10/19 03:48:10 DEBUG : data/potato: md5 = 0f837f08638403030699917651bfc126 OK
2026/10/19 03:48:12 DEBUG : test.sum: md5 = fc36f97d90724c0b2b7f40c94c526717 OK
=== RUN   TestCheckSumDownload/subtest2
2026/10/19 03:48:17 DEBUG : test.sum: md5 = 19d963523a73b3d3e96fbda4b36374c4 OK
=== RUN   TestCheckSumDownload/subtest3
2026/10/19 03:48:22 DEBUG : test.sum: md5 = fdd97de1707322d4b4dfd010ef02fa7d OK
=== RUN   TestCheckSumDownload/subtest4
2026/10/19 03:48:27 DEBUG : test.sum: md5 = c5eb3ee2d930f6d15bfa9d80b4322460 OK
=== RUN   TestCheckSumDownload/subtest5
2026/10/19 03:48:31 DEBUG : test.sum: md5 = a14f6aea77c16b422aa360c38fa1e5fa OK
=== RUN   TestCheckSumDownload/subtest6
2026/10/19 03:48:36 DEBUG : data/banana: md5 = 9e6c6540e0c47bc08d4f866ef57c7d82 OK
2026/10/19 03:48:38 DEBUG : data/potato: md5 = a6bc07dd872f5181814061a137e7055c OK
2026/10/19 03:48:39 DEBUG : test.sum: md5 = 5c1607e8b13842ae2dc52d84de9396a0 OK
=== RUN   TestCheckSumDownload/subtest7
--- PASS: TestCheckSumDownload (47.52s)
    --- PASS: TestCheckSumDownload/subtest1 (2.37s)
    --- PASS: TestCheckSumDownload/subtest2 (1.93s)
    --- PASS: TestCheckSumDownload/subtest3 (2.14s)
    --- PASS: TestCheckSumDownload/subtest4 (1.96s)
    --- PASS: TestCheckSumDownload/subtest5 (1.98s)
    --- PASS: TestCheckSumDownload/subtest6 (1.81s)
    --- PASS: TestCheckSumDownload/subtest7 (2.08s)
=== RUN   TestCheckSumConcurrency
2026/10/19 03:48:46 DEBUG : file0: md5 = 022eb0c0085c731f335df970fafa7446 OK
2026/10/19 03:48:46 DEBUG : file2: md5 = a58f09ae3db8ae4b943fdbaace096072 OK
2026/10/19 03:48:46 DEBUG : file1: md5 = 17fbabbccbdea0e6be9eb64c8c6fea41 OK
2026/10/19 03:48:46 DEBUG : file3: md5 = e0639b17e0744aca9a3ba29c049e59ee OK
2026/10/19 03:48:46 NOTICE: Mock file system at checkSum: 0 differences found
2026/10/19 03:48:46 NOTICE: Mock file system at checkSum: 4 matching files
--- PASS: TestCheckSumConcurrency (0.00s)
=== RUN   TestCheckSumDownloadConcurrency
2026/10/19 03:48:46 DEBUG : file0: md5 = 022eb0c0085c731f335df970fafa7446 OK
2026/10/19 03:48:46 DEBUG : file2: md5 = a58f09ae3db8ae4b943fdbaace096072 OK
2026/10/19 03:48:46 DEBUG : file1: md5 = 17fbabbccbdea0e6be9eb64c8c6fea41 OK
2026/10/19 03:48:46 DEBUG : file3: md5 = e0639b17e0744aca9a3ba29c049e59ee OK
2026/10/19 03:48:46 NOTICE: Mock file system at checkSum: 0 differences found
2026/10/19 03:48:46 NOTICE: Mock file system at checkSum: 4 matching files
--- PASS: TestCheckSumDownloadConcurrency (0.00s)
=== RUN   TestApplyTransforms
2026/10/19 03:48:46 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-kaheten9ziqi"
2026/10/19 03:48:46 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/19 03:48:46 DEBUG : Creating backend with remote "TestDrive:crypt/lqo8f5sof6mlq53ntb90o76up1qf7mo7v4316pdv6retvkf38m9g"
2026/10/19 03:48:47 DEBUG : Creating backend with remote "/tmp/rclone1835942805"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kaheten9ziqi'", Local "Local file system at /tmp/rclone1835942805", Modify Window "1ms"
2026/10/19 03:48:50 DEBUG : hello, world!: md5 = 9353a18124d63c9e33f9a94c0726dfa7 OK
upper checkfile vs. lower remote (without normalization)
2026/10/19 03:48:50 ERROR : hello, world!: sum not found
2026/10/19 03:48:50 ERROR : HELLO, WORLD!: file not in Encrypted drive 'TestCryptDrive:rclone-test-kaheten9ziqi'
2026/10/19 03:48:50 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kaheten9ziqi': 1 files missing
2026/10/19 03:48:50 NOTICE: 1 hashes missing
2026/10/19 03:48:50 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kaheten9ziqi': 1 differences found
2026/10/19 03:48:50 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kaheten9ziqi': 2 errors while checking
upper checkfile vs. lower remote (with normalization)
2026/10/19 03:48:51 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/19 03:48:51 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kaheten9ziqi': 0 differences found
2026/10/19 03:48:51 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kaheten9ziqi': 1 matching files
2026/10/19 03:48:51 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-buxamov2duxi"
2026/10/19 03:48:51 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/19 03:48:51 DEBUG : Creating backend with remote "TestDrive:crypt/kjbdqtdn7s2i2c9ovu1o6cufuejeac794b7a543fcg9s809ckt60"
2026/10/19 03:48:53 DEBUG : Creating backend with remote "/tmp/rclone891696015"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-buxamov2duxi'", Local "Local file system at /tmp/rclone891696015", Modify Window "1ms"
2026/10/19 03:48:55 DEBUG : HELLO, WORLD!: md5 = 6179798f42876da489852a9033d1a131 OK
lower checkfile vs. upper remote (without normalization)
2026/10/19 03:48:56 ERROR : HELLO, WORLD!: sum not found
2026/10/19 03:48:56 ERROR : hello, world!: file not in Encrypted drive 'TestCryptDrive:rclone-test-buxamov2duxi'
2026/10/19 03:48:56 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-buxamov2duxi': 1 files missing
2026/10/19 03:48:56 NOTICE: 1 hashes missing
2026/10/19 03:48:56 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-buxamov2duxi': 1 differences found
2026/10/19 03:48:56 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-buxamov2duxi': 2 errors while checking
lower checkfile vs. upper remote (with normalization)
2026/10/19 03:48:57 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/19 03:48:57 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-buxamov2duxi': 0 differences found
2026/10/19 03:48:57 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-buxamov2duxi': 1 matching files
2026/10/19 03:48:57 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-wulexap1kiru"
2026/10/19 03:48:57 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/19 03:48:57 DEBUG : Creating backend with remote "TestDrive:crypt/21422avlmeqpvg3tpv4ert5gtn3j6c9f32os82dift4msgtntogg"
2026/10/19 03:48:58 DEBUG : Creating backend with remote "/tmp/rclone3934751312"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wulexap1kiru'", Local "Local file system at /tmp/rclone3934751312", Modify Window "1ms"
2026/10/19 03:49:01 DEBUG : HeLlO, wOrLd!: md5 = 64a4bd87ad74028015f1f95b2a54991d OK
lower checkfile vs. upperlowermixed remote (without normalization)
2026/10/19 03:49:02 ERROR : HeLlO, wOrLd!: sum not found
2026/10/19 03:49:02 ERROR : hello, world!: file not in Encrypted drive 'TestCryptDrive:rclone-test-wulexap1kiru'
2026/10/19 03:49:02 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-wulexap1kiru': 1 files missing
2026/10/19 03:49:02 NOTICE: 1 hashes missing
2026/10/19 03:49:02 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-wulexap1kiru': 1 differences found
2026/10/19 03:49:02 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-wulexap1kiru': 2 errors while checking
lower checkfile vs. upperlowermixed remote (with normalization)
2026/10/19 03:49:03 DEBUG : HeLlO, wOrLd!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/19 03:49:03 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-wulexap1kiru': 0 differences found
2026/10/19 03:49:03 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-wulexap1kiru': 1 matching files
2026/10/19 03:49:03 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-vamepaz9jixo"
2026/10/19 03:49:03 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/19 03:49:03 DEBUG : Creating backend with remote "TestDrive:crypt/s2lkqjqrkvsj47ued3t39blktdis1vmuf4a225aa6qg99ac3cn0g"
2026/10/19 03:49:04 DEBUG : Creating backend with remote "/tmp/rclone730916070"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-vamepaz9jixo'", Local "Local file system at /tmp/rclone730916070", Modify Window "1ms"
2026/10/19 03:49:07 DEBUG : HELLO, WORLD!: md5 = ba9ffaa32d01da51644d4a2dd5598d72 OK
upperlowermixed checkfile vs. upper remote (without normalization)
2026/10/19 03:49:08 ERROR : HELLO, WORLD!: sum not found
2026/10/19 03:49:08 ERROR : HeLlO, wOrLd!: file not in Encrypted drive 'TestCryptDrive:rclone-test-vamepaz9jixo'
2026/10/19 03:49:08 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-vamepaz9jixo': 1 files missing
2026/10/19 03:49:08 NOTICE: 1 hashes missing
2026/10/19 03:49:08 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-vamepaz9jixo': 1 differences found
2026/10/19 03:49:08 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-vamepaz9jixo': 2 errors while checking
upperlowermixed checkfile vs. upper remote (with normalization)
2026/10/19 03:49:09 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/19 03:49:09 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-vamepaz9jixo': 0 differences found
2026/10/19 03:49:09 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-vamepaz9jixo': 1 matching files
2026/10/19 03:49:09 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-lonozag4zage"
2026/10/19 03:49:09 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/19 03:49:09 DEBUG : Creating backend with remote "TestDrive:crypt/9objoh2kaj28sdjqvp5ao44rctcjao4bg8kp2e6ma7hhjhk1orpg"
2026/10/19 03:49:10 DEBUG : Creating backend with remote "/tmp/rclone3002069164"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-lonozag4zage'", Local "Local file system at /tmp/rclone3002069164", Modify Window "1ms"
2026/10/19 03:49:13 DEBUG : 測試_Русский___ě_áñ: md5 = c298fa363a2b802c063ae7d0c4bf907c OK
NFD checkfile vs. NFC remote (without normalization)
2026/10/19 03:49:14 ERROR : 測試_Русский___ě_áñ: sum not found
2026/10/19 03:49:14 ERROR : 測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-lonozag4zage'
2026/10/19 03:49:14 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-lonozag4zage': 1 files missing
2026/10/19 03:49:14 NOTICE: 1 hashes missing
2026/10/19 03:49:14 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-lonozag4zage': 1 differences found
2026/10/19 03:49:14 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-lonozag4zage': 2 errors while checking
NFD checkfile vs. NFC remote (with normalization)
2026/10/19 03:49:15 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/19 03:49:15 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-lonozag4zage': 0 differences found
2026/10/19 03:49:15 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-lonozag4zage': 1 matching files
2026/10/19 03:49:15 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-ranivex6kate"
2026/10/19 03:49:15 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/19 03:49:15 DEBUG : Creating backend with remote "TestDrive:crypt/dk14o5nb22l2nll020rjttsm5f4uvc3iegtvkachaucfg4d3ca50"
2026/10/19 03:49:16 DEBUG : Creating backend with remote "/tmp/rclone3472467676"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-ranivex6kate'", Local "Local file system at /tmp/rclone3472467676", Modify Window "1ms"
2026/10/19 03:49:19 DEBUG : 測試_Русский___ě_áñ: md5 = 57d7c3e3cba8a97f47127e130bbee502 OK
NFC checkfile vs. NFD remote (without normalization)
2026/10/19 03:49:19 ERROR : 測試_Русский___ě_áñ: sum not found
2026/10/19 03:49:19 ERROR : 測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-ranivex6kate'
2026/10/19 03:49:19 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-ranivex6kate': 1 files missing
2026/10/19 03:49:19 NOTICE: 1 hashes missing
2026/10/19 03:49:19 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-ranivex6kate': 1 differences found
2026/10/19 03:49:19 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-ranivex6kate': 2 errors while checking
NFC checkfile vs. NFD remote (with normalization)
2026/10/19 03:49:20 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/19 03:49:20 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-ranivex6kate': 0 differences found
2026/10/19 03:49:20 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-ranivex6kate': 1 matching files
2026/10/19 03:49:20 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-bosojud6yapu"
2026/10/19 03:49:20 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/19 03:49:21 DEBUG : Creating backend with remote "TestDrive:crypt/pcd82vl22bi12ji72t76hvm1ph6seaqolm78ktchq48conl2b0pg"
2026/10/19 03:49:22 DEBUG : Creating backend with remote "/tmp/rclone537811570"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-bosojud6yapu'", Local "Local file system at /tmp/rclone537811570", Modify Window "1ms"
2026/10/19 03:49:25 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 897a07bd954e512f2076239dd5e4a200 OK
NFDx2 checkfile vs. both remote (without normalization)
2026/10/19 03:49:26 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found
2026/10/19 03:49:26 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-bosojud6yapu'
2026/10/19 03:49:26 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-bosojud6yapu': 1 files missing
2026/10/19 03:49:26 NOTICE: 1 hashes missing
2026/10/19 03:49:26 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-bosojud6yapu': 1 differences found
2026/10/19 03:49:26 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-bosojud6yapu': 2 errors while checking
NFDx2 checkfile vs. both remote (with normalization)
2026/10/19 03:49:27 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/19 03:49:27 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-bosojud6yapu': 0 differences found
2026/10/19 03:49:27 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-bosojud6yapu': 1 matching files
2026/10/19 03:49:27 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-bugepuz8qasu"
2026/10/19 03:49:27 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/19 03:49:27 DEBUG : Creating backend with remote "TestDrive:crypt/ou28fgsppspi545tg5vit6bmpe6u8t2f2evgfbppgbs8nrdm8ot0"
2026/10/19 03:49:28 DEBUG : Creating backend with remote "/tmp/rclone93215358"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-bugepuz8qasu'", Local "Local file system at /tmp/rclone93215358", Modify Window "1ms"
2026/10/19 03:49:31 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 325dfb08dd602f6c05d3b7725f1127c4 OK
NFCx2 checkfile vs. both remote (without normalization)
2026/10/19 03:49:32 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found
2026/10/19 03:49:32 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-bugepuz8qasu'
2026/10/19 03:49:32 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-bugepuz8qasu': 1 files missing
2026/10/19 03:49:32 NOTICE: 1 hashes missing
2026/10/19 03:49:32 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-bugepuz8qasu': 1 differences found
2026/10/19 03:49:32 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-bugepuz8qasu': 2 errors while checking
NFCx2 checkfile vs. both remote (with normalization)
2026/10/19 03:49:33 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/19 03:49:33 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-bugepuz8qasu': 0 differences found
2026/10/19 03:49:33 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-bugepuz8qasu': 1 matching files
2026/10/19 03:49:33 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-sakegeg7xexu"
2026/10/19 03:49:33 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/19 03:49:33 DEBUG : Creating backend with remote "TestDrive:crypt/rm9ak5k4n78rq16uhi1k9qfdn4smhd80d60bddfn5ns49fbs7h10"
2026/10/19 03:49:34 DEBUG : Creating backend with remote "/tmp/rclone3789455238"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-sakegeg7xexu'", Local "Local file system at /tmp/rclone3789455238", Modify Window "1ms"
2026/10/19 03:49:37 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 689bc9026044740aafb675a8d9dc6290 OK
both checkfile vs. NFDx2 remote (without normalization)
2026/10/19 03:49:38 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found
2026/10/19 03:49:38 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-sakegeg7xexu'
2026/10/19 03:49:38 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-sakegeg7xexu': 1 files missing
2026/10/19 03:49:38 NOTICE: 1 hashes missing
2026/10/19 03:49:38 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-sakegeg7xexu': 1 differences found
2026/10/19 03:49:38 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-sakegeg7xexu': 2 errors while checking
both checkfile vs. NFDx2 remote (with normalization)
2026/10/19 03:49:39 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/19 03:49:39 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-sakegeg7xexu': 0 differences found
2026/10/19 03:49:39 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-sakegeg7xexu': 1 matching files
2026/10/19 03:49:39 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-qigicey5wimi"
2026/10/19 03:49:39 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/19 03:49:39 DEBUG : Creating backend with remote "TestDrive:crypt/2cqre3ueesqine3qmn6vkppj3cna0ffrums931hh65le7k3h84r0"
2026/10/19 03:49:40 DEBUG : Creating backend with remote "/tmp/rclone978356116"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-qigicey5wimi'", Local "Local file system at /tmp/rclone978356116", Modify Window "1ms"
2026/10/19 03:49:43 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 11117e41e4f29a7403ec8932edb8286d OK
both checkfile vs. NFCx2 remote (without normalization)
2026/10/19 03:49:43 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found
2026/10/19 03:49:43 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-qigicey5wimi'
2026/10/19 03:49:43 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-qigicey5wimi': 1 files missing
2026/10/19 03:49:43 NOTICE: 1 hashes missing
2026/10/19 03:49:43 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-qigicey5wimi': 1 differences found
2026/10/19 03:49:43 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-qigicey5wimi': 2 errors while checking
both checkfile vs. NFCx2 remote (with normalization)
2026/10/19 03:49:45 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/10/19 03:49:45 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-qigicey5wimi': 0 differences found
2026/10/19 03:49:45 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-qigicey5wimi': 1 matching files
2026/10/19 03:49:45 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-qigicey5wimi': Purge remote
2026/10/19 03:49:45 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-sakegeg7xexu': Purge remote
2026/10/19 03:49:46 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-bugepuz8qasu': Purge remote
2026/10/19 03:49:47 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-bosojud6yapu': Purge remote
2026/10/19 03:49:48 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-ranivex6kate': Purge remote
2026/10/19 03:49:48 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-lonozag4zage': Purge remote
2026/10/19 03:49:49 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-vamepaz9jixo': Purge remote
2026/10/19 03:49:50 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-wulexap1kiru': Purge remote
2026/10/19 03:49:50 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-buxamov2duxi': Purge remote
2026/10/19 03:49:51 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-kaheten9ziqi': Purge remote
--- PASS: TestApplyTransforms (65.65s)
=== RUN   TestTruncateString
--- PASS: TestTruncateString (0.00s)
=== RUN   TestCopyFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 03:49:52 DEBUG : file1: Need to transfer - File not found at Destination
2026/10/19 03:49:55 DEBUG : sub/file2: md5 = 8c772b567ee0bb60fe826eb30bcef2db OK
2026/10/19 03:49:55 DEBUG : sub/file2: size = 14 OK
2026/10/19 03:49:55 INFO  : file1: Copied (new) to: sub/file2
2026/10/19 03:49:56 DEBUG : sub/file2: size = 14 OK
2026/10/19 03:49:56 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2026/10/19 03:49:56 DEBUG : file1: Unchanged skipping
2026/10/19 03:49:57 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu': don't need to copy/move sub/file2, it is already at target location
--- PASS: TestCopyFile (8.41s)
=== RUN   TestCopyFileImmutable
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 03:50:00 DEBUG : existing: Need to transfer - File not found at Destination
2026/10/19 03:50:02 DEBUG : existing: md5 = 90c2857441bcd393d522ea5a4e8662ef OK
2026/10/19 03:50:02 DEBUG : existing: size = 6 OK
2026/10/19 03:50:02 INFO  : existing: Copied (new)
2026/10/19 03:50:03 DEBUG : existing: size = 6 OK
2026/10/19 03:50:03 DEBUG : existing: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2026/10/19 03:50:03 DEBUG : existing: Unchanged skipping
2026/10/19 03:50:03 DEBUG : existing: size = 8 (Local file system at /tmp/rclone144652753)
2026/10/19 03:50:03 DEBUG : existing: size = 6 (Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu')
2026/10/19 03:50:03 DEBUG : existing: Sizes differ
2026/10/19 03:50:03 ERROR : existing: Source and destination exist but do not match: immutable file modified
--- PASS: TestCopyFileImmutable (5.38s)
=== RUN   TestCopyLongFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
    copy_test.go:188: Test only runs on local
--- SKIP: TestCopyLongFile (0.63s)
=== RUN   TestCopyFileBackupDir
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 03:50:09 DEBUG : dst/file1: md5 = 4b823da036f02440250c78dbcab47793 OK
2026/10/19 03:50:10 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-weyapag1ziqu/backup"
2026/10/19 03:50:10 DEBUG : Creating backend with remote "TestDrive:crypt/mgqrlgabkh4qu4jmvhvvahqod9cv2gjeqsmi6sk74c30p3v3f7jg/1nrff024r7pq65ecp72fc28jb0"
2026/10/19 03:50:12 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone144652753)
2026/10/19 03:50:12 DEBUG : dst/file1: size = 18 (Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu')
2026/10/19 03:50:12 DEBUG : dst/file1: Sizes differ
2026/10/19 03:50:16 INFO  : dst/file1: Moved (server-side)
2026/10/19 03:50:17 DEBUG : dst/file1: md5 = 567122f87d7a353189390db79002c150 OK
2026/10/19 03:50:17 DEBUG : dst/file1: size = 14 OK
2026/10/19 03:50:17 INFO  : dst/file1: Copied (new)
--- PASS: TestCopyFileBackupDir (17.30s)
=== RUN   TestCopyFileCompareDest
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 03:50:23 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-weyapag1ziqu/dst"
2026/10/19 03:50:23 DEBUG : Creating backend with remote "TestDrive:crypt/mgqrlgabkh4qu4jmvhvvahqod9cv2gjeqsmi6sk74c30p3v3f7jg/31u3jie661vd5p8j7rtc3hgbh0"
2026/10/19 03:50:26 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-weyapag1ziqu/CompareDest"
2026/10/19 03:50:26 DEBUG : Creating backend with remote "TestDrive:crypt/mgqrlgabkh4qu4jmvhvvahqod9cv2gjeqsmi6sk74c30p3v3f7jg/gveqi14airsml4bgu7krj116o8"
2026/10/19 03:50:28 DEBUG : one: Need to transfer - File not found at Destination
2026/10/19 03:50:31 DEBUG : one: md5 = 9447a2c4ed02aa9fea6d50fcc9db2ce3 OK
2026/10/19 03:50:31 DEBUG : one: size = 3 OK
2026/10/19 03:50:31 INFO  : one: Copied (new)
2026/10/19 03:50:33 DEBUG : one: size = 5 (Local file system at /tmp/rclone144652753)
2026/10/19 03:50:33 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu/dst')
2026/10/19 03:50:33 DEBUG : one: Sizes differ
2026/10/19 03:50:35 DEBUG : one: md5 = ed8eefed66288d01d0b3136733186412 OK
2026/10/19 03:50:35 DEBUG : one: size = 5 OK
2026/10/19 03:50:35 INFO  : one: Copied (replaced existing)
2026/10/19 03:50:37 DEBUG : dst/one: md5 = f6944f873c0bf6e312440bfaba2736cb OK
2026/10/19 03:50:40 DEBUG : CompareDest/one: md5 = 74cd9799b7e07cf93e4143e26a3f59f0 OK
2026/10/19 03:50:41 DEBUG : one: size = 5 (Local file system at /tmp/rclone144652753)
2026/10/19 03:50:41 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu/dst')
2026/10/19 03:50:41 DEBUG : one: Sizes differ
2026/10/19 03:50:42 DEBUG : one: size = 5 OK
2026/10/19 03:50:42 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/10/19 03:50:42 DEBUG : one: Destination found in --compare-dest, skipping
2026/10/19 03:50:44 DEBUG : CompareDest/two: md5 = 37826fe15194157f357425db8e917a1c OK
2026/10/19 03:50:45 DEBUG : two: Need to transfer - File not found at Destination
2026/10/19 03:50:46 DEBUG : two: size = 3 OK
2026/10/19 03:50:46 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/10/19 03:50:46 DEBUG : two: Destination found in --compare-dest, skipping
2026/10/19 03:50:47 DEBUG : two: Need to transfer - File not found at Destination
2026/10/19 03:50:47 DEBUG : two: size = 3 OK
2026/10/19 03:50:47 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/10/19 03:50:47 DEBUG : two: Destination found in --compare-dest, skipping
2026/10/19 03:50:49 DEBUG : two: Need to transfer - File not found at Destination
2026/10/19 03:50:49 DEBUG : two: size = 5 (Local file system at /tmp/rclone144652753)
2026/10/19 03:50:49 DEBUG : two: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu/CompareDest')
2026/10/19 03:50:49 DEBUG : two: Sizes differ
2026/10/19 03:50:50 DEBUG : two: md5 = 3e3fca33879d08ff81def15e2ae665ce OK
2026/10/19 03:50:50 DEBUG : two: size = 5 OK
2026/10/19 03:50:50 INFO  : two: Copied (new)
--- PASS: TestCopyFileCompareDest (32.98s)
=== RUN   TestCopyFileCopyDestImmutable
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 03:50:56 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-weyapag1ziqu/dst"
2026/10/19 03:50:56 DEBUG : Creating backend with remote "TestDrive:crypt/mgqrlgabkh4qu4jmvhvvahqod9cv2gjeqsmi6sk74c30p3v3f7jg/31u3jie661vd5p8j7rtc3hgbh0"
2026/10/19 03:51:01 DEBUG : dst/one: md5 = f0bcc1d6153c5a430cdcaad21b79a12d OK
2026/10/19 03:51:04 DEBUG : CopyDest/one: md5 = f7f46737f3c4eb9349d9c7ee390079d6 OK
2026/10/19 03:51:05 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-weyapag1ziqu/CopyDest"
2026/10/19 03:51:05 DEBUG : Creating backend with remote "TestDrive:crypt/mgqrlgabkh4qu4jmvhvvahqod9cv2gjeqsmi6sk74c30p3v3f7jg/d09o6po3f7bm6ce32vdgs8h9ls"
2026/10/19 03:51:06 DEBUG : one: size = 5 (Local file system at /tmp/rclone144652753)
2026/10/19 03:51:06 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu/dst')
2026/10/19 03:51:06 DEBUG : one: Sizes differ
2026/10/19 03:51:07 DEBUG : one: size = 5 OK
2026/10/19 03:51:07 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/10/19 03:51:07 DEBUG : one: size = 5 (Local file system at /tmp/rclone144652753)
2026/10/19 03:51:07 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu/dst')
2026/10/19 03:51:07 DEBUG : one: Sizes differ
2026/10/19 03:51:07 ERROR : one: Source and destination exist but do not match: immutable file modified
--- PASS: TestCopyFileCopyDestImmutable (14.67s)
=== RUN   TestCopyFileCopyDest
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 03:51:11 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-weyapag1ziqu/dst"
2026/10/19 03:51:11 DEBUG : Creating backend with remote "TestDrive:crypt/mgqrlgabkh4qu4jmvhvvahqod9cv2gjeqsmi6sk74c30p3v3f7jg/31u3jie661vd5p8j7rtc3hgbh0"
2026/10/19 03:51:13 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-weyapag1ziqu/CopyDest"
2026/10/19 03:51:13 DEBUG : Creating backend with remote "TestDrive:crypt/mgqrlgabkh4qu4jmvhvvahqod9cv2gjeqsmi6sk74c30p3v3f7jg/d09o6po3f7bm6ce32vdgs8h9ls"
2026/10/19 03:51:15 DEBUG : one: Need to transfer - File not found at Destination
2026/10/19 03:51:18 DEBUG : one: md5 = 076a688d4d0483860a78335797f69fa8 OK
2026/10/19 03:51:18 DEBUG : one: size = 3 OK
2026/10/19 03:51:18 INFO  : one: Copied (new)
2026/10/19 03:51:20 DEBUG : one: size = 5 (Local file system at /tmp/rclone144652753)
2026/10/19 03:51:20 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu/dst')
2026/10/19 03:51:20 DEBUG : one: Sizes differ
2026/10/19 03:51:22 DEBUG : one: md5 = 467758cb2780689c6d678bb1c67195a9 OK
2026/10/19 03:51:22 DEBUG : one: size = 5 OK
2026/10/19 03:51:22 INFO  : one: Copied (replaced existing)
2026/10/19 03:51:25 DEBUG : dst/one: md5 = 49f7aa49ba6e262b3008c5e9bb6d9e60 OK
2026/10/19 03:51:28 DEBUG : CopyDest/one: md5 = 1f32dca9b62108abc17555b083cb6045 OK
2026/10/19 03:51:29 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-weyapag1ziqu/BackupDir"
2026/10/19 03:51:29 DEBUG : Creating backend with remote "TestDrive:crypt/mgqrlgabkh4qu4jmvhvvahqod9cv2gjeqsmi6sk74c30p3v3f7jg/s6dbk3lfi7c9kfvo6j7bla9m0g"
2026/10/19 03:51:30 DEBUG : one: size = 5 (Local file system at /tmp/rclone144652753)
2026/10/19 03:51:30 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu/dst')
2026/10/19 03:51:30 DEBUG : one: Sizes differ
2026/10/19 03:51:31 DEBUG : one: size = 5 OK
2026/10/19 03:51:31 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/10/19 03:51:31 DEBUG : one: size = 5 (Local file system at /tmp/rclone144652753)
2026/10/19 03:51:31 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu/dst')
2026/10/19 03:51:31 DEBUG : one: Sizes differ
2026/10/19 03:51:33 INFO  : one: Moved (server-side)
2026/10/19 03:51:34 DEBUG : one: size = 5 OK
2026/10/19 03:51:34 INFO  : one: Copied (server-side copy)
2026/10/19 03:51:34 DEBUG : one: Destination found in --copy-dest, using server-side copy
2026/10/19 03:51:37 DEBUG : CopyDest/two: md5 = 6699dc271f3cb882443ce3a31b03a803 OK
2026/10/19 03:51:38 DEBUG : two: Need to transfer - File not found at Destination
2026/10/19 03:51:38 DEBUG : two: size = 3 OK
2026/10/19 03:51:38 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/10/19 03:51:40 DEBUG : two: size = 3 OK
2026/10/19 03:51:40 INFO  : two: Copied (server-side copy)
2026/10/19 03:51:40 DEBUG : two: Destination found in --copy-dest, using server-side copy
2026/10/19 03:51:41 DEBUG : two: size = 3 OK
2026/10/19 03:51:41 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms)
2026/10/19 03:51:41 DEBUG : two: Unchanged skipping
2026/10/19 03:51:43 DEBUG : CopyDest/three: md5 = 478218e66ecb8b92e9e3ed761f6d4ebe OK
2026/10/19 03:51:44 DEBUG : three: Need to transfer - File not found at Destination
2026/10/19 03:51:44 DEBUG : three: size = 7 (Local file system at /tmp/rclone144652753)
2026/10/19 03:51:44 DEBUG : three: size = 5 (Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu/CopyDest')
2026/10/19 03:51:44 DEBUG : three: Sizes differ
2026/10/19 03:51:44 DEBUG : three: Destination not found in --copy-dest
2026/10/19 03:51:46 DEBUG : three: md5 = ab3a11ac9eba2bd78af105d4deb9b825 OK
2026/10/19 03:51:46 DEBUG : three: size = 7 OK
2026/10/19 03:51:46 INFO  : three: Copied (new)
--- PASS: TestCopyFileCopyDest (44.82s)
=== RUN   TestCopyInplace
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
    copy_test.go:434: Partial uploads not supported
--- SKIP: TestCopyInplace (0.53s)
=== RUN   TestCopyLongFileName
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
    copy_test.go:467: Partial uploads not supported
--- SKIP: TestCopyLongFileName (0.51s)
=== RUN   TestCopyLongFileNameCollision
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
    copy_test.go:500: Partial uploads not supported
--- SKIP: TestCopyLongFileNameCollision (0.54s)
=== RUN   TestCopyFileMaxTransfer
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 03:51:58 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination
2026/10/19 03:52:01 DEBUG : TestCopyFileMaxTransfer/file1: md5 = 5afee0e0729511d3cd06ab86e69ff204 OK
2026/10/19 03:52:01 DEBUG : TestCopyFileMaxTransfer/file1: size = 14 OK
2026/10/19 03:52:01 INFO  : TestCopyFileMaxTransfer/file1: Copied (new)
2026/10/19 03:52:02 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination
2026/10/19 03:52:02 ERROR : TestCopyFileMaxTransfer/file2: Failed to copy: Post "https://www.googleapis.com/upload/drive/v3/files?alt=json&fields=id%2Cname%2Csize%2Cmd5Checksum%2Csha1Checksum%2Csha256Checksum%2Ctrashed%2CexplicitlyTrashed%2CmodifiedTime%2CcreatedTime%2CmimeType%2Cparents%2CwebViewLink%2CshortcutDetails%2CexportLinks%2CresourceKey&keepRevisionForever=false&prettyPrint=false&supportsAllDrives=true&uploadType=multipart": googleapi: Copy failed: max transfer limit reached as set by --max-transfer
    copy_test.go:563: Expecting error to contain accounting.ErrorMaxTransferLimitReachedFatal
2026/10/19 03:52:03 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination
2026/10/19 03:52:04 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination
2026/10/19 03:52:06 DEBUG : TestCopyFileMaxTransfer/file4: md5 = de49e18140307b65ef379ea42ab1f986 OK
2026/10/19 03:52:06 DEBUG : TestCopyFileMaxTransfer/file4: size = 2062 OK
2026/10/19 03:52:06 INFO  : TestCopyFileMaxTransfer/file4: Copied (new)
--- PASS: TestCopyFileMaxTransfer (11.83s)
=== RUN   TestDeduplicateInteractive
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
    dedupe_test.go:37: Can't run this test without a hash
--- SKIP: TestDeduplicateInteractive (0.62s)
=== RUN   TestDeduplicateSkip
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 03:52:13 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu': Looking for duplicate names using skip mode.
2026/10/19 03:52:14 NOTICE: one: Found 2 files with duplicate names
2026/10/19 03:52:14 NOTICE: one: Skipping 2 files with duplicate names
--- PASS: TestDeduplicateSkip (6.15s)
=== RUN   TestDeduplicateSizeOnly
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 03:52:21 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu': Looking for duplicate names using skip mode.
2026/10/19 03:52:21 NOTICE: one: Found 3 files with duplicate names
2026/10/19 03:52:21 NOTICE: one: Deleting 1/2 identical duplicates (size 11)
2026/10/19 03:52:22 INFO  : one: Deleted
2026/10/19 03:52:22 NOTICE: one: Skipping 2 files with duplicate names
--- PASS: TestDeduplicateSizeOnly (8.15s)
=== RUN   TestDeduplicateFirst
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 03:52:29 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu': Looking for duplicate names using first mode.
2026/10/19 03:52:30 NOTICE: one: Found 3 files with duplicate names
2026/10/19 03:52:31 INFO  : one: Deleted
2026/10/19 03:52:31 INFO  : one: Deleted
2026/10/19 03:52:31 NOTICE: one: Deleted 2 extra copies
--- PASS: TestDeduplicateFirst (8.74s)
=== RUN   TestDeduplicateNewest
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 03:52:38 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu': Looking for duplicate names using newest mode.
2026/10/19 03:52:39 NOTICE: one: Found 3 files with duplicate names
2026/10/19 03:52:39 INFO  : one: Deleted
2026/10/19 03:52:40 INFO  : one: Deleted
2026/10/19 03:52:40 NOTICE: one: Deleted 2 extra copies
--- PASS: TestDeduplicateNewest (8.69s)
=== RUN   TestDeduplicateNewestByHash
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
    dedupe_test.go:37: Can't run this test without a hash
--- SKIP: TestDeduplicateNewestByHash (0.57s)
=== RUN   TestDeduplicateOldest
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 03:52:47 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu': Looking for duplicate names using oldest mode.
2026/10/19 03:52:48 NOTICE: one: Found 3 files with duplicate names
2026/10/19 03:52:49 INFO  : one: Deleted
2026/10/19 03:52:50 INFO  : one: Deleted
2026/10/19 03:52:50 NOTICE: one: Deleted 2 extra copies
--- PASS: TestDeduplicateOldest (9.13s)
=== RUN   TestDeduplicateLargest
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 03:52:56 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu': Looking for duplicate names using largest mode.
2026/10/19 03:52:57 NOTICE: one: Found 3 files with duplicate names
2026/10/19 03:52:57 INFO  : one: Deleted
2026/10/19 03:52:58 INFO  : one: Deleted
2026/10/19 03:52:58 NOTICE: one: Deleted 2 extra copies
--- PASS: TestDeduplicateLargest (8.45s)
=== RUN   TestDeduplicateSmallest
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 03:53:04 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu': Looking for duplicate names using smallest mode.
2026/10/19 03:53:05 NOTICE: one: Found 3 files with duplicate names
2026/10/19 03:53:06 INFO  : one: Deleted
2026/10/19 03:53:06 INFO  : one: Deleted
2026/10/19 03:53:06 NOTICE: one: Deleted 2 extra copies
--- PASS: TestDeduplicateSmallest (8.15s)
=== RUN   TestDeduplicateRename
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 03:53:15 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu': Looking for duplicate names using rename mode.
2026/10/19 03:53:16 NOTICE: one.txt: Found 3 files with duplicate names
2026/10/19 03:53:17 INFO  : one-2.txt: renamed from: one.txt
2026/10/19 03:53:17 INFO  : one-3.txt: renamed from: one.txt
2026/10/19 03:53:18 INFO  : one-4.txt: renamed from: one.txt
--- PASS: TestDeduplicateRename (13.95s)
=== RUN   TestDeduplicateRenameManyExisting
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 03:53:24 DEBUG : one-1.txt: md5 = 77bb604bf5dbb0c793fbba1af1209c5f OK
2026/10/19 03:53:25 DEBUG : one-2.txt: md5 = dcdc3e59b9f66168f059c8ef045e9669 OK
2026/10/19 03:53:27 DEBUG : one-3.txt: md5 = d89ada6f244f4d19062abe44584056ff OK
2026/10/19 03:53:29 DEBUG : one-4.txt: md5 = 79af43eb921d0af49a828325e3cb5e5d OK
2026/10/19 03:53:31 DEBUG : one-5.txt: md5 = 90d6d9a38ed46e59cb2ee8e241f4748a OK
2026/10/19 03:53:32 DEBUG : one-6.txt: md5 = 83f19f2530064dd22444ed3737c2967e OK
2026/10/19 03:53:34 DEBUG : one-7.txt: md5 = 375977b0f6b22a7a2352eb7170dd272c OK
2026/10/19 03:53:36 DEBUG : one-8.txt: md5 = 3e8fe36b12b14036037b6d00c2eef410 OK
2026/10/19 03:53:38 DEBUG : one-9.txt: md5 = dfd64bc70b63dbfe539283d525916f31 OK
2026/10/19 03:53:40 DEBUG : one-10.txt: md5 = 135868a36a345ff9aa41a5b638ba008c OK
2026/10/19 03:53:41 DEBUG : one-11.txt: md5 = 1b20d5c3fc7cf413c730b4cfabf59f00 OK
2026/10/19 03:53:43 DEBUG : one-12.txt: md5 = 097add048d2d4da4724009b60a6521d6 OK
2026/10/19 03:53:45 DEBUG : one-13.txt: md5 = 182bf4193eb072ea267cae5abb0b2846 OK
2026/10/19 03:53:47 DEBUG : one-14.txt: md5 = d7ea6a5fff4afde4c4319cbd4b81775a OK
2026/10/19 03:53:49 DEBUG : one-15.txt: md5 = 12d70c289917fd261d85d64b2d868cd8 OK
2026/10/19 03:53:51 DEBUG : one-16.txt: md5 = 4671f947e524417b1a2e3a01242f0d67 OK
2026/10/19 03:53:53 DEBUG : one-17.txt: md5 = 98c4565b056c28702d65883a2413ba5e OK
2026/10/19 03:53:54 DEBUG : one-18.txt: md5 = 76be1fe082fda50c9dfac94393fbe1e9 OK
2026/10/19 03:53:56 DEBUG : one-19.txt: md5 = ee67af844e361a5af2d5105b29285de3 OK
2026/10/19 03:53:58 DEBUG : one-20.txt: md5 = 0751ac22a86dbb5f11c3b45ff2fc6e34 OK
2026/10/19 03:54:00 DEBUG : one-21.txt: md5 = 65fc09ce66546533336172b624c3a012 OK
2026/10/19 03:54:01 DEBUG : one-22.txt: md5 = a8257116dd0e7cf8a79ebff297bf4cad OK
2026/10/19 03:54:03 DEBUG : one-23.txt: md5 = 2541a0f19dad2b1bd40ac8e9f9779af2 OK
2026/10/19 03:54:05 DEBUG : one-24.txt: md5 = 0c85ae7d011df0969b16e3ce875bee19 OK
2026/10/19 03:54:07 DEBUG : one-25.txt: md5 = 4b97d89144cf1bc1e24f5cf9632ae0c4 OK
2026/10/19 03:54:09 DEBUG : one-26.txt: md5 = 3d829e1e0c13b0f5f601dcd734a8dc7e OK
2026/10/19 03:54:10 DEBUG : one-27.txt: md5 = 6272fad68d51d8177b8bde1edc000e4b OK
2026/10/19 03:54:12 DEBUG : one-28.txt: md5 = 963d065d6314f1ca7726c1296596ca8f OK
2026/10/19 03:54:14 DEBUG : one-29.txt: md5 = 226df0bf1d69c8c9f2a710b734e49802 OK
2026/10/19 03:54:16 DEBUG : one-30.txt: md5 = a77e943e3b95a4e889eb6ac166a4cd61 OK
2026/10/19 03:54:18 DEBUG : one-31.txt: md5 = c1e7a3e71cda0981fa9457daacf06edf OK
2026/10/19 03:54:20 DEBUG : one-32.txt: md5 = 350cfaf95a3af2ed3a0ab8eb29354d51 OK
2026/10/19 03:54:21 DEBUG : one-33.txt: md5 = 0ee99bd0a4cf8158d5f89578ec9c07ca OK
2026/10/19 03:54:23 DEBUG : one-34.txt: md5 = 149f70dd8ecbe089f791ea7d759c2f53 OK
2026/10/19 03:54:25 DEBUG : one-35.txt: md5 = 1f583d6617c76309651d5e6e03faf9b2 OK
2026/10/19 03:54:28 DEBUG : one-36.txt: md5 = 3690af33676e8cfda986a21aaef15887 OK
2026/10/19 03:54:30 DEBUG : one-37.txt: md5 = 124bc8b5942538a27f3f306c94e224fe OK
2026/10/19 03:54:32 DEBUG : one-38.txt: md5 = 330516776bda99ed4e560645169bb90d OK
2026/10/19 03:54:33 DEBUG : one-39.txt: md5 = 7124b5962854ab86a035409e192e3cd1 OK
2026/10/19 03:54:35 DEBUG : one-40.txt: md5 = 9221ddf3d8d7b95d57d970d7b16f1186 OK
2026/10/19 03:54:37 DEBUG : one-41.txt: md5 = 221484fa339420d839b5c0ebf58ee441 OK
2026/10/19 03:54:39 DEBUG : one-42.txt: md5 = 88fda9d928f9abcb552732cd5f0c6495 OK
2026/10/19 03:54:41 DEBUG : one-43.txt: md5 = 739bf499ab4ea2b1e88ca0de1d2ea7a3 OK
2026/10/19 03:54:42 DEBUG : one-44.txt: md5 = f01d67067904dc54a7d212b9226dee1c OK
2026/10/19 03:54:44 DEBUG : one-45.txt: md5 = 09334ea8b2a9382885238513cf25fe2d OK
2026/10/19 03:54:46 DEBUG : one-46.txt: md5 = dd8d0f8d27ab1679bc9b73d11fc9b7aa OK
2026/10/19 03:54:48 DEBUG : one-47.txt: md5 = f50ecd938d4d4bd4d72bb773951b8849 OK
2026/10/19 03:54:49 DEBUG : one-48.txt: md5 = b551d0a372fa263e596e50ede59344c5 OK
2026/10/19 03:54:51 DEBUG : one-49.txt: md5 = 323ecd5cd30bda4e0b4b4ee1a127c5cc OK
2026/10/19 03:54:53 DEBUG : one-50.txt: md5 = 47d8054281c9cfec2145bf2de8f6426b OK
2026/10/19 03:54:55 DEBUG : one-51.txt: md5 = 46ebd07b52b1fcd228dc3c1b7830ccae OK
2026/10/19 03:54:57 DEBUG : one-52.txt: md5 = b6bd6dc858c15f345bc3a2fd6428afc6 OK
2026/10/19 03:54:59 DEBUG : one-53.txt: md5 = 89d411b306054d1afb6b184f2392f879 OK
2026/10/19 03:55:01 DEBUG : one-54.txt: md5 = 388826e2eca50b79313a1853588cbeec OK
2026/10/19 03:55:02 DEBUG : one-55.txt: md5 = ffe3736cb63fec8bbbfc0183975899f5 OK
2026/10/19 03:55:04 DEBUG : one-56.txt: md5 = abe903f4d8797fd82f459b63e98351ea OK
2026/10/19 03:55:06 DEBUG : one-57.txt: md5 = 4c4e54fc6714293fd3abe49b89bbb86d OK
2026/10/19 03:55:07 DEBUG : one-58.txt: md5 = b3ceea87e0dba952bf42b167d2ebadb2 OK
2026/10/19 03:55:09 DEBUG : one-59.txt: md5 = 206d8c6f1272f7237f729782de4de163 OK
2026/10/19 03:55:11 DEBUG : one-60.txt: md5 = fb067772e440061f82a715ec0ab47151 OK
2026/10/19 03:55:13 DEBUG : one-61.txt: md5 = fecf44cdc8731682a72df30ade911bd4 OK
2026/10/19 03:55:15 DEBUG : one-62.txt: md5 = df822d790d03fab3c16781bcf3071f8e OK
2026/10/19 03:55:16 DEBUG : one-63.txt: md5 = c970da086ecf48ae10e43eb9113a709a OK
2026/10/19 03:55:18 DEBUG : one-64.txt: md5 = c618b2dfe04ab2e7d65a9f7999b108c5 OK
2026/10/19 03:55:20 DEBUG : one-65.txt: md5 = 799a9fa42f7704d37d8ef044dfd091b1 OK
2026/10/19 03:55:22 DEBUG : one-66.txt: md5 = 8f687df9d041beb763caacf93f9b8528 OK
2026/10/19 03:55:23 DEBUG : one-67.txt: md5 = 3751a40fe07a9680fa7ceaf59d95e3ff OK
2026/10/19 03:55:25 DEBUG : one-68.txt: md5 = 6170bca649d46b11c5c47599496b886e OK
2026/10/19 03:55:27 DEBUG : one-69.txt: md5 = bbb25bf2a93776f5bb155786a94a6cbc OK
2026/10/19 03:55:34 DEBUG : one-70.txt: md5 = c071705f259bc463f1952248297d83ce OK
2026/10/19 03:55:36 DEBUG : one-71.txt: md5 = 5e74919704fa26f9e2f139611bd38b56 OK
2026/10/19 03:55:37 DEBUG : one-72.txt: md5 = 6229cddac4596f846291671a7770158a OK
2026/10/19 03:55:39 DEBUG : one-73.txt: md5 = 0da54286fe115e19ea861e67f7f2f7ac OK
2026/10/19 03:55:41 DEBUG : one-74.txt: md5 = 96e044b4306fe239fd3acdc874f73bde OK
2026/10/19 03:55:43 DEBUG : one-75.txt: md5 = 7cb11b6109db8c06528619dda2a99b1c OK
2026/10/19 03:55:45 DEBUG : one-76.txt: md5 = a207150d70791644f8354599c1489c7f OK
2026/10/19 03:55:47 DEBUG : one-77.txt: md5 = 9cb44a23a26faeba15bd82a8f3dd7748 OK
2026/10/19 03:55:49 DEBUG : one-78.txt: md5 = a46816fdb4c05d456d788882d871c2c6 OK
2026/10/19 03:55:50 DEBUG : one-79.txt: md5 = 024f5dde3b99e4a51ea391cc346f7360 OK
2026/10/19 03:55:53 DEBUG : one-80.txt: md5 = 54e455652c8d0128ef834b75b31b2c67 OK
2026/10/19 03:55:54 DEBUG : one-81.txt: md5 = 55681fe352c250af810d09b42c5454cc OK
2026/10/19 03:55:56 DEBUG : one-82.txt: md5 = 3cfc7221778a52a5499db18700061c51 OK
2026/10/19 03:55:58 DEBUG : one-83.txt: md5 = 58b182372c4f0202d1f0b9d2700f7707 OK
2026/10/19 03:56:00 DEBUG : one-84.txt: md5 = 7cc2f4444b0d874b0104c772ed43ca39 OK
2026/10/19 03:56:02 DEBUG : one-85.txt: md5 = 92dca7adb992c5ffaf28e82837f95e9c OK
2026/10/19 03:56:04 DEBUG : one-86.txt: md5 = 04b3f8ebcba2f0d48c055fcdfb345465 OK
2026/10/19 03:56:05 DEBUG : one-87.txt: md5 = 4544a5cf1657d66264a2eee709116fdf OK
2026/10/19 03:56:07 DEBUG : one-88.txt: md5 = 7434bb43bdcf72288efd7729722f5392 OK
2026/10/19 03:56:09 DEBUG : one-89.txt: md5 = fb5bc471ce1aa7dd9a1da23751834719 OK
2026/10/19 03:56:11 DEBUG : one-90.txt: md5 = 73746e8f62d2b5de268aeb72cd1b50da OK
2026/10/19 03:56:13 DEBUG : one-91.txt: md5 = bbc213c34467b9c429058a2709e24373 OK
2026/10/19 03:56:15 DEBUG : one-92.txt: md5 = 9a35ca85826e6255b485304490e7280b OK
2026/10/19 03:56:17 DEBUG : one-93.txt: md5 = 6d7ab12cb09d5accf706a4c914d8c672 OK
2026/10/19 03:56:19 DEBUG : one-94.txt: md5 = c385f5cd346bab6476e6f47d9d959233 OK
2026/10/19 03:56:20 DEBUG : one-95.txt: md5 = 134f95bf9fc2d871cc9aed2d0b090553 OK
2026/10/19 03:56:22 DEBUG : one-96.txt: md5 = d31c0e3a1827321d71c007b2c63b9c0b OK
2026/10/19 03:56:24 DEBUG : one-97.txt: md5 = 3ef616327db7bf03e253183103ed0d9a OK
2026/10/19 03:56:26 DEBUG : one-98.txt: md5 = f7440651a242ad5178296eb02cbcc418 OK
2026/10/19 03:56:27 DEBUG : one-99.txt: md5 = fc9e0c15aa2883b430f059c14d5f085a OK
2026/10/19 03:56:29 DEBUG : one-100.txt: md5 = 02a65afeb3ce22705f12c1512d85fc52 OK
2026/10/19 03:56:31 DEBUG : one-101.txt: md5 = 9a0e4cd8bbe9111415eab5cbcf8657e0 OK
2026/10/19 03:56:33 DEBUG : one-102.txt: md5 = a7fadba95de975ddc755e189d588fbd2 OK
2026/10/19 03:56:35 DEBUG : one-103.txt: md5 = 5e5346e6dd3c955d656cce43f89c6a6d OK
2026/10/19 03:56:36 DEBUG : one-104.txt: md5 = 322a51b223580c4ad827e040b23d5ad7 OK
2026/10/19 03:56:38 DEBUG : one-105.txt: md5 = c014636f236ebd35f1a4a82091333f83 OK
2026/10/19 03:56:41 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu': Looking for duplicate names using rename mode.
2026/10/19 03:56:42 NOTICE: one.txt: Found 2 files with duplicate names
2026/10/19 03:56:43 INFO  : one-106.txt: renamed from: one.txt
2026/10/19 03:56:44 INFO  : one-107.txt: renamed from: one.txt
--- PASS: TestDeduplicateRenameManyExisting (273.02s)
=== RUN   TestMergeDirs
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 03:57:58 DEBUG : dupe1/one.txt: md5 = 2dcbf8e02769dc4b8a274bc2a41fec0c OK
2026/10/19 03:58:00 DEBUG : dupe2/two.txt: md5 = 2851f96aa64f4bb635cd78b07259ea02 OK
2026/10/19 03:58:03 DEBUG : dupe3/three.txt: md5 = 54d3153e801abb06588b72c354792ce4 OK
2026/10/19 03:58:04 INFO  : urj8gsducqbtvekeq181ntd2u8: merging "sfrom47189m9qr3mt4qonu281c"
2026/10/19 03:58:05 INFO  : urj8gsducqbtvekeq181ntd2u8: removing empty directory
2026/10/19 03:58:06 INFO  : 1u1r4ei7c628fnjg9blqt3j60o: merging "tr2hj63d80ftlmvm6a952snjcc"
2026/10/19 03:58:06 INFO  : 1u1r4ei7c628fnjg9blqt3j60o: removing empty directory
--- PASS: TestMergeDirs (17.26s)
=== RUN   TestIndex
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 03:58:14 DEBUG : file1.txt: md5 = 708f8de9871c7ac2827919d7d807b52a OK
2026/10/19 03:58:17 DEBUG : sub/file2.txt: md5 = 3f5fc846e7b54e03cf68fbc0db7cf68d OK
2026/10/19 03:58:20 DEBUG : sub/deep/file3.txt: md5 = fd87cb5aa0e50cc63a4c97e39c46fb11 OK
2026/10/19 03:58:22 DEBUG : index.html: md5 = 228614c716cfd0748a4ef517aee73b01 OK
2026/10/19 03:58:22 DEBUG : index.html: size = 12147 OK
2026/10/19 03:58:22 INFO  : index.html: Copied (new)
2026/10/19 03:58:22 DEBUG : index.json: md5 = ae5f2f64e06f2b1af06a143931d97fff OK
2026/10/19 03:58:22 DEBUG : index.json: size = 213 OK
2026/10/19 03:58:22 INFO  : index.json: Copied (new)
2026/10/19 03:58:22 DEBUG : caddy.json: md5 = 8608c0f396eb43d527b84fb3c0767b29 OK
2026/10/19 03:58:22 DEBUG : caddy.json: size = 259 OK
2026/10/19 03:58:22 INFO  : caddy.json: Copied (new)
2026/10/19 03:58:23 DEBUG : sub/deep/index.html: md5 = 653d92bcd59abe2db43a392bf7da3e9b OK
2026/10/19 03:58:23 DEBUG : sub/deep/index.html: size = 12087 OK
2026/10/19 03:58:23 INFO  : sub/deep/index.html: Copied (new)
2026/10/19 03:58:24 DEBUG : sub/deep/index.json: md5 = 8479f5aa6dac5c1f60b407f3a496d78d OK
2026/10/19 03:58:24 DEBUG : sub/deep/index.json: size = 119 OK
2026/10/19 03:58:24 INFO  : sub/deep/index.json: Copied (new)
2026/10/19 03:58:24 DEBUG : sub/index.html: md5 = 692269d5497d0b0ce0f806da9f797a0f OK
2026/10/19 03:58:24 DEBUG : sub/index.html: size = 12306 OK
2026/10/19 03:58:24 INFO  : sub/index.html: Copied (new)
2026/10/19 03:58:24 DEBUG : sub/deep/caddy.json: md5 = a06701f06dfc9f1e6495a9b8d04f5315 OK
2026/10/19 03:58:24 DEBUG : sub/deep/caddy.json: size = 131 OK
2026/10/19 03:58:24 INFO  : sub/deep/caddy.json: Copied (new)
2026/10/19 03:58:24 DEBUG : sub/index.json: md5 = 6620a4d13b02c77ef748055d5e0ac2bf OK
2026/10/19 03:58:24 DEBUG : sub/index.json: size = 215 OK
2026/10/19 03:58:24 INFO  : sub/index.json: Copied (new)
2026/10/19 03:58:26 DEBUG : sub/caddy.json: md5 = a066003bb9f29e7479cbfae8d0db4dcb OK
2026/10/19 03:58:26 DEBUG : sub/caddy.json: size = 261 OK
2026/10/19 03:58:26 INFO  : sub/caddy.json: Copied (new)
2026/10/19 03:58:33 DEBUG : index.html: Unchanged skipping
2026/10/19 03:58:33 DEBUG : sub/index.html: Unchanged skipping
2026/10/19 03:58:33 DEBUG : sub/deep/index.html: Unchanged skipping
2026/10/19 03:58:33 DEBUG : index.json: Unchanged skipping
2026/10/19 03:58:34 DEBUG : sub/index.json: Unchanged skipping
2026/10/19 03:58:34 DEBUG : sub/deep/index.json: Unchanged skipping
2026/10/19 03:58:34 DEBUG : caddy.json: Unchanged skipping
2026/10/19 03:58:34 DEBUG : sub/deep/caddy.json: Unchanged skipping
2026/10/19 03:58:34 DEBUG : sub/caddy.json: Unchanged skipping
2026/10/19 03:58:37 DEBUG : index.json: md5 = 53c12324869678b89a3546f52b6ae0cf OK
2026/10/19 03:58:37 DEBUG : index.json: size = 213 OK
2026/10/19 03:58:37 INFO  : index.json: Copied (replaced existing)
2026/10/19 03:58:37 DEBUG : sub/index.html: md5 = 133fa7981342224f73e37c6dfa2bae02 OK
2026/10/19 03:58:37 DEBUG : sub/index.html: size = 12306 OK
2026/10/19 03:58:37 INFO  : sub/index.html: Copied (replaced existing)
2026/10/19 03:58:37 DEBUG : index.html: md5 = b41a09411f5f3d7acfdad5f0985fb76b OK
2026/10/19 03:58:37 DEBUG : index.html: size = 12147 OK
2026/10/19 03:58:37 INFO  : index.html: Copied (replaced existing)
2026/10/19 03:58:37 DEBUG : caddy.json: md5 = 707b54c36711ebc2bee4acb6659b61f8 OK
2026/10/19 03:58:37 DEBUG : caddy.json: size = 259 OK
2026/10/19 03:58:37 INFO  : caddy.json: Copied (replaced existing)
2026/10/19 03:58:38 DEBUG : sub/index.json: md5 = bccddbdbbe0929d49a52b942ddf40678 OK
2026/10/19 03:58:38 DEBUG : sub/index.json: size = 215 OK
2026/10/19 03:58:38 INFO  : sub/index.json: Copied (replaced existing)
2026/10/19 03:58:38 DEBUG : sub/deep/index.json: md5 = b1ed87c58f6e515c08bad099cfcbd27d OK
2026/10/19 03:58:38 DEBUG : sub/deep/index.json: size = 119 OK
2026/10/19 03:58:38 INFO  : sub/deep/index.json: Copied (replaced existing)
2026/10/19 03:58:38 DEBUG : sub/deep/index.html: md5 = 5403b108cf8f1b58e53dfcafd5d77828 OK
2026/10/19 03:58:38 DEBUG : sub/deep/index.html: size = 12087 OK
2026/10/19 03:58:38 INFO  : sub/deep/index.html: Copied (replaced existing)
2026/10/19 03:58:39 DEBUG : sub/caddy.json: md5 = 299f6a29aa5a133746b78dab2cfa9624 OK
2026/10/19 03:58:39 DEBUG : sub/caddy.json: size = 261 OK
2026/10/19 03:58:39 INFO  : sub/caddy.json: Copied (replaced existing)
2026/10/19 03:58:40 DEBUG : sub/deep/caddy.json: md5 = 5562145152898615cd00f7305a27b5b8 OK
2026/10/19 03:58:40 DEBUG : sub/deep/caddy.json: size = 131 OK
2026/10/19 03:58:40 INFO  : sub/deep/caddy.json: Copied (replaced existing)
2026/10/19 03:58:42 DEBUG : sub/deep/file4.txt: md5 = 6d17db8e4b8faa87a620c825634a0b31 OK
2026/10/19 03:58:44 DEBUG : sub/index.html: Unchanged skipping
2026/10/19 03:58:44 DEBUG : index.html: Unchanged skipping
2026/10/19 03:58:44 DEBUG : sub/deep/caddy.json: md5 = 3e7eae3076bd68a8939deea631d9d144 OK
2026/10/19 03:58:44 DEBUG : sub/deep/caddy.json: size = 260 OK
2026/10/19 03:58:44 INFO  : sub/deep/caddy.json: Copied (replaced existing)
2026/10/19 03:58:44 DEBUG : index.json: Unchanged skipping
2026/10/19 03:58:44 DEBUG : sub/index.json: Unchanged skipping
2026/10/19 03:58:45 DEBUG : sub/deep/index.html: md5 = 38255b49f554d59a8290b9c66cee2acc OK
2026/10/19 03:58:45 DEBUG : sub/deep/index.html: size = 12346 OK
2026/10/19 03:58:45 INFO  : sub/deep/index.html: Copied (replaced existing)
2026/10/19 03:58:45 DEBUG : sub/deep/index.json: md5 = a5fda5d27615af298bf04e965dfb6ce2 OK
2026/10/19 03:58:45 DEBUG : sub/deep/index.json: size = 235 OK
2026/10/19 03:58:45 INFO  : sub/deep/index.json: Copied (replaced existing)
2026/10/19 03:58:45 DEBUG : sub/caddy.json: Unchanged skipping
2026/10/19 03:58:45 DEBUG : caddy.json: Unchanged skipping
2026/10/19 03:58:48 DEBUG : sub/deep/file5.txt: md5 = 37385a2c4b01ae8954a79cd8062ec6f5 OK
2026/10/19 03:58:49 NOTICE: sub/deep/caddy.json: Skipped copy as --dry-run is set (size 389)
2026/10/19 03:58:49 NOTICE: sub/deep/index.html: Skipped copy as --dry-run is set (size 12.309Ki)
2026/10/19 03:58:49 NOTICE: sub/deep/index.json: Skipped copy as --dry-run is set (size 351)
2026/10/19 03:58:49 DEBUG : sub/index.html: Unchanged skipping
2026/10/19 03:58:50 DEBUG : index.html: Unchanged skipping
2026/10/19 03:58:50 DEBUG : index.json: Unchanged skipping
2026/10/19 03:58:50 DEBUG : sub/index.json: Unchanged skipping
2026/10/19 03:58:51 DEBUG : sub/caddy.json: Unchanged skipping
2026/10/19 03:58:51 DEBUG : caddy.json: Unchanged skipping
--- PASS: TestIndex (51.67s)
=== RUN   TestIndexDelete
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 03:59:06 DEBUG : file1.txt: md5 = 7551a04adc05c8098d9061647351d62d OK
2026/10/19 03:59:08 DEBUG : sub/file2.txt: md5 = 9d3ad8ce7884eaf309d02320afcbfdd9 OK
2026/10/19 03:59:11 DEBUG : sub/deep/file3.txt: md5 = d2a32cf58ce86a2a0f209893f57fecbd OK
2026/10/19 03:59:14 DEBUG : sub/deep/index.html: md5 = dfa52a856de266de0c1848132e76d3e0 OK
2026/10/19 03:59:14 DEBUG : sub/deep/index.html: size = 12087 OK
2026/10/19 03:59:14 INFO  : sub/deep/index.html: Copied (new)
2026/10/19 03:59:14 DEBUG : sub/index.html: md5 = 9a6a1585fa34d94e5aff6e971d2cb097 OK
2026/10/19 03:59:14 DEBUG : sub/index.html: size = 12306 OK
2026/10/19 03:59:14 INFO  : sub/index.html: Copied (new)
2026/10/19 03:59:14 DEBUG : index.html: md5 = 10385761364cf771f2520902a82f16df OK
2026/10/19 03:59:14 DEBUG : index.html: size = 12147 OK
2026/10/19 03:59:14 INFO  : index.html: Copied (new)
2026/10/19 03:59:17 DEBUG : index.html: Unchanged skipping
2026/10/19 03:59:17 DEBUG : sub/index.html: Unchanged skipping
2026/10/19 03:59:18 DEBUG : sub/deep/index.html: md5 = 105505a2456483634d4afed8bbaaab27 OK
2026/10/19 03:59:18 DEBUG : sub/deep/index.html: size = 11829 OK
2026/10/19 03:59:18 INFO  : sub/deep/index.html: Copied (replaced existing)
--- PASS: TestIndexDelete (23.84s)
=== RUN   TestIndexFilter
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 03:59:30 DEBUG : file1.txt: md5 = 5344e9ad7be70c1e13f401dd4fbdbcb8 OK
2026/10/19 03:59:32 DEBUG : sub/file2.txt: md5 = 1f541be70d363eedca6137c7fd13a01f OK
2026/10/19 03:59:35 DEBUG : sub/deep/file3.txt: md5 = f93b311fa9a9ebde2dd8159ddfcc31d1 OK
2026/10/19 03:59:37 DEBUG : sub/deep/index.html: md5 = f4be5fdd7733c8047d2fa83c5660a5f2 OK
2026/10/19 03:59:40 DEBUG : other/notes.md: md5 = c70825e0ccb5261e68054e876a6feccd OK
2026/10/19 03:59:41 DEBUG : other/notes.md: Excluded (Path Filter)
2026/10/19 03:59:43 DEBUG : other/index.html: md5 = b819e138a6a2cdc9fb478ed1a2b16c4a OK
2026/10/19 03:59:43 DEBUG : other/index.html: size = 11802 OK
2026/10/19 03:59:43 INFO  : other/index.html: Copied (new)
2026/10/19 03:59:43 DEBUG : sub/index.html: md5 = 18a4fe74bc2818e34c7341e96b63465f OK
2026/10/19 03:59:43 DEBUG : sub/index.html: size = 12056 OK
2026/10/19 03:59:43 INFO  : sub/index.html: Copied (new)
2026/10/19 03:59:43 DEBUG : index.html: md5 = 472f9c42da69857c3fd5fded4e269db5 OK
2026/10/19 03:59:43 DEBUG : index.html: size = 12399 OK
2026/10/19 03:59:43 INFO  : index.html: Copied (new)
2026/10/19 03:59:50 DEBUG : other/notes.md: Excluded (Path Filter)
2026/10/19 03:59:50 DEBUG : sub/index.html: Unchanged skipping
2026/10/19 03:59:50 DEBUG : other/index.html: Unchanged skipping
2026/10/19 03:59:51 DEBUG : index.html: Unchanged skipping
--- PASS: TestIndexFilter (31.84s)
=== RUN   TestIndexRules
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 04:00:01 DEBUG : file1.txt: md5 = abf3dc8243f41a08431c5ed4b0e9bec2 OK
2026/10/19 04:00:04 DEBUG : sub/file2.txt: md5 = 5bf0883fe9a7f884ef60e3e78e22b3a8 OK
2026/10/19 04:00:07 DEBUG : sub/deep/file3.txt: md5 = e9e008aec8d6cdef7c605ef006284e4e OK
2026/10/19 04:00:09 DEBUG : sub/deep/index.html: md5 = b37171a4d12217ce4d7ba0fa0bb7b4cb OK
2026/10/19 04:00:12 DEBUG : sub/index.html: md5 = 291cf796fd9af60640cc18e7919709a5 OK
2026/10/19 04:00:12 DEBUG : sub/index.html: size = 12306 OK
2026/10/19 04:00:12 INFO  : sub/index.html: Copied (new)
2026/10/19 04:00:12 DEBUG : index.html: md5 = 750e87de9b481611ce7fae897afae991 OK
2026/10/19 04:00:12 DEBUG : index.html: size = 12147 OK
2026/10/19 04:00:12 INFO  : index.html: Copied (new)
2026/10/19 04:00:18 DEBUG : sub/index.html: Unchanged skipping
2026/10/19 04:00:19 DEBUG : sub/deep/index.html: md5 = 87246a76162825258ff4758566571b80 OK
2026/10/19 04:00:19 DEBUG : sub/deep/index.html: size = 12087 OK
2026/10/19 04:00:19 INFO  : sub/deep/index.html: Copied (replaced existing)
--- PASS: TestIndexRules (28.28s)
=== RUN   TestIndexMaxDepth
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 04:00:30 DEBUG : file1.txt: md5 = e49d9ba451260bfe0d44a2cc317ada03 OK
2026/10/19 04:00:33 DEBUG : sub/file2.txt: md5 = f4f1f813bd42cb1930c0108dc80d584b OK
2026/10/19 04:00:36 DEBUG : sub/deep/file3.txt: md5 = 7d2a07997a3f135290d1aa6e7ec6a145 OK
2026/10/19 04:00:39 DEBUG : index.html: md5 = 052ff4df31e82c44f9ea81d4c93c33f6 OK
2026/10/19 04:00:39 DEBUG : index.html: size = 12147 OK
2026/10/19 04:00:39 INFO  : index.html: Copied (new)
--- PASS: TestIndexMaxDepth (18.69s)
=== RUN   TestIndexLinkIndex
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 04:00:48 DEBUG : file1.txt: md5 = 059c0d0aadb8a90de39e1f39f83d5cdd OK
2026/10/19 04:00:51 DEBUG : sub/file2.txt: md5 = 3191e94eee1241d96193b8266c79d99f OK
2026/10/19 04:00:54 DEBUG : sub/deep/file3.txt: md5 = 476c1ee3d31f131268ff7bead9056110 OK
2026/10/19 04:00:57 DEBUG : index.html: md5 = 572d9fe7e5dc14009f264a1e89dc3688 OK
2026/10/19 04:00:57 DEBUG : index.html: size = 12167 OK
2026/10/19 04:00:57 INFO  : index.html: Copied (new)
2026/10/19 04:00:57 DEBUG : sub/index.html: md5 = b1664ae42842263ba19e4ad7ec1f1cfe OK
2026/10/19 04:00:57 DEBUG : sub/index.html: size = 12347 OK
2026/10/19 04:00:57 INFO  : sub/index.html: Copied (new)
2026/10/19 04:00:57 DEBUG : sub/deep/index.html: md5 = 8901362bf2e520876f5b3ee406882074 OK
2026/10/19 04:00:57 DEBUG : sub/deep/index.html: size = 12128 OK
2026/10/19 04:00:57 INFO  : sub/deep/index.html: Copied (new)
--- PASS: TestIndexLinkIndex (20.23s)
=== RUN   TestIndexDirTime
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 04:01:09 DEBUG : file1.txt: md5 = abf65fb7e48a6ac1859c072c88b5a0e4 OK
2026/10/19 04:01:12 DEBUG : sub/file2.txt: md5 = 1af3098b916513a7002435d753c6c81a OK
2026/10/19 04:01:14 DEBUG : sub/deep/file3.txt: md5 = 03c84e576fac24ef9ad07ece04d693b1 OK
2026/10/19 04:01:17 DEBUG : index.json: md5 = f7d012211a6fd8cfa2aa55557d2661f7 OK
2026/10/19 04:01:17 DEBUG : index.json: size = 257 OK
2026/10/19 04:01:17 INFO  : index.json: Copied (new)
2026/10/19 04:01:17 DEBUG : sub/deep/index.json: md5 = 334a713fc03573e83e30d00a940d434d OK
2026/10/19 04:01:17 DEBUG : sub/deep/index.json: size = 139 OK
2026/10/19 04:01:17 INFO  : sub/deep/index.json: Copied (new)
2026/10/19 04:01:17 DEBUG : sub/index.json: md5 = 48671eee53a2fb16ce1f9d6b1c52d1f0 OK
2026/10/19 04:01:17 DEBUG : sub/index.json: size = 259 OK
2026/10/19 04:01:17 INFO  : sub/index.json: Copied (new)
2026/10/19 04:01:21 DEBUG : sub/deep/index.json: Unchanged skipping
2026/10/19 04:01:21 DEBUG : index.json: md5 = 121ed7bb85c67bf6ffcea9ff2d8dc1f1 OK
2026/10/19 04:01:21 DEBUG : index.json: size = 237 OK
2026/10/19 04:01:21 INFO  : index.json: Copied (replaced existing)
2026/10/19 04:01:22 DEBUG : sub/index.json: md5 = 1be1216883d4bf4c572711b53ec43226 OK
2026/10/19 04:01:22 DEBUG : sub/index.json: size = 239 OK
2026/10/19 04:01:22 INFO  : sub/index.json: Copied (replaced existing)
2026/10/19 04:01:25 DEBUG : index.json: md5 = 5633b4a8c0bc139fbd81821e5f25ae97 OK
2026/10/19 04:01:25 DEBUG : index.json: size = 213 OK
2026/10/19 04:01:25 INFO  : index.json: Copied (replaced existing)
2026/10/19 04:01:25 DEBUG : sub/deep/index.json: md5 = 411086c56d588cd0c05c17f0d695c950 OK
2026/10/19 04:01:25 DEBUG : sub/deep/index.json: size = 119 OK
2026/10/19 04:01:25 INFO  : sub/deep/index.json: Copied (replaced existing)
2026/10/19 04:01:25 DEBUG : sub/index.json: md5 = 3aa357832667f7c2f5a854cef72e4fa8 OK
2026/10/19 04:01:25 DEBUG : sub/index.json: size = 215 OK
2026/10/19 04:01:25 INFO  : sub/index.json: Copied (replaced existing)
--- PASS: TestIndexDirTime (26.92s)
=== RUN   TestIndexTemplate
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 04:01:35 DEBUG : file1.txt: md5 = d9df26d1b9f181e72cc202a435dcb93c OK
2026/10/19 04:01:38 DEBUG : sub/file2.txt: md5 = 0917eaf0da3710daa782c77c771d84ae OK
2026/10/19 04:01:41 DEBUG : sub/deep/file3.txt: md5 = f76fde150bf46ca8213f7d44f13e63a8 OK
2026/10/19 04:01:44 DEBUG : list.txt: md5 = a6374688ac3c124f95a03f3998ec2bc3 OK
2026/10/19 04:01:44 DEBUG : list.txt: size = 59 OK
2026/10/19 04:01:44 INFO  : list.txt: Copied (new)
2026/10/19 04:01:44 DEBUG : sub/deep/list.txt: md5 = 7773d9ff516885846b59024c7b843a19 OK
2026/10/19 04:01:44 DEBUG : sub/deep/list.txt: size = 47 OK
2026/10/19 04:01:44 INFO  : sub/deep/list.txt: Copied (new)
2026/10/19 04:01:44 DEBUG : sub/list.txt: md5 = 1069c65553693dede356aeecfaa8ee4d OK
2026/10/19 04:01:44 DEBUG : sub/list.txt: size = 64 OK
2026/10/19 04:01:44 INFO  : sub/list.txt: Copied (new)
--- PASS: TestIndexTemplate (19.77s)
=== RUN   TestIndexTemplateBuiltin
--- PASS: TestIndexTemplateBuiltin (0.00s)
=== RUN   TestRcIndex
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcIndex (0.00s)
=== RUN   TestIndexNoHash
    index_test.go:385: Skipping test on non local remote
--- SKIP: TestIndexNoHash (0.00s)
=== RUN   TestIndexChanged
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 04:01:56 DEBUG : file1.txt: md5 = 15864cf496eb5ee707a03771a9a83ea4 OK
2026/10/19 04:01:59 DEBUG : sub/file2.txt: md5 = b795403d47243dad90a06bc109709c3c OK
2026/10/19 04:02:02 DEBUG : sub/deep/file3.txt: md5 = c941b4092f00b072a4e05867a161f13b OK
2026/10/19 04:02:05 DEBUG : other/notes.md: md5 = c795601d1cc9ba6be362e934d3d5ef08 OK
2026/10/19 04:02:07 DEBUG : sub/deep/index.html: md5 = e475d03f2bde3dc1f4e48c2d99c5a114 OK
2026/10/19 04:02:07 DEBUG : sub/deep/index.html: size = 12087 OK
2026/10/19 04:02:07 INFO  : sub/deep/index.html: Copied (new)
2026/10/19 04:02:07 DEBUG : index.html: md5 = 36876a950ca6f0880b43d80de0da2e8d OK
2026/10/19 04:02:07 DEBUG : index.html: size = 12399 OK
2026/10/19 04:02:07 INFO  : index.html: Copied (new)
2026/10/19 04:02:07 DEBUG : sub/index.html: md5 = 0ff1052b23ec517f8285f9fd2e23dd42 OK
2026/10/19 04:02:07 DEBUG : sub/index.html: size = 12306 OK
2026/10/19 04:02:07 INFO  : sub/index.html: Copied (new)
2026/10/19 04:02:08 DEBUG : other/index.html: md5 = 62be4a117dcb57e1524845e46f3c2f41 OK
2026/10/19 04:02:08 DEBUG : other/index.html: size = 12058 OK
2026/10/19 04:02:08 INFO  : other/index.html: Copied (new)
2026/10/19 04:02:09 DEBUG : sub/deep/file4.txt: md5 = 47f52cee09d5a36eb1584e3c226935e0 OK
2026/10/19 04:02:11 DEBUG : other/undeclared.txt: md5 = 1bbfc666e85c1c21aecdffec164cfd89 OK
2026/10/19 04:02:11 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu': Partial index: listing 3 directories and walking 0 changed directories
2026/10/19 04:02:13 DEBUG : index.html: Unchanged skipping
2026/10/19 04:02:13 DEBUG : sub/index.html: Unchanged skipping
2026/10/19 04:02:14 DEBUG : sub/deep/index.html: md5 = c94d8a020bc9fca60a55646383e21130 OK
2026/10/19 04:02:14 DEBUG : sub/deep/index.html: size = 12346 OK
2026/10/19 04:02:14 INFO  : sub/deep/index.html: Copied (replaced existing)
2026/10/19 04:02:18 DEBUG : sub/new/file5.txt: md5 = 10be593cc331b80e8763055fc46665e5 OK
2026/10/19 04:02:18 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu': Partial index: listing 3 directories and walking 0 changed directories
2026/10/19 04:02:20 DEBUG : index.html: Unchanged skipping
2026/10/19 04:02:21 DEBUG : sub/index.html: md5 = a5dd7859e7349a949a3d51b2ef6fb864 OK
2026/10/19 04:02:21 DEBUG : sub/index.html: size = 12554 OK
2026/10/19 04:02:21 INFO  : sub/index.html: Copied (replaced existing)
2026/10/19 04:02:21 DEBUG : sub/new/index.html: md5 = 372aae82a777e2a0cffb508c5f3545fe OK
2026/10/19 04:02:21 DEBUG : sub/new/index.html: size = 12085 OK
2026/10/19 04:02:21 INFO  : sub/new/index.html: Copied (new)
2026/10/19 04:02:25 DEBUG : other/undeclared0.txt: md5 = 4e65bad9a79b28514d87989cd9917631 OK
2026/10/19 04:02:25 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu': Partial index: listing 1 directories and walking 1 changed directories
2026/10/19 04:02:26 DEBUG : index.html: Unchanged skipping
2026/10/19 04:02:27 DEBUG : other/index.html: md5 = 09d5ff82facdfc4140ef7724918d3f00 OK
2026/10/19 04:02:27 DEBUG : other/index.html: size = 12597 OK
2026/10/19 04:02:27 INFO  : other/index.html: Copied (replaced existing)
2026/10/19 04:02:30 DEBUG : other/undeclared1.txt: md5 = 3517518dfc15ba4fb5c1b2936e24c146 OK
2026/10/19 04:02:30 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu': Partial index: listing 1 directories and walking 0 changed directories
2026/10/19 04:02:31 DEBUG : index.html: Unchanged skipping
2026/10/19 04:02:35 DEBUG : other/index.html: md5 = 5de8657b33520a2900521eeb8aaea878 OK
2026/10/19 04:02:35 DEBUG : other/index.html: size = 12867 OK
2026/10/19 04:02:35 INFO  : other/index.html: Copied (replaced existing)
2026/10/19 04:02:38 DEBUG : sub/file6.txt: md5 = 90bdd9cb86d546e37976adb427158d2a OK
2026/10/19 04:02:38 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu': Nothing changed so nothing to index
2026/10/19 04:02:39 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu': Root changed so doing a full index
2026/10/19 04:02:41 DEBUG : index.html: Unchanged skipping
2026/10/19 04:02:41 DEBUG : other/index.html: Unchanged skipping
2026/10/19 04:02:41 DEBUG : sub/deep/index.html: Unchanged skipping
2026/10/19 04:02:41 DEBUG : sub/new/index.html: Unchanged skipping
2026/10/19 04:02:42 DEBUG : sub/index.html: md5 = 02d975772ae1ddba6b8dddcee75ca13d OK
2026/10/19 04:02:42 DEBUG : sub/index.html: size = 12813 OK
2026/10/19 04:02:42 INFO  : sub/index.html: Copied (replaced existing)
2026/10/19 04:02:45 DEBUG : sub/file7.txt: md5 = 1e258834742b079886d668295363a95e OK
2026/10/19 04:02:45 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu': Partial index would list 2 directories, more than 1, so doing a full index
2026/10/19 04:02:46 DEBUG : sub/deep/index.html: Unchanged skipping
2026/10/19 04:02:46 DEBUG : index.html: Unchanged skipping
2026/10/19 04:02:46 DEBUG : other/index.html: Unchanged skipping
2026/10/19 04:02:47 DEBUG : sub/new/index.html: Unchanged skipping
2026/10/19 04:02:47 DEBUG : sub/index.html: md5 = 54fb0d8fe24d698af7f78833df4ee8be OK
2026/10/19 04:02:47 DEBUG : sub/index.html: size = 13071 OK
2026/10/19 04:02:47 INFO  : sub/index.html: Copied (replaced existing)
2026/10/19 04:02:50 DEBUG : sub/deep/file8.txt: md5 = 8cc8d61d445b512a7ab0531d8c1926a1 OK
2026/10/19 04:02:50 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu': Partial index: listing 1 directories and walking 1 changed directories
2026/10/19 04:02:52 DEBUG : index.html: Unchanged skipping
2026/10/19 04:02:52 DEBUG : sub/new/index.html: Unchanged skipping
2026/10/19 04:02:52 DEBUG : sub/index.html: Unchanged skipping
2026/10/19 04:02:53 DEBUG : sub/deep/index.html: md5 = 7c9716f3d7c084b158069bb50f8882f0 OK
2026/10/19 04:02:53 DEBUG : sub/deep/index.html: size = 12604 OK
2026/10/19 04:02:53 INFO  : sub/deep/index.html: Copied (replaced existing)
2026/10/19 04:02:56 DEBUG : sub/deep/file9.txt: md5 = 3ddee587de7cae1e1e3a15a05f0ab136 OK
2026/10/19 04:02:58 DEBUG : sub/file10.txt: md5 = 9482a08fd544405fd089450cc2e0fc0e OK
2026/10/19 04:02:58 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu': Partial index: listing 2 directories and walking 0 changed directories
2026/10/19 04:02:59 DEBUG : index.html: Unchanged skipping
2026/10/19 04:03:00 DEBUG : sub/index.html: md5 = 9cef3dba267b89698d73c65f4ae01a37 OK
2026/10/19 04:03:00 DEBUG : sub/index.html: size = 13331 OK
2026/10/19 04:03:00 INFO  : sub/index.html: Copied (replaced existing)
--- PASS: TestIndexChanged (86.54s)
=== RUN   TestIndexChangedDelete
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 04:03:22 DEBUG : file1.txt: md5 = dfe026456e5843e734e14ec11e9689be OK
2026/10/19 04:03:25 DEBUG : sub/file2.txt: md5 = 616178f9d9faf878e7284d6f5b76ea4e OK
2026/10/19 04:03:28 DEBUG : sub/deep/file3.txt: md5 = e65b4635593f76d2e0df093f3c8c5797 OK
2026/10/19 04:03:31 DEBUG : index.html: md5 = 1706247cb4ac62727ebcb4f72f4544f8 OK
2026/10/19 04:03:31 DEBUG : index.html: size = 12147 OK
2026/10/19 04:03:31 INFO  : index.html: Copied (new)
2026/10/19 04:03:31 DEBUG : sub/index.html: md5 = fad0fa64987954682982b2cad6745c0f OK
2026/10/19 04:03:31 DEBUG : sub/index.html: size = 12306 OK
2026/10/19 04:03:31 INFO  : sub/index.html: Copied (new)
2026/10/19 04:03:31 DEBUG : sub/deep/index.html: md5 = 5e0caf16f5e2236105ccab73d19a8a4d OK
2026/10/19 04:03:31 DEBUG : sub/deep/index.html: size = 12087 OK
2026/10/19 04:03:31 INFO  : sub/deep/index.html: Copied (new)
2026/10/19 04:03:32 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu': Partial index: listing 3 directories and walking 0 changed directories
2026/10/19 04:03:33 DEBUG : index.html: Unchanged skipping
2026/10/19 04:03:34 DEBUG : sub/index.html: Unchanged skipping
2026/10/19 04:03:34 DEBUG : sub/deep/index.html: md5 = 4e2c2537e0c9af3c026900e01cd91347 OK
2026/10/19 04:03:34 DEBUG : sub/deep/index.html: size = 11829 OK
2026/10/19 04:03:34 INFO  : sub/deep/index.html: Copied (replaced existing)
--- PASS: TestIndexChangedDelete (22.09s)
=== RUN   TestIndexChangedFiles
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 04:03:44 DEBUG : file1.txt: md5 = 366ca12b97d5498abda8110693b01c6d OK
2026/10/19 04:03:47 DEBUG : sub/file2.txt: md5 = a0cb75a0820391fe6e193c21ae65dbff OK
2026/10/19 04:03:50 DEBUG : sub/deep/file3.txt: md5 = 904a485e3e0c0a5dff117921290733c2 OK
2026/10/19 04:03:53 DEBUG : - odd/file.txt: md5 = 8dd0b1ee8a2e792ea513d53ea8bd014f OK
2026/10/19 04:03:56 DEBUG : sub/deep/index.html: md5 = 0744bf4bfcc9d7e121de33b1d5d74ace OK
2026/10/19 04:03:56 DEBUG : sub/deep/index.html: size = 12087 OK
2026/10/19 04:03:56 INFO  : sub/deep/index.html: Copied (new)
2026/10/19 04:03:56 DEBUG : index.html: md5 = 995aa4b164945b098a28b4a25f4b7f96 OK
2026/10/19 04:03:56 DEBUG : index.html: size = 12401 OK
2026/10/19 04:03:56 INFO  : index.html: Copied (new)
2026/10/19 04:03:56 DEBUG : - odd/index.html: md5 = 43181d6f21bdfd9110b8c7e14851e696 OK
2026/10/19 04:03:56 DEBUG : - odd/index.html: size = 12058 OK
2026/10/19 04:03:56 INFO  : - odd/index.html: Copied (new)
2026/10/19 04:03:56 DEBUG : sub/index.html: md5 = 9a850e00f9bc6cb096d6d49145d1babb OK
2026/10/19 04:03:56 DEBUG : sub/index.html: size = 12306 OK
2026/10/19 04:03:56 INFO  : sub/index.html: Copied (new)
2026/10/19 04:03:58 DEBUG : - odd/file8.txt: md5 = 4b41195384ad403f06e99a48d8842603 OK
2026/10/19 04:04:00 DEBUG : sub/file9.txt: md5 = bbd7a73064a37fede0ab1fc46456dfb1 OK
2026/10/19 04:04:00 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu': Partial index: listing 2 directories and walking 0 changed directories
2026/10/19 04:04:01 DEBUG : index.html: Unchanged skipping
2026/10/19 04:04:02 DEBUG : - odd/index.html: md5 = 4d97318271544b4eee7d19846f6bcbaa OK
2026/10/19 04:04:02 DEBUG : - odd/index.html: size = 12316 OK
2026/10/19 04:04:02 INFO  : - odd/index.html: Copied (replaced existing)
2026/10/19 04:04:04 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu': Partial index: listing 4 directories and walking 0 changed directories
2026/10/19 04:04:06 DEBUG : - odd/index.html: Unchanged skipping
2026/10/19 04:04:06 DEBUG : index.html: Unchanged skipping
2026/10/19 04:04:06 DEBUG : sub/deep/index.html: Unchanged skipping
2026/10/19 04:04:07 DEBUG : sub/index.html: md5 = b316a2365ab59490006fbbf85cd3b46b OK
2026/10/19 04:04:07 DEBUG : sub/index.html: size = 12565 OK
2026/10/19 04:04:07 INFO  : sub/index.html: Copied (replaced existing)
--- PASS: TestIndexChangedFiles (37.15s)
=== RUN   TestIndexChangedDirTime
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 04:04:21 DEBUG : file1.txt: md5 = e7752577a8efcfea6160c7b20a6f76eb OK
2026/10/19 04:04:24 DEBUG : sub/file2.txt: md5 = b0a6a394b7ae25aaa3aaf559dd1c258f OK
2026/10/19 04:04:27 DEBUG : sub/deep/file3.txt: md5 = d917deb7f892b3de8c20a9b933ee9929 OK
2026/10/19 04:04:30 DEBUG : other/notes.md: md5 = cb531653a7401e78d9773d41d13efcc0 OK
2026/10/19 04:04:32 DEBUG : index.json: md5 = a89a5fdaf8e4103d4913a887c392f664 OK
2026/10/19 04:04:32 DEBUG : index.json: size = 379 OK
2026/10/19 04:04:32 INFO  : index.json: Copied (new)
2026/10/19 04:04:33 DEBUG : sub/deep/index.json: md5 = c86b60b7765353ec7617821e2c4d9571 OK
2026/10/19 04:04:33 DEBUG : sub/deep/index.json: size = 139 OK
2026/10/19 04:04:33 INFO  : sub/deep/index.json: Copied (new)
2026/10/19 04:04:33 DEBUG : other/index.json: md5 = 9c4ee10dccd01f2233d7706926320b36 OK
2026/10/19 04:04:33 DEBUG : other/index.json: size = 144 OK
2026/10/19 04:04:33 INFO  : other/index.json: Copied (new)
2026/10/19 04:04:33 DEBUG : sub/index.json: md5 = 8e366d745b80b20f13edff4d9871df33 OK
2026/10/19 04:04:33 DEBUG : sub/index.json: size = 259 OK
2026/10/19 04:04:33 INFO  : sub/index.json: Copied (new)
2026/10/19 04:04:34 DEBUG : sub/deep/file4.txt: md5 = 49b1bcba428f18ab8a5b0a04e70e2ed8 OK
2026/10/19 04:04:34 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu': Partial index: listing 3 directories and walking 0 changed directories
2026/10/19 04:04:38 DEBUG : sub/deep/index.json: md5 = 7a83d935b4d0d04428c30c20910fd7a8 OK
2026/10/19 04:04:38 DEBUG : sub/deep/index.json: size = 275 OK
2026/10/19 04:04:38 INFO  : sub/deep/index.json: Copied (replaced existing)
2026/10/19 04:04:38 DEBUG : index.json: md5 = 656954a50ae6939cb7c611c58bf725b1 OK
2026/10/19 04:04:38 DEBUG : index.json: size = 379 OK
2026/10/19 04:04:38 INFO  : index.json: Copied (replaced existing)
2026/10/19 04:04:38 DEBUG : sub/index.json: md5 = 095f419ef9466d4839b6487b8921e0ac OK
2026/10/19 04:04:38 DEBUG : sub/index.json: size = 259 OK
2026/10/19 04:04:38 INFO  : sub/index.json: Copied (replaced existing)
--- PASS: TestIndexChangedDirTime (29.94s)
=== RUN   TestListDirSorted
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 04:04:51 DEBUG : a.txt: md5 = 6b38d96f1ee22ba740ad3d3552776df1 OK
2026/10/19 04:04:53 DEBUG : zend.txt: md5 = 36f64e6492a74c96ff767294c7b94b5d OK
2026/10/19 04:04:56 DEBUG : sub dir/hello world: md5 = 0854a08e1a7b96cd223ae0198b51829a OK
2026/10/19 04:04:58 DEBUG : sub dir/hello world2: md5 = 20174c72a504d1eae1e4a08e8cdc4e4e OK
2026/10/19 04:05:00 DEBUG : sub dir/ignore dir/.ignore: md5 = 8f0fe8af4eb5186688f01ea922caa900 OK
2026/10/19 04:05:02 DEBUG : sub dir/ignore dir/should be ignored: md5 = fb860b264a2882e4dcf07a11839a352e OK
2026/10/19 04:05:05 DEBUG : sub dir/sub sub dir/hello world3: md5 = da375ac67d998bf6da293d09dbe42044 OK
2026/10/19 04:05:07 DEBUG : a.txt: Excluded (Size Filter)
2026/10/19 04:05:07 DEBUG : a.txt: Excluded
2026/10/19 04:05:08 DEBUG : sub dir/hello world: Excluded (Size Filter)
2026/10/19 04:05:08 DEBUG : sub dir/hello world: Excluded
2026/10/19 04:05:08 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2026/10/19 04:05:08 DEBUG : sub dir/hello world2: Excluded
2026/10/19 04:05:09 DEBUG : sub dir/ignore dir: Excluded
2026/10/19 04:05:09 DEBUG : sub dir/hello world: Excluded (Size Filter)
2026/10/19 04:05:09 DEBUG : sub dir/hello world: Excluded
2026/10/19 04:05:09 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2026/10/19 04:05:09 DEBUG : sub dir/hello world2: Excluded
2026/10/19 04:05:10 DEBUG : sub dir/ignore dir: Excluded
--- PASS: TestListDirSorted (30.65s)
=== RUN   TestListDirSortedFn
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 04:05:22 DEBUG : a.txt: md5 = ed4a1eb1fdbd1c733965a075f0ca4ec0 OK
2026/10/19 04:05:23 DEBUG : zend.txt: md5 = a4a6db16a581322fb2bfa588238192c5 OK
2026/10/19 04:05:26 DEBUG : sub dir/hello world: md5 = 42952de036c04718ade6462be6849587 OK
2026/10/19 04:05:28 DEBUG : sub dir/hello world2: md5 = 85f325c72b2af3416cc4be4226422f4c OK
2026/10/19 04:05:31 DEBUG : sub dir/ignore dir/.ignore: md5 = 6e51bb3ff006665b24c2d8114e84e93c OK
2026/10/19 04:05:33 DEBUG : sub dir/ignore dir/should be ignored: md5 = 0f7ad5e0c24100f6c9cebe88e41bedd9 OK
2026/10/19 04:05:36 DEBUG : sub dir/sub sub dir/hello world3: md5 = 1c1fef93739c84a73ac8ed1e03ddc787 OK
2026/10/19 04:05:37 DEBUG : a.txt: Excluded (Size Filter)
2026/10/19 04:05:37 DEBUG : a.txt: Excluded
2026/10/19 04:05:38 DEBUG : sub dir/hello world: Excluded (Size Filter)
2026/10/19 04:05:38 DEBUG : sub dir/hello world: Excluded
2026/10/19 04:05:38 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2026/10/19 04:05:38 DEBUG : sub dir/hello world2: Excluded
2026/10/19 04:05:39 DEBUG : sub dir/ignore dir: Excluded
2026/10/19 04:05:39 DEBUG : sub dir/hello world: Excluded (Size Filter)
2026/10/19 04:05:39 DEBUG : sub dir/hello world: Excluded
2026/10/19 04:05:39 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2026/10/19 04:05:39 DEBUG : sub dir/hello world2: Excluded
2026/10/19 04:05:39 DEBUG : sub dir/ignore dir: Excluded
--- PASS: TestListDirSortedFn (33.85s)
=== RUN   TestListJSON
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 04:05:56 DEBUG : file1: md5 = 573b68d179893472d7de41bde99bbec2 OK
2026/10/19 04:05:59 DEBUG : sub/file2: md5 = bb63fb9199712f95d1cc6427204c7834 OK
=== RUN   TestListJSON/Default
=== RUN   TestListJSON/FilesOnly
=== RUN   TestListJSON/DirsOnly
=== RUN   TestListJSON/Recurse
=== RUN   TestListJSON/SubDir
=== RUN   TestListJSON/NoModTime
=== RUN   TestListJSON/NoMimeType
=== RUN   TestListJSON/ShowHash
=== RUN   TestListJSON/HashTypes
2026/10/19 04:06:03 ERROR : file1: Failed to read hash: hash type not supported
=== RUN   TestListJSON/Metadata
2026/10/19 04:06:03 DEBUG : eer8kka55qnghc34cq76ca668g: Fetching metadata
2026/10/19 04:06:03 DEBUG : 150fuo3cn4j1uenq6r4g4qk6t4: Fetching metadata
--- PASS: TestListJSON (13.17s)
    --- PASS: TestListJSON/Default (0.50s)
    --- PASS: TestListJSON/FilesOnly (0.39s)
    --- PASS: TestListJSON/DirsOnly (0.25s)
    --- PASS: TestListJSON/Recurse (0.68s)
    --- PASS: TestListJSON/SubDir (0.26s)
    --- PASS: TestListJSON/NoModTime (0.29s)
    --- PASS: TestListJSON/NoMimeType (0.27s)
    --- PASS: TestListJSON/ShowHash (0.35s)
    --- PASS: TestListJSON/HashTypes (0.35s)
    --- PASS: TestListJSON/Metadata (0.84s)
=== RUN   TestStatJSON
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 04:06:09 DEBUG : file1: md5 = 64dc5e257f7f7a6cc1c29e00dee1af45 OK
2026/10/19 04:06:12 DEBUG : sub/file2: md5 = 4a25cb26153df213a3e9acf349b602aa OK
=== RUN   TestStatJSON/Root
=== RUN   TestStatJSON/RootFilesOnly
=== RUN   TestStatJSON/RootDirsOnly
=== RUN   TestStatJSON/Dir
=== RUN   TestStatJSON/DirWithTrailingSlash
=== RUN   TestStatJSON/File
=== RUN   TestStatJSON/NotFound
=== RUN   TestStatJSON/DirFilesOnly
=== RUN   TestStatJSON/FileFilesOnly
=== RUN   TestStatJSON/NotFoundFilesOnly
=== RUN   TestStatJSON/DirDirsOnly
=== RUN   TestStatJSON/FileDirsOnly
=== RUN   TestStatJSON/NotFoundDirsOnly
=== RUN   TestStatJSON/RootNotFound
2026/10/19 04:06:17 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-weyapag1ziqu/notfound"
2026/10/19 04:06:17 DEBUG : Config file has changed externally - reloading
2026/10/19 04:06:18 DEBUG : Creating backend with remote "TestDrive:crypt/mgqrlgabkh4qu4jmvhvvahqod9cv2gjeqsmi6sk74c30p3v3f7jg/qe0i31qdkoejk60elps0ni5sqk"
--- PASS: TestStatJSON (15.94s)
    --- PASS: TestStatJSON/Root (0.36s)
    --- PASS: TestStatJSON/RootFilesOnly (0.00s)
    --- PASS: TestStatJSON/RootDirsOnly (0.29s)
    --- PASS: TestStatJSON/Dir (0.83s)
    --- PASS: TestStatJSON/DirWithTrailingSlash (0.36s)
    --- PASS: TestStatJSON/File (0.27s)
    --- PASS: TestStatJSON/NotFound (0.63s)
    --- PASS: TestStatJSON/DirFilesOnly (0.37s)
    --- PASS: TestStatJSON/FileFilesOnly (0.38s)
    --- PASS: TestStatJSON/NotFoundFilesOnly (0.26s)
    --- PASS: TestStatJSON/DirDirsOnly (0.34s)
    --- PASS: TestStatJSON/FileDirsOnly (0.36s)
    --- PASS: TestStatJSON/NotFoundDirsOnly (0.36s)
    --- PASS: TestStatJSON/RootNotFound (2.19s)
=== RUN   TestStatJSONMemory
2026/10/19 04:06:23 DEBUG : Creating backend with remote ":memory:"
2026/10/19 04:06:23 DEBUG : Memory root '': File to upload is small (5 bytes), uploading instead of streaming
2026/10/19 04:06:23 DEBUG : sub/file1: size = 5 OK
2026/10/19 04:06:23 DEBUG : sub/file1: md5 = 5d41402abc4b2a76b9719d911017c592 OK
2026/10/19 04:06:23 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-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 04:06:23 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu': Making directory
2026/10/19 04:06:23 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu': Making directory
--- PASS: TestMkdir (0.82s)
=== RUN   TestLsd
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 04:06:26 DEBUG : sub dir/hello world: md5 = a779e2d13d64a5202510d809ee2bf4d6 OK
--- PASS: TestLsd (6.83s)
=== RUN   TestLs
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 04:06:32 DEBUG : potato2: md5 = 7f590ff473f475b471fa6ee928ee6ed8 OK
2026/10/19 04:06:34 DEBUG : empty space: md5 = 464b2e6517482d81c093e11e0f1c4874 OK
--- PASS: TestLs (6.24s)
=== RUN   TestLsWithFilesFrom
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 04:06:39 DEBUG : potato2: md5 = f61af3b9e59feef943cc30c240f9e0b6 OK
2026/10/19 04:06:41 DEBUG : empty space: md5 = 8b352dea3e52833421de656f36856f8f OK
2026/10/19 04:06:41 DEBUG : empty space: Excluded (FilesFrom Filter)
2026/10/19 04:06:41 DEBUG : empty space: Excluded
--- PASS: TestLsWithFilesFrom (6.94s)
=== RUN   TestLsLong
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 04:06:45 DEBUG : potato2: md5 = dc542527d2b58af9f2fca510fcb5250c OK
2026/10/19 04:06:47 DEBUG : empty space: md5 = 9b03cde6833af373c7bb6cc9f51767f4 OK
--- PASS: TestLsLong (6.40s)
=== RUN   TestHashSums
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 04:06:52 DEBUG : potato2: md5 = 7a476f057945d89c64bb6c2551ff0878 OK
2026/10/19 04:06:54 DEBUG : empty space: md5 = 6b909b770d0a407c8789b2248b09046a OK
--- PASS: TestHashSums (5.96s)
=== RUN   TestHashSumsWithErrors
2026/10/19 04:06:56 DEBUG : Creating backend with remote ":memory:"
2026/10/19 04:06:56 ERROR : sub/file1: hash unsupported: hash type not supported
2026/10/19 04:06:56 ERROR : file1: hash unsupported: hash type not supported
2026/10/19 04:06:56 ERROR : file1: hash unsupported: hash type not supported
--- PASS: TestHashSumsWithErrors (0.00s)
=== RUN   TestHashStream
2026/10/19 04:06:56 DEBUG : Creating md5 hash of 0 bytes read from input stream
2026/10/19 04:06:56 DEBUG : Creating md5 hash of 0 bytes read from input stream
2026/10/19 04:06:56 DEBUG : Creating sha1 hash of 0 bytes read from input stream
2026/10/19 04:06:56 DEBUG : Creating sha1 hash of 0 bytes read from input stream
2026/10/19 04:06:56 DEBUG : Creating md5 hash of 12 bytes read from input stream
2026/10/19 04:06:56 DEBUG : Creating md5 hash of 12 bytes read from input stream
2026/10/19 04:06:56 DEBUG : Creating sha1 hash of 12 bytes read from input stream
2026/10/19 04:06:56 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-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 04:06:58 DEBUG : potato2: md5 = 8d6946798a0baf51db4f591fed52f3df OK
2026/10/19 04:07:00 DEBUG : empty space: md5 = d6d6074ac4f0a399b602eea217675e05 OK
2026/10/19 04:07:03 DEBUG : sub dir/potato3: md5 = ae28977ca14a728cfd9fdd7908cd22d0 OK
--- PASS: TestCount (11.88s)
=== RUN   TestDelete
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 04:07:09 DEBUG : small: md5 = e051fff225b1ad75a467171d32ec64cb OK
2026/10/19 04:07:11 DEBUG : medium: md5 = 81c39b4f08ecd6b021aab2354b8405c7 OK
2026/10/19 04:07:13 DEBUG : large: md5 = 79699f8f8dc9ad921eece6ae7d36cb0f OK
2026/10/19 04:07:13 DEBUG : Waiting for deletions to finish
2026/10/19 04:07:14 DEBUG : large: Excluded (Size Filter)
2026/10/19 04:07:14 INFO  : medium: Deleted
2026/10/19 04:07:14 INFO  : small: Deleted
--- PASS: TestDelete (8.18s)
=== RUN   TestDeleteFatalError
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 04:07:18 DEBUG : file0: md5 = 809cefcb31325c84c99f318ab968daef OK
2026/10/19 04:07:20 DEBUG : file1: md5 = 1271ad7a3766a09199ca2e576a527f1e OK
2026/10/19 04:07:22 DEBUG : file2: md5 = 7330412c0cbf65e0b2f65077fbe69f29 OK
2026/10/19 04:07:23 DEBUG : file3: md5 = 840ef259db929886e890641f2070fe7f OK
2026/10/19 04:07:25 DEBUG : file4: md5 = dca615a751b3662d1393a910c9bcf6d9 OK
2026/10/19 04:07:27 DEBUG : file5: md5 = e5aa9451a4b274fc39060f397414f480 OK
2026/10/19 04:07:29 DEBUG : file6: md5 = c19bdac85cf5ae56b001abe5dde9482d OK
2026/10/19 04:07:30 DEBUG : file7: md5 = 724c03ff3c4a056c60df8f61b5394260 OK
2026/10/19 04:07:32 DEBUG : file8: md5 = 51a81662a99c6cd75dbbf7ed89aaf3b5 OK
2026/10/19 04:07:34 DEBUG : file9: md5 = e2623e3b1e16bab8bcbf5c9a1a4a417c OK
2026/10/19 04:07:36 DEBUG : file10: md5 = b1e21211ca292dff4edb568b40806a1b OK
2026/10/19 04:07:37 DEBUG : file11: md5 = 2930887ac4c0084b2ef4eee30b0a260c OK
2026/10/19 04:07:39 DEBUG : file12: md5 = a44914d7fbba01d7172571b7df37f88b OK
2026/10/19 04:07:41 DEBUG : file13: md5 = cd1cc1beb8434e3239e7fe2e000131dc OK
2026/10/19 04:07:43 DEBUG : file14: md5 = bd7da6c2845e75fce089083cc22a6e34 OK
2026/10/19 04:07:45 DEBUG : file15: md5 = aca2d22acd031207da0a5221b404a8cd OK
2026/10/19 04:07:46 DEBUG : file16: md5 = 54e0e6086401ccb60eabae9505897739 OK
2026/10/19 04:07:48 DEBUG : file17: md5 = 4e73d212d6c2e4590494d864dfc8cf94 OK
2026/10/19 04:07:50 DEBUG : file18: md5 = bb9652d5e3436ef97a0db7953653474d OK
2026/10/19 04:07:52 DEBUG : file19: md5 = a9ad5ab9f5d915b7d1d6b1edc3ff5102 OK
2026/10/19 04:07:52 DEBUG : Waiting for deletions to finish
2026/10/19 04:07:52 ERROR : file8: Got fatal error on delete: --max-delete threshold reached
2026/10/19 04:07:52 ERROR : file7: Got fatal error on delete: --max-delete threshold reached
2026/10/19 04:07:52 ERROR : file10: Got fatal error on delete: --max-delete threshold reached
2026/10/19 04:07:52 ERROR : file2: Got fatal error on delete: --max-delete threshold reached
2026/10/19 04:07:52 ERROR : file12: Got fatal error on delete: --max-delete threshold reached
2026/10/19 04:07:52 ERROR : file13: Got fatal error on delete: --max-delete threshold reached
2026/10/19 04:07:52 ERROR : file1: Got fatal error on delete: --max-delete threshold reached
2026/10/19 04:07:52 ERROR : file14: Got fatal error on delete: --max-delete threshold reached
2026/10/19 04:07:52 ERROR : file9: Got fatal error on delete: --max-delete threshold reached
2026/10/19 04:07:52 ERROR : file6: Got fatal error on delete: --max-delete threshold reached
2026/10/19 04:07:52 ERROR : file5: Got fatal error on delete: --max-delete threshold reached
2026/10/19 04:07:52 ERROR : file19: Got fatal error on delete: --max-delete threshold reached
2026/10/19 04:07:52 ERROR : file17: Got fatal error on delete: --max-delete threshold reached
2026/10/19 04:07:52 ERROR : file4: Got fatal error on delete: --max-delete threshold reached
2026/10/19 04:07:52 ERROR : file3: Got fatal error on delete: --max-delete threshold reached
2026/10/19 04:07:52 ERROR : file18: Got fatal error on delete: --max-delete threshold reached
2026/10/19 04:07:52 ERROR : file15: Got fatal error on delete: --max-delete threshold reached
2026/10/19 04:07:52 ERROR : file16: Got fatal error on delete: --max-delete threshold reached
2026/10/19 04:07:52 ERROR : file0: Got fatal error on delete: --max-delete threshold reached
2026/10/19 04:07:52 ERROR : file11: Got fatal error on delete: --max-delete threshold reached
--- PASS: TestDeleteFatalError (53.90s)
=== RUN   TestMaxDelete
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 04:08:12 DEBUG : small: md5 = 5190c58a6ac645cf6a9a6a7ebe10f8c5 OK
2026/10/19 04:08:14 DEBUG : medium: md5 = da705f94478eb76b92f4b9014fd311c9 OK
2026/10/19 04:08:15 DEBUG : large: md5 = 51b3315588920b30b3bf05a833599692 OK
2026/10/19 04:08:16 DEBUG : Waiting for deletions to finish
2026/10/19 04:08:16 ERROR : medium: Got fatal error on delete: --max-delete threshold reached
2026/10/19 04:08:17 INFO  : large: Deleted
2026/10/19 04:08:17 INFO  : small: Deleted
--- PASS: TestMaxDelete (9.00s)
=== RUN   TestMaxDeleteSizeLargeFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 04:08:21 DEBUG : small: md5 = 65659a987d1e54a836ed5cb1eda79633 OK
2026/10/19 04:08:23 DEBUG : medium: md5 = 506f32b232ba7aec91bb8e784f89d980 OK
2026/10/19 04:08:24 DEBUG : large: md5 = 9978dac0b795ed61382e026ceb9832b7 OK
2026/10/19 04:08:25 DEBUG : Waiting for deletions to finish
2026/10/19 04:08:25 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached
2026/10/19 04:08:26 INFO  : small: Deleted
2026/10/19 04:08:26 INFO  : medium: Deleted
--- PASS: TestMaxDeleteSizeLargeFile (9.04s)
=== RUN   TestMaxDeleteSize
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 04:08:30 DEBUG : small: md5 = aa80c7606bfae5215c3effee604a87d2 OK
2026/10/19 04:08:32 DEBUG : medium: md5 = 39b897ff7724b5211f8f22c924cd4571 OK
2026/10/19 04:08:33 DEBUG : large: md5 = 068fc04705cf454f0d0337822750f43d OK
2026/10/19 04:08:33 DEBUG : Waiting for deletions to finish
2026/10/19 04:08:34 ERROR : medium: Got fatal error on delete: --max-delete-size threshold reached
2026/10/19 04:08:35 INFO  : small: Deleted
2026/10/19 04:08:35 INFO  : large: Deleted
--- PASS: TestMaxDeleteSize (9.99s)
=== RUN   TestReadFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 04:08:40 DEBUG : ReadFile: md5 = c29d7a7243cd1d4aa3b07c615470526e OK
--- PASS: TestReadFile (5.11s)
=== RUN   TestRetry
2026/10/19 04:08:43 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 1/5
2026/10/19 04:08:43 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 2/5
2026/10/19 04:08:43 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms
2026/10/19 04:08:43 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms
2026/10/19 04:08:43 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms
2026/10/19 04:08:43 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms
--- PASS: TestRetry (0.04s)
=== RUN   TestRetryAfterContextCancel
--- PASS: TestRetryAfterContextCancel (0.00s)
=== RUN   TestRetryAfterLastTry
--- PASS: TestRetryAfterLastTry (0.00s)
=== RUN   TestCat
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 04:08:45 DEBUG : file1: md5 = 9e5d48b0e043108e2654eb305d7ce976 OK
2026/10/19 04:08:47 DEBUG : file2: md5 = f063c9b923dad14f45bf875d57ec6ec1 OK
--- PASS: TestCat (16.16s)
=== RUN   TestPurge
2026/10/19 04:08:59 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-hulepat9nose"
2026/10/19 04:08:59 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/19 04:08:59 DEBUG : Creating backend with remote "TestDrive:crypt/60u0c8dl7mb11rdrrj9oi5cttgfsv630rqkvs3phtbql1og5cum0"
2026/10/19 04:09:01 DEBUG : Creating backend with remote "/tmp/rclone2787912167"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hulepat9nose'", Local "Local file system at /tmp/rclone2787912167", Modify Window "1ms"
2026/10/19 04:09:07 DEBUG : A1/B1/C1/one: md5 = 454ca275bb34411badeed80fa833d843 OK
2026/10/19 04:09:07 INFO  : A2: Making directory
2026/10/19 04:09:08 INFO  : A1/B2: Making directory
2026/10/19 04:09:09 INFO  : A1/B2/C2: Making directory
2026/10/19 04:09:11 INFO  : A1/B1/C3: Making directory
2026/10/19 04:09:12 INFO  : A3: Making directory
2026/10/19 04:09:13 INFO  : A3/B3: Making directory
2026/10/19 04:09:14 INFO  : A3/B3/C4: Making directory
2026/10/19 04:09:17 DEBUG : A1/two: md5 = b1f5ab7ae86a33875108e152827a74f1 OK
2026/10/19 04:09:22 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-hulepat9nose': Purge remote
2026/10/19 04:09:22 NOTICE: purge failed: directory not found
--- PASS: TestPurge (22.88s)
=== RUN   TestRmdirsNoLeaveRoot
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 04:09:27 DEBUG : A1/B1/C1/one: md5 = 2dd8bb8df8a2237af5ba18d43c9678cd OK
2026/10/19 04:09:27 INFO  : A2: Making directory
2026/10/19 04:09:28 INFO  : A1/B2: Making directory
2026/10/19 04:09:30 INFO  : A1/B2/C2: Making directory
2026/10/19 04:09:31 INFO  : A1/B1/C3: Making directory
2026/10/19 04:09:32 INFO  : A3: Making directory
2026/10/19 04:09:33 INFO  : A3/B3: Making directory
2026/10/19 04:09:33 INFO  : A3/B3/C4: Making directory
2026/10/19 04:09:37 DEBUG : A1/two: md5 = 3fcff86e17c189e694f91739d4ff4fa3 OK
2026/10/19 04:09:38 DEBUG : removing 1 level 3 directories
2026/10/19 04:09:38 INFO  : A3/B3/C4: Removing directory
2026/10/19 04:09:42 DEBUG : removing 2 level 3 directories
2026/10/19 04:09:42 INFO  : A1/B2/C2: Removing directory
2026/10/19 04:09:42 INFO  : A1/B1/C3: Removing directory
2026/10/19 04:09:43 DEBUG : removing 2 level 2 directories
2026/10/19 04:09:43 INFO  : A3/B3: Removing directory
2026/10/19 04:09:43 INFO  : A1/B2: Removing directory
2026/10/19 04:09:44 DEBUG : removing 2 level 1 directories
2026/10/19 04:09:44 INFO  : A3: Removing directory
2026/10/19 04:09:44 INFO  : A2: Removing directory
2026/10/19 04:09:50 DEBUG : removing 1 level 3 directories
2026/10/19 04:09:50 INFO  : A1/B1/C1: Removing directory
2026/10/19 04:09:51 DEBUG : removing 1 level 2 directories
2026/10/19 04:09:51 INFO  : A1/B1: Removing directory
2026/10/19 04:09:52 DEBUG : removing 1 level 1 directories
2026/10/19 04:09:52 INFO  : A1: Removing directory
2026/10/19 04:09:53 DEBUG : removing 1 level 0 directories
2026/10/19 04:09:53 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu': Removing directory
--- PASS: TestRmdirsNoLeaveRoot (32.46s)
=== RUN   TestRmdirsLeaveRoot
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 04:09:56 INFO  : A1: Making directory
2026/10/19 04:09:57 INFO  : A1/B1: Making directory
2026/10/19 04:09:58 INFO  : A1/B1/C1: Making directory
2026/10/19 04:10:01 DEBUG : removing 1 level 3 directories
2026/10/19 04:10:01 INFO  : A1/B1/C1: Removing directory
2026/10/19 04:10:02 DEBUG : removing 1 level 2 directories
2026/10/19 04:10:02 INFO  : A1/B1: Removing directory
--- PASS: TestRmdirsLeaveRoot (10.87s)
=== RUN   TestRmdirsWithFilter
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 04:10:05 INFO  : A1: Making directory
2026/10/19 04:10:07 INFO  : A1/B1: Making directory
2026/10/19 04:10:08 INFO  : A1/B1/C1: Making directory
2026/10/19 04:10:11 DEBUG : removing 1 level 3 directories
2026/10/19 04:10:11 INFO  : A1/B1/C1: Removing directory
2026/10/19 04:10:12 DEBUG : removing 1 level 2 directories
2026/10/19 04:10:12 INFO  : A1/B1: Removing directory
--- PASS: TestRmdirsWithFilter (10.39s)
=== RUN   TestCopyURL
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 04:10:18 DEBUG : file1: md5 = fdb56f74dd62750d4aa64e986f4cd81a OK
2026/10/19 04:10:18 DEBUG : file1: size = 14 OK
2026/10/19 04:10:19 DEBUG : filename.txt: File name found in url
2026/10/19 04:10:21 DEBUG : filename.txt: md5 = df1c9e7e33fe04c174921aa4c1d2a08c OK
2026/10/19 04:10:21 DEBUG : filename.txt: size = 14 OK
2026/10/19 04:10:21 DEBUG : headerfilename.txt: filename found in Content-Disposition header.
2026/10/19 04:10:22 DEBUG : headerfilename.txt: md5 = 66dca8f4b85542027e1d144212bf0d77 OK
2026/10/19 04:10:22 DEBUG : headerfilename.txt: size = 14 OK
2026/10/19 04:10:24 DEBUG : file2: md5 = f2336d77d116dda25d58f02f7f84c51c OK
2026/10/19 04:10:24 DEBUG : file2: size = 14 OK
--- PASS: TestCopyURL (12.29s)
=== RUN   TestCopyURLDownloadHeaders
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 04:10:30 DEBUG : file1: md5 = 2b70f2aeb3956d5eb2e9a95321495c8d OK
2026/10/19 04:10:30 DEBUG : file1: size = 14 OK
--- PASS: TestCopyURLDownloadHeaders (2.76s)
=== RUN   TestCopyURLToWriter
--- PASS: TestCopyURLToWriter (0.00s)
=== RUN   TestMoveFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 04:10:31 DEBUG : file1: Need to transfer - File not found at Destination
2026/10/19 04:10:34 DEBUG : sub/file2: md5 = 975342dab7b85ea27ce006924821b1f6 OK
2026/10/19 04:10:34 DEBUG : sub/file2: size = 14 OK
2026/10/19 04:10:34 INFO  : file1: Copied (new) to: sub/file2
2026/10/19 04:10:34 INFO  : file1: Deleted
2026/10/19 04:10:35 DEBUG : sub/file2: size = 14 OK
2026/10/19 04:10:35 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2026/10/19 04:10:35 DEBUG : file1: Unchanged skipping
2026/10/19 04:10:35 INFO  : file1: Deleted
2026/10/19 04:10:36 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu': don't need to copy/move sub/file2, it is already at target location
--- PASS: TestMoveFile (7.96s)
=== RUN   TestMoveFileWithIgnoreExisting
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 04:10:39 DEBUG : file1: Need to transfer - File not found at Destination
2026/10/19 04:10:41 DEBUG : file1: md5 = 4de9b0d96676410192d5afc99e705006 OK
2026/10/19 04:10:41 DEBUG : file1: size = 14 OK
2026/10/19 04:10:41 INFO  : file1: Copied (new)
2026/10/19 04:10:41 INFO  : file1: Deleted
2026/10/19 04:10:42 DEBUG : file1: Destination exists, skipping
2026/10/19 04:10:42 DEBUG : file1: Not removing source file as destination file exists and --ignore-existing is set
--- PASS: TestMoveFileWithIgnoreExisting (4.68s)
=== RUN   TestMoveFileImmutable
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 04:10:46 DEBUG : existing: md5 = 3210a82372b7a5f22dccaef1775252cc OK
2026/10/19 04:10:46 DEBUG : existing: size = 8 (Local file system at /tmp/rclone144652753)
2026/10/19 04:10:46 DEBUG : existing: size = 6 (Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu')
2026/10/19 04:10:46 DEBUG : existing: Sizes differ
2026/10/19 04:10:46 ERROR : existing: Source and destination exist but do not match: immutable file modified
--- PASS: TestMoveFileImmutable (4.45s)
=== RUN   TestCaseInsensitiveMoveFile
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
--- PASS: TestCaseInsensitiveMoveFile (0.57s)
=== RUN   TestMoveUnicodeEquivalentWithoutServerSideMove
2026/10/19 04:10:49 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-foraqaf1vaye"
2026/10/19 04:10:49 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/19 04:10:49 DEBUG : Creating backend with remote "TestDrive:crypt/u92tp3h5g0uhion5kics3n7v0ef8i68rvd2s2bc3sj4j9l39upm0"
2026/10/19 04:10:50 DEBUG : Creating backend with remote "/tmp/rclone325382368"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-foraqaf1vaye'", Local "Local file system at /tmp/rclone325382368", Modify Window "1ms"
2026/10/19 04:10:50 DEBUG : Reset feature "Move"
2026/10/19 04:10:54 DEBUG : sub/équivalent.txt: md5 = 9548c66b4c2b802722c9903f437b2507 OK
2026/10/19 04:10:54 DEBUG : sub/équivalent.txt: moving to sub/équivalent.txt-rclone-move-pugocis2
2026/10/19 04:10:56 DEBUG : sub/équivalent.txt-rclone-move-pugocis2: size = 14 OK
2026/10/19 04:10:56 INFO  : sub/équivalent.txt: Copied (server-side copy) to: sub/équivalent.txt-rclone-move-pugocis2
2026/10/19 04:10:57 INFO  : sub/équivalent.txt: Deleted
2026/10/19 04:10:57 DEBUG : sub/équivalent.txt: moving to sub/équivalent.txt
2026/10/19 04:10:59 DEBUG : sub/équivalent.txt: size = 14 OK
2026/10/19 04:10:59 INFO  : sub/équivalent.txt-rclone-move-pugocis2: Copied (server-side copy) to: sub/équivalent.txt
2026/10/19 04:10:59 INFO  : sub/équivalent.txt-rclone-move-pugocis2: Deleted
2026/10/19 04:11:00 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-foraqaf1vaye': Purge remote
--- PASS: TestMoveUnicodeEquivalentWithoutServerSideMove (11.81s)
=== RUN   TestCaseInsensitiveMoveWithoutServerSideMove
2026/10/19 04:11:00 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-xihiful0nine"
2026/10/19 04:11:00 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/19 04:11:00 DEBUG : Creating backend with remote "TestDrive:crypt/krsemqdujhmegp3mrfpq0rbrpkhb3mtiqq5t4j64mrnniqjirokg"
2026/10/19 04:11:02 DEBUG : Creating backend with remote "/tmp/rclone1036799369"
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-xihiful0nine'", Local "Local file system at /tmp/rclone1036799369", Modify Window "1ms"
    operations_test.go:1161: remote is case sensitive
2026/10/19 04:11:02 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-xihiful0nine': Purge remote
2026/10/19 04:11:02 NOTICE: purge failed: directory not found
--- SKIP: TestCaseInsensitiveMoveWithoutServerSideMove (1.59s)
=== RUN   TestCaseInsensitiveMoveFileDryRun
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
--- PASS: TestCaseInsensitiveMoveFileDryRun (0.56s)
=== RUN   TestMoveFileBackupDir
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 04:11:05 DEBUG : dst/file1: md5 = 5975b57b9324cb0d85cfd0b5230b9fb6 OK
2026/10/19 04:11:06 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-weyapag1ziqu/backup"
2026/10/19 04:11:07 DEBUG : Creating backend with remote "TestDrive:crypt/mgqrlgabkh4qu4jmvhvvahqod9cv2gjeqsmi6sk74c30p3v3f7jg/1nrff024r7pq65ecp72fc28jb0"
2026/10/19 04:11:08 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone144652753)
2026/10/19 04:11:08 DEBUG : dst/file1: size = 18 (Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu')
2026/10/19 04:11:08 DEBUG : dst/file1: Sizes differ
2026/10/19 04:11:12 INFO  : dst/file1: Moved (server-side)
2026/10/19 04:11:14 DEBUG : dst/file1: md5 = e7b4a38dbc22c4eefd15461dd4162452 OK
2026/10/19 04:11:14 DEBUG : dst/file1: size = 14 OK
2026/10/19 04:11:14 INFO  : dst/file1: Copied (new)
2026/10/19 04:11:14 INFO  : dst/file1: Deleted
--- PASS: TestMoveFileBackupDir (18.54s)
=== RUN   TestSameConfig
--- PASS: TestSameConfig (0.00s)
=== RUN   TestSame
--- PASS: TestSame (0.00s)
=== RUN   TestOverlappingFilterCheckWithoutFilter
--- PASS: TestOverlappingFilterCheckWithoutFilter (0.00s)
=== RUN   TestOverlappingFilterCheckWithFilter
--- PASS: TestOverlappingFilterCheckWithFilter (0.00s)
=== RUN   TestListFormat
--- PASS: TestListFormat (0.00s)
=== RUN   TestDirMoveMoveError
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 04:11:24 DEBUG : A/file0: md5 = 16d81685885114f153cfb1382c24bc9c OK
2026/10/19 04:11:26 DEBUG : A/file1: md5 = cff9e78d5dc743629f788c96f361eab3 OK
2026/10/19 04:11:28 DEBUG : A/file2: md5 = 87ddf047055587bb153d32f4a2e18d2b OK
2026/10/19 04:11:30 DEBUG : A/file3: md5 = 9d92a2e36ad00317748d6060ead9c580 OK
2026/10/19 04:11:32 DEBUG : A/file4: md5 = a86d0c78033c01c78d908a74d40c61e6 OK
2026/10/19 04:11:34 DEBUG : A/file5: md5 = d9a5bfe2c614c380647d88ce8e835694 OK
2026/10/19 04:11:35 DEBUG : A/file6: md5 = c2f7d4ba8ac7cc0d7e2f660f2872a7ff OK
2026/10/19 04:11:37 DEBUG : A/file7: md5 = f6945519b7dfed3ac67470e93fa82582 OK
2026/10/19 04:11:39 DEBUG : A/file8: md5 = ed15f8ca9b452356b4cf9eef33f32f22 OK
2026/10/19 04:11:41 DEBUG : A/file9: md5 = 05182d033d60327a191c29a2fda368af OK
2026/10/19 04:11:43 DEBUG : A/file10: md5 = 7b21f7e2e8000dec36dbbcdfe7d2d54c OK
2026/10/19 04:11:45 DEBUG : A/file11: md5 = 0a68394488c071fee167ad5fc597d1e9 OK
2026/10/19 04:11:47 DEBUG : A/file12: md5 = 2ccb9366e66e8f208dcb9f8e25090196 OK
2026/10/19 04:11:49 DEBUG : A/file13: md5 = 56b2a660eba8889429a890b809cb01cc OK
2026/10/19 04:11:51 DEBUG : A/file14: md5 = 0e52b80868477f46daa5a1b5eb8cbef0 OK
2026/10/19 04:11:52 DEBUG : A/file15: md5 = 1fbe9104ba4306193ff4e6fbf74138d9 OK
2026/10/19 04:11:54 DEBUG : A/file16: md5 = bb3bc7466f9b41f77e3d1a766b110789 OK
2026/10/19 04:11:56 DEBUG : A/file17: md5 = 8ceaf6204840178b08b0fb7cea451d3f OK
2026/10/19 04:11:58 DEBUG : A/file18: md5 = c87920dc8b4fa3552355a9aac8809e5f OK
2026/10/19 04:12:00 DEBUG : A/file19: md5 = 41f7dd43168224f3995dac082c977ec8 OK
    operations_test.go:1526: Skipping test on non local remote
--- SKIP: TestDirMoveMoveError (54.11s)
=== RUN   TestDirMoveContext
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 04:12:18 DEBUG : A/one: md5 = 7fbd9fa94e0acf29cc44a1d3021f217e OK
2026/10/19 04:12:20 DEBUG : A/two: md5 = 868e376fd5bd188a5407446c9f3f7e31 OK
    operations_test.go:1526: Skipping test on non local remote
--- SKIP: TestDirMoveContext (8.45s)
=== RUN   TestDirMove
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 04:12:27 DEBUG : A1/one: md5 = 8aa7964544a0fa267d864e213b7ddb49 OK
2026/10/19 04:12:28 DEBUG : A1/two: md5 = cc42c3dae4d9c7cbbddac81f92914724 OK
2026/10/19 04:12:31 DEBUG : A1/B1/three: md5 = 58851e1655a677b4fedc7aa6007e992b OK
2026/10/19 04:12:34 DEBUG : A1/B1/C1/four: md5 = 954fe0e4852637c39cdc64f9e047f817 OK
2026/10/19 04:12:37 DEBUG : A1/B1/C2/five: md5 = 3b4c0a446944628bd3ee972a55e8447c OK
2026/10/19 04:12:37 INFO  : A1/B2: Making directory
2026/10/19 04:12:38 INFO  : A1/B1/C3: Making directory
2026/10/19 04:12:51 INFO  : A2/B1/C1/four: Moved (server-side) to: A3/B1/C1/four
2026/10/19 04:12:51 INFO  : A2/B1/C2/five: Moved (server-side) to: A3/B1/C2/five
2026/10/19 04:12:51 INFO  : A2/one: Moved (server-side) to: A3/one
2026/10/19 04:12:52 INFO  : A2/two: Moved (server-side) to: A3/two
2026/10/19 04:12:52 INFO  : A2/B1/three: Moved (server-side) to: A3/B1/three
2026/10/19 04:12:59 INFO  : Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu': Can't DirMove - falling back to file moves: can't move directory - incompatible remotes
2026/10/19 04:13:07 INFO  : A3/B1/C2/five: Moved (server-side) to: A4/B1/C2/five
2026/10/19 04:13:07 INFO  : A3/B1/C1/four: Moved (server-side) to: A4/B1/C1/four
2026/10/19 04:13:07 INFO  : A3/one: Moved (server-side) to: A4/one
2026/10/19 04:13:08 INFO  : A3/two: Moved (server-side) to: A4/two
2026/10/19 04:13:08 INFO  : A3/B1/three: Moved (server-side) to: A4/B1/three
--- PASS: TestDirMove (64.94s)
=== RUN   TestGetFsInfo
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 04:13:29 DEBUG : Config file has changed externally - reloading
--- PASS: TestGetFsInfo (0.59s)
=== RUN   TestRcat
=== RUN   TestRcat/withChecksum=false,ignoreChecksum=false
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 04:13:29 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu': File to upload is small (34 bytes), uploading instead of streaming
2026/10/19 04:13:31 DEBUG : no_checksum_small_file_from_pipe: md5 = aca40e799ad13f7990189b28f760d019 OK
2026/10/19 04:13:31 DEBUG : no_checksum_small_file_from_pipe: size = 34 OK
2026/10/19 04:13:31 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only
2026/10/19 04:13:31 DEBUG : no_checksum_small_file_from_pipe: Size of src and dst objects identical
2026/10/19 04:13:32 DEBUG : qc9k4uauqa9ls6a2r6lo8jf4b1m7r0510pkurois1cc70egbs4h0: Sending chunk 0 length 102465
2026/10/19 04:13:34 DEBUG : no_checksum_big_file_from_pipe: md5 = 969ec2ac4dca298c51cc60fd02334fd0 OK
2026/10/19 04:13:34 DEBUG : no_checksum_big_file_from_pipe: size = 102401 OK
2026/10/19 04:13:34 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-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 04:13:36 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu': File to upload is small (34 bytes), uploading instead of streaming
2026/10/19 04:13:38 DEBUG : with_checksum_small_file_from_pipe: md5 = 4125ef41db5608a0665b2747d94f11e8 OK
2026/10/19 04:13:38 DEBUG : with_checksum_small_file_from_pipe: size = 34 OK
2026/10/19 04:13:38 DEBUG : with_checksum_small_file_from_pipe: Size of src and dst objects identical
2026/10/19 04:13:39 DEBUG : e74jo8a7lr1aimg2u78fct67gnuetc1qujiofaci0c8fnq7rjt1d4i296pcklqhgdihs03etvgdi4: Sending chunk 0 length 102465
2026/10/19 04:13:41 DEBUG : with_checksum_big_file_from_pipe: md5 = c733c0e0955a3b81f809ec3129889394 OK
2026/10/19 04:13:41 DEBUG : with_checksum_big_file_from_pipe: size = 102401 OK
2026/10/19 04:13:41 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-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 04:13:43 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu': File to upload is small (34 bytes), uploading instead of streaming
2026/10/19 04:13:45 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK
2026/10/19 04:13:45 DEBUG : ignore_checksum_small_file_from_pipe: Size and modification time the same (differ by -999.999µs, within tolerance 1ms)
2026/10/19 04:13:46 DEBUG : 1963rn6olajfbjn4nbqi9nacu7bk879vpf0d2bg8r0ndcqqsfntob0urt056p0qujf574rqnb5f62: Sending chunk 0 length 102465
2026/10/19 04:13:47 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK
2026/10/19 04:13:47 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-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 04:13:50 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu': File to upload is small (34 bytes), uploading instead of streaming
2026/10/19 04:13:52 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK
2026/10/19 04:13:52 DEBUG : ignore_checksum_small_file_from_pipe: Size of src and dst objects identical
2026/10/19 04:13:52 DEBUG : 1963rn6olajfbjn4nbqi9nacu7bk879vpf0d2bg8r0ndcqqsfntob0urt056p0qujf574rqnb5f62: Sending chunk 0 length 102465
2026/10/19 04:13:54 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK
2026/10/19 04:13:54 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical
--- PASS: TestRcat (26.90s)
    --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (6.78s)
    --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (7.21s)
    --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (6.17s)
    --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (6.74s)
=== RUN   TestRcatMetadata
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
=== RUN   TestRcatMetadata/Normal
2026/10/19 04:13:56 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu': File to upload is small (48 bytes), uploading instead of streaming
2026/10/19 04:13:58 DEBUG : rcat_metadata: md5 = a9dec1cdbcbdc81e8f4f70ae30e06cee OK
2026/10/19 04:13:58 DEBUG : rcat_metadata: size = 48 OK
2026/10/19 04:13:58 DEBUG : rcat_metadata: Size of src and dst objects identical
=== RUN   TestRcatMetadata/ViaDisk
2026/10/19 04:14:01 DEBUG : rijssoobhbn85c5p1imhg55m6frank1nlplla6hkdjlqmqb2471g: Sending chunk 0 length 111
2026/10/19 04:14:02 DEBUG : rcat_metadata_uploadcutoff0: md5 = d1a89a9a1bf4ca1d80a4840218b4c47d OK
2026/10/19 04:14:02 DEBUG : rcat_metadata_uploadcutoff0: size = 63 OK
2026/10/19 04:14:02 DEBUG : rcat_metadata_uploadcutoff0: Size of src and dst objects identical
--- PASS: TestRcatMetadata (7.73s)
    --- PASS: TestRcatMetadata/Normal (3.42s)
    --- PASS: TestRcatMetadata/ViaDisk (3.75s)
=== RUN   TestRcatSize
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 04:14:06 DEBUG : potato1: md5 = eba7dc692be0aaa0da37a19d8ed2b588 OK
2026/10/19 04:14:06 DEBUG : potato1: size = 60 OK
2026/10/19 04:14:06 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu': File to upload is small (60 bytes), uploading instead of streaming
2026/10/19 04:14:07 DEBUG : potato2: md5 = 6424d5964131189f568f23558e5f64a8 OK
2026/10/19 04:14:07 DEBUG : potato2: size = 60 OK
2026/10/19 04:14:07 DEBUG : potato2: Size of src and dst objects identical
--- PASS: TestRcatSize (5.65s)
=== RUN   TestRcatSizeShortEOF
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 04:14:11 DEBUG : potato1: md5 = b6914f5d5be2e8f2144d17c53d6834d0 OK
2026/10/19 04:14:11 DEBUG : potato1: size = 120 (Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu')
2026/10/19 04:14:11 DEBUG : potato1: size = 60 (Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu')
2026/10/19 04:14:11 ERROR : potato1: corrupted on transfer: sizes differ src 120 vs dst(Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu') 60
2026/10/19 04:14:11 INFO  : potato1: Removing failed copy
--- PASS: TestRcatSizeShortEOF (3.02s)
=== RUN   TestRcatSizeMetadata
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 04:14:14 DEBUG : potato1: md5 = cc1d099f656dc09a209e91aced6b9bc7 OK
2026/10/19 04:14:14 DEBUG : potato1: size = 60 OK
2026/10/19 04:14:14 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu': File to upload is small (60 bytes), uploading instead of streaming
2026/10/19 04:14:16 DEBUG : potato2: md5 = 19f1d82386737d9b763150563b7828b4 OK
2026/10/19 04:14:16 DEBUG : potato2: size = 60 OK
2026/10/19 04:14:16 DEBUG : potato2: Size of src and dst objects identical
--- PASS: TestRcatSizeMetadata (6.67s)
=== RUN   TestRcatSizeUploadHeaders
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 04:14:21 DEBUG : potato1: md5 = 361075fa014f3163f51a3df39dfcedf1 OK
2026/10/19 04:14:21 DEBUG : potato1: size = 60 OK
--- PASS: TestRcatSizeUploadHeaders (3.24s)
=== RUN   TestRcatSizeChecksum
=== RUN   TestRcatSizeChecksum/Corrupted
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
    operations_test.go:2011: Skipping as destination doesn't support hashes
=== RUN   TestRcatSizeChecksum/SizeDiffers
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 04:14:25 DEBUG : potato4: md5 = 2e55c8ef86b451af771da165e6765329 OK
2026/10/19 04:14:25 DEBUG : potato4: size = 60 (Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu')
2026/10/19 04:14:25 DEBUG : potato4: size = 59 (Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu')
2026/10/19 04:14:25 ERROR : potato4: corrupted on transfer: sizes differ src 60 vs dst(Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu') 59
2026/10/19 04:14:25 INFO  : potato4: Removing failed copy
=== RUN   TestRcatSizeChecksum/IgnoreChecksum
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 04:14:28 DEBUG : potato2: size = 60 OK
=== RUN   TestRcatSizeChecksum/NoHashes
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 04:14:31 DEBUG : potato3: md5 = f50618fe02c115e4493254881544ba51 OK
2026/10/19 04:14:31 DEBUG : potato3: size = 60 OK
--- PASS: TestRcatSizeChecksum (10.43s)
    --- SKIP: TestRcatSizeChecksum/Corrupted (0.55s)
    --- PASS: TestRcatSizeChecksum/SizeDiffers (3.52s)
    --- PASS: TestRcatSizeChecksum/IgnoreChecksum (3.26s)
    --- PASS: TestRcatSizeChecksum/NoHashes (3.09s)
=== RUN   TestTouchDir
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 04:14:35 DEBUG : potato2: md5 = f7068c667f7e1b29c338483c2c9c8726 OK
2026/10/19 04:14:37 DEBUG : empty space: md5 = 505fead9004e8deab65f62558f03d2b2 OK
2026/10/19 04:14:40 DEBUG : sub dir/potato3: md5 = ea9732ab48c6a66f56ea7a00aa99fcd7 OK
2026/10/19 04:14:41 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu': Touching "sub dir/potato3"
2026/10/19 04:14:41 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu': Touching "empty space"
2026/10/19 04:14:41 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu': Touching "potato2"
--- PASS: TestTouchDir (14.81s)
=== RUN   TestMkdirMetadata
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 04:14:48 DEBUG : dir with metadata: Making directory with metadata
2026/10/19 04:14:49 INFO  : dir with metadata: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z)
--- PASS: TestMkdirMetadata (3.30s)
=== RUN   TestMkdirModTime
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 04:14:51 DEBUG : directory with modtime: Making directory with metadata
2026/10/19 04:14:52 INFO  : directory with modtime: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2026/10/19 04:14:52 NOTICE: dry run directory with modtime: Skipped make directory as --dry-run is set
--- PASS: TestMkdirModTime (3.39s)
=== RUN   TestCopyDirMetadata
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 04:14:54 DEBUG : dir with metadata to be copied: Making directory with metadata
2026/10/19 04:14:54 INFO  : dir with metadata to be copied: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z)
2026/10/19 04:14:55 DEBUG : Google drive root 'crypt/mgqrlgabkh4qu4jmvhvvahqod9cv2gjeqsmi6sk74c30p3v3f7jg': Skipping btime metadata as can't update it on an existing file: 2026-10-19T04:14:54.810662826Z
2026/10/19 04:14:55 INFO  : non existent directory: Updated directory metadata
2026/10/19 04:14:58 DEBUG : Google drive root 'crypt/mgqrlgabkh4qu4jmvhvvahqod9cv2gjeqsmi6sk74c30p3v3f7jg': Skipping btime metadata as can't update it on an existing file: 2026-10-19T04:14:54.810662826Z
2026/10/19 04:14:59 INFO  : existing directory: Updated directory metadata
--- PASS: TestCopyDirMetadata (6.96s)
=== RUN   TestSetDirModTime
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 04:15:01 DEBUG : set modtime on non existent directory: Skipping set directory modification time as --no-update-dir-modtime is set
2026/10/19 04:15:03 INFO  : set modtime on existing directory: Set directory modification time (using SetModTime)
2026/10/19 04:15:05 INFO  : set modtime on existing directory: Set directory modification time (using DirSetModTime)
--- PASS: TestSetDirModTime (5.59s)
=== RUN   TestDirsEqual
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 04:15:07 DEBUG : dir with metadata to be copied: Making directory with metadata
2026/10/19 04:15:07 INFO  : dir with metadata to be copied: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z)
2026/10/19 04:15:07 DEBUG : dst: Making directory with metadata
2026/10/19 04:15:08 INFO  : dst: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z)
2026/10/19 04:15:08 DEBUG : dst: Directory modification time the same (differ by -999.999µs, within tolerance 1ms)
2026/10/19 04:15:08 INFO  : dst: Set directory modification time (using SetModTime)
2026/10/19 04:15:09 INFO  : dst: Set directory modification time (using SetModTime)
2026/10/19 04:15:09 DEBUG : dst: Directory modification time the same (differ by 1ns, within tolerance 1ms)
2026/10/19 04:15:10 INFO  : dst: Set directory modification time (using SetModTime)
2026/10/19 04:15:10 DEBUG : dst: Destination directory is newer than source, skipping
--- PASS: TestDirsEqual (4.59s)
=== RUN   TestRemoveExisting
    run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu'", Local "Local file system at /tmp/rclone144652753", Modify Window "1ms"
2026/10/19 04:15:14 DEBUG : sub dir/test remove existing: md5 = 9fb6e72271c7f92b57febbb18a5ca74a OK
2026/10/19 04:15:16 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: md5 = cf48e99f467e1c1685300752aaf28baa OK
2026/10/19 04:15:19 DEBUG : sub dir/test remove existing: TEST: renaming existing object to "sub dir/test remove existing.fonahoh9" before starting
2026/10/19 04:15:20 DEBUG : sub dir/test remove existing.fonahoh9: TEST: removing renamed existing file after operation
2026/10/19 04:15:22 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: TEST: renaming existing object to "sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.berisad7" before starting
2026/10/19 04:15:24 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.berisad7: TEST: renaming existing back after failed operation
2026/10/19 04:15:25 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: TEST: renaming existing object to "sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.sefupuh1" before starting
2026/10/19 04:15:27 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.sefupuh1: TEST: removing renamed existing file after operation
--- PASS: TestRemoveExisting (18.18s)
=== RUN   TestRcatInputFailurePreservesDestination
2026/10/19 04:15:30 DEBUG : Creating backend with remote "/tmp/TestRcatInputFailurePreservesDestination1128477731/001"
--- PASS: TestRcatInputFailurePreservesDestination (0.00s)
=== RUN   TestRcAbout
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcAbout (0.00s)
=== RUN   TestRcCleanup
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcCleanup (0.00s)
=== RUN   TestRcCopyfile
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcCopyfile (0.00s)
=== RUN   TestRcCopyurl
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcCopyurl (0.00s)
=== RUN   TestRcDelete
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcDelete (0.00s)
=== RUN   TestRcDeletefile
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcDeletefile (0.00s)
=== RUN   TestRcDeletefileBackupDir
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcDeletefileBackupDir (0.00s)
=== RUN   TestRcList
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcList (0.00s)
=== RUN   TestRcStat
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcStat (0.00s)
=== RUN   TestRcSetTier
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcSetTier (0.00s)
=== RUN   TestRcSetTierFile
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcSetTierFile (0.00s)
=== RUN   TestRcMkdir
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcMkdir (0.00s)
=== RUN   TestRcMovefile
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcMovefile (0.00s)
=== RUN   TestRcPurge
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcPurge (0.00s)
=== RUN   TestRcRmdir
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcRmdir (0.00s)
=== RUN   TestRcRmdirs
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcRmdirs (0.00s)
=== RUN   TestRcSize
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcSize (0.00s)
=== RUN   TestRcPublicLink
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcPublicLink (0.00s)
=== RUN   TestRcFsInfo
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcFsInfo (0.00s)
=== RUN   TestUploadFile
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestUploadFile (0.00s)
=== RUN   TestRcCommand
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcCommand (0.00s)
=== RUN   TestRcDu
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcDu (0.00s)
=== RUN   TestRcCheck
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcCheck (0.00s)
=== RUN   TestRcHashsum
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcHashsum (0.00s)
=== RUN   TestRcHashsumSingleFile
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcHashsumSingleFile (0.00s)
=== RUN   TestRcHashsumFile
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcHashsumFile (0.00s)
=== RUN   TestRcGetFile
    rc_test.go:31: Skipping test on non local remote
--- SKIP: TestRcGetFile (0.00s)
PASS
2026/10/19 04:15:30 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-weyapag1ziqu': Purge remote
"./operations.test -test.v -test.timeout 1h0m0s -remote TestCryptDrive: -verbose" - Finished OK in 28m33.225724085s (try 1/5)