Oct 02 09:03:00 volumio sudo[1230]: pam_unix(sudo:session): session closed for user root Oct 02 09:03:00 volumio wpa_supplicant[1233]: Successfully initialized wpa_supplicant Oct 02 09:03:00 volumio wireless.js[714]: WIRELESS.JS - INFO: SNM: Transition to scan mode completed in 2870ms Oct 02 09:03:00 volumio wireless.js[714]: WIRELESS.JS - INFO: SNM: wlan0 is UP without IP, scan capable Oct 02 09:03:00 volumio wireless.js[714]: Failed to connect to non-global ctrl_ifname: wlan0 error: No such file or directory Oct 02 09:03:00 volumio volumio-remote-updater[707]: [2026-10-02 09:03:00] [info] asio async_connect error: asio.system:111 (Connection refused) Oct 02 09:03:00 volumio volumio-remote-updater[707]: [2026-10-02 09:03:00] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Oct 02 09:03:00 volumio volumio-remote-updater[707]: [2026-10-02 09:03:00] [error] handle_connect error: Connection refused Oct 02 09:03:00 volumio wireless.js[714]: WIRELESS.JS - INFO: Notified systemd about wireless ready Oct 02 09:03:00 volumio systemd[1]: Started wireless.service - Wireless Services. Oct 02 09:03:00 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Oct 02 09:03:00 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Oct 02 09:03:00 volumio systemd[1]: Starting plymouth-quit.service - Terminate Plymouth Boot Screen... Oct 02 09:03:00 volumio systemd[1]: Started volumio_cpu_tweak.service - Volumio Cpu Tweaker. Oct 02 09:03:00 volumio systemd[1]: Received SIGRTMIN+21 from PID 310 (plymouthd). Oct 02 09:03:00 volumio volumio-cpu-tweak[1254]: Setting RT Priority for mpd Oct 02 09:03:00 volumio volumio-cpu-tweak[1268]: pid 35's current scheduling policy: SCHED_OTHER Oct 02 09:03:00 volumio volumio-cpu-tweak[1268]: pid 35's current scheduling priority: 0 Oct 02 09:03:00 volumio systemd[1]: Received SIGRTMIN+21 from PID 310 (plymouthd). Oct 02 09:03:00 volumio volumio-cpu-tweak[1254]: Setting MPD Affinity Oct 02 09:03:00 volumio systemd[1]: Finished plymouth-quit-wait.service - Hold until boot process finishes up. Oct 02 09:03:00 volumio systemd[1]: Finished plymouth-quit.service - Terminate Plymouth Boot Screen. Oct 02 09:03:01 volumio systemd[1]: Reached target multi-user.target - Multi-User System. Oct 02 09:03:01 volumio systemd[1]: Reached target graphical.target - Graphical Interface. Oct 02 09:03:01 volumio volumio-cpu-tweak[1269]: pid 3's current affinity mask: f Oct 02 09:03:01 volumio volumio-cpu-tweak[1254]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Oct 02 09:03:01 volumio volumio-cpu-tweak[1254]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Oct 02 09:03:01 volumio systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Oct 02 09:03:01 volumio systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Oct 02 09:03:01 volumio systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Oct 02 09:03:01 volumio systemd[1]: Startup finished in 19.224s (kernel) + 19.887s (userspace) = 39.111s. Oct 02 09:03:01 volumio systemd[1]: volumio_cpu_tweak.service: Deactivated successfully. Oct 02 09:03:05 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1. Oct 02 09:03:05 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 02 09:03:05 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 02 09:03:05 volumio upmpdcli[1274]: Could not open config: /tmp/upmpdcli.conf Oct 02 09:03:05 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 02 09:03:05 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 02 09:03:05 volumio volumio[1252]: info: ------------------------------------------- Oct 02 09:03:05 volumio volumio[1252]: info: ----- Volumio3 ---- Oct 02 09:03:05 volumio volumio[1252]: info: ------------------------------------------- Oct 02 09:03:05 volumio volumio[1252]: info: ----- System startup ---- Oct 02 09:03:05 volumio volumio[1252]: info: ------------------------------------------- Oct 02 09:03:07 volumio volumio[1252]: info: MYVOLUMIO Environment detected Oct 02 09:03:07 volumio volumio[1252]: info: Plugin folders cleanup Oct 02 09:03:07 volumio volumio[1252]: info: Scanning into folder /volumio/app/plugins/ Oct 02 09:03:07 volumio volumio[1252]: info: Scanning category audio_interface Oct 02 09:03:07 volumio volumio[1252]: info: Scanning category miscellanea Oct 02 09:03:07 volumio volumio[1252]: info: Scanning category music_service Oct 02 09:03:07 volumio volumio[1252]: info: Scanning category plugins.json Oct 02 09:03:07 volumio volumio[1252]: info: Scanning category system_controller Oct 02 09:03:07 volumio volumio[1252]: info: Scanning category user_interface Oct 02 09:03:07 volumio volumio[1252]: info: Scanning into folder /data/plugins/ Oct 02 09:03:07 volumio volumio[1252]: info: Scanning category audio_interface Oct 02 09:03:07 volumio volumio[1252]: info: Scanning category user_interface Oct 02 09:03:07 volumio volumio[1252]: info: Plugin folders cleanup completed Oct 02 09:03:07 volumio volumio[1252]: info: ------------------------------------------- Oct 02 09:03:07 volumio volumio[1252]: info: ----- Core plugins startup ---- Oct 02 09:03:07 volumio volumio[1252]: info: ------------------------------------------- Oct 02 09:03:07 volumio volumio[1252]: info: Loading plugins from folder /volumio/app/plugins/ Oct 02 09:03:07 volumio volumio[1252]: info: Adding plugin upnp to MyMusic Plugins Oct 02 09:03:07 volumio volumio[1252]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 02 09:03:07 volumio volumio[1252]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 02 09:03:07 volumio volumio[1252]: info: Loading plugins from folder /data/plugins/ Oct 02 09:03:07 volumio volumio[1252]: info: Loading plugin "system"... Oct 02 09:03:07 volumio volumio[1252]: info: Loading plugin "appearance"... Oct 02 09:03:10 volumio volumio[1252]: info: Loading plugin "network"... Oct 02 09:03:10 volumio volumio[1252]: info: Refreshing Cached IP Addresses Oct 02 09:03:10 volumio volumio[1252]: info: Loading plugin "services"... Oct 02 09:03:10 volumio volumio[1252]: info: Loading plugin "volumio5onboarding"... Oct 02 09:03:10 volumio sudo[1297]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 02 09:03:10 volumio sudo[1297]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 09:03:10 volumio sudo[1288]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 02 09:03:10 volumio sudo[1288]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 09:03:10 volumio sudo[1290]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 02 09:03:10 volumio sudo[1290]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 09:03:10 volumio volumio[1252]: info: Loading plugin "alsa_controller"... Oct 02 09:03:10 volumio sudo[1290]: pam_unix(sudo:session): session closed for user root Oct 02 09:03:10 volumio sudo[1288]: pam_unix(sudo:session): session closed for user root Oct 02 09:03:10 volumio volumio-remote-updater[707]: [2026-10-02 09:03:10] [connect] Successful connection Oct 02 09:03:10 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 02 09:03:10 volumio volumio[1252]: info: Loading plugin "wizard"... Oct 02 09:03:10 volumio volumio[1252]: info: Loading plugin "networkfs"... Oct 02 09:03:10 volumio sudo[1319]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=media,password=media123#media,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //diskstation6/audio\\flac /mnt/NAS/FLAC Oct 02 09:03:10 volumio sudo[1319]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 09:03:10 volumio volumio[1252]: info: Starting Udev Watcher for removable devices Oct 02 09:03:10 volumio sudo[1321]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=media,password=media123#media,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //diskstation6/audio2\\flac2 /mnt/NAS/FLAC_2 Oct 02 09:03:10 volumio sudo[1321]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 09:03:10 volumio volumio[1252]: info: Ignoring mount for partition: boot Oct 02 09:03:10 volumio volumio[1252]: info: Ignoring mount for partition: volumio Oct 02 09:03:10 volumio volumio[1252]: info: Ignoring mount for partition: volumio_data Oct 02 09:03:10 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 02 09:03:10 volumio volumio[1252]: info: Loading plugin "volumio_command_line_client"... Oct 02 09:03:10 volumio volumio[1252]: info: Loading plugin "upnp"... Oct 02 09:03:10 volumio kernel: netfs: FS-Cache loaded Oct 02 09:03:10 volumio volumio[1252]: info: [1790924590989] Starting Upmpd Daemon Oct 02 09:03:10 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 02 09:03:10 volumio volumio[1252]: info: Loading plugin "my_music"... Oct 02 09:03:11 volumio volumio[1252]: info: Loading plugin "mpd"... Oct 02 09:03:11 volumio sudo[1297]: pam_unix(sudo:session): session closed for user root Oct 02 09:03:11 volumio kernel: Key type cifs.spnego registered Oct 02 09:03:11 volumio kernel: Key type cifs.idmap registered Oct 02 09:03:11 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. Oct 02 09:03:11 volumio kernel: CIFS: Attempting to mount //diskstation6/audio2/flac2 Oct 02 09:03:11 volumio sudo[1321]: pam_unix(sudo:session): session closed for user root Oct 02 09:03:11 volumio kernel: CIFS: Attempting to mount //diskstation6/audio/flac Oct 02 09:03:11 volumio sudo[1319]: pam_unix(sudo:session): session closed for user root Oct 02 09:03:12 volumio volumio[1252]: info: Loading plugin "upnp_browser"... Oct 02 09:03:14 volumio volumio[1252]: info: Starting UPNP Browser Oct 02 09:03:14 volumio volumio[1252]: info: Loading plugin "alarm-clock"... Oct 02 09:03:14 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully. Oct 02 09:03:15 volumio volumio[1252]: info: Loading plugin "airplay_emulation"... Oct 02 09:03:15 volumio volumio[1252]: info: Starting Shairport Sync Oct 02 09:03:15 volumio volumio[1252]: info: Loading plugin "last_100"... Oct 02 09:03:15 volumio volumio[1252]: info: Loading plugin "webradio"... Oct 02 09:03:15 volumio volumio[1252]: info: Loading plugin "i2s_dacs"... Oct 02 09:03:15 volumio volumio[1252]: info: I2S DAC not set, start Auto-detection Oct 02 09:03:15 volumio volumio[1252]: info: Loading plugin "volumiodiscovery"... Oct 02 09:03:15 volumio volumio[1252]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 02 09:03:15 volumio node[1252]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 02 09:03:15 volumio volumio[1252]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 02 09:03:15 volumio node[1252]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 02 09:03:15 volumio volumio[1252]: *** WARNING *** For more information see Oct 02 09:03:15 volumio node[1252]: *** WARNING *** For more information see Oct 02 09:03:15 volumio volumio[1252]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 02 09:03:15 volumio node[1252]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 02 09:03:15 volumio volumio[1252]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 02 09:03:15 volumio node[1252]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 02 09:03:15 volumio volumio[1252]: *** WARNING *** For more information see Oct 02 09:03:15 volumio node[1252]: *** WARNING *** For more information see Oct 02 09:03:15 volumio volumio[1252]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 02 09:03:15 volumio volumio[1252]: info: Discovery: Started advertising with name: Volumio Oct 02 09:03:15 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 02 09:03:15 volumio volumio[1252]: info: Loading plugin "outputs"... Oct 02 09:03:15 volumio volumio[1252]: info: Loading plugin "albumart"... Oct 02 09:03:15 volumio volumio[1252]: info: Plugin example_plugin is not enabled Oct 02 09:03:15 volumio volumio[1252]: info: Loading plugin "inputs"... Oct 02 09:03:15 volumio volumio[1252]: info: Loading plugin "updater_comm"... Oct 02 09:03:15 volumio volumio[1252]: info: Plugin mpdemulation is not enabled Oct 02 09:03:16 volumio volumio[1252]: info: Loading plugin "rest_api"... Oct 02 09:03:16 volumio volumio[1252]: info: Loading plugin "websocket"... Oct 02 09:03:16 volumio volumio[1252]: info: Starting Socket.io Server version 1.7.4 Oct 02 09:03:16 volumio volumio[1252]: info: Loading plugin "audio_keepalive"... Oct 02 09:03:17 volumio volumio[1252]: info: Loading plugin "touch_display"... Oct 02 09:03:17 volumio volumio[1366]: Forking 3 albumart workers Oct 02 09:03:18 volumio volumio[1252]: info: Applying required configuration parameters for plugin touch_display Oct 02 09:03:19 volumio volumio[1252]: info: Loading i18n strings for locale de Oct 02 09:03:19 volumio volumio[1252]: Updating browse sources language Oct 02 09:03:19 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 09:03:20 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Oct 02 09:03:20 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 02 09:03:20 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 02 09:03:20 volumio upmpdcli[1406]: Could not open config: /tmp/upmpdcli.conf Oct 02 09:03:20 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 02 09:03:20 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 02 09:03:21 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 09:03:21 volumio volumio[1252]: info: CoreCommandRouter::initPlayerControls Oct 02 09:03:21 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 09:03:21 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 09:03:21 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 09:03:21 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 09:03:21 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 09:03:21 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 09:03:21 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 09:03:21 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 09:03:21 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 09:03:21 volumio volumio[1252]: Express server listening on port 3000 Oct 02 09:03:21 volumio volumio[1252]: [Metrics] WebUI: 17s 294.24ms Oct 02 09:03:21 volumio volumio[1252]: info: CoreStateMachine::resetVolumioState Oct 02 09:03:21 volumio volumio[1252]: info: CoreStateMachine::getcurrentVolume Oct 02 09:03:21 volumio volumio[1252]: info: CoreCommandRouter::volumioRetrievevolume Oct 02 09:03:22 volumio volumio[1252]: info: CoreStateMachine::pushState Oct 02 09:03:22 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Oct 02 09:03:22 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 09:03:22 volumio volumio[1252]: info: CoreCommandRouter::volumioPushState Oct 02 09:03:22 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 09:03:22 volumio sudo[1425]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 02 09:03:22 volumio sudo[1425]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 09:03:22 volumio sudo[1427]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 02 09:03:22 volumio sudo[1427]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 09:03:22 volumio sudo[1425]: pam_unix(sudo:session): session closed for user root Oct 02 09:03:22 volumio sudo[1427]: pam_unix(sudo:session): session closed for user root Oct 02 09:03:22 volumio volumio[1252]: info: Volumio Network Manager: Network status updated: 1 Oct 02 09:03:23 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Oct 02 09:03:23 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. Oct 02 09:03:24 volumio volumio[1252]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Oct 02 09:03:24 volumio volumio[1252]: info: Reloading queue from file Oct 02 09:03:25 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Oct 02 09:03:25 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Oct 02 09:03:25 volumio systemd[1]: setdatetime-helper.service: Consumed 1.270s CPU time. Oct 02 09:03:25 volumio volumio-remote-updater[707]: [2026-10-02 09:03:25] [connect] Successful connection Oct 02 09:03:25 volumio volumio[1252]: info: CoreStateMachine::setRepeat null single undefined Oct 02 09:03:25 volumio volumio[1252]: info: CoreStateMachine::pushState Oct 02 09:03:25 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Oct 02 09:03:25 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 09:03:25 volumio volumio[1252]: info: CoreCommandRouter::volumioPushState Oct 02 09:03:25 volumio volumio[1252]: info: CoreStateMachine::setRandom null Oct 02 09:03:25 volumio volumio[1252]: info: CoreStateMachine::pushState Oct 02 09:03:25 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Oct 02 09:03:25 volumio volumio[1252]: info: CoreCommandRouter::volumioPushState Oct 02 09:03:25 volumio volumio[1252]: info: Setting Device type: Raspberry PI Oct 02 09:03:25 volumio volumio-remote-updater[707]: [2026-10-02 09:03:25] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1790924605 101 Oct 02 09:03:25 volumio volumio[1252]: 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: 2 Oct 02 09:03:26 volumio sudo[1468]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 02 09:03:26 volumio sudo[1468]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 09:03:26 volumio volumio[1252]: info: Discovery: adding cfab1e8e-b3e2-472d-86f1-7f656e4757af Oct 02 09:03:26 volumio volumio[1252]: info: Discovery: Found device Volumio Oct 02 09:03:26 volumio volumio[1252]: info: CoreCommandRouter::volumioGetState Oct 02 09:03:26 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Oct 02 09:03:26 volumio volumio[1252]: info: Discovery: this is already registered, cfab1e8e-b3e2-472d-86f1-7f656e4757af Oct 02 09:03:26 volumio volumio[1252]: info: Discovery: Found device Volumio Oct 02 09:03:26 volumio volumio[1252]: info: CoreCommandRouter::volumioGetState Oct 02 09:03:26 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Oct 02 09:03:26 volumio volumio[1252]: info: Completed loading Core Plugins Oct 02 09:03:26 volumio volumio[1252]: info: Preparing to generate the ALSA configuration file Oct 02 09:03:27 volumio volumio[1252]: info: Asound.conf file unchanged, so no further update is needed Oct 02 09:03:27 volumio volumio[1252]: info: Output device has changed, restarting MPD Oct 02 09:03:27 volumio volumio[1252]: info: Output device has changed, restarting Shairport Sync Oct 02 09:03:27 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 09:03:27 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 09:03:27 volumio sudo[1473]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 02 09:03:27 volumio sudo[1471]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 02 09:03:27 volumio sudo[1471]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 09:03:27 volumio sudo[1473]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 09:03:27 volumio sudo[1471]: pam_unix(sudo:session): session closed for user root Oct 02 09:03:27 volumio volumio[1252]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 02 09:03:27 volumio volumio[1252]: info: ___________ START PLUGINS ___________ Oct 02 09:03:28 volumio volumio[1252]: info: ControllerMpd::onStart: Initializing MPD Oct 02 09:03:28 volumio volumio[1252]: info: Creating MPD Configuration file Oct 02 09:03:28 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 09:03:28 volumio volumio[1252]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 02 09:03:28 volumio volumio[1252]: info: [1790924608168] CoreMusicLibrary::Adding element Medienserver Oct 02 09:03:28 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 02 09:03:28 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 02 09:03:28 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 09:03:28 volumio volumio[1252]: info: UPNP Browser: Client initialized successfully Oct 02 09:03:28 volumio sudo[1487]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 02 09:03:28 volumio sudo[1485]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 02 09:03:28 volumio sudo[1487]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 02 09:03:28 volumio sudo[1485]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 09:03:28 volumio sudo[1488]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 02 09:03:28 volumio sudo[1487]: pam_unix(sudo:session): session closed for user root Oct 02 09:03:28 volumio sudo[1481]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Oct 02 09:03:28 volumio sudo[1481]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 09:03:28 volumio sudo[1486]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 02 09:03:28 volumio sudo[1486]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 09:03:28 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 09:03:28 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 09:03:28 volumio sudo[1486]: pam_unix(sudo:session): session closed for user root Oct 02 09:03:28 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 02 09:03:28 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 02 09:03:28 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 02 09:03:28 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 02 09:03:28 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 02 09:03:28 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 02 09:03:28 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 02 09:03:29 volumio volumio[1252]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 02 09:03:29 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 09:03:29 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 02 09:03:29 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 09:03:29 volumio sudo[1481]: pam_unix(sudo:session): session closed for user root Oct 02 09:03:29 volumio sudo[1498]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 02 09:03:29 volumio sudo[1498]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 02 09:03:29 volumio sudo[1502]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 02 09:03:29 volumio sudo[1498]: pam_unix(sudo:session): session closed for user root Oct 02 09:03:29 volumio volumio[1252]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 02 09:03:29 volumio volumio[1252]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 02 09:03:29 volumio volumio[1252]: info: [1790924609401] CoreMusicLibrary::Adding element Last_100 Oct 02 09:03:29 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 09:03:29 volumio volumio[1252]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 02 09:03:29 volumio volumio[1252]: info: [1790924609410] CoreMusicLibrary::Adding element Webradio Oct 02 09:03:29 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 09:03:29 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 02 09:03:29 volumio volumio[1252]: info: Initializing BBC Radios Oct 02 09:03:30 volumio volumio5-onboarding[1499]: time=2026-10-02T09:03:30.109+02:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Oct 02 09:03:30 volumio volumio[1377]: Starting albumart workers Oct 02 09:03:30 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 02 09:03:30 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 09:03:30 volumio volumio[1376]: Starting albumart workers Oct 02 09:03:30 volumio volumio[1252]: info: Loading i18n strings for locale de Oct 02 09:03:31 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 02 09:03:31 volumio sudo[1527]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop audio-keepalive.service Oct 02 09:03:31 volumio sudo[1527]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 09:03:31 volumio volumio[1252]: info: Volumio Calling Home Oct 02 09:03:31 volumio sudo[1527]: pam_unix(sudo:session): session closed for user root Oct 02 09:03:31 volumio sudo[1530]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /data/volumiokioskextensions/IframeKeyboardBridge Oct 02 09:03:31 volumio sudo[1530]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 09:03:31 volumio sudo[1530]: pam_unix(sudo:session): session closed for user root Oct 02 09:03:32 volumio volumio[1378]: Starting albumart workers Oct 02 09:03:32 volumio sudo[1551]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Oct 02 09:03:32 volumio sudo[1551]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 09:03:32 volumio sudo[1556]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Oct 02 09:03:32 volumio sudo[1556]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 09:03:32 volumio sudo[1551]: pam_unix(sudo:session): session closed for user root Oct 02 09:03:32 volumio sudo[1553]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Oct 02 09:03:32 volumio sudo[1553]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 09:03:33 volumio systemd[1]: Reloading. Oct 02 09:03:35 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Oct 02 09:03:35 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 02 09:03:35 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 02 09:03:35 volumio systemd[1]: Reloading. Oct 02 09:03:35 volumio sudo[1556]: pam_unix(sudo:session): session closed for user root Oct 02 09:03:36 volumio sudo[1468]: pam_unix(sudo:session): session closed for user root Oct 02 09:03:38 volumio sudo[1553]: pam_unix(sudo:session): session closed for user root Oct 02 09:03:39 volumio mpd[1503]: 2026-10-02T09:03:39 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 02 09:03:40 volumio volumio5-onboarding[1499]: failed to create app: failed to initialize host: failed to create socket connection: could not connect to socket.io server: read tcp 127.0.0.1:36386->127.0.0.1:3000: i/o timeout Oct 02 09:03:40 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Oct 02 09:03:40 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Oct 02 09:03:40 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 1. Oct 02 09:03:40 volumio systemd[1]: Started mpd.service - Music Player Daemon. Oct 02 09:03:40 volumio sudo[1473]: pam_unix(sudo:session): session closed for user root Oct 02 09:03:40 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 02 09:03:40 volumio sudo[1485]: pam_unix(sudo:session): session closed for user root Oct 02 09:03:40 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 02 09:03:40 volumio volumio5-onboarding[1618]: time=2026-10-02T09:03:40.580+02:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Oct 02 09:03:41 volumio volumio[1252]: info: touch_display: No backlight interface detected. Oct 02 09:03:41 volumio volumio[1252]: info: touch_display: systemctl daemon-reload succeeded. Oct 02 09:03:41 volumio volumio[1252]: info: touch_display: systemctl disable getty@tty1.service succeeded. Oct 02 09:03:41 volumio volumio[1252]: info: touch_display: systemctl stop getty@tty1.service succeeded. Oct 02 09:03:41 volumio volumio[1252]: info: AudioKeepalive: systemctl stop ok Oct 02 09:03:41 volumio volumio[1252]: info: MPD Permissions set Oct 02 09:03:41 volumio volumio[1252]: info: MPD Permissions set Oct 02 09:03:41 volumio volumio[1252]: info: Upmpdcli Daemon Started Oct 02 09:03:41 volumio sudo[1633]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Oct 02 09:03:41 volumio sudo[1633]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 09:03:41 volumio volumio[1252]: info: Volumio called home Oct 02 09:03:41 volumio systemd[1]: Started volumio-kiosk.service - Volumio Kiosk. Oct 02 09:03:41 volumio sudo[1633]: pam_unix(sudo:session): session closed for user root Oct 02 09:03:42 volumio startx[1676]: X.Org X Server 1.21.1.7 Oct 02 09:03:42 volumio startx[1676]: X Protocol Version 11, Revision 0 Oct 02 09:03:42 volumio startx[1676]: Current Operating System: Linux volumio 6.12.74-v7+ #1948 SMP Mon Mar 2 11:25:27 GMT 2026 armv7l Oct 02 09:03:42 volumio startx[1676]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory snd_bcm2835.enable_hdmi=0 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=9caf6474-168f-4972-add4-ace36a467bc2 imgfile=/volumio_current.sqsh bootpart=UUID=807D-2966 datapart=UUID=bea3e05c-62cf-4b22-a58d-66213927f56a uuidconfig=cmdline.txt rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Oct 02 09:03:42 volumio startx[1676]: xorg-server 2:21.1.7-3+rpt3+deb12u13 (https://www.debian.org/support) Oct 02 09:03:42 volumio startx[1676]: Current version of pixman: 0.44.0 Oct 02 09:03:42 volumio startx[1676]: Before reporting problems, check http://wiki.x.org Oct 02 09:03:42 volumio startx[1676]: to make sure that you have the latest version. Oct 02 09:03:42 volumio startx[1676]: Markers: (--) probed, (**) from config file, (==) default setting, Oct 02 09:03:42 volumio startx[1676]: (++) from command line, (!!) notice, (II) informational, Oct 02 09:03:42 volumio startx[1676]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Oct 02 09:03:42 volumio startx[1676]: (==) Log file: "/var/log/Xorg.0.log", Time: Fri Oct 2 09:03:42 2026 Oct 02 09:03:42 volumio startx[1676]: (==) Using config directory: "/etc/X11/xorg.conf.d" Oct 02 09:03:42 volumio startx[1676]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Oct 02 09:03:42 volumio sudo[1636]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/cp -r /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/content.js /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/manifest.json /data/volumiokioskextensions/IframeKeyboardBridge/ Oct 02 09:03:42 volumio sudo[1636]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 09:03:42 volumio sudo[1636]: pam_unix(sudo:session): session closed for user root Oct 02 09:03:43 volumio volumio[1252]: error: MPD error: The expression evaluated to a falsy value: Oct 02 09:03:43 volumio volumio[1252]: assert.ok(self.idling) Oct 02 09:03:43 volumio volumio[1252]: error: The expression evaluated to a falsy value: Oct 02 09:03:43 volumio volumio[1252]: assert.ok(self.idling) Oct 02 09:03:44 volumio volumio[1252]: 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: 3 Oct 02 09:03:44 volumio volumio[1252]: info: MPD running with PID1503 Oct 02 09:03:44 volumio volumio[1252]: ,establishing connection Oct 02 09:03:44 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 09:03:44 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 09:03:44 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 09:03:44 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 09:03:44 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 09:03:44 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 09:03:44 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 09:03:44 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 09:03:44 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 09:03:44 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 09:03:44 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 09:03:44 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 09:03:44 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 09:03:44 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 09:03:44 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 09:03:44 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 09:03:44 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 09:03:44 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 09:03:44 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 09:03:44 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 09:03:44 volumio volumio[1252]: error: updateQueue error: null Oct 02 09:03:45 volumio volumio[1252]: 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: 3 Oct 02 09:03:45 volumio volumio[1252]: info: Received Get System Info Oct 02 09:03:45 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 09:03:45 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 09:03:45 volumio volumio[1252]: info: Discovery: Getting this device information Oct 02 09:03:45 volumio volumio[1252]: info: CoreCommandRouter::volumioGetState Oct 02 09:03:45 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Oct 02 09:03:45 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 09:03:45 volumio volumio5-onboarding[1618]: time=2026-10-02T09:03:45.384+02:00 level=INFO msg="system info for d0db6bf6002de76da42dc5bbfde75755" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.119 Oct 02 09:03:45 volumio volumio5-onboarding[1618]: time=2026-10-02T09:03:45.418+02:00 level=INFO msg="bootstrapping state" hasInternet=true Oct 02 09:03:46 volumio volumio[1252]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Oct 02 09:03:46 volumio volumio[1252]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Oct 02 09:03:46 volumio volumio[1252]: info: touch_display: Volumio Kiosk started. Oct 02 09:03:46 volumio volumio[1252]: info: CoreCommandRouter::volumioGetState Oct 02 09:03:46 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Oct 02 09:03:46 volumio volumio[1252]: info: Completed starting Core Plugins Oct 02 09:03:46 volumio volumio[1252]: info: ------------------------------------------- Oct 02 09:03:46 volumio volumio[1252]: info: ----- MyVolumio plugins startup ---- Oct 02 09:03:46 volumio volumio[1252]: info: ------------------------------------------- Oct 02 09:03:46 volumio volumio[1252]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 02 09:03:46 volumio volumio[1252]: info: touch_display: IframeKeyboardBridge extension installed successfully Oct 02 09:03:46 volumio volumio[1252]: info: Received Get System Info Oct 02 09:03:46 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 09:03:46 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 09:03:46 volumio volumio[1252]: info: Discovery: Getting this device information Oct 02 09:03:46 volumio volumio[1252]: info: CoreCommandRouter::volumioGetState Oct 02 09:03:46 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Oct 02 09:03:46 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 09:03:46 volumio volumio[1252]: info: CoreCommandRouter::volumioGetState Oct 02 09:03:46 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Oct 02 09:03:46 volumio volumio[1252]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Oct 02 09:03:46 volumio volumio-remote-updater[707]: Test mode disabled Oct 02 09:03:46 volumio volumio-remote-updater[707]: Alpha mode disabled Oct 02 09:03:46 volumio volumio-remote-updater[707]: Alpha legacy test mode disabled Oct 02 09:03:46 volumio volumio[1252]: error: updateQueue error: null Oct 02 09:03:46 volumio volumio[1252]: info: touch_display: X display number found: 0 Oct 02 09:03:46 volumio volumio[1252]: info: Starting Shairport Sync Oct 02 09:03:46 volumio sudo[1768]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 02 09:03:46 volumio sudo[1768]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 09:03:46 volumio volumio[1252]: info: Starting Shairport Sync Oct 02 09:03:46 volumio sudo[1772]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 02 09:03:46 volumio sudo[1772]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 09:03:46 volumio volumio[1252]: info: Starting Shairport Sync Oct 02 09:03:46 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 02 09:03:46 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Oct 02 09:03:46 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 02 09:03:46 volumio systemd[1]: shairport-sync.service: Consumed 2.025s CPU time. Oct 02 09:03:46 volumio sudo[1765]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Oct 02 09:03:46 volumio sudo[1776]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 02 09:03:46 volumio sudo[1776]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 09:03:47 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 02 09:03:47 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 02 09:03:47 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Oct 02 09:03:47 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 02 09:03:47 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 02 09:03:47 volumio sudo[1772]: pam_unix(sudo:session): session closed for user root Oct 02 09:03:47 volumio sudo[1765]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 09:03:47 volumio sudo[1768]: pam_unix(sudo:session): session closed for user root Oct 02 09:03:47 volumio sudo[1765]: pam_unix(sudo:session): session closed for user root Oct 02 09:03:47 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 02 09:03:47 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Oct 02 09:03:47 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 02 09:03:47 volumio sudo[1769]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Oct 02 09:03:47 volumio sudo[1769]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 09:03:47 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 02 09:03:47 volumio volumio[1252]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 02 09:03:47 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 02 09:03:47 volumio sudo[1769]: pam_unix(sudo:session): session closed for user root Oct 02 09:03:47 volumio sudo[1776]: pam_unix(sudo:session): session closed for user root Oct 02 09:03:47 volumio volumio[1252]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Oct 02 09:03:47 volumio volumio[1252]: info: Shairport-Sync Started Oct 02 09:03:47 volumio volumio[1252]: Error adding Membership: Error: addMembership EINVAL Oct 02 09:03:47 volumio volumio[1252]: info: Shairport-Sync Started Oct 02 09:03:47 volumio volumio[1252]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Oct 02 09:03:47 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 02 09:03:47 volumio volumio5-onboarding[1618]: time=2026-10-02T09:03:47.569+02: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" Oct 02 09:03:47 volumio volumio5-onboarding[1618]: time=2026-10-02T09:03:47.571+02: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" Oct 02 09:03:47 volumio volumio5-onboarding[1618]: time=2026-10-02T09:03:47.571+02: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" Oct 02 09:03:47 volumio volumio[1252]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Oct 02 09:03:47 volumio volumio[1252]: info: Shairport-Sync Started Oct 02 09:03:47 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 02 09:03:48 volumio volumio[1252]: info: touch_display: X display number found: 0 Oct 02 09:03:48 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 02 09:03:48 volumio volumio[1252]: info: Received Get System Info Oct 02 09:03:48 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 09:03:48 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 09:03:48 volumio volumio[1252]: info: Discovery: Getting this device information Oct 02 09:03:48 volumio volumio[1252]: info: CoreCommandRouter::volumioGetState Oct 02 09:03:48 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Oct 02 09:03:48 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 09:03:49 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 02 09:03:49 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 02 09:03:49 volumio volumio5-onboarding[1618]: time=2026-10-02T09:03:49.323+02:00 level=INFO msg="enabling local network discovery" Oct 02 09:03:49 volumio volumio5-onboarding[1618]: time=2026-10-02T09:03:49.382+02:00 level=INFO msg="enabling BLE discovery" Oct 02 09:03:49 volumio volumio[1252]: info: CoreCommandRouter::volumioGetState Oct 02 09:03:49 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Oct 02 09:03:49 volumio volumio[1252]: info: touch_display: Setting screensaver timeout to 120 seconds. Oct 02 09:03:50 volumio volumio[1252]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Oct 02 09:03:50 volumio volumio5-onboarding[1618]: time=2026-10-02T09:03:50.491+02:00 level=INFO msg="service successfully established" component=discovery/localnet Oct 02 09:04:00 volumio volumio[1252]: Upnp client error: Error: This socket has been ended by the other party Oct 02 09:04:06 volumio ntpd[878]: CLOCK: time stepped by 0.769973 Oct 02 09:04:06 volumio ntpd[878]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Oct 02 09:04:09 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 02 09:04:09 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 09:04:09 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 02 09:04:09 volumio volumio[1252]: error: MyVolumio Plugin failed to start in a timely fashion Oct 02 09:04:09 volumio volumio[1252]: [Metrics] CommandRouter: 63s 296.57ms Oct 02 09:04:09 volumio volumio[1252]: info: CoreCommandRouter::volumiosetStartupVolume Oct 02 09:04:09 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 09:04:09 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 09:04:09 volumio volumio[1252]: info: CoreCommandRouter::Close All Modals sent Oct 02 09:04:09 volumio volumio[1252]: info: CoreCommandRouter::Close All Modals sent Oct 02 09:04:10 volumio upmpdcli[1971]: writing RSA key Oct 02 09:04:12 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 02 09:04:12 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 02 09:04:13 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 02 09:04:19 volumio volumio[1252]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Oct 02 09:04:19 volumio volumio[1252]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Oct 02 09:04:19 volumio volumio[1252]: aplay: set_params:1352: Sample format non available Oct 02 09:04:19 volumio volumio[1252]: Available formats: Oct 02 09:04:19 volumio volumio[1252]: - IEC958_SUBFRAME_LE Oct 02 09:04:19 volumio volumio[1252]: info: BOOT COMPLETED Oct 02 09:04:19 volumio volumio[1252]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 02 09:04:20 volumio volumio[1252]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Oct 02 09:04:20 volumio volumio[1252]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Oct 02 09:04:20 volumio volumio[1252]: at new NodeError (node:internal/errors:405:5) Oct 02 09:04:20 volumio volumio[1252]: at assert (node:internal/assert:14:11) Oct 02 09:04:20 volumio volumio[1252]: at internalConnectMultiple (node:net:1118:3) Oct 02 09:04:20 volumio volumio[1252]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Oct 02 09:04:20 volumio volumio[1252]: at listOnTimeout (node:internal/timers:575:11) Oct 02 09:04:20 volumio volumio[1252]: at process.processTimers (node:internal/timers:514:7) { Oct 02 09:04:20 volumio volumio[1252]: code: 'ERR_INTERNAL_ASSERTION' Oct 02 09:04:20 volumio volumio[1252]: } Oct 02 09:04:20 volumio volumio[1252]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 02 09:04:29 volumio sudo[2024]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-02 09:03' Oct 02 09:04:29 volumio sudo[2024]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"