Feb 22 14:41:01 volumio volumio[8173]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Feb 22 14:41:01 volumio volumio[8173]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 22 14:41:01 volumio volumio[8173]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Feb 22 14:41:01 volumio volumio-remote-updater[26837]: Test mode disabled
Feb 22 14:41:01 volumio volumio-remote-updater[26837]: Alpha mode disabled
Feb 22 14:41:01 volumio volumio-remote-updater[26837]: Alpha legacy test mode disabled
Feb 22 14:41:01 volumio volumio[8173]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Feb 22 14:41:01 volumio volumio[8173]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\n- Fix for CPU utilization spike
\n- Fix for triggerhappy input permission
\n
\nNEW ADDITIONS
\n\n- Add BLE onboarding plugin
\n- Enable vc4-kms-v3d for all compatible RPi models
\n- Update Tidal SDK
\n
\n","title":"Update v4.096","updateavailable":true}
Feb 22 14:41:01 volumio volumio[8173]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Feb 22 14:41:01 volumio volumio[8173]: info: Initializing connection to go-librespot Websocket
Feb 22 14:41:01 volumio volumio[8173]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 14:41:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 52.
Feb 22 14:41:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 22 14:41:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 22 14:41:01 volumio go-librespot[8752]: go-librespot daemon starting...
Feb 22 14:41:01 volumio go-librespot[8753]: time="2026-02-22T14:41:01-06:00" level=info msg="running go-librespot 0.4.0"
Feb 22 14:41:01 volumio go-librespot[8753]: time="2026-02-22T14:41:01-06:00" level=debug msg="app state loaded"
Feb 22 14:41:01 volumio go-librespot[8753]: time="2026-02-22T14:41:01-06:00" level=debug msg="stored credentials not found"
Feb 22 14:41:01 volumio go-librespot[8753]: time="2026-02-22T14:41:01-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 14:41:02 volumio go-librespot[8753]: time="2026-02-22T14:41:02-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 22 14:41:02 volumio go-librespot[8753]: time="2026-02-22T14:41:02-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 22 14:41:02 volumio go-librespot[8753]: time="2026-02-22T14:41:02-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 22 14:41:02 volumio go-librespot[8753]: time="2026-02-22T14:41:02-06:00" level=info msg="zeroconf server listening on port 44801"
Feb 22 14:41:02 volumio go-librespot[8753]: time="2026-02-22T14:41:02-06:00" level=debug msg="obtained new client token: AAAw87YIsj5qtTPMH3LKeW+nbk17DH+cYTW7w9vE/xGKSIVYeBUPSIKmAYWdB0yZmVzXGbcCErZ+4nH0pRXe91mhqISMD/qYWUp26N1tXKjzMx72JQYzXyAkurR+qXjvjPV3hUUy4W5yFpD64k7A0m4HZtHXiBRoP7eDdNIqrmcnJ0d4tiPssY2uKoQZ/tcEkND8oalGSUhqiCxpyxwL+6Epgrhx+o+hHN+gO6UbVsjJfADn5thrd70Zag=="
Feb 22 14:41:02 volumio go-librespot[8753]: time="2026-02-22T14:41:02-06:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp 104.154.127.247:4070: connect: connection refused"
Feb 22 14:41:02 volumio go-librespot[8753]: time="2026-02-22T14:41:02-06:00" level=debug msg="connected to ap-guc3.spotify.com:443"
Feb 22 14:41:02 volumio go-librespot[8753]: time="2026-02-22T14:41:02-06:00" level=debug msg="completed keyexchange"
Feb 22 14:41:02 volumio go-librespot[8753]: time="2026-02-22T14:41:02-06:00" level=debug msg="completed challenge"
Feb 22 14:41:02 volumio go-librespot[8753]: time="2026-02-22T14:41:02-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 22 14:41:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 22 14:41:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 22 14:41:03 volumio volumio[8173]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Feb 22 14:41:03 volumio volumio[8173]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4
Feb 22 14:41:03 volumio volumio[8173]: info: CoreCommandRouter::volumioGetState
Feb 22 14:41:03 volumio volumio[8173]: info: CorePlayQueue::getTrack 0
Feb 22 14:41:04 volumio volumio[8173]: info: Initializing connection to go-librespot Websocket
Feb 22 14:41:04 volumio volumio[8173]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 14:41:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 53.
Feb 22 14:41:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 22 14:41:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 22 14:41:05 volumio go-librespot[8760]: go-librespot daemon starting...
Feb 22 14:41:05 volumio go-librespot[8761]: time="2026-02-22T14:41:05-06:00" level=info msg="running go-librespot 0.4.0"
Feb 22 14:41:05 volumio go-librespot[8761]: time="2026-02-22T14:41:05-06:00" level=debug msg="app state loaded"
Feb 22 14:41:05 volumio go-librespot[8761]: time="2026-02-22T14:41:05-06:00" level=debug msg="stored credentials not found"
Feb 22 14:41:05 volumio go-librespot[8761]: time="2026-02-22T14:41:05-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 14:41:05 volumio go-librespot[8761]: time="2026-02-22T14:41:05-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 22 14:41:05 volumio go-librespot[8761]: time="2026-02-22T14:41:05-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 22 14:41:05 volumio go-librespot[8761]: time="2026-02-22T14:41:05-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 22 14:41:05 volumio go-librespot[8761]: time="2026-02-22T14:41:05-06:00" level=info msg="zeroconf server listening on port 33281"
Feb 22 14:41:06 volumio go-librespot[8761]: time="2026-02-22T14:41:06-06:00" level=debug msg="obtained new client token: AAAQW6O8y91uRxGLSy+bDkUakvqWU+oWubfSuR6Hqi7Z4JEwVD6FjXWcEw7gAH69kn2ctlbNl4h7sJHL1ShLciH8HCU/0Mw5IvI2dCJYpfTsXcGNyhPm/aM39HdeTB2sE8ODDiV1PcPFp7Qmb6LBoIEaD6gDvuwYJithiHEdgSwkcLXTWUP6F1gbLfLzqLakqymFF4Vz5jlJYFMPQUyoZb7sefotsDHpXFb/pZmJSjFJx93NCKBfEdU="
Feb 22 14:41:06 volumio go-librespot[8761]: time="2026-02-22T14:41:06-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 22 14:41:06 volumio go-librespot[8761]: time="2026-02-22T14:41:06-06:00" level=debug msg="completed keyexchange"
Feb 22 14:41:06 volumio go-librespot[8761]: time="2026-02-22T14:41:06-06:00" level=debug msg="completed challenge"
Feb 22 14:41:06 volumio go-librespot[8761]: time="2026-02-22T14:41:06-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 22 14:41:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 22 14:41:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 22 14:41:07 volumio volumio[8173]: info: Initializing connection to go-librespot Websocket
Feb 22 14:41:07 volumio volumio[8173]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 14:41:08 volumio volumio[8173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 14:41:08 volumio volumio[8173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 14:41:08 volumio volumio[8173]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Feb 22 14:41:08 volumio volumio[8173]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Feb 22 14:41:08 volumio volumio[8173]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Feb 22 14:41:08 volumio volumio[8173]: info: CoreCommandRouter::volumioGetBrowseSources
Feb 22 14:41:08 volumio volumio[8173]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Feb 22 14:41:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 54.
Feb 22 14:41:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 22 14:41:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 22 14:41:09 volumio go-librespot[8783]: go-librespot daemon starting...
Feb 22 14:41:09 volumio go-librespot[8784]: time="2026-02-22T14:41:09-06:00" level=info msg="running go-librespot 0.4.0"
Feb 22 14:41:09 volumio go-librespot[8784]: time="2026-02-22T14:41:09-06:00" level=debug msg="app state loaded"
Feb 22 14:41:09 volumio go-librespot[8784]: time="2026-02-22T14:41:09-06:00" level=debug msg="stored credentials not found"
Feb 22 14:41:09 volumio go-librespot[8784]: time="2026-02-22T14:41:09-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 14:41:09 volumio go-librespot[8784]: time="2026-02-22T14:41:09-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 22 14:41:09 volumio go-librespot[8784]: time="2026-02-22T14:41:09-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 22 14:41:09 volumio go-librespot[8784]: time="2026-02-22T14:41:09-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 22 14:41:09 volumio go-librespot[8784]: time="2026-02-22T14:41:09-06:00" level=info msg="zeroconf server listening on port 42611"
Feb 22 14:41:09 volumio volumio[8173]: error: MyVolumio Plugin failed to authenticate in a timely fashion
Feb 22 14:41:09 volumio volumio[8173]: info: Completed starting MyVolumio Plugin
Feb 22 14:41:09 volumio volumio[8173]: [Metrics] CommandRouter: 55s 518.51ms
Feb 22 14:41:09 volumio volumio[8173]: info: CoreCommandRouter::volumiosetStartupVolume
Feb 22 14:41:09 volumio volumio[8173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 14:41:09 volumio volumio[8173]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 22 14:41:09 volumio volumio[8173]: info: CoreCommandRouter::Close All Modals sent
Feb 22 14:41:09 volumio volumio[8173]: info: CoreCommandRouter::Close All Modals sent
Feb 22 14:41:09 volumio go-librespot[8784]: time="2026-02-22T14:41:09-06:00" level=debug msg="obtained new client token: AADUTIqnA2moN+2LNEcvDuFNsEjwJbbadHpcg+1FlmUGeHUXttRuFngedCeDxGLq33cJ9ILVNU3jO432eC0FUizP/paJLXP2buwAr2yp+rBerTdA76rtOSLlQRK7Al6xCCYWH6XV1A9xX0WeL5SeyCUWNpZOVKxqJScuoL3rOwc0kVf9xH0adpY/oN8KTuAdO/0VttKcZLNxFyQ/C3rrss6s4IpUjqP9zfsvaIjU8EHWfrIEjZsM0aR3nQ=="
Feb 22 14:41:09 volumio go-librespot[8784]: time="2026-02-22T14:41:09-06:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp 104.154.127.247:4070: connect: connection refused"
Feb 22 14:41:09 volumio go-librespot[8784]: time="2026-02-22T14:41:09-06:00" level=debug msg="connected to ap-guc3.spotify.com:443"
Feb 22 14:41:09 volumio go-librespot[8784]: time="2026-02-22T14:41:09-06:00" level=debug msg="completed keyexchange"
Feb 22 14:41:09 volumio go-librespot[8784]: time="2026-02-22T14:41:09-06:00" level=debug msg="completed challenge"
Feb 22 14:41:09 volumio go-librespot[8784]: time="2026-02-22T14:41:09-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 22 14:41:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 22 14:41:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 22 14:41:10 volumio volumio[8173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Feb 22 14:41:10 volumio volumio[8173]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Feb 22 14:41:10 volumio volumio[8173]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Feb 22 14:41:10 volumio volumio[8173]: info: Initializing connection to go-librespot Websocket
Feb 22 14:41:10 volumio volumio[8173]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 14:41:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 55.
Feb 22 14:41:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 22 14:41:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 22 14:41:13 volumio go-librespot[8797]: go-librespot daemon starting...
Feb 22 14:41:13 volumio go-librespot[8798]: time="2026-02-22T14:41:13-06:00" level=info msg="running go-librespot 0.4.0"
Feb 22 14:41:13 volumio go-librespot[8798]: time="2026-02-22T14:41:13-06:00" level=debug msg="app state loaded"
Feb 22 14:41:13 volumio go-librespot[8798]: time="2026-02-22T14:41:13-06:00" level=debug msg="stored credentials not found"
Feb 22 14:41:13 volumio go-librespot[8798]: time="2026-02-22T14:41:13-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 14:41:13 volumio go-librespot[8798]: time="2026-02-22T14:41:13-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 22 14:41:13 volumio go-librespot[8798]: time="2026-02-22T14:41:13-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 22 14:41:13 volumio go-librespot[8798]: time="2026-02-22T14:41:13-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 22 14:41:13 volumio go-librespot[8798]: time="2026-02-22T14:41:13-06:00" level=info msg="zeroconf server listening on port 40261"
Feb 22 14:41:13 volumio go-librespot[8798]: time="2026-02-22T14:41:13-06:00" level=debug msg="obtained new client token: AAAAegKrF7kx8p1BiuoCKa9I23bQooW/GW1k6wGqc5UlBcdiBrJcg+4wKrmm+OhyINHvNIrbsybAvxuKp8uONLQc4bMoX0aUkkrIWMazMzt8NEjqe4RXwgQFsy0clHMAc8axqaQjnI4JTnxz0LM5vcRC33zXNpyNqIT++498GnRPEQ8bTvRkqwQ5K1If6tV9jxEXXzJmVmbyDbMYb4440SCex1e0Ydr8ICYh1HfGQSE+hIOTjRSob00vKQ=="
Feb 22 14:41:13 volumio go-librespot[8798]: time="2026-02-22T14:41:13-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 22 14:41:13 volumio go-librespot[8798]: time="2026-02-22T14:41:13-06:00" level=debug msg="completed keyexchange"
Feb 22 14:41:13 volumio go-librespot[8798]: time="2026-02-22T14:41:13-06:00" level=debug msg="completed challenge"
Feb 22 14:41:13 volumio go-librespot[8798]: time="2026-02-22T14:41:13-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 22 14:41:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 22 14:41:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 22 14:41:13 volumio volumio[8173]: info: Initializing connection to go-librespot Websocket
Feb 22 14:41:13 volumio volumio[8173]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 14:41:16 volumio volumio[8173]: info: BOOT COMPLETED
Feb 22 14:41:16 volumio volumio[8173]: info: Initializing connection to go-librespot Websocket
Feb 22 14:41:16 volumio volumio[8173]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 14:41:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 56.
Feb 22 14:41:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 22 14:41:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 22 14:41:16 volumio go-librespot[8819]: go-librespot daemon starting...
Feb 22 14:41:16 volumio go-librespot[8820]: time="2026-02-22T14:41:16-06:00" level=info msg="running go-librespot 0.4.0"
Feb 22 14:41:16 volumio go-librespot[8820]: time="2026-02-22T14:41:16-06:00" level=debug msg="app state loaded"
Feb 22 14:41:16 volumio go-librespot[8820]: time="2026-02-22T14:41:16-06:00" level=debug msg="stored credentials not found"
Feb 22 14:41:16 volumio go-librespot[8820]: time="2026-02-22T14:41:16-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 14:41:17 volumio go-librespot[8820]: time="2026-02-22T14:41:17-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 22 14:41:17 volumio go-librespot[8820]: time="2026-02-22T14:41:17-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 22 14:41:17 volumio go-librespot[8820]: time="2026-02-22T14:41:17-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 22 14:41:17 volumio go-librespot[8820]: time="2026-02-22T14:41:17-06:00" level=info msg="zeroconf server listening on port 33181"
Feb 22 14:41:17 volumio go-librespot[8820]: time="2026-02-22T14:41:17-06:00" level=debug msg="obtained new client token: AADVfU4q73SrRRlV615HIHUCzHWi7oFBoFsyuhkmAzz37+C8q0CyThEQmusByiQomxLP4KteuGZfPS4cqRtL1LV9OoqK3Gw8WXI6oq398CJ36CycNV82G4+La1eU+Y+nD84o8X7A4L9Ces7onRBQLGQtKoMbflmVIl4boYEMDrA8+9W8hwRZNoN+0MC4H6ALeRD953whvP7BndhGwNW4kb9IPXxOv8RbG85f09IJcSqan7216j8SozSRIQ=="
Feb 22 14:41:17 volumio go-librespot[8820]: time="2026-02-22T14:41:17-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 22 14:41:17 volumio go-librespot[8820]: time="2026-02-22T14:41:17-06:00" level=debug msg="completed keyexchange"
Feb 22 14:41:17 volumio go-librespot[8820]: time="2026-02-22T14:41:17-06:00" level=debug msg="completed challenge"
Feb 22 14:41:17 volumio go-librespot[8820]: time="2026-02-22T14:41:17-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 22 14:41:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 22 14:41:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 22 14:41:19 volumio volumio[8173]: info: Initializing connection to go-librespot Websocket
Feb 22 14:41:19 volumio volumio[8173]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 14:41:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 57.
Feb 22 14:41:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 22 14:41:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 22 14:41:20 volumio go-librespot[8830]: go-librespot daemon starting...
Feb 22 14:41:20 volumio go-librespot[8831]: time="2026-02-22T14:41:20-06:00" level=info msg="running go-librespot 0.4.0"
Feb 22 14:41:20 volumio go-librespot[8831]: time="2026-02-22T14:41:20-06:00" level=debug msg="app state loaded"
Feb 22 14:41:20 volumio go-librespot[8831]: time="2026-02-22T14:41:20-06:00" level=debug msg="stored credentials not found"
Feb 22 14:41:20 volumio go-librespot[8831]: time="2026-02-22T14:41:20-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 14:41:20 volumio go-librespot[8831]: time="2026-02-22T14:41:20-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Feb 22 14:41:20 volumio go-librespot[8831]: time="2026-02-22T14:41:20-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Feb 22 14:41:20 volumio go-librespot[8831]: time="2026-02-22T14:41:20-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Feb 22 14:41:20 volumio go-librespot[8831]: time="2026-02-22T14:41:20-06:00" level=info msg="zeroconf server listening on port 44169"
Feb 22 14:41:21 volumio go-librespot[8831]: time="2026-02-22T14:41:21-06:00" level=debug msg="obtained new client token: AADlWtBH5KemDa5C41A+IICunNzEcrxRLqPv/W6GSw8X9qRKNQZVJuMjzw8NJ+n5fsp65bKJ+tmpVebW8yyQEDL/9rCgniSSDzx3AJW9roY89h4SqG3XGYDZPO6+wk6AClbFpUG8VavMu+GF5D4W3XUJBn2EMBqb9kwQX16zF+jblYXMKLpz8XUmRCE3BJU08SmYMbhvF734EfRHhRRGPbNd9XYreahZCfl0eQ1gYoolXfHKm/HJips="
Feb 22 14:41:21 volumio go-librespot[8831]: time="2026-02-22T14:41:21-06:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp 104.154.127.247:4070: connect: connection refused"
Feb 22 14:41:21 volumio go-librespot[8831]: time="2026-02-22T14:41:21-06:00" level=debug msg="connected to ap-guc3.spotify.com:443"
Feb 22 14:41:21 volumio go-librespot[8831]: time="2026-02-22T14:41:21-06:00" level=debug msg="completed keyexchange"
Feb 22 14:41:21 volumio go-librespot[8831]: time="2026-02-22T14:41:21-06:00" level=debug msg="completed challenge"
Feb 22 14:41:21 volumio go-librespot[8831]: time="2026-02-22T14:41:21-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 22 14:41:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 22 14:41:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 22 14:41:22 volumio volumio[8173]: info: Initializing connection to go-librespot Websocket
Feb 22 14:41:22 volumio volumio[8173]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 14:41:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 58.
Feb 22 14:41:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 22 14:41:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 22 14:41:24 volumio go-librespot[8839]: go-librespot daemon starting...
Feb 22 14:41:24 volumio go-librespot[8840]: time="2026-02-22T14:41:24-06:00" level=info msg="running go-librespot 0.4.0"
Feb 22 14:41:24 volumio go-librespot[8840]: time="2026-02-22T14:41:24-06:00" level=debug msg="app state loaded"
Feb 22 14:41:24 volumio go-librespot[8840]: time="2026-02-22T14:41:24-06:00" level=debug msg="stored credentials not found"
Feb 22 14:41:24 volumio go-librespot[8840]: time="2026-02-22T14:41:24-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 14:41:24 volumio go-librespot[8840]: time="2026-02-22T14:41:24-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 22 14:41:24 volumio go-librespot[8840]: time="2026-02-22T14:41:24-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 22 14:41:24 volumio go-librespot[8840]: time="2026-02-22T14:41:24-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 22 14:41:24 volumio go-librespot[8840]: time="2026-02-22T14:41:24-06:00" level=info msg="zeroconf server listening on port 45929"
Feb 22 14:41:24 volumio go-librespot[8840]: time="2026-02-22T14:41:24-06:00" level=debug msg="obtained new client token: AADT1Vn7HVeRlliTyQj3cw8CH6EiiZjLUZ+RRyHKzkHDjB+AfNqSOn+zQUx+xy4CVrqa/k1QkU2WH4TJUh1mFPNLY3tbd1VL4Zu7APyOCbrbEFDVNFj7k0Sz5/FFF5g7YgALoBIkrnOIYTssaNfUyZKv9XWqy8aaxdBz5BuOxbv7vsW63zZQg4dtRf7UDAGJw5fmjmHaLZLONtpBBP7PFTSpJzhLyxhldbF2JrN7SgD1c00Cr4G6hCZTsA=="
Feb 22 14:41:24 volumio go-librespot[8840]: time="2026-02-22T14:41:24-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 22 14:41:24 volumio go-librespot[8840]: time="2026-02-22T14:41:24-06:00" level=debug msg="completed keyexchange"
Feb 22 14:41:24 volumio go-librespot[8840]: time="2026-02-22T14:41:24-06:00" level=debug msg="completed challenge"
Feb 22 14:41:24 volumio go-librespot[8840]: time="2026-02-22T14:41:24-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 22 14:41:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 22 14:41:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 22 14:41:25 volumio volumio[8173]: info: Initializing connection to go-librespot Websocket
Feb 22 14:41:25 volumio volumio[8173]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 14:41:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 59.
Feb 22 14:41:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 22 14:41:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 22 14:41:28 volumio go-librespot[8861]: go-librespot daemon starting...
Feb 22 14:41:28 volumio go-librespot[8863]: time="2026-02-22T14:41:28-06:00" level=info msg="running go-librespot 0.4.0"
Feb 22 14:41:28 volumio go-librespot[8863]: time="2026-02-22T14:41:28-06:00" level=debug msg="app state loaded"
Feb 22 14:41:28 volumio go-librespot[8863]: time="2026-02-22T14:41:28-06:00" level=debug msg="stored credentials not found"
Feb 22 14:41:28 volumio go-librespot[8863]: time="2026-02-22T14:41:28-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 14:41:28 volumio go-librespot[8863]: time="2026-02-22T14:41:28-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Feb 22 14:41:28 volumio go-librespot[8863]: time="2026-02-22T14:41:28-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Feb 22 14:41:28 volumio go-librespot[8863]: time="2026-02-22T14:41:28-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Feb 22 14:41:28 volumio go-librespot[8863]: time="2026-02-22T14:41:28-06:00" level=info msg="zeroconf server listening on port 33505"
Feb 22 14:41:28 volumio go-librespot[8863]: time="2026-02-22T14:41:28-06:00" level=debug msg="obtained new client token: AADaWb6FoWeJECJ1GYmiE1QwYOI4w2UZ1dj0jNHw0FffUTij3HIwiEYAY7vLCKQmH49G0RlMPKAjGiuDYiRjVRz5ZieB+DAyDcXNLTS3TAca7GdBZ+1dqro5VU4AgQwXxgR/aoLkarmUuHl8DP6amdfUZw7Eor1mDC7gv3kM2AIvid6rokis2UlBD4c6eV1xzYkZt9FifJNnTXMpTf0ExQtHHfb8+Skau/h5gvcSs/f+zIWxAhVQpbvGww=="
Feb 22 14:41:28 volumio go-librespot[8863]: time="2026-02-22T14:41:28-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 22 14:41:28 volumio go-librespot[8863]: time="2026-02-22T14:41:28-06:00" level=debug msg="completed keyexchange"
Feb 22 14:41:28 volumio go-librespot[8863]: time="2026-02-22T14:41:28-06:00" level=debug msg="completed challenge"
Feb 22 14:41:28 volumio go-librespot[8863]: time="2026-02-22T14:41:28-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 22 14:41:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 22 14:41:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 22 14:41:28 volumio volumio[8173]: info: Initializing connection to go-librespot Websocket
Feb 22 14:41:28 volumio volumio[8173]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 14:41:31 volumio volumio[8173]: info: UPDATER: Scheduling automatic update
Feb 22 14:41:31 volumio volumio[8173]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStartTime
Feb 22 14:41:31 volumio volumio[8173]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStopTime
Feb 22 14:41:31 volumio volumio[8173]: info: UPDATER: Auto update will take place at: Mon Feb 23 2026 03:07:44 GMT-0600 (Central Standard Time)
Feb 22 14:41:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 60.
Feb 22 14:41:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 22 14:41:31 volumio volumio[8173]: info: Initializing connection to go-librespot Websocket
Feb 22 14:41:31 volumio volumio[8173]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 14:41:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 22 14:41:31 volumio go-librespot[8870]: go-librespot daemon starting...
Feb 22 14:41:31 volumio go-librespot[8871]: time="2026-02-22T14:41:31-06:00" level=info msg="running go-librespot 0.4.0"
Feb 22 14:41:31 volumio go-librespot[8871]: time="2026-02-22T14:41:31-06:00" level=debug msg="app state loaded"
Feb 22 14:41:31 volumio go-librespot[8871]: time="2026-02-22T14:41:31-06:00" level=debug msg="stored credentials not found"
Feb 22 14:41:31 volumio go-librespot[8871]: time="2026-02-22T14:41:31-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 14:41:32 volumio go-librespot[8871]: time="2026-02-22T14:41:32-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 22 14:41:32 volumio go-librespot[8871]: time="2026-02-22T14:41:32-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 22 14:41:32 volumio go-librespot[8871]: time="2026-02-22T14:41:32-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 22 14:41:32 volumio go-librespot[8871]: time="2026-02-22T14:41:32-06:00" level=info msg="zeroconf server listening on port 36253"
Feb 22 14:41:32 volumio go-librespot[8871]: time="2026-02-22T14:41:32-06:00" level=debug msg="obtained new client token: AACI0g8slqwV0R/KH9QimaMwsA+7ubLv9o3ISwaNDPvtnTdMunhlaWFf2B93Z+P1NYR2s1Smx+wb6qSxgOr7h7bO1qhqHnRN3y2f1xOwqkEUyMg6YVDVN6BSS3sCejqJ925TuBWZksiibIkjbRp8ed8NbxoIgvfWuzufM0QxQiiv/3WA9zQLPkPx2iAKONhQy4w4B5q6lzdNoIAF2x5UQLaetY96LdkHE1QMaoRBFmHEn5ijBhozZIN0qw=="
Feb 22 14:41:32 volumio go-librespot[8871]: time="2026-02-22T14:41:32-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 22 14:41:32 volumio go-librespot[8871]: time="2026-02-22T14:41:32-06:00" level=debug msg="completed keyexchange"
Feb 22 14:41:32 volumio go-librespot[8871]: time="2026-02-22T14:41:32-06:00" level=debug msg="completed challenge"
Feb 22 14:41:32 volumio go-librespot[8871]: time="2026-02-22T14:41:32-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 22 14:41:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 22 14:41:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 22 14:41:34 volumio volumio[8173]: info: Initializing connection to go-librespot Websocket
Feb 22 14:41:34 volumio volumio[8173]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 14:41:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 61.
Feb 22 14:41:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 22 14:41:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 22 14:41:35 volumio go-librespot[8878]: go-librespot daemon starting...
Feb 22 14:41:35 volumio go-librespot[8879]: time="2026-02-22T14:41:35-06:00" level=info msg="running go-librespot 0.4.0"
Feb 22 14:41:35 volumio go-librespot[8879]: time="2026-02-22T14:41:35-06:00" level=debug msg="app state loaded"
Feb 22 14:41:35 volumio go-librespot[8879]: time="2026-02-22T14:41:35-06:00" level=debug msg="stored credentials not found"
Feb 22 14:41:35 volumio go-librespot[8879]: time="2026-02-22T14:41:35-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 14:41:35 volumio go-librespot[8879]: time="2026-02-22T14:41:35-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 22 14:41:35 volumio go-librespot[8879]: time="2026-02-22T14:41:35-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 22 14:41:35 volumio go-librespot[8879]: time="2026-02-22T14:41:35-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 22 14:41:35 volumio go-librespot[8879]: time="2026-02-22T14:41:35-06:00" level=info msg="zeroconf server listening on port 40173"
Feb 22 14:41:36 volumio go-librespot[8879]: time="2026-02-22T14:41:36-06:00" level=debug msg="obtained new client token: AABqBrg9ZCswx/DLHnYyM0HETYUwK0YS4t57rZjh3FCQ/zOkMa5Cx+NyILsNv/mzMAlHJARSkkKyqtzLYX19TTsXwYzVX5JodtpEcqjaviEkhvNNlOveW/I+PIgzrDNepyG4+v6QD+V3cshIg2XYNnSk00utANtTCs9N/vQ91S7ojhFPBjXgwu8Xur2CfYJQSQd6mkh09EjnkE863/25DUPbmeS0t2Zea8Aj7KL9VcUpoA4qkoDLimw="
Feb 22 14:41:36 volumio go-librespot[8879]: time="2026-02-22T14:41:36-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 22 14:41:36 volumio go-librespot[8879]: time="2026-02-22T14:41:36-06:00" level=debug msg="completed keyexchange"
Feb 22 14:41:36 volumio go-librespot[8879]: time="2026-02-22T14:41:36-06:00" level=debug msg="completed challenge"
Feb 22 14:41:36 volumio go-librespot[8879]: time="2026-02-22T14:41:36-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 22 14:41:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 22 14:41:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 22 14:41:37 volumio volumio[8173]: info: Initializing connection to go-librespot Websocket
Feb 22 14:41:37 volumio volumio[8173]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 14:41:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 62.
Feb 22 14:41:39 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 22 14:41:39 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 22 14:41:39 volumio go-librespot[8900]: go-librespot daemon starting...
Feb 22 14:41:39 volumio go-librespot[8901]: time="2026-02-22T14:41:39-06:00" level=info msg="running go-librespot 0.4.0"
Feb 22 14:41:39 volumio go-librespot[8901]: time="2026-02-22T14:41:39-06:00" level=debug msg="app state loaded"
Feb 22 14:41:39 volumio go-librespot[8901]: time="2026-02-22T14:41:39-06:00" level=debug msg="stored credentials not found"
Feb 22 14:41:39 volumio go-librespot[8901]: time="2026-02-22T14:41:39-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 14:41:39 volumio go-librespot[8901]: time="2026-02-22T14:41:39-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Feb 22 14:41:39 volumio go-librespot[8901]: time="2026-02-22T14:41:39-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Feb 22 14:41:39 volumio go-librespot[8901]: time="2026-02-22T14:41:39-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Feb 22 14:41:39 volumio go-librespot[8901]: time="2026-02-22T14:41:39-06:00" level=info msg="zeroconf server listening on port 35371"
Feb 22 14:41:39 volumio go-librespot[8901]: time="2026-02-22T14:41:39-06:00" level=debug msg="obtained new client token: AAAW9Q6qeFnCZyQP6/vBI7uTCpSicOr8cphpLvX/2YxUon9sghiOiv2dxmCMRvVwPi2aUrNXS2yqOaQpjXmMaG72Soq6oMGQZAp+NfylrUMt0dhYb2CBOt/Xo9bA9Z0y6E8nDZ7N314zZZ3SOKpV0Eg7KF7CsCgUbjtBiabmgp9o0pXUdY97hQ9zS4FJCTqvAgnqCd1/5o+iw7tW+GFq2gr4Xepo5hBT1g+NYnltwIUoWvjLxGmjEIZkxQ=="
Feb 22 14:41:39 volumio go-librespot[8901]: time="2026-02-22T14:41:39-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 22 14:41:39 volumio go-librespot[8901]: time="2026-02-22T14:41:39-06:00" level=debug msg="completed keyexchange"
Feb 22 14:41:39 volumio go-librespot[8901]: time="2026-02-22T14:41:39-06:00" level=debug msg="completed challenge"
Feb 22 14:41:39 volumio go-librespot[8901]: time="2026-02-22T14:41:39-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 22 14:41:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 22 14:41:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 22 14:41:40 volumio volumio[8173]: info: Initializing connection to go-librespot Websocket
Feb 22 14:41:40 volumio volumio[8173]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 14:41:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 63.
Feb 22 14:41:43 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 22 14:41:43 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 22 14:41:43 volumio go-librespot[8911]: go-librespot daemon starting...
Feb 22 14:41:43 volumio go-librespot[8912]: time="2026-02-22T14:41:43-06:00" level=info msg="running go-librespot 0.4.0"
Feb 22 14:41:43 volumio go-librespot[8912]: time="2026-02-22T14:41:43-06:00" level=debug msg="app state loaded"
Feb 22 14:41:43 volumio go-librespot[8912]: time="2026-02-22T14:41:43-06:00" level=debug msg="stored credentials not found"
Feb 22 14:41:43 volumio go-librespot[8912]: time="2026-02-22T14:41:43-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 14:41:43 volumio go-librespot[8912]: time="2026-02-22T14:41:43-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 22 14:41:43 volumio go-librespot[8912]: time="2026-02-22T14:41:43-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 22 14:41:43 volumio go-librespot[8912]: time="2026-02-22T14:41:43-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 22 14:41:43 volumio go-librespot[8912]: time="2026-02-22T14:41:43-06:00" level=info msg="zeroconf server listening on port 45201"
Feb 22 14:41:43 volumio go-librespot[8912]: time="2026-02-22T14:41:43-06:00" level=debug msg="obtained new client token: AABctD1JYUvMb6bJs7r8sDr70MhG1C682gh9ayZZsldFSuagv4SnPUK2neavYtVsS99kHfI6qFRCwzj9WYj3V8jkdefYXaN+rqZq4GaRJg7bAIoBEVfqBgvCAH/Qom9byHcuAmkp4tby17p6DYQSwhpfgxZ5qYoG1M5ly/qGU6DI9Wet2ktwHotHRjkI8N24V+NYwxwTybVeqhorxQtxRRIXVz929oBUltMjNAbsd6GAr20sLA/FQNtGGQ=="
Feb 22 14:41:43 volumio go-librespot[8912]: time="2026-02-22T14:41:43-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 22 14:41:43 volumio go-librespot[8912]: time="2026-02-22T14:41:43-06:00" level=debug msg="completed keyexchange"
Feb 22 14:41:43 volumio go-librespot[8912]: time="2026-02-22T14:41:43-06:00" level=debug msg="completed challenge"
Feb 22 14:41:43 volumio go-librespot[8912]: time="2026-02-22T14:41:43-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 22 14:41:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 22 14:41:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 22 14:41:43 volumio volumio[8173]: info: Initializing connection to go-librespot Websocket
Feb 22 14:41:43 volumio volumio[8173]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 14:41:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 64.
Feb 22 14:41:46 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 22 14:41:46 volumio volumio[8173]: info: Initializing connection to go-librespot Websocket
Feb 22 14:41:46 volumio volumio[8173]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 14:41:46 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 22 14:41:46 volumio go-librespot[8933]: go-librespot daemon starting...
Feb 22 14:41:46 volumio go-librespot[8934]: time="2026-02-22T14:41:46-06:00" level=info msg="running go-librespot 0.4.0"
Feb 22 14:41:46 volumio go-librespot[8934]: time="2026-02-22T14:41:46-06:00" level=debug msg="app state loaded"
Feb 22 14:41:46 volumio go-librespot[8934]: time="2026-02-22T14:41:46-06:00" level=debug msg="stored credentials not found"
Feb 22 14:41:47 volumio go-librespot[8934]: time="2026-02-22T14:41:47-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 14:41:47 volumio go-librespot[8934]: time="2026-02-22T14:41:47-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Feb 22 14:41:47 volumio go-librespot[8934]: time="2026-02-22T14:41:47-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Feb 22 14:41:47 volumio go-librespot[8934]: time="2026-02-22T14:41:47-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Feb 22 14:41:47 volumio go-librespot[8934]: time="2026-02-22T14:41:47-06:00" level=info msg="zeroconf server listening on port 39127"
Feb 22 14:41:47 volumio go-librespot[8934]: time="2026-02-22T14:41:47-06:00" level=debug msg="obtained new client token: AAAguMvQPNPlfUGrICEKG6qtEuVG6D+sozDR82yYXx9EmjlwuBPoBzsLu/cwWZnEu+Him/+A1FqpS1BdrRJI57Q+YCDxCtzU8eFIzpWGMtH1RRmV/vY8cZQtJAvDzkJnIeaHYrFhUs86RXJNM4RmSGkT2KOZNS0qQ4dU5xZpqEI4+rj80ADFcjj9vcJyPU4JTGYR8gDpjbW9p5LHvyxstrES1h+drlzehesU6CfMT3/ilYkVGoSV+2YNUQ=="
Feb 22 14:41:47 volumio go-librespot[8934]: time="2026-02-22T14:41:47-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 22 14:41:47 volumio go-librespot[8934]: time="2026-02-22T14:41:47-06:00" level=debug msg="completed keyexchange"
Feb 22 14:41:47 volumio go-librespot[8934]: time="2026-02-22T14:41:47-06:00" level=debug msg="completed challenge"
Feb 22 14:41:47 volumio go-librespot[8934]: time="2026-02-22T14:41:47-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 22 14:41:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 22 14:41:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 22 14:41:49 volumio volumio[8173]: info: Initializing connection to go-librespot Websocket
Feb 22 14:41:49 volumio volumio[8173]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 14:41:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 65.
Feb 22 14:41:50 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 22 14:41:50 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 22 14:41:50 volumio go-librespot[8942]: go-librespot daemon starting...
Feb 22 14:41:50 volumio go-librespot[8943]: time="2026-02-22T14:41:50-06:00" level=info msg="running go-librespot 0.4.0"
Feb 22 14:41:50 volumio go-librespot[8943]: time="2026-02-22T14:41:50-06:00" level=debug msg="app state loaded"
Feb 22 14:41:50 volumio go-librespot[8943]: time="2026-02-22T14:41:50-06:00" level=debug msg="stored credentials not found"
Feb 22 14:41:50 volumio go-librespot[8943]: time="2026-02-22T14:41:50-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 14:41:50 volumio go-librespot[8943]: time="2026-02-22T14:41:50-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 22 14:41:50 volumio go-librespot[8943]: time="2026-02-22T14:41:50-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 22 14:41:50 volumio go-librespot[8943]: time="2026-02-22T14:41:50-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 22 14:41:50 volumio go-librespot[8943]: time="2026-02-22T14:41:50-06:00" level=info msg="zeroconf server listening on port 45789"
Feb 22 14:41:51 volumio go-librespot[8943]: time="2026-02-22T14:41:51-06:00" level=debug msg="obtained new client token: AADUcxt7DA+4P7mdYAhzmQoZbdCArq+LMhIIWe+HUfRXIEJrTYBN/1uDhf/6wNo/4tURs2lr9DUbdvQjfxxYyu4gAW5zXupU9k28bmob+4AH26cG0KO8eKYfEXt8fqmwzur1euIkfjFY8gml/8g4PU1hQc1zgM7EfFAqWUSEZHPmV6reREbU3Fi/KTnQC5VKwZl65oYSwO1koUIAxGN0Oirq16diGQL/0dT658H+rqYPmrbAZO75OSA="
Feb 22 14:41:51 volumio go-librespot[8943]: time="2026-02-22T14:41:51-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 22 14:41:51 volumio go-librespot[8943]: time="2026-02-22T14:41:51-06:00" level=debug msg="completed keyexchange"
Feb 22 14:41:51 volumio go-librespot[8943]: time="2026-02-22T14:41:51-06:00" level=debug msg="completed challenge"
Feb 22 14:41:51 volumio go-librespot[8943]: time="2026-02-22T14:41:51-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 22 14:41:51 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 22 14:41:51 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 22 14:41:52 volumio volumio[8173]: info: Initializing connection to go-librespot Websocket
Feb 22 14:41:52 volumio volumio[8173]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 14:41:54 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 66.
Feb 22 14:41:54 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 22 14:41:54 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 22 14:41:54 volumio go-librespot[8950]: go-librespot daemon starting...
Feb 22 14:41:54 volumio go-librespot[8951]: time="2026-02-22T14:41:54-06:00" level=info msg="running go-librespot 0.4.0"
Feb 22 14:41:54 volumio go-librespot[8951]: time="2026-02-22T14:41:54-06:00" level=debug msg="app state loaded"
Feb 22 14:41:54 volumio go-librespot[8951]: time="2026-02-22T14:41:54-06:00" level=debug msg="stored credentials not found"
Feb 22 14:41:54 volumio go-librespot[8951]: time="2026-02-22T14:41:54-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 14:41:54 volumio go-librespot[8951]: time="2026-02-22T14:41:54-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Feb 22 14:41:54 volumio go-librespot[8951]: time="2026-02-22T14:41:54-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Feb 22 14:41:54 volumio go-librespot[8951]: time="2026-02-22T14:41:54-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Feb 22 14:41:54 volumio go-librespot[8951]: time="2026-02-22T14:41:54-06:00" level=info msg="zeroconf server listening on port 39945"
Feb 22 14:41:54 volumio go-librespot[8951]: time="2026-02-22T14:41:54-06:00" level=debug msg="obtained new client token: AABx9W3wqIBZw9+esAJnZJxr7vXfjFvlpRdOuDwiBjja80qsHurGYE0KvapPN1+R/cHrPehmsLHeeN8b6zm4gxAmxz3OTFxV9ZctOJ/jVFCCMMrOgnKmlrXCO+HqfE49k05Ol3kq5zEKBpQOgIEyD70oKUGzCZDDPKjRbNRNxFQH4mJfcbQkhVAu6K4KBl7V0A+8HOUIHYs+m49rdZRSd0VB33sHZH+1a+3PHzmPiQHWBRF7nRbT1g2Lgg=="
Feb 22 14:41:54 volumio go-librespot[8951]: time="2026-02-22T14:41:54-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 22 14:41:54 volumio go-librespot[8951]: time="2026-02-22T14:41:54-06:00" level=debug msg="completed keyexchange"
Feb 22 14:41:54 volumio go-librespot[8951]: time="2026-02-22T14:41:54-06:00" level=debug msg="completed challenge"
Feb 22 14:41:54 volumio go-librespot[8951]: time="2026-02-22T14:41:54-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 22 14:41:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 22 14:41:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 22 14:41:55 volumio volumio[8173]: info: Initializing connection to go-librespot Websocket
Feb 22 14:41:55 volumio volumio[8173]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 14:41:58 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 67.
Feb 22 14:41:58 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 22 14:41:58 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 22 14:41:58 volumio go-librespot[8972]: go-librespot daemon starting...
Feb 22 14:41:58 volumio go-librespot[8973]: time="2026-02-22T14:41:58-06:00" level=info msg="running go-librespot 0.4.0"
Feb 22 14:41:58 volumio go-librespot[8973]: time="2026-02-22T14:41:58-06:00" level=debug msg="app state loaded"
Feb 22 14:41:58 volumio go-librespot[8973]: time="2026-02-22T14:41:58-06:00" level=debug msg="stored credentials not found"
Feb 22 14:41:58 volumio go-librespot[8973]: time="2026-02-22T14:41:58-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 14:41:58 volumio go-librespot[8973]: time="2026-02-22T14:41:58-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 22 14:41:58 volumio go-librespot[8973]: time="2026-02-22T14:41:58-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 22 14:41:58 volumio go-librespot[8973]: time="2026-02-22T14:41:58-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 22 14:41:58 volumio go-librespot[8973]: time="2026-02-22T14:41:58-06:00" level=info msg="zeroconf server listening on port 45801"
Feb 22 14:41:58 volumio go-librespot[8973]: time="2026-02-22T14:41:58-06:00" level=debug msg="obtained new client token: AAAMcnp7HTTFFCfa/Wb3bGRho3UrVjzmAxClLlKNLTP2/D985cp8odm8txLgmIvsBHice/Npobd50+8BpqcIT1sIp+S8LjDu601gHRBGHhiqqI1UxaXYHd5EOkd3mWRUNs6MxlndRGOV8vU+jM1T6ZroN+za0VpX+jPFTJHT8EiLRXuGQ4Mx5EsadofvazcMXsgp2fSYAAmA2yHDESZnc5Vp+ZzYUH0MXzTTm4l2qGylOjJXeVho+nns5w=="
Feb 22 14:41:58 volumio go-librespot[8973]: time="2026-02-22T14:41:58-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 22 14:41:58 volumio go-librespot[8973]: time="2026-02-22T14:41:58-06:00" level=debug msg="completed keyexchange"
Feb 22 14:41:58 volumio go-librespot[8973]: time="2026-02-22T14:41:58-06:00" level=debug msg="completed challenge"
Feb 22 14:41:58 volumio go-librespot[8973]: time="2026-02-22T14:41:58-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 22 14:41:58 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 22 14:41:58 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 22 14:41:58 volumio volumio[8173]: info: Initializing connection to go-librespot Websocket
Feb 22 14:41:58 volumio volumio[8173]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 14:42:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 68.
Feb 22 14:42:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 22 14:42:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 22 14:42:01 volumio go-librespot[8983]: go-librespot daemon starting...
Feb 22 14:42:01 volumio go-librespot[8984]: time="2026-02-22T14:42:01-06:00" level=info msg="running go-librespot 0.4.0"
Feb 22 14:42:01 volumio go-librespot[8984]: time="2026-02-22T14:42:01-06:00" level=debug msg="app state loaded"
Feb 22 14:42:01 volumio go-librespot[8984]: time="2026-02-22T14:42:01-06:00" level=debug msg="stored credentials not found"
Feb 22 14:42:01 volumio volumio[8173]: info: Initializing connection to go-librespot Websocket
Feb 22 14:42:01 volumio go-librespot[8984]: time="2026-02-22T14:42:01-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 14:42:01 volumio go-librespot[8984]: time="2026-02-22T14:42:01-06:00" level=debug msg="new websocket client"
Feb 22 14:42:01 volumio volumio[8173]: info: Connection to go-librespot Websocket established
Feb 22 14:42:02 volumio go-librespot[8984]: time="2026-02-22T14:42:02-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 22 14:42:02 volumio go-librespot[8984]: time="2026-02-22T14:42:02-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 22 14:42:02 volumio go-librespot[8984]: time="2026-02-22T14:42:02-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 22 14:42:02 volumio go-librespot[8984]: time="2026-02-22T14:42:02-06:00" level=info msg="zeroconf server listening on port 34495"
Feb 22 14:42:02 volumio go-librespot[8984]: time="2026-02-22T14:42:02-06:00" level=debug msg="obtained new client token: AAB5aud8byvvyjQ+nlOf3SoxR+HjcvHxhVQJh77yRx4XGCIdyJhlkRTiYBWAZINiRiwm3pHIU8rhc3EW6H+A4s1c6KyyGRZ3J6btfUh7kyxn8NSnu355OYzbG/P7JPlaaO7rE6kQM7A0FYz8LLd/yuUmfJTNmG8FDMc25wnPUgL5v76PtwPCIBQ1C7WTRINgxIT3ljHSgsSC1cP6oozR+yJE5PvHttAly2AbORzcZE7lIgoaSonEuGhSRg=="
Feb 22 14:42:02 volumio go-librespot[8984]: time="2026-02-22T14:42:02-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 22 14:42:02 volumio go-librespot[8984]: time="2026-02-22T14:42:02-06:00" level=debug msg="completed keyexchange"
Feb 22 14:42:02 volumio go-librespot[8984]: time="2026-02-22T14:42:02-06:00" level=debug msg="completed challenge"
Feb 22 14:42:02 volumio go-librespot[8984]: time="2026-02-22T14:42:02-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 22 14:42:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 22 14:42:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 22 14:42:02 volumio volumio[8173]: info: Connection to go-librespot Websocket closed
Feb 22 14:42:05 volumio volumio[8173]: info: Getting Spotify volume
Feb 22 14:42:05 volumio volumio[8173]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Feb 22 14:42:05 volumio volumio[8173]: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 14:42:05 volumio volumio[8173]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) {
Feb 22 14:42:05 volumio volumio[8173]: errno: -111,
Feb 22 14:42:05 volumio volumio[8173]: code: 'ECONNREFUSED',
Feb 22 14:42:05 volumio volumio[8173]: syscall: 'connect',
Feb 22 14:42:05 volumio volumio[8173]: address: '127.0.0.1',
Feb 22 14:42:05 volumio volumio[8173]: port: 9879,
Feb 22 14:42:05 volumio volumio[8173]: response: undefined
Feb 22 14:42:05 volumio volumio[8173]: }
Feb 22 14:42:05 volumio volumio[8173]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Feb 22 14:42:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 69.
Feb 22 14:42:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 22 14:42:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 22 14:42:05 volumio go-librespot[9003]: go-librespot daemon starting...
Feb 22 14:42:05 volumio go-librespot[9004]: time="2026-02-22T14:42:05-06:00" level=info msg="running go-librespot 0.4.0"
Feb 22 14:42:05 volumio go-librespot[9004]: time="2026-02-22T14:42:05-06:00" level=debug msg="app state loaded"
Feb 22 14:42:05 volumio go-librespot[9004]: time="2026-02-22T14:42:05-06:00" level=debug msg="stored credentials not found"
Feb 22 14:42:05 volumio go-librespot[9004]: time="2026-02-22T14:42:05-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 14:42:05 volumio sudo[9012]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-22 14:41'
Feb 22 14:42:05 volumio sudo[9012]: 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="ac7dd5922ede329d1459d48b226ddc71f5209a2c"
VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd"
VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2"
VOLUMIO_BE_VERSION="3441cddd43405c36fe444484553f10f2f5bc830b"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Thu Nov 13 11:04:27 UTC 2025"
VOLUMIO_VERSION="4.069"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="7da8d6d9f8baf8621af4ec2af5a00f00"