Local Disk - openPending Hangs

Help and Support
1

What is the problem you are having with rclone?

Significant hangs, seemingly for no reason

Run the command 'rclone version' and share the full output of the command.

rclone v1.72.1

  • os/version: rocky 9.7 (64 bit)
  • os/kernel: 5.14.0-611.47.1.el9_7.x86_64 (x86_64)
  • os/type: linux
  • os/arch: amd64
  • go/version: go1.25.3 (Red Hat 1.25.3-1.el9_7)
  • go/linking: dynamic
  • go/tags: none

Which cloud storage system are you using? (eg Google Drive)

Local Disk

The command you were trying to run (eg rclone copy /tmp remote:tmp)

Using dd to read the file at the beginning, and at the end.  rclone itself was mounted to a caching mount.

The rclone config contents with secrets removed.

rclone mount /mnt/ltfs /mnt/ltfs_cache --check-first --checkers 1 --size-only --vfs-cache-max-age 52w --dir-cache-time 180s --allow-other --vfs-cache-mode full --vfs-cache-min-free-space 10G --vfs-cache-poll-interval 180s --vfs-read-ahead 2097152 --buffer-size 1048576 --vfs-fast-fingerprint --vfs-read-chunk-streams 1 --vfs-refresh --no-checksum --no-modtime --no-update-modtime --transfers 1 --attr-timeout 5s --cache-dir /home/rclone --uid=1000 --gid=1000 --read-only --write-back-cache --multi-thread-streams 0 -vv

A log from the command with the -vv flag

2026/05/03 23:38:19 DEBUG : /: >Lookup: node=000002L5/, err=<nil>
2026/05/03 23:38:19 DEBUG : 000002L5/: Attr:
2026/05/03 23:38:19 DEBUG : 000002L5/: >Attr: attr=valid=5s ino=0 size=0 mode=drwxr-xr-x, err=<nil>
2026/05/03 23:38:19 DEBUG : 000002L5/: Lookup: name="Drone"
2026/05/03 23:38:19 DEBUG : 000002L5/: >Lookup: node=000002L5/Drone/, err=<nil>
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/: Attr:
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/: >Attr: attr=valid=5s ino=0 size=0 mode=drwxr-xr-x, err=<nil>
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/: Lookup: name="Vid1.mp4"
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/: >Lookup: node=000002L5/Drone/Vid1.mp4, err=<nil>
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/Vid1.mp4: Attr:
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/Vid1.mp4: >Attr: a=valid=5s ino=0 size=2258682829 mode=-rw-r--r--, err=<nil>
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/Vid1.mp4: Open: flags=OpenReadOnly
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/Vid1.mp4: Open: flags=O_RDONLY
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/Vid1.mp4: newRWFileHandle:
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/Vid1.mp4: >newRWFileHandle: err=<nil>
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/Vid1.mp4: >Open: fd=000002L5/Drone/Vid1.mp4 (rw), err=<nil>
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/Vid1.mp4: >Open: fh=&{000002L5/Drone/Vid1.mp4 (rw)}, err=<nil>
2026/05/03 23:38:19 DEBUG : &{Handle:000002L5/Drone/Vid1.mp4 (rw)}: Flush:
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/Vid1.mp4(0xc001285780): RWFileHandle.Flush
2026/05/03 23:38:19 DEBUG : &{Handle:000002L5/Drone/Vid1.mp4 (rw)}: >Flush: err=<nil>
2026/05/03 23:38:19 DEBUG : &{Handle:000002L5/Drone/Vid1.mp4 (rw)}: Read: len=131072, offset=0
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/Vid1.mp4(0xc001285780): _readAt: size=131072, off=0
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/Vid1.mp4(0xc001285780): openPending:
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/Vid1.mp4: vfs cache: checking remote fingerprint "2258682829,2020-12-30 05:38:28 +0000 UTC" against cached fingerprint "2258682829,2020-12-30 05:38:28 +0000 UTC"
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/Vid1.mp4: vfs cache: truncate to size=2258682829 (not needed as size correct)
2026/05/03 23:38:19 DEBUG : 000002L5/Drone: Added virtual directory entry vAddFile: "Vid1.mp4"
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/Vid1.mp4(0xc001285780): >openPending: err=<nil>
2026/05/03 23:38:19 DEBUG : vfs cache: looking for range={Pos:0 Size:131072} in [{Pos:0 Size:6287360} {Pos:2256535552 Size:2147277}] - present true
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/Vid1.mp4: ChunkedReader.RangeSeek from -1 to 6287360 length -1
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/Vid1.mp4(0xc001285780): >_readAt: n=131072, err=<nil>
2026/05/03 23:38:19 DEBUG : &{Handle:000002L5/Drone/Vid1.mp4 (rw)}: >Read: read=131072, err=<nil>
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/Vid1.mp4: ChunkedReader.Read at -1 length 4096 chunkOffset 6287360 chunkSize 134217728
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/Vid1.mp4: ChunkedReader.openRange at 6287360 length 134217728
2026/05/03 23:38:19 DEBUG : &{Handle:000002L5/Drone/Vid1.mp4 (rw)}: Read: len=131072, offset=131072
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/Vid1.mp4(0xc001285780): _readAt: size=131072, off=131072
2026/05/03 23:38:19 DEBUG : vfs cache: looking for range={Pos:131072 Size:131072} in [{Pos:0 Size:6287360} {Pos:2256535552 Size:2147277}] - present true
2026/05/03 23:38:19 DEBUG : &{Handle:000002L5/Drone/Vid1.mp4 (rw)}: Read: len=131072, offset=262144
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/Vid1.mp4(0xc001285780): _readAt: size=131072, off=262144
2026/05/03 23:38:19 DEBUG : vfs cache: looking for range={Pos:262144 Size:131072} in [{Pos:0 Size:6287360} {Pos:2256535552 Size:2147277}] - present true
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/Vid1.mp4(0xc001285780): >_readAt: n=131072, err=<nil>
2026/05/03 23:38:19 DEBUG : &{Handle:000002L5/Drone/Vid1.mp4 (rw)}: >Read: read=131072, err=<nil>
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/Vid1.mp4(0xc001285780): >_readAt: n=131072, err=<nil>
2026/05/03 23:38:19 DEBUG : &{Handle:000002L5/Drone/Vid1.mp4 (rw)}: >Read: read=131072, err=<nil>
2026/05/03 23:38:19 DEBUG : &{Handle:000002L5/Drone/Vid1.mp4 (rw)}: Read: len=131072, offset=393216
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/Vid1.mp4(0xc001285780): _readAt: size=131072, off=393216
2026/05/03 23:38:19 DEBUG : vfs cache: looking for range={Pos:393216 Size:131072} in [{Pos:0 Size:6287360} {Pos:2256535552 Size:2147277}] - present true
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/Vid1.mp4(0xc001285780): >_readAt: n=131072, err=<nil>
2026/05/03 23:38:19 DEBUG : &{Handle:000002L5/Drone/Vid1.mp4 (rw)}: >Read: read=131072, err=<nil>
2026/05/03 23:38:19 DEBUG : &{Handle:000002L5/Drone/Vid1.mp4 (rw)}: Read: len=131072, offset=524288
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/Vid1.mp4(0xc001285780): _readAt: size=131072, off=524288
2026/05/03 23:38:19 DEBUG : vfs cache: looking for range={Pos:524288 Size:131072} in [{Pos:0 Size:6287360} {Pos:2256535552 Size:2147277}] - present true
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/Vid1.mp4(0xc001285780): >_readAt: n=131072, err=<nil>
2026/05/03 23:38:19 DEBUG : &{Handle:000002L5/Drone/Vid1.mp4 (rw)}: >Read: read=131072, err=<nil>
2026/05/03 23:38:19 DEBUG : &{Handle:000002L5/Drone/Vid1.mp4 (rw)}: Read: len=131072, offset=655360
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/Vid1.mp4(0xc001285780): _readAt: size=131072, off=655360
2026/05/03 23:38:19 DEBUG : vfs cache: looking for range={Pos:655360 Size:131072} in [{Pos:0 Size:6287360} {Pos:2256535552 Size:2147277}] - present true
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/Vid1.mp4(0xc001285780): >_readAt: n=131072, err=<nil>
2026/05/03 23:38:19 DEBUG : &{Handle:000002L5/Drone/Vid1.mp4 (rw)}: Read: len=131072, offset=786432
2026/05/03 23:38:19 DEBUG : &{Handle:000002L5/Drone/Vid1.mp4 (rw)}: >Read: read=131072, err=<nil>
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/Vid1.mp4(0xc001285780): _readAt: size=131072, off=786432
2026/05/03 23:38:19 DEBUG : vfs cache: looking for range={Pos:786432 Size:131072} in [{Pos:0 Size:6287360} {Pos:2256535552 Size:2147277}] - present true
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/Vid1.mp4(0xc001285780): >_readAt: n=131072, err=<nil>
2026/05/03 23:38:19 DEBUG : &{Handle:000002L5/Drone/Vid1.mp4 (rw)}: >Read: read=131072, err=<nil>
2026/05/03 23:38:19 DEBUG : &{Handle:000002L5/Drone/Vid1.mp4 (rw)}: Read: len=131072, offset=917504
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/Vid1.mp4(0xc001285780): _readAt: size=131072, off=917504
2026/05/03 23:38:19 DEBUG : vfs cache: looking for range={Pos:917504 Size:131072} in [{Pos:0 Size:6287360} {Pos:2256535552 Size:2147277}] - present true
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/Vid1.mp4(0xc001285780): >_readAt: n=131072, err=<nil>
2026/05/03 23:38:19 DEBUG : &{Handle:000002L5/Drone/Vid1.mp4 (rw)}: Read: len=131072, offset=1048576
2026/05/03 23:38:19 DEBUG : &{Handle:000002L5/Drone/Vid1.mp4 (rw)}: >Read: read=131072, err=<nil>
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/Vid1.mp4(0xc001285780): _readAt: size=131072, off=1048576
2026/05/03 23:38:19 DEBUG : vfs cache: looking for range={Pos:1048576 Size:131072} in [{Pos:0 Size:6287360} {Pos:2256535552 Size:2147277}] - present true
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/Vid1.mp4(0xc001285780): >_readAt: n=131072, err=<nil>
2026/05/03 23:38:19 DEBUG : &{Handle:000002L5/Drone/Vid1.mp4 (rw)}: >Read: read=131072, err=<nil>
2026/05/03 23:38:19 DEBUG : &{Handle:000002L5/Drone/Vid1.mp4 (rw)}: Read: len=131072, offset=1310720
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/Vid1.mp4(0xc001285780): _readAt: size=131072, off=1310720
2026/05/03 23:38:19 DEBUG : vfs cache: looking for range={Pos:1310720 Size:131072} in [{Pos:0 Size:6287360} {Pos:2256535552 Size:2147277}] - present true
2026/05/03 23:38:19 DEBUG : &{Handle:000002L5/Drone/Vid1.mp4 (rw)}: Read: len=131072, offset=1179648
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/Vid1.mp4(0xc001285780): >_readAt: n=131072, err=<nil>
2026/05/03 23:38:19 DEBUG : &{Handle:000002L5/Drone/Vid1.mp4 (rw)}: >Read: read=131072, err=<nil>
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/Vid1.mp4(0xc001285780): _readAt: size=131072, off=1179648
2026/05/03 23:38:19 DEBUG : vfs cache: looking for range={Pos:1179648 Size:131072} in [{Pos:0 Size:6287360} {Pos:2256535552 Size:2147277}] - present true
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/Vid1.mp4(0xc001285780): >_readAt: n=131072, err=<nil>
2026/05/03 23:38:19 DEBUG : &{Handle:000002L5/Drone/Vid1.mp4 (rw)}: >Read: read=131072, err=<nil>
2026/05/03 23:38:19 DEBUG : &{Handle:000002L5/Drone/Vid1.mp4 (rw)}: Read: len=131072, offset=1441792
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/Vid1.mp4(0xc001285780): _readAt: size=131072, off=1441792
2026/05/03 23:38:19 DEBUG : vfs cache: looking for range={Pos:1441792 Size:131072} in [{Pos:0 Size:6287360} {Pos:2256535552 Size:2147277}] - present true
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/Vid1.mp4(0xc001285780): >_readAt: n=131072, err=<nil>
2026/05/03 23:38:19 DEBUG : &{Handle:000002L5/Drone/Vid1.mp4 (rw)}: >Read: read=131072, err=<nil>
2026/05/03 23:38:19 DEBUG : &{Handle:000002L5/Drone/Vid1.mp4 (rw)}: Read: len=131072, offset=1572864
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/Vid1.mp4(0xc001285780): _readAt: size=131072, off=1572864
2026/05/03 23:38:19 DEBUG : vfs cache: looking for range={Pos:1572864 Size:131072} in [{Pos:0 Size:6287360} {Pos:2256535552 Size:2147277}] - present true
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/Vid1.mp4(0xc001285780): >_readAt: n=131072, err=<nil>
2026/05/03 23:38:19 DEBUG : &{Handle:000002L5/Drone/Vid1.mp4 (rw)}: >Read: read=131072, err=<nil>
2026/05/03 23:38:19 DEBUG : &{Handle:000002L5/Drone/Vid1.mp4 (rw)}: Flush:
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/Vid1.mp4(0xc001285780): RWFileHandle.Flush
2026/05/03 23:38:19 DEBUG : &{Handle:000002L5/Drone/Vid1.mp4 (rw)}: >Flush: err=<nil>
2026/05/03 23:38:19 DEBUG : &{Handle:000002L5/Drone/Vid1.mp4 (rw)}: Release:
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/Vid1.mp4(0xc001285780): RWFileHandle.Release
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/Vid1.mp4(0xc001285780): close:
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/Vid1.mp4: Open: flags=OpenReadOnly
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/Vid1.mp4: Open: flags=O_RDONLY
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/Vid1.mp4: newRWFileHandle:
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/Vid1.mp4: >newRWFileHandle: err=<nil>
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/Vid1.mp4: >Open: fd=000002L5/Drone/Vid1.mp4 (rw), err=<nil>
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/Vid1.mp4: >Open: fh=&{000002L5/Drone/Vid1.mp4 (rw)}, err=<nil>
2026/05/03 23:38:19 DEBUG : &{Handle:000002L5/Drone/Vid1.mp4 (rw)}: Flush:
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/Vid1.mp4(0xc000db0380): RWFileHandle.Flush
2026/05/03 23:38:19 DEBUG : &{Handle:000002L5/Drone/Vid1.mp4 (rw)}: >Flush: err=<nil>
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/Vid1.mp4: Attr:
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/Vid1.mp4: >Attr: a=valid=5s ino=0 size=2258682829 mode=-rw-r--r--, err=<nil>
2026/05/03 23:38:19 DEBUG : &{Handle:000002L5/Drone/Vid1.mp4 (rw)}: Read: len=131072, offset=2256535552
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/Vid1.mp4(0xc000db0380): _readAt: size=131072, off=2256535552
2026/05/03 23:38:19 DEBUG : 000002L5/Drone/Vid1.mp4(0xc000db0380): openPending:
2026/05/03 23:38:49 DEBUG : 000002L5/Drone/Vid1.mp4: vfs cache: setting modification time to 2020-12-29 23:38:28 -0600 CST
2026/05/03 23:38:49 DEBUG : 000002L5/Drone/Vid1.mp4(0xc001285780): >close: err=<nil>
2026/05/03 23:38:49 DEBUG : &{Handle:000002L5/Drone/Vid1.mp4 (rw)}: >Release: err=<nil>
2026/05/03 23:38:49 DEBUG : 000002L5/Drone/Vid1.mp4: vfs cache: checking remote fingerprint "2258682829,2020-12-30 05:38:28 +0000 UTC" against cached fingerprint "2258682829,2020-12-30 05:38:28 +0000 UTC"
2026/05/03 23:38:49 DEBUG : 000002L5/Drone/Vid1.mp4: vfs cache: truncate to size=2258682829 (not needed as size correct)
2026/05/03 23:38:49 DEBUG : 000002L5/Drone: Added virtual directory entry vAddFile: "Vid1.mp4"
2026/05/03 23:38:49 DEBUG : 000002L5/Drone/Vid1.mp4(0xc000db0380): >openPending: err=<nil>
2026/05/03 23:38:49 DEBUG : vfs cache: looking for range={Pos:2256535552 Size:131072} in [{Pos:0 Size:6291456} {Pos:2256535552 Size:2147277}] - present true
2026/05/03 23:38:49 DEBUG : 000002L5/Drone/Vid1.mp4(0xc000db0380): >_readAt: n=131072, err=<nil>
2026/05/03 23:38:49 DEBUG : &{Handle:000002L5/Drone/Vid1.mp4 (rw)}: >Read: read=131072, err=<nil>
2026/05/03 23:38:49 DEBUG : &{Handle:000002L5/Drone/Vid1.mp4 (rw)}: Read: len=131072, offset=2256666624
2026/05/03 23:38:49 DEBUG : 000002L5/Drone/Vid1.mp4(0xc000db0380): _readAt: size=131072, off=2256666624
2026/05/03 23:38:49 DEBUG : vfs cache: looking for range={Pos:2256666624 Size:131072} in [{Pos:0 Size:6291456} {Pos:2256535552 Size:2147277}] - present true
2026/05/03 23:38:49 DEBUG : &{Handle:000002L5/Drone/Vid1.mp4 (rw)}: Read: len=131072, offset=2256797696
2026/05/03 23:38:49 DEBUG : 000002L5/Drone/Vid1.mp4(0xc000db0380): >_readAt: n=131072, err=<nil>
2026/05/03 23:38:49 DEBUG : &{Handle:000002L5/Drone/Vid1.mp4 (rw)}: >Read: read=131072, err=<nil>
2026/05/03 23:38:49 DEBUG : 000002L5/Drone/Vid1.mp4(0xc000db0380): _readAt: size=131072, off=2256797696
2026/05/03 23:38:49 DEBUG : vfs cache: looking for range={Pos:2256797696 Size:131072} in [{Pos:0 Size:6291456} {Pos:2256535552 Size:2147277}] - present true
2026/05/03 23:38:49 DEBUG : 000002L5/Drone/Vid1.mp4(0xc000db0380): >_readAt: n=131072, err=<nil>
2026/05/03 23:38:49 DEBUG : &{Handle:000002L5/Drone/Vid1.mp4 (rw)}: >Read: read=131072, err=<nil>
2026/05/03 23:38:49 DEBUG : &{Handle:000002L5/Drone/Vid1.mp4 (rw)}: Read: len=131072, offset=2256928768
2026/05/03 23:38:49 DEBUG : 000002L5/Drone/Vid1.mp4(0xc000db0380): _readAt: size=131072, off=2256928768
2026/05/03 23:38:49 DEBUG : vfs cache: looking for range={Pos:2256928768 Size:131072} in [{Pos:0 Size:6291456} {Pos:2256535552 Size:2147277}] - present true
2026/05/03 23:38:49 DEBUG : 000002L5/Drone/Vid1.mp4(0xc000db0380): >_readAt: n=131072, err=<nil>
2026/05/03 23:38:49 DEBUG : &{Handle:000002L5/Drone/Vid1.mp4 (rw)}: >Read: read=131072, err=<nil>
2026/05/03 23:38:49 DEBUG : &{Handle:000002L5/Drone/Vid1.mp4 (rw)}: Read: len=131072, offset=2257059840
2026/05/03 23:38:49 DEBUG : 000002L5/Drone/Vid1.mp4(0xc000db0380): _readAt: size=131072, off=2257059840
2026/05/03 23:38:49 DEBUG : vfs cache: looking for range={Pos:2257059840 Size:131072} in [{Pos:0 Size:6291456} {Pos:2256535552 Size:2147277}] - present true
2026/05/03 23:38:49 DEBUG : 000002L5/Drone/Vid1.mp4(0xc000db0380): >_readAt: n=131072, err=<nil>
2026/05/03 23:38:49 DEBUG : &{Handle:000002L5/Drone/Vid1.mp4 (rw)}: >Read: read=131072, err=<nil>
2026/05/03 23:38:49 DEBUG : &{Handle:000002L5/Drone/Vid1.mp4 (rw)}: Flush:
2026/05/03 23:38:49 DEBUG : 000002L5/Drone/Vid1.mp4(0xc000db0380): RWFileHandle.Flush
2026/05/03 23:38:49 DEBUG : &{Handle:000002L5/Drone/Vid1.mp4 (rw)}: >Flush: err=<nil>
2026/05/03 23:38:49 DEBUG : &{Handle:000002L5/Drone/Vid1.mp4 (rw)}: Release:
2026/05/03 23:38:49 DEBUG : 000002L5/Drone/Vid1.mp4(0xc000db0380): RWFileHandle.Release
2026/05/03 23:38:49 DEBUG : 000002L5/Drone/Vid1.mp4(0xc000db0380): close:
2

Please update you rclone to the latest one and try again.

3

welcome to the forum,

on a mount, those do nothing.

as mentioned, first run rclone selfupdate and test again

if the issue persists, then run a test using the simplest command possible

in most cases, the vfs file cache is not required.

so as a test, run this and see what happens

rclone mount /mnt/ltfs /mnt/ltfs_cache --allow-other --read-only -vv

check out my summary of the two vfs caches

4

Well, I don’t know what was changed, but doing rclone selfupdate to the new version made a HUGE difference!

5

A new interesting batch of fun, I added –debug-fuse:

rclone mount /mnt/ltfs /mnt/ltfs_cache --size-only --vfs-cache-max-age 52w --dir-cache-time 52w --allow-other --vfs-cache-mode full --vfs-cache-min-free-space 10G --vfs-cache-poll-interval 180s --vfs-read-ahead 2097152 --buffer-size 1048576 --vfs-fast-fingerprint --vfs-read-chunk-streams 1 --vfs-refresh --no-checksum --no-modtime --no-update-modtime --transfers 1 --attr-timeout 5s --cache-dir /home/rclone --uid=1000 --gid=1000 --read-only --multi-thread-streams 0 --debug-fuse -vv

AND I see errors that something isn’t closing files correctly - which makes it difficult/impossible to unmount. I think it does eventually happen, but it takes quite a while.

2026/05/04 22:14:39 ERROR : file.mp4: vfs cache: failed to open item: vfs cache item: create cache file failed: vfs cache item: internal error: didn't Close file
2026/05/04 22:14:39 ERROR : file.mp4: Non-out-of-space error encountered during open
2026/05/04 22:14:39 DEBUG : file.mp4(0x26c39aca6180): >openPending: err=open RW handle failed to open cache file: vfs cache item: create cache file failed: vfs cache item: internal error: didn't Close file
2026/05/04 22:14:39 DEBUG : file.mp4(0x26c39aca6180): >_readAt: n=0, err=open RW handle failed to open cache file: vfs cache item: create cache file failed: vfs cache item: internal error: didn't Close file
2026/05/04 22:14:39 ERROR : IO error: open RW handle failed to open cache file: vfs cache item: create cache file failed: vfs cache item: internal error: didn't Close file
2026/05/04 22:14:39 DEBUG : &{Handle:file.mp4(rw)}: >Read: read=0, err=open RW handle failed to open cache file: vfs cache item: create cache file failed: vfs cache item: internal error: didn't Close file
2026/05/04 22:14:39 DEBUG : fuse: -> [ID=0x802] Read error=EIO: open RW handle failed to open cache file: vfs cache item: create cache file failed: vfs cache item: internal error: didn't Close file
2026/05/04 22:14:39 DEBUG : fuse: <- Read [ID=0x804 Node=0x17 Uid=983 Gid=1001 Pid=6402] 0x14 4096 @0x0 dir=false fl=0 owner=0 ffl=OpenReadOnly

1 Like