Jan 13 09:51:00 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Jan 13 09:51:00 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Jan 13 09:51:00 volumio systemd[1]: setdatetime-helper.service: Consumed 2.777s CPU time. Jan 13 09:51:00 volumio systemd[1]: Started smbd.service - Samba SMB Daemon. Jan 13 09:51:00 volumio systemd[1]: Reached target multi-user.target - Multi-User System. Jan 13 09:51:00 volumio systemd[1]: Reached target graphical.target - Graphical Interface. Jan 13 09:51:00 volumio systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Jan 13 09:51:00 volumio systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Jan 13 09:51:00 volumio systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Jan 13 09:51:00 volumio systemd[1]: Startup finished in 21.181s (kernel) + 48.213s (userspace) = 1min 9.394s. Jan 13 09:51:08 volumio volumio-remote-updater[642]: [2026-01-13 09:51:08] [connect] Successful connection Jan 13 09:51:08 volumio volumio[1160]: info: ------------------------------------------- Jan 13 09:51:08 volumio volumio[1160]: info: ----- Volumio3 ---- Jan 13 09:51:08 volumio volumio[1160]: info: ------------------------------------------- Jan 13 09:51:08 volumio volumio[1160]: info: ----- System startup ---- Jan 13 09:51:08 volumio volumio[1160]: info: ------------------------------------------- Jan 13 09:51:09 volumio kernel: hwmon hwmon1: Voltage normalised Jan 13 09:51:11 volumio volumio[1160]: info: MYVOLUMIO Environment detected Jan 13 09:51:11 volumio volumio[1160]: info: Plugin folders cleanup Jan 13 09:51:11 volumio volumio[1160]: info: Scanning into folder /volumio/app/plugins/ Jan 13 09:51:11 volumio volumio[1160]: info: Scanning category audio_interface Jan 13 09:51:11 volumio volumio[1160]: info: Scanning category miscellanea Jan 13 09:51:11 volumio volumio[1160]: info: Scanning category music_service Jan 13 09:51:11 volumio volumio[1160]: info: Scanning category plugins.json Jan 13 09:51:11 volumio volumio[1160]: info: Scanning category system_controller Jan 13 09:51:11 volumio volumio[1160]: info: Scanning category user_interface Jan 13 09:51:11 volumio volumio[1160]: info: Scanning into folder /data/plugins/ Jan 13 09:51:11 volumio volumio[1160]: info: Plugin folders cleanup completed Jan 13 09:51:11 volumio volumio[1160]: info: ------------------------------------------- Jan 13 09:51:11 volumio volumio[1160]: info: ----- Core plugins startup ---- Jan 13 09:51:11 volumio volumio[1160]: info: ------------------------------------------- Jan 13 09:51:11 volumio volumio[1160]: info: Loading plugins from folder /volumio/app/plugins/ Jan 13 09:51:11 volumio volumio[1160]: info: Adding plugin upnp to MyMusic Plugins Jan 13 09:51:11 volumio volumio[1160]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 13 09:51:11 volumio volumio[1160]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 13 09:51:11 volumio volumio[1160]: info: Loading plugins from folder /data/plugins/ Jan 13 09:51:11 volumio volumio[1160]: info: Loading plugin "system"... Jan 13 09:51:11 volumio volumio[1160]: info: Loading plugin "appearance"... Jan 13 09:51:12 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Jan 13 09:51:12 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 13 09:51:12 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 13 09:51:12 volumio upmpdcli[1267]: Could not open config: /tmp/upmpdcli.conf Jan 13 09:51:12 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 13 09:51:12 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 13 09:51:15 volumio volumio[1160]: info: Loading plugin "network"... Jan 13 09:51:15 volumio volumio[1160]: info: Refreshing Cached IP Addresses Jan 13 09:51:15 volumio sudo[1271]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 13 09:51:15 volumio volumio[1160]: info: Loading plugin "services"... Jan 13 09:51:15 volumio sudo[1271]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 09:51:15 volumio sudo[1269]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 13 09:51:15 volumio sudo[1269]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 09:51:15 volumio volumio[1160]: info: Loading plugin "alsa_controller"... Jan 13 09:51:15 volumio sudo[1271]: pam_unix(sudo:session): session closed for user root Jan 13 09:51:15 volumio sudo[1274]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 13 09:51:15 volumio sudo[1274]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 09:51:15 volumio sudo[1269]: pam_unix(sudo:session): session closed for user root Jan 13 09:51:15 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 09:51:15 volumio volumio[1160]: info: Loading plugin "wizard"... Jan 13 09:51:16 volumio volumio[1160]: info: Loading plugin "networkfs"... Jan 13 09:51:16 volumio volumio[1160]: info: Starting Udev Watcher for removable devices Jan 13 09:51:16 volumio sudo[1300]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=musicbox,password=ZufBPb8ysPjyR39A6DX2,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //diskstation/music/chris/flac /mnt/NAS/Diskstation Jan 13 09:51:16 volumio sudo[1300]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 09:51:16 volumio volumio[1160]: info: Ignoring mount for partition: boot Jan 13 09:51:16 volumio volumio[1160]: info: Ignoring mount for partition: volumio Jan 13 09:51:16 volumio volumio[1160]: info: Ignoring mount for partition: volumio_data Jan 13 09:51:16 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 13 09:51:16 volumio volumio[1160]: info: Loading plugin "volumio_command_line_client"... Jan 13 09:51:16 volumio volumio[1160]: info: Loading plugin "upnp"... Jan 13 09:51:16 volumio volumio[1160]: info: [1768323076536] Starting Upmpd Daemon Jan 13 09:51:16 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 13 09:51:16 volumio volumio[1160]: info: Loading plugin "my_music"... Jan 13 09:51:16 volumio kernel: netfs: FS-Cache loaded Jan 13 09:51:16 volumio volumio[1160]: info: Loading plugin "mpd"... Jan 13 09:51:16 volumio kernel: Key type cifs.spnego registered Jan 13 09:51:16 volumio kernel: Key type cifs.idmap registered Jan 13 09:51:16 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. Jan 13 09:51:16 volumio kernel: CIFS: Attempting to mount //diskstation/music/chris/flac Jan 13 09:51:18 volumio volumio[1160]: info: Loading plugin "upnp_browser"... Jan 13 09:51:18 volumio sudo[1300]: pam_unix(sudo:session): session closed for user root Jan 13 09:51:19 volumio sudo[1274]: pam_unix(sudo:session): session closed for user root Jan 13 09:51:22 volumio volumio[1160]: info: Starting UPNP Browser Jan 13 09:51:22 volumio volumio[1160]: info: Loading plugin "alarm-clock"... Jan 13 09:51:23 volumio volumio-remote-updater[642]: [2026-01-13 09:51:23] [connect] Successful connection Jan 13 09:51:23 volumio volumio[1160]: info: Loading plugin "airplay_emulation"... Jan 13 09:51:23 volumio volumio[1160]: info: Starting Shairport Sync Jan 13 09:51:23 volumio volumio[1160]: info: Loading plugin "last_100"... Jan 13 09:51:23 volumio volumio[1160]: info: Loading plugin "webradio"... Jan 13 09:51:23 volumio volumio[1160]: info: Loading plugin "i2s_dacs"... Jan 13 09:51:23 volumio volumio[1160]: info: Loading plugin "volumiodiscovery"... Jan 13 09:51:23 volumio volumio[1160]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 13 09:51:23 volumio node[1160]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 13 09:51:23 volumio volumio[1160]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 09:51:23 volumio node[1160]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 09:51:23 volumio volumio[1160]: *** WARNING *** For more information see Jan 13 09:51:23 volumio node[1160]: *** WARNING *** For more information see Jan 13 09:51:23 volumio volumio[1160]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 13 09:51:23 volumio node[1160]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 13 09:51:23 volumio volumio[1160]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 09:51:23 volumio node[1160]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 09:51:23 volumio volumio[1160]: *** WARNING *** For more information see Jan 13 09:51:23 volumio node[1160]: *** WARNING *** For more information see Jan 13 09:51:23 volumio volumio[1160]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 13 09:51:23 volumio volumio[1160]: info: Discovery: Started advertising with name: Volumio Jan 13 09:51:23 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 13 09:51:23 volumio volumio[1160]: info: Loading plugin "outputs"... Jan 13 09:51:23 volumio volumio[1160]: info: Loading plugin "albumart"... Jan 13 09:51:24 volumio volumio[1160]: info: Plugin example_plugin is not enabled Jan 13 09:51:24 volumio volumio[1160]: info: Loading plugin "inputs"... Jan 13 09:51:24 volumio volumio[1160]: info: Loading plugin "updater_comm"... Jan 13 09:51:25 volumio volumio[1160]: info: Plugin mpdemulation is not enabled Jan 13 09:51:25 volumio volumio[1160]: info: Loading plugin "rest_api"... Jan 13 09:51:25 volumio volumio[1160]: info: Loading plugin "websocket"... Jan 13 09:51:25 volumio volumio[1160]: info: Starting Socket.io Server version 1.7.4 Jan 13 09:51:25 volumio volumio[1160]: info: Loading i18n strings for locale en Jan 13 09:51:25 volumio volumio[1160]: Updating browse sources language Jan 13 09:51:25 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 09:51:27 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 4. Jan 13 09:51:27 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 13 09:51:27 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 13 09:51:27 volumio upmpdcli[1367]: Could not open config: /tmp/upmpdcli.conf Jan 13 09:51:27 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 13 09:51:27 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 13 09:51:27 volumio volumio[1343]: Forking 3 albumart workers Jan 13 09:51:27 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 09:51:27 volumio volumio[1160]: info: CoreCommandRouter::initPlayerControls Jan 13 09:51:27 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 09:51:27 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 09:51:27 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 09:51:27 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 09:51:27 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 09:51:27 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 09:51:27 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 09:51:27 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 09:51:28 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 09:51:28 volumio volumio[1160]: Express server listening on port 3000 Jan 13 09:51:28 volumio volumio[1160]: [Metrics] WebUI: 21s 571.77ms Jan 13 09:51:28 volumio volumio[1160]: info: CoreStateMachine::resetVolumioState Jan 13 09:51:28 volumio volumio[1160]: info: CoreStateMachine::getcurrentVolume Jan 13 09:51:28 volumio volumio[1160]: info: CoreCommandRouter::volumioRetrievevolume Jan 13 09:51:29 volumio sudo[1401]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 13 09:51:29 volumio sudo[1401]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 09:51:29 volumio sudo[1403]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 13 09:51:29 volumio sudo[1403]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 09:51:29 volumio sudo[1401]: pam_unix(sudo:session): session closed for user root Jan 13 09:51:29 volumio sudo[1403]: pam_unix(sudo:session): session closed for user root Jan 13 09:51:29 volumio volumio[1160]: info: Volumio Network Manager: Network status updated: 2 Jan 13 09:51:30 volumio volumio[1160]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jan 13 09:51:31 volumio volumio[1160]: info: VolumeController:: Volume=undefined Mute =false Jan 13 09:51:31 volumio volumio[1160]: info: CoreStateMachine::pushState Jan 13 09:51:31 volumio volumio[1160]: info: CorePlayQueue::getTrack 0 Jan 13 09:51:32 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 09:51:32 volumio volumio[1160]: info: CoreCommandRouter::volumioPushState Jan 13 09:51:32 volumio volumio[1160]: info: CoreStateMachine::updateTrackBlock Jan 13 09:51:32 volumio volumio[1160]: info: CorePlayQueue::getTrackBlock Jan 13 09:51:32 volumio volumio[1160]: info: CoreCommandRouter::volumioRetrievevolume Jan 13 09:51:32 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 09:51:33 volumio volumio[1160]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jan 13 09:51:33 volumio volumio[1160]: info: CoreStateMachine::setRepeat null single undefined Jan 13 09:51:33 volumio volumio[1160]: info: CoreStateMachine::pushState Jan 13 09:51:33 volumio volumio[1160]: info: CorePlayQueue::getTrack 0 Jan 13 09:51:33 volumio volumio[1160]: info: CoreCommandRouter::volumioPushState Jan 13 09:51:33 volumio volumio[1160]: info: CoreStateMachine::setRandom null Jan 13 09:51:33 volumio volumio[1160]: info: CoreStateMachine::pushState Jan 13 09:51:33 volumio volumio[1160]: info: CorePlayQueue::getTrack 0 Jan 13 09:51:33 volumio volumio[1160]: info: CoreCommandRouter::volumioPushState Jan 13 09:51:33 volumio volumio[1160]: info: Setting Device type: Raspberry PI Jan 13 09:51:33 volumio volumio[1160]: info: Completed loading Core Plugins Jan 13 09:51:33 volumio volumio[1160]: info: Preparing to generate the ALSA configuration file Jan 13 09:51:34 volumio volumio[1160]: info: VolumeController:: Volume=undefined Mute =false Jan 13 09:51:34 volumio volumio[1160]: info: CoreStateMachine::pushState Jan 13 09:51:34 volumio volumio[1160]: info: CorePlayQueue::getTrack 0 Jan 13 09:51:34 volumio volumio[1160]: info: CoreCommandRouter::volumioPushState Jan 13 09:51:34 volumio volumio[1160]: info: Discovery: adding 80b7fd87-0efe-489a-b5b1-a2252fe3a3b1 Jan 13 09:51:35 volumio volumio[1160]: info: Discovery: Found device Volumio Jan 13 09:51:35 volumio volumio[1160]: info: CoreCommandRouter::volumioGetState Jan 13 09:51:35 volumio volumio[1160]: info: CorePlayQueue::getTrack 0 Jan 13 09:51:35 volumio volumio[1160]: info: Discovery: this is already registered, 80b7fd87-0efe-489a-b5b1-a2252fe3a3b1 Jan 13 09:51:35 volumio volumio[1160]: info: Discovery: Found device Volumio Jan 13 09:51:35 volumio sudo[1421]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 13 09:51:35 volumio sudo[1421]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 09:51:35 volumio volumio[1160]: info: CoreCommandRouter::volumioGetState Jan 13 09:51:35 volumio volumio[1160]: info: CorePlayQueue::getTrack 0 Jan 13 09:51:35 volumio volumio[1160]: info: Asound.conf file unchanged, so no further update is needed Jan 13 09:51:35 volumio volumio[1160]: info: Output device has changed, restarting MPD Jan 13 09:51:35 volumio volumio[1160]: info: Output device has changed, restarting Shairport Sync Jan 13 09:51:35 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 09:51:35 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 09:51:35 volumio sudo[1426]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 13 09:51:35 volumio sudo[1426]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 09:51:35 volumio sudo[1424]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 13 09:51:35 volumio sudo[1424]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 09:51:36 volumio volumio[1160]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 09:51:36 volumio volumio[1160]: info: ___________ START PLUGINS ___________ Jan 13 09:51:36 volumio sudo[1424]: pam_unix(sudo:session): session closed for user root Jan 13 09:51:36 volumio volumio[1160]: info: ControllerMpd::onStart: Initializing MPD Jan 13 09:51:36 volumio volumio[1160]: info: Creating MPD Configuration file Jan 13 09:51:36 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 13 09:51:36 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 13 09:51:36 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 09:51:36 volumio volumio[1160]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 09:51:36 volumio volumio[1160]: info: [1768323096715] CoreMusicLibrary::Adding element Media Servers Jan 13 09:51:36 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 09:51:36 volumio sudo[1439]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 13 09:51:36 volumio sudo[1439]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 09:51:36 volumio sudo[1443]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 13 09:51:36 volumio sudo[1443]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 13 09:51:36 volumio sudo[1452]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jan 13 09:51:36 volumio sudo[1443]: pam_unix(sudo:session): session closed for user root Jan 13 09:51:37 volumio sudo[1439]: pam_unix(sudo:session): session closed for user root Jan 13 09:51:37 volumio volumio[1160]: info: UPNP Browser: Client initialized successfully Jan 13 09:51:37 volumio sudo[1446]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 13 09:51:37 volumio sudo[1446]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 09:51:37 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 09:51:37 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 09:51:37 volumio systemd[1]: mpd.service: Deactivated successfully. Jan 13 09:51:37 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 13 09:51:37 volumio systemd[1]: mpd.socket: Deactivated successfully. Jan 13 09:51:37 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 13 09:51:37 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 13 09:51:37 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 13 09:51:37 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 13 09:51:37 volumio volumio[1160]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 09:51:37 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 09:51:37 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 09:51:38 volumio volumio[1160]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 09:51:38 volumio volumio[1160]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 09:51:38 volumio volumio[1160]: info: [1768323098139] CoreMusicLibrary::Adding element Last_100 Jan 13 09:51:38 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 09:51:38 volumio volumio[1160]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 09:51:38 volumio volumio-remote-updater[642]: [2026-01-13 09:51:38] [connect] Successful connection Jan 13 09:51:38 volumio sudo[1462]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 13 09:51:38 volumio sudo[1462]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 13 09:51:38 volumio volumio[1160]: info: [1768323098148] CoreMusicLibrary::Adding element Webradio Jan 13 09:51:38 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 09:51:38 volumio sudo[1467]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jan 13 09:51:38 volumio sudo[1462]: pam_unix(sudo:session): session closed for user root Jan 13 09:51:38 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 09:51:38 volumio volumio[1160]: info: Initializing BBC Radios Jan 13 09:51:39 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 09:51:39 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 09:51:39 volumio volumio[1160]: info: Volumio Calling Home Jan 13 09:51:42 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 5. Jan 13 09:51:42 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 13 09:51:42 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 13 09:51:42 volumio sudo[1421]: pam_unix(sudo:session): session closed for user root Jan 13 09:51:44 volumio kernel: hwmon hwmon1: Undervoltage detected! Jan 13 09:51:50 volumio mpd[1468]: 2026-01-13T09:51:50 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 13 09:51:50 volumio systemd[1]: Started mpd.service - Music Player Daemon. Jan 13 09:51:50 volumio sudo[1446]: pam_unix(sudo:session): session closed for user root Jan 13 09:51:50 volumio sudo[1426]: pam_unix(sudo:session): session closed for user root Jan 13 09:51:50 volumio kernel: hwmon hwmon1: Voltage normalised Jan 13 09:51:51 volumio volumio[1371]: Starting albumart workers Jan 13 09:51:52 volumio volumio[1369]: Starting albumart workers Jan 13 09:51:52 volumio volumio[1370]: Starting albumart workers Jan 13 09:51:53 volumio volumio-remote-updater[642]: [2026-01-13 09:51:53] [connect] Successful connection Jan 13 09:51:55 volumio volumio[1160]: info: Completed starting Core Plugins Jan 13 09:51:55 volumio volumio[1160]: info: ------------------------------------------- Jan 13 09:51:55 volumio volumio[1160]: info: ----- MyVolumio plugins startup ---- Jan 13 09:51:55 volumio volumio[1160]: info: ------------------------------------------- Jan 13 09:51:55 volumio volumio[1160]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 13 09:51:55 volumio volumio[1160]: info: MPD Permissions set Jan 13 09:51:55 volumio volumio[1160]: info: MPD Permissions set Jan 13 09:51:55 volumio volumio[1160]: info: Upmpdcli Daemon Started Jan 13 09:51:56 volumio volumio[1160]: info: MPD running with PID1468 Jan 13 09:51:56 volumio volumio[1160]: ,establishing connection Jan 13 09:51:56 volumio volumio[1160]: info: Volumio called home Jan 13 09:51:57 volumio kernel: hwmon hwmon1: Undervoltage detected! Jan 13 09:52:01 volumio volumio[1160]: error: MPD error: The expression evaluated to a falsy value: Jan 13 09:52:01 volumio volumio[1160]: assert.ok(self.idling) Jan 13 09:52:01 volumio volumio[1160]: error: The expression evaluated to a falsy value: Jan 13 09:52:01 volumio volumio[1160]: assert.ok(self.idling) Jan 13 09:52:01 volumio volumio[1160]: error: MPD error: The expression evaluated to a falsy value: Jan 13 09:52:01 volumio volumio[1160]: assert.ok(self.idling) Jan 13 09:52:01 volumio volumio[1160]: error: The expression evaluated to a falsy value: Jan 13 09:52:01 volumio volumio[1160]: assert.ok(self.idling) Jan 13 09:52:02 volumio volumio[1160]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Jan 13 09:52:03 volumio volumio[1160]: error: error Jan 13 09:52:03 volumio volumio[1160]: error: error Jan 13 09:52:03 volumio volumio[1160]: error: error Jan 13 09:52:03 volumio volumio[1160]: error: error Jan 13 09:52:03 volumio volumio[1160]: error: error Jan 13 09:52:03 volumio volumio[1160]: error: error Jan 13 09:52:03 volumio sudo[1541]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 13 09:52:03 volumio sudo[1541]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 09:52:03 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 09:52:03 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 09:52:03 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 09:52:03 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 09:52:03 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 09:52:03 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 09:52:03 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 09:52:03 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 09:52:03 volumio sudo[1543]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 13 09:52:04 volumio sudo[1543]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 09:52:04 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 09:52:04 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 09:52:04 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Jan 13 09:52:04 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 09:52:04 volumio mpd_monitor.sh[1556]: MPD Monitor Service: Starting MPD Monitor Service Jan 13 09:52:04 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 09:52:04 volumio sudo[1541]: pam_unix(sudo:session): session closed for user root Jan 13 09:52:04 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 09:52:04 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 09:52:04 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 09:52:04 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 09:52:04 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 09:52:04 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 09:52:04 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Jan 13 09:52:04 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Jan 13 09:52:04 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Jan 13 09:52:04 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Jan 13 09:52:04 volumio mpd_monitor.sh[1562]: MPD Monitor Service: Starting MPD Monitor Service Jan 13 09:52:04 volumio sudo[1543]: pam_unix(sudo:session): session closed for user root Jan 13 09:52:04 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 09:52:04 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 09:52:06 volumio volumio[1160]: error: updateQueue error: null Jan 13 09:52:06 volumio volumio[1160]: info: Successfully started MPD Monitor Jan 13 09:52:06 volumio volumio[1160]: info: Successfully started MPD Monitor Jan 13 09:52:06 volumio volumio[1160]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jan 13 09:52:08 volumio volumio-remote-updater[642]: [2026-01-13 09:52:08] [connect] Successful connection Jan 13 09:52:12 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 09:52:12 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 09:52:12 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 13 09:52:12 volumio volumio[1160]: info: Starting Shairport Sync Jan 13 09:52:12 volumio volumio[1160]: info: Starting Shairport Sync Jan 13 09:52:12 volumio volumio[1160]: info: Starting Shairport Sync Jan 13 09:52:12 volumio sudo[1594]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 13 09:52:12 volumio sudo[1594]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 09:52:12 volumio sudo[1598]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 13 09:52:12 volumio sudo[1596]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 13 09:52:12 volumio sudo[1596]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 09:52:12 volumio sudo[1598]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 09:52:13 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 13 09:52:13 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jan 13 09:52:13 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 13 09:52:13 volumio systemd[1]: shairport-sync.service: Consumed 2.768s CPU time. Jan 13 09:52:13 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 13 09:52:13 volumio sudo[1594]: pam_unix(sudo:session): session closed for user root Jan 13 09:52:13 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 13 09:52:13 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jan 13 09:52:13 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 13 09:52:13 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 13 09:52:13 volumio sudo[1596]: pam_unix(sudo:session): session closed for user root Jan 13 09:52:13 volumio sudo[1598]: pam_unix(sudo:session): session closed for user root Jan 13 09:52:13 volumio kernel: hwmon hwmon1: Voltage normalised Jan 13 09:52:13 volumio volumio[1160]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jan 13 09:52:14 volumio volumio[1160]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 09:52:14 volumio volumio[1160]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Jan 13 09:52:14 volumio volumio[1160]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Jan 13 09:52:14 volumio volumio[1160]: at new NodeError (node:internal/errors:405:5) Jan 13 09:52:14 volumio volumio[1160]: at assert (node:internal/assert:14:11) Jan 13 09:52:14 volumio volumio[1160]: at internalConnectMultiple (node:net:1118:3) Jan 13 09:52:14 volumio volumio[1160]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Jan 13 09:52:14 volumio volumio[1160]: at listOnTimeout (node:internal/timers:575:11) Jan 13 09:52:14 volumio volumio[1160]: at process.processTimers (node:internal/timers:514:7) { Jan 13 09:52:14 volumio volumio[1160]: code: 'ERR_INTERNAL_ASSERTION' Jan 13 09:52:14 volumio volumio[1160]: } Jan 13 09:52:14 volumio volumio[1160]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 09:52:15 volumio kernel: hwmon hwmon1: Undervoltage detected! Jan 13 09:52:19 volumio kernel: hwmon hwmon1: Voltage normalised Jan 13 09:52:23 volumio volumio-remote-updater[642]: [2026-01-13 09:52:23] [connect] Successful connection Jan 13 09:52:27 volumio sudo[1660]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-13 09:51' Jan 13 09:52:27 volumio sudo[1660]: 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"