Feb 12 18:03:00 volumio volumio[19793]: info: Initializing connection to go-librespot Websocket
Feb 12 18:03:00 volumio volumio[19793]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 12 18:03:02 volumio volumio[19793]: info: MYVOLUMIO: Adding device
Feb 12 18:03:02 volumio volumio[19793]: info: MYVOLUMIO: Evaluating Server
Feb 12 18:03:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 57.
Feb 12 18:03:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 12 18:03:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 12 18:03:02 volumio go-librespot[20340]: go-librespot daemon starting...
Feb 12 18:03:02 volumio go-librespot[20345]: time="2026-02-12T18:03:02-06:00" level=info msg="running go-librespot 0.4.0"
Feb 12 18:03:02 volumio go-librespot[20345]: time="2026-02-12T18:03:02-06:00" level=debug msg="app state loaded"
Feb 12 18:03:02 volumio go-librespot[20345]: time="2026-02-12T18:03:02-06:00" level=debug msg="stored credentials not found"
Feb 12 18:03:02 volumio go-librespot[20345]: time="2026-02-12T18:03:02-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 12 18:03:03 volumio go-librespot[20345]: time="2026-02-12T18:03:03-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 12 18:03:03 volumio go-librespot[20345]: time="2026-02-12T18:03:03-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 12 18:03:03 volumio go-librespot[20345]: time="2026-02-12T18:03:03-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 12 18:03:03 volumio go-librespot[20345]: time="2026-02-12T18:03:03-06:00" level=info msg="zeroconf server listening on port 44863"
Feb 12 18:03:03 volumio go-librespot[20345]: time="2026-02-12T18:03:03-06:00" level=debug msg="obtained new client token: AACVrKi0whJT6Gu9kJ7j7+FitE8DhD+gl2TkPw3in7XnK5m6CUdUPNwycDfyhL4yeSavDJFI+MRHYZibf4rMzhzxmhnmvtUeH2erzg+b8BDf1Loi2nbW2unl9NpOXA2/gjH0EAZifjn1kLh3iifo73X4KEZOr8RjYHeQW95DDSVqaapUyDxe/i7OwLoG37raFuuE1G3js+JIxfLKXO1ABeloXgISKuQaiDT9I7ir3/2dW/GdKyUIUGg="
Feb 12 18:03:03 volumio go-librespot[20345]: time="2026-02-12T18:03:03-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 12 18:03:03 volumio go-librespot[20345]: time="2026-02-12T18:03:03-06:00" level=debug msg="completed keyexchange"
Feb 12 18:03:03 volumio go-librespot[20345]: time="2026-02-12T18:03:03-06:00" level=debug msg="completed challenge"
Feb 12 18:03:03 volumio go-librespot[20345]: time="2026-02-12T18:03:03-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 12 18:03:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 12 18:03:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 12 18:03:03 volumio volumio[19793]: info: Initializing connection to go-librespot Websocket
Feb 12 18:03:03 volumio volumio[19793]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 12 18:03:03 volumio volumio[19793]: info: Setting Geolocation for MyVolumio to us1
Feb 12 18:03:03 volumio volumio[19793]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 12 18:03:03 volumio volumio[19793]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 12 18:03:03 volumio volumio[19793]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 12 18:03:04 volumio volumio[19793]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Feb 12 18:03:04 volumio volumio[19793]: info: Updating MyVolumio device info
Feb 12 18:03:05 volumio volumio[19793]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 12 18:03:05 volumio volumio[19793]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 12 18:03:05 volumio volumio[19793]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 12 18:03:05 volumio volumio[19793]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Feb 12 18:03:06 volumio volumio[19793]: info: Initializing connection to go-librespot Websocket
Feb 12 18:03:06 volumio volumio[19793]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 12 18:03:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 58.
Feb 12 18:03:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 12 18:03:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 12 18:03:06 volumio go-librespot[20376]: go-librespot daemon starting...
Feb 12 18:03:06 volumio go-librespot[20377]: time="2026-02-12T18:03:06-06:00" level=info msg="running go-librespot 0.4.0"
Feb 12 18:03:06 volumio go-librespot[20377]: time="2026-02-12T18:03:06-06:00" level=debug msg="app state loaded"
Feb 12 18:03:06 volumio go-librespot[20377]: time="2026-02-12T18:03:06-06:00" level=debug msg="stored credentials not found"
Feb 12 18:03:06 volumio go-librespot[20377]: time="2026-02-12T18:03:06-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 12 18:03:06 volumio go-librespot[20377]: time="2026-02-12T18:03:06-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 12 18:03:06 volumio go-librespot[20377]: time="2026-02-12T18:03:06-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 12 18:03:06 volumio go-librespot[20377]: time="2026-02-12T18:03:06-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 12 18:03:06 volumio go-librespot[20377]: time="2026-02-12T18:03:06-06:00" level=info msg="zeroconf server listening on port 34611"
Feb 12 18:03:06 volumio go-librespot[20377]: time="2026-02-12T18:03:06-06:00" level=debug msg="obtained new client token: AAAFktt2x0yger6v9BRF25+erSqpgjDA9Wanq97bkBRcA+Jx196sTGIkNOFJzIgRG20C3DjzoX8wS019MxjZ9dtetRTykmY79A1qStuTiBwNWcSBp+Edi6NM5SJrhXJPyFWuhNwx/tAvhoRefCgxyGFmVCClABh+UzRH/gtMyJ2UASen8LWVCviIFhzTb0CCxsacpRRLz5dJ0WVa1IFVVc0iIYed7o74FyoHlxAwppc+kqLiCnKm+Mh4VQ=="
Feb 12 18:03:06 volumio go-librespot[20377]: time="2026-02-12T18:03:06-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 12 18:03:07 volumio go-librespot[20377]: time="2026-02-12T18:03:07-06:00" level=debug msg="completed keyexchange"
Feb 12 18:03:07 volumio go-librespot[20377]: time="2026-02-12T18:03:07-06:00" level=debug msg="completed challenge"
Feb 12 18:03:07 volumio go-librespot[20377]: time="2026-02-12T18:03:07-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 12 18:03:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 12 18:03:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 12 18:03:09 volumio volumio[19793]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Feb 12 18:03:09 volumio volumio[19793]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 12 18:03:09 volumio volumio[19793]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Feb 12 18:03:09 volumio volumio[19793]: info: Initializing connection to go-librespot Websocket
Feb 12 18:03:09 volumio volumio[19793]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 12 18:03:09 volumio volumio-remote-updater[2265]: Test mode disabled
Feb 12 18:03:09 volumio volumio-remote-updater[2265]: Alpha mode disabled
Feb 12 18:03:09 volumio volumio-remote-updater[2265]: Alpha legacy test mode disabled
Feb 12 18:03:09 volumio volumio[19793]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Feb 12 18:03:09 volumio volumio[19793]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\nFix for CPU utilization spike
\nFix for triggerhappy input permission
\n
\nNEW ADDITIONS
\n\nAdd BLE onboarding plugin
\nEnable vc4-kms-v3d for all compatible RPi models
\nUpdate Tidal SDK
\n\n","title":"Update v4.096","updateavailable":true}
Feb 12 18:03:09 volumio volumio[19793]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Feb 12 18:03:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 59.
Feb 12 18:03:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 12 18:03:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 12 18:03:10 volumio go-librespot[20391]: go-librespot daemon starting...
Feb 12 18:03:10 volumio go-librespot[20392]: time="2026-02-12T18:03:10-06:00" level=info msg="running go-librespot 0.4.0"
Feb 12 18:03:10 volumio go-librespot[20392]: time="2026-02-12T18:03:10-06:00" level=debug msg="app state loaded"
Feb 12 18:03:10 volumio go-librespot[20392]: time="2026-02-12T18:03:10-06:00" level=debug msg="stored credentials not found"
Feb 12 18:03:10 volumio go-librespot[20392]: time="2026-02-12T18:03:10-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 12 18:03:10 volumio go-librespot[20392]: time="2026-02-12T18:03:10-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 12 18:03:10 volumio go-librespot[20392]: time="2026-02-12T18:03:10-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 12 18:03:10 volumio go-librespot[20392]: time="2026-02-12T18:03:10-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 12 18:03:10 volumio go-librespot[20392]: time="2026-02-12T18:03:10-06:00" level=info msg="zeroconf server listening on port 40903"
Feb 12 18:03:10 volumio go-librespot[20392]: time="2026-02-12T18:03:10-06:00" level=debug msg="obtained new client token: AABJj4+0nXyVsVQ4HXPzo56A/Em/usSWWoyfZikqZstm+111Ije2YugMN2ky1O+nSbiNlZD28SLOy2bbuyL0KDPm8y5/H2KmYZSfzbVmPaW/MZSQBnuo8uymkNIpfSyj0xmKrrjJ2FI4jeCXealObYT9TydMKeszbXB2PsmRnAnn1oD0MDVBU901FWiHKvPpyLXs6jTiTEEGhPoR280mnYpiNz3ouIAWldFGZ7BOLlyKW5BMhvh+Veaf2w=="
Feb 12 18:03:10 volumio go-librespot[20392]: time="2026-02-12T18:03:10-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 12 18:03:10 volumio go-librespot[20392]: time="2026-02-12T18:03:10-06:00" level=debug msg="completed keyexchange"
Feb 12 18:03:10 volumio go-librespot[20392]: time="2026-02-12T18:03:10-06:00" level=debug msg="completed challenge"
Feb 12 18:03:10 volumio go-librespot[20392]: time="2026-02-12T18:03:10-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 12 18:03:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 12 18:03:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 12 18:03:11 volumio volumio[19793]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Feb 12 18:03:11 volumio volumio[19793]: 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 12 18:03:11 volumio volumio[19793]: info: CoreCommandRouter::volumioGetState
Feb 12 18:03:11 volumio volumio[19793]: info: CorePlayQueue::getTrack 0
Feb 12 18:03:12 volumio volumio[19793]: info: Initializing connection to go-librespot Websocket
Feb 12 18:03:12 volumio volumio[19793]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 12 18:03:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 60.
Feb 12 18:03:14 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 12 18:03:14 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 12 18:03:14 volumio go-librespot[20400]: go-librespot daemon starting...
Feb 12 18:03:14 volumio go-librespot[20401]: time="2026-02-12T18:03:14-06:00" level=info msg="running go-librespot 0.4.0"
Feb 12 18:03:14 volumio go-librespot[20401]: time="2026-02-12T18:03:14-06:00" level=debug msg="app state loaded"
Feb 12 18:03:14 volumio go-librespot[20401]: time="2026-02-12T18:03:14-06:00" level=debug msg="stored credentials not found"
Feb 12 18:03:14 volumio go-librespot[20401]: time="2026-02-12T18:03:14-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 12 18:03:14 volumio go-librespot[20401]: time="2026-02-12T18:03:14-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 12 18:03:14 volumio go-librespot[20401]: time="2026-02-12T18:03:14-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 12 18:03:14 volumio go-librespot[20401]: time="2026-02-12T18:03:14-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 12 18:03:14 volumio go-librespot[20401]: time="2026-02-12T18:03:14-06:00" level=info msg="zeroconf server listening on port 44925"
Feb 12 18:03:14 volumio go-librespot[20401]: time="2026-02-12T18:03:14-06:00" level=debug msg="obtained new client token: AADF6uTdw6fs0D6+enJn1oyqDga1eUrKtd7aV0Sk9TLgGoVZ36WvKDYIf/NjQWaCPJqAWwzYVpThTZwDuyi8Qz2WdUYo3JXvO0lbiBGEpeif1Uokti2Own+z6miLa4XsKryAyrRT3Mq4PsizNUMgg4lv4GAEmFab/EJ8OgHnrxZ0RdVj8LxgkqV1QxKdqKu+JJw0S6i9NRRu4wI4Z4q1O9GnphXfjwau5uefbzQ0Jga9O3Be90FEt2+c8Q=="
Feb 12 18:03:14 volumio go-librespot[20401]: time="2026-02-12T18:03:14-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 12 18:03:14 volumio go-librespot[20401]: time="2026-02-12T18:03:14-06:00" level=debug msg="completed keyexchange"
Feb 12 18:03:14 volumio go-librespot[20401]: time="2026-02-12T18:03:14-06:00" level=debug msg="completed challenge"
Feb 12 18:03:14 volumio go-librespot[20401]: time="2026-02-12T18:03:14-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 12 18:03:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 12 18:03:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 12 18:03:15 volumio volumio[19793]: info: Initializing connection to go-librespot Websocket
Feb 12 18:03:15 volumio volumio[19793]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 12 18:03:16 volumio volumio[19793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 12 18:03:16 volumio volumio[19793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 12 18:03:16 volumio volumio[19793]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Feb 12 18:03:16 volumio volumio[19793]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Feb 12 18:03:16 volumio volumio[19793]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Feb 12 18:03:16 volumio volumio[19793]: info: CoreCommandRouter::volumioGetBrowseSources
Feb 12 18:03:16 volumio volumio[19793]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Feb 12 18:03:17 volumio volumio[19793]: error: MyVolumio Plugin failed to authenticate in a timely fashion
Feb 12 18:03:17 volumio volumio[19793]: info: Completed starting MyVolumio Plugin
Feb 12 18:03:17 volumio volumio[19793]:  [Metrics]  CommandRouter:  55s 51.20ms 
Feb 12 18:03:17 volumio volumio[19793]: info: CoreCommandRouter::volumiosetStartupVolume
Feb 12 18:03:17 volumio volumio[19793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 12 18:03:17 volumio volumio[19793]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 12 18:03:17 volumio volumio[19793]: info: CoreCommandRouter::Close All Modals sent
Feb 12 18:03:17 volumio volumio[19793]: info: CoreCommandRouter::Close All Modals sent
Feb 12 18:03:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 61.
Feb 12 18:03:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 12 18:03:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 12 18:03:17 volumio go-librespot[20425]: go-librespot daemon starting...
Feb 12 18:03:17 volumio go-librespot[20426]: time="2026-02-12T18:03:17-06:00" level=info msg="running go-librespot 0.4.0"
Feb 12 18:03:17 volumio go-librespot[20426]: time="2026-02-12T18:03:17-06:00" level=debug msg="app state loaded"
Feb 12 18:03:17 volumio go-librespot[20426]: time="2026-02-12T18:03:17-06:00" level=debug msg="stored credentials not found"
Feb 12 18:03:17 volumio go-librespot[20426]: time="2026-02-12T18:03:17-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 12 18:03:18 volumio go-librespot[20426]: time="2026-02-12T18:03:18-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 12 18:03:18 volumio go-librespot[20426]: time="2026-02-12T18:03:18-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 12 18:03:18 volumio go-librespot[20426]: time="2026-02-12T18:03:18-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 12 18:03:18 volumio go-librespot[20426]: time="2026-02-12T18:03:18-06:00" level=info msg="zeroconf server listening on port 36799"
Feb 12 18:03:18 volumio go-librespot[20426]: time="2026-02-12T18:03:18-06:00" level=debug msg="obtained new client token: AACQJ6qcTYOdHhyVbAs6BFPLwIQFp69nxxGLD0mclnCsQSP0CmgEKV8/6zMrqo/pdD0IThnUF4rfZ8HPGbRQqP1KfTQ5L6kMGt1DyVkZdyUf0BS3w4KmVeRvogTEPjRczv8qumkBIUQCXhQTOf6q2wTTmD04LVEp9Az36BeWA4QYtQPjkZ3wQDvdMI3fFis+1gP+q4YD5p6XOnfOZuDz0m7fUejl14GnrizwVer6lNia6Czp4ZQpk/o="
Feb 12 18:03:18 volumio go-librespot[20426]: time="2026-02-12T18:03:18-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 12 18:03:18 volumio go-librespot[20426]: time="2026-02-12T18:03:18-06:00" level=debug msg="connected to ap-guc3.spotify.com:443"
Feb 12 18:03:18 volumio go-librespot[20426]: time="2026-02-12T18:03:18-06:00" level=debug msg="completed keyexchange"
Feb 12 18:03:18 volumio go-librespot[20426]: time="2026-02-12T18:03:18-06:00" level=debug msg="completed challenge"
Feb 12 18:03:18 volumio volumio[19793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Feb 12 18:03:18 volumio volumio[19793]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Feb 12 18:03:18 volumio volumio[19793]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Feb 12 18:03:18 volumio go-librespot[20426]: time="2026-02-12T18:03:18-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 12 18:03:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 12 18:03:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 12 18:03:18 volumio volumio[19793]: info: Initializing connection to go-librespot Websocket
Feb 12 18:03:18 volumio volumio[19793]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 12 18:03:21 volumio volumio[19793]: info: Initializing connection to go-librespot Websocket
Feb 12 18:03:21 volumio volumio[19793]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 12 18:03:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 62.
Feb 12 18:03:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 12 18:03:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 12 18:03:21 volumio go-librespot[20436]: go-librespot daemon starting...
Feb 12 18:03:21 volumio go-librespot[20437]: time="2026-02-12T18:03:21-06:00" level=info msg="running go-librespot 0.4.0"
Feb 12 18:03:21 volumio go-librespot[20437]: time="2026-02-12T18:03:21-06:00" level=debug msg="app state loaded"
Feb 12 18:03:21 volumio go-librespot[20437]: time="2026-02-12T18:03:21-06:00" level=debug msg="stored credentials not found"
Feb 12 18:03:21 volumio go-librespot[20437]: time="2026-02-12T18:03:21-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 12 18:03:21 volumio go-librespot[20437]: time="2026-02-12T18:03:21-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 12 18:03:21 volumio go-librespot[20437]: time="2026-02-12T18:03:21-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 12 18:03:21 volumio go-librespot[20437]: time="2026-02-12T18:03:21-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 12 18:03:21 volumio go-librespot[20437]: time="2026-02-12T18:03:21-06:00" level=info msg="zeroconf server listening on port 46771"
Feb 12 18:03:21 volumio go-librespot[20437]: time="2026-02-12T18:03:21-06:00" level=debug msg="obtained new client token: AAC1W2hKL17Tb44+6z/RnT2EQYPD3oPWSnmzAe50zwLfgQ4Q80D32EE8VXGW3ULCtk7N0yWAo2odjKkSL8LnNdTqMLK8i/cAJKbHW7DBkuyd206yM3+RlhMM+Q/5iTtTurk6+JZvswaKs5AhQnsrflsDVRafreyEUtELfWWujtii3rUHWBgaxoFcbMiL/WIaPZtbuLaMnZQ1pwlI6gvCo/KtPWc/hrs+RIl1GVXPKVtS/37kxC8OCmpCCQ=="
Feb 12 18:03:22 volumio go-librespot[20437]: time="2026-02-12T18:03:22-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 12 18:03:22 volumio go-librespot[20437]: time="2026-02-12T18:03:22-06:00" level=debug msg="completed keyexchange"
Feb 12 18:03:22 volumio go-librespot[20437]: time="2026-02-12T18:03:22-06:00" level=debug msg="completed challenge"
Feb 12 18:03:22 volumio go-librespot[20437]: time="2026-02-12T18:03:22-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 12 18:03:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 12 18:03:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 12 18:03:24 volumio volumio[19793]: info: BOOT COMPLETED
Feb 12 18:03:24 volumio volumio[19793]: info: Initializing connection to go-librespot Websocket
Feb 12 18:03:24 volumio volumio[19793]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 12 18:03:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 63.
Feb 12 18:03:25 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 12 18:03:25 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 12 18:03:25 volumio go-librespot[20458]: go-librespot daemon starting...
Feb 12 18:03:25 volumio go-librespot[20459]: time="2026-02-12T18:03:25-06:00" level=info msg="running go-librespot 0.4.0"
Feb 12 18:03:25 volumio go-librespot[20459]: time="2026-02-12T18:03:25-06:00" level=debug msg="app state loaded"
Feb 12 18:03:25 volumio go-librespot[20459]: time="2026-02-12T18:03:25-06:00" level=debug msg="stored credentials not found"
Feb 12 18:03:25 volumio go-librespot[20459]: time="2026-02-12T18:03:25-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 12 18:03:25 volumio go-librespot[20459]: time="2026-02-12T18:03:25-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 12 18:03:25 volumio go-librespot[20459]: time="2026-02-12T18:03:25-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 12 18:03:25 volumio go-librespot[20459]: time="2026-02-12T18:03:25-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 12 18:03:25 volumio go-librespot[20459]: time="2026-02-12T18:03:25-06:00" level=info msg="zeroconf server listening on port 41559"
Feb 12 18:03:25 volumio go-librespot[20459]: time="2026-02-12T18:03:25-06:00" level=debug msg="obtained new client token: AADP0pO8Y9GT83wR/bZrlhAd2epnbMOtfA8G27YaPBrFRgSuRBfc9h5ysOMzTv1PTnEZOWj+jdsysFUsfc9QXa+Ltg7TqIdrnNL9JN+h2xfyiReJIvReUUYrQpRgKD8LfEZ+m3m971B8iIgvLlCUQW6xhcKGVUAGtpVPqUuTAjmyhL8GWYZRYp2Z51rm/AjCcOFDZA7PN9J3CFl7R7JVO816UjrQ7xQEECrtyEyQUMt946XIx3h1OoxkhQ=="
Feb 12 18:03:25 volumio go-librespot[20459]: time="2026-02-12T18:03:25-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 12 18:03:25 volumio go-librespot[20459]: time="2026-02-12T18:03:25-06:00" level=debug msg="completed keyexchange"
Feb 12 18:03:25 volumio go-librespot[20459]: time="2026-02-12T18:03:25-06:00" level=debug msg="completed challenge"
Feb 12 18:03:25 volumio go-librespot[20459]: time="2026-02-12T18:03:25-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 12 18:03:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 12 18:03:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 12 18:03:27 volumio volumio[19793]: info: Initializing connection to go-librespot Websocket
Feb 12 18:03:27 volumio volumio[19793]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 12 18:03:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 64.
Feb 12 18:03:29 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 12 18:03:29 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 12 18:03:29 volumio go-librespot[20466]: go-librespot daemon starting...
Feb 12 18:03:29 volumio go-librespot[20467]: time="2026-02-12T18:03:29-06:00" level=info msg="running go-librespot 0.4.0"
Feb 12 18:03:29 volumio go-librespot[20467]: time="2026-02-12T18:03:29-06:00" level=debug msg="app state loaded"
Feb 12 18:03:29 volumio go-librespot[20467]: time="2026-02-12T18:03:29-06:00" level=debug msg="stored credentials not found"
Feb 12 18:03:29 volumio go-librespot[20467]: time="2026-02-12T18:03:29-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 12 18:03:29 volumio go-librespot[20467]: time="2026-02-12T18:03:29-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 12 18:03:29 volumio go-librespot[20467]: time="2026-02-12T18:03:29-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 12 18:03:29 volumio go-librespot[20467]: time="2026-02-12T18:03:29-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 12 18:03:29 volumio go-librespot[20467]: time="2026-02-12T18:03:29-06:00" level=info msg="zeroconf server listening on port 36847"
Feb 12 18:03:29 volumio go-librespot[20467]: time="2026-02-12T18:03:29-06:00" level=debug msg="obtained new client token: AAA6bLySFF6Ttjoxdb3MPuB5YigxdOu4SUiIHFRBKC2aQox3uY2D1HBQ3geeRH9fzvBmrAoSzN/25BsxSneJei0EKy/vAZOAurlQnS2+dSFemLOzEaQUFumCpm/GgNq7DXEpcZfUVvJCuyLXQ5Ok1qvmCp2aD7caJKpw1RjMojAtzxBoliq93DxbP9fn/KYs9b+cyAYEiWM1Ev129oZYYwNcxTfeh7iYoPBwWchvpxctHlO7NLM7qfjSrQ=="
Feb 12 18:03:29 volumio go-librespot[20467]: time="2026-02-12T18:03:29-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 12 18:03:29 volumio go-librespot[20467]: time="2026-02-12T18:03:29-06:00" level=debug msg="completed keyexchange"
Feb 12 18:03:29 volumio go-librespot[20467]: time="2026-02-12T18:03:29-06:00" level=debug msg="completed challenge"
Feb 12 18:03:29 volumio go-librespot[20467]: time="2026-02-12T18:03:29-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 12 18:03:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 12 18:03:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 12 18:03:30 volumio volumio[19793]: info: Initializing connection to go-librespot Websocket
Feb 12 18:03:30 volumio volumio[19793]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 12 18:03:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 65.
Feb 12 18:03:32 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 12 18:03:32 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 12 18:03:32 volumio go-librespot[20478]: go-librespot daemon starting...
Feb 12 18:03:32 volumio go-librespot[20479]: time="2026-02-12T18:03:32-06:00" level=info msg="running go-librespot 0.4.0"
Feb 12 18:03:32 volumio go-librespot[20479]: time="2026-02-12T18:03:32-06:00" level=debug msg="app state loaded"
Feb 12 18:03:32 volumio go-librespot[20479]: time="2026-02-12T18:03:32-06:00" level=debug msg="stored credentials not found"
Feb 12 18:03:32 volumio go-librespot[20479]: time="2026-02-12T18:03:32-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 12 18:03:33 volumio go-librespot[20479]: time="2026-02-12T18:03:33-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 12 18:03:33 volumio go-librespot[20479]: time="2026-02-12T18:03:33-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 12 18:03:33 volumio go-librespot[20479]: time="2026-02-12T18:03:33-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 12 18:03:33 volumio go-librespot[20479]: time="2026-02-12T18:03:33-06:00" level=info msg="zeroconf server listening on port 46161"
Feb 12 18:03:33 volumio go-librespot[20479]: time="2026-02-12T18:03:33-06:00" level=debug msg="obtained new client token: AADlY6WfsWA+50dOaomYZndeMJF52XHcOexm7pLS4q3efTeHafubuA0qmWxCEduN6mjCw3Bl+073UY575pZ8INfqHgDEa0VT7GGrPlzRRZ4X9DYJoFlMGXJzf74nb8vk/0l63LVWerNxEHhZJguXabjF70oXf9o2xbog6lPZGdk/7a3YQDvyMUBa5lRoX1fEJ0BaIJdoJ3Gr5U8EeU687sKV2RY1Cr+EAPR/J6xVFr0tacw1pUevrsU="
Feb 12 18:03:33 volumio go-librespot[20479]: time="2026-02-12T18:03:33-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 12 18:03:33 volumio go-librespot[20479]: time="2026-02-12T18:03:33-06:00" level=debug msg="completed keyexchange"
Feb 12 18:03:33 volumio go-librespot[20479]: time="2026-02-12T18:03:33-06:00" level=debug msg="completed challenge"
Feb 12 18:03:33 volumio go-librespot[20479]: time="2026-02-12T18:03:33-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 12 18:03:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 12 18:03:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 12 18:03:33 volumio volumio[19793]: info: Initializing connection to go-librespot Websocket
Feb 12 18:03:33 volumio volumio[19793]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 12 18:03:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 66.
Feb 12 18:03:36 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 12 18:03:36 volumio volumio[19793]: info: Initializing connection to go-librespot Websocket
Feb 12 18:03:36 volumio volumio[19793]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 12 18:03:36 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 12 18:03:36 volumio go-librespot[20500]: go-librespot daemon starting...
Feb 12 18:03:36 volumio go-librespot[20501]: time="2026-02-12T18:03:36-06:00" level=info msg="running go-librespot 0.4.0"
Feb 12 18:03:36 volumio go-librespot[20501]: time="2026-02-12T18:03:36-06:00" level=debug msg="app state loaded"
Feb 12 18:03:36 volumio go-librespot[20501]: time="2026-02-12T18:03:36-06:00" level=debug msg="stored credentials not found"
Feb 12 18:03:36 volumio go-librespot[20501]: time="2026-02-12T18:03:36-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 12 18:03:36 volumio go-librespot[20501]: time="2026-02-12T18:03:36-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 12 18:03:36 volumio go-librespot[20501]: time="2026-02-12T18:03:36-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 12 18:03:36 volumio go-librespot[20501]: time="2026-02-12T18:03:36-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 12 18:03:36 volumio go-librespot[20501]: time="2026-02-12T18:03:36-06:00" level=info msg="zeroconf server listening on port 34207"
Feb 12 18:03:36 volumio go-librespot[20501]: time="2026-02-12T18:03:36-06:00" level=debug msg="obtained new client token: AABonpqXLoxo1Q00xyrXMSfqZ6Ev8hX+lW/x9vPLbtEk7z3iytJS7F21x48pX6rN/j8hYi9iPOqU86A0XIqrrHQb2KmU0rYvcpGk3qzhXkpWyFxbmwkWmDdcuFtKCUJWRNBHv8JNpWgCjHMquKNzOgV/sbHbBAGCwW2ttgMeJTOzMSwifQvHAN6Jd3igC46byydvDAFRVlav+MWqGMVFMs9oS0YeUtSOKtzxYPF/PBChFc7rBQSvDNKlPg=="
Feb 12 18:03:36 volumio go-librespot[20501]: time="2026-02-12T18:03:36-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 12 18:03:37 volumio go-librespot[20501]: time="2026-02-12T18:03:37-06:00" level=debug msg="completed keyexchange"
Feb 12 18:03:37 volumio go-librespot[20501]: time="2026-02-12T18:03:37-06:00" level=debug msg="completed challenge"
Feb 12 18:03:37 volumio go-librespot[20501]: time="2026-02-12T18:03:37-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 12 18:03:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 12 18:03:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 12 18:03:39 volumio volumio[19793]: info: Initializing connection to go-librespot Websocket
Feb 12 18:03:39 volumio volumio[19793]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 12 18:03:39 volumio volumio[19793]: info: UPDATER: Scheduling automatic update
Feb 12 18:03:39 volumio volumio[19793]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStartTime
Feb 12 18:03:39 volumio volumio[19793]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStopTime
Feb 12 18:03:39 volumio volumio[19793]: info: UPDATER: Auto update will take place at: Fri Feb 13 2026 04:36:36 GMT-0600 (Central Standard Time)
Feb 12 18:03:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 67.
Feb 12 18:03:40 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 12 18:03:40 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 12 18:03:40 volumio go-librespot[20508]: go-librespot daemon starting...
Feb 12 18:03:40 volumio go-librespot[20509]: time="2026-02-12T18:03:40-06:00" level=info msg="running go-librespot 0.4.0"
Feb 12 18:03:40 volumio go-librespot[20509]: time="2026-02-12T18:03:40-06:00" level=debug msg="app state loaded"
Feb 12 18:03:40 volumio go-librespot[20509]: time="2026-02-12T18:03:40-06:00" level=debug msg="stored credentials not found"
Feb 12 18:03:40 volumio go-librespot[20509]: time="2026-02-12T18:03:40-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 12 18:03:40 volumio go-librespot[20509]: time="2026-02-12T18:03:40-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 12 18:03:40 volumio go-librespot[20509]: time="2026-02-12T18:03:40-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 12 18:03:40 volumio go-librespot[20509]: time="2026-02-12T18:03:40-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 12 18:03:40 volumio go-librespot[20509]: time="2026-02-12T18:03:40-06:00" level=info msg="zeroconf server listening on port 40013"
Feb 12 18:03:40 volumio go-librespot[20509]: time="2026-02-12T18:03:40-06:00" level=debug msg="obtained new client token: AADjztnRfLHvlee+fiMrLh3eAGx9/WZ4Yal4Jy4MebJd+jJSRyz13rI7J46J0MY5POYwIiO+LPwUZDm9bNv+QsYQPBCmi17tOK+FJ57GaYMBYQYYKoO3oZKWbd1GZ0wQ+AJgtvNb+WOjlB/XjJzUYvUSXt3Ls4e54xZRa3Xp0Swxs5D0jEWISXpIjCUBpxd1VgtpU7L+zlS0MOVQTL0rXKxt4dfrlo6y5cBE+TvkuafVsTvB3KbyEIVa1Q=="
Feb 12 18:03:40 volumio go-librespot[20509]: time="2026-02-12T18:03:40-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 12 18:03:40 volumio go-librespot[20509]: time="2026-02-12T18:03:40-06:00" level=debug msg="completed keyexchange"
Feb 12 18:03:40 volumio go-librespot[20509]: time="2026-02-12T18:03:40-06:00" level=debug msg="completed challenge"
Feb 12 18:03:40 volumio go-librespot[20509]: time="2026-02-12T18:03:40-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 12 18:03:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 12 18:03:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 12 18:03:42 volumio volumio[19793]: info: Initializing connection to go-librespot Websocket
Feb 12 18:03:42 volumio volumio[19793]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 12 18:03:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 68.
Feb 12 18:03:44 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 12 18:03:44 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 12 18:03:44 volumio go-librespot[20516]: go-librespot daemon starting...
Feb 12 18:03:44 volumio go-librespot[20517]: time="2026-02-12T18:03:44-06:00" level=info msg="running go-librespot 0.4.0"
Feb 12 18:03:44 volumio go-librespot[20517]: time="2026-02-12T18:03:44-06:00" level=debug msg="app state loaded"
Feb 12 18:03:44 volumio go-librespot[20517]: time="2026-02-12T18:03:44-06:00" level=debug msg="stored credentials not found"
Feb 12 18:03:44 volumio go-librespot[20517]: time="2026-02-12T18:03:44-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 12 18:03:44 volumio go-librespot[20517]: time="2026-02-12T18:03:44-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 12 18:03:44 volumio go-librespot[20517]: time="2026-02-12T18:03:44-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 12 18:03:44 volumio go-librespot[20517]: time="2026-02-12T18:03:44-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 12 18:03:44 volumio go-librespot[20517]: time="2026-02-12T18:03:44-06:00" level=info msg="zeroconf server listening on port 46073"
Feb 12 18:03:44 volumio go-librespot[20517]: time="2026-02-12T18:03:44-06:00" level=debug msg="obtained new client token: AACcWzbfMREeJMwGxTFjZwnXab+EpEpzwySLRqxswcJuO0T8HHw20koTUU0BjetEusQTgdPXIRKJz+Xia4uD+hWpzMxzfd74wjooVgfVmW0aDWINO4Syvbte6ZLcxSvLhB92QHSYysbVfLWUyb7LLS1WJAG8ova2lf2Ko9DXbVS4mBBRUDdpA8JJ3RaqKZVuB/KwSFl6VuDstTuYHUoDgvJuUUXq/DwBcEgqIEFHFg99S8H9gRWxUIjt2A=="
Feb 12 18:03:44 volumio go-librespot[20517]: time="2026-02-12T18:03:44-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 12 18:03:44 volumio go-librespot[20517]: time="2026-02-12T18:03:44-06:00" level=debug msg="completed keyexchange"
Feb 12 18:03:44 volumio go-librespot[20517]: time="2026-02-12T18:03:44-06:00" level=debug msg="completed challenge"
Feb 12 18:03:44 volumio go-librespot[20517]: time="2026-02-12T18:03:44-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 12 18:03:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 12 18:03:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 12 18:03:45 volumio volumio[19793]: info: Initializing connection to go-librespot Websocket
Feb 12 18:03:45 volumio volumio[19793]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 12 18:03:47 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 69.
Feb 12 18:03:47 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 12 18:03:47 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 12 18:03:47 volumio go-librespot[20538]: go-librespot daemon starting...
Feb 12 18:03:47 volumio go-librespot[20539]: time="2026-02-12T18:03:47-06:00" level=info msg="running go-librespot 0.4.0"
Feb 12 18:03:47 volumio go-librespot[20539]: time="2026-02-12T18:03:47-06:00" level=debug msg="app state loaded"
Feb 12 18:03:47 volumio go-librespot[20539]: time="2026-02-12T18:03:47-06:00" level=debug msg="stored credentials not found"
Feb 12 18:03:47 volumio go-librespot[20539]: time="2026-02-12T18:03:47-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 12 18:03:48 volumio go-librespot[20539]: time="2026-02-12T18:03:48-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 12 18:03:48 volumio go-librespot[20539]: time="2026-02-12T18:03:48-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 12 18:03:48 volumio go-librespot[20539]: time="2026-02-12T18:03:48-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 12 18:03:48 volumio go-librespot[20539]: time="2026-02-12T18:03:48-06:00" level=info msg="zeroconf server listening on port 33437"
Feb 12 18:03:48 volumio go-librespot[20539]: time="2026-02-12T18:03:48-06:00" level=debug msg="obtained new client token: AAD4AGqFQQWsUiQ/uF49rxDlEUcq7E75IShEMNSW3dradoVT8IVhG5wTZLMmzjarneRCJJIcQobQmZYgcwmvFdxYfDtZAd5T95JlneRGJzDp0SCCvPxppJ/upESJugQQpAA4X/9g7ZQ7IpTsxrHaE2fZgoVrhFuf+IsxuIwlUIoYZUb2mMsNvQ+EOGDHmtWBHLodckX+8VZyfHJ72o6cL/0Jo4k9URr1WSNY09ZQOPXmxJOfgt2yeT0="
Feb 12 18:03:48 volumio go-librespot[20539]: time="2026-02-12T18:03:48-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 12 18:03:48 volumio go-librespot[20539]: time="2026-02-12T18:03:48-06:00" level=debug msg="completed keyexchange"
Feb 12 18:03:48 volumio go-librespot[20539]: time="2026-02-12T18:03:48-06:00" level=debug msg="completed challenge"
Feb 12 18:03:48 volumio go-librespot[20539]: time="2026-02-12T18:03:48-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 12 18:03:48 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 12 18:03:48 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 12 18:03:48 volumio volumio[19793]: info: Initializing connection to go-librespot Websocket
Feb 12 18:03:48 volumio volumio[19793]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 12 18:03:51 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 70.
Feb 12 18:03:51 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 12 18:03:51 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 12 18:03:51 volumio go-librespot[20549]: go-librespot daemon starting...
Feb 12 18:03:51 volumio volumio[19793]: info: Initializing connection to go-librespot Websocket
Feb 12 18:03:51 volumio volumio[19793]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 12 18:03:51 volumio go-librespot[20550]: time="2026-02-12T18:03:51-06:00" level=info msg="running go-librespot 0.4.0"
Feb 12 18:03:51 volumio go-librespot[20550]: time="2026-02-12T18:03:51-06:00" level=debug msg="app state loaded"
Feb 12 18:03:51 volumio go-librespot[20550]: time="2026-02-12T18:03:51-06:00" level=debug msg="stored credentials not found"
Feb 12 18:03:51 volumio go-librespot[20550]: time="2026-02-12T18:03:51-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 12 18:03:51 volumio go-librespot[20550]: time="2026-02-12T18:03:51-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 12 18:03:51 volumio go-librespot[20550]: time="2026-02-12T18:03:51-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 12 18:03:51 volumio go-librespot[20550]: time="2026-02-12T18:03:51-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 12 18:03:51 volumio go-librespot[20550]: time="2026-02-12T18:03:51-06:00" level=info msg="zeroconf server listening on port 32871"
Feb 12 18:03:51 volumio go-librespot[20550]: time="2026-02-12T18:03:51-06:00" level=debug msg="obtained new client token: AABeeUuE27YKsuiDmG+xY7jLgDp929uRn1rEL1XV4mrT/v2CSIBYqBWamHf7IfeWD3MXaWACuFZwXVKXhrCUTwVvnx3dHEgRsDs3KUOUX739GLWdU55ysWegm+9Eci98WmxH7lBd80zgPB9Y1cf9kO/pG/PoXfdJ3/Vq1ujdGE0BwoZ4yegknnw7WqQmA/lndgN+/1+0U0Aj2lGUDTDATI2OVviw70rMq++1epR9Ag2LHhjs4putzc4PIw=="
Feb 12 18:03:52 volumio go-librespot[20550]: time="2026-02-12T18:03:52-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 12 18:03:52 volumio go-librespot[20550]: time="2026-02-12T18:03:52-06:00" level=debug msg="completed keyexchange"
Feb 12 18:03:52 volumio go-librespot[20550]: time="2026-02-12T18:03:52-06:00" level=debug msg="completed challenge"
Feb 12 18:03:52 volumio go-librespot[20550]: time="2026-02-12T18:03:52-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 12 18:03:52 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 12 18:03:52 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 12 18:03:54 volumio volumio[19793]: info: Initializing connection to go-librespot Websocket
Feb 12 18:03:54 volumio volumio[19793]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 12 18:03:55 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 71.
Feb 12 18:03:55 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 12 18:03:55 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 12 18:03:55 volumio go-librespot[20572]: go-librespot daemon starting...
Feb 12 18:03:55 volumio go-librespot[20573]: time="2026-02-12T18:03:55-06:00" level=info msg="running go-librespot 0.4.0"
Feb 12 18:03:55 volumio go-librespot[20573]: time="2026-02-12T18:03:55-06:00" level=debug msg="app state loaded"
Feb 12 18:03:55 volumio go-librespot[20573]: time="2026-02-12T18:03:55-06:00" level=debug msg="stored credentials not found"
Feb 12 18:03:55 volumio go-librespot[20573]: time="2026-02-12T18:03:55-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 12 18:03:55 volumio go-librespot[20573]: time="2026-02-12T18:03:55-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 12 18:03:55 volumio go-librespot[20573]: time="2026-02-12T18:03:55-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 12 18:03:55 volumio go-librespot[20573]: time="2026-02-12T18:03:55-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 12 18:03:55 volumio go-librespot[20573]: time="2026-02-12T18:03:55-06:00" level=info msg="zeroconf server listening on port 39841"
Feb 12 18:03:55 volumio go-librespot[20573]: time="2026-02-12T18:03:55-06:00" level=debug msg="obtained new client token: AAADjHizwSL0Vy3O36PyIOjBINVuvd5ZsRd9joY22Bf79ZC+GWCQwobKLgXnZ5/2NA/48eu405uOUotkgk0LurY2DXvc/64WxB/2dloZM9kZ1dOzZwosZCwALubSCIM9xkQN1QhEpoi0euqwn+1ABgT+SOJ4ebMlDk5Gnrfpncd3fb0G26UbKYh1Q9LtLmOWQoSTNxVdgDNZQp5zRE4ySci3S1AClKE2Mh7Dl82a8wdnsXY5QzyxaliEaQ=="
Feb 12 18:03:55 volumio go-librespot[20573]: time="2026-02-12T18:03:55-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 12 18:03:55 volumio go-librespot[20573]: time="2026-02-12T18:03:55-06:00" level=debug msg="completed keyexchange"
Feb 12 18:03:55 volumio go-librespot[20573]: time="2026-02-12T18:03:55-06:00" level=debug msg="completed challenge"
Feb 12 18:03:55 volumio go-librespot[20573]: time="2026-02-12T18:03:55-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 12 18:03:55 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 12 18:03:55 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 12 18:03:57 volumio volumio[19793]: info: Initializing connection to go-librespot Websocket
Feb 12 18:03:57 volumio volumio[19793]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 12 18:03:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 72.
Feb 12 18:03:59 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 12 18:03:59 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 12 18:03:59 volumio go-librespot[20581]: go-librespot daemon starting...
Feb 12 18:03:59 volumio go-librespot[20582]: time="2026-02-12T18:03:59-06:00" level=info msg="running go-librespot 0.4.0"
Feb 12 18:03:59 volumio go-librespot[20582]: time="2026-02-12T18:03:59-06:00" level=debug msg="app state loaded"
Feb 12 18:03:59 volumio go-librespot[20582]: time="2026-02-12T18:03:59-06:00" level=debug msg="stored credentials not found"
Feb 12 18:03:59 volumio go-librespot[20582]: time="2026-02-12T18:03:59-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 12 18:03:59 volumio go-librespot[20582]: time="2026-02-12T18:03:59-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 12 18:03:59 volumio go-librespot[20582]: time="2026-02-12T18:03:59-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 12 18:03:59 volumio go-librespot[20582]: time="2026-02-12T18:03:59-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 12 18:03:59 volumio go-librespot[20582]: time="2026-02-12T18:03:59-06:00" level=info msg="zeroconf server listening on port 33681"
Feb 12 18:03:59 volumio go-librespot[20582]: time="2026-02-12T18:03:59-06:00" level=debug msg="obtained new client token: AACbOAOI5WB49J0HkOdAH7mR9nifP6LpGiP6z0hlL6a/x+3BQnLT8gSOK/HY7p2Ex2IK2CuyDAX1OrsqKoY49XT1ZJx8+RCxeN1kMr7q5JAAuLfXkAz1j+ycx8l4HJePdIeDI+d19KJcWAqvZHY98EfVKhqE+1L1FH16lMQryE9VQF+jhf8zz+zQt9FGi0UeJUZcN8CwpeRxckQMv8UJOaPFOuZfrX2Yj11riXJz6V5RNqQyAg+X8w5BkQ=="
Feb 12 18:03:59 volumio go-librespot[20582]: time="2026-02-12T18:03:59-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 12 18:03:59 volumio go-librespot[20582]: time="2026-02-12T18:03:59-06:00" level=debug msg="completed keyexchange"
Feb 12 18:03:59 volumio go-librespot[20582]: time="2026-02-12T18:03:59-06:00" level=debug msg="completed challenge"
Feb 12 18:03:59 volumio go-librespot[20582]: time="2026-02-12T18:03:59-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 12 18:03:59 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 12 18:03:59 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 12 18:04:00 volumio volumio[19793]: info: Initializing connection to go-librespot Websocket
Feb 12 18:04:00 volumio volumio[19793]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 12 18:04:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 73.
Feb 12 18:04:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 12 18:04:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 12 18:04:02 volumio go-librespot[20589]: go-librespot daemon starting...
Feb 12 18:04:02 volumio go-librespot[20590]: time="2026-02-12T18:04:02-06:00" level=info msg="running go-librespot 0.4.0"
Feb 12 18:04:02 volumio go-librespot[20590]: time="2026-02-12T18:04:02-06:00" level=debug msg="app state loaded"
Feb 12 18:04:02 volumio go-librespot[20590]: time="2026-02-12T18:04:02-06:00" level=debug msg="stored credentials not found"
Feb 12 18:04:02 volumio go-librespot[20590]: time="2026-02-12T18:04:02-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 12 18:04:03 volumio go-librespot[20590]: time="2026-02-12T18:04:03-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 12 18:04:03 volumio go-librespot[20590]: time="2026-02-12T18:04:03-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 12 18:04:03 volumio go-librespot[20590]: time="2026-02-12T18:04:03-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 12 18:04:03 volumio go-librespot[20590]: time="2026-02-12T18:04:03-06:00" level=info msg="zeroconf server listening on port 39307"
Feb 12 18:04:03 volumio go-librespot[20590]: time="2026-02-12T18:04:03-06:00" level=debug msg="obtained new client token: AADCsFzKi8py/rCtghC/rgK46IKenvuMIuBD1JgbWRDvFNJxVk+w/HDdcZrTK2jeOZn4Sl5dm5bSQ3Ex4hku8tTiYAQP/TFqMyvCwrcjL0mvlzUFi5AV4k+CarLKOKFDmLYJR8F0gGvnSLisYT3EYI1H2QgXKlrM0MUDtPIUB3f/ygvDwxOw4MesMd3F/kBMK6/CvVDlEG32B3Z7QbYSLrcbq7GgqZbZUZTUVuUTLVjZIW4rCKWC6Bk="
Feb 12 18:04:03 volumio go-librespot[20590]: time="2026-02-12T18:04:03-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 12 18:04:03 volumio go-librespot[20590]: time="2026-02-12T18:04:03-06:00" level=debug msg="completed keyexchange"
Feb 12 18:04:03 volumio go-librespot[20590]: time="2026-02-12T18:04:03-06:00" level=debug msg="completed challenge"
Feb 12 18:04:03 volumio go-librespot[20590]: time="2026-02-12T18:04:03-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 12 18:04:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 12 18:04:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 12 18:04:03 volumio volumio[19793]: info: Initializing connection to go-librespot Websocket
Feb 12 18:04:03 volumio volumio[19793]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 12 18:04:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 74.
Feb 12 18:04:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 12 18:04:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 12 18:04:06 volumio go-librespot[20611]: go-librespot daemon starting...
Feb 12 18:04:06 volumio go-librespot[20612]: time="2026-02-12T18:04:06-06:00" level=info msg="running go-librespot 0.4.0"
Feb 12 18:04:06 volumio go-librespot[20612]: time="2026-02-12T18:04:06-06:00" level=debug msg="app state loaded"
Feb 12 18:04:06 volumio go-librespot[20612]: time="2026-02-12T18:04:06-06:00" level=debug msg="stored credentials not found"
Feb 12 18:04:06 volumio go-librespot[20612]: time="2026-02-12T18:04:06-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 12 18:04:06 volumio volumio[19793]: info: Initializing connection to go-librespot Websocket
Feb 12 18:04:06 volumio go-librespot[20612]: time="2026-02-12T18:04:06-06:00" level=debug msg="new websocket client"
Feb 12 18:04:06 volumio volumio[19793]: info: Connection to go-librespot Websocket established
Feb 12 18:04:06 volumio go-librespot[20612]: time="2026-02-12T18:04:06-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 12 18:04:06 volumio go-librespot[20612]: time="2026-02-12T18:04:06-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 12 18:04:06 volumio go-librespot[20612]: time="2026-02-12T18:04:06-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 12 18:04:06 volumio go-librespot[20612]: time="2026-02-12T18:04:06-06:00" level=info msg="zeroconf server listening on port 33007"
Feb 12 18:04:06 volumio go-librespot[20612]: time="2026-02-12T18:04:06-06:00" level=debug msg="obtained new client token: AAD+RYFfedkG+oBp3vBUl0YkXPwRHyy/Nae8zuZlkWBtcJcCIWPbRwbX+cMcmnVzOkwhfKbZSiNVF9VZ6l1I4C6PDUmsQA9JPvaq5Fghr9AY52Rp56i9T2LHB+kVTGNWG9RkbvQjWaR6tpNAT01bxVPLtf0QYeETPEt71lKesHqRHoe/1FWbjlf5MeXcbrOSEbodIMTbG6OXvlF2sg9gbaWl6HDXBUw+LO5Q0QdwWszdI2NDByhyjkG0zA=="
Feb 12 18:04:06 volumio go-librespot[20612]: time="2026-02-12T18:04:06-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 12 18:04:07 volumio go-librespot[20612]: time="2026-02-12T18:04:07-06:00" level=debug msg="completed keyexchange"
Feb 12 18:04:07 volumio go-librespot[20612]: time="2026-02-12T18:04:07-06:00" level=debug msg="completed challenge"
Feb 12 18:04:07 volumio go-librespot[20612]: time="2026-02-12T18:04:07-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 12 18:04:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 12 18:04:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 12 18:04:07 volumio volumio[19793]: info: Connection to go-librespot Websocket closed
Feb 12 18:04:09 volumio volumio[19793]: info: Getting Spotify volume
Feb 12 18:04:09 volumio volumio[19793]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Feb 12 18:04:09 volumio volumio[19793]: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 12 18:04:09 volumio volumio[19793]:     at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) {
Feb 12 18:04:09 volumio volumio[19793]:   errno: -111,
Feb 12 18:04:09 volumio volumio[19793]:   code: 'ECONNREFUSED',
Feb 12 18:04:09 volumio volumio[19793]:   syscall: 'connect',
Feb 12 18:04:09 volumio volumio[19793]:   address: '127.0.0.1',
Feb 12 18:04:09 volumio volumio[19793]:   port: 9879,
Feb 12 18:04:09 volumio volumio[19793]:   response: undefined
Feb 12 18:04:09 volumio volumio[19793]: }
Feb 12 18:04:09 volumio volumio[19793]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Feb 12 18:04:10 volumio sudo[20636]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-12 18:03'
Feb 12 18:04:10 volumio sudo[20636]: 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"