Feb 21 10:57:01 volumio-salon ntpd[1169]: CLOCK: time stepped by 937867.216712 Feb 21 10:57:01 volumio-salon ntpd[1169]: CLOCK: time changed from 2026-02-10 to 2026-02-21 Feb 21 10:57:01 volumio-salon ntpd[1169]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Feb 21 10:57:01 volumio-salon systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... Feb 21 10:57:01 volumio-salon systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Feb 21 10:57:02 volumio-salon systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Feb 21 10:57:02 volumio-salon systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Feb 21 10:57:02 volumio-salon systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 21 10:57:02 volumio-salon systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 21 10:57:02 volumio-salon systemd[1]: dpkg-db-backup.service: Deactivated successfully. Feb 21 10:57:02 volumio-salon systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. Feb 21 10:57:02 volumio-salon upmpdcli[1575]: Could not open config: /tmp/upmpdcli.conf Feb 21 10:57:02 volumio-salon systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 21 10:57:02 volumio-salon systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 21 10:57:02 volumio-salon volumio[1390]: info: MYVOLUMIO Environment detected Feb 21 10:57:03 volumio-salon volumio[1390]: info: Plugin folders cleanup Feb 21 10:57:03 volumio-salon volumio[1390]: info: Scanning into folder /volumio/app/plugins/ Feb 21 10:57:03 volumio-salon volumio[1390]: info: Scanning category audio_interface Feb 21 10:57:03 volumio-salon volumio[1390]: info: Scanning category miscellanea Feb 21 10:57:03 volumio-salon volumio[1390]: info: Scanning category music_service Feb 21 10:57:03 volumio-salon volumio[1390]: info: Scanning category plugins.json Feb 21 10:57:03 volumio-salon volumio[1390]: info: Scanning category system_controller Feb 21 10:57:03 volumio-salon volumio[1390]: info: Scanning category user_interface Feb 21 10:57:03 volumio-salon volumio[1390]: info: Scanning into folder /data/plugins/ Feb 21 10:57:03 volumio-salon volumio[1390]: info: Scanning category music_service Feb 21 10:57:03 volumio-salon volumio[1390]: info: Scanning category user_interface Feb 21 10:57:03 volumio-salon volumio[1390]: info: Plugin folders cleanup completed Feb 21 10:57:03 volumio-salon volumio[1390]: info: ------------------------------------------- Feb 21 10:57:03 volumio-salon volumio[1390]: info: ----- Core plugins startup ---- Feb 21 10:57:03 volumio-salon volumio[1390]: info: ------------------------------------------- Feb 21 10:57:03 volumio-salon volumio[1390]: info: Loading plugins from folder /volumio/app/plugins/ Feb 21 10:57:03 volumio-salon volumio[1390]: info: Adding plugin upnp to MyMusic Plugins Feb 21 10:57:03 volumio-salon volumio[1390]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 21 10:57:03 volumio-salon volumio[1390]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 21 10:57:03 volumio-salon volumio[1390]: info: Loading plugins from folder /data/plugins/ Feb 21 10:57:03 volumio-salon volumio[1390]: info: Loading plugin "system"... Feb 21 10:57:03 volumio-salon volumio[1390]: info: Loading plugin "appearance"... Feb 21 10:57:03 volumio-salon systemd[1]: systemd-hostnamed.service: Deactivated successfully. Feb 21 10:57:03 volumio-salon volumio-remote-updater[866]: [2026-02-21 10:57:03] [connect] Successful connection Feb 21 10:57:06 volumio-salon volumio[1390]: info: Loading plugin "network"... Feb 21 10:57:06 volumio-salon volumio[1390]: info: Refreshing Cached IP Addresses Feb 21 10:57:06 volumio-salon sudo[1589]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 21 10:57:06 volumio-salon sudo[1590]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 21 10:57:06 volumio-salon sudo[1589]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 10:57:06 volumio-salon sudo[1590]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 10:57:06 volumio-salon volumio[1390]: info: Loading plugin "services"... Feb 21 10:57:06 volumio-salon volumio[1390]: info: Loading plugin "volumio5onboarding"... Feb 21 10:57:06 volumio-salon sudo[1589]: pam_unix(sudo:session): session closed for user root Feb 21 10:57:06 volumio-salon sudo[1590]: pam_unix(sudo:session): session closed for user root Feb 21 10:57:06 volumio-salon sudo[1597]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 21 10:57:06 volumio-salon sudo[1597]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 10:57:06 volumio-salon volumio[1390]: info: Loading plugin "alsa_controller"... Feb 21 10:57:07 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 21 10:57:07 volumio-salon volumio[1390]: info: Loading plugin "wizard"... Feb 21 10:57:07 volumio-salon volumio[1390]: info: Loading plugin "networkfs"... Feb 21 10:57:07 volumio-salon volumio[1390]: info: Starting Udev Watcher for removable devices Feb 21 10:57:07 volumio-salon volumio[1390]: info: Ignoring mount for partition: boot Feb 21 10:57:07 volumio-salon volumio[1390]: info: Ignoring mount for partition: volumio Feb 21 10:57:07 volumio-salon volumio[1390]: info: Ignoring mount for partition: volumio_data Feb 21 10:57:07 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 21 10:57:07 volumio-salon volumio[1390]: info: Loading plugin "volumio_command_line_client"... Feb 21 10:57:07 volumio-salon sudo[1622]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=volumio,password=V0lumio!!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.59/Musique /mnt/NAS/Nas-Musique Feb 21 10:57:07 volumio-salon sudo[1622]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 10:57:07 volumio-salon volumio[1390]: info: Plugin upnp is not enabled Feb 21 10:57:07 volumio-salon volumio[1390]: info: Loading plugin "my_music"... Feb 21 10:57:07 volumio-salon volumio[1390]: info: Loading plugin "mpd"... Feb 21 10:57:07 volumio-salon kernel: netfs: FS-Cache loaded Feb 21 10:57:07 volumio-salon kernel: Key type cifs.spnego registered Feb 21 10:57:07 volumio-salon kernel: Key type cifs.idmap registered Feb 21 10:57:07 volumio-salon 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. Feb 21 10:57:07 volumio-salon kernel: CIFS: Attempting to mount //192.168.0.59/Musique Feb 21 10:57:08 volumio-salon volumio[1390]: info: Plugin upnp_browser is not enabled Feb 21 10:57:08 volumio-salon volumio[1390]: info: Loading plugin "alarm-clock"... Feb 21 10:57:09 volumio-salon volumio[1390]: info: Plugin airplay_emulation is not enabled Feb 21 10:57:09 volumio-salon volumio[1390]: info: Loading plugin "last_100"... Feb 21 10:57:09 volumio-salon volumio[1390]: info: Loading plugin "webradio"... Feb 21 10:57:09 volumio-salon sudo[1622]: pam_unix(sudo:session): session closed for user root Feb 21 10:57:09 volumio-salon systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Feb 21 10:57:10 volumio-salon sudo[1597]: pam_unix(sudo:session): session closed for user root Feb 21 10:57:11 volumio-salon volumio[1390]: info: Loading plugin "i2s_dacs"... Feb 21 10:57:11 volumio-salon volumio[1390]: info: Loading plugin "volumiodiscovery"... Feb 21 10:57:12 volumio-salon volumio[1390]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 21 10:57:12 volumio-salon volumio[1390]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 21 10:57:12 volumio-salon volumio[1390]: *** WARNING *** For more information see Feb 21 10:57:12 volumio-salon volumio[1390]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 21 10:57:12 volumio-salon volumio[1390]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 21 10:57:12 volumio-salon node[1390]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 21 10:57:12 volumio-salon volumio[1390]: *** WARNING *** For more information see Feb 21 10:57:12 volumio-salon node[1390]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 21 10:57:12 volumio-salon node[1390]: *** WARNING *** For more information see Feb 21 10:57:12 volumio-salon node[1390]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 21 10:57:12 volumio-salon node[1390]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 21 10:57:12 volumio-salon node[1390]: *** WARNING *** For more information see Feb 21 10:57:12 volumio-salon volumio[1390]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 21 10:57:12 volumio-salon volumio[1390]: info: Discovery: Started advertising with name: Volumio-salon Feb 21 10:57:12 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 21 10:57:12 volumio-salon volumio[1390]: info: Loading plugin "jellyfin"... Feb 21 10:57:13 volumio-salon systemd[1]: setdatetime-helper.service: Deactivated successfully. Feb 21 10:57:13 volumio-salon systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Feb 21 10:57:13 volumio-salon systemd[1]: setdatetime-helper.service: Consumed 1.266s CPU time. Feb 21 10:57:15 volumio-salon volumio[1390]: info: Loading plugin "spop"... Feb 21 10:57:17 volumio-salon systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Feb 21 10:57:17 volumio-salon systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 21 10:57:17 volumio-salon systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 21 10:57:17 volumio-salon upmpdcli[1692]: Could not open config: /tmp/upmpdcli.conf Feb 21 10:57:17 volumio-salon systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 21 10:57:17 volumio-salon systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 21 10:57:18 volumio-salon volumio-remote-updater[866]: [2026-02-21 10:57:18] [connect] Successful connection Feb 21 10:57:19 volumio-salon volumio[1390]: info: Loading plugin "outputs"... Feb 21 10:57:19 volumio-salon volumio[1390]: info: Loading plugin "albumart"... Feb 21 10:57:19 volumio-salon volumio[1390]: info: Plugin example_plugin is not enabled Feb 21 10:57:19 volumio-salon volumio[1390]: info: Loading plugin "inputs"... Feb 21 10:57:19 volumio-salon volumio[1390]: info: Loading plugin "updater_comm"... Feb 21 10:57:20 volumio-salon volumio[1390]: info: Plugin mpdemulation is not enabled Feb 21 10:57:20 volumio-salon volumio[1390]: info: Loading plugin "rest_api"... Feb 21 10:57:20 volumio-salon volumio[1390]: info: Loading plugin "websocket"... Feb 21 10:57:20 volumio-salon volumio[1390]: info: Starting Socket.io Server version 1.7.4 Feb 21 10:57:20 volumio-salon volumio[1390]: info: Loading plugin "touch_display"... Feb 21 10:57:21 volumio-salon volumio[1390]: info: Applying required configuration parameters for plugin touch_display Feb 21 10:57:21 volumio-salon volumio[1693]: Forking 3 albumart workers Feb 21 10:57:23 volumio-salon volumio[1390]: info: Loading i18n strings for locale fr Feb 21 10:57:23 volumio-salon volumio[1390]: Updating browse sources language Feb 21 10:57:23 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 21 10:57:25 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 10:57:25 volumio-salon volumio[1390]: info: CoreCommandRouter::initPlayerControls Feb 21 10:57:25 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 10:57:25 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 10:57:26 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 10:57:26 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 10:57:26 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 10:57:26 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 10:57:26 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 10:57:26 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 10:57:26 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 10:57:26 volumio-salon volumio[1390]: Express server listening on port 3000 Feb 21 10:57:26 volumio-salon volumio[1390]: [Metrics] WebUI: 28s 308.18ms Feb 21 10:57:26 volumio-salon volumio[1390]: info: CoreStateMachine::resetVolumioState Feb 21 10:57:26 volumio-salon volumio[1390]: info: CoreStateMachine::getcurrentVolume Feb 21 10:57:26 volumio-salon volumio[1390]: info: CoreCommandRouter::volumioRetrievevolume Feb 21 10:57:27 volumio-salon volumio[1390]: info: Volumio Network Manager: Network status updated: 2 Feb 21 10:57:28 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 10:57:28 volumio-salon volumio[1390]: info: Reloading queue from file Feb 21 10:57:29 volumio-salon volumio[1390]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Feb 21 10:57:29 volumio-salon volumio[1390]: info: Setting Device type: Raspberry PI Feb 21 10:57:29 volumio-salon volumio[1390]: info: CoreStateMachine::setRepeat false single undefined Feb 21 10:57:29 volumio-salon volumio[1390]: info: CoreStateMachine::pushState Feb 21 10:57:29 volumio-salon volumio[1390]: info: CorePlayQueue::getTrack 0 Feb 21 10:57:29 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 10:57:29 volumio-salon volumio[1390]: info: CoreCommandRouter::volumioPushState Feb 21 10:57:29 volumio-salon volumio[1390]: info: CoreStateMachine::setRandom true Feb 21 10:57:29 volumio-salon volumio[1390]: info: CoreStateMachine::pushState Feb 21 10:57:29 volumio-salon volumio[1390]: info: CorePlayQueue::getTrack 0 Feb 21 10:57:29 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 10:57:29 volumio-salon volumio[1390]: info: CoreCommandRouter::volumioPushState Feb 21 10:57:30 volumio-salon volumio[1390]: info: Completed loading Core Plugins Feb 21 10:57:30 volumio-salon volumio[1390]: info: Preparing to generate the ALSA configuration file Feb 21 10:57:30 volumio-salon volumio[1390]: info: VolumeController:: Volume=46 Mute =false Feb 21 10:57:30 volumio-salon volumio[1390]: info: CoreStateMachine::pushState Feb 21 10:57:30 volumio-salon volumio[1390]: info: CorePlayQueue::getTrack 0 Feb 21 10:57:30 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 10:57:30 volumio-salon volumio[1390]: info: CoreCommandRouter::volumioPushState Feb 21 10:57:30 volumio-salon volumio[1390]: info: CoreStateMachine::updateTrackBlock Feb 21 10:57:30 volumio-salon volumio[1390]: info: CorePlayQueue::getTrackBlock Feb 21 10:57:30 volumio-salon volumio[1390]: info: CoreCommandRouter::volumioRetrievevolume Feb 21 10:57:30 volumio-salon volumio[1390]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Feb 21 10:57:30 volumio-salon volumio[1390]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03112 Feb 21 10:57:30 volumio-salon volumio[1390]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Feb 21 10:57:31 volumio-salon volumio[1390]: info: Discovery: adding b23eae8f-afd5-4a3b-bc4f-92f4a621b32d Feb 21 10:57:31 volumio-salon volumio[1390]: info: Discovery: Found device Volumio-salon Feb 21 10:57:31 volumio-salon volumio[1390]: info: CoreCommandRouter::volumioGetState Feb 21 10:57:31 volumio-salon volumio[1390]: info: CorePlayQueue::getTrack 0 Feb 21 10:57:31 volumio-salon volumio[1390]: info: Discovery: this is already registered, b23eae8f-afd5-4a3b-bc4f-92f4a621b32d Feb 21 10:57:31 volumio-salon volumio[1390]: info: Discovery: Found device Volumio-salon Feb 21 10:57:31 volumio-salon volumio[1390]: info: CoreCommandRouter::volumioGetState Feb 21 10:57:31 volumio-salon volumio[1390]: info: CorePlayQueue::getTrack 0 Feb 21 10:57:31 volumio-salon volumio[1703]: Starting albumart workers Feb 21 10:57:32 volumio-salon volumio[1390]: info: VolumeController:: Volume=46 Mute =false Feb 21 10:57:32 volumio-salon volumio[1390]: info: CoreStateMachine::pushState Feb 21 10:57:32 volumio-salon volumio[1390]: info: CorePlayQueue::getTrack 0 Feb 21 10:57:32 volumio-salon volumio[1390]: info: CoreCommandRouter::volumioPushState Feb 21 10:57:32 volumio-salon volumio[1390]: info: Asound.conf file written Feb 21 10:57:32 volumio-salon sudo[1767]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 21 10:57:32 volumio-salon sudo[1767]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 10:57:32 volumio-salon sudo[1767]: pam_unix(sudo:session): session closed for user root Feb 21 10:57:32 volumio-salon systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 4. Feb 21 10:57:33 volumio-salon wpa_supplicant[1351]: RRM: Ignoring radio measurement request: Not RRM network Feb 21 10:57:33 volumio-salon systemd[1]: Starting e2scrub_all.service - Online ext4 Metadata Check for All Filesystems... Feb 21 10:57:33 volumio-salon systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 21 10:57:33 volumio-salon volumio[1390]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Feb 21 10:57:33 volumio-salon volumio[1390]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Feb 21 10:57:33 volumio-salon volumio[1390]: No state is present for card vc4hdmi0 Feb 21 10:57:33 volumio-salon volumio[1390]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Feb 21 10:57:33 volumio-salon volumio[1390]: Found hardware: "vc4-hdmi" "" "" "" "" Feb 21 10:57:33 volumio-salon volumio[1390]: Hardware is initialized using a generic method Feb 21 10:57:33 volumio-salon volumio[1390]: No state is present for card vc4hdmi0 Feb 21 10:57:33 volumio-salon volumio[1390]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Feb 21 10:57:33 volumio-salon volumio[1390]: No state is present for card vc4hdmi1 Feb 21 10:57:33 volumio-salon volumio[1390]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Feb 21 10:57:33 volumio-salon volumio[1390]: Found hardware: "vc4-hdmi" "" "" "" "" Feb 21 10:57:33 volumio-salon volumio[1390]: Hardware is initialized using a generic method Feb 21 10:57:33 volumio-salon volumio[1390]: No state is present for card vc4hdmi1 Feb 21 10:57:33 volumio-salon volumio[1390]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Feb 21 10:57:33 volumio-salon volumio[1390]: info: Output device has changed, restarting MPD Feb 21 10:57:33 volumio-salon systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 21 10:57:33 volumio-salon systemd[1]: e2scrub_all.service: Deactivated successfully. Feb 21 10:57:33 volumio-salon systemd[1]: Finished e2scrub_all.service - Online ext4 Metadata Check for All Filesystems. Feb 21 10:57:33 volumio-salon upmpdcli[1773]: Could not open config: /tmp/upmpdcli.conf Feb 21 10:57:33 volumio-salon systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 21 10:57:33 volumio-salon systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 21 10:57:33 volumio-salon volumio[1713]: Starting albumart workers Feb 21 10:57:33 volumio-salon volumio[1390]: info: ___________ START PLUGINS ___________ Feb 21 10:57:33 volumio-salon sudo[1775]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 21 10:57:33 volumio-salon volumio[1390]: info: ControllerMpd::onStart: Initializing MPD Feb 21 10:57:33 volumio-salon sudo[1777]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 21 10:57:33 volumio-salon sudo[1777]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 10:57:33 volumio-salon volumio[1390]: info: Creating MPD Configuration file Feb 21 10:57:33 volumio-salon sudo[1775]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 10:57:33 volumio-salon sudo[1775]: pam_unix(sudo:session): session closed for user root Feb 21 10:57:33 volumio-salon volumio[1390]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 21 10:57:33 volumio-salon volumio[1390]: info: [1771667853731] CoreMusicLibrary::Adding element Last_100 Feb 21 10:57:33 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 21 10:57:33 volumio-salon volumio-remote-updater[866]: [2026-02-21 10:57:33] [connect] Successful connection Feb 21 10:57:33 volumio-salon volumio[1390]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 21 10:57:33 volumio-salon volumio[1390]: info: [1771667853805] CoreMusicLibrary::Adding element Webradio Feb 21 10:57:33 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 21 10:57:33 volumio-salon volumio[1704]: Starting albumart workers Feb 21 10:57:33 volumio-salon sudo[1779]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Feb 21 10:57:33 volumio-salon sudo[1779]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 10:57:33 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 21 10:57:33 volumio-salon volumio[1390]: info: Initializing BBC Radios Feb 21 10:57:33 volumio-salon systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 21 10:57:33 volumio-salon sudo[1782]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 21 10:57:33 volumio-salon sudo[1782]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 10:57:33 volumio-salon systemd[1]: Starting mpd.service - Music Player Daemon... Feb 21 10:57:34 volumio-salon sudo[1785]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 21 10:57:34 volumio-salon sudo[1785]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 10:57:34 volumio-salon sudo[1782]: pam_unix(sudo:session): session closed for user root Feb 21 10:57:34 volumio-salon sudo[1790]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 21 10:57:34 volumio-salon sudo[1790]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 21 10:57:34 volumio-salon sudo[1794]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Feb 21 10:57:34 volumio-salon sudo[1790]: pam_unix(sudo:session): session closed for user root Feb 21 10:57:34 volumio-salon systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Feb 21 10:57:34 volumio-salon sudo[1779]: pam_unix(sudo:session): session closed for user root Feb 21 10:57:34 volumio-salon systemd[1]: mpd.service: Deactivated successfully. Feb 21 10:57:34 volumio-salon systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 21 10:57:34 volumio-salon systemd[1]: mpd.socket: Deactivated successfully. Feb 21 10:57:34 volumio-salon systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 21 10:57:34 volumio-salon systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 21 10:57:34 volumio-salon systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 21 10:57:34 volumio-salon systemd[1]: Starting mpd.service - Music Player Daemon... Feb 21 10:57:34 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 21 10:57:34 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 10:57:35 volumio-salon sudo[1811]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 21 10:57:35 volumio-salon sudo[1811]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 21 10:57:35 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 21 10:57:35 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 10:57:35 volumio-salon volumio[1390]: info: Discovery: Getting this device information Feb 21 10:57:35 volumio-salon volumio[1390]: info: CoreCommandRouter::volumioGetState Feb 21 10:57:35 volumio-salon volumio[1390]: info: CorePlayQueue::getTrack 0 Feb 21 10:57:35 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 10:57:35 volumio-salon sudo[1815]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Feb 21 10:57:35 volumio-salon sudo[1811]: pam_unix(sudo:session): session closed for user root Feb 21 10:57:35 volumio-salon volumio5-onboarding[1797]: time=2026-02-21T10:57:35.373+01:00 level=INFO msg="running volumio5-device-gateway" version=30d1950e+CHANGES buildDate=2026-01-29T13:10:08Z Feb 21 10:57:35 volumio-salon volumio[1390]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 21 10:57:35 volumio-salon volumio[1390]: info: [1771667855452] CoreMusicLibrary::Adding element Jellyfin Feb 21 10:57:35 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 21 10:57:35 volumio-salon volumio[1390]: Cannot find translation for source Jellyfin Feb 21 10:57:35 volumio-salon volumio[1390]: info: [jellyfin] Initialized plugin with device info: {"host":"http://192.168.0.91","id":"b23eae8f-afd5-4a3b-bc4f-92f4a621b32d","name":"Volumio-salon","serviceName":"Volumio","state":{"albumart":"https://static.qobuz.com/images/covers/30/21/0093624932130_600.jpg","artist":"Red Hot Chili Peppers","mute":false,"status":"stop","track":"Around the World","volume":46},"type":"device"} Feb 21 10:57:35 volumio-salon volumio[1390]: info: Creating Spotify config file Feb 21 10:57:35 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 10:57:39 volumio-salon volumio[1390]: info: Loading i18n strings for locale fr Feb 21 10:57:39 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 21 10:57:39 volumio-salon volumio[1390]: info: Volumio Calling Home Feb 21 10:57:40 volumio-salon sudo[1846]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /data/volumiokioskextensions/IframeKeyboardBridge Feb 21 10:57:40 volumio-salon sudo[1846]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 10:57:40 volumio-salon sudo[1846]: pam_unix(sudo:session): session closed for user root Feb 21 10:57:41 volumio-salon sudo[1858]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Feb 21 10:57:41 volumio-salon sudo[1858]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 10:57:41 volumio-salon sudo[1861]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Feb 21 10:57:41 volumio-salon sudo[1861]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 10:57:41 volumio-salon sudo[1862]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Feb 21 10:57:41 volumio-salon sudo[1858]: pam_unix(sudo:session): session closed for user root Feb 21 10:57:41 volumio-salon sudo[1862]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 10:57:41 volumio-salon systemd[1]: Reloading. Feb 21 10:57:42 volumio-salon volumio[1390]: info: touch_display: Backlight interface detected. Feb 21 10:57:43 volumio-salon volumio[1390]: info: touch_display: systemctl stop getty@tty1.service succeeded. Feb 21 10:57:43 volumio-salon volumio[1390]: info: MPD Permissions set Feb 21 10:57:43 volumio-salon volumio[1390]: info: MPD Permissions set Feb 21 10:57:43 volumio-salon sudo[1885]: 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/ Feb 21 10:57:43 volumio-salon sudo[1885]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 10:57:44 volumio-salon sudo[1885]: pam_unix(sudo:session): session closed for user root Feb 21 10:57:44 volumio-salon volumio[1390]: info: Volumio called home Feb 21 10:57:44 volumio-salon volumio[1390]: info: Spotify config file written Feb 21 10:57:44 volumio-salon sudo[1861]: pam_unix(sudo:session): session closed for user root Feb 21 10:57:44 volumio-salon systemd[1]: Reloading. Feb 21 10:57:44 volumio-salon mpd[1816]: 2026-02-21T10:57:44 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 21 10:57:44 volumio-salon sudo[1890]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 21 10:57:44 volumio-salon sudo[1890]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 10:57:45 volumio-salon volumio5-onboarding[1797]: failed to create app: failed to initialize host: failed to create socket connection: could not connect to socket.io server: read tcp 127.0.0.1:55828->127.0.0.1:3000: i/o timeout Feb 21 10:57:45 volumio-salon volumio[1390]: info: touch_display: IframeKeyboardBridge extension installed successfully Feb 21 10:57:46 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 10:57:46 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 10:57:46 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 10:57:46 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 10:57:46 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 10:57:46 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 10:57:46 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 10:57:46 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 10:57:46 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 10:57:46 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 10:57:46 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 10:57:46 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 10:57:46 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 10:57:46 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 10:57:46 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 10:57:46 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 10:57:46 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 10:57:46 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 10:57:46 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 10:57:46 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 10:57:46 volumio-salon volumio[1390]: info: No need to fix Spotify hosts Feb 21 10:57:47 volumio-salon systemd[1]: Started mpd.service - Music Player Daemon. Feb 21 10:57:47 volumio-salon systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Feb 21 10:57:47 volumio-salon systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Feb 21 10:57:47 volumio-salon sudo[1862]: pam_unix(sudo:session): session closed for user root Feb 21 10:57:47 volumio-salon sudo[1785]: pam_unix(sudo:session): session closed for user root Feb 21 10:57:47 volumio-salon 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. Feb 21 10:57:47 volumio-salon 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. Feb 21 10:57:47 volumio-salon sudo[1777]: pam_unix(sudo:session): session closed for user root Feb 21 10:57:47 volumio-salon systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 21 10:57:47 volumio-salon go-librespot[1922]: go-librespot daemon starting... Feb 21 10:57:47 volumio-salon systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 1. Feb 21 10:57:47 volumio-salon sudo[1890]: pam_unix(sudo:session): session closed for user root Feb 21 10:57:47 volumio-salon systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Feb 21 10:57:47 volumio-salon systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Feb 21 10:57:47 volumio-salon volumio5-onboarding[1924]: time=2026-02-21T10:57:47.513+01:00 level=INFO msg="running volumio5-device-gateway" version=30d1950e+CHANGES buildDate=2026-01-29T13:10:08Z Feb 21 10:57:47 volumio-salon volumio[1390]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Feb 21 10:57:47 volumio-salon volumio[1390]: info: touch_display: systemctl daemon-reload succeeded. Feb 21 10:57:47 volumio-salon go-librespot[1923]: time="2026-02-21T10:57:47+01:00" level=info msg="running go-librespot 0.4.0" Feb 21 10:57:47 volumio-salon go-librespot[1923]: time="2026-02-21T10:57:47+01:00" level=debug msg="app state loaded" Feb 21 10:57:47 volumio-salon go-librespot[1923]: time="2026-02-21T10:57:47+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 21 10:57:47 volumio-salon sudo[1938]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Feb 21 10:57:48 volumio-salon sudo[1938]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 10:57:48 volumio-salon systemd[1]: Started volumio-kiosk.service - Volumio Kiosk. Feb 21 10:57:48 volumio-salon sudo[1938]: pam_unix(sudo:session): session closed for user root Feb 21 10:57:48 volumio-salon systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 5. Feb 21 10:57:48 volumio-salon volumio[1390]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 21 10:57:48 volumio-salon volumio[1390]: SPOTIFY: BQAWHMx9TqD08IE6PScLFdWme5C5Nhs50iUSZ6jjgaNC1S5LoLCc8uen1qAP8Z0iP0XoxDSMXTvkAi16IvPkkIlwU5XaNmExlUqqNJy-haRBDIhARJZDifKICCAmCEg4-wZc41I0Rjs9LJwG8Zki6aDcvkHnNIz7MVnY3CMNuw4Av-U-d-P58Ot3TGscYBP1l8u-mIOtnx9JwMFrVRWRCx0SqhtLa2OCsSBtUMGhGotnYUrJjD6pHkthiDRn70784EH2Z2fTWLQN3oMIPsCI5JVdjcJ1soEfCE8AISmmifwNXUGIQdHhxXT1-wy9akAX_XwWH5l0GKZu4VNuORvJgljj7AK6gEQ5SQolNizTMJlVAkNLdNKU Feb 21 10:57:48 volumio-salon volumio[1390]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 21 10:57:48 volumio-salon systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 21 10:57:48 volumio-salon systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 21 10:57:48 volumio-salon volumio[1390]: info: New Spotify access token = BQAWHMx9TqD08IE6PScLFdWme5C5Nhs50iUSZ6jjgaNC1S5LoLCc8uen1qAP8Z0iP0XoxDSMXTvkAi16IvPkkIlwU5XaNmExlUqqNJy-haRBDIhARJZDifKICCAmCEg4-wZc41I0Rjs9LJwG8Zki6aDcvkHnNIz7MVnY3CMNuw4Av-U-d-P58Ot3TGscYBP1l8u-mIOtnx9JwMFrVRWRCx0SqhtLa2OCsSBtUMGhGotnYUrJjD6pHkthiDRn70784EH2Z2fTWLQN3oMIPsCI5JVdjcJ1soEfCE8AISmmifwNXUGIQdHhxXT1-wy9akAX_XwWH5l0GKZu4VNuORvJgljj7AK6gEQ5SQolNizTMJlVAkNLdNKU Feb 21 10:57:48 volumio-salon volumio[1390]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 21 10:57:48 volumio-salon upmpdcli[1943]: Could not open config: /tmp/upmpdcli.conf Feb 21 10:57:48 volumio-salon systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 21 10:57:48 volumio-salon systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 21 10:57:48 volumio-salon go-librespot[1923]: time="2026-02-21T10:57:48+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Feb 21 10:57:48 volumio-salon go-librespot[1923]: time="2026-02-21T10:57:48+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 21 10:57:48 volumio-salon go-librespot[1923]: time="2026-02-21T10:57:48+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 21 10:57:48 volumio-salon go-librespot[1923]: time="2026-02-21T10:57:48+01:00" level=info msg="zeroconf server listening on port 34089" Feb 21 10:57:48 volumio-salon startx[1972]: X.Org X Server 1.21.1.7 Feb 21 10:57:48 volumio-salon startx[1972]: X Protocol Version 11, Revision 0 Feb 21 10:57:48 volumio-salon startx[1972]: Current Operating System: Linux volumio-salon 6.12.47-v7l+ #1904 SMP Mon Sep 15 13:08:10 BST 2025 armv7l Feb 21 10:57:48 volumio-salon startx[1972]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory numa_policy=interleave nvme.max_host_mem_size_mb=0 snd_bcm2835.enable_hdmi=0 video=HDMI-A-1:640x480M@60D numa=fake=2 system_heap.max_order=0 smsc95xx.macaddr=DC:A6:32:9D:57:E7 vc_mem.mem_base=0x3eb00000 vc_mem.mem_size=0x3ff00000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=dafa3844-b779-48cd-9b4c-01ecfd09e0f4 imgfile=/volumio_current.sqsh bootpart=UUID=3B89-0B23 datapart=UUID=752d19ad-b702-471d-847a-f79ae83515d0 uuidconfig=cmdline.txt rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Feb 21 10:57:48 volumio-salon startx[1972]: xorg-server 2:21.1.7-3+rpt3+deb12u11 (https://www.debian.org/support) Feb 21 10:57:48 volumio-salon startx[1972]: Current version of pixman: 0.44.0 Feb 21 10:57:48 volumio-salon startx[1972]: Before reporting problems, check http://wiki.x.org Feb 21 10:57:48 volumio-salon startx[1972]: to make sure that you have the latest version. Feb 21 10:57:48 volumio-salon startx[1972]: Markers: (--) probed, (**) from config file, (==) default setting, Feb 21 10:57:48 volumio-salon startx[1972]: (++) from command line, (!!) notice, (II) informational, Feb 21 10:57:48 volumio-salon startx[1972]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Feb 21 10:57:48 volumio-salon startx[1972]: (==) Log file: "/var/log/Xorg.0.log", Time: Sat Feb 21 10:57:48 2026 Feb 21 10:57:48 volumio-salon go-librespot[1923]: time="2026-02-21T10:57:48+01:00" level=debug msg="obtained new client token: AAAHeYPx4db1v1xRVZbxUV02pR6tG0ASEidC/yr6V8zFuWJ8g6MQq5I/YitrgFmNOwU7Zf/8lLe/8eEnM8pz6/sQacj05ncOHtZlZ1eLaEIz0wt5NgAHI3OIAHYY5cQVhoB01D2tMsVPJe/m1cpQ4hwLH467iMQ00F2mFEXog7q5+idRhx4ULnKLwoU0efmt/TyW37cStGV3p7q/Cwu2hdC22KB0uBm2uEmTE5JrJAm0Vn6U4Gyg7g==" Feb 21 10:57:48 volumio-salon startx[1972]: (==) Using config directory: "/etc/X11/xorg.conf.d" Feb 21 10:57:48 volumio-salon startx[1972]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Feb 21 10:57:48 volumio-salon go-librespot[1923]: time="2026-02-21T10:57:48+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Feb 21 10:57:48 volumio-salon go-librespot[1923]: time="2026-02-21T10:57:48+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 21 10:57:48 volumio-salon systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 21 10:57:48 volumio-salon systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 21 10:57:48 volumio-salon volumio-remote-updater[866]: [2026-02-21 10:57:48] [connect] Successful connection Feb 21 10:57:49 volumio-salon volumio[1390]: info: touch_display: systemctl disable getty@tty1.service succeeded. Feb 21 10:57:49 volumio-salon volumio[1390]: info: touch_display: Raspberry Pi Foundation touch screen detected. Feb 21 10:57:49 volumio-salon sudo[1983]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/class/backlight/10-0045/brightness Feb 21 10:57:49 volumio-salon sudo[1983]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 10:57:49 volumio-salon sudo[1984]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Feb 21 10:57:49 volumio-salon volumio[1390]: info: [jellyfin-poller] Polled http://192.168.0.19:8096: online Feb 21 10:57:49 volumio-salon sudo[1984]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 10:57:49 volumio-salon sudo[1983]: pam_unix(sudo:session): session closed for user root Feb 21 10:57:49 volumio-salon sudo[1984]: pam_unix(sudo:session): session closed for user root Feb 21 10:57:49 volumio-salon sudo[1987]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Feb 21 10:57:49 volumio-salon sudo[1987]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 10:57:49 volumio-salon sudo[1987]: pam_unix(sudo:session): session closed for user root Feb 21 10:57:49 volumio-salon volumio[1390]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Feb 21 10:57:49 volumio-salon volumio[1390]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Feb 21 10:57:49 volumio-salon volumio[1390]: info: touch_display: Volumio Kiosk started. Feb 21 10:57:49 volumio-salon volumio[1390]: info: CoreCommandRouter::volumioGetState Feb 21 10:57:49 volumio-salon volumio[1390]: info: CorePlayQueue::getTrack 0 Feb 21 10:57:49 volumio-salon volumio[1390]: info: Completed starting Core Plugins Feb 21 10:57:49 volumio-salon volumio[1390]: info: ------------------------------------------- Feb 21 10:57:49 volumio-salon volumio[1390]: info: ----- MyVolumio plugins startup ---- Feb 21 10:57:49 volumio-salon volumio[1390]: info: ------------------------------------------- Feb 21 10:57:50 volumio-salon volumio[1390]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 21 10:57:50 volumio-salon volumio[1390]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Feb 21 10:57:50 volumio-salon volumio[1390]: info: Received Get System Info Feb 21 10:57:50 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 10:57:50 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 10:57:50 volumio-salon volumio[1390]: info: Discovery: Getting this device information Feb 21 10:57:50 volumio-salon volumio[1390]: info: CoreCommandRouter::volumioGetState Feb 21 10:57:50 volumio-salon volumio[1390]: info: CorePlayQueue::getTrack 0 Feb 21 10:57:50 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 10:57:50 volumio-salon volumio5-onboarding[1924]: time=2026-02-21T10:57:50.360+01:00 level=INFO msg="system info for 9fc08a67e7146773111ef669bc33293a" deviceName=Volumio-salon deviceVariant=volumio deviceModel= softwareVersion=4.096 Feb 21 10:57:50 volumio-salon volumio[1390]: error: MPD error: The expression evaluated to a falsy value: Feb 21 10:57:50 volumio-salon volumio[1390]: assert.ok(self.idling) Feb 21 10:57:50 volumio-salon volumio[1390]: error: The expression evaluated to a falsy value: Feb 21 10:57:50 volumio-salon volumio[1390]: assert.ok(self.idling) Feb 21 10:57:51 volumio-salon volumio[1390]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Feb 21 10:57:51 volumio-salon volumio[1390]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Feb 21 10:57:51 volumio-salon volumio[1390]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Feb 21 10:57:51 volumio-salon volumio[1390]: info: touch_display: File permissions for backlight brightness control set. Feb 21 10:57:51 volumio-salon volumio[1390]: info: MPD running with PID1816 Feb 21 10:57:51 volumio-salon volumio[1390]: ,establishing connection Feb 21 10:57:51 volumio-salon volumio[1390]: info: Received Get System Info Feb 21 10:57:51 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 10:57:51 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 10:57:51 volumio-salon volumio[1390]: info: Discovery: Getting this device information Feb 21 10:57:51 volumio-salon volumio[1390]: info: CoreCommandRouter::volumioGetState Feb 21 10:57:51 volumio-salon volumio[1390]: info: CorePlayQueue::getTrack 0 Feb 21 10:57:51 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 10:57:51 volumio-salon volumio[1390]: error: updateQueue error: null Feb 21 10:57:51 volumio-salon kernel: ------------[ cut here ]------------ Feb 21 10:57:51 volumio-salon kernel: WARNING: CPU: 2 PID: 1972 at drivers/gpu/drm/vc4/vc4_hvs.c:1064 __vc4_hvs_stop_channel+0x168/0x1dc [vc4] Feb 21 10:57:51 volumio-salon kernel: Modules linked in: md5 sha512_generic nls_utf8 cifs cifs_arc4 nls_ucs2_utils netfs cifs_md4 cmac algif_hash aes_arm_bs crypto_simd cryptd aes_arm aes_generic algif_skcipher af_alg bnep nft_chain_nat xt_REDIRECT nf_nat nf_conntrack nf_defrag_ipv6 nf_defrag_ipv4 xt_tcpudp nft_compat nf_tables joydev nfnetlink 8021q garp stp llc brcmfmac_wcc binfmt_misc hci_uart btbcm bluetooth brcmfmac bcm2835_codec(C) snd_soc_pcm179x_i2c bcm2835_v4l2(C) edt_ft5x06 bcm2835_isp(C) snd_soc_pcm179x_codec brcmutil rpi_hevc_dec cfg80211 bcm2835_mmal_vchiq(C) vc_sm_cma(C) snd_soc_hifiberry_dacplushd v4l2_mem2mem videobuf2_vmalloc videobuf2_dma_contig videobuf2_memops videobuf2_v4l2 videodev ecdh_generic raspberrypi_hwmon ecc libaes rfkill raspberrypi_gpiomem videobuf2_common sg mc nvmem_rmem uio_pdrv_genirq uio i2c_dev dm_mod ip_tables x_tables ipv6 ads7846 fb_hx8357d(C) fb_st7789v(C) fb_st7735r(C) fb_ili9341(C) fb_ili9340(C) fbtft(C) panel_waveshare_dsi_v2 panel_waveshare_dsi panel_ilitek_ili9881c panel_raspberrypi_touchscreen Feb 21 10:57:51 volumio-salon kernel: spi_bcm2835 squashfs overlay nls_iso8859_1 fuse tc358762 rpi_panel_attiny_regulator clk_hifiberry_dachd regmap_i2c vc4 v3d snd_soc_hdmi_codec gpu_sched drm_display_helper drm_shmem_helper cec drm_dma_helper snd_soc_bcm2835_i2s snd_soc_core drm_kms_helper snd_compress snd_bcm2835(C) snd_pcm_dmaengine snd_pcm snd_timer i2c_mux_pinctrl i2c_mux i2c_brcmstb snd panel_simple drm i2c_bcm2835 drm_panel_orientation_quirks backlight Feb 21 10:57:51 volumio-salon kernel: CPU: 2 UID: 0 PID: 1972 Comm: Xorg Tainted: G C 6.12.47-v7l+ #1904 Feb 21 10:57:51 volumio-salon kernel: Tainted: [C]=CRAP Feb 21 10:57:51 volumio-salon kernel: Hardware name: BCM2711 Feb 21 10:57:51 volumio-salon kernel: Call trace: Feb 21 10:57:51 volumio-salon kernel: unwind_backtrace from show_stack+0x18/0x1c Feb 21 10:57:51 volumio-salon kernel: show_stack from dump_stack_lvl+0x5c/0x80 Feb 21 10:57:51 volumio-salon kernel: dump_stack_lvl from __warn+0x88/0x124 Feb 21 10:57:51 volumio-salon kernel: __warn from warn_slowpath_fmt+0x184/0x190 Feb 21 10:57:51 volumio-salon kernel: warn_slowpath_fmt from __vc4_hvs_stop_channel+0x168/0x1dc [vc4] Feb 21 10:57:51 volumio-salon kernel: __vc4_hvs_stop_channel [vc4] from vc4_crtc_disable+0x138/0x1dc [vc4] Feb 21 10:57:51 volumio-salon kernel: vc4_crtc_disable [vc4] from vc4_crtc_atomic_disable+0x9c/0xc0 [vc4] Feb 21 10:57:51 volumio-salon kernel: vc4_crtc_atomic_disable [vc4] from disable_outputs+0x268/0x3a0 [drm_kms_helper] Feb 21 10:57:51 volumio-salon kernel: disable_outputs [drm_kms_helper] from drm_atomic_helper_commit_modeset_disables+0x18/0x3c [drm_kms_helper] Feb 21 10:57:51 volumio-salon kernel: drm_atomic_helper_commit_modeset_disables [drm_kms_helper] from vc4_atomic_commit_tail+0x100/0x998 [vc4] Feb 21 10:57:51 volumio-salon kernel: vc4_atomic_commit_tail [vc4] from commit_tail+0xa4/0x18c [drm_kms_helper] Feb 21 10:57:51 volumio-salon kernel: commit_tail [drm_kms_helper] from drm_atomic_helper_commit+0x140/0x164 [drm_kms_helper] Feb 21 10:57:51 volumio-salon kernel: drm_atomic_helper_commit [drm_kms_helper] from drm_atomic_commit+0xc8/0x100 [drm] Feb 21 10:57:51 volumio-salon kernel: drm_atomic_commit [drm] from drm_atomic_helper_set_config+0x90/0xc8 [drm_kms_helper] Feb 21 10:57:51 volumio-salon kernel: drm_atomic_helper_set_config [drm_kms_helper] from drm_mode_setcrtc+0x200/0x814 [drm] Feb 21 10:57:51 volumio-salon kernel: drm_mode_setcrtc [drm] from drm_ioctl+0x2b4/0x4d0 [drm] Feb 21 10:57:51 volumio-salon kernel: drm_ioctl [drm] from sys_ioctl+0x130/0xbcc Feb 21 10:57:51 volumio-salon kernel: sys_ioctl from ret_fast_syscall+0x0/0x5c Feb 21 10:57:51 volumio-salon kernel: Exception stack(0xf0f45fa8 to 0xf0f45ff0) Feb 21 10:57:51 volumio-salon kernel: 5fa0: 0000000c be9219e0 0000000c c06864a2 be9219e0 be9219c0 Feb 21 10:57:51 volumio-salon kernel: 5fc0: 0000000c be9219e0 c06864a2 00000036 be921a98 01360ea8 00a18e50 00000000 Feb 21 10:57:51 volumio-salon kernel: 5fe0: 00000000 be9219a8 b69c5000 b69481e4 Feb 21 10:57:51 volumio-salon kernel: ---[ end trace 0000000000000000 ]--- Feb 21 10:57:51 volumio-salon volumio-remote-updater[866]: [2026-02-21 10:57:51] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1771667868 101 Feb 21 10:57:51 volumio-salon volumio[1390]: 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: 5 Feb 21 10:57:51 volumio-salon volumio[1390]: info: touch_display: X display number found: 0 Feb 21 10:57:51 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 21 10:57:51 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 21 10:57:51 volumio-salon volumio5-onboarding[1924]: time=2026-02-21T10:57:51.867+01:00 level=INFO msg="enabling local network discovery" Feb 21 10:57:51 volumio-salon systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Feb 21 10:57:51 volumio-salon systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 21 10:57:51 volumio-salon volumio5-onboarding[1924]: time=2026-02-21T10:57:51.934+01:00 level=INFO msg="bootstrapping state" hasInternet=true Feb 21 10:57:52 volumio-salon systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 21 10:57:52 volumio-salon go-librespot[2021]: go-librespot daemon starting... Feb 21 10:57:52 volumio-salon go-librespot[2022]: time="2026-02-21T10:57:52+01:00" level=info msg="running go-librespot 0.4.0" Feb 21 10:57:52 volumio-salon go-librespot[2022]: time="2026-02-21T10:57:52+01:00" level=debug msg="app state loaded" Feb 21 10:57:52 volumio-salon go-librespot[2022]: time="2026-02-21T10:57:52+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 21 10:57:52 volumio-salon volumio[1390]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Feb 21 10:57:52 volumio-salon volumio[1390]: info: go-librespot daemon successfully initialized Feb 21 10:57:52 volumio-salon volumio[1390]: info: Received Get System Info Feb 21 10:57:52 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 10:57:52 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 10:57:52 volumio-salon volumio[1390]: info: Discovery: Getting this device information Feb 21 10:57:52 volumio-salon volumio[1390]: info: CoreCommandRouter::volumioGetState Feb 21 10:57:52 volumio-salon volumio[1390]: info: CorePlayQueue::getTrack 0 Feb 21 10:57:52 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 10:57:52 volumio-salon volumio[1390]: error: updateQueue error: null Feb 21 10:57:52 volumio-salon go-librespot[2022]: time="2026-02-21T10:57:52+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 21 10:57:52 volumio-salon go-librespot[2022]: time="2026-02-21T10:57:52+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 21 10:57:52 volumio-salon go-librespot[2022]: time="2026-02-21T10:57:52+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 21 10:57:52 volumio-salon go-librespot[2022]: time="2026-02-21T10:57:52+01:00" level=info msg="zeroconf server listening on port 37207" Feb 21 10:57:52 volumio-salon volumio[1390]: info: touch_display: X display number found: 0 Feb 21 10:57:52 volumio-salon go-librespot[2022]: time="2026-02-21T10:57:52+01:00" level=debug msg="obtained new client token: AAC4Rp0sxLaTjtDeivHdiwbb96Qk8YSFuWXNHWEzTHRT1f/p4Kh1gLtqEJgHn+r7FZHrM41h4kELmvEx1L3PjVv1u1NuC5PktPO+KStxrnLTfkh4bxYb6mWPgtrKuaXXhIv1bmGTEv00LJmGY7GHn+6uldh/MstVaoTaccYzNCqoxD7pGbkYwXLDZGdNgURR+JYQ3aVFXhNrfec1GdWs7He6oKZAontD/lQu85BtttaeKSB2mpndOg==" Feb 21 10:57:52 volumio-salon go-librespot[2022]: time="2026-02-21T10:57:52+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Feb 21 10:57:52 volumio-salon go-librespot[2022]: time="2026-02-21T10:57:52+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 21 10:57:52 volumio-salon systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 21 10:57:52 volumio-salon systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 21 10:57:52 volumio-salon volumio-remote-updater[866]: Test mode disabled Feb 21 10:57:52 volumio-salon volumio-remote-updater[866]: Alpha mode disabled Feb 21 10:57:52 volumio-salon volumio-remote-updater[866]: Alpha legacy test mode disabled Feb 21 10:57:53 volumio-salon volumio[1390]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 21 10:57:53 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 21 10:57:53 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 21 10:57:53 volumio-salon volumio[1390]: info: CoreCommandRouter::volumioGetState Feb 21 10:57:53 volumio-salon volumio[1390]: info: CorePlayQueue::getTrack 0 Feb 21 10:57:53 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 21 10:57:53 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 21 10:57:53 volumio-salon volumio[1390]: info: CoreCommandRouter::volumioGetState Feb 21 10:57:53 volumio-salon volumio[1390]: info: CorePlayQueue::getTrack 0 Feb 21 10:57:53 volumio-salon volumio[1390]: info: touch_display: Setting screensaver timeout to 120 seconds. Feb 21 10:57:54 volumio-salon volumio[1390]: SPOTIFY: User informations: {"country":"FR","display_name":"thibzom","email":"thib.mura68@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/thibzom"},"followers":{"href":null,"total":13},"href":"https://api.spotify.com/v1/users/thibzom","id":"thibzom","images":[],"product":"premium","type":"user","uri":"spotify:user:thibzom"} Feb 21 10:57:54 volumio-salon volumio[1390]: info: Spotify Successfully logged in Feb 21 10:57:54 volumio-salon volumio[1390]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 21 10:57:54 volumio-salon volumio[1390]: info: [1771667874133] CoreMusicLibrary::Adding element Spotify Feb 21 10:57:54 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 21 10:57:54 volumio-salon volumio[1390]: Cannot find translation for source Jellyfin Feb 21 10:57:54 volumio-salon volumio[1390]: Cannot find translation for source Spotify Feb 21 10:57:55 volumio-salon volumio[1390]: info: Initializing connection to go-librespot Websocket Feb 21 10:57:55 volumio-salon systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Feb 21 10:57:55 volumio-salon systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 21 10:57:55 volumio-salon volumio[1390]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 21 10:57:56 volumio-salon systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 21 10:57:56 volumio-salon go-librespot[2118]: go-librespot daemon starting... Feb 21 10:57:56 volumio-salon go-librespot[2122]: time="2026-02-21T10:57:56+01:00" level=info msg="running go-librespot 0.4.0" Feb 21 10:57:56 volumio-salon go-librespot[2122]: time="2026-02-21T10:57:56+01:00" level=debug msg="app state loaded" Feb 21 10:57:56 volumio-salon go-librespot[2122]: time="2026-02-21T10:57:56+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 21 10:57:56 volumio-salon go-librespot[2122]: time="2026-02-21T10:57:56+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Feb 21 10:57:56 volumio-salon go-librespot[2122]: time="2026-02-21T10:57:56+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 21 10:57:56 volumio-salon go-librespot[2122]: time="2026-02-21T10:57:56+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 21 10:57:56 volumio-salon go-librespot[2122]: time="2026-02-21T10:57:56+01:00" level=info msg="zeroconf server listening on port 32961" Feb 21 10:57:56 volumio-salon volumio[1390]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 21 10:57:56 volumio-salon go-librespot[2122]: time="2026-02-21T10:57:56+01:00" level=debug msg="obtained new client token: AACrS6retWhPjFVBWc63DrX3iDAV58f23yzzrN+fpxY2ftuwQGbRpN9SHYBgKqhxuC/JVJx51S31gqix+e1cpD5j7jwHGsnS87iJSFkuqvXEFut+xSN15OnUQDfHLauVdRN5rzzBTqgRwazALETbNc0gWpfvrf0jsVnA2MepECNj2ZwriPUJJiII6BvyhhF7xE9m4nDXyUY1zODqKoXAIcoQvdcK+QRRD9EMXmHXJonzFS+ATlxFFQ==" Feb 21 10:57:56 volumio-salon go-librespot[2122]: time="2026-02-21T10:57:56+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Feb 21 10:57:56 volumio-salon go-librespot[2122]: time="2026-02-21T10:57:56+01:00" level=debug msg="completed keyexchange" Feb 21 10:57:56 volumio-salon go-librespot[2122]: time="2026-02-21T10:57:56+01:00" level=debug msg="completed challenge" Feb 21 10:57:56 volumio-salon go-librespot[2122]: time="2026-02-21T10:57:56+01:00" level=info msg="authenticated AP" username="th***om" Feb 21 10:57:56 volumio-salon go-librespot[2122]: time="2026-02-21T10:57:56+01:00" level=info msg="authenticated Login5" username="th***om" Feb 21 10:57:56 volumio-salon go-librespot[2122]: time="2026-02-21T10:57:56+01:00" level=debug msg="initializing zeroconf session" username="th***om" Feb 21 10:57:56 volumio-salon go-librespot[2122]: time="2026-02-21T10:57:56+01:00" level=debug msg="dealer connection opened" Feb 21 10:57:56 volumio-salon go-librespot[2122]: time="2026-02-21T10:57:56+01:00" level=trace msg="starting accesspoint recv loop" Feb 21 10:57:56 volumio-salon go-librespot[2122]: time="2026-02-21T10:57:56+01:00" level=trace msg="starting dealer recv loop" Feb 21 10:57:56 volumio-salon go-librespot[2122]: time="2026-02-21T10:57:56+01:00" level=trace msg="received accesspoint ping" Feb 21 10:57:56 volumio-salon go-librespot[2122]: time="2026-02-21T10:57:56+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Feb 21 10:57:56 volumio-salon go-librespot[2122]: time="2026-02-21T10:57:56+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Feb 21 10:57:56 volumio-salon go-librespot[2122]: time="2026-02-21T10:57:56+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Feb 21 10:57:56 volumio-salon go-librespot[2122]: time="2026-02-21T10:57:56+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Feb 21 10:57:56 volumio-salon go-librespot[2122]: time="2026-02-21T10:57:56+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Feb 21 10:57:56 volumio-salon go-librespot[2122]: time="2026-02-21T10:57:56+01:00" level=trace msg="received accesspoint pong ack" Feb 21 10:57:56 volumio-salon go-librespot[2122]: time="2026-02-21T10:57:56+01:00" level=debug msg="received connection id: NmJkYzM2NWItYmY1...QjUwQzg0NkUzMw==" Feb 21 10:57:57 volumio-salon go-librespot[2122]: time="2026-02-21T10:57:57+01:00" level=debug msg="put connect state because NEW_DEVICE" Feb 21 10:57:59 volumio-salon wpa_supplicant[1351]: RRM: Ignoring radio measurement request: Not RRM network Feb 21 10:58:01 volumio-salon volumio[1390]: info: Initializing connection to go-librespot Websocket Feb 21 10:58:03 volumio-salon systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 6. Feb 21 10:58:03 volumio-salon systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 21 10:58:03 volumio-salon systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 21 10:58:03 volumio-salon upmpdcli[2226]: Could not open config: /tmp/upmpdcli.conf Feb 21 10:58:03 volumio-salon systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 21 10:58:03 volumio-salon systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 21 10:58:05 volumio-salon go-librespot[2122]: time="2026-02-21T10:58:05+01:00" level=debug msg="new websocket client" Feb 21 10:58:10 volumio-salon volumio[1390]: info: Connection to go-librespot Websocket established Feb 21 10:58:10 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 21 10:58:10 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 10:58:10 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 21 10:58:10 volumio-salon volumio[1390]: error: MyVolumio Plugin failed to start in a timely fashion Feb 21 10:58:10 volumio-salon volumio[1390]: [Metrics] CommandRouter: 70s 193.47ms Feb 21 10:58:10 volumio-salon volumio[1390]: info: CoreCommandRouter::volumiosetStartupVolume Feb 21 10:58:10 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 10:58:10 volumio-salon volumio[1390]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 10:58:10 volumio-salon volumio[1390]: info: CoreCommandRouter::Close All Modals sent Feb 21 10:58:10 volumio-salon volumio[1390]: info: CoreCommandRouter::Close All Modals sent Feb 21 10:58:10 volumio-salon volumio[1390]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 21 10:58:10 volumio-salon volumio[1390]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Feb 21 10:58:10 volumio-salon volumio[1390]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Feb 21 10:58:10 volumio-salon volumio[1390]: at new NodeError (node:internal/errors:405:5) Feb 21 10:58:10 volumio-salon volumio[1390]: at assert (node:internal/assert:14:11) Feb 21 10:58:10 volumio-salon volumio[1390]: at internalConnectMultiple (node:net:1118:3) Feb 21 10:58:10 volumio-salon volumio[1390]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Feb 21 10:58:10 volumio-salon volumio[1390]: at listOnTimeout (node:internal/timers:575:11) Feb 21 10:58:10 volumio-salon volumio[1390]: at process.processTimers (node:internal/timers:514:7) { Feb 21 10:58:10 volumio-salon volumio[1390]: code: 'ERR_INTERNAL_ASSERTION' Feb 21 10:58:10 volumio-salon volumio[1390]: } Feb 21 10:58:10 volumio-salon volumio[1390]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 21 10:58:11 volumio-salon kernel: Bluetooth: Unexpected continuation frame (len 0) Feb 21 10:58:11 volumio-salon bluetoothd[1004]: Authentication attempt without agent Feb 21 10:58:11 volumio-salon bluetoothd[1004]: profiles/audio/a2dp.c:auth_cb() Access denied: org.bluez.Error.Rejected Feb 21 10:58:11 volumio-salon kernel: hwmon hwmon1: Voltage normalised Feb 21 10:58:13 volumio-salon sudo[2261]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-21 10:57' Feb 21 10:58:13 volumio-salon sudo[2261]: 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="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"