Aug 27 17:03:00 kitchenblaster volumio[1681]: Starting albumart workers Aug 27 17:03:00 kitchenblaster volumio[1683]: Starting albumart workers Aug 27 17:03:00 kitchenblaster volumio[1682]: Starting albumart workers Aug 27 17:03:02 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 17:03:02 kitchenblaster volumio[1290]: info: CoreCommandRouter::initPlayerControls Aug 27 17:03:02 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:03:03 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:03:03 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:03:03 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:03:03 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:03:03 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:03:03 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:03:03 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:03:03 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 17:03:03 kitchenblaster volumio[1290]: Express server listening on port 3000 Aug 27 17:03:03 kitchenblaster volumio[1290]: [Metrics] WebUI: 50s 549.98ms Aug 27 17:03:03 kitchenblaster volumio[1290]: info: CoreStateMachine::resetVolumioState Aug 27 17:03:03 kitchenblaster volumio[1290]: info: CoreStateMachine::getcurrentVolume Aug 27 17:03:03 kitchenblaster volumio[1290]: info: CoreCommandRouter::volumioRetrievevolume Aug 27 17:03:04 kitchenblaster sudo[1745]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 27 17:03:04 kitchenblaster sudo[1745]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 17:03:04 kitchenblaster sudo[1745]: pam_unix(sudo:session): session closed for user root Aug 27 17:03:04 kitchenblaster sudo[1747]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 17:03:04 kitchenblaster sudo[1747]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 17:03:04 kitchenblaster sudo[1747]: pam_unix(sudo:session): session closed for user root Aug 27 17:03:05 kitchenblaster volumio[1290]: info: Volumio Network Manager: Network status updated: 2 Aug 27 17:03:05 kitchenblaster volumio[1290]: Unable to parse: Aug 27 17:03:05 kitchenblaster volumio[1290]: Simple mixer control 'Master',0 Aug 27 17:03:05 kitchenblaster volumio[1290]: Capabilities: volume volume-joined Aug 27 17:03:05 kitchenblaster volumio[1290]: Playback channels: Mono Aug 27 17:03:05 kitchenblaster volumio[1290]: Capture channels: Mono Aug 27 17:03:05 kitchenblaster volumio[1290]: Limits: 0 - 248 Aug 27 17:03:05 kitchenblaster volumio[1290]: Mono: 141 [57%] Aug 27 17:03:05 kitchenblaster volumio[1290]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Aug 27 17:03:06 kitchenblaster volumio[1290]: Unable to parse: Aug 27 17:03:06 kitchenblaster volumio[1290]: Simple mixer control 'Master',0 Aug 27 17:03:06 kitchenblaster volumio[1290]: Capabilities: volume volume-joined Aug 27 17:03:06 kitchenblaster volumio[1290]: Playback channels: Mono Aug 27 17:03:06 kitchenblaster volumio[1290]: Capture channels: Mono Aug 27 17:03:06 kitchenblaster volumio[1290]: Limits: 0 - 248 Aug 27 17:03:06 kitchenblaster volumio[1290]: Mono: 141 [57%] Aug 27 17:03:06 kitchenblaster volumio[1290]: info: VolumeController:: Volume=undefined Mute =false Aug 27 17:03:06 kitchenblaster volumio[1290]: info: CoreStateMachine::pushState Aug 27 17:03:07 kitchenblaster volumio[1290]: info: CorePlayQueue::getTrack 0 Aug 27 17:03:07 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 17:03:07 kitchenblaster volumio[1290]: info: CoreCommandRouter::volumioPushState Aug 27 17:03:07 kitchenblaster volumio[1290]: info: CoreStateMachine::updateTrackBlock Aug 27 17:03:07 kitchenblaster volumio[1290]: info: CorePlayQueue::getTrackBlock Aug 27 17:03:07 kitchenblaster volumio[1290]: info: CoreCommandRouter::volumioRetrievevolume Aug 27 17:03:08 kitchenblaster systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 5. Aug 27 17:03:08 kitchenblaster systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 27 17:03:08 kitchenblaster volumio[1290]: verbose: New Socket.io Connection to 10.10.40.109:3000 from 10.10.40.108 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Aug 27 17:03:08 kitchenblaster systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 27 17:03:08 kitchenblaster upmpdcli[1770]: :3:../libupnpp/upnpplib.cxx:273::LibUPnP: Using IPV4 10.10.40.109 port 49152 IPV6 port 49152 Aug 27 17:03:08 kitchenblaster upmpdcli[1770]: :3:../src/mediaserver/cdplugins/plgwithslave.cxx:248::PlgWithSlave: starting streaming proxy on port 49149 Aug 27 17:03:08 kitchenblaster upmpdcli[1770]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Connection refused Aug 27 17:03:08 kitchenblaster upmpdcli[1770]: :2:../src/main.cxx:732::MPD connection failed Aug 27 17:03:08 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 17:03:08 kitchenblaster volumio[1290]: info: Reloading queue from file Aug 27 17:03:08 kitchenblaster volumio[1290]: verbose: New Socket.io Connection to 10.10.40.109:3000 from 10.10.40.108 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Aug 27 17:03:08 kitchenblaster volumio[1290]: Unable to parse: Aug 27 17:03:08 kitchenblaster volumio[1290]: Simple mixer control 'Master',0 Aug 27 17:03:08 kitchenblaster volumio[1290]: Capabilities: volume volume-joined Aug 27 17:03:08 kitchenblaster volumio[1290]: Playback channels: Mono Aug 27 17:03:08 kitchenblaster volumio[1290]: Capture channels: Mono Aug 27 17:03:08 kitchenblaster volumio[1290]: Limits: 0 - 248 Aug 27 17:03:08 kitchenblaster volumio[1290]: Mono: 141 [57%] Aug 27 17:03:08 kitchenblaster volumio[1290]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Aug 27 17:03:09 kitchenblaster volumio[1290]: info: CoreStateMachine::setRepeat null single undefined Aug 27 17:03:09 kitchenblaster volumio[1290]: info: CoreStateMachine::pushState Aug 27 17:03:09 kitchenblaster volumio[1290]: info: CorePlayQueue::getTrack 0 Aug 27 17:03:09 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 17:03:09 kitchenblaster volumio[1290]: info: CoreCommandRouter::volumioPushState Aug 27 17:03:09 kitchenblaster volumio[1290]: info: CoreStateMachine::setRandom null Aug 27 17:03:09 kitchenblaster volumio[1290]: info: CoreStateMachine::pushState Aug 27 17:03:09 kitchenblaster volumio[1290]: info: CorePlayQueue::getTrack 0 Aug 27 17:03:09 kitchenblaster volumio[1290]: info: CoreCommandRouter::volumioPushState Aug 27 17:03:09 kitchenblaster volumio[1290]: info: Setting Device type: Raspberry PI Aug 27 17:03:09 kitchenblaster volumio[1290]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Aug 27 17:03:09 kitchenblaster volumio[1290]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a22082 Aug 27 17:03:09 kitchenblaster volumio[1290]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Aug 27 17:03:09 kitchenblaster volumio[1290]: info: Completed loading Core Plugins Aug 27 17:03:09 kitchenblaster volumio[1290]: info: Preparing to generate the ALSA configuration file Aug 27 17:03:09 kitchenblaster volumio[1290]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 27 17:03:09 kitchenblaster volumio[1290]: info: Reading ALSA contributions from plugins. Aug 27 17:03:10 kitchenblaster volumio[1290]: verbose: New Socket.io Connection to 10.10.40.109:3000 from 10.10.40.108 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Aug 27 17:03:10 kitchenblaster volumio[1290]: Unable to parse: Aug 27 17:03:10 kitchenblaster volumio[1290]: Simple mixer control 'Master',0 Aug 27 17:03:10 kitchenblaster volumio[1290]: Capabilities: volume volume-joined Aug 27 17:03:10 kitchenblaster volumio[1290]: Playback channels: Mono Aug 27 17:03:10 kitchenblaster volumio[1290]: Capture channels: Mono Aug 27 17:03:10 kitchenblaster volumio[1290]: Limits: 0 - 248 Aug 27 17:03:10 kitchenblaster volumio[1290]: Mono: 141 [57%] Aug 27 17:03:10 kitchenblaster volumio[1290]: info: VolumeController:: Volume=undefined Mute =false Aug 27 17:03:10 kitchenblaster volumio[1290]: info: CoreStateMachine::pushState Aug 27 17:03:10 kitchenblaster volumio[1290]: info: CorePlayQueue::getTrack 0 Aug 27 17:03:10 kitchenblaster volumio[1290]: info: CoreCommandRouter::volumioPushState Aug 27 17:03:10 kitchenblaster upmpdcli[1770]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Connection refused Aug 27 17:03:10 kitchenblaster upmpdcli[1770]: :2:../src/main.cxx:732::MPD connection failed Aug 27 17:03:10 kitchenblaster sudo[1806]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 27 17:03:10 kitchenblaster sudo[1806]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 17:03:10 kitchenblaster volumio[1290]: verbose: New Socket.io Connection to 10.10.40.109 from 10.10.30.104 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/151.0.0.0 Safari/537.36 Edg/151.0.0.0 Engine version: 3 Transport: polling Total Clients: 4 Aug 27 17:03:10 kitchenblaster volumio[1290]: verbose: New Socket.io Connection to 10.10.40.109:3000 from 10.10.40.108 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Aug 27 17:03:10 kitchenblaster sudo[1806]: pam_unix(sudo:session): session closed for user root Aug 27 17:03:10 kitchenblaster volumio[1290]: info: Discovery: adding 047b3127-d206-4935-9057-a24c209cd47e Aug 27 17:03:10 kitchenblaster volumio[1290]: info: Discovery: Found device KitchenBlaster Aug 27 17:03:10 kitchenblaster volumio[1290]: info: CoreCommandRouter::volumioGetState Aug 27 17:03:10 kitchenblaster volumio[1290]: info: CorePlayQueue::getTrack 0 Aug 27 17:03:10 kitchenblaster volumio[1290]: info: Discovery: this is already registered, 047b3127-d206-4935-9057-a24c209cd47e Aug 27 17:03:10 kitchenblaster volumio[1290]: info: Discovery: Found device KitchenBlaster Aug 27 17:03:10 kitchenblaster volumio[1290]: info: CoreCommandRouter::volumioGetState Aug 27 17:03:10 kitchenblaster volumio[1290]: info: CorePlayQueue::getTrack 0 Aug 27 17:03:11 kitchenblaster volumio[1290]: verbose: New Socket.io Connection to 10.10.40.109:3000 from 10.10.40.108 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Aug 27 17:03:11 kitchenblaster volumio[1290]: info: Upmpdcli Daemon Started Aug 27 17:03:11 kitchenblaster volumio[1290]: info: Discovery: adding e7ddf721-5888-4b22-9d1f-7e5db59187e6 Aug 27 17:03:11 kitchenblaster volumio[1290]: info: Discovery: Found device OfficeBlaster Aug 27 17:03:11 kitchenblaster volumio[1290]: info: Discovery: Connecting to remote: 10.10.40.108 Aug 27 17:03:11 kitchenblaster volumio[1290]: verbose: New Socket.io Connection to 10.10.40.109:3000 from 10.10.40.108 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Aug 27 17:03:11 kitchenblaster volumio[1290]: verbose: New Socket.io Connection to 10.10.40.109 from 10.10.30.104 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/151.0.0.0 Safari/537.36 Edg/151.0.0.0 Engine version: 3 Transport: polling Total Clients: 8 Aug 27 17:03:11 kitchenblaster volumio[1290]: verbose: New Socket.io Connection to 10.10.40.109 from 10.10.30.104 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/151.0.0.0 Safari/537.36 Edg/151.0.0.0 Engine version: 3 Transport: polling Total Clients: 9 Aug 27 17:03:11 kitchenblaster volumio[1290]: verbose: New Socket.io Connection to 10.10.40.109 from 10.10.30.104 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/151.0.0.0 Safari/537.36 Edg/151.0.0.0 Engine version: 3 Transport: polling Total Clients: 10 Aug 27 17:03:12 kitchenblaster volumio[1290]: info: Asound.conf file unchanged, so no further update is needed Aug 27 17:03:12 kitchenblaster volumio[1290]: info: Output device has changed, restarting MPD Aug 27 17:03:12 kitchenblaster volumio[1290]: info: Output device has changed, restarting Shairport Sync Aug 27 17:03:12 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:03:12 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 17:03:12 kitchenblaster sudo[1811]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 27 17:03:12 kitchenblaster sudo[1811]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 17:03:12 kitchenblaster sudo[1809]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 27 17:03:12 kitchenblaster sudo[1809]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 17:03:12 kitchenblaster sudo[1809]: pam_unix(sudo:session): session closed for user root Aug 27 17:03:12 kitchenblaster volumio[1290]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 27 17:03:12 kitchenblaster volumio[1290]: info: ___________ START PLUGINS ___________ Aug 27 17:03:12 kitchenblaster volumio-remote-updater[774]: [2026-08-27 17:03:12] [connect] Successful connection Aug 27 17:03:12 kitchenblaster systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 27 17:03:12 kitchenblaster systemd[1]: Starting mpd.service - Music Player Daemon... Aug 27 17:03:12 kitchenblaster volumio[1290]: info: ControllerMpd::onStart: Initializing MPD Aug 27 17:03:12 kitchenblaster volumio[1290]: info: Creating MPD Configuration file Aug 27 17:03:13 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 17:03:13 kitchenblaster volumio[1290]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 27 17:03:13 kitchenblaster volumio[1290]: info: [1787842993128] CoreMusicLibrary::Adding element Medienserver Aug 27 17:03:13 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 17:03:13 kitchenblaster sudo[1821]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Aug 27 17:03:13 kitchenblaster volumio[1290]: info: UPNP Browser: Client initialized successfully Aug 27 17:03:13 kitchenblaster sudo[1819]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 27 17:03:13 kitchenblaster sudo[1819]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 27 17:03:13 kitchenblaster sudo[1826]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Aug 27 17:03:13 kitchenblaster sudo[1819]: pam_unix(sudo:session): session closed for user root Aug 27 17:03:13 kitchenblaster sudo[1821]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 17:03:13 kitchenblaster sudo[1823]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 27 17:03:13 kitchenblaster sudo[1823]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 17:03:13 kitchenblaster sudo[1823]: pam_unix(sudo:session): session closed for user root Aug 27 17:03:13 kitchenblaster sudo[1825]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 27 17:03:13 kitchenblaster sudo[1825]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 17:03:13 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:03:13 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 17:03:13 kitchenblaster volumio[1290]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 27 17:03:13 kitchenblaster systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Aug 27 17:03:13 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:03:14 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 17:03:14 kitchenblaster sudo[1821]: pam_unix(sudo:session): session closed for user root Aug 27 17:03:14 kitchenblaster systemd[1]: mpd.service: Deactivated successfully. Aug 27 17:03:14 kitchenblaster systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 27 17:03:14 kitchenblaster systemd[1]: mpd.socket: Deactivated successfully. Aug 27 17:03:14 kitchenblaster systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 27 17:03:14 kitchenblaster systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 27 17:03:14 kitchenblaster systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 27 17:03:14 kitchenblaster systemd[1]: Starting mpd.service - Music Player Daemon... Aug 27 17:03:14 kitchenblaster volumio[1290]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 27 17:03:14 kitchenblaster volumio[1290]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 27 17:03:14 kitchenblaster volumio[1290]: info: [1787842994577] CoreMusicLibrary::Adding element Last_100 Aug 27 17:03:14 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 17:03:14 kitchenblaster sudo[1841]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 27 17:03:14 kitchenblaster sudo[1841]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 27 17:03:14 kitchenblaster volumio[1290]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 27 17:03:14 kitchenblaster volumio[1290]: info: [1787842994691] CoreMusicLibrary::Adding element Webradio Aug 27 17:03:14 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 17:03:14 kitchenblaster sudo[1847]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Aug 27 17:03:14 kitchenblaster sudo[1841]: pam_unix(sudo:session): session closed for user root Aug 27 17:03:14 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 27 17:03:14 kitchenblaster volumio[1290]: info: Initializing BBC Radios Aug 27 17:03:15 kitchenblaster volumio5-onboarding[1835]: time=2026-08-27T17:03:15.120+02:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Aug 27 17:03:15 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 27 17:03:15 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 17:03:16 kitchenblaster volumio[1290]: info: Creating Spotify config file Aug 27 17:03:16 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:03:16 kitchenblaster upmpdcli[1770]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Timeout Aug 27 17:03:16 kitchenblaster upmpdcli[1770]: :2:../src/main.cxx:732::MPD connection failed Aug 27 17:03:16 kitchenblaster volumio[1290]: info: FusionDsp - CamillaDSP binary found. Aug 27 17:03:16 kitchenblaster volumio[1290]: info: Loading i18n strings for locale de Aug 27 17:03:16 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Aug 27 17:03:16 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:03:16 kitchenblaster volumio[1290]: info: FusionDsp - mixtype--------------------- Hardware Aug 27 17:03:16 kitchenblaster volumio[1290]: info: [MQTT] Starting MQTT Client plugin Aug 27 17:03:16 kitchenblaster volumio[1290]: info: [MQTT] Device ID: kitchenblaster Aug 27 17:03:16 kitchenblaster volumio[1290]: info: [MQTT] Base topic: volumio Aug 27 17:03:17 kitchenblaster volumio[1290]: info: [MQTT] Connecting to MQTT broker: mqtt://homie.edv-msnufsktur.de:1883 Aug 27 17:03:22 kitchenblaster volumio[1290]: info: Loading i18n strings for locale de Aug 27 17:03:22 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 27 17:03:22 kitchenblaster volumio[1290]: info: Cannot retrieve data for calling home Aug 27 17:03:23 kitchenblaster sudo[1880]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /data/volumiokioskextensions/IframeKeyboardBridge Aug 27 17:03:23 kitchenblaster sudo[1880]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 17:03:23 kitchenblaster sudo[1880]: pam_unix(sudo:session): session closed for user root Aug 27 17:03:23 kitchenblaster volumio[1290]: info: Preparing to generate the ALSA configuration file Aug 27 17:03:24 kitchenblaster sudo[1885]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Aug 27 17:03:24 kitchenblaster sudo[1885]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 17:03:24 kitchenblaster sudo[1883]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Aug 27 17:03:24 kitchenblaster sudo[1883]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 17:03:25 kitchenblaster sudo[1887]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Aug 27 17:03:25 kitchenblaster sudo[1887]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 17:03:25 kitchenblaster volumio5-onboarding[1835]: 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:40380->127.0.0.1:3000: i/o timeout Aug 27 17:03:25 kitchenblaster systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Aug 27 17:03:25 kitchenblaster systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Aug 27 17:03:25 kitchenblaster systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 1. Aug 27 17:03:25 kitchenblaster systemd[1]: Reloading. Aug 27 17:03:26 kitchenblaster upmpdcli[1770]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Timeout Aug 27 17:03:26 kitchenblaster upmpdcli[1770]: :2:../src/main.cxx:732::MPD connection failed Aug 27 17:03:27 kitchenblaster volumio-remote-updater[774]: [2026-08-27 17:03:27] [connect] Successful connection Aug 27 17:03:28 kitchenblaster mpd[1848]: 2026-08-27T17:03:28 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 27 17:03:29 kitchenblaster systemd[1]: Started mpd.service - Music Player Daemon. Aug 27 17:03:29 kitchenblaster sudo[1825]: pam_unix(sudo:session): session closed for user root Aug 27 17:03:29 kitchenblaster systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 2. Aug 27 17:03:29 kitchenblaster sudo[1887]: pam_unix(sudo:session): session closed for user root Aug 27 17:03:29 kitchenblaster systemd[1]: Reloading. Aug 27 17:03:29 kitchenblaster sudo[1811]: pam_unix(sudo:session): session closed for user root Aug 27 17:03:31 kitchenblaster volumio[1290]: error: [MQTT] MQTT connection error: Error: getaddrinfo ENOTFOUND homie.edv-msnufsktur.de Aug 27 17:03:31 kitchenblaster volumio[1290]: error: [MQTT] Failed to connect on startup: Error: getaddrinfo ENOTFOUND homie.edv-msnufsktur.de Aug 27 17:03:31 kitchenblaster volumio[1290]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 27 17:03:31 kitchenblaster volumio[1290]: info: Reading ALSA contributions from plugins. Aug 27 17:03:31 kitchenblaster volumio[1290]: info: touch_display: Backlight interface detected. Aug 27 17:03:32 kitchenblaster systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 3. Aug 27 17:03:32 kitchenblaster systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Aug 27 17:03:32 kitchenblaster sudo[1885]: pam_unix(sudo:session): session closed for user root Aug 27 17:03:32 kitchenblaster volumio[1290]: info: FusionDsp - If filter freq >samplerate/2 then disable it Aug 27 17:03:32 kitchenblaster systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Aug 27 17:03:32 kitchenblaster volumio5-onboarding[1946]: time=2026-08-27T17:03:32.843+02:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Aug 27 17:03:33 kitchenblaster sudo[1883]: pam_unix(sudo:session): session closed for user root Aug 27 17:03:33 kitchenblaster volumio[1290]: info: camilladsp service started and running in background, instance 1 Aug 27 17:03:33 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:03:33 kitchenblaster volumio[1290]: info: FusionDsp - AAAAAAAAAAAAAA-> 22050 44100 48000 <-AAAAAAAAAAAAA Aug 27 17:03:33 kitchenblaster volumio[1290]: info: FusionDsp loaded Aug 27 17:03:33 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 17:03:33 kitchenblaster volumio[1290]: info: FusionDsp - --[object Object] Aug 27 17:03:33 kitchenblaster volumio[1290]: error: FusionDsp - Socket connection not established Aug 27 17:03:33 kitchenblaster volumio[1290]: info: FusionDsp - Reporting Fusion DSP Enabled Aug 27 17:03:33 kitchenblaster volumio[1290]: info: Adding Signal Path Element [object Object] Aug 27 17:03:33 kitchenblaster volumio[1290]: info: Adding fusiondspeq DSP Signal Path Element Aug 27 17:03:33 kitchenblaster volumio[1290]: info: FusionDsp - ---- installed callbackRead (debounced 200ms) Aug 27 17:03:33 kitchenblaster volumio[1290]: info: FusionDsp - PEQ graph server listening on port 10015 Aug 27 17:03:33 kitchenblaster sudo[1962]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Aug 27 17:03:33 kitchenblaster sudo[1962]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 17:03:34 kitchenblaster volumio[1290]: verbose: New Socket.io Connection to 10.10.40.109:3000 from 10.10.40.108 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Aug 27 17:03:34 kitchenblaster systemd[1]: Starting fusiondsp.service - FusionDsp Daemon... Aug 27 17:03:34 kitchenblaster volumio[1290]: info: Discovery: Connected to remote: 10.10.40.108 Aug 27 17:03:34 kitchenblaster volumio[1290]: info: touch_display: systemctl daemon-reload succeeded. Aug 27 17:03:34 kitchenblaster volumio[1290]: info: touch_display: systemctl disable getty@tty1.service succeeded. Aug 27 17:03:34 kitchenblaster volumio[1290]: info: touch_display: systemctl stop getty@tty1.service succeeded. Aug 27 17:03:34 kitchenblaster volumio[1290]: info: MPD Permissions set Aug 27 17:03:34 kitchenblaster sudo[1968]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Aug 27 17:03:34 kitchenblaster sudo[1968]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 17:03:34 kitchenblaster volumio[1290]: info: MPD Permissions set Aug 27 17:03:35 kitchenblaster volumio[1290]: verbose: New Socket.io Connection to 10.10.40.109:3000 from 10.10.40.108 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Aug 27 17:03:35 kitchenblaster volumio[1290]: error: FusionDsp - Reload WebSocket error: [object Object] Aug 27 17:03:35 kitchenblaster systemd[1]: Started volumio-kiosk.service - Volumio Kiosk. Aug 27 17:03:35 kitchenblaster sudo[1968]: pam_unix(sudo:session): session closed for user root Aug 27 17:03:35 kitchenblaster volumio[1290]: info: Spotify config file written Aug 27 17:03:35 kitchenblaster sudo[1986]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Aug 27 17:03:35 kitchenblaster sudo[1986]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 17:03:35 kitchenblaster startx[2000]: X.Org X Server 1.21.1.7 Aug 27 17:03:35 kitchenblaster startx[2000]: X Protocol Version 11, Revision 0 Aug 27 17:03:35 kitchenblaster startx[2000]: Current Operating System: Linux kitchenblaster 6.12.74-v7+ #1948 SMP Mon Mar 2 11:25:27 GMT 2026 armv7l Aug 27 17:03:35 kitchenblaster startx[2000]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory snd_bcm2835.enable_hdmi=0 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=9caf6474-168f-4972-add4-ace36a467bc2 imgfile=/volumio_current.sqsh bootpart=UUID=807D-2966 datapart=UUID=bea3e05c-62cf-4b22-a58d-66213927f56a uuidconfig=cmdline.txt rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Aug 27 17:03:35 kitchenblaster startx[2000]: xorg-server 2:21.1.7-3+rpt3+deb12u13 (https://www.debian.org/support) Aug 27 17:03:35 kitchenblaster startx[2000]: Current version of pixman: 0.44.0 Aug 27 17:03:35 kitchenblaster startx[2000]: Before reporting problems, check http://wiki.x.org Aug 27 17:03:35 kitchenblaster startx[2000]: to make sure that you have the latest version. Aug 27 17:03:35 kitchenblaster startx[2000]: Markers: (--) probed, (**) from config file, (==) default setting, Aug 27 17:03:35 kitchenblaster startx[2000]: (++) from command line, (!!) notice, (II) informational, Aug 27 17:03:35 kitchenblaster startx[2000]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Aug 27 17:03:35 kitchenblaster startx[2000]: (==) Log file: "/var/log/Xorg.0.log", Time: Thu Aug 27 17:03:35 2026 Aug 27 17:03:35 kitchenblaster startx[2000]: (==) Using config directory: "/etc/X11/xorg.conf.d" Aug 27 17:03:35 kitchenblaster startx[2000]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Aug 27 17:03:35 kitchenblaster 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 27 17:03:35 kitchenblaster 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 27 17:03:35 kitchenblaster systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 27 17:03:35 kitchenblaster go-librespot[2002]: go-librespot daemon starting... Aug 27 17:03:35 kitchenblaster sudo[1986]: pam_unix(sudo:session): session closed for user root Aug 27 17:03:36 kitchenblaster sudo[1971]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/cp -r /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/content.js /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/manifest.json /data/volumiokioskextensions/IframeKeyboardBridge/ Aug 27 17:03:36 kitchenblaster sudo[1971]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 17:03:36 kitchenblaster volumio[1290]: error: MPD error: The expression evaluated to a falsy value: Aug 27 17:03:36 kitchenblaster volumio[1290]: assert.ok(self.idling) Aug 27 17:03:36 kitchenblaster volumio[1290]: error: The expression evaluated to a falsy value: Aug 27 17:03:36 kitchenblaster volumio[1290]: assert.ok(self.idling) Aug 27 17:03:36 kitchenblaster volumio[1290]: verbose: New Socket.io Connection to 10.10.40.109:3000 from 10.10.40.108 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Aug 27 17:03:36 kitchenblaster volumio[1290]: info: MPD running with PID1848 Aug 27 17:03:36 kitchenblaster volumio[1290]: ,establishing connection Aug 27 17:03:36 kitchenblaster sudo[1971]: pam_unix(sudo:session): session closed for user root Aug 27 17:03:36 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:03:36 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:03:36 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:03:36 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:03:36 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:03:36 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:03:36 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:03:36 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:03:36 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 17:03:36 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:03:36 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:03:36 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:03:36 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:03:36 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:03:36 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:03:36 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:03:36 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:03:36 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:03:37 kitchenblaster go-librespot[2003]: time="2026-08-27T17:03:37+02:00" level=info msg="running go-librespot 0.7.1" Aug 27 17:03:37 kitchenblaster go-librespot[2003]: time="2026-08-27T17:03:37+02:00" level=debug msg="app state loaded" Aug 27 17:03:37 kitchenblaster go-librespot[2003]: time="2026-08-27T17:03:37+02:00" level=debug msg="stored credentials not found" Aug 27 17:03:37 kitchenblaster go-librespot[2003]: time="2026-08-27T17:03:37+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 27 17:03:37 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 17:03:37 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:03:37 kitchenblaster volumio[1290]: info: No need to fix Spotify hosts Aug 27 17:03:37 kitchenblaster volumio[1290]: error: updateQueue error: null Aug 27 17:03:38 kitchenblaster volumio[1290]: verbose: New Socket.io Connection to 10.10.40.109:3000 from 10.10.40.108 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Aug 27 17:03:38 kitchenblaster volumio[1290]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Aug 27 17:03:38 kitchenblaster volumio[1290]: info: touch_display: Volumio Kiosk started. Aug 27 17:03:38 kitchenblaster volumio[1290]: info: CoreCommandRouter::volumioGetState Aug 27 17:03:38 kitchenblaster volumio[1290]: info: CorePlayQueue::getTrack 0 Aug 27 17:03:38 kitchenblaster volumio[1290]: info: Completed starting Core Plugins Aug 27 17:03:38 kitchenblaster volumio[1290]: info: ------------------------------------------- Aug 27 17:03:38 kitchenblaster volumio[1290]: info: ----- MyVolumio plugins startup ---- Aug 27 17:03:38 kitchenblaster volumio[1290]: info: ------------------------------------------- Aug 27 17:03:38 kitchenblaster volumio[1290]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 27 17:03:38 kitchenblaster go-librespot[2003]: time="2026-08-27T17:03:38+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 27 17:03:38 kitchenblaster go-librespot[2003]: time="2026-08-27T17:03:38+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 27 17:03:38 kitchenblaster go-librespot[2003]: time="2026-08-27T17:03:38+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 27 17:03:38 kitchenblaster go-librespot[2003]: time="2026-08-27T17:03:38+02:00" level=info msg="zeroconf server listening on port 37129" Aug 27 17:03:38 kitchenblaster go-librespot[2003]: time="2026-08-27T17:03:38+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 27 17:03:39 kitchenblaster volumio[1290]: error: [MQTT] MQTT connection error: Error: getaddrinfo ENOTFOUND homie.edv-msnufsktur.de Aug 27 17:03:39 kitchenblaster systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Aug 27 17:03:39 kitchenblaster sudo[1962]: pam_unix(sudo:session): session closed for user root Aug 27 17:03:39 kitchenblaster volumio[1290]: info: touch_display: X display number found: 0 Aug 27 17:03:39 kitchenblaster volumio[1290]: info: touch_display: IframeKeyboardBridge extension installed successfully Aug 27 17:03:39 kitchenblaster sudo[2051]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/class/backlight/10-0045/brightness Aug 27 17:03:39 kitchenblaster sudo[2051]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 17:03:39 kitchenblaster volumio[1290]: error: updateQueue error: null Aug 27 17:03:39 kitchenblaster sudo[2051]: pam_unix(sudo:session): session closed for user root Aug 27 17:03:39 kitchenblaster volumio[1290]: verbose: New Socket.io Connection to 10.10.40.109 from 10.10.30.104 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/151.0.0.0 Safari/537.36 Edg/151.0.0.0 Engine version: 3 Transport: polling Total Clients: 15 Aug 27 17:03:40 kitchenblaster volumio[1290]: info: Starting Shairport Sync Aug 27 17:03:40 kitchenblaster volumio[1290]: info: Starting Shairport Sync Aug 27 17:03:40 kitchenblaster volumio[1290]: info: Starting Shairport Sync Aug 27 17:03:40 kitchenblaster sudo[2085]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 27 17:03:40 kitchenblaster sudo[2085]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 17:03:40 kitchenblaster sudo[2101]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 27 17:03:40 kitchenblaster sudo[2101]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 17:03:41 kitchenblaster systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 27 17:03:41 kitchenblaster systemd[1]: shairport-sync.service: Deactivated successfully. Aug 27 17:03:41 kitchenblaster systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 27 17:03:41 kitchenblaster systemd[1]: shairport-sync.service: Consumed 1.932s CPU time. Aug 27 17:03:41 kitchenblaster volumio[1290]: verbose: New Socket.io Connection to 10.10.40.109 from 10.10.30.104 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/151.0.0.0 Safari/537.36 Edg/151.0.0.0 Engine version: 3 Transport: polling Total Clients: 16 Aug 27 17:03:41 kitchenblaster systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 27 17:03:41 kitchenblaster volumio[1290]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Aug 27 17:03:41 kitchenblaster sudo[2085]: pam_unix(sudo:session): session closed for user root Aug 27 17:03:41 kitchenblaster volumio[1290]: info: touch_display: File permissions for backlight brightness control set. Aug 27 17:03:41 kitchenblaster sudo[2093]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 27 17:03:41 kitchenblaster sudo[2101]: pam_unix(sudo:session): session closed for user root Aug 27 17:03:41 kitchenblaster sudo[2093]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 17:03:41 kitchenblaster volumio[1290]: info: go-librespot daemon successfully initialized Aug 27 17:03:41 kitchenblaster volumio[1290]: info: Asound.conf file unchanged, so no further update is needed Aug 27 17:03:41 kitchenblaster volumio[1290]: info: Output device has changed, restarting MPD Aug 27 17:03:41 kitchenblaster systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 27 17:03:41 kitchenblaster systemd[1]: shairport-sync.service: Deactivated successfully. Aug 27 17:03:41 kitchenblaster systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 27 17:03:41 kitchenblaster systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 27 17:03:41 kitchenblaster sudo[2093]: pam_unix(sudo:session): session closed for user root Aug 27 17:03:41 kitchenblaster volumio[1290]: info: Output device has changed, restarting Shairport Sync Aug 27 17:03:42 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:03:42 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 17:03:42 kitchenblaster sudo[2141]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 27 17:03:42 kitchenblaster sudo[2143]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 27 17:03:42 kitchenblaster sudo[2143]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 17:03:42 kitchenblaster sudo[2141]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 17:03:42 kitchenblaster volumio[1290]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 27 17:03:42 kitchenblaster sshd[2027]: Accepted password for volumio from 10.10.30.104 port 55091 ssh2 Aug 27 17:03:42 kitchenblaster upmpdcli[2164]: writing RSA key Aug 27 17:03:42 kitchenblaster sshd[2027]: pam_unix(sshd:session): session opened for user volumio(uid=1000) by (uid=0) Aug 27 17:03:42 kitchenblaster volumio-remote-updater[774]: [2026-08-27 17:03:42] [connect] Successful connection Aug 27 17:03:42 kitchenblaster volumio5-onboarding[1946]: 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:49584->127.0.0.1:3000: i/o timeout Aug 27 17:03:43 kitchenblaster systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Aug 27 17:03:43 kitchenblaster systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Aug 27 17:03:43 kitchenblaster sudo[2141]: pam_unix(sudo:session): session closed for user root Aug 27 17:03:43 kitchenblaster systemd[1]: Created slice user-1000.slice - User Slice of UID 1000. Aug 27 17:03:43 kitchenblaster systemd[1]: Starting user-runtime-dir@1000.service - User Runtime Directory /run/user/1000... Aug 27 17:03:43 kitchenblaster systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 4. Aug 27 17:03:43 kitchenblaster systemd-logind[762]: New session 1 of user volumio. Aug 27 17:03:43 kitchenblaster systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Aug 27 17:03:43 kitchenblaster systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Aug 27 17:03:43 kitchenblaster systemd[1]: Finished user-runtime-dir@1000.service - User Runtime Directory /run/user/1000. Aug 27 17:03:43 kitchenblaster systemd[1]: Stopping mpd.service - Music Player Daemon... Aug 27 17:03:43 kitchenblaster systemd[1]: Starting user@1000.service - User Manager for UID 1000... Aug 27 17:03:43 kitchenblaster volumio[1290]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Aug 27 17:03:43 kitchenblaster (systemd)[2183]: pam_unix(systemd-user:session): session opened for user volumio(uid=1000) by (uid=0) Aug 27 17:03:43 kitchenblaster volumio5-onboarding[2173]: time=2026-08-27T17:03:43.569+02:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Aug 27 17:03:43 kitchenblaster upmpdcli[1770]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Aug 27 17:03:43 kitchenblaster volumio[1290]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 17 Aug 27 17:03:44 kitchenblaster volumio[1290]: info: Shairport-Sync Started Aug 27 17:03:44 kitchenblaster volumio[1290]: Error adding Membership: Error: addMembership EINVAL Aug 27 17:03:44 kitchenblaster volumio[1290]: info: Shairport-Sync Started Aug 27 17:03:44 kitchenblaster volumio[1290]: info: touch_display: X display number found: 0 Aug 27 17:03:44 kitchenblaster volumio[1290]: verbose: New Socket.io Connection to 10.10.40.109:3000 from 10.10.40.108 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 18 Aug 27 17:03:44 kitchenblaster volumio[1290]: info: MPD Permissions set Aug 27 17:03:44 kitchenblaster volumio[1290]: info: Shairport-Sync Started Aug 27 17:03:44 kitchenblaster volumio[1290]: verbose: New Socket.io Connection to 10.10.40.109:3000 from 10.10.40.108 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 19 Aug 27 17:03:44 kitchenblaster volumio[1290]: info: Initializing connection to go-librespot Websocket Aug 27 17:03:44 kitchenblaster volumio[1290]: error: [MQTT] MQTT connection error: Error: getaddrinfo ENOTFOUND homie.edv-msnufsktur.de Aug 27 17:03:44 kitchenblaster volumio[1290]: verbose: New Socket.io Connection to 10.10.40.109 from 10.10.30.104 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/151.0.0.0 Safari/537.36 Edg/151.0.0.0 Engine version: 3 Transport: polling Total Clients: 20 Aug 27 17:03:44 kitchenblaster volumio[1290]: verbose: New Socket.io Connection to 10.10.40.109 from 10.10.30.104 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/151.0.0.0 Safari/537.36 Edg/151.0.0.0 Engine version: 3 Transport: polling Total Clients: 21 Aug 27 17:03:44 kitchenblaster go-librespot[2003]: time="2026-08-27T17:03:44+02:00" level=debug msg="new websocket client" Aug 27 17:03:45 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:03:45 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:03:45 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:03:45 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:03:45 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:03:45 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:03:45 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:03:45 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:03:45 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 17:03:45 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:03:45 kitchenblaster volumio[1290]: verbose: New Socket.io Connection to 10.10.40.109 from 10.10.30.104 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/151.0.0.0 Safari/537.36 Edg/151.0.0.0 Engine version: 3 Transport: polling Total Clients: 22 Aug 27 17:03:45 kitchenblaster volumio[1290]: info: touch_display: Setting screensaver timeout to 120 seconds. Aug 27 17:03:45 kitchenblaster volumio[1290]: info: Connection to go-librespot Websocket established Aug 27 17:03:45 kitchenblaster volumio[1290]: verbose: New Socket.io Connection to 10.10.40.109 from 10.10.30.104 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/151.0.0.0 Safari/537.36 Edg/151.0.0.0 Engine version: 3 Transport: polling Total Clients: 23 Aug 27 17:03:45 kitchenblaster volumio[1290]: verbose: New Socket.io Connection to 10.10.40.109 from 10.10.30.104 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/151.0.0.0 Safari/537.36 Edg/151.0.0.0 Engine version: 3 Transport: polling Total Clients: 24 Aug 27 17:03:45 kitchenblaster volumio[1290]: info: Starting Shairport Sync Aug 27 17:03:45 kitchenblaster sudo[2224]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 27 17:03:45 kitchenblaster systemd[2183]: Queued start job for default target default.target. Aug 27 17:03:45 kitchenblaster sudo[2224]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 17:03:45 kitchenblaster volumio[1290]: verbose: New Socket.io Connection to 10.10.40.109 from 10.10.30.104 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/151.0.0.0 Safari/537.36 Edg/151.0.0.0 Engine version: 3 Transport: polling Total Clients: 25 Aug 27 17:03:46 kitchenblaster systemd[2183]: Created slice app.slice - User Application Slice. Aug 27 17:03:46 kitchenblaster systemd[2183]: Reached target paths.target - Paths. Aug 27 17:03:46 kitchenblaster systemd[2183]: Reached target timers.target - Timers. Aug 27 17:03:46 kitchenblaster systemd[2183]: Starting dbus.socket - D-Bus User Message Bus Socket... Aug 27 17:03:46 kitchenblaster systemd[2183]: Listening on dirmngr.socket - GnuPG network certificate management daemon. Aug 27 17:03:46 kitchenblaster systemd[2183]: Listening on gpg-agent-browser.socket - GnuPG cryptographic agent and passphrase cache (access for web browsers). Aug 27 17:03:46 kitchenblaster systemd[2183]: Listening on gpg-agent-extra.socket - GnuPG cryptographic agent and passphrase cache (restricted). Aug 27 17:03:46 kitchenblaster systemd[2183]: Listening on gpg-agent-ssh.socket - GnuPG cryptographic agent (ssh-agent emulation). Aug 27 17:03:46 kitchenblaster systemd[2183]: Listening on gpg-agent.socket - GnuPG cryptographic agent and passphrase cache. Aug 27 17:03:46 kitchenblaster volumio[1290]: verbose: New Socket.io Connection to 10.10.40.109 from 10.10.30.104 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/151.0.0.0 Safari/537.36 Edg/151.0.0.0 Engine version: 3 Transport: polling Total Clients: 26 Aug 27 17:03:46 kitchenblaster volumio[1290]: verbose: New Socket.io Connection to 10.10.40.109 from 10.10.30.104 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/151.0.0.0 Safari/537.36 Edg/151.0.0.0 Engine version: 3 Transport: polling Total Clients: 27 Aug 27 17:03:46 kitchenblaster systemd[2183]: Listening on dbus.socket - D-Bus User Message Bus Socket. Aug 27 17:03:46 kitchenblaster systemd[2183]: Reached target sockets.target - Sockets. Aug 27 17:03:46 kitchenblaster systemd[2183]: Reached target basic.target - Basic System. Aug 27 17:03:46 kitchenblaster systemd[1]: Started user@1000.service - User Manager for UID 1000. Aug 27 17:03:46 kitchenblaster systemd[2183]: Started mpris-proxy.service - Bluetooth mpris proxy. Aug 27 17:03:46 kitchenblaster systemd[2183]: Reached target default.target - Main User Target. Aug 27 17:03:46 kitchenblaster systemd[2183]: Startup finished in 2.592s. Aug 27 17:03:46 kitchenblaster systemd[1]: Started session-1.scope - Session 1 of User volumio. Aug 27 17:03:46 kitchenblaster volumio[1290]: verbose: New Socket.io Connection to 10.10.40.109 from 10.10.30.104 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/151.0.0.0 Safari/537.36 Edg/151.0.0.0 Engine version: 3 Transport: polling Total Clients: 28 Aug 27 17:03:46 kitchenblaster systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 27 17:03:46 kitchenblaster systemd[1]: shairport-sync.service: Deactivated successfully. Aug 27 17:03:46 kitchenblaster systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 27 17:03:46 kitchenblaster systemd[1]: shairport-sync.service: Consumed 1.175s CPU time. Aug 27 17:03:46 kitchenblaster systemd[1]: mpd.service: Deactivated successfully. Aug 27 17:03:46 kitchenblaster systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 27 17:03:46 kitchenblaster systemd[1]: mpd.service: Consumed 9.951s CPU time. Aug 27 17:03:46 kitchenblaster systemd[1]: mpd.socket: Deactivated successfully. Aug 27 17:03:46 kitchenblaster upmpdcli[1770]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Connection refused Aug 27 17:03:46 kitchenblaster upmpdcli[1770]: :2:../src/mpdcli.cxx:396::MPDCli::updStatus: connection failed Aug 27 17:03:46 kitchenblaster upmpdcli[1770]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Aug 27 17:03:46 kitchenblaster systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 27 17:03:46 kitchenblaster systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 27 17:03:46 kitchenblaster systemd[2183]: Created slice session.slice - User Core Session Slice. Aug 27 17:03:46 kitchenblaster systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 27 17:03:46 kitchenblaster systemd[2183]: Starting dbus.service - D-Bus User Message Bus... Aug 27 17:03:46 kitchenblaster systemd[1]: Starting mpd.service - Music Player Daemon... Aug 27 17:03:46 kitchenblaster systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 27 17:03:46 kitchenblaster sudo[2224]: pam_unix(sudo:session): session closed for user root Aug 27 17:03:46 kitchenblaster systemd[2183]: Started dbus.service - D-Bus User Message Bus. Aug 27 17:03:46 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 27 17:03:46 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 17:03:46 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 27 17:03:46 kitchenblaster volumio[1290]: info: Shairport-Sync Started Aug 27 17:03:47 kitchenblaster sshd[2027]: pam_env(sshd:session): deprecated reading of user environment enabled Aug 27 17:03:48 kitchenblaster volumio[1290]: verbose: New Socket.io Connection to 10.10.40.109:3000 from 10.10.40.108 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 29 Aug 27 17:03:48 kitchenblaster volumio[1290]: verbose: New Socket.io Connection to 10.10.40.109:3000 from 10.10.40.108 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 30 Aug 27 17:03:48 kitchenblaster volumio[1290]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 27 17:03:48 kitchenblaster volumio[1290]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 27 17:03:48 kitchenblaster volumio[1290]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 27 17:03:48 kitchenblaster volumio[1290]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 27 17:03:48 kitchenblaster volumio[1290]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 27 17:03:48 kitchenblaster volumio[1290]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 27 17:03:48 kitchenblaster volumio[1290]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 27 17:03:48 kitchenblaster volumio[1290]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 27 17:03:48 kitchenblaster volumio[1290]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 27 17:03:48 kitchenblaster volumio[1290]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 27 17:03:48 kitchenblaster volumio[1290]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Aug 27 17:03:48 kitchenblaster volumio[1290]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 27 17:03:48 kitchenblaster volumio[1290]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 27 17:03:48 kitchenblaster volumio[1290]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 27 17:03:48 kitchenblaster volumio[1290]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 27 17:03:48 kitchenblaster volumio[1290]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 27 17:03:48 kitchenblaster volumio[1290]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 27 17:03:48 kitchenblaster volumio[1290]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 27 17:03:48 kitchenblaster volumio[1290]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 27 17:03:48 kitchenblaster volumio[1290]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 27 17:03:48 kitchenblaster volumio[1290]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 27 17:03:48 kitchenblaster volumio[1290]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Aug 27 17:03:48 kitchenblaster volumio[1290]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 27 17:03:48 kitchenblaster volumio[1290]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 27 17:03:48 kitchenblaster volumio[1290]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 27 17:03:48 kitchenblaster volumio[1290]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 27 17:03:48 kitchenblaster volumio[1290]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 27 17:03:48 kitchenblaster volumio[1290]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 27 17:03:48 kitchenblaster volumio[1290]: info: Adding plugin bluetooth to MyMusic Plugins Aug 27 17:03:48 kitchenblaster volumio[1290]: info: Adding plugin multiroom to MyMusic Plugins Aug 27 17:03:48 kitchenblaster volumio[1290]: info: Adding plugin metavolumio to MyMusic Plugins Aug 27 17:03:48 kitchenblaster volumio[1290]: info: Adding plugin cd_controller to MyMusic Plugins Aug 27 17:03:48 kitchenblaster volumio[1290]: info: Adding plugin qobuzconnect to MyMusic Plugins Aug 27 17:03:48 kitchenblaster volumio[1290]: info: Adding plugin smart_inputs to MyMusic Plugins Aug 27 17:03:48 kitchenblaster volumio[1290]: info: Adding plugin tidalconnect to MyMusic Plugins Aug 27 17:03:48 kitchenblaster volumio[1290]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 27 17:03:48 kitchenblaster sudo[2233]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 27 17:03:48 kitchenblaster sudo[2233]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 27 17:03:49 kitchenblaster sudo[2233]: pam_unix(sudo:session): session closed for user root Aug 27 17:03:52 kitchenblaster upmpdcli[1770]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Timeout Aug 27 17:03:52 kitchenblaster upmpdcli[1770]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Aug 27 17:03:52 kitchenblaster upmpdcli[1770]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Aug 27 17:03:53 kitchenblaster volumio5-onboarding[2173]: 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:51102->127.0.0.1:3000: i/o timeout Aug 27 17:03:53 kitchenblaster systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Aug 27 17:03:53 kitchenblaster systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Aug 27 17:03:53 kitchenblaster systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 5. Aug 27 17:03:53 kitchenblaster systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Aug 27 17:03:53 kitchenblaster systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Aug 27 17:03:54 kitchenblaster volumio5-onboarding[2306]: time=2026-08-27T17:03:54.241+02:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Aug 27 17:03:57 kitchenblaster volumio-remote-updater[774]: [2026-08-27 17:03:57] [connect] Successful connection Aug 27 17:03:58 kitchenblaster upmpdcli[1770]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Timeout Aug 27 17:03:58 kitchenblaster upmpdcli[1770]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Aug 27 17:03:58 kitchenblaster upmpdcli[1770]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Aug 27 17:04:00 kitchenblaster volumio[1290]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 27 17:04:00 kitchenblaster volumio[1290]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 27 17:04:00 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 17:04:00 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 17:04:00 kitchenblaster volumio[1290]: info: Starting MyVolumio Remote Streaming Endpoints Aug 27 17:04:00 kitchenblaster volumio[1290]: info: MyVolumio login type: Token Aug 27 17:04:00 kitchenblaster volumio[1290]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 27 17:04:00 kitchenblaster volumio[1290]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Aug 27 17:04:04 kitchenblaster volumio5-onboarding[2306]: 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:36042->127.0.0.1:3000: i/o timeout Aug 27 17:04:04 kitchenblaster systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Aug 27 17:04:04 kitchenblaster systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Aug 27 17:04:04 kitchenblaster upmpdcli[1770]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Timeout Aug 27 17:04:04 kitchenblaster upmpdcli[1770]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Aug 27 17:04:04 kitchenblaster upmpdcli[1770]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Aug 27 17:04:04 kitchenblaster systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 6. Aug 27 17:04:04 kitchenblaster systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Aug 27 17:04:04 kitchenblaster systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Aug 27 17:04:04 kitchenblaster volumio5-onboarding[2342]: time=2026-08-27T17:04:04.868+02:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Aug 27 17:04:10 kitchenblaster upmpdcli[1770]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Timeout while connecting Aug 27 17:04:10 kitchenblaster upmpdcli[1770]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Aug 27 17:04:10 kitchenblaster upmpdcli[1770]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Aug 27 17:04:12 kitchenblaster volumio-remote-updater[774]: [2026-08-27 17:04:12] [connect] Successful connection Aug 27 17:04:13 kitchenblaster volumio[1290]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Aug 27 17:04:13 kitchenblaster volumio[1290]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Aug 27 17:04:13 kitchenblaster volumio[1290]: info: Streaming services startup Aug 27 17:04:13 kitchenblaster volumio[1290]: info: Starting Streaming Daemon Aug 27 17:04:13 kitchenblaster volumio[1290]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Aug 27 17:04:14 kitchenblaster sudo[2405]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 27 17:04:14 kitchenblaster sudo[2405]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 17:04:14 kitchenblaster sudo[2405]: pam_unix(sudo:session): session closed for user root Aug 27 17:04:14 kitchenblaster volumio[1290]: verbose: New Socket.io Connection to 10.10.40.109:3000 from 10.10.40.108 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 31 Aug 27 17:04:14 kitchenblaster sshd[2375]: Accepted password for volumio from 10.10.30.104 port 54787 ssh2 Aug 27 17:04:14 kitchenblaster sshd[2375]: pam_unix(sshd:session): session opened for user volumio(uid=1000) by (uid=0) Aug 27 17:04:14 kitchenblaster systemd-logind[762]: New session 3 of user volumio. Aug 27 17:04:14 kitchenblaster systemd[1]: Started session-3.scope - Session 3 of User volumio. Aug 27 17:04:14 kitchenblaster volumio5-onboarding[2342]: 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:43252->127.0.0.1:3000: i/o timeout Aug 27 17:04:14 kitchenblaster systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Aug 27 17:04:14 kitchenblaster systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Aug 27 17:04:14 kitchenblaster volumio[1290]: info: Getting Spotify volume Aug 27 17:04:15 kitchenblaster systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 7. Aug 27 17:04:15 kitchenblaster systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Aug 27 17:04:15 kitchenblaster systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Aug 27 17:04:15 kitchenblaster volumio[1290]: error: [MQTT] MQTT connection error: Error: getaddrinfo ENOTFOUND homie.edv-msnufsktur.de Aug 27 17:04:15 kitchenblaster sshd[2375]: pam_env(sshd:session): deprecated reading of user environment enabled Aug 27 17:04:15 kitchenblaster volumio[1290]: verbose: New Socket.io Connection to 10.10.40.109:3000 from 10.10.40.108 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 32 Aug 27 17:04:15 kitchenblaster volumio[1290]: error: Cannot start Volumio Streaming Daemon Aug 27 17:04:15 kitchenblaster volumio[1290]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 27 17:04:15 kitchenblaster volumio[1290]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 27 17:04:15 kitchenblaster volumio5-onboarding[2417]: time=2026-08-27T17:04:15.921+02:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Aug 27 17:04:15 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 27 17:04:16 kitchenblaster upmpdcli[1770]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Timeout while connecting Aug 27 17:04:16 kitchenblaster upmpdcli[1770]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Aug 27 17:04:16 kitchenblaster upmpdcli[1770]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Aug 27 17:04:16 kitchenblaster mpd[2277]: 2026-08-27T17:04:16 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 27 17:04:16 kitchenblaster volumio[1290]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 33 Aug 27 17:04:16 kitchenblaster volumio[1290]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 34 Aug 27 17:04:16 kitchenblaster sudo[2143]: pam_unix(sudo:session): session closed for user root Aug 27 17:04:16 kitchenblaster systemd[1]: Started mpd.service - Music Player Daemon. Aug 27 17:04:17 kitchenblaster volumio[1290]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 35 Aug 27 17:04:18 kitchenblaster upmpdcli[1770]: :3:../src/mpdcli.cxx:148::MPDCli::startEventLoop: already started Aug 27 17:04:18 kitchenblaster volumio[1290]: verbose: New Socket.io Connection to 10.10.40.109:3000 from 10.10.40.108 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 36 Aug 27 17:04:19 kitchenblaster volumio[1290]: verbose: New Socket.io Connection to 10.10.40.109:3000 from 10.10.40.108 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 37 Aug 27 17:04:19 kitchenblaster volumio[1290]: error: updateQueue error: null Aug 27 17:04:19 kitchenblaster volumio[1290]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 38 Aug 27 17:04:19 kitchenblaster sudo[2455]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 27 17:04:19 kitchenblaster sudo[2455]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 17:04:19 kitchenblaster sudo[2457]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 17:04:19 kitchenblaster sudo[2457]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 17:04:19 kitchenblaster sudo[2455]: pam_unix(sudo:session): session closed for user root Aug 27 17:04:19 kitchenblaster sudo[2457]: pam_unix(sudo:session): session closed for user root Aug 27 17:04:20 kitchenblaster volumio[1290]: verbose: New Socket.io Connection to 10.10.40.109 from 10.10.30.104 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/151.0.0.0 Safari/537.36 Edg/151.0.0.0 Engine version: 3 Transport: polling Total Clients: 39 Aug 27 17:04:20 kitchenblaster volumio[1290]: verbose: New Socket.io Connection to 10.10.40.109:3000 from 10.10.40.108 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 40 Aug 27 17:04:20 kitchenblaster volumio[1290]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Aug 27 17:04:20 kitchenblaster volumio[1290]: verbose: New Socket.io Connection to 10.10.40.109:3000 from 10.10.40.108 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 41 Aug 27 17:04:20 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:04:20 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:04:20 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Aug 27 17:04:20 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Aug 27 17:04:20 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 27 17:04:20 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Aug 27 17:04:20 kitchenblaster volumio[1290]: info: CoreCommandRouter::volumioGetBrowseSources Aug 27 17:04:20 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 27 17:04:21 kitchenblaster volumio[1290]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 42 Aug 27 17:04:21 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 27 17:04:21 kitchenblaster volumio[1290]: error: [MQTT] MQTT connection error: Error: getaddrinfo ENOTFOUND homie.edv-msnufsktur.de Aug 27 17:04:22 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 27 17:04:22 kitchenblaster volumio[1290]: info: CoreCommandRouter::volumioGetState Aug 27 17:04:22 kitchenblaster volumio[1290]: info: CorePlayQueue::getTrack 0 Aug 27 17:04:23 kitchenblaster volumio[1290]: verbose: New Socket.io Connection to 10.10.40.109:3000 from 10.10.40.108 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 43 Aug 27 17:04:24 kitchenblaster volumio[1290]: info: Listing playlists Aug 27 17:04:24 kitchenblaster volumio[1290]: info: Listing playlists Aug 27 17:04:24 kitchenblaster volumio[1290]: verbose: New Socket.io Connection to 10.10.40.109:3000 from 10.10.40.108 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 44 Aug 27 17:04:24 kitchenblaster volumio[1290]: verbose: New Socket.io Connection to 10.10.40.109:3000 from 10.10.40.108 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 45 Aug 27 17:04:24 kitchenblaster volumio[1290]: verbose: New Socket.io Connection to 10.10.40.109:3000 from 10.10.40.108 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 46 Aug 27 17:04:25 kitchenblaster volumio[1290]: info: MyVolumio token set successfully Aug 27 17:04:25 kitchenblaster volumio[1290]: info: MYVOLUMIO: Adding device Aug 27 17:04:25 kitchenblaster volumio[1290]: info: MYVOLUMIO: Evaluating Server Aug 27 17:04:25 kitchenblaster volumio[1290]: verbose: New Socket.io Connection to 10.10.40.109:3000 from 10.10.40.108 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 47 Aug 27 17:04:25 kitchenblaster volumio[1290]: verbose: New Socket.io Connection to 10.10.40.109:3000 from 10.10.40.108 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 48 Aug 27 17:04:25 kitchenblaster volumio[1290]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 49 Aug 27 17:04:25 kitchenblaster volumio[1290]: verbose: New Socket.io Connection to 10.10.40.109:3000 from 10.10.40.108 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 50 Aug 27 17:04:25 kitchenblaster volumio5-onboarding[2417]: 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:34368->127.0.0.1:3000: i/o timeout Aug 27 17:04:25 kitchenblaster systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Aug 27 17:04:25 kitchenblaster systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Aug 27 17:04:26 kitchenblaster systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 8. Aug 27 17:04:26 kitchenblaster systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Aug 27 17:04:26 kitchenblaster systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Aug 27 17:04:26 kitchenblaster volumio5-onboarding[2498]: time=2026-08-27T17:04:26.739+02:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Aug 27 17:04:26 kitchenblaster volumio[1290]: info: MyVolumio Plan changed: premium Aug 27 17:04:26 kitchenblaster volumio[1290]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Aug 27 17:04:26 kitchenblaster volumio[1290]: info: Removing browser output: myVolumio user plan is not superstar Aug 27 17:04:26 kitchenblaster volumio[1290]: info: Removing audio output: Aug 27 17:04:26 kitchenblaster volumio[1290]: info: MYVOLUMIO: Adding device Aug 27 17:04:26 kitchenblaster volumio[1290]: info: MYVOLUMIO: Evaluating Server Aug 27 17:04:27 kitchenblaster volumio[1290]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 51 Aug 27 17:04:27 kitchenblaster volumio-remote-updater[774]: [2026-08-27 17:04:27] [connect] Successful connection Aug 27 17:04:28 kitchenblaster volumio[1290]: error: [MQTT] MQTT connection error: Error: getaddrinfo ENOTFOUND homie.edv-msnufsktur.de Aug 27 17:04:29 kitchenblaster volumio[1290]: info: Remote config written successfully Aug 27 17:04:29 kitchenblaster volumio[1290]: info: Starting Tunnel 1 Aug 27 17:04:29 kitchenblaster volumio[1290]: info: Starting Tunnel Connection Checker Aug 27 17:04:29 kitchenblaster volumio[1290]: info: Completed starting MyVolumio Plugin Aug 27 17:04:29 kitchenblaster volumio[1290]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 52 Aug 27 17:04:29 kitchenblaster volumio[1290]: info: MYVolumio Device disabled Aug 27 17:04:29 kitchenblaster volumio[1290]: info: MyVolumio status changed Aug 27 17:04:29 kitchenblaster volumio[1290]: info: Streaming services startup Aug 27 17:04:29 kitchenblaster volumio[1290]: info: Starting Streaming Daemon Aug 27 17:04:30 kitchenblaster volumio[1290]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 53 Aug 27 17:04:30 kitchenblaster sudo[2535]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 27 17:04:30 kitchenblaster sudo[2535]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 17:04:30 kitchenblaster sudo[2535]: pam_unix(sudo:session): session closed for user root Aug 27 17:04:31 kitchenblaster volumio[1290]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 54 Aug 27 17:04:31 kitchenblaster volumio[1290]: error: Cannot start Volumio Streaming Daemon Aug 27 17:04:31 kitchenblaster volumio[1290]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 27 17:04:31 kitchenblaster volumio[1290]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 27 17:04:31 kitchenblaster volumio[1290]: verbose: New Socket.io Connection to 10.10.40.109:3000 from 10.10.40.108 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 55 Aug 27 17:04:31 kitchenblaster volumio[1290]: verbose: New Socket.io Connection to 10.10.40.109:3000 from 10.10.40.108 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 56 Aug 27 17:04:31 kitchenblaster volumio[1290]: 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: 57 Aug 27 17:04:32 kitchenblaster volumio[1290]: 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: 57 Aug 27 17:04:32 kitchenblaster volumio[1290]: info: Setting Geolocation for MyVolumio to eu6 Aug 27 17:04:32 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 17:04:32 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 17:04:32 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 17:04:32 kitchenblaster sudo[2557]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 17:04:32 kitchenblaster sudo[2555]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 27 17:04:32 kitchenblaster sudo[2555]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 17:04:32 kitchenblaster sudo[2555]: pam_unix(sudo:session): session closed for user root Aug 27 17:04:32 kitchenblaster sudo[2557]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 17:04:33 kitchenblaster sudo[2557]: pam_unix(sudo:session): session closed for user root Aug 27 17:04:33 kitchenblaster volumio[1290]: info: Received Get System Info Aug 27 17:04:33 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 17:04:33 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 17:04:33 kitchenblaster volumio[1290]: info: Discovery: Getting this device information Aug 27 17:04:33 kitchenblaster volumio[1290]: info: CoreCommandRouter::volumioGetState Aug 27 17:04:33 kitchenblaster volumio[1290]: info: CorePlayQueue::getTrack 0 Aug 27 17:04:33 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 17:04:33 kitchenblaster volumio5-onboarding[2498]: time=2026-08-27T17:04:33.292+02:00 level=INFO msg="system info for e7b03979034f7703b9083994a0148315" deviceName=KitchenBlaster deviceVariant="" deviceModel= softwareVersion="" Aug 27 17:04:33 kitchenblaster volumio5-onboarding[2498]: time=2026-08-27T17:04:33.340+02:00 level=INFO msg="bootstrapping state" hasInternet=true Aug 27 17:04:33 kitchenblaster volumio[1290]: info: Received Get System Info Aug 27 17:04:33 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 17:04:33 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 17:04:33 kitchenblaster volumio[1290]: info: Discovery: Getting this device information Aug 27 17:04:33 kitchenblaster volumio[1290]: info: CoreCommandRouter::volumioGetState Aug 27 17:04:33 kitchenblaster volumio[1290]: info: CorePlayQueue::getTrack 0 Aug 27 17:04:33 kitchenblaster volumio[1290]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 17:04:33 kitchenblaster volumio[1290]: info: MYVolumio Device enabled Aug 27 17:04:33 kitchenblaster volumio[1290]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Aug 27 17:04:34 kitchenblaster volumio[1290]: info: Successfully Added MyVolumio device Aug 27 17:04:34 kitchenblaster volumio[1290]: [2026-08-27T15:04:34.190Z] @firebase/database: FIREBASE WARNING: Exception was thrown by user callback. TypeError: Cannot read properties of null (reading 'replace') Aug 27 17:04:34 kitchenblaster volumio[1290]: at MyVolumioPluginManager.fetchPluginKeys (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:8044) Aug 27 17:04:34 kitchenblaster volumio[1290]: at MyVolumioPluginManager.loadMyVolumioPlugins (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:11563) Aug 27 17:04:34 kitchenblaster volumio[1290]: at MyVolumioPluginManager.myVolumioDeviceStatusChanged (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:5527) Aug 27 17:04:34 kitchenblaster volumio[1290]: at /myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:6807 Aug 27 17:04:34 kitchenblaster volumio[1290]: at /volumio/node_modules/v-conf/index.js:166:13 Aug 27 17:04:34 kitchenblaster volumio[1290]: at /volumio/node_modules/multimap/index.js:140:9 Aug 27 17:04:34 kitchenblaster volumio[1290]: at Array.forEach () Aug 27 17:04:34 kitchenblaster volumio[1290]: at /volumio/node_modules/multimap/index.js:139:13 Aug 27 17:04:34 kitchenblaster volumio[1290]: at mapEach (/volumio/node_modules/multimap/index.js:9:5) Aug 27 17:04:34 kitchenblaster volumio[1290]: at Multimap.forEachEntry (/volumio/node_modules/multimap/index.js:133:5) Aug 27 17:04:34 kitchenblaster volumio[1290]: at Multimap.forEach (/volumio/node_modules/multimap/index.js:138:10) Aug 27 17:04:34 kitchenblaster volumio[1290]: at Config.set (/volumio/node_modules/v-conf/index.js:163:20) Aug 27 17:04:34 kitchenblaster volumio[1290]: at myVolumio.togglePremiumPlugins (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:36229) Aug 27 17:04:34 kitchenblaster volumio[1290]: at /myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:17831 Aug 27 17:04:34 kitchenblaster volumio[1290]: at /volumio/node_modules/@firebase/database/dist/index.node.cjs.js:4549:22 Aug 27 17:04:34 kitchenblaster volumio[1290]: at exceptionGuard (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:698:9) Aug 27 17:04:34 kitchenblaster volumio[1290]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 17:04:34 kitchenblaster volumio[1290]: TypeError: Cannot read properties of null (reading 'replace') Aug 27 17:04:34 kitchenblaster volumio[1290]: at MyVolumioPluginManager.fetchPluginKeys (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:8044) Aug 27 17:04:34 kitchenblaster volumio[1290]: at MyVolumioPluginManager.loadMyVolumioPlugins (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:11563) Aug 27 17:04:34 kitchenblaster volumio[1290]: at MyVolumioPluginManager.myVolumioDeviceStatusChanged (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:5527) Aug 27 17:04:34 kitchenblaster volumio[1290]: at /myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:6807 Aug 27 17:04:34 kitchenblaster volumio[1290]: at /volumio/node_modules/v-conf/index.js:166:13 Aug 27 17:04:34 kitchenblaster volumio[1290]: at /volumio/node_modules/multimap/index.js:140:9 Aug 27 17:04:34 kitchenblaster volumio[1290]: at Array.forEach () Aug 27 17:04:34 kitchenblaster volumio[1290]: at /volumio/node_modules/multimap/index.js:139:13 Aug 27 17:04:34 kitchenblaster volumio[1290]: at mapEach (/volumio/node_modules/multimap/index.js:9:5) Aug 27 17:04:34 kitchenblaster volumio[1290]: at Multimap.forEachEntry (/volumio/node_modules/multimap/index.js:133:5) Aug 27 17:04:34 kitchenblaster volumio[1290]: at Multimap.forEach (/volumio/node_modules/multimap/index.js:138:10) Aug 27 17:04:34 kitchenblaster volumio[1290]: at Config.set (/volumio/node_modules/v-conf/index.js:163:20) Aug 27 17:04:34 kitchenblaster volumio[1290]: at myVolumio.togglePremiumPlugins (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:36229) Aug 27 17:04:34 kitchenblaster volumio[1290]: at /myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:17831 Aug 27 17:04:34 kitchenblaster volumio[1290]: at /volumio/node_modules/@firebase/database/dist/index.node.cjs.js:4549:22 Aug 27 17:04:34 kitchenblaster volumio[1290]: at exceptionGuard (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:698:9) Aug 27 17:04:34 kitchenblaster volumio[1290]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 17:04:42 kitchenblaster volumio-remote-updater[774]: [2026-08-27 17:04:42] [connect] Successful connection Aug 27 17:04:49 kitchenblaster sudo[2590]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-27 17:03' Aug 27 17:04:49 kitchenblaster sudo[2590]: 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"