"./operations.test -test.v -test.timeout 1h0m0s -remote TestCryptDrive: -verbose" - Starting (try 1/5) 2026/11/02 01:47:10 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-wiyopif5fuya" 2026/11/02 01:47:10 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/11/02 01:47:10 DEBUG : Creating backend with remote "TestDrive:crypt/vsdfbtsla04vamvkknaanjiop2bgv6q97ajse673ngudlhnuln10" 2026/11/02 01:47:11 DEBUG : Creating backend with remote "/tmp/rclone1219163137" === 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-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" multithread_test.go:120: multithread writing not supported --- SKIP: TestMultithreadCopy (0.23s) === RUN TestMultithreadCopyAbort run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" multithread_test.go:120: multithread writing not supported --- SKIP: TestMultithreadCopyAbort (0.22s) === RUN TestMultithreadCopyWriterAtErrors === RUN TestMultithreadCopyWriterAtErrors/open/fatal=false 2026/11/02 01:47:11 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt === RUN TestMultithreadCopyWriterAtErrors/open/fatal=true 2026/11/02 01:47:11 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt === RUN TestMultithreadCopyWriterAtErrors/write/fatal=false 2026/11/02 01:47:11 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2026/11/02 01:47:11 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams 2026/11/02 01:47:11 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting 2026/11/02 01:47:11 DEBUG : file.txt: writing chunk 1 2026/11/02 01:47:11 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting 2026/11/02 01:47:11 DEBUG : file.txt: multi-thread copy: chunk 2/4 failed: multi-thread copy: failed to write chunk: disk full 2026/11/02 01:47:11 DEBUG : file.txt: writing chunk 0 2026/11/02 01:47:11 DEBUG : file.txt: multi-thread copy: chunk 1/4 failed: multi-thread copy: failed to write chunk: disk full 2026/11/02 01:47:11 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting 2026/11/02 01:47:11 DEBUG : file.txt: writing chunk 2 2026/11/02 01:47:11 DEBUG : file.txt: multi-thread copy: chunk 3/4 failed: multi-thread copy: failed to write chunk: disk full 2026/11/02 01:47:11 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit 2026/11/02 01:47:11 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/02 01:47:11 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2026/11/02 01:47:11 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams 2026/11/02 01:47:11 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting 2026/11/02 01:47:11 DEBUG : file.txt: writing chunk 1 2026/11/02 01:47:11 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting 2026/11/02 01:47:11 DEBUG : file.txt: multi-thread copy: chunk 2/4 failed: multi-thread copy: failed to write chunk: disk full 2026/11/02 01:47:11 DEBUG : file.txt: writing chunk 0 2026/11/02 01:47:11 DEBUG : file.txt: multi-thread copy: chunk 1/4 failed: multi-thread copy: failed to write chunk: disk full 2026/11/02 01:47:11 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting 2026/11/02 01:47:11 DEBUG : file.txt: writing chunk 2 2026/11/02 01:47:11 DEBUG : file.txt: multi-thread copy: chunk 3/4 failed: multi-thread copy: failed to write chunk: disk full 2026/11/02 01:47:11 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit 2026/11/02 01:47:11 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/02 01:47:11 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2026/11/02 01:47:11 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams 2026/11/02 01:47:11 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting 2026/11/02 01:47:11 DEBUG : file.txt: writing chunk 1 2026/11/02 01:47:11 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 finished 2026/11/02 01:47:11 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting 2026/11/02 01:47:11 DEBUG : file.txt: writing chunk 2 2026/11/02 01:47:11 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 finished 2026/11/02 01:47:11 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting 2026/11/02 01:47:11 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 starting 2026/11/02 01:47:11 DEBUG : file.txt: writing chunk 0 2026/11/02 01:47:11 DEBUG : file.txt: writing chunk 3 2026/11/02 01:47:11 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 finished 2026/11/02 01:47:11 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 finished 2026/11/02 01:47:11 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit 2026/11/02 01:47:11 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/02 01:47:11 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2026/11/02 01:47:11 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams 2026/11/02 01:47:11 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting 2026/11/02 01:47:11 DEBUG : file.txt: writing chunk 1 2026/11/02 01:47:11 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 finished 2026/11/02 01:47:11 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting 2026/11/02 01:47:11 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting 2026/11/02 01:47:11 DEBUG : file.txt: writing chunk 2 2026/11/02 01:47:11 DEBUG : file.txt: writing chunk 0 2026/11/02 01:47:11 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 finished 2026/11/02 01:47:11 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 finished 2026/11/02 01:47:11 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 starting 2026/11/02 01:47:11 DEBUG : file.txt: writing chunk 3 2026/11/02 01:47:11 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 finished 2026/11/02 01:47:11 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit 2026/11/02 01:47:11 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/02 01:47:11 DEBUG : a: size = 0 OK 2026/11/02 01:47:11 DEBUG : a: size = 1 (memory) 2026/11/02 01:47:11 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/02 01:47:11 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/ErrorAtStart === RUN TestReOpen/Normal/WithErrors 2026/11/02 01:47:11 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/11/02 01:47:11 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/11/02 01:47:11 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/Normal/TooManyErrors 2026/11/02 01:47:11 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/11/02 01:47:11 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/11/02 01:47:11 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/11/02 01:47:11 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/Normal/ReadAt 2026/11/02 01:47:11 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/11/02 01:47:11 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/11/02 01:47:11 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/Normal/Seek 2026/11/02 01:47:11 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/11/02 01:47:11 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/11/02 01:47:11 DEBUG : potato: Seek from 5 to 2 === RUN TestReOpen/Normal/AccountRead === RUN TestReOpen/Normal/AccountReadDelay 2026/11/02 01:47:11 DEBUG : potato: Seek from 10 to 0 2026/11/02 01:47:11 DEBUG : potato: Seek from 10 to 0 2026/11/02 01:47:11 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/AccountReadError === RUN TestReOpen/WithRangeOption === RUN TestReOpen/WithRangeOption/Basics 2026/11/02 01:47:11 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/ErrorAtStart === RUN TestReOpen/WithRangeOption/WithErrors 2026/11/02 01:47:11 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/11/02 01:47:11 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/11/02 01:47:11 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithRangeOption/TooManyErrors 2026/11/02 01:47:11 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/11/02 01:47:11 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/11/02 01:47:11 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/11/02 01:47:11 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithRangeOption/ReadAt 2026/11/02 01:47:11 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/11/02 01:47:11 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/11/02 01:47:11 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/WithRangeOption/Seek 2026/11/02 01:47:11 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/11/02 01:47:11 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/11/02 01:47:11 DEBUG : potato: Seek from 5 to 2 2026/11/02 01:47:11 DEBUG : potato: Seek from 7 to 4 === RUN TestReOpen/WithRangeOption/AccountRead === RUN TestReOpen/WithRangeOption/AccountReadDelay 2026/11/02 01:47:11 DEBUG : potato: Seek from 7 to 0 2026/11/02 01:47:11 DEBUG : potato: Seek from 7 to 0 2026/11/02 01:47:11 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/AccountReadError === RUN TestReOpen/WithSeekOption === RUN TestReOpen/WithSeekOption/Basics 2026/11/02 01:47:11 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/ErrorAtStart === RUN TestReOpen/WithSeekOption/WithErrors 2026/11/02 01:47:11 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/11/02 01:47:11 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/11/02 01:47:11 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithSeekOption/TooManyErrors 2026/11/02 01:47:11 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/11/02 01:47:11 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/11/02 01:47:11 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/11/02 01:47:11 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithSeekOption/ReadAt 2026/11/02 01:47:11 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/11/02 01:47:11 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/11/02 01:47:11 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/WithSeekOption/Seek 2026/11/02 01:47:11 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/11/02 01:47:11 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/11/02 01:47:11 DEBUG : potato: Seek from 5 to 2 2026/11/02 01:47:11 DEBUG : potato: Seek from 7 to 5 === RUN TestReOpen/WithSeekOption/AccountRead === RUN TestReOpen/WithSeekOption/AccountReadDelay 2026/11/02 01:47:11 DEBUG : potato: Seek from 8 to 0 2026/11/02 01:47:11 DEBUG : potato: Seek from 8 to 0 2026/11/02 01:47:11 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/AccountReadError === RUN TestReOpen/UnknownSize === RUN TestReOpen/UnknownSize/Basics 2026/11/02 01:47:11 DEBUG : potato: Seek from 9 to 0 === RUN TestReOpen/UnknownSize/ErrorAtStart === RUN TestReOpen/UnknownSize/WithErrors 2026/11/02 01:47:11 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/11/02 01:47:11 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/11/02 01:47:11 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/UnknownSize/TooManyErrors 2026/11/02 01:47:11 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/11/02 01:47:11 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/11/02 01:47:11 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/11/02 01:47:11 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/UnknownSize/ReadAt 2026/11/02 01:47:11 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/11/02 01:47:11 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/11/02 01:47:11 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/UnknownSize/Seek 2026/11/02 01:47:11 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/11/02 01:47:11 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/11/02 01:47:11 DEBUG : potato: Seek from 5 to 2 2026/11/02 01:47:11 DEBUG : potato: Seek from 7 to 6 === RUN TestReOpen/UnknownSize/AccountRead === RUN TestReOpen/UnknownSize/AccountReadDelay 2026/11/02 01:47:11 DEBUG : potato: Seek from 9 to 0 2026/11/02 01:47:11 DEBUG : potato: Seek from 9 to 0 2026/11/02 01:47:11 DEBUG : potato: Seek from 9 to 0 === RUN TestReOpen/UnknownSize/AccountReadError --- PASS: TestReOpen (0.00s) --- PASS: TestReOpen/Normal (0.00s) --- PASS: TestReOpen/Normal/Basics (0.00s) --- PASS: TestReOpen/Normal/ErrorAtStart (0.00s) --- PASS: TestReOpen/Normal/WithErrors (0.00s) --- PASS: TestReOpen/Normal/TooManyErrors (0.00s) --- PASS: TestReOpen/Normal/ReadAt (0.00s) --- PASS: TestReOpen/Normal/Seek (0.00s) --- PASS: TestReOpen/Normal/AccountRead (0.00s) --- PASS: TestReOpen/Normal/AccountReadDelay (0.00s) --- PASS: TestReOpen/Normal/AccountReadError (0.00s) --- PASS: TestReOpen/WithRangeOption (0.00s) --- PASS: TestReOpen/WithRangeOption/Basics (0.00s) --- PASS: TestReOpen/WithRangeOption/ErrorAtStart (0.00s) --- PASS: TestReOpen/WithRangeOption/WithErrors (0.00s) --- PASS: TestReOpen/WithRangeOption/TooManyErrors (0.00s) --- PASS: TestReOpen/WithRangeOption/ReadAt (0.00s) --- PASS: TestReOpen/WithRangeOption/Seek (0.00s) --- PASS: TestReOpen/WithRangeOption/AccountRead (0.00s) --- PASS: TestReOpen/WithRangeOption/AccountReadDelay (0.00s) --- PASS: TestReOpen/WithRangeOption/AccountReadError (0.00s) --- PASS: TestReOpen/WithSeekOption (0.00s) --- PASS: TestReOpen/WithSeekOption/Basics (0.00s) --- PASS: TestReOpen/WithSeekOption/ErrorAtStart (0.00s) --- PASS: TestReOpen/WithSeekOption/WithErrors (0.00s) --- PASS: TestReOpen/WithSeekOption/TooManyErrors (0.00s) --- PASS: TestReOpen/WithSeekOption/ReadAt (0.00s) --- PASS: TestReOpen/WithSeekOption/Seek (0.00s) --- PASS: TestReOpen/WithSeekOption/AccountRead (0.00s) --- PASS: TestReOpen/WithSeekOption/AccountReadDelay (0.00s) --- PASS: TestReOpen/WithSeekOption/AccountReadError (0.00s) --- PASS: TestReOpen/UnknownSize (0.00s) --- PASS: TestReOpen/UnknownSize/Basics (0.00s) --- PASS: TestReOpen/UnknownSize/ErrorAtStart (0.00s) --- PASS: TestReOpen/UnknownSize/WithErrors (0.00s) --- PASS: TestReOpen/UnknownSize/TooManyErrors (0.00s) --- PASS: TestReOpen/UnknownSize/ReadAt (0.00s) --- PASS: TestReOpen/UnknownSize/Seek (0.00s) --- PASS: TestReOpen/UnknownSize/AccountRead (0.00s) --- PASS: TestReOpen/UnknownSize/AccountReadDelay (0.00s) --- PASS: TestReOpen/UnknownSize/AccountReadError (0.00s) === RUN TestCheck run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 01:47:13 DEBUG : rutabaga: md5 = ef055cbad3d9a0f28b67552dbfbda02a OK === RUN TestCheck/1 === RUN TestCheck/2 2026/11/02 01:47:15 DEBUG : empty space: md5 = 59cc70b29ded90bb40108d08aa921362 OK === RUN TestCheck/3 2026/11/02 01:47:17 DEBUG : potato2: md5 = a6d86616abd656271927d188b0f5b284 OK === RUN TestCheck/4 === RUN TestCheck/5 2026/11/02 01:47:19 DEBUG : remotepotato: md5 = d79420fdb52c1eba0b007edcdeefe347 OK === RUN TestCheck/6 === RUN TestCheck/7 --- PASS: TestCheck (11.43s) --- PASS: TestCheck/1 (0.22s) --- PASS: TestCheck/2 (0.23s) --- PASS: TestCheck/3 (0.30s) --- PASS: TestCheck/4 (0.21s) --- PASS: TestCheck/5 (0.23s) --- PASS: TestCheck/6 (0.26s) --- PASS: TestCheck/7 (0.24s) === RUN TestCheckFsError 2026/11/02 01:47:22 DEBUG : Creating backend with remote "nonexistent" 2026/11/02 01:47:22 DEBUG : Creating backend with remote "nonexistent" 2026/11/02 01:47:22 DEBUG : Local file system at nonexistent: Waiting for checks to finish 2026/11/02 01:47:22 ERROR : Local file system at nonexistent: error reading source root directory: directory not found 2026/11/02 01:47:22 NOTICE: Local file system at nonexistent: 0 differences found 2026/11/02 01:47:22 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-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 01:47:24 DEBUG : rutabaga: md5 = 3a0321a956299b8c615cd335e82af48f OK === RUN TestCheckDownload/1 === RUN TestCheckDownload/2 2026/11/02 01:47:27 DEBUG : empty space: md5 = 2b996140437b341cc789b741ab43436f OK === RUN TestCheckDownload/3 2026/11/02 01:47:30 DEBUG : potato2: md5 = db206c8f9c312441f9d69aa02f2d4253 OK === RUN TestCheckDownload/4 === RUN TestCheckDownload/5 2026/11/02 01:47:33 DEBUG : remotepotato: md5 = 0ca5e8a772b4283c15e1667c95871d84 OK === RUN TestCheckDownload/6 === RUN TestCheckDownload/7 --- PASS: TestCheckDownload (14.93s) --- PASS: TestCheckDownload/1 (0.91s) --- PASS: TestCheckDownload/2 (0.95s) --- PASS: TestCheckDownload/3 (0.72s) --- PASS: TestCheckDownload/4 (0.91s) --- PASS: TestCheckDownload/5 (0.80s) --- PASS: TestCheckDownload/6 (0.87s) --- PASS: TestCheckDownload/7 (0.72s) === RUN TestCheckSizeOnly run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 01:47:39 DEBUG : rutabaga: md5 = d11f73395f5a44afb782be8b6a6c8e53 OK === RUN TestCheckSizeOnly/1 === RUN TestCheckSizeOnly/2 2026/11/02 01:47:41 DEBUG : empty space: md5 = 94580354695d97650f8f24c8835e2076 OK === RUN TestCheckSizeOnly/3 2026/11/02 01:47:43 DEBUG : potato2: md5 = 0515ce0ea61762cf665af26d600902f0 OK === RUN TestCheckSizeOnly/4 === RUN TestCheckSizeOnly/5 2026/11/02 01:47:45 DEBUG : remotepotato: md5 = 16497904a207037cd826d1f7eff74979 OK === RUN TestCheckSizeOnly/6 === RUN TestCheckSizeOnly/7 --- PASS: TestCheckSizeOnly (10.87s) --- PASS: TestCheckSizeOnly/1 (0.22s) --- PASS: TestCheckSizeOnly/2 (0.25s) --- PASS: TestCheckSizeOnly/3 (0.24s) --- PASS: TestCheckSizeOnly/4 (0.24s) --- PASS: TestCheckSizeOnly/5 (0.24s) --- PASS: TestCheckSizeOnly/6 (0.24s) --- PASS: TestCheckSizeOnly/7 (0.29s) === RUN TestCheckEqualReaders --- PASS: TestCheckEqualReaders (0.00s) === RUN TestParseSumFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 01:47:50 DEBUG : test.sum: md5 = cf435591f8589c66db71d95212a621da OK 2026/11/02 01:47:50 NOTICE: test.sum: improperly formatted checksum line 4 2026/11/02 01:47:50 NOTICE: test.sum: improperly formatted checksum line 5 2026/11/02 01:47:50 NOTICE: test.sum: improperly formatted checksum line 6 2026/11/02 01:47:50 NOTICE: test.sum: 2 warning(s) suppressed... 2026/11/02 01:47:52 DEBUG : test.sum: md5 = 678035610301508901c40ecf973b2ba1 OK 2026/11/02 01:47:53 NOTICE: test.sum: improperly formatted checksum line 4 2026/11/02 01:47:53 NOTICE: test.sum: improperly formatted checksum line 5 2026/11/02 01:47:53 NOTICE: test.sum: improperly formatted checksum line 6 2026/11/02 01:47:53 NOTICE: test.sum: 2 warning(s) suppressed... --- PASS: TestParseSumFile (5.48s) === RUN TestCheckSum run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 01:47:54 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-wiyopif5fuya/data" 2026/11/02 01:47:54 DEBUG : Config file has changed externally - reloading 2026/11/02 01:47:54 DEBUG : Creating backend with remote "TestDrive:crypt/vsdfbtsla04vamvkknaanjiop2bgv6q97ajse673ngudlhnuln10/vjrnln8ratgmqakfosrqe8espk" check_test.go:354: Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya/data' lacks md5, skipping --- SKIP: TestCheckSum (2.00s) === RUN TestCheckSumDownload run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 01:47:56 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-wiyopif5fuya/data" 2026/11/02 01:47:56 DEBUG : Creating backend with remote "TestDrive:crypt/vsdfbtsla04vamvkknaanjiop2bgv6q97ajse673ngudlhnuln10/vjrnln8ratgmqakfosrqe8espk" 2026/11/02 01:47:59 DEBUG : data/banana: md5 = e36a714437abc5fa2fd620f1b3ed7cf2 OK 2026/11/02 01:48:01 DEBUG : test.sum: md5 = f2e425b51ca12afad5490e4cedb5ba6c OK === RUN TestCheckSumDownload/subtest1 2026/11/02 01:48:05 DEBUG : data/potato: md5 = 4a21f8aa45f30fe63e2992f63ab0959d OK 2026/11/02 01:48:06 DEBUG : test.sum: md5 = 587fb5060c5cb4b79907c6f36f5ad320 OK === RUN TestCheckSumDownload/subtest2 2026/11/02 01:48:10 DEBUG : test.sum: md5 = d08b37f3da06cacff08de78b957bed07 OK === RUN TestCheckSumDownload/subtest3 2026/11/02 01:48:13 DEBUG : test.sum: md5 = 8423b5cb90882757d841d4672537b76e OK === RUN TestCheckSumDownload/subtest4 2026/11/02 01:48:17 DEBUG : test.sum: md5 = cf79ad563ac8b00b586a42d30fa244d7 OK === RUN TestCheckSumDownload/subtest5 2026/11/02 01:48:21 DEBUG : test.sum: md5 = b2b05b0419c39610a4314bd8b27adbee OK === RUN TestCheckSumDownload/subtest6 2026/11/02 01:48:24 DEBUG : data/banana: md5 = 7ca15e0c86d39adc39007a502d320d23 OK 2026/11/02 01:48:26 DEBUG : data/potato: md5 = 8ba8f126d6f0a27af0ea4128df1e0316 OK 2026/11/02 01:48:27 DEBUG : test.sum: md5 = 99048736b3ec1cd27057f934373c74bc OK === RUN TestCheckSumDownload/subtest7 --- PASS: TestCheckSumDownload (36.39s) --- PASS: TestCheckSumDownload/subtest1 (2.11s) --- PASS: TestCheckSumDownload/subtest2 (1.88s) --- PASS: TestCheckSumDownload/subtest3 (1.74s) --- PASS: TestCheckSumDownload/subtest4 (1.82s) --- PASS: TestCheckSumDownload/subtest5 (1.65s) --- PASS: TestCheckSumDownload/subtest6 (1.78s) --- PASS: TestCheckSumDownload/subtest7 (1.89s) === RUN TestCheckSumConcurrency 2026/11/02 01:48:32 DEBUG : file0: md5 = 022eb0c0085c731f335df970fafa7446 OK 2026/11/02 01:48:32 DEBUG : file2: md5 = a58f09ae3db8ae4b943fdbaace096072 OK 2026/11/02 01:48:32 DEBUG : file1: md5 = 17fbabbccbdea0e6be9eb64c8c6fea41 OK 2026/11/02 01:48:32 DEBUG : file3: md5 = e0639b17e0744aca9a3ba29c049e59ee OK 2026/11/02 01:48:32 NOTICE: Mock file system at checkSum: 0 differences found 2026/11/02 01:48:32 NOTICE: Mock file system at checkSum: 4 matching files --- PASS: TestCheckSumConcurrency (0.00s) === RUN TestCheckSumDownloadConcurrency 2026/11/02 01:48:32 DEBUG : file0: md5 = 022eb0c0085c731f335df970fafa7446 OK 2026/11/02 01:48:32 DEBUG : file2: md5 = a58f09ae3db8ae4b943fdbaace096072 OK 2026/11/02 01:48:32 DEBUG : file1: md5 = 17fbabbccbdea0e6be9eb64c8c6fea41 OK 2026/11/02 01:48:32 DEBUG : file3: md5 = e0639b17e0744aca9a3ba29c049e59ee OK 2026/11/02 01:48:32 NOTICE: Mock file system at checkSum: 0 differences found 2026/11/02 01:48:32 NOTICE: Mock file system at checkSum: 4 matching files --- PASS: TestCheckSumDownloadConcurrency (0.00s) === RUN TestApplyTransforms 2026/11/02 01:48:32 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-musayax2fubu" 2026/11/02 01:48:32 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/11/02 01:48:32 DEBUG : Creating backend with remote "TestDrive:crypt/2364quauaipuucllveumjbj2o0u6lotgsbvjosd17sqr7hp3nj80" 2026/11/02 01:48:33 DEBUG : Creating backend with remote "/tmp/rclone357248138" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-musayax2fubu'", Local "Local file system at /tmp/rclone357248138", Modify Window "1ms" 2026/11/02 01:48:36 DEBUG : hello, world!: md5 = eb1565e6e614efaef84bed63175d5302 OK upper checkfile vs. lower remote (without normalization) 2026/11/02 01:48:36 ERROR : hello, world!: sum not found 2026/11/02 01:48:36 ERROR : HELLO, WORLD!: file not in Encrypted drive 'TestCryptDrive:rclone-test-musayax2fubu' 2026/11/02 01:48:36 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-musayax2fubu': 1 files missing 2026/11/02 01:48:36 NOTICE: 1 hashes missing 2026/11/02 01:48:36 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-musayax2fubu': 1 differences found 2026/11/02 01:48:36 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-musayax2fubu': 2 errors while checking upper checkfile vs. lower remote (with normalization) 2026/11/02 01:48:37 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/11/02 01:48:37 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-musayax2fubu': 0 differences found 2026/11/02 01:48:37 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-musayax2fubu': 1 matching files 2026/11/02 01:48:37 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jazoyiv3jini" 2026/11/02 01:48:37 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/11/02 01:48:37 DEBUG : Creating backend with remote "TestDrive:crypt/fs25o4a27mgq0e1mqcgvka6346q5hpom32hdfukvjbrrq81oslt0" 2026/11/02 01:48:38 DEBUG : Creating backend with remote "/tmp/rclone3329800938" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jazoyiv3jini'", Local "Local file system at /tmp/rclone3329800938", Modify Window "1ms" 2026/11/02 01:48:40 DEBUG : HELLO, WORLD!: md5 = 365ae2ad13a63e7188ac4d9098ad8f0d OK lower checkfile vs. upper remote (without normalization) 2026/11/02 01:48:41 ERROR : HELLO, WORLD!: sum not found 2026/11/02 01:48:41 ERROR : hello, world!: file not in Encrypted drive 'TestCryptDrive:rclone-test-jazoyiv3jini' 2026/11/02 01:48:41 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-jazoyiv3jini': 1 files missing 2026/11/02 01:48:41 NOTICE: 1 hashes missing 2026/11/02 01:48:41 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-jazoyiv3jini': 1 differences found 2026/11/02 01:48:41 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-jazoyiv3jini': 2 errors while checking lower checkfile vs. upper remote (with normalization) 2026/11/02 01:48:42 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/11/02 01:48:42 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-jazoyiv3jini': 0 differences found 2026/11/02 01:48:42 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-jazoyiv3jini': 1 matching files 2026/11/02 01:48:42 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-bigahon8fuji" 2026/11/02 01:48:42 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/11/02 01:48:42 DEBUG : Creating backend with remote "TestDrive:crypt/s7mqqmij9q55sgjrqg001b4ij4b37s5ouovdpgnbfp29rjpjramg" 2026/11/02 01:48:43 DEBUG : Creating backend with remote "/tmp/rclone3443555525" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-bigahon8fuji'", Local "Local file system at /tmp/rclone3443555525", Modify Window "1ms" 2026/11/02 01:48:45 DEBUG : HeLlO, wOrLd!: md5 = 05175b1fd53cb013d21cdaa63ca8244b OK lower checkfile vs. upperlowermixed remote (without normalization) 2026/11/02 01:48:45 ERROR : HeLlO, wOrLd!: sum not found 2026/11/02 01:48:45 ERROR : hello, world!: file not in Encrypted drive 'TestCryptDrive:rclone-test-bigahon8fuji' 2026/11/02 01:48:45 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-bigahon8fuji': 1 files missing 2026/11/02 01:48:45 NOTICE: 1 hashes missing 2026/11/02 01:48:45 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-bigahon8fuji': 1 differences found 2026/11/02 01:48:45 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-bigahon8fuji': 2 errors while checking lower checkfile vs. upperlowermixed remote (with normalization) 2026/11/02 01:48:46 DEBUG : HeLlO, wOrLd!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/11/02 01:48:46 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-bigahon8fuji': 0 differences found 2026/11/02 01:48:46 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-bigahon8fuji': 1 matching files 2026/11/02 01:48:46 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-wogatif5niji" 2026/11/02 01:48:46 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/11/02 01:48:46 DEBUG : Creating backend with remote "TestDrive:crypt/mgd08h2im169s3fseafv1ff0f3p1uv3r82lrl464ia9203uh5j2g" 2026/11/02 01:48:47 DEBUG : Creating backend with remote "/tmp/rclone1741709000" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wogatif5niji'", Local "Local file system at /tmp/rclone1741709000", Modify Window "1ms" 2026/11/02 01:48:49 DEBUG : HELLO, WORLD!: md5 = 6a93ebe4da6eeaf41279f9f3818e90a8 OK upperlowermixed checkfile vs. upper remote (without normalization) 2026/11/02 01:48:50 ERROR : HELLO, WORLD!: sum not found 2026/11/02 01:48:50 ERROR : HeLlO, wOrLd!: file not in Encrypted drive 'TestCryptDrive:rclone-test-wogatif5niji' 2026/11/02 01:48:50 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-wogatif5niji': 1 files missing 2026/11/02 01:48:50 NOTICE: 1 hashes missing 2026/11/02 01:48:50 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-wogatif5niji': 1 differences found 2026/11/02 01:48:50 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-wogatif5niji': 2 errors while checking upperlowermixed checkfile vs. upper remote (with normalization) 2026/11/02 01:48:51 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/11/02 01:48:51 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-wogatif5niji': 0 differences found 2026/11/02 01:48:51 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-wogatif5niji': 1 matching files 2026/11/02 01:48:51 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-kiyexul4qebe" 2026/11/02 01:48:51 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/11/02 01:48:51 DEBUG : Creating backend with remote "TestDrive:crypt/8na6leucnj8rumur84lkc4l6lpbo5v18kl9s088j6u1rdisdpmm0" 2026/11/02 01:48:52 DEBUG : Creating backend with remote "/tmp/rclone3401065005" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kiyexul4qebe'", Local "Local file system at /tmp/rclone3401065005", Modify Window "1ms" 2026/11/02 01:48:54 DEBUG : 測試_Русский___ě_áñ: md5 = 38a134f366a048869f29197142324fcb OK NFD checkfile vs. NFC remote (without normalization) 2026/11/02 01:48:54 ERROR : 測試_Русский___ě_áñ: sum not found 2026/11/02 01:48:54 ERROR : 測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-kiyexul4qebe' 2026/11/02 01:48:54 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kiyexul4qebe': 1 files missing 2026/11/02 01:48:54 NOTICE: 1 hashes missing 2026/11/02 01:48:54 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kiyexul4qebe': 1 differences found 2026/11/02 01:48:54 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kiyexul4qebe': 2 errors while checking NFD checkfile vs. NFC remote (with normalization) 2026/11/02 01:48:55 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/11/02 01:48:55 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kiyexul4qebe': 0 differences found 2026/11/02 01:48:55 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kiyexul4qebe': 1 matching files 2026/11/02 01:48:55 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-nepifuy5paju" 2026/11/02 01:48:55 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/11/02 01:48:55 DEBUG : Creating backend with remote "TestDrive:crypt/sgu9vvkra43cgf2itp7krjptcpoctuqdi5ajd6999efoj99785s0" 2026/11/02 01:48:56 DEBUG : Creating backend with remote "/tmp/rclone3127420029" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-nepifuy5paju'", Local "Local file system at /tmp/rclone3127420029", Modify Window "1ms" 2026/11/02 01:48:58 DEBUG : 測試_Русский___ě_áñ: md5 = f7f26e93385243256f4dd8dbcd6487b1 OK NFC checkfile vs. NFD remote (without normalization) 2026/11/02 01:48:59 ERROR : 測試_Русский___ě_áñ: sum not found 2026/11/02 01:48:59 ERROR : 測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-nepifuy5paju' 2026/11/02 01:48:59 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-nepifuy5paju': 1 files missing 2026/11/02 01:48:59 NOTICE: 1 hashes missing 2026/11/02 01:48:59 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-nepifuy5paju': 1 differences found 2026/11/02 01:48:59 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-nepifuy5paju': 2 errors while checking NFC checkfile vs. NFD remote (with normalization) 2026/11/02 01:49:00 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/11/02 01:49:00 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-nepifuy5paju': 0 differences found 2026/11/02 01:49:00 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-nepifuy5paju': 1 matching files 2026/11/02 01:49:00 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-bawatev4qeha" 2026/11/02 01:49:00 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/11/02 01:49:00 DEBUG : Creating backend with remote "TestDrive:crypt/4dkuo3ur6n55fc314t90t9enjmd07fbqv2flmupu22pjcsd8am9g" 2026/11/02 01:49:01 DEBUG : Creating backend with remote "/tmp/rclone2883549551" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-bawatev4qeha'", Local "Local file system at /tmp/rclone2883549551", Modify Window "1ms" 2026/11/02 01:49:03 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 17fde7195291660f6a78cd384116cde8 OK NFDx2 checkfile vs. both remote (without normalization) 2026/11/02 01:49:03 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/11/02 01:49:03 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-bawatev4qeha' 2026/11/02 01:49:03 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-bawatev4qeha': 1 files missing 2026/11/02 01:49:03 NOTICE: 1 hashes missing 2026/11/02 01:49:03 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-bawatev4qeha': 1 differences found 2026/11/02 01:49:03 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-bawatev4qeha': 2 errors while checking NFDx2 checkfile vs. both remote (with normalization) 2026/11/02 01:49:04 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/11/02 01:49:04 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-bawatev4qeha': 0 differences found 2026/11/02 01:49:04 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-bawatev4qeha': 1 matching files 2026/11/02 01:49:04 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-kutulaq7vuxi" 2026/11/02 01:49:04 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/11/02 01:49:04 DEBUG : Creating backend with remote "TestDrive:crypt/pm3a07l0qp3r3t435h375n7paoncv0qkg9pomdm7q1bnk5gvidf0" 2026/11/02 01:49:05 DEBUG : Creating backend with remote "/tmp/rclone2215279758" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kutulaq7vuxi'", Local "Local file system at /tmp/rclone2215279758", Modify Window "1ms" 2026/11/02 01:49:07 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 68bc60dd12ef3793d1dc14baac0964cc OK NFCx2 checkfile vs. both remote (without normalization) 2026/11/02 01:49:08 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/11/02 01:49:08 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-kutulaq7vuxi' 2026/11/02 01:49:08 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kutulaq7vuxi': 1 files missing 2026/11/02 01:49:08 NOTICE: 1 hashes missing 2026/11/02 01:49:08 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kutulaq7vuxi': 1 differences found 2026/11/02 01:49:08 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kutulaq7vuxi': 2 errors while checking NFCx2 checkfile vs. both remote (with normalization) 2026/11/02 01:49:09 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/11/02 01:49:09 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kutulaq7vuxi': 0 differences found 2026/11/02 01:49:09 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kutulaq7vuxi': 1 matching files 2026/11/02 01:49:09 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-tajujoz7qonu" 2026/11/02 01:49:09 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/11/02 01:49:09 DEBUG : Creating backend with remote "TestDrive:crypt/i204loisnvpmhnh2tmvfld20sug38tc6lqcovuf8mkeirp8tabi0" 2026/11/02 01:49:10 DEBUG : Creating backend with remote "/tmp/rclone4122853618" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tajujoz7qonu'", Local "Local file system at /tmp/rclone4122853618", Modify Window "1ms" 2026/11/02 01:49:12 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 688531de39d6222fc11baf5cc613a80a OK both checkfile vs. NFDx2 remote (without normalization) 2026/11/02 01:49:12 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/11/02 01:49:12 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-tajujoz7qonu' 2026/11/02 01:49:12 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-tajujoz7qonu': 1 files missing 2026/11/02 01:49:12 NOTICE: 1 hashes missing 2026/11/02 01:49:12 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-tajujoz7qonu': 1 differences found 2026/11/02 01:49:12 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-tajujoz7qonu': 2 errors while checking both checkfile vs. NFDx2 remote (with normalization) 2026/11/02 01:49:13 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/11/02 01:49:13 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-tajujoz7qonu': 0 differences found 2026/11/02 01:49:13 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-tajujoz7qonu': 1 matching files 2026/11/02 01:49:13 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-ninaset3hiza" 2026/11/02 01:49:13 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/11/02 01:49:13 DEBUG : Creating backend with remote "TestDrive:crypt/hftrr3dhthp02s4pb6k5s0k4vnp2ukdqq5r629e2k3l1tdl9v49g" 2026/11/02 01:49:14 DEBUG : Creating backend with remote "/tmp/rclone1805674707" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-ninaset3hiza'", Local "Local file system at /tmp/rclone1805674707", Modify Window "1ms" 2026/11/02 01:49:16 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = d7002c4c5850687c23126c736c91e175 OK both checkfile vs. NFCx2 remote (without normalization) 2026/11/02 01:49:16 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/11/02 01:49:16 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-ninaset3hiza' 2026/11/02 01:49:16 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-ninaset3hiza': 1 files missing 2026/11/02 01:49:16 NOTICE: 1 hashes missing 2026/11/02 01:49:16 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-ninaset3hiza': 1 differences found 2026/11/02 01:49:16 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-ninaset3hiza': 2 errors while checking both checkfile vs. NFCx2 remote (with normalization) 2026/11/02 01:49:17 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/11/02 01:49:17 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-ninaset3hiza': 0 differences found 2026/11/02 01:49:17 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-ninaset3hiza': 1 matching files 2026/11/02 01:49:17 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-ninaset3hiza': Purge remote 2026/11/02 01:49:18 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-tajujoz7qonu': Purge remote 2026/11/02 01:49:18 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-kutulaq7vuxi': Purge remote 2026/11/02 01:49:19 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-bawatev4qeha': Purge remote 2026/11/02 01:49:19 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-nepifuy5paju': Purge remote 2026/11/02 01:49:19 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-kiyexul4qebe': Purge remote 2026/11/02 01:49:20 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-wogatif5niji': Purge remote 2026/11/02 01:49:20 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-bigahon8fuji': Purge remote 2026/11/02 01:49:21 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jazoyiv3jini': Purge remote 2026/11/02 01:49:21 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-musayax2fubu': Purge remote --- PASS: TestApplyTransforms (49.62s) === RUN TestTruncateString --- PASS: TestTruncateString (0.00s) === RUN TestCopyFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 01:49:22 DEBUG : file1: Need to transfer - File not found at Destination 2026/11/02 01:49:24 DEBUG : sub/file2: md5 = e0bbd84713d349de54266fb46c2f3315 OK 2026/11/02 01:49:24 DEBUG : sub/file2: size = 14 OK 2026/11/02 01:49:24 INFO : file1: Copied (new) to: sub/file2 2026/11/02 01:49:25 DEBUG : sub/file2: size = 14 OK 2026/11/02 01:49:25 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/11/02 01:49:25 DEBUG : file1: Unchanged skipping 2026/11/02 01:49:25 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya': don't need to copy/move sub/file2, it is already at target location --- PASS: TestCopyFile (5.68s) === RUN TestCopyFileImmutable run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 01:49:28 DEBUG : existing: Need to transfer - File not found at Destination 2026/11/02 01:49:29 DEBUG : existing: md5 = 4168afd605cea7c8b0bdc573ac2eef80 OK 2026/11/02 01:49:29 DEBUG : existing: size = 6 OK 2026/11/02 01:49:29 INFO : existing: Copied (new) 2026/11/02 01:49:30 DEBUG : existing: size = 6 OK 2026/11/02 01:49:30 DEBUG : existing: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/11/02 01:49:30 DEBUG : existing: Unchanged skipping 2026/11/02 01:49:30 DEBUG : existing: size = 8 (Local file system at /tmp/rclone1219163137) 2026/11/02 01:49:30 DEBUG : existing: size = 6 (Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya') 2026/11/02 01:49:30 DEBUG : existing: Sizes differ 2026/11/02 01:49:30 ERROR : existing: Source and destination exist but do not match: immutable file modified --- PASS: TestCopyFileImmutable (4.02s) === RUN TestCopyLongFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" copy_test.go:188: Test only runs on local --- SKIP: TestCopyLongFile (0.44s) === RUN TestCopyFileBackupDir run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 01:49:34 DEBUG : dst/file1: md5 = f93e896e93c132cbbcfe53c6d2fcc142 OK 2026/11/02 01:49:35 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-wiyopif5fuya/backup" 2026/11/02 01:49:35 DEBUG : Creating backend with remote "TestDrive:crypt/vsdfbtsla04vamvkknaanjiop2bgv6q97ajse673ngudlhnuln10/1nrff024r7pq65ecp72fc28jb0" 2026/11/02 01:49:36 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone1219163137) 2026/11/02 01:49:36 DEBUG : dst/file1: size = 18 (Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya') 2026/11/02 01:49:36 DEBUG : dst/file1: Sizes differ 2026/11/02 01:49:39 INFO : dst/file1: Moved (server-side) 2026/11/02 01:49:40 DEBUG : dst/file1: md5 = dfd2f45e60e0e53df72066cff06feb5f OK 2026/11/02 01:49:40 DEBUG : dst/file1: size = 14 OK 2026/11/02 01:49:40 INFO : dst/file1: Copied (new) --- PASS: TestCopyFileBackupDir (12.67s) === RUN TestCopyFileCompareDest run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 01:49:44 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-wiyopif5fuya/dst" 2026/11/02 01:49:45 DEBUG : Creating backend with remote "TestDrive:crypt/vsdfbtsla04vamvkknaanjiop2bgv6q97ajse673ngudlhnuln10/31u3jie661vd5p8j7rtc3hgbh0" 2026/11/02 01:49:46 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-wiyopif5fuya/CompareDest" 2026/11/02 01:49:46 DEBUG : Creating backend with remote "TestDrive:crypt/vsdfbtsla04vamvkknaanjiop2bgv6q97ajse673ngudlhnuln10/gveqi14airsml4bgu7krj116o8" 2026/11/02 01:49:48 DEBUG : one: Need to transfer - File not found at Destination 2026/11/02 01:49:50 DEBUG : one: md5 = 0221f50389659e278e0afb3f996f75a8 OK 2026/11/02 01:49:50 DEBUG : one: size = 3 OK 2026/11/02 01:49:50 INFO : one: Copied (new) 2026/11/02 01:49:51 DEBUG : one: size = 5 (Local file system at /tmp/rclone1219163137) 2026/11/02 01:49:51 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya/dst') 2026/11/02 01:49:51 DEBUG : one: Sizes differ 2026/11/02 01:49:53 DEBUG : one: md5 = 8240c6af50564c551dcf88b340404752 OK 2026/11/02 01:49:53 DEBUG : one: size = 5 OK 2026/11/02 01:49:53 INFO : one: Copied (replaced existing) 2026/11/02 01:49:55 DEBUG : dst/one: md5 = 3f88dc52a8c97e4add810c8b2fffc4df OK 2026/11/02 01:49:57 DEBUG : CompareDest/one: md5 = a4904a49ba0d5439092d2953c13a27e8 OK 2026/11/02 01:49:58 DEBUG : one: size = 5 (Local file system at /tmp/rclone1219163137) 2026/11/02 01:49:58 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya/dst') 2026/11/02 01:49:58 DEBUG : one: Sizes differ 2026/11/02 01:49:59 DEBUG : one: size = 5 OK 2026/11/02 01:49:59 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/11/02 01:49:59 DEBUG : one: Destination found in --compare-dest, skipping 2026/11/02 01:50:00 DEBUG : CompareDest/two: md5 = c6826d8dba3e92f93f01f46343f960c0 OK 2026/11/02 01:50:01 DEBUG : two: Need to transfer - File not found at Destination 2026/11/02 01:50:01 DEBUG : two: size = 3 OK 2026/11/02 01:50:01 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/11/02 01:50:01 DEBUG : two: Destination found in --compare-dest, skipping 2026/11/02 01:50:02 DEBUG : two: Need to transfer - File not found at Destination 2026/11/02 01:50:02 DEBUG : two: size = 3 OK 2026/11/02 01:50:02 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/11/02 01:50:02 DEBUG : two: Destination found in --compare-dest, skipping 2026/11/02 01:50:03 DEBUG : two: Need to transfer - File not found at Destination 2026/11/02 01:50:04 DEBUG : two: size = 5 (Local file system at /tmp/rclone1219163137) 2026/11/02 01:50:04 DEBUG : two: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya/CompareDest') 2026/11/02 01:50:04 DEBUG : two: Sizes differ 2026/11/02 01:50:05 DEBUG : two: md5 = de05cfd86ea1a8ca1e5b16ac690e733d OK 2026/11/02 01:50:05 DEBUG : two: size = 5 OK 2026/11/02 01:50:05 INFO : two: Copied (new) --- PASS: TestCopyFileCompareDest (24.68s) === RUN TestCopyFileCopyDestImmutable run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 01:50:09 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-wiyopif5fuya/dst" 2026/11/02 01:50:09 DEBUG : Creating backend with remote "TestDrive:crypt/vsdfbtsla04vamvkknaanjiop2bgv6q97ajse673ngudlhnuln10/31u3jie661vd5p8j7rtc3hgbh0" 2026/11/02 01:50:13 DEBUG : dst/one: md5 = f7889a3a256d49c7fd077cc8ab2c99b1 OK 2026/11/02 01:50:15 DEBUG : CopyDest/one: md5 = 7f0c55d145de1b6e2672c1f3db01579f OK 2026/11/02 01:50:16 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-wiyopif5fuya/CopyDest" 2026/11/02 01:50:16 DEBUG : Creating backend with remote "TestDrive:crypt/vsdfbtsla04vamvkknaanjiop2bgv6q97ajse673ngudlhnuln10/d09o6po3f7bm6ce32vdgs8h9ls" 2026/11/02 01:50:17 DEBUG : one: size = 5 (Local file system at /tmp/rclone1219163137) 2026/11/02 01:50:17 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya/dst') 2026/11/02 01:50:17 DEBUG : one: Sizes differ 2026/11/02 01:50:17 DEBUG : one: size = 5 OK 2026/11/02 01:50:17 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/11/02 01:50:17 DEBUG : one: size = 5 (Local file system at /tmp/rclone1219163137) 2026/11/02 01:50:17 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya/dst') 2026/11/02 01:50:17 DEBUG : one: Sizes differ 2026/11/02 01:50:17 ERROR : one: Source and destination exist but do not match: immutable file modified --- PASS: TestCopyFileCopyDestImmutable (11.05s) === RUN TestCopyFileCopyDest run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 01:50:20 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-wiyopif5fuya/dst" 2026/11/02 01:50:20 DEBUG : Creating backend with remote "TestDrive:crypt/vsdfbtsla04vamvkknaanjiop2bgv6q97ajse673ngudlhnuln10/31u3jie661vd5p8j7rtc3hgbh0" 2026/11/02 01:50:22 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-wiyopif5fuya/CopyDest" 2026/11/02 01:50:22 DEBUG : Creating backend with remote "TestDrive:crypt/vsdfbtsla04vamvkknaanjiop2bgv6q97ajse673ngudlhnuln10/d09o6po3f7bm6ce32vdgs8h9ls" 2026/11/02 01:50:23 DEBUG : one: Need to transfer - File not found at Destination 2026/11/02 01:50:26 DEBUG : one: md5 = e8004d8682f7dbfb44df528c9ac8502a OK 2026/11/02 01:50:26 DEBUG : one: size = 3 OK 2026/11/02 01:50:26 INFO : one: Copied (new) 2026/11/02 01:50:27 DEBUG : one: size = 5 (Local file system at /tmp/rclone1219163137) 2026/11/02 01:50:27 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya/dst') 2026/11/02 01:50:27 DEBUG : one: Sizes differ 2026/11/02 01:50:28 DEBUG : one: md5 = 7f5daee98ef46743ddd3ffcf2c00d0fe OK 2026/11/02 01:50:28 DEBUG : one: size = 5 OK 2026/11/02 01:50:28 INFO : one: Copied (replaced existing) 2026/11/02 01:50:30 DEBUG : dst/one: md5 = d69304b0bd7b240e9811a1d5804fc4c6 OK 2026/11/02 01:50:32 DEBUG : CopyDest/one: md5 = b903c791d7ff2c60e55c9495819ef21a OK 2026/11/02 01:50:33 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-wiyopif5fuya/BackupDir" 2026/11/02 01:50:33 DEBUG : Creating backend with remote "TestDrive:crypt/vsdfbtsla04vamvkknaanjiop2bgv6q97ajse673ngudlhnuln10/s6dbk3lfi7c9kfvo6j7bla9m0g" 2026/11/02 01:50:34 DEBUG : one: size = 5 (Local file system at /tmp/rclone1219163137) 2026/11/02 01:50:34 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya/dst') 2026/11/02 01:50:34 DEBUG : one: Sizes differ 2026/11/02 01:50:35 DEBUG : one: size = 5 OK 2026/11/02 01:50:35 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/11/02 01:50:35 DEBUG : one: size = 5 (Local file system at /tmp/rclone1219163137) 2026/11/02 01:50:35 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya/dst') 2026/11/02 01:50:35 DEBUG : one: Sizes differ 2026/11/02 01:50:36 INFO : one: Moved (server-side) 2026/11/02 01:50:37 DEBUG : one: size = 5 OK 2026/11/02 01:50:37 INFO : one: Copied (server-side copy) 2026/11/02 01:50:37 DEBUG : one: Destination found in --copy-dest, using server-side copy 2026/11/02 01:50:39 DEBUG : CopyDest/two: md5 = e04fd984893486102d0ad38598ac0dbf OK 2026/11/02 01:50:40 DEBUG : two: Need to transfer - File not found at Destination 2026/11/02 01:50:40 DEBUG : two: size = 3 OK 2026/11/02 01:50:40 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/11/02 01:50:41 DEBUG : two: size = 3 OK 2026/11/02 01:50:41 INFO : two: Copied (server-side copy) 2026/11/02 01:50:41 DEBUG : two: Destination found in --copy-dest, using server-side copy 2026/11/02 01:50:42 DEBUG : two: size = 3 OK 2026/11/02 01:50:42 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/11/02 01:50:42 DEBUG : two: Unchanged skipping 2026/11/02 01:50:44 DEBUG : CopyDest/three: md5 = e090f734bc508ce6f7dbcfd88b493e79 OK 2026/11/02 01:50:45 DEBUG : three: Need to transfer - File not found at Destination 2026/11/02 01:50:45 DEBUG : three: size = 7 (Local file system at /tmp/rclone1219163137) 2026/11/02 01:50:45 DEBUG : three: size = 5 (Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya/CopyDest') 2026/11/02 01:50:45 DEBUG : three: Sizes differ 2026/11/02 01:50:45 DEBUG : three: Destination not found in --copy-dest 2026/11/02 01:50:46 DEBUG : three: md5 = be0ec88161cc1499906a7d2b6b22414d OK 2026/11/02 01:50:46 DEBUG : three: size = 7 OK 2026/11/02 01:50:46 INFO : three: Copied (new) --- PASS: TestCopyFileCopyDest (32.53s) === RUN TestCopyInplace run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" copy_test.go:434: Partial uploads not supported --- SKIP: TestCopyInplace (0.44s) === RUN TestCopyLongFileName run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" copy_test.go:467: Partial uploads not supported --- SKIP: TestCopyLongFileName (0.40s) === RUN TestCopyLongFileNameCollision run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" copy_test.go:500: Partial uploads not supported --- SKIP: TestCopyLongFileNameCollision (0.46s) === RUN TestCopyFileMaxTransfer run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 01:50:54 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination 2026/11/02 01:50:56 DEBUG : TestCopyFileMaxTransfer/file1: md5 = 15d49babf934c939384c800a85509445 OK 2026/11/02 01:50:56 DEBUG : TestCopyFileMaxTransfer/file1: size = 14 OK 2026/11/02 01:50:56 INFO : TestCopyFileMaxTransfer/file1: Copied (new) 2026/11/02 01:50:57 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination 2026/11/02 01:50:57 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/02 01:50:58 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination 2026/11/02 01:50:59 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination 2026/11/02 01:51:00 DEBUG : TestCopyFileMaxTransfer/file4: md5 = 42f139600bde148e329de83c02e2abdb OK 2026/11/02 01:51:00 DEBUG : TestCopyFileMaxTransfer/file4: size = 2062 OK 2026/11/02 01:51:00 INFO : TestCopyFileMaxTransfer/file4: Copied (new) --- PASS: TestCopyFileMaxTransfer (8.79s) === RUN TestDeduplicateInteractive run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" dedupe_test.go:37: Can't run this test without a hash --- SKIP: TestDeduplicateInteractive (0.50s) === RUN TestDeduplicateSkip run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 01:51:06 INFO : Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya': Looking for duplicate names using skip mode. 2026/11/02 01:51:07 NOTICE: one: Found 2 files with duplicate names 2026/11/02 01:51:07 NOTICE: one: Skipping 2 files with duplicate names --- PASS: TestDeduplicateSkip (4.90s) === RUN TestDeduplicateSizeOnly run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 01:51:12 INFO : Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya': Looking for duplicate names using skip mode. 2026/11/02 01:51:12 NOTICE: one: Found 3 files with duplicate names 2026/11/02 01:51:12 NOTICE: one: Deleting 1/2 identical duplicates (size 11) 2026/11/02 01:51:13 INFO : one: Deleted 2026/11/02 01:51:13 NOTICE: one: Skipping 2 files with duplicate names --- PASS: TestDeduplicateSizeOnly (6.21s) === RUN TestDeduplicateFirst run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 01:51:18 INFO : Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya': Looking for duplicate names using first mode. 2026/11/02 01:51:19 NOTICE: one: Found 3 files with duplicate names 2026/11/02 01:51:19 INFO : one: Deleted 2026/11/02 01:51:19 INFO : one: Deleted 2026/11/02 01:51:19 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateFirst (6.14s) === RUN TestDeduplicateNewest run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 01:51:25 INFO : Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya': Looking for duplicate names using newest mode. 2026/11/02 01:51:25 NOTICE: one: Found 3 files with duplicate names 2026/11/02 01:51:25 INFO : one: Deleted 2026/11/02 01:51:26 INFO : one: Deleted 2026/11/02 01:51:26 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateNewest (6.47s) === RUN TestDeduplicateNewestByHash run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" dedupe_test.go:37: Can't run this test without a hash --- SKIP: TestDeduplicateNewestByHash (0.41s) === RUN TestDeduplicateOldest run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 01:51:31 INFO : Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya': Looking for duplicate names using oldest mode. 2026/11/02 01:51:32 NOTICE: one: Found 3 files with duplicate names 2026/11/02 01:51:32 INFO : one: Deleted 2026/11/02 01:51:33 INFO : one: Deleted 2026/11/02 01:51:33 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateOldest (6.26s) === RUN TestDeduplicateLargest run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 01:51:37 INFO : Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya': Looking for duplicate names using largest mode. 2026/11/02 01:51:38 NOTICE: one: Found 3 files with duplicate names 2026/11/02 01:51:38 INFO : one: Deleted 2026/11/02 01:51:39 INFO : one: Deleted 2026/11/02 01:51:39 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateLargest (6.42s) === RUN TestDeduplicateSmallest run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 01:51:44 INFO : Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya': Looking for duplicate names using smallest mode. 2026/11/02 01:51:45 NOTICE: one: Found 3 files with duplicate names 2026/11/02 01:51:45 INFO : one: Deleted 2026/11/02 01:51:46 INFO : one: Deleted 2026/11/02 01:51:46 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateSmallest (6.65s) === RUN TestDeduplicateRename run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 01:51:52 INFO : Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya': Looking for duplicate names using rename mode. 2026/11/02 01:51:52 NOTICE: one.txt: Found 3 files with duplicate names 2026/11/02 01:51:53 INFO : one-2.txt: renamed from: one.txt 2026/11/02 01:51:54 INFO : one-3.txt: renamed from: one.txt 2026/11/02 01:51:54 INFO : one-4.txt: renamed from: one.txt --- PASS: TestDeduplicateRename (9.95s) === RUN TestDeduplicateRenameManyExisting run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 01:51:58 DEBUG : one-1.txt: md5 = a9bfbf1ee9ea9059a0e56de569153d9c OK 2026/11/02 01:52:00 DEBUG : one-2.txt: md5 = d3e866a2b09021aadcfc3e575be834c9 OK 2026/11/02 01:52:01 DEBUG : one-3.txt: md5 = 7d77b1dc9c9ad6d6d4cced030d442f0c OK 2026/11/02 01:52:02 DEBUG : one-4.txt: md5 = 51b87784e0ef3d97eb91457c75d65f0f OK 2026/11/02 01:52:04 DEBUG : one-5.txt: md5 = 709258c7733de28b4f478cf772faa330 OK 2026/11/02 01:52:05 DEBUG : one-6.txt: md5 = 25cb1f9c0c93aa8bad13dbf7ea0d40ce OK 2026/11/02 01:52:07 DEBUG : one-7.txt: md5 = 1d67aa0e393b7f8653cec21bf6d2cae6 OK 2026/11/02 01:52:08 DEBUG : one-8.txt: md5 = 49151073df8efc319bc4c7ce065733b7 OK 2026/11/02 01:52:10 DEBUG : one-9.txt: md5 = 04b370837ae02056c1864c5099178b95 OK 2026/11/02 01:52:11 DEBUG : one-10.txt: md5 = 4d86fcef8388ed4633f655cdfb004c5e OK 2026/11/02 01:52:12 DEBUG : one-11.txt: md5 = 70751c2b54cbe34792157e1c7418e63c OK 2026/11/02 01:52:14 DEBUG : one-12.txt: md5 = ce27216562be13b0230137c9dbac36cd OK 2026/11/02 01:52:15 DEBUG : one-13.txt: md5 = 867b94704c9de37fffb1e01f451eb2ab OK 2026/11/02 01:52:17 DEBUG : one-14.txt: md5 = 8649fd6ffd8d7e4f2754d4d93bc79fb7 OK 2026/11/02 01:52:18 DEBUG : one-15.txt: md5 = 23ed856f650bb6b8ac47f8cb7923e866 OK 2026/11/02 01:52:19 DEBUG : one-16.txt: md5 = 085d1424fe62cc6db39cc7956d21b320 OK 2026/11/02 01:52:21 DEBUG : one-17.txt: md5 = a4d04c17e2574e4a4cd40edf4c087d17 OK 2026/11/02 01:52:22 DEBUG : one-18.txt: md5 = abcd96e1f7305d3568993eebc154125c OK 2026/11/02 01:52:24 DEBUG : one-19.txt: md5 = 8e340c23384982b1ccd633239c80c905 OK 2026/11/02 01:52:25 DEBUG : one-20.txt: md5 = 897b9b8fb4d1d21f88cb3d9bacacc99f OK 2026/11/02 01:52:27 DEBUG : one-21.txt: md5 = aa4aec9ed50f0e05e9cb8c9a9c7703cb OK 2026/11/02 01:52:28 DEBUG : one-22.txt: md5 = 4bb74ed120fe14d71b9ad4bf2df115d5 OK 2026/11/02 01:52:30 DEBUG : one-23.txt: md5 = f1a4e74b562f61b454fbe4d479b1b9b8 OK 2026/11/02 01:52:31 DEBUG : one-24.txt: md5 = b40aa103db3d4ff1d8d331b28aee515a OK 2026/11/02 01:52:33 DEBUG : one-25.txt: md5 = 2eec29f1f86441aebcea0a594f43e7ab OK 2026/11/02 01:52:34 DEBUG : one-26.txt: md5 = 2994dcd2abcc9f83ae3e54396c3a1682 OK 2026/11/02 01:52:36 DEBUG : one-27.txt: md5 = fb612f792b9b2872b48ff1bb9b62fa2e OK 2026/11/02 01:52:37 DEBUG : one-28.txt: md5 = 6e69de318f078497d5d47222947012e2 OK 2026/11/02 01:52:38 DEBUG : one-29.txt: md5 = 951a9a982fafda40cdca28258ac92c06 OK 2026/11/02 01:52:40 DEBUG : one-30.txt: md5 = b1e2ff15e093e0b58a9b55317ba1a23c OK 2026/11/02 01:52:41 DEBUG : one-31.txt: md5 = eefc8f3dd64238824122a1b3ab72b392 OK 2026/11/02 01:52:43 DEBUG : one-32.txt: md5 = 8a540e6f8710f2db80e62b5dfcb9ebba OK 2026/11/02 01:52:44 DEBUG : one-33.txt: md5 = c75f7472bddc340423159db57a73bf63 OK 2026/11/02 01:52:45 DEBUG : one-34.txt: md5 = 843c03eedbaefbc62daf7604249b9213 OK 2026/11/02 01:52:47 DEBUG : one-35.txt: md5 = a878cf0e7cf09619e777e5ac3d523249 OK 2026/11/02 01:52:48 DEBUG : one-36.txt: md5 = dfb9a58c05806760d78b1ba61ebbd745 OK 2026/11/02 01:52:50 DEBUG : one-37.txt: md5 = 58a79d3f5b1527b2b801601112394d5d OK 2026/11/02 01:52:51 DEBUG : one-38.txt: md5 = 60059882e50752124d92d8eaa35bb61f OK 2026/11/02 01:52:53 DEBUG : one-39.txt: md5 = 42c04d7ab05c39c09e3325e993b1226c OK 2026/11/02 01:52:54 DEBUG : one-40.txt: md5 = c269fd39ed2c00b0d7eb5ffd0fe1391e OK 2026/11/02 01:52:56 DEBUG : one-41.txt: md5 = 0ef51ba5e62e9b043c3291dd72dc5bee OK 2026/11/02 01:52:57 DEBUG : one-42.txt: md5 = 8c3f4df4c837180fc4876632b23a9503 OK 2026/11/02 01:52:58 DEBUG : one-43.txt: md5 = 3ed94bff2512ea5c23333776e01026a6 OK 2026/11/02 01:53:00 DEBUG : one-44.txt: md5 = 037c32058ae69d8a262963964454aebb OK 2026/11/02 01:53:02 DEBUG : one-45.txt: md5 = ffea80ffbe6a19a8cb85d4d83deece68 OK 2026/11/02 01:53:03 DEBUG : one-46.txt: md5 = a62b2617d8a8f24a392a460706588ff5 OK 2026/11/02 01:53:04 DEBUG : one-47.txt: md5 = 9aa026be93922ae962393c1ea98abec0 OK 2026/11/02 01:53:06 DEBUG : one-48.txt: md5 = deab12d4a32c107649877bda85cd99a5 OK 2026/11/02 01:53:07 DEBUG : one-49.txt: md5 = 360066053eb0d99637e84ffab8e83d91 OK 2026/11/02 01:53:09 DEBUG : one-50.txt: md5 = aa5ebbb6561853679a8257d6e6f0ff16 OK 2026/11/02 01:53:10 DEBUG : one-51.txt: md5 = d0771730629f15d3949ae835552cd265 OK 2026/11/02 01:53:11 DEBUG : one-52.txt: md5 = 3bb44c397d3d82700df39581ce35a040 OK 2026/11/02 01:53:13 DEBUG : one-53.txt: md5 = 9f25fbf3e3e45561bf57bc37717fe232 OK 2026/11/02 01:53:14 DEBUG : one-54.txt: md5 = 89e92a084f41b0ad6715a51cd5860a03 OK 2026/11/02 01:53:18 DEBUG : one-55.txt: md5 = c1b0148f868b0b6a1da6a256a8e089cc OK 2026/11/02 01:53:20 DEBUG : one-56.txt: md5 = b7bdf743483896ddc880c022c2bcf5a9 OK 2026/11/02 01:53:21 DEBUG : one-57.txt: md5 = f0bacd818b95181aec5da29fcdc5921e OK 2026/11/02 01:53:23 DEBUG : one-58.txt: md5 = be472ac4c75ef86e1257749cb4512eda OK 2026/11/02 01:53:24 DEBUG : one-59.txt: md5 = 116630636d80ef0950fbc350c64e6d2c OK 2026/11/02 01:53:26 DEBUG : one-60.txt: md5 = bd0f330551e78f030a990767258cc213 OK 2026/11/02 01:53:27 DEBUG : one-61.txt: md5 = d20c4f1d50b952098238027639892594 OK 2026/11/02 01:53:29 DEBUG : one-62.txt: md5 = 0a8f8c85ef6934face487f9af739da58 OK 2026/11/02 01:53:30 DEBUG : one-63.txt: md5 = 02fcc73787eb543af989de34dd3c087d OK 2026/11/02 01:53:31 DEBUG : one-64.txt: md5 = 373906c65eff3015f1c2921baca065c4 OK 2026/11/02 01:53:33 DEBUG : one-65.txt: md5 = 141889353bc5a1d064e2a202e283329f OK 2026/11/02 01:53:34 DEBUG : one-66.txt: md5 = 7ba55b7bbf421be42a8b7bbc63531cf3 OK 2026/11/02 01:53:36 DEBUG : one-67.txt: md5 = 4a293b5194eaad5a0043ba4cccced5ce OK 2026/11/02 01:53:37 DEBUG : one-68.txt: md5 = 3592f7570cac85f79c2658c8dcba0dbe OK 2026/11/02 01:53:39 DEBUG : one-69.txt: md5 = cc5c17b2e841486f55337f3d7647bb3d OK 2026/11/02 01:53:40 DEBUG : one-70.txt: md5 = b77dfdb4290aa54cce37a8a99bf2c5c9 OK 2026/11/02 01:53:42 DEBUG : one-71.txt: md5 = adfdefec61001bc23503cc84c2a1ed5d OK 2026/11/02 01:53:43 DEBUG : one-72.txt: md5 = 6efb4249fc0405e250f46de4f8e4ca63 OK 2026/11/02 01:53:44 DEBUG : one-73.txt: md5 = 99f1d807e8c61950648a1df925f057e0 OK 2026/11/02 01:53:46 DEBUG : one-74.txt: md5 = 9bd659b15a146234e08048217c2cd678 OK 2026/11/02 01:53:47 DEBUG : one-75.txt: md5 = b7feae6f5c7ce3fe1a5e7290d3cbf98b OK 2026/11/02 01:53:49 DEBUG : one-76.txt: md5 = 048335b72c96bb480b16894ed8cd253c OK 2026/11/02 01:53:50 DEBUG : one-77.txt: md5 = 84738f37e0b8d876cac04eedac5c960f OK 2026/11/02 01:53:52 DEBUG : one-78.txt: md5 = d6c725d7846356f41f22d0c9632c95b7 OK 2026/11/02 01:53:53 DEBUG : one-79.txt: md5 = 88cc9391e707c795c4fc76d7e1a778bc OK 2026/11/02 01:53:55 DEBUG : one-80.txt: md5 = cfb57f0befc92b7d14983924983d280b OK 2026/11/02 01:53:56 DEBUG : one-81.txt: md5 = 70fc7b7ecc56e53ea9e2c38a4e584673 OK 2026/11/02 01:53:58 DEBUG : one-82.txt: md5 = eed4c1686885b2f7399af3b532b2af9e OK 2026/11/02 01:53:59 DEBUG : one-83.txt: md5 = 183b52547523a4cb9f2cb9cf47ffbbc6 OK 2026/11/02 01:54:00 DEBUG : one-84.txt: md5 = 771ceb117a1d18ba34fac01f33cb8fa3 OK 2026/11/02 01:54:02 DEBUG : one-85.txt: md5 = bd986ec4fb3edf33ade96c88fa77dde5 OK 2026/11/02 01:54:03 DEBUG : one-86.txt: md5 = 6a537c1b9e0905a78fce1040a0e662ea OK 2026/11/02 01:54:04 DEBUG : one-87.txt: md5 = d9791408f5f07d1aef6e2860c513a014 OK 2026/11/02 01:54:06 DEBUG : one-88.txt: md5 = 38db8459e9bc8d4a05dfc0740249e98f OK 2026/11/02 01:54:07 DEBUG : one-89.txt: md5 = 0f01b799e406d48ef73200820fbb03e7 OK 2026/11/02 01:54:09 DEBUG : one-90.txt: md5 = b483aff367e8cfbee1245845c524d3f8 OK 2026/11/02 01:54:10 DEBUG : one-91.txt: md5 = ea607c40e7929fb35cf1a01c9052698b OK 2026/11/02 01:54:12 DEBUG : one-92.txt: md5 = ad2602ae311e4396d4c7d2b2ca383100 OK 2026/11/02 01:54:13 DEBUG : one-93.txt: md5 = 68940caf828606e25218acf46af3abdd OK 2026/11/02 01:54:15 DEBUG : one-94.txt: md5 = e5184a32474e6d664573266f065a43c8 OK 2026/11/02 01:54:16 DEBUG : one-95.txt: md5 = f7f592debc829a2624addaaa562b3606 OK 2026/11/02 01:54:18 DEBUG : one-96.txt: md5 = 15fc1c164e329b3fb17085838650c332 OK 2026/11/02 01:54:19 DEBUG : one-97.txt: md5 = e58643ff69f667787aeeaf41a43f63b7 OK 2026/11/02 01:54:21 DEBUG : one-98.txt: md5 = 7ba48d6a1365ee832f592fc5c4642827 OK 2026/11/02 01:54:22 DEBUG : one-99.txt: md5 = 9a6cc7e0e49fef09cb10db7a9bfda439 OK 2026/11/02 01:54:24 DEBUG : one-100.txt: md5 = c9a5c61887a752320d1061eaa0da63a4 OK 2026/11/02 01:54:25 DEBUG : one-101.txt: md5 = 6ce693e21f6740ab3ec27feb274ac213 OK 2026/11/02 01:54:27 DEBUG : one-102.txt: md5 = cfccb30b394167b831ba9e9d9ced4169 OK 2026/11/02 01:54:28 DEBUG : one-103.txt: md5 = a2c175e13a8802c4ef11ed31b64c8abd OK 2026/11/02 01:54:30 DEBUG : one-104.txt: md5 = 5d10fe84c693e66ddafdb98bf2874d8a OK 2026/11/02 01:54:31 DEBUG : one-105.txt: md5 = 85f0b15b60281b1b6a625ecd3fac2118 OK 2026/11/02 01:54:34 INFO : Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya': Looking for duplicate names using rename mode. 2026/11/02 01:54:35 NOTICE: one.txt: Found 2 files with duplicate names 2026/11/02 01:54:35 INFO : one-106.txt: renamed from: one.txt 2026/11/02 01:54:36 INFO : one-107.txt: renamed from: one.txt --- PASS: TestDeduplicateRenameManyExisting (211.02s) === RUN TestMergeDirs run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 01:55:30 DEBUG : dupe1/one.txt: md5 = e31a0b69cc7a7a4d9f954a295e531104 OK 2026/11/02 01:55:32 DEBUG : dupe2/two.txt: md5 = 1e244a91ebb77a967801590d69663f90 OK 2026/11/02 01:55:34 DEBUG : dupe3/three.txt: md5 = bae62f79507ff25437ce9b2162b4f37b OK 2026/11/02 01:55:35 INFO : urj8gsducqbtvekeq181ntd2u8: merging "sfrom47189m9qr3mt4qonu281c" 2026/11/02 01:55:36 INFO : urj8gsducqbtvekeq181ntd2u8: removing empty directory 2026/11/02 01:55:37 INFO : 1u1r4ei7c628fnjg9blqt3j60o: merging "tr2hj63d80ftlmvm6a952snjcc" 2026/11/02 01:55:37 INFO : 1u1r4ei7c628fnjg9blqt3j60o: removing empty directory --- PASS: TestMergeDirs (13.29s) === RUN TestIndex run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 01:55:43 DEBUG : file1.txt: md5 = f565d2e5505366088e81eeb88a14f23d OK 2026/11/02 01:55:45 DEBUG : sub/file2.txt: md5 = f9de6d2d9e1a44982e30759afb99c2a4 OK 2026/11/02 01:55:47 DEBUG : sub/deep/file3.txt: md5 = f43f35674f2ab63eeb9382ad7b8ec3f4 OK 2026/11/02 01:55:49 DEBUG : index.html: md5 = 7d5289219cfd2677a3708eec54211f2d OK 2026/11/02 01:55:49 DEBUG : index.html: size = 12147 OK 2026/11/02 01:55:49 INFO : index.html: Copied (new) 2026/11/02 01:55:49 DEBUG : caddy.json: md5 = b7ddc98b423160d6ac2a7dbe619c928d OK 2026/11/02 01:55:49 DEBUG : caddy.json: size = 259 OK 2026/11/02 01:55:49 INFO : caddy.json: Copied (new) 2026/11/02 01:55:49 DEBUG : index.json: md5 = 56b4ea18e2970e523265523c422f35e6 OK 2026/11/02 01:55:49 DEBUG : index.json: size = 213 OK 2026/11/02 01:55:49 INFO : index.json: Copied (new) 2026/11/02 01:55:49 DEBUG : sub/deep/index.html: md5 = bb774b8ec97f70695c58964f777312be OK 2026/11/02 01:55:49 DEBUG : sub/deep/index.html: size = 12087 OK 2026/11/02 01:55:49 INFO : sub/deep/index.html: Copied (new) 2026/11/02 01:55:51 DEBUG : sub/deep/caddy.json: md5 = dad9b78e12d2ed761a165f452a646e7b OK 2026/11/02 01:55:51 DEBUG : sub/deep/caddy.json: size = 131 OK 2026/11/02 01:55:51 INFO : sub/deep/caddy.json: Copied (new) 2026/11/02 01:55:51 DEBUG : sub/index.html: md5 = 49a3d063616513bf6f94839e3a809918 OK 2026/11/02 01:55:51 DEBUG : sub/index.html: size = 12306 OK 2026/11/02 01:55:51 INFO : sub/index.html: Copied (new) 2026/11/02 01:55:51 DEBUG : sub/deep/index.json: md5 = 9dd80734aa6bcd3386ffd7cc0766e523 OK 2026/11/02 01:55:51 DEBUG : sub/deep/index.json: size = 119 OK 2026/11/02 01:55:51 INFO : sub/deep/index.json: Copied (new) 2026/11/02 01:55:51 DEBUG : sub/index.json: md5 = 41707581c4a4f54bd3ea8d309869251b OK 2026/11/02 01:55:51 DEBUG : sub/index.json: size = 215 OK 2026/11/02 01:55:51 INFO : sub/index.json: Copied (new) 2026/11/02 01:55:52 DEBUG : sub/caddy.json: md5 = cfe2cba8d6a3b27b960ab2a0bdaa5820 OK 2026/11/02 01:55:52 DEBUG : sub/caddy.json: size = 261 OK 2026/11/02 01:55:52 INFO : sub/caddy.json: Copied (new) 2026/11/02 01:55:58 DEBUG : sub/index.html: Unchanged skipping 2026/11/02 01:55:58 DEBUG : sub/deep/index.html: Unchanged skipping 2026/11/02 01:55:58 DEBUG : index.html: Unchanged skipping 2026/11/02 01:55:58 DEBUG : sub/index.json: Unchanged skipping 2026/11/02 01:55:58 DEBUG : sub/deep/index.json: Unchanged skipping 2026/11/02 01:55:58 DEBUG : index.json: Unchanged skipping 2026/11/02 01:55:59 DEBUG : sub/deep/caddy.json: Unchanged skipping 2026/11/02 01:55:59 DEBUG : caddy.json: Unchanged skipping 2026/11/02 01:55:59 DEBUG : sub/caddy.json: Unchanged skipping 2026/11/02 01:56:01 DEBUG : sub/deep/index.html: md5 = 1dfa9ddb9169e4467b3886ceda08a8c9 OK 2026/11/02 01:56:01 DEBUG : sub/deep/index.html: size = 12087 OK 2026/11/02 01:56:01 INFO : sub/deep/index.html: Copied (replaced existing) 2026/11/02 01:56:01 DEBUG : index.json: md5 = d79a22cc3ea6fc04b4536e7596fe2734 OK 2026/11/02 01:56:01 DEBUG : index.json: size = 213 OK 2026/11/02 01:56:01 INFO : index.json: Copied (replaced existing) 2026/11/02 01:56:01 DEBUG : index.html: md5 = 54a9016144f5fb7cf6d2c75dc7074b65 OK 2026/11/02 01:56:01 DEBUG : index.html: size = 12147 OK 2026/11/02 01:56:01 INFO : index.html: Copied (replaced existing) 2026/11/02 01:56:01 DEBUG : caddy.json: md5 = cb77b803658f27429818c3621d06f382 OK 2026/11/02 01:56:01 DEBUG : caddy.json: size = 259 OK 2026/11/02 01:56:01 INFO : caddy.json: Copied (replaced existing) 2026/11/02 01:56:02 DEBUG : sub/deep/index.json: md5 = 713ba36c037a8beb34ad457c9aee6b34 OK 2026/11/02 01:56:02 DEBUG : sub/deep/index.json: size = 119 OK 2026/11/02 01:56:02 INFO : sub/deep/index.json: Copied (replaced existing) 2026/11/02 01:56:02 DEBUG : sub/deep/caddy.json: md5 = 9303a0bb109a98ab5eb20c352c5bb30e OK 2026/11/02 01:56:02 DEBUG : sub/deep/caddy.json: size = 131 OK 2026/11/02 01:56:02 INFO : sub/deep/caddy.json: Copied (replaced existing) 2026/11/02 01:56:02 DEBUG : sub/index.json: md5 = 1b166b7d64b66715676cbf0691a1b039 OK 2026/11/02 01:56:02 DEBUG : sub/index.json: size = 215 OK 2026/11/02 01:56:02 INFO : sub/index.json: Copied (replaced existing) 2026/11/02 01:56:03 DEBUG : sub/index.html: md5 = 63dff75aecfb13bc45487abe105fc1d7 OK 2026/11/02 01:56:03 DEBUG : sub/index.html: size = 12306 OK 2026/11/02 01:56:03 INFO : sub/index.html: Copied (replaced existing) 2026/11/02 01:56:04 DEBUG : sub/caddy.json: md5 = 2ab0780ec9a8a4f2ce9eac329fe78a2b OK 2026/11/02 01:56:04 DEBUG : sub/caddy.json: size = 261 OK 2026/11/02 01:56:04 INFO : sub/caddy.json: Copied (replaced existing) 2026/11/02 01:56:05 DEBUG : sub/deep/file4.txt: md5 = 4eadc71e4cb03c3375eea2dc55587538 OK 2026/11/02 01:56:07 DEBUG : index.html: Unchanged skipping 2026/11/02 01:56:07 DEBUG : sub/index.html: Unchanged skipping 2026/11/02 01:56:07 DEBUG : sub/deep/index.json: md5 = d856c4b971d7337c69d8d31f84d053c6 OK 2026/11/02 01:56:07 DEBUG : sub/deep/index.json: size = 235 OK 2026/11/02 01:56:07 INFO : sub/deep/index.json: Copied (replaced existing) 2026/11/02 01:56:07 DEBUG : sub/deep/caddy.json: md5 = b23a603a704e49a6db5573696ae82d97 OK 2026/11/02 01:56:07 DEBUG : sub/deep/caddy.json: size = 260 OK 2026/11/02 01:56:07 INFO : sub/deep/caddy.json: Copied (replaced existing) 2026/11/02 01:56:07 DEBUG : sub/deep/index.html: md5 = 95cc9378f85ff91eef4b29bfefb871e6 OK 2026/11/02 01:56:07 DEBUG : sub/deep/index.html: size = 12346 OK 2026/11/02 01:56:07 INFO : sub/deep/index.html: Copied (replaced existing) 2026/11/02 01:56:07 DEBUG : index.json: Unchanged skipping 2026/11/02 01:56:07 DEBUG : sub/index.json: Unchanged skipping 2026/11/02 01:56:08 DEBUG : caddy.json: Unchanged skipping 2026/11/02 01:56:08 DEBUG : sub/caddy.json: Unchanged skipping 2026/11/02 01:56:11 DEBUG : sub/deep/file5.txt: md5 = a186341f97efa929ff873863e7d86b68 OK 2026/11/02 01:56:11 NOTICE: sub/deep/index.html: Skipped copy as --dry-run is set (size 12.309Ki) 2026/11/02 01:56:11 NOTICE: sub/deep/caddy.json: Skipped copy as --dry-run is set (size 389) 2026/11/02 01:56:11 NOTICE: sub/deep/index.json: Skipped copy as --dry-run is set (size 351) 2026/11/02 01:56:12 DEBUG : sub/index.html: Unchanged skipping 2026/11/02 01:56:12 DEBUG : index.html: Unchanged skipping 2026/11/02 01:56:12 DEBUG : sub/index.json: Unchanged skipping 2026/11/02 01:56:13 DEBUG : index.json: Unchanged skipping 2026/11/02 01:56:13 DEBUG : sub/caddy.json: Unchanged skipping 2026/11/02 01:56:13 DEBUG : caddy.json: Unchanged skipping --- PASS: TestIndex (42.01s) === RUN TestIndexDelete run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 01:56:24 DEBUG : file1.txt: md5 = 2b9cdd112f9dc202196510d4cccbec84 OK 2026/11/02 01:56:27 DEBUG : sub/file2.txt: md5 = 3149a6b08f7feab061f9f1f4a6fec6f2 OK 2026/11/02 01:56:29 DEBUG : sub/deep/file3.txt: md5 = f068c099fa78dbc489aa04d1df627078 OK 2026/11/02 01:56:31 DEBUG : sub/deep/index.html: md5 = 88b84f1c2f214d540ff3e10ef00ed9c8 OK 2026/11/02 01:56:31 DEBUG : sub/deep/index.html: size = 12087 OK 2026/11/02 01:56:31 INFO : sub/deep/index.html: Copied (new) 2026/11/02 01:56:31 DEBUG : index.html: md5 = 021f3d34e867df7fb13973053985bc4d OK 2026/11/02 01:56:31 DEBUG : index.html: size = 12147 OK 2026/11/02 01:56:31 INFO : index.html: Copied (new) 2026/11/02 01:56:31 DEBUG : sub/index.html: md5 = 2146de08fc42a77c3aab84c1dca807bd OK 2026/11/02 01:56:31 DEBUG : sub/index.html: size = 12306 OK 2026/11/02 01:56:31 INFO : sub/index.html: Copied (new) 2026/11/02 01:56:33 DEBUG : sub/index.html: Unchanged skipping 2026/11/02 01:56:33 DEBUG : index.html: Unchanged skipping 2026/11/02 01:56:34 DEBUG : sub/deep/index.html: md5 = e9741882040f556a4506e40f96411e30 OK 2026/11/02 01:56:34 DEBUG : sub/deep/index.html: size = 11829 OK 2026/11/02 01:56:34 INFO : sub/deep/index.html: Copied (replaced existing) --- PASS: TestIndexDelete (17.37s) === RUN TestIndexFilter run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 01:56:42 DEBUG : file1.txt: md5 = 5ee6ca13b96647b267d66e5f61d83a7d OK 2026/11/02 01:56:44 DEBUG : sub/file2.txt: md5 = 9d2f81513fa355b399c46cb2b25edb05 OK 2026/11/02 01:56:46 DEBUG : sub/deep/file3.txt: md5 = 127d75c8df763e2cfe0a80962c7678b3 OK 2026/11/02 01:56:48 DEBUG : sub/deep/index.html: md5 = 71be238a5901b41846302a3435b9fe95 OK 2026/11/02 01:56:50 DEBUG : other/notes.md: md5 = 97c5980c8f198b6a20287a2d4d917a6d OK 2026/11/02 01:56:51 DEBUG : other/notes.md: Excluded (Path Filter) 2026/11/02 01:56:52 DEBUG : other/index.html: md5 = fd8c4dae710de8dc0192866d3ed7d8c1 OK 2026/11/02 01:56:52 DEBUG : other/index.html: size = 11802 OK 2026/11/02 01:56:52 INFO : other/index.html: Copied (new) 2026/11/02 01:56:53 DEBUG : sub/index.html: md5 = 152d022738f7233888408f16c4003fc8 OK 2026/11/02 01:56:53 DEBUG : sub/index.html: size = 12056 OK 2026/11/02 01:56:53 INFO : sub/index.html: Copied (new) 2026/11/02 01:56:53 DEBUG : index.html: md5 = 094064e6ae9d830635c6004bdcf8319f OK 2026/11/02 01:56:53 DEBUG : index.html: size = 12399 OK 2026/11/02 01:56:53 INFO : index.html: Copied (new) 2026/11/02 01:56:57 DEBUG : other/notes.md: Excluded (Path Filter) 2026/11/02 01:56:58 DEBUG : sub/index.html: Unchanged skipping 2026/11/02 01:56:58 DEBUG : index.html: Unchanged skipping 2026/11/02 01:56:58 DEBUG : other/index.html: Unchanged skipping --- PASS: TestIndexFilter (24.53s) === RUN TestIndexRules run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 01:57:07 DEBUG : file1.txt: md5 = e050d47b0f796cb2358bde44ff6e3721 OK 2026/11/02 01:57:09 DEBUG : sub/file2.txt: md5 = 6c261c53d5fcf93597bc95133f740fcb OK 2026/11/02 01:57:11 DEBUG : sub/deep/file3.txt: md5 = e0d2d2c21c3970b9ad6a93833b5b025e OK 2026/11/02 01:57:13 DEBUG : sub/deep/index.html: md5 = 4be3090407627b52132af5971a525a8e OK 2026/11/02 01:57:15 DEBUG : index.html: md5 = 9369b733637451a1699a72ee10077af4 OK 2026/11/02 01:57:15 DEBUG : index.html: size = 12147 OK 2026/11/02 01:57:15 INFO : index.html: Copied (new) 2026/11/02 01:57:15 DEBUG : sub/index.html: md5 = 394d058c23c22ad341b587bb694f7290 OK 2026/11/02 01:57:15 DEBUG : sub/index.html: size = 12306 OK 2026/11/02 01:57:15 INFO : sub/index.html: Copied (new) 2026/11/02 01:57:20 DEBUG : sub/index.html: Unchanged skipping 2026/11/02 01:57:20 DEBUG : sub/deep/index.html: md5 = 16f1f84fb48f31578d45f23956b878f5 OK 2026/11/02 01:57:20 DEBUG : sub/deep/index.html: size = 12087 OK 2026/11/02 01:57:20 INFO : sub/deep/index.html: Copied (replaced existing) --- PASS: TestIndexRules (21.52s) === RUN TestIndexMaxDepth run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 01:57:28 DEBUG : file1.txt: md5 = 6a229279278804b76fd89d5c312b2ec2 OK 2026/11/02 01:57:30 DEBUG : sub/file2.txt: md5 = d44a10e3805af687c82222ae9585242b OK 2026/11/02 01:57:32 DEBUG : sub/deep/file3.txt: md5 = 86fda7563e8eb0fdf61693f1504db690 OK 2026/11/02 01:57:35 DEBUG : index.html: md5 = 1a2fe4ccf98cbb53754aaab00e0e75d7 OK 2026/11/02 01:57:35 DEBUG : index.html: size = 12147 OK 2026/11/02 01:57:35 INFO : index.html: Copied (new) --- PASS: TestIndexMaxDepth (14.00s) === RUN TestIndexLinkIndex run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 01:57:42 DEBUG : file1.txt: md5 = 84177c3d7cda42d0f50461e751eeb9e2 OK 2026/11/02 01:57:44 DEBUG : sub/file2.txt: md5 = f3c2e57f7d59cfb057fc5b2fec2f16f1 OK 2026/11/02 01:57:46 DEBUG : sub/deep/file3.txt: md5 = cd8491d02de9e0d89620c6a9d0614692 OK 2026/11/02 01:57:48 DEBUG : index.html: md5 = 8ef8f6e65e71d9af7f23aff8fc44fd6a OK 2026/11/02 01:57:48 DEBUG : index.html: size = 12167 OK 2026/11/02 01:57:48 INFO : index.html: Copied (new) 2026/11/02 01:57:48 DEBUG : sub/deep/index.html: md5 = ddd26dd5d322d7ccf2967cbdbdee2d64 OK 2026/11/02 01:57:48 DEBUG : sub/deep/index.html: size = 12128 OK 2026/11/02 01:57:48 INFO : sub/deep/index.html: Copied (new) 2026/11/02 01:57:48 DEBUG : sub/index.html: md5 = 85a986384bcdc1917df5f49378dcef3f OK 2026/11/02 01:57:48 DEBUG : sub/index.html: size = 12347 OK 2026/11/02 01:57:48 INFO : sub/index.html: Copied (new) --- PASS: TestIndexLinkIndex (14.93s) === RUN TestIndexDirTime run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 01:57:57 DEBUG : file1.txt: md5 = 7608115f4f6fcced16bea6360915f17c OK 2026/11/02 01:57:59 DEBUG : sub/file2.txt: md5 = 0a974856e646537bf1caeea987e10a88 OK 2026/11/02 01:58:01 DEBUG : sub/deep/file3.txt: md5 = e9eff8a8b4cf725b66406a1e5b1b3e60 OK 2026/11/02 01:58:03 DEBUG : index.json: md5 = 5719e1d369f7f9d67590c6f6dd937d68 OK 2026/11/02 01:58:03 DEBUG : index.json: size = 257 OK 2026/11/02 01:58:03 INFO : index.json: Copied (new) 2026/11/02 01:58:03 DEBUG : sub/deep/index.json: md5 = dc0ec3b0af7ca8c5478ef9f334e8539a OK 2026/11/02 01:58:03 DEBUG : sub/deep/index.json: size = 139 OK 2026/11/02 01:58:03 INFO : sub/deep/index.json: Copied (new) 2026/11/02 01:58:03 DEBUG : sub/index.json: md5 = 2fc27134481049e9d070d619f3196aa7 OK 2026/11/02 01:58:03 DEBUG : sub/index.json: size = 259 OK 2026/11/02 01:58:03 INFO : sub/index.json: Copied (new) 2026/11/02 01:58:06 DEBUG : sub/deep/index.json: Unchanged skipping 2026/11/02 01:58:06 DEBUG : sub/index.json: md5 = 422cca963dc27d2a5eacf3fea9d65770 OK 2026/11/02 01:58:06 DEBUG : sub/index.json: size = 239 OK 2026/11/02 01:58:06 INFO : sub/index.json: Copied (replaced existing) 2026/11/02 01:58:06 DEBUG : index.json: md5 = 320656624bdbda859b2e0bfc58588f56 OK 2026/11/02 01:58:06 DEBUG : index.json: size = 237 OK 2026/11/02 01:58:06 INFO : index.json: Copied (replaced existing) 2026/11/02 01:58:09 DEBUG : sub/index.json: md5 = f6193f0a97d903efe2e444f0e0d4850c OK 2026/11/02 01:58:09 DEBUG : sub/index.json: size = 215 OK 2026/11/02 01:58:09 INFO : sub/index.json: Copied (replaced existing) 2026/11/02 01:58:09 DEBUG : index.json: md5 = c0febdacf32bf847c76e4c49f506030a OK 2026/11/02 01:58:09 DEBUG : index.json: size = 213 OK 2026/11/02 01:58:09 INFO : index.json: Copied (replaced existing) 2026/11/02 01:58:09 DEBUG : sub/deep/index.json: md5 = c3339560b2869d8b786bbcf092879f17 OK 2026/11/02 01:58:09 DEBUG : sub/deep/index.json: size = 119 OK 2026/11/02 01:58:09 INFO : sub/deep/index.json: Copied (replaced existing) --- PASS: TestIndexDirTime (19.88s) === RUN TestIndexTemplate run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 01:58:17 DEBUG : file1.txt: md5 = c95495d65695f9568027163b9f30abf5 OK 2026/11/02 01:58:19 DEBUG : sub/file2.txt: md5 = 011b6eefd27845bbd25ca627f53c0982 OK 2026/11/02 01:58:21 DEBUG : sub/deep/file3.txt: md5 = c181c69026104db6a9ba9386db547f1e OK 2026/11/02 01:58:26 DEBUG : list.txt: md5 = 9cb142a5c9a1023a5aa314b5c13beada OK 2026/11/02 01:58:26 DEBUG : list.txt: size = 59 OK 2026/11/02 01:58:26 INFO : list.txt: Copied (new) 2026/11/02 01:58:26 DEBUG : sub/list.txt: md5 = eb822c34383fb07e13ac014cf6f4bead OK 2026/11/02 01:58:26 DEBUG : sub/list.txt: size = 64 OK 2026/11/02 01:58:26 INFO : sub/list.txt: Copied (new) 2026/11/02 01:58:26 DEBUG : sub/deep/list.txt: md5 = a5962c18ecfb3b68f9694cbdbf3312ad OK 2026/11/02 01:58:26 DEBUG : sub/deep/list.txt: size = 47 OK 2026/11/02 01:58:26 INFO : sub/deep/list.txt: Copied (new) --- PASS: TestIndexTemplate (17.20s) === 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-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 01:58:34 DEBUG : file1.txt: md5 = 7b234fc663dcd6f5f3d4f555216a7f98 OK 2026/11/02 01:58:36 DEBUG : sub/file2.txt: md5 = d6dde7754c57abf12ca7de2443759b90 OK 2026/11/02 01:58:38 DEBUG : sub/deep/file3.txt: md5 = a352600ad4ec6d208431f44cd9213dcc OK 2026/11/02 01:58:41 DEBUG : other/notes.md: md5 = 9c7b13745af5614b333e7b8f961387cb OK 2026/11/02 01:58:43 DEBUG : index.html: md5 = 8248d305efa594c85a097d3a0a3425c9 OK 2026/11/02 01:58:43 DEBUG : index.html: size = 12399 OK 2026/11/02 01:58:43 INFO : index.html: Copied (new) 2026/11/02 01:58:43 DEBUG : sub/deep/index.html: md5 = ab62f5bf59811990435baa06ac83d8f3 OK 2026/11/02 01:58:43 DEBUG : sub/deep/index.html: size = 12087 OK 2026/11/02 01:58:43 INFO : sub/deep/index.html: Copied (new) 2026/11/02 01:58:43 DEBUG : other/index.html: md5 = c3ad45cc2712f4475dc3d7431bfa6ddb OK 2026/11/02 01:58:43 DEBUG : other/index.html: size = 12058 OK 2026/11/02 01:58:43 INFO : other/index.html: Copied (new) 2026/11/02 01:58:43 DEBUG : sub/index.html: md5 = 3543c652692fd87f0f54caff65fe4cf6 OK 2026/11/02 01:58:43 DEBUG : sub/index.html: size = 12306 OK 2026/11/02 01:58:43 INFO : sub/index.html: Copied (new) 2026/11/02 01:58:44 DEBUG : sub/deep/file4.txt: md5 = 085ed08340485187552c8d41b389f891 OK 2026/11/02 01:58:46 DEBUG : other/undeclared.txt: md5 = f74e67b15a08ec5d6e24aada12e0753a OK 2026/11/02 01:58:46 INFO : Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya': Partial index: listing 3 directories and walking 0 changed directories 2026/11/02 01:58:47 DEBUG : index.html: Unchanged skipping 2026/11/02 01:58:47 DEBUG : sub/index.html: Unchanged skipping 2026/11/02 01:58:48 DEBUG : sub/deep/index.html: md5 = dd2b1c7e77102aad74579c9ca7ec510b OK 2026/11/02 01:58:48 DEBUG : sub/deep/index.html: size = 12346 OK 2026/11/02 01:58:48 INFO : sub/deep/index.html: Copied (replaced existing) 2026/11/02 01:58:52 DEBUG : sub/new/file5.txt: md5 = 744f713ff83a34039c631e200b7d97a9 OK 2026/11/02 01:58:52 INFO : Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya': Partial index: listing 3 directories and walking 0 changed directories 2026/11/02 01:58:53 DEBUG : index.html: Unchanged skipping 2026/11/02 01:58:54 DEBUG : sub/index.html: md5 = 80cf29f0434553a1e529c14ce02ab075 OK 2026/11/02 01:58:54 DEBUG : sub/index.html: size = 12554 OK 2026/11/02 01:58:54 INFO : sub/index.html: Copied (replaced existing) 2026/11/02 01:58:54 DEBUG : sub/new/index.html: md5 = 5b1b17702591cdb189a8774b7d5bd79c OK 2026/11/02 01:58:54 DEBUG : sub/new/index.html: size = 12085 OK 2026/11/02 01:58:54 INFO : sub/new/index.html: Copied (new) 2026/11/02 01:58:57 DEBUG : other/undeclared0.txt: md5 = 5f99b3bb89e0b808b888ed812ee4e73f OK 2026/11/02 01:58:57 INFO : Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya': Partial index: listing 1 directories and walking 1 changed directories 2026/11/02 01:58:58 DEBUG : index.html: Unchanged skipping 2026/11/02 01:58:59 DEBUG : other/index.html: md5 = 7e7f4977e8a7b9e50b22a28d51dd0180 OK 2026/11/02 01:58:59 DEBUG : other/index.html: size = 12597 OK 2026/11/02 01:58:59 INFO : other/index.html: Copied (replaced existing) 2026/11/02 01:59:01 DEBUG : other/undeclared1.txt: md5 = f71b0149b9a66c93cc01eddfd2773433 OK 2026/11/02 01:59:01 INFO : Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya': Partial index: listing 1 directories and walking 0 changed directories 2026/11/02 01:59:02 DEBUG : index.html: Unchanged skipping 2026/11/02 01:59:03 DEBUG : other/index.html: md5 = ac41cf0cac1a99c94e84849ae4526064 OK 2026/11/02 01:59:03 DEBUG : other/index.html: size = 12867 OK 2026/11/02 01:59:03 INFO : other/index.html: Copied (replaced existing) 2026/11/02 01:59:05 DEBUG : sub/file6.txt: md5 = bb77c8c765968b08e365d628d5c4d654 OK 2026/11/02 01:59:05 INFO : Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya': Nothing changed so nothing to index 2026/11/02 01:59:06 INFO : Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya': Root changed so doing a full index 2026/11/02 01:59:07 DEBUG : index.html: Unchanged skipping 2026/11/02 01:59:07 DEBUG : sub/deep/index.html: Unchanged skipping 2026/11/02 01:59:07 DEBUG : other/index.html: Unchanged skipping 2026/11/02 01:59:07 DEBUG : sub/new/index.html: Unchanged skipping 2026/11/02 01:59:08 DEBUG : sub/index.html: md5 = b3b672419a1c9b8ade7d294af4cc5e4c OK 2026/11/02 01:59:08 DEBUG : sub/index.html: size = 12813 OK 2026/11/02 01:59:08 INFO : sub/index.html: Copied (replaced existing) 2026/11/02 01:59:10 DEBUG : sub/file7.txt: md5 = 02fdd59be897cf4b3f37d38617939dfa OK 2026/11/02 01:59:10 INFO : Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya': Partial index would list 2 directories, more than 1, so doing a full index 2026/11/02 01:59:11 DEBUG : other/index.html: Unchanged skipping 2026/11/02 01:59:11 DEBUG : index.html: Unchanged skipping 2026/11/02 01:59:12 DEBUG : sub/new/index.html: Unchanged skipping 2026/11/02 01:59:12 DEBUG : sub/deep/index.html: Unchanged skipping 2026/11/02 01:59:12 DEBUG : sub/index.html: md5 = a20692f7209c44e39decf40d3bb0bc9b OK 2026/11/02 01:59:12 DEBUG : sub/index.html: size = 13071 OK 2026/11/02 01:59:12 INFO : sub/index.html: Copied (replaced existing) 2026/11/02 01:59:15 DEBUG : sub/deep/file8.txt: md5 = 3b08183af594810f088a135bd05f2b0f OK 2026/11/02 01:59:15 INFO : Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya': Partial index: listing 1 directories and walking 1 changed directories 2026/11/02 01:59:16 DEBUG : sub/new/index.html: Unchanged skipping 2026/11/02 01:59:16 DEBUG : index.html: Unchanged skipping 2026/11/02 01:59:16 DEBUG : sub/index.html: Unchanged skipping 2026/11/02 01:59:17 DEBUG : sub/deep/index.html: md5 = 611209011f6c2afb7640617271d48ac5 OK 2026/11/02 01:59:17 DEBUG : sub/deep/index.html: size = 12604 OK 2026/11/02 01:59:17 INFO : sub/deep/index.html: Copied (replaced existing) 2026/11/02 01:59:19 DEBUG : sub/deep/file9.txt: md5 = 1944223f1bbadc38dbb892bc970b3081 OK 2026/11/02 01:59:20 DEBUG : sub/file10.txt: md5 = 2c111f9988ae933d7c17a6a59641d996 OK 2026/11/02 01:59:20 INFO : Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya': Partial index: listing 2 directories and walking 0 changed directories 2026/11/02 01:59:22 DEBUG : index.html: Unchanged skipping 2026/11/02 01:59:22 DEBUG : sub/index.html: md5 = 8b482221d1b2c9b21d8ac43fd3730d4e OK 2026/11/02 01:59:22 DEBUG : sub/index.html: size = 13331 OK 2026/11/02 01:59:22 INFO : sub/index.html: Copied (replaced existing) --- PASS: TestIndexChanged (64.22s) === RUN TestIndexChangedDelete run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 01:59:38 DEBUG : file1.txt: md5 = 166d950d7145a6f5510aaf91b503fc1d OK 2026/11/02 01:59:40 DEBUG : sub/file2.txt: md5 = dcd277f3c84bd7b2351e7e06dd4d1e60 OK 2026/11/02 01:59:43 DEBUG : sub/deep/file3.txt: md5 = 8e94b9b8fb4c49a2b80da7b1e0c8aeff OK 2026/11/02 01:59:46 DEBUG : index.html: md5 = 3209bbe9257025afb8cf0957bba6f3ac OK 2026/11/02 01:59:46 DEBUG : index.html: size = 12147 OK 2026/11/02 01:59:46 INFO : index.html: Copied (new) 2026/11/02 01:59:47 DEBUG : sub/index.html: md5 = f158d6978b8f067ca43c9d3f2a309218 OK 2026/11/02 01:59:47 DEBUG : sub/index.html: size = 12306 OK 2026/11/02 01:59:47 INFO : sub/index.html: Copied (new) 2026/11/02 01:59:48 DEBUG : sub/deep/index.html: md5 = 08b88f234806558802ecd70a67748b16 OK 2026/11/02 01:59:48 DEBUG : sub/deep/index.html: size = 12087 OK 2026/11/02 01:59:48 INFO : sub/deep/index.html: Copied (new) 2026/11/02 01:59:49 INFO : Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya': Partial index: listing 3 directories and walking 0 changed directories 2026/11/02 01:59:50 DEBUG : sub/index.html: Unchanged skipping 2026/11/02 01:59:50 DEBUG : index.html: Unchanged skipping 2026/11/02 01:59:51 DEBUG : sub/deep/index.html: md5 = e7f816d76a8d7f862413952114597a3d OK 2026/11/02 01:59:51 DEBUG : sub/deep/index.html: size = 11829 OK 2026/11/02 01:59:51 INFO : sub/deep/index.html: Copied (replaced existing) --- PASS: TestIndexChangedDelete (19.83s) === RUN TestIndexChangedFiles run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 01:59:59 DEBUG : file1.txt: md5 = 2db46bd6452ee9b2bdd53ea8b4c1d451 OK 2026/11/02 02:00:02 DEBUG : sub/file2.txt: md5 = 514e860bc3d9a1d3fe2b58e91a9fe1f0 OK 2026/11/02 02:00:08 DEBUG : sub/deep/file3.txt: md5 = 8a1e4fc2cb982d6d934457e4842854a4 OK 2026/11/02 02:00:10 DEBUG : - odd/file.txt: md5 = 2b565d6828dbce790481b303c044e864 OK 2026/11/02 02:00:12 DEBUG : index.html: md5 = 59a5ed55ce6854c87038275becaf1c21 OK 2026/11/02 02:00:12 DEBUG : index.html: size = 12401 OK 2026/11/02 02:00:12 INFO : index.html: Copied (new) 2026/11/02 02:00:12 DEBUG : sub/index.html: md5 = 4c8ff3569d16a6a4c2189c1901e49a41 OK 2026/11/02 02:00:12 DEBUG : sub/index.html: size = 12306 OK 2026/11/02 02:00:12 INFO : sub/index.html: Copied (new) 2026/11/02 02:00:12 DEBUG : sub/deep/index.html: md5 = df7b69f2acca6206901c87625efd2056 OK 2026/11/02 02:00:12 DEBUG : sub/deep/index.html: size = 12087 OK 2026/11/02 02:00:12 INFO : sub/deep/index.html: Copied (new) 2026/11/02 02:00:12 DEBUG : - odd/index.html: md5 = 2cdddee643e7e0db1a2dfb3eab9a7e9e OK 2026/11/02 02:00:12 DEBUG : - odd/index.html: size = 12058 OK 2026/11/02 02:00:12 INFO : - odd/index.html: Copied (new) 2026/11/02 02:00:14 DEBUG : - odd/file8.txt: md5 = b600e9d536c9c87d38c3fb7f725eb3bd OK 2026/11/02 02:00:15 DEBUG : sub/file9.txt: md5 = 45072e2200e2bf215f6e9465eed52bb1 OK 2026/11/02 02:00:15 INFO : Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya': Partial index: listing 2 directories and walking 0 changed directories 2026/11/02 02:00:17 DEBUG : index.html: Unchanged skipping 2026/11/02 02:00:17 DEBUG : - odd/index.html: md5 = f436d1db91295033ae3c3b2084a80fa9 OK 2026/11/02 02:00:17 DEBUG : - odd/index.html: size = 12316 OK 2026/11/02 02:00:17 INFO : - odd/index.html: Copied (replaced existing) 2026/11/02 02:00:19 INFO : Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya': Partial index: listing 4 directories and walking 0 changed directories 2026/11/02 02:00:21 DEBUG : - odd/index.html: Unchanged skipping 2026/11/02 02:00:21 DEBUG : index.html: Unchanged skipping 2026/11/02 02:00:21 DEBUG : sub/deep/index.html: Unchanged skipping 2026/11/02 02:00:21 DEBUG : sub/index.html: md5 = b81d8c90d2a6f943d1f9a0c202101d37 OK 2026/11/02 02:00:21 DEBUG : sub/index.html: size = 12565 OK 2026/11/02 02:00:21 INFO : sub/index.html: Copied (replaced existing) --- PASS: TestIndexChangedFiles (34.31s) === RUN TestIndexChangedDirTime run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 02:00:32 DEBUG : file1.txt: md5 = 3b3d5053ad84a95915e9c58d303f72f4 OK 2026/11/02 02:00:34 DEBUG : sub/file2.txt: md5 = db98ca4de8fede69b569eaa86fdf6d90 OK 2026/11/02 02:00:37 DEBUG : sub/deep/file3.txt: md5 = a364cc9db528330ce5f61d1dc1c2503f OK 2026/11/02 02:00:39 DEBUG : other/notes.md: md5 = c86cceecc44bde0611f97a3e57562a01 OK 2026/11/02 02:00:41 DEBUG : other/index.json: md5 = a160b572a64bf0530be65c7956d164a2 OK 2026/11/02 02:00:41 DEBUG : other/index.json: size = 144 OK 2026/11/02 02:00:41 INFO : other/index.json: Copied (new) 2026/11/02 02:00:41 DEBUG : index.json: md5 = 53b188d2cdcdf97d0e090786aa65a323 OK 2026/11/02 02:00:41 DEBUG : index.json: size = 379 OK 2026/11/02 02:00:41 INFO : index.json: Copied (new) 2026/11/02 02:00:41 DEBUG : sub/index.json: md5 = 29bca55853d7d32171a3aaf9e7772801 OK 2026/11/02 02:00:41 DEBUG : sub/index.json: size = 259 OK 2026/11/02 02:00:41 INFO : sub/index.json: Copied (new) 2026/11/02 02:00:41 DEBUG : sub/deep/index.json: md5 = f159fbff77bce5b33fd74e29e466ad23 OK 2026/11/02 02:00:41 DEBUG : sub/deep/index.json: size = 139 OK 2026/11/02 02:00:41 INFO : sub/deep/index.json: Copied (new) 2026/11/02 02:00:43 DEBUG : sub/deep/file4.txt: md5 = 7d42813a802f63a8d65064496510e9b9 OK 2026/11/02 02:00:43 INFO : Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya': Partial index: listing 3 directories and walking 0 changed directories 2026/11/02 02:00:45 DEBUG : sub/deep/index.json: md5 = 41a58126b7d9ed1987106e34040d72ba OK 2026/11/02 02:00:45 DEBUG : sub/deep/index.json: size = 275 OK 2026/11/02 02:00:45 INFO : sub/deep/index.json: Copied (replaced existing) 2026/11/02 02:00:46 DEBUG : sub/index.json: md5 = 77316770670b6f200c08f7b5f17c7d74 OK 2026/11/02 02:00:46 DEBUG : sub/index.json: size = 259 OK 2026/11/02 02:00:46 INFO : sub/index.json: Copied (replaced existing) 2026/11/02 02:00:46 DEBUG : index.json: md5 = be6c6b6af2ad54f89429818109705d73 OK 2026/11/02 02:00:46 DEBUG : index.json: size = 379 OK 2026/11/02 02:00:46 INFO : index.json: Copied (replaced existing) --- PASS: TestIndexChangedDirTime (23.44s) === RUN TestListDirSorted run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 02:00:56 DEBUG : a.txt: md5 = 1d5be156a20e75fcbb29f1ad83a0c5c5 OK 2026/11/02 02:00:57 DEBUG : zend.txt: md5 = f2d65799a3cc2dfe3085d8f1b0c91b2c OK 2026/11/02 02:00:59 DEBUG : sub dir/hello world: md5 = 15a8195ff5176f3fb8a53e54fdc19027 OK 2026/11/02 02:01:01 DEBUG : sub dir/hello world2: md5 = fbdc5559e0efaf0c58001488c7e7acb6 OK 2026/11/02 02:01:03 DEBUG : sub dir/ignore dir/.ignore: md5 = e7f5c8dcf31b1ad4542e7328f3aa76ac OK 2026/11/02 02:01:05 DEBUG : sub dir/ignore dir/should be ignored: md5 = 275657a3c49150cd727e65e8c57fbf88 OK 2026/11/02 02:01:07 DEBUG : sub dir/sub sub dir/hello world3: md5 = 10ebbc78806b94cb3fb8810cf1d25d15 OK 2026/11/02 02:01:08 DEBUG : a.txt: Excluded (Size Filter) 2026/11/02 02:01:08 DEBUG : a.txt: Excluded 2026/11/02 02:01:09 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/11/02 02:01:09 DEBUG : sub dir/hello world2: Excluded 2026/11/02 02:01:09 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/11/02 02:01:09 DEBUG : sub dir/hello world: Excluded 2026/11/02 02:01:10 DEBUG : sub dir/ignore dir: Excluded 2026/11/02 02:01:10 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/11/02 02:01:10 DEBUG : sub dir/hello world2: Excluded 2026/11/02 02:01:10 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/11/02 02:01:10 DEBUG : sub dir/hello world: Excluded 2026/11/02 02:01:10 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSorted (22.79s) === RUN TestListDirSortedFn run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 02:01:19 DEBUG : a.txt: md5 = 1e54fac34cb19d8cf69bd49de683a9bc OK 2026/11/02 02:01:20 DEBUG : zend.txt: md5 = 92de7e65f0c120872b9bf62078e98d63 OK 2026/11/02 02:01:22 DEBUG : sub dir/hello world: md5 = 3540d8da93fa35a3ebf9b996f3fec165 OK 2026/11/02 02:01:24 DEBUG : sub dir/hello world2: md5 = 100909719021144263f173aa7c54d83f OK 2026/11/02 02:01:26 DEBUG : sub dir/ignore dir/.ignore: md5 = 330f2f95d64013b5d1681a57919a07a8 OK 2026/11/02 02:01:28 DEBUG : sub dir/ignore dir/should be ignored: md5 = d7ac0c0a67367c0df50e78327d5c7775 OK 2026/11/02 02:01:30 DEBUG : sub dir/sub sub dir/hello world3: md5 = 384d4d08fb2806df7a5ecefd1e392588 OK 2026/11/02 02:01:31 DEBUG : a.txt: Excluded (Size Filter) 2026/11/02 02:01:31 DEBUG : a.txt: Excluded 2026/11/02 02:01:32 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/11/02 02:01:32 DEBUG : sub dir/hello world2: Excluded 2026/11/02 02:01:32 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/11/02 02:01:32 DEBUG : sub dir/hello world: Excluded 2026/11/02 02:01:32 DEBUG : sub dir/ignore dir: Excluded 2026/11/02 02:01:32 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/11/02 02:01:32 DEBUG : sub dir/hello world2: Excluded 2026/11/02 02:01:32 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/11/02 02:01:32 DEBUG : sub dir/hello world: Excluded 2026/11/02 02:01:33 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSortedFn (22.37s) === RUN TestListJSON run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 02:01:41 DEBUG : file1: md5 = 9790d8ba7cda39afa76fdb484f92d86f OK 2026/11/02 02:01:43 DEBUG : sub/file2: md5 = 13a96b4456ec0ee68f4de05ea2a6c34f 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/02 02:01:46 ERROR : file1: Failed to read hash: hash type not supported === RUN TestListJSON/Metadata 2026/11/02 02:01:46 DEBUG : eer8kka55qnghc34cq76ca668g: Fetching metadata 2026/11/02 02:01:46 DEBUG : 150fuo3cn4j1uenq6r4g4qk6t4: Fetching metadata --- PASS: TestListJSON (9.68s) --- PASS: TestListJSON/Default (0.24s) --- PASS: TestListJSON/FilesOnly (0.23s) --- PASS: TestListJSON/DirsOnly (0.22s) --- PASS: TestListJSON/Recurse (0.45s) --- PASS: TestListJSON/SubDir (0.23s) --- PASS: TestListJSON/NoModTime (0.25s) --- PASS: TestListJSON/NoMimeType (0.25s) --- PASS: TestListJSON/ShowHash (0.24s) --- PASS: TestListJSON/HashTypes (0.24s) --- PASS: TestListJSON/Metadata (0.71s) === RUN TestStatJSON run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 02:01:51 DEBUG : file1: md5 = 4d264de0a9ceb6472d6d51ead1b5eb6c OK 2026/11/02 02:01:53 DEBUG : sub/file2: md5 = 961f0767c8ad751b0f4b89695bdd9abc 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/02 02:01:57 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-wiyopif5fuya/notfound" 2026/11/02 02:01:57 DEBUG : Config file has changed externally - reloading 2026/11/02 02:01:57 DEBUG : Creating backend with remote "TestDrive:crypt/vsdfbtsla04vamvkknaanjiop2bgv6q97ajse673ngudlhnuln10/qe0i31qdkoejk60elps0ni5sqk" --- PASS: TestStatJSON (12.22s) --- PASS: TestStatJSON/Root (0.23s) --- PASS: TestStatJSON/RootFilesOnly (0.00s) --- PASS: TestStatJSON/RootDirsOnly (0.22s) --- PASS: TestStatJSON/Dir (0.46s) --- PASS: TestStatJSON/DirWithTrailingSlash (0.24s) --- PASS: TestStatJSON/File (0.26s) --- PASS: TestStatJSON/NotFound (0.45s) --- PASS: TestStatJSON/DirFilesOnly (0.23s) --- PASS: TestStatJSON/FileFilesOnly (0.24s) --- PASS: TestStatJSON/NotFoundFilesOnly (0.23s) --- PASS: TestStatJSON/DirDirsOnly (0.25s) --- PASS: TestStatJSON/FileDirsOnly (0.24s) --- PASS: TestStatJSON/NotFoundDirsOnly (0.26s) --- PASS: TestStatJSON/RootNotFound (2.05s) === RUN TestStatJSONMemory 2026/11/02 02:02:01 DEBUG : Creating backend with remote ":memory:" 2026/11/02 02:02:01 DEBUG : Memory root '': File to upload is small (5 bytes), uploading instead of streaming 2026/11/02 02:02:01 DEBUG : sub/file1: size = 5 OK 2026/11/02 02:02:01 DEBUG : sub/file1: md5 = 5d41402abc4b2a76b9719d911017c592 OK 2026/11/02 02:02:01 DEBUG : sub/file1: Size and md5 of src and dst objects identical === RUN TestStatJSONMemory/Dir === RUN TestStatJSONMemory/DirWithTrailingSlash === RUN TestStatJSONMemory/File === RUN TestStatJSONMemory/NotFound --- PASS: TestStatJSONMemory (0.01s) --- PASS: TestStatJSONMemory/Dir (0.00s) --- PASS: TestStatJSONMemory/DirWithTrailingSlash (0.00s) --- PASS: TestStatJSONMemory/File (0.00s) --- PASS: TestStatJSONMemory/NotFound (0.00s) === RUN TestStatJSONConfinement --- PASS: TestStatJSONConfinement (0.00s) === RUN TestMkdir run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 02:02:01 INFO : Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya': Making directory 2026/11/02 02:02:02 INFO : Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya': Making directory --- PASS: TestMkdir (0.69s) === RUN TestLsd run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 02:02:04 DEBUG : sub dir/hello world: md5 = 6d77a8cc9275d6fd4bc949418ceded84 OK --- PASS: TestLsd (5.05s) === RUN TestLs run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 02:02:09 DEBUG : potato2: md5 = 29f7cc266febe4faf28df8ec823eb340 OK 2026/11/02 02:02:10 DEBUG : empty space: md5 = 08d9f6db02f14e08bcea48d54124ec2f OK --- PASS: TestLs (5.01s) === RUN TestLsWithFilesFrom run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 02:02:14 DEBUG : potato2: md5 = af19b7a763bbaf083e2abd03b5b78d94 OK 2026/11/02 02:02:15 DEBUG : empty space: md5 = 07b099df7f784e246f6b76fa90c221f7 OK 2026/11/02 02:02:15 DEBUG : empty space: Excluded (FilesFrom Filter) 2026/11/02 02:02:15 DEBUG : empty space: Excluded --- PASS: TestLsWithFilesFrom (5.10s) === RUN TestLsLong run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 02:02:19 DEBUG : potato2: md5 = 0935a246baaa354ba4120d6d1f565c2f OK 2026/11/02 02:02:20 DEBUG : empty space: md5 = 8ae73e355edd67d90219e436ea1b4377 OK --- PASS: TestLsLong (4.56s) === RUN TestHashSums run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 02:02:23 DEBUG : potato2: md5 = f79c9067f43729b8c5f29b54cfb3b4d7 OK 2026/11/02 02:02:25 DEBUG : empty space: md5 = 5abc2d622557ae15ab94edb8d91c334a OK --- PASS: TestHashSums (4.69s) === RUN TestHashSumsWithErrors 2026/11/02 02:02:27 DEBUG : Creating backend with remote ":memory:" 2026/11/02 02:02:27 ERROR : file1: hash unsupported: hash type not supported 2026/11/02 02:02:27 ERROR : sub/file1: hash unsupported: hash type not supported 2026/11/02 02:02:27 ERROR : sub/file1: hash unsupported: hash type not supported --- PASS: TestHashSumsWithErrors (0.00s) === RUN TestHashStream 2026/11/02 02:02:27 DEBUG : Creating md5 hash of 0 bytes read from input stream 2026/11/02 02:02:27 DEBUG : Creating md5 hash of 0 bytes read from input stream 2026/11/02 02:02:27 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2026/11/02 02:02:27 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2026/11/02 02:02:27 DEBUG : Creating md5 hash of 12 bytes read from input stream 2026/11/02 02:02:27 DEBUG : Creating md5 hash of 12 bytes read from input stream 2026/11/02 02:02:27 DEBUG : Creating sha1 hash of 12 bytes read from input stream 2026/11/02 02:02:27 DEBUG : Creating sha1 hash of 12 bytes read from input stream --- PASS: TestHashStream (0.00s) === RUN TestSuffixName --- PASS: TestSuffixName (0.00s) === RUN TestCount run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 02:02:28 DEBUG : potato2: md5 = d6834ce96a722a7a8dc9203212424048 OK 2026/11/02 02:02:30 DEBUG : empty space: md5 = 6c388297cfd9426a19aeec723d5ce422 OK 2026/11/02 02:02:32 DEBUG : sub dir/potato3: md5 = 0b20e090b7f9e48eee17cd671d7c250d OK --- PASS: TestCount (9.13s) === RUN TestDelete run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 02:02:37 DEBUG : small: md5 = a951ad60581d6808eedfcb92a90de9a7 OK 2026/11/02 02:02:38 DEBUG : medium: md5 = 4fd11c506daa89f2e51b6892d33182d3 OK 2026/11/02 02:02:40 DEBUG : large: md5 = aee2b7aef77700c7f3d24b8fb89d5681 OK 2026/11/02 02:02:40 DEBUG : Waiting for deletions to finish 2026/11/02 02:02:40 DEBUG : large: Excluded (Size Filter) 2026/11/02 02:02:41 INFO : medium: Deleted 2026/11/02 02:02:41 INFO : small: Deleted --- PASS: TestDelete (6.53s) === RUN TestDeleteFatalError run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 02:02:44 DEBUG : file0: md5 = b91eb27e62acb6396835578bcb251efc OK 2026/11/02 02:02:45 DEBUG : file1: md5 = c0ef1a799b9e1822380a95eaff8a6b59 OK 2026/11/02 02:02:47 DEBUG : file2: md5 = 408dea177331d5daab4156e6820fdd25 OK 2026/11/02 02:02:48 DEBUG : file3: md5 = c1ecc970cc86b21035657a0c3eaa3a8e OK 2026/11/02 02:02:50 DEBUG : file4: md5 = d8e249164aece29eea28361b00d72b24 OK 2026/11/02 02:02:51 DEBUG : file5: md5 = 1be1e06cbbdc3b0a080b54c1ab080751 OK 2026/11/02 02:02:53 DEBUG : file6: md5 = b69f77782f5baafe1c462851adac631e OK 2026/11/02 02:02:54 DEBUG : file7: md5 = c2df0ff7cff0d5c141d1b264821dea17 OK 2026/11/02 02:02:55 DEBUG : file8: md5 = d3b9d534a3888b6b9a345696e0ebd7b5 OK 2026/11/02 02:02:57 DEBUG : file9: md5 = 48a644794cecb9aa2137c8fc9a54a2e2 OK 2026/11/02 02:02:58 DEBUG : file10: md5 = 852b9d12a9bc3346b8c07f754e307482 OK 2026/11/02 02:03:00 DEBUG : file11: md5 = 84ab40a2d36e0968c1e317b6b69798f7 OK 2026/11/02 02:03:01 DEBUG : file12: md5 = c68d559ca18cf867a94c213b9836ae3c OK 2026/11/02 02:03:03 DEBUG : file13: md5 = 6e8c3fbad1d7041457715e1de3db4155 OK 2026/11/02 02:03:04 DEBUG : file14: md5 = 97b82374af42d6e448a133dc3f4f5e24 OK 2026/11/02 02:03:06 DEBUG : file15: md5 = 155ba66368b40486dcf681aad58d0f0b OK 2026/11/02 02:03:08 DEBUG : file16: md5 = ee81cea646136f29f0e2afb635765aa4 OK 2026/11/02 02:03:09 DEBUG : file17: md5 = 4f9a869074272c53e0eee60b200c25bc OK 2026/11/02 02:03:11 DEBUG : file18: md5 = e60e2b074b7a8d76e9f13cc5cae8c444 OK 2026/11/02 02:03:12 DEBUG : file19: md5 = f0e82a3cc76336a8818dbfffa82329e3 OK 2026/11/02 02:03:12 DEBUG : Waiting for deletions to finish 2026/11/02 02:03:12 ERROR : file17: Got fatal error on delete: --max-delete threshold reached 2026/11/02 02:03:12 ERROR : file16: Got fatal error on delete: --max-delete threshold reached 2026/11/02 02:03:12 ERROR : file18: Got fatal error on delete: --max-delete threshold reached 2026/11/02 02:03:12 ERROR : file11: Got fatal error on delete: --max-delete threshold reached 2026/11/02 02:03:12 ERROR : file5: Got fatal error on delete: --max-delete threshold reached 2026/11/02 02:03:12 ERROR : file12: Got fatal error on delete: --max-delete threshold reached 2026/11/02 02:03:12 ERROR : file15: Got fatal error on delete: --max-delete threshold reached 2026/11/02 02:03:12 ERROR : file10: Got fatal error on delete: --max-delete threshold reached 2026/11/02 02:03:12 ERROR : file13: Got fatal error on delete: --max-delete threshold reached 2026/11/02 02:03:12 ERROR : file19: Got fatal error on delete: --max-delete threshold reached 2026/11/02 02:03:12 ERROR : file9: Got fatal error on delete: --max-delete threshold reached 2026/11/02 02:03:12 ERROR : file3: Got fatal error on delete: --max-delete threshold reached 2026/11/02 02:03:12 ERROR : file2: Got fatal error on delete: --max-delete threshold reached 2026/11/02 02:03:12 ERROR : file7: Got fatal error on delete: --max-delete threshold reached 2026/11/02 02:03:12 ERROR : file6: Got fatal error on delete: --max-delete threshold reached 2026/11/02 02:03:12 ERROR : file8: Got fatal error on delete: --max-delete threshold reached 2026/11/02 02:03:12 ERROR : file0: Got fatal error on delete: --max-delete threshold reached 2026/11/02 02:03:12 ERROR : file4: Got fatal error on delete: --max-delete threshold reached 2026/11/02 02:03:12 ERROR : file14: Got fatal error on delete: --max-delete threshold reached 2026/11/02 02:03:12 ERROR : file1: Got fatal error on delete: --max-delete threshold reached --- PASS: TestDeleteFatalError (39.87s) === RUN TestMaxDelete run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 02:03:23 DEBUG : small: md5 = b18e8997d2f8b69fff307cab3ee1d05f OK 2026/11/02 02:03:25 DEBUG : medium: md5 = 7370ab9023255ffc3822402df2423e94 OK 2026/11/02 02:03:26 DEBUG : large: md5 = dffe936a638085641885ab6d0a53c1a7 OK 2026/11/02 02:03:27 DEBUG : Waiting for deletions to finish 2026/11/02 02:03:27 ERROR : large: Got fatal error on delete: --max-delete threshold reached 2026/11/02 02:03:27 INFO : medium: Deleted 2026/11/02 02:03:27 INFO : small: Deleted --- PASS: TestMaxDelete (7.21s) === RUN TestMaxDeleteSizeLargeFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 02:03:31 DEBUG : small: md5 = 36df710304584e745409f9b08595ee59 OK 2026/11/02 02:03:32 DEBUG : medium: md5 = 69c92c5a52ab2af5bbdb1108e7ca2438 OK 2026/11/02 02:03:34 DEBUG : large: md5 = 91242ad2eb32c08e4b436ee174e998b7 OK 2026/11/02 02:03:34 DEBUG : Waiting for deletions to finish 2026/11/02 02:03:34 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached 2026/11/02 02:03:35 INFO : small: Deleted 2026/11/02 02:03:35 INFO : medium: Deleted --- PASS: TestMaxDeleteSizeLargeFile (7.00s) === RUN TestMaxDeleteSize run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 02:03:38 DEBUG : small: md5 = b9e966390ad2bf89e98475831a0a8924 OK 2026/11/02 02:03:39 DEBUG : medium: md5 = f0e67b02141fb0db8115e473391bf474 OK 2026/11/02 02:03:41 DEBUG : large: md5 = d37266996a23be084c9ab4af0482a674 OK 2026/11/02 02:03:41 DEBUG : Waiting for deletions to finish 2026/11/02 02:03:41 ERROR : medium: Got fatal error on delete: --max-delete-size threshold reached 2026/11/02 02:03:42 INFO : small: Deleted 2026/11/02 02:03:42 INFO : large: Deleted --- PASS: TestMaxDeleteSize (7.17s) === RUN TestReadFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 02:03:45 DEBUG : ReadFile: md5 = aa138f526ce46869fbcd19bc69d03906 OK --- PASS: TestReadFile (3.96s) === RUN TestRetry 2026/11/02 02:03:47 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 1/5 2026/11/02 02:03:47 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 2/5 2026/11/02 02:03:47 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2026/11/02 02:03:47 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2026/11/02 02:03:47 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2026/11/02 02:03:47 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms --- PASS: TestRetry (0.04s) === RUN TestRetryAfterContextCancel 2026/11/02 02:03:47 DEBUG : Sleeping for 1h0m0s (as indicated by the server) to obey Retry-After error: BANG: trying again in 1h0m0s --- PASS: TestRetryAfterContextCancel (0.00s) === RUN TestRetryAfterLastTry --- PASS: TestRetryAfterLastTry (0.00s) === RUN TestCat run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 02:03:49 DEBUG : file1: md5 = 12f8211f1bdfa31c35cb3f7f1346747c OK 2026/11/02 02:03:50 DEBUG : file2: md5 = f1999b3a9aedd473595ff8a20eb5a54f OK --- PASS: TestCat (15.69s) === RUN TestPurge 2026/11/02 02:04:03 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-talener0yubu" 2026/11/02 02:04:03 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/11/02 02:04:03 DEBUG : Creating backend with remote "TestDrive:crypt/mgffcc0hlc6u4ijg6spjqbskvsog9ioubgqk830resl2kok9q9kg" 2026/11/02 02:04:04 DEBUG : Creating backend with remote "/tmp/rclone1789513111" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-talener0yubu'", Local "Local file system at /tmp/rclone1789513111", Modify Window "1ms" 2026/11/02 02:04:09 DEBUG : A1/B1/C1/one: md5 = bf9cce8435cf6abfc2d2d56522d27db2 OK 2026/11/02 02:04:09 INFO : A2: Making directory 2026/11/02 02:04:09 INFO : A1/B2: Making directory 2026/11/02 02:04:10 INFO : A1/B2/C2: Making directory 2026/11/02 02:04:11 INFO : A1/B1/C3: Making directory 2026/11/02 02:04:12 INFO : A3: Making directory 2026/11/02 02:04:12 INFO : A3/B3: Making directory 2026/11/02 02:04:13 INFO : A3/B3/C4: Making directory 2026/11/02 02:04:15 DEBUG : A1/two: md5 = 29f88798a5e2a344d4091db4cb020ae6 OK 2026/11/02 02:04:19 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-talener0yubu': Purge remote 2026/11/02 02:04:19 NOTICE: purge failed: directory not found --- PASS: TestPurge (15.65s) === RUN TestRmdirsNoLeaveRoot run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 02:04:22 DEBUG : A1/B1/C1/one: md5 = 67640b8bc85ed981cd250613befa0ff7 OK 2026/11/02 02:04:22 INFO : A2: Making directory 2026/11/02 02:04:23 INFO : A1/B2: Making directory 2026/11/02 02:04:24 INFO : A1/B2/C2: Making directory 2026/11/02 02:04:24 INFO : A1/B1/C3: Making directory 2026/11/02 02:04:25 INFO : A3: Making directory 2026/11/02 02:04:26 INFO : A3/B3: Making directory 2026/11/02 02:04:27 INFO : A3/B3/C4: Making directory 2026/11/02 02:04:29 DEBUG : A1/two: md5 = ce3675f0f675ed6d14a747a0a4c33465 OK 2026/11/02 02:04:30 DEBUG : removing 1 level 3 directories 2026/11/02 02:04:30 INFO : A3/B3/C4: Removing directory 2026/11/02 02:04:32 DEBUG : removing 2 level 3 directories 2026/11/02 02:04:32 INFO : A1/B2/C2: Removing directory 2026/11/02 02:04:32 INFO : A1/B1/C3: Removing directory 2026/11/02 02:04:33 DEBUG : removing 2 level 2 directories 2026/11/02 02:04:33 INFO : A3/B3: Removing directory 2026/11/02 02:04:33 INFO : A1/B2: Removing directory 2026/11/02 02:04:34 DEBUG : removing 2 level 1 directories 2026/11/02 02:04:34 INFO : A3: Removing directory 2026/11/02 02:04:34 INFO : A2: Removing directory 2026/11/02 02:04:38 DEBUG : removing 1 level 3 directories 2026/11/02 02:04:38 INFO : A1/B1/C1: Removing directory 2026/11/02 02:04:39 DEBUG : removing 1 level 2 directories 2026/11/02 02:04:39 INFO : A1/B1: Removing directory 2026/11/02 02:04:39 DEBUG : removing 1 level 1 directories 2026/11/02 02:04:39 INFO : A1: Removing directory 2026/11/02 02:04:40 DEBUG : removing 1 level 0 directories 2026/11/02 02:04:40 INFO : Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya': Removing directory --- PASS: TestRmdirsNoLeaveRoot (22.76s) === RUN TestRmdirsLeaveRoot run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 02:04:42 INFO : A1: Making directory 2026/11/02 02:04:43 INFO : A1/B1: Making directory 2026/11/02 02:04:44 INFO : A1/B1/C1: Making directory 2026/11/02 02:04:47 DEBUG : removing 1 level 3 directories 2026/11/02 02:04:47 INFO : A1/B1/C1: Removing directory 2026/11/02 02:04:47 DEBUG : removing 1 level 2 directories 2026/11/02 02:04:47 INFO : A1/B1: Removing directory --- PASS: TestRmdirsLeaveRoot (8.18s) === RUN TestRmdirsWithFilter run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 02:04:50 INFO : A1: Making directory 2026/11/02 02:04:50 INFO : A1/B1: Making directory 2026/11/02 02:04:51 INFO : A1/B1/C1: Making directory 2026/11/02 02:04:54 DEBUG : removing 1 level 3 directories 2026/11/02 02:04:54 INFO : A1/B1/C1: Removing directory 2026/11/02 02:04:54 DEBUG : removing 1 level 2 directories 2026/11/02 02:04:54 INFO : A1/B1: Removing directory --- PASS: TestRmdirsWithFilter (7.11s) === RUN TestCopyURL run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 02:04:58 DEBUG : file1: md5 = cbe4397f0f4a06ed1053629d3d45d549 OK 2026/11/02 02:04:58 DEBUG : file1: size = 14 OK 2026/11/02 02:04:59 DEBUG : filename.txt: File name found in url 2026/11/02 02:05:00 DEBUG : filename.txt: md5 = a5e2cb77f57c387552d106410e879545 OK 2026/11/02 02:05:00 DEBUG : filename.txt: size = 14 OK 2026/11/02 02:05:00 DEBUG : headerfilename.txt: filename found in Content-Disposition header. 2026/11/02 02:05:02 DEBUG : headerfilename.txt: md5 = 8367881e511c7affd82317d9bb3299b4 OK 2026/11/02 02:05:02 DEBUG : headerfilename.txt: size = 14 OK 2026/11/02 02:05:03 DEBUG : file2: md5 = 7b6e6514e6ccda6b6cf388ae53ea93f2 OK 2026/11/02 02:05:03 DEBUG : file2: size = 14 OK --- PASS: TestCopyURL (8.93s) === RUN TestCopyURLDownloadHeaders run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 02:05:07 DEBUG : file1: md5 = 17d90867f1348086488cff48fc42bb93 OK 2026/11/02 02:05:07 DEBUG : file1: size = 14 OK --- PASS: TestCopyURLDownloadHeaders (2.29s) === RUN TestCopyURLToWriter --- PASS: TestCopyURLToWriter (0.00s) === RUN TestMoveFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 02:05:08 DEBUG : file1: Need to transfer - File not found at Destination 2026/11/02 02:05:10 DEBUG : sub/file2: md5 = e9bef7673e5d26b8bfb0b8527dfd21ab OK 2026/11/02 02:05:10 DEBUG : sub/file2: size = 14 OK 2026/11/02 02:05:10 INFO : file1: Copied (new) to: sub/file2 2026/11/02 02:05:10 INFO : file1: Deleted 2026/11/02 02:05:11 DEBUG : sub/file2: size = 14 OK 2026/11/02 02:05:11 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/11/02 02:05:11 DEBUG : file1: Unchanged skipping 2026/11/02 02:05:11 INFO : file1: Deleted 2026/11/02 02:05:11 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya': don't need to copy/move sub/file2, it is already at target location --- PASS: TestMoveFile (5.79s) === RUN TestMoveFileWithIgnoreExisting run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 02:05:14 DEBUG : file1: Need to transfer - File not found at Destination 2026/11/02 02:05:15 DEBUG : file1: md5 = 6ae74176a8e9a658ebe8b29dcf195dad OK 2026/11/02 02:05:15 DEBUG : file1: size = 14 OK 2026/11/02 02:05:15 INFO : file1: Copied (new) 2026/11/02 02:05:15 INFO : file1: Deleted 2026/11/02 02:05:16 DEBUG : file1: Destination exists, skipping 2026/11/02 02:05:16 DEBUG : file1: Not removing source file as destination file exists and --ignore-existing is set --- PASS: TestMoveFileWithIgnoreExisting (3.22s) === RUN TestMoveFileImmutable run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 02:05:19 DEBUG : existing: md5 = 782aa013e07282958ed152e9f36defb6 OK 2026/11/02 02:05:19 DEBUG : existing: size = 8 (Local file system at /tmp/rclone1219163137) 2026/11/02 02:05:19 DEBUG : existing: size = 6 (Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya') 2026/11/02 02:05:19 DEBUG : existing: Sizes differ 2026/11/02 02:05:19 ERROR : existing: Source and destination exist but do not match: immutable file modified --- PASS: TestMoveFileImmutable (3.22s) === RUN TestCaseInsensitiveMoveFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFile (0.44s) === RUN TestMoveOntoItself === RUN TestMoveOntoItself/viaSubdir=false,disableMove=false 2026/11/02 02:05:21 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-najitol2hiye" 2026/11/02 02:05:21 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/11/02 02:05:21 DEBUG : Creating backend with remote "TestDrive:crypt/hi80b4ps1b93jvalk557bvkrrmne3l520fq4guj7jsvb05jooqtg" 2026/11/02 02:05:22 DEBUG : Creating backend with remote "/tmp/rclone3233846098" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-najitol2hiye'", Local "Local file system at /tmp/rclone3233846098", Modify Window "1ms" 2026/11/02 02:05:25 DEBUG : sub/file1: md5 = 9a520d570af87ad9eed99f67ccb783c1 OK 2026/11/02 02:05:25 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-najitol2hiye': Purge remote === RUN TestMoveOntoItself/viaSubdir=false,disableMove=true 2026/11/02 02:05:26 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-xayakas2piho" 2026/11/02 02:05:26 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/11/02 02:05:26 DEBUG : Creating backend with remote "TestDrive:crypt/3p8dpql9mi3ks5nbb81ik48s9f05716lufnb7rli5v9hsdjjbhj0" 2026/11/02 02:05:27 DEBUG : Creating backend with remote "/tmp/rclone482145567" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-xayakas2piho'", Local "Local file system at /tmp/rclone482145567", Modify Window "1ms" 2026/11/02 02:05:30 DEBUG : sub/file1: md5 = b0b0de793904129dff3445206f4b8391 OK 2026/11/02 02:05:31 DEBUG : Reset feature "Move" 2026/11/02 02:05:31 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-xayakas2piho': Purge remote === RUN TestMoveOntoItself/viaSubdir=true,disableMove=false 2026/11/02 02:05:32 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-dorosit6wefa" 2026/11/02 02:05:32 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/11/02 02:05:32 DEBUG : Creating backend with remote "TestDrive:crypt/d6umcuipldabi6dt15saqspb8774m46071bkabkuuvsm6v90mok0" 2026/11/02 02:05:33 DEBUG : Creating backend with remote "/tmp/rclone1126572287" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-dorosit6wefa'", Local "Local file system at /tmp/rclone1126572287", Modify Window "1ms" 2026/11/02 02:05:35 DEBUG : sub/file1: md5 = 197dba7f71dccf6df41b70a8e86d7655 OK 2026/11/02 02:05:36 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-dorosit6wefa/sub" 2026/11/02 02:05:36 DEBUG : Creating backend with remote "TestDrive:crypt/d6umcuipldabi6dt15saqspb8774m46071bkabkuuvsm6v90mok0/150fuo3cn4j1uenq6r4g4qk6t4" 2026/11/02 02:05:37 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-dorosit6wefa': Purge remote === RUN TestMoveOntoItself/viaSubdir=true,disableMove=true 2026/11/02 02:05:37 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-kuwijit0yifo" 2026/11/02 02:05:37 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/11/02 02:05:37 DEBUG : Creating backend with remote "TestDrive:crypt/kom3t4pa3o6v7regkr02aj8q210qmfqubet98fhoebf9dhl7ql70" 2026/11/02 02:05:38 DEBUG : Creating backend with remote "/tmp/rclone440111589" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kuwijit0yifo'", Local "Local file system at /tmp/rclone440111589", Modify Window "1ms" 2026/11/02 02:05:41 DEBUG : sub/file1: md5 = 9ff05fcf77e57e565fbadd37b4b461e6 OK 2026/11/02 02:05:42 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-kuwijit0yifo/sub" 2026/11/02 02:05:42 DEBUG : Creating backend with remote "TestDrive:crypt/kom3t4pa3o6v7regkr02aj8q210qmfqubet98fhoebf9dhl7ql70/150fuo3cn4j1uenq6r4g4qk6t4" 2026/11/02 02:05:42 DEBUG : Reset feature "Move" 2026/11/02 02:05:43 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-kuwijit0yifo': Purge remote --- PASS: TestMoveOntoItself (22.69s) --- PASS: TestMoveOntoItself/viaSubdir=false,disableMove=false (5.18s) --- PASS: TestMoveOntoItself/viaSubdir=false,disableMove=true (5.66s) --- PASS: TestMoveOntoItself/viaSubdir=true,disableMove=false (5.72s) --- PASS: TestMoveOntoItself/viaSubdir=true,disableMove=true (6.12s) === RUN TestMoveUnicodeEquivalentWithoutServerSideMove 2026/11/02 02:05:43 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jacesek3sipe" 2026/11/02 02:05:43 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/11/02 02:05:43 DEBUG : Creating backend with remote "TestDrive:crypt/jlv6rojjusfku0st5pgkldci1gq8ldbjuhsrqat42ill17mpn32g" 2026/11/02 02:05:44 DEBUG : Creating backend with remote "/tmp/rclone384969972" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jacesek3sipe'", Local "Local file system at /tmp/rclone384969972", Modify Window "1ms" 2026/11/02 02:05:44 DEBUG : Reset feature "Move" 2026/11/02 02:05:47 DEBUG : sub/équivalent.txt: md5 = 29f2584e337280b58cc80299a8680f11 OK 2026/11/02 02:05:48 DEBUG : sub/équivalent.txt: moving to sub/équivalent.txt-rclone-move-qanapaq2 2026/11/02 02:05:49 DEBUG : sub/équivalent.txt-rclone-move-qanapaq2: size = 14 OK 2026/11/02 02:05:49 INFO : sub/équivalent.txt: Copied (server-side copy) to: sub/équivalent.txt-rclone-move-qanapaq2 2026/11/02 02:05:49 INFO : sub/équivalent.txt: Deleted 2026/11/02 02:05:49 DEBUG : sub/équivalent.txt: moving to sub/équivalent.txt 2026/11/02 02:05:50 DEBUG : sub/équivalent.txt: size = 14 OK 2026/11/02 02:05:50 INFO : sub/équivalent.txt-rclone-move-qanapaq2: Copied (server-side copy) to: sub/équivalent.txt 2026/11/02 02:05:51 INFO : sub/équivalent.txt-rclone-move-qanapaq2: Deleted 2026/11/02 02:05:51 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jacesek3sipe': Purge remote --- PASS: TestMoveUnicodeEquivalentWithoutServerSideMove (8.29s) === RUN TestCaseInsensitiveMoveWithoutServerSideMove 2026/11/02 02:05:52 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-yonoros2yono" 2026/11/02 02:05:52 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/11/02 02:05:52 DEBUG : Creating backend with remote "TestDrive:crypt/nos8qtjirhh1imi7ck4fsdr1i5vfoigcl5sga6pclf77rjj4s23g" 2026/11/02 02:05:53 DEBUG : Creating backend with remote "/tmp/rclone2549524970" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-yonoros2yono'", Local "Local file system at /tmp/rclone2549524970", Modify Window "1ms" operations_test.go:1198: remote is case sensitive 2026/11/02 02:05:53 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-yonoros2yono': Purge remote 2026/11/02 02:05:53 NOTICE: purge failed: directory not found --- SKIP: TestCaseInsensitiveMoveWithoutServerSideMove (1.28s) === RUN TestCaseInsensitiveMoveFileDryRun run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFileDryRun (0.45s) === RUN TestMoveFileBackupDir run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 02:05:56 DEBUG : dst/file1: md5 = 6599957dc861a44c0c37470dde8d0dd4 OK 2026/11/02 02:05:56 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-wiyopif5fuya/backup" 2026/11/02 02:05:56 DEBUG : Creating backend with remote "TestDrive:crypt/vsdfbtsla04vamvkknaanjiop2bgv6q97ajse673ngudlhnuln10/1nrff024r7pq65ecp72fc28jb0" 2026/11/02 02:05:58 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone1219163137) 2026/11/02 02:05:58 DEBUG : dst/file1: size = 18 (Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya') 2026/11/02 02:05:58 DEBUG : dst/file1: Sizes differ 2026/11/02 02:06:00 INFO : dst/file1: Moved (server-side) 2026/11/02 02:06:01 DEBUG : dst/file1: md5 = cd335d589f369ff8149812f61d9944d0 OK 2026/11/02 02:06:01 DEBUG : dst/file1: size = 14 OK 2026/11/02 02:06:01 INFO : dst/file1: Copied (new) 2026/11/02 02:06:01 INFO : dst/file1: Deleted --- PASS: TestMoveFileBackupDir (12.15s) === 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-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 02:06:08 DEBUG : A/file0: md5 = a4bd9410f664f918ce2fd3444281b9cc OK 2026/11/02 02:06:09 DEBUG : A/file1: md5 = d61dc406e3eb15eba13b187293688dba OK 2026/11/02 02:06:11 DEBUG : A/file2: md5 = c23ff6534b9490dc02834e260d6f7f9c OK 2026/11/02 02:06:12 DEBUG : A/file3: md5 = c8e638b2a470ad35cac594685e8d8734 OK 2026/11/02 02:06:13 DEBUG : A/file4: md5 = b5eb94f53abc7f3c1f0dc17a58cbbb06 OK 2026/11/02 02:06:15 DEBUG : A/file5: md5 = e03eda272fb5690b5ceb00a35921b2a3 OK 2026/11/02 02:06:17 DEBUG : A/file6: md5 = f2a59d271ef08da673e2c6a4fa742b3b OK 2026/11/02 02:06:18 DEBUG : A/file7: md5 = 11a8edcf527f614c05401b65eee1a176 OK 2026/11/02 02:06:19 DEBUG : A/file8: md5 = b50bdebd9394b19482f10b2705980480 OK 2026/11/02 02:06:21 DEBUG : A/file9: md5 = baf4b1ebcebe2f72e16d94c6ee13f303 OK 2026/11/02 02:06:22 DEBUG : A/file10: md5 = bdf882d520b3d52de03cfef6c1be4392 OK 2026/11/02 02:06:24 DEBUG : A/file11: md5 = e9a8d5b2283b08109f1b23227fb7e113 OK 2026/11/02 02:06:25 DEBUG : A/file12: md5 = 14d59734a63d24f2ff03544c5e5acf0a OK 2026/11/02 02:06:27 DEBUG : A/file13: md5 = d3fdd3285b17cdb5321637a7d3a70694 OK 2026/11/02 02:06:28 DEBUG : A/file14: md5 = 6c65f128f0103dfbb6bade1310de1e2f OK 2026/11/02 02:06:29 DEBUG : A/file15: md5 = 07100716407ee0537b6ea2753db6b2a4 OK 2026/11/02 02:06:31 DEBUG : A/file16: md5 = d10650d0723b73fca1eba5b388d65299 OK 2026/11/02 02:06:32 DEBUG : A/file17: md5 = 4dde09c2dbb04cef3a131f216954e45e OK 2026/11/02 02:06:34 DEBUG : A/file18: md5 = 63f3e4ee9a0cbffa10b228a23688ec28 OK 2026/11/02 02:06:35 DEBUG : A/file19: md5 = 6d302cded647322fdde9b93ef3aaf9eb OK operations_test.go:1563: Skipping test on non local remote --- SKIP: TestDirMoveMoveError (40.10s) === RUN TestDirMoveContext run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 02:06:48 DEBUG : A/one: md5 = a330669b70adc04513c919b5a20ec8d0 OK 2026/11/02 02:06:49 DEBUG : A/two: md5 = 7e6abd0b1304cec5de23f9ba5eacd83c OK operations_test.go:1563: Skipping test on non local remote --- SKIP: TestDirMoveContext (5.95s) === RUN TestDirMove run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 02:06:54 DEBUG : A1/one: md5 = d1e16ab072c75763c015133ddce2547b OK 2026/11/02 02:06:55 DEBUG : A1/two: md5 = 405dde9bfb8a337b344a7e6c01b2686a OK 2026/11/02 02:06:57 DEBUG : A1/B1/three: md5 = 91aa2de89a0c8a640888eea50a201a91 OK 2026/11/02 02:06:59 DEBUG : A1/B1/C1/four: md5 = d001e45e0c041730537da7b30bb01a99 OK 2026/11/02 02:07:02 DEBUG : A1/B1/C2/five: md5 = c4f6037de0716cb086f4e0956e3f012e OK 2026/11/02 02:07:02 INFO : A1/B2: Making directory 2026/11/02 02:07:03 INFO : A1/B1/C3: Making directory 2026/11/02 02:07:12 INFO : A2/B1/C1/four: Moved (server-side) to: A3/B1/C1/four 2026/11/02 02:07:12 INFO : A2/B1/C2/five: Moved (server-side) to: A3/B1/C2/five 2026/11/02 02:07:12 INFO : A2/two: Moved (server-side) to: A3/two 2026/11/02 02:07:12 INFO : A2/one: Moved (server-side) to: A3/one 2026/11/02 02:07:12 INFO : A2/B1/three: Moved (server-side) to: A3/B1/three 2026/11/02 02:07:18 INFO : Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya': Can't DirMove - falling back to file moves: can't move directory - incompatible remotes 2026/11/02 02:07:24 INFO : A3/two: Moved (server-side) to: A4/two 2026/11/02 02:07:24 INFO : A3/B1/C2/five: Moved (server-side) to: A4/B1/C2/five 2026/11/02 02:07:24 INFO : A3/B1/C1/four: Moved (server-side) to: A4/B1/C1/four 2026/11/02 02:07:24 INFO : A3/one: Moved (server-side) to: A4/one 2026/11/02 02:07:24 INFO : A3/B1/three: Moved (server-side) to: A4/B1/three --- PASS: TestDirMove (44.72s) === RUN TestGetFsInfo run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 02:07:36 DEBUG : Config file has changed externally - reloading --- PASS: TestGetFsInfo (0.43s) === RUN TestRcat === RUN TestRcat/withChecksum=false,ignoreChecksum=false run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 02:07:37 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya': File to upload is small (34 bytes), uploading instead of streaming 2026/11/02 02:07:38 DEBUG : no_checksum_small_file_from_pipe: md5 = ede66e293d60ee5b08deedd087012a10 OK 2026/11/02 02:07:38 DEBUG : no_checksum_small_file_from_pipe: size = 34 OK 2026/11/02 02:07:38 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2026/11/02 02:07:38 DEBUG : no_checksum_small_file_from_pipe: Size of src and dst objects identical 2026/11/02 02:07:39 DEBUG : qc9k4uauqa9ls6a2r6lo8jf4b1m7r0510pkurois1cc70egbs4h0: Sending chunk 0 length 102465 2026/11/02 02:07:40 DEBUG : no_checksum_big_file_from_pipe: md5 = 678f89b3e1670815d00948ee5886f417 OK 2026/11/02 02:07:40 DEBUG : no_checksum_big_file_from_pipe: size = 102401 OK 2026/11/02 02:07:40 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-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 02:07:42 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya': File to upload is small (34 bytes), uploading instead of streaming 2026/11/02 02:07:43 DEBUG : with_checksum_small_file_from_pipe: md5 = 9752361bfcb9256fce6eee35e5810298 OK 2026/11/02 02:07:43 DEBUG : with_checksum_small_file_from_pipe: size = 34 OK 2026/11/02 02:07:43 DEBUG : with_checksum_small_file_from_pipe: Size of src and dst objects identical 2026/11/02 02:07:44 DEBUG : e74jo8a7lr1aimg2u78fct67gnuetc1qujiofaci0c8fnq7rjt1d4i296pcklqhgdihs03etvgdi4: Sending chunk 0 length 102465 2026/11/02 02:07:45 DEBUG : with_checksum_big_file_from_pipe: md5 = 5c1323191e4a6d20b371ea57d0e8340d OK 2026/11/02 02:07:45 DEBUG : with_checksum_big_file_from_pipe: size = 102401 OK 2026/11/02 02:07:45 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-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 02:07:47 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya': File to upload is small (34 bytes), uploading instead of streaming 2026/11/02 02:07:49 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK 2026/11/02 02:07:49 DEBUG : ignore_checksum_small_file_from_pipe: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/11/02 02:07:49 DEBUG : 1963rn6olajfbjn4nbqi9nacu7bk879vpf0d2bg8r0ndcqqsfntob0urt056p0qujf574rqnb5f62: Sending chunk 0 length 102465 2026/11/02 02:07:50 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK 2026/11/02 02:07:50 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-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 02:07:52 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya': File to upload is small (34 bytes), uploading instead of streaming 2026/11/02 02:07:54 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK 2026/11/02 02:07:54 DEBUG : ignore_checksum_small_file_from_pipe: Size of src and dst objects identical 2026/11/02 02:07:55 DEBUG : 1963rn6olajfbjn4nbqi9nacu7bk879vpf0d2bg8r0ndcqqsfntob0urt056p0qujf574rqnb5f62: Sending chunk 0 length 102465 2026/11/02 02:07:56 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK 2026/11/02 02:07:56 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical --- PASS: TestRcat (20.56s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (4.99s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (4.90s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (5.47s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (5.19s) === RUN TestRcatMetadata run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" === RUN TestRcatMetadata/Normal 2026/11/02 02:07:58 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya': File to upload is small (48 bytes), uploading instead of streaming 2026/11/02 02:07:59 DEBUG : rcat_metadata: md5 = 0a3a3dbedaf48bb66331b93a158d2ea7 OK 2026/11/02 02:07:59 DEBUG : rcat_metadata: size = 48 OK 2026/11/02 02:07:59 DEBUG : rcat_metadata: Size of src and dst objects identical === RUN TestRcatMetadata/ViaDisk 2026/11/02 02:08:01 DEBUG : rijssoobhbn85c5p1imhg55m6frank1nlplla6hkdjlqmqb2471g: Sending chunk 0 length 111 2026/11/02 02:08:02 DEBUG : rcat_metadata_uploadcutoff0: md5 = 9d0d8b58484b36f4bdbc288481b5f514 OK 2026/11/02 02:08:02 DEBUG : rcat_metadata_uploadcutoff0: size = 63 OK 2026/11/02 02:08:02 DEBUG : rcat_metadata_uploadcutoff0: Size of src and dst objects identical --- PASS: TestRcatMetadata (5.76s) --- PASS: TestRcatMetadata/Normal (2.59s) --- PASS: TestRcatMetadata/ViaDisk (2.70s) === RUN TestRcatSize run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 02:08:04 DEBUG : potato1: md5 = 93816b9603739dda0e78af1667ae7f8a OK 2026/11/02 02:08:04 DEBUG : potato1: size = 60 OK 2026/11/02 02:08:04 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya': File to upload is small (60 bytes), uploading instead of streaming 2026/11/02 02:08:06 DEBUG : potato2: md5 = f9bf1029d086df62c6fd00b54a9759f3 OK 2026/11/02 02:08:06 DEBUG : potato2: size = 60 OK 2026/11/02 02:08:06 DEBUG : potato2: Size of src and dst objects identical --- PASS: TestRcatSize (4.37s) === RUN TestRcatSizeShortEOF run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 02:08:09 DEBUG : potato1: md5 = fd6baa3c50a0b99db3b52bad568a7c72 OK 2026/11/02 02:08:09 DEBUG : potato1: size = 120 (Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya') 2026/11/02 02:08:09 DEBUG : potato1: size = 60 (Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya') 2026/11/02 02:08:09 ERROR : potato1: corrupted on transfer: sizes differ src 120 vs dst(Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya') 60 2026/11/02 02:08:09 INFO : potato1: Removing failed copy --- PASS: TestRcatSizeShortEOF (2.37s) === RUN TestRcatSizeMetadata run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 02:08:11 DEBUG : potato1: md5 = e9be56fa5743bb6a6e4563779773a384 OK 2026/11/02 02:08:11 DEBUG : potato1: size = 60 OK 2026/11/02 02:08:11 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya': File to upload is small (60 bytes), uploading instead of streaming 2026/11/02 02:08:13 DEBUG : potato2: md5 = 56449487af98d69cc61d59a8348e6896 OK 2026/11/02 02:08:13 DEBUG : potato2: size = 60 OK 2026/11/02 02:08:13 DEBUG : potato2: Size of src and dst objects identical --- PASS: TestRcatSizeMetadata (4.84s) === RUN TestRcatSizeUploadHeaders run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 02:08:16 DEBUG : potato1: md5 = fd2baf207c49e97281fa391ecc9fd069 OK 2026/11/02 02:08:16 DEBUG : potato1: size = 60 OK --- PASS: TestRcatSizeUploadHeaders (2.25s) === RUN TestRcatSizeChecksum === RUN TestRcatSizeChecksum/Corrupted run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", 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-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 02:08:19 DEBUG : potato4: md5 = 07d88d0c5ac9c72939df90101a6d6cdf OK 2026/11/02 02:08:19 DEBUG : potato4: size = 60 (Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya') 2026/11/02 02:08:19 DEBUG : potato4: size = 59 (Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya') 2026/11/02 02:08:19 ERROR : potato4: corrupted on transfer: sizes differ src 60 vs dst(Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya') 59 2026/11/02 02:08:19 INFO : potato4: Removing failed copy === RUN TestRcatSizeChecksum/IgnoreChecksum run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 02:08:21 DEBUG : potato2: size = 60 OK === RUN TestRcatSizeChecksum/NoHashes run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 02:08:23 DEBUG : potato3: md5 = 9e004de19e704de04f0a0b9d2f412ae5 OK 2026/11/02 02:08:23 DEBUG : potato3: size = 60 OK --- PASS: TestRcatSizeChecksum (7.55s) --- SKIP: TestRcatSizeChecksum/Corrupted (0.39s) --- PASS: TestRcatSizeChecksum/SizeDiffers (2.38s) --- PASS: TestRcatSizeChecksum/IgnoreChecksum (2.44s) --- PASS: TestRcatSizeChecksum/NoHashes (2.34s) === RUN TestTouchDir run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 02:08:26 DEBUG : potato2: md5 = e80e0fdcd65abf4bf33caad9da9dc36c OK 2026/11/02 02:08:27 DEBUG : empty space: md5 = 1a1facf92632e83684af4328f22c3d55 OK 2026/11/02 02:08:29 DEBUG : sub dir/potato3: md5 = 551d89076b7b5a80e3296b3f735d8f19 OK 2026/11/02 02:08:30 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya': Touching "sub dir/potato3" 2026/11/02 02:08:30 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya': Touching "empty space" 2026/11/02 02:08:30 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya': Touching "potato2" --- PASS: TestTouchDir (9.42s) === RUN TestMkdirMetadata run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 02:08:34 DEBUG : dir with metadata: Making directory with metadata 2026/11/02 02:08:35 INFO : dir with metadata: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) --- PASS: TestMkdirMetadata (2.25s) === RUN TestMkdirModTime run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 02:08:36 DEBUG : directory with modtime: Making directory with metadata 2026/11/02 02:08:37 INFO : directory with modtime: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/11/02 02:08:37 NOTICE: dry run directory with modtime: Skipped make directory as --dry-run is set --- PASS: TestMkdirModTime (2.37s) === RUN TestCopyDirMetadata run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 02:08:38 DEBUG : dir with metadata to be copied: Making directory with metadata 2026/11/02 02:08:38 INFO : dir with metadata to be copied: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2026/11/02 02:08:39 DEBUG : Google drive root 'crypt/vsdfbtsla04vamvkknaanjiop2bgv6q97ajse673ngudlhnuln10': Skipping btime metadata as can't update it on an existing file: 2026-11-02T02:08:38.98769512Z 2026/11/02 02:08:39 INFO : non existent directory: Updated directory metadata 2026/11/02 02:08:41 DEBUG : Google drive root 'crypt/vsdfbtsla04vamvkknaanjiop2bgv6q97ajse673ngudlhnuln10': Skipping btime metadata as can't update it on an existing file: 2026-11-02T02:08:38.98769512Z 2026/11/02 02:08:42 INFO : existing directory: Updated directory metadata --- PASS: TestCopyDirMetadata (4.67s) === RUN TestSetDirModTime run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 02:08:43 DEBUG : set modtime on non existent directory: Skipping set directory modification time as --no-update-dir-modtime is set 2026/11/02 02:08:45 INFO : set modtime on existing directory: Set directory modification time (using SetModTime) 2026/11/02 02:08:46 INFO : set modtime on existing directory: Set directory modification time (using DirSetModTime) --- PASS: TestSetDirModTime (3.91s) === RUN TestDirsEqual run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 02:08:47 DEBUG : dir with metadata to be copied: Making directory with metadata 2026/11/02 02:08:47 INFO : dir with metadata to be copied: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2026/11/02 02:08:47 DEBUG : dst: Making directory with metadata 2026/11/02 02:08:48 INFO : dst: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2026/11/02 02:08:48 DEBUG : dst: Directory modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/11/02 02:08:48 INFO : dst: Set directory modification time (using SetModTime) 2026/11/02 02:08:49 INFO : dst: Set directory modification time (using SetModTime) 2026/11/02 02:08:49 DEBUG : dst: Directory modification time the same (differ by 1ns, within tolerance 1ms) 2026/11/02 02:08:49 INFO : dst: Set directory modification time (using SetModTime) 2026/11/02 02:08:49 DEBUG : dst: Destination directory is newer than source, skipping --- PASS: TestDirsEqual (3.52s) === RUN TestRemoveExisting run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya'", Local "Local file system at /tmp/rclone1219163137", Modify Window "1ms" 2026/11/02 02:08:53 DEBUG : sub dir/test remove existing: md5 = b4fee3bf6ee7945f141441fa4810a0d7 OK 2026/11/02 02:08:54 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: md5 = 0b4967ebaf372e242acba3e0162b6a7e OK 2026/11/02 02:08:56 DEBUG : sub dir/test remove existing: TEST: renaming existing object to "sub dir/test remove existing.naqedom0" before starting 2026/11/02 02:08:57 DEBUG : sub dir/test remove existing.naqedom0: TEST: removing renamed existing file after operation 2026/11/02 02:08:58 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: TEST: renaming existing object to "sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.bimeves2" before starting 2026/11/02 02:08:59 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.bimeves2: TEST: renaming existing back after failed operation 2026/11/02 02:09:00 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: TEST: renaming existing object to "sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.beqijoy3" before starting 2026/11/02 02:09:01 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.beqijoy3: TEST: removing renamed existing file after operation --- PASS: TestRemoveExisting (13.02s) === RUN TestRcatInputFailurePreservesDestination 2026/11/02 02:09:04 DEBUG : Creating backend with remote "/tmp/TestRcatInputFailurePreservesDestination2519558862/001" 2026/11/02 02:09:04 DEBUG : Config file has changed externally - reloading --- 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/02 02:09:04 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-wiyopif5fuya': Purge remote "./operations.test -test.v -test.timeout 1h0m0s -remote TestCryptDrive: -verbose" - Finished OK in 21m54.599004s (try 1/5)