Feb 07 22:27:00 volumio volumio[32467]: info: Loading plugin "upnp"... Feb 07 22:27:00 volumio volumio[32467]: info: [1770478020025] Starting Upmpd Daemon Feb 07 22:27:00 volumio volumio[32467]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 07 22:27:00 volumio volumio[32467]: info: Loading plugin "my_music"... Feb 07 22:27:00 volumio volumio[32467]: info: Loading plugin "mpd"... Feb 07 22:27:00 volumio volumio[32467]: info: Loading plugin "upnp_browser"... Feb 07 22:27:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Feb 07 22:27:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 22:27:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 22:27:01 volumio go-librespot[32547]: go-librespot daemon starting... Feb 07 22:27:01 volumio go-librespot[32548]: time="2026-02-07T22:27:01+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 22:27:01 volumio go-librespot[32548]: time="2026-02-07T22:27:01+07:00" level=debug msg="app state loaded" Feb 07 22:27:01 volumio go-librespot[32548]: time="2026-02-07T22:27:01+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 22:27:02 volumio go-librespot[32548]: time="2026-02-07T22:27:02+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 22:27:02 volumio go-librespot[32548]: time="2026-02-07T22:27:02+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 22:27:02 volumio go-librespot[32548]: time="2026-02-07T22:27:02+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 22:27:02 volumio go-librespot[32548]: time="2026-02-07T22:27:02+07:00" level=info msg="zeroconf server listening on port 34471" Feb 07 22:27:02 volumio sudo[32523]: pam_unix(sudo:session): session closed for user root Feb 07 22:27:02 volumio go-librespot[32548]: time="2026-02-07T22:27:02+07:00" level=debug msg="obtained new client token: AABywmbtz8L0EDSHx1eIbriLNkXKAhd6Y4oEj+U/0v5PjwqlbFpdZzDKnRaKsP/u46oyLzHz2wJa27bxnoyYz4fRBzWHlaykBm+J/3CPVKy97d2XMc3XJFPy7l0qCLIiwSxVc2UUEs61kikAiEwOkTmhv7WFGKecwdETw/r+/MS1ohVnoWTeaOg+tS7MOQDMhQjdYncnPYACwLpvwWvaP/NGq61K9XkMK+x+DGaCdBRORKPInhIfHfWVyA==" Feb 07 22:27:02 volumio go-librespot[32548]: time="2026-02-07T22:27:02+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 22:27:02 volumio go-librespot[32548]: time="2026-02-07T22:27:02+07:00" level=debug msg="completed keyexchange" Feb 07 22:27:02 volumio go-librespot[32548]: time="2026-02-07T22:27:02+07:00" level=debug msg="completed challenge" Feb 07 22:27:02 volumio go-librespot[32548]: time="2026-02-07T22:27:02+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 22:27:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 22:27:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 22:27:03 volumio volumio[32467]: info: Starting UPNP Browser Feb 07 22:27:03 volumio volumio[32467]: info: Loading plugin "alarm-clock"... Feb 07 22:27:03 volumio volumio[32467]: info: Loading plugin "airplay_emulation"... Feb 07 22:27:03 volumio volumio[32467]: info: Starting Shairport Sync Feb 07 22:27:03 volumio volumio[32467]: info: Loading plugin "last_100"... Feb 07 22:27:03 volumio volumio[32467]: info: Loading plugin "webradio"... Feb 07 22:27:04 volumio volumio[32467]: info: Loading plugin "i2s_dacs"... Feb 07 22:27:04 volumio volumio[32467]: info: Loading plugin "volumiodiscovery"... Feb 07 22:27:04 volumio volumio[32467]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 07 22:27:04 volumio volumio[32467]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 22:27:04 volumio node[32467]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 07 22:27:04 volumio volumio[32467]: *** WARNING *** For more information see Feb 07 22:27:04 volumio volumio[32467]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 07 22:27:04 volumio volumio[32467]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 22:27:04 volumio volumio[32467]: *** WARNING *** For more information see Feb 07 22:27:04 volumio node[32467]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 22:27:04 volumio node[32467]: *** WARNING *** For more information see Feb 07 22:27:04 volumio node[32467]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 07 22:27:04 volumio node[32467]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 22:27:04 volumio node[32467]: *** WARNING *** For more information see Feb 07 22:27:04 volumio volumio[32467]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 07 22:27:04 volumio volumio[32467]: info: Discovery: Started advertising with name: Volumio Feb 07 22:27:04 volumio volumio[32467]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 07 22:27:04 volumio volumio[32467]: info: Loading plugin "spop"... Feb 07 22:27:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Feb 07 22:27:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 22:27:06 volumio volumio[32467]: info: Loading plugin "ytcr"... Feb 07 22:27:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 22:27:06 volumio go-librespot[32557]: go-librespot daemon starting... Feb 07 22:27:06 volumio go-librespot[32558]: time="2026-02-07T22:27:06+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 22:27:06 volumio go-librespot[32558]: time="2026-02-07T22:27:06+07:00" level=debug msg="app state loaded" Feb 07 22:27:06 volumio go-librespot[32558]: time="2026-02-07T22:27:06+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 22:27:06 volumio go-librespot[32558]: time="2026-02-07T22:27:06+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 22:27:06 volumio go-librespot[32558]: time="2026-02-07T22:27:06+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 22:27:06 volumio go-librespot[32558]: time="2026-02-07T22:27:06+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 22:27:06 volumio go-librespot[32558]: time="2026-02-07T22:27:06+07:00" level=info msg="zeroconf server listening on port 37465" Feb 07 22:27:06 volumio go-librespot[32558]: time="2026-02-07T22:27:06+07:00" level=debug msg="obtained new client token: AADeGvD7DFs7UVwYYamPAeQ8/lz0Ho6pVMpa0PlvKawcBqkcfWPBx5n4KhPIlTI+vZp2l+jjBLT4UbCX5xQhpSXjy5pabYx5OAeQFnE5vJwUCfMxSxFwNa5djZiMyI4MBOVQr2m8cBoyoAQCsJlubMtbfAEncSAL1jtvH/iw5AU0hZ3YvnNn6zv+/9QKANJpXOrQG1eNqr8T+OSubY7mNqAaT8OA/w7DEbyO/xkyOSjJkevOLobEjjXu0w==" Feb 07 22:27:07 volumio go-librespot[32558]: time="2026-02-07T22:27:07+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 22:27:07 volumio go-librespot[32558]: time="2026-02-07T22:27:07+07:00" level=debug msg="completed keyexchange" Feb 07 22:27:07 volumio go-librespot[32558]: time="2026-02-07T22:27:07+07:00" level=debug msg="completed challenge" Feb 07 22:27:07 volumio go-librespot[32558]: time="2026-02-07T22:27:07+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 22:27:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 22:27:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 22:27:08 volumio volumio[32467]: info: Loading plugin "ytmusic"... Feb 07 22:27:09 volumio volumio-remote-updater[643]: [2026-02-07 22:27:09] [connect] Successful connection Feb 07 22:27:09 volumio volumio[32467]: info: Loading plugin "outputs"... Feb 07 22:27:09 volumio volumio[32467]: info: Loading plugin "albumart"... Feb 07 22:27:09 volumio volumio[32467]: info: Plugin example_plugin is not enabled Feb 07 22:27:09 volumio volumio[32467]: info: Loading plugin "inputs"... Feb 07 22:27:09 volumio volumio[32467]: info: Loading plugin "updater_comm"... Feb 07 22:27:10 volumio volumio[32467]: info: Plugin mpdemulation is not enabled Feb 07 22:27:10 volumio volumio[32467]: info: Loading plugin "rest_api"... Feb 07 22:27:10 volumio volumio[32467]: info: Loading plugin "websocket"... Feb 07 22:27:10 volumio volumio[32467]: info: Starting Socket.io Server version 1.7.4 Feb 07 22:27:10 volumio volumio[32467]: info: Loading plugin "RoonBridge"... Feb 07 22:27:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Feb 07 22:27:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 22:27:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 22:27:10 volumio go-librespot[32589]: go-librespot daemon starting... Feb 07 22:27:10 volumio go-librespot[32590]: time="2026-02-07T22:27:10+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 22:27:10 volumio go-librespot[32590]: time="2026-02-07T22:27:10+07:00" level=debug msg="app state loaded" Feb 07 22:27:10 volumio go-librespot[32590]: time="2026-02-07T22:27:10+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 22:27:10 volumio volumio[32467]: info: Applying required configuration parameters for plugin RoonBridge Feb 07 22:27:10 volumio volumio[32467]: info: Loading i18n strings for locale en Feb 07 22:27:10 volumio volumio[32467]: Updating browse sources language Feb 07 22:27:10 volumio volumio[32467]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 22:27:10 volumio volumio[32579]: Forking 3 albumart workers Feb 07 22:27:11 volumio go-librespot[32590]: time="2026-02-07T22:27:11+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 22:27:11 volumio go-librespot[32590]: time="2026-02-07T22:27:11+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 22:27:11 volumio go-librespot[32590]: time="2026-02-07T22:27:11+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 22:27:11 volumio go-librespot[32590]: time="2026-02-07T22:27:11+07:00" level=info msg="zeroconf server listening on port 37295" Feb 07 22:27:11 volumio volumio[32467]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 22:27:11 volumio volumio[32467]: info: CoreCommandRouter::initPlayerControls Feb 07 22:27:11 volumio volumio[32467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:27:11 volumio volumio[32467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:27:11 volumio volumio[32467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:27:11 volumio volumio[32467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:27:11 volumio volumio[32467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:27:11 volumio volumio[32467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:27:11 volumio volumio[32467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:27:11 volumio volumio[32467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:27:11 volumio volumio[32467]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 22:27:11 volumio volumio[32467]: Express server listening on port 3000 Feb 07 22:27:11 volumio volumio[32467]: [Metrics] WebUI: 16s 171.43ms Feb 07 22:27:11 volumio volumio[32467]: info: CoreStateMachine::resetVolumioState Feb 07 22:27:11 volumio volumio[32467]: info: CoreStateMachine::getcurrentVolume Feb 07 22:27:11 volumio volumio[32467]: info: CoreCommandRouter::volumioRetrievevolume Feb 07 22:27:11 volumio go-librespot[32590]: time="2026-02-07T22:27:11+07:00" level=debug msg="obtained new client token: AABtOziU5w+yPJZF6AIYRgtY7R6kR7SXb7O2WzG2MU7Hn6j+3D8ZlOUv+zHSVXGq0nd5ruo9SuZgxeQD0FftU2GbD4PrKPwp4TSshS9ds7s1Vop9Mh+mGY8B7OGy0l+uO4XkKqkDczQH8Ssp2mCJt5oeSRElMgud1zDqI8A8LTDKjfViFQaAgx/6tJmEkRn+uiuyffjAFpCy/EOmzb6tpu8/v9I5KLlH4U1XFylX2k6wMClt5R4zyzD3cQ==" Feb 07 22:27:11 volumio go-librespot[32590]: time="2026-02-07T22:27:11+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 22:27:11 volumio sudo[32633]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 07 22:27:11 volumio sudo[32633]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 22:27:11 volumio sudo[32633]: pam_unix(sudo:session): session closed for user root Feb 07 22:27:11 volumio sudo[32635]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 07 22:27:11 volumio volumio[32467]: info: Volumio Network Manager: Network status updated: 1 Feb 07 22:27:11 volumio sudo[32635]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 22:27:11 volumio go-librespot[32590]: time="2026-02-07T22:27:11+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 07 22:27:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 22:27:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 22:27:11 volumio sudo[32635]: pam_unix(sudo:session): session closed for user root Feb 07 22:27:11 volumio volumio[32467]: info: VolumeController:: Volume=100 Mute =false Feb 07 22:27:11 volumio volumio[32467]: info: CoreStateMachine::pushState Feb 07 22:27:11 volumio volumio[32467]: info: CorePlayQueue::getTrack 0 Feb 07 22:27:11 volumio volumio[32467]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 22:27:11 volumio volumio[32467]: info: CoreCommandRouter::volumioPushState Feb 07 22:27:11 volumio volumio[32467]: info: CoreStateMachine::updateTrackBlock Feb 07 22:27:11 volumio volumio[32467]: info: CorePlayQueue::getTrackBlock Feb 07 22:27:11 volumio volumio[32467]: info: CoreCommandRouter::volumioRetrievevolume Feb 07 22:27:11 volumio volumio-remote-updater[643]: [2026-02-07 22:27:11] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1770478029 101 Feb 07 22:27:11 volumio volumio[32467]: 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 22:27:12 volumio volumio[32467]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 22:27:12 volumio volumio[32467]: info: Reloading queue from file Feb 07 22:27:12 volumio volumio[32467]: info: CoreStateMachine::setRepeat null single undefined Feb 07 22:27:12 volumio volumio[32467]: info: CoreStateMachine::pushState Feb 07 22:27:12 volumio volumio[32467]: info: CorePlayQueue::getTrack 0 Feb 07 22:27:12 volumio volumio[32467]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 22:27:12 volumio volumio[32467]: info: CoreCommandRouter::volumioPushState Feb 07 22:27:12 volumio volumio[32467]: info: CoreStateMachine::setRandom null Feb 07 22:27:12 volumio volumio[32467]: info: CoreStateMachine::pushState Feb 07 22:27:12 volumio volumio[32467]: info: CorePlayQueue::getTrack 0 Feb 07 22:27:12 volumio volumio[32467]: info: CoreCommandRouter::volumioPushState Feb 07 22:27:12 volumio volumio[32467]: info: Setting Device type: Raspberry PI Feb 07 22:27:12 volumio volumio[32467]: info: Completed loading Core Plugins Feb 07 22:27:12 volumio volumio[32467]: info: Preparing to generate the ALSA configuration file Feb 07 22:27:12 volumio volumio[32467]: info: VolumeController:: Volume=100 Mute =false Feb 07 22:27:12 volumio volumio[32467]: info: CoreStateMachine::pushState Feb 07 22:27:12 volumio volumio[32467]: info: CorePlayQueue::getTrack 0 Feb 07 22:27:12 volumio volumio[32467]: info: CoreCommandRouter::volumioPushState Feb 07 22:27:12 volumio volumio[32467]: info: Asound.conf file unchanged, so no further update is needed Feb 07 22:27:12 volumio volumio[32467]: info: Output device has changed, restarting MPD Feb 07 22:27:12 volumio sudo[32652]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 07 22:27:12 volumio sudo[32652]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 22:27:12 volumio volumio[32467]: info: Output device has changed, restarting Shairport Sync Feb 07 22:27:12 volumio volumio[32467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:27:12 volumio volumio[32467]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 22:27:12 volumio sudo[32654]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 07 22:27:12 volumio sudo[32654]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 22:27:12 volumio sudo[32652]: pam_unix(sudo:session): session closed for user root Feb 07 22:27:12 volumio sudo[32657]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 07 22:27:12 volumio sudo[32654]: pam_unix(sudo:session): session closed for user root Feb 07 22:27:12 volumio sudo[32657]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 22:27:12 volumio volumio[32467]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 07 22:27:12 volumio volumio[32467]: info: ___________ START PLUGINS ___________ Feb 07 22:27:12 volumio volumio[32467]: info: ControllerMpd::onStart: Initializing MPD Feb 07 22:27:12 volumio volumio[32467]: info: Creating MPD Configuration file Feb 07 22:27:12 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 07 22:27:12 volumio sudo[32665]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 07 22:27:12 volumio sudo[32665]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 22:27:12 volumio sudo[32665]: pam_unix(sudo:session): session closed for user root Feb 07 22:27:12 volumio volumio[32467]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 22:27:12 volumio volumio[32467]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 22:27:12 volumio volumio[32467]: info: [1770478032785] CoreMusicLibrary::Adding element Media Servers Feb 07 22:27:12 volumio volumio[32467]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 22:27:12 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 07 22:27:12 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 07 22:27:12 volumio systemd[1]: mpd.service: Consumed 7.106s CPU time. Feb 07 22:27:12 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 07 22:27:12 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 07 22:27:12 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 07 22:27:12 volumio sudo[32668]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 07 22:27:12 volumio sudo[32668]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 22:27:12 volumio volumio[32467]: info: UPNP Browser: Client initialized successfully Feb 07 22:27:12 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 07 22:27:12 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 07 22:27:12 volumio volumio[32467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:27:12 volumio volumio[32467]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 22:27:13 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 07 22:27:13 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 07 22:27:13 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 07 22:27:13 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 07 22:27:13 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 07 22:27:13 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 07 22:27:13 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 07 22:27:13 volumio volumio[32467]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 07 22:27:13 volumio volumio[32467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:27:13 volumio volumio[32467]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 22:27:13 volumio volumio[32467]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 07 22:27:13 volumio volumio[32467]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 22:27:13 volumio volumio[32467]: info: [1770478033138] CoreMusicLibrary::Adding element Last_100 Feb 07 22:27:13 volumio volumio[32467]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 22:27:13 volumio volumio[32467]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 22:27:13 volumio volumio[32467]: info: [1770478033147] CoreMusicLibrary::Adding element Webradio Feb 07 22:27:13 volumio volumio[32467]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 22:27:13 volumio volumio[32467]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 07 22:27:13 volumio volumio[32467]: info: Initializing BBC Radios Feb 07 22:27:13 volumio volumio[32467]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 07 22:27:13 volumio volumio[32467]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 22:27:13 volumio volumio[32467]: info: Creating Spotify config file Feb 07 22:27:13 volumio volumio[32467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:27:13 volumio sudo[32677]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 07 22:27:13 volumio sudo[32677]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 07 22:27:13 volumio sudo[32677]: pam_unix(sudo:session): session closed for user root Feb 07 22:27:14 volumio volumio[32599]: Starting albumart workers Feb 07 22:27:14 volumio volumio[32598]: Starting albumart workers Feb 07 22:27:14 volumio volumio[32600]: Starting albumart workers Feb 07 22:27:14 volumio volumio[32467]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 22:27:14 volumio volumio[32467]: info: [1770478034717] CoreMusicLibrary::Adding element YouTube Music Feb 07 22:27:14 volumio volumio[32467]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 22:27:14 volumio volumio[32467]: Cannot find translation for source YouTube Music Feb 07 22:27:14 volumio volumio[32467]: info: Volumio Calling Home Feb 07 22:27:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Feb 07 22:27:14 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 22:27:14 volumio sudo[32698]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 07 22:27:14 volumio sudo[32698]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 22:27:14 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 22:27:14 volumio go-librespot[32701]: go-librespot daemon starting... Feb 07 22:27:15 volumio sudo[32698]: pam_unix(sudo:session): session closed for user root Feb 07 22:27:15 volumio go-librespot[32703]: time="2026-02-07T22:27:15+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 22:27:15 volumio go-librespot[32703]: time="2026-02-07T22:27:15+07:00" level=info msg="zeroconf server listening on port 44687" Feb 07 22:27:16 volumio volumio[32467]: info: Discovery: adding 921648ed-0557-4d70-9927-1420682efcc7 Feb 07 22:27:16 volumio volumio[32467]: info: Discovery: Found device Volumio Feb 07 22:27:16 volumio volumio[32467]: info: CoreCommandRouter::volumioGetState Feb 07 22:27:16 volumio volumio[32467]: info: CorePlayQueue::getTrack 0 Feb 07 22:27:16 volumio volumio[32467]: info: Discovery: this is already registered, 921648ed-0557-4d70-9927-1420682efcc7 Feb 07 22:27:16 volumio volumio[32467]: info: Discovery: Found device Volumio Feb 07 22:27:16 volumio volumio[32467]: info: CoreCommandRouter::volumioGetState Feb 07 22:27:16 volumio volumio[32467]: info: CorePlayQueue::getTrack 0 Feb 07 22:27:16 volumio volumio[32467]: info: MPD Permissions set Feb 07 22:27:16 volumio volumio[32467]: info: MPD Permissions set Feb 07 22:27:16 volumio volumio[32467]: info: Upmpdcli Daemon Started Feb 07 22:27:16 volumio volumio[32467]: info: Volumio called home Feb 07 22:27:16 volumio volumio[32467]: info: Spotify config file written Feb 07 22:27:16 volumio volumio[32467]: 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 22:27:16 volumio sudo[32727]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 07 22:27:16 volumio sudo[32727]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 22:27:16 volumio volumio[32467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:27:16 volumio volumio[32467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:27:16 volumio volumio[32467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:27:16 volumio volumio[32467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:27:16 volumio volumio[32467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:27:16 volumio volumio[32467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:27:16 volumio volumio[32467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:27:16 volumio volumio[32467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:27:16 volumio volumio[32467]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 22:27:16 volumio volumio[32467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:27:16 volumio volumio[32467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:27:16 volumio volumio[32467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:27:16 volumio volumio[32467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:27:16 volumio volumio[32467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:27:16 volumio volumio[32467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:27:16 volumio volumio[32467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:27:16 volumio volumio[32467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:27:16 volumio volumio[32467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:27:16 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 07 22:27:16 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 07 22:27:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 22:27:16 volumio volumio[32467]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 22:27:16 volumio volumio[32467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:27:16 volumio volumio[32467]: info: No need to fix Spotify hosts Feb 07 22:27:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 22:27:16 volumio go-librespot[32742]: go-librespot daemon starting... Feb 07 22:27:16 volumio sudo[32727]: pam_unix(sudo:session): session closed for user root Feb 07 22:27:16 volumio go-librespot[32743]: time="2026-02-07T22:27:16+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 22:27:16 volumio go-librespot[32743]: time="2026-02-07T22:27:16+07:00" level=debug msg="app state loaded" Feb 07 22:27:16 volumio go-librespot[32743]: time="2026-02-07T22:27:16+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 22:27:17 volumio go-librespot[32743]: time="2026-02-07T22:27:17+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 22:27:17 volumio go-librespot[32743]: time="2026-02-07T22:27:17+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 22:27:17 volumio go-librespot[32743]: time="2026-02-07T22:27:17+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 22:27:17 volumio go-librespot[32743]: time="2026-02-07T22:27:17+07:00" level=info msg="zeroconf server listening on port 36313" Feb 07 22:27:17 volumio volumio[32467]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 07 22:27:17 volumio volumio[32467]: SPOTIFY: BQAOvNWndgwWNHMfZYB-b-m4z1fJqdEZuY22q0s_0cJHtlKbVQh_DPVfgbLSa5fVMUqwt6n8t42XZ8faxYp6ICIJGzLLmuau18biDjGU5_A2PX-G5phLFN5N8QMNQTvypgeJhg5d_GjLuFl8KEL9hvm2GB2RJ9wgFShFzp6ONqitRB32AhiUBU95AawNDLwcczb23lsD_Lqi2DchMkWD1oIYv49nGSbfzC4OYqjqs2NwAaqd34zqZ_6Xr7-5L5vxTKIEhdjTUpcNgXUNkF_WU-GtSOxAkyupaR4LIZJ2zBIe7DKjHGyWlEoC Feb 07 22:27:17 volumio volumio[32467]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 07 22:27:17 volumio volumio[32467]: info: New Spotify access token = BQAOvNWndgwWNHMfZYB-b-m4z1fJqdEZuY22q0s_0cJHtlKbVQh_DPVfgbLSa5fVMUqwt6n8t42XZ8faxYp6ICIJGzLLmuau18biDjGU5_A2PX-G5phLFN5N8QMNQTvypgeJhg5d_GjLuFl8KEL9hvm2GB2RJ9wgFShFzp6ONqitRB32AhiUBU95AawNDLwcczb23lsD_Lqi2DchMkWD1oIYv49nGSbfzC4OYqjqs2NwAaqd34zqZ_6Xr7-5L5vxTKIEhdjTUpcNgXUNkF_WU-GtSOxAkyupaR4LIZJ2zBIe7DKjHGyWlEoC Feb 07 22:27:17 volumio volumio[32467]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 07 22:27:17 volumio go-librespot[32743]: time="2026-02-07T22:27:17+07:00" level=debug msg="obtained new client token: AADeI+VbuQiG6CICXUbBmu5OPRfJIxePMhuA2hw4U9QaVUk1RYy+IermzxvX5gaM6SsrDIRUWO13o1nE3pq50OD+ffGXrOHVRMvoEo8i/21iEc/1oO6ksfY0W8+uhT6dgKdfdJIqw87vbge5nN9uTdW9tOISf0NtQ+NQHnW8fTj78Q7Elx0vQwZeNVxXMHY/pOdQxDaubiargNEX1KF8rB9Hf2Pnw5/zFUBQq64fn7JFbkQQb8njr6ounA==" Feb 07 22:27:17 volumio volumio[32467]: info: Starting Shairport Sync Feb 07 22:27:17 volumio volumio[32467]: info: Starting Shairport Sync Feb 07 22:27:17 volumio volumio[32467]: info: Starting Shairport Sync Feb 07 22:27:17 volumio sudo[32753]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 07 22:27:17 volumio go-librespot[32743]: time="2026-02-07T22:27:17+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 22:27:17 volumio sudo[32753]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 22:27:17 volumio sudo[32755]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 07 22:27:17 volumio sudo[32755]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 22:27:17 volumio sudo[32757]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 07 22:27:17 volumio go-librespot[32743]: time="2026-02-07T22:27:17+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 07 22:27:17 volumio sudo[32757]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 22:27:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 22:27:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 22:27:17 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 07 22:27:17 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 07 22:27:17 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 07 22:27:17 volumio systemd[1]: shairport-sync.service: Consumed 2.322s CPU time. Feb 07 22:27:17 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 07 22:27:17 volumio sudo[32757]: pam_unix(sudo:session): session closed for user root Feb 07 22:27:17 volumio sudo[32753]: pam_unix(sudo:session): session closed for user root Feb 07 22:27:17 volumio sudo[32755]: pam_unix(sudo:session): session closed for user root Feb 07 22:27:17 volumio volumio[32467]: info: CoreCommandRouter::volumioGetState Feb 07 22:27:17 volumio volumio[32467]: info: CorePlayQueue::getTrack 0 Feb 07 22:27:17 volumio volumio[32467]: info: Shairport-Sync Started Feb 07 22:27:17 volumio volumio[32467]: Error adding Membership: Error: addMembership EINVAL Feb 07 22:27:18 volumio volumio[32467]: info: Shairport-Sync Started Feb 07 22:27:18 volumio volumio[32467]: info: Shairport-Sync Started Feb 07 22:27:18 volumio volumio[32467]: 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 22:27:18 volumio volumio[32467]: info: Spotify Successfully logged in Feb 07 22:27:18 volumio volumio[32467]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 22:27:18 volumio volumio[32467]: info: [1770478038269] CoreMusicLibrary::Adding element Spotify Feb 07 22:27:18 volumio volumio[32467]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 22:27:18 volumio volumio[32467]: Cannot find translation for source YouTube Music Feb 07 22:27:18 volumio volumio[32467]: Cannot find translation for source Spotify Feb 07 22:27:19 volumio volumio[32467]: info: [yt-cast-receiver] DIAL server listening on port 8098 Feb 07 22:27:19 volumio volumio[32467]: info: CoreCommandRouter::volumioRetrievevolume Feb 07 22:27:19 volumio volumio[32467]: info: VolumeController:: Volume=100 Mute =false Feb 07 22:27:19 volumio volumio[32467]: info: CoreCommandRouter::volumioGetState Feb 07 22:27:19 volumio volumio[32467]: info: CorePlayQueue::getTrack 0 Feb 07 22:27:19 volumio volumio[32467]: info: CoreStateMachine::pushState Feb 07 22:27:19 volumio volumio[32467]: info: CorePlayQueue::getTrack 0 Feb 07 22:27:19 volumio volumio[32467]: info: CoreCommandRouter::volumioPushState Feb 07 22:27:20 volumio volumio[32467]: info: go-librespot daemon successfully initialized Feb 07 22:27:20 volumio mpd[32696]: 2026-02-07T22:27:20 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 07 22:27:20 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 07 22:27:20 volumio sudo[32657]: pam_unix(sudo:session): session closed for user root Feb 07 22:27:20 volumio sudo[32668]: pam_unix(sudo:session): session closed for user root Feb 07 22:27:20 volumio volumio[32467]: error: MPD error: The expression evaluated to a falsy value: Feb 07 22:27:20 volumio volumio[32467]: assert.ok(self.idling) Feb 07 22:27:20 volumio volumio[32467]: error: The expression evaluated to a falsy value: Feb 07 22:27:20 volumio volumio[32467]: assert.ok(self.idling) Feb 07 22:27:20 volumio volumio[32467]: error: updateQueue error: null Feb 07 22:27:20 volumio volumio[32467]: info: MPD running with PID32696 Feb 07 22:27:20 volumio volumio[32467]: ,establishing connection Feb 07 22:27:20 volumio volumio[32467]: info: Completed starting Core Plugins Feb 07 22:27:20 volumio volumio[32467]: info: ------------------------------------------- Feb 07 22:27:20 volumio volumio[32467]: info: ----- MyVolumio plugins startup ---- Feb 07 22:27:20 volumio volumio[32467]: info: ------------------------------------------- Feb 07 22:27:20 volumio volumio[32467]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 07 22:27:20 volumio volumio[32467]: error: updateQueue error: null Feb 07 22:27:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Feb 07 22:27:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 22:27:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 22:27:20 volumio go-librespot[329]: go-librespot daemon starting... Feb 07 22:27:20 volumio go-librespot[330]: time="2026-02-07T22:27:20+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 22:27:20 volumio go-librespot[330]: time="2026-02-07T22:27:20+07:00" level=debug msg="app state loaded" Feb 07 22:27:20 volumio go-librespot[330]: time="2026-02-07T22:27:20+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 22:27:21 volumio go-librespot[330]: time="2026-02-07T22:27: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-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 22:27:21 volumio go-librespot[330]: time="2026-02-07T22:27:21+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 22:27:21 volumio go-librespot[330]: time="2026-02-07T22:27:21+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 22:27:21 volumio go-librespot[330]: time="2026-02-07T22:27:21+07:00" level=info msg="zeroconf server listening on port 40523" Feb 07 22:27:21 volumio go-librespot[330]: time="2026-02-07T22:27:21+07:00" level=debug msg="obtained new client token: AADtjHlPmUUlJfV7I36rRrJVyGbIUrhQFZpMSnBLIQrdFoL9ts/2ePK9ysadoq1OfIOXW1vPB0ZlOiSSM24fvbfBhH3/HUoz+S5lKsbUmezoG+V5BuJ1deyIz/ImVQI9zJkOQ7mA5gK2aix67//az7AodY9p4lImLG97YHXbZv31NEVzbVDe5nZWizAxnEMNIxs302vH/Maab1wzs0DLaiE60dJhfcrvPc/6/uVcjFlAsREFRCuk8O5imw==" Feb 07 22:27:21 volumio go-librespot[330]: time="2026-02-07T22:27:21+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 22:27:21 volumio go-librespot[330]: time="2026-02-07T22:27:21+07:00" level=debug msg="completed keyexchange" Feb 07 22:27:21 volumio go-librespot[330]: time="2026-02-07T22:27:21+07:00" level=debug msg="completed challenge" Feb 07 22:27:21 volumio go-librespot[330]: time="2026-02-07T22:27: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 22:27:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 22:27:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 22:27:23 volumio volumio[32467]: info: Initializing connection to go-librespot Websocket Feb 07 22:27:23 volumio volumio[32467]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 07 22:27:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Feb 07 22:27:25 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 22:27:25 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 22:27:25 volumio go-librespot[339]: go-librespot daemon starting... Feb 07 22:27:25 volumio go-librespot[340]: time="2026-02-07T22:27:25+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 22:27:25 volumio go-librespot[340]: time="2026-02-07T22:27:25+07:00" level=debug msg="app state loaded" Feb 07 22:27:25 volumio go-librespot[340]: time="2026-02-07T22:27:25+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 22:27:25 volumio volumio[32467]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 07 22:27:25 volumio go-librespot[340]: time="2026-02-07T22:27: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-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 22:27:25 volumio go-librespot[340]: time="2026-02-07T22:27:25+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 22:27:25 volumio go-librespot[340]: time="2026-02-07T22:27:25+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 22:27:25 volumio go-librespot[340]: time="2026-02-07T22:27:25+07:00" level=info msg="zeroconf server listening on port 36975" Feb 07 22:27:25 volumio go-librespot[340]: time="2026-02-07T22:27:25+07:00" level=debug msg="obtained new client token: AABvZR60H52qdyhmGxsOkGQUS+cvsOUj6X7/2z/HrLGZfcXljXjxx65IgxnCay5Dn4EI39JU7OjNsqVaMmbTHfU74jWnyjg8dj8U8S5p/OJLFpXc5Y4EybBRJD3wQEG9nnOvQmCZUr2P6aSUhISQqiftx4PF6tcd3wVwEh4wIA3gRj4jC6m+J2gvWaoDa1HE7hch8meXmLhMe8CNSGkecRMgwpOclvex0i8NOPcfRzVMIlDhbQzgI7uPjw==" Feb 07 22:27:25 volumio go-librespot[340]: time="2026-02-07T22:27:25+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 22:27:26 volumio go-librespot[340]: time="2026-02-07T22:27:26+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 07 22:27:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 22:27:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 22:27:26 volumio volumio[32467]: info: Initializing connection to go-librespot Websocket Feb 07 22:27:26 volumio volumio[32467]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 07 22:27:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Feb 07 22:27:29 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 22:27:29 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 22:27:29 volumio go-librespot[348]: go-librespot daemon starting... Feb 07 22:27:29 volumio go-librespot[349]: time="2026-02-07T22:27:29+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 22:27:29 volumio go-librespot[349]: time="2026-02-07T22:27:29+07:00" level=debug msg="app state loaded" Feb 07 22:27:29 volumio go-librespot[349]: time="2026-02-07T22:27:29+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 22:27:29 volumio volumio[32467]: info: Initializing connection to go-librespot Websocket Feb 07 22:27:29 volumio go-librespot[349]: time="2026-02-07T22:27:29+07:00" level=debug msg="new websocket client" Feb 07 22:27:29 volumio volumio[32467]: info: Connection to go-librespot Websocket established Feb 07 22:27:29 volumio go-librespot[349]: time="2026-02-07T22:27: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-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 07 22:27:29 volumio go-librespot[349]: time="2026-02-07T22:27:29+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 22:27:29 volumio go-librespot[349]: time="2026-02-07T22:27:29+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 22:27:29 volumio volumio[32467]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 07 22:27:29 volumio volumio[32467]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 07 22:27:29 volumio volumio[32467]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 07 22:27:29 volumio volumio[32467]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 07 22:27:29 volumio volumio[32467]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 07 22:27:29 volumio volumio[32467]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 07 22:27:29 volumio volumio[32467]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 07 22:27:29 volumio volumio[32467]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 07 22:27:29 volumio volumio[32467]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 07 22:27:29 volumio volumio[32467]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 07 22:27:29 volumio volumio[32467]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 07 22:27:29 volumio volumio[32467]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 07 22:27:29 volumio volumio[32467]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 07 22:27:29 volumio volumio[32467]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 07 22:27:29 volumio volumio[32467]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 07 22:27:29 volumio volumio[32467]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 07 22:27:29 volumio volumio[32467]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 07 22:27:29 volumio volumio[32467]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 07 22:27:29 volumio volumio[32467]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 07 22:27:29 volumio volumio[32467]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 07 22:27:29 volumio volumio[32467]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 07 22:27:29 volumio volumio[32467]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 07 22:27:29 volumio volumio[32467]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 07 22:27:29 volumio volumio[32467]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 07 22:27:29 volumio volumio[32467]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 07 22:27:29 volumio volumio[32467]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 07 22:27:29 volumio volumio[32467]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 07 22:27:29 volumio volumio[32467]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 07 22:27:29 volumio volumio[32467]: info: Adding plugin bluetooth to MyMusic Plugins Feb 07 22:27:29 volumio volumio[32467]: info: Adding plugin multiroom to MyMusic Plugins Feb 07 22:27:29 volumio volumio[32467]: info: Adding plugin metavolumio to MyMusic Plugins Feb 07 22:27:29 volumio volumio[32467]: info: Adding plugin cd_controller to MyMusic Plugins Feb 07 22:27:29 volumio volumio[32467]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 07 22:27:29 volumio volumio[32467]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 07 22:27:29 volumio go-librespot[349]: time="2026-02-07T22:27:29+07:00" level=info msg="zeroconf server listening on port 45967" Feb 07 22:27:29 volumio volumio[32467]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 07 22:27:29 volumio volumio[32467]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 07 22:27:29 volumio go-librespot[349]: time="2026-02-07T22:27:29+07:00" level=debug msg="obtained new client token: AACYq2SGd9T9lyKnddCyVxtnf+nnuKGFwle2atSQ5t9l2hZ11CNS96I2+PHYBKYZHO7o2JWNjKvbJ3DZqyRniNv40wcJfEymCJgeVJAUzaxye922B/GnIAxFNBTb7/4wJVQjtA4PjDsomQ8bia7xLp4LT7CrI37JvXlIbZhgzGKf0/TJ3cfYbdB++TnTjA7Niioe80mx/N76+X61cyhm77tDoCSC1qrgp3EkrjqXJ9SqiPbmzovhDuVbJA==" Feb 07 22:27:29 volumio go-librespot[349]: time="2026-02-07T22:27:29+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 22:27:30 volumio go-librespot[349]: time="2026-02-07T22:27:30+07:00" level=debug msg="completed keyexchange" Feb 07 22:27:30 volumio go-librespot[349]: time="2026-02-07T22:27:30+07:00" level=debug msg="completed challenge" Feb 07 22:27:30 volumio go-librespot[349]: time="2026-02-07T22:27: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 22:27:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 22:27:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 22:27:31 volumio volumio[32467]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 07 22:27:31 volumio volumio[32467]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 07 22:27:31 volumio volumio[32467]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 22:27:31 volumio volumio[32467]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 22:27:31 volumio volumio[32467]: info: Starting MyVolumio Remote Streaming Endpoints Feb 07 22:27:31 volumio volumio[32467]: info: MyVolumio login type: Token Feb 07 22:27:31 volumio volumio[32467]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 07 22:27:31 volumio volumio[32467]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 07 22:27:33 volumio volumio[32467]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 07 22:27:33 volumio volumio[32467]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 07 22:27:33 volumio volumio[32467]: info: Streaming services startup Feb 07 22:27:33 volumio volumio[32467]: info: Starting Streaming Daemon Feb 07 22:27:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Feb 07 22:27:33 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 22:27:33 volumio sudo[377]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 07 22:27:33 volumio sudo[377]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 22:27:33 volumio volumio[32467]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 07 22:27:33 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 22:27:33 volumio go-librespot[378]: go-librespot daemon starting... Feb 07 22:27:33 volumio sudo[377]: pam_unix(sudo:session): session closed for user root Feb 07 22:27:33 volumio go-librespot[384]: time="2026-02-07T22:27:33+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 22:27:33 volumio go-librespot[384]: time="2026-02-07T22:27:33+07:00" level=debug msg="app state loaded" Feb 07 22:27:33 volumio go-librespot[384]: time="2026-02-07T22:27:33+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 22:27:33 volumio volumio[32467]: info: Getting Spotify volume Feb 07 22:27:33 volumio volumio[32467]: info: Connection to go-librespot Websocket closed Feb 07 22:27:33 volumio volumio[32467]: error: Cannot start Volumio Streaming Daemon Feb 07 22:27:33 volumio volumio[32467]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 07 22:27:33 volumio volumio[32467]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 07 22:27:33 volumio volumio[32467]: 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 22:27:33 volumio volumio[32467]: info: CoreCommandRouter::volumioGetState Feb 07 22:27:33 volumio volumio[32467]: info: CorePlayQueue::getTrack 0 Feb 07 22:27:33 volumio volumio[32467]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 07 22:27:33 volumio volumio[32467]: SPOTIFY: SPOTIFY VOLUME undefined Feb 07 22:27:33 volumio volumio[32467]: SPOTIFY: VOLUMIO VOLUME 100 Feb 07 22:27:33 volumio volumio[32467]: info: Aligning Spotify Volume to Volumio Volume Feb 07 22:27:33 volumio volumio[32467]: info: CoreCommandRouter::volumioGetState Feb 07 22:27:33 volumio volumio[32467]: info: CorePlayQueue::getTrack 0 Feb 07 22:27:33 volumio volumio[32467]: info: Setting Spotify Volume from Volumio: 100 Feb 07 22:27:33 volumio go-librespot[384]: time="2026-02-07T22:27:33+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 22:27:33 volumio go-librespot[384]: time="2026-02-07T22:27:33+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 22:27:33 volumio go-librespot[384]: time="2026-02-07T22:27:33+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 22:27:34 volumio go-librespot[384]: time="2026-02-07T22:27:34+07:00" level=info msg="zeroconf server listening on port 36787" Feb 07 22:27:34 volumio go-librespot[384]: time="2026-02-07T22:27:34+07:00" level=debug msg="obtained new client token: AACNjjqdY9uAnlkJTQu+QZxf+ybWXzJaVPxJ9NMWtN+eNhUlCt0gzPQ+8AqMNIBEf5i2bbnpxbNDs12oCuS1D3Oe1diBcyygJovTOmqG2rJJihrURhcogBtgs14qBa7p8o8101zeQZJOByGXgqWOUz9/AW0Vmfa66PzCVtX4+X7L0tYLbe/bpACHiVrZfrUUEFfO3ftLk1g2e9tjL3CaEvjxVdZI698Q82zHKsXJ8L0HrXc1lDaXB1o=" Feb 07 22:27:34 volumio go-librespot[384]: time="2026-02-07T22:27:34+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 22:27:34 volumio go-librespot[384]: time="2026-02-07T22:27:34+07:00" level=debug msg="completed keyexchange" Feb 07 22:27:34 volumio go-librespot[384]: time="2026-02-07T22:27:34+07:00" level=debug msg="completed challenge" Feb 07 22:27:34 volumio go-librespot[384]: time="2026-02-07T22:27: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 22:27:34 volumio volumio[32467]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 07 22:27:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 22:27:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 22:27:34 volumio volumio[32467]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 22:27:34 volumio volumio[32467]: Error: socket hang up Feb 07 22:27:34 volumio volumio[32467]: at connResetException (node:internal/errors:720:14) Feb 07 22:27:34 volumio volumio[32467]: at Socket.socketOnEnd (node:_http_client:519:23) Feb 07 22:27:34 volumio volumio[32467]: at Socket.emit (node:events:526:35) Feb 07 22:27:34 volumio volumio[32467]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 07 22:27:34 volumio volumio[32467]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Feb 07 22:27:34 volumio volumio[32467]: code: 'ECONNRESET', Feb 07 22:27:34 volumio volumio[32467]: response: undefined Feb 07 22:27:34 volumio volumio[32467]: } Feb 07 22:27:34 volumio volumio[32467]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 22:27:36 volumio sudo[405]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-07 22:26' Feb 07 22:27:36 volumio sudo[405]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 22:27:36 volumio sudo[405]: pam_unix(sudo:session): session closed for user root Feb 07 22:27:36 volumio volumio-remote-updater[643]: [2026-02-07 22:27:36] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 07 22:27:36 volumio volumio-remote-updater[643]: [2026-02-07 22:27:36] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 07 22:27:36 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 07 22:27:36 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 07 22:27:36 volumio systemd[1]: volumio.service: Consumed 55.857s CPU time. Feb 07 22:27:36 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 07 22:27:36 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 07 22:27:36 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 6405. Feb 07 22:27:36 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 07 22:27:36 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 07 22:27:36 volumio systemd[1]: volumio.service: Consumed 55.857s CPU time. Feb 07 22:27:36 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 07 22:27:36 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 07 22:27:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Feb 07 22:27:37 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 22:27:37 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 22:27:37 volumio go-librespot[435]: go-librespot daemon starting... Feb 07 22:27:37 volumio go-librespot[436]: time="2026-02-07T22:27:37+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 22:27:37 volumio go-librespot[436]: time="2026-02-07T22:27:37+07:00" level=debug msg="app state loaded" Feb 07 22:27:37 volumio go-librespot[436]: time="2026-02-07T22:27:37+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 22:27:38 volumio go-librespot[436]: time="2026-02-07T22:27: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-gew4.spotify.com:80]" Feb 07 22:27:38 volumio go-librespot[436]: time="2026-02-07T22:27:38+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 22:27:38 volumio go-librespot[436]: time="2026-02-07T22:27:38+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 22:27:38 volumio go-librespot[436]: time="2026-02-07T22:27:38+07:00" level=info msg="zeroconf server listening on port 43077" Feb 07 22:27:38 volumio go-librespot[436]: time="2026-02-07T22:27:38+07:00" level=debug msg="obtained new client token: AACbD2Iht6HyHQBuB6ctWRj+43zkwTDMoYxx9dDaRJtvNU34+IbUCuh2EywtUUE2FAz5DLvQRi55q6HfCU/WOtzk67gqXLkxUMcDNIiGYTeQ/Zg13v1H1bR/zCBZ5lDaXqJhw10rL1xqHnEldigltKDiG4PAvoXE6qSKM2tZOWsoxDjCRCSY80Av0nHkB4u/G1nEsOoE1rxzuaZyemsd9XrEHKi5Ml5AvSM59yyidOwpxip0Ph+YEXRgig==" Feb 07 22:27:38 volumio go-librespot[436]: time="2026-02-07T22:27:38+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 22:27:38 volumio go-librespot[436]: time="2026-02-07T22:27:38+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Feb 07 22:27:38 volumio go-librespot[436]: time="2026-02-07T22:27:38+07:00" level=debug msg="completed keyexchange" Feb 07 22:27:38 volumio go-librespot[436]: time="2026-02-07T22:27:38+07:00" level=debug msg="completed challenge" Feb 07 22:27:38 volumio go-librespot[436]: time="2026-02-07T22:27:38+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 22:27:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 22:27:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 22:27:40 volumio volumio[419]: info: ------------------------------------------- Feb 07 22:27:40 volumio volumio[419]: info: ----- Volumio3 ---- Feb 07 22:27:40 volumio volumio[419]: info: ------------------------------------------- Feb 07 22:27:40 volumio volumio[419]: info: ----- System startup ---- Feb 07 22:27:40 volumio volumio[419]: info: ------------------------------------------- Feb 07 22:27:41 volumio volumio-remote-updater[643]: [2026-02-07 22:27:41] [connect] Successful connection Feb 07 22:27:41 volumio volumio[419]: info: MYVOLUMIO Environment detected Feb 07 22:27:41 volumio volumio[419]: info: Plugin folders cleanup Feb 07 22:27:41 volumio volumio[419]: info: Scanning into folder /volumio/app/plugins/ Feb 07 22:27:41 volumio volumio[419]: info: Scanning category audio_interface Feb 07 22:27:41 volumio volumio[419]: info: Scanning category miscellanea Feb 07 22:27:41 volumio volumio[419]: info: Scanning category music_service Feb 07 22:27:41 volumio volumio[419]: info: Scanning category plugins.json Feb 07 22:27:41 volumio volumio[419]: info: Scanning category system_controller Feb 07 22:27:41 volumio volumio[419]: info: Scanning category user_interface Feb 07 22:27:41 volumio volumio[419]: info: Scanning into folder /data/plugins/ Feb 07 22:27:41 volumio volumio[419]: info: Scanning category music_service Feb 07 22:27:41 volumio volumio[419]: info: Plugin folders cleanup completed Feb 07 22:27:41 volumio volumio[419]: info: ------------------------------------------- Feb 07 22:27:41 volumio volumio[419]: info: ----- Core plugins startup ---- Feb 07 22:27:41 volumio volumio[419]: info: ------------------------------------------- Feb 07 22:27:41 volumio volumio[419]: info: Loading plugins from folder /volumio/app/plugins/ Feb 07 22:27:41 volumio volumio[419]: info: Adding plugin upnp to MyMusic Plugins Feb 07 22:27:41 volumio volumio[419]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 07 22:27:41 volumio volumio[419]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 07 22:27:41 volumio volumio[419]: info: Loading plugins from folder /data/plugins/ Feb 07 22:27:41 volumio volumio[419]: info: Loading plugin "system"... Feb 07 22:27:41 volumio volumio[419]: info: Loading plugin "appearance"... Feb 07 22:27:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Feb 07 22:27:42 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 22:27:42 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 22:27:42 volumio go-librespot[455]: go-librespot daemon starting... Feb 07 22:27:42 volumio go-librespot[456]: time="2026-02-07T22:27:42+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 22:27:42 volumio go-librespot[456]: time="2026-02-07T22:27:42+07:00" level=debug msg="app state loaded" Feb 07 22:27:42 volumio go-librespot[456]: time="2026-02-07T22:27:42+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 22:27:42 volumio go-librespot[456]: time="2026-02-07T22:27: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 22:27:42 volumio go-librespot[456]: time="2026-02-07T22:27: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 22:27:42 volumio go-librespot[456]: time="2026-02-07T22:27: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 22:27:42 volumio go-librespot[456]: time="2026-02-07T22:27:42+07:00" level=info msg="zeroconf server listening on port 41355" Feb 07 22:27:42 volumio go-librespot[456]: time="2026-02-07T22:27:42+07:00" level=debug msg="obtained new client token: AAA8udMFrAp+XohWGnCosLWIM8301jhTlFomn4sdJYbRKyfIdJyc+msi9Uv2VkiuD6YswBCL0vGkKK7XltzD0ThygaVmdlz9m8610rZk/U3wwIT0dhVKQ0PVhWyfuQabwOd+a1yTojULxNaKq19aJTX7Kmt7NEz5FnjDdKFzkj5DsEUN697PveR9fHoSbUVqewz0TPfOmxh0irZmsacaYZG1KzlETSRys8Z0nvrm7zLWx8kj96M4+oxS6A==" Feb 07 22:27:43 volumio go-librespot[456]: time="2026-02-07T22:27:43+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 22:27:43 volumio go-librespot[456]: time="2026-02-07T22:27:43+07:00" level=debug msg="completed keyexchange" Feb 07 22:27:43 volumio go-librespot[456]: time="2026-02-07T22:27:43+07:00" level=debug msg="completed challenge" Feb 07 22:27:43 volumio go-librespot[456]: time="2026-02-07T22:27: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 22:27:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 22:27:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 22:27:43 volumio volumio[419]: info: Loading plugin "network"... Feb 07 22:27:43 volumio volumio[419]: info: Refreshing Cached IP Addresses Feb 07 22:27:43 volumio sudo[465]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 07 22:27:43 volumio sudo[465]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 22:27:43 volumio sudo[465]: pam_unix(sudo:session): session closed for user root Feb 07 22:27:43 volumio volumio[419]: info: Loading plugin "services"... Feb 07 22:27:43 volumio sudo[467]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 07 22:27:43 volumio sudo[467]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 22:27:43 volumio volumio[419]: info: Loading plugin "alsa_controller"... Feb 07 22:27:44 volumio sudo[467]: pam_unix(sudo:session): session closed for user root Feb 07 22:27:44 volumio sudo[475]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 07 22:27:44 volumio sudo[475]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 22:27:44 volumio volumio[419]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 07 22:27:44 volumio volumio[419]: info: Loading plugin "wizard"... Feb 07 22:27:44 volumio volumio[419]: info: Loading plugin "networkfs"... Feb 07 22:27:44 volumio volumio[419]: info: Starting Udev Watcher for removable devices Feb 07 22:27:44 volumio volumio[419]: info: Ignoring mount for partition: boot Feb 07 22:27:44 volumio volumio[419]: info: Ignoring mount for partition: volumio Feb 07 22:27:44 volumio volumio[419]: info: Ignoring mount for partition: volumio_data Feb 07 22:27:44 volumio volumio[419]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 07 22:27:44 volumio volumio[419]: info: Loading plugin "volumio_command_line_client"... Feb 07 22:27:44 volumio volumio[419]: info: Loading plugin "upnp"... Feb 07 22:27:44 volumio volumio[419]: info: [1770478064284] Starting Upmpd Daemon Feb 07 22:27:44 volumio volumio[419]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 07 22:27:44 volumio volumio[419]: info: Loading plugin "my_music"... Feb 07 22:27:44 volumio volumio[419]: info: Loading plugin "mpd"... Feb 07 22:27:44 volumio volumio[419]: info: Loading plugin "upnp_browser"... Feb 07 22:27:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Feb 07 22:27:46 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 22:27:46 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 22:27:46 volumio go-librespot[500]: go-librespot daemon starting... Feb 07 22:27:46 volumio go-librespot[501]: time="2026-02-07T22:27:46+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 22:27:46 volumio go-librespot[501]: time="2026-02-07T22:27:46+07:00" level=debug msg="app state loaded" Feb 07 22:27:46 volumio go-librespot[501]: time="2026-02-07T22:27:46+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 22:27:46 volumio sudo[475]: pam_unix(sudo:session): session closed for user root Feb 07 22:27:46 volumio go-librespot[501]: time="2026-02-07T22:27:46+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 22:27:46 volumio go-librespot[501]: time="2026-02-07T22:27:46+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 22:27:46 volumio go-librespot[501]: time="2026-02-07T22:27:46+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 22:27:46 volumio go-librespot[501]: time="2026-02-07T22:27:46+07:00" level=info msg="zeroconf server listening on port 44059" Feb 07 22:27:47 volumio go-librespot[501]: time="2026-02-07T22:27:47+07:00" level=debug msg="obtained new client token: AAAfLn9ujjQ5sPIdzQxivBHdElPjykKMjT2pfaQ3CU0ACydlZgpqjD2XI8DqWHTlFd7EBxYOCQ2oW5ZchtDnIur2g/0kRR/y/IYUSGBBcTBLmQKw8FMDjBcKzrh4LAN4CMM3wCgki8EwFBE3oOalveDpM+C8IvzFce7u5hTUwPtGMu0XpEBny+XPNJ+eycxM/vQiHUhMyk2TVrdY8ChvewubgAUU8uFqFQcpmwCKVP7n9vr6aD/NT7E=" Feb 07 22:27:47 volumio go-librespot[501]: time="2026-02-07T22:27:47+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 22:27:47 volumio go-librespot[501]: time="2026-02-07T22:27:47+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Feb 07 22:27:47 volumio go-librespot[501]: time="2026-02-07T22:27:47+07:00" level=debug msg="completed keyexchange" Feb 07 22:27:47 volumio go-librespot[501]: time="2026-02-07T22:27:47+07:00" level=debug msg="completed challenge" Feb 07 22:27:47 volumio go-librespot[501]: time="2026-02-07T22:27: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 22:27:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 22:27:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 22:27:47 volumio volumio[419]: info: Starting UPNP Browser Feb 07 22:27:47 volumio volumio[419]: info: Loading plugin "alarm-clock"... Feb 07 22:27:48 volumio volumio[419]: info: Loading plugin "airplay_emulation"... Feb 07 22:27:48 volumio volumio[419]: info: Starting Shairport Sync Feb 07 22:27:48 volumio volumio[419]: info: Loading plugin "last_100"... Feb 07 22:27:48 volumio volumio[419]: info: Loading plugin "webradio"... Feb 07 22:27:48 volumio volumio[419]: info: Loading plugin "i2s_dacs"... Feb 07 22:27:48 volumio volumio[419]: info: Loading plugin "volumiodiscovery"... Feb 07 22:27:48 volumio volumio[419]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 07 22:27:48 volumio volumio[419]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 22:27:48 volumio volumio[419]: *** WARNING *** For more information see Feb 07 22:27:48 volumio volumio[419]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 07 22:27:48 volumio volumio[419]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 22:27:48 volumio volumio[419]: *** WARNING *** For more information see Feb 07 22:27:48 volumio node[419]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 07 22:27:48 volumio node[419]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 22:27:48 volumio node[419]: *** WARNING *** For more information see Feb 07 22:27:48 volumio node[419]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 07 22:27:48 volumio node[419]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 22:27:48 volumio node[419]: *** WARNING *** For more information see Feb 07 22:27:48 volumio volumio[419]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 07 22:27:48 volumio volumio[419]: info: Discovery: Started advertising with name: Volumio Feb 07 22:27:48 volumio volumio[419]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 07 22:27:48 volumio volumio[419]: info: Loading plugin "spop"... Feb 07 22:27:50 volumio volumio[419]: info: Loading plugin "ytcr"... Feb 07 22:27:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Feb 07 22:27:50 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 22:27:50 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 22:27:50 volumio go-librespot[518]: go-librespot daemon starting... Feb 07 22:27:50 volumio go-librespot[519]: time="2026-02-07T22:27:50+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 22:27:50 volumio go-librespot[519]: time="2026-02-07T22:27:50+07:00" level=debug msg="app state loaded" Feb 07 22:27:50 volumio go-librespot[519]: time="2026-02-07T22:27:50+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 22:27:51 volumio go-librespot[519]: time="2026-02-07T22:27: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-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 22:27:51 volumio go-librespot[519]: time="2026-02-07T22:27:51+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 22:27:51 volumio go-librespot[519]: time="2026-02-07T22:27:51+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 22:27:51 volumio go-librespot[519]: time="2026-02-07T22:27:51+07:00" level=info msg="zeroconf server listening on port 43241" Feb 07 22:27:51 volumio go-librespot[519]: time="2026-02-07T22:27:51+07:00" level=debug msg="obtained new client token: AAANdBB9yVqPNe5QN92GCv4FeiFRGDPdL7jrYE9n39Q0SqQRy3sUgh3wpUtKrtnviTCUtTw3GYtEOd8yh3HbMynkbW6NKrt1j3PLI0jPkm7OiE0XpnJcOvpt+e5zewPygDbCSqua/NBN9uDfcB9+1dn1jGzW87jaj/DlMWC8rfLpwMPFqL/J6R3YrB8f/V/5cdYyKp0gIaT1jK5nvqUGav0iSdsp/0D7wVzGP8yDvl9eOF+IviEvZe58Dw==" Feb 07 22:27:51 volumio go-librespot[519]: time="2026-02-07T22:27:51+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 22:27:51 volumio go-librespot[519]: time="2026-02-07T22:27:51+07:00" level=debug msg="completed keyexchange" Feb 07 22:27:51 volumio go-librespot[519]: time="2026-02-07T22:27:51+07:00" level=debug msg="completed challenge" Feb 07 22:27:51 volumio go-librespot[519]: time="2026-02-07T22:27:51+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 22:27:51 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 22:27:51 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 22:27:53 volumio volumio[419]: info: Loading plugin "ytmusic"... Feb 07 22:27:54 volumio volumio-remote-updater[643]: [2026-02-07 22:27:54] [connect] Successful connection Feb 07 22:27:54 volumio volumio[419]: info: Loading plugin "outputs"... Feb 07 22:27:54 volumio volumio[419]: info: Loading plugin "albumart"... Feb 07 22:27:54 volumio volumio[419]: info: Plugin example_plugin is not enabled Feb 07 22:27:54 volumio volumio[419]: info: Loading plugin "inputs"... Feb 07 22:27:54 volumio volumio[419]: info: Loading plugin "updater_comm"... Feb 07 22:27:54 volumio volumio[419]: info: Plugin mpdemulation is not enabled Feb 07 22:27:54 volumio volumio[419]: info: Loading plugin "rest_api"... Feb 07 22:27:54 volumio volumio[419]: info: Loading plugin "websocket"... Feb 07 22:27:54 volumio volumio[419]: info: Starting Socket.io Server version 1.7.4 Feb 07 22:27:54 volumio volumio[419]: info: Loading plugin "RoonBridge"... Feb 07 22:27:54 volumio volumio[419]: info: Applying required configuration parameters for plugin RoonBridge Feb 07 22:27:55 volumio volumio[419]: info: Loading i18n strings for locale en Feb 07 22:27:55 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Feb 07 22:27:55 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 22:27:55 volumio volumio[419]: Updating browse sources language Feb 07 22:27:55 volumio volumio[419]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 22:27:55 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 22:27:55 volumio go-librespot[550]: go-librespot daemon starting... Feb 07 22:27:55 volumio volumio[540]: Forking 3 albumart workers Feb 07 22:27:55 volumio go-librespot[551]: time="2026-02-07T22:27:55+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 22:27:55 volumio go-librespot[551]: time="2026-02-07T22:27:55+07:00" level=debug msg="app state loaded" Feb 07 22:27:55 volumio go-librespot[551]: time="2026-02-07T22:27:55+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 22:27:55 volumio volumio[419]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 22:27:55 volumio volumio[419]: info: CoreCommandRouter::initPlayerControls Feb 07 22:27:55 volumio volumio[419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:27:55 volumio volumio[419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:27:55 volumio volumio[419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:27:55 volumio volumio[419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:27:55 volumio volumio[419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:27:55 volumio volumio[419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:27:55 volumio volumio[419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:27:55 volumio volumio[419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:27:55 volumio volumio[419]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 22:27:55 volumio volumio[419]: Express server listening on port 3000 Feb 07 22:27:55 volumio volumio[419]: [Metrics] WebUI: 16s 8.61ms Feb 07 22:27:55 volumio volumio[419]: info: CoreStateMachine::resetVolumioState Feb 07 22:27:55 volumio volumio[419]: info: CoreStateMachine::getcurrentVolume Feb 07 22:27:55 volumio volumio[419]: info: CoreCommandRouter::volumioRetrievevolume Feb 07 22:27:55 volumio sudo[599]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 07 22:27:55 volumio sudo[599]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 22:27:55 volumio sudo[597]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 07 22:27:55 volumio sudo[597]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 22:27:55 volumio sudo[599]: pam_unix(sudo:session): session closed for user root Feb 07 22:27:55 volumio sudo[597]: pam_unix(sudo:session): session closed for user root Feb 07 22:27:55 volumio go-librespot[551]: time="2026-02-07T22:27: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-gew4.spotify.com:80]" Feb 07 22:27:55 volumio go-librespot[551]: time="2026-02-07T22:27:55+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 22:27:55 volumio go-librespot[551]: time="2026-02-07T22:27:55+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 22:27:55 volumio go-librespot[551]: time="2026-02-07T22:27:55+07:00" level=info msg="zeroconf server listening on port 37713" Feb 07 22:27:55 volumio volumio[419]: info: Volumio Network Manager: Network status updated: 1 Feb 07 22:27:56 volumio go-librespot[551]: time="2026-02-07T22:27:56+07:00" level=debug msg="obtained new client token: AADqPDc+25JVuRJSYdw8S5nBGhMaIOxDFzzQ99w47+93uKAmM+F/+yCRZXasgtgr/q9Mz7tMd9b7+3Bg1jejUWFV/CivcB7d1By1oR3YIUv6qnKZ/id851eyiFoKk56A9c7Vh8oA4F7yU9R16LdKCwk7dHJwSyQXkm4gdlBguCOSyTLyyQSmJYh4o4LTEeJ6SswJcJigHIm1MsizMJRMGVx0LT7NC0ouhrGmS6zbSeWcD1VDP+chCKk=" Feb 07 22:27:56 volumio volumio[419]: info: VolumeController:: Volume=100 Mute =false Feb 07 22:27:56 volumio volumio[419]: info: CoreStateMachine::pushState Feb 07 22:27:56 volumio volumio[419]: info: CorePlayQueue::getTrack 0 Feb 07 22:27:56 volumio volumio[419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 22:27:56 volumio volumio[419]: info: CoreCommandRouter::volumioPushState Feb 07 22:27:56 volumio go-librespot[551]: time="2026-02-07T22:27:56+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 22:27:56 volumio volumio[419]: info: CoreStateMachine::updateTrackBlock Feb 07 22:27:56 volumio volumio[419]: info: CorePlayQueue::getTrackBlock Feb 07 22:27:56 volumio volumio[419]: info: CoreCommandRouter::volumioRetrievevolume Feb 07 22:27:56 volumio volumio-remote-updater[643]: [2026-02-07 22:27:56] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1770478074 101 Feb 07 22:27:56 volumio go-librespot[551]: time="2026-02-07T22:27:56+07:00" level=debug msg="completed keyexchange" Feb 07 22:27:56 volumio volumio[419]: 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 22:27:56 volumio go-librespot[551]: time="2026-02-07T22:27:56+07:00" level=debug msg="completed challenge" Feb 07 22:27:56 volumio go-librespot[551]: time="2026-02-07T22:27: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 22:27:56 volumio volumio[419]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 22:27:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 22:27:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 22:27:56 volumio volumio[419]: info: Reloading queue from file Feb 07 22:27:56 volumio volumio[419]: info: CoreStateMachine::setRepeat null single undefined Feb 07 22:27:56 volumio volumio[419]: info: CoreStateMachine::pushState Feb 07 22:27:56 volumio volumio[419]: info: CorePlayQueue::getTrack 0 Feb 07 22:27:56 volumio volumio[419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 22:27:56 volumio volumio[419]: info: CoreCommandRouter::volumioPushState Feb 07 22:27:56 volumio volumio[419]: info: CoreStateMachine::setRandom null Feb 07 22:27:56 volumio volumio[419]: info: CoreStateMachine::pushState Feb 07 22:27:56 volumio volumio[419]: info: CorePlayQueue::getTrack 0 Feb 07 22:27:56 volumio volumio[419]: info: CoreCommandRouter::volumioPushState Feb 07 22:27:56 volumio volumio[419]: info: Setting Device type: Raspberry PI Feb 07 22:27:56 volumio volumio[419]: info: Completed loading Core Plugins Feb 07 22:27:56 volumio volumio[419]: info: Preparing to generate the ALSA configuration file Feb 07 22:27:56 volumio volumio[419]: info: VolumeController:: Volume=100 Mute =false Feb 07 22:27:56 volumio volumio[419]: info: CoreStateMachine::pushState Feb 07 22:27:56 volumio volumio[419]: info: CorePlayQueue::getTrack 0 Feb 07 22:27:56 volumio volumio[419]: info: CoreCommandRouter::volumioPushState Feb 07 22:27:56 volumio volumio[419]: info: Asound.conf file unchanged, so no further update is needed Feb 07 22:27:56 volumio volumio[419]: info: Output device has changed, restarting MPD Feb 07 22:27:56 volumio sudo[612]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 07 22:27:56 volumio sudo[612]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 22:27:56 volumio volumio[419]: info: Output device has changed, restarting Shairport Sync Feb 07 22:27:56 volumio volumio[419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:27:56 volumio volumio[419]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 22:27:57 volumio sudo[614]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 07 22:27:57 volumio sudo[614]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 22:27:57 volumio volumio[419]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 07 22:27:57 volumio volumio[419]: info: ___________ START PLUGINS ___________ Feb 07 22:27:57 volumio sudo[617]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 07 22:27:57 volumio sudo[617]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 22:27:57 volumio sudo[614]: pam_unix(sudo:session): session closed for user root Feb 07 22:27:57 volumio volumio[419]: info: ControllerMpd::onStart: Initializing MPD Feb 07 22:27:57 volumio volumio[419]: info: Creating MPD Configuration file Feb 07 22:27:57 volumio sudo[612]: pam_unix(sudo:session): session closed for user root Feb 07 22:27:57 volumio volumio[419]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 22:27:57 volumio volumio[419]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 22:27:57 volumio volumio[419]: info: [1770478077191] CoreMusicLibrary::Adding element Media Servers Feb 07 22:27:57 volumio volumio[419]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 22:27:57 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 07 22:27:57 volumio sudo[627]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 07 22:27:57 volumio sudo[627]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 22:27:57 volumio sudo[627]: pam_unix(sudo:session): session closed for user root Feb 07 22:27:57 volumio volumio[419]: info: UPNP Browser: Client initialized successfully Feb 07 22:27:57 volumio sudo[629]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 07 22:27:57 volumio sudo[629]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 22:27:57 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 07 22:27:57 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 07 22:27:57 volumio systemd[1]: mpd.service: Consumed 7.148s CPU time. Feb 07 22:27:57 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 07 22:27:57 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 07 22:27:57 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 07 22:27:57 volumio volumio[419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:27:57 volumio volumio[419]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 22:27:57 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 07 22:27:57 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 07 22:27:57 volumio volumio[419]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 07 22:27:57 volumio volumio[419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:27:57 volumio volumio[419]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 22:27:57 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 07 22:27:57 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 07 22:27:57 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 07 22:27:57 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 07 22:27:57 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 07 22:27:57 volumio volumio[419]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 07 22:27:57 volumio volumio[419]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 22:27:57 volumio volumio[419]: info: [1770478077603] CoreMusicLibrary::Adding element Last_100 Feb 07 22:27:57 volumio volumio[419]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 22:27:57 volumio volumio[419]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 22:27:57 volumio volumio[419]: info: [1770478077615] CoreMusicLibrary::Adding element Webradio Feb 07 22:27:57 volumio volumio[419]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 22:27:57 volumio volumio[419]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 07 22:27:57 volumio volumio[419]: info: Initializing BBC Radios Feb 07 22:27:57 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 07 22:27:57 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 07 22:27:57 volumio volumio[419]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 07 22:27:57 volumio volumio[419]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 22:27:57 volumio volumio[419]: info: Creating Spotify config file Feb 07 22:27:57 volumio volumio[419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:27:58 volumio sudo[653]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 07 22:27:58 volumio sudo[653]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 07 22:27:58 volumio sudo[653]: pam_unix(sudo:session): session closed for user root Feb 07 22:27:58 volumio volumio[557]: Starting albumart workers Feb 07 22:27:58 volumio volumio[559]: Starting albumart workers Feb 07 22:27:58 volumio volumio[558]: Starting albumart workers Feb 07 22:27:59 volumio volumio[419]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 22:27:59 volumio volumio[419]: info: [1770478079512] CoreMusicLibrary::Adding element YouTube Music Feb 07 22:27:59 volumio volumio[419]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 22:27:59 volumio volumio[419]: Cannot find translation for source YouTube Music Feb 07 22:27:59 volumio volumio[419]: info: Volumio Calling Home Feb 07 22:27:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Feb 07 22:27:59 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 22:27:59 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 22:27:59 volumio go-librespot[670]: go-librespot daemon starting... Feb 07 22:27:59 volumio sudo[667]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 07 22:27:59 volumio sudo[667]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 22:27:59 volumio go-librespot[675]: time="2026-02-07T22:27:59+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 22:27:59 volumio sudo[667]: pam_unix(sudo:session): session closed for user root Feb 07 22:28:00 volumio volumio[419]: info: Discovery: adding 921648ed-0557-4d70-9927-1420682efcc7 Feb 07 22:28:00 volumio volumio[419]: info: Discovery: Found device Volumio Feb 07 22:28:00 volumio volumio[419]: info: CoreCommandRouter::volumioGetState Feb 07 22:28:00 volumio volumio[419]: info: CorePlayQueue::getTrack 0 Feb 07 22:28:00 volumio go-librespot[675]: time="2026-02-07T22:28:00+07:00" level=info msg="zeroconf server listening on port 38885" Feb 07 22:28:00 volumio volumio[419]: info: Discovery: this is already registered, 921648ed-0557-4d70-9927-1420682efcc7 Feb 07 22:28:00 volumio volumio[419]: info: Discovery: Found device Volumio Feb 07 22:28:00 volumio volumio[419]: info: CoreCommandRouter::volumioGetState Feb 07 22:28:00 volumio volumio[419]: info: CorePlayQueue::getTrack 0 Feb 07 22:28:00 volumio volumio[419]: info: MPD Permissions set Feb 07 22:28:00 volumio volumio[419]: info: MPD Permissions set Feb 07 22:28:00 volumio volumio[419]: info: Upmpdcli Daemon Started Feb 07 22:28:00 volumio volumio[419]: info: Volumio called home Feb 07 22:28:00 volumio volumio[419]: info: Spotify config file written Feb 07 22:28:00 volumio volumio[419]: 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 22:28:00 volumio sudo[705]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 07 22:28:00 volumio sudo[705]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 22:28:00 volumio volumio[419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:28:00 volumio volumio[419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:28:00 volumio volumio[419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:28:00 volumio volumio[419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:28:00 volumio volumio[419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:28:00 volumio volumio[419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:28:00 volumio volumio[419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:28:00 volumio volumio[419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:28:00 volumio volumio[419]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 22:28:00 volumio volumio[419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:28:00 volumio volumio[419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:28:00 volumio volumio[419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:28:00 volumio volumio[419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:28:00 volumio volumio[419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:28:00 volumio volumio[419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:28:00 volumio volumio[419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:28:00 volumio volumio[419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:28:00 volumio volumio[419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:28:00 volumio volumio[419]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 22:28:00 volumio volumio[419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 22:28:00 volumio volumio[419]: info: No need to fix Spotify hosts Feb 07 22:28:00 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 07 22:28:00 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 07 22:28:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 22:28:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 22:28:00 volumio go-librespot[718]: go-librespot daemon starting... Feb 07 22:28:00 volumio sudo[705]: pam_unix(sudo:session): session closed for user root Feb 07 22:28:00 volumio go-librespot[719]: time="2026-02-07T22:28:00+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 22:28:00 volumio go-librespot[719]: time="2026-02-07T22:28:00+07:00" level=debug msg="app state loaded" Feb 07 22:28:00 volumio go-librespot[719]: time="2026-02-07T22:28:00+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 22:28:01 volumio go-librespot[719]: time="2026-02-07T22:28:01+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 22:28:01 volumio go-librespot[719]: time="2026-02-07T22:28:01+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 22:28:01 volumio go-librespot[719]: time="2026-02-07T22:28:01+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 22:28:01 volumio go-librespot[719]: time="2026-02-07T22:28:01+07:00" level=info msg="zeroconf server listening on port 45053" Feb 07 22:28:01 volumio volumio[419]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 07 22:28:01 volumio volumio[419]: SPOTIFY: BQAXMSL02BOS-_TfQXK23dxT54qZs7hlmHqk7qJG5U51-INb6Z0TR9wkRkg4pSf8wCaJMQTsfY7IFpLxOHZ50U7L6Vjx6vkpN-zKXR-IA6MWa-GmfpWTWvcrSyrXNxYDVX8LP6rbMj1NRZZRnYB2j8EZYuj2f89Ohn14SCwJJlGncUK5GsR2rO8WabIm6SSLolk5-PdCb8Db2NMb344uKM5EoZv1M58vJXHypmPQQww12BT_1Rwxl0erN_74DaxCM520HT2l0gUJx5iyHnbUXNCyU6ELBQ5FjpP_kE9d2RtCft5mI9trG4BO Feb 07 22:28:01 volumio volumio[419]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 07 22:28:01 volumio volumio[419]: info: New Spotify access token = BQAXMSL02BOS-_TfQXK23dxT54qZs7hlmHqk7qJG5U51-INb6Z0TR9wkRkg4pSf8wCaJMQTsfY7IFpLxOHZ50U7L6Vjx6vkpN-zKXR-IA6MWa-GmfpWTWvcrSyrXNxYDVX8LP6rbMj1NRZZRnYB2j8EZYuj2f89Ohn14SCwJJlGncUK5GsR2rO8WabIm6SSLolk5-PdCb8Db2NMb344uKM5EoZv1M58vJXHypmPQQww12BT_1Rwxl0erN_74DaxCM520HT2l0gUJx5iyHnbUXNCyU6ELBQ5FjpP_kE9d2RtCft5mI9trG4BO Feb 07 22:28:01 volumio volumio[419]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 07 22:28:01 volumio volumio[419]: info: Starting Shairport Sync Feb 07 22:28:01 volumio volumio[419]: info: Starting Shairport Sync Feb 07 22:28:01 volumio volumio[419]: info: Starting Shairport Sync Feb 07 22:28:01 volumio sudo[732]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 07 22:28:01 volumio sudo[732]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 22:28:01 volumio sudo[730]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 07 22:28:01 volumio sudo[729]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 07 22:28:01 volumio sudo[730]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 22:28:01 volumio sudo[729]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 22:28:01 volumio go-librespot[719]: time="2026-02-07T22:28:01+07:00" level=debug msg="obtained new client token: AAASKUcy+khyDnu1bmeAEy/LcyRV15N1dPe+mczI3KBd5c3k3IMk6cIjmOtJxHz4IImGTosj3F7En8bqUPkCk0fj6CfKr1rtjjGungX7E1jDnVX4wgARQVnQRuuvJA+ZMk7uXu94Z42Ov0E9bsZQPWhvdm4k0O9gki+sZ7svF4YJl01VxFQjNKrnFgL8ONlZY5rvCAq7pNXpZLxR0+oPXUuUQyoOrZGuK6nn9lOJs8VouIOq13NVINvkgQ==" Feb 07 22:28:01 volumio go-librespot[719]: time="2026-02-07T22:28:01+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 22:28:01 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 07 22:28:02 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 07 22:28:02 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 07 22:28:02 volumio systemd[1]: shairport-sync.service: Consumed 2.315s CPU time. Feb 07 22:28:02 volumio go-librespot[719]: time="2026-02-07T22:28:02+07:00" level=debug msg="completed keyexchange" Feb 07 22:28:02 volumio go-librespot[719]: time="2026-02-07T22:28:02+07:00" level=debug msg="completed challenge" Feb 07 22:28:02 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 07 22:28:02 volumio sudo[732]: pam_unix(sudo:session): session closed for user root Feb 07 22:28:02 volumio go-librespot[719]: time="2026-02-07T22:28:02+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 22:28:02 volumio sudo[729]: pam_unix(sudo:session): session closed for user root Feb 07 22:28:02 volumio sudo[730]: pam_unix(sudo:session): session closed for user root Feb 07 22:28:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 22:28:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 22:28:02 volumio volumio[419]: info: CoreCommandRouter::volumioGetState Feb 07 22:28:02 volumio volumio[419]: info: CorePlayQueue::getTrack 0 Feb 07 22:28:02 volumio volumio[419]: info: Shairport-Sync Started Feb 07 22:28:02 volumio volumio[419]: Error adding Membership: Error: addMembership EINVAL Feb 07 22:28:02 volumio volumio[419]: info: Shairport-Sync Started Feb 07 22:28:02 volumio volumio[419]: info: Shairport-Sync Started Feb 07 22:28:02 volumio volumio[419]: 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 22:28:02 volumio volumio[419]: info: Spotify Successfully logged in Feb 07 22:28:02 volumio volumio[419]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 22:28:02 volumio volumio[419]: info: [1770478082341] CoreMusicLibrary::Adding element Spotify Feb 07 22:28:02 volumio volumio[419]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 22:28:02 volumio volumio[419]: Cannot find translation for source YouTube Music Feb 07 22:28:02 volumio volumio[419]: Cannot find translation for source Spotify Feb 07 22:28:03 volumio volumio[419]: info: [yt-cast-receiver] DIAL server listening on port 8098 Feb 07 22:28:03 volumio volumio[419]: info: CoreCommandRouter::volumioRetrievevolume Feb 07 22:28:03 volumio volumio[419]: info: VolumeController:: Volume=100 Mute =false Feb 07 22:28:03 volumio volumio[419]: info: CoreCommandRouter::volumioGetState Feb 07 22:28:03 volumio volumio[419]: info: CorePlayQueue::getTrack 0 Feb 07 22:28:03 volumio volumio[419]: info: CoreStateMachine::pushState Feb 07 22:28:03 volumio volumio[419]: info: CorePlayQueue::getTrack 0 Feb 07 22:28:03 volumio volumio[419]: info: CoreCommandRouter::volumioPushState Feb 07 22:28:04 volumio volumio[419]: info: go-librespot daemon successfully initialized Feb 07 22:28:05 volumio mpd[665]: 2026-02-07T22:28:05 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 07 22:28:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Feb 07 22:28:05 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 07 22:28:05 volumio sudo[629]: pam_unix(sudo:session): session closed for user root Feb 07 22:28:05 volumio sudo[617]: pam_unix(sudo:session): session closed for user root Feb 07 22:28:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 22:28:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 22:28:05 volumio go-librespot[776]: go-librespot daemon starting... Feb 07 22:28:05 volumio go-librespot[778]: time="2026-02-07T22:28:05+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 22:28:05 volumio go-librespot[778]: time="2026-02-07T22:28:05+07:00" level=debug msg="app state loaded" Feb 07 22:28:05 volumio go-librespot[778]: time="2026-02-07T22:28:05+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 22:28:05 volumio volumio[419]: error: MPD error: The expression evaluated to a falsy value: Feb 07 22:28:05 volumio volumio[419]: assert.ok(self.idling) Feb 07 22:28:05 volumio volumio[419]: error: The expression evaluated to a falsy value: Feb 07 22:28:05 volumio volumio[419]: assert.ok(self.idling) Feb 07 22:28:05 volumio volumio[419]: error: updateQueue error: null Feb 07 22:28:05 volumio volumio[419]: info: MPD running with PID665 Feb 07 22:28:05 volumio volumio[419]: ,establishing connection Feb 07 22:28:05 volumio volumio[419]: info: Completed starting Core Plugins Feb 07 22:28:05 volumio volumio[419]: info: ------------------------------------------- Feb 07 22:28:05 volumio volumio[419]: info: ----- MyVolumio plugins startup ---- Feb 07 22:28:05 volumio volumio[419]: info: ------------------------------------------- Feb 07 22:28:05 volumio volumio[419]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 07 22:28:05 volumio volumio[419]: error: updateQueue error: null Feb 07 22:28:05 volumio go-librespot[778]: time="2026-02-07T22:28:05+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 22:28:05 volumio go-librespot[778]: time="2026-02-07T22:28:05+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 22:28:05 volumio go-librespot[778]: time="2026-02-07T22:28:05+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 22:28:05 volumio go-librespot[778]: time="2026-02-07T22:28:05+07:00" level=info msg="zeroconf server listening on port 34923" Feb 07 22:28:06 volumio go-librespot[778]: time="2026-02-07T22:28:06+07:00" level=debug msg="obtained new client token: AADjY+FwKc8l1kfZe47xpgxXM0DhP4KEck9n/77okSwBXcBR4kLQC+NLRkOfF6icoxrDRtt6w/c3/MoOT02QFXe5uk8IfNU76hOGSXy96X8pjwrDjxBbo7Wfj+4ZTAqWgbVqM/y58VAAF2naFbJVegP4Yct8vyEfY7H5L6uKF2sPwmsXaRfey+u5cbfl5r6MeVyzTOW+lAdWW27gQi+8lommP5dDvD1vVtCkcnIkYljhkOh9ytxEiT0=" Feb 07 22:28:06 volumio go-librespot[778]: time="2026-02-07T22:28:06+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 22:28:06 volumio go-librespot[778]: time="2026-02-07T22:28:06+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Feb 07 22:28:06 volumio go-librespot[778]: time="2026-02-07T22:28:06+07:00" level=debug msg="completed keyexchange" Feb 07 22:28:06 volumio go-librespot[778]: time="2026-02-07T22:28:06+07:00" level=debug msg="completed challenge" Feb 07 22:28:06 volumio go-librespot[778]: time="2026-02-07T22:28:06+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 22:28:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 22:28:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 22:28:07 volumio volumio[419]: info: Initializing connection to go-librespot Websocket Feb 07 22:28:07 volumio volumio[419]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 07 22:28:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Feb 07 22:28:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 22:28:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 22:28:09 volumio go-librespot[786]: go-librespot daemon starting... Feb 07 22:28:09 volumio go-librespot[787]: time="2026-02-07T22:28:09+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 22:28:09 volumio go-librespot[787]: time="2026-02-07T22:28:09+07:00" level=debug msg="app state loaded" Feb 07 22:28:09 volumio go-librespot[787]: time="2026-02-07T22:28:09+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 22:28:10 volumio go-librespot[787]: time="2026-02-07T22:28:10+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 22:28:10 volumio go-librespot[787]: time="2026-02-07T22:28:10+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 22:28:10 volumio go-librespot[787]: time="2026-02-07T22:28:10+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 22:28:10 volumio go-librespot[787]: time="2026-02-07T22:28:10+07:00" level=info msg="zeroconf server listening on port 36647" Feb 07 22:28:10 volumio volumio[419]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 07 22:28:10 volumio go-librespot[787]: time="2026-02-07T22:28:10+07:00" level=debug msg="obtained new client token: AACFM8hfcXhbdUClFgtzHYqxi6+Ygvmfk7kawH+yzL2fhVw7Esd5KGcYuR0e7WCTjWZmEBKhH3N7UMHmLm1GpKFkJoPPirKeTP8ZEjtO81+lR74kGgj7qP7jlNmB7XoZJ+tpVzudnWH6fcFyTXrmNJVBf1xNaXqNnMoNakwYDVUKH5J+Owx9oJYWbKxDdV7+gfNeOhe4rP2gcqGphTOETSjzOw0XMiqoTBJpgbteFYVEoOkAa0b8Ezbg5A==" Feb 07 22:28:10 volumio go-librespot[787]: time="2026-02-07T22:28:10+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 22:28:10 volumio go-librespot[787]: time="2026-02-07T22:28:10+07:00" level=debug msg="completed keyexchange" Feb 07 22:28:10 volumio go-librespot[787]: time="2026-02-07T22:28:10+07:00" level=debug msg="completed challenge" Feb 07 22:28:10 volumio volumio[419]: info: Initializing connection to go-librespot Websocket Feb 07 22:28:10 volumio go-librespot[787]: time="2026-02-07T22:28:10+07:00" level=debug msg="new websocket client" Feb 07 22:28:10 volumio volumio[419]: info: Connection to go-librespot Websocket established Feb 07 22:28:10 volumio go-librespot[787]: time="2026-02-07T22:28:10+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 22:28:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 22:28:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 22:28:10 volumio volumio[419]: info: Connection to go-librespot Websocket closed Feb 07 22:28:13 volumio volumio[419]: info: Getting Spotify volume Feb 07 22:28:13 volumio volumio[419]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 22:28:13 volumio volumio[419]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 07 22:28:13 volumio volumio[419]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 07 22:28:13 volumio volumio[419]: errno: -111, Feb 07 22:28:13 volumio volumio[419]: code: 'ECONNREFUSED', Feb 07 22:28:13 volumio volumio[419]: syscall: 'connect', Feb 07 22:28:13 volumio volumio[419]: address: '127.0.0.1', Feb 07 22:28:13 volumio volumio[419]: port: 9879, Feb 07 22:28:13 volumio volumio[419]: response: undefined Feb 07 22:28:13 volumio volumio[419]: } Feb 07 22:28:13 volumio volumio[419]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 22:28:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Feb 07 22:28:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 22:28:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 22:28:13 volumio go-librespot[811]: go-librespot daemon starting... Feb 07 22:28:13 volumio go-librespot[812]: time="2026-02-07T22:28:13+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 22:28:13 volumio go-librespot[812]: time="2026-02-07T22:28:13+07:00" level=debug msg="app state loaded" Feb 07 22:28:13 volumio go-librespot[812]: time="2026-02-07T22:28:13+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 22:28:14 volumio go-librespot[812]: time="2026-02-07T22:28:14+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 22:28:14 volumio go-librespot[812]: time="2026-02-07T22:28:14+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 22:28:14 volumio go-librespot[812]: time="2026-02-07T22:28:14+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 22:28:14 volumio go-librespot[812]: time="2026-02-07T22:28:14+07:00" level=info msg="zeroconf server listening on port 40835" Feb 07 22:28:14 volumio go-librespot[812]: time="2026-02-07T22:28:14+07:00" level=debug msg="obtained new client token: AAALYd/M3oJhXnNbaNrg+LxHwcpBAzISPi7sApWsf457w35364isufT64WlODWw7C2Q72xH7kB53f2YNv1of4SUJYME7W5OMpSY6Na+boR75SOAqF3j8MS17wmPMtJImuJnxzBGttyp2r3FufwQnDLrPkJ81rv3GYBpV5wVK97AFZqpp8XDQQOwHGbiyXjrFA4FDe/FkmaaB/NKWjFwktt4HxSq7/u9h3S6JtDPqIM8QD2C0+PJyoV6Wkw==" Feb 07 22:28:14 volumio go-librespot[812]: time="2026-02-07T22:28:14+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 22:28:14 volumio go-librespot[812]: time="2026-02-07T22:28:14+07:00" level=debug msg="completed keyexchange" Feb 07 22:28:14 volumio go-librespot[812]: time="2026-02-07T22:28:14+07:00" level=debug msg="completed challenge" Feb 07 22:28:15 volumio go-librespot[812]: time="2026-02-07T22:28:15+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 22:28:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 22:28:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 22:28:15 volumio sudo[839]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-07 22:27' Feb 07 22:28:15 volumio sudo[839]: 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"