Apr 12 18:56:20 vlounge ntpd[1067]: CLOCK: time stepped by 40.828348 Apr 12 18:56:20 vlounge ntpd[1067]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Apr 12 18:56:25 vlounge volumio[1509]: info: Starting UPNP Browser Apr 12 18:56:25 vlounge volumio[1509]: info: Loading plugin "alarm-clock"... Apr 12 18:56:26 vlounge volumio[1509]: info: Loading plugin "airplay_emulation"... Apr 12 18:56:26 vlounge volumio[1509]: info: Starting Shairport Sync Apr 12 18:56:26 vlounge volumio[1509]: info: Loading plugin "last_100"... Apr 12 18:56:26 vlounge volumio[1509]: info: Loading plugin "webradio"... Apr 12 18:56:26 vlounge volumio[1509]: info: Loading plugin "i2s_dacs"... Apr 12 18:56:26 vlounge volumio[1509]: info: I2S DAC not set, start Auto-detection Apr 12 18:56:26 vlounge volumio[1509]: info: Loading plugin "volumiodiscovery"... Apr 12 18:56:26 vlounge volumio[1509]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 12 18:56:26 vlounge node[1509]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 12 18:56:26 vlounge volumio[1509]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 12 18:56:26 vlounge node[1509]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 12 18:56:26 vlounge volumio[1509]: *** WARNING *** For more information see Apr 12 18:56:26 vlounge volumio[1509]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 12 18:56:26 vlounge volumio[1509]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 12 18:56:26 vlounge volumio[1509]: *** WARNING *** For more information see Apr 12 18:56:26 vlounge node[1509]: *** WARNING *** For more information see Apr 12 18:56:26 vlounge node[1509]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 12 18:56:26 vlounge node[1509]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 12 18:56:26 vlounge node[1509]: *** WARNING *** For more information see Apr 12 18:56:26 vlounge volumio[1509]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 12 18:56:26 vlounge volumio[1509]: info: Discovery: Started advertising with name: vlounge Apr 12 18:56:26 vlounge volumio[1509]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 12 18:56:26 vlounge volumio[1509]: info: Loading plugin "squeezelite_mc"... Apr 12 18:56:26 vlounge systemd[1]: systemd-fsckd.service: Deactivated successfully. Apr 12 18:56:27 vlounge volumio-remote-updater[791]: [2026-04-12 18:56:27] [connect] Successful connection Apr 12 18:56:27 vlounge volumio[1509]: info: Loading plugin "now_playing"... Apr 12 18:56:30 vlounge systemd[1]: systemd-hostnamed.service: Deactivated successfully. Apr 12 18:56:30 vlounge volumio[1509]: info: Loading plugin "outputs"... Apr 12 18:56:30 vlounge volumio[1509]: info: Loading plugin "albumart"... Apr 12 18:56:30 vlounge volumio[1509]: info: Plugin example_plugin is not enabled Apr 12 18:56:30 vlounge volumio[1509]: info: Loading plugin "inputs"... Apr 12 18:56:30 vlounge volumio[1509]: info: Loading plugin "updater_comm"... Apr 12 18:56:30 vlounge volumio[1509]: info: Plugin mpdemulation is not enabled Apr 12 18:56:30 vlounge volumio[1509]: info: Loading plugin "rest_api"... Apr 12 18:56:30 vlounge volumio[1509]: info: Loading plugin "websocket"... Apr 12 18:56:30 vlounge volumio[1509]: info: Starting Socket.io Server version 1.7.4 Apr 12 18:56:30 vlounge volumio[1509]: info: Plugin fusiondsp is not enabled Apr 12 18:56:30 vlounge volumio[1509]: info: Loading plugin "touch_display"... Apr 12 18:56:30 vlounge systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Apr 12 18:56:30 vlounge systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Apr 12 18:56:30 vlounge volumio[1509]: info: Applying required configuration parameters for plugin touch_display Apr 12 18:56:30 vlounge systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Apr 12 18:56:31 vlounge upmpdcli[1755]: Could not open config: /tmp/upmpdcli.conf Apr 12 18:56:31 vlounge systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Apr 12 18:56:31 vlounge systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Apr 12 18:56:31 vlounge volumio[1744]: Forking 3 albumart workers Apr 12 18:56:31 vlounge volumio[1509]: info: Loading i18n strings for locale en Apr 12 18:56:31 vlounge volumio[1509]: Updating browse sources language Apr 12 18:56:31 vlounge volumio[1509]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 12 18:56:32 vlounge volumio[1509]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 18:56:32 vlounge volumio[1509]: info: CoreCommandRouter::initPlayerControls Apr 12 18:56:32 vlounge volumio[1509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:56:32 vlounge volumio[1509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:56:32 vlounge volumio[1509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:56:32 vlounge volumio[1509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:56:32 vlounge volumio[1509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:56:32 vlounge volumio[1509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:56:32 vlounge volumio[1509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:56:32 vlounge volumio[1509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:56:32 vlounge volumio[1509]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 18:56:32 vlounge volumio[1509]: Express server listening on port 3000 Apr 12 18:56:32 vlounge volumio[1509]: [Metrics] WebUI: 21s 350.53ms Apr 12 18:56:32 vlounge volumio[1509]: info: CoreStateMachine::resetVolumioState Apr 12 18:56:32 vlounge volumio[1509]: info: CoreStateMachine::getcurrentVolume Apr 12 18:56:32 vlounge volumio[1509]: info: CoreCommandRouter::volumioRetrievevolume Apr 12 18:56:32 vlounge volumio[1509]: info: CoreStateMachine::pushState Apr 12 18:56:32 vlounge volumio[1509]: info: CorePlayQueue::getTrack 0 Apr 12 18:56:32 vlounge volumio[1509]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 18:56:32 vlounge volumio[1509]: info: CoreCommandRouter::volumioPushState Apr 12 18:56:32 vlounge volumio[1509]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 18:56:33 vlounge sudo[1790]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 12 18:56:33 vlounge sudo[1790]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 18:56:33 vlounge sudo[1792]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 12 18:56:33 vlounge sudo[1790]: pam_unix(sudo:session): session closed for user root Apr 12 18:56:33 vlounge sudo[1792]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 18:56:33 vlounge sudo[1792]: pam_unix(sudo:session): session closed for user root Apr 12 18:56:33 vlounge volumio[1509]: info: Volumio Network Manager: Network status updated: 1 Apr 12 18:56:33 vlounge volumio[1509]: verbose: New Socket.io Connection to 192.168.111.177 from 192.168.111.197 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/147.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Apr 12 18:56:33 vlounge volumio[1509]: info: Reloading queue from file Apr 12 18:56:34 vlounge volumio[1509]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Apr 12 18:56:34 vlounge volumio[1509]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03114 Apr 12 18:56:34 vlounge volumio[1509]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Apr 12 18:56:34 vlounge volumio[1509]: info: CoreStateMachine::setRepeat null single undefined Apr 12 18:56:34 vlounge volumio[1509]: info: CoreStateMachine::pushState Apr 12 18:56:34 vlounge volumio[1509]: info: CorePlayQueue::getTrack 0 Apr 12 18:56:34 vlounge volumio[1509]: info: CoreCommandRouter::volumioPushState Apr 12 18:56:34 vlounge volumio[1509]: info: CoreStateMachine::setRandom null Apr 12 18:56:34 vlounge volumio[1509]: info: CoreStateMachine::pushState Apr 12 18:56:34 vlounge volumio[1509]: info: CorePlayQueue::getTrack 0 Apr 12 18:56:34 vlounge volumio[1509]: info: CoreCommandRouter::volumioPushState Apr 12 18:56:34 vlounge volumio[1509]: info: Setting Device type: Raspberry PI Apr 12 18:56:34 vlounge volumio[1509]: info: CoreCommandRouter::volumioGetState Apr 12 18:56:34 vlounge volumio[1509]: info: CorePlayQueue::getTrack 0 Apr 12 18:56:34 vlounge volumio[1509]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Apr 12 18:56:34 vlounge sudo[1805]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 12 18:56:34 vlounge volumio[1509]: info: Listing playlists Apr 12 18:56:34 vlounge sudo[1805]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 18:56:34 vlounge volumio[1509]: info: Listing playlists Apr 12 18:56:34 vlounge volumio[1509]: info: Discovery: adding 1bfe0610-c7c5-467b-b590-83705e4f1102 Apr 12 18:56:34 vlounge volumio[1509]: info: Discovery: Found device vlounge Apr 12 18:56:34 vlounge volumio[1509]: info: CoreCommandRouter::volumioGetState Apr 12 18:56:34 vlounge volumio[1509]: info: CorePlayQueue::getTrack 0 Apr 12 18:56:34 vlounge volumio[1509]: info: Discovery: this is already registered, 1bfe0610-c7c5-467b-b590-83705e4f1102 Apr 12 18:56:34 vlounge volumio[1509]: info: Discovery: Found device vlounge Apr 12 18:56:34 vlounge volumio[1509]: info: CoreCommandRouter::volumioGetState Apr 12 18:56:34 vlounge volumio[1509]: info: CorePlayQueue::getTrack 0 Apr 12 18:56:34 vlounge systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Apr 12 18:56:35 vlounge volumio[1509]: info: Completed loading Core Plugins Apr 12 18:56:35 vlounge volumio[1509]: info: Preparing to generate the ALSA configuration file Apr 12 18:56:35 vlounge volumio[1509]: info: Asound.conf file unchanged, so no further update is needed Apr 12 18:56:35 vlounge volumio[1509]: info: Output device has changed, restarting MPD Apr 12 18:56:35 vlounge volumio[1509]: info: Output device has changed, restarting Shairport Sync Apr 12 18:56:35 vlounge volumio[1509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:56:35 vlounge volumio[1509]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 18:56:35 vlounge sudo[1825]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 12 18:56:35 vlounge sudo[1826]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 12 18:56:35 vlounge sudo[1826]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 18:56:35 vlounge sudo[1825]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 18:56:35 vlounge sudo[1826]: pam_unix(sudo:session): session closed for user root Apr 12 18:56:35 vlounge volumio[1509]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 12 18:56:35 vlounge volumio[1509]: info: ___________ START PLUGINS ___________ Apr 12 18:56:35 vlounge volumio[1509]: info: ControllerMpd::onStart: Initializing MPD Apr 12 18:56:35 vlounge volumio[1509]: info: Creating MPD Configuration file Apr 12 18:56:36 vlounge systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 12 18:56:36 vlounge systemd[1]: Starting mpd.service - Music Player Daemon... Apr 12 18:56:36 vlounge volumio[1509]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 18:56:36 vlounge volumio[1509]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 12 18:56:36 vlounge sudo[1842]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 12 18:56:36 vlounge sudo[1838]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 12 18:56:36 vlounge sudo[1842]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 18:56:36 vlounge sudo[1838]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 18:56:36 vlounge volumio[1509]: info: [1776016596193] CoreMusicLibrary::Adding element Media Servers Apr 12 18:56:36 vlounge volumio[1509]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 12 18:56:36 vlounge volumio[1509]: info: UPNP Browser: Client initialized successfully Apr 12 18:56:36 vlounge sudo[1843]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 12 18:56:36 vlounge sudo[1843]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 12 18:56:36 vlounge sudo[1846]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Apr 12 18:56:36 vlounge sudo[1843]: pam_unix(sudo:session): session closed for user root Apr 12 18:56:36 vlounge sudo[1838]: pam_unix(sudo:session): session closed for user root Apr 12 18:56:36 vlounge volumio[1509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:56:36 vlounge volumio[1509]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 18:56:36 vlounge systemd[1]: setdatetime-helper.service: Deactivated successfully. Apr 12 18:56:36 vlounge systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Apr 12 18:56:36 vlounge systemd[1]: mpd.service: Deactivated successfully. Apr 12 18:56:36 vlounge systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 12 18:56:36 vlounge systemd[1]: mpd.socket: Deactivated successfully. Apr 12 18:56:36 vlounge systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 12 18:56:36 vlounge systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 12 18:56:36 vlounge systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 12 18:56:36 vlounge systemd[1]: Starting mpd.service - Music Player Daemon... Apr 12 18:56:36 vlounge volumio[1509]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 12 18:56:36 vlounge volumio[1509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:56:36 vlounge volumio[1509]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 18:56:36 vlounge volumio[1509]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 12 18:56:36 vlounge volumio[1509]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 12 18:56:36 vlounge sudo[1857]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 12 18:56:36 vlounge sudo[1862]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Apr 12 18:56:36 vlounge sudo[1857]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 12 18:56:36 vlounge sudo[1857]: pam_unix(sudo:session): session closed for user root Apr 12 18:56:36 vlounge volumio[1509]: info: [1776016596756] CoreMusicLibrary::Adding element Last_100 Apr 12 18:56:36 vlounge volumio[1509]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 12 18:56:36 vlounge volumio[1509]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 12 18:56:36 vlounge volumio[1509]: info: [1776016596785] CoreMusicLibrary::Adding element Webradio Apr 12 18:56:36 vlounge volumio[1509]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 12 18:56:36 vlounge volumio[1509]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 12 18:56:36 vlounge volumio[1509]: info: Initializing BBC Radios Apr 12 18:56:37 vlounge volumio[1509]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 12 18:56:37 vlounge volumio[1509]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 18:56:37 vlounge volumio[1509]: info: [squeezelite_mc] Starting proxy server... Apr 12 18:56:37 vlounge volumio[1756]: Starting albumart workers Apr 12 18:56:37 vlounge volumio[1758]: Starting albumart workers Apr 12 18:56:37 vlounge volumio[1509]: info: [now-playing] ConfigUpdater: config is up to date. Apr 12 18:56:37 vlounge volumio[1509]: info: Loading i18n strings for locale en Apr 12 18:56:37 vlounge volumio[1757]: Starting albumart workers Apr 12 18:56:37 vlounge volumio[1509]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 12 18:56:37 vlounge volumio[1509]: info: Volumio Calling Home Apr 12 18:56:37 vlounge sudo[1893]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /data/volumiokioskextensions/IframeKeyboardBridge Apr 12 18:56:37 vlounge sudo[1893]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 18:56:37 vlounge sudo[1893]: pam_unix(sudo:session): session closed for user root Apr 12 18:56:38 vlounge volumio[1509]: info: [squeezelite_mc] Proxy server started on port 45677 Apr 12 18:56:38 vlounge sudo[1899]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Apr 12 18:56:38 vlounge sudo[1899]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 18:56:38 vlounge sudo[1901]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Apr 12 18:56:38 vlounge sudo[1901]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 18:56:38 vlounge systemd[1]: Reloading. Apr 12 18:56:38 vlounge sudo[1903]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Apr 12 18:56:38 vlounge sudo[1903]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 18:56:39 vlounge systemd[1]: Reloading. Apr 12 18:56:40 vlounge sudo[1901]: pam_unix(sudo:session): session closed for user root Apr 12 18:56:42 vlounge volumio-remote-updater[791]: [2026-04-12 18:56:42] [connect] Successful connection Apr 12 18:56:43 vlounge sudo[1903]: pam_unix(sudo:session): session closed for user root Apr 12 18:56:43 vlounge volumio[1509]: info: [now-playing] Fetching API key... Apr 12 18:56:43 vlounge sudo[1899]: pam_unix(sudo:session): session closed for user root Apr 12 18:56:43 vlounge volumio[1509]: info: CoreCommandRouter::volumioRetrievevolume Apr 12 18:56:43 vlounge volumio[1509]: info: [now-playing] App is listening on port 4004. Apr 12 18:56:43 vlounge volumio[1509]: info: CoreStateMachine::pushState Apr 12 18:56:43 vlounge volumio[1509]: info: CorePlayQueue::getTrack 0 Apr 12 18:56:43 vlounge volumio[1509]: info: CoreCommandRouter::volumioPushState Apr 12 18:56:45 vlounge volumio[1509]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Apr 12 18:56:45 vlounge volumio[1509]: info: [squeezelite_mc] Server discovery started Apr 12 18:56:45 vlounge volumio[1509]: info: [squeezelite_mc] Player finder started Apr 12 18:56:45 vlounge volumio[1509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:56:45 vlounge volumio[1509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:56:45 vlounge volumio[1509]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 18:56:45 vlounge volumio[1509]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Apr 12 18:56:45 vlounge volumio[1509]: info: touch_display: No backlight interface detected. Apr 12 18:56:45 vlounge volumio[1509]: info: touch_display: systemctl daemon-reload succeeded. Apr 12 18:56:45 vlounge volumio[1509]: info: touch_display: systemctl disable getty@tty1.service succeeded. Apr 12 18:56:45 vlounge volumio[1509]: info: touch_display: systemctl stop getty@tty1.service succeeded. Apr 12 18:56:45 vlounge volumio[1509]: info: MPD Permissions set Apr 12 18:56:45 vlounge volumio[1509]: info: MPD Permissions set Apr 12 18:56:45 vlounge sudo[1962]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Apr 12 18:56:45 vlounge sudo[1962]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 18:56:45 vlounge systemd[1]: Started volumio-kiosk.service - Volumio Kiosk. Apr 12 18:56:45 vlounge volumio-remote-updater[791]: [2026-04-12 18:56:45] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1776016602 101 Apr 12 18:56:45 vlounge sudo[1962]: pam_unix(sudo:session): session closed for user root Apr 12 18:56:45 vlounge volumio[1509]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 3 Apr 12 18:56:45 vlounge volumio[1509]: info: [squeezelite_mc] Server discovered: {"ip":"192.168.111.10","name":"Lyrion Music Server (Docker)","ver":"9.1.0","uuid":"6f13eb49-8cc3-46b9-b306-73c39b4aaef2","jsonPort":"9000","cliPort":"9090"} Apr 12 18:56:46 vlounge sudo[1965]: 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/ Apr 12 18:56:46 vlounge sudo[1965]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 18:56:46 vlounge systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Apr 12 18:56:46 vlounge systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Apr 12 18:56:46 vlounge systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Apr 12 18:56:46 vlounge sudo[1805]: pam_unix(sudo:session): session closed for user root Apr 12 18:56:46 vlounge sudo[1965]: pam_unix(sudo:session): session closed for user root Apr 12 18:56:46 vlounge startx[1997]: X.Org X Server 1.21.1.7 Apr 12 18:56:46 vlounge startx[1997]: X Protocol Version 11, Revision 0 Apr 12 18:56:46 vlounge startx[1997]: Current Operating System: Linux vlounge 6.12.47-v7l+ #1904 SMP Mon Sep 15 13:08:10 BST 2025 armv7l Apr 12 18:56:46 vlounge startx[1997]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory numa_policy=interleave nvme.max_host_mem_size_mb=0 snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_hdmi=0 video=HDMI-A-1:3840x2160M@30D video=HDMI-A-2:1920x1080M@60 numa=fake=2 system_heap.max_order=0 smsc95xx.macaddr=DC:A6:32:E2:2F:3D 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=dafa3844-b779-48cd-9b4c-01ecfd09e0f4 imgfile=/volumio_current.sqsh bootpart=UUID=3B89-0B23 datapart=UUID=752d19ad-b702-471d-847a-f79ae83515d0 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 Apr 12 18:56:46 vlounge startx[1997]: xorg-server 2:21.1.7-3+rpt3+deb12u11 (https://www.debian.org/support) Apr 12 18:56:46 vlounge startx[1997]: Current version of pixman: 0.44.0 Apr 12 18:56:46 vlounge startx[1997]: Before reporting problems, check http://wiki.x.org Apr 12 18:56:46 vlounge startx[1997]: to make sure that you have the latest version. Apr 12 18:56:46 vlounge startx[1997]: Markers: (--) probed, (**) from config file, (==) default setting, Apr 12 18:56:46 vlounge startx[1997]: (++) from command line, (!!) notice, (II) informational, Apr 12 18:56:46 vlounge startx[1997]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Apr 12 18:56:46 vlounge startx[1997]: (==) Log file: "/var/log/Xorg.0.log", Time: Sun Apr 12 18:56:46 2026 Apr 12 18:56:46 vlounge startx[1997]: (==) Using config directory: "/etc/X11/xorg.conf.d" Apr 12 18:56:46 vlounge startx[1997]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Apr 12 18:56:46 vlounge volumio[1509]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Apr 12 18:56:46 vlounge volumio[1509]: info: touch_display: Volumio Kiosk started. Apr 12 18:56:46 vlounge volumio[1509]: info: CoreCommandRouter::volumioGetState Apr 12 18:56:46 vlounge volumio[1509]: info: CorePlayQueue::getTrack 0 Apr 12 18:56:46 vlounge volumio[1509]: info: [squeezelite_mc] Card 2 supports the following ALSA formats: ["IEC958_SUBFRAME_LE"] Apr 12 18:56:46 vlounge volumio[1509]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"basic","playerName":"vlounge","dsdFormat":null,"card":"2","mixerType":"None","mixer":null} Apr 12 18:56:47 vlounge volumio[1509]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Apr 12 18:56:47 vlounge volumio[1509]: info: Volumio called home Apr 12 18:56:47 vlounge volumio[1509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:56:47 vlounge volumio[1509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:56:47 vlounge volumio[1509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:56:47 vlounge volumio[1509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:56:47 vlounge volumio[1509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:56:47 vlounge volumio[1509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:56:47 vlounge volumio[1509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:56:47 vlounge volumio[1509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:56:47 vlounge volumio[1509]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 18:56:47 vlounge volumio[1509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:56:47 vlounge volumio[1509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:56:47 vlounge volumio[1509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:56:47 vlounge volumio[1509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:56:47 vlounge volumio[1509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:56:47 vlounge volumio[1509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:56:47 vlounge volumio[1509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:56:47 vlounge volumio[1509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:56:47 vlounge volumio[1509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:56:47 vlounge sudo[2012]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Apr 12 18:56:47 vlounge sudo[2012]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 18:56:47 vlounge sudo[2012]: pam_unix(sudo:session): session closed for user root Apr 12 18:56:47 vlounge volumio[1509]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 18:56:47 vlounge volumio[1509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:56:48 vlounge volumio[1509]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Apr 12 18:56:48 vlounge volumio[1509]: info: touch_display: X display number found: 0 Apr 12 18:56:48 vlounge volumio[1509]: info: Upmpdcli Daemon Started Apr 12 18:56:48 vlounge volumio[1509]: info: touch_display: IframeKeyboardBridge extension installed successfully Apr 12 18:56:48 vlounge volumio[1509]: info: [squeezelite_mc] Executing alsactl -L -R nrestore Apr 12 18:56:48 vlounge sudo[2041]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl -L -R nrestore Apr 12 18:56:48 vlounge sudo[2041]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 18:56:48 vlounge sudo[2041]: pam_unix(sudo:session): session closed for user root Apr 12 18:56:48 vlounge volumio[1509]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Apr 12 18:56:48 vlounge sudo[2046]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Apr 12 18:56:48 vlounge sudo[2046]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 18:56:48 vlounge sudo[2046]: pam_unix(sudo:session): session closed for user root Apr 12 18:56:48 vlounge sudo[2049]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Apr 12 18:56:48 vlounge sudo[2049]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 18:56:48 vlounge volumio[1509]: info: CoreCommandRouter::volumioGetState Apr 12 18:56:48 vlounge volumio[1509]: info: CorePlayQueue::getTrack 0 Apr 12 18:56:48 vlounge sudo[2049]: pam_unix(sudo:session): session closed for user root Apr 12 18:56:48 vlounge mpd[1863]: 2026-04-12T18:56:48 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 12 18:56:49 vlounge volumio[1509]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Apr 12 18:56:49 vlounge volumio[1509]: info: Starting Shairport Sync Apr 12 18:56:49 vlounge volumio[1509]: info: Starting Shairport Sync Apr 12 18:56:49 vlounge systemd[1]: Started mpd.service - Music Player Daemon. Apr 12 18:56:49 vlounge sudo[1825]: pam_unix(sudo:session): session closed for user root Apr 12 18:56:49 vlounge sudo[1842]: pam_unix(sudo:session): session closed for user root Apr 12 18:56:49 vlounge sudo[2053]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 12 18:56:49 vlounge sudo[2057]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 12 18:56:49 vlounge sudo[2053]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 18:56:49 vlounge sudo[2057]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 18:56:49 vlounge volumio[1509]: info: Starting Shairport Sync Apr 12 18:56:49 vlounge systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 12 18:56:49 vlounge systemd[1]: shairport-sync.service: Deactivated successfully. Apr 12 18:56:49 vlounge systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 12 18:56:49 vlounge systemd[1]: shairport-sync.service: Consumed 1.680s CPU time. Apr 12 18:56:49 vlounge sudo[2061]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 12 18:56:49 vlounge sudo[2061]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 18:56:49 vlounge systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 12 18:56:49 vlounge systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 12 18:56:49 vlounge sudo[2057]: pam_unix(sudo:session): session closed for user root Apr 12 18:56:49 vlounge systemd[1]: shairport-sync.service: Deactivated successfully. Apr 12 18:56:49 vlounge sudo[2053]: pam_unix(sudo:session): session closed for user root Apr 12 18:56:49 vlounge systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 12 18:56:49 vlounge systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 12 18:56:49 vlounge sudo[2061]: pam_unix(sudo:session): session closed for user root Apr 12 18:56:49 vlounge volumio[1509]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Apr 12 18:56:49 vlounge volumio[1509]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Apr 12 18:56:49 vlounge volumio[1509]: info: touch_display: X display number found: 0 Apr 12 18:56:49 vlounge volumio[1509]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Apr 12 18:56:49 vlounge sudo[2094]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Apr 12 18:56:49 vlounge sudo[2094]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 18:56:49 vlounge volumio[1509]: info: Shairport-Sync Started Apr 12 18:56:49 vlounge volumio[1509]: Error adding Membership: Error: addMembership EINVAL Apr 12 18:56:49 vlounge volumio[1509]: info: Shairport-Sync Started Apr 12 18:56:49 vlounge volumio[1509]: info: Shairport-Sync Started Apr 12 18:56:49 vlounge sudo[2094]: pam_unix(sudo:session): session closed for user root Apr 12 18:56:49 vlounge volumio[1509]: info: [squeezelite_mc] Notification listener started Apr 12 18:56:49 vlounge volumio[1509]: info: [squeezelite_mc] Getting players connected to Lyrion Music Server (Docker) (192.168.111.10) Apr 12 18:56:50 vlounge volumio[1509]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl daemon-reload || true Apr 12 18:56:50 vlounge sudo[2101]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Apr 12 18:56:50 vlounge sudo[2101]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 18:56:50 vlounge systemd[1]: Reloading. Apr 12 18:56:50 vlounge volumio[1509]: error: MPD error: The expression evaluated to a falsy value: Apr 12 18:56:50 vlounge volumio[1509]: assert.ok(self.idling) Apr 12 18:56:50 vlounge volumio[1509]: error: The expression evaluated to a falsy value: Apr 12 18:56:50 vlounge volumio[1509]: assert.ok(self.idling) Apr 12 18:56:51 vlounge volumio[1509]: info: MPD running with PID1863 Apr 12 18:56:51 vlounge volumio[1509]: ,establishing connection Apr 12 18:56:51 vlounge volumio[1509]: error: updateQueue error: null Apr 12 18:56:51 vlounge volumio[1509]: info: CoreCommandRouter::volumioGetState Apr 12 18:56:51 vlounge volumio[1509]: info: CorePlayQueue::getTrack 0 Apr 12 18:56:51 vlounge sudo[2101]: pam_unix(sudo:session): session closed for user root Apr 12 18:56:51 vlounge volumio[1509]: error: [now-playing] Caught error in callback of WeatherAPI.#getFetchPromise(): Could not obtain API key Apr 12 18:56:51 vlounge volumio[1509]: info: [squeezelite_mc] Players connected to Lyrion Music Server (Docker) (192.168.111.10): [{"id":"b8:27:eb:86:56:75","uuid":null,"ip":"192.168.111.222","name":"Bedroom","server":{"ip":"192.168.111.10","name":"Lyrion Music Server (Docker)","ver":"9.1.0","uuid":"6f13eb49-8cc3-46b9-b306-73c39b4aaef2","jsonPort":"9000","cliPort":"9090"}}] Apr 12 18:56:51 vlounge volumio[1509]: error: updateQueue error: null Apr 12 18:56:51 vlounge volumio[1509]: info: CoreCommandRouter::volumioGetState Apr 12 18:56:51 vlounge volumio[1509]: info: CorePlayQueue::getTrack 0 Apr 12 18:56:51 vlounge volumio[1509]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Apr 12 18:56:51 vlounge sudo[2154]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Apr 12 18:56:51 vlounge sudo[2154]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 18:56:52 vlounge volumio[1509]: info: touch_display: Setting screensaver timeout to 0 seconds. Apr 12 18:56:52 vlounge sudo[2154]: pam_unix(sudo:session): session closed for user root Apr 12 18:56:52 vlounge volumio[1509]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl start squeezelite || true Apr 12 18:56:52 vlounge sudo[2176]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start squeezelite Apr 12 18:56:52 vlounge sudo[2176]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 18:56:52 vlounge systemd[1]: Starting squeezelite.service - Squeezelite lightweight headless Squeezebox emulator... Apr 12 18:56:52 vlounge kernel: process '/opt/squeezelite/squeezelite' started with executable stack Apr 12 18:56:52 vlounge systemd[1]: Started squeezelite.service - Squeezelite lightweight headless Squeezebox emulator. Apr 12 18:56:52 vlounge sudo[2176]: pam_unix(sudo:session): session closed for user root Apr 12 18:56:52 vlounge volumio[1509]: info: [squeezelite_mc] 'client' notification received from Lyrion Music Server (Docker) (192.168.111.10); type is 'connect' Apr 12 18:56:52 vlounge volumio[1509]: info: [squeezelite_mc] Getting players connected to Lyrion Music Server (Docker) (192.168.111.10) Apr 12 18:56:52 vlounge volumio[1509]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Apr 12 18:56:53 vlounge volumio[1509]: info: [squeezelite_mc] Players connected to Lyrion Music Server (Docker) (192.168.111.10): [{"id":"dc:a6:32:e2:2f:3d","uuid":null,"ip":"192.168.111.177","name":"vlounge","server":{"ip":"192.168.111.10","name":"Lyrion Music Server (Docker)","ver":"9.1.0","uuid":"6f13eb49-8cc3-46b9-b306-73c39b4aaef2","jsonPort":"9000","cliPort":"9090"}},{"id":"b8:27:eb:86:56:75","uuid":null,"ip":"192.168.111.222","name":"Bedroom","server":{"ip":"192.168.111.10","name":"Lyrion Music Server (Docker)","ver":"9.1.0","uuid":"6f13eb49-8cc3-46b9-b306-73c39b4aaef2","jsonPort":"9000","cliPort":"9090"}}] Apr 12 18:56:53 vlounge volumio[1509]: info: [squeezelite_mc] Player found: {"id":"dc:a6:32:e2:2f:3d","uuid":null,"ip":"192.168.111.177","name":"vlounge","server":{"ip":"192.168.111.10","name":"Lyrion Music Server (Docker)","ver":"9.1.0","uuid":"6f13eb49-8cc3-46b9-b306-73c39b4aaef2","jsonPort":"9000","cliPort":"9090"}} Apr 12 18:56:53 vlounge sudo[2205]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Apr 12 18:56:53 vlounge sudo[2205]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 18:56:53 vlounge sudo[2205]: pam_unix(sudo:session): session closed for user root Apr 12 18:56:54 vlounge volumio[1509]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Apr 12 18:56:54 vlounge sudo[2216]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Apr 12 18:56:54 vlounge sudo[2216]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 18:56:54 vlounge sudo[2216]: pam_unix(sudo:session): session closed for user root Apr 12 18:56:54 vlounge volumio[1509]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Apr 12 18:56:54 vlounge sudo[2229]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Apr 12 18:56:54 vlounge sudo[2229]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 18:56:54 vlounge sudo[2229]: pam_unix(sudo:session): session closed for user root Apr 12 18:56:55 vlounge volumio[1509]: info: CoreCommandRouter::volumioGetState Apr 12 18:56:55 vlounge volumio[1509]: info: CorePlayQueue::getTrack 0 Apr 12 18:56:55 vlounge volumio[1509]: info: [squeezelite_mc] 'play' status received while not being the current service. Apr 12 18:56:55 vlounge volumio[1509]: info: CoreCommandRouter::volumioGetState Apr 12 18:56:55 vlounge volumio[1509]: info: CorePlayQueue::getTrack 0 Apr 12 18:56:55 vlounge volumio[1509]: info: [squeezelite_mc] Stopping playback by current service... Apr 12 18:56:55 vlounge volumio[1509]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 18:56:55 vlounge volumio[1509]: info: CoreCommandRouter::volumioGetState Apr 12 18:56:55 vlounge volumio[1509]: info: CorePlayQueue::getTrack 0 Apr 12 18:56:55 vlounge volumio[1509]: info: CoreCommandRouter::volumioStop Apr 12 18:56:55 vlounge volumio[1509]: info: CoreStateMachine::stop Apr 12 18:56:55 vlounge volumio[1509]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 18:56:55 vlounge volumio[1509]: info: [squeezelite_mc] Setting ourselves as the current service... Apr 12 18:56:55 vlounge volumio[1509]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 18:56:55 vlounge volumio[1509]: info: [squeezelite_mc] #pushState(): {"status":"play","service":"squeezelite_mc","title":"Roll Me Sunday Morning","artist":"Colin James","album":"Roll Me Sunday Morning","albumart":"http://192.168.111.177:45677/?server_name=Lyrion+Music+Server+%28Docker%29&url=http%3A%2F%2F192.168.111.10%3A9000%2Fimageproxy%2Fhttps%253A%252F%252Flastfm.freetls.fastly.net%252Fi%252Fu%252Fce2bcd6da3cf2182b7fcfe9d10edaaa4.png%2Fimage.png&fallback=http%3A%2F%2F192.168.111.177%2Falbumart","uri":"","trackType":"mp3","duration":0,"samplerate":"320kbps VBR","isStreaming":true,"volume":0,"repeat":false,"repeatSingle":false,"random":false} Apr 12 18:56:55 vlounge volumio[1509]: info: CoreCommandRouter::servicePushState Apr 12 18:56:55 vlounge volumio[1509]: info: CoreStateMachine::pushState Apr 12 18:56:55 vlounge volumio[1509]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 18:56:55 vlounge volumio[1509]: info: CoreCommandRouter::volumioPushState Apr 12 18:56:55 vlounge volumio[1509]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Apr 12 18:56:55 vlounge sudo[2242]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Apr 12 18:56:55 vlounge sudo[2242]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 18:56:55 vlounge sudo[2242]: pam_unix(sudo:session): session closed for user root Apr 12 18:56:56 vlounge volumio[1509]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Apr 12 18:56:56 vlounge volumio[1509]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Apr 12 18:56:56 vlounge sudo[2248]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Apr 12 18:56:56 vlounge sudo[2248]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 18:56:56 vlounge sudo[2248]: pam_unix(sudo:session): session closed for user root Apr 12 18:56:57 vlounge volumio[1509]: info: Completed starting Core Plugins Apr 12 18:56:57 vlounge volumio[1509]: info: ------------------------------------------- Apr 12 18:56:57 vlounge volumio[1509]: info: ----- MyVolumio plugins startup ---- Apr 12 18:56:57 vlounge volumio[1509]: info: ------------------------------------------- Apr 12 18:56:57 vlounge volumio[1509]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 12 18:56:59 vlounge volumio[1509]: info: CoreCommandRouter::volumioGetState Apr 12 18:56:59 vlounge volumio[1509]: info: CoreCommandRouter::volumioGetState Apr 12 18:56:59 vlounge volumio[1509]: verbose: New Socket.io Connection to 192.168.111.177 from 192.168.111.197 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/147.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Apr 12 18:57:05 vlounge volumio[1509]: info: Listing playlists Apr 12 18:57:05 vlounge volumio[1509]: info: Listing playlists Apr 12 18:57:08 vlounge volumio[1509]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 12 18:57:08 vlounge volumio[1509]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 12 18:57:08 vlounge volumio[1509]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 18:57:08 vlounge volumio[1509]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 12 18:57:17 vlounge volumio[1509]: info: CoreCommandRouter::volumioGetState Apr 12 18:57:17 vlounge volumio[1509]: info: [squeezelite_mc] #pushState(): {"status":"play","service":"squeezelite_mc","title":"Roll Me Sunday Morning","artist":"Colin James","album":"Roll Me Sunday Morning","albumart":"http://192.168.111.177:45677/?server_name=Lyrion+Music+Server+%28Docker%29&url=http%3A%2F%2F192.168.111.10%3A9000%2Fimageproxy%2Fhttps%253A%252F%252Flastfm.freetls.fastly.net%252Fi%252Fu%252Fce2bcd6da3cf2182b7fcfe9d10edaaa4.png%2Fimage.png&fallback=http%3A%2F%2F192.168.111.177%2Falbumart","uri":"","trackType":"mp3","duration":0,"samplerate":"320kbps VBR","isStreaming":true,"volume":0,"repeat":false,"repeatSingle":false,"random":false} Apr 12 18:57:17 vlounge volumio[1509]: info: CoreCommandRouter::servicePushState Apr 12 18:57:17 vlounge volumio[1509]: info: CoreStateMachine::pushState Apr 12 18:57:17 vlounge volumio[1509]: info: CoreCommandRouter::volumioPushState Apr 12 18:57:18 vlounge volumio[1509]: error: MyVolumio Plugin failed to start in a timely fashion Apr 12 18:57:18 vlounge volumio[1509]: [Metrics] CommandRouter: 66s 587.63ms Apr 12 18:57:18 vlounge volumio[1509]: info: CoreCommandRouter::volumiosetStartupVolume Apr 12 18:57:18 vlounge volumio[1509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:57:18 vlounge volumio[1509]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 18:57:18 vlounge volumio[1509]: info: CoreCommandRouter::Close All Modals sent Apr 12 18:57:18 vlounge volumio[1509]: info: CoreCommandRouter::Close All Modals sent Apr 12 18:57:18 vlounge volumio[1509]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 12 18:57:18 vlounge volumio[1509]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Apr 12 18:57:18 vlounge volumio[1509]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Apr 12 18:57:18 vlounge volumio[1509]: at new NodeError (node:internal/errors:405:5) Apr 12 18:57:18 vlounge volumio[1509]: at assert (node:internal/assert:14:11) Apr 12 18:57:18 vlounge volumio[1509]: at internalConnectMultiple (node:net:1118:3) Apr 12 18:57:18 vlounge volumio[1509]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Apr 12 18:57:18 vlounge volumio[1509]: at listOnTimeout (node:internal/timers:575:11) Apr 12 18:57:18 vlounge volumio[1509]: at process.processTimers (node:internal/timers:514:7) { Apr 12 18:57:18 vlounge volumio[1509]: code: 'ERR_INTERNAL_ASSERTION' Apr 12 18:57:18 vlounge volumio[1509]: } Apr 12 18:57:18 vlounge volumio[1509]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 12 18:57:20 vlounge upmpdcli[2403]: writing RSA key Apr 12 18:57:22 vlounge sudo[2441]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-12 18:56' Apr 12 18:57:22 vlounge sudo[2441]: 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="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"