Apr 12 01:15:00 volumio volumio[1286]: info: Loading plugin "airplay_emulation"... Apr 12 01:15:00 volumio volumio[1286]: info: Starting Shairport Sync Apr 12 01:15:00 volumio volumio[1286]: info: Loading plugin "last_100"... Apr 12 01:15:00 volumio volumio[1286]: info: Loading plugin "webradio"... Apr 12 01:15:00 volumio volumio[1286]: info: Loading plugin "i2s_dacs"... Apr 12 01:15:00 volumio volumio[1286]: info: Loading plugin "volumiodiscovery"... Apr 12 01:15:00 volumio volumio[1286]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 12 01:15:00 volumio volumio[1286]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 12 01:15:00 volumio node[1286]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 12 01:15:00 volumio volumio[1286]: *** WARNING *** For more information see Apr 12 01:15:00 volumio volumio[1286]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 12 01:15:00 volumio volumio[1286]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 12 01:15:00 volumio volumio[1286]: *** WARNING *** For more information see Apr 12 01:15:00 volumio node[1286]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 12 01:15:00 volumio node[1286]: *** WARNING *** For more information see Apr 12 01:15:00 volumio node[1286]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 12 01:15:00 volumio node[1286]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 12 01:15:00 volumio node[1286]: *** WARNING *** For more information see Apr 12 01:15:00 volumio volumio[1286]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 12 01:15:00 volumio volumio[1286]: info: Discovery: Started advertising with name: Volumio Apr 12 01:15:00 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 12 01:15:00 volumio volumio[1286]: info: Loading plugin "outputs"... Apr 12 01:15:00 volumio volumio[1286]: info: Loading plugin "albumart"... Apr 12 01:15:00 volumio volumio[1286]: info: Plugin example_plugin is not enabled Apr 12 01:15:00 volumio volumio[1286]: info: Loading plugin "inputs"... Apr 12 01:15:00 volumio volumio[1286]: info: Loading plugin "updater_comm"... Apr 12 01:15:00 volumio sudo[1445]: pam_unix(sudo:session): session closed for user root Apr 12 01:15:00 volumio volumio[1286]: info: Plugin mpdemulation is not enabled Apr 12 01:15:00 volumio volumio[1286]: info: Loading plugin "rest_api"... Apr 12 01:15:01 volumio volumio[1286]: info: Loading plugin "websocket"... Apr 12 01:15:01 volumio volumio[1286]: info: Starting Socket.io Server version 1.7.4 Apr 12 01:15:01 volumio volumio[1286]: info: Loading plugin "touch_display"... Apr 12 01:15:01 volumio volumio[1471]: Forking 3 albumart workers Apr 12 01:15:01 volumio volumio-remote-updater[803]: [2026-04-12 01:15:01] [connect] Successful connection Apr 12 01:15:02 volumio volumio[1286]: info: Applying required configuration parameters for plugin touch_display Apr 12 01:15:03 volumio volumio[1286]: info: Loading i18n strings for locale pl Apr 12 01:15:03 volumio volumio[1286]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Apr 12 01:15:03 volumio volumio[1286]: Updating browse sources language Apr 12 01:15:03 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 12 01:15:04 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 01:15:04 volumio volumio[1286]: info: CoreCommandRouter::initPlayerControls Apr 12 01:15:04 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:15:04 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:15:04 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:15:04 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:15:04 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:15:04 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:15:04 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:15:04 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:15:04 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 01:15:04 volumio volumio[1286]: Express server listening on port 3000 Apr 12 01:15:04 volumio volumio[1286]: [Metrics] WebUI: 10s 651.29ms Apr 12 01:15:04 volumio volumio[1286]: info: CoreStateMachine::resetVolumioState Apr 12 01:15:04 volumio volumio[1286]: info: CoreStateMachine::getcurrentVolume Apr 12 01:15:04 volumio volumio[1286]: info: CoreCommandRouter::volumioRetrievevolume Apr 12 01:15:04 volumio volumio[1286]: info: Cannot read play queue from file Apr 12 01:15:04 volumio volumio[1286]: info: Volumio Network Manager: Network status updated: 1 Apr 12 01:15:05 volumio volumio[1286]: verbose: New Socket.io Connection to 192.168.0.70 from 192.168.0.40 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Apr 12 01:15:05 volumio volumio[1286]: verbose: New Socket.io Connection to 192.168.0.70 from 192.168.0.40 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Apr 12 01:15:05 volumio volumio[1286]: verbose: New Socket.io Connection to 192.168.0.70 from 192.168.0.40 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Apr 12 01:15:05 volumio volumio[1286]: info: VolumeController:: Volume=100 Mute =false Apr 12 01:15:05 volumio volumio[1286]: info: CoreStateMachine::pushState Apr 12 01:15:05 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:05 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 01:15:05 volumio volumio[1286]: info: CoreCommandRouter::volumioPushState Apr 12 01:15:05 volumio volumio[1286]: info: CoreStateMachine::updateTrackBlock Apr 12 01:15:05 volumio volumio[1286]: info: CorePlayQueue::getTrackBlock Apr 12 01:15:05 volumio volumio[1286]: info: CoreCommandRouter::volumioRetrievevolume Apr 12 01:15:05 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 01:15:05 volumio volumio-remote-updater[803]: [2026-04-12 01:15:05] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1775949301 101 Apr 12 01:15:05 volumio volumio[1481]: Starting albumart workers Apr 12 01:15:05 volumio volumio[1482]: Starting albumart workers Apr 12 01:15:05 volumio volumio[1286]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 4 Apr 12 01:15:05 volumio volumio[1286]: info: CoreStateMachine::setRepeat null single undefined Apr 12 01:15:05 volumio volumio[1286]: info: CoreStateMachine::pushState Apr 12 01:15:05 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:05 volumio volumio[1286]: info: CoreCommandRouter::volumioPushState Apr 12 01:15:05 volumio volumio[1286]: info: CoreStateMachine::setRandom null Apr 12 01:15:05 volumio volumio[1286]: info: CoreStateMachine::pushState Apr 12 01:15:05 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:05 volumio volumio[1286]: info: CoreCommandRouter::volumioPushState Apr 12 01:15:05 volumio volumio[1286]: info: Setting Device type: Raspberry PI Apr 12 01:15:06 volumio volumio[1483]: Starting albumart workers Apr 12 01:15:06 volumio volumio[1286]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Apr 12 01:15:06 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully. Apr 12 01:15:06 volumio volumio[1286]: info: Completed loading Core Plugins Apr 12 01:15:06 volumio volumio[1286]: info: Preparing to generate the ALSA configuration file Apr 12 01:15:06 volumio volumio[1286]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Apr 12 01:15:06 volumio volumio[1286]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03114 Apr 12 01:15:06 volumio volumio[1286]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Apr 12 01:15:06 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 12 01:15:06 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:06 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:06 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 12 01:15:06 volumio volumio[1286]: info: CoreCommandRouter::volumioGetVisibleSources Apr 12 01:15:06 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 12 01:15:06 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:06 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:06 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:06 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:06 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 12 01:15:06 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 12 01:15:06 volumio volumio[1286]: info: Received Get System Info Apr 12 01:15:06 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 12 01:15:06 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 12 01:15:06 volumio volumio[1286]: info: Discovery: Getting this device information Apr 12 01:15:06 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:06 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:06 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 12 01:15:06 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:06 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:06 volumio volumio[1286]: info: Listing playlists Apr 12 01:15:07 volumio volumio[1286]: info: VolumeController:: Volume=100 Mute =false Apr 12 01:15:07 volumio volumio[1286]: info: CoreStateMachine::pushState Apr 12 01:15:07 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:07 volumio volumio[1286]: info: CoreCommandRouter::volumioPushState Apr 12 01:15:07 volumio volumio[1286]: info: Discovery: adding f6cee528-8d68-40a0-9289-331347bb4a11 Apr 12 01:15:07 volumio volumio[1286]: info: Discovery: Found device Volumio Apr 12 01:15:07 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:07 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:07 volumio volumio[1286]: info: Discovery: this is already registered, f6cee528-8d68-40a0-9289-331347bb4a11 Apr 12 01:15:07 volumio volumio[1286]: info: Discovery: Found device Volumio Apr 12 01:15:07 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:07 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:08 volumio sudo[1543]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 12 01:15:08 volumio volumio[1286]: info: Asound.conf file written Apr 12 01:15:08 volumio sudo[1543]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:15:08 volumio sudo[1548]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 12 01:15:08 volumio sudo[1548]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:15:08 volumio sudo[1543]: pam_unix(sudo:session): session closed for user root Apr 12 01:15:08 volumio sudo[1548]: pam_unix(sudo:session): session closed for user root Apr 12 01:15:08 volumio sudo[1556]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 12 01:15:08 volumio sudo[1556]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:15:08 volumio sudo[1556]: pam_unix(sudo:session): session closed for user root Apr 12 01:15:08 volumio volumio[1286]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Apr 12 01:15:08 volumio volumio[1286]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Apr 12 01:15:08 volumio volumio[1286]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Apr 12 01:15:08 volumio volumio[1286]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Apr 12 01:15:08 volumio volumio[1286]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:4 use case configuration -2 Apr 12 01:15:08 volumio volumio[1286]: info: Output device has changed, restarting MPD Apr 12 01:15:08 volumio volumio[1286]: info: Output device has changed, restarting Shairport Sync Apr 12 01:15:08 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:15:08 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 01:15:08 volumio sudo[1570]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 12 01:15:08 volumio sudo[1570]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:15:08 volumio volumio[1286]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 12 01:15:08 volumio sudo[1570]: pam_unix(sudo:session): session closed for user root Apr 12 01:15:08 volumio volumio[1286]: info: ___________ START PLUGINS ___________ Apr 12 01:15:08 volumio sudo[1576]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 12 01:15:08 volumio sudo[1576]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:15:09 volumio volumio[1286]: info: ControllerMpd::onStart: Initializing MPD Apr 12 01:15:09 volumio volumio[1286]: info: Creating MPD Configuration file Apr 12 01:15:09 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 01:15:09 volumio volumio[1286]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 12 01:15:09 volumio volumio[1286]: info: [1775949309081] CoreMusicLibrary::Adding element Serwery Mediów Apr 12 01:15:09 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 12 01:15:09 volumio volumio[1286]: info: UPNP Browser: Client initialized successfully Apr 12 01:15:09 volumio sudo[1585]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Apr 12 01:15:09 volumio sudo[1585]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:15:09 volumio sudo[1587]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 12 01:15:09 volumio sudo[1587]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:15:09 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:15:09 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 01:15:09 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 12 01:15:09 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Apr 12 01:15:09 volumio sudo[1587]: pam_unix(sudo:session): session closed for user root Apr 12 01:15:09 volumio sudo[1589]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 12 01:15:09 volumio sudo[1589]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:15:09 volumio sudo[1594]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 12 01:15:09 volumio sudo[1594]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 12 01:15:09 volumio sudo[1598]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Apr 12 01:15:09 volumio sudo[1594]: pam_unix(sudo:session): session closed for user root Apr 12 01:15:09 volumio volumio[1286]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 12 01:15:09 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:15:09 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 01:15:09 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Apr 12 01:15:09 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Apr 12 01:15:09 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Apr 12 01:15:09 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Apr 12 01:15:09 volumio systemd[1]: mpd.service: Deactivated successfully. Apr 12 01:15:09 volumio upmpdcli[1604]: Could not open config: /tmp/upmpdcli.conf Apr 12 01:15:09 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 12 01:15:09 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Apr 12 01:15:09 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Apr 12 01:15:09 volumio systemd[1]: mpd.socket: Deactivated successfully. Apr 12 01:15:09 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 12 01:15:09 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 12 01:15:09 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 12 01:15:09 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Apr 12 01:15:09 volumio sudo[1585]: pam_unix(sudo:session): session closed for user root Apr 12 01:15:09 volumio volumio[1286]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 12 01:15:09 volumio volumio[1286]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 12 01:15:09 volumio volumio[1286]: info: [1775949309518] CoreMusicLibrary::Adding element Last_100 Apr 12 01:15:09 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 12 01:15:09 volumio volumio[1286]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 12 01:15:09 volumio volumio[1286]: info: [1775949309530] CoreMusicLibrary::Adding element Webradio Apr 12 01:15:09 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 12 01:15:09 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 12 01:15:09 volumio sudo[1608]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 12 01:15:09 volumio sudo[1608]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 12 01:15:09 volumio sudo[1614]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Apr 12 01:15:09 volumio sudo[1608]: pam_unix(sudo:session): session closed for user root Apr 12 01:15:09 volumio volumio[1286]: info: Initializing BBC Radios Apr 12 01:15:10 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. Apr 12 01:15:10 volumio volumio5-onboarding[1606]: time=2026-04-12T01:15:10.038+02:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Apr 12 01:15:10 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 12 01:15:10 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 01:15:10 volumio volumio[1286]: info: Loading i18n strings for locale pl Apr 12 01:15:10 volumio volumio[1286]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Apr 12 01:15:10 volumio sudo[1636]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /data/volumiokioskextensions/IframeKeyboardBridge Apr 12 01:15:10 volumio sudo[1636]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:15:10 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 12 01:15:10 volumio volumio[1286]: info: Volumio Calling Home Apr 12 01:15:10 volumio sudo[1636]: pam_unix(sudo:session): session closed for user root Apr 12 01:15:10 volumio sudo[1644]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Apr 12 01:15:10 volumio sudo[1644]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:15:10 volumio sudo[1646]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Apr 12 01:15:10 volumio sudo[1646]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:15:10 volumio sudo[1643]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Apr 12 01:15:10 volumio sudo[1643]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:15:10 volumio systemd[1]: Reloading. Apr 12 01:15:12 volumio sudo[1644]: pam_unix(sudo:session): session closed for user root Apr 12 01:15:12 volumio sudo[1646]: pam_unix(sudo:session): session closed for user root Apr 12 01:15:12 volumio systemd[1]: Reloading. Apr 12 01:15:13 volumio volumio[1286]: info: touch_display: Backlight interface detected. Apr 12 01:15:14 volumio sudo[1643]: pam_unix(sudo:session): session closed for user root Apr 12 01:15:14 volumio volumio[1286]: info: touch_display: systemctl daemon-reload succeeded. Apr 12 01:15:14 volumio volumio[1286]: info: touch_display: systemctl stop getty@tty1.service succeeded. Apr 12 01:15:14 volumio sudo[1685]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Apr 12 01:15:14 volumio sudo[1685]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:15:14 volumio sudo[1688]: 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 01:15:14 volumio sudo[1688]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:15:14 volumio volumio[1286]: info: MPD Permissions set Apr 12 01:15:14 volumio volumio[1286]: info: MPD Permissions set Apr 12 01:15:14 volumio systemd[1]: Started volumio-kiosk.service - Volumio Kiosk. Apr 12 01:15:14 volumio sudo[1685]: pam_unix(sudo:session): session closed for user root Apr 12 01:15:14 volumio sudo[1688]: pam_unix(sudo:session): session closed for user root Apr 12 01:15:14 volumio volumio[1286]: 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: 6 Apr 12 01:15:14 volumio startx[1720]: X.Org X Server 1.21.1.7 Apr 12 01:15:14 volumio startx[1720]: X Protocol Version 11, Revision 0 Apr 12 01:15:14 volumio startx[1720]: Current Operating System: Linux volumio 6.12.74-v7l+ #1948 SMP Mon Mar 2 11:27:49 GMT 2026 armv7l Apr 12 01:15:14 volumio startx[1720]: 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_hdmi=0 snd_bcm2835.enable_hdmi=0 video=HDMI-A-1:640x480M@60D numa=fake=2 system_heap.max_order=0 iommu_dma_numa_policy=interleave smsc95xx.macaddr=E4:5F:01:0F:19:23 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=9caf6474-168f-4972-add4-ace36a467bc2 imgfile=/volumio_current.sqsh bootpart=UUID=807D-2966 datapart=UUID=bea3e05c-62cf-4b22-a58d-66213927f56a uuidconfig=cmdline.txt rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Apr 12 01:15:14 volumio startx[1720]: xorg-server 2:21.1.7-3+rpt3+deb12u11 (https://www.debian.org/support) Apr 12 01:15:14 volumio startx[1720]: Current version of pixman: 0.44.0 Apr 12 01:15:14 volumio startx[1720]: Before reporting problems, check http://wiki.x.org Apr 12 01:15:14 volumio startx[1720]: to make sure that you have the latest version. Apr 12 01:15:14 volumio startx[1720]: Markers: (--) probed, (**) from config file, (==) default setting, Apr 12 01:15:14 volumio startx[1720]: (++) from command line, (!!) notice, (II) informational, Apr 12 01:15:14 volumio startx[1720]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Apr 12 01:15:14 volumio startx[1720]: (==) Log file: "/var/log/Xorg.0.log", Time: Sun Apr 12 01:15:14 2026 Apr 12 01:15:14 volumio startx[1720]: (==) Using config directory: "/etc/X11/xorg.conf.d" Apr 12 01:15:14 volumio startx[1720]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Apr 12 01:15:14 volumio volumio[1286]: info: touch_display: systemctl disable getty@tty1.service succeeded. Apr 12 01:15:14 volumio volumio[1286]: info: Volumio called home Apr 12 01:15:15 volumio mpd[1615]: 2026-04-12T01:15:15 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 12 01:15:15 volumio volumio[1286]: 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: 6 Apr 12 01:15:15 volumio volumio[1286]: info: Received Get System Info Apr 12 01:15:15 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 12 01:15:15 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 12 01:15:15 volumio volumio[1286]: info: Discovery: Getting this device information Apr 12 01:15:15 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:15 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:15 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 12 01:15:15 volumio volumio5-onboarding[1606]: time=2026-04-12T01:15:15.470+02:00 level=INFO msg="system info for 81d3a6f20ac63875b3c6cccfa20fb84e" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.119 Apr 12 01:15:15 volumio systemd[1]: Started mpd.service - Music Player Daemon. Apr 12 01:15:15 volumio sudo[1576]: pam_unix(sudo:session): session closed for user root Apr 12 01:15:15 volumio volumio5-onboarding[1606]: time=2026-04-12T01:15:15.486+02:00 level=INFO msg="bootstrapping state" hasInternet=true Apr 12 01:15:15 volumio sudo[1589]: pam_unix(sudo:session): session closed for user root Apr 12 01:15:15 volumio volumio[1286]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Apr 12 01:15:15 volumio volumio[1286]: info: touch_display: Volumio Kiosk started. Apr 12 01:15:15 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:15 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:15 volumio volumio[1286]: info: touch_display: IframeKeyboardBridge extension installed successfully Apr 12 01:15:15 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:15:15 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:15:15 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:15:15 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:15:15 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:15:15 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:15:15 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:15:15 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:15:15 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 01:15:15 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:15:15 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:15:15 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:15:15 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:15:15 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:15:15 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:15:15 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:15:15 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:15:15 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:15:16 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 01:15:16 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:15:16 volumio volumio[1286]: info: Received Get System Info Apr 12 01:15:16 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 12 01:15:16 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 12 01:15:16 volumio volumio[1286]: info: Discovery: Getting this device information Apr 12 01:15:16 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:16 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:16 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 12 01:15:16 volumio volumio[1286]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Apr 12 01:15:16 volumio volumio[1286]: info: touch_display: X display number found: 0 Apr 12 01:15:16 volumio volumio[1286]: info: Completed starting Core Plugins Apr 12 01:15:16 volumio volumio[1286]: info: ------------------------------------------- Apr 12 01:15:16 volumio volumio[1286]: info: ----- MyVolumio plugins startup ---- Apr 12 01:15:16 volumio volumio[1286]: info: ------------------------------------------- Apr 12 01:15:16 volumio volumio[1286]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 12 01:15:16 volumio volumio-remote-updater[803]: Test mode enabled Apr 12 01:15:16 volumio volumio-remote-updater[803]: Alpha mode disabled Apr 12 01:15:16 volumio volumio-remote-updater[803]: Alpha legacy test mode disabled Apr 12 01:15:16 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:16 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:16 volumio kernel: ------------[ cut here ]------------ Apr 12 01:15:16 volumio kernel: WARNING: CPU: 3 PID: 1720 at drivers/gpu/drm/vc4/vc4_hvs.c:1064 __vc4_hvs_stop_channel+0x168/0x1dc [vc4] Apr 12 01:15:16 volumio kernel: Modules linked in: cmac algif_hash aes_arm_bs crypto_simd cryptd aes_arm aes_generic algif_skcipher af_alg bnep nft_chain_nat xt_REDIRECT nf_nat nf_conntrack nf_defrag_ipv6 nf_defrag_ipv4 xt_tcpudp nft_compat nf_tables nfnetlink brcmfmac_wcc joydev bcm2835_codec(C) bcm2835_v4l2(C) bcm2835_isp(C) 8021q garp bcm2835_mmal_vchiq(C) stp llc rpi_hevc_dec binfmt_misc brcmfmac hci_uart vc_sm_cma(C) videobuf2_vmalloc btbcm v4l2_mem2mem videobuf2_dma_contig videobuf2_memops bluetooth videobuf2_v4l2 goodix_ts videodev brcmutil snd_soc_i_sabre_codec ecdh_generic regmap_i2c ecc cfg80211 snd_soc_i_sabre_q2m libaes raspberrypi_hwmon rfkill videobuf2_common raspberrypi_gpiomem mc nvmem_rmem uio_pdrv_genirq uio i2c_dev dm_mod ip_tables x_tables ipv6 ads7846 fb_hx8357d(C) fb_st7789v(C) fb_st7735r(C) fb_ili9341(C) fb_ili9340(C) fbtft(C) panel_waveshare_dsi_v2 panel_ilitek_ili9881c panel_raspberrypi_touchscreen pwm_bcm2835 spi_bcm2835 squashfs overlay nls_iso8859_1 fuse vc4 panel_waveshare_dsi snd_soc_hdmi_codec Apr 12 01:15:16 volumio kernel: drm_display_helper v3d cec gpu_sched drm_shmem_helper drm_dma_helper drm_kms_helper drm snd_soc_bcm2835_i2s drm_panel_orientation_quirks i2c_mux_pinctrl i2c_mux snd_soc_core i2c_brcmstb snd_bcm2835(C) snd_compress snd_pcm_dmaengine snd_pcm i2c_bcm2835 snd_timer snd backlight Apr 12 01:15:16 volumio kernel: CPU: 3 UID: 0 PID: 1720 Comm: Xorg Tainted: G C 6.12.74-v7l+ #1948 Apr 12 01:15:16 volumio kernel: Tainted: [C]=CRAP Apr 12 01:15:16 volumio kernel: Hardware name: BCM2711 Apr 12 01:15:16 volumio kernel: Call trace: Apr 12 01:15:16 volumio kernel: unwind_backtrace from show_stack+0x18/0x1c Apr 12 01:15:16 volumio kernel: show_stack from dump_stack_lvl+0x5c/0x80 Apr 12 01:15:16 volumio kernel: dump_stack_lvl from __warn+0x88/0x124 Apr 12 01:15:16 volumio kernel: __warn from warn_slowpath_fmt+0x184/0x190 Apr 12 01:15:16 volumio kernel: warn_slowpath_fmt from __vc4_hvs_stop_channel+0x168/0x1dc [vc4] Apr 12 01:15:16 volumio kernel: __vc4_hvs_stop_channel [vc4] from vc4_crtc_disable+0x138/0x1dc [vc4] Apr 12 01:15:16 volumio kernel: vc4_crtc_disable [vc4] from vc4_crtc_atomic_disable+0x9c/0xc0 [vc4] Apr 12 01:15:16 volumio kernel: vc4_crtc_atomic_disable [vc4] from disable_outputs+0x268/0x3a0 [drm_kms_helper] Apr 12 01:15:16 volumio kernel: disable_outputs [drm_kms_helper] from drm_atomic_helper_commit_modeset_disables+0x18/0x3c [drm_kms_helper] Apr 12 01:15:16 volumio kernel: drm_atomic_helper_commit_modeset_disables [drm_kms_helper] from vc4_atomic_commit_tail+0x194/0x998 [vc4] Apr 12 01:15:16 volumio kernel: vc4_atomic_commit_tail [vc4] from commit_tail+0xa4/0x18c [drm_kms_helper] Apr 12 01:15:16 volumio kernel: commit_tail [drm_kms_helper] from drm_atomic_helper_commit+0x140/0x164 [drm_kms_helper] Apr 12 01:15:16 volumio kernel: drm_atomic_helper_commit [drm_kms_helper] from drm_atomic_commit+0xc8/0x100 [drm] Apr 12 01:15:16 volumio kernel: drm_atomic_commit [drm] from drm_atomic_helper_set_config+0x90/0xc8 [drm_kms_helper] Apr 12 01:15:16 volumio kernel: drm_atomic_helper_set_config [drm_kms_helper] from drm_mode_setcrtc+0x200/0x814 [drm] Apr 12 01:15:16 volumio kernel: drm_mode_setcrtc [drm] from drm_ioctl+0x2b4/0x4d0 [drm] Apr 12 01:15:16 volumio kernel: drm_ioctl [drm] from sys_ioctl+0x130/0xbcc Apr 12 01:15:16 volumio kernel: sys_ioctl from ret_fast_syscall+0x0/0x5c Apr 12 01:15:16 volumio kernel: Exception stack(0xf0f31fa8 to 0xf0f31ff0) Apr 12 01:15:16 volumio kernel: 1fa0: 0000000c beeaa9e0 0000000c c06864a2 beeaa9e0 beeaa9c0 Apr 12 01:15:16 volumio kernel: 1fc0: 0000000c beeaa9e0 c06864a2 00000036 beeaaa98 00e594f8 00510850 00000000 Apr 12 01:15:16 volumio kernel: 1fe0: 00000000 beeaa9a8 b6a15000 b69981e4 Apr 12 01:15:16 volumio kernel: ---[ end trace 0000000000000000 ]--- Apr 12 01:15:16 volumio sudo[1743]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/class/backlight/10-0045/brightness Apr 12 01:15:16 volumio sudo[1743]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:15:16 volumio sudo[1743]: pam_unix(sudo:session): session closed for user root Apr 12 01:15:16 volumio volumio[1286]: info: MPD running with PID1615 Apr 12 01:15:16 volumio volumio[1286]: ,establishing connection Apr 12 01:15:16 volumio volumio[1286]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Apr 12 01:15:16 volumio sudo[1771]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 12 01:15:16 volumio sudo[1765]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 12 01:15:16 volumio sudo[1765]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:15:16 volumio sudo[1771]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:15:16 volumio sudo[1771]: pam_unix(sudo:session): session closed for user root Apr 12 01:15:16 volumio sudo[1768]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Apr 12 01:15:16 volumio sudo[1772]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 12 01:15:16 volumio sudo[1772]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:15:16 volumio sudo[1768]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:15:16 volumio volumio[1286]: error: MPD error: The expression evaluated to a falsy value: Apr 12 01:15:16 volumio volumio[1286]: assert.ok(self.idling) Apr 12 01:15:16 volumio volumio[1286]: error: The expression evaluated to a falsy value: Apr 12 01:15:16 volumio volumio[1286]: assert.ok(self.idling) Apr 12 01:15:16 volumio volumio[1286]: error: MPD error: The expression evaluated to a falsy value: Apr 12 01:15:16 volumio volumio[1286]: assert.ok(self.idling) Apr 12 01:15:16 volumio volumio[1286]: error: The expression evaluated to a falsy value: Apr 12 01:15:16 volumio volumio[1286]: assert.ok(self.idling) Apr 12 01:15:16 volumio sudo[1768]: pam_unix(sudo:session): session closed for user root Apr 12 01:15:16 volumio sudo[1772]: pam_unix(sudo:session): session closed for user root Apr 12 01:15:16 volumio volumio[1286]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 12 01:15:16 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 12 01:15:16 volumio volumio[1286]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Apr 12 01:15:16 volumio volumio[1286]: info: touch_display: File permissions for backlight brightness control set. Apr 12 01:15:16 volumio volumio[1286]: info: Starting Shairport Sync Apr 12 01:15:16 volumio volumio[1286]: info: Starting Shairport Sync Apr 12 01:15:16 volumio volumio[1286]: info: Starting Shairport Sync Apr 12 01:15:17 volumio sudo[1780]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 12 01:15:17 volumio sudo[1780]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:15:17 volumio sudo[1782]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 12 01:15:17 volumio sudo[1782]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:15:17 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 12 01:15:17 volumio sudo[1778]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 12 01:15:17 volumio sudo[1778]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:15:17 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 12 01:15:17 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Apr 12 01:15:17 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 12 01:15:17 volumio systemd[1]: shairport-sync.service: Consumed 1.481s CPU time. Apr 12 01:15:17 volumio volumio[1286]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Apr 12 01:15:17 volumio sudo[1782]: pam_unix(sudo:session): session closed for user root Apr 12 01:15:17 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 12 01:15:17 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 12 01:15:17 volumio volumio[1286]: error: updateQueue error: null Apr 12 01:15:17 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Apr 12 01:15:17 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 12 01:15:17 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 12 01:15:17 volumio sudo[1778]: pam_unix(sudo:session): session closed for user root Apr 12 01:15:17 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 12 01:15:17 volumio sudo[1780]: pam_unix(sudo:session): session closed for user root Apr 12 01:15:17 volumio volumio[1286]: info: touch_display: X display number found: 0 Apr 12 01:15:17 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 12 01:15:17 volumio volumio[1286]: info: Shairport-Sync Started Apr 12 01:15:17 volumio volumio[1286]: Error adding Membership: Error: addMembership EINVAL Apr 12 01:15:17 volumio volumio[1286]: info: Shairport-Sync Started Apr 12 01:15:17 volumio volumio[1286]: info: Received Get System Info Apr 12 01:15:17 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 12 01:15:17 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 12 01:15:17 volumio volumio[1286]: info: Discovery: Getting this device information Apr 12 01:15:17 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:17 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:17 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 12 01:15:17 volumio sudo[1833]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Apr 12 01:15:17 volumio sudo[1833]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:15:17 volumio sudo[1833]: pam_unix(sudo:session): session closed for user root Apr 12 01:15:17 volumio volumio[1286]: verbose: New Socket.io Connection to 192.168.0.70 from 192.168.0.40 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Apr 12 01:15:17 volumio volumio[1286]: info: Shairport-Sync Started Apr 12 01:15:18 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 12 01:15:18 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 12 01:15:18 volumio volumio5-onboarding[1606]: time=2026-04-12T01:15:18.101+02:00 level=INFO msg="enabling local network discovery" Apr 12 01:15:18 volumio volumio5-onboarding[1606]: time=2026-04-12T01:15:18.144+02:00 level=INFO msg="enabling BLE discovery" Apr 12 01:15:18 volumio volumio[1286]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Apr 12 01:15:18 volumio volumio[1286]: info: touch_display: Setting screensaver timeout to 120 seconds. Apr 12 01:15:18 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:18 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:18 volumio sudo[1893]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 12 01:15:18 volumio sudo[1893]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:15:18 volumio sudo[1893]: pam_unix(sudo:session): session closed for user root Apr 12 01:15:18 volumio sudo[1896]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 12 01:15:18 volumio volumio[1286]: verbose: New Socket.io Connection to 192.168.0.70 from 192.168.0.40 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Apr 12 01:15:18 volumio sudo[1896]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:15:19 volumio sudo[1896]: pam_unix(sudo:session): session closed for user root Apr 12 01:15:19 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:19 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:19 volumio volumio[1286]: info: Received Get System Info Apr 12 01:15:19 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 12 01:15:19 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 12 01:15:19 volumio volumio[1286]: info: Discovery: Getting this device information Apr 12 01:15:19 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:19 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:19 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 12 01:15:19 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:19 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:19 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 12 01:15:19 volumio volumio[1286]: info: CoreCommandRouter::volumioGetVisibleSources Apr 12 01:15:19 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 12 01:15:19 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:19 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:19 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:19 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:19 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 12 01:15:19 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 12 01:15:19 volumio volumio[1286]: info: Received Get System Info Apr 12 01:15:19 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 12 01:15:19 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 12 01:15:19 volumio volumio[1286]: info: Discovery: Getting this device information Apr 12 01:15:19 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:19 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:19 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 12 01:15:19 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:19 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:19 volumio volumio5-onboarding[1606]: time=2026-04-12T01:15:19.169+02:00 level=INFO msg="service successfully established" component=discovery/localnet Apr 12 01:15:19 volumio volumio[1286]: info: Listing playlists Apr 12 01:15:19 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 12 01:15:19 volumio volumio[1286]: info: CoreCommandRouter::volumioGetVisibleSources Apr 12 01:15:19 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 12 01:15:19 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:19 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:19 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:19 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:19 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 12 01:15:19 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 12 01:15:19 volumio volumio[1286]: info: Received Get System Info Apr 12 01:15:19 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 12 01:15:19 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 12 01:15:19 volumio volumio[1286]: info: Discovery: Getting this device information Apr 12 01:15:19 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:19 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:19 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 12 01:15:19 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:19 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:19 volumio volumio[1286]: info: Listing playlists Apr 12 01:15:19 volumio volumio[1286]: verbose: New Socket.io Connection to 192.168.0.70 from 192.168.0.40 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Apr 12 01:15:19 volumio volumio[1286]: info: touch_display: Rotation settings written to /etc/X11/xorg.conf.d/95-touch_display-plugin.conf. Apr 12 01:15:19 volumio sudo[1901]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-kiosk.service Apr 12 01:15:19 volumio sudo[1901]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:15:20 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Apr 12 01:15:20 volumio startx[1718]: xinit: connection to X server lost Apr 12 01:15:20 volumio startx[1718]: Apr 12 01:15:20 volumio systemd[1]: Stopping volumio-kiosk.service - Volumio Kiosk... Apr 12 01:15:20 volumio startx[1718]: waiting for X server to shut down Apr 12 01:15:20 volumio startx[1720]: (II) Server terminated successfully (0). Closing log file. Apr 12 01:15:20 volumio volumio[1286]: verbose: New Socket.io Connection to 192.168.0.70 from 192.168.0.40 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Apr 12 01:15:20 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:20 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:21 volumio volumio[1286]: info: Received Get System Info Apr 12 01:15:21 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 12 01:15:21 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 12 01:15:21 volumio volumio[1286]: info: Discovery: Getting this device information Apr 12 01:15:21 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:21 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:21 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 12 01:15:21 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:21 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:21 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 12 01:15:21 volumio volumio[1286]: info: CoreCommandRouter::volumioGetVisibleSources Apr 12 01:15:21 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 12 01:15:21 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:21 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:21 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:21 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:21 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 12 01:15:21 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 12 01:15:21 volumio volumio[1286]: info: Received Get System Info Apr 12 01:15:21 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 12 01:15:21 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 12 01:15:21 volumio volumio[1286]: info: Discovery: Getting this device information Apr 12 01:15:21 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:21 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:21 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 12 01:15:21 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:21 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:21 volumio volumio[1286]: info: Listing playlists Apr 12 01:15:21 volumio startx[1718]: xinit: unexpected signal 15 Apr 12 01:15:21 volumio systemd[1]: volumio-kiosk.service: Main process exited, code=exited, status=1/FAILURE Apr 12 01:15:21 volumio systemd[1]: volumio-kiosk.service: Failed with result 'exit-code'. Apr 12 01:15:21 volumio systemd[1]: Stopped volumio-kiosk.service - Volumio Kiosk. Apr 12 01:15:21 volumio systemd[1]: volumio-kiosk.service: Consumed 2.336s CPU time. Apr 12 01:15:21 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 12 01:15:21 volumio volumio[1286]: info: CoreCommandRouter::volumioGetVisibleSources Apr 12 01:15:21 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 12 01:15:21 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:21 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:21 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:21 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:21 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 12 01:15:21 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 12 01:15:21 volumio volumio[1286]: info: Received Get System Info Apr 12 01:15:21 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 12 01:15:21 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 12 01:15:21 volumio volumio[1286]: info: Discovery: Getting this device information Apr 12 01:15:21 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:21 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:21 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 12 01:15:21 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:21 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:21 volumio volumio[1286]: info: Listing playlists Apr 12 01:15:21 volumio systemd[1]: Started volumio-kiosk.service - Volumio Kiosk. Apr 12 01:15:21 volumio sudo[1901]: pam_unix(sudo:session): session closed for user root Apr 12 01:15:21 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 12 01:15:21 volumio volumio[1286]: info: CoreCommandRouter::volumioGetVisibleSources Apr 12 01:15:21 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 12 01:15:21 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:21 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:21 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:21 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:21 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 12 01:15:21 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 12 01:15:21 volumio volumio[1286]: info: Received Get System Info Apr 12 01:15:21 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 12 01:15:21 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 12 01:15:21 volumio volumio[1286]: info: Discovery: Getting this device information Apr 12 01:15:21 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:21 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:21 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 12 01:15:21 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:21 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:21 volumio startx[1950]: X.Org X Server 1.21.1.7 Apr 12 01:15:21 volumio startx[1950]: X Protocol Version 11, Revision 0 Apr 12 01:15:21 volumio startx[1950]: Current Operating System: Linux volumio 6.12.74-v7l+ #1948 SMP Mon Mar 2 11:27:49 GMT 2026 armv7l Apr 12 01:15:21 volumio startx[1950]: 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_hdmi=0 snd_bcm2835.enable_hdmi=0 video=HDMI-A-1:640x480M@60D numa=fake=2 system_heap.max_order=0 iommu_dma_numa_policy=interleave smsc95xx.macaddr=E4:5F:01:0F:19:23 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=9caf6474-168f-4972-add4-ace36a467bc2 imgfile=/volumio_current.sqsh bootpart=UUID=807D-2966 datapart=UUID=bea3e05c-62cf-4b22-a58d-66213927f56a uuidconfig=cmdline.txt rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Apr 12 01:15:21 volumio startx[1950]: xorg-server 2:21.1.7-3+rpt3+deb12u11 (https://www.debian.org/support) Apr 12 01:15:21 volumio startx[1950]: Current version of pixman: 0.44.0 Apr 12 01:15:21 volumio startx[1950]: Before reporting problems, check http://wiki.x.org Apr 12 01:15:21 volumio startx[1950]: to make sure that you have the latest version. Apr 12 01:15:21 volumio startx[1950]: Markers: (--) probed, (**) from config file, (==) default setting, Apr 12 01:15:21 volumio startx[1950]: (++) from command line, (!!) notice, (II) informational, Apr 12 01:15:21 volumio startx[1950]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Apr 12 01:15:21 volumio startx[1950]: (==) Log file: "/var/log/Xorg.0.log", Time: Sun Apr 12 01:15:21 2026 Apr 12 01:15:21 volumio startx[1950]: (==) Using config directory: "/etc/X11/xorg.conf.d" Apr 12 01:15:21 volumio startx[1950]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Apr 12 01:15:21 volumio volumio[1286]: info: Listing playlists Apr 12 01:15:21 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 12 01:15:21 volumio volumio[1286]: info: Received Get System Info Apr 12 01:15:21 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 12 01:15:21 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 12 01:15:21 volumio volumio[1286]: info: Discovery: Getting this device information Apr 12 01:15:21 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:21 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:21 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 12 01:15:21 volumio volumio[1286]: info: touch_display: systemctl restart volumio-kiosk.service succeeded. Apr 12 01:15:21 volumio volumio[1286]: info: touch_display: Volumio Kiosk restarted. Apr 12 01:15:22 volumio volumio[1286]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Apr 12 01:15:22 volumio volumio[1286]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Apr 12 01:15:22 volumio volumio[1286]: info: touch_display: X display number found: 0 Apr 12 01:15:22 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Apr 12 01:15:23 volumio volumio[1286]: info: touch_display: Setting screensaver timeout to 120 seconds. Apr 12 01:15:24 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Apr 12 01:15:24 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Apr 12 01:15:24 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Apr 12 01:15:24 volumio sudo[1765]: pam_unix(sudo:session): session closed for user root Apr 12 01:15:24 volumio volumio[1286]: info: Upmpdcli Daemon Started Apr 12 01:15:24 volumio volumio[1286]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Apr 12 01:15:24 volumio volumio[1286]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Apr 12 01:15:24 volumio volumio[1286]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Apr 12 01:15:24 volumio volumio[1286]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Apr 12 01:15:24 volumio volumio[1286]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Apr 12 01:15:24 volumio volumio[1286]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Apr 12 01:15:24 volumio volumio[1286]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Apr 12 01:15:24 volumio volumio[1286]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Apr 12 01:15:24 volumio volumio[1286]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Apr 12 01:15:24 volumio volumio[1286]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Apr 12 01:15:24 volumio volumio[1286]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Apr 12 01:15:24 volumio volumio[1286]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 12 01:15:24 volumio volumio[1286]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 12 01:15:24 volumio volumio[1286]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 12 01:15:24 volumio volumio[1286]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Apr 12 01:15:24 volumio volumio[1286]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 12 01:15:24 volumio volumio[1286]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 12 01:15:24 volumio volumio[1286]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 12 01:15:24 volumio volumio[1286]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 12 01:15:24 volumio volumio[1286]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 12 01:15:24 volumio volumio[1286]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 12 01:15:24 volumio volumio[1286]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Apr 12 01:15:24 volumio volumio[1286]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 12 01:15:24 volumio volumio[1286]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Apr 12 01:15:24 volumio volumio[1286]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 12 01:15:24 volumio volumio[1286]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 12 01:15:24 volumio volumio[1286]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 12 01:15:24 volumio volumio[1286]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 12 01:15:24 volumio volumio[1286]: info: Adding plugin bluetooth to MyMusic Plugins Apr 12 01:15:24 volumio volumio[1286]: info: Adding plugin multiroom to MyMusic Plugins Apr 12 01:15:24 volumio volumio[1286]: info: Adding plugin metavolumio to MyMusic Plugins Apr 12 01:15:24 volumio volumio[1286]: info: Adding plugin cd_controller to MyMusic Plugins Apr 12 01:15:24 volumio volumio[1286]: info: Adding plugin qobuzconnect to MyMusic Plugins Apr 12 01:15:24 volumio volumio[1286]: info: Adding plugin smart_inputs to MyMusic Plugins Apr 12 01:15:24 volumio volumio[1286]: info: Adding plugin tidalconnect to MyMusic Plugins Apr 12 01:15:24 volumio volumio[1286]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Apr 12 01:15:31 volumio volumio[1286]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 12 01:15:31 volumio volumio[1286]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 12 01:15:31 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 01:15:31 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 01:15:31 volumio volumio[1286]: info: Starting MyVolumio Remote Streaming Endpoints Apr 12 01:15:31 volumio volumio[1286]: info: MyVolumio login type: Token Apr 12 01:15:31 volumio volumio[1286]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 12 01:15:31 volumio volumio[1286]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Apr 12 01:15:36 volumio upmpdcli[2180]: writing RSA key Apr 12 01:15:37 volumio volumio[1286]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Apr 12 01:15:37 volumio volumio[1286]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Apr 12 01:15:37 volumio volumio[1286]: info: Streaming services startup Apr 12 01:15:37 volumio volumio[1286]: info: Starting Streaming Daemon Apr 12 01:15:37 volumio volumio[1286]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Apr 12 01:15:37 volumio sudo[2200]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 12 01:15:37 volumio sudo[2200]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:15:37 volumio sudo[2200]: pam_unix(sudo:session): session closed for user root Apr 12 01:15:37 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 12 01:15:37 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 12 01:15:37 volumio volumio[1286]: error: Cannot start Volumio Streaming Daemon Apr 12 01:15:37 volumio volumio[1286]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 12 01:15:37 volumio volumio[1286]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 12 01:15:37 volumio sudo[2210]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 12 01:15:37 volumio sudo[2210]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:15:37 volumio sudo[2212]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 12 01:15:37 volumio sudo[2212]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:15:37 volumio sudo[2210]: pam_unix(sudo:session): session closed for user root Apr 12 01:15:37 volumio sudo[2212]: pam_unix(sudo:session): session closed for user root Apr 12 01:15:38 volumio sudo[2216]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 12 01:15:38 volumio sudo[2216]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:15:38 volumio sudo[2216]: pam_unix(sudo:session): session closed for user root Apr 12 01:15:38 volumio sudo[2218]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 12 01:15:38 volumio sudo[2218]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:15:38 volumio sudo[2218]: pam_unix(sudo:session): session closed for user root Apr 12 01:15:38 volumio volumio[1286]: verbose: New Socket.io Connection to 192.168.0.70 from 192.168.0.40 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Apr 12 01:15:38 volumio sudo[2224]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 12 01:15:38 volumio sudo[2224]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:15:38 volumio sudo[2222]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 12 01:15:38 volumio sudo[2222]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:15:38 volumio sudo[2224]: pam_unix(sudo:session): session closed for user root Apr 12 01:15:38 volumio sudo[2222]: pam_unix(sudo:session): session closed for user root Apr 12 01:15:38 volumio volumio[1286]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Apr 12 01:15:39 volumio volumio[1286]: verbose: New Socket.io Connection to 192.168.0.70 from 192.168.0.40 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Apr 12 01:15:39 volumio volumio[1286]: verbose: New Socket.io Connection to 192.168.0.70 from 192.168.0.40 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Apr 12 01:15:39 volumio sudo[2241]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 12 01:15:39 volumio sudo[2241]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:15:39 volumio sudo[2246]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 12 01:15:39 volumio sudo[2246]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:15:39 volumio sudo[2241]: pam_unix(sudo:session): session closed for user root Apr 12 01:15:39 volumio sudo[2246]: pam_unix(sudo:session): session closed for user root Apr 12 01:15:39 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:39 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:39 volumio volumio[1286]: info: Received Get System Info Apr 12 01:15:39 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 12 01:15:39 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 12 01:15:39 volumio volumio[1286]: info: Discovery: Getting this device information Apr 12 01:15:39 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:39 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:39 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 12 01:15:39 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:39 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:40 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 12 01:15:40 volumio volumio[1286]: info: CoreCommandRouter::volumioGetVisibleSources Apr 12 01:15:40 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 12 01:15:40 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:40 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:40 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:40 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:40 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 12 01:15:40 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 12 01:15:40 volumio volumio[1286]: info: Received Get System Info Apr 12 01:15:40 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 12 01:15:40 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 12 01:15:40 volumio volumio[1286]: info: Discovery: Getting this device information Apr 12 01:15:40 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:40 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:40 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 12 01:15:40 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:40 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:40 volumio volumio[1286]: info: Listing playlists Apr 12 01:15:40 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 12 01:15:40 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 01:15:40 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 12 01:15:40 volumio volumio[1286]: verbose: New Socket.io Connection to 192.168.0.70 from 192.168.0.40 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Apr 12 01:15:40 volumio volumio-remote-updater[803]: Test mode enabled Apr 12 01:15:40 volumio volumio-remote-updater[803]: Alpha mode disabled Apr 12 01:15:40 volumio volumio-remote-updater[803]: Alpha legacy test mode disabled Apr 12 01:15:40 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 12 01:15:40 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 12 01:15:40 volumio sudo[2259]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 12 01:15:40 volumio sudo[2259]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:15:40 volumio sudo[2261]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 12 01:15:40 volumio sudo[2261]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:15:40 volumio volumio[1286]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 12 01:15:40 volumio sudo[2259]: pam_unix(sudo:session): session closed for user root Apr 12 01:15:40 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 12 01:15:40 volumio sudo[2261]: pam_unix(sudo:session): session closed for user root Apr 12 01:15:41 volumio volumio[1286]: verbose: New Socket.io Connection to 192.168.0.70 from 192.168.0.40 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Apr 12 01:15:41 volumio volumio[1286]: verbose: New Socket.io Connection to 192.168.0.70 from 192.168.0.40 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Apr 12 01:15:41 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:41 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:41 volumio volumio[1286]: info: Received Get System Info Apr 12 01:15:41 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 12 01:15:41 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 12 01:15:41 volumio volumio[1286]: info: Discovery: Getting this device information Apr 12 01:15:41 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:41 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:41 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 12 01:15:41 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:41 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:41 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 12 01:15:41 volumio volumio[1286]: info: CoreCommandRouter::volumioGetVisibleSources Apr 12 01:15:41 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 12 01:15:41 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:41 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:41 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:41 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:41 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 12 01:15:41 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 12 01:15:41 volumio volumio[1286]: info: Received Get System Info Apr 12 01:15:41 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 12 01:15:41 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 12 01:15:41 volumio volumio[1286]: info: Discovery: Getting this device information Apr 12 01:15:41 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:41 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:41 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 12 01:15:41 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:41 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:41 volumio volumio[1286]: info: Listing playlists Apr 12 01:15:42 volumio sudo[2271]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 12 01:15:42 volumio sudo[2271]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:15:42 volumio sudo[2271]: pam_unix(sudo:session): session closed for user root Apr 12 01:15:42 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 12 01:15:42 volumio sudo[2269]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 12 01:15:42 volumio sudo[2269]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:15:42 volumio sudo[2269]: pam_unix(sudo:session): session closed for user root Apr 12 01:15:42 volumio volumio[1286]: verbose: New Socket.io Connection to 192.168.0.70 from 192.168.0.40 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Apr 12 01:15:43 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:43 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:43 volumio volumio[1286]: info: Received Get System Info Apr 12 01:15:43 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 12 01:15:43 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 12 01:15:43 volumio volumio[1286]: info: Discovery: Getting this device information Apr 12 01:15:43 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:43 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:43 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 12 01:15:43 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:43 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:43 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 12 01:15:43 volumio volumio[1286]: info: CoreCommandRouter::volumioGetVisibleSources Apr 12 01:15:43 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 12 01:15:43 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:43 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:43 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:43 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:43 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 12 01:15:43 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 12 01:15:43 volumio volumio[1286]: info: Received Get System Info Apr 12 01:15:43 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 12 01:15:43 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 12 01:15:43 volumio volumio[1286]: info: Discovery: Getting this device information Apr 12 01:15:43 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:43 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:43 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 12 01:15:43 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:43 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:43 volumio volumio[1286]: info: Listing playlists Apr 12 01:15:43 volumio volumio[1286]: info: Received Get System Info Apr 12 01:15:43 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 12 01:15:43 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 12 01:15:43 volumio volumio[1286]: info: Discovery: Getting this device information Apr 12 01:15:43 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:43 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:43 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 12 01:15:43 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:43 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:43 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 12 01:15:43 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:43 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:46 volumio volumio[1286]: info: MyVolumio token set successfully Apr 12 01:15:46 volumio volumio[1286]: info: MYVOLUMIO: Adding device Apr 12 01:15:46 volumio volumio[1286]: info: MYVOLUMIO: Evaluating Server Apr 12 01:15:46 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 12 01:15:47 volumio volumio[1286]: info: MyVolumio Plan changed: premium Apr 12 01:15:47 volumio volumio[1286]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Apr 12 01:15:47 volumio volumio[1286]: info: Removing browser output: myVolumio user plan is not superstar Apr 12 01:15:47 volumio volumio[1286]: info: Removing audio output: Apr 12 01:15:47 volumio volumio[1286]: info: MYVOLUMIO: Adding device Apr 12 01:15:47 volumio volumio[1286]: info: MYVOLUMIO: Evaluating Server Apr 12 01:15:48 volumio volumio[1286]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Apr 12 01:15:48 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 12 01:15:48 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 12 01:15:48 volumio volumio[1286]: info: Remote config written successfully Apr 12 01:15:48 volumio volumio[1286]: info: Starting Tunnel 1 Apr 12 01:15:48 volumio volumio[1286]: info: Starting Tunnel Connection Checker Apr 12 01:15:48 volumio volumio[1286]: info: Completed starting MyVolumio Plugin Apr 12 01:15:49 volumio volumio[1286]: info: MYVolumio Device enabled Apr 12 01:15:49 volumio volumio[1286]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Apr 12 01:15:49 volumio volumio[1286]: info: MyVolumio status changed Apr 12 01:15:49 volumio volumio[1286]: info: Streaming services startup Apr 12 01:15:49 volumio volumio[1286]: info: Starting Streaming Daemon Apr 12 01:15:49 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Apr 12 01:15:49 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Apr 12 01:15:49 volumio sudo[2322]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 12 01:15:49 volumio sudo[2322]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:15:49 volumio sudo[2322]: pam_unix(sudo:session): session closed for user root Apr 12 01:15:49 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 12 01:15:49 volumio volumio[1286]: error: Cannot start Volumio Streaming Daemon Apr 12 01:15:49 volumio volumio[1286]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 12 01:15:49 volumio volumio[1286]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 12 01:15:49 volumio volumio[1286]: info: Setting Geolocation for MyVolumio to eu4 Apr 12 01:15:49 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 01:15:49 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 01:15:49 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 01:15:50 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Apr 12 01:15:50 volumio volumio[1286]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Apr 12 01:15:50 volumio volumio[1286]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin bluetooth Apr 12 01:15:50 volumio volumio[1286]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin bluetooth Apr 12 01:15:50 volumio volumio[1286]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Apr 12 01:15:50 volumio volumio[1286]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Apr 12 01:15:55 volumio volumio[1286]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Apr 12 01:15:55 volumio sudo[2347]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Apr 12 01:15:55 volumio sudo[2347]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:15:55 volumio sudo[2347]: pam_unix(sudo:session): session closed for user root Apr 12 01:15:56 volumio volumio[1286]: info: MRS: MultiRoom plugin initialized Apr 12 01:15:56 volumio volumio[1286]: info: MRS: STOPPING SNAPCLIENT Apr 12 01:15:56 volumio volumio[1286]: info: MRS: Snap server stop Apr 12 01:15:56 volumio volumio[1286]: info: MRS: STOPPING volumioStreaming Apr 12 01:15:56 volumio sudo[2364]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Apr 12 01:15:56 volumio sudo[2364]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:15:56 volumio volumio[1286]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Apr 12 01:15:56 volumio sudo[2366]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Apr 12 01:15:56 volumio sudo[2366]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:15:56 volumio sudo[2369]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Apr 12 01:15:56 volumio sudo[2369]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:15:56 volumio sudo[2364]: pam_unix(sudo:session): session closed for user root Apr 12 01:15:56 volumio sudo[2371]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Apr 12 01:15:56 volumio sudo[2371]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:15:57 volumio volumio[1286]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Apr 12 01:15:57 volumio sudo[2371]: pam_unix(sudo:session): session closed for user root Apr 12 01:15:57 volumio sudo[2369]: pam_unix(sudo:session): session closed for user root Apr 12 01:15:57 volumio sudo[2366]: pam_unix(sudo:session): session closed for user root Apr 12 01:15:57 volumio volumio[1286]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Apr 12 01:15:57 volumio volumio[1286]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Apr 12 01:15:57 volumio volumio[1286]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Apr 12 01:15:57 volumio volumio[1286]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Apr 12 01:15:57 volumio volumio[1286]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Apr 12 01:15:57 volumio volumio[1286]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Apr 12 01:15:57 volumio volumio[1286]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Apr 12 01:15:57 volumio volumio[1286]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Apr 12 01:15:57 volumio volumio[1286]: info: Preparing to generate the ALSA configuration file Apr 12 01:15:57 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 12 01:15:57 volumio volumio[1286]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 12 01:15:57 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 12 01:15:57 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 12 01:15:57 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:15:57 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:15:57 volumio volumio[1286]: info: Updating MyVolumio device info Apr 12 01:15:57 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 01:15:57 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 01:15:57 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 01:15:57 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:15:57 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:15:57 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Apr 12 01:15:57 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Apr 12 01:15:57 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 12 01:15:57 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 12 01:15:57 volumio volumio[1286]: info: CoreCommandRouter::volumioGetBrowseSources Apr 12 01:15:57 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 12 01:15:57 volumio volumio[1286]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 12 01:15:57 volumio volumio[1286]: info: Reading ALSA contributions from plugins. Apr 12 01:15:57 volumio volumio[1286]: info: Setting Geolocation for MyVolumio to eu4 Apr 12 01:15:57 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 01:15:57 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 01:15:57 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 01:15:57 volumio volumio[1286]: info: MRS: Removed streaming files Apr 12 01:15:57 volumio volumio[1286]: info: MRS: volumioStreaming STOPPED Apr 12 01:15:57 volumio volumio[1286]: info: MRS: SNAPSERVER STOPPED Apr 12 01:15:57 volumio volumio[1286]: info: MRS: SNAPCLIENT STOPPED Apr 12 01:15:58 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 12 01:15:58 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 01:15:58 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 12 01:15:58 volumio volumio[1286]: info: Getting Alsa Cards List without I2S DAC Apr 12 01:15:58 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Apr 12 01:15:58 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 01:15:58 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 01:15:58 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 01:15:58 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 01:15:58 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 01:15:58 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 01:15:58 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 01:15:58 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 01:15:58 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 12 01:15:58 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 12 01:15:59 volumio volumio[1286]: info: Updating MyVolumio device info Apr 12 01:15:59 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 01:15:59 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 01:15:59 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 01:15:59 volumio volumio[1286]: info: Successfully Added MyVolumio device Apr 12 01:15:59 volumio volumio[1286]: info: Successfully Added MyVolumio device Apr 12 01:15:59 volumio volumio[1286]: info: Successfully Updated MyVolumio device Apr 12 01:15:59 volumio volumio[1286]: info: Successfully Updated MyVolumio device Apr 12 01:16:02 volumio volumio[1286]: info: Asound.conf file written Apr 12 01:16:02 volumio sudo[2411]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 12 01:16:02 volumio sudo[2411]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:16:02 volumio sudo[2411]: pam_unix(sudo:session): session closed for user root Apr 12 01:16:02 volumio volumio[1286]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Apr 12 01:16:02 volumio volumio[1286]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Apr 12 01:16:02 volumio volumio[1286]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Apr 12 01:16:02 volumio volumio[1286]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Apr 12 01:16:02 volumio volumio[1286]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:4 use case configuration -2 Apr 12 01:16:02 volumio volumio[1286]: info: Output device has changed, restarting MPD Apr 12 01:16:02 volumio volumio[1286]: info: Output device has changed, restarting Shairport Sync Apr 12 01:16:02 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:16:02 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 01:16:02 volumio sudo[2417]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 12 01:16:02 volumio sudo[2417]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:16:02 volumio sudo[2419]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 12 01:16:02 volumio sudo[2419]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:16:02 volumio sudo[2417]: pam_unix(sudo:session): session closed for user root Apr 12 01:16:02 volumio volumio[1286]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 12 01:16:02 volumio volumio[1286]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 12 01:16:02 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:16:03 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Apr 12 01:16:03 volumio volumio[1286]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Apr 12 01:16:03 volumio volumio[1286]: ------------------------------------ BT MESSAGE: [FUNC] onStart Apr 12 01:16:03 volumio volumio[1286]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service Apr 12 01:16:03 volumio volumio[1286]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp Apr 12 01:16:03 volumio volumio[1286]: ------------------------------------ BT MESSAGE: [metaCache] Created directory: /tmp/bluetooth-cache/ Apr 12 01:16:03 volumio volumio[1286]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. Apr 12 01:16:03 volumio systemd[1]: mpd.service: Deactivated successfully. Apr 12 01:16:03 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 12 01:16:03 volumio systemd[1]: mpd.service: Consumed 2.998s CPU time. Apr 12 01:16:03 volumio systemd[1]: mpd.socket: Deactivated successfully. Apr 12 01:16:03 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 12 01:16:03 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 12 01:16:03 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 12 01:16:03 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Apr 12 01:16:03 volumio volumio[1286]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Apr 12 01:16:03 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Apr 12 01:16:03 volumio volumio[1286]: info: MRS: Pushing multiroomSync output for this device Apr 12 01:16:03 volumio volumio[1286]: info: MRS: Pushing multiroomSync output Apr 12 01:16:03 volumio volumio[1286]: info: Adding audio output: Apr 12 01:16:03 volumio volumio[1286]: info: Adding audio output: Apr 12 01:16:03 volumio sudo[2429]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 12 01:16:03 volumio sudo[2429]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 12 01:16:03 volumio sudo[2429]: pam_unix(sudo:session): session closed for user root Apr 12 01:16:03 volumio volumio[1286]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 12 01:16:03 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:16:03 volumio bluetoothd[884]: Path / reserved for Adv Monitor app :1.26 Apr 12 01:16:03 volumio volumio[1286]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Apr 12 01:16:03 volumio volumio[1286]: info: Adding METAVOLUMIO REST API Endpoints Apr 12 01:16:03 volumio volumio[1286]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Apr 12 01:16:03 volumio volumio[1286]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Apr 12 01:16:03 volumio volumio[1286]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Apr 12 01:16:03 volumio volumio[1286]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Apr 12 01:16:03 volumio volumio[1286]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Apr 12 01:16:03 volumio volumio[1286]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Apr 12 01:16:03 volumio bluetoothd[884]: Adv Monitor app :1.26 disconnected from D-Bus Apr 12 01:16:03 volumio volumio[1286]: info: Preparing CD Folders Apr 12 01:16:03 volumio volumio[1286]: info: Adding CD REST API Endpoints Apr 12 01:16:03 volumio volumio[1286]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Apr 12 01:16:03 volumio volumio[1286]: info: Starting UDEV Watcher for CD Apr 12 01:16:03 volumio volumio[1286]: info: Detecting CD presence with UDEV Apr 12 01:16:03 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Apr 12 01:16:04 volumio mpd[2434]: 2026-04-12T01:16:04 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 12 01:16:04 volumio systemd[1]: Started mpd.service - Music Player Daemon. Apr 12 01:16:04 volumio sudo[2419]: pam_unix(sudo:session): session closed for user root Apr 12 01:16:09 volumio volumio[1286]: warn: [cd-plugin] cdspeedctl: device or media not ready Apr 12 01:16:09 volumio volumio[1286]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Apr 12 01:16:09 volumio volumio[1286]: info: Adding inputs REST Endpoints Apr 12 01:16:09 volumio volumio[1286]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Apr 12 01:16:09 volumio volumio[1286]: info: Scanning Audio Inputs Apr 12 01:16:09 volumio volumio[1286]: info: Checking against Known Cards name Apr 12 01:16:09 volumio volumio[1286]: info: Adding Server instance for streaming Apr 12 01:16:09 volumio volumio[1286]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Apr 12 01:16:09 volumio volumio[1286]: error: Hi Res Audio Failed Login: Missing Login Data Apr 12 01:16:09 volumio volumio[1286]: info: Adding HIGHRESAUDIO REST API Endpoints Apr 12 01:16:09 volumio volumio[1286]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Apr 12 01:16:09 volumio volumio[1286]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Apr 12 01:16:09 volumio volumio[1286]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Apr 12 01:16:09 volumio volumio[1286]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Apr 12 01:16:09 volumio volumio[1286]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Apr 12 01:16:09 volumio volumio[1286]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Apr 12 01:16:09 volumio volumio[1286]: info: QobuzConnect: Starting Qobuz Connect socket and service Apr 12 01:16:09 volumio volumio[1286]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Apr 12 01:16:09 volumio sudo[2451]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 12 01:16:09 volumio sudo[2451]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:16:09 volumio sudo[2453]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Apr 12 01:16:09 volumio sudo[2453]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:16:09 volumio volumio[1286]: info: Adding TIDAL REST API Endpoints Apr 12 01:16:09 volumio volumio[1286]: info: Stopping AccessToken refresher cron for QOBUZ Apr 12 01:16:09 volumio sudo[2451]: pam_unix(sudo:session): session closed for user root Apr 12 01:16:09 volumio sudo[2453]: pam_unix(sudo:session): session closed for user root Apr 12 01:16:09 volumio volumio[1286]: info: AccessToken refresher cron started for QOBUZ Apr 12 01:16:09 volumio volumio[1286]: info: Adding QOBUZ REST API Endpoints Apr 12 01:16:09 volumio sudo[2457]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Apr 12 01:16:09 volumio sudo[2457]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:16:09 volumio volumio[1286]: info: MRS: Getting audio outputs on start Apr 12 01:16:09 volumio volumio[1286]: info: MRS: Requesting all other devices output Apr 12 01:16:10 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Apr 12 01:16:10 volumio sudo[2457]: pam_unix(sudo:session): session closed for user root Apr 12 01:16:10 volumio volumio[1286]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered on Apr 12 01:16:10 volumio sudo[2475]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Apr 12 01:16:10 volumio sudo[2475]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:16:10 volumio volumio[1286]: info: MPD Permissions set Apr 12 01:16:10 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Apr 12 01:16:10 volumio sudo[2475]: pam_unix(sudo:session): session closed for user root Apr 12 01:16:10 volumio volumiobt[2480]: INFO [BTSTART] Ensuring Bluetooth directory exists... Apr 12 01:16:10 volumio sudo[2481]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Apr 12 01:16:10 volumio sudo[2481]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:16:10 volumio sudo[2481]: pam_unix(sudo:session): session closed for user root Apr 12 01:16:10 volumio sudo[2478]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Apr 12 01:16:10 volumio sudo[2478]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:16:10 volumio sudo[2484]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Apr 12 01:16:10 volumio sudo[2484]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:16:10 volumio sudo[2484]: pam_unix(sudo:session): session closed for user root Apr 12 01:16:10 volumio volumiobt[2487]: INFO [BTSTART] Powering on Bluetooth if needed... Apr 12 01:16:10 volumio volumio[1286]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 Apr 12 01:16:10 volumio bluetoothd[884]: Adv Monitor app :1.28 disconnected from D-Bus Apr 12 01:16:10 volumio volumiobt[2490]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Apr 12 01:16:10 volumio volumio[1286]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully Apr 12 01:16:10 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. Apr 12 01:16:10 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. Apr 12 01:16:10 volumio volumio[1286]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart Apr 12 01:16:10 volumio volumiobt[2491]: [176B blob data] Apr 12 01:16:10 volumio volumiobt[2491]: [157B blob data] Apr 12 01:16:10 volumio volumiobt[2491]: [157B blob data] Apr 12 01:16:10 volumio volumiobt[2491]: [157B blob data] Apr 12 01:16:10 volumio volumiobt[2491]: [113B blob data] Apr 12 01:16:10 volumio volumiobt[2491]: [bluetoothctl]> discoverable on Apr 12 01:16:10 volumio volumiobt[2491]: Warning: setting discoverable while discoverable-timeout not set(0) is not recommended Apr 12 01:16:10 volumio volumiobt[2491]: [bluetoothctl]> pairable on Apr 12 01:16:10 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:16:10 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:16:10 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:16:10 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:16:10 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:16:10 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:16:10 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:16:10 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:16:10 volumio bluetoothd[884]: Path / reserved for Adv Monitor app :1.29 Apr 12 01:16:10 volumio bluetoothd[884]: Adv Monitor app :1.29 disconnected from D-Bus Apr 12 01:16:10 volumio volumiobt[2491]: [bluetoothctl]> Apr 12 01:16:10 volumio volumiobt[2493]: INFO [BTSTART] Registering Bluetooth agent... Apr 12 01:16:10 volumio volumiobt[2494]: [NEW] Media /org/bluez/hci0 Apr 12 01:16:10 volumio volumiobt[2494]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Apr 12 01:16:10 volumio volumiobt[2494]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Apr 12 01:16:10 volumio volumiobt[2494]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Apr 12 01:16:10 volumio bluetoothd[884]: Path / reserved for Adv Monitor app :1.30 Apr 12 01:16:10 volumio bluetoothd[884]: Adv Monitor app :1.30 disconnected from D-Bus Apr 12 01:16:10 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Apr 12 01:16:10 volumio sudo[2478]: pam_unix(sudo:session): session closed for user root Apr 12 01:16:10 volumio volumiobt[2496]: No agent is registered Apr 12 01:16:10 volumio volumiobt[2496]: [NEW] Media /org/bluez/hci0 Apr 12 01:16:10 volumio volumiobt[2496]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Apr 12 01:16:10 volumio volumiobt[2496]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Apr 12 01:16:10 volumio volumiobt[2496]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Apr 12 01:16:10 volumio bluetoothd[884]: Adv Monitor app :1.31 disconnected from D-Bus Apr 12 01:16:10 volumio volumiobt[2499]: INFO [BTSTART] Agent registered successfully. Apr 12 01:16:10 volumio volumiobt[2500]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Apr 12 01:16:10 volumio autossh[2497]: port set to 0, monitoring disabled Apr 12 01:16:10 volumio autossh[2497]: starting ssh (count 1) Apr 12 01:16:10 volumio autossh[2497]: ssh child pid is 2507 Apr 12 01:16:11 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 01:16:11 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:16:11 volumio volumio[1286]: error: updateQueue error: null Apr 12 01:16:11 volumio volumiossh-tunnel[2507]: Warning: Permanently added '[eu4.myvolumio.org]:2222' (RSA) to the list of known hosts. Apr 12 01:16:11 volumio volumio[1286]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 15 Apr 12 01:16:11 volumio volumio[1286]: info: Remote SSH Started Apr 12 01:16:11 volumio volumiobt[2501]: 2026-04-12 01:16:11 a2dp-agent [INFO] Connecting to system D-Bus Apr 12 01:16:11 volumio volumiobt[2501]: 2026-04-12 01:16:11 a2dp-agent [INFO] Connected to system D-Bus Apr 12 01:16:11 volumio volumiobt[2501]: 2026-04-12 01:16:11 bluezutils [INFO] Found adapter at: /org/bluez/hci0 Apr 12 01:16:11 volumio volumiobt[2501]: 2026-04-12 01:16:11 a2dp-agent [INFO] Found Bluetooth adapter: /org/bluez/hci0 Apr 12 01:16:11 volumio volumiobt[2501]: 2026-04-12 01:16:11 a2dp-agent [INFO] Set DiscoverableTimeout to infinite Apr 12 01:16:11 volumio volumiobt[2501]: 2026-04-12 01:16:11 a2dp-agent [INFO] Enabled Discoverable mode Apr 12 01:16:11 volumio volumiobt[2501]: 2026-04-12 01:16:11 a2dp-agent [INFO] Agent registered at /local/a2dpagent Apr 12 01:16:11 volumio volumiobt[2501]: 2026-04-12 01:16:11 a2dp-agent [INFO] Agent set as default Apr 12 01:16:11 volumio volumiobt[2501]: 2026-04-12 01:16:11 a2dp-agent [INFO] A2DP agent running, waiting for connections... Apr 12 01:16:12 volumio volumio[1286]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 16 Apr 12 01:16:12 volumio volumio[1286]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 17 Apr 12 01:16:12 volumio volumio[1286]: info: Starting Shairport Sync Apr 12 01:16:12 volumio volumio[1286]: info: Preparing to generate the ALSA configuration file Apr 12 01:16:12 volumio sudo[2511]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 12 01:16:12 volumio sudo[2511]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:16:12 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 12 01:16:12 volumio volumio[1286]: info: MRS: Found cast device: SHIELD-Android-TV-6342eac47aaaecaa412ced02b70b9d3c Apr 12 01:16:12 volumio volumio[1286]: info: Adding audio output: Apr 12 01:16:12 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 12 01:16:12 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Apr 12 01:16:12 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 12 01:16:12 volumio systemd[1]: shairport-sync.service: Consumed 1.356s CPU time. Apr 12 01:16:12 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 12 01:16:13 volumio sudo[2511]: pam_unix(sudo:session): session closed for user root Apr 12 01:16:13 volumio volumio[1286]: info: CoreCommandRouter::volumioGetVisibleSources Apr 12 01:16:13 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 12 01:16:13 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:16:13 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:16:13 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 12 01:16:13 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 12 01:16:13 volumio volumio[1286]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Apr 12 01:16:13 volumio volumio[1286]: info: Received Get System Info Apr 12 01:16:13 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 12 01:16:13 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 12 01:16:13 volumio volumio[1286]: info: Discovery: Getting this device information Apr 12 01:16:13 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:16:13 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:16:13 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 12 01:16:13 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:16:13 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:16:13 volumio volumio[1286]: info: Listing playlists Apr 12 01:16:13 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 12 01:16:13 volumio volumio[1286]: info: Received Get System Info Apr 12 01:16:13 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 12 01:16:13 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 12 01:16:13 volumio volumio[1286]: info: Discovery: Getting this device information Apr 12 01:16:13 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:16:13 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:16:13 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 12 01:16:13 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 12 01:16:13 volumio volumio[1286]: info: CoreCommandRouter::volumioGetVisibleSources Apr 12 01:16:13 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 12 01:16:13 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:16:13 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:16:13 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 12 01:16:13 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 12 01:16:13 volumio volumio[1286]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Apr 12 01:16:13 volumio volumio[1286]: info: Received Get System Info Apr 12 01:16:13 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 12 01:16:13 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 12 01:16:13 volumio volumio[1286]: info: Discovery: Getting this device information Apr 12 01:16:13 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:16:13 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:16:13 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 12 01:16:13 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:16:13 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:16:13 volumio volumio[1286]: info: Listing playlists Apr 12 01:16:13 volumio volumio[1286]: info: TidalConnect service stoped! Apr 12 01:16:13 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 12 01:16:13 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 12 01:16:14 volumio volumio[1286]: info: Executing endpoint qc_getconfig Apr 12 01:16:14 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Apr 12 01:16:14 volumio qobuz-connect[2472]: 20260412 01:16:14.332 [2472.2472] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Apr 12 01:16:14 volumio volumio[1286]: info: Shairport-Sync Started Apr 12 01:16:14 volumio qobuz-connect[2472]: 20260412 01:16:14.512 [2472.2472] INFO VolumeManager: [0x21bb068]: Setting new playback volume: 75 Apr 12 01:16:14 volumio qobuz-connect[2472]: 20260412 01:16:14.512 [2472.2472] INFO VolumeManager: [0x21bb068]: Setting new mute state: 0 Apr 12 01:16:14 volumio qobuz-connect[2472]: 20260412 01:16:14.512 [2472.2472] INFO AudioStreamManager: [0x21badc0]: Setting new audio download buffer size: 1048576 Apr 12 01:16:14 volumio qobuz-connect[2472]: 20260412 01:16:14.512 [2472.2472] INFO QobuzConnect: [0x21bb930]: Client initialized! Apr 12 01:16:14 volumio qobuz-connect[2472]: 20260412 01:16:14.513 [2472.2472] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Apr 12 01:16:14 volumio qobuz-connect[2472]: 20260412 01:16:14.526 [2472.2472] INFO LocalConfigManager: [0x21baae8]: Starting Local Configuration server Apr 12 01:16:14 volumio qobuz-connect[2472]: 20260412 01:16:14.526 [2472.2472] INFO SampleApp: Starting Local configuration server Apr 12 01:16:14 volumio qobuz-connect[2472]: 20260412 01:16:14.527 [2472.2472] INFO SampleApp: Connected to UNIX socket client 0x21a5818 Apr 12 01:16:14 volumio qobuz-connect[2472]: 20260412 01:16:14.527 [2472.2472] INFO SampleApp: Playback volume changed: 75 Apr 12 01:16:14 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 12 01:16:14 volumio volumio[1286]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Apr 12 01:16:14 volumio volumio[1286]: info: QobuzConnect: QOBUZ Connect daemon connected Apr 12 01:16:14 volumio volumio[1286]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 18 Apr 12 01:16:14 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 12 01:16:14 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 12 01:16:14 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:16:14 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:16:15 volumio volumio[1286]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 12 01:16:15 volumio volumio[1286]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 12 01:16:15 volumio volumio[1286]: info: Asound.conf file written Apr 12 01:16:15 volumio sudo[2545]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 12 01:16:15 volumio sudo[2545]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:16:15 volumio sudo[2545]: pam_unix(sudo:session): session closed for user root Apr 12 01:16:15 volumio volumio[1286]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Apr 12 01:16:15 volumio volumio[1286]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Apr 12 01:16:15 volumio volumio[1286]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Apr 12 01:16:15 volumio volumio[1286]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Apr 12 01:16:15 volumio volumio[1286]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:4 use case configuration -2 Apr 12 01:16:15 volumio volumio[1286]: info: Output device has changed, restarting MPD Apr 12 01:16:15 volumio volumio[1286]: info: Output device has changed, restarting Shairport Sync Apr 12 01:16:15 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:16:15 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 01:16:15 volumio sudo[2551]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 12 01:16:15 volumio sudo[2551]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:16:15 volumio sudo[2551]: pam_unix(sudo:session): session closed for user root Apr 12 01:16:15 volumio sudo[2553]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 12 01:16:15 volumio sudo[2553]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:16:15 volumio volumio[1286]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 12 01:16:15 volumio volumio[1286]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 12 01:16:15 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:16:15 volumio volumio[1286]: info: QobuzConnect: setDeactiveState invoked Apr 12 01:16:15 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:16:15 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:16:15 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Apr 12 01:16:16 volumio volumio[1286]: info: Preparing to generate the ALSA configuration file Apr 12 01:16:16 volumio systemd[1]: mpd.service: Deactivated successfully. Apr 12 01:16:16 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 12 01:16:16 volumio systemd[1]: mpd.service: Consumed 1.601s CPU time. Apr 12 01:16:16 volumio systemd[1]: mpd.socket: Deactivated successfully. Apr 12 01:16:16 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 12 01:16:16 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 12 01:16:16 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 12 01:16:16 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Apr 12 01:16:16 volumio sudo[2566]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 12 01:16:16 volumio sudo[2566]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:16:16 volumio sudo[2564]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 12 01:16:16 volumio sudo[2564]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:16:16 volumio sudo[2568]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 12 01:16:16 volumio sudo[2568]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 12 01:16:16 volumio sudo[2568]: pam_unix(sudo:session): session closed for user root Apr 12 01:16:16 volumio volumio[1286]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 12 01:16:16 volumio volumio[1286]: info: Reading ALSA contributions from plugins. Apr 12 01:16:16 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 12 01:16:16 volumio sudo[2564]: pam_unix(sudo:session): session closed for user root Apr 12 01:16:16 volumio sudo[2577]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Apr 12 01:16:16 volumio sudo[2577]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:16:16 volumio sudo[2566]: pam_unix(sudo:session): session closed for user root Apr 12 01:16:16 volumio volumio[1286]: info: MPD Permissions set Apr 12 01:16:16 volumio sudo[2577]: pam_unix(sudo:session): session closed for user root Apr 12 01:16:16 volumio sudo[2583]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 12 01:16:16 volumio sudo[2583]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:16:16 volumio sudo[2582]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Apr 12 01:16:16 volumio sudo[2582]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:16:16 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:16:16 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:16:16 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Apr 12 01:16:16 volumio sudo[2583]: pam_unix(sudo:session): session closed for user root Apr 12 01:16:16 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Apr 12 01:16:16 volumio qobuz-connect[2472]: 20260412 01:16:16.955 [2472.2472] INFO SampleApp: Stopping Local configuration server Apr 12 01:16:16 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:16:16 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:16:16 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:16:16 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:16:16 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:16:16 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:16:16 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:16:16 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:16:17 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 01:16:17 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:16:17 volumio volumio[1286]: info: Starting Shairport Sync Apr 12 01:16:17 volumio volumio[1286]: info: Asound.conf file written Apr 12 01:16:17 volumio sudo[2595]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 12 01:16:17 volumio sudo[2595]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:16:17 volumio sudo[2598]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 12 01:16:17 volumio sudo[2598]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:16:17 volumio mpd[2576]: 2026-04-12T01:16:17 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 12 01:16:17 volumio systemd[1]: Started mpd.service - Music Player Daemon. Apr 12 01:16:17 volumio sudo[2553]: pam_unix(sudo:session): session closed for user root Apr 12 01:16:17 volumio sudo[2598]: pam_unix(sudo:session): session closed for user root Apr 12 01:16:17 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 12 01:16:17 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Apr 12 01:16:17 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 12 01:16:17 volumio systemd[1]: shairport-sync.service: Consumed 1.256s CPU time. Apr 12 01:16:17 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 12 01:16:17 volumio sudo[2595]: pam_unix(sudo:session): session closed for user root Apr 12 01:16:18 volumio nmbd[1170]: [2026/04/12 01:16:18.001865, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Apr 12 01:16:18 volumio nmbd[1170]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.0.70 for name WORKGROUP<1d>. Apr 12 01:16:18 volumio nmbd[1170]: This response was from IP 192.168.0.81, reporting an IP address of 192.168.0.81. Apr 12 01:16:18 volumio volumio[1286]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Apr 12 01:16:18 volumio volumio[1286]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Apr 12 01:16:18 volumio volumio[1286]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Apr 12 01:16:18 volumio volumio[1286]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Apr 12 01:16:18 volumio volumio[1286]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:4 use case configuration -2 Apr 12 01:16:18 volumio volumio[1286]: info: Output device has changed, restarting MPD Apr 12 01:16:18 volumio volumio[1286]: info: Output device has changed, restarting Shairport Sync Apr 12 01:16:18 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:16:18 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 01:16:18 volumio volumio[1286]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 12 01:16:18 volumio volumio[1286]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 12 01:16:18 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:16:18 volumio volumio[1286]: info: QobuzConnect: setDeactiveState invoked Apr 12 01:16:18 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:16:18 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:16:18 volumio qobuz-connect[2472]: 20260412 01:16:18.546 [2472.2472] INFO SampleApp: shat down connection on UNIX socket Apr 12 01:16:18 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Apr 12 01:16:18 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Apr 12 01:16:18 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Apr 12 01:16:18 volumio sudo[2582]: pam_unix(sudo:session): session closed for user root Apr 12 01:16:18 volumio volumio[1286]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Apr 12 01:16:18 volumio volumio[1286]: info: QobuzConnect: setDeactiveState invoked Apr 12 01:16:18 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:16:18 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:16:18 volumio volumio[1286]: info: Shairport-Sync Started Apr 12 01:16:18 volumio volumio[1286]: error: MyVolumio Plugins failed to start in a timely fashion Apr 12 01:16:18 volumio volumio[1286]: error: Plugin music_service tidalconnect failed to complete 'onStart' in a timely fashion Apr 12 01:16:18 volumio volumio[1286]: [Metrics] CommandRouter: 83s 890.24ms Apr 12 01:16:18 volumio volumio[1286]: info: CoreCommandRouter::volumiosetStartupVolume Apr 12 01:16:18 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:16:18 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 01:16:19 volumio volumio[1286]: info: CoreCommandRouter::Close All Modals sent Apr 12 01:16:19 volumio volumio[1286]: info: CoreCommandRouter::Close All Modals sent Apr 12 01:16:19 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:16:19 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:16:19 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:16:19 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:16:19 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:16:19 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:16:19 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:16:19 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:16:19 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 01:16:19 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 01:16:19 volumio volumio[1286]: error: updateQueue error: null Apr 12 01:16:19 volumio volumio[1286]: info: Executing endpoint qc_getconfig Apr 12 01:16:19 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Apr 12 01:16:19 volumio qobuz-connect[2640]: 20260412 01:16:19.437 [2640.2640] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Apr 12 01:16:19 volumio qobuz-connect[2640]: 20260412 01:16:19.441 [2640.2640] INFO VolumeManager: [0x23f3068]: Setting new playback volume: 75 Apr 12 01:16:19 volumio qobuz-connect[2640]: 20260412 01:16:19.441 [2640.2640] INFO VolumeManager: [0x23f3068]: Setting new mute state: 0 Apr 12 01:16:19 volumio qobuz-connect[2640]: 20260412 01:16:19.441 [2640.2640] INFO AudioStreamManager: [0x23f2dc0]: Setting new audio download buffer size: 1048576 Apr 12 01:16:19 volumio qobuz-connect[2640]: 20260412 01:16:19.441 [2640.2640] INFO QobuzConnect: [0x23f3930]: Client initialized! Apr 12 01:16:19 volumio qobuz-connect[2640]: 20260412 01:16:19.441 [2640.2640] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Apr 12 01:16:19 volumio qobuz-connect[2640]: 20260412 01:16:19.452 [2640.2640] INFO LocalConfigManager: [0x23f2ae8]: Starting Local Configuration server Apr 12 01:16:19 volumio qobuz-connect[2640]: 20260412 01:16:19.452 [2640.2640] INFO SampleApp: Starting Local configuration server Apr 12 01:16:19 volumio qobuz-connect[2640]: 20260412 01:16:19.453 [2640.2640] INFO SampleApp: Connected to UNIX socket client 0x23dd818 Apr 12 01:16:19 volumio volumio[1286]: info: TidalConnect service stoped! Apr 12 01:16:19 volumio volumio[1286]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Apr 12 01:16:19 volumio volumio[1286]: info: QobuzConnect: QOBUZ Connect daemon connected Apr 12 01:16:19 volumio volumio[1286]: info: TidalConnect service stoped! Apr 12 01:16:19 volumio volumio[1286]: info: Starting Shairport Sync Apr 12 01:16:19 volumio qobuz-connect[2640]: 20260412 01:16:19.591 [2640.2640] INFO SampleApp: Playback volume changed: 75 Apr 12 01:16:19 volumio volumio[1286]: info: CoreCommandRouter::volumioGetState Apr 12 01:16:19 volumio volumio[1286]: info: CorePlayQueue::getTrack 0 Apr 12 01:16:19 volumio volumio[1286]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 12 01:16:19 volumio volumio[1286]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 12 01:16:20 volumio volumio[1286]: info: TidalConnect service started! Apr 12 01:16:20 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 12 01:16:20 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 12 01:16:20 volumio volumio[1286]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 12 01:16:20 volumio volumio[1286]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 19 Apr 12 01:16:21 volumio sudo[2625]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 12 01:16:21 volumio sudo[2625]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:16:21 volumio sudo[2631]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 12 01:16:21 volumio sudo[2631]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:16:21 volumio sudo[2641]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Apr 12 01:16:21 volumio sudo[2641]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:16:21 volumio sudo[2636]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 12 01:16:21 volumio sudo[2636]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:16:21 volumio sudo[2659]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 12 01:16:21 volumio sudo[2659]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:16:21 volumio sudo[2628]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 12 01:16:21 volumio sudo[2628]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:16:21 volumio sudo[2665]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 12 01:16:21 volumio sudo[2665]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:16:21 volumio sudo[2641]: pam_unix(sudo:session): session closed for user root Apr 12 01:16:21 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Apr 12 01:16:21 volumio systemd[1]: mpd.service: Deactivated successfully. Apr 12 01:16:21 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 12 01:16:21 volumio systemd[1]: mpd.service: Consumed 1.397s CPU time. Apr 12 01:16:21 volumio systemd[1]: mpd.socket: Deactivated successfully. Apr 12 01:16:21 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 12 01:16:21 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 12 01:16:21 volumio sudo[2687]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Apr 12 01:16:21 volumio sudo[2687]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 01:16:21 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 12 01:16:21 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Apr 12 01:16:21 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 12 01:16:21 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Apr 12 01:16:21 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Apr 12 01:16:21 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 12 01:16:21 volumio systemd[1]: shairport-sync.service: Consumed 1.298s CPU time. Apr 12 01:16:21 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 12 01:16:21 volumio sudo[2636]: pam_unix(sudo:session): session closed for user root Apr 12 01:16:21 volumio sudo[2659]: pam_unix(sudo:session): session closed for user root Apr 12 01:16:21 volumio sudo[2631]: pam_unix(sudo:session): session closed for user root Apr 12 01:16:21 volumio sudo[2692]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 12 01:16:21 volumio sudo[2692]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 12 01:16:21 volumio sudo[2692]: pam_unix(sudo:session): session closed for user root Apr 12 01:16:21 volumio sudo[2628]: pam_unix(sudo:session): session closed for user root Apr 12 01:16:21 volumio qobuz-connect[2640]: 20260412 01:16:21.699 [2640.2640] INFO SampleApp: Stopping Local configuration server Apr 12 01:16:21 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Apr 12 01:16:21 volumio volumio[1286]: info: Shairport-Sync Started Apr 12 01:16:21 volumio volumio[1286]: error: Error starting TidalConnect Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Apr 12 01:16:21 volumio volumio[1286]: Job for vtcs.service canceled. Apr 12 01:16:21 volumio volumio[1286]: {"cmd":"/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3","code":1,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3\nJob for vtcs.service canceled.\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)","stderr":"Job for vtcs.service canceled.\n","stdout":""} Apr 12 01:16:21 volumio volumio[1286]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 12 01:16:21 volumio volumio[1286]: Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Apr 12 01:16:21 volumio volumio[1286]: Job for vtcs.service canceled. Apr 12 01:16:21 volumio volumio[1286]: at ChildProcess.exithandler (node:child_process:421:12) Apr 12 01:16:21 volumio volumio[1286]: at ChildProcess.emit (node:events:514:28) Apr 12 01:16:21 volumio volumio[1286]: at maybeClose (node:internal/child_process:1105:16) Apr 12 01:16:21 volumio volumio[1286]: at Socket. (node:internal/child_process:457:11) Apr 12 01:16:21 volumio volumio[1286]: at Socket.emit (node:events:514:28) Apr 12 01:16:21 volumio volumio[1286]: at Pipe. (node:net:337:12) { Apr 12 01:16:21 volumio volumio[1286]: code: 1, Apr 12 01:16:21 volumio volumio[1286]: killed: false, Apr 12 01:16:21 volumio volumio[1286]: signal: null, Apr 12 01:16:21 volumio volumio[1286]: cmd: '/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3', Apr 12 01:16:21 volumio volumio[1286]: stdout: '', Apr 12 01:16:21 volumio volumio[1286]: stderr: 'Job for vtcs.service canceled.\n' Apr 12 01:16:21 volumio volumio[1286]: } Apr 12 01:16:21 volumio volumio[1286]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 12 01:16:22 volumio systemd[1]: vtcs.service: Deactivated successfully. Apr 12 01:16:22 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Apr 12 01:16:22 volumio sudo[2665]: pam_unix(sudo:session): session closed for user root Apr 12 01:16:23 volumio qobuz-connect[2640]: 20260412 01:16:23.464 [2640.2640] INFO SampleApp: shat down connection on UNIX socket Apr 12 01:16:23 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Apr 12 01:16:23 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Apr 12 01:16:23 volumio mpd[2710]: 2026-04-12T01:16:23 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 12 01:16:23 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Apr 12 01:16:23 volumio systemd[1]: Started mpd.service - Music Player Daemon. Apr 12 01:16:23 volumio sudo[2687]: pam_unix(sudo:session): session closed for user root Apr 12 01:16:23 volumio sudo[2625]: pam_unix(sudo:session): session closed for user root Apr 12 01:16:26 volumio sudo[2734]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-12 01:15' Apr 12 01:16:26 volumio sudo[2734]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"