Oct 03 12:28:18 volumio ntpd[1012]: CLOCK: time stepped by 10612555.365147 Oct 03 12:28:18 volumio ntpd[1012]: CLOCK: time changed from 2026-06-02 to 2026-10-03 Oct 03 12:28:18 volumio ntpd[1012]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Oct 03 12:28:18 volumio systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... Oct 03 12:28:18 volumio systemd[1]: Starting e2scrub_all.service - Online ext4 Metadata Check for All Filesystems... Oct 03 12:28:18 volumio systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Oct 03 12:28:18 volumio systemd[1]: e2scrub_all.service: Deactivated successfully. Oct 03 12:28:18 volumio systemd[1]: Finished e2scrub_all.service - Online ext4 Metadata Check for All Filesystems. Oct 03 12:28:18 volumio systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Oct 03 12:28:18 volumio systemd[1]: dpkg-db-backup.service: Deactivated successfully. Oct 03 12:28:18 volumio systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. Oct 03 12:28:19 volumio volumio-remote-updater[741]: [2026-10-03 12:28:19] [info] asio async_connect error: asio.system:111 (Connection refused) Oct 03 12:28:19 volumio volumio-remote-updater[741]: [2026-10-03 12:28:19] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Oct 03 12:28:19 volumio volumio-remote-updater[741]: [2026-10-03 12:28:19] [error] handle_connect error: Connection refused Oct 03 12:28:20 volumio sudo[1222]: pam_unix(sudo:session): session closed for user root Oct 03 12:28:20 volumio wireless.js[742]: WIRELESS.JS - INFO: SETTING APPROPRIATE REG DOMAIN: DE Oct 03 12:28:20 volumio sudo[1244]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set DE Oct 03 12:28:20 volumio sudo[1244]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 12:28:20 volumio sudo[1244]: pam_unix(sudo:session): session closed for user root Oct 03 12:28:20 volumio wireless.js[742]: WIRELESS.JS - INFO: SUCCESSFULLY SET NEW REGDOMAIN: DE Oct 03 12:28:20 volumio wireless.js[742]: WIRELESS.JS - INFO: netconfigured file not found, starting hotspot Oct 03 12:28:20 volumio wireless.js[742]: WIRELESS.JS - INFO: Single Network Mode: Ethernet active, maintaining WiFi scan capability Oct 03 12:28:20 volumio wireless.js[742]: WIRELESS.JS - INFO: SNM: Maintaining wlan0 UP without IP (scan mode) Oct 03 12:28:20 volumio wireless.js[742]: WIRELESS.JS - INFO: SNM: Users can configure WiFi via WebUI while ethernet is active Oct 03 12:28:20 volumio sudo[1253]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 03 12:28:20 volumio sudo[1253]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 03 12:28:20 volumio sudo[1253]: pam_unix(sudo:session): session closed for user root Oct 03 12:28:20 volumio sudo[1256]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Oct 03 12:28:20 volumio sudo[1256]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 03 12:28:20 volumio sudo[1256]: pam_unix(sudo:session): session closed for user root Oct 03 12:28:20 volumio wpa_supplicant[1259]: Successfully initialized wpa_supplicant Oct 03 12:28:20 volumio wpa_supplicant[1259]: nl80211: kernel reports: Registration to specific type not supported Oct 03 12:28:20 volumio wireless.js[742]: WIRELESS.JS - INFO: SNM: Transition to scan mode completed in 10612561523ms Oct 03 12:28:20 volumio wireless.js[742]: WIRELESS.JS - INFO: SNM: wlan0 is UP without IP, scan capable Oct 03 12:28:20 volumio wireless.js[742]: Failed to connect to non-global ctrl_ifname: wlan0 error: No such file or directory Oct 03 12:28:21 volumio wireless.js[742]: WIRELESS.JS - INFO: Notified systemd about wireless ready Oct 03 12:28:21 volumio systemd[1]: Started wireless.service - Wireless Services. Oct 03 12:28:21 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Oct 03 12:28:21 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Oct 03 12:28:21 volumio systemd[1]: Starting plymouth-quit.service - Terminate Plymouth Boot Screen... Oct 03 12:28:21 volumio systemd[1]: Started volumio_cpu_tweak.service - Volumio Cpu Tweaker. Oct 03 12:28:21 volumio systemd[1]: Received SIGRTMIN+21 from PID 300 (plymouthd). Oct 03 12:28:21 volumio systemd[1]: Received SIGRTMIN+21 from PID 300 (plymouthd). Oct 03 12:28:21 volumio systemd[1]: Finished plymouth-quit.service - Terminate Plymouth Boot Screen. Oct 03 12:28:21 volumio systemd[1]: Finished plymouth-quit-wait.service - Hold until boot process finishes up. Oct 03 12:28:21 volumio volumio-cpu-tweak[1283]: Setting RT Priority for mpd Oct 03 12:28:21 volumio volumio-cpu-tweak[1301]: pid 35's current scheduling policy: SCHED_OTHER Oct 03 12:28:21 volumio volumio-cpu-tweak[1301]: pid 35's current scheduling priority: 0 Oct 03 12:28:21 volumio volumio-cpu-tweak[1283]: Setting MPD Affinity Oct 03 12:28:21 volumio volumio-cpu-tweak[1302]: pid 3's current affinity mask: f Oct 03 12:28:21 volumio volumio-cpu-tweak[1283]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Oct 03 12:28:21 volumio volumio-cpu-tweak[1283]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Oct 03 12:28:21 volumio systemd[1]: volumio_cpu_tweak.service: Deactivated successfully. Oct 03 12:28:21 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Oct 03 12:28:21 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Oct 03 12:28:21 volumio systemd[1]: setdatetime-helper.service: Consumed 1.189s CPU time. Oct 03 12:28:21 volumio systemd[1]: Reached target multi-user.target - Multi-User System. Oct 03 12:28:21 volumio systemd[1]: Reached target graphical.target - Graphical Interface. Oct 03 12:28:21 volumio systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Oct 03 12:28:21 volumio systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Oct 03 12:28:21 volumio systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Oct 03 12:28:21 volumio systemd[1]: Startup finished in 13.621s (kernel) + 32.906s (userspace) = 46.528s. Oct 03 12:28:24 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1. Oct 03 12:28:24 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 03 12:28:24 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 03 12:28:24 volumio upmpdcli[1312]: Could not open config: /tmp/upmpdcli.conf Oct 03 12:28:24 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 03 12:28:24 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 03 12:28:25 volumio volumio[1281]: info: ------------------------------------------- Oct 03 12:28:25 volumio volumio[1281]: info: ----- Volumio3 ---- Oct 03 12:28:25 volumio volumio[1281]: info: ------------------------------------------- Oct 03 12:28:25 volumio volumio[1281]: info: ----- System startup ---- Oct 03 12:28:25 volumio volumio[1281]: info: ------------------------------------------- Oct 03 12:28:26 volumio volumio[1281]: info: MYVOLUMIO Environment detected Oct 03 12:28:26 volumio volumio[1281]: info: Plugin folders cleanup Oct 03 12:28:26 volumio volumio[1281]: info: Scanning into folder /volumio/app/plugins/ Oct 03 12:28:26 volumio volumio[1281]: info: Scanning category audio_interface Oct 03 12:28:26 volumio volumio[1281]: info: Scanning category miscellanea Oct 03 12:28:26 volumio volumio[1281]: info: Scanning category music_service Oct 03 12:28:26 volumio volumio[1281]: info: Scanning category plugins.json Oct 03 12:28:26 volumio volumio[1281]: info: Scanning category system_controller Oct 03 12:28:26 volumio volumio[1281]: info: Scanning category user_interface Oct 03 12:28:26 volumio volumio[1281]: info: Scanning into folder /data/plugins/ Oct 03 12:28:26 volumio volumio[1281]: info: Scanning category audio_interface Oct 03 12:28:26 volumio volumio[1281]: info: Scanning category music_service Oct 03 12:28:26 volumio volumio[1281]: info: Scanning category user_interface Oct 03 12:28:26 volumio volumio[1281]: info: Plugin folders cleanup completed Oct 03 12:28:26 volumio volumio[1281]: info: ------------------------------------------- Oct 03 12:28:26 volumio volumio[1281]: info: ----- Core plugins startup ---- Oct 03 12:28:26 volumio volumio[1281]: info: ------------------------------------------- Oct 03 12:28:26 volumio volumio[1281]: info: Loading plugins from folder /volumio/app/plugins/ Oct 03 12:28:26 volumio volumio[1281]: info: Adding plugin upnp to MyMusic Plugins Oct 03 12:28:26 volumio volumio[1281]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 03 12:28:26 volumio volumio[1281]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 03 12:28:26 volumio volumio[1281]: info: Loading plugins from folder /data/plugins/ Oct 03 12:28:26 volumio volumio[1281]: info: Loading plugin "system"... Oct 03 12:28:26 volumio volumio[1281]: info: Loading plugin "appearance"... Oct 03 12:28:27 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully. Oct 03 12:28:28 volumio volumio[1281]: info: Loading plugin "network"... Oct 03 12:28:28 volumio volumio[1281]: info: Refreshing Cached IP Addresses Oct 03 12:28:28 volumio sudo[1327]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 03 12:28:28 volumio sudo[1327]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 12:28:28 volumio sudo[1327]: pam_unix(sudo:session): session closed for user root Oct 03 12:28:28 volumio volumio[1281]: info: Loading plugin "services"... Oct 03 12:28:28 volumio volumio[1281]: info: Loading plugin "volumio5onboarding"... Oct 03 12:28:28 volumio sudo[1329]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 03 12:28:28 volumio volumio[1281]: info: Loading plugin "alsa_controller"... Oct 03 12:28:28 volumio sudo[1329]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 12:28:28 volumio sudo[1329]: pam_unix(sudo:session): session closed for user root Oct 03 12:28:28 volumio sudo[1340]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 03 12:28:28 volumio sudo[1340]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 12:28:28 volumio volumio[1281]: warn: Unable to locate the audio output device HiFiBerry DAC Plus. Please configure a valid output device. Oct 03 12:28:28 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 03 12:28:28 volumio volumio[1281]: info: Loading plugin "wizard"... Oct 03 12:28:29 volumio volumio[1281]: info: Loading plugin "networkfs"... Oct 03 12:28:29 volumio volumio[1281]: info: Starting Udev Watcher for removable devices Oct 03 12:28:29 volumio volumio[1281]: info: Ignoring mount for partition: boot Oct 03 12:28:29 volumio volumio[1281]: info: Ignoring mount for partition: volumio Oct 03 12:28:29 volumio volumio[1281]: info: Ignoring mount for partition: volumio_data Oct 03 12:28:29 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 03 12:28:29 volumio volumio[1281]: info: Loading plugin "volumio_command_line_client"... Oct 03 12:28:29 volumio volumio[1281]: info: Loading plugin "upnp"... Oct 03 12:28:29 volumio volumio[1281]: info: [1791023309214] Starting Upmpd Daemon Oct 03 12:28:29 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 03 12:28:29 volumio volumio[1281]: info: Loading plugin "my_music"... Oct 03 12:28:29 volumio volumio[1281]: info: Loading plugin "mpd"... Oct 03 12:28:29 volumio volumio-remote-updater[741]: [2026-10-03 12:28:29] [connect] Successful connection Oct 03 12:28:29 volumio volumio[1281]: info: Loading plugin "upnp_browser"... Oct 03 12:28:30 volumio volumio[1281]: info: Starting UPNP Browser Oct 03 12:28:30 volumio volumio[1281]: info: Loading plugin "alarm-clock"... Oct 03 12:28:30 volumio volumio[1281]: info: Loading plugin "airplay_emulation"... Oct 03 12:28:30 volumio volumio[1281]: info: Starting Shairport Sync Oct 03 12:28:30 volumio volumio[1281]: info: Loading plugin "last_100"... Oct 03 12:28:30 volumio volumio[1281]: info: Loading plugin "webradio"... Oct 03 12:28:31 volumio volumio[1281]: info: Loading plugin "i2s_dacs"... Oct 03 12:28:31 volumio volumio[1281]: info: Loading plugin "volumiodiscovery"... Oct 03 12:28:31 volumio volumio[1281]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 03 12:28:31 volumio node[1281]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 03 12:28:31 volumio volumio[1281]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 03 12:28:31 volumio volumio[1281]: *** WARNING *** For more information see Oct 03 12:28:31 volumio volumio[1281]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 03 12:28:31 volumio volumio[1281]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 03 12:28:31 volumio volumio[1281]: *** WARNING *** For more information see Oct 03 12:28:31 volumio node[1281]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 03 12:28:31 volumio node[1281]: *** WARNING *** For more information see Oct 03 12:28:31 volumio node[1281]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 03 12:28:31 volumio node[1281]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 03 12:28:31 volumio node[1281]: *** WARNING *** For more information see Oct 03 12:28:31 volumio volumio[1281]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 03 12:28:31 volumio volumio[1281]: info: Discovery: Started advertising with name: Volumio Oct 03 12:28:31 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 03 12:28:31 volumio volumio[1281]: info: Loading plugin "spop"... Oct 03 12:28:31 volumio sudo[1340]: pam_unix(sudo:session): session closed for user root Oct 03 12:28:33 volumio volumio[1281]: info: Loading plugin "now_playing"... Oct 03 12:28:35 volumio volumio[1281]: info: Loading plugin "outputs"... Oct 03 12:28:35 volumio volumio[1281]: info: Loading plugin "albumart"... Oct 03 12:28:35 volumio volumio[1281]: info: Plugin example_plugin is not enabled Oct 03 12:28:35 volumio volumio[1281]: info: Loading plugin "inputs"... Oct 03 12:28:35 volumio volumio[1281]: info: Loading plugin "updater_comm"... Oct 03 12:28:36 volumio volumio[1281]: info: Plugin mpdemulation is not enabled Oct 03 12:28:36 volumio volumio[1281]: info: Loading plugin "rest_api"... Oct 03 12:28:36 volumio volumio[1281]: info: Loading plugin "websocket"... Oct 03 12:28:36 volumio volumio[1281]: info: Starting Socket.io Server version 1.7.4 Oct 03 12:28:36 volumio volumio[1281]: info: Loading plugin "fusiondsp"... Oct 03 12:28:36 volumio volumio[1358]: Forking 3 albumart workers Oct 03 12:28:37 volumio volumio[1281]: info: Applying required configuration parameters for plugin fusiondsp Oct 03 12:28:37 volumio volumio[1281]: info: Loading plugin "touch_display"... Oct 03 12:28:38 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. Oct 03 12:28:38 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Oct 03 12:28:38 volumio volumio[1281]: info: Applying required configuration parameters for plugin touch_display Oct 03 12:28:39 volumio volumio[1281]: info: Loading i18n strings for locale de Oct 03 12:28:39 volumio volumio[1281]: Updating browse sources language Oct 03 12:28:39 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 03 12:28:39 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Oct 03 12:28:39 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 03 12:28:39 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 03 12:28:39 volumio upmpdcli[1426]: Could not open config: /tmp/upmpdcli.conf Oct 03 12:28:39 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 03 12:28:39 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 03 12:28:39 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Oct 03 12:28:39 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Oct 03 12:28:39 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 12:28:39 volumio volumio[1281]: info: CoreCommandRouter::initPlayerControls Oct 03 12:28:39 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 12:28:39 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 12:28:39 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 12:28:39 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 12:28:39 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 12:28:39 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 12:28:39 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 12:28:39 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 12:28:39 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 12:28:39 volumio volumio[1281]: Express server listening on port 3000 Oct 03 12:28:39 volumio volumio[1281]: [Metrics] WebUI: 14s 710.77ms Oct 03 12:28:40 volumio volumio[1281]: info: CoreStateMachine::resetVolumioState Oct 03 12:28:40 volumio volumio[1281]: info: CoreStateMachine::getcurrentVolume Oct 03 12:28:40 volumio volumio[1281]: info: CoreCommandRouter::volumioRetrievevolume Oct 03 12:28:40 volumio sudo[1446]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 03 12:28:40 volumio sudo[1446]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 12:28:40 volumio sudo[1448]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 03 12:28:40 volumio sudo[1448]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 12:28:40 volumio sudo[1446]: pam_unix(sudo:session): session closed for user root Oct 03 12:28:40 volumio sudo[1448]: pam_unix(sudo:session): session closed for user root Oct 03 12:28:40 volumio volumio[1281]: info: Volumio Network Manager: Network status updated: 1 Oct 03 12:28:40 volumio volumio[1281]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '2'. Oct 03 12:28:40 volumio volumio[1281]: info: VolumeController:: Volume=undefined Mute =false Oct 03 12:28:40 volumio volumio[1281]: info: CoreStateMachine::pushState Oct 03 12:28:40 volumio volumio[1281]: info: CorePlayQueue::getTrack 0 Oct 03 12:28:40 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 12:28:40 volumio volumio[1281]: info: CoreCommandRouter::volumioPushState Oct 03 12:28:40 volumio volumio[1281]: info: CoreStateMachine::updateTrackBlock Oct 03 12:28:40 volumio volumio[1281]: info: CorePlayQueue::getTrackBlock Oct 03 12:28:40 volumio volumio[1281]: info: CoreCommandRouter::volumioRetrievevolume Oct 03 12:28:41 volumio volumio[1281]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Oct 03 12:28:41 volumio volumio[1281]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '2'. Oct 03 12:28:41 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 12:28:41 volumio volumio[1281]: info: Reloading queue from file Oct 03 12:28:41 volumio volumio[1281]: info: VolumeController:: Volume=undefined Mute =false Oct 03 12:28:41 volumio volumio[1281]: info: CoreStateMachine::pushState Oct 03 12:28:41 volumio volumio[1281]: info: CorePlayQueue::getTrack 0 Oct 03 12:28:41 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 12:28:41 volumio volumio[1281]: info: CoreCommandRouter::volumioPushState Oct 03 12:28:41 volumio volumio[1281]: info: CoreStateMachine::setRepeat null single undefined Oct 03 12:28:41 volumio volumio[1281]: info: CoreStateMachine::pushState Oct 03 12:28:41 volumio volumio[1281]: info: CorePlayQueue::getTrack 0 Oct 03 12:28:41 volumio volumio[1281]: info: CoreCommandRouter::volumioPushState Oct 03 12:28:41 volumio volumio[1281]: info: CoreStateMachine::setRandom null Oct 03 12:28:41 volumio volumio[1281]: info: CoreStateMachine::pushState Oct 03 12:28:41 volumio volumio[1281]: info: CorePlayQueue::getTrack 0 Oct 03 12:28:41 volumio volumio[1281]: info: CoreCommandRouter::volumioPushState Oct 03 12:28:41 volumio volumio[1281]: info: Setting Device type: Raspberry PI Oct 03 12:28:41 volumio volumio[1281]: info: Completed loading Core Plugins Oct 03 12:28:41 volumio volumio[1281]: info: Preparing to generate the ALSA configuration file Oct 03 12:28:42 volumio volumio[1281]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Oct 03 12:28:42 volumio volumio[1281]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03115 Oct 03 12:28:42 volumio volumio[1281]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Oct 03 12:28:42 volumio volumio[1281]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 03 12:28:42 volumio volumio[1281]: info: Reading ALSA contributions from plugins. Oct 03 12:28:42 volumio sudo[1464]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 03 12:28:42 volumio sudo[1464]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 12:28:42 volumio volumio[1368]: Starting albumart workers Oct 03 12:28:42 volumio volumio[1369]: Starting albumart workers Oct 03 12:28:42 volumio volumio[1370]: Starting albumart workers Oct 03 12:28:42 volumio volumio[1281]: info: Discovery: adding 5d21a15e-6c3c-4da3-8b7b-3fa3711dc17b Oct 03 12:28:42 volumio volumio[1281]: info: Discovery: Found device Volumio Oct 03 12:28:42 volumio volumio[1281]: info: CoreCommandRouter::volumioGetState Oct 03 12:28:42 volumio volumio[1281]: info: CorePlayQueue::getTrack 0 Oct 03 12:28:42 volumio volumio[1281]: info: Discovery: this is already registered, 5d21a15e-6c3c-4da3-8b7b-3fa3711dc17b Oct 03 12:28:42 volumio volumio[1281]: info: Discovery: Found device Volumio Oct 03 12:28:42 volumio volumio[1281]: info: CoreCommandRouter::volumioGetState Oct 03 12:28:42 volumio volumio[1281]: info: CorePlayQueue::getTrack 0 Oct 03 12:28:42 volumio volumio[1281]: info: Asound.conf file unchanged, so no further update is needed Oct 03 12:28:42 volumio volumio[1281]: info: Output device has changed, restarting MPD Oct 03 12:28:43 volumio volumio[1281]: info: Output device has changed, restarting Shairport Sync Oct 03 12:28:43 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 12:28:43 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 12:28:43 volumio sudo[1467]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 03 12:28:43 volumio sudo[1469]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 03 12:28:43 volumio sudo[1469]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 12:28:43 volumio sudo[1467]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 12:28:43 volumio sudo[1467]: pam_unix(sudo:session): session closed for user root Oct 03 12:28:43 volumio volumio[1281]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 03 12:28:43 volumio volumio[1281]: info: ___________ START PLUGINS ___________ Oct 03 12:28:43 volumio volumio[1281]: info: ControllerMpd::onStart: Initializing MPD Oct 03 12:28:43 volumio volumio[1281]: info: Creating MPD Configuration file Oct 03 12:28:43 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 03 12:28:43 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 03 12:28:43 volumio sudo[1477]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Oct 03 12:28:43 volumio sudo[1477]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 12:28:43 volumio sudo[1480]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 03 12:28:43 volumio sudo[1480]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 03 12:28:43 volumio sudo[1483]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 03 12:28:43 volumio sudo[1480]: pam_unix(sudo:session): session closed for user root Oct 03 12:28:43 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 12:28:43 volumio volumio[1281]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 03 12:28:43 volumio volumio[1281]: info: [1791023323581] CoreMusicLibrary::Adding element Medienserver Oct 03 12:28:43 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 03 12:28:43 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 03 12:28:43 volumio volumio[1281]: info: UPNP Browser: Client initialized successfully Oct 03 12:28:43 volumio sudo[1482]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 03 12:28:43 volumio sudo[1482]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 12:28:43 volumio sudo[1482]: pam_unix(sudo:session): session closed for user root Oct 03 12:28:43 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 12:28:43 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 12:28:43 volumio sudo[1477]: pam_unix(sudo:session): session closed for user root Oct 03 12:28:44 volumio sudo[1487]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 03 12:28:44 volumio sudo[1487]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 12:28:44 volumio volumio[1281]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 03 12:28:44 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 12:28:44 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 12:28:44 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 03 12:28:44 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 03 12:28:44 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 03 12:28:44 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 03 12:28:44 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 03 12:28:44 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 03 12:28:44 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 03 12:28:44 volumio volumio-remote-updater[741]: [2026-10-03 12:28:44] [connect] Successful connection Oct 03 12:28:44 volumio sudo[1505]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 03 12:28:44 volumio sudo[1505]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 03 12:28:44 volumio sudo[1506]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 03 12:28:44 volumio sudo[1505]: pam_unix(sudo:session): session closed for user root Oct 03 12:28:44 volumio volumio[1281]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 03 12:28:44 volumio volumio[1281]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 03 12:28:44 volumio volumio[1281]: info: [1791023324704] CoreMusicLibrary::Adding element Last_100 Oct 03 12:28:44 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 03 12:28:44 volumio volumio[1281]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 03 12:28:44 volumio volumio[1281]: info: [1791023324721] CoreMusicLibrary::Adding element Webradio Oct 03 12:28:44 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 03 12:28:44 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 03 12:28:44 volumio volumio5-onboarding[1486]: time=2026-10-03T12:28:44.748+02:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Oct 03 12:28:44 volumio volumio[1281]: info: Initializing BBC Radios Oct 03 12:28:45 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 03 12:28:45 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 12:28:45 volumio volumio[1281]: info: Creating Spotify config file Oct 03 12:28:45 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 12:28:46 volumio volumio[1281]: info: [now-playing] ConfigUpdater: config is up to date. Oct 03 12:28:46 volumio volumio[1281]: info: FusionDsp - CamillaDSP binary found. Oct 03 12:28:46 volumio volumio[1281]: info: Loading i18n strings for locale de Oct 03 12:28:46 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Oct 03 12:28:46 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 12:28:46 volumio volumio[1281]: info: FusionDsp - mixtype--------------------- undefined Oct 03 12:28:46 volumio volumio[1281]: info: Loading i18n strings for locale de Oct 03 12:28:46 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 03 12:28:46 volumio volumio[1281]: info: Volumio Calling Home Oct 03 12:28:46 volumio sudo[1544]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /data/volumiokioskextensions/IframeKeyboardBridge Oct 03 12:28:46 volumio volumio[1281]: info: Preparing to generate the ALSA configuration file Oct 03 12:28:46 volumio sudo[1544]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 12:28:47 volumio sudo[1549]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Oct 03 12:28:47 volumio sudo[1549]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 12:28:47 volumio sudo[1551]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Oct 03 12:28:47 volumio sudo[1551]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 12:28:47 volumio sudo[1554]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Oct 03 12:28:47 volumio sudo[1554]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 12:28:47 volumio sudo[1544]: pam_unix(sudo:session): session closed for user root Oct 03 12:28:47 volumio sudo[1549]: pam_unix(sudo:session): session closed for user root Oct 03 12:28:47 volumio systemd[1]: Reloading. Oct 03 12:28:48 volumio volumio[1281]: info: [now-playing] App is listening on port 4004. Oct 03 12:28:49 volumio volumio[1281]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Oct 03 12:28:49 volumio sudo[1554]: pam_unix(sudo:session): session closed for user root Oct 03 12:28:49 volumio systemd[1]: Reloading. Oct 03 12:28:50 volumio volumio[1281]: info: FusionDsp - If filter freq >samplerate/2 then disable it Oct 03 12:28:50 volumio volumio[1281]: info: FusionDsp - Loudness is ON true Oct 03 12:28:50 volumio volumio[1281]: info: camilladsp service started and running in background, instance 1 Oct 03 12:28:50 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 12:28:50 volumio sudo[1551]: pam_unix(sudo:session): session closed for user root Oct 03 12:28:51 volumio volumio[1281]: ALSA lib confmisc.c:165:(snd_config_get_card) Cannot get card index for sndrpihifiberry Oct 03 12:28:51 volumio volumio[1281]: cannot open device 'volumioHw': No such device Oct 03 12:28:51 volumio volumio[1281]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Oct 03 12:28:51 volumio volumio[1281]: ALSA lib confmisc.c:165:(snd_config_get_card) Cannot get card index for sndrpihifiberry Oct 03 12:28:51 volumio volumio[1281]: cannot open device 'volumioHw': No such device Oct 03 12:28:51 volumio volumio[1281]: info: FusionDsp loaded Oct 03 12:28:51 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 03 12:28:51 volumio volumio[1281]: info: FusionDsp - --[object Object] Oct 03 12:28:51 volumio volumio[1281]: error: FusionDsp - Socket connection not established Oct 03 12:28:51 volumio volumio[1281]: info: FusionDsp - Reporting Fusion DSP Enabled Oct 03 12:28:51 volumio volumio[1281]: info: Adding Signal Path Element [object Object] Oct 03 12:28:51 volumio volumio[1281]: info: Adding fusiondspeq DSP Signal Path Element Oct 03 12:28:51 volumio volumio[1281]: info: FusionDsp - ---- installed callbackRead (debounced 200ms) Oct 03 12:28:51 volumio volumio[1281]: info: FusionDsp - PEQ graph server listening on port 10015 Oct 03 12:28:51 volumio volumio[1281]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 03 12:28:51 volumio volumio[1281]: info: Reading ALSA contributions from plugins. Oct 03 12:28:51 volumio volumio[1281]: info: touch_display: No backlight interface detected. Oct 03 12:28:51 volumio volumio[1281]: info: touch_display: systemctl daemon-reload succeeded. Oct 03 12:28:51 volumio sudo[1622]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Oct 03 12:28:51 volumio sudo[1618]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Oct 03 12:28:51 volumio sudo[1622]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 12:28:51 volumio sudo[1618]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 12:28:51 volumio volumio[1281]: info: touch_display: systemctl disable getty@tty1.service succeeded. Oct 03 12:28:51 volumio volumio[1281]: info: touch_display: systemctl stop getty@tty1.service succeeded. Oct 03 12:28:51 volumio volumio[1281]: info: MPD Permissions set Oct 03 12:28:51 volumio volumio[1281]: info: MPD Permissions set Oct 03 12:28:51 volumio systemd[1]: Started volumio-kiosk.service - Volumio Kiosk. Oct 03 12:28:51 volumio sudo[1622]: pam_unix(sudo:session): session closed for user root Oct 03 12:28:51 volumio volumio[1281]: error: FusionDsp - Reload WebSocket error: [object Object] Oct 03 12:28:51 volumio volumio[1281]: info: Volumio called home Oct 03 12:28:52 volumio volumio[1281]: 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: 2 Oct 03 12:28:52 volumio volumio[1281]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Oct 03 12:28:52 volumio volumio[1281]: info: touch_display: Volumio Kiosk started. Oct 03 12:28:52 volumio volumio[1281]: info: CoreCommandRouter::volumioGetState Oct 03 12:28:52 volumio volumio[1281]: info: CorePlayQueue::getTrack 0 Oct 03 12:28:52 volumio sudo[1629]: 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/ Oct 03 12:28:52 volumio sudo[1629]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 12:28:52 volumio volumio[1281]: info: Spotify config file written Oct 03 12:28:52 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 12:28:52 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 12:28:52 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 12:28:52 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 12:28:52 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 12:28:52 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 12:28:52 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 12:28:52 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 12:28:52 volumio systemd[1]: Starting fusiondsp.service - FusionDsp Daemon... Oct 03 12:28:52 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 12:28:52 volumio sudo[1639]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Oct 03 12:28:52 volumio sudo[1639]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 12:28:52 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 12:28:52 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 12:28:52 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 12:28:52 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 12:28:52 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 12:28:52 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 12:28:52 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 12:28:52 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 12:28:52 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 12:28:52 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 12:28:52 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 12:28:52 volumio 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. Oct 03 12:28:52 volumio 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. Oct 03 12:28:53 volumio volumio[1281]: info: CoreCommandRouter::volumioGetState Oct 03 12:28:53 volumio volumio[1281]: info: CorePlayQueue::getTrack 0 Oct 03 12:28:53 volumio volumio[1281]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Oct 03 12:28:53 volumio volumio[1281]: info: No need to fix Spotify hosts Oct 03 12:28:53 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 03 12:28:53 volumio volumio[1281]: 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 Oct 03 12:28:53 volumio volumio[1281]: info: Received Get System Info Oct 03 12:28:53 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 03 12:28:53 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 03 12:28:53 volumio volumio[1281]: info: Discovery: Getting this device information Oct 03 12:28:53 volumio volumio[1281]: info: CoreCommandRouter::volumioGetState Oct 03 12:28:53 volumio volumio[1281]: info: CorePlayQueue::getTrack 0 Oct 03 12:28:53 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 03 12:28:53 volumio volumio5-onboarding[1486]: time=2026-10-03T12:28:53.153+02:00 level=INFO msg="system info for 6ae869a042fcd477aa68917501f85b20" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.119 Oct 03 12:28:53 volumio volumio[1281]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Oct 03 12:28:53 volumio sudo[1639]: pam_unix(sudo:session): session closed for user root Oct 03 12:28:53 volumio volumio[1281]: info: touch_display: X display number found: Oct 03 12:28:53 volumio go-librespot[1661]: go-librespot daemon starting... Oct 03 12:28:53 volumio volumio5-onboarding[1486]: time=2026-10-03T12:28:53.347+02:00 level=INFO msg="bootstrapping state" hasInternet=true Oct 03 12:28:53 volumio sudo[1629]: pam_unix(sudo:session): session closed for user root Oct 03 12:28:53 volumio volumio[1281]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Oct 03 12:28:53 volumio volumio[1281]: info: New Spotify access tokenBQBfhl7UbW... Oct 03 12:28:53 volumio volumio[1281]: info: Spotify credentials grant success - running version from March 24, 2019 Oct 03 12:28:53 volumio sudo[1671]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Oct 03 12:28:53 volumio volumio[1281]: info: Received Get System Info Oct 03 12:28:53 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 03 12:28:53 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 03 12:28:53 volumio volumio[1281]: info: Discovery: Getting this device information Oct 03 12:28:53 volumio volumio[1281]: info: CoreCommandRouter::volumioGetState Oct 03 12:28:53 volumio volumio[1281]: info: CorePlayQueue::getTrack 0 Oct 03 12:28:53 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 03 12:28:53 volumio sudo[1669]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Oct 03 12:28:53 volumio sudo[1671]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 12:28:53 volumio sudo[1671]: pam_unix(sudo:session): session closed for user root Oct 03 12:28:53 volumio sudo[1669]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 12:28:53 volumio volumio[1281]: info: touch_display: IframeKeyboardBridge extension installed successfully Oct 03 12:28:53 volumio volumio[1281]: info: touch_display: X display number found: Oct 03 12:28:53 volumio volumio[1281]: info: Starting Shairport Sync Oct 03 12:28:53 volumio volumio[1281]: info: Starting Shairport Sync Oct 03 12:28:53 volumio volumio[1281]: info: Starting Shairport Sync Oct 03 12:28:53 volumio sudo[1684]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 03 12:28:53 volumio sudo[1684]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 12:28:53 volumio sudo[1686]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 03 12:28:53 volumio sudo[1686]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 12:28:54 volumio volumio[1281]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Oct 03 12:28:54 volumio sudo[1681]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 03 12:28:54 volumio volumio[1281]: info: Asound.conf file unchanged, so no further update is needed Oct 03 12:28:54 volumio volumio[1281]: info: Output device has changed, restarting MPD Oct 03 12:28:54 volumio sudo[1681]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 12:28:54 volumio sudo[1669]: pam_unix(sudo:session): session closed for user root Oct 03 12:28:54 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 03 12:28:54 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Oct 03 12:28:54 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 03 12:28:54 volumio systemd[1]: shairport-sync.service: Consumed 1.553s CPU time. Oct 03 12:28:54 volumio volumio[1281]: info: Output device has changed, restarting Shairport Sync Oct 03 12:28:54 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 12:28:54 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 12:28:54 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 03 12:28:54 volumio sudo[1684]: pam_unix(sudo:session): session closed for user root Oct 03 12:28:54 volumio sudo[1707]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 03 12:28:54 volumio sudo[1707]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 12:28:54 volumio sudo[1686]: pam_unix(sudo:session): session closed for user root Oct 03 12:28:54 volumio sudo[1690]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 03 12:28:54 volumio sudo[1690]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 12:28:54 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 03 12:28:54 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Oct 03 12:28:54 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 03 12:28:54 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 03 12:28:54 volumio sudo[1690]: pam_unix(sudo:session): session closed for user root Oct 03 12:28:54 volumio sudo[1681]: pam_unix(sudo:session): session closed for user root Oct 03 12:28:54 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 03 12:28:54 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 03 12:28:54 volumio systemd[1]: mpd.service: Consumed 1.985s CPU time. Oct 03 12:28:54 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Oct 03 12:28:54 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 03 12:28:54 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 03 12:28:54 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 03 12:28:54 volumio volumio[1281]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 03 12:28:54 volumio startx[1705]: X.Org X Server 1.21.1.7 Oct 03 12:28:54 volumio startx[1705]: X Protocol Version 11, Revision 0 Oct 03 12:28:54 volumio startx[1705]: Current Operating System: Linux volumio 6.12.74-v7l+ #1948 SMP Mon Mar 2 11:27:49 GMT 2026 armv7l Oct 03 12:28:54 volumio startx[1705]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory numa_policy=interleave nvme.max_host_mem_size_mb=0 snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=800 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 numa=fake=2 system_heap.max_order=0 iommu_dma_numa_policy=interleave smsc95xx.macaddr=98:FE:54:22:B5:A3 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=9caf6474-168f-4972-add4-ace36a467bc2 imgfile=/volumio_current.sqsh bootpart=UUID=807D-2966 datapart=UUID=bea3e05c-62cf-4b22-a58d-66213927f56a uuidconfig=cmdline.txt rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Oct 03 12:28:54 volumio startx[1705]: xorg-server 2:21.1.7-3+rpt3+deb12u11 (https://www.debian.org/support) Oct 03 12:28:54 volumio startx[1705]: Current version of pixman: 0.44.0 Oct 03 12:28:54 volumio startx[1705]: Before reporting problems, check http://wiki.x.org Oct 03 12:28:54 volumio startx[1705]: to make sure that you have the latest version. Oct 03 12:28:54 volumio startx[1705]: Markers: (--) probed, (**) from config file, (==) default setting, Oct 03 12:28:54 volumio startx[1705]: (++) from command line, (!!) notice, (II) informational, Oct 03 12:28:54 volumio startx[1705]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Oct 03 12:28:54 volumio startx[1705]: (==) Log file: "/var/log/Xorg.0.log", Time: Sat Oct 3 12:28:54 2026 Oct 03 12:28:54 volumio startx[1705]: (==) Using config directory: "/etc/X11/xorg.conf.d" Oct 03 12:28:54 volumio startx[1705]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Oct 03 12:28:54 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 03 12:28:54 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 03 12:28:54 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 03 12:28:54 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 03 12:28:54 volumio sudo[1464]: pam_unix(sudo:session): session closed for user root Oct 03 12:28:54 volumio volumio[1281]: info: touch_display: X display number found: Oct 03 12:28:55 volumio sudo[1742]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 03 12:28:55 volumio sudo[1742]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 03 12:28:55 volumio volumio[1281]: info: MPD Permissions set Oct 03 12:28:55 volumio sudo[1746]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 03 12:28:55 volumio sudo[1742]: pam_unix(sudo:session): session closed for user root Oct 03 12:28:55 volumio volumio[1281]: info: Upmpdcli Daemon Started Oct 03 12:28:55 volumio volumio[1281]: info: Shairport-Sync Started Oct 03 12:28:55 volumio volumio[1281]: Error adding Membership: Error: addMembership EINVAL Oct 03 12:28:55 volumio volumio[1281]: info: Shairport-Sync Started Oct 03 12:28:55 volumio volumio[1281]: info: Shairport-Sync Started Oct 03 12:28:55 volumio volumio[1281]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Oct 03 12:28:55 volumio go-librespot[1664]: time="2026-10-03T12:28:55+02:00" level=info msg="running go-librespot 0.7.1" Oct 03 12:28:55 volumio go-librespot[1664]: time="2026-10-03T12:28:55+02:00" level=debug msg="app state loaded" Oct 03 12:28:55 volumio go-librespot[1664]: time="2026-10-03T12:28:55+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 03 12:28:55 volumio volumio[1281]: info: touch_display: X display number found: 0 Oct 03 12:28:55 volumio volumio[1281]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Oct 03 12:28:56 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 12:28:56 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 12:28:56 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 12:28:56 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 12:28:56 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 12:28:56 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 12:28:56 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 12:28:56 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 12:28:56 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 12:28:56 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 12:28:56 volumio volumio[1281]: info: touch_display: X display number found: 0 Oct 03 12:28:56 volumio volumio[1281]: info: go-librespot daemon successfully initialized Oct 03 12:28:56 volumio volumio[1281]: info: CoreCommandRouter::volumioGetState Oct 03 12:28:56 volumio volumio[1281]: info: CorePlayQueue::getTrack 0 Oct 03 12:28:56 volumio volumio[1281]: info: Starting Shairport Sync Oct 03 12:28:56 volumio volumio[1281]: SPOTIFY: User informations: {"account_id":"l5kSbCCeBa","country":"DE","display_name":"Sepp","email":"josef_reger@web.de","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/y6lbdcjtjigbusfebm7xzorya"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/y6lbdcjtjigbusfebm7xzorya","id":"y6lbdcjtjigbusfebm7xzorya","images":[],"product":"premium","type":"user","uri":"spotify:user:y6lbdcjtjigbusfebm7xzorya"} Oct 03 12:28:56 volumio volumio[1281]: info: Spotify Successfully logged in Oct 03 12:28:56 volumio volumio[1281]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 03 12:28:56 volumio volumio[1281]: info: [1791023336958] CoreMusicLibrary::Adding element Spotify Oct 03 12:28:56 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 03 12:28:56 volumio volumio[1281]: Cannot find translation for source Spotify Oct 03 12:28:56 volumio sudo[1773]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 03 12:28:56 volumio sudo[1773]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 12:28:57 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 03 12:28:57 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Oct 03 12:28:57 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 03 12:28:57 volumio systemd[1]: shairport-sync.service: Consumed 1.204s CPU time. Oct 03 12:28:57 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 03 12:28:57 volumio sudo[1773]: pam_unix(sudo:session): session closed for user root Oct 03 12:28:57 volumio volumio[1281]: info: Shairport-Sync Started Oct 03 12:28:57 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Oct 03 12:28:57 volumio go-librespot[1664]: time="2026-10-03T12:28:57+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Oct 03 12:28:57 volumio go-librespot[1664]: time="2026-10-03T12:28:57+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Oct 03 12:28:57 volumio go-librespot[1664]: time="2026-10-03T12:28:57+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Oct 03 12:28:57 volumio go-librespot[1664]: time="2026-10-03T12:28:57+02:00" level=info msg="zeroconf server listening on port 36835" Oct 03 12:28:57 volumio go-librespot[1664]: time="2026-10-03T12:28:57+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 03 12:28:57 volumio sudo[1618]: pam_unix(sudo:session): session closed for user root Oct 03 12:28:57 volumio go-librespot[1664]: time="2026-10-03T12:28:57+02:00" level=debug msg="obtained new client token: AAFFHc7e9DnoKZS9STvZ9j6JLM3Ki4pWr7QGG8CvOzGUSrPJZRLA+vw55uLYoMYGpOjWnvN2lOkE/bLnhmI1nwKj0emjICubUTuWXYoIuM9GDIBKygo947e9qNSZSfDD9sEcVvNAwJKdST+wNe0OzPfQ/7Q1YTalxZd/Qdm7eiBa4/atEc+EzX2XevM3drioaEfZ6T8Br+mVqQwNhqcktURrcDtd6G+aQvO4E9jCPslme7TNqK28lXvdeg==" Oct 03 12:28:57 volumio go-librespot[1664]: time="2026-10-03T12:28:57+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 03 12:28:58 volumio go-librespot[1664]: time="2026-10-03T12:28:58+02:00" level=debug msg="completed keyexchange" Oct 03 12:28:58 volumio go-librespot[1664]: time="2026-10-03T12:28:58+02:00" level=debug msg="completed challenge" Oct 03 12:28:58 volumio go-librespot[1664]: time="2026-10-03T12:28:58+02:00" level=info msg="authenticated AP" username="y6*********************ya" Oct 03 12:28:58 volumio go-librespot[1664]: time="2026-10-03T12:28:58+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 03 12:28:58 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 03 12:28:58 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 03 12:28:59 volumio volumio-remote-updater[741]: [2026-10-03 12:28:59] [connect] Successful connection Oct 03 12:28:59 volumio volumio-remote-updater[741]: [2026-10-03 12:28:59] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1791023339 101 Oct 03 12:28:59 volumio volumio[1281]: 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 Oct 03 12:28:59 volumio volumio[1281]: info: Initializing connection to go-librespot Websocket Oct 03 12:28:59 volumio volumio[1281]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 03 12:29:01 volumio startx[1705]: (EE) Oct 03 12:29:01 volumio startx[1705]: (EE) Backtrace: Oct 03 12:29:01 volumio startx[1705]: (EE) Oct 03 12:29:01 volumio startx[1705]: (EE) Bus error at address 0xb13b00b0 Oct 03 12:29:01 volumio startx[1705]: (EE) Oct 03 12:29:01 volumio startx[1705]: Fatal server error: Oct 03 12:29:01 volumio startx[1705]: (EE) Caught signal 7 (Bus error). Server aborting Oct 03 12:29:01 volumio startx[1705]: (EE) Oct 03 12:29:01 volumio kernel: SQUASHFS error: zlib decompression failed, data probably corrupt Oct 03 12:29:01 volumio kernel: SQUASHFS error: Failed to read block 0x8f89e65: -5 Oct 03 12:29:01 volumio kernel: SQUASHFS error: zlib decompression failed, data probably corrupt Oct 03 12:29:01 volumio kernel: SQUASHFS error: Failed to read block 0x8f89e65: -5 Oct 03 12:29:01 volumio kernel: SQUASHFS error: Unable to read data cache entry [8f89e65] Oct 03 12:29:01 volumio kernel: SQUASHFS error: Unable to read page, block 8f89e65, size c885 Oct 03 12:29:01 volumio kernel: SQUASHFS error: Unable to read data cache entry [8f89e65] Oct 03 12:29:01 volumio kernel: SQUASHFS error: Unable to read page, block 8f89e65, size c885 Oct 03 12:29:01 volumio kernel: SQUASHFS error: Unable to read data cache entry [8f89e65] Oct 03 12:29:01 volumio kernel: SQUASHFS error: Unable to read page, block 8f89e65, size c885 Oct 03 12:29:01 volumio kernel: SQUASHFS error: Unable to read data cache entry [8f89e65] Oct 03 12:29:01 volumio kernel: SQUASHFS error: Unable to read page, block 8f89e65, size c885 Oct 03 12:29:01 volumio kernel: SQUASHFS error: Unable to read data cache entry [8f89e65] Oct 03 12:29:01 volumio kernel: SQUASHFS error: Unable to read page, block 8f89e65, size c885 Oct 03 12:29:01 volumio startx[1705]: (EE) Oct 03 12:29:01 volumio startx[1705]: Please consult the The X.Org Foundation support Oct 03 12:29:01 volumio startx[1705]: at http://wiki.x.org Oct 03 12:29:01 volumio startx[1705]: for help. Oct 03 12:29:01 volumio startx[1705]: (EE) Please also check the log file at "/var/log/Xorg.0.log" for additional information. Oct 03 12:29:01 volumio startx[1705]: (EE) Oct 03 12:29:01 volumio startx[1705]: (EE) Server terminated with error (1). Closing log file. Oct 03 12:29:01 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=7/BUS Oct 03 12:29:01 volumio systemd[1]: mpd.service: Failed with result 'signal'. Oct 03 12:29:01 volumio systemd[1]: Failed to start mpd.service - Music Player Daemon. Oct 03 12:29:01 volumio systemd[1]: mpd.service: Consumed 1.618s CPU time. Oct 03 12:29:01 volumio volumio[1281]: error: touch_display: Error determining the video outputs: Error: Command failed: /usr/bin/xrandr -display :0 Oct 03 12:29:01 volumio volumio[1281]: Can't open display :0 Oct 03 12:29:01 volumio sudo[1487]: pam_unix(sudo:session): session closed for user root Oct 03 12:29:01 volumio sudo[1707]: pam_unix(sudo:session): session closed for user root Oct 03 12:29:01 volumio sudo[1469]: pam_unix(sudo:session): session closed for user root Oct 03 12:29:01 volumio volumio[1281]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Oct 03 12:29:01 volumio volumio[1281]: Job for mpd.service failed because a fatal signal was delivered to the control process. Oct 03 12:29:01 volumio volumio[1281]: See "systemctl status mpd.service" and "journalctl -xeu mpd.service" for details. Oct 03 12:29:01 volumio volumio[1281]: error: Cannot start MPD on system Start: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Oct 03 12:29:01 volumio volumio[1281]: Job for mpd.service failed because a fatal signal was delivered to the control process. Oct 03 12:29:01 volumio volumio[1281]: See "systemctl status mpd.service" and "journalctl -xeu mpd.service" for details. Oct 03 12:29:01 volumio volumio[1281]: info: Completed starting Core Plugins Oct 03 12:29:01 volumio volumio[1281]: info: ------------------------------------------- Oct 03 12:29:01 volumio volumio[1281]: info: ----- MyVolumio plugins startup ---- Oct 03 12:29:01 volumio volumio[1281]: info: ------------------------------------------- Oct 03 12:29:01 volumio volumio[1281]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 03 12:29:01 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 03 12:29:01 volumio sudo[1806]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 03 12:29:01 volumio sudo[1806]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 03 12:29:01 volumio sudo[1807]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 03 12:29:01 volumio sudo[1806]: pam_unix(sudo:session): session closed for user root Oct 03 12:29:01 volumio volumio[1281]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Oct 03 12:29:01 volumio volumio[1281]: Job for mpd.service failed because a fatal signal was delivered to the control process. Oct 03 12:29:01 volumio volumio[1281]: See "systemctl status mpd.service" and "journalctl -xeu mpd.service" for details. Oct 03 12:29:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Oct 03 12:29:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 03 12:29:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 03 12:29:01 volumio go-librespot[1809]: go-librespot daemon starting... Oct 03 12:29:01 volumio go-librespot[1810]: time="2026-10-03T12:29:01+02:00" level=info msg="running go-librespot 0.7.1" Oct 03 12:29:01 volumio go-librespot[1810]: time="2026-10-03T12:29:01+02:00" level=debug msg="app state loaded" Oct 03 12:29:01 volumio go-librespot[1810]: time="2026-10-03T12:29:01+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 03 12:29:01 volumio go-librespot[1810]: time="2026-10-03T12:29:01+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Oct 03 12:29:01 volumio go-librespot[1810]: time="2026-10-03T12:29:01+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Oct 03 12:29:01 volumio go-librespot[1810]: time="2026-10-03T12:29:01+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Oct 03 12:29:01 volumio go-librespot[1810]: time="2026-10-03T12:29:01+02:00" level=info msg="zeroconf server listening on port 37827" Oct 03 12:29:01 volumio go-librespot[1810]: time="2026-10-03T12:29:01+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 03 12:29:01 volumio go-librespot[1810]: time="2026-10-03T12:29:01+02:00" level=debug msg="obtained new client token: AAGmyOTikDE1DQNcMB8TMALfRvXqL/fYyd6b2EhXLtpbRHD49kLVEdgVGM+zcbNi+QxUo53xKBc+qwLX6G0qBVDjuMUsJx8as00vgdcS2l6K65dN40WDKWsZhi2nTlc5Ytih3bbO26zreoGJU6XvqemV2L34ZKU3YPj6pB9gdd5p4/SORzCtwcHCqnLpAJ8x/o0FE75vOX0ge6Nda1pLHuBWbh1598JsxLTqlurQsN9R2QerjKOFsLKGWw==" Oct 03 12:29:01 volumio go-librespot[1810]: time="2026-10-03T12:29:01+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 03 12:29:01 volumio go-librespot[1810]: time="2026-10-03T12:29:01+02:00" level=debug msg="completed keyexchange" Oct 03 12:29:01 volumio go-librespot[1810]: time="2026-10-03T12:29:01+02:00" level=debug msg="completed challenge" Oct 03 12:29:01 volumio go-librespot[1810]: time="2026-10-03T12:29:01+02:00" level=info msg="authenticated AP" username="y6*********************ya" Oct 03 12:29:02 volumio go-librespot[1810]: time="2026-10-03T12:29:02+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 03 12:29:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 03 12:29:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 03 12:29:02 volumio kernel: SQUASHFS error: Unable to read data cache entry [8f89e65] Oct 03 12:29:02 volumio kernel: SQUASHFS error: Unable to read page, block 8f89e65, size c885 Oct 03 12:29:02 volumio kernel: SQUASHFS error: Unable to read data cache entry [8f89e65] Oct 03 12:29:02 volumio kernel: SQUASHFS error: Unable to read page, block 8f89e65, size c885 Oct 03 12:29:02 volumio kernel: SQUASHFS error: Unable to read data cache entry [8f89e65] Oct 03 12:29:02 volumio kernel: SQUASHFS error: Unable to read page, block 8f89e65, size c885 Oct 03 12:29:02 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=7/BUS Oct 03 12:29:02 volumio systemd[1]: mpd.service: Failed with result 'signal'. Oct 03 12:29:02 volumio systemd[1]: Failed to start mpd.service - Music Player Daemon. Oct 03 12:29:02 volumio systemd[1]: mpd.service: Consumed 1.267s CPU time. Oct 03 12:29:02 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 03 12:29:02 volumio volumio[1281]: info: Initializing connection to go-librespot Websocket Oct 03 12:29:02 volumio volumio[1281]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 03 12:29:02 volumio sudo[1819]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 03 12:29:02 volumio sudo[1819]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 03 12:29:02 volumio sudo[1820]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 03 12:29:02 volumio sudo[1819]: pam_unix(sudo:session): session closed for user root Oct 03 12:29:04 volumio kernel: SQUASHFS error: Unable to read data cache entry [8f89e65] Oct 03 12:29:04 volumio kernel: SQUASHFS error: Unable to read page, block 8f89e65, size c885 Oct 03 12:29:04 volumio kernel: SQUASHFS error: Unable to read data cache entry [8f89e65] Oct 03 12:29:04 volumio kernel: SQUASHFS error: Unable to read page, block 8f89e65, size c885 Oct 03 12:29:04 volumio kernel: SQUASHFS error: Unable to read data cache entry [8f89e65] Oct 03 12:29:04 volumio kernel: SQUASHFS error: Unable to read page, block 8f89e65, size c885 Oct 03 12:29:04 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=7/BUS Oct 03 12:29:04 volumio systemd[1]: mpd.service: Failed with result 'signal'. Oct 03 12:29:04 volumio systemd[1]: Failed to start mpd.service - Music Player Daemon. Oct 03 12:29:04 volumio systemd[1]: mpd.service: Consumed 1.357s CPU time. Oct 03 12:29:04 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 03 12:29:04 volumio sudo[1822]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 03 12:29:04 volumio sudo[1822]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 03 12:29:04 volumio sudo[1823]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 03 12:29:04 volumio sudo[1822]: pam_unix(sudo:session): session closed for user root Oct 03 12:29:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Oct 03 12:29:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 03 12:29:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 03 12:29:05 volumio go-librespot[1825]: go-librespot daemon starting... Oct 03 12:29:05 volumio go-librespot[1826]: time="2026-10-03T12:29:05+02:00" level=info msg="running go-librespot 0.7.1" Oct 03 12:29:05 volumio go-librespot[1826]: time="2026-10-03T12:29:05+02:00" level=debug msg="app state loaded" Oct 03 12:29:05 volumio go-librespot[1826]: time="2026-10-03T12:29:05+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 03 12:29:05 volumio go-librespot[1826]: time="2026-10-03T12:29:05+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Oct 03 12:29:05 volumio go-librespot[1826]: time="2026-10-03T12:29:05+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Oct 03 12:29:05 volumio go-librespot[1826]: time="2026-10-03T12:29:05+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Oct 03 12:29:05 volumio go-librespot[1826]: time="2026-10-03T12:29:05+02:00" level=info msg="zeroconf server listening on port 41113" Oct 03 12:29:05 volumio go-librespot[1826]: time="2026-10-03T12:29:05+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 03 12:29:05 volumio go-librespot[1826]: time="2026-10-03T12:29:05+02:00" level=debug msg="obtained new client token: AAGH5E4NsX8zYbDJ+6T2NoyOCRIWQFKHZ3VUrBi+FCUgecRBe+E9xaCO5fGHvL29Q1mhLtIwS+dVV1HUieBAIKZ9AOEW0lLO8v+5cKSycYLB3TIWxCcI7oGQxmL+7HcMKqtfCmLDL56GDlCwbPTwSxkSvPODJQutTSETjtVQmID1+yTU46cbppMCH6eD4Em4kL5ioKdSHcBc8oXZcw7u9819V/Vc9nb1quhU29kgdVYsxxeSjQj8zv6u8A==" Oct 03 12:29:05 volumio go-librespot[1826]: time="2026-10-03T12:29:05+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 03 12:29:05 volumio kernel: SQUASHFS error: Unable to read data cache entry [8f89e65] Oct 03 12:29:05 volumio kernel: SQUASHFS error: Unable to read page, block 8f89e65, size c885 Oct 03 12:29:05 volumio kernel: SQUASHFS error: Unable to read data cache entry [8f89e65] Oct 03 12:29:05 volumio kernel: SQUASHFS error: Unable to read page, block 8f89e65, size c885 Oct 03 12:29:05 volumio kernel: SQUASHFS error: Unable to read data cache entry [8f89e65] Oct 03 12:29:05 volumio kernel: SQUASHFS error: Unable to read page, block 8f89e65, size c885 Oct 03 12:29:05 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=7/BUS Oct 03 12:29:05 volumio systemd[1]: mpd.service: Failed with result 'signal'. Oct 03 12:29:05 volumio systemd[1]: Failed to start mpd.service - Music Player Daemon. Oct 03 12:29:05 volumio systemd[1]: mpd.service: Consumed 1.231s CPU time. Oct 03 12:29:05 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 03 12:29:05 volumio volumio[1281]: info: Initializing connection to go-librespot Websocket Oct 03 12:29:05 volumio go-librespot[1826]: time="2026-10-03T12:29:05+02:00" level=debug msg="new websocket client" Oct 03 12:29:05 volumio volumio[1281]: info: Connection to go-librespot Websocket established Oct 03 12:29:05 volumio sudo[1835]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 03 12:29:05 volumio sudo[1835]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 03 12:29:05 volumio sudo[1836]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 03 12:29:05 volumio sudo[1835]: pam_unix(sudo:session): session closed for user root Oct 03 12:29:06 volumio go-librespot[1826]: time="2026-10-03T12:29:06+02:00" level=debug msg="completed keyexchange" Oct 03 12:29:06 volumio go-librespot[1826]: time="2026-10-03T12:29:06+02:00" level=debug msg="completed challenge" Oct 03 12:29:06 volumio go-librespot[1826]: time="2026-10-03T12:29:06+02:00" level=info msg="authenticated AP" username="y6*********************ya" Oct 03 12:29:06 volumio go-librespot[1826]: time="2026-10-03T12:29:06+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 03 12:29:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 03 12:29:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 03 12:29:06 volumio volumio[1281]: info: Connection to go-librespot Websocket closed Oct 03 12:29:07 volumio kernel: SQUASHFS error: Unable to read data cache entry [8f89e65] Oct 03 12:29:07 volumio kernel: SQUASHFS error: Unable to read page, block 8f89e65, size c885 Oct 03 12:29:07 volumio kernel: SQUASHFS error: Unable to read data cache entry [8f89e65] Oct 03 12:29:07 volumio kernel: SQUASHFS error: Unable to read page, block 8f89e65, size c885 Oct 03 12:29:07 volumio kernel: SQUASHFS error: Unable to read data cache entry [8f89e65] Oct 03 12:29:07 volumio kernel: SQUASHFS error: Unable to read page, block 8f89e65, size c885 Oct 03 12:29:07 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=7/BUS Oct 03 12:29:07 volumio systemd[1]: mpd.service: Failed with result 'signal'. Oct 03 12:29:07 volumio systemd[1]: Failed to start mpd.service - Music Player Daemon. Oct 03 12:29:07 volumio systemd[1]: mpd.service: Consumed 1.235s CPU time. Oct 03 12:29:07 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 03 12:29:07 volumio sudo[1839]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 03 12:29:07 volumio sudo[1839]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 03 12:29:07 volumio sudo[1840]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 03 12:29:07 volumio sudo[1839]: pam_unix(sudo:session): session closed for user root Oct 03 12:29:08 volumio kernel: SQUASHFS error: Unable to read data cache entry [8f89e65] Oct 03 12:29:08 volumio kernel: SQUASHFS error: Unable to read page, block 8f89e65, size c885 Oct 03 12:29:08 volumio kernel: SQUASHFS error: Unable to read data cache entry [8f89e65] Oct 03 12:29:08 volumio kernel: SQUASHFS error: Unable to read page, block 8f89e65, size c885 Oct 03 12:29:08 volumio kernel: SQUASHFS error: Unable to read data cache entry [8f89e65] Oct 03 12:29:08 volumio kernel: SQUASHFS error: Unable to read page, block 8f89e65, size c885 Oct 03 12:29:08 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=7/BUS Oct 03 12:29:08 volumio systemd[1]: mpd.service: Failed with result 'signal'. Oct 03 12:29:08 volumio systemd[1]: Failed to start mpd.service - Music Player Daemon. Oct 03 12:29:08 volumio systemd[1]: mpd.service: Consumed 1.192s CPU time. Oct 03 12:29:08 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 03 12:29:08 volumio sudo[1842]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 03 12:29:08 volumio sudo[1842]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 03 12:29:08 volumio sudo[1843]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 03 12:29:08 volumio sudo[1842]: pam_unix(sudo:session): session closed for user root Oct 03 12:29:08 volumio volumio[1281]: info: Getting Spotify volume Oct 03 12:29:09 volumio volumio[1281]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 03 12:29:09 volumio volumio[1281]: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 03 12:29:09 volumio volumio[1281]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Oct 03 12:29:09 volumio volumio[1281]: errno: -111, Oct 03 12:29:09 volumio volumio[1281]: code: 'ECONNREFUSED', Oct 03 12:29:09 volumio volumio[1281]: syscall: 'connect', Oct 03 12:29:09 volumio volumio[1281]: address: '127.0.0.1', Oct 03 12:29:09 volumio volumio[1281]: port: 9879, Oct 03 12:29:09 volumio volumio[1281]: response: undefined Oct 03 12:29:09 volumio volumio[1281]: } Oct 03 12:29:09 volumio volumio[1281]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 03 12:29:09 volumio startx[1700]: xinit: giving up Oct 03 12:29:09 volumio startx[1700]: xinit: unable to connect to X server: Cannot assign requested address Oct 03 12:29:09 volumio startx[1700]: xinit: server error Oct 03 12:29:09 volumio systemd[1]: volumio-kiosk.service: Main process exited, code=exited, status=1/FAILURE Oct 03 12:29:09 volumio systemd[1]: volumio-kiosk.service: Failed with result 'exit-code'. Oct 03 12:29:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Oct 03 12:29:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 03 12:29:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 03 12:29:09 volumio go-librespot[1857]: go-librespot daemon starting... Oct 03 12:29:09 volumio go-librespot[1858]: time="2026-10-03T12:29:09+02:00" level=info msg="running go-librespot 0.7.1" Oct 03 12:29:09 volumio go-librespot[1858]: time="2026-10-03T12:29:09+02:00" level=debug msg="app state loaded" Oct 03 12:29:09 volumio go-librespot[1858]: time="2026-10-03T12:29:09+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 03 12:29:09 volumio go-librespot[1858]: time="2026-10-03T12:29:09+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 03 12:29:09 volumio go-librespot[1858]: time="2026-10-03T12:29:09+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 03 12:29:09 volumio go-librespot[1858]: time="2026-10-03T12:29:09+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 03 12:29:09 volumio go-librespot[1858]: time="2026-10-03T12:29:09+02:00" level=info msg="zeroconf server listening on port 45415" Oct 03 12:29:09 volumio go-librespot[1858]: time="2026-10-03T12:29:09+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 03 12:29:09 volumio kernel: SQUASHFS error: Unable to read data cache entry [8f89e65] Oct 03 12:29:09 volumio kernel: SQUASHFS error: Unable to read page, block 8f89e65, size c885 Oct 03 12:29:09 volumio kernel: SQUASHFS error: Unable to read data cache entry [8f89e65] Oct 03 12:29:09 volumio kernel: SQUASHFS error: Unable to read page, block 8f89e65, size c885 Oct 03 12:29:09 volumio kernel: SQUASHFS error: Unable to read data cache entry [8f89e65] Oct 03 12:29:09 volumio kernel: SQUASHFS error: Unable to read page, block 8f89e65, size c885 Oct 03 12:29:09 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=7/BUS Oct 03 12:29:09 volumio systemd[1]: mpd.service: Failed with result 'signal'. Oct 03 12:29:09 volumio systemd[1]: Failed to start mpd.service - Music Player Daemon. Oct 03 12:29:09 volumio systemd[1]: mpd.service: Consumed 1.230s CPU time. Oct 03 12:29:10 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 03 12:29:10 volumio go-librespot[1858]: time="2026-10-03T12:29:10+02:00" level=debug msg="obtained new client token: AAGR8nba0x+mcrLfsKMwJ4f32Mf79Vjet5EreDwWiwh6LAbVsDu9ubikdUFfFQywizU53a0701EyRpZnIv4cQt1jyIfJ/XQjI0aSBU4lSME/oVan54o835d9NThXPcO7E6hBqUYKKKHMXlNzadX3Als9am+6twKmi6EAUx6g1nWPs5vznKNC+B2xaHhCMhEMg8HayXbL/rjGuCHAv/C7LL2SaYiITeh6Ahn7IBLPInXNb0zf39bjgDY=" Oct 03 12:29:10 volumio go-librespot[1858]: time="2026-10-03T12:29:10+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 03 12:29:10 volumio go-librespot[1858]: time="2026-10-03T12:29:10+02:00" level=debug msg="completed keyexchange" Oct 03 12:29:10 volumio go-librespot[1858]: time="2026-10-03T12:29:10+02:00" level=debug msg="completed challenge" Oct 03 12:29:10 volumio sudo[1868]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 03 12:29:10 volumio sudo[1868]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 03 12:29:10 volumio sudo[1869]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 03 12:29:10 volumio go-librespot[1858]: time="2026-10-03T12:29:10+02:00" level=info msg="authenticated AP" username="y6*********************ya" Oct 03 12:29:10 volumio sudo[1868]: pam_unix(sudo:session): session closed for user root Oct 03 12:29:10 volumio go-librespot[1858]: time="2026-10-03T12:29:10+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 03 12:29:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 03 12:29:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 03 12:29:11 volumio kernel: SQUASHFS error: Unable to read data cache entry [8f89e65] Oct 03 12:29:11 volumio kernel: SQUASHFS error: Unable to read page, block 8f89e65, size c885 Oct 03 12:29:11 volumio kernel: SQUASHFS error: Unable to read data cache entry [8f89e65] Oct 03 12:29:11 volumio kernel: SQUASHFS error: Unable to read page, block 8f89e65, size c885 Oct 03 12:29:11 volumio kernel: SQUASHFS error: Unable to read data cache entry [8f89e65] Oct 03 12:29:11 volumio kernel: SQUASHFS error: Unable to read page, block 8f89e65, size c885 Oct 03 12:29:11 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=7/BUS Oct 03 12:29:11 volumio systemd[1]: mpd.service: Failed with result 'signal'. Oct 03 12:29:11 volumio systemd[1]: Failed to start mpd.service - Music Player Daemon. Oct 03 12:29:11 volumio systemd[1]: mpd.service: Consumed 1.220s CPU time. Oct 03 12:29:11 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 03 12:29:11 volumio sudo[1885]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 03 12:29:11 volumio sudo[1885]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 03 12:29:11 volumio sudo[1886]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 03 12:29:11 volumio sudo[1885]: pam_unix(sudo:session): session closed for user root Oct 03 12:29:12 volumio kernel: SQUASHFS error: Unable to read data cache entry [8f89e65] Oct 03 12:29:12 volumio kernel: SQUASHFS error: Unable to read page, block 8f89e65, size c885 Oct 03 12:29:12 volumio kernel: SQUASHFS error: Unable to read data cache entry [8f89e65] Oct 03 12:29:12 volumio kernel: SQUASHFS error: Unable to read page, block 8f89e65, size c885 Oct 03 12:29:12 volumio kernel: SQUASHFS error: Unable to read data cache entry [8f89e65] Oct 03 12:29:12 volumio kernel: SQUASHFS error: Unable to read page, block 8f89e65, size c885 Oct 03 12:29:12 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=7/BUS Oct 03 12:29:12 volumio systemd[1]: mpd.service: Failed with result 'signal'. Oct 03 12:29:12 volumio systemd[1]: Failed to start mpd.service - Music Player Daemon. Oct 03 12:29:12 volumio systemd[1]: mpd.service: Consumed 1.226s CPU time. Oct 03 12:29:12 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 03 12:29:12 volumio sudo[1888]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 03 12:29:12 volumio sudo[1888]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 03 12:29:12 volumio sudo[1889]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 03 12:29:12 volumio sudo[1888]: pam_unix(sudo:session): session closed for user root Oct 03 12:29:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Oct 03 12:29:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 03 12:29:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 03 12:29:13 volumio go-librespot[1891]: go-librespot daemon starting... Oct 03 12:29:13 volumio go-librespot[1892]: time="2026-10-03T12:29:13+02:00" level=info msg="running go-librespot 0.7.1" Oct 03 12:29:13 volumio go-librespot[1892]: time="2026-10-03T12:29:13+02:00" level=debug msg="app state loaded" Oct 03 12:29:13 volumio go-librespot[1892]: time="2026-10-03T12:29:13+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 03 12:29:13 volumio go-librespot[1892]: time="2026-10-03T12:29:13+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 03 12:29:13 volumio go-librespot[1892]: time="2026-10-03T12:29:13+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 03 12:29:13 volumio go-librespot[1892]: time="2026-10-03T12:29:13+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 03 12:29:13 volumio go-librespot[1892]: time="2026-10-03T12:29:13+02:00" level=info msg="zeroconf server listening on port 38921" Oct 03 12:29:13 volumio go-librespot[1892]: time="2026-10-03T12:29:13+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 03 12:29:13 volumio go-librespot[1892]: time="2026-10-03T12:29:13+02:00" level=debug msg="obtained new client token: AAGsw68/oa29uKgDUBC747Bl4pon89Q5JGKncU3OrSJts+ET4vDGK5IZ8SaAebYoJ/udcrosaotMVCLPct35l+ilfuOyxgauXAQmBdqJuIyKXFGp608WVmrj98V9Y6vXtzlpogY/WTmeYJA2Sr3Efm9YLpGllEsO2uWAceWy8utVh/5fRkS7QZR2MLNmlJqgBpMN/z10J09WxYWDuOoDkBK1TCnMjIw9u1zSRs81gMzWEao8MWPvum1FsA==" Oct 03 12:29:13 volumio sudo[1903]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-03 12:28' Oct 03 12:29:13 volumio go-librespot[1892]: time="2026-10-03T12:29:13+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 03 12:29:13 volumio sudo[1903]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 12:29:13 volumio go-librespot[1892]: time="2026-10-03T12:29:13+02:00" level=debug msg="completed keyexchange" Oct 03 12:29:13 volumio go-librespot[1892]: time="2026-10-03T12:29:13+02:00" level=debug msg="completed challenge" Oct 03 12:29:13 volumio go-librespot[1892]: time="2026-10-03T12:29:13+02:00" level=info msg="authenticated AP" username="y6*********************ya" Oct 03 12:29:14 volumio go-librespot[1892]: time="2026-10-03T12:29:14+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 03 12:29:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 03 12:29:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 03 12:29:14 volumio kernel: SQUASHFS error: Unable to read data cache entry [8f89e65] Oct 03 12:29:14 volumio kernel: SQUASHFS error: Unable to read page, block 8f89e65, size c885 Oct 03 12:29:14 volumio kernel: SQUASHFS error: Unable to read data cache entry [8f89e65] Oct 03 12:29:14 volumio kernel: SQUASHFS error: Unable to read page, block 8f89e65, size c885 Oct 03 12:29:14 volumio kernel: SQUASHFS error: Unable to read data cache entry [8f89e65] Oct 03 12:29:14 volumio kernel: SQUASHFS error: Unable to read page, block 8f89e65, size c885 Oct 03 12:29:14 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=7/BUS Oct 03 12:29:14 volumio systemd[1]: mpd.service: Failed with result 'signal'. Oct 03 12:29:14 volumio systemd[1]: Failed to start mpd.service - Music Player Daemon. Oct 03 12:29:14 volumio systemd[1]: mpd.service: Consumed 1.259s CPU time. Oct 03 12:29:14 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 03 12:29:14 volumio sudo[1905]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 03 12:29:14 volumio sudo[1905]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 03 12:29:14 volumio sudo[1906]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 03 12:29:14 volumio sudo[1905]: pam_unix(sudo:session): session closed for user root PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"