Jan 13 22:44:00 volumio-audio volumio[5029]: info: Loading plugin "network"... Jan 13 22:44:00 volumio-audio volumio[5029]: info: Refreshing Cached IP Addresses Jan 13 22:44:00 volumio-audio sudo[6780]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 13 22:44:00 volumio-audio sudo[6780]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:00 volumio-audio volumio[5029]: info: Loading plugin "services"... Jan 13 22:44:00 volumio-audio volumio[5029]: info: Loading plugin "alsa_controller"... Jan 13 22:44:00 volumio-audio sudo[6780]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:00 volumio-audio sudo[6784]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 13 22:44:00 volumio-audio sudo[6784]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:00 volumio-audio sudo[6784]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:00 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 22:44:00 volumio-audio volumio[5029]: info: Loading plugin "wizard"... Jan 13 22:44:00 volumio-audio volumio[5029]: info: Loading plugin "networkfs"... Jan 13 22:44:00 volumio-audio volumio[5029]: info: Starting Udev Watcher for removable devices Jan 13 22:44:00 volumio-audio volumio[5029]: info: Ignoring mount for partition: boot Jan 13 22:44:00 volumio-audio volumio[5029]: info: Ignoring mount for partition: volumio Jan 13 22:44:00 volumio-audio volumio[5029]: info: Ignoring mount for partition: volumio_data Jan 13 22:44:00 volumio-audio volumio[5029]: info: Mounting Device Nowy Jan 13 22:44:00 volumio-audio volumio[5029]: /bin/mkdir: cannot create directory ‘/mnt/USB/Nowy’: File exists Jan 13 22:44:00 volumio-audio volumio[5029]: error: Failed to create folder Error: Command failed: /bin/mkdir -m 777 "/mnt/USB/Nowy" Jan 13 22:44:00 volumio-audio volumio[5029]: /bin/mkdir: cannot create directory ‘/mnt/USB/Nowy’: File exists Jan 13 22:44:00 volumio-audio sudo[6904]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/Nowy -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Jan 13 22:44:00 volumio-audio sudo[6904]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:00 volumio-audio sudo[6904]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:00 volumio-audio volumio[5029]: Mount is denied because the NTFS volume is already exclusively opened. Jan 13 22:44:00 volumio-audio volumio[5029]: The volume may be already mounted, or another software may use it which Jan 13 22:44:00 volumio-audio volumio[5029]: could be identified for example by the help of the 'fuser' command. Jan 13 22:44:00 volumio-audio volumio[5029]: error: Failed to mount Nowy: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/Nowy" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Jan 13 22:44:00 volumio-audio volumio[5029]: Mount is denied because the NTFS volume is already exclusively opened. Jan 13 22:44:00 volumio-audio volumio[5029]: The volume may be already mounted, or another software may use it which Jan 13 22:44:00 volumio-audio volumio[5029]: could be identified for example by the help of the 'fuser' command. Jan 13 22:44:00 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 13 22:44:00 volumio-audio volumio[5029]: info: Loading plugin "volumio_command_line_client"... Jan 13 22:44:00 volumio-audio volumio[5029]: info: Loading plugin "upnp"... Jan 13 22:44:00 volumio-audio volumio[5029]: info: [1768340640764] Starting Upmpd Daemon Jan 13 22:44:00 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 13 22:44:00 volumio-audio volumio[5029]: info: Loading plugin "my_music"... Jan 13 22:44:00 volumio-audio volumio[5029]: info: Loading plugin "mpd"... Jan 13 22:44:01 volumio-audio volumio[5029]: info: Loading plugin "upnp_browser"... Jan 13 22:44:01 volumio-audio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Jan 13 22:44:01 volumio-audio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 13 22:44:01 volumio-audio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 13 22:44:01 volumio-audio go-librespot[7455]: go-librespot daemon starting... Jan 13 22:44:01 volumio-audio go-librespot[7479]: time="2026-01-13T22:44:01+01:00" level=info msg="running go-librespot 0.4.0" Jan 13 22:44:01 volumio-audio go-librespot[7479]: time="2026-01-13T22:44:01+01:00" level=debug msg="app state loaded" Jan 13 22:44:01 volumio-audio go-librespot[7479]: time="2026-01-13T22:44:01+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 13 22:44:01 volumio-audio go-librespot[7479]: time="2026-01-13T22:44:01+01: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]" Jan 13 22:44:01 volumio-audio go-librespot[7479]: time="2026-01-13T22:44:01+01: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]" Jan 13 22:44:01 volumio-audio go-librespot[7479]: time="2026-01-13T22:44:01+01: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]" Jan 13 22:44:01 volumio-audio go-librespot[7479]: time="2026-01-13T22:44:01+01:00" level=info msg="zeroconf server listening on port 46543" Jan 13 22:44:01 volumio-audio volumio[5029]: info: Starting UPNP Browser Jan 13 22:44:01 volumio-audio volumio[5029]: info: Loading plugin "alarm-clock"... Jan 13 22:44:02 volumio-audio go-librespot[7479]: time="2026-01-13T22:44:02+01:00" level=debug msg="obtained new client token: AABU+j5yvAiY/8H5ZEI93AL8xkGCJgEg/dUtMZb33VGS2zFwPw9pB4rm6ecXYeLrdyvSTQt+ZevBdMlbYvBvRWJ/4VeC7Y/aW76ct1CGS4duP0EsJ47u1MzZEaXsJASUUtLUFQdkTPGGOG9Ks2qTlUF6K5qxOG7/NCL2iNXJNQIZeqh+fp7EkVEY2O2jfjpP6IydS2KHzf1NdOPwzYsiBd4Xfr2SIQGobdi003h8MUpIwoBXVykzOLTxJA==" Jan 13 22:44:02 volumio-audio go-librespot[7479]: time="2026-01-13T22:44:02+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jan 13 22:44:02 volumio-audio volumio[5029]: info: Loading plugin "airplay_emulation"... Jan 13 22:44:02 volumio-audio volumio[5029]: info: Starting Shairport Sync Jan 13 22:44:02 volumio-audio volumio[5029]: info: Loading plugin "last_100"... Jan 13 22:44:02 volumio-audio volumio[5029]: info: Loading plugin "webradio"... Jan 13 22:44:02 volumio-audio go-librespot[7479]: time="2026-01-13T22:44:02+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: read tcp 192.168.8.126:58222->34.158.1.133:4070: read: connection reset by peer" Jan 13 22:44:02 volumio-audio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 22:44:02 volumio-audio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 22:44:02 volumio-audio volumio[5029]: info: Loading plugin "i2s_dacs"... Jan 13 22:44:02 volumio-audio volumio[5029]: info: I2S DAC not set, start Auto-detection Jan 13 22:44:02 volumio-audio volumio[5029]: info: Loading plugin "volumiodiscovery"... Jan 13 22:44:02 volumio-audio volumio[5029]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 13 22:44:02 volumio-audio volumio[5029]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 22:44:02 volumio-audio volumio[5029]: *** WARNING *** For more information see Jan 13 22:44:02 volumio-audio volumio[5029]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 13 22:44:02 volumio-audio volumio[5029]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 22:44:02 volumio-audio volumio[5029]: *** WARNING *** For more information see Jan 13 22:44:02 volumio-audio node[5029]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 13 22:44:02 volumio-audio node[5029]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 22:44:02 volumio-audio node[5029]: *** WARNING *** For more information see Jan 13 22:44:02 volumio-audio node[5029]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 13 22:44:02 volumio-audio node[5029]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 22:44:02 volumio-audio node[5029]: *** WARNING *** For more information see Jan 13 22:44:02 volumio-audio volumio[5029]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 13 22:44:02 volumio-audio volumio[5029]: info: Discovery: Started advertising with name: Volumio Audio Jan 13 22:44:02 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 13 22:44:02 volumio-audio volumio[5029]: info: Loading plugin "spop"... Jan 13 22:44:02 volumio-audio volumio-remote-updater[687]: [2026-01-13 22:44:02] [connect] Successful connection Jan 13 22:44:02 volumio-audio volumio[5029]: info: Loading plugin "outputs"... Jan 13 22:44:02 volumio-audio volumio[5029]: info: Loading plugin "albumart"... Jan 13 22:44:02 volumio-audio volumio[5029]: info: Plugin example_plugin is not enabled Jan 13 22:44:02 volumio-audio volumio[5029]: info: Loading plugin "inputs"... Jan 13 22:44:02 volumio-audio volumio[5029]: info: Loading plugin "updater_comm"... Jan 13 22:44:03 volumio-audio volumio[5029]: info: Plugin mpdemulation is not enabled Jan 13 22:44:03 volumio-audio volumio[5029]: info: Loading plugin "rest_api"... Jan 13 22:44:03 volumio-audio volumio[5029]: info: Loading plugin "websocket"... Jan 13 22:44:03 volumio-audio volumio[5029]: info: Starting Socket.io Server version 1.7.4 Jan 13 22:44:03 volumio-audio volumio[5029]: info: Loading plugin "minidlna"... Jan 13 22:44:03 volumio-audio volumio[5029]: info: Applying required configuration parameters for plugin minidlna Jan 13 22:44:03 volumio-audio volumio[5029]: info: Loading plugin "touch_display"... Jan 13 22:44:03 volumio-audio volumio[8248]: Forking 3 albumart workers Jan 13 22:44:03 volumio-audio volumio[5029]: info: Applying required configuration parameters for plugin touch_display Jan 13 22:44:03 volumio-audio volumio[5029]: info: Loading i18n strings for locale pl Jan 13 22:44:03 volumio-audio volumio[5029]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Jan 13 22:44:03 volumio-audio volumio[5029]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Jan 13 22:44:03 volumio-audio volumio[5029]: Updating browse sources language Jan 13 22:44:03 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 22:44:03 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 22:44:03 volumio-audio volumio[5029]: info: CoreCommandRouter::initPlayerControls Jan 13 22:44:03 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:03 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:03 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:03 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:03 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:03 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:03 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:03 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:03 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 22:44:03 volumio-audio volumio[5029]: Express server listening on port 3000 Jan 13 22:44:03 volumio-audio volumio[5029]: [Metrics] WebUI: 5s 59.69ms Jan 13 22:44:03 volumio-audio volumio[5029]: info: CoreStateMachine::resetVolumioState Jan 13 22:44:03 volumio-audio volumio[5029]: info: CoreStateMachine::getcurrentVolume Jan 13 22:44:03 volumio-audio volumio[5029]: info: CoreCommandRouter::volumioRetrievevolume Jan 13 22:44:03 volumio-audio volumio[5029]: info: CoreStateMachine::pushState Jan 13 22:44:03 volumio-audio volumio[5029]: info: CorePlayQueue::getTrack 0 Jan 13 22:44:03 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 22:44:03 volumio-audio volumio[5029]: info: CoreCommandRouter::volumioPushState Jan 13 22:44:03 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:44:03 volumio-audio volumio[5029]: info: Volumio Network Manager: Network status updated: 1 Jan 13 22:44:04 volumio-audio volumio[5029]: verbose: New Socket.io Connection to 192.168.8.126 from 192.168.8.124 UA: Mozilla/5.0 (Linux; Android 14; SM-M135F Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.192 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jan 13 22:44:04 volumio-audio volumio[5029]: info: Reloading queue from file Jan 13 22:44:04 volumio-audio volumio[5029]: verbose: New Socket.io Connection to 192.168.8.126:3000 from 192.168.8.124 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 2 Jan 13 22:44:04 volumio-audio volumio-remote-updater[687]: [2026-01-13 22:44:04] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1768340642 101 Jan 13 22:44:04 volumio-audio volumio[5029]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 3 Jan 13 22:44:04 volumio-audio volumio[5029]: info: Setting Device type: Raspberry PI Jan 13 22:44:04 volumio-audio volumio[5029]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jan 13 22:44:04 volumio-audio volumio[5029]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03111 Jan 13 22:44:04 volumio-audio volumio[5029]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jan 13 22:44:04 volumio-audio volumio[5029]: info: CoreStateMachine::setRepeat null single undefined Jan 13 22:44:04 volumio-audio volumio[5029]: info: CoreStateMachine::pushState Jan 13 22:44:04 volumio-audio volumio[5029]: info: CorePlayQueue::getTrack 0 Jan 13 22:44:04 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 22:44:04 volumio-audio volumio[5029]: info: CoreCommandRouter::volumioPushState Jan 13 22:44:04 volumio-audio volumio[5029]: info: CoreStateMachine::setRandom null Jan 13 22:44:04 volumio-audio volumio[5029]: info: CoreStateMachine::pushState Jan 13 22:44:04 volumio-audio volumio[5029]: info: CorePlayQueue::getTrack 0 Jan 13 22:44:04 volumio-audio volumio[5029]: info: CoreCommandRouter::volumioPushState Jan 13 22:44:04 volumio-audio volumio[5029]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jan 13 22:44:04 volumio-audio volumio[5029]: info: Completed loading Core Plugins Jan 13 22:44:04 volumio-audio volumio[5029]: info: Preparing to generate the ALSA configuration file Jan 13 22:44:04 volumio-audio volumio[5029]: info: Discovery: adding 901c8b4a-224a-4026-9901-c39bd9ea1d93 Jan 13 22:44:04 volumio-audio volumio[5029]: info: Discovery: Found device Volumio Audio Jan 13 22:44:04 volumio-audio volumio[5029]: info: CoreCommandRouter::volumioGetState Jan 13 22:44:04 volumio-audio volumio[5029]: info: CorePlayQueue::getTrack 0 Jan 13 22:44:04 volumio-audio volumio[5029]: info: Discovery: this is already registered, 901c8b4a-224a-4026-9901-c39bd9ea1d93 Jan 13 22:44:04 volumio-audio volumio[5029]: info: Discovery: Found device Volumio Audio Jan 13 22:44:04 volumio-audio volumio[5029]: info: CoreCommandRouter::volumioGetState Jan 13 22:44:04 volumio-audio volumio[5029]: info: CorePlayQueue::getTrack 0 Jan 13 22:44:04 volumio-audio volumio[5029]: verbose: New Socket.io Connection to 192.168.8.126 from 192.168.8.124 UA: Mozilla/5.0 (Linux; Android 14; SM-M135F Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.192 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Jan 13 22:44:04 volumio-audio volumio[8486]: Starting albumart workers Jan 13 22:44:04 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 13 22:44:04 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 13 22:44:04 volumio-audio volumio[5029]: info: Discovery: Getting this device information Jan 13 22:44:04 volumio-audio volumio[5029]: info: CoreCommandRouter::volumioGetState Jan 13 22:44:04 volumio-audio volumio[5029]: info: CorePlayQueue::getTrack 0 Jan 13 22:44:04 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 13 22:44:04 volumio-audio volumio[5029]: info: Asound.conf file unchanged, so no further update is needed Jan 13 22:44:04 volumio-audio volumio[5029]: info: Output device has changed, restarting MPD Jan 13 22:44:04 volumio-audio volumio[5029]: info: Output device has changed, restarting Shairport Sync Jan 13 22:44:04 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:04 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:44:04 volumio-audio volumio[5029]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 22:44:04 volumio-audio volumio[5029]: info: ___________ START PLUGINS ___________ Jan 13 22:44:04 volumio-audio sudo[9032]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 13 22:44:04 volumio-audio sudo[9032]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:04 volumio-audio sudo[9043]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 13 22:44:04 volumio-audio volumio[5029]: info: ControllerMpd::onStart: Initializing MPD Jan 13 22:44:04 volumio-audio volumio[5029]: info: Creating MPD Configuration file Jan 13 22:44:04 volumio-audio sudo[9032]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:04 volumio-audio sudo[9043]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:04 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 22:44:04 volumio-audio volumio[5029]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 22:44:04 volumio-audio volumio[5029]: info: [1768340644743] CoreMusicLibrary::Adding element Serwery Mediów Jan 13 22:44:04 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 22:44:04 volumio-audio volumio[5029]: info: UPNP Browser: Client initialized successfully Jan 13 22:44:04 volumio-audio sudo[9083]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 13 22:44:04 volumio-audio systemd[1]: Stopping mpd.service - Music Player Daemon... Jan 13 22:44:04 volumio-audio sudo[9083]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:04 volumio-audio upmpdcli[1585]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 13 22:44:04 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:04 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:44:04 volumio-audio sudo[9083]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:04 volumio-audio sudo[9120]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 13 22:44:04 volumio-audio sudo[9120]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:04 volumio-audio volumio[5029]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 22:44:04 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:04 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:44:04 volumio-audio systemd[1]: mpd.service: Deactivated successfully. Jan 13 22:44:04 volumio-audio systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 13 22:44:04 volumio-audio systemd[1]: mpd.service: Consumed 2.471s CPU time. Jan 13 22:44:04 volumio-audio systemd[1]: mpd.socket: Deactivated successfully. Jan 13 22:44:04 volumio-audio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 13 22:44:04 volumio-audio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 13 22:44:04 volumio-audio volumio[8479]: Starting albumart workers Jan 13 22:44:04 volumio-audio volumio[5029]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 22:44:04 volumio-audio volumio[5029]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 22:44:04 volumio-audio volumio[5029]: info: [1768340644889] CoreMusicLibrary::Adding element Last_100 Jan 13 22:44:04 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 22:44:04 volumio-audio volumio[5029]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 22:44:04 volumio-audio volumio[5029]: info: [1768340644892] CoreMusicLibrary::Adding element Webradio Jan 13 22:44:04 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 22:44:04 volumio-audio volumio[8475]: Starting albumart workers Jan 13 22:44:04 volumio-audio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 13 22:44:04 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 22:44:04 volumio-audio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 13 22:44:04 volumio-audio volumio[5029]: info: Initializing BBC Radios Jan 13 22:44:04 volumio-audio systemd[1]: mpd.service: Deactivated successfully. Jan 13 22:44:04 volumio-audio systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 13 22:44:04 volumio-audio systemd[1]: mpd.socket: Deactivated successfully. Jan 13 22:44:04 volumio-audio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 13 22:44:04 volumio-audio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 13 22:44:04 volumio-audio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 13 22:44:04 volumio-audio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 13 22:44:05 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 22:44:05 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:44:05 volumio-audio volumio[5029]: info: Creating Spotify config file Jan 13 22:44:05 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:05 volumio-audio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Jan 13 22:44:05 volumio-audio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 13 22:44:05 volumio-audio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 13 22:44:05 volumio-audio go-librespot[9264]: go-librespot daemon starting... Jan 13 22:44:05 volumio-audio sudo[9218]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 13 22:44:05 volumio-audio sudo[9218]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 13 22:44:05 volumio-audio go-librespot[9282]: time="2026-01-13T22:44:05+01:00" level=info msg="running go-librespot 0.4.0" Jan 13 22:44:05 volumio-audio sudo[9218]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:05 volumio-audio go-librespot[9282]: time="2026-01-13T22:44:05+01:00" level=info msg="zeroconf server listening on port 45201" Jan 13 22:44:05 volumio-audio volumio[5029]: info: Loading i18n strings for locale pl Jan 13 22:44:05 volumio-audio volumio[5029]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Jan 13 22:44:05 volumio-audio volumio[5029]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Jan 13 22:44:05 volumio-audio volumio[5029]: info: Loading i18n strings for locale pl Jan 13 22:44:05 volumio-audio volumio[5029]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Jan 13 22:44:05 volumio-audio volumio[5029]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Jan 13 22:44:05 volumio-audio sudo[9472]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /data/volumiokioskextensions/IframeKeyboardBridge Jan 13 22:44:05 volumio-audio sudo[9472]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:05 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 22:44:05 volumio-audio sudo[9472]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:05 volumio-audio volumio[5029]: info: Volumio Calling Home Jan 13 22:44:05 volumio-audio sudo[9540]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Jan 13 22:44:05 volumio-audio sudo[9540]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:05 volumio-audio sudo[9547]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Jan 13 22:44:05 volumio-audio sudo[9547]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:05 volumio-audio sudo[9558]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jan 13 22:44:05 volumio-audio sudo[9558]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:06 volumio-audio systemd[1]: Reloading. Jan 13 22:44:06 volumio-audio volumio[5029]: info: touch_display: No backlight interface detected. Jan 13 22:44:06 volumio-audio volumio[5029]: info: minidlna: Found miniDLNA version 1.3.0 Jan 13 22:44:06 volumio-audio volumio[5029]: info: minidlna: Starting minidlna.service Jan 13 22:44:06 volumio-audio volumio[5029]: info: MPD Permissions set Jan 13 22:44:06 volumio-audio volumio[5029]: info: MPD Permissions set Jan 13 22:44:06 volumio-audio sudo[9682]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Jan 13 22:44:06 volumio-audio sudo[9682]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:06 volumio-audio sudo[9673]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/cp -r /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/content.js /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/manifest.json /data/volumiokioskextensions/IframeKeyboardBridge/ Jan 13 22:44:06 volumio-audio sudo[9673]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:06 volumio-audio sudo[9673]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:06 volumio-audio volumio[5029]: info: Volumio called home Jan 13 22:44:06 volumio-audio volumio[5029]: info: Spotify config file written Jan 13 22:44:06 volumio-audio sudo[9742]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jan 13 22:44:06 volumio-audio sudo[9742]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:06 volumio-audio volumio[5029]: info: touch_display: IframeKeyboardBridge extension installed successfully Jan 13 22:44:06 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:06 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:06 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:06 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:06 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:06 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:06 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:06 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:06 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:44:06 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:06 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:06 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:06 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:06 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:06 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:06 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:06 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:06 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:06 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:44:06 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:06 volumio-audio volumio[5029]: info: No need to fix Spotify hosts Jan 13 22:44:06 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 13 22:44:06 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 13 22:44:06 volumio-audio volumio[5029]: info: Discovery: Getting this device information Jan 13 22:44:06 volumio-audio volumio[5029]: info: CoreCommandRouter::volumioGetState Jan 13 22:44:06 volumio-audio volumio[5029]: info: CorePlayQueue::getTrack 0 Jan 13 22:44:06 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 13 22:44:06 volumio-audio volumio[5029]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jan 13 22:44:06 volumio-audio volumio[5029]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Jan 13 22:44:06 volumio-audio volumio[5029]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jan 13 22:44:06 volumio-audio sudo[9904]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Jan 13 22:44:06 volumio-audio volumio[5029]: SPOTIFY: BQAxDRpy_JXkq-IyqJpqNooRRE1o8pgA3mOSv88hgYxRwAIH-hC7VGfhF9jz0ZC08ah0_wtr3AGRLnlBOfNO9CCZXJtiICg0QtYC4xFTC0WVYcyPmde4RzlZu4_t8iImIyfg2EosaDSf62eHzLkMR2l5ILBCghIQTHN42MtmGczT8xk4C7skmONLQqVqS5FWqnhJNFNDVEo34rcv72Jtv3Gfci6O6Bu_amr8ZXHSSdA7dPU80JZokc-32IWD7PKOuh64Y8iw2ERGrNdxfSr-4aWhMcfAt2IPAIzpONS9-wSLablRtZIPUWd1vV3W Jan 13 22:44:06 volumio-audio volumio[5029]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jan 13 22:44:06 volumio-audio volumio[5029]: info: New Spotify access token = BQAxDRpy_JXkq-IyqJpqNooRRE1o8pgA3mOSv88hgYxRwAIH-hC7VGfhF9jz0ZC08ah0_wtr3AGRLnlBOfNO9CCZXJtiICg0QtYC4xFTC0WVYcyPmde4RzlZu4_t8iImIyfg2EosaDSf62eHzLkMR2l5ILBCghIQTHN42MtmGczT8xk4C7skmONLQqVqS5FWqnhJNFNDVEo34rcv72Jtv3Gfci6O6Bu_amr8ZXHSSdA7dPU80JZokc-32IWD7PKOuh64Y8iw2ERGrNdxfSr-4aWhMcfAt2IPAIzpONS9-wSLablRtZIPUWd1vV3W Jan 13 22:44:06 volumio-audio volumio[5029]: info: Spotify credentials grant success - running version from March 24, 2019 Jan 13 22:44:06 volumio-audio sudo[9904]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:06 volumio-audio sudo[9904]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:06 volumio-audio sudo[9914]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Jan 13 22:44:06 volumio-audio sudo[9914]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:06 volumio-audio volumio[5029]: info: Starting Shairport Sync Jan 13 22:44:06 volumio-audio sudo[9914]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:06 volumio-audio volumio[5029]: info: Starting Shairport Sync Jan 13 22:44:06 volumio-audio volumio[5029]: info: Starting Shairport Sync Jan 13 22:44:06 volumio-audio sudo[9933]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 13 22:44:06 volumio-audio sudo[9933]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:06 volumio-audio volumio[5029]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Jan 13 22:44:06 volumio-audio sudo[9946]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 13 22:44:06 volumio-audio sudo[9946]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:06 volumio-audio sudo[9942]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 13 22:44:06 volumio-audio sudo[9942]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:06 volumio-audio volumio[5029]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Jan 13 22:44:07 volumio-audio volumio[5029]: info: CoreCommandRouter::volumioGetState Jan 13 22:44:07 volumio-audio volumio[5029]: info: CorePlayQueue::getTrack 0 Jan 13 22:44:07 volumio-audio volumio[5029]: SPOTIFY: User informations: {"country":"PL","display_name":"Rafał Bednarski","email":"rbednarski08@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31qkindigqrwplw655lp5r7ef2la"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/31qkindigqrwplw655lp5r7ef2la","id":"31qkindigqrwplw655lp5r7ef2la","images":[{"height":300,"url":"https://platform-lookaside.fbsbx.com/platform/profilepic/?asid=4995835327155858&height=300&width=300&ext=1770921956&hash=AT8B2TEWdfhRCm7Z2YwuvI4W","width":300},{"height":64,"url":"https://platform-lookaside.fbsbx.com/platform/profilepic/?asid=4995835327155858&height=50&width=50&ext=1770921956&hash=AT9iycZnhgRSfOcVXIdqmnMY","width":64}],"product":"premium","type":"user","uri":"spotify:user:31qkindigqrwplw655lp5r7ef2la"} Jan 13 22:44:07 volumio-audio volumio[5029]: info: Spotify Successfully logged in Jan 13 22:44:07 volumio-audio volumio[5029]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 22:44:07 volumio-audio volumio[5029]: info: [1768340647180] CoreMusicLibrary::Adding element Spotify Jan 13 22:44:07 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 22:44:07 volumio-audio volumio[5029]: Cannot find translation for source Spotify Jan 13 22:44:07 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 13 22:44:07 volumio-audio volumio[5029]: info: CoreCommandRouter::volumioGetVisibleSources Jan 13 22:44:07 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 22:44:07 volumio-audio volumio[5029]: info: CoreCommandRouter::volumioGetState Jan 13 22:44:07 volumio-audio volumio[5029]: info: CorePlayQueue::getTrack 0 Jan 13 22:44:07 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 13 22:44:07 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 13 22:44:07 volumio-audio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 13 22:44:07 volumio-audio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 13 22:44:07 volumio-audio mpd[9309]: 2026-01-13T22:44:07 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 13 22:44:07 volumio-audio upmpdcli[1585]: :3:../src/mpdcli.cxx:148::MPDCli::startEventLoop: already started Jan 13 22:44:07 volumio-audio systemd[1]: Started mpd.service - Music Player Daemon. Jan 13 22:44:07 volumio-audio sudo[9120]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:07 volumio-audio sudo[9043]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:07 volumio-audio sudo[9558]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:07 volumio-audio volumio[5029]: info: touch_display: systemctl daemon-reload succeeded. Jan 13 22:44:07 volumio-audio sudo[10314]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Jan 13 22:44:07 volumio-audio sudo[10314]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:07 volumio-audio systemd[1]: Reloading. Jan 13 22:44:07 volumio-audio volumio[5029]: error: MPD error: The expression evaluated to a falsy value: Jan 13 22:44:07 volumio-audio volumio[5029]: assert.ok(self.idling) Jan 13 22:44:07 volumio-audio volumio[5029]: error: The expression evaluated to a falsy value: Jan 13 22:44:07 volumio-audio volumio[5029]: assert.ok(self.idling) Jan 13 22:44:07 volumio-audio volumio[5029]: error: updateQueue error: null Jan 13 22:44:07 volumio-audio volumio[5029]: info: MPD running with PID9309 Jan 13 22:44:07 volumio-audio volumio[5029]: ,establishing connection Jan 13 22:44:07 volumio-audio volumio[5029]: error: updateQueue error: null Jan 13 22:44:07 volumio-audio volumio[5029]: info: Received Get System Info Jan 13 22:44:07 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 13 22:44:07 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 13 22:44:07 volumio-audio volumio[5029]: info: Discovery: Getting this device information Jan 13 22:44:07 volumio-audio volumio[5029]: info: CoreCommandRouter::volumioGetState Jan 13 22:44:07 volumio-audio volumio[5029]: info: CorePlayQueue::getTrack 0 Jan 13 22:44:07 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 13 22:44:07 volumio-audio volumio[5029]: info: CoreCommandRouter::volumioGetState Jan 13 22:44:07 volumio-audio volumio[5029]: info: CorePlayQueue::getTrack 0 Jan 13 22:44:07 volumio-audio volumio[5029]: info: Listing playlists Jan 13 22:44:07 volumio-audio volumio[5029]: verbose: New Socket.io Connection to 192.168.8.126:3000 from 192.168.8.124 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Jan 13 22:44:08 volumio-audio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 13 22:44:08 volumio-audio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 13 22:44:08 volumio-audio sudo[9547]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:08 volumio-audio volumio[5029]: info: touch_display: systemctl disable getty@tty1.service succeeded. Jan 13 22:44:08 volumio-audio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Jan 13 22:44:08 volumio-audio sudo[9682]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:08 volumio-audio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 13 22:44:08 volumio-audio sudo[10314]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:08 volumio-audio volumio[5029]: info: minidlna: systemctl start minidlna.service succeeded. Jan 13 22:44:08 volumio-audio volumio[5029]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Jan 13 22:44:08 volumio-audio volumio[5029]: info: touch_display: Volumio Kiosk started. Jan 13 22:44:08 volumio-audio systemd[1]: shairport-sync.service: Deactivated successfully. Jan 13 22:44:08 volumio-audio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 13 22:44:08 volumio-audio systemd[1]: shairport-sync.service: Consumed 1.888s CPU time. Jan 13 22:44:08 volumio-audio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Jan 13 22:44:08 volumio-audio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 13 22:44:08 volumio-audio volumio[5029]: info: CoreCommandRouter::volumioGetState Jan 13 22:44:08 volumio-audio volumio[5029]: info: CorePlayQueue::getTrack 0 Jan 13 22:44:08 volumio-audio volumio[5029]: info: Completed starting Core Plugins Jan 13 22:44:08 volumio-audio volumio[5029]: info: ------------------------------------------- Jan 13 22:44:08 volumio-audio volumio[5029]: info: ----- MyVolumio plugins startup ---- Jan 13 22:44:08 volumio-audio volumio[5029]: info: ------------------------------------------- Jan 13 22:44:08 volumio-audio volumio[5029]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 13 22:44:08 volumio-audio volumio[5029]: info: CoreCommandRouter::volumioGetState Jan 13 22:44:08 volumio-audio volumio[5029]: info: CorePlayQueue::getTrack 0 Jan 13 22:44:08 volumio-audio sudo[9540]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:08 volumio-audio volumio[5029]: info: touch_display: systemctl stop getty@tty1.service succeeded. Jan 13 22:44:08 volumio-audio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 13 22:44:08 volumio-audio go-librespot[11213]: go-librespot daemon starting... Jan 13 22:44:08 volumio-audio sudo[9742]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:08 volumio-audio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 13 22:44:08 volumio-audio sudo[9933]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:08 volumio-audio sudo[9946]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:08 volumio-audio sudo[9942]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:08 volumio-audio volumio[5029]: info: Shairport-Sync Started Jan 13 22:44:09 volumio-audio go-librespot[11240]: time="2026-01-13T22:44:08+01:00" level=info msg="running go-librespot 0.4.0" Jan 13 22:44:09 volumio-audio go-librespot[11240]: time="2026-01-13T22:44:09+01:00" level=debug msg="app state loaded" Jan 13 22:44:09 volumio-audio go-librespot[11240]: time="2026-01-13T22:44:09+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 13 22:44:09 volumio-audio volumio[5029]: Error adding Membership: Error: addMembership EINVAL Jan 13 22:44:09 volumio-audio volumio[5029]: info: Shairport-Sync Started Jan 13 22:44:09 volumio-audio volumio[5029]: info: Shairport-Sync Started Jan 13 22:44:09 volumio-audio volumio[5029]: info: touch_display: X display number found: 0 Jan 13 22:44:09 volumio-audio volumio[5029]: info: touch_display: X display number found: 0 Jan 13 22:44:09 volumio-audio volumio[5029]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jan 13 22:44:09 volumio-audio volumio[5029]: info: touch_display: X display number found: 0 Jan 13 22:44:09 volumio-audio volumio[5029]: info: CoreCommandRouter::volumioGetState Jan 13 22:44:09 volumio-audio volumio[5029]: info: CorePlayQueue::getTrack 0 Jan 13 22:44:09 volumio-audio volumio[5029]: info: touch_display: Setting screensaver timeout to 120 seconds. Jan 13 22:44:09 volumio-audio go-librespot[11240]: time="2026-01-13T22:44:09+01: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]" Jan 13 22:44:09 volumio-audio go-librespot[11240]: time="2026-01-13T22:44:09+01: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]" Jan 13 22:44:09 volumio-audio go-librespot[11240]: time="2026-01-13T22:44:09+01: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]" Jan 13 22:44:09 volumio-audio go-librespot[11240]: time="2026-01-13T22:44:09+01:00" level=info msg="zeroconf server listening on port 40383" Jan 13 22:44:09 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 13 22:44:09 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 13 22:44:09 volumio-audio go-librespot[11240]: time="2026-01-13T22:44:09+01:00" level=debug msg="obtained new client token: AADUqSG6v2gu51A6QIRf1k0JhuaKPRWIiJ8H6Lrs/brY6xfNB/Ie2mGMlWLWPVqhHA4Fjrl7btb/WhmVyH3RkeWQ+gXGjnlm104J3GYlpq6VoM3JarKhKaIlUF2k7c09I9mvuOSOiNcfBTs26vH1lPW6Dimb+0gjkv0KW+4ZCtjPnLaLGCSHmZqk4t7s8UkE1vVBGLRD5B2eH2J4kIBZX27P/OZz688XVs43wohOmGWlhzoxhWulTEXN1A==" Jan 13 22:44:09 volumio-audio go-librespot[11240]: time="2026-01-13T22:44:09+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jan 13 22:44:09 volumio-audio go-librespot[11240]: time="2026-01-13T22:44:09+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: read tcp 192.168.8.126:44260->34.158.1.133:4070: read: connection reset by peer" Jan 13 22:44:09 volumio-audio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 22:44:09 volumio-audio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 22:44:10 volumio-audio sudo[12310]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 13 22:44:10 volumio-audio sudo[12310]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:10 volumio-audio sudo[12321]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 13 22:44:10 volumio-audio sudo[12321]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:10 volumio-audio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Jan 13 22:44:10 volumio-audio systemd[1]: mpd_monitor.service: Deactivated successfully. Jan 13 22:44:10 volumio-audio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Jan 13 22:44:10 volumio-audio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Jan 13 22:44:10 volumio-audio sudo[12310]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:10 volumio-audio mpd_monitor.sh[12358]: MPD Monitor Service: Starting MPD Monitor Service Jan 13 22:44:10 volumio-audio volumio[5029]: info: Successfully started MPD Monitor Jan 13 22:44:10 volumio-audio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Jan 13 22:44:10 volumio-audio systemd[1]: mpd_monitor.service: Deactivated successfully. Jan 13 22:44:10 volumio-audio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Jan 13 22:44:10 volumio-audio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Jan 13 22:44:10 volumio-audio sudo[12321]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:10 volumio-audio volumio[5029]: info: Successfully started MPD Monitor Jan 13 22:44:10 volumio-audio mpd_monitor.sh[12419]: MPD Monitor Service: Starting MPD Monitor Service Jan 13 22:44:10 volumio-audio sudo[12456]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 13 22:44:10 volumio-audio sudo[12456]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:10 volumio-audio sudo[12456]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:10 volumio-audio sudo[12461]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 13 22:44:10 volumio-audio sudo[12461]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:10 volumio-audio sudo[12461]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:10 volumio-audio sudo[12480]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 13 22:44:10 volumio-audio sudo[12480]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:10 volumio-audio sudo[12480]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:10 volumio-audio volumio[5029]: info: Upmpdcli Daemon Started Jan 13 22:44:11 volumio-audio volumio[5029]: info: go-librespot daemon successfully initialized Jan 13 22:44:12 volumio-audio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Jan 13 22:44:12 volumio-audio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 13 22:44:12 volumio-audio volumio[5029]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jan 13 22:44:12 volumio-audio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 13 22:44:12 volumio-audio go-librespot[13837]: go-librespot daemon starting... Jan 13 22:44:12 volumio-audio go-librespot[13869]: time="2026-01-13T22:44:12+01:00" level=info msg="running go-librespot 0.4.0" Jan 13 22:44:12 volumio-audio go-librespot[13869]: time="2026-01-13T22:44:12+01:00" level=debug msg="app state loaded" Jan 13 22:44:12 volumio-audio go-librespot[13869]: time="2026-01-13T22:44:12+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 13 22:44:12 volumio-audio go-librespot[13869]: time="2026-01-13T22:44:12+01: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]" Jan 13 22:44:12 volumio-audio go-librespot[13869]: time="2026-01-13T22:44:12+01: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]" Jan 13 22:44:12 volumio-audio go-librespot[13869]: time="2026-01-13T22:44:12+01: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]" Jan 13 22:44:12 volumio-audio go-librespot[13869]: time="2026-01-13T22:44:12+01:00" level=info msg="zeroconf server listening on port 33409" Jan 13 22:44:13 volumio-audio go-librespot[13869]: time="2026-01-13T22:44:13+01:00" level=debug msg="obtained new client token: AACkqWWEGqZaTF3RfcilYej5A7jDoxyk3+UwrRimwj8JAl8h9pRWkgRwflS+bLjVb6G7YIAH6R4Qkrqxr29YX2iWUHWRBXQQPTzjB/z4xqWL5ggOXh0uSCHEGpBaZiQJPi6V6ifx04EK5u1cPcivqT8TBTm2+0pJlJbq5Zl4bEoDnyVK0od1POlWD2y9OrYh0so//gQ4zdmk5ciKSDKcmxybXdV1erV6XAcKGNofcrF42VNXcjVieRsfRw==" Jan 13 22:44:13 volumio-audio go-librespot[13869]: time="2026-01-13T22:44:13+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jan 13 22:44:13 volumio-audio go-librespot[13869]: time="2026-01-13T22:44:13+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: read tcp 192.168.8.126:44266->34.158.1.133:4070: read: connection reset by peer" Jan 13 22:44:13 volumio-audio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 22:44:13 volumio-audio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 22:44:15 volumio-audio volumio[5029]: info: Initializing connection to go-librespot Websocket Jan 13 22:44:15 volumio-audio volumio[5029]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 22:44:16 volumio-audio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Jan 13 22:44:16 volumio-audio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 13 22:44:16 volumio-audio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 13 22:44:16 volumio-audio go-librespot[16510]: go-librespot daemon starting... Jan 13 22:44:16 volumio-audio go-librespot[16584]: time="2026-01-13T22:44:16+01:00" level=info msg="running go-librespot 0.4.0" Jan 13 22:44:16 volumio-audio go-librespot[16584]: time="2026-01-13T22:44:16+01:00" level=debug msg="app state loaded" Jan 13 22:44:16 volumio-audio go-librespot[16584]: time="2026-01-13T22:44:16+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 13 22:44:16 volumio-audio go-librespot[16584]: time="2026-01-13T22:44:16+01: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-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 13 22:44:16 volumio-audio go-librespot[16584]: time="2026-01-13T22:44:16+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jan 13 22:44:16 volumio-audio go-librespot[16584]: time="2026-01-13T22:44:16+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jan 13 22:44:16 volumio-audio go-librespot[16584]: time="2026-01-13T22:44:16+01:00" level=info msg="zeroconf server listening on port 38959" Jan 13 22:44:16 volumio-audio go-librespot[16584]: time="2026-01-13T22:44:16+01:00" level=debug msg="obtained new client token: AAA9vE8UZRp5ULxPBTv01xi9D4ajrDnpeDFweZpdPHF6d4m5nYrARLLqOC7qYASib/5b54FcTiCMXZgmQgQlTTTkEph8g8gb+S2mq+Qd6YtlQ3tRShm3wonPBEOsbl7FyzanXk7ebA/89CvqvSLjQF08x63YCOaHqIKaBTcGB9MZ5naNLan3KohhIkQxk8+w1KSojDA4TANK2p4H3kB5mSBRl4Aao1xEFComnEnkORSx9OES4+hB2N5HJg==" Jan 13 22:44:16 volumio-audio go-librespot[16584]: time="2026-01-13T22:44:16+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jan 13 22:44:16 volumio-audio go-librespot[16584]: time="2026-01-13T22:44:16+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: read tcp 192.168.8.126:44274->34.158.1.133:4070: read: connection reset by peer" Jan 13 22:44:16 volumio-audio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 22:44:16 volumio-audio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 22:44:17 volumio-audio volumio[5029]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jan 13 22:44:17 volumio-audio volumio[5029]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jan 13 22:44:17 volumio-audio volumio[5029]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jan 13 22:44:17 volumio-audio volumio[5029]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jan 13 22:44:17 volumio-audio volumio[5029]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jan 13 22:44:17 volumio-audio volumio[5029]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jan 13 22:44:17 volumio-audio volumio[5029]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jan 13 22:44:17 volumio-audio volumio[5029]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jan 13 22:44:17 volumio-audio volumio[5029]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jan 13 22:44:17 volumio-audio volumio[5029]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jan 13 22:44:17 volumio-audio volumio[5029]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jan 13 22:44:17 volumio-audio volumio[5029]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jan 13 22:44:17 volumio-audio volumio[5029]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jan 13 22:44:17 volumio-audio volumio[5029]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jan 13 22:44:17 volumio-audio volumio[5029]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jan 13 22:44:17 volumio-audio volumio[5029]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jan 13 22:44:17 volumio-audio volumio[5029]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jan 13 22:44:17 volumio-audio volumio[5029]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jan 13 22:44:17 volumio-audio volumio[5029]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jan 13 22:44:17 volumio-audio volumio[5029]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jan 13 22:44:17 volumio-audio volumio[5029]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jan 13 22:44:17 volumio-audio volumio[5029]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jan 13 22:44:17 volumio-audio volumio[5029]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jan 13 22:44:17 volumio-audio volumio[5029]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jan 13 22:44:17 volumio-audio volumio[5029]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jan 13 22:44:17 volumio-audio volumio[5029]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jan 13 22:44:17 volumio-audio volumio[5029]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jan 13 22:44:17 volumio-audio volumio[5029]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jan 13 22:44:17 volumio-audio volumio[5029]: info: Adding plugin bluetooth to MyMusic Plugins Jan 13 22:44:17 volumio-audio volumio[5029]: info: Adding plugin multiroom to MyMusic Plugins Jan 13 22:44:17 volumio-audio volumio[5029]: info: Adding plugin metavolumio to MyMusic Plugins Jan 13 22:44:17 volumio-audio volumio[5029]: info: Adding plugin cd_controller to MyMusic Plugins Jan 13 22:44:17 volumio-audio volumio[5029]: info: Adding plugin qobuzconnect to MyMusic Plugins Jan 13 22:44:17 volumio-audio volumio[5029]: info: Adding plugin smart_inputs to MyMusic Plugins Jan 13 22:44:17 volumio-audio volumio[5029]: info: Adding plugin tidalconnect to MyMusic Plugins Jan 13 22:44:17 volumio-audio volumio[5029]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jan 13 22:44:17 volumio-audio volumio[5029]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jan 13 22:44:17 volumio-audio volumio[5029]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jan 13 22:44:17 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:44:17 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:44:17 volumio-audio volumio[5029]: info: Starting MyVolumio Remote Streaming Endpoints Jan 13 22:44:17 volumio-audio volumio[5029]: info: MyVolumio login type: Token Jan 13 22:44:17 volumio-audio volumio[5029]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jan 13 22:44:17 volumio-audio volumio[5029]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jan 13 22:44:18 volumio-audio volumio[5029]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jan 13 22:44:18 volumio-audio volumio[5029]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jan 13 22:44:18 volumio-audio volumio[5029]: info: Streaming services startup Jan 13 22:44:18 volumio-audio volumio[5029]: info: Starting Streaming Daemon Jan 13 22:44:18 volumio-audio sudo[18152]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 13 22:44:18 volumio-audio sudo[18152]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:18 volumio-audio volumio[5029]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jan 13 22:44:18 volumio-audio sudo[18152]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:18 volumio-audio volumio[5029]: info: Initializing connection to go-librespot Websocket Jan 13 22:44:18 volumio-audio volumio[5029]: error: Cannot start Volumio Streaming Daemon Jan 13 22:44:18 volumio-audio volumio[5029]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 13 22:44:18 volumio-audio volumio[5029]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 13 22:44:18 volumio-audio volumio[5029]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 22:44:19 volumio-audio volumio[5029]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jan 13 22:44:19 volumio-audio volumio[5029]: info: MyVolumio token set successfully Jan 13 22:44:19 volumio-audio volumio[5029]: info: MYVOLUMIO: Adding device Jan 13 22:44:19 volumio-audio volumio[5029]: info: MYVOLUMIO: Evaluating Server Jan 13 22:44:19 volumio-audio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Jan 13 22:44:19 volumio-audio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 13 22:44:19 volumio-audio volumio[5029]: info: MyVolumio status changed Jan 13 22:44:19 volumio-audio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 13 22:44:19 volumio-audio volumio[5029]: info: Streaming services startup Jan 13 22:44:19 volumio-audio volumio[5029]: info: Starting Streaming Daemon Jan 13 22:44:19 volumio-audio go-librespot[19172]: go-librespot daemon starting... Jan 13 22:44:19 volumio-audio volumio[5029]: info: Removing browser output: myVolumio user plan is not superstar Jan 13 22:44:19 volumio-audio volumio[5029]: info: Removing audio output: Jan 13 22:44:19 volumio-audio volumio[5029]: info: Stoppping Tunnel 1 Jan 13 22:44:19 volumio-audio go-librespot[19203]: time="2026-01-13T22:44:19+01:00" level=info msg="running go-librespot 0.4.0" Jan 13 22:44:19 volumio-audio go-librespot[19203]: time="2026-01-13T22:44:19+01:00" level=debug msg="app state loaded" Jan 13 22:44:19 volumio-audio go-librespot[19203]: time="2026-01-13T22:44:19+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 13 22:44:19 volumio-audio sudo[19214]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 13 22:44:19 volumio-audio sudo[19214]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:19 volumio-audio sudo[19224]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jan 13 22:44:19 volumio-audio sudo[19224]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:19 volumio-audio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 13 22:44:19 volumio-audio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 13 22:44:19 volumio-audio sudo[19214]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:19 volumio-audio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 13 22:44:19 volumio-audio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 13 22:44:19 volumio-audio volumio[5029]: error: Cannot start Volumio Streaming Daemon Jan 13 22:44:19 volumio-audio volumio[5029]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 13 22:44:19 volumio-audio volumio[5029]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 13 22:44:19 volumio-audio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 13 22:44:19 volumio-audio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 13 22:44:20 volumio-audio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 13 22:44:20 volumio-audio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 13 22:44:20 volumio-audio sudo[19224]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:20 volumio-audio volumio[5029]: info: Remote SSH Stopped Jan 13 22:44:20 volumio-audio go-librespot[19203]: time="2026-01-13T22:44:20+01: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]" Jan 13 22:44:20 volumio-audio go-librespot[19203]: time="2026-01-13T22:44:20+01: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]" Jan 13 22:44:20 volumio-audio go-librespot[19203]: time="2026-01-13T22:44:20+01: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]" Jan 13 22:44:20 volumio-audio go-librespot[19203]: time="2026-01-13T22:44:20+01:00" level=info msg="zeroconf server listening on port 41305" Jan 13 22:44:20 volumio-audio volumio[5029]: info: Setting Geolocation for MyVolumio to eu6 Jan 13 22:44:20 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:44:20 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:44:20 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:44:20 volumio-audio go-librespot[19203]: time="2026-01-13T22:44:20+01:00" level=debug msg="obtained new client token: AACT9nfUddpCSWmstE+xyvFcSIPpOLA4KW+f7WKOvY8wdd7v/ZgwdNHqgUL3Pi+CaohxlczS02SlrSIg6bwbDIkV6CWIT4LQ4mSsp/o+pg04FIvFHgWvo9y5TVWFEBPw1GTZn4Y7kkeTx07l1+nZ/R9SHrEh7v8TwlnN8elv8tQrAOxCHUV6JRrL+04j5DSZ8tsIEcDFA2KhkeO3QmcT+gXRrW9JlMluIKbYMpnryHhlRd6O9brNv9o=" Jan 13 22:44:20 volumio-audio go-librespot[19203]: time="2026-01-13T22:44:20+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jan 13 22:44:20 volumio-audio go-librespot[19203]: time="2026-01-13T22:44:20+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: read tcp 192.168.8.126:60390->34.158.1.133:4070: read: connection reset by peer" Jan 13 22:44:20 volumio-audio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 22:44:20 volumio-audio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 22:44:20 volumio-audio volumio[5029]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jan 13 22:44:21 volumio-audio volumio[5029]: info: Updating MyVolumio device info Jan 13 22:44:21 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:44:21 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:44:21 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:44:21 volumio-audio volumio[5029]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jan 13 22:44:21 volumio-audio volumio[5029]: info: Initializing connection to go-librespot Websocket Jan 13 22:44:21 volumio-audio volumio[5029]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 22:44:23 volumio-audio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Jan 13 22:44:23 volumio-audio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 13 22:44:23 volumio-audio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 13 22:44:23 volumio-audio go-librespot[21977]: go-librespot daemon starting... Jan 13 22:44:23 volumio-audio go-librespot[22017]: time="2026-01-13T22:44:23+01:00" level=info msg="running go-librespot 0.4.0" Jan 13 22:44:23 volumio-audio go-librespot[22017]: time="2026-01-13T22:44:23+01:00" level=debug msg="app state loaded" Jan 13 22:44:23 volumio-audio go-librespot[22017]: time="2026-01-13T22:44:23+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 13 22:44:23 volumio-audio go-librespot[22017]: time="2026-01-13T22:44:23+01: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]" Jan 13 22:44:23 volumio-audio go-librespot[22017]: time="2026-01-13T22:44:23+01: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]" Jan 13 22:44:23 volumio-audio go-librespot[22017]: time="2026-01-13T22:44:23+01: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]" Jan 13 22:44:23 volumio-audio go-librespot[22017]: time="2026-01-13T22:44:23+01:00" level=info msg="zeroconf server listening on port 32881" Jan 13 22:44:23 volumio-audio go-librespot[22017]: time="2026-01-13T22:44:23+01:00" level=debug msg="obtained new client token: AABmk9ZLEACiCE6fSooMO95QBcpvebwA4yWEWBFEfyP9KdtkSE9dnfPKC0jean9JPwv5E6vIu1sBlqQN9nFYKxm9lbGkMwCExYjKsj1Av0MhfP32BLaekxgJ6x0ylVo9t2ek3FIp+jdxyhlMSOYCgLOMUWps7xd8b4SJU5MEby/6U2doN5KLf6ZfbbeH8XGnjvPIqzo02LQX92NY6+BxUPQtw9zHxZYLIut1TcxsTjGwWVBYhxx2v4P0ZA==" Jan 13 22:44:23 volumio-audio go-librespot[22017]: time="2026-01-13T22:44:23+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jan 13 22:44:24 volumio-audio volumio[5029]: info: Initializing connection to go-librespot Websocket Jan 13 22:44:24 volumio-audio go-librespot[22017]: time="2026-01-13T22:44:24+01:00" level=debug msg="new websocket client" Jan 13 22:44:24 volumio-audio volumio[5029]: info: Connection to go-librespot Websocket established Jan 13 22:44:24 volumio-audio volumio[5029]: info: MYVOLUMIO: Adding device Jan 13 22:44:24 volumio-audio volumio[5029]: info: MYVOLUMIO: Evaluating Server Jan 13 22:44:25 volumio-audio go-librespot[22017]: time="2026-01-13T22:44:25+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: read tcp 192.168.8.126:60400->34.158.1.133:4070: read: connection reset by peer" Jan 13 22:44:25 volumio-audio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 22:44:25 volumio-audio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 22:44:25 volumio-audio volumio[5029]: info: Connection to go-librespot Websocket closed Jan 13 22:44:26 volumio-audio volumio[5029]: info: Setting Geolocation for MyVolumio to eu11 Jan 13 22:44:26 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:44:26 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:44:26 volumio-audio volumio[5029]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:44:27 volumio-audio volumio[5029]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jan 13 22:44:27 volumio-audio volumio[5029]: info: Getting Spotify volume Jan 13 22:44:27 volumio-audio volumio[5029]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 22:44:27 volumio-audio volumio[5029]: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 22:44:27 volumio-audio volumio[5029]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Jan 13 22:44:27 volumio-audio volumio[5029]: errno: -111, Jan 13 22:44:27 volumio-audio volumio[5029]: code: 'ECONNREFUSED', Jan 13 22:44:27 volumio-audio volumio[5029]: syscall: 'connect', Jan 13 22:44:27 volumio-audio volumio[5029]: address: '127.0.0.1', Jan 13 22:44:27 volumio-audio volumio[5029]: port: 9879, Jan 13 22:44:27 volumio-audio volumio[5029]: response: undefined Jan 13 22:44:27 volumio-audio volumio[5029]: } Jan 13 22:44:27 volumio-audio volumio[5029]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 22:44:28 volumio-audio sudo[25725]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-13 22:43' Jan 13 22:44:28 volumio-audio sudo[25725]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:28 volumio-audio sudo[25725]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:28 volumio-audio volumio-remote-updater[687]: [2026-01-13 22:44:28] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jan 13 22:44:28 volumio-audio volumio-remote-updater[687]: [2026-01-13 22:44:28] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jan 13 22:44:28 volumio-audio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jan 13 22:44:28 volumio-audio systemd[1]: volumio.service: Failed with result 'exit-code'. Jan 13 22:44:28 volumio-audio systemd[1]: volumio.service: Consumed 21.546s CPU time. Jan 13 22:44:28 volumio-audio systemd[1]: Started dynamicswap.service - dynamicswap service. Jan 13 22:44:28 volumio-audio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Jan 13 22:44:28 volumio-audio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 13 22:44:28 volumio-audio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 13 22:44:28 volumio-audio go-librespot[25935]: go-librespot daemon starting... Jan 13 22:44:28 volumio-audio systemd[1]: dynamicswap.service: Deactivated successfully. Jan 13 22:44:28 volumio-audio go-librespot[25943]: time="2026-01-13T22:44:28+01:00" level=info msg="running go-librespot 0.4.0" Jan 13 22:44:28 volumio-audio go-librespot[25943]: time="2026-01-13T22:44:28+01:00" level=debug msg="app state loaded" Jan 13 22:44:28 volumio-audio go-librespot[25943]: time="2026-01-13T22:44:28+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 13 22:44:28 volumio-audio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 3. Jan 13 22:44:28 volumio-audio systemd[1]: Started dynamicswap.service - dynamicswap service. Jan 13 22:44:28 volumio-audio systemd[1]: Stopped volumio.service - Volumio Backend Module. Jan 13 22:44:28 volumio-audio systemd[1]: volumio.service: Consumed 21.546s CPU time. Jan 13 22:44:28 volumio-audio systemd[1]: Started volumio.service - Volumio Backend Module. Jan 13 22:44:28 volumio-audio systemd[1]: dynamicswap.service: Deactivated successfully. Jan 13 22:44:28 volumio-audio go-librespot[25943]: time="2026-01-13T22:44:28+01: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]" Jan 13 22:44:28 volumio-audio go-librespot[25943]: time="2026-01-13T22:44:28+01: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]" Jan 13 22:44:28 volumio-audio go-librespot[25943]: time="2026-01-13T22:44:28+01: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]" Jan 13 22:44:28 volumio-audio go-librespot[25943]: time="2026-01-13T22:44:28+01:00" level=info msg="zeroconf server listening on port 34995" Jan 13 22:44:28 volumio-audio go-librespot[25943]: time="2026-01-13T22:44:28+01:00" level=debug msg="obtained new client token: AABfD9O2Gdh5Kz1Lrd9jqxuiPKkbNfG2CUDg66MxKNUEKiER0d7VXC5mVaNMDOg6YE3X62JNcpS5eSAjuZjcOmx52J+01JIeOrPkenRIpJ3bgb4+o9TVH2vbuBDgm+mdzln53o63MNFdtOZjB1CVZ1TvTFP72dvggm3A39FBf6rjCawRRUaQ5x2o5bvZkltlZSuo+Ya/fINTjEcx0y4Xwy//VtHzk53xBuNAReXKB8Iho0Whh+vmmicjxA==" Jan 13 22:44:28 volumio-audio go-librespot[25943]: time="2026-01-13T22:44:28+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jan 13 22:44:28 volumio-audio go-librespot[25943]: time="2026-01-13T22:44:28+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" Jan 13 22:44:28 volumio-audio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 22:44:28 volumio-audio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 22:44:30 volumio-audio volumio[26065]: info: ------------------------------------------- Jan 13 22:44:30 volumio-audio volumio[26065]: info: ----- Volumio3 ---- Jan 13 22:44:30 volumio-audio volumio[26065]: info: ------------------------------------------- Jan 13 22:44:30 volumio-audio volumio[26065]: info: ----- System startup ---- Jan 13 22:44:30 volumio-audio volumio[26065]: info: ------------------------------------------- Jan 13 22:44:30 volumio-audio volumio[26065]: info: MYVOLUMIO Environment detected Jan 13 22:44:30 volumio-audio volumio[26065]: info: Plugin folders cleanup Jan 13 22:44:30 volumio-audio volumio[26065]: info: Scanning into folder /volumio/app/plugins/ Jan 13 22:44:30 volumio-audio volumio[26065]: info: Scanning category audio_interface Jan 13 22:44:30 volumio-audio volumio[26065]: info: Scanning category miscellanea Jan 13 22:44:30 volumio-audio volumio[26065]: info: Scanning category music_service Jan 13 22:44:30 volumio-audio volumio[26065]: info: Scanning category plugins.json Jan 13 22:44:30 volumio-audio volumio[26065]: info: Scanning category system_controller Jan 13 22:44:30 volumio-audio volumio[26065]: info: Scanning category user_interface Jan 13 22:44:30 volumio-audio volumio[26065]: info: Scanning into folder /data/plugins/ Jan 13 22:44:30 volumio-audio volumio[26065]: info: Scanning category music_service Jan 13 22:44:30 volumio-audio volumio[26065]: info: Scanning category user_interface Jan 13 22:44:30 volumio-audio volumio[26065]: info: Plugin folders cleanup completed Jan 13 22:44:30 volumio-audio volumio[26065]: info: ------------------------------------------- Jan 13 22:44:30 volumio-audio volumio[26065]: info: ----- Core plugins startup ---- Jan 13 22:44:30 volumio-audio volumio[26065]: info: ------------------------------------------- Jan 13 22:44:30 volumio-audio volumio[26065]: info: Loading plugins from folder /volumio/app/plugins/ Jan 13 22:44:30 volumio-audio volumio[26065]: info: Adding plugin upnp to MyMusic Plugins Jan 13 22:44:30 volumio-audio volumio[26065]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 13 22:44:30 volumio-audio volumio[26065]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 13 22:44:30 volumio-audio volumio[26065]: info: Loading plugins from folder /data/plugins/ Jan 13 22:44:30 volumio-audio volumio[26065]: info: Loading plugin "system"... Jan 13 22:44:30 volumio-audio volumio[26065]: info: Loading plugin "appearance"... Jan 13 22:44:31 volumio-audio volumio[26065]: info: Loading plugin "network"... Jan 13 22:44:31 volumio-audio volumio[26065]: info: Refreshing Cached IP Addresses Jan 13 22:44:31 volumio-audio sudo[27771]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 13 22:44:31 volumio-audio sudo[27771]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:31 volumio-audio volumio[26065]: info: Loading plugin "services"... Jan 13 22:44:31 volumio-audio sudo[27776]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 13 22:44:31 volumio-audio sudo[27776]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:31 volumio-audio sudo[27771]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:31 volumio-audio volumio[26065]: info: Loading plugin "alsa_controller"... Jan 13 22:44:31 volumio-audio sudo[27776]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:31 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 22:44:31 volumio-audio volumio[26065]: info: Loading plugin "wizard"... Jan 13 22:44:31 volumio-audio volumio[26065]: info: Loading plugin "networkfs"... Jan 13 22:44:31 volumio-audio volumio[26065]: info: Starting Udev Watcher for removable devices Jan 13 22:44:31 volumio-audio volumio[26065]: info: Ignoring mount for partition: boot Jan 13 22:44:31 volumio-audio volumio[26065]: info: Ignoring mount for partition: volumio Jan 13 22:44:31 volumio-audio volumio[26065]: info: Ignoring mount for partition: volumio_data Jan 13 22:44:31 volumio-audio volumio[26065]: info: Mounting Device Nowy Jan 13 22:44:31 volumio-audio volumio[26065]: /bin/mkdir: cannot create directory ‘/mnt/USB/Nowy’: File exists Jan 13 22:44:31 volumio-audio volumio[26065]: error: Failed to create folder Error: Command failed: /bin/mkdir -m 777 "/mnt/USB/Nowy" Jan 13 22:44:31 volumio-audio volumio[26065]: /bin/mkdir: cannot create directory ‘/mnt/USB/Nowy’: File exists Jan 13 22:44:31 volumio-audio sudo[27905]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/Nowy -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Jan 13 22:44:31 volumio-audio sudo[27905]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:31 volumio-audio sudo[27905]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:31 volumio-audio volumio[26065]: Mount is denied because the NTFS volume is already exclusively opened. Jan 13 22:44:31 volumio-audio volumio[26065]: The volume may be already mounted, or another software may use it which Jan 13 22:44:31 volumio-audio volumio[26065]: could be identified for example by the help of the 'fuser' command. Jan 13 22:44:31 volumio-audio volumio[26065]: error: Failed to mount Nowy: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/Nowy" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Jan 13 22:44:31 volumio-audio volumio[26065]: Mount is denied because the NTFS volume is already exclusively opened. Jan 13 22:44:31 volumio-audio volumio[26065]: The volume may be already mounted, or another software may use it which Jan 13 22:44:31 volumio-audio volumio[26065]: could be identified for example by the help of the 'fuser' command. Jan 13 22:44:31 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 13 22:44:31 volumio-audio volumio[26065]: info: Loading plugin "volumio_command_line_client"... Jan 13 22:44:31 volumio-audio volumio[26065]: info: Loading plugin "upnp"... Jan 13 22:44:31 volumio-audio volumio[26065]: info: [1768340671794] Starting Upmpd Daemon Jan 13 22:44:31 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 13 22:44:31 volumio-audio volumio[26065]: info: Loading plugin "my_music"... Jan 13 22:44:31 volumio-audio volumio[26065]: info: Loading plugin "mpd"... Jan 13 22:44:32 volumio-audio volumio[26065]: info: Loading plugin "upnp_browser"... Jan 13 22:44:32 volumio-audio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Jan 13 22:44:32 volumio-audio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 13 22:44:32 volumio-audio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 13 22:44:32 volumio-audio go-librespot[28118]: go-librespot daemon starting... Jan 13 22:44:32 volumio-audio go-librespot[28148]: time="2026-01-13T22:44:32+01:00" level=info msg="running go-librespot 0.4.0" Jan 13 22:44:32 volumio-audio go-librespot[28148]: time="2026-01-13T22:44:32+01:00" level=debug msg="app state loaded" Jan 13 22:44:32 volumio-audio go-librespot[28148]: time="2026-01-13T22:44:32+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 13 22:44:32 volumio-audio go-librespot[28148]: time="2026-01-13T22:44:32+01: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]" Jan 13 22:44:32 volumio-audio go-librespot[28148]: time="2026-01-13T22:44:32+01: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]" Jan 13 22:44:32 volumio-audio go-librespot[28148]: time="2026-01-13T22:44:32+01: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]" Jan 13 22:44:32 volumio-audio go-librespot[28148]: time="2026-01-13T22:44:32+01:00" level=info msg="zeroconf server listening on port 41279" Jan 13 22:44:32 volumio-audio go-librespot[28148]: time="2026-01-13T22:44:32+01:00" level=debug msg="obtained new client token: AABPgSJ73BngJyUMEkA1oiSK6GEUeRTfUa8ZNB/rGrDgiuOQxXqj9tv9yOBpZOI9GtFLGgRIrUQVafJBwNtCTVxxNt+W8Mn/FFpiHUIocNFE4FPJejmlTKuEIBfl9sd8k+SZbERlQztT2NObHYxwRBMnr9P9NvLFaTLI9O4H3mRxs9iLUjO6xLQhTn3IX+eixFh906kQgHqCGAI0LUFDtbLITCsV2o/vZ40Q2lBoyIfldaYWt1TkpB4ZUQ==" Jan 13 22:44:32 volumio-audio go-librespot[28148]: time="2026-01-13T22:44:32+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jan 13 22:44:32 volumio-audio go-librespot[28148]: time="2026-01-13T22:44:32+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: read tcp 192.168.8.126:38286->34.158.1.133:4070: read: connection reset by peer" Jan 13 22:44:32 volumio-audio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 22:44:32 volumio-audio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 22:44:32 volumio-audio volumio[26065]: info: Starting UPNP Browser Jan 13 22:44:32 volumio-audio volumio[26065]: info: Loading plugin "alarm-clock"... Jan 13 22:44:33 volumio-audio volumio[26065]: info: Loading plugin "airplay_emulation"... Jan 13 22:44:33 volumio-audio volumio[26065]: info: Starting Shairport Sync Jan 13 22:44:33 volumio-audio volumio[26065]: info: Loading plugin "last_100"... Jan 13 22:44:33 volumio-audio volumio[26065]: info: Loading plugin "webradio"... Jan 13 22:44:33 volumio-audio volumio[26065]: info: Loading plugin "i2s_dacs"... Jan 13 22:44:33 volumio-audio volumio[26065]: info: I2S DAC not set, start Auto-detection Jan 13 22:44:33 volumio-audio volumio[26065]: info: Loading plugin "volumiodiscovery"... Jan 13 22:44:33 volumio-audio volumio[26065]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 13 22:44:33 volumio-audio volumio[26065]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 22:44:33 volumio-audio node[26065]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 13 22:44:33 volumio-audio volumio[26065]: *** WARNING *** For more information see Jan 13 22:44:33 volumio-audio volumio[26065]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 13 22:44:33 volumio-audio volumio[26065]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 22:44:33 volumio-audio volumio[26065]: *** WARNING *** For more information see Jan 13 22:44:33 volumio-audio node[26065]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 22:44:33 volumio-audio node[26065]: *** WARNING *** For more information see Jan 13 22:44:33 volumio-audio node[26065]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 13 22:44:33 volumio-audio node[26065]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 22:44:33 volumio-audio node[26065]: *** WARNING *** For more information see Jan 13 22:44:33 volumio-audio volumio[26065]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 13 22:44:33 volumio-audio volumio[26065]: info: Discovery: Started advertising with name: Volumio Audio Jan 13 22:44:33 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 13 22:44:33 volumio-audio volumio[26065]: info: Loading plugin "spop"... Jan 13 22:44:33 volumio-audio volumio-remote-updater[687]: [2026-01-13 22:44:33] [connect] Successful connection Jan 13 22:44:34 volumio-audio volumio[26065]: info: Loading plugin "outputs"... Jan 13 22:44:34 volumio-audio volumio[26065]: info: Loading plugin "albumart"... Jan 13 22:44:34 volumio-audio volumio[26065]: info: Plugin example_plugin is not enabled Jan 13 22:44:34 volumio-audio volumio[26065]: info: Loading plugin "inputs"... Jan 13 22:44:34 volumio-audio volumio[26065]: info: Loading plugin "updater_comm"... Jan 13 22:44:34 volumio-audio volumio[26065]: info: Plugin mpdemulation is not enabled Jan 13 22:44:34 volumio-audio volumio[26065]: info: Loading plugin "rest_api"... Jan 13 22:44:34 volumio-audio volumio[26065]: info: Loading plugin "websocket"... Jan 13 22:44:34 volumio-audio volumio[26065]: info: Starting Socket.io Server version 1.7.4 Jan 13 22:44:34 volumio-audio volumio[26065]: info: Loading plugin "minidlna"... Jan 13 22:44:34 volumio-audio volumio[26065]: info: Applying required configuration parameters for plugin minidlna Jan 13 22:44:34 volumio-audio volumio[26065]: info: Loading plugin "touch_display"... Jan 13 22:44:34 volumio-audio volumio[29257]: Forking 3 albumart workers Jan 13 22:44:34 volumio-audio volumio[26065]: info: Applying required configuration parameters for plugin touch_display Jan 13 22:44:34 volumio-audio volumio[26065]: info: Loading i18n strings for locale pl Jan 13 22:44:34 volumio-audio volumio[26065]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Jan 13 22:44:34 volumio-audio volumio[26065]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Jan 13 22:44:34 volumio-audio volumio[26065]: Updating browse sources language Jan 13 22:44:34 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 22:44:34 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 22:44:34 volumio-audio volumio[26065]: info: CoreCommandRouter::initPlayerControls Jan 13 22:44:34 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:34 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:34 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:34 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:34 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:34 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:34 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:34 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:34 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 22:44:34 volumio-audio volumio[26065]: Express server listening on port 3000 Jan 13 22:44:34 volumio-audio volumio[26065]: [Metrics] WebUI: 4s 995.20ms Jan 13 22:44:34 volumio-audio volumio[26065]: info: CoreStateMachine::resetVolumioState Jan 13 22:44:34 volumio-audio volumio[26065]: info: CoreStateMachine::getcurrentVolume Jan 13 22:44:34 volumio-audio volumio[26065]: info: CoreCommandRouter::volumioRetrievevolume Jan 13 22:44:34 volumio-audio volumio[26065]: info: CoreStateMachine::pushState Jan 13 22:44:34 volumio-audio volumio[26065]: info: CorePlayQueue::getTrack 0 Jan 13 22:44:34 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 22:44:34 volumio-audio volumio[26065]: info: CoreCommandRouter::volumioPushState Jan 13 22:44:34 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:44:34 volumio-audio volumio[26065]: info: Volumio Network Manager: Network status updated: 1 Jan 13 22:44:35 volumio-audio volumio[26065]: verbose: New Socket.io Connection to 192.168.8.126:3000 from 192.168.8.124 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 1 Jan 13 22:44:35 volumio-audio volumio-remote-updater[687]: [2026-01-13 22:44:35] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1768340673 101 Jan 13 22:44:35 volumio-audio volumio[26065]: 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: 2 Jan 13 22:44:35 volumio-audio volumio[26065]: info: Reloading queue from file Jan 13 22:44:35 volumio-audio volumio[26065]: verbose: New Socket.io Connection to 192.168.8.126 from 192.168.8.124 UA: Mozilla/5.0 (Linux; Android 14; SM-M135F Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.192 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jan 13 22:44:35 volumio-audio volumio[26065]: info: CoreStateMachine::setRepeat null single undefined Jan 13 22:44:35 volumio-audio volumio[26065]: info: CoreStateMachine::pushState Jan 13 22:44:35 volumio-audio volumio[26065]: info: CorePlayQueue::getTrack 0 Jan 13 22:44:35 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 22:44:35 volumio-audio volumio[26065]: info: CoreCommandRouter::volumioPushState Jan 13 22:44:35 volumio-audio volumio[26065]: info: CoreStateMachine::setRandom null Jan 13 22:44:35 volumio-audio volumio[26065]: info: CoreStateMachine::pushState Jan 13 22:44:35 volumio-audio volumio[26065]: info: CorePlayQueue::getTrack 0 Jan 13 22:44:35 volumio-audio volumio[26065]: info: CoreCommandRouter::volumioPushState Jan 13 22:44:35 volumio-audio volumio[26065]: info: Setting Device type: Raspberry PI Jan 13 22:44:35 volumio-audio volumio[26065]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jan 13 22:44:35 volumio-audio volumio[26065]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03111 Jan 13 22:44:35 volumio-audio volumio[26065]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jan 13 22:44:35 volumio-audio volumio[26065]: verbose: New Socket.io Connection to 192.168.8.126 from 192.168.8.124 UA: Mozilla/5.0 (Linux; Android 14; SM-M135F Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.192 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jan 13 22:44:35 volumio-audio volumio[26065]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jan 13 22:44:35 volumio-audio volumio[26065]: info: Discovery: adding 901c8b4a-224a-4026-9901-c39bd9ea1d93 Jan 13 22:44:35 volumio-audio volumio[26065]: info: Discovery: Found device Volumio Audio Jan 13 22:44:35 volumio-audio volumio[26065]: info: CoreCommandRouter::volumioGetState Jan 13 22:44:35 volumio-audio volumio[26065]: info: CorePlayQueue::getTrack 0 Jan 13 22:44:35 volumio-audio volumio[26065]: info: Discovery: this is already registered, 901c8b4a-224a-4026-9901-c39bd9ea1d93 Jan 13 22:44:35 volumio-audio volumio[26065]: info: Discovery: Found device Volumio Audio Jan 13 22:44:35 volumio-audio volumio[26065]: info: CoreCommandRouter::volumioGetState Jan 13 22:44:35 volumio-audio volumio[26065]: info: CorePlayQueue::getTrack 0 Jan 13 22:44:35 volumio-audio volumio[26065]: info: Completed loading Core Plugins Jan 13 22:44:35 volumio-audio volumio[26065]: info: Preparing to generate the ALSA configuration file Jan 13 22:44:35 volumio-audio volumio[26065]: info: Asound.conf file unchanged, so no further update is needed Jan 13 22:44:35 volumio-audio volumio[26065]: info: Output device has changed, restarting MPD Jan 13 22:44:35 volumio-audio volumio[29478]: Starting albumart workers Jan 13 22:44:35 volumio-audio volumio[26065]: info: Output device has changed, restarting Shairport Sync Jan 13 22:44:35 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:35 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:44:35 volumio-audio sudo[29985]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 13 22:44:35 volumio-audio sudo[29985]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:35 volumio-audio volumio[26065]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 22:44:35 volumio-audio sudo[29985]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:35 volumio-audio volumio[26065]: info: ___________ START PLUGINS ___________ Jan 13 22:44:35 volumio-audio sudo[29997]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 13 22:44:35 volumio-audio volumio[26065]: info: ControllerMpd::onStart: Initializing MPD Jan 13 22:44:35 volumio-audio sudo[29997]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:35 volumio-audio volumio[26065]: info: Creating MPD Configuration file Jan 13 22:44:35 volumio-audio volumio[29486]: Starting albumart workers Jan 13 22:44:35 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 22:44:35 volumio-audio volumio[26065]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 22:44:35 volumio-audio volumio[26065]: info: [1768340675773] CoreMusicLibrary::Adding element Serwery Mediów Jan 13 22:44:35 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 22:44:35 volumio-audio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Jan 13 22:44:35 volumio-audio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 13 22:44:35 volumio-audio sudo[30026]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 13 22:44:35 volumio-audio sudo[30026]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:35 volumio-audio volumio[26065]: info: UPNP Browser: Client initialized successfully Jan 13 22:44:35 volumio-audio sudo[30026]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:35 volumio-audio sudo[30035]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 13 22:44:35 volumio-audio sudo[30035]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:35 volumio-audio volumio[29483]: Starting albumart workers Jan 13 22:44:35 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:35 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:44:35 volumio-audio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 13 22:44:35 volumio-audio go-librespot[30046]: go-librespot daemon starting... Jan 13 22:44:35 volumio-audio systemd[1]: Stopping mpd.service - Music Player Daemon... Jan 13 22:44:35 volumio-audio upmpdcli[1585]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 13 22:44:35 volumio-audio go-librespot[30077]: time="2026-01-13T22:44:35+01:00" level=info msg="running go-librespot 0.4.0" Jan 13 22:44:35 volumio-audio go-librespot[30077]: time="2026-01-13T22:44:35+01:00" level=debug msg="app state loaded" Jan 13 22:44:35 volumio-audio volumio[26065]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 22:44:35 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:35 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:44:35 volumio-audio go-librespot[30077]: time="2026-01-13T22:44:35+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 13 22:44:35 volumio-audio systemd[1]: mpd.service: Deactivated successfully. Jan 13 22:44:35 volumio-audio systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 13 22:44:35 volumio-audio systemd[1]: mpd.service: Consumed 2.249s CPU time. Jan 13 22:44:35 volumio-audio systemd[1]: mpd.socket: Deactivated successfully. Jan 13 22:44:35 volumio-audio volumio[26065]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 22:44:35 volumio-audio volumio[26065]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 22:44:35 volumio-audio volumio[26065]: info: [1768340675938] CoreMusicLibrary::Adding element Last_100 Jan 13 22:44:35 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 22:44:35 volumio-audio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 13 22:44:35 volumio-audio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 13 22:44:35 volumio-audio volumio[26065]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 22:44:35 volumio-audio volumio[26065]: info: [1768340675941] CoreMusicLibrary::Adding element Webradio Jan 13 22:44:35 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 22:44:35 volumio-audio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 13 22:44:35 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 22:44:35 volumio-audio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 13 22:44:35 volumio-audio volumio[26065]: info: Initializing BBC Radios Jan 13 22:44:36 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 22:44:36 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:44:36 volumio-audio volumio[26065]: info: Creating Spotify config file Jan 13 22:44:36 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:36 volumio-audio sudo[30123]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 13 22:44:36 volumio-audio sudo[30123]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 13 22:44:36 volumio-audio sudo[30123]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:36 volumio-audio go-librespot[30077]: time="2026-01-13T22:44:36+01: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]" Jan 13 22:44:36 volumio-audio go-librespot[30077]: time="2026-01-13T22:44:36+01: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]" Jan 13 22:44:36 volumio-audio go-librespot[30077]: time="2026-01-13T22:44:36+01: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]" Jan 13 22:44:36 volumio-audio go-librespot[30077]: time="2026-01-13T22:44:36+01:00" level=info msg="zeroconf server listening on port 45203" Jan 13 22:44:36 volumio-audio go-librespot[30077]: time="2026-01-13T22:44:36+01:00" level=debug msg="obtained new client token: AADHSPeYw6Rd1h99wOw+wsreUHFsyCVdHwftzwSgWWKXZUf7o+Wvc0Wd44PEux6exv6grVjFLOU2q12iSJbOKujJnEX4Or4Ph/EDhZ5xPeHzR4fu+LErhOWWYXxSsK8toCdsJe8KkrHP/1vOaWTALoHROujtrBiQ/UUojwwx0IyMPE3+0bgaMLA1hEsydyY/1U0PhSJG5gJYIVOr6+bvN0K1Rd3XnQayq7ydZrWcxXAEpK665/bFqutLAw==" Jan 13 22:44:36 volumio-audio go-librespot[30077]: time="2026-01-13T22:44:36+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jan 13 22:44:36 volumio-audio go-librespot[30077]: time="2026-01-13T22:44:36+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: read tcp 192.168.8.126:38296->34.158.1.133:4070: read: connection reset by peer" Jan 13 22:44:36 volumio-audio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 22:44:36 volumio-audio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 22:44:36 volumio-audio volumio[26065]: info: Loading i18n strings for locale pl Jan 13 22:44:36 volumio-audio volumio[26065]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Jan 13 22:44:36 volumio-audio volumio[26065]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Jan 13 22:44:36 volumio-audio volumio[26065]: info: Loading i18n strings for locale pl Jan 13 22:44:36 volumio-audio volumio[26065]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Jan 13 22:44:36 volumio-audio volumio[26065]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Jan 13 22:44:36 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 22:44:36 volumio-audio volumio[26065]: info: Volumio Calling Home Jan 13 22:44:36 volumio-audio sudo[30359]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /data/volumiokioskextensions/IframeKeyboardBridge Jan 13 22:44:36 volumio-audio sudo[30359]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:36 volumio-audio sudo[30359]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:36 volumio-audio sudo[30449]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Jan 13 22:44:36 volumio-audio sudo[30449]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:36 volumio-audio sudo[30463]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Jan 13 22:44:36 volumio-audio sudo[30463]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:36 volumio-audio sudo[30477]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jan 13 22:44:36 volumio-audio sudo[30477]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:37 volumio-audio systemd[1]: Reloading. Jan 13 22:44:37 volumio-audio volumio[26065]: info: touch_display: No backlight interface detected. Jan 13 22:44:37 volumio-audio volumio[26065]: info: minidlna: Found miniDLNA version 1.3.0 Jan 13 22:44:37 volumio-audio volumio[26065]: info: minidlna: Starting minidlna.service Jan 13 22:44:37 volumio-audio volumio[26065]: info: MPD Permissions set Jan 13 22:44:37 volumio-audio volumio[26065]: info: MPD Permissions set Jan 13 22:44:37 volumio-audio sudo[30560]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/cp -r /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/content.js /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/manifest.json /data/volumiokioskextensions/IframeKeyboardBridge/ Jan 13 22:44:37 volumio-audio sudo[30560]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:37 volumio-audio sudo[30570]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Jan 13 22:44:37 volumio-audio sudo[30570]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:37 volumio-audio sudo[30560]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:37 volumio-audio volumio[26065]: info: Volumio called home Jan 13 22:44:37 volumio-audio volumio[26065]: info: Spotify config file written Jan 13 22:44:37 volumio-audio sudo[30637]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jan 13 22:44:37 volumio-audio sudo[30637]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:37 volumio-audio volumio[26065]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jan 13 22:44:37 volumio-audio volumio[26065]: info: touch_display: IframeKeyboardBridge extension installed successfully Jan 13 22:44:37 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:37 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:37 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:37 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:37 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:37 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:37 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:37 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:37 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:44:37 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:37 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:37 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:37 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:37 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:37 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:37 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:37 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:37 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:37 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:44:37 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:44:37 volumio-audio volumio[26065]: info: No need to fix Spotify hosts Jan 13 22:44:37 volumio-audio volumio[26065]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Jan 13 22:44:37 volumio-audio sudo[30779]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Jan 13 22:44:37 volumio-audio sudo[30779]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:37 volumio-audio sudo[30785]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Jan 13 22:44:37 volumio-audio sudo[30785]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:37 volumio-audio sudo[30779]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:37 volumio-audio sudo[30785]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:37 volumio-audio volumio[26065]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jan 13 22:44:37 volumio-audio volumio[26065]: SPOTIFY: BQA1ghPYB_xsg2t9hy05Ebg6U_6LH6PwLlTyVr77hRGlXBkwuIo_Q-asyfdCVY7mtNMnxFbC4A5jquhQcW9tKj2S6fW7slFOrNiDQLILKDbk9SUbRv7QEYFjhptlMzxnhBNom8GJPOKbDxmh8Bfy1RPPvo6-BQ7JC09N7hVzXG6yvULRbmE16KG_kjL_mVXEb5zt6kxmaaD-BgSUkKOIiYEYwJ6AYVwNRDtWArkmeBUDeUIecFzXrcY6AR_QwDo8LZPadKum9tqZumroKNTvDZZZwr6RZeOBq_-MFKTO_qNwjeCc340iMai32zkf Jan 13 22:44:37 volumio-audio volumio[26065]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jan 13 22:44:37 volumio-audio volumio[26065]: info: New Spotify access token = BQA1ghPYB_xsg2t9hy05Ebg6U_6LH6PwLlTyVr77hRGlXBkwuIo_Q-asyfdCVY7mtNMnxFbC4A5jquhQcW9tKj2S6fW7slFOrNiDQLILKDbk9SUbRv7QEYFjhptlMzxnhBNom8GJPOKbDxmh8Bfy1RPPvo6-BQ7JC09N7hVzXG6yvULRbmE16KG_kjL_mVXEb5zt6kxmaaD-BgSUkKOIiYEYwJ6AYVwNRDtWArkmeBUDeUIecFzXrcY6AR_QwDo8LZPadKum9tqZumroKNTvDZZZwr6RZeOBq_-MFKTO_qNwjeCc340iMai32zkf Jan 13 22:44:37 volumio-audio volumio[26065]: info: Spotify credentials grant success - running version from March 24, 2019 Jan 13 22:44:37 volumio-audio volumio[26065]: info: Starting Shairport Sync Jan 13 22:44:37 volumio-audio volumio[26065]: info: Starting Shairport Sync Jan 13 22:44:37 volumio-audio volumio[26065]: info: Starting Shairport Sync Jan 13 22:44:37 volumio-audio sudo[30827]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 13 22:44:37 volumio-audio sudo[30827]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:37 volumio-audio volumio[26065]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Jan 13 22:44:37 volumio-audio volumio[26065]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Jan 13 22:44:37 volumio-audio sudo[30834]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 13 22:44:37 volumio-audio sudo[30834]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:37 volumio-audio sudo[30832]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 13 22:44:37 volumio-audio sudo[30832]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:37 volumio-audio volumio[26065]: verbose: New Socket.io Connection to 192.168.8.126 from 192.168.8.124 UA: Mozilla/5.0 (Linux; Android 14; SM-M135F Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.192 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Jan 13 22:44:37 volumio-audio volumio[26065]: info: CoreCommandRouter::volumioGetState Jan 13 22:44:37 volumio-audio volumio[26065]: info: CorePlayQueue::getTrack 0 Jan 13 22:44:38 volumio-audio volumio[26065]: SPOTIFY: User informations: {"country":"PL","display_name":"Rafał Bednarski","email":"rbednarski08@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31qkindigqrwplw655lp5r7ef2la"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/31qkindigqrwplw655lp5r7ef2la","id":"31qkindigqrwplw655lp5r7ef2la","images":[{"height":300,"url":"https://platform-lookaside.fbsbx.com/platform/profilepic/?asid=4995835327155858&height=300&width=300&ext=1770921956&hash=AT8B2TEWdfhRCm7Z2YwuvI4W","width":300},{"height":64,"url":"https://platform-lookaside.fbsbx.com/platform/profilepic/?asid=4995835327155858&height=50&width=50&ext=1770921956&hash=AT9iycZnhgRSfOcVXIdqmnMY","width":64}],"product":"premium","type":"user","uri":"spotify:user:31qkindigqrwplw655lp5r7ef2la"} Jan 13 22:44:38 volumio-audio volumio[26065]: info: Spotify Successfully logged in Jan 13 22:44:38 volumio-audio volumio[26065]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 22:44:38 volumio-audio volumio[26065]: info: [1768340678085] CoreMusicLibrary::Adding element Spotify Jan 13 22:44:38 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 22:44:38 volumio-audio volumio[26065]: Cannot find translation for source Spotify Jan 13 22:44:38 volumio-audio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 13 22:44:38 volumio-audio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 13 22:44:38 volumio-audio sudo[30463]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:38 volumio-audio volumio[26065]: info: touch_display: systemctl disable getty@tty1.service succeeded. Jan 13 22:44:38 volumio-audio systemd[1]: Reloading. Jan 13 22:44:38 volumio-audio mpd[30205]: 2026-01-13T22:44:38 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 13 22:44:38 volumio-audio upmpdcli[1585]: :3:../src/mpdcli.cxx:148::MPDCli::startEventLoop: already started Jan 13 22:44:39 volumio-audio volumio[26065]: verbose: New Socket.io Connection to 192.168.8.126:3000 from 192.168.8.124 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Jan 13 22:44:39 volumio-audio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 13 22:44:39 volumio-audio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 13 22:44:39 volumio-audio systemd[1]: Started mpd.service - Music Player Daemon. Jan 13 22:44:39 volumio-audio sudo[30035]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:39 volumio-audio sudo[30477]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:39 volumio-audio sudo[29997]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:39 volumio-audio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Jan 13 22:44:39 volumio-audio volumio[26065]: info: touch_display: systemctl daemon-reload succeeded. Jan 13 22:44:39 volumio-audio sudo[32022]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Jan 13 22:44:39 volumio-audio sudo[32022]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:39 volumio-audio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 13 22:44:39 volumio-audio volumio[26065]: error: MPD error: The expression evaluated to a falsy value: Jan 13 22:44:39 volumio-audio volumio[26065]: assert.ok(self.idling) Jan 13 22:44:39 volumio-audio volumio[26065]: error: The expression evaluated to a falsy value: Jan 13 22:44:39 volumio-audio volumio[26065]: assert.ok(self.idling) Jan 13 22:44:39 volumio-audio volumio[26065]: info: MPD running with PID30205 Jan 13 22:44:39 volumio-audio volumio[26065]: ,establishing connection Jan 13 22:44:39 volumio-audio volumio[26065]: error: updateQueue error: null Jan 13 22:44:39 volumio-audio volumio[26065]: error: updateQueue error: null Jan 13 22:44:39 volumio-audio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 13 22:44:39 volumio-audio go-librespot[32047]: go-librespot daemon starting... Jan 13 22:44:39 volumio-audio sudo[30637]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:39 volumio-audio sudo[30570]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:39 volumio-audio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 13 22:44:39 volumio-audio systemd[1]: shairport-sync.service: Deactivated successfully. Jan 13 22:44:39 volumio-audio volumio[26065]: info: minidlna: systemctl start minidlna.service succeeded. Jan 13 22:44:39 volumio-audio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 13 22:44:39 volumio-audio systemd[1]: shairport-sync.service: Consumed 1.803s CPU time. Jan 13 22:44:39 volumio-audio go-librespot[32089]: time="2026-01-13T22:44:39+01:00" level=info msg="running go-librespot 0.4.0" Jan 13 22:44:39 volumio-audio go-librespot[32089]: time="2026-01-13T22:44:39+01:00" level=debug msg="app state loaded" Jan 13 22:44:39 volumio-audio go-librespot[32089]: time="2026-01-13T22:44:39+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 13 22:44:39 volumio-audio sudo[30449]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:39 volumio-audio volumio[26065]: info: touch_display: systemctl stop getty@tty1.service succeeded. Jan 13 22:44:39 volumio-audio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 13 22:44:39 volumio-audio sudo[30834]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:39 volumio-audio sudo[30827]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:39 volumio-audio sudo[30832]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:39 volumio-audio sudo[32022]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:40 volumio-audio volumio[26065]: info: Shairport-Sync Started Jan 13 22:44:40 volumio-audio volumio[26065]: Error adding Membership: Error: addMembership EINVAL Jan 13 22:44:40 volumio-audio volumio[26065]: info: Shairport-Sync Started Jan 13 22:44:40 volumio-audio volumio[26065]: info: Shairport-Sync Started Jan 13 22:44:40 volumio-audio volumio[26065]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Jan 13 22:44:40 volumio-audio volumio[26065]: info: touch_display: Volumio Kiosk started. Jan 13 22:44:40 volumio-audio volumio[26065]: info: CoreCommandRouter::volumioGetState Jan 13 22:44:40 volumio-audio volumio[26065]: info: CorePlayQueue::getTrack 0 Jan 13 22:44:40 volumio-audio volumio[26065]: info: Completed starting Core Plugins Jan 13 22:44:40 volumio-audio volumio[26065]: info: ------------------------------------------- Jan 13 22:44:40 volumio-audio volumio[26065]: info: ----- MyVolumio plugins startup ---- Jan 13 22:44:40 volumio-audio volumio[26065]: info: ------------------------------------------- Jan 13 22:44:40 volumio-audio volumio[26065]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 13 22:44:40 volumio-audio volumio[26065]: info: CoreCommandRouter::volumioGetState Jan 13 22:44:40 volumio-audio volumio[26065]: info: CorePlayQueue::getTrack 0 Jan 13 22:44:40 volumio-audio go-librespot[32089]: time="2026-01-13T22:44:40+01: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]" Jan 13 22:44:40 volumio-audio go-librespot[32089]: time="2026-01-13T22:44:40+01: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]" Jan 13 22:44:40 volumio-audio go-librespot[32089]: time="2026-01-13T22:44:40+01: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]" Jan 13 22:44:40 volumio-audio go-librespot[32089]: time="2026-01-13T22:44:40+01:00" level=info msg="zeroconf server listening on port 39183" Jan 13 22:44:40 volumio-audio volumio[26065]: info: touch_display: X display number found: 0 Jan 13 22:44:40 volumio-audio volumio[26065]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jan 13 22:44:40 volumio-audio go-librespot[32089]: time="2026-01-13T22:44:40+01:00" level=debug msg="obtained new client token: AAB8x401G+i5toMZH2fq8CmgA8aaN+b4ZrOc04Axy2wguwX+C8MvVCjJjK5JPwQNOEZPwM0aNsewIpO8VSDepdcr8QTpmfWrn84B5EeLK57zb/lQT/sw7rG1d08haN4Pnt0vqvhGNJDOD1RFlDnY8JeEWqvWPLNyjD5vxn098zR77HIr3Ntv3H0xS44pkUj6K/XNKmthskcc0ukCz9aQfCDQcrH7dZS/iAIM90BHtTFhIn5StZG0m+4=" Jan 13 22:44:40 volumio-audio volumio[26065]: info: touch_display: X display number found: 0 Jan 13 22:44:40 volumio-audio volumio[26065]: info: CoreCommandRouter::volumioGetState Jan 13 22:44:40 volumio-audio volumio[26065]: info: CorePlayQueue::getTrack 0 Jan 13 22:44:40 volumio-audio volumio[26065]: info: touch_display: Setting screensaver timeout to 120 seconds. Jan 13 22:44:40 volumio-audio go-librespot[32089]: time="2026-01-13T22:44:40+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jan 13 22:44:40 volumio-audio go-librespot[32089]: time="2026-01-13T22:44:40+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: read tcp 192.168.8.126:51366->34.158.1.133:4070: read: connection reset by peer" Jan 13 22:44:40 volumio-audio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 22:44:40 volumio-audio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 22:44:41 volumio-audio sudo[1054]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 13 22:44:41 volumio-audio sudo[1054]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:41 volumio-audio sudo[1054]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:41 volumio-audio sudo[1064]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 13 22:44:41 volumio-audio sudo[1064]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:41 volumio-audio sudo[1064]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:41 volumio-audio sudo[1081]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 13 22:44:41 volumio-audio sudo[1081]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:41 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 13 22:44:41 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 13 22:44:41 volumio-audio volumio[26065]: info: Discovery: Getting this device information Jan 13 22:44:41 volumio-audio volumio[26065]: info: CoreCommandRouter::volumioGetState Jan 13 22:44:41 volumio-audio volumio[26065]: info: CorePlayQueue::getTrack 0 Jan 13 22:44:41 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 13 22:44:41 volumio-audio sudo[1081]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:41 volumio-audio volumio[26065]: info: Upmpdcli Daemon Started Jan 13 22:44:42 volumio-audio sudo[1757]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 13 22:44:42 volumio-audio sudo[1757]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:42 volumio-audio sudo[1776]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 13 22:44:42 volumio-audio sudo[1776]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:42 volumio-audio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Jan 13 22:44:42 volumio-audio systemd[1]: mpd_monitor.service: Deactivated successfully. Jan 13 22:44:42 volumio-audio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Jan 13 22:44:42 volumio-audio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Jan 13 22:44:42 volumio-audio sudo[1757]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:42 volumio-audio mpd_monitor.sh[1794]: MPD Monitor Service: Starting MPD Monitor Service Jan 13 22:44:42 volumio-audio volumio[26065]: info: Successfully started MPD Monitor Jan 13 22:44:42 volumio-audio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Jan 13 22:44:42 volumio-audio systemd[1]: mpd_monitor.service: Deactivated successfully. Jan 13 22:44:42 volumio-audio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Jan 13 22:44:42 volumio-audio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Jan 13 22:44:42 volumio-audio sudo[1776]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:42 volumio-audio mpd_monitor.sh[1842]: MPD Monitor Service: Starting MPD Monitor Service Jan 13 22:44:42 volumio-audio volumio[26065]: info: Successfully started MPD Monitor Jan 13 22:44:42 volumio-audio volumio[26065]: info: go-librespot daemon successfully initialized Jan 13 22:44:42 volumio-audio volumio[26065]: verbose: New Socket.io Connection to 192.168.8.126 from 192.168.8.124 UA: Mozilla/5.0 (Linux; Android 14; SM-M135F Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.192 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Jan 13 22:44:43 volumio-audio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Jan 13 22:44:43 volumio-audio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 13 22:44:43 volumio-audio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 13 22:44:43 volumio-audio go-librespot[2261]: go-librespot daemon starting... Jan 13 22:44:43 volumio-audio go-librespot[2317]: time="2026-01-13T22:44:43+01:00" level=info msg="running go-librespot 0.4.0" Jan 13 22:44:43 volumio-audio go-librespot[2317]: time="2026-01-13T22:44:43+01:00" level=debug msg="app state loaded" Jan 13 22:44:43 volumio-audio go-librespot[2317]: time="2026-01-13T22:44:43+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 13 22:44:43 volumio-audio go-librespot[2317]: time="2026-01-13T22:44:43+01: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]" Jan 13 22:44:43 volumio-audio go-librespot[2317]: time="2026-01-13T22:44:43+01: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]" Jan 13 22:44:43 volumio-audio go-librespot[2317]: time="2026-01-13T22:44:43+01: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]" Jan 13 22:44:43 volumio-audio go-librespot[2317]: time="2026-01-13T22:44:43+01:00" level=info msg="zeroconf server listening on port 46469" Jan 13 22:44:43 volumio-audio go-librespot[2317]: time="2026-01-13T22:44:43+01:00" level=debug msg="obtained new client token: AADYWS2bnkN1q61pXnLq4guFIQH2aQfopNePYwpiXZn2lGbCsX/ShoGEfLYmfciCDGaj41JUmVme3b+EklgIwPhTWIvFMq4PJpCReBzHcRyg/qtAQcLFGOc+iDof0cJzl1BAaWeApFvX8PHMAMVYwzmuVUsEl9Sa798gIX6joxJV22ul+xVPU65Ww+jJoHF86Fo7NGX/alOa3HKXaQJUaYIFfvQq3UsiRsXELkwKuBBQ8EuvuN31ieE7VQ==" Jan 13 22:44:43 volumio-audio go-librespot[2317]: time="2026-01-13T22:44:43+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jan 13 22:44:43 volumio-audio go-librespot[2317]: time="2026-01-13T22:44:43+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: read tcp 192.168.8.126:51376->34.158.1.133:4070: read: connection reset by peer" Jan 13 22:44:43 volumio-audio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 22:44:43 volumio-audio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 22:44:44 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 13 22:44:44 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 13 22:44:44 volumio-audio volumio[26065]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 22:44:44 volumio-audio volumio[26065]: info: touch_display: About to detect HDMI ports... Jan 13 22:44:44 volumio-audio volumio[26065]: info: touch_display: No HDMI ports detected. Jan 13 22:44:44 volumio-audio volumio[26065]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jan 13 22:44:45 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 13 22:44:45 volumio-audio volumio[26065]: info: CoreCommandRouter::volumioGetVisibleSources Jan 13 22:44:45 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 22:44:45 volumio-audio volumio[26065]: info: CoreCommandRouter::volumioGetState Jan 13 22:44:45 volumio-audio volumio[26065]: info: CorePlayQueue::getTrack 0 Jan 13 22:44:45 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 13 22:44:45 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 13 22:44:45 volumio-audio volumio[26065]: info: Received Get System Info Jan 13 22:44:45 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 13 22:44:45 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 13 22:44:45 volumio-audio volumio[26065]: info: Discovery: Getting this device information Jan 13 22:44:45 volumio-audio volumio[26065]: info: CoreCommandRouter::volumioGetState Jan 13 22:44:45 volumio-audio volumio[26065]: info: CorePlayQueue::getTrack 0 Jan 13 22:44:45 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 13 22:44:45 volumio-audio volumio[26065]: info: CoreCommandRouter::volumioGetState Jan 13 22:44:45 volumio-audio volumio[26065]: info: CorePlayQueue::getTrack 0 Jan 13 22:44:45 volumio-audio volumio[26065]: info: Listing playlists Jan 13 22:44:45 volumio-audio volumio[26065]: info: Initializing connection to go-librespot Websocket Jan 13 22:44:45 volumio-audio volumio[26065]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 22:44:46 volumio-audio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Jan 13 22:44:46 volumio-audio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 13 22:44:46 volumio-audio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 13 22:44:46 volumio-audio go-librespot[4999]: go-librespot daemon starting... Jan 13 22:44:46 volumio-audio go-librespot[5050]: time="2026-01-13T22:44:46+01:00" level=info msg="running go-librespot 0.4.0" Jan 13 22:44:46 volumio-audio go-librespot[5050]: time="2026-01-13T22:44:46+01:00" level=debug msg="app state loaded" Jan 13 22:44:46 volumio-audio go-librespot[5050]: time="2026-01-13T22:44:46+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 13 22:44:47 volumio-audio go-librespot[5050]: time="2026-01-13T22:44:47+01: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-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 13 22:44:47 volumio-audio go-librespot[5050]: time="2026-01-13T22:44:47+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jan 13 22:44:47 volumio-audio go-librespot[5050]: time="2026-01-13T22:44:47+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jan 13 22:44:47 volumio-audio go-librespot[5050]: time="2026-01-13T22:44:47+01:00" level=info msg="zeroconf server listening on port 33755" Jan 13 22:44:47 volumio-audio sudo[5247]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 13 22:44:47 volumio-audio sudo[5247]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:47 volumio-audio sudo[5247]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:47 volumio-audio sudo[5253]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 13 22:44:47 volumio-audio sudo[5253]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:47 volumio-audio sudo[5253]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:47 volumio-audio go-librespot[5050]: time="2026-01-13T22:44:47+01:00" level=debug msg="obtained new client token: AABUV8qexo0yB2RyS68Pf2zDzJDHY1lOhKWfsQl0YIa0GWlP1J6X1Ep/XtDynh49C/3T6y2H2BsYekQ3KXWUt/UBYk5zEa2XibDTRFyF2e/V9AylRVcNdawhNadEM4NtqNMuApId7i+zcgfi45eRGcyYiIJFI5j8opeecQrMJJBVxqpWXATkgBAfzp1V1c9dko3sCo6jXMNTB0Yqve3Rje4csdT9/PRs48TPtekA8U/2HAeXFS5es+0=" Jan 13 22:44:47 volumio-audio go-librespot[5050]: time="2026-01-13T22:44:47+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jan 13 22:44:47 volumio-audio go-librespot[5050]: time="2026-01-13T22:44:47+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: read tcp 192.168.8.126:51380->34.158.1.133:4070: read: connection reset by peer" Jan 13 22:44:47 volumio-audio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 22:44:47 volumio-audio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 22:44:47 volumio-audio volumio[26065]: verbose: New Socket.io Connection to 192.168.8.126 from 192.168.8.124 UA: Mozilla/5.0 (Linux; Android 14; SM-M135F Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.192 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Jan 13 22:44:48 volumio-audio volumio[26065]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jan 13 22:44:48 volumio-audio volumio[26065]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jan 13 22:44:48 volumio-audio volumio[26065]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jan 13 22:44:48 volumio-audio volumio[26065]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jan 13 22:44:48 volumio-audio volumio[26065]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jan 13 22:44:48 volumio-audio volumio[26065]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jan 13 22:44:48 volumio-audio volumio[26065]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jan 13 22:44:48 volumio-audio volumio[26065]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jan 13 22:44:48 volumio-audio volumio[26065]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jan 13 22:44:48 volumio-audio volumio[26065]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jan 13 22:44:48 volumio-audio volumio[26065]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jan 13 22:44:48 volumio-audio volumio[26065]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jan 13 22:44:48 volumio-audio volumio[26065]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jan 13 22:44:48 volumio-audio volumio[26065]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jan 13 22:44:48 volumio-audio volumio[26065]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jan 13 22:44:48 volumio-audio volumio[26065]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jan 13 22:44:48 volumio-audio volumio[26065]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jan 13 22:44:48 volumio-audio volumio[26065]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jan 13 22:44:48 volumio-audio volumio[26065]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jan 13 22:44:48 volumio-audio volumio[26065]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jan 13 22:44:48 volumio-audio volumio[26065]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jan 13 22:44:48 volumio-audio volumio[26065]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jan 13 22:44:48 volumio-audio volumio[26065]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jan 13 22:44:48 volumio-audio volumio[26065]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jan 13 22:44:48 volumio-audio volumio[26065]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jan 13 22:44:48 volumio-audio volumio[26065]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jan 13 22:44:48 volumio-audio volumio[26065]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jan 13 22:44:48 volumio-audio volumio[26065]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jan 13 22:44:48 volumio-audio volumio[26065]: info: Adding plugin bluetooth to MyMusic Plugins Jan 13 22:44:48 volumio-audio volumio[26065]: info: Adding plugin multiroom to MyMusic Plugins Jan 13 22:44:48 volumio-audio volumio[26065]: info: Adding plugin metavolumio to MyMusic Plugins Jan 13 22:44:48 volumio-audio volumio[26065]: info: Adding plugin cd_controller to MyMusic Plugins Jan 13 22:44:48 volumio-audio volumio[26065]: info: Adding plugin qobuzconnect to MyMusic Plugins Jan 13 22:44:48 volumio-audio volumio[26065]: info: Adding plugin smart_inputs to MyMusic Plugins Jan 13 22:44:48 volumio-audio volumio[26065]: info: Adding plugin tidalconnect to MyMusic Plugins Jan 13 22:44:48 volumio-audio volumio[26065]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jan 13 22:44:48 volumio-audio volumio[26065]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jan 13 22:44:48 volumio-audio volumio[26065]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jan 13 22:44:48 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:44:48 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:44:48 volumio-audio volumio[26065]: info: Starting MyVolumio Remote Streaming Endpoints Jan 13 22:44:48 volumio-audio volumio[26065]: info: MyVolumio login type: Token Jan 13 22:44:49 volumio-audio volumio[26065]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jan 13 22:44:49 volumio-audio volumio[26065]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jan 13 22:44:49 volumio-audio volumio[26065]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jan 13 22:44:49 volumio-audio volumio[26065]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jan 13 22:44:49 volumio-audio volumio[26065]: info: Streaming services startup Jan 13 22:44:49 volumio-audio volumio[26065]: info: Starting Streaming Daemon Jan 13 22:44:49 volumio-audio sudo[6988]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 13 22:44:49 volumio-audio sudo[6988]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:49 volumio-audio volumio[26065]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jan 13 22:44:49 volumio-audio sudo[6988]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:49 volumio-audio volumio[26065]: info: Initializing connection to go-librespot Websocket Jan 13 22:44:49 volumio-audio sudo[7044]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 13 22:44:49 volumio-audio sudo[7044]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:49 volumio-audio volumio[26065]: error: Cannot start Volumio Streaming Daemon Jan 13 22:44:49 volumio-audio volumio[26065]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 13 22:44:49 volumio-audio volumio[26065]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 13 22:44:49 volumio-audio sudo[7044]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:49 volumio-audio sudo[7049]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 13 22:44:49 volumio-audio sudo[7049]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:49 volumio-audio sudo[7049]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:49 volumio-audio volumio[26065]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 22:44:49 volumio-audio volumio[26065]: verbose: New Socket.io Connection to 192.168.8.126 from 192.168.8.124 UA: Mozilla/5.0 (Linux; Android 14; SM-M135F Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.192 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Jan 13 22:44:50 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 13 22:44:50 volumio-audio volumio[26065]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jan 13 22:44:50 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 13 22:44:50 volumio-audio volumio[26065]: info: CoreCommandRouter::volumioGetVisibleSources Jan 13 22:44:50 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 22:44:50 volumio-audio volumio[26065]: info: CoreCommandRouter::volumioGetState Jan 13 22:44:50 volumio-audio volumio[26065]: info: CorePlayQueue::getTrack 0 Jan 13 22:44:50 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 13 22:44:50 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 13 22:44:50 volumio-audio volumio[26065]: info: Received Get System Info Jan 13 22:44:50 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 13 22:44:50 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 13 22:44:50 volumio-audio volumio[26065]: info: Discovery: Getting this device information Jan 13 22:44:50 volumio-audio volumio[26065]: info: CoreCommandRouter::volumioGetState Jan 13 22:44:50 volumio-audio volumio[26065]: info: CorePlayQueue::getTrack 0 Jan 13 22:44:50 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 13 22:44:50 volumio-audio volumio[26065]: info: CoreCommandRouter::volumioGetState Jan 13 22:44:50 volumio-audio volumio[26065]: info: CorePlayQueue::getTrack 0 Jan 13 22:44:50 volumio-audio volumio[26065]: info: Listing playlists Jan 13 22:44:50 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 13 22:44:50 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 13 22:44:50 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 13 22:44:50 volumio-audio volumio[26065]: info: Received Get System Info Jan 13 22:44:50 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 13 22:44:50 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 13 22:44:50 volumio-audio volumio[26065]: info: Discovery: Getting this device information Jan 13 22:44:50 volumio-audio volumio[26065]: info: CoreCommandRouter::volumioGetState Jan 13 22:44:50 volumio-audio volumio[26065]: info: CorePlayQueue::getTrack 0 Jan 13 22:44:50 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 13 22:44:50 volumio-audio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Jan 13 22:44:50 volumio-audio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 13 22:44:50 volumio-audio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 13 22:44:50 volumio-audio go-librespot[7640]: go-librespot daemon starting... Jan 13 22:44:50 volumio-audio go-librespot[7688]: time="2026-01-13T22:44:50+01:00" level=info msg="running go-librespot 0.4.0" Jan 13 22:44:50 volumio-audio go-librespot[7688]: time="2026-01-13T22:44:50+01:00" level=debug msg="app state loaded" Jan 13 22:44:50 volumio-audio go-librespot[7688]: time="2026-01-13T22:44:50+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 13 22:44:50 volumio-audio volumio[26065]: info: MyVolumio token set successfully Jan 13 22:44:50 volumio-audio volumio[26065]: info: MYVOLUMIO: Adding device Jan 13 22:44:50 volumio-audio volumio[26065]: info: MYVOLUMIO: Evaluating Server Jan 13 22:44:50 volumio-audio go-librespot[7688]: time="2026-01-13T22:44:50+01: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]" Jan 13 22:44:50 volumio-audio go-librespot[7688]: time="2026-01-13T22:44:50+01: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]" Jan 13 22:44:50 volumio-audio go-librespot[7688]: time="2026-01-13T22:44:50+01: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]" Jan 13 22:44:50 volumio-audio go-librespot[7688]: time="2026-01-13T22:44:50+01:00" level=info msg="zeroconf server listening on port 44871" Jan 13 22:44:51 volumio-audio go-librespot[7688]: time="2026-01-13T22:44:51+01:00" level=debug msg="obtained new client token: AADwLNqmfIxcAGoKU7nKweNE7W3bvNfA+ldUGUR7P0CF1re5AaGp1KY3XGEGcmW6CnWPoX5uj0JeWiw8dgHq+Nl6NQclxNMgc0wV7KvQTw4QL5T5svIyE2gdNGmdS0mUwtVBuEBvqeKW+u/1pCsl0i97kUj5QK0Y9HMTfIu9Wp1GWJodg903tkFRdtxDc8QWcvoEifiL/tNC4sKqd6Zjk6zMUSc0lgUO5NpGsh9giF9abJnBsO8HVY526A==" Jan 13 22:44:51 volumio-audio go-librespot[7688]: time="2026-01-13T22:44:51+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jan 13 22:44:51 volumio-audio volumio[26065]: info: MyVolumio status changed Jan 13 22:44:51 volumio-audio volumio[26065]: info: Streaming services startup Jan 13 22:44:51 volumio-audio volumio[26065]: info: Starting Streaming Daemon Jan 13 22:44:51 volumio-audio volumio[26065]: info: Removing browser output: myVolumio user plan is not superstar Jan 13 22:44:51 volumio-audio volumio[26065]: info: Removing audio output: Jan 13 22:44:51 volumio-audio volumio[26065]: info: Stoppping Tunnel 1 Jan 13 22:44:51 volumio-audio sudo[8070]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 13 22:44:51 volumio-audio sudo[8070]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:51 volumio-audio sudo[8078]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jan 13 22:44:51 volumio-audio sudo[8078]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:44:51 volumio-audio sudo[8070]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:51 volumio-audio volumio[26065]: error: Cannot start Volumio Streaming Daemon Jan 13 22:44:51 volumio-audio volumio[26065]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 13 22:44:51 volumio-audio volumio[26065]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 13 22:44:51 volumio-audio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 13 22:44:51 volumio-audio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 13 22:44:51 volumio-audio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 13 22:44:51 volumio-audio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 13 22:44:51 volumio-audio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 13 22:44:51 volumio-audio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 13 22:44:51 volumio-audio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 13 22:44:51 volumio-audio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 13 22:44:51 volumio-audio sudo[8078]: pam_unix(sudo:session): session closed for user root Jan 13 22:44:51 volumio-audio volumio[26065]: info: Remote SSH Stopped Jan 13 22:44:51 volumio-audio volumio[26065]: info: Setting Geolocation for MyVolumio to eu4 Jan 13 22:44:51 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:44:51 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:44:51 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:44:51 volumio-audio volumio[26065]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jan 13 22:44:52 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jan 13 22:44:52 volumio-audio volumio[26065]: info: Updating MyVolumio device info Jan 13 22:44:52 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:44:52 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:44:52 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:44:52 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 13 22:44:52 volumio-audio volumio[26065]: info: Received Get System Info Jan 13 22:44:52 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 13 22:44:52 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 13 22:44:52 volumio-audio volumio[26065]: info: Discovery: Getting this device information Jan 13 22:44:52 volumio-audio volumio[26065]: info: CoreCommandRouter::volumioGetState Jan 13 22:44:52 volumio-audio volumio[26065]: info: CorePlayQueue::getTrack 0 Jan 13 22:44:52 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 13 22:44:52 volumio-audio volumio[26065]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jan 13 22:44:52 volumio-audio volumio[26065]: info: Initializing connection to go-librespot Websocket Jan 13 22:44:52 volumio-audio go-librespot[7688]: time="2026-01-13T22:44:52+01:00" level=debug msg="new websocket client" Jan 13 22:44:52 volumio-audio volumio[26065]: info: Connection to go-librespot Websocket established Jan 13 22:44:54 volumio-audio volumio[26065]: info: CoreCommandRouter::volumioPlay Jan 13 22:44:54 volumio-audio volumio[26065]: info: CoreStateMachine::play index undefined Jan 13 22:44:54 volumio-audio volumio[26065]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 13 22:44:54 volumio-audio volumio[26065]: info: CorePlayQueue::getTrack 0 Jan 13 22:44:54 volumio-audio volumio[26065]: info: CoreStateMachine::startPlaybackTimer Jan 13 22:44:54 volumio-audio volumio[26065]: info: CorePlayQueue::getTrack 0 Jan 13 22:44:54 volumio-audio volumio[26065]: verbose: ControllerMpd::clearAddPlayTracks USB/Nowy/CDrip/Nickelback/[2005] Nickelback - All the Right Reasons/Nickelback - All the Right Reasons - 01 - Follow You Home.flac Jan 13 22:44:54 volumio-audio volumio[26065]: verbose: ControllerMpd::sendMpdCommand stop Jan 13 22:44:54 volumio-audio volumio[26065]: info: sendMpdCommand stop took 1 milliseconds Jan 13 22:44:54 volumio-audio volumio[26065]: verbose: ControllerMpd::sendMpdCommand clear Jan 13 22:44:54 volumio-audio volumio[26065]: info: Jan 13 22:44:54 volumio-audio volumio[26065]: ---------------------------- MPD announces system playlist update Jan 13 22:44:54 volumio-audio volumio[26065]: info: Ignoring MPD Status Update Jan 13 22:44:54 volumio-audio volumio[26065]: info: sendMpdCommand clear took 3 milliseconds Jan 13 22:44:54 volumio-audio volumio[26065]: verbose: ControllerMpd::sendMpdCommand add "USB/Nowy/CDrip/Nickelback/[2005] Nickelback - All the Right Reasons/Nickelback - All the Right Reasons - 01 - Follow You Home.flac" Jan 13 22:44:54 volumio-audio volumio[26065]: info: Jan 13 22:44:54 volumio-audio volumio[26065]: ---------------------------- MPD announces system playlist update Jan 13 22:44:54 volumio-audio volumio[26065]: info: Ignoring MPD Status Update Jan 13 22:44:54 volumio-audio volumio[26065]: info: Jan 13 22:44:54 volumio-audio volumio[26065]: ---------------------------- MPD announces system playlist update Jan 13 22:44:54 volumio-audio volumio[26065]: info: Ignoring MPD Status Update Jan 13 22:44:54 volumio-audio volumio[26065]: error: updateQueue error: null Jan 13 22:44:54 volumio-audio volumio[26065]: error: updateQueue error: null Jan 13 22:44:54 volumio-audio volumio[26065]: info: ------------------------------ 7ms Jan 13 22:44:54 volumio-audio volumio[26065]: info: ------------------------------ 4ms Jan 13 22:44:54 volumio-audio volumio[26065]: error: updateQueue error: null Jan 13 22:44:54 volumio-audio volumio[26065]: info: ------------------------------ 4ms Jan 13 22:44:55 volumio-audio volumio[26065]: info: MYVOLUMIO: Adding device Jan 13 22:44:55 volumio-audio volumio[26065]: info: MYVOLUMIO: Evaluating Server Jan 13 22:44:55 volumio-audio volumio[26065]: info: Getting Spotify volume Jan 13 22:44:56 volumio-audio volumio[26065]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Jan 13 22:44:56 volumio-audio volumio[26065]: info: CoreCommandRouter::volumioGetState Jan 13 22:44:56 volumio-audio volumio[26065]: info: CorePlayQueue::getTrack 0 Jan 13 22:44:56 volumio-audio volumio[26065]: info: Setting Geolocation for MyVolumio to eu11 Jan 13 22:44:56 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:44:56 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:44:56 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:44:56 volumio-audio volumio[26065]: info: CoreCommandRouter::volumioPlay Jan 13 22:44:56 volumio-audio volumio[26065]: info: CoreStateMachine::play index undefined Jan 13 22:44:56 volumio-audio volumio[26065]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 13 22:44:56 volumio-audio volumio[26065]: info: CorePlayQueue::getTrack 0 Jan 13 22:44:56 volumio-audio volumio[26065]: info: CoreStateMachine::startPlaybackTimer Jan 13 22:44:56 volumio-audio volumio[26065]: info: CorePlayQueue::getTrack 0 Jan 13 22:44:56 volumio-audio volumio[26065]: verbose: ControllerMpd::clearAddPlayTracks USB/Nowy/CDrip/Nickelback/[2005] Nickelback - All the Right Reasons/Nickelback - All the Right Reasons - 01 - Follow You Home.flac Jan 13 22:44:56 volumio-audio volumio[26065]: verbose: ControllerMpd::sendMpdCommand stop Jan 13 22:44:56 volumio-audio volumio[26065]: info: sendMpdCommand stop took 1 milliseconds Jan 13 22:44:56 volumio-audio volumio[26065]: verbose: ControllerMpd::sendMpdCommand clear Jan 13 22:44:56 volumio-audio volumio[26065]: info: Jan 13 22:44:56 volumio-audio volumio[26065]: ---------------------------- MPD announces system playlist update Jan 13 22:44:56 volumio-audio volumio[26065]: info: Ignoring MPD Status Update Jan 13 22:44:56 volumio-audio volumio[26065]: info: sendMpdCommand clear took 2 milliseconds Jan 13 22:44:56 volumio-audio volumio[26065]: verbose: ControllerMpd::sendMpdCommand add "USB/Nowy/CDrip/Nickelback/[2005] Nickelback - All the Right Reasons/Nickelback - All the Right Reasons - 01 - Follow You Home.flac" Jan 13 22:44:56 volumio-audio volumio[26065]: info: Jan 13 22:44:56 volumio-audio volumio[26065]: ---------------------------- MPD announces system playlist update Jan 13 22:44:56 volumio-audio volumio[26065]: info: Ignoring MPD Status Update Jan 13 22:44:56 volumio-audio volumio[26065]: info: Jan 13 22:44:56 volumio-audio volumio[26065]: ---------------------------- MPD announces system playlist update Jan 13 22:44:56 volumio-audio volumio[26065]: info: Ignoring MPD Status Update Jan 13 22:44:56 volumio-audio volumio[26065]: error: updateQueue error: null Jan 13 22:44:56 volumio-audio volumio[26065]: error: updateQueue error: null Jan 13 22:44:56 volumio-audio volumio[26065]: error: updateQueue error: null Jan 13 22:44:56 volumio-audio volumio[26065]: info: ------------------------------ 5ms Jan 13 22:44:56 volumio-audio volumio[26065]: info: ------------------------------ 3ms Jan 13 22:44:56 volumio-audio volumio[26065]: info: ------------------------------ 2ms Jan 13 22:44:56 volumio-audio volumio[26065]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jan 13 22:44:57 volumio-audio volumio[26065]: info: Updating MyVolumio device info Jan 13 22:44:57 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:44:57 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:44:57 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:44:57 volumio-audio volumio[26065]: info: CoreCommandRouter::volumioGetQueue Jan 13 22:44:57 volumio-audio volumio[26065]: info: CoreStateMachine::getQueue Jan 13 22:44:57 volumio-audio volumio[26065]: info: CorePlayQueue::getQueue Jan 13 22:44:57 volumio-audio volumio[26065]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jan 13 22:44:59 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 13 22:45:03 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Jan 13 22:45:03 volumio-audio volumio[26065]: info: In handleBrowseUri, curUri=spotify Jan 13 22:45:03 volumio-audio volumio[26065]: info: Preload queue cleared Jan 13 22:45:03 volumio-audio volumio[26065]: info: Preload queue cleared Jan 13 22:45:03 volumio-audio volumio[26065]: info: Preload queue cleared Jan 13 22:45:03 volumio-audio volumio[26065]: info: Preload queue cleared Jan 13 22:45:03 volumio-audio volumio[26065]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 13 22:45:03 volumio-audio volumio[26065]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Jan 13 22:45:04 volumio-audio volumio[26065]: info: CoreCommandRouter::volumioGetState Jan 13 22:45:04 volumio-audio volumio[26065]: info: CorePlayQueue::getTrack 0 Jan 13 22:45:06 volumio-audio go-librespot[7688]: time="2026-01-13T22:45:06+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: read tcp 192.168.8.126:52432->34.158.1.133:4070: read: connection reset by peer" Jan 13 22:45:06 volumio-audio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 22:45:06 volumio-audio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 22:45:06 volumio-audio volumio[26065]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 22:45:06 volumio-audio volumio[26065]: Error: socket hang up Jan 13 22:45:06 volumio-audio volumio[26065]: at connResetException (node:internal/errors:720:14) Jan 13 22:45:06 volumio-audio volumio[26065]: at Socket.socketOnEnd (node:_http_client:519:23) Jan 13 22:45:06 volumio-audio volumio[26065]: at Socket.emit (node:events:526:35) Jan 13 22:45:06 volumio-audio volumio[26065]: at endReadableNT (node:internal/streams/readable:1376:12) Jan 13 22:45:06 volumio-audio volumio[26065]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Jan 13 22:45:06 volumio-audio volumio[26065]: code: 'ECONNRESET', Jan 13 22:45:06 volumio-audio volumio[26065]: response: undefined Jan 13 22:45:06 volumio-audio volumio[26065]: } Jan 13 22:45:06 volumio-audio volumio[26065]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 22:45:06 volumio-audio sudo[20513]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-13 22:44' Jan 13 22:45:06 volumio-audio sudo[20513]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:45:06 volumio-audio sudo[20513]: pam_unix(sudo:session): session closed for user root Jan 13 22:45:07 volumio-audio volumio-remote-updater[687]: [2026-01-13 22:45:07] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jan 13 22:45:07 volumio-audio volumio-remote-updater[687]: [2026-01-13 22:45:07] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jan 13 22:45:07 volumio-audio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jan 13 22:45:07 volumio-audio systemd[1]: volumio.service: Failed with result 'exit-code'. Jan 13 22:45:07 volumio-audio systemd[1]: volumio.service: Consumed 23.543s CPU time. Jan 13 22:45:07 volumio-audio systemd[1]: Started dynamicswap.service - dynamicswap service. Jan 13 22:45:07 volumio-audio systemd[1]: dynamicswap.service: Deactivated successfully. Jan 13 22:45:07 volumio-audio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 4. Jan 13 22:45:07 volumio-audio systemd[1]: Started dynamicswap.service - dynamicswap service. Jan 13 22:45:07 volumio-audio systemd[1]: Stopped volumio.service - Volumio Backend Module. Jan 13 22:45:07 volumio-audio systemd[1]: volumio.service: Consumed 23.543s CPU time. Jan 13 22:45:07 volumio-audio systemd[1]: Started volumio.service - Volumio Backend Module. Jan 13 22:45:07 volumio-audio systemd[1]: dynamicswap.service: Deactivated successfully. Jan 13 22:45:09 volumio-audio volumio[21131]: info: ------------------------------------------- Jan 13 22:45:09 volumio-audio volumio[21131]: info: ----- Volumio3 ---- Jan 13 22:45:09 volumio-audio volumio[21131]: info: ------------------------------------------- Jan 13 22:45:09 volumio-audio volumio[21131]: info: ----- System startup ---- Jan 13 22:45:09 volumio-audio volumio[21131]: info: ------------------------------------------- Jan 13 22:45:09 volumio-audio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Jan 13 22:45:09 volumio-audio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 13 22:45:09 volumio-audio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 13 22:45:09 volumio-audio go-librespot[22182]: go-librespot daemon starting... Jan 13 22:45:09 volumio-audio go-librespot[22213]: time="2026-01-13T22:45:09+01:00" level=info msg="running go-librespot 0.4.0" Jan 13 22:45:09 volumio-audio go-librespot[22213]: time="2026-01-13T22:45:09+01:00" level=debug msg="app state loaded" Jan 13 22:45:09 volumio-audio go-librespot[22213]: time="2026-01-13T22:45:09+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 13 22:45:09 volumio-audio volumio[21131]: info: MYVOLUMIO Environment detected Jan 13 22:45:09 volumio-audio go-librespot[22213]: time="2026-01-13T22:45:09+01: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]" Jan 13 22:45:09 volumio-audio go-librespot[22213]: time="2026-01-13T22:45:09+01: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]" Jan 13 22:45:09 volumio-audio go-librespot[22213]: time="2026-01-13T22:45:09+01: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]" Jan 13 22:45:09 volumio-audio go-librespot[22213]: time="2026-01-13T22:45:09+01:00" level=info msg="zeroconf server listening on port 41979" Jan 13 22:45:09 volumio-audio volumio[21131]: info: Plugin folders cleanup Jan 13 22:45:09 volumio-audio volumio[21131]: info: Scanning into folder /volumio/app/plugins/ Jan 13 22:45:09 volumio-audio volumio[21131]: info: Scanning category audio_interface Jan 13 22:45:09 volumio-audio volumio[21131]: info: Scanning category miscellanea Jan 13 22:45:09 volumio-audio volumio[21131]: info: Scanning category music_service Jan 13 22:45:09 volumio-audio volumio[21131]: info: Scanning category plugins.json Jan 13 22:45:09 volumio-audio volumio[21131]: info: Scanning category system_controller Jan 13 22:45:09 volumio-audio volumio[21131]: info: Scanning category user_interface Jan 13 22:45:09 volumio-audio volumio[21131]: info: Scanning into folder /data/plugins/ Jan 13 22:45:09 volumio-audio volumio[21131]: info: Scanning category music_service Jan 13 22:45:09 volumio-audio volumio[21131]: info: Scanning category user_interface Jan 13 22:45:09 volumio-audio volumio[21131]: info: Plugin folders cleanup completed Jan 13 22:45:09 volumio-audio volumio[21131]: info: ------------------------------------------- Jan 13 22:45:09 volumio-audio volumio[21131]: info: ----- Core plugins startup ---- Jan 13 22:45:09 volumio-audio volumio[21131]: info: ------------------------------------------- Jan 13 22:45:09 volumio-audio volumio[21131]: info: Loading plugins from folder /volumio/app/plugins/ Jan 13 22:45:09 volumio-audio volumio[21131]: info: Adding plugin upnp to MyMusic Plugins Jan 13 22:45:09 volumio-audio volumio[21131]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 13 22:45:09 volumio-audio volumio[21131]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 13 22:45:09 volumio-audio volumio[21131]: info: Loading plugins from folder /data/plugins/ Jan 13 22:45:09 volumio-audio volumio[21131]: info: Loading plugin "system"... Jan 13 22:45:09 volumio-audio volumio[21131]: info: Loading plugin "appearance"... Jan 13 22:45:09 volumio-audio go-librespot[22213]: time="2026-01-13T22:45:09+01:00" level=debug msg="obtained new client token: AABZyajn6eb4cUPaq2WDCJAllMxSRi92prHoeZm4PWERDiIvRTWV3NPq24VPeP63RdhedYuB3JrZL8r2x59sltj8PW7vjFcgaIYMPFwO3yxMwu0yoZCPwDJ3JgVywa4b0n74QBhxElAXT8botRaApMsa5zSSiLfzSZ7w4M9CUJy+TZHfpaL1w7uLw1oBvp7qjWuPp/9pjBQHOYuYsG9BE+13DMzNm4XQ0f1t1lUtf46Xz/03U4KNzkhwLA==" Jan 13 22:45:10 volumio-audio volumio[21131]: info: Loading plugin "network"... Jan 13 22:45:10 volumio-audio volumio[21131]: info: Refreshing Cached IP Addresses Jan 13 22:45:10 volumio-audio sudo[22879]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 13 22:45:10 volumio-audio sudo[22879]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:45:10 volumio-audio sudo[22884]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 13 22:45:10 volumio-audio sudo[22884]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:45:10 volumio-audio volumio[21131]: info: Loading plugin "services"... Jan 13 22:45:10 volumio-audio volumio[21131]: info: Loading plugin "alsa_controller"... Jan 13 22:45:10 volumio-audio sudo[22879]: pam_unix(sudo:session): session closed for user root Jan 13 22:45:10 volumio-audio sudo[22884]: pam_unix(sudo:session): session closed for user root Jan 13 22:45:10 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 22:45:10 volumio-audio volumio[21131]: info: Loading plugin "wizard"... Jan 13 22:45:10 volumio-audio volumio[21131]: info: Loading plugin "networkfs"... Jan 13 22:45:10 volumio-audio volumio[21131]: info: Starting Udev Watcher for removable devices Jan 13 22:45:10 volumio-audio volumio[21131]: info: Ignoring mount for partition: boot Jan 13 22:45:10 volumio-audio volumio[21131]: info: Ignoring mount for partition: volumio Jan 13 22:45:10 volumio-audio volumio[21131]: info: Ignoring mount for partition: volumio_data Jan 13 22:45:10 volumio-audio volumio[21131]: info: Mounting Device Nowy Jan 13 22:45:10 volumio-audio volumio[21131]: /bin/mkdir: cannot create directory ‘/mnt/USB/Nowy’: File exists Jan 13 22:45:10 volumio-audio volumio[21131]: error: Failed to create folder Error: Command failed: /bin/mkdir -m 777 "/mnt/USB/Nowy" Jan 13 22:45:10 volumio-audio volumio[21131]: /bin/mkdir: cannot create directory ‘/mnt/USB/Nowy’: File exists Jan 13 22:45:10 volumio-audio sudo[23008]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/Nowy -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Jan 13 22:45:10 volumio-audio sudo[23008]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:45:10 volumio-audio sudo[23008]: pam_unix(sudo:session): session closed for user root Jan 13 22:45:10 volumio-audio volumio[21131]: Mount is denied because the NTFS volume is already exclusively opened. Jan 13 22:45:10 volumio-audio volumio[21131]: The volume may be already mounted, or another software may use it which Jan 13 22:45:10 volumio-audio volumio[21131]: could be identified for example by the help of the 'fuser' command. Jan 13 22:45:10 volumio-audio volumio[21131]: error: Failed to mount Nowy: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/Nowy" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Jan 13 22:45:10 volumio-audio volumio[21131]: Mount is denied because the NTFS volume is already exclusively opened. Jan 13 22:45:10 volumio-audio volumio[21131]: The volume may be already mounted, or another software may use it which Jan 13 22:45:10 volumio-audio volumio[21131]: could be identified for example by the help of the 'fuser' command. Jan 13 22:45:10 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 13 22:45:10 volumio-audio volumio[21131]: info: Loading plugin "volumio_command_line_client"... Jan 13 22:45:10 volumio-audio volumio[21131]: info: Loading plugin "upnp"... Jan 13 22:45:10 volumio-audio volumio[21131]: info: [1768340710770] Starting Upmpd Daemon Jan 13 22:45:10 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 13 22:45:10 volumio-audio volumio[21131]: info: Loading plugin "my_music"... Jan 13 22:45:10 volumio-audio volumio[21131]: info: Loading plugin "mpd"... Jan 13 22:45:10 volumio-audio go-librespot[22213]: time="2026-01-13T22:45:10+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jan 13 22:45:10 volumio-audio go-librespot[22213]: time="2026-01-13T22:45:10+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: read tcp 192.168.8.126:47792->34.158.1.133:4070: read: connection reset by peer" Jan 13 22:45:10 volumio-audio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 22:45:10 volumio-audio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 22:45:11 volumio-audio volumio[21131]: info: Loading plugin "upnp_browser"... Jan 13 22:45:11 volumio-audio volumio[21131]: info: Starting UPNP Browser Jan 13 22:45:11 volumio-audio volumio[21131]: info: Loading plugin "alarm-clock"... Jan 13 22:45:12 volumio-audio volumio[21131]: info: Loading plugin "airplay_emulation"... Jan 13 22:45:12 volumio-audio volumio[21131]: info: Starting Shairport Sync Jan 13 22:45:12 volumio-audio volumio[21131]: info: Loading plugin "last_100"... Jan 13 22:45:12 volumio-audio volumio[21131]: info: Loading plugin "webradio"... Jan 13 22:45:12 volumio-audio volumio[21131]: info: Loading plugin "i2s_dacs"... Jan 13 22:45:12 volumio-audio volumio[21131]: info: I2S DAC not set, start Auto-detection Jan 13 22:45:12 volumio-audio volumio[21131]: info: Loading plugin "volumiodiscovery"... Jan 13 22:45:12 volumio-audio volumio[21131]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 13 22:45:12 volumio-audio volumio[21131]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 22:45:12 volumio-audio node[21131]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 13 22:45:12 volumio-audio volumio[21131]: *** WARNING *** For more information see Jan 13 22:45:12 volumio-audio volumio[21131]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 13 22:45:12 volumio-audio volumio[21131]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 22:45:12 volumio-audio volumio[21131]: *** WARNING *** For more information see Jan 13 22:45:12 volumio-audio node[21131]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 22:45:12 volumio-audio node[21131]: *** WARNING *** For more information see Jan 13 22:45:12 volumio-audio node[21131]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 13 22:45:12 volumio-audio node[21131]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 22:45:12 volumio-audio node[21131]: *** WARNING *** For more information see Jan 13 22:45:12 volumio-audio volumio[21131]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 13 22:45:12 volumio-audio volumio[21131]: info: Discovery: Started advertising with name: Volumio Audio Jan 13 22:45:12 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 13 22:45:12 volumio-audio volumio[21131]: info: Loading plugin "spop"... Jan 13 22:45:12 volumio-audio volumio-remote-updater[687]: [2026-01-13 22:45:12] [connect] Successful connection Jan 13 22:45:12 volumio-audio volumio[21131]: info: Loading plugin "outputs"... Jan 13 22:45:12 volumio-audio volumio[21131]: info: Loading plugin "albumart"... Jan 13 22:45:13 volumio-audio volumio[21131]: info: Plugin example_plugin is not enabled Jan 13 22:45:13 volumio-audio volumio[21131]: info: Loading plugin "inputs"... Jan 13 22:45:13 volumio-audio volumio[21131]: info: Loading plugin "updater_comm"... Jan 13 22:45:13 volumio-audio volumio[21131]: info: Plugin mpdemulation is not enabled Jan 13 22:45:13 volumio-audio volumio[21131]: info: Loading plugin "rest_api"... Jan 13 22:45:13 volumio-audio volumio[21131]: info: Loading plugin "websocket"... Jan 13 22:45:13 volumio-audio volumio[21131]: info: Starting Socket.io Server version 1.7.4 Jan 13 22:45:13 volumio-audio volumio[21131]: info: Loading plugin "minidlna"... Jan 13 22:45:13 volumio-audio volumio[21131]: info: Applying required configuration parameters for plugin minidlna Jan 13 22:45:13 volumio-audio volumio[21131]: info: Loading plugin "touch_display"... Jan 13 22:45:13 volumio-audio volumio[24385]: Forking 3 albumart workers Jan 13 22:45:13 volumio-audio volumio[21131]: info: Applying required configuration parameters for plugin touch_display Jan 13 22:45:13 volumio-audio volumio[21131]: info: Loading i18n strings for locale pl Jan 13 22:45:13 volumio-audio volumio[21131]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Jan 13 22:45:13 volumio-audio volumio[21131]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Jan 13 22:45:13 volumio-audio volumio[21131]: Updating browse sources language Jan 13 22:45:13 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 22:45:13 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 22:45:13 volumio-audio volumio[21131]: info: CoreCommandRouter::initPlayerControls Jan 13 22:45:13 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:45:13 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:45:13 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:45:13 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:45:13 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:45:13 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:45:13 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:45:13 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:45:13 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 22:45:13 volumio-audio volumio[21131]: Express server listening on port 3000 Jan 13 22:45:13 volumio-audio volumio[21131]: [Metrics] WebUI: 5s 75.97ms Jan 13 22:45:13 volumio-audio volumio[21131]: info: CoreStateMachine::resetVolumioState Jan 13 22:45:13 volumio-audio volumio[21131]: info: CoreStateMachine::getcurrentVolume Jan 13 22:45:13 volumio-audio volumio[21131]: info: CoreCommandRouter::volumioRetrievevolume Jan 13 22:45:13 volumio-audio volumio[21131]: info: CoreStateMachine::pushState Jan 13 22:45:13 volumio-audio volumio[21131]: info: CorePlayQueue::getTrack 0 Jan 13 22:45:13 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 22:45:13 volumio-audio volumio[21131]: info: CoreCommandRouter::volumioPushState Jan 13 22:45:13 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:45:13 volumio-audio volumio[21131]: info: Volumio Network Manager: Network status updated: 1 Jan 13 22:45:14 volumio-audio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Jan 13 22:45:14 volumio-audio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 13 22:45:14 volumio-audio volumio[21131]: verbose: New Socket.io Connection to 192.168.8.126 from 192.168.8.124 UA: Mozilla/5.0 (Linux; Android 14; SM-M135F Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.192 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jan 13 22:45:14 volumio-audio volumio[21131]: verbose: New Socket.io Connection to 192.168.8.126 from 192.168.8.124 UA: Mozilla/5.0 (Linux; Android 14; SM-M135F Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.192 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Jan 13 22:45:14 volumio-audio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 13 22:45:14 volumio-audio go-librespot[24937]: go-librespot daemon starting... Jan 13 22:45:14 volumio-audio volumio[21131]: info: Reloading queue from file Jan 13 22:45:14 volumio-audio go-librespot[24959]: time="2026-01-13T22:45:14+01:00" level=info msg="running go-librespot 0.4.0" Jan 13 22:45:14 volumio-audio go-librespot[24959]: time="2026-01-13T22:45:14+01:00" level=debug msg="app state loaded" Jan 13 22:45:14 volumio-audio go-librespot[24959]: time="2026-01-13T22:45:14+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 13 22:45:14 volumio-audio volumio[21131]: verbose: New Socket.io Connection to 192.168.8.126:3000 from 192.168.8.124 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 3 Jan 13 22:45:14 volumio-audio volumio[21131]: info: CoreStateMachine::setRepeat null single undefined Jan 13 22:45:14 volumio-audio volumio[21131]: info: CoreStateMachine::pushState Jan 13 22:45:14 volumio-audio volumio[21131]: info: CorePlayQueue::getTrack 0 Jan 13 22:45:14 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 22:45:14 volumio-audio volumio[21131]: info: CoreCommandRouter::volumioPushState Jan 13 22:45:14 volumio-audio volumio[21131]: info: CoreStateMachine::setRandom null Jan 13 22:45:14 volumio-audio volumio[21131]: info: CoreStateMachine::pushState Jan 13 22:45:14 volumio-audio volumio[21131]: info: CorePlayQueue::getTrack 0 Jan 13 22:45:14 volumio-audio volumio[21131]: info: CoreCommandRouter::volumioPushState Jan 13 22:45:14 volumio-audio volumio[21131]: info: Setting Device type: Raspberry PI Jan 13 22:45:14 volumio-audio volumio[21131]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jan 13 22:45:14 volumio-audio volumio[21131]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03111 Jan 13 22:45:14 volumio-audio volumio[21131]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jan 13 22:45:14 volumio-audio volumio-remote-updater[687]: [2026-01-13 22:45:14] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1768340712 101 Jan 13 22:45:14 volumio-audio volumio[21131]: 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: 4 Jan 13 22:45:14 volumio-audio volumio[21131]: verbose: New Socket.io Connection to 192.168.8.126 from 192.168.8.124 UA: Mozilla/5.0 (Linux; Android 14; SM-M135F Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.192 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Jan 13 22:45:14 volumio-audio volumio[21131]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jan 13 22:45:14 volumio-audio volumio[21131]: info: Completed loading Core Plugins Jan 13 22:45:14 volumio-audio volumio[21131]: info: Preparing to generate the ALSA configuration file Jan 13 22:45:14 volumio-audio go-librespot[24959]: time="2026-01-13T22:45:14+01: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]" Jan 13 22:45:14 volumio-audio go-librespot[24959]: time="2026-01-13T22:45:14+01: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]" Jan 13 22:45:14 volumio-audio go-librespot[24959]: time="2026-01-13T22:45:14+01: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]" Jan 13 22:45:14 volumio-audio go-librespot[24959]: time="2026-01-13T22:45:14+01:00" level=info msg="zeroconf server listening on port 39079" Jan 13 22:45:14 volumio-audio volumio[21131]: info: Discovery: adding 901c8b4a-224a-4026-9901-c39bd9ea1d93 Jan 13 22:45:14 volumio-audio volumio[21131]: info: Discovery: Found device Volumio Audio Jan 13 22:45:14 volumio-audio volumio[21131]: info: CoreCommandRouter::volumioGetState Jan 13 22:45:14 volumio-audio volumio[21131]: info: CorePlayQueue::getTrack 0 Jan 13 22:45:14 volumio-audio volumio[21131]: info: Discovery: this is already registered, 901c8b4a-224a-4026-9901-c39bd9ea1d93 Jan 13 22:45:14 volumio-audio volumio[21131]: info: Discovery: Found device Volumio Audio Jan 13 22:45:14 volumio-audio volumio[21131]: info: CoreCommandRouter::volumioGetState Jan 13 22:45:14 volumio-audio volumio[21131]: info: CorePlayQueue::getTrack 0 Jan 13 22:45:14 volumio-audio go-librespot[24959]: time="2026-01-13T22:45:14+01:00" level=debug msg="obtained new client token: AAAX17w75CrQeEAycdP8DXVDVP5EovyQ+Npr8IgCTw6UCfxEAJYOGtcZWV3oCU7ttnGdIpb3Ztz7E7M4ivUS3gpyWXR8iBeYFInCtFypGnUFk/JH0tmxFuc74UUsI3fjfxpGO0gXNFr1KXddOwTeXREhM4ul2iQjdfFHQH2b5nHY4/WzPAIv5lPN5nWYxpjzNPObeh4IILCndzOIayWXYcU0sGcNFrX+tXuPYSMH2TyUOC3UH7fcfIqRbA==" Jan 13 22:45:14 volumio-audio go-librespot[24959]: time="2026-01-13T22:45:14+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jan 13 22:45:14 volumio-audio volumio[21131]: info: Asound.conf file unchanged, so no further update is needed Jan 13 22:45:14 volumio-audio volumio[21131]: info: Output device has changed, restarting MPD Jan 13 22:45:14 volumio-audio go-librespot[24959]: time="2026-01-13T22:45:14+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" Jan 13 22:45:14 volumio-audio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 22:45:14 volumio-audio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 22:45:14 volumio-audio volumio[21131]: info: Output device has changed, restarting Shairport Sync Jan 13 22:45:14 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:45:14 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:45:14 volumio-audio volumio[24606]: Starting albumart workers Jan 13 22:45:14 volumio-audio sudo[25162]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 13 22:45:14 volumio-audio sudo[25162]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:45:14 volumio-audio sudo[25159]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 13 22:45:14 volumio-audio volumio[21131]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 22:45:14 volumio-audio volumio[21131]: info: ___________ START PLUGINS ___________ Jan 13 22:45:14 volumio-audio sudo[25159]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:45:14 volumio-audio volumio[21131]: info: ControllerMpd::onStart: Initializing MPD Jan 13 22:45:14 volumio-audio volumio[21131]: info: Creating MPD Configuration file Jan 13 22:45:14 volumio-audio sudo[25159]: pam_unix(sudo:session): session closed for user root Jan 13 22:45:14 volumio-audio volumio[24614]: Starting albumart workers Jan 13 22:45:14 volumio-audio systemd[1]: Stopping mpd.service - Music Player Daemon... Jan 13 22:45:14 volumio-audio upmpdcli[1585]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 13 22:45:14 volumio-audio sudo[25202]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 13 22:45:14 volumio-audio sudo[25202]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:45:14 volumio-audio sudo[25202]: pam_unix(sudo:session): session closed for user root Jan 13 22:45:14 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 22:45:14 volumio-audio volumio[21131]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 22:45:14 volumio-audio volumio[21131]: info: [1768340714847] CoreMusicLibrary::Adding element Serwery Mediów Jan 13 22:45:14 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 22:45:14 volumio-audio systemd[1]: mpd.service: Deactivated successfully. Jan 13 22:45:14 volumio-audio systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 13 22:45:14 volumio-audio systemd[1]: mpd.service: Consumed 2.188s CPU time. Jan 13 22:45:14 volumio-audio systemd[1]: mpd.socket: Deactivated successfully. Jan 13 22:45:14 volumio-audio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 13 22:45:14 volumio-audio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 13 22:45:14 volumio-audio volumio[21131]: info: UPNP Browser: Client initialized successfully Jan 13 22:45:14 volumio-audio sudo[25212]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 13 22:45:14 volumio-audio sudo[25212]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:45:14 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:45:14 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:45:14 volumio-audio volumio[24611]: Starting albumart workers Jan 13 22:45:14 volumio-audio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 13 22:45:14 volumio-audio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 13 22:45:14 volumio-audio volumio[21131]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 22:45:14 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:45:14 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:45:14 volumio-audio systemd[1]: mpd.service: Deactivated successfully. Jan 13 22:45:14 volumio-audio systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 13 22:45:14 volumio-audio systemd[1]: mpd.socket: Deactivated successfully. Jan 13 22:45:14 volumio-audio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 13 22:45:14 volumio-audio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 13 22:45:14 volumio-audio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 13 22:45:14 volumio-audio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 13 22:45:14 volumio-audio volumio[21131]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 22:45:14 volumio-audio volumio[21131]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 22:45:14 volumio-audio volumio[21131]: info: [1768340714997] CoreMusicLibrary::Adding element Last_100 Jan 13 22:45:14 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 22:45:14 volumio-audio volumio[21131]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 22:45:15 volumio-audio volumio[21131]: info: [1768340714999] CoreMusicLibrary::Adding element Webradio Jan 13 22:45:15 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 22:45:15 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 22:45:15 volumio-audio volumio[21131]: info: Initializing BBC Radios Jan 13 22:45:15 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 22:45:15 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:45:15 volumio-audio sudo[25287]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 13 22:45:15 volumio-audio sudo[25287]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 13 22:45:15 volumio-audio sudo[25287]: pam_unix(sudo:session): session closed for user root Jan 13 22:45:15 volumio-audio volumio[21131]: info: Creating Spotify config file Jan 13 22:45:15 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:45:15 volumio-audio volumio[21131]: info: Loading i18n strings for locale pl Jan 13 22:45:15 volumio-audio volumio[21131]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Jan 13 22:45:15 volumio-audio volumio[21131]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Jan 13 22:45:15 volumio-audio volumio[21131]: info: Loading i18n strings for locale pl Jan 13 22:45:15 volumio-audio volumio[21131]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Jan 13 22:45:15 volumio-audio volumio[21131]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Jan 13 22:45:15 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 22:45:15 volumio-audio volumio[21131]: info: Volumio Calling Home Jan 13 22:45:15 volumio-audio sudo[25525]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /data/volumiokioskextensions/IframeKeyboardBridge Jan 13 22:45:15 volumio-audio sudo[25525]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:45:15 volumio-audio sudo[25525]: pam_unix(sudo:session): session closed for user root Jan 13 22:45:15 volumio-audio sudo[25616]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Jan 13 22:45:15 volumio-audio sudo[25616]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:45:15 volumio-audio sudo[25637]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Jan 13 22:45:15 volumio-audio sudo[25637]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:45:15 volumio-audio sudo[25644]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jan 13 22:45:15 volumio-audio sudo[25644]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:45:15 volumio-audio systemd[1]: Reloading. Jan 13 22:45:16 volumio-audio volumio[21131]: info: touch_display: No backlight interface detected. Jan 13 22:45:16 volumio-audio volumio[21131]: verbose: New Socket.io Connection to 192.168.8.126 from 192.168.8.124 UA: Mozilla/5.0 (Linux; Android 14; SM-M135F Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.192 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Jan 13 22:45:16 volumio-audio volumio[21131]: info: minidlna: Found miniDLNA version 1.3.0 Jan 13 22:45:16 volumio-audio volumio[21131]: info: minidlna: Starting minidlna.service Jan 13 22:45:16 volumio-audio volumio[21131]: info: MPD Permissions set Jan 13 22:45:16 volumio-audio volumio[21131]: info: MPD Permissions set Jan 13 22:45:16 volumio-audio sudo[25816]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/cp -r /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/content.js /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/manifest.json /data/volumiokioskextensions/IframeKeyboardBridge/ Jan 13 22:45:16 volumio-audio sudo[25816]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:45:16 volumio-audio sudo[25822]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Jan 13 22:45:16 volumio-audio sudo[25816]: pam_unix(sudo:session): session closed for user root Jan 13 22:45:16 volumio-audio sudo[25822]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:45:16 volumio-audio volumio[21131]: info: Volumio called home Jan 13 22:45:16 volumio-audio volumio[21131]: info: Spotify config file written Jan 13 22:45:16 volumio-audio sudo[25876]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jan 13 22:45:16 volumio-audio sudo[25876]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:45:16 volumio-audio volumio[21131]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jan 13 22:45:16 volumio-audio volumio[21131]: info: touch_display: IframeKeyboardBridge extension installed successfully Jan 13 22:45:16 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:45:16 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:45:16 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:45:16 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:45:16 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:45:16 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:45:16 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:45:16 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:45:16 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:45:16 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:45:16 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:45:16 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:45:16 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:45:16 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:45:16 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:45:16 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:45:16 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:45:16 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:45:16 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:45:16 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:45:16 volumio-audio volumio[21131]: info: No need to fix Spotify hosts Jan 13 22:45:16 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 13 22:45:16 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 13 22:45:16 volumio-audio volumio[21131]: info: Discovery: Getting this device information Jan 13 22:45:16 volumio-audio volumio[21131]: info: CoreCommandRouter::volumioGetState Jan 13 22:45:16 volumio-audio volumio[21131]: info: CorePlayQueue::getTrack 0 Jan 13 22:45:16 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 13 22:45:16 volumio-audio volumio[21131]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Jan 13 22:45:16 volumio-audio volumio[21131]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jan 13 22:45:16 volumio-audio volumio[21131]: SPOTIFY: BQDG3Ax-A_-1zAlUl3KWZ-SWCP9CU0_pqy1NYQ_ml_G_rz4i4NHX92hC0kUj5GxYBhnAOL0DRlfNq8rpzZiURItTT4VqXmTTa_9o5HcGvpeyYnUiOP2DWeWZh4c9HBcjN-Of4WD8U5QfIhRea1MAzTIFOV63FsD5oN62LR0u_Ow1yhxohw3MIoBjApyoZ5us0DnTSzB8CcRdlSRuehUj6YnfUPkR8FgVxjw8YvR1FgrkCfaVt5cB06QcEhwvogJchiqLDEdeVQ2T1LlcX0Hqbeg8nRbifp2S-9OMrFfEXsa8RT1Sp2qhF-IHusqi Jan 13 22:45:16 volumio-audio volumio[21131]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jan 13 22:45:16 volumio-audio volumio[21131]: info: New Spotify access token = BQDG3Ax-A_-1zAlUl3KWZ-SWCP9CU0_pqy1NYQ_ml_G_rz4i4NHX92hC0kUj5GxYBhnAOL0DRlfNq8rpzZiURItTT4VqXmTTa_9o5HcGvpeyYnUiOP2DWeWZh4c9HBcjN-Of4WD8U5QfIhRea1MAzTIFOV63FsD5oN62LR0u_Ow1yhxohw3MIoBjApyoZ5us0DnTSzB8CcRdlSRuehUj6YnfUPkR8FgVxjw8YvR1FgrkCfaVt5cB06QcEhwvogJchiqLDEdeVQ2T1LlcX0Hqbeg8nRbifp2S-9OMrFfEXsa8RT1Sp2qhF-IHusqi Jan 13 22:45:16 volumio-audio volumio[21131]: info: Spotify credentials grant success - running version from March 24, 2019 Jan 13 22:45:16 volumio-audio volumio[21131]: info: Starting Shairport Sync Jan 13 22:45:16 volumio-audio sudo[26023]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Jan 13 22:45:16 volumio-audio sudo[26023]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:45:16 volumio-audio volumio[21131]: info: Starting Shairport Sync Jan 13 22:45:16 volumio-audio sudo[26028]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Jan 13 22:45:16 volumio-audio sudo[26028]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:45:16 volumio-audio sudo[26028]: pam_unix(sudo:session): session closed for user root Jan 13 22:45:16 volumio-audio volumio[21131]: info: Starting Shairport Sync Jan 13 22:45:16 volumio-audio sudo[26043]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 13 22:45:16 volumio-audio sudo[26043]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:45:16 volumio-audio sudo[26023]: pam_unix(sudo:session): session closed for user root Jan 13 22:45:16 volumio-audio sudo[26050]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 13 22:45:16 volumio-audio sudo[26050]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:45:16 volumio-audio sudo[26064]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 13 22:45:16 volumio-audio sudo[26064]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:45:16 volumio-audio volumio[21131]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Jan 13 22:45:16 volumio-audio volumio[21131]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Jan 13 22:45:17 volumio-audio volumio[21131]: info: CoreCommandRouter::volumioGetState Jan 13 22:45:17 volumio-audio volumio[21131]: info: CorePlayQueue::getTrack 0 Jan 13 22:45:17 volumio-audio volumio[21131]: SPOTIFY: User informations: {"country":"PL","display_name":"Rafał Bednarski","email":"rbednarski08@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31qkindigqrwplw655lp5r7ef2la"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/31qkindigqrwplw655lp5r7ef2la","id":"31qkindigqrwplw655lp5r7ef2la","images":[{"height":300,"url":"https://platform-lookaside.fbsbx.com/platform/profilepic/?asid=4995835327155858&height=300&width=300&ext=1770921956&hash=AT8B2TEWdfhRCm7Z2YwuvI4W","width":300},{"height":64,"url":"https://platform-lookaside.fbsbx.com/platform/profilepic/?asid=4995835327155858&height=50&width=50&ext=1770921956&hash=AT9iycZnhgRSfOcVXIdqmnMY","width":64}],"product":"premium","type":"user","uri":"spotify:user:31qkindigqrwplw655lp5r7ef2la"} Jan 13 22:45:17 volumio-audio volumio[21131]: info: Spotify Successfully logged in Jan 13 22:45:17 volumio-audio volumio[21131]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 22:45:17 volumio-audio volumio[21131]: info: [1768340717160] CoreMusicLibrary::Adding element Spotify Jan 13 22:45:17 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 22:45:17 volumio-audio volumio[21131]: Cannot find translation for source Spotify Jan 13 22:45:17 volumio-audio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 13 22:45:17 volumio-audio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 13 22:45:17 volumio-audio sudo[25644]: pam_unix(sudo:session): session closed for user root Jan 13 22:45:17 volumio-audio volumio[21131]: info: touch_display: systemctl daemon-reload succeeded. Jan 13 22:45:17 volumio-audio sudo[26280]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Jan 13 22:45:17 volumio-audio sudo[26280]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:45:17 volumio-audio systemd[1]: Reloading. Jan 13 22:45:17 volumio-audio mpd[25370]: 2026-01-13T22:45:17 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 13 22:45:17 volumio-audio upmpdcli[1585]: :3:../src/mpdcli.cxx:148::MPDCli::startEventLoop: already started Jan 13 22:45:18 volumio-audio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 13 22:45:18 volumio-audio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 13 22:45:18 volumio-audio systemd[1]: Started mpd.service - Music Player Daemon. Jan 13 22:45:18 volumio-audio sudo[25637]: pam_unix(sudo:session): session closed for user root Jan 13 22:45:18 volumio-audio sudo[25162]: pam_unix(sudo:session): session closed for user root Jan 13 22:45:18 volumio-audio volumio[21131]: info: touch_display: systemctl disable getty@tty1.service succeeded. Jan 13 22:45:18 volumio-audio sudo[25212]: pam_unix(sudo:session): session closed for user root Jan 13 22:45:18 volumio-audio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Jan 13 22:45:18 volumio-audio volumio[21131]: error: MPD error: The expression evaluated to a falsy value: Jan 13 22:45:18 volumio-audio volumio[21131]: assert.ok(self.idling) Jan 13 22:45:18 volumio-audio volumio[21131]: error: The expression evaluated to a falsy value: Jan 13 22:45:18 volumio-audio volumio[21131]: assert.ok(self.idling) Jan 13 22:45:18 volumio-audio volumio[21131]: error: updateQueue error: null Jan 13 22:45:18 volumio-audio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 13 22:45:18 volumio-audio volumio[21131]: info: MPD running with PID25370 Jan 13 22:45:18 volumio-audio volumio[21131]: ,establishing connection Jan 13 22:45:18 volumio-audio volumio[21131]: error: updateQueue error: null Jan 13 22:45:18 volumio-audio go-librespot[27196]: go-librespot daemon starting... Jan 13 22:45:18 volumio-audio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 13 22:45:18 volumio-audio sudo[25876]: pam_unix(sudo:session): session closed for user root Jan 13 22:45:18 volumio-audio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 13 22:45:18 volumio-audio sudo[26280]: pam_unix(sudo:session): session closed for user root Jan 13 22:45:18 volumio-audio sudo[25822]: pam_unix(sudo:session): session closed for user root Jan 13 22:45:18 volumio-audio volumio[21131]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Jan 13 22:45:18 volumio-audio volumio[21131]: info: touch_display: Volumio Kiosk started. Jan 13 22:45:18 volumio-audio volumio[21131]: info: CoreCommandRouter::volumioGetState Jan 13 22:45:18 volumio-audio volumio[21131]: info: CorePlayQueue::getTrack 0 Jan 13 22:45:18 volumio-audio systemd[1]: shairport-sync.service: Deactivated successfully. Jan 13 22:45:18 volumio-audio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 13 22:45:18 volumio-audio go-librespot[27278]: time="2026-01-13T22:45:18+01:00" level=info msg="running go-librespot 0.4.0" Jan 13 22:45:18 volumio-audio go-librespot[27278]: time="2026-01-13T22:45:18+01:00" level=debug msg="app state loaded" Jan 13 22:45:18 volumio-audio systemd[1]: shairport-sync.service: Consumed 1.857s CPU time. Jan 13 22:45:18 volumio-audio go-librespot[27278]: time="2026-01-13T22:45:18+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 13 22:45:18 volumio-audio volumio[21131]: info: minidlna: systemctl start minidlna.service succeeded. Jan 13 22:45:18 volumio-audio volumio[21131]: info: Completed starting Core Plugins Jan 13 22:45:18 volumio-audio volumio[21131]: info: ------------------------------------------- Jan 13 22:45:18 volumio-audio volumio[21131]: info: ----- MyVolumio plugins startup ---- Jan 13 22:45:18 volumio-audio volumio[21131]: info: ------------------------------------------- Jan 13 22:45:18 volumio-audio volumio[21131]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 13 22:45:18 volumio-audio volumio[21131]: info: CoreCommandRouter::volumioGetState Jan 13 22:45:18 volumio-audio volumio[21131]: info: CorePlayQueue::getTrack 0 Jan 13 22:45:18 volumio-audio sudo[25616]: pam_unix(sudo:session): session closed for user root Jan 13 22:45:18 volumio-audio volumio[21131]: info: touch_display: systemctl stop getty@tty1.service succeeded. Jan 13 22:45:18 volumio-audio volumio[21131]: info: touch_display: X display number found: 0 Jan 13 22:45:18 volumio-audio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 13 22:45:19 volumio-audio sudo[26043]: pam_unix(sudo:session): session closed for user root Jan 13 22:45:19 volumio-audio volumio[21131]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jan 13 22:45:19 volumio-audio sudo[26050]: pam_unix(sudo:session): session closed for user root Jan 13 22:45:19 volumio-audio sudo[26064]: pam_unix(sudo:session): session closed for user root Jan 13 22:45:19 volumio-audio volumio[21131]: info: Shairport-Sync Started Jan 13 22:45:19 volumio-audio volumio[21131]: Error adding Membership: Error: addMembership EINVAL Jan 13 22:45:19 volumio-audio volumio[21131]: info: Shairport-Sync Started Jan 13 22:45:19 volumio-audio volumio[21131]: info: Shairport-Sync Started Jan 13 22:45:19 volumio-audio volumio[21131]: info: CoreCommandRouter::volumioGetState Jan 13 22:45:19 volumio-audio volumio[21131]: info: CorePlayQueue::getTrack 0 Jan 13 22:45:19 volumio-audio volumio[21131]: info: touch_display: X display number found: 0 Jan 13 22:45:19 volumio-audio volumio[21131]: info: touch_display: Setting screensaver timeout to 120 seconds. Jan 13 22:45:19 volumio-audio go-librespot[27278]: time="2026-01-13T22:45:19+01: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]" Jan 13 22:45:19 volumio-audio go-librespot[27278]: time="2026-01-13T22:45:19+01: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]" Jan 13 22:45:19 volumio-audio go-librespot[27278]: time="2026-01-13T22:45:19+01: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]" Jan 13 22:45:19 volumio-audio go-librespot[27278]: time="2026-01-13T22:45:19+01:00" level=info msg="zeroconf server listening on port 39561" Jan 13 22:45:19 volumio-audio go-librespot[27278]: time="2026-01-13T22:45:19+01:00" level=debug msg="obtained new client token: AADiJGDNh2ItgUg1NKPUh4/tpnjiXPiJdWLOxYypg12dmzYC63Gy3h56Kv+qoE4HSn4nY37AhqqO5S6uExa32kg1l9/dnjZdrIEsHyO2NiWUnmmsx0hz1e9pNrE/kqjVGQK2fqzCBMTg1ZFE3DF5c8cY7Uw9gvjqFZvVkOj6XKp95E02LG7esKctGspQpa8pFkbOSLh+Gql2usD8gy4jmfWxL3ZmHvXljkpFvNU9r7T/W+rcc18/RkI=" Jan 13 22:45:19 volumio-audio go-librespot[27278]: time="2026-01-13T22:45:19+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jan 13 22:45:19 volumio-audio go-librespot[27278]: time="2026-01-13T22:45:19+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: read tcp 192.168.8.126:36834->34.158.1.133:4070: read: connection reset by peer" Jan 13 22:45:19 volumio-audio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 22:45:19 volumio-audio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 22:45:20 volumio-audio sudo[28629]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 13 22:45:20 volumio-audio sudo[28629]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:45:20 volumio-audio sudo[28629]: pam_unix(sudo:session): session closed for user root Jan 13 22:45:20 volumio-audio sudo[28634]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 13 22:45:20 volumio-audio sudo[28634]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:45:20 volumio-audio sudo[28634]: pam_unix(sudo:session): session closed for user root Jan 13 22:45:20 volumio-audio sudo[28644]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 13 22:45:20 volumio-audio sudo[28644]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:45:20 volumio-audio volumio[21131]: verbose: New Socket.io Connection to 192.168.8.126 from 192.168.8.124 UA: Mozilla/5.0 (Linux; Android 14; SM-M135F Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.192 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Jan 13 22:45:20 volumio-audio sudo[28644]: pam_unix(sudo:session): session closed for user root Jan 13 22:45:20 volumio-audio volumio[21131]: info: Upmpdcli Daemon Started Jan 13 22:45:21 volumio-audio sudo[29287]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 13 22:45:21 volumio-audio sudo[29287]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:45:21 volumio-audio sudo[29294]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 13 22:45:21 volumio-audio sudo[29294]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:45:21 volumio-audio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Jan 13 22:45:21 volumio-audio systemd[1]: mpd_monitor.service: Deactivated successfully. Jan 13 22:45:21 volumio-audio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Jan 13 22:45:21 volumio-audio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Jan 13 22:45:21 volumio-audio sudo[29287]: pam_unix(sudo:session): session closed for user root Jan 13 22:45:21 volumio-audio mpd_monitor.sh[29332]: MPD Monitor Service: Starting MPD Monitor Service Jan 13 22:45:21 volumio-audio sudo[29294]: pam_unix(sudo:session): session closed for user root Jan 13 22:45:21 volumio-audio volumio[21131]: info: Successfully started MPD Monitor Jan 13 22:45:21 volumio-audio volumio[21131]: info: Successfully started MPD Monitor Jan 13 22:45:21 volumio-audio volumio[21131]: verbose: New Socket.io Connection to 192.168.8.126 from 192.168.8.124 UA: Mozilla/5.0 (Linux; Android 14; SM-M135F Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.192 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Jan 13 22:45:21 volumio-audio volumio[21131]: info: go-librespot daemon successfully initialized Jan 13 22:45:22 volumio-audio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Jan 13 22:45:22 volumio-audio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 13 22:45:22 volumio-audio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 13 22:45:22 volumio-audio go-librespot[30004]: go-librespot daemon starting... Jan 13 22:45:22 volumio-audio go-librespot[30048]: time="2026-01-13T22:45:22+01:00" level=info msg="running go-librespot 0.4.0" Jan 13 22:45:22 volumio-audio go-librespot[30048]: time="2026-01-13T22:45:22+01:00" level=debug msg="app state loaded" Jan 13 22:45:22 volumio-audio go-librespot[30048]: time="2026-01-13T22:45:22+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 13 22:45:22 volumio-audio go-librespot[30048]: time="2026-01-13T22:45:22+01: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]" Jan 13 22:45:22 volumio-audio go-librespot[30048]: time="2026-01-13T22:45:22+01: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]" Jan 13 22:45:22 volumio-audio go-librespot[30048]: time="2026-01-13T22:45:22+01: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]" Jan 13 22:45:22 volumio-audio go-librespot[30048]: time="2026-01-13T22:45:22+01:00" level=info msg="zeroconf server listening on port 37567" Jan 13 22:45:22 volumio-audio go-librespot[30048]: time="2026-01-13T22:45:22+01:00" level=debug msg="obtained new client token: AACUUT8/CoNDvcLk20RpII5QZS2E9+kVWwPsZAf4ZO/IUtSU8AKgkXZgBFPMltD2cPJEfdVmWNXp4PouBmHeHZ7rpvzp6HXQBfWOp5tjfoVZcNi9tzMYxgmhjJa1lJknFtMrDwE//L4TIO31thRwpm+Xh8js9TsMMNZsKIi0m4uN2W4z486K91ZRLTRVqzRfdV+OQtzX1pvylh+dJMuSvGUPA8alvt+oUzDOBue3I82ysBigsbqlR1kDxw==" Jan 13 22:45:23 volumio-audio go-librespot[30048]: time="2026-01-13T22:45:23+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jan 13 22:45:23 volumio-audio go-librespot[30048]: time="2026-01-13T22:45:23+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: read tcp 192.168.8.126:36846->34.158.1.133:4070: read: connection reset by peer" Jan 13 22:45:23 volumio-audio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 22:45:23 volumio-audio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 22:45:23 volumio-audio volumio[21131]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jan 13 22:45:24 volumio-audio volumio[21131]: info: Initializing connection to go-librespot Websocket Jan 13 22:45:24 volumio-audio volumio[21131]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 22:45:25 volumio-audio volumio[21131]: verbose: New Socket.io Connection to 192.168.8.126 from 192.168.8.124 UA: Mozilla/5.0 (Linux; Android 14; SM-M135F Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.192 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Jan 13 22:45:26 volumio-audio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. Jan 13 22:45:26 volumio-audio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 13 22:45:26 volumio-audio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 13 22:45:26 volumio-audio go-librespot[32708]: go-librespot daemon starting... Jan 13 22:45:26 volumio-audio go-librespot[32766]: time="2026-01-13T22:45:26+01:00" level=info msg="running go-librespot 0.4.0" Jan 13 22:45:26 volumio-audio go-librespot[32766]: time="2026-01-13T22:45:26+01:00" level=debug msg="app state loaded" Jan 13 22:45:26 volumio-audio go-librespot[32766]: time="2026-01-13T22:45:26+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 13 22:45:26 volumio-audio volumio[21131]: verbose: New Socket.io Connection to 192.168.8.126 from 192.168.8.124 UA: Mozilla/5.0 (Linux; Android 14; SM-M135F Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.192 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Jan 13 22:45:26 volumio-audio go-librespot[32766]: time="2026-01-13T22:45:26+01: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]" Jan 13 22:45:26 volumio-audio go-librespot[32766]: time="2026-01-13T22:45:26+01: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]" Jan 13 22:45:26 volumio-audio go-librespot[32766]: time="2026-01-13T22:45:26+01: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]" Jan 13 22:45:26 volumio-audio go-librespot[32766]: time="2026-01-13T22:45:26+01:00" level=info msg="zeroconf server listening on port 40587" Jan 13 22:45:26 volumio-audio go-librespot[32766]: time="2026-01-13T22:45:26+01:00" level=debug msg="obtained new client token: AABnJrZUnw8UArHIOaf39CIBGx8JLQ5tDWZQVYQpcEMhS40o281AznwI0YZtkSWZMp53ix4ZF2mgP/NY+yIrJU4orVp9JXeRKbaTNbGS92XyJVGGAYhI4peLTo+oeYm5qtrnfqB459+ELccXa8WBHBIbkhBZnVhW4oO2g0jcpdVOucbhRB04rsQfUPg+URXmOcu0KTLUI7wgACPy+o/YzC9fc7n+GnyB/920dBp61ysezWXTMbSoteRbCg==" Jan 13 22:45:26 volumio-audio go-librespot[32766]: time="2026-01-13T22:45:26+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jan 13 22:45:26 volumio-audio go-librespot[32766]: time="2026-01-13T22:45:26+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: read tcp 192.168.8.126:36860->34.158.1.133:4070: read: connection reset by peer" Jan 13 22:45:26 volumio-audio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 22:45:26 volumio-audio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 22:45:27 volumio-audio volumio[21131]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jan 13 22:45:27 volumio-audio volumio[21131]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jan 13 22:45:27 volumio-audio volumio[21131]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jan 13 22:45:27 volumio-audio volumio[21131]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jan 13 22:45:27 volumio-audio volumio[21131]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jan 13 22:45:27 volumio-audio volumio[21131]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jan 13 22:45:27 volumio-audio volumio[21131]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jan 13 22:45:27 volumio-audio volumio[21131]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jan 13 22:45:27 volumio-audio volumio[21131]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jan 13 22:45:27 volumio-audio volumio[21131]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jan 13 22:45:27 volumio-audio volumio[21131]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jan 13 22:45:27 volumio-audio volumio[21131]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jan 13 22:45:27 volumio-audio volumio[21131]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jan 13 22:45:27 volumio-audio volumio[21131]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jan 13 22:45:27 volumio-audio volumio[21131]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jan 13 22:45:27 volumio-audio volumio[21131]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jan 13 22:45:27 volumio-audio volumio[21131]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jan 13 22:45:27 volumio-audio volumio[21131]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jan 13 22:45:27 volumio-audio volumio[21131]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jan 13 22:45:27 volumio-audio volumio[21131]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jan 13 22:45:27 volumio-audio volumio[21131]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jan 13 22:45:27 volumio-audio volumio[21131]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jan 13 22:45:27 volumio-audio volumio[21131]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jan 13 22:45:27 volumio-audio volumio[21131]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jan 13 22:45:27 volumio-audio volumio[21131]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jan 13 22:45:27 volumio-audio volumio[21131]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jan 13 22:45:27 volumio-audio volumio[21131]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jan 13 22:45:27 volumio-audio volumio[21131]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jan 13 22:45:27 volumio-audio volumio[21131]: info: Adding plugin bluetooth to MyMusic Plugins Jan 13 22:45:27 volumio-audio volumio[21131]: info: Adding plugin multiroom to MyMusic Plugins Jan 13 22:45:27 volumio-audio volumio[21131]: info: Adding plugin metavolumio to MyMusic Plugins Jan 13 22:45:27 volumio-audio volumio[21131]: info: Adding plugin cd_controller to MyMusic Plugins Jan 13 22:45:27 volumio-audio volumio[21131]: info: Adding plugin qobuzconnect to MyMusic Plugins Jan 13 22:45:27 volumio-audio volumio[21131]: info: Adding plugin smart_inputs to MyMusic Plugins Jan 13 22:45:27 volumio-audio volumio[21131]: info: Adding plugin tidalconnect to MyMusic Plugins Jan 13 22:45:27 volumio-audio volumio[21131]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jan 13 22:45:27 volumio-audio volumio[21131]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jan 13 22:45:27 volumio-audio volumio[21131]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jan 13 22:45:27 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:45:27 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:45:27 volumio-audio volumio[21131]: info: Starting MyVolumio Remote Streaming Endpoints Jan 13 22:45:27 volumio-audio volumio[21131]: info: MyVolumio login type: Token Jan 13 22:45:27 volumio-audio volumio[21131]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jan 13 22:45:27 volumio-audio volumio[21131]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jan 13 22:45:28 volumio-audio volumio[21131]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jan 13 22:45:28 volumio-audio volumio[21131]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jan 13 22:45:28 volumio-audio volumio[21131]: info: Streaming services startup Jan 13 22:45:28 volumio-audio volumio[21131]: info: Starting Streaming Daemon Jan 13 22:45:28 volumio-audio sudo[2162]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 13 22:45:28 volumio-audio sudo[2162]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:45:28 volumio-audio volumio[21131]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jan 13 22:45:28 volumio-audio sudo[2162]: pam_unix(sudo:session): session closed for user root Jan 13 22:45:28 volumio-audio volumio[21131]: info: Initializing connection to go-librespot Websocket Jan 13 22:45:28 volumio-audio volumio[21131]: error: Cannot start Volumio Streaming Daemon Jan 13 22:45:28 volumio-audio volumio[21131]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 13 22:45:28 volumio-audio volumio[21131]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 13 22:45:28 volumio-audio volumio[21131]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 22:45:29 volumio-audio volumio[21131]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jan 13 22:45:29 volumio-audio volumio[21131]: info: MyVolumio token set successfully Jan 13 22:45:29 volumio-audio volumio[21131]: info: MYVOLUMIO: Adding device Jan 13 22:45:29 volumio-audio volumio[21131]: info: MYVOLUMIO: Evaluating Server Jan 13 22:45:29 volumio-audio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17. Jan 13 22:45:29 volumio-audio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 13 22:45:29 volumio-audio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 13 22:45:29 volumio-audio go-librespot[3084]: go-librespot daemon starting... Jan 13 22:45:29 volumio-audio go-librespot[3127]: time="2026-01-13T22:45:29+01:00" level=info msg="running go-librespot 0.4.0" Jan 13 22:45:29 volumio-audio go-librespot[3127]: time="2026-01-13T22:45:29+01:00" level=debug msg="app state loaded" Jan 13 22:45:29 volumio-audio go-librespot[3127]: time="2026-01-13T22:45:29+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 13 22:45:30 volumio-audio volumio[21131]: info: MyVolumio status changed Jan 13 22:45:30 volumio-audio volumio[21131]: info: Streaming services startup Jan 13 22:45:30 volumio-audio volumio[21131]: info: Starting Streaming Daemon Jan 13 22:45:30 volumio-audio volumio[21131]: info: Removing browser output: myVolumio user plan is not superstar Jan 13 22:45:30 volumio-audio volumio[21131]: info: Removing audio output: Jan 13 22:45:30 volumio-audio volumio[21131]: info: Stoppping Tunnel 1 Jan 13 22:45:30 volumio-audio sudo[3218]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 13 22:45:30 volumio-audio sudo[3218]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:45:30 volumio-audio sudo[3224]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jan 13 22:45:30 volumio-audio sudo[3224]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 22:45:30 volumio-audio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 13 22:45:30 volumio-audio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 13 22:45:30 volumio-audio sudo[3218]: pam_unix(sudo:session): session closed for user root Jan 13 22:45:30 volumio-audio volumio[21131]: error: Cannot start Volumio Streaming Daemon Jan 13 22:45:30 volumio-audio volumio[21131]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 13 22:45:30 volumio-audio volumio[21131]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 13 22:45:30 volumio-audio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 13 22:45:30 volumio-audio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 13 22:45:30 volumio-audio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 13 22:45:30 volumio-audio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 13 22:45:30 volumio-audio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 13 22:45:30 volumio-audio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 13 22:45:30 volumio-audio sudo[3224]: pam_unix(sudo:session): session closed for user root Jan 13 22:45:30 volumio-audio volumio[21131]: info: Remote SSH Stopped Jan 13 22:45:30 volumio-audio go-librespot[3127]: time="2026-01-13T22:45:30+01: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]" Jan 13 22:45:30 volumio-audio go-librespot[3127]: time="2026-01-13T22:45:30+01: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]" Jan 13 22:45:30 volumio-audio go-librespot[3127]: time="2026-01-13T22:45:30+01: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]" Jan 13 22:45:30 volumio-audio go-librespot[3127]: time="2026-01-13T22:45:30+01:00" level=info msg="zeroconf server listening on port 46459" Jan 13 22:45:30 volumio-audio volumio[21131]: info: Setting Geolocation for MyVolumio to eu6 Jan 13 22:45:30 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:45:30 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:45:30 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:45:30 volumio-audio go-librespot[3127]: time="2026-01-13T22:45:30+01:00" level=debug msg="obtained new client token: AAAw+p1qzIZBJBhQtn0XiELr0gUyfGi0fuPyXSWuZpH9OKtGo0EU8Gzg3olE/FuqXJkpXyGNGXP3nxpwIDnkAmNeAR/b447UNW26gfP88SpTwJ9ec4sE0sDyJanp6QjEnR8ghMv4YLYjXpeqPm9Xhjp8m+ORl8ML5UtG356CB6U0zVgI8e4+gWX1h8el/YMqb/I9j+fJWeIg/3K1W6VdNVcM+e//qtpRuoEHVmocRJlUPssjzjW5qgA=" Jan 13 22:45:30 volumio-audio go-librespot[3127]: time="2026-01-13T22:45:30+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jan 13 22:45:30 volumio-audio go-librespot[3127]: time="2026-01-13T22:45:30+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: read tcp 192.168.8.126:58382->34.158.1.133:4070: read: connection reset by peer" Jan 13 22:45:30 volumio-audio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 22:45:30 volumio-audio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 22:45:30 volumio-audio volumio[21131]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jan 13 22:45:31 volumio-audio volumio[21131]: info: Updating MyVolumio device info Jan 13 22:45:31 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:45:31 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:45:31 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:45:31 volumio-audio volumio[21131]: info: Initializing connection to go-librespot Websocket Jan 13 22:45:31 volumio-audio volumio[21131]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 22:45:31 volumio-audio volumio[21131]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jan 13 22:45:33 volumio-audio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18. Jan 13 22:45:33 volumio-audio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 13 22:45:33 volumio-audio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 13 22:45:33 volumio-audio go-librespot[6101]: go-librespot daemon starting... Jan 13 22:45:33 volumio-audio go-librespot[6160]: time="2026-01-13T22:45:33+01:00" level=info msg="running go-librespot 0.4.0" Jan 13 22:45:33 volumio-audio go-librespot[6160]: time="2026-01-13T22:45:33+01:00" level=debug msg="app state loaded" Jan 13 22:45:33 volumio-audio go-librespot[6160]: time="2026-01-13T22:45:33+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 13 22:45:33 volumio-audio volumio[21131]: verbose: New Socket.io Connection to 192.168.8.126 from 192.168.8.124 UA: Mozilla/5.0 (Linux; Android 14; SM-M135F Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.192 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Jan 13 22:45:33 volumio-audio volumio[21131]: verbose: New Socket.io Connection to 192.168.8.126 from 192.168.8.124 UA: Mozilla/5.0 (Linux; Android 14; SM-M135F Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.192 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 Jan 13 22:45:33 volumio-audio go-librespot[6160]: time="2026-01-13T22:45:33+01: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-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 13 22:45:33 volumio-audio go-librespot[6160]: time="2026-01-13T22:45:33+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jan 13 22:45:33 volumio-audio go-librespot[6160]: time="2026-01-13T22:45:33+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jan 13 22:45:33 volumio-audio go-librespot[6160]: time="2026-01-13T22:45:33+01:00" level=info msg="zeroconf server listening on port 38989" Jan 13 22:45:34 volumio-audio go-librespot[6160]: time="2026-01-13T22:45:34+01:00" level=debug msg="obtained new client token: AABL2vGcWmPQPHcgwjJl6n3t1rbqnXfnwsQI6tjqsEufXHSrv2KWivuHsBdQZqNtOtUm5IZBcakUt8qpZ5buiK8tB94qg9tcYm6zMqfIpXuNyS2P5eFKwrKQTMUaQdNuN841+PDbwhj+5lHZqn1TPHI79dPGVWZuOSyNqfq0TAjXWBP/CILVBrkwlbb6ZKYwNIi3XmREvd64WxYWNxjKhJMCEDYs23llrigHxVgmX8MIQEvlejkXLGpcyQ==" Jan 13 22:45:34 volumio-audio go-librespot[6160]: time="2026-01-13T22:45:34+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jan 13 22:45:34 volumio-audio go-librespot[6160]: time="2026-01-13T22:45:34+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: read tcp 192.168.8.126:58392->34.158.1.133:4070: read: connection reset by peer" Jan 13 22:45:34 volumio-audio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 22:45:34 volumio-audio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 22:45:34 volumio-audio volumio[21131]: info: MYVOLUMIO: Adding device Jan 13 22:45:34 volumio-audio volumio[21131]: info: MYVOLUMIO: Evaluating Server Jan 13 22:45:34 volumio-audio volumio[21131]: info: Initializing connection to go-librespot Websocket Jan 13 22:45:34 volumio-audio volumio[21131]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 22:45:35 volumio-audio volumio[21131]: info: Setting Geolocation for MyVolumio to eu10 Jan 13 22:45:35 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:45:35 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:45:35 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:45:35 volumio-audio volumio[21131]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jan 13 22:45:36 volumio-audio volumio[21131]: info: Updating MyVolumio device info Jan 13 22:45:36 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:45:36 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:45:36 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:45:36 volumio-audio volumio[21131]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jan 13 22:45:37 volumio-audio volumio[21131]: verbose: New Socket.io Connection to 192.168.8.126 from 192.168.8.124 UA: Mozilla/5.0 (Linux; Android 14; SM-M135F Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.192 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 15 Jan 13 22:45:37 volumio-audio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19. Jan 13 22:45:37 volumio-audio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 13 22:45:37 volumio-audio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 13 22:45:37 volumio-audio go-librespot[9204]: go-librespot daemon starting... Jan 13 22:45:37 volumio-audio go-librespot[9244]: time="2026-01-13T22:45:37+01:00" level=info msg="running go-librespot 0.4.0" Jan 13 22:45:37 volumio-audio go-librespot[9244]: time="2026-01-13T22:45:37+01:00" level=debug msg="app state loaded" Jan 13 22:45:37 volumio-audio go-librespot[9244]: time="2026-01-13T22:45:37+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 13 22:45:37 volumio-audio go-librespot[9244]: time="2026-01-13T22:45:37+01: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]" Jan 13 22:45:37 volumio-audio go-librespot[9244]: time="2026-01-13T22:45:37+01: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]" Jan 13 22:45:37 volumio-audio go-librespot[9244]: time="2026-01-13T22:45:37+01: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]" Jan 13 22:45:37 volumio-audio go-librespot[9244]: time="2026-01-13T22:45:37+01:00" level=info msg="zeroconf server listening on port 43655" Jan 13 22:45:37 volumio-audio go-librespot[9244]: time="2026-01-13T22:45:37+01:00" level=debug msg="obtained new client token: AAAFPL/ZsgX0DuYfLp6Vezo5uNEnlsxeqvzAG6QXPVXqOW5rRKfMjQ8qOPBYYngP9BbvYBUGghyQbBbjz8d3hTQvJRfw9+um3qREOFyiJPnWdefG7kiYNQW3VISgUI2g+dY+9me9NuwwN6paUsndCQyx1qoSO1x16wa5OXV8hD/qyAgaWiQeKfH2+g6KAfk8uXFZU3UEfUxZ/jP4hBSM0YTNrLDuBwCsnxJmvvGrD5CMoPM2EWVriW2B2w==" Jan 13 22:45:37 volumio-audio volumio[21131]: info: Initializing connection to go-librespot Websocket Jan 13 22:45:37 volumio-audio go-librespot[9244]: time="2026-01-13T22:45:37+01:00" level=debug msg="new websocket client" Jan 13 22:45:37 volumio-audio volumio[21131]: info: Connection to go-librespot Websocket established Jan 13 22:45:38 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 13 22:45:38 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 13 22:45:38 volumio-audio go-librespot[9244]: time="2026-01-13T22:45:38+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused" Jan 13 22:45:38 volumio-audio go-librespot[9244]: time="2026-01-13T22:45:38+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Jan 13 22:45:38 volumio-audio go-librespot[9244]: time="2026-01-13T22:45:38+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: read tcp 192.168.8.126:54736->34.158.1.133:443: read: connection reset by peer" Jan 13 22:45:38 volumio-audio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 22:45:38 volumio-audio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 22:45:38 volumio-audio volumio[21131]: info: Connection to go-librespot Websocket closed Jan 13 22:45:40 volumio-audio volumio[21131]: verbose: New Socket.io Connection to 192.168.8.126 from 192.168.8.124 UA: Mozilla/5.0 (Linux; Android 14; SM-M135F Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.192 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 16 Jan 13 22:45:40 volumio-audio volumio[21131]: info: CoreCommandRouter::volumioGetVisibleSources Jan 13 22:45:40 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 22:45:40 volumio-audio volumio[21131]: info: CoreCommandRouter::volumioGetState Jan 13 22:45:40 volumio-audio volumio[21131]: info: CorePlayQueue::getTrack 0 Jan 13 22:45:40 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 13 22:45:40 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 13 22:45:40 volumio-audio volumio[21131]: info: Received Get System Info Jan 13 22:45:40 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 13 22:45:40 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 13 22:45:40 volumio-audio volumio[21131]: info: Discovery: Getting this device information Jan 13 22:45:40 volumio-audio volumio[21131]: info: CoreCommandRouter::volumioGetState Jan 13 22:45:40 volumio-audio volumio[21131]: info: CorePlayQueue::getTrack 0 Jan 13 22:45:40 volumio-audio volumio[21131]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 13 22:45:40 volumio-audio volumio[21131]: info: CoreCommandRouter::volumioGetState Jan 13 22:45:40 volumio-audio volumio[21131]: info: CorePlayQueue::getTrack 0 Jan 13 22:45:40 volumio-audio volumio[21131]: info: Listing playlists Jan 13 22:45:40 volumio-audio volumio[21131]: info: CoreCommandRouter::volumioGetQueue Jan 13 22:45:40 volumio-audio volumio[21131]: info: CoreStateMachine::getQueue Jan 13 22:45:40 volumio-audio volumio[21131]: info: CorePlayQueue::getQueue Jan 13 22:45:40 volumio-audio volumio[21131]: info: Getting Spotify volume Jan 13 22:45:40 volumio-audio volumio[21131]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 22:45:40 volumio-audio volumio[21131]: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 22:45:40 volumio-audio volumio[21131]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Jan 13 22:45:40 volumio-audio volumio[21131]: errno: -111, Jan 13 22:45:40 volumio-audio volumio[21131]: code: 'ECONNREFUSED', Jan 13 22:45:40 volumio-audio volumio[21131]: syscall: 'connect', Jan 13 22:45:40 volumio-audio volumio[21131]: address: '127.0.0.1', Jan 13 22:45:40 volumio-audio volumio[21131]: port: 9879, Jan 13 22:45:40 volumio-audio volumio[21131]: response: undefined Jan 13 22:45:40 volumio-audio volumio[21131]: } Jan 13 22:45:40 volumio-audio volumio[21131]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 22:45:41 volumio-audio sudo[12431]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-13 22:44' Jan 13 22:45:41 volumio-audio sudo[12431]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"