Feb 23 11:48:00 volumio ntpd[1085]: CLOCK: time stepped by 35.993096 Feb 23 11:48:00 volumio ntpd[1085]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Feb 23 11:48:00 volumio volumio[1502]: Forking 3 albumart workers Feb 23 11:48:00 volumio sudo[1471]: pam_unix(sudo:session): session closed for user root Feb 23 11:48:00 volumio volumio[1289]: info: Loading i18n strings for locale en Feb 23 11:48:01 volumio volumio[1289]: Updating browse sources language Feb 23 11:48:01 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 11:48:02 volumio wpa_supplicant[1251]: RRM: Ignoring radio measurement request: Not RRM network Feb 23 11:48:02 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 11:48:02 volumio volumio[1289]: info: CoreCommandRouter::initPlayerControls Feb 23 11:48:02 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully. Feb 23 11:48:02 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 11:48:02 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 11:48:02 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 11:48:02 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 11:48:02 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 11:48:02 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 11:48:02 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 11:48:02 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 11:48:02 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 11:48:02 volumio volumio[1289]: Express server listening on port 3000 Feb 23 11:48:02 volumio volumio[1289]: [Metrics] WebUI: 10s 172.24ms Feb 23 11:48:02 volumio volumio[1289]: info: CoreStateMachine::resetVolumioState Feb 23 11:48:02 volumio volumio[1289]: info: CoreStateMachine::getcurrentVolume Feb 23 11:48:02 volumio volumio[1289]: info: CoreCommandRouter::volumioRetrievevolume Feb 23 11:48:02 volumio volumio[1289]: info: CoreStateMachine::pushState Feb 23 11:48:02 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Feb 23 11:48:02 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 11:48:02 volumio volumio[1289]: info: CoreCommandRouter::volumioPushState Feb 23 11:48:02 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 11:48:02 volumio volumio[1289]: info: Volumio Network Manager: Network status updated: 2 Feb 23 11:48:03 volumio volumio[1289]: verbose: New Socket.io Connection to 192.168.1.71 from 192.168.1.70 UA: Mozilla/5.0 (Linux; Android 15; SM-G991B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.132 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Feb 23 11:48:03 volumio volumio[1289]: info: Reloading queue from file Feb 23 11:48:03 volumio volumio[1289]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Feb 23 11:48:03 volumio volumio[1289]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Feb 23 11:48:03 volumio volumio[1289]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03115 Feb 23 11:48:03 volumio volumio[1289]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Feb 23 11:48:03 volumio volumio[1289]: info: Setting Device type: Raspberry PI Feb 23 11:48:03 volumio volumio[1289]: info: CoreStateMachine::setRepeat null single undefined Feb 23 11:48:03 volumio volumio[1289]: info: CoreStateMachine::pushState Feb 23 11:48:03 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Feb 23 11:48:03 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 11:48:03 volumio volumio[1289]: info: CoreCommandRouter::volumioPushState Feb 23 11:48:03 volumio volumio[1289]: info: CoreStateMachine::setRandom null Feb 23 11:48:03 volumio volumio[1289]: info: CoreStateMachine::pushState Feb 23 11:48:03 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Feb 23 11:48:03 volumio volumio[1289]: info: CoreCommandRouter::volumioPushState Feb 23 11:48:04 volumio wpa_supplicant[1251]: RRM: Ignoring radio measurement request: Not RRM network Feb 23 11:48:04 volumio volumio[1289]: verbose: New Socket.io Connection to 192.168.1.71 from 192.168.1.70 UA: Mozilla/5.0 (Linux; Android 15; SM-G991B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.132 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Feb 23 11:48:04 volumio volumio[1289]: info: Discovery: adding fb1bcf16-c8fa-47ab-93f8-bedc78c713f8 Feb 23 11:48:04 volumio volumio[1289]: info: Discovery: Found device Volumio Feb 23 11:48:04 volumio volumio[1289]: info: CoreCommandRouter::volumioGetState Feb 23 11:48:04 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Feb 23 11:48:04 volumio dhcpcd[747]: timed out Feb 23 11:48:04 volumio sh[689]: timed out Feb 23 11:48:04 volumio volumio[1289]: info: Discovery: this is already registered, fb1bcf16-c8fa-47ab-93f8-bedc78c713f8 Feb 23 11:48:04 volumio volumio[1289]: info: Discovery: Found device Volumio Feb 23 11:48:04 volumio volumio[1289]: info: CoreCommandRouter::volumioGetState Feb 23 11:48:04 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Feb 23 11:48:04 volumio sh[624]: ifup: failed to bring up eth0 Feb 23 11:48:04 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Feb 23 11:48:04 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Feb 23 11:48:04 volumio volumio[1289]: info: Completed loading Core Plugins Feb 23 11:48:04 volumio volumio[1289]: info: Preparing to generate the ALSA configuration file Feb 23 11:48:05 volumio volumio[1289]: info: Asound.conf file unchanged, so no further update is needed Feb 23 11:48:05 volumio volumio[1289]: info: Output device has changed, restarting MPD Feb 23 11:48:05 volumio volumio[1289]: info: Output device has changed, restarting Shairport Sync Feb 23 11:48:05 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 11:48:05 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 11:48:05 volumio sudo[1558]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 23 11:48:05 volumio sudo[1558]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 11:48:05 volumio volumio[1513]: Starting albumart workers Feb 23 11:48:05 volumio sudo[1556]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 23 11:48:05 volumio sudo[1556]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 11:48:05 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 23 11:48:05 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 23 11:48:05 volumio sudo[1556]: pam_unix(sudo:session): session closed for user root Feb 23 11:48:05 volumio volumio[1289]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 23 11:48:05 volumio volumio[1289]: info: ___________ START PLUGINS ___________ Feb 23 11:48:05 volumio sudo[1566]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 23 11:48:05 volumio sudo[1566]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 23 11:48:05 volumio sudo[1568]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Feb 23 11:48:05 volumio sudo[1566]: pam_unix(sudo:session): session closed for user root Feb 23 11:48:05 volumio volumio[1289]: info: ControllerMpd::onStart: Initializing MPD Feb 23 11:48:05 volumio volumio[1289]: info: Creating MPD Configuration file Feb 23 11:48:05 volumio sudo[1572]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 23 11:48:05 volumio sudo[1572]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 11:48:05 volumio sudo[1572]: pam_unix(sudo:session): session closed for user root Feb 23 11:48:05 volumio sudo[1569]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Feb 23 11:48:05 volumio sudo[1569]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 11:48:05 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 11:48:05 volumio volumio[1289]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 11:48:05 volumio volumio[1289]: info: [1771843685645] CoreMusicLibrary::Adding element Media Servers Feb 23 11:48:05 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 11:48:05 volumio sudo[1574]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 23 11:48:05 volumio sudo[1574]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 11:48:05 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Feb 23 11:48:05 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 23 11:48:05 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 23 11:48:05 volumio volumio[1289]: info: UPNP Browser: Client initialized successfully Feb 23 11:48:05 volumio volumio[1512]: Starting albumart workers Feb 23 11:48:05 volumio upmpdcli[1578]: Could not open config: /tmp/upmpdcli.conf Feb 23 11:48:05 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 23 11:48:05 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 23 11:48:05 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. Feb 23 11:48:05 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 11:48:05 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 11:48:06 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Feb 23 11:48:06 volumio sudo[1569]: pam_unix(sudo:session): session closed for user root Feb 23 11:48:06 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 23 11:48:06 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 23 11:48:06 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 23 11:48:06 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 23 11:48:06 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 23 11:48:06 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 23 11:48:06 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 23 11:48:06 volumio volumio[1289]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 23 11:48:06 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 11:48:06 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 11:48:06 volumio sudo[1588]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 23 11:48:06 volumio sudo[1588]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 23 11:48:06 volumio sudo[1597]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Feb 23 11:48:06 volumio sudo[1588]: pam_unix(sudo:session): session closed for user root Feb 23 11:48:06 volumio volumio[1514]: Starting albumart workers Feb 23 11:48:06 volumio volumio[1289]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 23 11:48:06 volumio volumio[1289]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 11:48:06 volumio volumio[1289]: info: [1771843686399] CoreMusicLibrary::Adding element Last_100 Feb 23 11:48:06 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 11:48:06 volumio volumio5-onboarding[1581]: time=2026-02-23T11:48:06.426+01:00 level=INFO msg="running volumio5-device-gateway" version=30d1950e+CHANGES buildDate=2026-01-29T13:10:08Z Feb 23 11:48:06 volumio volumio[1289]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 11:48:06 volumio volumio[1289]: info: [1771843686431] CoreMusicLibrary::Adding element Webradio Feb 23 11:48:06 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 11:48:06 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 11:48:06 volumio volumio[1289]: info: Initializing BBC Radios Feb 23 11:48:06 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 11:48:06 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 11:48:06 volumio volumio[1289]: info: Loading i18n strings for locale en Feb 23 11:48:07 volumio sudo[1625]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /data/volumiokioskextensions/IframeKeyboardBridge Feb 23 11:48:07 volumio sudo[1625]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 11:48:07 volumio sudo[1625]: pam_unix(sudo:session): session closed for user root Feb 23 11:48:07 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 11:48:07 volumio volumio[1289]: info: Volumio Calling Home Feb 23 11:48:07 volumio sudo[1638]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Feb 23 11:48:07 volumio sudo[1638]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 11:48:07 volumio sudo[1640]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Feb 23 11:48:08 volumio sudo[1638]: pam_unix(sudo:session): session closed for user root Feb 23 11:48:08 volumio sudo[1640]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 11:48:08 volumio sudo[1642]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Feb 23 11:48:08 volumio sudo[1642]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 11:48:08 volumio systemd[1]: Reloading. Feb 23 11:48:09 volumio systemd[1]: Reloading. Feb 23 11:48:09 volumio sudo[1640]: pam_unix(sudo:session): session closed for user root Feb 23 11:48:10 volumio mpd[1598]: 2026-02-23T11:48:10 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 23 11:48:10 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 23 11:48:10 volumio sudo[1558]: pam_unix(sudo:session): session closed for user root Feb 23 11:48:10 volumio sudo[1574]: pam_unix(sudo:session): session closed for user root Feb 23 11:48:10 volumio sudo[1642]: pam_unix(sudo:session): session closed for user root Feb 23 11:48:10 volumio volumio[1289]: info: Received Get System Info Feb 23 11:48:10 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 11:48:10 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 11:48:10 volumio volumio[1289]: info: Discovery: Getting this device information Feb 23 11:48:10 volumio volumio[1289]: info: CoreCommandRouter::volumioGetState Feb 23 11:48:10 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Feb 23 11:48:10 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 11:48:10 volumio volumio[1289]: info: CoreCommandRouter::volumioGetState Feb 23 11:48:10 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Feb 23 11:48:11 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 23 11:48:11 volumio volumio[1289]: info: CoreCommandRouter::volumioGetVisibleSources Feb 23 11:48:11 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 11:48:11 volumio volumio[1289]: info: CoreCommandRouter::volumioGetState Feb 23 11:48:11 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Feb 23 11:48:11 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 23 11:48:11 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 23 11:48:11 volumio volumio[1289]: info: Received Get System Info Feb 23 11:48:11 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 11:48:11 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 11:48:11 volumio volumio[1289]: info: Discovery: Getting this device information Feb 23 11:48:11 volumio volumio[1289]: info: CoreCommandRouter::volumioGetState Feb 23 11:48:11 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Feb 23 11:48:11 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 11:48:11 volumio volumio[1289]: info: CoreCommandRouter::volumioGetState Feb 23 11:48:11 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Feb 23 11:48:11 volumio volumio[1289]: info: Listing playlists Feb 23 11:48:11 volumio volumio[1289]: info: Received Get System Info Feb 23 11:48:11 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 11:48:11 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 11:48:11 volumio volumio[1289]: info: Discovery: Getting this device information Feb 23 11:48:11 volumio volumio[1289]: info: CoreCommandRouter::volumioGetState Feb 23 11:48:11 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Feb 23 11:48:11 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 11:48:11 volumio volumio[1289]: info: CoreCommandRouter::volumioGetState Feb 23 11:48:11 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Feb 23 11:48:11 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 23 11:48:11 volumio volumio[1289]: info: CoreCommandRouter::volumioGetVisibleSources Feb 23 11:48:11 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 11:48:11 volumio volumio[1289]: info: CoreCommandRouter::volumioGetState Feb 23 11:48:11 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Feb 23 11:48:11 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 23 11:48:11 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 23 11:48:11 volumio volumio[1289]: info: Received Get System Info Feb 23 11:48:11 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 11:48:11 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 11:48:11 volumio volumio[1289]: info: Discovery: Getting this device information Feb 23 11:48:11 volumio volumio[1289]: info: CoreCommandRouter::volumioGetState Feb 23 11:48:11 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Feb 23 11:48:11 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 11:48:11 volumio volumio[1289]: info: CoreCommandRouter::volumioGetState Feb 23 11:48:11 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Feb 23 11:48:11 volumio volumio[1289]: info: Listing playlists Feb 23 11:48:11 volumio volumio[1289]: info: Received Get System Info Feb 23 11:48:11 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 11:48:11 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 11:48:11 volumio volumio[1289]: info: Discovery: Getting this device information Feb 23 11:48:11 volumio volumio[1289]: info: CoreCommandRouter::volumioGetState Feb 23 11:48:11 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Feb 23 11:48:11 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 11:48:11 volumio volumio[1289]: info: CoreCommandRouter::volumioGetState Feb 23 11:48:11 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Feb 23 11:48:11 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 23 11:48:11 volumio volumio[1289]: info: CoreCommandRouter::volumioGetVisibleSources Feb 23 11:48:11 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 11:48:11 volumio volumio[1289]: info: CoreCommandRouter::volumioGetState Feb 23 11:48:11 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Feb 23 11:48:11 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 23 11:48:11 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 23 11:48:11 volumio volumio[1289]: info: Received Get System Info Feb 23 11:48:11 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 11:48:11 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 11:48:11 volumio volumio[1289]: info: Discovery: Getting this device information Feb 23 11:48:11 volumio volumio[1289]: info: CoreCommandRouter::volumioGetState Feb 23 11:48:11 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Feb 23 11:48:11 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 11:48:11 volumio volumio[1289]: info: CoreCommandRouter::volumioGetState Feb 23 11:48:11 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Feb 23 11:48:11 volumio volumio[1289]: info: Listing playlists Feb 23 11:48:12 volumio volumio[1289]: info: Received Get System Info Feb 23 11:48:12 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 11:48:12 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 11:48:12 volumio volumio[1289]: info: Discovery: Getting this device information Feb 23 11:48:12 volumio volumio[1289]: info: CoreCommandRouter::volumioGetState Feb 23 11:48:12 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Feb 23 11:48:12 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 11:48:12 volumio volumio[1289]: info: CoreCommandRouter::volumioGetState Feb 23 11:48:12 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Feb 23 11:48:12 volumio volumio[1289]: info: touch_display: No backlight interface detected. Feb 23 11:48:12 volumio sudo[1701]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 23 11:48:12 volumio sudo[1701]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 11:48:12 volumio sudo[1701]: pam_unix(sudo:session): session closed for user root Feb 23 11:48:12 volumio volumio[1289]: info: touch_display: systemctl daemon-reload succeeded. Feb 23 11:48:12 volumio volumio-remote-updater[836]: [2026-02-23 11:48:12] [connect] Successful connection Feb 23 11:48:12 volumio volumio[1289]: info: touch_display: systemctl disable getty@tty1.service succeeded. Feb 23 11:48:12 volumio volumio[1289]: info: touch_display: systemctl stop getty@tty1.service succeeded. Feb 23 11:48:12 volumio sudo[1704]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 23 11:48:12 volumio sudo[1704]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 11:48:12 volumio sudo[1708]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Feb 23 11:48:12 volumio sudo[1708]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 11:48:12 volumio volumio[1289]: info: MPD Permissions set Feb 23 11:48:12 volumio volumio[1289]: info: MPD Permissions set Feb 23 11:48:12 volumio sudo[1708]: pam_unix(sudo:session): session closed for user root Feb 23 11:48:12 volumio sudo[1704]: pam_unix(sudo:session): session closed for user root Feb 23 11:48:12 volumio volumio[1289]: 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: 4 Feb 23 11:48:12 volumio volumio[1289]: info: Volumio called home Feb 23 11:48:12 volumio sudo[1715]: 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/ Feb 23 11:48:12 volumio sudo[1715]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 11:48:12 volumio sudo[1715]: pam_unix(sudo:session): session closed for user root Feb 23 11:48:12 volumio volumio[1289]: 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: 4 Feb 23 11:48:12 volumio volumio[1289]: info: Received Get System Info Feb 23 11:48:12 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 11:48:12 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 11:48:12 volumio volumio[1289]: info: Discovery: Getting this device information Feb 23 11:48:12 volumio volumio[1289]: info: CoreCommandRouter::volumioGetState Feb 23 11:48:12 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Feb 23 11:48:12 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 11:48:12 volumio volumio5-onboarding[1581]: time=2026-02-23T11:48:12.903+01:00 level=INFO msg="system info for 1d7a137a676f17f76b2cce6ec7c1f579" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.096 Feb 23 11:48:13 volumio wpa_supplicant[1251]: RRM: Ignoring radio measurement request: Not RRM network Feb 23 11:48:13 volumio volumio[1289]: error: MPD error: The expression evaluated to a falsy value: Feb 23 11:48:13 volumio volumio[1289]: assert.ok(self.idling) Feb 23 11:48:13 volumio volumio[1289]: error: The expression evaluated to a falsy value: Feb 23 11:48:13 volumio volumio[1289]: assert.ok(self.idling) Feb 23 11:48:13 volumio volumio[1289]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Feb 23 11:48:13 volumio volumio[1289]: error: touch_display: Failed to start volumio-kiosk.service: Error: Command failed: /usr/bin/sudo /bin/systemctl start volumio-kiosk.service Feb 23 11:48:13 volumio volumio[1289]: Failed to start volumio-kiosk.service: Unit volumio-kiosk.service not found. Feb 23 11:48:13 volumio volumio[1289]: info: Completed starting Core Plugins Feb 23 11:48:13 volumio volumio[1289]: info: ------------------------------------------- Feb 23 11:48:13 volumio volumio[1289]: info: ----- MyVolumio plugins startup ---- Feb 23 11:48:13 volumio volumio[1289]: info: ------------------------------------------- Feb 23 11:48:13 volumio volumio[1289]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 23 11:48:13 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 11:48:13 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 11:48:13 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 11:48:13 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 11:48:13 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 11:48:13 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 11:48:13 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 11:48:13 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 11:48:13 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 11:48:13 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 11:48:13 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 11:48:13 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 11:48:13 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 11:48:13 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 11:48:13 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 11:48:13 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 11:48:13 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 11:48:13 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 11:48:13 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 11:48:13 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 11:48:14 volumio volumio[1289]: info: Received Get System Info Feb 23 11:48:14 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 11:48:14 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 11:48:14 volumio volumio[1289]: info: Discovery: Getting this device information Feb 23 11:48:14 volumio volumio[1289]: info: CoreCommandRouter::volumioGetState Feb 23 11:48:14 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Feb 23 11:48:14 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 11:48:14 volumio volumio[1289]: error: updateQueue error: null Feb 23 11:48:14 volumio volumio-remote-updater[836]: [2026-02-23 11:48:14] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1771843692 101 Feb 23 11:48:14 volumio volumio[1289]: 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: 6 Feb 23 11:48:14 volumio volumio[1289]: info: MPD running with PID1598 Feb 23 11:48:14 volumio volumio[1289]: ,establishing connection Feb 23 11:48:14 volumio volumio[1289]: info: touch_display: IframeKeyboardBridge extension installed successfully Feb 23 11:48:15 volumio wpa_supplicant[1251]: RRM: Ignoring radio measurement request: Not RRM network Feb 23 11:48:15 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 23 11:48:15 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 23 11:48:15 volumio volumio5-onboarding[1581]: time=2026-02-23T11:48:15.941+01:00 level=INFO msg="enabling local network discovery" Feb 23 11:48:15 volumio volumio5-onboarding[1581]: time=2026-02-23T11:48:15.983+01:00 level=INFO msg="bootstrapping state" hasInternet=true Feb 23 11:48:16 volumio volumio[1289]: info: Received Get System Info Feb 23 11:48:16 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 11:48:16 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 11:48:16 volumio volumio[1289]: info: Discovery: Getting this device information Feb 23 11:48:16 volumio volumio[1289]: info: CoreCommandRouter::volumioGetState Feb 23 11:48:16 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Feb 23 11:48:16 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 11:48:16 volumio volumio[1289]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Feb 23 11:48:16 volumio volumio[1289]: info: Starting Shairport Sync Feb 23 11:48:16 volumio volumio[1289]: info: Starting Shairport Sync Feb 23 11:48:16 volumio sudo[1736]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Feb 23 11:48:16 volumio volumio[1289]: info: Starting Shairport Sync Feb 23 11:48:16 volumio sudo[1736]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 11:48:16 volumio sudo[1740]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 23 11:48:16 volumio sudo[1738]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 23 11:48:16 volumio sudo[1738]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 11:48:16 volumio volumio-remote-updater[836]: Test mode disabled Feb 23 11:48:16 volumio volumio-remote-updater[836]: Alpha mode disabled Feb 23 11:48:16 volumio volumio-remote-updater[836]: Alpha legacy test mode disabled Feb 23 11:48:16 volumio volumio[1289]: error: updateQueue error: null Feb 23 11:48:16 volumio sudo[1736]: pam_unix(sudo:session): session closed for user root Feb 23 11:48:16 volumio sudo[1740]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 11:48:16 volumio sudo[1745]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 23 11:48:16 volumio sudo[1745]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 11:48:16 volumio volumio[1289]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 23 11:48:16 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 23 11:48:16 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Feb 23 11:48:16 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 23 11:48:16 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 23 11:48:16 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 23 11:48:16 volumio systemd[1]: shairport-sync.service: Consumed 1.162s CPU time. Feb 23 11:48:16 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 23 11:48:16 volumio sudo[1740]: pam_unix(sudo:session): session closed for user root Feb 23 11:48:16 volumio sudo[1738]: pam_unix(sudo:session): session closed for user root Feb 23 11:48:16 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 23 11:48:16 volumio volumio[1289]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Feb 23 11:48:16 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 23 11:48:16 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 23 11:48:16 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 23 11:48:16 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 23 11:48:16 volumio volumio5-onboarding[1581]: time=2026-02-23T11:48:16.521+01:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=tidal error="could not open plugin config file for \"tidal\": open /data/configuration/music_service/tidal/config.json: no such file or directory" Feb 23 11:48:16 volumio volumio5-onboarding[1581]: time=2026-02-23T11:48:16.522+01:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=qobuz error="could not open plugin config file for \"qobuz\": open /data/configuration/music_service/qobuz/config.json: no such file or directory" Feb 23 11:48:16 volumio volumio5-onboarding[1581]: time=2026-02-23T11:48:16.522+01:00 level=WARN msg="could not read username/password data for music provider" component=volumio provider=hi_res_audio error="could not open plugin config file for \"hi_res_audio\": open /data/configuration/music_service/hi_res_audio/config.json: no such file or directory" Feb 23 11:48:16 volumio sudo[1749]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 23 11:48:16 volumio volumio[1289]: info: Shairport-Sync Started Feb 23 11:48:16 volumio sudo[1749]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 11:48:16 volumio sudo[1745]: pam_unix(sudo:session): session closed for user root Feb 23 11:48:16 volumio volumio[1289]: Error adding Membership: Error: addMembership EINVAL Feb 23 11:48:16 volumio volumio[1289]: info: Shairport-Sync Started Feb 23 11:48:16 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 23 11:48:16 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 23 11:48:16 volumio volumio[1289]: info: CoreCommandRouter::volumioGetState Feb 23 11:48:16 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Feb 23 11:48:16 volumio volumio[1289]: info: Shairport-Sync Started Feb 23 11:48:17 volumio wpa_supplicant[1251]: RRM: Ignoring radio measurement request: Not RRM network Feb 23 11:48:17 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Feb 23 11:48:17 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Feb 23 11:48:20 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Feb 23 11:48:20 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 23 11:48:21 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 23 11:48:21 volumio sudo[1749]: pam_unix(sudo:session): session closed for user root Feb 23 11:48:21 volumio volumio[1289]: info: Upmpdcli Daemon Started Feb 23 11:48:22 volumio volumio[1289]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 23 11:48:22 volumio volumio[1289]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 23 11:48:22 volumio volumio[1289]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 23 11:48:22 volumio volumio[1289]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 23 11:48:22 volumio volumio[1289]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 23 11:48:22 volumio volumio[1289]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 23 11:48:22 volumio volumio[1289]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 23 11:48:22 volumio volumio[1289]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 23 11:48:22 volumio volumio[1289]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 23 11:48:22 volumio volumio[1289]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 23 11:48:22 volumio volumio[1289]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 23 11:48:22 volumio volumio[1289]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 23 11:48:22 volumio volumio[1289]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 23 11:48:22 volumio volumio[1289]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 23 11:48:22 volumio volumio[1289]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 23 11:48:22 volumio volumio[1289]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 23 11:48:22 volumio volumio[1289]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 23 11:48:22 volumio volumio[1289]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 23 11:48:22 volumio volumio[1289]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 23 11:48:22 volumio volumio[1289]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 23 11:48:22 volumio volumio[1289]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 23 11:48:22 volumio volumio[1289]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 23 11:48:22 volumio volumio[1289]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 23 11:48:22 volumio volumio[1289]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 23 11:48:22 volumio volumio[1289]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 23 11:48:22 volumio volumio[1289]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 23 11:48:22 volumio volumio[1289]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 23 11:48:22 volumio volumio[1289]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 23 11:48:22 volumio volumio[1289]: info: Adding plugin bluetooth to MyMusic Plugins Feb 23 11:48:22 volumio volumio[1289]: info: Adding plugin multiroom to MyMusic Plugins Feb 23 11:48:22 volumio volumio[1289]: info: Adding plugin metavolumio to MyMusic Plugins Feb 23 11:48:22 volumio volumio[1289]: info: Adding plugin cd_controller to MyMusic Plugins Feb 23 11:48:22 volumio volumio[1289]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 23 11:48:22 volumio volumio[1289]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 23 11:48:22 volumio volumio[1289]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 23 11:48:22 volumio volumio[1289]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 23 11:48:28 volumio wpa_supplicant[1251]: RRM: Ignoring radio measurement request: Not RRM network Feb 23 11:48:28 volumio volumio[1289]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 23 11:48:28 volumio volumio[1289]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 23 11:48:28 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 11:48:28 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 11:48:28 volumio volumio[1289]: info: Starting MyVolumio Remote Streaming Endpoints Feb 23 11:48:28 volumio volumio[1289]: info: MyVolumio login type: Token Feb 23 11:48:29 volumio volumio[1289]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 23 11:48:29 volumio volumio[1289]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 23 11:48:30 volumio wpa_supplicant[1251]: RRM: Ignoring radio measurement request: Not RRM network Feb 23 11:48:31 volumio volumio5-onboarding[1581]: time=2026-02-23T11:48:31.117+01:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.70:57860 Feb 23 11:48:32 volumio wpa_supplicant[1251]: RRM: Ignoring radio measurement request: Not RRM network Feb 23 11:48:34 volumio volumio[1289]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 23 11:48:34 volumio volumio[1289]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 23 11:48:34 volumio volumio[1289]: info: Streaming services startup Feb 23 11:48:34 volumio volumio[1289]: info: Starting Streaming Daemon Feb 23 11:48:34 volumio sudo[1838]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 23 11:48:34 volumio sudo[1838]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 11:48:34 volumio volumio[1289]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 23 11:48:34 volumio sudo[1838]: pam_unix(sudo:session): session closed for user root Feb 23 11:48:34 volumio volumio[1289]: error: Cannot start Volumio Streaming Daemon Feb 23 11:48:34 volumio volumio[1289]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 23 11:48:34 volumio volumio[1289]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 23 11:48:35 volumio wpa_supplicant[1251]: RRM: Ignoring radio measurement request: Not RRM network Feb 23 11:48:36 volumio volumio[1289]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 23 11:48:37 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 11:48:37 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 11:48:37 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 23 11:48:37 volumio volumio5-onboarding[1581]: time=2026-02-23T11:48:37.309+01:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.70:57860 @ 0x2d191d0" latency=1.391200109s platform=PLATFORM_ANDROID version=5.260120.0 Feb 23 11:48:37 volumio volumio5-onboarding[1581]: time=2026-02-23T11:48:37.310+01:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.70:57860 @ 0x2d191d0" latency=1.391048535s timeout=10s Feb 23 11:48:37 volumio volumio5-onboarding[1581]: time=2026-02-23T11:48:37.310+01:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.70:57860 @ 0x2d191d0" Feb 23 11:48:37 volumio volumio-remote-updater[836]: Test mode disabled Feb 23 11:48:37 volumio volumio-remote-updater[836]: Alpha mode disabled Feb 23 11:48:37 volumio volumio-remote-updater[836]: Alpha legacy test mode disabled Feb 23 11:48:37 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Feb 23 11:48:37 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 23 11:48:37 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 23 11:48:37 volumio volumio[1289]: info: Received Get System Info Feb 23 11:48:37 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 11:48:37 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 11:48:37 volumio volumio[1289]: info: Discovery: Getting this device information Feb 23 11:48:37 volumio volumio[1289]: info: CoreCommandRouter::volumioGetState Feb 23 11:48:37 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Feb 23 11:48:37 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 11:48:37 volumio volumio5-onboarding[1581]: time=2026-02-23T11:48:37.660+01:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.1.70:57860 @ 0x2d191d0" name=Volumio Feb 23 11:48:37 volumio volumio5-onboarding[1581]: time=2026-02-23T11:48:37.662+01:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.1.70:57860 @ 0x2d191d0" language=en Feb 23 11:48:37 volumio wpa_supplicant[1251]: RRM: Ignoring radio measurement request: Not RRM network Feb 23 11:48:37 volumio volumio[1289]: info: MyVolumio token set successfully Feb 23 11:48:37 volumio volumio[1289]: info: MYVOLUMIO: Adding device Feb 23 11:48:37 volumio volumio[1289]: info: MYVOLUMIO: Evaluating Server Feb 23 11:48:37 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Feb 23 11:48:37 volumio volumio5-onboarding[1581]: time=2026-02-23T11:48:37.725+01:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.1.70:57860 @ 0x2d191d0" timezone=Europe/Rome Feb 23 11:48:37 volumio volumio5-onboarding[1581]: time=2026-02-23T11:48:37.726+01:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.1.70:57860 @ 0x2d191d0" available=true connected=false macAddress= ip4Address= ip6Address= Feb 23 11:48:37 volumio volumio5-onboarding[1581]: time=2026-02-23T11:48:37.729+01:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.70:57860 @ 0x2d191d0" available=true connected=true macAddress=88:a2:9e:9c:6c:94 ip4Address=192.168.1.71/24 ip6Address= ssid="FRITZ!Box 7490" Feb 23 11:48:37 volumio volumio5-onboarding[1581]: time=2026-02-23T11:48:37.730+01:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.1.70:57860 @ 0x2d191d0" setupComplete=true Feb 23 11:48:37 volumio volumio[1289]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 23 11:48:37 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 23 11:48:37 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Feb 23 11:48:38 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 23 11:48:38 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 11:48:38 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 23 11:48:38 volumio volumio[1289]: amixer -c 0 info | grep "bcm2835 ALSA" Feb 23 11:48:38 volumio volumio[1289]: amixer -c 1 info | grep "bcm2835 Headphones" Feb 23 11:48:38 volumio volumio[1289]: Card sysdefault:1 'Headphones'/'bcm2835 Headphones' Feb 23 11:48:38 volumio volumio[1289]: amixer -c 2 info | grep "vc4-hdmi-0" Feb 23 11:48:38 volumio volumio[1289]: Card sysdefault:2 'vc4hdmi0'/'vc4-hdmi-0' Feb 23 11:48:38 volumio volumio[1289]: amixer -c 3 info | grep "vc4-hdmi-1" Feb 23 11:48:38 volumio volumio[1289]: Card sysdefault:3 'vc4hdmi1'/'vc4-hdmi-1' Feb 23 11:48:39 volumio volumio[1289]: info: MyVolumio status changed Feb 23 11:48:39 volumio volumio[1289]: info: Streaming services startup Feb 23 11:48:39 volumio volumio[1289]: info: Starting Streaming Daemon Feb 23 11:48:39 volumio sudo[1907]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 23 11:48:39 volumio sudo[1907]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 11:48:39 volumio volumio[1289]: info: Removing browser output: myVolumio user plan is not superstar Feb 23 11:48:39 volumio volumio[1289]: info: Removing audio output: Feb 23 11:48:39 volumio volumio[1289]: info: Stoppping Tunnel 1 Feb 23 11:48:39 volumio sudo[1907]: pam_unix(sudo:session): session closed for user root Feb 23 11:48:39 volumio wpa_supplicant[1251]: RRM: Ignoring radio measurement request: Not RRM network Feb 23 11:48:39 volumio sudo[1910]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Feb 23 11:48:39 volumio sudo[1910]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 11:48:39 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 23 11:48:39 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 23 11:48:39 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 23 11:48:39 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 23 11:48:39 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 23 11:48:39 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 23 11:48:39 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 23 11:48:39 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 23 11:48:39 volumio sudo[1910]: pam_unix(sudo:session): session closed for user root Feb 23 11:48:39 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 11:48:39 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 11:48:39 volumio volumio[1289]: info: Discovery: Getting this device information Feb 23 11:48:39 volumio volumio[1289]: info: CoreCommandRouter::volumioGetState Feb 23 11:48:39 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Feb 23 11:48:39 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 11:48:40 volumio volumio[1289]: info: Setting Geolocation for MyVolumio to eu4 Feb 23 11:48:40 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 11:48:40 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 11:48:40 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 11:48:40 volumio volumio[1289]: error: Cannot start Volumio Streaming Daemon Feb 23 11:48:40 volumio volumio[1289]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 23 11:48:40 volumio volumio[1289]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 23 11:48:40 volumio volumio[1289]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 5 Feb 23 11:48:40 volumio volumio[1289]: /bin/sh: 1: /usr/local/bin/alsacap: not found Feb 23 11:48:40 volumio volumio[1289]: {"cmd":"/usr/local/bin/alsacap -C 5","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 5\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Feb 23 11:48:40 volumio volumio[1289]: amixer -c 5 info | grep "SMSL USB AUDIO" Feb 23 11:48:40 volumio volumio[1289]: Card sysdefault:5 'AUDIO'/'SMSL SMSL USB AUDIO at usb-0000:01:00.0-1.2, high speed' Feb 23 11:48:40 volumio volumio[1289]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 5 Feb 23 11:48:40 volumio volumio[1289]: /bin/sh: 1: /usr/local/bin/alsacap: not found Feb 23 11:48:40 volumio volumio[1289]: {"cmd":"/usr/local/bin/alsacap -C 5","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 5\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Feb 23 11:48:40 volumio volumio[1289]: amixer -c 5 info | grep "SMSL USB AUDIO" Feb 23 11:48:40 volumio volumio[1289]: Card sysdefault:5 'AUDIO'/'SMSL SMSL USB AUDIO at usb-0000:01:00.0-1.2, high speed' Feb 23 11:48:40 volumio volumio5-onboarding[1581]: time=2026-02-23T11:48:40.337+01:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.1.70:57860 @ 0x2d191d0" selectedOutputId=5 Feb 23 11:48:40 volumio volumio[1289]: info: Remote SSH Stopped Feb 23 11:48:40 volumio volumio[1289]: info: Received Get System Info Feb 23 11:48:40 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 11:48:40 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 11:48:40 volumio volumio[1289]: info: Discovery: Getting this device information Feb 23 11:48:40 volumio volumio[1289]: info: CoreCommandRouter::volumioGetState Feb 23 11:48:40 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Feb 23 11:48:40 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 11:48:40 volumio volumio5-onboarding[1581]: time=2026-02-23T11:48:40.389+01:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.1.70:57860 @ 0x2d191d0" currentVersion=4.096 latestVersion=4.096 Feb 23 11:48:40 volumio volumio5-onboarding[1581]: time=2026-02-23T11:48:40.389+01:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.1.70:57860 @ 0x2d191d0" status=UPDATE_STATUS_NONE progress=0 Feb 23 11:48:40 volumio volumio5-onboarding[1581]: time=2026-02-23T11:48:40.390+01:00 level=INFO msg="emitting user changed event" component=server peer="192.168.1.70:57860 @ 0x2d191d0" userId= Feb 23 11:48:40 volumio volumio5-onboarding[1581]: time=2026-02-23T11:48:40.390+01:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.1.70:57860 @ 0x2d191d0" providers=3 Feb 23 11:48:40 volumio volumio[1289]: verbose: New Socket.io Connection to 192.168.1.71:3000 from 192.168.1.70 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Feb 23 11:48:40 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 23 11:48:40 volumio volumio[1289]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 23 11:48:40 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 23 11:48:40 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 23 11:48:40 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 23 11:48:40 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 23 11:48:41 volumio volumio[1289]: info: Updating MyVolumio device info Feb 23 11:48:41 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 11:48:41 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 11:48:41 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 11:48:41 volumio volumio[1289]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 23 11:48:42 volumio upmpdcli[1920]: writing RSA key Feb 23 11:48:42 volumio volumio5-onboarding[1581]: time=2026-02-23T11:48:42.378+01:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.1.70:57860 @ 0x2d191d0" plugins=60 Feb 23 11:48:42 volumio volumio[1289]: info: CoreCommandRouter::volumioGetState Feb 23 11:48:42 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Feb 23 11:48:42 volumio volumio5-onboarding[1581]: time=2026-02-23T11:48:42.403+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.70:57860 @ 0x2d191d0" state=STATUS_STOPPED positionMs=0 volume=100 Feb 23 11:48:42 volumio volumio5-onboarding[1581]: time=2026-02-23T11:48:42.404+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.70:57860 @ 0x2d191d0" id="mnt/USB/C20AD2A50AD295AF/Liked songs/We Rabbitz - 2017 - Evil Morty (For the Damaged Coda)/Amy Winehouse - 2003 - Frank (Deluxe Edition)/Amy Winehouse - Amy Amy Amy Outro.flac" title="Amy Amy Amy / Outro" Feb 23 11:48:42 volumio volumio5-onboarding[1581]: time=2026-02-23T11:48:42.472+01:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.1.70:57860 @ 0x2d191d0" latency=1.393416624s timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Feb 23 11:48:42 volumio wpa_supplicant[1251]: RRM: Ignoring radio measurement request: Not RRM network Feb 23 11:48:43 volumio sudo[1930]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 23 11:48:43 volumio sudo[1930]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 11:48:43 volumio sudo[1930]: pam_unix(sudo:session): session closed for user root Feb 23 11:48:43 volumio sudo[1928]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 23 11:48:43 volumio sudo[1928]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 11:48:43 volumio sudo[1928]: pam_unix(sudo:session): session closed for user root Feb 23 11:48:43 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 23 11:48:43 volumio volumio[1289]: verbose: New Socket.io Connection to 192.168.1.71 from 192.168.1.70 UA: Mozilla/5.0 (Linux; Android 15; SM-G991B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.132 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Feb 23 11:48:44 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 23 11:48:44 volumio volumio[1289]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 23 11:48:44 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 23 11:48:44 volumio volumio[1289]: info: MYVOLUMIO: Adding device Feb 23 11:48:44 volumio volumio[1289]: info: MYVOLUMIO: Evaluating Server Feb 23 11:48:44 volumio volumio[1289]: info: CoreCommandRouter::volumioGetState Feb 23 11:48:44 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Feb 23 11:48:44 volumio wpa_supplicant[1251]: RRM: Ignoring radio measurement request: Not RRM network Feb 23 11:48:45 volumio sudo[1953]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 23 11:48:45 volumio sudo[1953]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 11:48:45 volumio sudo[1954]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 23 11:48:45 volumio sudo[1954]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 11:48:45 volumio sudo[1954]: pam_unix(sudo:session): session closed for user root Feb 23 11:48:45 volumio sudo[1953]: pam_unix(sudo:session): session closed for user root Feb 23 11:48:45 volumio volumio[1289]: verbose: New Socket.io Connection to 192.168.1.71 from 192.168.1.70 UA: Mozilla/5.0 (Linux; Android 15; SM-G991B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.132 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Feb 23 11:48:45 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 23 11:48:46 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 23 11:48:46 volumio volumio[1289]: info: CoreCommandRouter::volumioGetVisibleSources Feb 23 11:48:46 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 11:48:46 volumio volumio[1289]: info: CoreCommandRouter::volumioGetState Feb 23 11:48:46 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Feb 23 11:48:46 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 23 11:48:46 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 23 11:48:46 volumio volumio[1289]: info: Received Get System Info Feb 23 11:48:46 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 11:48:46 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 11:48:46 volumio volumio[1289]: info: Discovery: Getting this device information Feb 23 11:48:46 volumio volumio[1289]: info: CoreCommandRouter::volumioGetState Feb 23 11:48:46 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Feb 23 11:48:46 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 11:48:46 volumio volumio[1289]: info: CoreCommandRouter::volumioGetState Feb 23 11:48:46 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Feb 23 11:48:46 volumio volumio[1289]: info: Listing playlists Feb 23 11:48:46 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 23 11:48:46 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 23 11:48:46 volumio wpa_supplicant[1251]: RRM: Ignoring radio measurement request: Not RRM network Feb 23 11:48:46 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 23 11:48:46 volumio volumio[1289]: info: Received Get System Info Feb 23 11:48:46 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 11:48:46 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 11:48:46 volumio volumio[1289]: info: Discovery: Getting this device information Feb 23 11:48:46 volumio volumio[1289]: info: CoreCommandRouter::volumioGetState Feb 23 11:48:46 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Feb 23 11:48:46 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 11:48:47 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 23 11:48:48 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 23 11:48:48 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 11:48:48 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 11:48:48 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 23 11:48:48 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 23 11:48:48 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 23 11:48:48 volumio volumio[1289]: info: CoreCommandRouter::volumioGetBrowseSources Feb 23 11:48:48 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 11:48:49 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 23 11:48:49 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 23 11:48:49 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 23 11:48:49 volumio volumio[1289]: info: Received Get System Info Feb 23 11:48:49 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 11:48:49 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 11:48:49 volumio volumio[1289]: info: Discovery: Getting this device information Feb 23 11:48:49 volumio volumio[1289]: info: CoreCommandRouter::volumioGetState Feb 23 11:48:49 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Feb 23 11:48:49 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 11:48:49 volumio wpa_supplicant[1251]: RRM: Ignoring radio measurement request: Not RRM network Feb 23 11:48:50 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 23 11:48:50 volumio volumio[1289]: info: Received Get System Info Feb 23 11:48:50 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 11:48:50 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 11:48:50 volumio volumio[1289]: info: Discovery: Getting this device information Feb 23 11:48:50 volumio volumio[1289]: info: CoreCommandRouter::volumioGetState Feb 23 11:48:50 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Feb 23 11:48:50 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 11:48:50 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 23 11:48:50 volumio volumio[1289]: info: Received Get System Info Feb 23 11:48:50 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 11:48:50 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 11:48:50 volumio volumio[1289]: info: Discovery: Getting this device information Feb 23 11:48:50 volumio volumio[1289]: info: CoreCommandRouter::volumioGetState Feb 23 11:48:50 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Feb 23 11:48:50 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 11:48:51 volumio wpa_supplicant[1251]: RRM: Ignoring radio measurement request: Not RRM network Feb 23 11:48:52 volumio volumio[1289]: info: Setting Geolocation for MyVolumio to eu4 Feb 23 11:48:52 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 11:48:52 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 11:48:52 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 11:48:53 volumio volumio[1289]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 23 11:48:53 volumio wpa_supplicant[1251]: RRM: Ignoring radio measurement request: Not RRM network Feb 23 11:48:53 volumio volumio[1289]: info: Updating MyVolumio device info Feb 23 11:48:53 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 11:48:53 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 11:48:53 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 11:48:54 volumio volumio[1289]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 23 11:48:54 volumio volumio[1289]: error: MyVolumio Plugin failed to authenticate in a timely fashion Feb 23 11:48:54 volumio volumio[1289]: info: Completed starting MyVolumio Plugin Feb 23 11:48:54 volumio volumio[1289]: [Metrics] CommandRouter: 61s 560.05ms Feb 23 11:48:54 volumio volumio[1289]: info: CoreCommandRouter::volumiosetStartupVolume Feb 23 11:48:54 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 11:48:54 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 11:48:54 volumio volumio[1289]: info: CoreCommandRouter::Close All Modals sent Feb 23 11:48:54 volumio volumio[1289]: info: CoreCommandRouter::Close All Modals sent Feb 23 11:48:55 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 23 11:48:55 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 23 11:48:55 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 23 11:48:55 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 23 11:48:57 volumio wpa_supplicant[1251]: RRM: Ignoring radio measurement request: Not RRM network Feb 23 11:48:59 volumio wpa_supplicant[1251]: RRM: Ignoring radio measurement request: Not RRM network Feb 23 11:48:59 volumio volumio[1289]: info: Enabling plugin touch_display Feb 23 11:48:59 volumio volumio[1289]: info: Loading plugin "touch_display"... Feb 23 11:48:59 volumio volumio[1289]: info: Applying required configuration parameters for plugin touch_display Feb 23 11:48:59 volumio volumio[1289]: info: PLUGIN START: touch_display Feb 23 11:48:59 volumio volumio[1289]: info: Loading i18n strings for locale en Feb 23 11:48:59 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 11:48:59 volumio sudo[1992]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /data/volumiokioskextensions/IframeKeyboardBridge Feb 23 11:48:59 volumio sudo[1996]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Feb 23 11:48:59 volumio sudo[1996]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 11:48:59 volumio volumio[1289]: info: touch_display: No backlight interface detected. Feb 23 11:48:59 volumio kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event for disabled endpoint slot 5 ep 1 Feb 23 11:48:59 volumio kernel: xhci_hcd 0000:01:00.0: @0000000428d10450 00000000 00000000 0e000000 05028000 Feb 23 11:48:59 volumio sudo[1992]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 11:48:59 volumio sudo[1992]: pam_unix(sudo:session): session closed for user root Feb 23 11:48:59 volumio sudo[1994]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Feb 23 11:48:59 volumio sudo[1994]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 11:48:59 volumio sudo[2000]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Feb 23 11:48:59 volumio sudo[2000]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 11:49:00 volumio sudo[1994]: pam_unix(sudo:session): session closed for user root Feb 23 11:49:00 volumio systemd[1]: Reloading. Feb 23 11:49:00 volumio volumio[1289]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Feb 23 11:49:00 volumio volumio[1289]: info: touch_display: systemctl stop getty@tty1.service succeeded. Feb 23 11:49:00 volumio sudo[2006]: 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/ Feb 23 11:49:00 volumio sudo[2006]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 11:49:00 volumio sudo[2006]: pam_unix(sudo:session): session closed for user root Feb 23 11:49:00 volumio volumio[1289]: info: touch_display: IframeKeyboardBridge extension installed successfully Feb 23 11:49:00 volumio sudo[2008]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Feb 23 11:49:00 volumio sudo[2008]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 11:49:00 volumio sudo[2008]: pam_unix(sudo:session): session closed for user root Feb 23 11:49:00 volumio volumio[1289]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Feb 23 11:49:01 volumio wpa_supplicant[1251]: RRM: Ignoring radio measurement request: Not RRM network Feb 23 11:49:01 volumio systemd[1]: Reloading. Feb 23 11:49:01 volumio sudo[2000]: pam_unix(sudo:session): session closed for user root Feb 23 11:49:01 volumio volumio[1289]: info: touch_display: systemctl daemon-reload succeeded. Feb 23 11:49:01 volumio sudo[2029]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Feb 23 11:49:01 volumio sudo[2029]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 11:49:01 volumio volumio[1289]: info: BOOT COMPLETED Feb 23 11:49:02 volumio sudo[1996]: pam_unix(sudo:session): session closed for user root Feb 23 11:49:02 volumio sudo[2029]: pam_unix(sudo:session): session closed for user root Feb 23 11:49:02 volumio volumio[1289]: info: touch_display: systemctl disable getty@tty1.service succeeded. Feb 23 11:49:02 volumio volumio[1289]: error: touch_display: Failed to start volumio-kiosk.service: Error: Command failed: /usr/bin/sudo /bin/systemctl start volumio-kiosk.service Feb 23 11:49:02 volumio volumio[1289]: Failed to start volumio-kiosk.service: Unit volumio-kiosk.service not found. Feb 23 11:49:02 volumio volumio[1289]: info: Error: Error Feb 23 11:49:03 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 23 11:49:03 volumio volumio[1289]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 23 11:49:03 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 23 11:49:03 volumio volumio[1289]: info: Received Get System Version Feb 23 11:49:03 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 11:49:03 volumio volumio[1289]: info: Received Get System Info Feb 23 11:49:03 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 11:49:03 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 11:49:03 volumio volumio[1289]: info: Discovery: Getting this device information Feb 23 11:49:03 volumio volumio[1289]: info: CoreCommandRouter::volumioGetState Feb 23 11:49:03 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Feb 23 11:49:03 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 11:49:04 volumio wpa_supplicant[1251]: RRM: Ignoring radio measurement request: Not RRM network Feb 23 11:49:04 volumio volumio[1289]: info: Starting Uninstall of plugin system_hardware - pi_screen_setup Feb 23 11:49:04 volumio volumio[1289]: info: Uninstalling plugin pi_screen_setup Feb 23 11:49:04 volumio volumio5-onboarding[1581]: time=2026-02-23T11:49:04.565+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 23 11:49:04 volumio volumio[1289]: info: Disabling plugin pi_screen_setup Feb 23 11:49:04 volumio volumio5-onboarding[1581]: time=2026-02-23T11:49:04.608+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 23 11:49:04 volumio volumio[1289]: info: Checking if uninstall.sh is present Feb 23 11:49:04 volumio volumio[1289]: info: Executing uninstall.sh Feb 23 11:49:04 volumio sudo[2050]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/system_hardware/pi_screen_setup/uninstall.sh Feb 23 11:49:04 volumio sudo[2050]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 11:49:05 volumio sudo[2056]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/cp /data/plugins/system_hardware/pi_screen_setup/backups/factory/config.txt /boot/config.txt Feb 23 11:49:05 volumio sudo[2056]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 23 11:49:05 volumio sudo[2056]: pam_unix(sudo:session): session closed for user root Feb 23 11:49:05 volumio sudo[2058]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/chmod 644 /boot/config.txt Feb 23 11:49:05 volumio sudo[2058]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 23 11:49:05 volumio sudo[2058]: pam_unix(sudo:session): session closed for user root Feb 23 11:49:05 volumio sudo[2060]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/cp /data/plugins/system_hardware/pi_screen_setup/backups/factory/cmdline.txt /boot/cmdline.txt Feb 23 11:49:05 volumio sudo[2060]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 23 11:49:05 volumio sudo[2060]: pam_unix(sudo:session): session closed for user root Feb 23 11:49:05 volumio sudo[2062]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/chmod 644 /boot/cmdline.txt Feb 23 11:49:05 volumio sudo[2062]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 23 11:49:05 volumio sudo[2062]: pam_unix(sudo:session): session closed for user root Feb 23 11:49:05 volumio sudo[2064]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/cp /data/plugins/system_hardware/pi_screen_setup/backups/factory/volumioconfig.txt /boot/volumioconfig.txt Feb 23 11:49:05 volumio sudo[2064]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 23 11:49:05 volumio sudo[2064]: pam_unix(sudo:session): session closed for user root Feb 23 11:49:05 volumio sudo[2066]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/chmod 644 /boot/volumioconfig.txt Feb 23 11:49:05 volumio sudo[2066]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 23 11:49:05 volumio sudo[2066]: pam_unix(sudo:session): session closed for user root Feb 23 11:49:05 volumio sudo[2068]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/cp /data/plugins/system_hardware/pi_screen_setup/backups/factory/userconfig.txt /boot/userconfig.txt Feb 23 11:49:05 volumio sudo[2068]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 23 11:49:05 volumio sudo[2068]: pam_unix(sudo:session): session closed for user root Feb 23 11:49:05 volumio sudo[2070]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/chmod 644 /boot/userconfig.txt Feb 23 11:49:05 volumio sudo[2070]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 23 11:49:05 volumio sudo[2070]: pam_unix(sudo:session): session closed for user root Feb 23 11:49:05 volumio sudo[2072]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/rm -f /boot/videoconfig.txt Feb 23 11:49:05 volumio sudo[2072]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 23 11:49:05 volumio sudo[2072]: pam_unix(sudo:session): session closed for user root Feb 23 11:49:05 volumio sudo[2074]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/rm -f /etc/sudoers.d/volumio-user-pi_screen_setup Feb 23 11:49:05 volumio sudo[2074]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 23 11:49:06 volumio sudo[2074]: pam_unix(sudo:session): session closed for user root Feb 23 11:49:06 volumio wpa_supplicant[1251]: RRM: Ignoring radio measurement request: Not RRM network Feb 23 11:49:06 volumio sudo[2050]: pam_unix(sudo:session): session closed for user root Feb 23 11:49:06 volumio volumio[1289]: info: Uninstall script completed Feb 23 11:49:06 volumio volumio5-onboarding[1581]: time=2026-02-23T11:49:06.510+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 23 11:49:06 volumio volumio[1289]: info: Removing plugin pi_screen_setup from configuration Feb 23 11:49:06 volumio volumio[1289]: info: Successfully removed pi_screen_setup configuration files Feb 23 11:49:06 volumio volumio5-onboarding[1581]: time=2026-02-23T11:49:06.788+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 23 11:49:06 volumio volumio[1289]: info: Plugin folders cleanup Feb 23 11:49:06 volumio volumio[1289]: info: Scanning into folder /volumio/app/plugins/ Feb 23 11:49:06 volumio volumio[1289]: info: Scanning category audio_interface Feb 23 11:49:06 volumio volumio[1289]: info: Scanning category miscellanea Feb 23 11:49:06 volumio volumio[1289]: info: Scanning category music_service Feb 23 11:49:06 volumio volumio[1289]: info: Scanning category plugins.json Feb 23 11:49:06 volumio volumio[1289]: info: Scanning category system_controller Feb 23 11:49:06 volumio volumio[1289]: info: Scanning category user_interface Feb 23 11:49:06 volumio volumio[1289]: info: Scanning into folder /data/plugins/ Feb 23 11:49:06 volumio volumio[1289]: info: Scanning category system_hardware Feb 23 11:49:06 volumio volumio[1289]: info: Scanning category user_interface Feb 23 11:49:06 volumio volumio[1289]: info: Plugin folders cleanup completed Feb 23 11:49:06 volumio volumio5-onboarding[1581]: time=2026-02-23T11:49:06.852+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 23 11:49:06 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 23 11:49:06 volumio volumio[1289]: info: [{"prettyName":"Touch Display","name":"touch_display","category":"user_interface","version":"3.6.0","icon":"fa-hand-pointer-o","isManuallyInstalled":false,"enabled":true,"active":true}] Feb 23 11:49:06 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 23 11:49:08 volumio wpa_supplicant[1251]: RRM: Ignoring radio measurement request: Not RRM network Feb 23 11:49:11 volumio wpa_supplicant[1251]: RRM: Ignoring radio measurement request: Not RRM network Feb 23 11:49:12 volumio sudo[2100]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start getty@tty1.service Feb 23 11:49:12 volumio sudo[2100]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 11:49:13 volumio sudo[2098]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Feb 23 11:49:13 volumio sudo[2098]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 11:49:13 volumio systemd[1]: Created slice system-getty.slice - Slice /system/getty. Feb 23 11:49:13 volumio sudo[2098]: pam_unix(sudo:session): session closed for user root Feb 23 11:49:13 volumio volumio[1289]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Feb 23 11:49:13 volumio volumio[1289]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 11:49:13 volumio sudo[2105]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service Feb 23 11:49:13 volumio sudo[2105]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 11:49:13 volumio sudo[2102]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable getty@tty1.service Feb 23 11:49:13 volumio sudo[2102]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 11:49:13 volumio volumio[1289]: TypeError: Cannot read properties of undefined (reading 'split') Feb 23 11:49:13 volumio volumio[1289]: at /data/plugins/user_interface/touch_display/index.js:1298:37 Feb 23 11:49:13 volumio volumio[1289]: at ChildProcess.exithandler (node:child_process:413:7) Feb 23 11:49:13 volumio volumio[1289]: at ChildProcess.emit (node:events:514:28) Feb 23 11:49:13 volumio volumio[1289]: at maybeClose (node:internal/child_process:1105:16) Feb 23 11:49:13 volumio volumio[1289]: at ChildProcess._handle.onexit (node:internal/child_process:305:5) Feb 23 11:49:13 volumio volumio[1289]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 11:49:13 volumio systemd[1]: Started getty@tty1.service - Getty on tty1. Feb 23 11:49:13 volumio sudo[2100]: pam_unix(sudo:session): session closed for user root Feb 23 11:49:13 volumio sudo[2105]: pam_unix(sudo:session): session closed for user root Feb 23 11:49:13 volumio systemd[1]: Reloading. Feb 23 11:49:13 volumio wpa_supplicant[1251]: RRM: Ignoring radio measurement request: Not RRM network Feb 23 11:49:14 volumio sudo[2102]: pam_unix(sudo:session): session closed for user root Feb 23 11:49:15 volumio wpa_supplicant[1251]: RRM: Ignoring radio measurement request: Not RRM network Feb 23 11:49:16 volumio sudo[2141]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-23 11:48' Feb 23 11:49:16 volumio sudo[2141]: 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="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"