Feb 24 13:58:00 volumio sudo[1171]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 24 13:58:00 volumio sudo[1171]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 24 13:58:00 volumio sudo[1171]: pam_unix(sudo:session): session closed for user root Feb 24 13:58:00 volumio sudo[1174]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Feb 24 13:58:00 volumio sudo[1174]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 24 13:58:00 volumio sudo[1174]: pam_unix(sudo:session): session closed for user root Feb 24 13:58:00 volumio wpa_supplicant[1177]: Successfully initialized wpa_supplicant Feb 24 13:58:00 volumio ntpd[862]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 24 13:58:00 volumio ntpd[862]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Feb 24 13:58:00 volumio ntpd[862]: DNS: Pool taking: 5.250.191.170 Feb 24 13:58:00 volumio ntpd[862]: DNS: Pool taking: 162.159.200.1 Feb 24 13:58:00 volumio ntpd[862]: DNS: Pool taking: 94.143.139.219 Feb 24 13:58:00 volumio ntpd[862]: DNS: Pool taking: 84.77.195.114 Feb 24 13:58:00 volumio ntpd[862]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Feb 24 13:58:00 volumio wpa_supplicant[1177]: nl80211: kernel reports: Registration to specific type not supported Feb 24 13:58:00 volumio wpa_supplicant[1181]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all Feb 24 13:58:00 volumio wireless.js[690]: WIRELESS.JS - INFO: SNM: Transition to scan mode completed in 28030ms Feb 24 13:58:00 volumio wireless.js[690]: WIRELESS.JS - INFO: SNM: wlan0 is UP without IP, scan capable Feb 24 13:58:01 volumio wireless.js[690]: WIRELESS.JS - INFO: Notified systemd about wireless ready Feb 24 13:58:01 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Feb 24 13:58:01 volumio systemd[1]: Started wireless.service - Wireless Services. Feb 24 13:58:01 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 24 13:58:01 volumio systemd[1]: Starting plymouth-quit.service - Terminate Plymouth Boot Screen... Feb 24 13:58:01 volumio systemd[1]: Started volumio_cpu_tweak.service - Volumio Cpu Tweaker. Feb 24 13:58:01 volumio systemd[1]: Received SIGRTMIN+21 from PID 290 (plymouthd). Feb 24 13:58:01 volumio systemd[1]: Received SIGRTMIN+21 from PID 290 (plymouthd). Feb 24 13:58:01 volumio systemd[1]: Finished plymouth-quit-wait.service - Hold until boot process finishes up. Feb 24 13:58:01 volumio systemd[1]: Finished plymouth-quit.service - Terminate Plymouth Boot Screen. Feb 24 13:58:01 volumio systemd[1]: Started getty@tty1.service - Getty on tty1. Feb 24 13:58:01 volumio systemd[1]: Reached target getty.target - Login Prompts. Feb 24 13:58:01 volumio systemd[1]: Reached target multi-user.target - Multi-User System. Feb 24 13:58:01 volumio systemd[1]: Reached target graphical.target - Graphical Interface. Feb 24 13:58:01 volumio systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Feb 24 13:58:01 volumio volumio-cpu-tweak[1200]: Setting RT Priority for mpd Feb 24 13:58:01 volumio volumio-cpu-tweak[1216]: pid 35's current scheduling policy: SCHED_OTHER Feb 24 13:58:01 volumio volumio-cpu-tweak[1216]: pid 35's current scheduling priority: 0 Feb 24 13:58:01 volumio volumio-cpu-tweak[1200]: Setting MPD Affinity Feb 24 13:58:01 volumio volumio-cpu-tweak[1217]: pid 3's current affinity mask: f Feb 24 13:58:01 volumio volumio-cpu-tweak[1200]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Feb 24 13:58:01 volumio volumio-cpu-tweak[1200]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Feb 24 13:58:01 volumio systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Feb 24 13:58:01 volumio systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Feb 24 13:58:01 volumio systemd[1]: Startup finished in 15.115s (kernel) + 16.346s (userspace) = 31.461s. Feb 24 13:58:01 volumio systemd[1]: volumio_cpu_tweak.service: Deactivated successfully. Feb 24 13:58:04 volumio volumio-remote-updater[688]: [2026-02-24 13:58:04] [info] asio async_connect error: asio.system:111 (Connection refused) Feb 24 13:58:04 volumio volumio-remote-updater[688]: [2026-02-24 13:58:04] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Feb 24 13:58:04 volumio volumio-remote-updater[688]: [2026-02-24 13:58:04] [error] handle_connect error: Connection refused Feb 24 13:58:06 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1. Feb 24 13:58:06 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 24 13:58:06 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 24 13:58:06 volumio upmpdcli[1221]: Could not open config: /tmp/upmpdcli.conf Feb 24 13:58:06 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 24 13:58:06 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 24 13:58:06 volumio kernel: hwmon hwmon1: Voltage normalised Feb 24 13:58:07 volumio volumio[1198]: info: ------------------------------------------- Feb 24 13:58:07 volumio volumio[1198]: info: ----- Volumio3 ---- Feb 24 13:58:07 volumio volumio[1198]: info: ------------------------------------------- Feb 24 13:58:07 volumio volumio[1198]: info: ----- System startup ---- Feb 24 13:58:07 volumio volumio[1198]: info: ------------------------------------------- Feb 24 13:58:09 volumio volumio[1198]: info: MYVOLUMIO Environment detected Feb 24 13:58:09 volumio volumio[1198]: info: Plugin folders cleanup Feb 24 13:58:09 volumio volumio[1198]: info: Scanning into folder /volumio/app/plugins/ Feb 24 13:58:09 volumio volumio[1198]: info: Scanning category audio_interface Feb 24 13:58:09 volumio volumio[1198]: info: Scanning category miscellanea Feb 24 13:58:09 volumio volumio[1198]: info: Scanning category music_service Feb 24 13:58:09 volumio volumio[1198]: info: Scanning category plugins.json Feb 24 13:58:09 volumio volumio[1198]: info: Scanning category system_controller Feb 24 13:58:09 volumio volumio[1198]: info: Scanning category user_interface Feb 24 13:58:09 volumio volumio[1198]: info: Scanning into folder /data/plugins/ Feb 24 13:58:09 volumio volumio[1198]: info: Scanning category audio_interface Feb 24 13:58:09 volumio volumio[1198]: info: Scanning category music_service Feb 24 13:58:09 volumio volumio[1198]: info: Scanning category system_hardware Feb 24 13:58:09 volumio volumio[1198]: info: Scanning category user_interface Feb 24 13:58:09 volumio volumio[1198]: info: Plugin folders cleanup completed Feb 24 13:58:09 volumio volumio[1198]: info: ------------------------------------------- Feb 24 13:58:09 volumio volumio[1198]: info: ----- Core plugins startup ---- Feb 24 13:58:09 volumio volumio[1198]: info: ------------------------------------------- Feb 24 13:58:09 volumio volumio[1198]: info: Loading plugins from folder /volumio/app/plugins/ Feb 24 13:58:09 volumio volumio[1198]: info: Adding plugin upnp to MyMusic Plugins Feb 24 13:58:09 volumio volumio[1198]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 24 13:58:09 volumio volumio[1198]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 24 13:58:09 volumio volumio[1198]: info: Loading plugins from folder /data/plugins/ Feb 24 13:58:09 volumio volumio[1198]: info: Adding plugin rtlsdr_radio to MyMusic Plugins Feb 24 13:58:09 volumio volumio[1198]: info: Loading plugin "system"... Feb 24 13:58:10 volumio volumio[1198]: info: Loading plugin "appearance"... Feb 24 13:58:12 volumio volumio[1198]: info: Loading plugin "network"... Feb 24 13:58:12 volumio volumio[1198]: info: Refreshing Cached IP Addresses Feb 24 13:58:12 volumio sudo[1237]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 24 13:58:12 volumio sudo[1237]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 13:58:12 volumio volumio[1198]: info: Loading plugin "services"... Feb 24 13:58:12 volumio sudo[1238]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 13:58:12 volumio sudo[1237]: pam_unix(sudo:session): session closed for user root Feb 24 13:58:12 volumio sudo[1245]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 24 13:58:12 volumio sudo[1245]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 13:58:12 volumio sudo[1238]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 13:58:12 volumio volumio[1198]: info: Loading plugin "volumio5onboarding"... Feb 24 13:58:12 volumio sudo[1238]: pam_unix(sudo:session): session closed for user root Feb 24 13:58:12 volumio volumio[1198]: info: Loading plugin "alsa_controller"... Feb 24 13:58:12 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 24 13:58:12 volumio volumio[1198]: info: Loading plugin "wizard"... Feb 24 13:58:12 volumio volumio[1198]: info: Loading plugin "networkfs"... Feb 24 13:58:12 volumio kernel: hwmon hwmon1: Undervoltage detected! Feb 24 13:58:12 volumio volumio[1198]: info: Starting Udev Watcher for removable devices Feb 24 13:58:13 volumio sudo[1268]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Admin,password=Qlfta16030924,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.20/music /mnt/NAS/Nas Feb 24 13:58:13 volumio sudo[1268]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 13:58:13 volumio volumio[1198]: info: Ignoring mount for partition: boot Feb 24 13:58:13 volumio volumio[1198]: info: Ignoring mount for partition: volumio Feb 24 13:58:13 volumio volumio[1198]: info: Ignoring mount for partition: volumio_data Feb 24 13:58:13 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 24 13:58:13 volumio volumio[1198]: info: Loading plugin "volumio_command_line_client"... Feb 24 13:58:13 volumio volumio[1198]: info: Loading plugin "upnp"... Feb 24 13:58:13 volumio volumio[1198]: info: [1771937893211] Starting Upmpd Daemon Feb 24 13:58:13 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 24 13:58:13 volumio volumio[1198]: info: Loading plugin "my_music"... Feb 24 13:58:13 volumio volumio[1198]: info: Loading plugin "mpd"... Feb 24 13:58:13 volumio kernel: netfs: FS-Cache loaded Feb 24 13:58:13 volumio kernel: Key type cifs.spnego registered Feb 24 13:58:13 volumio kernel: Key type cifs.idmap registered Feb 24 13:58:13 volumio kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Feb 24 13:58:13 volumio kernel: CIFS: Attempting to mount //192.168.0.20/music Feb 24 13:58:14 volumio volumio-remote-updater[688]: [2026-02-24 13:58:14] [connect] Successful connection Feb 24 13:58:14 volumio sudo[1268]: pam_unix(sudo:session): session closed for user root Feb 24 13:58:14 volumio volumio[1198]: info: Loading plugin "upnp_browser"... Feb 24 13:58:15 volumio sudo[1245]: pam_unix(sudo:session): session closed for user root Feb 24 13:58:17 volumio volumio[1198]: info: Starting UPNP Browser Feb 24 13:58:17 volumio volumio[1198]: info: Loading plugin "alarm-clock"... Feb 24 13:58:18 volumio volumio[1198]: info: Loading plugin "airplay_emulation"... Feb 24 13:58:18 volumio volumio[1198]: info: Starting Shairport Sync Feb 24 13:58:18 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully. Feb 24 13:58:18 volumio volumio[1198]: info: Loading plugin "last_100"... Feb 24 13:58:18 volumio volumio[1198]: info: Loading plugin "webradio"... Feb 24 13:58:18 volumio volumio[1198]: info: Loading plugin "i2s_dacs"... Feb 24 13:58:18 volumio volumio[1198]: info: Loading plugin "volumiodiscovery"... Feb 24 13:58:18 volumio volumio[1198]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 24 13:58:18 volumio node[1198]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 24 13:58:18 volumio volumio[1198]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 24 13:58:18 volumio node[1198]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 24 13:58:18 volumio volumio[1198]: *** WARNING *** For more information see Feb 24 13:58:18 volumio node[1198]: *** WARNING *** For more information see Feb 24 13:58:18 volumio volumio[1198]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 24 13:58:18 volumio node[1198]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 24 13:58:18 volumio volumio[1198]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 24 13:58:18 volumio volumio[1198]: *** WARNING *** For more information see Feb 24 13:58:18 volumio node[1198]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 24 13:58:18 volumio node[1198]: *** WARNING *** For more information see Feb 24 13:58:18 volumio volumio[1198]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 24 13:58:18 volumio volumio[1198]: info: Discovery: Started advertising with name: Volumio Feb 24 13:58:18 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 24 13:58:18 volumio volumio[1198]: info: Loading plugin "spop"... Feb 24 13:58:19 volumio kernel: hwmon hwmon1: Voltage normalised Feb 24 13:58:21 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Feb 24 13:58:21 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 24 13:58:21 volumio volumio[1198]: info: Loading plugin "ytmusic"... Feb 24 13:58:21 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 24 13:58:21 volumio upmpdcli[1308]: Could not open config: /tmp/upmpdcli.conf Feb 24 13:58:21 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 24 13:58:21 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 24 13:58:23 volumio volumio[1198]: info: Loading plugin "outputs"... Feb 24 13:58:23 volumio volumio[1198]: info: Loading plugin "albumart"... Feb 24 13:58:23 volumio volumio[1198]: info: Plugin example_plugin is not enabled Feb 24 13:58:23 volumio volumio[1198]: info: Loading plugin "inputs"... Feb 24 13:58:23 volumio volumio[1198]: info: Loading plugin "updater_comm"... Feb 24 13:58:23 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. Feb 24 13:58:23 volumio volumio[1198]: info: Plugin mpdemulation is not enabled Feb 24 13:58:23 volumio volumio[1198]: info: Loading plugin "rest_api"... Feb 24 13:58:23 volumio volumio[1198]: info: Loading plugin "websocket"... Feb 24 13:58:23 volumio volumio[1198]: info: Starting Socket.io Server version 1.7.4 Feb 24 13:58:23 volumio volumio[1198]: info: Loading plugin "mpdoutput"... Feb 24 13:58:24 volumio volumio[1198]: info: Loading plugin "podcast"... Feb 24 13:58:25 volumio volumio[1323]: Forking 3 albumart workers Feb 24 13:58:25 volumio volumio[1198]: info: ControllerPodcast::constructor Feb 24 13:58:26 volumio volumio[1198]: info: Loading plugin "rtlsdr_radio"... Feb 24 13:58:29 volumio volumio-remote-updater[688]: [2026-02-24 13:58:29] [connect] Successful connection Feb 24 13:58:33 volumio volumio[1198]: info: [RTL-SDR Radio] Loaded FM region data Feb 24 13:58:33 volumio volumio[1198]: info: Loading plugin "ir_controller"... Feb 24 13:58:33 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Feb 24 13:58:34 volumio volumio[1336]: Starting albumart workers Feb 24 13:58:34 volumio volumio[1338]: Starting albumart workers Feb 24 13:58:35 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Feb 24 13:58:35 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Feb 24 13:58:35 volumio volumio[1337]: Starting albumart workers Feb 24 13:58:35 volumio volumio[1198]: info: Applying required configuration parameters for plugin ir_controller Feb 24 13:58:36 volumio volumio[1198]: info: Loading plugin "Systeminfo"... Feb 24 13:58:36 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Feb 24 13:58:36 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 24 13:58:37 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 24 13:58:37 volumio upmpdcli[1405]: Could not open config: /tmp/upmpdcli.conf Feb 24 13:58:37 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 24 13:58:37 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 24 13:58:40 volumio volumio[1198]: info: Loading i18n strings for locale es Feb 24 13:58:40 volumio volumio[1198]: error: ir_controller: Fetching language file: Error: i18n file complementing the system language not found. Feb 24 13:58:40 volumio volumio[1198]: Updating browse sources language Feb 24 13:58:40 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 13:58:42 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 13:58:42 volumio volumio[1198]: info: CoreCommandRouter::initPlayerControls Feb 24 13:58:42 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 13:58:42 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 13:58:42 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 13:58:42 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 13:58:42 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 13:58:42 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 13:58:42 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 13:58:42 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 13:58:42 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 13:58:42 volumio volumio[1198]: Express server listening on port 3000 Feb 24 13:58:42 volumio volumio[1198]: [Metrics] WebUI: 35s 840.60ms Feb 24 13:58:42 volumio volumio[1198]: info: CoreStateMachine::resetVolumioState Feb 24 13:58:42 volumio volumio[1198]: info: CoreStateMachine::getcurrentVolume Feb 24 13:58:42 volumio volumio[1198]: info: CoreCommandRouter::volumioRetrievevolume Feb 24 13:58:42 volumio volumio[1198]: info: CoreStateMachine::pushState Feb 24 13:58:42 volumio volumio[1198]: info: CorePlayQueue::getTrack 0 Feb 24 13:58:42 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 13:58:42 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Feb 24 13:58:42 volumio sudo[1430]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 13:58:42 volumio sudo[1430]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 13:58:42 volumio sudo[1428]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 24 13:58:42 volumio sudo[1428]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 13:58:42 volumio sudo[1430]: pam_unix(sudo:session): session closed for user root Feb 24 13:58:42 volumio sudo[1428]: pam_unix(sudo:session): session closed for user root Feb 24 13:58:42 volumio volumio[1198]: info: Volumio Network Manager: Network status updated: 1 Feb 24 13:58:43 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 13:58:43 volumio volumio[1198]: info: Executing start script for DAC Volumio Zero ES9039 DAC Feb 24 13:58:43 volumio volumio[1198]: info: Reloading queue from file Feb 24 13:58:43 volumio volumio[1198]: info: CoreStateMachine::setRepeat null single undefined Feb 24 13:58:43 volumio volumio[1198]: info: CoreStateMachine::pushState Feb 24 13:58:43 volumio volumio[1198]: info: CorePlayQueue::getTrack 0 Feb 24 13:58:43 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 13:58:43 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Feb 24 13:58:43 volumio sudo[1457]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x48 0x00 0x02 Feb 24 13:58:43 volumio sudo[1457]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 13:58:43 volumio volumio[1198]: info: CoreStateMachine::setRandom null Feb 24 13:58:43 volumio volumio[1198]: info: CoreStateMachine::pushState Feb 24 13:58:43 volumio volumio[1198]: info: CorePlayQueue::getTrack 0 Feb 24 13:58:43 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Feb 24 13:58:43 volumio volumio[1198]: info: Setting Device type: Raspberry PI Feb 24 13:58:43 volumio sudo[1457]: pam_unix(sudo:session): session closed for user root Feb 24 13:58:44 volumio volumio[1198]: info: DAC script executed Feb 24 13:58:44 volumio volumio-remote-updater[688]: [2026-02-24 13:58:44] [connect] Successful connection Feb 24 13:58:44 volumio volumio[1198]: info: Completed loading Core Plugins Feb 24 13:58:44 volumio volumio[1198]: info: Preparing to generate the ALSA configuration file Feb 24 13:58:44 volumio sudo[1462]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 24 13:58:44 volumio sudo[1462]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 13:58:44 volumio volumio-remote-updater[688]: [2026-02-24 13:58:44] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1771937924 101 Feb 24 13:58:44 volumio volumio[1198]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 1 Feb 24 13:58:44 volumio volumio[1198]: info: Asound.conf file unchanged, so no further update is needed Feb 24 13:58:44 volumio volumio[1198]: info: Output device has changed, restarting MPD Feb 24 13:58:44 volumio volumio[1198]: info: Output device has changed, restarting Shairport Sync Feb 24 13:58:44 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 13:58:44 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 13:58:44 volumio sudo[1467]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 24 13:58:44 volumio sudo[1467]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 13:58:44 volumio sudo[1465]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 24 13:58:44 volumio sudo[1465]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 13:58:44 volumio sudo[1465]: pam_unix(sudo:session): session closed for user root Feb 24 13:58:44 volumio volumio[1198]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 24 13:58:44 volumio volumio[1198]: info: ___________ START PLUGINS ___________ Feb 24 13:58:44 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 24 13:58:44 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 24 13:58:44 volumio volumio[1198]: info: ControllerMpd::onStart: Initializing MPD Feb 24 13:58:44 volumio volumio[1198]: info: Creating MPD Configuration file Feb 24 13:58:44 volumio sudo[1476]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Feb 24 13:58:44 volumio sudo[1476]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 13:58:44 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 13:58:44 volumio volumio[1198]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 24 13:58:44 volumio volumio[1198]: info: [1771937924997] CoreMusicLibrary::Adding element Media Servers Feb 24 13:58:44 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 13:58:45 volumio sudo[1480]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 24 13:58:45 volumio sudo[1480]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 13:58:45 volumio volumio[1198]: info: UPNP Browser: Client initialized successfully Feb 24 13:58:45 volumio sudo[1482]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 24 13:58:45 volumio sudo[1482]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 13:58:45 volumio sudo[1477]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 24 13:58:45 volumio sudo[1477]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 24 13:58:45 volumio sudo[1480]: pam_unix(sudo:session): session closed for user root Feb 24 13:58:45 volumio sudo[1485]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Feb 24 13:58:45 volumio sudo[1477]: pam_unix(sudo:session): session closed for user root Feb 24 13:58:45 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Feb 24 13:58:45 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 13:58:45 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 13:58:45 volumio sudo[1476]: pam_unix(sudo:session): session closed for user root Feb 24 13:58:45 volumio volumio[1198]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 24 13:58:45 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 13:58:45 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 13:58:45 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 24 13:58:45 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 24 13:58:45 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 24 13:58:45 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 24 13:58:45 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 24 13:58:45 volumio volumio[1198]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 24 13:58:45 volumio volumio[1198]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 24 13:58:45 volumio volumio[1198]: info: [1771937925417] CoreMusicLibrary::Adding element Last_100 Feb 24 13:58:45 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 13:58:45 volumio volumio[1198]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 24 13:58:45 volumio volumio[1198]: info: [1771937925430] CoreMusicLibrary::Adding element Webradio Feb 24 13:58:45 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 13:58:45 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 24 13:58:45 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 24 13:58:45 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 24 13:58:45 volumio volumio[1198]: info: Initializing BBC Radios Feb 24 13:58:45 volumio sudo[1504]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 24 13:58:45 volumio sudo[1504]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 24 13:58:45 volumio sudo[1518]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Feb 24 13:58:45 volumio sudo[1504]: pam_unix(sudo:session): session closed for user root Feb 24 13:58:45 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 24 13:58:45 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 13:58:45 volumio volumio5-onboarding[1484]: time=2026-02-24T13:58:45.895+01:00 level=INFO msg="running volumio5-device-gateway" version=30d1950e+CHANGES buildDate=2026-01-29T13:10:08Z Feb 24 13:58:45 volumio volumio[1198]: info: Creating Spotify config file Feb 24 13:58:45 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 13:58:48 volumio volumio[1198]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 24 13:58:48 volumio volumio[1198]: info: [1771937928212] CoreMusicLibrary::Adding element YouTube Music Feb 24 13:58:48 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 13:58:48 volumio volumio[1198]: Cannot find translation for source YouTube Music Feb 24 13:58:48 volumio volumio[1198]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 24 13:58:48 volumio volumio[1198]: info: [1771937928342] CoreMusicLibrary::Adding element Podcast Feb 24 13:58:48 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 13:58:48 volumio volumio[1198]: Cannot find translation for source YouTube Music Feb 24 13:58:48 volumio volumio[1198]: Cannot find translation for source Podcast Feb 24 13:58:48 volumio volumio[1198]: info: [RTL-SDR Radio] Starting plugin Feb 24 13:58:48 volumio volumio[1198]: info: [RTL-SDR Radio] Loaded i18n strings for language: es Feb 24 13:58:48 volumio volumio[1198]: info: Loading i18n strings for locale es Feb 24 13:58:48 volumio volumio[1198]: error: ir_controller: Fetching language file: Error: i18n file complementing the system language not found. Feb 24 13:58:48 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 24 13:58:48 volumio volumio[1198]: info: Volumio Calling Home Feb 24 13:58:49 volumio volumio[1198]: info: [RTL-SDR Radio] snd-aloop already loaded Feb 24 13:58:50 volumio volumio[1198]: info: [RTL-SDR Radio] No stations database found, creating v2 Feb 24 13:58:50 volumio volumio[1198]: info: [RTL-SDR Radio] Database loaded at: 2026-02-24T12:58:50.033Z Feb 24 13:58:50 volumio volumio[1198]: info: [RTL-SDR Radio] Loading blocklist from: /data/plugins/music_service/rtlsdr_radio/blocklist.json Feb 24 13:58:50 volumio volumio[1198]: info: [RTL-SDR Radio] Got 21 phrases from file Feb 24 13:58:50 volumio volumio[1198]: info: [RTL-SDR Radio] Updated metadata blocklist Feb 24 13:58:50 volumio volumio[1198]: info: [RTL-SDR Radio] Metadata module now has 21 phrases Feb 24 13:58:50 volumio sudo[1533]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R a+rwX /etc/lirc/irexec.lircrc /etc/lirc/lirc_options.conf /etc/lirc/lircd.conf /etc/lirc/lircd.conf.d /etc/lirc/lircmd.conf /etc/lirc/lircrc Feb 24 13:58:50 volumio sudo[1533]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 13:58:50 volumio sudo[1533]: pam_unix(sudo:session): session closed for user root Feb 24 13:58:51 volumio volumio[1198]: info: [RTL-SDR Radio] Management server started on port 3456 Feb 24 13:58:51 volumio volumio[1198]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 24 13:58:51 volumio volumio[1198]: info: [1771937931672] CoreMusicLibrary::Adding element FM/DAB Radio Feb 24 13:58:51 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 13:58:51 volumio volumio[1198]: Cannot find translation for source YouTube Music Feb 24 13:58:51 volumio volumio[1198]: Cannot find translation for source Podcast Feb 24 13:58:51 volumio volumio[1198]: Cannot find translation for source FM/DAB Radio Feb 24 13:58:51 volumio volumio[1198]: info: [RTL-SDR Radio] Plugin started successfully Feb 24 13:58:52 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 4. Feb 24 13:58:52 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 24 13:58:52 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 24 13:58:52 volumio sudo[1462]: pam_unix(sudo:session): session closed for user root Feb 24 13:58:52 volumio volumio[1198]: info: ir_controller: File permissions successfully set on /etc/lirc/*. Feb 24 13:58:52 volumio volumio[1198]: info: MPD Permissions set Feb 24 13:58:52 volumio volumio[1198]: info: MPD Permissions set Feb 24 13:58:52 volumio volumio[1198]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Feb 24 13:58:52 volumio volumio[1198]: info: Upmpdcli Daemon Started Feb 24 13:58:52 volumio volumio[1198]: info: Volumio called home Feb 24 13:58:52 volumio volumio[1198]: info: Spotify config file written Feb 24 13:58:53 volumio volumio[1198]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Feb 24 13:58:53 volumio volumio[1198]: info: Received Get System Info Feb 24 13:58:53 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 24 13:58:53 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 24 13:58:53 volumio volumio[1198]: info: Discovery: Getting this device information Feb 24 13:58:53 volumio volumio[1198]: info: CoreCommandRouter::volumioGetState Feb 24 13:58:53 volumio volumio[1198]: info: CorePlayQueue::getTrack 0 Feb 24 13:58:53 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 24 13:58:53 volumio sudo[1553]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 24 13:58:53 volumio sudo[1553]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 13:58:53 volumio volumio5-onboarding[1484]: time=2026-02-24T13:58:53.252+01:00 level=INFO msg="system info for 6b86dc4be29839ca7e62fbacebb9c40e" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.096 Feb 24 13:58:53 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 24 13:58:53 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 24 13:58:53 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 24 13:58:53 volumio go-librespot[1569]: go-librespot daemon starting... Feb 24 13:58:53 volumio sudo[1553]: pam_unix(sudo:session): session closed for user root Feb 24 13:58:53 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 13:58:54 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 13:58:54 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 13:58:54 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 13:58:54 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 13:58:54 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 13:58:54 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 13:58:54 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 13:58:54 volumio mpd[1519]: 2026-02-24T13:58:54 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 24 13:58:54 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 13:58:54 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 13:58:54 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 13:58:54 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 13:58:54 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 13:58:54 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 13:58:54 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 13:58:54 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 13:58:54 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 13:58:54 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 13:58:54 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 13:58:54 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 13:58:54 volumio volumio[1198]: info: No need to fix Spotify hosts Feb 24 13:58:54 volumio go-librespot[1570]: time="2026-02-24T13:58:54+01:00" level=info msg="running go-librespot 0.6.2" Feb 24 13:58:54 volumio go-librespot[1570]: time="2026-02-24T13:58:54+01:00" level=debug msg="app state loaded" Feb 24 13:58:54 volumio go-librespot[1570]: time="2026-02-24T13:58:54+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 24 13:58:54 volumio volumio[1198]: info: Received Get System Info Feb 24 13:58:54 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 24 13:58:54 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 24 13:58:54 volumio volumio[1198]: info: Discovery: Getting this device information Feb 24 13:58:54 volumio volumio[1198]: info: CoreCommandRouter::volumioGetState Feb 24 13:58:54 volumio volumio[1198]: info: CorePlayQueue::getTrack 0 Feb 24 13:58:54 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 24 13:58:54 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 24 13:58:54 volumio sudo[1482]: pam_unix(sudo:session): session closed for user root Feb 24 13:58:54 volumio sudo[1467]: pam_unix(sudo:session): session closed for user root Feb 24 13:58:55 volumio go-librespot[1570]: time="2026-02-24T13:58:55+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Feb 24 13:58:55 volumio go-librespot[1570]: time="2026-02-24T13:58:55+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 24 13:58:55 volumio go-librespot[1570]: time="2026-02-24T13:58:55+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 24 13:58:55 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 24 13:58:55 volumio go-librespot[1570]: time="2026-02-24T13:58:55+01:00" level=info msg="zeroconf server listening on port 41345" Feb 24 13:58:55 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 24 13:58:55 volumio volumio5-onboarding[1484]: time=2026-02-24T13:58:55.205+01:00 level=INFO msg="enabling local network discovery" Feb 24 13:58:55 volumio volumio[1198]: Upnp client error: Error: This socket has been ended by the other party Feb 24 13:58:55 volumio volumio5-onboarding[1484]: time=2026-02-24T13:58:55.247+01:00 level=INFO msg="bootstrapping state" hasInternet=true Feb 24 13:58:55 volumio go-librespot[1570]: time="2026-02-24T13:58:55+01:00" level=debug msg="obtained new client token: AADtj+ivANkrbTGMjuuOTRQ+/+QQvufzSInVJTc4RWs9lpD7Cc+ZDTbdIs3NElHDx/nfLwpeP54R3i62NPWy85jny7GwFxkYuTD5ZVYzyCLCGayYhqubfIrrq6geppIp5ZKnaEjK1ZTeXZRAIf0oZWIpZjjcwpsxhVDvOs0733RQ57LR9g5aupUK/HDHPDrNj55LZe0fTbhyoJhIS1HScY61tgVbSHnG9oKIfPeHKRP5hNBqrY5Ad2T0bg==" Feb 24 13:58:55 volumio volumio[1198]: info: Received Get System Info Feb 24 13:58:55 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 24 13:58:55 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 24 13:58:55 volumio volumio[1198]: info: Discovery: Getting this device information Feb 24 13:58:55 volumio volumio[1198]: info: CoreCommandRouter::volumioGetState Feb 24 13:58:55 volumio volumio[1198]: info: CorePlayQueue::getTrack 0 Feb 24 13:58:55 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 24 13:58:55 volumio go-librespot[1570]: time="2026-02-24T13:58:55+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Feb 24 13:58:55 volumio volumio[1198]: info: Starting Shairport Sync Feb 24 13:58:55 volumio go-librespot[1570]: time="2026-02-24T13:58:55+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 24 13:58:55 volumio volumio[1198]: info: Starting Shairport Sync Feb 24 13:58:55 volumio volumio[1198]: info: Starting Shairport Sync Feb 24 13:58:55 volumio volumio-remote-updater[688]: Test mode disabled Feb 24 13:58:55 volumio volumio-remote-updater[688]: Alpha mode disabled Feb 24 13:58:55 volumio volumio-remote-updater[688]: Alpha legacy test mode disabled Feb 24 13:58:55 volumio sudo[1598]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 24 13:58:55 volumio sudo[1600]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 24 13:58:55 volumio sudo[1598]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 13:58:55 volumio sudo[1600]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 13:58:55 volumio sudo[1602]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 24 13:58:55 volumio sudo[1602]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 13:58:55 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 24 13:58:55 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 24 13:58:55 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 24 13:58:55 volumio systemd[1]: shairport-sync.service: Consumed 2.316s CPU time. Feb 24 13:58:55 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 24 13:58:55 volumio sudo[1598]: pam_unix(sudo:session): session closed for user root Feb 24 13:58:55 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 24 13:58:55 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 24 13:58:55 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 24 13:58:55 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 24 13:58:55 volumio sudo[1600]: pam_unix(sudo:session): session closed for user root Feb 24 13:58:55 volumio sudo[1602]: pam_unix(sudo:session): session closed for user root Feb 24 13:58:55 volumio go-librespot[1570]: time="2026-02-24T13:58:55+01:00" level=debug msg="connected to ap-gew1.spotify.com:443" Feb 24 13:58:56 volumio go-librespot[1570]: time="2026-02-24T13:58:56+01:00" level=debug msg="completed keyexchange" Feb 24 13:58:56 volumio go-librespot[1570]: time="2026-02-24T13:58:56+01:00" level=debug msg="completed challenge" Feb 24 13:58:56 volumio go-librespot[1570]: time="2026-02-24T13:58:56+01:00" level=info msg="authenticated AP" username="od*ur" Feb 24 13:58:56 volumio go-librespot[1570]: time="2026-02-24T13:58:56+01:00" level=info msg="authenticated Login5" username="od*ur" Feb 24 13:58:56 volumio go-librespot[1570]: time="2026-02-24T13:58:56+01:00" level=debug msg="initializing zeroconf session" username="od*ur" Feb 24 13:58:56 volumio go-librespot[1570]: time="2026-02-24T13:58:56+01:00" level=debug msg="dealer connection opened" Feb 24 13:58:56 volumio go-librespot[1570]: time="2026-02-24T13:58:56+01:00" level=trace msg="starting accesspoint recv loop" Feb 24 13:58:56 volumio go-librespot[1570]: time="2026-02-24T13:58:56+01:00" level=trace msg="starting dealer recv loop" Feb 24 13:58:56 volumio go-librespot[1570]: time="2026-02-24T13:58:56+01:00" level=trace msg="received accesspoint ping" Feb 24 13:58:56 volumio go-librespot[1570]: time="2026-02-24T13:58:56+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Feb 24 13:58:56 volumio go-librespot[1570]: time="2026-02-24T13:58:56+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Feb 24 13:58:56 volumio go-librespot[1570]: time="2026-02-24T13:58:56+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Feb 24 13:58:56 volumio go-librespot[1570]: time="2026-02-24T13:58:56+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Feb 24 13:58:56 volumio go-librespot[1570]: time="2026-02-24T13:58:56+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Feb 24 13:58:56 volumio go-librespot[1570]: time="2026-02-24T13:58:56+01:00" level=debug msg="received connection id: MGNmMGI3NzYtMDBi...NTM1MTk4NUI0NA==" Feb 24 13:58:56 volumio go-librespot[1570]: time="2026-02-24T13:58:56+01:00" level=trace msg="received accesspoint pong ack" Feb 24 13:58:56 volumio go-librespot[1570]: time="2026-02-24T13:58:56+01:00" level=debug msg="put connect state because NEW_DEVICE" Feb 24 13:58:56 volumio go-librespot[1570]: time="2026-02-24T13:58:56+01:00" level=debug msg="update volume requested to 52428/65535" Feb 24 13:58:56 volumio volumio[1198]: error: MPD error: The expression evaluated to a falsy value: Feb 24 13:58:56 volumio volumio[1198]: assert.ok(self.idling) Feb 24 13:58:56 volumio volumio[1198]: error: The expression evaluated to a falsy value: Feb 24 13:58:56 volumio volumio[1198]: assert.ok(self.idling) Feb 24 13:58:56 volumio go-librespot[1570]: time="2026-02-24T13:58:56+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Feb 24 13:58:56 volumio go-librespot[1570]: time="2026-02-24T13:58:56+01:00" level=trace msg="emitting websocket event: volume" Feb 24 13:58:56 volumio volumio[1198]: info: ir_controller: LIRC correctly updated. Feb 24 13:58:56 volumio volumio[1198]: info: ir_controller: HAT did not load /proc/device-tree/ir_receiver! Feb 24 13:58:56 volumio volumio[1198]: info: Completed starting Core Plugins Feb 24 13:58:56 volumio volumio[1198]: info: ------------------------------------------- Feb 24 13:58:56 volumio volumio[1198]: info: ----- MyVolumio plugins startup ---- Feb 24 13:58:56 volumio volumio[1198]: info: ------------------------------------------- Feb 24 13:58:56 volumio volumio[1198]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 24 13:58:56 volumio volumio[1198]: error: updateQueue error: null Feb 24 13:58:57 volumio volumio[1198]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 24 13:58:57 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 24 13:58:57 volumio volumio[1198]: info: Shairport-Sync Started Feb 24 13:58:57 volumio volumio[1198]: Error adding Membership: Error: addMembership EINVAL Feb 24 13:58:57 volumio volumio[1198]: info: Shairport-Sync Started Feb 24 13:58:57 volumio volumio[1198]: info: Shairport-Sync Started Feb 24 13:58:57 volumio volumio[1198]: info: MPD running with PID1519 Feb 24 13:58:57 volumio volumio[1198]: ,establishing connection Feb 24 13:58:57 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 24 13:58:57 volumio volumio5-onboarding[1484]: time=2026-02-24T13:58:57.258+01:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=tidal error="could not open plugin config file for \"tidal\": open /data/configuration/music_service/tidal/config.json: no such file or directory" Feb 24 13:58:57 volumio volumio5-onboarding[1484]: time=2026-02-24T13:58:57.260+01:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=qobuz error="could not open plugin config file for \"qobuz\": open /data/configuration/music_service/qobuz/config.json: no such file or directory" Feb 24 13:58:57 volumio volumio5-onboarding[1484]: time=2026-02-24T13:58:57.260+01:00 level=WARN msg="could not read username/password data for music provider" component=volumio provider=hi_res_audio error="could not open plugin config file for \"hi_res_audio\": open /data/configuration/music_service/hi_res_audio/config.json: no such file or directory" Feb 24 13:58:57 volumio volumio[1198]: info: ir_controller: Raspberry Pi revision code: a020d3 Feb 24 13:58:57 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 24 13:58:57 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 24 13:58:57 volumio volumio[1198]: error: updateQueue error: null Feb 24 13:58:57 volumio sudo[1629]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -r -1 Feb 24 13:58:57 volumio sudo[1629]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 13:58:57 volumio sudo[1629]: pam_unix(sudo:session): session closed for user root Feb 24 13:58:57 volumio sudo[1641]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay gpio-ir gpio_pin=25 gpio_pull=up Feb 24 13:58:57 volumio sudo[1641]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 13:58:58 volumio volumio[1198]: info: go-librespot daemon successfully initialized Feb 24 13:58:58 volumio sudo[1643]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart lircd.service Feb 24 13:58:58 volumio sudo[1643]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 13:58:58 volumio kernel: Registered IR keymap rc-rc6-mce Feb 24 13:58:58 volumio kernel: IR RC6 protocol handler initialized Feb 24 13:58:58 volumio kernel: rc rc1: gpio_ir_recv as /devices/platform/ir-receiver@19/rc/rc1 Feb 24 13:58:58 volumio kernel: rc rc1: lirc_dev: driver gpio_ir_recv registered at minor = 0, raw IR receiver, no transmitter Feb 24 13:58:58 volumio kernel: input: gpio_ir_recv as /devices/platform/ir-receiver@19/rc/rc1/input2 Feb 24 13:58:58 volumio systemd[1]: Starting lircd-setup.service - lircd(8) initialization helper tool... Feb 24 13:58:58 volumio sudo[1641]: pam_unix(sudo:session): session closed for user root Feb 24 13:58:58 volumio volumio[1198]: info: ir_controller: Overlay gpio-ir gpio_pin=25 gpio_pull=up loaded. Feb 24 13:58:58 volumio systemd-logind[684]: Watching system buttons on /dev/input/event2 (gpio_ir_recv) Feb 24 13:58:58 volumio lircd-setup[1652]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in Python 3.12. Use ConfigParser directly instead. Feb 24 13:58:58 volumio lircd-setup[1652]: parser = configparser.SafeConfigParser() Feb 24 13:58:58 volumio systemd[1]: lircd-setup.service: Deactivated successfully. Feb 24 13:58:58 volumio systemd[1]: Finished lircd-setup.service - lircd(8) initialization helper tool. Feb 24 13:58:58 volumio systemd[1]: Starting lircd.service - Flexible IR remote input/output application support... Feb 24 13:58:58 volumio sudo[1657]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart lircd.service Feb 24 13:58:58 volumio lircd-0.10.1[1658]: Info: lircd: Opening log, level: Info Feb 24 13:58:58 volumio lircd-0.10.1[1658]: Notice: Version: lircd 0.10.1 Feb 24 13:58:58 volumio lircd-0.10.1[1658]: Notice: System info: Linux volumio 6.12.47-v7+ #1904 SMP Mon Sep 15 13:05:52 BST 2025 armv7l GNU/Linux Feb 24 13:58:58 volumio sudo[1657]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 13:58:58 volumio systemd[1]: lircd.service: Deactivated successfully. Feb 24 13:58:58 volumio systemd[1]: Stopped lircd.service - Flexible IR remote input/output application support. Feb 24 13:58:59 volumio systemd[1]: Starting lircd-setup.service - lircd(8) initialization helper tool... Feb 24 13:58:59 volumio lircd-setup[1662]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in Python 3.12. Use ConfigParser directly instead. Feb 24 13:58:59 volumio lircd-setup[1662]: parser = configparser.SafeConfigParser() Feb 24 13:58:59 volumio systemd[1]: lircd-setup.service: Deactivated successfully. Feb 24 13:58:59 volumio systemd[1]: Finished lircd-setup.service - lircd(8) initialization helper tool. Feb 24 13:58:59 volumio systemd[1]: Starting lircd.service - Flexible IR remote input/output application support... Feb 24 13:58:59 volumio lircd-0.10.1[1663]: Info: lircd: Opening log, level: Info Feb 24 13:58:59 volumio lircd-0.10.1[1663]: Notice: Version: lircd 0.10.1 Feb 24 13:58:59 volumio lircd-0.10.1[1663]: Notice: System info: Linux volumio 6.12.47-v7+ #1904 SMP Mon Sep 15 13:05:52 BST 2025 armv7l GNU/Linux Feb 24 13:58:59 volumio lircd-0.10.1[1663]: Info: Initial device: /dev/lirc0 Feb 24 13:58:59 volumio lircd-0.10.1[1663]: Notice: 'lirc' written to protocols file /sys/class/rc/rc1/protocols Feb 24 13:58:59 volumio lircd-0.10.1[1663]: Info: Initial device: /dev/lirc0 Feb 24 13:58:59 volumio lircd-0.10.1[1663]: Info: [lirc] protocol is enabled Feb 24 13:58:59 volumio lircd[1663]: lircd-0.10.1[1663]: Info: lircd: Opening log, level: Info Feb 24 13:58:59 volumio lircd-0.10.1[1663]: Notice: Options: driver: default Feb 24 13:58:59 volumio lircd[1663]: lircd-0.10.1[1663]: Notice: Using systemd fd Feb 24 13:58:59 volumio lircd[1663]: lircd-0.10.1[1663]: Warning: Running as root Feb 24 13:58:59 volumio lircd[1663]: lircd-0.10.1[1663]: Info: Using remote: lircd.conf. Feb 24 13:58:59 volumio lircd-0.10.1[1663]: Notice: Options: output: /var/run/lirc/lircd Feb 24 13:58:59 volumio lircd[1663]: lircd-0.10.1[1663]: Notice: lircd(default) ready, using /var/run/lirc/lircd Feb 24 13:58:59 volumio lircd-0.10.1[1663]: Notice: Options: nodaemon: 1 Feb 24 13:58:59 volumio systemd[1]: Started lircd.service - Flexible IR remote input/output application support. Feb 24 13:58:59 volumio lircd-0.10.1[1663]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins Feb 24 13:58:59 volumio lircd-0.10.1[1663]: Notice: Options: logfile: syslog Feb 24 13:58:59 volumio lircd-0.10.1[1663]: Notice: Options: immediate-init: 0 Feb 24 13:58:59 volumio lircd-0.10.1[1663]: Notice: Options: permission: 666 Feb 24 13:58:59 volumio lircd-0.10.1[1663]: Notice: Options: driver-options: Feb 24 13:58:59 volumio lircd-0.10.1[1663]: Notice: Options: pidfile: /var/run/lirc/lircd.pid Feb 24 13:58:59 volumio lircd-0.10.1[1663]: Notice: Options: listen: 0 Feb 24 13:58:59 volumio lircd-0.10.1[1663]: Notice: Options: connect: (null) Feb 24 13:58:59 volumio lircd-0.10.1[1663]: Notice: Options: userelease: 0 Feb 24 13:58:59 volumio lircd-0.10.1[1663]: Notice: Options: effective_user: (null) Feb 24 13:58:59 volumio lircd-0.10.1[1663]: Notice: Options: release_suffix: _EVUP Feb 24 13:58:59 volumio lircd-0.10.1[1663]: Notice: Options: allow_simulate: 0 Feb 24 13:58:59 volumio lircd-0.10.1[1663]: Notice: Options: repeat_max: 600 Feb 24 13:58:59 volumio lircd-0.10.1[1663]: Notice: Options: configfile: /etc/lirc/lircd.conf Feb 24 13:58:59 volumio lircd-0.10.1[1663]: Notice: Options: dynamic_codes: (null) Feb 24 13:58:59 volumio lircd-0.10.1[1663]: Notice: Current driver: default Feb 24 13:58:59 volumio lircd-0.10.1[1663]: Notice: Driver API version: 3 Feb 24 13:58:59 volumio lircd-0.10.1[1663]: Notice: Driver version: 0.10.0 Feb 24 13:58:59 volumio lircd-0.10.1[1663]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/default.html Feb 24 13:58:59 volumio lircd-0.10.1[1663]: Info: lircd: Opening log, level: Info Feb 24 13:58:59 volumio lircd-0.10.1[1663]: Notice: Using systemd fd Feb 24 13:58:59 volumio lircd-0.10.1[1663]: Warning: Running as root Feb 24 13:58:59 volumio lircd-0.10.1[1663]: Info: Using remote: lircd.conf. Feb 24 13:58:59 volumio lircd-0.10.1[1663]: Notice: lircd(default) ready, using /var/run/lirc/lircd Feb 24 13:58:59 volumio sudo[1657]: pam_unix(sudo:session): session closed for user root Feb 24 13:58:59 volumio sudo[1643]: pam_unix(sudo:session): session closed for user root Feb 24 13:58:59 volumio volumio[1198]: info: ir_controller: systemctl restart lircd.service succeeded. Feb 24 13:58:59 volumio volumio[1198]: info: ir_controller: systemctl restart lircd.service succeeded. Feb 24 13:58:59 volumio sudo[1667]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart irexec.service Feb 24 13:58:59 volumio sudo[1669]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart irexec.service Feb 24 13:58:59 volumio sudo[1669]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 13:58:59 volumio sudo[1667]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 13:59:00 volumio systemd[1]: Started irexec.service - Handle events from IR remotes decoded by lircd(8). Feb 24 13:59:00 volumio lircd[1663]: lircd-0.10.1[1663]: Notice: accepted new client on /var/run/lirc/lircd Feb 24 13:59:00 volumio lircd-0.10.1[1663]: Notice: accepted new client on /var/run/lirc/lircd Feb 24 13:59:00 volumio lircd[1663]: lircd-0.10.1[1663]: Info: [lirc] protocol is enabled Feb 24 13:59:00 volumio lircd-0.10.1[1663]: Info: [lirc] protocol is enabled Feb 24 13:59:00 volumio sudo[1669]: pam_unix(sudo:session): session closed for user root Feb 24 13:59:00 volumio lircd[1663]: lircd-0.10.1[1663]: Info: removed client Feb 24 13:59:00 volumio lircd-0.10.1[1663]: Info: removed client Feb 24 13:59:00 volumio systemd[1]: Stopping irexec.service - Handle events from IR remotes decoded by lircd(8)... Feb 24 13:59:00 volumio systemd[1]: irexec.service: Deactivated successfully. Feb 24 13:59:00 volumio systemd[1]: Stopped irexec.service - Handle events from IR remotes decoded by lircd(8). Feb 24 13:59:00 volumio systemd[1]: Started irexec.service - Handle events from IR remotes decoded by lircd(8). Feb 24 13:59:00 volumio sudo[1667]: pam_unix(sudo:session): session closed for user root Feb 24 13:59:00 volumio volumio[1198]: info: ir_controller: systemctl restart irexec.service succeeded. Feb 24 13:59:00 volumio volumio[1198]: info: ir_controller: systemctl restart irexec.service succeeded. Feb 24 13:59:00 volumio lircd[1663]: lircd-0.10.1[1663]: Notice: accepted new client on /var/run/lirc/lircd Feb 24 13:59:00 volumio lircd[1663]: lircd-0.10.1[1663]: Info: [lirc] protocol is enabled Feb 24 13:59:00 volumio lircd-0.10.1[1663]: Notice: accepted new client on /var/run/lirc/lircd Feb 24 13:59:00 volumio lircd-0.10.1[1663]: Info: [lirc] protocol is enabled Feb 24 13:59:00 volumio volumio[1198]: info: Discovery: adding 9747f86f-d2e8-47e2-a09b-14f0dfd83934 Feb 24 13:59:00 volumio volumio[1198]: info: Discovery: Found device Volumio Feb 24 13:59:00 volumio volumio[1198]: info: CoreCommandRouter::volumioGetState Feb 24 13:59:00 volumio volumio[1198]: info: CorePlayQueue::getTrack 0 Feb 24 13:59:00 volumio volumio[1198]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Feb 24 13:59:00 volumio volumio[1198]: info: Discovery: this is already registered, 9747f86f-d2e8-47e2-a09b-14f0dfd83934 Feb 24 13:59:00 volumio volumio[1198]: info: Discovery: Found device Volumio Feb 24 13:59:00 volumio volumio[1198]: info: CoreCommandRouter::volumioGetState Feb 24 13:59:00 volumio volumio[1198]: info: CorePlayQueue::getTrack 0 Feb 24 13:59:02 volumio volumio[1198]: info: Initializing connection to go-librespot Websocket Feb 24 13:59:02 volumio volumio[1198]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 24 13:59:07 volumio go-librespot[1570]: time="2026-02-24T13:59:07+01:00" level=debug msg="new websocket client" Feb 24 13:59:19 volumio volumio[1198]: info: Connection to go-librespot Websocket established Feb 24 13:59:20 volumio volumio[1198]: error: MyVolumio Plugin failed to start in a timely fashion Feb 24 13:59:20 volumio volumio[1198]: [Metrics] CommandRouter: 72s 247.83ms Feb 24 13:59:20 volumio volumio[1198]: info: CoreCommandRouter::volumiosetStartupVolume Feb 24 13:59:20 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 13:59:20 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 13:59:20 volumio volumio[1198]: info: CoreCommandRouter::Close All Modals sent Feb 24 13:59:20 volumio volumio[1198]: info: CoreCommandRouter::Close All Modals sent Feb 24 13:59:20 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 24 13:59:20 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 13:59:20 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 24 13:59:26 volumio go-librespot[1570]: time="2026-02-24T13:59:26+01:00" level=trace msg="sent dealer ping" Feb 24 13:59:26 volumio go-librespot[1570]: time="2026-02-24T13:59:26+01:00" level=trace msg="received dealer pong" Feb 24 13:59:32 volumio volumio[1198]: info: New Spotify access tokenBQBTnDSToo... Feb 24 13:59:32 volumio volumio[1198]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 24 13:59:33 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 24 13:59:33 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 24 13:59:33 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 24 13:59:33 volumio volumio[1198]: info: Getting Spotify volume Feb 24 13:59:33 volumio volumio[1198]: info: BOOT COMPLETED Feb 24 13:59:33 volumio volumio[1198]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 13:59:33 volumio volumio[1198]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Feb 24 13:59:33 volumio volumio[1198]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Feb 24 13:59:33 volumio volumio[1198]: at new NodeError (node:internal/errors:405:5) Feb 24 13:59:33 volumio volumio[1198]: at assert (node:internal/assert:14:11) Feb 24 13:59:33 volumio volumio[1198]: at internalConnectMultiple (node:net:1118:3) Feb 24 13:59:33 volumio volumio[1198]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Feb 24 13:59:33 volumio volumio[1198]: at listOnTimeout (node:internal/timers:575:11) Feb 24 13:59:33 volumio volumio[1198]: at process.processTimers (node:internal/timers:514:7) { Feb 24 13:59:33 volumio volumio[1198]: code: 'ERR_INTERNAL_ASSERTION' Feb 24 13:59:33 volumio volumio[1198]: } Feb 24 13:59:33 volumio volumio[1198]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 13:59:39 volumio upmpdcli[1747]: writing RSA key Feb 24 13:59:41 volumio sudo[1791]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-24 13:58' Feb 24 13:59:41 volumio sudo[1791]: 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="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"