Dec 05 14:15:00 volumio go-librespot[21414]: time="2025-12-05T14:15:00-06:00" level=info msg="running go-librespot 0.4.0" Dec 05 14:15:00 volumio go-librespot[21414]: time="2025-12-05T14:15:00-06:00" level=debug msg="app state loaded" Dec 05 14:15:00 volumio go-librespot[21414]: time="2025-12-05T14:15:00-06:00" level=debug msg="stored credentials not found" Dec 05 14:15:00 volumio go-librespot[21414]: time="2025-12-05T14:15:00-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 14:15:00 volumio go-librespot[21414]: time="2025-12-05T14:15:00-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]" Dec 05 14:15:00 volumio go-librespot[21414]: time="2025-12-05T14:15:00-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]" Dec 05 14:15:00 volumio go-librespot[21414]: time="2025-12-05T14:15:00-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]" Dec 05 14:15:00 volumio go-librespot[21414]: time="2025-12-05T14:15:00-06:00" level=info msg="zeroconf server listening on port 34325" Dec 05 14:15:00 volumio go-librespot[21414]: time="2025-12-05T14:15:00-06:00" level=debug msg="obtained new client token: AACikyE7r6TN+iaF4PWcziSpz3e0F8SXJnGlsULAaAaN3Ltm5f0nxrMa23ld5z7cIItv8WO8026TxYmZjyUhGhQPR679x7o1mpzq55z2GuDWx7DMjhsYKF99WzT6TS3OKOEfq8aTlhUnpq1A54M0TRByg/v90wpA0k6m4UYnoUYNkn+EMjvhSgEPh0oFieuSeh91K5oCw9xwCyiTyC5ptSVzSHwC/nxo3lCf+OwVqS3p5v5ahXVDC72GnQ==" Dec 05 14:15:00 volumio go-librespot[21414]: time="2025-12-05T14:15:00-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 05 14:15:00 volumio go-librespot[21414]: time="2025-12-05T14:15:00-06:00" level=debug msg="completed keyexchange" Dec 05 14:15:00 volumio go-librespot[21414]: time="2025-12-05T14:15:00-06:00" level=debug msg="completed challenge" Dec 05 14:15:00 volumio go-librespot[21414]: time="2025-12-05T14:15:00-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 " Dec 05 14:15:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 14:15:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 14:15:02 volumio volumio[20701]: info: Initializing connection to go-librespot Websocket Dec 05 14:15:02 volumio volumio[20701]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 14:15:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 26. Dec 05 14:15:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 14:15:03 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 14:15:03 volumio go-librespot[21421]: go-librespot daemon starting... Dec 05 14:15:03 volumio go-librespot[21422]: time="2025-12-05T14:15:03-06:00" level=info msg="running go-librespot 0.4.0" Dec 05 14:15:03 volumio go-librespot[21422]: time="2025-12-05T14:15:03-06:00" level=debug msg="app state loaded" Dec 05 14:15:03 volumio go-librespot[21422]: time="2025-12-05T14:15:03-06:00" level=debug msg="stored credentials not found" Dec 05 14:15:03 volumio go-librespot[21422]: time="2025-12-05T14:15:03-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 14:15:04 volumio go-librespot[21422]: time="2025-12-05T14:15:04-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]" Dec 05 14:15:04 volumio go-librespot[21422]: time="2025-12-05T14:15:04-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]" Dec 05 14:15:04 volumio go-librespot[21422]: time="2025-12-05T14:15:04-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]" Dec 05 14:15:04 volumio go-librespot[21422]: time="2025-12-05T14:15:04-06:00" level=info msg="zeroconf server listening on port 33097" Dec 05 14:15:04 volumio go-librespot[21422]: time="2025-12-05T14:15:04-06:00" level=debug msg="obtained new client token: AAAndNZHeYQ9C7DFqKQH2qWwgEV98bYU0NPnO3mQWHmICd7AEHBvIT9Rx2T7ZscRbxM4iqAnmS3RGM+7T/sYywEWhLm4RZE5lj8JpN7Z4rrE9QCxrIb6p43dHluqceJWpYNAALG4LoL5Dv4sTIOAM2Dreq68pNa9bN33Ra8/mMmf3/VTMGITgyh+93a2cINQe5pMSQsg/diKmOcpL9GwqrMptnfI/7Q38d/VpESqW5K2NF872KEbZQI=" Dec 05 14:15:04 volumio go-librespot[21422]: time="2025-12-05T14:15:04-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 05 14:15:04 volumio go-librespot[21422]: time="2025-12-05T14:15:04-06:00" level=debug msg="completed keyexchange" Dec 05 14:15:04 volumio go-librespot[21422]: time="2025-12-05T14:15:04-06:00" level=debug msg="completed challenge" Dec 05 14:15:04 volumio go-librespot[21422]: time="2025-12-05T14:15:04-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 " Dec 05 14:15:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 14:15:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 14:15:05 volumio volumio[20701]: info: Initializing connection to go-librespot Websocket Dec 05 14:15:05 volumio volumio[20701]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 14:15:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 27. Dec 05 14:15:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 14:15:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 14:15:07 volumio go-librespot[21444]: go-librespot daemon starting... Dec 05 14:15:07 volumio go-librespot[21445]: time="2025-12-05T14:15:07-06:00" level=info msg="running go-librespot 0.4.0" Dec 05 14:15:07 volumio go-librespot[21445]: time="2025-12-05T14:15:07-06:00" level=debug msg="app state loaded" Dec 05 14:15:07 volumio go-librespot[21445]: time="2025-12-05T14:15:07-06:00" level=debug msg="stored credentials not found" Dec 05 14:15:07 volumio go-librespot[21445]: time="2025-12-05T14:15:07-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 14:15:07 volumio go-librespot[21445]: time="2025-12-05T14:15:07-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]" Dec 05 14:15:07 volumio go-librespot[21445]: time="2025-12-05T14:15:07-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]" Dec 05 14:15:07 volumio go-librespot[21445]: time="2025-12-05T14:15:07-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]" Dec 05 14:15:07 volumio go-librespot[21445]: time="2025-12-05T14:15:07-06:00" level=info msg="zeroconf server listening on port 36533" Dec 05 14:15:07 volumio go-librespot[21445]: time="2025-12-05T14:15:07-06:00" level=debug msg="obtained new client token: AADMK0F7592NjT6pbqTzLT4uxukzYHsOg1GRLY47WR4Rx4dWHWQ4/g+8kE14FBOkFL0jBiKZ0PghNYlecD5RuD/VTBArq8MgahUMPl5m4OW1uuShhZtdU8ymFhSUnBiYLFuLaHAS6zQohPjtdhqfAesiebl4+12EYoi9YD/M9ES1FLUO7HI0WteJTT9HGaGl2bQbNvVuHUY58LFCHkGw3sdGYNld8emXGWSsViJXXjcZjS0aqe1nWFib7Q==" Dec 05 14:15:07 volumio go-librespot[21445]: time="2025-12-05T14:15:07-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 05 14:15:07 volumio go-librespot[21445]: time="2025-12-05T14:15:07-06:00" level=debug msg="completed keyexchange" Dec 05 14:15:07 volumio go-librespot[21445]: time="2025-12-05T14:15:07-06:00" level=debug msg="completed challenge" Dec 05 14:15:08 volumio go-librespot[21445]: time="2025-12-05T14:15:08-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 " Dec 05 14:15:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 14:15:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 14:15:08 volumio volumio[20701]: info: Initializing connection to go-librespot Websocket Dec 05 14:15:08 volumio volumio[20701]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 14:15:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 28. Dec 05 14:15:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 14:15:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 14:15:11 volumio go-librespot[21455]: go-librespot daemon starting... Dec 05 14:15:11 volumio go-librespot[21456]: time="2025-12-05T14:15:11-06:00" level=info msg="running go-librespot 0.4.0" Dec 05 14:15:11 volumio go-librespot[21456]: time="2025-12-05T14:15:11-06:00" level=debug msg="app state loaded" Dec 05 14:15:11 volumio go-librespot[21456]: time="2025-12-05T14:15:11-06:00" level=debug msg="stored credentials not found" Dec 05 14:15:11 volumio go-librespot[21456]: time="2025-12-05T14:15:11-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 14:15:11 volumio go-librespot[21456]: time="2025-12-05T14:15:11-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]" Dec 05 14:15:11 volumio go-librespot[21456]: time="2025-12-05T14:15:11-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]" Dec 05 14:15:11 volumio go-librespot[21456]: time="2025-12-05T14:15:11-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]" Dec 05 14:15:11 volumio go-librespot[21456]: time="2025-12-05T14:15:11-06:00" level=info msg="zeroconf server listening on port 33235" Dec 05 14:15:11 volumio go-librespot[21456]: time="2025-12-05T14:15:11-06:00" level=debug msg="obtained new client token: AAAVqYUBy/2zvwDPEXXEzKs0nxyCL4X2vKy40CSDJRYRtyg2xSJ4suDfL63IaBgdzfQ7sSuh/Jy2e5Ze06elZGQB1qbmPy52wW4//oZT3skdeDBk3wKRm66ZvIRmAibLs/6F+yLamN2ENFhDz7NIRywg7JpBBC+Zkor4MxCCpwY125nj2Oad5WkEkr//wlNZVdle9enVmTu6WPjwzOFpYC0SKMcrn8o1yedtJzFmLEmkWbdMRAJU2gltKg==" Dec 05 14:15:11 volumio go-librespot[21456]: time="2025-12-05T14:15:11-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 05 14:15:11 volumio go-librespot[21456]: time="2025-12-05T14:15:11-06:00" level=debug msg="completed keyexchange" Dec 05 14:15:11 volumio go-librespot[21456]: time="2025-12-05T14:15:11-06:00" level=debug msg="completed challenge" Dec 05 14:15:11 volumio go-librespot[21456]: time="2025-12-05T14:15:11-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 " Dec 05 14:15:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 14:15:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 14:15:11 volumio volumio[20701]: info: Initializing connection to go-librespot Websocket Dec 05 14:15:11 volumio volumio[20701]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 14:15:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 29. Dec 05 14:15:14 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 14:15:14 volumio volumio[20701]: info: Initializing connection to go-librespot Websocket Dec 05 14:15:14 volumio volumio[20701]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 14:15:14 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 14:15:14 volumio go-librespot[21463]: go-librespot daemon starting... Dec 05 14:15:15 volumio go-librespot[21464]: time="2025-12-05T14:15:15-06:00" level=info msg="running go-librespot 0.4.0" Dec 05 14:15:15 volumio go-librespot[21464]: time="2025-12-05T14:15:15-06:00" level=debug msg="app state loaded" Dec 05 14:15:15 volumio go-librespot[21464]: time="2025-12-05T14:15:15-06:00" level=debug msg="stored credentials not found" Dec 05 14:15:15 volumio go-librespot[21464]: time="2025-12-05T14:15:15-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 14:15:15 volumio go-librespot[21464]: time="2025-12-05T14:15:15-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]" Dec 05 14:15:15 volumio go-librespot[21464]: time="2025-12-05T14:15:15-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]" Dec 05 14:15:15 volumio go-librespot[21464]: time="2025-12-05T14:15:15-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]" Dec 05 14:15:15 volumio go-librespot[21464]: time="2025-12-05T14:15:15-06:00" level=info msg="zeroconf server listening on port 33661" Dec 05 14:15:15 volumio go-librespot[21464]: time="2025-12-05T14:15:15-06:00" level=debug msg="obtained new client token: AAAOU6TUffsDqU8ZwTJDFFSTR6aD4NDj+cK7Z/QtMoRFk8t8irrKg48f5E3XOP6T1FvBUG72BBHGFfJOjaB+doiqBBF6EbuVBv5SgOqMmR9mxwDFot+2hkQE5KQKbgFB+fsAQ8yHWk8ieimNqRQQS4ey93gRnRYoWyT/5uuwJtqnc0OfD/UuGIVKfjgx9Ezgzk3TM23YPs76O3Kfo4f0fOGEoTErfrPWD3rhUc+SRJNM5dKi2kH/GJzgmw==" Dec 05 14:15:15 volumio go-librespot[21464]: time="2025-12-05T14:15:15-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 05 14:15:15 volumio go-librespot[21464]: time="2025-12-05T14:15:15-06:00" level=debug msg="completed keyexchange" Dec 05 14:15:15 volumio go-librespot[21464]: time="2025-12-05T14:15:15-06:00" level=debug msg="completed challenge" Dec 05 14:15:15 volumio go-librespot[21464]: time="2025-12-05T14:15:15-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 " Dec 05 14:15:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 14:15:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 14:15:17 volumio volumio[20701]: info: Initializing connection to go-librespot Websocket Dec 05 14:15:17 volumio volumio[20701]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 14:15:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 30. Dec 05 14:15:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 14:15:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 14:15:18 volumio go-librespot[21486]: go-librespot daemon starting... Dec 05 14:15:18 volumio go-librespot[21487]: time="2025-12-05T14:15:18-06:00" level=info msg="running go-librespot 0.4.0" Dec 05 14:15:18 volumio go-librespot[21487]: time="2025-12-05T14:15:18-06:00" level=debug msg="app state loaded" Dec 05 14:15:18 volumio go-librespot[21487]: time="2025-12-05T14:15:18-06:00" level=debug msg="stored credentials not found" Dec 05 14:15:18 volumio go-librespot[21487]: time="2025-12-05T14:15:18-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 14:15:18 volumio go-librespot[21487]: time="2025-12-05T14:15: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-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 05 14:15:18 volumio go-librespot[21487]: time="2025-12-05T14:15:18-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]" Dec 05 14:15:18 volumio go-librespot[21487]: time="2025-12-05T14:15:18-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]" Dec 05 14:15:18 volumio go-librespot[21487]: time="2025-12-05T14:15:18-06:00" level=info msg="zeroconf server listening on port 42079" Dec 05 14:15:19 volumio go-librespot[21487]: time="2025-12-05T14:15:19-06:00" level=debug msg="obtained new client token: AAAfDdWItFkhvNPK+UK+KOKlu3nOncaEuGAk9siNzz7sHc+MHHfG7EJRFBMeGWYAHNIBVbiF8zz9SD+nadxjlR/LLh/aTBL0GItwnRr8mKZ+FRNDQQeIbgZEcn6huf/akvQCfb5vR7uAjN/mQ3cPNSVDnyBm6N/o4Rp12RNELvSljAsYnikv6uM45YASyw4I6TOosbe2AG/Np3pG8xnnP93MgtyYZMUsqwdMBIsg7sjWtCdfGFlREW8=" Dec 05 14:15:19 volumio go-librespot[21487]: time="2025-12-05T14:15:19-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 05 14:15:19 volumio go-librespot[21487]: time="2025-12-05T14:15:19-06:00" level=debug msg="completed keyexchange" Dec 05 14:15:19 volumio go-librespot[21487]: time="2025-12-05T14:15:19-06:00" level=debug msg="completed challenge" Dec 05 14:15:19 volumio go-librespot[21487]: time="2025-12-05T14:15:19-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 " Dec 05 14:15:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 14:15:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 14:15:20 volumio volumio[20701]: info: Initializing connection to go-librespot Websocket Dec 05 14:15:20 volumio volumio[20701]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 14:15:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 31. Dec 05 14:15:22 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 14:15:22 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 14:15:22 volumio go-librespot[21494]: go-librespot daemon starting... Dec 05 14:15:22 volumio go-librespot[21495]: time="2025-12-05T14:15:22-06:00" level=info msg="running go-librespot 0.4.0" Dec 05 14:15:22 volumio go-librespot[21495]: time="2025-12-05T14:15:22-06:00" level=debug msg="app state loaded" Dec 05 14:15:22 volumio go-librespot[21495]: time="2025-12-05T14:15:22-06:00" level=debug msg="stored credentials not found" Dec 05 14:15:22 volumio go-librespot[21495]: time="2025-12-05T14:15:22-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 14:15:22 volumio go-librespot[21495]: time="2025-12-05T14:15:22-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]" Dec 05 14:15:22 volumio go-librespot[21495]: time="2025-12-05T14:15:22-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]" Dec 05 14:15:22 volumio go-librespot[21495]: time="2025-12-05T14:15:22-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]" Dec 05 14:15:22 volumio go-librespot[21495]: time="2025-12-05T14:15:22-06:00" level=info msg="zeroconf server listening on port 34219" Dec 05 14:15:22 volumio go-librespot[21495]: time="2025-12-05T14:15:22-06:00" level=debug msg="obtained new client token: AACmspWqZ+iJmd8kGZ4DQhZLif4zjpMr1f26Bdu0Y93uTyiCbLMpPgC9Q4RXXpAN8MyDmdXTKdBi86h6YRxwQoD0zmgEP0rY6PrvyKLtaf1b3bPZcUQLtZiTVs/+6EULHwmUymSV7DeXxiilaK5CpjN1BFESUti18WSkCu2MmWjAyL0aKN2BDFynWfi69RhoKyIoPtAlAt+dod+fX+yWeb9jymIlkCaa4knCFq6v+S0y50jN/KHYKUW1Ug==" Dec 05 14:15:22 volumio go-librespot[21495]: time="2025-12-05T14:15:22-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 05 14:15:22 volumio go-librespot[21495]: time="2025-12-05T14:15:22-06:00" level=debug msg="completed keyexchange" Dec 05 14:15:22 volumio go-librespot[21495]: time="2025-12-05T14:15:22-06:00" level=debug msg="completed challenge" Dec 05 14:15:23 volumio go-librespot[21495]: time="2025-12-05T14:15:23-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 " Dec 05 14:15:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 14:15:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 14:15:23 volumio volumio[20701]: info: Initializing connection to go-librespot Websocket Dec 05 14:15:23 volumio volumio[20701]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 14:15:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 32. Dec 05 14:15:26 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 14:15:26 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 14:15:26 volumio go-librespot[21502]: go-librespot daemon starting... Dec 05 14:15:26 volumio go-librespot[21503]: time="2025-12-05T14:15:26-06:00" level=info msg="running go-librespot 0.4.0" Dec 05 14:15:26 volumio go-librespot[21503]: time="2025-12-05T14:15:26-06:00" level=debug msg="app state loaded" Dec 05 14:15:26 volumio go-librespot[21503]: time="2025-12-05T14:15:26-06:00" level=debug msg="stored credentials not found" Dec 05 14:15:26 volumio go-librespot[21503]: time="2025-12-05T14:15:26-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 14:15:26 volumio go-librespot[21503]: time="2025-12-05T14:15:26-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]" Dec 05 14:15:26 volumio go-librespot[21503]: time="2025-12-05T14:15:26-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]" Dec 05 14:15:26 volumio go-librespot[21503]: time="2025-12-05T14:15:26-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]" Dec 05 14:15:26 volumio go-librespot[21503]: time="2025-12-05T14:15:26-06:00" level=info msg="zeroconf server listening on port 44807" Dec 05 14:15:26 volumio go-librespot[21503]: time="2025-12-05T14:15:26-06:00" level=debug msg="obtained new client token: AACqgyEAgvA5nsCvdP0Ew0wfXjIcdidnZ4uGKCmMSgknTQS3093iH2lQLNtiowUWduFAXvDOZGacC+oKYLJoUT4S8qYH846uUshWpvO7z/65pwnwHTZ/w2ZCaejcsGIQsl8YRnCq8vVwzAZ09jpojcEOf6G7jXk8q5GFHW15TU2FSRiIPH8aimhDsa73HUfc/SoTcqg5kKr/lZ0AL8qF9NavhIMpvta0nrqNB4SnQ3EFH7n4oiLEWfQcQA==" Dec 05 14:15:26 volumio go-librespot[21503]: time="2025-12-05T14:15:26-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 05 14:15:26 volumio go-librespot[21503]: time="2025-12-05T14:15:26-06:00" level=debug msg="completed keyexchange" Dec 05 14:15:26 volumio go-librespot[21503]: time="2025-12-05T14:15:26-06:00" level=debug msg="completed challenge" Dec 05 14:15:26 volumio go-librespot[21503]: time="2025-12-05T14:15:26-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 " Dec 05 14:15:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 14:15:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 14:15:26 volumio volumio[20701]: info: Initializing connection to go-librespot Websocket Dec 05 14:15:26 volumio volumio[20701]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 14:15:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 33. Dec 05 14:15:29 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 14:15:29 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 14:15:29 volumio go-librespot[21527]: go-librespot daemon starting... Dec 05 14:15:29 volumio volumio[20701]: info: Initializing connection to go-librespot Websocket Dec 05 14:15:29 volumio volumio[20701]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 14:15:30 volumio go-librespot[21528]: time="2025-12-05T14:15:30-06:00" level=info msg="running go-librespot 0.4.0" Dec 05 14:15:30 volumio go-librespot[21528]: time="2025-12-05T14:15:30-06:00" level=debug msg="app state loaded" Dec 05 14:15:30 volumio go-librespot[21528]: time="2025-12-05T14:15:30-06:00" level=debug msg="stored credentials not found" Dec 05 14:15:30 volumio go-librespot[21528]: time="2025-12-05T14:15:30-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 14:15:30 volumio go-librespot[21528]: time="2025-12-05T14:15:30-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]" Dec 05 14:15:30 volumio go-librespot[21528]: time="2025-12-05T14:15:30-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]" Dec 05 14:15:30 volumio go-librespot[21528]: time="2025-12-05T14:15:30-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]" Dec 05 14:15:30 volumio go-librespot[21528]: time="2025-12-05T14:15:30-06:00" level=info msg="zeroconf server listening on port 34031" Dec 05 14:15:30 volumio go-librespot[21528]: time="2025-12-05T14:15:30-06:00" level=debug msg="obtained new client token: AABE1oAUcs2Yx0ACnM5Jfi9DK7C2FidgxSXlUyd8b6eDBNsY1UZht5Bf37xIX6j/QBijF/5Y+GRoMUufSbxcynyu3wgv9h+9Pjfz+VRXY1qGg8fYDvWRRT/xlhkwQu2YMtvz77PpXcyjZvd9CxQfIkYfsEppYsIM9CLnUtEpr5pAySylc4vyVvZtWgMg0q2/zZ2QqqX8DDwA3uLA8A+HcM0dFMLXWdMUyVPoaEWHmHmbsmIqMRPUR63qLQ==" Dec 05 14:15:30 volumio go-librespot[21528]: time="2025-12-05T14:15:30-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 05 14:15:30 volumio go-librespot[21528]: time="2025-12-05T14:15:30-06:00" level=debug msg="completed keyexchange" Dec 05 14:15:30 volumio go-librespot[21528]: time="2025-12-05T14:15:30-06:00" level=debug msg="completed challenge" Dec 05 14:15:30 volumio go-librespot[21528]: time="2025-12-05T14:15:30-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 " Dec 05 14:15:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 14:15:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 14:15:32 volumio volumio[20701]: info: Initializing connection to go-librespot Websocket Dec 05 14:15:32 volumio volumio[20701]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 14:15:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 34. Dec 05 14:15:33 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 14:15:33 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 14:15:33 volumio go-librespot[21535]: go-librespot daemon starting... Dec 05 14:15:33 volumio go-librespot[21536]: time="2025-12-05T14:15:33-06:00" level=info msg="running go-librespot 0.4.0" Dec 05 14:15:33 volumio go-librespot[21536]: time="2025-12-05T14:15:33-06:00" level=debug msg="app state loaded" Dec 05 14:15:33 volumio go-librespot[21536]: time="2025-12-05T14:15:33-06:00" level=debug msg="stored credentials not found" Dec 05 14:15:33 volumio go-librespot[21536]: time="2025-12-05T14:15:33-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 14:15:34 volumio go-librespot[21536]: time="2025-12-05T14:15: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-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 05 14:15:34 volumio go-librespot[21536]: time="2025-12-05T14:15:33-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]" Dec 05 14:15:34 volumio go-librespot[21536]: time="2025-12-05T14:15:33-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]" Dec 05 14:15:34 volumio go-librespot[21536]: time="2025-12-05T14:15:34-06:00" level=info msg="zeroconf server listening on port 43379" Dec 05 14:15:34 volumio go-librespot[21536]: time="2025-12-05T14:15:34-06:00" level=debug msg="obtained new client token: AADst1FqryRVX44iHbf/Hll1EjIJzLGZ0RiB5ihSqLn1rQCPiCJtI4OuHGyj3+TYvlWWtNKtCD2b/SbkbZPbi6oPbkIuWmANkSbsWefqlMEnGKpSG0Vyw2dWm2TcEvS9ufjQEX6Y+W7QSdRbvlN4Vv0DrBtCJNKzr+rhsRo1qaEpVq+BRxIHTHAkg1BOJUwo+PCzWlGH+a8qJr/P+s94KKR5K9u53Fyi2Z+nnaAn1sFjh/tsaj3PYq4=" Dec 05 14:15:34 volumio go-librespot[21536]: time="2025-12-05T14:15:34-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 05 14:15:34 volumio go-librespot[21536]: time="2025-12-05T14:15:34-06:00" level=debug msg="completed keyexchange" Dec 05 14:15:34 volumio go-librespot[21536]: time="2025-12-05T14:15:34-06:00" level=debug msg="completed challenge" Dec 05 14:15:34 volumio go-librespot[21536]: time="2025-12-05T14:15:34-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 " Dec 05 14:15:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 14:15:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 14:15:36 volumio volumio[20701]: info: Initializing connection to go-librespot Websocket Dec 05 14:15:36 volumio volumio[20701]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 14:15:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 35. Dec 05 14:15:37 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 14:15:37 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 14:15:37 volumio go-librespot[21558]: go-librespot daemon starting... Dec 05 14:15:37 volumio go-librespot[21559]: time="2025-12-05T14:15:37-06:00" level=info msg="running go-librespot 0.4.0" Dec 05 14:15:37 volumio go-librespot[21559]: time="2025-12-05T14:15:37-06:00" level=debug msg="app state loaded" Dec 05 14:15:37 volumio go-librespot[21559]: time="2025-12-05T14:15:37-06:00" level=debug msg="stored credentials not found" Dec 05 14:15:37 volumio go-librespot[21559]: time="2025-12-05T14:15:37-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 14:15:37 volumio go-librespot[21559]: time="2025-12-05T14:15:37-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]" Dec 05 14:15:37 volumio go-librespot[21559]: time="2025-12-05T14:15:37-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]" Dec 05 14:15:37 volumio go-librespot[21559]: time="2025-12-05T14:15:37-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]" Dec 05 14:15:37 volumio go-librespot[21559]: time="2025-12-05T14:15:37-06:00" level=info msg="zeroconf server listening on port 42065" Dec 05 14:15:37 volumio go-librespot[21559]: time="2025-12-05T14:15:37-06:00" level=debug msg="obtained new client token: AAAeEgCozGba1bPg5QebnMOmQEn0tSeDx49+KFkzMuyUbKJGQq2rxkZKnl8dmC28ZVLs3EYFYOiVOu15cvXnz6OLtrSav/yoNtIkflrZ8pgvxwWvMYA3JatJw/B5009Zo4hlj6IndkQYaE/fF5EZ/br7Z8nIexYIOUsg4krLuEEinMTlLnHcKutbMG+rYOxSED7wT8ZMyG8h8prkoQr+abYorMM0M3eGZbUq/IOBGFNatHwB9U7bdZhixg==" Dec 05 14:15:37 volumio go-librespot[21559]: time="2025-12-05T14:15:37-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 05 14:15:37 volumio go-librespot[21559]: time="2025-12-05T14:15:37-06:00" level=debug msg="completed keyexchange" Dec 05 14:15:37 volumio go-librespot[21559]: time="2025-12-05T14:15:37-06:00" level=debug msg="completed challenge" Dec 05 14:15:37 volumio go-librespot[21559]: time="2025-12-05T14:15: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 " Dec 05 14:15:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 14:15:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 14:15:39 volumio volumio[20701]: info: Initializing connection to go-librespot Websocket Dec 05 14:15:39 volumio volumio[20701]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 14:15:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 36. Dec 05 14:15:41 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 14:15:41 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 14:15:41 volumio go-librespot[21566]: go-librespot daemon starting... Dec 05 14:15:41 volumio go-librespot[21567]: time="2025-12-05T14:15:41-06:00" level=info msg="running go-librespot 0.4.0" Dec 05 14:15:41 volumio go-librespot[21567]: time="2025-12-05T14:15:41-06:00" level=debug msg="app state loaded" Dec 05 14:15:41 volumio go-librespot[21567]: time="2025-12-05T14:15:41-06:00" level=debug msg="stored credentials not found" Dec 05 14:15:41 volumio go-librespot[21567]: time="2025-12-05T14:15:41-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 14:15:41 volumio go-librespot[21567]: time="2025-12-05T14:15:41-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]" Dec 05 14:15:41 volumio go-librespot[21567]: time="2025-12-05T14:15:41-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]" Dec 05 14:15:41 volumio go-librespot[21567]: time="2025-12-05T14:15:41-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]" Dec 05 14:15:41 volumio go-librespot[21567]: time="2025-12-05T14:15:41-06:00" level=info msg="zeroconf server listening on port 43247" Dec 05 14:15:41 volumio go-librespot[21567]: time="2025-12-05T14:15:41-06:00" level=debug msg="obtained new client token: AAAdC/5EubeCAKC/dH0UcDEShZNzRJpI0nHQyWMES8gSBxYQ4YDgNe0PSTK3ikA3J/za+LnHT0AKV2kobZypZC/xG6QdAwUXVpzz9nAQZr6TZ6G1Sg7VZ3JrhweOQObng3Rm27coUUjarzPik5+pqRli30rtuKvcnia/j8bI/G+Ad8G+k5TLHsoozyk04Yj0/FZgDbQtg1a5UVjuSCJNKVGORvIy2GSlTmyDAMVDW2mzxKlQNjmvD7RMRA==" Dec 05 14:15:41 volumio go-librespot[21567]: time="2025-12-05T14:15:41-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 05 14:15:41 volumio go-librespot[21567]: time="2025-12-05T14:15:41-06:00" level=debug msg="completed keyexchange" Dec 05 14:15:41 volumio go-librespot[21567]: time="2025-12-05T14:15:41-06:00" level=debug msg="completed challenge" Dec 05 14:15:41 volumio go-librespot[21567]: time="2025-12-05T14:15:41-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 " Dec 05 14:15:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 14:15:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 14:15:42 volumio volumio[20701]: info: Initializing connection to go-librespot Websocket Dec 05 14:15:42 volumio volumio[20701]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 14:15:45 volumio volumio[20701]: info: Initializing connection to go-librespot Websocket Dec 05 14:15:45 volumio volumio[20701]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 14:15:45 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 37. Dec 05 14:15:45 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 14:15:45 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 14:15:45 volumio go-librespot[21574]: go-librespot daemon starting... Dec 05 14:15:45 volumio go-librespot[21575]: time="2025-12-05T14:15:45-06:00" level=info msg="running go-librespot 0.4.0" Dec 05 14:15:45 volumio go-librespot[21575]: time="2025-12-05T14:15:45-06:00" level=debug msg="app state loaded" Dec 05 14:15:45 volumio go-librespot[21575]: time="2025-12-05T14:15:45-06:00" level=debug msg="stored credentials not found" Dec 05 14:15:45 volumio go-librespot[21575]: time="2025-12-05T14:15:45-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 14:15:45 volumio go-librespot[21575]: time="2025-12-05T14:15:45-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]" Dec 05 14:15:45 volumio go-librespot[21575]: time="2025-12-05T14:15:45-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]" Dec 05 14:15:45 volumio go-librespot[21575]: time="2025-12-05T14:15:45-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]" Dec 05 14:15:45 volumio go-librespot[21575]: time="2025-12-05T14:15:45-06:00" level=info msg="zeroconf server listening on port 44789" Dec 05 14:15:45 volumio go-librespot[21575]: time="2025-12-05T14:15:45-06:00" level=debug msg="obtained new client token: AADSYzlm1e02LqmNbPIPwxjNcTYTRDMj7wygrkmwv23swt3tYQIJ89OUG3biTsdnZ93o0NgigpcZqUoBD4Z+f++wxtSg8y65tblznWXtZHuJlXlAj100AYCGRc+j7rKEpdYZXql0LlZYvDs7GzwgWzZzvV5bZLrZweSBBHgT7OflV4vn8Ra8zoboJmr3dpj8XHdfoutJ1dpOUY1oG2HrUs5ynZjrkRxyMJR+P/Ns6NxGWKUes0SQNydKPQ==" Dec 05 14:15:45 volumio go-librespot[21575]: time="2025-12-05T14:15:45-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 05 14:15:45 volumio go-librespot[21575]: time="2025-12-05T14:15:45-06:00" level=debug msg="completed keyexchange" Dec 05 14:15:45 volumio go-librespot[21575]: time="2025-12-05T14:15:45-06:00" level=debug msg="completed challenge" Dec 05 14:15:45 volumio go-librespot[21575]: time="2025-12-05T14:15:45-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 " Dec 05 14:15:45 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 14:15:45 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 14:15:48 volumio volumio[20701]: info: Initializing connection to go-librespot Websocket Dec 05 14:15:48 volumio volumio[20701]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 14:15:48 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 38. Dec 05 14:15:48 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 14:15:48 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 14:15:48 volumio go-librespot[21599]: go-librespot daemon starting... Dec 05 14:15:49 volumio go-librespot[21600]: time="2025-12-05T14:15:49-06:00" level=info msg="running go-librespot 0.4.0" Dec 05 14:15:49 volumio go-librespot[21600]: time="2025-12-05T14:15:49-06:00" level=debug msg="app state loaded" Dec 05 14:15:49 volumio go-librespot[21600]: time="2025-12-05T14:15:49-06:00" level=debug msg="stored credentials not found" Dec 05 14:15:49 volumio go-librespot[21600]: time="2025-12-05T14:15:49-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 14:15:49 volumio go-librespot[21600]: time="2025-12-05T14:15:49-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]" Dec 05 14:15:49 volumio go-librespot[21600]: time="2025-12-05T14:15:49-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]" Dec 05 14:15:49 volumio go-librespot[21600]: time="2025-12-05T14:15:49-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]" Dec 05 14:15:49 volumio go-librespot[21600]: time="2025-12-05T14:15:49-06:00" level=info msg="zeroconf server listening on port 33137" Dec 05 14:15:49 volumio go-librespot[21600]: time="2025-12-05T14:15:49-06:00" level=debug msg="obtained new client token: AABkgK6B5vBEMIjOOF62GwKJJLN5uPSqIHNjt+fgU2ut++qsysXc3GksvdH/LI70QdPF2yTm1VKVs6kEzZXg8ZJxZVsRKMu3ZuwXE1KtcSkZlC3Mb/TF4T4pNSDDK6EzG2+NUPmpchR2HHYKVrq67x2KienIPrAXgqbynr+7TBLdTnOIyl3qFh1xU9JbP+nFuHmx1yL8s0xIy3scRFhiF5EHgf5WbDexFpDaRFTX1z/XDOivA2FuC1/81Q==" Dec 05 14:15:49 volumio go-librespot[21600]: time="2025-12-05T14:15:49-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 05 14:15:49 volumio go-librespot[21600]: time="2025-12-05T14:15:49-06:00" level=debug msg="completed keyexchange" Dec 05 14:15:49 volumio go-librespot[21600]: time="2025-12-05T14:15:49-06:00" level=debug msg="completed challenge" Dec 05 14:15:49 volumio go-librespot[21600]: time="2025-12-05T14:15:49-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 " Dec 05 14:15:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 14:15:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 14:15:51 volumio volumio[20701]: info: Initializing connection to go-librespot Websocket Dec 05 14:15:51 volumio volumio[20701]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 14:15:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 39. Dec 05 14:15:53 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 14:15:53 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 14:15:53 volumio go-librespot[21608]: go-librespot daemon starting... Dec 05 14:15:53 volumio go-librespot[21609]: time="2025-12-05T14:15:53-06:00" level=info msg="running go-librespot 0.4.0" Dec 05 14:15:53 volumio go-librespot[21609]: time="2025-12-05T14:15:53-06:00" level=debug msg="app state loaded" Dec 05 14:15:53 volumio go-librespot[21609]: time="2025-12-05T14:15:53-06:00" level=debug msg="stored credentials not found" Dec 05 14:15:53 volumio go-librespot[21609]: time="2025-12-05T14:15:53-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 14:15:53 volumio go-librespot[21609]: time="2025-12-05T14:15:53-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]" Dec 05 14:15:53 volumio go-librespot[21609]: time="2025-12-05T14:15:53-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]" Dec 05 14:15:53 volumio go-librespot[21609]: time="2025-12-05T14:15:53-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]" Dec 05 14:15:53 volumio go-librespot[21609]: time="2025-12-05T14:15:53-06:00" level=info msg="zeroconf server listening on port 40273" Dec 05 14:15:53 volumio go-librespot[21609]: time="2025-12-05T14:15:53-06:00" level=debug msg="obtained new client token: AAAYpMzSThwNAns6Da2+fY98/nSlTRVIMKVyNSvv45xXy+/Jw9SBTUJiie3BIOkZ8ctQS64K0EyDlj/ztHKf7QG/cnO6QhId4PX63XOR5lmfSYKFIxMXqxzf/wcdyZrZdTi7XIoZ+j6596YG6e3+msrnRwlqpl5KaaIy45UXsPJl6W3qxZpfIBixL3l8flNe1QRJRrI3h3ZpyvpeB/5fXuG/pm+1Xv3ret/HW71o7PU9PWS7lpwmH8JGag==" Dec 05 14:15:53 volumio go-librespot[21609]: time="2025-12-05T14:15:53-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 05 14:15:53 volumio go-librespot[21609]: time="2025-12-05T14:15:53-06:00" level=debug msg="completed keyexchange" Dec 05 14:15:53 volumio go-librespot[21609]: time="2025-12-05T14:15:53-06:00" level=debug msg="completed challenge" Dec 05 14:15:53 volumio go-librespot[21609]: time="2025-12-05T14:15:53-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 " Dec 05 14:15:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 14:15:53 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 14:15:54 volumio volumio[20701]: info: Initializing connection to go-librespot Websocket Dec 05 14:15:54 volumio volumio[20701]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 14:15:56 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 40. Dec 05 14:15:56 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 14:15:56 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 14:15:56 volumio go-librespot[21631]: go-librespot daemon starting... Dec 05 14:15:57 volumio go-librespot[21632]: time="2025-12-05T14:15:57-06:00" level=info msg="running go-librespot 0.4.0" Dec 05 14:15:57 volumio go-librespot[21632]: time="2025-12-05T14:15:57-06:00" level=debug msg="app state loaded" Dec 05 14:15:57 volumio go-librespot[21632]: time="2025-12-05T14:15:57-06:00" level=debug msg="stored credentials not found" Dec 05 14:15:57 volumio go-librespot[21632]: time="2025-12-05T14:15:57-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 14:15:57 volumio volumio[20701]: info: Initializing connection to go-librespot Websocket Dec 05 14:15:57 volumio go-librespot[21632]: time="2025-12-05T14:15:57-06:00" level=debug msg="new websocket client" Dec 05 14:15:57 volumio volumio[20701]: info: Connection to go-librespot Websocket established Dec 05 14:15:57 volumio go-librespot[21632]: time="2025-12-05T14:15:57-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]" Dec 05 14:15:57 volumio go-librespot[21632]: time="2025-12-05T14:15:57-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]" Dec 05 14:15:57 volumio go-librespot[21632]: time="2025-12-05T14:15:57-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]" Dec 05 14:15:57 volumio go-librespot[21632]: time="2025-12-05T14:15:57-06:00" level=info msg="zeroconf server listening on port 34351" Dec 05 14:15:57 volumio go-librespot[21632]: time="2025-12-05T14:15:57-06:00" level=debug msg="obtained new client token: AADrS/S5wEatSTYZ3vwPjVcdc1DIPcsL0PWl5yl1DL6rtAEcZXnCMW29syvjCaRDYkzJnqLrspAsyjuF+gnU4Bmpx3KYknOOBGwnz60YEpojaFqRlrEt6eBcPm4ACGwshGHK31SWvm70ZBP5N4aeg0gH8Z/pHkkcU782yvEcWiZRgu0VaK+/ku0KTaOWQXvwFN/GjdQUUmEIia14bHFtKV+74jcQnPzSq534S16ZeBBTYzdkuFCRBBAx3g==" Dec 05 14:15:57 volumio go-librespot[21632]: time="2025-12-05T14:15:57-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 05 14:15:57 volumio go-librespot[21632]: time="2025-12-05T14:15:57-06:00" level=debug msg="completed keyexchange" Dec 05 14:15:57 volumio go-librespot[21632]: time="2025-12-05T14:15:57-06:00" level=debug msg="completed challenge" Dec 05 14:15:57 volumio go-librespot[21632]: time="2025-12-05T14:15:57-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 " Dec 05 14:15:57 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 14:15:57 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 14:15:57 volumio volumio[20701]: info: Connection to go-librespot Websocket closed Dec 05 14:16:00 volumio volumio[20701]: info: Getting Spotify volume Dec 05 14:16:00 volumio volumio[20701]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 05 14:16:00 volumio volumio[20701]: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 14:16:00 volumio volumio[20701]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Dec 05 14:16:00 volumio volumio[20701]: errno: -111, Dec 05 14:16:00 volumio volumio[20701]: code: 'ECONNREFUSED', Dec 05 14:16:00 volumio volumio[20701]: syscall: 'connect', Dec 05 14:16:00 volumio volumio[20701]: address: '127.0.0.1', Dec 05 14:16:00 volumio volumio[20701]: port: 9879, Dec 05 14:16:00 volumio volumio[20701]: response: undefined Dec 05 14:16:00 volumio volumio[20701]: } Dec 05 14:16:00 volumio volumio[20701]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 05 14:16:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 41. Dec 05 14:16:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 14:16:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 14:16:00 volumio go-librespot[21651]: go-librespot daemon starting... Dec 05 14:16:00 volumio go-librespot[21652]: time="2025-12-05T14:16:00-06:00" level=info msg="running go-librespot 0.4.0" Dec 05 14:16:00 volumio go-librespot[21652]: time="2025-12-05T14:16:00-06:00" level=debug msg="app state loaded" Dec 05 14:16:00 volumio go-librespot[21652]: time="2025-12-05T14:16:00-06:00" level=debug msg="stored credentials not found" Dec 05 14:16:00 volumio go-librespot[21652]: time="2025-12-05T14:16:00-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 14:16:00 volumio sudo[21661]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-05 14:15' Dec 05 14:16:00 volumio sudo[21661]: 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"