"./operations.test -test.v -test.timeout 1h0m0s -remote TestCryptDrive: -verbose" - Starting (try 1/5) 2026/09/21 04:45:00 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-tilefol3vuga" 2026/09/21 04:45:00 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/21 04:45:00 DEBUG : Creating backend with remote "TestDrive:crypt/ljsouapbvkh23ogo0it9v08lej41ihf8r2f7fjm8l5ikksfld8m0" 2026/09/21 04:45:01 DEBUG : Creating backend with remote "/tmp/rclone1633621919" === 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-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" multithread_test.go:120: multithread writing not supported --- SKIP: TestMultithreadCopy (0.25s) === RUN TestMultithreadCopyAbort run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" multithread_test.go:120: multithread writing not supported --- SKIP: TestMultithreadCopyAbort (0.24s) === RUN TestMultithreadCopyWriterAtErrors === RUN TestMultithreadCopyWriterAtErrors/open/fatal=false 2026/09/21 04:45:01 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt === RUN TestMultithreadCopyWriterAtErrors/open/fatal=true 2026/09/21 04:45:01 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt === RUN TestMultithreadCopyWriterAtErrors/write/fatal=false 2026/09/21 04:45:01 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2026/09/21 04:45:01 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams 2026/09/21 04:45:01 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting 2026/09/21 04:45:01 DEBUG : file.txt: writing chunk 1 2026/09/21 04:45:01 DEBUG : file.txt: multi-thread copy: chunk 2/4 failed: multi-thread copy: failed to write chunk: disk full 2026/09/21 04:45:01 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting 2026/09/21 04:45:01 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting 2026/09/21 04:45:01 DEBUG : file.txt: writing chunk 2 2026/09/21 04:45:01 DEBUG : file.txt: multi-thread copy: chunk 3/4 failed: multi-thread copy: failed to write chunk: disk full 2026/09/21 04:45:01 DEBUG : file.txt: writing chunk 0 2026/09/21 04:45:01 DEBUG : file.txt: multi-thread copy: chunk 1/4 failed: multi-thread copy: failed to write chunk: disk full 2026/09/21 04:45:01 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit 2026/09/21 04:45:01 DEBUG : file.txt: multi-thread copy: abort failed: multi-thread copy: failed to find temp file when aborting chunk writer: object not found === RUN TestMultithreadCopyWriterAtErrors/write/fatal=true 2026/09/21 04:45:01 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2026/09/21 04:45:01 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams 2026/09/21 04:45:01 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting 2026/09/21 04:45:01 DEBUG : file.txt: writing chunk 1 2026/09/21 04:45:01 DEBUG : file.txt: multi-thread copy: chunk 2/4 failed: multi-thread copy: failed to write chunk: disk full 2026/09/21 04:45:01 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting 2026/09/21 04:45:01 DEBUG : file.txt: writing chunk 0 2026/09/21 04:45:01 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting 2026/09/21 04:45:01 DEBUG : file.txt: writing chunk 2 2026/09/21 04:45:01 DEBUG : file.txt: multi-thread copy: chunk 1/4 failed: multi-thread copy: failed to write chunk: disk full 2026/09/21 04:45:01 DEBUG : file.txt: multi-thread copy: chunk 3/4 failed: multi-thread copy: failed to write chunk: disk full 2026/09/21 04:45:01 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit 2026/09/21 04:45:01 DEBUG : file.txt: multi-thread copy: abort failed: multi-thread copy: failed to find temp file when aborting chunk writer: object not found === RUN TestMultithreadCopyWriterAtErrors/close/fatal=false 2026/09/21 04:45:01 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2026/09/21 04:45:01 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams 2026/09/21 04:45:01 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting 2026/09/21 04:45:01 DEBUG : file.txt: writing chunk 1 2026/09/21 04:45:01 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 finished 2026/09/21 04:45:01 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting 2026/09/21 04:45:01 DEBUG : file.txt: writing chunk 2 2026/09/21 04:45:01 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 finished 2026/09/21 04:45:01 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 starting 2026/09/21 04:45:01 DEBUG : file.txt: writing chunk 3 2026/09/21 04:45:01 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 finished 2026/09/21 04:45:01 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting 2026/09/21 04:45:01 DEBUG : file.txt: writing chunk 0 2026/09/21 04:45:01 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 finished 2026/09/21 04:45:01 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit 2026/09/21 04:45:01 DEBUG : file.txt: multi-thread copy: abort failed: multi-thread copy: failed to find temp file when aborting chunk writer: object not found === RUN TestMultithreadCopyWriterAtErrors/close/fatal=true 2026/09/21 04:45:01 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2026/09/21 04:45:01 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams 2026/09/21 04:45:01 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting 2026/09/21 04:45:01 DEBUG : file.txt: writing chunk 1 2026/09/21 04:45:01 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 finished 2026/09/21 04:45:01 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting 2026/09/21 04:45:01 DEBUG : file.txt: writing chunk 2 2026/09/21 04:45:01 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 finished 2026/09/21 04:45:01 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 starting 2026/09/21 04:45:01 DEBUG : file.txt: writing chunk 3 2026/09/21 04:45:01 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 finished 2026/09/21 04:45:01 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting 2026/09/21 04:45:01 DEBUG : file.txt: writing chunk 0 2026/09/21 04:45:01 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 finished 2026/09/21 04:45:01 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit 2026/09/21 04:45:01 DEBUG : file.txt: multi-thread copy: abort failed: multi-thread copy: failed to find temp file when aborting chunk writer: object not found --- PASS: TestMultithreadCopyWriterAtErrors (0.00s) --- PASS: TestMultithreadCopyWriterAtErrors/open/fatal=false (0.00s) --- PASS: TestMultithreadCopyWriterAtErrors/open/fatal=true (0.00s) --- PASS: TestMultithreadCopyWriterAtErrors/write/fatal=false (0.00s) --- PASS: TestMultithreadCopyWriterAtErrors/write/fatal=true (0.00s) --- PASS: TestMultithreadCopyWriterAtErrors/close/fatal=false (0.00s) --- PASS: TestMultithreadCopyWriterAtErrors/close/fatal=true (0.00s) === RUN TestSizeDiffers 2026/09/21 04:45:01 DEBUG : a: size = 0 OK 2026/09/21 04:45:01 DEBUG : a: size = 1 (memory) 2026/09/21 04:45:01 DEBUG : a: size = 2 (memory) --- PASS: TestSizeDiffers (0.00s) === RUN TestDirTransferEntry --- PASS: TestDirTransferEntry (0.00s) === RUN TestReOpen === RUN TestReOpen/Normal === RUN TestReOpen/Normal/Basics 2026/09/21 04:45:01 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/ErrorAtStart === RUN TestReOpen/Normal/WithErrors 2026/09/21 04:45:01 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/09/21 04:45:01 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/09/21 04:45:01 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/Normal/TooManyErrors 2026/09/21 04:45:01 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/09/21 04:45:01 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/09/21 04:45:01 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/09/21 04:45:01 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/Normal/ReadAt 2026/09/21 04:45:01 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/09/21 04:45:01 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/09/21 04:45:01 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/Normal/Seek 2026/09/21 04:45:01 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/09/21 04:45:01 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/09/21 04:45:01 DEBUG : potato: Seek from 5 to 2 === RUN TestReOpen/Normal/AccountRead === RUN TestReOpen/Normal/AccountReadDelay 2026/09/21 04:45:01 DEBUG : potato: Seek from 10 to 0 2026/09/21 04:45:01 DEBUG : potato: Seek from 10 to 0 2026/09/21 04:45:01 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/AccountReadError === RUN TestReOpen/WithRangeOption === RUN TestReOpen/WithRangeOption/Basics 2026/09/21 04:45:01 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/ErrorAtStart === RUN TestReOpen/WithRangeOption/WithErrors 2026/09/21 04:45:01 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/09/21 04:45:01 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/09/21 04:45:01 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithRangeOption/TooManyErrors 2026/09/21 04:45:01 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/09/21 04:45:01 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/09/21 04:45:01 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/09/21 04:45:01 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithRangeOption/ReadAt 2026/09/21 04:45:01 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/09/21 04:45:01 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/09/21 04:45:01 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/WithRangeOption/Seek 2026/09/21 04:45:01 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/09/21 04:45:01 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/09/21 04:45:01 DEBUG : potato: Seek from 5 to 2 2026/09/21 04:45:01 DEBUG : potato: Seek from 7 to 4 === RUN TestReOpen/WithRangeOption/AccountRead === RUN TestReOpen/WithRangeOption/AccountReadDelay 2026/09/21 04:45:01 DEBUG : potato: Seek from 7 to 0 2026/09/21 04:45:01 DEBUG : potato: Seek from 7 to 0 2026/09/21 04:45:01 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/AccountReadError === RUN TestReOpen/WithSeekOption === RUN TestReOpen/WithSeekOption/Basics 2026/09/21 04:45:01 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/ErrorAtStart === RUN TestReOpen/WithSeekOption/WithErrors 2026/09/21 04:45:01 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/09/21 04:45:01 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/09/21 04:45:01 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithSeekOption/TooManyErrors 2026/09/21 04:45:01 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/09/21 04:45:01 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/09/21 04:45:01 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/09/21 04:45:01 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithSeekOption/ReadAt 2026/09/21 04:45:01 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/09/21 04:45:01 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/09/21 04:45:01 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/WithSeekOption/Seek 2026/09/21 04:45:01 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/09/21 04:45:01 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/09/21 04:45:01 DEBUG : potato: Seek from 5 to 2 2026/09/21 04:45:01 DEBUG : potato: Seek from 7 to 5 === RUN TestReOpen/WithSeekOption/AccountRead === RUN TestReOpen/WithSeekOption/AccountReadDelay 2026/09/21 04:45:01 DEBUG : potato: Seek from 8 to 0 2026/09/21 04:45:01 DEBUG : potato: Seek from 8 to 0 2026/09/21 04:45:01 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/AccountReadError === RUN TestReOpen/UnknownSize === RUN TestReOpen/UnknownSize/Basics 2026/09/21 04:45:01 DEBUG : potato: Seek from 9 to 0 === RUN TestReOpen/UnknownSize/ErrorAtStart === RUN TestReOpen/UnknownSize/WithErrors 2026/09/21 04:45:01 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/09/21 04:45:01 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/09/21 04:45:01 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/UnknownSize/TooManyErrors 2026/09/21 04:45:01 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/09/21 04:45:01 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/09/21 04:45:01 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/09/21 04:45:01 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/UnknownSize/ReadAt 2026/09/21 04:45:01 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/09/21 04:45:01 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/09/21 04:45:01 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/UnknownSize/Seek 2026/09/21 04:45:01 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/09/21 04:45:01 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/09/21 04:45:01 DEBUG : potato: Seek from 5 to 2 2026/09/21 04:45:01 DEBUG : potato: Seek from 7 to 6 === RUN TestReOpen/UnknownSize/AccountRead === RUN TestReOpen/UnknownSize/AccountReadDelay 2026/09/21 04:45:01 DEBUG : potato: Seek from 9 to 0 2026/09/21 04:45:01 DEBUG : potato: Seek from 9 to 0 2026/09/21 04:45:01 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-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 04:45:03 DEBUG : rutabaga: md5 = ef3b3730d69e2ad7dbb4880c45a549be OK === RUN TestCheck/1 === RUN TestCheck/2 2026/09/21 04:45:05 DEBUG : empty space: md5 = e123efae305c12d567565661be3dca62 OK === RUN TestCheck/3 2026/09/21 04:45:07 DEBUG : potato2: md5 = 52274d6283b3e16a272f021bdc234296 OK === RUN TestCheck/4 === RUN TestCheck/5 2026/09/21 04:45:10 DEBUG : remotepotato: md5 = dd6e398874219d5ef1f624ffd647f907 OK === RUN TestCheck/6 === RUN TestCheck/7 --- PASS: TestCheck (11.83s) --- PASS: TestCheck/1 (0.25s) --- PASS: TestCheck/2 (0.27s) --- PASS: TestCheck/3 (0.27s) --- PASS: TestCheck/4 (0.24s) --- PASS: TestCheck/5 (0.28s) --- PASS: TestCheck/6 (0.25s) --- PASS: TestCheck/7 (0.29s) === RUN TestCheckFsError 2026/09/21 04:45:13 DEBUG : Creating backend with remote "nonexistent" 2026/09/21 04:45:13 DEBUG : Config file has changed externally - reloading 2026/09/21 04:45:13 DEBUG : Creating backend with remote "nonexistent" 2026/09/21 04:45:13 DEBUG : Local file system at nonexistent: Waiting for checks to finish 2026/09/21 04:45:13 ERROR : Local file system at nonexistent: error reading source root directory: directory not found 2026/09/21 04:45:13 NOTICE: Local file system at nonexistent: 0 differences found 2026/09/21 04:45:13 NOTICE: Local file system at nonexistent: 2 errors while checking --- PASS: TestCheckFsError (0.01s) === RUN TestCheckDownload run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 04:45:15 DEBUG : rutabaga: md5 = 3d21fe854e711545d23471e913c4fa92 OK === RUN TestCheckDownload/1 === RUN TestCheckDownload/2 2026/09/21 04:45:18 DEBUG : empty space: md5 = 71854806171d80445eeee6ed93928a41 OK === RUN TestCheckDownload/3 2026/09/21 04:45:20 DEBUG : potato2: md5 = 0c5d4ab6cb4b8050c66c32a1575a4db6 OK === RUN TestCheckDownload/4 === RUN TestCheckDownload/5 2026/09/21 04:45:24 DEBUG : remotepotato: md5 = 5c96c757d556bc3261b9ecb378efb22f OK === RUN TestCheckDownload/6 === RUN TestCheckDownload/7 --- PASS: TestCheckDownload (15.18s) --- PASS: TestCheckDownload/1 (0.92s) --- PASS: TestCheckDownload/2 (0.75s) --- PASS: TestCheckDownload/3 (0.78s) --- PASS: TestCheckDownload/4 (0.91s) --- PASS: TestCheckDownload/5 (0.82s) --- PASS: TestCheckDownload/6 (0.81s) --- PASS: TestCheckDownload/7 (0.82s) === RUN TestCheckSizeOnly run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 04:45:30 DEBUG : rutabaga: md5 = 26332800f43e40c021c6e6a46287c8b9 OK === RUN TestCheckSizeOnly/1 === RUN TestCheckSizeOnly/2 2026/09/21 04:45:32 DEBUG : empty space: md5 = 95cf2af1f2c9fa7d2eb0f43571be4c82 OK === RUN TestCheckSizeOnly/3 2026/09/21 04:45:34 DEBUG : potato2: md5 = 5dd67e3f8ca018b532969e9fe7ca3e14 OK === RUN TestCheckSizeOnly/4 === RUN TestCheckSizeOnly/5 2026/09/21 04:45:36 DEBUG : remotepotato: md5 = 89ed30c0b400e2aae51cf4aeb5b0e7d1 OK === RUN TestCheckSizeOnly/6 === RUN TestCheckSizeOnly/7 --- PASS: TestCheckSizeOnly (11.05s) --- PASS: TestCheckSizeOnly/1 (0.24s) --- PASS: TestCheckSizeOnly/2 (0.27s) --- PASS: TestCheckSizeOnly/3 (0.31s) --- PASS: TestCheckSizeOnly/4 (0.22s) --- PASS: TestCheckSizeOnly/5 (0.25s) --- PASS: TestCheckSizeOnly/6 (0.25s) --- PASS: TestCheckSizeOnly/7 (0.28s) === RUN TestCheckEqualReaders --- PASS: TestCheckEqualReaders (0.00s) === RUN TestParseSumFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 04:45:41 DEBUG : test.sum: md5 = 7f0487790c4190bd5f0700905a5fde35 OK 2026/09/21 04:45:42 NOTICE: test.sum: improperly formatted checksum line 4 2026/09/21 04:45:42 NOTICE: test.sum: improperly formatted checksum line 5 2026/09/21 04:45:42 NOTICE: test.sum: improperly formatted checksum line 6 2026/09/21 04:45:42 NOTICE: test.sum: 2 warning(s) suppressed... 2026/09/21 04:45:43 DEBUG : test.sum: md5 = 8629590a24962adac3bcff6380d0abb5 OK 2026/09/21 04:45:44 NOTICE: test.sum: improperly formatted checksum line 4 2026/09/21 04:45:44 NOTICE: test.sum: improperly formatted checksum line 5 2026/09/21 04:45:44 NOTICE: test.sum: improperly formatted checksum line 6 2026/09/21 04:45:44 NOTICE: test.sum: 2 warning(s) suppressed... --- PASS: TestParseSumFile (5.80s) === RUN TestCheckSum run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 04:45:45 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-tilefol3vuga/data" 2026/09/21 04:45:45 DEBUG : Config file has changed externally - reloading 2026/09/21 04:45:45 DEBUG : Creating backend with remote "TestDrive:crypt/ljsouapbvkh23ogo0it9v08lej41ihf8r2f7fjm8l5ikksfld8m0/vjrnln8ratgmqakfosrqe8espk" check_test.go:354: Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga/data' lacks md5, skipping --- SKIP: TestCheckSum (2.09s) === RUN TestCheckSumDownload run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 04:45:47 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-tilefol3vuga/data" 2026/09/21 04:45:47 DEBUG : Config file has changed externally - reloading 2026/09/21 04:45:47 DEBUG : Creating backend with remote "TestDrive:crypt/ljsouapbvkh23ogo0it9v08lej41ihf8r2f7fjm8l5ikksfld8m0/vjrnln8ratgmqakfosrqe8espk" 2026/09/21 04:45:51 DEBUG : data/banana: md5 = 414042da94525495d18ad966c1e8f6a8 OK 2026/09/21 04:45:52 DEBUG : test.sum: md5 = 1bf24e82b13c2f0e98dc7cd0ce84b017 OK === RUN TestCheckSumDownload/subtest1 2026/09/21 04:45:56 DEBUG : data/potato: md5 = 844387ef5f9670673ac507d7eb6b7250 OK 2026/09/21 04:45:58 DEBUG : test.sum: md5 = 611e68dc504f4120ecbb1947318a05e8 OK === RUN TestCheckSumDownload/subtest2 2026/09/21 04:46:02 DEBUG : test.sum: md5 = b31f366e3a8ea19bb4c1aff9f57135e9 OK === RUN TestCheckSumDownload/subtest3 2026/09/21 04:46:05 DEBUG : test.sum: md5 = 510df971edc086515da0d7d93e04e1a3 OK === RUN TestCheckSumDownload/subtest4 2026/09/21 04:46:09 DEBUG : test.sum: md5 = 83dd9108c2f9aab5299aa55c136c51b4 OK === RUN TestCheckSumDownload/subtest5 2026/09/21 04:46:12 DEBUG : test.sum: md5 = 27c66e5e4b5ae15f5793456d94df0f41 OK === RUN TestCheckSumDownload/subtest6 2026/09/21 04:46:16 DEBUG : data/banana: md5 = 620fbb275e7a79380956243cac9e030c OK 2026/09/21 04:46:17 DEBUG : data/potato: md5 = 0103faf5cae2ad6cedc2eb930dd708f8 OK 2026/09/21 04:46:19 DEBUG : test.sum: md5 = 6c3a2bc53e2da0a4c03d7a4e310a5006 OK === RUN TestCheckSumDownload/subtest7 --- PASS: TestCheckSumDownload (36.54s) --- PASS: TestCheckSumDownload/subtest1 (2.06s) --- PASS: TestCheckSumDownload/subtest2 (1.70s) --- PASS: TestCheckSumDownload/subtest3 (1.81s) --- PASS: TestCheckSumDownload/subtest4 (1.64s) --- PASS: TestCheckSumDownload/subtest5 (1.78s) --- PASS: TestCheckSumDownload/subtest6 (1.63s) --- PASS: TestCheckSumDownload/subtest7 (1.80s) === RUN TestCheckSumConcurrency 2026/09/21 04:46:24 DEBUG : file0: md5 = 022eb0c0085c731f335df970fafa7446 OK 2026/09/21 04:46:24 DEBUG : file2: md5 = a58f09ae3db8ae4b943fdbaace096072 OK 2026/09/21 04:46:24 DEBUG : file1: md5 = 17fbabbccbdea0e6be9eb64c8c6fea41 OK 2026/09/21 04:46:24 DEBUG : file3: md5 = e0639b17e0744aca9a3ba29c049e59ee OK 2026/09/21 04:46:24 NOTICE: Mock file system at checkSum: 0 differences found 2026/09/21 04:46:24 NOTICE: Mock file system at checkSum: 4 matching files --- PASS: TestCheckSumConcurrency (0.00s) === RUN TestCheckSumDownloadConcurrency 2026/09/21 04:46:24 DEBUG : file0: md5 = 022eb0c0085c731f335df970fafa7446 OK 2026/09/21 04:46:24 DEBUG : file2: md5 = a58f09ae3db8ae4b943fdbaace096072 OK 2026/09/21 04:46:24 DEBUG : file3: md5 = e0639b17e0744aca9a3ba29c049e59ee OK 2026/09/21 04:46:24 DEBUG : file1: md5 = 17fbabbccbdea0e6be9eb64c8c6fea41 OK 2026/09/21 04:46:24 NOTICE: Mock file system at checkSum: 0 differences found 2026/09/21 04:46:24 NOTICE: Mock file system at checkSum: 4 matching files --- PASS: TestCheckSumDownloadConcurrency (0.00s) === RUN TestApplyTransforms 2026/09/21 04:46:24 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-polahoj8sida" 2026/09/21 04:46:24 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/21 04:46:24 DEBUG : Creating backend with remote "TestDrive:crypt/0p8qp6lpejaq5juamjmjkj237p3q4241ejou8dfrf68438254qag" 2026/09/21 04:46:25 DEBUG : Creating backend with remote "/tmp/rclone2072558734" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-polahoj8sida'", Local "Local file system at /tmp/rclone2072558734", Modify Window "1ms" 2026/09/21 04:46:27 DEBUG : hello, world!: md5 = 9303b4245c46fd5da4b1ea50cab34abc OK upper checkfile vs. lower remote (without normalization) 2026/09/21 04:46:27 ERROR : hello, world!: sum not found 2026/09/21 04:46:27 ERROR : HELLO, WORLD!: file not in Encrypted drive 'TestCryptDrive:rclone-test-polahoj8sida' 2026/09/21 04:46:27 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-polahoj8sida': 1 files missing 2026/09/21 04:46:27 NOTICE: 1 hashes missing 2026/09/21 04:46:27 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-polahoj8sida': 1 differences found 2026/09/21 04:46:27 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-polahoj8sida': 2 errors while checking upper checkfile vs. lower remote (with normalization) 2026/09/21 04:46:28 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/21 04:46:28 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-polahoj8sida': 0 differences found 2026/09/21 04:46:28 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-polahoj8sida': 1 matching files 2026/09/21 04:46:28 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-xoteduv0zeno" 2026/09/21 04:46:28 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/21 04:46:28 DEBUG : Creating backend with remote "TestDrive:crypt/i26913se60gn5lbn44og98relqmiofk0s6m3l9nnnah6qo0f1ri0" 2026/09/21 04:46:29 DEBUG : Creating backend with remote "/tmp/rclone3218914338" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-xoteduv0zeno'", Local "Local file system at /tmp/rclone3218914338", Modify Window "1ms" 2026/09/21 04:46:31 DEBUG : HELLO, WORLD!: md5 = b7c157a8c9705a3fef1921eaf5883d35 OK lower checkfile vs. upper remote (without normalization) 2026/09/21 04:46:32 ERROR : HELLO, WORLD!: sum not found 2026/09/21 04:46:32 ERROR : hello, world!: file not in Encrypted drive 'TestCryptDrive:rclone-test-xoteduv0zeno' 2026/09/21 04:46:32 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-xoteduv0zeno': 1 files missing 2026/09/21 04:46:32 NOTICE: 1 hashes missing 2026/09/21 04:46:32 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-xoteduv0zeno': 1 differences found 2026/09/21 04:46:32 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-xoteduv0zeno': 2 errors while checking lower checkfile vs. upper remote (with normalization) 2026/09/21 04:46:33 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/21 04:46:33 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-xoteduv0zeno': 0 differences found 2026/09/21 04:46:33 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-xoteduv0zeno': 1 matching files 2026/09/21 04:46:33 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-hipizoh4qasa" 2026/09/21 04:46:33 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/21 04:46:33 DEBUG : Creating backend with remote "TestDrive:crypt/sgp2dq7obo6gd7bf4f13f6q5bchagv5njcinf1iebhb0lb2vcn0g" 2026/09/21 04:46:34 DEBUG : Creating backend with remote "/tmp/rclone3233902364" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hipizoh4qasa'", Local "Local file system at /tmp/rclone3233902364", Modify Window "1ms" 2026/09/21 04:46:36 DEBUG : HeLlO, wOrLd!: md5 = 6b17aff867f0c5aeed59ee90cbc48af1 OK lower checkfile vs. upperlowermixed remote (without normalization) 2026/09/21 04:46:37 ERROR : HeLlO, wOrLd!: sum not found 2026/09/21 04:46:37 ERROR : hello, world!: file not in Encrypted drive 'TestCryptDrive:rclone-test-hipizoh4qasa' 2026/09/21 04:46:37 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-hipizoh4qasa': 1 files missing 2026/09/21 04:46:37 NOTICE: 1 hashes missing 2026/09/21 04:46:37 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-hipizoh4qasa': 1 differences found 2026/09/21 04:46:37 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-hipizoh4qasa': 2 errors while checking lower checkfile vs. upperlowermixed remote (with normalization) 2026/09/21 04:46:38 DEBUG : HeLlO, wOrLd!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/21 04:46:38 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-hipizoh4qasa': 0 differences found 2026/09/21 04:46:38 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-hipizoh4qasa': 1 matching files 2026/09/21 04:46:38 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-zahohah0vure" 2026/09/21 04:46:38 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/21 04:46:38 DEBUG : Creating backend with remote "TestDrive:crypt/avnebgtuq41uv1n8euakp611m09t70fsom43i5oqr8264l2sfgm0" 2026/09/21 04:46:39 DEBUG : Creating backend with remote "/tmp/rclone590910977" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zahohah0vure'", Local "Local file system at /tmp/rclone590910977", Modify Window "1ms" 2026/09/21 04:46:41 DEBUG : HELLO, WORLD!: md5 = ae375cbdc85af155225b55c7b5cd5507 OK upperlowermixed checkfile vs. upper remote (without normalization) 2026/09/21 04:46:41 ERROR : HELLO, WORLD!: sum not found 2026/09/21 04:46:41 ERROR : HeLlO, wOrLd!: file not in Encrypted drive 'TestCryptDrive:rclone-test-zahohah0vure' 2026/09/21 04:46:41 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-zahohah0vure': 1 files missing 2026/09/21 04:46:41 NOTICE: 1 hashes missing 2026/09/21 04:46:41 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-zahohah0vure': 1 differences found 2026/09/21 04:46:41 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-zahohah0vure': 2 errors while checking upperlowermixed checkfile vs. upper remote (with normalization) 2026/09/21 04:46:42 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/21 04:46:42 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-zahohah0vure': 0 differences found 2026/09/21 04:46:42 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-zahohah0vure': 1 matching files 2026/09/21 04:46:42 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-vohuton1kafo" 2026/09/21 04:46:42 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/21 04:46:43 DEBUG : Creating backend with remote "TestDrive:crypt/uv57dvoh7eud62crdq1thhs5e76b6j2b4llukooqrrd5gf694umg" 2026/09/21 04:46:44 DEBUG : Creating backend with remote "/tmp/rclone3163462857" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-vohuton1kafo'", Local "Local file system at /tmp/rclone3163462857", Modify Window "1ms" 2026/09/21 04:46:46 DEBUG : 測試_Русский___ě_áñ: md5 = 784d0c87e494c5c623f820d1701d69c1 OK NFD checkfile vs. NFC remote (without normalization) 2026/09/21 04:46:46 ERROR : 測試_Русский___ě_áñ: sum not found 2026/09/21 04:46:46 ERROR : 測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-vohuton1kafo' 2026/09/21 04:46:46 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-vohuton1kafo': 1 files missing 2026/09/21 04:46:46 NOTICE: 1 hashes missing 2026/09/21 04:46:46 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-vohuton1kafo': 1 differences found 2026/09/21 04:46:46 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-vohuton1kafo': 2 errors while checking NFD checkfile vs. NFC remote (with normalization) 2026/09/21 04:46:47 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/21 04:46:47 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-vohuton1kafo': 0 differences found 2026/09/21 04:46:47 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-vohuton1kafo': 1 matching files 2026/09/21 04:46:47 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-mekigav8buyo" 2026/09/21 04:46:47 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/21 04:46:47 DEBUG : Creating backend with remote "TestDrive:crypt/d41qf1t9iqv04a01si7lnr4d09jcrktbqa3kqa9knrgqo63npre0" 2026/09/21 04:46:48 DEBUG : Creating backend with remote "/tmp/rclone3048727549" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mekigav8buyo'", Local "Local file system at /tmp/rclone3048727549", Modify Window "1ms" 2026/09/21 04:46:50 DEBUG : 測試_Русский___ě_áñ: md5 = f73f21d61a4f2a3b1b26f695b5d7d33b OK NFC checkfile vs. NFD remote (without normalization) 2026/09/21 04:46:51 ERROR : 測試_Русский___ě_áñ: sum not found 2026/09/21 04:46:51 ERROR : 測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-mekigav8buyo' 2026/09/21 04:46:51 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-mekigav8buyo': 1 files missing 2026/09/21 04:46:51 NOTICE: 1 hashes missing 2026/09/21 04:46:51 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-mekigav8buyo': 1 differences found 2026/09/21 04:46:51 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-mekigav8buyo': 2 errors while checking NFC checkfile vs. NFD remote (with normalization) 2026/09/21 04:46:52 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/21 04:46:52 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-mekigav8buyo': 0 differences found 2026/09/21 04:46:52 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-mekigav8buyo': 1 matching files 2026/09/21 04:46:52 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-duxuzek3gici" 2026/09/21 04:46:52 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/21 04:46:52 DEBUG : Creating backend with remote "TestDrive:crypt/devtusaf09rp7pnjfltobdqve0gu1nlvn2e8bki0efke17eqio00" 2026/09/21 04:46:53 DEBUG : Creating backend with remote "/tmp/rclone3955284154" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-duxuzek3gici'", Local "Local file system at /tmp/rclone3955284154", Modify Window "1ms" 2026/09/21 04:46:55 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 91a5a3c14c8de4e934916ac306acd46e OK NFDx2 checkfile vs. both remote (without normalization) 2026/09/21 04:46:56 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/09/21 04:46:56 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-duxuzek3gici' 2026/09/21 04:46:56 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-duxuzek3gici': 1 files missing 2026/09/21 04:46:56 NOTICE: 1 hashes missing 2026/09/21 04:46:56 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-duxuzek3gici': 1 differences found 2026/09/21 04:46:56 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-duxuzek3gici': 2 errors while checking NFDx2 checkfile vs. both remote (with normalization) 2026/09/21 04:46:57 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/21 04:46:57 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-duxuzek3gici': 0 differences found 2026/09/21 04:46:57 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-duxuzek3gici': 1 matching files 2026/09/21 04:46:57 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jujageq1feza" 2026/09/21 04:46:57 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/21 04:46:57 DEBUG : Creating backend with remote "TestDrive:crypt/rhak2nkcjmbhkasm7mp8i1fmclokls2o879cq79pvns8939nquc0" 2026/09/21 04:46:58 DEBUG : Creating backend with remote "/tmp/rclone1680410473" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jujageq1feza'", Local "Local file system at /tmp/rclone1680410473", Modify Window "1ms" 2026/09/21 04:47:00 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 1309913f80e71da6e36ba9fd2cb7ccf0 OK NFCx2 checkfile vs. both remote (without normalization) 2026/09/21 04:47:00 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/09/21 04:47:00 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-jujageq1feza' 2026/09/21 04:47:00 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-jujageq1feza': 1 files missing 2026/09/21 04:47:00 NOTICE: 1 hashes missing 2026/09/21 04:47:00 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-jujageq1feza': 1 differences found 2026/09/21 04:47:00 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-jujageq1feza': 2 errors while checking NFCx2 checkfile vs. both remote (with normalization) 2026/09/21 04:47:01 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/21 04:47:01 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-jujageq1feza': 0 differences found 2026/09/21 04:47:01 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-jujageq1feza': 1 matching files 2026/09/21 04:47:01 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-bimowit7rava" 2026/09/21 04:47:01 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/21 04:47:01 DEBUG : Creating backend with remote "TestDrive:crypt/4oanq5to0tgfqu36q7vgq7aoqrrjh317uggr4v8trtvjh9u0a6fg" 2026/09/21 04:47:02 DEBUG : Creating backend with remote "/tmp/rclone690247390" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-bimowit7rava'", Local "Local file system at /tmp/rclone690247390", Modify Window "1ms" 2026/09/21 04:47:04 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 56e78025cbbf5fa6d38b98bd15af7c2c OK both checkfile vs. NFDx2 remote (without normalization) 2026/09/21 04:47:05 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/09/21 04:47:05 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-bimowit7rava' 2026/09/21 04:47:05 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-bimowit7rava': 1 files missing 2026/09/21 04:47:05 NOTICE: 1 hashes missing 2026/09/21 04:47:05 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-bimowit7rava': 1 differences found 2026/09/21 04:47:05 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-bimowit7rava': 2 errors while checking both checkfile vs. NFDx2 remote (with normalization) 2026/09/21 04:47:06 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/21 04:47:06 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-bimowit7rava': 0 differences found 2026/09/21 04:47:06 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-bimowit7rava': 1 matching files 2026/09/21 04:47:06 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-pixadaj7lafe" 2026/09/21 04:47:06 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/21 04:47:06 DEBUG : Creating backend with remote "TestDrive:crypt/4v1lu45kk1cqvp3pcqrat0r3u9p496cf73mv9qar49jmcpl0v2q0" 2026/09/21 04:47:07 DEBUG : Creating backend with remote "/tmp/rclone34659085" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-pixadaj7lafe'", Local "Local file system at /tmp/rclone34659085", Modify Window "1ms" 2026/09/21 04:47:09 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 323ae8ab3873354af641e74ea2a3e1a7 OK both checkfile vs. NFCx2 remote (without normalization) 2026/09/21 04:47:09 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/09/21 04:47:09 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-pixadaj7lafe' 2026/09/21 04:47:09 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-pixadaj7lafe': 1 files missing 2026/09/21 04:47:09 NOTICE: 1 hashes missing 2026/09/21 04:47:09 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-pixadaj7lafe': 1 differences found 2026/09/21 04:47:09 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-pixadaj7lafe': 2 errors while checking both checkfile vs. NFCx2 remote (with normalization) 2026/09/21 04:47:10 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/21 04:47:10 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-pixadaj7lafe': 0 differences found 2026/09/21 04:47:10 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-pixadaj7lafe': 1 matching files 2026/09/21 04:47:10 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-pixadaj7lafe': Purge remote 2026/09/21 04:47:11 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-bimowit7rava': Purge remote 2026/09/21 04:47:11 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jujageq1feza': Purge remote 2026/09/21 04:47:12 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-duxuzek3gici': Purge remote 2026/09/21 04:47:12 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-mekigav8buyo': Purge remote 2026/09/21 04:47:12 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-vohuton1kafo': Purge remote 2026/09/21 04:47:13 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zahohah0vure': Purge remote 2026/09/21 04:47:13 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-hipizoh4qasa': Purge remote 2026/09/21 04:47:14 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-xoteduv0zeno': Purge remote 2026/09/21 04:47:14 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-polahoj8sida': Purge remote --- PASS: TestApplyTransforms (50.75s) === RUN TestTruncateString --- PASS: TestTruncateString (0.00s) === RUN TestCopyFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 04:47:15 DEBUG : file1: Need to transfer - File not found at Destination 2026/09/21 04:47:17 DEBUG : sub/file2: md5 = 1523cf08304fe281b457a09e308f8a78 OK 2026/09/21 04:47:17 DEBUG : sub/file2: size = 14 OK 2026/09/21 04:47:17 INFO : file1: Copied (new) to: sub/file2 2026/09/21 04:47:18 DEBUG : sub/file2: size = 14 OK 2026/09/21 04:47:18 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/21 04:47:18 DEBUG : file1: Unchanged skipping 2026/09/21 04:47:18 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga': don't need to copy/move sub/file2, it is already at target location --- PASS: TestCopyFile (6.01s) === RUN TestCopyLongFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" copy_test.go:154: Test only runs on local --- SKIP: TestCopyLongFile (0.48s) === RUN TestCopyFileBackupDir run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 04:47:23 DEBUG : dst/file1: md5 = 9f415651ab6e3afc27221fc640e3810d OK 2026/09/21 04:47:24 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-tilefol3vuga/backup" 2026/09/21 04:47:24 DEBUG : Config file has changed externally - reloading 2026/09/21 04:47:24 DEBUG : Creating backend with remote "TestDrive:crypt/ljsouapbvkh23ogo0it9v08lej41ihf8r2f7fjm8l5ikksfld8m0/1nrff024r7pq65ecp72fc28jb0" 2026/09/21 04:47:26 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone1633621919) 2026/09/21 04:47:26 DEBUG : dst/file1: size = 18 (Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga') 2026/09/21 04:47:26 DEBUG : dst/file1: Sizes differ 2026/09/21 04:47:28 INFO : dst/file1: Moved (server-side) 2026/09/21 04:47:29 DEBUG : dst/file1: md5 = 4f7fd67ba29465f2349b3e26e46b14b8 OK 2026/09/21 04:47:29 DEBUG : dst/file1: size = 14 OK 2026/09/21 04:47:29 INFO : dst/file1: Copied (new) --- PASS: TestCopyFileBackupDir (12.74s) === RUN TestCopyFileCompareDest run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 04:47:34 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-tilefol3vuga/dst" 2026/09/21 04:47:34 DEBUG : Config file has changed externally - reloading 2026/09/21 04:47:34 DEBUG : Creating backend with remote "TestDrive:crypt/ljsouapbvkh23ogo0it9v08lej41ihf8r2f7fjm8l5ikksfld8m0/31u3jie661vd5p8j7rtc3hgbh0" 2026/09/21 04:47:35 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-tilefol3vuga/CompareDest" 2026/09/21 04:47:36 DEBUG : Creating backend with remote "TestDrive:crypt/ljsouapbvkh23ogo0it9v08lej41ihf8r2f7fjm8l5ikksfld8m0/gveqi14airsml4bgu7krj116o8" 2026/09/21 04:47:37 DEBUG : one: Need to transfer - File not found at Destination 2026/09/21 04:47:39 DEBUG : one: md5 = 613a907b3922a109121a9666007ae1b2 OK 2026/09/21 04:47:39 DEBUG : one: size = 3 OK 2026/09/21 04:47:39 INFO : one: Copied (new) 2026/09/21 04:47:40 DEBUG : one: size = 5 (Local file system at /tmp/rclone1633621919) 2026/09/21 04:47:40 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga/dst') 2026/09/21 04:47:40 DEBUG : one: Sizes differ 2026/09/21 04:47:42 DEBUG : one: md5 = 3c14dd3d4d782bfe5f85f36f0db87ed4 OK 2026/09/21 04:47:42 DEBUG : one: size = 5 OK 2026/09/21 04:47:42 INFO : one: Copied (replaced existing) 2026/09/21 04:47:45 DEBUG : dst/one: md5 = c8497e3912c302ee8d0c44a85d5d445e OK 2026/09/21 04:47:47 DEBUG : CompareDest/one: md5 = 9eb499c691a47fd9fea567228562813c OK 2026/09/21 04:47:47 DEBUG : one: size = 5 (Local file system at /tmp/rclone1633621919) 2026/09/21 04:47:47 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga/dst') 2026/09/21 04:47:47 DEBUG : one: Sizes differ 2026/09/21 04:47:48 DEBUG : one: size = 5 OK 2026/09/21 04:47:48 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/09/21 04:47:48 DEBUG : one: Destination found in --compare-dest, skipping 2026/09/21 04:47:50 DEBUG : CompareDest/two: md5 = 1153d76ae7f11c717242d027f525d37e OK 2026/09/21 04:47:51 DEBUG : two: Need to transfer - File not found at Destination 2026/09/21 04:47:51 DEBUG : two: size = 3 OK 2026/09/21 04:47:51 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/09/21 04:47:51 DEBUG : two: Destination found in --compare-dest, skipping 2026/09/21 04:47:52 DEBUG : two: Need to transfer - File not found at Destination 2026/09/21 04:47:52 DEBUG : two: size = 3 OK 2026/09/21 04:47:52 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/09/21 04:47:52 DEBUG : two: Destination found in --compare-dest, skipping 2026/09/21 04:47:53 DEBUG : two: Need to transfer - File not found at Destination 2026/09/21 04:47:53 DEBUG : two: size = 5 (Local file system at /tmp/rclone1633621919) 2026/09/21 04:47:53 DEBUG : two: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga/CompareDest') 2026/09/21 04:47:53 DEBUG : two: Sizes differ 2026/09/21 04:47:55 DEBUG : two: md5 = 8731f0e2af447b670a8b38f0892e3f0c OK 2026/09/21 04:47:55 DEBUG : two: size = 5 OK 2026/09/21 04:47:55 INFO : two: Copied (new) --- PASS: TestCopyFileCompareDest (25.35s) === RUN TestCopyFileCopyDest run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 04:47:59 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-tilefol3vuga/dst" 2026/09/21 04:47:59 DEBUG : Config file has changed externally - reloading 2026/09/21 04:47:59 DEBUG : Creating backend with remote "TestDrive:crypt/ljsouapbvkh23ogo0it9v08lej41ihf8r2f7fjm8l5ikksfld8m0/31u3jie661vd5p8j7rtc3hgbh0" 2026/09/21 04:48:01 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-tilefol3vuga/CopyDest" 2026/09/21 04:48:01 DEBUG : Creating backend with remote "TestDrive:crypt/ljsouapbvkh23ogo0it9v08lej41ihf8r2f7fjm8l5ikksfld8m0/d09o6po3f7bm6ce32vdgs8h9ls" 2026/09/21 04:48:02 DEBUG : one: Need to transfer - File not found at Destination 2026/09/21 04:48:05 DEBUG : one: md5 = 9e38086951367b9b3e3113638351c3d2 OK 2026/09/21 04:48:05 DEBUG : one: size = 3 OK 2026/09/21 04:48:05 INFO : one: Copied (new) 2026/09/21 04:48:06 DEBUG : one: size = 5 (Local file system at /tmp/rclone1633621919) 2026/09/21 04:48:06 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga/dst') 2026/09/21 04:48:06 DEBUG : one: Sizes differ 2026/09/21 04:48:07 DEBUG : one: md5 = 94e98e759a130c7427f5fdafd8017457 OK 2026/09/21 04:48:07 DEBUG : one: size = 5 OK 2026/09/21 04:48:07 INFO : one: Copied (replaced existing) 2026/09/21 04:48:09 DEBUG : dst/one: md5 = 712abf4b8aac10ac9d985cd62c137fe7 OK 2026/09/21 04:48:12 DEBUG : CopyDest/one: md5 = bde3638e3a373150f0d8a5f069a78ef5 OK 2026/09/21 04:48:12 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-tilefol3vuga/BackupDir" 2026/09/21 04:48:12 DEBUG : Config file has changed externally - reloading 2026/09/21 04:48:12 DEBUG : Creating backend with remote "TestDrive:crypt/ljsouapbvkh23ogo0it9v08lej41ihf8r2f7fjm8l5ikksfld8m0/s6dbk3lfi7c9kfvo6j7bla9m0g" 2026/09/21 04:48:14 DEBUG : one: size = 5 (Local file system at /tmp/rclone1633621919) 2026/09/21 04:48:14 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga/dst') 2026/09/21 04:48:14 DEBUG : one: Sizes differ 2026/09/21 04:48:14 DEBUG : one: size = 5 OK 2026/09/21 04:48:14 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/09/21 04:48:14 DEBUG : one: size = 5 (Local file system at /tmp/rclone1633621919) 2026/09/21 04:48:14 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga/dst') 2026/09/21 04:48:14 DEBUG : one: Sizes differ 2026/09/21 04:48:16 INFO : one: Moved (server-side) 2026/09/21 04:48:17 DEBUG : one: size = 5 OK 2026/09/21 04:48:17 INFO : one: Copied (server-side copy) 2026/09/21 04:48:17 DEBUG : one: Destination found in --copy-dest, using server-side copy 2026/09/21 04:48:19 DEBUG : CopyDest/two: md5 = a04fe5f4b579a32152b8d79cd2259f73 OK 2026/09/21 04:48:20 DEBUG : two: Need to transfer - File not found at Destination 2026/09/21 04:48:20 DEBUG : two: size = 3 OK 2026/09/21 04:48:20 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/09/21 04:48:21 DEBUG : two: size = 3 OK 2026/09/21 04:48:21 INFO : two: Copied (server-side copy) 2026/09/21 04:48:21 DEBUG : two: Destination found in --copy-dest, using server-side copy 2026/09/21 04:48:22 DEBUG : two: size = 3 OK 2026/09/21 04:48:22 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/09/21 04:48:22 DEBUG : two: Unchanged skipping 2026/09/21 04:48:24 DEBUG : CopyDest/three: md5 = e002342b2d7f11f4ccac13aa957f4509 OK 2026/09/21 04:48:25 DEBUG : three: Need to transfer - File not found at Destination 2026/09/21 04:48:25 DEBUG : three: size = 7 (Local file system at /tmp/rclone1633621919) 2026/09/21 04:48:25 DEBUG : three: size = 5 (Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga/CopyDest') 2026/09/21 04:48:25 DEBUG : three: Sizes differ 2026/09/21 04:48:25 DEBUG : three: Destination not found in --copy-dest 2026/09/21 04:48:27 DEBUG : three: md5 = e648ef3c2960d34c9af7669159c1e47a OK 2026/09/21 04:48:27 DEBUG : three: size = 7 OK 2026/09/21 04:48:27 INFO : three: Copied (new) --- PASS: TestCopyFileCopyDest (33.88s) === RUN TestCopyInplace run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" copy_test.go:371: Partial uploads not supported --- SKIP: TestCopyInplace (0.49s) === RUN TestCopyLongFileName run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" copy_test.go:404: Partial uploads not supported --- SKIP: TestCopyLongFileName (0.44s) === RUN TestCopyLongFileNameCollision run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" copy_test.go:437: Partial uploads not supported --- SKIP: TestCopyLongFileNameCollision (0.47s) === RUN TestCopyFileMaxTransfer run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 04:48:35 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination 2026/09/21 04:48:37 DEBUG : TestCopyFileMaxTransfer/file1: md5 = d378ef103eed30b73827cab2e742dd7f OK 2026/09/21 04:48:37 DEBUG : TestCopyFileMaxTransfer/file1: size = 14 OK 2026/09/21 04:48:37 INFO : TestCopyFileMaxTransfer/file1: Copied (new) 2026/09/21 04:48:37 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination 2026/09/21 04:48:38 ERROR : TestCopyFileMaxTransfer/file2: Failed to copy: Post "https://www.googleapis.com/upload/drive/v3/files?alt=json&fields=id%2Cname%2Csize%2Cmd5Checksum%2Csha1Checksum%2Csha256Checksum%2Ctrashed%2CexplicitlyTrashed%2CmodifiedTime%2CcreatedTime%2CmimeType%2Cparents%2CwebViewLink%2CshortcutDetails%2CexportLinks%2CresourceKey&keepRevisionForever=false&prettyPrint=false&supportsAllDrives=true&uploadType=multipart": googleapi: Copy failed: max transfer limit reached as set by --max-transfer copy_test.go:500: Expecting error to contain accounting.ErrorMaxTransferLimitReachedFatal 2026/09/21 04:48:38 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination 2026/09/21 04:48:39 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination 2026/09/21 04:48:40 DEBUG : TestCopyFileMaxTransfer/file4: md5 = aa815aed989360f12664b90d1ebe202b OK 2026/09/21 04:48:40 DEBUG : TestCopyFileMaxTransfer/file4: size = 2062 OK 2026/09/21 04:48:40 INFO : TestCopyFileMaxTransfer/file4: Copied (new) --- PASS: TestCopyFileMaxTransfer (9.43s) === RUN TestDeduplicateInteractive run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" dedupe_test.go:37: Can't run this test without a hash --- SKIP: TestDeduplicateInteractive (0.54s) === RUN TestDeduplicateSkip run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 04:48:47 INFO : Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga': Looking for duplicate names using skip mode. 2026/09/21 04:48:48 NOTICE: one: Found 2 files with duplicate names 2026/09/21 04:48:48 NOTICE: one: Skipping 2 files with duplicate names --- PASS: TestDeduplicateSkip (4.83s) === RUN TestDeduplicateSizeOnly run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 04:48:53 INFO : Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga': Looking for duplicate names using skip mode. 2026/09/21 04:48:54 NOTICE: one: Found 3 files with duplicate names 2026/09/21 04:48:54 NOTICE: one: Deleting 1/2 identical duplicates (size 11) 2026/09/21 04:48:54 INFO : one: Deleted 2026/09/21 04:48:54 NOTICE: one: Skipping 2 files with duplicate names --- PASS: TestDeduplicateSizeOnly (7.04s) === RUN TestDeduplicateFirst run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 04:49:00 INFO : Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga': Looking for duplicate names using first mode. 2026/09/21 04:49:00 NOTICE: one: Found 3 files with duplicate names 2026/09/21 04:49:01 INFO : one: Deleted 2026/09/21 04:49:01 INFO : one: Deleted 2026/09/21 04:49:01 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateFirst (6.25s) === RUN TestDeduplicateNewest run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 04:49:06 INFO : Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga': Looking for duplicate names using newest mode. 2026/09/21 04:49:07 NOTICE: one: Found 3 files with duplicate names 2026/09/21 04:49:07 INFO : one: Deleted 2026/09/21 04:49:07 INFO : one: Deleted 2026/09/21 04:49:07 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateNewest (6.03s) === RUN TestDeduplicateNewestByHash run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", 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-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 04:49:13 INFO : Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga': Looking for duplicate names using oldest mode. 2026/09/21 04:49:13 NOTICE: one: Found 3 files with duplicate names 2026/09/21 04:49:13 INFO : one: Deleted 2026/09/21 04:49:14 INFO : one: Deleted 2026/09/21 04:49:14 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateOldest (6.28s) === RUN TestDeduplicateLargest run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 04:49:19 INFO : Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga': Looking for duplicate names using largest mode. 2026/09/21 04:49:19 NOTICE: one: Found 3 files with duplicate names 2026/09/21 04:49:20 INFO : one: Deleted 2026/09/21 04:49:20 INFO : one: Deleted 2026/09/21 04:49:20 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateLargest (6.39s) === RUN TestDeduplicateSmallest run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 04:49:25 INFO : Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga': Looking for duplicate names using smallest mode. 2026/09/21 04:49:26 NOTICE: one: Found 3 files with duplicate names 2026/09/21 04:49:27 INFO : one: Deleted 2026/09/21 04:49:27 INFO : one: Deleted 2026/09/21 04:49:27 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateSmallest (6.67s) === RUN TestDeduplicateRename run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 04:49:33 INFO : Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga': Looking for duplicate names using rename mode. 2026/09/21 04:49:34 NOTICE: one.txt: Found 3 files with duplicate names 2026/09/21 04:49:34 INFO : one-2.txt: renamed from: one.txt 2026/09/21 04:49:35 INFO : one-3.txt: renamed from: one.txt 2026/09/21 04:49:36 INFO : one-4.txt: renamed from: one.txt --- PASS: TestDeduplicateRename (9.91s) === RUN TestDeduplicateRenameManyExisting run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 04:49:40 DEBUG : one-1.txt: md5 = 8470fad1b007b644992c54ef44c06a1f OK 2026/09/21 04:49:41 DEBUG : one-2.txt: md5 = b5677ae270bcd16163b2cb8b3fcd1f6d OK 2026/09/21 04:49:43 DEBUG : one-3.txt: md5 = 8f3a65a4427d34b98ef29b1ab20d3847 OK 2026/09/21 04:49:44 DEBUG : one-4.txt: md5 = 5e5916df97e35c5db6dea00aa9e4dd65 OK 2026/09/21 04:49:46 DEBUG : one-5.txt: md5 = f706ebf90740d2d2da3697e1958bb258 OK 2026/09/21 04:49:47 DEBUG : one-6.txt: md5 = abd5a8c52045b62489155bd1c636c588 OK 2026/09/21 04:49:48 DEBUG : one-7.txt: md5 = d3fd392ec26dccebd6953b2f7367efba OK 2026/09/21 04:49:50 DEBUG : one-8.txt: md5 = f40dd1103ee0bfc8b0933c8a6e08623f OK 2026/09/21 04:49:51 DEBUG : one-9.txt: md5 = de0b96d45b1a53877140076dafa831ed OK 2026/09/21 04:49:53 DEBUG : one-10.txt: md5 = f3f0d89abd37341ebd84dad0081fd662 OK 2026/09/21 04:49:54 DEBUG : one-11.txt: md5 = 0ee83fb04ef394cae3b38b9f92d83b40 OK 2026/09/21 04:49:56 DEBUG : one-12.txt: md5 = d4e1a108fdd9ef8518bef399fd716028 OK 2026/09/21 04:49:58 DEBUG : one-13.txt: md5 = 807b94bf4686c89db63c6a52d38956a9 OK 2026/09/21 04:49:59 DEBUG : one-14.txt: md5 = 7b9e00311d9e773be6081c82e5b0f537 OK 2026/09/21 04:50:01 DEBUG : one-15.txt: md5 = d198eccb5417bd6e08ca17683e1d2b69 OK 2026/09/21 04:50:02 DEBUG : one-16.txt: md5 = cce3ae839c5ba170c0027e41ac59cf3c OK 2026/09/21 04:50:03 DEBUG : one-17.txt: md5 = 989092d5108c39f0f8909bb411b0e6a3 OK 2026/09/21 04:50:05 DEBUG : one-18.txt: md5 = f323d483e697a9f88a1a21c20c441cee OK 2026/09/21 04:50:06 DEBUG : one-19.txt: md5 = ab3548948c17585397d83a046f5c3208 OK 2026/09/21 04:50:07 DEBUG : one-20.txt: md5 = 1018a46bf5fe97d19c2c906eba9603c8 OK 2026/09/21 04:50:09 DEBUG : one-21.txt: md5 = 8151dc6589dbb111a181ced512fa166f OK 2026/09/21 04:50:10 DEBUG : one-22.txt: md5 = 7b447e79d9fc3d15d07e64402d638afc OK 2026/09/21 04:50:12 DEBUG : one-23.txt: md5 = 31ee161c92684cc1680092422240c4f6 OK 2026/09/21 04:50:13 DEBUG : one-24.txt: md5 = 9e08ea2bdc0a7f2f0e31503114866c7b OK 2026/09/21 04:50:14 DEBUG : one-25.txt: md5 = 0e49e26ee008e7e3ae87cabbb1482f0f OK 2026/09/21 04:50:16 DEBUG : one-26.txt: md5 = 82877ca62332b395767b6466b0adb67a OK 2026/09/21 04:50:17 DEBUG : one-27.txt: md5 = 32786f9dac03f30518ab8862817a1a4f OK 2026/09/21 04:50:19 DEBUG : one-28.txt: md5 = 6b225774cac109f54df821b0b7161c57 OK 2026/09/21 04:50:20 DEBUG : one-29.txt: md5 = eb975ed08d36a82e4cb6405516a67f05 OK 2026/09/21 04:50:22 DEBUG : one-30.txt: md5 = 18d24a27db0a9c8caa7a4308f5ce357f OK 2026/09/21 04:50:23 DEBUG : one-31.txt: md5 = 2c9d551f04f3bb56853a1403c4d83083 OK 2026/09/21 04:50:25 DEBUG : one-32.txt: md5 = 30eadb0c9e59c40ec353d6862206249b OK 2026/09/21 04:50:26 DEBUG : one-33.txt: md5 = 0c44478b5008228332f1b0ef334e555c OK 2026/09/21 04:50:28 DEBUG : one-34.txt: md5 = e61cc0c841cbc7887fe6837c5c73fa84 OK 2026/09/21 04:50:29 DEBUG : one-35.txt: md5 = d6ea07199442db74e72d1ca4a1f17bb5 OK 2026/09/21 04:50:30 DEBUG : one-36.txt: md5 = cb9234e4935382b81dfa2a0c7ddffb73 OK 2026/09/21 04:50:32 DEBUG : one-37.txt: md5 = 151181227ae70b8bf87d7a5cf9b8d4b3 OK 2026/09/21 04:50:33 DEBUG : one-38.txt: md5 = 6f4b8570a8d7764da7003d7e1f09297d OK 2026/09/21 04:50:35 DEBUG : one-39.txt: md5 = b68b05ee1ab52ace4f419eddd613e5f3 OK 2026/09/21 04:50:36 DEBUG : one-40.txt: md5 = 5ada841c25dd6beec104485d8651ba9c OK 2026/09/21 04:50:38 DEBUG : one-41.txt: md5 = d510ce906eb405b523f0582b9fc87f1c OK 2026/09/21 04:50:39 DEBUG : one-42.txt: md5 = 8e6d204c5aba07fa0d319641dbe0af57 OK 2026/09/21 04:50:40 DEBUG : one-43.txt: md5 = 16c2fb7b8cf15f5bd062637e3f3ad931 OK 2026/09/21 04:50:42 DEBUG : one-44.txt: md5 = 13d1ebcb21ee805b893483c95cdfc5e1 OK 2026/09/21 04:50:43 DEBUG : one-45.txt: md5 = 68310fe25a1f5e87ad1cf381afca1644 OK 2026/09/21 04:50:45 DEBUG : one-46.txt: md5 = 093c645a1963ac977ad5cbf56b011318 OK 2026/09/21 04:50:46 DEBUG : one-47.txt: md5 = 09e3f50698008e12c4ea09d99ba77349 OK 2026/09/21 04:50:48 DEBUG : one-48.txt: md5 = 81451c93398223941dad7438db234638 OK 2026/09/21 04:50:49 DEBUG : one-49.txt: md5 = c08e934fd84779d0a0444b15b46a035b OK 2026/09/21 04:50:51 DEBUG : one-50.txt: md5 = 3d99b9b1916e8b476b5a2676063937e0 OK 2026/09/21 04:50:52 DEBUG : one-51.txt: md5 = e7006434ef723caf789627baab74eb6a OK 2026/09/21 04:50:54 DEBUG : one-52.txt: md5 = c8caa0192ccd124619327b7860b70607 OK 2026/09/21 04:50:55 DEBUG : one-53.txt: md5 = 78e062de857aca9f53c3e42d3e197f0f OK 2026/09/21 04:50:56 DEBUG : one-54.txt: md5 = 955fdee5e6897e145542383ce17cbe58 OK 2026/09/21 04:50:58 DEBUG : one-55.txt: md5 = 46a5ec3e2449f4ce069096232b5b7e48 OK 2026/09/21 04:50:59 DEBUG : one-56.txt: md5 = bb288d81eaf5fdbcfe7567ba527913a0 OK 2026/09/21 04:51:01 DEBUG : one-57.txt: md5 = 325e88c585ab9c76db90a3c7936448d5 OK 2026/09/21 04:51:02 DEBUG : one-58.txt: md5 = b2638f3930b6680c67cf600527e245b4 OK 2026/09/21 04:51:04 DEBUG : one-59.txt: md5 = ae06a5efec1cc91c86325702e63422d9 OK 2026/09/21 04:51:05 DEBUG : one-60.txt: md5 = eaaa6afdabbb6ae26056fdab0511d91a OK 2026/09/21 04:51:07 DEBUG : one-61.txt: md5 = 2035b2dc2131d558f78ec080cd3bb236 OK 2026/09/21 04:51:08 DEBUG : one-62.txt: md5 = 16814ff7ffa0188c4e73876c1b90c143 OK 2026/09/21 04:51:10 DEBUG : one-63.txt: md5 = 3b12fdef96a88b767a5f7ed1a2463258 OK 2026/09/21 04:51:11 DEBUG : one-64.txt: md5 = 91ae362a84a40a620a6be69aa7e5331f OK 2026/09/21 04:51:13 DEBUG : one-65.txt: md5 = 56df9080d8186d59ebeedce900f77c73 OK 2026/09/21 04:51:14 DEBUG : one-66.txt: md5 = 2288271815a822ef654f895a46aa7297 OK 2026/09/21 04:51:16 DEBUG : one-67.txt: md5 = b1f2d4bd33286a1460ac42a347fbcd75 OK 2026/09/21 04:51:17 DEBUG : one-68.txt: md5 = f7cf0be29dd3f7b6d3a82d64ed910dfe OK 2026/09/21 04:51:19 DEBUG : one-69.txt: md5 = cbed3f872e5ad0d5671295e7c7e8bcf4 OK 2026/09/21 04:51:20 DEBUG : one-70.txt: md5 = 054679dc39345b31ef37ba137541491e OK 2026/09/21 04:51:22 DEBUG : one-71.txt: md5 = e65c1c767bc7e15a67624d14cc2f7552 OK 2026/09/21 04:51:23 DEBUG : one-72.txt: md5 = 0dd6bff3aa9b0207c617ac7e51dd3801 OK 2026/09/21 04:51:24 DEBUG : one-73.txt: md5 = 739e3d087a0b8fc8af1ba4b1d26f6a0c OK 2026/09/21 04:51:26 DEBUG : one-74.txt: md5 = da993b77893844f8a64ab112f4b16e0a OK 2026/09/21 04:51:27 DEBUG : one-75.txt: md5 = 44f853de20c0f1205a299468d2af7a70 OK 2026/09/21 04:51:29 DEBUG : one-76.txt: md5 = f5481366a7e8ffa13fc96fe848add17b OK 2026/09/21 04:51:30 DEBUG : one-77.txt: md5 = 6d2ad8928b024849ac7f09baac47c0cf OK 2026/09/21 04:51:32 DEBUG : one-78.txt: md5 = 8cba61fff38ed3dba0e158046d56dc43 OK 2026/09/21 04:51:33 DEBUG : one-79.txt: md5 = 27b510a489466264f7930dd91b38c27b OK 2026/09/21 04:51:34 DEBUG : one-80.txt: md5 = 5ed9056a0ab230225d46d3eacb6df063 OK 2026/09/21 04:51:36 DEBUG : one-81.txt: md5 = b47c952bcf7a65acc4155890cba8ced1 OK 2026/09/21 04:51:38 DEBUG : one-82.txt: md5 = a608035d35e30b109754fe822e7fd8be OK 2026/09/21 04:51:39 DEBUG : one-83.txt: md5 = 14a53a66c1a4a7f851967411b2eeda9e OK 2026/09/21 04:51:41 DEBUG : one-84.txt: md5 = 80707e15c332cf06eac6ac57e78ce49f OK 2026/09/21 04:51:42 DEBUG : one-85.txt: md5 = 5bb2e9aad53028b5ad1f4c3fe6ea76a0 OK 2026/09/21 04:51:43 DEBUG : one-86.txt: md5 = 1c9641eda14d3a743080971d4fd7f806 OK 2026/09/21 04:51:45 DEBUG : one-87.txt: md5 = 625b24bb6facf6d8689d449f7086fa16 OK 2026/09/21 04:51:46 DEBUG : one-88.txt: md5 = e297de36ae8030460fc9a98eb2077398 OK 2026/09/21 04:51:48 DEBUG : one-89.txt: md5 = c83e6a396aa04795ed9b2d262577d381 OK 2026/09/21 04:51:49 DEBUG : one-90.txt: md5 = 19be8544094fa469d93738dcc861a507 OK 2026/09/21 04:51:51 DEBUG : one-91.txt: md5 = c137a342f3e743dd1a8d9625bea18015 OK 2026/09/21 04:51:52 DEBUG : one-92.txt: md5 = 18033a15dcf032f666f258d836fef13d OK 2026/09/21 04:51:53 DEBUG : one-93.txt: md5 = dc5d055f711b0ab4f7ea0d6539a2fea6 OK 2026/09/21 04:51:55 DEBUG : one-94.txt: md5 = ed54a93a67f871f8995ee81cd890f37a OK 2026/09/21 04:51:56 DEBUG : one-95.txt: md5 = c6b7309dc32c8a8883a1c850583e2842 OK 2026/09/21 04:51:58 DEBUG : one-96.txt: md5 = 5873fe213689ca7fb41f31093dbb26e0 OK 2026/09/21 04:51:59 DEBUG : one-97.txt: md5 = 0ca6de1cb21a00a0aa59da979637a827 OK 2026/09/21 04:52:01 DEBUG : one-98.txt: md5 = 9b6bc4dd6af5487b6f8134bed3a7e920 OK 2026/09/21 04:52:02 DEBUG : one-99.txt: md5 = 8342781424fbcd33803da1e402fdca07 OK 2026/09/21 04:52:03 DEBUG : one-100.txt: md5 = 20d86fd662925c5eaf4cdc74dc29e06c OK 2026/09/21 04:52:05 DEBUG : one-101.txt: md5 = 2998101ecc2b5ad3ed71b22332efdc9d OK 2026/09/21 04:52:06 DEBUG : one-102.txt: md5 = 3f5f04f4fc66ec4d87e42e1b2c176f8a OK 2026/09/21 04:52:07 DEBUG : one-103.txt: md5 = 7dcd63a47d168806b9ca6a04ca810b7d OK 2026/09/21 04:52:09 DEBUG : one-104.txt: md5 = a853859a1d59e629899f4bb89fa79d5e OK 2026/09/21 04:52:10 DEBUG : one-105.txt: md5 = 476b04980c3d73c9dc670dbd5888ae1c OK 2026/09/21 04:52:13 INFO : Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga': Looking for duplicate names using rename mode. 2026/09/21 04:52:14 NOTICE: one.txt: Found 2 files with duplicate names 2026/09/21 04:52:15 INFO : one-106.txt: renamed from: one.txt 2026/09/21 04:52:15 INFO : one-107.txt: renamed from: one.txt --- PASS: TestDeduplicateRenameManyExisting (204.00s) === RUN TestMergeDirs run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 04:53:04 DEBUG : dupe1/one.txt: md5 = e2d21ff10d3830fb8299e45206ae8a52 OK 2026/09/21 04:53:06 DEBUG : dupe2/two.txt: md5 = 3dd1f5aaf64fd0688d3cb72bc50879f8 OK 2026/09/21 04:53:09 DEBUG : dupe3/three.txt: md5 = 0378332ac5d8544a713a110a30bac896 OK 2026/09/21 04:53:09 INFO : urj8gsducqbtvekeq181ntd2u8: merging "sfrom47189m9qr3mt4qonu281c" 2026/09/21 04:53:10 INFO : urj8gsducqbtvekeq181ntd2u8: removing empty directory 2026/09/21 04:53:11 INFO : 1u1r4ei7c628fnjg9blqt3j60o: merging "tr2hj63d80ftlmvm6a952snjcc" 2026/09/21 04:53:11 INFO : 1u1r4ei7c628fnjg9blqt3j60o: removing empty directory --- PASS: TestMergeDirs (12.88s) === RUN TestListDirSorted run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 04:53:16 DEBUG : a.txt: md5 = 9bf64390b19a42d61a8ab59d7881ff55 OK 2026/09/21 04:53:18 DEBUG : zend.txt: md5 = 7e1114e032fe652d39de1bdef03596ca OK 2026/09/21 04:53:20 DEBUG : sub dir/hello world: md5 = 0407e3859db23b1388b495f5e43ec84a OK 2026/09/21 04:53:22 DEBUG : sub dir/hello world2: md5 = b9252e75a9ffcc2f9275ce09596aa8e7 OK 2026/09/21 04:53:24 DEBUG : sub dir/ignore dir/.ignore: md5 = 0957dcadad597ac243ce6b81873554c5 OK 2026/09/21 04:53:25 DEBUG : sub dir/ignore dir/should be ignored: md5 = 74fd86a94688bfb9a4b5c4e568e40481 OK 2026/09/21 04:53:27 DEBUG : sub dir/sub sub dir/hello world3: md5 = 1e2ea16071398753ab47f95c74794263 OK 2026/09/21 04:53:29 DEBUG : a.txt: Excluded (Size Filter) 2026/09/21 04:53:29 DEBUG : a.txt: Excluded 2026/09/21 04:53:29 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/09/21 04:53:29 DEBUG : sub dir/hello world: Excluded 2026/09/21 04:53:29 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/09/21 04:53:29 DEBUG : sub dir/hello world2: Excluded 2026/09/21 04:53:30 DEBUG : sub dir/ignore dir: Excluded 2026/09/21 04:53:30 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/09/21 04:53:30 DEBUG : sub dir/hello world: Excluded 2026/09/21 04:53:30 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/09/21 04:53:30 DEBUG : sub dir/hello world2: Excluded 2026/09/21 04:53:30 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSorted (22.04s) === RUN TestListDirSortedFn run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 04:53:38 DEBUG : a.txt: md5 = fcd1e9494593cc70bef188c6387b0d91 OK 2026/09/21 04:53:40 DEBUG : zend.txt: md5 = e3b3d19616d774b3e263a3435d41c412 OK 2026/09/21 04:53:42 DEBUG : sub dir/hello world: md5 = 73875cf60c576eae6e66ff74e2f39bca OK 2026/09/21 04:53:43 DEBUG : sub dir/hello world2: md5 = cce5cec21979ca157f43c33e8238150a OK 2026/09/21 04:53:45 DEBUG : sub dir/ignore dir/.ignore: md5 = f8f4c129e312a15a6f5ea74f315adf21 OK 2026/09/21 04:53:47 DEBUG : sub dir/ignore dir/should be ignored: md5 = 4f46cadcae3e45af0aeede074fc538e8 OK 2026/09/21 04:53:49 DEBUG : sub dir/sub sub dir/hello world3: md5 = 195879dacc29d0a3b6b1fc5e943e7216 OK 2026/09/21 04:53:50 DEBUG : a.txt: Excluded (Size Filter) 2026/09/21 04:53:50 DEBUG : a.txt: Excluded 2026/09/21 04:53:51 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/09/21 04:53:51 DEBUG : sub dir/hello world: Excluded 2026/09/21 04:53:51 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/09/21 04:53:51 DEBUG : sub dir/hello world2: Excluded 2026/09/21 04:53:52 DEBUG : sub dir/ignore dir: Excluded 2026/09/21 04:53:52 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/09/21 04:53:52 DEBUG : sub dir/hello world: Excluded 2026/09/21 04:53:52 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/09/21 04:53:52 DEBUG : sub dir/hello world2: Excluded 2026/09/21 04:53:52 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSortedFn (21.34s) === RUN TestListJSON run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 04:54:00 DEBUG : file1: md5 = 845ec9a4581918486c7435f9bf3fcb65 OK 2026/09/21 04:54:02 DEBUG : sub/file2: md5 = fa6ee05be4fb88e5f01678934fec2b23 OK === RUN TestListJSON/Default === RUN TestListJSON/FilesOnly === RUN TestListJSON/DirsOnly === RUN TestListJSON/Recurse === RUN TestListJSON/SubDir === RUN TestListJSON/NoModTime === RUN TestListJSON/NoMimeType === RUN TestListJSON/ShowHash === RUN TestListJSON/HashTypes 2026/09/21 04:54:06 ERROR : file1: Failed to read hash: hash type not supported === RUN TestListJSON/Metadata 2026/09/21 04:54:06 DEBUG : eer8kka55qnghc34cq76ca668g: Fetching metadata 2026/09/21 04:54:06 DEBUG : 150fuo3cn4j1uenq6r4g4qk6t4: Fetching metadata --- PASS: TestListJSON (10.44s) --- PASS: TestListJSON/Default (0.28s) --- PASS: TestListJSON/FilesOnly (0.24s) --- PASS: TestListJSON/DirsOnly (0.41s) --- PASS: TestListJSON/Recurse (0.52s) --- PASS: TestListJSON/SubDir (0.26s) --- PASS: TestListJSON/NoModTime (0.26s) --- PASS: TestListJSON/NoMimeType (0.25s) --- PASS: TestListJSON/ShowHash (0.32s) --- PASS: TestListJSON/HashTypes (0.26s) --- PASS: TestListJSON/Metadata (0.68s) === RUN TestStatJSON run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 04:54:10 DEBUG : file1: md5 = 47b59399b0717d6e6fb756707aeb7ca5 OK 2026/09/21 04:54:12 DEBUG : sub/file2: md5 = 1a2e9829b0b0cbf2eeb47a8d394db76a OK === RUN TestStatJSON/Root === RUN TestStatJSON/RootFilesOnly === RUN TestStatJSON/RootDirsOnly === RUN TestStatJSON/Dir === RUN TestStatJSON/DirWithTrailingSlash === RUN TestStatJSON/File === RUN TestStatJSON/NotFound === RUN TestStatJSON/DirFilesOnly === RUN TestStatJSON/FileFilesOnly === RUN TestStatJSON/NotFoundFilesOnly === RUN TestStatJSON/DirDirsOnly === RUN TestStatJSON/FileDirsOnly === RUN TestStatJSON/NotFoundDirsOnly === RUN TestStatJSON/RootNotFound 2026/09/21 04:54:16 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-tilefol3vuga/notfound" 2026/09/21 04:54:16 DEBUG : Config file has changed externally - reloading 2026/09/21 04:54:16 DEBUG : Creating backend with remote "TestDrive:crypt/ljsouapbvkh23ogo0it9v08lej41ihf8r2f7fjm8l5ikksfld8m0/qe0i31qdkoejk60elps0ni5sqk" --- PASS: TestStatJSON (11.48s) --- PASS: TestStatJSON/Root (0.23s) --- PASS: TestStatJSON/RootFilesOnly (0.00s) --- PASS: TestStatJSON/RootDirsOnly (0.23s) --- PASS: TestStatJSON/Dir (0.49s) --- PASS: TestStatJSON/DirWithTrailingSlash (0.25s) --- PASS: TestStatJSON/File (0.36s) --- PASS: TestStatJSON/NotFound (0.47s) --- PASS: TestStatJSON/DirFilesOnly (0.23s) --- PASS: TestStatJSON/FileFilesOnly (0.23s) --- PASS: TestStatJSON/NotFoundFilesOnly (0.25s) --- PASS: TestStatJSON/DirDirsOnly (0.23s) --- PASS: TestStatJSON/FileDirsOnly (0.23s) --- PASS: TestStatJSON/NotFoundDirsOnly (0.25s) --- PASS: TestStatJSON/RootNotFound (1.74s) === RUN TestStatJSONMemory 2026/09/21 04:54:20 DEBUG : Creating backend with remote ":memory:" 2026/09/21 04:54:20 DEBUG : Config file has changed externally - reloading 2026/09/21 04:54:20 DEBUG : Memory root '': File to upload is small (5 bytes), uploading instead of streaming 2026/09/21 04:54:20 DEBUG : sub/file1: size = 5 OK 2026/09/21 04:54:20 DEBUG : sub/file1: md5 = 5d41402abc4b2a76b9719d911017c592 OK 2026/09/21 04:54:20 DEBUG : sub/file1: Size and md5 of src and dst objects identical === RUN TestStatJSONMemory/Dir === RUN TestStatJSONMemory/DirWithTrailingSlash === RUN TestStatJSONMemory/File === RUN TestStatJSONMemory/NotFound --- PASS: TestStatJSONMemory (0.00s) --- PASS: TestStatJSONMemory/Dir (0.00s) --- PASS: TestStatJSONMemory/DirWithTrailingSlash (0.00s) --- PASS: TestStatJSONMemory/File (0.00s) --- PASS: TestStatJSONMemory/NotFound (0.00s) === RUN TestStatJSONConfinement --- PASS: TestStatJSONConfinement (0.00s) === RUN TestMkdir run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 04:54:20 INFO : Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga': Making directory 2026/09/21 04:54:21 INFO : Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga': Making directory --- PASS: TestMkdir (0.75s) === RUN TestLsd run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 04:54:23 DEBUG : sub dir/hello world: md5 = cc609be2a1b2c4091fff9eb9a956f305 OK --- PASS: TestLsd (4.81s) === RUN TestLs run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 04:54:27 DEBUG : potato2: md5 = e5c2f15325005d1b02e78f371a514d19 OK 2026/09/21 04:54:29 DEBUG : empty space: md5 = f3422fce377fc8a1bd62b2932415e1d2 OK --- PASS: TestLs (4.70s) === RUN TestLsWithFilesFrom run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 04:54:32 DEBUG : potato2: md5 = 787aad0be250a4de62528fa79b93a76d OK 2026/09/21 04:54:34 DEBUG : empty space: md5 = fa5f1ad8553411c1b2ae77caa6834101 OK 2026/09/21 04:54:34 DEBUG : empty space: Excluded (FilesFrom Filter) 2026/09/21 04:54:34 DEBUG : empty space: Excluded --- PASS: TestLsWithFilesFrom (5.03s) === RUN TestLsLong run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 04:54:37 DEBUG : potato2: md5 = 4b7e7c3fc893abb5ffb1e03b80f7d940 OK 2026/09/21 04:54:38 DEBUG : empty space: md5 = 792e5afea7e0ba158d0c2d5938656a0e OK --- PASS: TestLsLong (4.62s) === RUN TestHashSums run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 04:54:42 DEBUG : potato2: md5 = e35da5bcd4114121c511a56cfe4c5c1c OK 2026/09/21 04:54:43 DEBUG : empty space: md5 = c8d695f23383f72afa996baf3e1fafe8 OK --- PASS: TestHashSums (4.50s) === RUN TestHashSumsWithErrors 2026/09/21 04:54:45 DEBUG : Creating backend with remote ":memory:" 2026/09/21 04:54:45 ERROR : sub/file1: hash unsupported: hash type not supported 2026/09/21 04:54:45 ERROR : file1: hash unsupported: hash type not supported 2026/09/21 04:54:45 ERROR : file1: hash unsupported: hash type not supported --- PASS: TestHashSumsWithErrors (0.00s) === RUN TestHashStream 2026/09/21 04:54:45 DEBUG : Creating md5 hash of 0 bytes read from input stream 2026/09/21 04:54:45 DEBUG : Creating md5 hash of 0 bytes read from input stream 2026/09/21 04:54:45 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2026/09/21 04:54:45 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2026/09/21 04:54:45 DEBUG : Creating md5 hash of 12 bytes read from input stream 2026/09/21 04:54:45 DEBUG : Creating md5 hash of 12 bytes read from input stream 2026/09/21 04:54:45 DEBUG : Creating sha1 hash of 12 bytes read from input stream 2026/09/21 04:54:45 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-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 04:54:46 DEBUG : potato2: md5 = fcac2c304e554a1a5e9e00988ffb6888 OK 2026/09/21 04:54:47 DEBUG : empty space: md5 = 7043a66e7405edbd4886fc922a3de3a1 OK 2026/09/21 04:54:50 DEBUG : sub dir/potato3: md5 = 174ec2764339c370ee5a147c07bfe633 OK --- PASS: TestCount (8.35s) === RUN TestDelete run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 04:54:54 DEBUG : small: md5 = 1cef0b2ccfd1491c2b5c47d470c3f0e6 OK 2026/09/21 04:54:56 DEBUG : medium: md5 = d5c504fd397d853d27d89b0ccca97f49 OK 2026/09/21 04:54:58 DEBUG : large: md5 = 9cd610a626f4802f6273c57134476e20 OK 2026/09/21 04:54:58 DEBUG : Waiting for deletions to finish 2026/09/21 04:54:58 DEBUG : large: Excluded (Size Filter) 2026/09/21 04:54:59 INFO : small: Deleted 2026/09/21 04:54:59 INFO : medium: Deleted --- PASS: TestDelete (6.88s) === RUN TestDeleteFatalError run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 04:55:01 DEBUG : file0: md5 = c5767e81aafb4132a67fc329a6926ce9 OK 2026/09/21 04:55:03 DEBUG : file1: md5 = 153bb8c07d966545ced0ab476dc657e0 OK 2026/09/21 04:55:04 DEBUG : file2: md5 = bed21f7d6b8a5ff5ae8c65d23cc1179c OK 2026/09/21 04:55:05 DEBUG : file3: md5 = e006d1ea3534e7075e19e684367824dc OK 2026/09/21 04:55:07 DEBUG : file4: md5 = f7c233f8da54e57216b64bb2081870a1 OK 2026/09/21 04:55:09 DEBUG : file5: md5 = 6f725affd168946d4aa1dac816fba9b5 OK 2026/09/21 04:55:10 DEBUG : file6: md5 = 65515ca00ab735e1f522c411a3880009 OK 2026/09/21 04:55:12 DEBUG : file7: md5 = c0ad932993f9e689dfdf490a63e5f25e OK 2026/09/21 04:55:13 DEBUG : file8: md5 = d4f409983c3f134a7a637050a323d78a OK 2026/09/21 04:55:15 DEBUG : file9: md5 = ca3795afc70ac88981e5705ddf14b051 OK 2026/09/21 04:55:16 DEBUG : file10: md5 = aee832a0ad1f96d0018a24834b37a95d OK 2026/09/21 04:55:17 DEBUG : file11: md5 = 1e8078899443cc5333116cae1f02396e OK 2026/09/21 04:55:19 DEBUG : file12: md5 = d4b1830a57a570471cf0db9a78962f79 OK 2026/09/21 04:55:20 DEBUG : file13: md5 = 7c25b10bac23c05d2339c4ea8a40fbf4 OK 2026/09/21 04:55:22 DEBUG : file14: md5 = 239ead3c769001a90da8a97e96642908 OK 2026/09/21 04:55:23 DEBUG : file15: md5 = 043a79edd49e034d9c61f16c483a7137 OK 2026/09/21 04:55:25 DEBUG : file16: md5 = 1c92f426c29aec15931de6460bb610f5 OK 2026/09/21 04:55:26 DEBUG : file17: md5 = 9004c51250b6508260aa74fc96ad35e7 OK 2026/09/21 04:55:28 DEBUG : file18: md5 = 58739ee54c7778c564b20e9dbb1cf2e6 OK 2026/09/21 04:55:29 DEBUG : file19: md5 = c3703686226692dde1eee71a75ac02f5 OK 2026/09/21 04:55:29 DEBUG : Waiting for deletions to finish 2026/09/21 04:55:29 ERROR : file0: Got fatal error on delete: --max-delete threshold reached 2026/09/21 04:55:29 ERROR : file15: Got fatal error on delete: --max-delete threshold reached 2026/09/21 04:55:29 ERROR : file17: Got fatal error on delete: --max-delete threshold reached 2026/09/21 04:55:29 ERROR : file7: Got fatal error on delete: --max-delete threshold reached 2026/09/21 04:55:29 ERROR : file6: Got fatal error on delete: --max-delete threshold reached 2026/09/21 04:55:29 ERROR : file8: Got fatal error on delete: --max-delete threshold reached 2026/09/21 04:55:29 ERROR : file5: Got fatal error on delete: --max-delete threshold reached 2026/09/21 04:55:29 ERROR : file11: Got fatal error on delete: --max-delete threshold reached 2026/09/21 04:55:29 ERROR : file13: Got fatal error on delete: --max-delete threshold reached 2026/09/21 04:55:29 ERROR : file12: Got fatal error on delete: --max-delete threshold reached 2026/09/21 04:55:29 ERROR : file18: Got fatal error on delete: --max-delete threshold reached 2026/09/21 04:55:29 ERROR : file14: Got fatal error on delete: --max-delete threshold reached 2026/09/21 04:55:29 ERROR : file2: Got fatal error on delete: --max-delete threshold reached 2026/09/21 04:55:29 ERROR : file16: Got fatal error on delete: --max-delete threshold reached 2026/09/21 04:55:29 ERROR : file10: Got fatal error on delete: --max-delete threshold reached 2026/09/21 04:55:29 ERROR : file19: Got fatal error on delete: --max-delete threshold reached 2026/09/21 04:55:29 ERROR : file9: Got fatal error on delete: --max-delete threshold reached 2026/09/21 04:55:29 ERROR : file4: Got fatal error on delete: --max-delete threshold reached 2026/09/21 04:55:29 ERROR : file3: Got fatal error on delete: --max-delete threshold reached 2026/09/21 04:55:29 ERROR : file1: Got fatal error on delete: --max-delete threshold reached --- PASS: TestDeleteFatalError (38.55s) === RUN TestMaxDelete run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 04:55:40 DEBUG : small: md5 = 10d17bdc30b61a938427998dcfcd3c16 OK 2026/09/21 04:55:41 DEBUG : medium: md5 = a5de48d4847752ec27dbc03c88c92569 OK 2026/09/21 04:55:43 DEBUG : large: md5 = 66160e70267102490a47aa931d601952 OK 2026/09/21 04:55:43 DEBUG : Waiting for deletions to finish 2026/09/21 04:55:43 ERROR : large: Got fatal error on delete: --max-delete threshold reached 2026/09/21 04:55:44 INFO : small: Deleted 2026/09/21 04:55:44 INFO : medium: Deleted --- PASS: TestMaxDelete (7.28s) === RUN TestMaxDeleteSizeLargeFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 04:55:47 DEBUG : small: md5 = 32004731a91ac22d31ca18155bcddcbb OK 2026/09/21 04:55:49 DEBUG : medium: md5 = 1cd97eb8d5883b79dc25acb82a818519 OK 2026/09/21 04:55:50 DEBUG : large: md5 = a9e827d598d7e466f91c9d09aed085e7 OK 2026/09/21 04:55:51 DEBUG : Waiting for deletions to finish 2026/09/21 04:55:51 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached 2026/09/21 04:55:51 INFO : medium: Deleted 2026/09/21 04:55:52 INFO : small: Deleted --- PASS: TestMaxDeleteSizeLargeFile (7.45s) === RUN TestMaxDeleteSize run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 04:55:55 DEBUG : small: md5 = 37d4d98b7c57fb9a454405859872741b OK 2026/09/21 04:55:56 DEBUG : medium: md5 = 55041b3c04d1d558254e098301468471 OK 2026/09/21 04:55:58 DEBUG : large: md5 = 8c96b42508a1989200ba11cb189ac2a8 OK 2026/09/21 04:55:58 DEBUG : Waiting for deletions to finish 2026/09/21 04:55:58 ERROR : medium: Got fatal error on delete: --max-delete-size threshold reached 2026/09/21 04:55:58 INFO : large: Deleted 2026/09/21 04:55:58 INFO : small: Deleted --- PASS: TestMaxDeleteSize (6.99s) === RUN TestReadFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 04:56:02 DEBUG : ReadFile: md5 = 2ed9ecb16f54e80a3a3bf9852fea57e1 OK --- PASS: TestReadFile (4.33s) === RUN TestRetry 2026/09/21 04:56:05 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 1/5 2026/09/21 04:56:05 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 2/5 2026/09/21 04:56:05 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2026/09/21 04:56:05 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2026/09/21 04:56:05 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2026/09/21 04:56:05 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/09/21 04:56:05 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-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 04:56:06 DEBUG : file1: md5 = 91b9a29e6679b9acd800e1b99917d7c1 OK 2026/09/21 04:56:07 DEBUG : file2: md5 = 0accddf227429d3762a8b0b95cb70b89 OK --- PASS: TestCat (13.25s) === RUN TestPurge 2026/09/21 04:56:18 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-davonal6tuvo" 2026/09/21 04:56:18 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/21 04:56:18 DEBUG : Creating backend with remote "TestDrive:crypt/ro6468nku55gc34kqu1kou3982jdk85ia98l11jq520jumfvkbkg" 2026/09/21 04:56:19 DEBUG : Creating backend with remote "/tmp/rclone3547414943" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-davonal6tuvo'", Local "Local file system at /tmp/rclone3547414943", Modify Window "1ms" 2026/09/21 04:56:24 DEBUG : A1/B1/C1/one: md5 = 26b19295a552fae92321048a28cc0542 OK 2026/09/21 04:56:24 INFO : A2: Making directory 2026/09/21 04:56:24 INFO : A1/B2: Making directory 2026/09/21 04:56:25 INFO : A1/B2/C2: Making directory 2026/09/21 04:56:26 INFO : A1/B1/C3: Making directory 2026/09/21 04:56:27 INFO : A3: Making directory 2026/09/21 04:56:27 INFO : A3/B3: Making directory 2026/09/21 04:56:28 INFO : A3/B3/C4: Making directory 2026/09/21 04:56:30 DEBUG : A1/two: md5 = c28e6e183e4f6fcaccc881bf736f0d55 OK 2026/09/21 04:56:33 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-davonal6tuvo': Purge remote 2026/09/21 04:56:34 NOTICE: purge failed: directory not found --- PASS: TestPurge (15.90s) === RUN TestRmdirsNoLeaveRoot run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 04:56:37 DEBUG : A1/B1/C1/one: md5 = 699d0bcecc4f5f9630b77c6d93d9f6b6 OK 2026/09/21 04:56:37 INFO : A2: Making directory 2026/09/21 04:56:38 INFO : A1/B2: Making directory 2026/09/21 04:56:39 INFO : A1/B2/C2: Making directory 2026/09/21 04:56:40 INFO : A1/B1/C3: Making directory 2026/09/21 04:56:40 INFO : A3: Making directory 2026/09/21 04:56:41 INFO : A3/B3: Making directory 2026/09/21 04:56:42 INFO : A3/B3/C4: Making directory 2026/09/21 04:56:44 DEBUG : A1/two: md5 = 12b86223d26db336e20eb8ba54b59fc2 OK 2026/09/21 04:56:45 DEBUG : removing 1 level 3 directories 2026/09/21 04:56:45 INFO : A3/B3/C4: Removing directory 2026/09/21 04:56:48 DEBUG : removing 2 level 3 directories 2026/09/21 04:56:48 INFO : A1/B2/C2: Removing directory 2026/09/21 04:56:48 INFO : A1/B1/C3: Removing directory 2026/09/21 04:56:49 DEBUG : removing 2 level 2 directories 2026/09/21 04:56:49 INFO : A3/B3: Removing directory 2026/09/21 04:56:49 INFO : A1/B2: Removing directory 2026/09/21 04:56:49 DEBUG : removing 2 level 1 directories 2026/09/21 04:56:49 INFO : A3: Removing directory 2026/09/21 04:56:49 INFO : A2: Removing directory 2026/09/21 04:56:53 DEBUG : removing 1 level 3 directories 2026/09/21 04:56:53 INFO : A1/B1/C1: Removing directory 2026/09/21 04:56:54 DEBUG : removing 1 level 2 directories 2026/09/21 04:56:54 INFO : A1/B1: Removing directory 2026/09/21 04:56:55 DEBUG : removing 1 level 1 directories 2026/09/21 04:56:55 INFO : A1: Removing directory 2026/09/21 04:56:55 DEBUG : removing 1 level 0 directories 2026/09/21 04:56:55 INFO : Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga': Removing directory --- PASS: TestRmdirsNoLeaveRoot (22.91s) === RUN TestRmdirsLeaveRoot run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 04:56:57 INFO : A1: Making directory 2026/09/21 04:56:58 INFO : A1/B1: Making directory 2026/09/21 04:56:59 INFO : A1/B1/C1: Making directory 2026/09/21 04:57:01 DEBUG : removing 1 level 3 directories 2026/09/21 04:57:01 INFO : A1/B1/C1: Removing directory 2026/09/21 04:57:02 DEBUG : removing 1 level 2 directories 2026/09/21 04:57:02 INFO : A1/B1: Removing directory --- PASS: TestRmdirsLeaveRoot (7.99s) === RUN TestRmdirsWithFilter run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 04:57:05 INFO : A1: Making directory 2026/09/21 04:57:05 INFO : A1/B1: Making directory 2026/09/21 04:57:06 INFO : A1/B1/C1: Making directory 2026/09/21 04:57:09 DEBUG : removing 1 level 3 directories 2026/09/21 04:57:09 INFO : A1/B1/C1: Removing directory 2026/09/21 04:57:09 DEBUG : removing 1 level 2 directories 2026/09/21 04:57:09 INFO : A1/B1: Removing directory --- PASS: TestRmdirsWithFilter (7.32s) === RUN TestCopyURL run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 04:57:14 DEBUG : file1: md5 = 9313761bcecf43a19c9a9f458421843a OK 2026/09/21 04:57:14 DEBUG : file1: size = 14 OK 2026/09/21 04:57:14 DEBUG : filename.txt: File name found in url 2026/09/21 04:57:16 DEBUG : filename.txt: md5 = dadd424bdf232619416d2ea7a548dccc OK 2026/09/21 04:57:16 DEBUG : filename.txt: size = 14 OK 2026/09/21 04:57:16 DEBUG : headerfilename.txt: filename found in Content-Disposition header. 2026/09/21 04:57:17 DEBUG : headerfilename.txt: md5 = 7e18067df414330c7f58e19d1ae936b6 OK 2026/09/21 04:57:17 DEBUG : headerfilename.txt: size = 14 OK 2026/09/21 04:57:19 DEBUG : file2: md5 = 5c6386d1c05c6d8cbe6d173e7f3d5593 OK 2026/09/21 04:57:19 DEBUG : file2: size = 14 OK --- PASS: TestCopyURL (9.35s) === RUN TestCopyURLDownloadHeaders run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 04:57:23 DEBUG : file1: md5 = fbc96f76d85d601953e71270b64285fb OK 2026/09/21 04:57:23 DEBUG : file1: size = 14 OK --- PASS: TestCopyURLDownloadHeaders (2.30s) === RUN TestCopyURLToWriter --- PASS: TestCopyURLToWriter (0.00s) === RUN TestMoveFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 04:57:24 DEBUG : file1: Need to transfer - File not found at Destination 2026/09/21 04:57:26 DEBUG : sub/file2: md5 = d580fa49b4a5ee0dd59f309b4e93fa3d OK 2026/09/21 04:57:26 DEBUG : sub/file2: size = 14 OK 2026/09/21 04:57:26 INFO : file1: Copied (new) to: sub/file2 2026/09/21 04:57:26 INFO : file1: Deleted 2026/09/21 04:57:27 DEBUG : sub/file2: size = 14 OK 2026/09/21 04:57:27 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/21 04:57:27 DEBUG : file1: Unchanged skipping 2026/09/21 04:57:27 INFO : file1: Deleted 2026/09/21 04:57:27 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga': don't need to copy/move sub/file2, it is already at target location --- PASS: TestMoveFile (6.04s) === RUN TestMoveFileWithIgnoreExisting run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 04:57:30 DEBUG : file1: Need to transfer - File not found at Destination 2026/09/21 04:57:31 DEBUG : file1: md5 = 23275fef6e5c13ec43752a0c786a0ee6 OK 2026/09/21 04:57:31 DEBUG : file1: size = 14 OK 2026/09/21 04:57:31 INFO : file1: Copied (new) 2026/09/21 04:57:31 INFO : file1: Deleted 2026/09/21 04:57:32 DEBUG : file1: Destination exists, skipping 2026/09/21 04:57:32 DEBUG : file1: Not removing source file as destination file exists and --ignore-existing is set --- PASS: TestMoveFileWithIgnoreExisting (3.19s) === RUN TestCaseInsensitiveMoveFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFile (0.47s) === RUN TestCaseInsensitiveMoveFileDryRun run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFileDryRun (0.45s) === RUN TestMoveFileBackupDir run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 04:57:36 DEBUG : dst/file1: md5 = 8d5fe7d73c94c0c43f93e0052ab11f3b OK 2026/09/21 04:57:37 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-tilefol3vuga/backup" 2026/09/21 04:57:37 DEBUG : Config file has changed externally - reloading 2026/09/21 04:57:37 DEBUG : Creating backend with remote "TestDrive:crypt/ljsouapbvkh23ogo0it9v08lej41ihf8r2f7fjm8l5ikksfld8m0/1nrff024r7pq65ecp72fc28jb0" 2026/09/21 04:57:38 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone1633621919) 2026/09/21 04:57:38 DEBUG : dst/file1: size = 18 (Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga') 2026/09/21 04:57:38 DEBUG : dst/file1: Sizes differ 2026/09/21 04:57:41 INFO : dst/file1: Moved (server-side) 2026/09/21 04:57:42 DEBUG : dst/file1: md5 = 5633eb4ab968d8956ebe7c85790ab19f OK 2026/09/21 04:57:42 DEBUG : dst/file1: size = 14 OK 2026/09/21 04:57:42 INFO : dst/file1: Copied (new) 2026/09/21 04:57:42 INFO : dst/file1: Deleted --- PASS: TestMoveFileBackupDir (12.93s) === 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-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 04:57:49 DEBUG : A/file0: md5 = 720b0db106f2ffcca79f3a0dcefbcf82 OK 2026/09/21 04:57:50 DEBUG : A/file1: md5 = a64191c082e3a946bd106ecede0b452b OK 2026/09/21 04:57:52 DEBUG : A/file2: md5 = 7bea7b004b9d904c224f187a8583523e OK 2026/09/21 04:57:53 DEBUG : A/file3: md5 = 50a52b3ca17e7244c9d393f37bf3f6e4 OK 2026/09/21 04:57:54 DEBUG : A/file4: md5 = fce0f67c0415f087967ee357f2c6d838 OK 2026/09/21 04:57:56 DEBUG : A/file5: md5 = c0b247668fb6bc577f74be49a5f9a640 OK 2026/09/21 04:57:57 DEBUG : A/file6: md5 = c6eb8fd524cb794fefdcd5544dfa5608 OK 2026/09/21 04:57:59 DEBUG : A/file7: md5 = 87c8859f484dff2144b92deb68b644c0 OK 2026/09/21 04:58:00 DEBUG : A/file8: md5 = f9be6b57858b99fd946c5bdb345c232e OK 2026/09/21 04:58:02 DEBUG : A/file9: md5 = 99f8fab2012c0281f3cbc4d06254f7cd OK 2026/09/21 04:58:03 DEBUG : A/file10: md5 = fe31be4c37b93c2c98754779223c6d1c OK 2026/09/21 04:58:06 DEBUG : A/file11: md5 = 28b5328bf8fe25e03ae001301a7314fa OK 2026/09/21 04:58:08 DEBUG : A/file12: md5 = c74ea78bcc8bce18b165b42d1f8addda OK 2026/09/21 04:58:09 DEBUG : A/file13: md5 = cdcb6f57dec84489572d393a31df09ef OK 2026/09/21 04:58:11 DEBUG : A/file14: md5 = 776e265e1028f7b8ad7c5542109ca7ff OK 2026/09/21 04:58:12 DEBUG : A/file15: md5 = 54c58ea380c5ca406e1fb7db1596e064 OK 2026/09/21 04:58:14 DEBUG : A/file16: md5 = 1b6cc2d2a1a8cbfb6499ef184ea07b8c OK 2026/09/21 04:58:15 DEBUG : A/file17: md5 = 5f89a3dc4f0f43eea9153f32828b49b2 OK 2026/09/21 04:58:16 DEBUG : A/file18: md5 = b76d01e38feb638848fc373ec23b8381 OK 2026/09/21 04:58:18 DEBUG : A/file19: md5 = 3ec37ae5dbdbc893394fc1fb35688b11 OK 2026/09/21 04:58:19 ERROR : A/file0: Couldn't move: boom 2026/09/21 04:58:19 ERROR : A/file1: Couldn't move: context canceled --- PASS: TestDirMoveMoveError (42.95s) === RUN TestDirMoveContext run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 04:58:32 DEBUG : A/one: md5 = efd7b93dd0a4130cbadc7291debaa058 OK 2026/09/21 04:58:33 DEBUG : A/two: md5 = 7ebc7269d987ca78e7ff36c5dc4d3c46 OK 2026/09/21 04:58:35 INFO : A/two: Moved (server-side) to: B/two 2026/09/21 04:58:35 INFO : A/one: Moved (server-side) to: B/one --- PASS: TestDirMoveContext (8.29s) === RUN TestDirMove run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 04:58:40 DEBUG : A1/one: md5 = 3725be48e0137d8a0cfa4e82aae42c33 OK 2026/09/21 04:58:42 DEBUG : A1/two: md5 = f59837b1fc30c4fb456a3c9bfb17505c OK 2026/09/21 04:58:44 DEBUG : A1/B1/three: md5 = 876eeb958f19ed198faf4010927f2464 OK 2026/09/21 04:58:46 DEBUG : A1/B1/C1/four: md5 = 33f622e2651ff58ad1ac0301b93d7bce OK 2026/09/21 04:58:48 DEBUG : A1/B1/C2/five: md5 = 3c32fcba31e06604759c1bb0cdfa8a3a OK 2026/09/21 04:58:48 INFO : A1/B2: Making directory 2026/09/21 04:58:49 INFO : A1/B1/C3: Making directory 2026/09/21 04:58:58 INFO : A2/B1/C1/four: Moved (server-side) to: A3/B1/C1/four 2026/09/21 04:58:58 INFO : A2/B1/three: Moved (server-side) to: A3/B1/three 2026/09/21 04:58:58 INFO : A2/two: Moved (server-side) to: A3/two 2026/09/21 04:58:58 INFO : A2/B1/C2/five: Moved (server-side) to: A3/B1/C2/five 2026/09/21 04:58:58 INFO : A2/one: Moved (server-side) to: A3/one 2026/09/21 04:59:03 INFO : Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga': Can't DirMove - falling back to file moves: can't move directory - incompatible remotes 2026/09/21 04:59:09 INFO : A3/B1/C1/four: Moved (server-side) to: A4/B1/C1/four 2026/09/21 04:59:09 INFO : A3/B1/C2/five: Moved (server-side) to: A4/B1/C2/five 2026/09/21 04:59:09 INFO : A3/B1/three: Moved (server-side) to: A4/B1/three 2026/09/21 04:59:09 INFO : A3/two: Moved (server-side) to: A4/two 2026/09/21 04:59:10 INFO : A3/one: Moved (server-side) to: A4/one --- PASS: TestDirMove (43.67s) === RUN TestGetFsInfo run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 04:59:22 DEBUG : Config file has changed externally - reloading --- PASS: TestGetFsInfo (0.50s) === RUN TestRcat === RUN TestRcat/withChecksum=false,ignoreChecksum=false run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 04:59:22 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga': File to upload is small (34 bytes), uploading instead of streaming 2026/09/21 04:59:24 DEBUG : no_checksum_small_file_from_pipe: md5 = b307af269efa0283f8b7a1c429922a6e OK 2026/09/21 04:59:24 DEBUG : no_checksum_small_file_from_pipe: size = 34 OK 2026/09/21 04:59:24 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2026/09/21 04:59:24 DEBUG : no_checksum_small_file_from_pipe: Size of src and dst objects identical 2026/09/21 04:59:24 DEBUG : qc9k4uauqa9ls6a2r6lo8jf4b1m7r0510pkurois1cc70egbs4h0: Sending chunk 0 length 102465 2026/09/21 04:59:25 DEBUG : no_checksum_big_file_from_pipe: md5 = 2408d53e8eb340e4f94d6a8a1e12444d OK 2026/09/21 04:59:25 DEBUG : no_checksum_big_file_from_pipe: size = 102401 OK 2026/09/21 04:59:25 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-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 04:59:27 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga': File to upload is small (34 bytes), uploading instead of streaming 2026/09/21 04:59:29 DEBUG : with_checksum_small_file_from_pipe: md5 = 1343bcca7deeba6a471ea1b67b677834 OK 2026/09/21 04:59:29 DEBUG : with_checksum_small_file_from_pipe: size = 34 OK 2026/09/21 04:59:29 DEBUG : with_checksum_small_file_from_pipe: Size of src and dst objects identical 2026/09/21 04:59:29 DEBUG : e74jo8a7lr1aimg2u78fct67gnuetc1qujiofaci0c8fnq7rjt1d4i296pcklqhgdihs03etvgdi4: Sending chunk 0 length 102465 2026/09/21 04:59:30 DEBUG : with_checksum_big_file_from_pipe: md5 = a421765a032b319daae4399e7de7ab6b OK 2026/09/21 04:59:30 DEBUG : with_checksum_big_file_from_pipe: size = 102401 OK 2026/09/21 04:59:30 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-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 04:59:32 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga': File to upload is small (34 bytes), uploading instead of streaming 2026/09/21 04:59:34 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK 2026/09/21 04:59:34 DEBUG : ignore_checksum_small_file_from_pipe: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/21 04:59:34 DEBUG : 1963rn6olajfbjn4nbqi9nacu7bk879vpf0d2bg8r0ndcqqsfntob0urt056p0qujf574rqnb5f62: Sending chunk 0 length 102465 2026/09/21 04:59:35 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK 2026/09/21 04:59:35 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-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 04:59:37 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga': File to upload is small (34 bytes), uploading instead of streaming 2026/09/21 04:59:39 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK 2026/09/21 04:59:39 DEBUG : ignore_checksum_small_file_from_pipe: Size of src and dst objects identical 2026/09/21 04:59:40 DEBUG : 1963rn6olajfbjn4nbqi9nacu7bk879vpf0d2bg8r0ndcqqsfntob0urt056p0qujf574rqnb5f62: Sending chunk 0 length 102465 2026/09/21 04:59:41 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK 2026/09/21 04:59:41 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical --- PASS: TestRcat (20.31s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (4.93s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (5.02s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (5.08s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (5.28s) === RUN TestRcatMetadata run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" === RUN TestRcatMetadata/Normal 2026/09/21 04:59:43 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga': File to upload is small (48 bytes), uploading instead of streaming 2026/09/21 04:59:44 DEBUG : rcat_metadata: md5 = 6b756003d4812ff339f698b3557a077f OK 2026/09/21 04:59:44 DEBUG : rcat_metadata: size = 48 OK 2026/09/21 04:59:44 DEBUG : rcat_metadata: Size of src and dst objects identical === RUN TestRcatMetadata/ViaDisk 2026/09/21 04:59:46 DEBUG : rijssoobhbn85c5p1imhg55m6frank1nlplla6hkdjlqmqb2471g: Sending chunk 0 length 111 2026/09/21 04:59:47 DEBUG : rcat_metadata_uploadcutoff0: md5 = 8dac71d0f089b21d2f192a0b4c62a910 OK 2026/09/21 04:59:47 DEBUG : rcat_metadata_uploadcutoff0: size = 63 OK 2026/09/21 04:59:47 DEBUG : rcat_metadata_uploadcutoff0: Size of src and dst objects identical --- PASS: TestRcatMetadata (6.00s) --- PASS: TestRcatMetadata/Normal (2.69s) --- PASS: TestRcatMetadata/ViaDisk (2.88s) === RUN TestRcatSize run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 04:59:50 DEBUG : potato1: md5 = 4c4a36b24c2ffe2a1f0e04777a8b9b43 OK 2026/09/21 04:59:50 DEBUG : potato1: size = 60 OK 2026/09/21 04:59:50 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga': File to upload is small (60 bytes), uploading instead of streaming 2026/09/21 04:59:52 DEBUG : potato2: md5 = cf845731e33cb5d4d6403b86e0857e62 OK 2026/09/21 04:59:52 DEBUG : potato2: size = 60 OK 2026/09/21 04:59:52 DEBUG : potato2: Size of src and dst objects identical --- PASS: TestRcatSize (4.87s) === RUN TestRcatSizeShortEOF run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 04:59:55 DEBUG : potato1: md5 = eec65a3e221c2bef260959df9572b91d OK 2026/09/21 04:59:55 DEBUG : potato1: size = 120 (Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga') 2026/09/21 04:59:55 DEBUG : potato1: size = 60 (Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga') 2026/09/21 04:59:55 ERROR : potato1: corrupted on transfer: sizes differ src 120 vs dst(Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga') 60 2026/09/21 04:59:55 INFO : potato1: Removing failed copy --- PASS: TestRcatSizeShortEOF (2.27s) === RUN TestRcatSizeMetadata run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 04:59:57 DEBUG : potato1: md5 = 2b73d0228444cf21de78df4325e3ced1 OK 2026/09/21 04:59:57 DEBUG : potato1: size = 60 OK 2026/09/21 04:59:57 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga': File to upload is small (60 bytes), uploading instead of streaming 2026/09/21 04:59:58 DEBUG : potato2: md5 = 2616eb86c0eaeddd6574f82affd65fdc OK 2026/09/21 04:59:58 DEBUG : potato2: size = 60 OK 2026/09/21 04:59:58 DEBUG : potato2: Size of src and dst objects identical --- PASS: TestRcatSizeMetadata (5.01s) === RUN TestRcatSizeUploadHeaders run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 05:00:02 DEBUG : potato1: md5 = 5b7b82196eb8d9226d0a7bfee4bd392c OK 2026/09/21 05:00:02 DEBUG : potato1: size = 60 OK --- PASS: TestRcatSizeUploadHeaders (2.49s) === RUN TestRcatSizeChecksum === RUN TestRcatSizeChecksum/Corrupted run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" operations_test.go:1950: Skipping as destination doesn't support hashes === RUN TestRcatSizeChecksum/SizeDiffers run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 05:00:05 DEBUG : potato4: md5 = 1d3c4fc1c0e07fed28d5bde96047b720 OK 2026/09/21 05:00:05 DEBUG : potato4: size = 60 (Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga') 2026/09/21 05:00:05 DEBUG : potato4: size = 59 (Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga') 2026/09/21 05:00:05 ERROR : potato4: corrupted on transfer: sizes differ src 60 vs dst(Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga') 59 2026/09/21 05:00:05 INFO : potato4: Removing failed copy === RUN TestRcatSizeChecksum/IgnoreChecksum run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 05:00:08 DEBUG : potato2: size = 60 OK === RUN TestRcatSizeChecksum/NoHashes run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 05:00:10 DEBUG : potato3: md5 = 254175d9b40152c6f3ab26678e2b1934 OK 2026/09/21 05:00:10 DEBUG : potato3: size = 60 OK --- PASS: TestRcatSizeChecksum (8.28s) --- SKIP: TestRcatSizeChecksum/Corrupted (0.47s) --- PASS: TestRcatSizeChecksum/SizeDiffers (2.91s) --- PASS: TestRcatSizeChecksum/IgnoreChecksum (2.56s) --- PASS: TestRcatSizeChecksum/NoHashes (2.35s) === RUN TestTouchDir run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 05:00:13 DEBUG : potato2: md5 = 1eb03bb1f7b9720b7a0c25276565d5ed OK 2026/09/21 05:00:14 DEBUG : empty space: md5 = ec81d1608d4045df9d6770ebdf5015b6 OK 2026/09/21 05:00:17 DEBUG : sub dir/potato3: md5 = f1a562e747457e30342950c8b535c5ca OK 2026/09/21 05:00:18 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga': Touching "sub dir/potato3" 2026/09/21 05:00:18 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga': Touching "empty space" 2026/09/21 05:00:18 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga': Touching "potato2" --- PASS: TestTouchDir (10.57s) === RUN TestMkdirMetadata run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 05:00:22 DEBUG : dir with metadata: Making directory with metadata 2026/09/21 05:00:23 INFO : dir with metadata: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) --- PASS: TestMkdirMetadata (2.43s) === RUN TestMkdirModTime run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 05:00:24 DEBUG : directory with modtime: Making directory with metadata 2026/09/21 05:00:25 INFO : directory with modtime: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/09/21 05:00:25 NOTICE: dry run directory with modtime: Skipped make directory as --dry-run is set --- PASS: TestMkdirModTime (2.50s) === RUN TestCopyDirMetadata run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 05:00:27 DEBUG : dir with metadata to be copied: Making directory with metadata 2026/09/21 05:00:27 INFO : dir with metadata to be copied: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2026/09/21 05:00:27 DEBUG : Google drive root 'crypt/ljsouapbvkh23ogo0it9v08lej41ihf8r2f7fjm8l5ikksfld8m0': Skipping btime metadata as can't update it on an existing file: 2026-09-21T05:00:27.32173768Z 2026/09/21 05:00:28 INFO : non existent directory: Updated directory metadata 2026/09/21 05:00:30 DEBUG : Google drive root 'crypt/ljsouapbvkh23ogo0it9v08lej41ihf8r2f7fjm8l5ikksfld8m0': Skipping btime metadata as can't update it on an existing file: 2026-09-21T05:00:27.32173768Z 2026/09/21 05:00:30 INFO : existing directory: Updated directory metadata --- PASS: TestCopyDirMetadata (4.88s) === RUN TestSetDirModTime run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 05:00:32 DEBUG : set modtime on non existent directory: Skipping set directory modification time as --no-update-dir-modtime is set 2026/09/21 05:00:33 INFO : set modtime on existing directory: Set directory modification time (using SetModTime) 2026/09/21 05:00:34 INFO : set modtime on existing directory: Set directory modification time (using DirSetModTime) --- PASS: TestSetDirModTime (4.19s) === RUN TestDirsEqual run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 05:00:36 DEBUG : dir with metadata to be copied: Making directory with metadata 2026/09/21 05:00:36 INFO : dir with metadata to be copied: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2026/09/21 05:00:36 DEBUG : dst: Making directory with metadata 2026/09/21 05:00:37 INFO : dst: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2026/09/21 05:00:37 DEBUG : dst: Directory modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/21 05:00:37 INFO : dst: Set directory modification time (using SetModTime) 2026/09/21 05:00:38 INFO : dst: Set directory modification time (using SetModTime) 2026/09/21 05:00:38 DEBUG : dst: Directory modification time the same (differ by 1ns, within tolerance 1ms) 2026/09/21 05:00:38 INFO : dst: Set directory modification time (using SetModTime) 2026/09/21 05:00:38 DEBUG : dst: Destination directory is newer than source, skipping --- PASS: TestDirsEqual (3.83s) === RUN TestRemoveExisting run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga'", Local "Local file system at /tmp/rclone1633621919", Modify Window "1ms" 2026/09/21 05:00:42 DEBUG : sub dir/test remove existing: md5 = aafaeb6cc06013e7191e60e9f7e705b1 OK 2026/09/21 05:00:43 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: md5 = 3c604e1f53be9db548a084810a443e10 OK 2026/09/21 05:00:45 DEBUG : sub dir/test remove existing: TEST: renaming existing object to "sub dir/test remove existing.kafotan9" before starting 2026/09/21 05:00:47 DEBUG : sub dir/test remove existing.kafotan9: TEST: removing renamed existing file after operation 2026/09/21 05:00:48 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: TEST: renaming existing object to "sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.cedicav5" before starting 2026/09/21 05:00:49 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.cedicav5: TEST: renaming existing back after failed operation 2026/09/21 05:00:50 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: TEST: renaming existing object to "sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.cupupit5" before starting 2026/09/21 05:00:51 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.cupupit5: TEST: removing renamed existing file after operation --- PASS: TestRemoveExisting (13.59s) === RUN TestRcatInputFailurePreservesDestination 2026/09/21 05:00:53 DEBUG : Creating backend with remote "/tmp/TestRcatInputFailurePreservesDestination1158098405/001" 2026/09/21 05:00:53 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 TestRcList rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcList (0.00s) === RUN TestRcStat rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcStat (0.00s) === RUN TestRcSetTier rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcSetTier (0.00s) === RUN TestRcSetTierFile rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcSetTierFile (0.00s) === RUN TestRcMkdir rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcMkdir (0.00s) === RUN TestRcMovefile rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcMovefile (0.00s) === RUN TestRcPurge rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcPurge (0.00s) === RUN TestRcRmdir rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcRmdir (0.00s) === RUN TestRcRmdirs rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcRmdirs (0.00s) === RUN TestRcSize rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcSize (0.00s) === RUN TestRcPublicLink rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcPublicLink (0.00s) === RUN TestRcFsInfo rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcFsInfo (0.00s) === RUN TestUploadFile rc_test.go:31: Skipping test on non local remote --- SKIP: TestUploadFile (0.00s) === RUN TestRcCommand rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcCommand (0.00s) === RUN TestRcDu rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcDu (0.00s) === RUN TestRcCheck rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcCheck (0.00s) === RUN TestRcHashsum rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcHashsum (0.00s) === RUN TestRcHashsumSingleFile rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcHashsumSingleFile (0.00s) === RUN TestRcHashsumFile rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcHashsumFile (0.00s) === RUN TestRcGetFile rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcGetFile (0.00s) PASS 2026/09/21 05:00:53 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-tilefol3vuga': Purge remote "./operations.test -test.v -test.timeout 1h0m0s -remote TestCryptDrive: -verbose" - Finished OK in 15m54.170726445s (try 1/5)