Oct 12 20:58:25 volumio ntpd[969]: CLOCK: time stepped by 496226.845537 Oct 12 20:58:25 volumio ntpd[969]: CLOCK: time changed from 2026-10-07 to 2026-10-12 Oct 12 20:58:25 volumio ntpd[969]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Oct 12 20:58:25 volumio volumio[1184]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 12 20:58:25 volumio volumio[1184]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 12 20:58:25 volumio volumio[1184]: info: Loading plugins from folder /data/plugins/ Oct 12 20:58:25 volumio volumio[1184]: info: Loading plugin "system"... Oct 12 20:58:25 volumio volumio[1184]: info: Loading plugin "appearance"... Oct 12 20:58:25 volumio volumio-remote-updater[721]: [2026-10-12 20:58:25] [connect] Successful connection Oct 12 20:58:27 volumio volumio[1184]: info: Loading plugin "network"... Oct 12 20:58:27 volumio volumio[1184]: info: Refreshing Cached IP Addresses Oct 12 20:58:27 volumio volumio[1184]: info: Loading plugin "services"... Oct 12 20:58:27 volumio volumio[1184]: info: Loading plugin "volumio5onboarding"... Oct 12 20:58:27 volumio sudo[1347]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 12 20:58:27 volumio sudo[1347]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 20:58:27 volumio volumio[1184]: info: Loading plugin "alsa_controller"... Oct 12 20:58:27 volumio sudo[1349]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 12 20:58:27 volumio sudo[1349]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 20:58:27 volumio sudo[1347]: pam_unix(sudo:session): session closed for user root Oct 12 20:58:27 volumio sudo[1359]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 12 20:58:27 volumio sudo[1349]: pam_unix(sudo:session): session closed for user root Oct 12 20:58:27 volumio sudo[1359]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 20:58:27 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 12 20:58:27 volumio volumio[1184]: info: Loading plugin "wizard"... Oct 12 20:58:27 volumio volumio[1184]: info: Loading plugin "networkfs"... Oct 12 20:58:27 volumio volumio[1184]: info: Starting Udev Watcher for removable devices Oct 12 20:58:27 volumio sudo[1380]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=qnap_reader,password=qnap_reader,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.10.222/music\\music /mnt/NAS/QNAP Oct 12 20:58:27 volumio sudo[1380]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 20:58:27 volumio volumio[1184]: info: Ignoring mount for partition: boot Oct 12 20:58:27 volumio volumio[1184]: info: Ignoring mount for partition: volumio Oct 12 20:58:27 volumio volumio[1184]: info: Ignoring mount for partition: volumio_data Oct 12 20:58:27 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 12 20:58:27 volumio volumio[1184]: info: Loading plugin "volumio_command_line_client"... Oct 12 20:58:27 volumio volumio[1184]: info: Plugin upnp is not enabled Oct 12 20:58:27 volumio volumio[1184]: info: Loading plugin "my_music"... Oct 12 20:58:27 volumio volumio[1184]: info: Loading plugin "mpd"... Oct 12 20:58:27 volumio kernel: netfs: FS-Cache loaded Oct 12 20:58:28 volumio kernel: Key type cifs.spnego registered Oct 12 20:58:28 volumio kernel: Key type cifs.idmap registered Oct 12 20:58:28 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 12 20:58:28 volumio kernel: CIFS: Attempting to mount //192.168.10.222/music/music Oct 12 20:58:28 volumio sudo[1359]: pam_unix(sudo:session): session closed for user root Oct 12 20:58:28 volumio volumio[1184]: info: Loading plugin "upnp_browser"... Oct 12 20:58:28 volumio sudo[1380]: pam_unix(sudo:session): session closed for user root Oct 12 20:58:28 volumio systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... Oct 12 20:58:28 volumio systemd[1]: Starting e2scrub_all.service - Online ext4 Metadata Check for All Filesystems... Oct 12 20:58:28 volumio systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Oct 12 20:58:29 volumio systemd[1]: e2scrub_all.service: Deactivated successfully. Oct 12 20:58:29 volumio systemd[1]: Finished e2scrub_all.service - Online ext4 Metadata Check for All Filesystems. Oct 12 20:58:29 volumio systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Oct 12 20:58:29 volumio systemd[1]: dpkg-db-backup.service: Deactivated successfully. Oct 12 20:58:29 volumio systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. Oct 12 20:58:29 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully. Oct 12 20:58:30 volumio sh[679]: timed out Oct 12 20:58:30 volumio dhcpcd[689]: timed out Oct 12 20:58:30 volumio sh[633]: ifup: failed to bring up eth0 Oct 12 20:58:30 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Oct 12 20:58:30 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Oct 12 20:58:31 volumio volumio[1184]: info: Starting UPNP Browser Oct 12 20:58:31 volumio volumio[1184]: info: Loading plugin "alarm-clock"... Oct 12 20:58:31 volumio volumio[1184]: info: Loading plugin "airplay_emulation"... Oct 12 20:58:32 volumio volumio[1184]: info: Starting Shairport Sync Oct 12 20:58:32 volumio volumio[1184]: info: Loading plugin "last_100"... Oct 12 20:58:32 volumio volumio[1184]: info: Loading plugin "webradio"... Oct 12 20:58:32 volumio volumio[1184]: info: Loading plugin "i2s_dacs"... Oct 12 20:58:32 volumio volumio[1184]: info: Loading plugin "volumiodiscovery"... Oct 12 20:58:32 volumio volumio[1184]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 12 20:58:32 volumio volumio[1184]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 12 20:58:32 volumio volumio[1184]: *** WARNING *** For more information see Oct 12 20:58:32 volumio volumio[1184]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 12 20:58:32 volumio volumio[1184]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 12 20:58:32 volumio volumio[1184]: *** WARNING *** For more information see Oct 12 20:58:32 volumio node[1184]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 12 20:58:32 volumio node[1184]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 12 20:58:32 volumio node[1184]: *** WARNING *** For more information see Oct 12 20:58:32 volumio node[1184]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 12 20:58:32 volumio node[1184]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 12 20:58:32 volumio node[1184]: *** WARNING *** For more information see Oct 12 20:58:32 volumio volumio[1184]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 12 20:58:32 volumio volumio[1184]: info: Discovery: Started advertising with name: Volumio Oct 12 20:58:32 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 12 20:58:32 volumio volumio[1184]: info: Loading plugin "outputs"... Oct 12 20:58:32 volumio volumio[1184]: info: Loading plugin "albumart"... Oct 12 20:58:32 volumio volumio[1184]: info: Plugin example_plugin is not enabled Oct 12 20:58:32 volumio volumio[1184]: info: Loading plugin "inputs"... Oct 12 20:58:32 volumio volumio[1184]: info: Loading plugin "updater_comm"... Oct 12 20:58:32 volumio volumio[1184]: info: Plugin mpdemulation is not enabled Oct 12 20:58:32 volumio volumio[1184]: info: Loading plugin "rest_api"... Oct 12 20:58:32 volumio volumio[1184]: info: Loading plugin "websocket"... Oct 12 20:58:32 volumio volumio[1184]: info: Starting Socket.io Server version 1.7.4 Oct 12 20:58:32 volumio volumio[1184]: info: Loading i18n strings for locale de Oct 12 20:58:32 volumio volumio[1184]: Updating browse sources language Oct 12 20:58:32 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 20:58:33 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 20:58:33 volumio volumio[1184]: info: CoreCommandRouter::initPlayerControls Oct 12 20:58:33 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 20:58:33 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 20:58:33 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 20:58:33 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 20:58:33 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 20:58:33 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 20:58:33 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 20:58:33 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 20:58:33 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 20:58:33 volumio volumio[1184]: Express server listening on port 3000 Oct 12 20:58:33 volumio volumio[1184]: [Metrics] WebUI: 11s 368.24ms Oct 12 20:58:33 volumio volumio[1184]: info: CoreStateMachine::resetVolumioState Oct 12 20:58:33 volumio volumio[1184]: info: CoreStateMachine::getcurrentVolume Oct 12 20:58:33 volumio volumio[1184]: info: CoreCommandRouter::volumioRetrievevolume Oct 12 20:58:33 volumio volumio[1184]: info: Volumio Network Manager: Network status updated: 2 Oct 12 20:58:33 volumio volumio[1184]: info: VolumeController:: Volume=64 Mute =false Oct 12 20:58:33 volumio volumio[1184]: info: CoreStateMachine::pushState Oct 12 20:58:33 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Oct 12 20:58:33 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 20:58:33 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Oct 12 20:58:33 volumio volumio[1184]: info: CoreStateMachine::updateTrackBlock Oct 12 20:58:33 volumio volumio[1184]: info: CorePlayQueue::getTrackBlock Oct 12 20:58:33 volumio volumio[1184]: info: CoreCommandRouter::volumioRetrievevolume Oct 12 20:58:33 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 20:58:33 volumio volumio[1184]: info: Reloading queue from file Oct 12 20:58:33 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Oct 12 20:58:33 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 12 20:58:33 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 12 20:58:33 volumio upmpdcli[1457]: Could not open config: /tmp/upmpdcli.conf Oct 12 20:58:33 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 12 20:58:33 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 12 20:58:33 volumio volumio[1184]: info: CoreStateMachine::setRepeat null single undefined Oct 12 20:58:33 volumio volumio[1184]: info: CoreStateMachine::pushState Oct 12 20:58:33 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Oct 12 20:58:33 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 20:58:33 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Oct 12 20:58:33 volumio volumio[1184]: info: CoreStateMachine::setRandom null Oct 12 20:58:33 volumio volumio[1184]: info: CoreStateMachine::pushState Oct 12 20:58:33 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Oct 12 20:58:33 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Oct 12 20:58:34 volumio volumio[1184]: info: Setting Device type: Raspberry PI Oct 12 20:58:34 volumio volumio[1184]: info: Completed loading Core Plugins Oct 12 20:58:34 volumio volumio[1184]: info: Preparing to generate the ALSA configuration file Oct 12 20:58:34 volumio nmbd[1048]: [2026/10/12 20:58:34.250310, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Oct 12 20:58:34 volumio nmbd[1048]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.10.89 for name WORKGROUP<1d>. Oct 12 20:58:34 volumio nmbd[1048]: This response was from IP 192.168.10.51, reporting an IP address of 192.168.10.51. Oct 12 20:58:34 volumio volumio[1184]: info: VolumeController:: Volume=64 Mute =false Oct 12 20:58:34 volumio volumio[1184]: info: CoreStateMachine::pushState Oct 12 20:58:34 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Oct 12 20:58:34 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Oct 12 20:58:34 volumio volumio[1184]: info: Discovery: adding a215eb68-0bc4-48de-bd94-91c5a81c0edf Oct 12 20:58:34 volumio volumio[1184]: info: Discovery: Found device Volumio Oct 12 20:58:34 volumio volumio[1184]: info: CoreCommandRouter::volumioGetState Oct 12 20:58:34 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Oct 12 20:58:34 volumio volumio[1184]: info: Discovery: this is already registered, a215eb68-0bc4-48de-bd94-91c5a81c0edf Oct 12 20:58:34 volumio volumio[1184]: info: Discovery: Found device Volumio Oct 12 20:58:34 volumio volumio[1184]: info: CoreCommandRouter::volumioGetState Oct 12 20:58:34 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Oct 12 20:58:34 volumio volumio[1184]: info: Asound.conf file unchanged, so no further update is needed Oct 12 20:58:34 volumio volumio[1184]: info: Output device has changed, restarting MPD Oct 12 20:58:34 volumio sudo[1463]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 12 20:58:34 volumio sudo[1463]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 20:58:34 volumio volumio[1184]: info: Output device has changed, restarting Shairport Sync Oct 12 20:58:34 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 20:58:34 volumio sudo[1463]: pam_unix(sudo:session): session closed for user root Oct 12 20:58:34 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 20:58:34 volumio sudo[1466]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 12 20:58:34 volumio sudo[1466]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 20:58:34 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. Oct 12 20:58:34 volumio volumio[1184]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 12 20:58:34 volumio volumio[1184]: info: ___________ START PLUGINS ___________ Oct 12 20:58:34 volumio volumio[1184]: info: ControllerMpd::onStart: Initializing MPD Oct 12 20:58:34 volumio volumio[1184]: info: Creating MPD Configuration file Oct 12 20:58:34 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 20:58:34 volumio volumio[1184]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 12 20:58:34 volumio volumio[1184]: info: [1791831514881] CoreMusicLibrary::Adding element Medienserver Oct 12 20:58:34 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 20:58:34 volumio volumio[1184]: info: UPNP Browser: Client initialized successfully Oct 12 20:58:34 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 20:58:34 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 20:58:34 volumio sudo[1478]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 12 20:58:34 volumio sudo[1478]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 20:58:34 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 12 20:58:34 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 12 20:58:35 volumio volumio[1184]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 12 20:58:35 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 20:58:35 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 20:58:35 volumio sudo[1476]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Oct 12 20:58:35 volumio sudo[1476]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 20:58:35 volumio sudo[1481]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 12 20:58:35 volumio sudo[1478]: pam_unix(sudo:session): session closed for user root Oct 12 20:58:35 volumio volumio[1184]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 12 20:58:35 volumio volumio[1184]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 12 20:58:35 volumio volumio[1184]: info: [1791831515074] CoreMusicLibrary::Adding element Last_100 Oct 12 20:58:35 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 20:58:35 volumio sudo[1481]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 20:58:35 volumio volumio[1184]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 12 20:58:35 volumio volumio[1184]: info: [1791831515093] CoreMusicLibrary::Adding element Webradio Oct 12 20:58:35 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 20:58:35 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 12 20:58:35 volumio volumio[1184]: info: Initializing BBC Radios Oct 12 20:58:35 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 12 20:58:35 volumio sudo[1476]: pam_unix(sudo:session): session closed for user root Oct 12 20:58:35 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 12 20:58:35 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 12 20:58:35 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 12 20:58:35 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 12 20:58:35 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 12 20:58:35 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 12 20:58:35 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 12 20:58:35 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 12 20:58:35 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 20:58:35 volumio volumio[1433]: Forking 3 albumart workers Oct 12 20:58:35 volumio volumio[1184]: info: Volumio Calling Home Oct 12 20:58:35 volumio sudo[1509]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 12 20:58:35 volumio sudo[1509]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 12 20:58:35 volumio sudo[1521]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 12 20:58:35 volumio sudo[1509]: pam_unix(sudo:session): session closed for user root Oct 12 20:58:35 volumio volumio5-onboarding[1497]: time=2026-10-12T20:58:35.977+02:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Oct 12 20:58:40 volumio volumio-remote-updater[721]: [2026-10-12 20:58:40] [connect] Successful connection Oct 12 20:58:42 volumio volumio[1184]: info: MPD Permissions set Oct 12 20:58:42 volumio volumio[1184]: info: MPD Permissions set Oct 12 20:58:42 volumio volumio[1184]: info: Volumio called home Oct 12 20:58:43 volumio mpd[1530]: 2026-10-12T20:58:43 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 12 20:58:44 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Oct 12 20:58:44 volumio systemd[1]: Started mpd.service - Music Player Daemon. Oct 12 20:58:44 volumio sudo[1466]: pam_unix(sudo:session): session closed for user root Oct 12 20:58:44 volumio sudo[1481]: pam_unix(sudo:session): session closed for user root Oct 12 20:58:44 volumio volumio[1184]: 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 Oct 12 20:58:44 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 20:58:44 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 20:58:44 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 20:58:44 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 20:58:44 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 20:58:44 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 20:58:44 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 20:58:44 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 20:58:45 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 20:58:45 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 20:58:45 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 20:58:45 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 20:58:45 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 20:58:45 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 20:58:45 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 20:58:45 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 20:58:45 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 20:58:45 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 20:58:45 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 20:58:45 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 20:58:46 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Oct 12 20:58:46 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Oct 12 20:58:46 volumio systemd[1]: setdatetime-helper.service: Consumed 1.081s CPU time. Oct 12 20:58:46 volumio volumio[1184]: 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 Oct 12 20:58:46 volumio volumio[1184]: info: Received Get System Info Oct 12 20:58:46 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 20:58:46 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 20:58:46 volumio volumio[1184]: info: Discovery: Getting this device information Oct 12 20:58:46 volumio volumio[1184]: info: CoreCommandRouter::volumioGetState Oct 12 20:58:46 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Oct 12 20:58:46 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 20:58:46 volumio volumio5-onboarding[1497]: time=2026-10-12T20:58:46.382+02:00 level=INFO msg="system info for c4d6da523d9ddd4927104cf732d10344" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.119 Oct 12 20:58:46 volumio volumio5-onboarding[1497]: time=2026-10-12T20:58:46.396+02:00 level=INFO msg="bootstrapping state" hasInternet=true Oct 12 20:58:46 volumio volumio[1520]: Starting albumart workers Oct 12 20:58:47 volumio volumio[1512]: Starting albumart workers Oct 12 20:58:47 volumio volumio[1518]: Starting albumart workers Oct 12 20:58:47 volumio volumio[1184]: info: Completed starting Core Plugins Oct 12 20:58:47 volumio volumio[1184]: info: ------------------------------------------- Oct 12 20:58:47 volumio volumio[1184]: info: ----- MyVolumio plugins startup ---- Oct 12 20:58:47 volumio volumio[1184]: info: ------------------------------------------- Oct 12 20:58:47 volumio volumio[1184]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 12 20:58:47 volumio volumio[1184]: info: Received Get System Info Oct 12 20:58:47 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 20:58:47 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 20:58:47 volumio volumio[1184]: info: Discovery: Getting this device information Oct 12 20:58:47 volumio volumio[1184]: info: CoreCommandRouter::volumioGetState Oct 12 20:58:47 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Oct 12 20:58:47 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 20:58:47 volumio sudo[1615]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 12 20:58:47 volumio sudo[1615]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 20:58:47 volumio sudo[1615]: pam_unix(sudo:session): session closed for user root Oct 12 20:58:48 volumio sudo[1617]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 12 20:58:48 volumio sudo[1617]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 20:58:48 volumio sudo[1617]: pam_unix(sudo:session): session closed for user root Oct 12 20:58:48 volumio volumio[1184]: error: MPD error: The expression evaluated to a falsy value: Oct 12 20:58:48 volumio volumio[1184]: assert.ok(self.idling) Oct 12 20:58:48 volumio volumio[1184]: error: The expression evaluated to a falsy value: Oct 12 20:58:48 volumio volumio[1184]: assert.ok(self.idling) Oct 12 20:58:48 volumio volumio[1184]: info: MPD running with PID1530 Oct 12 20:58:48 volumio volumio[1184]: ,establishing connection Oct 12 20:58:48 volumio volumio[1184]: info: Starting Shairport Sync Oct 12 20:58:48 volumio volumio[1184]: info: Starting Shairport Sync Oct 12 20:58:48 volumio volumio[1184]: info: Starting Shairport Sync Oct 12 20:58:48 volumio volumio[1184]: error: updateQueue error: null Oct 12 20:58:48 volumio sudo[1635]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 12 20:58:48 volumio sudo[1635]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 20:58:48 volumio sudo[1637]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 12 20:58:48 volumio sudo[1637]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 20:58:48 volumio sudo[1639]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 12 20:58:48 volumio sudo[1639]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 20:58:49 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Oct 12 20:58:49 volumio systemd[1]: Starting apt-daily-upgrade.service - Daily apt upgrade and clean activities... Oct 12 20:58:49 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 12 20:58:49 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 12 20:58:49 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 12 20:58:49 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Oct 12 20:58:49 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 12 20:58:49 volumio systemd[1]: shairport-sync.service: Consumed 1.609s CPU time. Oct 12 20:58:49 volumio upmpdcli[1644]: Could not open config: /tmp/upmpdcli.conf Oct 12 20:58:49 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 12 20:58:49 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 12 20:58:49 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 12 20:58:49 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 12 20:58:49 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Oct 12 20:58:49 volumio volumio[1184]: verbose: New Socket.io Connection to 192.168.10.89 from 192.168.10.25 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/154.0.0.0 Safari/537.36 Edg/154.0.0.0 Engine version: 3 Transport: polling Total Clients: 2 Oct 12 20:58:49 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 12 20:58:49 volumio sudo[1637]: pam_unix(sudo:session): session closed for user root Oct 12 20:58:49 volumio volumio[1184]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Oct 12 20:58:49 volumio sudo[1635]: pam_unix(sudo:session): session closed for user root Oct 12 20:58:49 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 12 20:58:49 volumio sudo[1639]: pam_unix(sudo:session): session closed for user root Oct 12 20:58:49 volumio volumio[1184]: error: updateQueue error: null Oct 12 20:58:50 volumio volumio[1184]: info: Shairport-Sync Started Oct 12 20:58:50 volumio volumio[1184]: Error adding Membership: Error: addMembership EINVAL Oct 12 20:58:50 volumio volumio[1184]: info: Shairport-Sync Started Oct 12 20:58:50 volumio volumio[1184]: info: Shairport-Sync Started Oct 12 20:58:50 volumio systemd[1]: apt-daily-upgrade.service: Deactivated successfully. Oct 12 20:58:50 volumio systemd[1]: Finished apt-daily-upgrade.service - Daily apt upgrade and clean activities. Oct 12 20:58:51 volumio volumio[1184]: info: CoreCommandRouter::volumioGetState Oct 12 20:58:51 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Oct 12 20:58:51 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 12 20:58:51 volumio volumio[1184]: info: Listing playlists Oct 12 20:58:51 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 12 20:58:51 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 12 20:58:51 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 12 20:58:51 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 12 20:58:52 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 12 20:58:52 volumio volumio[1184]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 12 20:58:52 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 12 20:58:52 volumio volumio[1184]: info: Received Get System Version Oct 12 20:58:52 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 12 20:58:53 volumio volumio[1184]: info: Received Get System Info Oct 12 20:58:53 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 20:58:53 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 20:58:53 volumio volumio[1184]: info: Discovery: Getting this device information Oct 12 20:58:53 volumio volumio[1184]: info: CoreCommandRouter::volumioGetState Oct 12 20:58:53 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Oct 12 20:58:53 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 20:58:53 volumio volumio[1184]: info: CoreCommandRouter::volumioGetState Oct 12 20:58:53 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Oct 12 20:58:54 volumio volumio[1184]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Oct 12 20:58:55 volumio volumio-remote-updater[721]: [2026-10-12 20:58:55] [connect] Successful connection Oct 12 20:59:00 volumio volumio-remote-updater[721]: [2026-10-12 20:59:00] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1791831535 101 Oct 12 20:59:00 volumio volumio[1184]: 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: 4 Oct 12 20:59:04 volumio wpa_supplicant[1135]: wlan0: WPA: Group rekeying completed with 2c:3a:fd:07:d4:ed [GTK=CCMP] Oct 12 20:59:04 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 4. Oct 12 20:59:04 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 12 20:59:04 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 12 20:59:04 volumio upmpdcli[1739]: Could not open config: /tmp/upmpdcli.conf Oct 12 20:59:04 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 12 20:59:04 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 12 20:59:05 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 12 20:59:05 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 20:59:05 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 12 20:59:07 volumio volumio[1184]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 12 20:59:07 volumio volumio[1184]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 12 20:59:07 volumio volumio[1184]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 12 20:59:07 volumio volumio[1184]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 12 20:59:07 volumio volumio[1184]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 12 20:59:07 volumio volumio[1184]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 12 20:59:07 volumio volumio[1184]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 12 20:59:07 volumio volumio[1184]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 12 20:59:07 volumio volumio[1184]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 12 20:59:07 volumio volumio[1184]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 12 20:59:07 volumio volumio[1184]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Oct 12 20:59:07 volumio volumio[1184]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 12 20:59:07 volumio volumio[1184]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 12 20:59:07 volumio volumio[1184]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 12 20:59:07 volumio volumio[1184]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 12 20:59:07 volumio volumio[1184]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 12 20:59:07 volumio volumio[1184]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 12 20:59:07 volumio volumio[1184]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 12 20:59:07 volumio volumio[1184]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 12 20:59:07 volumio volumio[1184]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 12 20:59:07 volumio volumio[1184]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 12 20:59:07 volumio volumio[1184]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Oct 12 20:59:07 volumio volumio[1184]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 12 20:59:07 volumio volumio[1184]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 12 20:59:07 volumio volumio[1184]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 12 20:59:07 volumio volumio[1184]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 12 20:59:07 volumio volumio[1184]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 12 20:59:07 volumio volumio[1184]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 12 20:59:07 volumio volumio[1184]: info: Adding plugin bluetooth to MyMusic Plugins Oct 12 20:59:07 volumio volumio[1184]: info: Adding plugin multiroom to MyMusic Plugins Oct 12 20:59:07 volumio volumio[1184]: info: Adding plugin metavolumio to MyMusic Plugins Oct 12 20:59:07 volumio volumio[1184]: info: Adding plugin cd_controller to MyMusic Plugins Oct 12 20:59:07 volumio volumio[1184]: info: Adding plugin qobuzconnect to MyMusic Plugins Oct 12 20:59:07 volumio volumio[1184]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 12 20:59:07 volumio volumio[1184]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 12 20:59:07 volumio volumio[1184]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 12 20:59:14 volumio volumio[1184]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 12 20:59:14 volumio volumio[1184]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 12 20:59:14 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 20:59:14 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 20:59:14 volumio volumio[1184]: info: Starting MyVolumio Remote Streaming Endpoints Oct 12 20:59:14 volumio volumio[1184]: info: MyVolumio not started Oct 12 20:59:14 volumio volumio[1184]: info: Initializing device activation check Oct 12 20:59:14 volumio volumio[1184]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 12 20:59:14 volumio volumio[1184]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 12 20:59:16 volumio volumio5-onboarding[1497]: failed to bootstrap state: failed to check for software update: could not check for updates: context deadline exceeded Oct 12 20:59:16 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Oct 12 20:59:16 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Oct 12 20:59:16 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 1. Oct 12 20:59:16 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 12 20:59:16 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 12 20:59:16 volumio volumio5-onboarding[1757]: time=2026-10-12T20:59:16.656+02:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Oct 12 20:59:19 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 5. Oct 12 20:59:19 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 12 20:59:19 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 12 20:59:19 volumio upmpdcli[1779]: Could not open config: /tmp/upmpdcli.conf Oct 12 20:59:19 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 12 20:59:19 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 12 20:59:19 volumio volumio[1184]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 12 20:59:19 volumio volumio[1184]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 12 20:59:19 volumio volumio[1184]: info: Streaming services startup Oct 12 20:59:19 volumio volumio[1184]: info: Starting Streaming Daemon Oct 12 20:59:20 volumio volumio[1184]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 12 20:59:20 volumio sudo[1782]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 12 20:59:20 volumio sudo[1782]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 20:59:20 volumio sudo[1782]: pam_unix(sudo:session): session closed for user root Oct 12 20:59:26 volumio volumio5-onboarding[1757]: 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:33634->127.0.0.1:3000: i/o timeout Oct 12 20:59:26 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Oct 12 20:59:26 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Oct 12 20:59:26 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 2. Oct 12 20:59:26 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 12 20:59:26 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 12 20:59:27 volumio volumio5-onboarding[1788]: time=2026-10-12T20:59:27.015+02:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Oct 12 20:59:33 volumio volumio[1184]: info: CoreCommandRouter::volumioGetVisibleSources Oct 12 20:59:33 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 20:59:33 volumio volumio[1184]: info: CoreCommandRouter::volumioGetQueue Oct 12 20:59:33 volumio volumio[1184]: info: CoreStateMachine::getQueue Oct 12 20:59:33 volumio volumio[1184]: info: CorePlayQueue::getQueue Oct 12 20:59:33 volumio volumio[1184]: Searching all installed plugins Oct 12 20:59:33 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 20:59:33 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: , search Oct 12 20:59:33 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 12 20:59:33 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Oct 12 20:59:33 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Oct 12 20:59:33 volumio volumio[1184]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Oct 12 20:59:33 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: webradio , search Oct 12 20:59:34 volumio volumio[1184]: error: Cannot start Volumio Streaming Daemon Oct 12 20:59:34 volumio volumio[1184]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 12 20:59:34 volumio volumio[1184]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 12 20:59:34 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 20:59:34 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 6. Oct 12 20:59:34 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 12 20:59:34 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 20:59:34 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 12 20:59:34 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 12 20:59:34 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 12 20:59:34 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 12 20:59:34 volumio volumio[1184]: info: CoreCommandRouter::volumioGetBrowseSources Oct 12 20:59:34 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 12 20:59:34 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 12 20:59:34 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 12 20:59:35 volumio upmpdcli[1809]: Could not open config: /tmp/upmpdcli.conf Oct 12 20:59:35 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 12 20:59:35 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 12 20:59:35 volumio volumio[1184]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 20:59:35 volumio volumio[1184]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Oct 12 20:59:35 volumio volumio[1184]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Oct 12 20:59:35 volumio volumio[1184]: at new NodeError (node:internal/errors:405:5) Oct 12 20:59:35 volumio volumio[1184]: at assert (node:internal/assert:14:11) Oct 12 20:59:35 volumio volumio[1184]: at internalConnectMultiple (node:net:1118:3) Oct 12 20:59:35 volumio volumio[1184]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Oct 12 20:59:35 volumio volumio[1184]: at listOnTimeout (node:internal/timers:575:11) Oct 12 20:59:35 volumio volumio[1184]: at process.processTimers (node:internal/timers:514:7) { Oct 12 20:59:35 volumio volumio[1184]: code: 'ERR_INTERNAL_ASSERTION' Oct 12 20:59:35 volumio volumio[1184]: } Oct 12 20:59:35 volumio volumio[1184]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 20:59:37 volumio volumio5-onboarding[1788]: 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:44612->127.0.0.1:3000: i/o timeout Oct 12 20:59:37 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Oct 12 20:59:37 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Oct 12 20:59:37 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 3. Oct 12 20:59:37 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 12 20:59:37 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 12 20:59:37 volumio volumio5-onboarding[1822]: time=2026-10-12T20:59:37.542+02:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Oct 12 20:59:42 volumio sudo[1847]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-12 20:58' Oct 12 20:59:42 volumio sudo[1847]: 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"