Feb 11 21:48:00 volumio2 sudo[1677]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable --now cava.service Feb 11 21:48:00 volumio2 sudo[1677]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 21:48:01 volumio2 systemd[1]: Reloading. Feb 11 21:48:01 volumio2 sudo[1622]: pam_unix(sudo:session): session closed for user root Feb 11 21:48:01 volumio2 volumio[1295]: info: [Quadify][SYSD] cmd OK(plan): /usr/bin/sudo -n /bin/systemctl daemon-reload Feb 11 21:48:01 volumio2 volumio[1295]: info: [Quadify][SYSD] exec(plan): /usr/bin/sudo -n /bin/systemctl enable --now ir_listener.service Feb 11 21:48:01 volumio2 sudo[1680]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable --now ir_listener.service Feb 11 21:48:01 volumio2 sudo[1680]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 21:48:02 volumio2 sudo[1698]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable quadify-leds-off.service Feb 11 21:48:02 volumio2 sudo[1698]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 21:48:02 volumio2 sudo[1702]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable volumio-clean-poweroff.service Feb 11 21:48:02 volumio2 sudo[1702]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 21:48:02 volumio2 volumio[1295]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Feb 11 21:48:03 volumio2 systemd[1]: Reloading. Feb 11 21:48:03 volumio2 sudo[1620]: pam_unix(sudo:session): session closed for user root Feb 11 21:48:03 volumio2 volumio[1295]: info: [Quadify][SYSD] cmd OK(plan): /usr/bin/sudo -n /bin/systemctl daemon-reload Feb 11 21:48:03 volumio2 volumio[1295]: info: [Quadify][SYSD] exec(plan): /usr/bin/sudo -n /bin/systemctl enable --now lircd.service Feb 11 21:48:03 volumio2 sudo[1712]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable --now lircd.service Feb 11 21:48:03 volumio2 sudo[1712]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 21:48:05 volumio2 systemd[1]: Reloading. Feb 11 21:48:07 volumio2 systemd[1]: Reloading. Feb 11 21:48:09 volumio2 systemd[1]: Reloading. Feb 11 21:48:11 volumio2 systemd[1]: Reloading. Feb 11 21:48:11 volumio2 sudo[1698]: pam_unix(sudo:session): session closed for user root Feb 11 21:48:11 volumio2 volumio[1295]: info: [Quadify] CMD OK: /usr/bin/sudo -n /bin/systemctl disable quadify-leds-off.service Feb 11 21:48:11 volumio2 volumio[1295]: stdout: Feb 11 21:48:11 volumio2 volumio[1295]: stderr: Feb 11 21:48:12 volumio2 volumio[1295]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Feb 11 21:48:13 volumio2 systemd[1]: Reloading. Feb 11 21:48:13 volumio2 sudo[1702]: pam_unix(sudo:session): session closed for user root Feb 11 21:48:13 volumio2 volumio[1295]: info: [Quadify] CMD OK: /usr/bin/sudo -n /bin/systemctl disable volumio-clean-poweroff.service Feb 11 21:48:13 volumio2 volumio[1295]: stdout: Feb 11 21:48:13 volumio2 volumio[1295]: stderr: Feb 11 21:48:14 volumio2 sudo[1677]: pam_unix(sudo:session): session closed for user root Feb 11 21:48:15 volumio2 volumio[1295]: info: [Quadify][SYSD] cmd OK(plan): /usr/bin/sudo -n /bin/systemctl enable --now cava.service Feb 11 21:48:15 volumio2 volumio[1295]: info: [Quadify][SYSD] exec(verify): /bin/systemctl show -p ActiveState -p SubState -p UnitFileState -p FragmentPath -p Result -p ExecMainPID -p ExecMainStatus cava.service Feb 11 21:48:15 volumio2 volumio[1295]: error: Plugin system_hardware quadify failed to complete 'onVolumioStart' in a timely fashion Feb 11 21:48:15 volumio2 volumio[1295]: info: Preparing to generate the ALSA configuration file Feb 11 21:48:15 volumio2 sudo[1680]: pam_unix(sudo:session): session closed for user root Feb 11 21:48:15 volumio2 volumio[1295]: info: [Quadify][SYSD] cmd OK(verify): /bin/systemctl show -p ActiveState -p SubState -p UnitFileState -p FragmentPath -p Result -p ExecMainPID -p ExecMainStatus cava.service Feb 11 21:48:15 volumio2 volumio[1295]: Result=success Feb 11 21:48:15 volumio2 volumio[1295]: ExecMainPID=774 Feb 11 21:48:15 volumio2 volumio[1295]: ExecMainStatus=0 Feb 11 21:48:15 volumio2 volumio[1295]: ActiveState=active Feb 11 21:48:15 volumio2 volumio[1295]: SubState=running Feb 11 21:48:15 volumio2 volumio[1295]: FragmentPath=/etc/systemd/system/cava.service Feb 11 21:48:15 volumio2 volumio[1295]: UnitFileState=enabled Feb 11 21:48:15 volumio2 volumio[1295]: info: [Quadify][SYSD] VERIFY cava: active=active sub=running enabled=enabled pid=774 status=0 result=success path=/etc/systemd/system/cava.service Feb 11 21:48:15 volumio2 volumio[1295]: info: [Quadify][SYSD] cava => active=active enabled=enabled Feb 11 21:48:15 volumio2 sudo[1624]: pam_unix(sudo:session): session closed for user root Feb 11 21:48:15 volumio2 volumio[1295]: info: [Quadify][SYSD] cmd OK(plan): /usr/bin/sudo -n /bin/systemctl disable --now quadify-buttonsleds.service Feb 11 21:48:15 volumio2 volumio[1295]: info: [Quadify][SYSD] exec(verify): /bin/systemctl show -p ActiveState -p SubState -p UnitFileState -p FragmentPath -p Result -p ExecMainPID -p ExecMainStatus quadify-buttonsleds.service Feb 11 21:48:15 volumio2 volumio[1295]: info: [Quadify][SYSD] cmd OK(plan): /usr/bin/sudo -n /bin/systemctl enable --now ir_listener.service Feb 11 21:48:15 volumio2 volumio[1295]: info: [Quadify][SYSD] exec(verify): /bin/systemctl show -p ActiveState -p SubState -p UnitFileState -p FragmentPath -p Result -p ExecMainPID -p ExecMainStatus ir_listener.service Feb 11 21:48:15 volumio2 volumio[1295]: info: Asound.conf file unchanged, so no further update is needed Feb 11 21:48:15 volumio2 volumio[1295]: info: Output device has changed, restarting MPD Feb 11 21:48:15 volumio2 volumio[1295]: info: Output device has changed, restarting Shairport Sync Feb 11 21:48:15 volumio2 volumio[1295]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 21:48:15 volumio2 volumio[1295]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 21:48:15 volumio2 sudo[1856]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 11 21:48:15 volumio2 sudo[1856]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 21:48:15 volumio2 sudo[1858]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 11 21:48:15 volumio2 sudo[1856]: pam_unix(sudo:session): session closed for user root Feb 11 21:48:15 volumio2 volumio[1295]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 11 21:48:15 volumio2 volumio[1295]: info: ___________ START PLUGINS ___________ Feb 11 21:48:15 volumio2 sudo[1858]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 21:48:16 volumio2 volumio[1295]: info: ControllerMpd::onStart: Initializing MPD Feb 11 21:48:16 volumio2 volumio[1295]: info: Creating MPD Configuration file Feb 11 21:48:16 volumio2 volumio[1295]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 11 21:48:16 volumio2 volumio[1295]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 11 21:48:16 volumio2 volumio[1295]: info: [1770846496170] CoreMusicLibrary::Adding element Media Servers Feb 11 21:48:16 volumio2 volumio[1295]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 21:48:16 volumio2 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 11 21:48:16 volumio2 systemd[1]: Starting mpd.service - Music Player Daemon... Feb 11 21:48:16 volumio2 sudo[1866]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 11 21:48:16 volumio2 sudo[1866]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 21:48:16 volumio2 python3[900]: 2026-02-11 21:48:16,291 - VolumioListener - INFO - [VolumioListener] Received pushBrowseSources event. Feb 11 21:48:16 volumio2 python3[900]: 2026-02-11 21:48:16,292 - VolumioListener - DEBUG - [VolumioListener] Sources: [{'albumart': '/albumart?sourceicon=music_service/mpd/favouritesicon.png', 'name': 'Favorites', 'uri': 'favourites', 'plugin_type': '', 'plugin_name': ''}, {'albumart': '/albumart?sourceicon=music_service/mpd/playlisticon.png', 'name': 'Playlists', 'uri': 'playlists', 'plugin_type': 'music_service', 'plugin_name': 'mpd'}, {'albumart': '/albumart?sourceicon=music_service/mpd/musiclibraryicon.png', 'name': 'Music Library', 'uri': 'music-library', 'plugin_type': 'music_service', 'plugin_name': 'mpd'}, {'albumart': '/albumart?sourceicon=music_service/mpd/artisticon.png', 'name': 'Artists', 'uri': 'artists://', 'plugin_type': 'music_service', 'plugin_name': 'mpd'}, {'albumart': '/albumart?sourceicon=music_service/mpd/albumicon.png', 'name': 'Albums', 'uri': 'albums://', 'plugin_type': 'music_service', 'plugin_name': 'mpd'}, {'albumart': '/albumart?sourceicon=music_service/mpd/genreicon.png', 'name': 'Genres', 'uri': 'genres://', 'plugin_type': 'music_service', 'plugin_name': 'mpd'}, {'name': 'Media Servers', 'uri': 'upnp', 'plugin_type': 'music_service', 'plugin_name': 'upnp_browser', 'albumart': '/albumart?sourceicon=music_service/upnp_browser/dlnaicon.png'}] Feb 11 21:48:16 volumio2 sudo[1866]: pam_unix(sudo:session): session closed for user root Feb 11 21:48:16 volumio2 systemd[1]: Reloading. Feb 11 21:48:16 volumio2 sudo[1869]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 11 21:48:16 volumio2 sudo[1869]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 21:48:16 volumio2 volumio[1295]: info: UPNP Browser: Client initialized successfully Feb 11 21:48:16 volumio2 sudo[1871]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 11 21:48:16 volumio2 sudo[1871]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 11 21:48:16 volumio2 sudo[1875]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Feb 11 21:48:16 volumio2 sudo[1871]: pam_unix(sudo:session): session closed for user root Feb 11 21:48:16 volumio2 volumio[1295]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 21:48:16 volumio2 volumio[1295]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 21:48:16 volumio2 volumio[1295]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 11 21:48:16 volumio2 volumio[1295]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 21:48:16 volumio2 volumio[1295]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 21:48:17 volumio2 volumio[1295]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 11 21:48:17 volumio2 volumio[1295]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 11 21:48:17 volumio2 volumio[1295]: info: [1770846497267] CoreMusicLibrary::Adding element Last_100 Feb 11 21:48:17 volumio2 volumio[1295]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 21:48:17 volumio2 volumio[1295]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 11 21:48:17 volumio2 volumio[1295]: info: [1770846497334] CoreMusicLibrary::Adding element Webradio Feb 11 21:48:17 volumio2 volumio[1295]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 21:48:17 volumio2 volumio[1295]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 21:48:17 volumio2 volumio[1295]: info: Initializing BBC Radios Feb 11 21:48:18 volumio2 volumio[1295]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 21:48:18 volumio2 volumio[1295]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 21:48:18 volumio2 volumio[1295]: info: Volumio Calling Home Feb 11 21:48:18 volumio2 python3[900]: 2026-02-11 21:48:18,397 - VolumioListener - INFO - [VolumioListener] Received pushBrowseSources event. Feb 11 21:48:18 volumio2 python3[900]: 2026-02-11 21:48:18,397 - VolumioListener - DEBUG - [VolumioListener] Sources: [{'albumart': '/albumart?sourceicon=music_service/mpd/favouritesicon.png', 'name': 'Favorites', 'uri': 'favourites', 'plugin_type': '', 'plugin_name': ''}, {'albumart': '/albumart?sourceicon=music_service/mpd/playlisticon.png', 'name': 'Playlists', 'uri': 'playlists', 'plugin_type': 'music_service', 'plugin_name': 'mpd'}, {'albumart': '/albumart?sourceicon=music_service/mpd/musiclibraryicon.png', 'name': 'Music Library', 'uri': 'music-library', 'plugin_type': 'music_service', 'plugin_name': 'mpd'}, {'albumart': '/albumart?sourceicon=music_service/mpd/artisticon.png', 'name': 'Artists', 'uri': 'artists://', 'plugin_type': 'music_service', 'plugin_name': 'mpd'}, {'albumart': '/albumart?sourceicon=music_service/mpd/albumicon.png', 'name': 'Albums', 'uri': 'albums://', 'plugin_type': 'music_service', 'plugin_name': 'mpd'}, {'albumart': '/albumart?sourceicon=music_service/mpd/genreicon.png', 'name': 'Genres', 'uri': 'genres://', 'plugin_type': 'music_service', 'plugin_name': 'mpd'}, {'name': 'Media Servers', 'uri': 'upnp', 'plugin_type': 'music_service', 'plugin_name': 'upnp_browser', 'albumart': '/albumart?sourceicon=music_service/upnp_browser/dlnaicon.png'}, {'albumart': '/albumart?sourceicon=music_service/last_100/icon.png', 'name': 'Last 100', 'uri': 'Last_100', 'plugin_type': 'music_service', 'plugin_name': 'last_100'}] Feb 11 21:48:18 volumio2 python3[900]: 2026-02-11 21:48:18,415 - VolumioListener - INFO - [VolumioListener] Received pushBrowseSources event. Feb 11 21:48:18 volumio2 python3[900]: 2026-02-11 21:48:18,418 - VolumioListener - DEBUG - [VolumioListener] Sources: [{'albumart': '/albumart?sourceicon=music_service/mpd/favouritesicon.png', 'name': 'Favorites', 'uri': 'favourites', 'plugin_type': '', 'plugin_name': ''}, {'albumart': '/albumart?sourceicon=music_service/mpd/playlisticon.png', 'name': 'Playlists', 'uri': 'playlists', 'plugin_type': 'music_service', 'plugin_name': 'mpd'}, {'albumart': '/albumart?sourceicon=music_service/mpd/musiclibraryicon.png', 'name': 'Music Library', 'uri': 'music-library', 'plugin_type': 'music_service', 'plugin_name': 'mpd'}, {'albumart': '/albumart?sourceicon=music_service/mpd/artisticon.png', 'name': 'Artists', 'uri': 'artists://', 'plugin_type': 'music_service', 'plugin_name': 'mpd'}, {'albumart': '/albumart?sourceicon=music_service/mpd/albumicon.png', 'name': 'Albums', 'uri': 'albums://', 'plugin_type': 'music_service', 'plugin_name': 'mpd'}, {'albumart': '/albumart?sourceicon=music_service/mpd/genreicon.png', 'name': 'Genres', 'uri': 'genres://', 'plugin_type': 'music_service', 'plugin_name': 'mpd'}, {'name': 'Media Servers', 'uri': 'upnp', 'plugin_type': 'music_service', 'plugin_name': 'upnp_browser', 'albumart': '/albumart?sourceicon=music_service/upnp_browser/dlnaicon.png'}, {'albumart': '/albumart?sourceicon=music_service/last_100/icon.png', 'name': 'Last 100', 'uri': 'Last_100', 'plugin_type': 'music_service', 'plugin_name': 'last_100'}, {'albumart': '/albumart?sourceicon=music_service/webradio/icon.png', 'icon': 'fa fa-microphone', 'name': 'Web Radio', 'uri': 'radio', 'plugin_type': 'music_service', 'plugin_name': 'webradio'}] Feb 11 21:48:18 volumio2 systemd[1]: mpd.service: Deactivated successfully. Feb 11 21:48:18 volumio2 systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 11 21:48:18 volumio2 systemd[1]: Reloading. Feb 11 21:48:20 volumio2 systemd[1]: mpd.socket: Deactivated successfully. Feb 11 21:48:20 volumio2 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 11 21:48:20 volumio2 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 11 21:48:20 volumio2 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 11 21:48:20 volumio2 systemd[1]: Starting mpd.service - Music Player Daemon... Feb 11 21:48:20 volumio2 systemd[1]: Starting lircd-setup.service - lircd(8) initialization helper tool... Feb 11 21:48:20 volumio2 lircd-setup[1955]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in Python 3.12. Use ConfigParser directly instead. Feb 11 21:48:20 volumio2 lircd-setup[1955]: parser = configparser.SafeConfigParser() Feb 11 21:48:20 volumio2 sudo[1954]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 11 21:48:20 volumio2 sudo[1954]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 11 21:48:20 volumio2 sudo[1956]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Feb 11 21:48:20 volumio2 sudo[1954]: pam_unix(sudo:session): session closed for user root Feb 11 21:48:20 volumio2 systemd[1]: lircd-setup.service: Deactivated successfully. Feb 11 21:48:20 volumio2 systemd[1]: Finished lircd-setup.service - lircd(8) initialization helper tool. Feb 11 21:48:20 volumio2 sudo[1712]: pam_unix(sudo:session): session closed for user root Feb 11 21:48:24 volumio2 volumio[1295]: info: [Quadify] CMD OK: /bin/systemctl show -p LoadState -p FragmentPath quadify-buttonsleds.service Feb 11 21:48:24 volumio2 volumio[1295]: stdout: LoadState=loaded Feb 11 21:48:24 volumio2 volumio[1295]: FragmentPath=/etc/systemd/system/quadify-buttonsleds.service Feb 11 21:48:24 volumio2 volumio[1295]: stderr: Feb 11 21:48:24 volumio2 volumio[1295]: info: [Quadify][DETECT] quadify-buttonsleds.service load=loaded path=/etc/systemd/system/quadify-buttonsleds.service Feb 11 21:48:24 volumio2 volumio[1295]: info: [Quadify][DETECT] using unit=quadify-buttonsleds Feb 11 21:48:24 volumio2 volumio[1295]: info: [Quadify] onStart ready Feb 11 21:48:24 volumio2 volumio[1295]: info: MPD Permissions set Feb 11 21:48:24 volumio2 volumio[1295]: info: MPD Permissions set Feb 11 21:48:24 volumio2 volumio[1295]: info: [Quadify][SYSD] cmd OK(plan): /usr/bin/sudo -n /bin/systemctl enable --now lircd.service Feb 11 21:48:24 volumio2 volumio[1295]: info: [Quadify][SYSD] exec(verify): /bin/systemctl show -p ActiveState -p SubState -p UnitFileState -p FragmentPath -p Result -p ExecMainPID -p ExecMainStatus lircd.service Feb 11 21:48:24 volumio2 volumio[1295]: info: [Quadify][SYSD] cmd OK(verify): /bin/systemctl show -p ActiveState -p SubState -p UnitFileState -p FragmentPath -p Result -p ExecMainPID -p ExecMainStatus ir_listener.service Feb 11 21:48:24 volumio2 volumio[1295]: Result=success Feb 11 21:48:24 volumio2 volumio[1295]: ExecMainPID=944 Feb 11 21:48:24 volumio2 volumio[1295]: ExecMainStatus=0 Feb 11 21:48:24 volumio2 volumio[1295]: ActiveState=active Feb 11 21:48:24 volumio2 volumio[1295]: SubState=running Feb 11 21:48:24 volumio2 volumio[1295]: FragmentPath=/etc/systemd/system/ir_listener.service Feb 11 21:48:24 volumio2 volumio[1295]: UnitFileState=enabled Feb 11 21:48:24 volumio2 volumio[1295]: info: [Quadify][SYSD] VERIFY ir_listener: active=active sub=running enabled=enabled pid=944 status=0 result=success path=/etc/systemd/system/ir_listener.service Feb 11 21:48:24 volumio2 volumio[1295]: info: [Quadify][SYSD] ir_listener => active=active enabled=enabled Feb 11 21:48:24 volumio2 volumio[1295]: info: [Quadify][SYSD] cmd OK(verify): /bin/systemctl show -p ActiveState -p SubState -p UnitFileState -p FragmentPath -p Result -p ExecMainPID -p ExecMainStatus quadify-buttonsleds.service Feb 11 21:48:24 volumio2 volumio[1295]: Result=success Feb 11 21:48:24 volumio2 volumio[1295]: ExecMainPID=0 Feb 11 21:48:24 volumio2 volumio[1295]: ExecMainStatus=0 Feb 11 21:48:24 volumio2 volumio[1295]: ActiveState=inactive Feb 11 21:48:24 volumio2 volumio[1295]: SubState=dead Feb 11 21:48:24 volumio2 volumio[1295]: FragmentPath=/etc/systemd/system/quadify-buttonsleds.service Feb 11 21:48:24 volumio2 volumio[1295]: UnitFileState=disabled Feb 11 21:48:24 volumio2 volumio[1295]: info: [Quadify][SYSD] VERIFY quadify-buttonsleds: active=inactive sub=dead enabled=disabled pid=0 status=0 result=success path=/etc/systemd/system/quadify-buttonsleds.service Feb 11 21:48:24 volumio2 volumio[1295]: info: [Quadify][SYSD] quadify-buttonsleds => active=inactive enabled=disabled Feb 11 21:48:24 volumio2 volumio[1295]: info: Volumio called home Feb 11 21:48:25 volumio2 volumio[1295]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Feb 11 21:48:25 volumio2 volumio[1295]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 21:48:25 volumio2 volumio[1295]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 21:48:25 volumio2 volumio[1295]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 21:48:25 volumio2 volumio[1295]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 21:48:25 volumio2 volumio[1295]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 21:48:25 volumio2 volumio[1295]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 21:48:25 volumio2 volumio[1295]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 21:48:25 volumio2 volumio[1295]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 21:48:25 volumio2 volumio[1295]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 21:48:26 volumio2 volumio[1295]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 21:48:26 volumio2 volumio[1295]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 21:48:26 volumio2 volumio[1295]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 21:48:26 volumio2 volumio[1295]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 21:48:26 volumio2 volumio[1295]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 21:48:26 volumio2 volumio[1295]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 21:48:26 volumio2 volumio[1295]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 21:48:26 volumio2 volumio[1295]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 21:48:26 volumio2 volumio[1295]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 21:48:26 volumio2 volumio[1295]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 21:48:26 volumio2 volumio[1295]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 21:48:26 volumio2 volumio[1295]: info: [Quadify][SYSD] cmd OK(verify): /bin/systemctl show -p ActiveState -p SubState -p UnitFileState -p FragmentPath -p Result -p ExecMainPID -p ExecMainStatus lircd.service Feb 11 21:48:26 volumio2 volumio[1295]: Result=success Feb 11 21:48:26 volumio2 volumio[1295]: ExecMainPID=891 Feb 11 21:48:26 volumio2 volumio[1295]: ExecMainStatus=0 Feb 11 21:48:26 volumio2 volumio[1295]: ActiveState=active Feb 11 21:48:26 volumio2 volumio[1295]: SubState=running Feb 11 21:48:26 volumio2 volumio[1295]: FragmentPath=/lib/systemd/system/lircd.service Feb 11 21:48:26 volumio2 volumio[1295]: UnitFileState=enabled Feb 11 21:48:26 volumio2 volumio[1295]: info: [Quadify][SYSD] VERIFY lircd: active=active sub=running enabled=enabled pid=891 status=0 result=success path=/lib/systemd/system/lircd.service Feb 11 21:48:26 volumio2 volumio[1295]: info: [Quadify][SYSD] lircd => active=active enabled=enabled Feb 11 21:48:26 volumio2 volumio[1295]: info: [Quadify][RESULT] cava: ok Feb 11 21:48:26 volumio2 volumio[1295]: info: [Quadify][RESULT] quadify-buttonsleds: active=inactive enabled=disabled Feb 11 21:48:26 volumio2 volumio[1295]: info: [Quadify][RESULT] lircd: active=active enabled=enabled Feb 11 21:48:26 volumio2 volumio[1295]: info: [Quadify][RESULT] ir_listener: active=active enabled=enabled Feb 11 21:48:26 volumio2 volumio[1295]: info: [Quadify][RESULT] safe-shutdown: ok Feb 11 21:48:27 volumio2 volumio[1295]: info: Starting Shairport Sync Feb 11 21:48:27 volumio2 volumio[1295]: info: Starting Shairport Sync Feb 11 21:48:27 volumio2 volumio[1295]: info: Starting Shairport Sync Feb 11 21:48:27 volumio2 sudo[1979]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 11 21:48:27 volumio2 sudo[1979]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 21:48:27 volumio2 sudo[1976]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 11 21:48:27 volumio2 sudo[1976]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 21:48:27 volumio2 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 11 21:48:27 volumio2 systemd[1]: shairport-sync.service: Deactivated successfully. Feb 11 21:48:27 volumio2 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 11 21:48:27 volumio2 systemd[1]: shairport-sync.service: Consumed 1.633s CPU time. Feb 11 21:48:27 volumio2 sudo[1980]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 11 21:48:27 volumio2 sudo[1980]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 21:48:27 volumio2 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 11 21:48:27 volumio2 sudo[1979]: pam_unix(sudo:session): session closed for user root Feb 11 21:48:27 volumio2 sudo[1976]: pam_unix(sudo:session): session closed for user root Feb 11 21:48:27 volumio2 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 11 21:48:27 volumio2 systemd[1]: shairport-sync.service: Deactivated successfully. Feb 11 21:48:27 volumio2 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 11 21:48:27 volumio2 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 11 21:48:27 volumio2 volumio[1295]: info: Shairport-Sync Started Feb 11 21:48:27 volumio2 volumio[1295]: Error adding Membership: Error: addMembership EINVAL Feb 11 21:48:27 volumio2 volumio[1295]: info: Shairport-Sync Started Feb 11 21:48:28 volumio2 sudo[1980]: pam_unix(sudo:session): session closed for user root Feb 11 21:48:28 volumio2 volumio[1295]: info: CoreCommandRouter::volumioGetState Feb 11 21:48:28 volumio2 volumio[1295]: info: CorePlayQueue::getTrack 0 Feb 11 21:48:28 volumio2 volumio[1295]: info: Shairport-Sync Started Feb 11 21:48:28 volumio2 mpd[1957]: 2026-02-11T21:48:28 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 11 21:48:28 volumio2 volumio[1295]: info: [Quadify][SNAPSHOT] post-toggles :: cava=active/enabled, quadify-buttonsleds.service=inactive/disabled, lircd=active/enabled, ir_listener=active/enabled Feb 11 21:48:28 volumio2 volumio[1295]: info: [Quadify] Applied service toggles from preference Feb 11 21:48:29 volumio2 systemd[1]: Started mpd.service - Music Player Daemon. Feb 11 21:48:29 volumio2 sudo[1858]: pam_unix(sudo:session): session closed for user root Feb 11 21:48:29 volumio2 sudo[1869]: pam_unix(sudo:session): session closed for user root Feb 11 21:48:29 volumio2 python3[900]: 2026-02-11 21:48:29,371 - VolumioListener - INFO - [VolumioListener] Received pushToastMessage event. Feb 11 21:48:29 volumio2 python3[900]: 2026-02-11 21:48:29,372 - VolumioListener - DEBUG - Toast Message Data: {'type': 'success', 'title': 'Configuration update', 'message': 'Player successfully restarted'} Feb 11 21:48:29 volumio2 volumio[1295]: info: Completed starting Core Plugins Feb 11 21:48:29 volumio2 volumio[1295]: info: ------------------------------------------- Feb 11 21:48:29 volumio2 volumio[1295]: info: ----- MyVolumio plugins startup ---- Feb 11 21:48:29 volumio2 volumio[1295]: info: ------------------------------------------- Feb 11 21:48:29 volumio2 volumio[1295]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 11 21:48:30 volumio2 volumio[1295]: error: MPD error: The expression evaluated to a falsy value: Feb 11 21:48:30 volumio2 volumio[1295]: assert.ok(self.idling) Feb 11 21:48:30 volumio2 volumio[1295]: error: The expression evaluated to a falsy value: Feb 11 21:48:30 volumio2 volumio[1295]: assert.ok(self.idling) Feb 11 21:48:30 volumio2 volumio[1295]: info: MPD running with PID1957 Feb 11 21:48:30 volumio2 volumio[1295]: ,establishing connection Feb 11 21:48:30 volumio2 volumio[1295]: error: updateQueue error: null Feb 11 21:48:30 volumio2 volumio[1295]: error: updateQueue error: null Feb 11 21:48:32 volumio2 sudo[2045]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 11 21:48:32 volumio2 sudo[2045]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 21:48:32 volumio2 sudo[2047]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 11 21:48:32 volumio2 sudo[2047]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 21:48:32 volumio2 systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Feb 11 21:48:32 volumio2 mpd_monitor.sh[2050]: MPD Monitor Service: Starting MPD Monitor Service Feb 11 21:48:32 volumio2 sudo[2045]: pam_unix(sudo:session): session closed for user root Feb 11 21:48:32 volumio2 systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Feb 11 21:48:32 volumio2 systemd[1]: mpd_monitor.service: Deactivated successfully. Feb 11 21:48:32 volumio2 systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Feb 11 21:48:32 volumio2 volumio[1295]: info: Successfully started MPD Monitor Feb 11 21:48:32 volumio2 systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Feb 11 21:48:33 volumio2 mpd_monitor.sh[2054]: MPD Monitor Service: Starting MPD Monitor Service Feb 11 21:48:33 volumio2 sudo[2047]: pam_unix(sudo:session): session closed for user root Feb 11 21:48:33 volumio2 volumio[1295]: info: Successfully started MPD Monitor Feb 11 21:48:35 volumio2 volumio[1295]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 11 21:48:48 volumio2 volumio[1295]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 21:48:48 volumio2 volumio[1295]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 21:48:48 volumio2 volumio[1295]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 11 21:48:52 volumio2 volumio[1295]: error: MyVolumio Plugin failed to start in a timely fashion Feb 11 21:48:52 volumio2 volumio[1295]: [Metrics] CommandRouter: 81s 23.87ms Feb 11 21:48:52 volumio2 volumio[1295]: info: CoreCommandRouter::volumiosetStartupVolume Feb 11 21:48:52 volumio2 volumio[1295]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 21:48:52 volumio2 volumio[1295]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 21:48:52 volumio2 volumio[1295]: info: CoreCommandRouter::Close All Modals sent Feb 11 21:48:52 volumio2 volumio[1295]: info: CoreCommandRouter::Close All Modals sent Feb 11 21:48:52 volumio2 upmpdcli[2092]: writing RSA key Feb 11 21:48:54 volumio2 volumio[1295]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Feb 11 21:48:54 volumio2 volumio[1295]: ALSA lib confmisc.c:165:(snd_config_get_card) Cannot get card index for M20 Feb 11 21:48:54 volumio2 volumio[1295]: aplay: main:831: audio open error: No such device Feb 11 21:48:54 volumio2 volumio[1295]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 11 21:48:54 volumio2 volumio[1295]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 11 21:48:55 volumio2 volumio[1295]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Feb 11 21:48:55 volumio2 volumio[1295]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 11 21:49:00 volumio2 volumio[1295]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 11 21:49:00 volumio2 volumio[1295]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 11 21:49:00 volumio2 volumio[1295]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 11 21:49:00 volumio2 volumio[1295]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 11 21:49:00 volumio2 volumio[1295]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 11 21:49:00 volumio2 volumio[1295]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 11 21:49:00 volumio2 volumio[1295]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 11 21:49:00 volumio2 volumio[1295]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 11 21:49:00 volumio2 volumio[1295]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 11 21:49:00 volumio2 volumio[1295]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 11 21:49:00 volumio2 volumio[1295]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 11 21:49:00 volumio2 volumio[1295]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 11 21:49:00 volumio2 volumio[1295]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 11 21:49:00 volumio2 volumio[1295]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 11 21:49:00 volumio2 volumio[1295]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 11 21:49:00 volumio2 volumio[1295]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 11 21:49:00 volumio2 volumio[1295]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 11 21:49:00 volumio2 volumio[1295]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 11 21:49:00 volumio2 volumio[1295]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 11 21:49:00 volumio2 volumio[1295]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 11 21:49:00 volumio2 volumio[1295]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 11 21:49:00 volumio2 volumio[1295]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 11 21:49:00 volumio2 volumio[1295]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 11 21:49:00 volumio2 volumio[1295]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 11 21:49:00 volumio2 volumio[1295]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 11 21:49:00 volumio2 volumio[1295]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 11 21:49:00 volumio2 volumio[1295]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 11 21:49:00 volumio2 volumio[1295]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 11 21:49:00 volumio2 volumio[1295]: info: Adding plugin bluetooth to MyMusic Plugins Feb 11 21:49:00 volumio2 volumio[1295]: info: Adding plugin multiroom to MyMusic Plugins Feb 11 21:49:00 volumio2 volumio[1295]: info: Adding plugin metavolumio to MyMusic Plugins Feb 11 21:49:00 volumio2 volumio[1295]: info: Adding plugin cd_controller to MyMusic Plugins Feb 11 21:49:00 volumio2 volumio[1295]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 11 21:49:00 volumio2 volumio[1295]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 11 21:49:00 volumio2 volumio[1295]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 11 21:49:00 volumio2 volumio[1295]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 11 21:49:08 volumio2 kernel: usb 1-1.4: new high-speed USB device number 4 using dwc_otg Feb 11 21:49:08 volumio2 kernel: usb 1-1.4: New USB device found, idVendor=2622, idProduct=0011, bcdDevice= 1.08 Feb 11 21:49:08 volumio2 kernel: usb 1-1.4: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Feb 11 21:49:08 volumio2 kernel: usb 1-1.4: Product: MISSION USB Audio 2.0 Feb 11 21:49:08 volumio2 kernel: usb 1-1.4: Manufacturer: MISSION Feb 11 21:49:08 volumio2 kernel: input: MISSION MISSION USB Audio 2.0 as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.4/1-1.4:1.3/0003:2622:0011.0001/input/input2 Feb 11 21:49:08 volumio2 kernel: hid-generic 0003:2622:0011.0001: input,hidraw0: USB HID v1.10 Device [MISSION MISSION USB Audio 2.0] on usb-3f980000.usb-1.4/input3 Feb 11 21:49:09 volumio2 kernel: usbcore: registered new interface driver snd-usb-audio Feb 11 21:49:09 volumio2 volumio[1295]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 11 21:49:09 volumio2 volumio[1295]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 11 21:49:09 volumio2 volumio[1295]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 21:49:09 volumio2 volumio[1295]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 21:49:09 volumio2 volumio[1295]: info: Starting MyVolumio Remote Streaming Endpoints Feb 11 21:49:09 volumio2 volumio[1295]: info: MyVolumio login type: Token Feb 11 21:49:10 volumio2 volumio[1295]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 11 21:49:10 volumio2 volumio[1295]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 11 21:49:20 volumio2 volumio[1295]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 11 21:49:20 volumio2 volumio[1295]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 11 21:49:20 volumio2 volumio[1295]: info: Streaming services startup Feb 11 21:49:20 volumio2 volumio[1295]: info: Starting Streaming Daemon Feb 11 21:49:20 volumio2 volumio[1295]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 11 21:49:20 volumio2 sudo[2167]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 11 21:49:20 volumio2 sudo[2167]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 21:49:20 volumio2 sudo[2167]: pam_unix(sudo:session): session closed for user root Feb 11 21:49:21 volumio2 volumio[1295]: info: BOOT COMPLETED Feb 11 21:49:21 volumio2 volumio[1295]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 11 21:49:21 volumio2 volumio[1295]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Feb 11 21:49:21 volumio2 volumio[1295]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Feb 11 21:49:21 volumio2 volumio[1295]: at new NodeError (node:internal/errors:405:5) Feb 11 21:49:21 volumio2 volumio[1295]: at assert (node:internal/assert:14:11) Feb 11 21:49:21 volumio2 volumio[1295]: at internalConnectMultiple (node:net:1118:3) Feb 11 21:49:21 volumio2 volumio[1295]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Feb 11 21:49:21 volumio2 volumio[1295]: at listOnTimeout (node:internal/timers:575:11) Feb 11 21:49:21 volumio2 volumio[1295]: at process.processTimers (node:internal/timers:514:7) { Feb 11 21:49:21 volumio2 volumio[1295]: code: 'ERR_INTERNAL_ASSERTION' Feb 11 21:49:21 volumio2 volumio[1295]: } Feb 11 21:49:21 volumio2 volumio[1295]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 11 21:49:22 volumio2 python3[900]: 2026-02-11 21:49:22,862 - QuadifyMain - INFO - Ready GIF exited, continuing to UI startup. Feb 11 21:49:22 volumio2 python3[900]: 2026-02-11 21:49:22,863 - ModeManager - DEBUG - ModeManager: Initializing... Feb 11 21:49:22 volumio2 python3[900]: 2026-02-11 21:49:22,869 - ModeManager - INFO - Loaded preferences: {'display_mode': 'vuscreen', 'modern_spectrum_mode': 'bars', 'clock_font_key': 'clock_sans', 'show_seconds': False, 'show_date': False, 'screensaver_enabled': True, 'screensaver_type': 'none', 'screensaver_timeout': 3600, 'oled_brightness': 255, 'cava_enabled': True, 'ignore_airplay': True, 'display': {'spectrum': True, 'screen': 'vuscreen', 'rotate': 180, 'oled_brightness': 255}, 'controls': {'buttons_led_service': False, 'mcp23017_address': '20'}, 'ir': {'enabled': True, 'profile': 'Xiaomi IR for TV box', 'gpio_bcm': 27}, 'safety': {'safe_shutdown': False}, 'meta': {'rev': 6, 'last_writer': 'rotary'}} Feb 11 21:49:22 volumio2 python3[900]: 2026-02-11 21:49:22,873 - ModeManager - DEBUG - ModeManager: idle_timeout=3600, display_mode=vuscreen Feb 11 21:49:22 volumio2 python3[900]: 2026-02-11 21:49:22,922 - transitions.core - WARNING - Skip binding of 'trigger' to model due to model override policy. Feb 11 21:49:22 volumio2 python3[900]: 2026-02-11 21:49:22,923 - transitions.core - WARNING - Skip binding of 'to_airplay' to model due to model override policy. Feb 11 21:49:22 volumio2 python3[900]: 2026-02-11 21:49:22,985 - ModeManager - DEBUG - ModeManager: Connected to volumio_listener.state_changed signal. Feb 11 21:49:22 volumio2 python3[900]: 2026-02-11 21:49:22,985 - ManagerFactory - INFO - ManagerFactory initialized. Feb 11 21:49:22 volumio2 python3[900]: 2026-02-11 21:49:22,989 - MenuManager - INFO - Loaded local icon: TIDAL from /data/plugins/system_hardware/quadify/quadifyapp/src/assets/pngs/TIDAL.png Feb 11 21:49:22 volumio2 python3[900]: 2026-02-11 21:49:22,994 - MenuManager - INFO - Loaded local icon: PLAYLISTS from /data/plugins/system_hardware/quadify/quadifyapp/src/assets/pngs/PLAYLISTS.png Feb 11 21:49:22 volumio2 python3[900]: 2026-02-11 21:49:22,996 - MenuManager - INFO - Loaded local icon: USB from /data/plugins/system_hardware/quadify/quadifyapp/src/assets/pngs/USB.png Feb 11 21:49:23 volumio2 python3[900]: 2026-02-11 21:49:23,000 - MenuManager - INFO - Loaded local icon: WEB_RADIO from /data/plugins/system_hardware/quadify/quadifyapp/src/assets/pngs/WEB_RADIO.png Feb 11 21:49:23 volumio2 python3[900]: 2026-02-11 21:49:23,002 - MenuManager - INFO - Loaded local icon: SOUNDCLOUD from /data/plugins/system_hardware/quadify/quadifyapp/src/assets/pngs/SOUNDCLOUD.png Feb 11 21:49:23 volumio2 python3[900]: 2026-02-11 21:49:23,005 - MenuManager - INFO - Loaded local icon: CONFIG from /data/plugins/system_hardware/quadify/quadifyapp/src/assets/pngs/CONFIG.png Feb 11 21:49:23 volumio2 python3[900]: 2026-02-11 21:49:23,031 - MenuManager - INFO - Loaded local icon: QOBUZ from /data/plugins/system_hardware/quadify/quadifyapp/src/assets/pngs/QOBUZ.png Feb 11 21:49:23 volumio2 python3[900]: 2026-02-11 21:49:23,231 - MenuManager - INFO - Loaded local icon: SPOTIFY from /data/plugins/system_hardware/quadify/quadifyapp/src/assets/pngs/SPOTIFY.png Feb 11 21:49:23 volumio2 python3[900]: 2026-02-11 21:49:23,234 - MenuManager - INFO - Loaded local icon: GENRES from /data/plugins/system_hardware/quadify/quadifyapp/src/assets/pngs/GENRES.png Feb 11 21:49:23 volumio2 python3[900]: 2026-02-11 21:49:23,237 - MenuManager - INFO - Loaded local icon: YOUTUBE_MUSIC from /data/plugins/system_hardware/quadify/quadifyapp/src/assets/pngs/YOUTUBE_MUSIC.png Feb 11 21:49:23 volumio2 python3[900]: 2026-02-11 21:49:23,239 - MenuManager - INFO - Loaded local icon: ARTISTS from /data/plugins/system_hardware/quadify/quadifyapp/src/assets/pngs/ARTISTS.png Feb 11 21:49:23 volumio2 python3[900]: 2026-02-11 21:49:23,241 - MenuManager - INFO - Loaded local icon: BACK from /data/plugins/system_hardware/quadify/quadifyapp/src/assets/pngs/BACK.png Feb 11 21:49:23 volumio2 python3[900]: 2026-02-11 21:49:23,243 - MenuManager - INFO - Loaded local icon: INTERNAL from /data/plugins/system_hardware/quadify/quadifyapp/src/assets/pngs/INTERNAL.png Feb 11 21:49:23 volumio2 python3[900]: 2026-02-11 21:49:23,245 - MenuManager - INFO - Loaded local icon: FAVORITES from /data/plugins/system_hardware/quadify/quadifyapp/src/assets/pngs/FAVORITES.png Feb 11 21:49:23 volumio2 python3[900]: 2026-02-11 21:49:23,247 - MenuManager - INFO - Loaded local icon: NAS from /data/plugins/system_hardware/quadify/quadifyapp/src/assets/pngs/NAS.png Feb 11 21:49:23 volumio2 python3[900]: 2026-02-11 21:49:23,248 - MenuManager - INFO - Loaded local icon: MEDIA_SERVERS from /data/plugins/system_hardware/quadify/quadifyapp/src/assets/pngs/MEDIA_SERVERS.png Feb 11 21:49:23 volumio2 python3[900]: 2026-02-11 21:49:23,254 - MenuManager - INFO - Loaded local icon: MOTHER_EARTH_RADIO from /data/plugins/system_hardware/quadify/quadifyapp/src/assets/pngs/MOTHER_EARTH_RADIO.png Feb 11 21:49:23 volumio2 python3[900]: 2026-02-11 21:49:23,273 - MenuManager - INFO - Loaded local icon: MIXCLOUD from /data/plugins/system_hardware/quadify/quadifyapp/src/assets/pngs/MIXCLOUD.png Feb 11 21:49:23 volumio2 python3[900]: 2026-02-11 21:49:23,275 - MenuManager - INFO - Loaded local icon: CALM_RADIO from /data/plugins/system_hardware/quadify/quadifyapp/src/assets/pngs/CALM_RADIO.png Feb 11 21:49:23 volumio2 python3[900]: 2026-02-11 21:49:23,277 - MenuManager - INFO - Loaded local icon: RADIO_PARADISE from /data/plugins/system_hardware/quadify/quadifyapp/src/assets/pngs/RADIO_PARADISE.png Feb 11 21:49:23 volumio2 python3[900]: 2026-02-11 21:49:23,279 - MenuManager - INFO - Loaded local icon: ALBUMS from /data/plugins/system_hardware/quadify/quadifyapp/src/assets/pngs/ALBUMS.png Feb 11 21:49:23 volumio2 python3[900]: 2026-02-11 21:49:23,281 - MenuManager - INFO - Loaded local icon: MUSIC_LIBRARY from /data/plugins/system_hardware/quadify/quadifyapp/src/assets/pngs/MUSIC_LIBRARY.png Feb 11 21:49:23 volumio2 python3[900]: 2026-02-11 21:49:23,283 - MenuManager - INFO - Loaded local icon: LAST_100 from /data/plugins/system_hardware/quadify/quadifyapp/src/assets/pngs/LAST_100.png Feb 11 21:49:23 volumio2 python3[900]: 2026-02-11 21:49:23,286 - MenuManager - INFO - Loaded local icon: 80S80S_RADIO from /data/plugins/system_hardware/quadify/quadifyapp/src/assets/pngs/80S80S_RADIO.png Feb 11 21:49:23 volumio2 python3[900]: 2026-02-11 21:49:23,294 - StreamingManager - INFO - Tidal StreamingManager initialised. Feb 11 21:49:23 volumio2 python3[900]: 2026-02-11 21:49:23,298 - RadioManager - INFO - RadioManager initialised. menu_controller=MenuManager Feb 11 21:49:23 volumio2 python3[900]: 2026-02-11 21:49:23,306 - ModernScreen - INFO - ModeManager: IconProvider dir=/data/plugins/system_hardware/quadify/quadifyapp/src/assets/pngs manifest=/data/plugins/system_hardware/quadify/quadifyapp/src/assets/icons_manifest.json Feb 11 21:49:23 volumio2 python3[900]: 2026-02-11 21:49:23,313 - ModernScreen - INFO - ModernScreen: Started background update thread. Feb 11 21:49:23 volumio2 python3[900]: 2026-02-11 21:49:23,314 - ModernScreen - INFO - ModernScreen initialized. Feb 11 21:49:23 volumio2 python3[900]: 2026-02-11 21:49:23,317 - MinimalScreen - INFO - MinimalScreen: Started background update thread. Feb 11 21:49:23 volumio2 python3[900]: 2026-02-11 21:49:23,318 - MinimalScreen - INFO - MinimalScreen initialized. Feb 11 21:49:23 volumio2 python3[900]: 2026-02-11 21:49:23,319 - OriginalScreen - INFO - OriginalScreen: Started background update thread. Feb 11 21:49:23 volumio2 python3[900]: 2026-02-11 21:49:23,319 - OriginalScreen - INFO - OriginalScreen initialized. Feb 11 21:49:23 volumio2 python3[900]: 2026-02-11 21:49:23,319 - OriginalScreen - INFO - OriginalScreen: IconProvider ready (dir=/data/plugins/system_hardware/quadify/quadifyapp/src/assets/pngs manifest=/data/plugins/system_hardware/quadify/quadifyapp/src/assets/icons_manifest.json) Feb 11 21:49:23 volumio2 python3[900]: DigitalVUScreen __init__ CALLED Feb 11 21:49:23 volumio2 python3[900]: 2026-02-11 21:49:23,342 - ConfigMenu - INFO - ConfigMenu initialised. menu_controller=MenuManager Feb 11 21:49:23 volumio2 python3[900]: 2026-02-11 21:49:23,347 - ClockMenu - INFO - ClockMenu initialised. menu_controller=MenuManager Feb 11 21:49:23 volumio2 python3[900]: 2026-02-11 21:49:23,356 - ManagerFactory - INFO - ManagerFactory: Using generic Screensaver (type=none). Feb 11 21:49:23 volumio2 python3[900]: 2026-02-11 21:49:23,360 - SystemUpdateMenu - INFO - SystemUpdateMenu initialised. menu_controller=MenuManager Feb 11 21:49:23 volumio2 python3[900]: 2026-02-11 21:49:23,360 - ManagerFactory - INFO - ManagerFactory: ModeManager fully configured with managers & screens. Feb 11 21:49:23 volumio2 python3[900]: 2026-02-11 21:49:23,361 - QuadifyMain - INFO - Handing off last Volumio state from DummyModeManager to real ModeManager Feb 11 21:49:23 volumio2 python3[900]: 2026-02-11 21:49:23,361 - ModeManager - DEBUG - ModeManager: process_state_change => {'status': 'stop', 'position': 0, 'title': "Devil's Answer", 'artist': 'Atomic Rooster', 'album': 'The Best Of', 'albumart': '/albumart?cacheid=658&web=Atomic%20Rooster/The%20Best%20Of/extralarge&path=%2FNAS%2Fshared%2FAtomic%20Rooster%2FThe%20Best%20Of&metadata=false', 'uri': "mnt/NAS/shared/Atomic Rooster/The Best Of/Atomic Rooster - Devil's Answer.flac", 'trackType': 'flac', 'seek': 0, 'duration': 253, 'random': None, 'repeat': None, 'repeatSingle': False, 'consume': False, 'volume': '', 'dbVolume': None, 'disableVolumeControl': False, 'mute': False, 'stream': 'flac', 'updatedb': False, 'volatile': False, 'service': 'mpd'} Feb 11 21:49:23 volumio2 python3[900]: 2026-02-11 21:49:23,361 - ModeManager - DEBUG - ModeManager: Skipping pause/stop timer after initial handoff. Feb 11 21:49:23 volumio2 python3[900]: 2026-02-11 21:49:23,361 - ModeManager - DEBUG - ModeManager: Completed process_state_change. Feb 11 21:49:23 volumio2 python3[900]: 2026-02-11 21:49:23,362 - ModeManager - DEBUG - ModeManager: Pushed 'clock' onto stack. Stack now: ['clock'] Feb 11 21:49:23 volumio2 python3[900]: 2026-02-11 21:49:23,362 - transitions.core - INFO - Finished processing state clock exit callbacks. Feb 11 21:49:23 volumio2 python3[900]: 2026-02-11 21:49:23,362 - ModeManager - INFO - ModeManager: Entering 'menu' state. Feb 11 21:49:23 volumio2 python3[900]: 2026-02-11 21:49:23,362 - ModeManager - DEBUG - ModeManager: stop_all_screens called. Feb 11 21:49:23 volumio2 python3[900]: 2026-02-11 21:49:23,417 - network.service_listener - INFO - Requesting Volumio root music services from: http://localhost:3000/api/v1/browse?uri= Feb 11 21:49:27 volumio2 sudo[2194]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-11 21:48' Feb 11 21:49:27 volumio2 sudo[2194]: 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"