"./operations.test -test.v -test.timeout 1h0m0s -remote TestCryptDrive: -verbose" - Starting (try 1/5) 2026/10/05 05:05:50 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-mivobir3wado" 2026/10/05 05:05:50 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/10/05 05:05:50 DEBUG : Creating backend with remote "TestDrive:crypt/nof1opn6ntbdfclfpa2iuae4bnqqkrnr94r6i17737dc3urifnu0" 2026/10/05 05:05:50 DEBUG : TestDrive: Token expired 2026/10/05 05:05:50 DEBUG : TestDrive: No updated token found in the config file 2026/10/05 05:05:50 DEBUG : TestDrive: Token refresh successful 2026/10/05 05:05:50 DEBUG : Saving config "token" in section "TestDrive" of the config file 2026/10/05 05:05:50 DEBUG : TestDrive: Saved new token in config file 2026/10/05 05:05:51 DEBUG : Creating backend with remote "/tmp/rclone3863668941" === 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-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" multithread_test.go:120: multithread writing not supported --- SKIP: TestMultithreadCopy (0.26s) === RUN TestMultithreadCopyAbort run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" multithread_test.go:120: multithread writing not supported --- SKIP: TestMultithreadCopyAbort (0.27s) === RUN TestMultithreadCopyWriterAtErrors === RUN TestMultithreadCopyWriterAtErrors/open/fatal=false 2026/10/05 05:05:51 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt === RUN TestMultithreadCopyWriterAtErrors/open/fatal=true 2026/10/05 05:05:51 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt === RUN TestMultithreadCopyWriterAtErrors/write/fatal=false 2026/10/05 05:05:51 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2026/10/05 05:05:51 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams 2026/10/05 05:05:51 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting 2026/10/05 05:05:51 DEBUG : file.txt: writing chunk 1 2026/10/05 05:05:51 DEBUG : file.txt: multi-thread copy: chunk 2/4 failed: multi-thread copy: failed to write chunk: disk full 2026/10/05 05:05:51 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting 2026/10/05 05:05:51 DEBUG : file.txt: writing chunk 2 2026/10/05 05:05:51 DEBUG : file.txt: multi-thread copy: chunk 3/4 failed: multi-thread copy: failed to write chunk: disk full 2026/10/05 05:05:51 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting 2026/10/05 05:05:51 DEBUG : file.txt: writing chunk 0 2026/10/05 05:05:51 DEBUG : file.txt: multi-thread copy: chunk 1/4 failed: multi-thread copy: failed to write chunk: disk full 2026/10/05 05:05:51 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit 2026/10/05 05:05:51 DEBUG : file.txt: multi-thread copy: abort failed: multi-thread copy: failed to find temp file when aborting chunk writer: object not found === RUN TestMultithreadCopyWriterAtErrors/write/fatal=true 2026/10/05 05:05:51 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2026/10/05 05:05:51 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams 2026/10/05 05:05:51 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting 2026/10/05 05:05:51 DEBUG : file.txt: writing chunk 1 2026/10/05 05:05:51 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting 2026/10/05 05:05:51 DEBUG : file.txt: writing chunk 0 2026/10/05 05:05:51 DEBUG : file.txt: multi-thread copy: chunk 1/4 failed: multi-thread copy: failed to write chunk: disk full 2026/10/05 05:05:51 DEBUG : file.txt: multi-thread copy: chunk 2/4 failed: multi-thread copy: failed to write chunk: disk full 2026/10/05 05:05:51 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting 2026/10/05 05:05:51 DEBUG : file.txt: writing chunk 2 2026/10/05 05:05:51 DEBUG : file.txt: multi-thread copy: chunk 3/4 failed: multi-thread copy: failed to write chunk: disk full 2026/10/05 05:05:51 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit 2026/10/05 05:05:51 DEBUG : file.txt: multi-thread copy: abort failed: multi-thread copy: failed to find temp file when aborting chunk writer: object not found === RUN TestMultithreadCopyWriterAtErrors/close/fatal=false 2026/10/05 05:05:51 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2026/10/05 05:05:51 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams 2026/10/05 05:05:51 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting 2026/10/05 05:05:51 DEBUG : file.txt: writing chunk 1 2026/10/05 05:05:51 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 finished 2026/10/05 05:05:51 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting 2026/10/05 05:05:51 DEBUG : file.txt: writing chunk 2 2026/10/05 05:05:51 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 finished 2026/10/05 05:05:51 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 starting 2026/10/05 05:05:51 DEBUG : file.txt: writing chunk 3 2026/10/05 05:05:51 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 finished 2026/10/05 05:05:51 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting 2026/10/05 05:05:51 DEBUG : file.txt: writing chunk 0 2026/10/05 05:05:51 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 finished 2026/10/05 05:05:51 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit 2026/10/05 05:05:51 DEBUG : file.txt: multi-thread copy: abort failed: multi-thread copy: failed to find temp file when aborting chunk writer: object not found === RUN TestMultithreadCopyWriterAtErrors/close/fatal=true 2026/10/05 05:05:51 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2026/10/05 05:05:51 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams 2026/10/05 05:05:51 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting 2026/10/05 05:05:51 DEBUG : file.txt: writing chunk 1 2026/10/05 05:05:51 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 finished 2026/10/05 05:05:51 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting 2026/10/05 05:05:51 DEBUG : file.txt: writing chunk 2 2026/10/05 05:05:51 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 finished 2026/10/05 05:05:51 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 starting 2026/10/05 05:05:51 DEBUG : file.txt: writing chunk 3 2026/10/05 05:05:51 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 finished 2026/10/05 05:05:51 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting 2026/10/05 05:05:51 DEBUG : file.txt: writing chunk 0 2026/10/05 05:05:51 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 finished 2026/10/05 05:05:51 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit 2026/10/05 05:05:51 DEBUG : file.txt: multi-thread copy: abort failed: multi-thread copy: failed to find temp file when aborting chunk writer: object not found --- PASS: TestMultithreadCopyWriterAtErrors (0.00s) --- PASS: TestMultithreadCopyWriterAtErrors/open/fatal=false (0.00s) --- PASS: TestMultithreadCopyWriterAtErrors/open/fatal=true (0.00s) --- PASS: TestMultithreadCopyWriterAtErrors/write/fatal=false (0.00s) --- PASS: TestMultithreadCopyWriterAtErrors/write/fatal=true (0.00s) --- PASS: TestMultithreadCopyWriterAtErrors/close/fatal=false (0.00s) --- PASS: TestMultithreadCopyWriterAtErrors/close/fatal=true (0.00s) === RUN TestSizeDiffers 2026/10/05 05:05:51 DEBUG : a: size = 0 OK 2026/10/05 05:05:51 DEBUG : a: size = 1 (memory) 2026/10/05 05:05:51 DEBUG : a: size = 2 (memory) --- PASS: TestSizeDiffers (0.00s) === RUN TestDirTransferEntry --- PASS: TestDirTransferEntry (0.00s) === RUN TestReOpen === RUN TestReOpen/Normal === RUN TestReOpen/Normal/Basics 2026/10/05 05:05:51 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/ErrorAtStart === RUN TestReOpen/Normal/WithErrors 2026/10/05 05:05:51 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/10/05 05:05:51 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/10/05 05:05:51 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/Normal/TooManyErrors 2026/10/05 05:05:51 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/10/05 05:05:51 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/10/05 05:05:51 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/10/05 05:05:51 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/Normal/ReadAt 2026/10/05 05:05:51 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/10/05 05:05:51 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/10/05 05:05:51 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/Normal/Seek 2026/10/05 05:05:51 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/10/05 05:05:51 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/10/05 05:05:51 DEBUG : potato: Seek from 5 to 2 === RUN TestReOpen/Normal/AccountRead === RUN TestReOpen/Normal/AccountReadDelay 2026/10/05 05:05:51 DEBUG : potato: Seek from 10 to 0 2026/10/05 05:05:51 DEBUG : potato: Seek from 10 to 0 2026/10/05 05:05:51 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/AccountReadError === RUN TestReOpen/WithRangeOption === RUN TestReOpen/WithRangeOption/Basics 2026/10/05 05:05:51 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/ErrorAtStart === RUN TestReOpen/WithRangeOption/WithErrors 2026/10/05 05:05:51 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/10/05 05:05:51 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/10/05 05:05:51 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithRangeOption/TooManyErrors 2026/10/05 05:05:51 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/10/05 05:05:51 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/10/05 05:05:51 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/10/05 05:05:51 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithRangeOption/ReadAt 2026/10/05 05:05:51 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/10/05 05:05:51 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/10/05 05:05:51 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/WithRangeOption/Seek 2026/10/05 05:05:51 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/10/05 05:05:51 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/10/05 05:05:51 DEBUG : potato: Seek from 5 to 2 2026/10/05 05:05:51 DEBUG : potato: Seek from 7 to 4 === RUN TestReOpen/WithRangeOption/AccountRead === RUN TestReOpen/WithRangeOption/AccountReadDelay 2026/10/05 05:05:51 DEBUG : potato: Seek from 7 to 0 2026/10/05 05:05:51 DEBUG : potato: Seek from 7 to 0 2026/10/05 05:05:51 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/AccountReadError === RUN TestReOpen/WithSeekOption === RUN TestReOpen/WithSeekOption/Basics 2026/10/05 05:05:51 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/ErrorAtStart === RUN TestReOpen/WithSeekOption/WithErrors 2026/10/05 05:05:51 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/10/05 05:05:51 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/10/05 05:05:51 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithSeekOption/TooManyErrors 2026/10/05 05:05:51 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/10/05 05:05:51 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/10/05 05:05:51 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/10/05 05:05:51 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithSeekOption/ReadAt 2026/10/05 05:05:51 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/10/05 05:05:51 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/10/05 05:05:51 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/WithSeekOption/Seek 2026/10/05 05:05:51 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/10/05 05:05:51 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/10/05 05:05:51 DEBUG : potato: Seek from 5 to 2 2026/10/05 05:05:51 DEBUG : potato: Seek from 7 to 5 === RUN TestReOpen/WithSeekOption/AccountRead === RUN TestReOpen/WithSeekOption/AccountReadDelay 2026/10/05 05:05:51 DEBUG : potato: Seek from 8 to 0 2026/10/05 05:05:51 DEBUG : potato: Seek from 8 to 0 2026/10/05 05:05:51 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/AccountReadError === RUN TestReOpen/UnknownSize === RUN TestReOpen/UnknownSize/Basics 2026/10/05 05:05:51 DEBUG : potato: Seek from 9 to 0 === RUN TestReOpen/UnknownSize/ErrorAtStart === RUN TestReOpen/UnknownSize/WithErrors 2026/10/05 05:05:51 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/10/05 05:05:51 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/10/05 05:05:51 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/UnknownSize/TooManyErrors 2026/10/05 05:05:51 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/10/05 05:05:51 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/10/05 05:05:51 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/10/05 05:05:51 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/UnknownSize/ReadAt 2026/10/05 05:05:51 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/10/05 05:05:51 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/10/05 05:05:51 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/UnknownSize/Seek 2026/10/05 05:05:51 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/10/05 05:05:51 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/10/05 05:05:51 DEBUG : potato: Seek from 5 to 2 2026/10/05 05:05:51 DEBUG : potato: Seek from 7 to 6 === RUN TestReOpen/UnknownSize/AccountRead === RUN TestReOpen/UnknownSize/AccountReadDelay 2026/10/05 05:05:51 DEBUG : potato: Seek from 9 to 0 2026/10/05 05:05:51 DEBUG : potato: Seek from 9 to 0 2026/10/05 05:05:51 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-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:05:54 DEBUG : rutabaga: md5 = f72eed31f0b9d81901962544e897310e OK === RUN TestCheck/1 === RUN TestCheck/2 2026/10/05 05:05:57 DEBUG : empty space: md5 = 03b24910504b420a7068a2162442493b OK === RUN TestCheck/3 2026/10/05 05:05:59 DEBUG : potato2: md5 = caa09fb9cd35ee3340f66e9074771e3e OK === RUN TestCheck/4 === RUN TestCheck/5 2026/10/05 05:06:02 DEBUG : remotepotato: md5 = 2a28377a7c72d9823126b09706504fa3 OK === RUN TestCheck/6 === RUN TestCheck/7 --- PASS: TestCheck (14.58s) --- PASS: TestCheck/1 (0.31s) --- PASS: TestCheck/2 (0.31s) --- PASS: TestCheck/3 (0.32s) --- PASS: TestCheck/4 (0.29s) --- PASS: TestCheck/5 (0.33s) --- PASS: TestCheck/6 (0.32s) --- PASS: TestCheck/7 (0.32s) === RUN TestCheckFsError 2026/10/05 05:06:06 DEBUG : Creating backend with remote "nonexistent" 2026/10/05 05:06:06 DEBUG : Creating backend with remote "nonexistent" 2026/10/05 05:06:06 DEBUG : Local file system at nonexistent: Waiting for checks to finish 2026/10/05 05:06:06 ERROR : Local file system at nonexistent: error reading source root directory: directory not found 2026/10/05 05:06:06 NOTICE: Local file system at nonexistent: 0 differences found 2026/10/05 05:06:06 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-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:06:08 DEBUG : rutabaga: md5 = c8df2f2c85ece1e674ecf7cc568ce345 OK === RUN TestCheckDownload/1 === RUN TestCheckDownload/2 2026/10/05 05:06:12 DEBUG : empty space: md5 = 1a3d92cb3378d15e8fa30bd9e9afc349 OK === RUN TestCheckDownload/3 2026/10/05 05:06:15 DEBUG : potato2: md5 = 0c33b9a344b4faeee1cc9861a068090e OK === RUN TestCheckDownload/4 === RUN TestCheckDownload/5 2026/10/05 05:06:19 DEBUG : remotepotato: md5 = 755ade18899cab75618ed6b264e2d119 OK === RUN TestCheckDownload/6 === RUN TestCheckDownload/7 --- PASS: TestCheckDownload (17.72s) --- PASS: TestCheckDownload/1 (0.96s) --- PASS: TestCheckDownload/2 (0.83s) --- PASS: TestCheckDownload/3 (0.81s) --- PASS: TestCheckDownload/4 (0.95s) --- PASS: TestCheckDownload/5 (1.15s) --- PASS: TestCheckDownload/6 (0.81s) --- PASS: TestCheckDownload/7 (0.79s) === RUN TestCheckSizeOnly run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:06:26 DEBUG : rutabaga: md5 = e75802e7cb4148ff33c077ce8a4ecb40 OK === RUN TestCheckSizeOnly/1 === RUN TestCheckSizeOnly/2 2026/10/05 05:06:28 DEBUG : empty space: md5 = a18b69cfffe9ce4ee956e964d3302dc8 OK === RUN TestCheckSizeOnly/3 2026/10/05 05:06:30 DEBUG : potato2: md5 = 9cc14f29490f883799921bfa21b2c627 OK === RUN TestCheckSizeOnly/4 === RUN TestCheckSizeOnly/5 2026/10/05 05:06:33 DEBUG : remotepotato: md5 = 755c3aef1ae4c1c94fcf11ebba2aad6a OK === RUN TestCheckSizeOnly/6 === RUN TestCheckSizeOnly/7 --- PASS: TestCheckSizeOnly (13.38s) --- PASS: TestCheckSizeOnly/1 (0.33s) --- PASS: TestCheckSizeOnly/2 (0.32s) --- PASS: TestCheckSizeOnly/3 (0.36s) --- PASS: TestCheckSizeOnly/4 (0.32s) --- PASS: TestCheckSizeOnly/5 (0.35s) --- PASS: TestCheckSizeOnly/6 (0.33s) --- PASS: TestCheckSizeOnly/7 (0.34s) === RUN TestCheckEqualReaders --- PASS: TestCheckEqualReaders (0.00s) === RUN TestParseSumFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:06:39 DEBUG : test.sum: md5 = f5d4824494ec3867762de8c16389584d OK 2026/10/05 05:06:40 NOTICE: test.sum: improperly formatted checksum line 4 2026/10/05 05:06:40 NOTICE: test.sum: improperly formatted checksum line 5 2026/10/05 05:06:40 NOTICE: test.sum: improperly formatted checksum line 6 2026/10/05 05:06:40 NOTICE: test.sum: 2 warning(s) suppressed... 2026/10/05 05:06:41 DEBUG : test.sum: md5 = 3faad3aff1a6b722d3f1cafc3fcbc6f1 OK 2026/10/05 05:06:42 NOTICE: test.sum: improperly formatted checksum line 4 2026/10/05 05:06:42 NOTICE: test.sum: improperly formatted checksum line 5 2026/10/05 05:06:42 NOTICE: test.sum: improperly formatted checksum line 6 2026/10/05 05:06:42 NOTICE: test.sum: 2 warning(s) suppressed... --- PASS: TestParseSumFile (6.36s) === RUN TestCheckSum run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:06:44 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-mivobir3wado/data" 2026/10/05 05:06:44 DEBUG : Creating backend with remote "TestDrive:crypt/nof1opn6ntbdfclfpa2iuae4bnqqkrnr94r6i17737dc3urifnu0/vjrnln8ratgmqakfosrqe8espk" check_test.go:354: Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado/data' lacks md5, skipping --- SKIP: TestCheckSum (2.30s) === RUN TestCheckSumDownload run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:06:46 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-mivobir3wado/data" 2026/10/05 05:06:46 DEBUG : Creating backend with remote "TestDrive:crypt/nof1opn6ntbdfclfpa2iuae4bnqqkrnr94r6i17737dc3urifnu0/vjrnln8ratgmqakfosrqe8espk" 2026/10/05 05:06:50 DEBUG : data/banana: md5 = d93d9c1830cd5c1293b7bc6700e40fa1 OK 2026/10/05 05:06:52 DEBUG : test.sum: md5 = 5738d37825fd99836cdeee16375bf379 OK === RUN TestCheckSumDownload/subtest1 2026/10/05 05:06:56 DEBUG : data/potato: md5 = 722ba7c7c6f89b7a65299c8551c6e5ff OK 2026/10/05 05:06:58 DEBUG : test.sum: md5 = c2a53e670584507a24b708708bd17f67 OK === RUN TestCheckSumDownload/subtest2 2026/10/05 05:07:02 DEBUG : test.sum: md5 = ece70ab9bd1c9ccbd92abd07c821cdb1 OK === RUN TestCheckSumDownload/subtest3 2026/10/05 05:07:06 DEBUG : test.sum: md5 = 7d40751fbb8a3cf71274f1b3ecf7ed1d OK === RUN TestCheckSumDownload/subtest4 2026/10/05 05:07:10 DEBUG : test.sum: md5 = 21005981770a15fc91e9e8302f425774 OK === RUN TestCheckSumDownload/subtest5 2026/10/05 05:07:14 DEBUG : test.sum: md5 = 3b04c82866dfb18fbf75f418673aefd4 OK === RUN TestCheckSumDownload/subtest6 2026/10/05 05:07:18 DEBUG : data/banana: md5 = 774e8315618d0681aa0c1340aa1ce8b9 OK 2026/10/05 05:07:20 DEBUG : data/potato: md5 = f8e1f4f07379d0a3d3b23df2df2dd972 OK 2026/10/05 05:07:21 DEBUG : test.sum: md5 = 0c78924a3db58d80c672005d56c3cf41 OK === RUN TestCheckSumDownload/subtest7 --- PASS: TestCheckSumDownload (41.59s) --- PASS: TestCheckSumDownload/subtest1 (2.02s) --- PASS: TestCheckSumDownload/subtest2 (1.71s) --- PASS: TestCheckSumDownload/subtest3 (1.74s) --- PASS: TestCheckSumDownload/subtest4 (1.71s) --- PASS: TestCheckSumDownload/subtest5 (1.68s) --- PASS: TestCheckSumDownload/subtest6 (1.68s) --- PASS: TestCheckSumDownload/subtest7 (1.84s) === RUN TestCheckSumConcurrency 2026/10/05 05:07:27 DEBUG : file0: md5 = 022eb0c0085c731f335df970fafa7446 OK 2026/10/05 05:07:27 DEBUG : file2: md5 = a58f09ae3db8ae4b943fdbaace096072 OK 2026/10/05 05:07:27 DEBUG : file1: md5 = 17fbabbccbdea0e6be9eb64c8c6fea41 OK 2026/10/05 05:07:27 DEBUG : file3: md5 = e0639b17e0744aca9a3ba29c049e59ee OK 2026/10/05 05:07:27 NOTICE: Mock file system at checkSum: 0 differences found 2026/10/05 05:07:27 NOTICE: Mock file system at checkSum: 4 matching files --- PASS: TestCheckSumConcurrency (0.00s) === RUN TestCheckSumDownloadConcurrency 2026/10/05 05:07:27 DEBUG : file0: md5 = 022eb0c0085c731f335df970fafa7446 OK 2026/10/05 05:07:27 DEBUG : file2: md5 = a58f09ae3db8ae4b943fdbaace096072 OK 2026/10/05 05:07:27 DEBUG : file1: md5 = 17fbabbccbdea0e6be9eb64c8c6fea41 OK 2026/10/05 05:07:27 DEBUG : file3: md5 = e0639b17e0744aca9a3ba29c049e59ee OK 2026/10/05 05:07:27 NOTICE: Mock file system at checkSum: 0 differences found 2026/10/05 05:07:27 NOTICE: Mock file system at checkSum: 4 matching files --- PASS: TestCheckSumDownloadConcurrency (0.00s) === RUN TestApplyTransforms 2026/10/05 05:07:27 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-gocipiq5zayo" 2026/10/05 05:07:27 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/10/05 05:07:27 DEBUG : Creating backend with remote "TestDrive:crypt/4moifk4s7q8l9ceim469nhk5cia4jvmu5oa5utn03cf33pcfu1v0" 2026/10/05 05:07:29 DEBUG : Creating backend with remote "/tmp/rclone1472074695" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gocipiq5zayo'", Local "Local file system at /tmp/rclone1472074695", Modify Window "1ms" 2026/10/05 05:07:31 DEBUG : hello, world!: md5 = e8825ef4937ff15d4693f002af95c90c OK upper checkfile vs. lower remote (without normalization) 2026/10/05 05:07:32 ERROR : hello, world!: sum not found 2026/10/05 05:07:32 ERROR : HELLO, WORLD!: file not in Encrypted drive 'TestCryptDrive:rclone-test-gocipiq5zayo' 2026/10/05 05:07:32 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-gocipiq5zayo': 1 files missing 2026/10/05 05:07:32 NOTICE: 1 hashes missing 2026/10/05 05:07:32 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-gocipiq5zayo': 1 differences found 2026/10/05 05:07:32 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-gocipiq5zayo': 2 errors while checking upper checkfile vs. lower remote (with normalization) 2026/10/05 05:07:33 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/10/05 05:07:33 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-gocipiq5zayo': 0 differences found 2026/10/05 05:07:33 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-gocipiq5zayo': 1 matching files 2026/10/05 05:07:33 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-nenixay9wipe" 2026/10/05 05:07:33 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/10/05 05:07:33 DEBUG : Creating backend with remote "TestDrive:crypt/mhhsorutvbtglg39gflkkshn6bkq9bct8v9e7hbul9aclh2uh3q0" 2026/10/05 05:07:34 DEBUG : Creating backend with remote "/tmp/rclone3022424524" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-nenixay9wipe'", Local "Local file system at /tmp/rclone3022424524", Modify Window "1ms" 2026/10/05 05:07:36 DEBUG : HELLO, WORLD!: md5 = 45e17de60d4e544b8105697d17068e58 OK lower checkfile vs. upper remote (without normalization) 2026/10/05 05:07:37 ERROR : HELLO, WORLD!: sum not found 2026/10/05 05:07:37 ERROR : hello, world!: file not in Encrypted drive 'TestCryptDrive:rclone-test-nenixay9wipe' 2026/10/05 05:07:37 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-nenixay9wipe': 1 files missing 2026/10/05 05:07:37 NOTICE: 1 hashes missing 2026/10/05 05:07:37 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-nenixay9wipe': 1 differences found 2026/10/05 05:07:37 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-nenixay9wipe': 2 errors while checking lower checkfile vs. upper remote (with normalization) 2026/10/05 05:07:38 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/10/05 05:07:38 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-nenixay9wipe': 0 differences found 2026/10/05 05:07:38 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-nenixay9wipe': 1 matching files 2026/10/05 05:07:38 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-xusonan6hoho" 2026/10/05 05:07:38 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/10/05 05:07:38 DEBUG : Creating backend with remote "TestDrive:crypt/7morn15m2jae2cfghb1f5bnnjvqp6vbk0fa3jajul98u80nisg40" 2026/10/05 05:07:39 DEBUG : Creating backend with remote "/tmp/rclone1527431583" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-xusonan6hoho'", Local "Local file system at /tmp/rclone1527431583", Modify Window "1ms" 2026/10/05 05:07:42 DEBUG : HeLlO, wOrLd!: md5 = 5a5e2a5d0925518c330958d53946ea6b OK lower checkfile vs. upperlowermixed remote (without normalization) 2026/10/05 05:07:43 ERROR : HeLlO, wOrLd!: sum not found 2026/10/05 05:07:43 ERROR : hello, world!: file not in Encrypted drive 'TestCryptDrive:rclone-test-xusonan6hoho' 2026/10/05 05:07:43 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-xusonan6hoho': 1 files missing 2026/10/05 05:07:43 NOTICE: 1 hashes missing 2026/10/05 05:07:43 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-xusonan6hoho': 1 differences found 2026/10/05 05:07:43 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-xusonan6hoho': 2 errors while checking lower checkfile vs. upperlowermixed remote (with normalization) 2026/10/05 05:07:43 DEBUG : HeLlO, wOrLd!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/10/05 05:07:43 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-xusonan6hoho': 0 differences found 2026/10/05 05:07:43 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-xusonan6hoho': 1 matching files 2026/10/05 05:07:43 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-basezec4yuba" 2026/10/05 05:07:43 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/10/05 05:07:44 DEBUG : Creating backend with remote "TestDrive:crypt/7nl3qa75mciimnuc572fg0htvl1t572nd2eplsdsd0834asdph00" 2026/10/05 05:07:45 DEBUG : Creating backend with remote "/tmp/rclone389612576" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-basezec4yuba'", Local "Local file system at /tmp/rclone389612576", Modify Window "1ms" 2026/10/05 05:07:47 DEBUG : HELLO, WORLD!: md5 = b5e1e24bb43f6d16998f475d08169ebf OK upperlowermixed checkfile vs. upper remote (without normalization) 2026/10/05 05:07:48 ERROR : HELLO, WORLD!: sum not found 2026/10/05 05:07:48 ERROR : HeLlO, wOrLd!: file not in Encrypted drive 'TestCryptDrive:rclone-test-basezec4yuba' 2026/10/05 05:07:48 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-basezec4yuba': 1 files missing 2026/10/05 05:07:48 NOTICE: 1 hashes missing 2026/10/05 05:07:48 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-basezec4yuba': 1 differences found 2026/10/05 05:07:48 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-basezec4yuba': 2 errors while checking upperlowermixed checkfile vs. upper remote (with normalization) 2026/10/05 05:07:49 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/10/05 05:07:49 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-basezec4yuba': 0 differences found 2026/10/05 05:07:49 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-basezec4yuba': 1 matching files 2026/10/05 05:07:49 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jadegur1naxe" 2026/10/05 05:07:49 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/10/05 05:07:49 DEBUG : Creating backend with remote "TestDrive:crypt/t9jhsgm7q61rbrdp7v62n60dlp9s56p704auup42286rvodpakhg" 2026/10/05 05:07:50 DEBUG : Creating backend with remote "/tmp/rclone548156365" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jadegur1naxe'", Local "Local file system at /tmp/rclone548156365", Modify Window "1ms" 2026/10/05 05:07:53 DEBUG : 測試_Русский___ě_áñ: md5 = be49a776368c5fc0f915eebd41f439bd OK NFD checkfile vs. NFC remote (without normalization) 2026/10/05 05:07:54 ERROR : 測試_Русский___ě_áñ: sum not found 2026/10/05 05:07:54 ERROR : 測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-jadegur1naxe' 2026/10/05 05:07:54 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-jadegur1naxe': 1 files missing 2026/10/05 05:07:54 NOTICE: 1 hashes missing 2026/10/05 05:07:54 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-jadegur1naxe': 1 differences found 2026/10/05 05:07:54 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-jadegur1naxe': 2 errors while checking NFD checkfile vs. NFC remote (with normalization) 2026/10/05 05:07:55 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/10/05 05:07:55 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-jadegur1naxe': 0 differences found 2026/10/05 05:07:55 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-jadegur1naxe': 1 matching files 2026/10/05 05:07:55 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-bixohad2qaga" 2026/10/05 05:07:55 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/10/05 05:07:55 DEBUG : Creating backend with remote "TestDrive:crypt/f7hdmelmmhr03tshhqk73lnsk9rihn2acfurl1f4i42fbr85kbrg" 2026/10/05 05:07:56 DEBUG : Creating backend with remote "/tmp/rclone1923415779" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-bixohad2qaga'", Local "Local file system at /tmp/rclone1923415779", Modify Window "1ms" 2026/10/05 05:07:59 DEBUG : 測試_Русский___ě_áñ: md5 = 6c143a875c848e955e5fbe156a3499d1 OK NFC checkfile vs. NFD remote (without normalization) 2026/10/05 05:07:59 ERROR : 測試_Русский___ě_áñ: sum not found 2026/10/05 05:07:59 ERROR : 測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-bixohad2qaga' 2026/10/05 05:07:59 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-bixohad2qaga': 1 files missing 2026/10/05 05:07:59 NOTICE: 1 hashes missing 2026/10/05 05:07:59 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-bixohad2qaga': 1 differences found 2026/10/05 05:07:59 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-bixohad2qaga': 2 errors while checking NFC checkfile vs. NFD remote (with normalization) 2026/10/05 05:08:00 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/10/05 05:08:00 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-bixohad2qaga': 0 differences found 2026/10/05 05:08:00 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-bixohad2qaga': 1 matching files 2026/10/05 05:08:00 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-kedawum4teje" 2026/10/05 05:08:00 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/10/05 05:08:00 DEBUG : Creating backend with remote "TestDrive:crypt/tpgimh909ls8ec0mue70li0ief3ie97139v4c6hsa91lii4lll20" 2026/10/05 05:08:02 DEBUG : Creating backend with remote "/tmp/rclone114802032" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kedawum4teje'", Local "Local file system at /tmp/rclone114802032", Modify Window "1ms" 2026/10/05 05:08:04 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 3b0043a2deb8974c69ef65701d428f99 OK NFDx2 checkfile vs. both remote (without normalization) 2026/10/05 05:08:05 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/10/05 05:08:05 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-kedawum4teje' 2026/10/05 05:08:05 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kedawum4teje': 1 files missing 2026/10/05 05:08:05 NOTICE: 1 hashes missing 2026/10/05 05:08:05 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kedawum4teje': 1 differences found 2026/10/05 05:08:05 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kedawum4teje': 2 errors while checking NFDx2 checkfile vs. both remote (with normalization) 2026/10/05 05:08:06 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/10/05 05:08:06 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kedawum4teje': 0 differences found 2026/10/05 05:08:06 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kedawum4teje': 1 matching files 2026/10/05 05:08:06 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-pisuvay3judo" 2026/10/05 05:08:06 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/10/05 05:08:06 DEBUG : Creating backend with remote "TestDrive:crypt/f8itqio1vd8c1b2376if0bpkvig3i1ku1j0eig6qsjjjbbngcni0" 2026/10/05 05:08:07 DEBUG : Creating backend with remote "/tmp/rclone641581167" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-pisuvay3judo'", Local "Local file system at /tmp/rclone641581167", Modify Window "1ms" 2026/10/05 05:08:09 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = afa1b6177262efc860fbc7ad687ec8c9 OK NFCx2 checkfile vs. both remote (without normalization) 2026/10/05 05:08:10 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/10/05 05:08:10 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-pisuvay3judo' 2026/10/05 05:08:10 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-pisuvay3judo': 1 files missing 2026/10/05 05:08:10 NOTICE: 1 hashes missing 2026/10/05 05:08:10 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-pisuvay3judo': 1 differences found 2026/10/05 05:08:10 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-pisuvay3judo': 2 errors while checking NFCx2 checkfile vs. both remote (with normalization) 2026/10/05 05:08:11 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/10/05 05:08:11 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-pisuvay3judo': 0 differences found 2026/10/05 05:08:11 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-pisuvay3judo': 1 matching files 2026/10/05 05:08:11 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-yesuyel0leju" 2026/10/05 05:08:11 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/10/05 05:08:11 DEBUG : Creating backend with remote "TestDrive:crypt/f1qkp2jhm6blndhrbkljlvf9of63he9sp97eqjouunnsuo3pern0" 2026/10/05 05:08:12 DEBUG : Creating backend with remote "/tmp/rclone2139647219" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-yesuyel0leju'", Local "Local file system at /tmp/rclone2139647219", Modify Window "1ms" 2026/10/05 05:08:15 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 8dcb06d6cbdf3cf1e3ff534a89de1ab1 OK both checkfile vs. NFDx2 remote (without normalization) 2026/10/05 05:08:16 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/10/05 05:08:16 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-yesuyel0leju' 2026/10/05 05:08:16 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-yesuyel0leju': 1 files missing 2026/10/05 05:08:16 NOTICE: 1 hashes missing 2026/10/05 05:08:16 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-yesuyel0leju': 1 differences found 2026/10/05 05:08:16 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-yesuyel0leju': 2 errors while checking both checkfile vs. NFDx2 remote (with normalization) 2026/10/05 05:08:17 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/10/05 05:08:17 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-yesuyel0leju': 0 differences found 2026/10/05 05:08:17 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-yesuyel0leju': 1 matching files 2026/10/05 05:08:17 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-fujiduj6fota" 2026/10/05 05:08:17 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/10/05 05:08:17 DEBUG : Creating backend with remote "TestDrive:crypt/52md4jcbnampjaqmicjf3vf16n4f5oh3k9agcn5ppfqcn1u9tfng" 2026/10/05 05:08:18 DEBUG : Creating backend with remote "/tmp/rclone123589662" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fujiduj6fota'", Local "Local file system at /tmp/rclone123589662", Modify Window "1ms" 2026/10/05 05:08:20 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 1d0eee6c35b82dc7ca80cf892eb32c37 OK both checkfile vs. NFCx2 remote (without normalization) 2026/10/05 05:08:21 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/10/05 05:08:21 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-fujiduj6fota' 2026/10/05 05:08:21 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fujiduj6fota': 1 files missing 2026/10/05 05:08:21 NOTICE: 1 hashes missing 2026/10/05 05:08:21 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fujiduj6fota': 1 differences found 2026/10/05 05:08:21 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fujiduj6fota': 2 errors while checking both checkfile vs. NFCx2 remote (with normalization) 2026/10/05 05:08:22 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/10/05 05:08:22 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fujiduj6fota': 0 differences found 2026/10/05 05:08:22 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fujiduj6fota': 1 matching files 2026/10/05 05:08:22 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-fujiduj6fota': Purge remote 2026/10/05 05:08:22 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-yesuyel0leju': Purge remote 2026/10/05 05:08:23 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-pisuvay3judo': Purge remote 2026/10/05 05:08:24 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-kedawum4teje': Purge remote 2026/10/05 05:08:24 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-bixohad2qaga': Purge remote 2026/10/05 05:08:25 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jadegur1naxe': Purge remote 2026/10/05 05:08:26 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-basezec4yuba': Purge remote 2026/10/05 05:08:26 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-xusonan6hoho': Purge remote 2026/10/05 05:08:27 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-nenixay9wipe': Purge remote 2026/10/05 05:08:27 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-gocipiq5zayo': Purge remote --- PASS: TestApplyTransforms (60.58s) === RUN TestTruncateString --- PASS: TestTruncateString (0.00s) === RUN TestCopyFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:08:28 DEBUG : file1: Need to transfer - File not found at Destination 2026/10/05 05:08:31 DEBUG : sub/file2: md5 = b01f2708d1d21cb7cc110d5f5848b21a OK 2026/10/05 05:08:31 DEBUG : sub/file2: size = 14 OK 2026/10/05 05:08:31 INFO : file1: Copied (new) to: sub/file2 2026/10/05 05:08:32 DEBUG : sub/file2: size = 14 OK 2026/10/05 05:08:32 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/10/05 05:08:32 DEBUG : file1: Unchanged skipping 2026/10/05 05:08:32 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado': don't need to copy/move sub/file2, it is already at target location --- PASS: TestCopyFile (7.27s) === RUN TestCopyFileImmutable run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:08:36 DEBUG : existing: Need to transfer - File not found at Destination 2026/10/05 05:08:37 DEBUG : existing: md5 = b8d838b8b80a4683bf695603eed99be5 OK 2026/10/05 05:08:37 DEBUG : existing: size = 6 OK 2026/10/05 05:08:37 INFO : existing: Copied (new) 2026/10/05 05:08:38 DEBUG : existing: size = 6 OK 2026/10/05 05:08:38 DEBUG : existing: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/10/05 05:08:38 DEBUG : existing: Unchanged skipping 2026/10/05 05:08:39 DEBUG : existing: size = 8 (Local file system at /tmp/rclone3863668941) 2026/10/05 05:08:39 DEBUG : existing: size = 6 (Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado') 2026/10/05 05:08:39 DEBUG : existing: Sizes differ 2026/10/05 05:08:39 ERROR : existing: Source and destination exist but do not match: immutable file modified --- PASS: TestCopyFileImmutable (5.00s) === RUN TestCopyLongFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" copy_test.go:188: Test only runs on local --- SKIP: TestCopyLongFile (0.48s) === RUN TestCopyFileBackupDir run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:08:43 DEBUG : dst/file1: md5 = 3e2d6aa8914a95041711cfa7d80adf10 OK 2026/10/05 05:08:44 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-mivobir3wado/backup" 2026/10/05 05:08:44 DEBUG : Creating backend with remote "TestDrive:crypt/nof1opn6ntbdfclfpa2iuae4bnqqkrnr94r6i17737dc3urifnu0/1nrff024r7pq65ecp72fc28jb0" 2026/10/05 05:08:46 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone3863668941) 2026/10/05 05:08:46 DEBUG : dst/file1: size = 18 (Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado') 2026/10/05 05:08:46 DEBUG : dst/file1: Sizes differ 2026/10/05 05:08:49 INFO : dst/file1: Moved (server-side) 2026/10/05 05:08:51 DEBUG : dst/file1: md5 = b7325e3e6308eaa1ac99e1b5c6602b95 OK 2026/10/05 05:08:51 DEBUG : dst/file1: size = 14 OK 2026/10/05 05:08:51 INFO : dst/file1: Copied (new) --- PASS: TestCopyFileBackupDir (16.03s) === RUN TestCopyFileCompareDest run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:08:57 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-mivobir3wado/dst" 2026/10/05 05:08:57 DEBUG : Creating backend with remote "TestDrive:crypt/nof1opn6ntbdfclfpa2iuae4bnqqkrnr94r6i17737dc3urifnu0/31u3jie661vd5p8j7rtc3hgbh0" 2026/10/05 05:08:59 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-mivobir3wado/CompareDest" 2026/10/05 05:08:59 DEBUG : Creating backend with remote "TestDrive:crypt/nof1opn6ntbdfclfpa2iuae4bnqqkrnr94r6i17737dc3urifnu0/gveqi14airsml4bgu7krj116o8" 2026/10/05 05:09:01 DEBUG : one: Need to transfer - File not found at Destination 2026/10/05 05:09:04 DEBUG : one: md5 = 509d8c3576e6104f2e3eb8c1e70a9f5e OK 2026/10/05 05:09:04 DEBUG : one: size = 3 OK 2026/10/05 05:09:04 INFO : one: Copied (new) 2026/10/05 05:09:05 DEBUG : one: size = 5 (Local file system at /tmp/rclone3863668941) 2026/10/05 05:09:05 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado/dst') 2026/10/05 05:09:05 DEBUG : one: Sizes differ 2026/10/05 05:09:07 DEBUG : one: md5 = 53463ace59b0fdcf1d1f699ebf71ccbd OK 2026/10/05 05:09:07 DEBUG : one: size = 5 OK 2026/10/05 05:09:07 INFO : one: Copied (replaced existing) 2026/10/05 05:09:10 DEBUG : dst/one: md5 = 2ecaf1a4b7d4040e8d002d1bdf9741b8 OK 2026/10/05 05:09:12 DEBUG : CompareDest/one: md5 = 9e1283a50cc79ff6aec118f490efe2e8 OK 2026/10/05 05:09:13 DEBUG : one: size = 5 (Local file system at /tmp/rclone3863668941) 2026/10/05 05:09:13 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado/dst') 2026/10/05 05:09:13 DEBUG : one: Sizes differ 2026/10/05 05:09:14 DEBUG : one: size = 5 OK 2026/10/05 05:09:14 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/10/05 05:09:14 DEBUG : one: Destination found in --compare-dest, skipping 2026/10/05 05:09:16 DEBUG : CompareDest/two: md5 = a766ed66491094ba9d5c2ca80127eb5a OK 2026/10/05 05:09:17 DEBUG : two: Need to transfer - File not found at Destination 2026/10/05 05:09:17 DEBUG : two: size = 3 OK 2026/10/05 05:09:17 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/10/05 05:09:17 DEBUG : two: Destination found in --compare-dest, skipping 2026/10/05 05:09:18 DEBUG : two: Need to transfer - File not found at Destination 2026/10/05 05:09:18 DEBUG : two: size = 3 OK 2026/10/05 05:09:18 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/10/05 05:09:18 DEBUG : two: Destination found in --compare-dest, skipping 2026/10/05 05:09:20 DEBUG : two: Need to transfer - File not found at Destination 2026/10/05 05:09:20 DEBUG : two: size = 5 (Local file system at /tmp/rclone3863668941) 2026/10/05 05:09:20 DEBUG : two: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado/CompareDest') 2026/10/05 05:09:20 DEBUG : two: Sizes differ 2026/10/05 05:09:22 DEBUG : two: md5 = fcf16a0dd70fedc8bca0df3931679b7f OK 2026/10/05 05:09:22 DEBUG : two: size = 5 OK 2026/10/05 05:09:22 INFO : two: Copied (new) --- PASS: TestCopyFileCompareDest (30.63s) === RUN TestCopyFileCopyDestImmutable run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:09:27 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-mivobir3wado/dst" 2026/10/05 05:09:27 DEBUG : Creating backend with remote "TestDrive:crypt/nof1opn6ntbdfclfpa2iuae4bnqqkrnr94r6i17737dc3urifnu0/31u3jie661vd5p8j7rtc3hgbh0" 2026/10/05 05:09:32 DEBUG : dst/one: md5 = 7904ba6483d206ce698ed9000e5657e1 OK 2026/10/05 05:09:35 DEBUG : CopyDest/one: md5 = 2494f16bd3663d18635d5450a762c8a0 OK 2026/10/05 05:09:36 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-mivobir3wado/CopyDest" 2026/10/05 05:09:36 DEBUG : Creating backend with remote "TestDrive:crypt/nof1opn6ntbdfclfpa2iuae4bnqqkrnr94r6i17737dc3urifnu0/d09o6po3f7bm6ce32vdgs8h9ls" 2026/10/05 05:09:37 DEBUG : one: size = 5 (Local file system at /tmp/rclone3863668941) 2026/10/05 05:09:37 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado/dst') 2026/10/05 05:09:37 DEBUG : one: Sizes differ 2026/10/05 05:09:37 DEBUG : one: size = 5 OK 2026/10/05 05:09:37 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/10/05 05:09:37 DEBUG : one: size = 5 (Local file system at /tmp/rclone3863668941) 2026/10/05 05:09:37 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado/dst') 2026/10/05 05:09:37 DEBUG : one: Sizes differ 2026/10/05 05:09:37 ERROR : one: Source and destination exist but do not match: immutable file modified --- PASS: TestCopyFileCopyDestImmutable (14.03s) === RUN TestCopyFileCopyDest run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:09:41 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-mivobir3wado/dst" 2026/10/05 05:09:41 DEBUG : Creating backend with remote "TestDrive:crypt/nof1opn6ntbdfclfpa2iuae4bnqqkrnr94r6i17737dc3urifnu0/31u3jie661vd5p8j7rtc3hgbh0" 2026/10/05 05:09:43 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-mivobir3wado/CopyDest" 2026/10/05 05:09:44 DEBUG : Creating backend with remote "TestDrive:crypt/nof1opn6ntbdfclfpa2iuae4bnqqkrnr94r6i17737dc3urifnu0/d09o6po3f7bm6ce32vdgs8h9ls" 2026/10/05 05:09:45 DEBUG : one: Need to transfer - File not found at Destination 2026/10/05 05:09:48 DEBUG : one: md5 = 0428bcf5973aee47bbb99119841da585 OK 2026/10/05 05:09:48 DEBUG : one: size = 3 OK 2026/10/05 05:09:48 INFO : one: Copied (new) 2026/10/05 05:09:50 DEBUG : one: size = 5 (Local file system at /tmp/rclone3863668941) 2026/10/05 05:09:50 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado/dst') 2026/10/05 05:09:50 DEBUG : one: Sizes differ 2026/10/05 05:09:51 DEBUG : one: md5 = e53ae7d6f2dba304c57b6aa830a16b53 OK 2026/10/05 05:09:51 DEBUG : one: size = 5 OK 2026/10/05 05:09:51 INFO : one: Copied (replaced existing) 2026/10/05 05:09:54 DEBUG : dst/one: md5 = 33ec81c46fee44c1dab0f0b8103839e9 OK 2026/10/05 05:09:56 DEBUG : CopyDest/one: md5 = e4e94e32cc90b85bec555f98d5c31d14 OK 2026/10/05 05:09:57 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-mivobir3wado/BackupDir" 2026/10/05 05:09:57 DEBUG : Creating backend with remote "TestDrive:crypt/nof1opn6ntbdfclfpa2iuae4bnqqkrnr94r6i17737dc3urifnu0/s6dbk3lfi7c9kfvo6j7bla9m0g" 2026/10/05 05:09:59 DEBUG : one: size = 5 (Local file system at /tmp/rclone3863668941) 2026/10/05 05:09:59 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado/dst') 2026/10/05 05:09:59 DEBUG : one: Sizes differ 2026/10/05 05:10:00 DEBUG : one: size = 5 OK 2026/10/05 05:10:00 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/10/05 05:10:00 DEBUG : one: size = 5 (Local file system at /tmp/rclone3863668941) 2026/10/05 05:10:00 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado/dst') 2026/10/05 05:10:00 DEBUG : one: Sizes differ 2026/10/05 05:10:01 INFO : one: Moved (server-side) 2026/10/05 05:10:03 DEBUG : one: size = 5 OK 2026/10/05 05:10:03 INFO : one: Copied (server-side copy) 2026/10/05 05:10:03 DEBUG : one: Destination found in --copy-dest, using server-side copy 2026/10/05 05:10:05 DEBUG : CopyDest/two: md5 = 297395be815b29ac3fc16bf7f9615fe6 OK 2026/10/05 05:10:06 DEBUG : two: Need to transfer - File not found at Destination 2026/10/05 05:10:06 DEBUG : two: size = 3 OK 2026/10/05 05:10:06 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/10/05 05:10:08 DEBUG : two: size = 3 OK 2026/10/05 05:10:08 INFO : two: Copied (server-side copy) 2026/10/05 05:10:08 DEBUG : two: Destination found in --copy-dest, using server-side copy 2026/10/05 05:10:08 DEBUG : two: size = 3 OK 2026/10/05 05:10:08 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/10/05 05:10:08 DEBUG : two: Unchanged skipping 2026/10/05 05:10:11 DEBUG : CopyDest/three: md5 = 3fc7dfb787977c05959eb0aa6aaa7cd2 OK 2026/10/05 05:10:12 DEBUG : three: Need to transfer - File not found at Destination 2026/10/05 05:10:12 DEBUG : three: size = 7 (Local file system at /tmp/rclone3863668941) 2026/10/05 05:10:12 DEBUG : three: size = 5 (Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado/CopyDest') 2026/10/05 05:10:12 DEBUG : three: Sizes differ 2026/10/05 05:10:12 DEBUG : three: Destination not found in --copy-dest 2026/10/05 05:10:14 DEBUG : three: md5 = 59ccaa9e8f5a7f34f80033fc001c5bac OK 2026/10/05 05:10:14 DEBUG : three: size = 7 OK 2026/10/05 05:10:14 INFO : three: Copied (new) --- PASS: TestCopyFileCopyDest (40.50s) === RUN TestCopyInplace run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" copy_test.go:434: Partial uploads not supported --- SKIP: TestCopyInplace (0.49s) === RUN TestCopyLongFileName run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" copy_test.go:467: Partial uploads not supported --- SKIP: TestCopyLongFileName (0.52s) === RUN TestCopyLongFileNameCollision run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" copy_test.go:500: Partial uploads not supported --- SKIP: TestCopyLongFileNameCollision (0.48s) === RUN TestCopyFileMaxTransfer run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:10:24 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination 2026/10/05 05:10:26 DEBUG : TestCopyFileMaxTransfer/file1: md5 = df8f38c9b27fe55fb473264dd64aa722 OK 2026/10/05 05:10:26 DEBUG : TestCopyFileMaxTransfer/file1: size = 14 OK 2026/10/05 05:10:26 INFO : TestCopyFileMaxTransfer/file1: Copied (new) 2026/10/05 05:10:27 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination 2026/10/05 05:10:28 ERROR : TestCopyFileMaxTransfer/file2: Failed to copy: Post "https://www.googleapis.com/upload/drive/v3/files?alt=json&fields=id%2Cname%2Csize%2Cmd5Checksum%2Csha1Checksum%2Csha256Checksum%2Ctrashed%2CexplicitlyTrashed%2CmodifiedTime%2CcreatedTime%2CmimeType%2Cparents%2CwebViewLink%2CshortcutDetails%2CexportLinks%2CresourceKey&keepRevisionForever=false&prettyPrint=false&supportsAllDrives=true&uploadType=multipart": googleapi: Copy failed: max transfer limit reached as set by --max-transfer copy_test.go:563: Expecting error to contain accounting.ErrorMaxTransferLimitReachedFatal 2026/10/05 05:10:28 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination 2026/10/05 05:10:29 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination 2026/10/05 05:10:31 DEBUG : TestCopyFileMaxTransfer/file4: md5 = b82035eddefe8ddc779473ec4716acb3 OK 2026/10/05 05:10:31 DEBUG : TestCopyFileMaxTransfer/file4: size = 2062 OK 2026/10/05 05:10:31 INFO : TestCopyFileMaxTransfer/file4: Copied (new) --- PASS: TestCopyFileMaxTransfer (10.93s) === RUN TestDeduplicateInteractive run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" dedupe_test.go:37: Can't run this test without a hash --- SKIP: TestDeduplicateInteractive (0.57s) === RUN TestDeduplicateSkip run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:10:38 INFO : Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado': Looking for duplicate names using skip mode. 2026/10/05 05:10:39 NOTICE: one: Found 2 files with duplicate names 2026/10/05 05:10:39 NOTICE: one: Skipping 2 files with duplicate names --- PASS: TestDeduplicateSkip (5.83s) === RUN TestDeduplicateSizeOnly run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:10:45 INFO : Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado': Looking for duplicate names using skip mode. 2026/10/05 05:10:46 NOTICE: one: Found 3 files with duplicate names 2026/10/05 05:10:46 NOTICE: one: Deleting 1/2 identical duplicates (size 11) 2026/10/05 05:10:46 INFO : one: Deleted 2026/10/05 05:10:46 NOTICE: one: Skipping 2 files with duplicate names --- PASS: TestDeduplicateSizeOnly (7.93s) === RUN TestDeduplicateFirst run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:10:53 INFO : Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado': Looking for duplicate names using first mode. 2026/10/05 05:10:54 NOTICE: one: Found 3 files with duplicate names 2026/10/05 05:10:55 INFO : one: Deleted 2026/10/05 05:10:55 INFO : one: Deleted 2026/10/05 05:10:55 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateFirst (8.05s) === RUN TestDeduplicateNewest run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:11:01 INFO : Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado': Looking for duplicate names using newest mode. 2026/10/05 05:11:02 NOTICE: one: Found 3 files with duplicate names 2026/10/05 05:11:02 INFO : one: Deleted 2026/10/05 05:11:03 INFO : one: Deleted 2026/10/05 05:11:03 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateNewest (7.90s) === RUN TestDeduplicateNewestByHash run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" dedupe_test.go:37: Can't run this test without a hash --- SKIP: TestDeduplicateNewestByHash (0.55s) === RUN TestDeduplicateOldest run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:11:10 INFO : Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado': Looking for duplicate names using oldest mode. 2026/10/05 05:11:10 NOTICE: one: Found 3 files with duplicate names 2026/10/05 05:11:11 INFO : one: Deleted 2026/10/05 05:11:12 INFO : one: Deleted 2026/10/05 05:11:12 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateOldest (7.74s) === RUN TestDeduplicateLargest run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:11:18 INFO : Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado': Looking for duplicate names using largest mode. 2026/10/05 05:11:18 NOTICE: one: Found 3 files with duplicate names 2026/10/05 05:11:19 INFO : one: Deleted 2026/10/05 05:11:19 INFO : one: Deleted 2026/10/05 05:11:19 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateLargest (8.01s) === RUN TestDeduplicateSmallest run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:11:25 INFO : Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado': Looking for duplicate names using smallest mode. 2026/10/05 05:11:26 NOTICE: one: Found 3 files with duplicate names 2026/10/05 05:11:26 INFO : one: Deleted 2026/10/05 05:11:27 INFO : one: Deleted 2026/10/05 05:11:27 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateSmallest (7.48s) === RUN TestDeduplicateRename run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:11:34 INFO : Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado': Looking for duplicate names using rename mode. 2026/10/05 05:11:35 NOTICE: one.txt: Found 3 files with duplicate names 2026/10/05 05:11:36 INFO : one-2.txt: renamed from: one.txt 2026/10/05 05:11:36 INFO : one-3.txt: renamed from: one.txt 2026/10/05 05:11:37 INFO : one-4.txt: renamed from: one.txt --- PASS: TestDeduplicateRename (12.05s) === RUN TestDeduplicateRenameManyExisting run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:11:42 DEBUG : one-1.txt: md5 = 9b9bff377fd34511f3e5555f72806978 OK 2026/10/05 05:11:44 DEBUG : one-2.txt: md5 = 223cafd6502b5b1c1a6f379fcd9ee0fb OK 2026/10/05 05:11:46 DEBUG : one-3.txt: md5 = 61bafd8655bc2387ede3825ba81e257f OK 2026/10/05 05:11:48 DEBUG : one-4.txt: md5 = ff30a90f0b8f9a02da7ca992edc2b3ec OK 2026/10/05 05:11:50 DEBUG : one-5.txt: md5 = 42085b88cfd219c9b1107ef1a31e90b1 OK 2026/10/05 05:11:52 DEBUG : one-6.txt: md5 = 31aef8b3195b74bb72602b60ef9f72ad OK 2026/10/05 05:11:53 DEBUG : one-7.txt: md5 = 4cfe168b49a7dd204bcba04abafbd918 OK 2026/10/05 05:11:55 DEBUG : one-8.txt: md5 = 0397a6b140946cf0da7af6b854c3149e OK 2026/10/05 05:11:57 DEBUG : one-9.txt: md5 = bb6bce3a276bbc99ac5132c9b6f3d339 OK 2026/10/05 05:11:58 DEBUG : one-10.txt: md5 = 1f320a2e8790b6e63e8fc106038fe2b9 OK 2026/10/05 05:12:00 DEBUG : one-11.txt: md5 = 6bf4aa5c1eb06e0e98383006ee49e16f OK 2026/10/05 05:12:02 DEBUG : one-12.txt: md5 = ccc688f62653305231b6098182210b8c OK 2026/10/05 05:12:03 DEBUG : one-13.txt: md5 = 762ee6220c733cc8fe7eef01c79275c4 OK 2026/10/05 05:12:05 DEBUG : one-14.txt: md5 = e44695d1c1a270b9856a2682c71f0178 OK 2026/10/05 05:12:07 DEBUG : one-15.txt: md5 = 3448dbad16a9aa57789b9f0c522c4ba6 OK 2026/10/05 05:12:08 DEBUG : one-16.txt: md5 = db20f7e80705d33b51417790a8907148 OK 2026/10/05 05:12:10 DEBUG : one-17.txt: md5 = bf138e89654bb0bfc53c8c025f2966e7 OK 2026/10/05 05:12:12 DEBUG : one-18.txt: md5 = 316166db7fd94a26e523d01e754b54ce OK 2026/10/05 05:12:13 DEBUG : one-19.txt: md5 = 01f446b06e29c6e08559c97ee36e4f94 OK 2026/10/05 05:12:15 DEBUG : one-20.txt: md5 = 918fc2aeb7b241f541819ffcaf38b7a7 OK 2026/10/05 05:12:17 DEBUG : one-21.txt: md5 = 44a893f441546f7ac1719f352a1be140 OK 2026/10/05 05:12:18 DEBUG : one-22.txt: md5 = 237017d44c13739bca0a57dd2324b1bd OK 2026/10/05 05:12:20 DEBUG : one-23.txt: md5 = 440a75b401094ffb4e462327ea5cdd75 OK 2026/10/05 05:12:22 DEBUG : one-24.txt: md5 = 6f956c8f62d5d8cc9b4810e3c5f44dc9 OK 2026/10/05 05:12:23 DEBUG : one-25.txt: md5 = 456dec3baff963bf06f0d1b22ed9976a OK 2026/10/05 05:12:25 DEBUG : one-26.txt: md5 = 47591f81d3f60df9ba7fd0a0da35cdcd OK 2026/10/05 05:12:27 DEBUG : one-27.txt: md5 = a773daa2be1c61ac25229bd3c3ae060f OK 2026/10/05 05:12:28 DEBUG : one-28.txt: md5 = e69f7d3b88d1e4dcd9e5156f676af95c OK 2026/10/05 05:12:30 DEBUG : one-29.txt: md5 = 16c66a672543d10080ccf419a048e4f1 OK 2026/10/05 05:12:32 DEBUG : one-30.txt: md5 = 88c33a0ff3cfda48d89250208afba86b OK 2026/10/05 05:12:33 DEBUG : one-31.txt: md5 = 65c088cb0e4728c3084b17ee40c9bdee OK 2026/10/05 05:12:35 DEBUG : one-32.txt: md5 = eb26ba1f9b591f30ef74ae9ac5343de4 OK 2026/10/05 05:12:36 DEBUG : one-33.txt: md5 = 64b9eef5ad5fbb07527162769764878b OK 2026/10/05 05:12:38 DEBUG : one-34.txt: md5 = 3bf2e9cbd1fc3e864796b9e8e64b079f OK 2026/10/05 05:12:40 DEBUG : one-35.txt: md5 = cfaee5a88eb2b5ab3ec57fa796f3ccf4 OK 2026/10/05 05:12:41 DEBUG : one-36.txt: md5 = 48ef2c33c2502a4a50dd3d6f172f27b8 OK 2026/10/05 05:12:43 DEBUG : one-37.txt: md5 = 5f5c254736004d8a114c1ac902363a75 OK 2026/10/05 05:12:45 DEBUG : one-38.txt: md5 = ea102de6ea6a7534e590f9e39b9a8579 OK 2026/10/05 05:12:47 DEBUG : one-39.txt: md5 = 17e589e4eb4acdb4bf49dddc8fdc54a2 OK 2026/10/05 05:12:48 DEBUG : one-40.txt: md5 = 76600d2f133c195de54897e4a975df1c OK 2026/10/05 05:12:50 DEBUG : one-41.txt: md5 = 036b07b0ecf430412743c992e3e375b3 OK 2026/10/05 05:12:52 DEBUG : one-42.txt: md5 = 1e7701c39ecf29eb6a5866a6e02fe67c OK 2026/10/05 05:12:53 DEBUG : one-43.txt: md5 = 8173af003122d999f901a15297947e72 OK 2026/10/05 05:12:55 DEBUG : one-44.txt: md5 = df4d92b9acb247aa65d0c422511781d5 OK 2026/10/05 05:12:57 DEBUG : one-45.txt: md5 = 79c9927cb43e9999fcdd5ab6cb6773a5 OK 2026/10/05 05:12:58 DEBUG : one-46.txt: md5 = 30915d7497b4b6a730ea27adbcd5da0f OK 2026/10/05 05:13:01 DEBUG : one-47.txt: md5 = c3c173871b861de09a46a1e7c3ec2d60 OK 2026/10/05 05:13:02 DEBUG : one-48.txt: md5 = 95ac86a0db3cbbfd0bf6ef836edc5303 OK 2026/10/05 05:13:04 DEBUG : one-49.txt: md5 = b2d67ebd8bc6176d4c36ae74cc77352b OK 2026/10/05 05:13:06 DEBUG : one-50.txt: md5 = 9f17baf3decbc7d772c8020519cbfdc9 OK 2026/10/05 05:13:07 DEBUG : one-51.txt: md5 = ca47d7e1374d508f74be3ad42350f1f6 OK 2026/10/05 05:13:09 DEBUG : one-52.txt: md5 = 0407e6b1dc0e0229cd979256391fb800 OK 2026/10/05 05:13:11 DEBUG : one-53.txt: md5 = 6d1654cd50ea80c2ea8da79406cfa45f OK 2026/10/05 05:13:12 DEBUG : one-54.txt: md5 = a554bb85da66bb0f571de9fae4ae0eec OK 2026/10/05 05:13:14 DEBUG : one-55.txt: md5 = 63e71c25b159dfbbba3d49c3c0273eb8 OK 2026/10/05 05:13:16 DEBUG : one-56.txt: md5 = aa6299bc3d37a9f099b778390eb9d262 OK 2026/10/05 05:13:17 DEBUG : one-57.txt: md5 = ef9d861e3e518badd736de9a417c1ca0 OK 2026/10/05 05:13:19 DEBUG : one-58.txt: md5 = f863d711ecc178ac97e8f5b53ae28417 OK 2026/10/05 05:13:21 DEBUG : one-59.txt: md5 = 074bc9a591be7f15515db2ead99c0b45 OK 2026/10/05 05:13:22 DEBUG : one-60.txt: md5 = 89722c4957b20ec44828ae7d24fd0a45 OK 2026/10/05 05:13:24 DEBUG : one-61.txt: md5 = 89111203b04df45dad81c32301f02989 OK 2026/10/05 05:13:26 DEBUG : one-62.txt: md5 = fc34103f8df62656ec5cedf89594ea1c OK 2026/10/05 05:13:27 DEBUG : one-63.txt: md5 = 1d7999c789666236d2f45595a4ade186 OK 2026/10/05 05:13:29 DEBUG : one-64.txt: md5 = 2586d8d715ae447fd64c24a990965912 OK 2026/10/05 05:13:31 DEBUG : one-65.txt: md5 = 175be1e0714a2ec8a74e5eb32d3c85d1 OK 2026/10/05 05:13:32 DEBUG : one-66.txt: md5 = 569c0f910013cb8f651e858a71c40dce OK 2026/10/05 05:13:34 DEBUG : one-67.txt: md5 = 974397772be370a256a3b9edae82c286 OK 2026/10/05 05:13:36 DEBUG : one-68.txt: md5 = b5cc4facb32298312105893357632cb6 OK 2026/10/05 05:13:38 DEBUG : one-69.txt: md5 = fb5fa4385432f6c9974b7b9919b66195 OK 2026/10/05 05:13:39 DEBUG : one-70.txt: md5 = 8ead3b97bc0709ace5709e38dffcb26d OK 2026/10/05 05:13:41 DEBUG : one-71.txt: md5 = 1a50393a5a1c19ec881e164529ee2319 OK 2026/10/05 05:13:42 DEBUG : one-72.txt: md5 = c35c1e31716fdf14e23ca39d0a2b4a40 OK 2026/10/05 05:13:44 DEBUG : one-73.txt: md5 = f33c22393ef76fec253e5daaf3f7035b OK 2026/10/05 05:13:46 DEBUG : one-74.txt: md5 = e41a8c73e79ac58059544dd40820aa0c OK 2026/10/05 05:13:47 DEBUG : one-75.txt: md5 = c9fb5788c0f995a39e362c905252ffb0 OK 2026/10/05 05:13:49 DEBUG : one-76.txt: md5 = c6446ada58cf787967cb843098adf900 OK 2026/10/05 05:13:51 DEBUG : one-77.txt: md5 = 75abe2dbbab002ab90634353092ed2f4 OK 2026/10/05 05:13:53 DEBUG : one-78.txt: md5 = f2f97a6b2e5ed32808c6ce7960e56547 OK 2026/10/05 05:13:54 DEBUG : one-79.txt: md5 = 7d08e0a8c2fc5cd1cb3db4f6299aab5c OK 2026/10/05 05:13:56 DEBUG : one-80.txt: md5 = 7690a3ec0296b0278508a29e186700ca OK 2026/10/05 05:13:58 DEBUG : one-81.txt: md5 = 71155c2664877a814477465d12c8cc5c OK 2026/10/05 05:13:59 DEBUG : one-82.txt: md5 = b0ff67772ce64e7d0dc8b7d31eb6fa8c OK 2026/10/05 05:14:01 DEBUG : one-83.txt: md5 = ef2d10c35ed90d162eef54f771da627e OK 2026/10/05 05:14:03 DEBUG : one-84.txt: md5 = b9b8d3d159789f7268e42115df547b15 OK 2026/10/05 05:14:04 DEBUG : one-85.txt: md5 = c03ec0372dd49e4045bec44f55ef8d5a OK 2026/10/05 05:14:06 DEBUG : one-86.txt: md5 = e6106a3b6f99efa4958a2524207cc935 OK 2026/10/05 05:14:08 DEBUG : one-87.txt: md5 = 6081e45472d8db03cdec8633cda78d2a OK 2026/10/05 05:14:09 DEBUG : one-88.txt: md5 = 7555f1ba0f8393765f070f1f25d158f6 OK 2026/10/05 05:14:11 DEBUG : one-89.txt: md5 = 3c1b7b7bd034530ec8daeca33c22c8bf OK 2026/10/05 05:14:13 DEBUG : one-90.txt: md5 = 5c45c1591e34299c2904e6014408c1fa OK 2026/10/05 05:14:14 DEBUG : one-91.txt: md5 = ae6031650dcd3644447f6f20779627f9 OK 2026/10/05 05:14:16 DEBUG : one-92.txt: md5 = d570e24d42fa362d443aa15533f0d882 OK 2026/10/05 05:14:18 DEBUG : one-93.txt: md5 = ff176679e96d811375ee40bfe2842a5a OK 2026/10/05 05:14:19 DEBUG : one-94.txt: md5 = 14df07d23b463cfd7c2707dbef739194 OK 2026/10/05 05:14:21 DEBUG : one-95.txt: md5 = d54c9d32d1d4056b1a6561971597a3d8 OK 2026/10/05 05:14:23 DEBUG : one-96.txt: md5 = 386ad239a85fe99e947ce5776fcdf913 OK 2026/10/05 05:14:25 DEBUG : one-97.txt: md5 = b3c0233f1ce37236ecfc9b40957d1c82 OK 2026/10/05 05:14:26 DEBUG : one-98.txt: md5 = 88bd2fb501219270099850981f05fb18 OK 2026/10/05 05:14:28 DEBUG : one-99.txt: md5 = ded9f6f8516f2473a5f7d78dff4f8c22 OK 2026/10/05 05:14:30 DEBUG : one-100.txt: md5 = a5de3bd351bcab8fe7143ac44bd1ce55 OK 2026/10/05 05:14:31 DEBUG : one-101.txt: md5 = a8b1e05bf9dd4634a6ce1ce5612e524e OK 2026/10/05 05:14:33 DEBUG : one-102.txt: md5 = 67171dea844ba56c07b40da478bd2d8b OK 2026/10/05 05:14:35 DEBUG : one-103.txt: md5 = a90c50965346edbae47224adb10e1655 OK 2026/10/05 05:14:37 DEBUG : one-104.txt: md5 = a0e93c7c1bee0eb247300627082d29d2 OK 2026/10/05 05:14:38 DEBUG : one-105.txt: md5 = 617192010252b4cb5d61682c5ffebc87 OK 2026/10/05 05:14:42 INFO : Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado': Looking for duplicate names using rename mode. 2026/10/05 05:14:42 NOTICE: one.txt: Found 2 files with duplicate names 2026/10/05 05:14:43 INFO : one-106.txt: renamed from: one.txt 2026/10/05 05:14:44 INFO : one-107.txt: renamed from: one.txt --- PASS: TestDeduplicateRenameManyExisting (249.45s) === RUN TestMergeDirs run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:15:53 DEBUG : dupe1/one.txt: md5 = 14ea2133f46c962d2eea2ce255ac5cfd OK 2026/10/05 05:15:55 DEBUG : dupe2/two.txt: md5 = a31f4ae3b697aa2d720423feeff60cb7 OK 2026/10/05 05:15:58 DEBUG : dupe3/three.txt: md5 = 0f51bd8f2f94acf2bd2023e7e08a7fb5 OK 2026/10/05 05:15:58 INFO : urj8gsducqbtvekeq181ntd2u8: merging "sfrom47189m9qr3mt4qonu281c" 2026/10/05 05:15:59 INFO : urj8gsducqbtvekeq181ntd2u8: removing empty directory 2026/10/05 05:16:00 INFO : 1u1r4ei7c628fnjg9blqt3j60o: merging "tr2hj63d80ftlmvm6a952snjcc" 2026/10/05 05:16:01 INFO : 1u1r4ei7c628fnjg9blqt3j60o: removing empty directory --- PASS: TestMergeDirs (16.09s) === RUN TestIndex run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:16:08 DEBUG : file1.txt: md5 = 1005c13ea919c92493648c9f878576b9 OK 2026/10/05 05:16:10 DEBUG : sub/file2.txt: md5 = 69b91b7c348e2001d58cfd5abee8dfda OK 2026/10/05 05:16:13 DEBUG : sub/deep/file3.txt: md5 = 3ce5d8643e9d7cd321e6358f0d331630 OK 2026/10/05 05:16:15 DEBUG : index.html: md5 = 52c46fe68142c33841acd14036d09688 OK 2026/10/05 05:16:15 DEBUG : index.html: size = 12147 OK 2026/10/05 05:16:15 INFO : index.html: Copied (new) 2026/10/05 05:16:15 DEBUG : sub/deep/index.json: md5 = b40a9c0bdd043e6ad5ec762e80af5812 OK 2026/10/05 05:16:15 DEBUG : sub/deep/index.json: size = 119 OK 2026/10/05 05:16:15 INFO : sub/deep/index.json: Copied (new) 2026/10/05 05:16:15 DEBUG : sub/deep/index.html: md5 = d133a15b5ef9d3ad22121204bd09b730 OK 2026/10/05 05:16:15 DEBUG : sub/deep/index.html: size = 12087 OK 2026/10/05 05:16:15 INFO : sub/deep/index.html: Copied (new) 2026/10/05 05:16:16 DEBUG : sub/deep/caddy.json: md5 = 56b3776e8a20620af89ebcec1f4ecfad OK 2026/10/05 05:16:16 DEBUG : sub/deep/caddy.json: size = 131 OK 2026/10/05 05:16:16 INFO : sub/deep/caddy.json: Copied (new) 2026/10/05 05:16:17 DEBUG : index.json: md5 = 7fdc73c8c287d282e2fcf708d80e5419 OK 2026/10/05 05:16:17 DEBUG : index.json: size = 213 OK 2026/10/05 05:16:17 INFO : index.json: Copied (new) 2026/10/05 05:16:17 DEBUG : sub/index.json: md5 = 3ffa675855f587044b9fd58fb4fe04de OK 2026/10/05 05:16:17 DEBUG : sub/index.json: size = 215 OK 2026/10/05 05:16:17 INFO : sub/index.json: Copied (new) 2026/10/05 05:16:17 DEBUG : sub/index.html: md5 = 5acee2559a6e766d4fe0107fa7912012 OK 2026/10/05 05:16:17 DEBUG : sub/index.html: size = 12306 OK 2026/10/05 05:16:17 INFO : sub/index.html: Copied (new) 2026/10/05 05:16:17 DEBUG : caddy.json: md5 = 2e30fa52a26345bf373a2c33c9f2a04f OK 2026/10/05 05:16:17 DEBUG : caddy.json: size = 259 OK 2026/10/05 05:16:17 INFO : caddy.json: Copied (new) 2026/10/05 05:16:19 DEBUG : sub/caddy.json: md5 = 804d4f941bcb2b9818f6c9680b394689 OK 2026/10/05 05:16:19 DEBUG : sub/caddy.json: size = 261 OK 2026/10/05 05:16:19 INFO : sub/caddy.json: Copied (new) 2026/10/05 05:16:25 DEBUG : index.html: Unchanged skipping 2026/10/05 05:16:25 DEBUG : sub/index.html: Unchanged skipping 2026/10/05 05:16:25 DEBUG : sub/deep/index.html: Unchanged skipping 2026/10/05 05:16:25 DEBUG : index.json: Unchanged skipping 2026/10/05 05:16:26 DEBUG : sub/index.json: Unchanged skipping 2026/10/05 05:16:26 DEBUG : sub/deep/index.json: Unchanged skipping 2026/10/05 05:16:26 DEBUG : caddy.json: Unchanged skipping 2026/10/05 05:16:26 DEBUG : sub/caddy.json: Unchanged skipping 2026/10/05 05:16:26 DEBUG : sub/deep/caddy.json: Unchanged skipping 2026/10/05 05:16:29 DEBUG : sub/deep/index.html: md5 = 596282e6ee48aaae00968b9f90cff304 OK 2026/10/05 05:16:29 DEBUG : sub/deep/index.html: size = 12087 OK 2026/10/05 05:16:29 INFO : sub/deep/index.html: Copied (replaced existing) 2026/10/05 05:16:29 DEBUG : caddy.json: md5 = 55f7950b6ab2c71cbf170d0c81987ed2 OK 2026/10/05 05:16:29 DEBUG : caddy.json: size = 259 OK 2026/10/05 05:16:29 INFO : caddy.json: Copied (replaced existing) 2026/10/05 05:16:29 DEBUG : index.html: md5 = 4af122be723ce874052e70fc92976129 OK 2026/10/05 05:16:29 DEBUG : index.html: size = 12147 OK 2026/10/05 05:16:29 INFO : index.html: Copied (replaced existing) 2026/10/05 05:16:29 DEBUG : index.json: md5 = d4054e8802bcd723b2bdb306d9264147 OK 2026/10/05 05:16:29 DEBUG : index.json: size = 213 OK 2026/10/05 05:16:29 INFO : index.json: Copied (replaced existing) 2026/10/05 05:16:30 DEBUG : sub/deep/index.json: md5 = 816643acfd884be13e266c15e782ff9a OK 2026/10/05 05:16:30 DEBUG : sub/deep/index.json: size = 119 OK 2026/10/05 05:16:30 INFO : sub/deep/index.json: Copied (replaced existing) 2026/10/05 05:16:30 DEBUG : sub/index.html: md5 = d82b15bea7be91b92caeec70bcf333ea OK 2026/10/05 05:16:30 DEBUG : sub/index.html: size = 12306 OK 2026/10/05 05:16:30 INFO : sub/index.html: Copied (replaced existing) 2026/10/05 05:16:30 DEBUG : sub/deep/caddy.json: md5 = d3c5726edb9aa415b0c4de72c888c786 OK 2026/10/05 05:16:30 DEBUG : sub/deep/caddy.json: size = 131 OK 2026/10/05 05:16:30 INFO : sub/deep/caddy.json: Copied (replaced existing) 2026/10/05 05:16:30 DEBUG : sub/index.json: md5 = e375254fdb20750286410e1463f44508 OK 2026/10/05 05:16:30 DEBUG : sub/index.json: size = 215 OK 2026/10/05 05:16:30 INFO : sub/index.json: Copied (replaced existing) 2026/10/05 05:16:31 DEBUG : sub/caddy.json: md5 = 4387fa43aa32a064116011f7f9cf52a5 OK 2026/10/05 05:16:31 DEBUG : sub/caddy.json: size = 261 OK 2026/10/05 05:16:31 INFO : sub/caddy.json: Copied (replaced existing) 2026/10/05 05:16:33 DEBUG : sub/deep/file4.txt: md5 = 9d02432b9caababefecde674e602553c OK 2026/10/05 05:16:35 DEBUG : index.html: Unchanged skipping 2026/10/05 05:16:35 DEBUG : sub/index.html: Unchanged skipping 2026/10/05 05:16:35 DEBUG : index.json: Unchanged skipping 2026/10/05 05:16:35 DEBUG : sub/deep/caddy.json: md5 = 9af0f8ab98cf0851f05afd8c83cdc3ec OK 2026/10/05 05:16:35 DEBUG : sub/deep/caddy.json: size = 260 OK 2026/10/05 05:16:35 INFO : sub/deep/caddy.json: Copied (replaced existing) 2026/10/05 05:16:35 DEBUG : sub/index.json: Unchanged skipping 2026/10/05 05:16:35 DEBUG : sub/deep/index.json: md5 = 727b45ea51f9543934eb2e24d30b9c36 OK 2026/10/05 05:16:35 DEBUG : sub/deep/index.json: size = 235 OK 2026/10/05 05:16:35 INFO : sub/deep/index.json: Copied (replaced existing) 2026/10/05 05:16:35 DEBUG : sub/deep/index.html: md5 = 1524ddf77fa6cc7d7c619e6b8682e815 OK 2026/10/05 05:16:35 DEBUG : sub/deep/index.html: size = 12346 OK 2026/10/05 05:16:35 INFO : sub/deep/index.html: Copied (replaced existing) 2026/10/05 05:16:36 DEBUG : caddy.json: Unchanged skipping 2026/10/05 05:16:36 DEBUG : sub/caddy.json: Unchanged skipping 2026/10/05 05:16:38 DEBUG : sub/deep/file5.txt: md5 = 133ea609494a0ae41e10e8efdb883cb6 OK 2026/10/05 05:16:39 NOTICE: sub/deep/caddy.json: Skipped copy as --dry-run is set (size 389) 2026/10/05 05:16:39 NOTICE: sub/deep/index.html: Skipped copy as --dry-run is set (size 12.309Ki) 2026/10/05 05:16:39 NOTICE: sub/deep/index.json: Skipped copy as --dry-run is set (size 351) 2026/10/05 05:16:40 DEBUG : index.html: Unchanged skipping 2026/10/05 05:16:40 DEBUG : sub/index.html: Unchanged skipping 2026/10/05 05:16:40 DEBUG : index.json: Unchanged skipping 2026/10/05 05:16:40 DEBUG : sub/index.json: Unchanged skipping 2026/10/05 05:16:41 DEBUG : sub/caddy.json: Unchanged skipping 2026/10/05 05:16:41 DEBUG : caddy.json: Unchanged skipping --- PASS: TestIndex (46.84s) === RUN TestIndexDelete run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:16:55 DEBUG : file1.txt: md5 = bfe910721401a1e5498088d09bc424cc OK 2026/10/05 05:16:57 DEBUG : sub/file2.txt: md5 = c94d5d1c1692dd6faad64e88b0b9167d OK 2026/10/05 05:17:00 DEBUG : sub/deep/file3.txt: md5 = ba05aa29e7b9c46a7d8d2be2f6dce74c OK 2026/10/05 05:17:02 DEBUG : index.html: md5 = 4958c4c717245738681f290695f11574 OK 2026/10/05 05:17:02 DEBUG : index.html: size = 12147 OK 2026/10/05 05:17:02 INFO : index.html: Copied (new) 2026/10/05 05:17:02 DEBUG : sub/deep/index.html: md5 = d13859035b9c4a0f867c049642b85f86 OK 2026/10/05 05:17:02 DEBUG : sub/deep/index.html: size = 12087 OK 2026/10/05 05:17:02 INFO : sub/deep/index.html: Copied (new) 2026/10/05 05:17:02 DEBUG : sub/index.html: md5 = 2d1501a91610231b3c071c256c3f5801 OK 2026/10/05 05:17:02 DEBUG : sub/index.html: size = 12306 OK 2026/10/05 05:17:02 INFO : sub/index.html: Copied (new) 2026/10/05 05:17:05 DEBUG : index.html: Unchanged skipping 2026/10/05 05:17:05 DEBUG : sub/index.html: Unchanged skipping 2026/10/05 05:17:06 DEBUG : sub/deep/index.html: md5 = a682a27507c71476d3ad8a197e72033b OK 2026/10/05 05:17:06 DEBUG : sub/deep/index.html: size = 11829 OK 2026/10/05 05:17:06 INFO : sub/deep/index.html: Copied (replaced existing) --- PASS: TestIndexDelete (21.15s) === RUN TestIndexFilter run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:17:16 DEBUG : file1.txt: md5 = 3cd22a971833c0984dab52360527d708 OK 2026/10/05 05:17:18 DEBUG : sub/file2.txt: md5 = 0124d43c7a88866d48f8a09568ddc6ca OK 2026/10/05 05:17:21 DEBUG : sub/deep/file3.txt: md5 = 5ef2e83e112915dcca695bba98fd2a0a OK 2026/10/05 05:17:23 DEBUG : sub/deep/index.html: md5 = a3d9d16e1b1a2308f980f25e157aa23b OK 2026/10/05 05:17:25 DEBUG : other/notes.md: md5 = 6e6bb3cb9730eb369f839800eb80404f OK 2026/10/05 05:17:26 DEBUG : other/notes.md: Excluded (Path Filter) 2026/10/05 05:17:28 DEBUG : sub/index.html: md5 = e8acfb33ff6f8babaf04de0a956f10d1 OK 2026/10/05 05:17:28 DEBUG : sub/index.html: size = 12056 OK 2026/10/05 05:17:28 INFO : sub/index.html: Copied (new) 2026/10/05 05:17:28 DEBUG : index.html: md5 = c266ff09bacb0fe672448d17d7496d68 OK 2026/10/05 05:17:28 DEBUG : index.html: size = 12399 OK 2026/10/05 05:17:28 INFO : index.html: Copied (new) 2026/10/05 05:17:28 DEBUG : other/index.html: md5 = cc79b15f58ea4e1becfadfc464f7a894 OK 2026/10/05 05:17:28 DEBUG : other/index.html: size = 11802 OK 2026/10/05 05:17:28 INFO : other/index.html: Copied (new) 2026/10/05 05:17:33 DEBUG : other/notes.md: Excluded (Path Filter) 2026/10/05 05:17:34 DEBUG : sub/index.html: Unchanged skipping 2026/10/05 05:17:34 DEBUG : other/index.html: Unchanged skipping 2026/10/05 05:17:34 DEBUG : index.html: Unchanged skipping --- PASS: TestIndexFilter (28.45s) === RUN TestIndexRules run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:17:44 DEBUG : file1.txt: md5 = 6b605d44b46119f9df4c3a0d97e0ffbc OK 2026/10/05 05:17:47 DEBUG : sub/file2.txt: md5 = 152a1806cbe4f54aaec50bcb3a66d39d OK 2026/10/05 05:17:49 DEBUG : sub/deep/file3.txt: md5 = a3de38084cb61724455405e3eff9c58c OK 2026/10/05 05:17:51 DEBUG : sub/deep/index.html: md5 = 4aab24c882cde05dbe927398af0b2e99 OK 2026/10/05 05:17:53 DEBUG : index.html: md5 = e05cc71e427423f4b2862c979bace690 OK 2026/10/05 05:17:53 DEBUG : index.html: size = 12147 OK 2026/10/05 05:17:53 INFO : index.html: Copied (new) 2026/10/05 05:17:54 DEBUG : sub/index.html: md5 = 1d362087a938fe82e5bcaeac959a89ab OK 2026/10/05 05:17:54 DEBUG : sub/index.html: size = 12306 OK 2026/10/05 05:17:54 INFO : sub/index.html: Copied (new) 2026/10/05 05:17:59 DEBUG : sub/index.html: Unchanged skipping 2026/10/05 05:18:00 DEBUG : sub/deep/index.html: md5 = a584972a7a7a07bf6064049ea6b0d704 OK 2026/10/05 05:18:00 DEBUG : sub/deep/index.html: size = 12087 OK 2026/10/05 05:18:00 INFO : sub/deep/index.html: Copied (replaced existing) --- PASS: TestIndexRules (25.00s) === RUN TestIndexMaxDepth run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:18:09 DEBUG : file1.txt: md5 = 37dfcc948185547cd51b87b8c8ed8ada OK 2026/10/05 05:18:12 DEBUG : sub/file2.txt: md5 = 12378d7af691403e107aa67bba12ea1d OK 2026/10/05 05:18:14 DEBUG : sub/deep/file3.txt: md5 = 83b6af20384b662ba8da388572ae562c OK 2026/10/05 05:18:17 DEBUG : index.html: md5 = 5202ddf936a1660c55d3c30c54f6ce71 OK 2026/10/05 05:18:17 DEBUG : index.html: size = 12147 OK 2026/10/05 05:18:17 INFO : index.html: Copied (new) --- PASS: TestIndexMaxDepth (17.49s) === RUN TestIndexLinkIndex run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:18:27 DEBUG : file1.txt: md5 = e43c9a596d20e4cbf746aec8c1c191ca OK 2026/10/05 05:18:29 DEBUG : sub/file2.txt: md5 = fefe82f2d1255842f758a89d9c53c366 OK 2026/10/05 05:18:32 DEBUG : sub/deep/file3.txt: md5 = ec0e2eab9874c901effcc739631010fa OK 2026/10/05 05:18:34 DEBUG : sub/deep/index.html: md5 = 1042654a157afc1af20354f8871e7c91 OK 2026/10/05 05:18:34 DEBUG : sub/deep/index.html: size = 12128 OK 2026/10/05 05:18:34 INFO : sub/deep/index.html: Copied (new) 2026/10/05 05:18:34 DEBUG : index.html: md5 = 7dbf4793f1fec0826c3a3bb136f7c204 OK 2026/10/05 05:18:34 DEBUG : index.html: size = 12167 OK 2026/10/05 05:18:34 INFO : index.html: Copied (new) 2026/10/05 05:18:35 DEBUG : sub/index.html: md5 = 4f7ce663544886f3a69b4ea8b712b1d7 OK 2026/10/05 05:18:35 DEBUG : sub/index.html: size = 12347 OK 2026/10/05 05:18:35 INFO : sub/index.html: Copied (new) --- PASS: TestIndexLinkIndex (18.82s) === RUN TestIndexDirTime run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:18:46 DEBUG : file1.txt: md5 = 0c806f66b195c484d4cbaad318a36dec OK 2026/10/05 05:18:48 DEBUG : sub/file2.txt: md5 = 6e7789977b1ccd70ae4b5f696791871e OK 2026/10/05 05:18:51 DEBUG : sub/deep/file3.txt: md5 = b4703ae82263ab31eb190837a6cbfbf3 OK 2026/10/05 05:18:53 DEBUG : index.json: md5 = e9bdcee1c31cedc890d59f047fa4f223 OK 2026/10/05 05:18:53 DEBUG : index.json: size = 257 OK 2026/10/05 05:18:53 INFO : index.json: Copied (new) 2026/10/05 05:18:53 DEBUG : sub/index.json: md5 = 91b516753c25ea9d18b05b8edc94ecae OK 2026/10/05 05:18:53 DEBUG : sub/index.json: size = 259 OK 2026/10/05 05:18:53 INFO : sub/index.json: Copied (new) 2026/10/05 05:18:53 DEBUG : sub/deep/index.json: md5 = 5f016feefe9aeade518cc159b4d25261 OK 2026/10/05 05:18:53 DEBUG : sub/deep/index.json: size = 139 OK 2026/10/05 05:18:53 INFO : sub/deep/index.json: Copied (new) 2026/10/05 05:18:56 DEBUG : sub/deep/index.json: Unchanged skipping 2026/10/05 05:18:57 DEBUG : index.json: md5 = c1b0cc3ca7281572638d3e3a841e7f1c OK 2026/10/05 05:18:57 DEBUG : index.json: size = 237 OK 2026/10/05 05:18:57 INFO : index.json: Copied (replaced existing) 2026/10/05 05:18:57 DEBUG : sub/index.json: md5 = afe40e4e0ecd72a0cb4bb82b3d8f2134 OK 2026/10/05 05:18:57 DEBUG : sub/index.json: size = 239 OK 2026/10/05 05:18:57 INFO : sub/index.json: Copied (replaced existing) 2026/10/05 05:19:00 DEBUG : sub/deep/index.json: md5 = 8638f242d41b2164852201f4f26289ae OK 2026/10/05 05:19:00 DEBUG : sub/deep/index.json: size = 119 OK 2026/10/05 05:19:00 INFO : sub/deep/index.json: Copied (replaced existing) 2026/10/05 05:19:00 DEBUG : index.json: md5 = 4870615b2f3404b5232cc95566681844 OK 2026/10/05 05:19:00 DEBUG : index.json: size = 213 OK 2026/10/05 05:19:00 INFO : index.json: Copied (replaced existing) 2026/10/05 05:19:00 DEBUG : sub/index.json: md5 = 8b61e66ad404bee12ba5be951331e258 OK 2026/10/05 05:19:00 DEBUG : sub/index.json: size = 215 OK 2026/10/05 05:19:00 INFO : sub/index.json: Copied (replaced existing) --- PASS: TestIndexDirTime (24.06s) === RUN TestIndexTemplate run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:19:09 DEBUG : file1.txt: md5 = 5818d843ae129bba5c321c50e2c4bf58 OK 2026/10/05 05:19:12 DEBUG : sub/file2.txt: md5 = 740f822d51a890cef52d4e58ea349f43 OK 2026/10/05 05:19:15 DEBUG : sub/deep/file3.txt: md5 = 3addd148d8f0cd3f9d4f36272dd5ccae OK 2026/10/05 05:19:17 DEBUG : list.txt: md5 = b37499679610739d96dbf249968fd8c5 OK 2026/10/05 05:19:17 DEBUG : list.txt: size = 59 OK 2026/10/05 05:19:17 INFO : list.txt: Copied (new) 2026/10/05 05:19:17 DEBUG : sub/deep/list.txt: md5 = b531ac766c4a9022f0e9156cb8ccf3de OK 2026/10/05 05:19:17 DEBUG : sub/deep/list.txt: size = 47 OK 2026/10/05 05:19:17 INFO : sub/deep/list.txt: Copied (new) 2026/10/05 05:19:17 DEBUG : sub/list.txt: md5 = d44dad98c47c97f9bdffca59b3afe4e6 OK 2026/10/05 05:19:17 DEBUG : sub/list.txt: size = 64 OK 2026/10/05 05:19:17 INFO : sub/list.txt: Copied (new) --- PASS: TestIndexTemplate (16.83s) === 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:371: Skipping test on non local remote --- SKIP: TestIndexNoHash (0.00s) === RUN TestIndexChanged run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:19:26 DEBUG : file1.txt: md5 = 4f7424ae1f6530e311d81054604c1d3c OK 2026/10/05 05:19:29 DEBUG : sub/file2.txt: md5 = 052a3c12ebfe84b072d39b2dca9af534 OK 2026/10/05 05:19:31 DEBUG : sub/deep/file3.txt: md5 = d28d08701be75c00d5698500736e6d2a OK 2026/10/05 05:19:34 DEBUG : other/notes.md: md5 = a2534b25e241ee511d94909f81889fc1 OK 2026/10/05 05:19:36 DEBUG : index.html: md5 = 3d06e663aa1088e6314784d679a770f4 OK 2026/10/05 05:19:36 DEBUG : index.html: size = 12399 OK 2026/10/05 05:19:36 INFO : index.html: Copied (new) 2026/10/05 05:19:36 DEBUG : other/index.html: md5 = 48f29263f889361b7309f28a63a6af80 OK 2026/10/05 05:19:36 DEBUG : other/index.html: size = 12058 OK 2026/10/05 05:19:36 INFO : other/index.html: Copied (new) 2026/10/05 05:19:36 DEBUG : sub/index.html: md5 = 4f3b7f6e9e93e20cc0aeaea616c2090c OK 2026/10/05 05:19:36 DEBUG : sub/index.html: size = 12306 OK 2026/10/05 05:19:36 INFO : sub/index.html: Copied (new) 2026/10/05 05:19:36 DEBUG : sub/deep/index.html: md5 = 71b20915fe0177145bce324369b2c53b OK 2026/10/05 05:19:36 DEBUG : sub/deep/index.html: size = 12087 OK 2026/10/05 05:19:36 INFO : sub/deep/index.html: Copied (new) 2026/10/05 05:19:38 DEBUG : sub/deep/file4.txt: md5 = c5c325b38c6423b8a7e0c11ead7e079e OK 2026/10/05 05:19:40 DEBUG : other/undeclared.txt: md5 = b4c5e695253773b2bb76b8b8818b8b8c OK 2026/10/05 05:19:40 INFO : Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado': Partial index: listing 3 directories and walking 0 changed directories 2026/10/05 05:19:41 DEBUG : sub/index.html: Unchanged skipping 2026/10/05 05:19:41 DEBUG : index.html: Unchanged skipping 2026/10/05 05:19:42 DEBUG : sub/deep/index.html: md5 = 47aa6af005dab0fe0fbbf68cb0d7eab8 OK 2026/10/05 05:19:42 DEBUG : sub/deep/index.html: size = 12346 OK 2026/10/05 05:19:42 INFO : sub/deep/index.html: Copied (replaced existing) 2026/10/05 05:19:46 DEBUG : sub/new/file5.txt: md5 = a75be6db7993f3acacd43924c437b660 OK 2026/10/05 05:19:46 INFO : Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado': Partial index: listing 3 directories and walking 0 changed directories 2026/10/05 05:19:48 DEBUG : index.html: Unchanged skipping 2026/10/05 05:19:49 DEBUG : sub/index.html: md5 = 9fcfcb8675fab705ceaf300fc04f6e36 OK 2026/10/05 05:19:49 DEBUG : sub/index.html: size = 12554 OK 2026/10/05 05:19:49 INFO : sub/index.html: Copied (replaced existing) 2026/10/05 05:19:49 DEBUG : sub/new/index.html: md5 = 20642158c66df3e0f62bc76796b5d755 OK 2026/10/05 05:19:49 DEBUG : sub/new/index.html: size = 12085 OK 2026/10/05 05:19:49 INFO : sub/new/index.html: Copied (new) 2026/10/05 05:19:53 DEBUG : other/undeclared0.txt: md5 = 3e948592dbb14c4f8bc029d3fa4a533b OK 2026/10/05 05:19:53 INFO : Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado': Partial index: listing 1 directories and walking 1 changed directories 2026/10/05 05:19:54 DEBUG : index.html: Unchanged skipping 2026/10/05 05:19:55 DEBUG : other/index.html: md5 = 889615b4476ee729ff2c19935f39c30c OK 2026/10/05 05:19:55 DEBUG : other/index.html: size = 12597 OK 2026/10/05 05:19:55 INFO : other/index.html: Copied (replaced existing) 2026/10/05 05:19:57 DEBUG : other/undeclared1.txt: md5 = 1ea8e0de58df0c43ac1332fa9f0f3bb5 OK 2026/10/05 05:19:57 INFO : Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado': Partial index: listing 1 directories and walking 0 changed directories 2026/10/05 05:19:58 DEBUG : index.html: Unchanged skipping 2026/10/05 05:19:59 DEBUG : other/index.html: md5 = 8eed3e86bca52fdcb34e1d8d15ff42df OK 2026/10/05 05:19:59 DEBUG : other/index.html: size = 12867 OK 2026/10/05 05:19:59 INFO : other/index.html: Copied (replaced existing) 2026/10/05 05:20:02 DEBUG : sub/file6.txt: md5 = 57104a8a2b3f08a6ddcc5db6ca92a964 OK 2026/10/05 05:20:02 INFO : Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado': Nothing changed so nothing to index 2026/10/05 05:20:03 INFO : Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado': Root changed so doing a full index 2026/10/05 05:20:04 DEBUG : index.html: Unchanged skipping 2026/10/05 05:20:04 DEBUG : other/index.html: Unchanged skipping 2026/10/05 05:20:04 DEBUG : sub/new/index.html: Unchanged skipping 2026/10/05 05:20:04 DEBUG : sub/deep/index.html: Unchanged skipping 2026/10/05 05:20:05 DEBUG : sub/index.html: md5 = 166d353f5be1b61a4ba387ce0d30eb18 OK 2026/10/05 05:20:05 DEBUG : sub/index.html: size = 12813 OK 2026/10/05 05:20:05 INFO : sub/index.html: Copied (replaced existing) 2026/10/05 05:20:08 DEBUG : sub/file7.txt: md5 = 59990074dd202970c235878bff730fad OK 2026/10/05 05:20:08 INFO : Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado': Partial index would list 2 directories, more than 1, so doing a full index 2026/10/05 05:20:09 DEBUG : other/index.html: Unchanged skipping 2026/10/05 05:20:09 DEBUG : sub/new/index.html: Unchanged skipping 2026/10/05 05:20:09 DEBUG : index.html: Unchanged skipping 2026/10/05 05:20:09 DEBUG : sub/deep/index.html: Unchanged skipping 2026/10/05 05:20:10 DEBUG : sub/index.html: md5 = 48a623943cad548fc5b20671efe2e148 OK 2026/10/05 05:20:10 DEBUG : sub/index.html: size = 13071 OK 2026/10/05 05:20:10 INFO : sub/index.html: Copied (replaced existing) 2026/10/05 05:20:13 DEBUG : sub/deep/file8.txt: md5 = 95b9262698d2573e33f22ab2cc46bbe2 OK 2026/10/05 05:20:13 INFO : Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado': Partial index: listing 1 directories and walking 1 changed directories 2026/10/05 05:20:14 DEBUG : index.html: Unchanged skipping 2026/10/05 05:20:14 DEBUG : sub/index.html: Unchanged skipping 2026/10/05 05:20:14 DEBUG : sub/new/index.html: Unchanged skipping 2026/10/05 05:20:15 DEBUG : sub/deep/index.html: md5 = ca0e26bb6d01551dbd9a28387e26d71e OK 2026/10/05 05:20:15 DEBUG : sub/deep/index.html: size = 12604 OK 2026/10/05 05:20:15 INFO : sub/deep/index.html: Copied (replaced existing) 2026/10/05 05:20:18 DEBUG : sub/deep/file9.txt: md5 = 16cf040508ef4d7b84b4d843567526da OK 2026/10/05 05:20:19 DEBUG : sub/file10.txt: md5 = 0ef0feacfd91b4b9aae1b802dc23d877 OK 2026/10/05 05:20:19 INFO : Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado': Partial index: listing 2 directories and walking 0 changed directories 2026/10/05 05:20:20 DEBUG : index.html: Unchanged skipping 2026/10/05 05:20:21 DEBUG : sub/index.html: md5 = d905fe35df0f1198713ec9bc063d93b5 OK 2026/10/05 05:20:21 DEBUG : sub/index.html: size = 13331 OK 2026/10/05 05:20:21 INFO : sub/index.html: Copied (replaced existing) --- PASS: TestIndexChanged (75.39s) === RUN TestIndexChangedDelete run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:20:42 DEBUG : file1.txt: md5 = 4562d53fb325cb1c8f903c22ac10b112 OK 2026/10/05 05:20:44 DEBUG : sub/file2.txt: md5 = 9ac879de7cd6ae930263aae586ab4052 OK 2026/10/05 05:20:47 DEBUG : sub/deep/file3.txt: md5 = 7af7bbc5586e7f8cccdc51f17f1fc9e9 OK 2026/10/05 05:20:50 DEBUG : index.html: md5 = 8682639b506034ac5608ffd9a0f08e44 OK 2026/10/05 05:20:50 DEBUG : index.html: size = 12147 OK 2026/10/05 05:20:50 INFO : index.html: Copied (new) 2026/10/05 05:20:50 DEBUG : sub/deep/index.html: md5 = 94b31f336aff7e71de616456a12ee765 OK 2026/10/05 05:20:50 DEBUG : sub/deep/index.html: size = 12087 OK 2026/10/05 05:20:50 INFO : sub/deep/index.html: Copied (new) 2026/10/05 05:20:50 DEBUG : sub/index.html: md5 = d93796a7020e8e9bf0e0e097639a0d3e OK 2026/10/05 05:20:50 DEBUG : sub/index.html: size = 12306 OK 2026/10/05 05:20:50 INFO : sub/index.html: Copied (new) 2026/10/05 05:20:51 INFO : Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado': Partial index: listing 3 directories and walking 0 changed directories 2026/10/05 05:20:52 DEBUG : sub/index.html: Unchanged skipping 2026/10/05 05:20:52 DEBUG : index.html: Unchanged skipping 2026/10/05 05:20:53 DEBUG : sub/deep/index.html: md5 = 8e909092660bd3d9f1176b140648a1f5 OK 2026/10/05 05:20:53 DEBUG : sub/deep/index.html: size = 11829 OK 2026/10/05 05:20:53 INFO : sub/deep/index.html: Copied (replaced existing) --- PASS: TestIndexChangedDelete (20.26s) === RUN TestIndexChangedFiles run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:21:02 DEBUG : file1.txt: md5 = 7d082657acd7c8e7b57166c935f345d2 OK 2026/10/05 05:21:04 DEBUG : sub/file2.txt: md5 = 4708b7ae3db90afa7f5976d92f1657c1 OK 2026/10/05 05:21:07 DEBUG : sub/deep/file3.txt: md5 = 8ba0e22559877dc28ce58a74e3dd259d OK 2026/10/05 05:21:10 DEBUG : - odd/file.txt: md5 = 0be957865ead954ee77887cf3fc44a58 OK 2026/10/05 05:21:12 DEBUG : sub/index.html: md5 = 8843d3901195c4852e6173863ea1008e OK 2026/10/05 05:21:12 DEBUG : sub/index.html: size = 12306 OK 2026/10/05 05:21:12 INFO : sub/index.html: Copied (new) 2026/10/05 05:21:12 DEBUG : - odd/index.html: md5 = a2c7dea43b2bdbf309c90921ae6ed565 OK 2026/10/05 05:21:12 DEBUG : - odd/index.html: size = 12058 OK 2026/10/05 05:21:12 INFO : - odd/index.html: Copied (new) 2026/10/05 05:21:12 DEBUG : index.html: md5 = a5d6317b30f45287d08fdcd78e405338 OK 2026/10/05 05:21:12 DEBUG : index.html: size = 12401 OK 2026/10/05 05:21:12 INFO : index.html: Copied (new) 2026/10/05 05:21:13 DEBUG : sub/deep/index.html: md5 = b957acab03aea8d06f94174a238c0b01 OK 2026/10/05 05:21:13 DEBUG : sub/deep/index.html: size = 12087 OK 2026/10/05 05:21:13 INFO : sub/deep/index.html: Copied (new) 2026/10/05 05:21:15 DEBUG : - odd/file8.txt: md5 = 9c7592a7286b33abf6255382b74954f4 OK 2026/10/05 05:21:17 DEBUG : sub/file9.txt: md5 = 3195a1d619f5686f99796bb64eff4bb8 OK 2026/10/05 05:21:17 INFO : Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado': Partial index: listing 2 directories and walking 0 changed directories 2026/10/05 05:21:18 DEBUG : index.html: Unchanged skipping 2026/10/05 05:21:19 DEBUG : - odd/index.html: md5 = 641c9bf52890c993bfc363f0134eacac OK 2026/10/05 05:21:19 DEBUG : - odd/index.html: size = 12316 OK 2026/10/05 05:21:19 INFO : - odd/index.html: Copied (replaced existing) 2026/10/05 05:21:20 INFO : Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado': Partial index: listing 4 directories and walking 0 changed directories 2026/10/05 05:21:22 DEBUG : - odd/index.html: Unchanged skipping 2026/10/05 05:21:22 DEBUG : sub/deep/index.html: Unchanged skipping 2026/10/05 05:21:22 DEBUG : index.html: Unchanged skipping 2026/10/05 05:21:23 DEBUG : sub/index.html: md5 = b2fd61e2ee5de1c59deb692826e4202f OK 2026/10/05 05:21:23 DEBUG : sub/index.html: size = 12565 OK 2026/10/05 05:21:23 INFO : sub/index.html: Copied (replaced existing) --- PASS: TestIndexChangedFiles (34.12s) === RUN TestIndexChangedDirTime run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:21:36 DEBUG : file1.txt: md5 = 436f060fbaad80f697cfcd7ee000c958 OK 2026/10/05 05:21:39 DEBUG : sub/file2.txt: md5 = 24604fa0c06af45989faae904113d111 OK 2026/10/05 05:21:41 DEBUG : sub/deep/file3.txt: md5 = 1ad0da1f8605eb0ede6f2e6f8a4636cb OK 2026/10/05 05:21:44 DEBUG : other/notes.md: md5 = 8ce11bb07f1d250dfee116401cb9a55c OK 2026/10/05 05:21:46 DEBUG : sub/deep/index.json: md5 = 37d08c3df09d038e46d3b0291f4b67fe OK 2026/10/05 05:21:46 DEBUG : sub/deep/index.json: size = 139 OK 2026/10/05 05:21:46 INFO : sub/deep/index.json: Copied (new) 2026/10/05 05:21:46 DEBUG : sub/index.json: md5 = 6ee16f0e4db01efb7b7285320576bc38 OK 2026/10/05 05:21:46 DEBUG : sub/index.json: size = 259 OK 2026/10/05 05:21:46 INFO : sub/index.json: Copied (new) 2026/10/05 05:21:46 DEBUG : other/index.json: md5 = 4dc29d114825edbdc4f5860593f0f6b4 OK 2026/10/05 05:21:46 DEBUG : other/index.json: size = 144 OK 2026/10/05 05:21:46 INFO : other/index.json: Copied (new) 2026/10/05 05:21:46 DEBUG : index.json: md5 = b7100d204e0673752b9d6de899a5b0d1 OK 2026/10/05 05:21:46 DEBUG : index.json: size = 379 OK 2026/10/05 05:21:46 INFO : index.json: Copied (new) 2026/10/05 05:21:48 DEBUG : sub/deep/file4.txt: md5 = 18e963ffb4d9b775efff8579622ddf7f OK 2026/10/05 05:21:48 INFO : Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado': Partial index: listing 3 directories and walking 0 changed directories 2026/10/05 05:21:51 DEBUG : sub/deep/index.json: md5 = 480258152df826ccdd41215e00324f79 OK 2026/10/05 05:21:51 DEBUG : sub/deep/index.json: size = 275 OK 2026/10/05 05:21:51 INFO : sub/deep/index.json: Copied (replaced existing) 2026/10/05 05:21:51 DEBUG : sub/index.json: md5 = a3cd6defbad027f7cfac56a0acf155cb OK 2026/10/05 05:21:51 DEBUG : sub/index.json: size = 259 OK 2026/10/05 05:21:51 INFO : sub/index.json: Copied (replaced existing) 2026/10/05 05:21:51 DEBUG : index.json: md5 = 77d70fe70b9bc05404143764fd21c9ec OK 2026/10/05 05:21:51 DEBUG : index.json: size = 379 OK 2026/10/05 05:21:51 INFO : index.json: Copied (replaced existing) --- PASS: TestIndexChangedDirTime (27.04s) === RUN TestListDirSorted run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:22:03 DEBUG : a.txt: md5 = e9290aa5dc224162eab05cdb73cf5a66 OK 2026/10/05 05:22:05 DEBUG : zend.txt: md5 = a6dbd407446ece7e82aa5b7909e90fd2 OK 2026/10/05 05:22:08 DEBUG : sub dir/hello world: md5 = 1219457d001693e286c043679a2255a9 OK 2026/10/05 05:22:09 DEBUG : sub dir/hello world2: md5 = c11010b706249bb7a2aff42a54d55a14 OK 2026/10/05 05:22:12 DEBUG : sub dir/ignore dir/.ignore: md5 = cab7e0677b574852c4cbb502c9d2f464 OK 2026/10/05 05:22:14 DEBUG : sub dir/ignore dir/should be ignored: md5 = 36fb902fba7ac34ac51b7c8e585c2436 OK 2026/10/05 05:22:16 DEBUG : sub dir/sub sub dir/hello world3: md5 = f9860eb8efd60ea1bcc158583b199bde OK 2026/10/05 05:22:18 DEBUG : a.txt: Excluded (Size Filter) 2026/10/05 05:22:18 DEBUG : a.txt: Excluded 2026/10/05 05:22:18 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/10/05 05:22:18 DEBUG : sub dir/hello world: Excluded 2026/10/05 05:22:18 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/10/05 05:22:18 DEBUG : sub dir/hello world2: Excluded 2026/10/05 05:22:19 DEBUG : sub dir/ignore dir: Excluded 2026/10/05 05:22:19 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/10/05 05:22:19 DEBUG : sub dir/hello world: Excluded 2026/10/05 05:22:19 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/10/05 05:22:19 DEBUG : sub dir/hello world2: Excluded 2026/10/05 05:22:20 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSorted (26.52s) === RUN TestListDirSortedFn run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:22:30 DEBUG : a.txt: md5 = f54cac1f21e6a0da39d6d237a22945b0 OK 2026/10/05 05:22:31 DEBUG : zend.txt: md5 = 29848d27ead8662573bdc5bb174cab49 OK 2026/10/05 05:22:34 DEBUG : sub dir/hello world: md5 = a3d919b5aed54446f6d5c9a337fcf3d0 OK 2026/10/05 05:22:36 DEBUG : sub dir/hello world2: md5 = 68b61dbf71dee0c3e4300ad7ddc1eb04 OK 2026/10/05 05:22:39 DEBUG : sub dir/ignore dir/.ignore: md5 = 90a012adb0cd2ad54ec2f07cbad4f1d6 OK 2026/10/05 05:22:40 DEBUG : sub dir/ignore dir/should be ignored: md5 = 04c20f2ad0c64cf8cbf0f680e6472a98 OK 2026/10/05 05:22:43 DEBUG : sub dir/sub sub dir/hello world3: md5 = abcaa3f7f55519c532127874cd6aa844 OK 2026/10/05 05:22:44 DEBUG : a.txt: Excluded (Size Filter) 2026/10/05 05:22:44 DEBUG : a.txt: Excluded 2026/10/05 05:22:45 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/10/05 05:22:45 DEBUG : sub dir/hello world: Excluded 2026/10/05 05:22:45 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/10/05 05:22:45 DEBUG : sub dir/hello world2: Excluded 2026/10/05 05:22:46 DEBUG : sub dir/ignore dir: Excluded 2026/10/05 05:22:46 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/10/05 05:22:46 DEBUG : sub dir/hello world: Excluded 2026/10/05 05:22:46 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/10/05 05:22:46 DEBUG : sub dir/hello world2: Excluded 2026/10/05 05:22:46 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSortedFn (26.48s) === RUN TestListJSON run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:22:56 DEBUG : file1: md5 = d4501393ae289e3b239d1493cd58ef4c OK 2026/10/05 05:22:59 DEBUG : sub/file2: md5 = 5a99eb03e2cd571665ec47eccdf7841f OK === RUN TestListJSON/Default === RUN TestListJSON/FilesOnly === RUN TestListJSON/DirsOnly === RUN TestListJSON/Recurse === RUN TestListJSON/SubDir === RUN TestListJSON/NoModTime === RUN TestListJSON/NoMimeType === RUN TestListJSON/ShowHash === RUN TestListJSON/HashTypes 2026/10/05 05:23:03 ERROR : file1: Failed to read hash: hash type not supported === RUN TestListJSON/Metadata 2026/10/05 05:23:03 DEBUG : eer8kka55qnghc34cq76ca668g: Fetching metadata 2026/10/05 05:23:03 DEBUG : 150fuo3cn4j1uenq6r4g4qk6t4: Fetching metadata --- PASS: TestListJSON (11.90s) --- PASS: TestListJSON/Default (0.30s) --- PASS: TestListJSON/FilesOnly (0.30s) --- PASS: TestListJSON/DirsOnly (0.31s) --- PASS: TestListJSON/Recurse (0.61s) --- PASS: TestListJSON/SubDir (0.29s) --- PASS: TestListJSON/NoModTime (0.29s) --- PASS: TestListJSON/NoMimeType (0.30s) --- PASS: TestListJSON/ShowHash (0.33s) --- PASS: TestListJSON/HashTypes (0.32s) --- PASS: TestListJSON/Metadata (0.78s) === RUN TestStatJSON run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:23:08 DEBUG : file1: md5 = 93d92c8a591a4abfb88234f5c799ba24 OK 2026/10/05 05:23:11 DEBUG : sub/file2: md5 = 62139b796a6d607da6b816e672f0edc0 OK === RUN TestStatJSON/Root === RUN TestStatJSON/RootFilesOnly === RUN TestStatJSON/RootDirsOnly === RUN TestStatJSON/Dir === RUN TestStatJSON/DirWithTrailingSlash === RUN TestStatJSON/File === RUN TestStatJSON/NotFound === RUN TestStatJSON/DirFilesOnly === RUN TestStatJSON/FileFilesOnly === RUN TestStatJSON/NotFoundFilesOnly === RUN TestStatJSON/DirDirsOnly === RUN TestStatJSON/FileDirsOnly === RUN TestStatJSON/NotFoundDirsOnly === RUN TestStatJSON/RootNotFound 2026/10/05 05:23:15 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-mivobir3wado/notfound" 2026/10/05 05:23:15 DEBUG : Config file has changed externally - reloading 2026/10/05 05:23:15 DEBUG : Creating backend with remote "TestDrive:crypt/nof1opn6ntbdfclfpa2iuae4bnqqkrnr94r6i17737dc3urifnu0/qe0i31qdkoejk60elps0ni5sqk" --- PASS: TestStatJSON (13.72s) --- PASS: TestStatJSON/Root (0.29s) --- PASS: TestStatJSON/RootFilesOnly (0.00s) --- PASS: TestStatJSON/RootDirsOnly (0.28s) --- PASS: TestStatJSON/Dir (0.58s) --- PASS: TestStatJSON/DirWithTrailingSlash (0.30s) --- PASS: TestStatJSON/File (0.28s) --- PASS: TestStatJSON/NotFound (0.56s) --- PASS: TestStatJSON/DirFilesOnly (0.32s) --- PASS: TestStatJSON/FileFilesOnly (0.33s) --- PASS: TestStatJSON/NotFoundFilesOnly (0.26s) --- PASS: TestStatJSON/DirDirsOnly (0.28s) --- PASS: TestStatJSON/FileDirsOnly (0.31s) --- PASS: TestStatJSON/NotFoundDirsOnly (0.28s) --- PASS: TestStatJSON/RootNotFound (1.91s) === RUN TestStatJSONMemory 2026/10/05 05:23:20 DEBUG : Creating backend with remote ":memory:" 2026/10/05 05:23:20 DEBUG : Memory root '': File to upload is small (5 bytes), uploading instead of streaming 2026/10/05 05:23:20 DEBUG : sub/file1: size = 5 OK 2026/10/05 05:23:20 DEBUG : sub/file1: md5 = 5d41402abc4b2a76b9719d911017c592 OK 2026/10/05 05:23:20 DEBUG : sub/file1: Size and md5 of src and dst objects identical === RUN TestStatJSONMemory/Dir === RUN TestStatJSONMemory/DirWithTrailingSlash === RUN TestStatJSONMemory/File === RUN TestStatJSONMemory/NotFound --- PASS: TestStatJSONMemory (0.00s) --- PASS: TestStatJSONMemory/Dir (0.00s) --- PASS: TestStatJSONMemory/DirWithTrailingSlash (0.00s) --- PASS: TestStatJSONMemory/File (0.00s) --- PASS: TestStatJSONMemory/NotFound (0.00s) === RUN TestStatJSONConfinement --- PASS: TestStatJSONConfinement (0.00s) === RUN TestMkdir run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:23:20 INFO : Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado': Making directory 2026/10/05 05:23:20 INFO : Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado': Making directory --- PASS: TestMkdir (0.72s) === RUN TestLsd run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:23:23 DEBUG : sub dir/hello world: md5 = 3eeaed6cb955d00c7c4d562e79cc3073 OK --- PASS: TestLsd (5.64s) === RUN TestLs run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:23:28 DEBUG : potato2: md5 = 9c7408c9c8edaacc026c096c96d84e06 OK 2026/10/05 05:23:30 DEBUG : empty space: md5 = 8dba4e08f9c9a6fec7c1522ebf251c64 OK --- PASS: TestLs (5.55s) === RUN TestLsWithFilesFrom run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:23:34 DEBUG : potato2: md5 = 98f1c9fe7bc249cda92009763eb38b65 OK 2026/10/05 05:23:36 DEBUG : empty space: md5 = 6bf6e855f91f23ff5e5414add40fdeee OK 2026/10/05 05:23:36 DEBUG : empty space: Excluded (FilesFrom Filter) 2026/10/05 05:23:36 DEBUG : empty space: Excluded --- PASS: TestLsWithFilesFrom (6.56s) === RUN TestLsLong run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:23:40 DEBUG : potato2: md5 = 0f6a20d571160ada303f8a11145f20c2 OK 2026/10/05 05:23:42 DEBUG : empty space: md5 = a9c1300d5b3bd388d05a3eafbec45bec OK --- PASS: TestLsLong (5.79s) === RUN TestHashSums run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:23:46 DEBUG : potato2: md5 = 1287e12e90b354744c2b5169f788b03b OK 2026/10/05 05:23:47 DEBUG : empty space: md5 = ab73d2a874bd72b1f08560ef412727ab OK --- PASS: TestHashSums (5.11s) === RUN TestHashSumsWithErrors 2026/10/05 05:23:49 DEBUG : Creating backend with remote ":memory:" 2026/10/05 05:23:49 ERROR : file1: hash unsupported: hash type not supported 2026/10/05 05:23:49 ERROR : sub/file1: hash unsupported: hash type not supported 2026/10/05 05:23:49 ERROR : sub/file1: hash unsupported: hash type not supported --- PASS: TestHashSumsWithErrors (0.00s) === RUN TestHashStream 2026/10/05 05:23:49 DEBUG : Creating md5 hash of 0 bytes read from input stream 2026/10/05 05:23:49 DEBUG : Creating md5 hash of 0 bytes read from input stream 2026/10/05 05:23:49 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2026/10/05 05:23:49 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2026/10/05 05:23:49 DEBUG : Creating md5 hash of 12 bytes read from input stream 2026/10/05 05:23:49 DEBUG : Creating md5 hash of 12 bytes read from input stream 2026/10/05 05:23:49 DEBUG : Creating sha1 hash of 12 bytes read from input stream 2026/10/05 05:23:49 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-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:23:51 DEBUG : potato2: md5 = ba88d7bd93050d7b6942afa25b7b6978 OK 2026/10/05 05:23:53 DEBUG : empty space: md5 = 173b3e464ef68a2b86007a7ebadd1398 OK 2026/10/05 05:23:55 DEBUG : sub dir/potato3: md5 = 98200a22fbe2006bebf446f09771bda7 OK --- PASS: TestCount (10.27s) === RUN TestDelete run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:24:01 DEBUG : small: md5 = 2e9cad6c507505ef657fbb24e0a81477 OK 2026/10/05 05:24:03 DEBUG : medium: md5 = fbc1b0c1458645d4f8029b4a0528997d OK 2026/10/05 05:24:05 DEBUG : large: md5 = 25fad4c19a80ebb77ecdec9408751fd1 OK 2026/10/05 05:24:05 DEBUG : Waiting for deletions to finish 2026/10/05 05:24:05 DEBUG : large: Excluded (Size Filter) 2026/10/05 05:24:06 INFO : small: Deleted 2026/10/05 05:24:06 INFO : medium: Deleted --- PASS: TestDelete (7.58s) === RUN TestDeleteFatalError run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:24:09 DEBUG : file0: md5 = 40f7cd57c6097e1246cd739f9e66c97b OK 2026/10/05 05:24:11 DEBUG : file1: md5 = b8cfda6eade2bbfa0b76573a24b392f5 OK 2026/10/05 05:24:13 DEBUG : file2: md5 = c397acacd2a4dd2a44c451bcf634a144 OK 2026/10/05 05:24:15 DEBUG : file3: md5 = 43e237eb5f965f7abf7d1f30f7f28dc2 OK 2026/10/05 05:24:16 DEBUG : file4: md5 = 013869c1c28c30ef585cf72457c06ee4 OK 2026/10/05 05:24:18 DEBUG : file5: md5 = f4f4a892d9daa43ea2d50a4f511812fc OK 2026/10/05 05:24:20 DEBUG : file6: md5 = c28189ae2b77b50094db95a6ed0bb9db OK 2026/10/05 05:24:21 DEBUG : file7: md5 = eaa1bfc85a8ff2a9f204b889af3f0cab OK 2026/10/05 05:24:23 DEBUG : file8: md5 = 1e61c585ffa065f383d1a6ac3e5b3c80 OK 2026/10/05 05:24:24 DEBUG : file9: md5 = 256a11e8fc803073e02a25ce0827856f OK 2026/10/05 05:24:26 DEBUG : file10: md5 = cd37f5ba4bb4f56512adcaa9bfd2019b OK 2026/10/05 05:24:27 DEBUG : file11: md5 = 472920138c240a9d7bc3d6dada4d1b8f OK 2026/10/05 05:24:29 DEBUG : file12: md5 = 9b5727ba5f61cdcbbdee8d374562241b OK 2026/10/05 05:24:31 DEBUG : file13: md5 = 4b996954342e5c71ac07779771b69836 OK 2026/10/05 05:24:32 DEBUG : file14: md5 = 19b5690acb7ba74c34e76f26287a3dfd OK 2026/10/05 05:24:34 DEBUG : file15: md5 = b8fdbd0fe10903d8856b66542cfb71e3 OK 2026/10/05 05:24:36 DEBUG : file16: md5 = 2a9288462e89cf8150f8c9144b74824b OK 2026/10/05 05:24:38 DEBUG : file17: md5 = ad4e922f69c8301206582989d6fd603c OK 2026/10/05 05:24:39 DEBUG : file18: md5 = abed127ca63051f086d3e508ae299a1c OK 2026/10/05 05:24:41 DEBUG : file19: md5 = 2094d119643292e7822fcf6126fd4d11 OK 2026/10/05 05:24:41 DEBUG : Waiting for deletions to finish 2026/10/05 05:24:41 ERROR : file4: Got fatal error on delete: --max-delete threshold reached 2026/10/05 05:24:41 ERROR : file2: Got fatal error on delete: --max-delete threshold reached 2026/10/05 05:24:41 ERROR : file13: Got fatal error on delete: --max-delete threshold reached 2026/10/05 05:24:41 ERROR : file10: Got fatal error on delete: --max-delete threshold reached 2026/10/05 05:24:41 ERROR : file11: Got fatal error on delete: --max-delete threshold reached 2026/10/05 05:24:41 ERROR : file7: Got fatal error on delete: --max-delete threshold reached 2026/10/05 05:24:41 ERROR : file19: Got fatal error on delete: --max-delete threshold reached 2026/10/05 05:24:41 ERROR : file16: Got fatal error on delete: --max-delete threshold reached 2026/10/05 05:24:41 ERROR : file15: Got fatal error on delete: --max-delete threshold reached 2026/10/05 05:24:41 ERROR : file8: Got fatal error on delete: --max-delete threshold reached 2026/10/05 05:24:41 ERROR : file14: Got fatal error on delete: --max-delete threshold reached 2026/10/05 05:24:41 ERROR : file1: Got fatal error on delete: --max-delete threshold reached 2026/10/05 05:24:41 ERROR : file12: Got fatal error on delete: --max-delete threshold reached 2026/10/05 05:24:41 ERROR : file18: Got fatal error on delete: --max-delete threshold reached 2026/10/05 05:24:41 ERROR : file6: Got fatal error on delete: --max-delete threshold reached 2026/10/05 05:24:41 ERROR : file0: Got fatal error on delete: --max-delete threshold reached 2026/10/05 05:24:41 ERROR : file3: Got fatal error on delete: --max-delete threshold reached 2026/10/05 05:24:41 ERROR : file17: Got fatal error on delete: --max-delete threshold reached 2026/10/05 05:24:41 ERROR : file9: Got fatal error on delete: --max-delete threshold reached 2026/10/05 05:24:41 ERROR : file5: Got fatal error on delete: --max-delete threshold reached --- PASS: TestDeleteFatalError (46.58s) === RUN TestMaxDelete run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:24:56 DEBUG : small: md5 = a310bfe685cfbeead8210bffe1b712d0 OK 2026/10/05 05:24:57 DEBUG : medium: md5 = 65ed4e1917ecfd2c5cab10e2eb42d359 OK 2026/10/05 05:24:59 DEBUG : large: md5 = 53aa97e00cf9390c5cf3489cd781a4df OK 2026/10/05 05:24:59 DEBUG : Waiting for deletions to finish 2026/10/05 05:24:59 ERROR : large: Got fatal error on delete: --max-delete threshold reached 2026/10/05 05:25:00 INFO : medium: Deleted 2026/10/05 05:25:00 INFO : small: Deleted --- PASS: TestMaxDelete (9.47s) === RUN TestMaxDeleteSizeLargeFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:25:05 DEBUG : small: md5 = b7742cfc0d1192e6d49b87e6e324b277 OK 2026/10/05 05:25:07 DEBUG : medium: md5 = 9e87dd741dbb47f80108ff160892f0f3 OK 2026/10/05 05:25:08 DEBUG : large: md5 = bb3da7e64fdc266740dee847b947ce54 OK 2026/10/05 05:25:09 DEBUG : Waiting for deletions to finish 2026/10/05 05:25:09 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached 2026/10/05 05:25:10 INFO : small: Deleted 2026/10/05 05:25:10 INFO : medium: Deleted --- PASS: TestMaxDeleteSizeLargeFile (8.30s) === RUN TestMaxDeleteSize run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:25:13 DEBUG : small: md5 = eb8a3b97d4ce911c102f37570e1e1bf1 OK 2026/10/05 05:25:15 DEBUG : medium: md5 = c32fb266878cb8d613db777ca68853d3 OK 2026/10/05 05:25:17 DEBUG : large: md5 = 964ddd7924d7eebe4d889da912ae6403 OK 2026/10/05 05:25:17 DEBUG : Waiting for deletions to finish 2026/10/05 05:25:17 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached 2026/10/05 05:25:18 INFO : medium: Deleted 2026/10/05 05:25:18 INFO : small: Deleted --- PASS: TestMaxDeleteSize (8.25s) === RUN TestReadFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:25:22 DEBUG : ReadFile: md5 = ef82f8221191db1b15672225eb9ababf OK --- PASS: TestReadFile (4.86s) === RUN TestRetry 2026/10/05 05:25:25 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 1/5 2026/10/05 05:25:25 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 2/5 2026/10/05 05:25:25 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2026/10/05 05:25:25 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2026/10/05 05:25:25 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2026/10/05 05:25:25 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/10/05 05:25:25 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-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:25:27 DEBUG : file1: md5 = dfa1634e8f095be83efcb07c467bf961 OK 2026/10/05 05:25:28 DEBUG : file2: md5 = da2a0a387eb19190c2b7ea99adba7da9 OK --- PASS: TestCat (14.22s) === RUN TestPurge 2026/10/05 05:25:39 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-liqogah4quga" 2026/10/05 05:25:39 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/10/05 05:25:39 DEBUG : Creating backend with remote "TestDrive:crypt/6je7vmtami5brs81ots0g4hbfri73tf2cvqhnhcj36eri63jpg7g" 2026/10/05 05:25:40 DEBUG : Creating backend with remote "/tmp/rclone940007382" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-liqogah4quga'", Local "Local file system at /tmp/rclone940007382", Modify Window "1ms" 2026/10/05 05:25:46 DEBUG : A1/B1/C1/one: md5 = a53b63c5a019bc2a35bba0033e7b0cc9 OK 2026/10/05 05:25:46 INFO : A2: Making directory 2026/10/05 05:25:47 INFO : A1/B2: Making directory 2026/10/05 05:25:48 INFO : A1/B2/C2: Making directory 2026/10/05 05:25:49 INFO : A1/B1/C3: Making directory 2026/10/05 05:25:50 INFO : A3: Making directory 2026/10/05 05:25:50 INFO : A3/B3: Making directory 2026/10/05 05:25:51 INFO : A3/B3/C4: Making directory 2026/10/05 05:25:54 DEBUG : A1/two: md5 = 41a79ad731c4ba50fba267aa5fcd03e3 OK 2026/10/05 05:25:58 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-liqogah4quga': Purge remote 2026/10/05 05:25:58 NOTICE: purge failed: directory not found --- PASS: TestPurge (19.41s) === RUN TestRmdirsNoLeaveRoot run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:26:03 DEBUG : A1/B1/C1/one: md5 = 8245d47f94bd709ae018055c17653c3c OK 2026/10/05 05:26:03 INFO : A2: Making directory 2026/10/05 05:26:04 INFO : A1/B2: Making directory 2026/10/05 05:26:05 INFO : A1/B2/C2: Making directory 2026/10/05 05:26:06 INFO : A1/B1/C3: Making directory 2026/10/05 05:26:07 INFO : A3: Making directory 2026/10/05 05:26:08 INFO : A3/B3: Making directory 2026/10/05 05:26:08 INFO : A3/B3/C4: Making directory 2026/10/05 05:26:11 DEBUG : A1/two: md5 = d2d4109f593c8522127ecc5f5a3e125f OK 2026/10/05 05:26:13 DEBUG : removing 1 level 3 directories 2026/10/05 05:26:13 INFO : A3/B3/C4: Removing directory 2026/10/05 05:26:16 DEBUG : removing 2 level 3 directories 2026/10/05 05:26:16 INFO : A1/B2/C2: Removing directory 2026/10/05 05:26:16 INFO : A1/B1/C3: Removing directory 2026/10/05 05:26:17 DEBUG : removing 2 level 2 directories 2026/10/05 05:26:17 INFO : A3/B3: Removing directory 2026/10/05 05:26:17 INFO : A1/B2: Removing directory 2026/10/05 05:26:18 DEBUG : removing 2 level 1 directories 2026/10/05 05:26:18 INFO : A3: Removing directory 2026/10/05 05:26:18 INFO : A2: Removing directory 2026/10/05 05:26:22 DEBUG : removing 1 level 3 directories 2026/10/05 05:26:22 INFO : A1/B1/C1: Removing directory 2026/10/05 05:26:23 DEBUG : removing 1 level 2 directories 2026/10/05 05:26:23 INFO : A1/B1: Removing directory 2026/10/05 05:26:24 DEBUG : removing 1 level 1 directories 2026/10/05 05:26:24 INFO : A1: Removing directory 2026/10/05 05:26:25 DEBUG : removing 1 level 0 directories 2026/10/05 05:26:25 INFO : Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado': Removing directory --- PASS: TestRmdirsNoLeaveRoot (28.23s) === RUN TestRmdirsLeaveRoot run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:26:28 INFO : A1: Making directory 2026/10/05 05:26:28 INFO : A1/B1: Making directory 2026/10/05 05:26:29 INFO : A1/B1/C1: Making directory 2026/10/05 05:26:32 DEBUG : removing 1 level 3 directories 2026/10/05 05:26:32 INFO : A1/B1/C1: Removing directory 2026/10/05 05:26:33 DEBUG : removing 1 level 2 directories 2026/10/05 05:26:33 INFO : A1/B1: Removing directory --- PASS: TestRmdirsLeaveRoot (9.65s) === RUN TestRmdirsWithFilter run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:26:36 INFO : A1: Making directory 2026/10/05 05:26:37 INFO : A1/B1: Making directory 2026/10/05 05:26:38 INFO : A1/B1/C1: Making directory 2026/10/05 05:26:42 DEBUG : removing 1 level 3 directories 2026/10/05 05:26:42 INFO : A1/B1/C1: Removing directory 2026/10/05 05:26:43 DEBUG : removing 1 level 2 directories 2026/10/05 05:26:43 INFO : A1/B1: Removing directory --- PASS: TestRmdirsWithFilter (9.34s) === RUN TestCopyURL run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:26:48 DEBUG : file1: md5 = 51c27d9c659bab10782fab475f1cf46c OK 2026/10/05 05:26:48 DEBUG : file1: size = 14 OK 2026/10/05 05:26:48 DEBUG : filename.txt: File name found in url 2026/10/05 05:26:50 DEBUG : filename.txt: md5 = d0742963899bd83ef41ccf4b186f77b7 OK 2026/10/05 05:26:50 DEBUG : filename.txt: size = 14 OK 2026/10/05 05:26:50 DEBUG : headerfilename.txt: filename found in Content-Disposition header. 2026/10/05 05:26:52 DEBUG : headerfilename.txt: md5 = 9f5144ad8d4098f740911401953934ae OK 2026/10/05 05:26:52 DEBUG : headerfilename.txt: size = 14 OK 2026/10/05 05:26:53 DEBUG : file2: md5 = afccc9607c895fa2a33d96d3ccb915e1 OK 2026/10/05 05:26:53 DEBUG : file2: size = 14 OK --- PASS: TestCopyURL (10.81s) === RUN TestCopyURLDownloadHeaders run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:26:58 DEBUG : file1: md5 = 09b321f3c79e2cfd9156f94813784e99 OK 2026/10/05 05:26:58 DEBUG : file1: size = 14 OK --- PASS: TestCopyURLDownloadHeaders (2.75s) === RUN TestCopyURLToWriter --- PASS: TestCopyURLToWriter (0.00s) === RUN TestMoveFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:26:59 DEBUG : file1: Need to transfer - File not found at Destination 2026/10/05 05:27:02 DEBUG : sub/file2: md5 = 08c0bf19c3b6f299fbbccc17b9bad2e5 OK 2026/10/05 05:27:02 DEBUG : sub/file2: size = 14 OK 2026/10/05 05:27:02 INFO : file1: Copied (new) to: sub/file2 2026/10/05 05:27:02 INFO : file1: Deleted 2026/10/05 05:27:03 DEBUG : sub/file2: size = 14 OK 2026/10/05 05:27:03 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/10/05 05:27:03 DEBUG : file1: Unchanged skipping 2026/10/05 05:27:03 INFO : file1: Deleted 2026/10/05 05:27:04 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado': don't need to copy/move sub/file2, it is already at target location --- PASS: TestMoveFile (7.25s) === RUN TestMoveFileWithIgnoreExisting run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:27:07 DEBUG : file1: Need to transfer - File not found at Destination 2026/10/05 05:27:09 DEBUG : file1: md5 = 7c8bfb4cf1c842873e7cf8dc4bcc42ab OK 2026/10/05 05:27:09 DEBUG : file1: size = 14 OK 2026/10/05 05:27:09 INFO : file1: Copied (new) 2026/10/05 05:27:09 INFO : file1: Deleted 2026/10/05 05:27:09 DEBUG : file1: Destination exists, skipping 2026/10/05 05:27:09 DEBUG : file1: Not removing source file as destination file exists and --ignore-existing is set --- PASS: TestMoveFileWithIgnoreExisting (4.05s) === RUN TestMoveFileImmutable run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:27:12 DEBUG : existing: md5 = a7fd4a86b1f21df7a8268947299ee99d OK 2026/10/05 05:27:13 DEBUG : existing: size = 8 (Local file system at /tmp/rclone3863668941) 2026/10/05 05:27:13 DEBUG : existing: size = 6 (Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado') 2026/10/05 05:27:13 DEBUG : existing: Sizes differ 2026/10/05 05:27:13 ERROR : existing: Source and destination exist but do not match: immutable file modified --- PASS: TestMoveFileImmutable (3.62s) === RUN TestCaseInsensitiveMoveFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFile (0.49s) === RUN TestCaseInsensitiveMoveFileDryRun run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFileDryRun (0.52s) === RUN TestMoveFileBackupDir run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:27:18 DEBUG : dst/file1: md5 = 010b9699839b604bec96cfbb3db35bbc OK 2026/10/05 05:27:19 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-mivobir3wado/backup" 2026/10/05 05:27:19 DEBUG : Creating backend with remote "TestDrive:crypt/nof1opn6ntbdfclfpa2iuae4bnqqkrnr94r6i17737dc3urifnu0/1nrff024r7pq65ecp72fc28jb0" 2026/10/05 05:27:20 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone3863668941) 2026/10/05 05:27:20 DEBUG : dst/file1: size = 18 (Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado') 2026/10/05 05:27:20 DEBUG : dst/file1: Sizes differ 2026/10/05 05:27:23 INFO : dst/file1: Moved (server-side) 2026/10/05 05:27:25 DEBUG : dst/file1: md5 = b8af6550a23dd1b5faa9123fdeb9fad5 OK 2026/10/05 05:27:25 DEBUG : dst/file1: size = 14 OK 2026/10/05 05:27:25 INFO : dst/file1: Copied (new) 2026/10/05 05:27:25 INFO : dst/file1: Deleted --- PASS: TestMoveFileBackupDir (15.11s) === 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-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:27:33 DEBUG : A/file0: md5 = e2a87c8ba3c67ec2a9c45cacbccb0e44 OK 2026/10/05 05:27:34 DEBUG : A/file1: md5 = 57d58cb9597601ff113ebdd20a0e8ec2 OK 2026/10/05 05:27:36 DEBUG : A/file2: md5 = 0df4f58313919140f33bbd12fda69e44 OK 2026/10/05 05:27:38 DEBUG : A/file3: md5 = f6baf899259115af01dd8ff66aadfb03 OK 2026/10/05 05:27:40 DEBUG : A/file4: md5 = 04f90ecec3f7d681b0561bbce048ce78 OK 2026/10/05 05:27:42 DEBUG : A/file5: md5 = a2698ce50ab147a8dd5913aeeb195f32 OK 2026/10/05 05:27:43 DEBUG : A/file6: md5 = 94888293399fe55a0adf4ba8d1141ed8 OK 2026/10/05 05:27:45 DEBUG : A/file7: md5 = 6d9e1d349bbccfa21737d8200b4d6971 OK 2026/10/05 05:27:46 DEBUG : A/file8: md5 = d81528dcbd3cf96f995cd8de48d9f4fd OK 2026/10/05 05:27:48 DEBUG : A/file9: md5 = 7ff29bd5ede76e734830491510e80ecb OK 2026/10/05 05:27:50 DEBUG : A/file10: md5 = 835581fcfcd6f34a6305a0c5fd274a09 OK 2026/10/05 05:27:51 DEBUG : A/file11: md5 = 1bbe53ec4f6fc377e9eb9648d8b08071 OK 2026/10/05 05:27:53 DEBUG : A/file12: md5 = bfca46c619a5d3a0d712ef4ed2577ad4 OK 2026/10/05 05:27:55 DEBUG : A/file13: md5 = f939b46d2f1b7c67fbac8d0ed49ca74a OK 2026/10/05 05:27:56 DEBUG : A/file14: md5 = edb9312c17010d896deed4353f10f794 OK 2026/10/05 05:27:58 DEBUG : A/file15: md5 = b5860d6da18cf210890854c99951b260 OK 2026/10/05 05:28:00 DEBUG : A/file16: md5 = acd84192dd69498e18d1a31f9b9ce8cc OK 2026/10/05 05:28:02 DEBUG : A/file17: md5 = 7780a1fb4e9fbd88e0d5ab65b5bbff2e OK 2026/10/05 05:28:03 DEBUG : A/file18: md5 = 36b7eb2913a44533b2b9f849ea6495f8 OK 2026/10/05 05:28:05 DEBUG : A/file19: md5 = f8b3403a1ff57bdc23cf6c77cc976b19 OK operations_test.go:1490: Skipping test on non local remote --- SKIP: TestDirMoveMoveError (50.23s) === RUN TestDirMoveContext run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:28:23 DEBUG : A/one: md5 = a8ceda93da2d4d2bbdc4d589a129234e OK 2026/10/05 05:28:25 DEBUG : A/two: md5 = adc3a3ae3daf955a311744c3b09daef4 OK operations_test.go:1490: Skipping test on non local remote --- SKIP: TestDirMoveContext (7.65s) === RUN TestDirMove run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:28:31 DEBUG : A1/one: md5 = 90ea4a13bfc0ffb50b675dc7a7cd5302 OK 2026/10/05 05:28:32 DEBUG : A1/two: md5 = b98fe6d2dcdc8fb128b28e7a7262ab00 OK 2026/10/05 05:28:35 DEBUG : A1/B1/three: md5 = 96615854dc08723850477c0f11e7f6ce OK 2026/10/05 05:28:38 DEBUG : A1/B1/C1/four: md5 = e6e41cfbd06b4df0e7d2e0c9480ace91 OK 2026/10/05 05:28:41 DEBUG : A1/B1/C2/five: md5 = e6ac5f08e1271fda2f512a5d4053a544 OK 2026/10/05 05:28:41 INFO : A1/B2: Making directory 2026/10/05 05:28:42 INFO : A1/B1/C3: Making directory 2026/10/05 05:28:53 INFO : A2/B1/three: Moved (server-side) to: A3/B1/three 2026/10/05 05:28:53 INFO : A2/B1/C2/five: Moved (server-side) to: A3/B1/C2/five 2026/10/05 05:28:53 INFO : A2/two: Moved (server-side) to: A3/two 2026/10/05 05:28:54 INFO : A2/one: Moved (server-side) to: A3/one 2026/10/05 05:28:54 INFO : A2/B1/C1/four: Moved (server-side) to: A3/B1/C1/four 2026/10/05 05:29:00 INFO : Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado': Can't DirMove - falling back to file moves: can't move directory - incompatible remotes 2026/10/05 05:29:07 INFO : A3/B1/C1/four: Moved (server-side) to: A4/B1/C1/four 2026/10/05 05:29:07 INFO : A3/B1/C2/five: Moved (server-side) to: A4/B1/C2/five 2026/10/05 05:29:08 INFO : A3/two: Moved (server-side) to: A4/two 2026/10/05 05:29:08 INFO : A3/one: Moved (server-side) to: A4/one 2026/10/05 05:29:08 INFO : A3/B1/three: Moved (server-side) to: A4/B1/three --- PASS: TestDirMove (55.94s) === RUN TestGetFsInfo run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" --- PASS: TestGetFsInfo (0.50s) === RUN TestRcat === RUN TestRcat/withChecksum=false,ignoreChecksum=false run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:29:25 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado': File to upload is small (34 bytes), uploading instead of streaming 2026/10/05 05:29:27 DEBUG : no_checksum_small_file_from_pipe: md5 = 11e8d1cba3bdc6279191b631f29d3968 OK 2026/10/05 05:29:27 DEBUG : no_checksum_small_file_from_pipe: size = 34 OK 2026/10/05 05:29:27 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2026/10/05 05:29:27 DEBUG : no_checksum_small_file_from_pipe: Size of src and dst objects identical 2026/10/05 05:29:28 DEBUG : qc9k4uauqa9ls6a2r6lo8jf4b1m7r0510pkurois1cc70egbs4h0: Sending chunk 0 length 102465 2026/10/05 05:29:29 DEBUG : no_checksum_big_file_from_pipe: md5 = 213785227d38249f293bc56d2e7e9893 OK 2026/10/05 05:29:29 DEBUG : no_checksum_big_file_from_pipe: size = 102401 OK 2026/10/05 05:29:29 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-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:29:31 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado': File to upload is small (34 bytes), uploading instead of streaming 2026/10/05 05:29:33 DEBUG : with_checksum_small_file_from_pipe: md5 = 73780683bbcc7feda9229b13194ace0f OK 2026/10/05 05:29:33 DEBUG : with_checksum_small_file_from_pipe: size = 34 OK 2026/10/05 05:29:33 DEBUG : with_checksum_small_file_from_pipe: Size of src and dst objects identical 2026/10/05 05:29:34 DEBUG : e74jo8a7lr1aimg2u78fct67gnuetc1qujiofaci0c8fnq7rjt1d4i296pcklqhgdihs03etvgdi4: Sending chunk 0 length 102465 2026/10/05 05:29:35 DEBUG : with_checksum_big_file_from_pipe: md5 = 758b29a862bfeb64933d3547ee196eb3 OK 2026/10/05 05:29:35 DEBUG : with_checksum_big_file_from_pipe: size = 102401 OK 2026/10/05 05:29:35 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-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:29:37 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado': File to upload is small (34 bytes), uploading instead of streaming 2026/10/05 05:29:39 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK 2026/10/05 05:29:39 DEBUG : ignore_checksum_small_file_from_pipe: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/10/05 05:29:40 DEBUG : 1963rn6olajfbjn4nbqi9nacu7bk879vpf0d2bg8r0ndcqqsfntob0urt056p0qujf574rqnb5f62: Sending chunk 0 length 102465 2026/10/05 05:29:41 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK 2026/10/05 05:29:41 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-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:29:43 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado': File to upload is small (34 bytes), uploading instead of streaming 2026/10/05 05:29:45 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK 2026/10/05 05:29:45 DEBUG : ignore_checksum_small_file_from_pipe: Size of src and dst objects identical 2026/10/05 05:29:46 DEBUG : 1963rn6olajfbjn4nbqi9nacu7bk879vpf0d2bg8r0ndcqqsfntob0urt056p0qujf574rqnb5f62: Sending chunk 0 length 102465 2026/10/05 05:29:47 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK 2026/10/05 05:29:47 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical --- PASS: TestRcat (24.30s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (6.29s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (6.03s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (6.08s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (5.89s) === RUN TestRcatMetadata run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" === RUN TestRcatMetadata/Normal 2026/10/05 05:29:49 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado': File to upload is small (48 bytes), uploading instead of streaming 2026/10/05 05:29:51 DEBUG : rcat_metadata: md5 = b32136add76831442d3d006225445b6c OK 2026/10/05 05:29:51 DEBUG : rcat_metadata: size = 48 OK 2026/10/05 05:29:51 DEBUG : rcat_metadata: Size of src and dst objects identical === RUN TestRcatMetadata/ViaDisk 2026/10/05 05:29:53 DEBUG : rijssoobhbn85c5p1imhg55m6frank1nlplla6hkdjlqmqb2471g: Sending chunk 0 length 111 2026/10/05 05:29:54 DEBUG : rcat_metadata_uploadcutoff0: md5 = b212eb5357fbc241c865309f81e8eb47 OK 2026/10/05 05:29:54 DEBUG : rcat_metadata_uploadcutoff0: size = 63 OK 2026/10/05 05:29:54 DEBUG : rcat_metadata_uploadcutoff0: Size of src and dst objects identical --- PASS: TestRcatMetadata (7.05s) --- PASS: TestRcatMetadata/Normal (3.24s) --- PASS: TestRcatMetadata/ViaDisk (3.33s) === RUN TestRcatSize run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:29:58 DEBUG : potato1: md5 = 723caf9bba930c7e373cd3e79975c0ca OK 2026/10/05 05:29:58 DEBUG : potato1: size = 60 OK 2026/10/05 05:29:58 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado': File to upload is small (60 bytes), uploading instead of streaming 2026/10/05 05:29:59 DEBUG : potato2: md5 = f7526152304ff47af9a10b20524e5f9b OK 2026/10/05 05:29:59 DEBUG : potato2: size = 60 OK 2026/10/05 05:29:59 DEBUG : potato2: Size of src and dst objects identical --- PASS: TestRcatSize (5.28s) === RUN TestRcatSizeShortEOF run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:30:03 DEBUG : potato1: md5 = 4b1621b594487cea81f565ac5f55db9c OK 2026/10/05 05:30:03 DEBUG : potato1: size = 120 (Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado') 2026/10/05 05:30:03 DEBUG : potato1: size = 60 (Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado') 2026/10/05 05:30:03 ERROR : potato1: corrupted on transfer: sizes differ src 120 vs dst(Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado') 60 2026/10/05 05:30:03 INFO : potato1: Removing failed copy --- PASS: TestRcatSizeShortEOF (2.94s) === RUN TestRcatSizeMetadata run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:30:06 DEBUG : potato1: md5 = 69ee826c009b652932aa7b9a81890f52 OK 2026/10/05 05:30:06 DEBUG : potato1: size = 60 OK 2026/10/05 05:30:06 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado': File to upload is small (60 bytes), uploading instead of streaming 2026/10/05 05:30:08 DEBUG : potato2: md5 = 80e70d3ae2c919be183ba5aa19935f0f OK 2026/10/05 05:30:08 DEBUG : potato2: size = 60 OK 2026/10/05 05:30:08 DEBUG : potato2: Size of src and dst objects identical --- PASS: TestRcatSizeMetadata (6.38s) === RUN TestRcatSizeUploadHeaders run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:30:12 DEBUG : potato1: md5 = 24292db0bd62a2322539c5aa56135140 OK 2026/10/05 05:30:12 DEBUG : potato1: size = 60 OK --- PASS: TestRcatSizeUploadHeaders (2.80s) === RUN TestRcatSizeChecksum === RUN TestRcatSizeChecksum/Corrupted run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" operations_test.go:1975: Skipping as destination doesn't support hashes === RUN TestRcatSizeChecksum/SizeDiffers run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:30:16 DEBUG : potato4: md5 = 1a577d77bcdabdcb329e969fbc9e1887 OK 2026/10/05 05:30:16 DEBUG : potato4: size = 60 (Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado') 2026/10/05 05:30:16 DEBUG : potato4: size = 59 (Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado') 2026/10/05 05:30:16 ERROR : potato4: corrupted on transfer: sizes differ src 60 vs dst(Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado') 59 2026/10/05 05:30:16 INFO : potato4: Removing failed copy === RUN TestRcatSizeChecksum/IgnoreChecksum run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:30:19 DEBUG : potato2: size = 60 OK === RUN TestRcatSizeChecksum/NoHashes run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:30:23 DEBUG : potato3: md5 = e27d407c4f02037c740d2f6df6a25b9d OK 2026/10/05 05:30:23 DEBUG : potato3: size = 60 OK --- PASS: TestRcatSizeChecksum (10.72s) --- SKIP: TestRcatSizeChecksum/Corrupted (0.50s) --- PASS: TestRcatSizeChecksum/SizeDiffers (3.22s) --- PASS: TestRcatSizeChecksum/IgnoreChecksum (2.89s) --- PASS: TestRcatSizeChecksum/NoHashes (4.12s) === RUN TestTouchDir run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:30:26 DEBUG : potato2: md5 = 5d31db7678d240574c961951594df429 OK 2026/10/05 05:30:28 DEBUG : empty space: md5 = aafd43a106f2dada338b217d7b7fe52f OK 2026/10/05 05:30:30 DEBUG : sub dir/potato3: md5 = 41620ee851961fb5843587e44433983a OK 2026/10/05 05:30:31 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado': Touching "empty space" 2026/10/05 05:30:31 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado': Touching "potato2" 2026/10/05 05:30:31 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado': Touching "sub dir/potato3" --- PASS: TestTouchDir (12.26s) === RUN TestMkdirMetadata run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:30:36 DEBUG : dir with metadata: Making directory with metadata 2026/10/05 05:30:37 INFO : dir with metadata: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) --- PASS: TestMkdirMetadata (3.75s) === RUN TestMkdirModTime run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:30:40 DEBUG : directory with modtime: Making directory with metadata 2026/10/05 05:30:41 INFO : directory with modtime: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/10/05 05:30:41 NOTICE: dry run directory with modtime: Skipped make directory as --dry-run is set --- PASS: TestMkdirModTime (3.16s) === RUN TestCopyDirMetadata run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:30:43 DEBUG : dir with metadata to be copied: Making directory with metadata 2026/10/05 05:30:43 INFO : dir with metadata to be copied: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2026/10/05 05:30:44 DEBUG : Google drive root 'crypt/nof1opn6ntbdfclfpa2iuae4bnqqkrnr94r6i17737dc3urifnu0': Skipping btime metadata as can't update it on an existing file: 2026-10-05T05:30:43.733225612Z 2026/10/05 05:30:44 INFO : non existent directory: Updated directory metadata 2026/10/05 05:30:47 DEBUG : Google drive root 'crypt/nof1opn6ntbdfclfpa2iuae4bnqqkrnr94r6i17737dc3urifnu0': Skipping btime metadata as can't update it on an existing file: 2026-10-05T05:30:43.733225612Z 2026/10/05 05:30:47 INFO : existing directory: Updated directory metadata --- PASS: TestCopyDirMetadata (6.22s) === RUN TestSetDirModTime run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:30:49 DEBUG : set modtime on non existent directory: Skipping set directory modification time as --no-update-dir-modtime is set 2026/10/05 05:30:52 INFO : set modtime on existing directory: Set directory modification time (using SetModTime) 2026/10/05 05:30:52 INFO : set modtime on existing directory: Set directory modification time (using DirSetModTime) --- PASS: TestSetDirModTime (4.97s) === RUN TestDirsEqual run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:30:54 DEBUG : dir with metadata to be copied: Making directory with metadata 2026/10/05 05:30:54 INFO : dir with metadata to be copied: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2026/10/05 05:30:54 DEBUG : dst: Making directory with metadata 2026/10/05 05:30:55 INFO : dst: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2026/10/05 05:30:55 DEBUG : dst: Directory modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/10/05 05:30:56 INFO : dst: Set directory modification time (using SetModTime) 2026/10/05 05:30:57 INFO : dst: Set directory modification time (using SetModTime) 2026/10/05 05:30:57 DEBUG : dst: Directory modification time the same (differ by 1ns, within tolerance 1ms) 2026/10/05 05:30:57 INFO : dst: Set directory modification time (using SetModTime) 2026/10/05 05:30:57 DEBUG : dst: Destination directory is newer than source, skipping --- PASS: TestDirsEqual (4.64s) === RUN TestRemoveExisting run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado'", Local "Local file system at /tmp/rclone3863668941", Modify Window "1ms" 2026/10/05 05:31:02 DEBUG : sub dir/test remove existing: md5 = 41a33c2617d6fd3689fab12d21b07511 OK 2026/10/05 05:31:03 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: md5 = e02940ad36802a6638b82d2be8096160 OK 2026/10/05 05:31:06 DEBUG : sub dir/test remove existing: TEST: renaming existing object to "sub dir/test remove existing.nijihuc5" before starting 2026/10/05 05:31:07 DEBUG : sub dir/test remove existing.nijihuc5: TEST: removing renamed existing file after operation 2026/10/05 05:31:08 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: TEST: renaming existing object to "sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.kufobub8" before starting 2026/10/05 05:31:09 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.kufobub8: TEST: renaming existing back after failed operation 2026/10/05 05:31:11 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: TEST: renaming existing object to "sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.wefuhar4" before starting 2026/10/05 05:31:12 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.wefuhar4: TEST: removing renamed existing file after operation --- PASS: TestRemoveExisting (15.43s) === RUN TestRcatInputFailurePreservesDestination 2026/10/05 05:31:14 DEBUG : Creating backend with remote "/tmp/TestRcatInputFailurePreservesDestination2859375098/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 TestRcList rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcList (0.00s) === RUN TestRcStat rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcStat (0.00s) === RUN TestRcSetTier rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcSetTier (0.00s) === RUN TestRcSetTierFile rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcSetTierFile (0.00s) === RUN TestRcMkdir rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcMkdir (0.00s) === RUN TestRcMovefile rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcMovefile (0.00s) === RUN TestRcPurge rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcPurge (0.00s) === RUN TestRcRmdir rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcRmdir (0.00s) === RUN TestRcRmdirs rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcRmdirs (0.00s) === RUN TestRcSize rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcSize (0.00s) === RUN TestRcPublicLink rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcPublicLink (0.00s) === RUN TestRcFsInfo rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcFsInfo (0.00s) === RUN TestUploadFile rc_test.go:31: Skipping test on non local remote --- SKIP: TestUploadFile (0.00s) === RUN TestRcCommand rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcCommand (0.00s) === RUN TestRcDu rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcDu (0.00s) === RUN TestRcCheck rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcCheck (0.00s) === RUN TestRcHashsum rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcHashsum (0.00s) === RUN TestRcHashsumSingleFile rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcHashsumSingleFile (0.00s) === RUN TestRcHashsumFile rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcHashsumFile (0.00s) === RUN TestRcGetFile rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcGetFile (0.00s) PASS 2026/10/05 05:31:14 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-mivobir3wado': Purge remote "./operations.test -test.v -test.timeout 1h0m0s -remote TestCryptDrive: -verbose" - Finished OK in 25m25.558167787s (try 1/5)