"./operations.test -test.v -test.timeout 1h0m0s -remote TestCryptDrive: -verbose" - Starting (try 1/5) 2026/11/06 01:54:31 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-gevuxol5nara" 2026/11/06 01:54:31 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/11/06 01:54:31 DEBUG : Creating backend with remote "TestDrive:crypt/o954njvhu8i73jqo00n8k29tcik3fbkejdsvjlmnh110c5qaqgog" 2026/11/06 01:54:32 DEBUG : Creating backend with remote "/tmp/rclone707300349" === 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-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" multithread_test.go:120: multithread writing not supported --- SKIP: TestMultithreadCopy (0.21s) === RUN TestMultithreadCopyAbort run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" multithread_test.go:120: multithread writing not supported --- SKIP: TestMultithreadCopyAbort (0.20s) === RUN TestMultithreadCopyWriterAtErrors === RUN TestMultithreadCopyWriterAtErrors/open/fatal=false 2026/11/06 01:54:32 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt === RUN TestMultithreadCopyWriterAtErrors/open/fatal=true 2026/11/06 01:54:32 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt === RUN TestMultithreadCopyWriterAtErrors/write/fatal=false 2026/11/06 01:54:32 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2026/11/06 01:54:32 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams 2026/11/06 01:54:32 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting 2026/11/06 01:54:32 DEBUG : file.txt: writing chunk 1 2026/11/06 01:54:32 DEBUG : file.txt: multi-thread copy: chunk 2/4 failed: multi-thread copy: failed to write chunk: disk full 2026/11/06 01:54:32 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting 2026/11/06 01:54:32 DEBUG : file.txt: writing chunk 2 2026/11/06 01:54:32 DEBUG : file.txt: multi-thread copy: chunk 3/4 failed: multi-thread copy: failed to write chunk: disk full 2026/11/06 01:54:32 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting 2026/11/06 01:54:32 DEBUG : file.txt: writing chunk 0 2026/11/06 01:54:32 DEBUG : file.txt: multi-thread copy: chunk 1/4 failed: multi-thread copy: failed to write chunk: disk full 2026/11/06 01:54:32 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit 2026/11/06 01:54:32 DEBUG : file.txt: multi-thread copy: abort failed: multi-thread copy: failed to find temp file when aborting chunk writer: object not found === RUN TestMultithreadCopyWriterAtErrors/write/fatal=true 2026/11/06 01:54:32 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2026/11/06 01:54:32 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams 2026/11/06 01:54:32 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting 2026/11/06 01:54:32 DEBUG : file.txt: writing chunk 1 2026/11/06 01:54:32 DEBUG : file.txt: multi-thread copy: chunk 2/4 failed: multi-thread copy: failed to write chunk: disk full 2026/11/06 01:54:32 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting 2026/11/06 01:54:32 DEBUG : file.txt: writing chunk 2 2026/11/06 01:54:32 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting 2026/11/06 01:54:32 DEBUG : file.txt: writing chunk 0 2026/11/06 01:54:32 DEBUG : file.txt: multi-thread copy: chunk 1/4 failed: multi-thread copy: failed to write chunk: disk full 2026/11/06 01:54:32 DEBUG : file.txt: multi-thread copy: chunk 3/4 failed: multi-thread copy: failed to write chunk: disk full 2026/11/06 01:54:32 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit 2026/11/06 01:54:32 DEBUG : file.txt: multi-thread copy: abort failed: multi-thread copy: failed to find temp file when aborting chunk writer: object not found === RUN TestMultithreadCopyWriterAtErrors/close/fatal=false 2026/11/06 01:54:32 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2026/11/06 01:54:32 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams 2026/11/06 01:54:32 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting 2026/11/06 01:54:32 DEBUG : file.txt: writing chunk 1 2026/11/06 01:54:32 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 finished 2026/11/06 01:54:32 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting 2026/11/06 01:54:32 DEBUG : file.txt: writing chunk 2 2026/11/06 01:54:32 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 finished 2026/11/06 01:54:32 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 starting 2026/11/06 01:54:32 DEBUG : file.txt: writing chunk 3 2026/11/06 01:54:32 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 finished 2026/11/06 01:54:32 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting 2026/11/06 01:54:32 DEBUG : file.txt: writing chunk 0 2026/11/06 01:54:32 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 finished 2026/11/06 01:54:32 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit 2026/11/06 01:54:32 DEBUG : file.txt: multi-thread copy: abort failed: multi-thread copy: failed to find temp file when aborting chunk writer: object not found === RUN TestMultithreadCopyWriterAtErrors/close/fatal=true 2026/11/06 01:54:32 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2026/11/06 01:54:32 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams 2026/11/06 01:54:32 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting 2026/11/06 01:54:32 DEBUG : file.txt: writing chunk 1 2026/11/06 01:54:32 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 finished 2026/11/06 01:54:32 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting 2026/11/06 01:54:32 DEBUG : file.txt: writing chunk 2 2026/11/06 01:54:32 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 finished 2026/11/06 01:54:32 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 starting 2026/11/06 01:54:32 DEBUG : file.txt: writing chunk 3 2026/11/06 01:54:32 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 finished 2026/11/06 01:54:32 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting 2026/11/06 01:54:32 DEBUG : file.txt: writing chunk 0 2026/11/06 01:54:32 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 finished 2026/11/06 01:54:32 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit 2026/11/06 01:54:32 DEBUG : file.txt: multi-thread copy: abort failed: multi-thread copy: failed to find temp file when aborting chunk writer: object not found --- PASS: TestMultithreadCopyWriterAtErrors (0.00s) --- PASS: TestMultithreadCopyWriterAtErrors/open/fatal=false (0.00s) --- PASS: TestMultithreadCopyWriterAtErrors/open/fatal=true (0.00s) --- PASS: TestMultithreadCopyWriterAtErrors/write/fatal=false (0.00s) --- PASS: TestMultithreadCopyWriterAtErrors/write/fatal=true (0.00s) --- PASS: TestMultithreadCopyWriterAtErrors/close/fatal=false (0.00s) --- PASS: TestMultithreadCopyWriterAtErrors/close/fatal=true (0.00s) === RUN TestSizeDiffers 2026/11/06 01:54:32 DEBUG : a: size = 0 OK 2026/11/06 01:54:32 DEBUG : a: size = 1 (memory) 2026/11/06 01:54:32 DEBUG : a: size = 2 (memory) --- PASS: TestSizeDiffers (0.00s) === RUN TestDirTransferEntry --- PASS: TestDirTransferEntry (0.00s) === RUN TestReOpen === RUN TestReOpen/Normal === RUN TestReOpen/Normal/Basics 2026/11/06 01:54:32 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/ErrorAtStart === RUN TestReOpen/Normal/WithErrors 2026/11/06 01:54:32 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/11/06 01:54:32 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/11/06 01:54:32 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/Normal/TooManyErrors 2026/11/06 01:54:32 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/11/06 01:54:32 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/11/06 01:54:32 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/11/06 01:54:32 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/Normal/ReadAt 2026/11/06 01:54:32 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/11/06 01:54:32 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/11/06 01:54:32 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/Normal/Seek 2026/11/06 01:54:32 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/11/06 01:54:32 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/11/06 01:54:32 DEBUG : potato: Seek from 5 to 2 === RUN TestReOpen/Normal/AccountRead === RUN TestReOpen/Normal/AccountReadDelay 2026/11/06 01:54:32 DEBUG : potato: Seek from 10 to 0 2026/11/06 01:54:32 DEBUG : potato: Seek from 10 to 0 2026/11/06 01:54:32 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/AccountReadError === RUN TestReOpen/WithRangeOption === RUN TestReOpen/WithRangeOption/Basics 2026/11/06 01:54:32 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/ErrorAtStart === RUN TestReOpen/WithRangeOption/WithErrors 2026/11/06 01:54:32 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/11/06 01:54:32 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/11/06 01:54:32 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithRangeOption/TooManyErrors 2026/11/06 01:54:32 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/11/06 01:54:32 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/11/06 01:54:32 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/11/06 01:54:32 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithRangeOption/ReadAt 2026/11/06 01:54:32 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/11/06 01:54:32 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/11/06 01:54:32 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/WithRangeOption/Seek 2026/11/06 01:54:32 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/11/06 01:54:32 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/11/06 01:54:32 DEBUG : potato: Seek from 5 to 2 2026/11/06 01:54:32 DEBUG : potato: Seek from 7 to 4 === RUN TestReOpen/WithRangeOption/AccountRead === RUN TestReOpen/WithRangeOption/AccountReadDelay 2026/11/06 01:54:32 DEBUG : potato: Seek from 7 to 0 2026/11/06 01:54:32 DEBUG : potato: Seek from 7 to 0 2026/11/06 01:54:32 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/AccountReadError === RUN TestReOpen/WithSeekOption === RUN TestReOpen/WithSeekOption/Basics 2026/11/06 01:54:32 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/ErrorAtStart === RUN TestReOpen/WithSeekOption/WithErrors 2026/11/06 01:54:32 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/11/06 01:54:32 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/11/06 01:54:32 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithSeekOption/TooManyErrors 2026/11/06 01:54:32 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/11/06 01:54:32 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/11/06 01:54:32 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/11/06 01:54:32 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithSeekOption/ReadAt 2026/11/06 01:54:32 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/11/06 01:54:32 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/11/06 01:54:32 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/WithSeekOption/Seek 2026/11/06 01:54:32 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/11/06 01:54:32 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/11/06 01:54:32 DEBUG : potato: Seek from 5 to 2 2026/11/06 01:54:32 DEBUG : potato: Seek from 7 to 5 === RUN TestReOpen/WithSeekOption/AccountRead === RUN TestReOpen/WithSeekOption/AccountReadDelay 2026/11/06 01:54:32 DEBUG : potato: Seek from 8 to 0 2026/11/06 01:54:32 DEBUG : potato: Seek from 8 to 0 2026/11/06 01:54:32 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/AccountReadError === RUN TestReOpen/UnknownSize === RUN TestReOpen/UnknownSize/Basics 2026/11/06 01:54:32 DEBUG : potato: Seek from 9 to 0 === RUN TestReOpen/UnknownSize/ErrorAtStart === RUN TestReOpen/UnknownSize/WithErrors 2026/11/06 01:54:32 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/11/06 01:54:32 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/11/06 01:54:32 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/UnknownSize/TooManyErrors 2026/11/06 01:54:32 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/11/06 01:54:32 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/11/06 01:54:32 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/11/06 01:54:32 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/UnknownSize/ReadAt 2026/11/06 01:54:32 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/11/06 01:54:32 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/11/06 01:54:32 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/UnknownSize/Seek 2026/11/06 01:54:32 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/11/06 01:54:32 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/11/06 01:54:32 DEBUG : potato: Seek from 5 to 2 2026/11/06 01:54:32 DEBUG : potato: Seek from 7 to 6 === RUN TestReOpen/UnknownSize/AccountRead === RUN TestReOpen/UnknownSize/AccountReadDelay 2026/11/06 01:54:32 DEBUG : potato: Seek from 9 to 0 2026/11/06 01:54:32 DEBUG : potato: Seek from 9 to 0 2026/11/06 01:54:32 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-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 01:54:34 DEBUG : rutabaga: md5 = 437ff300a035feb9894c3aadf3533eff OK === RUN TestCheck/1 === RUN TestCheck/2 2026/11/06 01:54:36 DEBUG : empty space: md5 = 3235d8106afee42d3d43957fe0215570 OK === RUN TestCheck/3 2026/11/06 01:54:38 DEBUG : potato2: md5 = 82bb4ddcb87f7dde3b0bc258b7e5a8a4 OK === RUN TestCheck/4 === RUN TestCheck/5 2026/11/06 01:54:41 DEBUG : remotepotato: md5 = fab34f2fa194daead017b3ae0d68d6e1 OK === RUN TestCheck/6 === RUN TestCheck/7 --- PASS: TestCheck (11.51s) --- PASS: TestCheck/1 (0.27s) --- PASS: TestCheck/2 (0.23s) --- PASS: TestCheck/3 (0.24s) --- PASS: TestCheck/4 (0.24s) --- PASS: TestCheck/5 (0.24s) --- PASS: TestCheck/6 (0.25s) --- PASS: TestCheck/7 (0.25s) === RUN TestCheckFsError 2026/11/06 01:54:44 DEBUG : Creating backend with remote "nonexistent" 2026/11/06 01:54:44 DEBUG : Creating backend with remote "nonexistent" 2026/11/06 01:54:44 DEBUG : Local file system at nonexistent: Waiting for checks to finish 2026/11/06 01:54:44 ERROR : Local file system at nonexistent: error reading source root directory: directory not found 2026/11/06 01:54:44 NOTICE: Local file system at nonexistent: 0 differences found 2026/11/06 01:54:44 NOTICE: Local file system at nonexistent: 2 errors while checking --- PASS: TestCheckFsError (0.00s) === RUN TestCheckDownload run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 01:54:45 DEBUG : rutabaga: md5 = fec989024129a9898509aaf7709a1039 OK === RUN TestCheckDownload/1 === RUN TestCheckDownload/2 2026/11/06 01:54:48 DEBUG : empty space: md5 = 9d0538710db95d1af09c0602aeb93f4e OK === RUN TestCheckDownload/3 2026/11/06 01:54:51 DEBUG : potato2: md5 = 4735c2ae26529f65caeb408a88ff53e4 OK === RUN TestCheckDownload/4 === RUN TestCheckDownload/5 2026/11/06 01:54:54 DEBUG : remotepotato: md5 = ec1b93bac619fec93b1d0fca1a47f30e OK === RUN TestCheckDownload/6 === RUN TestCheckDownload/7 --- PASS: TestCheckDownload (14.77s) --- PASS: TestCheckDownload/1 (0.88s) --- PASS: TestCheckDownload/2 (0.85s) --- PASS: TestCheckDownload/3 (0.79s) --- PASS: TestCheckDownload/4 (0.90s) --- PASS: TestCheckDownload/5 (0.75s) --- PASS: TestCheckDownload/6 (0.79s) --- PASS: TestCheckDownload/7 (0.90s) === RUN TestCheckSizeOnly run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 01:55:00 DEBUG : rutabaga: md5 = a4a60bbf5c1ae395538a25284e13b3f3 OK === RUN TestCheckSizeOnly/1 === RUN TestCheckSizeOnly/2 2026/11/06 01:55:02 DEBUG : empty space: md5 = a844b71a673ee02bb26695393ba8dc08 OK === RUN TestCheckSizeOnly/3 2026/11/06 01:55:04 DEBUG : potato2: md5 = 734608138edeca75038b09e67f2916b2 OK === RUN TestCheckSizeOnly/4 === RUN TestCheckSizeOnly/5 2026/11/06 01:55:06 DEBUG : remotepotato: md5 = 04f322867fdec59cab0819408c771723 OK === RUN TestCheckSizeOnly/6 === RUN TestCheckSizeOnly/7 --- PASS: TestCheckSizeOnly (10.98s) --- PASS: TestCheckSizeOnly/1 (0.24s) --- PASS: TestCheckSizeOnly/2 (0.21s) --- PASS: TestCheckSizeOnly/3 (0.26s) --- PASS: TestCheckSizeOnly/4 (0.25s) --- PASS: TestCheckSizeOnly/5 (0.24s) --- PASS: TestCheckSizeOnly/6 (0.27s) --- PASS: TestCheckSizeOnly/7 (0.24s) === RUN TestCheckEqualReaders --- PASS: TestCheckEqualReaders (0.00s) === RUN TestParseSumFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 01:55:11 DEBUG : test.sum: md5 = 692ff8a8be864a408fe4df738f9557a5 OK 2026/11/06 01:55:13 NOTICE: test.sum: improperly formatted checksum line 4 2026/11/06 01:55:13 NOTICE: test.sum: improperly formatted checksum line 5 2026/11/06 01:55:13 NOTICE: test.sum: improperly formatted checksum line 6 2026/11/06 01:55:13 NOTICE: test.sum: 2 warning(s) suppressed... 2026/11/06 01:55:16 DEBUG : test.sum: md5 = f8f1567d199d55943c7d4b7be4a39e19 OK 2026/11/06 01:55:16 NOTICE: test.sum: improperly formatted checksum line 4 2026/11/06 01:55:16 NOTICE: test.sum: improperly formatted checksum line 5 2026/11/06 01:55:16 NOTICE: test.sum: improperly formatted checksum line 6 2026/11/06 01:55:16 NOTICE: test.sum: 2 warning(s) suppressed... --- PASS: TestParseSumFile (8.10s) === RUN TestCheckSum run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 01:55:17 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-gevuxol5nara/data" 2026/11/06 01:55:17 DEBUG : Config file has changed externally - reloading 2026/11/06 01:55:17 DEBUG : Creating backend with remote "TestDrive:crypt/o954njvhu8i73jqo00n8k29tcik3fbkejdsvjlmnh110c5qaqgog/vjrnln8ratgmqakfosrqe8espk" check_test.go:354: Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara/data' lacks md5, skipping --- SKIP: TestCheckSum (1.91s) === RUN TestCheckSumDownload run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 01:55:19 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-gevuxol5nara/data" 2026/11/06 01:55:19 DEBUG : Creating backend with remote "TestDrive:crypt/o954njvhu8i73jqo00n8k29tcik3fbkejdsvjlmnh110c5qaqgog/vjrnln8ratgmqakfosrqe8espk" 2026/11/06 01:55:23 DEBUG : data/banana: md5 = f079c34a6e5b47901e7cfc3151cc0dfb OK 2026/11/06 01:55:25 DEBUG : test.sum: md5 = 9c33a00c65f8740eec8afb3faf5b4ce2 OK === RUN TestCheckSumDownload/subtest1 2026/11/06 01:55:28 DEBUG : data/potato: md5 = 811505d828e44282deeb5bedbfc1b3ca OK 2026/11/06 01:55:30 DEBUG : test.sum: md5 = 3b557b90d2ebe60f1c98aed38a345fe5 OK === RUN TestCheckSumDownload/subtest2 2026/11/06 01:55:33 DEBUG : test.sum: md5 = c2c8480cfb08a1273adfd9dd3c529957 OK === RUN TestCheckSumDownload/subtest3 2026/11/06 01:55:37 DEBUG : test.sum: md5 = eed29fe714d8bb3555097824de324d7f OK === RUN TestCheckSumDownload/subtest4 2026/11/06 01:55:41 DEBUG : test.sum: md5 = 3eef453678354c6d8d3f3dafa12bc994 OK === RUN TestCheckSumDownload/subtest5 2026/11/06 01:55:45 DEBUG : test.sum: md5 = dab963a93bf9071a00194fa58ba627ca OK === RUN TestCheckSumDownload/subtest6 2026/11/06 01:55:49 DEBUG : data/banana: md5 = 8e349969574022010f385394e586f22f OK 2026/11/06 01:55:51 DEBUG : data/potato: md5 = ba6aecaf701f7f3898de9811dc931524 OK 2026/11/06 01:55:52 DEBUG : test.sum: md5 = 6dc1d0792863851776010085eb3165d2 OK === RUN TestCheckSumDownload/subtest7 --- PASS: TestCheckSumDownload (37.94s) --- PASS: TestCheckSumDownload/subtest1 (2.16s) --- PASS: TestCheckSumDownload/subtest2 (1.71s) --- PASS: TestCheckSumDownload/subtest3 (1.72s) --- PASS: TestCheckSumDownload/subtest4 (1.77s) --- PASS: TestCheckSumDownload/subtest5 (1.73s) --- PASS: TestCheckSumDownload/subtest6 (1.84s) --- PASS: TestCheckSumDownload/subtest7 (1.87s) === RUN TestCheckSumConcurrency 2026/11/06 01:55:57 DEBUG : file0: md5 = 022eb0c0085c731f335df970fafa7446 OK 2026/11/06 01:55:57 DEBUG : file2: md5 = a58f09ae3db8ae4b943fdbaace096072 OK 2026/11/06 01:55:57 DEBUG : file1: md5 = 17fbabbccbdea0e6be9eb64c8c6fea41 OK 2026/11/06 01:55:57 DEBUG : file3: md5 = e0639b17e0744aca9a3ba29c049e59ee OK 2026/11/06 01:55:57 NOTICE: Mock file system at checkSum: 0 differences found 2026/11/06 01:55:57 NOTICE: Mock file system at checkSum: 4 matching files --- PASS: TestCheckSumConcurrency (0.00s) === RUN TestCheckSumDownloadConcurrency 2026/11/06 01:55:57 DEBUG : file0: md5 = 022eb0c0085c731f335df970fafa7446 OK 2026/11/06 01:55:57 DEBUG : file2: md5 = a58f09ae3db8ae4b943fdbaace096072 OK 2026/11/06 01:55:57 DEBUG : file3: md5 = e0639b17e0744aca9a3ba29c049e59ee OK 2026/11/06 01:55:57 DEBUG : file1: md5 = 17fbabbccbdea0e6be9eb64c8c6fea41 OK 2026/11/06 01:55:57 NOTICE: Mock file system at checkSum: 0 differences found 2026/11/06 01:55:57 NOTICE: Mock file system at checkSum: 4 matching files --- PASS: TestCheckSumDownloadConcurrency (0.00s) === RUN TestApplyTransforms 2026/11/06 01:55:57 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-selalav4gihi" 2026/11/06 01:55:57 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/11/06 01:55:57 DEBUG : Creating backend with remote "TestDrive:crypt/sjdge3jbvf2cmrnjcvc63o6rklavi8e8mubpadiv23d02m3k8at0" 2026/11/06 01:55:58 DEBUG : Creating backend with remote "/tmp/rclone2367287907" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-selalav4gihi'", Local "Local file system at /tmp/rclone2367287907", Modify Window "1ms" 2026/11/06 01:56:01 DEBUG : hello, world!: md5 = c3d74fea4319c0a6152df1fc23093315 OK upper checkfile vs. lower remote (without normalization) 2026/11/06 01:56:01 ERROR : hello, world!: sum not found 2026/11/06 01:56:01 ERROR : HELLO, WORLD!: file not in Encrypted drive 'TestCryptDrive:rclone-test-selalav4gihi' 2026/11/06 01:56:01 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-selalav4gihi': 1 files missing 2026/11/06 01:56:01 NOTICE: 1 hashes missing 2026/11/06 01:56:01 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-selalav4gihi': 1 differences found 2026/11/06 01:56:01 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-selalav4gihi': 2 errors while checking upper checkfile vs. lower remote (with normalization) 2026/11/06 01:56:02 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/11/06 01:56:02 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-selalav4gihi': 0 differences found 2026/11/06 01:56:02 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-selalav4gihi': 1 matching files 2026/11/06 01:56:02 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-kapasuf0vuhe" 2026/11/06 01:56:02 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/11/06 01:56:02 DEBUG : Creating backend with remote "TestDrive:crypt/gl67gd78h5ri9tcbu7re0hktbu63q6fr31oko4md0bceda3vlqfg" 2026/11/06 01:56:03 DEBUG : Creating backend with remote "/tmp/rclone3588716442" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kapasuf0vuhe'", Local "Local file system at /tmp/rclone3588716442", Modify Window "1ms" 2026/11/06 01:56:06 DEBUG : HELLO, WORLD!: md5 = 522709cfe42a7ca14471b9e263e00383 OK lower checkfile vs. upper remote (without normalization) 2026/11/06 01:56:06 ERROR : HELLO, WORLD!: sum not found 2026/11/06 01:56:06 ERROR : hello, world!: file not in Encrypted drive 'TestCryptDrive:rclone-test-kapasuf0vuhe' 2026/11/06 01:56:06 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kapasuf0vuhe': 1 files missing 2026/11/06 01:56:06 NOTICE: 1 hashes missing 2026/11/06 01:56:06 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kapasuf0vuhe': 1 differences found 2026/11/06 01:56:06 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kapasuf0vuhe': 2 errors while checking lower checkfile vs. upper remote (with normalization) 2026/11/06 01:56:07 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/11/06 01:56:07 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kapasuf0vuhe': 0 differences found 2026/11/06 01:56:07 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kapasuf0vuhe': 1 matching files 2026/11/06 01:56:07 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-fazixim8woqo" 2026/11/06 01:56:07 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/11/06 01:56:07 DEBUG : Creating backend with remote "TestDrive:crypt/01e59g4gj4dr6cvl1josgv6m5nbvl0ba0i074vi27h34ao03qcbg" 2026/11/06 01:56:08 DEBUG : Creating backend with remote "/tmp/rclone701109546" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fazixim8woqo'", Local "Local file system at /tmp/rclone701109546", Modify Window "1ms" 2026/11/06 01:56:11 DEBUG : HeLlO, wOrLd!: md5 = 7a46a2e5c237762f9077c6cafd328be5 OK lower checkfile vs. upperlowermixed remote (without normalization) 2026/11/06 01:56:11 ERROR : HeLlO, wOrLd!: sum not found 2026/11/06 01:56:11 ERROR : hello, world!: file not in Encrypted drive 'TestCryptDrive:rclone-test-fazixim8woqo' 2026/11/06 01:56:11 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fazixim8woqo': 1 files missing 2026/11/06 01:56:11 NOTICE: 1 hashes missing 2026/11/06 01:56:11 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fazixim8woqo': 1 differences found 2026/11/06 01:56:11 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fazixim8woqo': 2 errors while checking lower checkfile vs. upperlowermixed remote (with normalization) 2026/11/06 01:56:12 DEBUG : HeLlO, wOrLd!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/11/06 01:56:12 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fazixim8woqo': 0 differences found 2026/11/06 01:56:12 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fazixim8woqo': 1 matching files 2026/11/06 01:56:12 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-kakifox8nome" 2026/11/06 01:56:12 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/11/06 01:56:12 DEBUG : Creating backend with remote "TestDrive:crypt/telqb26jrktesurcfd9d8f36hj22d3qj5o0atcdobtvbqhe2g52g" 2026/11/06 01:56:13 DEBUG : Creating backend with remote "/tmp/rclone3414742525" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kakifox8nome'", Local "Local file system at /tmp/rclone3414742525", Modify Window "1ms" 2026/11/06 01:56:15 DEBUG : HELLO, WORLD!: md5 = bc7db01c8537f3828e9553a0c0f93bf0 OK upperlowermixed checkfile vs. upper remote (without normalization) 2026/11/06 01:56:16 ERROR : HELLO, WORLD!: sum not found 2026/11/06 01:56:16 ERROR : HeLlO, wOrLd!: file not in Encrypted drive 'TestCryptDrive:rclone-test-kakifox8nome' 2026/11/06 01:56:16 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kakifox8nome': 1 files missing 2026/11/06 01:56:16 NOTICE: 1 hashes missing 2026/11/06 01:56:16 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kakifox8nome': 1 differences found 2026/11/06 01:56:16 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kakifox8nome': 2 errors while checking upperlowermixed checkfile vs. upper remote (with normalization) 2026/11/06 01:56:17 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/11/06 01:56:17 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kakifox8nome': 0 differences found 2026/11/06 01:56:17 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kakifox8nome': 1 matching files 2026/11/06 01:56:17 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-vujajed1waci" 2026/11/06 01:56:17 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/11/06 01:56:17 DEBUG : Creating backend with remote "TestDrive:crypt/8easmeinutt5pipusdphrq03keasadbo57e6knud5ikvn6qo2m80" 2026/11/06 01:56:18 DEBUG : Creating backend with remote "/tmp/rclone1669798378" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-vujajed1waci'", Local "Local file system at /tmp/rclone1669798378", Modify Window "1ms" 2026/11/06 01:56:20 DEBUG : 測試_Русский___ě_áñ: md5 = e39a8cdbc21a3564c69fa9fec6d06a17 OK NFD checkfile vs. NFC remote (without normalization) 2026/11/06 01:56:21 ERROR : 測試_Русский___ě_áñ: sum not found 2026/11/06 01:56:21 ERROR : 測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-vujajed1waci' 2026/11/06 01:56:21 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-vujajed1waci': 1 files missing 2026/11/06 01:56:21 NOTICE: 1 hashes missing 2026/11/06 01:56:21 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-vujajed1waci': 1 differences found 2026/11/06 01:56:21 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-vujajed1waci': 2 errors while checking NFD checkfile vs. NFC remote (with normalization) 2026/11/06 01:56:22 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/11/06 01:56:22 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-vujajed1waci': 0 differences found 2026/11/06 01:56:22 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-vujajed1waci': 1 matching files 2026/11/06 01:56:22 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-maqahux3huti" 2026/11/06 01:56:22 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/11/06 01:56:22 DEBUG : Creating backend with remote "TestDrive:crypt/htl86gog8lv4739ruo1sr2mg3vm44aol55fnnknbo1asljn831dg" 2026/11/06 01:56:23 DEBUG : Creating backend with remote "/tmp/rclone1592599297" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-maqahux3huti'", Local "Local file system at /tmp/rclone1592599297", Modify Window "1ms" 2026/11/06 01:56:25 DEBUG : 測試_Русский___ě_áñ: md5 = 3e4447d8048a8299a6818d5410a39a4b OK NFC checkfile vs. NFD remote (without normalization) 2026/11/06 01:56:25 ERROR : 測試_Русский___ě_áñ: sum not found 2026/11/06 01:56:25 ERROR : 測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-maqahux3huti' 2026/11/06 01:56:25 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-maqahux3huti': 1 files missing 2026/11/06 01:56:25 NOTICE: 1 hashes missing 2026/11/06 01:56:25 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-maqahux3huti': 1 differences found 2026/11/06 01:56:25 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-maqahux3huti': 2 errors while checking NFC checkfile vs. NFD remote (with normalization) 2026/11/06 01:56:26 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/11/06 01:56:26 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-maqahux3huti': 0 differences found 2026/11/06 01:56:26 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-maqahux3huti': 1 matching files 2026/11/06 01:56:26 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-xeziwaw0zizi" 2026/11/06 01:56:26 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/11/06 01:56:26 DEBUG : Creating backend with remote "TestDrive:crypt/cmbbqbsoikr9ouva0lou9sesp5lj1o7spk4dmddffp0e7vbtt4p0" 2026/11/06 01:56:27 DEBUG : Creating backend with remote "/tmp/rclone1612225934" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-xeziwaw0zizi'", Local "Local file system at /tmp/rclone1612225934", Modify Window "1ms" 2026/11/06 01:56:30 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 3ea98a08f09f9f8c580719f8dd44352a OK NFDx2 checkfile vs. both remote (without normalization) 2026/11/06 01:56:30 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/11/06 01:56:30 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-xeziwaw0zizi' 2026/11/06 01:56:30 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-xeziwaw0zizi': 1 files missing 2026/11/06 01:56:30 NOTICE: 1 hashes missing 2026/11/06 01:56:30 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-xeziwaw0zizi': 1 differences found 2026/11/06 01:56:30 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-xeziwaw0zizi': 2 errors while checking NFDx2 checkfile vs. both remote (with normalization) 2026/11/06 01:56:31 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/11/06 01:56:31 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-xeziwaw0zizi': 0 differences found 2026/11/06 01:56:31 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-xeziwaw0zizi': 1 matching files 2026/11/06 01:56:31 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-cicapec3tova" 2026/11/06 01:56:31 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/11/06 01:56:31 DEBUG : Creating backend with remote "TestDrive:crypt/jqegh8m6ptu31kmv1feedhissopimk8e4nhkqdebsci737iqmc00" 2026/11/06 01:56:32 DEBUG : Creating backend with remote "/tmp/rclone760293146" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cicapec3tova'", Local "Local file system at /tmp/rclone760293146", Modify Window "1ms" 2026/11/06 01:56:34 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 9e0369e008a2ace6060ee191d648c7ae OK NFCx2 checkfile vs. both remote (without normalization) 2026/11/06 01:56:35 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/11/06 01:56:35 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-cicapec3tova' 2026/11/06 01:56:35 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-cicapec3tova': 1 files missing 2026/11/06 01:56:35 NOTICE: 1 hashes missing 2026/11/06 01:56:35 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-cicapec3tova': 1 differences found 2026/11/06 01:56:35 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-cicapec3tova': 2 errors while checking NFCx2 checkfile vs. both remote (with normalization) 2026/11/06 01:56:36 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/11/06 01:56:36 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-cicapec3tova': 0 differences found 2026/11/06 01:56:36 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-cicapec3tova': 1 matching files 2026/11/06 01:56:36 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-cibuxet3ledo" 2026/11/06 01:56:36 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/11/06 01:56:36 DEBUG : Creating backend with remote "TestDrive:crypt/9r2mnub00i45l10krao76qcjb6e6au5i63ctd1hhiblr0i3fa3t0" 2026/11/06 01:56:36 DEBUG : Creating backend with remote "/tmp/rclone1091861599" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cibuxet3ledo'", Local "Local file system at /tmp/rclone1091861599", Modify Window "1ms" 2026/11/06 01:56:39 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = fe0321689b85dd49006a2cbe5c98a7ca OK both checkfile vs. NFDx2 remote (without normalization) 2026/11/06 01:56:39 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/11/06 01:56:39 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-cibuxet3ledo' 2026/11/06 01:56:39 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-cibuxet3ledo': 1 files missing 2026/11/06 01:56:39 NOTICE: 1 hashes missing 2026/11/06 01:56:39 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-cibuxet3ledo': 1 differences found 2026/11/06 01:56:39 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-cibuxet3ledo': 2 errors while checking both checkfile vs. NFDx2 remote (with normalization) 2026/11/06 01:56:40 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/11/06 01:56:40 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-cibuxet3ledo': 0 differences found 2026/11/06 01:56:40 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-cibuxet3ledo': 1 matching files 2026/11/06 01:56:40 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-fonakop6rone" 2026/11/06 01:56:40 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/11/06 01:56:40 DEBUG : Creating backend with remote "TestDrive:crypt/apsnk7al954sumqia3dfp59bs0jb08n2vf93lachcm3rm88696qg" 2026/11/06 01:56:41 DEBUG : Creating backend with remote "/tmp/rclone1361024720" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fonakop6rone'", Local "Local file system at /tmp/rclone1361024720", Modify Window "1ms" 2026/11/06 01:56:44 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 4ecf3a7fe619a00ab2d2e34981728c14 OK both checkfile vs. NFCx2 remote (without normalization) 2026/11/06 01:56:44 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/11/06 01:56:44 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-fonakop6rone' 2026/11/06 01:56:44 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fonakop6rone': 1 files missing 2026/11/06 01:56:44 NOTICE: 1 hashes missing 2026/11/06 01:56:44 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fonakop6rone': 1 differences found 2026/11/06 01:56:44 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fonakop6rone': 2 errors while checking both checkfile vs. NFCx2 remote (with normalization) 2026/11/06 01:56:45 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/11/06 01:56:45 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fonakop6rone': 0 differences found 2026/11/06 01:56:45 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fonakop6rone': 1 matching files 2026/11/06 01:56:45 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-fonakop6rone': Purge remote 2026/11/06 01:56:45 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cibuxet3ledo': Purge remote 2026/11/06 01:56:46 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cicapec3tova': Purge remote 2026/11/06 01:56:46 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-xeziwaw0zizi': Purge remote 2026/11/06 01:56:47 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-maqahux3huti': Purge remote 2026/11/06 01:56:47 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-vujajed1waci': Purge remote 2026/11/06 01:56:48 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-kakifox8nome': Purge remote 2026/11/06 01:56:48 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-fazixim8woqo': Purge remote 2026/11/06 01:56:49 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-kapasuf0vuhe': Purge remote 2026/11/06 01:56:49 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-selalav4gihi': Purge remote --- PASS: TestApplyTransforms (52.57s) === RUN TestTruncateString --- PASS: TestTruncateString (0.00s) === RUN TestCopyFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 01:56:50 DEBUG : file1: Need to transfer - File not found at Destination 2026/11/06 01:56:52 DEBUG : sub/file2: md5 = 454b9ad0ab1307b809b6fed2ea5baf6a OK 2026/11/06 01:56:52 DEBUG : sub/file2: size = 14 OK 2026/11/06 01:56:52 INFO : file1: Copied (new) to: sub/file2 2026/11/06 01:56:53 DEBUG : sub/file2: size = 14 OK 2026/11/06 01:56:53 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/11/06 01:56:53 DEBUG : file1: Unchanged skipping 2026/11/06 01:56:54 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara': don't need to copy/move sub/file2, it is already at target location --- PASS: TestCopyFile (6.03s) === RUN TestCopyFileImmutable run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 01:56:56 DEBUG : existing: Need to transfer - File not found at Destination 2026/11/06 01:56:58 DEBUG : existing: md5 = c8d974289798ae14740d6f2d36fbb527 OK 2026/11/06 01:56:58 DEBUG : existing: size = 6 OK 2026/11/06 01:56:58 INFO : existing: Copied (new) 2026/11/06 01:56:58 DEBUG : existing: size = 6 OK 2026/11/06 01:56:58 DEBUG : existing: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/11/06 01:56:58 DEBUG : existing: Unchanged skipping 2026/11/06 01:56:58 DEBUG : existing: size = 8 (Local file system at /tmp/rclone707300349) 2026/11/06 01:56:58 DEBUG : existing: size = 6 (Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara') 2026/11/06 01:56:58 DEBUG : existing: Sizes differ 2026/11/06 01:56:58 ERROR : existing: Source and destination exist but do not match: immutable file modified --- PASS: TestCopyFileImmutable (4.02s) === RUN TestCopyLongFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" copy_test.go:188: Test only runs on local --- SKIP: TestCopyLongFile (0.42s) === RUN TestCopyFileBackupDir run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 01:57:03 DEBUG : dst/file1: md5 = ed5a0fdeb5243950a30b910e5370d5b4 OK 2026/11/06 01:57:03 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-gevuxol5nara/backup" 2026/11/06 01:57:03 DEBUG : Creating backend with remote "TestDrive:crypt/o954njvhu8i73jqo00n8k29tcik3fbkejdsvjlmnh110c5qaqgog/1nrff024r7pq65ecp72fc28jb0" 2026/11/06 01:57:05 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone707300349) 2026/11/06 01:57:05 DEBUG : dst/file1: size = 18 (Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara') 2026/11/06 01:57:05 DEBUG : dst/file1: Sizes differ 2026/11/06 01:57:07 INFO : dst/file1: Moved (server-side) 2026/11/06 01:57:09 DEBUG : dst/file1: md5 = d811aa947770b7fd8b739a08c1b1151f OK 2026/11/06 01:57:09 DEBUG : dst/file1: size = 14 OK 2026/11/06 01:57:09 INFO : dst/file1: Copied (new) --- PASS: TestCopyFileBackupDir (13.34s) === RUN TestCopyFileCompareDest run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 01:57:14 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-gevuxol5nara/dst" 2026/11/06 01:57:14 DEBUG : Creating backend with remote "TestDrive:crypt/o954njvhu8i73jqo00n8k29tcik3fbkejdsvjlmnh110c5qaqgog/31u3jie661vd5p8j7rtc3hgbh0" 2026/11/06 01:57:15 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-gevuxol5nara/CompareDest" 2026/11/06 01:57:15 DEBUG : Creating backend with remote "TestDrive:crypt/o954njvhu8i73jqo00n8k29tcik3fbkejdsvjlmnh110c5qaqgog/gveqi14airsml4bgu7krj116o8" 2026/11/06 01:57:17 DEBUG : one: Need to transfer - File not found at Destination 2026/11/06 01:57:19 DEBUG : one: md5 = ab20d9be29c08ffe85bb6b156d1340b9 OK 2026/11/06 01:57:19 DEBUG : one: size = 3 OK 2026/11/06 01:57:19 INFO : one: Copied (new) 2026/11/06 01:57:21 DEBUG : one: size = 5 (Local file system at /tmp/rclone707300349) 2026/11/06 01:57:21 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara/dst') 2026/11/06 01:57:21 DEBUG : one: Sizes differ 2026/11/06 01:57:22 DEBUG : one: md5 = ebe21001dd037baf05ff37a84c474cd2 OK 2026/11/06 01:57:22 DEBUG : one: size = 5 OK 2026/11/06 01:57:22 INFO : one: Copied (replaced existing) 2026/11/06 01:57:24 DEBUG : dst/one: md5 = 0b86a869c462b31bdfc23ba6ec29eb42 OK 2026/11/06 01:57:26 DEBUG : CompareDest/one: md5 = 8e634a7df2a2de9eaa1d5de84ab9c301 OK 2026/11/06 01:57:27 DEBUG : one: size = 5 (Local file system at /tmp/rclone707300349) 2026/11/06 01:57:27 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara/dst') 2026/11/06 01:57:27 DEBUG : one: Sizes differ 2026/11/06 01:57:28 DEBUG : one: size = 5 OK 2026/11/06 01:57:28 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/11/06 01:57:28 DEBUG : one: Destination found in --compare-dest, skipping 2026/11/06 01:57:30 DEBUG : CompareDest/two: md5 = cae0921a6e35976ac12b3bf546adb19c OK 2026/11/06 01:57:31 DEBUG : two: Need to transfer - File not found at Destination 2026/11/06 01:57:31 DEBUG : two: size = 3 OK 2026/11/06 01:57:31 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/11/06 01:57:31 DEBUG : two: Destination found in --compare-dest, skipping 2026/11/06 01:57:32 DEBUG : two: Need to transfer - File not found at Destination 2026/11/06 01:57:32 DEBUG : two: size = 3 OK 2026/11/06 01:57:32 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/11/06 01:57:32 DEBUG : two: Destination found in --compare-dest, skipping 2026/11/06 01:57:33 DEBUG : two: Need to transfer - File not found at Destination 2026/11/06 01:57:34 DEBUG : two: size = 5 (Local file system at /tmp/rclone707300349) 2026/11/06 01:57:34 DEBUG : two: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara/CompareDest') 2026/11/06 01:57:34 DEBUG : two: Sizes differ 2026/11/06 01:57:35 DEBUG : two: md5 = 7cc8e72cc33933f89f57dcf0e8e0839a OK 2026/11/06 01:57:35 DEBUG : two: size = 5 OK 2026/11/06 01:57:35 INFO : two: Copied (new) --- PASS: TestCopyFileCompareDest (26.22s) === RUN TestCopyFileCopyDestImmutable run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 01:57:40 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-gevuxol5nara/dst" 2026/11/06 01:57:40 DEBUG : Creating backend with remote "TestDrive:crypt/o954njvhu8i73jqo00n8k29tcik3fbkejdsvjlmnh110c5qaqgog/31u3jie661vd5p8j7rtc3hgbh0" 2026/11/06 01:57:44 DEBUG : dst/one: md5 = ad0787c3e9b7092195bb2e91124c35a2 OK 2026/11/06 01:57:46 DEBUG : CopyDest/one: md5 = 7622098e0ba19423460a2ee89e3c1bbc OK 2026/11/06 01:57:47 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-gevuxol5nara/CopyDest" 2026/11/06 01:57:47 DEBUG : Creating backend with remote "TestDrive:crypt/o954njvhu8i73jqo00n8k29tcik3fbkejdsvjlmnh110c5qaqgog/d09o6po3f7bm6ce32vdgs8h9ls" 2026/11/06 01:57:48 DEBUG : one: size = 5 (Local file system at /tmp/rclone707300349) 2026/11/06 01:57:48 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara/dst') 2026/11/06 01:57:48 DEBUG : one: Sizes differ 2026/11/06 01:57:48 DEBUG : one: size = 5 OK 2026/11/06 01:57:48 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/11/06 01:57:48 DEBUG : one: size = 5 (Local file system at /tmp/rclone707300349) 2026/11/06 01:57:48 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara/dst') 2026/11/06 01:57:48 DEBUG : one: Sizes differ 2026/11/06 01:57:48 ERROR : one: Source and destination exist but do not match: immutable file modified --- PASS: TestCopyFileCopyDestImmutable (11.66s) === RUN TestCopyFileCopyDest run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 01:57:52 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-gevuxol5nara/dst" 2026/11/06 01:57:52 DEBUG : Creating backend with remote "TestDrive:crypt/o954njvhu8i73jqo00n8k29tcik3fbkejdsvjlmnh110c5qaqgog/31u3jie661vd5p8j7rtc3hgbh0" 2026/11/06 01:57:53 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-gevuxol5nara/CopyDest" 2026/11/06 01:57:53 DEBUG : Creating backend with remote "TestDrive:crypt/o954njvhu8i73jqo00n8k29tcik3fbkejdsvjlmnh110c5qaqgog/d09o6po3f7bm6ce32vdgs8h9ls" 2026/11/06 01:57:55 DEBUG : one: Need to transfer - File not found at Destination 2026/11/06 01:57:57 DEBUG : one: md5 = e6b6b50c8176a5282520dae13204c5b5 OK 2026/11/06 01:57:57 DEBUG : one: size = 3 OK 2026/11/06 01:57:57 INFO : one: Copied (new) 2026/11/06 01:57:58 DEBUG : one: size = 5 (Local file system at /tmp/rclone707300349) 2026/11/06 01:57:58 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara/dst') 2026/11/06 01:57:58 DEBUG : one: Sizes differ 2026/11/06 01:58:00 DEBUG : one: md5 = 0dea621ef7c3c0d5def1022a97a1458f OK 2026/11/06 01:58:00 DEBUG : one: size = 5 OK 2026/11/06 01:58:00 INFO : one: Copied (replaced existing) 2026/11/06 01:58:02 DEBUG : dst/one: md5 = f373054b0d9f82d81df16fdb3cbdd758 OK 2026/11/06 01:58:04 DEBUG : CopyDest/one: md5 = 0fb4ea974a128a6906a515746f43c289 OK 2026/11/06 01:58:05 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-gevuxol5nara/BackupDir" 2026/11/06 01:58:05 DEBUG : Creating backend with remote "TestDrive:crypt/o954njvhu8i73jqo00n8k29tcik3fbkejdsvjlmnh110c5qaqgog/s6dbk3lfi7c9kfvo6j7bla9m0g" 2026/11/06 01:58:06 DEBUG : one: size = 5 (Local file system at /tmp/rclone707300349) 2026/11/06 01:58:06 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara/dst') 2026/11/06 01:58:06 DEBUG : one: Sizes differ 2026/11/06 01:58:07 DEBUG : one: size = 5 OK 2026/11/06 01:58:07 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/11/06 01:58:07 DEBUG : one: size = 5 (Local file system at /tmp/rclone707300349) 2026/11/06 01:58:07 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara/dst') 2026/11/06 01:58:07 DEBUG : one: Sizes differ 2026/11/06 01:58:08 INFO : one: Moved (server-side) 2026/11/06 01:58:10 DEBUG : one: size = 5 OK 2026/11/06 01:58:10 INFO : one: Copied (server-side copy) 2026/11/06 01:58:10 DEBUG : one: Destination found in --copy-dest, using server-side copy 2026/11/06 01:58:11 DEBUG : CopyDest/two: md5 = 04931a9747429ac388c3a695834e66cc OK 2026/11/06 01:58:12 DEBUG : two: Need to transfer - File not found at Destination 2026/11/06 01:58:13 DEBUG : two: size = 3 OK 2026/11/06 01:58:13 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/11/06 01:58:14 DEBUG : two: size = 3 OK 2026/11/06 01:58:14 INFO : two: Copied (server-side copy) 2026/11/06 01:58:14 DEBUG : two: Destination found in --copy-dest, using server-side copy 2026/11/06 01:58:15 DEBUG : two: size = 3 OK 2026/11/06 01:58:15 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/11/06 01:58:15 DEBUG : two: Unchanged skipping 2026/11/06 01:58:17 DEBUG : CopyDest/three: md5 = 66764969e58b50b1d0dbfcd41340df1a OK 2026/11/06 01:58:17 DEBUG : three: Need to transfer - File not found at Destination 2026/11/06 01:58:18 DEBUG : three: size = 7 (Local file system at /tmp/rclone707300349) 2026/11/06 01:58:18 DEBUG : three: size = 5 (Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara/CopyDest') 2026/11/06 01:58:18 DEBUG : three: Sizes differ 2026/11/06 01:58:18 DEBUG : three: Destination not found in --copy-dest 2026/11/06 01:58:19 DEBUG : three: md5 = 3b75adebca76bf9b80f73a8472305d8c OK 2026/11/06 01:58:19 DEBUG : three: size = 7 OK 2026/11/06 01:58:19 INFO : three: Copied (new) --- PASS: TestCopyFileCopyDest (34.17s) === RUN TestCopyInplace run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" copy_test.go:434: Partial uploads not supported --- SKIP: TestCopyInplace (0.42s) === RUN TestCopyLongFileName run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" copy_test.go:467: Partial uploads not supported --- SKIP: TestCopyLongFileName (0.45s) === RUN TestCopyLongFileNameCollision run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" copy_test.go:500: Partial uploads not supported --- SKIP: TestCopyLongFileNameCollision (0.47s) === RUN TestCopyFileMaxTransfer run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 01:58:27 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination 2026/11/06 01:58:30 DEBUG : TestCopyFileMaxTransfer/file1: md5 = 7b30d23fdf3544b62dc83c7e67341eb4 OK 2026/11/06 01:58:30 DEBUG : TestCopyFileMaxTransfer/file1: size = 14 OK 2026/11/06 01:58:30 INFO : TestCopyFileMaxTransfer/file1: Copied (new) 2026/11/06 01:58:30 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination 2026/11/06 01:58:31 ERROR : TestCopyFileMaxTransfer/file2: Failed to copy: Post "https://www.googleapis.com/upload/drive/v3/files?alt=json&fields=id%2Cname%2Csize%2Cmd5Checksum%2Csha1Checksum%2Csha256Checksum%2Ctrashed%2CexplicitlyTrashed%2CmodifiedTime%2CcreatedTime%2CmimeType%2Cparents%2CwebViewLink%2CshortcutDetails%2CexportLinks%2CresourceKey&keepRevisionForever=false&prettyPrint=false&supportsAllDrives=true&uploadType=multipart": googleapi: Copy failed: max transfer limit reached as set by --max-transfer copy_test.go:563: Expecting error to contain accounting.ErrorMaxTransferLimitReachedFatal 2026/11/06 01:58:31 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination 2026/11/06 01:58:32 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination 2026/11/06 01:58:33 DEBUG : TestCopyFileMaxTransfer/file4: md5 = 40dfb7b5beaf66833e90e54a1e9714b6 OK 2026/11/06 01:58:33 DEBUG : TestCopyFileMaxTransfer/file4: size = 2062 OK 2026/11/06 01:58:33 INFO : TestCopyFileMaxTransfer/file4: Copied (new) --- PASS: TestCopyFileMaxTransfer (9.25s) === RUN TestDeduplicateInteractive run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" dedupe_test.go:37: Can't run this test without a hash --- SKIP: TestDeduplicateInteractive (0.39s) === RUN TestDeduplicateSkip run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 01:58:39 INFO : Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara': Looking for duplicate names using skip mode. 2026/11/06 01:58:40 NOTICE: one: Found 2 files with duplicate names 2026/11/06 01:58:40 NOTICE: one: Skipping 2 files with duplicate names --- PASS: TestDeduplicateSkip (4.83s) === RUN TestDeduplicateSizeOnly run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 01:58:46 INFO : Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara': Looking for duplicate names using skip mode. 2026/11/06 01:58:46 NOTICE: one: Found 3 files with duplicate names 2026/11/06 01:58:46 NOTICE: one: Deleting 1/2 identical duplicates (size 11) 2026/11/06 01:58:47 INFO : one: Deleted 2026/11/06 01:58:47 NOTICE: one: Skipping 2 files with duplicate names --- PASS: TestDeduplicateSizeOnly (6.90s) === RUN TestDeduplicateFirst run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 01:59:06 INFO : Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara': Looking for duplicate names using first mode. 2026/11/06 01:59:06 NOTICE: one: Found 3 files with duplicate names 2026/11/06 01:59:07 INFO : one: Deleted 2026/11/06 01:59:07 INFO : one: Deleted 2026/11/06 01:59:07 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateFirst (19.84s) === RUN TestDeduplicateNewest run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 01:59:16 INFO : Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara': Looking for duplicate names using newest mode. 2026/11/06 01:59:17 NOTICE: one: Found 3 files with duplicate names 2026/11/06 01:59:17 INFO : one: Deleted 2026/11/06 01:59:18 INFO : one: Deleted 2026/11/06 01:59:18 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateNewest (10.61s) === RUN TestDeduplicateNewestByHash run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" dedupe_test.go:37: Can't run this test without a hash --- SKIP: TestDeduplicateNewestByHash (0.47s) === RUN TestDeduplicateOldest run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 01:59:23 INFO : Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara': Looking for duplicate names using oldest mode. 2026/11/06 01:59:24 NOTICE: one: Found 3 files with duplicate names 2026/11/06 01:59:24 INFO : one: Deleted 2026/11/06 01:59:25 INFO : one: Deleted 2026/11/06 01:59:25 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateOldest (6.76s) === RUN TestDeduplicateLargest run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 01:59:30 INFO : Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara': Looking for duplicate names using largest mode. 2026/11/06 01:59:30 NOTICE: one: Found 3 files with duplicate names 2026/11/06 01:59:31 INFO : one: Deleted 2026/11/06 01:59:31 INFO : one: Deleted 2026/11/06 01:59:31 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateLargest (6.71s) === RUN TestDeduplicateSmallest run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 01:59:37 INFO : Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara': Looking for duplicate names using smallest mode. 2026/11/06 01:59:37 NOTICE: one: Found 3 files with duplicate names 2026/11/06 01:59:38 INFO : one: Deleted 2026/11/06 01:59:38 INFO : one: Deleted 2026/11/06 01:59:38 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateSmallest (6.55s) === RUN TestDeduplicateRename run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 01:59:45 INFO : Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara': Looking for duplicate names using rename mode. 2026/11/06 01:59:46 NOTICE: one.txt: Found 3 files with duplicate names 2026/11/06 01:59:46 INFO : one-2.txt: renamed from: one.txt 2026/11/06 01:59:47 INFO : one-3.txt: renamed from: one.txt 2026/11/06 01:59:47 INFO : one-4.txt: renamed from: one.txt --- PASS: TestDeduplicateRename (10.68s) === RUN TestDeduplicateRenameManyExisting run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 01:59:51 DEBUG : one-1.txt: md5 = 65fb8b69249cf81b026ed041d68e48cf OK 2026/11/06 01:59:55 DEBUG : one-2.txt: md5 = ec4c89993ac784e904dbe881edae8d5c OK 2026/11/06 01:59:57 DEBUG : one-3.txt: md5 = 2f1cd52441d2470fd70e398b9d810f50 OK 2026/11/06 01:59:58 DEBUG : one-4.txt: md5 = 44dca583f3c089b829e9ecef1e701048 OK 2026/11/06 01:59:59 DEBUG : one-5.txt: md5 = 039c86f43dfe721ca6ad1ef6918804d7 OK 2026/11/06 02:00:01 DEBUG : one-6.txt: md5 = 789f7a3f0d732a9c23d3af3827cfed8a OK 2026/11/06 02:00:03 DEBUG : one-7.txt: md5 = cec68895985438ed317a0906f27ff094 OK 2026/11/06 02:00:04 DEBUG : one-8.txt: md5 = bc22da2293e61e895b4ece6312ad3ec4 OK 2026/11/06 02:00:06 DEBUG : one-9.txt: md5 = 00d32e46e130d8f3bd734115580fc4c1 OK 2026/11/06 02:00:07 DEBUG : one-10.txt: md5 = 947b8405861c95d03d2e26caed5c986a OK 2026/11/06 02:00:09 DEBUG : one-11.txt: md5 = 73257b2528bde4d086914995393a800f OK 2026/11/06 02:00:10 DEBUG : one-12.txt: md5 = b2a105cb0381b13caa38f6d6d01f0c6a OK 2026/11/06 02:00:12 DEBUG : one-13.txt: md5 = dc526b1affc2c586b208735d48373f8e OK 2026/11/06 02:00:13 DEBUG : one-14.txt: md5 = 658b87683a532ec767328a4c87799c3c OK 2026/11/06 02:00:15 DEBUG : one-15.txt: md5 = 5a1c6ae09d796cf10fa52708fcf790a1 OK 2026/11/06 02:00:17 DEBUG : one-16.txt: md5 = 0e22752b9c74d51d4550f3cb765dc549 OK 2026/11/06 02:00:19 DEBUG : one-17.txt: md5 = 07ed9d68e016cf66f54f62422eae4317 OK 2026/11/06 02:00:20 DEBUG : one-18.txt: md5 = f95e16644a4bb0852fe1b41dc01c05df OK 2026/11/06 02:00:22 DEBUG : one-19.txt: md5 = 05e33894e3bf5308c088f2d4817ec2ff OK 2026/11/06 02:00:24 DEBUG : one-20.txt: md5 = 821bad396148383094d9a61acad2415c OK 2026/11/06 02:00:25 DEBUG : one-21.txt: md5 = 6c93b81cef081c1dca4a52e99b746793 OK 2026/11/06 02:00:27 DEBUG : one-22.txt: md5 = df809e6a617a683280515725f9e3385e OK 2026/11/06 02:00:28 DEBUG : one-23.txt: md5 = c410b29e04bca7f3b2c6f096fb3ee9d2 OK 2026/11/06 02:00:30 DEBUG : one-24.txt: md5 = 2fdff6c5bfed7a97046c23fe030b1842 OK 2026/11/06 02:00:31 DEBUG : one-25.txt: md5 = 2f4284c514b3d137554d917e111103b0 OK 2026/11/06 02:00:33 DEBUG : one-26.txt: md5 = 8197c698cf8ad3bcac20c335ea163b3e OK 2026/11/06 02:00:34 DEBUG : one-27.txt: md5 = 1a9d2edebaaa50381a5444aac2995a35 OK 2026/11/06 02:00:36 DEBUG : one-28.txt: md5 = 8292a6ceb381a803417c44a80bf1ea6d OK 2026/11/06 02:00:37 DEBUG : one-29.txt: md5 = 58d5b70918799097b02f2c9d74e63fb9 OK 2026/11/06 02:00:39 DEBUG : one-30.txt: md5 = a3db7924e1a5598214066c18fa02fefe OK 2026/11/06 02:00:41 DEBUG : one-31.txt: md5 = 189295195fd8f7859b8ddc227e09b364 OK 2026/11/06 02:00:42 DEBUG : one-32.txt: md5 = 116819559da354006cb2c67e7e0641fb OK 2026/11/06 02:00:44 DEBUG : one-33.txt: md5 = 368f89b4591ef860a4cb2e51d437591b OK 2026/11/06 02:00:46 DEBUG : one-34.txt: md5 = d9e6b5c769f3bae5c249c08d19da15fd OK 2026/11/06 02:00:47 DEBUG : one-35.txt: md5 = 1f635225164b50bb6cf7285d46514507 OK 2026/11/06 02:00:48 DEBUG : one-36.txt: md5 = 73c2028cd808ec580994b533fda002b3 OK 2026/11/06 02:00:50 DEBUG : one-37.txt: md5 = 1e94986dafbed528bdbf0af98f17ea8e OK 2026/11/06 02:00:52 DEBUG : one-38.txt: md5 = a848c70c2d2b6c3271c9aa68bdab12a2 OK 2026/11/06 02:00:53 DEBUG : one-39.txt: md5 = 6e7b9f4df2879056d8fcc1784324b397 OK 2026/11/06 02:00:55 DEBUG : one-40.txt: md5 = 202d919eb521b3b88357e7199f92ff23 OK 2026/11/06 02:00:56 DEBUG : one-41.txt: md5 = c71958a07455aef684a772273c25ee6b OK 2026/11/06 02:00:58 DEBUG : one-42.txt: md5 = 600c4513322ff5ba97267977752a961a OK 2026/11/06 02:00:59 DEBUG : one-43.txt: md5 = 88b5a0bf5a6075889beb01741131ad22 OK 2026/11/06 02:01:01 DEBUG : one-44.txt: md5 = 90bae94a26f4a163c73bc39bd40d6909 OK 2026/11/06 02:01:02 DEBUG : one-45.txt: md5 = 4bea70b9a80f45067bd1549b7ea24706 OK 2026/11/06 02:01:04 DEBUG : one-46.txt: md5 = 810d8d0a81ae8ac0aef87eb936af625e OK 2026/11/06 02:01:06 DEBUG : one-47.txt: md5 = 91b5ccbed2e8bfcfb3f4b0c79b053162 OK 2026/11/06 02:01:07 DEBUG : one-48.txt: md5 = 34c9335515cdeb70b0ea7e17b9fc2a28 OK 2026/11/06 02:01:08 DEBUG : one-49.txt: md5 = 5e44f8a5dce799a3c6e2b560e21b73ff OK 2026/11/06 02:01:10 DEBUG : one-50.txt: md5 = a429731705e0ba2e8605ef8a6c3d7753 OK 2026/11/06 02:01:11 DEBUG : one-51.txt: md5 = f2fe7a2258aecff8dbc0ca6310542391 OK 2026/11/06 02:01:13 DEBUG : one-52.txt: md5 = ddfccb0b7e68da9d42fdd9771dbb19d8 OK 2026/11/06 02:01:14 DEBUG : one-53.txt: md5 = 1fc78526057e6691e3c9a3bd717e43c6 OK 2026/11/06 02:01:15 DEBUG : one-54.txt: md5 = d969ad802ae903d39d96e5f79c5974a2 OK 2026/11/06 02:01:17 DEBUG : one-55.txt: md5 = baad797f1d0c68af30d64c1786648e74 OK 2026/11/06 02:01:18 DEBUG : one-56.txt: md5 = a6c104a88af88060458420747071e3df OK 2026/11/06 02:01:21 DEBUG : one-57.txt: md5 = 3e49e8b166b1879165531a228f23ef66 OK 2026/11/06 02:01:25 DEBUG : one-58.txt: md5 = 79549b22ea07cd896141a7ad54d6a90e OK 2026/11/06 02:01:26 DEBUG : one-59.txt: md5 = 81bdcbd2a244dc73cdf2a24375c5b94c OK 2026/11/06 02:01:28 DEBUG : one-60.txt: md5 = e0aa39aad34799ee30a28cb1c4506a41 OK 2026/11/06 02:01:29 DEBUG : one-61.txt: md5 = f6d23e31a017d7d85506e6422bf179c3 OK 2026/11/06 02:01:31 DEBUG : one-62.txt: md5 = fb8e12da6a1f1ebf6e894c99e5711ea9 OK 2026/11/06 02:01:32 DEBUG : one-63.txt: md5 = 4c3f21081f2e977e047e8b3339fa3815 OK 2026/11/06 02:01:34 DEBUG : one-64.txt: md5 = 6430a2b44d15dce1face2a95dedf8e75 OK 2026/11/06 02:01:35 DEBUG : one-65.txt: md5 = ad12f0f5ca3c927a0743e3b5fad5fa37 OK 2026/11/06 02:01:37 DEBUG : one-66.txt: md5 = 8eb19b419953cf38b2e9f3c64f1c609c OK 2026/11/06 02:01:38 DEBUG : one-67.txt: md5 = 661880a67f066672a830a5bd135a95e1 OK 2026/11/06 02:01:39 DEBUG : one-68.txt: md5 = 223e36b823538a8fe52c8446bc6d3a2c OK 2026/11/06 02:01:41 DEBUG : one-69.txt: md5 = bcc494ca0a84d7b6bf9c351c7a226443 OK 2026/11/06 02:01:42 DEBUG : one-70.txt: md5 = f2b6d23ba12bbda17bd92ec7a666171f OK 2026/11/06 02:01:44 DEBUG : one-71.txt: md5 = 2e70ba4b79bb359e857de2c67e02b8ea OK 2026/11/06 02:01:45 DEBUG : one-72.txt: md5 = 75e5b5511374b3f047b29c2b05023e60 OK 2026/11/06 02:01:47 DEBUG : one-73.txt: md5 = c0b8ca894ed28e1a55a700d50d92fd42 OK 2026/11/06 02:01:48 DEBUG : one-74.txt: md5 = b426c2aba4e6063fbb761b0d105ef0b0 OK 2026/11/06 02:01:50 DEBUG : one-75.txt: md5 = 2b5f2c23b334b3de74f06a4d5e802dc5 OK 2026/11/06 02:01:51 DEBUG : one-76.txt: md5 = 1fc49c363c8a6d8febfed847a22b6d18 OK 2026/11/06 02:01:53 DEBUG : one-77.txt: md5 = 062d45d29d3eed307b04b8372bf37a65 OK 2026/11/06 02:01:54 DEBUG : one-78.txt: md5 = 551540d6244b86046b0932d9ce8fc20a OK 2026/11/06 02:01:56 DEBUG : one-79.txt: md5 = 02db03d09d6baafad808e953736e8daf OK 2026/11/06 02:01:57 DEBUG : one-80.txt: md5 = 54116c24c0c19b88d53f029e58429fa2 OK 2026/11/06 02:01:59 DEBUG : one-81.txt: md5 = 7f93e98aa79f442fc8d58ff6b3e94f2d OK 2026/11/06 02:02:00 DEBUG : one-82.txt: md5 = e7be140fc4fc022d58ad478cd1499f65 OK 2026/11/06 02:02:01 DEBUG : one-83.txt: md5 = 944d714490b85528c720cc3bad3cfb93 OK 2026/11/06 02:02:03 DEBUG : one-84.txt: md5 = b3ac975defb61b8d7fb661b859dbf3a2 OK 2026/11/06 02:02:04 DEBUG : one-85.txt: md5 = 4dcfec347b423f7fbc2052dfd311d4c8 OK 2026/11/06 02:02:06 DEBUG : one-86.txt: md5 = 10d46feb6779b7b251cbdfeae6fc1d55 OK 2026/11/06 02:02:07 DEBUG : one-87.txt: md5 = aea67b8bf12db0b43b43cb6ed09c395f OK 2026/11/06 02:02:09 DEBUG : one-88.txt: md5 = 5102c7e7e89a72cf7903b063a87ae103 OK 2026/11/06 02:02:10 DEBUG : one-89.txt: md5 = 4cf2ce0c860a41790118a2fda4cc6b68 OK 2026/11/06 02:02:11 DEBUG : one-90.txt: md5 = 7f79ee720f82cac170694bae87dc6029 OK 2026/11/06 02:02:13 DEBUG : one-91.txt: md5 = d780d0b42de249cdfbc7e05b2ca83940 OK 2026/11/06 02:02:15 DEBUG : one-92.txt: md5 = d148e9a28f0fae19989ad7d9870692e3 OK 2026/11/06 02:02:16 DEBUG : one-93.txt: md5 = 2a0a7c06677cfd004e78603850c0ec69 OK 2026/11/06 02:02:18 DEBUG : one-94.txt: md5 = 7be27caa82123091c38e01211d62a941 OK 2026/11/06 02:02:19 DEBUG : one-95.txt: md5 = 96612c9a73179e1944d3af59e628a63f OK 2026/11/06 02:02:21 DEBUG : one-96.txt: md5 = 7709f6aaa47ef71466ae8ba1f03b7a75 OK 2026/11/06 02:02:22 DEBUG : one-97.txt: md5 = c6c8c9739f97cc0311014d633da84c04 OK 2026/11/06 02:02:24 DEBUG : one-98.txt: md5 = e6e73364465720f759e93529de2c69bc OK 2026/11/06 02:02:25 DEBUG : one-99.txt: md5 = 1a713f23327bb08cabdd516b0471d34f OK 2026/11/06 02:02:27 DEBUG : one-100.txt: md5 = 2292a585e8d708efd3be23ef6eb6e635 OK 2026/11/06 02:02:28 DEBUG : one-101.txt: md5 = 55be0d08c42057819ddf4cc0d00ade71 OK 2026/11/06 02:02:30 DEBUG : one-102.txt: md5 = 46fe2fa1cc9ec0d1e95fdf75d6f8fe40 OK 2026/11/06 02:02:31 DEBUG : one-103.txt: md5 = b21e7f985d3b45dd98bde2871a8a5f3a OK 2026/11/06 02:02:33 DEBUG : one-104.txt: md5 = aed5dbabd54e4f2e765f107ef1ed4eda OK 2026/11/06 02:02:34 DEBUG : one-105.txt: md5 = a93c1bc82055d25683e65e5d7ee4a7dd OK 2026/11/06 02:02:37 INFO : Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara': Looking for duplicate names using rename mode. 2026/11/06 02:02:38 NOTICE: one.txt: Found 2 files with duplicate names 2026/11/06 02:02:39 INFO : one-106.txt: renamed from: one.txt 2026/11/06 02:02:40 INFO : one-107.txt: renamed from: one.txt --- PASS: TestDeduplicateRenameManyExisting (222.23s) === RUN TestMergeDirs run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:03:34 DEBUG : dupe1/one.txt: md5 = 4a1e7f23a3584c033107e238b591709e OK 2026/11/06 02:03:37 DEBUG : dupe2/two.txt: md5 = be9a9087eb8075785c5a4f431d0d62e6 OK 2026/11/06 02:03:39 DEBUG : dupe3/three.txt: md5 = 6b5792d608fb2bb3b5bd085c738c128f OK 2026/11/06 02:03:40 INFO : urj8gsducqbtvekeq181ntd2u8: merging "sfrom47189m9qr3mt4qonu281c" 2026/11/06 02:03:40 INFO : urj8gsducqbtvekeq181ntd2u8: removing empty directory 2026/11/06 02:03:41 INFO : 1u1r4ei7c628fnjg9blqt3j60o: merging "tr2hj63d80ftlmvm6a952snjcc" 2026/11/06 02:03:43 INFO : 1u1r4ei7c628fnjg9blqt3j60o: removing empty directory --- PASS: TestMergeDirs (14.53s) === RUN TestIndex run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:03:48 DEBUG : file1.txt: md5 = 3497fa9fcd7871d70d37a9cc3efe742f OK 2026/11/06 02:03:51 DEBUG : sub/file2.txt: md5 = 2cdfd5eef47f1e1dae0e16ee99b1ed00 OK 2026/11/06 02:03:53 DEBUG : sub/deep/file3.txt: md5 = f2e7a3e67d85a5c4b7a8862d012339a9 OK 2026/11/06 02:03:55 DEBUG : index.json: md5 = 54e11330dc81fc19a3809dd559a48270 OK 2026/11/06 02:03:55 DEBUG : index.json: size = 213 OK 2026/11/06 02:03:55 INFO : index.json: Copied (new) 2026/11/06 02:03:55 DEBUG : caddy.json: md5 = 1e203bf3182267e2248c2529e1be0a5a OK 2026/11/06 02:03:55 DEBUG : caddy.json: size = 259 OK 2026/11/06 02:03:55 INFO : caddy.json: Copied (new) 2026/11/06 02:03:55 DEBUG : sub/deep/index.html: md5 = 98e783bbe0078b12f06bb4ba04c7c269 OK 2026/11/06 02:03:55 DEBUG : sub/deep/index.html: size = 12087 OK 2026/11/06 02:03:55 INFO : sub/deep/index.html: Copied (new) 2026/11/06 02:03:55 DEBUG : index.html: md5 = 655ffd179699f82458ce53fe176e25a6 OK 2026/11/06 02:03:55 DEBUG : index.html: size = 12147 OK 2026/11/06 02:03:55 INFO : index.html: Copied (new) 2026/11/06 02:03:56 DEBUG : sub/deep/index.json: md5 = d7fd256911749a411ccf422cff2d47a0 OK 2026/11/06 02:03:56 DEBUG : sub/deep/index.json: size = 119 OK 2026/11/06 02:03:56 INFO : sub/deep/index.json: Copied (new) 2026/11/06 02:03:57 DEBUG : sub/index.json: md5 = 7a2a623fbd17b9b548c4ea02ed0e8fa4 OK 2026/11/06 02:03:57 DEBUG : sub/index.json: size = 215 OK 2026/11/06 02:03:57 INFO : sub/index.json: Copied (new) 2026/11/06 02:03:57 DEBUG : sub/index.html: md5 = 3ccc7ae747727705abee4eab97c470bb OK 2026/11/06 02:03:57 DEBUG : sub/index.html: size = 12306 OK 2026/11/06 02:03:57 INFO : sub/index.html: Copied (new) 2026/11/06 02:03:57 DEBUG : sub/deep/caddy.json: md5 = 7886467d1e548adaa94327bd89020024 OK 2026/11/06 02:03:57 DEBUG : sub/deep/caddy.json: size = 131 OK 2026/11/06 02:03:57 INFO : sub/deep/caddy.json: Copied (new) 2026/11/06 02:03:58 DEBUG : sub/caddy.json: md5 = dd4e445f2235541f90c9ce62c5377219 OK 2026/11/06 02:03:58 DEBUG : sub/caddy.json: size = 261 OK 2026/11/06 02:03:58 INFO : sub/caddy.json: Copied (new) 2026/11/06 02:04:04 DEBUG : sub/index.html: Unchanged skipping 2026/11/06 02:04:04 DEBUG : index.html: Unchanged skipping 2026/11/06 02:04:04 DEBUG : sub/deep/index.html: Unchanged skipping 2026/11/06 02:04:04 DEBUG : sub/index.json: Unchanged skipping 2026/11/06 02:04:04 DEBUG : index.json: Unchanged skipping 2026/11/06 02:04:04 DEBUG : sub/deep/index.json: Unchanged skipping 2026/11/06 02:04:05 DEBUG : caddy.json: Unchanged skipping 2026/11/06 02:04:05 DEBUG : sub/caddy.json: Unchanged skipping 2026/11/06 02:04:05 DEBUG : sub/deep/caddy.json: Unchanged skipping 2026/11/06 02:04:07 DEBUG : index.html: md5 = fd52e10e815d8d9504b1ab1a80d0b402 OK 2026/11/06 02:04:07 DEBUG : index.html: size = 12147 OK 2026/11/06 02:04:07 INFO : index.html: Copied (replaced existing) 2026/11/06 02:04:07 DEBUG : caddy.json: md5 = c9b61d7a842131edf2a7e0aa2a4bb3ec OK 2026/11/06 02:04:07 DEBUG : caddy.json: size = 259 OK 2026/11/06 02:04:07 INFO : caddy.json: Copied (replaced existing) 2026/11/06 02:04:07 DEBUG : sub/deep/index.html: md5 = b191a2e8603d09a2b6880d74bbaad0ce OK 2026/11/06 02:04:07 DEBUG : sub/deep/index.html: size = 12087 OK 2026/11/06 02:04:07 INFO : sub/deep/index.html: Copied (replaced existing) 2026/11/06 02:04:07 DEBUG : index.json: md5 = e67c340c42e9e4c2a3fcee6f4c999d83 OK 2026/11/06 02:04:07 DEBUG : index.json: size = 213 OK 2026/11/06 02:04:07 INFO : index.json: Copied (replaced existing) 2026/11/06 02:04:08 DEBUG : sub/index.html: md5 = 4b83372ba1e7ff213870aa572a0a4b15 OK 2026/11/06 02:04:08 DEBUG : sub/index.html: size = 12306 OK 2026/11/06 02:04:08 INFO : sub/index.html: Copied (replaced existing) 2026/11/06 02:04:08 DEBUG : sub/deep/index.json: md5 = ff06c0f03c0a4870aba61007561440c0 OK 2026/11/06 02:04:08 DEBUG : sub/deep/index.json: size = 119 OK 2026/11/06 02:04:08 INFO : sub/deep/index.json: Copied (replaced existing) 2026/11/06 02:04:08 DEBUG : sub/index.json: md5 = 18c98272e443f93f73524afc860ee367 OK 2026/11/06 02:04:08 DEBUG : sub/index.json: size = 215 OK 2026/11/06 02:04:08 INFO : sub/index.json: Copied (replaced existing) 2026/11/06 02:04:08 DEBUG : sub/deep/caddy.json: md5 = 5b4f709d483c78384f8a7c0b9af1c5db OK 2026/11/06 02:04:08 DEBUG : sub/deep/caddy.json: size = 131 OK 2026/11/06 02:04:08 INFO : sub/deep/caddy.json: Copied (replaced existing) 2026/11/06 02:04:10 DEBUG : sub/caddy.json: md5 = c10cf601fb11fb8f3c4bf7e4308b3607 OK 2026/11/06 02:04:10 DEBUG : sub/caddy.json: size = 261 OK 2026/11/06 02:04:10 INFO : sub/caddy.json: Copied (replaced existing) 2026/11/06 02:04:11 DEBUG : sub/deep/file4.txt: md5 = 81faf4af5863bc86291b9fd8be766f29 OK 2026/11/06 02:04:13 DEBUG : index.html: Unchanged skipping 2026/11/06 02:04:13 DEBUG : sub/index.html: Unchanged skipping 2026/11/06 02:04:13 DEBUG : sub/deep/caddy.json: md5 = de11268edef20d2be0375adce4c3bc02 OK 2026/11/06 02:04:13 DEBUG : sub/deep/caddy.json: size = 260 OK 2026/11/06 02:04:13 INFO : sub/deep/caddy.json: Copied (replaced existing) 2026/11/06 02:04:13 DEBUG : sub/deep/index.json: md5 = e52214fceeb951393d2f9dd5799297cd OK 2026/11/06 02:04:13 DEBUG : sub/deep/index.json: size = 235 OK 2026/11/06 02:04:13 INFO : sub/deep/index.json: Copied (replaced existing) 2026/11/06 02:04:13 DEBUG : sub/deep/index.html: md5 = e7a28f50c97f302f76649509031b0064 OK 2026/11/06 02:04:13 DEBUG : sub/deep/index.html: size = 12346 OK 2026/11/06 02:04:13 INFO : sub/deep/index.html: Copied (replaced existing) 2026/11/06 02:04:13 DEBUG : index.json: Unchanged skipping 2026/11/06 02:04:13 DEBUG : sub/index.json: Unchanged skipping 2026/11/06 02:04:14 DEBUG : caddy.json: Unchanged skipping 2026/11/06 02:04:14 DEBUG : sub/caddy.json: Unchanged skipping 2026/11/06 02:04:16 DEBUG : sub/deep/file5.txt: md5 = 1d22dcccbdca27418fe02ec89f06e115 OK 2026/11/06 02:04:17 NOTICE: sub/deep/caddy.json: Skipped copy as --dry-run is set (size 389) 2026/11/06 02:04:17 NOTICE: sub/deep/index.json: Skipped copy as --dry-run is set (size 351) 2026/11/06 02:04:17 NOTICE: sub/deep/index.html: Skipped copy as --dry-run is set (size 12.309Ki) 2026/11/06 02:04:18 DEBUG : index.html: Unchanged skipping 2026/11/06 02:04:18 DEBUG : sub/index.html: Unchanged skipping 2026/11/06 02:04:18 DEBUG : sub/index.json: Unchanged skipping 2026/11/06 02:04:18 DEBUG : index.json: Unchanged skipping 2026/11/06 02:04:19 DEBUG : sub/caddy.json: Unchanged skipping 2026/11/06 02:04:19 DEBUG : caddy.json: Unchanged skipping --- PASS: TestIndex (42.09s) === RUN TestIndexDelete run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:04:30 DEBUG : file1.txt: md5 = dbe30b08c626a7cacf55f75aa69cfcfa OK 2026/11/06 02:04:33 DEBUG : sub/file2.txt: md5 = df711ffa033dcf7401528c56db38bc83 OK 2026/11/06 02:04:35 DEBUG : sub/deep/file3.txt: md5 = 4d5a67016eac6fe577ffecf656986860 OK 2026/11/06 02:04:37 DEBUG : sub/index.html: md5 = 0b760d010b215e746db5210d69ce2df5 OK 2026/11/06 02:04:37 DEBUG : sub/index.html: size = 12306 OK 2026/11/06 02:04:37 INFO : sub/index.html: Copied (new) 2026/11/06 02:04:37 DEBUG : sub/deep/index.html: md5 = b37b02ab219e403279d45d622b02353d OK 2026/11/06 02:04:37 DEBUG : sub/deep/index.html: size = 12087 OK 2026/11/06 02:04:37 INFO : sub/deep/index.html: Copied (new) 2026/11/06 02:04:37 DEBUG : index.html: md5 = 92bab05af7dc9645d0d82c39fcf012d5 OK 2026/11/06 02:04:37 DEBUG : index.html: size = 12147 OK 2026/11/06 02:04:37 INFO : index.html: Copied (new) 2026/11/06 02:04:39 DEBUG : index.html: Unchanged skipping 2026/11/06 02:04:39 DEBUG : sub/index.html: Unchanged skipping 2026/11/06 02:04:40 DEBUG : sub/deep/index.html: md5 = 44cd719bc3e0e2b353953d8ad8dff2b1 OK 2026/11/06 02:04:40 DEBUG : sub/deep/index.html: size = 11829 OK 2026/11/06 02:04:40 INFO : sub/deep/index.html: Copied (replaced existing) --- PASS: TestIndexDelete (18.06s) === RUN TestIndexFilter run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:04:48 DEBUG : file1.txt: md5 = 8971e40f52ca34b3fb7ecbff9faa6c61 OK 2026/11/06 02:04:51 DEBUG : sub/file2.txt: md5 = 6007b21915d9ffbd6b0e031e0b7a9f82 OK 2026/11/06 02:04:53 DEBUG : sub/deep/file3.txt: md5 = a89ca79bdd35e8202417d8a7a206d8bc OK 2026/11/06 02:04:54 DEBUG : sub/deep/index.html: md5 = 4de19a8ad1c206fe0ab928eedae24767 OK 2026/11/06 02:04:56 DEBUG : other/notes.md: md5 = 3d52f48709d2ed02a62da3ded50f1ec8 OK 2026/11/06 02:04:57 DEBUG : other/notes.md: Excluded (Path Filter) 2026/11/06 02:04:58 DEBUG : other/index.html: md5 = a63f7cb2ae4477c9a18556be2e1f4858 OK 2026/11/06 02:04:58 DEBUG : other/index.html: size = 11802 OK 2026/11/06 02:04:58 INFO : other/index.html: Copied (new) 2026/11/06 02:04:58 DEBUG : index.html: md5 = 16abeab8f32a67b7bb84de01c0452440 OK 2026/11/06 02:04:58 DEBUG : index.html: size = 12399 OK 2026/11/06 02:04:58 INFO : index.html: Copied (new) 2026/11/06 02:04:58 DEBUG : sub/index.html: md5 = 76e19cbf2b0092bfeaf0c545655a8c8b OK 2026/11/06 02:04:58 DEBUG : sub/index.html: size = 12056 OK 2026/11/06 02:04:58 INFO : sub/index.html: Copied (new) 2026/11/06 02:05:03 DEBUG : other/notes.md: Excluded (Path Filter) 2026/11/06 02:05:04 DEBUG : other/index.html: Unchanged skipping 2026/11/06 02:05:04 DEBUG : sub/index.html: Unchanged skipping 2026/11/06 02:05:04 DEBUG : index.html: Unchanged skipping --- PASS: TestIndexFilter (24.29s) === RUN TestIndexRules run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:05:13 DEBUG : file1.txt: md5 = 6ffdf0dd4ac590b73bc99ea64c8e70ab OK 2026/11/06 02:05:15 DEBUG : sub/file2.txt: md5 = 111c3c771030e9d1a3de893cf6cd2175 OK 2026/11/06 02:05:17 DEBUG : sub/deep/file3.txt: md5 = 1ffc8a29c4d6b868ae53d58ef32804ee OK 2026/11/06 02:05:19 DEBUG : sub/deep/index.html: md5 = f73c51f5f7ec5993cd28632512ce78bd OK 2026/11/06 02:05:21 DEBUG : sub/index.html: md5 = 440d4f04365108fdb368ef404cf690ff OK 2026/11/06 02:05:21 DEBUG : sub/index.html: size = 12306 OK 2026/11/06 02:05:21 INFO : sub/index.html: Copied (new) 2026/11/06 02:05:21 DEBUG : index.html: md5 = 2a94fe48bde859e7b43ebd5fd49e188d OK 2026/11/06 02:05:21 DEBUG : index.html: size = 12147 OK 2026/11/06 02:05:21 INFO : index.html: Copied (new) 2026/11/06 02:05:26 DEBUG : sub/index.html: Unchanged skipping 2026/11/06 02:05:26 DEBUG : sub/deep/index.html: md5 = f556631f46490da8f066bea5d6cb324a OK 2026/11/06 02:05:26 DEBUG : sub/deep/index.html: size = 12087 OK 2026/11/06 02:05:26 INFO : sub/deep/index.html: Copied (replaced existing) --- PASS: TestIndexRules (21.35s) === RUN TestIndexMaxDepth run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:05:34 DEBUG : file1.txt: md5 = 8ee820b6e00bce64506deebba34b3402 OK 2026/11/06 02:05:36 DEBUG : sub/file2.txt: md5 = ddb8fd69450c5555e7aaef1a1d10e51b OK 2026/11/06 02:05:38 DEBUG : sub/deep/file3.txt: md5 = c6d900ab9b9ca4e1d8560a3cdda3b661 OK 2026/11/06 02:05:40 DEBUG : index.html: md5 = 274a938b787ba7c7d94fc438ff65c997 OK 2026/11/06 02:05:40 DEBUG : index.html: size = 12147 OK 2026/11/06 02:05:40 INFO : index.html: Copied (new) --- PASS: TestIndexMaxDepth (13.58s) === RUN TestIndexLinkIndex run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:05:48 DEBUG : file1.txt: md5 = b11bb88fd0c1892b9172e67688f2d96c OK 2026/11/06 02:05:50 DEBUG : sub/file2.txt: md5 = ee3420539ea048f820f67082813bd783 OK 2026/11/06 02:05:52 DEBUG : sub/deep/file3.txt: md5 = 6b945d6c0a1dd5a4fac215b1dbe1ca94 OK 2026/11/06 02:05:54 DEBUG : sub/index.html: md5 = 29cabe315b6606e400e17f1c19baee31 OK 2026/11/06 02:05:54 DEBUG : sub/index.html: size = 12347 OK 2026/11/06 02:05:54 INFO : sub/index.html: Copied (new) 2026/11/06 02:05:54 DEBUG : index.html: md5 = a7476da23dff256670dd64b05628960c OK 2026/11/06 02:05:54 DEBUG : index.html: size = 12167 OK 2026/11/06 02:05:54 INFO : index.html: Copied (new) 2026/11/06 02:05:54 DEBUG : sub/deep/index.html: md5 = 623fef6c59106fe64feaaea87c7517f9 OK 2026/11/06 02:05:54 DEBUG : sub/deep/index.html: size = 12128 OK 2026/11/06 02:05:54 INFO : sub/deep/index.html: Copied (new) --- PASS: TestIndexLinkIndex (14.97s) === RUN TestIndexDirTime run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:06:03 DEBUG : file1.txt: md5 = 46d2ea456e1e7cfde1e959a455503dcd OK 2026/11/06 02:06:05 DEBUG : sub/file2.txt: md5 = f2f3fb20b440ad2af7ef7ef89d6dd1ea OK 2026/11/06 02:06:07 DEBUG : sub/deep/file3.txt: md5 = 4d1df87c0df956bf0f1d60598e68acb3 OK 2026/11/06 02:06:09 DEBUG : sub/index.json: md5 = 7864db6a1f69a6860260434e369532ca OK 2026/11/06 02:06:09 DEBUG : sub/index.json: size = 259 OK 2026/11/06 02:06:09 INFO : sub/index.json: Copied (new) 2026/11/06 02:06:09 DEBUG : sub/deep/index.json: md5 = 40353db0cec21067be64b5be944b38c6 OK 2026/11/06 02:06:09 DEBUG : sub/deep/index.json: size = 139 OK 2026/11/06 02:06:09 INFO : sub/deep/index.json: Copied (new) 2026/11/06 02:06:09 DEBUG : index.json: md5 = 93e21892e95ab0cc5e4cdf08f2530903 OK 2026/11/06 02:06:09 DEBUG : index.json: size = 257 OK 2026/11/06 02:06:09 INFO : index.json: Copied (new) 2026/11/06 02:06:12 DEBUG : sub/deep/index.json: Unchanged skipping 2026/11/06 02:06:13 DEBUG : sub/index.json: md5 = 83e11e3603ef8f06dcab4710169eff10 OK 2026/11/06 02:06:13 DEBUG : sub/index.json: size = 239 OK 2026/11/06 02:06:13 INFO : sub/index.json: Copied (replaced existing) 2026/11/06 02:06:13 DEBUG : index.json: md5 = 9a59eb9bfd094dfe0a7ad49f3960b148 OK 2026/11/06 02:06:13 DEBUG : index.json: size = 237 OK 2026/11/06 02:06:13 INFO : index.json: Copied (replaced existing) 2026/11/06 02:06:16 DEBUG : sub/deep/index.json: md5 = c881cb739d2fddcf0ee39166fc46b8cc OK 2026/11/06 02:06:16 DEBUG : sub/deep/index.json: size = 119 OK 2026/11/06 02:06:16 INFO : sub/deep/index.json: Copied (replaced existing) 2026/11/06 02:06:16 DEBUG : index.json: md5 = 11d07239dc699978ab9b95d20f4ddc91 OK 2026/11/06 02:06:16 DEBUG : index.json: size = 213 OK 2026/11/06 02:06:16 INFO : index.json: Copied (replaced existing) 2026/11/06 02:06:16 DEBUG : sub/index.json: md5 = 6b126cce57db294d9dcfe99f1d5bad47 OK 2026/11/06 02:06:16 DEBUG : sub/index.json: size = 215 OK 2026/11/06 02:06:16 INFO : sub/index.json: Copied (replaced existing) --- PASS: TestIndexDirTime (20.52s) === RUN TestIndexTemplate run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:06:23 DEBUG : file1.txt: md5 = e58fc6ec5014bd719c799f0312e39baf OK 2026/11/06 02:06:25 DEBUG : sub/file2.txt: md5 = 9c0cd9ef4ef3af14a807a2e97167ff22 OK 2026/11/06 02:06:27 DEBUG : sub/deep/file3.txt: md5 = fa1bb027f3281f23f3b77aba265c857a OK 2026/11/06 02:06:29 DEBUG : list.txt: md5 = 173b4a168e7518aca216dec8b9dcfd7c OK 2026/11/06 02:06:29 DEBUG : list.txt: size = 59 OK 2026/11/06 02:06:29 INFO : list.txt: Copied (new) 2026/11/06 02:06:29 DEBUG : sub/list.txt: md5 = 8fd7d91e91050bd53fd70c18cdc37f48 OK 2026/11/06 02:06:29 DEBUG : sub/list.txt: size = 64 OK 2026/11/06 02:06:29 INFO : sub/list.txt: Copied (new) 2026/11/06 02:06:30 DEBUG : sub/deep/list.txt: md5 = 4bdd5fa6dbc8e201b08ce2ef7f784a34 OK 2026/11/06 02:06:30 DEBUG : sub/deep/list.txt: size = 47 OK 2026/11/06 02:06:30 INFO : sub/deep/list.txt: Copied (new) --- PASS: TestIndexTemplate (14.13s) === RUN TestIndexTemplateBuiltin --- PASS: TestIndexTemplateBuiltin (0.00s) === RUN TestRcIndex rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcIndex (0.00s) === RUN TestIndexNoHash index_test.go:385: Skipping test on non local remote --- SKIP: TestIndexNoHash (0.00s) === RUN TestIndexChanged run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:06:37 DEBUG : file1.txt: md5 = f9e051cae2b8dabefc277ea59410c275 OK 2026/11/06 02:06:40 DEBUG : sub/file2.txt: md5 = d08d8e300194077b015117ab7971dc46 OK 2026/11/06 02:06:42 DEBUG : sub/deep/file3.txt: md5 = b5248a4080929fa7e3882fa3f8860f83 OK 2026/11/06 02:06:44 DEBUG : other/notes.md: md5 = 9ffce0890dae66525bd195cbd049bc19 OK 2026/11/06 02:06:46 DEBUG : index.html: md5 = 3f75f48821e24a7a59c89217f640a08f OK 2026/11/06 02:06:46 DEBUG : index.html: size = 12399 OK 2026/11/06 02:06:46 INFO : index.html: Copied (new) 2026/11/06 02:06:46 DEBUG : sub/index.html: md5 = a6f30d6efc0d75598e7473a17084a2a5 OK 2026/11/06 02:06:46 DEBUG : sub/index.html: size = 12306 OK 2026/11/06 02:06:46 INFO : sub/index.html: Copied (new) 2026/11/06 02:06:46 DEBUG : other/index.html: md5 = d436aa4b697ec6e00f695804809cd6d5 OK 2026/11/06 02:06:46 DEBUG : other/index.html: size = 12058 OK 2026/11/06 02:06:46 INFO : other/index.html: Copied (new) 2026/11/06 02:06:46 DEBUG : sub/deep/index.html: md5 = c5c52808cbb098c873da63fd175e5eff OK 2026/11/06 02:06:46 DEBUG : sub/deep/index.html: size = 12087 OK 2026/11/06 02:06:46 INFO : sub/deep/index.html: Copied (new) 2026/11/06 02:06:48 DEBUG : sub/deep/file4.txt: md5 = 1e2d1a485f7942374785ad43f037460a OK 2026/11/06 02:06:49 DEBUG : other/undeclared.txt: md5 = 2a8aebaefd3f26031102b748a976df81 OK 2026/11/06 02:06:49 INFO : Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara': Partial index: listing 3 directories and walking 0 changed directories 2026/11/06 02:06:51 DEBUG : sub/index.html: Unchanged skipping 2026/11/06 02:06:51 DEBUG : index.html: Unchanged skipping 2026/11/06 02:06:51 DEBUG : sub/deep/index.html: md5 = 3c3fc59067b5ec98d48461e27600f01a OK 2026/11/06 02:06:51 DEBUG : sub/deep/index.html: size = 12346 OK 2026/11/06 02:06:51 INFO : sub/deep/index.html: Copied (replaced existing) 2026/11/06 02:06:55 DEBUG : sub/new/file5.txt: md5 = d0cb76dbce020ab6ed6273c7af29d781 OK 2026/11/06 02:06:55 INFO : Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara': Partial index: listing 3 directories and walking 0 changed directories 2026/11/06 02:06:57 DEBUG : index.html: Unchanged skipping 2026/11/06 02:06:57 DEBUG : sub/index.html: md5 = 7af6f959d3d13125c75cdb8492bd24ff OK 2026/11/06 02:06:57 DEBUG : sub/index.html: size = 12554 OK 2026/11/06 02:06:57 INFO : sub/index.html: Copied (replaced existing) 2026/11/06 02:06:57 DEBUG : sub/new/index.html: md5 = 5f204e5adae14bbab8870f44c1c98423 OK 2026/11/06 02:06:57 DEBUG : sub/new/index.html: size = 12085 OK 2026/11/06 02:06:57 INFO : sub/new/index.html: Copied (new) 2026/11/06 02:07:01 DEBUG : other/undeclared0.txt: md5 = 21d19c071d092b07fe002db882988e59 OK 2026/11/06 02:07:01 INFO : Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara': Partial index: listing 1 directories and walking 1 changed directories 2026/11/06 02:07:02 DEBUG : index.html: Unchanged skipping 2026/11/06 02:07:02 DEBUG : other/index.html: md5 = 47dbdf2d32e87fe539af6149f68e526c OK 2026/11/06 02:07:02 DEBUG : other/index.html: size = 12597 OK 2026/11/06 02:07:02 INFO : other/index.html: Copied (replaced existing) 2026/11/06 02:07:05 DEBUG : other/undeclared1.txt: md5 = d1fc490cf4f26485e993402397d58fb0 OK 2026/11/06 02:07:05 INFO : Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara': Partial index: listing 1 directories and walking 0 changed directories 2026/11/06 02:07:06 DEBUG : index.html: Unchanged skipping 2026/11/06 02:07:07 DEBUG : other/index.html: md5 = 5ad9762a6831d729850b16323c11c0a4 OK 2026/11/06 02:07:07 DEBUG : other/index.html: size = 12867 OK 2026/11/06 02:07:07 INFO : other/index.html: Copied (replaced existing) 2026/11/06 02:07:09 DEBUG : sub/file6.txt: md5 = f25b68de4b229f0aea7e34fc1a135560 OK 2026/11/06 02:07:09 INFO : Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara': Nothing changed so nothing to index 2026/11/06 02:07:10 INFO : Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara': Root changed so doing a full index 2026/11/06 02:07:11 DEBUG : other/index.html: Unchanged skipping 2026/11/06 02:07:11 DEBUG : index.html: Unchanged skipping 2026/11/06 02:07:11 DEBUG : sub/new/index.html: Unchanged skipping 2026/11/06 02:07:11 DEBUG : sub/deep/index.html: Unchanged skipping 2026/11/06 02:07:12 DEBUG : sub/index.html: md5 = c642ec9972174937b6a811e5fe6667f1 OK 2026/11/06 02:07:12 DEBUG : sub/index.html: size = 12813 OK 2026/11/06 02:07:12 INFO : sub/index.html: Copied (replaced existing) 2026/11/06 02:07:14 DEBUG : sub/file7.txt: md5 = a07e7c9c536a83533a5e864023d568cd OK 2026/11/06 02:07:14 INFO : Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara': Partial index would list 2 directories, more than 1, so doing a full index 2026/11/06 02:07:15 DEBUG : index.html: Unchanged skipping 2026/11/06 02:07:15 DEBUG : sub/deep/index.html: Unchanged skipping 2026/11/06 02:07:16 DEBUG : other/index.html: Unchanged skipping 2026/11/06 02:07:16 DEBUG : sub/new/index.html: Unchanged skipping 2026/11/06 02:07:16 DEBUG : sub/index.html: md5 = cdf87a2dda747b360817a034b35d3f6f OK 2026/11/06 02:07:16 DEBUG : sub/index.html: size = 13071 OK 2026/11/06 02:07:16 INFO : sub/index.html: Copied (replaced existing) 2026/11/06 02:07:19 DEBUG : sub/deep/file8.txt: md5 = 2696d36d1ba771e01b606a5de87c5483 OK 2026/11/06 02:07:19 INFO : Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara': Partial index: listing 1 directories and walking 1 changed directories 2026/11/06 02:07:20 DEBUG : index.html: Unchanged skipping 2026/11/06 02:07:20 DEBUG : sub/index.html: Unchanged skipping 2026/11/06 02:07:20 DEBUG : sub/new/index.html: Unchanged skipping 2026/11/06 02:07:21 DEBUG : sub/deep/index.html: md5 = e1fcceb79714aff25da7e9b51e557945 OK 2026/11/06 02:07:21 DEBUG : sub/deep/index.html: size = 12604 OK 2026/11/06 02:07:21 INFO : sub/deep/index.html: Copied (replaced existing) 2026/11/06 02:07:23 DEBUG : sub/deep/file9.txt: md5 = ebec2bef54f41b87e22fbc73c1b56195 OK 2026/11/06 02:07:25 DEBUG : sub/file10.txt: md5 = 6047c14aee6b4a184e090ddda7c36f83 OK 2026/11/06 02:07:25 INFO : Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara': Partial index: listing 2 directories and walking 0 changed directories 2026/11/06 02:07:25 DEBUG : index.html: Unchanged skipping 2026/11/06 02:07:26 DEBUG : sub/index.html: md5 = e6abb30f6bcb9a9d43f35f301cf63e3f OK 2026/11/06 02:07:26 DEBUG : sub/index.html: size = 13331 OK 2026/11/06 02:07:26 INFO : sub/index.html: Copied (replaced existing) --- PASS: TestIndexChanged (64.69s) === RUN TestIndexChangedDelete run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:07:42 DEBUG : file1.txt: md5 = cb274af9faa247b54e986d6161004dba OK 2026/11/06 02:07:44 DEBUG : sub/file2.txt: md5 = b33c9a643b7de6f5a6e9542e4e3054e3 OK 2026/11/06 02:07:46 DEBUG : sub/deep/file3.txt: md5 = 126e1f3dbadaf03573e3e6c92eacf822 OK 2026/11/06 02:07:48 DEBUG : sub/deep/index.html: md5 = bfdfb827eae85c3bc469593a340729cd OK 2026/11/06 02:07:48 DEBUG : sub/deep/index.html: size = 12087 OK 2026/11/06 02:07:48 INFO : sub/deep/index.html: Copied (new) 2026/11/06 02:07:48 DEBUG : index.html: md5 = 5a203eb9bdb0c40610478e26748a3cb6 OK 2026/11/06 02:07:48 DEBUG : index.html: size = 12147 OK 2026/11/06 02:07:48 INFO : index.html: Copied (new) 2026/11/06 02:07:48 DEBUG : sub/index.html: md5 = 5c992f940da181d0f815c5805963eea9 OK 2026/11/06 02:07:48 DEBUG : sub/index.html: size = 12306 OK 2026/11/06 02:07:48 INFO : sub/index.html: Copied (new) 2026/11/06 02:07:49 INFO : Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara': Partial index: listing 3 directories and walking 0 changed directories 2026/11/06 02:07:51 DEBUG : index.html: Unchanged skipping 2026/11/06 02:07:51 DEBUG : sub/index.html: Unchanged skipping 2026/11/06 02:07:51 DEBUG : sub/deep/index.html: md5 = ec367ab32e5cba2b6b680b4366b4a25e OK 2026/11/06 02:07:51 DEBUG : sub/deep/index.html: size = 11829 OK 2026/11/06 02:07:51 INFO : sub/deep/index.html: Copied (replaced existing) --- PASS: TestIndexChangedDelete (16.36s) === RUN TestIndexChangedFiles run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:07:58 DEBUG : file1.txt: md5 = d9ae2d3462c20e3a6d330dfbd57b0e46 OK 2026/11/06 02:08:01 DEBUG : sub/file2.txt: md5 = 3d5020571bcfcfef88aaac1afec9c8da OK 2026/11/06 02:08:03 DEBUG : sub/deep/file3.txt: md5 = 06622b7b09f86ae11fb7bb1a22da7b96 OK 2026/11/06 02:08:05 DEBUG : - odd/file.txt: md5 = 221ae6f5b91b7f84241bfc8a3ed41061 OK 2026/11/06 02:08:07 DEBUG : sub/deep/index.html: md5 = 3046f5bb00508cce02b3e3be08c5ee53 OK 2026/11/06 02:08:07 DEBUG : sub/deep/index.html: size = 12087 OK 2026/11/06 02:08:07 INFO : sub/deep/index.html: Copied (new) 2026/11/06 02:08:07 DEBUG : sub/index.html: md5 = 6e1fde4da5febd943c94ed4137e536af OK 2026/11/06 02:08:07 DEBUG : sub/index.html: size = 12306 OK 2026/11/06 02:08:07 INFO : sub/index.html: Copied (new) 2026/11/06 02:08:08 DEBUG : - odd/index.html: md5 = 4a0c0b2ca0269c172f1657655ae6d83a OK 2026/11/06 02:08:08 DEBUG : - odd/index.html: size = 12058 OK 2026/11/06 02:08:08 INFO : - odd/index.html: Copied (new) 2026/11/06 02:08:08 DEBUG : index.html: md5 = 46940b98f19c66ee7c89afc3f4aadc32 OK 2026/11/06 02:08:08 DEBUG : index.html: size = 12401 OK 2026/11/06 02:08:08 INFO : index.html: Copied (new) 2026/11/06 02:08:09 DEBUG : - odd/file8.txt: md5 = 0d20d6801f5e94f455362a54f00d3a95 OK 2026/11/06 02:08:11 DEBUG : sub/file9.txt: md5 = 414655c4b597445d38d4350bd8f93974 OK 2026/11/06 02:08:11 INFO : Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara': Partial index: listing 2 directories and walking 0 changed directories 2026/11/06 02:08:12 DEBUG : index.html: Unchanged skipping 2026/11/06 02:08:12 DEBUG : - odd/index.html: md5 = 124d04c0017a78d40eeb871fe434bfcc OK 2026/11/06 02:08:12 DEBUG : - odd/index.html: size = 12316 OK 2026/11/06 02:08:12 INFO : - odd/index.html: Copied (replaced existing) 2026/11/06 02:08:14 INFO : Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara': Partial index: listing 4 directories and walking 0 changed directories 2026/11/06 02:08:16 DEBUG : - odd/index.html: Unchanged skipping 2026/11/06 02:08:16 DEBUG : index.html: Unchanged skipping 2026/11/06 02:08:16 DEBUG : sub/deep/index.html: Unchanged skipping 2026/11/06 02:08:17 DEBUG : sub/index.html: md5 = 67039ac60d3d884e6ec6f2b71c70dd66 OK 2026/11/06 02:08:17 DEBUG : sub/index.html: size = 12565 OK 2026/11/06 02:08:17 INFO : sub/index.html: Copied (replaced existing) --- PASS: TestIndexChangedFiles (28.80s) === RUN TestIndexChangedDirTime run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:08:27 DEBUG : file1.txt: md5 = e5dbfc34c04436adb901631ad16d5867 OK 2026/11/06 02:08:29 DEBUG : sub/file2.txt: md5 = 4a2a9833cfecfff3fd2504cd2e5ee0c6 OK 2026/11/06 02:08:32 DEBUG : sub/deep/file3.txt: md5 = ae48f08b7f1f1a2856e0d5c0716f2e29 OK 2026/11/06 02:08:34 DEBUG : other/notes.md: md5 = 260709d3636c7387b5f28ddbd8784867 OK 2026/11/06 02:08:36 DEBUG : index.json: md5 = 6d2b48cde1841daec1495767d6525dd2 OK 2026/11/06 02:08:36 DEBUG : index.json: size = 379 OK 2026/11/06 02:08:36 INFO : index.json: Copied (new) 2026/11/06 02:08:36 DEBUG : sub/deep/index.json: md5 = e91bb31f818975485a28a5ebc8688e79 OK 2026/11/06 02:08:36 DEBUG : sub/deep/index.json: size = 139 OK 2026/11/06 02:08:36 INFO : sub/deep/index.json: Copied (new) 2026/11/06 02:08:36 DEBUG : other/index.json: md5 = b3a5b97f850db38e293de96ca79b227c OK 2026/11/06 02:08:36 DEBUG : other/index.json: size = 144 OK 2026/11/06 02:08:36 INFO : other/index.json: Copied (new) 2026/11/06 02:08:36 DEBUG : sub/index.json: md5 = cbd1fb98ed9b1df8a924a15fec0cec86 OK 2026/11/06 02:08:36 DEBUG : sub/index.json: size = 259 OK 2026/11/06 02:08:36 INFO : sub/index.json: Copied (new) 2026/11/06 02:08:37 DEBUG : sub/deep/file4.txt: md5 = b4499361c6f5b00a912c6e373932463a OK 2026/11/06 02:08:37 INFO : Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara': Partial index: listing 3 directories and walking 0 changed directories 2026/11/06 02:08:40 DEBUG : sub/deep/index.json: md5 = 3c72f80165458d298e65910155121779 OK 2026/11/06 02:08:40 DEBUG : sub/deep/index.json: size = 275 OK 2026/11/06 02:08:40 INFO : sub/deep/index.json: Copied (replaced existing) 2026/11/06 02:08:40 DEBUG : index.json: md5 = d979dae0ca315595dc2d747d730e210c OK 2026/11/06 02:08:40 DEBUG : index.json: size = 379 OK 2026/11/06 02:08:40 INFO : index.json: Copied (replaced existing) 2026/11/06 02:08:40 DEBUG : sub/index.json: md5 = fcdda64d78171579daf004195126ad52 OK 2026/11/06 02:08:40 DEBUG : sub/index.json: size = 259 OK 2026/11/06 02:08:40 INFO : sub/index.json: Copied (replaced existing) --- PASS: TestIndexChangedDirTime (23.42s) === RUN TestListDirSorted run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:08:50 DEBUG : a.txt: md5 = 757b06427c1f837b21d28be7168e0bba OK 2026/11/06 02:08:52 DEBUG : zend.txt: md5 = 674badc1a0734e57dcef354e198eb3d1 OK 2026/11/06 02:08:54 DEBUG : sub dir/hello world: md5 = 92ffb831d41f1da1ec962c4e23aeb293 OK 2026/11/06 02:08:56 DEBUG : sub dir/hello world2: md5 = 5cf16a866398d0029484f068cb092cd0 OK 2026/11/06 02:08:58 DEBUG : sub dir/ignore dir/.ignore: md5 = 4fc3cb56960bb0d4dd273947bc60dd85 OK 2026/11/06 02:08:59 DEBUG : sub dir/ignore dir/should be ignored: md5 = 28b34cab80e4688d8d2868d15623d988 OK 2026/11/06 02:09:02 DEBUG : sub dir/sub sub dir/hello world3: md5 = ee2dedad238bf22609d10cc1b8954fd8 OK 2026/11/06 02:09:03 DEBUG : a.txt: Excluded (Size Filter) 2026/11/06 02:09:03 DEBUG : a.txt: Excluded 2026/11/06 02:09:03 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/11/06 02:09:03 DEBUG : sub dir/hello world: Excluded 2026/11/06 02:09:03 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/11/06 02:09:03 DEBUG : sub dir/hello world2: Excluded 2026/11/06 02:09:04 DEBUG : sub dir/ignore dir: Excluded 2026/11/06 02:09:04 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/11/06 02:09:04 DEBUG : sub dir/hello world: Excluded 2026/11/06 02:09:04 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/11/06 02:09:04 DEBUG : sub dir/hello world2: Excluded 2026/11/06 02:09:04 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSorted (22.42s) === RUN TestListDirSortedFn run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:09:13 DEBUG : a.txt: md5 = a512554e93a95bc6f6ef631fe0ce9b72 OK 2026/11/06 02:09:14 DEBUG : zend.txt: md5 = ddc7ea15b120ec1585f5aba962b515ae OK 2026/11/06 02:09:17 DEBUG : sub dir/hello world: md5 = a97a59c7f5ee3ec47ca639232aa623c9 OK 2026/11/06 02:09:18 DEBUG : sub dir/hello world2: md5 = c1a50313cb5df582e45ad5ada13f371b OK 2026/11/06 02:09:20 DEBUG : sub dir/ignore dir/.ignore: md5 = 0f34b58f0def5ec1a776436e6c32c060 OK 2026/11/06 02:09:21 DEBUG : sub dir/ignore dir/should be ignored: md5 = 7e28fe08ec7edb0586be95d485d19b13 OK 2026/11/06 02:09:24 DEBUG : sub dir/sub sub dir/hello world3: md5 = a0a5d7403eee26684bb4bc676bc96d01 OK 2026/11/06 02:09:25 DEBUG : a.txt: Excluded (Size Filter) 2026/11/06 02:09:25 DEBUG : a.txt: Excluded 2026/11/06 02:09:26 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/11/06 02:09:26 DEBUG : sub dir/hello world2: Excluded 2026/11/06 02:09:26 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/11/06 02:09:26 DEBUG : sub dir/hello world: Excluded 2026/11/06 02:09:26 DEBUG : sub dir/ignore dir: Excluded 2026/11/06 02:09:26 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/11/06 02:09:26 DEBUG : sub dir/hello world2: Excluded 2026/11/06 02:09:26 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/11/06 02:09:26 DEBUG : sub dir/hello world: Excluded 2026/11/06 02:09:26 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSortedFn (21.97s) === RUN TestListJSON run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:09:35 DEBUG : file1: md5 = 077d5f76764575cfe48667567022212d OK 2026/11/06 02:09:37 DEBUG : sub/file2: md5 = d28f3cf681cc0f7788092b968c1d6088 OK === RUN TestListJSON/Default === RUN TestListJSON/FilesOnly === RUN TestListJSON/DirsOnly === RUN TestListJSON/Recurse === RUN TestListJSON/SubDir === RUN TestListJSON/NoModTime === RUN TestListJSON/NoMimeType === RUN TestListJSON/ShowHash === RUN TestListJSON/HashTypes 2026/11/06 02:09:41 ERROR : file1: Failed to read hash: hash type not supported === RUN TestListJSON/Metadata 2026/11/06 02:09:41 DEBUG : eer8kka55qnghc34cq76ca668g: Fetching metadata 2026/11/06 02:09:41 DEBUG : 150fuo3cn4j1uenq6r4g4qk6t4: Fetching metadata --- PASS: TestListJSON (10.19s) --- PASS: TestListJSON/Default (0.24s) --- PASS: TestListJSON/FilesOnly (0.24s) --- PASS: TestListJSON/DirsOnly (0.27s) --- PASS: TestListJSON/Recurse (0.48s) --- PASS: TestListJSON/SubDir (0.21s) --- PASS: TestListJSON/NoModTime (0.22s) --- PASS: TestListJSON/NoMimeType (0.31s) --- PASS: TestListJSON/ShowHash (0.23s) --- PASS: TestListJSON/HashTypes (0.21s) --- PASS: TestListJSON/Metadata (0.66s) === RUN TestStatJSON run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:09:45 DEBUG : file1: md5 = b6ef64209a1bea4cc3a0cd2725d99392 OK 2026/11/06 02:09:47 DEBUG : sub/file2: md5 = 3a8bf59e8c9e7f1e0521d69d3cd9873f OK === RUN TestStatJSON/Root === RUN TestStatJSON/RootFilesOnly === RUN TestStatJSON/RootDirsOnly === RUN TestStatJSON/Dir === RUN TestStatJSON/DirWithTrailingSlash === RUN TestStatJSON/File === RUN TestStatJSON/NotFound === RUN TestStatJSON/DirFilesOnly === RUN TestStatJSON/FileFilesOnly === RUN TestStatJSON/NotFoundFilesOnly === RUN TestStatJSON/DirDirsOnly === RUN TestStatJSON/FileDirsOnly === RUN TestStatJSON/NotFoundDirsOnly === RUN TestStatJSON/RootNotFound 2026/11/06 02:09:51 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-gevuxol5nara/notfound" 2026/11/06 02:09:51 DEBUG : Config file has changed externally - reloading 2026/11/06 02:09:51 DEBUG : Creating backend with remote "TestDrive:crypt/o954njvhu8i73jqo00n8k29tcik3fbkejdsvjlmnh110c5qaqgog/qe0i31qdkoejk60elps0ni5sqk" --- PASS: TestStatJSON (11.39s) --- PASS: TestStatJSON/Root (0.26s) --- PASS: TestStatJSON/RootFilesOnly (0.00s) --- PASS: TestStatJSON/RootDirsOnly (0.26s) --- PASS: TestStatJSON/Dir (0.49s) --- PASS: TestStatJSON/DirWithTrailingSlash (0.25s) --- PASS: TestStatJSON/File (0.29s) --- PASS: TestStatJSON/NotFound (0.47s) --- PASS: TestStatJSON/DirFilesOnly (0.25s) --- PASS: TestStatJSON/FileFilesOnly (0.22s) --- PASS: TestStatJSON/NotFoundFilesOnly (0.20s) --- PASS: TestStatJSON/DirDirsOnly (0.22s) --- PASS: TestStatJSON/FileDirsOnly (0.26s) --- PASS: TestStatJSON/NotFoundDirsOnly (0.23s) --- PASS: TestStatJSON/RootNotFound (1.62s) === RUN TestStatJSONMemory 2026/11/06 02:09:55 DEBUG : Creating backend with remote ":memory:" 2026/11/06 02:09:55 DEBUG : Memory root '': File to upload is small (5 bytes), uploading instead of streaming 2026/11/06 02:09:55 DEBUG : sub/file1: size = 5 OK 2026/11/06 02:09:55 DEBUG : sub/file1: md5 = 5d41402abc4b2a76b9719d911017c592 OK 2026/11/06 02:09:55 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-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:09:55 INFO : Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara': Making directory 2026/11/06 02:09:55 INFO : Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara': Making directory --- PASS: TestMkdir (0.74s) === RUN TestLsd run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:09:58 DEBUG : sub dir/hello world: md5 = 45b587630745de559c4fbbb30b6d655b OK --- PASS: TestLsd (4.63s) === RUN TestLs run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:10:02 DEBUG : potato2: md5 = 9d8802372e0b529ce10116ec860750a9 OK 2026/11/06 02:10:03 DEBUG : empty space: md5 = a805fcbc44a6b0e5733c8ad79a874f6f OK --- PASS: TestLs (4.65s) === RUN TestLsWithFilesFrom run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:10:06 DEBUG : potato2: md5 = 54d41c10ccc2e7713a4d617fe379b289 OK 2026/11/06 02:10:08 DEBUG : empty space: md5 = dff5e60046d142222c31c51118c7f39c OK 2026/11/06 02:10:08 DEBUG : empty space: Excluded (FilesFrom Filter) 2026/11/06 02:10:08 DEBUG : empty space: Excluded --- PASS: TestLsWithFilesFrom (5.00s) === RUN TestLsLong run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:10:11 DEBUG : potato2: md5 = 8dc3e7d365b205d12b46e678500ae1f2 OK 2026/11/06 02:10:13 DEBUG : empty space: md5 = 01b094576f0262d4f82b36d23a869218 OK --- PASS: TestLsLong (4.61s) === RUN TestHashSums run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:10:16 DEBUG : potato2: md5 = d38c6e7dfe7924bc37e15b1c1872e958 OK 2026/11/06 02:10:18 DEBUG : empty space: md5 = 9dc5c5bd727964612a3dcdafec1e2950 OK --- PASS: TestHashSums (4.48s) === RUN TestHashSumsWithErrors 2026/11/06 02:10:19 DEBUG : Creating backend with remote ":memory:" 2026/11/06 02:10:19 ERROR : file1: hash unsupported: hash type not supported 2026/11/06 02:10:19 ERROR : sub/file1: hash unsupported: hash type not supported 2026/11/06 02:10:19 ERROR : sub/file1: hash unsupported: hash type not supported --- PASS: TestHashSumsWithErrors (0.00s) === RUN TestHashStream 2026/11/06 02:10:19 DEBUG : Creating md5 hash of 0 bytes read from input stream 2026/11/06 02:10:19 DEBUG : Creating md5 hash of 0 bytes read from input stream 2026/11/06 02:10:19 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2026/11/06 02:10:19 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2026/11/06 02:10:19 DEBUG : Creating md5 hash of 12 bytes read from input stream 2026/11/06 02:10:19 DEBUG : Creating md5 hash of 12 bytes read from input stream 2026/11/06 02:10:19 DEBUG : Creating sha1 hash of 12 bytes read from input stream 2026/11/06 02:10:19 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-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:10:21 DEBUG : potato2: md5 = 287a13ab48ee0642f94bf85bdcff2375 OK 2026/11/06 02:10:22 DEBUG : empty space: md5 = 3e2ea63e5d67bd114e6a31c2cab4406f OK 2026/11/06 02:10:24 DEBUG : sub dir/potato3: md5 = 4c46f4dbc711a2fedd8791eb4db2b0de OK --- PASS: TestCount (8.65s) === RUN TestDelete run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:10:29 DEBUG : small: md5 = f4c3870644ddb7479a035955c35d9049 OK 2026/11/06 02:10:31 DEBUG : medium: md5 = d75088b409d1ae9f91e8739963105650 OK 2026/11/06 02:10:32 DEBUG : large: md5 = 61b459f572ea89904e5c641e22da2583 OK 2026/11/06 02:10:32 DEBUG : Waiting for deletions to finish 2026/11/06 02:10:33 DEBUG : large: Excluded (Size Filter) 2026/11/06 02:10:33 INFO : medium: Deleted 2026/11/06 02:10:33 INFO : small: Deleted --- PASS: TestDelete (6.67s) === RUN TestDeleteFatalError run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:10:36 DEBUG : file0: md5 = 61122ebbe91cb5ef6d02eeecc3330d3d OK 2026/11/06 02:10:38 DEBUG : file1: md5 = 67a84990c3e45389e45e4f852414d3f7 OK 2026/11/06 02:10:39 DEBUG : file2: md5 = 3d4c88db7a3d3764a4232b4b2fd0c481 OK 2026/11/06 02:10:40 DEBUG : file3: md5 = f014d8fd9382f5fab3437284204ba0f3 OK 2026/11/06 02:10:42 DEBUG : file4: md5 = ae1d4c924bc5f266ea24798ce237869e OK 2026/11/06 02:10:44 DEBUG : file5: md5 = edb430a2aabd8a01c4d775cf12095702 OK 2026/11/06 02:10:45 DEBUG : file6: md5 = 8f849551c63a49acd4182cbf284d6fec OK 2026/11/06 02:10:47 DEBUG : file7: md5 = f2796b31ffe70b053410492413671675 OK 2026/11/06 02:10:48 DEBUG : file8: md5 = 7c4b70af1649744b23c6ad79338335de OK 2026/11/06 02:10:50 DEBUG : file9: md5 = 7d04543012cf27e3357d41f24f183be7 OK 2026/11/06 02:10:51 DEBUG : file10: md5 = d6c5e4d174a2bfcf17e5b4e3bdcfb3ff OK 2026/11/06 02:10:52 DEBUG : file11: md5 = 39b8c40129e0ecac6ebacc4e093f4cce OK 2026/11/06 02:10:54 DEBUG : file12: md5 = 85e3ffd358bcca22d21d5161a9026453 OK 2026/11/06 02:10:55 DEBUG : file13: md5 = 7e87765b451610bdcdde3d4f65aeb0b3 OK 2026/11/06 02:10:57 DEBUG : file14: md5 = e02b4146761ff27ecd19eac74d9a1f89 OK 2026/11/06 02:10:58 DEBUG : file15: md5 = 6fd64f6f78f9619b689a9ea80c0f2465 OK 2026/11/06 02:10:59 DEBUG : file16: md5 = a3e2e9fe0a3d438480d173e685eb34dd OK 2026/11/06 02:11:01 DEBUG : file17: md5 = f8bc75b2682fd66c78108774368e3c05 OK 2026/11/06 02:11:02 DEBUG : file18: md5 = ceb3f2aa865647b206dba94b72d73437 OK 2026/11/06 02:11:04 DEBUG : file19: md5 = b896d1396ee34c0a66da7799faeaf3cd OK 2026/11/06 02:11:04 DEBUG : Waiting for deletions to finish 2026/11/06 02:11:04 ERROR : file19: Got fatal error on delete: --max-delete threshold reached 2026/11/06 02:11:04 ERROR : file2: Got fatal error on delete: --max-delete threshold reached 2026/11/06 02:11:04 ERROR : file17: Got fatal error on delete: --max-delete threshold reached 2026/11/06 02:11:04 ERROR : file14: Got fatal error on delete: --max-delete threshold reached 2026/11/06 02:11:04 ERROR : file15: Got fatal error on delete: --max-delete threshold reached 2026/11/06 02:11:04 ERROR : file5: Got fatal error on delete: --max-delete threshold reached 2026/11/06 02:11:04 ERROR : file7: Got fatal error on delete: --max-delete threshold reached 2026/11/06 02:11:04 ERROR : file13: Got fatal error on delete: --max-delete threshold reached 2026/11/06 02:11:04 ERROR : file3: Got fatal error on delete: --max-delete threshold reached 2026/11/06 02:11:04 ERROR : file10: Got fatal error on delete: --max-delete threshold reached 2026/11/06 02:11:04 ERROR : file0: Got fatal error on delete: --max-delete threshold reached 2026/11/06 02:11:04 ERROR : file9: Got fatal error on delete: --max-delete threshold reached 2026/11/06 02:11:04 ERROR : file18: Got fatal error on delete: --max-delete threshold reached 2026/11/06 02:11:04 ERROR : file4: Got fatal error on delete: --max-delete threshold reached 2026/11/06 02:11:04 ERROR : file12: Got fatal error on delete: --max-delete threshold reached 2026/11/06 02:11:04 ERROR : file6: Got fatal error on delete: --max-delete threshold reached 2026/11/06 02:11:04 ERROR : file11: Got fatal error on delete: --max-delete threshold reached 2026/11/06 02:11:04 ERROR : file1: Got fatal error on delete: --max-delete threshold reached 2026/11/06 02:11:04 ERROR : file8: Got fatal error on delete: --max-delete threshold reached 2026/11/06 02:11:04 ERROR : file16: Got fatal error on delete: --max-delete threshold reached --- PASS: TestDeleteFatalError (39.05s) === RUN TestMaxDelete run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:11:15 DEBUG : small: md5 = 29f7a0bfbe36f7a85420af0102c5df75 OK 2026/11/06 02:11:16 DEBUG : medium: md5 = 89bed3a2287417970648f60bd1186269 OK 2026/11/06 02:11:18 DEBUG : large: md5 = d5ab3c45f6d5e31e22af7ed361001079 OK 2026/11/06 02:11:18 DEBUG : Waiting for deletions to finish 2026/11/06 02:11:18 ERROR : large: Got fatal error on delete: --max-delete threshold reached 2026/11/06 02:11:19 INFO : small: Deleted 2026/11/06 02:11:19 INFO : medium: Deleted --- PASS: TestMaxDelete (6.87s) === RUN TestMaxDeleteSizeLargeFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:11:22 DEBUG : small: md5 = 79a9d81d58ccec78bee9b7704d2771d0 OK 2026/11/06 02:11:23 DEBUG : medium: md5 = 3177573c95103d05216d60878db78a2b OK 2026/11/06 02:11:25 DEBUG : large: md5 = bd02759b9b60b19daab52bb48c7b45ef OK 2026/11/06 02:11:25 DEBUG : Waiting for deletions to finish 2026/11/06 02:11:25 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached 2026/11/06 02:11:26 INFO : medium: Deleted 2026/11/06 02:11:26 INFO : small: Deleted --- PASS: TestMaxDeleteSizeLargeFile (6.99s) === RUN TestMaxDeleteSize run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:11:29 DEBUG : small: md5 = 99a0d368cb7163c4c5b986e777e04e17 OK 2026/11/06 02:11:30 DEBUG : medium: md5 = ee72ca060a420b19a10101805446fb73 OK 2026/11/06 02:11:32 DEBUG : large: md5 = b77f036ed5275b3ab68cb941be5ef004 OK 2026/11/06 02:11:32 DEBUG : Waiting for deletions to finish 2026/11/06 02:11:32 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached 2026/11/06 02:11:33 INFO : small: Deleted 2026/11/06 02:11:33 INFO : medium: Deleted --- PASS: TestMaxDeleteSize (7.14s) === RUN TestReadFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:11:36 DEBUG : ReadFile: md5 = 6c00bd67e23f0268b54fd3b38a6a7403 OK --- PASS: TestReadFile (4.13s) === RUN TestRetry 2026/11/06 02:11:39 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 1/5 2026/11/06 02:11:39 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 2/5 2026/11/06 02:11:39 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2026/11/06 02:11:39 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2026/11/06 02:11:39 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2026/11/06 02:11:39 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms --- PASS: TestRetry (0.04s) === RUN TestRetryAfterContextCancel 2026/11/06 02:11:39 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-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:11:40 DEBUG : file1: md5 = 6aa5e89c61863e09afef748a5b507d15 OK 2026/11/06 02:11:42 DEBUG : file2: md5 = 204e0dfc03db9a61dfd9e9eb046a5b04 OK --- PASS: TestCat (16.53s) === RUN TestPurge 2026/11/06 02:11:55 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-yokocox6yoqo" 2026/11/06 02:11:55 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/11/06 02:11:55 DEBUG : Creating backend with remote "TestDrive:crypt/tnuje7qiiu13qfsbr3uvt3gulklk9dvplrao9ialtb0190n4fdtg" 2026/11/06 02:11:56 DEBUG : Creating backend with remote "/tmp/rclone760937342" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-yokocox6yoqo'", Local "Local file system at /tmp/rclone760937342", Modify Window "1ms" 2026/11/06 02:12:01 DEBUG : A1/B1/C1/one: md5 = faaa188c147b77758ad427b28e2f6855 OK 2026/11/06 02:12:01 INFO : A2: Making directory 2026/11/06 02:12:01 INFO : A1/B2: Making directory 2026/11/06 02:12:02 INFO : A1/B2/C2: Making directory 2026/11/06 02:12:03 INFO : A1/B1/C3: Making directory 2026/11/06 02:12:04 INFO : A3: Making directory 2026/11/06 02:12:05 INFO : A3/B3: Making directory 2026/11/06 02:12:05 INFO : A3/B3/C4: Making directory 2026/11/06 02:12:08 DEBUG : A1/two: md5 = d028aaeab22269b21d64b72ae4b8a98f OK 2026/11/06 02:12:11 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-yokocox6yoqo': Purge remote 2026/11/06 02:12:11 NOTICE: purge failed: directory not found --- PASS: TestPurge (16.13s) === RUN TestRmdirsNoLeaveRoot run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:12:15 DEBUG : A1/B1/C1/one: md5 = 347eb227d2d430703175e62593a8f555 OK 2026/11/06 02:12:15 INFO : A2: Making directory 2026/11/06 02:12:16 INFO : A1/B2: Making directory 2026/11/06 02:12:16 INFO : A1/B2/C2: Making directory 2026/11/06 02:12:18 INFO : A1/B1/C3: Making directory 2026/11/06 02:12:19 INFO : A3: Making directory 2026/11/06 02:12:19 INFO : A3/B3: Making directory 2026/11/06 02:12:20 INFO : A3/B3/C4: Making directory 2026/11/06 02:12:22 DEBUG : A1/two: md5 = 640653d372ce8db4a99b4a149e169765 OK 2026/11/06 02:12:23 DEBUG : removing 1 level 3 directories 2026/11/06 02:12:23 INFO : A3/B3/C4: Removing directory 2026/11/06 02:12:26 DEBUG : removing 2 level 3 directories 2026/11/06 02:12:26 INFO : A1/B2/C2: Removing directory 2026/11/06 02:12:26 INFO : A1/B1/C3: Removing directory 2026/11/06 02:12:27 DEBUG : removing 2 level 2 directories 2026/11/06 02:12:27 INFO : A3/B3: Removing directory 2026/11/06 02:12:27 INFO : A1/B2: Removing directory 2026/11/06 02:12:28 DEBUG : removing 2 level 1 directories 2026/11/06 02:12:28 INFO : A3: Removing directory 2026/11/06 02:12:28 INFO : A2: Removing directory 2026/11/06 02:12:31 DEBUG : removing 1 level 3 directories 2026/11/06 02:12:31 INFO : A1/B1/C1: Removing directory 2026/11/06 02:12:32 DEBUG : removing 1 level 2 directories 2026/11/06 02:12:32 INFO : A1/B1: Removing directory 2026/11/06 02:12:33 DEBUG : removing 1 level 1 directories 2026/11/06 02:12:33 INFO : A1: Removing directory 2026/11/06 02:12:34 DEBUG : removing 1 level 0 directories 2026/11/06 02:12:34 INFO : Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara': Removing directory --- PASS: TestRmdirsNoLeaveRoot (23.64s) === RUN TestRmdirsLeaveRoot run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:12:36 INFO : A1: Making directory 2026/11/06 02:12:36 INFO : A1/B1: Making directory 2026/11/06 02:12:37 INFO : A1/B1/C1: Making directory 2026/11/06 02:12:39 DEBUG : removing 1 level 3 directories 2026/11/06 02:12:39 INFO : A1/B1/C1: Removing directory 2026/11/06 02:12:40 DEBUG : removing 1 level 2 directories 2026/11/06 02:12:40 INFO : A1/B1: Removing directory --- PASS: TestRmdirsLeaveRoot (7.88s) === RUN TestRmdirsWithFilter run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:12:43 INFO : A1: Making directory 2026/11/06 02:12:44 INFO : A1/B1: Making directory 2026/11/06 02:12:44 INFO : A1/B1/C1: Making directory 2026/11/06 02:12:47 DEBUG : removing 1 level 3 directories 2026/11/06 02:12:47 INFO : A1/B1/C1: Removing directory 2026/11/06 02:12:48 DEBUG : removing 1 level 2 directories 2026/11/06 02:12:48 INFO : A1/B1: Removing directory --- PASS: TestRmdirsWithFilter (7.23s) === RUN TestCopyURL run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:12:52 DEBUG : file1: md5 = 239a1b208701d55ba0b93d89a35d8668 OK 2026/11/06 02:12:52 DEBUG : file1: size = 14 OK 2026/11/06 02:12:52 DEBUG : filename.txt: File name found in url 2026/11/06 02:12:54 DEBUG : filename.txt: md5 = 1c3184c4e80732a2e07816d9c7ed0292 OK 2026/11/06 02:12:54 DEBUG : filename.txt: size = 14 OK 2026/11/06 02:12:54 DEBUG : headerfilename.txt: filename found in Content-Disposition header. 2026/11/06 02:12:55 DEBUG : headerfilename.txt: md5 = b8252f0eeef4b8c095e7b0e604fe1513 OK 2026/11/06 02:12:55 DEBUG : headerfilename.txt: size = 14 OK 2026/11/06 02:12:56 DEBUG : file2: md5 = ff3882902a69eac2a81f6bfa6c21284b OK 2026/11/06 02:12:56 DEBUG : file2: size = 14 OK --- PASS: TestCopyURL (8.75s) === RUN TestCopyURLDownloadHeaders run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:13:00 DEBUG : file1: md5 = 23a8f45e5c5dfa87497b1921592c64ff OK 2026/11/06 02:13:00 DEBUG : file1: size = 14 OK --- PASS: TestCopyURLDownloadHeaders (2.20s) === RUN TestCopyURLToWriter --- PASS: TestCopyURLToWriter (0.00s) === RUN TestMoveFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:13:01 DEBUG : file1: Need to transfer - File not found at Destination 2026/11/06 02:13:03 DEBUG : sub/file2: md5 = 8c1473f52ae4e88c14b67f423db6230a OK 2026/11/06 02:13:03 DEBUG : sub/file2: size = 14 OK 2026/11/06 02:13:03 INFO : file1: Copied (new) to: sub/file2 2026/11/06 02:13:03 INFO : file1: Deleted 2026/11/06 02:13:04 DEBUG : sub/file2: size = 14 OK 2026/11/06 02:13:04 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/11/06 02:13:04 DEBUG : file1: Unchanged skipping 2026/11/06 02:13:04 INFO : file1: Deleted 2026/11/06 02:13:05 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara': don't need to copy/move sub/file2, it is already at target location --- PASS: TestMoveFile (5.98s) === RUN TestMoveFileWithIgnoreExisting run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:13:07 DEBUG : file1: Need to transfer - File not found at Destination 2026/11/06 02:13:09 DEBUG : file1: md5 = 04028c9eae3142bfff9807113fdd2ea8 OK 2026/11/06 02:13:09 DEBUG : file1: size = 14 OK 2026/11/06 02:13:09 INFO : file1: Copied (new) 2026/11/06 02:13:09 INFO : file1: Deleted 2026/11/06 02:13:09 DEBUG : file1: Destination exists, skipping 2026/11/06 02:13:09 DEBUG : file1: Not removing source file as destination file exists and --ignore-existing is set --- PASS: TestMoveFileWithIgnoreExisting (3.29s) === RUN TestMoveFileImmutable run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:13:12 DEBUG : existing: md5 = c5dd529b8fea66bb956161214d527bbc OK 2026/11/06 02:13:12 DEBUG : existing: size = 8 (Local file system at /tmp/rclone707300349) 2026/11/06 02:13:12 DEBUG : existing: size = 6 (Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara') 2026/11/06 02:13:12 DEBUG : existing: Sizes differ 2026/11/06 02:13:12 ERROR : existing: Source and destination exist but do not match: immutable file modified --- PASS: TestMoveFileImmutable (3.20s) === RUN TestCaseInsensitiveMoveFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFile (0.41s) === RUN TestMoveOntoItself === RUN TestMoveOntoItself/viaSubdir=false,disableMove=false 2026/11/06 02:13:14 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-fuvukav6silo" 2026/11/06 02:13:14 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/11/06 02:13:14 DEBUG : Creating backend with remote "TestDrive:crypt/s8cl7b1ogkt69hhf38cubmlf15ko1arnmhhsc441d9thu5h2upkg" 2026/11/06 02:13:15 DEBUG : Creating backend with remote "/tmp/rclone1359335285" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fuvukav6silo'", Local "Local file system at /tmp/rclone1359335285", Modify Window "1ms" 2026/11/06 02:13:18 DEBUG : sub/file1: md5 = b559988c1857037e4419fe3a9ec81d96 OK 2026/11/06 02:13:19 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-fuvukav6silo': Purge remote === RUN TestMoveOntoItself/viaSubdir=false,disableMove=true 2026/11/06 02:13:19 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-rarecom8ruxo" 2026/11/06 02:13:19 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/11/06 02:13:19 DEBUG : Creating backend with remote "TestDrive:crypt/r41q7kkvvk8ss13q8mb5925c80driuma0qjfv1u82bnvgengcftg" 2026/11/06 02:13:20 DEBUG : Creating backend with remote "/tmp/rclone4115986051" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-rarecom8ruxo'", Local "Local file system at /tmp/rclone4115986051", Modify Window "1ms" 2026/11/06 02:13:23 DEBUG : sub/file1: md5 = 01b6b612a6ba960e47c0bdb4159881f4 OK 2026/11/06 02:13:23 DEBUG : Reset feature "Move" 2026/11/06 02:13:24 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-rarecom8ruxo': Purge remote === RUN TestMoveOntoItself/viaSubdir=true,disableMove=false 2026/11/06 02:13:24 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-daciwar2jije" 2026/11/06 02:13:24 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/11/06 02:13:24 DEBUG : Creating backend with remote "TestDrive:crypt/oit1pnn6b6gk4mm39jljimjao7qusq8p9l9it0omve44jiaemg70" 2026/11/06 02:13:25 DEBUG : Creating backend with remote "/tmp/rclone3888957867" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-daciwar2jije'", Local "Local file system at /tmp/rclone3888957867", Modify Window "1ms" 2026/11/06 02:13:28 DEBUG : sub/file1: md5 = 7d789e492e651034fefddaca7415da07 OK 2026/11/06 02:13:28 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-daciwar2jije/sub" 2026/11/06 02:13:28 DEBUG : Creating backend with remote "TestDrive:crypt/oit1pnn6b6gk4mm39jljimjao7qusq8p9l9it0omve44jiaemg70/150fuo3cn4j1uenq6r4g4qk6t4" 2026/11/06 02:13:30 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-daciwar2jije': Purge remote === RUN TestMoveOntoItself/viaSubdir=true,disableMove=true 2026/11/06 02:13:30 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-hufopun8mayo" 2026/11/06 02:13:30 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/11/06 02:13:30 DEBUG : Creating backend with remote "TestDrive:crypt/3sr48pa38t7belnaonm7ebc5pka0ne4bg7f9g5qq5d1th7vduugg" 2026/11/06 02:13:31 DEBUG : Creating backend with remote "/tmp/rclone2679065871" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hufopun8mayo'", Local "Local file system at /tmp/rclone2679065871", Modify Window "1ms" 2026/11/06 02:13:34 DEBUG : sub/file1: md5 = 3c5e013eb08175345a0eca48f704f71a OK 2026/11/06 02:13:34 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-hufopun8mayo/sub" 2026/11/06 02:13:34 DEBUG : Creating backend with remote "TestDrive:crypt/3sr48pa38t7belnaonm7ebc5pka0ne4bg7f9g5qq5d1th7vduugg/150fuo3cn4j1uenq6r4g4qk6t4" 2026/11/06 02:13:35 DEBUG : Reset feature "Move" 2026/11/06 02:13:35 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-hufopun8mayo': Purge remote --- PASS: TestMoveOntoItself (21.97s) --- PASS: TestMoveOntoItself/viaSubdir=false,disableMove=false (5.16s) --- PASS: TestMoveOntoItself/viaSubdir=false,disableMove=true (5.09s) --- PASS: TestMoveOntoItself/viaSubdir=true,disableMove=false (5.81s) --- PASS: TestMoveOntoItself/viaSubdir=true,disableMove=true (5.91s) === RUN TestMoveUnicodeEquivalentWithoutServerSideMove 2026/11/06 02:13:36 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-zepeqid3dege" 2026/11/06 02:13:36 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/11/06 02:13:36 DEBUG : Creating backend with remote "TestDrive:crypt/2513aq5qkvo7temkqslg9nhmlolsfa46pe3o8026lf05gp4v27jg" 2026/11/06 02:13:37 DEBUG : Creating backend with remote "/tmp/rclone2850520247" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-zepeqid3dege'", Local "Local file system at /tmp/rclone2850520247", Modify Window "1ms" 2026/11/06 02:13:37 DEBUG : Reset feature "Move" 2026/11/06 02:13:40 DEBUG : sub/équivalent.txt: md5 = f79bf5127e51ff165a844fd9c61589fa OK 2026/11/06 02:13:40 DEBUG : sub/équivalent.txt: moving to sub/équivalent.txt-rclone-move-guporox1 2026/11/06 02:13:41 DEBUG : sub/équivalent.txt-rclone-move-guporox1: size = 14 OK 2026/11/06 02:13:41 INFO : sub/équivalent.txt: Copied (server-side copy) to: sub/équivalent.txt-rclone-move-guporox1 2026/11/06 02:13:42 INFO : sub/équivalent.txt: Deleted 2026/11/06 02:13:42 DEBUG : sub/équivalent.txt: moving to sub/équivalent.txt 2026/11/06 02:13:43 DEBUG : sub/équivalent.txt: size = 14 OK 2026/11/06 02:13:43 INFO : sub/équivalent.txt-rclone-move-guporox1: Copied (server-side copy) to: sub/équivalent.txt 2026/11/06 02:13:43 INFO : sub/équivalent.txt-rclone-move-guporox1: Deleted 2026/11/06 02:13:44 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-zepeqid3dege': Purge remote --- PASS: TestMoveUnicodeEquivalentWithoutServerSideMove (8.52s) === RUN TestCaseInsensitiveMoveWithoutServerSideMove 2026/11/06 02:13:44 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-hovones7teci" 2026/11/06 02:13:44 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/11/06 02:13:44 DEBUG : Creating backend with remote "TestDrive:crypt/f1eh29ngdm81hp0vfvi7rpgfgarcr3oritfg6f9ns5r3t23fj2bg" 2026/11/06 02:13:45 DEBUG : Creating backend with remote "/tmp/rclone4038572104" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hovones7teci'", Local "Local file system at /tmp/rclone4038572104", Modify Window "1ms" operations_test.go:1198: remote is case sensitive 2026/11/06 02:13:45 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-hovones7teci': Purge remote 2026/11/06 02:13:46 NOTICE: purge failed: directory not found --- SKIP: TestCaseInsensitiveMoveWithoutServerSideMove (1.22s) === RUN TestCaseInsensitiveMoveFileDryRun run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFileDryRun (0.42s) === RUN TestMoveFileBackupDir run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:13:48 DEBUG : dst/file1: md5 = 77a911a822efb6d7b38064a5efc532d9 OK 2026/11/06 02:13:49 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-gevuxol5nara/backup" 2026/11/06 02:13:49 DEBUG : Creating backend with remote "TestDrive:crypt/o954njvhu8i73jqo00n8k29tcik3fbkejdsvjlmnh110c5qaqgog/1nrff024r7pq65ecp72fc28jb0" 2026/11/06 02:13:50 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone707300349) 2026/11/06 02:13:50 DEBUG : dst/file1: size = 18 (Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara') 2026/11/06 02:13:50 DEBUG : dst/file1: Sizes differ 2026/11/06 02:13:53 INFO : dst/file1: Moved (server-side) 2026/11/06 02:13:54 DEBUG : dst/file1: md5 = ade16ab5fecf228db4a826eca8d96771 OK 2026/11/06 02:13:54 DEBUG : dst/file1: size = 14 OK 2026/11/06 02:13:54 INFO : dst/file1: Copied (new) 2026/11/06 02:13:54 INFO : dst/file1: Deleted --- PASS: TestMoveFileBackupDir (13.10s) === 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-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:14:01 DEBUG : A/file0: md5 = a45ae860c295b23632953fc5ed200718 OK 2026/11/06 02:14:03 DEBUG : A/file1: md5 = ed5713c1976694b4aafb01fff89b401d OK 2026/11/06 02:14:04 DEBUG : A/file2: md5 = b43b64478e6df22a878fe0f66635a048 OK 2026/11/06 02:14:06 DEBUG : A/file3: md5 = 91d5de82aef6e7212f523181c9311518 OK 2026/11/06 02:14:07 DEBUG : A/file4: md5 = 5981ced27b0ef9ecc02e73c2cdecf2b5 OK 2026/11/06 02:14:09 DEBUG : A/file5: md5 = 88ae5e9ee0da1524974726e5b6586d81 OK 2026/11/06 02:14:10 DEBUG : A/file6: md5 = 4908a9f849e9c7e21732bc48c74be9f4 OK 2026/11/06 02:14:11 DEBUG : A/file7: md5 = 9b50e1c06c4cbfec54478544fd92ef34 OK 2026/11/06 02:14:13 DEBUG : A/file8: md5 = 40e10060593f3042b531f705cd9f02e4 OK 2026/11/06 02:14:14 DEBUG : A/file9: md5 = 24cc50d1c3679be8051dae83484bbfb1 OK 2026/11/06 02:14:16 DEBUG : A/file10: md5 = cf7285ebe6d2250e7a8803672fb7c90a OK 2026/11/06 02:14:17 DEBUG : A/file11: md5 = 311ac766d25f819be8e5a82d18eeb184 OK 2026/11/06 02:14:18 DEBUG : A/file12: md5 = 5fcf2b782d34489ed978e8417214f37e OK 2026/11/06 02:14:20 DEBUG : A/file13: md5 = f6e90040d396fc410b8d58e538a0c776 OK 2026/11/06 02:14:21 DEBUG : A/file14: md5 = 6fb1b80e5ee6e205ed87af269cf1c1b3 OK 2026/11/06 02:14:23 DEBUG : A/file15: md5 = 664e533c9e9a9d01363cb42b8e0685bf OK 2026/11/06 02:14:24 DEBUG : A/file16: md5 = c2eb80e8211bc106e7ff83312ea031e6 OK 2026/11/06 02:14:25 DEBUG : A/file17: md5 = bd0408e0e9c8cfde434f26156b55bcb1 OK 2026/11/06 02:14:27 DEBUG : A/file18: md5 = 5165c3a17ad2c436e620a4e0953311ae OK 2026/11/06 02:14:28 DEBUG : A/file19: md5 = 48b6d5926dc86ec4b08b2eb95331da28 OK operations_test.go:1563: Skipping test on non local remote --- SKIP: TestDirMoveMoveError (40.11s) === RUN TestDirMoveContext run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:14:41 DEBUG : A/one: md5 = 81480c72190fce4eb8bb5a8c3b68c021 OK 2026/11/06 02:14:43 DEBUG : A/two: md5 = 5d03391ac6d2da52b14c852e17e9f2e4 OK operations_test.go:1563: Skipping test on non local remote --- SKIP: TestDirMoveContext (6.15s) === RUN TestDirMove run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:14:48 DEBUG : A1/one: md5 = 2bac1f5f3f528bfbabda6d9f4aa12d88 OK 2026/11/06 02:14:49 DEBUG : A1/two: md5 = 03d53c9226fb8fd577cd825a3160228a OK 2026/11/06 02:14:51 DEBUG : A1/B1/three: md5 = 59869b794d7aa00c033472d27852c997 OK 2026/11/06 02:14:54 DEBUG : A1/B1/C1/four: md5 = 1cd4d285c745996a668de4f9ce1c7c2a OK 2026/11/06 02:14:56 DEBUG : A1/B1/C2/five: md5 = f0e1b507872283d8eef630da872eff08 OK 2026/11/06 02:14:56 INFO : A1/B2: Making directory 2026/11/06 02:14:56 INFO : A1/B1/C3: Making directory 2026/11/06 02:15:06 INFO : A2/two: Moved (server-side) to: A3/two 2026/11/06 02:15:06 INFO : A2/B1/C2/five: Moved (server-side) to: A3/B1/C2/five 2026/11/06 02:15:06 INFO : A2/B1/C1/four: Moved (server-side) to: A3/B1/C1/four 2026/11/06 02:15:06 INFO : A2/one: Moved (server-side) to: A3/one 2026/11/06 02:15:06 INFO : A2/B1/three: Moved (server-side) to: A3/B1/three 2026/11/06 02:15:12 INFO : Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara': Can't DirMove - falling back to file moves: can't move directory - incompatible remotes 2026/11/06 02:15:18 INFO : A3/two: Moved (server-side) to: A4/two 2026/11/06 02:15:18 INFO : A3/B1/C1/four: Moved (server-side) to: A4/B1/C1/four 2026/11/06 02:15:18 INFO : A3/B1/C2/five: Moved (server-side) to: A4/B1/C2/five 2026/11/06 02:15:18 INFO : A3/one: Moved (server-side) to: A4/one 2026/11/06 02:15:18 INFO : A3/B1/three: Moved (server-side) to: A4/B1/three --- PASS: TestDirMove (44.97s) === RUN TestGetFsInfo run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:15:30 DEBUG : Config file has changed externally - reloading --- PASS: TestGetFsInfo (0.45s) === RUN TestRcat === RUN TestRcat/withChecksum=false,ignoreChecksum=false run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:15:31 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara': File to upload is small (34 bytes), uploading instead of streaming 2026/11/06 02:15:32 DEBUG : no_checksum_small_file_from_pipe: md5 = d452e24ae84e9bfe9b24bcc4ccc9715d OK 2026/11/06 02:15:32 DEBUG : no_checksum_small_file_from_pipe: size = 34 OK 2026/11/06 02:15:32 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2026/11/06 02:15:32 DEBUG : no_checksum_small_file_from_pipe: Size of src and dst objects identical 2026/11/06 02:15:33 DEBUG : qc9k4uauqa9ls6a2r6lo8jf4b1m7r0510pkurois1cc70egbs4h0: Sending chunk 0 length 102465 2026/11/06 02:15:34 DEBUG : no_checksum_big_file_from_pipe: md5 = 83b621f15c3826e965b3b49923bebd86 OK 2026/11/06 02:15:34 DEBUG : no_checksum_big_file_from_pipe: size = 102401 OK 2026/11/06 02:15:34 DEBUG : no_checksum_big_file_from_pipe: Size of src and dst objects identical === RUN TestRcat/withChecksum=true,ignoreChecksum=false run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:15:36 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara': File to upload is small (34 bytes), uploading instead of streaming 2026/11/06 02:15:38 DEBUG : with_checksum_small_file_from_pipe: md5 = fcf2b9fca6b924ab6ae1dbeadaeeac83 OK 2026/11/06 02:15:38 DEBUG : with_checksum_small_file_from_pipe: size = 34 OK 2026/11/06 02:15:38 DEBUG : with_checksum_small_file_from_pipe: Size of src and dst objects identical 2026/11/06 02:15:38 DEBUG : e74jo8a7lr1aimg2u78fct67gnuetc1qujiofaci0c8fnq7rjt1d4i296pcklqhgdihs03etvgdi4: Sending chunk 0 length 102465 2026/11/06 02:15:39 DEBUG : with_checksum_big_file_from_pipe: md5 = 2f5d7a5b8b7dddee460fa2b33832b53e OK 2026/11/06 02:15:39 DEBUG : with_checksum_big_file_from_pipe: size = 102401 OK 2026/11/06 02:15:39 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-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:15:41 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara': File to upload is small (34 bytes), uploading instead of streaming 2026/11/06 02:15:43 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK 2026/11/06 02:15:43 DEBUG : ignore_checksum_small_file_from_pipe: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/11/06 02:15:44 DEBUG : 1963rn6olajfbjn4nbqi9nacu7bk879vpf0d2bg8r0ndcqqsfntob0urt056p0qujf574rqnb5f62: Sending chunk 0 length 102465 2026/11/06 02:15:45 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK 2026/11/06 02:15:45 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-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:15:47 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara': File to upload is small (34 bytes), uploading instead of streaming 2026/11/06 02:15:48 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK 2026/11/06 02:15:48 DEBUG : ignore_checksum_small_file_from_pipe: Size of src and dst objects identical 2026/11/06 02:15:49 DEBUG : 1963rn6olajfbjn4nbqi9nacu7bk879vpf0d2bg8r0ndcqqsfntob0urt056p0qujf574rqnb5f62: Sending chunk 0 length 102465 2026/11/06 02:15:50 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK 2026/11/06 02:15:50 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical --- PASS: TestRcat (21.02s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (5.14s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (5.15s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (5.46s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (5.26s) === RUN TestRcatMetadata run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" === RUN TestRcatMetadata/Normal 2026/11/06 02:15:52 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara': File to upload is small (48 bytes), uploading instead of streaming 2026/11/06 02:15:53 DEBUG : rcat_metadata: md5 = 5d03b0f0c4cc54e48df1e4dd94623d95 OK 2026/11/06 02:15:53 DEBUG : rcat_metadata: size = 48 OK 2026/11/06 02:15:53 DEBUG : rcat_metadata: Size of src and dst objects identical === RUN TestRcatMetadata/ViaDisk 2026/11/06 02:15:55 DEBUG : rijssoobhbn85c5p1imhg55m6frank1nlplla6hkdjlqmqb2471g: Sending chunk 0 length 111 2026/11/06 02:15:56 DEBUG : rcat_metadata_uploadcutoff0: md5 = 72e8d6a42b2cf6808a03828f682bf55a OK 2026/11/06 02:15:56 DEBUG : rcat_metadata_uploadcutoff0: size = 63 OK 2026/11/06 02:15:56 DEBUG : rcat_metadata_uploadcutoff0: Size of src and dst objects identical --- PASS: TestRcatMetadata (5.79s) --- PASS: TestRcatMetadata/Normal (2.54s) --- PASS: TestRcatMetadata/ViaDisk (2.82s) === RUN TestRcatSize run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:15:59 DEBUG : potato1: md5 = 7262c6509ba867edcee0c3a979a5527f OK 2026/11/06 02:15:59 DEBUG : potato1: size = 60 OK 2026/11/06 02:15:59 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara': File to upload is small (60 bytes), uploading instead of streaming 2026/11/06 02:16:01 DEBUG : potato2: md5 = 7e370842f5588553946f406fc5fc939d OK 2026/11/06 02:16:01 DEBUG : potato2: size = 60 OK 2026/11/06 02:16:01 DEBUG : potato2: Size of src and dst objects identical --- PASS: TestRcatSize (4.64s) === RUN TestRcatSizeShortEOF run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:16:04 DEBUG : potato1: md5 = 31f6117dc1d1e75dfc66f5b65e83ea8f OK 2026/11/06 02:16:04 DEBUG : potato1: size = 120 (Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara') 2026/11/06 02:16:04 DEBUG : potato1: size = 60 (Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara') 2026/11/06 02:16:04 ERROR : potato1: corrupted on transfer: sizes differ src 120 vs dst(Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara') 60 2026/11/06 02:16:04 INFO : potato1: Removing failed copy --- PASS: TestRcatSizeShortEOF (2.40s) === RUN TestRcatSizeMetadata run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:16:06 DEBUG : potato1: md5 = 49082478b8f23f60fc94f1e7d2d39900 OK 2026/11/06 02:16:06 DEBUG : potato1: size = 60 OK 2026/11/06 02:16:06 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara': File to upload is small (60 bytes), uploading instead of streaming 2026/11/06 02:16:08 DEBUG : potato2: md5 = 91f36528482f8dd0dac2bf3a44c19cf4 OK 2026/11/06 02:16:08 DEBUG : potato2: size = 60 OK 2026/11/06 02:16:08 DEBUG : potato2: Size of src and dst objects identical --- PASS: TestRcatSizeMetadata (5.15s) === RUN TestRcatSizeUploadHeaders run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:16:11 DEBUG : potato1: md5 = 7e218cd301ca1ab5b41ea5f7e2b30ba7 OK 2026/11/06 02:16:11 DEBUG : potato1: size = 60 OK --- PASS: TestRcatSizeUploadHeaders (2.47s) === RUN TestRcatSizeChecksum === RUN TestRcatSizeChecksum/Corrupted run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" operations_test.go:2048: Skipping as destination doesn't support hashes === RUN TestRcatSizeChecksum/SizeDiffers run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:16:14 DEBUG : potato4: md5 = 4aae7757f0d1f0a366702077601e4a0b OK 2026/11/06 02:16:14 DEBUG : potato4: size = 60 (Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara') 2026/11/06 02:16:14 DEBUG : potato4: size = 59 (Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara') 2026/11/06 02:16:14 ERROR : potato4: corrupted on transfer: sizes differ src 60 vs dst(Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara') 59 2026/11/06 02:16:14 INFO : potato4: Removing failed copy === RUN TestRcatSizeChecksum/IgnoreChecksum run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:16:17 DEBUG : potato2: size = 60 OK === RUN TestRcatSizeChecksum/NoHashes run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:16:19 DEBUG : potato3: md5 = bdbe39b41cdcd173a5d7fbf9f4222de2 OK 2026/11/06 02:16:19 DEBUG : potato3: size = 60 OK --- PASS: TestRcatSizeChecksum (7.62s) --- SKIP: TestRcatSizeChecksum/Corrupted (0.46s) --- PASS: TestRcatSizeChecksum/SizeDiffers (2.61s) --- PASS: TestRcatSizeChecksum/IgnoreChecksum (2.27s) --- PASS: TestRcatSizeChecksum/NoHashes (2.29s) === RUN TestTouchDir run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:16:22 DEBUG : potato2: md5 = 1783f7a582ab7d11b5102fdf42740151 OK 2026/11/06 02:16:23 DEBUG : empty space: md5 = b38dcaebf61f1d3ec21261f1d9b3d722 OK 2026/11/06 02:16:25 DEBUG : sub dir/potato3: md5 = a80f073b6b99442337cc097e649a3a51 OK 2026/11/06 02:16:26 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara': Touching "empty space" 2026/11/06 02:16:26 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara': Touching "potato2" 2026/11/06 02:16:26 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara': Touching "sub dir/potato3" --- PASS: TestTouchDir (10.79s) === RUN TestMkdirMetadata run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:16:31 DEBUG : dir with metadata: Making directory with metadata 2026/11/06 02:16:31 INFO : dir with metadata: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) --- PASS: TestMkdirMetadata (2.50s) === RUN TestMkdirModTime run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:16:33 DEBUG : directory with modtime: Making directory with metadata 2026/11/06 02:16:34 INFO : directory with modtime: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/11/06 02:16:34 NOTICE: dry run directory with modtime: Skipped make directory as --dry-run is set --- PASS: TestMkdirModTime (2.35s) === RUN TestCopyDirMetadata run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:16:36 DEBUG : dir with metadata to be copied: Making directory with metadata 2026/11/06 02:16:36 INFO : dir with metadata to be copied: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2026/11/06 02:16:36 DEBUG : Google drive root 'crypt/o954njvhu8i73jqo00n8k29tcik3fbkejdsvjlmnh110c5qaqgog': Skipping btime metadata as can't update it on an existing file: 2026-11-06T02:16:36.024333757Z 2026/11/06 02:16:36 INFO : non existent directory: Updated directory metadata 2026/11/06 02:16:38 DEBUG : Google drive root 'crypt/o954njvhu8i73jqo00n8k29tcik3fbkejdsvjlmnh110c5qaqgog': Skipping btime metadata as can't update it on an existing file: 2026-11-06T02:16:36.024333757Z 2026/11/06 02:16:39 INFO : existing directory: Updated directory metadata --- PASS: TestCopyDirMetadata (4.69s) === RUN TestSetDirModTime run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:16:40 DEBUG : set modtime on non existent directory: Skipping set directory modification time as --no-update-dir-modtime is set 2026/11/06 02:16:42 INFO : set modtime on existing directory: Set directory modification time (using SetModTime) 2026/11/06 02:16:43 INFO : set modtime on existing directory: Set directory modification time (using DirSetModTime) --- PASS: TestSetDirModTime (4.03s) === RUN TestDirsEqual run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:16:44 DEBUG : dir with metadata to be copied: Making directory with metadata 2026/11/06 02:16:44 INFO : dir with metadata to be copied: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2026/11/06 02:16:44 DEBUG : dst: Making directory with metadata 2026/11/06 02:16:45 INFO : dst: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2026/11/06 02:16:45 DEBUG : dst: Directory modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/11/06 02:16:45 INFO : dst: Set directory modification time (using SetModTime) 2026/11/06 02:16:46 INFO : dst: Set directory modification time (using SetModTime) 2026/11/06 02:16:46 DEBUG : dst: Directory modification time the same (differ by 1ns, within tolerance 1ms) 2026/11/06 02:16:46 INFO : dst: Set directory modification time (using SetModTime) 2026/11/06 02:16:46 DEBUG : dst: Destination directory is newer than source, skipping --- PASS: TestDirsEqual (3.70s) === RUN TestRemoveExisting run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara'", Local "Local file system at /tmp/rclone707300349", Modify Window "1ms" 2026/11/06 02:16:50 DEBUG : sub dir/test remove existing: md5 = c9fe743699ca39280f812e862902b1a2 OK 2026/11/06 02:16:52 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: md5 = af33b659ec9a26c014048a889b5cb84f OK 2026/11/06 02:16:54 DEBUG : sub dir/test remove existing: TEST: renaming existing object to "sub dir/test remove existing.koxixec7" before starting 2026/11/06 02:16:54 DEBUG : sub dir/test remove existing.koxixec7: TEST: removing renamed existing file after operation 2026/11/06 02:16:56 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: TEST: renaming existing object to "sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.ledudop0" before starting 2026/11/06 02:16:56 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.ledudop0: TEST: renaming existing back after failed operation 2026/11/06 02:16:58 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: TEST: renaming existing object to "sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.sodetuy1" before starting 2026/11/06 02:16:59 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.sodetuy1: TEST: removing renamed existing file after operation --- PASS: TestRemoveExisting (13.25s) === RUN TestRcatInputFailurePreservesDestination 2026/11/06 02:17:01 DEBUG : Creating backend with remote "/tmp/TestRcatInputFailurePreservesDestination4029144/001" --- PASS: TestRcatInputFailurePreservesDestination (0.00s) === RUN TestRcAbout rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcAbout (0.00s) === RUN TestRcCleanup rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcCleanup (0.00s) === RUN TestRcCopyfile rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcCopyfile (0.00s) === RUN TestRcCopyurl rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcCopyurl (0.00s) === RUN TestRcDelete rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcDelete (0.00s) === RUN TestRcDeletefile rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcDeletefile (0.00s) === RUN TestRcDeletefileBackupDir rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcDeletefileBackupDir (0.00s) === RUN TestRcList rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcList (0.00s) === RUN TestRcStat rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcStat (0.00s) === RUN TestRcSetTier rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcSetTier (0.00s) === RUN TestRcSetTierFile rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcSetTierFile (0.00s) === RUN TestRcMkdir rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcMkdir (0.00s) === RUN TestRcMovefile rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcMovefile (0.00s) === RUN TestRcPurge rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcPurge (0.00s) === RUN TestRcRmdir rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcRmdir (0.00s) === RUN TestRcRmdirs rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcRmdirs (0.00s) === RUN TestRcSize rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcSize (0.00s) === RUN TestRcPublicLink rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcPublicLink (0.00s) === RUN TestRcFsInfo rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcFsInfo (0.00s) === RUN TestUploadFile rc_test.go:31: Skipping test on non local remote --- SKIP: TestUploadFile (0.00s) === RUN TestRcCommand rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcCommand (0.00s) === RUN TestRcDu rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcDu (0.00s) === RUN TestRcCheck rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcCheck (0.00s) === RUN TestRcHashsum rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcHashsum (0.00s) === RUN TestRcHashsumSingleFile rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcHashsumSingleFile (0.00s) === RUN TestRcHashsumFile rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcHashsumFile (0.00s) === RUN TestRcGetFile rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcGetFile (0.00s) PASS 2026/11/06 02:17:01 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-gevuxol5nara': Purge remote "./operations.test -test.v -test.timeout 1h0m0s -remote TestCryptDrive: -verbose" - Finished OK in 22m31.059995402s (try 1/5)