Possible Bug with rclone rc vfs/refresh command

Suspected Bug
1

What is the problem you are having with rclone?

Before updating to rclone 1.68 my assemblies worked perfectly now it gives an error and when investigating I found the cause which in my case is precisely in the command ExecStartPost=/usr/bin/rclone rc vfs/refresh where it gives error 503.

root@nextcloud:~# /usr/bin/rclone rc vfs/refresh recursive=true --rc-addr 127.0.0.1:55555 _async=true
{
	"error": "connection failed: Post \"http://[\\\"127.0.0.1:55555\\\"]/vfs/refresh\": dial tcp: lookup \"127.0.0.1:55555\": no such host",
	"path": "vfs/refresh",
	"status": 503
}
2024/09/11 02:50:31 NOTICE: Failed to rc: connection failed: Post "http://[\"127.0.0.1:55555\"]/vfs/refresh": dial tcp: lookup "127.0.0.1:55555": no such host

Now if I remove the lines and command referring to rc from the systemd file it mounts normally and the same happens with the original file without changes only changing the version of the previous rclone 1.67

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

rclone v1.68.0

  • os/version: ubuntu 24.04 (64 bit)
  • os/kernel: 6.8.0-44-generic (x86_64)
  • os/type: linux
  • os/arch: amd64
  • go/version: go1.23.1
  • go/linking: static
  • go/tags: non

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

Google Drive

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

ExecStart=/usr/bin/rclone mount GoogleDrive:Backup /mnt/Rclone/Backup \
--allow-other \
--dir-cache-time 9999h \
--log-level DEBUG \
--log-file /var/log/Rclone/Borg.log \
--poll-interval 15s \
--user-agent "ISV|rclone.org|rclone/v1.68.0" \
--rc \
--rc-addr=:5575 \
--rc-no-auth \
--cache-dir=/root/.cache/rclone \
--drive-use-trash \
--vfs-cache-mode full \
--vfs-cache-max-size 100G \
--vfs-read-ahead 256M \
--vfs-cache-max-age 1h \
--vfs-cache-poll-interval 5m  
ExecStartPost=/usr/bin/rclone rc vfs/refresh recursive=true --rc-addr 127.0.0.1:5572 _async=true

The rclone config contents with secrets removed.

[GoogleDrive]
type = drive
client_id = XXX
client_secret = XXX
scope = drive
token = XXX
team_drive = 
root_folder_id = 

[Local]
type = local

[M365]
type = union
upstreams = M365-edson: M365-edson_s.barbosa::ro M365-edson_silvasp::ro M365-edsons.barbosa::ro M365-edsons.barbosaj::ro M365-edsons.barbosasp::ro M365-edsonsbj.sp::ro M365-flores.rosas629: M365-nem.ssantos: M365-nems.santos: M365-nextcloud:

[M365-Filmes]
type = crypt
remote = 36w7l3:Apps2
password = XXX
password2 = XXX
server_side_across_configs = true
filename_encoding = base32768

[M365-Series]
type = crypt
remote = M365:Apps
password = XXX
password2 = XXX
server_side_across_configs = true
filename_encoding = base32768

[M365-Series_v2]
type = crypt
remote = M365:Apps
password = XXX
password2 = XXX
server_side_across_configs = true
filename_encoding = base32768

[M365-edson]
type = onedrive
token = XXX
drive_id = XXX
drive_type = personal
client_id = XXX
client_secret = XXX
server_side_across_configs = true

[M365-edson_s.barbosa]
type = onedrive
token = XXX
drive_id = XXX
drive_type = personal
client_id = XXX
client_secret = XXX

[M365-edson_silvasp]
type = onedrive
token = XXX
drive_id = XXX
drive_type = personal
client_id = XXX
client_secret = XXX

[M365-edsons.barbosa]
type = onedrive
token = XXX
drive_id = XXX
drive_type = personal
client_id = XXX
client_secret = XXX

[M365-edsons.barbosaj]
type = onedrive
token = XXX
drive_id = XXX
drive_type = personal
client_id = XXX
client_secret = XXX

[M365-edsons.barbosasp]
type = onedrive
token = XXX
drive_id = XXX
drive_type = personal
client_id = XXX
client_secret = XXX

[M365-edsonsbj.sp]
type = onedrive
token = XXX
drive_id = XXX
drive_type = personal
client_id = XXX
client_secret = XXX
server_side_across_configs = true

[M365-flores.rosas629]
type = onedrive
token = XXX
drive_id = XXX
drive_type = personal
client_id = XXX
client_secret = XXX
server_side_across_configs = true

[M365-nem.ssantos]
type = onedrive
token = XXX
drive_id = XXX
drive_type = personal
client_id = XXX
client_secret = XXX
server_side_across_configs = true

[M365-nems.santos]
type = onedrive
token = XXX
drive_id = XXX
drive_type = personal
client_id = XXX
client_secret = XXX
server_side_across_configs = true

[M365-nextcloud]
type = onedrive
token = XXX
drive_id = XXX
drive_type = personal
client_id = XXX
client_secret = XXX
link_type = edit

A log from the command with the -vv flag

2024/09/11 02:45:04 DEBUG : rclone: Version "v1.68.0" starting with parameters ["/usr/bin/rclone" "mount" "GoogleDrive:Backup" "/mnt/Rclone/Backup" "--allow-other" "--dir-cache-time" "9999h" "--log-level" "DEBUG" "--log-file" "/var/log/Rclone/Backup.log" "--poll-interval" "15s" "--user-agent" "ISV|rclone.org|rclone/v1.68.0" "--rc" "--rc-addr=:55555" "--rc-no-auth" "--cache-dir=/root/.cache/rclone" "--drive-use-trash" "--vfs-cache-mode" "full" "--vfs-cache-max-size" "100G" "--vfs-read-ahead" "256M" "--vfs-cache-max-age" "1h" "--vfs-cache-poll-interval" "5m"]
2024/09/11 02:45:04 NOTICE: Serving remote control on http://[::]:55555/
2024/09/11 02:45:04 DEBUG : Creating backend with remote "GoogleDrive:Backup"
2024/09/11 02:45:05 DEBUG : Using config file from "/root/.config/rclone/rclone.conf"
2024/09/11 02:45:06 DEBUG : Google drive root 'Backup': 'root_folder_id = 0ABI-UibreOSKUk9PVA' - save this in the config to speed up startup
2024/09/11 02:45:06 DEBUG : vfs cache: root is "/root/.cache/rclone"
2024/09/11 02:45:06 DEBUG : vfs cache: data root is "/root/.cache/rclone/vfs/GoogleDrive/Backup"
2024/09/11 02:45:06 DEBUG : vfs cache: metadata root is "/root/.cache/rclone/vfsMeta/GoogleDrive/Backup"
2024/09/11 02:45:06 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot':/root/.cache/rclone/vfs/GoogleDrive/Backup"
2024/09/11 02:45:06 DEBUG : :local: detected overridden config - adding "{bxYPm}" suffix to name
2024/09/11 02:45:06 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot':/root/.cache/rclone/vfs/GoogleDrive/Backup" to be canonical ":local{bxYPm}:/root/.cache/rclone/vfs/GoogleDrive/Backup"
2024/09/11 02:45:06 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot':/root/.cache/rclone/vfsMeta/GoogleDrive/Backup"
2024/09/11 02:45:06 DEBUG : :local: detected overridden config - adding "{bxYPm}" suffix to name
2024/09/11 02:45:06 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot':/root/.cache/rclone/vfsMeta/GoogleDrive/Backup" to be canonical ":local{bxYPm}:/root/.cache/rclone/vfsMeta/GoogleDrive/Backup"
2024/09/11 02:45:06 INFO  : vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0)
2024/09/11 02:45:06 DEBUG : Google drive root 'Backup': Mounting on "/mnt/Rclone/Backup"
2024/09/11 02:45:06 DEBUG : : Root: 
2024/09/11 02:45:06 DEBUG : : >Root: node=/, err=<nil>
2024/09/11 02:45:07 INFO  : Signal received: terminated
2024/09/11 02:45:07 DEBUG : vfs cache: cleaner exiting
2024/09/11 02:45:07 ERROR : /mnt/Rclone/Backup: Unmounted rclone mount
2024/09/11 02:45:07 DEBUG : rclone: Version "v1.68.0" finishing with parameters ["/usr/bin/rclone" "mount" "GoogleDrive:Backup" "/mnt/Rclone/Backup" "--allow-other" "--dir-cache-time" "9999h" "--log-level" "DEBUG" "--log-file" "/var/log/Rclone/Backup.log" "--poll-interval" "15s" "--user-agent" "ISV|rclone.org|rclone/v1.68.0" "--rc" "--rc-addr=:55555" "--rc-no-auth" "--cache-dir=/root/.cache/rclone" "--drive-use-trash" "--vfs-cache-mode" "full" "--vfs-cache-max-size" "100G" "--vfs-read-ahead" "256M" "--vfs-cache-max-age" "1h" "--vfs-cache-poll-interval" "5m"]
2024/09/11 02:45:07 INFO  : Exiting...
2024/09/11 02:45:12 DEBUG : rclone: Version "v1.68.0" starting with parameters ["/usr/bin/rclone" "mount" "GoogleDrive:Backup" "/mnt/Rclone/Backup" "--allow-other" "--dir-cache-time" "9999h" "--log-level" "DEBUG" "--log-file" "/var/log/Rclone/Backup.log" "--poll-interval" "15s" "--user-agent" "ISV|rclone.org|rclone/v1.68.0" "--rc" "--rc-addr=:55555" "--rc-no-auth" "--cache-dir=/root/.cache/rclone" "--drive-use-trash" "--vfs-cache-mode" "full" "--vfs-cache-max-size" "100G" "--vfs-read-ahead" "256M" "--vfs-cache-max-age" "1h" "--vfs-cache-poll-interval" "5m"]
2024/09/11 02:45:12 NOTICE: Serving remote control on http://[::]:55555/
2024/09/11 02:45:12 DEBUG : Creating backend with remote "GoogleDrive:Backup"
2024/09/11 02:45:13 DEBUG : Using config file from "/root/.config/rclone/rclone.conf"
2024/09/11 02:45:14 DEBUG : Google drive root 'Backup': 'root_folder_id = 0ABI-UibreOSKUk9PVA' - save this in the config to speed up startup
2024/09/11 02:45:14 DEBUG : vfs cache: root is "/root/.cache/rclone"
2024/09/11 02:45:14 DEBUG : vfs cache: data root is "/root/.cache/rclone/vfs/GoogleDrive/Backup"
2024/09/11 02:45:14 DEBUG : vfs cache: metadata root is "/root/.cache/rclone/vfsMeta/GoogleDrive/Backup"
2024/09/11 02:45:14 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot':/root/.cache/rclone/vfs/GoogleDrive/Backup"
2024/09/11 02:45:14 DEBUG : :local: detected overridden config - adding "{bxYPm}" suffix to name
2024/09/11 02:45:14 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot':/root/.cache/rclone/vfs/GoogleDrive/Backup" to be canonical ":local{bxYPm}:/root/.cache/rclone/vfs/GoogleDrive/Backup"
2024/09/11 02:45:14 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot':/root/.cache/rclone/vfsMeta/GoogleDrive/Backup"
2024/09/11 02:45:14 DEBUG : :local: detected overridden config - adding "{bxYPm}" suffix to name
2024/09/11 02:45:14 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot':/root/.cache/rclone/vfsMeta/GoogleDrive/Backup" to be canonical ":local{bxYPm}:/root/.cache/rclone/vfsMeta/GoogleDrive/Backup"
2024/09/11 02:45:14 INFO  : vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0)
2024/09/11 02:45:14 DEBUG : Google drive root 'Backup': Mounting on "/mnt/Rclone/Backup"
2024/09/11 02:45:14 DEBUG : : Root: 
2024/09/11 02:45:14 DEBUG : : >Root: node=/, err=<nil>
2024/09/11 02:45:14 INFO  : Signal received: terminated
2024/09/11 02:45:14 DEBUG : vfs cache: cleaner exiting
2024/09/11 02:45:14 ERROR : /mnt/Rclone/Backup: Unmounted rclone mount
2024/09/11 02:45:14 DEBUG : rclone: Version "v1.68.0" finishing with parameters ["/usr/bin/rclone" "mount" "GoogleDrive:Backup" "/mnt/Rclone/Backup" "--allow-other" "--dir-cache-time" "9999h" "--log-level" "DEBUG" "--log-file" "/var/log/Rclone/Backup.log" "--poll-interval" "15s" "--user-agent" "ISV|rclone.org|rclone/v1.68.0" "--rc" "--rc-addr=:55555" "--rc-no-auth" "--cache-dir=/root/.cache/rclone" "--drive-use-trash" "--vfs-cache-mode" "full" "--vfs-cache-max-size" "100G" "--vfs-read-ahead" "256M" "--vfs-cache-max-age" "1h" "--vfs-cache-poll-interval" "5m"]
2024/09/11 02:45:14 INFO  : Exiting...
2024/09/11 02:45:19 DEBUG : rclone: Version "v1.68.0" starting with parameters ["/usr/bin/rclone" "mount" "GoogleDrive:Backup" "/mnt/Rclone/Backup" "--allow-other" "--dir-cache-time" "9999h" "--log-level" "DEBUG" "--log-file" "/var/log/Rclone/Backup.log" "--poll-interval" "15s" "--user-agent" "ISV|rclone.org|rclone/v1.68.0" "--rc" "--rc-addr=:55555" "--rc-no-auth" "--cache-dir=/root/.cache/rclone" "--drive-use-trash" "--vfs-cache-mode" "full" "--vfs-cache-max-size" "100G" "--vfs-read-ahead" "256M" "--vfs-cache-max-age" "1h" "--vfs-cache-poll-interval" "5m"]
2024/09/11 02:45:19 NOTICE: Serving remote control on http://[::]:55555/
2024/09/11 02:45:19 DEBUG : Creating backend with remote "GoogleDrive:Backup"
2024/09/11 02:45:21 DEBUG : Using config file from "/root/.config/rclone/rclone.conf"
2024/09/11 02:45:21 DEBUG : Google drive root 'Backup': 'root_folder_id = 0ABI-UibreOSKUk9PVA' - save this in the config to speed up startup
2024/09/11 02:45:22 DEBUG : vfs cache: root is "/root/.cache/rclone"
2024/09/11 02:45:22 DEBUG : vfs cache: data root is "/root/.cache/rclone/vfs/GoogleDrive/Backup"
2024/09/11 02:45:22 DEBUG : vfs cache: metadata root is "/root/.cache/rclone/vfsMeta/GoogleDrive/Backup"
2024/09/11 02:45:22 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot':/root/.cache/rclone/vfs/GoogleDrive/Backup"
2024/09/11 02:45:22 DEBUG : :local: detected overridden config - adding "{bxYPm}" suffix to name
2024/09/11 02:45:22 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot':/root/.cache/rclone/vfs/GoogleDrive/Backup" to be canonical ":local{bxYPm}:/root/.cache/rclone/vfs/GoogleDrive/Backup"
2024/09/11 02:45:22 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot':/root/.cache/rclone/vfsMeta/GoogleDrive/Backup"
2024/09/11 02:45:22 DEBUG : :local: detected overridden config - adding "{bxYPm}" suffix to name
2024/09/11 02:45:22 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot':/root/.cache/rclone/vfsMeta/GoogleDrive/Backup" to be canonical ":local{bxYPm}:/root/.cache/rclone/vfsMeta/GoogleDrive/Backup"
2024/09/11 02:45:22 INFO  : vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0)
2024/09/11 02:45:22 DEBUG : Google drive root 'Backup': Mounting on "/mnt/Rclone/Backup"
2024/09/11 02:45:22 DEBUG : : Root: 
2024/09/11 02:45:22 DEBUG : : >Root: node=/, err=<nil>
2024/09/11 02:45:22 INFO  : Signal received: terminated
2024/09/11 02:45:22 DEBUG : vfs cache: cleaner exiting
2024/09/11 02:45:22 ERROR : /mnt/Rclone/Backup: Unmounted rclone mount
2024/09/11 02:45:22 INFO  : Exiting...
2024/09/11 02:45:27 DEBUG : rclone: Version "v1.68.0" starting with parameters ["/usr/bin/rclone" "mount" "GoogleDrive:Backup" "/mnt/Rclone/Backup" "--allow-other" "--dir-cache-time" "9999h" "--log-level" "DEBUG" "--log-file" "/var/log/Rclone/Backup.log" "--poll-interval" "15s" "--user-agent" "ISV|rclone.org|rclone/v1.68.0" "--rc" "--rc-addr=:55555" "--rc-no-auth" "--cache-dir=/root/.cache/rclone" "--drive-use-trash" "--vfs-cache-mode" "full" "--vfs-cache-max-size" "100G" "--vfs-read-ahead" "256M" "--vfs-cache-max-age" "1h" "--vfs-cache-poll-interval" "5m"]
2024/09/11 02:45:27 NOTICE: Serving remote control on http://[::]:55555/
2024/09/11 02:45:27 DEBUG : Creating backend with remote "GoogleDrive:Backup"
2024/09/11 02:45:28 DEBUG : Using config file from "/root/.config/rclone/rclone.conf"
2024/09/11 02:45:28 DEBUG : Google drive root 'Backup': 'root_folder_id = 0ABI-UibreOSKUk9PVA' - save this in the config to speed up startup
2024/09/11 02:45:29 DEBUG : vfs cache: root is "/root/.cache/rclone"
2024/09/11 02:45:29 DEBUG : vfs cache: data root is "/root/.cache/rclone/vfs/GoogleDrive/Backup"
2024/09/11 02:45:29 DEBUG : vfs cache: metadata root is "/root/.cache/rclone/vfsMeta/GoogleDrive/Backup"
2024/09/11 02:45:29 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot':/root/.cache/rclone/vfs/GoogleDrive/Backup"
2024/09/11 02:45:29 DEBUG : :local: detected overridden config - adding "{bxYPm}" suffix to name
2024/09/11 02:45:29 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot':/root/.cache/rclone/vfs/GoogleDrive/Backup" to be canonical ":local{bxYPm}:/root/.cache/rclone/vfs/GoogleDrive/Backup"
2024/09/11 02:45:29 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot':/root/.cache/rclone/vfsMeta/GoogleDrive/Backup"
2024/09/11 02:45:29 DEBUG : :local: detected overridden config - adding "{bxYPm}" suffix to name
2024/09/11 02:45:29 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot':/root/.cache/rclone/vfsMeta/GoogleDrive/Backup" to be canonical ":local{bxYPm}:/root/.cache/rclone/vfsMeta/GoogleDrive/Backup"
2024/09/11 02:45:29 INFO  : vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0)
2024/09/11 02:45:29 DEBUG : Google drive root 'Backup': Mounting on "/mnt/Rclone/Backup"
2024/09/11 02:45:29 DEBUG : : Root: 
2024/09/11 02:45:29 DEBUG : : >Root: node=/, err=<nil>
2024/09/11 02:45:29 INFO  : Signal received: terminated
2024/09/11 02:45:29 DEBUG : vfs cache: cleaner exiting
2024/09/11 02:45:29 ERROR : /mnt/Rclone/Backup: Unmounted rclone mount
2024/09/11 02:45:29 INFO  : Exiting...
2024/09/11 02:45:34 DEBUG : rclone: Version "v1.68.0" starting with parameters ["/usr/bin/rclone" "mount" "GoogleDrive:Backup" "/mnt/Rclone/Backup" "--allow-other" "--dir-cache-time" "9999h" "--log-level" "DEBUG" "--log-file" "/var/log/Rclone/Backup.log" "--poll-interval" "15s" "--user-agent" "ISV|rclone.org|rclone/v1.68.0" "--rc" "--rc-addr=:55555" "--rc-no-auth" "--cache-dir=/root/.cache/rclone" "--drive-use-trash" "--vfs-cache-mode" "full" "--vfs-cache-max-size" "100G" "--vfs-read-ahead" "256M" "--vfs-cache-max-age" "1h" "--vfs-cache-poll-interval" "5m"]
2024/09/11 02:45:34 NOTICE: Serving remote control on http://[::]:55555/
2024/09/11 02:45:34 DEBUG : Creating backend with remote "GoogleDrive:Backup"
2024/09/11 02:45:36 DEBUG : Using config file from "/root/.config/rclone/rclone.conf"
2024/09/11 02:45:37 DEBUG : Google drive root 'Backup': 'root_folder_id = 0ABI-UibreOSKUk9PVA' - save this in the config to speed up startup
2024/09/11 02:45:37 DEBUG : vfs cache: root is "/root/.cache/rclone"
2024/09/11 02:45:37 DEBUG : vfs cache: data root is "/root/.cache/rclone/vfs/GoogleDrive/Backup"
2024/09/11 02:45:37 DEBUG : vfs cache: metadata root is "/root/.cache/rclone/vfsMeta/GoogleDrive/Backup"
2024/09/11 02:45:37 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot':/root/.cache/rclone/vfs/GoogleDrive/Backup"
2024/09/11 02:45:37 DEBUG : :local: detected overridden config - adding "{bxYPm}" suffix to name
2024/09/11 02:45:37 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot':/root/.cache/rclone/vfs/GoogleDrive/Backup" to be canonical ":local{bxYPm}:/root/.cache/rclone/vfs/GoogleDrive/Backup"
2024/09/11 02:45:37 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot':/root/.cache/rclone/vfsMeta/GoogleDrive/Backup"
2024/09/11 02:45:37 DEBUG : :local: detected overridden config - adding "{bxYPm}" suffix to name
2024/09/11 02:45:37 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot':/root/.cache/rclone/vfsMeta/GoogleDrive/Backup" to be canonical ":local{bxYPm}:/root/.cache/rclone/vfsMeta/GoogleDrive/Backup"
2024/09/11 02:45:37 INFO  : vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0)
2024/09/11 02:45:37 DEBUG : Google drive root 'Backup': Mounting on "/mnt/Rclone/Backup"
2024/09/11 02:45:37 DEBUG : : Root: 
2024/09/11 02:45:37 DEBUG : : >Root: node=/, err=<nil>
2024/09/11 02:45:37 INFO  : Signal received: terminated
2024/09/11 02:45:37 DEBUG : vfs cache: cleaner exiting
2024/09/11 02:45:37 ERROR : /mnt/Rclone/Backup: Unmounted rclone mount
2024/09/11 02:45:37 INFO  : Exiting...
2024/09/11 02:45:42 DEBUG : rclone: Version "v1.68.0" starting with parameters ["/usr/bin/rclone" "mount" "GoogleDrive:Backup" "/mnt/Rclone/Backup" "--allow-other" "--dir-cache-time" "9999h" "--log-level" "DEBUG" "--log-file" "/var/log/Rclone/Backup.log" "--poll-interval" "15s" "--user-agent" "ISV|rclone.org|rclone/v1.68.0" "--rc" "--rc-addr=:55555" "--rc-no-auth" "--cache-dir=/root/.cache/rclone" "--drive-use-trash" "--vfs-cache-mode" "full" "--vfs-cache-max-size" "100G" "--vfs-read-ahead" "256M" "--vfs-cache-max-age" "1h" "--vfs-cache-poll-interval" "5m"]
2024/09/11 02:45:42 NOTICE: Serving remote control on http://[::]:55555/
2024/09/11 02:45:42 DEBUG : Creating backend with remote "GoogleDrive:Backup"
2024/09/11 02:45:44 DEBUG : Using config file from "/root/.config/rclone/rclone.conf"
2024/09/11 02:45:44 DEBUG : Google drive root 'Backup': 'root_folder_id = 0ABI-UibreOSKUk9PVA' - save this in the config to speed up startup
2024/09/11 02:45:45 DEBUG : vfs cache: root is "/root/.cache/rclone"
2024/09/11 02:45:45 DEBUG : vfs cache: data root is "/root/.cache/rclone/vfs/GoogleDrive/Backup"
2024/09/11 02:45:45 DEBUG : vfs cache: metadata root is "/root/.cache/rclone/vfsMeta/GoogleDrive/Backup"
2024/09/11 02:45:45 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot':/root/.cache/rclone/vfs/GoogleDrive/Backup"
2024/09/11 02:45:45 DEBUG : :local: detected overridden config - adding "{bxYPm}" suffix to name
2024/09/11 02:45:45 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot':/root/.cache/rclone/vfs/GoogleDrive/Backup" to be canonical ":local{bxYPm}:/root/.cache/rclone/vfs/GoogleDrive/Backup"
2024/09/11 02:45:45 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot':/root/.cache/rclone/vfsMeta/GoogleDrive/Backup"
2024/09/11 02:45:45 DEBUG : :local: detected overridden config - adding "{bxYPm}" suffix to name
2024/09/11 02:45:45 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot':/root/.cache/rclone/vfsMeta/GoogleDrive/Backup" to be canonical ":local{bxYPm}:/root/.cache/rclone/vfsMeta/GoogleDrive/Backup"
2024/09/11 02:45:45 INFO  : vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0)
2024/09/11 02:45:45 DEBUG : Google drive root 'Backup': Mounting on "/mnt/Rclone/Backup"
2024/09/11 02:45:45 DEBUG : : Root: 
2024/09/11 02:45:45 DEBUG : : >Root: node=/, err=<nil>
2024/09/11 02:45:45 INFO  : Signal received: terminated
2024/09/11 02:45:45 DEBUG : vfs cache: cleaner exiting
2024/09/11 02:45:45 ERROR : /mnt/Rclone/Backup: Unmounted rclone mount
2024/09/11 02:45:45 INFO  : Exiting...
2

What's your whole service file?

Rclone gets killed by systemd it looks like.

3 
[Unit]
Description=RClone Mount Backup
Wants=network-online.target
After=network-online.target

[Service]
Type=notify
Environment=RCLONE_CONFIG=/root/.config/rclone/rclone.conf
RestartSec=5
ExecStart=/usr/bin/rclone mount GoogleDrive:Backup /mnt/Rclone/Backup \
--allow-other \
--dir-cache-time 9999h \
--log-level DEBUG \
--log-file /var/log/Rclone/Backup.log \
--poll-interval 15s \
--user-agent "ISV|rclone.org|rclone/v1.68.0" \
--rc \
--rc-addr=:55555 \
--rc-no-auth \
--cache-dir=/root/.cache/rclone \
--drive-use-trash \
--vfs-cache-mode full \
--vfs-cache-max-size 100G \
--vfs-read-ahead 256M \
--vfs-cache-max-age 1h \
--vfs-cache-poll-interval 5m  
ExecStartPost=/usr/bin/rclone rc vfs/refresh recursive=true --rc-addr 127.0.0.1:55555 _async=true
ExecStop=/bin/fusermount -uz /mnt/Rclone/Backup
Restart=on-failure
User=edson
Group=edson

[Install]
WantedBy=default.target
4

does that work anymore as I thought it was fusermount3 now?

I'd check the journal output from systemd to see if it's restarting it as from your logs, it's getting killed which is most likely by systemd

So when you rc command runs, it's not there.

Also if you need to refresh and no other rc command, you can use:

      --vfs-refresh                            Refreshes the directory cache recursively in the background on start

You still do want to see why the mount is being killed though.

5

So all this is very strange for the following reasons:

  • If I remove everything related to rc from the systemd file, the mount works perfectly.
  • If you change the rclone version from 1.68 to 1.67 it also works perfectly with the same systemd file
6

Yes something has changed.

It was okay in v1.67.0 but error in v1.68.0 with rclone rc vfs/refresh --rc-addr. If you replace argument --rc-addr with --url, then everything works fine.

It wasn't easy to find related commits but rc: convert options to new style · rclone/rclone@ce1859c · GitHub or cmd/rc: add --unix-socket option · rclone/rclone@aee2480 · GitHub might cause the issue.

3 Likes
7

Are you seeing that on some output? Not saying it's wrong by any means, just trying to show it.

rclone rc vfs/refresh --rc-addr 127.0.0.1:5525 -vv
2024/09/11 12:39:47 DEBUG : rclone: Version "v1.68.0" starting with parameters ["rclone" "rc" "vfs/refresh" "--rc-addr" "127.0.0.1:5525" "-vv"]
{
	"error": "connection failed: Post \"http://[\\\"127.0.0.1:5525\\\"]/vfs/refresh\": dial tcp: lookup \"127.0.0.1:5525\": no such host",
	"path": "vfs/refresh",
	"status": 503
}
2024/09/11 12:39:47 DEBUG : 5 go routines active
2024/09/11 12:39:47 NOTICE: Failed to rc: connection failed: Post "http://[\"127.0.0.1:5525\"]/vfs/refresh": dial tcp: lookup "127.0.0.1:5525": no such host

I get a no such host, which I would expect but it 'seems' to work.

I'd like to see the journal output though to see the mount failing and it killing it as that would show what's going on perhaps.

8 
set 11 22:46:45 nextcloud rclone[1438292]: Signal received: terminated
set 11 22:46:45 nextcloud rclone[1438292]: /mnt/Rclone/Backup: Unmounted rclone mount
set 11 22:46:45 nextcloud rclone[1438292]: Exiting...
set 11 22:46:45 nextcloud systemd[1]: borgbackup.service: Main process exited, code=exited, status=143/n/a
set 11 22:46:45 nextcloud systemd[1]: borgbackup.service: Failed with result 'exit-code'.
set 11 22:46:45 nextcloud systemd[1]: Failed to start borgbackup.service - RClone Mount Backup.
set 11 22:46:45 nextcloud systemd[1]: borgbackup.service: Consumed 2.372s CPU time.
set 11 22:46:50 nextcloud systemd[1]: borgbackup.service: Scheduled restart job, restart counter is at 70.
set 11 22:46:50 nextcloud systemd[1]: Starting borgbackup.service - RClone Mount Backup...
set 11 22:46:50 nextcloud rclone[1438619]: Serving remote control on http://[::]:55555/
set 11 22:46:52 nextcloud rclone[1438619]: vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0)
set 11 22:46:53 nextcloud rclone[1438923]: {
set 11 22:46:53 nextcloud rclone[1438923]:         "error": "connection failed: Post \"http://[\\\"127.0.0.1:55555\\\"]/vfs/refresh\": dial tcp: lookup \"127.0.0.1:55555\": no such host",
set 11 22:46:53 nextcloud rclone[1438923]:         "path": "vfs/refresh",
set 11 22:46:53 nextcloud rclone[1438923]:         "status": 503
set 11 22:46:53 nextcloud rclone[1438923]: }
set 11 22:46:53 nextcloud rclone[1438923]: NOTICE: Failed to rc: connection failed: Post "http://[\"127.0.0.1:55555\"]/vfs/refresh": dial tcp: lookup "127.0.0.1:55555": no such host
set 11 22:46:53 nextcloud systemd[1]: borgbackup.service: Control process exited, code=exited, status=1/FAILURE
set 11 22:46:53 nextcloud rclone[1438619]: Signal received: terminated
set 11 22:46:53 nextcloud rclone[1438619]: /mnt/Rclone/Backup: Unmounted rclone mount
set 11 22:46:53 nextcloud rclone[1438619]: Exiting...
set 11 22:46:53 nextcloud systemd[1]: borgbackup.service: Main process exited, code=exited, status=143/n/a
set 11 22:46:53 nextcloud systemd[1]: borgbackup.service: Failed with result 'exit-code'.
set 11 22:46:53 nextcloud systemd[1]: Failed to start borgbackup.service - RClone Mount Backup.
set 11 22:46:53 nextcloud systemd[1]: borgbackup.service: Consumed 2.618s CPU time.
set 11 22:46:58 nextcloud systemd[1]: borgbackup.service: Scheduled restart job, restart counter is at 71.
set 11 22:46:58 nextcloud systemd[1]: Starting borgbackup.service - RClone Mount Backup...
set 11 22:46:58 nextcloud rclone[1438938]: Serving remote control on http://[::]:55555/
^C
9

I made the change here and now everything works perfectly. Thanks for the help!!!

1 Like
10

Well done for finding the root cause.

Can you open a new issue on Github about this please.

I think this is a regression which needs to be fixed - the docs clearly say

Note that --rc-addr, --rc-user, --rc-pass will be read also for
--url, --user, --pass.

Thanks

1 Like
11

Issue opened `--rc-addr` argument for rc command not functioning as expected in v1.68.0 · Issue #8073 · rclone/rclone · GitHub

Thanks

2 Likes
12

This topic was automatically closed 3 days after the last reply. New replies are no longer allowed.

13

This is working now with the latest beta - it was fixed by commit 874d66658ed3e614b8073b29299321be200a4d21 which I didn't realise would fix this problem too!