"./operations.test -test.v -test.timeout 1h0m0s -remote TestCryptDrive: -verbose" - Starting (try 1/5) 2026/11/04 02:45:01 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-birusev6cano" 2026/11/04 02:45:01 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/11/04 02:45:01 DEBUG : Creating backend with remote "TestDrive:crypt/ruedvu180ro2fcktkdj2gabtvu3sr8pt7hsglppuerfam48l4v10" 2026/11/04 02:45:02 DEBUG : Creating backend with remote "/tmp/rclone974098245" === 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-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" multithread_test.go:120: multithread writing not supported --- SKIP: TestMultithreadCopy (0.28s) === RUN TestMultithreadCopyAbort run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" multithread_test.go:120: multithread writing not supported --- SKIP: TestMultithreadCopyAbort (0.23s) === RUN TestMultithreadCopyWriterAtErrors === RUN TestMultithreadCopyWriterAtErrors/open/fatal=false 2026/11/04 02:45:02 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt === RUN TestMultithreadCopyWriterAtErrors/open/fatal=true 2026/11/04 02:45:02 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt === RUN TestMultithreadCopyWriterAtErrors/write/fatal=false 2026/11/04 02:45:02 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2026/11/04 02:45:02 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams 2026/11/04 02:45:02 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting 2026/11/04 02:45:02 DEBUG : file.txt: writing chunk 1 2026/11/04 02:45:02 DEBUG : file.txt: multi-thread copy: chunk 2/4 failed: multi-thread copy: failed to write chunk: disk full 2026/11/04 02:45:02 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting 2026/11/04 02:45:02 DEBUG : file.txt: writing chunk 2 2026/11/04 02:45:02 DEBUG : file.txt: multi-thread copy: chunk 3/4 failed: multi-thread copy: failed to write chunk: disk full 2026/11/04 02:45:02 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting 2026/11/04 02:45:02 DEBUG : file.txt: writing chunk 0 2026/11/04 02:45:02 DEBUG : file.txt: multi-thread copy: chunk 1/4 failed: multi-thread copy: failed to write chunk: disk full 2026/11/04 02:45:02 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit 2026/11/04 02:45:02 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/11/04 02:45:02 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2026/11/04 02:45:02 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams 2026/11/04 02:45:02 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting 2026/11/04 02:45:02 DEBUG : file.txt: writing chunk 1 2026/11/04 02:45:02 DEBUG : file.txt: multi-thread copy: chunk 2/4 failed: multi-thread copy: failed to write chunk: disk full 2026/11/04 02:45:02 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting 2026/11/04 02:45:02 DEBUG : file.txt: writing chunk 2 2026/11/04 02:45:02 DEBUG : file.txt: multi-thread copy: chunk 3/4 failed: multi-thread copy: failed to write chunk: disk full 2026/11/04 02:45:02 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting 2026/11/04 02:45:02 DEBUG : file.txt: writing chunk 0 2026/11/04 02:45:02 DEBUG : file.txt: multi-thread copy: chunk 1/4 failed: multi-thread copy: failed to write chunk: disk full 2026/11/04 02:45:02 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit 2026/11/04 02:45:02 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/11/04 02:45:02 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2026/11/04 02:45:02 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams 2026/11/04 02:45:02 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting 2026/11/04 02:45:02 DEBUG : file.txt: writing chunk 1 2026/11/04 02:45:02 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 finished 2026/11/04 02:45:02 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting 2026/11/04 02:45:02 DEBUG : file.txt: writing chunk 2 2026/11/04 02:45:02 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 finished 2026/11/04 02:45:02 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 starting 2026/11/04 02:45:02 DEBUG : file.txt: writing chunk 3 2026/11/04 02:45:02 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 finished 2026/11/04 02:45:02 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting 2026/11/04 02:45:02 DEBUG : file.txt: writing chunk 0 2026/11/04 02:45:02 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 finished 2026/11/04 02:45:02 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit 2026/11/04 02:45:02 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/11/04 02:45:02 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2026/11/04 02:45:02 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams 2026/11/04 02:45:02 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting 2026/11/04 02:45:02 DEBUG : file.txt: writing chunk 1 2026/11/04 02:45:02 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 finished 2026/11/04 02:45:02 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting 2026/11/04 02:45:02 DEBUG : file.txt: writing chunk 2 2026/11/04 02:45:02 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 finished 2026/11/04 02:45:02 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 starting 2026/11/04 02:45:02 DEBUG : file.txt: writing chunk 3 2026/11/04 02:45:02 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 finished 2026/11/04 02:45:02 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting 2026/11/04 02:45:02 DEBUG : file.txt: writing chunk 0 2026/11/04 02:45:02 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 finished 2026/11/04 02:45:02 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit 2026/11/04 02:45:02 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/11/04 02:45:02 DEBUG : a: size = 0 OK 2026/11/04 02:45:02 DEBUG : a: size = 1 (memory) 2026/11/04 02:45:02 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/11/04 02:45:02 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/ErrorAtStart === RUN TestReOpen/Normal/WithErrors 2026/11/04 02:45:02 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/11/04 02:45:02 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/11/04 02:45:02 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/Normal/TooManyErrors 2026/11/04 02:45:02 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/11/04 02:45:02 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/11/04 02:45:02 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/11/04 02:45:02 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/Normal/ReadAt 2026/11/04 02:45:02 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/11/04 02:45:02 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/11/04 02:45:02 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/Normal/Seek 2026/11/04 02:45:02 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/11/04 02:45:02 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/11/04 02:45:02 DEBUG : potato: Seek from 5 to 2 === RUN TestReOpen/Normal/AccountRead === RUN TestReOpen/Normal/AccountReadDelay 2026/11/04 02:45:02 DEBUG : potato: Seek from 10 to 0 2026/11/04 02:45:02 DEBUG : potato: Seek from 10 to 0 2026/11/04 02:45:02 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/AccountReadError === RUN TestReOpen/WithRangeOption === RUN TestReOpen/WithRangeOption/Basics 2026/11/04 02:45:02 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/ErrorAtStart === RUN TestReOpen/WithRangeOption/WithErrors 2026/11/04 02:45:02 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/11/04 02:45:02 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/11/04 02:45:02 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithRangeOption/TooManyErrors 2026/11/04 02:45:02 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/11/04 02:45:02 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/11/04 02:45:02 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/11/04 02:45:02 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithRangeOption/ReadAt 2026/11/04 02:45:02 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/11/04 02:45:02 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/11/04 02:45:02 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/WithRangeOption/Seek 2026/11/04 02:45:02 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/11/04 02:45:02 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/11/04 02:45:02 DEBUG : potato: Seek from 5 to 2 2026/11/04 02:45:02 DEBUG : potato: Seek from 7 to 4 === RUN TestReOpen/WithRangeOption/AccountRead === RUN TestReOpen/WithRangeOption/AccountReadDelay 2026/11/04 02:45:02 DEBUG : potato: Seek from 7 to 0 2026/11/04 02:45:02 DEBUG : potato: Seek from 7 to 0 2026/11/04 02:45:02 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/AccountReadError === RUN TestReOpen/WithSeekOption === RUN TestReOpen/WithSeekOption/Basics 2026/11/04 02:45:02 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/ErrorAtStart === RUN TestReOpen/WithSeekOption/WithErrors 2026/11/04 02:45:02 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/11/04 02:45:02 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/11/04 02:45:02 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithSeekOption/TooManyErrors 2026/11/04 02:45:02 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/11/04 02:45:02 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/11/04 02:45:02 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/11/04 02:45:02 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithSeekOption/ReadAt 2026/11/04 02:45:02 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/11/04 02:45:02 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/11/04 02:45:02 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/WithSeekOption/Seek 2026/11/04 02:45:02 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/11/04 02:45:02 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/11/04 02:45:02 DEBUG : potato: Seek from 5 to 2 2026/11/04 02:45:02 DEBUG : potato: Seek from 7 to 5 === RUN TestReOpen/WithSeekOption/AccountRead === RUN TestReOpen/WithSeekOption/AccountReadDelay 2026/11/04 02:45:02 DEBUG : potato: Seek from 8 to 0 2026/11/04 02:45:02 DEBUG : potato: Seek from 8 to 0 2026/11/04 02:45:02 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/AccountReadError === RUN TestReOpen/UnknownSize === RUN TestReOpen/UnknownSize/Basics 2026/11/04 02:45:02 DEBUG : potato: Seek from 9 to 0 === RUN TestReOpen/UnknownSize/ErrorAtStart === RUN TestReOpen/UnknownSize/WithErrors 2026/11/04 02:45:02 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/11/04 02:45:02 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/11/04 02:45:02 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/UnknownSize/TooManyErrors 2026/11/04 02:45:02 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/11/04 02:45:02 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/11/04 02:45:02 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/11/04 02:45:02 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/UnknownSize/ReadAt 2026/11/04 02:45:02 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/11/04 02:45:02 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/11/04 02:45:02 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/UnknownSize/Seek 2026/11/04 02:45:02 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/11/04 02:45:02 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/11/04 02:45:02 DEBUG : potato: Seek from 5 to 2 2026/11/04 02:45:02 DEBUG : potato: Seek from 7 to 6 === RUN TestReOpen/UnknownSize/AccountRead === RUN TestReOpen/UnknownSize/AccountReadDelay 2026/11/04 02:45:02 DEBUG : potato: Seek from 9 to 0 2026/11/04 02:45:02 DEBUG : potato: Seek from 9 to 0 2026/11/04 02:45:02 DEBUG : potato: Seek from 9 to 0 === RUN TestReOpen/UnknownSize/AccountReadError --- PASS: TestReOpen (0.01s) --- 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-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 02:45:05 DEBUG : rutabaga: md5 = 630ed64d462f761c813ff8ddfeefd333 OK === RUN TestCheck/1 === RUN TestCheck/2 2026/11/04 02:45:07 DEBUG : empty space: md5 = 9ecfc9aad8c02d351599e2f188887bd3 OK === RUN TestCheck/3 2026/11/04 02:45:09 DEBUG : potato2: md5 = 12a895332597a6bd844209ceaac45ac6 OK === RUN TestCheck/4 === RUN TestCheck/5 2026/11/04 02:45:11 DEBUG : remotepotato: md5 = 77a9c21571de91a620451fa4f396a0bc OK === RUN TestCheck/6 === RUN TestCheck/7 --- PASS: TestCheck (11.73s) --- PASS: TestCheck/1 (0.35s) --- PASS: TestCheck/2 (0.25s) --- PASS: TestCheck/3 (0.22s) --- PASS: TestCheck/4 (0.25s) --- PASS: TestCheck/5 (0.29s) --- PASS: TestCheck/6 (0.21s) --- PASS: TestCheck/7 (0.25s) === RUN TestCheckFsError 2026/11/04 02:45:14 DEBUG : Creating backend with remote "nonexistent" 2026/11/04 02:45:14 DEBUG : Creating backend with remote "nonexistent" 2026/11/04 02:45:14 DEBUG : Local file system at nonexistent: Waiting for checks to finish 2026/11/04 02:45:14 ERROR : Local file system at nonexistent: error reading source root directory: directory not found 2026/11/04 02:45:14 NOTICE: Local file system at nonexistent: 0 differences found 2026/11/04 02:45:14 NOTICE: Local file system at nonexistent: 2 errors while checking --- PASS: TestCheckFsError (0.00s) === RUN TestCheckDownload run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 02:45:16 DEBUG : rutabaga: md5 = 43710f5239e664839b108dc4dcc80c73 OK === RUN TestCheckDownload/1 === RUN TestCheckDownload/2 2026/11/04 02:45:19 DEBUG : empty space: md5 = a20278265cb549e037c7b2bed08732e7 OK === RUN TestCheckDownload/3 2026/11/04 02:45:22 DEBUG : potato2: md5 = 8b84555e6e9a34ddf3e609df0ecf3ca1 OK === RUN TestCheckDownload/4 === RUN TestCheckDownload/5 2026/11/04 02:45:25 DEBUG : remotepotato: md5 = d9bafc72d1b32eba1ce33f22e5142d91 OK === RUN TestCheckDownload/6 === RUN TestCheckDownload/7 --- PASS: TestCheckDownload (15.70s) --- PASS: TestCheckDownload/1 (0.94s) --- PASS: TestCheckDownload/2 (0.74s) --- PASS: TestCheckDownload/3 (0.99s) --- PASS: TestCheckDownload/4 (0.92s) --- PASS: TestCheckDownload/5 (0.98s) --- PASS: TestCheckDownload/6 (0.83s) --- PASS: TestCheckDownload/7 (0.72s) === RUN TestCheckSizeOnly run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 02:45:31 DEBUG : rutabaga: md5 = 7298ddd4711fd8fb8912157106abff32 OK === RUN TestCheckSizeOnly/1 === RUN TestCheckSizeOnly/2 2026/11/04 02:45:33 DEBUG : empty space: md5 = 8d85ea154e5c0c704f4381fe00c7a373 OK === RUN TestCheckSizeOnly/3 2026/11/04 02:45:35 DEBUG : potato2: md5 = 0a04488f651abac7b10ef64f9608701f OK === RUN TestCheckSizeOnly/4 === RUN TestCheckSizeOnly/5 2026/11/04 02:45:37 DEBUG : remotepotato: md5 = 4faa10659603c575646cedc39080c031 OK === RUN TestCheckSizeOnly/6 === RUN TestCheckSizeOnly/7 --- PASS: TestCheckSizeOnly (10.80s) --- PASS: TestCheckSizeOnly/1 (0.22s) --- PASS: TestCheckSizeOnly/2 (0.25s) --- PASS: TestCheckSizeOnly/3 (0.25s) --- PASS: TestCheckSizeOnly/4 (0.22s) --- PASS: TestCheckSizeOnly/5 (0.23s) --- PASS: TestCheckSizeOnly/6 (0.24s) --- PASS: TestCheckSizeOnly/7 (0.21s) === RUN TestCheckEqualReaders --- PASS: TestCheckEqualReaders (0.00s) === RUN TestParseSumFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 02:45:42 DEBUG : test.sum: md5 = b4dbf2fc37d51be55f285a7f3b67f1f0 OK 2026/11/04 02:45:43 NOTICE: test.sum: improperly formatted checksum line 4 2026/11/04 02:45:43 NOTICE: test.sum: improperly formatted checksum line 5 2026/11/04 02:45:43 NOTICE: test.sum: improperly formatted checksum line 6 2026/11/04 02:45:43 NOTICE: test.sum: 2 warning(s) suppressed... 2026/11/04 02:45:44 DEBUG : test.sum: md5 = e582b7695c824820236843708061d579 OK 2026/11/04 02:45:45 NOTICE: test.sum: improperly formatted checksum line 4 2026/11/04 02:45:45 NOTICE: test.sum: improperly formatted checksum line 5 2026/11/04 02:45:45 NOTICE: test.sum: improperly formatted checksum line 6 2026/11/04 02:45:45 NOTICE: test.sum: 2 warning(s) suppressed... --- PASS: TestParseSumFile (5.50s) === RUN TestCheckSum run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 02:45:46 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-birusev6cano/data" 2026/11/04 02:45:46 DEBUG : Creating backend with remote "TestDrive:crypt/ruedvu180ro2fcktkdj2gabtvu3sr8pt7hsglppuerfam48l4v10/vjrnln8ratgmqakfosrqe8espk" check_test.go:354: Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano/data' lacks md5, skipping --- SKIP: TestCheckSum (1.84s) === RUN TestCheckSumDownload run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 02:45:48 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-birusev6cano/data" 2026/11/04 02:45:48 DEBUG : Creating backend with remote "TestDrive:crypt/ruedvu180ro2fcktkdj2gabtvu3sr8pt7hsglppuerfam48l4v10/vjrnln8ratgmqakfosrqe8espk" 2026/11/04 02:45:52 DEBUG : data/banana: md5 = a7d2790528ec543e004c8358419c17e1 OK 2026/11/04 02:45:54 DEBUG : test.sum: md5 = fad8b073f9e6e640584efdd18da53b31 OK === RUN TestCheckSumDownload/subtest1 2026/11/04 02:45:58 DEBUG : data/potato: md5 = 91ba89d61c5fea267622836aa6a91ff8 OK 2026/11/04 02:45:59 DEBUG : test.sum: md5 = ba839ca282fea2c7ad3a12d8ccf5bb7a OK === RUN TestCheckSumDownload/subtest2 2026/11/04 02:46:03 DEBUG : test.sum: md5 = d04516212dcd104f6cb806b5627d088a OK === RUN TestCheckSumDownload/subtest3 2026/11/04 02:46:06 DEBUG : test.sum: md5 = c4ca66e49dabaa8720611a385b4436ff OK === RUN TestCheckSumDownload/subtest4 2026/11/04 02:46:11 DEBUG : test.sum: md5 = e35814fa9886d0316b335cc6c0876ed7 OK === RUN TestCheckSumDownload/subtest5 2026/11/04 02:46:15 DEBUG : test.sum: md5 = 8b4c0d82d8dc30b1d54eae4833c9ec8f OK === RUN TestCheckSumDownload/subtest6 2026/11/04 02:46:19 DEBUG : data/banana: md5 = e8b74543b3731fc4fa1ee27f2140be8e OK 2026/11/04 02:46:20 DEBUG : data/potato: md5 = 9117665ceb9c8ab1d6d30d7a1fd114c7 OK 2026/11/04 02:46:21 DEBUG : test.sum: md5 = d9d56c617145c6d2cdbb4215bcc578af OK === RUN TestCheckSumDownload/subtest7 --- PASS: TestCheckSumDownload (38.50s) --- PASS: TestCheckSumDownload/subtest1 (1.99s) --- PASS: TestCheckSumDownload/subtest2 (1.83s) --- PASS: TestCheckSumDownload/subtest3 (1.83s) --- PASS: TestCheckSumDownload/subtest4 (2.87s) --- PASS: TestCheckSumDownload/subtest5 (1.68s) --- PASS: TestCheckSumDownload/subtest6 (1.84s) --- PASS: TestCheckSumDownload/subtest7 (1.80s) === RUN TestCheckSumConcurrency 2026/11/04 02:46:26 DEBUG : file0: md5 = 022eb0c0085c731f335df970fafa7446 OK 2026/11/04 02:46:26 DEBUG : file2: md5 = a58f09ae3db8ae4b943fdbaace096072 OK 2026/11/04 02:46:26 DEBUG : file1: md5 = 17fbabbccbdea0e6be9eb64c8c6fea41 OK 2026/11/04 02:46:26 DEBUG : file3: md5 = e0639b17e0744aca9a3ba29c049e59ee OK 2026/11/04 02:46:26 NOTICE: Mock file system at checkSum: 0 differences found 2026/11/04 02:46:26 NOTICE: Mock file system at checkSum: 4 matching files --- PASS: TestCheckSumConcurrency (0.00s) === RUN TestCheckSumDownloadConcurrency 2026/11/04 02:46:26 DEBUG : file0: md5 = 022eb0c0085c731f335df970fafa7446 OK 2026/11/04 02:46:26 DEBUG : file2: md5 = a58f09ae3db8ae4b943fdbaace096072 OK 2026/11/04 02:46:26 DEBUG : file1: md5 = 17fbabbccbdea0e6be9eb64c8c6fea41 OK 2026/11/04 02:46:26 DEBUG : file3: md5 = e0639b17e0744aca9a3ba29c049e59ee OK 2026/11/04 02:46:26 NOTICE: Mock file system at checkSum: 0 differences found 2026/11/04 02:46:26 NOTICE: Mock file system at checkSum: 4 matching files --- PASS: TestCheckSumDownloadConcurrency (0.00s) === RUN TestApplyTransforms 2026/11/04 02:46:26 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-lifimoc8nagu" 2026/11/04 02:46:26 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/11/04 02:46:26 DEBUG : Creating backend with remote "TestDrive:crypt/m7jf513t1ajekrghknpop7qkr0ngr6kdbn4bg5vacv9d3tlig45g" 2026/11/04 02:46:27 DEBUG : Creating backend with remote "/tmp/rclone2328829260" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-lifimoc8nagu'", Local "Local file system at /tmp/rclone2328829260", Modify Window "1ms" 2026/11/04 02:46:30 DEBUG : hello, world!: md5 = a9ea97d2e577c847154fee28f439d3f9 OK upper checkfile vs. lower remote (without normalization) 2026/11/04 02:46:30 ERROR : hello, world!: sum not found 2026/11/04 02:46:30 ERROR : HELLO, WORLD!: file not in Encrypted drive 'TestCryptDrive:rclone-test-lifimoc8nagu' 2026/11/04 02:46:30 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-lifimoc8nagu': 1 files missing 2026/11/04 02:46:30 NOTICE: 1 hashes missing 2026/11/04 02:46:30 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-lifimoc8nagu': 1 differences found 2026/11/04 02:46:30 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-lifimoc8nagu': 2 errors while checking upper checkfile vs. lower remote (with normalization) 2026/11/04 02:46:31 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/11/04 02:46:31 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-lifimoc8nagu': 0 differences found 2026/11/04 02:46:31 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-lifimoc8nagu': 1 matching files 2026/11/04 02:46:31 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-fehucod4zoki" 2026/11/04 02:46:31 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/11/04 02:46:31 DEBUG : Creating backend with remote "TestDrive:crypt/2sq0ih5bal8qe6j9brs5shslteudbknbk73uh75trk2edeh10450" 2026/11/04 02:46:32 DEBUG : Creating backend with remote "/tmp/rclone3905311339" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fehucod4zoki'", Local "Local file system at /tmp/rclone3905311339", Modify Window "1ms" 2026/11/04 02:46:34 DEBUG : HELLO, WORLD!: md5 = e4e66e39ed30cc3a09781c95e666cb06 OK lower checkfile vs. upper remote (without normalization) 2026/11/04 02:46:35 ERROR : HELLO, WORLD!: sum not found 2026/11/04 02:46:35 ERROR : hello, world!: file not in Encrypted drive 'TestCryptDrive:rclone-test-fehucod4zoki' 2026/11/04 02:46:35 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fehucod4zoki': 1 files missing 2026/11/04 02:46:35 NOTICE: 1 hashes missing 2026/11/04 02:46:35 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fehucod4zoki': 1 differences found 2026/11/04 02:46:35 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fehucod4zoki': 2 errors while checking lower checkfile vs. upper remote (with normalization) 2026/11/04 02:46:36 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/11/04 02:46:36 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fehucod4zoki': 0 differences found 2026/11/04 02:46:36 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fehucod4zoki': 1 matching files 2026/11/04 02:46:36 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-bawimet7foqa" 2026/11/04 02:46:36 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/11/04 02:46:36 DEBUG : Creating backend with remote "TestDrive:crypt/60ht9dm6dph6fp41nl4v34s3hsp938s2k6iq1eaa7jiiokjbv570" 2026/11/04 02:46:37 DEBUG : Creating backend with remote "/tmp/rclone422404244" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-bawimet7foqa'", Local "Local file system at /tmp/rclone422404244", Modify Window "1ms" 2026/11/04 02:46:39 DEBUG : HeLlO, wOrLd!: md5 = 6a6b553e33175b670cad117c4ceb32a9 OK lower checkfile vs. upperlowermixed remote (without normalization) 2026/11/04 02:46:39 ERROR : HeLlO, wOrLd!: sum not found 2026/11/04 02:46:39 ERROR : hello, world!: file not in Encrypted drive 'TestCryptDrive:rclone-test-bawimet7foqa' 2026/11/04 02:46:39 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-bawimet7foqa': 1 files missing 2026/11/04 02:46:39 NOTICE: 1 hashes missing 2026/11/04 02:46:39 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-bawimet7foqa': 1 differences found 2026/11/04 02:46:39 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-bawimet7foqa': 2 errors while checking lower checkfile vs. upperlowermixed remote (with normalization) 2026/11/04 02:46:41 DEBUG : HeLlO, wOrLd!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/11/04 02:46:41 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-bawimet7foqa': 0 differences found 2026/11/04 02:46:41 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-bawimet7foqa': 1 matching files 2026/11/04 02:46:41 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-hurifan8gebo" 2026/11/04 02:46:41 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/11/04 02:46:41 DEBUG : Creating backend with remote "TestDrive:crypt/vcgec2e3k891jsiunhcs54j2kivpe2psvkd6m1eao22sj7arp560" 2026/11/04 02:46:41 DEBUG : Creating backend with remote "/tmp/rclone544557633" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hurifan8gebo'", Local "Local file system at /tmp/rclone544557633", Modify Window "1ms" 2026/11/04 02:46:44 DEBUG : HELLO, WORLD!: md5 = 189c8800e2e94ae244e0fb96acd12e28 OK upperlowermixed checkfile vs. upper remote (without normalization) 2026/11/04 02:46:44 ERROR : HELLO, WORLD!: sum not found 2026/11/04 02:46:44 ERROR : HeLlO, wOrLd!: file not in Encrypted drive 'TestCryptDrive:rclone-test-hurifan8gebo' 2026/11/04 02:46:44 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-hurifan8gebo': 1 files missing 2026/11/04 02:46:44 NOTICE: 1 hashes missing 2026/11/04 02:46:44 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-hurifan8gebo': 1 differences found 2026/11/04 02:46:44 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-hurifan8gebo': 2 errors while checking upperlowermixed checkfile vs. upper remote (with normalization) 2026/11/04 02:46:45 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/11/04 02:46:45 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-hurifan8gebo': 0 differences found 2026/11/04 02:46:45 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-hurifan8gebo': 1 matching files 2026/11/04 02:46:45 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-husofar7lomi" 2026/11/04 02:46:45 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/11/04 02:46:45 DEBUG : Creating backend with remote "TestDrive:crypt/0o0cpeg7r4kpsqe219vkpram0oh1q1lrn7avvh6s20saafkmn0eg" 2026/11/04 02:46:46 DEBUG : Creating backend with remote "/tmp/rclone3954125863" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-husofar7lomi'", Local "Local file system at /tmp/rclone3954125863", Modify Window "1ms" 2026/11/04 02:46:48 DEBUG : 測試_Русский___ě_áñ: md5 = 97e7c766951911debef8b082b200c0dd OK NFD checkfile vs. NFC remote (without normalization) 2026/11/04 02:46:49 ERROR : 測試_Русский___ě_áñ: sum not found 2026/11/04 02:46:49 ERROR : 測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-husofar7lomi' 2026/11/04 02:46:49 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-husofar7lomi': 1 files missing 2026/11/04 02:46:49 NOTICE: 1 hashes missing 2026/11/04 02:46:49 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-husofar7lomi': 1 differences found 2026/11/04 02:46:49 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-husofar7lomi': 2 errors while checking NFD checkfile vs. NFC remote (with normalization) 2026/11/04 02:46:50 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/11/04 02:46:50 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-husofar7lomi': 0 differences found 2026/11/04 02:46:50 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-husofar7lomi': 1 matching files 2026/11/04 02:46:50 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-voyakik1neci" 2026/11/04 02:46:50 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/11/04 02:46:50 DEBUG : Creating backend with remote "TestDrive:crypt/5k5621jpr225ja2pel6jvl5mr9mpofkablstg42hseuic8jo1090" 2026/11/04 02:46:51 DEBUG : Creating backend with remote "/tmp/rclone527091910" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-voyakik1neci'", Local "Local file system at /tmp/rclone527091910", Modify Window "1ms" 2026/11/04 02:46:53 DEBUG : 測試_Русский___ě_áñ: md5 = bfc16880cb68de484807c93fc40320c8 OK NFC checkfile vs. NFD remote (without normalization) 2026/11/04 02:46:53 ERROR : 測試_Русский___ě_áñ: sum not found 2026/11/04 02:46:53 ERROR : 測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-voyakik1neci' 2026/11/04 02:46:53 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-voyakik1neci': 1 files missing 2026/11/04 02:46:53 NOTICE: 1 hashes missing 2026/11/04 02:46:53 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-voyakik1neci': 1 differences found 2026/11/04 02:46:53 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-voyakik1neci': 2 errors while checking NFC checkfile vs. NFD remote (with normalization) 2026/11/04 02:46:54 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/11/04 02:46:54 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-voyakik1neci': 0 differences found 2026/11/04 02:46:54 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-voyakik1neci': 1 matching files 2026/11/04 02:46:54 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-kenikah2jaho" 2026/11/04 02:46:54 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/11/04 02:46:54 DEBUG : Creating backend with remote "TestDrive:crypt/crcdajrb2nt1o1o75s6vrpvut3mjq5k93vg49hvbukj0crtvihg0" 2026/11/04 02:46:55 DEBUG : Creating backend with remote "/tmp/rclone1908944898" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kenikah2jaho'", Local "Local file system at /tmp/rclone1908944898", Modify Window "1ms" 2026/11/04 02:46:58 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 5b5c6ec85d0baa29cf6cc7d9b0b7d464 OK NFDx2 checkfile vs. both remote (without normalization) 2026/11/04 02:46:58 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/11/04 02:46:58 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-kenikah2jaho' 2026/11/04 02:46:58 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kenikah2jaho': 1 files missing 2026/11/04 02:46:58 NOTICE: 1 hashes missing 2026/11/04 02:46:58 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kenikah2jaho': 1 differences found 2026/11/04 02:46:58 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kenikah2jaho': 2 errors while checking NFDx2 checkfile vs. both remote (with normalization) 2026/11/04 02:46:59 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/11/04 02:46:59 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kenikah2jaho': 0 differences found 2026/11/04 02:46:59 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kenikah2jaho': 1 matching files 2026/11/04 02:46:59 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-ciretup4qose" 2026/11/04 02:46:59 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/11/04 02:46:59 DEBUG : Creating backend with remote "TestDrive:crypt/gua6e9odvkdpvm9ldotncg0vje63n2n07l99psi2lnb8v4fbnrv0" 2026/11/04 02:47:00 DEBUG : Creating backend with remote "/tmp/rclone3534063699" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-ciretup4qose'", Local "Local file system at /tmp/rclone3534063699", Modify Window "1ms" 2026/11/04 02:47:02 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 9e61b240e02c7fbac67f65da56f2300e OK NFCx2 checkfile vs. both remote (without normalization) 2026/11/04 02:47:03 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/11/04 02:47:03 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-ciretup4qose' 2026/11/04 02:47:03 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-ciretup4qose': 1 files missing 2026/11/04 02:47:03 NOTICE: 1 hashes missing 2026/11/04 02:47:03 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-ciretup4qose': 1 differences found 2026/11/04 02:47:03 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-ciretup4qose': 2 errors while checking NFCx2 checkfile vs. both remote (with normalization) 2026/11/04 02:47:04 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/11/04 02:47:04 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-ciretup4qose': 0 differences found 2026/11/04 02:47:04 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-ciretup4qose': 1 matching files 2026/11/04 02:47:04 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-kiheroy3melo" 2026/11/04 02:47:04 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/11/04 02:47:04 DEBUG : Creating backend with remote "TestDrive:crypt/pllre2r8r7hguetr7bdbei5spjf1fq6ilv5dnlgchoortqqcvd10" 2026/11/04 02:47:05 DEBUG : Creating backend with remote "/tmp/rclone4250285669" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kiheroy3melo'", Local "Local file system at /tmp/rclone4250285669", Modify Window "1ms" 2026/11/04 02:47:07 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 70f86c816ecd0b1dc0bf5c74695e7803 OK both checkfile vs. NFDx2 remote (without normalization) 2026/11/04 02:47:07 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/11/04 02:47:07 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-kiheroy3melo' 2026/11/04 02:47:07 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kiheroy3melo': 1 files missing 2026/11/04 02:47:07 NOTICE: 1 hashes missing 2026/11/04 02:47:07 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kiheroy3melo': 1 differences found 2026/11/04 02:47:07 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kiheroy3melo': 2 errors while checking both checkfile vs. NFDx2 remote (with normalization) 2026/11/04 02:47:08 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/11/04 02:47:08 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kiheroy3melo': 0 differences found 2026/11/04 02:47:08 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kiheroy3melo': 1 matching files 2026/11/04 02:47:08 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-behiyik8tosu" 2026/11/04 02:47:08 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/11/04 02:47:08 DEBUG : Creating backend with remote "TestDrive:crypt/395dncc6lprg8mgk4ikh3g3ilrrcnh2tu9554hokkmfcshvdsaug" 2026/11/04 02:47:09 DEBUG : Creating backend with remote "/tmp/rclone1067259325" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-behiyik8tosu'", Local "Local file system at /tmp/rclone1067259325", Modify Window "1ms" 2026/11/04 02:47:12 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 172fa3e3ec51ffe7c5e5e6740875092b OK both checkfile vs. NFCx2 remote (without normalization) 2026/11/04 02:47:12 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/11/04 02:47:12 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-behiyik8tosu' 2026/11/04 02:47:12 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-behiyik8tosu': 1 files missing 2026/11/04 02:47:12 NOTICE: 1 hashes missing 2026/11/04 02:47:12 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-behiyik8tosu': 1 differences found 2026/11/04 02:47:12 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-behiyik8tosu': 2 errors while checking both checkfile vs. NFCx2 remote (with normalization) 2026/11/04 02:47:13 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/11/04 02:47:13 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-behiyik8tosu': 0 differences found 2026/11/04 02:47:13 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-behiyik8tosu': 1 matching files 2026/11/04 02:47:13 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-behiyik8tosu': Purge remote 2026/11/04 02:47:13 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-kiheroy3melo': Purge remote 2026/11/04 02:47:14 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-ciretup4qose': Purge remote 2026/11/04 02:47:14 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-kenikah2jaho': Purge remote 2026/11/04 02:47:15 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-voyakik1neci': Purge remote 2026/11/04 02:47:15 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-husofar7lomi': Purge remote 2026/11/04 02:47:16 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-hurifan8gebo': Purge remote 2026/11/04 02:47:16 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-bawimet7foqa': Purge remote 2026/11/04 02:47:17 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-fehucod4zoki': Purge remote 2026/11/04 02:47:17 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-lifimoc8nagu': Purge remote --- PASS: TestApplyTransforms (51.11s) === RUN TestTruncateString --- PASS: TestTruncateString (0.00s) === RUN TestCopyFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 02:47:18 DEBUG : file1: Need to transfer - File not found at Destination 2026/11/04 02:47:20 DEBUG : sub/file2: md5 = 2692c88633ea0ebc950ae20eaa71534d OK 2026/11/04 02:47:20 DEBUG : sub/file2: size = 14 OK 2026/11/04 02:47:20 INFO : file1: Copied (new) to: sub/file2 2026/11/04 02:47:21 DEBUG : sub/file2: size = 14 OK 2026/11/04 02:47:21 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/11/04 02:47:21 DEBUG : file1: Unchanged skipping 2026/11/04 02:47:21 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano': don't need to copy/move sub/file2, it is already at target location --- PASS: TestCopyFile (5.90s) === RUN TestCopyFileImmutable run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 02:47:24 DEBUG : existing: Need to transfer - File not found at Destination 2026/11/04 02:47:25 DEBUG : existing: md5 = b1f2c12443a948e61295caf87e17d199 OK 2026/11/04 02:47:25 DEBUG : existing: size = 6 OK 2026/11/04 02:47:25 INFO : existing: Copied (new) 2026/11/04 02:47:26 DEBUG : existing: size = 6 OK 2026/11/04 02:47:26 DEBUG : existing: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/11/04 02:47:26 DEBUG : existing: Unchanged skipping 2026/11/04 02:47:26 DEBUG : existing: size = 8 (Local file system at /tmp/rclone974098245) 2026/11/04 02:47:26 DEBUG : existing: size = 6 (Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano') 2026/11/04 02:47:26 DEBUG : existing: Sizes differ 2026/11/04 02:47:26 ERROR : existing: Source and destination exist but do not match: immutable file modified --- PASS: TestCopyFileImmutable (4.12s) === RUN TestCopyLongFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" copy_test.go:188: Test only runs on local --- SKIP: TestCopyLongFile (0.45s) === RUN TestCopyFileBackupDir run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 02:47:30 DEBUG : dst/file1: md5 = 667c083319a31f21de431f02675dc26a OK 2026/11/04 02:47:31 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-birusev6cano/backup" 2026/11/04 02:47:31 DEBUG : Creating backend with remote "TestDrive:crypt/ruedvu180ro2fcktkdj2gabtvu3sr8pt7hsglppuerfam48l4v10/1nrff024r7pq65ecp72fc28jb0" 2026/11/04 02:47:32 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone974098245) 2026/11/04 02:47:32 DEBUG : dst/file1: size = 18 (Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano') 2026/11/04 02:47:32 DEBUG : dst/file1: Sizes differ 2026/11/04 02:47:35 INFO : dst/file1: Moved (server-side) 2026/11/04 02:47:36 DEBUG : dst/file1: md5 = 7ab44273ec54ca409c4b885605ae9082 OK 2026/11/04 02:47:36 DEBUG : dst/file1: size = 14 OK 2026/11/04 02:47:36 INFO : dst/file1: Copied (new) --- PASS: TestCopyFileBackupDir (12.67s) === RUN TestCopyFileCompareDest run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 02:47:41 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-birusev6cano/dst" 2026/11/04 02:47:41 DEBUG : Creating backend with remote "TestDrive:crypt/ruedvu180ro2fcktkdj2gabtvu3sr8pt7hsglppuerfam48l4v10/31u3jie661vd5p8j7rtc3hgbh0" 2026/11/04 02:47:42 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-birusev6cano/CompareDest" 2026/11/04 02:47:42 DEBUG : Creating backend with remote "TestDrive:crypt/ruedvu180ro2fcktkdj2gabtvu3sr8pt7hsglppuerfam48l4v10/gveqi14airsml4bgu7krj116o8" 2026/11/04 02:47:44 DEBUG : one: Need to transfer - File not found at Destination 2026/11/04 02:47:46 DEBUG : one: md5 = 56369bb5859ac1575ccc52c10335ecb6 OK 2026/11/04 02:47:46 DEBUG : one: size = 3 OK 2026/11/04 02:47:46 INFO : one: Copied (new) 2026/11/04 02:47:47 DEBUG : one: size = 5 (Local file system at /tmp/rclone974098245) 2026/11/04 02:47:47 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano/dst') 2026/11/04 02:47:47 DEBUG : one: Sizes differ 2026/11/04 02:47:49 DEBUG : one: md5 = b14e0619356327f6ed7a8c813b4e1bda OK 2026/11/04 02:47:49 DEBUG : one: size = 5 OK 2026/11/04 02:47:49 INFO : one: Copied (replaced existing) 2026/11/04 02:47:51 DEBUG : dst/one: md5 = b33f6cf11518cec121af74f1580195d7 OK 2026/11/04 02:47:53 DEBUG : CompareDest/one: md5 = 945695ddd99428e340a25289299f0cd0 OK 2026/11/04 02:47:54 DEBUG : one: size = 5 (Local file system at /tmp/rclone974098245) 2026/11/04 02:47:54 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano/dst') 2026/11/04 02:47:54 DEBUG : one: Sizes differ 2026/11/04 02:47:54 DEBUG : one: size = 5 OK 2026/11/04 02:47:54 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/11/04 02:47:54 DEBUG : one: Destination found in --compare-dest, skipping 2026/11/04 02:47:56 DEBUG : CompareDest/two: md5 = 2720981497c5bc144190380fdfc03988 OK 2026/11/04 02:47:57 DEBUG : two: Need to transfer - File not found at Destination 2026/11/04 02:47:57 DEBUG : two: size = 3 OK 2026/11/04 02:47:57 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/11/04 02:47:57 DEBUG : two: Destination found in --compare-dest, skipping 2026/11/04 02:47:58 DEBUG : two: Need to transfer - File not found at Destination 2026/11/04 02:47:58 DEBUG : two: size = 3 OK 2026/11/04 02:47:58 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/11/04 02:47:58 DEBUG : two: Destination found in --compare-dest, skipping 2026/11/04 02:47:59 DEBUG : two: Need to transfer - File not found at Destination 2026/11/04 02:48:00 DEBUG : two: size = 5 (Local file system at /tmp/rclone974098245) 2026/11/04 02:48:00 DEBUG : two: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano/CompareDest') 2026/11/04 02:48:00 DEBUG : two: Sizes differ 2026/11/04 02:48:01 DEBUG : two: md5 = 0ca9f776b9ba3156a9ac89837d61e562 OK 2026/11/04 02:48:01 DEBUG : two: size = 5 OK 2026/11/04 02:48:01 INFO : two: Copied (new) --- PASS: TestCopyFileCompareDest (24.96s) === RUN TestCopyFileCopyDestImmutable run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 02:48:06 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-birusev6cano/dst" 2026/11/04 02:48:06 DEBUG : Creating backend with remote "TestDrive:crypt/ruedvu180ro2fcktkdj2gabtvu3sr8pt7hsglppuerfam48l4v10/31u3jie661vd5p8j7rtc3hgbh0" 2026/11/04 02:48:09 DEBUG : dst/one: md5 = e163cd1289e8491d060d2e04bf653550 OK 2026/11/04 02:48:12 DEBUG : CopyDest/one: md5 = 3a10fd7ccf29de0d846ccc85015db090 OK 2026/11/04 02:48:13 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-birusev6cano/CopyDest" 2026/11/04 02:48:13 DEBUG : Creating backend with remote "TestDrive:crypt/ruedvu180ro2fcktkdj2gabtvu3sr8pt7hsglppuerfam48l4v10/d09o6po3f7bm6ce32vdgs8h9ls" 2026/11/04 02:48:13 DEBUG : one: size = 5 (Local file system at /tmp/rclone974098245) 2026/11/04 02:48:13 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano/dst') 2026/11/04 02:48:13 DEBUG : one: Sizes differ 2026/11/04 02:48:14 DEBUG : one: size = 5 OK 2026/11/04 02:48:14 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/11/04 02:48:14 DEBUG : one: size = 5 (Local file system at /tmp/rclone974098245) 2026/11/04 02:48:14 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano/dst') 2026/11/04 02:48:14 DEBUG : one: Sizes differ 2026/11/04 02:48:14 ERROR : one: Source and destination exist but do not match: immutable file modified --- PASS: TestCopyFileCopyDestImmutable (11.39s) === RUN TestCopyFileCopyDest run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 02:48:17 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-birusev6cano/dst" 2026/11/04 02:48:17 DEBUG : Creating backend with remote "TestDrive:crypt/ruedvu180ro2fcktkdj2gabtvu3sr8pt7hsglppuerfam48l4v10/31u3jie661vd5p8j7rtc3hgbh0" 2026/11/04 02:48:19 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-birusev6cano/CopyDest" 2026/11/04 02:48:19 DEBUG : Creating backend with remote "TestDrive:crypt/ruedvu180ro2fcktkdj2gabtvu3sr8pt7hsglppuerfam48l4v10/d09o6po3f7bm6ce32vdgs8h9ls" 2026/11/04 02:48:20 DEBUG : one: Need to transfer - File not found at Destination 2026/11/04 02:48:23 DEBUG : one: md5 = f43bb2708fe0ff8cd49a712432e05104 OK 2026/11/04 02:48:23 DEBUG : one: size = 3 OK 2026/11/04 02:48:23 INFO : one: Copied (new) 2026/11/04 02:48:24 DEBUG : one: size = 5 (Local file system at /tmp/rclone974098245) 2026/11/04 02:48:24 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano/dst') 2026/11/04 02:48:24 DEBUG : one: Sizes differ 2026/11/04 02:48:25 DEBUG : one: md5 = 87e63bc1aee04fc160f1cd22450c76ac OK 2026/11/04 02:48:25 DEBUG : one: size = 5 OK 2026/11/04 02:48:25 INFO : one: Copied (replaced existing) 2026/11/04 02:48:28 DEBUG : dst/one: md5 = 427d70ce88ee65be18c25ec1e4cd4886 OK 2026/11/04 02:48:30 DEBUG : CopyDest/one: md5 = d86b762ea345ec6217bcaf131bd649e0 OK 2026/11/04 02:48:31 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-birusev6cano/BackupDir" 2026/11/04 02:48:31 DEBUG : Creating backend with remote "TestDrive:crypt/ruedvu180ro2fcktkdj2gabtvu3sr8pt7hsglppuerfam48l4v10/s6dbk3lfi7c9kfvo6j7bla9m0g" 2026/11/04 02:48:33 DEBUG : one: size = 5 (Local file system at /tmp/rclone974098245) 2026/11/04 02:48:33 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano/dst') 2026/11/04 02:48:33 DEBUG : one: Sizes differ 2026/11/04 02:48:33 DEBUG : one: size = 5 OK 2026/11/04 02:48:33 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/11/04 02:48:33 DEBUG : one: size = 5 (Local file system at /tmp/rclone974098245) 2026/11/04 02:48:33 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano/dst') 2026/11/04 02:48:33 DEBUG : one: Sizes differ 2026/11/04 02:48:35 INFO : one: Moved (server-side) 2026/11/04 02:48:36 DEBUG : one: size = 5 OK 2026/11/04 02:48:36 INFO : one: Copied (server-side copy) 2026/11/04 02:48:36 DEBUG : one: Destination found in --copy-dest, using server-side copy 2026/11/04 02:48:37 DEBUG : CopyDest/two: md5 = f27228a170cf56b84504862f882590b2 OK 2026/11/04 02:48:38 DEBUG : two: Need to transfer - File not found at Destination 2026/11/04 02:48:38 DEBUG : two: size = 3 OK 2026/11/04 02:48:38 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/11/04 02:48:39 DEBUG : two: size = 3 OK 2026/11/04 02:48:39 INFO : two: Copied (server-side copy) 2026/11/04 02:48:39 DEBUG : two: Destination found in --copy-dest, using server-side copy 2026/11/04 02:48:40 DEBUG : two: size = 3 OK 2026/11/04 02:48:40 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/11/04 02:48:40 DEBUG : two: Unchanged skipping 2026/11/04 02:48:42 DEBUG : CopyDest/three: md5 = 22604da38ac703cc7db4136ccba35795 OK 2026/11/04 02:48:43 DEBUG : three: Need to transfer - File not found at Destination 2026/11/04 02:48:44 DEBUG : three: size = 7 (Local file system at /tmp/rclone974098245) 2026/11/04 02:48:44 DEBUG : three: size = 5 (Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano/CopyDest') 2026/11/04 02:48:44 DEBUG : three: Sizes differ 2026/11/04 02:48:44 DEBUG : three: Destination not found in --copy-dest 2026/11/04 02:48:45 DEBUG : three: md5 = ffdea7fe021b1d6467246c78124acc14 OK 2026/11/04 02:48:45 DEBUG : three: size = 7 OK 2026/11/04 02:48:45 INFO : three: Copied (new) --- PASS: TestCopyFileCopyDest (34.27s) === RUN TestCopyInplace run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" copy_test.go:434: Partial uploads not supported --- SKIP: TestCopyInplace (0.48s) === RUN TestCopyLongFileName run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" copy_test.go:467: Partial uploads not supported --- SKIP: TestCopyLongFileName (0.44s) === RUN TestCopyLongFileNameCollision run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" copy_test.go:500: Partial uploads not supported --- SKIP: TestCopyLongFileNameCollision (0.45s) === RUN TestCopyFileMaxTransfer run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 02:48:53 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination 2026/11/04 02:48:55 DEBUG : TestCopyFileMaxTransfer/file1: md5 = 7ff488926f0280ca4ce53256b5d8c1dc OK 2026/11/04 02:48:55 DEBUG : TestCopyFileMaxTransfer/file1: size = 14 OK 2026/11/04 02:48:55 INFO : TestCopyFileMaxTransfer/file1: Copied (new) 2026/11/04 02:48:56 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination 2026/11/04 02:48:56 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/11/04 02:48:57 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination 2026/11/04 02:48:57 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination 2026/11/04 02:48:59 DEBUG : TestCopyFileMaxTransfer/file4: md5 = adbab99e48e4c6ba824ed3136b52df37 OK 2026/11/04 02:48:59 DEBUG : TestCopyFileMaxTransfer/file4: size = 2062 OK 2026/11/04 02:48:59 INFO : TestCopyFileMaxTransfer/file4: Copied (new) --- PASS: TestCopyFileMaxTransfer (9.07s) === RUN TestDeduplicateInteractive run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" dedupe_test.go:37: Can't run this test without a hash --- SKIP: TestDeduplicateInteractive (0.45s) === RUN TestDeduplicateSkip run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 02:49:05 INFO : Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano': Looking for duplicate names using skip mode. 2026/11/04 02:49:05 NOTICE: one: Found 2 files with duplicate names 2026/11/04 02:49:05 NOTICE: one: Skipping 2 files with duplicate names --- PASS: TestDeduplicateSkip (4.55s) === RUN TestDeduplicateSizeOnly run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 02:49:10 INFO : Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano': Looking for duplicate names using skip mode. 2026/11/04 02:49:11 NOTICE: one: Found 3 files with duplicate names 2026/11/04 02:49:11 NOTICE: one: Deleting 1/2 identical duplicates (size 11) 2026/11/04 02:49:11 INFO : one: Deleted 2026/11/04 02:49:11 NOTICE: one: Skipping 2 files with duplicate names --- PASS: TestDeduplicateSizeOnly (6.45s) === RUN TestDeduplicateFirst run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 02:49:17 INFO : Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano': Looking for duplicate names using first mode. 2026/11/04 02:49:17 NOTICE: one: Found 3 files with duplicate names 2026/11/04 02:49:18 INFO : one: Deleted 2026/11/04 02:49:18 INFO : one: Deleted 2026/11/04 02:49:18 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateFirst (6.13s) === RUN TestDeduplicateNewest run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 02:49:23 INFO : Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano': Looking for duplicate names using newest mode. 2026/11/04 02:49:23 NOTICE: one: Found 3 files with duplicate names 2026/11/04 02:49:24 INFO : one: Deleted 2026/11/04 02:49:24 INFO : one: Deleted 2026/11/04 02:49:24 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateNewest (6.06s) === RUN TestDeduplicateNewestByHash run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" dedupe_test.go:37: Can't run this test without a hash --- SKIP: TestDeduplicateNewestByHash (0.45s) === RUN TestDeduplicateOldest run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 02:49:30 INFO : Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano': Looking for duplicate names using oldest mode. 2026/11/04 02:49:30 NOTICE: one: Found 3 files with duplicate names 2026/11/04 02:49:31 INFO : one: Deleted 2026/11/04 02:49:31 INFO : one: Deleted 2026/11/04 02:49:31 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateOldest (6.71s) === RUN TestDeduplicateLargest run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 02:49:36 INFO : Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano': Looking for duplicate names using largest mode. 2026/11/04 02:49:37 NOTICE: one: Found 3 files with duplicate names 2026/11/04 02:49:37 INFO : one: Deleted 2026/11/04 02:49:38 INFO : one: Deleted 2026/11/04 02:49:38 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateLargest (6.31s) === RUN TestDeduplicateSmallest run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 02:49:43 INFO : Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano': Looking for duplicate names using smallest mode. 2026/11/04 02:49:43 NOTICE: one: Found 3 files with duplicate names 2026/11/04 02:49:44 INFO : one: Deleted 2026/11/04 02:49:44 INFO : one: Deleted 2026/11/04 02:49:44 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateSmallest (6.69s) === RUN TestDeduplicateRename run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 02:49:51 INFO : Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano': Looking for duplicate names using rename mode. 2026/11/04 02:49:51 NOTICE: one.txt: Found 3 files with duplicate names 2026/11/04 02:49:52 INFO : one-2.txt: renamed from: one.txt 2026/11/04 02:49:52 INFO : one-3.txt: renamed from: one.txt 2026/11/04 02:49:53 INFO : one-4.txt: renamed from: one.txt --- PASS: TestDeduplicateRename (10.13s) === RUN TestDeduplicateRenameManyExisting run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 02:49:57 DEBUG : one-1.txt: md5 = 05fc80a1e1195ca2eeb365742bc20797 OK 2026/11/04 02:49:58 DEBUG : one-2.txt: md5 = ecb0946920448363d3db09b8328a9dc7 OK 2026/11/04 02:50:00 DEBUG : one-3.txt: md5 = 23d62e34f96942273f2656be0cec704a OK 2026/11/04 02:50:01 DEBUG : one-4.txt: md5 = aee0dafa054c64c24ab67043ccf7b3f0 OK 2026/11/04 02:50:03 DEBUG : one-5.txt: md5 = dc55b996b843ad8533b3bcd1b847ba02 OK 2026/11/04 02:50:04 DEBUG : one-6.txt: md5 = cb328442b211abfed457ada17e60dcb8 OK 2026/11/04 02:50:06 DEBUG : one-7.txt: md5 = b5a5ee47b3ba7e68977d23b2f2133438 OK 2026/11/04 02:50:07 DEBUG : one-8.txt: md5 = d9552643d4aa985b7dd4e7db4dce2929 OK 2026/11/04 02:50:09 DEBUG : one-9.txt: md5 = f177c61c49313ee28d7e192031e47625 OK 2026/11/04 02:50:10 DEBUG : one-10.txt: md5 = 8a73d57a9130a6cd0300cf58916bdbf1 OK 2026/11/04 02:50:12 DEBUG : one-11.txt: md5 = 8235c00dd72643e5404fdc49c81e2189 OK 2026/11/04 02:50:13 DEBUG : one-12.txt: md5 = 94cbe152c7e93f596f8d14ae75fc82b7 OK 2026/11/04 02:50:14 DEBUG : one-13.txt: md5 = 4d40afafb7472b6b95d86a21624f9f5e OK 2026/11/04 02:50:16 DEBUG : one-14.txt: md5 = 2e3ee1ba4eb9b50fd8de4c6183f32cfb OK 2026/11/04 02:50:17 DEBUG : one-15.txt: md5 = e3420bf02ebd3d329435a4af80eb4fa4 OK 2026/11/04 02:50:19 DEBUG : one-16.txt: md5 = c4e2411c043107c7dcb7aee2bece418d OK 2026/11/04 02:50:20 DEBUG : one-17.txt: md5 = c2f11a73e4a1afdc614ec43cc5d4d2a9 OK 2026/11/04 02:50:22 DEBUG : one-18.txt: md5 = 27a60c09e88ad33b2fb71d2f8f0d61d2 OK 2026/11/04 02:50:23 DEBUG : one-19.txt: md5 = a64c1c6684587c3f0789bacae8aaf41b OK 2026/11/04 02:50:25 DEBUG : one-20.txt: md5 = 0438601cf0cc86b1ebdefbb6e2b755ac OK 2026/11/04 02:50:26 DEBUG : one-21.txt: md5 = 7140d7e9c64c32d4ffb7e62df897afd3 OK 2026/11/04 02:50:28 DEBUG : one-22.txt: md5 = 2a568c364ca824343bc977581c2989de OK 2026/11/04 02:50:29 DEBUG : one-23.txt: md5 = 9f3b5c537879167826154eeab75a7717 OK 2026/11/04 02:50:30 DEBUG : one-24.txt: md5 = c0c4141f7ee4b60faabb8b2b1e2fc375 OK 2026/11/04 02:50:32 DEBUG : one-25.txt: md5 = cb6c8cea5a6b17c552d9f7f48d131c7a OK 2026/11/04 02:50:33 DEBUG : one-26.txt: md5 = bbb377df966bf332950b116980690334 OK 2026/11/04 02:50:35 DEBUG : one-27.txt: md5 = 2cb578a157a19bcb398460ecca936e01 OK 2026/11/04 02:50:36 DEBUG : one-28.txt: md5 = f406d92e9b8232b766901a8564a3ac5d OK 2026/11/04 02:50:38 DEBUG : one-29.txt: md5 = 099421b8ca3bc7d5d0869ee8635403ce OK 2026/11/04 02:50:39 DEBUG : one-30.txt: md5 = e837a8efe82f32d64e524b5b3c365c15 OK 2026/11/04 02:50:40 DEBUG : one-31.txt: md5 = 0c65f49f4e2b21e674f87d66d8743a7f OK 2026/11/04 02:50:42 DEBUG : one-32.txt: md5 = 3ea0da363ea2e46094f60e6499f75712 OK 2026/11/04 02:50:43 DEBUG : one-33.txt: md5 = bdfd14196c4fcccf354a8f906073283f OK 2026/11/04 02:50:45 DEBUG : one-34.txt: md5 = f5292a3c2448aba43c5782dee65a7bd7 OK 2026/11/04 02:50:46 DEBUG : one-35.txt: md5 = 9dd87dd3c2fa79105e2a8e6b1945cf0b OK 2026/11/04 02:50:48 DEBUG : one-36.txt: md5 = 78330389d5eefd2e3a003ca49cc81b16 OK 2026/11/04 02:50:49 DEBUG : one-37.txt: md5 = 660678086283dc003a4ef8814e0b436a OK 2026/11/04 02:50:51 DEBUG : one-38.txt: md5 = bbde879dad8a97586c89292926b3c9ab OK 2026/11/04 02:50:52 DEBUG : one-39.txt: md5 = f0056f9a23048115ac7bd4ddfc15d966 OK 2026/11/04 02:50:54 DEBUG : one-40.txt: md5 = ee1dc1df04303c7f47a3d4e1998c1f36 OK 2026/11/04 02:50:55 DEBUG : one-41.txt: md5 = bcdeb25d85ca138938b4d73876aa1f87 OK 2026/11/04 02:50:57 DEBUG : one-42.txt: md5 = 1b828433846077a563cc128b351f7e22 OK 2026/11/04 02:50:59 DEBUG : one-43.txt: md5 = f1b473557abc1255f9282490bd89f4fb OK 2026/11/04 02:51:00 DEBUG : one-44.txt: md5 = e890be70c876c230b67ad72b1a75d9ce OK 2026/11/04 02:51:01 DEBUG : one-45.txt: md5 = c633172d567da19c32ea332dcc9e7ac2 OK 2026/11/04 02:51:03 DEBUG : one-46.txt: md5 = 836c1c791416c3f7af4ab1c9a867aa7c OK 2026/11/04 02:51:04 DEBUG : one-47.txt: md5 = f1310828eb1526b337db59b526fd9f52 OK 2026/11/04 02:51:06 DEBUG : one-48.txt: md5 = 78d0900ea604d4778c9446aa410471da OK 2026/11/04 02:51:07 DEBUG : one-49.txt: md5 = 1ba38b323b2b7e7fc12f9a79960f9fb1 OK 2026/11/04 02:51:09 DEBUG : one-50.txt: md5 = e311fff044e770596ea845f55b777e1a OK 2026/11/04 02:51:10 DEBUG : one-51.txt: md5 = 370bb978f051dbc6dba78412abe57c51 OK 2026/11/04 02:51:12 DEBUG : one-52.txt: md5 = efb885b21c17844c7d75544b1feb5be8 OK 2026/11/04 02:51:13 DEBUG : one-53.txt: md5 = a5da25181a1dee87272c1c017ff5c5cf OK 2026/11/04 02:51:15 DEBUG : one-54.txt: md5 = 6f1c8d87147e9c4e15ff08e21aa5edb1 OK 2026/11/04 02:51:16 DEBUG : one-55.txt: md5 = c9b85b4eb0ed86b3a80dc43f5ef964a6 OK 2026/11/04 02:51:18 DEBUG : one-56.txt: md5 = 5458a8c3c0980c723a632d0b56fc9f77 OK 2026/11/04 02:51:19 DEBUG : one-57.txt: md5 = 4620051a6fd09a9e4422f5267bdbcce5 OK 2026/11/04 02:51:21 DEBUG : one-58.txt: md5 = 465af624c8d774576966adbc3679a539 OK 2026/11/04 02:51:22 DEBUG : one-59.txt: md5 = e48bef57bd59fa16a36c9a8f3629914f OK 2026/11/04 02:51:24 DEBUG : one-60.txt: md5 = a2ca9b583980e686ebef5c9ece428c03 OK 2026/11/04 02:51:25 DEBUG : one-61.txt: md5 = ed0d86ae5738fcf9c82f1f184c7bcfb3 OK 2026/11/04 02:51:26 DEBUG : one-62.txt: md5 = 19b210d20a7ab79f080310ceaabf988d OK 2026/11/04 02:51:28 DEBUG : one-63.txt: md5 = 73be0183964d05954cdd2b9ee35f92b8 OK 2026/11/04 02:51:29 DEBUG : one-64.txt: md5 = 87906347cb47b747f23fda65f2f22efc OK 2026/11/04 02:51:31 DEBUG : one-65.txt: md5 = 3dcfcd80610037398c16e3f6a7d52d1d OK 2026/11/04 02:51:32 DEBUG : one-66.txt: md5 = 9a31e1413e7a5d6391f39b3ca9f80205 OK 2026/11/04 02:51:34 DEBUG : one-67.txt: md5 = fe37359142357f09e0b7f2b28d92a29b OK 2026/11/04 02:51:35 DEBUG : one-68.txt: md5 = d9d0db8f06a1bf372e139b636236dd36 OK 2026/11/04 02:51:37 DEBUG : one-69.txt: md5 = cf959b542e50f4484ac1b0b6135fb8ca OK 2026/11/04 02:51:38 DEBUG : one-70.txt: md5 = 2d8a109c45e9f69b8234937e334f8884 OK 2026/11/04 02:51:40 DEBUG : one-71.txt: md5 = 2707b3bf37d925765fc4ced97fec9256 OK 2026/11/04 02:51:41 DEBUG : one-72.txt: md5 = 8edfbb4acf2ebbe2b8fe7840cf6de8fd OK 2026/11/04 02:51:43 DEBUG : one-73.txt: md5 = a83f400f961a5bdf89cee9a586818bfa OK 2026/11/04 02:51:44 DEBUG : one-74.txt: md5 = 18b7542167da2c7fd57465f56cc44827 OK 2026/11/04 02:51:45 DEBUG : one-75.txt: md5 = 8c37da9cbdffc946ccc42e213bf498a2 OK 2026/11/04 02:51:47 DEBUG : one-76.txt: md5 = 7283f080c284985108b9bcd97bd10920 OK 2026/11/04 02:51:48 DEBUG : one-77.txt: md5 = e8b52b40f676d528f4fb9bf782c26eed OK 2026/11/04 02:51:50 DEBUG : one-78.txt: md5 = 45a9d860fdbdb60b96c88c6d00ebfbcc OK 2026/11/04 02:51:51 DEBUG : one-79.txt: md5 = 6dc83c77bb19860f1d7d38cfe316f7ab OK 2026/11/04 02:51:53 DEBUG : one-80.txt: md5 = 1bd08fbc1c0f759d1cdbe8bdd4f7fe46 OK 2026/11/04 02:51:54 DEBUG : one-81.txt: md5 = 43348e32d7cad6b6017c089219cd54d5 OK 2026/11/04 02:51:55 DEBUG : one-82.txt: md5 = 1341f8a88bda76ee9cb3afe9003482c2 OK 2026/11/04 02:51:57 DEBUG : one-83.txt: md5 = f45df7059b85f29b2bdb7ba3229191ca OK 2026/11/04 02:51:58 DEBUG : one-84.txt: md5 = 483ab352983381283e7af3e7636c8095 OK 2026/11/04 02:52:00 DEBUG : one-85.txt: md5 = 9f7f757239ff058b933091743e1e76ab OK 2026/11/04 02:52:01 DEBUG : one-86.txt: md5 = c509e5f1c4069fc229a8b14278187fa3 OK 2026/11/04 02:52:03 DEBUG : one-87.txt: md5 = 59173e786685f435a6422ea3f6314695 OK 2026/11/04 02:52:04 DEBUG : one-88.txt: md5 = 4f335015c378c00f85c2f56f61af727a OK 2026/11/04 02:52:06 DEBUG : one-89.txt: md5 = 15bb1dbdc5c863a6d43ea1021bc805ed OK 2026/11/04 02:52:07 DEBUG : one-90.txt: md5 = 321ce563e54b6b78543b546c9c3a8430 OK 2026/11/04 02:52:08 DEBUG : one-91.txt: md5 = fce97e7945a10d6a98dae28f2b1ec396 OK 2026/11/04 02:52:10 DEBUG : one-92.txt: md5 = d77bb3d7d794532fde8905367679dca3 OK 2026/11/04 02:52:11 DEBUG : one-93.txt: md5 = e178a8bae8ade0a717a029a935ce8f79 OK 2026/11/04 02:52:13 DEBUG : one-94.txt: md5 = ad75d766343488206a41f916ff915763 OK 2026/11/04 02:52:14 DEBUG : one-95.txt: md5 = c6d0f27732b051905ef2226ac5046422 OK 2026/11/04 02:52:16 DEBUG : one-96.txt: md5 = 966b93f475e0dd1cbe1724ddde43def5 OK 2026/11/04 02:52:17 DEBUG : one-97.txt: md5 = 49403f4ec1e4bcd693cc689b5ac4c568 OK 2026/11/04 02:52:18 DEBUG : one-98.txt: md5 = 8b87b2aab37c83fe2e495dd47b0655f8 OK 2026/11/04 02:52:20 DEBUG : one-99.txt: md5 = 0b4847a55b52bc7a81bf5155b5be2d76 OK 2026/11/04 02:52:21 DEBUG : one-100.txt: md5 = 241e5e489d9b4e0c4015d70c367879dc OK 2026/11/04 02:52:23 DEBUG : one-101.txt: md5 = 853ea874f9ce74d0b38b113875eebeee OK 2026/11/04 02:52:24 DEBUG : one-102.txt: md5 = e667fcbb4a2afa236b601f2503618d5c OK 2026/11/04 02:52:25 DEBUG : one-103.txt: md5 = 5558489f8decf901be05e94e37639028 OK 2026/11/04 02:52:27 DEBUG : one-104.txt: md5 = 279c0b5e6fd68d56120bd99715dbc254 OK 2026/11/04 02:52:28 DEBUG : one-105.txt: md5 = c5e87a48861cd52406e5d92c00d7a6f1 OK 2026/11/04 02:52:31 INFO : Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano': Looking for duplicate names using rename mode. 2026/11/04 02:52:32 NOTICE: one.txt: Found 2 files with duplicate names 2026/11/04 02:52:32 INFO : one-106.txt: renamed from: one.txt 2026/11/04 02:52:34 INFO : one-107.txt: renamed from: one.txt --- PASS: TestDeduplicateRenameManyExisting (207.90s) === RUN TestMergeDirs run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 02:53:26 DEBUG : dupe1/one.txt: md5 = 7e32529d3eb2c80a93f142eeafda4aad OK 2026/11/04 02:53:28 DEBUG : dupe2/two.txt: md5 = 374cdfe431120b46e5965c1c4f2f1345 OK 2026/11/04 02:53:30 DEBUG : dupe3/three.txt: md5 = 1ea127304a8963f0adacd692139b9f07 OK 2026/11/04 02:53:31 INFO : urj8gsducqbtvekeq181ntd2u8: merging "sfrom47189m9qr3mt4qonu281c" 2026/11/04 02:53:31 INFO : urj8gsducqbtvekeq181ntd2u8: removing empty directory 2026/11/04 02:53:32 INFO : 1u1r4ei7c628fnjg9blqt3j60o: merging "tr2hj63d80ftlmvm6a952snjcc" 2026/11/04 02:53:32 INFO : 1u1r4ei7c628fnjg9blqt3j60o: removing empty directory --- PASS: TestMergeDirs (13.14s) === RUN TestIndex run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 02:53:38 DEBUG : file1.txt: md5 = d2c764511ad39c6f79c38394efcaa3ef OK 2026/11/04 02:53:40 DEBUG : sub/file2.txt: md5 = c52d13fa426ceacee02f313c6ce26b63 OK 2026/11/04 02:53:42 DEBUG : sub/deep/file3.txt: md5 = e7253f5436c61ce97527e98f474ab0c3 OK 2026/11/04 02:53:45 DEBUG : index.html: md5 = 91789ffe4a56dfb85c34f6d0aa2e0d62 OK 2026/11/04 02:53:45 DEBUG : index.html: size = 12147 OK 2026/11/04 02:53:45 INFO : index.html: Copied (new) 2026/11/04 02:53:45 DEBUG : sub/index.html: md5 = afe25a7b7e7df4c5ac6b53c4abf34c58 OK 2026/11/04 02:53:45 DEBUG : sub/index.html: size = 12306 OK 2026/11/04 02:53:45 INFO : sub/index.html: Copied (new) 2026/11/04 02:53:45 DEBUG : caddy.json: md5 = 0c406cda3d2009807bdcb09f40869dda OK 2026/11/04 02:53:45 DEBUG : caddy.json: size = 259 OK 2026/11/04 02:53:45 INFO : caddy.json: Copied (new) 2026/11/04 02:53:45 DEBUG : index.json: md5 = abe201ddaeea9b0ca015cefc59321786 OK 2026/11/04 02:53:45 DEBUG : index.json: size = 213 OK 2026/11/04 02:53:45 INFO : index.json: Copied (new) 2026/11/04 02:53:46 DEBUG : sub/deep/index.html: md5 = f21e0d8ec9e0448bfd31a70a55a89cf4 OK 2026/11/04 02:53:46 DEBUG : sub/deep/index.html: size = 12087 OK 2026/11/04 02:53:46 INFO : sub/deep/index.html: Copied (new) 2026/11/04 02:53:46 DEBUG : sub/index.json: md5 = f8556785627d59dc416a54d56680412c OK 2026/11/04 02:53:46 DEBUG : sub/index.json: size = 215 OK 2026/11/04 02:53:46 INFO : sub/index.json: Copied (new) 2026/11/04 02:53:46 DEBUG : sub/caddy.json: md5 = af2b3427337d3895d020895920b060d1 OK 2026/11/04 02:53:46 DEBUG : sub/caddy.json: size = 261 OK 2026/11/04 02:53:46 INFO : sub/caddy.json: Copied (new) 2026/11/04 02:53:46 DEBUG : sub/deep/index.json: md5 = e748f80662779ce39115137bb744e14f OK 2026/11/04 02:53:46 DEBUG : sub/deep/index.json: size = 119 OK 2026/11/04 02:53:46 INFO : sub/deep/index.json: Copied (new) 2026/11/04 02:53:47 DEBUG : sub/deep/caddy.json: md5 = f9213e134e7f65f4cfc7b79453e6d093 OK 2026/11/04 02:53:47 DEBUG : sub/deep/caddy.json: size = 131 OK 2026/11/04 02:53:47 INFO : sub/deep/caddy.json: Copied (new) 2026/11/04 02:53:53 DEBUG : index.html: Unchanged skipping 2026/11/04 02:53:53 DEBUG : sub/index.html: Unchanged skipping 2026/11/04 02:53:53 DEBUG : sub/deep/index.html: Unchanged skipping 2026/11/04 02:53:53 DEBUG : index.json: Unchanged skipping 2026/11/04 02:53:54 DEBUG : sub/index.json: Unchanged skipping 2026/11/04 02:53:54 DEBUG : sub/deep/index.json: Unchanged skipping 2026/11/04 02:53:54 DEBUG : caddy.json: Unchanged skipping 2026/11/04 02:53:54 DEBUG : sub/caddy.json: Unchanged skipping 2026/11/04 02:53:54 DEBUG : sub/deep/caddy.json: Unchanged skipping 2026/11/04 02:53:56 DEBUG : index.json: md5 = e0b4464bcdad9380a3031f97d8b36260 OK 2026/11/04 02:53:56 DEBUG : index.json: size = 213 OK 2026/11/04 02:53:56 INFO : index.json: Copied (replaced existing) 2026/11/04 02:53:56 DEBUG : index.html: md5 = 85a8a95f25a3f0cf265593d226a96596 OK 2026/11/04 02:53:56 DEBUG : index.html: size = 12147 OK 2026/11/04 02:53:56 INFO : index.html: Copied (replaced existing) 2026/11/04 02:53:56 DEBUG : sub/deep/index.html: md5 = 2e33195103c3ede6fcfdab0981386248 OK 2026/11/04 02:53:56 DEBUG : sub/deep/index.html: size = 12087 OK 2026/11/04 02:53:56 INFO : sub/deep/index.html: Copied (replaced existing) 2026/11/04 02:53:56 DEBUG : caddy.json: md5 = 4bcbc9987135ed3ec2c920d2f7991324 OK 2026/11/04 02:53:56 DEBUG : caddy.json: size = 259 OK 2026/11/04 02:53:56 INFO : caddy.json: Copied (replaced existing) 2026/11/04 02:53:57 DEBUG : sub/deep/index.json: md5 = c8f44fdb11d55c0b2f7348e106cdea41 OK 2026/11/04 02:53:57 DEBUG : sub/deep/index.json: size = 119 OK 2026/11/04 02:53:57 INFO : sub/deep/index.json: Copied (replaced existing) 2026/11/04 02:53:57 DEBUG : sub/deep/caddy.json: md5 = acae1470ef21b3996cdf4399115ec25e OK 2026/11/04 02:53:57 DEBUG : sub/deep/caddy.json: size = 131 OK 2026/11/04 02:53:57 INFO : sub/deep/caddy.json: Copied (replaced existing) 2026/11/04 02:53:57 DEBUG : sub/index.html: md5 = aeef4e603185c3ac5fa3f2732075712c OK 2026/11/04 02:53:57 DEBUG : sub/index.html: size = 12306 OK 2026/11/04 02:53:57 INFO : sub/index.html: Copied (replaced existing) 2026/11/04 02:53:58 DEBUG : sub/index.json: md5 = 04f754d386761c23c90cac717b2d3e2c OK 2026/11/04 02:53:58 DEBUG : sub/index.json: size = 215 OK 2026/11/04 02:53:58 INFO : sub/index.json: Copied (replaced existing) 2026/11/04 02:53:59 DEBUG : sub/caddy.json: md5 = 93d4dfece1cc75dd4bad4331f7621f9e OK 2026/11/04 02:53:59 DEBUG : sub/caddy.json: size = 261 OK 2026/11/04 02:53:59 INFO : sub/caddy.json: Copied (replaced existing) 2026/11/04 02:54:00 DEBUG : sub/deep/file4.txt: md5 = 20d0846d950aab64aa15297de2457ef3 OK 2026/11/04 02:54:01 DEBUG : sub/index.html: Unchanged skipping 2026/11/04 02:54:01 DEBUG : index.html: Unchanged skipping 2026/11/04 02:54:02 DEBUG : sub/deep/index.json: md5 = c251445fe60485d143cb507b0db734f0 OK 2026/11/04 02:54:02 DEBUG : sub/deep/index.json: size = 235 OK 2026/11/04 02:54:02 INFO : sub/deep/index.json: Copied (replaced existing) 2026/11/04 02:54:02 DEBUG : sub/deep/index.html: md5 = 0edeca4f3c12f6726af157c8c7e04f9b OK 2026/11/04 02:54:02 DEBUG : sub/deep/index.html: size = 12346 OK 2026/11/04 02:54:02 INFO : sub/deep/index.html: Copied (replaced existing) 2026/11/04 02:54:02 DEBUG : sub/deep/caddy.json: md5 = 1ac023a6aff4ea2975c534282cc72700 OK 2026/11/04 02:54:02 DEBUG : sub/deep/caddy.json: size = 260 OK 2026/11/04 02:54:02 INFO : sub/deep/caddy.json: Copied (replaced existing) 2026/11/04 02:54:02 DEBUG : index.json: Unchanged skipping 2026/11/04 02:54:02 DEBUG : sub/index.json: Unchanged skipping 2026/11/04 02:54:03 DEBUG : caddy.json: Unchanged skipping 2026/11/04 02:54:03 DEBUG : sub/caddy.json: Unchanged skipping 2026/11/04 02:54:05 DEBUG : sub/deep/file5.txt: md5 = e5d77244334db2551368d4c2379d8a9f OK 2026/11/04 02:54:06 NOTICE: sub/deep/index.html: Skipped copy as --dry-run is set (size 12.309Ki) 2026/11/04 02:54:06 NOTICE: sub/deep/index.json: Skipped copy as --dry-run is set (size 351) 2026/11/04 02:54:06 NOTICE: sub/deep/caddy.json: Skipped copy as --dry-run is set (size 389) 2026/11/04 02:54:06 DEBUG : index.html: Unchanged skipping 2026/11/04 02:54:06 DEBUG : sub/index.html: Unchanged skipping 2026/11/04 02:54:07 DEBUG : sub/index.json: Unchanged skipping 2026/11/04 02:54:07 DEBUG : index.json: Unchanged skipping 2026/11/04 02:54:08 DEBUG : caddy.json: Unchanged skipping 2026/11/04 02:54:08 DEBUG : sub/caddy.json: Unchanged skipping --- PASS: TestIndex (40.41s) === RUN TestIndexDelete run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 02:54:19 DEBUG : file1.txt: md5 = d66be4a9787b7693c5dc5e1200da9a69 OK 2026/11/04 02:54:21 DEBUG : sub/file2.txt: md5 = 669010b7bc0800d821574535c984893d OK 2026/11/04 02:54:23 DEBUG : sub/deep/file3.txt: md5 = ad1e5bbae6d7ced34a83df6d5dce81d6 OK 2026/11/04 02:54:25 DEBUG : sub/index.html: md5 = f57abda2c908f5031ca8959573320330 OK 2026/11/04 02:54:25 DEBUG : sub/index.html: size = 12306 OK 2026/11/04 02:54:25 INFO : sub/index.html: Copied (new) 2026/11/04 02:54:25 DEBUG : index.html: md5 = 5a771b06d1245ee991ca026431b545fa OK 2026/11/04 02:54:25 DEBUG : index.html: size = 12147 OK 2026/11/04 02:54:25 INFO : index.html: Copied (new) 2026/11/04 02:54:25 DEBUG : sub/deep/index.html: md5 = 68e371facd3b99617008bf98a7929ae5 OK 2026/11/04 02:54:25 DEBUG : sub/deep/index.html: size = 12087 OK 2026/11/04 02:54:25 INFO : sub/deep/index.html: Copied (new) 2026/11/04 02:54:28 DEBUG : index.html: Unchanged skipping 2026/11/04 02:54:28 DEBUG : sub/index.html: Unchanged skipping 2026/11/04 02:54:28 DEBUG : sub/deep/index.html: md5 = 1c68d87522b99fbd7c7aec0053dab5af OK 2026/11/04 02:54:28 DEBUG : sub/deep/index.html: size = 11829 OK 2026/11/04 02:54:28 INFO : sub/deep/index.html: Copied (replaced existing) --- PASS: TestIndexDelete (18.24s) === RUN TestIndexFilter run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 02:54:37 DEBUG : file1.txt: md5 = e9fa0244104d07cc0165b304c964f3c8 OK 2026/11/04 02:54:39 DEBUG : sub/file2.txt: md5 = ebc49ba31bfb69fd1123868f2cd670ea OK 2026/11/04 02:54:41 DEBUG : sub/deep/file3.txt: md5 = 80cc8e999ce3b495eb6b54912a82d3b5 OK 2026/11/04 02:54:43 DEBUG : sub/deep/index.html: md5 = 33616e91ada32e49d30461c2cf313025 OK 2026/11/04 02:54:45 DEBUG : other/notes.md: md5 = 2f629b563e098401961a40553cd3fc0b OK 2026/11/04 02:54:45 DEBUG : other/notes.md: Excluded (Path Filter) 2026/11/04 02:54:47 DEBUG : index.html: md5 = ffe1b1b3aae48efff292cd05ab5544e6 OK 2026/11/04 02:54:47 DEBUG : index.html: size = 12399 OK 2026/11/04 02:54:47 INFO : index.html: Copied (new) 2026/11/04 02:54:47 DEBUG : sub/index.html: md5 = 2779e78d7c13088148db07edd9ade23c OK 2026/11/04 02:54:47 DEBUG : sub/index.html: size = 12056 OK 2026/11/04 02:54:47 INFO : sub/index.html: Copied (new) 2026/11/04 02:54:47 DEBUG : other/index.html: md5 = cd7917a2897bab1c030dedb0324f1ba4 OK 2026/11/04 02:54:47 DEBUG : other/index.html: size = 11802 OK 2026/11/04 02:54:47 INFO : other/index.html: Copied (new) 2026/11/04 02:54:52 DEBUG : other/notes.md: Excluded (Path Filter) 2026/11/04 02:54:53 DEBUG : sub/index.html: Unchanged skipping 2026/11/04 02:54:53 DEBUG : index.html: Unchanged skipping 2026/11/04 02:54:53 DEBUG : other/index.html: Unchanged skipping --- PASS: TestIndexFilter (23.93s) === RUN TestIndexRules run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 02:55:01 DEBUG : file1.txt: md5 = 7ab58785b6de53e5a70d0e069fa69220 OK 2026/11/04 02:55:03 DEBUG : sub/file2.txt: md5 = 5c613f8971a8f085ba461d8af5c87d1e OK 2026/11/04 02:55:05 DEBUG : sub/deep/file3.txt: md5 = 0f32e65e9836dc2b48c2271c68970d6b OK 2026/11/04 02:55:07 DEBUG : sub/deep/index.html: md5 = 7cb0be6444f1525843d93367071852b6 OK 2026/11/04 02:55:09 DEBUG : index.html: md5 = 4faeeb4db6ff93d3a3e0180942598fb6 OK 2026/11/04 02:55:09 DEBUG : index.html: size = 12147 OK 2026/11/04 02:55:09 INFO : index.html: Copied (new) 2026/11/04 02:55:09 DEBUG : sub/index.html: md5 = 0c8dc8533a80871b95fb67eccb7d94c8 OK 2026/11/04 02:55:09 DEBUG : sub/index.html: size = 12306 OK 2026/11/04 02:55:09 INFO : sub/index.html: Copied (new) 2026/11/04 02:55:13 DEBUG : sub/index.html: Unchanged skipping 2026/11/04 02:55:14 DEBUG : sub/deep/index.html: md5 = a26deff81c09cf4e679ef0b0ebd4ea67 OK 2026/11/04 02:55:14 DEBUG : sub/deep/index.html: size = 12087 OK 2026/11/04 02:55:14 INFO : sub/deep/index.html: Copied (replaced existing) --- PASS: TestIndexRules (21.30s) === RUN TestIndexMaxDepth run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 02:55:22 DEBUG : file1.txt: md5 = 61a779a603a602d9f5d1850e40602615 OK 2026/11/04 02:55:24 DEBUG : sub/file2.txt: md5 = 04879354858bbd2daf8a9e5a9df6be21 OK 2026/11/04 02:55:27 DEBUG : sub/deep/file3.txt: md5 = 7ab7364e2504c3b3b990d9c913775e52 OK 2026/11/04 02:55:29 DEBUG : index.html: md5 = a7475d1ef3201966d97d0d442beec7ac OK 2026/11/04 02:55:29 DEBUG : index.html: size = 12147 OK 2026/11/04 02:55:29 INFO : index.html: Copied (new) --- PASS: TestIndexMaxDepth (14.03s) === RUN TestIndexLinkIndex run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 02:55:36 DEBUG : file1.txt: md5 = 4142d8db77f3ed04671a7615dc8186ec OK 2026/11/04 02:55:38 DEBUG : sub/file2.txt: md5 = 69a4110f3aa02e05fd93a2e42c15f364 OK 2026/11/04 02:55:40 DEBUG : sub/deep/file3.txt: md5 = a3732b90ff0c483baa92e994b7f74f89 OK 2026/11/04 02:55:43 DEBUG : index.html: md5 = 845a7d3d20aab69f1a084508f759bb16 OK 2026/11/04 02:55:43 DEBUG : index.html: size = 12167 OK 2026/11/04 02:55:43 INFO : index.html: Copied (new) 2026/11/04 02:55:43 DEBUG : sub/deep/index.html: md5 = 0553c9ddd0d9cd4364f6ecdffc46a25c OK 2026/11/04 02:55:43 DEBUG : sub/deep/index.html: size = 12128 OK 2026/11/04 02:55:43 INFO : sub/deep/index.html: Copied (new) 2026/11/04 02:55:43 DEBUG : sub/index.html: md5 = a4cafae1aed414f19412e761526503d1 OK 2026/11/04 02:55:43 DEBUG : sub/index.html: size = 12347 OK 2026/11/04 02:55:43 INFO : sub/index.html: Copied (new) --- PASS: TestIndexLinkIndex (15.85s) === RUN TestIndexDirTime run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 02:55:52 DEBUG : file1.txt: md5 = a3855c2c2dbcc1d9fcd4eb58d7071193 OK 2026/11/04 02:55:54 DEBUG : sub/file2.txt: md5 = 4792b77d712c1be703634b0303062ab1 OK 2026/11/04 02:55:56 DEBUG : sub/deep/file3.txt: md5 = 584715cad1fd272aa3bcbcc122140570 OK 2026/11/04 02:55:58 DEBUG : sub/deep/index.json: md5 = 921ff58c5d41483410a438ba1c2e9a8f OK 2026/11/04 02:55:58 DEBUG : sub/deep/index.json: size = 139 OK 2026/11/04 02:55:58 INFO : sub/deep/index.json: Copied (new) 2026/11/04 02:55:58 DEBUG : sub/index.json: md5 = 916c032e367beac689973517ce1c09b8 OK 2026/11/04 02:55:58 DEBUG : sub/index.json: size = 259 OK 2026/11/04 02:55:58 INFO : sub/index.json: Copied (new) 2026/11/04 02:55:59 DEBUG : index.json: md5 = 1740f88804295eb5180ede33e58a57c9 OK 2026/11/04 02:55:59 DEBUG : index.json: size = 257 OK 2026/11/04 02:55:59 INFO : index.json: Copied (new) 2026/11/04 02:56:01 DEBUG : sub/deep/index.json: Unchanged skipping 2026/11/04 02:56:02 DEBUG : sub/index.json: md5 = a481c47611d4714a3a4b7f88a5580167 OK 2026/11/04 02:56:02 DEBUG : sub/index.json: size = 239 OK 2026/11/04 02:56:02 INFO : sub/index.json: Copied (replaced existing) 2026/11/04 02:56:02 DEBUG : index.json: md5 = ac2e3f415340ad79e0e9e8351485ef20 OK 2026/11/04 02:56:02 DEBUG : index.json: size = 237 OK 2026/11/04 02:56:02 INFO : index.json: Copied (replaced existing) 2026/11/04 02:56:05 DEBUG : sub/deep/index.json: md5 = a2d40fb5d9533e6c4eddee88ae4fcfc4 OK 2026/11/04 02:56:05 DEBUG : sub/deep/index.json: size = 119 OK 2026/11/04 02:56:05 INFO : sub/deep/index.json: Copied (replaced existing) 2026/11/04 02:56:05 DEBUG : index.json: md5 = 82b81b3526870733e42e65d23def31b2 OK 2026/11/04 02:56:05 DEBUG : index.json: size = 213 OK 2026/11/04 02:56:05 INFO : index.json: Copied (replaced existing) 2026/11/04 02:56:05 DEBUG : sub/index.json: md5 = 72d0a5845decc7dfce0563dd1c83ee4a OK 2026/11/04 02:56:05 DEBUG : sub/index.json: size = 215 OK 2026/11/04 02:56:05 INFO : sub/index.json: Copied (replaced existing) --- PASS: TestIndexDirTime (20.25s) === RUN TestIndexTemplate run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 02:56:12 DEBUG : file1.txt: md5 = 1e78dccb265ca5ffec925f50a36c3e28 OK 2026/11/04 02:56:14 DEBUG : sub/file2.txt: md5 = f858537f85dea1f95a2f3bd05f449ae8 OK 2026/11/04 02:56:17 DEBUG : sub/deep/file3.txt: md5 = e69245e59fc863f9dec645cfdf2d940d OK 2026/11/04 02:56:19 DEBUG : list.txt: md5 = a899a6dbcdf9aab16235a891bfb7a6b6 OK 2026/11/04 02:56:19 DEBUG : list.txt: size = 59 OK 2026/11/04 02:56:19 INFO : list.txt: Copied (new) 2026/11/04 02:56:19 DEBUG : sub/deep/list.txt: md5 = 3419cea721d8d11d021976b44ba6efd6 OK 2026/11/04 02:56:19 DEBUG : sub/deep/list.txt: size = 47 OK 2026/11/04 02:56:19 INFO : sub/deep/list.txt: Copied (new) 2026/11/04 02:56:19 DEBUG : sub/list.txt: md5 = 500cc02a1a5b0ce7ffc2399acee8ae7f OK 2026/11/04 02:56:19 DEBUG : sub/list.txt: size = 64 OK 2026/11/04 02:56:19 INFO : sub/list.txt: Copied (new) --- PASS: TestIndexTemplate (14.34s) === 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-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 02:56:26 DEBUG : file1.txt: md5 = 5f464dd6cb2cc3a2c131746c6e41b238 OK 2026/11/04 02:56:29 DEBUG : sub/file2.txt: md5 = b574b674078436ddf847ce1d33bdc7b0 OK 2026/11/04 02:56:31 DEBUG : sub/deep/file3.txt: md5 = 4af902bce7067c1a08dce018b8536c62 OK 2026/11/04 02:56:33 DEBUG : other/notes.md: md5 = 0920948b760e221390dee4372cadf1a7 OK 2026/11/04 02:56:35 DEBUG : sub/deep/index.html: md5 = 5e63f9154fc3d65a959a626018fd185c OK 2026/11/04 02:56:35 DEBUG : sub/deep/index.html: size = 12087 OK 2026/11/04 02:56:35 INFO : sub/deep/index.html: Copied (new) 2026/11/04 02:56:35 DEBUG : index.html: md5 = b4254cb86407faa58020b61286ad5822 OK 2026/11/04 02:56:35 DEBUG : index.html: size = 12399 OK 2026/11/04 02:56:35 INFO : index.html: Copied (new) 2026/11/04 02:56:35 DEBUG : other/index.html: md5 = abc13d49e7cb36f55c2e98454b2db673 OK 2026/11/04 02:56:35 DEBUG : other/index.html: size = 12058 OK 2026/11/04 02:56:35 INFO : other/index.html: Copied (new) 2026/11/04 02:56:35 DEBUG : sub/index.html: md5 = a460c48536b38bbd0965690dbd0f6893 OK 2026/11/04 02:56:35 DEBUG : sub/index.html: size = 12306 OK 2026/11/04 02:56:35 INFO : sub/index.html: Copied (new) 2026/11/04 02:56:36 DEBUG : sub/deep/file4.txt: md5 = 5a7c07943f45fb1ded6868c08b2996c8 OK 2026/11/04 02:56:38 DEBUG : other/undeclared.txt: md5 = d82dc96debeffac5ca967c7d87187ae7 OK 2026/11/04 02:56:38 INFO : Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano': Partial index: listing 3 directories and walking 0 changed directories 2026/11/04 02:56:39 DEBUG : index.html: Unchanged skipping 2026/11/04 02:56:39 DEBUG : sub/index.html: Unchanged skipping 2026/11/04 02:56:40 DEBUG : sub/deep/index.html: md5 = c5535941d58523168290ecf6c73c6087 OK 2026/11/04 02:56:40 DEBUG : sub/deep/index.html: size = 12346 OK 2026/11/04 02:56:40 INFO : sub/deep/index.html: Copied (replaced existing) 2026/11/04 02:56:44 DEBUG : sub/new/file5.txt: md5 = 3b2b901ca60977a481a937047710ab19 OK 2026/11/04 02:56:44 INFO : Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano': Partial index: listing 3 directories and walking 0 changed directories 2026/11/04 02:56:45 DEBUG : index.html: Unchanged skipping 2026/11/04 02:56:46 DEBUG : sub/index.html: md5 = fd864f7966ddef202dd216b4fbce72ba OK 2026/11/04 02:56:46 DEBUG : sub/index.html: size = 12554 OK 2026/11/04 02:56:46 INFO : sub/index.html: Copied (replaced existing) 2026/11/04 02:56:46 DEBUG : sub/new/index.html: md5 = ae1b0ef53c793164b204b769fae706db OK 2026/11/04 02:56:46 DEBUG : sub/new/index.html: size = 12085 OK 2026/11/04 02:56:46 INFO : sub/new/index.html: Copied (new) 2026/11/04 02:56:49 DEBUG : other/undeclared0.txt: md5 = ae5d1f9af480afe0cc8d0874b27dafb3 OK 2026/11/04 02:56:49 INFO : Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano': Partial index: listing 1 directories and walking 1 changed directories 2026/11/04 02:56:50 DEBUG : index.html: Unchanged skipping 2026/11/04 02:56:51 DEBUG : other/index.html: md5 = 09351e1c88d8cb7322aad0955f810ab7 OK 2026/11/04 02:56:51 DEBUG : other/index.html: size = 12597 OK 2026/11/04 02:56:51 INFO : other/index.html: Copied (replaced existing) 2026/11/04 02:56:53 DEBUG : other/undeclared1.txt: md5 = a05923aec67a6d497c6ff70b34449255 OK 2026/11/04 02:56:53 INFO : Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano': Partial index: listing 1 directories and walking 0 changed directories 2026/11/04 02:56:54 DEBUG : index.html: Unchanged skipping 2026/11/04 02:56:55 DEBUG : other/index.html: md5 = ac59417b38081bccb71a65e6bec91f3d OK 2026/11/04 02:56:55 DEBUG : other/index.html: size = 12867 OK 2026/11/04 02:56:55 INFO : other/index.html: Copied (replaced existing) 2026/11/04 02:56:57 DEBUG : sub/file6.txt: md5 = 773cea88bb4cbdb9d3ae640909997446 OK 2026/11/04 02:56:57 INFO : Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano': Nothing changed so nothing to index 2026/11/04 02:56:58 INFO : Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano': Root changed so doing a full index 2026/11/04 02:56:59 DEBUG : index.html: Unchanged skipping 2026/11/04 02:56:59 DEBUG : sub/deep/index.html: Unchanged skipping 2026/11/04 02:56:59 DEBUG : sub/new/index.html: Unchanged skipping 2026/11/04 02:56:59 DEBUG : other/index.html: Unchanged skipping 2026/11/04 02:57:00 DEBUG : sub/index.html: md5 = 4bc38c4f2cf91c0f36b3b6428e8e8510 OK 2026/11/04 02:57:00 DEBUG : sub/index.html: size = 12813 OK 2026/11/04 02:57:00 INFO : sub/index.html: Copied (replaced existing) 2026/11/04 02:57:02 DEBUG : sub/file7.txt: md5 = 4131db56a553fbf8079a310fe201847d OK 2026/11/04 02:57:02 INFO : Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano': Partial index would list 2 directories, more than 1, so doing a full index 2026/11/04 02:57:04 DEBUG : sub/new/index.html: Unchanged skipping 2026/11/04 02:57:04 DEBUG : index.html: Unchanged skipping 2026/11/04 02:57:04 DEBUG : other/index.html: Unchanged skipping 2026/11/04 02:57:04 DEBUG : sub/deep/index.html: Unchanged skipping 2026/11/04 02:57:04 DEBUG : sub/index.html: md5 = d9af873e9ad09ef94f4177fd1ed64d50 OK 2026/11/04 02:57:04 DEBUG : sub/index.html: size = 13071 OK 2026/11/04 02:57:04 INFO : sub/index.html: Copied (replaced existing) 2026/11/04 02:57:07 DEBUG : sub/deep/file8.txt: md5 = 31d2ad6e61b53e4de6a0410de55e4292 OK 2026/11/04 02:57:07 INFO : Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano': Partial index: listing 1 directories and walking 1 changed directories 2026/11/04 02:57:08 DEBUG : index.html: Unchanged skipping 2026/11/04 02:57:08 DEBUG : sub/new/index.html: Unchanged skipping 2026/11/04 02:57:08 DEBUG : sub/index.html: Unchanged skipping 2026/11/04 02:57:09 DEBUG : sub/deep/index.html: md5 = 8e27b9ba37c5509ac4c9a94b8ed28540 OK 2026/11/04 02:57:09 DEBUG : sub/deep/index.html: size = 12604 OK 2026/11/04 02:57:09 INFO : sub/deep/index.html: Copied (replaced existing) 2026/11/04 02:57:11 DEBUG : sub/deep/file9.txt: md5 = 56540b726b7ca3ce1f468a3a575422b9 OK 2026/11/04 02:57:13 DEBUG : sub/file10.txt: md5 = af86c08e5f93ece956d7a25a3bc879f6 OK 2026/11/04 02:57:13 INFO : Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano': Partial index: listing 2 directories and walking 0 changed directories 2026/11/04 02:57:14 DEBUG : index.html: Unchanged skipping 2026/11/04 02:57:14 DEBUG : sub/index.html: md5 = a23e54a8db348514e4984b597e9832c9 OK 2026/11/04 02:57:14 DEBUG : sub/index.html: size = 13331 OK 2026/11/04 02:57:14 INFO : sub/index.html: Copied (replaced existing) --- PASS: TestIndexChanged (63.98s) === RUN TestIndexChangedDelete run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 02:57:30 DEBUG : file1.txt: md5 = c4e16436d5da472db8a0a8c875c46a18 OK 2026/11/04 02:57:33 DEBUG : sub/file2.txt: md5 = f78f5c8ecf16558d35128038912789ed OK 2026/11/04 02:57:35 DEBUG : sub/deep/file3.txt: md5 = 92efb833957062d3a1732568356c5d4a OK 2026/11/04 02:57:37 DEBUG : sub/deep/index.html: md5 = 93193b10533f8b0d001bb8443184a7e3 OK 2026/11/04 02:57:37 DEBUG : sub/deep/index.html: size = 12087 OK 2026/11/04 02:57:37 INFO : sub/deep/index.html: Copied (new) 2026/11/04 02:57:37 DEBUG : sub/index.html: md5 = cfa3fdb904d0c736187bdfe16b54b2bd OK 2026/11/04 02:57:37 DEBUG : sub/index.html: size = 12306 OK 2026/11/04 02:57:37 INFO : sub/index.html: Copied (new) 2026/11/04 02:57:37 DEBUG : index.html: md5 = 45e6b8461dd764ca6145abb2d4520b6b OK 2026/11/04 02:57:37 DEBUG : index.html: size = 12147 OK 2026/11/04 02:57:37 INFO : index.html: Copied (new) 2026/11/04 02:57:38 INFO : Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano': Partial index: listing 3 directories and walking 0 changed directories 2026/11/04 02:57:39 DEBUG : index.html: Unchanged skipping 2026/11/04 02:57:39 DEBUG : sub/index.html: Unchanged skipping 2026/11/04 02:57:40 DEBUG : sub/deep/index.html: md5 = 65f56f970c579182e4a74762513a17d2 OK 2026/11/04 02:57:40 DEBUG : sub/deep/index.html: size = 11829 OK 2026/11/04 02:57:40 INFO : sub/deep/index.html: Copied (replaced existing) --- PASS: TestIndexChangedDelete (16.40s) === RUN TestIndexChangedFiles run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 02:57:47 DEBUG : file1.txt: md5 = 27692afc5d1a6bb01e4db782e224eb40 OK 2026/11/04 02:57:49 DEBUG : sub/file2.txt: md5 = 75153557f8df50f3095f3a572cd3878d OK 2026/11/04 02:57:51 DEBUG : sub/deep/file3.txt: md5 = 3ce7634a17da30861b23f56af6745749 OK 2026/11/04 02:57:53 DEBUG : - odd/file.txt: md5 = 87b59b0954cd8e911d97b9b1c8711907 OK 2026/11/04 02:57:55 DEBUG : index.html: md5 = 76a207a97f90ccd8a1f394aa71716136 OK 2026/11/04 02:57:55 DEBUG : index.html: size = 12401 OK 2026/11/04 02:57:55 INFO : index.html: Copied (new) 2026/11/04 02:57:55 DEBUG : sub/deep/index.html: md5 = adb4877a919c077056e8ae5c4d3f36d6 OK 2026/11/04 02:57:55 DEBUG : sub/deep/index.html: size = 12087 OK 2026/11/04 02:57:55 INFO : sub/deep/index.html: Copied (new) 2026/11/04 02:57:55 DEBUG : - odd/index.html: md5 = 87d04e6ca6a928c6831f9f88de5b6899 OK 2026/11/04 02:57:55 DEBUG : - odd/index.html: size = 12058 OK 2026/11/04 02:57:55 INFO : - odd/index.html: Copied (new) 2026/11/04 02:57:56 DEBUG : sub/index.html: md5 = 1fb0c04044aa83929f0a46f5f2f65533 OK 2026/11/04 02:57:56 DEBUG : sub/index.html: size = 12306 OK 2026/11/04 02:57:56 INFO : sub/index.html: Copied (new) 2026/11/04 02:57:57 DEBUG : - odd/file8.txt: md5 = 980dfd4800bf1ad434a39a32de592b6c OK 2026/11/04 02:57:58 DEBUG : sub/file9.txt: md5 = 40223282b051f178f41cc251d9747a1e OK 2026/11/04 02:57:58 INFO : Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano': Partial index: listing 2 directories and walking 0 changed directories 2026/11/04 02:57:59 DEBUG : index.html: Unchanged skipping 2026/11/04 02:58:00 DEBUG : - odd/index.html: md5 = 31672c62260e67b1d29bb6431651996c OK 2026/11/04 02:58:00 DEBUG : - odd/index.html: size = 12316 OK 2026/11/04 02:58:00 INFO : - odd/index.html: Copied (replaced existing) 2026/11/04 02:58:02 INFO : Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano': Partial index: listing 4 directories and walking 0 changed directories 2026/11/04 02:58:03 DEBUG : - odd/index.html: Unchanged skipping 2026/11/04 02:58:03 DEBUG : index.html: Unchanged skipping 2026/11/04 02:58:03 DEBUG : sub/deep/index.html: Unchanged skipping 2026/11/04 02:58:04 DEBUG : sub/index.html: md5 = ac67eddff3ab39648042c79c6a203463 OK 2026/11/04 02:58:04 DEBUG : sub/index.html: size = 12565 OK 2026/11/04 02:58:04 INFO : sub/index.html: Copied (replaced existing) --- PASS: TestIndexChangedFiles (27.14s) === RUN TestIndexChangedDirTime run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 02:58:14 DEBUG : file1.txt: md5 = 92c02ed8ca6f80e7aa5647b34177f8f1 OK 2026/11/04 02:58:16 DEBUG : sub/file2.txt: md5 = 2dcdaa7043b2bee272bc4fe564f57c50 OK 2026/11/04 02:58:18 DEBUG : sub/deep/file3.txt: md5 = 51000449d5a2b90d0ca878d2bb7cf315 OK 2026/11/04 02:58:21 DEBUG : other/notes.md: md5 = c237c8e21b6b73a76784fe44dbf2ee7c OK 2026/11/04 02:58:23 DEBUG : sub/deep/index.json: md5 = 7b5b8c2dfc1d5d6774bba9a99dc869ae OK 2026/11/04 02:58:23 DEBUG : sub/deep/index.json: size = 139 OK 2026/11/04 02:58:23 INFO : sub/deep/index.json: Copied (new) 2026/11/04 02:58:23 DEBUG : index.json: md5 = 4a1e07b99b836b528b735c3df01861f4 OK 2026/11/04 02:58:23 DEBUG : index.json: size = 379 OK 2026/11/04 02:58:23 INFO : index.json: Copied (new) 2026/11/04 02:58:23 DEBUG : other/index.json: md5 = 7df835f945a7f336b7796d5ca2feb27f OK 2026/11/04 02:58:23 DEBUG : other/index.json: size = 144 OK 2026/11/04 02:58:23 INFO : other/index.json: Copied (new) 2026/11/04 02:58:23 DEBUG : sub/index.json: md5 = 831260bd3a7cd58d316101a450182c61 OK 2026/11/04 02:58:23 DEBUG : sub/index.json: size = 259 OK 2026/11/04 02:58:23 INFO : sub/index.json: Copied (new) 2026/11/04 02:58:24 DEBUG : sub/deep/file4.txt: md5 = e30e4f51c57d1fda1082f437dddced5a OK 2026/11/04 02:58:24 INFO : Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano': Partial index: listing 3 directories and walking 0 changed directories 2026/11/04 02:58:26 DEBUG : sub/deep/index.json: md5 = 5d7d54df8a9bc92932961394c0f391a5 OK 2026/11/04 02:58:26 DEBUG : sub/deep/index.json: size = 275 OK 2026/11/04 02:58:26 INFO : sub/deep/index.json: Copied (replaced existing) 2026/11/04 02:58:27 DEBUG : index.json: md5 = 647bbc37736f1d313cb221e7d980a610 OK 2026/11/04 02:58:27 DEBUG : index.json: size = 379 OK 2026/11/04 02:58:27 INFO : index.json: Copied (replaced existing) 2026/11/04 02:58:27 DEBUG : sub/index.json: md5 = 7a6bd2a2052fefbca3b434b6a3ab3933 OK 2026/11/04 02:58:27 DEBUG : sub/index.json: size = 259 OK 2026/11/04 02:58:27 INFO : sub/index.json: Copied (replaced existing) --- PASS: TestIndexChangedDirTime (22.77s) === RUN TestListDirSorted run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 02:58:37 DEBUG : a.txt: md5 = 3f202831f59ae56baf2a11db70706b49 OK 2026/11/04 02:58:38 DEBUG : zend.txt: md5 = 62219c73a6ee592e834bf80f421c716b OK 2026/11/04 02:58:40 DEBUG : sub dir/hello world: md5 = 18d19c2d18aeaeb15a765da6f8a0c9d2 OK 2026/11/04 02:58:42 DEBUG : sub dir/hello world2: md5 = 468bda6daae5e839ef0e64f0a112d9f7 OK 2026/11/04 02:58:44 DEBUG : sub dir/ignore dir/.ignore: md5 = 009f6336467293a6a93bb90100b35b5f OK 2026/11/04 02:58:46 DEBUG : sub dir/ignore dir/should be ignored: md5 = 3f32ab3eae5bf4f6ef990c5b4fea8dc2 OK 2026/11/04 02:58:48 DEBUG : sub dir/sub sub dir/hello world3: md5 = a098055368444a4d4376598f561aa288 OK 2026/11/04 02:58:49 DEBUG : a.txt: Excluded (Size Filter) 2026/11/04 02:58:49 DEBUG : a.txt: Excluded 2026/11/04 02:58:50 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/11/04 02:58:50 DEBUG : sub dir/hello world2: Excluded 2026/11/04 02:58:50 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/11/04 02:58:50 DEBUG : sub dir/hello world: Excluded 2026/11/04 02:58:50 DEBUG : sub dir/ignore dir: Excluded 2026/11/04 02:58:50 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/11/04 02:58:50 DEBUG : sub dir/hello world2: Excluded 2026/11/04 02:58:50 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/11/04 02:58:50 DEBUG : sub dir/hello world: Excluded 2026/11/04 02:58:51 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSorted (22.35s) === RUN TestListDirSortedFn run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 02:58:59 DEBUG : a.txt: md5 = ed47c7c78001fba1787dfc282ce553d6 OK 2026/11/04 02:59:01 DEBUG : zend.txt: md5 = 05633fb5600e017485563169edf5088c OK 2026/11/04 02:59:03 DEBUG : sub dir/hello world: md5 = 62e78033d75675a73f36934b2dc16175 OK 2026/11/04 02:59:04 DEBUG : sub dir/hello world2: md5 = 11b7216d0432bf4c6fa2b9510381c156 OK 2026/11/04 02:59:06 DEBUG : sub dir/ignore dir/.ignore: md5 = 8ff610b35cdbc33feef79c219e9085bb OK 2026/11/04 02:59:08 DEBUG : sub dir/ignore dir/should be ignored: md5 = 8014c1efcfe788f0063863fff6f1a746 OK 2026/11/04 02:59:10 DEBUG : sub dir/sub sub dir/hello world3: md5 = 2e54e7fb44d7651e750f0679757b664f OK 2026/11/04 02:59:11 DEBUG : a.txt: Excluded (Size Filter) 2026/11/04 02:59:11 DEBUG : a.txt: Excluded 2026/11/04 02:59:12 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/11/04 02:59:12 DEBUG : sub dir/hello world: Excluded 2026/11/04 02:59:12 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/11/04 02:59:12 DEBUG : sub dir/hello world2: Excluded 2026/11/04 02:59:13 DEBUG : sub dir/ignore dir: Excluded 2026/11/04 02:59:13 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/11/04 02:59:13 DEBUG : sub dir/hello world: Excluded 2026/11/04 02:59:13 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/11/04 02:59:13 DEBUG : sub dir/hello world2: Excluded 2026/11/04 02:59:13 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSortedFn (21.87s) === RUN TestListJSON run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 02:59:21 DEBUG : file1: md5 = 5b634cf5db99313bfff1e9049cffbeae OK 2026/11/04 02:59:23 DEBUG : sub/file2: md5 = f0fad7f2f02a3cc0b88211fc9db4877a 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/11/04 02:59:26 ERROR : file1: Failed to read hash: hash type not supported === RUN TestListJSON/Metadata 2026/11/04 02:59:26 DEBUG : eer8kka55qnghc34cq76ca668g: Fetching metadata 2026/11/04 02:59:27 DEBUG : 150fuo3cn4j1uenq6r4g4qk6t4: Fetching metadata --- PASS: TestListJSON (9.62s) --- PASS: TestListJSON/Default (0.25s) --- PASS: TestListJSON/FilesOnly (0.26s) --- PASS: TestListJSON/DirsOnly (0.26s) --- PASS: TestListJSON/Recurse (0.47s) --- PASS: TestListJSON/SubDir (0.25s) --- PASS: TestListJSON/NoModTime (0.25s) --- PASS: TestListJSON/NoMimeType (0.25s) --- PASS: TestListJSON/ShowHash (0.23s) --- PASS: TestListJSON/HashTypes (0.25s) --- PASS: TestListJSON/Metadata (0.63s) === RUN TestStatJSON run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 02:59:31 DEBUG : file1: md5 = 8916b738d58953e4e5f4bee2292dd391 OK 2026/11/04 02:59:33 DEBUG : sub/file2: md5 = 08f4ea5f8834787b5a6e58bc84c1700b 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/11/04 02:59:37 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-birusev6cano/notfound" 2026/11/04 02:59:37 DEBUG : Creating backend with remote "TestDrive:crypt/ruedvu180ro2fcktkdj2gabtvu3sr8pt7hsglppuerfam48l4v10/qe0i31qdkoejk60elps0ni5sqk" --- PASS: TestStatJSON (11.93s) --- PASS: TestStatJSON/Root (0.28s) --- PASS: TestStatJSON/RootFilesOnly (0.00s) --- PASS: TestStatJSON/RootDirsOnly (0.33s) --- PASS: TestStatJSON/Dir (0.48s) --- PASS: TestStatJSON/DirWithTrailingSlash (0.22s) --- PASS: TestStatJSON/File (0.24s) --- PASS: TestStatJSON/NotFound (0.46s) --- PASS: TestStatJSON/DirFilesOnly (0.22s) --- PASS: TestStatJSON/FileFilesOnly (0.24s) --- PASS: TestStatJSON/NotFoundFilesOnly (0.31s) --- PASS: TestStatJSON/DirDirsOnly (0.24s) --- PASS: TestStatJSON/FileDirsOnly (0.24s) --- PASS: TestStatJSON/NotFoundDirsOnly (0.26s) --- PASS: TestStatJSON/RootNotFound (1.75s) === RUN TestStatJSONMemory 2026/11/04 02:59:41 DEBUG : Creating backend with remote ":memory:" 2026/11/04 02:59:41 DEBUG : Memory root '': File to upload is small (5 bytes), uploading instead of streaming 2026/11/04 02:59:41 DEBUG : sub/file1: size = 5 OK 2026/11/04 02:59:41 DEBUG : sub/file1: md5 = 5d41402abc4b2a76b9719d911017c592 OK 2026/11/04 02:59:41 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-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 02:59:41 INFO : Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano': Making directory 2026/11/04 02:59:41 INFO : Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano': Making directory --- PASS: TestMkdir (0.66s) === RUN TestLsd run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 02:59:44 DEBUG : sub dir/hello world: md5 = 4d05acb23cda58b10129faae624e8c1d OK --- PASS: TestLsd (5.01s) === RUN TestLs run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 02:59:48 DEBUG : potato2: md5 = cb0d558797e08b0ee8a4a1187f76b3e4 OK 2026/11/04 02:59:49 DEBUG : empty space: md5 = 8c30cbc36af7a725e6ad1353aa64ccf1 OK --- PASS: TestLs (4.69s) === RUN TestLsWithFilesFrom run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 02:59:53 DEBUG : potato2: md5 = cbac0101d86ce80cbf369390e4551a9e OK 2026/11/04 02:59:54 DEBUG : empty space: md5 = be9a246e442ca119312f33a1d757de3b OK 2026/11/04 02:59:55 DEBUG : empty space: Excluded (FilesFrom Filter) 2026/11/04 02:59:55 DEBUG : empty space: Excluded --- PASS: TestLsWithFilesFrom (4.79s) === RUN TestLsLong run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 02:59:58 DEBUG : potato2: md5 = 471884d8598e0c8de23da0a5d6cd321f OK 2026/11/04 02:59:59 DEBUG : empty space: md5 = 1fb52a50a319795b30801d8f8eb7ab43 OK --- PASS: TestLsLong (4.87s) === RUN TestHashSums run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 03:00:03 DEBUG : potato2: md5 = e683c4db74fcc89e1d2d13d5eba6df62 OK 2026/11/04 03:00:04 DEBUG : empty space: md5 = 7de078fec9d1c3fc9e6e48c6c0fd04ca OK --- PASS: TestHashSums (4.62s) === RUN TestHashSumsWithErrors 2026/11/04 03:00:06 DEBUG : Creating backend with remote ":memory:" 2026/11/04 03:00:06 ERROR : sub/file1: hash unsupported: hash type not supported 2026/11/04 03:00:06 ERROR : file1: hash unsupported: hash type not supported 2026/11/04 03:00:06 ERROR : file1: hash unsupported: hash type not supported --- PASS: TestHashSumsWithErrors (0.00s) === RUN TestHashStream 2026/11/04 03:00:06 DEBUG : Creating md5 hash of 0 bytes read from input stream 2026/11/04 03:00:06 DEBUG : Creating md5 hash of 0 bytes read from input stream 2026/11/04 03:00:06 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2026/11/04 03:00:06 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2026/11/04 03:00:06 DEBUG : Creating md5 hash of 12 bytes read from input stream 2026/11/04 03:00:06 DEBUG : Creating md5 hash of 12 bytes read from input stream 2026/11/04 03:00:06 DEBUG : Creating sha1 hash of 12 bytes read from input stream 2026/11/04 03:00:06 DEBUG : Creating sha1 hash of 12 bytes read from input stream --- PASS: TestHashStream (0.00s) === RUN TestSuffixName --- PASS: TestSuffixName (0.00s) === RUN TestCount run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 03:00:07 DEBUG : potato2: md5 = 11b85d0c062255edfd1d70f7e7d39064 OK 2026/11/04 03:00:09 DEBUG : empty space: md5 = 27209bb8dbce5bf1a0ffb545381456e1 OK 2026/11/04 03:00:11 DEBUG : sub dir/potato3: md5 = 65ef5ae10c9a38fedd120e6b379f5db5 OK --- PASS: TestCount (9.65s) === RUN TestDelete run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 03:00:17 DEBUG : small: md5 = e7a9b49d10f0f0d5be42550f296e7fae OK 2026/11/04 03:00:18 DEBUG : medium: md5 = 5f5ecbbddc952cc57e745018aefa9691 OK 2026/11/04 03:00:20 DEBUG : large: md5 = 5a844636d82e8d83a8fa331c87f6259d OK 2026/11/04 03:00:20 DEBUG : Waiting for deletions to finish 2026/11/04 03:00:20 DEBUG : large: Excluded (Size Filter) 2026/11/04 03:00:21 INFO : small: Deleted 2026/11/04 03:00:21 INFO : medium: Deleted --- PASS: TestDelete (6.48s) === RUN TestDeleteFatalError run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 03:00:23 DEBUG : file0: md5 = 3599b5cc80d25c996b2a6e3f6a691eef OK 2026/11/04 03:00:25 DEBUG : file1: md5 = 6f9b8754912b21b9e92ac69a94276d17 OK 2026/11/04 03:00:26 DEBUG : file2: md5 = f2b0d7f5498e59d8a8c9d86846e76f7f OK 2026/11/04 03:00:28 DEBUG : file3: md5 = e60cbbebb7a327d096a5bc497ca35ba9 OK 2026/11/04 03:00:29 DEBUG : file4: md5 = 80f29d4cb9dcfa6e76e9e2b58a330b30 OK 2026/11/04 03:00:31 DEBUG : file5: md5 = 9cce56ffaa23781778b35cb39307f453 OK 2026/11/04 03:00:32 DEBUG : file6: md5 = 6f9929cf0e7866371a4b6b76afdf964e OK 2026/11/04 03:00:33 DEBUG : file7: md5 = 8e7ba1aae8c17dab0305371f27abc30f OK 2026/11/04 03:00:35 DEBUG : file8: md5 = 958e034f7808363991663d24cb6c6f41 OK 2026/11/04 03:00:36 DEBUG : file9: md5 = 7489f8040a5a5310aa3bb192f63fe663 OK 2026/11/04 03:00:38 DEBUG : file10: md5 = e2d746819815fb8d9cf4df285833526a OK 2026/11/04 03:00:39 DEBUG : file11: md5 = ee1e47ed9f05f17f84322f19cab358ad OK 2026/11/04 03:00:41 DEBUG : file12: md5 = 86eed1e81164ff4fa5c66e3eedbfce63 OK 2026/11/04 03:00:42 DEBUG : file13: md5 = bc7daef077136d4b06911d1b77ffb32a OK 2026/11/04 03:00:44 DEBUG : file14: md5 = 7332baa790acd159d54e282379bfb11f OK 2026/11/04 03:00:45 DEBUG : file15: md5 = 9d308ebaf597995594d3fb04251ee390 OK 2026/11/04 03:00:47 DEBUG : file16: md5 = 6edfd1e413c44bfed64e30d5db63e333 OK 2026/11/04 03:00:48 DEBUG : file17: md5 = bf7cae73f6556548805a19a63089b4b1 OK 2026/11/04 03:00:50 DEBUG : file18: md5 = fe912705aa4b733822148d49ed96ded2 OK 2026/11/04 03:00:51 DEBUG : file19: md5 = d5be52aec3207adf5c00b00c11014bdd OK 2026/11/04 03:00:51 DEBUG : Waiting for deletions to finish 2026/11/04 03:00:52 ERROR : file6: Got fatal error on delete: --max-delete threshold reached 2026/11/04 03:00:52 ERROR : file9: Got fatal error on delete: --max-delete threshold reached 2026/11/04 03:00:52 ERROR : file8: Got fatal error on delete: --max-delete threshold reached 2026/11/04 03:00:52 ERROR : file11: Got fatal error on delete: --max-delete threshold reached 2026/11/04 03:00:52 ERROR : file19: Got fatal error on delete: --max-delete threshold reached 2026/11/04 03:00:52 ERROR : file15: Got fatal error on delete: --max-delete threshold reached 2026/11/04 03:00:52 ERROR : file0: Got fatal error on delete: --max-delete threshold reached 2026/11/04 03:00:52 ERROR : file7: Got fatal error on delete: --max-delete threshold reached 2026/11/04 03:00:52 ERROR : file4: Got fatal error on delete: --max-delete threshold reached 2026/11/04 03:00:52 ERROR : file16: Got fatal error on delete: --max-delete threshold reached 2026/11/04 03:00:52 ERROR : file18: Got fatal error on delete: --max-delete threshold reached 2026/11/04 03:00:52 ERROR : file3: Got fatal error on delete: --max-delete threshold reached 2026/11/04 03:00:52 ERROR : file2: Got fatal error on delete: --max-delete threshold reached 2026/11/04 03:00:52 ERROR : file10: Got fatal error on delete: --max-delete threshold reached 2026/11/04 03:00:52 ERROR : file17: Got fatal error on delete: --max-delete threshold reached 2026/11/04 03:00:52 ERROR : file13: Got fatal error on delete: --max-delete threshold reached 2026/11/04 03:00:52 ERROR : file14: Got fatal error on delete: --max-delete threshold reached 2026/11/04 03:00:52 ERROR : file1: Got fatal error on delete: --max-delete threshold reached 2026/11/04 03:00:52 ERROR : file5: Got fatal error on delete: --max-delete threshold reached 2026/11/04 03:00:52 ERROR : file12: Got fatal error on delete: --max-delete threshold reached --- PASS: TestDeleteFatalError (40.36s) === RUN TestMaxDelete run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 03:01:04 DEBUG : small: md5 = 44eeb6a771a779f6b083969389c4c5a8 OK 2026/11/04 03:01:05 DEBUG : medium: md5 = 398d3fc3b67b63f331b9ddb048c0a228 OK 2026/11/04 03:01:07 DEBUG : large: md5 = aee5217cd0980edfb1335789757699d8 OK 2026/11/04 03:01:07 DEBUG : Waiting for deletions to finish 2026/11/04 03:01:07 ERROR : large: Got fatal error on delete: --max-delete threshold reached 2026/11/04 03:01:07 INFO : medium: Deleted 2026/11/04 03:01:08 INFO : small: Deleted --- PASS: TestMaxDelete (7.22s) === RUN TestMaxDeleteSizeLargeFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 03:01:11 DEBUG : small: md5 = 2f62caedec023cbe56e5eb566bb177f3 OK 2026/11/04 03:01:13 DEBUG : medium: md5 = 02f9e40b1153138d1f2cf3df45aca0ed OK 2026/11/04 03:01:14 DEBUG : large: md5 = 287306eca197c2c1f0da6139b7765d0e OK 2026/11/04 03:01:14 DEBUG : Waiting for deletions to finish 2026/11/04 03:01:15 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached 2026/11/04 03:01:15 INFO : medium: Deleted 2026/11/04 03:01:15 INFO : small: Deleted --- PASS: TestMaxDeleteSizeLargeFile (7.20s) === RUN TestMaxDeleteSize run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 03:01:18 DEBUG : small: md5 = c2512962fab0ac768fed38ceb0224c08 OK 2026/11/04 03:01:20 DEBUG : medium: md5 = a3bb3067933927d643611e98c9cdf119 OK 2026/11/04 03:01:21 DEBUG : large: md5 = 52c8b624d4c5ea88a3d4bf737750ce6b OK 2026/11/04 03:01:21 DEBUG : Waiting for deletions to finish 2026/11/04 03:01:22 ERROR : medium: Got fatal error on delete: --max-delete-size threshold reached 2026/11/04 03:01:22 INFO : small: Deleted 2026/11/04 03:01:22 INFO : large: Deleted --- PASS: TestMaxDeleteSize (7.57s) === RUN TestReadFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 03:01:26 DEBUG : ReadFile: md5 = 0ae2e43b78339215cc863d1b23e84242 OK --- PASS: TestReadFile (4.23s) === RUN TestRetry 2026/11/04 03:01:28 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 1/5 2026/11/04 03:01:28 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 2/5 2026/11/04 03:01:28 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2026/11/04 03:01:28 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2026/11/04 03:01:28 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2026/11/04 03:01:28 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms --- PASS: TestRetry (0.04s) === RUN TestRetryAfterContextCancel 2026/11/04 03:01:28 DEBUG : Sleeping for 1h0m0s (as indicated by the server) to obey Retry-After error: BANG: trying again in 1h0m0s --- PASS: TestRetryAfterContextCancel (0.00s) === RUN TestRetryAfterLastTry --- PASS: TestRetryAfterLastTry (0.00s) === RUN TestCat run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 03:01:30 DEBUG : file1: md5 = 4b0c6c312cfea8c078932ae48c67dc9f OK 2026/11/04 03:01:32 DEBUG : file2: md5 = 78d0049d36b66f91ad7027fbc5db82c9 OK --- PASS: TestCat (17.30s) === RUN TestPurge 2026/11/04 03:01:46 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-yaculux3pazi" 2026/11/04 03:01:46 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/11/04 03:01:46 DEBUG : Creating backend with remote "TestDrive:crypt/ert58f6rve99b5fv4a2qsa76inqo9dhfd9db1hg4u7g06m3lsong" 2026/11/04 03:01:47 DEBUG : Creating backend with remote "/tmp/rclone2922152241" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-yaculux3pazi'", Local "Local file system at /tmp/rclone2922152241", Modify Window "1ms" 2026/11/04 03:01:52 DEBUG : A1/B1/C1/one: md5 = 739daa02f2680c2d88be8e0de423fe2e OK 2026/11/04 03:01:52 INFO : A2: Making directory 2026/11/04 03:01:52 INFO : A1/B2: Making directory 2026/11/04 03:01:53 INFO : A1/B2/C2: Making directory 2026/11/04 03:01:54 INFO : A1/B1/C3: Making directory 2026/11/04 03:01:55 INFO : A3: Making directory 2026/11/04 03:01:56 INFO : A3/B3: Making directory 2026/11/04 03:01:56 INFO : A3/B3/C4: Making directory 2026/11/04 03:01:59 DEBUG : A1/two: md5 = adabff0f1481c979ccefedc38d9e37c1 OK 2026/11/04 03:02:03 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-yaculux3pazi': Purge remote 2026/11/04 03:02:03 NOTICE: purge failed: directory not found --- PASS: TestPurge (17.33s) === RUN TestRmdirsNoLeaveRoot run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 03:02:07 DEBUG : A1/B1/C1/one: md5 = 6904ff18a63be815503918e07876c606 OK 2026/11/04 03:02:07 INFO : A2: Making directory 2026/11/04 03:02:08 INFO : A1/B2: Making directory 2026/11/04 03:02:09 INFO : A1/B2/C2: Making directory 2026/11/04 03:02:10 INFO : A1/B1/C3: Making directory 2026/11/04 03:02:10 INFO : A3: Making directory 2026/11/04 03:02:11 INFO : A3/B3: Making directory 2026/11/04 03:02:12 INFO : A3/B3/C4: Making directory 2026/11/04 03:02:14 DEBUG : A1/two: md5 = cc8559522af5bded93d5c86a20b9ac00 OK 2026/11/04 03:02:16 DEBUG : removing 1 level 3 directories 2026/11/04 03:02:16 INFO : A3/B3/C4: Removing directory 2026/11/04 03:02:19 DEBUG : removing 2 level 3 directories 2026/11/04 03:02:19 INFO : A1/B2/C2: Removing directory 2026/11/04 03:02:19 INFO : A1/B1/C3: Removing directory 2026/11/04 03:02:20 DEBUG : removing 2 level 2 directories 2026/11/04 03:02:20 INFO : A3/B3: Removing directory 2026/11/04 03:02:20 INFO : A1/B2: Removing directory 2026/11/04 03:02:21 DEBUG : removing 2 level 1 directories 2026/11/04 03:02:21 INFO : A3: Removing directory 2026/11/04 03:02:21 INFO : A2: Removing directory 2026/11/04 03:02:25 DEBUG : removing 1 level 3 directories 2026/11/04 03:02:25 INFO : A1/B1/C1: Removing directory 2026/11/04 03:02:26 DEBUG : removing 1 level 2 directories 2026/11/04 03:02:26 INFO : A1/B1: Removing directory 2026/11/04 03:02:26 DEBUG : removing 1 level 1 directories 2026/11/04 03:02:26 INFO : A1: Removing directory 2026/11/04 03:02:27 DEBUG : removing 1 level 0 directories 2026/11/04 03:02:27 INFO : Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano': Removing directory --- PASS: TestRmdirsNoLeaveRoot (25.32s) === RUN TestRmdirsLeaveRoot run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 03:02:29 INFO : A1: Making directory 2026/11/04 03:02:30 INFO : A1/B1: Making directory 2026/11/04 03:02:31 INFO : A1/B1/C1: Making directory 2026/11/04 03:02:33 DEBUG : removing 1 level 3 directories 2026/11/04 03:02:33 INFO : A1/B1/C1: Removing directory 2026/11/04 03:02:34 DEBUG : removing 1 level 2 directories 2026/11/04 03:02:34 INFO : A1/B1: Removing directory --- PASS: TestRmdirsLeaveRoot (8.52s) === RUN TestRmdirsWithFilter run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 03:02:37 INFO : A1: Making directory 2026/11/04 03:02:38 INFO : A1/B1: Making directory 2026/11/04 03:02:38 INFO : A1/B1/C1: Making directory 2026/11/04 03:02:41 DEBUG : removing 1 level 3 directories 2026/11/04 03:02:41 INFO : A1/B1/C1: Removing directory 2026/11/04 03:02:42 DEBUG : removing 1 level 2 directories 2026/11/04 03:02:42 INFO : A1/B1: Removing directory --- PASS: TestRmdirsWithFilter (7.79s) === RUN TestCopyURL run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 03:02:46 DEBUG : file1: md5 = caf7f5a27a4db4671ab40870d9f861e5 OK 2026/11/04 03:02:46 DEBUG : file1: size = 14 OK 2026/11/04 03:02:47 DEBUG : filename.txt: File name found in url 2026/11/04 03:02:48 DEBUG : filename.txt: md5 = b81e09e115b6bbf362e8a7e6d99b1bff OK 2026/11/04 03:02:48 DEBUG : filename.txt: size = 14 OK 2026/11/04 03:02:48 DEBUG : headerfilename.txt: filename found in Content-Disposition header. 2026/11/04 03:02:50 DEBUG : headerfilename.txt: md5 = bca71dbfc3b1c1452ce340856b7a745a OK 2026/11/04 03:02:50 DEBUG : headerfilename.txt: size = 14 OK 2026/11/04 03:02:52 DEBUG : file2: md5 = 0b770cee84366b3b95aafcb77c20ebb6 OK 2026/11/04 03:02:52 DEBUG : file2: size = 14 OK --- PASS: TestCopyURL (9.34s) === RUN TestCopyURLDownloadHeaders run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 03:02:56 DEBUG : file1: md5 = 3b01e1b27da6b793c80f33df9e8aa8ae OK 2026/11/04 03:02:56 DEBUG : file1: size = 14 OK --- PASS: TestCopyURLDownloadHeaders (2.36s) === RUN TestCopyURLToWriter --- PASS: TestCopyURLToWriter (0.00s) === RUN TestMoveFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 03:02:57 DEBUG : file1: Need to transfer - File not found at Destination 2026/11/04 03:02:59 DEBUG : sub/file2: md5 = dec066c8bb1c362bfa255bb3e027f0ab OK 2026/11/04 03:02:59 DEBUG : sub/file2: size = 14 OK 2026/11/04 03:02:59 INFO : file1: Copied (new) to: sub/file2 2026/11/04 03:02:59 INFO : file1: Deleted 2026/11/04 03:03:00 DEBUG : sub/file2: size = 14 OK 2026/11/04 03:03:00 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/11/04 03:03:00 DEBUG : file1: Unchanged skipping 2026/11/04 03:03:00 INFO : file1: Deleted 2026/11/04 03:03:01 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano': don't need to copy/move sub/file2, it is already at target location --- PASS: TestMoveFile (6.47s) === RUN TestMoveFileWithIgnoreExisting run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 03:03:03 DEBUG : file1: Need to transfer - File not found at Destination 2026/11/04 03:03:05 DEBUG : file1: md5 = baf620f7f02e2dffed2f31bddef11726 OK 2026/11/04 03:03:05 DEBUG : file1: size = 14 OK 2026/11/04 03:03:05 INFO : file1: Copied (new) 2026/11/04 03:03:05 INFO : file1: Deleted 2026/11/04 03:03:05 DEBUG : file1: Destination exists, skipping 2026/11/04 03:03:05 DEBUG : file1: Not removing source file as destination file exists and --ignore-existing is set --- PASS: TestMoveFileWithIgnoreExisting (3.37s) === RUN TestMoveFileImmutable run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 03:03:08 DEBUG : existing: md5 = 646352d19fb3e16563f0484be72be3a5 OK 2026/11/04 03:03:08 DEBUG : existing: size = 8 (Local file system at /tmp/rclone974098245) 2026/11/04 03:03:08 DEBUG : existing: size = 6 (Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano') 2026/11/04 03:03:08 DEBUG : existing: Sizes differ 2026/11/04 03:03:08 ERROR : existing: Source and destination exist but do not match: immutable file modified --- PASS: TestMoveFileImmutable (3.13s) === RUN TestCaseInsensitiveMoveFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFile (0.50s) === RUN TestMoveOntoItself === RUN TestMoveOntoItself/viaSubdir=false,disableMove=false 2026/11/04 03:03:10 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-biqobow6qahu" 2026/11/04 03:03:10 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/11/04 03:03:10 DEBUG : Creating backend with remote "TestDrive:crypt/ju93rligv3vr8506vsukgd3gt58lfh2nhqkfell6ngc87e8jh4p0" 2026/11/04 03:03:11 DEBUG : Creating backend with remote "/tmp/rclone3897649005" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-biqobow6qahu'", Local "Local file system at /tmp/rclone3897649005", Modify Window "1ms" 2026/11/04 03:03:14 DEBUG : sub/file1: md5 = 9357f8839897753d519ad2bc580bee7c OK 2026/11/04 03:03:15 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-biqobow6qahu': Purge remote === RUN TestMoveOntoItself/viaSubdir=false,disableMove=true 2026/11/04 03:03:16 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-niroyuq5wogi" 2026/11/04 03:03:16 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/11/04 03:03:16 DEBUG : Creating backend with remote "TestDrive:crypt/4obi4jp6sku8biqgdkj7nm47hh1rkq39fmprm7ne3ue59sjbrhv0" 2026/11/04 03:03:17 DEBUG : Creating backend with remote "/tmp/rclone701305979" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-niroyuq5wogi'", Local "Local file system at /tmp/rclone701305979", Modify Window "1ms" 2026/11/04 03:03:20 DEBUG : sub/file1: md5 = 32d677210fbb94c07e026fb51dd42d99 OK 2026/11/04 03:03:20 DEBUG : Reset feature "Move" 2026/11/04 03:03:20 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-niroyuq5wogi': Purge remote === RUN TestMoveOntoItself/viaSubdir=true,disableMove=false 2026/11/04 03:03:21 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-dakuwam4jatu" 2026/11/04 03:03:21 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/11/04 03:03:21 DEBUG : Creating backend with remote "TestDrive:crypt/lgpuh1fs3bmus2qtr23eqj0mlufnbsapjjd9sdk4s4luvdh6b0ug" 2026/11/04 03:03:22 DEBUG : Creating backend with remote "/tmp/rclone4103961765" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-dakuwam4jatu'", Local "Local file system at /tmp/rclone4103961765", Modify Window "1ms" 2026/11/04 03:03:25 DEBUG : sub/file1: md5 = b7745edd59226de61962efe4309bdb3c OK 2026/11/04 03:03:25 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-dakuwam4jatu/sub" 2026/11/04 03:03:25 DEBUG : Creating backend with remote "TestDrive:crypt/lgpuh1fs3bmus2qtr23eqj0mlufnbsapjjd9sdk4s4luvdh6b0ug/150fuo3cn4j1uenq6r4g4qk6t4" 2026/11/04 03:03:26 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-dakuwam4jatu': Purge remote === RUN TestMoveOntoItself/viaSubdir=true,disableMove=true 2026/11/04 03:03:27 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-gidisir7nuri" 2026/11/04 03:03:27 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/11/04 03:03:27 DEBUG : Creating backend with remote "TestDrive:crypt/7naff95834fg6v8imcf0oqj4na3hro6rg7r4jhjojf50q7bgeab0" 2026/11/04 03:03:28 DEBUG : Creating backend with remote "/tmp/rclone2913976669" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gidisir7nuri'", Local "Local file system at /tmp/rclone2913976669", Modify Window "1ms" 2026/11/04 03:03:31 DEBUG : sub/file1: md5 = ba83449c839da056224d2e01e8e90771 OK 2026/11/04 03:03:31 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-gidisir7nuri/sub" 2026/11/04 03:03:31 DEBUG : Creating backend with remote "TestDrive:crypt/7naff95834fg6v8imcf0oqj4na3hro6rg7r4jhjojf50q7bgeab0/150fuo3cn4j1uenq6r4g4qk6t4" 2026/11/04 03:03:32 DEBUG : Reset feature "Move" 2026/11/04 03:03:33 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-gidisir7nuri': Purge remote --- PASS: TestMoveOntoItself (23.27s) --- PASS: TestMoveOntoItself/viaSubdir=false,disableMove=false (5.58s) --- PASS: TestMoveOntoItself/viaSubdir=false,disableMove=true (5.34s) --- PASS: TestMoveOntoItself/viaSubdir=true,disableMove=false (5.98s) --- PASS: TestMoveOntoItself/viaSubdir=true,disableMove=true (6.37s) === RUN TestMoveUnicodeEquivalentWithoutServerSideMove 2026/11/04 03:03:33 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-qejozes9lepa" 2026/11/04 03:03:33 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/11/04 03:03:33 DEBUG : Creating backend with remote "TestDrive:crypt/3mqhh1cturk9iapicrrmnuvd57pfpmnalmc6jedulmdvtc3blebg" 2026/11/04 03:03:34 DEBUG : Creating backend with remote "/tmp/rclone1972296902" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-qejozes9lepa'", Local "Local file system at /tmp/rclone1972296902", Modify Window "1ms" 2026/11/04 03:03:34 DEBUG : Reset feature "Move" 2026/11/04 03:03:37 DEBUG : sub/équivalent.txt: md5 = 719df9eee5de9f17ad603391bea961d0 OK 2026/11/04 03:03:38 DEBUG : sub/équivalent.txt: moving to sub/équivalent.txt-rclone-move-yosowun7 2026/11/04 03:03:39 DEBUG : sub/équivalent.txt-rclone-move-yosowun7: size = 14 OK 2026/11/04 03:03:39 INFO : sub/équivalent.txt: Copied (server-side copy) to: sub/équivalent.txt-rclone-move-yosowun7 2026/11/04 03:03:39 INFO : sub/équivalent.txt: Deleted 2026/11/04 03:03:39 DEBUG : sub/équivalent.txt: moving to sub/équivalent.txt 2026/11/04 03:03:40 DEBUG : sub/équivalent.txt: size = 14 OK 2026/11/04 03:03:40 INFO : sub/équivalent.txt-rclone-move-yosowun7: Copied (server-side copy) to: sub/équivalent.txt 2026/11/04 03:03:41 INFO : sub/équivalent.txt-rclone-move-yosowun7: Deleted 2026/11/04 03:03:41 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-qejozes9lepa': Purge remote --- PASS: TestMoveUnicodeEquivalentWithoutServerSideMove (8.63s) === RUN TestCaseInsensitiveMoveWithoutServerSideMove 2026/11/04 03:03:42 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-wodadax1tupi" 2026/11/04 03:03:42 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/11/04 03:03:42 DEBUG : Creating backend with remote "TestDrive:crypt/iv53qc1docenj7ec5t6lho08dhhsm2jl7jf29g01o3uv484h5ej0" 2026/11/04 03:03:43 DEBUG : Creating backend with remote "/tmp/rclone2109046712" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wodadax1tupi'", Local "Local file system at /tmp/rclone2109046712", Modify Window "1ms" operations_test.go:1198: remote is case sensitive 2026/11/04 03:03:43 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-wodadax1tupi': Purge remote 2026/11/04 03:03:43 NOTICE: purge failed: directory not found --- SKIP: TestCaseInsensitiveMoveWithoutServerSideMove (1.19s) === RUN TestCaseInsensitiveMoveFileDryRun run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFileDryRun (0.44s) === RUN TestMoveFileBackupDir run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 03:03:46 DEBUG : dst/file1: md5 = d962cf31b8d1bfd44d9a44ccd7d28097 OK 2026/11/04 03:03:46 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-birusev6cano/backup" 2026/11/04 03:03:46 DEBUG : Creating backend with remote "TestDrive:crypt/ruedvu180ro2fcktkdj2gabtvu3sr8pt7hsglppuerfam48l4v10/1nrff024r7pq65ecp72fc28jb0" 2026/11/04 03:03:48 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone974098245) 2026/11/04 03:03:48 DEBUG : dst/file1: size = 18 (Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano') 2026/11/04 03:03:48 DEBUG : dst/file1: Sizes differ 2026/11/04 03:03:50 INFO : dst/file1: Moved (server-side) 2026/11/04 03:03:52 DEBUG : dst/file1: md5 = c28a45eda85d9245f9e999104082e5c7 OK 2026/11/04 03:03:52 DEBUG : dst/file1: size = 14 OK 2026/11/04 03:03:52 INFO : dst/file1: Copied (new) 2026/11/04 03:03:52 INFO : dst/file1: Deleted --- PASS: TestMoveFileBackupDir (13.09s) === 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-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 03:03:59 DEBUG : A/file0: md5 = cdc64d538f3bb1292e4bffa090f18ee7 OK 2026/11/04 03:04:00 DEBUG : A/file1: md5 = 2d79c5781261609337bbf7b14d3d4afb OK 2026/11/04 03:04:02 DEBUG : A/file2: md5 = c2e5430ed51266214a2286a35344e09d OK 2026/11/04 03:04:03 DEBUG : A/file3: md5 = 48f41054fc6b039168ba735497140ffd OK 2026/11/04 03:04:05 DEBUG : A/file4: md5 = 488b50ead6cf1f8188fa679bee6fe3cc OK 2026/11/04 03:04:06 DEBUG : A/file5: md5 = 0e4a02f04674a9706e7d8c19324d5e51 OK 2026/11/04 03:04:08 DEBUG : A/file6: md5 = dadaae75d00d1dd875fa709d10d2bd99 OK 2026/11/04 03:04:09 DEBUG : A/file7: md5 = 8e292bdd731bd4321e6217110f5f587f OK 2026/11/04 03:04:11 DEBUG : A/file8: md5 = 959fdce5c628dd0e65c26543f2b7ddd2 OK 2026/11/04 03:04:12 DEBUG : A/file9: md5 = 78a12c3dfbc0522b24b003e619e4da67 OK 2026/11/04 03:04:14 DEBUG : A/file10: md5 = 22db4f07c57bd7b9f7a356a61c705402 OK 2026/11/04 03:04:16 DEBUG : A/file11: md5 = 8615366a9be6668a501c57d128a49a65 OK 2026/11/04 03:04:17 DEBUG : A/file12: md5 = db05d99aa642306ddb47c1b828065df1 OK 2026/11/04 03:04:19 DEBUG : A/file13: md5 = 6c97040da08c9d883e857b0aee2f03f0 OK 2026/11/04 03:04:20 DEBUG : A/file14: md5 = 2451f82b7562f71eb8f043bc442a382f OK 2026/11/04 03:04:21 DEBUG : A/file15: md5 = 35e10a02aa44f2f232a1012cfa584e23 OK 2026/11/04 03:04:23 DEBUG : A/file16: md5 = 67ada787462bcabe0e1c04284e068694 OK 2026/11/04 03:04:25 DEBUG : A/file17: md5 = 35a3cbdaa37025b692f31ed011c2cdaa OK 2026/11/04 03:04:26 DEBUG : A/file18: md5 = e287e469db4a8820742b71b2bb9cb7e2 OK 2026/11/04 03:04:28 DEBUG : A/file19: md5 = df07be5737f5294418ac11e65f2bd1e4 OK operations_test.go:1563: Skipping test on non local remote --- SKIP: TestDirMoveMoveError (42.41s) === RUN TestDirMoveContext run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 03:04:41 DEBUG : A/one: md5 = 09d2d9d95bd698e94abe66b2cd288328 OK 2026/11/04 03:04:43 DEBUG : A/two: md5 = 36ea9e274db392d6f403f306c2f25b32 OK operations_test.go:1563: Skipping test on non local remote --- SKIP: TestDirMoveContext (6.11s) === RUN TestDirMove run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 03:04:47 DEBUG : A1/one: md5 = d08861cf3c16c65188c06af8a0006c03 OK 2026/11/04 03:04:49 DEBUG : A1/two: md5 = 93f1e2cb64505a9b705dd907a7bd9183 OK 2026/11/04 03:04:51 DEBUG : A1/B1/three: md5 = 2fa9f87bf585e22fc6f9a921893fcef5 OK 2026/11/04 03:04:54 DEBUG : A1/B1/C1/four: md5 = fa69d2cb8d2449f19c69f88f9ea203ea OK 2026/11/04 03:04:56 DEBUG : A1/B1/C2/five: md5 = 782504cdcda17b74143e7f199180b361 OK 2026/11/04 03:04:56 INFO : A1/B2: Making directory 2026/11/04 03:04:57 INFO : A1/B1/C3: Making directory 2026/11/04 03:05:07 INFO : A2/B1/three: Moved (server-side) to: A3/B1/three 2026/11/04 03:05:07 INFO : A2/one: Moved (server-side) to: A3/one 2026/11/04 03:05:07 INFO : A2/B1/C2/five: Moved (server-side) to: A3/B1/C2/five 2026/11/04 03:05:07 INFO : A2/two: Moved (server-side) to: A3/two 2026/11/04 03:05:07 INFO : A2/B1/C1/four: Moved (server-side) to: A3/B1/C1/four 2026/11/04 03:05:13 INFO : Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano': Can't DirMove - falling back to file moves: can't move directory - incompatible remotes 2026/11/04 03:05:19 INFO : A3/one: Moved (server-side) to: A4/one 2026/11/04 03:05:19 INFO : A3/B1/C1/four: Moved (server-side) to: A4/B1/C1/four 2026/11/04 03:05:19 INFO : A3/B1/C2/five: Moved (server-side) to: A4/B1/C2/five 2026/11/04 03:05:19 INFO : A3/two: Moved (server-side) to: A4/two 2026/11/04 03:05:19 INFO : A3/B1/three: Moved (server-side) to: A4/B1/three --- PASS: TestDirMove (47.94s) === RUN TestGetFsInfo run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" --- PASS: TestGetFsInfo (0.49s) === RUN TestRcat === RUN TestRcat/withChecksum=false,ignoreChecksum=false run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 03:05:34 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano': File to upload is small (34 bytes), uploading instead of streaming 2026/11/04 03:05:35 DEBUG : no_checksum_small_file_from_pipe: md5 = 3c9dc48375d8c58f1b5267ce12860730 OK 2026/11/04 03:05:35 DEBUG : no_checksum_small_file_from_pipe: size = 34 OK 2026/11/04 03:05:35 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2026/11/04 03:05:35 DEBUG : no_checksum_small_file_from_pipe: Size of src and dst objects identical 2026/11/04 03:05:36 DEBUG : qc9k4uauqa9ls6a2r6lo8jf4b1m7r0510pkurois1cc70egbs4h0: Sending chunk 0 length 102465 2026/11/04 03:05:37 DEBUG : no_checksum_big_file_from_pipe: md5 = f2a2b2ad645f9167530fef0eada8fc47 OK 2026/11/04 03:05:37 DEBUG : no_checksum_big_file_from_pipe: size = 102401 OK 2026/11/04 03:05:37 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-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 03:05:39 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano': File to upload is small (34 bytes), uploading instead of streaming 2026/11/04 03:05:41 DEBUG : with_checksum_small_file_from_pipe: md5 = 566ddfa61ce9c9322359347c7d4ddb81 OK 2026/11/04 03:05:41 DEBUG : with_checksum_small_file_from_pipe: size = 34 OK 2026/11/04 03:05:41 DEBUG : with_checksum_small_file_from_pipe: Size of src and dst objects identical 2026/11/04 03:05:41 DEBUG : e74jo8a7lr1aimg2u78fct67gnuetc1qujiofaci0c8fnq7rjt1d4i296pcklqhgdihs03etvgdi4: Sending chunk 0 length 102465 2026/11/04 03:05:42 DEBUG : with_checksum_big_file_from_pipe: md5 = 80508d88efdfcead3ddf09a0f4a67b95 OK 2026/11/04 03:05:42 DEBUG : with_checksum_big_file_from_pipe: size = 102401 OK 2026/11/04 03:05:42 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-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 03:05:44 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano': File to upload is small (34 bytes), uploading instead of streaming 2026/11/04 03:05:46 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK 2026/11/04 03:05:46 DEBUG : ignore_checksum_small_file_from_pipe: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/11/04 03:05:46 DEBUG : 1963rn6olajfbjn4nbqi9nacu7bk879vpf0d2bg8r0ndcqqsfntob0urt056p0qujf574rqnb5f62: Sending chunk 0 length 102465 2026/11/04 03:05:47 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK 2026/11/04 03:05: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-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 03:05:49 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano': File to upload is small (34 bytes), uploading instead of streaming 2026/11/04 03:05:51 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK 2026/11/04 03:05:51 DEBUG : ignore_checksum_small_file_from_pipe: Size of src and dst objects identical 2026/11/04 03:05:52 DEBUG : 1963rn6olajfbjn4nbqi9nacu7bk879vpf0d2bg8r0ndcqqsfntob0urt056p0qujf574rqnb5f62: Sending chunk 0 length 102465 2026/11/04 03:05:53 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK 2026/11/04 03:05:53 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical --- PASS: TestRcat (20.82s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (5.46s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (5.16s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (5.01s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (5.19s) === RUN TestRcatMetadata run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" === RUN TestRcatMetadata/Normal 2026/11/04 03:05:55 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano': File to upload is small (48 bytes), uploading instead of streaming 2026/11/04 03:05:56 DEBUG : rcat_metadata: md5 = a7f64897efe0c438ea57db1deb44ce22 OK 2026/11/04 03:05:56 DEBUG : rcat_metadata: size = 48 OK 2026/11/04 03:05:56 DEBUG : rcat_metadata: Size of src and dst objects identical === RUN TestRcatMetadata/ViaDisk 2026/11/04 03:05:58 DEBUG : rijssoobhbn85c5p1imhg55m6frank1nlplla6hkdjlqmqb2471g: Sending chunk 0 length 111 2026/11/04 03:05:59 DEBUG : rcat_metadata_uploadcutoff0: md5 = c1e3107c4e38562097e7793eefd44b41 OK 2026/11/04 03:05:59 DEBUG : rcat_metadata_uploadcutoff0: size = 63 OK 2026/11/04 03:05:59 DEBUG : rcat_metadata_uploadcutoff0: Size of src and dst objects identical --- PASS: TestRcatMetadata (6.43s) --- PASS: TestRcatMetadata/Normal (2.94s) --- PASS: TestRcatMetadata/ViaDisk (2.87s) === RUN TestRcatSize run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 03:06:02 DEBUG : potato1: md5 = ec754a0c06f6bb22bce7c8b836d65e2a OK 2026/11/04 03:06:02 DEBUG : potato1: size = 60 OK 2026/11/04 03:06:02 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano': File to upload is small (60 bytes), uploading instead of streaming 2026/11/04 03:06:04 DEBUG : potato2: md5 = 9794459b762e5df566b603b35571b1db OK 2026/11/04 03:06:04 DEBUG : potato2: size = 60 OK 2026/11/04 03:06:04 DEBUG : potato2: Size of src and dst objects identical --- PASS: TestRcatSize (4.77s) === RUN TestRcatSizeShortEOF run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 03:06:07 DEBUG : potato1: md5 = f310ef36d13f2c98f0e502cbf236559c OK 2026/11/04 03:06:07 DEBUG : potato1: size = 120 (Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano') 2026/11/04 03:06:07 DEBUG : potato1: size = 60 (Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano') 2026/11/04 03:06:07 ERROR : potato1: corrupted on transfer: sizes differ src 120 vs dst(Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano') 60 2026/11/04 03:06:07 INFO : potato1: Removing failed copy --- PASS: TestRcatSizeShortEOF (2.49s) === RUN TestRcatSizeMetadata run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 03:06:09 DEBUG : potato1: md5 = 8e9e20cd5afb81ad2bc0126e0f9ce0cc OK 2026/11/04 03:06:09 DEBUG : potato1: size = 60 OK 2026/11/04 03:06:09 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano': File to upload is small (60 bytes), uploading instead of streaming 2026/11/04 03:06:11 DEBUG : potato2: md5 = 94dae76ab3a1f65b8b324cab6292fb57 OK 2026/11/04 03:06:11 DEBUG : potato2: size = 60 OK 2026/11/04 03:06:11 DEBUG : potato2: Size of src and dst objects identical --- PASS: TestRcatSizeMetadata (5.17s) === RUN TestRcatSizeUploadHeaders run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 03:06:15 DEBUG : potato1: md5 = b5236e1657397618a151021addd1ffb3 OK 2026/11/04 03:06:15 DEBUG : potato1: size = 60 OK --- PASS: TestRcatSizeUploadHeaders (2.41s) === RUN TestRcatSizeChecksum === RUN TestRcatSizeChecksum/Corrupted run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" operations_test.go:2048: Skipping as destination doesn't support hashes === RUN TestRcatSizeChecksum/SizeDiffers run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 03:06:18 DEBUG : potato4: md5 = 9b6eeffaa1e7cd4286b250c108eace61 OK 2026/11/04 03:06:18 DEBUG : potato4: size = 60 (Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano') 2026/11/04 03:06:18 DEBUG : potato4: size = 59 (Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano') 2026/11/04 03:06:18 ERROR : potato4: corrupted on transfer: sizes differ src 60 vs dst(Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano') 59 2026/11/04 03:06:18 INFO : potato4: Removing failed copy === RUN TestRcatSizeChecksum/IgnoreChecksum run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 03:06:20 DEBUG : potato2: size = 60 OK === RUN TestRcatSizeChecksum/NoHashes run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 03:06:23 DEBUG : potato3: md5 = e33daf3faa0d628da22a7310d0897a67 OK 2026/11/04 03:06:23 DEBUG : potato3: size = 60 OK --- PASS: TestRcatSizeChecksum (8.11s) --- SKIP: TestRcatSizeChecksum/Corrupted (0.44s) --- PASS: TestRcatSizeChecksum/SizeDiffers (2.77s) --- PASS: TestRcatSizeChecksum/IgnoreChecksum (2.38s) --- PASS: TestRcatSizeChecksum/NoHashes (2.52s) === RUN TestTouchDir run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 03:06:25 DEBUG : potato2: md5 = 5464e8457cbfccdd5a2a6c08e6486ba5 OK 2026/11/04 03:06:26 DEBUG : empty space: md5 = 827b646c91fd8aea3648672650e6ac70 OK 2026/11/04 03:06:29 DEBUG : sub dir/potato3: md5 = ae5b6c3cf71b5bc0f98ca9c9af49cbda OK 2026/11/04 03:06:30 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano': Touching "sub dir/potato3" 2026/11/04 03:06:30 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano': Touching "potato2" 2026/11/04 03:06:30 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano': Touching "empty space" --- PASS: TestTouchDir (10.20s) === RUN TestMkdirMetadata run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 03:06:34 DEBUG : dir with metadata: Making directory with metadata 2026/11/04 03:06:35 INFO : dir with metadata: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) --- PASS: TestMkdirMetadata (2.64s) === RUN TestMkdirModTime run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 03:06:37 DEBUG : directory with modtime: Making directory with metadata 2026/11/04 03:06:37 INFO : directory with modtime: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/11/04 03:06:38 NOTICE: dry run directory with modtime: Skipped make directory as --dry-run is set --- PASS: TestMkdirModTime (2.48s) === RUN TestCopyDirMetadata run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 03:06:39 DEBUG : dir with metadata to be copied: Making directory with metadata 2026/11/04 03:06:39 INFO : dir with metadata to be copied: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2026/11/04 03:06:39 DEBUG : Google drive root 'crypt/ruedvu180ro2fcktkdj2gabtvu3sr8pt7hsglppuerfam48l4v10': Skipping btime metadata as can't update it on an existing file: 2026-11-04T03:06:39.517133783Z 2026/11/04 03:06:40 INFO : non existent directory: Updated directory metadata 2026/11/04 03:06:42 DEBUG : Google drive root 'crypt/ruedvu180ro2fcktkdj2gabtvu3sr8pt7hsglppuerfam48l4v10': Skipping btime metadata as can't update it on an existing file: 2026-11-04T03:06:39.517133783Z 2026/11/04 03:06:42 INFO : existing directory: Updated directory metadata --- PASS: TestCopyDirMetadata (5.06s) === RUN TestSetDirModTime run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 03:06:44 DEBUG : set modtime on non existent directory: Skipping set directory modification time as --no-update-dir-modtime is set 2026/11/04 03:06:46 INFO : set modtime on existing directory: Set directory modification time (using SetModTime) 2026/11/04 03:06:47 INFO : set modtime on existing directory: Set directory modification time (using DirSetModTime) --- PASS: TestSetDirModTime (4.30s) === RUN TestDirsEqual run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 03:06:48 DEBUG : dir with metadata to be copied: Making directory with metadata 2026/11/04 03:06:48 INFO : dir with metadata to be copied: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2026/11/04 03:06:48 DEBUG : dst: Making directory with metadata 2026/11/04 03:06:49 INFO : dst: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2026/11/04 03:06:49 DEBUG : dst: Directory modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/11/04 03:06:50 INFO : dst: Set directory modification time (using SetModTime) 2026/11/04 03:06:50 INFO : dst: Set directory modification time (using SetModTime) 2026/11/04 03:06:50 DEBUG : dst: Directory modification time the same (differ by 1ns, within tolerance 1ms) 2026/11/04 03:06:51 INFO : dst: Set directory modification time (using SetModTime) 2026/11/04 03:06:51 DEBUG : dst: Destination directory is newer than source, skipping --- PASS: TestDirsEqual (3.92s) === RUN TestRemoveExisting run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano'", Local "Local file system at /tmp/rclone974098245", Modify Window "1ms" 2026/11/04 03:06:55 DEBUG : sub dir/test remove existing: md5 = 4e117db6bf6d75a7becdefbc42550e59 OK 2026/11/04 03:06:56 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: md5 = 859afb0ceeb8454a5e416519bfed537b OK 2026/11/04 03:06:58 DEBUG : sub dir/test remove existing: TEST: renaming existing object to "sub dir/test remove existing.vilugef9" before starting 2026/11/04 03:06:59 DEBUG : sub dir/test remove existing.vilugef9: TEST: removing renamed existing file after operation 2026/11/04 03:07:01 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: TEST: renaming existing object to "sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.wuqocen8" before starting 2026/11/04 03:07:02 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.wuqocen8: TEST: renaming existing back after failed operation 2026/11/04 03:07:03 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: TEST: renaming existing object to "sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.cupopok6" before starting 2026/11/04 03:07:04 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.cupopok6: TEST: removing renamed existing file after operation --- PASS: TestRemoveExisting (13.71s) === RUN TestRcatInputFailurePreservesDestination 2026/11/04 03:07:06 DEBUG : Creating backend with remote "/tmp/TestRcatInputFailurePreservesDestination2823741824/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/11/04 03:07:06 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-birusev6cano': Purge remote "./operations.test -test.v -test.timeout 1h0m0s -remote TestCryptDrive: -verbose" - Finished OK in 22m5.804082767s (try 1/5)