Jan 13 11:46:59 volumio22 ntpd[1039]: CLOCK: time stepped by 37225.394071 Jan 13 11:46:59 volumio22 ntpd[1039]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Jan 13 11:46:59 volumio22 systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Jan 13 11:46:59 volumio22 systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Jan 13 11:47:00 volumio22 volumio[1278]: info: Loading plugin "network"... Jan 13 11:47:00 volumio22 volumio[1278]: info: Refreshing Cached IP Addresses Jan 13 11:47:00 volumio22 sudo[1366]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 13 11:47:00 volumio22 sudo[1367]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 13 11:47:00 volumio22 sudo[1367]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 11:47:00 volumio22 sudo[1366]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 11:47:00 volumio22 volumio[1278]: info: Loading plugin "services"... Jan 13 11:47:00 volumio22 sudo[1366]: pam_unix(sudo:session): session closed for user root Jan 13 11:47:00 volumio22 volumio[1278]: info: Loading plugin "alsa_controller"... Jan 13 11:47:00 volumio22 sudo[1367]: pam_unix(sudo:session): session closed for user root Jan 13 11:47:00 volumio22 sudo[1374]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 13 11:47:00 volumio22 sudo[1374]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 11:47:02 volumio22 volumio[1278]: warn: Unable to locate the audio output device FIIO K17. Please configure a valid output device. Jan 13 11:47:02 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 11:47:02 volumio22 volumio[1278]: info: Loading plugin "wizard"... Jan 13 11:47:02 volumio22 volumio[1278]: info: Loading plugin "networkfs"... Jan 13 11:47:02 volumio22 volumio[1278]: info: Starting Udev Watcher for removable devices Jan 13 11:47:02 volumio22 sudo[1396]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=22pid,password=Vayut12345,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.22/music /mnt/NAS/NAS Jan 13 11:47:02 volumio22 sudo[1396]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 11:47:02 volumio22 volumio[1278]: info: Ignoring mount for partition: boot Jan 13 11:47:02 volumio22 volumio[1278]: info: Ignoring mount for partition: volumio Jan 13 11:47:02 volumio22 volumio[1278]: info: Ignoring mount for partition: volumio_data Jan 13 11:47:02 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 13 11:47:02 volumio22 volumio[1278]: info: Loading plugin "volumio_command_line_client"... Jan 13 11:47:02 volumio22 volumio[1278]: info: Loading plugin "upnp"... Jan 13 11:47:02 volumio22 volumio[1278]: info: [1768279622661] Starting Upmpd Daemon Jan 13 11:47:02 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 13 11:47:02 volumio22 volumio[1278]: info: Loading plugin "my_music"... Jan 13 11:47:02 volumio22 systemd[1]: systemd-fsckd.service: Deactivated successfully. Jan 13 11:47:02 volumio22 kernel: netfs: FS-Cache loaded Jan 13 11:47:02 volumio22 volumio[1278]: info: Loading plugin "mpd"... Jan 13 11:47:02 volumio22 kernel: Key type cifs.spnego registered Jan 13 11:47:02 volumio22 kernel: Key type cifs.idmap registered Jan 13 11:47:02 volumio22 kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Jan 13 11:47:02 volumio22 kernel: CIFS: Attempting to mount //192.168.1.22/music Jan 13 11:47:03 volumio22 volumio[1278]: info: Loading plugin "upnp_browser"... Jan 13 11:47:03 volumio22 sudo[1396]: pam_unix(sudo:session): session closed for user root Jan 13 11:47:03 volumio22 dhcpcd[680]: timed out Jan 13 11:47:03 volumio22 sh[661]: timed out Jan 13 11:47:03 volumio22 dhcpcd[680]: dhcpcd exited Jan 13 11:47:03 volumio22 sh[615]: ifup: failed to bring up eth0 Jan 13 11:47:03 volumio22 systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Jan 13 11:47:03 volumio22 systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Jan 13 11:47:04 volumio22 sudo[1374]: pam_unix(sudo:session): session closed for user root Jan 13 11:47:04 volumio22 volumio[1278]: info: Starting UPNP Browser Jan 13 11:47:04 volumio22 volumio[1278]: info: Loading plugin "alarm-clock"... Jan 13 11:47:04 volumio22 volumio[1278]: info: Loading plugin "airplay_emulation"... Jan 13 11:47:04 volumio22 volumio[1278]: info: Starting Shairport Sync Jan 13 11:47:04 volumio22 volumio[1278]: info: Loading plugin "last_100"... Jan 13 11:47:04 volumio22 volumio[1278]: info: Loading plugin "webradio"... Jan 13 11:47:04 volumio22 volumio[1278]: info: Loading plugin "i2s_dacs"... Jan 13 11:47:04 volumio22 volumio[1278]: info: I2S DAC not set, start Auto-detection Jan 13 11:47:04 volumio22 volumio[1278]: info: Loading plugin "volumiodiscovery"... Jan 13 11:47:04 volumio22 volumio[1278]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 13 11:47:04 volumio22 volumio[1278]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 11:47:04 volumio22 volumio[1278]: *** WARNING *** For more information see Jan 13 11:47:04 volumio22 volumio[1278]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 13 11:47:04 volumio22 volumio[1278]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 11:47:04 volumio22 volumio[1278]: *** WARNING *** For more information see Jan 13 11:47:04 volumio22 node[1278]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 13 11:47:04 volumio22 node[1278]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 11:47:04 volumio22 node[1278]: *** WARNING *** For more information see Jan 13 11:47:04 volumio22 node[1278]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 13 11:47:04 volumio22 node[1278]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 11:47:04 volumio22 node[1278]: *** WARNING *** For more information see Jan 13 11:47:04 volumio22 volumio[1278]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 13 11:47:04 volumio22 volumio[1278]: info: Discovery: Started advertising with name: Volumio22 Jan 13 11:47:04 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 13 11:47:04 volumio22 volumio[1278]: info: Loading plugin "spop"... Jan 13 11:47:05 volumio22 systemd[1]: systemd-hostnamed.service: Deactivated successfully. Jan 13 11:47:05 volumio22 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Jan 13 11:47:05 volumio22 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 13 11:47:06 volumio22 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 13 11:47:06 volumio22 upmpdcli[1440]: Could not open config: /tmp/upmpdcli.conf Jan 13 11:47:06 volumio22 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 13 11:47:06 volumio22 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 13 11:47:06 volumio22 volumio[1278]: info: Loading plugin "outputs"... Jan 13 11:47:06 volumio22 volumio[1278]: info: Loading plugin "albumart"... Jan 13 11:47:06 volumio22 volumio[1278]: info: Plugin example_plugin is not enabled Jan 13 11:47:06 volumio22 volumio[1278]: info: Loading plugin "inputs"... Jan 13 11:47:06 volumio22 volumio[1278]: info: Loading plugin "updater_comm"... Jan 13 11:47:07 volumio22 volumio[1278]: info: Plugin mpdemulation is not enabled Jan 13 11:47:07 volumio22 volumio[1278]: info: Loading plugin "rest_api"... Jan 13 11:47:07 volumio22 volumio[1278]: info: Loading plugin "websocket"... Jan 13 11:47:07 volumio22 volumio[1278]: info: Starting Socket.io Server version 1.7.4 Jan 13 11:47:07 volumio22 volumio[1278]: info: Plugin peppymeterbasic is not enabled Jan 13 11:47:07 volumio22 volumio[1278]: info: Plugin peppyspectrum is not enabled Jan 13 11:47:07 volumio22 volumio[1278]: info: Loading plugin "touch_display"... Jan 13 11:47:07 volumio22 volumio[1278]: info: Applying required configuration parameters for plugin touch_display Jan 13 11:47:07 volumio22 volumio[1441]: Forking 3 albumart workers Jan 13 11:47:07 volumio22 volumio[1278]: info: Loading i18n strings for locale en Jan 13 11:47:08 volumio22 volumio[1278]: Updating browse sources language Jan 13 11:47:08 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 11:47:09 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 11:47:09 volumio22 volumio[1278]: info: CoreCommandRouter::initPlayerControls Jan 13 11:47:09 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:47:09 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:47:09 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:47:09 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:47:09 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:47:09 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:47:09 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:47:09 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:47:09 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 11:47:09 volumio22 volumio[1278]: Express server listening on port 3000 Jan 13 11:47:09 volumio22 volumio[1278]: [Metrics] WebUI: 13s 262.12ms Jan 13 11:47:09 volumio22 volumio[1278]: info: CoreStateMachine::resetVolumioState Jan 13 11:47:09 volumio22 volumio[1278]: info: CoreStateMachine::getcurrentVolume Jan 13 11:47:09 volumio22 volumio[1278]: info: CoreCommandRouter::volumioRetrievevolume Jan 13 11:47:09 volumio22 volumio[1278]: info: CoreStateMachine::pushState Jan 13 11:47:09 volumio22 volumio[1278]: info: CorePlayQueue::getTrack 0 Jan 13 11:47:09 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 11:47:09 volumio22 volumio[1278]: info: CoreCommandRouter::volumioPushState Jan 13 11:47:09 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 11:47:10 volumio22 volumio[1278]: info: Volumio Network Manager: Network status updated: 2 Jan 13 11:47:10 volumio22 volumio[1278]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Jan 13 11:47:11 volumio22 volumio[1278]: info: CoreStateMachine::setRepeat null single undefined Jan 13 11:47:11 volumio22 volumio[1278]: info: CoreStateMachine::pushState Jan 13 11:47:11 volumio22 volumio[1278]: info: CorePlayQueue::getTrack 0 Jan 13 11:47:11 volumio22 volumio[1278]: info: CoreCommandRouter::volumioPushState Jan 13 11:47:11 volumio22 volumio[1278]: info: CoreStateMachine::setRandom null Jan 13 11:47:11 volumio22 volumio[1278]: info: CoreStateMachine::pushState Jan 13 11:47:11 volumio22 volumio[1278]: info: CorePlayQueue::getTrack 0 Jan 13 11:47:11 volumio22 volumio[1278]: info: CoreCommandRouter::volumioPushState Jan 13 11:47:11 volumio22 volumio[1278]: info: Setting Device type: Raspberry PI Jan 13 11:47:11 volumio22 volumio[1278]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jan 13 11:47:11 volumio22 volumio[1278]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03114 Jan 13 11:47:11 volumio22 volumio[1278]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jan 13 11:47:11 volumio22 volumio[1278]: info: Discovery: adding 2a570e84-7347-4089-8591-0996e6cc5692 Jan 13 11:47:11 volumio22 volumio[1278]: info: Discovery: Found device Volumio22 Jan 13 11:47:11 volumio22 volumio[1278]: info: CoreCommandRouter::volumioGetState Jan 13 11:47:11 volumio22 volumio[1278]: info: CorePlayQueue::getTrack 0 Jan 13 11:47:11 volumio22 volumio[1278]: info: Completed loading Core Plugins Jan 13 11:47:11 volumio22 volumio[1278]: info: Preparing to generate the ALSA configuration file Jan 13 11:47:11 volumio22 volumio[1278]: info: Discovery: this is already registered, 2a570e84-7347-4089-8591-0996e6cc5692 Jan 13 11:47:11 volumio22 volumio[1278]: info: Discovery: Found device Volumio22 Jan 13 11:47:11 volumio22 volumio[1278]: info: CoreCommandRouter::volumioGetState Jan 13 11:47:11 volumio22 volumio[1278]: info: CorePlayQueue::getTrack 0 Jan 13 11:47:11 volumio22 volumio[1278]: info: Asound.conf file unchanged, so no further update is needed Jan 13 11:47:11 volumio22 volumio[1278]: info: Output device has changed, restarting MPD Jan 13 11:47:11 volumio22 volumio[1278]: info: Output device has changed, restarting Shairport Sync Jan 13 11:47:11 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:47:11 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 11:47:12 volumio22 sudo[1493]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 13 11:47:12 volumio22 sudo[1493]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 11:47:12 volumio22 sudo[1493]: pam_unix(sudo:session): session closed for user root Jan 13 11:47:12 volumio22 volumio[1278]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 11:47:12 volumio22 volumio[1278]: info: ___________ START PLUGINS ___________ Jan 13 11:47:12 volumio22 sudo[1495]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 13 11:47:12 volumio22 sudo[1495]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 11:47:12 volumio22 volumio[1278]: info: ControllerMpd::onStart: Initializing MPD Jan 13 11:47:12 volumio22 volumio[1278]: info: Creating MPD Configuration file Jan 13 11:47:12 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 11:47:12 volumio22 volumio[1278]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 11:47:12 volumio22 volumio[1278]: info: [1768279632307] CoreMusicLibrary::Adding element Media Servers Jan 13 11:47:12 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 11:47:12 volumio22 sudo[1503]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 13 11:47:12 volumio22 sudo[1503]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 11:47:12 volumio22 sudo[1505]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 13 11:47:12 volumio22 volumio[1278]: info: UPNP Browser: Client initialized successfully Jan 13 11:47:12 volumio22 sudo[1505]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 11:47:12 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:47:12 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 11:47:12 volumio22 sudo[1503]: pam_unix(sudo:session): session closed for user root Jan 13 11:47:12 volumio22 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 13 11:47:12 volumio22 systemd[1]: Starting mpd.service - Music Player Daemon... Jan 13 11:47:12 volumio22 systemd[1]: mpd.service: Deactivated successfully. Jan 13 11:47:12 volumio22 systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 13 11:47:12 volumio22 systemd[1]: mpd.socket: Deactivated successfully. Jan 13 11:47:12 volumio22 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 13 11:47:12 volumio22 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 13 11:47:12 volumio22 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 13 11:47:12 volumio22 systemd[1]: Starting mpd.service - Music Player Daemon... Jan 13 11:47:12 volumio22 volumio-remote-updater[736]: [2026-01-13 11:47:12] [connect] Successful connection Jan 13 11:47:12 volumio22 sudo[1517]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 13 11:47:12 volumio22 sudo[1517]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 13 11:47:12 volumio22 sudo[1523]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jan 13 11:47:12 volumio22 sudo[1517]: pam_unix(sudo:session): session closed for user root Jan 13 11:47:12 volumio22 volumio[1278]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 11:47:12 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:47:12 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 11:47:12 volumio22 volumio[1278]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 11:47:12 volumio22 volumio[1278]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 11:47:12 volumio22 volumio[1278]: info: [1768279632807] CoreMusicLibrary::Adding element Last_100 Jan 13 11:47:12 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 11:47:12 volumio22 volumio[1278]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 11:47:12 volumio22 volumio[1278]: info: [1768279632863] CoreMusicLibrary::Adding element Webradio Jan 13 11:47:12 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 11:47:12 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 11:47:12 volumio22 volumio[1278]: info: Initializing BBC Radios Jan 13 11:47:13 volumio22 volumio[1452]: Starting albumart workers Jan 13 11:47:13 volumio22 volumio[1453]: Starting albumart workers Jan 13 11:47:13 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 11:47:13 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 11:47:13 volumio22 volumio[1278]: info: Creating Spotify config file Jan 13 11:47:13 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:47:13 volumio22 volumio[1278]: info: Loading i18n strings for locale en Jan 13 11:47:13 volumio22 volumio[1451]: Starting albumart workers Jan 13 11:47:13 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 11:47:13 volumio22 sudo[1551]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /data/volumiokioskextensions/IframeKeyboardBridge Jan 13 11:47:13 volumio22 sudo[1551]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 11:47:13 volumio22 volumio[1278]: info: Volumio Calling Home Jan 13 11:47:13 volumio22 sudo[1551]: pam_unix(sudo:session): session closed for user root Jan 13 11:47:14 volumio22 sudo[1565]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Jan 13 11:47:14 volumio22 sudo[1565]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 11:47:14 volumio22 systemd[1]: Reloading. Jan 13 11:47:14 volumio22 sudo[1563]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Jan 13 11:47:14 volumio22 sudo[1563]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 11:47:14 volumio22 sudo[1568]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jan 13 11:47:14 volumio22 sudo[1568]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 11:47:15 volumio22 systemd[1]: Reloading. Jan 13 11:47:16 volumio22 sudo[1565]: pam_unix(sudo:session): session closed for user root Jan 13 11:47:17 volumio22 sudo[1568]: pam_unix(sudo:session): session closed for user root Jan 13 11:47:17 volumio22 systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Jan 13 11:47:17 volumio22 sudo[1563]: pam_unix(sudo:session): session closed for user root Jan 13 11:47:17 volumio22 mpd[1524]: 2026-01-13T11:47:17 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 13 11:47:18 volumio22 systemd[1]: Started mpd.service - Music Player Daemon. Jan 13 11:47:18 volumio22 sudo[1505]: pam_unix(sudo:session): session closed for user root Jan 13 11:47:18 volumio22 sudo[1495]: pam_unix(sudo:session): session closed for user root Jan 13 11:47:18 volumio22 sudo[1634]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 13 11:47:18 volumio22 volumio[1278]: info: touch_display: No backlight interface detected. Jan 13 11:47:18 volumio22 sudo[1636]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 13 11:47:18 volumio22 sudo[1636]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 11:47:18 volumio22 sudo[1634]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 11:47:18 volumio22 sudo[1636]: pam_unix(sudo:session): session closed for user root Jan 13 11:47:18 volumio22 sudo[1634]: pam_unix(sudo:session): session closed for user root Jan 13 11:47:18 volumio22 volumio[1278]: info: touch_display: systemctl daemon-reload succeeded. Jan 13 11:47:18 volumio22 volumio[1278]: info: touch_display: systemctl disable getty@tty1.service succeeded. Jan 13 11:47:18 volumio22 volumio[1278]: info: touch_display: systemctl stop getty@tty1.service succeeded. Jan 13 11:47:18 volumio22 volumio[1278]: info: MPD Permissions set Jan 13 11:47:18 volumio22 sudo[1645]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Jan 13 11:47:18 volumio22 sudo[1645]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 11:47:18 volumio22 volumio[1278]: info: MPD Permissions set Jan 13 11:47:18 volumio22 sudo[1647]: 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/ Jan 13 11:47:18 volumio22 sudo[1647]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 11:47:18 volumio22 volumio[1278]: info: Volumio called home Jan 13 11:47:18 volumio22 sudo[1647]: pam_unix(sudo:session): session closed for user root Jan 13 11:47:18 volumio22 systemd[1]: Started volumio-kiosk.service - Volumio Kiosk. Jan 13 11:47:18 volumio22 volumio[1278]: info: Spotify config file written Jan 13 11:47:18 volumio22 sudo[1645]: pam_unix(sudo:session): session closed for user root Jan 13 11:47:18 volumio22 sudo[1658]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jan 13 11:47:18 volumio22 sudo[1658]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 11:47:18 volumio22 systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 13 11:47:18 volumio22 systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 13 11:47:18 volumio22 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 13 11:47:18 volumio22 go-librespot[1678]: go-librespot daemon starting... Jan 13 11:47:18 volumio22 sudo[1658]: pam_unix(sudo:session): session closed for user root Jan 13 11:47:19 volumio22 startx[1679]: X.Org X Server 1.21.1.7 Jan 13 11:47:19 volumio22 startx[1679]: X Protocol Version 11, Revision 0 Jan 13 11:47:19 volumio22 startx[1679]: Current Operating System: Linux volumio22 6.12.47-v7l+ #1904 SMP Mon Sep 15 13:08:10 BST 2025 armv7l Jan 13 11:47:19 volumio22 startx[1679]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory numa_policy=interleave nvme.max_host_mem_size_mb=0 snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=640 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 numa=fake=2 system_heap.max_order=0 smsc95xx.macaddr=E4:5F:01:73:9E:AE vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=dafa3844-b779-48cd-9b4c-01ecfd09e0f4 imgfile=/volumio_current.sqsh bootpart=UUID=3B89-0B23 datapart=UUID=752d19ad-b702-471d-847a-f79ae83515d0 uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Jan 13 11:47:19 volumio22 startx[1679]: xorg-server 2:21.1.7-3+rpt3+deb12u11 (https://www.debian.org/support) Jan 13 11:47:19 volumio22 startx[1679]: Current version of pixman: 0.44.0 Jan 13 11:47:19 volumio22 startx[1679]: Before reporting problems, check http://wiki.x.org Jan 13 11:47:19 volumio22 startx[1679]: to make sure that you have the latest version. Jan 13 11:47:19 volumio22 startx[1679]: Markers: (--) probed, (**) from config file, (==) default setting, Jan 13 11:47:19 volumio22 startx[1679]: (++) from command line, (!!) notice, (II) informational, Jan 13 11:47:19 volumio22 startx[1679]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Jan 13 11:47:19 volumio22 startx[1679]: (==) Log file: "/var/log/Xorg.0.log", Time: Tue Jan 13 11:47:19 2026 Jan 13 11:47:19 volumio22 startx[1679]: (==) Using config directory: "/etc/X11/xorg.conf.d" Jan 13 11:47:19 volumio22 startx[1679]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Jan 13 11:47:19 volumio22 volumio[1278]: error: MPD error: The expression evaluated to a falsy value: Jan 13 11:47:19 volumio22 volumio[1278]: assert.ok(self.idling) Jan 13 11:47:19 volumio22 volumio[1278]: error: The expression evaluated to a falsy value: Jan 13 11:47:19 volumio22 volumio[1278]: assert.ok(self.idling) Jan 13 11:47:19 volumio22 volumio[1278]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jan 13 11:47:19 volumio22 volumio[1278]: info: MPD running with PID1524 Jan 13 11:47:19 volumio22 volumio[1278]: ,establishing connection Jan 13 11:47:19 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:47:19 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:47:19 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:47:19 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:47:19 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:47:19 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:47:19 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:47:19 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:47:19 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 11:47:19 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:47:19 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:47:19 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:47:19 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:47:19 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:47:19 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:47:19 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:47:19 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:47:19 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:47:19 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 11:47:19 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:47:19 volumio22 volumio[1278]: info: No need to fix Spotify hosts Jan 13 11:47:19 volumio22 volumio[1278]: error: updateQueue error: null Jan 13 11:47:19 volumio22 volumio[1278]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Jan 13 11:47:19 volumio22 volumio[1278]: info: touch_display: Volumio Kiosk started. Jan 13 11:47:19 volumio22 volumio[1278]: info: CoreCommandRouter::volumioGetState Jan 13 11:47:19 volumio22 volumio[1278]: info: CorePlayQueue::getTrack 0 Jan 13 11:47:19 volumio22 volumio[1278]: info: Completed starting Core Plugins Jan 13 11:47:19 volumio22 volumio[1278]: info: ------------------------------------------- Jan 13 11:47:19 volumio22 volumio[1278]: info: ----- MyVolumio plugins startup ---- Jan 13 11:47:19 volumio22 volumio[1278]: info: ------------------------------------------- Jan 13 11:47:19 volumio22 volumio[1278]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 13 11:47:19 volumio22 volumio[1278]: info: touch_display: IframeKeyboardBridge extension installed successfully Jan 13 11:47:19 volumio22 go-librespot[1680]: time="2026-01-13T11:47:19+07:00" level=info msg="running go-librespot 0.4.0" Jan 13 11:47:19 volumio22 go-librespot[1680]: time="2026-01-13T11:47:19+07:00" level=debug msg="app state loaded" Jan 13 11:47:19 volumio22 go-librespot[1680]: time="2026-01-13T11:47:19+07:00" level=debug msg="stored credentials not found" Jan 13 11:47:19 volumio22 go-librespot[1680]: time="2026-01-13T11:47:19+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 13 11:47:20 volumio22 go-librespot[1680]: time="2026-01-13T11:47:20+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jan 13 11:47:20 volumio22 go-librespot[1680]: time="2026-01-13T11:47:20+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jan 13 11:47:20 volumio22 go-librespot[1680]: time="2026-01-13T11:47:20+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jan 13 11:47:20 volumio22 go-librespot[1680]: time="2026-01-13T11:47:20+07:00" level=info msg="zeroconf server listening on port 33511" Jan 13 11:47:20 volumio22 volumio[1278]: info: CoreCommandRouter::volumioGetState Jan 13 11:47:20 volumio22 volumio[1278]: info: CorePlayQueue::getTrack 0 Jan 13 11:47:20 volumio22 sudo[1728]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Jan 13 11:47:20 volumio22 sudo[1728]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 11:47:20 volumio22 volumio[1278]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Jan 13 11:47:20 volumio22 sudo[1728]: pam_unix(sudo:session): session closed for user root Jan 13 11:47:20 volumio22 systemd[1]: setdatetime-helper.service: Deactivated successfully. Jan 13 11:47:20 volumio22 systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Jan 13 11:47:20 volumio22 volumio[1278]: error: updateQueue error: null Jan 13 11:47:20 volumio22 sudo[1731]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Jan 13 11:47:20 volumio22 sudo[1731]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 11:47:20 volumio22 volumio[1278]: info: touch_display: X display number found: 0 Jan 13 11:47:20 volumio22 sudo[1731]: pam_unix(sudo:session): session closed for user root Jan 13 11:47:20 volumio22 volumio[1278]: info: Starting Shairport Sync Jan 13 11:47:20 volumio22 volumio[1278]: info: Starting Shairport Sync Jan 13 11:47:20 volumio22 sudo[1740]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 13 11:47:20 volumio22 sudo[1740]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 11:47:20 volumio22 volumio[1278]: info: Starting Shairport Sync Jan 13 11:47:20 volumio22 sudo[1745]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 13 11:47:20 volumio22 sudo[1745]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 11:47:20 volumio22 sudo[1743]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 13 11:47:20 volumio22 sudo[1748]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 13 11:47:20 volumio22 sudo[1748]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 11:47:20 volumio22 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 13 11:47:20 volumio22 systemd[1]: shairport-sync.service: Deactivated successfully. Jan 13 11:47:20 volumio22 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 13 11:47:20 volumio22 systemd[1]: shairport-sync.service: Consumed 1.478s CPU time. Jan 13 11:47:20 volumio22 volumio[1278]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jan 13 11:47:20 volumio22 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 13 11:47:20 volumio22 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 13 11:47:20 volumio22 systemd[1]: shairport-sync.service: Deactivated successfully. Jan 13 11:47:20 volumio22 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 13 11:47:20 volumio22 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 13 11:47:20 volumio22 sudo[1740]: pam_unix(sudo:session): session closed for user root Jan 13 11:47:20 volumio22 sudo[1745]: pam_unix(sudo:session): session closed for user root Jan 13 11:47:20 volumio22 volumio[1278]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Jan 13 11:47:20 volumio22 volumio[1278]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Jan 13 11:47:20 volumio22 sudo[1743]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 11:47:21 volumio22 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 13 11:47:21 volumio22 systemd[1]: shairport-sync.service: Deactivated successfully. Jan 13 11:47:21 volumio22 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 13 11:47:21 volumio22 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 13 11:47:21 volumio22 sudo[1743]: pam_unix(sudo:session): session closed for user root Jan 13 11:47:21 volumio22 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Jan 13 11:47:21 volumio22 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 13 11:47:21 volumio22 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 13 11:47:21 volumio22 sudo[1748]: pam_unix(sudo:session): session closed for user root Jan 13 11:47:21 volumio22 volumio[1278]: info: Shairport-Sync Started Jan 13 11:47:21 volumio22 volumio[1278]: Error adding Membership: Error: addMembership EINVAL Jan 13 11:47:21 volumio22 volumio[1278]: info: Shairport-Sync Started Jan 13 11:47:21 volumio22 volumio[1278]: info: touch_display: X display number found: 0 Jan 13 11:47:21 volumio22 volumio[1278]: info: CoreCommandRouter::volumioGetState Jan 13 11:47:21 volumio22 volumio[1278]: info: CorePlayQueue::getTrack 0 Jan 13 11:47:21 volumio22 volumio[1278]: info: Upmpdcli Daemon Started Jan 13 11:47:21 volumio22 volumio[1278]: info: Shairport-Sync Started Jan 13 11:47:21 volumio22 sudo[1843]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 13 11:47:21 volumio22 sudo[1843]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 11:47:21 volumio22 sudo[1845]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 13 11:47:21 volumio22 sudo[1845]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 11:47:21 volumio22 systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Jan 13 11:47:21 volumio22 mpd_monitor.sh[1848]: MPD Monitor Service: Starting MPD Monitor Service Jan 13 11:47:21 volumio22 sudo[1845]: pam_unix(sudo:session): session closed for user root Jan 13 11:47:21 volumio22 sudo[1843]: pam_unix(sudo:session): session closed for user root Jan 13 11:47:22 volumio22 volumio[1278]: info: CoreCommandRouter::volumioGetState Jan 13 11:47:22 volumio22 volumio[1278]: info: CorePlayQueue::getTrack 0 Jan 13 11:47:22 volumio22 volumio[1278]: info: Successfully started MPD Monitor Jan 13 11:47:22 volumio22 volumio[1278]: info: Successfully started MPD Monitor Jan 13 11:47:22 volumio22 volumio[1278]: info: touch_display: Setting screensaver timeout to 120 seconds. Jan 13 11:47:22 volumio22 volumio[1278]: info: go-librespot daemon successfully initialized Jan 13 11:47:24 volumio22 volumio[1278]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jan 13 11:47:26 volumio22 volumio[1278]: info: Initializing connection to go-librespot Websocket Jan 13 11:47:27 volumio22 volumio-remote-updater[736]: [2026-01-13 11:47:27] [connect] Successful connection Jan 13 11:47:29 volumio22 go-librespot[1680]: time="2026-01-13T11:47:29+07:00" level=debug msg="new websocket client" Jan 13 11:47:30 volumio22 volumio[1278]: info: Connection to go-librespot Websocket established Jan 13 11:47:31 volumio22 volumio-remote-updater[736]: [2026-01-13 11:47:31] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1768279647 101 Jan 13 11:47:31 volumio22 volumio[1278]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 3 Jan 13 11:47:35 volumio22 volumio[1278]: info: Getting Spotify volume Jan 13 11:47:40 volumio22 volumio[1278]: error: MyVolumio Plugin failed to start in a timely fashion Jan 13 11:47:40 volumio22 volumio[1278]: [Metrics] CommandRouter: 42s 728.04ms Jan 13 11:47:40 volumio22 volumio[1278]: info: CoreCommandRouter::volumiosetStartupVolume Jan 13 11:47:40 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:47:40 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 11:47:40 volumio22 volumio[1278]: info: CoreCommandRouter::Close All Modals sent Jan 13 11:47:40 volumio22 volumio[1278]: info: CoreCommandRouter::Close All Modals sent Jan 13 11:47:41 volumio22 volumio[1278]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jan 13 11:47:41 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 13 11:47:41 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 13 11:47:41 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Jan 13 11:47:41 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 13 11:47:42 volumio22 volumio[1278]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jan 13 11:47:42 volumio22 volumio[1278]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jan 13 11:47:42 volumio22 volumio[1278]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jan 13 11:47:42 volumio22 volumio[1278]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jan 13 11:47:42 volumio22 volumio[1278]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jan 13 11:47:42 volumio22 volumio[1278]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jan 13 11:47:42 volumio22 volumio[1278]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jan 13 11:47:42 volumio22 volumio[1278]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jan 13 11:47:42 volumio22 volumio[1278]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jan 13 11:47:42 volumio22 volumio[1278]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jan 13 11:47:42 volumio22 volumio[1278]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jan 13 11:47:42 volumio22 volumio[1278]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jan 13 11:47:42 volumio22 volumio[1278]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jan 13 11:47:42 volumio22 volumio[1278]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jan 13 11:47:42 volumio22 volumio[1278]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jan 13 11:47:42 volumio22 volumio[1278]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jan 13 11:47:42 volumio22 volumio[1278]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jan 13 11:47:42 volumio22 volumio[1278]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jan 13 11:47:42 volumio22 volumio[1278]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jan 13 11:47:42 volumio22 volumio[1278]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jan 13 11:47:42 volumio22 volumio[1278]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jan 13 11:47:42 volumio22 volumio[1278]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jan 13 11:47:42 volumio22 volumio[1278]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jan 13 11:47:42 volumio22 volumio[1278]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jan 13 11:47:42 volumio22 volumio[1278]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jan 13 11:47:42 volumio22 volumio[1278]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jan 13 11:47:42 volumio22 volumio[1278]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jan 13 11:47:42 volumio22 volumio[1278]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jan 13 11:47:42 volumio22 volumio[1278]: info: Adding plugin bluetooth to MyMusic Plugins Jan 13 11:47:42 volumio22 volumio[1278]: info: Adding plugin multiroom to MyMusic Plugins Jan 13 11:47:42 volumio22 volumio[1278]: info: Adding plugin metavolumio to MyMusic Plugins Jan 13 11:47:42 volumio22 volumio[1278]: info: Adding plugin cd_controller to MyMusic Plugins Jan 13 11:47:42 volumio22 volumio[1278]: info: Adding plugin qobuzconnect to MyMusic Plugins Jan 13 11:47:43 volumio22 volumio[1278]: info: Adding plugin smart_inputs to MyMusic Plugins Jan 13 11:47:43 volumio22 volumio[1278]: info: Adding plugin tidalconnect to MyMusic Plugins Jan 13 11:47:43 volumio22 volumio[1278]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jan 13 11:47:45 volumio22 upmpdcli[2028]: writing RSA key Jan 13 11:47:47 volumio22 volumio[1278]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jan 13 11:47:47 volumio22 volumio[1278]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jan 13 11:47:47 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 11:47:47 volumio22 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 11:47:47 volumio22 volumio[1278]: info: Starting MyVolumio Remote Streaming Endpoints Jan 13 11:47:47 volumio22 volumio[1278]: info: MyVolumio login type: Token Jan 13 11:47:47 volumio22 volumio[1278]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jan 13 11:47:47 volumio22 volumio[1278]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jan 13 11:47:49 volumio22 volumio[1278]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jan 13 11:47:49 volumio22 volumio[1278]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jan 13 11:47:49 volumio22 volumio[1278]: info: Streaming services startup Jan 13 11:47:49 volumio22 volumio[1278]: info: Starting Streaming Daemon Jan 13 11:47:49 volumio22 sudo[2041]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 13 11:47:49 volumio22 volumio[1278]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jan 13 11:47:49 volumio22 sudo[2041]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 11:47:49 volumio22 sudo[2041]: pam_unix(sudo:session): session closed for user root Jan 13 11:47:49 volumio22 volumio[1278]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Jan 13 11:47:49 volumio22 volumio[1278]: ALSA lib confmisc.c:165:(snd_config_get_card) Cannot get card index for K17 Jan 13 11:47:49 volumio22 volumio[1278]: aplay: main:831: audio open error: No such device Jan 13 11:47:49 volumio22 volumio[1278]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 11:47:49 volumio22 volumio[1278]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Jan 13 11:47:49 volumio22 volumio[1278]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Jan 13 11:47:49 volumio22 volumio[1278]: at new NodeError (node:internal/errors:405:5) Jan 13 11:47:49 volumio22 volumio[1278]: at assert (node:internal/assert:14:11) Jan 13 11:47:49 volumio22 volumio[1278]: at internalConnectMultiple (node:net:1118:3) Jan 13 11:47:49 volumio22 volumio[1278]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Jan 13 11:47:49 volumio22 volumio[1278]: at listOnTimeout (node:internal/timers:575:11) Jan 13 11:47:49 volumio22 volumio[1278]: at process.processTimers (node:internal/timers:514:7) { Jan 13 11:47:49 volumio22 volumio[1278]: code: 'ERR_INTERNAL_ASSERTION' Jan 13 11:47:49 volumio22 volumio[1278]: } Jan 13 11:47:49 volumio22 volumio[1278]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 11:47:53 volumio22 sudo[2060]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-13 11:46' Jan 13 11:47:53 volumio22 sudo[2060]: 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"