Feb 07 13:22:00 volumio volumio[30171]: info: Loading plugin "upnp_browser"... Feb 07 13:22:02 volumio sudo[30228]: pam_unix(sudo:session): session closed for user root Feb 07 13:22:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 40. Feb 07 13:22:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 13:22:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 13:22:02 volumio go-librespot[30249]: go-librespot daemon starting... Feb 07 13:22:02 volumio go-librespot[30250]: time="2026-02-07T13:22:02+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 13:22:02 volumio go-librespot[30250]: time="2026-02-07T13:22:02+07:00" level=debug msg="app state loaded" Feb 07 13:22:02 volumio go-librespot[30250]: time="2026-02-07T13:22:02+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 13:22:03 volumio volumio[30171]: info: Starting UPNP Browser Feb 07 13:22:03 volumio volumio[30171]: info: Loading plugin "alarm-clock"... Feb 07 13:22:03 volumio go-librespot[30250]: time="2026-02-07T13:22:03+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 13:22:03 volumio go-librespot[30250]: time="2026-02-07T13:22:03+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 07 13:22:03 volumio go-librespot[30250]: time="2026-02-07T13:22:03+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 07 13:22:03 volumio go-librespot[30250]: time="2026-02-07T13:22:03+07:00" level=info msg="zeroconf server listening on port 40153" Feb 07 13:22:03 volumio volumio[30171]: info: Loading plugin "airplay_emulation"... Feb 07 13:22:03 volumio volumio[30171]: info: Starting Shairport Sync Feb 07 13:22:03 volumio volumio[30171]: info: Loading plugin "last_100"... Feb 07 13:22:03 volumio volumio[30171]: info: Loading plugin "webradio"... Feb 07 13:22:03 volumio go-librespot[30250]: time="2026-02-07T13:22:03+07:00" level=debug msg="obtained new client token: AAAD53fYZUKb6X5oakSe+afAqE/uAOCpJOxeVVtac23Jm542M22GBfUM0UT6nP2L8ShDiNqGB+3l2+gRIEoFv9XQHQk40EclwXiiM3+f51JRR3hNolb2YJiBZct5dxNzCC93v6PasqQTUmWLS2obgxygTRhr984fuwwtDxgBp+NKjUaN0/c8zxNek9XY2m6eFNDFWk4PeVuCFqo+KiBgUgq2coA3WeZkCIItFG3OpW7mpnGF3VsEYPY9Cg==" Feb 07 13:22:03 volumio go-librespot[30250]: time="2026-02-07T13:22:03+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 13:22:03 volumio volumio[30171]: info: Loading plugin "i2s_dacs"... Feb 07 13:22:03 volumio volumio[30171]: info: Loading plugin "volumiodiscovery"... Feb 07 13:22:03 volumio volumio[30171]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 07 13:22:03 volumio volumio[30171]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 13:22:03 volumio node[30171]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 07 13:22:03 volumio volumio[30171]: *** WARNING *** For more information see Feb 07 13:22:03 volumio volumio[30171]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 07 13:22:03 volumio volumio[30171]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 13:22:03 volumio volumio[30171]: *** WARNING *** For more information see Feb 07 13:22:03 volumio node[30171]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 13:22:03 volumio node[30171]: *** WARNING *** For more information see Feb 07 13:22:03 volumio node[30171]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 07 13:22:03 volumio node[30171]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 13:22:03 volumio node[30171]: *** WARNING *** For more information see Feb 07 13:22:03 volumio volumio[30171]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 07 13:22:03 volumio volumio[30171]: info: Discovery: Started advertising with name: Volumio Feb 07 13:22:03 volumio go-librespot[30250]: time="2026-02-07T13:22:03+07:00" level=debug msg="completed keyexchange" Feb 07 13:22:03 volumio go-librespot[30250]: time="2026-02-07T13:22:03+07:00" level=debug msg="completed challenge" Feb 07 13:22:04 volumio volumio[30171]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 07 13:22:04 volumio volumio[30171]: info: Loading plugin "spop"... Feb 07 13:22:04 volumio go-librespot[30250]: time="2026-02-07T13:22:04+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 13:22:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 13:22:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 13:22:05 volumio volumio[30171]: info: Loading plugin "ytcr"... Feb 07 13:22:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 41. Feb 07 13:22:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 13:22:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 13:22:07 volumio go-librespot[30260]: go-librespot daemon starting... Feb 07 13:22:07 volumio go-librespot[30261]: time="2026-02-07T13:22:07+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 13:22:07 volumio go-librespot[30261]: time="2026-02-07T13:22:07+07:00" level=debug msg="app state loaded" Feb 07 13:22:07 volumio go-librespot[30261]: time="2026-02-07T13:22:07+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 13:22:07 volumio go-librespot[30261]: time="2026-02-07T13:22:07+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 13:22:07 volumio go-librespot[30261]: time="2026-02-07T13:22:07+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 07 13:22:07 volumio go-librespot[30261]: time="2026-02-07T13:22:07+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 07 13:22:07 volumio go-librespot[30261]: time="2026-02-07T13:22:07+07:00" level=info msg="zeroconf server listening on port 46171" Feb 07 13:22:08 volumio go-librespot[30261]: time="2026-02-07T13:22:08+07:00" level=debug msg="obtained new client token: AADoI5owNQiqNO/HLGZk40z9hgA7td/FpUqEkNvjbn/tSVbE8f1jPxhtzil0QsH5DVbFrm9eALIREWTltyHpNRR7DNqvOdBHtpMjGcOYkhpG3rT/QZ8XijNA7wXSdPsN8tg50RiJUsAYKziUahNMZOJgZcIStpCr5HILEJxuNBnJGhsiHxaaFN5cbSslNzbyJl87Qn7E9ShqZetT9CzminoMlEJ/7Xzieal5vEQzPrUETY3qHkIo7Bk=" Feb 07 13:22:08 volumio go-librespot[30261]: time="2026-02-07T13:22:08+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 13:22:08 volumio go-librespot[30261]: time="2026-02-07T13:22:08+07:00" level=debug msg="completed keyexchange" Feb 07 13:22:08 volumio go-librespot[30261]: time="2026-02-07T13:22:08+07:00" level=debug msg="completed challenge" Feb 07 13:22:08 volumio volumio[30171]: info: Loading plugin "ytmusic"... Feb 07 13:22:08 volumio go-librespot[30261]: time="2026-02-07T13:22:08+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 13:22:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 13:22:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 13:22:09 volumio volumio-remote-updater[643]: [2026-02-07 13:22:09] [connect] Successful connection Feb 07 13:22:09 volumio volumio[30171]: info: Loading plugin "outputs"... Feb 07 13:22:09 volumio volumio[30171]: info: Loading plugin "albumart"... Feb 07 13:22:09 volumio volumio[30171]: info: Plugin example_plugin is not enabled Feb 07 13:22:09 volumio volumio[30171]: info: Loading plugin "inputs"... Feb 07 13:22:09 volumio volumio[30171]: info: Loading plugin "updater_comm"... Feb 07 13:22:10 volumio volumio[30171]: info: Plugin mpdemulation is not enabled Feb 07 13:22:10 volumio volumio[30171]: info: Loading plugin "rest_api"... Feb 07 13:22:10 volumio volumio[30171]: info: Loading plugin "websocket"... Feb 07 13:22:10 volumio volumio[30171]: info: Starting Socket.io Server version 1.7.4 Feb 07 13:22:10 volumio volumio[30171]: info: Loading plugin "RoonBridge"... Feb 07 13:22:10 volumio volumio[30171]: info: Applying required configuration parameters for plugin RoonBridge Feb 07 13:22:10 volumio volumio[30171]: info: Loading i18n strings for locale en Feb 07 13:22:10 volumio volumio[30171]: Updating browse sources language Feb 07 13:22:10 volumio volumio[30171]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 13:22:10 volumio volumio[30171]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 13:22:10 volumio volumio[30171]: info: CoreCommandRouter::initPlayerControls Feb 07 13:22:10 volumio volumio[30284]: Forking 3 albumart workers Feb 07 13:22:10 volumio volumio[30171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:10 volumio volumio[30171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:10 volumio volumio[30171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:10 volumio volumio[30171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:10 volumio volumio[30171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:10 volumio volumio[30171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:10 volumio volumio[30171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:10 volumio volumio[30171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:10 volumio volumio[30171]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 13:22:10 volumio volumio[30171]: Express server listening on port 3000 Feb 07 13:22:10 volumio volumio[30171]: [Metrics] WebUI: 16s 15.45ms Feb 07 13:22:10 volumio volumio[30171]: info: CoreStateMachine::resetVolumioState Feb 07 13:22:10 volumio volumio[30171]: info: CoreStateMachine::getcurrentVolume Feb 07 13:22:10 volumio volumio[30171]: info: CoreCommandRouter::volumioRetrievevolume Feb 07 13:22:11 volumio sudo[30328]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 07 13:22:11 volumio sudo[30326]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 07 13:22:11 volumio sudo[30328]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 13:22:11 volumio sudo[30326]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 13:22:11 volumio sudo[30328]: pam_unix(sudo:session): session closed for user root Feb 07 13:22:11 volumio volumio[30171]: info: Volumio Network Manager: Network status updated: 1 Feb 07 13:22:11 volumio sudo[30326]: pam_unix(sudo:session): session closed for user root Feb 07 13:22:11 volumio volumio[30171]: info: VolumeController:: Volume=100 Mute =false Feb 07 13:22:11 volumio volumio[30171]: info: CoreStateMachine::pushState Feb 07 13:22:11 volumio volumio[30171]: info: CorePlayQueue::getTrack 0 Feb 07 13:22:11 volumio volumio[30171]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 13:22:11 volumio volumio[30171]: info: CoreCommandRouter::volumioPushState Feb 07 13:22:11 volumio volumio[30171]: info: CoreStateMachine::updateTrackBlock Feb 07 13:22:11 volumio volumio[30171]: info: CorePlayQueue::getTrackBlock Feb 07 13:22:11 volumio volumio[30171]: info: CoreCommandRouter::volumioRetrievevolume Feb 07 13:22:11 volumio volumio-remote-updater[643]: [2026-02-07 13:22:11] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1770445329 101 Feb 07 13:22:11 volumio volumio[30171]: 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: 1 Feb 07 13:22:11 volumio volumio[30171]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 13:22:11 volumio volumio[30171]: info: Reloading queue from file Feb 07 13:22:11 volumio volumio[30171]: info: CoreStateMachine::setRepeat null single undefined Feb 07 13:22:11 volumio volumio[30171]: info: CoreStateMachine::pushState Feb 07 13:22:11 volumio volumio[30171]: info: CorePlayQueue::getTrack 0 Feb 07 13:22:11 volumio volumio[30171]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 13:22:11 volumio volumio[30171]: info: CoreCommandRouter::volumioPushState Feb 07 13:22:11 volumio volumio[30171]: info: CoreStateMachine::setRandom null Feb 07 13:22:11 volumio volumio[30171]: info: CoreStateMachine::pushState Feb 07 13:22:11 volumio volumio[30171]: info: CorePlayQueue::getTrack 0 Feb 07 13:22:11 volumio volumio[30171]: info: CoreCommandRouter::volumioPushState Feb 07 13:22:11 volumio volumio[30171]: info: Setting Device type: Raspberry PI Feb 07 13:22:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 42. Feb 07 13:22:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 13:22:11 volumio volumio[30171]: info: Completed loading Core Plugins Feb 07 13:22:11 volumio volumio[30171]: info: Preparing to generate the ALSA configuration file Feb 07 13:22:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 13:22:11 volumio go-librespot[30343]: go-librespot daemon starting... Feb 07 13:22:12 volumio volumio[30171]: info: VolumeController:: Volume=100 Mute =false Feb 07 13:22:12 volumio volumio[30171]: info: CoreStateMachine::pushState Feb 07 13:22:12 volumio volumio[30171]: info: CorePlayQueue::getTrack 0 Feb 07 13:22:12 volumio volumio[30171]: info: CoreCommandRouter::volumioPushState Feb 07 13:22:12 volumio go-librespot[30345]: time="2026-02-07T13:22:12+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 13:22:12 volumio go-librespot[30345]: time="2026-02-07T13:22:12+07:00" level=debug msg="app state loaded" Feb 07 13:22:12 volumio go-librespot[30345]: time="2026-02-07T13:22:12+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 13:22:12 volumio volumio[30171]: info: Asound.conf file unchanged, so no further update is needed Feb 07 13:22:12 volumio volumio[30171]: info: Output device has changed, restarting MPD Feb 07 13:22:12 volumio sudo[30352]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 07 13:22:12 volumio sudo[30352]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 13:22:12 volumio volumio[30171]: info: Output device has changed, restarting Shairport Sync Feb 07 13:22:12 volumio volumio[30171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:12 volumio volumio[30171]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 13:22:12 volumio sudo[30357]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 07 13:22:12 volumio sudo[30354]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 07 13:22:12 volumio sudo[30357]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 13:22:12 volumio sudo[30354]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 13:22:12 volumio sudo[30354]: pam_unix(sudo:session): session closed for user root Feb 07 13:22:12 volumio sudo[30352]: pam_unix(sudo:session): session closed for user root Feb 07 13:22:12 volumio volumio[30171]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 07 13:22:12 volumio volumio[30171]: info: ___________ START PLUGINS ___________ Feb 07 13:22:12 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 07 13:22:12 volumio volumio[30171]: info: ControllerMpd::onStart: Initializing MPD Feb 07 13:22:12 volumio volumio[30171]: info: Creating MPD Configuration file Feb 07 13:22:12 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 07 13:22:12 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 07 13:22:12 volumio systemd[1]: mpd.service: Consumed 7.155s CPU time. Feb 07 13:22:12 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 07 13:22:12 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 07 13:22:12 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 07 13:22:12 volumio volumio[30171]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 13:22:12 volumio volumio[30171]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 13:22:12 volumio volumio[30171]: info: [1770445332636] CoreMusicLibrary::Adding element Media Servers Feb 07 13:22:12 volumio volumio[30171]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 13:22:12 volumio sudo[30370]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 07 13:22:12 volumio sudo[30370]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 13:22:12 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 07 13:22:12 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 07 13:22:12 volumio sudo[30367]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 07 13:22:12 volumio sudo[30367]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 13:22:12 volumio volumio[30171]: info: UPNP Browser: Client initialized successfully Feb 07 13:22:12 volumio sudo[30367]: pam_unix(sudo:session): session closed for user root Feb 07 13:22:12 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 07 13:22:12 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 07 13:22:12 volumio volumio[30171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:12 volumio volumio[30171]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 13:22:12 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 07 13:22:12 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 07 13:22:12 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 07 13:22:12 volumio go-librespot[30345]: time="2026-02-07T13:22:12+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 13:22:12 volumio go-librespot[30345]: time="2026-02-07T13:22:12+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 07 13:22:12 volumio go-librespot[30345]: time="2026-02-07T13:22:12+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 07 13:22:12 volumio go-librespot[30345]: time="2026-02-07T13:22:12+07:00" level=info msg="zeroconf server listening on port 45329" Feb 07 13:22:12 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 07 13:22:12 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 07 13:22:12 volumio volumio[30171]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 07 13:22:12 volumio volumio[30171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:12 volumio volumio[30171]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 13:22:13 volumio volumio[30171]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 07 13:22:13 volumio volumio[30171]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 13:22:13 volumio volumio[30171]: info: [1770445333090] CoreMusicLibrary::Adding element Last_100 Feb 07 13:22:13 volumio volumio[30171]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 13:22:13 volumio go-librespot[30345]: time="2026-02-07T13:22:13+07:00" level=debug msg="obtained new client token: AACJ2ZSzUvEUb+W27iV4GbV1I14hVwwsN5E8OIj9uihGvmrpx1gz0YNnywF65rno5Wiuqar9dIhPFRop+UMEhE3DvoL8cT+t200FR/LwcL6MhOeSmDK1oU9u9UUfNRc8Ik4Prt1kzxunDWxprTVAjkLZ01VEkmt+AzfXdEI5/XkUXfmb4nwetHGRQjRx3+N8bOUKJbP7Mw5CkFbpHQshZNZKlqd5yEMEDZKAWIo3n/NRB3U2iCl+N5I=" Feb 07 13:22:13 volumio volumio[30171]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 13:22:13 volumio volumio[30171]: info: [1770445333114] CoreMusicLibrary::Adding element Webradio Feb 07 13:22:13 volumio volumio[30171]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 13:22:13 volumio volumio[30171]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 07 13:22:13 volumio volumio[30171]: info: Initializing BBC Radios Feb 07 13:22:13 volumio go-librespot[30345]: time="2026-02-07T13:22:13+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 13:22:13 volumio go-librespot[30345]: time="2026-02-07T13:22:13+07:00" level=debug msg="completed keyexchange" Feb 07 13:22:13 volumio go-librespot[30345]: time="2026-02-07T13:22:13+07:00" level=debug msg="completed challenge" Feb 07 13:22:13 volumio go-librespot[30345]: time="2026-02-07T13:22:13+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 13:22:13 volumio volumio[30171]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 07 13:22:13 volumio volumio[30171]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 13:22:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 13:22:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 13:22:13 volumio volumio[30171]: info: Creating Spotify config file Feb 07 13:22:13 volumio volumio[30171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:13 volumio sudo[30379]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 07 13:22:13 volumio sudo[30379]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 07 13:22:13 volumio sudo[30379]: pam_unix(sudo:session): session closed for user root Feb 07 13:22:14 volumio volumio[30296]: Starting albumart workers Feb 07 13:22:14 volumio volumio[30294]: Starting albumart workers Feb 07 13:22:14 volumio volumio[30295]: Starting albumart workers Feb 07 13:22:14 volumio volumio[30171]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 13:22:14 volumio volumio[30171]: info: [1770445334834] CoreMusicLibrary::Adding element YouTube Music Feb 07 13:22:14 volumio volumio[30171]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 13:22:14 volumio volumio[30171]: Cannot find translation for source YouTube Music Feb 07 13:22:14 volumio volumio[30171]: info: Volumio Calling Home Feb 07 13:22:15 volumio sudo[30400]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 07 13:22:15 volumio sudo[30400]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 13:22:15 volumio sudo[30400]: pam_unix(sudo:session): session closed for user root Feb 07 13:22:15 volumio volumio[30171]: info: Discovery: adding 921648ed-0557-4d70-9927-1420682efcc7 Feb 07 13:22:15 volumio volumio[30171]: info: Discovery: Found device Volumio Feb 07 13:22:15 volumio volumio[30171]: info: CoreCommandRouter::volumioGetState Feb 07 13:22:15 volumio volumio[30171]: info: CorePlayQueue::getTrack 0 Feb 07 13:22:15 volumio volumio[30171]: info: Discovery: this is already registered, 921648ed-0557-4d70-9927-1420682efcc7 Feb 07 13:22:15 volumio volumio[30171]: info: Discovery: Found device Volumio Feb 07 13:22:15 volumio volumio[30171]: info: CoreCommandRouter::volumioGetState Feb 07 13:22:15 volumio volumio[30171]: info: CorePlayQueue::getTrack 0 Feb 07 13:22:15 volumio volumio[30171]: info: MPD Permissions set Feb 07 13:22:15 volumio volumio[30171]: info: MPD Permissions set Feb 07 13:22:15 volumio volumio[30171]: info: Upmpdcli Daemon Started Feb 07 13:22:15 volumio volumio[30171]: info: Volumio called home Feb 07 13:22:15 volumio volumio[30171]: info: Spotify config file written Feb 07 13:22:16 volumio sudo[30414]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 07 13:22:16 volumio sudo[30414]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 13:22:16 volumio volumio[30171]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Feb 07 13:22:16 volumio volumio[30171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:16 volumio volumio[30171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:16 volumio volumio[30171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:16 volumio volumio[30171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:16 volumio volumio[30171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:16 volumio volumio[30171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:16 volumio volumio[30171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:16 volumio volumio[30171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:16 volumio volumio[30171]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 13:22:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 13:22:16 volumio volumio[30171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:16 volumio volumio[30171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:16 volumio volumio[30171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:16 volumio volumio[30171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:16 volumio volumio[30171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:16 volumio volumio[30171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:16 volumio volumio[30171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:16 volumio volumio[30171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:16 volumio volumio[30171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 13:22:16 volumio go-librespot[30426]: go-librespot daemon starting... Feb 07 13:22:16 volumio volumio[30171]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 13:22:16 volumio volumio[30171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:16 volumio volumio[30171]: info: No need to fix Spotify hosts Feb 07 13:22:16 volumio sudo[30414]: pam_unix(sudo:session): session closed for user root Feb 07 13:22:16 volumio go-librespot[30437]: time="2026-02-07T13:22:16+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 13:22:16 volumio go-librespot[30437]: time="2026-02-07T13:22:16+07:00" level=debug msg="app state loaded" Feb 07 13:22:16 volumio go-librespot[30437]: time="2026-02-07T13:22:16+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 13:22:16 volumio go-librespot[30437]: time="2026-02-07T13:22:16+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 13:22:16 volumio go-librespot[30437]: time="2026-02-07T13:22:16+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 07 13:22:16 volumio go-librespot[30437]: time="2026-02-07T13:22:16+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 07 13:22:16 volumio go-librespot[30437]: time="2026-02-07T13:22:16+07:00" level=info msg="zeroconf server listening on port 40565" Feb 07 13:22:17 volumio volumio[30171]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 07 13:22:17 volumio volumio[30171]: SPOTIFY: BQDKJiRwS2wr5GKC5cKXP3F6xsQHQecwDDgi-x8lO_hcAwzwDBL-Gt3DieY0wznWRcipBVNwlWyynVzh3qfexUfUTJVfZTb86Fp_tvAZ9U_dKWCxSlmS7ESXzunoRt1hvF6sedEgIUp7N8joGdYuHclJZudMafrXUE6ewDoA5mTB6qk4AMhYfM7nGmaDtkP2ZNuDle2SCSNF1gaWiZOe87FR8vSUIwmt5xBNUDZazpvTFYP8xifYil1NNTEAIXhfDyV9-s991QLcRMMjsHBhwqLOmGWZFMxh0wnLW_uUyohUrApZppxtiFuv Feb 07 13:22:17 volumio volumio[30171]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 07 13:22:17 volumio volumio[30171]: info: New Spotify access token = BQDKJiRwS2wr5GKC5cKXP3F6xsQHQecwDDgi-x8lO_hcAwzwDBL-Gt3DieY0wznWRcipBVNwlWyynVzh3qfexUfUTJVfZTb86Fp_tvAZ9U_dKWCxSlmS7ESXzunoRt1hvF6sedEgIUp7N8joGdYuHclJZudMafrXUE6ewDoA5mTB6qk4AMhYfM7nGmaDtkP2ZNuDle2SCSNF1gaWiZOe87FR8vSUIwmt5xBNUDZazpvTFYP8xifYil1NNTEAIXhfDyV9-s991QLcRMMjsHBhwqLOmGWZFMxh0wnLW_uUyohUrApZppxtiFuv Feb 07 13:22:17 volumio volumio[30171]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 07 13:22:17 volumio volumio[30171]: info: Starting Shairport Sync Feb 07 13:22:17 volumio volumio[30171]: info: Starting Shairport Sync Feb 07 13:22:17 volumio volumio[30171]: info: Starting Shairport Sync Feb 07 13:22:17 volumio go-librespot[30437]: time="2026-02-07T13:22:17+07:00" level=debug msg="obtained new client token: AABYiukKUQ/c4ZfoBXp2XbhZnFHcobVXGOW0oCOuO88Ue1bQwsmZs+u0CvDmMWLTXK1IEqcoOFj68z/Ftp3f0O38LTlX/y4AOspCR/acHaqm5eh1yNgrZo6c1Fn8jkpgoL4PFQTXHSXEo+TlhOjGrJEt74l3L4kXC1gM1h5UMzupmNrhwU8ZktpqoMhWGlXtCNIprIVjT5W7kiSqkoyvXR9mDr3LYxMKQnbJ88O6uI/Fisx+IuwlJOw=" Feb 07 13:22:17 volumio sudo[30446]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 07 13:22:17 volumio sudo[30446]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 13:22:17 volumio sudo[30451]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 07 13:22:17 volumio sudo[30451]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 13:22:17 volumio go-librespot[30437]: time="2026-02-07T13:22:17+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 13:22:17 volumio sudo[30448]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 07 13:22:17 volumio sudo[30448]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 13:22:17 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 07 13:22:17 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 07 13:22:17 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 07 13:22:17 volumio systemd[1]: shairport-sync.service: Consumed 2.335s CPU time. Feb 07 13:22:17 volumio go-librespot[30437]: time="2026-02-07T13:22:17+07:00" level=debug msg="completed keyexchange" Feb 07 13:22:17 volumio go-librespot[30437]: time="2026-02-07T13:22:17+07:00" level=debug msg="completed challenge" Feb 07 13:22:17 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 07 13:22:17 volumio go-librespot[30437]: time="2026-02-07T13:22:17+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 13:22:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 13:22:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 13:22:17 volumio sudo[30446]: pam_unix(sudo:session): session closed for user root Feb 07 13:22:17 volumio volumio[30171]: info: CoreCommandRouter::volumioGetState Feb 07 13:22:17 volumio volumio[30171]: info: CorePlayQueue::getTrack 0 Feb 07 13:22:17 volumio volumio[30171]: info: Shairport-Sync Started Feb 07 13:22:17 volumio volumio[30171]: Error adding Membership: Error: addMembership EINVAL Feb 07 13:22:17 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 07 13:22:17 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 07 13:22:17 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 07 13:22:17 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 07 13:22:17 volumio sudo[30451]: pam_unix(sudo:session): session closed for user root Feb 07 13:22:17 volumio sudo[30448]: pam_unix(sudo:session): session closed for user root Feb 07 13:22:17 volumio volumio[30171]: info: Shairport-Sync Started Feb 07 13:22:17 volumio volumio[30171]: info: Shairport-Sync Started Feb 07 13:22:17 volumio volumio[30171]: SPOTIFY: User informations: {"country":"PK","display_name":"215mmgea6kss3jxeqsqdquvji","email":"linh.ngotran@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/215mmgea6kss3jxeqsqdquvji"},"followers":{"href":null,"total":1},"href":"https://api.spotify.com/v1/users/215mmgea6kss3jxeqsqdquvji","id":"215mmgea6kss3jxeqsqdquvji","images":[],"product":"premium","type":"user","uri":"spotify:user:215mmgea6kss3jxeqsqdquvji"} Feb 07 13:22:17 volumio volumio[30171]: info: Spotify Successfully logged in Feb 07 13:22:17 volumio volumio[30171]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 13:22:17 volumio volumio[30171]: info: [1770445337917] CoreMusicLibrary::Adding element Spotify Feb 07 13:22:17 volumio volumio[30171]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 13:22:17 volumio volumio[30171]: Cannot find translation for source YouTube Music Feb 07 13:22:17 volumio volumio[30171]: Cannot find translation for source Spotify Feb 07 13:22:18 volumio volumio[30171]: info: [yt-cast-receiver] DIAL server listening on port 8098 Feb 07 13:22:18 volumio volumio[30171]: info: CoreCommandRouter::volumioRetrievevolume Feb 07 13:22:18 volumio volumio[30171]: info: VolumeController:: Volume=100 Mute =false Feb 07 13:22:18 volumio volumio[30171]: info: CoreCommandRouter::volumioGetState Feb 07 13:22:18 volumio volumio[30171]: info: CorePlayQueue::getTrack 0 Feb 07 13:22:18 volumio volumio[30171]: info: CoreStateMachine::pushState Feb 07 13:22:18 volumio volumio[30171]: info: CorePlayQueue::getTrack 0 Feb 07 13:22:18 volumio volumio[30171]: info: CoreCommandRouter::volumioPushState Feb 07 13:22:20 volumio volumio[30171]: info: go-librespot daemon successfully initialized Feb 07 13:22:20 volumio mpd[30398]: 2026-02-07T13:22:20 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 07 13:22:20 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 07 13:22:20 volumio sudo[30370]: pam_unix(sudo:session): session closed for user root Feb 07 13:22:20 volumio sudo[30357]: pam_unix(sudo:session): session closed for user root Feb 07 13:22:20 volumio volumio[30171]: error: MPD error: The expression evaluated to a falsy value: Feb 07 13:22:20 volumio volumio[30171]: assert.ok(self.idling) Feb 07 13:22:20 volumio volumio[30171]: error: The expression evaluated to a falsy value: Feb 07 13:22:20 volumio volumio[30171]: assert.ok(self.idling) Feb 07 13:22:20 volumio volumio[30171]: info: MPD running with PID30398 Feb 07 13:22:20 volumio volumio[30171]: ,establishing connection Feb 07 13:22:20 volumio volumio[30171]: error: updateQueue error: null Feb 07 13:22:20 volumio volumio[30171]: info: Completed starting Core Plugins Feb 07 13:22:20 volumio volumio[30171]: info: ------------------------------------------- Feb 07 13:22:20 volumio volumio[30171]: info: ----- MyVolumio plugins startup ---- Feb 07 13:22:20 volumio volumio[30171]: info: ------------------------------------------- Feb 07 13:22:20 volumio volumio[30171]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 07 13:22:20 volumio volumio[30171]: error: updateQueue error: null Feb 07 13:22:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 43. Feb 07 13:22:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 13:22:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 13:22:20 volumio go-librespot[30496]: go-librespot daemon starting... Feb 07 13:22:20 volumio go-librespot[30497]: time="2026-02-07T13:22:20+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 13:22:20 volumio go-librespot[30497]: time="2026-02-07T13:22:20+07:00" level=debug msg="app state loaded" Feb 07 13:22:20 volumio go-librespot[30497]: time="2026-02-07T13:22:20+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 13:22:21 volumio go-librespot[30497]: time="2026-02-07T13:22:21+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 07 13:22:21 volumio go-librespot[30497]: time="2026-02-07T13:22:21+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 07 13:22:21 volumio go-librespot[30497]: time="2026-02-07T13:22:21+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 07 13:22:21 volumio go-librespot[30497]: time="2026-02-07T13:22:21+07:00" level=info msg="zeroconf server listening on port 33077" Feb 07 13:22:21 volumio go-librespot[30497]: time="2026-02-07T13:22:21+07:00" level=debug msg="obtained new client token: AABsrRFfRfHc4j3Voe51j18ZsVxdH0O8ry6yGi6WEpDGlfrFwPEp8ZWG9jK/3FKAmVrTlPmiC4fwU7iJd0YU0MP0u6d7nQhgN6gMXeUlblYk0FizcDnf3BMm5PL+2C/2zKN61r559RwLMwql4CAjQXcw+v50II9QDwL47F7tKeMZnK899EQi7M5NGxqO23L7rWd/zy+eJ2iKcBhhQ5JQZu9fkeleIWZWRHXr226IcD14rgx9HqooV1t4pg==" Feb 07 13:22:21 volumio go-librespot[30497]: time="2026-02-07T13:22:21+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 13:22:21 volumio go-librespot[30497]: time="2026-02-07T13:22:21+07:00" level=debug msg="completed keyexchange" Feb 07 13:22:21 volumio go-librespot[30497]: time="2026-02-07T13:22:21+07:00" level=debug msg="completed challenge" Feb 07 13:22:21 volumio go-librespot[30497]: time="2026-02-07T13:22:21+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 13:22:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 13:22:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 13:22:23 volumio volumio[30171]: info: Initializing connection to go-librespot Websocket Feb 07 13:22:23 volumio volumio[30171]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 07 13:22:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 44. Feb 07 13:22:25 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 13:22:25 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 13:22:25 volumio go-librespot[30504]: go-librespot daemon starting... Feb 07 13:22:25 volumio go-librespot[30505]: time="2026-02-07T13:22:25+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 13:22:25 volumio go-librespot[30505]: time="2026-02-07T13:22:25+07:00" level=debug msg="app state loaded" Feb 07 13:22:25 volumio go-librespot[30505]: time="2026-02-07T13:22:25+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 13:22:25 volumio volumio[30171]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 07 13:22:25 volumio go-librespot[30505]: time="2026-02-07T13:22:25+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 13:22:25 volumio go-librespot[30505]: time="2026-02-07T13:22:25+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 07 13:22:25 volumio go-librespot[30505]: time="2026-02-07T13:22:25+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 07 13:22:25 volumio go-librespot[30505]: time="2026-02-07T13:22:25+07:00" level=info msg="zeroconf server listening on port 41947" Feb 07 13:22:25 volumio go-librespot[30505]: time="2026-02-07T13:22:25+07:00" level=debug msg="obtained new client token: AACWh3dxE8TFVtZzIF705QLHrtUwq1J9R4Frk0SQ72hgYV4oN3bLaqyrCsaubAbGy0vwgnvUc8F2E6lZo/NLe6Oh0HNL/w/mXXg6vmQW9MDRptTQDdqdSZnAwz52tuiOJ48Vw2syJpP43ZwOjBwQwIGLSEYRDg8sWysWehX3YCZjW1DqRdHTl/euvuEd37vLwL1Df0IiCL6hSy8PkqqeJB4SK3yPtsY3uFPUk4BEGVOuesI7/GRqmOzxJg==" Feb 07 13:22:26 volumio go-librespot[30505]: time="2026-02-07T13:22:26+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 13:22:26 volumio volumio[30171]: info: Initializing connection to go-librespot Websocket Feb 07 13:22:26 volumio go-librespot[30505]: time="2026-02-07T13:22:26+07:00" level=debug msg="new websocket client" Feb 07 13:22:26 volumio volumio[30171]: info: Connection to go-librespot Websocket established Feb 07 13:22:26 volumio go-librespot[30505]: time="2026-02-07T13:22:26+07:00" level=debug msg="completed keyexchange" Feb 07 13:22:26 volumio go-librespot[30505]: time="2026-02-07T13:22:26+07:00" level=debug msg="completed challenge" Feb 07 13:22:26 volumio go-librespot[30505]: time="2026-02-07T13:22:26+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 13:22:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 13:22:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 13:22:26 volumio volumio[30171]: info: Connection to go-librespot Websocket closed Feb 07 13:22:29 volumio volumio[30171]: info: Getting Spotify volume Feb 07 13:22:29 volumio volumio[30171]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 13:22:29 volumio volumio[30171]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 07 13:22:29 volumio volumio[30171]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 07 13:22:29 volumio volumio[30171]: errno: -111, Feb 07 13:22:29 volumio volumio[30171]: code: 'ECONNREFUSED', Feb 07 13:22:29 volumio volumio[30171]: syscall: 'connect', Feb 07 13:22:29 volumio volumio[30171]: address: '127.0.0.1', Feb 07 13:22:29 volumio volumio[30171]: port: 9879, Feb 07 13:22:29 volumio volumio[30171]: response: undefined Feb 07 13:22:29 volumio volumio[30171]: } Feb 07 13:22:29 volumio volumio[30171]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 13:22:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 45. Feb 07 13:22:29 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 13:22:29 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 13:22:29 volumio go-librespot[30524]: go-librespot daemon starting... Feb 07 13:22:29 volumio go-librespot[30525]: time="2026-02-07T13:22:29+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 13:22:29 volumio go-librespot[30525]: time="2026-02-07T13:22:29+07:00" level=debug msg="app state loaded" Feb 07 13:22:29 volumio go-librespot[30525]: time="2026-02-07T13:22:29+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 13:22:29 volumio go-librespot[30525]: time="2026-02-07T13:22:29+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 13:22:29 volumio go-librespot[30525]: time="2026-02-07T13:22:29+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 07 13:22:29 volumio go-librespot[30525]: time="2026-02-07T13:22:29+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 07 13:22:29 volumio go-librespot[30525]: time="2026-02-07T13:22:29+07:00" level=info msg="zeroconf server listening on port 34057" Feb 07 13:22:30 volumio go-librespot[30525]: time="2026-02-07T13:22:30+07:00" level=debug msg="obtained new client token: AAACyM84BpzfxPvExWfkonPcTnBoZ4t5pm2rRekmNKXVZ2QfhudDLUg61hnAdxpDtM3ebw9tar4FSRfCitpc4wxgG5x2cOai2Kfp0uX8ODXimMFqF2vZmmJMnVzQjFHUHm9AXiZ1DCg5uzBlCC3rGaOfN1ONRHepVZ1U7PJ1ilqSRSs73Q9FxUOjzxDrvj8z29Y9tz0mt/2Uzlp6qE632rtOJ4sg/D9JW4uFEY4W3uWgijGH46JZwIU=" Feb 07 13:22:30 volumio go-librespot[30525]: time="2026-02-07T13:22:30+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 13:22:30 volumio go-librespot[30525]: time="2026-02-07T13:22:30+07:00" level=debug msg="completed keyexchange" Feb 07 13:22:30 volumio go-librespot[30525]: time="2026-02-07T13:22:30+07:00" level=debug msg="completed challenge" Feb 07 13:22:30 volumio go-librespot[30525]: time="2026-02-07T13:22:30+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 13:22:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 13:22:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 13:22:30 volumio sudo[30549]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-07 13:21' Feb 07 13:22:30 volumio sudo[30549]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 13:22:31 volumio sudo[30549]: pam_unix(sudo:session): session closed for user root Feb 07 13:22:31 volumio volumio-remote-updater[643]: [2026-02-07 13:22:31] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 07 13:22:31 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 07 13:22:31 volumio volumio-remote-updater[643]: [2026-02-07 13:22:31] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 07 13:22:31 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 07 13:22:31 volumio systemd[1]: volumio.service: Consumed 49.040s CPU time. Feb 07 13:22:31 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 07 13:22:31 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 07 13:22:31 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 5826. Feb 07 13:22:31 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 07 13:22:31 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 07 13:22:31 volumio systemd[1]: volumio.service: Consumed 49.040s CPU time. Feb 07 13:22:31 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 07 13:22:31 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 07 13:22:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 46. Feb 07 13:22:33 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 13:22:33 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 13:22:33 volumio go-librespot[30578]: go-librespot daemon starting... Feb 07 13:22:33 volumio go-librespot[30579]: time="2026-02-07T13:22:33+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 13:22:33 volumio go-librespot[30579]: time="2026-02-07T13:22:33+07:00" level=debug msg="app state loaded" Feb 07 13:22:33 volumio go-librespot[30579]: time="2026-02-07T13:22:33+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 13:22:34 volumio go-librespot[30579]: time="2026-02-07T13:22:34+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 13:22:34 volumio go-librespot[30579]: time="2026-02-07T13:22:34+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 07 13:22:34 volumio go-librespot[30579]: time="2026-02-07T13:22:34+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 07 13:22:34 volumio go-librespot[30579]: time="2026-02-07T13:22:34+07:00" level=info msg="zeroconf server listening on port 35741" Feb 07 13:22:34 volumio go-librespot[30579]: time="2026-02-07T13:22:34+07:00" level=debug msg="obtained new client token: AACkNmjEUHjw+8aSiSMs5jVnlRz0UcfzMWdk622DMsLBsmuyEvGKSObhU6pe5ABeaubXZ2XrRRX2eGCICCXnz4Up/Q1VgrBvn5jBzKqv8YN9d9za9DJEgsjkDe2BthgUVV1JLLbkNVOBtflVGPF4FCx/dp5pLwOjDoyhiEGyDAyYr3+IO5lD1ihnTo+rF7gVr3Wqflk+Go9I2NgTFxRy7yb1IGvOChKMsBZ2saBWcOId9gIitHQ3djU10w==" Feb 07 13:22:34 volumio go-librespot[30579]: time="2026-02-07T13:22:34+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 13:22:34 volumio go-librespot[30579]: time="2026-02-07T13:22:34+07:00" level=debug msg="completed keyexchange" Feb 07 13:22:34 volumio go-librespot[30579]: time="2026-02-07T13:22:34+07:00" level=debug msg="completed challenge" Feb 07 13:22:34 volumio go-librespot[30579]: time="2026-02-07T13:22:34+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 13:22:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 13:22:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 13:22:35 volumio volumio[30562]: info: ------------------------------------------- Feb 07 13:22:35 volumio volumio[30562]: info: ----- Volumio3 ---- Feb 07 13:22:35 volumio volumio[30562]: info: ------------------------------------------- Feb 07 13:22:35 volumio volumio[30562]: info: ----- System startup ---- Feb 07 13:22:35 volumio volumio[30562]: info: ------------------------------------------- Feb 07 13:22:36 volumio volumio-remote-updater[643]: [2026-02-07 13:22:36] [connect] Successful connection Feb 07 13:22:36 volumio volumio[30562]: info: MYVOLUMIO Environment detected Feb 07 13:22:36 volumio volumio[30562]: info: Plugin folders cleanup Feb 07 13:22:36 volumio volumio[30562]: info: Scanning into folder /volumio/app/plugins/ Feb 07 13:22:36 volumio volumio[30562]: info: Scanning category audio_interface Feb 07 13:22:36 volumio volumio[30562]: info: Scanning category miscellanea Feb 07 13:22:36 volumio volumio[30562]: info: Scanning category music_service Feb 07 13:22:36 volumio volumio[30562]: info: Scanning category plugins.json Feb 07 13:22:36 volumio volumio[30562]: info: Scanning category system_controller Feb 07 13:22:36 volumio volumio[30562]: info: Scanning category user_interface Feb 07 13:22:36 volumio volumio[30562]: info: Scanning into folder /data/plugins/ Feb 07 13:22:36 volumio volumio[30562]: info: Scanning category music_service Feb 07 13:22:36 volumio volumio[30562]: info: Plugin folders cleanup completed Feb 07 13:22:36 volumio volumio[30562]: info: ------------------------------------------- Feb 07 13:22:36 volumio volumio[30562]: info: ----- Core plugins startup ---- Feb 07 13:22:36 volumio volumio[30562]: info: ------------------------------------------- Feb 07 13:22:36 volumio volumio[30562]: info: Loading plugins from folder /volumio/app/plugins/ Feb 07 13:22:36 volumio volumio[30562]: info: Adding plugin upnp to MyMusic Plugins Feb 07 13:22:36 volumio volumio[30562]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 07 13:22:36 volumio volumio[30562]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 07 13:22:36 volumio volumio[30562]: info: Loading plugins from folder /data/plugins/ Feb 07 13:22:36 volumio volumio[30562]: info: Loading plugin "system"... Feb 07 13:22:36 volumio volumio[30562]: info: Loading plugin "appearance"... Feb 07 13:22:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 47. Feb 07 13:22:38 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 13:22:38 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 13:22:38 volumio go-librespot[30598]: go-librespot daemon starting... Feb 07 13:22:38 volumio go-librespot[30599]: time="2026-02-07T13:22:38+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 13:22:38 volumio go-librespot[30599]: time="2026-02-07T13:22:38+07:00" level=debug msg="app state loaded" Feb 07 13:22:38 volumio go-librespot[30599]: time="2026-02-07T13:22:38+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 13:22:38 volumio volumio[30562]: info: Loading plugin "network"... Feb 07 13:22:38 volumio volumio[30562]: info: Refreshing Cached IP Addresses Feb 07 13:22:38 volumio sudo[30607]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 07 13:22:38 volumio sudo[30607]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 13:22:38 volumio sudo[30609]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 07 13:22:38 volumio sudo[30607]: pam_unix(sudo:session): session closed for user root Feb 07 13:22:38 volumio sudo[30609]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 13:22:38 volumio sudo[30609]: pam_unix(sudo:session): session closed for user root Feb 07 13:22:38 volumio volumio[30562]: info: Loading plugin "services"... Feb 07 13:22:38 volumio volumio[30562]: info: Loading plugin "alsa_controller"... Feb 07 13:22:38 volumio sudo[30618]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 07 13:22:38 volumio sudo[30618]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 13:22:38 volumio go-librespot[30599]: time="2026-02-07T13:22:38+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 07 13:22:38 volumio go-librespot[30599]: time="2026-02-07T13:22:38+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 07 13:22:38 volumio go-librespot[30599]: time="2026-02-07T13:22:38+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 07 13:22:38 volumio go-librespot[30599]: time="2026-02-07T13:22:38+07:00" level=info msg="zeroconf server listening on port 39071" Feb 07 13:22:38 volumio volumio[30562]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 07 13:22:38 volumio volumio[30562]: info: Loading plugin "wizard"... Feb 07 13:22:38 volumio volumio[30562]: info: Loading plugin "networkfs"... Feb 07 13:22:38 volumio volumio[30562]: info: Starting Udev Watcher for removable devices Feb 07 13:22:38 volumio volumio[30562]: info: Ignoring mount for partition: boot Feb 07 13:22:38 volumio volumio[30562]: info: Ignoring mount for partition: volumio Feb 07 13:22:38 volumio volumio[30562]: info: Ignoring mount for partition: volumio_data Feb 07 13:22:38 volumio volumio[30562]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 07 13:22:38 volumio volumio[30562]: info: Loading plugin "volumio_command_line_client"... Feb 07 13:22:38 volumio volumio[30562]: info: Loading plugin "upnp"... Feb 07 13:22:38 volumio volumio[30562]: info: [1770445358993] Starting Upmpd Daemon Feb 07 13:22:38 volumio volumio[30562]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 07 13:22:38 volumio volumio[30562]: info: Loading plugin "my_music"... Feb 07 13:22:39 volumio volumio[30562]: info: Loading plugin "mpd"... Feb 07 13:22:39 volumio go-librespot[30599]: time="2026-02-07T13:22:39+07:00" level=debug msg="obtained new client token: AACglzUnjCufwvc8LD5wTPA0HwNd+JhPWrwj13gHiFA38V3hn7duysy+qnBOEh5plODjN4+qRtWOpz/xUSft3IcUR7o6x2cLSpZy2TFXAamrRc6FvPAqCCP3lyU8c9K5dk0dZvwxg/g6qVYuu6TYLQ+hKSZyNz1LglnodGWRfAsPPa1BxS5m189nCy25bzdiOBAnIc+gnC+4D/zJo0u7YD/K/LhLIAZCNF4hvtvPiTHOVMRzD0sNdRzEhg==" Feb 07 13:22:39 volumio go-librespot[30599]: time="2026-02-07T13:22:39+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused" Feb 07 13:22:39 volumio go-librespot[30599]: time="2026-02-07T13:22:39+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:443, retrying with a different AP" error="dial tcp 104.199.241.202:443: connect: connection refused" Feb 07 13:22:39 volumio go-librespot[30599]: time="2026-02-07T13:22:39+07:00" level=debug msg="connected to ap-gae2.spotify.com:80" Feb 07 13:22:39 volumio go-librespot[30599]: time="2026-02-07T13:22:39+07:00" level=debug msg="completed keyexchange" Feb 07 13:22:39 volumio go-librespot[30599]: time="2026-02-07T13:22:39+07:00" level=debug msg="completed challenge" Feb 07 13:22:39 volumio go-librespot[30599]: time="2026-02-07T13:22:39+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 13:22:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 13:22:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 13:22:39 volumio volumio[30562]: info: Loading plugin "upnp_browser"... Feb 07 13:22:41 volumio sudo[30618]: pam_unix(sudo:session): session closed for user root Feb 07 13:22:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 48. Feb 07 13:22:42 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 13:22:42 volumio volumio[30562]: info: Starting UPNP Browser Feb 07 13:22:42 volumio volumio[30562]: info: Loading plugin "alarm-clock"... Feb 07 13:22:42 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 13:22:42 volumio go-librespot[30642]: go-librespot daemon starting... Feb 07 13:22:42 volumio go-librespot[30643]: time="2026-02-07T13:22:42+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 13:22:42 volumio go-librespot[30643]: time="2026-02-07T13:22:42+07:00" level=debug msg="app state loaded" Feb 07 13:22:42 volumio go-librespot[30643]: time="2026-02-07T13:22:42+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 13:22:42 volumio volumio[30562]: info: Loading plugin "airplay_emulation"... Feb 07 13:22:42 volumio volumio[30562]: info: Starting Shairport Sync Feb 07 13:22:42 volumio volumio[30562]: info: Loading plugin "last_100"... Feb 07 13:22:42 volumio volumio[30562]: info: Loading plugin "webradio"... Feb 07 13:22:43 volumio volumio[30562]: info: Loading plugin "i2s_dacs"... Feb 07 13:22:43 volumio volumio[30562]: info: Loading plugin "volumiodiscovery"... Feb 07 13:22:43 volumio volumio[30562]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 07 13:22:43 volumio volumio[30562]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 13:22:43 volumio volumio[30562]: *** WARNING *** For more information see Feb 07 13:22:43 volumio volumio[30562]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 07 13:22:43 volumio volumio[30562]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 13:22:43 volumio volumio[30562]: *** WARNING *** For more information see Feb 07 13:22:43 volumio node[30562]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 07 13:22:43 volumio node[30562]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 13:22:43 volumio node[30562]: *** WARNING *** For more information see Feb 07 13:22:43 volumio node[30562]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 07 13:22:43 volumio node[30562]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 13:22:43 volumio node[30562]: *** WARNING *** For more information see Feb 07 13:22:43 volumio volumio[30562]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 07 13:22:43 volumio volumio[30562]: info: Discovery: Started advertising with name: Volumio Feb 07 13:22:43 volumio go-librespot[30643]: time="2026-02-07T13:22:43+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 13:22:43 volumio go-librespot[30643]: time="2026-02-07T13:22:43+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 07 13:22:43 volumio go-librespot[30643]: time="2026-02-07T13:22:43+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 07 13:22:43 volumio go-librespot[30643]: time="2026-02-07T13:22:43+07:00" level=info msg="zeroconf server listening on port 33549" Feb 07 13:22:43 volumio volumio[30562]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 07 13:22:43 volumio volumio[30562]: info: Loading plugin "spop"... Feb 07 13:22:43 volumio go-librespot[30643]: time="2026-02-07T13:22:43+07:00" level=debug msg="obtained new client token: AACQYgx/dhcc6hbeWCwpKSivhJl0PjUqzPZvEKMstV5SGM1SdBR0JYIJeoGmu8GXTPTJlEp1xU1Qd+NCMeLOtzymHqRUrZwgQeezHJiYAG85YBSi1rP4c0FNEVcYjG8UBh/yF69YtuDw4okD5y4AM0rp5tpr2EBzTCJyw9HkyScyovsPjF8STNqzrkvub79lccLCCB6Lpfr64UFVdIs5HH9Hxx/OsPj51QvI9pMwFL+xQeejsg5PaZop+g==" Feb 07 13:22:43 volumio go-librespot[30643]: time="2026-02-07T13:22:43+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 13:22:43 volumio go-librespot[30643]: time="2026-02-07T13:22:43+07:00" level=debug msg="completed keyexchange" Feb 07 13:22:43 volumio go-librespot[30643]: time="2026-02-07T13:22:43+07:00" level=debug msg="completed challenge" Feb 07 13:22:43 volumio go-librespot[30643]: time="2026-02-07T13:22:43+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 13:22:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 13:22:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 13:22:45 volumio volumio[30562]: info: Loading plugin "ytcr"... Feb 07 13:22:47 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 49. Feb 07 13:22:47 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 13:22:47 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 13:22:47 volumio go-librespot[30653]: go-librespot daemon starting... Feb 07 13:22:47 volumio go-librespot[30654]: time="2026-02-07T13:22:47+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 13:22:47 volumio go-librespot[30654]: time="2026-02-07T13:22:47+07:00" level=debug msg="app state loaded" Feb 07 13:22:47 volumio go-librespot[30654]: time="2026-02-07T13:22:47+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 13:22:47 volumio go-librespot[30654]: time="2026-02-07T13:22:47+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 13:22:47 volumio go-librespot[30654]: time="2026-02-07T13:22:47+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 07 13:22:47 volumio go-librespot[30654]: time="2026-02-07T13:22:47+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 07 13:22:47 volumio go-librespot[30654]: time="2026-02-07T13:22:47+07:00" level=info msg="zeroconf server listening on port 45375" Feb 07 13:22:47 volumio volumio[30562]: info: Loading plugin "ytmusic"... Feb 07 13:22:48 volumio go-librespot[30654]: time="2026-02-07T13:22:48+07:00" level=debug msg="obtained new client token: AABdnljr0xxTQkSk1aY6obXHOz5V+aGO8LQje99JbNI72EtJitlmwXHRC8o4l9Lg4zFbKywCdOlXq/5FLvEG63gDxAWwhRtMHzVVcYoCCa3+4DZvp+OFyLYpd06elO5PK8TewZdoPwVBBTPTrYAVgcAyC+p2C38UcWyTv/+FSZlvUifvGGmp/N7Xt/6jE5UZjh4VbGNH6Ec8ni7fgWVVyVxaE8O83j05Y/e3umDF9f5KYMv+x+lOQpKS/w==" Feb 07 13:22:48 volumio go-librespot[30654]: time="2026-02-07T13:22:48+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 13:22:48 volumio go-librespot[30654]: time="2026-02-07T13:22:48+07:00" level=debug msg="completed keyexchange" Feb 07 13:22:48 volumio go-librespot[30654]: time="2026-02-07T13:22:48+07:00" level=debug msg="completed challenge" Feb 07 13:22:48 volumio go-librespot[30654]: time="2026-02-07T13:22:48+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 13:22:48 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 13:22:48 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 13:22:48 volumio volumio-remote-updater[643]: [2026-02-07 13:22:48] [connect] Successful connection Feb 07 13:22:49 volumio volumio[30562]: info: Loading plugin "outputs"... Feb 07 13:22:49 volumio volumio[30562]: info: Loading plugin "albumart"... Feb 07 13:22:49 volumio volumio[30562]: info: Plugin example_plugin is not enabled Feb 07 13:22:49 volumio volumio[30562]: info: Loading plugin "inputs"... Feb 07 13:22:49 volumio volumio[30562]: info: Loading plugin "updater_comm"... Feb 07 13:22:49 volumio volumio[30562]: info: Plugin mpdemulation is not enabled Feb 07 13:22:49 volumio volumio[30562]: info: Loading plugin "rest_api"... Feb 07 13:22:49 volumio volumio[30562]: info: Loading plugin "websocket"... Feb 07 13:22:49 volumio volumio[30562]: info: Starting Socket.io Server version 1.7.4 Feb 07 13:22:49 volumio volumio[30562]: info: Loading plugin "RoonBridge"... Feb 07 13:22:49 volumio volumio[30562]: info: Applying required configuration parameters for plugin RoonBridge Feb 07 13:22:49 volumio volumio[30562]: info: Loading i18n strings for locale en Feb 07 13:22:50 volumio volumio[30562]: Updating browse sources language Feb 07 13:22:50 volumio volumio[30562]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 13:22:50 volumio volumio[30676]: Forking 3 albumart workers Feb 07 13:22:50 volumio volumio[30562]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 13:22:50 volumio volumio[30562]: info: CoreCommandRouter::initPlayerControls Feb 07 13:22:50 volumio volumio[30562]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:50 volumio volumio[30562]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:50 volumio volumio[30562]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:50 volumio volumio[30562]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:50 volumio volumio[30562]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:50 volumio volumio[30562]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:50 volumio volumio[30562]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:50 volumio volumio[30562]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:50 volumio volumio[30562]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 13:22:50 volumio volumio[30562]: Express server listening on port 3000 Feb 07 13:22:50 volumio volumio[30562]: [Metrics] WebUI: 16s 293.16ms Feb 07 13:22:50 volumio volumio[30562]: info: CoreStateMachine::resetVolumioState Feb 07 13:22:50 volumio volumio[30562]: info: CoreStateMachine::getcurrentVolume Feb 07 13:22:50 volumio volumio[30562]: info: CoreCommandRouter::volumioRetrievevolume Feb 07 13:22:50 volumio sudo[30723]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 07 13:22:50 volumio sudo[30723]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 13:22:50 volumio sudo[30723]: pam_unix(sudo:session): session closed for user root Feb 07 13:22:50 volumio sudo[30720]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 07 13:22:50 volumio sudo[30720]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 13:22:50 volumio sudo[30720]: pam_unix(sudo:session): session closed for user root Feb 07 13:22:50 volumio volumio[30562]: info: Volumio Network Manager: Network status updated: 1 Feb 07 13:22:50 volumio volumio[30562]: info: VolumeController:: Volume=100 Mute =false Feb 07 13:22:50 volumio volumio[30562]: info: CoreStateMachine::pushState Feb 07 13:22:50 volumio volumio[30562]: info: CorePlayQueue::getTrack 0 Feb 07 13:22:50 volumio volumio[30562]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 13:22:50 volumio volumio[30562]: info: CoreCommandRouter::volumioPushState Feb 07 13:22:50 volumio volumio[30562]: info: CoreStateMachine::updateTrackBlock Feb 07 13:22:50 volumio volumio[30562]: info: CorePlayQueue::getTrackBlock Feb 07 13:22:50 volumio volumio[30562]: info: CoreCommandRouter::volumioRetrievevolume Feb 07 13:22:51 volumio volumio-remote-updater[643]: [2026-02-07 13:22:51] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1770445368 101 Feb 07 13:22:51 volumio volumio[30562]: 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: 1 Feb 07 13:22:51 volumio volumio[30562]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 13:22:51 volumio volumio[30562]: info: Reloading queue from file Feb 07 13:22:51 volumio volumio[30562]: info: CoreStateMachine::setRepeat null single undefined Feb 07 13:22:51 volumio volumio[30562]: info: CoreStateMachine::pushState Feb 07 13:22:51 volumio volumio[30562]: info: CorePlayQueue::getTrack 0 Feb 07 13:22:51 volumio volumio[30562]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 13:22:51 volumio volumio[30562]: info: CoreCommandRouter::volumioPushState Feb 07 13:22:51 volumio volumio[30562]: info: CoreStateMachine::setRandom null Feb 07 13:22:51 volumio volumio[30562]: info: CoreStateMachine::pushState Feb 07 13:22:51 volumio volumio[30562]: info: CorePlayQueue::getTrack 0 Feb 07 13:22:51 volumio volumio[30562]: info: CoreCommandRouter::volumioPushState Feb 07 13:22:51 volumio volumio[30562]: info: Setting Device type: Raspberry PI Feb 07 13:22:51 volumio volumio[30562]: info: Completed loading Core Plugins Feb 07 13:22:51 volumio volumio[30562]: info: Preparing to generate the ALSA configuration file Feb 07 13:22:51 volumio volumio[30562]: info: VolumeController:: Volume=100 Mute =false Feb 07 13:22:51 volumio volumio[30562]: info: CoreStateMachine::pushState Feb 07 13:22:51 volumio volumio[30562]: info: CorePlayQueue::getTrack 0 Feb 07 13:22:51 volumio volumio[30562]: info: CoreCommandRouter::volumioPushState Feb 07 13:22:51 volumio volumio[30562]: info: Asound.conf file unchanged, so no further update is needed Feb 07 13:22:51 volumio volumio[30562]: info: Output device has changed, restarting MPD Feb 07 13:22:51 volumio sudo[30737]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 07 13:22:51 volumio sudo[30737]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 13:22:51 volumio volumio[30562]: info: Output device has changed, restarting Shairport Sync Feb 07 13:22:51 volumio volumio[30562]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:51 volumio volumio[30562]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 13:22:51 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 50. Feb 07 13:22:51 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 13:22:51 volumio sudo[30739]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 07 13:22:51 volumio sudo[30739]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 13:22:51 volumio sudo[30739]: pam_unix(sudo:session): session closed for user root Feb 07 13:22:51 volumio volumio[30562]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 07 13:22:51 volumio volumio[30562]: info: ___________ START PLUGINS ___________ Feb 07 13:22:51 volumio sudo[30741]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 07 13:22:51 volumio sudo[30741]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 13:22:51 volumio volumio[30562]: info: ControllerMpd::onStart: Initializing MPD Feb 07 13:22:51 volumio volumio[30562]: info: Creating MPD Configuration file Feb 07 13:22:51 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 13:22:51 volumio go-librespot[30747]: go-librespot daemon starting... Feb 07 13:22:51 volumio go-librespot[30751]: time="2026-02-07T13:22:51+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 13:22:51 volumio go-librespot[30751]: time="2026-02-07T13:22:51+07:00" level=debug msg="app state loaded" Feb 07 13:22:51 volumio go-librespot[30751]: time="2026-02-07T13:22:51+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 13:22:51 volumio volumio[30562]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 13:22:51 volumio sudo[30737]: pam_unix(sudo:session): session closed for user root Feb 07 13:22:51 volumio volumio[30562]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 13:22:51 volumio volumio[30562]: info: [1770445371790] CoreMusicLibrary::Adding element Media Servers Feb 07 13:22:51 volumio volumio[30562]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 13:22:51 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 07 13:22:51 volumio volumio[30562]: info: UPNP Browser: Client initialized successfully Feb 07 13:22:51 volumio sudo[30759]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 07 13:22:51 volumio sudo[30759]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 13:22:51 volumio volumio[30562]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:51 volumio volumio[30562]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 13:22:51 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 07 13:22:51 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 07 13:22:51 volumio systemd[1]: mpd.service: Consumed 7.043s CPU time. Feb 07 13:22:51 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 07 13:22:51 volumio sudo[30757]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 07 13:22:51 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 07 13:22:51 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 07 13:22:51 volumio sudo[30757]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 13:22:51 volumio sudo[30757]: pam_unix(sudo:session): session closed for user root Feb 07 13:22:52 volumio volumio[30562]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 07 13:22:52 volumio volumio[30562]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:52 volumio volumio[30562]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 13:22:52 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 07 13:22:52 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 07 13:22:52 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 07 13:22:52 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 07 13:22:52 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 07 13:22:52 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 07 13:22:52 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 07 13:22:52 volumio volumio[30562]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 07 13:22:52 volumio volumio[30562]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 13:22:52 volumio volumio[30562]: info: [1770445372217] CoreMusicLibrary::Adding element Last_100 Feb 07 13:22:52 volumio volumio[30562]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 13:22:52 volumio volumio[30562]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 13:22:52 volumio volumio[30562]: info: [1770445372230] CoreMusicLibrary::Adding element Webradio Feb 07 13:22:52 volumio volumio[30562]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 13:22:52 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 07 13:22:52 volumio volumio[30562]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 07 13:22:52 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 07 13:22:52 volumio volumio[30562]: info: Initializing BBC Radios Feb 07 13:22:52 volumio volumio[30562]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 07 13:22:52 volumio volumio[30562]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 13:22:52 volumio go-librespot[30751]: time="2026-02-07T13:22:52+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 13:22:52 volumio go-librespot[30751]: time="2026-02-07T13:22:52+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 07 13:22:52 volumio go-librespot[30751]: time="2026-02-07T13:22:52+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 07 13:22:52 volumio go-librespot[30751]: time="2026-02-07T13:22:52+07:00" level=info msg="zeroconf server listening on port 37829" Feb 07 13:22:52 volumio volumio[30562]: info: Creating Spotify config file Feb 07 13:22:52 volumio volumio[30562]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:52 volumio sudo[30774]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 07 13:22:52 volumio sudo[30774]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 07 13:22:52 volumio sudo[30774]: pam_unix(sudo:session): session closed for user root Feb 07 13:22:52 volumio go-librespot[30751]: time="2026-02-07T13:22:52+07:00" level=debug msg="obtained new client token: AAC+eu0ljed2anFxHo9dkl03ZxSrMA9FuIlu09E3WQl6md4gDWKmmjfPwp5BhL7q0JNCg/Dq+m7kRmjKVOoqw9RA3Z09R5vyhy6MNPNJOVjTcqdSdnUDQiS8aj0BHyZ9neKtV8LKxH999sM2D1OeXsAY0T9Ca6GoGhmY+P5sNgYw0NcbIS8Wtafg/Wjdkx5/E1flN8xHdJi8hIzPF9KRFl75ghy2KKfzLbjebcOVt+04Z/eYsseBOBtz3g==" Feb 07 13:22:52 volumio go-librespot[30751]: time="2026-02-07T13:22:52+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 13:22:53 volumio go-librespot[30751]: time="2026-02-07T13:22:53+07:00" level=debug msg="completed keyexchange" Feb 07 13:22:53 volumio go-librespot[30751]: time="2026-02-07T13:22:53+07:00" level=debug msg="completed challenge" Feb 07 13:22:53 volumio go-librespot[30751]: time="2026-02-07T13:22:53+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 13:22:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 13:22:53 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 13:22:53 volumio volumio[30686]: Starting albumart workers Feb 07 13:22:53 volumio volumio[30687]: Starting albumart workers Feb 07 13:22:53 volumio volumio[30688]: Starting albumart workers Feb 07 13:22:53 volumio volumio[30562]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 13:22:53 volumio volumio[30562]: info: [1770445373855] CoreMusicLibrary::Adding element YouTube Music Feb 07 13:22:53 volumio volumio[30562]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 13:22:53 volumio volumio[30562]: Cannot find translation for source YouTube Music Feb 07 13:22:53 volumio volumio[30562]: info: Volumio Calling Home Feb 07 13:22:54 volumio sudo[30791]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 07 13:22:54 volumio sudo[30791]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 13:22:54 volumio sudo[30791]: pam_unix(sudo:session): session closed for user root Feb 07 13:22:54 volumio volumio[30562]: info: Discovery: adding 921648ed-0557-4d70-9927-1420682efcc7 Feb 07 13:22:54 volumio volumio[30562]: info: Discovery: Found device Volumio Feb 07 13:22:54 volumio volumio[30562]: info: CoreCommandRouter::volumioGetState Feb 07 13:22:54 volumio volumio[30562]: info: CorePlayQueue::getTrack 0 Feb 07 13:22:54 volumio volumio[30562]: info: MPD Permissions set Feb 07 13:22:54 volumio volumio[30562]: info: MPD Permissions set Feb 07 13:22:54 volumio volumio[30562]: info: Upmpdcli Daemon Started Feb 07 13:22:54 volumio volumio[30562]: info: Discovery: this is already registered, 921648ed-0557-4d70-9927-1420682efcc7 Feb 07 13:22:54 volumio volumio[30562]: info: Discovery: Found device Volumio Feb 07 13:22:54 volumio volumio[30562]: info: CoreCommandRouter::volumioGetState Feb 07 13:22:54 volumio volumio[30562]: info: CorePlayQueue::getTrack 0 Feb 07 13:22:54 volumio volumio[30562]: info: Volumio called home Feb 07 13:22:54 volumio volumio[30562]: info: Spotify config file written Feb 07 13:22:54 volumio sudo[30797]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 07 13:22:55 volumio sudo[30797]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 13:22:55 volumio volumio[30562]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Feb 07 13:22:55 volumio volumio[30562]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:55 volumio volumio[30562]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:55 volumio volumio[30562]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:55 volumio volumio[30562]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:55 volumio volumio[30562]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:55 volumio volumio[30562]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:55 volumio volumio[30562]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:55 volumio volumio[30562]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:55 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 13:22:55 volumio volumio[30562]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 13:22:55 volumio volumio[30562]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:55 volumio volumio[30562]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:55 volumio volumio[30562]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:55 volumio volumio[30562]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:55 volumio volumio[30562]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:55 volumio volumio[30562]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:55 volumio volumio[30562]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:55 volumio volumio[30562]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:55 volumio volumio[30562]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:55 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 13:22:55 volumio go-librespot[30804]: go-librespot daemon starting... Feb 07 13:22:55 volumio sudo[30797]: pam_unix(sudo:session): session closed for user root Feb 07 13:22:55 volumio volumio[30562]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 13:22:55 volumio volumio[30562]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:22:55 volumio volumio[30562]: info: No need to fix Spotify hosts Feb 07 13:22:55 volumio go-librespot[30811]: time="2026-02-07T13:22:55+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 13:22:55 volumio go-librespot[30811]: time="2026-02-07T13:22:55+07:00" level=debug msg="app state loaded" Feb 07 13:22:55 volumio go-librespot[30811]: time="2026-02-07T13:22:55+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 13:22:55 volumio go-librespot[30811]: time="2026-02-07T13:22:55+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 07 13:22:55 volumio go-librespot[30811]: time="2026-02-07T13:22:55+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 07 13:22:55 volumio go-librespot[30811]: time="2026-02-07T13:22:55+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 07 13:22:56 volumio go-librespot[30811]: time="2026-02-07T13:22:56+07:00" level=info msg="zeroconf server listening on port 36303" Feb 07 13:22:56 volumio go-librespot[30811]: time="2026-02-07T13:22:56+07:00" level=debug msg="obtained new client token: AAAvZBOL8YADe0SgVrta3xB28hxREW2tcthRdGMgza3hFZ8idpctYMRiMyeWD/gpnIFBOYOdzuXC+aG6l9iixfNVl7636gkz+Tsc42WvUSDDopQbZK/pE3EVVv4At4Lla+cffA+jg7JgYwCIlsB9ELTASIIlUCAbFFwS33zeknH0wzbSxWBZJfyU0/RuT4up9NwsZdoNQpResvkVqn/HUpcQ2cJJA57jOha6FCiKWOGn4z5kfJw2DXw=" Feb 07 13:22:56 volumio go-librespot[30811]: time="2026-02-07T13:22:56+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 13:22:56 volumio volumio[30562]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 07 13:22:56 volumio volumio[30562]: SPOTIFY: BQBj7sY3_2csf_tnToF_vPHxd5tteW5JMyxprchDuO8qN8kVpU7yIPYGvoIOpeBhyQTJ0YJaYBIXcB52PLTGposL1mOZal821-R_j7qkh5wQpjkN0B4lS5BDhSB5uVVYsL3dDnFgstVmeA9PjlBY5XAL8lZPDvRoMnatHwLnTjNf4xYZgGgs4io3vORVp7hws-jWqbKSEWuMnAOSbgjwzgP4sr9UZrFhgv8rEQUI1JTs4-YipYgWg7de6XBM2dCiE7eYTt5CE5ANkUkx1Glb-RJzNY9nYiYczucggr2llvrhMypS7-MNqDlO Feb 07 13:22:56 volumio volumio[30562]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 07 13:22:56 volumio volumio[30562]: info: New Spotify access token = BQBj7sY3_2csf_tnToF_vPHxd5tteW5JMyxprchDuO8qN8kVpU7yIPYGvoIOpeBhyQTJ0YJaYBIXcB52PLTGposL1mOZal821-R_j7qkh5wQpjkN0B4lS5BDhSB5uVVYsL3dDnFgstVmeA9PjlBY5XAL8lZPDvRoMnatHwLnTjNf4xYZgGgs4io3vORVp7hws-jWqbKSEWuMnAOSbgjwzgP4sr9UZrFhgv8rEQUI1JTs4-YipYgWg7de6XBM2dCiE7eYTt5CE5ANkUkx1Glb-RJzNY9nYiYczucggr2llvrhMypS7-MNqDlO Feb 07 13:22:56 volumio volumio[30562]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 07 13:22:56 volumio go-librespot[30811]: time="2026-02-07T13:22:56+07:00" level=debug msg="completed keyexchange" Feb 07 13:22:56 volumio go-librespot[30811]: time="2026-02-07T13:22:56+07:00" level=debug msg="completed challenge" Feb 07 13:22:56 volumio volumio[30562]: info: Starting Shairport Sync Feb 07 13:22:56 volumio volumio[30562]: info: Starting Shairport Sync Feb 07 13:22:56 volumio volumio[30562]: info: Starting Shairport Sync Feb 07 13:22:56 volumio go-librespot[30811]: time="2026-02-07T13:22:56+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 13:22:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 13:22:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 13:22:56 volumio sudo[30837]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 07 13:22:56 volumio sudo[30837]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 13:22:56 volumio sudo[30839]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 07 13:22:56 volumio sudo[30839]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 13:22:56 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 07 13:22:56 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 07 13:22:56 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 07 13:22:56 volumio systemd[1]: shairport-sync.service: Consumed 2.202s CPU time. Feb 07 13:22:56 volumio sudo[30842]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 07 13:22:56 volumio sudo[30842]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 13:22:56 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 07 13:22:56 volumio sudo[30837]: pam_unix(sudo:session): session closed for user root Feb 07 13:22:56 volumio volumio[30562]: info: Shairport-Sync Started Feb 07 13:22:56 volumio volumio[30562]: Error adding Membership: Error: addMembership EINVAL Feb 07 13:22:57 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 07 13:22:57 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 07 13:22:57 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 07 13:22:57 volumio volumio[30562]: info: CoreCommandRouter::volumioGetState Feb 07 13:22:57 volumio volumio[30562]: info: CorePlayQueue::getTrack 0 Feb 07 13:22:57 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 07 13:22:57 volumio sudo[30839]: pam_unix(sudo:session): session closed for user root Feb 07 13:22:57 volumio sudo[30842]: pam_unix(sudo:session): session closed for user root Feb 07 13:22:57 volumio volumio[30562]: info: Shairport-Sync Started Feb 07 13:22:57 volumio volumio[30562]: info: Shairport-Sync Started Feb 07 13:22:57 volumio volumio[30562]: SPOTIFY: User informations: {"country":"PK","display_name":"215mmgea6kss3jxeqsqdquvji","email":"linh.ngotran@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/215mmgea6kss3jxeqsqdquvji"},"followers":{"href":null,"total":1},"href":"https://api.spotify.com/v1/users/215mmgea6kss3jxeqsqdquvji","id":"215mmgea6kss3jxeqsqdquvji","images":[],"product":"premium","type":"user","uri":"spotify:user:215mmgea6kss3jxeqsqdquvji"} Feb 07 13:22:57 volumio volumio[30562]: info: Spotify Successfully logged in Feb 07 13:22:57 volumio volumio[30562]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 13:22:57 volumio volumio[30562]: info: [1770445377223] CoreMusicLibrary::Adding element Spotify Feb 07 13:22:57 volumio volumio[30562]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 13:22:57 volumio volumio[30562]: Cannot find translation for source YouTube Music Feb 07 13:22:57 volumio volumio[30562]: Cannot find translation for source Spotify Feb 07 13:22:57 volumio volumio[30562]: info: [yt-cast-receiver] DIAL server listening on port 8098 Feb 07 13:22:57 volumio volumio[30562]: info: CoreCommandRouter::volumioRetrievevolume Feb 07 13:22:58 volumio volumio[30562]: info: VolumeController:: Volume=100 Mute =false Feb 07 13:22:58 volumio volumio[30562]: info: CoreCommandRouter::volumioGetState Feb 07 13:22:58 volumio volumio[30562]: info: CorePlayQueue::getTrack 0 Feb 07 13:22:58 volumio volumio[30562]: info: CoreStateMachine::pushState Feb 07 13:22:58 volumio volumio[30562]: info: CorePlayQueue::getTrack 0 Feb 07 13:22:58 volumio volumio[30562]: info: CoreCommandRouter::volumioPushState Feb 07 13:22:59 volumio volumio[30562]: info: go-librespot daemon successfully initialized Feb 07 13:22:59 volumio mpd[30789]: 2026-02-07T13:22:59 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 07 13:22:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 51. Feb 07 13:22:59 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 07 13:22:59 volumio sudo[30741]: pam_unix(sudo:session): session closed for user root Feb 07 13:22:59 volumio sudo[30759]: pam_unix(sudo:session): session closed for user root Feb 07 13:22:59 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 13:22:59 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 13:22:59 volumio go-librespot[30892]: go-librespot daemon starting... Feb 07 13:22:59 volumio go-librespot[30895]: time="2026-02-07T13:22:59+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 13:22:59 volumio go-librespot[30895]: time="2026-02-07T13:22:59+07:00" level=debug msg="app state loaded" Feb 07 13:22:59 volumio go-librespot[30895]: time="2026-02-07T13:22:59+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 13:22:59 volumio volumio[30562]: error: MPD error: The expression evaluated to a falsy value: Feb 07 13:22:59 volumio volumio[30562]: assert.ok(self.idling) Feb 07 13:22:59 volumio volumio[30562]: error: The expression evaluated to a falsy value: Feb 07 13:22:59 volumio volumio[30562]: assert.ok(self.idling) Feb 07 13:22:59 volumio volumio[30562]: info: MPD running with PID30789 Feb 07 13:22:59 volumio volumio[30562]: ,establishing connection Feb 07 13:22:59 volumio volumio[30562]: error: updateQueue error: null Feb 07 13:23:00 volumio volumio[30562]: info: Completed starting Core Plugins Feb 07 13:23:00 volumio volumio[30562]: info: ------------------------------------------- Feb 07 13:23:00 volumio volumio[30562]: info: ----- MyVolumio plugins startup ---- Feb 07 13:23:00 volumio volumio[30562]: info: ------------------------------------------- Feb 07 13:23:00 volumio volumio[30562]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 07 13:23:00 volumio volumio[30562]: error: updateQueue error: null Feb 07 13:23:00 volumio go-librespot[30895]: time="2026-02-07T13:23:00+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 13:23:00 volumio go-librespot[30895]: time="2026-02-07T13:23:00+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 07 13:23:00 volumio go-librespot[30895]: time="2026-02-07T13:23:00+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 07 13:23:00 volumio go-librespot[30895]: time="2026-02-07T13:23:00+07:00" level=info msg="zeroconf server listening on port 38611" Feb 07 13:23:00 volumio go-librespot[30895]: time="2026-02-07T13:23:00+07:00" level=debug msg="obtained new client token: AACqt6GwHLbvPW9AkIDNEq/faZc/4OrLeWS5ZB3zNDTEFtHGHLBxrB55eRuDl9TImNMkzvEpJhNU4wcGLevkb5pk5DcfNBlSnH2ssmU+xP9oVLZSK1+sSRSzofQFlRY1+EV31flkyHf4rR4Hqc4hOMnKf+ithB8nkYVDINrfkt2Plw4KUCkAe0fAoqRV+NfxsFTzhkHm++4VeIArB1XkSBaESNiKmkobY3JuJ9Mf6uURhlxZXRWoizxXbQ==" Feb 07 13:23:00 volumio go-librespot[30895]: time="2026-02-07T13:23:00+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 13:23:00 volumio go-librespot[30895]: time="2026-02-07T13:23:00+07:00" level=debug msg="completed keyexchange" Feb 07 13:23:00 volumio go-librespot[30895]: time="2026-02-07T13:23:00+07:00" level=debug msg="completed challenge" Feb 07 13:23:00 volumio go-librespot[30895]: time="2026-02-07T13:23:00+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 13:23:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 13:23:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 13:23:02 volumio volumio[30562]: info: Initializing connection to go-librespot Websocket Feb 07 13:23:02 volumio volumio[30562]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 07 13:23:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 52. Feb 07 13:23:04 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 13:23:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 13:23:04 volumio go-librespot[30903]: go-librespot daemon starting... Feb 07 13:23:04 volumio go-librespot[30904]: time="2026-02-07T13:23:04+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 13:23:04 volumio go-librespot[30904]: time="2026-02-07T13:23:04+07:00" level=debug msg="app state loaded" Feb 07 13:23:04 volumio go-librespot[30904]: time="2026-02-07T13:23:04+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 13:23:04 volumio go-librespot[30904]: time="2026-02-07T13:23:04+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 13:23:04 volumio go-librespot[30904]: time="2026-02-07T13:23:04+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 07 13:23:04 volumio go-librespot[30904]: time="2026-02-07T13:23:04+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 07 13:23:04 volumio go-librespot[30904]: time="2026-02-07T13:23:04+07:00" level=info msg="zeroconf server listening on port 39963" Feb 07 13:23:04 volumio volumio[30562]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 07 13:23:04 volumio go-librespot[30904]: time="2026-02-07T13:23:04+07:00" level=debug msg="obtained new client token: AACr6uZIzRzpB1vKNRWqVrKYUl4zpTq7uts4c3MPLx+xOuVeaVxKZ78GmuPEllN4hVLxpBZeImsmbEA44WGj4io1ca3HfR8xI4larX02NM8hUzYKR1T5ptcF0G4Z+Z9DpRUR3wEi0AKEEnlAHSBxlt5thnzCe8D6f196eNLHxvljCKl8cawnBREBUXJD+HVbtQA65rdmSzB5KVrJ0FbdlWLxmkBzchlYXizc3QNKiXWlnR9gVgtSbXRpiw==" Feb 07 13:23:05 volumio go-librespot[30904]: time="2026-02-07T13:23:05+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 13:23:05 volumio go-librespot[30904]: time="2026-02-07T13:23:05+07:00" level=debug msg="completed keyexchange" Feb 07 13:23:05 volumio go-librespot[30904]: time="2026-02-07T13:23:05+07:00" level=debug msg="completed challenge" Feb 07 13:23:05 volumio go-librespot[30904]: time="2026-02-07T13:23:05+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 13:23:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 13:23:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 13:23:05 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Feb 07 13:23:05 volumio volumio[30562]: info: Initializing connection to go-librespot Websocket Feb 07 13:23:05 volumio volumio[30562]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 07 13:23:07 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Feb 07 13:23:07 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Feb 07 13:23:07 volumio systemd[1]: setdatetime-helper.service: Consumed 1.734s CPU time. Feb 07 13:23:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 53. Feb 07 13:23:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 13:23:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 13:23:08 volumio go-librespot[30937]: go-librespot daemon starting... Feb 07 13:23:08 volumio go-librespot[30938]: time="2026-02-07T13:23:08+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 13:23:08 volumio go-librespot[30938]: time="2026-02-07T13:23:08+07:00" level=debug msg="app state loaded" Feb 07 13:23:08 volumio go-librespot[30938]: time="2026-02-07T13:23:08+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 13:23:08 volumio volumio[30562]: info: Initializing connection to go-librespot Websocket Feb 07 13:23:08 volumio go-librespot[30938]: time="2026-02-07T13:23:08+07:00" level=debug msg="new websocket client" Feb 07 13:23:08 volumio volumio[30562]: info: Connection to go-librespot Websocket established Feb 07 13:23:08 volumio volumio[30562]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 07 13:23:08 volumio volumio[30562]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 07 13:23:08 volumio volumio[30562]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 07 13:23:08 volumio volumio[30562]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 07 13:23:08 volumio volumio[30562]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 07 13:23:08 volumio volumio[30562]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 07 13:23:08 volumio volumio[30562]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 07 13:23:08 volumio volumio[30562]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 07 13:23:08 volumio volumio[30562]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 07 13:23:08 volumio volumio[30562]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 07 13:23:08 volumio volumio[30562]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 07 13:23:08 volumio volumio[30562]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 07 13:23:08 volumio volumio[30562]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 07 13:23:08 volumio volumio[30562]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 07 13:23:08 volumio volumio[30562]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 07 13:23:08 volumio volumio[30562]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 07 13:23:08 volumio volumio[30562]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 07 13:23:08 volumio volumio[30562]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 07 13:23:08 volumio volumio[30562]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 07 13:23:08 volumio volumio[30562]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 07 13:23:08 volumio volumio[30562]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 07 13:23:08 volumio volumio[30562]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 07 13:23:08 volumio volumio[30562]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 07 13:23:08 volumio volumio[30562]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 07 13:23:08 volumio volumio[30562]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 07 13:23:08 volumio volumio[30562]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 07 13:23:08 volumio volumio[30562]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 07 13:23:08 volumio volumio[30562]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 07 13:23:08 volumio volumio[30562]: info: Adding plugin bluetooth to MyMusic Plugins Feb 07 13:23:08 volumio volumio[30562]: info: Adding plugin multiroom to MyMusic Plugins Feb 07 13:23:08 volumio volumio[30562]: info: Adding plugin metavolumio to MyMusic Plugins Feb 07 13:23:08 volumio volumio[30562]: info: Adding plugin cd_controller to MyMusic Plugins Feb 07 13:23:08 volumio volumio[30562]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 07 13:23:08 volumio volumio[30562]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 07 13:23:08 volumio volumio[30562]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 07 13:23:08 volumio volumio[30562]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 07 13:23:08 volumio go-librespot[30938]: time="2026-02-07T13:23:08+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 13:23:08 volumio go-librespot[30938]: time="2026-02-07T13:23:08+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 07 13:23:08 volumio go-librespot[30938]: time="2026-02-07T13:23:08+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 07 13:23:08 volumio go-librespot[30938]: time="2026-02-07T13:23:08+07:00" level=info msg="zeroconf server listening on port 46015" Feb 07 13:23:09 volumio go-librespot[30938]: time="2026-02-07T13:23:09+07:00" level=debug msg="obtained new client token: AAAzTEW2lZ7fiBHPf/TKV95BtY5uKLPItM/jwiAGrbYEHWxFtBRAM5XLiWOsDib5698IBdDKxsGhtyI0rwneNhCC9zBPIjaC2bhezfzu8aOJ6EvPWHb99Br0UC2a61YNsP1vZpJGqdknKFzLYHgyKNOA+WA55T/jxMI7LLcmAspJg/+MzA5ikxlsQk7FOjovbM+se8hOZEcbit7zkOGs/QKok7sUG7ZMiMgkFg1qXoZ3E+1gGu9mRAo=" Feb 07 13:23:09 volumio go-librespot[30938]: time="2026-02-07T13:23:09+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 13:23:09 volumio go-librespot[30938]: time="2026-02-07T13:23:09+07:00" level=debug msg="completed keyexchange" Feb 07 13:23:09 volumio go-librespot[30938]: time="2026-02-07T13:23:09+07:00" level=debug msg="completed challenge" Feb 07 13:23:09 volumio go-librespot[30938]: time="2026-02-07T13:23:09+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 13:23:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 13:23:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 13:23:10 volumio volumio[30562]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 07 13:23:10 volumio volumio[30562]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 07 13:23:10 volumio volumio[30562]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 13:23:10 volumio volumio[30562]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 13:23:10 volumio volumio[30562]: info: Starting MyVolumio Remote Streaming Endpoints Feb 07 13:23:10 volumio volumio[30562]: info: MyVolumio login type: Token Feb 07 13:23:10 volumio volumio[30562]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 07 13:23:10 volumio volumio[30562]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 07 13:23:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 54. Feb 07 13:23:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 13:23:12 volumio volumio[30562]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 07 13:23:12 volumio volumio[30562]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 07 13:23:12 volumio volumio[30562]: info: Streaming services startup Feb 07 13:23:12 volumio volumio[30562]: info: Starting Streaming Daemon Feb 07 13:23:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 13:23:12 volumio go-librespot[30961]: go-librespot daemon starting... Feb 07 13:23:12 volumio sudo[30963]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 07 13:23:12 volumio volumio[30562]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 07 13:23:12 volumio sudo[30963]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 13:23:12 volumio go-librespot[30964]: time="2026-02-07T13:23:12+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 13:23:12 volumio go-librespot[30964]: time="2026-02-07T13:23:12+07:00" level=debug msg="app state loaded" Feb 07 13:23:12 volumio go-librespot[30964]: time="2026-02-07T13:23:12+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 13:23:12 volumio sudo[30963]: pam_unix(sudo:session): session closed for user root Feb 07 13:23:12 volumio volumio[30562]: info: Getting Spotify volume Feb 07 13:23:12 volumio volumio[30562]: info: Connection to go-librespot Websocket closed Feb 07 13:23:12 volumio volumio[30562]: error: Cannot start Volumio Streaming Daemon Feb 07 13:23:12 volumio volumio[30562]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 07 13:23:12 volumio volumio[30562]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 07 13:23:12 volumio volumio[30562]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Feb 07 13:23:13 volumio volumio[30562]: info: CoreCommandRouter::volumioGetState Feb 07 13:23:13 volumio volumio[30562]: info: CorePlayQueue::getTrack 0 Feb 07 13:23:13 volumio volumio[30562]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 07 13:23:13 volumio volumio[30562]: SPOTIFY: SPOTIFY VOLUME undefined Feb 07 13:23:13 volumio volumio[30562]: SPOTIFY: VOLUMIO VOLUME 100 Feb 07 13:23:13 volumio volumio[30562]: info: Aligning Spotify Volume to Volumio Volume Feb 07 13:23:13 volumio volumio[30562]: info: CoreCommandRouter::volumioGetState Feb 07 13:23:13 volumio volumio[30562]: info: CorePlayQueue::getTrack 0 Feb 07 13:23:13 volumio volumio[30562]: info: Setting Spotify Volume from Volumio: 100 Feb 07 13:23:13 volumio go-librespot[30964]: time="2026-02-07T13:23:13+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 13:23:13 volumio go-librespot[30964]: time="2026-02-07T13:23:13+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 07 13:23:13 volumio go-librespot[30964]: time="2026-02-07T13:23:13+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 07 13:23:13 volumio go-librespot[30964]: time="2026-02-07T13:23:13+07:00" level=info msg="zeroconf server listening on port 34907" Feb 07 13:23:13 volumio go-librespot[30964]: time="2026-02-07T13:23:13+07:00" level=debug msg="obtained new client token: AADe87pEl12T0x5ve2zICvfjMI5Clq/npYGhllSvCCMxL975JZmsQD8ZKKhLIfnhre3bljILrRuB1KSqZS6TjZjXjI4Ciglu2IjqSsu2GgSVQcSWrDbCQFmzZLsk2zAvhL03Y/yMlW+yeRut4hZOIuDnbVTqbjhpAJXhytt4VVt3t6Zqhe8pfalDTG4YROdeHXMu2BPkOrfGFX8OPDQ5pDmmW8aiWoBVI+Poq4wfnKfphPTGYkdQ0zxh0Q==" Feb 07 13:23:13 volumio go-librespot[30964]: time="2026-02-07T13:23:13+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 13:23:13 volumio volumio[30562]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 07 13:23:14 volumio go-librespot[30964]: time="2026-02-07T13:23:14+07:00" level=debug msg="completed keyexchange" Feb 07 13:23:14 volumio go-librespot[30964]: time="2026-02-07T13:23:14+07:00" level=debug msg="completed challenge" Feb 07 13:23:14 volumio go-librespot[30964]: time="2026-02-07T13:23:14+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 13:23:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 13:23:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 13:23:14 volumio volumio[30562]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 13:23:14 volumio volumio[30562]: Error: socket hang up Feb 07 13:23:14 volumio volumio[30562]: at connResetException (node:internal/errors:720:14) Feb 07 13:23:14 volumio volumio[30562]: at Socket.socketOnEnd (node:_http_client:519:23) Feb 07 13:23:14 volumio volumio[30562]: at Socket.emit (node:events:526:35) Feb 07 13:23:14 volumio volumio[30562]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 07 13:23:14 volumio volumio[30562]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Feb 07 13:23:14 volumio volumio[30562]: code: 'ECONNRESET', Feb 07 13:23:14 volumio volumio[30562]: response: undefined Feb 07 13:23:14 volumio volumio[30562]: } Feb 07 13:23:14 volumio volumio[30562]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 13:23:15 volumio sudo[30990]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-07 13:22' Feb 07 13:23:15 volumio sudo[30990]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 13:23:16 volumio sudo[30990]: pam_unix(sudo:session): session closed for user root Feb 07 13:23:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 55. Feb 07 13:23:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 13:23:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 13:23:17 volumio go-librespot[30998]: go-librespot daemon starting... Feb 07 13:23:17 volumio volumio-remote-updater[643]: [2026-02-07 13:23:17] [error] handle_read_frame error: asio.system:104 (Connection reset by peer) Feb 07 13:23:17 volumio volumio-remote-updater[643]: [2026-02-07 13:23:17] [disconnect] Disconnect close local:[1006,Connection reset by peer] remote:[1006] Feb 07 13:23:17 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 07 13:23:17 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 07 13:23:17 volumio systemd[1]: volumio.service: Consumed 56.204s CPU time. Feb 07 13:23:17 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 07 13:23:17 volumio go-librespot[30999]: time="2026-02-07T13:23:17+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 13:23:17 volumio go-librespot[30999]: time="2026-02-07T13:23:17+07:00" level=debug msg="app state loaded" Feb 07 13:23:17 volumio go-librespot[30999]: time="2026-02-07T13:23:17+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 13:23:17 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 07 13:23:17 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 5827. Feb 07 13:23:17 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 07 13:23:17 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 07 13:23:17 volumio systemd[1]: volumio.service: Consumed 56.204s CPU time. Feb 07 13:23:17 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 07 13:23:17 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 07 13:23:18 volumio go-librespot[30999]: time="2026-02-07T13:23:18+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 13:23:18 volumio go-librespot[30999]: time="2026-02-07T13:23:18+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 07 13:23:18 volumio go-librespot[30999]: time="2026-02-07T13:23:18+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 07 13:23:18 volumio go-librespot[30999]: time="2026-02-07T13:23:18+07:00" level=info msg="zeroconf server listening on port 37575" Feb 07 13:23:18 volumio go-librespot[30999]: time="2026-02-07T13:23:18+07:00" level=debug msg="obtained new client token: AAB1P16uh7ew5AJJeWXCwazekX5e8mr1VQOCKk8Wrt9Rn/1XtyXfJV8HBqloMGj7UMEUOyOG/uYRuTvC+AyE7GkULraQMy3PaYNZ8Lft4ZaU8fUVh5+b2ydXiAKOAiYgrATyE5PYUg9m5+P2DonsnUqFl2aGiQzxa3cbzvLcrxbaju+ZQ4w1Ueszb9nbmhmAGAXuSqmRS1ULmyINzw0ux/eBS4N8HS9dzH5PJP7FlW+Y3bHusbkSrnGOkQ==" Feb 07 13:23:18 volumio go-librespot[30999]: time="2026-02-07T13:23:18+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 13:23:18 volumio go-librespot[30999]: time="2026-02-07T13:23:18+07:00" level=debug msg="completed keyexchange" Feb 07 13:23:18 volumio go-librespot[30999]: time="2026-02-07T13:23:18+07:00" level=debug msg="completed challenge" Feb 07 13:23:18 volumio go-librespot[30999]: time="2026-02-07T13:23:18+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 13:23:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 13:23:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 13:23:21 volumio volumio[31018]: info: ------------------------------------------- Feb 07 13:23:21 volumio volumio[31018]: info: ----- Volumio3 ---- Feb 07 13:23:21 volumio volumio[31018]: info: ------------------------------------------- Feb 07 13:23:21 volumio volumio[31018]: info: ----- System startup ---- Feb 07 13:23:21 volumio volumio[31018]: info: ------------------------------------------- Feb 07 13:23:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 56. Feb 07 13:23:22 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 13:23:22 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 13:23:22 volumio go-librespot[31040]: go-librespot daemon starting... Feb 07 13:23:22 volumio go-librespot[31041]: time="2026-02-07T13:23:22+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 13:23:22 volumio go-librespot[31041]: time="2026-02-07T13:23:22+07:00" level=debug msg="app state loaded" Feb 07 13:23:22 volumio go-librespot[31041]: time="2026-02-07T13:23:22+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 13:23:22 volumio volumio-remote-updater[643]: [2026-02-07 13:23:22] [connect] Successful connection Feb 07 13:23:22 volumio go-librespot[31041]: time="2026-02-07T13:23:22+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 13:23:22 volumio go-librespot[31041]: time="2026-02-07T13:23:22+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 07 13:23:22 volumio go-librespot[31041]: time="2026-02-07T13:23:22+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 07 13:23:22 volumio go-librespot[31041]: time="2026-02-07T13:23:22+07:00" level=info msg="zeroconf server listening on port 37965" Feb 07 13:23:23 volumio volumio[31018]: info: MYVOLUMIO Environment detected Feb 07 13:23:23 volumio go-librespot[31041]: time="2026-02-07T13:23:23+07:00" level=debug msg="obtained new client token: AAAVEnLaAyHJ4OjbiIyy4RPBgb6YFUbtJ8l4h0s7ank4mIx1fCQXDpg0Wg+CwgGninQdfEHbR8aMuf3d0cVh2xm7M2wNGTYoN71VJj/Y/mLy5/1gp4bHwlmah3lrjG2o7c4Np4SwcHqujP2d2MDTsdBV8MWKa1fSxcW+45839gxc9kZ0pxOU+B1xIikcCU0fRLd5SK92hexTpd8k6hDjDEHChqc0utqWT4qUnnE8JYuh86uxDCko7f0=" Feb 07 13:23:23 volumio volumio[31018]: info: Plugin folders cleanup Feb 07 13:23:23 volumio volumio[31018]: info: Scanning into folder /volumio/app/plugins/ Feb 07 13:23:23 volumio volumio[31018]: info: Scanning category audio_interface Feb 07 13:23:23 volumio volumio[31018]: info: Scanning category miscellanea Feb 07 13:23:23 volumio volumio[31018]: info: Scanning category music_service Feb 07 13:23:23 volumio volumio[31018]: info: Scanning category plugins.json Feb 07 13:23:23 volumio volumio[31018]: info: Scanning category system_controller Feb 07 13:23:23 volumio volumio[31018]: info: Scanning category user_interface Feb 07 13:23:23 volumio volumio[31018]: info: Scanning into folder /data/plugins/ Feb 07 13:23:23 volumio volumio[31018]: info: Scanning category music_service Feb 07 13:23:23 volumio volumio[31018]: info: Plugin folders cleanup completed Feb 07 13:23:23 volumio volumio[31018]: info: ------------------------------------------- Feb 07 13:23:23 volumio volumio[31018]: info: ----- Core plugins startup ---- Feb 07 13:23:23 volumio volumio[31018]: info: ------------------------------------------- Feb 07 13:23:23 volumio volumio[31018]: info: Loading plugins from folder /volumio/app/plugins/ Feb 07 13:23:23 volumio volumio[31018]: info: Adding plugin upnp to MyMusic Plugins Feb 07 13:23:23 volumio volumio[31018]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 07 13:23:23 volumio volumio[31018]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 07 13:23:23 volumio volumio[31018]: info: Loading plugins from folder /data/plugins/ Feb 07 13:23:23 volumio volumio[31018]: info: Loading plugin "system"... Feb 07 13:23:23 volumio go-librespot[31041]: time="2026-02-07T13:23:23+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 13:23:23 volumio volumio[31018]: info: Loading plugin "appearance"... Feb 07 13:23:23 volumio go-librespot[31041]: time="2026-02-07T13:23:23+07:00" level=debug msg="completed keyexchange" Feb 07 13:23:23 volumio go-librespot[31041]: time="2026-02-07T13:23:23+07:00" level=debug msg="completed challenge" Feb 07 13:23:23 volumio go-librespot[31041]: time="2026-02-07T13:23:23+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 13:23:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 13:23:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 13:23:25 volumio volumio[31018]: info: Loading plugin "network"... Feb 07 13:23:25 volumio volumio[31018]: info: Refreshing Cached IP Addresses Feb 07 13:23:25 volumio sudo[31055]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 07 13:23:25 volumio sudo[31055]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 13:23:25 volumio sudo[31057]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 07 13:23:25 volumio volumio[31018]: info: Loading plugin "services"... Feb 07 13:23:25 volumio sudo[31057]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 13:23:25 volumio sudo[31055]: pam_unix(sudo:session): session closed for user root Feb 07 13:23:25 volumio sudo[31057]: pam_unix(sudo:session): session closed for user root Feb 07 13:23:25 volumio volumio[31018]: info: Loading plugin "alsa_controller"... Feb 07 13:23:25 volumio sudo[31065]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 07 13:23:25 volumio sudo[31065]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 13:23:25 volumio volumio[31018]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 07 13:23:25 volumio volumio[31018]: info: Loading plugin "wizard"... Feb 07 13:23:25 volumio volumio[31018]: info: Loading plugin "networkfs"... Feb 07 13:23:25 volumio volumio[31018]: info: Starting Udev Watcher for removable devices Feb 07 13:23:25 volumio volumio[31018]: info: Ignoring mount for partition: boot Feb 07 13:23:25 volumio volumio[31018]: info: Ignoring mount for partition: volumio Feb 07 13:23:25 volumio volumio[31018]: info: Ignoring mount for partition: volumio_data Feb 07 13:23:25 volumio volumio[31018]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 07 13:23:25 volumio volumio[31018]: info: Loading plugin "volumio_command_line_client"... Feb 07 13:23:25 volumio volumio[31018]: info: Loading plugin "upnp"... Feb 07 13:23:25 volumio volumio[31018]: info: [1770445405561] Starting Upmpd Daemon Feb 07 13:23:25 volumio volumio[31018]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 07 13:23:25 volumio volumio[31018]: info: Loading plugin "my_music"... Feb 07 13:23:25 volumio volumio[31018]: info: Loading plugin "mpd"... Feb 07 13:23:26 volumio volumio[31018]: info: Loading plugin "upnp_browser"... Feb 07 13:23:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 57. Feb 07 13:23:26 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 13:23:26 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 13:23:26 volumio go-librespot[31088]: go-librespot daemon starting... Feb 07 13:23:26 volumio go-librespot[31089]: time="2026-02-07T13:23:26+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 13:23:26 volumio go-librespot[31089]: time="2026-02-07T13:23:26+07:00" level=debug msg="app state loaded" Feb 07 13:23:26 volumio go-librespot[31089]: time="2026-02-07T13:23:26+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 13:23:27 volumio go-librespot[31089]: time="2026-02-07T13:23:27+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 13:23:27 volumio go-librespot[31089]: time="2026-02-07T13:23:27+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 07 13:23:27 volumio go-librespot[31089]: time="2026-02-07T13:23:27+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 07 13:23:27 volumio go-librespot[31089]: time="2026-02-07T13:23:27+07:00" level=info msg="zeroconf server listening on port 37571" Feb 07 13:23:27 volumio go-librespot[31089]: time="2026-02-07T13:23:27+07:00" level=debug msg="obtained new client token: AAD/oW1MIxpDRI/4C3SmbZfknw/jT8O8DEorD/jGGud6kjDg20/wdUhVweO91BRA/YOK//EfED2iP9Om7R7gyQjekzIWV5x+xWyTrO0xVHFe33h94t1xbFjhJEWwf3idW9P5zjJznh6lPw9Bcm1Jxs4EUynqfftq9HXzHXII8DffM65NYtS+cA9pFJTtbeYqEqLikz4WK4kA+1FO1pjb4zFuCmsn9Xx6PReyRYJMKi3Vp9p/p1OSO4na4w==" Feb 07 13:23:27 volumio go-librespot[31089]: time="2026-02-07T13:23:27+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 13:23:27 volumio go-librespot[31089]: time="2026-02-07T13:23:27+07:00" level=debug msg="completed keyexchange" Feb 07 13:23:27 volumio go-librespot[31089]: time="2026-02-07T13:23:27+07:00" level=debug msg="completed challenge" Feb 07 13:23:27 volumio go-librespot[31089]: time="2026-02-07T13:23:27+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 13:23:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 13:23:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 13:23:28 volumio sudo[31065]: pam_unix(sudo:session): session closed for user root Feb 07 13:23:29 volumio volumio[31018]: info: Starting UPNP Browser Feb 07 13:23:29 volumio volumio[31018]: info: Loading plugin "alarm-clock"... Feb 07 13:23:29 volumio volumio[31018]: info: Loading plugin "airplay_emulation"... Feb 07 13:23:29 volumio volumio[31018]: info: Starting Shairport Sync Feb 07 13:23:29 volumio volumio[31018]: info: Loading plugin "last_100"... Feb 07 13:23:29 volumio volumio[31018]: info: Loading plugin "webradio"... Feb 07 13:23:29 volumio volumio[31018]: info: Loading plugin "i2s_dacs"... Feb 07 13:23:29 volumio volumio[31018]: info: Loading plugin "volumiodiscovery"... Feb 07 13:23:29 volumio volumio[31018]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 07 13:23:29 volumio volumio[31018]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 13:23:29 volumio node[31018]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 07 13:23:29 volumio volumio[31018]: *** WARNING *** For more information see Feb 07 13:23:29 volumio volumio[31018]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 07 13:23:29 volumio volumio[31018]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 13:23:29 volumio volumio[31018]: *** WARNING *** For more information see Feb 07 13:23:29 volumio node[31018]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 13:23:29 volumio node[31018]: *** WARNING *** For more information see Feb 07 13:23:29 volumio node[31018]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 07 13:23:29 volumio node[31018]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 13:23:29 volumio node[31018]: *** WARNING *** For more information see Feb 07 13:23:29 volumio volumio[31018]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 07 13:23:29 volumio volumio[31018]: info: Discovery: Started advertising with name: Volumio Feb 07 13:23:29 volumio volumio[31018]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 07 13:23:29 volumio volumio[31018]: info: Loading plugin "spop"... Feb 07 13:23:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 58. Feb 07 13:23:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 13:23:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 13:23:31 volumio go-librespot[31098]: go-librespot daemon starting... Feb 07 13:23:31 volumio go-librespot[31099]: time="2026-02-07T13:23:31+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 13:23:31 volumio go-librespot[31099]: time="2026-02-07T13:23:31+07:00" level=debug msg="app state loaded" Feb 07 13:23:31 volumio go-librespot[31099]: time="2026-02-07T13:23:31+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 13:23:31 volumio volumio[31018]: info: Loading plugin "ytcr"... Feb 07 13:23:31 volumio go-librespot[31099]: time="2026-02-07T13:23:31+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 13:23:31 volumio go-librespot[31099]: time="2026-02-07T13:23:31+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 07 13:23:31 volumio go-librespot[31099]: time="2026-02-07T13:23:31+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 07 13:23:31 volumio go-librespot[31099]: time="2026-02-07T13:23:31+07:00" level=info msg="zeroconf server listening on port 38643" Feb 07 13:23:32 volumio go-librespot[31099]: time="2026-02-07T13:23:32+07:00" level=debug msg="obtained new client token: AAD+qA4+2a2PVIoMjO0McsUBWYHKtMrTIe/v9Uibwqxrnz0lZh6tO+frky1sHjWzn1jBvtd/U5fSbIalleiU+XFtoG8PN89/S+e/ixVssbK3g6vuVag1r9W35/I7BVOGJHTBA5epQKCmZ9AwDr4md/fRO+BTcOPiiXSogfLJo6JKx8I4NSMjMy6gkOxQ57CmX0bB4zW1qpm/EYzDJL/oDTRDIxli+B4oflf90Z1tIGt9oXP5jngCQHc=" Feb 07 13:23:32 volumio go-librespot[31099]: time="2026-02-07T13:23:32+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 13:23:32 volumio go-librespot[31099]: time="2026-02-07T13:23:32+07:00" level=debug msg="completed keyexchange" Feb 07 13:23:32 volumio go-librespot[31099]: time="2026-02-07T13:23:32+07:00" level=debug msg="completed challenge" Feb 07 13:23:32 volumio go-librespot[31099]: time="2026-02-07T13:23:32+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 13:23:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 13:23:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 13:23:34 volumio volumio[31018]: info: Loading plugin "ytmusic"... Feb 07 13:23:35 volumio volumio-remote-updater[643]: [2026-02-07 13:23:35] [connect] Successful connection Feb 07 13:23:35 volumio volumio[31018]: info: Loading plugin "outputs"... Feb 07 13:23:35 volumio volumio[31018]: info: Loading plugin "albumart"... Feb 07 13:23:35 volumio volumio[31018]: info: Plugin example_plugin is not enabled Feb 07 13:23:35 volumio volumio[31018]: info: Loading plugin "inputs"... Feb 07 13:23:35 volumio volumio[31018]: info: Loading plugin "updater_comm"... Feb 07 13:23:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 59. Feb 07 13:23:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 13:23:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 13:23:35 volumio go-librespot[31131]: go-librespot daemon starting... Feb 07 13:23:35 volumio go-librespot[31132]: time="2026-02-07T13:23:35+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 13:23:35 volumio go-librespot[31132]: time="2026-02-07T13:23:35+07:00" level=debug msg="app state loaded" Feb 07 13:23:35 volumio go-librespot[31132]: time="2026-02-07T13:23:35+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 13:23:35 volumio volumio[31018]: info: Plugin mpdemulation is not enabled Feb 07 13:23:35 volumio volumio[31018]: info: Loading plugin "rest_api"... Feb 07 13:23:36 volumio volumio[31018]: info: Loading plugin "websocket"... Feb 07 13:23:36 volumio volumio[31018]: info: Starting Socket.io Server version 1.7.4 Feb 07 13:23:36 volumio volumio[31018]: info: Loading plugin "RoonBridge"... Feb 07 13:23:36 volumio go-librespot[31132]: time="2026-02-07T13:23:36+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 13:23:36 volumio go-librespot[31132]: time="2026-02-07T13:23:36+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 07 13:23:36 volumio go-librespot[31132]: time="2026-02-07T13:23:36+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 07 13:23:36 volumio go-librespot[31132]: time="2026-02-07T13:23:36+07:00" level=info msg="zeroconf server listening on port 43183" Feb 07 13:23:36 volumio volumio[31018]: info: Applying required configuration parameters for plugin RoonBridge Feb 07 13:23:36 volumio volumio[31018]: info: Loading i18n strings for locale en Feb 07 13:23:36 volumio volumio[31018]: Updating browse sources language Feb 07 13:23:36 volumio volumio[31018]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 13:23:36 volumio go-librespot[31132]: time="2026-02-07T13:23:36+07:00" level=debug msg="obtained new client token: AAAPGjgbvf1nI5J8ssdA0d9t6kpyIuqrZiDd/WLkKgsCRw/+mZ34Itymwk93BjNgIzUQ4TpMvlW81PdXCEuhcPFcjatbv4fvA/GlBgrc3AnllUx8SiXnySoG+LjQ6M1P61sBvk+kpRYju5IzQTbpT7n3cx+X6Y8WQcJU2lpfh+Fl1RWVr+9qZYdZawc3QVz1zPCM5Bk/b4BXvzL3lpRIrOlgzGMZhn90npILd66/nSsaZoskU6G+0o9EnA==" Feb 07 13:23:36 volumio volumio[31122]: Forking 3 albumart workers Feb 07 13:23:36 volumio go-librespot[31132]: time="2026-02-07T13:23:36+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused" Feb 07 13:23:36 volumio go-librespot[31132]: time="2026-02-07T13:23:36+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Feb 07 13:23:36 volumio volumio[31018]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 13:23:36 volumio volumio[31018]: info: CoreCommandRouter::initPlayerControls Feb 07 13:23:36 volumio volumio[31018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:23:36 volumio volumio[31018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:23:36 volumio volumio[31018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:23:36 volumio volumio[31018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:23:36 volumio volumio[31018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:23:36 volumio volumio[31018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:23:36 volumio volumio[31018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:23:36 volumio volumio[31018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:23:36 volumio volumio[31018]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 13:23:36 volumio volumio[31018]: Express server listening on port 3000 Feb 07 13:23:36 volumio volumio[31018]: [Metrics] WebUI: 16s 235.24ms Feb 07 13:23:36 volumio go-librespot[31132]: time="2026-02-07T13:23:36+07:00" level=debug msg="completed keyexchange" Feb 07 13:23:36 volumio go-librespot[31132]: time="2026-02-07T13:23:36+07:00" level=debug msg="completed challenge" Feb 07 13:23:36 volumio volumio[31018]: info: CoreStateMachine::resetVolumioState Feb 07 13:23:36 volumio volumio[31018]: info: CoreStateMachine::getcurrentVolume Feb 07 13:23:36 volumio volumio[31018]: info: CoreCommandRouter::volumioRetrievevolume Feb 07 13:23:36 volumio go-librespot[31132]: time="2026-02-07T13:23:36+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 13:23:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 13:23:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 13:23:37 volumio sudo[31174]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 07 13:23:37 volumio sudo[31174]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 13:23:37 volumio sudo[31177]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 07 13:23:37 volumio sudo[31174]: pam_unix(sudo:session): session closed for user root Feb 07 13:23:37 volumio sudo[31177]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 13:23:37 volumio sudo[31177]: pam_unix(sudo:session): session closed for user root Feb 07 13:23:37 volumio volumio[31018]: info: Volumio Network Manager: Network status updated: 1 Feb 07 13:23:37 volumio volumio[31018]: info: VolumeController:: Volume=100 Mute =false Feb 07 13:23:37 volumio volumio[31018]: info: CoreStateMachine::pushState Feb 07 13:23:37 volumio volumio[31018]: info: CorePlayQueue::getTrack 0 Feb 07 13:23:37 volumio volumio[31018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 13:23:37 volumio volumio[31018]: info: CoreCommandRouter::volumioPushState Feb 07 13:23:37 volumio volumio[31018]: info: CoreStateMachine::updateTrackBlock Feb 07 13:23:37 volumio volumio[31018]: info: CorePlayQueue::getTrackBlock Feb 07 13:23:37 volumio volumio[31018]: info: CoreCommandRouter::volumioRetrievevolume Feb 07 13:23:37 volumio volumio-remote-updater[643]: [2026-02-07 13:23:37] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1770445415 101 Feb 07 13:23:37 volumio volumio[31018]: 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: 1 Feb 07 13:23:37 volumio volumio[31018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 13:23:37 volumio volumio[31018]: info: Reloading queue from file Feb 07 13:23:37 volumio volumio[31018]: info: CoreStateMachine::setRepeat null single undefined Feb 07 13:23:37 volumio volumio[31018]: info: CoreStateMachine::pushState Feb 07 13:23:37 volumio volumio[31018]: info: CorePlayQueue::getTrack 0 Feb 07 13:23:37 volumio volumio[31018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 13:23:37 volumio volumio[31018]: info: CoreCommandRouter::volumioPushState Feb 07 13:23:37 volumio volumio[31018]: info: CoreStateMachine::setRandom null Feb 07 13:23:37 volumio volumio[31018]: info: CoreStateMachine::pushState Feb 07 13:23:37 volumio volumio[31018]: info: CorePlayQueue::getTrack 0 Feb 07 13:23:37 volumio volumio[31018]: info: CoreCommandRouter::volumioPushState Feb 07 13:23:37 volumio volumio[31018]: info: Setting Device type: Raspberry PI Feb 07 13:23:37 volumio volumio[31018]: info: Completed loading Core Plugins Feb 07 13:23:37 volumio volumio[31018]: info: Preparing to generate the ALSA configuration file Feb 07 13:23:37 volumio volumio[31018]: info: VolumeController:: Volume=100 Mute =false Feb 07 13:23:37 volumio volumio[31018]: info: CoreStateMachine::pushState Feb 07 13:23:37 volumio volumio[31018]: info: CorePlayQueue::getTrack 0 Feb 07 13:23:37 volumio volumio[31018]: info: CoreCommandRouter::volumioPushState Feb 07 13:23:37 volumio volumio[31018]: info: Asound.conf file unchanged, so no further update is needed Feb 07 13:23:37 volumio volumio[31018]: info: Output device has changed, restarting MPD Feb 07 13:23:38 volumio volumio[31018]: info: Output device has changed, restarting Shairport Sync Feb 07 13:23:38 volumio volumio[31018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:23:38 volumio volumio[31018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 13:23:38 volumio sudo[31190]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 07 13:23:38 volumio sudo[31192]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 07 13:23:38 volumio sudo[31192]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 13:23:38 volumio sudo[31190]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 13:23:38 volumio sudo[31192]: pam_unix(sudo:session): session closed for user root Feb 07 13:23:38 volumio sudo[31194]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 07 13:23:38 volumio volumio[31018]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 07 13:23:38 volumio sudo[31194]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 13:23:38 volumio volumio[31018]: info: ___________ START PLUGINS ___________ Feb 07 13:23:38 volumio volumio[31018]: info: ControllerMpd::onStart: Initializing MPD Feb 07 13:23:38 volumio volumio[31018]: info: Creating MPD Configuration file Feb 07 13:23:38 volumio sudo[31190]: pam_unix(sudo:session): session closed for user root Feb 07 13:23:38 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 07 13:23:38 volumio volumio[31018]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 13:23:38 volumio volumio[31018]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 13:23:38 volumio volumio[31018]: info: [1770445418276] CoreMusicLibrary::Adding element Media Servers Feb 07 13:23:38 volumio volumio[31018]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 13:23:38 volumio sudo[31205]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 07 13:23:38 volumio sudo[31205]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 13:23:38 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 07 13:23:38 volumio volumio[31018]: info: UPNP Browser: Client initialized successfully Feb 07 13:23:38 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 07 13:23:38 volumio systemd[1]: mpd.service: Consumed 7.081s CPU time. Feb 07 13:23:38 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 07 13:23:38 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 07 13:23:38 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 07 13:23:38 volumio sudo[31203]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 07 13:23:38 volumio sudo[31203]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 13:23:38 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 07 13:23:38 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 07 13:23:38 volumio sudo[31203]: pam_unix(sudo:session): session closed for user root Feb 07 13:23:38 volumio volumio[31018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:23:38 volumio volumio[31018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 13:23:38 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 07 13:23:38 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 07 13:23:38 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 07 13:23:38 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 07 13:23:38 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 07 13:23:38 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 07 13:23:38 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 07 13:23:38 volumio volumio[31018]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 07 13:23:38 volumio volumio[31018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:23:38 volumio volumio[31018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 13:23:38 volumio volumio[31018]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 07 13:23:38 volumio volumio[31018]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 13:23:38 volumio volumio[31018]: info: [1770445418747] CoreMusicLibrary::Adding element Last_100 Feb 07 13:23:38 volumio volumio[31018]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 13:23:38 volumio volumio[31018]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 13:23:38 volumio volumio[31018]: info: [1770445418769] CoreMusicLibrary::Adding element Webradio Feb 07 13:23:38 volumio volumio[31018]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 13:23:38 volumio volumio[31018]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 07 13:23:38 volumio volumio[31018]: info: Initializing BBC Radios Feb 07 13:23:39 volumio volumio[31018]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 07 13:23:39 volumio volumio[31018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 13:23:39 volumio volumio[31018]: info: Creating Spotify config file Feb 07 13:23:39 volumio volumio[31018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:23:39 volumio sudo[31215]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 07 13:23:39 volumio sudo[31215]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 07 13:23:39 volumio sudo[31215]: pam_unix(sudo:session): session closed for user root Feb 07 13:23:39 volumio volumio[31141]: Starting albumart workers Feb 07 13:23:39 volumio volumio[31142]: Starting albumart workers Feb 07 13:23:40 volumio volumio[31140]: Starting albumart workers Feb 07 13:23:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 60. Feb 07 13:23:40 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 13:23:40 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 13:23:40 volumio go-librespot[31235]: go-librespot daemon starting... Feb 07 13:23:40 volumio go-librespot[31236]: time="2026-02-07T13:23:40+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 13:23:40 volumio volumio[31018]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 13:23:40 volumio volumio[31018]: info: [1770445420882] CoreMusicLibrary::Adding element YouTube Music Feb 07 13:23:40 volumio volumio[31018]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 13:23:40 volumio volumio[31018]: Cannot find translation for source YouTube Music Feb 07 13:23:40 volumio volumio[31018]: info: Volumio Calling Home Feb 07 13:23:40 volumio go-librespot[31236]: time="2026-02-07T13:23:40+07:00" level=info msg="zeroconf server listening on port 36777" Feb 07 13:23:41 volumio sudo[31245]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 07 13:23:41 volumio sudo[31245]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 13:23:41 volumio sudo[31245]: pam_unix(sudo:session): session closed for user root Feb 07 13:23:41 volumio volumio[31018]: info: Discovery: adding 921648ed-0557-4d70-9927-1420682efcc7 Feb 07 13:23:41 volumio volumio[31018]: info: Discovery: Found device Volumio Feb 07 13:23:41 volumio volumio[31018]: info: CoreCommandRouter::volumioGetState Feb 07 13:23:41 volumio volumio[31018]: info: CorePlayQueue::getTrack 0 Feb 07 13:23:41 volumio volumio[31018]: info: MPD Permissions set Feb 07 13:23:41 volumio volumio[31018]: info: MPD Permissions set Feb 07 13:23:41 volumio volumio[31018]: info: Upmpdcli Daemon Started Feb 07 13:23:41 volumio volumio[31018]: info: Discovery: this is already registered, 921648ed-0557-4d70-9927-1420682efcc7 Feb 07 13:23:41 volumio volumio[31018]: info: Discovery: Found device Volumio Feb 07 13:23:41 volumio volumio[31018]: info: CoreCommandRouter::volumioGetState Feb 07 13:23:41 volumio volumio[31018]: info: CorePlayQueue::getTrack 0 Feb 07 13:23:41 volumio volumio[31018]: info: Volumio called home Feb 07 13:23:41 volumio volumio[31018]: info: Spotify config file written Feb 07 13:23:42 volumio volumio[31018]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Feb 07 13:23:42 volumio sudo[31267]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 07 13:23:42 volumio sudo[31267]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 13:23:42 volumio volumio[31018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:23:42 volumio volumio[31018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:23:42 volumio volumio[31018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:23:42 volumio volumio[31018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:23:42 volumio volumio[31018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:23:42 volumio volumio[31018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:23:42 volumio volumio[31018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:23:42 volumio volumio[31018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:23:42 volumio volumio[31018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 13:23:42 volumio volumio[31018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:23:42 volumio volumio[31018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:23:42 volumio volumio[31018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:23:42 volumio volumio[31018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:23:42 volumio volumio[31018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:23:42 volumio volumio[31018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:23:42 volumio volumio[31018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:23:42 volumio volumio[31018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:23:42 volumio volumio[31018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:23:42 volumio volumio[31018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 13:23:42 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 07 13:23:42 volumio volumio[31018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 13:23:42 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 07 13:23:42 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 13:23:42 volumio volumio[31018]: info: No need to fix Spotify hosts Feb 07 13:23:42 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 13:23:42 volumio go-librespot[31282]: go-librespot daemon starting... Feb 07 13:23:42 volumio sudo[31267]: pam_unix(sudo:session): session closed for user root Feb 07 13:23:42 volumio go-librespot[31283]: time="2026-02-07T13:23:42+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 13:23:42 volumio go-librespot[31283]: time="2026-02-07T13:23:42+07:00" level=debug msg="app state loaded" Feb 07 13:23:42 volumio go-librespot[31283]: time="2026-02-07T13:23:42+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 13:23:42 volumio go-librespot[31283]: time="2026-02-07T13:23:42+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 13:23:42 volumio go-librespot[31283]: time="2026-02-07T13:23:42+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 07 13:23:42 volumio go-librespot[31283]: time="2026-02-07T13:23:42+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 07 13:23:42 volumio go-librespot[31283]: time="2026-02-07T13:23:42+07:00" level=info msg="zeroconf server listening on port 34077" Feb 07 13:23:43 volumio volumio[31018]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 07 13:23:43 volumio volumio[31018]: SPOTIFY: BQB4mbzGs6DmLLvUwFJA4hJqULGBEJDt7A0cEVZBvgidS7uRpF-lK1hjLWaezOivMr6wE7hs9kQL5WS3HMGEQoeYpR3yTr5k-1F2yLZIkjXaKV5WR1W7LfqNR872eCMal4K-_W31l-ncq7qXOFiaHTBhIncR2tylZpqJ09V9ZFbWNgNBt2EZEOsJ3Ck0YF3aiIXhypS8HCpV-OwirzJ6IE5Ri4xIlksW4fQcsdexl0RUE8UqJVxoKMCAfAqo7Tr2Sm1gjfR8KJusChoUajyDnhErTdfYu3Lb3-_2LIcQpsx07u46Kt58WfMK Feb 07 13:23:43 volumio volumio[31018]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 07 13:23:43 volumio volumio[31018]: info: New Spotify access token = BQB4mbzGs6DmLLvUwFJA4hJqULGBEJDt7A0cEVZBvgidS7uRpF-lK1hjLWaezOivMr6wE7hs9kQL5WS3HMGEQoeYpR3yTr5k-1F2yLZIkjXaKV5WR1W7LfqNR872eCMal4K-_W31l-ncq7qXOFiaHTBhIncR2tylZpqJ09V9ZFbWNgNBt2EZEOsJ3Ck0YF3aiIXhypS8HCpV-OwirzJ6IE5Ri4xIlksW4fQcsdexl0RUE8UqJVxoKMCAfAqo7Tr2Sm1gjfR8KJusChoUajyDnhErTdfYu3Lb3-_2LIcQpsx07u46Kt58WfMK Feb 07 13:23:43 volumio volumio[31018]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 07 13:23:43 volumio volumio[31018]: info: Starting Shairport Sync Feb 07 13:23:43 volumio volumio[31018]: info: Starting Shairport Sync Feb 07 13:23:43 volumio volumio[31018]: info: Starting Shairport Sync Feb 07 13:23:43 volumio go-librespot[31283]: time="2026-02-07T13:23:43+07:00" level=debug msg="obtained new client token: AAAHFmyPOxNXFw0uZXMtbfCFlBm/hPU//jIvoENDGKZ73W0oy8PxSj0u2IHesq7E9HII/3bZQYUQk1WUN3+A/Q+uK+2/8x9peEFIjcklsPRJGhKmGci+ZzAF876/tyQoseqfp5omFUV3twPa1CjDEdnk6htN66Q+HnmYHembmNcnqaBk6QNgaozNxAdrJUCsUvxLcSEMNk925tDWEEsU6xcj9XiWU2fX2q3I4f9FerOGK6uxqQyp/Xg=" Feb 07 13:23:43 volumio sudo[31292]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 07 13:23:43 volumio sudo[31292]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 13:23:43 volumio sudo[31295]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 07 13:23:43 volumio sudo[31295]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 13:23:43 volumio sudo[31296]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 07 13:23:43 volumio sudo[31296]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 13:23:43 volumio go-librespot[31283]: time="2026-02-07T13:23:43+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 13:23:43 volumio go-librespot[31283]: time="2026-02-07T13:23:43+07:00" level=debug msg="completed keyexchange" Feb 07 13:23:43 volumio go-librespot[31283]: time="2026-02-07T13:23:43+07:00" level=debug msg="completed challenge" Feb 07 13:23:43 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 07 13:23:43 volumio volumio[31018]: info: CoreCommandRouter::volumioGetState Feb 07 13:23:43 volumio volumio[31018]: info: CorePlayQueue::getTrack 0 Feb 07 13:23:43 volumio go-librespot[31283]: time="2026-02-07T13:23:43+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 13:23:43 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 07 13:23:43 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 07 13:23:43 volumio systemd[1]: shairport-sync.service: Consumed 2.386s CPU time. Feb 07 13:23:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 13:23:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 13:23:43 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 07 13:23:43 volumio sudo[31295]: pam_unix(sudo:session): session closed for user root Feb 07 13:23:43 volumio sudo[31292]: pam_unix(sudo:session): session closed for user root Feb 07 13:23:43 volumio sudo[31296]: pam_unix(sudo:session): session closed for user root Feb 07 13:23:43 volumio volumio[31018]: info: Shairport-Sync Started Feb 07 13:23:43 volumio volumio[31018]: Error adding Membership: Error: addMembership EINVAL Feb 07 13:23:43 volumio volumio[31018]: info: Shairport-Sync Started Feb 07 13:23:43 volumio volumio[31018]: info: Shairport-Sync Started Feb 07 13:23:43 volumio volumio[31018]: SPOTIFY: User informations: {"country":"PK","display_name":"215mmgea6kss3jxeqsqdquvji","email":"linh.ngotran@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/215mmgea6kss3jxeqsqdquvji"},"followers":{"href":null,"total":1},"href":"https://api.spotify.com/v1/users/215mmgea6kss3jxeqsqdquvji","id":"215mmgea6kss3jxeqsqdquvji","images":[],"product":"premium","type":"user","uri":"spotify:user:215mmgea6kss3jxeqsqdquvji"} Feb 07 13:23:43 volumio volumio[31018]: info: Spotify Successfully logged in Feb 07 13:23:43 volumio volumio[31018]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 13:23:43 volumio volumio[31018]: info: [1770445423886] CoreMusicLibrary::Adding element Spotify Feb 07 13:23:43 volumio volumio[31018]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 13:23:43 volumio volumio[31018]: Cannot find translation for source YouTube Music Feb 07 13:23:43 volumio volumio[31018]: Cannot find translation for source Spotify Feb 07 13:23:44 volumio volumio[31018]: info: [yt-cast-receiver] DIAL server listening on port 8098 Feb 07 13:23:44 volumio volumio[31018]: info: CoreCommandRouter::volumioRetrievevolume Feb 07 13:23:44 volumio volumio[31018]: info: VolumeController:: Volume=100 Mute =false Feb 07 13:23:44 volumio volumio[31018]: info: CoreCommandRouter::volumioGetState Feb 07 13:23:44 volumio volumio[31018]: info: CorePlayQueue::getTrack 0 Feb 07 13:23:44 volumio volumio[31018]: info: CoreStateMachine::pushState Feb 07 13:23:44 volumio volumio[31018]: info: CorePlayQueue::getTrack 0 Feb 07 13:23:44 volumio volumio[31018]: info: CoreCommandRouter::volumioPushState Feb 07 13:23:46 volumio volumio[31018]: info: go-librespot daemon successfully initialized Feb 07 13:23:46 volumio mpd[31234]: 2026-02-07T13:23:46 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 07 13:23:46 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 07 13:23:46 volumio sudo[31205]: pam_unix(sudo:session): session closed for user root Feb 07 13:23:46 volumio sudo[31194]: pam_unix(sudo:session): session closed for user root Feb 07 13:23:46 volumio volumio[31018]: error: MPD error: The expression evaluated to a falsy value: Feb 07 13:23:46 volumio volumio[31018]: assert.ok(self.idling) Feb 07 13:23:46 volumio volumio[31018]: error: The expression evaluated to a falsy value: Feb 07 13:23:46 volumio volumio[31018]: assert.ok(self.idling) Feb 07 13:23:46 volumio volumio[31018]: error: updateQueue error: null Feb 07 13:23:46 volumio volumio[31018]: info: MPD running with PID31234 Feb 07 13:23:46 volumio volumio[31018]: ,establishing connection Feb 07 13:23:46 volumio volumio[31018]: info: Completed starting Core Plugins Feb 07 13:23:46 volumio volumio[31018]: info: ------------------------------------------- Feb 07 13:23:46 volumio volumio[31018]: info: ----- MyVolumio plugins startup ---- Feb 07 13:23:46 volumio volumio[31018]: info: ------------------------------------------- Feb 07 13:23:46 volumio volumio[31018]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 07 13:23:46 volumio volumio[31018]: error: updateQueue error: null Feb 07 13:23:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Feb 07 13:23:46 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 13:23:46 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 13:23:46 volumio go-librespot[31335]: go-librespot daemon starting... Feb 07 13:23:46 volumio go-librespot[31336]: time="2026-02-07T13:23:46+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 13:23:46 volumio go-librespot[31336]: time="2026-02-07T13:23:46+07:00" level=debug msg="app state loaded" Feb 07 13:23:46 volumio go-librespot[31336]: time="2026-02-07T13:23:46+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 13:23:47 volumio go-librespot[31336]: time="2026-02-07T13:23:47+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 13:23:47 volumio go-librespot[31336]: time="2026-02-07T13:23:47+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 07 13:23:47 volumio go-librespot[31336]: time="2026-02-07T13:23:47+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 07 13:23:47 volumio go-librespot[31336]: time="2026-02-07T13:23:47+07:00" level=info msg="zeroconf server listening on port 39381" Feb 07 13:23:47 volumio go-librespot[31336]: time="2026-02-07T13:23:47+07:00" level=debug msg="obtained new client token: AAB0M2RK6bXBrch2FNfRH2/nutRzERRXvarRyoDdF1/qDHtHqdfKuIM9fhmTASPxl1cODlhZzVsaV1JDXG6mYPK07sRKjjmZajSSV9RDVnO+KA7SOua4xyV7SZaGF4smd9oSDJKK+d/WMuTDbyTB52sSvkTUzN7lJccwg1IdUHq7hb7x0Rg1+wO8dbxgYHf5cH3wtVAt91oPEiYN1QgXBLWetJpXuCeRvIW6uYganx6wrBZNpIVgg/5BKQ==" Feb 07 13:23:47 volumio go-librespot[31336]: time="2026-02-07T13:23:47+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 13:23:47 volumio go-librespot[31336]: time="2026-02-07T13:23:47+07:00" level=debug msg="completed keyexchange" Feb 07 13:23:47 volumio go-librespot[31336]: time="2026-02-07T13:23:47+07:00" level=debug msg="completed challenge" Feb 07 13:23:47 volumio go-librespot[31336]: time="2026-02-07T13:23:47+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 13:23:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 13:23:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 13:23:49 volumio volumio[31018]: info: Initializing connection to go-librespot Websocket Feb 07 13:23:49 volumio volumio[31018]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 07 13:23:51 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Feb 07 13:23:51 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 13:23:51 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 13:23:51 volumio go-librespot[31344]: go-librespot daemon starting... Feb 07 13:23:51 volumio go-librespot[31345]: time="2026-02-07T13:23:51+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 13:23:51 volumio go-librespot[31345]: time="2026-02-07T13:23:51+07:00" level=debug msg="app state loaded" Feb 07 13:23:51 volumio volumio[31018]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 07 13:23:51 volumio go-librespot[31345]: time="2026-02-07T13:23:51+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 13:23:51 volumio go-librespot[31345]: time="2026-02-07T13:23:51+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 07 13:23:51 volumio go-librespot[31345]: time="2026-02-07T13:23:51+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 07 13:23:51 volumio go-librespot[31345]: time="2026-02-07T13:23:51+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 07 13:23:51 volumio go-librespot[31345]: time="2026-02-07T13:23:51+07:00" level=info msg="zeroconf server listening on port 38595" Feb 07 13:23:51 volumio go-librespot[31345]: time="2026-02-07T13:23:51+07:00" level=debug msg="obtained new client token: AAAUbwOBniBXfQNwBIFwS4f4ADPqciOvmW4vyLuPgpXmk64nGtJhGgXjhfNPFV8sXF51USQLHf9GCXaBEZ4nkBJH1Dk1ZlMIw760TiKFfUU4AjSl67BUK7cGW3AcFqsC/stV7Iw5qYeB6VcCcwMP3zxoTIEozvS9szggpXClX8ExOAR6GcxEVdZrvXT03X+HiOhe75vp2fvxfdotQn5akAWDT1FbhPdL1kc/Ok2MveqOMiL1eXPHo9xi1g==" Feb 07 13:23:51 volumio go-librespot[31345]: time="2026-02-07T13:23:51+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 13:23:52 volumio go-librespot[31345]: time="2026-02-07T13:23:52+07:00" level=debug msg="completed keyexchange" Feb 07 13:23:52 volumio go-librespot[31345]: time="2026-02-07T13:23:52+07:00" level=debug msg="completed challenge" Feb 07 13:23:52 volumio volumio[31018]: info: Initializing connection to go-librespot Websocket Feb 07 13:23:52 volumio go-librespot[31345]: time="2026-02-07T13:23:52+07:00" level=debug msg="new websocket client" Feb 07 13:23:52 volumio volumio[31018]: info: Connection to go-librespot Websocket established Feb 07 13:23:52 volumio go-librespot[31345]: time="2026-02-07T13:23:52+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 13:23:52 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 13:23:52 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 13:23:52 volumio volumio[31018]: info: Connection to go-librespot Websocket closed Feb 07 13:23:55 volumio volumio[31018]: info: Getting Spotify volume Feb 07 13:23:55 volumio volumio[31018]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 13:23:55 volumio volumio[31018]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 07 13:23:55 volumio volumio[31018]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 07 13:23:55 volumio volumio[31018]: errno: -111, Feb 07 13:23:55 volumio volumio[31018]: code: 'ECONNREFUSED', Feb 07 13:23:55 volumio volumio[31018]: syscall: 'connect', Feb 07 13:23:55 volumio volumio[31018]: address: '127.0.0.1', Feb 07 13:23:55 volumio volumio[31018]: port: 9879, Feb 07 13:23:55 volumio volumio[31018]: response: undefined Feb 07 13:23:55 volumio volumio[31018]: } Feb 07 13:23:55 volumio volumio[31018]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 13:23:55 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Feb 07 13:23:55 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 13:23:55 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 13:23:55 volumio go-librespot[31366]: go-librespot daemon starting... Feb 07 13:23:55 volumio go-librespot[31368]: time="2026-02-07T13:23:55+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 13:23:55 volumio go-librespot[31368]: time="2026-02-07T13:23:55+07:00" level=debug msg="app state loaded" Feb 07 13:23:55 volumio go-librespot[31368]: time="2026-02-07T13:23:55+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 13:23:55 volumio go-librespot[31368]: time="2026-02-07T13:23:55+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 13:23:55 volumio go-librespot[31368]: time="2026-02-07T13:23:55+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 07 13:23:55 volumio go-librespot[31368]: time="2026-02-07T13:23:55+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 07 13:23:55 volumio go-librespot[31368]: time="2026-02-07T13:23:55+07:00" level=info msg="zeroconf server listening on port 42893" Feb 07 13:23:56 volumio go-librespot[31368]: time="2026-02-07T13:23:56+07:00" level=debug msg="obtained new client token: AABqU4oHiHrPxRO+ukwOUv2YJHwz1pHIAwG56D0K2hNhusAJJkr5qJGS0R1vEae/wyQG5ZChE3kZ+HYHOdWjVtM8DGpKmbCVA/tZCjKqGmQJQZvKV/YIKv0VxsTnehbPipK9dFecK8rw0FepJsm9wHz291oDZJ57oTK2KfuGJpGJX8IizICImOT9KWPXKdiI1FlZ8nnb4R6+ei2UoLCMbjNYcwG3rwsQr5M7Qa6YwYLZ1fYEKlpQOks=" Feb 07 13:23:56 volumio go-librespot[31368]: time="2026-02-07T13:23:56+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 13:23:56 volumio go-librespot[31368]: time="2026-02-07T13:23:56+07:00" level=debug msg="completed keyexchange" Feb 07 13:23:56 volumio go-librespot[31368]: time="2026-02-07T13:23:56+07:00" level=debug msg="completed challenge" Feb 07 13:23:56 volumio go-librespot[31368]: time="2026-02-07T13:23:56+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 13:23:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 13:23:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 13:23:56 volumio sudo[31392]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-07 13:22' Feb 07 13:23:56 volumio sudo[31392]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"