Feb 11 10:21:02 studio ntpd[1249]: CLOCK: time stepped by 1984702.871690
Feb 11 10:21:02 studio ntpd[1249]: CLOCK: time changed from 2026-01-19 to 2026-02-11
Feb 11 10:21:02 studio ntpd[1249]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes
Feb 11 10:21:02 studio systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service...
Feb 11 10:21:02 studio systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats.
Feb 11 10:21:02 studio systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully.
Feb 11 10:21:02 studio systemd[1]: dpkg-db-backup.service: Deactivated successfully.
Feb 11 10:21:02 studio systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service.
Feb 11 10:21:03 studio sudo[2214]: pam_unix(sudo:session): session closed for user root
Feb 11 10:21:03 studio kernel: CIFS: VFS: Error connecting to socket. Aborting operation.
Feb 11 10:21:03 studio kernel: CIFS: VFS: cifs_mount failed w/return code = -113
Feb 11 10:21:03 studio volumio[1421]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6
Feb 11 10:21:03 studio volumio[1421]: info: Cannot mount NAS Music at system boot, trial number 2 ,retrying in 5 seconds
Feb 11 10:21:03 studio volumio[1421]: info: Volumio called home
Feb 11 10:21:04 studio volumio[1421]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Feb 11 10:21:04 studio volumio[1421]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Feb 11 10:21:04 studio volumio[1421]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Feb 11 10:21:04 studio volumio[1421]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Feb 11 10:21:04 studio volumio[1421]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Feb 11 10:21:04 studio volumio[1421]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Feb 11 10:21:04 studio volumio[1421]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Feb 11 10:21:04 studio volumio[1421]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Feb 11 10:21:04 studio volumio[1421]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Feb 11 10:21:04 studio volumio[1421]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Feb 11 10:21:04 studio volumio[1421]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium
Feb 11 10:21:04 studio volumio[1421]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Feb 11 10:21:04 studio volumio[1421]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Feb 11 10:21:04 studio volumio[1421]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Feb 11 10:21:04 studio volumio[1421]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Feb 11 10:21:04 studio volumio[1421]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Feb 11 10:21:04 studio volumio[1421]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Feb 11 10:21:04 studio volumio[1421]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Feb 11 10:21:04 studio volumio[1421]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Feb 11 10:21:04 studio volumio[1421]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Feb 11 10:21:04 studio volumio[1421]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Feb 11 10:21:04 studio volumio[1421]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar
Feb 11 10:21:04 studio volumio[1421]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Feb 11 10:21:04 studio volumio[1421]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Feb 11 10:21:04 studio volumio[1421]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Feb 11 10:21:04 studio volumio[1421]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Feb 11 10:21:04 studio volumio[1421]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Feb 11 10:21:04 studio volumio[1421]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Feb 11 10:21:04 studio volumio[1421]: info: Adding plugin bluetooth to MyMusic Plugins
Feb 11 10:21:04 studio volumio[1421]: info: Adding plugin multiroom to MyMusic Plugins
Feb 11 10:21:04 studio volumio[1421]: info: Adding plugin metavolumio to MyMusic Plugins
Feb 11 10:21:04 studio volumio[1421]: info: Adding plugin cd_controller to MyMusic Plugins
Feb 11 10:21:04 studio volumio[1421]: info: Adding plugin qobuzconnect to MyMusic Plugins
Feb 11 10:21:04 studio volumio[1421]: info: Adding plugin smart_inputs to MyMusic Plugins
Feb 11 10:21:04 studio volumio[1421]: info: Adding plugin tidalconnect to MyMusic Plugins
Feb 11 10:21:04 studio volumio[1421]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Feb 11 10:21:04 studio volumio[1421]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Feb 11 10:21:04 studio volumio[1421]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Feb 11 10:21:04 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 11 10:21:04 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 11 10:21:04 studio volumio[1421]: info: Starting MyVolumio Remote Streaming Endpoints
Feb 11 10:21:04 studio volumio[1421]: info: MyVolumio login type: Token
Feb 11 10:21:04 studio volumio[1421]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Feb 11 10:21:04 studio volumio[1421]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Feb 11 10:21:05 studio volumio[1421]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Feb 11 10:21:05 studio volumio[1421]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Feb 11 10:21:05 studio volumio[1421]: info: Streaming services startup
Feb 11 10:21:05 studio volumio[1421]: info: Starting Streaming Daemon
Feb 11 10:21:05 studio volumio[1421]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Feb 11 10:21:05 studio sudo[2272]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Feb 11 10:21:05 studio sudo[2272]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 11 10:21:05 studio sudo[2272]: pam_unix(sudo:session): session closed for user root
Feb 11 10:21:06 studio volumio[1421]: info: CoreCommandRouter::getUIConfigOnPlugin
Feb 11 10:21:06 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Feb 11 10:21:06 studio volumio[1421]: error: Cannot start Volumio Streaming Daemon
Feb 11 10:21:06 studio volumio[1421]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Feb 11 10:21:06 studio volumio[1421]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Feb 11 10:21:06 studio volumio[1421]: info: Discovery: A device disappeared from network
Feb 11 10:21:06 studio volumio[1421]: info: Discovery: A device disappeared from network
Feb 11 10:21:06 studio volumio[1421]: info: Discovery: A device disappeared from network
Feb 11 10:21:06 studio volumio[1421]: info: CoreCommandRouter::volumioGetState
Feb 11 10:21:06 studio volumio[1421]: info: CorePlayQueue::getTrack 0
Feb 11 10:21:06 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Feb 11 10:21:06 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Feb 11 10:21:06 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs
Feb 11 10:21:06 studio volumio[1421]: info: Discovery: adding 42726e1c-0f86-42b7-a80c-f768d91e4d78
Feb 11 10:21:06 studio volumio[1421]: info: Discovery: Found device Volumio
Feb 11 10:21:06 studio volumio[1421]: info: CoreCommandRouter::volumioGetState
Feb 11 10:21:06 studio volumio[1421]: info: CorePlayQueue::getTrack 0
Feb 11 10:21:06 studio volumio[1421]: info: Discovery: this is already registered, 42726e1c-0f86-42b7-a80c-f768d91e4d78
Feb 11 10:21:06 studio volumio[1421]: info: Discovery: Found device Volumio
Feb 11 10:21:06 studio volumio[1421]: info: CoreCommandRouter::volumioGetState
Feb 11 10:21:06 studio volumio[1421]: info: CorePlayQueue::getTrack 0
Feb 11 10:21:06 studio volumio[1421]: info: Discovery: this is already registered, 42726e1c-0f86-42b7-a80c-f768d91e4d78
Feb 11 10:21:06 studio volumio[1421]: info: Discovery: Found device Volumio
Feb 11 10:21:06 studio volumio[1421]: info: CoreCommandRouter::volumioGetState
Feb 11 10:21:06 studio volumio[1421]: info: CorePlayQueue::getTrack 0
Feb 11 10:21:06 studio volumio[1421]: info: Received Get System Version
Feb 11 10:21:06 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Feb 11 10:21:06 studio volumio[1421]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
Feb 11 10:21:06 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: appearance , getBackgrounds
Feb 11 10:21:06 studio volumio[1421]: info: MyVolumio token set successfully
Feb 11 10:21:06 studio volumio[1421]: info: MYVOLUMIO: Adding device
Feb 11 10:21:06 studio volumio[1421]: info: MYVOLUMIO: Evaluating Server
Feb 11 10:21:07 studio volumio[1421]: info: MyVolumio status changed
Feb 11 10:21:07 studio volumio[1421]: info: Streaming services startup
Feb 11 10:21:07 studio volumio[1421]: info: Starting Streaming Daemon
Feb 11 10:21:07 studio volumio[1421]: info: Removing browser output: myVolumio user plan is not superstar
Feb 11 10:21:07 studio volumio[1421]: info: Removing audio output:
Feb 11 10:21:07 studio volumio[1421]: info: Stoppping Tunnel 1
Feb 11 10:21:07 studio sudo[2326]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Feb 11 10:21:07 studio sudo[2326]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 11 10:21:07 studio sudo[2328]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service
Feb 11 10:21:07 studio sudo[2328]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 11 10:21:07 studio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Feb 11 10:21:07 studio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Feb 11 10:21:07 studio sudo[2326]: pam_unix(sudo:session): session closed for user root
Feb 11 10:21:07 studio volumio[1421]: error: Cannot start Volumio Streaming Daemon
Feb 11 10:21:07 studio volumio[1421]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Feb 11 10:21:07 studio volumio[1421]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Feb 11 10:21:07 studio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Feb 11 10:21:07 studio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Feb 11 10:21:07 studio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Feb 11 10:21:07 studio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Feb 11 10:21:07 studio sudo[2328]: pam_unix(sudo:session): session closed for user root
Feb 11 10:21:07 studio volumio[1421]: info: Remote SSH Stopped
Feb 11 10:21:08 studio sudo[2332]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=stephen.oram@blueyonder.co.uk,password=Montydog12,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.87/Music /mnt/NAS/Music
Feb 11 10:21:08 studio sudo[2332]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 11 10:21:08 studio kernel: CIFS: Attempting to mount //192.168.0.87/Music
Feb 11 10:21:09 studio volumio[1421]: info: Setting Geolocation for MyVolumio to eu3
Feb 11 10:21:09 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 11 10:21:09 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 11 10:21:09 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 11 10:21:09 studio volumio[1421]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Feb 11 10:21:10 studio volumio[1421]: info: Updating MyVolumio device info
Feb 11 10:21:10 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 11 10:21:10 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 11 10:21:10 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 11 10:21:10 studio volumio[1421]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Feb 11 10:21:12 studio volumio-remote-updater[1031]: Test mode enabled
Feb 11 10:21:12 studio volumio-remote-updater[1031]: Alpha mode disabled
Feb 11 10:21:12 studio volumio-remote-updater[1031]: Alpha legacy test mode disabled
Feb 11 10:21:12 studio volumio[1421]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\n- Fix for BLE notification issues
\n- Fix for mDNS/BCT
\n
\nNEW ADDITIONS
\n\n- Update Tidal SDK
\n
\n","title":"Update v4.097","updateavailable":true}
Feb 11 10:21:12 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Feb 11 10:21:13 studio volumio[1421]: info: MYVOLUMIO: Adding device
Feb 11 10:21:13 studio volumio[1421]: info: MYVOLUMIO: Evaluating Server
Feb 11 10:21:13 studio systemd[1]: systemd-fsckd.service: Deactivated successfully.
Feb 11 10:21:13 studio volumio[1421]: info: Setting Geolocation for MyVolumio to eu3
Feb 11 10:21:13 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 11 10:21:13 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 11 10:21:13 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 11 10:21:14 studio volumio[1421]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Feb 11 10:21:14 studio sudo[2332]: pam_unix(sudo:session): session closed for user root
Feb 11 10:21:14 studio volumio[1421]: info: Cannot mount NAS Music at system boot, trial number 3 ,retrying in 5 seconds
Feb 11 10:21:14 studio kernel: CIFS: VFS: Error connecting to socket. Aborting operation.
Feb 11 10:21:14 studio kernel: CIFS: VFS: cifs_mount failed w/return code = -113
Feb 11 10:21:14 studio systemd[1]: systemd-hostnamed.service: Deactivated successfully.
Feb 11 10:21:14 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Feb 11 10:21:14 studio volumio[1421]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
Feb 11 10:21:14 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
Feb 11 10:21:14 studio volumio[1421]: info: Received Get System Version
Feb 11 10:21:14 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Feb 11 10:21:14 studio volumio[1421]: info: Received Get System Info
Feb 11 10:21:14 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 11 10:21:14 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 11 10:21:14 studio volumio[1421]: info: Discovery: Getting this device information
Feb 11 10:21:14 studio volumio[1421]: info: CoreCommandRouter::volumioGetState
Feb 11 10:21:14 studio volumio[1421]: info: CorePlayQueue::getTrack 0
Feb 11 10:21:14 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 11 10:21:14 studio volumio[1421]: info: Updating MyVolumio device info
Feb 11 10:21:14 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 11 10:21:14 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 11 10:21:14 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 11 10:21:15 studio volumio[1421]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Feb 11 10:21:15 studio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2.
Feb 11 10:21:15 studio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD.
Feb 11 10:21:15 studio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD.
Feb 11 10:21:15 studio sudo[2255]: pam_unix(sudo:session): session closed for user root
Feb 11 10:21:15 studio volumio[1421]: info: Upmpdcli Daemon Started
Feb 11 10:21:18 studio volumio[1421]: info: Update: [object Object]
Feb 11 10:21:18 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: updater_comm , checkSystemIntegrity
Feb 11 10:21:18 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 11 10:21:18 studio upmpdcli[2400]: writing RSA key
Feb 11 10:21:19 studio sudo[2414]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=stephen.oram@blueyonder.co.uk,password=Montydog12,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.87/Music /mnt/NAS/Music
Feb 11 10:21:19 studio sudo[2414]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 11 10:21:19 studio kernel: CIFS: Attempting to mount //192.168.0.87/Music
Feb 11 10:21:19 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Feb 11 10:21:19 studio volumio[1421]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7
Feb 11 10:21:19 studio volumio[1421]: info: CoreCommandRouter::volumioGetState
Feb 11 10:21:19 studio volumio[1421]: info: CorePlayQueue::getTrack 0
Feb 11 10:21:20 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: system , setTestSystem
Feb 11 10:21:20 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: updater_comm , checkUpdates
Feb 11 10:21:20 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Feb 11 10:21:20 studio volumio-remote-updater[1031]: PROGRESS: 0, STATUS: "Starting update", ETA: "6m"
Feb 11 10:21:20 studio volumio-remote-updater[2433]: /bin/rm: cannot remove '/imgpart/rck*': No such file or directory
Feb 11 10:21:20 studio volumio-remote-updater[2433]: /bin/rm: cannot remove '/imgpart/*.part': No such file or directory
Feb 11 10:21:20 studio volumio-remote-updater[2433]: /bin/rm: cannot remove '/imgpart/*.zs-old': No such file or directory
Feb 11 10:21:20 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: updater_comm , notifyProgress
Feb 11 10:21:20 studio volumio-remote-updater[1031]: PROGRESS: 5, STATUS: "Preparing update", ETA: "5m"
Feb 11 10:21:20 studio volumio[1421]: info: Killing processes that might interfere with OTA Updates
Feb 11 10:21:20 studio sudo[2441]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/killall matchbox-keyboard
Feb 11 10:21:20 studio sudo[2441]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 11 10:21:20 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Feb 11 10:21:20 studio sudo[2441]: pam_unix(sudo:session): session closed for user root
Feb 11 10:21:20 studio volumio[1421]: updateProgress
Feb 11 10:21:20 studio volumio[1421]: {
Feb 11 10:21:20 studio volumio[1421]: downloadSpeed: '',
Feb 11 10:21:20 studio volumio[1421]: eta: '5m',
Feb 11 10:21:20 studio volumio[1421]: progress: 5,
Feb 11 10:21:20 studio volumio[1421]: status: 'Preparing update'
Feb 11 10:21:20 studio volumio[1421]: }
Feb 11 10:21:20 studio sudo[2443]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/killall matchbox-window-manager
Feb 11 10:21:20 studio volumio[1421]: error: Cannot kill process: Error: Command failed: /usr/bin/sudo /usr/bin/killall matchbox-keyboard
Feb 11 10:21:20 studio volumio[1421]: matchbox-keyboard: no process found
Feb 11 10:21:20 studio sudo[2443]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 11 10:21:20 studio sudo[2443]: pam_unix(sudo:session): session closed for user root
Feb 11 10:21:21 studio volumio[1421]: error: Cannot kill process: Error: Command failed: /usr/bin/sudo /usr/bin/killall matchbox-window-manager
Feb 11 10:21:21 studio volumio[1421]: matchbox-window-manager: no process found
Feb 11 10:21:21 studio volumio-remote-updater[1031]: PROGRESS: 5, STATUS: "Preparing update", ETA: "5m"
Feb 11 10:21:21 studio volumio-remote-updater[1031]: PROGRESS: 5, STATUS: "Preparing update", ETA: "5m"
Feb 11 10:21:21 studio volumio-remote-updater[1031]: PROGRESS: 5, STATUS: "Preparing update", ETA: "5m"
Feb 11 10:21:21 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Feb 11 10:21:21 studio volumio[1421]: updateProgress
Feb 11 10:21:21 studio volumio[1421]: {
Feb 11 10:21:21 studio volumio[1421]: downloadSpeed: '',
Feb 11 10:21:21 studio volumio[1421]: eta: '5m',
Feb 11 10:21:21 studio volumio[1421]: progress: 5,
Feb 11 10:21:21 studio volumio[1421]: status: 'Preparing update'
Feb 11 10:21:21 studio volumio[1421]: }
Feb 11 10:21:21 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Feb 11 10:21:21 studio volumio-remote-updater[1031]: PROGRESS: 10, STATUS: "Creating backup", ETA: "5m"
Feb 11 10:21:21 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Feb 11 10:21:21 studio volumio[1421]: updateProgress
Feb 11 10:21:21 studio volumio[1421]: {
Feb 11 10:21:21 studio volumio[1421]: downloadSpeed: '',
Feb 11 10:21:21 studio volumio[1421]: eta: '5m',
Feb 11 10:21:21 studio volumio[1421]: progress: 10,
Feb 11 10:21:21 studio volumio[1421]: status: 'Creating backup'
Feb 11 10:21:21 studio volumio[1421]: }
Feb 11 10:21:21 studio volumio-remote-updater[1031]: PROGRESS: 10, STATUS: "Creating backup", ETA: "5m"
Feb 11 10:21:21 studio volumio-remote-updater[1031]: PROGRESS: 10, STATUS: "Creating backup", ETA: "5m"
Feb 11 10:21:21 studio volumio-remote-updater[1031]: PROGRESS: 10, STATUS: "Creating backup", ETA: "5m"
Feb 11 10:21:21 studio volumio-remote-updater[1031]: PROGRESS: 10, STATUS: "Creating backup", ETA: "5m"
Feb 11 10:21:21 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Feb 11 10:21:21 studio volumio-remote-updater[1031]: PROGRESS: 10, STATUS: "Creating backup", ETA: "5m"
Feb 11 10:21:21 studio volumio-remote-updater[1031]: PROGRESS: 10, STATUS: "Creating backup", ETA: "5m"
Feb 11 10:21:21 studio volumio-remote-updater[1031]: PROGRESS: 10, STATUS: "Creating backup", ETA: "5m"
Feb 11 10:21:21 studio volumio-remote-updater[1031]: PROGRESS: 10, STATUS: "Creating backup", ETA: "5m"
Feb 11 10:21:21 studio volumio-remote-updater[1031]: PROGRESS: 10, STATUS: "Creating backup", ETA: "5m"
Feb 11 10:21:21 studio volumio[1421]: updateProgress
Feb 11 10:21:21 studio volumio[1421]: {
Feb 11 10:21:21 studio volumio[1421]: downloadSpeed: '',
Feb 11 10:21:21 studio volumio[1421]: eta: '5m',
Feb 11 10:21:21 studio volumio[1421]: progress: 10,
Feb 11 10:21:21 studio volumio[1421]: status: 'Creating backup'
Feb 11 10:21:21 studio volumio[1421]: }
Feb 11 10:21:21 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Feb 11 10:21:21 studio volumio[1421]: updateProgress
Feb 11 10:21:21 studio volumio[1421]: {
Feb 11 10:21:21 studio volumio[1421]: downloadSpeed: '',
Feb 11 10:21:21 studio volumio[1421]: eta: '5m',
Feb 11 10:21:21 studio volumio[1421]: progress: 10,
Feb 11 10:21:21 studio volumio[1421]: status: 'Creating backup'
Feb 11 10:21:21 studio volumio[1421]: }
Feb 11 10:21:22 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Feb 11 10:21:22 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 11 10:21:22 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Feb 11 10:21:22 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Feb 11 10:21:22 studio volumio-remote-updater[1031]: PROGRESS: 10, STATUS: "Creating backup", ETA: "5m"
Feb 11 10:21:22 studio volumio-remote-updater[1031]: PROGRESS: 16, STATUS: "Creating backup", ETA: "5m"
Feb 11 10:21:22 studio volumio-remote-updater[1031]: PROGRESS: 16, STATUS: "Creating backup", ETA: "5m"
Feb 11 10:21:22 studio volumio-remote-updater[1031]: PROGRESS: 16, STATUS: "Creating backup", ETA: "5m"
Feb 11 10:21:22 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Feb 11 10:21:22 studio volumio[1421]: updateProgress
Feb 11 10:21:22 studio volumio[1421]: {
Feb 11 10:21:22 studio volumio[1421]: downloadSpeed: '',
Feb 11 10:21:22 studio volumio[1421]: eta: '5m',
Feb 11 10:21:22 studio volumio[1421]: progress: 16,
Feb 11 10:21:22 studio volumio[1421]: status: 'Creating backup'
Feb 11 10:21:22 studio volumio[1421]: }
Feb 11 10:21:23 studio volumio-remote-updater[1031]: PROGRESS: 16, STATUS: "Creating backup", ETA: "5m"
Feb 11 10:21:23 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Feb 11 10:21:23 studio volumio-remote-updater[1031]: PROGRESS: 20, STATUS: "Creating backup", ETA: "5m"
Feb 11 10:21:23 studio volumio-remote-updater[1031]: PROGRESS: 20, STATUS: "Creating backup", ETA: "5m"
Feb 11 10:21:23 studio volumio-remote-updater[1031]: PROGRESS: 20, STATUS: "Creating backup", ETA: "5m"
Feb 11 10:21:23 studio volumio[1421]: updateProgress
Feb 11 10:21:23 studio volumio[1421]: {
Feb 11 10:21:23 studio volumio[1421]: downloadSpeed: '',
Feb 11 10:21:23 studio volumio[1421]: eta: '5m',
Feb 11 10:21:23 studio volumio[1421]: progress: 16,
Feb 11 10:21:23 studio volumio[1421]: status: 'Creating backup'
Feb 11 10:21:23 studio volumio[1421]: }
Feb 11 10:21:23 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Feb 11 10:21:23 studio volumio[1421]: updateProgress
Feb 11 10:21:23 studio volumio[1421]: {
Feb 11 10:21:23 studio volumio[1421]: downloadSpeed: '',
Feb 11 10:21:23 studio volumio[1421]: eta: '5m',
Feb 11 10:21:23 studio volumio[1421]: progress: 20,
Feb 11 10:21:23 studio volumio[1421]: status: 'Creating backup'
Feb 11 10:21:23 studio volumio[1421]: }
Feb 11 10:21:24 studio volumio-remote-updater[1031]: PROGRESS: 20, STATUS: "Creating backup", ETA: "5m"
Feb 11 10:21:24 studio volumio-remote-updater[1031]: PROGRESS: 24, STATUS: "Creating backup", ETA: "5m"
Feb 11 10:21:24 studio volumio-remote-updater[1031]: PROGRESS: 24, STATUS: "Creating backup", ETA: "5m"
Feb 11 10:21:24 studio volumio-remote-updater[1031]: PROGRESS: 24, STATUS: "Creating backup", ETA: "5m"
Feb 11 10:21:24 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Feb 11 10:21:24 studio volumio[1421]: updateProgress
Feb 11 10:21:24 studio volumio[1421]: {
Feb 11 10:21:24 studio volumio[1421]: downloadSpeed: '',
Feb 11 10:21:24 studio volumio[1421]: eta: '5m',
Feb 11 10:21:24 studio volumio[1421]: progress: 24,
Feb 11 10:21:24 studio volumio[1421]: status: 'Creating backup'
Feb 11 10:21:24 studio volumio[1421]: }
Feb 11 10:21:24 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Feb 11 10:21:24 studio volumio[1421]: updateProgress
Feb 11 10:21:24 studio volumio[1421]: {
Feb 11 10:21:24 studio volumio[1421]: downloadSpeed: '',
Feb 11 10:21:24 studio volumio[1421]: eta: '5m',
Feb 11 10:21:24 studio volumio[1421]: progress: 24,
Feb 11 10:21:24 studio volumio[1421]: status: 'Creating backup'
Feb 11 10:21:24 studio volumio[1421]: }
Feb 11 10:21:24 studio volumio-remote-updater[1031]: PROGRESS: 24, STATUS: "Creating backup", ETA: "5m"
Feb 11 10:21:24 studio volumio-remote-updater[1031]: PROGRESS: 25, STATUS: "Creating backup", ETA: "5m"
Feb 11 10:21:24 studio volumio-remote-updater[1031]: PROGRESS: 25, STATUS: "Creating backup", ETA: "5m"
Feb 11 10:21:24 studio volumio-remote-updater[1031]: PROGRESS: 25, STATUS: "Creating backup", ETA: "5m"
Feb 11 10:21:24 studio volumio-remote-updater[1031]: PROGRESS: 25, STATUS: "Creating backup", ETA: "5m"
Feb 11 10:21:24 studio volumio-remote-updater[1031]: PROGRESS: 25, STATUS: "Creating backup", ETA: "5m"
Feb 11 10:21:24 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Feb 11 10:21:24 studio volumio[1421]: updateProgress
Feb 11 10:21:24 studio volumio[1421]: {
Feb 11 10:21:24 studio volumio[1421]: downloadSpeed: '',
Feb 11 10:21:24 studio volumio[1421]: eta: '5m',
Feb 11 10:21:24 studio volumio[1421]: progress: 25,
Feb 11 10:21:24 studio volumio[1421]: status: 'Creating backup'
Feb 11 10:21:24 studio volumio[1421]: }
Feb 11 10:21:24 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 11 10:21:24 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 11 10:21:24 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Feb 11 10:21:24 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Feb 11 10:21:24 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Feb 11 10:21:24 studio volumio[1421]: info: CoreCommandRouter::volumioGetBrowseSources
Feb 11 10:21:24 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Feb 11 10:21:25 studio volumio-remote-updater[1031]: PROGRESS: 25, STATUS: "Creating backup", ETA: "5m"
Feb 11 10:21:25 studio volumio-remote-updater[1031]: PROGRESS: 25, STATUS: "Creating backup", ETA: "5m"
Feb 11 10:21:25 studio volumio-remote-updater[1031]: PROGRESS: 25, STATUS: "Creating backup", ETA: "5m"
Feb 11 10:21:25 studio volumio-remote-updater[1031]: PROGRESS: 25, STATUS: "Creating backup", ETA: "5m"
Feb 11 10:21:25 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Feb 11 10:21:25 studio volumio[1421]: updateProgress
Feb 11 10:21:25 studio volumio[1421]: {
Feb 11 10:21:25 studio volumio[1421]: downloadSpeed: '',
Feb 11 10:21:25 studio volumio[1421]: eta: '5m',
Feb 11 10:21:25 studio volumio[1421]: progress: 25,
Feb 11 10:21:25 studio volumio[1421]: status: 'Creating backup'
Feb 11 10:21:25 studio volumio[1421]: }
Feb 11 10:21:25 studio volumio-remote-updater[1031]: PROGRESS: 25, STATUS: "Creating backup", ETA: "5m"
Feb 11 10:21:25 studio volumio-remote-updater[1031]: PROGRESS: 25, STATUS: "Creating backup", ETA: "5m"
Feb 11 10:21:25 studio volumio-remote-updater[1031]: PROGRESS: 25, STATUS: "Creating backup", ETA: "5m"
Feb 11 10:21:25 studio volumio-remote-updater[1031]: PROGRESS: 25, STATUS: "Creating backup", ETA: "5m"
Feb 11 10:21:25 studio volumio-remote-updater[1031]: PROGRESS: 25, STATUS: "Creating backup", ETA: "5m"
Feb 11 10:21:25 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Feb 11 10:21:25 studio volumio[1421]: updateProgress
Feb 11 10:21:25 studio volumio[1421]: {
Feb 11 10:21:25 studio volumio[1421]: downloadSpeed: '',
Feb 11 10:21:25 studio volumio[1421]: eta: '5m',
Feb 11 10:21:25 studio volumio[1421]: progress: 25,
Feb 11 10:21:25 studio volumio[1421]: status: 'Creating backup'
Feb 11 10:21:25 studio volumio[1421]: }
Feb 11 10:21:25 studio volumio[1421]: error: MyVolumio Plugin failed to authenticate in a timely fashion
Feb 11 10:21:25 studio volumio[1421]: info: Completed starting MyVolumio Plugin
Feb 11 10:21:25 studio volumio[1421]: [Metrics] CommandRouter: 35s 634.99ms
Feb 11 10:21:25 studio volumio[1421]: info: CoreCommandRouter::volumiosetStartupVolume
Feb 11 10:21:25 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 11 10:21:25 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 11 10:21:25 studio volumio[1421]: info: CoreCommandRouter::Close All Modals sent
Feb 11 10:21:25 studio volumio[1421]: info: CoreCommandRouter::Close All Modals sent
Feb 11 10:21:25 studio volumio[1421]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav
Feb 11 10:21:25 studio volumio[1421]: ALSA lib confmisc.c:165:(snd_config_get_card) Cannot get card index for Max
Feb 11 10:21:25 studio volumio[1421]: aplay: main:831: audio open error: No such device
Feb 11 10:21:25 studio volumio-remote-updater[1031]: PROGRESS: 25, STATUS: "Creating backup", ETA: "5m"
Feb 11 10:21:25 studio volumio-remote-updater[1031]: PROGRESS: 30, STATUS: "Creating backup", ETA: "5m"
Feb 11 10:21:25 studio volumio-remote-updater[1031]: PROGRESS: 30, STATUS: "Creating backup", ETA: "5m"
Feb 11 10:21:25 studio volumio-remote-updater[1031]: PROGRESS: 30, STATUS: "Creating backup", ETA: "5m"
Feb 11 10:21:25 studio volumio-remote-updater[1031]: PROGRESS: 30, STATUS: "Creating backup", ETA: "5m"
Feb 11 10:21:25 studio volumio-remote-updater[1031]: PROGRESS: 30, STATUS: "Creating backup", ETA: "5m"
Feb 11 10:21:25 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Feb 11 10:21:25 studio volumio[1421]: updateProgress
Feb 11 10:21:25 studio volumio[1421]: {
Feb 11 10:21:25 studio volumio[1421]: downloadSpeed: '',
Feb 11 10:21:25 studio volumio[1421]: eta: '5m',
Feb 11 10:21:25 studio volumio[1421]: progress: 30,
Feb 11 10:21:25 studio volumio[1421]: status: 'Creating backup'
Feb 11 10:21:25 studio volumio[1421]: }
Feb 11 10:21:25 studio volumio-remote-updater[1031]: PROGRESS: 30, STATUS: "Downloading new update", ETA: "4m"
Feb 11 10:21:25 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Feb 11 10:21:25 studio volumio[1421]: updateProgress
Feb 11 10:21:25 studio volumio[1421]: {
Feb 11 10:21:25 studio volumio[1421]: downloadSpeed: '',
Feb 11 10:21:25 studio volumio[1421]: eta: '4m',
Feb 11 10:21:25 studio volumio[1421]: progress: 30,
Feb 11 10:21:25 studio volumio[1421]: status: 'Downloading new update'
Feb 11 10:21:25 studio volumio[1421]: }
Feb 11 10:21:25 studio sudo[2414]: pam_unix(sudo:session): session closed for user root
Feb 11 10:21:25 studio volumio[1421]: info: Cannot mount NAS Music at system boot, trial number 4 ,retrying in 5 seconds
Feb 11 10:21:25 studio kernel: CIFS: VFS: Error connecting to socket. Aborting operation.
Feb 11 10:21:25 studio kernel: CIFS: VFS: cifs_mount failed w/return code = -113
Feb 11 10:21:25 studio volumio-remote-updater[1031]: PROGRESS: 30, STATUS: "Downloading new update", ETA: "4m"
Feb 11 10:21:25 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Feb 11 10:21:25 studio volumio[1421]: updateProgress
Feb 11 10:21:25 studio volumio[1421]: {
Feb 11 10:21:25 studio volumio[1421]: downloadSpeed: '0.0',
Feb 11 10:21:25 studio volumio[1421]: eta: '4m',
Feb 11 10:21:25 studio volumio[1421]: progress: 30,
Feb 11 10:21:25 studio volumio[1421]: status: 'Downloading new update'
Feb 11 10:21:25 studio volumio[1421]: }
Feb 11 10:21:26 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Feb 11 10:21:26 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Feb 11 10:21:26 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: raat , onStop
Feb 11 10:21:26 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Feb 11 10:21:30 studio sudo[2507]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=stephen.oram@blueyonder.co.uk,password=Montydog12,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.87/Music /mnt/NAS/Music
Feb 11 10:21:30 studio sudo[2507]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 11 10:21:30 studio kernel: CIFS: Attempting to mount //192.168.0.87/Music
Feb 11 10:21:32 studio volumio-remote-updater[2501]: [826B blob data]
Feb 11 10:21:32 studio volumio-remote-updater[2501]: downloading from http://updates2.volumio.org/pi/volumio/4.097/volumio_current.sqsh:
Feb 11 10:21:32 studio volumio-remote-updater[1031]: PROGRESS: 30, STATUS: "Downloading new update", ETA: "4m"
Feb 11 10:21:32 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Feb 11 10:21:32 studio volumio[1421]: updateProgress
Feb 11 10:21:32 studio volumio[1421]: {
Feb 11 10:21:32 studio volumio[1421]: downloadSpeed: '0.0',
Feb 11 10:21:32 studio volumio[1421]: eta: '4m',
Feb 11 10:21:32 studio volumio[1421]: progress: 30,
Feb 11 10:21:32 studio volumio[1421]: status: 'Downloading new update'
Feb 11 10:21:32 studio volumio[1421]: }
Feb 11 10:21:32 studio volumio[1421]: info: BOOT COMPLETED
Feb 11 10:21:33 studio volumio-remote-updater[1031]: PROGRESS: 30, STATUS: "Downloading new update", ETA: "4m"
Feb 11 10:21:33 studio volumio-remote-updater[1031]: PROGRESS: 30, STATUS: "Downloading new update", ETA: "4m"
Feb 11 10:21:33 studio volumio-remote-updater[1031]: PROGRESS: 37, STATUS: "Downloading new update", ETA: "4m"
Feb 11 10:21:33 studio volumio-remote-updater[1031]: PROGRESS: 37, STATUS: "Downloading new update", ETA: "4m"
Feb 11 10:21:33 studio volumio-remote-updater[1031]: PROGRESS: 37, STATUS: "Downloading new update", ETA: "4m"
Feb 11 10:21:33 studio volumio-remote-updater[1031]: PROGRESS: 37, STATUS: "Downloading new update", ETA: "4m"
Feb 11 10:21:33 studio volumio-remote-updater[1031]: PROGRESS: 37, STATUS: "Downloading new update", ETA: "4m"
Feb 11 10:21:33 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Feb 11 10:21:33 studio volumio[1421]: updateProgress
Feb 11 10:21:33 studio volumio[1421]: {
Feb 11 10:21:33 studio volumio[1421]: downloadSpeed: '9757.6',
Feb 11 10:21:33 studio volumio[1421]: eta: '4m',
Feb 11 10:21:33 studio volumio[1421]: progress: 37,
Feb 11 10:21:33 studio volumio[1421]: status: 'Downloading new update'
Feb 11 10:21:33 studio volumio[1421]: }
Feb 11 10:21:33 studio systemd[1]: Starting e2scrub_all.service - Online ext4 Metadata Check for All Filesystems...
Feb 11 10:21:33 studio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service...
Feb 11 10:21:33 studio systemd[1]: e2scrub_all.service: Deactivated successfully.
Feb 11 10:21:33 studio systemd[1]: Finished e2scrub_all.service - Online ext4 Metadata Check for All Filesystems.
Feb 11 10:21:34 studio volumio-remote-updater[1031]: PROGRESS: 37, STATUS: "Downloading new update", ETA: "4m"
Feb 11 10:21:34 studio volumio-remote-updater[1031]: PROGRESS: 40, STATUS: "Downloading new update", ETA: "4m"
Feb 11 10:21:34 studio volumio-remote-updater[1031]: PROGRESS: 40, STATUS: "Downloading new update", ETA: "4m"
Feb 11 10:21:34 studio volumio-remote-updater[1031]: PROGRESS: 40, STATUS: "Downloading new update", ETA: "4m"
Feb 11 10:21:34 studio volumio-remote-updater[1031]: PROGRESS: 40, STATUS: "Downloading new update", ETA: "4m"
Feb 11 10:21:34 studio volumio-remote-updater[1031]: PROGRESS: 40, STATUS: "Downloading new update", ETA: "4m"
Feb 11 10:21:34 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Feb 11 10:21:34 studio volumio[1421]: updateProgress
Feb 11 10:21:34 studio volumio[1421]: {
Feb 11 10:21:34 studio volumio[1421]: downloadSpeed: '11999.2',
Feb 11 10:21:34 studio volumio[1421]: eta: '4m',
Feb 11 10:21:34 studio volumio[1421]: progress: 40,
Feb 11 10:21:34 studio volumio[1421]: status: 'Downloading new update'
Feb 11 10:21:34 studio volumio[1421]: }
Feb 11 10:21:35 studio volumio-remote-updater[1031]: PROGRESS: 40, STATUS: "Downloading new update", ETA: "4m"
Feb 11 10:21:35 studio volumio-remote-updater[1031]: PROGRESS: 48, STATUS: "Downloading new update", ETA: "4m"
Feb 11 10:21:35 studio volumio-remote-updater[1031]: PROGRESS: 48, STATUS: "Downloading new update", ETA: "4m"
Feb 11 10:21:35 studio volumio-remote-updater[1031]: PROGRESS: 48, STATUS: "Downloading new update", ETA: "4m"
Feb 11 10:21:35 studio volumio-remote-updater[1031]: PROGRESS: 48, STATUS: "Downloading new update", ETA: "4m"
Feb 11 10:21:35 studio volumio-remote-updater[1031]: PROGRESS: 48, STATUS: "Downloading new update", ETA: "4m"
Feb 11 10:21:35 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Feb 11 10:21:35 studio volumio[1421]: updateProgress
Feb 11 10:21:35 studio volumio[1421]: {
Feb 11 10:21:35 studio volumio[1421]: downloadSpeed: '14890.8',
Feb 11 10:21:35 studio volumio[1421]: eta: '4m',
Feb 11 10:21:35 studio volumio[1421]: progress: 48,
Feb 11 10:21:35 studio volumio[1421]: status: 'Downloading new update'
Feb 11 10:21:35 studio volumio[1421]: }
Feb 11 10:21:36 studio volumio-remote-updater[1031]: PROGRESS: 48, STATUS: "Downloading new update", ETA: "4m"
Feb 11 10:21:36 studio volumio-remote-updater[1031]: PROGRESS: 58, STATUS: "Downloading new update", ETA: "4m"
Feb 11 10:21:36 studio volumio-remote-updater[1031]: PROGRESS: 58, STATUS: "Downloading new update", ETA: "4m"
Feb 11 10:21:36 studio volumio-remote-updater[1031]: PROGRESS: 58, STATUS: "Downloading new update", ETA: "4m"
Feb 11 10:21:36 studio volumio-remote-updater[1031]: PROGRESS: 58, STATUS: "Downloading new update", ETA: "4m"
Feb 11 10:21:36 studio volumio-remote-updater[1031]: PROGRESS: 58, STATUS: "Downloading new update", ETA: "4m"
Feb 11 10:21:36 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Feb 11 10:21:36 studio volumio[1421]: updateProgress
Feb 11 10:21:36 studio volumio[1421]: {
Feb 11 10:21:36 studio volumio[1421]: downloadSpeed: '19660.3',
Feb 11 10:21:36 studio volumio[1421]: eta: '4m',
Feb 11 10:21:36 studio volumio[1421]: progress: 58,
Feb 11 10:21:36 studio volumio[1421]: status: 'Downloading new update'
Feb 11 10:21:36 studio volumio[1421]: }
Feb 11 10:21:36 studio sudo[2507]: pam_unix(sudo:session): session closed for user root
Feb 11 10:21:36 studio volumio[1421]: info: Cannot mount NAS at system boot, trial number 4 ,stopping
Feb 11 10:21:36 studio kernel: CIFS: VFS: Error connecting to socket. Aborting operation.
Feb 11 10:21:36 studio kernel: CIFS: VFS: cifs_mount failed w/return code = -113
Feb 11 10:21:37 studio volumio-remote-updater[1031]: PROGRESS: 58, STATUS: "Downloading new update", ETA: "4m"
Feb 11 10:21:37 studio volumio-remote-updater[1031]: PROGRESS: 65, STATUS: "Downloading new update", ETA: "4m"
Feb 11 10:21:37 studio volumio-remote-updater[1031]: PROGRESS: 65, STATUS: "Downloading new update", ETA: "4m"
Feb 11 10:21:37 studio volumio-remote-updater[1031]: PROGRESS: 65, STATUS: "Downloading new update", ETA: "4m"
Feb 11 10:21:37 studio volumio-remote-updater[1031]: PROGRESS: 65, STATUS: "Downloading new update", ETA: "4m"
Feb 11 10:21:37 studio volumio-remote-updater[1031]: PROGRESS: 65, STATUS: "Downloading new update", ETA: "4m"
Feb 11 10:21:37 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Feb 11 10:21:37 studio volumio[1421]: updateProgress
Feb 11 10:21:37 studio volumio[1421]: {
Feb 11 10:21:37 studio volumio[1421]: downloadSpeed: '17134.6',
Feb 11 10:21:37 studio volumio[1421]: eta: '4m',
Feb 11 10:21:37 studio volumio[1421]: progress: 65,
Feb 11 10:21:37 studio volumio[1421]: status: 'Downloading new update'
Feb 11 10:21:37 studio volumio[1421]: }
Feb 11 10:21:37 studio systemd[1]: setdatetime-helper.service: Deactivated successfully.
Feb 11 10:21:37 studio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service.
Feb 11 10:21:38 studio volumio-remote-updater[1031]: PROGRESS: 65, STATUS: "Downloading new update", ETA: "4m"
Feb 11 10:21:38 studio volumio-remote-updater[1031]: PROGRESS: 76, STATUS: "Downloading new update", ETA: "4m"
Feb 11 10:21:38 studio volumio-remote-updater[1031]: PROGRESS: 76, STATUS: "Downloading new update", ETA: "4m"
Feb 11 10:21:38 studio volumio-remote-updater[1031]: PROGRESS: 76, STATUS: "Downloading new update", ETA: "4m"
Feb 11 10:21:38 studio volumio-remote-updater[1031]: PROGRESS: 76, STATUS: "Downloading new update", ETA: "4m"
Feb 11 10:21:38 studio volumio-remote-updater[1031]: PROGRESS: 76, STATUS: "Downloading new update", ETA: "4m"
Feb 11 10:21:38 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Feb 11 10:21:38 studio volumio[1421]: updateProgress
Feb 11 10:21:38 studio volumio[1421]: {
Feb 11 10:21:38 studio volumio[1421]: downloadSpeed: '16992.8',
Feb 11 10:21:38 studio volumio[1421]: eta: '4m',
Feb 11 10:21:38 studio volumio[1421]: progress: 76,
Feb 11 10:21:38 studio volumio[1421]: status: 'Downloading new update'
Feb 11 10:21:38 studio volumio[1421]: }
Feb 11 10:21:38 studio volumio-remote-updater[1031]: PROGRESS: 76, STATUS: "Downloading new update", ETA: "4m"
Feb 11 10:21:38 studio volumio-remote-updater[1031]: PROGRESS: 80, STATUS: "Downloading new update", ETA: "4m"
Feb 11 10:21:38 studio volumio-remote-updater[1031]: PROGRESS: 80, STATUS: "Downloading new update", ETA: "4m"
Feb 11 10:21:38 studio volumio-remote-updater[1031]: PROGRESS: 80, STATUS: "Downloading new update", ETA: "4m"
Feb 11 10:21:38 studio volumio-remote-updater[1031]: PROGRESS: 80, STATUS: "Downloading new update", ETA: "4m"
Feb 11 10:21:38 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Feb 11 10:21:38 studio volumio[1421]: updateProgress
Feb 11 10:21:38 studio volumio[1421]: {
Feb 11 10:21:38 studio volumio[1421]: downloadSpeed: '13913.1',
Feb 11 10:21:38 studio volumio[1421]: eta: '4m',
Feb 11 10:21:38 studio volumio[1421]: progress: 80,
Feb 11 10:21:38 studio volumio[1421]: status: 'Downloading new update'
Feb 11 10:21:38 studio volumio[1421]: }
Feb 11 10:21:40 studio volumio-remote-updater[1031]: PROGRESS: 80, STATUS: "Downloading new update", ETA: "4m"
Feb 11 10:21:40 studio volumio-remote-updater[1031]: PROGRESS: 80, STATUS: "Downloading new update", ETA: "4m"
Feb 11 10:21:40 studio volumio-remote-updater[1031]: PROGRESS: 80, STATUS: "Downloading new update", ETA: "4m"
Feb 11 10:21:40 studio volumio-remote-updater[1031]: PROGRESS: 80, STATUS: "Downloading new update", ETA: "4m"
Feb 11 10:21:40 studio volumio-remote-updater[1031]: PROGRESS: 80, STATUS: "Downloading new update", ETA: "4m"
Feb 11 10:21:40 studio volumio-remote-updater[1031]: PROGRESS: 80, STATUS: "Downloading new update", ETA: "4m"
Feb 11 10:21:40 studio volumio-remote-updater[1031]: PROGRESS: 80, STATUS: "Downloading new update", ETA: "4m"
Feb 11 10:21:40 studio volumio-remote-updater[1031]: PROGRESS: 80, STATUS: "Downloading new update", ETA: "4m"
Feb 11 10:21:40 studio volumio-remote-updater[1031]: PROGRESS: 80, STATUS: "Downloading new update", ETA: "4m"
Feb 11 10:21:40 studio volumio-remote-updater[1031]: zsync done
Feb 11 10:21:40 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Feb 11 10:21:40 studio volumio[1421]: updateProgress
Feb 11 10:21:40 studio volumio[1421]: {
Feb 11 10:21:40 studio volumio[1421]: downloadSpeed: '13913.1',
Feb 11 10:21:40 studio volumio[1421]: eta: '4m',
Feb 11 10:21:40 studio volumio[1421]: progress: 80,
Feb 11 10:21:40 studio volumio[1421]: status: 'Downloading new update'
Feb 11 10:21:40 studio volumio[1421]: }
Feb 11 10:21:40 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Feb 11 10:21:40 studio volumio[1421]: updateProgress
Feb 11 10:21:40 studio volumio[1421]: {
Feb 11 10:21:40 studio volumio[1421]: downloadSpeed: '13913.1',
Feb 11 10:21:40 studio volumio[1421]: eta: '4m',
Feb 11 10:21:40 studio volumio[1421]: progress: 80,
Feb 11 10:21:40 studio volumio[1421]: status: 'Downloading new update'
Feb 11 10:21:40 studio volumio[1421]: }
Feb 11 10:21:40 studio volumio-remote-updater[1031]: PROGRESS: 80, STATUS: "Downloading new update", ETA: "2m"
Feb 11 10:21:40 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Feb 11 10:21:40 studio volumio[1421]: updateProgress
Feb 11 10:21:40 studio volumio[1421]: {
Feb 11 10:21:40 studio volumio[1421]: downloadSpeed: '',
Feb 11 10:21:40 studio volumio[1421]: eta: '2m',
Feb 11 10:21:40 studio volumio[1421]: progress: 80,
Feb 11 10:21:40 studio volumio[1421]: status: 'Downloading new update'
Feb 11 10:21:40 studio volumio[1421]: }
Feb 11 10:21:41 studio volumio-remote-updater[1031]: PROGRESS: 80, STATUS: "Downloading new update", ETA: "2m"
Feb 11 10:21:41 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Feb 11 10:21:41 studio volumio[1421]: updateProgress
Feb 11 10:21:41 studio volumio[1421]: {
Feb 11 10:21:41 studio volumio[1421]: downloadSpeed: '43.0',
Feb 11 10:21:41 studio volumio[1421]: eta: '2m',
Feb 11 10:21:41 studio volumio[1421]: progress: 80,
Feb 11 10:21:41 studio volumio[1421]: status: 'Downloading new update'
Feb 11 10:21:41 studio volumio[1421]: }
Feb 11 10:21:41 studio volumio-remote-updater[2578]: [181B blob data]
Feb 11 10:21:41 studio volumio-remote-updater[2578]: downloading from http://updates2.volumio.org/pi/volumio/4.097/kernel_current.tar:
Feb 11 10:21:41 studio volumio-remote-updater[1031]: PROGRESS: 80, STATUS: "Downloading new update", ETA: "2m"
Feb 11 10:21:41 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Feb 11 10:21:41 studio volumio[1421]: updateProgress
Feb 11 10:21:41 studio volumio[1421]: {
Feb 11 10:21:41 studio volumio[1421]: downloadSpeed: '43.0',
Feb 11 10:21:41 studio volumio[1421]: eta: '2m',
Feb 11 10:21:41 studio volumio[1421]: progress: 80,
Feb 11 10:21:41 studio volumio[1421]: status: 'Downloading new update'
Feb 11 10:21:41 studio volumio[1421]: }
Feb 11 10:21:42 studio volumio-remote-updater[1031]: PROGRESS: 80, STATUS: "Downloading new update", ETA: "2m"
Feb 11 10:21:42 studio volumio-remote-updater[1031]: PROGRESS: 80, STATUS: "Downloading new update", ETA: "2m"
Feb 11 10:21:42 studio volumio-remote-updater[1031]: PROGRESS: 80, STATUS: "Downloading new update", ETA: "2m"
Feb 11 10:21:42 studio volumio-remote-updater[1031]: PROGRESS: 80, STATUS: "Downloading new update", ETA: "2m"
Feb 11 10:21:42 studio volumio-remote-updater[1031]: PROGRESS: 80, STATUS: "Downloading new update", ETA: "2m"
Feb 11 10:21:42 studio volumio-remote-updater[1031]: PROGRESS: 80, STATUS: "Downloading new update", ETA: "2m"
Feb 11 10:21:42 studio volumio-remote-updater[1031]: PROGRESS: 80, STATUS: "Downloading new update", ETA: "2m"
Feb 11 10:21:42 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Feb 11 10:21:42 studio volumio[1421]: updateProgress
Feb 11 10:21:42 studio volumio[1421]: {
Feb 11 10:21:42 studio volumio[1421]: downloadSpeed: '3323.5',
Feb 11 10:21:42 studio volumio[1421]: eta: '2m',
Feb 11 10:21:42 studio volumio[1421]: progress: 80,
Feb 11 10:21:42 studio volumio[1421]: status: 'Downloading new update'
Feb 11 10:21:42 studio volumio[1421]: }
Feb 11 10:21:43 studio volumio-remote-updater[1031]: PROGRESS: 80, STATUS: "Downloading new update", ETA: "2m"
Feb 11 10:21:43 studio volumio-remote-updater[1031]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m"
Feb 11 10:21:43 studio volumio-remote-updater[1031]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m"
Feb 11 10:21:43 studio volumio-remote-updater[1031]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m"
Feb 11 10:21:43 studio volumio-remote-updater[1031]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m"
Feb 11 10:21:43 studio volumio-remote-updater[1031]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m"
Feb 11 10:21:43 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Feb 11 10:21:43 studio volumio[1421]: updateProgress
Feb 11 10:21:43 studio volumio[1421]: {
Feb 11 10:21:43 studio volumio[1421]: downloadSpeed: '16441.5',
Feb 11 10:21:43 studio volumio[1421]: eta: '2m',
Feb 11 10:21:43 studio volumio[1421]: progress: 85,
Feb 11 10:21:43 studio volumio[1421]: status: 'Downloading new update'
Feb 11 10:21:43 studio volumio[1421]: }
Feb 11 10:21:43 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Feb 11 10:21:43 studio volumio[1421]: updateProgress
Feb 11 10:21:43 studio volumio[1421]: {
Feb 11 10:21:43 studio volumio[1421]: downloadSpeed: '16441.5',
Feb 11 10:21:43 studio volumio[1421]: eta: '2m',
Feb 11 10:21:43 studio volumio[1421]: progress: 85,
Feb 11 10:21:43 studio volumio[1421]: status: 'Downloading new update'
Feb 11 10:21:43 studio volumio[1421]: }
Feb 11 10:21:43 studio volumio-remote-updater[1031]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m"
Feb 11 10:21:43 studio volumio-remote-updater[1031]: PROGRESS: 90, STATUS: "Downloading new update", ETA: "2m"
Feb 11 10:21:43 studio volumio-remote-updater[1031]: PROGRESS: 90, STATUS: "Downloading new update", ETA: "2m"
Feb 11 10:21:43 studio volumio-remote-updater[1031]: PROGRESS: 90, STATUS: "Downloading new update", ETA: "2m"
Feb 11 10:21:43 studio volumio-remote-updater[1031]: PROGRESS: 90, STATUS: "Downloading new update", ETA: "2m"
Feb 11 10:21:43 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Feb 11 10:21:43 studio volumio[1421]: updateProgress
Feb 11 10:21:43 studio volumio[1421]: {
Feb 11 10:21:43 studio volumio[1421]: downloadSpeed: '7906.0',
Feb 11 10:21:43 studio volumio[1421]: eta: '2m',
Feb 11 10:21:43 studio volumio[1421]: progress: 90,
Feb 11 10:21:43 studio volumio[1421]: status: 'Downloading new update'
Feb 11 10:21:43 studio volumio[1421]: }
Feb 11 10:21:43 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Feb 11 10:21:43 studio volumio[1421]: updateProgress
Feb 11 10:21:43 studio volumio[1421]: {
Feb 11 10:21:43 studio volumio[1421]: downloadSpeed: '7906.0',
Feb 11 10:21:43 studio volumio[1421]: eta: '2m',
Feb 11 10:21:43 studio volumio[1421]: progress: 90,
Feb 11 10:21:43 studio volumio[1421]: status: 'Downloading new update'
Feb 11 10:21:43 studio volumio[1421]: }
Feb 11 10:21:44 studio volumio-remote-updater[1031]: PROGRESS: 90, STATUS: "Downloading new update", ETA: "2m"
Feb 11 10:21:44 studio volumio-remote-updater[1031]: PROGRESS: 90, STATUS: "Downloading new update", ETA: "2m"
Feb 11 10:21:44 studio volumio-remote-updater[1031]: PROGRESS: 90, STATUS: "Downloading new update", ETA: "2m"
Feb 11 10:21:44 studio volumio-remote-updater[1031]: PROGRESS: 90, STATUS: "Downloading new update", ETA: "2m"
Feb 11 10:21:44 studio volumio-remote-updater[1031]: PROGRESS: 90, STATUS: "Downloading new update", ETA: "2m"
Feb 11 10:21:44 studio volumio-remote-updater[1031]: PROGRESS: 90, STATUS: "Downloading new update", ETA: "2m"
Feb 11 10:21:44 studio volumio-remote-updater[1031]: PROGRESS: 90, STATUS: "Downloading new update", ETA: "2m"
Feb 11 10:21:44 studio volumio-remote-updater[1031]: PROGRESS: 90, STATUS: "Downloading new update", ETA: "2m"
Feb 11 10:21:44 studio volumio-remote-updater[1031]: PROGRESS: 90, STATUS: "Downloading new update", ETA: "2m"
Feb 11 10:21:44 studio volumio-remote-updater[1031]: zsync done
Feb 11 10:21:44 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Feb 11 10:21:44 studio volumio[1421]: updateProgress
Feb 11 10:21:44 studio volumio[1421]: {
Feb 11 10:21:44 studio volumio[1421]: downloadSpeed: '7906.0',
Feb 11 10:21:44 studio volumio[1421]: eta: '2m',
Feb 11 10:21:44 studio volumio[1421]: progress: 90,
Feb 11 10:21:44 studio volumio[1421]: status: 'Downloading new update'
Feb 11 10:21:44 studio volumio[1421]: }
Feb 11 10:21:44 studio volumio-remote-updater[1031]: PROGRESS: 90, STATUS: "Cleaning old files", ETA: "1m"
Feb 11 10:21:44 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Feb 11 10:21:44 studio volumio[1421]: updateProgress
Feb 11 10:21:44 studio volumio[1421]: {
Feb 11 10:21:44 studio volumio[1421]: downloadSpeed: '',
Feb 11 10:21:44 studio volumio[1421]: eta: '1m',
Feb 11 10:21:44 studio volumio[1421]: progress: 90,
Feb 11 10:21:44 studio volumio[1421]: status: 'Cleaning old files'
Feb 11 10:21:44 studio volumio[1421]: }
Feb 11 10:21:44 studio volumio-remote-updater[2584]: /bin/cp: cannot stat '/data/volumio_factory.sqsh': No such file or directory
Feb 11 10:21:44 studio volumio-remote-updater[2588]: /bin/rm: cannot remove '/data/volumio_factory.sqsh': No such file or directory
Feb 11 10:21:44 studio volumio-remote-updater[1031]: PROGRESS: 95, STATUS: "Finalizing update", ETA: "30s"
Feb 11 10:21:44 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Feb 11 10:21:44 studio volumio[1421]: updateProgress
Feb 11 10:21:44 studio volumio[1421]: {
Feb 11 10:21:44 studio volumio[1421]: downloadSpeed: '',
Feb 11 10:21:44 studio volumio[1421]: eta: '30s',
Feb 11 10:21:44 studio volumio[1421]: progress: 95,
Feb 11 10:21:44 studio volumio[1421]: status: 'Finalizing update'
Feb 11 10:21:44 studio volumio[1421]: }
Feb 11 10:21:44 studio volumio-remote-updater[1031]: PROGRESS: 100, STATUS: "success", MESSAGE: "Successfully updated to \"4.097\" version. System restart required."
Feb 11 10:21:44 studio volumio-remote-updater[1031]: Test mode enabled
Feb 11 10:21:44 studio volumio-remote-updater[1031]: Alpha mode disabled
Feb 11 10:21:44 studio volumio-remote-updater[1031]: Alpha legacy test mode disabled
Feb 11 10:21:44 studio volumio-remote-updater[1031]: Test mode enabled
Feb 11 10:21:44 studio volumio-remote-updater[1031]: Alpha mode disabled
Feb 11 10:21:44 studio volumio-remote-updater[1031]: Alpha legacy test mode disabled
Feb 11 10:21:44 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Feb 11 10:21:44 studio volumio[1421]: updateReady
Feb 11 10:21:44 studio volumio[1421]: {
Feb 11 10:21:44 studio volumio[1421]: changeLogLink: 'http://volumio.org',
Feb 11 10:21:44 studio volumio[1421]: description: 'FIXES
\n' +
Feb 11 10:21:44 studio volumio[1421]: '\n' +
Feb 11 10:21:44 studio volumio[1421]: '- Fix for BLE notification issues
\n' +
Feb 11 10:21:44 studio volumio[1421]: '- Fix for mDNS/BCT
\n' +
Feb 11 10:21:44 studio volumio[1421]: '
\n' +
Feb 11 10:21:44 studio volumio[1421]: 'NEW ADDITIONS
\n' +
Feb 11 10:21:44 studio volumio[1421]: '\n' +
Feb 11 10:21:44 studio volumio[1421]: '- Update Tidal SDK
\n' +
Feb 11 10:21:44 studio volumio[1421]: '
\n',
Feb 11 10:21:44 studio volumio[1421]: title: 'Update v4.097',
Feb 11 10:21:44 studio volumio[1421]: updateavailable: true
Feb 11 10:21:44 studio volumio[1421]: }
Feb 11 10:21:44 studio volumio-remote-updater[1031]: Test mode enabled
Feb 11 10:21:44 studio volumio-remote-updater[1031]: Alpha mode disabled
Feb 11 10:21:44 studio volumio-remote-updater[1031]: Alpha legacy test mode disabled
Feb 11 10:21:44 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Feb 11 10:21:44 studio volumio[1421]: updateReady
Feb 11 10:21:44 studio volumio[1421]: {
Feb 11 10:21:44 studio volumio[1421]: changeLogLink: 'http://volumio.org',
Feb 11 10:21:44 studio volumio[1421]: description: 'FIXES
\n' +
Feb 11 10:21:44 studio volumio[1421]: '\n' +
Feb 11 10:21:44 studio volumio[1421]: '- Fix for BLE notification issues
\n' +
Feb 11 10:21:44 studio volumio[1421]: '- Fix for mDNS/BCT
\n' +
Feb 11 10:21:44 studio volumio[1421]: '
\n' +
Feb 11 10:21:44 studio volumio[1421]: 'NEW ADDITIONS
\n' +
Feb 11 10:21:44 studio volumio[1421]: '\n' +
Feb 11 10:21:44 studio volumio[1421]: '- Update Tidal SDK
\n' +
Feb 11 10:21:44 studio volumio[1421]: '
\n',
Feb 11 10:21:44 studio volumio[1421]: title: 'Update v4.097',
Feb 11 10:21:44 studio volumio[1421]: updateavailable: true
Feb 11 10:21:44 studio volumio[1421]: }
Feb 11 10:21:44 studio volumio[1421]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\n- Fix for BLE notification issues
\n- Fix for mDNS/BCT
\n
\nNEW ADDITIONS
\n\n- Update Tidal SDK
\n
\n","title":"Update v4.097","updateavailable":true}
Feb 11 10:21:44 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Feb 11 10:21:44 studio volumio[1421]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\n- Fix for BLE notification issues
\n- Fix for mDNS/BCT
\n
\nNEW ADDITIONS
\n\n- Update Tidal SDK
\n
\n","title":"Update v4.097","updateavailable":true}
Feb 11 10:21:44 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Feb 11 10:21:44 studio volumio[1421]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\n- Fix for BLE notification issues
\n- Fix for mDNS/BCT
\n
\nNEW ADDITIONS
\n\n- Update Tidal SDK
\n
\n","title":"Update v4.097","updateavailable":true}
Feb 11 10:21:44 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Feb 11 10:21:44 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Feb 11 10:21:44 studio volumio[1421]: updateReady
Feb 11 10:21:44 studio volumio[1421]: {
Feb 11 10:21:44 studio volumio[1421]: changeLogLink: 'http://volumio.org',
Feb 11 10:21:44 studio volumio[1421]: description: 'FIXES
\n' +
Feb 11 10:21:44 studio volumio[1421]: '\n' +
Feb 11 10:21:44 studio volumio[1421]: '- Fix for BLE notification issues
\n' +
Feb 11 10:21:44 studio volumio[1421]: '- Fix for mDNS/BCT
\n' +
Feb 11 10:21:44 studio volumio[1421]: '
\n' +
Feb 11 10:21:44 studio volumio[1421]: 'NEW ADDITIONS
\n' +
Feb 11 10:21:44 studio volumio[1421]: '\n' +
Feb 11 10:21:44 studio volumio[1421]: '- Update Tidal SDK
\n' +
Feb 11 10:21:44 studio volumio[1421]: '
\n',
Feb 11 10:21:44 studio volumio[1421]: title: 'Update v4.097',
Feb 11 10:21:44 studio volumio[1421]: updateavailable: true
Feb 11 10:21:44 studio volumio[1421]: }
Feb 11 10:21:45 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Feb 11 10:21:46 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Feb 11 10:21:47 studio volumio[1421]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________
Feb 11 10:21:47 studio volumio[1421]: info: PLUGIN onReboot : networkfs
Feb 11 10:21:47 studio sudo[2599]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/umount -f /mnt/NAS/Music
Feb 11 10:21:47 studio sudo[2599]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 11 10:21:47 studio sudo[2599]: pam_unix(sudo:session): session closed for user root
Feb 11 10:21:47 studio volumio[1421]: umount: /mnt/NAS/Music: not mounted.
Feb 11 10:21:47 studio volumio[1421]: error: Cannot umount share Music : Error: Command failed: /usr/bin/sudo /bin/umount -f /mnt/NAS/Music
Feb 11 10:21:47 studio volumio[1421]: umount: /mnt/NAS/Music: not mounted.
Feb 11 10:21:47 studio volumio[1421]: info: PLUGIN onReboot : touch_display
Feb 11 10:21:47 studio sudo[2602]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service
Feb 11 10:21:47 studio sudo[2602]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 11 10:21:47 studio systemd[1]: Stopping volumio-kiosk.service - Volumio Kiosk...
Feb 11 10:21:47 studio startx[1880]: xinit: connection to X server lost
Feb 11 10:21:47 studio startx[1880]:
Feb 11 10:21:47 studio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Feb 11 10:21:47 studio sudo[2609]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reboot
Feb 11 10:21:47 studio sudo[2609]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 11 10:21:47 studio systemd-logind[1027]: The system will reboot now!
Feb 11 10:21:47 studio systemd-logind[1027]: System is rebooting.
Feb 11 10:21:47 studio sudo[2609]: pam_unix(sudo:session): session closed for user root
Feb 11 10:21:47 studio volumio[1421]: info: Rebooting
Feb 11 10:21:47 studio systemd[1]: Removed slice system-modprobe.slice - Slice /system/modprobe.
Feb 11 10:21:47 studio systemd[1]: Stopped target bluetooth.target - Bluetooth Support.
Feb 11 10:21:47 studio systemd[1]: Stopped target graphical.target - Graphical Interface.
Feb 11 10:21:47 studio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Feb 11 10:21:47 studio systemd[1]: Stopped target multi-user.target - Multi-User System.
Feb 11 10:21:47 studio systemd[1]: Stopped target getty.target - Login Prompts.
Feb 11 10:21:47 studio systemd[1]: Stopped target rpc_pipefs.target.
Feb 11 10:21:47 studio systemd[1]: Stopped target rpcbind.target - RPC Port Mapper.
Feb 11 10:21:47 studio systemd[1]: Stopped target timers.target - Timer Units.
Feb 11 10:21:47 studio systemd[1]: apt-daily-upgrade.timer: Deactivated successfully.
Feb 11 10:21:47 studio systemd[1]: Stopped apt-daily-upgrade.timer - Daily apt upgrade and clean activities.
Feb 11 10:21:47 studio systemd[1]: apt-daily.timer: Deactivated successfully.
Feb 11 10:21:47 studio systemd[1]: Stopped apt-daily.timer - Daily apt download activities.
Feb 11 10:21:47 studio systemd[1]: dpkg-db-backup.timer: Deactivated successfully.
Feb 11 10:21:47 studio systemd[1]: Stopped dpkg-db-backup.timer - Daily dpkg database backup timer.
Feb 11 10:21:47 studio systemd[1]: e2scrub_all.timer: Deactivated successfully.
Feb 11 10:21:47 studio systemd[1]: Stopped e2scrub_all.timer - Periodic ext4 Online Metadata Check for All Filesystems.
Feb 11 10:21:47 studio systemd[1]: fstrim.timer: Deactivated successfully.
Feb 11 10:21:47 studio systemd[1]: Stopped fstrim.timer - Discard unused blocks once a week.
Feb 11 10:21:47 studio systemd[1]: man-db.timer: Deactivated successfully.
Feb 11 10:21:47 studio systemd[1]: Stopped man-db.timer - Daily man-db regeneration.
Feb 11 10:21:47 studio systemd[1]: ntpsec-rotate-stats.timer: Deactivated successfully.
Feb 11 10:21:47 studio systemd[1]: Stopped ntpsec-rotate-stats.timer - Rotate ntpd stats daily.
Feb 11 10:21:47 studio systemd[1]: setdatetime-helper.timer: Deactivated successfully.
Feb 11 10:21:47 studio systemd[1]: Stopped setdatetime-helper.timer - Volumio Time Sync Watchdog Timer.
Feb 11 10:21:47 studio systemd[1]: systemd-tmpfiles-clean.timer: Deactivated successfully.
Feb 11 10:21:47 studio systemd[1]: Stopped systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories.
Feb 11 10:21:47 studio systemd[1]: systemd-rfkill.socket: Deactivated successfully.
Feb 11 10:21:47 studio systemd[1]: Closed systemd-rfkill.socket - Load/Save RF Kill Switch Status /dev/rfkill Watch.
Feb 11 10:21:47 studio systemd[1]: Unmounting run-rpc_pipefs.mount - RPC Pipe File System...
Feb 11 10:21:47 studio bluealsa[1179]: ../src/ba-adapter.c:144: Freeing adapter: hci0
Feb 11 10:21:47 studio bluetoothd[1011]: Endpoint unregistered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/source/1
Feb 11 10:21:47 studio systemd[1]: Stopping bluealsa.service - BlueALSA service...
Feb 11 10:21:47 studio bluetoothd[1011]: Endpoint unregistered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/sink/2
Feb 11 10:21:47 studio bluetoothd[1011]: Endpoint unregistered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/source/2
Feb 11 10:21:47 studio bluetoothd[1011]: Endpoint unregistered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/sink/1
Feb 11 10:21:47 studio systemd[1]: Stopping cpufrequtils.service - LSB: set CPUFreq kernel parameters...
Feb 11 10:21:47 studio systemd[1]: glamor-test.service: Deactivated successfully.
Feb 11 10:21:47 studio systemd[1]: Stopped glamor-test.service - Check for glamor.
Feb 11 10:21:47 studio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon...
Feb 11 10:21:47 studio volumio[1421]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Feb 11 10:21:47 studio systemd[1]: Stopping ifplugd.service - LSB: Brings up/down network automatically...
Feb 11 10:21:47 studio volumio[1421]: Error: socket hang up
Feb 11 10:21:47 studio volumio[1421]: at connResetException (node:internal/errors:720:14)
Feb 11 10:21:47 studio volumio[1421]: at Socket.socketOnEnd (node:_http_client:519:23)
Feb 11 10:21:47 studio volumio[1421]: at Socket.emit (node:events:526:35)
Feb 11 10:21:47 studio volumio[1421]: at endReadableNT (node:internal/streams/readable:1376:12)
Feb 11 10:21:47 studio volumio[1421]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) {
Feb 11 10:21:47 studio volumio[1421]: code: 'ECONNRESET',
Feb 11 10:21:47 studio volumio[1421]: response: undefined
Feb 11 10:21:47 studio volumio[1421]: }
Feb 11 10:21:47 studio volumio[1421]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Feb 11 10:21:47 studio systemd[1]: Stopping mpd.service - Music Player Daemon...
Feb 11 10:21:47 studio ntpd[1249]: ERR: ntpd exiting on signal 15 (Terminated)
Feb 11 10:21:47 studio systemd[1]: Stopping ntpsec.service - Network Time Service...
Feb 11 10:21:47 studio ntpd[1249]: PROTO: 194.213.3.203 unlink local addr 192.168.0.236 ->
Feb 11 10:21:47 studio systemd[1]: plymouth-quit.service: Deactivated successfully.
Feb 11 10:21:47 studio ntpd[1249]: PROTO: 91.109.118.94 unlink local addr 192.168.0.236 ->
Feb 11 10:21:47 studio systemd[1]: Stopped plymouth-quit.service - Terminate Plymouth Boot Screen.
Feb 11 10:21:47 studio ntpd[1249]: PROTO: 217.154.60.177 unlink local addr 192.168.0.236 ->
Feb 11 10:21:47 studio systemd[1]: rp1-test.service: Deactivated successfully.
Feb 11 10:21:47 studio ntpd[1249]: PROTO: 176.58.120.34 unlink local addr 192.168.0.236 ->
Feb 11 10:21:47 studio systemd[1]: Stopped rp1-test.service - Check for RP1 displays for Xorg.
Feb 11 10:21:47 studio ntpd[1249]: PROTO: 212.71.233.40 unlink local addr 192.168.0.236 ->
Feb 11 10:21:47 studio systemd[1]: rpi-display-backlight.service - Turns off Raspberry Pi display backlight on shutdown/reboot was skipped because of an unmet condition check (ConditionPathIsDirectory=/proc/device-tree/rpi_backlight).
Feb 11 10:21:47 studio ntpd[1249]: PROTO: 162.159.200.123 unlink local addr 192.168.0.236 ->
Feb 11 10:21:47 studio ntpd[1249]: PROTO: 178.62.68.79 unlink local addr 192.168.0.236 ->
Feb 11 10:21:47 studio ntpd[1249]: PROTO: 85.199.214.98 unlink local addr 192.168.0.236 ->
Feb 11 10:21:47 studio ntpd[1249]: PROTO: 176.58.124.166 unlink local addr 192.168.0.236 ->
Feb 11 10:21:47 studio ntpd[1249]: PROTO: 131.111.8.61 unlink local addr 192.168.0.236 ->
Feb 11 10:21:47 studio ntpd[1249]: PROTO: 139.143.5.30 unlink local addr 192.168.0.236 ->
Feb 11 10:21:47 studio ntpd[1249]: PROTO: 212.71.233.44 unlink local addr 192.168.0.236 ->
Feb 11 10:21:47 studio ntpd[1249]: PROTO: 178.215.228.24 unlink local addr 192.168.0.236 ->
Feb 11 10:21:47 studio ntpd[1249]: PROTO: 185.57.191.230 unlink local addr 192.168.0.236 ->
Feb 11 10:21:47 studio systemd[1]: Stopping serial-getty@ttyAMA10.service - Serial Getty on ttyAMA10...
Feb 11 10:21:47 studio ntpd[1249]: PROTO: 178.79.158.157 unlink local addr 192.168.0.236 ->
Feb 11 10:21:47 studio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Feb 11 10:21:47 studio systemd[1]: Stopping smbd.service - Samba SMB Daemon...
Feb 11 10:21:47 studio systemd[1]: Stopping systemd-logind.service - User Login Management...
Feb 11 10:21:47 studio kernel: drm-rp1-dsi 1f00118000.dsi: [drm] rp1dsi: Command time (outvact): 11
Feb 11 10:21:47 studio kernel: drm-rp1-dsi 1f00118000.dsi: [drm] rp1dsi: Nominal Byte clock 125000000 DPI clock 83333333 (parent rate 125000000)
Feb 11 10:21:47 studio systemd[1]: Stopping systemd-random-seed.service - Load/Save Random Seed...
Feb 11 10:21:47 studio systemd[1]: Stopping triggerhappy.service - triggerhappy global hotkey daemon...
Feb 11 10:21:47 studio systemd[1]: Stopping upmpdcli.service - UPnP Renderer front-end to MPD...
Feb 11 10:21:47 studio systemd[1]: Stopping upower.service - Daemon for power management...
Feb 11 10:21:47 studio systemd[1]: Stopping volumio-ipchange.service - Volumio IP Change Monitor...
Feb 11 10:21:47 studio systemd[1]: Stopping volumio-remote-updater.service - Volumio Remote OTA Updater...
Feb 11 10:21:47 studio systemd[1]: Stopping volumio5-onboarding.service - Volumio5 Onboarding Server...
Feb 11 10:21:47 studio systemd[1]: welcome.service: Deactivated successfully.
Feb 11 10:21:47 studio systemd[1]: Stopped welcome.service - Show a welcome message on console.
Feb 11 10:21:47 studio systemd[1]: volumio-remote-updater.service: Deactivated successfully.
Feb 11 10:21:47 studio systemd[1]: Stopped volumio-remote-updater.service - Volumio Remote OTA Updater.
Feb 11 10:21:47 studio systemd[1]: volumio-remote-updater.service: Consumed 12.655s CPU time.
Feb 11 10:21:47 studio systemd[1]: bluealsa.service: Deactivated successfully.
Feb 11 10:21:47 studio systemd[1]: Stopped bluealsa.service - BlueALSA service.
Feb 11 10:21:47 studio systemd[1]: volumio-ipchange.service: Deactivated successfully.
Feb 11 10:21:47 studio systemd[1]: Stopped volumio-ipchange.service - Volumio IP Change Monitor.
Feb 11 10:21:47 studio systemd[1]: ntpsec.service: Deactivated successfully.
Feb 11 10:21:47 studio systemd[1]: Stopped ntpsec.service - Network Time Service.
Feb 11 10:21:47 studio systemd[1]: upower.service: Deactivated successfully.
Feb 11 10:21:47 studio systemd[1]: Stopped upower.service - Daemon for power management.
Feb 11 10:21:47 studio systemd[1]: serial-getty@ttyAMA10.service: Deactivated successfully.
Feb 11 10:21:47 studio systemd[1]: Stopped serial-getty@ttyAMA10.service - Serial Getty on ttyAMA10.
Feb 11 10:21:47 studio sudo[2645]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-11 10:20'
Feb 11 10:21:47 studio sudo[2645]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)"
NAME="Raspbian GNU/Linux"
VERSION_ID="12"
VERSION="12 (bookworm)"
VERSION_CODENAME=bookworm
ID=raspbian
ID_LIKE=debian
HOME_URL="http://www.raspbian.org/"
SUPPORT_URL="http://www.raspbian.org/RaspbianForums"
BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs"
VOLUMIO_BUILD_VERSION="78457f783d63b89c4d83aa18ac27544b9e82ea5a"
VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd"
VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2"
VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Sat Jan 17 12:50:56 UTC 2026"
VOLUMIO_VERSION="4.088"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="5e6591c42f2c96d67eeb9dd31105c420"