Aug 30 11:39:16 volumio ntpd[886]: CLOCK: time stepped by 10339026.205815 Aug 30 11:39:16 volumio ntpd[886]: CLOCK: time changed from 2026-05-02 to 2026-08-30 Aug 30 11:39:16 volumio ntpd[886]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Aug 30 11:39:17 volumio systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... Aug 30 11:39:17 volumio systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Aug 30 11:39:17 volumio systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Aug 30 11:39:17 volumio systemd[1]: dpkg-db-backup.service: Deactivated successfully. Aug 30 11:39:17 volumio systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. Aug 30 11:39:18 volumio volumio-remote-updater[727]: [2026-08-30 11:39:18] [info] asio async_connect error: asio.system:111 (Connection refused) Aug 30 11:39:18 volumio volumio-remote-updater[727]: [2026-08-30 11:39:18] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Aug 30 11:39:18 volumio volumio-remote-updater[727]: [2026-08-30 11:39:18] [error] handle_connect error: Connection refused Aug 30 11:39:19 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Aug 30 11:39:19 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Aug 30 11:39:19 volumio systemd[1]: setdatetime-helper.service: Consumed 2.426s CPU time. Aug 30 11:39:19 volumio systemd[1]: Reached target multi-user.target - Multi-User System. Aug 30 11:39:19 volumio systemd[1]: Reached target graphical.target - Graphical Interface. Aug 30 11:39:19 volumio systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Aug 30 11:39:19 volumio systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Aug 30 11:39:19 volumio systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Aug 30 11:39:19 volumio systemd[1]: Startup finished in 17.554s (kernel) + 20.722s (userspace) = 38.276s. Aug 30 11:39:21 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1. Aug 30 11:39:21 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 30 11:39:21 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 30 11:39:21 volumio upmpdcli[1315]: Could not open config: /tmp/upmpdcli.conf Aug 30 11:39:21 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 30 11:39:21 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 30 11:39:21 volumio volumio[1271]: info: ------------------------------------------- Aug 30 11:39:21 volumio volumio[1271]: info: ----- Volumio3 ---- Aug 30 11:39:21 volumio volumio[1271]: info: ------------------------------------------- Aug 30 11:39:21 volumio volumio[1271]: info: ----- System startup ---- Aug 30 11:39:21 volumio volumio[1271]: info: ------------------------------------------- Aug 30 11:39:23 volumio volumio[1271]: info: MYVOLUMIO Environment detected Aug 30 11:39:23 volumio volumio[1271]: info: Plugin folders cleanup Aug 30 11:39:23 volumio volumio[1271]: info: Scanning into folder /volumio/app/plugins/ Aug 30 11:39:23 volumio volumio[1271]: info: Scanning category audio_interface Aug 30 11:39:23 volumio volumio[1271]: info: Scanning category miscellanea Aug 30 11:39:23 volumio volumio[1271]: info: Scanning category music_service Aug 30 11:39:23 volumio volumio[1271]: info: Scanning category plugins.json Aug 30 11:39:23 volumio volumio[1271]: info: Scanning category system_controller Aug 30 11:39:23 volumio volumio[1271]: info: Scanning category user_interface Aug 30 11:39:23 volumio volumio[1271]: info: Scanning into folder /data/plugins/ Aug 30 11:39:23 volumio volumio[1271]: info: Scanning category music_service Aug 30 11:39:23 volumio volumio[1271]: info: Plugin folders cleanup completed Aug 30 11:39:23 volumio volumio[1271]: info: ------------------------------------------- Aug 30 11:39:23 volumio volumio[1271]: info: ----- Core plugins startup ---- Aug 30 11:39:23 volumio volumio[1271]: info: ------------------------------------------- Aug 30 11:39:23 volumio volumio[1271]: info: Loading plugins from folder /volumio/app/plugins/ Aug 30 11:39:23 volumio volumio[1271]: info: Adding plugin upnp to MyMusic Plugins Aug 30 11:39:23 volumio volumio[1271]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 30 11:39:23 volumio volumio[1271]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 30 11:39:23 volumio volumio[1271]: info: Loading plugins from folder /data/plugins/ Aug 30 11:39:23 volumio volumio[1271]: info: Loading plugin "system"... Aug 30 11:39:23 volumio volumio[1271]: info: Loading plugin "appearance"... Aug 30 11:39:26 volumio volumio[1271]: info: Loading plugin "network"... Aug 30 11:39:26 volumio volumio[1271]: info: Refreshing Cached IP Addresses Aug 30 11:39:26 volumio sudo[1330]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 30 11:39:26 volumio sudo[1330]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 11:39:26 volumio volumio[1271]: info: Loading plugin "services"... Aug 30 11:39:26 volumio sudo[1330]: pam_unix(sudo:session): session closed for user root Aug 30 11:39:26 volumio volumio[1271]: info: Loading plugin "volumio5onboarding"... Aug 30 11:39:26 volumio sudo[1338]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 30 11:39:26 volumio sudo[1331]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 30 11:39:26 volumio sudo[1338]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 11:39:26 volumio sudo[1331]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 11:39:26 volumio volumio[1271]: info: Loading plugin "alsa_controller"... Aug 30 11:39:26 volumio sudo[1331]: pam_unix(sudo:session): session closed for user root Aug 30 11:39:26 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 11:39:26 volumio volumio[1271]: info: Loading plugin "wizard"... Aug 30 11:39:26 volumio volumio[1271]: info: Loading plugin "networkfs"... Aug 30 11:39:26 volumio volumio[1271]: info: Starting Udev Watcher for removable devices Aug 30 11:39:26 volumio sudo[1361]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=nVng30$97sCnt,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //bunker.local/music/_collection /mnt/NAS/bunker Aug 30 11:39:26 volumio sudo[1361]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 11:39:26 volumio volumio[1271]: info: Ignoring mount for partition: boot Aug 30 11:39:26 volumio volumio[1271]: info: Ignoring mount for partition: volumio Aug 30 11:39:26 volumio volumio[1271]: info: Ignoring mount for partition: volumio_data Aug 30 11:39:26 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 30 11:39:26 volumio volumio[1271]: info: Loading plugin "volumio_command_line_client"... Aug 30 11:39:26 volumio volumio[1271]: info: Loading plugin "upnp"... Aug 30 11:39:26 volumio volumio[1271]: info: [1788082766486] Starting Upmpd Daemon Aug 30 11:39:26 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 30 11:39:26 volumio volumio[1271]: info: Loading plugin "my_music"... Aug 30 11:39:26 volumio volumio[1271]: info: Loading plugin "mpd"... Aug 30 11:39:26 volumio kernel: netfs: FS-Cache loaded Aug 30 11:39:26 volumio sudo[1338]: pam_unix(sudo:session): session closed for user root Aug 30 11:39:27 volumio kernel: Key type cifs.spnego registered Aug 30 11:39:27 volumio kernel: Key type cifs.idmap registered Aug 30 11:39:27 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. Aug 30 11:39:27 volumio kernel: CIFS: Attempting to mount //bunker.local/music/_collection Aug 30 11:39:27 volumio volumio[1271]: info: Loading plugin "upnp_browser"... Aug 30 11:39:28 volumio volumio-remote-updater[727]: [2026-08-30 11:39:28] [connect] Successful connection Aug 30 11:39:28 volumio sudo[1361]: pam_unix(sudo:session): session closed for user root Aug 30 11:39:30 volumio volumio[1271]: info: Starting UPNP Browser Aug 30 11:39:30 volumio volumio[1271]: info: Loading plugin "alarm-clock"... Aug 30 11:39:30 volumio volumio[1271]: info: Loading plugin "airplay_emulation"... Aug 30 11:39:30 volumio volumio[1271]: info: Starting Shairport Sync Aug 30 11:39:30 volumio volumio[1271]: info: Loading plugin "last_100"... Aug 30 11:39:30 volumio volumio[1271]: info: Loading plugin "webradio"... Aug 30 11:39:30 volumio volumio[1271]: info: Loading plugin "i2s_dacs"... Aug 30 11:39:30 volumio volumio[1271]: info: Loading plugin "volumiodiscovery"... Aug 30 11:39:30 volumio volumio[1271]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 30 11:39:30 volumio node[1271]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 30 11:39:30 volumio node[1271]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 30 11:39:30 volumio volumio[1271]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 30 11:39:30 volumio volumio[1271]: *** WARNING *** For more information see Aug 30 11:39:30 volumio volumio[1271]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 30 11:39:30 volumio volumio[1271]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 30 11:39:30 volumio volumio[1271]: *** WARNING *** For more information see Aug 30 11:39:30 volumio node[1271]: *** WARNING *** For more information see Aug 30 11:39:30 volumio node[1271]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 30 11:39:30 volumio node[1271]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 30 11:39:30 volumio node[1271]: *** WARNING *** For more information see Aug 30 11:39:30 volumio volumio[1271]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 30 11:39:30 volumio volumio[1271]: info: Discovery: Started advertising with name: Volumio Aug 30 11:39:30 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 30 11:39:30 volumio volumio[1271]: info: Loading plugin "spop"... Aug 30 11:39:32 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully. Aug 30 11:39:34 volumio volumio[1271]: info: Loading plugin "outputs"... Aug 30 11:39:34 volumio volumio[1271]: info: Loading plugin "albumart"... Aug 30 11:39:34 volumio volumio[1271]: info: Plugin example_plugin is not enabled Aug 30 11:39:34 volumio volumio[1271]: info: Loading plugin "inputs"... Aug 30 11:39:34 volumio volumio[1271]: info: Loading plugin "updater_comm"... Aug 30 11:39:34 volumio volumio[1271]: info: Plugin mpdemulation is not enabled Aug 30 11:39:34 volumio volumio[1271]: info: Loading plugin "rest_api"... Aug 30 11:39:34 volumio volumio[1271]: info: Loading plugin "websocket"... Aug 30 11:39:34 volumio volumio[1271]: info: Starting Socket.io Server version 1.7.4 Aug 30 11:39:34 volumio volumio[1271]: info: Loading plugin "volusonic"... Aug 30 11:39:35 volumio volumio[1400]: Forking 3 albumart workers Aug 30 11:39:36 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Aug 30 11:39:36 volumio systemd[1]: Starting e2scrub_all.service - Online ext4 Metadata Check for All Filesystems... Aug 30 11:39:36 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 30 11:39:36 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 30 11:39:36 volumio systemd[1]: e2scrub_all.service: Deactivated successfully. Aug 30 11:39:36 volumio systemd[1]: Finished e2scrub_all.service - Online ext4 Metadata Check for All Filesystems. Aug 30 11:39:36 volumio upmpdcli[1455]: Could not open config: /tmp/upmpdcli.conf Aug 30 11:39:36 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 30 11:39:36 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 30 11:39:39 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. Aug 30 11:39:43 volumio volumio-remote-updater[727]: [2026-08-30 11:39:43] [connect] Successful connection Aug 30 11:39:45 volumio volumio[1411]: Starting albumart workers Aug 30 11:39:45 volumio volumio[1412]: Starting albumart workers Aug 30 11:39:46 volumio volumio[1410]: Starting albumart workers Aug 30 11:39:46 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Aug 30 11:39:47 volumio volumio[1271]: info: Applying required configuration parameters for plugin volusonic Aug 30 11:39:47 volumio volumio[1271]: info: Loading i18n strings for locale en Aug 30 11:39:47 volumio volumio[1271]: Updating browse sources language Aug 30 11:39:47 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 11:39:48 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Aug 30 11:39:48 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Aug 30 11:39:48 volumio systemd[1]: setdatetime-helper.service: Consumed 1.134s CPU time. Aug 30 11:39:49 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 11:39:49 volumio volumio[1271]: info: CoreCommandRouter::initPlayerControls Aug 30 11:39:49 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 11:39:49 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 11:39:49 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 11:39:49 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 11:39:49 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 11:39:49 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 11:39:49 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 11:39:49 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 11:39:49 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 11:39:49 volumio volumio[1271]: Express server listening on port 3000 Aug 30 11:39:49 volumio volumio[1271]: [Metrics] WebUI: 28s 618.04ms Aug 30 11:39:49 volumio volumio[1271]: info: CoreStateMachine::resetVolumioState Aug 30 11:39:49 volumio volumio[1271]: info: CoreStateMachine::getcurrentVolume Aug 30 11:39:49 volumio volumio[1271]: info: CoreCommandRouter::volumioRetrievevolume Aug 30 11:39:49 volumio sudo[1509]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 30 11:39:50 volumio sudo[1509]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 11:39:50 volumio sudo[1510]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 30 11:39:50 volumio sudo[1510]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 11:39:50 volumio sudo[1510]: pam_unix(sudo:session): session closed for user root Aug 30 11:39:50 volumio sudo[1509]: pam_unix(sudo:session): session closed for user root Aug 30 11:39:50 volumio volumio[1271]: info: Volumio Network Manager: Network status updated: 1 Aug 30 11:39:51 volumio volumio[1271]: info: CoreStateMachine::pushState Aug 30 11:39:51 volumio volumio[1271]: info: CorePlayQueue::getTrack 0 Aug 30 11:39:51 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 11:39:51 volumio volumio[1271]: info: CoreCommandRouter::volumioPushState Aug 30 11:39:51 volumio volumio[1271]: info: CoreStateMachine::updateTrackBlock Aug 30 11:39:51 volumio volumio[1271]: info: CorePlayQueue::getTrackBlock Aug 30 11:39:51 volumio volumio[1271]: info: CoreCommandRouter::volumioRetrievevolume Aug 30 11:39:51 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 11:39:51 volumio volumio[1271]: info: Reloading queue from file Aug 30 11:39:51 volumio volumio[1271]: info: CoreStateMachine::setRepeat null single undefined Aug 30 11:39:51 volumio volumio[1271]: info: CoreStateMachine::pushState Aug 30 11:39:51 volumio volumio[1271]: info: CorePlayQueue::getTrack 0 Aug 30 11:39:51 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 11:39:51 volumio volumio[1271]: info: CoreCommandRouter::volumioPushState Aug 30 11:39:51 volumio volumio[1271]: info: CoreStateMachine::setRandom null Aug 30 11:39:51 volumio volumio[1271]: info: CoreStateMachine::pushState Aug 30 11:39:51 volumio volumio[1271]: info: CorePlayQueue::getTrack 0 Aug 30 11:39:51 volumio volumio[1271]: info: CoreCommandRouter::volumioPushState Aug 30 11:39:51 volumio volumio[1271]: info: Setting Device type: Raspberry PI Aug 30 11:39:51 volumio volumio[1271]: info: Completed loading Core Plugins Aug 30 11:39:51 volumio volumio[1271]: info: Preparing to generate the ALSA configuration file Aug 30 11:39:51 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Aug 30 11:39:51 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 30 11:39:51 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 30 11:39:51 volumio volumio[1271]: info: CoreStateMachine::pushState Aug 30 11:39:51 volumio volumio[1271]: info: CorePlayQueue::getTrack 0 Aug 30 11:39:51 volumio volumio[1271]: info: CoreCommandRouter::volumioPushState Aug 30 11:39:52 volumio volumio[1271]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Aug 30 11:39:52 volumio volumio[1271]: info: Reading ALSA contributions from plugins. Aug 30 11:39:52 volumio volumio[1271]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Aug 30 11:39:52 volumio sudo[1557]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 30 11:39:52 volumio sudo[1557]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 11:39:52 volumio volumio[1271]: info: Discovery: adding 57bd9636-0bd4-489a-9bb6-b7b8c45bbf04 Aug 30 11:39:52 volumio volumio[1271]: info: Discovery: Found device Volumio Aug 30 11:39:52 volumio volumio[1271]: info: CoreCommandRouter::volumioGetState Aug 30 11:39:52 volumio volumio[1271]: info: CorePlayQueue::getTrack 0 Aug 30 11:39:52 volumio volumio[1271]: info: Discovery: this is already registered, 57bd9636-0bd4-489a-9bb6-b7b8c45bbf04 Aug 30 11:39:52 volumio volumio[1271]: info: Discovery: Found device Volumio Aug 30 11:39:52 volumio volumio[1271]: info: CoreCommandRouter::volumioGetState Aug 30 11:39:52 volumio volumio[1271]: info: CorePlayQueue::getTrack 0 Aug 30 11:39:52 volumio sudo[1557]: pam_unix(sudo:session): session closed for user root Aug 30 11:39:52 volumio volumio[1271]: info: Upmpdcli Daemon Started Aug 30 11:39:52 volumio volumio[1271]: info: Asound.conf file unchanged, so no further update is needed Aug 30 11:39:52 volumio volumio[1271]: info: Output device has changed, restarting MPD Aug 30 11:39:52 volumio volumio[1271]: info: Output device has changed, restarting Shairport Sync Aug 30 11:39:52 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 11:39:52 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 11:39:52 volumio sudo[1560]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 30 11:39:52 volumio sudo[1560]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 11:39:52 volumio sudo[1562]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 30 11:39:52 volumio sudo[1562]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 11:39:52 volumio sudo[1560]: pam_unix(sudo:session): session closed for user root Aug 30 11:39:52 volumio volumio[1271]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 30 11:39:52 volumio volumio[1271]: info: ___________ START PLUGINS ___________ Aug 30 11:39:53 volumio volumio[1271]: info: ControllerMpd::onStart: Initializing MPD Aug 30 11:39:53 volumio volumio[1271]: info: Creating MPD Configuration file Aug 30 11:39:53 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 30 11:39:53 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Aug 30 11:39:53 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 11:39:53 volumio volumio[1271]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 30 11:39:53 volumio volumio[1271]: info: [1788082793254] CoreMusicLibrary::Adding element Media Servers Aug 30 11:39:53 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 11:39:53 volumio sudo[1571]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Aug 30 11:39:53 volumio volumio[1271]: info: UPNP Browser: Client initialized successfully Aug 30 11:39:53 volumio sudo[1574]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 30 11:39:53 volumio sudo[1574]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 11:39:53 volumio sudo[1571]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 11:39:53 volumio sudo[1573]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 30 11:39:53 volumio sudo[1573]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 30 11:39:53 volumio sudo[1579]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Aug 30 11:39:53 volumio sudo[1573]: pam_unix(sudo:session): session closed for user root Aug 30 11:39:53 volumio sudo[1574]: pam_unix(sudo:session): session closed for user root Aug 30 11:39:53 volumio sudo[1576]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 30 11:39:53 volumio sudo[1576]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 11:39:53 volumio systemd[1]: mpd.service: Deactivated successfully. Aug 30 11:39:53 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 30 11:39:53 volumio systemd[1]: mpd.socket: Deactivated successfully. Aug 30 11:39:53 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 30 11:39:53 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 30 11:39:53 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 30 11:39:53 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 11:39:53 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 11:39:53 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Aug 30 11:39:53 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Aug 30 11:39:53 volumio sudo[1571]: pam_unix(sudo:session): session closed for user root Aug 30 11:39:53 volumio sudo[1584]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 30 11:39:53 volumio sudo[1584]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 30 11:39:53 volumio volumio[1271]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 30 11:39:53 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 11:39:53 volumio sudo[1589]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Aug 30 11:39:53 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 11:39:53 volumio sudo[1584]: pam_unix(sudo:session): session closed for user root Aug 30 11:39:54 volumio volumio[1271]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 30 11:39:54 volumio volumio[1271]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 30 11:39:54 volumio volumio[1271]: info: [1788082794031] CoreMusicLibrary::Adding element Last_100 Aug 30 11:39:54 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 11:39:54 volumio volumio[1271]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 30 11:39:54 volumio volumio[1271]: info: [1788082794130] CoreMusicLibrary::Adding element Webradio Aug 30 11:39:54 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 11:39:54 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 11:39:54 volumio volumio[1271]: info: Initializing BBC Radios Aug 30 11:39:54 volumio volumio5-onboarding[1586]: time=2026-08-30T11:39:54.498+02:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Aug 30 11:39:54 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 11:39:54 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 11:39:54 volumio volumio[1271]: info: Creating Spotify config file Aug 30 11:39:54 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 11:39:57 volumio volumio[1271]: info: Loading i18n strings for locale en Aug 30 11:39:58 volumio volumio[1271]: Updating browse sources language Aug 30 11:39:58 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 11:39:58 volumio volumio[1271]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 30 11:39:58 volumio volumio[1271]: info: [1788082798016] CoreMusicLibrary::Adding element Volusonic Aug 30 11:39:58 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 11:39:58 volumio volumio[1271]: Cannot find translation for source Volusonic Aug 30 11:39:58 volumio volumio-remote-updater[727]: [2026-08-30 11:39:58] [connect] Successful connection Aug 30 11:39:58 volumio volumio[1271]: info: Volumio Calling Home Aug 30 11:40:00 volumio volumio[1271]: info: MPD Permissions set Aug 30 11:40:00 volumio volumio[1271]: info: MPD Permissions set Aug 30 11:40:01 volumio volumio[1271]: 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: 1 Aug 30 11:40:01 volumio volumio[1271]: info: Volumio called home Aug 30 11:40:01 volumio volumio[1271]: info: Spotify config file written Aug 30 11:40:01 volumio sudo[1638]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Aug 30 11:40:01 volumio sudo[1638]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 11:40:01 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 30 11:40:01 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 30 11:40:01 volumio mpd[1592]: 2026-08-30T11:40:01 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 30 11:40:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 11:40:01 volumio go-librespot[1640]: go-librespot daemon starting... Aug 30 11:40:01 volumio sudo[1638]: pam_unix(sudo:session): session closed for user root Aug 30 11:40:02 volumio volumio[1271]: 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: 1 Aug 30 11:40:02 volumio volumio[1271]: info: Received Get System Info Aug 30 11:40:02 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 11:40:02 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 11:40:02 volumio volumio[1271]: info: Discovery: Getting this device information Aug 30 11:40:02 volumio volumio[1271]: info: CoreCommandRouter::volumioGetState Aug 30 11:40:02 volumio volumio[1271]: info: CorePlayQueue::getTrack 0 Aug 30 11:40:02 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 11:40:02 volumio volumio5-onboarding[1586]: time=2026-08-30T11:40:02.310+02:00 level=INFO msg="system info for dcdf8ce376176a059293946f5ca17709" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.119 Aug 30 11:40:02 volumio volumio5-onboarding[1586]: time=2026-08-30T11:40:02.333+02:00 level=INFO msg="bootstrapping state" hasInternet=true Aug 30 11:40:02 volumio systemd[1]: Started mpd.service - Music Player Daemon. Aug 30 11:40:02 volumio sudo[1576]: pam_unix(sudo:session): session closed for user root Aug 30 11:40:02 volumio sudo[1562]: pam_unix(sudo:session): session closed for user root Aug 30 11:40:02 volumio go-librespot[1641]: time="2026-08-30T11:40:02+02:00" level=info msg="running go-librespot 0.7.1" Aug 30 11:40:02 volumio go-librespot[1641]: time="2026-08-30T11:40:02+02:00" level=debug msg="app state loaded" Aug 30 11:40:02 volumio go-librespot[1641]: time="2026-08-30T11:40:02+02:00" level=debug msg="stored credentials not found" Aug 30 11:40:02 volumio go-librespot[1641]: time="2026-08-30T11:40:02+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 11:40:03 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 11:40:03 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 11:40:03 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 11:40:03 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 11:40:03 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 11:40:03 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 11:40:03 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 11:40:03 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 11:40:03 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 11:40:03 volumio go-librespot[1641]: time="2026-08-30T11:40:03+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 30 11:40:03 volumio go-librespot[1641]: time="2026-08-30T11:40:03+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 30 11:40:03 volumio go-librespot[1641]: time="2026-08-30T11:40:03+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 30 11:40:03 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 11:40:03 volumio go-librespot[1641]: time="2026-08-30T11:40:03+02:00" level=info msg="zeroconf server listening on port 46335" Aug 30 11:40:03 volumio go-librespot[1641]: time="2026-08-30T11:40:03+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 30 11:40:03 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 11:40:03 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 11:40:03 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 11:40:03 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 11:40:03 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 11:40:03 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 11:40:03 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 11:40:03 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 11:40:03 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 11:40:03 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 11:40:03 volumio volumio[1271]: info: No need to fix Spotify hosts Aug 30 11:40:03 volumio volumio[1271]: info: Received Get System Info Aug 30 11:40:03 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 11:40:03 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 11:40:03 volumio volumio[1271]: info: Discovery: Getting this device information Aug 30 11:40:03 volumio volumio[1271]: info: CoreCommandRouter::volumioGetState Aug 30 11:40:03 volumio volumio[1271]: info: CorePlayQueue::getTrack 0 Aug 30 11:40:03 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 11:40:04 volumio volumio[1271]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Aug 30 11:40:04 volumio volumio[1271]: info: Completed starting Core Plugins Aug 30 11:40:04 volumio volumio[1271]: info: ------------------------------------------- Aug 30 11:40:04 volumio volumio[1271]: info: ----- MyVolumio plugins startup ---- Aug 30 11:40:04 volumio volumio[1271]: info: ------------------------------------------- Aug 30 11:40:04 volumio volumio[1271]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 30 11:40:05 volumio volumio[1271]: info: An error occurred while refreshing Spotify Token Error: Bad Request Aug 30 11:40:05 volumio volumio[1271]: error: MPD error: The expression evaluated to a falsy value: Aug 30 11:40:05 volumio volumio[1271]: assert.ok(self.idling) Aug 30 11:40:05 volumio volumio[1271]: error: The expression evaluated to a falsy value: Aug 30 11:40:05 volumio volumio[1271]: assert.ok(self.idling) Aug 30 11:40:05 volumio volumio[1271]: info: MPD running with PID1592 Aug 30 11:40:05 volumio volumio[1271]: ,establishing connection Aug 30 11:40:05 volumio volumio[1271]: info: Starting Shairport Sync Aug 30 11:40:05 volumio volumio[1271]: info: Starting Shairport Sync Aug 30 11:40:05 volumio volumio[1271]: info: Starting Shairport Sync Aug 30 11:40:05 volumio volumio[1271]: error: updateQueue error: null Aug 30 11:40:05 volumio sudo[1669]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 30 11:40:05 volumio sudo[1669]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 11:40:05 volumio volumio[1271]: error: updateQueue error: null Aug 30 11:40:05 volumio sudo[1671]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 30 11:40:05 volumio sudo[1671]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 11:40:05 volumio sudo[1673]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 30 11:40:05 volumio sudo[1673]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 11:40:06 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 30 11:40:06 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Aug 30 11:40:06 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 30 11:40:06 volumio systemd[1]: shairport-sync.service: Consumed 1.640s CPU time. Aug 30 11:40:06 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 30 11:40:06 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 30 11:40:06 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Aug 30 11:40:06 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 30 11:40:06 volumio sudo[1669]: pam_unix(sudo:session): session closed for user root Aug 30 11:40:06 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 30 11:40:06 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 30 11:40:06 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Aug 30 11:40:06 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 30 11:40:06 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 30 11:40:06 volumio sudo[1673]: pam_unix(sudo:session): session closed for user root Aug 30 11:40:06 volumio sudo[1671]: pam_unix(sudo:session): session closed for user root Aug 30 11:40:06 volumio volumio[1271]: info: Shairport-Sync Started Aug 30 11:40:06 volumio volumio[1271]: Error adding Membership: Error: addMembership EINVAL Aug 30 11:40:06 volumio volumio[1271]: info: Shairport-Sync Started Aug 30 11:40:06 volumio volumio[1271]: info: Shairport-Sync Started Aug 30 11:40:06 volumio volumio[1271]: info: CoreCommandRouter::volumioGetState Aug 30 11:40:06 volumio volumio[1271]: info: CorePlayQueue::getTrack 0 Aug 30 11:40:07 volumio volumio[1271]: info: go-librespot daemon successfully initialized Aug 30 11:40:10 volumio volumio[1271]: info: Initializing connection to go-librespot Websocket Aug 30 11:40:10 volumio go-librespot[1641]: time="2026-08-30T11:40:10+02:00" level=debug msg="new websocket client" Aug 30 11:40:10 volumio volumio[1271]: info: Connection to go-librespot Websocket established Aug 30 11:40:11 volumio volumio[1271]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Aug 30 11:40:13 volumio volumio-remote-updater[727]: [2026-08-30 11:40:13] [connect] Successful connection Aug 30 11:40:13 volumio volumio[1271]: info: Getting Spotify volume Aug 30 11:40:14 volumio volumio-remote-updater[727]: [2026-08-30 11:40:14] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1788082813 101 Aug 30 11:40:14 volumio volumio[1271]: 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: 3 Aug 30 11:40:15 volumio volumio[1271]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Aug 30 11:40:29 volumio volumio[1271]: error: MyVolumio Plugin failed to start in a timely fashion Aug 30 11:40:29 volumio volumio[1271]: [Metrics] CommandRouter: 67s 358.36ms Aug 30 11:40:29 volumio volumio[1271]: info: CoreCommandRouter::volumiosetStartupVolume Aug 30 11:40:29 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 11:40:29 volumio volumio[1271]: info: VolumeController:: Setting startup Volume 85 Aug 30 11:40:29 volumio volumio[1271]: info: VolumeController::SetAlsaVolume85 Aug 30 11:40:29 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 11:40:29 volumio volumio[1271]: info: CoreCommandRouter::Close All Modals sent Aug 30 11:40:29 volumio volumio[1271]: info: CoreCommandRouter::Close All Modals sent Aug 30 11:40:29 volumio volumio[1271]: info: CoreStateMachine::pushState Aug 30 11:40:29 volumio volumio[1271]: info: CorePlayQueue::getTrack 0 Aug 30 11:40:29 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 11:40:29 volumio volumio[1271]: info: CoreCommandRouter::volumioPushState Aug 30 11:40:30 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 11:40:30 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 11:40:30 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 30 11:40:32 volumio volumio5-onboarding[1586]: failed to bootstrap state: failed to check for software update: could not check for updates: context deadline exceeded Aug 30 11:40:32 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Aug 30 11:40:32 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Aug 30 11:40:32 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 1. Aug 30 11:40:32 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Aug 30 11:40:32 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Aug 30 11:40:32 volumio volumio5-onboarding[1748]: time=2026-08-30T11:40:32.674+02:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Aug 30 11:40:36 volumio volumio[1271]: Upnp client error: Error: This socket has been ended by the other party Aug 30 11:40:36 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 30 11:40:36 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 30 11:40:36 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 30 11:40:37 volumio volumio[1271]: info: BOOT COMPLETED Aug 30 11:40:37 volumio volumio[1271]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 11:40:37 volumio volumio[1271]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Aug 30 11:40:37 volumio volumio[1271]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Aug 30 11:40:37 volumio volumio[1271]: at new NodeError (node:internal/errors:405:5) Aug 30 11:40:37 volumio volumio[1271]: at assert (node:internal/assert:14:11) Aug 30 11:40:37 volumio volumio[1271]: at internalConnectMultiple (node:net:1118:3) Aug 30 11:40:37 volumio volumio[1271]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Aug 30 11:40:37 volumio volumio[1271]: at listOnTimeout (node:internal/timers:575:11) Aug 30 11:40:37 volumio volumio[1271]: at process.processTimers (node:internal/timers:514:7) { Aug 30 11:40:37 volumio volumio[1271]: code: 'ERR_INTERNAL_ASSERTION' Aug 30 11:40:37 volumio volumio[1271]: } Aug 30 11:40:37 volumio volumio[1271]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 11:40:42 volumio volumio5-onboarding[1748]: 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:53080->127.0.0.1:3000: i/o timeout Aug 30 11:40:42 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Aug 30 11:40:42 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Aug 30 11:40:42 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 2. Aug 30 11:40:42 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Aug 30 11:40:43 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Aug 30 11:40:43 volumio volumio5-onboarding[1782]: time=2026-08-30T11:40:43.100+02:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Aug 30 11:40:46 volumio sudo[1792]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-30 11:39' Aug 30 11:40:46 volumio sudo[1792]: 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"