I tried remounting the drive with --vfs-cache-mode=full, no change, same error
Ah, good to know. So here is the (long) snippet from the log that seems relevant, starting from when I tried to save the file:
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL.lyx: Attr:
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL.lyx: >Attr: a=valid=1s ino=0 size=8109 mode=-rw-r--r--, err=<nil>
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL.lyx: Open: flags=OpenReadOnly
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL.lyx: Open: flags=O_RDONLY
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL.lyx: newRWFileHandle:
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL.lyx: >newRWFileHandle: err=<nil>
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL.lyx: >Open: fd=Merchants-of-Enchantment-FINAL.lyx (rw), err=<nil>
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL.lyx: >Open: fh=&{Merchants-of-Enchantment-FINAL.lyx (rw)}, err=<nil>
2025/11/30 17:10:35 DEBUG : &{Handle:Merchants-of-Enchantment-FINAL.lyx (rw)}: Read: len=8192, offset=0
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL.lyx(0xc000f12480): _readAt: size=8192, off=0
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL.lyx(0xc000f12480): openPending:
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL.lyx: vfs cache: checking remote fingerprint "8109,2025-11-30 17:27:42.998 +0000 UTC,8dc38a9b7d345c24e6d22c60dba35c86" against cached fingerprint "8109,2025-11-30 17:27:42.998 +0000 UTC,8dc38a9b7d345c24e6d22c60dba35c86"
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL.lyx: vfs cache: truncate to size=8109 (not needed as size correct)
2025/11/30 17:10:35 DEBUG : Added virtual directory entry vAddFile: "Merchants-of-Enchantment-FINAL.lyx"
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL.lyx(0xc000f12480): >openPending: err=<nil>
2025/11/30 17:10:35 DEBUG : vfs cache: looking for range={Pos:0 Size:8109} in [{Pos:0 Size:8109}] - present true
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL.lyx(0xc000f12480): >_readAt: n=8109, err=EOF
2025/11/30 17:10:35 DEBUG : &{Handle:Merchants-of-Enchantment-FINAL.lyx (rw)}: >Read: read=8109, err=<nil>
2025/11/30 17:10:35 DEBUG : &{Handle:Merchants-of-Enchantment-FINAL.lyx (rw)}: Flush:
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL.lyx(0xc000f12480): RWFileHandle.Flush
2025/11/30 17:10:35 DEBUG : &{Handle:Merchants-of-Enchantment-FINAL.lyx (rw)}: >Flush: err=<nil>
2025/11/30 17:10:35 DEBUG : &{Handle:Merchants-of-Enchantment-FINAL.lyx (rw)}: Release:
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL.lyx(0xc000f12480): RWFileHandle.Release
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL.lyx(0xc000f12480): close:
2025/11/30 17:10:35 DEBUG : /: Lookup: name="Merchants-of-Enchantment-FINAL-ueUuve.lyx"
2025/11/30 17:10:35 DEBUG : /: >Lookup: node=<nil>, err=no such file or directory
2025/11/30 17:10:35 DEBUG : /: Create: name="Merchants-of-Enchantment-FINAL-ueUuve.lyx"
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL.lyx: vfs cache: setting modification time to 2025-11-30 17:27:42.998 +0000 UTC
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL-ueUuve.lyx: Open: flags=O_RDWR|O_CREATE|O_EXCL
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL.lyx(0xc000f12480): >close: err=<nil>
2025/11/30 17:10:35 DEBUG : &{Handle:Merchants-of-Enchantment-FINAL.lyx (rw)}: >Release: err=<nil>
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL-ueUuve.lyx: newRWFileHandle:
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL-ueUuve.lyx(0xc000c5a500): openPending:
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL-ueUuve.lyx: vfs cache: truncate to size=0 (not needed as size correct)
2025/11/30 17:10:35 DEBUG : Added virtual directory entry vAddFile: "Merchants-of-Enchantment-FINAL-ueUuve.lyx"
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL-ueUuve.lyx(0xc000c5a500): >openPending: err=<nil>
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL-ueUuve.lyx: >newRWFileHandle: err=<nil>
2025/11/30 17:10:35 DEBUG : Added virtual directory entry vAddFile: "Merchants-of-Enchantment-FINAL-ueUuve.lyx"
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL-ueUuve.lyx: >Open: fd=Merchants-of-Enchantment-FINAL-ueUuve.lyx (rw), err=<nil>
2025/11/30 17:10:35 DEBUG : /: >Create: node=Merchants-of-Enchantment-FINAL-ueUuve.lyx, handle=&{Merchants-of-Enchantment-FINAL-ueUuve.lyx (rw)}, err=<nil>
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL-ueUuve.lyx: Attr:
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL-ueUuve.lyx: >Attr: a=valid=1s ino=0 size=0 mode=-rw-r--r--, err=<nil>
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL.lyx: Attr:
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL.lyx: >Attr: a=valid=1s ino=0 size=8109 mode=-rw-r--r--, err=<nil>
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL-ueUuve.lyx: Setattr: a=Setattr [ID=0x2302 Node=0x6 Uid=1001 Gid=10 Pid=296308] mode=-rwxr--r-- handle=INVALID-0x0
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL-ueUuve.lyx: >Setattr: err=<nil>
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL-ueUuve.lyx: Attr:
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL-ueUuve.lyx: >Attr: a=valid=1s ino=0 size=0 mode=-rw-r--r--, err=<nil>
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL-ueUuve.lyx: Open: flags=OpenWriteOnly
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL-ueUuve.lyx: Open: flags=O_WRONLY
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL-ueUuve.lyx: newRWFileHandle:
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL-ueUuve.lyx: >newRWFileHandle: err=<nil>
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL-ueUuve.lyx: >Open: fd=Merchants-of-Enchantment-FINAL-ueUuve.lyx (rw), err=<nil>
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL-ueUuve.lyx: >Open: fh=&{Merchants-of-Enchantment-FINAL-ueUuve.lyx (rw)}, err=<nil>
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL-ueUuve.lyx: Setattr: a=Setattr [ID=0x2306 Node=0x6 Uid=1001 Gid=10 Pid=296308] size=0 handle=INVALID-0x0 lockowner
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL-ueUuve.lyx: Truncating 2 file handles
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL-ueUuve.lyx(0xc000c5ab80): openPending:
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL-ueUuve.lyx: vfs cache: truncate to size=0 (not needed as size correct)
2025/11/30 17:10:35 DEBUG : Added virtual directory entry vAddFile: "Merchants-of-Enchantment-FINAL-ueUuve.lyx"
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL-ueUuve.lyx(0xc000c5ab80): >openPending: err=<nil>
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL-ueUuve.lyx: >Setattr: err=<nil>
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL-ueUuve.lyx: Attr:
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL-ueUuve.lyx: >Attr: a=valid=1s ino=0 size=0 mode=-rw-r--r--, err=<nil>
2025/11/30 17:10:35 DEBUG : &{Handle:Merchants-of-Enchantment-FINAL-ueUuve.lyx (rw)}: Write: len=4917, offset=0
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL-ueUuve.lyx(0xc000c5ab80): _writeAt: size=4917, off=0
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL-ueUuve.lyx(0xc000c5ab80): >_writeAt: n=4917, err=<nil>
a long list of almost identical write operations follow, then comes the sequence that leads to the permission error:
2025/11/30 17:10:35 DEBUG : &{Handle:Merchants-of-Enchantment-FINAL-ueUuve.lyx (rw)}: Flush:
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL-ueUuve.lyx(0xc000c5ab80): RWFileHandle.Flush
2025/11/30 17:10:35 DEBUG : &{Handle:Merchants-of-Enchantment-FINAL-ueUuve.lyx (rw)}: >Flush: err=<nil>
2025/11/30 17:10:35 DEBUG : &{Handle:Merchants-of-Enchantment-FINAL-ueUuve.lyx (rw)}: Release:
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL-ueUuve.lyx(0xc000c5ab80): RWFileHandle.Release
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL-ueUuve.lyx(0xc000c5ab80): close:
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL-ueUuve.lyx(0xc000c5ab80): >close: err=<nil>
2025/11/30 17:10:35 DEBUG : &{Handle:Merchants-of-Enchantment-FINAL-ueUuve.lyx (rw)}: >Release: err=<nil>
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL.lyx: Open: flags=OpenReadOnly
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL.lyx: Open: flags=O_RDONLY
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL.lyx: newRWFileHandle:
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL.lyx: >newRWFileHandle: err=<nil>
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL.lyx: >Open: fd=Merchants-of-Enchantment-FINAL.lyx (rw), err=<nil>
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL.lyx: >Open: fh=&{Merchants-of-Enchantment-FINAL.lyx (rw)}, err=<nil>
2025/11/30 17:10:35 DEBUG : &{Handle:Merchants-of-Enchantment-FINAL.lyx (rw)}: Read: len=8192, offset=0
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL.lyx(0xc000f12ac0): _readAt: size=8192, off=0
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL.lyx(0xc000f12ac0): openPending:
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL.lyx: vfs cache: checking remote fingerprint "8109,2025-11-30 17:27:42.998 +0000 UTC,8dc38a9b7d345c24e6d22c60dba35c86" against cached fingerprint "8109,2025-11-30 17:27:42.998 +0000 UTC,8dc38a9b7d345c24e6d22c60dba35c86"
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL.lyx: vfs cache: truncate to size=8109 (not needed as size correct)
2025/11/30 17:10:35 DEBUG : Added virtual directory entry vAddFile: "Merchants-of-Enchantment-FINAL.lyx"
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL.lyx(0xc000f12ac0): >openPending: err=<nil>
2025/11/30 17:10:35 DEBUG : vfs cache: looking for range={Pos:0 Size:8109} in [{Pos:0 Size:8109}] - present true
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL.lyx(0xc000f12ac0): >_readAt: n=8109, err=EOF
2025/11/30 17:10:35 DEBUG : &{Handle:Merchants-of-Enchantment-FINAL.lyx (rw)}: >Read: read=8109, err=<nil>
2025/11/30 17:10:35 DEBUG : &{Handle:Merchants-of-Enchantment-FINAL.lyx (rw)}: Flush:
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL.lyx(0xc000f12ac0): RWFileHandle.Flush
2025/11/30 17:10:35 DEBUG : &{Handle:Merchants-of-Enchantment-FINAL.lyx (rw)}: >Flush: err=<nil>
2025/11/30 17:10:35 DEBUG : &{Handle:Merchants-of-Enchantment-FINAL.lyx (rw)}: Release:
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL.lyx(0xc000f12ac0): RWFileHandle.Release
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL.lyx(0xc000f12ac0): close:
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL.lyx: vfs cache: setting modification time to 2025-11-30 17:27:42.998 +0000 UTC
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL.lyx(0xc000f12ac0): >close: err=<nil>
2025/11/30 17:10:35 DEBUG : &{Handle:Merchants-of-Enchantment-FINAL.lyx (rw)}: >Release: err=<nil>
2025/11/30 17:10:35 DEBUG : /: Lookup: name="Merchants-of-Enchantment-FINAL.lyx~"
2025/11/30 17:10:35 DEBUG : /: >Lookup: node=Merchants-of-Enchantment-FINAL.lyx~, err=<nil>
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL.lyx~: Attr:
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL.lyx~: >Attr: a=valid=1s ino=0 size=7286 mode=-rw-r--r--, err=<nil>
2025/11/30 17:10:35 DEBUG : /: Remove: name="Merchants-of-Enchantment-FINAL.lyx~"
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL.lyx~: Remove:
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL.lyx~: File.Remove file error: googleapi: Error 403: The user does not have sufficient permissions for this file., insufficientFilePermissions
2025/11/30 17:10:35 DEBUG : Merchants-of-Enchantment-FINAL.lyx~: >Remove: err=googleapi: Error 403: The user does not have sufficient permissions for this file., insufficientFilePermissions
2025/11/30 17:10:35 ERROR : IO error: googleapi: Error 403: The user does not have sufficient permissions for this file., insufficientFilePermissions
2025/11/30 17:10:35 DEBUG : /: >Remove: err=googleapi: Error 403: The user does not have sufficient permissions for this file., insufficientFilePermissions
And finally, here is the beginning of the log, showing the successful mount and its parameters:
2025/11/30 17:08:53 DEBUG : rclone: Version "v1.72.0" starting with parameters ["rclone" "mount" "--log-level=DEBUG" "--log-file=/home/stefano/temp/rclone.log" "--vfs-cache-mode=full" "gdrive:/Merchants-of-Enchantments-CURRENT" "--drive-shared-with-me" "/home/stefano/Documents/Manu/Merchants-of-Enchantments-from-Google-Drive/"]
2025/11/30 17:08:53 DEBUG : Creating backend with remote "gdrive:/Merchants-of-Enchantments-CURRENT"
2025/11/30 17:08:53 DEBUG : Using config file from "/home/stefano/.config/rclone/rclone.conf"
2025/11/30 17:08:53 DEBUG : gdrive: detected overridden config - adding "{ysOIc}" suffix to name
2025/11/30 17:08:53 DEBUG : Google drive root 'Merchants-of-Enchantments-CURRENT': 'root_folder_id = 0ALayq_ddRnS-Uk9PVA' - save this in the config to speed up startup
2025/11/30 17:08:53 DEBUG : fs cache: renaming cache item "gdrive:/Merchants-of-Enchantments-CURRENT" to be canonical "gdrive{ysOIc}:Merchants-of-Enchantments-CURRENT"
2025/11/30 17:08:53 DEBUG : Google drive root 'Merchants-of-Enchantments-CURRENT': vfs cache: root is "/home/stefano/.cache/rclone"
2025/11/30 17:08:53 DEBUG : Google drive root 'Merchants-of-Enchantments-CURRENT': vfs cache: data root is "/home/stefano/.cache/rclone/vfs/gdrive{ysOIc}/Merchants-of-Enchantments-CURRENT"
2025/11/30 17:08:53 DEBUG : Google drive root 'Merchants-of-Enchantments-CURRENT': vfs cache: metadata root is "/home/stefano/.cache/rclone/vfsMeta/gdrive{ysOIc}/Merchants-of-Enchantments-CURRENT"
2025/11/30 17:08:53 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/stefano/.cache/rclone/vfs/gdrive{ysOIc}/Merchants-of-Enchantments-CURRENT"
2025/11/30 17:08:53 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name
2025/11/30 17:08:53 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/stefano/.cache/rclone/vfs/gdrive{ysOIc}/Merchants-of-Enchantments-CURRENT" to be canonical ":local{8un-i}:/home/stefano/.cache/rclone/vfs/gdrive{ysOIc}/Merchants-of-Enchantments-CURRENT"
2025/11/30 17:08:53 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/stefano/.cache/rclone/vfsMeta/gdrive{ysOIc}/Merchants-of-Enchantments-CURRENT"
2025/11/30 17:08:53 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name
2025/11/30 17:08:53 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/stefano/.cache/rclone/vfsMeta/gdrive{ysOIc}/Merchants-of-Enchantments-CURRENT" to be canonical ":local{8un-i}:/home/stefano/.cache/rclone/vfsMeta/gdrive{ysOIc}/Merchants-of-Enchantments-CURRENT"
2025/11/30 17:08:53 INFO : Google drive root 'Merchants-of-Enchantments-CURRENT': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0)
2025/11/30 17:08:53 DEBUG : Google drive root 'Merchants-of-Enchantments-CURRENT': Mounting on "/home/stefano/Documents/Manu/Merchants-of-Enchantments-from-Google-Drive/"
2025/11/30 17:08:53 DEBUG : Root: