Feb 26 17:35:13 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Feb 26 17:35:13 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Feb 26 17:35:13 volumio smbd[1144]: [2026/02/26 17:35:13.483777, 0] ../../source3/smbd/server.c:1741(main) Feb 26 17:35:13 volumio smbd[1144]: smbd version 4.17.8-Raspbian started. Feb 26 17:35:13 volumio smbd[1144]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Feb 26 17:35:13 volumio sudo[1060]: pam_unix(sudo:session): session closed for user root Feb 26 17:35:13 volumio wireless.js[667]: WIRELESS.JS - INFO: SETTING APPROPRIATE REG DOMAIN: ES Feb 26 17:35:13 volumio sudo[1150]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set ES Feb 26 17:35:13 volumio sudo[1150]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 17:35:13 volumio sudo[1150]: pam_unix(sudo:session): session closed for user root Feb 26 17:35:13 volumio wireless.js[667]: WIRELESS.JS - INFO: SUCCESSFULLY SET NEW REGDOMAIN: ES Feb 26 17:35:13 volumio ntpd[853]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 26 17:35:13 volumio ntpd[853]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Feb 26 17:35:13 volumio ntpd[853]: DNS: Pool taking: 195.95.153.43 Feb 26 17:35:13 volumio ntpd[853]: DNS: Pool taking: 178.215.228.24 Feb 26 17:35:13 volumio ntpd[853]: DNS: Pool taking: 172.233.111.193 Feb 26 17:35:13 volumio ntpd[853]: DNS: Pool taking: 162.159.200.123 Feb 26 17:35:13 volumio wireless.js[667]: WIRELESS.JS - INFO: Single Network Mode: Ethernet active, maintaining WiFi scan capability Feb 26 17:35:13 volumio ntpd[853]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Feb 26 17:35:13 volumio wireless.js[667]: WIRELESS.JS - INFO: SNM: Maintaining wlan0 UP without IP (scan mode) Feb 26 17:35:13 volumio wireless.js[667]: WIRELESS.JS - INFO: SNM: Users can configure WiFi via WebUI while ethernet is active Feb 26 17:35:14 volumio sudo[1160]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 26 17:35:14 volumio sudo[1160]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 26 17:35:14 volumio sudo[1160]: pam_unix(sudo:session): session closed for user root Feb 26 17:35:14 volumio sudo[1164]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Feb 26 17:35:14 volumio sudo[1164]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 26 17:35:14 volumio sudo[1164]: pam_unix(sudo:session): session closed for user root Feb 26 17:35:14 volumio wpa_supplicant[1167]: Successfully initialized wpa_supplicant Feb 26 17:35:14 volumio systemd[1]: Started smbd.service - Samba SMB Daemon. Feb 26 17:35:14 volumio wpa_supplicant[1167]: nl80211: kernel reports: Registration to specific type not supported Feb 26 17:35:14 volumio wpa_supplicant[1173]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all Feb 26 17:35:14 volumio wireless.js[667]: WIRELESS.JS - INFO: SNM: Transition to scan mode completed in 27294ms Feb 26 17:35:14 volumio wireless.js[667]: WIRELESS.JS - INFO: SNM: wlan0 is UP without IP, scan capable Feb 26 17:35:14 volumio wireless.js[667]: WIRELESS.JS - INFO: Notified systemd about wireless ready Feb 26 17:35:14 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Feb 26 17:35:14 volumio systemd[1]: Started wireless.service - Wireless Services. Feb 26 17:35:14 volumio ntpd[853]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 26 17:35:14 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 26 17:35:14 volumio ntpd[853]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Feb 26 17:35:14 volumio ntpd[853]: DNS: Pool taking: 212.227.232.46 Feb 26 17:35:14 volumio ntpd[853]: DNS: Pool taking: 212.227.145.233 Feb 26 17:35:14 volumio ntpd[853]: DNS: Pool skipping: 82.165.173.235 Feb 26 17:35:14 volumio ntpd[853]: DNS: Pool taking: 185.134.42.7 Feb 26 17:35:14 volumio ntpd[853]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Feb 26 17:35:14 volumio systemd[1]: Starting plymouth-quit.service - Terminate Plymouth Boot Screen... Feb 26 17:35:14 volumio systemd[1]: Started volumio_cpu_tweak.service - Volumio Cpu Tweaker. Feb 26 17:35:14 volumio systemd[1]: Received SIGRTMIN+21 from PID 289 (plymouthd). Feb 26 17:35:14 volumio systemd[1]: Received SIGRTMIN+21 from PID 289 (plymouthd). Feb 26 17:35:14 volumio systemd[1]: Finished plymouth-quit.service - Terminate Plymouth Boot Screen. Feb 26 17:35:14 volumio systemd[1]: Finished plymouth-quit-wait.service - Hold until boot process finishes up. Feb 26 17:35:14 volumio systemd[1]: Started getty@tty1.service - Getty on tty1. Feb 26 17:35:14 volumio systemd[1]: Reached target getty.target - Login Prompts. Feb 26 17:35:14 volumio systemd[1]: Reached target multi-user.target - Multi-User System. Feb 26 17:35:14 volumio systemd[1]: Reached target graphical.target - Graphical Interface. Feb 26 17:35:14 volumio systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Feb 26 17:35:15 volumio volumio-cpu-tweak[1194]: Setting RT Priority for mpd Feb 26 17:35:15 volumio volumio-cpu-tweak[1210]: pid 35's current scheduling policy: SCHED_OTHER Feb 26 17:35:15 volumio volumio-cpu-tweak[1210]: pid 35's current scheduling priority: 0 Feb 26 17:35:15 volumio volumio-cpu-tweak[1194]: Setting MPD Affinity Feb 26 17:35:15 volumio systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Feb 26 17:35:15 volumio systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Feb 26 17:35:15 volumio systemd[1]: Startup finished in 15.280s (kernel) + 15.188s (userspace) = 30.469s. Feb 26 17:35:15 volumio volumio-cpu-tweak[1211]: pid 3's current affinity mask: f Feb 26 17:35:15 volumio volumio-cpu-tweak[1194]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Feb 26 17:35:15 volumio volumio-cpu-tweak[1194]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Feb 26 17:35:15 volumio systemd[1]: volumio_cpu_tweak.service: Deactivated successfully. Feb 26 17:35:15 volumio ntpd[853]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 26 17:35:15 volumio dhcpcd[741]: eth0: leased 192.168.0.23 for 86400 seconds Feb 26 17:35:15 volumio ntpd[853]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Feb 26 17:35:15 volumio ntpd[853]: DNS: Pool taking: 194.164.164.175 Feb 26 17:35:15 volumio ntpd[853]: DNS: Pool taking: 217.160.163.33 Feb 26 17:35:15 volumio ntpd[853]: DNS: Pool taking: 185.198.109.227 Feb 26 17:35:15 volumio ntpd[853]: DNS: Pool skipping: 172.233.111.111 Feb 26 17:35:15 volumio ntpd[853]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Feb 26 17:35:15 volumio dhcpcd[741]: eth0: adding route to 192.168.0.0/24 Feb 26 17:35:15 volumio dhcpcd[741]: eth0: adding default route via 192.168.0.1 Feb 26 17:35:15 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Feb 26 17:35:15 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Feb 26 17:35:15 volumio systemd[1]: welcome.service: Deactivated successfully. Feb 26 17:35:15 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Feb 26 17:35:15 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Feb 26 17:35:16 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Feb 26 17:35:16 volumio welcome[1232]: Resolved ip:[1] 192.168.0.23 Feb 26 17:35:16 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Feb 26 17:35:16 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Feb 26 17:35:18 volumio volumio-remote-updater[662]: [2026-02-26 17:35:18] [info] asio async_connect error: asio.system:111 (Connection refused) Feb 26 17:35:18 volumio volumio-remote-updater[662]: [2026-02-26 17:35:18] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Feb 26 17:35:18 volumio volumio-remote-updater[662]: [2026-02-26 17:35:18] [error] handle_connect error: Connection refused Feb 26 17:35:19 volumio volumio[1192]: info: ------------------------------------------- Feb 26 17:35:19 volumio volumio[1192]: info: ----- Volumio3 ---- Feb 26 17:35:19 volumio volumio[1192]: info: ------------------------------------------- Feb 26 17:35:19 volumio volumio[1192]: info: ----- System startup ---- Feb 26 17:35:19 volumio volumio[1192]: info: ------------------------------------------- Feb 26 17:35:20 volumio ntpd[853]: CLOCK: time stepped by 0.857045 Feb 26 17:35:20 volumio ntpd[853]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Feb 26 17:35:21 volumio volumio[1192]: info: MYVOLUMIO Environment detected Feb 26 17:35:22 volumio volumio[1192]: info: Plugin folders cleanup Feb 26 17:35:22 volumio volumio[1192]: info: Scanning into folder /volumio/app/plugins/ Feb 26 17:35:22 volumio volumio[1192]: info: Scanning category audio_interface Feb 26 17:35:22 volumio volumio[1192]: info: Scanning category miscellanea Feb 26 17:35:22 volumio volumio[1192]: info: Scanning category music_service Feb 26 17:35:22 volumio volumio[1192]: info: Scanning category plugins.json Feb 26 17:35:22 volumio volumio[1192]: info: Scanning category system_controller Feb 26 17:35:22 volumio volumio[1192]: info: Scanning category user_interface Feb 26 17:35:22 volumio volumio[1192]: info: Scanning into folder /data/plugins/ Feb 26 17:35:22 volumio volumio[1192]: info: Scanning category audio_interface Feb 26 17:35:22 volumio volumio[1192]: info: Scanning category music_service Feb 26 17:35:22 volumio volumio[1192]: info: Scanning category user_interface Feb 26 17:35:22 volumio volumio[1192]: info: Plugin folders cleanup completed Feb 26 17:35:22 volumio volumio[1192]: info: ------------------------------------------- Feb 26 17:35:22 volumio volumio[1192]: info: ----- Core plugins startup ---- Feb 26 17:35:22 volumio volumio[1192]: info: ------------------------------------------- Feb 26 17:35:22 volumio volumio[1192]: info: Loading plugins from folder /volumio/app/plugins/ Feb 26 17:35:22 volumio volumio[1192]: info: Adding plugin upnp to MyMusic Plugins Feb 26 17:35:22 volumio volumio[1192]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 26 17:35:22 volumio volumio[1192]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 26 17:35:22 volumio volumio[1192]: info: Loading plugins from folder /data/plugins/ Feb 26 17:35:22 volumio volumio[1192]: info: Adding plugin rtlsdr_radio to MyMusic Plugins Feb 26 17:35:22 volumio volumio[1192]: info: Loading plugin "system"... Feb 26 17:35:22 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1. Feb 26 17:35:22 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 26 17:35:22 volumio volumio[1192]: info: Loading plugin "appearance"... Feb 26 17:35:22 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 26 17:35:22 volumio upmpdcli[1252]: Could not open config: /tmp/upmpdcli.conf Feb 26 17:35:22 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 26 17:35:22 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 26 17:35:24 volumio volumio[1192]: info: Loading plugin "network"... Feb 26 17:35:24 volumio volumio[1192]: info: Refreshing Cached IP Addresses Feb 26 17:35:24 volumio sudo[1256]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 26 17:35:24 volumio sudo[1256]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 17:35:24 volumio sudo[1256]: pam_unix(sudo:session): session closed for user root Feb 26 17:35:24 volumio sudo[1255]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 26 17:35:24 volumio sudo[1255]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 17:35:24 volumio volumio[1192]: info: Loading plugin "services"... Feb 26 17:35:24 volumio volumio[1192]: info: Loading plugin "volumio5onboarding"... Feb 26 17:35:24 volumio sudo[1255]: pam_unix(sudo:session): session closed for user root Feb 26 17:35:24 volumio sudo[1268]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 26 17:35:24 volumio sudo[1268]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 17:35:24 volumio volumio[1192]: info: Loading plugin "alsa_controller"... Feb 26 17:35:24 volumio volumio[1192]: warn: Unable to locate the audio output device Volumio Zero ES9039 DAC. Please configure a valid output device. Feb 26 17:35:24 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 26 17:35:25 volumio volumio[1192]: info: Loading plugin "wizard"... Feb 26 17:35:25 volumio volumio[1192]: info: Loading plugin "networkfs"... Feb 26 17:35:25 volumio volumio[1192]: info: Starting Udev Watcher for removable devices Feb 26 17:35:25 volumio sudo[1285]: 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 26 17:35:25 volumio sudo[1285]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 17:35:25 volumio volumio[1192]: info: Ignoring mount for partition: boot Feb 26 17:35:25 volumio volumio[1192]: info: Ignoring mount for partition: volumio Feb 26 17:35:25 volumio volumio[1192]: info: Ignoring mount for partition: volumio_data Feb 26 17:35:25 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 26 17:35:25 volumio volumio[1192]: info: Loading plugin "volumio_command_line_client"... Feb 26 17:35:25 volumio volumio[1192]: info: Loading plugin "upnp"... Feb 26 17:35:25 volumio volumio[1192]: info: [1772123725220] Starting Upmpd Daemon Feb 26 17:35:25 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 26 17:35:25 volumio volumio[1192]: info: Loading plugin "my_music"... Feb 26 17:35:25 volumio volumio[1192]: info: Loading plugin "mpd"... Feb 26 17:35:25 volumio kernel: netfs: FS-Cache loaded Feb 26 17:35:25 volumio kernel: Key type cifs.spnego registered Feb 26 17:35:25 volumio kernel: Key type cifs.idmap registered Feb 26 17:35:25 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 26 17:35:25 volumio kernel: CIFS: Attempting to mount //192.168.0.20/music Feb 26 17:35:26 volumio sudo[1285]: pam_unix(sudo:session): session closed for user root Feb 26 17:35:26 volumio volumio[1192]: info: Loading plugin "upnp_browser"... Feb 26 17:35:27 volumio sudo[1268]: pam_unix(sudo:session): session closed for user root Feb 26 17:35:28 volumio volumio[1192]: info: Starting UPNP Browser Feb 26 17:35:28 volumio volumio[1192]: info: Loading plugin "alarm-clock"... Feb 26 17:35:28 volumio volumio[1192]: info: Loading plugin "airplay_emulation"... Feb 26 17:35:29 volumio volumio[1192]: info: Starting Shairport Sync Feb 26 17:35:29 volumio volumio[1192]: info: Loading plugin "last_100"... Feb 26 17:35:29 volumio volumio[1192]: info: Loading plugin "webradio"... Feb 26 17:35:29 volumio volumio-remote-updater[662]: [2026-02-26 17:35:29] [connect] Successful connection Feb 26 17:35:29 volumio volumio[1192]: info: Loading plugin "i2s_dacs"... Feb 26 17:35:29 volumio volumio[1192]: info: Loading plugin "volumiodiscovery"... Feb 26 17:35:29 volumio volumio[1192]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 26 17:35:29 volumio volumio[1192]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 26 17:35:29 volumio volumio[1192]: *** WARNING *** For more information see Feb 26 17:35:29 volumio volumio[1192]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 26 17:35:29 volumio volumio[1192]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 26 17:35:29 volumio volumio[1192]: *** WARNING *** For more information see Feb 26 17:35:29 volumio node[1192]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 26 17:35:29 volumio node[1192]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 26 17:35:29 volumio node[1192]: *** WARNING *** For more information see Feb 26 17:35:29 volumio node[1192]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 26 17:35:29 volumio node[1192]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 26 17:35:29 volumio node[1192]: *** WARNING *** For more information see Feb 26 17:35:29 volumio volumio[1192]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 26 17:35:29 volumio volumio[1192]: info: Discovery: Started advertising with name: Volumio Feb 26 17:35:29 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 26 17:35:29 volumio volumio[1192]: info: Loading plugin "ytmusic"... Feb 26 17:35:31 volumio volumio[1192]: info: Loading plugin "outputs"... Feb 26 17:35:31 volumio volumio[1192]: info: Loading plugin "albumart"... Feb 26 17:35:31 volumio volumio[1192]: info: Plugin example_plugin is not enabled Feb 26 17:35:31 volumio volumio[1192]: info: Loading plugin "inputs"... Feb 26 17:35:31 volumio volumio[1192]: info: Loading plugin "updater_comm"... Feb 26 17:35:31 volumio volumio[1192]: info: Plugin mpdemulation is not enabled Feb 26 17:35:31 volumio volumio[1192]: info: Loading plugin "rest_api"... Feb 26 17:35:31 volumio volumio[1192]: info: Loading plugin "websocket"... Feb 26 17:35:31 volumio volumio[1192]: info: Starting Socket.io Server version 1.7.4 Feb 26 17:35:31 volumio volumio[1192]: info: Loading plugin "mpdoutput"... Feb 26 17:35:32 volumio volumio[1192]: info: Loading plugin "podcast"... Feb 26 17:35:33 volumio volumio[1325]: Forking 3 albumart workers Feb 26 17:35:33 volumio volumio[1192]: info: ControllerPodcast::constructor Feb 26 17:35:33 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully. Feb 26 17:35:34 volumio volumio[1192]: info: Loading plugin "rtlsdr_radio"... Feb 26 17:35:37 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Feb 26 17:35:37 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 26 17:35:37 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 26 17:35:37 volumio upmpdcli[1379]: Could not open config: /tmp/upmpdcli.conf Feb 26 17:35:37 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 26 17:35:37 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 26 17:35:39 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. Feb 26 17:35:42 volumio volumio[1192]: info: [RTL-SDR Radio] Loaded FM region data Feb 26 17:35:42 volumio volumio[1192]: info: Loading plugin "Systeminfo"... Feb 26 17:35:43 volumio volumio[1337]: Starting albumart workers Feb 26 17:35:43 volumio volumio[1335]: Starting albumart workers Feb 26 17:35:43 volumio volumio[1336]: Starting albumart workers Feb 26 17:35:44 volumio volumio-remote-updater[662]: [2026-02-26 17:35:44] [connect] Successful connection Feb 26 17:35:46 volumio volumio[1192]: info: Loading i18n strings for locale es Feb 26 17:35:46 volumio volumio[1192]: Updating browse sources language Feb 26 17:35:46 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 26 17:35:48 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 26 17:35:48 volumio volumio[1192]: info: CoreCommandRouter::initPlayerControls Feb 26 17:35:48 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 17:35:48 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 17:35:48 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 17:35:48 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 17:35:48 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 17:35:48 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 17:35:48 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 17:35:48 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 17:35:48 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 26 17:35:48 volumio volumio[1192]: Express server listening on port 3000 Feb 26 17:35:48 volumio volumio[1192]: [Metrics] WebUI: 29s 203.27ms Feb 26 17:35:48 volumio volumio[1192]: info: CoreStateMachine::resetVolumioState Feb 26 17:35:48 volumio volumio[1192]: info: CoreStateMachine::getcurrentVolume Feb 26 17:35:48 volumio volumio[1192]: info: CoreCommandRouter::volumioRetrievevolume Feb 26 17:35:48 volumio sudo[1426]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 26 17:35:48 volumio sudo[1426]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 17:35:48 volumio sudo[1426]: pam_unix(sudo:session): session closed for user root Feb 26 17:35:48 volumio sudo[1428]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 26 17:35:48 volumio sudo[1428]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 17:35:48 volumio sudo[1428]: pam_unix(sudo:session): session closed for user root Feb 26 17:35:48 volumio volumio[1192]: info: Volumio Network Manager: Network status updated: 1 Feb 26 17:35:49 volumio volumio[1192]: info: CoreStateMachine::pushState Feb 26 17:35:49 volumio volumio[1192]: info: CorePlayQueue::getTrack 0 Feb 26 17:35:49 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 17:35:49 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState Feb 26 17:35:49 volumio volumio[1192]: info: CoreStateMachine::updateTrackBlock Feb 26 17:35:49 volumio volumio[1192]: info: CorePlayQueue::getTrackBlock Feb 26 17:35:49 volumio volumio[1192]: info: CoreCommandRouter::volumioRetrievevolume Feb 26 17:35:50 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 17:35:50 volumio volumio[1192]: info: Executing start script for DAC Volumio Zero ES9039 DAC Feb 26 17:35:50 volumio volumio[1192]: info: Reloading queue from file Feb 26 17:35:50 volumio sudo[1446]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x48 0x00 0x02 Feb 26 17:35:50 volumio sudo[1446]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 17:35:50 volumio sudo[1446]: pam_unix(sudo:session): session closed for user root Feb 26 17:35:50 volumio volumio[1192]: info: CoreStateMachine::setRepeat null single undefined Feb 26 17:35:50 volumio volumio[1192]: info: CoreStateMachine::pushState Feb 26 17:35:50 volumio volumio[1192]: info: CorePlayQueue::getTrack 0 Feb 26 17:35:50 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 17:35:50 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState Feb 26 17:35:50 volumio volumio[1192]: info: CoreStateMachine::setRandom null Feb 26 17:35:50 volumio volumio[1192]: info: CoreStateMachine::pushState Feb 26 17:35:50 volumio volumio[1192]: info: CorePlayQueue::getTrack 0 Feb 26 17:35:50 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState Feb 26 17:35:50 volumio volumio[1192]: info: Setting Device type: Raspberry PI Feb 26 17:35:50 volumio volumio[1192]: info: DAC script executed Feb 26 17:35:50 volumio volumio[1192]: info: Completed loading Core Plugins Feb 26 17:35:50 volumio volumio[1192]: info: Preparing to generate the ALSA configuration file Feb 26 17:35:50 volumio volumio[1192]: info: CoreStateMachine::pushState Feb 26 17:35:50 volumio volumio[1192]: info: CorePlayQueue::getTrack 0 Feb 26 17:35:50 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState Feb 26 17:35:50 volumio volumio[1192]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Feb 26 17:35:50 volumio volumio[1192]: info: Reading ALSA contributions from plugins. Feb 26 17:35:50 volumio volumio[1192]: verbose: New Socket.io Connection to 192.168.0.23 from 192.168.0.55 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Feb 26 17:35:51 volumio sudo[1451]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 26 17:35:51 volumio sudo[1451]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 17:35:51 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 26 17:35:51 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Feb 26 17:35:51 volumio volumio[1192]: info: Received Get System Info Feb 26 17:35:51 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 26 17:35:51 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 26 17:35:51 volumio volumio[1192]: info: Discovery: Getting this device information Feb 26 17:35:51 volumio volumio[1192]: info: CoreCommandRouter::volumioGetState Feb 26 17:35:51 volumio volumio[1192]: info: CorePlayQueue::getTrack 0 Feb 26 17:35:51 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 26 17:35:51 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 26 17:35:51 volumio volumio[1192]: info: CoreCommandRouter::volumioGetVisibleSources Feb 26 17:35:51 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 26 17:35:51 volumio volumio[1192]: info: CoreCommandRouter::volumioGetState Feb 26 17:35:51 volumio volumio[1192]: info: CorePlayQueue::getTrack 0 Feb 26 17:35:51 volumio volumio[1192]: info: CoreCommandRouter::volumioGetState Feb 26 17:35:51 volumio volumio[1192]: info: CorePlayQueue::getTrack 0 Feb 26 17:35:51 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 26 17:35:51 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 26 17:35:51 volumio volumio[1192]: info: Received Get System Info Feb 26 17:35:51 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 26 17:35:51 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 26 17:35:51 volumio volumio[1192]: info: Discovery: Getting this device information Feb 26 17:35:51 volumio volumio[1192]: info: CoreCommandRouter::volumioGetState Feb 26 17:35:51 volumio volumio[1192]: info: CorePlayQueue::getTrack 0 Feb 26 17:35:51 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 26 17:35:51 volumio volumio[1192]: info: CoreCommandRouter::volumioGetState Feb 26 17:35:51 volumio volumio[1192]: info: CorePlayQueue::getTrack 0 Feb 26 17:35:51 volumio volumio[1192]: info: Listing playlists Feb 26 17:35:52 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Feb 26 17:35:52 volumio volumio[1192]: info: Asound.conf file unchanged, so no further update is needed Feb 26 17:35:52 volumio volumio[1192]: info: Output device has changed, restarting MPD Feb 26 17:35:52 volumio sudo[1469]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 26 17:35:52 volumio sudo[1469]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 17:35:52 volumio sudo[1469]: pam_unix(sudo:session): session closed for user root Feb 26 17:35:52 volumio volumio[1192]: info: Output device has changed, restarting Shairport Sync Feb 26 17:35:52 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 17:35:52 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 17:35:52 volumio sudo[1472]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 26 17:35:52 volumio sudo[1472]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 17:35:52 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Feb 26 17:35:52 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 26 17:35:52 volumio volumio[1192]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 26 17:35:52 volumio volumio[1192]: info: ___________ START PLUGINS ___________ Feb 26 17:35:52 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 26 17:35:52 volumio sudo[1451]: pam_unix(sudo:session): session closed for user root Feb 26 17:35:52 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 26 17:35:52 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 26 17:35:52 volumio volumio[1192]: info: ControllerMpd::onStart: Initializing MPD Feb 26 17:35:52 volumio volumio[1192]: info: Creating MPD Configuration file Feb 26 17:35:53 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 26 17:35:53 volumio volumio[1192]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 26 17:35:53 volumio volumio[1192]: info: [1772123753085] CoreMusicLibrary::Adding element Media Servers Feb 26 17:35:53 volumio sudo[1496]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 26 17:35:53 volumio sudo[1496]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 17:35:53 volumio sudo[1496]: pam_unix(sudo:session): session closed for user root Feb 26 17:35:53 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 26 17:35:53 volumio sudo[1482]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Feb 26 17:35:53 volumio volumio[1192]: info: UPNP Browser: Client initialized successfully Feb 26 17:35:53 volumio sudo[1498]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 26 17:35:53 volumio sudo[1498]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 17:35:53 volumio sudo[1484]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 26 17:35:53 volumio sudo[1484]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 26 17:35:53 volumio sudo[1482]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 17:35:53 volumio sudo[1506]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Feb 26 17:35:53 volumio sudo[1484]: pam_unix(sudo:session): session closed for user root Feb 26 17:35:53 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 17:35:53 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 17:35:53 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 26 17:35:53 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 26 17:35:53 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 26 17:35:53 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 26 17:35:53 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 26 17:35:53 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 26 17:35:53 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 26 17:35:53 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Feb 26 17:35:53 volumio sudo[1482]: pam_unix(sudo:session): session closed for user root Feb 26 17:35:53 volumio volumio[1192]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 26 17:35:53 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 17:35:53 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 17:35:53 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Feb 26 17:35:53 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Feb 26 17:35:53 volumio systemd[1]: setdatetime-helper.service: Consumed 1.386s CPU time. Feb 26 17:35:53 volumio sudo[1514]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 26 17:35:53 volumio sudo[1514]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 26 17:35:53 volumio sudo[1530]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Feb 26 17:35:53 volumio sudo[1514]: pam_unix(sudo:session): session closed for user root Feb 26 17:35:53 volumio volumio[1192]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 26 17:35:53 volumio volumio[1192]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 26 17:35:53 volumio volumio[1192]: info: [1772123753961] CoreMusicLibrary::Adding element Last_100 Feb 26 17:35:53 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 26 17:35:53 volumio volumio[1192]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 26 17:35:53 volumio volumio[1192]: info: [1772123753992] CoreMusicLibrary::Adding element Webradio Feb 26 17:35:53 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 26 17:35:54 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 26 17:35:54 volumio volumio[1192]: info: Initializing BBC Radios Feb 26 17:35:54 volumio volumio5-onboarding[1515]: time=2026-02-26T17:35:54.262+01:00 level=INFO msg="running volumio5-device-gateway" version=30d1950e+CHANGES buildDate=2026-01-29T13:10:08Z Feb 26 17:35:54 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 26 17:35:54 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 17:35:54 volumio volumio[1192]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 26 17:35:54 volumio volumio[1192]: info: [1772123754572] CoreMusicLibrary::Adding element YouTube Music Feb 26 17:35:54 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 26 17:35:54 volumio volumio[1192]: Cannot find translation for source YouTube Music Feb 26 17:35:54 volumio volumio[1192]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 26 17:35:54 volumio volumio[1192]: info: [1772123754798] CoreMusicLibrary::Adding element Podcast Feb 26 17:35:54 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 26 17:35:54 volumio volumio[1192]: Cannot find translation for source YouTube Music Feb 26 17:35:54 volumio volumio[1192]: Cannot find translation for source Podcast Feb 26 17:35:54 volumio volumio[1192]: info: [RTL-SDR Radio] Starting plugin Feb 26 17:35:54 volumio volumio[1192]: info: [RTL-SDR Radio] Loaded i18n strings for language: es Feb 26 17:35:54 volumio volumio[1192]: info: Volumio Calling Home Feb 26 17:35:55 volumio volumio[1192]: info: [RTL-SDR Radio] snd-aloop already loaded Feb 26 17:35:55 volumio volumio[1192]: info: [RTL-SDR Radio] No stations database found, creating v2 Feb 26 17:35:55 volumio volumio[1192]: info: [RTL-SDR Radio] Database loaded at: 2026-02-26T16:35:55.910Z Feb 26 17:35:55 volumio volumio[1192]: info: [RTL-SDR Radio] Loading blocklist from: /data/plugins/music_service/rtlsdr_radio/blocklist.json Feb 26 17:35:55 volumio volumio[1192]: info: [RTL-SDR Radio] Got 21 phrases from file Feb 26 17:35:55 volumio volumio[1192]: info: [RTL-SDR Radio] Updated metadata blocklist Feb 26 17:35:55 volumio volumio[1192]: info: [RTL-SDR Radio] Metadata module now has 21 phrases Feb 26 17:35:57 volumio volumio[1192]: info: [RTL-SDR Radio] Management server started on port 3456 Feb 26 17:35:57 volumio volumio[1192]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 26 17:35:57 volumio volumio[1192]: info: [1772123757839] CoreMusicLibrary::Adding element FM/DAB Radio Feb 26 17:35:57 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 26 17:35:57 volumio volumio[1192]: Cannot find translation for source YouTube Music Feb 26 17:35:57 volumio volumio[1192]: Cannot find translation for source Podcast Feb 26 17:35:57 volumio volumio[1192]: Cannot find translation for source FM/DAB Radio Feb 26 17:35:57 volumio volumio[1192]: info: [RTL-SDR Radio] Plugin started successfully Feb 26 17:35:59 volumio volumio-remote-updater[662]: [2026-02-26 17:35:59] [connect] Successful connection Feb 26 17:36:01 volumio volumio[1192]: info: MPD Permissions set Feb 26 17:36:01 volumio volumio[1192]: info: MPD Permissions set Feb 26 17:36:01 volumio volumio[1192]: info: Upmpdcli Daemon Started Feb 26 17:36:01 volumio volumio[1192]: info: Volumio called home Feb 26 17:36:01 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 26 17:36:01 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 26 17:36:01 volumio volumio[1192]: info: Discovery: Getting this device information Feb 26 17:36:01 volumio volumio[1192]: info: CoreCommandRouter::volumioGetState Feb 26 17:36:01 volumio volumio[1192]: info: CorePlayQueue::getTrack 0 Feb 26 17:36:01 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 26 17:36:01 volumio mpd[1531]: 2026-02-26T17:36:01 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 26 17:36:02 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 26 17:36:02 volumio sudo[1498]: pam_unix(sudo:session): session closed for user root Feb 26 17:36:02 volumio sudo[1472]: pam_unix(sudo:session): session closed for user root Feb 26 17:36:02 volumio volumio[1192]: 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: 5 Feb 26 17:36:02 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 17:36:02 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 17:36:02 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 17:36:02 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 17:36:02 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 17:36:02 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 17:36:02 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 17:36:02 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 17:36:02 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 17:36:02 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 17:36:02 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 17:36:02 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 17:36:02 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 17:36:02 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 17:36:02 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 17:36:02 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 17:36:02 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 17:36:02 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 17:36:02 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 17:36:02 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 17:36:02 volumio volumio[1192]: 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: 5 Feb 26 17:36:02 volumio volumio[1192]: info: Received Get System Info Feb 26 17:36:02 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 26 17:36:02 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 26 17:36:02 volumio volumio[1192]: info: Discovery: Getting this device information Feb 26 17:36:02 volumio volumio[1192]: info: CoreCommandRouter::volumioGetState Feb 26 17:36:02 volumio volumio[1192]: info: CorePlayQueue::getTrack 0 Feb 26 17:36:02 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 26 17:36:03 volumio volumio5-onboarding[1515]: time=2026-02-26T17:36:03.014+01:00 level=INFO msg="system info for 6b86dc4be29839ca7e62fbacebb9c40e" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.096 Feb 26 17:36:03 volumio volumio-remote-updater[662]: [2026-02-26 17:36:03] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1772123759 101 Feb 26 17:36:03 volumio volumio[1192]: 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: 6 Feb 26 17:36:03 volumio volumio[1192]: info: Completed starting Core Plugins Feb 26 17:36:03 volumio volumio[1192]: info: ------------------------------------------- Feb 26 17:36:03 volumio volumio[1192]: info: ----- MyVolumio plugins startup ---- Feb 26 17:36:03 volumio volumio[1192]: info: ------------------------------------------- Feb 26 17:36:03 volumio volumio[1192]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 26 17:36:03 volumio volumio[1192]: info: Received Get System Info Feb 26 17:36:03 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 26 17:36:03 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 26 17:36:03 volumio volumio[1192]: info: Discovery: Getting this device information Feb 26 17:36:03 volumio volumio[1192]: info: CoreCommandRouter::volumioGetState Feb 26 17:36:03 volumio volumio[1192]: info: CorePlayQueue::getTrack 0 Feb 26 17:36:03 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 26 17:36:03 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 26 17:36:03 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 26 17:36:03 volumio volumio5-onboarding[1515]: time=2026-02-26T17:36:03.692+01:00 level=INFO msg="enabling local network discovery" Feb 26 17:36:03 volumio volumio5-onboarding[1515]: time=2026-02-26T17:36:03.740+01:00 level=INFO msg="bootstrapping state" hasInternet=true Feb 26 17:36:04 volumio volumio[1192]: error: MPD error: The expression evaluated to a falsy value: Feb 26 17:36:04 volumio volumio[1192]: assert.ok(self.idling) Feb 26 17:36:04 volumio volumio[1192]: error: The expression evaluated to a falsy value: Feb 26 17:36:04 volumio volumio[1192]: assert.ok(self.idling) Feb 26 17:36:04 volumio volumio[1192]: info: MPD running with PID1531 Feb 26 17:36:04 volumio volumio[1192]: ,establishing connection Feb 26 17:36:04 volumio volumio[1192]: info: Starting Shairport Sync Feb 26 17:36:04 volumio volumio[1192]: info: Starting Shairport Sync Feb 26 17:36:04 volumio volumio[1192]: info: Starting Shairport Sync Feb 26 17:36:04 volumio sudo[1592]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 26 17:36:04 volumio volumio[1192]: info: Received Get System Info Feb 26 17:36:04 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 26 17:36:04 volumio sudo[1590]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 26 17:36:04 volumio sudo[1590]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 17:36:04 volumio sudo[1592]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 17:36:04 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 26 17:36:04 volumio volumio[1192]: info: Discovery: Getting this device information Feb 26 17:36:04 volumio volumio[1192]: info: CoreCommandRouter::volumioGetState Feb 26 17:36:04 volumio volumio[1192]: info: CorePlayQueue::getTrack 0 Feb 26 17:36:04 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 26 17:36:04 volumio volumio[1192]: error: updateQueue error: null Feb 26 17:36:04 volumio sudo[1594]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 26 17:36:04 volumio sudo[1594]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 17:36:04 volumio volumio-remote-updater[662]: Test mode disabled Feb 26 17:36:04 volumio volumio-remote-updater[662]: Alpha mode disabled Feb 26 17:36:04 volumio volumio-remote-updater[662]: Alpha legacy test mode disabled Feb 26 17:36:04 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 26 17:36:04 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 26 17:36:04 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 26 17:36:04 volumio systemd[1]: shairport-sync.service: Consumed 1.908s CPU time. Feb 26 17:36:04 volumio volumio[1192]: error: updateQueue error: null Feb 26 17:36:04 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 26 17:36:04 volumio sudo[1592]: pam_unix(sudo:session): session closed for user root Feb 26 17:36:04 volumio volumio[1192]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 26 17:36:04 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 26 17:36:04 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 26 17:36:04 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 26 17:36:04 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 26 17:36:04 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 26 17:36:04 volumio sudo[1590]: pam_unix(sudo:session): session closed for user root Feb 26 17:36:04 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 26 17:36:04 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 26 17:36:04 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 26 17:36:04 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 26 17:36:04 volumio volumio5-onboarding[1515]: time=2026-02-26T17:36:04.726+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 26 17:36:04 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 26 17:36:04 volumio volumio5-onboarding[1515]: time=2026-02-26T17:36:04.728+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 26 17:36:04 volumio volumio5-onboarding[1515]: time=2026-02-26T17:36:04.729+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 26 17:36:04 volumio sudo[1594]: pam_unix(sudo:session): session closed for user root Feb 26 17:36:04 volumio volumio[1192]: info: Shairport-Sync Started Feb 26 17:36:04 volumio volumio[1192]: Error adding Membership: Error: addMembership EINVAL Feb 26 17:36:04 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 26 17:36:04 volumio volumio[1192]: info: Shairport-Sync Started Feb 26 17:36:04 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 26 17:36:05 volumio volumio[1192]: info: Shairport-Sync Started Feb 26 17:36:06 volumio volumio[1192]: info: Discovery: adding 9747f86f-d2e8-47e2-a09b-14f0dfd83934 Feb 26 17:36:06 volumio volumio[1192]: info: Discovery: Found device Volumio Feb 26 17:36:06 volumio volumio[1192]: info: CoreCommandRouter::volumioGetState Feb 26 17:36:06 volumio volumio[1192]: info: CorePlayQueue::getTrack 0 Feb 26 17:36:07 volumio volumio[1192]: info: Discovery: this is already registered, 9747f86f-d2e8-47e2-a09b-14f0dfd83934 Feb 26 17:36:07 volumio volumio[1192]: info: Discovery: Found device Volumio Feb 26 17:36:07 volumio volumio[1192]: info: CoreCommandRouter::volumioGetState Feb 26 17:36:07 volumio volumio[1192]: info: CorePlayQueue::getTrack 0 Feb 26 17:36:07 volumio volumio[1192]: 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 26 17:36:08 volumio volumio[1192]: info: CoreCommandRouter::volumioGetState Feb 26 17:36:08 volumio volumio[1192]: info: CorePlayQueue::getTrack 0 Feb 26 17:36:10 volumio volumio[1192]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 26 17:36:11 volumio upmpdcli[1636]: writing RSA key Feb 26 17:36:36 volumio volumio[1192]: error: MyVolumio Plugin failed to start in a timely fashion Feb 26 17:36:36 volumio volumio[1192]: [Metrics] CommandRouter: 76s 110.76ms Feb 26 17:36:36 volumio volumio[1192]: info: CoreCommandRouter::volumiosetStartupVolume Feb 26 17:36:36 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 17:36:36 volumio volumio[1192]: info: VolumeController:: Setting startup Volume 10 Feb 26 17:36:36 volumio volumio[1192]: info: VolumeController::SetAlsaVolume10 Feb 26 17:36:36 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 17:36:36 volumio volumio[1192]: info: CoreCommandRouter::Close All Modals sent Feb 26 17:36:36 volumio volumio[1192]: info: CoreCommandRouter::Close All Modals sent Feb 26 17:36:36 volumio volumio[1192]: info: CoreStateMachine::pushState Feb 26 17:36:36 volumio volumio[1192]: info: CorePlayQueue::getTrack 0 Feb 26 17:36:36 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 17:36:36 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState Feb 26 17:36:36 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 26 17:36:36 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 17:36:36 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 26 17:36:43 volumio volumio[1192]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Feb 26 17:36:43 volumio volumio[1192]: ALSA lib confmisc.c:165:(snd_config_get_card) Cannot get card index for es9039q2m Feb 26 17:36:43 volumio volumio[1192]: aplay: main:831: audio open error: No such device Feb 26 17:36:43 volumio volumio[1192]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'SoftMaster',0 Feb 26 17:36:43 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 26 17:36:43 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 26 17:36:43 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 26 17:36:43 volumio volumio[1192]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 17:36:43 volumio volumio[1192]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Feb 26 17:36:43 volumio volumio[1192]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Feb 26 17:36:43 volumio volumio[1192]: at new NodeError (node:internal/errors:405:5) Feb 26 17:36:43 volumio volumio[1192]: at assert (node:internal/assert:14:11) Feb 26 17:36:43 volumio volumio[1192]: at internalConnectMultiple (node:net:1118:3) Feb 26 17:36:43 volumio volumio[1192]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Feb 26 17:36:43 volumio volumio[1192]: at listOnTimeout (node:internal/timers:575:11) Feb 26 17:36:43 volumio volumio[1192]: at process.processTimers (node:internal/timers:514:7) { Feb 26 17:36:43 volumio volumio[1192]: code: 'ERR_INTERNAL_ASSERTION' Feb 26 17:36:43 volumio volumio[1192]: } Feb 26 17:36:43 volumio volumio[1192]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 17:36:50 volumio sudo[1811]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-26 17:35' Feb 26 17:36:50 volumio sudo[1811]: 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"