Jan 29 00:42:00 myvolumio volumio[1291]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 29 00:42:00 myvolumio volumio[1291]: info: ___________ START PLUGINS ___________ Jan 29 00:42:00 myvolumio sudo[1570]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 29 00:42:00 myvolumio sudo[1570]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 29 00:42:00 myvolumio volumio[1291]: info: ControllerMpd::onStart: Initializing MPD Jan 29 00:42:00 myvolumio volumio[1291]: info: Creating MPD Configuration file Jan 29 00:42:00 myvolumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 29 00:42:00 myvolumio volumio[1291]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 29 00:42:00 myvolumio volumio[1291]: info: [1769614920360] CoreMusicLibrary::Adding element 미디어 서버 Jan 29 00:42:00 myvolumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 29 00:42:00 myvolumio volumio[1291]: info: UPNP Browser: Client initialized successfully Jan 29 00:42:00 myvolumio sudo[1580]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 29 00:42:00 myvolumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 29 00:42:00 myvolumio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 29 00:42:00 myvolumio sudo[1578]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 29 00:42:00 myvolumio sudo[1580]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 29 00:42:00 myvolumio sudo[1578]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 29 00:42:00 myvolumio sudo[1578]: pam_unix(sudo:session): session closed for user root Jan 29 00:42:00 myvolumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 29 00:42:00 myvolumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 29 00:42:00 myvolumio sudo[1582]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 29 00:42:00 myvolumio sudo[1582]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 29 00:42:00 myvolumio sudo[1589]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jan 29 00:42:00 myvolumio sudo[1582]: pam_unix(sudo:session): session closed for user root Jan 29 00:42:01 myvolumio systemd[1]: mpd.service: Deactivated successfully. Jan 29 00:42:01 myvolumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 29 00:42:01 myvolumio systemd[1]: mpd.socket: Deactivated successfully. Jan 29 00:42:01 myvolumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 29 00:42:01 myvolumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 29 00:42:01 myvolumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 29 00:42:01 myvolumio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 29 00:42:01 myvolumio volumio[1291]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 29 00:42:01 myvolumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 29 00:42:01 myvolumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 29 00:42:01 myvolumio sudo[1592]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 29 00:42:01 myvolumio sudo[1592]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 29 00:42:01 myvolumio sudo[1595]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jan 29 00:42:01 myvolumio sudo[1592]: pam_unix(sudo:session): session closed for user root Jan 29 00:42:01 myvolumio volumio[1291]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 29 00:42:01 myvolumio volumio[1291]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 29 00:42:01 myvolumio volumio[1291]: info: [1769614921487] CoreMusicLibrary::Adding element Last_100 Jan 29 00:42:01 myvolumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 29 00:42:01 myvolumio volumio[1291]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 29 00:42:01 myvolumio volumio[1291]: info: [1769614921562] CoreMusicLibrary::Adding element Webradio Jan 29 00:42:01 myvolumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 29 00:42:01 myvolumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 29 00:42:01 myvolumio volumio[1291]: info: Initializing BBC Radios Jan 29 00:42:02 myvolumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 29 00:42:02 myvolumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 29 00:42:03 myvolumio volumio[1291]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 29 00:42:03 myvolumio volumio[1291]: info: [1769614923062] CoreMusicLibrary::Adding element YouTube Music Jan 29 00:42:03 myvolumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 29 00:42:03 myvolumio volumio[1291]: Cannot find translation for source YouTube Music Jan 29 00:42:03 myvolumio volumio[1501]: Starting albumart workers Jan 29 00:42:03 myvolumio volumio[1291]: info: [now-playing] ConfigUpdater: config is up to date. Jan 29 00:42:03 myvolumio volumio[1503]: Starting albumart workers Jan 29 00:42:04 myvolumio volumio[1291]: info: Loading i18n strings for locale ko Jan 29 00:42:04 myvolumio volumio[1291]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Jan 29 00:42:04 myvolumio volumio[1291]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Jan 29 00:42:04 myvolumio volumio[1291]: info: Loading i18n strings for locale ko Jan 29 00:42:04 myvolumio volumio[1291]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Jan 29 00:42:04 myvolumio volumio[1291]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Jan 29 00:42:04 myvolumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 29 00:42:04 myvolumio volumio[1291]: info: Volumio Calling Home Jan 29 00:42:04 myvolumio volumio[1502]: Starting albumart workers Jan 29 00:42:05 myvolumio sudo[1630]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /data/volumiokioskextensions/IframeKeyboardBridge Jan 29 00:42:05 myvolumio sudo[1630]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 29 00:42:05 myvolumio sudo[1630]: pam_unix(sudo:session): session closed for user root Jan 29 00:42:05 myvolumio sudo[1636]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Jan 29 00:42:05 myvolumio sudo[1636]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 29 00:42:05 myvolumio sudo[1638]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Jan 29 00:42:05 myvolumio sudo[1638]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 29 00:42:05 myvolumio sudo[1640]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jan 29 00:42:05 myvolumio sudo[1640]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 29 00:42:06 myvolumio sudo[1636]: pam_unix(sudo:session): session closed for user root Jan 29 00:42:06 myvolumio systemd[1]: Reloading. Jan 29 00:42:09 myvolumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 6. Jan 29 00:42:09 myvolumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 29 00:42:09 myvolumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 29 00:42:09 myvolumio systemd[1]: Reloading. Jan 29 00:42:09 myvolumio sudo[1565]: pam_unix(sudo:session): session closed for user root Jan 29 00:42:09 myvolumio sudo[1640]: pam_unix(sudo:session): session closed for user root Jan 29 00:42:10 myvolumio mpd[1598]: 2026-01-29T00:42:10 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 29 00:42:12 myvolumio volumio[1291]: info: [now-playing] Fetching API key... Jan 29 00:42:12 myvolumio volumio-remote-updater[722]: [2026-01-29 00:42:12] [connect] Successful connection Jan 29 00:42:12 myvolumio systemd[1]: Started mpd.service - Music Player Daemon. Jan 29 00:42:12 myvolumio sudo[1570]: pam_unix(sudo:session): session closed for user root Jan 29 00:42:12 myvolumio sudo[1580]: pam_unix(sudo:session): session closed for user root Jan 29 00:42:12 myvolumio sudo[1638]: pam_unix(sudo:session): session closed for user root Jan 29 00:42:13 myvolumio volumio[1291]: info: [now-playing] App is listening on port 4004. Jan 29 00:42:13 myvolumio volumio[1291]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Jan 29 00:42:13 myvolumio volumio[1291]: info: Discovery: this is already registered, e710b553-1d49-4fa7-b4a8-dd94d3c8c67f Jan 29 00:42:13 myvolumio volumio[1291]: info: Discovery: Found device MyVolumio Jan 29 00:42:13 myvolumio volumio[1291]: info: CoreCommandRouter::volumioGetState Jan 29 00:42:13 myvolumio volumio[1291]: info: CorePlayQueue::getTrack 0 Jan 29 00:42:13 myvolumio volumio[1291]: info: touch_display: No backlight interface detected. Jan 29 00:42:13 myvolumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 29 00:42:13 myvolumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 29 00:42:13 myvolumio volumio[1291]: info: Discovery: Getting this device information Jan 29 00:42:13 myvolumio volumio[1291]: info: CoreCommandRouter::volumioGetState Jan 29 00:42:13 myvolumio volumio[1291]: info: CorePlayQueue::getTrack 0 Jan 29 00:42:13 myvolumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 29 00:42:14 myvolumio volumio[1291]: info: touch_display: systemctl daemon-reload succeeded. Jan 29 00:42:14 myvolumio volumio[1291]: info: touch_display: systemctl disable getty@tty1.service succeeded. Jan 29 00:42:14 myvolumio volumio[1291]: info: touch_display: systemctl stop getty@tty1.service succeeded. Jan 29 00:42:14 myvolumio volumio[1291]: info: minidlna: Found miniDLNA version 1.3.0 Jan 29 00:42:14 myvolumio volumio[1291]: info: minidlna: Starting minidlna.service Jan 29 00:42:14 myvolumio sudo[1710]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Jan 29 00:42:14 myvolumio sudo[1710]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 29 00:42:14 myvolumio volumio[1291]: info: MPD Permissions set Jan 29 00:42:14 myvolumio volumio[1291]: info: MPD Permissions set Jan 29 00:42:14 myvolumio volumio[1291]: info: Upmpdcli Daemon Started Jan 29 00:42:14 myvolumio sudo[1715]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Jan 29 00:42:14 myvolumio sudo[1715]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 29 00:42:14 myvolumio systemd[1]: Started volumio-kiosk.service - Volumio Kiosk. Jan 29 00:42:14 myvolumio sudo[1710]: pam_unix(sudo:session): session closed for user root Jan 29 00:42:15 myvolumio systemd[1]: Started minidlna.service - MiniDLNA lightweight DLNA/UPnP-AV server. Jan 29 00:42:15 myvolumio sudo[1715]: pam_unix(sudo:session): session closed for user root Jan 29 00:42:15 myvolumio sudo[1713]: 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 29 00:42:15 myvolumio startx[1748]: X.Org X Server 1.21.1.7 Jan 29 00:42:15 myvolumio startx[1748]: X Protocol Version 11, Revision 0 Jan 29 00:42:15 myvolumio startx[1748]: Current Operating System: Linux myvolumio 6.12.47-v7l+ #1904 SMP Mon Sep 15 13:08:10 BST 2025 armv7l Jan 29 00:42:15 myvolumio startx[1748]: 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=1920 bcm2708_fb.fbheight=1080 bcm2708_fb.fbswap=1 numa=fake=2 system_heap.max_order=0 smsc95xx.macaddr=DC:A6:32:39:11:82 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=43b5ba58-4103-46fe-bd0e-6eddbfd17b61 imgfile=/volumio_current.sqsh bootpart=UUID=B4E4-2F02 datapart=UUID=d7fd400f-7752-4077-a873-723dace73dbe 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 Jan 29 00:42:15 myvolumio startx[1748]: xorg-server 2:21.1.7-3+rpt3+deb12u11 (https://www.debian.org/support) Jan 29 00:42:15 myvolumio startx[1748]: Current version of pixman: 0.44.0 Jan 29 00:42:15 myvolumio startx[1748]: Before reporting problems, check http://wiki.x.org Jan 29 00:42:15 myvolumio startx[1748]: to make sure that you have the latest version. Jan 29 00:42:15 myvolumio startx[1748]: Markers: (--) probed, (**) from config file, (==) default setting, Jan 29 00:42:15 myvolumio startx[1748]: (++) from command line, (!!) notice, (II) informational, Jan 29 00:42:15 myvolumio startx[1748]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Jan 29 00:42:15 myvolumio startx[1748]: (==) Log file: "/var/log/Xorg.0.log", Time: Thu Jan 29 00:42:15 2026 Jan 29 00:42:15 myvolumio startx[1748]: (==) Using config directory: "/etc/X11/xorg.conf.d" Jan 29 00:42:15 myvolumio startx[1748]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Jan 29 00:42:15 myvolumio sudo[1713]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 29 00:42:15 myvolumio sudo[1713]: pam_unix(sudo:session): session closed for user root Jan 29 00:42:15 myvolumio systemd[1]: minidlna.service: Deactivated successfully. Jan 29 00:42:15 myvolumio volumio[1291]: 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 29 00:42:15 myvolumio volumio[1291]: info: Volumio called home Jan 29 00:42:18 myvolumio volumio[1291]: error: MPD error: The expression evaluated to a falsy value: Jan 29 00:42:18 myvolumio volumio[1291]: assert.ok(self.idling) Jan 29 00:42:18 myvolumio volumio[1291]: error: The expression evaluated to a falsy value: Jan 29 00:42:18 myvolumio volumio[1291]: assert.ok(self.idling) Jan 29 00:42:18 myvolumio volumio[1291]: info: minidlna: systemctl start minidlna.service succeeded. Jan 29 00:42:18 myvolumio volumio[1291]: info: MPD running with PID1598 Jan 29 00:42:18 myvolumio volumio[1291]: ,establishing connection Jan 29 00:42:18 myvolumio volumio[1291]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Jan 29 00:42:18 myvolumio volumio[1291]: info: touch_display: Volumio Kiosk started. Jan 29 00:42:18 myvolumio volumio[1291]: info: CoreCommandRouter::volumioGetState Jan 29 00:42:18 myvolumio volumio[1291]: info: CorePlayQueue::getTrack 0 Jan 29 00:42:18 myvolumio volumio[1291]: info: Completed starting Core Plugins Jan 29 00:42:18 myvolumio volumio[1291]: info: ------------------------------------------- Jan 29 00:42:18 myvolumio volumio[1291]: info: ----- MyVolumio plugins startup ---- Jan 29 00:42:18 myvolumio volumio[1291]: info: ------------------------------------------- Jan 29 00:42:18 myvolumio volumio[1291]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 29 00:42:18 myvolumio volumio[1291]: info: touch_display: IframeKeyboardBridge extension installed successfully Jan 29 00:42:19 myvolumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 29 00:42:19 myvolumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 29 00:42:19 myvolumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 29 00:42:19 myvolumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 29 00:42:19 myvolumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 29 00:42:19 myvolumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 29 00:42:19 myvolumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 29 00:42:19 myvolumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 29 00:42:19 myvolumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 29 00:42:19 myvolumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 29 00:42:19 myvolumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 29 00:42:19 myvolumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 29 00:42:19 myvolumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 29 00:42:19 myvolumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 29 00:42:19 myvolumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 29 00:42:19 myvolumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 29 00:42:19 myvolumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 29 00:42:19 myvolumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 29 00:42:19 myvolumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 29 00:42:19 myvolumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 29 00:42:20 myvolumio volumio[1291]: error: updateQueue error: null Jan 29 00:42:20 myvolumio volumio[1291]: info: touch_display: X display number found: 0 Jan 29 00:42:21 myvolumio volumio[1291]: 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 29 00:42:21 myvolumio volumio[1291]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Jan 29 00:42:21 myvolumio volumio[1291]: error: updateQueue error: null Jan 29 00:42:21 myvolumio volumio[1291]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jan 29 00:42:21 myvolumio sudo[1872]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Jan 29 00:42:21 myvolumio sudo[1872]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 29 00:42:22 myvolumio sudo[1874]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Jan 29 00:42:22 myvolumio sudo[1872]: pam_unix(sudo:session): session closed for user root Jan 29 00:42:22 myvolumio sudo[1874]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 29 00:42:22 myvolumio sudo[1874]: pam_unix(sudo:session): session closed for user root Jan 29 00:42:22 myvolumio volumio[1291]: info: Starting Shairport Sync Jan 29 00:42:22 myvolumio volumio[1291]: info: Starting Shairport Sync Jan 29 00:42:22 myvolumio volumio[1291]: info: Starting Shairport Sync Jan 29 00:42:22 myvolumio sudo[1883]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 29 00:42:22 myvolumio sudo[1883]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 29 00:42:22 myvolumio sudo[1881]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 29 00:42:22 myvolumio sudo[1881]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 29 00:42:23 myvolumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 29 00:42:23 myvolumio systemd[1]: shairport-sync.service: Deactivated successfully. Jan 29 00:42:23 myvolumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 29 00:42:23 myvolumio systemd[1]: shairport-sync.service: Consumed 2.659s CPU time. Jan 29 00:42:23 myvolumio sudo[1886]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 29 00:42:23 myvolumio sudo[1886]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 29 00:42:23 myvolumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 29 00:42:23 myvolumio volumio[1291]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jan 29 00:42:23 myvolumio sudo[1883]: pam_unix(sudo:session): session closed for user root Jan 29 00:42:23 myvolumio volumio[1291]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Jan 29 00:42:23 myvolumio volumio[1291]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Jan 29 00:42:23 myvolumio volumio[1291]: info: touch_display: X display number found: 0 Jan 29 00:42:23 myvolumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 29 00:42:23 myvolumio systemd[1]: shairport-sync.service: Deactivated successfully. Jan 29 00:42:23 myvolumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 29 00:42:23 myvolumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 29 00:42:23 myvolumio sudo[1881]: pam_unix(sudo:session): session closed for user root Jan 29 00:42:23 myvolumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 29 00:42:23 myvolumio systemd[1]: shairport-sync.service: Deactivated successfully. Jan 29 00:42:23 myvolumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 29 00:42:23 myvolumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 29 00:42:23 myvolumio sudo[1886]: pam_unix(sudo:session): session closed for user root Jan 29 00:42:24 myvolumio volumio[1291]: info: CoreCommandRouter::volumioGetState Jan 29 00:42:24 myvolumio volumio[1291]: info: CorePlayQueue::getTrack 0 Jan 29 00:42:24 myvolumio volumio[1291]: info: Shairport-Sync Started Jan 29 00:42:24 myvolumio volumio[1291]: Error adding Membership: Error: addMembership EINVAL Jan 29 00:42:25 myvolumio volumio[1291]: info: Shairport-Sync Started Jan 29 00:42:25 myvolumio volumio[1291]: info: Shairport-Sync Started Jan 29 00:42:27 myvolumio volumio-remote-updater[722]: [2026-01-29 00:42:27] [connect] Successful connection Jan 29 00:42:28 myvolumio volumio[1291]: error: [now-playing] Caught error in callback of WeatherAPI.#getFetchPromise(): Could not obtain API key Jan 29 00:42:31 myvolumio volumio[1291]: info: touch_display: Setting screensaver timeout to 0 seconds. Jan 29 00:42:33 myvolumio volumio[1291]: info: CoreCommandRouter::volumioGetState Jan 29 00:42:33 myvolumio volumio[1291]: info: CorePlayQueue::getTrack 0 Jan 29 00:42:34 myvolumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 29 00:42:34 myvolumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 29 00:42:34 myvolumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 29 00:42:36 myvolumio volumio[1291]: info: CoreCommandRouter::volumioGetState Jan 29 00:42:36 myvolumio volumio[1291]: info: CorePlayQueue::getTrack 0 Jan 29 00:42:38 myvolumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 29 00:42:38 myvolumio volumio[1291]: info: Discovery: Getting this device information Jan 29 00:42:38 myvolumio volumio[1291]: info: CoreCommandRouter::volumioGetState Jan 29 00:42:38 myvolumio volumio[1291]: info: CorePlayQueue::getTrack 0 Jan 29 00:42:38 myvolumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 29 00:42:42 myvolumio volumio-remote-updater[722]: [2026-01-29 00:42:42] [connect] Successful connection Jan 29 00:42:57 myvolumio volumio-remote-updater[722]: [2026-01-29 00:42:57] [connect] Successful connection Jan 29 00:43:06 myvolumio volumio[1291]: error: MyVolumio Plugin failed to start in a timely fashion Jan 29 00:43:06 myvolumio volumio[1291]: [Metrics] CommandRouter: 129s 246.52ms Jan 29 00:43:06 myvolumio volumio[1291]: info: CoreCommandRouter::volumiosetStartupVolume Jan 29 00:43:06 myvolumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 29 00:43:06 myvolumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 29 00:43:06 myvolumio volumio[1291]: info: CoreCommandRouter::Close All Modals sent Jan 29 00:43:06 myvolumio volumio[1291]: info: CoreCommandRouter::Close All Modals sent Jan 29 00:43:07 myvolumio volumio[1291]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jan 29 00:43:07 myvolumio volumio[1291]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jan 29 00:43:07 myvolumio volumio[1291]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jan 29 00:43:07 myvolumio volumio[1291]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jan 29 00:43:07 myvolumio volumio[1291]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jan 29 00:43:07 myvolumio volumio[1291]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jan 29 00:43:07 myvolumio volumio[1291]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jan 29 00:43:07 myvolumio volumio[1291]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jan 29 00:43:07 myvolumio volumio[1291]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jan 29 00:43:07 myvolumio volumio[1291]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jan 29 00:43:07 myvolumio volumio[1291]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jan 29 00:43:07 myvolumio volumio[1291]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jan 29 00:43:07 myvolumio volumio[1291]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jan 29 00:43:07 myvolumio volumio[1291]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jan 29 00:43:07 myvolumio volumio[1291]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jan 29 00:43:07 myvolumio volumio[1291]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jan 29 00:43:07 myvolumio volumio[1291]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jan 29 00:43:07 myvolumio volumio[1291]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jan 29 00:43:07 myvolumio volumio[1291]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jan 29 00:43:07 myvolumio volumio[1291]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jan 29 00:43:07 myvolumio volumio[1291]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jan 29 00:43:07 myvolumio volumio[1291]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jan 29 00:43:07 myvolumio volumio[1291]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jan 29 00:43:07 myvolumio volumio[1291]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jan 29 00:43:07 myvolumio volumio[1291]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jan 29 00:43:07 myvolumio volumio[1291]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jan 29 00:43:07 myvolumio volumio[1291]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jan 29 00:43:07 myvolumio volumio[1291]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jan 29 00:43:07 myvolumio volumio[1291]: info: Adding plugin bluetooth to MyMusic Plugins Jan 29 00:43:07 myvolumio volumio[1291]: info: Adding plugin multiroom to MyMusic Plugins Jan 29 00:43:07 myvolumio volumio[1291]: info: Adding plugin metavolumio to MyMusic Plugins Jan 29 00:43:07 myvolumio volumio[1291]: info: Adding plugin cd_controller to MyMusic Plugins Jan 29 00:43:07 myvolumio volumio[1291]: info: Adding plugin qobuzconnect to MyMusic Plugins Jan 29 00:43:07 myvolumio volumio[1291]: info: Adding plugin smart_inputs to MyMusic Plugins Jan 29 00:43:08 myvolumio volumio[1291]: info: Adding plugin tidalconnect to MyMusic Plugins Jan 29 00:43:08 myvolumio volumio[1291]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jan 29 00:43:12 myvolumio volumio-remote-updater[722]: [2026-01-29 00:43:12] [connect] Successful connection Jan 29 00:43:20 myvolumio volumio[1291]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jan 29 00:43:20 myvolumio volumio[1291]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jan 29 00:43:20 myvolumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 29 00:43:20 myvolumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 29 00:43:20 myvolumio volumio[1291]: info: Starting MyVolumio Remote Streaming Endpoints Jan 29 00:43:21 myvolumio volumio[1291]: info: MyVolumio login type: Token Jan 29 00:43:21 myvolumio volumio[1291]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jan 29 00:43:21 myvolumio volumio[1291]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jan 29 00:43:27 myvolumio volumio-remote-updater[722]: [2026-01-29 00:43:27] [connect] Successful connection Jan 29 00:43:30 myvolumio volumio[1291]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jan 29 00:43:30 myvolumio volumio[1291]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jan 29 00:43:30 myvolumio volumio[1291]: info: Streaming services startup Jan 29 00:43:30 myvolumio volumio[1291]: info: Starting Streaming Daemon Jan 29 00:43:31 myvolumio volumio[1291]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jan 29 00:43:31 myvolumio sudo[2133]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 29 00:43:31 myvolumio sudo[2133]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 29 00:43:31 myvolumio sudo[2133]: pam_unix(sudo:session): session closed for user root Jan 29 00:43:32 myvolumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 29 00:43:32 myvolumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 29 00:43:32 myvolumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 29 00:43:32 myvolumio volumio[1291]: info: Version has changed, forcing UI Reload Jan 29 00:43:32 myvolumio volumio[1291]: info: CoreCommandRouter::Reload Ui Jan 29 00:43:35 myvolumio volumio[1291]: info: BOOT COMPLETED Jan 29 00:43:35 myvolumio volumio[1291]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 29 00:43:35 myvolumio volumio[1291]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Jan 29 00:43:35 myvolumio volumio[1291]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Jan 29 00:43:35 myvolumio volumio[1291]: at new NodeError (node:internal/errors:405:5) Jan 29 00:43:35 myvolumio volumio[1291]: at assert (node:internal/assert:14:11) Jan 29 00:43:35 myvolumio volumio[1291]: at internalConnectMultiple (node:net:1118:3) Jan 29 00:43:35 myvolumio volumio[1291]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Jan 29 00:43:35 myvolumio volumio[1291]: at listOnTimeout (node:internal/timers:575:11) Jan 29 00:43:35 myvolumio volumio[1291]: at process.processTimers (node:internal/timers:514:7) { Jan 29 00:43:35 myvolumio volumio[1291]: code: 'ERR_INTERNAL_ASSERTION' Jan 29 00:43:35 myvolumio volumio[1291]: } Jan 29 00:43:35 myvolumio volumio[1291]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 29 00:43:42 myvolumio volumio-remote-updater[722]: [2026-01-29 00:43:42] [connect] Successful connection Jan 29 00:43:44 myvolumio sudo[2222]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-29 00:42' Jan 29 00:43:44 myvolumio sudo[2222]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"